[May 15 01:04:22] DEBUG[27636] chan_sip.c: = Looking for Call ID: bbcdb11d-d09b75a2@192.168.42.101 (Checking From) --From tag de49dea9dbdc4586o2 --To-tag [May 15 01:04:22] DEBUG[27636] chan_sip.c: **** Received NOTIFY (4) - Command in SIP NOTIFY [May 15 01:04:22] DEBUG[27636] chan_sip.c: Trying to put 'SIP/2.0 200' onto UDP socket destined for 95.140.28.114:5062 [May 15 01:04:22] DEBUG[27636] chan_sip.c: = Looking for Call ID: f4efc0c4-cb8cdae@192.168.42.186 (Checking From) --From tag bd0b188c1a0c1886o0 --To-tag [May 15 01:04:22] DEBUG[27636] chan_sip.c: **** Received NOTIFY (4) - Command in SIP NOTIFY [May 15 01:04:22] DEBUG[27636] chan_sip.c: Trying to put 'SIP/2.0 200' onto UDP socket destined for 89.175.52.166:1024 [May 15 01:04:22] DEBUG[27636] chan_sip.c: Allocating new SIP dialog for 19156b384980ebe75104274609085900@89.188.109.170:5060 - OPTIONS (No RTP) [May 15 01:04:22] DEBUG[27636] acl.c: For destination '195.128.55.18', our source address is '89.188.109.170'. [May 15 01:04:22] DEBUG[27636] chan_sip.c: Target address 195.128.55.18:1062 is not local, substituting externaddr [May 15 01:04:22] DEBUG[27636] chan_sip.c: Setting SIP_TRANSPORT_UDP with address 89.188.109.170:5060 [May 15 01:04:22] DEBUG[27636] chan_sip.c: SIP call-id changed from '19156b384980ebe75104274609085900@89.188.109.170:5060' to '7c56f5c1609a0c58215892f134bf9ff9@89.188.109.170:5060' [May 15 01:04:22] DEBUG[27636] chan_sip.c: Initializing initreq for method OPTIONS - callid 7c56f5c1609a0c58215892f134bf9ff9@89.188.109.170:5060 [May 15 01:04:22] DEBUG[27636] chan_sip.c: Trying to put 'OPTIONS sip' onto UDP socket destined for 195.128.55.18:1062 [May 15 01:04:22] DEBUG[27636] chan_sip.c: = Looking for Call ID: 7c56f5c1609a0c58215892f134bf9ff9@89.188.109.170:5060 (Checking To) --From tag as39b23c94 --To-tag 1f94f8dcbbab34f6i0 [May 15 01:04:22] DEBUG[27636] chan_sip.c: Stopping retransmission on '7c56f5c1609a0c58215892f134bf9ff9@89.188.109.170:5060' of Request 102: Match Found [May 15 01:04:22] DEBUG[27636] chan_sip.c: Destroying SIP dialog 7c56f5c1609a0c58215892f134bf9ff9@89.188.109.170:5060 [May 15 01:04:22] DEBUG[27636] chan_sip.c: Allocating new SIP dialog for 1c223b0e40f89a165f9741ee210b81ed@89.188.109.170:5060 - OPTIONS (No RTP) [May 15 01:04:22] DEBUG[27636] acl.c: For destination '195.190.123.162', our source address is '89.188.109.170'. [May 15 01:04:22] DEBUG[27636] chan_sip.c: Target address 195.190.123.162:5060 is not local, substituting externaddr [May 15 01:04:22] DEBUG[27636] chan_sip.c: Setting SIP_TRANSPORT_UDP with address 89.188.109.170:5060 [May 15 01:04:22] DEBUG[27636] chan_sip.c: SIP call-id changed from '1c223b0e40f89a165f9741ee210b81ed@89.188.109.170:5060' to '1f528f9b289050d97d2d394c3bbc007f@89.188.109.170:5060' [May 15 01:04:22] DEBUG[27636] chan_sip.c: Initializing initreq for method OPTIONS - callid 1f528f9b289050d97d2d394c3bbc007f@89.188.109.170:5060 [May 15 01:04:22] DEBUG[27636] chan_sip.c: Trying to put 'OPTIONS sip' onto UDP socket destined for 195.190.123.162:5060 [May 15 01:04:22] DEBUG[27636] chan_sip.c: = Looking for Call ID: 1f528f9b289050d97d2d394c3bbc007f@89.188.109.170:5060 (Checking To) --From tag as19bbaac5 --To-tag 480f53961b91097di0 [May 15 01:04:22] DEBUG[27636] chan_sip.c: Stopping retransmission on '1f528f9b289050d97d2d394c3bbc007f@89.188.109.170:5060' of Request 102: Match Found [May 15 01:04:22] DEBUG[27636] chan_sip.c: Destroying SIP dialog 1f528f9b289050d97d2d394c3bbc007f@89.188.109.170:5060 [May 15 01:04:22] DEBUG[27636] chan_sip.c: = Looking for Call ID: 4b64fa21523e50442f9eb0d642a9c3b1@10.77.9.246 (Checking From) --From tag as6845cb4d --To-tag [May 15 01:04:22] DEBUG[27636] acl.c: For destination '10.77.9.246', our source address is '10.77.9.146'. [May 15 01:04:22] DEBUG[27636] chan_sip.c: Setting SIP_TRANSPORT_UDP with address 10.77.9.146:5060 [May 15 01:04:22] DEBUG[27636] netsock2.c: Splitting '10.77.9.246:5060' into... [May 15 01:04:22] DEBUG[27636] netsock2.c: ...host '10.77.9.246' and port '5060'. [May 15 01:04:22] DEBUG[27636] chan_sip.c: Allocating new SIP dialog for 4b64fa21523e50442f9eb0d642a9c3b1@10.77.9.246 - INVITE (No RTP) [May 15 01:04:22] DEBUG[27636][C-0000153d] chan_sip.c: **** Received INVITE (5) - Command in SIP INVITE [May 15 01:04:22] DEBUG[27636][C-0000153d] sip/reqresp_parser.c: Begin: parsing SIP "Supported: replaces, timer" [May 15 01:04:22] DEBUG[27636][C-0000153d] sip/reqresp_parser.c: Found SIP option: -replaces- [May 15 01:04:22] DEBUG[27636][C-0000153d] sip/reqresp_parser.c: Matched SIP option: replaces [May 15 01:04:22] DEBUG[27636][C-0000153d] sip/reqresp_parser.c: Found SIP option: -timer- [May 15 01:04:22] DEBUG[27636][C-0000153d] sip/reqresp_parser.c: Matched SIP option: timer [May 15 01:04:22] DEBUG[27636][C-0000153d] netsock2.c: Splitting '10.77.9.246:5060' into... [May 15 01:04:22] DEBUG[27636][C-0000153d] netsock2.c: ...host '10.77.9.246' and port '5060'. [May 15 01:04:22] DEBUG[27636][C-0000153d] netsock2.c: Splitting '10.77.9.246' into... [May 15 01:04:22] DEBUG[27636][C-0000153d] netsock2.c: ...host '10.77.9.246' and port ''. [May 15 01:04:22] DEBUG[27636][C-0000153d] rtp_engine.c: Using engine 'asterisk' for RTP instance '0x7f693c981548' [May 15 01:04:22] DEBUG[27636][C-0000153d] res_rtp_asterisk.c: Allocated port 11354 for RTP instance '0x7f693c981548' [May 15 01:04:22] DEBUG[27636][C-0000153d] netsock2.c: Splitting '89.188.109.170' into... [May 15 01:04:22] DEBUG[27636][C-0000153d] netsock2.c: ...host '89.188.109.170' and port ''. [May 15 01:04:22] DEBUG[27636][C-0000153d] netsock2.c: Splitting '10.77.9.146' into... [May 15 01:04:22] DEBUG[27636][C-0000153d] netsock2.c: ...host '10.77.9.146' and port ''. [May 15 01:04:22] DEBUG[27636][C-0000153d] netsock2.c: Splitting '192.168.99.1' into... [May 15 01:04:22] DEBUG[27636][C-0000153d] netsock2.c: ...host '192.168.99.1' and port ''. [May 15 01:04:22] DEBUG[27636][C-0000153d] rtp_engine.c: RTP instance '0x7f693c981548' is setup and ready to go [May 15 01:04:22] DEBUG[27636][C-0000153d] res_rtp_asterisk.c: Setup RTCP on RTP instance '0x7f693c981548' [May 15 01:04:22] VERBOSE[27636][C-0000153d] netsock2.c: [May 15 01:04:22] == Using SIP RTP CoS mark 5 [May 15 01:04:22] DEBUG[27636][C-0000153d] chan_sip.c: Setting NAT on RTP to Off [May 15 01:04:22] DEBUG[27636][C-0000153d] chan_sip.c: Processing session-level SDP v=0... UNSUPPORTED OR FAILED. [May 15 01:04:22] DEBUG[27636][C-0000153d] chan_sip.c: Processing session-level SDP o=root 589150490 589150490 IN IP4 10.77.9.246... OK. [May 15 01:04:22] DEBUG[27636][C-0000153d] chan_sip.c: Processing session-level SDP s=Asterisk PBX 1.6.0.9... UNSUPPORTED OR FAILED. [May 15 01:04:22] DEBUG[27636][C-0000153d] netsock2.c: Splitting '10.77.9.246' into... [May 15 01:04:22] DEBUG[27636][C-0000153d] netsock2.c: ...host '10.77.9.246' and port ''. [May 15 01:04:22] DEBUG[27636][C-0000153d] chan_sip.c: Processing session-level SDP c=IN IP4 10.77.9.246... OK. [May 15 01:04:22] DEBUG[27636][C-0000153d] chan_sip.c: Processing session-level SDP t=0 0... UNSUPPORTED OR FAILED. [May 15 01:04:22] DEBUG[27636][C-0000153d] rtp_engine.c: Setting payload 8 based on m type on 0x7f69799e4c10 [May 15 01:04:22] DEBUG[27636][C-0000153d] rtp_engine.c: Setting payload 0 based on m type on 0x7f69799e4c10 [May 15 01:04:22] DEBUG[27636][C-0000153d] chan_sip.c: Processing media-level (audio) SDP a=rtpmap:8 PCMA/8000... OK. [May 15 01:04:22] DEBUG[27636][C-0000153d] chan_sip.c: Processing media-level (audio) SDP a=rtpmap:0 PCMU/8000... OK. [May 15 01:04:22] DEBUG[27636][C-0000153d] chan_sip.c: Processing media-level (audio) SDP a=ptime:20... OK. [May 15 01:04:22] DEBUG[27636][C-0000153d] chan_sip.c: Processing media-level (audio) SDP a=sendrecv... OK. [May 15 01:04:22] DEBUG[27636][C-0000153d] res_rtp_asterisk.c: Setting RTCP address on RTP instance '0x7f693c981548' [May 15 01:04:22] DEBUG[27636][C-0000153d] rtp_engine.c: Copying payload 0 from 0x7f69799e4c10 to 0x7f693c981710 [May 15 01:04:22] DEBUG[27636][C-0000153d] rtp_engine.c: Copying payload 8 from 0x7f69799e4c10 to 0x7f693c981710 [May 15 01:04:22] DEBUG[27636][C-0000153d] res_rtp_asterisk.c: Ignoring duplicate RTCP property on RTP instance '0x7f693c981548' [May 15 01:04:22] DEBUG[27636][C-0000153d] chan_sip.c: We're settling with these formats: (ulaw|alaw) [May 15 01:04:22] DEBUG[27636][C-0000153d] chan_sip.c: Checking SIP call limits for device [May 15 01:04:22] DEBUG[27636][C-0000153d] chan_sip.c: Updating call counter for incoming call [May 15 01:04:22] DEBUG[27636][C-0000153d] netsock2.c: Splitting '10.77.9.146' into... [May 15 01:04:22] DEBUG[27636][C-0000153d] netsock2.c: ...host '10.77.9.146' and port ''. [May 15 01:04:22] DEBUG[27636][C-0000153d] netsock2.c: Splitting '10.77.9.246' into... [May 15 01:04:22] DEBUG[27636][C-0000153d] netsock2.c: ...host '10.77.9.246' and port ''. [May 15 01:04:22] DEBUG[27636][C-0000153d] chan_sip.c: Incoming INVITE with 'timer' option supported [May 15 01:04:22] DEBUG[27636][C-0000153d] chan_sip.c: *** Our native formats are (alaw) [May 15 01:04:22] DEBUG[27636][C-0000153d] chan_sip.c: *** Joint capabilities are (ulaw|alaw) [May 15 01:04:22] DEBUG[27629] devicestate.c: No provider found, checking channel drivers for SIP - bluesky_pbx [May 15 01:04:22] DEBUG[27636][C-0000153d] chan_sip.c: *** Our capabilities are (ulaw|alaw) [May 15 01:04:22] DEBUG[27636][C-0000153d] chan_sip.c: *** AST_CODEC_CHOOSE formats are alaw [May 15 01:04:22] DEBUG[27636][C-0000153d] chan_sip.c: This channel will not be able to handle video. [May 15 01:04:22] DEBUG[27629] chan_sip.c: Checking device state for peer bluesky_pbx [May 15 01:04:22] DEBUG[27629] devicestate.c: Changing state for SIP/bluesky_pbx - state 2 (In use) [May 15 01:04:22] DEBUG[27636][C-0000153d] chan_sip.c: build_route: Contact hop: [May 15 01:04:22] DEBUG[27629] devicestate.c: device 'SIP/bluesky_pbx' state '2' [May 15 01:04:22] DEBUG[27636][C-0000153d] chan_sip.c: SIP/bluesky_pbx-00001fe5: New call is still down.... Trying... [May 15 01:04:22] DEBUG[27636][C-0000153d] chan_sip.c: Trying to put 'SIP/2.0 100' onto UDP socket destined for 10.77.9.246:5060 [May 15 01:04:22] DEBUG[27629] devicestate.c: No provider found, checking channel drivers for SIP - bluesky_pbx [May 15 01:04:22] DEBUG[27629] chan_sip.c: Checking device state for peer bluesky_pbx [May 15 01:04:22] DEBUG[27629] devicestate.c: Changing state for SIP/bluesky_pbx - state 2 (In use) [May 15 01:04:22] DEBUG[27629] devicestate.c: device 'SIP/bluesky_pbx' state '2' [May 15 01:04:22] DEBUG[7181][C-0000153d] pbx.c: Launching 'Dial' [May 15 01:04:22] VERBOSE[7181][C-0000153d] pbx.c: [May 15 01:04:22] -- Executing [2321201@catch-incoming:1] Dial("SIP/bluesky_pbx-00001fe5", "SIP/6203") in new stack [May 15 01:04:22] DEBUG[7181][C-0000153d] chan_sip.c: Asked to create a SIP channel with formats: (alaw) [May 15 01:04:22] DEBUG[27642] app_queue.c: Device 'SIP/bluesky_pbx' changed to state '2' (In use) but we don't care because they're not a member of any queue. [May 15 01:04:22] DEBUG[27642] app_queue.c: Device 'SIP/bluesky_pbx' changed to state '2' (In use) but we don't care because they're not a member of any queue. [May 15 01:04:22] DEBUG[7181][C-0000153d] chan_sip.c: Allocating new SIP dialog for 0bbe73672ba38aaa13c89f7d254a581a@89.188.109.170:5060 - INVITE (No RTP) [May 15 01:04:22] DEBUG[7181][C-0000153d] rtp_engine.c: Using engine 'asterisk' for RTP instance '0x7f68c4011468' [May 15 01:04:22] DEBUG[7181][C-0000153d] res_rtp_asterisk.c: Allocated port 17690 for RTP instance '0x7f68c4011468' [May 15 01:04:22] DEBUG[7181][C-0000153d] netsock2.c: Splitting '89.188.109.170' into... [May 15 01:04:22] DEBUG[7181][C-0000153d] netsock2.c: ...host '89.188.109.170' and port ''. [May 15 01:04:22] DEBUG[7181][C-0000153d] netsock2.c: Splitting '10.77.9.146' into... [May 15 01:04:22] DEBUG[7181][C-0000153d] netsock2.c: ...host '10.77.9.146' and port ''. [May 15 01:04:22] DEBUG[7181][C-0000153d] netsock2.c: Splitting '192.168.99.1' into... [May 15 01:04:22] DEBUG[7181][C-0000153d] netsock2.c: ...host '192.168.99.1' and port ''. [May 15 01:04:22] DEBUG[7181][C-0000153d] rtp_engine.c: RTP instance '0x7f68c4011468' is setup and ready to go [May 15 01:04:22] DEBUG[7181][C-0000153d] res_rtp_asterisk.c: Setup RTCP on RTP instance '0x7f68c4011468' [May 15 01:04:22] VERBOSE[7181][C-0000153d] netsock2.c: [May 15 01:04:22] == Using SIP RTP CoS mark 5 [May 15 01:04:22] DEBUG[7181][C-0000153d] chan_sip.c: Setting NAT on RTP to On [May 15 01:04:22] DEBUG[7181][C-0000153d] acl.c: For destination '195.128.55.18', our source address is '89.188.109.170'. [May 15 01:04:22] DEBUG[7181][C-0000153d] chan_sip.c: Target address 195.128.55.18:1095 is not local, substituting externaddr [May 15 01:04:22] DEBUG[7181][C-0000153d] chan_sip.c: Setting SIP_TRANSPORT_UDP with address 89.188.109.170:5060 [May 15 01:04:22] DEBUG[7181][C-0000153d] chan_sip.c: Setting NAT on RTP to On [May 15 01:04:22] DEBUG[7181][C-0000153d] chan_sip.c: SIP call-id changed from '0bbe73672ba38aaa13c89f7d254a581a@89.188.109.170:5060' to '73d9d3443c136abc6c72a260496e8bf9@89.188.109.170:5060' [May 15 01:04:22] DEBUG[7181][C-0000153d] chan_sip.c: *** Our native formats are (alaw) [May 15 01:04:22] DEBUG[7181][C-0000153d] chan_sip.c: *** Joint capabilities are (alaw) [May 15 01:04:22] DEBUG[7181][C-0000153d] chan_sip.c: *** Our capabilities are (ulaw|alaw) [May 15 01:04:22] DEBUG[7181][C-0000153d] chan_sip.c: *** AST_CODEC_CHOOSE formats are alaw [May 15 01:04:22] DEBUG[7181][C-0000153d] chan_sip.c: *** Our preferred formats from the incoming channel are (alaw) [May 15 01:04:22] DEBUG[7181][C-0000153d] chan_sip.c: This channel will not be able to handle video. [May 15 01:04:22] DEBUG[7181][C-0000153d] channel_internal_api.c: Channel Call ID changing from [C-0000153d] to [C-0000153d] [May 15 01:04:22] DEBUG[7181][C-0000153d] chan_sip.c: Outgoing Call for 6203 [May 15 01:04:22] DEBUG[7181][C-0000153d] chan_sip.c: Updating call counter for outgoing call [May 15 01:04:22] DEBUG[27629] devicestate.c: No provider found, checking channel drivers for SIP - 6203 [May 15 01:04:22] DEBUG[27629] chan_sip.c: Checking device state for peer 6203 [May 15 01:04:22] DEBUG[27629] devicestate.c: Changing state for SIP/6203 - state 6 (Ringing) [May 15 01:04:22] DEBUG[27629] devicestate.c: device 'SIP/6203' state '6' [May 15 01:04:22] DEBUG[27642] devicestate.c: device 'Queue:q-cgroup-19_avail' state '1' [May 15 01:04:22] DEBUG[7181][C-0000153d] chan_sip.c: ** Our capability: (ulaw|alaw) Video flag: False Text flag: False [May 15 01:04:22] DEBUG[7181][C-0000153d] chan_sip.c: ** Our prefcodec: (alaw) [May 15 01:04:22] DEBUG[7181][C-0000153d] chan_sip.c: -- Done with adding codecs to SDP [May 15 01:04:22] DEBUG[27642] app_queue.c: Device 'SIP/6203' changed to state '6' (Ringing) [May 15 01:04:22] DEBUG[7181][C-0000153d] chan_sip.c: Done building SDP. Settling with this capability: (ulaw|alaw) [May 15 01:04:22] DEBUG[7181][C-0000153d] chan_sip.c: Initializing initreq for method INVITE - callid 73d9d3443c136abc6c72a260496e8bf9@89.188.109.170:5060 [May 15 01:04:22] DEBUG[27642] app_queue.c: Device 'Queue:q-cgroup-19_avail' changed to state '1' (Not in use) but we don't care because they're not a member of any queue. [May 15 01:04:22] DEBUG[7181][C-0000153d] chan_sip.c: Trying to put 'INVITE sip:' onto UDP socket destined for 195.128.55.18:1095 [May 15 01:04:22] VERBOSE[7181][C-0000153d] app_dial.c: [May 15 01:04:22] -- Called SIP/6203 [May 15 01:04:22] DEBUG[27636] chan_sip.c: = Looking for Call ID: 73d9d3443c136abc6c72a260496e8bf9@89.188.109.170:5060 (Checking To) --From tag as77affb55 --To-tag [May 15 01:04:22] DEBUG[27636][C-0000153d] chan_sip.c: (Provisional) Stopping retransmission (but retaining packet) on '73d9d3443c136abc6c72a260496e8bf9@89.188.109.170:5060' Request 102: Found [May 15 01:04:22] DEBUG[27636][C-0000153d] chan_sip.c: SIP response 100 to standard invite [May 15 01:04:22] DEBUG[27636] chan_sip.c: = Looking for Call ID: b6c6a52c-95e4f56d@192.168.42.26 (Checking From) --From tag 57f382007579ea9o0 --To-tag [May 15 01:04:22] DEBUG[27636] chan_sip.c: **** Received NOTIFY (4) - Command in SIP NOTIFY [May 15 01:04:22] DEBUG[27636] chan_sip.c: Trying to put 'SIP/2.0 200' onto UDP socket destined for 95.140.28.114:5060 [May 15 01:04:22] DEBUG[27636] chan_sip.c: = Looking for Call ID: 73d9d3443c136abc6c72a260496e8bf9@89.188.109.170:5060 (Checking To) --From tag as77affb55 --To-tag fd18ef86984e302bi0 [May 15 01:04:22] DEBUG[27636][C-0000153d] chan_sip.c: (Provisional) Stopping retransmission (but retaining packet) on '73d9d3443c136abc6c72a260496e8bf9@89.188.109.170:5060' Request 102: Found [May 15 01:04:22] DEBUG[27636][C-0000153d] chan_sip.c: SIP response 180 to standard invite [May 15 01:04:22] DEBUG[27636][C-0000153d] chan_sip.c: build_route: Contact hop: [May 15 01:04:22] DEBUG[27629] devicestate.c: No provider found, checking channel drivers for SIP - 6203 [May 15 01:04:22] DEBUG[27629] chan_sip.c: Checking device state for peer 6203 [May 15 01:04:22] DEBUG[27629] devicestate.c: Changing state for SIP/6203 - state 6 (Ringing) [May 15 01:04:22] DEBUG[27629] devicestate.c: device 'SIP/6203' state '6' [May 15 01:04:22] VERBOSE[7181][C-0000153d] app_dial.c: [May 15 01:04:22] -- SIP/6203-00001fe6 is ringing [May 15 01:04:22] DEBUG[7181][C-0000153d] rtp_engine.c: Setting early bridge SDP of 'SIP/bluesky_pbx-00001fe5' with that of 'SIP/6203-00001fe6' [May 15 01:04:22] DEBUG[7181][C-0000153d] chan_sip.c: Trying to put 'SIP/2.0 180' onto UDP socket destined for 10.77.9.246:5060 [May 15 01:04:22] DEBUG[27642] devicestate.c: device 'Queue:q-cgroup-19_avail' state '1' [May 15 01:04:22] DEBUG[27642] app_queue.c: Device 'SIP/6203' changed to state '6' (Ringing) [May 15 01:04:22] DEBUG[27642] app_queue.c: Device 'Queue:q-cgroup-19_avail' changed to state '1' (Not in use) but we don't care because they're not a member of any queue. [May 15 01:04:23] DEBUG[27636] chan_sip.c: = Looking for Call ID: d4dfef12-211ec44f@192.168.42.9 (Checking From) --From tag ee4f18beb11bc693o2 --To-tag [May 15 01:04:23] DEBUG[27636] chan_sip.c: **** Received NOTIFY (4) - Command in SIP NOTIFY [May 15 01:04:23] DEBUG[27636] chan_sip.c: Trying to put 'SIP/2.0 200' onto UDP socket destined for 89.175.176.198:5062 [May 15 01:04:23] DEBUG[27636] chan_sip.c: = Looking for Call ID: bc39a926-2f6831d0@192.168.43.2 (Checking From) --From tag 9a19bbeed9c5fda8o0 --To-tag [May 15 01:04:23] DEBUG[27636] chan_sip.c: **** Received NOTIFY (4) - Command in SIP NOTIFY [May 15 01:04:23] DEBUG[27636] chan_sip.c: Trying to put 'SIP/2.0 200' onto UDP socket destined for 195.128.55.18:1095 [May 15 01:04:23] DEBUG[27636] chan_sip.c: = Looking for Call ID: b130c185-59c95cf1@192.168.42.168 (Checking From) --From tag e06f855573cceb61o2 --To-tag [May 15 01:04:23] DEBUG[27636] chan_sip.c: **** Received NOTIFY (4) - Command in SIP NOTIFY [May 15 01:04:23] DEBUG[27636] chan_sip.c: Trying to put 'SIP/2.0 200' onto UDP socket destined for 195.68.128.222:5062 [May 15 01:04:23] DEBUG[27636] chan_sip.c: Allocating new SIP dialog for 4698b4b712aa614a6d5ec31a5c7c9f52@89.188.109.170:5060 - OPTIONS (No RTP) [May 15 01:04:23] DEBUG[27636] acl.c: For destination '82.142.173.58', our source address is '89.188.109.170'. [May 15 01:04:23] DEBUG[27636] chan_sip.c: Target address 82.142.173.58:5060 is not local, substituting externaddr [May 15 01:04:23] DEBUG[27636] chan_sip.c: Setting SIP_TRANSPORT_UDP with address 89.188.109.170:5060 [May 15 01:04:23] DEBUG[27636] chan_sip.c: SIP call-id changed from '4698b4b712aa614a6d5ec31a5c7c9f52@89.188.109.170:5060' to '07717f973ebd657a7513745942f5a460@89.188.109.170:5060' [May 15 01:04:23] DEBUG[27636] chan_sip.c: Initializing initreq for method OPTIONS - callid 07717f973ebd657a7513745942f5a460@89.188.109.170:5060 [May 15 01:04:23] DEBUG[27636] chan_sip.c: Trying to put 'OPTIONS sip' onto UDP socket destined for 82.142.173.58:5060 [May 15 01:04:23] DEBUG[27636] chan_sip.c: = Looking for Call ID: 88f95f1b-875c6bc3@192.168.43.24 (Checking From) --From tag 1067275b586f4083o1 --To-tag [May 15 01:04:23] DEBUG[27636] chan_sip.c: **** Received NOTIFY (4) - Command in SIP NOTIFY [May 15 01:04:23] DEBUG[27636] chan_sip.c: Trying to put 'SIP/2.0 200' onto UDP socket destined for 195.128.55.18:1049 [May 15 01:04:23] DEBUG[27636] chan_sip.c: = Looking for Call ID: 55028e37-6a91a6a6@192.168.42.59 (Checking From) --From tag 813b3323bb4166aao0 --To-tag [May 15 01:04:23] DEBUG[27636] chan_sip.c: **** Received NOTIFY (4) - Command in SIP NOTIFY [May 15 01:04:23] DEBUG[27636] chan_sip.c: Trying to put 'SIP/2.0 200' onto UDP socket destined for 82.142.173.58:5060 [May 15 01:04:23] DEBUG[27636] chan_sip.c: = Looking for Call ID: a69b87c3-6e7c1acc@192.168.43.63 (Checking From) --From tag 39f82a37d2b8bae8o0 --To-tag [May 15 01:04:23] DEBUG[27636] chan_sip.c: **** Received NOTIFY (4) - Command in SIP NOTIFY [May 15 01:04:23] DEBUG[27636] chan_sip.c: Trying to put 'SIP/2.0 200' onto UDP socket destined for 195.128.55.18:1027 [May 15 01:04:23] DEBUG[27636] chan_sip.c: Allocating new SIP dialog for 590c4c9c693d1ed931b80bbf27cad4b9@89.188.109.170:5060 - OPTIONS (No RTP) [May 15 01:04:23] DEBUG[27636] acl.c: For destination '195.128.55.18', our source address is '89.188.109.170'. [May 15 01:04:23] DEBUG[27636] chan_sip.c: Target address 195.128.55.18:1072 is not local, substituting externaddr [May 15 01:04:23] DEBUG[27636] chan_sip.c: Setting SIP_TRANSPORT_UDP with address 89.188.109.170:5060 [May 15 01:04:23] DEBUG[27636] chan_sip.c: SIP call-id changed from '590c4c9c693d1ed931b80bbf27cad4b9@89.188.109.170:5060' to '63931cbd7676df9d70bde08f3eb95b86@89.188.109.170:5060' [May 15 01:04:23] DEBUG[27636] chan_sip.c: Initializing initreq for method OPTIONS - callid 63931cbd7676df9d70bde08f3eb95b86@89.188.109.170:5060 [May 15 01:04:23] DEBUG[27636] chan_sip.c: Trying to put 'OPTIONS sip' onto UDP socket destined for 195.128.55.18:1072 [May 15 01:04:23] DEBUG[27636] chan_sip.c: = Looking for Call ID: 63931cbd7676df9d70bde08f3eb95b86@89.188.109.170:5060 (Checking To) --From tag as6a34cc87 --To-tag 76ab103798330d3fi0 [May 15 01:04:23] DEBUG[27636] chan_sip.c: Stopping retransmission on '63931cbd7676df9d70bde08f3eb95b86@89.188.109.170:5060' of Request 102: Match Found [May 15 01:04:23] DEBUG[27636] chan_sip.c: Destroying SIP dialog 63931cbd7676df9d70bde08f3eb95b86@89.188.109.170:5060 [May 15 01:04:23] DEBUG[27636] chan_sip.c: Allocating new SIP dialog for 7b66c1ca0ce413d9288c068153515b91@89.188.109.170:5060 - OPTIONS (No RTP) [May 15 01:04:23] DEBUG[27636] acl.c: For destination '195.128.55.18', our source address is '89.188.109.170'. [May 15 01:04:23] DEBUG[27636] chan_sip.c: Target address 195.128.55.18:1044 is not local, substituting externaddr [May 15 01:04:23] DEBUG[27636] chan_sip.c: Setting SIP_TRANSPORT_UDP with address 89.188.109.170:5060 [May 15 01:04:23] DEBUG[27636] chan_sip.c: SIP call-id changed from '7b66c1ca0ce413d9288c068153515b91@89.188.109.170:5060' to '06008b9164c31dde63fa487b16b47709@89.188.109.170:5060' [May 15 01:04:23] DEBUG[27636] chan_sip.c: Initializing initreq for method OPTIONS - callid 06008b9164c31dde63fa487b16b47709@89.188.109.170:5060 [May 15 01:04:23] DEBUG[27636] chan_sip.c: Trying to put 'OPTIONS sip' onto UDP socket destined for 195.128.55.18:1044 [May 15 01:04:23] DEBUG[27636] chan_sip.c: = Looking for Call ID: 06008b9164c31dde63fa487b16b47709@89.188.109.170:5060 (Checking To) --From tag as2cf7280e --To-tag b5de654df4cecb4fi0 [May 15 01:04:23] DEBUG[27636] chan_sip.c: Stopping retransmission on '06008b9164c31dde63fa487b16b47709@89.188.109.170:5060' of Request 102: Match Found [May 15 01:04:23] DEBUG[27636] chan_sip.c: Destroying SIP dialog 06008b9164c31dde63fa487b16b47709@89.188.109.170:5060 [May 15 01:04:23] DEBUG[27636] chan_sip.c: Allocating new SIP dialog for 1498b955295175df364480ed04af04aa@89.188.109.170:5060 - OPTIONS (No RTP) [May 15 01:04:23] DEBUG[27636] acl.c: For destination '62.141.78.138', our source address is '89.188.109.170'. [May 15 01:04:23] DEBUG[27636] chan_sip.c: Target address 62.141.78.138:5060 is not local, substituting externaddr [May 15 01:04:23] DEBUG[27636] chan_sip.c: Setting SIP_TRANSPORT_UDP with address 89.188.109.170:5060 [May 15 01:04:23] DEBUG[27636] chan_sip.c: SIP call-id changed from '1498b955295175df364480ed04af04aa@89.188.109.170:5060' to '270f8980158f148c5c9244b33dddef05@89.188.109.170:5060' [May 15 01:04:23] DEBUG[27636] chan_sip.c: Initializing initreq for method OPTIONS - callid 270f8980158f148c5c9244b33dddef05@89.188.109.170:5060 [May 15 01:04:23] DEBUG[27636] chan_sip.c: Trying to put 'OPTIONS sip' onto UDP socket destined for 62.141.78.138:5060 [May 15 01:04:23] DEBUG[27636] chan_sip.c: = Looking for Call ID: 270f8980158f148c5c9244b33dddef05@89.188.109.170:5060 (Checking To) --From tag as66267274 --To-tag d7df6bc3896987b2i0 [May 15 01:04:23] DEBUG[27636] chan_sip.c: Stopping retransmission on '270f8980158f148c5c9244b33dddef05@89.188.109.170:5060' of Request 102: Match Found [May 15 01:04:23] DEBUG[27636] chan_sip.c: Destroying SIP dialog 270f8980158f148c5c9244b33dddef05@89.188.109.170:5060 [May 15 01:04:23] DEBUG[27636] chan_sip.c: Allocating new SIP dialog for 1c37deeb431f7bef7c985081336cb85c@89.188.109.170:5060 - OPTIONS (No RTP) [May 15 01:04:23] DEBUG[27636] acl.c: For destination '195.128.55.30', our source address is '89.188.109.170'. [May 15 01:04:23] DEBUG[27636] chan_sip.c: Target address 195.128.55.30:61953 is not local, substituting externaddr [May 15 01:04:23] DEBUG[27636] chan_sip.c: Setting SIP_TRANSPORT_UDP with address 89.188.109.170:5060 [May 15 01:04:23] DEBUG[27636] chan_sip.c: SIP call-id changed from '1c37deeb431f7bef7c985081336cb85c@89.188.109.170:5060' to '35a9a8703b37773b3185f1cb020226aa@89.188.109.170:5060' [May 15 01:04:23] DEBUG[27636] chan_sip.c: Initializing initreq for method OPTIONS - callid 35a9a8703b37773b3185f1cb020226aa@89.188.109.170:5060 [May 15 01:04:23] DEBUG[27636] chan_sip.c: Trying to put 'OPTIONS sip' onto UDP socket destined for 195.128.55.30:61953 [May 15 01:04:24] DEBUG[27636] chan_sip.c: = Looking for Call ID: fff145fa-1cf1a84@192.168.43.27 (Checking From) --From tag 6ac3370a71f75cb4o1 --To-tag [May 15 01:04:24] DEBUG[27636] chan_sip.c: **** Received NOTIFY (4) - Command in SIP NOTIFY [May 15 01:04:24] DEBUG[27636] chan_sip.c: Trying to put 'SIP/2.0 200' onto UDP socket destined for 195.128.55.18:1053 [May 15 01:04:24] DEBUG[27636] chan_sip.c: = Looking for Call ID: 35a9a8703b37773b3185f1cb020226aa@89.188.109.170:5060 (Checking To) --From tag as5d9afa9e --To-tag ar4e8aga8d [May 15 01:04:24] DEBUG[27636] chan_sip.c: Stopping retransmission on '35a9a8703b37773b3185f1cb020226aa@89.188.109.170:5060' of Request 102: Match Found [May 15 01:04:24] DEBUG[27636] chan_sip.c: Destroying SIP dialog 35a9a8703b37773b3185f1cb020226aa@89.188.109.170:5060 [May 15 01:04:24] DEBUG[27636] chan_sip.c: Allocating new SIP dialog for 661065ec61253038790e076d5f7c2834@89.188.109.170:5060 - OPTIONS (No RTP) [May 15 01:04:24] DEBUG[27636] acl.c: For destination '195.128.55.18', our source address is '89.188.109.170'. [May 15 01:04:24] DEBUG[27636] chan_sip.c: Target address 195.128.55.18:1029 is not local, substituting externaddr [May 15 01:04:24] DEBUG[27636] chan_sip.c: Setting SIP_TRANSPORT_UDP with address 89.188.109.170:5060 [May 15 01:04:24] DEBUG[27636] chan_sip.c: SIP call-id changed from '661065ec61253038790e076d5f7c2834@89.188.109.170:5060' to '54422f55023456cf33903e2a538414ef@89.188.109.170:5060' [May 15 01:04:24] DEBUG[27636] chan_sip.c: Initializing initreq for method OPTIONS - callid 54422f55023456cf33903e2a538414ef@89.188.109.170:5060 [May 15 01:04:24] DEBUG[27636] chan_sip.c: Trying to put 'OPTIONS sip' onto UDP socket destined for 195.128.55.18:1029 [May 15 01:04:24] DEBUG[27636] chan_sip.c: = Looking for Call ID: 54422f55023456cf33903e2a538414ef@89.188.109.170:5060 (Checking To) --From tag as21874464 --To-tag a50b35c89e7a3cc8i0 [May 15 01:04:24] DEBUG[27636] chan_sip.c: Stopping retransmission on '54422f55023456cf33903e2a538414ef@89.188.109.170:5060' of Request 102: Match Found [May 15 01:04:24] DEBUG[27636] chan_sip.c: Destroying SIP dialog 54422f55023456cf33903e2a538414ef@89.188.109.170:5060 [May 15 01:04:24] DEBUG[27636] chan_sip.c: Allocating new SIP dialog for 66f80cae6940490876f560395ca8f4ed@89.188.109.170:5060 - OPTIONS (No RTP) [May 15 01:04:24] DEBUG[27636] acl.c: For destination '195.128.55.18', our source address is '89.188.109.170'. [May 15 01:04:24] DEBUG[27636] chan_sip.c: Target address 195.128.55.18:1041 is not local, substituting externaddr [May 15 01:04:24] DEBUG[27636] chan_sip.c: Setting SIP_TRANSPORT_UDP with address 89.188.109.170:5060 [May 15 01:04:24] DEBUG[27636] chan_sip.c: SIP call-id changed from '66f80cae6940490876f560395ca8f4ed@89.188.109.170:5060' to '2f651a2765637a9f792da139646fb976@89.188.109.170:5060' [May 15 01:04:24] DEBUG[27636] chan_sip.c: Initializing initreq for method OPTIONS - callid 2f651a2765637a9f792da139646fb976@89.188.109.170:5060 [May 15 01:04:24] DEBUG[27636] chan_sip.c: Trying to put 'OPTIONS sip' onto UDP socket destined for 195.128.55.18:1041 [May 15 01:04:24] DEBUG[27636] chan_sip.c: = Looking for Call ID: 2f651a2765637a9f792da139646fb976@89.188.109.170:5060 (Checking To) --From tag as0ccdce39 --To-tag 7438fb472f3f9d84i0 [May 15 01:04:24] DEBUG[27636] chan_sip.c: Stopping retransmission on '2f651a2765637a9f792da139646fb976@89.188.109.170:5060' of Request 102: Match Found [May 15 01:04:24] DEBUG[27636] chan_sip.c: Destroying SIP dialog 2f651a2765637a9f792da139646fb976@89.188.109.170:5060 [May 15 01:04:24] DEBUG[27636] chan_sip.c: = Looking for Call ID: 8125dc6e-86f45752@192.168.42.103 (Checking From) --From tag 122e5d3eb0f14cc2o0 --To-tag [May 15 01:04:24] DEBUG[27636] chan_sip.c: **** Received NOTIFY (4) - Command in SIP NOTIFY [May 15 01:04:24] DEBUG[27636] chan_sip.c: Trying to put 'SIP/2.0 200' onto UDP socket destined for 213.184.150.74:5060 [May 15 01:04:24] DEBUG[27636] chan_sip.c: = Looking for Call ID: 8c01e99-6f2181fc@192.168.42.188 (Checking From) --From tag 5d6401953e89b30o0 --To-tag [May 15 01:04:24] DEBUG[27636] chan_sip.c: **** Received NOTIFY (4) - Command in SIP NOTIFY [May 15 01:04:24] DEBUG[27636] chan_sip.c: Trying to put 'SIP/2.0 200' onto UDP socket destined for 195.68.128.222:1025 [May 15 01:04:24] DEBUG[27636] chan_sip.c: = Looking for Call ID: 4dfa6d47-221cd2f3@192.168.42.146 (Checking From) --From tag 15a96927c2670893o1 --To-tag [May 15 01:04:24] DEBUG[27636] chan_sip.c: **** Received NOTIFY (4) - Command in SIP NOTIFY [May 15 01:04:24] DEBUG[27636] chan_sip.c: Trying to put 'SIP/2.0 200' onto UDP socket destined for 82.204.246.142:5061 [May 15 01:04:24] DEBUG[27636] chan_sip.c: Allocating new SIP dialog for 2d06631c2742b9b03ece90f94b11cdef@89.188.109.170:5060 - OPTIONS (No RTP) [May 15 01:04:24] DEBUG[27636] acl.c: For destination '195.128.55.18', our source address is '89.188.109.170'. [May 15 01:04:24] DEBUG[27636] chan_sip.c: Target address 195.128.55.18:1093 is not local, substituting externaddr [May 15 01:04:24] DEBUG[27636] chan_sip.c: Setting SIP_TRANSPORT_UDP with address 89.188.109.170:5060 [May 15 01:04:24] DEBUG[27636] chan_sip.c: SIP call-id changed from '2d06631c2742b9b03ece90f94b11cdef@89.188.109.170:5060' to '1bf47f667ef7c25649fe8acf7342c378@89.188.109.170:5060' [May 15 01:04:24] DEBUG[27636] chan_sip.c: Initializing initreq for method OPTIONS - callid 1bf47f667ef7c25649fe8acf7342c378@89.188.109.170:5060 [May 15 01:04:24] DEBUG[27636] chan_sip.c: Trying to put 'OPTIONS sip' onto UDP socket destined for 195.128.55.18:1093 [May 15 01:04:24] DEBUG[27636] chan_sip.c: = Looking for Call ID: 1bf47f667ef7c25649fe8acf7342c378@89.188.109.170:5060 (Checking To) --From tag as2c6148f5 --To-tag e6b66d91102abf23i0 [May 15 01:04:24] DEBUG[27636] chan_sip.c: Stopping retransmission on '1bf47f667ef7c25649fe8acf7342c378@89.188.109.170:5060' of Request 102: Match Found [May 15 01:04:24] DEBUG[27636] chan_sip.c: Destroying SIP dialog 1bf47f667ef7c25649fe8acf7342c378@89.188.109.170:5060 [May 15 01:04:24] DEBUG[27636] chan_sip.c: = Looking for Call ID: 3f6d9573-9881e9a1@192.168.43.7 (Checking From) --From tag ca3d4d8b214bd069o0 --To-tag [May 15 01:04:24] DEBUG[27636] chan_sip.c: **** Received NOTIFY (4) - Command in SIP NOTIFY [May 15 01:04:24] DEBUG[27636] chan_sip.c: Trying to put 'SIP/2.0 200' onto UDP socket destined for 195.128.55.18:1073 [May 15 01:04:24] DEBUG[27636] chan_sip.c: = Looking for Call ID: 3f2c045b-7aa80783@192.168.43.24 (Checking From) --From tag 34252ddb3324ec03o3 --To-tag [May 15 01:04:24] DEBUG[27636] chan_sip.c: **** Received NOTIFY (4) - Command in SIP NOTIFY [May 15 01:04:24] DEBUG[27636] chan_sip.c: Trying to put 'SIP/2.0 200' onto UDP socket destined for 195.128.55.18:1051 [May 15 01:04:24] DEBUG[27636] chan_sip.c: = Looking for Call ID: f55b73-a42cdb48@192.168.43.6 (Checking From) --From tag 33672ad75b54b334o0 --To-tag [May 15 01:04:24] DEBUG[27636] chan_sip.c: **** Received NOTIFY (4) - Command in SIP NOTIFY [May 15 01:04:24] DEBUG[27636] chan_sip.c: Trying to put 'SIP/2.0 200' onto UDP socket destined for 195.128.55.18:1060 [May 15 01:04:24] DEBUG[27636] chan_sip.c: Allocating new SIP dialog for 7fc1bb405fc00fc70c39e2214b06ad3a@89.188.109.170:5060 - OPTIONS (No RTP) [May 15 01:04:24] DEBUG[27636] acl.c: For destination '82.142.173.58', our source address is '89.188.109.170'. [May 15 01:04:24] DEBUG[27636] chan_sip.c: Target address 82.142.173.58:1024 is not local, substituting externaddr [May 15 01:04:24] DEBUG[27636] chan_sip.c: Setting SIP_TRANSPORT_UDP with address 89.188.109.170:5060 [May 15 01:04:24] DEBUG[27636] chan_sip.c: SIP call-id changed from '7fc1bb405fc00fc70c39e2214b06ad3a@89.188.109.170:5060' to '0364cccd31a114e2529bba87290f62ce@89.188.109.170:5060' [May 15 01:04:24] DEBUG[27636] chan_sip.c: Initializing initreq for method OPTIONS - callid 0364cccd31a114e2529bba87290f62ce@89.188.109.170:5060 [May 15 01:04:24] DEBUG[27636] chan_sip.c: Trying to put 'OPTIONS sip' onto UDP socket destined for 82.142.173.58:1024 [May 15 01:04:24] DEBUG[27636] chan_sip.c: Trying to put 'OPTIONS sip' onto UDP socket destined for 82.142.173.58:5060 [May 15 01:04:24] DEBUG[27636] chan_sip.c: = Looking for Call ID: 7d824ced-bae307e8@192.168.43.41 (Checking From) --From tag 8cfee1c9ece618bco0 --To-tag [May 15 01:04:24] DEBUG[27636] chan_sip.c: **** Received NOTIFY (4) - Command in SIP NOTIFY [May 15 01:04:24] DEBUG[27636] chan_sip.c: Trying to put 'SIP/2.0 200' onto UDP socket destined for 195.128.55.18:1029 [May 15 01:04:24] DEBUG[27636] chan_sip.c: = Looking for Call ID: 5db7b32e-9d4e6ec5@192.168.43.28 (Checking From) --From tag 883ab83ed158acf5o3 --To-tag [May 15 01:04:24] DEBUG[27636] chan_sip.c: **** Received NOTIFY (4) - Command in SIP NOTIFY [May 15 01:04:24] DEBUG[27636] chan_sip.c: Trying to put 'SIP/2.0 200' onto UDP socket destined for 195.128.55.18:1059 [May 15 01:04:24] DEBUG[27636] chan_sip.c: = Looking for Call ID: 07717f973ebd657a7513745942f5a460@89.188.109.170:5060 (Checking To) --From tag as45d2d1d1 --To-tag 378813577d34e406i0 [May 15 01:04:24] DEBUG[27636] chan_sip.c: Stopping retransmission on '07717f973ebd657a7513745942f5a460@89.188.109.170:5060' of Request 102: Match Found [May 15 01:04:24] DEBUG[27636] chan_sip.c: Destroying SIP dialog 07717f973ebd657a7513745942f5a460@89.188.109.170:5060 [May 15 01:04:24] DEBUG[27636] chan_sip.c: = Looking for Call ID: 6ae84e63-9965042b@192.168.42.60 (Checking From) --From tag 64845003834fef0bo0 --To-tag [May 15 01:04:24] DEBUG[27636] chan_sip.c: **** Received NOTIFY (4) - Command in SIP NOTIFY [May 15 01:04:24] DEBUG[27636] chan_sip.c: Trying to put 'SIP/2.0 200' onto UDP socket destined for 82.142.173.58:1024 [May 15 01:04:24] DEBUG[27636] chan_sip.c: Auto destroying SIP dialog '2295150129@192_168_42_147' [May 15 01:04:24] DEBUG[27636] chan_sip.c: Destroying SIP dialog 2295150129@192_168_42_147 [May 15 01:04:24] DEBUG[27636] chan_sip.c: = Looking for Call ID: 41ab899-54f7bb99@192.168.43.19 (Checking From) --From tag 48db089976b3ab99o3 --To-tag [May 15 01:04:24] DEBUG[27636] chan_sip.c: **** Received NOTIFY (4) - Command in SIP NOTIFY [May 15 01:04:24] DEBUG[27636] chan_sip.c: Trying to put 'SIP/2.0 200' onto UDP socket destined for 195.128.55.18:1078 [May 15 01:04:24] DEBUG[27636] chan_sip.c: = Looking for Call ID: 7b7588d6-80be5741@192.168.42.100 (Checking From) --From tag 84504672696b5655o0 --To-tag [May 15 01:04:24] DEBUG[27636] chan_sip.c: **** Received NOTIFY (4) - Command in SIP NOTIFY [May 15 01:04:24] DEBUG[27636] chan_sip.c: Trying to put 'SIP/2.0 200' onto UDP socket destined for 80.249.177.38:5060 [May 15 01:04:24] DEBUG[27636] chan_sip.c: Allocating new SIP dialog for 7cf4a9460f4a50ce3a1eed4d7317969c@89.188.109.170:5060 - OPTIONS (No RTP) [May 15 01:04:24] DEBUG[27636] acl.c: For destination '213.33.196.118', our source address is '89.188.109.170'. [May 15 01:04:24] DEBUG[27636] chan_sip.c: Target address 213.33.196.118:1025 is not local, substituting externaddr [May 15 01:04:24] DEBUG[27636] chan_sip.c: Setting SIP_TRANSPORT_UDP with address 89.188.109.170:5060 [May 15 01:04:24] DEBUG[27636] chan_sip.c: SIP call-id changed from '7cf4a9460f4a50ce3a1eed4d7317969c@89.188.109.170:5060' to '7644243d450852291fadb0d42a33a7d2@89.188.109.170:5060' [May 15 01:04:24] DEBUG[27636] chan_sip.c: Initializing initreq for method OPTIONS - callid 7644243d450852291fadb0d42a33a7d2@89.188.109.170:5060 [May 15 01:04:24] DEBUG[27636] chan_sip.c: Trying to put 'OPTIONS sip' onto UDP socket destined for 213.33.196.118:1025 [May 15 01:04:24] DEBUG[27636] chan_sip.c: = Looking for Call ID: 7644243d450852291fadb0d42a33a7d2@89.188.109.170:5060 (Checking To) --From tag as64d61095 --To-tag 9ed3936de31e735fi0 [May 15 01:04:24] DEBUG[27636] chan_sip.c: Stopping retransmission on '7644243d450852291fadb0d42a33a7d2@89.188.109.170:5060' of Request 102: Match Found [May 15 01:04:24] DEBUG[27636] chan_sip.c: Destroying SIP dialog 7644243d450852291fadb0d42a33a7d2@89.188.109.170:5060 [May 15 01:04:24] DEBUG[27636] chan_sip.c: = Looking for Call ID: 327e25d1-9f2c63e3@192.168.43.45 (Checking From) --From tag 38374ab9a1a7441bo0 --To-tag [May 15 01:04:24] DEBUG[27636] chan_sip.c: **** Received NOTIFY (4) - Command in SIP NOTIFY [May 15 01:04:24] DEBUG[27636] chan_sip.c: Trying to put 'SIP/2.0 200' onto UDP socket destined for 195.128.55.18:1093 [May 15 01:04:24] DEBUG[27636] chan_sip.c: = Looking for Call ID: cc3be286-247c477c@192.168.43.58 (Checking From) --From tag ee3bf83e21486d24o0 --To-tag [May 15 01:04:24] DEBUG[27636] chan_sip.c: **** Received NOTIFY (4) - Command in SIP NOTIFY [May 15 01:04:24] DEBUG[27636] chan_sip.c: Trying to put 'SIP/2.0 200' onto UDP socket destined for 195.128.55.18:1030 [May 15 01:04:24] DEBUG[27636] chan_sip.c: = Looking for Call ID: 1ab2882c-32b26dfa@192.168.43.3 (Checking From) --From tag 779638c46984b642o0 --To-tag [May 15 01:04:24] DEBUG[27636] chan_sip.c: **** Received NOTIFY (4) - Command in SIP NOTIFY [May 15 01:04:24] DEBUG[27636] chan_sip.c: Trying to put 'SIP/2.0 200' onto UDP socket destined for 195.128.55.18:1033 [May 15 01:04:24] DEBUG[27636] chan_sip.c: Allocating new SIP dialog for 0be0342262b33499517915b6169d362d@89.188.109.170:5060 - OPTIONS (No RTP) [May 15 01:04:24] DEBUG[27636] acl.c: For destination '195.128.55.18', our source address is '89.188.109.170'. [May 15 01:04:24] DEBUG[27636] chan_sip.c: Target address 195.128.55.18:1027 is not local, substituting externaddr [May 15 01:04:24] DEBUG[27636] chan_sip.c: Setting SIP_TRANSPORT_UDP with address 89.188.109.170:5060 [May 15 01:04:24] DEBUG[27636] chan_sip.c: SIP call-id changed from '0be0342262b33499517915b6169d362d@89.188.109.170:5060' to '779f6ba22f4dc98c1f4e719c72cdd748@89.188.109.170:5060' [May 15 01:04:24] DEBUG[27636] chan_sip.c: Initializing initreq for method OPTIONS - callid 779f6ba22f4dc98c1f4e719c72cdd748@89.188.109.170:5060 [May 15 01:04:24] DEBUG[27636] chan_sip.c: Trying to put 'OPTIONS sip' onto UDP socket destined for 195.128.55.18:1027 [May 15 01:04:24] DEBUG[27636] chan_sip.c: = Looking for Call ID: 779f6ba22f4dc98c1f4e719c72cdd748@89.188.109.170:5060 (Checking To) --From tag as24ce69b9 --To-tag 11ad2ba38e83c86ci0 [May 15 01:04:24] DEBUG[27636] chan_sip.c: Stopping retransmission on '779f6ba22f4dc98c1f4e719c72cdd748@89.188.109.170:5060' of Request 102: Match Found [May 15 01:04:24] DEBUG[27636] chan_sip.c: Destroying SIP dialog 779f6ba22f4dc98c1f4e719c72cdd748@89.188.109.170:5060 [May 15 01:04:24] DEBUG[27636] chan_sip.c: Allocating new SIP dialog for 62a25cdb6fda524e6a6e36f341f157bc@89.188.109.170:5060 - OPTIONS (No RTP) [May 15 01:04:24] DEBUG[27636] acl.c: For destination '195.128.55.30', our source address is '89.188.109.170'. [May 15 01:04:24] DEBUG[27636] chan_sip.c: Target address 195.128.55.30:61580 is not local, substituting externaddr [May 15 01:04:24] DEBUG[27636] chan_sip.c: Setting SIP_TRANSPORT_UDP with address 89.188.109.170:5060 [May 15 01:04:24] DEBUG[27636] chan_sip.c: SIP call-id changed from '62a25cdb6fda524e6a6e36f341f157bc@89.188.109.170:5060' to '2d4e856b69b451636712a69e2d2a891f@89.188.109.170:5060' [May 15 01:04:24] DEBUG[27636] chan_sip.c: Initializing initreq for method OPTIONS - callid 2d4e856b69b451636712a69e2d2a891f@89.188.109.170:5060 [May 15 01:04:24] DEBUG[27636] chan_sip.c: Trying to put 'OPTIONS sip' onto UDP socket destined for 195.128.55.30:61580 [May 15 01:04:24] DEBUG[27636] chan_sip.c: = Looking for Call ID: 2d4e856b69b451636712a69e2d2a891f@89.188.109.170:5060 (Checking To) --From tag as777e832c --To-tag 175add9b2f04ff45i0 [May 15 01:04:24] DEBUG[27636] chan_sip.c: Stopping retransmission on '2d4e856b69b451636712a69e2d2a891f@89.188.109.170:5060' of Request 102: Match Found [May 15 01:04:24] DEBUG[27636] chan_sip.c: Destroying SIP dialog 2d4e856b69b451636712a69e2d2a891f@89.188.109.170:5060 [May 15 01:04:25] DEBUG[27636] chan_sip.c: = Looking for Call ID: 97b80863-e7489316@192.168.43.68 (Checking From) --From tag 3b8da99fd43f030ao0 --To-tag [May 15 01:04:25] DEBUG[27636] chan_sip.c: **** Received NOTIFY (4) - Command in SIP NOTIFY [May 15 01:04:25] DEBUG[27636] chan_sip.c: Trying to put 'SIP/2.0 200' onto UDP socket destined for 195.128.55.18:1064 [May 15 01:04:25] DEBUG[27636] chan_sip.c: = Looking for Call ID: 8294a215-b53c7502@192.168.43.53 (Checking From) --From tag 257fe9598806368eo0 --To-tag [May 15 01:04:25] DEBUG[27636] chan_sip.c: **** Received NOTIFY (4) - Command in SIP NOTIFY [May 15 01:04:25] DEBUG[27636] chan_sip.c: Trying to put 'SIP/2.0 200' onto UDP socket destined for 195.128.55.18:1036 [May 15 01:04:25] DEBUG[27636] chan_sip.c: = Looking for Call ID: 5ba8cc1b-cc2be2c5@192.168.43.21 (Checking From) --From tag 166184b313ecc30do2 --To-tag [May 15 01:04:25] DEBUG[27636] chan_sip.c: **** Received NOTIFY (4) - Command in SIP NOTIFY [May 15 01:04:25] DEBUG[27636] chan_sip.c: Trying to put 'SIP/2.0 200' onto UDP socket destined for 195.128.55.18:1098 [May 15 01:04:25] DEBUG[27636] chan_sip.c: = Looking for Call ID: 11e4ff5b-454b4891@192.168.43.52 (Checking From) --From tag 352bda13cd776d39o0 --To-tag [May 15 01:04:25] DEBUG[27636] chan_sip.c: **** Received NOTIFY (4) - Command in SIP NOTIFY [May 15 01:04:25] DEBUG[27636] chan_sip.c: Trying to put 'SIP/2.0 200' onto UDP socket destined for 195.128.55.18:1037 [May 15 01:04:25] DEBUG[27636] chan_sip.c: = Looking for Call ID: 6c4b7565-7adb3a03@192.168.43.71 (Checking From) --From tag a82d5a3df48adb0bo0 --To-tag [May 15 01:04:25] DEBUG[27636] chan_sip.c: **** Received NOTIFY (4) - Command in SIP NOTIFY [May 15 01:04:25] DEBUG[27636] chan_sip.c: Trying to put 'SIP/2.0 200' onto UDP socket destined for 195.128.55.18:1086 [May 15 01:04:25] DEBUG[27636] chan_sip.c: = Looking for Call ID: 2883aad7-54e232a3@192.168.42.146 (Checking From) --From tag 400233c78d13be73o0 --To-tag [May 15 01:04:25] DEBUG[27636] chan_sip.c: **** Received NOTIFY (4) - Command in SIP NOTIFY [May 15 01:04:25] DEBUG[27636] chan_sip.c: Trying to put 'SIP/2.0 200' onto UDP socket destined for 82.204.246.142:5060 [May 15 01:04:25] DEBUG[27636] chan_sip.c: = Looking for Call ID: 8e88a0db-79601505@192.168.42.58 (Checking From) --From tag 79ec39a3da012ddo0 --To-tag [May 15 01:04:25] DEBUG[27636] chan_sip.c: **** Received NOTIFY (4) - Command in SIP NOTIFY [May 15 01:04:25] DEBUG[27636] chan_sip.c: Trying to put 'SIP/2.0 200' onto UDP socket destined for 195.128.55.30:61580 [May 15 01:04:25] DEBUG[27636] chan_sip.c: Trying to put 'OPTIONS sip' onto UDP socket destined for 82.142.173.58:1024 [May 15 01:04:25] DEBUG[27636] chan_sip.c: = Looking for Call ID: 0364cccd31a114e2529bba87290f62ce@89.188.109.170:5060 (Checking To) --From tag as0f60f671 --To-tag e9c93d635ae0e12bi0 [May 15 01:04:25] DEBUG[27636] chan_sip.c: Stopping retransmission on '0364cccd31a114e2529bba87290f62ce@89.188.109.170:5060' of Request 102: Match Found [May 15 01:04:25] DEBUG[27636] chan_sip.c: Destroying SIP dialog 0364cccd31a114e2529bba87290f62ce@89.188.109.170:5060 [May 15 01:04:25] DEBUG[27636] chan_sip.c: = Looking for Call ID: 8fd54f53-e23a78ed@192.168.43.21 (Checking From) --From tag 3c3076e35e46f69do1 --To-tag [May 15 01:04:25] DEBUG[27636] chan_sip.c: **** Received NOTIFY (4) - Command in SIP NOTIFY [May 15 01:04:25] DEBUG[27636] chan_sip.c: Trying to put 'SIP/2.0 200' onto UDP socket destined for 195.128.55.18:1097 [May 15 01:04:25] DEBUG[27636] chan_sip.c: = Looking for Call ID: a47b6499-7a2eff99@192.168.43.19 (Checking From) --From tag 2f54c099efc55399o0 --To-tag [May 15 01:04:25] DEBUG[27636] chan_sip.c: **** Received NOTIFY (4) - Command in SIP NOTIFY [May 15 01:04:25] DEBUG[27636] chan_sip.c: Trying to put 'SIP/2.0 200' onto UDP socket destined for 195.128.55.18:1075 [May 15 01:04:25] DEBUG[27636] chan_sip.c: = Looking for Call ID: f9a79cb6-baa052cb@192.168.43.67 (Checking From) --From tag 987e191a466361b7o0 --To-tag [May 15 01:04:25] DEBUG[27636] chan_sip.c: **** Received NOTIFY (4) - Command in SIP NOTIFY [May 15 01:04:25] DEBUG[27636] chan_sip.c: Trying to put 'SIP/2.0 200' onto UDP socket destined for 195.128.55.18:1035 [May 15 01:04:25] DEBUG[27636] chan_sip.c: = Looking for Call ID: 29477b8d-1a6eaa0f@192.168.43.69 (Checking From) --From tag a880cd3530394487o0 --To-tag [May 15 01:04:25] DEBUG[27636] chan_sip.c: **** Received NOTIFY (4) - Command in SIP NOTIFY [May 15 01:04:25] DEBUG[27636] chan_sip.c: Trying to put 'SIP/2.0 200' onto UDP socket destined for 195.128.55.18:1044 [May 15 01:04:25] DEBUG[27636] chan_sip.c: = Looking for Call ID: c781382e-bc0aad20@192.168.43.42 (Checking From) --From tag e08fee96421129d8o0 --To-tag [May 15 01:04:25] DEBUG[27636] chan_sip.c: **** Received NOTIFY (4) - Command in SIP NOTIFY [May 15 01:04:25] DEBUG[27636] chan_sip.c: Trying to put 'SIP/2.0 200' onto UDP socket destined for 195.128.55.18:1032 [May 15 01:04:25] DEBUG[27636] chan_sip.c: = Looking for Call ID: 6b098a6f-936018f6@192.168.42.143 (Checking From) --From tag 665322c74ddf9c7eo1 --To-tag [May 15 01:04:25] DEBUG[27636] chan_sip.c: **** Received NOTIFY (4) - Command in SIP NOTIFY [May 15 01:04:25] DEBUG[27636] chan_sip.c: Trying to put 'SIP/2.0 200' onto UDP socket destined for 62.141.78.138:5061 [May 15 01:04:25] DEBUG[27636] chan_sip.c: = Looking for Call ID: 171503f6-e92014a9@192.168.43.20 (Checking From) --From tag 5cb9d3243e9ec9do0 --To-tag [May 15 01:04:25] DEBUG[27636] chan_sip.c: **** Received NOTIFY (4) - Command in SIP NOTIFY [May 15 01:04:25] DEBUG[27636] chan_sip.c: Trying to put 'SIP/2.0 200' onto UDP socket destined for 195.128.55.18:1066 [May 15 01:04:25] DEBUG[27636] chan_sip.c: = Looking for Call ID: 111e2cd9-1f90c864@192.168.43.44 (Checking From) --From tag 4466a8f5c9471af8o0 --To-tag [May 15 01:04:25] DEBUG[27636] chan_sip.c: **** Received NOTIFY (4) - Command in SIP NOTIFY [May 15 01:04:25] DEBUG[27636] chan_sip.c: Trying to put 'SIP/2.0 200' onto UDP socket destined for 195.128.55.18:1092 [May 15 01:04:25] DEBUG[27636] chan_sip.c: = Looking for Call ID: 1d4f8237-eb894375@192.168.43.30 (Checking From) --From tag e8c2fb8f3c8059fdo0 --To-tag [May 15 01:04:25] DEBUG[27636] chan_sip.c: **** Received NOTIFY (4) - Command in SIP NOTIFY [May 15 01:04:25] DEBUG[27636] chan_sip.c: Trying to put 'SIP/2.0 200' onto UDP socket destined for 195.128.55.18:1082 [May 15 01:04:25] DEBUG[27636] chan_sip.c: = Looking for Call ID: 51c9abbe-9c2a4f6b@192.168.43.49 (Checking From) --From tag 73ef120230687df7o0 --To-tag [May 15 01:04:25] DEBUG[27636] chan_sip.c: **** Received NOTIFY (4) - Command in SIP NOTIFY [May 15 01:04:25] DEBUG[27636] chan_sip.c: Trying to put 'SIP/2.0 200' onto UDP socket destined for 195.128.55.18:1040 [May 15 01:04:25] DEBUG[27636] chan_sip.c: = Looking for Call ID: 1a3681e7-162ec764@192.168.43.55 (Checking From) --From tag 35fc986be0aa02b0o0 --To-tag [May 15 01:04:25] DEBUG[27636] chan_sip.c: **** Received NOTIFY (4) - Command in SIP NOTIFY [May 15 01:04:25] DEBUG[27636] chan_sip.c: Trying to put 'SIP/2.0 200' onto UDP socket destined for 195.128.55.18:1041 [May 15 01:04:26] DEBUG[27636] chan_sip.c: = Looking for Call ID: 9cda4a55-de58e9d3@192.168.43.71 (Checking From) --From tag 741cf61d9c8cf0abo2 --To-tag [May 15 01:04:26] DEBUG[27636] chan_sip.c: **** Received NOTIFY (4) - Command in SIP NOTIFY [May 15 01:04:26] DEBUG[27636] chan_sip.c: Trying to put 'SIP/2.0 200' onto UDP socket destined for 195.128.55.18:1088 [May 15 01:04:26] DEBUG[27636] chan_sip.c: = Looking for Call ID: 9d388da2-2cf437db@192.168.43.43 (Checking From) --From tag 98a4b3d6bfe12737o0 --To-tag [May 15 01:04:26] DEBUG[27636] chan_sip.c: **** Received NOTIFY (4) - Command in SIP NOTIFY [May 15 01:04:26] DEBUG[27636] chan_sip.c: Trying to put 'SIP/2.0 200' onto UDP socket destined for 195.128.55.18:1109 [May 15 01:04:26] DEBUG[27636] chan_sip.c: Allocating new SIP dialog for 2f94318771e71443462b85a74eda8009@89.188.109.170:5060 - OPTIONS (No RTP) [May 15 01:04:26] DEBUG[27636] acl.c: For destination '195.68.128.222', our source address is '89.188.109.170'. [May 15 01:04:26] DEBUG[27636] chan_sip.c: Target address 195.68.128.222:1024 is not local, substituting externaddr [May 15 01:04:26] DEBUG[27636] chan_sip.c: Setting SIP_TRANSPORT_UDP with address 89.188.109.170:5060 [May 15 01:04:26] DEBUG[27636] chan_sip.c: SIP call-id changed from '2f94318771e71443462b85a74eda8009@89.188.109.170:5060' to '3aa942272e0186e2387a0f9714ec411b@89.188.109.170:5060' [May 15 01:04:26] DEBUG[27636] chan_sip.c: Initializing initreq for method OPTIONS - callid 3aa942272e0186e2387a0f9714ec411b@89.188.109.170:5060 [May 15 01:04:26] DEBUG[27636] chan_sip.c: Trying to put 'OPTIONS sip' onto UDP socket destined for 195.68.128.222:1024 [May 15 01:04:26] DEBUG[27636] chan_sip.c: = Looking for Call ID: f5378c61-2d7ae58@192.168.42.143 (Checking From) --From tag 333988656d0c4224o2 --To-tag [May 15 01:04:26] DEBUG[27636] chan_sip.c: **** Received NOTIFY (4) - Command in SIP NOTIFY [May 15 01:04:26] DEBUG[27636] chan_sip.c: Trying to put 'SIP/2.0 200' onto UDP socket destined for 213.33.196.118:5062 [May 15 01:04:26] DEBUG[27636] chan_sip.c: = Looking for Call ID: 3aa942272e0186e2387a0f9714ec411b@89.188.109.170:5060 (Checking To) --From tag as57c299f1 --To-tag 45ecb851a50af358i0 [May 15 01:04:26] DEBUG[27636] chan_sip.c: Stopping retransmission on '3aa942272e0186e2387a0f9714ec411b@89.188.109.170:5060' of Request 102: Match Found [May 15 01:04:26] DEBUG[27636] chan_sip.c: Destroying SIP dialog 3aa942272e0186e2387a0f9714ec411b@89.188.109.170:5060 [May 15 01:04:26] DEBUG[27636] chan_sip.c: = Looking for Call ID: d810873b-70626223@192.168.43.24 (Checking From) --From tag 97b6309b54a9c843o2 --To-tag [May 15 01:04:26] DEBUG[27636] chan_sip.c: **** Received NOTIFY (4) - Command in SIP NOTIFY [May 15 01:04:26] DEBUG[27636] chan_sip.c: Trying to put 'SIP/2.0 200' onto UDP socket destined for 195.128.55.18:1050 [May 15 01:04:26] DEBUG[27636] chan_sip.c: = Looking for Call ID: 60cb2851-2a3f44df@192.168.42.191 (Checking From) --From tag 1ba36fe9b24de227o0 --To-tag [May 15 01:04:26] DEBUG[27636] chan_sip.c: **** Received NOTIFY (4) - Command in SIP NOTIFY [May 15 01:04:26] DEBUG[27636] chan_sip.c: Trying to put 'SIP/2.0 200' onto UDP socket destined for 82.204.246.142:49468 [May 15 01:04:26] DEBUG[27636] chan_sip.c: = Looking for Call ID: 1d6e2dab-2436b07@192.168.42.191 (Checking From) --From tag 1ba36fe9b24de227o0 --To-tag [May 15 01:04:26] DEBUG[27636] netsock2.c: Splitting '89.188.109.170' into... [May 15 01:04:26] DEBUG[27636] netsock2.c: ...host '89.188.109.170' and port ''. [May 15 01:04:26] DEBUG[27636] netsock2.c: Splitting '89.188.109.170' into... [May 15 01:04:26] DEBUG[27636] netsock2.c: ...host '89.188.109.170' and port ''. [May 15 01:04:26] DEBUG[27636] chan_sip.c: **** Received REGISTER (2) - Command in SIP REGISTER [May 15 01:04:26] DEBUG[27636] netsock2.c: Splitting '192.168.42.191:5060' into... [May 15 01:04:26] DEBUG[27636] netsock2.c: ...host '192.168.42.191' and port '5060'. [May 15 01:04:26] DEBUG[27636] chan_sip.c: NAT detected for 192.168.42.191 / 82.204.246.142 [May 15 01:04:26] DEBUG[27636] netsock2.c: Splitting '89.188.109.170' into... [May 15 01:04:26] DEBUG[27636] netsock2.c: ...host '89.188.109.170' and port ''. [May 15 01:04:26] DEBUG[27636] chan_sip.c: Trying to put 'SIP/2.0 401' onto UDP socket destined for 82.204.246.142:49468 [May 15 01:04:26] DEBUG[27636] chan_sip.c: = Looking for Call ID: 1d6e2dab-2436b07@192.168.42.191 (Checking From) --From tag 1ba36fe9b24de227o0 --To-tag [May 15 01:04:26] DEBUG[27636] netsock2.c: Splitting '89.188.109.170' into... [May 15 01:04:26] DEBUG[27636] netsock2.c: ...host '89.188.109.170' and port ''. [May 15 01:04:26] DEBUG[27636] netsock2.c: Splitting '89.188.109.170' into... [May 15 01:04:26] DEBUG[27636] netsock2.c: ...host '89.188.109.170' and port ''. [May 15 01:04:26] DEBUG[27636] chan_sip.c: **** Received REGISTER (2) - Command in SIP REGISTER [May 15 01:04:26] DEBUG[27636] netsock2.c: Splitting '192.168.42.191:5060' into... [May 15 01:04:26] DEBUG[27636] netsock2.c: ...host '192.168.42.191' and port '5060'. [May 15 01:04:26] DEBUG[27636] chan_sip.c: NAT detected for 192.168.42.191 / 82.204.246.142 [May 15 01:04:26] DEBUG[27636] netsock2.c: Splitting '89.188.109.170' into... [May 15 01:04:26] DEBUG[27636] netsock2.c: ...host '89.188.109.170' and port ''. [May 15 01:04:26] DEBUG[27636] chan_sip.c: Store REGISTER's src-IP:port for call routing. [May 15 01:04:26] VERBOSE[27636] chan_sip.c: [May 15 01:04:26] -- Registered SIP '6225' at 82.204.246.142:49468 [May 15 01:04:26] DEBUG[27636] chan_sip.c: Allocating new SIP dialog for 04966cfe12694e4f3a4ed6f57a6c46d7@89.188.109.170:5060 - OPTIONS (No RTP) [May 15 01:04:26] DEBUG[27636] acl.c: For destination '82.204.246.142', our source address is '89.188.109.170'. [May 15 01:04:26] DEBUG[27636] chan_sip.c: Target address 82.204.246.142:49468 is not local, substituting externaddr [May 15 01:04:26] DEBUG[27636] chan_sip.c: Setting SIP_TRANSPORT_UDP with address 89.188.109.170:5060 [May 15 01:04:26] DEBUG[27636] chan_sip.c: SIP call-id changed from '04966cfe12694e4f3a4ed6f57a6c46d7@89.188.109.170:5060' to '6095664c46f8bcb9401253a61497ab18@89.188.109.170:5060' [May 15 01:04:26] DEBUG[27636] chan_sip.c: Initializing initreq for method OPTIONS - callid 6095664c46f8bcb9401253a61497ab18@89.188.109.170:5060 [May 15 01:04:26] DEBUG[27636] chan_sip.c: Trying to put 'OPTIONS sip' onto UDP socket destined for 82.204.246.142:49468 [May 15 01:04:26] DEBUG[27636] chan_sip.c: Trying to put 'SIP/2.0 200' onto UDP socket destined for 82.204.246.142:49468 [May 15 01:04:26] DEBUG[27629] devicestate.c: No provider found, checking channel drivers for SIP - 6225 [May 15 01:04:26] DEBUG[27629] chan_sip.c: Checking device state for peer 6225 [May 15 01:04:26] DEBUG[27629] devicestate.c: Changing state for SIP/6225 - state 1 (Not in use) [May 15 01:04:26] DEBUG[27629] devicestate.c: device 'SIP/6225' state '1' [May 15 01:04:26] DEBUG[27642] devicestate.c: device 'Queue:q-cgroup-14_avail' state '1' [May 15 01:04:26] DEBUG[27642] app_queue.c: Device 'SIP/6225' changed to state '1' (Not in use) [May 15 01:04:26] DEBUG[27642] app_queue.c: Device 'Queue:q-cgroup-14_avail' changed to state '1' (Not in use) but we don't care because they're not a member of any queue. [May 15 01:04:26] DEBUG[27636] chan_sip.c: = Looking for Call ID: 6095664c46f8bcb9401253a61497ab18@89.188.109.170:5060 (Checking To) --From tag as1ef3660f --To-tag 260bac918b3e8d9fi0 [May 15 01:04:26] DEBUG[27636] chan_sip.c: Stopping retransmission on '6095664c46f8bcb9401253a61497ab18@89.188.109.170:5060' of Request 102: Match Found [May 15 01:04:26] DEBUG[27636] chan_sip.c: Destroying SIP dialog 6095664c46f8bcb9401253a61497ab18@89.188.109.170:5060 [May 15 01:04:26] DEBUG[27636] chan_sip.c: = Looking for Call ID: 299d4f2c-c3ee5304@192.168.43.15 (Checking From) --From tag 7976660c831d29a4o0 --To-tag [May 15 01:04:26] DEBUG[27636] chan_sip.c: **** Received NOTIFY (4) - Command in SIP NOTIFY [May 15 01:04:26] DEBUG[27636] chan_sip.c: Trying to put 'SIP/2.0 200' onto UDP socket destined for 195.128.55.18:1042 [May 15 01:04:26] DEBUG[27636] chan_sip.c: = Looking for Call ID: bfc60e33-83f5c7cc@192.168.42.101 (Checking From) --From tag 3bf212db6cb54b44o1 --To-tag [May 15 01:04:26] DEBUG[27636] chan_sip.c: **** Received NOTIFY (4) - Command in SIP NOTIFY [May 15 01:04:26] DEBUG[27636] chan_sip.c: Trying to put 'SIP/2.0 200' onto UDP socket destined for 80.249.177.38:1025 [May 15 01:04:26] DEBUG[27636] chan_sip.c: = Looking for Call ID: 95c803fb-54630c63@192.168.43.24 (Checking From) --From tag 7366d21b6d1594c3o0 --To-tag [May 15 01:04:26] DEBUG[27636] chan_sip.c: **** Received NOTIFY (4) - Command in SIP NOTIFY [May 15 01:04:26] DEBUG[27636] chan_sip.c: Trying to put 'SIP/2.0 200' onto UDP socket destined for 195.128.55.18:1047 [May 15 01:04:26] DEBUG[27636] chan_sip.c: = Looking for Call ID: 96100449-2f03585c@192.168.43.47 (Checking From) --From tag ce120905e74cbad0o0 --To-tag [May 15 01:04:26] DEBUG[27636] chan_sip.c: **** Received NOTIFY (4) - Command in SIP NOTIFY [May 15 01:04:26] DEBUG[27636] chan_sip.c: Trying to put 'SIP/2.0 200' onto UDP socket destined for 195.128.55.18:1048 [May 15 01:04:26] DEBUG[27636] chan_sip.c: Allocating new SIP dialog for 11e1f499156ff0cb2a33a4413a7e4f33@89.188.109.170:5060 - OPTIONS (No RTP) [May 15 01:04:26] DEBUG[27636] acl.c: For destination '10.77.9.246', our source address is '10.77.9.146'. [May 15 01:04:26] DEBUG[27636] chan_sip.c: Setting SIP_TRANSPORT_UDP with address 10.77.9.146:5060 [May 15 01:04:26] DEBUG[27636] chan_sip.c: SIP call-id changed from '11e1f499156ff0cb2a33a4413a7e4f33@89.188.109.170:5060' to '07fe3ad009e01b616c424d9b33ef6de1@10.77.9.146:5060' [May 15 01:04:26] DEBUG[27636] chan_sip.c: Initializing initreq for method OPTIONS - callid 07fe3ad009e01b616c424d9b33ef6de1@10.77.9.146:5060 [May 15 01:04:26] DEBUG[27636] chan_sip.c: Trying to put 'OPTIONS sip' onto UDP socket destined for 10.77.9.246:5060 [May 15 01:04:26] DEBUG[27636] chan_sip.c: = Looking for Call ID: 07fe3ad009e01b616c424d9b33ef6de1@10.77.9.146:5060 (Checking To) --From tag as5346b41c --To-tag as47c79948 [May 15 01:04:26] DEBUG[27636] chan_sip.c: Stopping retransmission on '07fe3ad009e01b616c424d9b33ef6de1@10.77.9.146:5060' of Request 102: Match Found [May 15 01:04:26] DEBUG[27636] chan_sip.c: Destroying SIP dialog 07fe3ad009e01b616c424d9b33ef6de1@10.77.9.146:5060 [May 15 01:04:26] DEBUG[27636] chan_sip.c: = Looking for Call ID: 49d60dfd-3af3ba4b@192.168.43.71 (Checking From) --From tag 485190adffd6975bo1 --To-tag [May 15 01:04:26] DEBUG[27636] chan_sip.c: **** Received NOTIFY (4) - Command in SIP NOTIFY [May 15 01:04:26] DEBUG[27636] chan_sip.c: Trying to put 'SIP/2.0 200' onto UDP socket destined for 195.128.55.18:1087 [May 15 01:04:26] DEBUG[27636] chan_sip.c: = Looking for Call ID: 523d06d2-26be9141@192.168.42.101 (Checking From) --From tag cadad7f6d9f4526do2 --To-tag [May 15 01:04:26] DEBUG[27636] chan_sip.c: **** Received NOTIFY (4) - Command in SIP NOTIFY [May 15 01:04:26] DEBUG[27636] chan_sip.c: Trying to put 'SIP/2.0 200' onto UDP socket destined for 82.204.246.142:5062 [May 15 01:04:26] DEBUG[27636] chan_sip.c: = Looking for Call ID: f0f16a7-dbc13a1e@192.168.42.143 (Checking From) --From tag 4076edb7d8dcce4eo3 --To-tag [May 15 01:04:26] DEBUG[27636] chan_sip.c: **** Received NOTIFY (4) - Command in SIP NOTIFY [May 15 01:04:26] DEBUG[27636] chan_sip.c: Trying to put 'SIP/2.0 200' onto UDP socket destined for 62.141.78.138:5063 [May 15 01:04:26] DEBUG[27636] chan_sip.c: Allocating new SIP dialog for 1bea1d3e326b977273b3614b7f9f6c50@89.188.109.170:5060 - OPTIONS (No RTP) [May 15 01:04:26] DEBUG[27636] acl.c: For destination '195.128.55.18', our source address is '89.188.109.170'. [May 15 01:04:26] DEBUG[27636] chan_sip.c: Target address 195.128.55.18:1039 is not local, substituting externaddr [May 15 01:04:26] DEBUG[27636] chan_sip.c: Setting SIP_TRANSPORT_UDP with address 89.188.109.170:5060 [May 15 01:04:26] DEBUG[27636] chan_sip.c: SIP call-id changed from '1bea1d3e326b977273b3614b7f9f6c50@89.188.109.170:5060' to '7b3515056019e8b618651b65752cd355@89.188.109.170:5060' [May 15 01:04:26] DEBUG[27636] chan_sip.c: Initializing initreq for method OPTIONS - callid 7b3515056019e8b618651b65752cd355@89.188.109.170:5060 [May 15 01:04:26] DEBUG[27636] chan_sip.c: Trying to put 'OPTIONS sip' onto UDP socket destined for 195.128.55.18:1039 [May 15 01:04:26] DEBUG[27636] chan_sip.c: = Looking for Call ID: 7b3515056019e8b618651b65752cd355@89.188.109.170:5060 (Checking To) --From tag as74ea0da0 --To-tag 32be9ee929a72bi0 [May 15 01:04:26] DEBUG[27636] chan_sip.c: Stopping retransmission on '7b3515056019e8b618651b65752cd355@89.188.109.170:5060' of Request 102: Match Found [May 15 01:04:26] DEBUG[27636] chan_sip.c: Destroying SIP dialog 7b3515056019e8b618651b65752cd355@89.188.109.170:5060 [May 15 01:04:26] DEBUG[27636] chan_sip.c: = Looking for Call ID: c1130937-dfc3683f@192.168.43.8 (Checking From) --From tag bedff3d7408a7e1fo0 --To-tag [May 15 01:04:26] DEBUG[27636] chan_sip.c: **** Received NOTIFY (4) - Command in SIP NOTIFY [May 15 01:04:26] DEBUG[27636] chan_sip.c: Trying to put 'SIP/2.0 200' onto UDP socket destined for 195.128.55.18:1072 [May 15 01:04:26] DEBUG[27636] chan_sip.c: = Looking for Call ID: 9a607aa7-e7ff2513@192.168.42.146 (Checking From) --From tag d3cb25e7770272d3o3 --To-tag [May 15 01:04:26] DEBUG[27636] chan_sip.c: **** Received NOTIFY (4) - Command in SIP NOTIFY [May 15 01:04:26] DEBUG[27636] chan_sip.c: Trying to put 'SIP/2.0 200' onto UDP socket destined for 82.204.246.142:5063 [May 15 01:04:26] DEBUG[27636] chan_sip.c: = Looking for Call ID: a55ef8e4-fdc5f484@192.168.43.29 (Checking From) --From tag 43ccb96431ff2e04o0 --To-tag [May 15 01:04:26] DEBUG[27636] chan_sip.c: **** Received NOTIFY (4) - Command in SIP NOTIFY [May 15 01:04:26] DEBUG[27636] chan_sip.c: Trying to put 'SIP/2.0 200' onto UDP socket destined for 195.128.55.18:1067 [May 15 01:04:26] DEBUG[27636] chan_sip.c: = Looking for Call ID: a0f3cf40-d9f1b1d6@192.168.43.48 (Checking From) --From tag 27d34d78aaf188feo0 --To-tag [May 15 01:04:26] DEBUG[27636] chan_sip.c: **** Received NOTIFY (4) - Command in SIP NOTIFY [May 15 01:04:26] DEBUG[27636] chan_sip.c: Trying to put 'SIP/2.0 200' onto UDP socket destined for 195.128.55.18:1080 [May 15 01:04:26] DEBUG[27636] chan_sip.c: = Looking for Call ID: e968b7d4-acf5c2d4@192.168.42.102 (Checking From) --From tag c462d3d4c2e256d4o0 --To-tag [May 15 01:04:26] DEBUG[27636] chan_sip.c: **** Received NOTIFY (4) - Command in SIP NOTIFY [May 15 01:04:26] DEBUG[27636] chan_sip.c: Trying to put 'SIP/2.0 200' onto UDP socket destined for 213.184.150.74:1024 [May 15 01:04:26] DEBUG[27636] chan_sip.c: = Looking for Call ID: c86ac23-4b44425d@192.168.43.21 (Checking From) --From tag 1e818783fce81e7do3 --To-tag [May 15 01:04:26] DEBUG[27636] chan_sip.c: **** Received NOTIFY (4) - Command in SIP NOTIFY [May 15 01:04:26] DEBUG[27636] chan_sip.c: Trying to put 'SIP/2.0 200' onto UDP socket destined for 195.128.55.18:1099 [May 15 01:04:26] DEBUG[27636] chan_sip.c: = Looking for Call ID: 860f4c29-3459b130@192.168.42.143 (Checking From) --From tag 919a86f5f374d4d4o0 --To-tag [May 15 01:04:26] DEBUG[27636] chan_sip.c: **** Received NOTIFY (4) - Command in SIP NOTIFY [May 15 01:04:26] DEBUG[27636] chan_sip.c: Trying to put 'SIP/2.0 200' onto UDP socket destined for 213.33.196.118:1026 [May 15 01:04:26] DEBUG[27636] chan_sip.c: Allocating new SIP dialog for 412f612c2cbffb000ec009c65a49cba3@89.188.109.170:5060 - OPTIONS (No RTP) [May 15 01:04:26] DEBUG[27636] acl.c: For destination '195.128.55.18', our source address is '89.188.109.170'. [May 15 01:04:26] DEBUG[27636] chan_sip.c: Target address 195.128.55.18:1043 is not local, substituting externaddr [May 15 01:04:26] DEBUG[27636] chan_sip.c: Setting SIP_TRANSPORT_UDP with address 89.188.109.170:5060 [May 15 01:04:26] DEBUG[27636] chan_sip.c: SIP call-id changed from '412f612c2cbffb000ec009c65a49cba3@89.188.109.170:5060' to '351abc7065cef5fe17a89eed56d5b31f@89.188.109.170:5060' [May 15 01:04:26] DEBUG[27636] chan_sip.c: Initializing initreq for method OPTIONS - callid 351abc7065cef5fe17a89eed56d5b31f@89.188.109.170:5060 [May 15 01:04:26] DEBUG[27636] chan_sip.c: Trying to put 'OPTIONS sip' onto UDP socket destined for 195.128.55.18:1043 [May 15 01:04:26] DEBUG[27636] chan_sip.c: = Looking for Call ID: 351abc7065cef5fe17a89eed56d5b31f@89.188.109.170:5060 (Checking To) --From tag as2dff65cf --To-tag 946e291cbc09c197i0 [May 15 01:04:26] DEBUG[27636] chan_sip.c: Stopping retransmission on '351abc7065cef5fe17a89eed56d5b31f@89.188.109.170:5060' of Request 102: Match Found [May 15 01:04:26] DEBUG[27636] chan_sip.c: Destroying SIP dialog 351abc7065cef5fe17a89eed56d5b31f@89.188.109.170:5060 [May 15 01:04:27] DEBUG[27636] chan_sip.c: = Looking for Call ID: 000e83be-d6550003-793bff7c-51d1a825@192.168.42.236 (Checking From) --From tag 000e83bed6556ed2099c41b3-52efa64e --To-tag [May 15 01:04:27] DEBUG[27636] acl.c: For destination '195.128.55.30', our source address is '89.188.109.170'. [May 15 01:04:27] DEBUG[27636] chan_sip.c: Target address 195.128.55.30:62606 is not local, substituting externaddr [May 15 01:04:27] DEBUG[27636] chan_sip.c: Setting SIP_TRANSPORT_UDP with address 89.188.109.170:5060 [May 15 01:04:27] DEBUG[27636] netsock2.c: Splitting '195.128.55.30:5061' into... [May 15 01:04:27] DEBUG[27636] netsock2.c: ...host '195.128.55.30' and port '5061'. [May 15 01:04:27] DEBUG[27636] chan_sip.c: Allocating new SIP dialog for 000e83be-d6550003-793bff7c-51d1a825@192.168.42.236 - REGISTER (No RTP) [May 15 01:04:27] DEBUG[27636] chan_sip.c: **** Received REGISTER (2) - Command in SIP REGISTER [May 15 01:04:27] DEBUG[27636] netsock2.c: Splitting '195.128.55.30:5061' into... [May 15 01:04:27] DEBUG[27636] netsock2.c: ...host '195.128.55.30' and port '5061'. [May 15 01:04:27] DEBUG[27636] netsock2.c: Splitting '89.188.109.170' into... [May 15 01:04:27] DEBUG[27636] netsock2.c: ...host '89.188.109.170' and port ''. [May 15 01:04:27] DEBUG[27636] chan_sip.c: Trying to put 'SIP/2.0 401' onto UDP socket destined for 195.128.55.30:62606 [May 15 01:04:27] DEBUG[27636] chan_sip.c: = Looking for Call ID: dbee0bb2-2546502c@192.168.43.27 (Checking From) --From tag fb6256bacf4340c4o0 --To-tag [May 15 01:04:27] DEBUG[27636] chan_sip.c: **** Received NOTIFY (4) - Command in SIP NOTIFY [May 15 01:04:27] DEBUG[27636] chan_sip.c: Trying to put 'SIP/2.0 200' onto UDP socket destined for 195.128.55.18:1052 [May 15 01:04:27] DEBUG[27636] chan_sip.c: Allocating new SIP dialog for 1e228e341bef9fdf57d698cc27606e30@89.188.109.170:5060 - OPTIONS (No RTP) [May 15 01:04:27] DEBUG[27636] acl.c: For destination '195.128.55.18', our source address is '89.188.109.170'. [May 15 01:04:27] DEBUG[27636] chan_sip.c: Target address 195.128.55.18:1035 is not local, substituting externaddr [May 15 01:04:27] DEBUG[27636] chan_sip.c: Setting SIP_TRANSPORT_UDP with address 89.188.109.170:5060 [May 15 01:04:27] DEBUG[27636] chan_sip.c: SIP call-id changed from '1e228e341bef9fdf57d698cc27606e30@89.188.109.170:5060' to '2015b89116aa0d6b5f46203532364a7d@89.188.109.170:5060' [May 15 01:04:27] DEBUG[27636] chan_sip.c: Initializing initreq for method OPTIONS - callid 2015b89116aa0d6b5f46203532364a7d@89.188.109.170:5060 [May 15 01:04:27] DEBUG[27636] chan_sip.c: Trying to put 'OPTIONS sip' onto UDP socket destined for 195.128.55.18:1035 [May 15 01:04:27] DEBUG[27636] chan_sip.c: = Looking for Call ID: 2015b89116aa0d6b5f46203532364a7d@89.188.109.170:5060 (Checking To) --From tag as11cc0ee6 --To-tag d4fd3b16577047ebi0 [May 15 01:04:27] DEBUG[27636] chan_sip.c: Stopping retransmission on '2015b89116aa0d6b5f46203532364a7d@89.188.109.170:5060' of Request 102: Match Found [May 15 01:04:27] DEBUG[27636] chan_sip.c: Destroying SIP dialog 2015b89116aa0d6b5f46203532364a7d@89.188.109.170:5060 [May 15 01:04:27] DEBUG[27636] chan_sip.c: = Looking for Call ID: 9a755eda-d57ed8d9@192.168.42.101 (Checking From) --From tag b52bbf0684ba169do0 --To-tag [May 15 01:04:27] DEBUG[27636] chan_sip.c: **** Received NOTIFY (4) - Command in SIP NOTIFY [May 15 01:04:27] DEBUG[27636] chan_sip.c: Trying to put 'SIP/2.0 200' onto UDP socket destined for 82.204.246.142:49469 [May 15 01:04:27] DEBUG[27636] chan_sip.c: = Looking for Call ID: 000e83be-d6550003-793bff7c-51d1a825@192.168.42.236 (Checking From) --From tag 000e83bed6556ed2099c41b3-52efa64e --To-tag [May 15 01:04:27] DEBUG[27636] netsock2.c: Splitting '89.188.109.170' into... [May 15 01:04:27] DEBUG[27636] netsock2.c: ...host '89.188.109.170' and port ''. [May 15 01:04:27] DEBUG[27636] netsock2.c: Splitting '89.188.109.170' into... [May 15 01:04:27] DEBUG[27636] netsock2.c: ...host '89.188.109.170' and port ''. [May 15 01:04:27] DEBUG[27636] chan_sip.c: **** Received REGISTER (2) - Command in SIP REGISTER [May 15 01:04:27] DEBUG[27636] netsock2.c: Splitting '195.128.55.30:5061' into... [May 15 01:04:27] DEBUG[27636] netsock2.c: ...host '195.128.55.30' and port '5061'. [May 15 01:04:27] DEBUG[27636] netsock2.c: Splitting '89.188.109.170' into... [May 15 01:04:27] DEBUG[27636] netsock2.c: ...host '89.188.109.170' and port ''. [May 15 01:04:27] DEBUG[27636] chan_sip.c: Store REGISTER's src-IP:port for call routing. [May 15 01:04:27] DEBUG[27636] chan_sip.c: Allocating new SIP dialog for 6249397c51404f8d1d2cb4222bdfa393@89.188.109.170:5060 - OPTIONS (No RTP) [May 15 01:04:27] DEBUG[27636] acl.c: For destination '195.128.55.30', our source address is '89.188.109.170'. [May 15 01:04:27] DEBUG[27636] chan_sip.c: Target address 195.128.55.30:62606 is not local, substituting externaddr [May 15 01:04:27] DEBUG[27636] chan_sip.c: Setting SIP_TRANSPORT_UDP with address 89.188.109.170:5060 [May 15 01:04:27] DEBUG[27636] chan_sip.c: SIP call-id changed from '6249397c51404f8d1d2cb4222bdfa393@89.188.109.170:5060' to '025d6c25084699c90d6e9d8317440bfa@89.188.109.170:5060' [May 15 01:04:27] DEBUG[27636] chan_sip.c: Initializing initreq for method OPTIONS - callid 025d6c25084699c90d6e9d8317440bfa@89.188.109.170:5060 [May 15 01:04:27] DEBUG[27636] chan_sip.c: Trying to put 'OPTIONS sip' onto UDP socket destined for 195.128.55.30:62606 [May 15 01:04:27] DEBUG[27636] chan_sip.c: Trying to put 'SIP/2.0 200' onto UDP socket destined for 195.128.55.30:62606 [May 15 01:04:27] DEBUG[27629] devicestate.c: No provider found, checking channel drivers for SIP - 725 [May 15 01:04:27] DEBUG[27629] chan_sip.c: Checking device state for peer 725 [May 15 01:04:27] DEBUG[27629] devicestate.c: Changing state for SIP/725 - state 1 (Not in use) [May 15 01:04:27] DEBUG[27629] devicestate.c: device 'SIP/725' state '1' [May 15 01:04:27] DEBUG[27642] app_queue.c: Device 'SIP/725' changed to state '1' (Not in use) but we don't care because they're not a member of any queue. [May 15 01:04:27] DEBUG[27636] chan_sip.c: = Looking for Call ID: df6c2cab-4cc577c7@192.168.42.101 (Checking From) --From tag b52bbf0684ba169do0 --To-tag [May 15 01:04:27] DEBUG[27636] netsock2.c: Splitting '89.188.109.170' into... [May 15 01:04:27] DEBUG[27636] netsock2.c: ...host '89.188.109.170' and port ''. [May 15 01:04:27] DEBUG[27636] netsock2.c: Splitting '89.188.109.170' into... [May 15 01:04:27] DEBUG[27636] netsock2.c: ...host '89.188.109.170' and port ''. [May 15 01:04:27] DEBUG[27636] chan_sip.c: **** Received REGISTER (2) - Command in SIP REGISTER [May 15 01:04:27] DEBUG[27636] netsock2.c: Splitting '192.168.42.101:5060' into... [May 15 01:04:27] DEBUG[27636] netsock2.c: ...host '192.168.42.101' and port '5060'. [May 15 01:04:27] DEBUG[27636] chan_sip.c: NAT detected for 192.168.42.101 / 82.204.246.142 [May 15 01:04:27] DEBUG[27636] netsock2.c: Splitting '89.188.109.170' into... [May 15 01:04:27] DEBUG[27636] netsock2.c: ...host '89.188.109.170' and port ''. [May 15 01:04:27] DEBUG[27636] chan_sip.c: Trying to put 'SIP/2.0 401' onto UDP socket destined for 82.204.246.142:49469 [May 15 01:04:27] DEBUG[27636] chan_sip.c: = Looking for Call ID: bbb41e23-87a57836@192.168.43.12 (Checking From) --From tag 33fd1adfc79442aao0 --To-tag [May 15 01:04:27] DEBUG[27636] chan_sip.c: **** Received NOTIFY (4) - Command in SIP NOTIFY [May 15 01:04:27] DEBUG[27636] chan_sip.c: Trying to put 'SIP/2.0 200' onto UDP socket destined for 195.128.55.18:1028 [May 15 01:04:27] DEBUG[27636] chan_sip.c: = Looking for Call ID: df6c2cab-4cc577c7@192.168.42.101 (Checking From) --From tag b52bbf0684ba169do0 --To-tag [May 15 01:04:27] DEBUG[27636] netsock2.c: Splitting '89.188.109.170' into... [May 15 01:04:27] DEBUG[27636] netsock2.c: ...host '89.188.109.170' and port ''. [May 15 01:04:27] DEBUG[27636] netsock2.c: Splitting '89.188.109.170' into... [May 15 01:04:27] DEBUG[27636] netsock2.c: ...host '89.188.109.170' and port ''. [May 15 01:04:27] DEBUG[27636] chan_sip.c: **** Received REGISTER (2) - Command in SIP REGISTER [May 15 01:04:27] DEBUG[27636] netsock2.c: Splitting '192.168.42.101:5060' into... [May 15 01:04:27] DEBUG[27636] netsock2.c: ...host '192.168.42.101' and port '5060'. [May 15 01:04:27] DEBUG[27636] chan_sip.c: NAT detected for 192.168.42.101 / 82.204.246.142 [May 15 01:04:27] DEBUG[27636] netsock2.c: Splitting '89.188.109.170' into... [May 15 01:04:27] DEBUG[27636] netsock2.c: ...host '89.188.109.170' and port ''. [May 15 01:04:27] DEBUG[27636] chan_sip.c: Store REGISTER's src-IP:port for call routing. [May 15 01:04:27] VERBOSE[27636] chan_sip.c: [May 15 01:04:27] -- Registered SIP '6226' at 82.204.246.142:49469 [May 15 01:04:27] DEBUG[27636] chan_sip.c: Allocating new SIP dialog for 09ef422e7ccd9e9e14774e7939a813e8@89.188.109.170:5060 - OPTIONS (No RTP) [May 15 01:04:27] DEBUG[27636] acl.c: For destination '82.204.246.142', our source address is '89.188.109.170'. [May 15 01:04:27] DEBUG[27636] chan_sip.c: Target address 82.204.246.142:49469 is not local, substituting externaddr [May 15 01:04:27] DEBUG[27636] chan_sip.c: Setting SIP_TRANSPORT_UDP with address 89.188.109.170:5060 [May 15 01:04:27] DEBUG[27636] chan_sip.c: SIP call-id changed from '09ef422e7ccd9e9e14774e7939a813e8@89.188.109.170:5060' to '049e062d45ef8b5b57e2ef5a1487eff9@89.188.109.170:5060' [May 15 01:04:27] DEBUG[27636] chan_sip.c: Initializing initreq for method OPTIONS - callid 049e062d45ef8b5b57e2ef5a1487eff9@89.188.109.170:5060 [May 15 01:04:27] DEBUG[27636] chan_sip.c: Trying to put 'OPTIONS sip' onto UDP socket destined for 82.204.246.142:49469 [May 15 01:04:27] DEBUG[27636] chan_sip.c: Trying to put 'SIP/2.0 200' onto UDP socket destined for 82.204.246.142:49469 [May 15 01:04:27] DEBUG[27629] devicestate.c: No provider found, checking channel drivers for SIP - 6226 [May 15 01:04:27] DEBUG[27629] chan_sip.c: Checking device state for peer 6226 [May 15 01:04:27] DEBUG[27629] devicestate.c: Changing state for SIP/6226 - state 1 (Not in use) [May 15 01:04:27] DEBUG[27629] devicestate.c: device 'SIP/6226' state '1' [May 15 01:04:27] DEBUG[27642] devicestate.c: device 'Queue:q-cgroup-14_avail' state '1' [May 15 01:04:27] DEBUG[27642] app_queue.c: Device 'SIP/6226' changed to state '1' (Not in use) [May 15 01:04:27] DEBUG[27642] app_queue.c: Device 'Queue:q-cgroup-14_avail' changed to state '1' (Not in use) but we don't care because they're not a member of any queue. [May 15 01:04:27] DEBUG[27636] chan_sip.c: = Looking for Call ID: 049e062d45ef8b5b57e2ef5a1487eff9@89.188.109.170:5060 (Checking To) --From tag as1d0698c8 --To-tag ee7c01fad8639039i0 [May 15 01:04:27] DEBUG[27636] chan_sip.c: Stopping retransmission on '049e062d45ef8b5b57e2ef5a1487eff9@89.188.109.170:5060' of Request 102: Match Found [May 15 01:04:27] DEBUG[27636] chan_sip.c: Destroying SIP dialog 049e062d45ef8b5b57e2ef5a1487eff9@89.188.109.170:5060 [May 15 01:04:27] DEBUG[27636] chan_sip.c: = Looking for Call ID: b69fc937-399da7c3@192.168.42.146 (Checking From) --From tag aef4487713be4b3o2 --To-tag [May 15 01:04:27] DEBUG[27636] chan_sip.c: **** Received NOTIFY (4) - Command in SIP NOTIFY [May 15 01:04:27] DEBUG[27636] chan_sip.c: Trying to put 'SIP/2.0 200' onto UDP socket destined for 82.204.246.142:49470 [May 15 01:04:27] DEBUG[27636] chan_sip.c: = Looking for Call ID: d16ce17c-28b364b7@192.168.43.11 (Checking From) --From tag e6d50b587537e48bo0 --To-tag [May 15 01:04:27] DEBUG[27636] chan_sip.c: **** Received NOTIFY (4) - Command in SIP NOTIFY [May 15 01:04:27] DEBUG[27636] chan_sip.c: Trying to put 'SIP/2.0 200' onto UDP socket destined for 195.128.55.18:1043 [May 15 01:04:27] DEBUG[27636] chan_sip.c: = Looking for Call ID: 025d6c25084699c90d6e9d8317440bfa@89.188.109.170:5060 (Checking To) --From tag as345bd0b7 --To-tag 000e83bed6556ed37a5efcf6-2139900a [May 15 01:04:27] DEBUG[27636] chan_sip.c: Stopping retransmission on '025d6c25084699c90d6e9d8317440bfa@89.188.109.170:5060' of Request 102: Match Found [May 15 01:04:27] DEBUG[27636] chan_sip.c: Destroying SIP dialog 025d6c25084699c90d6e9d8317440bfa@89.188.109.170:5060 [May 15 01:04:27] DEBUG[27636] chan_sip.c: = Looking for Call ID: e3d22818-59af6f3d@192.168.43.61 (Checking From) --From tag 1e1235bc75141de9o0 --To-tag [May 15 01:04:27] DEBUG[27636] chan_sip.c: **** Received NOTIFY (4) - Command in SIP NOTIFY [May 15 01:04:27] DEBUG[27636] chan_sip.c: Trying to put 'SIP/2.0 200' onto UDP socket destined for 195.128.55.18:1034 [May 15 01:04:27] DEBUG[27636] chan_sip.c: = Looking for Call ID: 27ef3307-bd58c908@192.168.42.101 (Checking From) --From tag 3a903e232977d89co2 --To-tag [May 15 01:04:27] DEBUG[27636] chan_sip.c: **** Received NOTIFY (4) - Command in SIP NOTIFY [May 15 01:04:27] DEBUG[27636] chan_sip.c: Trying to put 'SIP/2.0 200' onto UDP socket destined for 80.249.177.38:1026 [May 15 01:04:27] DEBUG[27636] chan_sip.c: = Looking for Call ID: 3ac0314a-ff74a73d@192.168.42.101 (Checking From) --From tag de848a9adbf39e2do3 --To-tag [May 15 01:04:27] DEBUG[27636] chan_sip.c: **** Received NOTIFY (4) - Command in SIP NOTIFY [May 15 01:04:27] DEBUG[27636] chan_sip.c: Trying to put 'SIP/2.0 200' onto UDP socket destined for 195.190.123.162:5063 [May 15 01:04:27] DEBUG[27636] chan_sip.c: = Looking for Call ID: 70c80ee6-c7d7083d@192.168.42.101 (Checking From) --From tag b79a13bea6550945o1 --To-tag [May 15 01:04:27] DEBUG[27636] chan_sip.c: **** Received NOTIFY (4) - Command in SIP NOTIFY [May 15 01:04:27] DEBUG[27636] chan_sip.c: Trying to put 'SIP/2.0 200' onto UDP socket destined for 82.204.246.142:49471 [May 15 01:04:27] DEBUG[27636] chan_sip.c: Allocating new SIP dialog for 06b94917529b80960ae467b810dcf2bc@89.188.109.170:5060 - OPTIONS (No RTP) [May 15 01:04:27] DEBUG[27636] acl.c: For destination '195.128.55.18', our source address is '89.188.109.170'. [May 15 01:04:27] DEBUG[27636] chan_sip.c: Target address 195.128.55.18:5060 is not local, substituting externaddr [May 15 01:04:27] DEBUG[27636] chan_sip.c: Setting SIP_TRANSPORT_UDP with address 89.188.109.170:5060 [May 15 01:04:27] DEBUG[27636] chan_sip.c: SIP call-id changed from '06b94917529b80960ae467b810dcf2bc@89.188.109.170:5060' to '551a412f5406be2504de417c7dbaa3a5@89.188.109.170:5060' [May 15 01:04:27] DEBUG[27636] chan_sip.c: Initializing initreq for method OPTIONS - callid 551a412f5406be2504de417c7dbaa3a5@89.188.109.170:5060 [May 15 01:04:27] DEBUG[27636] chan_sip.c: Trying to put 'OPTIONS sip' onto UDP socket destined for 195.128.55.18:5060 [May 15 01:04:27] DEBUG[27636] chan_sip.c: Allocating new SIP dialog for 1c01d9182bd89ed0173b93ce0a7d63b2@89.188.109.170:5060 - OPTIONS (No RTP) [May 15 01:04:27] DEBUG[27636] acl.c: For destination '195.128.55.18', our source address is '89.188.109.170'. [May 15 01:04:27] DEBUG[27636] chan_sip.c: Target address 195.128.55.18:1064 is not local, substituting externaddr [May 15 01:04:27] DEBUG[27636] chan_sip.c: Setting SIP_TRANSPORT_UDP with address 89.188.109.170:5060 [May 15 01:04:27] DEBUG[27636] chan_sip.c: SIP call-id changed from '1c01d9182bd89ed0173b93ce0a7d63b2@89.188.109.170:5060' to '1bf8e4200b78f728575e30b37bda1e14@89.188.109.170:5060' [May 15 01:04:27] DEBUG[27636] chan_sip.c: Initializing initreq for method OPTIONS - callid 1bf8e4200b78f728575e30b37bda1e14@89.188.109.170:5060 [May 15 01:04:27] DEBUG[27636] chan_sip.c: Trying to put 'OPTIONS sip' onto UDP socket destined for 195.128.55.18:1064 [May 15 01:04:27] DEBUG[27636] chan_sip.c: = Looking for Call ID: 551a412f5406be2504de417c7dbaa3a5@89.188.109.170:5060 (Checking To) --From tag as6dd61e9f --To-tag d80d55cec0642bdci0 [May 15 01:04:27] DEBUG[27636] chan_sip.c: Stopping retransmission on '551a412f5406be2504de417c7dbaa3a5@89.188.109.170:5060' of Request 102: Match Found [May 15 01:04:27] DEBUG[27636] chan_sip.c: Destroying SIP dialog 551a412f5406be2504de417c7dbaa3a5@89.188.109.170:5060 [May 15 01:04:27] DEBUG[27636] chan_sip.c: = Looking for Call ID: 1bf8e4200b78f728575e30b37bda1e14@89.188.109.170:5060 (Checking To) --From tag as755f280b --To-tag 1970f903baa35af6i0 [May 15 01:04:27] DEBUG[27636] chan_sip.c: Stopping retransmission on '1bf8e4200b78f728575e30b37bda1e14@89.188.109.170:5060' of Request 102: Match Found [May 15 01:04:27] DEBUG[27636] chan_sip.c: Destroying SIP dialog 1bf8e4200b78f728575e30b37bda1e14@89.188.109.170:5060 [May 15 01:04:27] DEBUG[27636] chan_sip.c: Allocating new SIP dialog for 0aed6a8d488a20e83045438441cd3cfc@89.188.109.170:5060 - OPTIONS (No RTP) [May 15 01:04:27] DEBUG[27636] acl.c: For destination '195.68.128.222', our source address is '89.188.109.170'. [May 15 01:04:27] DEBUG[27636] chan_sip.c: Target address 195.68.128.222:1025 is not local, substituting externaddr [May 15 01:04:27] DEBUG[27636] chan_sip.c: Setting SIP_TRANSPORT_UDP with address 89.188.109.170:5060 [May 15 01:04:27] DEBUG[27636] chan_sip.c: SIP call-id changed from '0aed6a8d488a20e83045438441cd3cfc@89.188.109.170:5060' to '46ec3bed0f5f26ce609bf90e6c49d558@89.188.109.170:5060' [May 15 01:04:27] DEBUG[27636] chan_sip.c: Initializing initreq for method OPTIONS - callid 46ec3bed0f5f26ce609bf90e6c49d558@89.188.109.170:5060 [May 15 01:04:27] DEBUG[27636] chan_sip.c: Trying to put 'OPTIONS sip' onto UDP socket destined for 195.68.128.222:1025 [May 15 01:04:27] DEBUG[27636] chan_sip.c: = Looking for Call ID: b4d74f34-3f591305@192.168.42.26 (Checking From) --From tag 5be8cd10cc244ed9o2 --To-tag [May 15 01:04:27] DEBUG[27636] chan_sip.c: **** Received NOTIFY (4) - Command in SIP NOTIFY [May 15 01:04:27] DEBUG[27636] chan_sip.c: Trying to put 'SIP/2.0 200' onto UDP socket destined for 95.140.28.114:50225 [May 15 01:04:27] DEBUG[27636] chan_sip.c: = Looking for Call ID: 46ec3bed0f5f26ce609bf90e6c49d558@89.188.109.170:5060 (Checking To) --From tag as6ccb0b4d --To-tag d429e93923f137dci0 [May 15 01:04:27] DEBUG[27636] chan_sip.c: Stopping retransmission on '46ec3bed0f5f26ce609bf90e6c49d558@89.188.109.170:5060' of Request 102: Match Found [May 15 01:04:27] DEBUG[27636] chan_sip.c: Destroying SIP dialog 46ec3bed0f5f26ce609bf90e6c49d558@89.188.109.170:5060 [May 15 01:04:27] DEBUG[27636] chan_sip.c: Allocating new SIP dialog for 5fa4dcf93c631c5b577ac70e4860273f@89.188.109.170:5060 - OPTIONS (No RTP) [May 15 01:04:27] DEBUG[27636] acl.c: For destination '195.128.55.18', our source address is '89.188.109.170'. [May 15 01:04:27] DEBUG[27636] chan_sip.c: Target address 195.128.55.18:1042 is not local, substituting externaddr [May 15 01:04:27] DEBUG[27636] chan_sip.c: Setting SIP_TRANSPORT_UDP with address 89.188.109.170:5060 [May 15 01:04:27] DEBUG[27636] chan_sip.c: SIP call-id changed from '5fa4dcf93c631c5b577ac70e4860273f@89.188.109.170:5060' to '57378462068c121e390a1a0d61cb7a7d@89.188.109.170:5060' [May 15 01:04:27] DEBUG[27636] chan_sip.c: Initializing initreq for method OPTIONS - callid 57378462068c121e390a1a0d61cb7a7d@89.188.109.170:5060 [May 15 01:04:27] DEBUG[27636] chan_sip.c: Trying to put 'OPTIONS sip' onto UDP socket destined for 195.128.55.18:1042 [May 15 01:04:27] DEBUG[27636] chan_sip.c: = Looking for Call ID: 57378462068c121e390a1a0d61cb7a7d@89.188.109.170:5060 (Checking To) --From tag as774a5b1b --To-tag 1c8c4c2c53037a04i0 [May 15 01:04:27] DEBUG[27636] chan_sip.c: Stopping retransmission on '57378462068c121e390a1a0d61cb7a7d@89.188.109.170:5060' of Request 102: Match Found [May 15 01:04:27] DEBUG[27636] chan_sip.c: Destroying SIP dialog 57378462068c121e390a1a0d61cb7a7d@89.188.109.170:5060 [May 15 01:04:27] DEBUG[27636] chan_sip.c: = Looking for Call ID: 191463d2-c06d875@192.168.42.100 (Checking From) --From tag 1fe03d8a52da0a1do1 --To-tag [May 15 01:04:27] DEBUG[27636] chan_sip.c: **** Received NOTIFY (4) - Command in SIP NOTIFY [May 15 01:04:27] DEBUG[27636] chan_sip.c: Trying to put 'SIP/2.0 200' onto UDP socket destined for 80.249.177.38:5061 [May 15 01:04:27] DEBUG[27636] chan_sip.c: Allocating new SIP dialog for 4cf14a3b7f787658126bdd4a41415b06@89.188.109.170:5060 - OPTIONS (No RTP) [May 15 01:04:27] DEBUG[27636] acl.c: For destination '195.128.55.18', our source address is '89.188.109.170'. [May 15 01:04:27] DEBUG[27636] chan_sip.c: Target address 195.128.55.18:1048 is not local, substituting externaddr [May 15 01:04:27] DEBUG[27636] chan_sip.c: Setting SIP_TRANSPORT_UDP with address 89.188.109.170:5060 [May 15 01:04:27] DEBUG[27636] chan_sip.c: SIP call-id changed from '4cf14a3b7f787658126bdd4a41415b06@89.188.109.170:5060' to '5d9dc8374c611bc171931ef754c8c91e@89.188.109.170:5060' [May 15 01:04:27] DEBUG[27636] chan_sip.c: Initializing initreq for method OPTIONS - callid 5d9dc8374c611bc171931ef754c8c91e@89.188.109.170:5060 [May 15 01:04:27] DEBUG[27636] chan_sip.c: Trying to put 'OPTIONS sip' onto UDP socket destined for 195.128.55.18:1048 [May 15 01:04:27] DEBUG[27636] chan_sip.c: Allocating new SIP dialog for 30ce0b2f1ff016ac75bda8fe14aae13c@89.188.109.170:5060 - OPTIONS (No RTP) [May 15 01:04:27] DEBUG[27636] acl.c: For destination '195.128.55.18', our source address is '89.188.109.170'. [May 15 01:04:27] DEBUG[27636] chan_sip.c: Target address 195.128.55.18:1034 is not local, substituting externaddr [May 15 01:04:27] DEBUG[27636] chan_sip.c: Setting SIP_TRANSPORT_UDP with address 89.188.109.170:5060 [May 15 01:04:27] DEBUG[27636] chan_sip.c: SIP call-id changed from '30ce0b2f1ff016ac75bda8fe14aae13c@89.188.109.170:5060' to '716db611372a6a356335084b7a3adf93@89.188.109.170:5060' [May 15 01:04:27] DEBUG[27636] chan_sip.c: Initializing initreq for method OPTIONS - callid 716db611372a6a356335084b7a3adf93@89.188.109.170:5060 [May 15 01:04:27] DEBUG[27636] chan_sip.c: Trying to put 'OPTIONS sip' onto UDP socket destined for 195.128.55.18:1034 [May 15 01:04:27] DEBUG[27636] chan_sip.c: = Looking for Call ID: 5d9dc8374c611bc171931ef754c8c91e@89.188.109.170:5060 (Checking To) --From tag as549f2600 --To-tag b2bfa8e9dab6fc3ci0 [May 15 01:04:27] DEBUG[27636] chan_sip.c: Stopping retransmission on '5d9dc8374c611bc171931ef754c8c91e@89.188.109.170:5060' of Request 102: Match Found [May 15 01:04:27] DEBUG[27636] chan_sip.c: Destroying SIP dialog 5d9dc8374c611bc171931ef754c8c91e@89.188.109.170:5060 [May 15 01:04:27] DEBUG[27636] chan_sip.c: = Looking for Call ID: 716db611372a6a356335084b7a3adf93@89.188.109.170:5060 (Checking To) --From tag as47ac1690 --To-tag bcc4747856da8e5di0 [May 15 01:04:27] DEBUG[27636] chan_sip.c: Stopping retransmission on '716db611372a6a356335084b7a3adf93@89.188.109.170:5060' of Request 102: Match Found [May 15 01:04:27] DEBUG[27636] chan_sip.c: Destroying SIP dialog 716db611372a6a356335084b7a3adf93@89.188.109.170:5060 [May 15 01:04:27] DEBUG[27636] chan_sip.c: = Looking for Call ID: 7b516930-f9e760b4@192.168.43.70 (Checking From) --From tag f0746f0d4951df4o3 --To-tag [May 15 01:04:27] DEBUG[27636] chan_sip.c: **** Received NOTIFY (4) - Command in SIP NOTIFY [May 15 01:04:27] DEBUG[27636] chan_sip.c: Trying to put 'SIP/2.0 200' onto UDP socket destined for 195.128.55.18:1071 [May 15 01:04:27] DEBUG[27636] chan_sip.c: = Looking for Call ID: bcc7a06d-f471ca9b@192.168.43.71 (Checking From) --From tag 7adafa8d5e5936fbo3 --To-tag [May 15 01:04:27] DEBUG[27636] chan_sip.c: **** Received NOTIFY (4) - Command in SIP NOTIFY [May 15 01:04:27] DEBUG[27636] chan_sip.c: Trying to put 'SIP/2.0 200' onto UDP socket destined for 195.128.55.18:1089 [May 15 01:04:27] DEBUG[27636] chan_sip.c: = Looking for Call ID: e681945f-77e1742@192.168.43.51 (Checking From) --From tag 19280d5b11339276o0 --To-tag [May 15 01:04:27] DEBUG[27636] chan_sip.c: **** Received NOTIFY (4) - Command in SIP NOTIFY [May 15 01:04:27] DEBUG[27636] chan_sip.c: Trying to put 'SIP/2.0 200' onto UDP socket destined for 195.128.55.18:1038 [May 15 01:04:28] DEBUG[27636] chan_sip.c: Allocating new SIP dialog for 0592a5246f0cdf033ffa89551896cd7d@89.188.109.170:5060 - OPTIONS (No RTP) [May 15 01:04:28] DEBUG[27636] acl.c: For destination '195.128.55.18', our source address is '89.188.109.170'. [May 15 01:04:28] DEBUG[27636] chan_sip.c: Target address 195.128.55.18:1066 is not local, substituting externaddr [May 15 01:04:28] DEBUG[27636] chan_sip.c: Setting SIP_TRANSPORT_UDP with address 89.188.109.170:5060 [May 15 01:04:28] DEBUG[27636] chan_sip.c: SIP call-id changed from '0592a5246f0cdf033ffa89551896cd7d@89.188.109.170:5060' to '5983fa0772fa0df410ccb7e77b87929e@89.188.109.170:5060' [May 15 01:04:28] DEBUG[27636] chan_sip.c: Initializing initreq for method OPTIONS - callid 5983fa0772fa0df410ccb7e77b87929e@89.188.109.170:5060 [May 15 01:04:28] DEBUG[27636] chan_sip.c: Trying to put 'OPTIONS sip' onto UDP socket destined for 195.128.55.18:1066 [May 15 01:04:28] DEBUG[27636] chan_sip.c: = Looking for Call ID: 5983fa0772fa0df410ccb7e77b87929e@89.188.109.170:5060 (Checking To) --From tag as76183b61 --To-tag d2ab3496ea3e9c89i0 [May 15 01:04:28] DEBUG[27636] chan_sip.c: Stopping retransmission on '5983fa0772fa0df410ccb7e77b87929e@89.188.109.170:5060' of Request 102: Match Found [May 15 01:04:28] DEBUG[27636] chan_sip.c: Destroying SIP dialog 5983fa0772fa0df410ccb7e77b87929e@89.188.109.170:5060 [May 15 01:04:28] DEBUG[27636] chan_sip.c: = Looking for Call ID: 4272718e-2afbc31c@192.168.43.9 (Checking From) --From tag a86d9d265a4cc64o0 --To-tag [May 15 01:04:28] DEBUG[27636] chan_sip.c: **** Received NOTIFY (4) - Command in SIP NOTIFY [May 15 01:04:28] DEBUG[27636] chan_sip.c: Trying to put 'SIP/2.0 200' onto UDP socket destined for 195.128.55.18:5060 [May 15 01:04:28] DEBUG[27636] chan_sip.c: = Looking for Call ID: 53a0cb75-134f5f2a@192.168.42.101 (Checking From) --From tag 2fa630592b3c9f96o0 --To-tag [May 15 01:04:28] DEBUG[27636] chan_sip.c: **** Received NOTIFY (4) - Command in SIP NOTIFY [May 15 01:04:28] DEBUG[27636] chan_sip.c: Trying to put 'SIP/2.0 200' onto UDP socket destined for 95.140.28.114:50226 [May 15 01:04:28] DEBUG[27636] chan_sip.c: = Looking for Call ID: 3fc514b93df5b4617fe8dcc860712ae1@10.1.1.2:5060 (Checking From) --From tag as644d7c2d --To-tag [May 15 01:04:28] DEBUG[27636] acl.c: For destination '10.1.1.2', our source address is '10.77.9.146'. [May 15 01:04:28] DEBUG[27636] chan_sip.c: Setting SIP_TRANSPORT_UDP with address 10.77.9.146:5060 [May 15 01:04:28] DEBUG[27636] netsock2.c: Splitting '10.1.1.2:5060' into... [May 15 01:04:28] DEBUG[27636] netsock2.c: ...host '10.1.1.2' and port '5060'. [May 15 01:04:28] DEBUG[27636] chan_sip.c: Allocating new SIP dialog for 3fc514b93df5b4617fe8dcc860712ae1@10.1.1.2:5060 - OPTIONS (No RTP) [May 15 01:04:28] DEBUG[27636] chan_sip.c: **** Received OPTIONS (3) - Command in SIP OPTIONS [May 15 01:04:28] DEBUG[27636] netsock2.c: Splitting '10.77.9.146:5060' into... [May 15 01:04:28] DEBUG[27636] netsock2.c: ...host '10.77.9.146' and port ''. [May 15 01:04:28] DEBUG[27636] netsock2.c: Splitting '10.1.1.2' into... [May 15 01:04:28] DEBUG[27636] netsock2.c: ...host '10.1.1.2' and port ''. [May 15 01:04:28] DEBUG[27636] chan_sip.c: Trying to put 'SIP/2.0 404' onto UDP socket destined for 10.1.1.2:5060 [May 15 01:04:28] DEBUG[27636] chan_sip.c: = Looking for Call ID: 6904750a-af77332e@192.168.42.101 (Checking From) --From tag 2fa630592b3c9f96o0 --To-tag [May 15 01:04:28] DEBUG[27636] netsock2.c: Splitting '89.188.109.170' into... [May 15 01:04:28] DEBUG[27636] netsock2.c: ...host '89.188.109.170' and port ''. [May 15 01:04:28] DEBUG[27636] netsock2.c: Splitting '89.188.109.170' into... [May 15 01:04:28] DEBUG[27636] netsock2.c: ...host '89.188.109.170' and port ''. [May 15 01:04:28] DEBUG[27636] chan_sip.c: **** Received REGISTER (2) - Command in SIP REGISTER [May 15 01:04:28] DEBUG[27636] netsock2.c: Splitting '192.168.42.101:5060' into... [May 15 01:04:28] DEBUG[27636] netsock2.c: ...host '192.168.42.101' and port '5060'. [May 15 01:04:28] DEBUG[27636] chan_sip.c: NAT detected for 192.168.42.101 / 95.140.28.114 [May 15 01:04:28] DEBUG[27636] netsock2.c: Splitting '89.188.109.170' into... [May 15 01:04:28] DEBUG[27636] netsock2.c: ...host '89.188.109.170' and port ''. [May 15 01:04:28] DEBUG[27636] chan_sip.c: Trying to put 'SIP/2.0 401' onto UDP socket destined for 95.140.28.114:50226 [May 15 01:04:28] DEBUG[27636] chan_sip.c: = Looking for Call ID: 9b66da1c-4580c4b6@192.168.43.1 (Checking From) --From tag 759797a45367e4ceo0 --To-tag [May 15 01:04:28] DEBUG[27636] chan_sip.c: **** Received NOTIFY (4) - Command in SIP NOTIFY [May 15 01:04:28] DEBUG[27636] chan_sip.c: Trying to put 'SIP/2.0 200' onto UDP socket destined for 195.128.55.18:1062 [May 15 01:04:28] DEBUG[27636] chan_sip.c: = Looking for Call ID: 6904750a-af77332e@192.168.42.101 (Checking From) --From tag 2fa630592b3c9f96o0 --To-tag [May 15 01:04:28] DEBUG[27636] netsock2.c: Splitting '89.188.109.170' into... [May 15 01:04:28] DEBUG[27636] netsock2.c: ...host '89.188.109.170' and port ''. [May 15 01:04:28] DEBUG[27636] netsock2.c: Splitting '89.188.109.170' into... [May 15 01:04:28] DEBUG[27636] netsock2.c: ...host '89.188.109.170' and port ''. [May 15 01:04:28] DEBUG[27636] chan_sip.c: **** Received REGISTER (2) - Command in SIP REGISTER [May 15 01:04:28] DEBUG[27636] netsock2.c: Splitting '192.168.42.101:5060' into... [May 15 01:04:28] DEBUG[27636] netsock2.c: ...host '192.168.42.101' and port '5060'. [May 15 01:04:28] DEBUG[27636] chan_sip.c: NAT detected for 192.168.42.101 / 95.140.28.114 [May 15 01:04:28] DEBUG[27636] netsock2.c: Splitting '89.188.109.170' into... [May 15 01:04:28] DEBUG[27636] netsock2.c: ...host '89.188.109.170' and port ''. [May 15 01:04:28] DEBUG[27636] chan_sip.c: Store REGISTER's src-IP:port for call routing. [May 15 01:04:28] VERBOSE[27636] chan_sip.c: [May 15 01:04:28] -- Registered SIP '6218' at 95.140.28.114:50226 [May 15 01:04:28] DEBUG[27636] chan_sip.c: Allocating new SIP dialog for 2cce843d402fbcbe2eaf8039403da19a@89.188.109.170:5060 - OPTIONS (No RTP) [May 15 01:04:28] DEBUG[27636] acl.c: For destination '95.140.28.114', our source address is '89.188.109.170'. [May 15 01:04:28] DEBUG[27636] chan_sip.c: Target address 95.140.28.114:50226 is not local, substituting externaddr [May 15 01:04:28] DEBUG[27636] chan_sip.c: Setting SIP_TRANSPORT_UDP with address 89.188.109.170:5060 [May 15 01:04:28] DEBUG[27636] chan_sip.c: SIP call-id changed from '2cce843d402fbcbe2eaf8039403da19a@89.188.109.170:5060' to '7aa0391a629629941d3e0263084cd221@89.188.109.170:5060' [May 15 01:04:28] DEBUG[27636] chan_sip.c: Initializing initreq for method OPTIONS - callid 7aa0391a629629941d3e0263084cd221@89.188.109.170:5060 [May 15 01:04:28] DEBUG[27636] chan_sip.c: Trying to put 'OPTIONS sip' onto UDP socket destined for 95.140.28.114:50226 [May 15 01:04:28] DEBUG[27636] chan_sip.c: Trying to put 'SIP/2.0 200' onto UDP socket destined for 95.140.28.114:50226 [May 15 01:04:28] DEBUG[27629] devicestate.c: No provider found, checking channel drivers for SIP - 6218 [May 15 01:04:28] DEBUG[27629] chan_sip.c: Checking device state for peer 6218 [May 15 01:04:28] DEBUG[27629] devicestate.c: Changing state for SIP/6218 - state 1 (Not in use) [May 15 01:04:28] DEBUG[27629] devicestate.c: device 'SIP/6218' state '1' [May 15 01:04:28] DEBUG[27642] devicestate.c: device 'Queue:q-cgroup-24_avail' state '1' [May 15 01:04:28] DEBUG[27642] app_queue.c: Device 'SIP/6218' changed to state '1' (Not in use) [May 15 01:04:28] DEBUG[27642] app_queue.c: Device 'Queue:q-cgroup-24_avail' changed to state '1' (Not in use) but we don't care because they're not a member of any queue. [May 15 01:04:28] DEBUG[27636] chan_sip.c: = Looking for Call ID: 7aa0391a629629941d3e0263084cd221@89.188.109.170:5060 (Checking To) --From tag as56becdb0 --To-tag 1fd5d5d540e6d84ai0 [May 15 01:04:28] DEBUG[27636] chan_sip.c: Stopping retransmission on '7aa0391a629629941d3e0263084cd221@89.188.109.170:5060' of Request 102: Match Found [May 15 01:04:28] DEBUG[27636] chan_sip.c: Destroying SIP dialog 7aa0391a629629941d3e0263084cd221@89.188.109.170:5060 [May 15 01:04:28] DEBUG[27636] chan_sip.c: = Looking for Call ID: 9895d08e-4097f10b@192.168.43.54 (Checking From) --From tag 26243124f608057o0 --To-tag [May 15 01:04:28] DEBUG[27636] chan_sip.c: **** Received NOTIFY (4) - Command in SIP NOTIFY [May 15 01:04:28] DEBUG[27636] chan_sip.c: Trying to put 'SIP/2.0 200' onto UDP socket destined for 195.128.55.18:1039 [May 15 01:04:28] DEBUG[27636] chan_sip.c: = Looking for Call ID: 71e4b192-66dce391@192.168.43.22 (Checking From) --From tag 805371be4ebb4155o0 --To-tag [May 15 01:04:28] DEBUG[27636] chan_sip.c: **** Received NOTIFY (4) - Command in SIP NOTIFY [May 15 01:04:28] DEBUG[27636] chan_sip.c: Trying to put 'SIP/2.0 200' onto UDP socket destined for 195.128.55.18:1079 [May 15 01:04:28] DEBUG[27636] chan_sip.c: = Looking for Call ID: 8b81719e-97f244e5@192.168.42.101 (Checking From) --From tag 509543aedb5a1a15o3 --To-tag [May 15 01:04:28] DEBUG[27636] chan_sip.c: **** Received NOTIFY (4) - Command in SIP NOTIFY [May 15 01:04:28] DEBUG[27636] chan_sip.c: Trying to put 'SIP/2.0 200' onto UDP socket destined for 82.204.246.142:49472 [May 15 01:04:28] DEBUG[27636] chan_sip.c: = Looking for Call ID: 55a29351-1bd6d4e4@192.168.42.101 (Checking From) --From tag 9657c60d8056e158o0 --To-tag [May 15 01:04:28] DEBUG[27636] chan_sip.c: **** Received NOTIFY (4) - Command in SIP NOTIFY [May 15 01:04:28] DEBUG[27636] chan_sip.c: Trying to put 'SIP/2.0 200' onto UDP socket destined for 77.108.85.72:5060 [May 15 01:04:28] DEBUG[27636] chan_sip.c: = Looking for Call ID: 48539df0-5c5cb2e6@192.168.43.10 (Checking From) --From tag 575573a89a12188eo0 --To-tag [May 15 01:04:28] DEBUG[27636] chan_sip.c: **** Received NOTIFY (4) - Command in SIP NOTIFY [May 15 01:04:28] DEBUG[27636] chan_sip.c: Trying to put 'SIP/2.0 200' onto UDP socket destined for 195.128.55.18:1063 [May 15 01:04:28] DEBUG[27636] chan_sip.c: = Looking for Call ID: 2a13968-3be30e61@192.168.42.26 (Checking From) --From tag ad40435873060d31o3 --To-tag [May 15 01:04:28] DEBUG[27636] chan_sip.c: **** Received NOTIFY (4) - Command in SIP NOTIFY [May 15 01:04:28] DEBUG[27636] chan_sip.c: Trying to put 'SIP/2.0 200' onto UDP socket destined for 95.140.28.114:5063 [May 15 01:04:28] DEBUG[27636] chan_sip.c: = Looking for Call ID: 7ea99a037f0a84362b05534e2107a1f4@10.77.9.246 (Checking From) --From tag as1b30ba03 --To-tag [May 15 01:04:28] DEBUG[27636] acl.c: For destination '10.77.9.246', our source address is '10.77.9.146'. [May 15 01:04:28] DEBUG[27636] chan_sip.c: Setting SIP_TRANSPORT_UDP with address 10.77.9.146:5060 [May 15 01:04:28] DEBUG[27636] netsock2.c: Splitting '10.77.9.246:5060' into... [May 15 01:04:28] DEBUG[27636] netsock2.c: ...host '10.77.9.246' and port '5060'. [May 15 01:04:28] DEBUG[27636] chan_sip.c: Allocating new SIP dialog for 7ea99a037f0a84362b05534e2107a1f4@10.77.9.246 - OPTIONS (No RTP) [May 15 01:04:28] DEBUG[27636] chan_sip.c: **** Received OPTIONS (3) - Command in SIP OPTIONS [May 15 01:04:28] DEBUG[27636] netsock2.c: Splitting '10.77.9.146' into... [May 15 01:04:28] DEBUG[27636] netsock2.c: ...host '10.77.9.146' and port ''. [May 15 01:04:28] DEBUG[27636] netsock2.c: Splitting '10.77.9.246' into... [May 15 01:04:28] DEBUG[27636] netsock2.c: ...host '10.77.9.246' and port ''. [May 15 01:04:28] DEBUG[27636] chan_sip.c: Trying to put 'SIP/2.0 404' onto UDP socket destined for 10.77.9.246:5060 [May 15 01:04:28] DEBUG[27636] chan_sip.c: = Looking for Call ID: 167d50c5-19b41600@192.168.42.101 (Checking From) --From tag d398c195953adb70o3 --To-tag [May 15 01:04:28] DEBUG[27636] chan_sip.c: **** Received NOTIFY (4) - Command in SIP NOTIFY [May 15 01:04:28] DEBUG[27636] chan_sip.c: Trying to put 'SIP/2.0 200' onto UDP socket destined for 77.108.85.72:5063 [May 15 01:04:28] DEBUG[27636] chan_sip.c: = Looking for Call ID: 4b64fa21523e50442f9eb0d642a9c3b1@10.77.9.246 (Checking From) --From tag as6845cb4d --To-tag [May 15 01:04:28] DEBUG[27636][C-0000153d] chan_sip.c: **** Received CANCEL (14) - Command in SIP CANCEL [May 15 01:04:28] DEBUG[27636][C-0000153d] netsock2.c: Splitting '10.77.9.246:5060' into... [May 15 01:04:28] DEBUG[27636][C-0000153d] netsock2.c: ...host '10.77.9.246' and port '5060'. [May 15 01:04:28] DEBUG[27636][C-0000153d] chan_sip.c: Setting SIP_ALREADYGONE on dialog 4b64fa21523e50442f9eb0d642a9c3b1@10.77.9.246 [May 15 01:04:28] DEBUG[27636][C-0000153d] chan_sip.c: Updating call counter for incoming call [May 15 01:04:28] DEBUG[27636][C-0000153d] res_rtp_asterisk.c: Setting RTCP address on RTP instance '0x7f693c981548' [May 15 01:04:28] DEBUG[27636][C-0000153d] chan_sip.c: Trying to put 'SIP/2.0 487' onto UDP socket destined for 10.77.9.246:5060 [May 15 01:04:28] DEBUG[27636][C-0000153d] chan_sip.c: Trying to put 'SIP/2.0 200' onto UDP socket destined for 10.77.9.246:5060 [May 15 01:04:28] DEBUG[27629] devicestate.c: No provider found, checking channel drivers for SIP - bluesky_pbx [May 15 01:04:28] DEBUG[27629] chan_sip.c: Checking device state for peer bluesky_pbx [May 15 01:04:28] DEBUG[27629] devicestate.c: Changing state for SIP/bluesky_pbx - state 1 (Not in use) [May 15 01:04:28] DEBUG[27629] devicestate.c: device 'SIP/bluesky_pbx' state '1' [May 15 01:04:28] DEBUG[27642] app_queue.c: Device 'SIP/bluesky_pbx' changed to state '1' (Not in use) but we don't care because they're not a member of any queue. [May 15 01:04:28] DEBUG[7181][C-0000153d] channel.c: Hanging up channel 'SIP/6203-00001fe6' [May 15 01:04:28] DEBUG[7181][C-0000153d] chan_sip.c: Hangup call SIP/6203-00001fe6, SIP callid 73d9d3443c136abc6c72a260496e8bf9@89.188.109.170:5060 [May 15 01:04:28] DEBUG[7181][C-0000153d] chan_sip.c: Updating call counter for outgoing call [May 15 01:04:28] DEBUG[7181][C-0000153d] chan_sip.c: Hanging up channel in state Ringing (not UP) [May 15 01:04:28] DEBUG[7181][C-0000153d] res_rtp_asterisk.c: Setting RTCP address on RTP instance '0x7f68c4011468' [May 15 01:04:28] DEBUG[7181][C-0000153d] chan_sip.c: (Provisional) Stopping retransmission (but retaining packet) on '73d9d3443c136abc6c72a260496e8bf9@89.188.109.170:5060' Request 102: Found [May 15 01:04:28] DEBUG[7181][C-0000153d] chan_sip.c: Trying to put 'CANCEL sip:' onto UDP socket destined for 195.128.55.18:1095 [May 15 01:04:28] DEBUG[7181][C-0000153d] app_dial.c: Exiting with DIALSTATUS=CANCEL. [May 15 01:04:28] DEBUG[27629] devicestate.c: No provider found, checking channel drivers for SIP - 6203 [May 15 01:04:28] DEBUG[7181][C-0000153d] pbx.c: Spawn extension (catch-incoming,2321201,1) exited non-zero on 'SIP/bluesky_pbx-00001fe5' [May 15 01:04:28] DEBUG[27629] chan_sip.c: Checking device state for peer 6203 [May 15 01:04:28] VERBOSE[7181][C-0000153d] pbx.c: [May 15 01:04:28] == Spawn extension (catch-incoming, 2321201, 1) exited non-zero on 'SIP/bluesky_pbx-00001fe5' [May 15 01:04:28] DEBUG[27629] devicestate.c: Changing state for SIP/6203 - state 1 (Not in use) [May 15 01:04:28] DEBUG[27629] devicestate.c: device 'SIP/6203' state '1' [May 15 01:04:28] DEBUG[7181][C-0000153d] channel.c: Soft-Hanging up channel 'SIP/bluesky_pbx-00001fe5' [May 15 01:04:28] DEBUG[27629] devicestate.c: No provider found, checking channel drivers for SIP - 6203 [May 15 01:04:28] DEBUG[7181][C-0000153d] channel.c: Hanging up channel 'SIP/bluesky_pbx-00001fe5' [May 15 01:04:28] DEBUG[7181][C-0000153d] chan_sip.c: Hangup call SIP/bluesky_pbx-00001fe5, SIP callid 4b64fa21523e50442f9eb0d642a9c3b1@10.77.9.246 [May 15 01:04:28] DEBUG[27629] chan_sip.c: Checking device state for peer 6203 [May 15 01:04:28] DEBUG[7181][C-0000153d] res_rtp_asterisk.c: Setting RTCP address on RTP instance '0x7f693c981548' [May 15 01:04:28] DEBUG[27629] devicestate.c: Changing state for SIP/6203 - state 1 (Not in use) [May 15 01:04:28] DEBUG[27629] devicestate.c: device 'SIP/6203' state '1' [May 15 01:04:28] DEBUG[27642] devicestate.c: device 'Queue:q-cgroup-19_avail' state '1' [May 15 01:04:28] DEBUG[27642] app_queue.c: Device 'SIP/6203' changed to state '1' (Not in use) [May 15 01:04:28] WARNING[7181][C-0000153d] cdr_adaptive_odbc.c: CDR variable callanswer is not a valid timestamp ('2321201'). [May 15 01:04:28] DEBUG[27642] devicestate.c: device 'Queue:q-cgroup-19_avail' state '1' [May 15 01:04:28] DEBUG[27642] app_queue.c: Device 'SIP/6203' changed to state '1' (Not in use) [May 15 01:04:28] DEBUG[27642] app_queue.c: Device 'Queue:q-cgroup-19_avail' changed to state '1' (Not in use) but we don't care because they're not a member of any queue. [May 15 01:04:28] DEBUG[27642] app_queue.c: Device 'Queue:q-cgroup-19_avail' changed to state '1' (Not in use) but we don't care because they're not a member of any queue. [May 15 01:04:28] DEBUG[7181][C-0000153d] res_odbc.c: odbc_release_obj2(0x14cd2a8) called (obj->txf = (nil)) [May 15 01:04:28] DEBUG[27629] devicestate.c: No provider found, checking channel drivers for SIP - bluesky_pbx [May 15 01:04:28] DEBUG[27629] chan_sip.c: Checking device state for peer bluesky_pbx [May 15 01:04:28] DEBUG[27629] devicestate.c: Changing state for SIP/bluesky_pbx - state 1 (Not in use) [May 15 01:04:28] DEBUG[27629] devicestate.c: device 'SIP/bluesky_pbx' state '1' [May 15 01:04:28] DEBUG[27642] app_queue.c: Device 'SIP/bluesky_pbx' changed to state '1' (Not in use) but we don't care because they're not a member of any queue. [May 15 01:04:28] DEBUG[27636] chan_sip.c: = Looking for Call ID: 4b64fa21523e50442f9eb0d642a9c3b1@10.77.9.246 (Checking From) --From tag as6845cb4d --To-tag as3057d83b [May 15 01:04:28] DEBUG[27636][C-0000153d] chan_sip.c: **** Received ACK (6) - Command in SIP ACK [May 15 01:04:28] DEBUG[27636][C-0000153d] chan_sip.c: Stopping retransmission on '4b64fa21523e50442f9eb0d642a9c3b1@10.77.9.246' of Response 102: Match Found [May 15 01:04:28] DEBUG[27636] chan_sip.c: Destroying SIP dialog 4b64fa21523e50442f9eb0d642a9c3b1@10.77.9.246 [May 15 01:04:28] DEBUG[27636] rtp_engine.c: Destroyed RTP instance '0x7f693c981548' [May 15 01:04:28] DEBUG[27636] chan_sip.c: = Looking for Call ID: 73d9d3443c136abc6c72a260496e8bf9@89.188.109.170:5060 (Checking To) --From tag as77affb55 --To-tag fd18ef86984e302bi0 [May 15 01:04:28] DEBUG[27636][C-0000153d] chan_sip.c: Acked pending invite 102 [May 15 01:04:28] DEBUG[27636][C-0000153d] chan_sip.c: Stopping retransmission on '73d9d3443c136abc6c72a260496e8bf9@89.188.109.170:5060' of Request 102: Match Found [May 15 01:04:28] DEBUG[27636][C-0000153d] chan_sip.c: SIP response 487 to standard invite [May 15 01:04:28] DEBUG[27636][C-0000153d] chan_sip.c: Trying to put 'ACK sip:620' onto UDP socket destined for 195.128.55.18:1095 [May 15 01:04:28] DEBUG[27636][C-0000153d] chan_sip.c: Updating call counter for outgoing call [May 15 01:04:28] DEBUG[27629] devicestate.c: No provider found, checking channel drivers for SIP - 6203 [May 15 01:04:28] DEBUG[27629] chan_sip.c: Checking device state for peer 6203 [May 15 01:04:28] DEBUG[27629] devicestate.c: Changing state for SIP/6203 - state 1 (Not in use) [May 15 01:04:28] DEBUG[27629] devicestate.c: device 'SIP/6203' state '1' [May 15 01:04:28] DEBUG[27642] devicestate.c: device 'Queue:q-cgroup-19_avail' state '1' [May 15 01:04:28] DEBUG[27642] app_queue.c: Device 'SIP/6203' changed to state '1' (Not in use) [May 15 01:04:28] DEBUG[27642] app_queue.c: Device 'Queue:q-cgroup-19_avail' changed to state '1' (Not in use) but we don't care because they're not a member of any queue. [May 15 01:04:28] DEBUG[27636] chan_sip.c: = Looking for Call ID: 73d9d3443c136abc6c72a260496e8bf9@89.188.109.170:5060 (Checking To) --From tag as77affb55 --To-tag fd18ef86984e302bi0 [May 15 01:04:28] DEBUG[27636][C-0000153d] chan_sip.c: Stopping retransmission on '73d9d3443c136abc6c72a260496e8bf9@89.188.109.170:5060' of Request 102: Match Found [May 15 01:04:28] DEBUG[27636] chan_sip.c: = Looking for Call ID: 3f5e9f10-79d8a454@192.168.43.70 (Checking From) --From tag ce0c6cb0a6fc3334o1 --To-tag [May 15 01:04:28] DEBUG[27636] chan_sip.c: **** Received NOTIFY (4) - Command in SIP NOTIFY [May 15 01:04:28] DEBUG[27636] chan_sip.c: Trying to put 'SIP/2.0 200' onto UDP socket destined for 195.128.55.18:1069 [May 15 01:04:28] DEBUG[27636] chan_sip.c: = Looking for Call ID: 53e52622-be3c7861@192.168.43.28 (Checking From) --From tag 82be420693116dcdo2 --To-tag [May 15 01:04:28] DEBUG[27636] chan_sip.c: **** Received NOTIFY (4) - Command in SIP NOTIFY [May 15 01:04:28] DEBUG[27636] chan_sip.c: Trying to put 'SIP/2.0 200' onto UDP socket destined for 195.128.55.18:1058