[Dec 6 22:45:32] Asterisk 11.14.0, Copyright (C) 1999 - 2013 Digium, Inc. and others. [Dec 6 22:45:32] Created by Mark Spencer [Dec 6 22:45:32] Asterisk comes with ABSOLUTELY NO WARRANTY; type 'core show warranty' for details. [Dec 6 22:45:32] This is free software, with components licensed under the GNU General Public [Dec 6 22:45:32] License version 2 and other licenses; you are welcome to redistribute it under [Dec 6 22:45:32] certain conditions. Type 'core show license' for details. [Dec 6 22:45:32] ========================================================================= [Dec 6 22:45:32] Running as user 'asterisk' [Dec 6 22:45:32] Running under group 'tk' [Dec 6 22:45:32] Connected to Asterisk 11.14.0 currently running on miranda (pid = 28873) miranda*CLI> core set verbose 9 Console verbose was 2 and is now 9. miranda*CLI> core set debug 9 Core debug was OFF and is now 9. [Dec 6 22:45:41] DEBUG[28692]: res_timing_timerfd.c:225 timerfd_timer_ack: Expected to acknowledge 1 ticks but got 4 instead [Dec 6 22:45:41] DEBUG[28692]: res_timing_timerfd.c:225 timerfd_timer_ack: Expected to acknowledge 1 ticks but got 2 instead [Dec 6 22:45:44] DEBUG[28895]: acl.c:979 ast_ouraddrfor: For destination '212.227.18.140', our source address is '144.76.51.196'. [Dec 6 22:45:45] DEBUG[28895]: acl.c:979 ast_ouraddrfor: For destination '212.227.18.200', our source address is '144.76.51.196'. [Dec 6 22:45:45] DEBUG[28880]: manager.c:1476 unref_mansession: Mansession: 0xb6c300f4 refcount now 2 [Dec 6 22:45:45] DEBUG[28692]: res_timing_timerfd.c:225 timerfd_timer_ack: Expected to acknowledge 1 ticks but got 2 instead [Dec 6 22:45:46] DEBUG[28687]: chan_iax2.c:2588 peercnt_remove: ip callno count decremented to 2 for 91.57.200.69 [Dec 6 22:45:46] DEBUG[28692]: res_timing_timerfd.c:225 timerfd_timer_ack: Expected to acknowledge 1 ticks but got 2 instead [Dec 6 22:45:50] DEBUG[28880]: manager.c:1476 unref_mansession: Mansession: 0xb6c300f4 refcount now 2 [Dec 6 22:45:51] DEBUG[28895]: acl.c:979 ast_ouraddrfor: For destination '85.183.95.26', our source address is '144.76.51.196'. [Dec 6 22:45:51] DEBUG[28692]: res_timing_timerfd.c:225 timerfd_timer_ack: Expected to acknowledge 1 ticks but got 3 instead [Dec 6 22:45:53] DEBUG[28692]: res_timing_timerfd.c:225 timerfd_timer_ack: Expected to acknowledge 1 ticks but got 8 instead [Dec 6 22:45:53] -- Remote UNIX connection [Dec 6 22:45:53] Reloading SIP [Dec 6 22:45:53] DEBUG[10058]: db.c:376 db_get_common: Unable to find key '(bogus_peer)' in family 'SIP/Registry' [Dec 6 22:45:53] DEBUG[28895]: config.c:1808 config_text_file_load: Parsing /etc/asterisk/sip.conf [Dec 6 22:45:53] == Parsing '/etc/asterisk/sip.conf': Found [Dec 6 22:45:53] -- Remote UNIX connection disconnected [Dec 6 22:45:53] DEBUG[28895]: config.c:1808 config_text_file_load: Parsing /etc/asterisk/local/sip.conf [Dec 6 22:45:53] == Parsing '/etc/asterisk/local/sip.conf': Found [Dec 6 22:45:53] DEBUG[28895]: config.c:1808 config_text_file_load: Parsing /etc/asterisk/local/sip.1+1 [Dec 6 22:45:53] == Parsing '/etc/asterisk/local/sip.1+1': Found [Dec 6 22:45:53] DEBUG[28895]: config.c:1808 config_text_file_load: Parsing /etc/asterisk/local/users.sip [Dec 6 22:45:53] == Parsing '/etc/asterisk/local/users.sip': Found [Dec 6 22:45:53] DEBUG[28895]: config.c:1808 config_text_file_load: Parsing /etc/asterisk/local/sip.robert [Dec 6 22:45:53] == Parsing '/etc/asterisk/local/sip.robert': Found [Dec 6 22:45:53] DEBUG[28895]: dnsmgr.c:135 ast_dnsmgr_release: removing dns manager for 'sipgate.de' [Dec 6 22:45:53] DEBUG[28895]: dnsmgr.c:135 ast_dnsmgr_release: removing dns manager for 'sipgate.de' [Dec 6 22:45:53] DEBUG[28895]: dnsmgr.c:135 ast_dnsmgr_release: removing dns manager for 'sip.viptel.dk' [Dec 6 22:45:53] DEBUG[28895]: dnsmgr.c:135 ast_dnsmgr_release: removing dns manager for 'sip.1und1.de.' [Dec 6 22:45:53] DEBUG[28895]: dnsmgr.c:135 ast_dnsmgr_release: removing dns manager for 'sipgate.de' [Dec 6 22:45:53] DEBUG[28895]: dnsmgr.c:135 ast_dnsmgr_release: removing dns manager for 'sipgate.de' [Dec 6 22:45:53] DEBUG[28895]: netsock2.c:138 ast_sockaddr_split_hostport: Splitting '0.0.0.0:0' into... [Dec 6 22:45:53] DEBUG[28895]: netsock2.c:192 ast_sockaddr_split_hostport: ...host '0.0.0.0' and port '0'. [Dec 6 22:45:53] DEBUG[28895]: netsock2.c:138 ast_sockaddr_split_hostport: Splitting '0.0.0.0' into... [Dec 6 22:45:53] DEBUG[28895]: netsock2.c:192 ast_sockaddr_split_hostport: ...host '0.0.0.0' and port ''. [Dec 6 22:45:53] DEBUG[28895]: config.c:3152 ast_parse_arg: extract addr from 0.0.0.0 gives 0.0.0.0:0(0) [Dec 6 22:45:53] DEBUG[28895]: netsock2.c:138 ast_sockaddr_split_hostport: Splitting '0.0.0.0' into... [Dec 6 22:45:53] DEBUG[28895]: netsock2.c:192 ast_sockaddr_split_hostport: ...host '0.0.0.0' and port ''. [Dec 6 22:45:53] DEBUG[28895]: config.c:3152 ast_parse_arg: extract addr from 0.0.0.0 gives 0.0.0.0:0(0) [Dec 6 22:45:53] DEBUG[28895]: config.c:3049 ast_parse_arg: extract int from [16] in [1, 2147483647] gives [16](0) [Dec 6 22:45:53] DEBUG[28895]: config.c:3049 ast_parse_arg: extract int from [16] in [1, 2147483647] gives [16](0) [Dec 6 22:45:53] DEBUG[28895]: frame.c:810 ast_parse_allow_disallow: Packetization for codec: g722 is 10 [Dec 6 22:45:53] DEBUG[28895]: frame.c:810 ast_parse_allow_disallow: Packetization for codec: alaw is 10 [Dec 6 22:45:53] DEBUG[28895]: frame.c:810 ast_parse_allow_disallow: Packetization for codec: ulaw is 10 [Dec 6 22:45:53] DEBUG[28895]: frame.c:810 ast_parse_allow_disallow: Packetization for codec: gsm is 20 [Dec 6 22:45:53] DEBUG[28895]: frame.c:810 ast_parse_allow_disallow: Packetization for codec: g726 is 20 [Dec 6 22:45:53] DEBUG[28895]: netsock2.c:138 ast_sockaddr_split_hostport: Splitting 'miranda' into... [Dec 6 22:45:53] DEBUG[28895]: netsock2.c:192 ast_sockaddr_split_hostport: ...host 'miranda' and port ''. [Dec 6 22:45:53] > Using SIP TOS bits 96 [Dec 6 22:45:53] > Using SIP CoS mark 3 [Dec 6 22:45:53] DEBUG[28895]: tcptls.c:973 ast_tcptls_server_start: Nothing changed in SIP TCP server [Dec 6 22:45:53] DEBUG[28895]: dnsmgr.c:201 dnsmgr_refresh: refreshing 'sipgate.de' [Dec 6 22:45:53] -- Remote UNIX connection [Dec 6 22:45:53] DEBUG[10060]: config.c:1808 config_text_file_load: Parsing /etc/asterisk/iax.conf [Dec 6 22:45:53] == Parsing '/etc/asterisk/iax.conf': Found [Dec 6 22:45:53] DEBUG[10060]: config.c:1808 config_text_file_load: Parsing /etc/asterisk/local/users.iax [Dec 6 22:45:53] == Parsing '/etc/asterisk/local/users.iax': Found [Dec 6 22:45:53] DEBUG[10060]: frame.c:810 ast_parse_allow_disallow: Packetization for codec: g722 is 10 [Dec 6 22:45:53] DEBUG[10060]: frame.c:810 ast_parse_allow_disallow: Packetization for codec: alaw is 10 [Dec 6 22:45:53] DEBUG[28895]: srv.c:294 ast_get_srv: ast_get_srv: SRV lookup for '_sip._udp.sipgate.de' mapped to host sipgate.de, port 5060 [Dec 6 22:45:53] DEBUG[28895]: netsock2.c:138 ast_sockaddr_split_hostport: Splitting 'sipgate.de' into... [Dec 6 22:45:53] DEBUG[28895]: netsock2.c:192 ast_sockaddr_split_hostport: ...host 'sipgate.de' and port ''. [Dec 6 22:45:53] DEBUG[10060]: frame.c:810 ast_parse_allow_disallow: Packetization for codec: ulaw is 10 [Dec 6 22:45:53] DEBUG[10060]: frame.c:810 ast_parse_allow_disallow: Packetization for codec: gsm is 20 [Dec 6 22:45:53] DEBUG[10060]: frame.c:810 ast_parse_allow_disallow: Packetization for codec: g726 is 20 [Dec 6 22:45:53] DEBUG[10060]: netsock2.c:138 ast_sockaddr_split_hostport: Splitting '0' into... [Dec 6 22:45:53] DEBUG[10060]: netsock2.c:192 ast_sockaddr_split_hostport: ...host '0' and port ''. [Dec 6 22:45:53] DEBUG[10060]: acl.c:720 ast_append_ha: 0.0.0.0:0/0.0.0.0:0 sense 1 appended to ACL [Dec 6 22:45:53] DEBUG[10060]: db.c:376 db_get_common: Unable to find key 'test1' in family 'IAX/Registry' [Dec 6 22:45:53] DEBUG[10060]: db.c:376 db_get_common: Unable to find key 'test2' in family 'IAX/Registry' [Dec 6 22:45:53] DEBUG[10060]: db.c:376 db_get_common: Unable to find key 'test3' in family 'IAX/Registry' [Dec 6 22:45:53] DEBUG[10060]: db.c:376 db_get_common: Unable to find key 'test4' in family 'IAX/Registry' [Dec 6 22:45:53] DEBUG[10060]: db.c:376 db_get_common: Unable to find key 'test5' in family 'IAX/Registry' [Dec 6 22:45:53] DEBUG[10060]: db.c:376 db_get_common: Unable to find key 'test6' in family 'IAX/Registry' [Dec 6 22:45:53] DEBUG[10060]: chan_iax2.c:2509 peercnt_modify: peercnt entry 87.139.190.172 modified limit:256 registered:0[Dec 6 22:45:53] DEBUG[10060]: netsock2.c:138 ast_sockaddr_split_hostport: Splitting '87.139.190.172:4569' into... [Dec 6 22:45:53] DEBUG[10060]: netsock2.c:192 ast_sockaddr_split_hostport: ...host '87.139.190.172' and port '4569'. [Dec 6 22:45:53] -- Seeding 's-mn24' at 87.139.190.172:4569 for 60 [Dec 6 22:45:53] DEBUG[10060]: chan_iax2.c:2556 peercnt_add: ip callno count incremented to 3 for 87.139.190.172 [Dec 6 22:45:53] DEBUG[28881]: devicestate.c:344 _ast_device_state: No provider found, checking channel drivers for IAX2 - s-mn24 [Dec 6 22:45:53] DEBUG[28881]: chan_iax2.c:14465 iax2_devicestate: Checking device state for device s-mn24 [Dec 6 22:45:53] DEBUG[28881]: devicestate.c:467 do_state_change: Changing state for IAX2/s-mn24 - state 4 (Invalid) [Dec 6 22:45:53] DEBUG[28881]: devicestate.c:442 devstate_event: device 'IAX2/s-mn24' state '4' [Dec 6 22:45:53] DEBUG[28883]: devicestate.c:344 _ast_device_state: No provider found, checking channel drivers for iax2 - s-mn24 [Dec 6 22:45:53] DEBUG[28883]: chan_iax2.c:14465 iax2_devicestate: Checking device state for device s-mn24 [Dec 6 22:45:53] DEBUG[28912]: app_queue.c:1860 handle_statechange: Device 'IAX2/s-mn24' changed to state '4' (Invalid) but we don't care because they're not a member of any queue. [Dec 6 22:45:53] DEBUG[28883]: manager.c:1476 unref_mansession: Mansession: 0xb6c300f4 refcount now 2 [Dec 6 22:45:53] DEBUG[28883]: app_queue.c:1962 extension_state_cb: Extension '::iax2:s-mn24@hints' changed to state '5' (Unavailable) but we don't care because they're not a member of any queue. [Dec 6 22:45:53] DEBUG[28883]: devicestate.c:344 _ast_device_state: No provider found, checking channel drivers for iax2 - s-mn24 [Dec 6 22:45:53] DEBUG[28883]: chan_iax2.c:14465 iax2_devicestate: Checking device state for device s-mn24 [Dec 6 22:45:53] DEBUG[10060]: db.c:376 db_get_common: Unable to find key 's-wuc' in family 'IAX/Registry' [Dec 6 22:45:53] DEBUG[28883]: chan_iax2.c:14473 iax2_devicestate: Found peer. What's device state of s-mn24? addr=1468776108, defaddr=0 maxms=2000, lastms=50 [Dec 6 22:45:53] DEBUG[10060]: chan_iax2.c:2509 peercnt_modify: peercnt entry 83.135.57.160 modified limit:256 registered:0[Dec 6 22:45:53] DEBUG[32276]: manager.c:4846 match_filter: Examining event: Event: ExtensionStatus Privilege: call,all Timestamp: 1417902353.146913 SequenceNumber: 75 File: manager.c Line: 5869 Func: manager_state_cb Exten: ::iax2:s-mn24 Context: hints Hint: iax2/s-mn24 Status: 4 [Dec 6 22:45:53] DEBUG[10060]: netsock2.c:138 ast_sockaddr_split_hostport: Splitting '83.135.57.160:4569' into... [Dec 6 22:45:53] DEBUG[10060]: netsock2.c:192 ast_sockaddr_split_hostport: ...host '83.135.57.160' and port '4569'. [Dec 6 22:45:53] -- Seeding 'hbc-hah' at 83.135.57.160:4569 for 60 [Dec 6 22:45:53] DEBUG[10060]: chan_iax2.c:2556 peercnt_add: ip callno count incremented to 3 for 83.135.57.160 [Dec 6 22:45:53] DEBUG[28881]: devicestate.c:344 _ast_device_state: No provider found, checking channel drivers for IAX2 - hbc-hah [Dec 6 22:45:53] DEBUG[28881]: chan_iax2.c:14465 iax2_devicestate: Checking device state for device hbc-hah [Dec 6 22:45:53] DEBUG[28881]: devicestate.c:467 do_state_change: Changing state for IAX2/hbc-hah - state 4 (Invalid) [Dec 6 22:45:53] DEBUG[28881]: devicestate.c:442 devstate_event: device 'IAX2/hbc-hah' state '4' [Dec 6 22:45:53] DEBUG[28912]: app_queue.c:1860 handle_statechange: Device 'IAX2/hbc-hah' changed to state '4' (Invalid) but we don't care because they're not a member of any queue. [Dec 6 22:45:53] DEBUG[28883]: devicestate.c:344 _ast_device_state: No provider found, checking channel drivers for iax2 - hbc-hah [Dec 6 22:45:53] DEBUG[28883]: chan_iax2.c:14465 iax2_devicestate: Checking device state for device hbc-hah [Dec 6 22:45:53] DEBUG[28883]: manager.c:1476 unref_mansession: Mansession: 0xb6c300f4 refcount now 2 [Dec 6 22:45:53] DEBUG[28883]: app_queue.c:1962 extension_state_cb: Extension '::iax2:hbc-hah@hints' changed to state '5' (Unavailable) but we don't care because they're not a member of any queue. [Dec 6 22:45:53] DEBUG[28883]: devicestate.c:344 _ast_device_state: No provider found, checking channel drivers for iax2 - hbc-hah [Dec 6 22:45:53] DEBUG[28883]: chan_iax2.c:14465 iax2_devicestate: Checking device state for device hbc-hah [Dec 6 22:45:53] DEBUG[28883]: manager.c:1476 unref_mansession: Mansession: 0xb6c300f4 refcount now 2 [Dec 6 22:45:53] DEBUG[28883]: app_queue.c:1962 extension_state_cb: Extension '+4946124499@hints' changed to state '5' (Unavailable) but we don't care because they're not a member of any queue. [Dec 6 22:45:53] DEBUG[28883]: devicestate.c:344 _ast_device_state: No provider found, checking channel drivers for iax2 - hbc-hah [Dec 6 22:45:53] DEBUG[28883]: chan_iax2.c:14465 iax2_devicestate: Checking device state for device hbc-hah [Dec 6 22:45:53] DEBUG[10060]: chan_iax2.c:2509 peercnt_modify: peercnt entry 87.122.52.17 modified limit:256 registered:0[Dec 6 22:45:53] DEBUG[28883]: chan_iax2.c:14473 iax2_devicestate: Found peer. What's device state of hbc-hah? addr=1401371040, defaddr=0 maxms=2000, lastms=25 [Dec 6 22:45:53] DEBUG[10060]: netsock2.c:138 ast_sockaddr_split_hostport: Splitting '87.122.52.17:4569' into... [Dec 6 22:45:53] DEBUG[10060]: netsock2.c:192 ast_sockaddr_split_hostport: ...host '87.122.52.17' and port '4569'. [Dec 6 22:45:53] DEBUG[28883]: devicestate.c:344 _ast_device_state: No provider found, checking channel drivers for iax2 - hbc-hah [Dec 6 22:45:53] DEBUG[28883]: chan_iax2.c:14465 iax2_devicestate: Checking device state for device hbc-hah [Dec 6 22:45:53] DEBUG[28883]: chan_iax2.c:14473 iax2_devicestate: Found peer. What's device state of hbc-hah? addr=1401371040, defaddr=0 maxms=2000, lastms=25 [Dec 6 22:45:53] -- Seeding 'hbc-pgsw' at 87.122.52.17:4569 for 60 [Dec 6 22:45:53] DEBUG[10060]: chan_iax2.c:2556 peercnt_add: ip callno count incremented to 3 for 87.122.52.17 [Dec 6 22:45:53] DEBUG[28883]: devicestate.c:344 _ast_device_state: No provider found, checking channel drivers for iax2 - hbc-hah [Dec 6 22:45:53] DEBUG[28883]: chan_iax2.c:14465 iax2_devicestate: Checking device state for device hbc-hah [Dec 6 22:45:53] DEBUG[28883]: chan_iax2.c:14473 iax2_devicestate: Found peer. What's device state of hbc-hah? addr=1401371040, defaddr=0 maxms=2000, lastms=25 [Dec 6 22:45:53] DEBUG[28883]: devicestate.c:344 _ast_device_state: No provider found, checking channel drivers for iax2 - hbc-hah [Dec 6 22:45:53] DEBUG[28883]: chan_iax2.c:14465 iax2_devicestate: Checking device state for device hbc-hah [Dec 6 22:45:53] DEBUG[28883]: chan_iax2.c:14473 iax2_devicestate: Found peer. What's device state of hbc-hah? addr=1401371040, defaddr=0 maxms=2000, lastms=25 [Dec 6 22:45:53] DEBUG[32276]: manager.c:4846 match_filter: Examining event: Event: ExtensionStatus Privilege: call,all Timestamp: 1417902353.147426 SequenceNumber: 76 File: manager.c Line: 5869 Func: manager_state_cb Exten: ::iax2:hbc-hah Context: hints Hint: iax2/hbc-hah Status: 4 [Dec 6 22:45:53] DEBUG[28881]: devicestate.c:344 _ast_device_state: No provider found, checking channel drivers for IAX2 - hbc-pgsw [Dec 6 22:45:53] DEBUG[28881]: chan_iax2.c:14465 iax2_devicestate: Checking device state for device hbc-pgsw [Dec 6 22:45:53] DEBUG[28881]: devicestate.c:467 do_state_change: Changing state for IAX2/hbc-pgsw - state 4 (Invalid) [Dec 6 22:45:53] DEBUG[28881]: devicestate.c:442 devstate_event: device 'IAX2/hbc-pgsw' state '4' [Dec 6 22:45:53] DEBUG[32276]: manager.c:4846 match_filter: Examining event: Event: ExtensionStatus Privilege: call,all Timestamp: 1417902353.147483 SequenceNumber: 77 File: manager.c Line: 5869 Func: manager_state_cb Exten: +4946124499 Context: hints Hint: iax2/hbc-hah Status: 4 [Dec 6 22:45:53] DEBUG[10060]: chan_iax2.c:2509 peercnt_modify: peercnt entry 91.57.200.69 modified limit:256 registered:0[Dec 6 22:45:53] DEBUG[10060]: netsock2.c:138 ast_sockaddr_split_hostport: Splitting '91.57.200.69:4569' into... [Dec 6 22:45:53] DEBUG[10060]: netsock2.c:192 ast_sockaddr_split_hostport: ...host '91.57.200.69' and port '4569'. [Dec 6 22:45:53] DEBUG[28912]: app_queue.c:1860 handle_statechange: Device 'IAX2/hbc-pgsw' changed to state '4' (Invalid) but we don't care because they're not a member of any queue. [Dec 6 22:45:53] DEBUG[28883]: devicestate.c:344 _ast_device_state: No provider found, checking channel drivers for iax2 - hbc-pgsw [Dec 6 22:45:53] DEBUG[28883]: chan_iax2.c:14465 iax2_devicestate: Checking device state for device hbc-pgsw [Dec 6 22:45:53] DEBUG[28883]: chan_iax2.c:14473 iax2_devicestate: Found peer. What's device state of hbc-pgsw? addr=1467626513, defaddr=0 maxms=2000, lastms=55 [Dec 6 22:45:53] -- Seeding 'hbc-hbst' at 91.57.200.69:4569 for 60 [Dec 6 22:45:53] DEBUG[10060]: chan_iax2.c:2556 peercnt_add: ip callno count incremented to 3 for 91.57.200.69 [Dec 6 22:45:53] DEBUG[28883]: devicestate.c:344 _ast_device_state: No provider found, checking channel drivers for iax2 - hbc-pgsw [Dec 6 22:45:53] DEBUG[28883]: chan_iax2.c:14465 iax2_devicestate: Checking device state for device hbc-pgsw [Dec 6 22:45:53] DEBUG[28883]: chan_iax2.c:14473 iax2_devicestate: Found peer. What's device state of hbc-pgsw? addr=1467626513, defaddr=0 maxms=2000, lastms=55 [Dec 6 22:45:53] DEBUG[28883]: devicestate.c:344 _ast_device_state: No provider found, checking channel drivers for iax2 - hbc-pgsw [Dec 6 22:45:53] DEBUG[28883]: chan_iax2.c:14465 iax2_devicestate: Checking device state for device hbc-pgsw [Dec 6 22:45:53] DEBUG[28883]: chan_iax2.c:14473 iax2_devicestate: Found peer. What's device state of hbc-pgsw? addr=1467626513, defaddr=0 maxms=2000, lastms=55 [Dec 6 22:45:53] DEBUG[28881]: devicestate.c:344 _ast_device_state: No provider found, checking channel drivers for IAX2 - hbc-hbst [Dec 6 22:45:53] DEBUG[28883]: devicestate.c:344 _ast_device_state: No provider found, checking channel drivers for iax2 - hbc-pgsw [Dec 6 22:45:53] DEBUG[28883]: chan_iax2.c:14465 iax2_devicestate: Checking device state for device hbc-pgsw [Dec 6 22:45:53] DEBUG[28881]: chan_iax2.c:14465 iax2_devicestate: Checking device state for device hbc-hbst [Dec 6 22:45:53] DEBUG[28883]: chan_iax2.c:14473 iax2_devicestate: Found peer. What's device state of hbc-pgsw? addr=1467626513, defaddr=0 maxms=2000, lastms=55 [Dec 6 22:45:53] DEBUG[28881]: devicestate.c:467 do_state_change: Changing state for IAX2/hbc-hbst - state 4 (Invalid) [Dec 6 22:45:53] DEBUG[28881]: devicestate.c:442 devstate_event: device 'IAX2/hbc-hbst' state '4' [Dec 6 22:45:53] DEBUG[28912]: app_queue.c:1860 handle_statechange: Device 'IAX2/hbc-hbst' changed to state '4' (Invalid) but we don't care because they're not a member of any queue. [Dec 6 22:45:53] DEBUG[28883]: devicestate.c:344 _ast_device_state: No provider found, checking channel drivers for iax2 - hbc-hbst [Dec 6 22:45:53] DEBUG[28883]: chan_iax2.c:14465 iax2_devicestate: Checking device state for device hbc-hbst [Dec 6 22:45:53] DEBUG[28883]: manager.c:1476 unref_mansession: Mansession: 0xb6c300f4 refcount now 2 [Dec 6 22:45:53] DEBUG[28883]: app_queue.c:1962 extension_state_cb: Extension '::iax2:hbc-hbst@hints' changed to state '5' (Unavailable) but we don't care because they're not a member of any queue. [Dec 6 22:45:53] DEBUG[28883]: devicestate.c:344 _ast_device_state: No provider found, checking channel drivers for iax2 - hbc-hbst [Dec 6 22:45:53] DEBUG[28883]: chan_iax2.c:14465 iax2_devicestate: Checking device state for device hbc-hbst [Dec 6 22:45:53] DEBUG[28883]: chan_iax2.c:14473 iax2_devicestate: Found peer. What's device state of hbc-hbst? addr=1530513477, defaddr=0 maxms=2000, lastms=54 [Dec 6 22:45:53] DEBUG[10060]: chan_iax2.c:2509 peercnt_modify: peercnt entry 79.228.39.209 modified limit:256 registered:0[Dec 6 22:45:53] DEBUG[28883]: devicestate.c:344 _ast_device_state: No provider found, checking channel drivers for iax2 - hbc-hbst [Dec 6 22:45:53] DEBUG[28883]: chan_iax2.c:14465 iax2_devicestate: Checking device state for device hbc-hbst [Dec 6 22:45:53] DEBUG[28883]: chan_iax2.c:14473 iax2_devicestate: Found peer. What's device state of hbc-hbst? addr=1530513477, defaddr=0 maxms=2000, lastms=54 [Dec 6 22:45:53] DEBUG[10060]: netsock2.c:138 ast_sockaddr_split_hostport: Splitting '79.228.39.209:4569' into... [Dec 6 22:45:53] DEBUG[10060]: netsock2.c:192 ast_sockaddr_split_hostport: ...host '79.228.39.209' and port '4569'. [Dec 6 22:45:53] DEBUG[28883]: devicestate.c:344 _ast_device_state: No provider found, checking channel drivers for iax2 - hbc-hbst [Dec 6 22:45:53] -- Seeding 'hbc-grau' at 79.228.39.209:4569 for 60 [Dec 6 22:45:53] DEBUG[28883]: chan_iax2.c:14465 iax2_devicestate: Checking device state for device hbc-hbst [Dec 6 22:45:53] DEBUG[28883]: chan_iax2.c:14473 iax2_devicestate: Found peer. What's device state of hbc-hbst? addr=1530513477, defaddr=0 maxms=2000, lastms=54 [Dec 6 22:45:53] DEBUG[10060]: chan_iax2.c:2556 peercnt_add: ip callno count incremented to 3 for 79.228.39.209 [Dec 6 22:45:53] DEBUG[28883]: devicestate.c:344 _ast_device_state: No provider found, checking channel drivers for iax2 - hbc-hbst [Dec 6 22:45:53] DEBUG[28883]: chan_iax2.c:14465 iax2_devicestate: Checking device state for device hbc-hbst [Dec 6 22:45:53] DEBUG[28883]: chan_iax2.c:14473 iax2_devicestate: Found peer. What's device state of hbc-hbst? addr=1530513477, defaddr=0 maxms=2000, lastms=54 [Dec 6 22:45:53] DEBUG[28883]: devicestate.c:344 _ast_device_state: No provider found, checking channel drivers for iax2 - hbc-hbst [Dec 6 22:45:53] DEBUG[28883]: chan_iax2.c:14465 iax2_devicestate: Checking device state for device hbc-hbst [Dec 6 22:45:53] DEBUG[28883]: chan_iax2.c:14473 iax2_devicestate: Found peer. What's device state of hbc-hbst? addr=1530513477, defaddr=0 maxms=2000, lastms=54 [Dec 6 22:45:53] DEBUG[28881]: devicestate.c:344 _ast_device_state: No provider found, checking channel drivers for IAX2 - hbc-grau [Dec 6 22:45:53] DEBUG[28883]: devicestate.c:344 _ast_device_state: No provider found, checking channel drivers for iax2 - hbc-hbst [Dec 6 22:45:53] DEBUG[28881]: chan_iax2.c:14465 iax2_devicestate: Checking device state for device hbc-grau [Dec 6 22:45:53] DEBUG[28883]: chan_iax2.c:14465 iax2_devicestate: Checking device state for device hbc-hbst [Dec 6 22:45:53] DEBUG[28881]: devicestate.c:467 do_state_change: Changing state for IAX2/hbc-grau - state 4 (Invalid) [Dec 6 22:45:53] DEBUG[28883]: chan_iax2.c:14473 iax2_devicestate: Found peer. What's device state of hbc-hbst? addr=1530513477, defaddr=0 maxms=2000, lastms=54 [Dec 6 22:45:53] DEBUG[28881]: devicestate.c:442 devstate_event: device 'IAX2/hbc-grau' state '4' [Dec 6 22:45:53] DEBUG[32276]: manager.c:4846 match_filter: Examining event: Event: ExtensionStatus Privilege: call,all Timestamp: 1417902353.148479 SequenceNumber: 78 File: manager.c Line: 5869 Func: manager_state_cb Exten: ::iax2:hbc-hbst Context: hints Hint: iax2/hbc-hbst Status: 4 [Dec 6 22:45:53] DEBUG[28912]: app_queue.c:1860 handle_statechange: Device 'IAX2/hbc-grau' changed to state '4' (Invalid) but we don't care because they're not a member of any queue. [Dec 6 22:45:53] DEBUG[28883]: devicestate.c:344 _ast_device_state: No provider found, checking channel drivers for iax2 - hbc-grau [Dec 6 22:45:53] DEBUG[28883]: chan_iax2.c:14465 iax2_devicestate: Checking device state for device hbc-grau [Dec 6 22:45:53] DEBUG[28883]: manager.c:1476 unref_mansession: Mansession: 0xb6c300f4 refcount now 2 [Dec 6 22:45:53] DEBUG[28883]: app_queue.c:1962 extension_state_cb: Extension '::iax2:hbc-grau@hints' changed to state '5' (Unavailable) but we don't care because they're not a member of any queue. [Dec 6 22:45:53] DEBUG[28883]: devicestate.c:344 _ast_device_state: No provider found, checking channel drivers for iax2 - hbc-grau [Dec 6 22:45:53] DEBUG[28883]: chan_iax2.c:14465 iax2_devicestate: Checking device state for device hbc-grau [Dec 6 22:45:53] DEBUG[32276]: manager.c:4846 match_filter: Examining event: Event: ExtensionStatus Privilege: call,all Timestamp: 1417902353.149131 SequenceNumber: 79 File: manager.c Line: 5869 Func: manager_state_cb Exten: ::iax2:hbc-grau Context: hints Hint: iax2/hbc-grau Status: 4 [Dec 6 22:45:53] DEBUG[32276]: manager.c:4846 match_filter: Examining event: Event: ExtensionStatus Privilege: call,all Timestamp: 1417902353.149211 SequenceNumber: 80 File: manager.c Line: 5869 Func: manager_state_cb Exten: +494022756090 Context: hints Hint: iax2/hbc-grau Status: 4 [Dec 6 22:45:53] DEBUG[10060]: db.c:376 db_get_common: Unable to find key 'hbc-mmil' in family 'IAX/Registry' [Dec 6 22:45:53] DEBUG[10060]: netsock2.c:138 ast_sockaddr_split_hostport: Splitting '10.9.42.1' into... [Dec 6 22:45:53] DEBUG[10060]: netsock2.c:192 ast_sockaddr_split_hostport: ...host '10.9.42.1' and port ''. [Dec 6 22:45:53] DEBUG[28895]: dnsmgr.c:201 dnsmgr_refresh: refreshing 'sipgate.de' [Dec 6 22:45:53] DEBUG[10060]: chan_iax2.c:2509 peercnt_modify: peercnt entry 91.35.57.63 modified limit:256 registered:0[Dec 6 22:45:53] DEBUG[10060]: netsock2.c:138 ast_sockaddr_split_hostport: Splitting '91.35.57.63:4569' into... [Dec 6 22:45:53] DEBUG[10060]: netsock2.c:192 ast_sockaddr_split_hostport: ...host '91.35.57.63' and port '4569'. [Dec 6 22:45:53] -- Seeding 'yetim-lmaa' at 91.35.57.63:4569 for 60 [Dec 6 22:45:53] DEBUG[28883]: manager.c:1476 unref_mansession: Mansession: 0xb6c300f4 refcount now 2 [Dec 6 22:45:53] DEBUG[28883]: app_queue.c:1962 extension_state_cb: Extension '+494022756090@hints' changed to state '5' (Unavailable) but we don't care because they're not a member of any queue. [Dec 6 22:45:53] DEBUG[28883]: devicestate.c:344 _ast_device_state: No provider found, checking channel drivers for iax2 - hbc-grau [Dec 6 22:45:53] DEBUG[28883]: chan_iax2.c:14465 iax2_devicestate: Checking device state for device hbc-grau [Dec 6 22:45:53] DEBUG[10060]: chan_iax2.c:2556 peercnt_add: ip callno count incremented to 3 for 91.35.57.63 [Dec 6 22:45:53] DEBUG[28883]: chan_iax2.c:14473 iax2_devicestate: Found peer. What's device state of hbc-grau? addr=1340352465, defaddr=0 maxms=2000, lastms=76 [Dec 6 22:45:53] DEBUG[28883]: devicestate.c:344 _ast_device_state: No provider found, checking channel drivers for iax2 - hbc-grau [Dec 6 22:45:53] DEBUG[28883]: chan_iax2.c:14465 iax2_devicestate: Checking device state for device hbc-grau [Dec 6 22:45:53] DEBUG[28881]: devicestate.c:344 _ast_device_state: No provider found, checking channel drivers for IAX2 - yetim-lmaa [Dec 6 22:45:53] DEBUG[28883]: chan_iax2.c:14473 iax2_devicestate: Found peer. What's device state of hbc-grau? addr=1340352465, defaddr=0 maxms=2000, lastms=76 [Dec 6 22:45:53] DEBUG[28881]: chan_iax2.c:14465 iax2_devicestate: Checking device state for device yetim-lmaa [Dec 6 22:45:53] DEBUG[28881]: devicestate.c:467 do_state_change: Changing state for IAX2/yetim-lmaa - state 4 (Invalid) [Dec 6 22:45:53] DEBUG[28881]: devicestate.c:442 devstate_event: device 'IAX2/yetim-lmaa' state '4' [Dec 6 22:45:53] DEBUG[28912]: app_queue.c:1860 handle_statechange: Device 'IAX2/yetim-lmaa' changed to state '4' (Invalid) but we don't care because they're not a member of any queue. [Dec 6 22:45:53] DEBUG[28883]: devicestate.c:344 _ast_device_state: No provider found, checking channel drivers for iax2 - yetim-lmaa [Dec 6 22:45:53] DEBUG[28883]: chan_iax2.c:14465 iax2_devicestate: Checking device state for device yetim-lmaa [Dec 6 22:45:53] DEBUG[28883]: manager.c:1476 unref_mansession: Mansession: 0xb6c300f4 refcount now 2 [Dec 6 22:45:53] DEBUG[28883]: app_queue.c:1962 extension_state_cb: Extension '::iax2:yetim-lmaa@hints' changed to state '5' (Unavailable) but we don't care because they're not a member of any queue. [Dec 6 22:45:53] DEBUG[32276]: manager.c:4846 match_filter: Examining event: Event: ExtensionStatus Privilege: call,all Timestamp: 1417902353.153094 SequenceNumber: 81 File: manager.c Line: 5869 Func: manager_state_cb Exten: ::iax2:yetim-lmaa Context: hints Hint: iax2/yetim-lmaa Status: 4 [Dec 6 22:45:53] DEBUG[28895]: srv.c:294 ast_get_srv: ast_get_srv: SRV lookup for '_sip._udp.sipgate.de' mapped to host sipgate.de, port 5060 [Dec 6 22:45:53] DEBUG[28895]: netsock2.c:138 ast_sockaddr_split_hostport: Splitting 'sipgate.de' into... [Dec 6 22:45:53] DEBUG[28895]: netsock2.c:192 ast_sockaddr_split_hostport: ...host 'sipgate.de' and port ''. [Dec 6 22:45:53] DEBUG[10060]: db.c:376 db_get_common: Unable to find key 'flelvis' in family 'IAX/Registry' [Dec 6 22:45:53] DEBUG[10060]: chan_iax2.c:2469 set_peercnt_limit_all_cb: Reset limits for peercnts table [Dec 6 22:45:53] DEBUG[10060]: chan_iax2.c:2469 set_peercnt_limit_all_cb: Reset limits for peercnts table [Dec 6 22:45:53] DEBUG[10060]: chan_iax2.c:2469 set_peercnt_limit_all_cb: Reset limits for peercnts table [Dec 6 22:45:53] DEBUG[10060]: chan_iax2.c:2469 set_peercnt_limit_all_cb: Reset limits for peercnts table [Dec 6 22:45:53] DEBUG[10060]: chan_iax2.c:2469 set_peercnt_limit_all_cb: Reset limits for peercnts table [Dec 6 22:45:53] DEBUG[10060]: chan_iax2.c:2469 set_peercnt_limit_all_cb: Reset limits for peercnts table [Dec 6 22:45:53] DEBUG[10060]: chan_iax2.c:2469 set_peercnt_limit_all_cb: Reset limits for peercnts table [Dec 6 22:45:53] NOTICE[10060]: chan_iax2.c:12444 iax2_poke_peer: Still have a callno... [Dec 6 22:45:53] DEBUG[10060]: chan_iax2.c:2912 sched_delay_remove: schedule decrement of callno used for 83.135.57.160 in 60 seconds [Dec 6 22:45:53] DEBUG[10060]: chan_iax2.c:2556 peercnt_add: ip callno count incremented to 4 for 83.135.57.160 [Dec 6 22:45:53] NOTICE[10060]: chan_iax2.c:12444 iax2_poke_peer: Still have a callno... [Dec 6 22:45:53] DEBUG[10060]: chan_iax2.c:2912 sched_delay_remove: schedule decrement of callno used for 91.57.200.69 in 60 seconds [Dec 6 22:45:53] DEBUG[10060]: chan_iax2.c:2556 peercnt_add: ip callno count incremented to 4 for 91.57.200.69 [Dec 6 22:45:53] NOTICE[10060]: chan_iax2.c:12444 iax2_poke_peer: Still have a callno... [Dec 6 22:45:53] DEBUG[10060]: chan_iax2.c:2912 sched_delay_remove: schedule decrement of callno used for 87.139.190.172 in 60 seconds [Dec 6 22:45:53] DEBUG[10060]: chan_iax2.c:2556 peercnt_add: ip callno count incremented to 4 for 87.139.190.172 [Dec 6 22:45:53] NOTICE[10060]: chan_iax2.c:12444 iax2_poke_peer: Still have a callno... [Dec 6 22:45:53] DEBUG[10060]: chan_iax2.c:2912 sched_delay_remove: schedule decrement of callno used for 91.35.57.63 in 60 seconds [Dec 6 22:45:53] DEBUG[10060]: chan_iax2.c:2556 peercnt_add: ip callno count incremented to 4 for 91.35.57.63 [Dec 6 22:45:53] NOTICE[10060]: chan_iax2.c:12444 iax2_poke_peer: Still have a callno... [Dec 6 22:45:53] DEBUG[10060]: chan_iax2.c:2912 sched_delay_remove: schedule decrement of callno used for 79.228.39.209 in 60 seconds [Dec 6 22:45:53] DEBUG[10060]: chan_iax2.c:2556 peercnt_add: ip callno count incremented to 4 for 79.228.39.209 [Dec 6 22:45:53] DEBUG[10060]: chan_iax2.c:2556 peercnt_add: ip callno count incremented to 2 for 10.9.42.1 [Dec 6 22:45:53] NOTICE[10060]: chan_iax2.c:12444 iax2_poke_peer: Still have a callno... [Dec 6 22:45:53] DEBUG[10060]: chan_iax2.c:2912 sched_delay_remove: schedule decrement of callno used for 87.122.52.17 in 60 seconds [Dec 6 22:45:53] DEBUG[10060]: chan_iax2.c:2556 peercnt_add: ip callno count incremented to 4 for 87.122.52.17 [Dec 6 22:45:53] NOTICE[10060]: iax2-provision.c:558 iax_provision_reload: No IAX provisioning configuration found, IAX provisioning disabled. [Dec 6 22:45:53] -- Remote UNIX connection disconnected [Dec 6 22:45:53] DEBUG[28895]: dnsmgr.c:201 dnsmgr_refresh: refreshing 'sipgate.de' [Dec 6 22:45:53] DEBUG[28895]: srv.c:294 ast_get_srv: ast_get_srv: SRV lookup for '_sip._udp.sipgate.de' mapped to host sipgate.de, port 5060 [Dec 6 22:45:53] DEBUG[28895]: netsock2.c:138 ast_sockaddr_split_hostport: Splitting 'sipgate.de' into... [Dec 6 22:45:53] DEBUG[28895]: netsock2.c:192 ast_sockaddr_split_hostport: ...host 'sipgate.de' and port ''. [Dec 6 22:45:53] -- Remote UNIX connection [Dec 6 22:45:53] DEBUG[10062]: config.c:1808 config_text_file_load: Parsing /etc/asterisk/extensions.conf [Dec 6 22:45:53] == Parsing '/etc/asterisk/extensions.conf': Found [Dec 6 22:45:53] DEBUG[10062]: config.c:1808 config_text_file_load: Parsing /etc/asterisk/local/exten-fc-cf [Dec 6 22:45:53] == Parsing '/etc/asterisk/local/exten-fc-cf': Found [Dec 6 22:45:53] DEBUG[10062]: config.c:1808 config_text_file_load: Parsing /etc/asterisk/local/exten-udial [Dec 6 22:45:53] == Parsing '/etc/asterisk/local/exten-udial': Found [Dec 6 22:45:53] DEBUG[10062]: config.c:1808 config_text_file_load: Parsing /etc/asterisk/local/exten-ssmmc [Dec 6 22:45:53] == Parsing '/etc/asterisk/local/exten-ssmmc': Found [Dec 6 22:45:53] DEBUG[10062]: config.c:1808 config_text_file_load: Parsing /etc/asterisk/local/users.extensions [Dec 6 22:45:53] == Parsing '/etc/asterisk/local/users.extensions': Found [Dec 6 22:45:53] DEBUG[10062]: config.c:1808 config_text_file_load: Parsing /etc/asterisk/local/extensions.hartbeat [Dec 6 22:45:53] == Parsing '/etc/asterisk/local/extensions.hartbeat': Found [Dec 6 22:45:53] DEBUG[10062]: config.c:1808 config_text_file_load: Parsing /etc/asterisk/local/extensions.robert [Dec 6 22:45:53] == Parsing '/etc/asterisk/local/extensions.robert': Found [Dec 6 22:45:53] DEBUG[28895]: dnsmgr.c:201 dnsmgr_refresh: refreshing 'sip.1und1.de.' [Dec 6 22:45:53] DEBUG[10062]: config.c:1808 config_text_file_load: Parsing /etc/asterisk/local/users.ext-hints [Dec 6 22:45:53] == Parsing '/etc/asterisk/local/users.ext-hints': Found [Dec 6 22:45:53] == Setting global variable 'x_alarm' to '1194' [Dec 6 22:45:53] DEBUG[32276]: manager.c:4846 match_filter: Examining event: Event: VarSet Privilege: dialplan,all Timestamp: 1417902353.164150 SequenceNumber: 82 File: pbx.c Line: 11681 Func: pbx_builtin_setvar_helper Channel: none Variable: x_alarm Value: 1194 Uniqueid: none [Dec 6 22:45:53] DEBUG[10062]: manager.c:1476 unref_mansession: Mansession: 0xb6c300f4 refcount now 2 [Dec 6 22:45:53] == Setting global variable 'vminext' to '1150' [Dec 6 22:45:53] DEBUG[32276]: manager.c:4846 match_filter: Examining event: Event: VarSet Privilege: dialplan,all Timestamp: 1417902353.164544 SequenceNumber: 83 File: pbx.c Line: 11681 Func: pbx_builtin_setvar_helper Channel: none Variable: vminext Value: 1150 Uniqueid: none [Dec 6 22:45:53] DEBUG[10062]: manager.c:1476 unref_mansession: Mansession: 0xb6c300f4 refcount now 2 [Dec 6 22:45:53] == Setting global variable 'L_CC' to '49' [Dec 6 22:45:53] DEBUG[32276]: manager.c:4846 match_filter: Examining event: Event: VarSet Privilege: dialplan,all Timestamp: 1417902353.164891 SequenceNumber: 84 File: pbx.c Line: 11681 Func: pbx_builtin_setvar_helper Channel: none Variable: L_CC Value: 49 Uniqueid: none [Dec 6 22:45:53] DEBUG[10062]: manager.c:1476 unref_mansession: Mansession: 0xb6c300f4 refcount now 2 [Dec 6 22:45:53] == Setting global variable 'L_AC' to '461' [Dec 6 22:45:53] DEBUG[32276]: manager.c:4846 match_filter: Examining event: Event: VarSet Privilege: dialplan,all Timestamp: 1417902353.165344 SequenceNumber: 85 File: pbx.c Line: 11681 Func: pbx_builtin_setvar_helper Channel: none Variable: L_AC Value: 461 Uniqueid: none [Dec 6 22:45:53] DEBUG[10062]: manager.c:1476 unref_mansession: Mansession: 0xb6c300f4 refcount now 2 [Dec 6 22:45:53] == Setting global variable 'dto' to '96' [Dec 6 22:45:53] DEBUG[32276]: manager.c:4846 match_filter: Examining event: Event: VarSet Privilege: dialplan,all Timestamp: 1417902353.165618 SequenceNumber: 86 File: pbx.c Line: 11681 Func: pbx_builtin_setvar_helper Channel: none Variable: dto Value: 96 Uniqueid: none [Dec 6 22:45:53] DEBUG[10062]: manager.c:1476 unref_mansession: Mansession: 0xb6c300f4 refcount now 2 [Dec 6 22:45:53] == Setting global variable 'matchall' to '_[a-zA-Z0-9*#+].' [Dec 6 22:45:53] DEBUG[32276]: manager.c:4846 match_filter: Examining event: Event: VarSet Privilege: dialplan,all Timestamp: 1417902353.167005 SequenceNumber: 87 File: pbx.c Line: 11681 Func: pbx_builtin_setvar_helper Channel: none Variable: matchall Value: _[a-zA-Z0-9*#+]. Uniqueid: none [Dec 6 22:45:53] DEBUG[10062]: manager.c:1476 unref_mansession: Mansession: 0xb6c300f4 refcount now 2 [Dec 6 22:45:53] == Setting global variable 'message_CFU' to 'unconditional' [Dec 6 22:45:53] DEBUG[32276]: manager.c:4846 match_filter: Examining event: Event: VarSet Privilege: dialplan,all Timestamp: 1417902353.167453 SequenceNumber: 88 File: pbx.c Line: 11681 Func: pbx_builtin_setvar_helper Channel: none Variable: message_CFU Value: unconditional Uniqueid: none [Dec 6 22:45:53] DEBUG[10062]: manager.c:1476 unref_mansession: Mansession: 0xb6c300f4 refcount now 2 [Dec 6 22:45:53] == Setting global variable 'message_CFB' to 'on-busy' [Dec 6 22:45:53] DEBUG[32276]: manager.c:4846 match_filter: Examining event: Event: VarSet Privilege: dialplan,all Timestamp: 1417902353.167734 SequenceNumber: 89 File: pbx.c Line: 11681 Func: pbx_builtin_setvar_helper Channel: none Variable: message_CFB Value: on-busy Uniqueid: none [Dec 6 22:45:53] DEBUG[10062]: manager.c:1476 unref_mansession: Mansession: 0xb6c300f4 refcount now 2 [Dec 6 22:45:53] == Setting global variable 'message_CFALD' to 'unavailable' [Dec 6 22:45:53] DEBUG[32276]: manager.c:4846 match_filter: Examining event: Event: VarSet Privilege: dialplan,all Timestamp: 1417902353.168126 SequenceNumber: 90 File: pbx.c Line: 11681 Func: pbx_builtin_setvar_helper Channel: none Variable: message_CFALD Value: unavailable Uniqueid: none [Dec 6 22:45:53] DEBUG[10062]: manager.c:1476 unref_mansession: Mansession: 0xb6c300f4 refcount now 2 [Dec 6 22:45:53] == Setting global variable 'message_CFNR' to 'on-no-answer' [Dec 6 22:45:53] DEBUG[32276]: manager.c:4846 match_filter: Examining event: Event: VarSet Privilege: dialplan,all Timestamp: 1417902353.168560 SequenceNumber: 91 File: pbx.c Line: 11681 Func: pbx_builtin_setvar_helper Channel: none Variable: message_CFNR Value: on-no-answer Uniqueid: none [Dec 6 22:45:53] DEBUG[10062]: manager.c:1476 unref_mansession: Mansession: 0xb6c300f4 refcount now 2 [Dec 6 22:45:53] == Setting global variable 'ssmmc_maxconf' to '9' [Dec 6 22:45:53] DEBUG[32276]: manager.c:4846 match_filter: Examining event: Event: VarSet Privilege: dialplan,all Timestamp: 1417902353.168909 SequenceNumber: 92 File: pbx.c Line: 11681 Func: pbx_builtin_setvar_helper Channel: none Variable: ssmmc_maxconf Value: 9 Uniqueid: none [Dec 6 22:45:53] DEBUG[10062]: manager.c:1476 unref_mansession: Mansession: 0xb6c300f4 refcount now 2 [Dec 6 22:45:53] DEBUG[10062]: pbx.c:8767 ast_context_find_or_create: Registered context 'fc-cf'(0xb50af778) in local table 0xb4c97050; registrar: pbx_config [Dec 6 22:45:53] -- Registered extension context 'fc-cf'; registrar: pbx_config [Dec 6 22:45:53] DEBUG[28895]: srv.c:294 ast_get_srv: ast_get_srv: SRV lookup for '_sip._udp.sip.1und1.de.' mapped to host 1und1-2.sip.1und1.de, port 5060 [Dec 6 22:45:53] DEBUG[28895]: netsock2.c:138 ast_sockaddr_split_hostport: Splitting '1und1-2.sip.1und1.de' into... [Dec 6 22:45:53] DEBUG[28895]: netsock2.c:192 ast_sockaddr_split_hostport: ...host '1und1-2.sip.1und1.de' and port ''. [Dec 6 22:45:53] DEBUG[28895]: acl.c:828 resolve_first: Multiple addresses. Using the first only [Dec 6 22:45:53] DEBUG[28895]: dnsmgr.c:201 dnsmgr_refresh: refreshing 'sipbalance1-1.1und1.de.' [Dec 6 22:45:53] DEBUG[28688]: chan_iax2.c:2912 sched_delay_remove: schedule decrement of callno used for 83.135.57.160 in 60 seconds [Dec 6 22:45:53] DEBUG[28688]: chan_iax2.c:11255 socket_process_helper: Peer hbc-hah: got pong, lastms 23, historicms 23, maxms 2000 [Dec 6 22:45:53] DEBUG[28895]: srv.c:294 ast_get_srv: ast_get_srv: SRV lookup for '_sip._udp.sipbalance1-1.1und1.de.' mapped to host sipbalance1-1.1und1.de, port 5060 [Dec 6 22:45:53] DEBUG[28895]: netsock2.c:138 ast_sockaddr_split_hostport: Splitting 'sipbalance1-1.1und1.de' into... [Dec 6 22:45:53] DEBUG[28895]: netsock2.c:192 ast_sockaddr_split_hostport: ...host 'sipbalance1-1.1und1.de' and port ''. [Dec 6 22:45:53] DEBUG[28895]: dnsmgr.c:201 dnsmgr_refresh: refreshing 'sipbalance1-2.1und1.de.' [Dec 6 22:45:53] DEBUG[28895]: srv.c:294 ast_get_srv: ast_get_srv: SRV lookup for '_sip._udp.sipbalance1-2.1und1.de.' mapped to host sipbalance1-2.1und1.de, port 5060 [Dec 6 22:45:53] DEBUG[28895]: netsock2.c:138 ast_sockaddr_split_hostport: Splitting 'sipbalance1-2.1und1.de' into... [Dec 6 22:45:53] DEBUG[28895]: netsock2.c:192 ast_sockaddr_split_hostport: ...host 'sipbalance1-2.1und1.de' and port ''. [Dec 6 22:45:53] DEBUG[28895]: dnsmgr.c:201 dnsmgr_refresh: refreshing 'sipbalance1-3.1und1.de.' [Dec 6 22:45:53] DEBUG[10062]: pbx.c:8767 ast_context_find_or_create: Registered context 'udial'(0xb4edf238) in local table 0xb4c97050; registrar: pbx_config [Dec 6 22:45:53] -- Registered extension context 'udial'; registrar: pbx_config [Dec 6 22:45:53] DEBUG[28895]: netsock2.c:138 ast_sockaddr_split_hostport: Splitting 'sipbalance1-3.1und1.de.' into... [Dec 6 22:45:53] DEBUG[28895]: netsock2.c:192 ast_sockaddr_split_hostport: ...host 'sipbalance1-3.1und1.de.' and port ''. [Dec 6 22:45:53] DEBUG[28689]: chan_iax2.c:2912 sched_delay_remove: schedule decrement of callno used for 10.9.42.1 in 60 seconds [Dec 6 22:45:53] DEBUG[28689]: chan_iax2.c:11255 socket_process_helper: Peer miranda-wimpy: got pong, lastms 38, historicms 38, maxms 2000 [Dec 6 22:45:53] DEBUG[28895]: dnsmgr.c:206 dnsmgr_refresh: Not an IPv4 nor IPv6 address, cannot get port. [Dec 6 22:45:53] DEBUG[28895]: dnsmgr.c:201 dnsmgr_refresh: refreshing 'sipbalance1-4.1und1.de.' [Dec 6 22:45:53] DEBUG[28688]: chan_iax2.c:2912 sched_delay_remove: schedule decrement of callno used for 91.35.57.63 in 60 seconds [Dec 6 22:45:53] DEBUG[28688]: chan_iax2.c:11255 socket_process_helper: Peer yetim-lmaa: got pong, lastms 43, historicms 43, maxms 2000 [Dec 6 22:45:53] DEBUG[28895]: netsock2.c:138 ast_sockaddr_split_hostport: Splitting 'sipbalance1-4.1und1.de.' into... [Dec 6 22:45:53] DEBUG[28895]: netsock2.c:192 ast_sockaddr_split_hostport: ...host 'sipbalance1-4.1und1.de.' and port ''. [Dec 6 22:45:53] DEBUG[10062]: pbx.c:8767 ast_context_find_or_create: Registered context 'ssmmc'(0xb47432b0) in local table 0xb4c97050; registrar: pbx_config [Dec 6 22:45:53] -- Registered extension context 'ssmmc'; registrar: pbx_config [Dec 6 22:45:53] DEBUG[28895]: dnsmgr.c:206 dnsmgr_refresh: Not an IPv4 nor IPv6 address, cannot get port. [Dec 6 22:45:53] DEBUG[28895]: dnsmgr.c:201 dnsmgr_refresh: refreshing 'sipbalance2-1.1und1.de.' [Dec 6 22:45:53] DEBUG[10062]: pbx.c:8767 ast_context_find_or_create: Registered context 'ssmmc-select'(0xb4743588) in local table 0xb4c97050; registrar: pbx_config [Dec 6 22:45:53] -- Registered extension context 'ssmmc-select'; registrar: pbx_config [Dec 6 22:45:53] DEBUG[28690]: chan_iax2.c:2912 sched_delay_remove: schedule decrement of callno used for 87.139.190.172 in 60 seconds [Dec 6 22:45:53] DEBUG[28690]: chan_iax2.c:11255 socket_process_helper: Peer s-mn24: got pong, lastms 50, historicms 50, maxms 2000 [Dec 6 22:45:53] DEBUG[28895]: srv.c:294 ast_get_srv: ast_get_srv: SRV lookup for '_sip._udp.sipbalance2-1.1und1.de.' mapped to host sipbalance2-1.1und1.de, port 5060 [Dec 6 22:45:53] DEBUG[28895]: netsock2.c:138 ast_sockaddr_split_hostport: Splitting 'sipbalance2-1.1und1.de' into... [Dec 6 22:45:53] DEBUG[28895]: netsock2.c:192 ast_sockaddr_split_hostport: ...host 'sipbalance2-1.1und1.de' and port ''. [Dec 6 22:45:53] DEBUG[10062]: pbx.c:8767 ast_context_find_or_create: Registered context 'ssmmc-main'(0xb503f398) in local table 0xb4c97050; registrar: pbx_config [Dec 6 22:45:53] -- Registered extension context 'ssmmc-main'; registrar: pbx_config [Dec 6 22:45:53] DEBUG[28688]: chan_iax2.c:2912 sched_delay_remove: schedule decrement of callno used for 91.57.200.69 in 60 seconds [Dec 6 22:45:53] DEBUG[28688]: chan_iax2.c:11255 socket_process_helper: Peer hbc-hbst: got pong, lastms 54, historicms 54, maxms 2000 [Dec 6 22:45:53] DEBUG[28895]: dnsmgr.c:201 dnsmgr_refresh: refreshing 'sipbalance2-2.1und1.de.' [Dec 6 22:45:53] DEBUG[10062]: pbx.c:8767 ast_context_find_or_create: Registered context 'ssmmc-create'(0xb473e308) in local table 0xb4c97050; registrar: pbx_config [Dec 6 22:45:53] -- Registered extension context 'ssmmc-create'; registrar: pbx_config [Dec 6 22:45:53] DEBUG[28689]: chan_iax2.c:2912 sched_delay_remove: schedule decrement of callno used for 87.122.52.17 in 60 seconds [Dec 6 22:45:53] DEBUG[28689]: chan_iax2.c:11255 socket_process_helper: Peer hbc-pgsw: got pong, lastms 55, historicms 55, maxms 2000 [Dec 6 22:45:53] DEBUG[10062]: pbx.c:8767 ast_context_find_or_create: Registered context 'ssmmc-record'(0xb473eab8) in local table 0xb4c97050; registrar: pbx_config [Dec 6 22:45:53] -- Registered extension context 'ssmmc-record'; registrar: pbx_config [Dec 6 22:45:53] DEBUG[10062]: pbx.c:8767 ast_context_find_or_create: Registered context 'realusers0'(0xb4741f20) in local table 0xb4c97050; registrar: pbx_config [Dec 6 22:45:53] -- Registered extension context 'realusers0'; registrar: pbx_config [Dec 6 22:45:53] DEBUG[28895]: srv.c:294 ast_get_srv: ast_get_srv: SRV lookup for '_sip._udp.sipbalance2-2.1und1.de.' mapped to host sipbalance2-2.1und1.de, port 5060 [Dec 6 22:45:53] DEBUG[28895]: netsock2.c:138 ast_sockaddr_split_hostport: Splitting 'sipbalance2-2.1und1.de' into... [Dec 6 22:45:53] DEBUG[28895]: netsock2.c:192 ast_sockaddr_split_hostport: ...host 'sipbalance2-2.1und1.de' and port ''. [Dec 6 22:45:53] DEBUG[10062]: pbx.c:8767 ast_context_find_or_create: Registered context 'realusers1'(0xb47411c0) in local table 0xb4c97050; registrar: pbx_config [Dec 6 22:45:53] -- Registered extension context 'realusers1'; registrar: pbx_config [Dec 6 22:45:53] DEBUG[28895]: dnsmgr.c:201 dnsmgr_refresh: refreshing 'sipbalance2-3.1und1.de.' [Dec 6 22:45:53] DEBUG[10062]: pbx.c:8767 ast_context_find_or_create: Registered context 'hartbeat'(0xb4ab2c78) in local table 0xb4c97050; registrar: pbx_config [Dec 6 22:45:53] -- Registered extension context 'hartbeat'; registrar: pbx_config [Dec 6 22:45:53] DEBUG[10062]: pbx.c:8767 ast_context_find_or_create: Registered context 'robert-vtln'(0xb4ab2968) in local table 0xb4c97050; registrar: pbx_config [Dec 6 22:45:53] -- Registered extension context 'robert-vtln'; registrar: pbx_config [Dec 6 22:45:53] DEBUG[10062]: pbx.c:8767 ast_context_find_or_create: Registered context 'robert-viptel'(0xb4ab35e8) in local table 0xb4c97050; registrar: pbx_config [Dec 6 22:45:53] -- Registered extension context 'robert-viptel'; registrar: pbx_config [Dec 6 22:45:53] DEBUG[10062]: pbx.c:8767 ast_context_find_or_create: Registered context 'robert-in-sipgate'(0xb4ab4b00) in local table 0xb4c97050; registrar: pbx_config [Dec 6 22:45:53] -- Registered extension context 'robert-in-sipgate'; registrar: pbx_config [Dec 6 22:45:53] DEBUG[28895]: netsock2.c:138 ast_sockaddr_split_hostport: Splitting 'sipbalance2-3.1und1.de.' into... [Dec 6 22:45:53] DEBUG[28895]: netsock2.c:192 ast_sockaddr_split_hostport: ...host 'sipbalance2-3.1und1.de.' and port ''. [Dec 6 22:45:53] DEBUG[10062]: pbx.c:8767 ast_context_find_or_create: Registered context 'robert-callback'(0xb4ab6350) in local table 0xb4c97050; registrar: pbx_config [Dec 6 22:45:53] -- Registered extension context 'robert-callback'; registrar: pbx_config [Dec 6 22:45:53] DEBUG[10062]: pbx.c:3671 ast_str_retrieve_variable: Result of 'matchall' is '_[a-zA-Z0-9*#+].' [Dec 6 22:45:53] DEBUG[10062]: pbx.c:8767 ast_context_find_or_create: Registered context 'robert-callback-dial'(0xb4ab5f60) in local table 0xb4c97050; registrar: pbx_config [Dec 6 22:45:53] -- Registered extension context 'robert-callback-dial'; registrar: pbx_config [Dec 6 22:45:53] -- Including context 'dial-user' in context 'robert-callback-dial' [Dec 6 22:45:53] -- Including context 'robert-callback-include' in context 'robert-callback-dial' [Dec 6 22:45:53] DEBUG[10062]: pbx.c:8767 ast_context_find_or_create: Registered context 'robert-callback-include'(0xb4ab6210) in local table 0xb4c97050; registrar: pbx_config [Dec 6 22:45:53] -- Registered extension context 'robert-callback-include'; registrar: pbx_config [Dec 6 22:45:53] DEBUG[28895]: dnsmgr.c:206 dnsmgr_refresh: Not an IPv4 nor IPv6 address, cannot get port. [Dec 6 22:45:53] DEBUG[28895]: dnsmgr.c:201 dnsmgr_refresh: refreshing 'sipbalance2-4.1und1.de.' [Dec 6 22:45:53] DEBUG[10062]: pbx.c:8767 ast_context_find_or_create: Registered context 'robert-callback-through'(0xb4ab8668) in local table 0xb4c97050; registrar: pbx_config [Dec 6 22:45:53] -- Registered extension context 'robert-callback-through'; registrar: pbx_config [Dec 6 22:45:53] DEBUG[10062]: pbx.c:3671 ast_str_retrieve_variable: Result of 'matchall' is '_[a-zA-Z0-9*#+].' [Dec 6 22:45:53] DEBUG[10062]: pbx.c:8767 ast_context_find_or_create: Registered context 'default'(0xb4f5fb28) in local table 0xb4c97050; registrar: pbx_config [Dec 6 22:45:53] -- Registered extension context 'default'; registrar: pbx_config [Dec 6 22:45:53] DEBUG[10062]: pbx.c:8767 ast_context_find_or_create: Registered context 'guest'(0xb4f5fbb8) in local table 0xb4c97050; registrar: pbx_config [Dec 6 22:45:53] -- Registered extension context 'guest'; registrar: pbx_config [Dec 6 22:45:53] -- Including context 'dial-user' in context 'guest' [Dec 6 22:45:53] DEBUG[10062]: pbx.c:8767 ast_context_find_or_create: Registered context 'wech'(0xb4f60148) in local table 0xb4c97050; registrar: pbx_config [Dec 6 22:45:53] -- Registered extension context 'wech'; registrar: pbx_config [Dec 6 22:45:53] -- Including switch 'Loopback/+${EXTEN:2}@usernum/_00!' in context 'wech' [Dec 6 22:45:53] -- Including switch 'Loopback/+${L_CC}${EXTEN:1}@usernum/_0Z!' in context 'wech' [Dec 6 22:45:53] -- Including switch 'Loopback/+${L_CC}${L_AC}${EXTEN}@usernum/_Z!' in context 'wech' [Dec 6 22:45:53] -- Including switch 'Loopback/+${EXTEN}@usernum/_Z!' in context 'wech' [Dec 6 22:45:53] -- Including switch 'Loopback/${EXTEN}@usernum' in context 'wech' [Dec 6 22:45:53] -- Including switch 'Loopback/${EXTEN}@localnum' in context 'wech' [Dec 6 22:45:53] DEBUG[10062]: pbx.c:8767 ast_context_find_or_create: Registered context 'invalid'(0xb4f601e0) in local table 0xb4c97050; registrar: pbx_config [Dec 6 22:45:53] -- Registered extension context 'invalid'; registrar: pbx_config [Dec 6 22:45:53] DEBUG[10062]: pbx.c:8767 ast_context_find_or_create: Registered context 'usernum'(0xb4f609b0) in local table 0xb4c97050; registrar: pbx_config [Dec 6 22:45:53] -- Registered extension context 'usernum'; registrar: pbx_config [Dec 6 22:45:53] DEBUG[28895]: netsock2.c:138 ast_sockaddr_split_hostport: Splitting 'sipbalance2-4.1und1.de.' into... [Dec 6 22:45:53] DEBUG[28895]: netsock2.c:192 ast_sockaddr_split_hostport: ...host 'sipbalance2-4.1und1.de.' and port ''. [Dec 6 22:45:53] -- Including context 'realusers0' in context 'usernum' [Dec 6 22:45:53] -- Including context 'realusers1' in context 'usernum' [Dec 6 22:45:53] -- Including context 'peers' in context 'usernum' [Dec 6 22:45:53] DEBUG[10062]: pbx.c:8767 ast_context_find_or_create: Registered context 'peers'(0xb4ab84a0) in local table 0xb4c97050; registrar: pbx_config [Dec 6 22:45:53] -- Registered extension context 'peers'; registrar: pbx_config [Dec 6 22:45:53] DEBUG[10062]: pbx.c:8767 ast_context_find_or_create: Registered context 'macro-captcha'(0xb4f618b0) in local table 0xb4c97050; registrar: pbx_config [Dec 6 22:45:53] -- Registered extension context 'macro-captcha'; registrar: pbx_config [Dec 6 22:45:53] DEBUG[10062]: pbx.c:8767 ast_context_find_or_create: Registered context 'in-1und1'(0xb4f62300) in local table 0xb4c97050; registrar: pbx_config [Dec 6 22:45:53] -- Registered extension context 'in-1und1'; registrar: pbx_config [Dec 6 22:45:53] DEBUG[10062]: pbx.c:3671 ast_str_retrieve_variable: Result of 'matchall' is '_[a-zA-Z0-9*#+].' [Dec 6 22:45:53] DEBUG[28690]: chan_iax2.c:2912 sched_delay_remove: schedule decrement of callno used for 79.228.39.209 in 60 seconds [Dec 6 22:45:53] DEBUG[28690]: chan_iax2.c:11255 socket_process_helper: Peer hbc-grau: got pong, lastms 73, historicms 73, maxms 2000 [Dec 6 22:45:53] DEBUG[28895]: dnsmgr.c:206 dnsmgr_refresh: Not an IPv4 nor IPv6 address, cannot get port. [Dec 6 22:45:53] DEBUG[28895]: dnsmgr.c:201 dnsmgr_refresh: refreshing 'sipbalance3-1.1und1.de.' [Dec 6 22:45:53] DEBUG[10062]: pbx.c:8767 ast_context_find_or_create: Registered context 'in-sipgate'(0xb4f62fa0) in local table 0xb4c97050; registrar: pbx_config [Dec 6 22:45:53] -- Registered extension context 'in-sipgate'; registrar: pbx_config [Dec 6 22:45:53] DEBUG[10062]: pbx.c:3671 ast_str_retrieve_variable: Result of 'matchall' is '_[a-zA-Z0-9*#+].' [Dec 6 22:45:53] DEBUG[10062]: pbx.c:8767 ast_context_find_or_create: Registered context 'in-hn'(0xb4f63540) in local table 0xb4c97050; registrar: pbx_config [Dec 6 22:45:53] -- Registered extension context 'in-hn'; registrar: pbx_config [Dec 6 22:45:53] DEBUG[10062]: pbx.c:3671 ast_str_retrieve_variable: Result of 'matchall' is '_[a-zA-Z0-9*#+].' [Dec 6 22:45:53] DEBUG[10062]: pbx.c:8767 ast_context_find_or_create: Registered context 'in-dtag'(0xb4f642a8) in local table 0xb4c97050; registrar: pbx_config [Dec 6 22:45:53] -- Registered extension context 'in-dtag'; registrar: pbx_config [Dec 6 22:45:53] DEBUG[10062]: pbx.c:3671 ast_str_retrieve_variable: Result of 'matchall' is '_[a-zA-Z0-9*#+].' [Dec 6 22:45:53] DEBUG[10062]: pbx.c:8767 ast_context_find_or_create: Registered context 'in-tt'(0xb4f624d0) in local table 0xb4c97050; registrar: pbx_config [Dec 6 22:45:53] -- Registered extension context 'in-tt'; registrar: pbx_config [Dec 6 22:45:53] DEBUG[10062]: pbx.c:3671 ast_str_retrieve_variable: Result of 'matchall' is '_[a-zA-Z0-9*#+].' [Dec 6 22:45:53] DEBUG[10062]: pbx.c:8767 ast_context_find_or_create: Registered context 'in-mn'(0xb4f64da8) in local table 0xb4c97050; registrar: pbx_config [Dec 6 22:45:53] -- Registered extension context 'in-mn'; registrar: pbx_config [Dec 6 22:45:53] DEBUG[10062]: pbx.c:3671 ast_str_retrieve_variable: Result of 'matchall' is '_[a-zA-Z0-9*#+].' [Dec 6 22:45:53] DEBUG[28895]: netsock2.c:138 ast_sockaddr_split_hostport: Splitting 'sipbalance3-1.1und1.de.' into... [Dec 6 22:45:53] DEBUG[28895]: netsock2.c:192 ast_sockaddr_split_hostport: ...host 'sipbalance3-1.1und1.de.' and port ''. [Dec 6 22:45:53] DEBUG[10062]: pbx.c:8767 ast_context_find_or_create: Registered context 'in-wu'(0xb4f65078) in local table 0xb4c97050; registrar: pbx_config [Dec 6 22:45:53] -- Registered extension context 'in-wu'; registrar: pbx_config [Dec 6 22:45:53] DEBUG[10062]: pbx.c:3671 ast_str_retrieve_variable: Result of 'matchall' is '_[a-zA-Z0-9*#+].' [Dec 6 22:45:53] DEBUG[10062]: pbx.c:8767 ast_context_find_or_create: Registered context 'in-gi'(0xb4f64690) in local table 0xb4c97050; registrar: pbx_config [Dec 6 22:45:53] -- Registered extension context 'in-gi'; registrar: pbx_config [Dec 6 22:45:53] DEBUG[10062]: pbx.c:3671 ast_str_retrieve_variable: Result of 'matchall' is '_[a-zA-Z0-9*#+].' [Dec 6 22:45:53] DEBUG[10062]: pbx.c:8767 ast_context_find_or_create: Registered context 'in-gs'(0xb5132e98) in local table 0xb4c97050; registrar: pbx_config [Dec 6 22:45:53] -- Registered extension context 'in-gs'; registrar: pbx_config [Dec 6 22:45:53] DEBUG[10062]: pbx.c:3671 ast_str_retrieve_variable: Result of 'matchall' is '_[a-zA-Z0-9*#+].' [Dec 6 22:45:53] DEBUG[10062]: pbx.c:8767 ast_context_find_or_create: Registered context 'in-gh'(0xb5132a10) in local table 0xb4c97050; registrar: pbx_config [Dec 6 22:45:53] -- Registered extension context 'in-gh'; registrar: pbx_config [Dec 6 22:45:53] DEBUG[10062]: pbx.c:3671 ast_str_retrieve_variable: Result of 'matchall' is '_[a-zA-Z0-9*#+].' [Dec 6 22:45:53] DEBUG[28895]: dnsmgr.c:206 dnsmgr_refresh: Not an IPv4 nor IPv6 address, cannot get port. [Dec 6 22:45:53] DEBUG[28895]: dnsmgr.c:201 dnsmgr_refresh: refreshing 'sipbalance3-2.1und1.de.' [Dec 6 22:45:53] DEBUG[10062]: pbx.c:8767 ast_context_find_or_create: Registered context 'localnumu'(0xb51353a8) in local table 0xb4c97050; registrar: pbx_config [Dec 6 22:45:53] -- Registered extension context 'localnumu'; registrar: pbx_config [Dec 6 22:45:53] -- Including context 'localnum' in context 'localnumu' [Dec 6 22:45:53] -- Including context 'fc-cf' in context 'localnumu' [Dec 6 22:45:53] -- Including context 'zkw' in context 'localnumu' [Dec 6 22:45:53] DEBUG[10062]: pbx.c:3671 ast_str_retrieve_variable: Result of 'x_alarm' is '1194' [Dec 6 22:45:53] DEBUG[28895]: netsock2.c:138 ast_sockaddr_split_hostport: Splitting 'sipbalance3-2.1und1.de.' into... [Dec 6 22:45:53] DEBUG[28895]: netsock2.c:192 ast_sockaddr_split_hostport: ...host 'sipbalance3-2.1und1.de.' and port ''. [Dec 6 22:45:53] DEBUG[10062]: pbx.c:3671 ast_str_retrieve_variable: Result of 'x_alarm' is '1194' [Dec 6 22:45:53] DEBUG[10062]: pbx.c:8767 ast_context_find_or_create: Registered context 'localnum'(0xb6e2d890) in local table 0xb4c97050; registrar: pbx_config [Dec 6 22:45:53] DEBUG[28895]: dnsmgr.c:206 dnsmgr_refresh: Not an IPv4 nor IPv6 address, cannot get port. [Dec 6 22:45:53] -- Registered extension context 'localnum'; registrar: pbx_config [Dec 6 22:45:53] DEBUG[28895]: dnsmgr.c:201 dnsmgr_refresh: refreshing 'sipbalance3-3.1und1.de.' [Dec 6 22:45:53] DEBUG[28895]: netsock2.c:138 ast_sockaddr_split_hostport: Splitting 'sipbalance3-3.1und1.de.' into... [Dec 6 22:45:53] DEBUG[28895]: netsock2.c:192 ast_sockaddr_split_hostport: ...host 'sipbalance3-3.1und1.de.' and port ''. [Dec 6 22:45:53] DEBUG[28895]: dnsmgr.c:206 dnsmgr_refresh: Not an IPv4 nor IPv6 address, cannot get port. [Dec 6 22:45:53] DEBUG[28895]: dnsmgr.c:201 dnsmgr_refresh: refreshing 'sipbalance3-4.1und1.de.' [Dec 6 22:45:53] DEBUG[28895]: netsock2.c:138 ast_sockaddr_split_hostport: Splitting 'sipbalance3-4.1und1.de.' into... [Dec 6 22:45:53] DEBUG[28895]: netsock2.c:192 ast_sockaddr_split_hostport: ...host 'sipbalance3-4.1und1.de.' and port ''. [Dec 6 22:45:53] DEBUG[28895]: dnsmgr.c:206 dnsmgr_refresh: Not an IPv4 nor IPv6 address, cannot get port. [Dec 6 22:45:53] DEBUG[28895]: dnsmgr.c:201 dnsmgr_refresh: refreshing 'sipbalance4-1.1und1.de.' [Dec 6 22:45:53] DEBUG[10062]: pbx.c:8767 ast_context_find_or_create: Registered context 'zkw'(0xb4704348) in local table 0xb4c97050; registrar: pbx_config [Dec 6 22:45:53] -- Registered extension context 'zkw'; registrar: pbx_config [Dec 6 22:45:53] DEBUG[10062]: pbx.c:8767 ast_context_find_or_create: Registered context 'enumrouting'(0xb47044d8) in local table 0xb4c97050; registrar: pbx_config [Dec 6 22:45:53] -- Registered extension context 'enumrouting'; registrar: pbx_config [Dec 6 22:45:53] DEBUG[10062]: pbx.c:8767 ast_context_find_or_create: Registered context 'autorouting'(0xb4704a78) in local table 0xb4c97050; registrar: pbx_config [Dec 6 22:45:53] -- Registered extension context 'autorouting'; registrar: pbx_config [Dec 6 22:45:53] -- Including switch 'DUNDi/dundi' in context 'autorouting' [Dec 6 22:45:53] DEBUG[10062]: pbx.c:8767 ast_context_find_or_create: Registered context 'dial-master'(0xb4703bc8) in local table 0xb4c97050; registrar: pbx_config [Dec 6 22:45:53] -- Registered extension context 'dial-master'; registrar: pbx_config [Dec 6 22:45:53] -- Including switch 'Loopback/+${EXTEN:2}@dial-canonical/_00!' in context 'dial-master' [Dec 6 22:45:53] -- Including switch 'Loopback/+${L_CC}${EXTEN:1}@dial-canonical/_0Z!' in context 'dial-master' [Dec 6 22:45:53] -- Including switch 'Loopback/+${L_CC}${L_AC}${EXTEN}@dial-canonical/_Z!' in context 'dial-master' [Dec 6 22:45:53] -- Including switch 'Loopback/+${EXTEN}@dial-canonical/_Z!' in context 'dial-master' [Dec 6 22:45:53] -- Including switch 'Loopback/${EXTEN}@dial-canonical' in context 'dial-master' [Dec 6 22:45:53] -- Including switch 'Loopback/${EXTEN}@localnumu' in context 'dial-master' [Dec 6 22:45:53] -- Including switch 'Loopback/${EXTEN}@masters' in context 'dial-master' [Dec 6 22:45:53] DEBUG[10062]: pbx.c:8767 ast_context_find_or_create: Registered context 'dial-user'(0xb47052a0) in local table 0xb4c97050; registrar: pbx_config [Dec 6 22:45:53] -- Registered extension context 'dial-user'; registrar: pbx_config [Dec 6 22:45:53] -- Including switch 'Loopback/+${EXTEN:2}@dial-canonical/_00!' in context 'dial-user' [Dec 6 22:45:53] -- Including switch 'Loopback/+${L_CC}${EXTEN:1}@dial-canonical/_0Z!' in context 'dial-user' [Dec 6 22:45:53] -- Including switch 'Loopback/+${L_CC}${L_AC}${EXTEN}@dial-canonical/_Z!' in context 'dial-user' [Dec 6 22:45:53] -- Including switch 'Loopback/+${EXTEN}@dial-canonical/_Z!' in context 'dial-user' [Dec 6 22:45:53] -- Including switch 'Loopback/${EXTEN}@dial-canonical' in context 'dial-user' [Dec 6 22:45:53] -- Including switch 'Loopback/${EXTEN}@localnumu' in context 'dial-user' [Dec 6 22:45:53] -- Including switch 'Loopback/${EXTEN}@users' in context 'dial-user' [Dec 6 22:45:53] DEBUG[10062]: pbx.c:8767 ast_context_find_or_create: Registered context 'dial-inbound'(0xb4705878) in local table 0xb4c97050; registrar: pbx_config [Dec 6 22:45:53] -- Registered extension context 'dial-inbound'; registrar: pbx_config [Dec 6 22:45:53] -- Including switch 'Loopback/+${EXTEN:2}@dial-canonical/_00!' in context 'dial-inbound' [Dec 6 22:45:53] -- Including switch 'Loopback/+${L_CC}${EXTEN:1}@dial-canonical/_0Z!' in context 'dial-inbound' [Dec 6 22:45:53] -- Including switch 'Loopback/+${L_CC}${L_AC}${EXTEN}@dial-canonical/_Z!' in context 'dial-inbound' [Dec 6 22:45:53] -- Including switch 'Loopback/+${EXTEN}@dial-canonical/_Z!' in context 'dial-inbound' [Dec 6 22:45:53] -- Including switch 'Loopback/${EXTEN}@dial-canonical' in context 'dial-inbound' [Dec 6 22:45:53] DEBUG[28895]: srv.c:294 ast_get_srv: ast_get_srv: SRV lookup for '_sip._udp.sipbalance4-1.1und1.de.' mapped to host sipbalance4-1.1und1.de, port 5060 [Dec 6 22:45:53] DEBUG[28895]: netsock2.c:138 ast_sockaddr_split_hostport: Splitting 'sipbalance4-1.1und1.de' into... [Dec 6 22:45:53] -- Including switch 'Loopback/${EXTEN}@localnumu' in context 'dial-inbound' [Dec 6 22:45:53] DEBUG[28895]: netsock2.c:192 ast_sockaddr_split_hostport: ...host 'sipbalance4-1.1und1.de' and port ''. [Dec 6 22:45:53] -- Including switch 'Loopback/${EXTEN}@users' in context 'dial-inbound' [Dec 6 22:45:53] DEBUG[10062]: pbx.c:8767 ast_context_find_or_create: Registered context 'dial-canonical'(0xb4705d48) in local table 0xb4c97050; registrar: pbx_config [Dec 6 22:45:53] -- Registered extension context 'dial-canonical'; registrar: pbx_config [Dec 6 22:45:53] -- Including context 'usernum' in context 'dial-canonical' [Dec 6 22:45:53] -- Including context 'autorouting' in context 'dial-canonical' [Dec 6 22:45:53] DEBUG[10062]: pbx.c:8767 ast_context_find_or_create: Registered context 'masters'(0xb4707358) in local table 0xb4c97050; registrar: pbx_config [Dec 6 22:45:53] -- Registered extension context 'masters'; registrar: pbx_config [Dec 6 22:45:53] DEBUG[28895]: dnsmgr.c:201 dnsmgr_refresh: refreshing 'sipbalance4-2.1und1.de.' [Dec 6 22:45:53] DEBUG[10062]: pbx.c:8767 ast_context_find_or_create: Registered context 'users'(0xb51cd200) in local table 0xb4c97050; registrar: pbx_config [Dec 6 22:45:53] -- Registered extension context 'users'; registrar: pbx_config [Dec 6 22:45:53] DEBUG[10062]: pbx.c:8767 ast_context_find_or_create: Registered context 'hints'(0xb51cd8b8) in local table 0xb4c97050; registrar: pbx_config [Dec 6 22:45:53] -- Registered extension context 'hints'; registrar: pbx_config [Dec 6 22:45:53] DEBUG[10062]: devicestate.c:340 _ast_device_state: Checking if I can find provider for "Custom" - number: state9 [Dec 6 22:45:53] DEBUG[10062]: devicestate.c:417 getproviderstate: Checking provider Custom with Custom [Dec 6 22:45:53] DEBUG[10062]: db.c:376 db_get_common: Unable to find key 'state9' in family 'CustomDevstate' [Dec 6 22:45:53] DEBUG[10062]: pbx.c:5916 ast_add_hint: HINTS: Adding hint *9: Custom:state9 [Dec 6 22:45:53] DEBUG[10062]: pbx.c:10258 ast_add_extension2_lockopt: Added extension '*9' priority -1 to hints (0xb51cd8b8) [Dec 6 22:45:53] -- Added extension '*9' priority -1 to hints [Dec 6 22:45:53] DEBUG[28895]: srv.c:294 ast_get_srv: ast_get_srv: SRV lookup for '_sip._udp.sipbalance4-2.1und1.de.' mapped to host sipbalance4-2.1und1.de, port 5060 [Dec 6 22:45:53] DEBUG[10062]: devicestate.c:340 _ast_device_state: Checking if I can find provider for "confbridge" - number: 0 [Dec 6 22:45:53] DEBUG[28895]: netsock2.c:138 ast_sockaddr_split_hostport: Splitting 'sipbalance4-2.1und1.de' into... [Dec 6 22:45:53] DEBUG[10062]: devicestate.c:417 getproviderstate: Checking provider Custom with confbridge [Dec 6 22:45:53] DEBUG[28895]: netsock2.c:192 ast_sockaddr_split_hostport: ...host 'sipbalance4-2.1und1.de' and port ''. [Dec 6 22:45:53] DEBUG[10062]: devicestate.c:417 getproviderstate: Checking provider ccss with confbridge [Dec 6 22:45:53] DEBUG[10062]: devicestate.c:417 getproviderstate: Checking provider Park with confbridge [Dec 6 22:45:53] DEBUG[10062]: pbx.c:5916 ast_add_hint: HINTS: Adding hint 11990: confbridge:0 [Dec 6 22:45:53] DEBUG[10062]: pbx.c:10258 ast_add_extension2_lockopt: Added extension '11990' priority -1 to hints (0xb51cd8b8) [Dec 6 22:45:53] -- Added extension '11990' priority -1 to hints [Dec 6 22:45:53] DEBUG[10062]: devicestate.c:340 _ast_device_state: Checking if I can find provider for "confbridge" - number: 1 [Dec 6 22:45:53] DEBUG[10062]: devicestate.c:417 getproviderstate: Checking provider Custom with confbridge [Dec 6 22:45:53] DEBUG[10062]: devicestate.c:417 getproviderstate: Checking provider ccss with confbridge [Dec 6 22:45:53] DEBUG[10062]: devicestate.c:417 getproviderstate: Checking provider Park with confbridge [Dec 6 22:45:53] DEBUG[10062]: pbx.c:5916 ast_add_hint: HINTS: Adding hint 11991: confbridge:1 [Dec 6 22:45:53] DEBUG[10062]: pbx.c:10258 ast_add_extension2_lockopt: Added extension '11991' priority -1 to hints (0xb51cd8b8) [Dec 6 22:45:53] -- Added extension '11991' priority -1 to hints [Dec 6 22:45:53] DEBUG[10062]: devicestate.c:340 _ast_device_state: Checking if I can find provider for "confbridge" - number: ssmmc-0 [Dec 6 22:45:53] DEBUG[10062]: devicestate.c:417 getproviderstate: Checking provider Custom with confbridge [Dec 6 22:45:53] DEBUG[10062]: devicestate.c:417 getproviderstate: Checking provider ccss with confbridge [Dec 6 22:45:53] DEBUG[10062]: devicestate.c:417 getproviderstate: Checking provider Park with confbridge [Dec 6 22:45:53] DEBUG[10062]: pbx.c:5916 ast_add_hint: HINTS: Adding hint ssmmc-0: confbridge:ssmmc-0 [Dec 6 22:45:53] DEBUG[10062]: pbx.c:10258 ast_add_extension2_lockopt: Added extension 'ssmmc-0' priority -1 to hints (0xb51cd8b8) [Dec 6 22:45:53] -- Added extension 'ssmmc-0' priority -1 to hints [Dec 6 22:45:53] DEBUG[10062]: devicestate.c:340 _ast_device_state: Checking if I can find provider for "confbridge" - number: ssmmc-1 [Dec 6 22:45:53] DEBUG[10062]: devicestate.c:417 getproviderstate: Checking provider Custom with confbridge [Dec 6 22:45:53] DEBUG[10062]: devicestate.c:417 getproviderstate: Checking provider ccss with confbridge [Dec 6 22:45:53] DEBUG[10062]: devicestate.c:417 getproviderstate: Checking provider Park with confbridge [Dec 6 22:45:53] DEBUG[10062]: pbx.c:5916 ast_add_hint: HINTS: Adding hint ssmmc-1: confbridge:ssmmc-1 [Dec 6 22:45:53] DEBUG[10062]: pbx.c:10258 ast_add_extension2_lockopt: Added extension 'ssmmc-1' priority -1 to hints (0xb51cd8b8) [Dec 6 22:45:53] -- Added extension 'ssmmc-1' priority -1 to hints [Dec 6 22:45:53] DEBUG[10062]: devicestate.c:340 _ast_device_state: Checking if I can find provider for "confbridge" - number: ssmmc-2 [Dec 6 22:45:53] DEBUG[10062]: devicestate.c:417 getproviderstate: Checking provider Custom with confbridge [Dec 6 22:45:53] DEBUG[10062]: devicestate.c:417 getproviderstate: Checking provider ccss with confbridge [Dec 6 22:45:53] DEBUG[10062]: devicestate.c:417 getproviderstate: Checking provider Park with confbridge [Dec 6 22:45:53] DEBUG[10062]: pbx.c:5916 ast_add_hint: HINTS: Adding hint ssmmc-2: confbridge:ssmmc-2 [Dec 6 22:45:53] DEBUG[10062]: pbx.c:10258 ast_add_extension2_lockopt: Added extension 'ssmmc-2' priority -1 to hints (0xb51cd8b8) [Dec 6 22:45:53] -- Added extension 'ssmmc-2' priority -1 to hints [Dec 6 22:45:53] DEBUG[10062]: devicestate.c:340 _ast_device_state: Checking if I can find provider for "confbridge" - number: ssmmc-3 [Dec 6 22:45:53] DEBUG[10062]: devicestate.c:417 getproviderstate: Checking provider Custom with confbridge [Dec 6 22:45:53] DEBUG[10062]: devicestate.c:417 getproviderstate: Checking provider ccss with confbridge [Dec 6 22:45:53] DEBUG[10062]: devicestate.c:417 getproviderstate: Checking provider Park with confbridge [Dec 6 22:45:53] DEBUG[10062]: pbx.c:5916 ast_add_hint: HINTS: Adding hint ssmmc-3: confbridge:ssmmc-3 [Dec 6 22:45:53] DEBUG[10062]: pbx.c:10258 ast_add_extension2_lockopt: Added extension 'ssmmc-3' priority -1 to hints (0xb51cd8b8) [Dec 6 22:45:53] -- Added extension 'ssmmc-3' priority -1 to hints [Dec 6 22:45:53] DEBUG[10062]: devicestate.c:340 _ast_device_state: Checking if I can find provider for "confbridge" - number: ssmmc-4 [Dec 6 22:45:53] DEBUG[10062]: devicestate.c:417 getproviderstate: Checking provider Custom with confbridge [Dec 6 22:45:53] DEBUG[10062]: devicestate.c:417 getproviderstate: Checking provider ccss with confbridge [Dec 6 22:45:53] DEBUG[10062]: devicestate.c:417 getproviderstate: Checking provider Park with confbridge [Dec 6 22:45:53] DEBUG[10062]: pbx.c:5916 ast_add_hint: HINTS: Adding hint ssmmc-4: confbridge:ssmmc-4 [Dec 6 22:45:53] DEBUG[10062]: pbx.c:10258 ast_add_extension2_lockopt: Added extension 'ssmmc-4' priority -1 to hints (0xb51cd8b8) [Dec 6 22:45:53] -- Added extension 'ssmmc-4' priority -1 to hints [Dec 6 22:45:53] DEBUG[10062]: devicestate.c:340 _ast_device_state: Checking if I can find provider for "confbridge" - number: ssmmc-5 [Dec 6 22:45:53] DEBUG[10062]: devicestate.c:417 getproviderstate: Checking provider Custom with confbridge [Dec 6 22:45:53] DEBUG[10062]: devicestate.c:417 getproviderstate: Checking provider ccss with confbridge [Dec 6 22:45:53] DEBUG[10062]: devicestate.c:417 getproviderstate: Checking provider Park with confbridge [Dec 6 22:45:53] DEBUG[10062]: pbx.c:5916 ast_add_hint: HINTS: Adding hint ssmmc-5: confbridge:ssmmc-5 [Dec 6 22:45:53] DEBUG[10062]: pbx.c:10258 ast_add_extension2_lockopt: Added extension 'ssmmc-5' priority -1 to hints (0xb51cd8b8) [Dec 6 22:45:53] -- Added extension 'ssmmc-5' priority -1 to hints [Dec 6 22:45:53] DEBUG[10062]: devicestate.c:340 _ast_device_state: Checking if I can find provider for "confbridge" - number: ssmmc-6 [Dec 6 22:45:53] DEBUG[10062]: devicestate.c:417 getproviderstate: Checking provider Custom with confbridge [Dec 6 22:45:53] DEBUG[10062]: devicestate.c:417 getproviderstate: Checking provider ccss with confbridge [Dec 6 22:45:53] DEBUG[10062]: devicestate.c:417 getproviderstate: Checking provider Park with confbridge [Dec 6 22:45:53] DEBUG[10062]: pbx.c:5916 ast_add_hint: HINTS: Adding hint ssmmc-6: confbridge:ssmmc-6 [Dec 6 22:45:53] DEBUG[10062]: pbx.c:10258 ast_add_extension2_lockopt: Added extension 'ssmmc-6' priority -1 to hints (0xb51cd8b8) [Dec 6 22:45:53] -- Added extension 'ssmmc-6' priority -1 to hints [Dec 6 22:45:53] DEBUG[10062]: devicestate.c:340 _ast_device_state: Checking if I can find provider for "confbridge" - number: ssmmc-7 [Dec 6 22:45:53] DEBUG[10062]: devicestate.c:417 getproviderstate: Checking provider Custom with confbridge [Dec 6 22:45:53] DEBUG[10062]: devicestate.c:417 getproviderstate: Checking provider ccss with confbridge [Dec 6 22:45:53] DEBUG[10062]: devicestate.c:417 getproviderstate: Checking provider Park with confbridge [Dec 6 22:45:53] DEBUG[10062]: pbx.c:5916 ast_add_hint: HINTS: Adding hint ssmmc-7: confbridge:ssmmc-7 [Dec 6 22:45:53] DEBUG[10062]: pbx.c:10258 ast_add_extension2_lockopt: Added extension 'ssmmc-7' priority -1 to hints (0xb51cd8b8) [Dec 6 22:45:53] -- Added extension 'ssmmc-7' priority -1 to hints [Dec 6 22:45:53] DEBUG[10062]: devicestate.c:340 _ast_device_state: Checking if I can find provider for "confbridge" - number: ssmmc-8 [Dec 6 22:45:53] DEBUG[10062]: devicestate.c:417 getproviderstate: Checking provider Custom with confbridge [Dec 6 22:45:53] DEBUG[10062]: devicestate.c:417 getproviderstate: Checking provider ccss with confbridge [Dec 6 22:45:53] DEBUG[10062]: devicestate.c:417 getproviderstate: Checking provider Park with confbridge [Dec 6 22:45:53] DEBUG[10062]: pbx.c:5916 ast_add_hint: HINTS: Adding hint ssmmc-8: confbridge:ssmmc-8 [Dec 6 22:45:53] DEBUG[10062]: pbx.c:10258 ast_add_extension2_lockopt: Added extension 'ssmmc-8' priority -1 to hints (0xb51cd8b8) [Dec 6 22:45:53] -- Added extension 'ssmmc-8' priority -1 to hints [Dec 6 22:45:53] DEBUG[10062]: devicestate.c:340 _ast_device_state: Checking if I can find provider for "confbridge" - number: ssmmc-9 [Dec 6 22:45:53] DEBUG[10062]: devicestate.c:417 getproviderstate: Checking provider Custom with confbridge [Dec 6 22:45:53] DEBUG[10062]: devicestate.c:417 getproviderstate: Checking provider ccss with confbridge [Dec 6 22:45:53] DEBUG[10062]: devicestate.c:417 getproviderstate: Checking provider Park with confbridge [Dec 6 22:45:53] DEBUG[10062]: pbx.c:5916 ast_add_hint: HINTS: Adding hint ssmmc-9: confbridge:ssmmc-9 [Dec 6 22:45:53] DEBUG[10062]: pbx.c:10258 ast_add_extension2_lockopt: Added extension 'ssmmc-9' priority -1 to hints (0xb51cd8b8) [Dec 6 22:45:53] -- Added extension 'ssmmc-9' priority -1 to hints [Dec 6 22:45:53] DEBUG[10062]: devicestate.c:344 _ast_device_state: No provider found, checking channel drivers for iax2 - yeti-lmaa [Dec 6 22:45:53] DEBUG[10062]: chan_iax2.c:14465 iax2_devicestate: Checking device state for device yeti-lmaa [Dec 6 22:45:53] DEBUG[28895]: dnsmgr.c:201 dnsmgr_refresh: refreshing 'sipbalance4-3.1und1.de.' [Dec 6 22:45:53] DEBUG[10062]: pbx.c:5916 ast_add_hint: HINTS: Adding hint 8402010: iax2/yeti-lmaa [Dec 6 22:45:53] DEBUG[10062]: pbx.c:10258 ast_add_extension2_lockopt: Added extension '8402010' priority -1 to hints (0xb51cd8b8) [Dec 6 22:45:53] -- Added extension '8402010' priority -1 to hints [Dec 6 22:45:53] DEBUG[10062]: devicestate.c:344 _ast_device_state: No provider found, checking channel drivers for iax2 - allbyte [Dec 6 22:45:53] DEBUG[10062]: chan_iax2.c:14465 iax2_devicestate: Checking device state for device allbyte [Dec 6 22:45:53] DEBUG[10062]: pbx.c:5916 ast_add_hint: HINTS: Adding hint 010*3**26: iax2/allbyte [Dec 6 22:45:53] DEBUG[10062]: pbx.c:10258 ast_add_extension2_lockopt: Added extension '010*3**26' priority -1 to hints (0xb51cd8b8) [Dec 6 22:45:53] -- Added extension '010*3**26' priority -1 to hints [Dec 6 22:45:53] DEBUG[10062]: devicestate.c:344 _ast_device_state: No provider found, checking channel drivers for sip - allbyte [Dec 6 22:45:53] DEBUG[10062]: pbx.c:5916 ast_add_hint: HINTS: Adding hint 010*3**21: sip/allbyte [Dec 6 22:45:53] DEBUG[10062]: pbx.c:10258 ast_add_extension2_lockopt: Added extension '010*3**21' priority -1 to hints (0xb51cd8b8) [Dec 6 22:45:53] -- Added extension '010*3**21' priority -1 to hints [Dec 6 22:45:53] DEBUG[10062]: devicestate.c:344 _ast_device_state: No provider found, checking channel drivers for iax2 - test1 [Dec 6 22:45:53] DEBUG[10062]: chan_iax2.c:14465 iax2_devicestate: Checking device state for device test1 [Dec 6 22:45:53] DEBUG[10062]: chan_iax2.c:14473 iax2_devicestate: Found peer. What's device state of test1? addr=0, defaddr=0 maxms=2000, lastms=0 [Dec 6 22:45:53] DEBUG[10062]: pbx.c:5916 ast_add_hint: HINTS: Adding hint ::iax2:test1: iax2/test1 [Dec 6 22:45:53] DEBUG[10062]: pbx.c:10258 ast_add_extension2_lockopt: Added extension '::iax2:test1' priority -1 to hints (0xb51cd8b8) [Dec 6 22:45:53] -- Added extension '::iax2:test1' priority -1 to hints [Dec 6 22:45:53] DEBUG[10062]: devicestate.c:344 _ast_device_state: No provider found, checking channel drivers for sip - test1 [Dec 6 22:45:53] DEBUG[10062]: pbx.c:5916 ast_add_hint: HINTS: Adding hint ::sip:test1: sip/test1 [Dec 6 22:45:53] DEBUG[10062]: pbx.c:10258 ast_add_extension2_lockopt: Added extension '::sip:test1' priority -1 to hints (0xb51cd8b8) [Dec 6 22:45:53] -- Added extension '::sip:test1' priority -1 to hints [Dec 6 22:45:53] DEBUG[10062]: devicestate.c:344 _ast_device_state: No provider found, checking channel drivers for iax2 - test2 [Dec 6 22:45:53] DEBUG[10062]: chan_iax2.c:14465 iax2_devicestate: Checking device state for device test2 [Dec 6 22:45:53] DEBUG[10062]: chan_iax2.c:14473 iax2_devicestate: Found peer. What's device state of test2? addr=0, defaddr=0 maxms=2000, lastms=0 [Dec 6 22:45:53] DEBUG[10062]: pbx.c:5916 ast_add_hint: HINTS: Adding hint ::iax2:test2: iax2/test2 [Dec 6 22:45:53] DEBUG[10062]: pbx.c:10258 ast_add_extension2_lockopt: Added extension '::iax2:test2' priority -1 to hints (0xb51cd8b8) [Dec 6 22:45:53] -- Added extension '::iax2:test2' priority -1 to hints [Dec 6 22:45:53] DEBUG[10062]: devicestate.c:344 _ast_device_state: No provider found, checking channel drivers for sip - test2 [Dec 6 22:45:53] DEBUG[10062]: pbx.c:5916 ast_add_hint: HINTS: Adding hint ::sip:test2: sip/test2 [Dec 6 22:45:53] DEBUG[10062]: pbx.c:10258 ast_add_extension2_lockopt: Added extension '::sip:test2' priority -1 to hints (0xb51cd8b8) [Dec 6 22:45:53] -- Added extension '::sip:test2' priority -1 to hints [Dec 6 22:45:53] DEBUG[10062]: devicestate.c:344 _ast_device_state: No provider found, checking channel drivers for iax2 - test3 [Dec 6 22:45:53] DEBUG[10062]: chan_iax2.c:14465 iax2_devicestate: Checking device state for device test3 [Dec 6 22:45:53] DEBUG[10062]: chan_iax2.c:14473 iax2_devicestate: Found peer. What's device state of test3? addr=0, defaddr=0 maxms=2000, lastms=0 [Dec 6 22:45:53] DEBUG[10062]: pbx.c:5916 ast_add_hint: HINTS: Adding hint ::iax2:test3: iax2/test3 [Dec 6 22:45:53] DEBUG[10062]: pbx.c:10258 ast_add_extension2_lockopt: Added extension '::iax2:test3' priority -1 to hints (0xb51cd8b8) [Dec 6 22:45:53] -- Added extension '::iax2:test3' priority -1 to hints [Dec 6 22:45:53] DEBUG[10062]: devicestate.c:344 _ast_device_state: No provider found, checking channel drivers for sip - test3 [Dec 6 22:45:53] DEBUG[10062]: pbx.c:5916 ast_add_hint: HINTS: Adding hint ::sip:test3: sip/test3 [Dec 6 22:45:53] DEBUG[10062]: pbx.c:10258 ast_add_extension2_lockopt: Added extension '::sip:test3' priority -1 to hints (0xb51cd8b8) [Dec 6 22:45:53] -- Added extension '::sip:test3' priority -1 to hints [Dec 6 22:45:53] DEBUG[10062]: devicestate.c:344 _ast_device_state: No provider found, checking channel drivers for iax2 - test4 [Dec 6 22:45:53] DEBUG[10062]: chan_iax2.c:14465 iax2_devicestate: Checking device state for device test4 [Dec 6 22:45:53] DEBUG[10062]: chan_iax2.c:14473 iax2_devicestate: Found peer. What's device state of test4? addr=0, defaddr=0 maxms=2000, lastms=0 [Dec 6 22:45:53] DEBUG[10062]: pbx.c:5916 ast_add_hint: HINTS: Adding hint ::iax2:test4: iax2/test4 [Dec 6 22:45:53] DEBUG[10062]: pbx.c:10258 ast_add_extension2_lockopt: Added extension '::iax2:test4' priority -1 to hints (0xb51cd8b8) [Dec 6 22:45:53] -- Added extension '::iax2:test4' priority -1 to hints [Dec 6 22:45:53] DEBUG[10062]: devicestate.c:344 _ast_device_state: No provider found, checking channel drivers for sip - test4 [Dec 6 22:45:53] DEBUG[10062]: pbx.c:5916 ast_add_hint: HINTS: Adding hint ::sip:test4: sip/test4 [Dec 6 22:45:53] DEBUG[10062]: pbx.c:10258 ast_add_extension2_lockopt: Added extension '::sip:test4' priority -1 to hints (0xb51cd8b8) [Dec 6 22:45:53] -- Added extension '::sip:test4' priority -1 to hints [Dec 6 22:45:53] DEBUG[10062]: devicestate.c:344 _ast_device_state: No provider found, checking channel drivers for iax2 - test5 [Dec 6 22:45:53] DEBUG[10062]: chan_iax2.c:14465 iax2_devicestate: Checking device state for device test5 [Dec 6 22:45:53] DEBUG[10062]: chan_iax2.c:14473 iax2_devicestate: Found peer. What's device state of test5? addr=0, defaddr=0 maxms=2000, lastms=0 [Dec 6 22:45:53] DEBUG[10062]: pbx.c:5916 ast_add_hint: HINTS: Adding hint ::iax2:test5: iax2/test5 [Dec 6 22:45:53] DEBUG[10062]: pbx.c:10258 ast_add_extension2_lockopt: Added extension '::iax2:test5' priority -1 to hints (0xb51cd8b8) [Dec 6 22:45:53] -- Added extension '::iax2:test5' priority -1 to hints [Dec 6 22:45:53] DEBUG[10062]: devicestate.c:344 _ast_device_state: No provider found, checking channel drivers for sip - test5 [Dec 6 22:45:53] DEBUG[10062]: pbx.c:5916 ast_add_hint: HINTS: Adding hint ::sip:test5: sip/test5 [Dec 6 22:45:53] DEBUG[10062]: pbx.c:10258 ast_add_extension2_lockopt: Added extension '::sip:test5' priority -1 to hints (0xb51cd8b8) [Dec 6 22:45:53] -- Added extension '::sip:test5' priority -1 to hints [Dec 6 22:45:53] DEBUG[10062]: devicestate.c:344 _ast_device_state: No provider found, checking channel drivers for iax2 - test6 [Dec 6 22:45:53] DEBUG[10062]: chan_iax2.c:14465 iax2_devicestate: Checking device state for device test6 [Dec 6 22:45:53] DEBUG[10062]: chan_iax2.c:14473 iax2_devicestate: Found peer. What's device state of test6? addr=0, defaddr=0 maxms=2000, lastms=0 [Dec 6 22:45:53] DEBUG[10062]: pbx.c:5916 ast_add_hint: HINTS: Adding hint ::iax2:test6: iax2/test6 [Dec 6 22:45:53] DEBUG[10062]: pbx.c:10258 ast_add_extension2_lockopt: Added extension '::iax2:test6' priority -1 to hints (0xb51cd8b8) [Dec 6 22:45:53] -- Added extension '::iax2:test6' priority -1 to hints [Dec 6 22:45:53] DEBUG[10062]: devicestate.c:344 _ast_device_state: No provider found, checking channel drivers for sip - test6 [Dec 6 22:45:53] DEBUG[10062]: pbx.c:5916 ast_add_hint: HINTS: Adding hint ::sip:test6: sip/test6 [Dec 6 22:45:53] DEBUG[10062]: pbx.c:10258 ast_add_extension2_lockopt: Added extension '::sip:test6' priority -1 to hints (0xb51cd8b8) [Dec 6 22:45:53] -- Added extension '::sip:test6' priority -1 to hints [Dec 6 22:45:53] DEBUG[10062]: devicestate.c:344 _ast_device_state: No provider found, checking channel drivers for sip - 11779 [Dec 6 22:45:53] DEBUG[10062]: pbx.c:5916 ast_add_hint: HINTS: Adding hint ::sip:11779: sip/11779 [Dec 6 22:45:53] DEBUG[10062]: pbx.c:10258 ast_add_extension2_lockopt: Added extension '::sip:11779' priority -1 to hints (0xb51cd8b8) [Dec 6 22:45:53] -- Added extension '::sip:11779' priority -1 to hints [Dec 6 22:45:53] DEBUG[10062]: devicestate.c:344 _ast_device_state: No provider found, checking channel drivers for iax2 - s-mn24 [Dec 6 22:45:53] DEBUG[10062]: chan_iax2.c:14465 iax2_devicestate: Checking device state for device s-mn24 [Dec 6 22:45:53] DEBUG[10062]: chan_iax2.c:14473 iax2_devicestate: Found peer. What's device state of s-mn24? addr=1468776108, defaddr=0 maxms=2000, lastms=50 [Dec 6 22:45:53] DEBUG[10062]: pbx.c:5916 ast_add_hint: HINTS: Adding hint ::iax2:s-mn24: iax2/s-mn24 [Dec 6 22:45:53] DEBUG[10062]: pbx.c:10258 ast_add_extension2_lockopt: Added extension '::iax2:s-mn24' priority -1 to hints (0xb51cd8b8) [Dec 6 22:45:53] -- Added extension '::iax2:s-mn24' priority -1 to hints [Dec 6 22:45:53] DEBUG[10062]: devicestate.c:344 _ast_device_state: No provider found, checking channel drivers for iax2 - s-wuc [Dec 6 22:45:53] DEBUG[10062]: chan_iax2.c:14465 iax2_devicestate: Checking device state for device s-wuc [Dec 6 22:45:53] DEBUG[10062]: chan_iax2.c:14473 iax2_devicestate: Found peer. What's device state of s-wuc? addr=0, defaddr=0 maxms=2000, lastms=0 [Dec 6 22:45:53] DEBUG[10062]: pbx.c:5916 ast_add_hint: HINTS: Adding hint ::iax2:s-wuc: iax2/s-wuc [Dec 6 22:45:53] DEBUG[10062]: pbx.c:10258 ast_add_extension2_lockopt: Added extension '::iax2:s-wuc' priority -1 to hints (0xb51cd8b8) [Dec 6 22:45:53] -- Added extension '::iax2:s-wuc' priority -1 to hints [Dec 6 22:45:53] DEBUG[10062]: devicestate.c:344 _ast_device_state: No provider found, checking channel drivers for iax2 - hbc-hah [Dec 6 22:45:53] DEBUG[10062]: chan_iax2.c:14465 iax2_devicestate: Checking device state for device hbc-hah [Dec 6 22:45:53] DEBUG[10062]: chan_iax2.c:14473 iax2_devicestate: Found peer. What's device state of hbc-hah? addr=1401371040, defaddr=0 maxms=2000, lastms=23 [Dec 6 22:45:53] DEBUG[10062]: pbx.c:5916 ast_add_hint: HINTS: Adding hint ::iax2:hbc-hah: iax2/hbc-hah [Dec 6 22:45:53] DEBUG[10062]: pbx.c:10258 ast_add_extension2_lockopt: Added extension '::iax2:hbc-hah' priority -1 to hints (0xb51cd8b8) [Dec 6 22:45:53] DEBUG[28895]: netsock2.c:138 ast_sockaddr_split_hostport: Splitting 'sipbalance4-3.1und1.de.' into... [Dec 6 22:45:53] DEBUG[28895]: netsock2.c:192 ast_sockaddr_split_hostport: ...host 'sipbalance4-3.1und1.de.' and port ''. [Dec 6 22:45:53] -- Added extension '::iax2:hbc-hah' priority -1 to hints [Dec 6 22:45:53] DEBUG[10062]: devicestate.c:344 _ast_device_state: No provider found, checking channel drivers for iax2 - hbc-pgsw [Dec 6 22:45:53] DEBUG[10062]: chan_iax2.c:14465 iax2_devicestate: Checking device state for device hbc-pgsw [Dec 6 22:45:53] DEBUG[10062]: chan_iax2.c:14473 iax2_devicestate: Found peer. What's device state of hbc-pgsw? addr=1467626513, defaddr=0 maxms=2000, lastms=55 [Dec 6 22:45:53] DEBUG[10062]: pbx.c:5916 ast_add_hint: HINTS: Adding hint ::iax2:hbc-pgsw: iax2/hbc-pgsw [Dec 6 22:45:53] DEBUG[10062]: pbx.c:10258 ast_add_extension2_lockopt: Added extension '::iax2:hbc-pgsw' priority -1 to hints (0xb51cd8b8) [Dec 6 22:45:53] -- Added extension '::iax2:hbc-pgsw' priority -1 to hints [Dec 6 22:45:53] DEBUG[10062]: devicestate.c:344 _ast_device_state: No provider found, checking channel drivers for iax2 - hbc-hbst [Dec 6 22:45:53] DEBUG[10062]: chan_iax2.c:14465 iax2_devicestate: Checking device state for device hbc-hbst [Dec 6 22:45:53] DEBUG[10062]: chan_iax2.c:14473 iax2_devicestate: Found peer. What's device state of hbc-hbst? addr=1530513477, defaddr=0 maxms=2000, lastms=54 [Dec 6 22:45:53] DEBUG[10062]: pbx.c:5916 ast_add_hint: HINTS: Adding hint ::iax2:hbc-hbst: iax2/hbc-hbst [Dec 6 22:45:53] DEBUG[10062]: pbx.c:10258 ast_add_extension2_lockopt: Added extension '::iax2:hbc-hbst' priority -1 to hints (0xb51cd8b8) [Dec 6 22:45:53] -- Added extension '::iax2:hbc-hbst' priority -1 to hints [Dec 6 22:45:53] DEBUG[10062]: devicestate.c:344 _ast_device_state: No provider found, checking channel drivers for iax2 - hbc-grau [Dec 6 22:45:53] DEBUG[10062]: chan_iax2.c:14465 iax2_devicestate: Checking device state for device hbc-grau [Dec 6 22:45:53] DEBUG[10062]: chan_iax2.c:14473 iax2_devicestate: Found peer. What's device state of hbc-grau? addr=1340352465, defaddr=0 maxms=2000, lastms=73 [Dec 6 22:45:53] DEBUG[10062]: pbx.c:5916 ast_add_hint: HINTS: Adding hint ::iax2:hbc-grau: iax2/hbc-grau [Dec 6 22:45:53] DEBUG[10062]: pbx.c:10258 ast_add_extension2_lockopt: Added extension '::iax2:hbc-grau' priority -1 to hints (0xb51cd8b8) [Dec 6 22:45:53] -- Added extension '::iax2:hbc-grau' priority -1 to hints [Dec 6 22:45:53] DEBUG[10062]: devicestate.c:344 _ast_device_state: No provider found, checking channel drivers for iax2 - hbc-mmil [Dec 6 22:45:53] DEBUG[10062]: chan_iax2.c:14465 iax2_devicestate: Checking device state for device hbc-mmil [Dec 6 22:45:53] DEBUG[10062]: chan_iax2.c:14473 iax2_devicestate: Found peer. What's device state of hbc-mmil? addr=0, defaddr=0 maxms=2000, lastms=0 [Dec 6 22:45:53] DEBUG[10062]: pbx.c:5916 ast_add_hint: HINTS: Adding hint ::iax2:hbc-mmil: iax2/hbc-mmil [Dec 6 22:45:53] DEBUG[10062]: pbx.c:10258 ast_add_extension2_lockopt: Added extension '::iax2:hbc-mmil' priority -1 to hints (0xb51cd8b8) [Dec 6 22:45:53] -- Added extension '::iax2:hbc-mmil' priority -1 to hints [Dec 6 22:45:53] DEBUG[10062]: devicestate.c:344 _ast_device_state: No provider found, checking channel drivers for iax2 - miranda-wimpy [Dec 6 22:45:53] DEBUG[10062]: chan_iax2.c:14465 iax2_devicestate: Checking device state for device miranda-wimpy [Dec 6 22:45:53] DEBUG[10062]: chan_iax2.c:14473 iax2_devicestate: Found peer. What's device state of miranda-wimpy? addr=168372737, defaddr=0 maxms=2000, lastms=38 [Dec 6 22:45:53] DEBUG[10062]: pbx.c:5916 ast_add_hint: HINTS: Adding hint ::iax2:miranda-wimpy: iax2/miranda-wimpy [Dec 6 22:45:53] DEBUG[10062]: pbx.c:10258 ast_add_extension2_lockopt: Added extension '::iax2:miranda-wimpy' priority -1 to hints (0xb51cd8b8) [Dec 6 22:45:53] -- Added extension '::iax2:miranda-wimpy' priority -1 to hints [Dec 6 22:45:53] DEBUG[10062]: devicestate.c:344 _ast_device_state: No provider found, checking channel drivers for iax2 - yetim-lmaa [Dec 6 22:45:53] DEBUG[10062]: chan_iax2.c:14465 iax2_devicestate: Checking device state for device yetim-lmaa [Dec 6 22:45:53] DEBUG[10062]: chan_iax2.c:14473 iax2_devicestate: Found peer. What's device state of yetim-lmaa? addr=1529035071, defaddr=0 maxms=2000, lastms=43 [Dec 6 22:45:53] DEBUG[10062]: pbx.c:5916 ast_add_hint: HINTS: Adding hint ::iax2:yetim-lmaa: iax2/yetim-lmaa [Dec 6 22:45:53] DEBUG[10062]: pbx.c:10258 ast_add_extension2_lockopt: Added extension '::iax2:yetim-lmaa' priority -1 to hints (0xb51cd8b8) [Dec 6 22:45:53] -- Added extension '::iax2:yetim-lmaa' priority -1 to hints [Dec 6 22:45:53] DEBUG[10062]: devicestate.c:344 _ast_device_state: No provider found, checking channel drivers for sip - shjans [Dec 6 22:45:53] DEBUG[10062]: pbx.c:5916 ast_add_hint: HINTS: Adding hint ::sip:shjans: sip/shjans [Dec 6 22:45:53] DEBUG[10062]: pbx.c:10258 ast_add_extension2_lockopt: Added extension '::sip:shjans' priority -1 to hints (0xb51cd8b8) [Dec 6 22:45:53] -- Added extension '::sip:shjans' priority -1 to hints [Dec 6 22:45:53] DEBUG[10062]: devicestate.c:344 _ast_device_state: No provider found, checking channel drivers for sip - jane [Dec 6 22:45:53] DEBUG[10062]: pbx.c:5916 ast_add_hint: HINTS: Adding hint ::sip:jane: sip/jane [Dec 6 22:45:53] DEBUG[10062]: pbx.c:10258 ast_add_extension2_lockopt: Added extension '::sip:jane' priority -1 to hints (0xb51cd8b8) [Dec 6 22:45:53] -- Added extension '::sip:jane' priority -1 to hints [Dec 6 22:45:53] DEBUG[10062]: devicestate.c:344 _ast_device_state: No provider found, checking channel drivers for iax2 - flelvis [Dec 6 22:45:53] DEBUG[10062]: chan_iax2.c:14465 iax2_devicestate: Checking device state for device flelvis [Dec 6 22:45:53] DEBUG[10062]: chan_iax2.c:14473 iax2_devicestate: Found peer. What's device state of flelvis? addr=0, defaddr=0 maxms=2000, lastms=0 [Dec 6 22:45:53] DEBUG[10062]: pbx.c:5916 ast_add_hint: HINTS: Adding hint ::iax2:flelvis: iax2/flelvis [Dec 6 22:45:53] DEBUG[10062]: pbx.c:10258 ast_add_extension2_lockopt: Added extension '::iax2:flelvis' priority -1 to hints (0xb51cd8b8) [Dec 6 22:45:53] -- Added extension '::iax2:flelvis' priority -1 to hints [Dec 6 22:45:53] DEBUG[10062]: devicestate.c:344 _ast_device_state: No provider found, checking channel drivers for sip - flelvis [Dec 6 22:45:53] DEBUG[10062]: pbx.c:5916 ast_add_hint: HINTS: Adding hint ::sip:flelvis: sip/flelvis [Dec 6 22:45:53] DEBUG[10062]: pbx.c:10258 ast_add_extension2_lockopt: Added extension '::sip:flelvis' priority -1 to hints (0xb51cd8b8) [Dec 6 22:45:53] -- Added extension '::sip:flelvis' priority -1 to hints [Dec 6 22:45:53] DEBUG[10062]: devicestate.c:344 _ast_device_state: No provider found, checking channel drivers for sip - bigfoot [Dec 6 22:45:53] DEBUG[10062]: pbx.c:5916 ast_add_hint: HINTS: Adding hint ::sip:bigfoot: sip/bigfoot [Dec 6 22:45:53] DEBUG[10062]: pbx.c:10258 ast_add_extension2_lockopt: Added extension '::sip:bigfoot' priority -1 to hints (0xb51cd8b8) [Dec 6 22:45:53] -- Added extension '::sip:bigfoot' priority -1 to hints [Dec 6 22:45:53] DEBUG[10062]: devicestate.c:344 _ast_device_state: No provider found, checking channel drivers for sip - bigfoot-s [Dec 6 22:45:53] DEBUG[10062]: pbx.c:5916 ast_add_hint: HINTS: Adding hint ::sip:bigfoot-s: sip/bigfoot-s [Dec 6 22:45:53] DEBUG[10062]: pbx.c:10258 ast_add_extension2_lockopt: Added extension '::sip:bigfoot-s' priority -1 to hints (0xb51cd8b8) [Dec 6 22:45:53] -- Added extension '::sip:bigfoot-s' priority -1 to hints [Dec 6 22:45:53] DEBUG[28895]: dnsmgr.c:206 dnsmgr_refresh: Not an IPv4 nor IPv6 address, cannot get port. [Dec 6 22:45:53] DEBUG[10062]: devicestate.c:344 _ast_device_state: No provider found, checking channel drivers for SIP - bigfoot-s [Dec 6 22:45:53] DEBUG[10062]: pbx.c:5916 ast_add_hint: HINTS: Adding hint +4536949391: SIP/bigfoot&SIP/bigfoot-s [Dec 6 22:45:53] DEBUG[10062]: pbx.c:10258 ast_add_extension2_lockopt: Added extension '+4536949391' priority -1 to hints (0xb51cd8b8) [Dec 6 22:45:53] DEBUG[28895]: dnsmgr.c:201 dnsmgr_refresh: refreshing 'sipbalance4-4.1und1.de.' [Dec 6 22:45:53] -- Added extension '+4536949391' priority -1 to hints [Dec 6 22:45:53] DEBUG[10062]: devicestate.c:340 _ast_device_state: Checking if I can find provider for "custom" - number: tln_+4536949391_CFU_a [Dec 6 22:45:53] DEBUG[10062]: devicestate.c:417 getproviderstate: Checking provider Custom with custom [Dec 6 22:45:53] DEBUG[10062]: db.c:376 db_get_common: Unable to find key 'tln_+4536949391_CFU_a' in family 'CustomDevstate' [Dec 6 22:45:53] DEBUG[10062]: pbx.c:5916 ast_add_hint: HINTS: Adding hint T_CFU_+4536949391: custom:tln_+4536949391_CFU_a [Dec 6 22:45:53] DEBUG[10062]: pbx.c:10258 ast_add_extension2_lockopt: Added extension 'T_CFU_+4536949391' priority -1 to hints (0xb51cd8b8) [Dec 6 22:45:53] -- Added extension 'T_CFU_+4536949391' priority -1 to hints [Dec 6 22:45:53] DEBUG[10062]: devicestate.c:340 _ast_device_state: Checking if I can find provider for "custom" - number: tln_+4536949391_CFNR_a [Dec 6 22:45:53] DEBUG[10062]: devicestate.c:417 getproviderstate: Checking provider Custom with custom [Dec 6 22:45:53] DEBUG[10062]: db.c:376 db_get_common: Unable to find key 'tln_+4536949391_CFNR_a' in family 'CustomDevstate' [Dec 6 22:45:53] DEBUG[10062]: pbx.c:5916 ast_add_hint: HINTS: Adding hint T_CFNR_+4536949391: custom:tln_+4536949391_CFNR_a [Dec 6 22:45:53] DEBUG[10062]: pbx.c:10258 ast_add_extension2_lockopt: Added extension 'T_CFNR_+4536949391' priority -1 to hints (0xb51cd8b8) [Dec 6 22:45:53] -- Added extension 'T_CFNR_+4536949391' priority -1 to hints [Dec 6 22:45:53] DEBUG[10062]: devicestate.c:340 _ast_device_state: Checking if I can find provider for "custom" - number: tln_+4536949391_CFALD_a [Dec 6 22:45:53] DEBUG[10062]: devicestate.c:417 getproviderstate: Checking provider Custom with custom [Dec 6 22:45:53] DEBUG[10062]: db.c:376 db_get_common: Unable to find key 'tln_+4536949391_CFALD_a' in family 'CustomDevstate' [Dec 6 22:45:53] DEBUG[10062]: pbx.c:5916 ast_add_hint: HINTS: Adding hint T_CFALD_+4536949391: custom:tln_+4536949391_CFALD_a [Dec 6 22:45:53] DEBUG[10062]: pbx.c:10258 ast_add_extension2_lockopt: Added extension 'T_CFALD_+4536949391' priority -1 to hints (0xb51cd8b8) [Dec 6 22:45:53] -- Added extension 'T_CFALD_+4536949391' priority -1 to hints [Dec 6 22:45:53] DEBUG[10062]: devicestate.c:340 _ast_device_state: Checking if I can find provider for "custom" - number: tln_+4536949391_CFB_a [Dec 6 22:45:53] DEBUG[10062]: devicestate.c:417 getproviderstate: Checking provider Custom with custom [Dec 6 22:45:53] DEBUG[10062]: db.c:376 db_get_common: Unable to find key 'tln_+4536949391_CFB_a' in family 'CustomDevstate' [Dec 6 22:45:53] DEBUG[10062]: pbx.c:5916 ast_add_hint: HINTS: Adding hint T_CFB_+4536949391: custom:tln_+4536949391_CFB_a [Dec 6 22:45:53] DEBUG[10062]: pbx.c:10258 ast_add_extension2_lockopt: Added extension 'T_CFB_+4536949391' priority -1 to hints (0xb51cd8b8) [Dec 6 22:45:53] -- Added extension 'T_CFB_+4536949391' priority -1 to hints [Dec 6 22:45:53] DEBUG[10062]: devicestate.c:344 _ast_device_state: No provider found, checking channel drivers for SIP - bigfoot-s [Dec 6 22:45:53] DEBUG[10062]: pbx.c:5916 ast_add_hint: HINTS: Adding hint +4582131279: SIP/bigfoot&SIP/bigfoot-s [Dec 6 22:45:53] DEBUG[10062]: pbx.c:10258 ast_add_extension2_lockopt: Added extension '+4582131279' priority -1 to hints (0xb51cd8b8) [Dec 6 22:45:53] -- Added extension '+4582131279' priority -1 to hints [Dec 6 22:45:53] DEBUG[10062]: devicestate.c:340 _ast_device_state: Checking if I can find provider for "custom" - number: tln_+4582131279_CFU_a [Dec 6 22:45:53] DEBUG[10062]: devicestate.c:417 getproviderstate: Checking provider Custom with custom [Dec 6 22:45:53] DEBUG[10062]: db.c:376 db_get_common: Unable to find key 'tln_+4582131279_CFU_a' in family 'CustomDevstate' [Dec 6 22:45:53] DEBUG[10062]: pbx.c:5916 ast_add_hint: HINTS: Adding hint T_CFU_+4582131279: custom:tln_+4582131279_CFU_a [Dec 6 22:45:53] DEBUG[10062]: pbx.c:10258 ast_add_extension2_lockopt: Added extension 'T_CFU_+4582131279' priority -1 to hints (0xb51cd8b8) [Dec 6 22:45:53] -- Added extension 'T_CFU_+4582131279' priority -1 to hints [Dec 6 22:45:53] DEBUG[10062]: devicestate.c:340 _ast_device_state: Checking if I can find provider for "custom" - number: tln_+4582131279_CFNR_a [Dec 6 22:45:53] DEBUG[10062]: devicestate.c:417 getproviderstate: Checking provider Custom with custom [Dec 6 22:45:53] DEBUG[10062]: db.c:376 db_get_common: Unable to find key 'tln_+4582131279_CFNR_a' in family 'CustomDevstate' [Dec 6 22:45:53] DEBUG[10062]: pbx.c:5916 ast_add_hint: HINTS: Adding hint T_CFNR_+4582131279: custom:tln_+4582131279_CFNR_a [Dec 6 22:45:53] DEBUG[10062]: pbx.c:10258 ast_add_extension2_lockopt: Added extension 'T_CFNR_+4582131279' priority -1 to hints (0xb51cd8b8) [Dec 6 22:45:53] -- Added extension 'T_CFNR_+4582131279' priority -1 to hints [Dec 6 22:45:53] DEBUG[10062]: devicestate.c:340 _ast_device_state: Checking if I can find provider for "custom" - number: tln_+4582131279_CFALD_a [Dec 6 22:45:53] DEBUG[10062]: devicestate.c:417 getproviderstate: Checking provider Custom with custom [Dec 6 22:45:53] DEBUG[10062]: db.c:376 db_get_common: Unable to find key 'tln_+4582131279_CFALD_a' in family 'CustomDevstate' [Dec 6 22:45:53] DEBUG[10062]: pbx.c:5916 ast_add_hint: HINTS: Adding hint T_CFALD_+4582131279: custom:tln_+4582131279_CFALD_a [Dec 6 22:45:53] DEBUG[10062]: pbx.c:10258 ast_add_extension2_lockopt: Added extension 'T_CFALD_+4582131279' priority -1 to hints (0xb51cd8b8) [Dec 6 22:45:53] -- Added extension 'T_CFALD_+4582131279' priority -1 to hints [Dec 6 22:45:53] DEBUG[10062]: devicestate.c:340 _ast_device_state: Checking if I can find provider for "custom" - number: tln_+4582131279_CFB_a [Dec 6 22:45:53] DEBUG[10062]: devicestate.c:417 getproviderstate: Checking provider Custom with custom [Dec 6 22:45:53] DEBUG[10062]: db.c:376 db_get_common: Unable to find key 'tln_+4582131279_CFB_a' in family 'CustomDevstate' [Dec 6 22:45:53] DEBUG[10062]: pbx.c:5916 ast_add_hint: HINTS: Adding hint T_CFB_+4582131279: custom:tln_+4582131279_CFB_a [Dec 6 22:45:53] DEBUG[10062]: pbx.c:10258 ast_add_extension2_lockopt: Added extension 'T_CFB_+4582131279' priority -1 to hints (0xb51cd8b8) [Dec 6 22:45:53] -- Added extension 'T_CFB_+4582131279' priority -1 to hints [Dec 6 22:45:53] DEBUG[10062]: devicestate.c:344 _ast_device_state: No provider found, checking channel drivers for SIP - bigfoot-s [Dec 6 22:45:53] DEBUG[10062]: pbx.c:5916 ast_add_hint: HINTS: Adding hint +4946157494420: SIP/bigfoot&SIP/bigfoot-s [Dec 6 22:45:53] DEBUG[10062]: pbx.c:10258 ast_add_extension2_lockopt: Added extension '+4946157494420' priority -1 to hints (0xb51cd8b8) [Dec 6 22:45:53] -- Added extension '+4946157494420' priority -1 to hints [Dec 6 22:45:53] DEBUG[10062]: devicestate.c:340 _ast_device_state: Checking if I can find provider for "custom" - number: tln_+4946157494420_CFU_a [Dec 6 22:45:53] DEBUG[10062]: devicestate.c:417 getproviderstate: Checking provider Custom with custom [Dec 6 22:45:53] DEBUG[10062]: db.c:376 db_get_common: Unable to find key 'tln_+4946157494420_CFU_a' in family 'CustomDevstate' [Dec 6 22:45:53] DEBUG[10062]: pbx.c:5916 ast_add_hint: HINTS: Adding hint T_CFU_+4946157494420: custom:tln_+4946157494420_CFU_a [Dec 6 22:45:53] DEBUG[10062]: pbx.c:10258 ast_add_extension2_lockopt: Added extension 'T_CFU_+4946157494420' priority -1 to hints (0xb51cd8b8) [Dec 6 22:45:53] -- Added extension 'T_CFU_+4946157494420' priority -1 to hints [Dec 6 22:45:53] DEBUG[10062]: devicestate.c:340 _ast_device_state: Checking if I can find provider for "custom" - number: tln_+4946157494420_CFNR_a [Dec 6 22:45:53] DEBUG[10062]: devicestate.c:417 getproviderstate: Checking provider Custom with custom [Dec 6 22:45:53] DEBUG[10062]: db.c:376 db_get_common: Unable to find key 'tln_+4946157494420_CFNR_a' in family 'CustomDevstate' [Dec 6 22:45:53] DEBUG[10062]: pbx.c:5916 ast_add_hint: HINTS: Adding hint T_CFNR_+4946157494420: custom:tln_+4946157494420_CFNR_a [Dec 6 22:45:53] DEBUG[10062]: pbx.c:10258 ast_add_extension2_lockopt: Added extension 'T_CFNR_+4946157494420' priority -1 to hints (0xb51cd8b8) [Dec 6 22:45:53] -- Added extension 'T_CFNR_+4946157494420' priority -1 to hints [Dec 6 22:45:53] DEBUG[10062]: devicestate.c:340 _ast_device_state: Checking if I can find provider for "custom" - number: tln_+4946157494420_CFALD_a [Dec 6 22:45:53] DEBUG[10062]: devicestate.c:417 getproviderstate: Checking provider Custom with custom [Dec 6 22:45:53] DEBUG[10062]: db.c:376 db_get_common: Unable to find key 'tln_+4946157494420_CFALD_a' in family 'CustomDevstate' [Dec 6 22:45:53] DEBUG[10062]: pbx.c:5916 ast_add_hint: HINTS: Adding hint T_CFALD_+4946157494420: custom:tln_+4946157494420_CFALD_a [Dec 6 22:45:53] DEBUG[10062]: pbx.c:10258 ast_add_extension2_lockopt: Added extension 'T_CFALD_+4946157494420' priority -1 to hints (0xb51cd8b8) [Dec 6 22:45:53] -- Added extension 'T_CFALD_+4946157494420' priority -1 to hints [Dec 6 22:45:53] DEBUG[10062]: devicestate.c:340 _ast_device_state: Checking if I can find provider for "custom" - number: tln_+4946157494420_CFB_a [Dec 6 22:45:53] DEBUG[10062]: devicestate.c:417 getproviderstate: Checking provider Custom with custom [Dec 6 22:45:53] DEBUG[28895]: netsock2.c:138 ast_sockaddr_split_hostport: Splitting 'sipbalance4-4.1und1.de.' into... [Dec 6 22:45:53] DEBUG[28895]: netsock2.c:192 ast_sockaddr_split_hostport: ...host 'sipbalance4-4.1und1.de.' and port ''. [Dec 6 22:45:53] DEBUG[10062]: db.c:376 db_get_common: Unable to find key 'tln_+4946157494420_CFB_a' in family 'CustomDevstate' [Dec 6 22:45:53] DEBUG[10062]: pbx.c:5916 ast_add_hint: HINTS: Adding hint T_CFB_+4946157494420: custom:tln_+4946157494420_CFB_a [Dec 6 22:45:53] DEBUG[10062]: pbx.c:10258 ast_add_extension2_lockopt: Added extension 'T_CFB_+4946157494420' priority -1 to hints (0xb51cd8b8) [Dec 6 22:45:53] -- Added extension 'T_CFB_+4946157494420' priority -1 to hints [Dec 6 22:45:53] DEBUG[10062]: devicestate.c:344 _ast_device_state: No provider found, checking channel drivers for SIP - bigfoot-s [Dec 6 22:45:53] DEBUG[10062]: pbx.c:5916 ast_add_hint: HINTS: Adding hint +4946157498937: SIP/bigfoot&SIP/bigfoot-s [Dec 6 22:45:53] DEBUG[10062]: pbx.c:10258 ast_add_extension2_lockopt: Added extension '+4946157498937' priority -1 to hints (0xb51cd8b8) [Dec 6 22:45:53] -- Added extension '+4946157498937' priority -1 to hints [Dec 6 22:45:53] DEBUG[10062]: devicestate.c:340 _ast_device_state: Checking if I can find provider for "custom" - number: tln_+4946157498937_CFU_a [Dec 6 22:45:53] DEBUG[10062]: devicestate.c:417 getproviderstate: Checking provider Custom with custom [Dec 6 22:45:53] DEBUG[10062]: db.c:376 db_get_common: Unable to find key 'tln_+4946157498937_CFU_a' in family 'CustomDevstate' [Dec 6 22:45:53] DEBUG[10062]: pbx.c:5916 ast_add_hint: HINTS: Adding hint T_CFU_+4946157498937: custom:tln_+4946157498937_CFU_a [Dec 6 22:45:53] DEBUG[10062]: pbx.c:10258 ast_add_extension2_lockopt: Added extension 'T_CFU_+4946157498937' priority -1 to hints (0xb51cd8b8) [Dec 6 22:45:53] -- Added extension 'T_CFU_+4946157498937' priority -1 to hints [Dec 6 22:45:53] DEBUG[10062]: devicestate.c:340 _ast_device_state: Checking if I can find provider for "custom" - number: tln_+4946157498937_CFNR_a [Dec 6 22:45:53] DEBUG[10062]: devicestate.c:417 getproviderstate: Checking provider Custom with custom [Dec 6 22:45:53] DEBUG[10062]: db.c:376 db_get_common: Unable to find key 'tln_+4946157498937_CFNR_a' in family 'CustomDevstate' [Dec 6 22:45:53] DEBUG[10062]: pbx.c:5916 ast_add_hint: HINTS: Adding hint T_CFNR_+4946157498937: custom:tln_+4946157498937_CFNR_a [Dec 6 22:45:53] DEBUG[10062]: pbx.c:10258 ast_add_extension2_lockopt: Added extension 'T_CFNR_+4946157498937' priority -1 to hints (0xb51cd8b8) [Dec 6 22:45:53] -- Added extension 'T_CFNR_+4946157498937' priority -1 to hints [Dec 6 22:45:53] DEBUG[10062]: devicestate.c:340 _ast_device_state: Checking if I can find provider for "custom" - number: tln_+4946157498937_CFALD_a [Dec 6 22:45:53] DEBUG[10062]: devicestate.c:417 getproviderstate: Checking provider Custom with custom [Dec 6 22:45:53] DEBUG[10062]: db.c:376 db_get_common: Unable to find key 'tln_+4946157498937_CFALD_a' in family 'CustomDevstate' [Dec 6 22:45:53] DEBUG[10062]: pbx.c:5916 ast_add_hint: HINTS: Adding hint T_CFALD_+4946157498937: custom:tln_+4946157498937_CFALD_a [Dec 6 22:45:53] DEBUG[10062]: pbx.c:10258 ast_add_extension2_lockopt: Added extension 'T_CFALD_+4946157498937' priority -1 to hints (0xb51cd8b8) [Dec 6 22:45:53] -- Added extension 'T_CFALD_+4946157498937' priority -1 to hints [Dec 6 22:45:53] DEBUG[10062]: devicestate.c:340 _ast_device_state: Checking if I can find provider for "custom" - number: tln_+4946157498937_CFB_a [Dec 6 22:45:53] DEBUG[10062]: devicestate.c:417 getproviderstate: Checking provider Custom with custom [Dec 6 22:45:53] DEBUG[10062]: db.c:376 db_get_common: Unable to find key 'tln_+4946157498937_CFB_a' in family 'CustomDevstate' [Dec 6 22:45:53] DEBUG[10062]: pbx.c:5916 ast_add_hint: HINTS: Adding hint T_CFB_+4946157498937: custom:tln_+4946157498937_CFB_a [Dec 6 22:45:53] DEBUG[10062]: pbx.c:10258 ast_add_extension2_lockopt: Added extension 'T_CFB_+4946157498937' priority -1 to hints (0xb51cd8b8) [Dec 6 22:45:53] -- Added extension 'T_CFB_+4946157498937' priority -1 to hints [Dec 6 22:45:53] DEBUG[10062]: devicestate.c:344 _ast_device_state: No provider found, checking channel drivers for SIP - bigfoot-s [Dec 6 22:45:53] DEBUG[10062]: pbx.c:5916 ast_add_hint: HINTS: Adding hint bigfoot: SIP/bigfoot&SIP/bigfoot-s [Dec 6 22:45:53] DEBUG[10062]: pbx.c:10258 ast_add_extension2_lockopt: Added extension 'bigfoot' priority -1 to hints (0xb51cd8b8) [Dec 6 22:45:53] -- Added extension 'bigfoot' priority -1 to hints [Dec 6 22:45:53] DEBUG[10062]: devicestate.c:340 _ast_device_state: Checking if I can find provider for "custom" - number: tln_bigfoot_CFU_a [Dec 6 22:45:53] DEBUG[10062]: devicestate.c:417 getproviderstate: Checking provider Custom with custom [Dec 6 22:45:53] DEBUG[10062]: db.c:376 db_get_common: Unable to find key 'tln_bigfoot_CFU_a' in family 'CustomDevstate' [Dec 6 22:45:53] DEBUG[10062]: pbx.c:5916 ast_add_hint: HINTS: Adding hint T_CFU_bigfoot: custom:tln_bigfoot_CFU_a [Dec 6 22:45:53] DEBUG[10062]: pbx.c:10258 ast_add_extension2_lockopt: Added extension 'T_CFU_bigfoot' priority -1 to hints (0xb51cd8b8) [Dec 6 22:45:53] -- Added extension 'T_CFU_bigfoot' priority -1 to hints [Dec 6 22:45:53] DEBUG[10062]: devicestate.c:340 _ast_device_state: Checking if I can find provider for "custom" - number: tln_bigfoot_CFNR_a [Dec 6 22:45:53] DEBUG[10062]: devicestate.c:417 getproviderstate: Checking provider Custom with custom [Dec 6 22:45:53] DEBUG[10062]: db.c:376 db_get_common: Unable to find key 'tln_bigfoot_CFNR_a' in family 'CustomDevstate' [Dec 6 22:45:53] DEBUG[10062]: pbx.c:5916 ast_add_hint: HINTS: Adding hint T_CFNR_bigfoot: custom:tln_bigfoot_CFNR_a [Dec 6 22:45:53] DEBUG[10062]: pbx.c:10258 ast_add_extension2_lockopt: Added extension 'T_CFNR_bigfoot' priority -1 to hints (0xb51cd8b8) [Dec 6 22:45:53] -- Added extension 'T_CFNR_bigfoot' priority -1 to hints [Dec 6 22:45:53] DEBUG[10062]: devicestate.c:340 _ast_device_state: Checking if I can find provider for "custom" - number: tln_bigfoot_CFALD_a [Dec 6 22:45:53] DEBUG[10062]: devicestate.c:417 getproviderstate: Checking provider Custom with custom [Dec 6 22:45:53] DEBUG[10062]: db.c:376 db_get_common: Unable to find key 'tln_bigfoot_CFALD_a' in family 'CustomDevstate' [Dec 6 22:45:53] DEBUG[10062]: pbx.c:5916 ast_add_hint: HINTS: Adding hint T_CFALD_bigfoot: custom:tln_bigfoot_CFALD_a [Dec 6 22:45:53] DEBUG[10062]: pbx.c:10258 ast_add_extension2_lockopt: Added extension 'T_CFALD_bigfoot' priority -1 to hints (0xb51cd8b8) [Dec 6 22:45:53] -- Added extension 'T_CFALD_bigfoot' priority -1 to hints [Dec 6 22:45:53] DEBUG[10062]: devicestate.c:340 _ast_device_state: Checking if I can find provider for "custom" - number: tln_bigfoot_CFB_a [Dec 6 22:45:53] DEBUG[10062]: devicestate.c:417 getproviderstate: Checking provider Custom with custom [Dec 6 22:45:53] DEBUG[10062]: db.c:376 db_get_common: Unable to find key 'tln_bigfoot_CFB_a' in family 'CustomDevstate' [Dec 6 22:45:53] DEBUG[10062]: pbx.c:5916 ast_add_hint: HINTS: Adding hint T_CFB_bigfoot: custom:tln_bigfoot_CFB_a [Dec 6 22:45:53] DEBUG[10062]: pbx.c:10258 ast_add_extension2_lockopt: Added extension 'T_CFB_bigfoot' priority -1 to hints (0xb51cd8b8) [Dec 6 22:45:53] -- Added extension 'T_CFB_bigfoot' priority -1 to hints [Dec 6 22:45:53] DEBUG[10062]: devicestate.c:344 _ast_device_state: No provider found, checking channel drivers for iax2 - hbc-grau [Dec 6 22:45:53] DEBUG[10062]: chan_iax2.c:14465 iax2_devicestate: Checking device state for device hbc-grau [Dec 6 22:45:53] DEBUG[10062]: chan_iax2.c:14473 iax2_devicestate: Found peer. What's device state of hbc-grau? addr=1340352465, defaddr=0 maxms=2000, lastms=73 [Dec 6 22:45:53] DEBUG[10062]: pbx.c:5916 ast_add_hint: HINTS: Adding hint +494022756090: iax2/hbc-grau [Dec 6 22:45:53] DEBUG[10062]: pbx.c:10258 ast_add_extension2_lockopt: Added extension '+494022756090' priority -1 to hints (0xb51cd8b8) [Dec 6 22:45:53] -- Added extension '+494022756090' priority -1 to hints [Dec 6 22:45:53] DEBUG[10062]: devicestate.c:344 _ast_device_state: No provider found, checking channel drivers for iax2 - hbc-grau [Dec 6 22:45:53] DEBUG[28895]: dnsmgr.c:206 dnsmgr_refresh: Not an IPv4 nor IPv6 address, cannot get port. [Dec 6 22:45:53] DEBUG[10062]: chan_iax2.c:14465 iax2_devicestate: Checking device state for device hbc-grau [Dec 6 22:45:53] DEBUG[10062]: chan_iax2.c:14473 iax2_devicestate: Found peer. What's device state of hbc-grau? addr=1340352465, defaddr=0 maxms=2000, lastms=73 [Dec 6 22:45:53] DEBUG[10062]: pbx.c:5916 ast_add_hint: HINTS: Adding hint +49402276389: iax2/hbc-grau [Dec 6 22:45:53] DEBUG[10062]: pbx.c:10258 ast_add_extension2_lockopt: Added extension '+49402276389' priority -1 to hints (0xb51cd8b8) [Dec 6 22:45:53] DEBUG[28895]: dnsmgr.c:201 dnsmgr_refresh: refreshing 'sipbalance5-1.1und1.de.' [Dec 6 22:45:53] -- Added extension '+49402276389' priority -1 to hints [Dec 6 22:45:53] DEBUG[10062]: devicestate.c:344 _ast_device_state: No provider found, checking channel drivers for iax2 - hbc-grau [Dec 6 22:45:53] DEBUG[10062]: chan_iax2.c:14465 iax2_devicestate: Checking device state for device hbc-grau [Dec 6 22:45:53] DEBUG[10062]: chan_iax2.c:14473 iax2_devicestate: Found peer. What's device state of hbc-grau? addr=1340352465, defaddr=0 maxms=2000, lastms=73 [Dec 6 22:45:53] DEBUG[10062]: pbx.c:5916 ast_add_hint: HINTS: Adding hint +49402276775: iax2/hbc-grau [Dec 6 22:45:53] DEBUG[10062]: pbx.c:10258 ast_add_extension2_lockopt: Added extension '+49402276775' priority -1 to hints (0xb51cd8b8) [Dec 6 22:45:53] -- Added extension '+49402276775' priority -1 to hints [Dec 6 22:45:53] DEBUG[10062]: pbx.c:5916 ast_add_hint: HINTS: Adding hint +49461182073: SIP/shjans [Dec 6 22:45:53] DEBUG[10062]: pbx.c:10258 ast_add_extension2_lockopt: Added extension '+49461182073' priority -1 to hints (0xb51cd8b8) [Dec 6 22:45:53] -- Added extension '+49461182073' priority -1 to hints [Dec 6 22:45:53] DEBUG[10062]: devicestate.c:344 _ast_device_state: No provider found, checking channel drivers for SIP - jane [Dec 6 22:45:53] DEBUG[10062]: pbx.c:5916 ast_add_hint: HINTS: Adding hint +494611824359: SIP/jane [Dec 6 22:45:53] DEBUG[10062]: pbx.c:10258 ast_add_extension2_lockopt: Added extension '+494611824359' priority -1 to hints (0xb51cd8b8) [Dec 6 22:45:53] -- Added extension '+494611824359' priority -1 to hints [Dec 6 22:45:53] DEBUG[10062]: devicestate.c:344 _ast_device_state: No provider found, checking channel drivers for iax2 - hbc-hah [Dec 6 22:45:53] DEBUG[10062]: chan_iax2.c:14465 iax2_devicestate: Checking device state for device hbc-hah [Dec 6 22:45:53] DEBUG[10062]: chan_iax2.c:14473 iax2_devicestate: Found peer. What's device state of hbc-hah? addr=1401371040, defaddr=0 maxms=2000, lastms=23 [Dec 6 22:45:53] DEBUG[10062]: pbx.c:5916 ast_add_hint: HINTS: Adding hint +4946124499: iax2/hbc-hah [Dec 6 22:45:53] DEBUG[10062]: pbx.c:10258 ast_add_extension2_lockopt: Added extension '+4946124499' priority -1 to hints (0xb51cd8b8) [Dec 6 22:45:53] -- Added extension '+4946124499' priority -1 to hints [Dec 6 22:45:53] DEBUG[10062]: devicestate.c:344 _ast_device_state: No provider found, checking channel drivers for iax2 - hbc-hah [Dec 6 22:45:53] DEBUG[10062]: chan_iax2.c:14465 iax2_devicestate: Checking device state for device hbc-hah [Dec 6 22:45:53] DEBUG[10062]: chan_iax2.c:14473 iax2_devicestate: Found peer. What's device state of hbc-hah? addr=1401371040, defaddr=0 maxms=2000, lastms=23 [Dec 6 22:45:53] DEBUG[10062]: pbx.c:5916 ast_add_hint: HINTS: Adding hint +4946126237: iax2/hbc-hah [Dec 6 22:45:53] DEBUG[10062]: pbx.c:10258 ast_add_extension2_lockopt: Added extension '+4946126237' priority -1 to hints (0xb51cd8b8) [Dec 6 22:45:53] -- Added extension '+4946126237' priority -1 to hints [Dec 6 22:45:53] DEBUG[10062]: devicestate.c:344 _ast_device_state: No provider found, checking channel drivers for SIP - jane [Dec 6 22:45:53] DEBUG[10062]: pbx.c:5916 ast_add_hint: HINTS: Adding hint +494615205035: SIP/jane [Dec 6 22:45:53] DEBUG[10062]: pbx.c:10258 ast_add_extension2_lockopt: Added extension '+494615205035' priority -1 to hints (0xb51cd8b8) [Dec 6 22:45:53] -- Added extension '+494615205035' priority -1 to hints [Dec 6 22:45:53] DEBUG[10062]: devicestate.c:344 _ast_device_state: No provider found, checking channel drivers for iax2 - hbc-hah [Dec 6 22:45:53] DEBUG[10062]: chan_iax2.c:14465 iax2_devicestate: Checking device state for device hbc-hah [Dec 6 22:45:53] DEBUG[10062]: chan_iax2.c:14473 iax2_devicestate: Found peer. What's device state of hbc-hah? addr=1401371040, defaddr=0 maxms=2000, lastms=23 [Dec 6 22:45:53] DEBUG[10062]: pbx.c:5916 ast_add_hint: HINTS: Adding hint +4946157494512: iax2/hbc-hah [Dec 6 22:45:53] DEBUG[10062]: pbx.c:10258 ast_add_extension2_lockopt: Added extension '+4946157494512' priority -1 to hints (0xb51cd8b8) [Dec 6 22:45:53] -- Added extension '+4946157494512' priority -1 to hints [Dec 6 22:45:53] DEBUG[10062]: devicestate.c:344 _ast_device_state: No provider found, checking channel drivers for iax2 - hbc-pgsw [Dec 6 22:45:53] DEBUG[10062]: chan_iax2.c:14465 iax2_devicestate: Checking device state for device hbc-pgsw [Dec 6 22:45:53] DEBUG[10062]: chan_iax2.c:14473 iax2_devicestate: Found peer. What's device state of hbc-pgsw? addr=1467626513, defaddr=0 maxms=2000, lastms=55 [Dec 6 22:45:53] DEBUG[10062]: pbx.c:5916 ast_add_hint: HINTS: Adding hint +4946157494537: iax2/hbc-pgsw [Dec 6 22:45:53] DEBUG[10062]: pbx.c:10258 ast_add_extension2_lockopt: Added extension '+4946157494537' priority -1 to hints (0xb51cd8b8) [Dec 6 22:45:53] -- Added extension '+4946157494537' priority -1 to hints [Dec 6 22:45:53] DEBUG[10062]: devicestate.c:344 _ast_device_state: No provider found, checking channel drivers for iax2 - hbc-pgsw [Dec 6 22:45:53] DEBUG[10062]: chan_iax2.c:14465 iax2_devicestate: Checking device state for device hbc-pgsw [Dec 6 22:45:53] DEBUG[10062]: chan_iax2.c:14473 iax2_devicestate: Found peer. What's device state of hbc-pgsw? addr=1467626513, defaddr=0 maxms=2000, lastms=55 [Dec 6 22:45:53] DEBUG[10062]: pbx.c:5916 ast_add_hint: HINTS: Adding hint +494618400550: iax2/hbc-pgsw [Dec 6 22:45:53] DEBUG[10062]: pbx.c:10258 ast_add_extension2_lockopt: Added extension '+494618400550' priority -1 to hints (0xb51cd8b8) [Dec 6 22:45:53] -- Added extension '+494618400550' priority -1 to hints [Dec 6 22:45:53] DEBUG[10062]: devicestate.c:344 _ast_device_state: No provider found, checking channel drivers for iax2 - hbc-hbst [Dec 6 22:45:53] DEBUG[10062]: chan_iax2.c:14465 iax2_devicestate: Checking device state for device hbc-hbst [Dec 6 22:45:53] DEBUG[10062]: chan_iax2.c:14473 iax2_devicestate: Found peer. What's device state of hbc-hbst? addr=1530513477, defaddr=0 maxms=2000, lastms=54 [Dec 6 22:45:53] DEBUG[10062]: pbx.c:5916 ast_add_hint: HINTS: Adding hint +4946713357: iax2/hbc-hbst [Dec 6 22:45:53] DEBUG[10062]: pbx.c:10258 ast_add_extension2_lockopt: Added extension '+4946713357' priority -1 to hints (0xb51cd8b8) [Dec 6 22:45:53] -- Added extension '+4946713357' priority -1 to hints [Dec 6 22:45:53] DEBUG[10062]: devicestate.c:344 _ast_device_state: No provider found, checking channel drivers for iax2 - hbc-hbst [Dec 6 22:45:53] DEBUG[10062]: chan_iax2.c:14465 iax2_devicestate: Checking device state for device hbc-hbst [Dec 6 22:45:53] DEBUG[10062]: chan_iax2.c:14473 iax2_devicestate: Found peer. What's device state of hbc-hbst? addr=1530513477, defaddr=0 maxms=2000, lastms=54 [Dec 6 22:45:53] DEBUG[10062]: pbx.c:5916 ast_add_hint: HINTS: Adding hint +4946716371: iax2/hbc-hbst [Dec 6 22:45:53] DEBUG[10062]: pbx.c:10258 ast_add_extension2_lockopt: Added extension '+4946716371' priority -1 to hints (0xb51cd8b8) [Dec 6 22:45:53] -- Added extension '+4946716371' priority -1 to hints [Dec 6 22:45:53] DEBUG[10062]: devicestate.c:344 _ast_device_state: No provider found, checking channel drivers for iax2 - hbc-hbst [Dec 6 22:45:53] DEBUG[10062]: chan_iax2.c:14465 iax2_devicestate: Checking device state for device hbc-hbst [Dec 6 22:45:53] DEBUG[10062]: chan_iax2.c:14473 iax2_devicestate: Found peer. What's device state of hbc-hbst? addr=1530513477, defaddr=0 maxms=2000, lastms=54 [Dec 6 22:45:53] DEBUG[10062]: pbx.c:5916 ast_add_hint: HINTS: Adding hint +494671931399: iax2/hbc-hbst [Dec 6 22:45:53] DEBUG[10062]: pbx.c:10258 ast_add_extension2_lockopt: Added extension '+494671931399' priority -1 to hints (0xb51cd8b8) [Dec 6 22:45:53] -- Added extension '+494671931399' priority -1 to hints [Dec 6 22:45:53] DEBUG[10062]: devicestate.c:344 _ast_device_state: No provider found, checking channel drivers for iax2 - hbc-hbst [Dec 6 22:45:53] DEBUG[10062]: chan_iax2.c:14465 iax2_devicestate: Checking device state for device hbc-hbst [Dec 6 22:45:53] DEBUG[10062]: chan_iax2.c:14473 iax2_devicestate: Found peer. What's device state of hbc-hbst? addr=1530513477, defaddr=0 maxms=2000, lastms=54 [Dec 6 22:45:53] DEBUG[10062]: pbx.c:5916 ast_add_hint: HINTS: Adding hint +4946719330793: iax2/hbc-hbst [Dec 6 22:45:53] DEBUG[10062]: pbx.c:10258 ast_add_extension2_lockopt: Added extension '+4946719330793' priority -1 to hints (0xb51cd8b8) [Dec 6 22:45:53] -- Added extension '+4946719330793' priority -1 to hints [Dec 6 22:45:53] DEBUG[10062]: devicestate.c:344 _ast_device_state: No provider found, checking channel drivers for iax2 - hbc-hbst [Dec 6 22:45:53] DEBUG[10062]: chan_iax2.c:14465 iax2_devicestate: Checking device state for device hbc-hbst [Dec 6 22:45:53] DEBUG[10062]: chan_iax2.c:14473 iax2_devicestate: Found peer. What's device state of hbc-hbst? addr=1530513477, defaddr=0 maxms=2000, lastms=54 [Dec 6 22:45:53] DEBUG[10062]: pbx.c:5916 ast_add_hint: HINTS: Adding hint +4946719438449: iax2/hbc-hbst [Dec 6 22:45:53] DEBUG[10062]: pbx.c:10258 ast_add_extension2_lockopt: Added extension '+4946719438449' priority -1 to hints (0xb51cd8b8) [Dec 6 22:45:53] -- Added extension '+4946719438449' priority -1 to hints [Dec 6 22:45:53] DEBUG[10062]: devicestate.c:344 _ast_device_state: No provider found, checking channel drivers for iax2 - test1 [Dec 6 22:45:53] DEBUG[10062]: chan_iax2.c:14465 iax2_devicestate: Checking device state for device test1 [Dec 6 22:45:53] DEBUG[10062]: chan_iax2.c:14473 iax2_devicestate: Found peer. What's device state of test1? addr=0, defaddr=0 maxms=2000, lastms=0 [Dec 6 22:45:53] DEBUG[10062]: devicestate.c:344 _ast_device_state: No provider found, checking channel drivers for SIP - test1 [Dec 6 22:45:53] DEBUG[10062]: pbx.c:5916 ast_add_hint: HINTS: Adding hint 11771: iax2/test1&SIP/test1 [Dec 6 22:45:53] DEBUG[10062]: pbx.c:10258 ast_add_extension2_lockopt: Added extension '11771' priority -1 to hints (0xb51cd8b8) [Dec 6 22:45:53] -- Added extension '11771' priority -1 to hints [Dec 6 22:45:53] DEBUG[10062]: devicestate.c:344 _ast_device_state: No provider found, checking channel drivers for iax2 - test2 [Dec 6 22:45:53] DEBUG[10062]: chan_iax2.c:14465 iax2_devicestate: Checking device state for device test2 [Dec 6 22:45:53] DEBUG[10062]: chan_iax2.c:14473 iax2_devicestate: Found peer. What's device state of test2? addr=0, defaddr=0 maxms=2000, lastms=0 [Dec 6 22:45:53] DEBUG[28895]: netsock2.c:138 ast_sockaddr_split_hostport: Splitting 'sipbalance5-1.1und1.de.' into... [Dec 6 22:45:53] DEBUG[10062]: devicestate.c:344 _ast_device_state: No provider found, checking channel drivers for SIP - test2 [Dec 6 22:45:53] DEBUG[28895]: netsock2.c:192 ast_sockaddr_split_hostport: ...host 'sipbalance5-1.1und1.de.' and port ''. [Dec 6 22:45:53] DEBUG[10062]: pbx.c:5916 ast_add_hint: HINTS: Adding hint 11772: iax2/test2&SIP/test2 [Dec 6 22:45:53] DEBUG[10062]: pbx.c:10258 ast_add_extension2_lockopt: Added extension '11772' priority -1 to hints (0xb51cd8b8) [Dec 6 22:45:53] -- Added extension '11772' priority -1 to hints [Dec 6 22:45:53] DEBUG[10062]: devicestate.c:344 _ast_device_state: No provider found, checking channel drivers for iax2 - test3 [Dec 6 22:45:53] DEBUG[10062]: chan_iax2.c:14465 iax2_devicestate: Checking device state for device test3 [Dec 6 22:45:53] DEBUG[10062]: chan_iax2.c:14473 iax2_devicestate: Found peer. What's device state of test3? addr=0, defaddr=0 maxms=2000, lastms=0 [Dec 6 22:45:53] DEBUG[10062]: pbx.c:5916 ast_add_hint: HINTS: Adding hint 11773: iax2/test3&SIP/test3 [Dec 6 22:45:53] DEBUG[10062]: pbx.c:10258 ast_add_extension2_lockopt: Added extension '11773' priority -1 to hints (0xb51cd8b8) [Dec 6 22:45:53] -- Added extension '11773' priority -1 to hints [Dec 6 22:45:53] DEBUG[10062]: devicestate.c:344 _ast_device_state: No provider found, checking channel drivers for iax2 - test4 [Dec 6 22:45:53] DEBUG[10062]: chan_iax2.c:14465 iax2_devicestate: Checking device state for device test4 [Dec 6 22:45:53] DEBUG[10062]: chan_iax2.c:14473 iax2_devicestate: Found peer. What's device state of test4? addr=0, defaddr=0 maxms=2000, lastms=0 [Dec 6 22:45:53] DEBUG[10062]: devicestate.c:344 _ast_device_state: No provider found, checking channel drivers for SIP - test4 [Dec 6 22:45:53] DEBUG[10062]: pbx.c:5916 ast_add_hint: HINTS: Adding hint 11774: iax2/test4&SIP/test4 [Dec 6 22:45:53] DEBUG[10062]: pbx.c:10258 ast_add_extension2_lockopt: Added extension '11774' priority -1 to hints (0xb51cd8b8) [Dec 6 22:45:53] -- Added extension '11774' priority -1 to hints [Dec 6 22:45:53] DEBUG[10062]: devicestate.c:344 _ast_device_state: No provider found, checking channel drivers for iax2 - test5 [Dec 6 22:45:53] DEBUG[10062]: chan_iax2.c:14465 iax2_devicestate: Checking device state for device test5 [Dec 6 22:45:53] DEBUG[10062]: chan_iax2.c:14473 iax2_devicestate: Found peer. What's device state of test5? addr=0, defaddr=0 maxms=2000, lastms=0 [Dec 6 22:45:53] DEBUG[10062]: devicestate.c:344 _ast_device_state: No provider found, checking channel drivers for SIP - test5 [Dec 6 22:45:53] DEBUG[10062]: pbx.c:5916 ast_add_hint: HINTS: Adding hint 11775: iax2/test5&SIP/test5 [Dec 6 22:45:53] DEBUG[10062]: pbx.c:10258 ast_add_extension2_lockopt: Added extension '11775' priority -1 to hints (0xb51cd8b8) [Dec 6 22:45:53] -- Added extension '11775' priority -1 to hints [Dec 6 22:45:53] DEBUG[10062]: devicestate.c:344 _ast_device_state: No provider found, checking channel drivers for iax2 - test6 [Dec 6 22:45:53] DEBUG[10062]: chan_iax2.c:14465 iax2_devicestate: Checking device state for device test6 [Dec 6 22:45:53] DEBUG[10062]: chan_iax2.c:14473 iax2_devicestate: Found peer. What's device state of test6? addr=0, defaddr=0 maxms=2000, lastms=0 [Dec 6 22:45:53] DEBUG[10062]: devicestate.c:344 _ast_device_state: No provider found, checking channel drivers for SIP - test6 [Dec 6 22:45:53] DEBUG[10062]: pbx.c:5916 ast_add_hint: HINTS: Adding hint 11776: iax2/test6&SIP/test6 [Dec 6 22:45:53] DEBUG[10062]: pbx.c:10258 ast_add_extension2_lockopt: Added extension '11776' priority -1 to hints (0xb51cd8b8) [Dec 6 22:45:53] -- Added extension '11776' priority -1 to hints [Dec 6 22:45:53] DEBUG[10062]: devicestate.c:344 _ast_device_state: No provider found, checking channel drivers for SIP - 11779 [Dec 6 22:45:53] DEBUG[10062]: pbx.c:5916 ast_add_hint: HINTS: Adding hint 11779: SIP/11779 [Dec 6 22:45:53] DEBUG[10062]: pbx.c:10258 ast_add_extension2_lockopt: Added extension '11779' priority -1 to hints (0xb51cd8b8) [Dec 6 22:45:53] -- Added extension '11779' priority -1 to hints [Dec 6 22:45:53] DEBUG[10062]: devicestate.c:344 _ast_device_state: No provider found, checking channel drivers for iax2 - s-wuc [Dec 6 22:45:53] DEBUG[10062]: chan_iax2.c:14465 iax2_devicestate: Checking device state for device s-wuc [Dec 6 22:45:53] DEBUG[10062]: chan_iax2.c:14473 iax2_devicestate: Found peer. What's device state of s-wuc? addr=0, defaddr=0 maxms=2000, lastms=0 [Dec 6 22:45:53] DEBUG[10062]: pbx.c:5916 ast_add_hint: HINTS: Adding hint _+49402533029.: iax2/s-wuc [Dec 6 22:45:53] DEBUG[10062]: pbx.c:10258 ast_add_extension2_lockopt: Added extension '_+49402533029.' priority -1 to hints (0xb51cd8b8) [Dec 6 22:45:53] -- Added extension '_+49402533029.' priority -1 to hints [Dec 6 22:45:53] DEBUG[10062]: devicestate.c:344 _ast_device_state: No provider found, checking channel drivers for iax2 - s-mn24 [Dec 6 22:45:53] DEBUG[10062]: chan_iax2.c:14465 iax2_devicestate: Checking device state for device s-mn24 [Dec 6 22:45:53] DEBUG[10062]: chan_iax2.c:14473 iax2_devicestate: Found peer. What's device state of s-mn24? addr=1468776108, defaddr=0 maxms=2000, lastms=50 [Dec 6 22:45:53] DEBUG[10062]: pbx.c:5916 ast_add_hint: HINTS: Adding hint _+4946116845[0-5]!: iax2/s-mn24 [Dec 6 22:45:53] DEBUG[10062]: pbx.c:10258 ast_add_extension2_lockopt: Added extension '_+4946116845[0-5]!' priority -1 to hints (0xb51cd8b8) [Dec 6 22:45:53] -- Added extension '_+4946116845[0-5]!' priority -1 to hints [Dec 6 22:45:53] DEBUG[10062]: devicestate.c:344 _ast_device_state: No provider found, checking channel drivers for iax2 - hbc-hah [Dec 6 22:45:53] DEBUG[10062]: chan_iax2.c:14465 iax2_devicestate: Checking device state for device hbc-hah [Dec 6 22:45:53] DEBUG[10062]: chan_iax2.c:14473 iax2_devicestate: Found peer. What's device state of hbc-hah? addr=1401371040, defaddr=0 maxms=2000, lastms=23 [Dec 6 22:45:53] DEBUG[10062]: pbx.c:5916 ast_add_hint: HINTS: Adding hint _+494612933[123]: iax2/hbc-hah [Dec 6 22:45:53] DEBUG[10062]: pbx.c:10258 ast_add_extension2_lockopt: Added extension '_+494612933[123]' priority -1 to hints (0xb51cd8b8) [Dec 6 22:45:53] -- Added extension '_+494612933[123]' priority -1 to hints [Dec 6 22:45:53] DEBUG[10062]: devicestate.c:344 _ast_device_state: No provider found, checking channel drivers for iax2 - hbc-hah [Dec 6 22:45:53] DEBUG[10062]: chan_iax2.c:14465 iax2_devicestate: Checking device state for device hbc-hah [Dec 6 22:45:53] DEBUG[10062]: chan_iax2.c:14473 iax2_devicestate: Found peer. What's device state of hbc-hah? addr=1401371040, defaddr=0 maxms=2000, lastms=23 [Dec 6 22:45:53] DEBUG[10062]: pbx.c:5916 ast_add_hint: HINTS: Adding hint _+494613183234.: iax2/hbc-hah [Dec 6 22:45:53] DEBUG[10062]: pbx.c:10258 ast_add_extension2_lockopt: Added extension '_+494613183234.' priority -1 to hints (0xb51cd8b8) [Dec 6 22:45:53] -- Added extension '_+494613183234.' priority -1 to hints [Dec 6 22:45:53] DEBUG[10062]: devicestate.c:344 _ast_device_state: No provider found, checking channel drivers for iax2 - hbc-pgsw [Dec 6 22:45:53] DEBUG[10062]: chan_iax2.c:14465 iax2_devicestate: Checking device state for device hbc-pgsw [Dec 6 22:45:53] DEBUG[10062]: chan_iax2.c:14473 iax2_devicestate: Found peer. What's device state of hbc-pgsw? addr=1467626513, defaddr=0 maxms=2000, lastms=55 [Dec 6 22:45:53] DEBUG[10062]: pbx.c:5916 ast_add_hint: HINTS: Adding hint _+494614305490[45]: iax2/hbc-pgsw [Dec 6 22:45:53] DEBUG[10062]: pbx.c:10258 ast_add_extension2_lockopt: Added extension '_+494614305490[45]' priority -1 to hints (0xb51cd8b8) [Dec 6 22:45:53] -- Added extension '_+494614305490[45]' priority -1 to hints [Dec 6 22:45:53] DEBUG[28895]: dnsmgr.c:206 dnsmgr_refresh: Not an IPv4 nor IPv6 address, cannot get port. [Dec 6 22:45:53] DEBUG[10062]: devicestate.c:344 _ast_device_state: No provider found, checking channel drivers for iax2 - hbc-hbst [Dec 6 22:45:53] DEBUG[10062]: chan_iax2.c:14465 iax2_devicestate: Checking device state for device hbc-hbst [Dec 6 22:45:53] DEBUG[10062]: chan_iax2.c:14473 iax2_devicestate: Found peer. What's device state of hbc-hbst? addr=1530513477, defaddr=0 maxms=2000, lastms=54 [Dec 6 22:45:53] DEBUG[28895]: dnsmgr.c:201 dnsmgr_refresh: refreshing 'sipbalance5-2.1und1.de.' [Dec 6 22:45:53] DEBUG[10062]: pbx.c:5916 ast_add_hint: HINTS: Adding hint _+494671943962[13]: iax2/hbc-hbst [Dec 6 22:45:53] DEBUG[10062]: pbx.c:10258 ast_add_extension2_lockopt: Added extension '_+494671943962[13]' priority -1 to hints (0xb51cd8b8) [Dec 6 22:45:53] -- Added extension '_+494671943962[13]' priority -1 to hints [Dec 6 22:45:53] DEBUG[10062]: devicestate.c:344 _ast_device_state: No provider found, checking channel drivers for iax2 - flelvis [Dec 6 22:45:53] DEBUG[10062]: chan_iax2.c:14465 iax2_devicestate: Checking device state for device flelvis [Dec 6 22:45:53] DEBUG[10062]: chan_iax2.c:14473 iax2_devicestate: Found peer. What's device state of flelvis? addr=0, defaddr=0 maxms=2000, lastms=0 [Dec 6 22:45:53] DEBUG[10062]: pbx.c:5916 ast_add_hint: HINTS: Adding hint flelvis: iax2/flelvis&SIP/flelvis [Dec 6 22:45:53] DEBUG[10062]: pbx.c:10258 ast_add_extension2_lockopt: Added extension 'flelvis' priority -1 to hints (0xb51cd8b8) [Dec 6 22:45:53] -- Added extension 'flelvis' priority -1 to hints [Dec 6 22:45:53] DEBUG[10062]: devicestate.c:344 _ast_device_state: No provider found, checking channel drivers for SIP - jane [Dec 6 22:45:53] DEBUG[10062]: pbx.c:5916 ast_add_hint: HINTS: Adding hint jane: SIP/jane [Dec 6 22:45:53] DEBUG[10062]: pbx.c:10258 ast_add_extension2_lockopt: Added extension 'jane' priority -1 to hints (0xb51cd8b8) [Dec 6 22:45:53] -- Added extension 'jane' priority -1 to hints [Dec 6 22:45:53] DEBUG[10062]: pbx.c:5916 ast_add_hint: HINTS: Adding hint shjans: SIP/shjans [Dec 6 22:45:53] DEBUG[10062]: pbx.c:10258 ast_add_extension2_lockopt: Added extension 'shjans' priority -1 to hints (0xb51cd8b8) [Dec 6 22:45:53] -- Added extension 'shjans' priority -1 to hints [Dec 6 22:45:53] DEBUG[10062]: devicestate.c:344 _ast_device_state: No provider found, checking channel drivers for iax2 - test1 [Dec 6 22:45:53] DEBUG[10062]: chan_iax2.c:14465 iax2_devicestate: Checking device state for device test1 [Dec 6 22:45:53] DEBUG[10062]: chan_iax2.c:14473 iax2_devicestate: Found peer. What's device state of test1? addr=0, defaddr=0 maxms=2000, lastms=0 [Dec 6 22:45:53] DEBUG[10062]: devicestate.c:344 _ast_device_state: No provider found, checking channel drivers for SIP - test1 [Dec 6 22:45:53] DEBUG[10062]: pbx.c:5916 ast_add_hint: HINTS: Adding hint test1: iax2/test1&SIP/test1 [Dec 6 22:45:53] DEBUG[10062]: pbx.c:10258 ast_add_extension2_lockopt: Added extension 'test1' priority -1 to hints (0xb51cd8b8) [Dec 6 22:45:53] -- Added extension 'test1' priority -1 to hints [Dec 6 22:45:53] DEBUG[10062]: devicestate.c:344 _ast_device_state: No provider found, checking channel drivers for iax2 - test2 [Dec 6 22:45:53] DEBUG[10062]: chan_iax2.c:14465 iax2_devicestate: Checking device state for device test2 [Dec 6 22:45:53] DEBUG[10062]: chan_iax2.c:14473 iax2_devicestate: Found peer. What's device state of test2? addr=0, defaddr=0 maxms=2000, lastms=0 [Dec 6 22:45:53] DEBUG[10062]: devicestate.c:344 _ast_device_state: No provider found, checking channel drivers for SIP - test2 [Dec 6 22:45:53] DEBUG[10062]: pbx.c:5916 ast_add_hint: HINTS: Adding hint test2: iax2/test2&SIP/test2 [Dec 6 22:45:53] DEBUG[10062]: pbx.c:10258 ast_add_extension2_lockopt: Added extension 'test2' priority -1 to hints (0xb51cd8b8) [Dec 6 22:45:53] -- Added extension 'test2' priority -1 to hints [Dec 6 22:45:53] DEBUG[10062]: devicestate.c:344 _ast_device_state: No provider found, checking channel drivers for iax2 - test3 [Dec 6 22:45:53] DEBUG[10062]: chan_iax2.c:14465 iax2_devicestate: Checking device state for device test3 [Dec 6 22:45:53] DEBUG[10062]: chan_iax2.c:14473 iax2_devicestate: Found peer. What's device state of test3? addr=0, defaddr=0 maxms=2000, lastms=0 [Dec 6 22:45:53] DEBUG[10062]: pbx.c:5916 ast_add_hint: HINTS: Adding hint test3: iax2/test3&SIP/test3 [Dec 6 22:45:53] DEBUG[10062]: pbx.c:10258 ast_add_extension2_lockopt: Added extension 'test3' priority -1 to hints (0xb51cd8b8) [Dec 6 22:45:53] -- Added extension 'test3' priority -1 to hints [Dec 6 22:45:53] DEBUG[10062]: devicestate.c:344 _ast_device_state: No provider found, checking channel drivers for iax2 - test4 [Dec 6 22:45:53] DEBUG[10062]: chan_iax2.c:14465 iax2_devicestate: Checking device state for device test4 [Dec 6 22:45:53] DEBUG[10062]: chan_iax2.c:14473 iax2_devicestate: Found peer. What's device state of test4? addr=0, defaddr=0 maxms=2000, lastms=0 [Dec 6 22:45:53] DEBUG[10062]: devicestate.c:344 _ast_device_state: No provider found, checking channel drivers for SIP - test4 [Dec 6 22:45:53] DEBUG[10062]: pbx.c:5916 ast_add_hint: HINTS: Adding hint test4: iax2/test4&SIP/test4 [Dec 6 22:45:53] DEBUG[10062]: pbx.c:10258 ast_add_extension2_lockopt: Added extension 'test4' priority -1 to hints (0xb51cd8b8) [Dec 6 22:45:53] -- Added extension 'test4' priority -1 to hints [Dec 6 22:45:53] DEBUG[10062]: devicestate.c:344 _ast_device_state: No provider found, checking channel drivers for iax2 - test5 [Dec 6 22:45:53] DEBUG[10062]: chan_iax2.c:14465 iax2_devicestate: Checking device state for device test5 [Dec 6 22:45:53] DEBUG[10062]: chan_iax2.c:14473 iax2_devicestate: Found peer. What's device state of test5? addr=0, defaddr=0 maxms=2000, lastms=0 [Dec 6 22:45:53] DEBUG[10062]: devicestate.c:344 _ast_device_state: No provider found, checking channel drivers for SIP - test5 [Dec 6 22:45:53] DEBUG[10062]: pbx.c:5916 ast_add_hint: HINTS: Adding hint test5: iax2/test5&SIP/test5 [Dec 6 22:45:53] DEBUG[10062]: pbx.c:10258 ast_add_extension2_lockopt: Added extension 'test5' priority -1 to hints (0xb51cd8b8) [Dec 6 22:45:53] -- Added extension 'test5' priority -1 to hints [Dec 6 22:45:53] DEBUG[10062]: devicestate.c:344 _ast_device_state: No provider found, checking channel drivers for iax2 - test6 [Dec 6 22:45:53] DEBUG[10062]: chan_iax2.c:14465 iax2_devicestate: Checking device state for device test6 [Dec 6 22:45:53] DEBUG[10062]: chan_iax2.c:14473 iax2_devicestate: Found peer. What's device state of test6? addr=0, defaddr=0 maxms=2000, lastms=0 [Dec 6 22:45:53] DEBUG[10062]: devicestate.c:344 _ast_device_state: No provider found, checking channel drivers for SIP - test6 [Dec 6 22:45:53] DEBUG[10062]: pbx.c:5916 ast_add_hint: HINTS: Adding hint test6: iax2/test6&SIP/test6 [Dec 6 22:45:53] DEBUG[10062]: pbx.c:10258 ast_add_extension2_lockopt: Added extension 'test6' priority -1 to hints (0xb51cd8b8) [Dec 6 22:45:53] -- Added extension 'test6' priority -1 to hints [Dec 6 22:45:53] DEBUG[10062]: devicestate.c:344 _ast_device_state: No provider found, checking channel drivers for SIP - 11779 [Dec 6 22:45:53] DEBUG[10062]: pbx.c:5916 ast_add_hint: HINTS: Adding hint test9: SIP/11779 [Dec 6 22:45:53] DEBUG[10062]: pbx.c:10258 ast_add_extension2_lockopt: Added extension 'test9' priority -1 to hints (0xb51cd8b8) [Dec 6 22:45:53] -- Added extension 'test9' priority -1 to hints [Dec 6 22:45:53] DEBUG[10062]: pbx.c:8767 ast_context_find_or_create: Registered context 'dundi2'(0xb6ed2dc0) in local table 0xb4c97050; registrar: SIP [Dec 6 22:45:53] -- Registered extension context 'dundi2'; registrar: SIP [Dec 6 22:45:53] -- merging incls/swits/igpats from old(dundi2) to new(dundi2) context, registrar = pbx_config [Dec 6 22:45:53] DEBUG[10062]: pbx.c:8767 ast_context_find_or_create: Registered context 'dundi1'(0xb6ed2b58) in local table 0xb4c97050; registrar: SIP [Dec 6 22:45:53] -- Registered extension context 'dundi1'; registrar: SIP [Dec 6 22:45:53] -- merging incls/swits/igpats from old(dundi1) to new(dundi1) context, registrar = pbx_config [Dec 6 22:45:53] DEBUG[10062]: pbx.c:8767 ast_context_find_or_create: Registered context 'dundi0'(0xb4a9c8a8) in local table 0xb4c97050; registrar: SIP [Dec 6 22:45:53] -- Registered extension context 'dundi0'; registrar: SIP [Dec 6 22:45:53] -- merging incls/swits/igpats from old(dundi0) to new(dundi0) context, registrar = pbx_config [Dec 6 22:45:53] DEBUG[10062]: pbx.c:8767 ast_context_find_or_create: Registered context 'parkedcalls'(0xb4c07760) in local table 0xb4c97050; registrar: features [Dec 6 22:45:53] -- Registered extension context 'parkedcalls'; registrar: features [Dec 6 22:45:53] DEBUG[28895]: netsock2.c:138 ast_sockaddr_split_hostport: Splitting 'sipbalance5-2.1und1.de.' into... [Dec 6 22:45:53] DEBUG[28895]: netsock2.c:192 ast_sockaddr_split_hostport: ...host 'sipbalance5-2.1und1.de.' and port ''. [Dec 6 22:45:53] -- merging incls/swits/igpats from old(parkedcalls) to new(parkedcalls) context, registrar = pbx_config [Dec 6 22:45:53] -- merging incls/swits/igpats from old(zkw) to new(zkw) context, registrar = pbx_config [Dec 6 22:45:53] -- merging incls/swits/igpats from old(guest) to new(guest) context, registrar = pbx_config [Dec 6 22:45:53] -- merging incls/swits/igpats from old(default) to new(default) context, registrar = pbx_config [Dec 6 22:45:53] -- merging incls/swits/igpats from old(robert-callback-dial) to new(robert-callback-dial) context, registrar = pbx_config [Dec 6 22:45:53] -- Time to scan old dialplan and merge leftovers back into the new: 0.001096 sec [Dec 6 22:45:53] -- Time to restore hints and swap in new dialplan: 0.000026 sec [Dec 6 22:45:53] -- Time to delete the old dialplan: 0.000995 sec [Dec 6 22:45:53] -- Total time merge_contexts_delete: 0.002117 sec [Dec 6 22:45:53] -- Remote UNIX connection disconnected [Dec 6 22:45:53] DEBUG[28895]: dnsmgr.c:206 dnsmgr_refresh: Not an IPv4 nor IPv6 address, cannot get port. [Dec 6 22:45:53] DEBUG[28895]: dnsmgr.c:201 dnsmgr_refresh: refreshing 'sipbalance5-3.1und1.de.' [Dec 6 22:45:53] DEBUG[28895]: netsock2.c:138 ast_sockaddr_split_hostport: Splitting 'sipbalance5-3.1und1.de.' into... [Dec 6 22:45:53] DEBUG[28895]: netsock2.c:192 ast_sockaddr_split_hostport: ...host 'sipbalance5-3.1und1.de.' and port ''. [Dec 6 22:45:53] DEBUG[28895]: dnsmgr.c:206 dnsmgr_refresh: Not an IPv4 nor IPv6 address, cannot get port. [Dec 6 22:45:53] DEBUG[28895]: dnsmgr.c:201 dnsmgr_refresh: refreshing 'sipbalance5-4.1und1.de.' [Dec 6 22:45:53] DEBUG[28895]: netsock2.c:138 ast_sockaddr_split_hostport: Splitting 'sipbalance5-4.1und1.de.' into... [Dec 6 22:45:53] DEBUG[28895]: netsock2.c:192 ast_sockaddr_split_hostport: ...host 'sipbalance5-4.1und1.de.' and port ''. [Dec 6 22:45:53] DEBUG[28895]: dnsmgr.c:206 dnsmgr_refresh: Not an IPv4 nor IPv6 address, cannot get port. [Dec 6 22:45:53] DEBUG[28895]: dnsmgr.c:201 dnsmgr_refresh: refreshing 'sipbalance6-1.1und1.de.' [Dec 6 22:45:53] DEBUG[28895]: netsock2.c:138 ast_sockaddr_split_hostport: Splitting 'sipbalance6-1.1und1.de.' into... [Dec 6 22:45:53] DEBUG[28895]: netsock2.c:192 ast_sockaddr_split_hostport: ...host 'sipbalance6-1.1und1.de.' and port ''. [Dec 6 22:45:53] DEBUG[28895]: dnsmgr.c:206 dnsmgr_refresh: Not an IPv4 nor IPv6 address, cannot get port. [Dec 6 22:45:53] DEBUG[28895]: dnsmgr.c:201 dnsmgr_refresh: refreshing 'sipbalance6-2.1und1.de.' [Dec 6 22:45:53] DEBUG[28895]: netsock2.c:138 ast_sockaddr_split_hostport: Splitting 'sipbalance6-2.1und1.de.' into... [Dec 6 22:45:53] DEBUG[28895]: netsock2.c:192 ast_sockaddr_split_hostport: ...host 'sipbalance6-2.1und1.de.' and port ''. [Dec 6 22:45:53] DEBUG[28895]: dnsmgr.c:206 dnsmgr_refresh: Not an IPv4 nor IPv6 address, cannot get port. [Dec 6 22:45:53] DEBUG[28895]: dnsmgr.c:201 dnsmgr_refresh: refreshing 'sipbalance6-3.1und1.de.' [Dec 6 22:45:53] DEBUG[28895]: netsock2.c:138 ast_sockaddr_split_hostport: Splitting 'sipbalance6-3.1und1.de.' into... [Dec 6 22:45:53] DEBUG[28895]: netsock2.c:192 ast_sockaddr_split_hostport: ...host 'sipbalance6-3.1und1.de.' and port ''. [Dec 6 22:45:53] DEBUG[28895]: dnsmgr.c:206 dnsmgr_refresh: Not an IPv4 nor IPv6 address, cannot get port. [Dec 6 22:45:53] DEBUG[28895]: dnsmgr.c:201 dnsmgr_refresh: refreshing 'sipbalance6-4.1und1.de.' [Dec 6 22:45:53] DEBUG[28895]: netsock2.c:138 ast_sockaddr_split_hostport: Splitting 'sipbalance6-4.1und1.de.' into... [Dec 6 22:45:53] DEBUG[28895]: netsock2.c:192 ast_sockaddr_split_hostport: ...host 'sipbalance6-4.1und1.de.' and port ''. [Dec 6 22:45:53] DEBUG[28895]: dnsmgr.c:206 dnsmgr_refresh: Not an IPv4 nor IPv6 address, cannot get port. [Dec 6 22:45:53] DEBUG[28895]: dnsmgr.c:201 dnsmgr_refresh: refreshing 'sipbalance7-1.1und1.de.' [Dec 6 22:45:53] DEBUG[28895]: netsock2.c:138 ast_sockaddr_split_hostport: Splitting 'sipbalance7-1.1und1.de.' into... [Dec 6 22:45:53] DEBUG[28895]: netsock2.c:192 ast_sockaddr_split_hostport: ...host 'sipbalance7-1.1und1.de.' and port ''. [Dec 6 22:45:53] DEBUG[28895]: dnsmgr.c:206 dnsmgr_refresh: Not an IPv4 nor IPv6 address, cannot get port. [Dec 6 22:45:53] DEBUG[28895]: dnsmgr.c:201 dnsmgr_refresh: refreshing 'sipbalance7-2.1und1.de.' [Dec 6 22:45:53] DEBUG[28895]: netsock2.c:138 ast_sockaddr_split_hostport: Splitting 'sipbalance7-2.1und1.de.' into... [Dec 6 22:45:53] DEBUG[28895]: netsock2.c:192 ast_sockaddr_split_hostport: ...host 'sipbalance7-2.1und1.de.' and port ''. [Dec 6 22:45:53] DEBUG[28895]: dnsmgr.c:206 dnsmgr_refresh: Not an IPv4 nor IPv6 address, cannot get port. [Dec 6 22:45:53] DEBUG[28895]: dnsmgr.c:201 dnsmgr_refresh: refreshing 'sipbalance7-3.1und1.de.' [Dec 6 22:45:53] DEBUG[28895]: netsock2.c:138 ast_sockaddr_split_hostport: Splitting 'sipbalance7-3.1und1.de.' into... [Dec 6 22:45:53] DEBUG[28895]: netsock2.c:192 ast_sockaddr_split_hostport: ...host 'sipbalance7-3.1und1.de.' and port ''. [Dec 6 22:45:53] DEBUG[28895]: dnsmgr.c:206 dnsmgr_refresh: Not an IPv4 nor IPv6 address, cannot get port. [Dec 6 22:45:53] DEBUG[28895]: dnsmgr.c:201 dnsmgr_refresh: refreshing 'sipbalance7-4.1und1.de.' [Dec 6 22:45:53] DEBUG[28895]: netsock2.c:138 ast_sockaddr_split_hostport: Splitting 'sipbalance7-4.1und1.de.' into... [Dec 6 22:45:53] DEBUG[28895]: netsock2.c:192 ast_sockaddr_split_hostport: ...host 'sipbalance7-4.1und1.de.' and port ''. [Dec 6 22:45:53] DEBUG[28895]: dnsmgr.c:206 dnsmgr_refresh: Not an IPv4 nor IPv6 address, cannot get port. [Dec 6 22:45:53] DEBUG[28895]: dnsmgr.c:201 dnsmgr_refresh: refreshing 'sipbalance8-1.1und1.de.' [Dec 6 22:45:53] DEBUG[28895]: netsock2.c:138 ast_sockaddr_split_hostport: Splitting 'sipbalance8-1.1und1.de.' into... [Dec 6 22:45:53] DEBUG[28895]: netsock2.c:192 ast_sockaddr_split_hostport: ...host 'sipbalance8-1.1und1.de.' and port ''. [Dec 6 22:45:53] DEBUG[28895]: dnsmgr.c:206 dnsmgr_refresh: Not an IPv4 nor IPv6 address, cannot get port. [Dec 6 22:45:53] DEBUG[28895]: dnsmgr.c:201 dnsmgr_refresh: refreshing 'sipbalance8-2.1und1.de.' [Dec 6 22:45:53] DEBUG[28895]: netsock2.c:138 ast_sockaddr_split_hostport: Splitting 'sipbalance8-2.1und1.de.' into... [Dec 6 22:45:53] DEBUG[28895]: netsock2.c:192 ast_sockaddr_split_hostport: ...host 'sipbalance8-2.1und1.de.' and port ''. [Dec 6 22:45:53] DEBUG[28895]: dnsmgr.c:206 dnsmgr_refresh: Not an IPv4 nor IPv6 address, cannot get port. [Dec 6 22:45:53] DEBUG[28895]: dnsmgr.c:201 dnsmgr_refresh: refreshing 'sipbalance8-3.1und1.de.' [Dec 6 22:45:53] DEBUG[28895]: netsock2.c:138 ast_sockaddr_split_hostport: Splitting 'sipbalance8-3.1und1.de.' into... [Dec 6 22:45:53] DEBUG[28895]: netsock2.c:192 ast_sockaddr_split_hostport: ...host 'sipbalance8-3.1und1.de.' and port ''. [Dec 6 22:45:53] DEBUG[28895]: dnsmgr.c:206 dnsmgr_refresh: Not an IPv4 nor IPv6 address, cannot get port. [Dec 6 22:45:53] DEBUG[28895]: dnsmgr.c:201 dnsmgr_refresh: refreshing 'sipbalance8-4.1und1.de.' [Dec 6 22:45:53] DEBUG[28895]: netsock2.c:138 ast_sockaddr_split_hostport: Splitting 'sipbalance8-4.1und1.de.' into... [Dec 6 22:45:53] DEBUG[28895]: netsock2.c:192 ast_sockaddr_split_hostport: ...host 'sipbalance8-4.1und1.de.' and port ''. [Dec 6 22:45:53] DEBUG[28895]: dnsmgr.c:206 dnsmgr_refresh: Not an IPv4 nor IPv6 address, cannot get port. [Dec 6 22:45:53] DEBUG[28895]: dnsmgr.c:201 dnsmgr_refresh: refreshing 'sipbalance9-1.1und1.de.' [Dec 6 22:45:53] DEBUG[28895]: netsock2.c:138 ast_sockaddr_split_hostport: Splitting 'sipbalance9-1.1und1.de.' into... [Dec 6 22:45:53] DEBUG[28895]: netsock2.c:192 ast_sockaddr_split_hostport: ...host 'sipbalance9-1.1und1.de.' and port ''. [Dec 6 22:45:53] DEBUG[28895]: dnsmgr.c:206 dnsmgr_refresh: Not an IPv4 nor IPv6 address, cannot get port. [Dec 6 22:45:53] DEBUG[28895]: dnsmgr.c:201 dnsmgr_refresh: refreshing 'sipbalance9-2.1und1.de.' [Dec 6 22:45:53] DEBUG[28895]: netsock2.c:138 ast_sockaddr_split_hostport: Splitting 'sipbalance9-2.1und1.de.' into... [Dec 6 22:45:53] DEBUG[28895]: netsock2.c:192 ast_sockaddr_split_hostport: ...host 'sipbalance9-2.1und1.de.' and port ''. [Dec 6 22:45:53] DEBUG[28895]: dnsmgr.c:206 dnsmgr_refresh: Not an IPv4 nor IPv6 address, cannot get port. [Dec 6 22:45:53] DEBUG[28895]: dnsmgr.c:201 dnsmgr_refresh: refreshing 'sipbalance9-3.1und1.de.' [Dec 6 22:45:53] DEBUG[28895]: netsock2.c:138 ast_sockaddr_split_hostport: Splitting 'sipbalance9-3.1und1.de.' into... [Dec 6 22:45:53] DEBUG[28895]: netsock2.c:192 ast_sockaddr_split_hostport: ...host 'sipbalance9-3.1und1.de.' and port ''. [Dec 6 22:45:53] DEBUG[28895]: dnsmgr.c:206 dnsmgr_refresh: Not an IPv4 nor IPv6 address, cannot get port. [Dec 6 22:45:53] DEBUG[28895]: dnsmgr.c:201 dnsmgr_refresh: refreshing 'sipbalance9-4.1und1.de.' [Dec 6 22:45:53] DEBUG[28895]: netsock2.c:138 ast_sockaddr_split_hostport: Splitting 'sipbalance9-4.1und1.de.' into... [Dec 6 22:45:53] DEBUG[28895]: netsock2.c:192 ast_sockaddr_split_hostport: ...host 'sipbalance9-4.1und1.de.' and port ''. [Dec 6 22:45:53] DEBUG[28895]: dnsmgr.c:206 dnsmgr_refresh: Not an IPv4 nor IPv6 address, cannot get port. [Dec 6 22:45:53] DEBUG[28895]: dnsmgr.c:201 dnsmgr_refresh: refreshing 'sipbalance10-1.1und1.de.' [Dec 6 22:45:53] DEBUG[28895]: netsock2.c:138 ast_sockaddr_split_hostport: Splitting 'sipbalance10-1.1und1.de.' into... [Dec 6 22:45:53] DEBUG[28895]: netsock2.c:192 ast_sockaddr_split_hostport: ...host 'sipbalance10-1.1und1.de.' and port ''. [Dec 6 22:45:53] DEBUG[28895]: dnsmgr.c:206 dnsmgr_refresh: Not an IPv4 nor IPv6 address, cannot get port. [Dec 6 22:45:53] DEBUG[28895]: dnsmgr.c:201 dnsmgr_refresh: refreshing 'sipbalance10-2.1und1.de.' [Dec 6 22:45:53] DEBUG[28895]: netsock2.c:138 ast_sockaddr_split_hostport: Splitting 'sipbalance10-2.1und1.de.' into... [Dec 6 22:45:53] DEBUG[28895]: netsock2.c:192 ast_sockaddr_split_hostport: ...host 'sipbalance10-2.1und1.de.' and port ''. [Dec 6 22:45:53] DEBUG[28895]: dnsmgr.c:206 dnsmgr_refresh: Not an IPv4 nor IPv6 address, cannot get port. [Dec 6 22:45:53] DEBUG[28895]: dnsmgr.c:201 dnsmgr_refresh: refreshing 'sipbalance10-3.1und1.de.' [Dec 6 22:45:53] DEBUG[28895]: netsock2.c:138 ast_sockaddr_split_hostport: Splitting 'sipbalance10-3.1und1.de.' into... [Dec 6 22:45:53] DEBUG[28895]: netsock2.c:192 ast_sockaddr_split_hostport: ...host 'sipbalance10-3.1und1.de.' and port ''. [Dec 6 22:45:53] DEBUG[28895]: dnsmgr.c:206 dnsmgr_refresh: Not an IPv4 nor IPv6 address, cannot get port. [Dec 6 22:45:53] DEBUG[28895]: dnsmgr.c:201 dnsmgr_refresh: refreshing 'sipbalance10-4.1und1.de.' [Dec 6 22:45:53] DEBUG[28895]: netsock2.c:138 ast_sockaddr_split_hostport: Splitting 'sipbalance10-4.1und1.de.' into... [Dec 6 22:45:53] DEBUG[28895]: netsock2.c:192 ast_sockaddr_split_hostport: ...host 'sipbalance10-4.1und1.de.' and port ''. [Dec 6 22:45:53] DEBUG[28895]: dnsmgr.c:206 dnsmgr_refresh: Not an IPv4 nor IPv6 address, cannot get port. [Dec 6 22:45:53] DEBUG[28895]: db.c:376 db_get_common: Unable to find key 'test1' in family 'SIP/Registry' [Dec 6 22:45:53] DEBUG[28895]: db.c:376 db_get_common: Unable to find key 'test2' in family 'SIP/Registry' [Dec 6 22:45:53] DEBUG[28895]: netsock2.c:138 ast_sockaddr_split_hostport: Splitting '85.183.95.26:5060' into... [Dec 6 22:45:53] DEBUG[28895]: netsock2.c:192 ast_sockaddr_split_hostport: ...host '85.183.95.26' and port '5060'. [Dec 6 22:45:53] DEBUG[28895]: acl.c:979 ast_ouraddrfor: For destination '85.183.95.26', our source address is '144.76.51.196'. [Dec 6 22:45:53] DEBUG[28895]: db.c:376 db_get_common: Unable to find key 'test4' in family 'SIP/Registry' [Dec 6 22:45:53] DEBUG[28895]: db.c:376 db_get_common: Unable to find key 'test5' in family 'SIP/Registry' [Dec 6 22:45:53] DEBUG[28895]: db.c:376 db_get_common: Unable to find key 'test6' in family 'SIP/Registry' [Dec 6 22:45:53] DEBUG[28895]: db.c:376 db_get_common: Unable to find key '11779' in family 'SIP/Registry' [Dec 6 22:45:53] DEBUG[28895]: netsock2.c:138 ast_sockaddr_split_hostport: Splitting '91.35.26.233:5060' into... [Dec 6 22:45:53] DEBUG[28895]: netsock2.c:192 ast_sockaddr_split_hostport: ...host '91.35.26.233' and port '5060'. [Dec 6 22:45:53] DEBUG[28895]: db.c:376 db_get_common: Unable to find key 'jane' in family 'SIP/Registry' [Dec 6 22:45:53] DEBUG[28895]: netsock2.c:138 ast_sockaddr_split_hostport: Splitting '88.71.80.160:5060' into... [Dec 6 22:45:53] DEBUG[28895]: netsock2.c:192 ast_sockaddr_split_hostport: ...host '88.71.80.160' and port '5060'. [Dec 6 22:45:53] DEBUG[28895]: acl.c:979 ast_ouraddrfor: For destination '88.71.80.160', our source address is '144.76.51.196'. [Dec 6 22:45:53] DEBUG[28895]: netsock2.c:138 ast_sockaddr_split_hostport: Splitting '31.17.238.101:5060' into... [Dec 6 22:45:53] DEBUG[28895]: netsock2.c:192 ast_sockaddr_split_hostport: ...host '31.17.238.101' and port '5060'. [Dec 6 22:45:53] DEBUG[28895]: acl.c:979 ast_ouraddrfor: For destination '31.17.238.101', our source address is '144.76.51.196'. [Dec 6 22:45:53] DEBUG[28895]: db.c:376 db_get_common: Unable to find key 'bigfoot-s' in family 'SIP/Registry' [Dec 6 22:45:53] DEBUG[28895]: dnsmgr.c:201 dnsmgr_refresh: refreshing 'sip.viptel.dk' [Dec 6 22:45:53] DEBUG[28895]: srv.c:294 ast_get_srv: ast_get_srv: SRV lookup for '_sip._udp.sip.viptel.dk' mapped to host vipsbc02.viptel.dk, port 5060 [Dec 6 22:45:53] DEBUG[28895]: netsock2.c:138 ast_sockaddr_split_hostport: Splitting 'vipsbc02.viptel.dk' into... [Dec 6 22:45:53] DEBUG[28895]: netsock2.c:192 ast_sockaddr_split_hostport: ...host 'vipsbc02.viptel.dk' and port ''. [Dec 6 22:45:53] DEBUG[28895]: dnsmgr.c:201 dnsmgr_refresh: refreshing 'sipgate.de' [Dec 6 22:45:53] DEBUG[28895]: srv.c:294 ast_get_srv: ast_get_srv: SRV lookup for '_sip._udp.sipgate.de' mapped to host sipgate.de, port 5060 [Dec 6 22:45:53] DEBUG[28895]: netsock2.c:138 ast_sockaddr_split_hostport: Splitting 'sipgate.de' into... [Dec 6 22:45:53] DEBUG[28895]: netsock2.c:192 ast_sockaddr_split_hostport: ...host 'sipgate.de' and port ''. [Dec 6 22:45:53] DEBUG[28895]: dnsmgr.c:201 dnsmgr_refresh: refreshing 'sipgate.de' [Dec 6 22:45:53] DEBUG[28895]: srv.c:294 ast_get_srv: ast_get_srv: SRV lookup for '_sip._udp.sipgate.de' mapped to host sipgate.de, port 5060 [Dec 6 22:45:53] DEBUG[28895]: netsock2.c:138 ast_sockaddr_split_hostport: Splitting 'sipgate.de' into... [Dec 6 22:45:53] DEBUG[28895]: netsock2.c:192 ast_sockaddr_split_hostport: ...host 'sipgate.de' and port ''. [Dec 6 22:45:53] DEBUG[28895]: dnsmgr.c:201 dnsmgr_refresh: refreshing 'sip.cosmovoip.com' [Dec 6 22:45:53] DEBUG[28895]: netsock2.c:138 ast_sockaddr_split_hostport: Splitting 'sip.cosmovoip.com' into... [Dec 6 22:45:53] DEBUG[28895]: netsock2.c:192 ast_sockaddr_split_hostport: ...host 'sip.cosmovoip.com' and port ''. [Dec 6 22:45:53] DEBUG[28895]: dnsmgr.c:206 dnsmgr_refresh: Not an IPv4 nor IPv6 address, cannot get port. [Dec 6 22:45:53] DEBUG[28895]: db.c:376 db_get_common: Unable to find key 'robert-mobile' in family 'SIP/Registry' [Dec 6 22:45:53] DEBUG[28895]: dnsmgr.c:201 dnsmgr_refresh: refreshing 'sip.voicetrading.com' [Dec 6 22:45:53] DEBUG[28895]: netsock2.c:138 ast_sockaddr_split_hostport: Splitting 'sip.voicetrading.com' into... [Dec 6 22:45:53] DEBUG[28895]: netsock2.c:192 ast_sockaddr_split_hostport: ...host 'sip.voicetrading.com' and port ''. [Dec 6 22:45:53] DEBUG[28895]: acl.c:828 resolve_first: Multiple addresses. Using the first only [Dec 6 22:45:53] DEBUG[28895]: dnsmgr.c:206 dnsmgr_refresh: Not an IPv4 nor IPv6 address, cannot get port. [Dec 6 22:45:53] DEBUG[28895]: config.c:1808 config_text_file_load: Parsing /etc/asterisk/sip_notify.conf [Dec 6 22:45:53] == Parsing '/etc/asterisk/sip_notify.conf': Found [Dec 6 22:45:53] DEBUG[32276]: manager.c:4846 match_filter: Examining event: Event: ChannelReload Privilege: system,all Timestamp: 1417902353.601187 SequenceNumber: 93 Line: 32522 Func: reload_config ChannelType: SIP ReloadReason: CLIRELOAD (Channel module reload by CLI command) Registry_Count: 0 Peer_Count: 62 [Dec 6 22:45:53] DEBUG[28895]: manager.c:1476 unref_mansession: Mansession: 0xb6c300f4 refcount now 2 [Dec 6 22:45:53] DEBUG[28895]: sched.c:546 ast_sched_dump: Asterisk Schedule Dump (61 in Q, 5087201 Total, 82 Cache, 142 high-water) [Dec 6 22:45:53] DEBUG[28895]: sched.c:551 ast_sched_dump: ============================================================= [Dec 6 22:45:53] DEBUG[28895]: sched.c:552 ast_sched_dump: |ID Callback Data Time (sec:ms) | [Dec 6 22:45:53] DEBUG[28895]: sched.c:553 ast_sched_dump: +-----+-----------------+-----------------+-----------------+ [Dec 6 22:45:53] DEBUG[28895]: sched.c:565 ast_sched_dump: |5087190 | 0xb678daf7 | 0xb6cc5b38 | -000001 : 953581 | [Dec 6 22:45:53] DEBUG[28895]: sched.c:565 ast_sched_dump: |5087051 | 0xb67c0cc2 | 0x980c0cc | 000031 : 397499 | [Dec 6 22:45:53] DEBUG[28895]: sched.c:565 ast_sched_dump: |5087197 | 0xb678daf7 | 0xb6c04ee8 | -000001 : 960340 | [Dec 6 22:45:53] DEBUG[28895]: sched.c:565 ast_sched_dump: |5087133 | 0xb67c0cc2 | 0x9818224 | 000034 : 071329 | [Dec 6 22:45:53] DEBUG[28895]: sched.c:565 ast_sched_dump: |5087039 | 0xb67c0cc2 | 0x96f6ea4 | 000039 : 106561 | [Dec 6 22:45:53] DEBUG[28895]: sched.c:565 ast_sched_dump: |5087189 | 0xb678ed57 | 0xb6c90144 | 000006 : 253346 | [Dec 6 22:45:53] DEBUG[28895]: sched.c:565 ast_sched_dump: |5087193 | 0xb678daf7 | 0xb52330b8 | -000001 : 979770 | [Dec 6 22:45:53] DEBUG[28895]: sched.c:565 ast_sched_dump: |5087072 | 0xb67c0cc2 | 0x972777c | 000039 : 851654 | [Dec 6 22:45:53] DEBUG[28895]: sched.c:565 ast_sched_dump: |5087194 | 0xb67c0cc2 | 0x97d3874 | 000036 : 523405 | [Dec 6 22:45:53] DEBUG[28895]: sched.c:565 ast_sched_dump: |5087048 | 0xb67c0cc2 | 0x96f0d9c | 000039 : 387270 | [Dec 6 22:45:53] DEBUG[28895]: sched.c:565 ast_sched_dump: |5087054 | 0xb67c0cc2 | 0x96deb1c | 000039 : 471498 | [Dec 6 22:45:53] DEBUG[28895]: sched.c:565 ast_sched_dump: |5087198 | 0xb67c0cc2 | 0x97e4b4c | 000022 : 213397 | [Dec 6 22:45:53] DEBUG[28895]: sched.c:565 ast_sched_dump: |5087042 | 0xb67c0cc2 | 0x96fd044 | 000039 : 215848 | [Dec 6 22:45:53] DEBUG[28895]: sched.c:565 ast_sched_dump: |5087182 | 0xb67c0cc2 | 0x977c554 | 000005 : 459920 | [Dec 6 22:45:53] DEBUG[28895]: sched.c:565 ast_sched_dump: |5087201 | 0xb678daf7 | 0xb6c1f7d8 | 000000 : 028398 | [Dec 6 22:45:53] DEBUG[28895]: sched.c:565 ast_sched_dump: |5087126 | 0xb67c0cc2 | 0x969bfd4 | 000041 : 910793 | [Dec 6 22:45:53] DEBUG[28895]: sched.c:565 ast_sched_dump: |5087087 | 0xb67c0cc2 | 0x971b4d4 | 000040 : 663547 | [Dec 6 22:45:53] DEBUG[28895]: sched.c:565 ast_sched_dump: |5087068 | 0xb67c0cc2 | 0x972d884 | 000039 : 737503 | [Dec 6 22:45:53] DEBUG[28895]: sched.c:565 ast_sched_dump: |5087069 | 0xb67c0cc2 | 0x97338f4 | 000039 : 746090 | [Dec 6 22:45:53] DEBUG[28895]: sched.c:565 ast_sched_dump: |5087057 | 0xb67c0cc2 | 0x96e4c54 | 000039 : 495917 | [Dec 6 22:45:53] DEBUG[28895]: sched.c:565 ast_sched_dump: |5087075 | 0xb67c0cc2 | 0x97703dc | 000040 : 222877 | [Dec 6 22:45:53] DEBUG[28895]: sched.c:565 ast_sched_dump: |5087063 | 0xb67c0cc2 | 0x96eacb4 | 000039 : 623349 | [Dec 6 22:45:53] DEBUG[28895]: sched.c:565 ast_sched_dump: |5087099 | 0xb67c0cc2 | 0x9764134 | 000041 : 025101 | [Dec 6 22:45:53] DEBUG[28895]: sched.c:565 ast_sched_dump: |5087093 | 0xb67c0cc2 | 0x9757f24 | 000040 : 893737 | [Dec 6 22:45:53] DEBUG[28895]: sched.c:565 ast_sched_dump: |5087062 | 0xb67c0cc2 | 0x97399fc | 000039 : 614625 | [Dec 6 22:45:53] DEBUG[28895]: sched.c:565 ast_sched_dump: |5087084 | 0xb67c0cc2 | 0x97153cc | 000040 : 636304 | [Dec 6 22:45:53] DEBUG[28895]: sched.c:565 ast_sched_dump: |5087045 | 0xb67c0cc2 | 0x970314c | 000039 : 228754 | [Dec 6 22:45:53] DEBUG[28895]: sched.c:565 ast_sched_dump: |5087174 | 0xb678ed57 | 0xb6ce1a1c | 000015 : 021847 | [Dec 6 22:45:53] DEBUG[28895]: sched.c:565 ast_sched_dump: |5087183 | 0xb67c0cc2 | 0x978265c | 000005 : 654714 | [Dec 6 22:45:53] DEBUG[28895]: sched.c:565 ast_sched_dump: |5087196 | 0xb678ed57 | 0xb52399fc | 000006 : 260109 | [Dec 6 22:45:53] DEBUG[28895]: sched.c:565 ast_sched_dump: |5087166 | 0xb67c0cc2 | 0x97f8a1c | 000035 : 433949 | [Dec 6 22:45:53] DEBUG[28895]: sched.c:565 ast_sched_dump: |5087144 | 0xb67c0cc2 | 0x96c6664 | 000042 : 289424 | [Dec 6 22:45:53] DEBUG[28895]: sched.c:565 ast_sched_dump: |5087136 | 0xb67c0cc2 | 0x96cc76c | 000042 : 161117 | [Dec 6 22:45:53] DEBUG[28895]: sched.c:565 ast_sched_dump: |5087187 | 0xb67c0cc2 | 0x979539c | 000043 : 152562 | [Dec 6 22:45:53] DEBUG[28895]: sched.c:565 ast_sched_dump: |5087116 | 0xb67c0cc2 | 0x9751eb4 | 000041 : 430036 | [Dec 6 22:45:53] DEBUG[28895]: sched.c:565 ast_sched_dump: |5084562 | 0xb678ed57 | 0xb6c9521c | 000922 : 675024 | [Dec 6 22:45:53] DEBUG[28895]: sched.c:565 ast_sched_dump: |5087078 | 0xb67c0cc2 | 0x97764e4 | 000040 : 493549 | [Dec 6 22:45:53] DEBUG[28895]: sched.c:565 ast_sched_dump: |5087104 | 0xb67c0cc2 | 0x973fb04 | 000041 : 154621 | [Dec 6 22:45:53] DEBUG[28895]: sched.c:565 ast_sched_dump: |5087191 | 0xb67c0cc2 | 0x97bda1c | 000052 : 507577 | [Dec 6 22:45:53] DEBUG[28895]: sched.c:565 ast_sched_dump: |5087113 | 0xb67c0cc2 | 0x974bdac | 000041 : 290206 | [Dec 6 22:45:53] DEBUG[28895]: sched.c:565 ast_sched_dump: |5087096 | 0xb67c0cc2 | 0x975e02c | 000041 : 015008 | [Dec 6 22:45:53] DEBUG[28895]: sched.c:565 ast_sched_dump: |5087145 | 0xb67c0cc2 | 0x96d8a14 | 000042 : 292985 | [Dec 6 22:45:53] DEBUG[28895]: sched.c:565 ast_sched_dump: |5087139 | 0xb67c0cc2 | 0x96d2874 | 000042 : 182510 | [Dec 6 22:45:53] DEBUG[28895]: sched.c:565 ast_sched_dump: |5087130 | 0xb67c0cc2 | 0x96a812c | 000042 : 041568 | [Dec 6 22:45:53] DEBUG[28895]: sched.c:565 ast_sched_dump: |5087081 | 0xb67c0cc2 | 0x9721674 | 000040 : 512953 | [Dec 6 22:45:53] DEBUG[28895]: sched.c:565 ast_sched_dump: |5087151 | 0xb67c0cc2 | 0x96ba50c | 000042 : 448347 | [Dec 6 22:45:53] DEBUG[28895]: sched.c:565 ast_sched_dump: |5087156 | 0xb67c0cc2 | 0x96ae234 | 000042 : 560155 | [Dec 6 22:45:53] DEBUG[28895]: sched.c:565 ast_sched_dump: |5087110 | 0xb67c0cc2 | 0x9745ca4 | 000041 : 268782 | [Dec 6 22:45:53] DEBUG[28895]: sched.c:565 ast_sched_dump: |5087127 | 0xb67c0cc2 | 0x96a2034 | 000041 : 914776 | [Dec 6 22:45:53] DEBUG[28895]: sched.c:565 ast_sched_dump: |5087090 | 0xb67c0cc2 | 0x970f2c4 | 000040 : 772991 | [Dec 6 22:45:53] DEBUG[28895]: sched.c:565 ast_sched_dump: |5087157 | 0xb67c0cc2 | 0x96c05f4 | 000042 : 560309 | [Dec 6 22:45:53] DEBUG[28895]: sched.c:565 ast_sched_dump: |5087160 | 0xb67c0cc2 | 0x9709254 | 000042 : 685198 | [Dec 6 22:45:53] DEBUG[28895]: sched.c:565 ast_sched_dump: |5087148 | 0xb67c0cc2 | 0x96b4404 | 000042 : 423742 | [Dec 6 22:45:53] DEBUG[28895]: sched.c:565 ast_sched_dump: |5087188 | 0xb67c0a09 | 0x979539c | 000309 : 852582 | [Dec 6 22:45:53] DEBUG[28895]: sched.c:565 ast_sched_dump: |5087121 | 0xb67c0cc2 | 0x9696134 | 000041 : 782273 | [Dec 6 22:45:53] DEBUG[28895]: sched.c:565 ast_sched_dump: |5087192 | 0xb67c0a09 | 0x97bda1c | 001809 : 857599 | [Dec 6 22:45:53] DEBUG[28895]: sched.c:565 ast_sched_dump: |5087195 | 0xb67c0a09 | 0x97d3874 | 001809 : 859423 | [Dec 6 22:45:53] DEBUG[28895]: sched.c:565 ast_sched_dump: |5087105 | 0xb67c0cc2 | 0x976a2d4 | 000041 : 158554 | [Dec 6 22:45:53] DEBUG[28895]: sched.c:565 ast_sched_dump: |5087199 | 0xb67c0a09 | 0x97e4b4c | 000189 : 861416 | [Dec 6 22:45:53] DEBUG[28895]: sched.c:565 ast_sched_dump: |5087163 | 0xb67c0cc2 | 0x967c34c | 000034 : 781946 | [Dec 6 22:45:53] DEBUG[28895]: sched.c:565 ast_sched_dump: |5087200 | 0xb678ed57 | 0xb6c204b4 | 000010 : 486163 | [Dec 6 22:45:53] DEBUG[28895]: sched.c:568 ast_sched_dump: ============================================================= [Dec 6 22:45:53] DEBUG[28895]: acl.c:979 ast_ouraddrfor: For destination '212.227.67.135', our source address is '144.76.51.196'. [Dec 6 22:45:53] DEBUG[28895]: acl.c:979 ast_ouraddrfor: For destination '212.227.18.135', our source address is '144.76.51.196'. [Dec 6 22:45:53] DEBUG[28895]: acl.c:979 ast_ouraddrfor: For destination '212.227.67.205', our source address is '144.76.51.196'. [Dec 6 22:45:53] DEBUG[28895]: netsock2.c:138 ast_sockaddr_split_hostport: Splitting 'sipgate.de' into... [Dec 6 22:45:53] DEBUG[28895]: netsock2.c:192 ast_sockaddr_split_hostport: ...host 'sipgate.de' and port ''. [Dec 6 22:45:53] DEBUG[28895]: dnsmgr.c:164 internal_dnsmgr_lookup: doing dnsmgr_lookup for 'sipgate.de' [Dec 6 22:45:53] DEBUG[28895]: srv.c:294 ast_get_srv: ast_get_srv: SRV lookup for '_sip._udp.sipgate.de' mapped to host sipgate.de, port 5060 [Dec 6 22:45:53] DEBUG[28895]: netsock2.c:138 ast_sockaddr_split_hostport: Splitting 'sipgate.de' into... [Dec 6 22:45:53] DEBUG[28895]: netsock2.c:192 ast_sockaddr_split_hostport: ...host 'sipgate.de' and port ''. [Dec 6 22:45:53] DEBUG[28895]: dnsmgr.c:174 internal_dnsmgr_lookup: adding dns manager for 'sipgate.de' [Dec 6 22:45:53] DEBUG[28895]: acl.c:979 ast_ouraddrfor: For destination '217.10.79.9', our source address is '144.76.51.196'. [Dec 6 22:45:53] DEBUG[28895]: netsock2.c:138 ast_sockaddr_split_hostport: Splitting 'sipgate.de' into... [Dec 6 22:45:53] DEBUG[28895]: netsock2.c:192 ast_sockaddr_split_hostport: ...host 'sipgate.de' and port ''. [Dec 6 22:45:53] DEBUG[28895]: netsock2.c:138 ast_sockaddr_split_hostport: Splitting 'sipgate.de' into... [Dec 6 22:45:53] DEBUG[28895]: netsock2.c:192 ast_sockaddr_split_hostport: ...host 'sipgate.de' and port ''. [Dec 6 22:45:53] DEBUG[28895]: netsock2.c:138 ast_sockaddr_split_hostport: Splitting 'sipgate.de' into... [Dec 6 22:45:53] DEBUG[28895]: netsock2.c:192 ast_sockaddr_split_hostport: ...host 'sipgate.de' and port ''. [Dec 6 22:45:53] DEBUG[28895]: netsock2.c:138 ast_sockaddr_split_hostport: Splitting 'sipgate.de' into... [Dec 6 22:45:53] DEBUG[28895]: netsock2.c:192 ast_sockaddr_split_hostport: ...host 'sipgate.de' and port ''. [Dec 6 22:45:53] DEBUG[28895]: netsock2.c:138 ast_sockaddr_split_hostport: Splitting 'sipgate.de' into... [Dec 6 22:45:53] DEBUG[28895]: netsock2.c:192 ast_sockaddr_split_hostport: ...host 'sipgate.de' and port ''. [Dec 6 22:45:53] DEBUG[28895]: netsock2.c:138 ast_sockaddr_split_hostport: Splitting 'sipgate.de' into... [Dec 6 22:45:53] DEBUG[28895]: netsock2.c:192 ast_sockaddr_split_hostport: ...host 'sipgate.de' and port ''. [Dec 6 22:45:53] DEBUG[28895]: manager.c:1476 unref_mansession: Mansession: 0xb6c300f4 refcount now 2 [Dec 6 22:45:53] DEBUG[32276]: manager.c:4846 match_filter: Examining event: Event: Registry Privilege: system,all Timestamp: 1417902353.837432 SequenceNumber: 94 Line: 23474 Func: handle_response_register ChannelType: SIP Username: 9215466 Domain: sipgate.de Status: Registered [Dec 6 22:45:53] DEBUG[28895]: acl.c:979 ast_ouraddrfor: For destination '77.72.169.131', our source address is '144.76.51.196'. [Dec 6 22:45:53] DEBUG[28895]: acl.c:979 ast_ouraddrfor: For destination '212.227.18.204', our source address is '144.76.51.196'. [Dec 6 22:45:53] DEBUG[28895]: netsock2.c:138 ast_sockaddr_split_hostport: Splitting 'sipgate.de' into... [Dec 6 22:45:53] DEBUG[28895]: netsock2.c:192 ast_sockaddr_split_hostport: ...host 'sipgate.de' and port ''. [Dec 6 22:45:53] DEBUG[28895]: dnsmgr.c:164 internal_dnsmgr_lookup: doing dnsmgr_lookup for 'sipgate.de' [Dec 6 22:45:53] DEBUG[28895]: srv.c:294 ast_get_srv: ast_get_srv: SRV lookup for '_sip._udp.sipgate.de' mapped to host sipgate.de, port 5060 [Dec 6 22:45:53] DEBUG[28895]: netsock2.c:138 ast_sockaddr_split_hostport: Splitting 'sipgate.de' into... [Dec 6 22:45:53] DEBUG[28895]: netsock2.c:192 ast_sockaddr_split_hostport: ...host 'sipgate.de' and port ''. [Dec 6 22:45:53] DEBUG[28895]: dnsmgr.c:174 internal_dnsmgr_lookup: adding dns manager for 'sipgate.de' [Dec 6 22:45:53] DEBUG[28895]: acl.c:979 ast_ouraddrfor: For destination '217.10.79.9', our source address is '144.76.51.196'. [Dec 6 22:45:53] DEBUG[28895]: netsock2.c:138 ast_sockaddr_split_hostport: Splitting 'sipgate.de' into... [Dec 6 22:45:53] DEBUG[28895]: netsock2.c:192 ast_sockaddr_split_hostport: ...host 'sipgate.de' and port ''. [Dec 6 22:45:53] DEBUG[28895]: netsock2.c:138 ast_sockaddr_split_hostport: Splitting 'sipgate.de' into... [Dec 6 22:45:53] DEBUG[28895]: netsock2.c:192 ast_sockaddr_split_hostport: ...host 'sipgate.de' and port ''. [Dec 6 22:45:53] DEBUG[28895]: netsock2.c:138 ast_sockaddr_split_hostport: Splitting 'sipgate.de' into... [Dec 6 22:45:53] DEBUG[28895]: netsock2.c:192 ast_sockaddr_split_hostport: ...host 'sipgate.de' and port ''. [Dec 6 22:45:53] DEBUG[28895]: netsock2.c:138 ast_sockaddr_split_hostport: Splitting 'sipgate.de' into... [Dec 6 22:45:53] DEBUG[28895]: netsock2.c:192 ast_sockaddr_split_hostport: ...host 'sipgate.de' and port ''. [Dec 6 22:45:53] DEBUG[28895]: netsock2.c:138 ast_sockaddr_split_hostport: Splitting 'sipgate.de' into... [Dec 6 22:45:53] DEBUG[28895]: netsock2.c:192 ast_sockaddr_split_hostport: ...host 'sipgate.de' and port ''. [Dec 6 22:45:53] DEBUG[28895]: netsock2.c:138 ast_sockaddr_split_hostport: Splitting 'sipgate.de' into... [Dec 6 22:45:53] DEBUG[28895]: netsock2.c:192 ast_sockaddr_split_hostport: ...host 'sipgate.de' and port ''. [Dec 6 22:45:53] DEBUG[28895]: manager.c:1476 unref_mansession: Mansession: 0xb6c300f4 refcount now 2 [Dec 6 22:45:53] DEBUG[32276]: manager.c:4846 match_filter: Examining event: Event: Registry Privilege: system,all Timestamp: 1417902353.938315 SequenceNumber: 95 Line: 23474 Func: handle_response_register ChannelType: SIP Username: 1221421 Domain: sipgate.de Status: Registered [Dec 6 22:45:53] DEBUG[28895]: acl.c:979 ast_ouraddrfor: For destination '212.227.67.134', our source address is '144.76.51.196'. [Dec 6 22:45:53] DEBUG[28895]: acl.c:979 ast_ouraddrfor: For destination '212.227.67.204', our source address is '144.76.51.196'. [Dec 6 22:45:54] DEBUG[28895]: netsock2.c:138 ast_sockaddr_split_hostport: Splitting 'sip.viptel.dk' into... [Dec 6 22:45:54] DEBUG[28895]: netsock2.c:192 ast_sockaddr_split_hostport: ...host 'sip.viptel.dk' and port ''. [Dec 6 22:45:54] DEBUG[28895]: dnsmgr.c:164 internal_dnsmgr_lookup: doing dnsmgr_lookup for 'sip.viptel.dk' [Dec 6 22:45:54] DEBUG[28895]: srv.c:294 ast_get_srv: ast_get_srv: SRV lookup for '_sip._udp.sip.viptel.dk' mapped to host vipsbc02.viptel.dk, port 5060 [Dec 6 22:45:54] DEBUG[28895]: netsock2.c:138 ast_sockaddr_split_hostport: Splitting 'vipsbc02.viptel.dk' into... [Dec 6 22:45:54] DEBUG[28895]: netsock2.c:192 ast_sockaddr_split_hostport: ...host 'vipsbc02.viptel.dk' and port ''. [Dec 6 22:45:54] DEBUG[28895]: dnsmgr.c:174 internal_dnsmgr_lookup: adding dns manager for 'sip.viptel.dk' [Dec 6 22:45:54] DEBUG[28895]: acl.c:979 ast_ouraddrfor: For destination '194.247.61.32', our source address is '144.76.51.196'. [Dec 6 22:45:54] DEBUG[28895]: netsock2.c:138 ast_sockaddr_split_hostport: Splitting 'sip.viptel.dk' into... [Dec 6 22:45:54] DEBUG[28895]: netsock2.c:192 ast_sockaddr_split_hostport: ...host 'sip.viptel.dk' and port ''. [Dec 6 22:45:54] DEBUG[28895]: netsock2.c:138 ast_sockaddr_split_hostport: Splitting 'sip.viptel.dk' into... [Dec 6 22:45:54] DEBUG[28895]: netsock2.c:192 ast_sockaddr_split_hostport: ...host 'sip.viptel.dk' and port ''. [Dec 6 22:45:54] DEBUG[28895]: netsock2.c:138 ast_sockaddr_split_hostport: Splitting 'sip.viptel.dk' into... [Dec 6 22:45:54] DEBUG[28895]: netsock2.c:192 ast_sockaddr_split_hostport: ...host 'sip.viptel.dk' and port ''. [Dec 6 22:45:54] DEBUG[28895]: netsock2.c:138 ast_sockaddr_split_hostport: Splitting 'sip.viptel.dk' into... [Dec 6 22:45:54] DEBUG[28895]: netsock2.c:192 ast_sockaddr_split_hostport: ...host 'sip.viptel.dk' and port ''. [Dec 6 22:45:54] DEBUG[28895]: netsock2.c:138 ast_sockaddr_split_hostport: Splitting 'sip.viptel.dk' into... [Dec 6 22:45:54] DEBUG[28895]: netsock2.c:192 ast_sockaddr_split_hostport: ...host 'sip.viptel.dk' and port ''. [Dec 6 22:45:54] DEBUG[28895]: netsock2.c:138 ast_sockaddr_split_hostport: Splitting 'sip.viptel.dk' into... [Dec 6 22:45:54] DEBUG[28895]: netsock2.c:192 ast_sockaddr_split_hostport: ...host 'sip.viptel.dk' and port ''. [Dec 6 22:45:54] DEBUG[28895]: netsock2.c:138 ast_sockaddr_split_hostport: Splitting 'sip.1und1.de.' into... [Dec 6 22:45:54] DEBUG[28895]: netsock2.c:192 ast_sockaddr_split_hostport: ...host 'sip.1und1.de.' and port ''. [Dec 6 22:45:54] DEBUG[28895]: dnsmgr.c:164 internal_dnsmgr_lookup: doing dnsmgr_lookup for 'sip.1und1.de.' [Dec 6 22:45:54] DEBUG[28895]: srv.c:294 ast_get_srv: ast_get_srv: SRV lookup for '_sip._udp.sip.1und1.de.' mapped to host 1und1-2.sip.1und1.de, port 5060 [Dec 6 22:45:54] DEBUG[28895]: netsock2.c:138 ast_sockaddr_split_hostport: Splitting '1und1-2.sip.1und1.de' into... [Dec 6 22:45:54] DEBUG[28895]: netsock2.c:192 ast_sockaddr_split_hostport: ...host '1und1-2.sip.1und1.de' and port ''. [Dec 6 22:45:54] DEBUG[28895]: acl.c:828 resolve_first: Multiple addresses. Using the first only [Dec 6 22:45:54] DEBUG[28895]: dnsmgr.c:174 internal_dnsmgr_lookup: adding dns manager for 'sip.1und1.de.' [Dec 6 22:45:54] DEBUG[28895]: acl.c:979 ast_ouraddrfor: For destination '212.227.67.206', our source address is '144.76.51.196'. [Dec 6 22:45:54] DEBUG[28895]: netsock2.c:138 ast_sockaddr_split_hostport: Splitting 'sip.1und1.de.' into... [Dec 6 22:45:54] DEBUG[28895]: netsock2.c:192 ast_sockaddr_split_hostport: ...host 'sip.1und1.de.' and port ''. [Dec 6 22:45:54] DEBUG[28895]: netsock2.c:138 ast_sockaddr_split_hostport: Splitting 'sip.1und1.de.' into... [Dec 6 22:45:54] DEBUG[28895]: netsock2.c:192 ast_sockaddr_split_hostport: ...host 'sip.1und1.de.' and port ''. [Dec 6 22:45:54] DEBUG[28895]: acl.c:979 ast_ouraddrfor: For destination '212.227.18.134', our source address is '144.76.51.196'. [Dec 6 22:45:54] DEBUG[28895]: acl.c:979 ast_ouraddrfor: For destination '212.227.18.197', our source address is '144.76.51.196'. [Dec 6 22:45:54] DEBUG[28895]: netsock2.c:138 ast_sockaddr_split_hostport: Splitting 'sip.1und1.de.' into... [Dec 6 22:45:54] DEBUG[28895]: netsock2.c:192 ast_sockaddr_split_hostport: ...host 'sip.1und1.de.' and port ''. [Dec 6 22:45:54] DEBUG[28895]: netsock2.c:138 ast_sockaddr_split_hostport: Splitting 'sip.1und1.de.' into... [Dec 6 22:45:54] DEBUG[28895]: netsock2.c:192 ast_sockaddr_split_hostport: ...host 'sip.1und1.de.' and port ''. [Dec 6 22:45:54] DEBUG[28895]: manager.c:1476 unref_mansession: Mansession: 0xb6c300f4 refcount now 2 [Dec 6 22:45:54] DEBUG[32276]: manager.c:4846 match_filter: Examining event: Event: Registry Privilege: system,all Timestamp: 1417902354.132306 SequenceNumber: 96 Line: 23474 Func: handle_response_register ChannelType: SIP Username: 4946153040755 Domain: sip.1und1.de. Status: Registered [Dec 6 22:45:54] DEBUG[28895]: manager.c:1476 unref_mansession: Mansession: 0xb6c300f4 refcount now 2 [Dec 6 22:45:54] DEBUG[32276]: manager.c:4846 match_filter: Examining event: Event: Registry Privilege: system,all Timestamp: 1417902354.176757 SequenceNumber: 97 Line: 23474 Func: handle_response_register ChannelType: SIP Username: 82131279 Domain: sip.viptel.dk Status: Registered [Dec 6 22:45:54] DEBUG[28895]: netsock2.c:138 ast_sockaddr_split_hostport: Splitting 'sipgate.de' into... [Dec 6 22:45:54] DEBUG[28895]: netsock2.c:192 ast_sockaddr_split_hostport: ...host 'sipgate.de' and port ''. [Dec 6 22:45:54] DEBUG[28895]: dnsmgr.c:164 internal_dnsmgr_lookup: doing dnsmgr_lookup for 'sipgate.de' [Dec 6 22:45:54] DEBUG[28895]: srv.c:294 ast_get_srv: ast_get_srv: SRV lookup for '_sip._udp.sipgate.de' mapped to host sipgate.de, port 5060 [Dec 6 22:45:54] DEBUG[28895]: netsock2.c:138 ast_sockaddr_split_hostport: Splitting 'sipgate.de' into... [Dec 6 22:45:54] DEBUG[28895]: netsock2.c:192 ast_sockaddr_split_hostport: ...host 'sipgate.de' and port ''. [Dec 6 22:45:54] DEBUG[28895]: dnsmgr.c:174 internal_dnsmgr_lookup: adding dns manager for 'sipgate.de' [Dec 6 22:45:54] DEBUG[28895]: acl.c:979 ast_ouraddrfor: For destination '217.10.79.9', our source address is '144.76.51.196'. [Dec 6 22:45:54] DEBUG[28895]: netsock2.c:138 ast_sockaddr_split_hostport: Splitting 'sipgate.de' into... [Dec 6 22:45:54] DEBUG[28895]: netsock2.c:192 ast_sockaddr_split_hostport: ...host 'sipgate.de' and port ''. [Dec 6 22:45:54] DEBUG[28895]: netsock2.c:138 ast_sockaddr_split_hostport: Splitting 'sipgate.de' into... [Dec 6 22:45:54] DEBUG[28895]: netsock2.c:192 ast_sockaddr_split_hostport: ...host 'sipgate.de' and port ''. [Dec 6 22:45:54] DEBUG[28895]: netsock2.c:138 ast_sockaddr_split_hostport: Splitting 'sipgate.de' into... [Dec 6 22:45:54] DEBUG[28895]: netsock2.c:192 ast_sockaddr_split_hostport: ...host 'sipgate.de' and port ''. [Dec 6 22:45:54] DEBUG[28895]: netsock2.c:138 ast_sockaddr_split_hostport: Splitting 'sipgate.de' into... [Dec 6 22:45:54] DEBUG[28895]: netsock2.c:192 ast_sockaddr_split_hostport: ...host 'sipgate.de' and port ''. [Dec 6 22:45:54] DEBUG[28895]: netsock2.c:138 ast_sockaddr_split_hostport: Splitting 'sipgate.de' into... [Dec 6 22:45:54] DEBUG[28895]: netsock2.c:192 ast_sockaddr_split_hostport: ...host 'sipgate.de' and port ''. [Dec 6 22:45:54] DEBUG[28895]: netsock2.c:138 ast_sockaddr_split_hostport: Splitting 'sipgate.de' into... [Dec 6 22:45:54] DEBUG[28895]: netsock2.c:192 ast_sockaddr_split_hostport: ...host 'sipgate.de' and port ''. [Dec 6 22:45:54] DEBUG[28895]: manager.c:1476 unref_mansession: Mansession: 0xb6c300f4 refcount now 2 [Dec 6 22:45:54] DEBUG[32276]: manager.c:4846 match_filter: Examining event: Event: Registry Privilege: system,all Timestamp: 1417902354.238062 SequenceNumber: 98 Line: 23474 Func: handle_response_register ChannelType: SIP Username: 2190947 Domain: sipgate.de Status: Registered [Dec 6 22:45:54] DEBUG[28895]: acl.c:979 ast_ouraddrfor: For destination '212.227.18.137', our source address is '144.76.51.196'. [Dec 6 22:45:54] DEBUG[28895]: acl.c:979 ast_ouraddrfor: For destination '212.227.67.197', our source address is '144.76.51.196'. [Dec 6 22:45:54] DEBUG[28895]: netsock2.c:138 ast_sockaddr_split_hostport: Splitting 'sipgate.de' into... [Dec 6 22:45:54] DEBUG[28895]: netsock2.c:192 ast_sockaddr_split_hostport: ...host 'sipgate.de' and port ''. [Dec 6 22:45:54] DEBUG[28895]: dnsmgr.c:164 internal_dnsmgr_lookup: doing dnsmgr_lookup for 'sipgate.de' [Dec 6 22:45:54] DEBUG[28895]: srv.c:294 ast_get_srv: ast_get_srv: SRV lookup for '_sip._udp.sipgate.de' mapped to host sipgate.de, port 5060 [Dec 6 22:45:54] DEBUG[28895]: netsock2.c:138 ast_sockaddr_split_hostport: Splitting 'sipgate.de' into... [Dec 6 22:45:54] DEBUG[28895]: netsock2.c:192 ast_sockaddr_split_hostport: ...host 'sipgate.de' and port ''. [Dec 6 22:45:54] DEBUG[28895]: dnsmgr.c:174 internal_dnsmgr_lookup: adding dns manager for 'sipgate.de' [Dec 6 22:45:54] DEBUG[28895]: acl.c:979 ast_ouraddrfor: For destination '217.10.79.9', our source address is '144.76.51.196'. [Dec 6 22:45:54] DEBUG[28895]: netsock2.c:138 ast_sockaddr_split_hostport: Splitting 'sipgate.de' into... [Dec 6 22:45:54] DEBUG[28895]: netsock2.c:192 ast_sockaddr_split_hostport: ...host 'sipgate.de' and port ''. [Dec 6 22:45:54] DEBUG[28895]: netsock2.c:138 ast_sockaddr_split_hostport: Splitting 'sipgate.de' into... [Dec 6 22:45:54] DEBUG[28895]: netsock2.c:192 ast_sockaddr_split_hostport: ...host 'sipgate.de' and port ''. [Dec 6 22:45:54] DEBUG[28895]: netsock2.c:138 ast_sockaddr_split_hostport: Splitting 'sipgate.de' into... [Dec 6 22:45:54] DEBUG[28895]: netsock2.c:192 ast_sockaddr_split_hostport: ...host 'sipgate.de' and port ''. [Dec 6 22:45:54] DEBUG[28895]: netsock2.c:138 ast_sockaddr_split_hostport: Splitting 'sipgate.de' into... [Dec 6 22:45:54] DEBUG[28895]: netsock2.c:192 ast_sockaddr_split_hostport: ...host 'sipgate.de' and port ''. [Dec 6 22:45:54] DEBUG[28895]: netsock2.c:138 ast_sockaddr_split_hostport: Splitting 'sipgate.de' into... [Dec 6 22:45:54] DEBUG[28895]: netsock2.c:192 ast_sockaddr_split_hostport: ...host 'sipgate.de' and port ''. [Dec 6 22:45:54] DEBUG[28895]: netsock2.c:138 ast_sockaddr_split_hostport: Splitting 'sipgate.de' into... [Dec 6 22:45:54] DEBUG[28895]: netsock2.c:192 ast_sockaddr_split_hostport: ...host 'sipgate.de' and port ''. [Dec 6 22:45:54] DEBUG[32276]: manager.c:4846 match_filter: Examining event: Event: Registry Privilege: system,all Timestamp: 1417902354.339512 SequenceNumber: 99 Line: 23474 Func: handle_response_register ChannelType: SIP Username: 2094839 Domain: sipgate.de Status: Registered [Dec 6 22:45:54] DEBUG[28895]: manager.c:1476 unref_mansession: Mansession: 0xb6c300f4 refcount now 2 [Dec 6 22:45:54] DEBUG[28895]: acl.c:979 ast_ouraddrfor: For destination '212.227.67.137', our source address is '144.76.51.196'. [Dec 6 22:45:54] DEBUG[28895]: acl.c:979 ast_ouraddrfor: For destination '85.183.95.26', our source address is '144.76.51.196'. [Dec 6 22:45:54] DEBUG[28895]: acl.c:979 ast_ouraddrfor: For destination '212.227.67.140', our source address is '144.76.51.196'. [Dec 6 22:45:54] DEBUG[28895]: acl.c:979 ast_ouraddrfor: For destination '212.227.18.140', our source address is '144.76.51.196'. [Dec 6 22:45:54] DEBUG[28895]: acl.c:979 ast_ouraddrfor: For destination '212.227.67.200', our source address is '144.76.51.196'. [Dec 6 22:45:55] DEBUG[28895]: acl.c:979 ast_ouraddrfor: For destination '212.227.18.200', our source address is '144.76.51.196'. [Dec 6 22:45:55] DEBUG[28895]: acl.c:979 ast_ouraddrfor: For destination '212.227.18.206', our source address is '144.76.51.196'. [Dec 6 22:45:55] DEBUG[28895]: acl.c:979 ast_ouraddrfor: For destination '212.227.67.206', our source address is '144.76.51.196'. [Dec 6 22:45:55] DEBUG[28895]: acl.c:979 ast_ouraddrfor: For destination '212.227.18.136', our source address is '144.76.51.196'. [Dec 6 22:45:55] DEBUG[28895]: acl.c:979 ast_ouraddrfor: For destination '212.227.67.136', our source address is '144.76.51.196'. [Dec 6 22:45:55] DEBUG[28895]: acl.c:979 ast_ouraddrfor: For destination '31.17.238.101', our source address is '144.76.51.196'. [Dec 6 22:45:55] DEBUG[28895]: acl.c:979 ast_ouraddrfor: For destination '212.227.67.139', our source address is '144.76.51.196'. [Dec 6 22:45:55] DEBUG[28895]: acl.c:979 ast_ouraddrfor: For destination '212.227.18.139', our source address is '144.76.51.196'. [Dec 6 22:45:55] DEBUG[28895]: acl.c:979 ast_ouraddrfor: For destination '212.227.67.199', our source address is '144.76.51.196'. [Dec 6 22:45:55] DEBUG[28895]: acl.c:979 ast_ouraddrfor: For destination '212.227.18.199', our source address is '144.76.51.196'. [Dec 6 22:45:55] DEBUG[28895]: acl.c:979 ast_ouraddrfor: For destination '212.227.67.138', our source address is '144.76.51.196'. [Dec 6 22:45:55] DEBUG[28880]: manager.c:1476 unref_mansession: Mansession: 0xb6c300f4 refcount now 2 [Dec 6 22:45:55] DEBUG[28895]: acl.c:979 ast_ouraddrfor: For destination '212.227.67.198', our source address is '144.76.51.196'. [Dec 6 22:45:55] DEBUG[28895]: acl.c:979 ast_ouraddrfor: For destination '212.227.18.138', our source address is '144.76.51.196'. [Dec 6 22:45:55] DEBUG[28895]: acl.c:979 ast_ouraddrfor: For destination '212.227.18.198', our source address is '144.76.51.196'. [Dec 6 22:45:55] DEBUG[28895]: acl.c:979 ast_ouraddrfor: For destination '91.35.26.233', our source address is '144.76.51.196'. [Dec 6 22:45:55] DEBUG[28690]: chan_iax2.c:2556 peercnt_add: ip callno count incremented to 5 for 91.35.57.63 [Dec 6 22:45:55] DEBUG[28881]: devicestate.c:344 _ast_device_state: No provider found, checking channel drivers for IAX2 - yetim-lmaa [Dec 6 22:45:55] DEBUG[28881]: chan_iax2.c:14465 iax2_devicestate: Checking device state for device yetim-lmaa [Dec 6 22:45:55] DEBUG[28881]: chan_iax2.c:14473 iax2_devicestate: Found peer. What's device state of yetim-lmaa? addr=1529035071, defaddr=0 maxms=2000, lastms=43 [Dec 6 22:45:55] DEBUG[28881]: devicestate.c:467 do_state_change: Changing state for IAX2/yetim-lmaa - state 0 (Unknown) [Dec 6 22:45:55] DEBUG[28881]: devicestate.c:442 devstate_event: device 'IAX2/yetim-lmaa' state '0' [Dec 6 22:45:55] DEBUG[28883]: devicestate.c:344 _ast_device_state: No provider found, checking channel drivers for iax2 - yetim-lmaa [Dec 6 22:45:55] DEBUG[28912]: app_queue.c:1860 handle_statechange: Device 'IAX2/yetim-lmaa' changed to state '0' (Unknown) but we don't care because they're not a member of any queue. [Dec 6 22:45:55] DEBUG[28883]: chan_iax2.c:14465 iax2_devicestate: Checking device state for device yetim-lmaa [Dec 6 22:45:55] DEBUG[28883]: chan_iax2.c:14473 iax2_devicestate: Found peer. What's device state of yetim-lmaa? addr=1529035071, defaddr=0 maxms=2000, lastms=43 [Dec 6 22:45:56] DEBUG[28881]: devicestate.c:344 _ast_device_state: No provider found, checking channel drivers for IAX2 - yetim-lmaa [Dec 6 22:45:56] DEBUG[28881]: chan_iax2.c:14465 iax2_devicestate: Checking device state for device yetim-lmaa [Dec 6 22:45:56] DEBUG[28881]: chan_iax2.c:14473 iax2_devicestate: Found peer. What's device state of yetim-lmaa? addr=1529035071, defaddr=0 maxms=2000, lastms=43 [Dec 6 22:45:56] DEBUG[28881]: devicestate.c:467 do_state_change: Changing state for IAX2/yetim-lmaa - state 0 (Unknown) [Dec 6 22:45:56] DEBUG[28881]: devicestate.c:442 devstate_event: device 'IAX2/yetim-lmaa' state '0' [Dec 6 22:45:56] DEBUG[28912]: app_queue.c:1860 handle_statechange: Device 'IAX2/yetim-lmaa' changed to state '0' (Unknown) but we don't care because they're not a member of any queue. [Dec 6 22:45:56] DEBUG[28883]: devicestate.c:344 _ast_device_state: No provider found, checking channel drivers for iax2 - yetim-lmaa [Dec 6 22:45:56] DEBUG[28883]: chan_iax2.c:14465 iax2_devicestate: Checking device state for device yetim-lmaa [Dec 6 22:45:56] DEBUG[28883]: chan_iax2.c:14473 iax2_devicestate: Found peer. What's device state of yetim-lmaa? addr=1529035071, defaddr=0 maxms=2000, lastms=43 [Dec 6 22:45:56] DEBUG[28895]: acl.c:979 ast_ouraddrfor: For destination '88.71.80.160', our source address is '144.76.51.196'. [Dec 6 22:45:56] DEBUG[28688]: chan_iax2.c:2912 sched_delay_remove: schedule decrement of callno used for 91.35.57.63 in 60 seconds [Dec 6 22:45:56] DEBUG[28895]: acl.c:979 ast_ouraddrfor: For destination '194.247.61.32', our source address is '144.76.51.196'. [Dec 6 22:45:56] DEBUG[28895]: acl.c:979 ast_ouraddrfor: For destination '212.227.67.131', our source address is '144.76.51.196'. [Dec 6 22:45:56] DEBUG[28690]: chan_iax2.c:2556 peercnt_add: ip callno count incremented to 5 for 87.139.190.172 [Dec 6 22:45:56] DEBUG[28881]: devicestate.c:344 _ast_device_state: No provider found, checking channel drivers for IAX2 - s-mn24 [Dec 6 22:45:56] DEBUG[28881]: chan_iax2.c:14465 iax2_devicestate: Checking device state for device s-mn24 [Dec 6 22:45:56] DEBUG[28881]: chan_iax2.c:14473 iax2_devicestate: Found peer. What's device state of s-mn24? addr=1468776108, defaddr=0 maxms=2000, lastms=50 [Dec 6 22:45:56] DEBUG[28881]: devicestate.c:467 do_state_change: Changing state for IAX2/s-mn24 - state 0 (Unknown) [Dec 6 22:45:56] DEBUG[28881]: devicestate.c:442 devstate_event: device 'IAX2/s-mn24' state '0' [Dec 6 22:45:56] DEBUG[28883]: devicestate.c:344 _ast_device_state: No provider found, checking channel drivers for iax2 - s-mn24 [Dec 6 22:45:56] DEBUG[28883]: chan_iax2.c:14465 iax2_devicestate: Checking device state for device s-mn24 [Dec 6 22:45:56] DEBUG[28883]: chan_iax2.c:14473 iax2_devicestate: Found peer. What's device state of s-mn24? addr=1468776108, defaddr=0 maxms=2000, lastms=50 [Dec 6 22:45:56] DEBUG[28883]: devicestate.c:344 _ast_device_state: No provider found, checking channel drivers for iax2 - s-mn24 [Dec 6 22:45:56] DEBUG[28883]: chan_iax2.c:14465 iax2_devicestate: Checking device state for device s-mn24 [Dec 6 22:45:56] DEBUG[28883]: chan_iax2.c:14473 iax2_devicestate: Found peer. What's device state of s-mn24? addr=1468776108, defaddr=0 maxms=2000, lastms=50 [Dec 6 22:45:56] DEBUG[28912]: app_queue.c:1860 handle_statechange: Device 'IAX2/s-mn24' changed to state '0' (Unknown) but we don't care because they're not a member of any queue. [Dec 6 22:45:56] DEBUG[28881]: devicestate.c:344 _ast_device_state: No provider found, checking channel drivers for IAX2 - s-mn24 [Dec 6 22:45:56] DEBUG[28881]: chan_iax2.c:14465 iax2_devicestate: Checking device state for device s-mn24 [Dec 6 22:45:56] DEBUG[28881]: chan_iax2.c:14473 iax2_devicestate: Found peer. What's device state of s-mn24? addr=1468776108, defaddr=0 maxms=2000, lastms=50 [Dec 6 22:45:56] DEBUG[28881]: devicestate.c:467 do_state_change: Changing state for IAX2/s-mn24 - state 0 (Unknown) [Dec 6 22:45:56] DEBUG[28881]: devicestate.c:442 devstate_event: device 'IAX2/s-mn24' state '0' [Dec 6 22:45:56] DEBUG[28883]: devicestate.c:344 _ast_device_state: No provider found, checking channel drivers for iax2 - s-mn24 [Dec 6 22:45:56] DEBUG[28883]: chan_iax2.c:14465 iax2_devicestate: Checking device state for device s-mn24 [Dec 6 22:45:56] DEBUG[28883]: chan_iax2.c:14473 iax2_devicestate: Found peer. What's device state of s-mn24? addr=1468776108, defaddr=0 maxms=2000, lastms=50 [Dec 6 22:45:56] DEBUG[28883]: devicestate.c:344 _ast_device_state: No provider found, checking channel drivers for iax2 - s-mn24 [Dec 6 22:45:56] DEBUG[28883]: chan_iax2.c:14465 iax2_devicestate: Checking device state for device s-mn24 [Dec 6 22:45:56] DEBUG[28883]: chan_iax2.c:14473 iax2_devicestate: Found peer. What's device state of s-mn24? addr=1468776108, defaddr=0 maxms=2000, lastms=50 [Dec 6 22:45:56] DEBUG[28912]: app_queue.c:1860 handle_statechange: Device 'IAX2/s-mn24' changed to state '0' (Unknown) but we don't care because they're not a member of any queue. [Dec 6 22:45:56] DEBUG[28688]: chan_iax2.c:2912 sched_delay_remove: schedule decrement of callno used for 87.139.190.172 in 60 seconds [Dec 6 22:45:56] DEBUG[28895]: acl.c:979 ast_ouraddrfor: For destination '212.227.18.131', our source address is '144.76.51.196'. [Dec 6 22:45:56] DEBUG[28895]: acl.c:979 ast_ouraddrfor: For destination '212.227.67.201', our source address is '144.76.51.196'. [Dec 6 22:45:56] DEBUG[28895]: acl.c:979 ast_ouraddrfor: For destination '212.227.18.201', our source address is '144.76.51.196'. [Dec 6 22:45:56] DEBUG[28895]: acl.c:979 ast_ouraddrfor: For destination '77.72.169.129', our source address is '144.76.51.196'. [Dec 6 22:45:56] DEBUG[28895]: acl.c:979 ast_ouraddrfor: For destination '212.227.18.133', our source address is '144.76.51.196'. [Dec 6 22:45:56] DEBUG[28895]: acl.c:979 ast_ouraddrfor: For destination '212.227.67.203', our source address is '144.76.51.196'. [Dec 6 22:45:56] DEBUG[28895]: acl.c:979 ast_ouraddrfor: For destination '212.227.67.133', our source address is '144.76.51.196'. [Dec 6 22:45:56] DEBUG[28895]: acl.c:979 ast_ouraddrfor: For destination '212.227.18.203', our source address is '144.76.51.196'. [Dec 6 22:45:56] DEBUG[28895]: acl.c:979 ast_ouraddrfor: For destination '212.227.67.202', our source address is '144.76.51.196'. [Dec 6 22:45:56] DEBUG[28895]: acl.c:979 ast_ouraddrfor: For destination '212.227.18.132', our source address is '144.76.51.196'. [Dec 6 22:45:56] DEBUG[28692]: res_timing_timerfd.c:225 timerfd_timer_ack: Expected to acknowledge 1 ticks but got 3 instead [Dec 6 22:45:57] DEBUG[28895]: acl.c:979 ast_ouraddrfor: For destination '212.227.67.132', our source address is '144.76.51.196'. [Dec 6 22:45:57] DEBUG[28895]: acl.c:979 ast_ouraddrfor: For destination '212.227.18.202', our source address is '144.76.51.196'. [Dec 6 22:45:57] DEBUG[28895]: acl.c:979 ast_ouraddrfor: For destination '212.227.18.205', our source address is '144.76.51.196'. [Dec 6 22:45:57] DEBUG[28895]: acl.c:979 ast_ouraddrfor: For destination '217.10.79.9', our source address is '144.76.51.196'. [Dec 6 22:46:00] DEBUG[28880]: manager.c:1476 unref_mansession: Mansession: 0xb6c300f4 refcount now 2