[May 11 05:27:44] Asterisk 13.3.2 built by root @ aterisk-sjc.teridion.com on a x86_64 running Linux on 2015-04-28 08:27:24 UTC [May 11 05:27:44] DEBUG[26759] config.c: Parsing /etc/asterisk/logger.conf [May 11 05:27:45] DEBUG[26742] chan_sip.c: ** SIP timers: Rescheduling retransmission 9 to 4000 ms (t1 500 ms (Retrans id #10)) [May 11 05:27:45] DEBUG[26742] chan_sip.c: Trying to put 'SIP/2.0 404' onto UDP socket destined for 50.30.47.49:5070 [May 11 05:27:45] DEBUG[26742] chan_sip.c: ** SIP timers: Rescheduling retransmission 11 to 4000 ms (t1 500 ms (Retrans id #8)) [May 11 05:27:45] DEBUG[26742] chan_sip.c: Trying to put 'SIP/2.0 404' onto UDP socket destined for 184.107.201.78:5071 [May 11 05:27:46] DEBUG[26742] chan_sip.c: Re-scheduled destruction of SIP call fa6a79c77bb64428ed26d300b84528d1 [May 11 05:27:46] WARNING[26742] chan_sip.c: Retransmission timeout reached on transmission fa6a79c77bb64428ed26d300b84528d1 for seqno 1 (Critical Response) -- See https://wiki.asterisk.org/wiki/display/AST/SIP+Retransmissions Packet timed out after 32000ms with no response [May 11 05:27:46] DEBUG[26742] chan_sip.c: Setting SIP_ALREADYGONE on dialog fa6a79c77bb64428ed26d300b84528d1 [May 11 05:27:46] DEBUG[26742] chan_sip.c: Destroying SIP dialog fa6a79c77bb64428ed26d300b84528d1 [May 11 05:27:46] DEBUG[26742] rtp_engine.c: Destroyed RTP instance '0x7f79c40075d8' [May 11 05:27:49] DEBUG[26742] chan_sip.c: ** SIP timers: Rescheduling retransmission 10 to 4000 ms (t1 500 ms (Retrans id #10)) [May 11 05:27:49] DEBUG[26742] chan_sip.c: Trying to put 'SIP/2.0 404' onto UDP socket destined for 50.30.47.49:5070 [May 11 05:27:53] DEBUG[26742] chan_sip.c: ** SIP timers: Rescheduling retransmission 11 to 4000 ms (t1 500 ms (Retrans id #10)) [May 11 05:27:53] DEBUG[26742] chan_sip.c: Trying to put 'SIP/2.0 404' onto UDP socket destined for 50.30.47.49:5070 [May 11 05:27:53] WARNING[26742] chan_sip.c: Retransmission timeout reached on transmission 527362c53556b66a830de46638db00d6 for seqno 1 (Critical Response) -- See https://wiki.asterisk.org/wiki/display/AST/SIP+Retransmissions Packet timed out after 31999ms with no response [May 11 05:27:53] DEBUG[26742] chan_sip.c: Setting SIP_ALREADYGONE on dialog 527362c53556b66a830de46638db00d6 [May 11 05:27:53] DEBUG[26742] chan_sip.c: Auto destroying SIP dialog '527362c53556b66a830de46638db00d6' [May 11 05:27:53] DEBUG[26742] chan_sip.c: Destroying SIP dialog 527362c53556b66a830de46638db00d6 [May 11 05:27:53] DEBUG[26742] rtp_engine.c: Destroyed RTP instance '0x7f79c400eee8' [May 11 05:28:16] DEBUG[26898] chan_sip.c: Starting thread for TCP server [May 11 05:28:16] DEBUG[26898] chan_sip.c: = Looking for Call ID: PYf6ZjIgNwjW9dDDQLAYR2Lyr7WyqnXC (Checking From) --From tag SIqg8gZHTaaj20FMr.OssYF9VTZmx4Cv --To-tag [May 11 05:28:16] DEBUG[26898] acl.c: For destination '159.122.0.248', our source address is '23.246.211.133'. [May 11 05:28:16] DEBUG[26898] chan_sip.c: Setting AST_TRANSPORT_TCP with address 23.246.211.133:5060 [May 11 05:28:16] DEBUG[26898] netsock2.c: Splitting '159.122.0.248:5060' into... [May 11 05:28:16] DEBUG[26898] netsock2.c: ...host '159.122.0.248' and port '5060'. [May 11 05:28:16] DEBUG[26898] chan_sip.c: Allocating new SIP dialog for PYf6ZjIgNwjW9dDDQLAYR2Lyr7WyqnXC - REGISTER (No RTP) [May 11 05:28:16] DEBUG[26898] chan_sip.c: **** Received REGISTER (2) - Command in SIP REGISTER [May 11 05:28:16] DEBUG[26898] netsock2.c: Splitting '159.122.0.248:5060' into... [May 11 05:28:16] DEBUG[26898] netsock2.c: ...host '159.122.0.248' and port '5060'. [May 11 05:28:16] DEBUG[26898] netsock2.c: Splitting '23.246.211.133' into... [May 11 05:28:16] DEBUG[26898] netsock2.c: ...host '23.246.211.133' and port ''. [May 11 05:28:16] DEBUG[26898] chan_sip.c: Trying to put 'SIP/2.0 401' onto TCP socket destined for 159.122.0.248:39834 [May 11 05:28:16] DEBUG[26898] chan_sip.c: = Looking for Call ID: PYf6ZjIgNwjW9dDDQLAYR2Lyr7WyqnXC (Checking From) --From tag SIqg8gZHTaaj20FMr.OssYF9VTZmx4Cv --To-tag [May 11 05:28:16] DEBUG[26898] chan_sip.c: **** Received REGISTER (2) - Command in SIP REGISTER [May 11 05:28:16] DEBUG[26898] netsock2.c: Splitting '159.122.0.248:5060' into... [May 11 05:28:16] DEBUG[26898] netsock2.c: ...host '159.122.0.248' and port '5060'. [May 11 05:28:16] DEBUG[26898] netsock2.c: Splitting '23.246.211.133' into... [May 11 05:28:16] DEBUG[26898] netsock2.c: ...host '23.246.211.133' and port ''. [May 11 05:28:16] DEBUG[26898] chan_sip.c: peer '101' has contacted us over TCP even though we prefer UDP. [May 11 05:28:16] DEBUG[26898] chan_sip.c: Store REGISTER's Contact header for call routing. [May 11 05:28:16] DEBUG[26898] netsock2.c: Splitting '192.116.52.233:37342' into... [May 11 05:28:16] DEBUG[26898] netsock2.c: ...host '192.116.52.233' and port '37342'. [May 11 05:28:16] DEBUG[26898] chan_sip.c: build_path: try to build pre-loaded route-set by parsing Path headers [May 11 05:28:16] DEBUG[26700] threadpool.c: Increasing threadpool stasis-core's size by 1 [May 11 05:28:16] NOTICE[26898] chan_sip.c: No field 'Path' present to copy [May 11 05:28:16] DEBUG[26898] chan_sip.c: Trying to put 'SIP/2.0 200' onto TCP socket destined for 159.122.0.248:39834 [May 11 05:28:16] DEBUG[26712] devicestate.c: No provider found, checking channel drivers for SIP - 101 [May 11 05:28:16] DEBUG[26712] chan_sip.c: Checking device state for peer 101 [May 11 05:28:16] DEBUG[26712] devicestate.c: Changing state for SIP/101 - state 1 (Not in use) [May 11 05:28:16] DEBUG[26756] app_queue.c: Device 'SIP/101' changed to state '1' (Not in use) but we don't care because they're not a member of any queue. [May 11 05:28:17] DEBUG[26898] chan_sip.c: = Looking for Call ID: CY.4ZhCaLm1gu4Lw2KqLtQpFMXyIEZA5 (Checking From) --From tag -nU5GlwnpRGhuF9dZWJuCXmC-C93D6Ls --To-tag [May 11 05:28:17] DEBUG[26898] acl.c: For destination '159.122.0.248', our source address is '23.246.211.133'. [May 11 05:28:17] DEBUG[26898] chan_sip.c: Setting AST_TRANSPORT_TCP with address 23.246.211.133:5060 [May 11 05:28:17] DEBUG[26898] netsock2.c: Splitting '159.122.0.248:5060' into... [May 11 05:28:17] DEBUG[26898] netsock2.c: ...host '159.122.0.248' and port '5060'. [May 11 05:28:17] DEBUG[26898] chan_sip.c: Allocating new SIP dialog for CY.4ZhCaLm1gu4Lw2KqLtQpFMXyIEZA5 - SUBSCRIBE (No RTP) [May 11 05:28:17] DEBUG[26898] chan_sip.c: **** Received SUBSCRIBE (10) - Command in SIP SUBSCRIBE [May 11 05:28:17] DEBUG[26898] netsock2.c: Splitting '159.122.0.248:5060' into... [May 11 05:28:17] DEBUG[26898] netsock2.c: ...host '159.122.0.248' and port '5060'. [May 11 05:28:17] DEBUG[26898] sip/route.c: sip_route_process_header: [May 11 05:28:17] DEBUG[26898] netsock2.c: Splitting '23.246.211.133' into... [May 11 05:28:17] DEBUG[26898] netsock2.c: ...host '23.246.211.133' and port ''. [May 11 05:28:17] DEBUG[26898] chan_sip.c: Trying to put 'SIP/2.0 401' onto TCP socket destined for 159.122.0.248:39834 [May 11 05:28:17] DEBUG[26898] chan_sip.c: = Looking for Call ID: CY.4ZhCaLm1gu4Lw2KqLtQpFMXyIEZA5 (Checking From) --From tag -nU5GlwnpRGhuF9dZWJuCXmC-C93D6Ls --To-tag [May 11 05:28:17] DEBUG[26898] netsock2.c: Splitting '23.246.211.133' into... [May 11 05:28:17] DEBUG[26898] netsock2.c: ...host '23.246.211.133' and port ''. [May 11 05:28:17] DEBUG[26898] netsock2.c: Splitting '23.246.211.133' into... [May 11 05:28:17] DEBUG[26898] netsock2.c: ...host '23.246.211.133' and port ''. [May 11 05:28:17] DEBUG[26898] chan_sip.c: **** Received SUBSCRIBE (10) - Command in SIP SUBSCRIBE [May 11 05:28:17] DEBUG[26898] netsock2.c: Splitting '159.122.0.248:5060' into... [May 11 05:28:17] DEBUG[26898] netsock2.c: ...host '159.122.0.248' and port '5060'. [May 11 05:28:17] DEBUG[26898] chan_sip.c: build_route: Retaining previous route: [May 11 05:28:17] DEBUG[26898] netsock2.c: Splitting '23.246.211.133' into... [May 11 05:28:17] DEBUG[26898] netsock2.c: ...host '23.246.211.133' and port ''. [May 11 05:28:17] DEBUG[26898] chan_sip.c: Trying to put 'SIP/2.0 404' onto TCP socket destined for 159.122.0.248:39834 [May 11 05:28:17] NOTICE[26898] chan_sip.c: Received SIP subscribe for peer without mailbox: 101 [May 11 05:28:17] DEBUG[26742] chan_sip.c: Destroying SIP dialog CY.4ZhCaLm1gu4Lw2KqLtQpFMXyIEZA5 [May 11 05:28:20] DEBUG[26742] chan_sip.c: = Looking for Call ID: 4c329396247fa73dc0d0c997de2717cf (Checking From) --From tag f1858d76 --To-tag [May 11 05:28:20] DEBUG[26742] acl.c: For destination '184.107.201.78', our source address is '23.246.211.133'. [May 11 05:28:20] DEBUG[26742] chan_sip.c: Setting AST_TRANSPORT_UDP with address 23.246.211.133:5060 [May 11 05:28:20] DEBUG[26742] netsock2.c: Splitting '184.107.201.78:5074' into... [May 11 05:28:20] DEBUG[26742] netsock2.c: ...host '184.107.201.78' and port '5074'. [May 11 05:28:20] DEBUG[26742] chan_sip.c: Allocating new SIP dialog for 4c329396247fa73dc0d0c997de2717cf - INVITE (No RTP) [May 11 05:28:20] DEBUG[26742][C-00000002] chan_sip.c: **** Received INVITE (5) - Command in SIP INVITE [May 11 05:28:20] DEBUG[26742][C-00000002] netsock2.c: Splitting '184.107.201.78:5074' into... [May 11 05:28:20] DEBUG[26742][C-00000002] netsock2.c: ...host '184.107.201.78' and port '5074'. [May 11 05:28:20] DEBUG[26742][C-00000002] netsock2.c: Splitting '23.246.211.133' into... [May 11 05:28:20] DEBUG[26742][C-00000002] netsock2.c: ...host '23.246.211.133' and port ''. [May 11 05:28:20] DEBUG[26742][C-00000002] rtp_engine.c: Using engine 'asterisk' for RTP instance '0x7f79c4013288' [May 11 05:28:20] DEBUG[26742][C-00000002] res_rtp_asterisk.c: Allocated port 11948 for RTP instance '0x7f79c4013288' [May 11 05:28:20] DEBUG[26742][C-00000002] rtp_engine.c: RTP instance '0x7f79c4013288' is setup and ready to go [May 11 05:28:20] DEBUG[26742][C-00000002] res_rtp_asterisk.c: Setup RTCP on RTP instance '0x7f79c4013288' [May 11 05:28:20] DEBUG[26742][C-00000002] chan_sip.c: Setting NAT on RTP to Off [May 11 05:28:20] DEBUG[26742][C-00000002] chan_sip.c: Processing session-level SDP v=0... UNSUPPORTED OR FAILED. [May 11 05:28:20] DEBUG[26742][C-00000002] chan_sip.c: Processing session-level SDP o=sipcli-Session 1185996030 674284643 IN IP4 184.107.201.78... OK. [May 11 05:28:20] DEBUG[26742][C-00000002] chan_sip.c: Processing session-level SDP s=sipcli... UNSUPPORTED OR FAILED. [May 11 05:28:20] DEBUG[26742][C-00000002] netsock2.c: Splitting '184.107.201.78' into... [May 11 05:28:20] DEBUG[26742][C-00000002] netsock2.c: ...host '184.107.201.78' and port ''. [May 11 05:28:20] DEBUG[26742][C-00000002] chan_sip.c: Processing session-level SDP c=IN IP4 184.107.201.78... OK. [May 11 05:28:20] DEBUG[26742][C-00000002] chan_sip.c: Processing session-level SDP t=0 0... UNSUPPORTED OR FAILED. [May 11 05:28:20] DEBUG[26742][C-00000002] rtp_engine.c: Setting payload 18 (0x7f79c4005978) based on m type on 0x7f798ba14b60 [May 11 05:28:20] DEBUG[26742][C-00000002] rtp_engine.c: Setting payload 0 (0x7f79c4005df8) based on m type on 0x7f798ba14b60 [May 11 05:28:20] DEBUG[26742][C-00000002] rtp_engine.c: Setting payload 8 (0x7f79c4006258) based on m type on 0x7f798ba14b60 [May 11 05:28:20] DEBUG[26742][C-00000002] rtp_engine.c: Setting payload 101 (0x7f79c40066b8) based on m type on 0x7f798ba14b60 [May 11 05:28:20] DEBUG[26742][C-00000002] chan_sip.c: Processing media-level (audio) SDP a=fmtp:101 0-15... UNSUPPORTED OR FAILED. [May 11 05:28:20] DEBUG[26742][C-00000002] chan_sip.c: Processing media-level (audio) SDP a=rtpmap:18 G729/8000... OK. [May 11 05:28:20] DEBUG[26742][C-00000002] chan_sip.c: Processing media-level (audio) SDP a=rtpmap:0 PCMU/8000... OK. [May 11 05:28:20] DEBUG[26742][C-00000002] chan_sip.c: Processing media-level (audio) SDP a=rtpmap:8 PCMA/8000... OK. [May 11 05:28:20] DEBUG[26742][C-00000002] chan_sip.c: Processing media-level (audio) SDP a=rtpmap:101 telephone-event/8000... OK. [May 11 05:28:20] DEBUG[26742][C-00000002] chan_sip.c: Processing media-level (audio) SDP a=ptime:20... OK. [May 11 05:28:20] DEBUG[26742][C-00000002] chan_sip.c: Processing media-level (audio) SDP a=sendrecv... OK. [May 11 05:28:20] DEBUG[26742][C-00000002] res_rtp_asterisk.c: Setting RTCP address on RTP instance '0x7f79c4013288' [May 11 05:28:20] DEBUG[26742][C-00000002] rtp_engine.c: Copying payload 0 (0x7f79c4005978) from 0x7f798ba14b60 to 0x7f79c4013450 [May 11 05:28:20] DEBUG[26742][C-00000002] rtp_engine.c: Copying payload 8 (0x7f79c4005df8) from 0x7f798ba14b60 to 0x7f79c4013450 [May 11 05:28:20] DEBUG[26742][C-00000002] rtp_engine.c: Copying payload 18 (0x7f79c4006ef8) from 0x7f798ba14b60 to 0x7f79c4013450 [May 11 05:28:20] DEBUG[26742][C-00000002] rtp_engine.c: Copying payload 101 (0x7f79c4006258) from 0x7f798ba14b60 to 0x7f79c4013450 [May 11 05:28:20] DEBUG[26742][C-00000002] res_rtp_asterisk.c: Ignoring duplicate RTCP property on RTP instance '0x7f79c4013288' [May 11 05:28:20] DEBUG[26742][C-00000002] chan_sip.c: We're settling with these formats: (ulaw|alaw) [May 11 05:28:20] DEBUG[26742][C-00000002] chan_sip.c: Checking SIP call limits for device [May 11 05:28:20] DEBUG[26742][C-00000002] chan_sip.c: Updating call counter for incoming call [May 11 05:28:20] DEBUG[26742][C-00000002] netsock2.c: Splitting '23.246.211.133' into... [May 11 05:28:20] DEBUG[26742][C-00000002] netsock2.c: ...host '23.246.211.133' and port ''. [May 11 05:28:20] DEBUG[26742][C-00000002] netsock2.c: Splitting '23.246.211.133' into... [May 11 05:28:20] DEBUG[26742][C-00000002] netsock2.c: ...host '23.246.211.133' and port ''. [May 11 05:28:20] DEBUG[26742][C-00000002] chan_sip.c: Trying to put 'SIP/2.0 404' onto UDP socket destined for 184.107.201.78:5074 [May 11 05:28:20] NOTICE[26742][C-00000002] chan_sip.c: Call from '' (184.107.201.78:5074) to extension '58972597365303' rejected because extension not found in context 'public'. [May 11 05:28:20] DEBUG[26742][C-00000002] chan_sip.c: Updating call counter for incoming call [May 11 05:28:20] DEBUG[26742] chan_sip.c: ** SIP timers: Rescheduling retransmission 2 to 1000 ms (t1 500 ms (Retrans id #16)) [May 11 05:28:20] DEBUG[26742] chan_sip.c: Trying to put 'SIP/2.0 404' onto UDP socket destined for 184.107.201.78:5074 [May 11 05:28:21] DEBUG[26742] chan_sip.c: ** SIP timers: Rescheduling retransmission 3 to 2000 ms (t1 500 ms (Retrans id #16)) [May 11 05:28:21] DEBUG[26742] chan_sip.c: Trying to put 'SIP/2.0 404' onto UDP socket destined for 184.107.201.78:5074 [May 11 05:28:23] DEBUG[26742] chan_sip.c: ** SIP timers: Rescheduling retransmission 4 to 4000 ms (t1 500 ms (Retrans id #16)) [May 11 05:28:23] DEBUG[26742] chan_sip.c: Trying to put 'SIP/2.0 404' onto UDP socket destined for 184.107.201.78:5074 [May 11 05:28:27] DEBUG[26742] chan_sip.c: ** SIP timers: Rescheduling retransmission 5 to 4000 ms (t1 500 ms (Retrans id #16)) [May 11 05:28:27] DEBUG[26742] chan_sip.c: Trying to put 'SIP/2.0 404' onto UDP socket destined for 184.107.201.78:5074 [May 11 05:28:29] DEBUG[26898] chan_sip.c: = Looking for Call ID: 3981b187082c4a8b84cb062a2cb3fb1c (Checking From) --From tag ee225d0289224968b7aaa4f6c13f3c7e --To-tag [May 11 05:28:29] DEBUG[26898] acl.c: For destination '159.122.0.248', our source address is '23.246.211.133'. [May 11 05:28:29] DEBUG[26898] chan_sip.c: Setting AST_TRANSPORT_TCP with address 23.246.211.133:5060 [May 11 05:28:29] DEBUG[26898] netsock2.c: Splitting '159.122.0.248:5060' into... [May 11 05:28:29] DEBUG[26898] netsock2.c: ...host '159.122.0.248' and port '5060'. [May 11 05:28:29] DEBUG[26898] chan_sip.c: Allocating new SIP dialog for 3981b187082c4a8b84cb062a2cb3fb1c - REGISTER (No RTP) [May 11 05:28:29] DEBUG[26898] chan_sip.c: **** Received REGISTER (2) - Command in SIP REGISTER [May 11 05:28:29] DEBUG[26898] netsock2.c: Splitting '159.122.0.248:5060' into... [May 11 05:28:29] DEBUG[26898] netsock2.c: ...host '159.122.0.248' and port '5060'. [May 11 05:28:29] DEBUG[26898] netsock2.c: Splitting '23.246.211.133' into... [May 11 05:28:29] DEBUG[26898] netsock2.c: ...host '23.246.211.133' and port ''. [May 11 05:28:29] DEBUG[26898] chan_sip.c: Trying to put 'SIP/2.0 401' onto TCP socket destined for 159.122.0.248:39834 [May 11 05:28:29] DEBUG[26898] chan_sip.c: = Looking for Call ID: 3981b187082c4a8b84cb062a2cb3fb1c (Checking From) --From tag ee225d0289224968b7aaa4f6c13f3c7e --To-tag [May 11 05:28:29] DEBUG[26898] chan_sip.c: **** Received REGISTER (2) - Command in SIP REGISTER [May 11 05:28:29] DEBUG[26898] netsock2.c: Splitting '159.122.0.248:5060' into... [May 11 05:28:29] DEBUG[26898] netsock2.c: ...host '159.122.0.248' and port '5060'. [May 11 05:28:29] DEBUG[26898] netsock2.c: Splitting '23.246.211.133' into... [May 11 05:28:29] DEBUG[26898] netsock2.c: ...host '23.246.211.133' and port ''. [May 11 05:28:29] DEBUG[26898] chan_sip.c: peer '100' has contacted us over TCP even though we prefer UDP. [May 11 05:28:29] DEBUG[26898] chan_sip.c: Store REGISTER's Contact header for call routing. [May 11 05:28:29] DEBUG[26898] netsock2.c: Splitting '192.116.52.233:5060' into... [May 11 05:28:29] DEBUG[26898] netsock2.c: ...host '192.116.52.233' and port '5060'. [May 11 05:28:29] DEBUG[26898] chan_sip.c: build_path: try to build pre-loaded route-set by parsing Path headers [May 11 05:28:29] NOTICE[26898] chan_sip.c: No field 'Path' present to copy [May 11 05:28:29] DEBUG[26898] chan_sip.c: Trying to put 'SIP/2.0 200' onto TCP socket destined for 159.122.0.248:39834 [May 11 05:28:29] DEBUG[26712] devicestate.c: No provider found, checking channel drivers for SIP - 100 [May 11 05:28:29] DEBUG[26712] chan_sip.c: Checking device state for peer 100 [May 11 05:28:29] DEBUG[26712] devicestate.c: Changing state for SIP/100 - state 1 (Not in use) [May 11 05:28:29] DEBUG[26756] app_queue.c: Device 'SIP/100' changed to state '1' (Not in use) but we don't care because they're not a member of any queue. [May 11 05:28:31] DEBUG[26742] chan_sip.c: ** SIP timers: Rescheduling retransmission 6 to 4000 ms (t1 500 ms (Retrans id #16)) [May 11 05:28:31] DEBUG[26742] chan_sip.c: Trying to put 'SIP/2.0 404' onto UDP socket destined for 184.107.201.78:5074 [May 11 05:28:35] DEBUG[26742] chan_sip.c: ** SIP timers: Rescheduling retransmission 7 to 4000 ms (t1 500 ms (Retrans id #16)) [May 11 05:28:35] DEBUG[26742] chan_sip.c: Trying to put 'SIP/2.0 404' onto UDP socket destined for 184.107.201.78:5074 [May 11 05:28:39] DEBUG[26742] chan_sip.c: ** SIP timers: Rescheduling retransmission 8 to 4000 ms (t1 500 ms (Retrans id #16)) [May 11 05:28:39] DEBUG[26742] chan_sip.c: Trying to put 'SIP/2.0 404' onto UDP socket destined for 184.107.201.78:5074 [May 11 05:28:41] DEBUG[26898] chan_sip.c: = Looking for Call ID: 8810faf09024439785f9e48d845c119c (Checking From) --From tag b87318a7fed544c0b67ae2b2e32b4e58 --To-tag [May 11 05:28:41] DEBUG[26898] acl.c: For destination '159.122.0.248', our source address is '23.246.211.133'. [May 11 05:28:41] DEBUG[26898] chan_sip.c: Setting AST_TRANSPORT_TCP with address 23.246.211.133:5060 [May 11 05:28:41] DEBUG[26898] netsock2.c: Splitting '159.122.0.248:5060' into... [May 11 05:28:41] DEBUG[26898] netsock2.c: ...host '159.122.0.248' and port '5060'. [May 11 05:28:41] DEBUG[26898] chan_sip.c: Allocating new SIP dialog for 8810faf09024439785f9e48d845c119c - INVITE (No RTP) [May 11 05:28:41] DEBUG[26898][C-00000003] chan_sip.c: **** Received INVITE (5) - Command in SIP INVITE [May 11 05:28:41] DEBUG[26898][C-00000003] sip/reqresp_parser.c: Begin: parsing SIP "Supported: replaces, 100rel, timer, norefersub" [May 11 05:28:41] DEBUG[26898][C-00000003] sip/reqresp_parser.c: Found SIP option: -replaces- [May 11 05:28:41] DEBUG[26898][C-00000003] sip/reqresp_parser.c: Matched SIP option: replaces [May 11 05:28:41] DEBUG[26898][C-00000003] sip/reqresp_parser.c: Found SIP option: -100rel- [May 11 05:28:41] DEBUG[26898][C-00000003] sip/reqresp_parser.c: Matched SIP option: 100rel [May 11 05:28:41] DEBUG[26898][C-00000003] sip/reqresp_parser.c: Found SIP option: -timer- [May 11 05:28:41] DEBUG[26898][C-00000003] sip/reqresp_parser.c: Matched SIP option: timer [May 11 05:28:41] DEBUG[26898][C-00000003] sip/reqresp_parser.c: Found SIP option: -norefersub- [May 11 05:28:41] DEBUG[26898][C-00000003] sip/reqresp_parser.c: Matched SIP option: norefersub [May 11 05:28:41] DEBUG[26898][C-00000003] netsock2.c: Splitting '159.122.0.248:5060' into... [May 11 05:28:41] DEBUG[26898][C-00000003] netsock2.c: ...host '159.122.0.248' and port '5060'. [May 11 05:28:41] DEBUG[26898][C-00000003] netsock2.c: Splitting '23.246.211.133' into... [May 11 05:28:41] DEBUG[26898][C-00000003] netsock2.c: ...host '23.246.211.133' and port ''. [May 11 05:28:41] DEBUG[26898][C-00000003] chan_sip.c: Trying to put 'SIP/2.0 401' onto TCP socket destined for 159.122.0.248:39834 [May 11 05:28:41] DEBUG[26898] chan_sip.c: = Looking for Call ID: 8810faf09024439785f9e48d845c119c (Checking From) --From tag b87318a7fed544c0b67ae2b2e32b4e58 --To-tag as3e528184 [May 11 05:28:41] DEBUG[26898][C-00000003] chan_sip.c: **** Received ACK (6) - Command in SIP ACK [May 11 05:28:41] DEBUG[26898][C-00000003] chan_sip.c: Stopping retransmission on '8810faf09024439785f9e48d845c119c' of Response 17475: Match Not Found [May 11 05:28:41] DEBUG[26898] chan_sip.c: = Looking for Call ID: 8810faf09024439785f9e48d845c119c (Checking From) --From tag b87318a7fed544c0b67ae2b2e32b4e58 --To-tag [May 11 05:28:41] DEBUG[26898] netsock2.c: Splitting '23.246.211.133' into... [May 11 05:28:41] DEBUG[26898] netsock2.c: ...host '23.246.211.133' and port ''. [May 11 05:28:41] DEBUG[26898] netsock2.c: Splitting '23.246.211.133' into... [May 11 05:28:41] DEBUG[26898] netsock2.c: ...host '23.246.211.133' and port ''. [May 11 05:28:41] DEBUG[26898][C-00000003] chan_sip.c: **** Received INVITE (5) - Command in SIP INVITE [May 11 05:28:41] DEBUG[26898][C-00000003] netsock2.c: Splitting '159.122.0.248:5060' into... [May 11 05:28:41] DEBUG[26898][C-00000003] netsock2.c: ...host '159.122.0.248' and port '5060'. [May 11 05:28:41] DEBUG[26898][C-00000003] netsock2.c: Splitting '23.246.211.133' into... [May 11 05:28:41] DEBUG[26898][C-00000003] netsock2.c: ...host '23.246.211.133' and port ''. [May 11 05:28:41] DEBUG[26898][C-00000003] rtp_engine.c: Using engine 'asterisk' for RTP instance '0x7f79c0011598' [May 11 05:28:41] DEBUG[26898][C-00000003] res_rtp_asterisk.c: Allocated port 12338 for RTP instance '0x7f79c0011598' [May 11 05:28:41] DEBUG[26898][C-00000003] rtp_engine.c: RTP instance '0x7f79c0011598' is setup and ready to go [May 11 05:28:41] DEBUG[26898][C-00000003] res_rtp_asterisk.c: Setup RTCP on RTP instance '0x7f79c0011598' [May 11 05:28:41] DEBUG[26898][C-00000003] chan_sip.c: Setting NAT on RTP to Off [May 11 05:28:41] DEBUG[26898][C-00000003] chan_sip.c: Processing session-level SDP v=0... UNSUPPORTED OR FAILED. [May 11 05:28:41] DEBUG[26898][C-00000003] chan_sip.c: Processing session-level SDP o=- 3640339666 3640339666 IN IP4 159.122.0.248... OK. [May 11 05:28:41] DEBUG[26898][C-00000003] chan_sip.c: Processing session-level SDP s=pjmedia... UNSUPPORTED OR FAILED. [May 11 05:28:41] DEBUG[26898][C-00000003] chan_sip.c: Processing session-level SDP b=AS:84... UNSUPPORTED OR FAILED. [May 11 05:28:41] DEBUG[26898][C-00000003] chan_sip.c: Processing session-level SDP t=0 0... UNSUPPORTED OR FAILED. [May 11 05:28:41] DEBUG[26898][C-00000003] chan_sip.c: Processing session-level SDP a=X-nat:0... UNSUPPORTED OR FAILED. [May 11 05:28:41] DEBUG[26898][C-00000003] rtp_engine.c: Setting payload 117 (0x7f79c000bb78) based on m type on 0x7f798b252d30 [May 11 05:28:41] DEBUG[26898][C-00000003] rtp_engine.c: Setting payload 107 (0x7f79c000f1d8) based on m type on 0x7f798b252d30 [May 11 05:28:41] DEBUG[26898][C-00000003] rtp_engine.c: Setting payload 9 (0x7f79c000f258) based on m type on 0x7f798b252d30 [May 11 05:28:41] DEBUG[26898][C-00000003] rtp_engine.c: Setting payload 8 (0x7f79c0013798) based on m type on 0x7f798b252d30 [May 11 05:28:41] DEBUG[26898][C-00000003] rtp_engine.c: Setting payload 0 (0x7f79c0013818) based on m type on 0x7f798b252d30 [May 11 05:28:41] DEBUG[26898][C-00000003] rtp_engine.c: Setting payload 110 (0x7f79c0013898) based on m type on 0x7f798b252d30 [May 11 05:28:41] DEBUG[26898][C-00000003] rtp_engine.c: Setting payload 96 (0x7f79c0013918) based on m type on 0x7f798b252d30 [May 11 05:28:41] DEBUG[26898][C-00000003] rtp_engine.c: Setting payload 97 (0x7f79c0013998) based on m type on 0x7f798b252d30 [May 11 05:28:41] DEBUG[26898][C-00000003] rtp_engine.c: Setting payload 3 (0x7f79c0013a18) based on m type on 0x7f798b252d30 [May 11 05:28:41] DEBUG[26898][C-00000003] rtp_engine.c: Setting payload 18 (0x7f79c0013a98) based on m type on 0x7f798b252d30 [May 11 05:28:41] DEBUG[26898][C-00000003] rtp_engine.c: Setting payload 101 (0x7f79c001b728) based on m type on 0x7f798b252d30 [May 11 05:28:41] DEBUG[26898][C-00000003] netsock2.c: Splitting '159.122.0.248' into... [May 11 05:28:41] DEBUG[26898][C-00000003] netsock2.c: ...host '159.122.0.248' and port ''. [May 11 05:28:41] DEBUG[26898][C-00000003] chan_sip.c: Processing media-level (audio) SDP c=IN IP4 159.122.0.248... OK. [May 11 05:28:41] DEBUG[26898][C-00000003] chan_sip.c: Processing media-level (audio) SDP b=TIAS:64000... UNSUPPORTED OR FAILED. [May 11 05:28:41] DEBUG[26898][C-00000003] chan_sip.c: Processing media-level (audio) SDP a=rtcp:51823... UNSUPPORTED OR FAILED. [May 11 05:28:41] DEBUG[26898][C-00000003] chan_sip.c: Processing media-level (audio) SDP a=sendrecv... OK. [May 11 05:28:41] DEBUG[26898][C-00000003] chan_sip.c: Processing media-level (audio) SDP a=rtpmap:117 speex/16000... OK. [May 11 05:28:41] DEBUG[26898][C-00000003] rtp_engine.c: Unsetting payload 107 on 0x7f798b252d30 [May 11 05:28:41] DEBUG[26898][C-00000003] chan_sip.c: Processing media-level (audio) SDP a=rtpmap:107 SILK/16000... UNSUPPORTED OR FAILED. [May 11 05:28:41] DEBUG[26898][C-00000003] chan_sip.c: Processing media-level (audio) SDP a=rtpmap:9 G722/8000... OK. [May 11 05:28:41] DEBUG[26898][C-00000003] chan_sip.c: Processing media-level (audio) SDP a=rtpmap:8 PCMA/8000... OK. [May 11 05:28:41] DEBUG[26898][C-00000003] chan_sip.c: Processing media-level (audio) SDP a=rtpmap:0 PCMU/8000... OK. [May 11 05:28:41] DEBUG[26898][C-00000003] chan_sip.c: Processing media-level (audio) SDP a=rtpmap:110 speex/8000... OK. [May 11 05:28:41] DEBUG[26898][C-00000003] rtp_engine.c: Unsetting payload 96 on 0x7f798b252d30 [May 11 05:28:41] DEBUG[26898][C-00000003] chan_sip.c: Processing media-level (audio) SDP a=rtpmap:96 SILK/8000... UNSUPPORTED OR FAILED. [May 11 05:28:41] DEBUG[26898][C-00000003] chan_sip.c: Processing media-level (audio) SDP a=rtpmap:97 iLBC/8000... OK. [May 11 05:28:41] DEBUG[26898][C-00000003] chan_sip.c: Processing media-level (audio) SDP a=fmtp:97 mode=30... OK. [May 11 05:28:41] DEBUG[26898][C-00000003] chan_sip.c: Processing media-level (audio) SDP a=rtpmap:3 GSM/8000... OK. [May 11 05:28:41] DEBUG[26898][C-00000003] chan_sip.c: Processing media-level (audio) SDP a=rtpmap:18 G729/8000... OK. [May 11 05:28:41] DEBUG[26898][C-00000003] chan_sip.c: Processing media-level (audio) SDP a=fmtp:18 annexb=no... OK. [May 11 05:28:41] DEBUG[26898][C-00000003] chan_sip.c: Processing media-level (audio) SDP a=rtpmap:101 telephone-event/8000... OK. [May 11 05:28:41] DEBUG[26898][C-00000003] chan_sip.c: Processing media-level (audio) SDP a=fmtp:101 0-16... UNSUPPORTED OR FAILED. [May 11 05:28:41] DEBUG[26898][C-00000003] chan_sip.c: Processing media-level (audio) SDP a=nortpproxy:yes... UNSUPPORTED OR FAILED. [May 11 05:28:41] DEBUG[26898][C-00000003] res_rtp_asterisk.c: Setting RTCP address on RTP instance '0x7f79c0011598' [May 11 05:28:41] DEBUG[26898][C-00000003] rtp_engine.c: Copying payload 0 (0x7f79c0013798) from 0x7f798b252d30 to 0x7f79c0011760 [May 11 05:28:41] DEBUG[26898][C-00000003] rtp_engine.c: Copying payload 3 (0x7f79c0013998) from 0x7f798b252d30 to 0x7f79c0011760 [May 11 05:28:41] DEBUG[26898][C-00000003] rtp_engine.c: Copying payload 8 (0x7f79c000f258) from 0x7f798b252d30 to 0x7f79c0011760 [May 11 05:28:41] DEBUG[26898][C-00000003] rtp_engine.c: Copying payload 9 (0x7f79c000f1d8) from 0x7f798b252d30 to 0x7f79c0011760 [May 11 05:28:41] DEBUG[26898][C-00000003] rtp_engine.c: Copying payload 18 (0x7f79c0013a18) from 0x7f798b252d30 to 0x7f79c0011760 [May 11 05:28:41] DEBUG[26898][C-00000003] rtp_engine.c: Copying payload 97 (0x7f79c0013918) from 0x7f798b252d30 to 0x7f79c0011760 [May 11 05:28:41] DEBUG[26898][C-00000003] rtp_engine.c: Copying payload 101 (0x7f79c0013a98) from 0x7f798b252d30 to 0x7f79c0011760 [May 11 05:28:41] DEBUG[26898][C-00000003] rtp_engine.c: Copying payload 110 (0x7f79c0013818) from 0x7f798b252d30 to 0x7f79c0011760 [May 11 05:28:41] DEBUG[26898][C-00000003] rtp_engine.c: Copying payload 117 (0x7f79c001c348) from 0x7f798b252d30 to 0x7f79c0011760 [May 11 05:28:41] DEBUG[26898][C-00000003] res_rtp_asterisk.c: Ignoring duplicate RTCP property on RTP instance '0x7f79c0011598' [May 11 05:28:41] DEBUG[26898][C-00000003] chan_sip.c: We're settling with these formats: (ulaw) [May 11 05:28:41] DEBUG[26898][C-00000003] chan_sip.c: Checking SIP call limits for device 100 [May 11 05:28:41] DEBUG[26898][C-00000003] chan_sip.c: Updating call counter for incoming call [May 11 05:28:41] DEBUG[26898][C-00000003] netsock2.c: Splitting '23.246.211.133' into... [May 11 05:28:41] DEBUG[26898][C-00000003] netsock2.c: ...host '23.246.211.133' and port ''. [May 11 05:28:41] DEBUG[26898][C-00000003] netsock2.c: Splitting '23.246.211.133' into... [May 11 05:28:41] DEBUG[26898][C-00000003] netsock2.c: ...host '23.246.211.133' and port ''. [May 11 05:28:41] DEBUG[26898][C-00000003] chan_sip.c: Incoming INVITE with 'timer' option supported [May 11 05:28:41] DEBUG[26898][C-00000003] chan_sip.c: INVITE also has "Session-Expires" header. [May 11 05:28:41] DEBUG[26898][C-00000003] chan_sip.c: Session-Expires: 1800 [May 11 05:28:41] DEBUG[26898][C-00000003] chan_sip.c: INVITE also has "Min-SE" header. [May 11 05:28:41] DEBUG[26898][C-00000003] chan_sip.c: Received Min-SE: 90 [May 11 05:28:41] DEBUG[26898][C-00000003] chan_sip.c: *** Our native formats are (ulaw) [May 11 05:28:41] DEBUG[26898][C-00000003] chan_sip.c: *** Joint capabilities are (ulaw) [May 11 05:28:41] DEBUG[26898][C-00000003] chan_sip.c: *** Our capabilities are (ulaw) [May 11 05:28:41] DEBUG[26898][C-00000003] chan_sip.c: *** AST_CODEC_CHOOSE formats are ulaw [May 11 05:28:41] DEBUG[26898][C-00000003] chan_sip.c: This channel will not be able to handle video. [May 11 05:28:41] DEBUG[26898][C-00000003] sip/route.c: sip_route_process_header: [May 11 05:28:41] DEBUG[26898][C-00000003] chan_sip.c: SIP/100-00000000: New call is still down.... Trying... [May 11 05:28:41] DEBUG[26898][C-00000003] chan_sip.c: Trying to put 'SIP/2.0 100' onto TCP socket destined for 159.122.0.248:39834 [May 11 05:28:41] DEBUG[26712] devicestate.c: No provider found, checking channel drivers for SIP - 100 [May 11 05:28:41] DEBUG[26712] chan_sip.c: Checking device state for peer 100 [May 11 05:28:41] DEBUG[26712] devicestate.c: Changing state for SIP/100 - state 1 (Not in use) [May 11 05:28:41] DEBUG[26908][C-00000003] pbx.c: Launching 'Dial' [May 11 05:28:41] DEBUG[26908][C-00000003] chan_sip.c: Asked to create a SIP channel with formats: (ulaw) [May 11 05:28:41] DEBUG[26908][C-00000003] chan_sip.c: Allocating new SIP dialog for 226c3769440609f64b13f42f3f52a231@23.246.211.133:5060 - INVITE (No RTP) [May 11 05:28:41] DEBUG[26908][C-00000003] rtp_engine.c: Using engine 'asterisk' for RTP instance '0x7f79ac004da8' [May 11 05:28:41] DEBUG[26908][C-00000003] res_rtp_asterisk.c: Allocated port 15214 for RTP instance '0x7f79ac004da8' [May 11 05:28:41] DEBUG[26908][C-00000003] rtp_engine.c: RTP instance '0x7f79ac004da8' is setup and ready to go [May 11 05:28:41] DEBUG[26908][C-00000003] res_rtp_asterisk.c: Setup RTCP on RTP instance '0x7f79ac004da8' [May 11 05:28:41] DEBUG[26908][C-00000003] chan_sip.c: Setting NAT on RTP to Off [May 11 05:28:41] DEBUG[26908][C-00000003] acl.c: For destination '192.116.52.233', our source address is '23.246.211.133'. [May 11 05:28:41] DEBUG[26908][C-00000003] chan_sip.c: Setting AST_TRANSPORT_TCP with address 23.246.211.133:5060 [May 11 05:28:41] DEBUG[26908][C-00000003] netsock2.c: Splitting '192.116.52.233:37342' into... [May 11 05:28:41] DEBUG[26908][C-00000003] netsock2.c: ...host '192.116.52.233' and port '37342'. [May 11 05:28:41] DEBUG[26908][C-00000003] chan_sip.c: Setting NAT on RTP to Off [May 11 05:28:41] DEBUG[26908][C-00000003] chan_sip.c: SIP call-id changed from '226c3769440609f64b13f42f3f52a231@23.246.211.133:5060' to '6d2e5c9c0eeda1a236ae6be12c98a599@23.246.211.133:5060' [May 11 05:28:41] DEBUG[26908][C-00000003] chan_sip.c: *** Our native formats are (ulaw) [May 11 05:28:41] DEBUG[26908][C-00000003] chan_sip.c: *** Joint capabilities are (ulaw) [May 11 05:28:41] DEBUG[26908][C-00000003] chan_sip.c: *** Our capabilities are (ulaw) [May 11 05:28:41] DEBUG[26908][C-00000003] chan_sip.c: *** AST_CODEC_CHOOSE formats are ulaw [May 11 05:28:41] DEBUG[26908][C-00000003] chan_sip.c: *** Our preferred formats from the incoming channel are (ulaw) [May 11 05:28:41] DEBUG[26908][C-00000003] chan_sip.c: This channel will not be able to handle video. [May 11 05:28:41] DEBUG[26908][C-00000003] channel_internal_api.c: Channel Call ID changing from [C-00000003] to [C-00000003] [May 11 05:28:41] DEBUG[26908][C-00000003] rtp_engine.c: Copying payload 0 (0x7f79c0013798) from 0x7f79c0011760 to 0x7f79ac004f70 [May 11 05:28:41] DEBUG[26908][C-00000003] rtp_engine.c: Copying payload 3 (0x7f79c0013998) from 0x7f79c0011760 to 0x7f79ac004f70 [May 11 05:28:41] DEBUG[26908][C-00000003] rtp_engine.c: Copying payload 8 (0x7f79c000f258) from 0x7f79c0011760 to 0x7f79ac004f70 [May 11 05:28:41] DEBUG[26908][C-00000003] rtp_engine.c: Copying payload 9 (0x7f79c000f1d8) from 0x7f79c0011760 to 0x7f79ac004f70 [May 11 05:28:41] DEBUG[26908][C-00000003] rtp_engine.c: Copying payload 18 (0x7f79c0013a18) from 0x7f79c0011760 to 0x7f79ac004f70 [May 11 05:28:41] DEBUG[26908][C-00000003] rtp_engine.c: Copying payload 97 (0x7f79c0013918) from 0x7f79c0011760 to 0x7f79ac004f70 [May 11 05:28:41] DEBUG[26908][C-00000003] rtp_engine.c: Copying payload 101 (0x7f79c0013a98) from 0x7f79c0011760 to 0x7f79ac004f70 [May 11 05:28:41] DEBUG[26908][C-00000003] rtp_engine.c: Copying payload 110 (0x7f79c0013818) from 0x7f79c0011760 to 0x7f79ac004f70 [May 11 05:28:41] DEBUG[26908][C-00000003] rtp_engine.c: Copying payload 117 (0x7f79c001c348) from 0x7f79c0011760 to 0x7f79ac004f70 [May 11 05:28:41] DEBUG[26908][C-00000003] rtp_engine.c: Seeded SDP of 'SIP/101-00000001' with that of 'SIP/100-00000000' [May 11 05:28:41] DEBUG[26908][C-00000003] chan_sip.c: Outgoing Call for 101 [May 11 05:28:41] DEBUG[26908][C-00000003] chan_sip.c: Updating call counter for outgoing call [May 11 05:28:41] DEBUG[26908][C-00000003] chan_sip.c: ** Our capability: (ulaw) Video flag: False Text flag: False [May 11 05:28:41] DEBUG[26908][C-00000003] chan_sip.c: ** Our prefcodec: (ulaw) [May 11 05:28:41] DEBUG[26908][C-00000003] chan_sip.c: -- Done with adding codecs to SDP [May 11 05:28:41] DEBUG[26908][C-00000003] chan_sip.c: Done building SDP. Settling with this capability: (ulaw) [May 11 05:28:41] DEBUG[26908][C-00000003] chan_sip.c: Initializing initreq for method INVITE - callid 6d2e5c9c0eeda1a236ae6be12c98a599@23.246.211.133:5060 [May 11 05:28:41] DEBUG[26908][C-00000003] chan_sip.c: Trying to put 'INVITE sip:' onto TCP socket destined for 192.116.52.233:37342 [May 11 05:28:43] DEBUG[26742] chan_sip.c: ** SIP timers: Rescheduling retransmission 9 to 4000 ms (t1 500 ms (Retrans id #16)) [May 11 05:28:43] DEBUG[26742] chan_sip.c: Trying to put 'SIP/2.0 404' onto UDP socket destined for 184.107.201.78:5074 [May 11 05:28:47] DEBUG[26742] chan_sip.c: ** SIP timers: Rescheduling retransmission 10 to 4000 ms (t1 500 ms (Retrans id #16)) [May 11 05:28:47] DEBUG[26742] chan_sip.c: Trying to put 'SIP/2.0 404' onto UDP socket destined for 184.107.201.78:5074 [May 11 05:28:48] DEBUG[26742] chan_sip.c: Auto destroying SIP dialog 'PYf6ZjIgNwjW9dDDQLAYR2Lyr7WyqnXC' [May 11 05:28:48] DEBUG[26742] chan_sip.c: Destroying SIP dialog PYf6ZjIgNwjW9dDDQLAYR2Lyr7WyqnXC [May 11 05:28:51] DEBUG[26742] chan_sip.c: ** SIP timers: Rescheduling retransmission 11 to 4000 ms (t1 500 ms (Retrans id #16)) [May 11 05:28:51] DEBUG[26742] chan_sip.c: Trying to put 'SIP/2.0 404' onto UDP socket destined for 184.107.201.78:5074 [May 11 05:28:52] WARNING[26742] chan_sip.c: Retransmission timeout reached on transmission 4c329396247fa73dc0d0c997de2717cf for seqno 1 (Critical Response) -- See https://wiki.asterisk.org/wiki/display/AST/SIP+Retransmissions Packet timed out after 31999ms with no response [May 11 05:28:52] DEBUG[26742] chan_sip.c: Setting SIP_ALREADYGONE on dialog 4c329396247fa73dc0d0c997de2717cf [May 11 05:28:52] DEBUG[26742] chan_sip.c: Auto destroying SIP dialog '4c329396247fa73dc0d0c997de2717cf' [May 11 05:28:52] DEBUG[26742] chan_sip.c: Destroying SIP dialog 4c329396247fa73dc0d0c997de2717cf [May 11 05:28:52] DEBUG[26742] rtp_engine.c: Destroyed RTP instance '0x7f79c4013288' [May 11 05:28:54] DEBUG[26742] chan_sip.c: = Looking for Call ID: 5e7d199ad708407bb3b940701faf6655 (Checking From) --From tag 36403834782d45fbac6b4c964d6943b1 --To-tag [May 11 05:28:54] DEBUG[26742] acl.c: For destination '198.11.204.170', our source address is '23.246.211.133'. [May 11 05:28:54] DEBUG[26742] chan_sip.c: Setting AST_TRANSPORT_UDP with address 23.246.211.133:5060 [May 11 05:28:54] DEBUG[26742] netsock2.c: Splitting '198.11.204.170:5060' into... [May 11 05:28:54] DEBUG[26742] netsock2.c: ...host '198.11.204.170' and port '5060'. [May 11 05:28:54] DEBUG[26742] chan_sip.c: Allocating new SIP dialog for 5e7d199ad708407bb3b940701faf6655 - REGISTER (No RTP) [May 11 05:28:54] DEBUG[26742] chan_sip.c: **** Received REGISTER (2) - Command in SIP REGISTER [May 11 05:28:54] DEBUG[26742] netsock2.c: Splitting '198.11.204.170:5060' into... [May 11 05:28:54] DEBUG[26742] netsock2.c: ...host '198.11.204.170' and port '5060'. [May 11 05:28:54] DEBUG[26742] netsock2.c: Splitting '23.246.211.133' into... [May 11 05:28:54] DEBUG[26742] netsock2.c: ...host '23.246.211.133' and port ''. [May 11 05:28:54] DEBUG[26742] chan_sip.c: Trying to put 'SIP/2.0 401' onto UDP socket destined for 198.11.204.170:5060 [May 11 05:28:54] DEBUG[26742] chan_sip.c: = Looking for Call ID: 5e7d199ad708407bb3b940701faf6655 (Checking From) --From tag 36403834782d45fbac6b4c964d6943b1 --To-tag [May 11 05:28:54] DEBUG[26742] chan_sip.c: **** Received REGISTER (2) - Command in SIP REGISTER [May 11 05:28:54] DEBUG[26742] netsock2.c: Splitting '198.11.204.170:5060' into... [May 11 05:28:54] DEBUG[26742] netsock2.c: ...host '198.11.204.170' and port '5060'. [May 11 05:28:54] DEBUG[26742] netsock2.c: Splitting '23.246.211.133' into... [May 11 05:28:54] DEBUG[26742] netsock2.c: ...host '23.246.211.133' and port ''. [May 11 05:28:54] DEBUG[26742] chan_sip.c: Store REGISTER's Contact header for call routing. [May 11 05:28:54] DEBUG[26742] netsock2.c: Splitting '192.116.52.233:5060' into... [May 11 05:28:54] DEBUG[26742] netsock2.c: ...host '192.116.52.233' and port '5060'. [May 11 05:28:54] DEBUG[26742] chan_sip.c: build_path: try to build pre-loaded route-set by parsing Path headers [May 11 05:28:54] NOTICE[26742] chan_sip.c: No field 'Path' present to copy [May 11 05:28:54] DEBUG[26742] chan_sip.c: Trying to put 'SIP/2.0 200' onto UDP socket destined for 198.11.204.170:5060 [May 11 05:28:54] DEBUG[26712] devicestate.c: No provider found, checking channel drivers for SIP - 110 [May 11 05:28:54] DEBUG[26712] chan_sip.c: Checking device state for peer 110 [May 11 05:28:54] DEBUG[26712] devicestate.c: Changing state for SIP/110 - state 1 (Not in use) [May 11 05:28:54] DEBUG[26756] app_queue.c: Device 'SIP/110' changed to state '1' (Not in use) but we don't care because they're not a member of any queue. [May 11 05:29:01] DEBUG[26742] chan_sip.c: Auto destroying SIP dialog '3981b187082c4a8b84cb062a2cb3fb1c' [May 11 05:29:01] DEBUG[26742] chan_sip.c: Destroying SIP dialog 3981b187082c4a8b84cb062a2cb3fb1c [May 11 05:29:06] DEBUG[26742] chan_sip.c: = Looking for Call ID: 8dfaff944b463ff04eceb547c50d5d90 (Checking From) --From tag 4fd775e5 --To-tag [May 11 05:29:06] DEBUG[26742] acl.c: For destination '23.92.80.40', our source address is '23.246.211.133'. [May 11 05:29:06] DEBUG[26742] chan_sip.c: Setting AST_TRANSPORT_UDP with address 23.246.211.133:5060 [May 11 05:29:06] DEBUG[26742] netsock2.c: Splitting '23.92.80.40:5074' into... [May 11 05:29:06] DEBUG[26742] netsock2.c: ...host '23.92.80.40' and port '5074'. [May 11 05:29:06] DEBUG[26742] chan_sip.c: Allocating new SIP dialog for 8dfaff944b463ff04eceb547c50d5d90 - INVITE (No RTP) [May 11 05:29:06] DEBUG[26742][C-00000004] chan_sip.c: **** Received INVITE (5) - Command in SIP INVITE [May 11 05:29:06] DEBUG[26742][C-00000004] netsock2.c: Splitting '23.92.80.40:5074' into... [May 11 05:29:06] DEBUG[26742][C-00000004] netsock2.c: ...host '23.92.80.40' and port '5074'. [May 11 05:29:06] DEBUG[26742][C-00000004] netsock2.c: Splitting '23.246.211.133' into... [May 11 05:29:06] DEBUG[26742][C-00000004] netsock2.c: ...host '23.246.211.133' and port ''. [May 11 05:29:06] DEBUG[26742][C-00000004] rtp_engine.c: Using engine 'asterisk' for RTP instance '0x7f79c400e958' [May 11 05:29:06] DEBUG[26742][C-00000004] res_rtp_asterisk.c: Allocated port 18450 for RTP instance '0x7f79c400e958' [May 11 05:29:06] DEBUG[26742][C-00000004] rtp_engine.c: RTP instance '0x7f79c400e958' is setup and ready to go [May 11 05:29:06] DEBUG[26742][C-00000004] res_rtp_asterisk.c: Setup RTCP on RTP instance '0x7f79c400e958' [May 11 05:29:06] DEBUG[26742][C-00000004] chan_sip.c: Setting NAT on RTP to Off [May 11 05:29:06] DEBUG[26742][C-00000004] chan_sip.c: Processing session-level SDP v=0... UNSUPPORTED OR FAILED. [May 11 05:29:06] DEBUG[26742][C-00000004] chan_sip.c: Processing session-level SDP o=sipcli-Session 1152671529 2026884978 IN IP4 23.92.80.40... OK. [May 11 05:29:06] DEBUG[26742][C-00000004] chan_sip.c: Processing session-level SDP s=sipcli... UNSUPPORTED OR FAILED. [May 11 05:29:06] DEBUG[26742][C-00000004] netsock2.c: Splitting '23.92.80.40' into... [May 11 05:29:06] DEBUG[26742][C-00000004] netsock2.c: ...host '23.92.80.40' and port ''. [May 11 05:29:06] DEBUG[26742][C-00000004] chan_sip.c: Processing session-level SDP c=IN IP4 23.92.80.40... OK. [May 11 05:29:06] DEBUG[26742][C-00000004] chan_sip.c: Processing session-level SDP t=0 0... UNSUPPORTED OR FAILED. [May 11 05:29:06] DEBUG[26742][C-00000004] rtp_engine.c: Setting payload 18 (0x7f79c4008778) based on m type on 0x7f798ba14b60 [May 11 05:29:06] DEBUG[26742][C-00000004] rtp_engine.c: Setting payload 0 (0x7f79c4008bf8) based on m type on 0x7f798ba14b60 [May 11 05:29:06] DEBUG[26742][C-00000004] rtp_engine.c: Setting payload 8 (0x7f79c4009058) based on m type on 0x7f798ba14b60 [May 11 05:29:06] DEBUG[26742][C-00000004] rtp_engine.c: Setting payload 101 (0x7f79c40094b8) based on m type on 0x7f798ba14b60 [May 11 05:29:06] DEBUG[26742][C-00000004] chan_sip.c: Processing media-level (audio) SDP a=fmtp:101 0-15... UNSUPPORTED OR FAILED. [May 11 05:29:06] DEBUG[26742][C-00000004] chan_sip.c: Processing media-level (audio) SDP a=rtpmap:18 G729/8000... OK. [May 11 05:29:06] DEBUG[26742][C-00000004] chan_sip.c: Processing media-level (audio) SDP a=rtpmap:0 PCMU/8000... OK. [May 11 05:29:06] DEBUG[26742][C-00000004] chan_sip.c: Processing media-level (audio) SDP a=rtpmap:8 PCMA/8000... OK. [May 11 05:29:06] DEBUG[26742][C-00000004] chan_sip.c: Processing media-level (audio) SDP a=rtpmap:101 telephone-event/8000... OK. [May 11 05:29:06] DEBUG[26742][C-00000004] chan_sip.c: Processing media-level (audio) SDP a=ptime:20... OK. [May 11 05:29:06] DEBUG[26742][C-00000004] chan_sip.c: Processing media-level (audio) SDP a=sendrecv... OK. [May 11 05:29:06] DEBUG[26742][C-00000004] res_rtp_asterisk.c: Setting RTCP address on RTP instance '0x7f79c400e958' [May 11 05:29:06] DEBUG[26742][C-00000004] rtp_engine.c: Copying payload 0 (0x7f79c4008778) from 0x7f798ba14b60 to 0x7f79c400eb20 [May 11 05:29:06] DEBUG[26742][C-00000004] rtp_engine.c: Copying payload 8 (0x7f79c4008bf8) from 0x7f798ba14b60 to 0x7f79c400eb20 [May 11 05:29:06] DEBUG[26742][C-00000004] rtp_engine.c: Copying payload 18 (0x7f79c4009cf8) from 0x7f798ba14b60 to 0x7f79c400eb20 [May 11 05:29:06] DEBUG[26742][C-00000004] rtp_engine.c: Copying payload 101 (0x7f79c4009058) from 0x7f798ba14b60 to 0x7f79c400eb20 [May 11 05:29:06] DEBUG[26742][C-00000004] res_rtp_asterisk.c: Ignoring duplicate RTCP property on RTP instance '0x7f79c400e958' [May 11 05:29:06] DEBUG[26742][C-00000004] chan_sip.c: We're settling with these formats: (ulaw|alaw) [May 11 05:29:06] DEBUG[26742][C-00000004] chan_sip.c: Checking SIP call limits for device [May 11 05:29:06] DEBUG[26742][C-00000004] chan_sip.c: Updating call counter for incoming call [May 11 05:29:06] DEBUG[26742][C-00000004] netsock2.c: Splitting '23.246.211.133' into... [May 11 05:29:06] DEBUG[26742][C-00000004] netsock2.c: ...host '23.246.211.133' and port ''. [May 11 05:29:06] DEBUG[26742][C-00000004] netsock2.c: Splitting '23.246.211.133' into... [May 11 05:29:06] DEBUG[26742][C-00000004] netsock2.c: ...host '23.246.211.133' and port ''. [May 11 05:29:06] DEBUG[26742][C-00000004] chan_sip.c: Trying to put 'SIP/2.0 404' onto UDP socket destined for 23.92.80.40:5074 [May 11 05:29:06] NOTICE[26742][C-00000004] chan_sip.c: Call from '' (23.92.80.40:5074) to extension '011972592180367' rejected because extension not found in context 'public'. [May 11 05:29:06] DEBUG[26742][C-00000004] chan_sip.c: Updating call counter for incoming call [May 11 05:29:07] DEBUG[26742] chan_sip.c: ** SIP timers: Rescheduling retransmission 2 to 1000 ms (t1 500 ms (Retrans id #27)) [May 11 05:29:07] DEBUG[26742] chan_sip.c: Trying to put 'SIP/2.0 404' onto UDP socket destined for 23.92.80.40:5074 [May 11 05:29:08] DEBUG[26742] chan_sip.c: ** SIP timers: Rescheduling retransmission 3 to 2000 ms (t1 500 ms (Retrans id #27)) [May 11 05:29:08] DEBUG[26742] chan_sip.c: Trying to put 'SIP/2.0 404' onto UDP socket destined for 23.92.80.40:5074 [May 11 05:29:10] DEBUG[26742] chan_sip.c: ** SIP timers: Rescheduling retransmission 4 to 4000 ms (t1 500 ms (Retrans id #27)) [May 11 05:29:10] DEBUG[26742] chan_sip.c: Trying to put 'SIP/2.0 404' onto UDP socket destined for 23.92.80.40:5074 [May 11 05:29:13] DEBUG[26908][C-00000003] channel.c: Hanging up channel 'SIP/101-00000001' [May 11 05:29:13] DEBUG[26908][C-00000003] chan_sip.c: Hangup call SIP/101-00000001, SIP callid 6d2e5c9c0eeda1a236ae6be12c98a599@23.246.211.133:5060 [May 11 05:29:13] DEBUG[26908][C-00000003] chan_sip.c: Hanging up channel in state Down (not UP) [May 11 05:29:13] DEBUG[26908][C-00000003] res_rtp_asterisk.c: Setting RTCP address on RTP instance '0x7f79ac004da8' [May 11 05:29:13] DEBUG[26908][C-00000003] app_dial.c: Exiting with DIALSTATUS=CONGESTION. [May 11 05:29:13] DEBUG[26908][C-00000003] chan_sip.c: Trying to put 'SIP/2.0 503' onto TCP socket destined for 159.122.0.248:39834 [May 11 05:29:13] DEBUG[26908][C-00000003] chan_sip.c: Setting SIP_ALREADYGONE on dialog 8810faf09024439785f9e48d845c119c [May 11 05:29:13] DEBUG[26908][C-00000003] channel.c: Soft-Hanging (0x01) up channel 'SIP/100-00000000' [May 11 05:29:13] DEBUG[26908][C-00000003] channel.c: Soft-Hanging (0x10) up channel 'SIP/100-00000000' [May 11 05:29:13] DEBUG[26908][C-00000003] channel.c: Hanging up channel 'SIP/100-00000000' [May 11 05:29:13] DEBUG[26908][C-00000003] chan_sip.c: Hangup call SIP/100-00000000, SIP callid 8810faf09024439785f9e48d845c119c [May 11 05:29:13] DEBUG[26908][C-00000003] res_rtp_asterisk.c: Setting RTCP address on RTP instance '0x7f79c0011598' [May 11 05:29:13] DEBUG[26713] cdr.c: Finalized CDR for SIP/100-00000000 - start 1431340121.651607 answer 0.000000 end 1431340153.657015 dispo FAILED [May 11 05:29:13] DEBUG[26713] cdr.c: Finalized CDR for SIP/101-00000001 - start 1431340121.654218 answer 0.000000 end 1431340153.657040 dispo FAILED [May 11 05:29:13] DEBUG[26713] cdr.c: CDR for SIP/101-00000001 is dialed and has no Party B; discarding [May 11 05:29:13] DEBUG[26700] threadpool.c: Increasing threadpool stasis-core's size by 1 [May 11 05:29:13] DEBUG[26712] devicestate.c: No provider found, checking channel drivers for SIP - 101 [May 11 05:29:13] DEBUG[26712] chan_sip.c: Checking device state for peer 101 [May 11 05:29:13] DEBUG[26712] devicestate.c: Changing state for SIP/101 - state 1 (Not in use) [May 11 05:29:13] DEBUG[26712] devicestate.c: No provider found, checking channel drivers for SIP - 100 [May 11 05:29:13] DEBUG[26712] chan_sip.c: Checking device state for peer 100 [May 11 05:29:13] DEBUG[26712] devicestate.c: Changing state for SIP/100 - state 1 (Not in use) [May 11 05:29:13] DEBUG[26712] devicestate.c: No provider found, checking channel drivers for SIP - 100 [May 11 05:29:13] DEBUG[26712] chan_sip.c: Checking device state for peer 100 [May 11 05:29:13] DEBUG[26712] devicestate.c: Changing state for SIP/100 - state 1 (Not in use) [May 11 05:29:13] DEBUG[26898] chan_sip.c: = Looking for Call ID: 8810faf09024439785f9e48d845c119c (Checking From) --From tag b87318a7fed544c0b67ae2b2e32b4e58 --To-tag as5c7fdad7 [May 11 05:29:13] DEBUG[26898][C-00000003] chan_sip.c: **** Received ACK (6) - Command in SIP ACK [May 11 05:29:13] DEBUG[26898][C-00000003] chan_sip.c: Stopping retransmission on '8810faf09024439785f9e48d845c119c' of Response 17476: Match Not Found [May 11 05:29:14] DEBUG[26742] chan_sip.c: ** SIP timers: Rescheduling retransmission 5 to 4000 ms (t1 500 ms (Retrans id #27)) [May 11 05:29:14] DEBUG[26742] chan_sip.c: Trying to put 'SIP/2.0 404' onto UDP socket destined for 23.92.80.40:5074 [May 11 05:29:14] DEBUG[26742] chan_sip.c: Session timer stopped: -1 - 8810faf09024439785f9e48d845c119c [May 11 05:29:14] DEBUG[26742] chan_sip.c: Destroying SIP dialog 8810faf09024439785f9e48d845c119c [May 11 05:29:14] DEBUG[26742] rtp_engine.c: Destroyed RTP instance '0x7f79c0011598' [May 11 05:29:18] DEBUG[26742] chan_sip.c: ** SIP timers: Rescheduling retransmission 6 to 4000 ms (t1 500 ms (Retrans id #27)) [May 11 05:29:18] DEBUG[26742] chan_sip.c: Trying to put 'SIP/2.0 404' onto UDP socket destined for 23.92.80.40:5074 [May 11 05:29:22] DEBUG[26742] chan_sip.c: ** SIP timers: Rescheduling retransmission 7 to 4000 ms (t1 500 ms (Retrans id #27)) [May 11 05:29:22] DEBUG[26742] chan_sip.c: Trying to put 'SIP/2.0 404' onto UDP socket destined for 23.92.80.40:5074 [May 11 05:29:25] DEBUG[26742] chan_sip.c: = Looking for Call ID: 32b595b21a7e6fb4b3a89a8c3c444916 (Checking From) --From tag af1f88b2 --To-tag [May 11 05:29:25] DEBUG[26742] acl.c: For destination '184.107.201.78', our source address is '23.246.211.133'. [May 11 05:29:25] DEBUG[26742] chan_sip.c: Setting AST_TRANSPORT_UDP with address 23.246.211.133:5060 [May 11 05:29:25] DEBUG[26742] netsock2.c: Splitting '184.107.201.78:5071' into... [May 11 05:29:25] DEBUG[26742] netsock2.c: ...host '184.107.201.78' and port '5071'. [May 11 05:29:25] DEBUG[26742] chan_sip.c: Allocating new SIP dialog for 32b595b21a7e6fb4b3a89a8c3c444916 - INVITE (No RTP) [May 11 05:29:25] DEBUG[26742][C-00000005] chan_sip.c: **** Received INVITE (5) - Command in SIP INVITE [May 11 05:29:25] DEBUG[26742][C-00000005] netsock2.c: Splitting '184.107.201.78:5071' into... [May 11 05:29:25] DEBUG[26742][C-00000005] netsock2.c: ...host '184.107.201.78' and port '5071'. [May 11 05:29:25] DEBUG[26742][C-00000005] netsock2.c: Splitting '23.246.211.133' into... [May 11 05:29:25] DEBUG[26742][C-00000005] netsock2.c: ...host '23.246.211.133' and port ''. [May 11 05:29:25] DEBUG[26742][C-00000005] rtp_engine.c: Using engine 'asterisk' for RTP instance '0x7f79c4004928' [May 11 05:29:25] DEBUG[26742][C-00000005] res_rtp_asterisk.c: Allocated port 16562 for RTP instance '0x7f79c4004928' [May 11 05:29:25] DEBUG[26742][C-00000005] rtp_engine.c: RTP instance '0x7f79c4004928' is setup and ready to go [May 11 05:29:25] DEBUG[26742][C-00000005] res_rtp_asterisk.c: Setup RTCP on RTP instance '0x7f79c4004928' [May 11 05:29:25] DEBUG[26742][C-00000005] chan_sip.c: Setting NAT on RTP to Off [May 11 05:29:25] DEBUG[26742][C-00000005] chan_sip.c: Processing session-level SDP v=0... UNSUPPORTED OR FAILED. [May 11 05:29:25] DEBUG[26742][C-00000005] chan_sip.c: Processing session-level SDP o=sipcli-Session 1038064928 1262993454 IN IP4 184.107.201.78... OK. [May 11 05:29:25] DEBUG[26742][C-00000005] chan_sip.c: Processing session-level SDP s=sipcli... UNSUPPORTED OR FAILED. [May 11 05:29:25] DEBUG[26742][C-00000005] netsock2.c: Splitting '184.107.201.78' into... [May 11 05:29:25] DEBUG[26742][C-00000005] netsock2.c: ...host '184.107.201.78' and port ''. [May 11 05:29:25] DEBUG[26742][C-00000005] chan_sip.c: Processing session-level SDP c=IN IP4 184.107.201.78... OK. [May 11 05:29:25] DEBUG[26742][C-00000005] chan_sip.c: Processing session-level SDP t=0 0... UNSUPPORTED OR FAILED. [May 11 05:29:25] DEBUG[26742][C-00000005] rtp_engine.c: Setting payload 18 (0x7f79c4008698) based on m type on 0x7f798ba14b60 [May 11 05:29:25] DEBUG[26742][C-00000005] rtp_engine.c: Setting payload 0 (0x7f79c4008f28) based on m type on 0x7f798ba14b60 [May 11 05:29:25] DEBUG[26742][C-00000005] rtp_engine.c: Setting payload 8 (0x7f79c4008fa8) based on m type on 0x7f798ba14b60 [May 11 05:29:25] DEBUG[26742][C-00000005] rtp_engine.c: Setting payload 101 (0x7f79c400a788) based on m type on 0x7f798ba14b60 [May 11 05:29:25] DEBUG[26742][C-00000005] chan_sip.c: Processing media-level (audio) SDP a=fmtp:101 0-15... UNSUPPORTED OR FAILED. [May 11 05:29:25] DEBUG[26742][C-00000005] chan_sip.c: Processing media-level (audio) SDP a=rtpmap:18 G729/8000... OK. [May 11 05:29:25] DEBUG[26742][C-00000005] chan_sip.c: Processing media-level (audio) SDP a=rtpmap:0 PCMU/8000... OK. [May 11 05:29:25] DEBUG[26742][C-00000005] chan_sip.c: Processing media-level (audio) SDP a=rtpmap:8 PCMA/8000... OK. [May 11 05:29:25] DEBUG[26742][C-00000005] chan_sip.c: Processing media-level (audio) SDP a=rtpmap:101 telephone-event/8000... OK. [May 11 05:29:25] DEBUG[26742][C-00000005] chan_sip.c: Processing media-level (audio) SDP a=ptime:20... OK. [May 11 05:29:25] DEBUG[26742][C-00000005] chan_sip.c: Processing media-level (audio) SDP a=sendrecv... OK. [May 11 05:29:25] DEBUG[26742][C-00000005] res_rtp_asterisk.c: Setting RTCP address on RTP instance '0x7f79c4004928' [May 11 05:29:25] DEBUG[26742][C-00000005] rtp_engine.c: Copying payload 0 (0x7f79c4008698) from 0x7f798ba14b60 to 0x7f79c4004af0 [May 11 05:29:25] DEBUG[26742][C-00000005] rtp_engine.c: Copying payload 8 (0x7f79c4008f28) from 0x7f798ba14b60 to 0x7f79c4004af0 [May 11 05:29:25] DEBUG[26742][C-00000005] rtp_engine.c: Copying payload 18 (0x7f79c400a808) from 0x7f798ba14b60 to 0x7f79c4004af0 [May 11 05:29:25] DEBUG[26742][C-00000005] rtp_engine.c: Copying payload 101 (0x7f79c4008fa8) from 0x7f798ba14b60 to 0x7f79c4004af0 [May 11 05:29:25] DEBUG[26742][C-00000005] res_rtp_asterisk.c: Ignoring duplicate RTCP property on RTP instance '0x7f79c4004928' [May 11 05:29:25] DEBUG[26742][C-00000005] chan_sip.c: We're settling with these formats: (ulaw|alaw) [May 11 05:29:25] DEBUG[26742][C-00000005] chan_sip.c: Checking SIP call limits for device [May 11 05:29:25] DEBUG[26742][C-00000005] chan_sip.c: Updating call counter for incoming call [May 11 05:29:25] DEBUG[26742][C-00000005] netsock2.c: Splitting '23.246.211.133' into... [May 11 05:29:25] DEBUG[26742][C-00000005] netsock2.c: ...host '23.246.211.133' and port ''. [May 11 05:29:25] DEBUG[26742][C-00000005] netsock2.c: Splitting '23.246.211.133' into... [May 11 05:29:25] DEBUG[26742][C-00000005] netsock2.c: ...host '23.246.211.133' and port ''. [May 11 05:29:25] DEBUG[26742][C-00000005] chan_sip.c: Trying to put 'SIP/2.0 404' onto UDP socket destined for 184.107.201.78:5071 [May 11 05:29:25] NOTICE[26742][C-00000005] chan_sip.c: Call from '' (184.107.201.78:5071) to extension '59972597365303' rejected because extension not found in context 'public'. [May 11 05:29:25] DEBUG[26742][C-00000005] chan_sip.c: Updating call counter for incoming call [May 11 05:29:25] DEBUG[26742] chan_sip.c: ** SIP timers: Rescheduling retransmission 2 to 1000 ms (t1 500 ms (Retrans id #31)) [May 11 05:29:25] DEBUG[26742] chan_sip.c: Trying to put 'SIP/2.0 404' onto UDP socket destined for 184.107.201.78:5071 [May 11 05:29:26] DEBUG[26742] chan_sip.c: ** SIP timers: Rescheduling retransmission 8 to 4000 ms (t1 500 ms (Retrans id #27)) [May 11 05:29:26] DEBUG[26742] chan_sip.c: Trying to put 'SIP/2.0 404' onto UDP socket destined for 23.92.80.40:5074 [May 11 05:29:26] DEBUG[26742] chan_sip.c: ** SIP timers: Rescheduling retransmission 3 to 2000 ms (t1 500 ms (Retrans id #31)) [May 11 05:29:26] DEBUG[26742] chan_sip.c: Trying to put 'SIP/2.0 404' onto UDP socket destined for 184.107.201.78:5071 [May 11 05:29:26] DEBUG[26742] chan_sip.c: Auto destroying SIP dialog '5e7d199ad708407bb3b940701faf6655' [May 11 05:29:26] DEBUG[26742] chan_sip.c: Destroying SIP dialog 5e7d199ad708407bb3b940701faf6655 [May 11 05:29:28] DEBUG[26742] chan_sip.c: ** SIP timers: Rescheduling retransmission 4 to 4000 ms (t1 500 ms (Retrans id #31)) [May 11 05:29:28] DEBUG[26742] chan_sip.c: Trying to put 'SIP/2.0 404' onto UDP socket destined for 184.107.201.78:5071 [May 11 05:29:30] DEBUG[26742] chan_sip.c: ** SIP timers: Rescheduling retransmission 9 to 4000 ms (t1 500 ms (Retrans id #27)) [May 11 05:29:30] DEBUG[26742] chan_sip.c: Trying to put 'SIP/2.0 404' onto UDP socket destined for 23.92.80.40:5074 [May 11 05:29:32] DEBUG[26742] chan_sip.c: ** SIP timers: Rescheduling retransmission 5 to 4000 ms (t1 500 ms (Retrans id #31)) [May 11 05:29:32] DEBUG[26742] chan_sip.c: Trying to put 'SIP/2.0 404' onto UDP socket destined for 184.107.201.78:5071 [May 11 05:29:33] DEBUG[26899] threadpool.c: Worker thread idle timeout reached. Dying. [May 11 05:29:33] DEBUG[26700] threadpool.c: Destroying worker thread 5 [May 11 05:29:33] DEBUG[26923] threadpool.c: Worker thread idle timeout reached. Dying. [May 11 05:29:33] DEBUG[26700] threadpool.c: Destroying worker thread 6 [May 11 05:29:34] DEBUG[26742] chan_sip.c: ** SIP timers: Rescheduling retransmission 10 to 4000 ms (t1 500 ms (Retrans id #27)) [May 11 05:29:34] DEBUG[26742] chan_sip.c: Trying to put 'SIP/2.0 404' onto UDP socket destined for 23.92.80.40:5074 [May 11 05:29:36] DEBUG[26742] chan_sip.c: ** SIP timers: Rescheduling retransmission 6 to 4000 ms (t1 500 ms (Retrans id #31)) [May 11 05:29:36] DEBUG[26742] chan_sip.c: Trying to put 'SIP/2.0 404' onto UDP socket destined for 184.107.201.78:5071 [May 11 05:29:38] DEBUG[26742] chan_sip.c: ** SIP timers: Rescheduling retransmission 11 to 4000 ms (t1 500 ms (Retrans id #27)) [May 11 05:29:38] DEBUG[26742] chan_sip.c: Trying to put 'SIP/2.0 404' onto UDP socket destined for 23.92.80.40:5074 [May 11 05:29:38] WARNING[26742] chan_sip.c: Retransmission timeout reached on transmission 8dfaff944b463ff04eceb547c50d5d90 for seqno 1 (Critical Response) -- See https://wiki.asterisk.org/wiki/display/AST/SIP+Retransmissions Packet timed out after 32000ms with no response [May 11 05:29:38] DEBUG[26742] chan_sip.c: Setting SIP_ALREADYGONE on dialog 8dfaff944b463ff04eceb547c50d5d90 [May 11 05:29:38] DEBUG[26742] chan_sip.c: Auto destroying SIP dialog '8dfaff944b463ff04eceb547c50d5d90' [May 11 05:29:38] DEBUG[26742] chan_sip.c: Destroying SIP dialog 8dfaff944b463ff04eceb547c50d5d90 [May 11 05:29:38] DEBUG[26742] rtp_engine.c: Destroyed RTP instance '0x7f79c400e958' [May 11 05:29:40] DEBUG[26742] chan_sip.c: ** SIP timers: Rescheduling retransmission 7 to 4000 ms (t1 500 ms (Retrans id #31)) [May 11 05:29:40] DEBUG[26742] chan_sip.c: Trying to put 'SIP/2.0 404' onto UDP socket destined for 184.107.201.78:5071 [May 11 05:29:44] DEBUG[26742] chan_sip.c: ** SIP timers: Rescheduling retransmission 8 to 4000 ms (t1 500 ms (Retrans id #31)) [May 11 05:29:44] DEBUG[26742] chan_sip.c: Trying to put 'SIP/2.0 404' onto UDP socket destined for 184.107.201.78:5071 [May 11 05:29:44] ERROR[26909] tcptls.c: Unable to connect SIP socket to 192.116.52.233:37342: Connection timed out [May 11 05:29:45] DEBUG[26742] chan_sip.c: Auto destroying SIP dialog '6d2e5c9c0eeda1a236ae6be12c98a599@23.246.211.133:5060' [May 11 05:29:45] DEBUG[26742] chan_sip.c: Destroying SIP dialog 6d2e5c9c0eeda1a236ae6be12c98a599@23.246.211.133:5060 [May 11 05:29:45] DEBUG[26742] rtp_engine.c: Destroyed RTP instance '0x7f79ac004da8' [May 11 05:29:48] DEBUG[26742] chan_sip.c: ** SIP timers: Rescheduling retransmission 9 to 4000 ms (t1 500 ms (Retrans id #31)) [May 11 05:29:48] DEBUG[26742] chan_sip.c: Trying to put 'SIP/2.0 404' onto UDP socket destined for 184.107.201.78:5071 [May 11 05:29:52] DEBUG[26742] chan_sip.c: ** SIP timers: Rescheduling retransmission 10 to 4000 ms (t1 500 ms (Retrans id #31)) [May 11 05:29:52] DEBUG[26742] chan_sip.c: Trying to put 'SIP/2.0 404' onto UDP socket destined for 184.107.201.78:5071 [May 11 05:29:56] DEBUG[26742] chan_sip.c: ** SIP timers: Rescheduling retransmission 11 to 4000 ms (t1 500 ms (Retrans id #31)) [May 11 05:29:56] DEBUG[26742] chan_sip.c: Trying to put 'SIP/2.0 404' onto UDP socket destined for 184.107.201.78:5071 [May 11 05:29:57] WARNING[26742] chan_sip.c: Retransmission timeout reached on transmission 32b595b21a7e6fb4b3a89a8c3c444916 for seqno 1 (Critical Response) -- See https://wiki.asterisk.org/wiki/display/AST/SIP+Retransmissions Packet timed out after 31999ms with no response [May 11 05:29:57] DEBUG[26742] chan_sip.c: Setting SIP_ALREADYGONE on dialog 32b595b21a7e6fb4b3a89a8c3c444916 [May 11 05:29:57] DEBUG[26742] chan_sip.c: Auto destroying SIP dialog '32b595b21a7e6fb4b3a89a8c3c444916' [May 11 05:29:57] DEBUG[26742] chan_sip.c: Destroying SIP dialog 32b595b21a7e6fb4b3a89a8c3c444916 [May 11 05:29:57] DEBUG[26742] rtp_engine.c: Destroyed RTP instance '0x7f79c4004928' [May 11 05:30:31] DEBUG[26742] chan_sip.c: = Looking for Call ID: c40f138d24363ee18cbb4e9c50202ac7 (Checking From) --From tag 9d202b95 --To-tag [May 11 05:30:31] DEBUG[26742] acl.c: For destination '184.107.201.78', our source address is '23.246.211.133'. [May 11 05:30:31] DEBUG[26742] chan_sip.c: Setting AST_TRANSPORT_UDP with address 23.246.211.133:5060 [May 11 05:30:31] DEBUG[26742] netsock2.c: Splitting '184.107.201.78:5071' into... [May 11 05:30:31] DEBUG[26742] netsock2.c: ...host '184.107.201.78' and port '5071'. [May 11 05:30:31] DEBUG[26742] chan_sip.c: Allocating new SIP dialog for c40f138d24363ee18cbb4e9c50202ac7 - INVITE (No RTP) [May 11 05:30:31] DEBUG[26742][C-00000006] chan_sip.c: **** Received INVITE (5) - Command in SIP INVITE [May 11 05:30:31] DEBUG[26742][C-00000006] netsock2.c: Splitting '184.107.201.78:5071' into... [May 11 05:30:31] DEBUG[26742][C-00000006] netsock2.c: ...host '184.107.201.78' and port '5071'. [May 11 05:30:31] DEBUG[26742][C-00000006] netsock2.c: Splitting '23.246.211.133' into... [May 11 05:30:31] DEBUG[26742][C-00000006] netsock2.c: ...host '23.246.211.133' and port ''. [May 11 05:30:31] DEBUG[26742][C-00000006] rtp_engine.c: Using engine 'asterisk' for RTP instance '0x7f79c40122b8' [May 11 05:30:31] DEBUG[26742][C-00000006] res_rtp_asterisk.c: Allocated port 13166 for RTP instance '0x7f79c40122b8' [May 11 05:30:31] DEBUG[26742][C-00000006] rtp_engine.c: RTP instance '0x7f79c40122b8' is setup and ready to go [May 11 05:30:31] DEBUG[26742][C-00000006] res_rtp_asterisk.c: Setup RTCP on RTP instance '0x7f79c40122b8' [May 11 05:30:31] DEBUG[26742][C-00000006] chan_sip.c: Setting NAT on RTP to Off [May 11 05:30:31] DEBUG[26742][C-00000006] chan_sip.c: Processing session-level SDP v=0... UNSUPPORTED OR FAILED. [May 11 05:30:31] DEBUG[26742][C-00000006] chan_sip.c: Processing session-level SDP o=sipcli-Session 1841010688 1920312735 IN IP4 184.107.201.78... OK. [May 11 05:30:31] DEBUG[26742][C-00000006] chan_sip.c: Processing session-level SDP s=sipcli... UNSUPPORTED OR FAILED. [May 11 05:30:31] DEBUG[26742][C-00000006] netsock2.c: Splitting '184.107.201.78' into... [May 11 05:30:31] DEBUG[26742][C-00000006] netsock2.c: ...host '184.107.201.78' and port ''. [May 11 05:30:31] DEBUG[26742][C-00000006] chan_sip.c: Processing session-level SDP c=IN IP4 184.107.201.78... OK. [May 11 05:30:31] DEBUG[26742][C-00000006] chan_sip.c: Processing session-level SDP t=0 0... UNSUPPORTED OR FAILED. [May 11 05:30:31] DEBUG[26742][C-00000006] rtp_engine.c: Setting payload 18 (0x7f79c4006188) based on m type on 0x7f798ba14b60 [May 11 05:30:31] DEBUG[26742][C-00000006] rtp_engine.c: Setting payload 0 (0x7f79c4006608) based on m type on 0x7f798ba14b60 [May 11 05:30:31] DEBUG[26742][C-00000006] rtp_engine.c: Setting payload 8 (0x7f79c4006a68) based on m type on 0x7f798ba14b60 [May 11 05:30:31] DEBUG[26742][C-00000006] rtp_engine.c: Setting payload 101 (0x7f79c4006ec8) based on m type on 0x7f798ba14b60 [May 11 05:30:31] DEBUG[26742][C-00000006] chan_sip.c: Processing media-level (audio) SDP a=fmtp:101 0-15... UNSUPPORTED OR FAILED. [May 11 05:30:31] DEBUG[26742][C-00000006] chan_sip.c: Processing media-level (audio) SDP a=rtpmap:18 G729/8000... OK. [May 11 05:30:31] DEBUG[26742][C-00000006] chan_sip.c: Processing media-level (audio) SDP a=rtpmap:0 PCMU/8000... OK. [May 11 05:30:31] DEBUG[26742][C-00000006] chan_sip.c: Processing media-level (audio) SDP a=rtpmap:8 PCMA/8000... OK. [May 11 05:30:31] DEBUG[26742][C-00000006] chan_sip.c: Processing media-level (audio) SDP a=rtpmap:101 telephone-event/8000... OK. [May 11 05:30:31] DEBUG[26742][C-00000006] chan_sip.c: Processing media-level (audio) SDP a=ptime:20... OK. [May 11 05:30:31] DEBUG[26742][C-00000006] chan_sip.c: Processing media-level (audio) SDP a=sendrecv... OK. [May 11 05:30:31] DEBUG[26742][C-00000006] res_rtp_asterisk.c: Setting RTCP address on RTP instance '0x7f79c40122b8' [May 11 05:30:31] DEBUG[26742][C-00000006] rtp_engine.c: Copying payload 0 (0x7f79c4006188) from 0x7f798ba14b60 to 0x7f79c4012480 [May 11 05:30:31] DEBUG[26742][C-00000006] rtp_engine.c: Copying payload 8 (0x7f79c4006608) from 0x7f798ba14b60 to 0x7f79c4012480 [May 11 05:30:31] DEBUG[26742][C-00000006] rtp_engine.c: Copying payload 18 (0x7f79c4007708) from 0x7f798ba14b60 to 0x7f79c4012480 [May 11 05:30:31] DEBUG[26742][C-00000006] rtp_engine.c: Copying payload 101 (0x7f79c4006a68) from 0x7f798ba14b60 to 0x7f79c4012480 [May 11 05:30:31] DEBUG[26742][C-00000006] res_rtp_asterisk.c: Ignoring duplicate RTCP property on RTP instance '0x7f79c40122b8' [May 11 05:30:31] DEBUG[26742][C-00000006] chan_sip.c: We're settling with these formats: (ulaw|alaw) [May 11 05:30:31] DEBUG[26742][C-00000006] chan_sip.c: Checking SIP call limits for device [May 11 05:30:31] DEBUG[26742][C-00000006] chan_sip.c: Updating call counter for incoming call [May 11 05:30:31] DEBUG[26742][C-00000006] netsock2.c: Splitting '23.246.211.133' into... [May 11 05:30:31] DEBUG[26742][C-00000006] netsock2.c: ...host '23.246.211.133' and port ''. [May 11 05:30:31] DEBUG[26742][C-00000006] netsock2.c: Splitting '23.246.211.133' into... [May 11 05:30:31] DEBUG[26742][C-00000006] netsock2.c: ...host '23.246.211.133' and port ''. [May 11 05:30:31] DEBUG[26742][C-00000006] chan_sip.c: Trying to put 'SIP/2.0 404' onto UDP socket destined for 184.107.201.78:5071 [May 11 05:30:31] NOTICE[26742][C-00000006] chan_sip.c: Call from '' (184.107.201.78:5071) to extension '60972597365303' rejected because extension not found in context 'public'. [May 11 05:30:31] DEBUG[26742][C-00000006] chan_sip.c: Updating call counter for incoming call [May 11 05:30:31] DEBUG[26742] chan_sip.c: ** SIP timers: Rescheduling retransmission 2 to 1000 ms (t1 500 ms (Retrans id #33)) [May 11 05:30:31] DEBUG[26742] chan_sip.c: Trying to put 'SIP/2.0 404' onto UDP socket destined for 184.107.201.78:5071 [May 11 05:30:32] DEBUG[26742] chan_sip.c: ** SIP timers: Rescheduling retransmission 3 to 2000 ms (t1 500 ms (Retrans id #33)) [May 11 05:30:32] DEBUG[26742] chan_sip.c: Trying to put 'SIP/2.0 404' onto UDP socket destined for 184.107.201.78:5071 [May 11 05:30:34] DEBUG[26742] chan_sip.c: ** SIP timers: Rescheduling retransmission 4 to 4000 ms (t1 500 ms (Retrans id #33)) [May 11 05:30:34] DEBUG[26742] chan_sip.c: Trying to put 'SIP/2.0 404' onto UDP socket destined for 184.107.201.78:5071 [May 11 05:30:38] DEBUG[26742] chan_sip.c: ** SIP timers: Rescheduling retransmission 5 to 4000 ms (t1 500 ms (Retrans id #33)) [May 11 05:30:38] DEBUG[26742] chan_sip.c: Trying to put 'SIP/2.0 404' onto UDP socket destined for 184.107.201.78:5071 [May 11 05:30:42] DEBUG[26742] chan_sip.c: ** SIP timers: Rescheduling retransmission 6 to 4000 ms (t1 500 ms (Retrans id #33)) [May 11 05:30:42] DEBUG[26742] chan_sip.c: Trying to put 'SIP/2.0 404' onto UDP socket destined for 184.107.201.78:5071 [May 11 05:30:45] DEBUG[26898] chan_sip.c: = Looking for Call ID: PYf6ZjIgNwjW9dDDQLAYR2Lyr7WyqnXC (Checking From) --From tag ROXYjaenTkEbEFU438jE9CyWHfojVxTz --To-tag [May 11 05:30:45] DEBUG[26898] acl.c: For destination '159.122.0.248', our source address is '23.246.211.133'. [May 11 05:30:45] DEBUG[26898] chan_sip.c: Setting AST_TRANSPORT_TCP with address 23.246.211.133:5060 [May 11 05:30:45] DEBUG[26898] netsock2.c: Splitting '159.122.0.248:5060' into... [May 11 05:30:45] DEBUG[26898] netsock2.c: ...host '159.122.0.248' and port '5060'. [May 11 05:30:45] DEBUG[26898] chan_sip.c: Allocating new SIP dialog for PYf6ZjIgNwjW9dDDQLAYR2Lyr7WyqnXC - REGISTER (No RTP) [May 11 05:30:45] DEBUG[26898] chan_sip.c: **** Received REGISTER (2) - Command in SIP REGISTER [May 11 05:30:45] DEBUG[26898] netsock2.c: Splitting '159.122.0.248:5060' into... [May 11 05:30:45] DEBUG[26898] netsock2.c: ...host '159.122.0.248' and port '5060'. [May 11 05:30:45] DEBUG[26898] netsock2.c: Splitting '23.246.211.133' into... [May 11 05:30:45] DEBUG[26898] netsock2.c: ...host '23.246.211.133' and port ''. [May 11 05:30:45] DEBUG[26898] chan_sip.c: Trying to put 'SIP/2.0 401' onto TCP socket destined for 159.122.0.248:39834 [May 11 05:30:45] DEBUG[26898] chan_sip.c: = Looking for Call ID: PYf6ZjIgNwjW9dDDQLAYR2Lyr7WyqnXC (Checking From) --From tag ROXYjaenTkEbEFU438jE9CyWHfojVxTz --To-tag [May 11 05:30:45] DEBUG[26898] chan_sip.c: **** Received REGISTER (2) - Command in SIP REGISTER [May 11 05:30:45] DEBUG[26898] netsock2.c: Splitting '159.122.0.248:5060' into... [May 11 05:30:45] DEBUG[26898] netsock2.c: ...host '159.122.0.248' and port '5060'. [May 11 05:30:45] DEBUG[26898] netsock2.c: Splitting '23.246.211.133' into... [May 11 05:30:45] DEBUG[26898] netsock2.c: ...host '23.246.211.133' and port ''. [May 11 05:30:45] DEBUG[26898] chan_sip.c: Store REGISTER's Contact header for call routing. [May 11 05:30:45] DEBUG[26898] netsock2.c: Splitting '192.116.52.233:37342' into... [May 11 05:30:45] DEBUG[26898] netsock2.c: ...host '192.116.52.233' and port '37342'. [May 11 05:30:45] DEBUG[26898] chan_sip.c: build_path: try to build pre-loaded route-set by parsing Path headers [May 11 05:30:45] NOTICE[26898] chan_sip.c: No field 'Path' present to copy [May 11 05:30:45] DEBUG[26898] chan_sip.c: Trying to put 'SIP/2.0 200' onto TCP socket destined for 159.122.0.248:39834 [May 11 05:30:45] DEBUG[26700] threadpool.c: Increasing threadpool stasis-core's size by 1 [May 11 05:30:45] DEBUG[26712] devicestate.c: No provider found, checking channel drivers for SIP - 101 [May 11 05:30:45] DEBUG[26712] chan_sip.c: Checking device state for peer 101 [May 11 05:30:45] DEBUG[26712] devicestate.c: Changing state for SIP/101 - state 1 (Not in use) [May 11 05:30:46] DEBUG[26898] chan_sip.c: = Looking for Call ID: FvpQtlP6qYTTud-qrL.HRwR2My6U2V0H (Checking From) --From tag BcbSH.I-stahqPykQnXeEu6iGD1.oQe1 --To-tag [May 11 05:30:46] DEBUG[26898] acl.c: For destination '159.122.0.248', our source address is '23.246.211.133'. [May 11 05:30:46] DEBUG[26898] chan_sip.c: Setting AST_TRANSPORT_TCP with address 23.246.211.133:5060 [May 11 05:30:46] DEBUG[26898] netsock2.c: Splitting '159.122.0.248:5060' into... [May 11 05:30:46] DEBUG[26898] netsock2.c: ...host '159.122.0.248' and port '5060'. [May 11 05:30:46] DEBUG[26898] chan_sip.c: Allocating new SIP dialog for FvpQtlP6qYTTud-qrL.HRwR2My6U2V0H - SUBSCRIBE (No RTP) [May 11 05:30:46] DEBUG[26898] chan_sip.c: **** Received SUBSCRIBE (10) - Command in SIP SUBSCRIBE [May 11 05:30:46] DEBUG[26898] netsock2.c: Splitting '159.122.0.248:5060' into... [May 11 05:30:46] DEBUG[26898] netsock2.c: ...host '159.122.0.248' and port '5060'. [May 11 05:30:46] DEBUG[26898] sip/route.c: sip_route_process_header: [May 11 05:30:46] DEBUG[26898] netsock2.c: Splitting '23.246.211.133' into... [May 11 05:30:46] DEBUG[26898] netsock2.c: ...host '23.246.211.133' and port ''. [May 11 05:30:46] DEBUG[26898] chan_sip.c: Trying to put 'SIP/2.0 401' onto TCP socket destined for 159.122.0.248:39834 [May 11 05:30:46] DEBUG[26898] chan_sip.c: = Looking for Call ID: FvpQtlP6qYTTud-qrL.HRwR2My6U2V0H (Checking From) --From tag BcbSH.I-stahqPykQnXeEu6iGD1.oQe1 --To-tag [May 11 05:30:46] DEBUG[26898] netsock2.c: Splitting '23.246.211.133' into... [May 11 05:30:46] DEBUG[26898] netsock2.c: ...host '23.246.211.133' and port ''. [May 11 05:30:46] DEBUG[26898] netsock2.c: Splitting '23.246.211.133' into... [May 11 05:30:46] DEBUG[26898] netsock2.c: ...host '23.246.211.133' and port ''. [May 11 05:30:46] DEBUG[26898] chan_sip.c: **** Received SUBSCRIBE (10) - Command in SIP SUBSCRIBE [May 11 05:30:46] DEBUG[26898] netsock2.c: Splitting '159.122.0.248:5060' into... [May 11 05:30:46] DEBUG[26898] netsock2.c: ...host '159.122.0.248' and port '5060'. [May 11 05:30:46] DEBUG[26898] chan_sip.c: build_route: Retaining previous route: [May 11 05:30:46] DEBUG[26898] netsock2.c: Splitting '23.246.211.133' into... [May 11 05:30:46] DEBUG[26898] netsock2.c: ...host '23.246.211.133' and port ''. [May 11 05:30:46] DEBUG[26898] chan_sip.c: Trying to put 'SIP/2.0 404' onto TCP socket destined for 159.122.0.248:39834 [May 11 05:30:46] NOTICE[26898] chan_sip.c: Received SIP subscribe for peer without mailbox: 101 [May 11 05:30:46] DEBUG[26742] chan_sip.c: ** SIP timers: Rescheduling retransmission 7 to 4000 ms (t1 500 ms (Retrans id #33)) [May 11 05:30:46] DEBUG[26742] chan_sip.c: Trying to put 'SIP/2.0 404' onto UDP socket destined for 184.107.201.78:5071 [May 11 05:30:46] DEBUG[26742] chan_sip.c: Destroying SIP dialog FvpQtlP6qYTTud-qrL.HRwR2My6U2V0H [May 11 05:30:50] DEBUG[26742] chan_sip.c: ** SIP timers: Rescheduling retransmission 8 to 4000 ms (t1 500 ms (Retrans id #33)) [May 11 05:30:50] DEBUG[26742] chan_sip.c: Trying to put 'SIP/2.0 404' onto UDP socket destined for 184.107.201.78:5071 [May 11 05:30:54] DEBUG[26742] chan_sip.c: ** SIP timers: Rescheduling retransmission 9 to 4000 ms (t1 500 ms (Retrans id #33)) [May 11 05:30:54] DEBUG[26742] chan_sip.c: Trying to put 'SIP/2.0 404' onto UDP socket destined for 184.107.201.78:5071 [May 11 05:30:58] DEBUG[26742] chan_sip.c: ** SIP timers: Rescheduling retransmission 10 to 4000 ms (t1 500 ms (Retrans id #33)) [May 11 05:30:58] DEBUG[26742] chan_sip.c: Trying to put 'SIP/2.0 404' onto UDP socket destined for 184.107.201.78:5071 [May 11 05:31:02] DEBUG[26742] chan_sip.c: ** SIP timers: Rescheduling retransmission 11 to 4000 ms (t1 500 ms (Retrans id #33)) [May 11 05:31:02] DEBUG[26742] chan_sip.c: Trying to put 'SIP/2.0 404' onto UDP socket destined for 184.107.201.78:5071 [May 11 05:31:03] WARNING[26742] chan_sip.c: Retransmission timeout reached on transmission c40f138d24363ee18cbb4e9c50202ac7 for seqno 1 (Critical Response) -- See https://wiki.asterisk.org/wiki/display/AST/SIP+Retransmissions Packet timed out after 31999ms with no response [May 11 05:31:03] DEBUG[26742] chan_sip.c: Setting SIP_ALREADYGONE on dialog c40f138d24363ee18cbb4e9c50202ac7 [May 11 05:31:03] DEBUG[26742] chan_sip.c: Auto destroying SIP dialog 'c40f138d24363ee18cbb4e9c50202ac7' [May 11 05:31:03] DEBUG[26742] chan_sip.c: Destroying SIP dialog c40f138d24363ee18cbb4e9c50202ac7 [May 11 05:31:03] DEBUG[26742] rtp_engine.c: Destroyed RTP instance '0x7f79c40122b8' [May 11 05:31:05] DEBUG[27071] threadpool.c: Worker thread idle timeout reached. Dying. [May 11 05:31:05] DEBUG[26700] threadpool.c: Destroying worker thread 7