[Apr 6 21:55:18] DEBUG[17308] res_config_mysql.c: MySQL RealTime: Everything is fine. [Apr 6 21:55:18] DEBUG[17308] res_config_mysql.c: MySQL RealTime: Update SQL: UPDATE iax SET ipaddr = '0.0.0.0', port = '0', regseconds = '0' WHERE name = ' 7762452' [Apr 6 21:55:18] DEBUG[17308] res_config_mysql.c: MySQL RealTime: Updated 1 rows on table: iax [Apr 6 21:55:18] DEBUG[17308] chan_iax2.c: realtime_peer: Bah, '7762452' is expired (27567111/1148322207/1175889318)! [Apr 6 21:55:18] DEBUG[17308] devicestate.c: Notification of state change to be queued on device/channel IAX2/7762452 [Apr 6 21:55:18] DEBUG[17297] devicestate.c: No provider found, checking channel drivers for IAX2 - 7762452 [Apr 6 21:55:18] DEBUG[17307] chan_iax2.c: Packet arrived out of order (expecting 1, got 0) (frametype = 6, subclass = 13) [Apr 6 21:55:18] DEBUG[17297] chan_iax2.c: Checking device state for device 7762452 [Apr 6 21:55:18] DEBUG[17307] chan_iax2.c: Acking anyway [Apr 6 21:55:18] DEBUG[17297] chan_iax2.c: iax2_devicestate: Found peer. What's device state of 7762452? addr=0, defaddr=0 maxms=0, lastms=0 [Apr 6 21:55:18] DEBUG[17297] devicestate.c: Changing state for IAX2/7762452 - state 5 (Unavailable) [Apr 6 21:55:18] DEBUG[17302] devicestate.c: Notification of state change to be queued on device/channel IAX2/7762452 [Apr 6 21:55:18] DEBUG[17297] devicestate.c: No provider found, checking channel drivers for IAX2 - 7762452 [Apr 6 21:55:18] DEBUG[17297] chan_iax2.c: Checking device state for device 7762452 [Apr 6 21:55:18] DEBUG[17297] chan_iax2.c: iax2_devicestate: Found peer. What's device state of 7762452? addr=-392132907, defaddr=0 maxms=0, lastms=0 [Apr 6 21:55:18] DEBUG[17297] devicestate.c: Changing state for IAX2/7762452 - state 1 (Not in use) [Apr 6 21:55:18] VERBOSE[17302] logger.c: -- Registered IAX2 '7762452' (AUTHENTICATED) at 213.134.160.232:1517 [Apr 6 21:55:18] DEBUG[17302] devicestate.c: Notification of state change to be queued on device/channel IAX2/7762452 [Apr 6 21:55:18] DEBUG[17297] devicestate.c: No provider found, checking channel drivers for IAX2 - 7762452 [Apr 6 21:55:18] DEBUG[17297] chan_iax2.c: Checking device state for device 7762452 [Apr 6 21:55:18] DEBUG[17297] chan_iax2.c: iax2_devicestate: Found peer. What's device state of 7762452? addr=-392132907, defaddr=0 maxms=0, lastms=0 [Apr 6 21:55:18] NOTICE[17302] chan_iax2.c: Restricting registration for peer '7762452' to 240 seconds (requested 300) [Apr 6 21:55:18] DEBUG[17297] devicestate.c: Changing state for IAX2/7762452 - state 1 (Not in use) [Apr 6 21:55:19] DEBUG[17306] chan_iax2.c: Received VNAK: resending outstanding frames [Apr 6 21:55:20] DEBUG[17310] chan_iax2.c: Immediately destroying 6, having received hangup [Apr 6 21:55:20] VERBOSE[17481] logger.c: -- AGI Script agi://10.0.1.246/echotest.php completed, returning 0 [Apr 6 21:55:20] DEBUG[17481] pbx.c: Extension 201, priority 3 returned normally even though call was hung up [Apr 6 21:55:20] DEBUG[17481] cdr.c: Dropping CDR ! [Apr 6 21:55:20] DEBUG[17481] channel.c: Hanging up channel 'IAX2/10666192-6' [Apr 6 21:55:20] DEBUG[17481] chan_iax2.c: We're hanging up IAX2/10666192-6 now... [Apr 6 21:55:20] DEBUG[17481] chan_iax2.c: Really destroying IAX2/10666192-6 now... [Apr 6 21:55:20] VERBOSE[17481] logger.c: -- Hungup 'IAX2/10666192-6' [Apr 6 21:55:20] DEBUG[17481] devicestate.c: Notification of state change to be queued on device/channel IAX2/10666192-6 [Apr 6 21:55:20] DEBUG[17297] devicestate.c: No provider found, checking channel drivers for IAX2 - 10666192 [Apr 6 21:55:20] DEBUG[17297] chan_iax2.c: Checking device state for device 10666192 [Apr 6 21:55:20] DEBUG[17297] chan_iax2.c: iax2_devicestate: Found peer. What's device state of 10666192? addr=452801104, defaddr=0 maxms=0, lastms=0 [Apr 6 21:55:20] DEBUG[17297] devicestate.c: Changing state for IAX2/10666192 - state 1 (Not in use) [Apr 6 21:55:25] DEBUG[17309] res_config_mysql.c: MySQL RealTime: Everything is fine. [Apr 6 21:55:25] DEBUG[17309] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM iax WHERE ipaddr = '80.50.82.30' AND port = '3318' [Apr 6 21:55:25] DEBUG[17309] res_config_mysql.c: MySQL RealTime: Everything is fine. [Apr 6 21:55:25] DEBUG[17309] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM iax WHERE name = '6689473' [Apr 6 21:55:25] DEBUG[17309] db.c: Unable to find key '6689473' in family 'IAX/Registry' [Apr 6 21:55:25] DEBUG[17309] res_config_mysql.c: MySQL RealTime: Everything is fine. [Apr 6 21:55:25] DEBUG[17309] res_config_mysql.c: MySQL RealTime: Update SQL: UPDATE iax SET ipaddr = '0.0.0.0', port = '0', regseconds = '0' WHERE name = '6689473' [Apr 6 21:55:25] DEBUG[17309] res_config_mysql.c: MySQL RealTime: Updated 1 rows on table: iax [Apr 6 21:55:25] DEBUG[17309] chan_iax2.c: realtime_peer: Bah, '6689473' is expired (1175889205/120/1175889325)! [Apr 6 21:55:25] DEBUG[17309] devicestate.c: Notification of state change to be queued on device/channel IAX2/6689473 [Apr 6 21:55:25] DEBUG[17297] devicestate.c: No provider found, checking channel drivers for IAX2 - 6689473 [Apr 6 21:55:25] DEBUG[17297] chan_iax2.c: Checking device state for device 6689473 [Apr 6 21:55:25] DEBUG[17297] chan_iax2.c: iax2_devicestate: Found peer. What's device state of 6689473? addr=0, defaddr=0 maxms=0, lastms=0 [Apr 6 21:55:25] DEBUG[17297] devicestate.c: Changing state for IAX2/6689473 - state 5 (Unavailable) [Apr 6 21:55:31] DEBUG[17308] res_config_mysql.c: MySQL RealTime: Everything is fine. [Apr 6 21:55:31] DEBUG[17308] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM iax WHERE ipaddr = '83.20.92.4' AND port = '1986' [Apr 6 21:55:31] DEBUG[17308] res_config_mysql.c: MySQL RealTime: Everything is fine. [Apr 6 21:55:31] DEBUG[17308] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM iax WHERE name = '9640164' [Apr 6 21:55:31] DEBUG[17308] db.c: Unable to find key '9640164' in family 'IAX/Registry' [Apr 6 21:55:31] DEBUG[17308] res_config_mysql.c: MySQL RealTime: Everything is fine. [Apr 6 21:55:31] DEBUG[17308] res_config_mysql.c: MySQL RealTime: Update SQL: UPDATE iax SET ipaddr = '0.0.0.0', port = '0', regseconds = '0' WHERE name = ' 9640164' [Apr 6 21:55:31] DEBUG[17308] res_config_mysql.c: MySQL RealTime: Updated 1 rows on table: iax [Apr 6 21:55:31] DEBUG[17308] chan_iax2.c: realtime_peer: Bah, '9640164' is expired (15409655/1160479676/1175889331)! [Apr 6 21:55:31] DEBUG[17308] devicestate.c: Notification of state change to be queued on device/channel IAX2/9640164 [Apr 6 21:55:31] DEBUG[17297] devicestate.c: No provider found, checking channel drivers for IAX2 - 9640164 [Apr 6 21:55:31] DEBUG[17297] chan_iax2.c: Checking device state for device 9640164 [Apr 6 21:55:31] DEBUG[17297] chan_iax2.c: iax2_devicestate: Found peer. What's device state of 9640164? addr=0, defaddr=0 maxms=0, lastms=0 [Apr 6 21:55:31] DEBUG[17297] devicestate.c: Changing state for IAX2/9640164 - state 5 (Unavailable) [Apr 6 21:55:31] DEBUG[17303] devicestate.c: Notification of state change to be queued on device/channel IAX2/9640164 [Apr 6 21:55:31] DEBUG[17297] devicestate.c: No provider found, checking channel drivers for IAX2 - 9640164 [Apr 6 21:55:31] DEBUG[17297] chan_iax2.c: Checking device state for device 9640164 [Apr 6 21:55:31] DEBUG[17297] chan_iax2.c: iax2_devicestate: Found peer. What's device state of 9640164? addr=73143379, defaddr=0 maxms=0, lastms=0 [Apr 6 21:55:31] DEBUG[17297] devicestate.c: Changing state for IAX2/9640164 - state 1 (Not in use) [Apr 6 21:55:31] VERBOSE[17303] logger.c: -- Registered IAX2 '9640164' (AUTHENTICATED) at 83.20.92.4:1986 [Apr 6 21:55:31] DEBUG[17303] devicestate.c: Notification of state change to be queued on device/channel IAX2/9640164 [Apr 6 21:55:31] NOTICE[17303] chan_iax2.c: Restricting registration for peer '9640164' to 240 seconds (requested 300) [Apr 6 21:55:31] DEBUG[17297] devicestate.c: No provider found, checking channel drivers for IAX2 - 9640164 [Apr 6 21:55:31] DEBUG[17297] chan_iax2.c: Checking device state for device 9640164 [Apr 6 21:55:31] DEBUG[17297] chan_iax2.c: iax2_devicestate: Found peer. What's device state of 9640164? addr=73143379, defaddr=0 maxms=0, lastms=0 [Apr 6 21:55:31] DEBUG[17297] devicestate.c: Changing state for IAX2/9640164 - state 1 (Not in use) [Apr 6 21:55:31] DEBUG[17309] chan_iax2.c: Packet arrived out of order (expecting 2, got 1) (frametype = 6, subclass = 13) [Apr 6 21:55:31] DEBUG[17309] chan_iax2.c: Acking anyway [Apr 6 21:55:32] DEBUG[17307] chan_iax2.c: Packet arrived out of order (expecting 2, got 1) (frametype = 6, subclass = 13) [Apr 6 21:55:32] DEBUG[17307] chan_iax2.c: Acking anyway [Apr 6 21:55:35] DEBUG[17304] res_config_mysql.c: MySQL RealTime: Everything is fine. [Apr 6 21:55:35] DEBUG[17304] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM iax WHERE ipaddr = '83.22.146.227' AND port = '1427' [Apr 6 21:55:35] DEBUG[17304] res_config_mysql.c: MySQL RealTime: Everything is fine. [Apr 6 21:55:35] DEBUG[17304] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM iax WHERE name = '5028863' [Apr 6 21:55:35] DEBUG[17304] db.c: Unable to find key '5028863' in family 'IAX/Registry' [Apr 6 21:55:35] DEBUG[17304] res_config_mysql.c: MySQL RealTime: Everything is fine. [Apr 6 21:55:35] DEBUG[17304] res_config_mysql.c: MySQL RealTime: Update SQL: UPDATE iax SET ipaddr = '0.0.0.0', port = '0', regseconds = '0' WHERE name = ' 5028863' [Apr 6 21:55:35] DEBUG[17304] res_config_mysql.c: MySQL RealTime: Updated 1 rows on table: iax [Apr 6 21:55:35] DEBUG[17304] chan_iax2.c: realtime_peer: Bah, '5028863' is expired (20313714/1155575621/1175889335)! [Apr 6 21:55:35] DEBUG[17304] devicestate.c: Notification of state change to be queued on device/channel IAX2/5028863 [Apr 6 21:55:35] DEBUG[17297] devicestate.c: No provider found, checking channel drivers for IAX2 - 5028863 [Apr 6 21:55:35] DEBUG[17297] chan_iax2.c: Checking device state for device 5028863 [Apr 6 21:55:35] DEBUG[17297] chan_iax2.c: iax2_devicestate: Found peer. What's device state of 5028863? addr=0, defaddr=0 maxms=0, lastms=0 [Apr 6 21:55:35] DEBUG[17297] devicestate.c: Changing state for IAX2/5028863 - state 5 (Unavailable) [Apr 6 21:55:35] DEBUG[17301] chan_iax2.c: Packet arrived out of order (expecting 1, got 0) (frametype = 6, subclass = 13) [Apr 6 21:55:35] DEBUG[17301] chan_iax2.c: Acking anyway [Apr 6 21:55:35] DEBUG[17305] devicestate.c: Notification of state change to be queued on device/channel IAX2/5028863 [Apr 6 21:55:35] DEBUG[17297] devicestate.c: No provider found, checking channel drivers for IAX2 - 5028863 [Apr 6 21:55:35] DEBUG[17297] chan_iax2.c: Checking device state for device 5028863 [Apr 6 21:55:35] DEBUG[17297] chan_iax2.c: iax2_devicestate: Found peer. What's device state of 5028863? addr=-476965293, defaddr=0 maxms=0, lastms=0 [Apr 6 21:55:35] DEBUG[17297] devicestate.c: Changing state for IAX2/5028863 - state 1 (Not in use) [Apr 6 21:55:35] VERBOSE[17305] logger.c: -- Registered IAX2 '5028863' (AUTHENTICATED) at 83.22.146.227:1427 [Apr 6 21:55:35] DEBUG[17305] devicestate.c: Notification of state change to be queued on device/channel IAX2/5028863 [Apr 6 21:55:35] NOTICE[17305] chan_iax2.c: Restricting registration for peer '5028863' to 240 seconds (requested 300) [Apr 6 21:55:35] DEBUG[17297] devicestate.c: No provider found, checking channel drivers for IAX2 - 5028863 [Apr 6 21:55:35] DEBUG[17297] chan_iax2.c: Checking device state for device 5028863 [Apr 6 21:55:35] DEBUG[17297] chan_iax2.c: iax2_devicestate: Found peer. What's device state of 5028863? addr=-476965293, defaddr=0 maxms=0, lastms=0 [Apr 6 21:55:35] DEBUG[17297] devicestate.c: Changing state for IAX2/5028863 - state 1 (Not in use) [Apr 6 21:55:39] DEBUG[17302] chan_iax2.c: Expiring registration for peer '6746467' [Apr 6 21:55:39] DEBUG[17302] db.c: Unable to find key '6746467' in family 'IAX/Registry' [Apr 6 21:55:39] DEBUG[17302] devicestate.c: Notification of state change to be queued on device/channel IAX2/6746467 [Apr 6 21:55:39] DEBUG[17297] devicestate.c: No provider found, checking channel drivers for IAX2 - 6746467 [Apr 6 21:55:39] DEBUG[17297] chan_iax2.c: Checking device state for device 6746467 [Apr 6 21:55:39] DEBUG[17297] res_config_mysql.c: MySQL RealTime: Everything is fine. [Apr 6 21:55:39] DEBUG[17297] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM iax WHERE name = '6746467' [Apr 6 21:55:39] DEBUG[17297] db.c: Unable to find key '6746467' in family 'IAX/Registry' [Apr 6 21:55:39] DEBUG[17297] res_config_mysql.c: MySQL RealTime: Everything is fine. [Apr 6 21:55:39] DEBUG[17297] res_config_mysql.c: MySQL RealTime: Update SQL: UPDATE iax SET ipaddr = '0.0.0.0', port = '0', regseconds = '0' WHERE name = ' 6746467' [Apr 6 21:55:39] DEBUG[17297] res_config_mysql.c: MySQL RealTime: Updated 0 rows on table: iax [Apr 6 21:55:39] DEBUG[17297] chan_iax2.c: realtime_peer: Bah, '6746467' is expired (1175889339/0/1175889339)! [Apr 6 21:55:39] DEBUG[17297] chan_iax2.c: iax2_devicestate: Found peer. What's device state of 6746467? addr=0, defaddr=0 maxms=0, lastms=0 [Apr 6 21:55:39] DEBUG[17297] devicestate.c: Changing state for IAX2/6746467 - state 5 (Unavailable) [Apr 6 21:55:42] DEBUG[17304] chan_iax2.c: Expiring registration for peer '4169730' [Apr 6 21:55:42] DEBUG[17304] db.c: Unable to find key '4169730' in family 'IAX/Registry' [Apr 6 21:55:42] DEBUG[17304] devicestate.c: Notification of state change to be queued on device/channel IAX2/4169730 [Apr 6 21:55:42] DEBUG[17297] devicestate.c: No provider found, checking channel drivers for IAX2 - 4169730 [Apr 6 21:55:42] DEBUG[17297] chan_iax2.c: Checking device state for device 4169730 [Apr 6 21:55:42] DEBUG[17297] res_config_mysql.c: MySQL RealTime: Everything is fine. [Apr 6 21:55:42] DEBUG[17297] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM iax WHERE name = '4169730'