[Jan 21 17:16:44] Asterisk 13.7.0 built by root @ debian1 on a i686 running Linux on 2016-01-21 14:51:32 UTC [Jan 21 17:16:44] DEBUG[31336] config.c: Parsing /etc/asterisk/logger.conf [Jan 21 17:16:53] DEBUG[31319] chan_sip.c: Header 0 [ 35]: INVITE sip:123@10.24.17.195 SIP/2.0 [Jan 21 17:16:53] DEBUG[31319] chan_sip.c: Header 1 [ 88]: Via: SIP/2.0/UDP 10.24.18.16:5060;rport;branch=z9hG4bKPjVR2HDliMH6imPphN1YFyb-UIP8XF34a3 [Jan 21 17:16:53] DEBUG[31319] chan_sip.c: Header 2 [ 16]: Max-Forwards: 70 [Jan 21 17:16:53] DEBUG[31319] chan_sip.c: Header 3 [ 71]: From: "333" ;tag=Q9smPlsbBZ-xV3mr6py9Q.7x8CQidAcU [Jan 21 17:16:53] DEBUG[31319] chan_sip.c: Header 4 [ 26]: To: [Jan 21 17:16:53] DEBUG[31319] chan_sip.c: Header 5 [ 44]: Contact: "333" [Jan 21 17:16:53] DEBUG[31319] chan_sip.c: Header 6 [ 41]: Call-ID: yjo08nkBGlkgv1gORGcfATVEbAUmGzZb [Jan 21 17:16:53] DEBUG[31319] chan_sip.c: Header 7 [ 18]: CSeq: 29297 INVITE [Jan 21 17:16:53] DEBUG[31319] chan_sip.c: Header 8 [ 90]: Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, SUBSCRIBE, NOTIFY, REFER, MESSAGE, OPTIONS [Jan 21 17:16:53] DEBUG[31319] chan_sip.c: Header 9 [ 46]: Supported: replaces, 100rel, timer, norefersub [Jan 21 17:16:53] DEBUG[31319] chan_sip.c: Header 10 [ 21]: Session-Expires: 1800 [Jan 21 17:16:53] DEBUG[31319] chan_sip.c: Header 11 [ 10]: Min-SE: 90 [Jan 21 17:16:53] DEBUG[31319] chan_sip.c: Header 12 [ 36]: User-Agent: Digium D40 1_4_2_0_63880 [Jan 21 17:16:53] DEBUG[31319] chan_sip.c: Header 13 [ 29]: Content-Type: application/sdp [Jan 21 17:16:53] DEBUG[31319] chan_sip.c: Header 14 [ 19]: Content-Length: 430 [Jan 21 17:16:53] DEBUG[31319] chan_sip.c: Header 15 [ 0]: [Jan 21 17:16:53] DEBUG[31319] chan_sip.c: Body 0 [ 3]: v=0 [Jan 21 17:16:53] DEBUG[31319] chan_sip.c: Body 1 [ 42]: o=- 148789504 148789504 IN IP4 10.24.18.16 [Jan 21 17:16:53] DEBUG[31319] chan_sip.c: Body 2 [ 8]: s=digphn [Jan 21 17:16:53] DEBUG[31319] chan_sip.c: Body 3 [ 20]: c=IN IP4 10.24.18.16 [Jan 21 17:16:53] DEBUG[31319] chan_sip.c: Body 4 [ 5]: t=0 0 [Jan 21 17:16:53] DEBUG[31319] chan_sip.c: Body 5 [ 9]: a=X-nat:0 [Jan 21 17:16:53] DEBUG[31319] chan_sip.c: Body 6 [ 46]: m=audio 4036 RTP/AVP 111 18 0 58 118 9 8 58 96 [Jan 21 17:16:53] DEBUG[31319] chan_sip.c: Body 7 [ 30]: a=rtcp:4037 IN IP4 10.24.18.16 [Jan 21 17:16:53] DEBUG[31319] chan_sip.c: Body 8 [ 25]: a=rtpmap:111 G726-32/8000 [Jan 21 17:16:53] DEBUG[31319] chan_sip.c: Body 9 [ 21]: a=rtpmap:18 G729/8000 [Jan 21 17:16:53] DEBUG[31319] chan_sip.c: Body 10 [ 20]: a=rtpmap:0 PCMU/8000 [Jan 21 17:16:53] DEBUG[31319] chan_sip.c: Body 11 [ 21]: a=rtpmap:58 L16/16000 [Jan 21 17:16:53] DEBUG[31319] chan_sip.c: Body 12 [ 21]: a=rtpmap:118 L16/8000 [Jan 21 17:16:53] DEBUG[31319] chan_sip.c: Body 13 [ 20]: a=rtpmap:9 G722/8000 [Jan 21 17:16:53] DEBUG[31319] chan_sip.c: Body 14 [ 20]: a=rtpmap:8 PCMA/8000 [Jan 21 17:16:53] DEBUG[31319] chan_sip.c: Body 15 [ 25]: a=rtpmap:58 L16-256/16000 [Jan 21 17:16:53] DEBUG[31319] chan_sip.c: Body 16 [ 10]: a=sendrecv [Jan 21 17:16:53] DEBUG[31319] chan_sip.c: Body 17 [ 32]: a=rtpmap:96 telephone-event/8000 [Jan 21 17:16:53] DEBUG[31319] chan_sip.c: Body 18 [ 14]: a=fmtp:96 0-15 [Jan 21 17:16:53] DEBUG[31319] chan_sip.c: = Looking for Call ID: yjo08nkBGlkgv1gORGcfATVEbAUmGzZb (Checking From) --From tag Q9smPlsbBZ-xV3mr6py9Q.7x8CQidAcU --To-tag [Jan 21 17:16:53] DEBUG[31319] acl.c: For destination '10.24.18.16', our source address is '10.24.17.195'. [Jan 21 17:16:53] DEBUG[31319] chan_sip.c: Setting AST_TRANSPORT_UDP with address 10.24.17.195:5060 [Jan 21 17:16:53] DEBUG[31319] netsock2.c: Splitting '10.24.18.16:5060' into... [Jan 21 17:16:53] DEBUG[31319] netsock2.c: ...host '10.24.18.16' and port '5060'. [Jan 21 17:16:53] DEBUG[31319] chan_sip.c: Allocating new SIP dialog for yjo08nkBGlkgv1gORGcfATVEbAUmGzZb - INVITE (No RTP) [Jan 21 17:16:53] DEBUG[31319][C-00000000] chan_sip.c: **** Received INVITE (5) - Command in SIP INVITE [Jan 21 17:16:53] DEBUG[31319][C-00000000] sip/reqresp_parser.c: Begin: parsing SIP "Supported: replaces, 100rel, timer, norefersub" [Jan 21 17:16:53] DEBUG[31319][C-00000000] sip/reqresp_parser.c: Found SIP option: -replaces- [Jan 21 17:16:53] DEBUG[31319][C-00000000] sip/reqresp_parser.c: Matched SIP option: replaces [Jan 21 17:16:53] DEBUG[31319][C-00000000] sip/reqresp_parser.c: Found SIP option: -100rel- [Jan 21 17:16:53] DEBUG[31319][C-00000000] sip/reqresp_parser.c: Matched SIP option: 100rel [Jan 21 17:16:53] DEBUG[31319][C-00000000] sip/reqresp_parser.c: Found SIP option: -timer- [Jan 21 17:16:53] DEBUG[31319][C-00000000] sip/reqresp_parser.c: Matched SIP option: timer [Jan 21 17:16:53] DEBUG[31319][C-00000000] sip/reqresp_parser.c: Found SIP option: -norefersub- [Jan 21 17:16:53] DEBUG[31319][C-00000000] sip/reqresp_parser.c: Matched SIP option: norefersub [Jan 21 17:16:53] DEBUG[31319][C-00000000] netsock2.c: Splitting '10.24.18.16:5060' into... [Jan 21 17:16:53] DEBUG[31319][C-00000000] netsock2.c: ...host '10.24.18.16' and port '5060'. [Jan 21 17:16:53] DEBUG[31319][C-00000000] chan_sip.c: Initializing initreq for method INVITE - callid yjo08nkBGlkgv1gORGcfATVEbAUmGzZb [Jan 21 17:16:53] DEBUG[31319][C-00000000] netsock2.c: Splitting '10.24.17.195' into... [Jan 21 17:16:53] DEBUG[31319][C-00000000] netsock2.c: ...host '10.24.17.195' and port ''. [Jan 21 17:16:53] DEBUG[31319][C-00000000] chan_sip.c: *** SIP TIMER: Initializing retransmit timer on packet: Id #12 [Jan 21 17:16:53] DEBUG[31319][C-00000000] chan_sip.c: Trying to put 'SIP/2.0 401' onto UDP socket destined for 10.24.18.16:5060 [Jan 21 17:16:53] DEBUG[31319] chan_sip.c: Header 0 [ 32]: ACK sip:123@10.24.17.195 SIP/2.0 [Jan 21 17:16:53] DEBUG[31319] chan_sip.c: Header 1 [ 88]: Via: SIP/2.0/UDP 10.24.18.16:5060;rport;branch=z9hG4bKPjVR2HDliMH6imPphN1YFyb-UIP8XF34a3 [Jan 21 17:16:53] DEBUG[31319] chan_sip.c: Header 2 [ 16]: Max-Forwards: 70 [Jan 21 17:16:53] DEBUG[31319] chan_sip.c: Header 3 [ 71]: From: "333" ;tag=Q9smPlsbBZ-xV3mr6py9Q.7x8CQidAcU [Jan 21 17:16:53] DEBUG[31319] chan_sip.c: Header 4 [ 41]: To: ;tag=as486618b1 [Jan 21 17:16:53] DEBUG[31319] chan_sip.c: Header 5 [ 41]: Call-ID: yjo08nkBGlkgv1gORGcfATVEbAUmGzZb [Jan 21 17:16:53] DEBUG[31319] chan_sip.c: Header 6 [ 15]: CSeq: 29297 ACK [Jan 21 17:16:53] DEBUG[31319] chan_sip.c: Header 7 [ 17]: Content-Length: 0 [Jan 21 17:16:53] DEBUG[31319] chan_sip.c: = Looking for Call ID: yjo08nkBGlkgv1gORGcfATVEbAUmGzZb (Checking From) --From tag Q9smPlsbBZ-xV3mr6py9Q.7x8CQidAcU --To-tag as486618b1 [Jan 21 17:16:53] DEBUG[31319][C-00000000] chan_sip.c: **** Received ACK (6) - Command in SIP ACK [Jan 21 17:16:53] DEBUG[31319][C-00000000] chan_sip.c: ** SIP TIMER: Cancelling retransmit of packet (reply received) Retransid #12 [Jan 21 17:16:53] DEBUG[31319][C-00000000] chan_sip.c: Stopping retransmission on 'yjo08nkBGlkgv1gORGcfATVEbAUmGzZb' of Response 29297: Match Found [Jan 21 17:16:53] DEBUG[31319] chan_sip.c: Header 0 [ 35]: INVITE sip:123@10.24.17.195 SIP/2.0 [Jan 21 17:16:53] DEBUG[31319] chan_sip.c: Header 1 [ 88]: Via: SIP/2.0/UDP 10.24.18.16:5060;rport;branch=z9hG4bKPj0lC6A2VvqVmm7B-w2LlyJwk8uGRHr1c7 [Jan 21 17:16:53] DEBUG[31319] chan_sip.c: Header 2 [ 16]: Max-Forwards: 70 [Jan 21 17:16:53] DEBUG[31319] chan_sip.c: Header 3 [ 71]: From: "333" ;tag=Q9smPlsbBZ-xV3mr6py9Q.7x8CQidAcU [Jan 21 17:16:53] DEBUG[31319] chan_sip.c: Header 4 [ 26]: To: [Jan 21 17:16:53] DEBUG[31319] chan_sip.c: Header 5 [ 44]: Contact: "333" [Jan 21 17:16:53] DEBUG[31319] chan_sip.c: Header 6 [ 41]: Call-ID: yjo08nkBGlkgv1gORGcfATVEbAUmGzZb [Jan 21 17:16:53] DEBUG[31319] chan_sip.c: Header 7 [ 18]: CSeq: 29298 INVITE [Jan 21 17:16:53] DEBUG[31319] chan_sip.c: Header 8 [ 90]: Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, SUBSCRIBE, NOTIFY, REFER, MESSAGE, OPTIONS [Jan 21 17:16:53] DEBUG[31319] chan_sip.c: Header 9 [ 46]: Supported: replaces, 100rel, timer, norefersub [Jan 21 17:16:53] DEBUG[31319] chan_sip.c: Header 10 [ 21]: Session-Expires: 1800 [Jan 21 17:16:53] DEBUG[31319] chan_sip.c: Header 11 [ 10]: Min-SE: 90 [Jan 21 17:16:53] DEBUG[31319] chan_sip.c: Header 12 [ 36]: User-Agent: Digium D40 1_4_2_0_63880 [Jan 21 17:16:53] DEBUG[31319] chan_sip.c: Header 13 [160]: Authorization: Digest username="333", realm="asterisk", nonce="7ea9c3a4", uri="sip:123@10.24.17.195", response="c40f82530bc5bfd19281d80bdbe81d22", algorithm=MD5 [Jan 21 17:16:53] DEBUG[31319] chan_sip.c: Header 14 [ 29]: Content-Type: application/sdp [Jan 21 17:16:53] DEBUG[31319] chan_sip.c: Header 15 [ 19]: Content-Length: 430 [Jan 21 17:16:53] DEBUG[31319] chan_sip.c: Header 16 [ 0]: [Jan 21 17:16:53] DEBUG[31319] chan_sip.c: Body 0 [ 3]: v=0 [Jan 21 17:16:53] DEBUG[31319] chan_sip.c: Body 1 [ 42]: o=- 148789504 148789504 IN IP4 10.24.18.16 [Jan 21 17:16:53] DEBUG[31319] chan_sip.c: Body 2 [ 8]: s=digphn [Jan 21 17:16:53] DEBUG[31319] chan_sip.c: Body 3 [ 20]: c=IN IP4 10.24.18.16 [Jan 21 17:16:53] DEBUG[31319] chan_sip.c: Body 4 [ 5]: t=0 0 [Jan 21 17:16:53] DEBUG[31319] chan_sip.c: Body 5 [ 9]: a=X-nat:0 [Jan 21 17:16:53] DEBUG[31319] chan_sip.c: Body 6 [ 46]: m=audio 4036 RTP/AVP 111 18 0 58 118 9 8 58 96 [Jan 21 17:16:53] DEBUG[31319] chan_sip.c: Body 7 [ 30]: a=rtcp:4037 IN IP4 10.24.18.16 [Jan 21 17:16:53] DEBUG[31319] chan_sip.c: Body 8 [ 25]: a=rtpmap:111 G726-32/8000 [Jan 21 17:16:53] DEBUG[31319] chan_sip.c: Body 9 [ 21]: a=rtpmap:18 G729/8000 [Jan 21 17:16:53] DEBUG[31319] chan_sip.c: Body 10 [ 20]: a=rtpmap:0 PCMU/8000 [Jan 21 17:16:53] DEBUG[31319] chan_sip.c: Body 11 [ 21]: a=rtpmap:58 L16/16000 [Jan 21 17:16:53] DEBUG[31319] chan_sip.c: Body 12 [ 21]: a=rtpmap:118 L16/8000 [Jan 21 17:16:53] DEBUG[31319] chan_sip.c: Body 13 [ 20]: a=rtpmap:9 G722/8000 [Jan 21 17:16:53] DEBUG[31319] chan_sip.c: Body 14 [ 20]: a=rtpmap:8 PCMA/8000 [Jan 21 17:16:53] DEBUG[31319] chan_sip.c: Body 15 [ 25]: a=rtpmap:58 L16-256/16000 [Jan 21 17:16:53] DEBUG[31319] chan_sip.c: Body 16 [ 10]: a=sendrecv [Jan 21 17:16:53] DEBUG[31319] chan_sip.c: Body 17 [ 32]: a=rtpmap:96 telephone-event/8000 [Jan 21 17:16:53] DEBUG[31319] chan_sip.c: Body 18 [ 14]: a=fmtp:96 0-15 [Jan 21 17:16:53] DEBUG[31319] chan_sip.c: = Looking for Call ID: yjo08nkBGlkgv1gORGcfATVEbAUmGzZb (Checking From) --From tag Q9smPlsbBZ-xV3mr6py9Q.7x8CQidAcU --To-tag [Jan 21 17:16:53] DEBUG[31319] netsock2.c: Splitting '10.24.17.195' into... [Jan 21 17:16:53] DEBUG[31319] netsock2.c: ...host '10.24.17.195' and port ''. [Jan 21 17:16:53] DEBUG[31319] netsock2.c: Splitting '10.24.17.195' into... [Jan 21 17:16:53] DEBUG[31319] netsock2.c: ...host '10.24.17.195' and port ''. [Jan 21 17:16:53] DEBUG[31319][C-00000000] chan_sip.c: **** Received INVITE (5) - Command in SIP INVITE [Jan 21 17:16:53] DEBUG[31319][C-00000000] netsock2.c: Splitting '10.24.18.16:5060' into... [Jan 21 17:16:53] DEBUG[31319][C-00000000] netsock2.c: ...host '10.24.18.16' and port '5060'. [Jan 21 17:16:53] DEBUG[31319][C-00000000] chan_sip.c: Initializing initreq for method INVITE - callid yjo08nkBGlkgv1gORGcfATVEbAUmGzZb [Jan 21 17:16:53] DEBUG[31319][C-00000000] netsock2.c: Splitting '10.24.17.195' into... [Jan 21 17:16:53] DEBUG[31319][C-00000000] netsock2.c: ...host '10.24.17.195' and port ''. [Jan 21 17:16:53] DEBUG[31319][C-00000000] rtp_engine.c: Using engine 'asterisk' for RTP instance '0x8d2e55c' [Jan 21 17:16:53] DEBUG[31319][C-00000000] res_rtp_asterisk.c: Allocated port 13314 for RTP instance '0x8d2e55c' [Jan 21 17:16:53] DEBUG[31319][C-00000000] res_rtp_asterisk.c: Creating ICE session 0.0.0.0:13314 (13314) for RTP instance '0x8d2e55c' [Jan 21 17:16:53] DEBUG[31319][C-00000000] netsock2.c: Splitting '10.24.17.195' into... [Jan 21 17:16:53] DEBUG[31319][C-00000000] netsock2.c: ...host '10.24.17.195' and port ''. [Jan 21 17:16:53] DEBUG[31319][C-00000000] rtp_engine.c: RTP instance '0x8d2e55c' is setup and ready to go [Jan 21 17:16:53] DEBUG[31319][C-00000000] res_rtp_asterisk.c: Setup RTCP on RTP instance '0x8d2e55c' [Jan 21 17:16:53] DEBUG[31319][C-00000000] chan_sip.c: Setting NAT on RTP to Off [Jan 21 17:16:53] DEBUG[31319][C-00000000] chan_sip.c: Processing session-level SDP v=0... UNSUPPORTED OR FAILED. [Jan 21 17:16:53] DEBUG[31319][C-00000000] chan_sip.c: Processing session-level SDP o=- 148789504 148789504 IN IP4 10.24.18.16... OK. [Jan 21 17:16:53] DEBUG[31319][C-00000000] chan_sip.c: Processing session-level SDP s=digphn... UNSUPPORTED OR FAILED. [Jan 21 17:16:53] DEBUG[31319][C-00000000] netsock2.c: Splitting '10.24.18.16' into... [Jan 21 17:16:53] DEBUG[31319][C-00000000] netsock2.c: ...host '10.24.18.16' and port ''. [Jan 21 17:16:53] DEBUG[31319][C-00000000] chan_sip.c: Processing session-level SDP c=IN IP4 10.24.18.16... OK. [Jan 21 17:16:53] DEBUG[31319][C-00000000] chan_sip.c: Processing session-level SDP t=0 0... UNSUPPORTED OR FAILED. [Jan 21 17:16:53] DEBUG[31319][C-00000000] chan_sip.c: Processing session-level SDP a=X-nat:0... UNSUPPORTED OR FAILED. [Jan 21 17:16:53] DEBUG[31319][C-00000000] rtp_engine.c: Setting tx payload type 111 based on m type on 0xb3698ac0 [Jan 21 17:16:53] DEBUG[31319][C-00000000] rtp_engine.c: Setting tx payload type 18 based on m type on 0xb3698ac0 [Jan 21 17:16:53] DEBUG[31319][C-00000000] rtp_engine.c: Setting tx payload type 0 based on m type on 0xb3698ac0 [Jan 21 17:16:53] DEBUG[31319][C-00000000] rtp_engine.c: Don't have a default tx payload type 58 format for m type on 0xb3698ac0 [Jan 21 17:16:53] DEBUG[31319][C-00000000] rtp_engine.c: Setting tx payload type 118 based on m type on 0xb3698ac0 [Jan 21 17:16:53] DEBUG[31319][C-00000000] rtp_engine.c: Setting tx payload type 9 based on m type on 0xb3698ac0 [Jan 21 17:16:53] DEBUG[31319][C-00000000] rtp_engine.c: Setting tx payload type 8 based on m type on 0xb3698ac0 [Jan 21 17:16:53] DEBUG[31319][C-00000000] rtp_engine.c: Don't have a default tx payload type 58 format for m type on 0xb3698ac0 [Jan 21 17:16:53] DEBUG[31319][C-00000000] rtp_engine.c: Don't have a default tx payload type 96 format for m type on 0xb3698ac0 [Jan 21 17:16:53] DEBUG[31319][C-00000000] chan_sip.c: Processing media-level (audio) SDP a=rtcp:4037 IN IP4 10.24.18.16... UNSUPPORTED OR FAILED. [Jan 21 17:16:53] DEBUG[31319][C-00000000] chan_sip.c: Processing media-level (audio) SDP a=rtpmap:111 G726-32/8000... OK. [Jan 21 17:16:53] DEBUG[31319][C-00000000] chan_sip.c: Processing media-level (audio) SDP a=rtpmap:18 G729/8000... OK. [Jan 21 17:16:53] DEBUG[31319][C-00000000] chan_sip.c: Processing media-level (audio) SDP a=rtpmap:0 PCMU/8000... OK. [Jan 21 17:16:53] DEBUG[31319][C-00000000] chan_sip.c: Processing media-level (audio) SDP a=rtpmap:58 L16/16000... OK. [Jan 21 17:16:53] DEBUG[31319][C-00000000] chan_sip.c: Processing media-level (audio) SDP a=rtpmap:118 L16/8000... OK. [Jan 21 17:16:53] DEBUG[31319][C-00000000] chan_sip.c: Processing media-level (audio) SDP a=rtpmap:9 G722/8000... OK. [Jan 21 17:16:53] DEBUG[31319][C-00000000] chan_sip.c: Processing media-level (audio) SDP a=rtpmap:8 PCMA/8000... OK. [Jan 21 17:16:53] DEBUG[31319][C-00000000] chan_sip.c: Processing media-level (audio) SDP a=rtpmap:58 L16-256/16000... OK. [Jan 21 17:16:53] DEBUG[31319][C-00000000] chan_sip.c: Processing media-level (audio) SDP a=sendrecv... OK. [Jan 21 17:16:53] DEBUG[31319][C-00000000] chan_sip.c: Processing media-level (audio) SDP a=rtpmap:96 telephone-event/8000... OK. [Jan 21 17:16:53] DEBUG[31319][C-00000000] chan_sip.c: Processing media-level (audio) SDP a=fmtp:96 0-15... UNSUPPORTED OR FAILED. [Jan 21 17:16:53] DEBUG[31319][C-00000000] res_rtp_asterisk.c: Setting RTCP address on RTP instance '0x8d2e55c' [Jan 21 17:16:53] DEBUG[31319][C-00000000] rtp_engine.c: Copying payload 0 (0x8d31ff4) from 0xb3698ac0 to 0x8d2e708 [Jan 21 17:16:53] DEBUG[31319][C-00000000] rtp_engine.c: Copying payload 8 (0x890ad94) from 0xb3698ac0 to 0x8d2e708 [Jan 21 17:16:53] DEBUG[31319][C-00000000] rtp_engine.c: Copying payload 9 (0x890ad6c) from 0xb3698ac0 to 0x8d2e708 [Jan 21 17:16:53] DEBUG[31319][C-00000000] rtp_engine.c: Copying payload 18 (0x8d31fcc) from 0xb3698ac0 to 0x8d2e708 [Jan 21 17:16:53] DEBUG[31319][C-00000000] rtp_engine.c: Copying payload 58 (0x890adbc) from 0xb3698ac0 to 0x8d2e708 [Jan 21 17:16:53] DEBUG[31319][C-00000000] rtp_engine.c: Copying payload 96 (0x8d3201c) from 0xb3698ac0 to 0x8d2e708 [Jan 21 17:16:53] DEBUG[31319][C-00000000] rtp_engine.c: Copying payload 111 (0x8d31fa4) from 0xb3698ac0 to 0x8d2e708 [Jan 21 17:16:53] DEBUG[31319][C-00000000] rtp_engine.c: Copying payload 118 (0x890ad44) from 0xb3698ac0 to 0x8d2e708 [Jan 21 17:16:53] DEBUG[31319][C-00000000] res_rtp_asterisk.c: Ignoring duplicate RTCP property on RTP instance '0x8d2e55c' [Jan 21 17:16:53] DEBUG[31319][C-00000000] chan_sip.c: We're settling with these formats: (g722) [Jan 21 17:16:53] DEBUG[31319][C-00000000] chan_sip.c: Checking SIP call limits for device 333 [Jan 21 17:16:53] DEBUG[31319][C-00000000] chan_sip.c: Updating call counter for incoming call [Jan 21 17:16:53] DEBUG[31319][C-00000000] netsock2.c: Splitting '10.24.17.195' into... [Jan 21 17:16:53] DEBUG[31319][C-00000000] netsock2.c: ...host '10.24.17.195' and port ''. [Jan 21 17:16:53] DEBUG[31319][C-00000000] netsock2.c: Splitting '10.24.17.195' into... [Jan 21 17:16:53] DEBUG[31319][C-00000000] netsock2.c: ...host '10.24.17.195' and port ''. [Jan 21 17:16:53] DEBUG[31319][C-00000000] chan_sip.c: Incoming INVITE with 'timer' option supported [Jan 21 17:16:53] DEBUG[31319][C-00000000] chan_sip.c: INVITE also has "Session-Expires" header. [Jan 21 17:16:53] DEBUG[31319][C-00000000] chan_sip.c: Session-Expires: 1800 [Jan 21 17:16:53] DEBUG[31319][C-00000000] chan_sip.c: INVITE also has "Min-SE" header. [Jan 21 17:16:53] DEBUG[31319][C-00000000] chan_sip.c: Received Min-SE: 90 [Jan 21 17:16:53] DEBUG[31319][C-00000000] chan_sip.c: *** Our native formats are (g722) [Jan 21 17:16:53] DEBUG[31319][C-00000000] chan_sip.c: *** Joint capabilities are (g722) [Jan 21 17:16:53] DEBUG[31319][C-00000000] chan_sip.c: *** Our capabilities are (g722) [Jan 21 17:16:53] DEBUG[31319][C-00000000] chan_sip.c: *** AST_CODEC_CHOOSE formats are g722 [Jan 21 17:16:53] DEBUG[31319][C-00000000] chan_sip.c: This channel will not be able to handle video. [Jan 21 17:16:53] DEBUG[31319][C-00000000] chan_sip.c: SIP/333-00000000: New call is still down.... Trying... [Jan 21 17:16:53] DEBUG[31319][C-00000000] chan_sip.c: Trying to put 'SIP/2.0 100' onto UDP socket destined for 10.24.18.16:5060 [Jan 21 17:16:53] DEBUG[31287] devicestate.c: No provider found, checking channel drivers for SIP - 333 [Jan 21 17:16:53] DEBUG[31287] chan_sip.c: Checking device state for peer 333 [Jan 21 17:16:53] DEBUG[31287] devicestate.c: Changing state for SIP/333 - state 1 (Not in use) [Jan 21 17:16:53] DEBUG[31337][C-00000000] pbx.c: Launching 'NoOp' [Jan 21 17:16:53] DEBUG[31337][C-00000000] pbx.c: Launching 'Set' [Jan 21 17:16:53] DEBUG[31337][C-00000000] pbx.c: Function CALLERID(number) result is '00333' [Jan 21 17:16:53] DEBUG[31337][C-00000000] pbx.c: Launching 'Set' [Jan 21 17:16:53] DEBUG[31337][C-00000000] pbx.c: Function CALLERID(number) result is '00333' [Jan 21 17:16:53] DEBUG[31337][C-00000000] pbx.c: Launching 'Set' [Jan 21 17:16:53] DEBUG[31337][C-00000000] pbx.c: Result of 'EXTEN' is '123' [Jan 21 17:16:53] DEBUG[31337][C-00000000] pbx.c: Launching 'Dial' [Jan 21 17:16:53] DEBUG[31337][C-00000000] chan_sip.c: Asked to create a SIP channel with formats: (g722) [Jan 21 17:16:53] DEBUG[31337][C-00000000] chan_sip.c: Allocating new SIP dialog for 4db67db731ca53686f5dd18f210a9350@127.0.1.1:5060 - INVITE (No RTP) [Jan 21 17:16:53] DEBUG[31337][C-00000000] rtp_engine.c: Using engine 'asterisk' for RTP instance '0x8d367e4' [Jan 21 17:16:53] DEBUG[31337][C-00000000] res_rtp_asterisk.c: Allocated port 17216 for RTP instance '0x8d367e4' [Jan 21 17:16:53] DEBUG[31337][C-00000000] res_rtp_asterisk.c: Creating ICE session 0.0.0.0:17216 (17216) for RTP instance '0x8d367e4' [Jan 21 17:16:53] DEBUG[31337][C-00000000] netsock2.c: Splitting '10.24.17.195' into... [Jan 21 17:16:53] DEBUG[31337][C-00000000] netsock2.c: ...host '10.24.17.195' and port ''. [Jan 21 17:16:53] DEBUG[31337][C-00000000] rtp_engine.c: RTP instance '0x8d367e4' is setup and ready to go [Jan 21 17:16:53] DEBUG[31337][C-00000000] res_rtp_asterisk.c: Setup RTCP on RTP instance '0x8d367e4' [Jan 21 17:16:53] DEBUG[31337][C-00000000] chan_sip.c: Setting NAT on RTP to Off [Jan 21 17:16:53] DEBUG[31337][C-00000000] chan_sip.c: OBPROXY: Not applying OBproxy to this call [Jan 21 17:16:53] DEBUG[31337][C-00000000] acl.c: For destination '10.24.18.190', our source address is '10.24.17.195'. [Jan 21 17:16:53] DEBUG[31337][C-00000000] chan_sip.c: Setting AST_TRANSPORT_UDP with address 10.24.17.195:5060 [Jan 21 17:16:53] DEBUG[31337][C-00000000] chan_sip.c: Setting NAT on RTP to Off [Jan 21 17:16:53] DEBUG[31337][C-00000000] chan_sip.c: SIP call-id changed from '4db67db731ca53686f5dd18f210a9350@127.0.1.1:5060' to '5e03f5042f326c3f4be367537982d134@10.24.17.195:5060' [Jan 21 17:16:53] DEBUG[31337][C-00000000] chan_sip.c: *** Our native formats are (g722) [Jan 21 17:16:53] DEBUG[31337][C-00000000] chan_sip.c: *** Joint capabilities are (g722) [Jan 21 17:16:53] DEBUG[31337][C-00000000] chan_sip.c: *** Our capabilities are (g722|alaw) [Jan 21 17:16:53] DEBUG[31337][C-00000000] chan_sip.c: *** AST_CODEC_CHOOSE formats are g722 [Jan 21 17:16:53] DEBUG[31337][C-00000000] chan_sip.c: *** Our preferred formats from the incoming channel are (g722) [Jan 21 17:16:53] DEBUG[31337][C-00000000] chan_sip.c: This channel will not be able to handle video. [Jan 21 17:16:53] DEBUG[31337][C-00000000] channel_internal_api.c: Channel Call ID changing from [C-00000000] to [C-00000000] [Jan 21 17:16:53] DEBUG[31337][C-00000000] rtp_engine.c: Copying payload 0 (0x8d31ff4) from 0x8d2e708 to 0x8d36990 [Jan 21 17:16:53] DEBUG[31337][C-00000000] rtp_engine.c: Copying payload 8 (0x890ad94) from 0x8d2e708 to 0x8d36990 [Jan 21 17:16:53] DEBUG[31337][C-00000000] rtp_engine.c: Copying payload 9 (0x890ad6c) from 0x8d2e708 to 0x8d36990 [Jan 21 17:16:53] DEBUG[31337][C-00000000] rtp_engine.c: Copying payload 18 (0x8d31fcc) from 0x8d2e708 to 0x8d36990 [Jan 21 17:16:53] DEBUG[31337][C-00000000] rtp_engine.c: Copying payload 58 (0x890adbc) from 0x8d2e708 to 0x8d36990 [Jan 21 17:16:53] DEBUG[31337][C-00000000] rtp_engine.c: Copying payload 96 (0x8d3201c) from 0x8d2e708 to 0x8d36990 [Jan 21 17:16:53] DEBUG[31337][C-00000000] rtp_engine.c: Copying payload 111 (0x8d31fa4) from 0x8d2e708 to 0x8d36990 [Jan 21 17:16:53] DEBUG[31337][C-00000000] rtp_engine.c: Copying payload 118 (0x890ad44) from 0x8d2e708 to 0x8d36990 [Jan 21 17:16:53] DEBUG[31337][C-00000000] rtp_engine.c: Seeded SDP of 'SIP/OutTrunk-00000001' with that of 'SIP/333-00000000' [Jan 21 17:16:53] DEBUG[31337][C-00000000] chan_sip.c: Outgoing Call for 123 [Jan 21 17:16:53] DEBUG[31337][C-00000000] chan_sip.c: Updating call counter for outgoing call [Jan 21 17:16:53] DEBUG[31337][C-00000000] chan_sip.c: ** Our capability: (g722|alaw) Video flag: False Text flag: False [Jan 21 17:16:53] DEBUG[31337][C-00000000] chan_sip.c: ** Our prefcodec: (g722) [Jan 21 17:16:53] DEBUG[31337][C-00000000] chan_sip.c: -- Done with adding codecs to SDP [Jan 21 17:16:53] DEBUG[31337][C-00000000] chan_sip.c: Done building SDP. Settling with this capability: (g722|alaw) [Jan 21 17:16:53] DEBUG[31337][C-00000000] chan_sip.c: Initializing initreq for method INVITE - callid 5e03f5042f326c3f4be367537982d134@10.24.17.195:5060 [Jan 21 17:16:53] DEBUG[31337][C-00000000] chan_sip.c: Header 0 [ 35]: INVITE sip:123@10.24.18.190 SIP/2.0 [Jan 21 17:16:53] DEBUG[31337][C-00000000] chan_sip.c: Header 1 [ 57]: Via: SIP/2.0/UDP 10.24.17.195:5060;branch=z9hG4bK53401a28 [Jan 21 17:16:53] DEBUG[31337][C-00000000] chan_sip.c: Header 2 [ 16]: Max-Forwards: 70 [Jan 21 17:16:53] DEBUG[31337][C-00000000] chan_sip.c: Header 3 [ 49]: From: "333" ;tag=as59d0b62d [Jan 21 17:16:53] DEBUG[31337][C-00000000] chan_sip.c: Header 4 [ 26]: To: [Jan 21 17:16:53] DEBUG[31337][C-00000000] chan_sip.c: Header 5 [ 36]: Contact: [Jan 21 17:16:53] DEBUG[31337][C-00000000] chan_sip.c: Header 6 [ 59]: Call-ID: 5e03f5042f326c3f4be367537982d134@10.24.17.195:5060 [Jan 21 17:16:53] DEBUG[31337][C-00000000] chan_sip.c: Header 7 [ 16]: CSeq: 102 INVITE [Jan 21 17:16:53] DEBUG[31337][C-00000000] chan_sip.c: Header 8 [ 31]: User-Agent: Asterisk PBX 13.7.0 [Jan 21 17:16:53] DEBUG[31337][C-00000000] chan_sip.c: Header 9 [ 35]: Date: Thu, 21 Jan 2016 23:16:53 GMT [Jan 21 17:16:53] DEBUG[31337][C-00000000] chan_sip.c: Header 10 [ 90]: Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE [Jan 21 17:16:53] DEBUG[31337][C-00000000] chan_sip.c: Header 11 [ 26]: Supported: replaces, timer [Jan 21 17:16:53] DEBUG[31337][C-00000000] chan_sip.c: Header 12 [ 29]: Content-Type: application/sdp [Jan 21 17:16:53] DEBUG[31337][C-00000000] chan_sip.c: *** SIP TIMER: Initializing retransmit timer on packet: Id #15 [Jan 21 17:16:53] DEBUG[31337][C-00000000] chan_sip.c: Trying to put 'INVITE sip:' onto UDP socket destined for 10.24.18.190:5060 [Jan 21 17:16:53] DEBUG[31319] chan_sip.c: Header 0 [ 18]: SIP/2.0 100 Trying [Jan 21 17:16:53] DEBUG[31319] chan_sip.c: Header 1 [ 79]: Via: SIP/2.0/UDP 10.24.17.195:5060;branch=z9hG4bK53401a28;received=10.24.17.195 [Jan 21 17:16:53] DEBUG[31319] chan_sip.c: Header 2 [ 49]: From: "333" ;tag=as59d0b62d [Jan 21 17:16:53] DEBUG[31319] chan_sip.c: Header 3 [ 26]: To: [Jan 21 17:16:53] DEBUG[31319] chan_sip.c: Header 4 [ 59]: Call-ID: 5e03f5042f326c3f4be367537982d134@10.24.17.195:5060 [Jan 21 17:16:53] DEBUG[31319] chan_sip.c: Header 5 [ 16]: CSeq: 102 INVITE [Jan 21 17:16:53] DEBUG[31319] chan_sip.c: Header 6 [ 31]: Server: Asterisk PBX 13.7.0-rc3 [Jan 21 17:16:53] DEBUG[31319] chan_sip.c: Header 7 [ 90]: Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE [Jan 21 17:16:53] DEBUG[31319] chan_sip.c: Header 8 [ 26]: Supported: replaces, timer [Jan 21 17:16:53] DEBUG[31319] chan_sip.c: Header 9 [ 35]: Session-Expires: 1800;refresher=uas [Jan 21 17:16:53] DEBUG[31319] chan_sip.c: Header 10 [ 36]: Contact: [Jan 21 17:16:53] DEBUG[31319] chan_sip.c: Header 11 [ 17]: Content-Length: 0 [Jan 21 17:16:53] DEBUG[31319] chan_sip.c: = Looking for Call ID: 5e03f5042f326c3f4be367537982d134@10.24.17.195:5060 (Checking To) --From tag as59d0b62d --To-tag [Jan 21 17:16:53] DEBUG[31319][C-00000000] chan_sip.c: *** SIP TIMER: Cancelling retransmission #15 - INVITE (got response) [Jan 21 17:16:53] DEBUG[31319][C-00000000] chan_sip.c: (Provisional) Stopping retransmission (but retaining packet) on '5e03f5042f326c3f4be367537982d134@10.24.17.195:5060' Request 102: Found [Jan 21 17:16:53] DEBUG[31319][C-00000000] chan_sip.c: SIP response 100 to standard invite [Jan 21 17:16:53] DEBUG[31319] chan_sip.c: Header 0 [ 28]: SIP/2.0 183 Session Progress [Jan 21 17:16:53] DEBUG[31319] chan_sip.c: Header 1 [ 79]: Via: SIP/2.0/UDP 10.24.17.195:5060;branch=z9hG4bK53401a28;received=10.24.17.195 [Jan 21 17:16:53] DEBUG[31319] chan_sip.c: Header 2 [ 49]: From: "333" ;tag=as59d0b62d [Jan 21 17:16:53] DEBUG[31319] chan_sip.c: Header 3 [ 41]: To: ;tag=as45fc47e8 [Jan 21 17:16:53] DEBUG[31319] chan_sip.c: Header 4 [ 59]: Call-ID: 5e03f5042f326c3f4be367537982d134@10.24.17.195:5060 [Jan 21 17:16:53] DEBUG[31319] chan_sip.c: Header 5 [ 16]: CSeq: 102 INVITE [Jan 21 17:16:53] DEBUG[31319] chan_sip.c: Header 6 [ 31]: Server: Asterisk PBX 13.7.0-rc3 [Jan 21 17:16:53] DEBUG[31319] chan_sip.c: Header 7 [ 90]: Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE [Jan 21 17:16:53] DEBUG[31319] chan_sip.c: Header 8 [ 26]: Supported: replaces, timer [Jan 21 17:16:53] DEBUG[31319] chan_sip.c: Header 9 [ 35]: Session-Expires: 1800;refresher=uas [Jan 21 17:16:53] DEBUG[31319] chan_sip.c: Header 10 [ 36]: Contact: [Jan 21 17:16:53] DEBUG[31319] chan_sip.c: Header 11 [ 29]: Content-Type: application/sdp [Jan 21 17:16:53] DEBUG[31319] chan_sip.c: Header 12 [ 14]: Require: timer [Jan 21 17:16:53] DEBUG[31319] chan_sip.c: Header 13 [ 19]: Content-Length: 238 [Jan 21 17:16:53] DEBUG[31319] chan_sip.c: Header 14 [ 0]: [Jan 21 17:16:53] DEBUG[31319] chan_sip.c: Body 0 [ 3]: v=0 [Jan 21 17:16:53] DEBUG[31319] chan_sip.c: Body 1 [ 46]: o=root 105845436 105845436 IN IP4 10.24.18.190 [Jan 21 17:16:53] DEBUG[31319] chan_sip.c: Body 2 [ 25]: s=Asterisk PBX 13.7.0-rc3 [Jan 21 17:16:53] DEBUG[31319] chan_sip.c: Body 3 [ 21]: c=IN IP4 10.24.18.190 [Jan 21 17:16:53] DEBUG[31319] chan_sip.c: Body 4 [ 5]: t=0 0 [Jan 21 17:16:53] DEBUG[31319] chan_sip.c: Body 5 [ 26]: m=audio 16064 RTP/AVP 8 96 [Jan 21 17:16:53] DEBUG[31319] chan_sip.c: Body 6 [ 20]: a=rtpmap:8 PCMA/8000 [Jan 21 17:16:53] DEBUG[31319] chan_sip.c: Body 7 [ 32]: a=rtpmap:96 telephone-event/8000 [Jan 21 17:16:53] DEBUG[31319] chan_sip.c: Body 8 [ 14]: a=fmtp:96 0-16 [Jan 21 17:16:53] DEBUG[31319] chan_sip.c: Body 9 [ 14]: a=maxptime:150 [Jan 21 17:16:53] DEBUG[31319] chan_sip.c: Body 10 [ 10]: a=sendrecv [Jan 21 17:16:53] DEBUG[31319] chan_sip.c: = Looking for Call ID: 5e03f5042f326c3f4be367537982d134@10.24.17.195:5060 (Checking To) --From tag as59d0b62d --To-tag as45fc47e8 [Jan 21 17:16:53] DEBUG[31319][C-00000000] chan_sip.c: (Provisional) Stopping retransmission (but retaining packet) on '5e03f5042f326c3f4be367537982d134@10.24.17.195:5060' Request 102: Found [Jan 21 17:16:53] DEBUG[31319][C-00000000] chan_sip.c: SIP response 183 to standard invite [Jan 21 17:16:53] DEBUG[31319][C-00000000] chan_sip.c: Processing session-level SDP v=0... UNSUPPORTED OR FAILED. [Jan 21 17:16:53] DEBUG[31319][C-00000000] chan_sip.c: Processing session-level SDP o=root 105845436 105845436 IN IP4 10.24.18.190... OK. [Jan 21 17:16:53] DEBUG[31319][C-00000000] chan_sip.c: Processing session-level SDP s=Asterisk PBX 13.7.0-rc3... UNSUPPORTED OR FAILED. [Jan 21 17:16:53] DEBUG[31319][C-00000000] netsock2.c: Splitting '10.24.18.190' into... [Jan 21 17:16:53] DEBUG[31319][C-00000000] netsock2.c: ...host '10.24.18.190' and port ''. [Jan 21 17:16:53] DEBUG[31319][C-00000000] chan_sip.c: Processing session-level SDP c=IN IP4 10.24.18.190... OK. [Jan 21 17:16:53] DEBUG[31319][C-00000000] chan_sip.c: Processing session-level SDP t=0 0... UNSUPPORTED OR FAILED. [Jan 21 17:16:53] DEBUG[31319][C-00000000] rtp_engine.c: Setting tx payload type 8 based on m type on 0xb3697c50 [Jan 21 17:16:53] DEBUG[31319][C-00000000] rtp_engine.c: Don't have a default tx payload type 96 format for m type on 0xb3697c50 [Jan 21 17:16:53] DEBUG[31319][C-00000000] chan_sip.c: Processing media-level (audio) SDP a=rtpmap:8 PCMA/8000... OK. [Jan 21 17:16:53] DEBUG[31319][C-00000000] chan_sip.c: Processing media-level (audio) SDP a=rtpmap:96 telephone-event/8000... OK. [Jan 21 17:16:53] DEBUG[31319][C-00000000] chan_sip.c: Processing media-level (audio) SDP a=fmtp:96 0-16... UNSUPPORTED OR FAILED. [Jan 21 17:16:53] DEBUG[31319][C-00000000] chan_sip.c: Processing media-level (audio) SDP a=maxptime:150... UNSUPPORTED OR FAILED. [Jan 21 17:16:53] DEBUG[31319][C-00000000] chan_sip.c: Processing media-level (audio) SDP a=sendrecv... OK. [Jan 21 17:16:53] DEBUG[31319][C-00000000] res_rtp_asterisk.c: Set role to CONTROLLING (0x8d367e4) [Jan 21 17:16:53] DEBUG[31319][C-00000000] res_rtp_asterisk.c: Set role failed; no ice instance (0x8d367e4) [Jan 21 17:16:53] DEBUG[31319][C-00000000] res_rtp_asterisk.c: Setting RTCP address on RTP instance '0x8d367e4' [Jan 21 17:16:53] DEBUG[31319][C-00000000] rtp_engine.c: Copying payload 8 (0x8bdc974) from 0xb3697c50 to 0x8d36990 [Jan 21 17:16:53] DEBUG[31319][C-00000000] rtp_engine.c: Copying payload 96 (0x8d453d4) from 0xb3697c50 to 0x8d36990 [Jan 21 17:16:53] DEBUG[31319][C-00000000] res_rtp_asterisk.c: Ignoring duplicate RTCP property on RTP instance '0x8d367e4' [Jan 21 17:16:53] DEBUG[31319][C-00000000] chan_sip.c: We're settling with these formats: (alaw) [Jan 21 17:16:53] DEBUG[31319][C-00000000] chan_sip.c: We have an owner, now see if we need to change this call [Jan 21 17:16:53] DEBUG[31319][C-00000000] chan_sip.c: Setting native formats after processing SDP. peer joint formats (alaw), old nativeformats (g722) [Jan 21 17:16:53] DEBUG[31319][C-00000000] channel.c: Channel SIP/OutTrunk-00000001 setting read format path: alaw -> g722 [Jan 21 17:16:53] DEBUG[31319][C-00000000] channel.c: Channel SIP/OutTrunk-00000001 setting write format path: g722 -> alaw [Jan 21 17:16:53] DEBUG[31319] chan_sip.c: Header 0 [ 14]: SIP/2.0 200 OK [Jan 21 17:16:53] DEBUG[31319] chan_sip.c: Header 1 [ 79]: Via: SIP/2.0/UDP 10.24.17.195:5060;branch=z9hG4bK53401a28;received=10.24.17.195 [Jan 21 17:16:53] DEBUG[31319] chan_sip.c: Header 2 [ 49]: From: "333" ;tag=as59d0b62d [Jan 21 17:16:53] DEBUG[31319] chan_sip.c: Header 3 [ 41]: To: ;tag=as45fc47e8 [Jan 21 17:16:53] DEBUG[31319] chan_sip.c: Header 4 [ 59]: Call-ID: 5e03f5042f326c3f4be367537982d134@10.24.17.195:5060 [Jan 21 17:16:53] DEBUG[31319] chan_sip.c: Header 5 [ 16]: CSeq: 102 INVITE [Jan 21 17:16:53] DEBUG[31319] chan_sip.c: Header 6 [ 31]: Server: Asterisk PBX 13.7.0-rc3 [Jan 21 17:16:53] DEBUG[31319] chan_sip.c: Header 7 [ 90]: Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE [Jan 21 17:16:53] DEBUG[31319] chan_sip.c: Header 8 [ 26]: Supported: replaces, timer [Jan 21 17:16:53] DEBUG[31319] chan_sip.c: Header 9 [ 35]: Session-Expires: 1800;refresher=uas [Jan 21 17:16:53] DEBUG[31319] chan_sip.c: Header 10 [ 36]: Contact: [Jan 21 17:16:53] DEBUG[31319] chan_sip.c: Header 11 [ 29]: Content-Type: application/sdp [Jan 21 17:16:53] DEBUG[31319] chan_sip.c: Header 12 [ 14]: Require: timer [Jan 21 17:16:53] DEBUG[31319] chan_sip.c: Header 13 [ 19]: Content-Length: 238 [Jan 21 17:16:53] DEBUG[31319] chan_sip.c: Header 14 [ 0]: [Jan 21 17:16:53] DEBUG[31319] chan_sip.c: Body 0 [ 3]: v=0 [Jan 21 17:16:53] DEBUG[31319] chan_sip.c: Body 1 [ 46]: o=root 105845436 105845436 IN IP4 10.24.18.190 [Jan 21 17:16:53] DEBUG[31319] chan_sip.c: Body 2 [ 25]: s=Asterisk PBX 13.7.0-rc3 [Jan 21 17:16:53] DEBUG[31319] chan_sip.c: Body 3 [ 21]: c=IN IP4 10.24.18.190 [Jan 21 17:16:53] DEBUG[31319] chan_sip.c: Body 4 [ 5]: t=0 0 [Jan 21 17:16:53] DEBUG[31319] chan_sip.c: Body 5 [ 26]: m=audio 16064 RTP/AVP 8 96 [Jan 21 17:16:53] DEBUG[31319] chan_sip.c: Body 6 [ 20]: a=rtpmap:8 PCMA/8000 [Jan 21 17:16:53] DEBUG[31319] chan_sip.c: Body 7 [ 32]: a=rtpmap:96 telephone-event/8000 [Jan 21 17:16:53] DEBUG[31319] chan_sip.c: Body 8 [ 14]: a=fmtp:96 0-16 [Jan 21 17:16:53] DEBUG[31319] chan_sip.c: Body 9 [ 14]: a=maxptime:150 [Jan 21 17:16:53] DEBUG[31319] chan_sip.c: Body 10 [ 10]: a=sendrecv [Jan 21 17:16:53] DEBUG[31319] chan_sip.c: = Looking for Call ID: 5e03f5042f326c3f4be367537982d134@10.24.17.195:5060 (Checking To) --From tag as59d0b62d --To-tag as45fc47e8 [Jan 21 17:16:53] DEBUG[31319][C-00000000] chan_sip.c: Acked pending invite 102 [Jan 21 17:16:53] DEBUG[31319][C-00000000] chan_sip.c: Stopping retransmission on '5e03f5042f326c3f4be367537982d134@10.24.17.195:5060' of Request 102: Match Found [Jan 21 17:16:53] DEBUG[31319][C-00000000] chan_sip.c: SIP response 200 to standard invite [Jan 21 17:16:53] DEBUG[31319][C-00000000] chan_sip.c: Processing session-level SDP v=0... UNSUPPORTED OR FAILED. [Jan 21 17:16:53] DEBUG[31319][C-00000000] chan_sip.c: Call 5e03f5042f326c3f4be367537982d134@10.24.17.195:5060 responded to our reinvite without changing SDP version; ignoring SDP. [Jan 21 17:16:53] DEBUG[31319][C-00000000] chan_sip.c: Updating call counter for outgoing call [Jan 21 17:16:53] DEBUG[31319][C-00000000] netsock2.c: Splitting '10.24.18.190:5060' into... [Jan 21 17:16:53] DEBUG[31319][C-00000000] netsock2.c: ...host '10.24.18.190' and port '5060'. [Jan 21 17:16:53] DEBUG[31319][C-00000000] chan_sip.c: Session-Expires: 1800 [Jan 21 17:16:53] DEBUG[31319][C-00000000] chan_sip.c: Refresher: UAS [Jan 21 17:16:53] DEBUG[31319][C-00000000] chan_sip.c: Session timer started: 3 - 5e03f5042f326c3f4be367537982d134@10.24.17.195:5060 1768000ms [Jan 21 17:16:53] DEBUG[31319][C-00000000] chan_sip.c: Strict routing enforced for session 5e03f5042f326c3f4be367537982d134@10.24.17.195:5060 [Jan 21 17:16:53] DEBUG[31319][C-00000000] netsock2.c: Splitting '10.24.18.190:5060' into... [Jan 21 17:16:53] DEBUG[31319][C-00000000] netsock2.c: ...host '10.24.18.190' and port '5060'. [Jan 21 17:16:53] DEBUG[31319][C-00000000] chan_sip.c: Trying to put 'ACK sip:123' onto UDP socket destined for 10.24.18.190:5060 [Jan 21 17:16:53] DEBUG[31337][C-00000000] rtp_engine.c: Setting early bridge SDP of 'SIP/333-00000000' with that of 'SIP/OutTrunk-00000001' [Jan 21 17:16:53] DEBUG[31337][C-00000000] chan_sip.c: ** Our capability: (g722) Video flag: True Text flag: True [Jan 21 17:16:53] DEBUG[31337][C-00000000] chan_sip.c: ** Our prefcodec: (nothing) [Jan 21 17:16:53] DEBUG[31337][C-00000000] chan_sip.c: -- Done with adding codecs to SDP [Jan 21 17:16:53] DEBUG[31337][C-00000000] chan_sip.c: Setting framing on incoming call: 0 [Jan 21 17:16:53] DEBUG[31337][C-00000000] chan_sip.c: Done building SDP. Settling with this capability: (g722) [Jan 21 17:16:53] DEBUG[31337][C-00000000] chan_sip.c: Trying to put 'SIP/2.0 183' onto UDP socket destined for 10.24.18.16:5060 [Jan 21 17:16:53] DEBUG[31287] devicestate.c: No provider found, checking channel drivers for SIP - OutTrunk [Jan 21 17:16:53] DEBUG[31287] chan_sip.c: Checking device state for peer OutTrunk [Jan 21 17:16:53] DEBUG[31287] devicestate.c: Changing state for SIP/OutTrunk - state 1 (Not in use) [Jan 21 17:16:53] DEBUG[31333] app_queue.c: Device 'SIP/OutTrunk' changed to state '1' (Not in use) but we don't care because they're not a member of any queue. [Jan 21 17:16:53] DEBUG[31337][C-00000000] rtp_engine.c: Setting early bridge SDP of 'SIP/333-00000000' with that of 'SIP/OutTrunk-00000001' [Jan 21 17:16:53] DEBUG[31337][C-00000000] channel.c: Channel SIP/OutTrunk-00000001 setting read format path: alaw -> slin16 [Jan 21 17:16:53] DEBUG[31337][C-00000000] channel.c: Channel SIP/333-00000000 setting write format path: slin16 -> g722 [Jan 21 17:16:53] DEBUG[31337][C-00000000] channel.c: Channel SIP/333-00000000 setting read format path: g722 -> slin16 [Jan 21 17:16:53] DEBUG[31337][C-00000000] channel.c: Channel SIP/OutTrunk-00000001 setting write format path: slin16 -> alaw [Jan 21 17:16:53] DEBUG[31287] devicestate.c: No provider found, checking channel drivers for SIP - 333 [Jan 21 17:16:53] DEBUG[31287] chan_sip.c: Checking device state for peer 333 [Jan 21 17:16:53] DEBUG[31287] devicestate.c: Changing state for SIP/333 - state 1 (Not in use) [Jan 21 17:16:53] DEBUG[31337][C-00000000] chan_sip.c: SIP answering channel: SIP/333-00000000 [Jan 21 17:16:53] DEBUG[31337][C-00000000] res_rtp_asterisk.c: Setting the marker bit due to a source update [Jan 21 17:16:53] DEBUG[31337][C-00000000] chan_sip.c: ** Our capability: (g722) Video flag: True Text flag: True [Jan 21 17:16:53] DEBUG[31337][C-00000000] chan_sip.c: ** Our prefcodec: (nothing) [Jan 21 17:16:53] DEBUG[31337][C-00000000] chan_sip.c: -- Done with adding codecs to SDP [Jan 21 17:16:53] DEBUG[31337][C-00000000] chan_sip.c: Setting framing on incoming call: 0 [Jan 21 17:16:53] DEBUG[31337][C-00000000] chan_sip.c: Done building SDP. Settling with this capability: (g722) [Jan 21 17:16:53] DEBUG[31337][C-00000000] chan_sip.c: *** SIP TIMER: Initializing retransmit timer on packet: Id #5 [Jan 21 17:16:53] DEBUG[31337][C-00000000] chan_sip.c: Trying to put 'SIP/2.0 200' onto UDP socket destined for 10.24.18.16:5060 [Jan 21 17:16:53] DEBUG[31337][C-00000000] chan_sip.c: Session timer started: 2 - yjo08nkBGlkgv1gORGcfATVEbAUmGzZb 900000ms [Jan 21 17:16:53] DEBUG[31337][C-00000000] bridge.c: Bridge technology softmix has less preference than simple_bridge (10 <= 50). Skipping. [Jan 21 17:16:53] DEBUG[31337][C-00000000] bridge.c: Bridge technology holding_bridge does not have any capabilities we want. [Jan 21 17:16:53] DEBUG[31337][C-00000000] bridge_native_rtp.c: Bridge 'ae15d38f-5b54-4f4e-ad30-028191d26f7a' can not use native RTP bridge as two channels are required [Jan 21 17:16:53] DEBUG[31337][C-00000000] bridge.c: Bridge technology native_rtp is not compatible with properties of existing bridge. [Jan 21 17:16:53] DEBUG[31337][C-00000000] bridge.c: Chose bridge technology simple_bridge [Jan 21 17:16:53] DEBUG[31337][C-00000000] bridge.c: Bridge ae15d38f-5b54-4f4e-ad30-028191d26f7a: calling simple_bridge technology constructor [Jan 21 17:16:53] DEBUG[31337][C-00000000] bridge.c: Bridge ae15d38f-5b54-4f4e-ad30-028191d26f7a: calling simple_bridge technology start [Jan 21 17:16:53] DEBUG[31338][C-00000000] bridge_channel.c: Bridge ae15d38f-5b54-4f4e-ad30-028191d26f7a: 0x8d3b4e4(SIP/OutTrunk-00000001) is joining [Jan 21 17:16:53] DEBUG[31338][C-00000000] bridge_channel.c: Bridge ae15d38f-5b54-4f4e-ad30-028191d26f7a: pushing 0x8d3b4e4(SIP/OutTrunk-00000001) [Jan 21 17:16:53] DEBUG[31338][C-00000000] bridge.c: Bridge technology softmix does not have any capabilities we want. [Jan 21 17:16:53] DEBUG[31338][C-00000000] bridge.c: Bridge technology holding_bridge does not have any capabilities we want. [Jan 21 17:16:53] DEBUG[31338][C-00000000] bridge_native_rtp.c: Bridge 'ae15d38f-5b54-4f4e-ad30-028191d26f7a' can not use native RTP bridge as two channels are required [Jan 21 17:16:53] DEBUG[31338][C-00000000] bridge.c: Bridge technology native_rtp is not compatible with properties of existing bridge. [Jan 21 17:16:53] DEBUG[31338][C-00000000] bridge.c: Chose bridge technology simple_bridge [Jan 21 17:16:53] DEBUG[31338][C-00000000] bridge.c: Bridge ae15d38f-5b54-4f4e-ad30-028191d26f7a is already using the new technology. [Jan 21 17:16:53] DEBUG[31338][C-00000000] bridge.c: Bridge ae15d38f-5b54-4f4e-ad30-028191d26f7a: 0x8d3b4e4(SIP/OutTrunk-00000001) is joining simple_bridge technology [Jan 21 17:16:53] DEBUG[31338][C-00000000] res_rtp_asterisk.c: Not changing SSRC since we haven't sent any RTP yet [Jan 21 17:16:53] DEBUG[31337][C-00000000] bridge_channel.c: Bridge ae15d38f-5b54-4f4e-ad30-028191d26f7a: 0x8d3b934(SIP/333-00000000) is joining [Jan 21 17:16:53] DEBUG[31337][C-00000000] bridge_channel.c: Bridge ae15d38f-5b54-4f4e-ad30-028191d26f7a: pushing 0x8d3b934(SIP/333-00000000) [Jan 21 17:16:53] DEBUG[31337][C-00000000] bridge.c: Bridge technology softmix does not have any capabilities we want. [Jan 21 17:16:53] DEBUG[31337][C-00000000] bridge.c: Bridge technology holding_bridge does not have any capabilities we want. [Jan 21 17:16:53] DEBUG[31337][C-00000000] bridge.c: Chose bridge technology native_rtp [Jan 21 17:16:53] DEBUG[31337][C-00000000] bridge.c: Bridge ae15d38f-5b54-4f4e-ad30-028191d26f7a: calling native_rtp technology constructor [Jan 21 17:16:53] DEBUG[31337][C-00000000] bridge.c: Bridge ae15d38f-5b54-4f4e-ad30-028191d26f7a: moving 0x8d3b4e4(SIP/OutTrunk-00000001) to dummy bridge temporarily [Jan 21 17:16:53] DEBUG[31337][C-00000000] bridge.c: Bridge ae15d38f-5b54-4f4e-ad30-028191d26f7a: 0x8d3b4e4(SIP/OutTrunk-00000001) is leaving simple_bridge technology (dummy) [Jan 21 17:16:53] DEBUG[31337][C-00000000] bridge.c: Bridge ae15d38f-5b54-4f4e-ad30-028191d26f7a: calling simple_bridge technology stop [Jan 21 17:16:53] DEBUG[31337][C-00000000] bridge.c: Bridge ae15d38f-5b54-4f4e-ad30-028191d26f7a: 0x8d3b934(SIP/333-00000000) is joining native_rtp technology [Jan 21 17:16:53] DEBUG[31337][C-00000000] chan_sip.c: Deferring reinvite on SIP 'yjo08nkBGlkgv1gORGcfATVEbAUmGzZb' - It's audio will be redirected to IP 10.24.18.190:16064 [Jan 21 17:16:53] DEBUG[31337][C-00000000] chan_sip.c: Sending reinvite on SIP '5e03f5042f326c3f4be367537982d134@10.24.17.195:5060' - It's audio soon redirected to IP 10.24.18.16:4036 [Jan 21 17:16:53] DEBUG[31337][C-00000000] chan_sip.c: Strict routing enforced for session 5e03f5042f326c3f4be367537982d134@10.24.17.195:5060 [Jan 21 17:16:53] DEBUG[31337][C-00000000] netsock2.c: Splitting '10.24.18.190:5060' into... [Jan 21 17:16:53] DEBUG[31337][C-00000000] netsock2.c: ...host '10.24.18.190' and port '5060'. [Jan 21 17:16:53] DEBUG[31337][C-00000000] chan_sip.c: ** Our native-bridge filtered capablity: (alaw) [Jan 21 17:16:53] DEBUG[31337][C-00000000] chan_sip.c: ** Our capability: (alaw) Video flag: True Text flag: True [Jan 21 17:16:53] DEBUG[31337][C-00000000] chan_sip.c: ** Our prefcodec: (g722) [Jan 21 17:16:53] DEBUG[31337][C-00000000] chan_sip.c: -- Done with adding codecs to SDP [Jan 21 17:16:53] DEBUG[31337][C-00000000] chan_sip.c: Done building SDP. Settling with this capability: (alaw) [Jan 21 17:16:53] DEBUG[31337][C-00000000] chan_sip.c: Initializing already initialized SIP dialog 5e03f5042f326c3f4be367537982d134@10.24.17.195:5060 (presumably reinvite) [Jan 21 17:16:53] DEBUG[31337][C-00000000] chan_sip.c: Header 0 [ 40]: INVITE sip:123@10.24.18.190:5060 SIP/2.0 [Jan 21 17:16:53] DEBUG[31337][C-00000000] chan_sip.c: Header 1 [ 57]: Via: SIP/2.0/UDP 10.24.17.195:5060;branch=z9hG4bK0fcd628f [Jan 21 17:16:53] DEBUG[31337][C-00000000] chan_sip.c: Header 2 [ 16]: Max-Forwards: 70 [Jan 21 17:16:53] DEBUG[31337][C-00000000] chan_sip.c: Header 3 [ 49]: From: "333" ;tag=as59d0b62d [Jan 21 17:16:53] DEBUG[31337][C-00000000] chan_sip.c: Header 4 [ 41]: To: ;tag=as45fc47e8 [Jan 21 17:16:53] DEBUG[31337][C-00000000] chan_sip.c: Header 5 [ 36]: Contact: [Jan 21 17:16:53] DEBUG[31337][C-00000000] chan_sip.c: Header 6 [ 59]: Call-ID: 5e03f5042f326c3f4be367537982d134@10.24.17.195:5060 [Jan 21 17:16:53] DEBUG[31337][C-00000000] chan_sip.c: Header 7 [ 16]: CSeq: 103 INVITE [Jan 21 17:16:53] DEBUG[31337][C-00000000] chan_sip.c: Header 8 [ 31]: User-Agent: Asterisk PBX 13.7.0 [Jan 21 17:16:53] DEBUG[31337][C-00000000] chan_sip.c: Header 9 [ 35]: Session-Expires: 1800;refresher=uas [Jan 21 17:16:53] DEBUG[31337][C-00000000] chan_sip.c: Header 10 [ 10]: Min-SE: 90 [Jan 21 17:16:53] DEBUG[31337][C-00000000] chan_sip.c: Header 11 [ 90]: Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE [Jan 21 17:16:53] DEBUG[31337][C-00000000] chan_sip.c: Header 12 [ 26]: Supported: replaces, timer [Jan 21 17:16:53] DEBUG[31337][C-00000000] chan_sip.c: Header 13 [ 52]: X-asterisk-Info: SIP re-invite (External RTP bridge) [Jan 21 17:16:53] DEBUG[31337][C-00000000] chan_sip.c: Header 14 [ 29]: Content-Type: application/sdp [Jan 21 17:16:53] DEBUG[31337][C-00000000] chan_sip.c: *** SIP TIMER: Initializing retransmit timer on packet: Id #6 [Jan 21 17:16:53] DEBUG[31337][C-00000000] chan_sip.c: Trying to put 'INVITE sip:' onto UDP socket destined for 10.24.18.190:5060 [Jan 21 17:16:53] DEBUG[31337][C-00000000] bridge.c: Bridge ae15d38f-5b54-4f4e-ad30-028191d26f7a: 0x8d3b4e4(SIP/OutTrunk-00000001) is joining native_rtp technology [Jan 21 17:16:53] DEBUG[31337][C-00000000] bridge.c: Bridge ae15d38f-5b54-4f4e-ad30-028191d26f7a: calling native_rtp technology start [Jan 21 17:16:53] DEBUG[31337][C-00000000] bridge.c: Bridge ae15d38f-5b54-4f4e-ad30-028191d26f7a: calling simple_bridge technology destructor [Jan 21 17:16:53] DEBUG[31337][C-00000000] res_rtp_asterisk.c: Not changing SSRC since we haven't sent any RTP yet [Jan 21 17:16:53] DEBUG[31337][C-00000000] bridge.c: Bridge technology softmix does not have any capabilities we want. [Jan 21 17:16:53] DEBUG[31337][C-00000000] bridge.c: Bridge technology holding_bridge does not have any capabilities we want. [Jan 21 17:16:53] DEBUG[31337][C-00000000] bridge.c: Chose bridge technology native_rtp [Jan 21 17:16:53] DEBUG[31337][C-00000000] bridge.c: Bridge ae15d38f-5b54-4f4e-ad30-028191d26f7a is already using the new technology. [Jan 21 17:16:53] DEBUG[31288] cdr.c: Finalized CDR for SIP/OutTrunk-00000001 - start 1453418213.812997 answer 1453418213.849575 end 1453418213.853211 dispo ANSWERED [Jan 21 17:16:53] DEBUG[31275] threadpool.c: Increasing threadpool stasis-core's size by 1 [Jan 21 17:16:53] DEBUG[31338][C-00000000] bridge.c: Bridge technology softmix does not have any capabilities we want. [Jan 21 17:16:53] DEBUG[31338][C-00000000] bridge.c: Bridge technology holding_bridge does not have any capabilities we want. [Jan 21 17:16:53] DEBUG[31338][C-00000000] bridge.c: Chose bridge technology native_rtp [Jan 21 17:16:53] DEBUG[31338][C-00000000] bridge.c: Bridge ae15d38f-5b54-4f4e-ad30-028191d26f7a is already using the new technology. [Jan 21 17:16:53] DEBUG[31319] chan_sip.c: Header 0 [ 18]: SIP/2.0 100 Trying [Jan 21 17:16:53] DEBUG[31319] chan_sip.c: Header 1 [ 79]: Via: SIP/2.0/UDP 10.24.17.195:5060;branch=z9hG4bK0fcd628f;received=10.24.17.195 [Jan 21 17:16:53] DEBUG[31319] chan_sip.c: Header 2 [ 49]: From: "333" ;tag=as59d0b62d [Jan 21 17:16:53] DEBUG[31319] chan_sip.c: Header 3 [ 41]: To: ;tag=as45fc47e8 [Jan 21 17:16:53] DEBUG[31319] chan_sip.c: Header 4 [ 59]: Call-ID: 5e03f5042f326c3f4be367537982d134@10.24.17.195:5060 [Jan 21 17:16:53] DEBUG[31319] chan_sip.c: Header 5 [ 16]: CSeq: 103 INVITE [Jan 21 17:16:53] DEBUG[31319] chan_sip.c: Header 6 [ 31]: Server: Asterisk PBX 13.7.0-rc3 [Jan 21 17:16:53] DEBUG[31319] chan_sip.c: Header 7 [ 90]: Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE [Jan 21 17:16:53] DEBUG[31319] chan_sip.c: Header 8 [ 26]: Supported: replaces, timer [Jan 21 17:16:53] DEBUG[31319] chan_sip.c: Header 9 [ 35]: Session-Expires: 1800;refresher=uas [Jan 21 17:16:53] DEBUG[31319] chan_sip.c: Header 10 [ 36]: Contact: [Jan 21 17:16:53] DEBUG[31319] chan_sip.c: Header 11 [ 17]: Content-Length: 0 [Jan 21 17:16:53] DEBUG[31319] chan_sip.c: = Looking for Call ID: 5e03f5042f326c3f4be367537982d134@10.24.17.195:5060 (Checking To) --From tag as59d0b62d --To-tag as45fc47e8 [Jan 21 17:16:53] DEBUG[31319][C-00000000] chan_sip.c: *** SIP TIMER: Cancelling retransmission #6 - INVITE (got response) [Jan 21 17:16:53] DEBUG[31319][C-00000000] chan_sip.c: (Provisional) Stopping retransmission (but retaining packet) on '5e03f5042f326c3f4be367537982d134@10.24.17.195:5060' Request 103: Found [Jan 21 17:16:53] DEBUG[31319][C-00000000] chan_sip.c: SIP response 100 to RE-invite on outgoing call 5e03f5042f326c3f4be367537982d134@10.24.17.195:5060 [Jan 21 17:16:53] DEBUG[31319] chan_sip.c: Header 0 [ 14]: SIP/2.0 200 OK [Jan 21 17:16:53] DEBUG[31319] chan_sip.c: Header 1 [ 79]: Via: SIP/2.0/UDP 10.24.17.195:5060;branch=z9hG4bK0fcd628f;received=10.24.17.195 [Jan 21 17:16:53] DEBUG[31319] chan_sip.c: Header 2 [ 49]: From: "333" ;tag=as59d0b62d [Jan 21 17:16:53] DEBUG[31319] chan_sip.c: Header 3 [ 41]: To: ;tag=as45fc47e8 [Jan 21 17:16:53] DEBUG[31319] chan_sip.c: Header 4 [ 59]: Call-ID: 5e03f5042f326c3f4be367537982d134@10.24.17.195:5060 [Jan 21 17:16:53] DEBUG[31319] chan_sip.c: Header 5 [ 16]: CSeq: 103 INVITE [Jan 21 17:16:53] DEBUG[31319] chan_sip.c: Header 6 [ 31]: Server: Asterisk PBX 13.7.0-rc3 [Jan 21 17:16:53] DEBUG[31319] chan_sip.c: Header 7 [ 90]: Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE [Jan 21 17:16:53] DEBUG[31319] chan_sip.c: Header 8 [ 26]: Supported: replaces, timer [Jan 21 17:16:53] DEBUG[31319] chan_sip.c: Header 9 [ 35]: Session-Expires: 1800;refresher=uas [Jan 21 17:16:53] DEBUG[31319] chan_sip.c: Header 10 [ 36]: Contact: [Jan 21 17:16:53] DEBUG[31319] chan_sip.c: Header 11 [ 29]: Content-Type: application/sdp [Jan 21 17:16:53] DEBUG[31319] chan_sip.c: Header 12 [ 14]: Require: timer [Jan 21 17:16:53] DEBUG[31319] chan_sip.c: Header 13 [ 19]: Content-Length: 238 [Jan 21 17:16:53] DEBUG[31319] chan_sip.c: Header 14 [ 0]: [Jan 21 17:16:53] DEBUG[31319] chan_sip.c: Body 0 [ 3]: v=0 [Jan 21 17:16:53] DEBUG[31319] chan_sip.c: Body 1 [ 46]: o=root 105845436 105845437 IN IP4 10.24.18.190 [Jan 21 17:16:53] DEBUG[31319] chan_sip.c: Body 2 [ 25]: s=Asterisk PBX 13.7.0-rc3 [Jan 21 17:16:53] DEBUG[31319] chan_sip.c: Body 3 [ 21]: c=IN IP4 10.24.18.190 [Jan 21 17:16:53] DEBUG[31319] chan_sip.c: Body 4 [ 5]: t=0 0 [Jan 21 17:16:53] DEBUG[31319] chan_sip.c: Body 5 [ 26]: m=audio 16064 RTP/AVP 8 96 [Jan 21 17:16:53] DEBUG[31319] chan_sip.c: Body 6 [ 20]: a=rtpmap:8 PCMA/8000 [Jan 21 17:16:53] DEBUG[31319] chan_sip.c: Body 7 [ 32]: a=rtpmap:96 telephone-event/8000 [Jan 21 17:16:53] DEBUG[31319] chan_sip.c: Body 8 [ 14]: a=fmtp:96 0-16 [Jan 21 17:16:53] DEBUG[31319] chan_sip.c: Body 9 [ 14]: a=maxptime:150 [Jan 21 17:16:53] DEBUG[31319] chan_sip.c: Body 10 [ 10]: a=sendrecv [Jan 21 17:16:53] DEBUG[31319] chan_sip.c: = Looking for Call ID: 5e03f5042f326c3f4be367537982d134@10.24.17.195:5060 (Checking To) --From tag as59d0b62d --To-tag as45fc47e8 [Jan 21 17:16:53] DEBUG[31319][C-00000000] chan_sip.c: Acked pending invite 103 [Jan 21 17:16:53] DEBUG[31319][C-00000000] chan_sip.c: Stopping retransmission on '5e03f5042f326c3f4be367537982d134@10.24.17.195:5060' of Request 103: Match Found [Jan 21 17:16:53] DEBUG[31319][C-00000000] chan_sip.c: SIP response 200 to RE-invite on outgoing call 5e03f5042f326c3f4be367537982d134@10.24.17.195:5060 [Jan 21 17:16:53] DEBUG[31319][C-00000000] chan_sip.c: Processing session-level SDP v=0... UNSUPPORTED OR FAILED. [Jan 21 17:16:53] DEBUG[31319][C-00000000] chan_sip.c: Processing session-level SDP o=root 105845436 105845437 IN IP4 10.24.18.190... OK. [Jan 21 17:16:53] DEBUG[31319][C-00000000] chan_sip.c: Processing session-level SDP s=Asterisk PBX 13.7.0-rc3... UNSUPPORTED OR FAILED. [Jan 21 17:16:53] DEBUG[31319][C-00000000] netsock2.c: Splitting '10.24.18.190' into... [Jan 21 17:16:53] DEBUG[31319][C-00000000] netsock2.c: ...host '10.24.18.190' and port ''. [Jan 21 17:16:53] DEBUG[31319][C-00000000] chan_sip.c: Processing session-level SDP c=IN IP4 10.24.18.190... OK. [Jan 21 17:16:53] DEBUG[31319][C-00000000] chan_sip.c: Processing session-level SDP t=0 0... UNSUPPORTED OR FAILED. [Jan 21 17:16:53] DEBUG[31319][C-00000000] rtp_engine.c: Setting tx payload type 8 based on m type on 0xb3697c60 [Jan 21 17:16:53] DEBUG[31319][C-00000000] rtp_engine.c: Don't have a default tx payload type 96 format for m type on 0xb3697c60 [Jan 21 17:16:53] DEBUG[31319][C-00000000] chan_sip.c: Processing media-level (audio) SDP a=rtpmap:8 PCMA/8000... OK. [Jan 21 17:16:53] DEBUG[31319][C-00000000] chan_sip.c: Processing media-level (audio) SDP a=rtpmap:96 telephone-event/8000... OK. [Jan 21 17:16:53] DEBUG[31319][C-00000000] chan_sip.c: Processing media-level (audio) SDP a=fmtp:96 0-16... UNSUPPORTED OR FAILED. [Jan 21 17:16:53] DEBUG[31319][C-00000000] chan_sip.c: Processing media-level (audio) SDP a=maxptime:150... UNSUPPORTED OR FAILED. [Jan 21 17:16:53] DEBUG[31319][C-00000000] chan_sip.c: Processing media-level (audio) SDP a=sendrecv... OK. [Jan 21 17:16:53] DEBUG[31319][C-00000000] res_rtp_asterisk.c: Set role to CONTROLLING (0x8d367e4) [Jan 21 17:16:53] DEBUG[31319][C-00000000] res_rtp_asterisk.c: Set role failed; no ice instance (0x8d367e4) [Jan 21 17:16:53] DEBUG[31319][C-00000000] rtp_engine.c: Copying payload 8 (0x93d9214) from 0xb3697c60 to 0x8d36990 [Jan 21 17:16:53] DEBUG[31319][C-00000000] rtp_engine.c: Copying payload 96 (0x93e5284) from 0xb3697c60 to 0x8d36990 [Jan 21 17:16:53] DEBUG[31319][C-00000000] res_rtp_asterisk.c: Setup RTCP on RTP instance '0x8d367e4' [Jan 21 17:16:53] DEBUG[31319][C-00000000] chan_sip.c: We're settling with these formats: (alaw) [Jan 21 17:16:53] DEBUG[31319][C-00000000] chan_sip.c: We have an owner, now see if we need to change this call [Jan 21 17:16:53] DEBUG[31319][C-00000000] chan_sip.c: Setting native formats after processing SDP. peer joint formats (alaw), old nativeformats (alaw) [Jan 21 17:16:53] DEBUG[31319][C-00000000] chan_sip.c: Updating call counter for outgoing call [Jan 21 17:16:53] DEBUG[31319][C-00000000] netsock2.c: Splitting '10.24.18.190:5060' into... [Jan 21 17:16:53] DEBUG[31319][C-00000000] netsock2.c: ...host '10.24.18.190' and port '5060'. [Jan 21 17:16:53] DEBUG[31319][C-00000000] chan_sip.c: Session-Expires: 1800 [Jan 21 17:16:53] DEBUG[31319][C-00000000] chan_sip.c: Refresher: UAS [Jan 21 17:16:53] DEBUG[31319][C-00000000] chan_sip.c: Session timer stopped: 3 - 5e03f5042f326c3f4be367537982d134@10.24.17.195:5060 [Jan 21 17:16:53] DEBUG[31319][C-00000000] chan_sip.c: Session timer started: 1 - 5e03f5042f326c3f4be367537982d134@10.24.17.195:5060 1768000ms [Jan 21 17:16:53] DEBUG[31319][C-00000000] chan_sip.c: Strict routing enforced for session 5e03f5042f326c3f4be367537982d134@10.24.17.195:5060 [Jan 21 17:16:53] DEBUG[31319][C-00000000] netsock2.c: Splitting '10.24.18.190:5060' into... [Jan 21 17:16:53] DEBUG[31319][C-00000000] netsock2.c: ...host '10.24.18.190' and port '5060'. [Jan 21 17:16:53] DEBUG[31319][C-00000000] chan_sip.c: Trying to put 'ACK sip:123' onto UDP socket destined for 10.24.18.190:5060 [Jan 21 17:16:54] DEBUG[31337][C-00000000] res_rtp_asterisk.c: 0x8d3e398 -- Probation learning mode pass with source address 10.24.18.16:4036 [Jan 21 17:16:54] DEBUG[31338][C-00000000] translate.c: Sample size different 320 vs 160 [Jan 21 17:16:54] DEBUG[31338][C-00000000] res_rtp_asterisk.c: Ooh, format changed from none to alaw [Jan 21 17:16:54] DEBUG[31338][C-00000000] res_rtp_asterisk.c: Starting RTCP transmission on RTP instance '0x8d367e4' [Jan 21 17:16:54] DEBUG[31338][C-00000000] translate.c: Sample size different 320 vs 160 [Jan 21 17:16:54] DEBUG[31338][C-00000000] translate.c: Sample size different 320 vs 160 [Jan 21 17:16:54] DEBUG[31338][C-00000000] translate.c: Sample size different 320 vs 160 [Jan 21 17:16:54] DEBUG[31338][C-00000000] translate.c: Sample size different 320 vs 160 [Jan 21 17:16:54] DEBUG[31338][C-00000000] translate.c: Sample size different 320 vs 160 [Jan 21 17:16:54] DEBUG[31338][C-00000000] translate.c: Sample size different 320 vs 160 [Jan 21 17:16:54] DEBUG[31319] chan_sip.c: Header 0 [ 37]: ACK sip:123@10.24.17.195:5060 SIP/2.0 [Jan 21 17:16:54] DEBUG[31319] chan_sip.c: Header 1 [ 88]: Via: SIP/2.0/UDP 10.24.18.16:5060;rport;branch=z9hG4bKPjpiC5uq9DFUnXtqyl2VG47o21q7LlAiwD [Jan 21 17:16:54] DEBUG[31319] chan_sip.c: Header 2 [ 16]: Max-Forwards: 70 [Jan 21 17:16:54] DEBUG[31319] chan_sip.c: Header 3 [ 71]: From: "333" ;tag=Q9smPlsbBZ-xV3mr6py9Q.7x8CQidAcU [Jan 21 17:16:54] DEBUG[31319] chan_sip.c: Header 4 [ 41]: To: ;tag=as698e1ba6 [Jan 21 17:16:54] DEBUG[31319] chan_sip.c: Header 5 [ 41]: Call-ID: yjo08nkBGlkgv1gORGcfATVEbAUmGzZb [Jan 21 17:16:54] DEBUG[31319] chan_sip.c: Header 6 [ 15]: CSeq: 29298 ACK [Jan 21 17:16:54] DEBUG[31319] chan_sip.c: Header 7 [ 17]: Content-Length: 0 [Jan 21 17:16:54] DEBUG[31319] chan_sip.c: = Looking for Call ID: yjo08nkBGlkgv1gORGcfATVEbAUmGzZb (Checking From) --From tag Q9smPlsbBZ-xV3mr6py9Q.7x8CQidAcU --To-tag as698e1ba6 [Jan 21 17:16:54] DEBUG[31319][C-00000000] chan_sip.c: **** Received ACK (6) - Command in SIP ACK [Jan 21 17:16:54] DEBUG[31319][C-00000000] chan_sip.c: ** SIP TIMER: Cancelling retransmit of packet (reply received) Retransid #5 [Jan 21 17:16:54] DEBUG[31319][C-00000000] chan_sip.c: Stopping retransmission on 'yjo08nkBGlkgv1gORGcfATVEbAUmGzZb' of Response 29298: Match Found [Jan 21 17:16:54] DEBUG[31319][C-00000000] chan_sip.c: Sending pending reinvite on 'yjo08nkBGlkgv1gORGcfATVEbAUmGzZb' [Jan 21 17:16:54] DEBUG[31319][C-00000000] chan_sip.c: Strict routing enforced for session yjo08nkBGlkgv1gORGcfATVEbAUmGzZb [Jan 21 17:16:54] DEBUG[31319][C-00000000] netsock2.c: Splitting '10.24.18.16:5060' into... [Jan 21 17:16:54] DEBUG[31319][C-00000000] netsock2.c: ...host '10.24.18.16' and port '5060'. [Jan 21 17:16:54] DEBUG[31319][C-00000000] chan_sip.c: ** Our native-bridge filtered capablity: (nothing) [Jan 21 17:16:54] DEBUG[31319][C-00000000] chan_sip.c: ** Our capability: (nothing) Video flag: True Text flag: True [Jan 21 17:16:54] DEBUG[31319][C-00000000] chan_sip.c: ** Our prefcodec: (nothing) [Jan 21 17:16:54] DEBUG[31319][C-00000000] chan_sip.c: -- Done with adding codecs to SDP [Jan 21 17:16:54] DEBUG[31319][C-00000000] chan_sip.c: Setting framing on incoming call: 0 [Jan 21 17:16:54] DEBUG[31319][C-00000000] chan_sip.c: Done building SDP. Settling with this capability: (nothing) [Jan 21 17:16:54] DEBUG[31319][C-00000000] chan_sip.c: Initializing already initialized SIP dialog yjo08nkBGlkgv1gORGcfATVEbAUmGzZb (presumably reinvite) [Jan 21 17:16:54] DEBUG[31319][C-00000000] chan_sip.c: Header 0 [ 42]: INVITE sip:333@10.24.18.16:5060;ob SIP/2.0 [Jan 21 17:16:54] DEBUG[31319][C-00000000] chan_sip.c: Header 1 [ 63]: Via: SIP/2.0/UDP 10.24.17.195:5060;branch=z9hG4bK646a96bf;rport [Jan 21 17:16:54] DEBUG[31319][C-00000000] chan_sip.c: Header 2 [ 16]: Max-Forwards: 70 [Jan 21 17:16:54] DEBUG[31319][C-00000000] chan_sip.c: Header 3 [ 43]: From: ;tag=as698e1ba6 [Jan 21 17:16:54] DEBUG[31319][C-00000000] chan_sip.c: Header 4 [ 69]: To: "333" ;tag=Q9smPlsbBZ-xV3mr6py9Q.7x8CQidAcU [Jan 21 17:16:54] DEBUG[31319][C-00000000] chan_sip.c: Header 5 [ 36]: Contact: [Jan 21 17:16:54] DEBUG[31319][C-00000000] chan_sip.c: Header 6 [ 41]: Call-ID: yjo08nkBGlkgv1gORGcfATVEbAUmGzZb [Jan 21 17:16:54] DEBUG[31319][C-00000000] chan_sip.c: Header 7 [ 16]: CSeq: 102 INVITE [Jan 21 17:16:54] DEBUG[31319][C-00000000] chan_sip.c: Header 8 [ 31]: User-Agent: Asterisk PBX 13.7.0 [Jan 21 17:16:54] DEBUG[31319][C-00000000] chan_sip.c: Header 9 [ 35]: Session-Expires: 1800;refresher=uac [Jan 21 17:16:54] DEBUG[31319][C-00000000] chan_sip.c: Header 10 [ 10]: Min-SE: 90 [Jan 21 17:16:54] DEBUG[31319][C-00000000] chan_sip.c: Header 11 [ 90]: Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE [Jan 21 17:16:54] DEBUG[31319][C-00000000] chan_sip.c: Header 12 [ 26]: Supported: replaces, timer [Jan 21 17:16:54] DEBUG[31319][C-00000000] chan_sip.c: Header 13 [ 52]: X-asterisk-Info: SIP re-invite (External RTP bridge) [Jan 21 17:16:54] DEBUG[31319][C-00000000] chan_sip.c: Header 14 [ 29]: Content-Type: application/sdp [Jan 21 17:16:54] DEBUG[31319][C-00000000] chan_sip.c: *** SIP TIMER: Initializing retransmit timer on packet: Id #8 [Jan 21 17:16:54] DEBUG[31319][C-00000000] chan_sip.c: Trying to put 'INVITE sip:' onto UDP socket destined for 10.24.18.16:5060 [Jan 21 17:16:54] DEBUG[31338][C-00000000] translate.c: Sample size different 320 vs 160 [Jan 21 17:16:54] DEBUG[31338][C-00000000] translate.c: Sample size different 320 vs 160 [Jan 21 17:16:54] DEBUG[31338][C-00000000] translate.c: Sample size different 320 vs 160 [Jan 21 17:16:54] DEBUG[31338][C-00000000] translate.c: Sample size different 320 vs 160 [Jan 21 17:16:54] DEBUG[31338][C-00000000] translate.c: Sample size different 320 vs 160 [Jan 21 17:16:54] DEBUG[31338][C-00000000] translate.c: Sample size different 320 vs 160 [Jan 21 17:16:54] DEBUG[31338][C-00000000] translate.c: Sample size different 320 vs 160 [Jan 21 17:16:54] DEBUG[31319] chan_sip.c: Header 0 [ 37]: BYE sip:123@10.24.17.195:5060 SIP/2.0 [Jan 21 17:16:54] DEBUG[31319] chan_sip.c: Header 1 [ 88]: Via: SIP/2.0/UDP 10.24.18.16:5060;rport;branch=z9hG4bKPjk7LhK8W7xu4NPxgQQfQqdS1uKoH5ZV5B [Jan 21 17:16:54] DEBUG[31319] chan_sip.c: Header 2 [ 16]: Max-Forwards: 70 [Jan 21 17:16:54] DEBUG[31319] chan_sip.c: Header 3 [ 71]: From: "333" ;tag=Q9smPlsbBZ-xV3mr6py9Q.7x8CQidAcU [Jan 21 17:16:54] DEBUG[31319] chan_sip.c: Header 4 [ 41]: To: ;tag=as698e1ba6 [Jan 21 17:16:54] DEBUG[31319] chan_sip.c: Header 5 [ 41]: Call-ID: yjo08nkBGlkgv1gORGcfATVEbAUmGzZb [Jan 21 17:16:54] DEBUG[31319] chan_sip.c: Header 6 [ 15]: CSeq: 29299 BYE [Jan 21 17:16:54] DEBUG[31319] chan_sip.c: Header 7 [ 17]: Content-Length: 0 [Jan 21 17:16:54] DEBUG[31319] chan_sip.c: = Looking for Call ID: yjo08nkBGlkgv1gORGcfATVEbAUmGzZb (Checking From) --From tag Q9smPlsbBZ-xV3mr6py9Q.7x8CQidAcU --To-tag as698e1ba6 [Jan 21 17:16:54] DEBUG[31319][C-00000000] chan_sip.c: **** Received BYE (8) - Command in SIP BYE [Jan 21 17:16:54] DEBUG[31319][C-00000000] chan_sip.c: Acked pending invite 102 [Jan 21 17:16:54] DEBUG[31319][C-00000000] chan_sip.c: ** SIP TIMER: Cancelling retransmit of packet (reply received) Retransid #8 [Jan 21 17:16:54] DEBUG[31319][C-00000000] chan_sip.c: Stopping retransmission on 'yjo08nkBGlkgv1gORGcfATVEbAUmGzZb' of Request 102: Match Found [Jan 21 17:16:54] DEBUG[31319][C-00000000] chan_sip.c: Initializing initreq for method BYE - callid yjo08nkBGlkgv1gORGcfATVEbAUmGzZb [Jan 21 17:16:54] DEBUG[31319][C-00000000] netsock2.c: Splitting '10.24.18.16:5060' into... [Jan 21 17:16:54] DEBUG[31319][C-00000000] netsock2.c: ...host '10.24.18.16' and port '5060'. [Jan 21 17:16:54] DEBUG[31319][C-00000000] chan_sip.c: Setting SIP_ALREADYGONE on dialog yjo08nkBGlkgv1gORGcfATVEbAUmGzZb [Jan 21 17:16:54] DEBUG[31319][C-00000000] chan_sip.c: Session timer stopped: 2 - yjo08nkBGlkgv1gORGcfATVEbAUmGzZb [Jan 21 17:16:54] DEBUG[31319][C-00000000] chan_sip.c: Received bye, issuing owner hangup [Jan 21 17:16:54] DEBUG[31319][C-00000000] chan_sip.c: Trying to put 'SIP/2.0 200' onto UDP socket destined for 10.24.18.16:5060 [Jan 21 17:16:54] DEBUG[31337][C-00000000] bridge_channel.c: Setting 0x8d3b934(SIP/333-00000000) state from:0 to:1 [Jan 21 17:16:54] DEBUG[31337][C-00000000] bridge_channel.c: Bridge ae15d38f-5b54-4f4e-ad30-028191d26f7a: pulling 0x8d3b934(SIP/333-00000000) [Jan 21 17:16:54] DEBUG[31337][C-00000000] bridge_channel.c: Bridge ae15d38f-5b54-4f4e-ad30-028191d26f7a: 0x8d3b934(SIP/333-00000000) is leaving native_rtp technology [Jan 21 17:16:54] DEBUG[31337][C-00000000] res_rtp_asterisk.c: Ignoring duplicate RTCP property on RTP instance '0x8d367e4' [Jan 21 17:16:54] DEBUG[31337][C-00000000] chan_sip.c: Sending reinvite on SIP '5e03f5042f326c3f4be367537982d134@10.24.17.195:5060' - It's audio soon redirected to IP 10.24.17.195:5060 [Jan 21 17:16:54] DEBUG[31337][C-00000000] chan_sip.c: Strict routing enforced for session 5e03f5042f326c3f4be367537982d134@10.24.17.195:5060 [Jan 21 17:16:54] DEBUG[31337][C-00000000] netsock2.c: Splitting '10.24.18.190:5060' into... [Jan 21 17:16:54] DEBUG[31337][C-00000000] netsock2.c: ...host '10.24.18.190' and port '5060'. [Jan 21 17:16:54] DEBUG[31337][C-00000000] chan_sip.c: ** Our capability: (alaw) Video flag: True Text flag: True [Jan 21 17:16:54] DEBUG[31337][C-00000000] chan_sip.c: ** Our prefcodec: (g722) [Jan 21 17:16:54] DEBUG[31337][C-00000000] chan_sip.c: -- Done with adding codecs to SDP [Jan 21 17:16:54] DEBUG[31337][C-00000000] chan_sip.c: Done building SDP. Settling with this capability: (alaw) [Jan 21 17:16:54] DEBUG[31337][C-00000000] chan_sip.c: Initializing already initialized SIP dialog 5e03f5042f326c3f4be367537982d134@10.24.17.195:5060 (presumably reinvite) [Jan 21 17:16:54] DEBUG[31337][C-00000000] chan_sip.c: Header 0 [ 40]: INVITE sip:123@10.24.18.190:5060 SIP/2.0 [Jan 21 17:16:54] DEBUG[31337][C-00000000] chan_sip.c: Header 1 [ 57]: Via: SIP/2.0/UDP 10.24.17.195:5060;branch=z9hG4bK0168ada6 [Jan 21 17:16:54] DEBUG[31337][C-00000000] chan_sip.c: Header 2 [ 16]: Max-Forwards: 70 [Jan 21 17:16:54] DEBUG[31337][C-00000000] chan_sip.c: Header 3 [ 49]: From: "333" ;tag=as59d0b62d [Jan 21 17:16:54] DEBUG[31337][C-00000000] chan_sip.c: Header 4 [ 41]: To: ;tag=as45fc47e8 [Jan 21 17:16:54] DEBUG[31337][C-00000000] chan_sip.c: Header 5 [ 36]: Contact: [Jan 21 17:16:54] DEBUG[31337][C-00000000] chan_sip.c: Header 6 [ 59]: Call-ID: 5e03f5042f326c3f4be367537982d134@10.24.17.195:5060 [Jan 21 17:16:54] DEBUG[31337][C-00000000] chan_sip.c: Header 7 [ 16]: CSeq: 104 INVITE [Jan 21 17:16:54] DEBUG[31337][C-00000000] chan_sip.c: Header 8 [ 31]: User-Agent: Asterisk PBX 13.7.0 [Jan 21 17:16:54] DEBUG[31337][C-00000000] chan_sip.c: Header 9 [ 35]: Session-Expires: 1800;refresher=uas [Jan 21 17:16:54] DEBUG[31337][C-00000000] chan_sip.c: Header 10 [ 10]: Min-SE: 90 [Jan 21 17:16:54] DEBUG[31337][C-00000000] chan_sip.c: Header 11 [ 90]: Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE [Jan 21 17:16:54] DEBUG[31337][C-00000000] chan_sip.c: Header 12 [ 26]: Supported: replaces, timer [Jan 21 17:16:54] DEBUG[31337][C-00000000] chan_sip.c: Header 13 [ 52]: X-asterisk-Info: SIP re-invite (External RTP bridge) [Jan 21 17:16:54] DEBUG[31337][C-00000000] chan_sip.c: Header 14 [ 29]: Content-Type: application/sdp [Jan 21 17:16:54] DEBUG[31337][C-00000000] chan_sip.c: *** SIP TIMER: Initializing retransmit timer on packet: Id #12 [Jan 21 17:16:54] DEBUG[31337][C-00000000] chan_sip.c: Trying to put 'INVITE sip:' onto UDP socket destined for 10.24.18.190:5060 [Jan 21 17:16:54] DEBUG[31337][C-00000000] bridge_native_rtp.c: Discontinued RTP bridging of 'SIP/333-00000000' and 'SIP/OutTrunk-00000001' - media will flow through Asterisk core [Jan 21 17:16:54] DEBUG[31337][C-00000000] bridge.c: Bridge ae15d38f-5b54-4f4e-ad30-028191d26f7a: dissolving bridge with cause 16(Normal Clearing) [Jan 21 17:16:54] DEBUG[31337][C-00000000] bridge_channel.c: Setting 0x8d3b4e4(SIP/OutTrunk-00000001) state from:0 to:2 [Jan 21 17:16:54] DEBUG[31337][C-00000000] bridge.c: Bridge ae15d38f-5b54-4f4e-ad30-028191d26f7a: queueing action type:13 sub:1001 [Jan 21 17:16:54] DEBUG[31275] threadpool.c: Increasing threadpool stasis-core's size by 1 [Jan 21 17:16:54] DEBUG[31319] chan_sip.c: Header 0 [ 18]: SIP/2.0 100 Trying [Jan 21 17:16:54] DEBUG[31319] chan_sip.c: Header 1 [ 79]: Via: SIP/2.0/UDP 10.24.17.195:5060;branch=z9hG4bK0168ada6;received=10.24.17.195 [Jan 21 17:16:54] DEBUG[31319] chan_sip.c: Header 2 [ 49]: From: "333" ;tag=as59d0b62d [Jan 21 17:16:54] DEBUG[31319] chan_sip.c: Header 3 [ 41]: To: ;tag=as45fc47e8 [Jan 21 17:16:54] DEBUG[31319] chan_sip.c: Header 4 [ 59]: Call-ID: 5e03f5042f326c3f4be367537982d134@10.24.17.195:5060 [Jan 21 17:16:54] DEBUG[31319] chan_sip.c: Header 5 [ 16]: CSeq: 104 INVITE [Jan 21 17:16:54] DEBUG[31319] chan_sip.c: Header 6 [ 31]: Server: Asterisk PBX 13.7.0-rc3 [Jan 21 17:16:54] DEBUG[31319] chan_sip.c: Header 7 [ 90]: Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE [Jan 21 17:16:54] DEBUG[31319] chan_sip.c: Header 8 [ 26]: Supported: replaces, timer [Jan 21 17:16:54] DEBUG[31319] chan_sip.c: Header 9 [ 35]: Session-Expires: 1800;refresher=uas [Jan 21 17:16:54] DEBUG[31319] chan_sip.c: Header 10 [ 36]: Contact: [Jan 21 17:16:54] DEBUG[31319] chan_sip.c: Header 11 [ 17]: Content-Length: 0 [Jan 21 17:16:54] DEBUG[31319] chan_sip.c: = Looking for Call ID: 5e03f5042f326c3f4be367537982d134@10.24.17.195:5060 (Checking To) --From tag as59d0b62d --To-tag as45fc47e8 [Jan 21 17:16:54] DEBUG[31319][C-00000000] chan_sip.c: *** SIP TIMER: Cancelling retransmission #12 - INVITE (got response) [Jan 21 17:16:54] DEBUG[31319][C-00000000] chan_sip.c: (Provisional) Stopping retransmission (but retaining packet) on '5e03f5042f326c3f4be367537982d134@10.24.17.195:5060' Request 104: Found [Jan 21 17:16:54] DEBUG[31319][C-00000000] chan_sip.c: SIP response 100 to RE-invite on outgoing call 5e03f5042f326c3f4be367537982d134@10.24.17.195:5060 [Jan 21 17:16:54] DEBUG[31319] chan_sip.c: Header 0 [ 14]: SIP/2.0 200 OK [Jan 21 17:16:54] DEBUG[31319] chan_sip.c: Header 1 [ 79]: Via: SIP/2.0/UDP 10.24.17.195:5060;branch=z9hG4bK0168ada6;received=10.24.17.195 [Jan 21 17:16:54] DEBUG[31319] chan_sip.c: Header 2 [ 49]: From: "333" ;tag=as59d0b62d [Jan 21 17:16:54] DEBUG[31319] chan_sip.c: Header 3 [ 41]: To: ;tag=as45fc47e8 [Jan 21 17:16:54] DEBUG[31319] chan_sip.c: Header 4 [ 59]: Call-ID: 5e03f5042f326c3f4be367537982d134@10.24.17.195:5060 [Jan 21 17:16:54] DEBUG[31319] chan_sip.c: Header 5 [ 16]: CSeq: 104 INVITE [Jan 21 17:16:54] DEBUG[31319] chan_sip.c: Header 6 [ 31]: Server: Asterisk PBX 13.7.0-rc3 [Jan 21 17:16:54] DEBUG[31319] chan_sip.c: Header 7 [ 90]: Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE [Jan 21 17:16:54] DEBUG[31319] chan_sip.c: Header 8 [ 26]: Supported: replaces, timer [Jan 21 17:16:54] DEBUG[31319] chan_sip.c: Header 9 [ 35]: Session-Expires: 1800;refresher=uas [Jan 21 17:16:54] DEBUG[31319] chan_sip.c: Header 10 [ 36]: Contact: [Jan 21 17:16:54] DEBUG[31319] chan_sip.c: Header 11 [ 29]: Content-Type: application/sdp [Jan 21 17:16:54] DEBUG[31319] chan_sip.c: Header 12 [ 14]: Require: timer [Jan 21 17:16:54] DEBUG[31319] chan_sip.c: Header 13 [ 19]: Content-Length: 238 [Jan 21 17:16:54] DEBUG[31319] chan_sip.c: Header 14 [ 0]: [Jan 21 17:16:54] DEBUG[31319] chan_sip.c: Body 0 [ 3]: v=0 [Jan 21 17:16:54] DEBUG[31319] chan_sip.c: Body 1 [ 46]: o=root 105845436 105845438 IN IP4 10.24.18.190 [Jan 21 17:16:54] DEBUG[31319] chan_sip.c: Body 2 [ 25]: s=Asterisk PBX 13.7.0-rc3 [Jan 21 17:16:54] DEBUG[31319] chan_sip.c: Body 3 [ 21]: c=IN IP4 10.24.18.190 [Jan 21 17:16:54] DEBUG[31319] chan_sip.c: Body 4 [ 5]: t=0 0 [Jan 21 17:16:54] DEBUG[31319] chan_sip.c: Body 5 [ 26]: m=audio 16064 RTP/AVP 8 96 [Jan 21 17:16:54] DEBUG[31319] chan_sip.c: Body 6 [ 20]: a=rtpmap:8 PCMA/8000 [Jan 21 17:16:54] DEBUG[31319] chan_sip.c: Body 7 [ 32]: a=rtpmap:96 telephone-event/8000 [Jan 21 17:16:54] DEBUG[31319] chan_sip.c: Body 8 [ 14]: a=fmtp:96 0-16 [Jan 21 17:16:54] DEBUG[31319] chan_sip.c: Body 9 [ 14]: a=maxptime:150 [Jan 21 17:16:54] DEBUG[31319] chan_sip.c: Body 10 [ 10]: a=sendrecv [Jan 21 17:16:54] DEBUG[31319] chan_sip.c: = Looking for Call ID: 5e03f5042f326c3f4be367537982d134@10.24.17.195:5060 (Checking To) --From tag as59d0b62d --To-tag as45fc47e8 [Jan 21 17:16:54] DEBUG[31319][C-00000000] chan_sip.c: Acked pending invite 104 [Jan 21 17:16:54] DEBUG[31319][C-00000000] chan_sip.c: Stopping retransmission on '5e03f5042f326c3f4be367537982d134@10.24.17.195:5060' of Request 104: Match Found [Jan 21 17:16:54] DEBUG[31319][C-00000000] chan_sip.c: SIP response 200 to RE-invite on outgoing call 5e03f5042f326c3f4be367537982d134@10.24.17.195:5060 [Jan 21 17:16:54] DEBUG[31319][C-00000000] chan_sip.c: Processing session-level SDP v=0... UNSUPPORTED OR FAILED. [Jan 21 17:16:54] DEBUG[31319][C-00000000] chan_sip.c: Processing session-level SDP o=root 105845436 105845438 IN IP4 10.24.18.190... OK. [Jan 21 17:16:54] DEBUG[31319][C-00000000] chan_sip.c: Processing session-level SDP s=Asterisk PBX 13.7.0-rc3... UNSUPPORTED OR FAILED. [Jan 21 17:16:54] DEBUG[31319][C-00000000] netsock2.c: Splitting '10.24.18.190' into... [Jan 21 17:16:54] DEBUG[31319][C-00000000] netsock2.c: ...host '10.24.18.190' and port ''. [Jan 21 17:16:54] DEBUG[31319][C-00000000] chan_sip.c: Processing session-level SDP c=IN IP4 10.24.18.190... OK. [Jan 21 17:16:54] DEBUG[31319][C-00000000] chan_sip.c: Processing session-level SDP t=0 0... UNSUPPORTED OR FAILED. [Jan 21 17:16:54] DEBUG[31319][C-00000000] rtp_engine.c: Setting tx payload type 8 based on m type on 0xb3697c60 [Jan 21 17:16:54] DEBUG[31319][C-00000000] rtp_engine.c: Don't have a default tx payload type 96 format for m type on 0xb3697c60 [Jan 21 17:16:54] DEBUG[31319][C-00000000] chan_sip.c: Processing media-level (audio) SDP a=rtpmap:8 PCMA/8000... OK. [Jan 21 17:16:54] DEBUG[31319][C-00000000] chan_sip.c: Processing media-level (audio) SDP a=rtpmap:96 telephone-event/8000... OK. [Jan 21 17:16:54] DEBUG[31319][C-00000000] chan_sip.c: Processing media-level (audio) SDP a=fmtp:96 0-16... UNSUPPORTED OR FAILED. [Jan 21 17:16:54] DEBUG[31319][C-00000000] chan_sip.c: Processing media-level (audio) SDP a=maxptime:150... UNSUPPORTED OR FAILED. [Jan 21 17:16:54] DEBUG[31319][C-00000000] chan_sip.c: Processing media-level (audio) SDP a=sendrecv... OK. [Jan 21 17:16:54] DEBUG[31319][C-00000000] res_rtp_asterisk.c: Set role to CONTROLLING (0x8d367e4) [Jan 21 17:16:54] DEBUG[31319][C-00000000] res_rtp_asterisk.c: Set role failed; no ice instance (0x8d367e4) [Jan 21 17:16:54] DEBUG[31319][C-00000000] res_rtp_asterisk.c: Setting RTCP address on RTP instance '0x8d367e4' [Jan 21 17:16:54] DEBUG[31319][C-00000000] rtp_engine.c: Copying payload 8 (0x890b1dc) from 0xb3697c60 to 0x8d36990 [Jan 21 17:16:54] DEBUG[31319][C-00000000] rtp_engine.c: Copying payload 96 (0x8d3b774) from 0xb3697c60 to 0x8d36990 [Jan 21 17:16:54] DEBUG[31319][C-00000000] res_rtp_asterisk.c: Ignoring duplicate RTCP property on RTP instance '0x8d367e4' [Jan 21 17:16:54] DEBUG[31319][C-00000000] chan_sip.c: We're settling with these formats: (alaw) [Jan 21 17:16:54] DEBUG[31319][C-00000000] chan_sip.c: We have an owner, now see if we need to change this call [Jan 21 17:16:54] DEBUG[31319][C-00000000] chan_sip.c: Setting native formats after processing SDP. peer joint formats (alaw), old nativeformats (alaw) [Jan 21 17:16:54] DEBUG[31319][C-00000000] chan_sip.c: Updating call counter for outgoing call [Jan 21 17:16:54] DEBUG[31319][C-00000000] netsock2.c: Splitting '10.24.18.190:5060' into... [Jan 21 17:16:54] DEBUG[31319][C-00000000] netsock2.c: ...host '10.24.18.190' and port '5060'. [Jan 21 17:16:54] DEBUG[31319][C-00000000] chan_sip.c: Session-Expires: 1800 [Jan 21 17:16:54] DEBUG[31319][C-00000000] chan_sip.c: Refresher: UAS [Jan 21 17:16:54] DEBUG[31319][C-00000000] chan_sip.c: Session timer stopped: 1 - 5e03f5042f326c3f4be367537982d134@10.24.17.195:5060 [Jan 21 17:16:54] DEBUG[31319][C-00000000] chan_sip.c: Session timer started: 13 - 5e03f5042f326c3f4be367537982d134@10.24.17.195:5060 1768000ms [Jan 21 17:16:54] DEBUG[31319][C-00000000] chan_sip.c: Strict routing enforced for session 5e03f5042f326c3f4be367537982d134@10.24.17.195:5060 [Jan 21 17:16:54] DEBUG[31319][C-00000000] netsock2.c: Splitting '10.24.18.190:5060' into... [Jan 21 17:16:54] DEBUG[31319][C-00000000] netsock2.c: ...host '10.24.18.190' and port '5060'. [Jan 21 17:16:54] DEBUG[31288] cdr.c: Finalized CDR for SIP/333-00000000 - start 1453418213.808790 answer 1453418213.850310 end 1453418214.495090 dispo ANSWERED [Jan 21 17:16:54] DEBUG[31337][C-00000000] bridge.c: Bridge ae15d38f-5b54-4f4e-ad30-028191d26f7a is dissolved, not performing smart bridge operation. [Jan 21 17:16:54] DEBUG[31337][C-00000000] app_dial.c: Exiting with DIALSTATUS=ANSWER. [Jan 21 17:16:54] DEBUG[31337][C-00000000] pbx.c: Spawn extension (users,123,5) exited non-zero on 'SIP/333-00000000' [Jan 21 17:16:54] DEBUG[31337][C-00000000] channel.c: Soft-Hanging (0x10) up channel 'SIP/333-00000000' [Jan 21 17:16:54] DEBUG[31337][C-00000000] channel.c: Hanging up channel 'SIP/333-00000000' [Jan 21 17:16:54] DEBUG[31337][C-00000000] chan_sip.c: Hangup call SIP/333-00000000, SIP callid yjo08nkBGlkgv1gORGcfATVEbAUmGzZb [Jan 21 17:16:54] DEBUG[31288] cdr_radius.c: Unable to create RADIUS record. CDR not recorded! [Jan 21 17:16:54] DEBUG[31288] res_config_sqlite.c: About to query table structure: SELECT sql FROM sqlite_master WHERE type='table' AND tbl_name='ast_cdr' [Jan 21 17:16:54] DEBUG[31288] res_config_sqlite.c: Found field: id INTEGER [Jan 21 17:16:54] DEBUG[31288] res_config_sqlite.c: Found field: clid VARCHAR(80) NOT NULL DEFAULT '' [Jan 21 17:16:54] DEBUG[31288] res_config_sqlite.c: Found field: src VARCHAR(80) NOT NULL DEFAULT '' [Jan 21 17:16:54] DEBUG[31288] res_config_sqlite.c: Found field: dst VARCHAR(80) NOT NULL DEFAULT '' [Jan 21 17:16:54] DEBUG[31288] res_config_sqlite.c: Found field: dcontext VARCHAR(80) NOT NULL DEFAULT '' [Jan 21 17:16:54] DEBUG[31288] res_config_sqlite.c: Found field: channel VARCHAR(80) NOT NULL DEFAULT '' [Jan 21 17:16:54] DEBUG[31288] res_config_sqlite.c: Found field: dstchannel VARCHAR(80) NOT NULL DEFAULT '' [Jan 21 17:16:54] DEBUG[31288] res_config_sqlite.c: Found field: lastapp VARCHAR(80) NOT NULL DEFAULT '' [Jan 21 17:16:54] DEBUG[31288] res_config_sqlite.c: Found field: lastdata VARCHAR(80) NOT NULL DEFAULT '' [Jan 21 17:16:54] DEBUG[31288] res_config_sqlite.c: Found field: start DATETIME NOT NULL DEFAULT '0000-00-00 00:00:00' [Jan 21 17:16:54] DEBUG[31288] res_config_sqlite.c: Found field: answer DATETIME NOT NULL DEFAULT '0000-00-00 00:00:00' [Jan 21 17:16:54] DEBUG[31288] res_config_sqlite.c: Found field: end DATETIME NOT NULL DEFAULT '0000-00-00 00:00:00' [Jan 21 17:16:54] DEBUG[31288] res_config_sqlite.c: Found field: duration INT(11) NOT NULL DEFAULT 0 [Jan 21 17:16:54] DEBUG[31288] res_config_sqlite.c: Found field: billsec INT(11) NOT NULL DEFAULT 0 [Jan 21 17:16:54] DEBUG[31288] res_config_sqlite.c: Found field: disposition VARCHAR(45) NOT NULL DEFAULT '' [Jan 21 17:16:54] DEBUG[31288] res_config_sqlite.c: Found field: amaflags INT(11) NOT NULL DEFAULT 0 [Jan 21 17:16:54] DEBUG[31288] res_config_sqlite.c: Found field: accountcode VARCHAR(20) NOT NULL DEFAULT '' [Jan 21 17:16:54] DEBUG[31288] res_config_sqlite.c: Found field: uniqueid VARCHAR(32) NOT NULL DEFAULT '' [Jan 21 17:16:54] DEBUG[31288] res_config_sqlite.c: Found field: userfield VARCHAR(255) NOT NULL DEFAULT '' [Jan 21 17:16:54] DEBUG[31288] res_config_sqlite.c: Found field: PRIMARY KEY (id) [Jan 21 17:16:54] DEBUG[31288] res_config_sqlite.c: SQL query: INSERT INTO ast_cdr (clid,src,dst,dcontext,channel,dstchannel,lastapp,lastdata,start,answer,end,duration,billsec,disposition,amaflags,uniqueid) VALUES ('"333" <333>','333','123','users','SIP/333-00000000','SIP/OutTrunk-00000001','Dial','SIP/123@OutTrunk,60,','2016-01-21 17:16:53','2016-01-21 17:16:53','2016-01-21 17:16:54','0','0','ANSWERED','DOCUMENTATION','1453418213.0') [Jan 21 17:16:54] DEBUG[31287] devicestate.c: No provider found, checking channel drivers for SIP - 333 [Jan 21 17:16:54] DEBUG[31287] chan_sip.c: Checking device state for peer 333 [Jan 21 17:16:54] DEBUG[31287] devicestate.c: Changing state for SIP/333 - state 1 (Not in use) [Jan 21 17:16:54] DEBUG[31319][C-00000000] chan_sip.c: Trying to put 'ACK sip:123' onto UDP socket destined for 10.24.18.190:5060 [Jan 21 17:16:54] DEBUG[31338][C-00000000] bridge_channel.c: Bridge ae15d38f-5b54-4f4e-ad30-028191d26f7a: pulling 0x8d3b4e4(SIP/OutTrunk-00000001) [Jan 21 17:16:54] DEBUG[31338][C-00000000] bridge_channel.c: Bridge ae15d38f-5b54-4f4e-ad30-028191d26f7a: 0x8d3b4e4(SIP/OutTrunk-00000001) is leaving native_rtp technology [Jan 21 17:16:54] DEBUG[31338][C-00000000] bridge_channel.c: Channel SIP/OutTrunk-00000001 will survive this bridge; clearing outgoing (dialed) flag [Jan 21 17:16:54] DEBUG[31338][C-00000000] bridge.c: Bridge ae15d38f-5b54-4f4e-ad30-028191d26f7a is dissolved, not performing smart bridge operation. [Jan 21 17:16:54] DEBUG[31338][C-00000000] res_rtp_asterisk.c: Changing ssrc from 1010992990 to 1232650747 due to a source change [Jan 21 17:16:54] DEBUG[31338][C-00000000] bridge.c: Bridge ae15d38f-5b54-4f4e-ad30-028191d26f7a: actually destroying basic bridge, nobody wants it anymore [Jan 21 17:16:54] DEBUG[31338][C-00000000] bridge.c: Bridge ae15d38f-5b54-4f4e-ad30-028191d26f7a: calling basic bridge destructor [Jan 21 17:16:54] DEBUG[31338][C-00000000] bridge.c: Bridge ae15d38f-5b54-4f4e-ad30-028191d26f7a: calling native_rtp technology stop [Jan 21 17:16:54] DEBUG[31338][C-00000000] bridge.c: Bridge ae15d38f-5b54-4f4e-ad30-028191d26f7a: calling native_rtp technology destructor [Jan 21 17:16:54] DEBUG[31338][C-00000000] channel.c: Hanging up channel 'SIP/OutTrunk-00000001' [Jan 21 17:16:54] DEBUG[31338][C-00000000] chan_sip.c: Hangup call SIP/OutTrunk-00000001, SIP callid 5e03f5042f326c3f4be367537982d134@10.24.17.195:5060 [Jan 21 17:16:54] DEBUG[31338][C-00000000] res_rtp_asterisk.c: Setting RTCP address on RTP instance '0x8d367e4' [Jan 21 17:16:54] DEBUG[31319] chan_sip.c: Header 0 [ 14]: SIP/2.0 200 OK [Jan 21 17:16:54] DEBUG[31319] chan_sip.c: Header 1 [ 90]: Via: SIP/2.0/UDP 10.24.17.195:5060;rport=5060;received=10.24.17.195;branch=z9hG4bK646a96bf [Jan 21 17:16:54] DEBUG[31319] chan_sip.c: Header 2 [ 41]: Call-ID: yjo08nkBGlkgv1gORGcfATVEbAUmGzZb [Jan 21 17:16:54] DEBUG[31338][C-00000000] chan_sip.c: Session timer stopped: 13 - 5e03f5042f326c3f4be367537982d134@10.24.17.195:5060 [Jan 21 17:16:54] DEBUG[31319] chan_sip.c: Header 3 [ 43]: From: ;tag=as698e1ba6 [Jan 21 17:16:54] DEBUG[31319] chan_sip.c: Header 4 [ 69]: To: "333" ;tag=Q9smPlsbBZ-xV3mr6py9Q.7x8CQidAcU [Jan 21 17:16:54] DEBUG[31319] chan_sip.c: Header 5 [ 16]: CSeq: 102 INVITE [Jan 21 17:16:54] DEBUG[31319] chan_sip.c: Header 6 [ 44]: Contact: "333" [Jan 21 17:16:54] DEBUG[31319] chan_sip.c: Header 7 [ 90]: Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, SUBSCRIBE, NOTIFY, REFER, MESSAGE, OPTIONS [Jan 21 17:16:54] DEBUG[31319] chan_sip.c: Header 8 [ 46]: Supported: replaces, 100rel, timer, norefersub [Jan 21 17:16:54] DEBUG[31319] chan_sip.c: Header 9 [ 29]: Content-Type: application/sdp [Jan 21 17:16:54] DEBUG[31319] chan_sip.c: Header 10 [ 19]: Content-Length: 218 [Jan 21 17:16:54] DEBUG[31319] chan_sip.c: Header 11 [ 0]: [Jan 21 17:16:54] DEBUG[31319] chan_sip.c: Body 0 [ 3]: v=0 [Jan 21 17:16:54] DEBUG[31319] chan_sip.c: Body 1 [ 42]: o=- 148789504 148789505 IN IP4 10.24.18.16 [Jan 21 17:16:54] DEBUG[31319] chan_sip.c: Body 2 [ 8]: s=digphn [Jan 21 17:16:54] DEBUG[31319] chan_sip.c: Body 3 [ 20]: c=IN IP4 10.24.18.16 [Jan 21 17:16:54] DEBUG[31319] chan_sip.c: Body 4 [ 5]: t=0 0 [Jan 21 17:16:54] DEBUG[31319] chan_sip.c: Body 5 [ 9]: a=X-nat:0 [Jan 21 17:16:54] DEBUG[31319] chan_sip.c: Body 6 [ 23]: m=audio 4036 RTP/AVP 96 [Jan 21 17:16:54] DEBUG[31319] chan_sip.c: Body 7 [ 30]: a=rtcp:4037 IN IP4 10.24.18.16 [Jan 21 17:16:54] DEBUG[31319] chan_sip.c: Body 8 [ 10]: a=sendrecv [Jan 21 17:16:54] DEBUG[31319] chan_sip.c: Body 9 [ 32]: a=rtpmap:96 telephone-event/8000 [Jan 21 17:16:54] DEBUG[31319] chan_sip.c: Body 10 [ 14]: a=fmtp:96 0-15 [Jan 21 17:16:54] DEBUG[31319] chan_sip.c: = Looking for Call ID: yjo08nkBGlkgv1gORGcfATVEbAUmGzZb (Checking To) --From tag as698e1ba6 --To-tag Q9smPlsbBZ-xV3mr6py9Q.7x8CQidAcU [Jan 21 17:16:54] DEBUG[31319][C-00000000] chan_sip.c: Stopping retransmission on 'yjo08nkBGlkgv1gORGcfATVEbAUmGzZb' of Request 102: Match Not Found [Jan 21 17:16:54] DEBUG[31319][C-00000000] chan_sip.c: Strict routing enforced for session yjo08nkBGlkgv1gORGcfATVEbAUmGzZb [Jan 21 17:16:54] DEBUG[31338][C-00000000] chan_sip.c: Strict routing enforced for session 5e03f5042f326c3f4be367537982d134@10.24.17.195:5060 [Jan 21 17:16:54] DEBUG[31319][C-00000000] netsock2.c: Splitting '10.24.18.16:5060' into... [Jan 21 17:16:54] DEBUG[31338][C-00000000] netsock2.c: Splitting '10.24.18.190:5060' into... [Jan 21 17:16:54] DEBUG[31338][C-00000000] netsock2.c: ...host '10.24.18.190' and port '5060'. [Jan 21 17:16:54] DEBUG[31319][C-00000000] netsock2.c: ...host '10.24.18.16' and port '5060'. [Jan 21 17:16:54] DEBUG[31319][C-00000000] chan_sip.c: Trying to put 'ACK sip:333' onto UDP socket destined for 10.24.18.16:5060 [Jan 21 17:16:54] DEBUG[31338][C-00000000] chan_sip.c: *** SIP TIMER: Initializing retransmit timer on packet: Id #16 [Jan 21 17:16:54] DEBUG[31338][C-00000000] chan_sip.c: Trying to put 'BYE sip:123' onto UDP socket destined for 10.24.18.190:5060 [Jan 21 17:16:54] DEBUG[31287] devicestate.c: No provider found, checking channel drivers for SIP - OutTrunk [Jan 21 17:16:54] DEBUG[31287] chan_sip.c: Checking device state for peer OutTrunk [Jan 21 17:16:54] DEBUG[31287] devicestate.c: Changing state for SIP/OutTrunk - state 1 (Not in use) [Jan 21 17:16:54] DEBUG[31319] chan_sip.c: Header 0 [ 14]: SIP/2.0 200 OK [Jan 21 17:16:54] DEBUG[31319] chan_sip.c: Header 1 [ 79]: Via: SIP/2.0/UDP 10.24.17.195:5060;branch=z9hG4bK67aa6bf9;received=10.24.17.195 [Jan 21 17:16:54] DEBUG[31319] chan_sip.c: Header 2 [ 49]: From: "333" ;tag=as59d0b62d [Jan 21 17:16:54] DEBUG[31319] chan_sip.c: Header 3 [ 41]: To: ;tag=as45fc47e8 [Jan 21 17:16:54] DEBUG[31319] chan_sip.c: Header 4 [ 59]: Call-ID: 5e03f5042f326c3f4be367537982d134@10.24.17.195:5060 [Jan 21 17:16:54] DEBUG[31319] chan_sip.c: Header 5 [ 13]: CSeq: 105 BYE [Jan 21 17:16:54] DEBUG[31319] chan_sip.c: Header 6 [ 31]: Server: Asterisk PBX 13.7.0-rc3 [Jan 21 17:16:54] DEBUG[31319] chan_sip.c: Header 7 [ 90]: Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE [Jan 21 17:16:54] DEBUG[31319] chan_sip.c: Header 8 [ 26]: Supported: replaces, timer [Jan 21 17:16:54] DEBUG[31319] chan_sip.c: Header 9 [ 17]: Content-Length: 0 [Jan 21 17:16:54] DEBUG[31319] chan_sip.c: = Looking for Call ID: 5e03f5042f326c3f4be367537982d134@10.24.17.195:5060 (Checking To) --From tag as59d0b62d --To-tag as45fc47e8 [Jan 21 17:16:54] DEBUG[31319][C-00000000] chan_sip.c: ** SIP TIMER: Cancelling retransmit of packet (reply received) Retransid #16 [Jan 21 17:16:54] DEBUG[31319][C-00000000] chan_sip.c: Stopping retransmission on '5e03f5042f326c3f4be367537982d134@10.24.17.195:5060' of Request 105: Match Found [Jan 21 17:16:54] DEBUG[31319] chan_sip.c: Destroying SIP dialog 5e03f5042f326c3f4be367537982d134@10.24.17.195:5060 [Jan 21 17:16:54] DEBUG[31319] rtp_engine.c: Destroyed RTP instance '0x8d367e4' [Jan 21 17:16:54] DEBUG[31288] cdr.c: CDR for SIP/OutTrunk-00000001 is dialed and has no Party B; discarding [Jan 21 17:16:55] DEBUG[31319] chan_sip.c: Header 0 [ 14]: SIP/2.0 200 OK [Jan 21 17:16:55] DEBUG[31319] chan_sip.c: Header 1 [ 90]: Via: SIP/2.0/UDP 10.24.17.195:5060;rport=5060;received=10.24.17.195;branch=z9hG4bK646a96bf [Jan 21 17:16:55] DEBUG[31319] chan_sip.c: Header 2 [ 41]: Call-ID: yjo08nkBGlkgv1gORGcfATVEbAUmGzZb [Jan 21 17:16:55] DEBUG[31319] chan_sip.c: Header 3 [ 43]: From: ;tag=as698e1ba6 [Jan 21 17:16:55] DEBUG[31319] chan_sip.c: Header 4 [ 69]: To: "333" ;tag=Q9smPlsbBZ-xV3mr6py9Q.7x8CQidAcU [Jan 21 17:16:55] DEBUG[31319] chan_sip.c: Header 5 [ 16]: CSeq: 102 INVITE [Jan 21 17:16:55] DEBUG[31319] chan_sip.c: Header 6 [ 44]: Contact: "333" [Jan 21 17:16:55] DEBUG[31319] chan_sip.c: Header 7 [ 90]: Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, SUBSCRIBE, NOTIFY, REFER, MESSAGE, OPTIONS [Jan 21 17:16:55] DEBUG[31319] chan_sip.c: Header 8 [ 46]: Supported: replaces, 100rel, timer, norefersub [Jan 21 17:16:55] DEBUG[31319] chan_sip.c: Header 9 [ 29]: Content-Type: application/sdp [Jan 21 17:16:55] DEBUG[31319] chan_sip.c: Header 10 [ 19]: Content-Length: 218 [Jan 21 17:16:55] DEBUG[31319] chan_sip.c: Header 11 [ 0]: [Jan 21 17:16:55] DEBUG[31319] chan_sip.c: Body 0 [ 3]: v=0 [Jan 21 17:16:55] DEBUG[31319] chan_sip.c: Body 1 [ 42]: o=- 148789504 148789505 IN IP4 10.24.18.16 [Jan 21 17:16:55] DEBUG[31319] chan_sip.c: Body 2 [ 8]: s=digphn [Jan 21 17:16:55] DEBUG[31319] chan_sip.c: Body 3 [ 20]: c=IN IP4 10.24.18.16 [Jan 21 17:16:55] DEBUG[31319] chan_sip.c: Body 4 [ 5]: t=0 0 [Jan 21 17:16:55] DEBUG[31319] chan_sip.c: Body 5 [ 9]: a=X-nat:0 [Jan 21 17:16:55] DEBUG[31319] chan_sip.c: Body 6 [ 23]: m=audio 4036 RTP/AVP 96 [Jan 21 17:16:55] DEBUG[31319] chan_sip.c: Body 7 [ 30]: a=rtcp:4037 IN IP4 10.24.18.16 [Jan 21 17:16:55] DEBUG[31319] chan_sip.c: Body 8 [ 10]: a=sendrecv [Jan 21 17:16:55] DEBUG[31319] chan_sip.c: Body 9 [ 32]: a=rtpmap:96 telephone-event/8000 [Jan 21 17:16:55] DEBUG[31319] chan_sip.c: Body 10 [ 14]: a=fmtp:96 0-15 [Jan 21 17:16:55] DEBUG[31319] chan_sip.c: = Looking for Call ID: yjo08nkBGlkgv1gORGcfATVEbAUmGzZb (Checking To) --From tag as698e1ba6 --To-tag Q9smPlsbBZ-xV3mr6py9Q.7x8CQidAcU [Jan 21 17:16:55] DEBUG[31319][C-00000000] chan_sip.c: Stopping retransmission on 'yjo08nkBGlkgv1gORGcfATVEbAUmGzZb' of Request 102: Match Not Found [Jan 21 17:16:55] DEBUG[31319][C-00000000] chan_sip.c: Strict routing enforced for session yjo08nkBGlkgv1gORGcfATVEbAUmGzZb [Jan 21 17:16:55] DEBUG[31319][C-00000000] netsock2.c: Splitting '10.24.18.16:5060' into... [Jan 21 17:16:55] DEBUG[31319][C-00000000] netsock2.c: ...host '10.24.18.16' and port '5060'. [Jan 21 17:16:55] DEBUG[31319][C-00000000] chan_sip.c: Trying to put 'ACK sip:333' onto UDP socket destined for 10.24.18.16:5060 [Jan 21 17:16:56] DEBUG[31319] chan_sip.c: Header 0 [ 14]: SIP/2.0 200 OK [Jan 21 17:16:56] DEBUG[31319] chan_sip.c: Header 1 [ 90]: Via: SIP/2.0/UDP 10.24.17.195:5060;rport=5060;received=10.24.17.195;branch=z9hG4bK646a96bf [Jan 21 17:16:56] DEBUG[31319] chan_sip.c: Header 2 [ 41]: Call-ID: yjo08nkBGlkgv1gORGcfATVEbAUmGzZb [Jan 21 17:16:56] DEBUG[31319] chan_sip.c: Header 3 [ 43]: From: ;tag=as698e1ba6 [Jan 21 17:16:56] DEBUG[31319] chan_sip.c: Header 4 [ 69]: To: "333" ;tag=Q9smPlsbBZ-xV3mr6py9Q.7x8CQidAcU [Jan 21 17:16:56] DEBUG[31319] chan_sip.c: Header 5 [ 16]: CSeq: 102 INVITE [Jan 21 17:16:56] DEBUG[31319] chan_sip.c: Header 6 [ 44]: Contact: "333" [Jan 21 17:16:56] DEBUG[31319] chan_sip.c: Header 7 [ 90]: Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, SUBSCRIBE, NOTIFY, REFER, MESSAGE, OPTIONS [Jan 21 17:16:56] DEBUG[31319] chan_sip.c: Header 8 [ 46]: Supported: replaces, 100rel, timer, norefersub [Jan 21 17:16:56] DEBUG[31319] chan_sip.c: Header 9 [ 29]: Content-Type: application/sdp [Jan 21 17:16:56] DEBUG[31319] chan_sip.c: Header 10 [ 19]: Content-Length: 218 [Jan 21 17:16:56] DEBUG[31319] chan_sip.c: Header 11 [ 0]: [Jan 21 17:16:56] DEBUG[31319] chan_sip.c: Body 0 [ 3]: v=0 [Jan 21 17:16:56] DEBUG[31319] chan_sip.c: Body 1 [ 42]: o=- 148789504 148789505 IN IP4 10.24.18.16 [Jan 21 17:16:56] DEBUG[31319] chan_sip.c: Body 2 [ 8]: s=digphn [Jan 21 17:16:56] DEBUG[31319] chan_sip.c: Body 3 [ 20]: c=IN IP4 10.24.18.16 [Jan 21 17:16:56] DEBUG[31319] chan_sip.c: Body 4 [ 5]: t=0 0 [Jan 21 17:16:56] DEBUG[31319] chan_sip.c: Body 5 [ 9]: a=X-nat:0 [Jan 21 17:16:56] DEBUG[31319] chan_sip.c: Body 6 [ 23]: m=audio 4036 RTP/AVP 96 [Jan 21 17:16:56] DEBUG[31319] chan_sip.c: Body 7 [ 30]: a=rtcp:4037 IN IP4 10.24.18.16 [Jan 21 17:16:56] DEBUG[31319] chan_sip.c: Body 8 [ 10]: a=sendrecv [Jan 21 17:16:56] DEBUG[31319] chan_sip.c: Body 9 [ 32]: a=rtpmap:96 telephone-event/8000 [Jan 21 17:16:56] DEBUG[31319] chan_sip.c: Body 10 [ 14]: a=fmtp:96 0-15 [Jan 21 17:16:56] DEBUG[31319] chan_sip.c: = Looking for Call ID: yjo08nkBGlkgv1gORGcfATVEbAUmGzZb (Checking To) --From tag as698e1ba6 --To-tag Q9smPlsbBZ-xV3mr6py9Q.7x8CQidAcU [Jan 21 17:16:56] DEBUG[31319][C-00000000] chan_sip.c: Stopping retransmission on 'yjo08nkBGlkgv1gORGcfATVEbAUmGzZb' of Request 102: Match Not Found [Jan 21 17:16:56] DEBUG[31319][C-00000000] chan_sip.c: Strict routing enforced for session yjo08nkBGlkgv1gORGcfATVEbAUmGzZb [Jan 21 17:16:56] DEBUG[31319][C-00000000] netsock2.c: Splitting '10.24.18.16:5060' into... [Jan 21 17:16:56] DEBUG[31319][C-00000000] netsock2.c: ...host '10.24.18.16' and port '5060'. [Jan 21 17:16:56] DEBUG[31319][C-00000000] chan_sip.c: Trying to put 'ACK sip:333' onto UDP socket destined for 10.24.18.16:5060 [Jan 21 17:16:58] DEBUG[31319] chan_sip.c: Header 0 [ 14]: SIP/2.0 200 OK [Jan 21 17:16:58] DEBUG[31319] chan_sip.c: Header 1 [ 90]: Via: SIP/2.0/UDP 10.24.17.195:5060;rport=5060;received=10.24.17.195;branch=z9hG4bK646a96bf [Jan 21 17:16:58] DEBUG[31319] chan_sip.c: Header 2 [ 41]: Call-ID: yjo08nkBGlkgv1gORGcfATVEbAUmGzZb [Jan 21 17:16:58] DEBUG[31319] chan_sip.c: Header 3 [ 43]: From: ;tag=as698e1ba6 [Jan 21 17:16:58] DEBUG[31319] chan_sip.c: Header 4 [ 69]: To: "333" ;tag=Q9smPlsbBZ-xV3mr6py9Q.7x8CQidAcU [Jan 21 17:16:58] DEBUG[31319] chan_sip.c: Header 5 [ 16]: CSeq: 102 INVITE [Jan 21 17:16:58] DEBUG[31319] chan_sip.c: Header 6 [ 44]: Contact: "333" [Jan 21 17:16:58] DEBUG[31319] chan_sip.c: Header 7 [ 90]: Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, SUBSCRIBE, NOTIFY, REFER, MESSAGE, OPTIONS [Jan 21 17:16:58] DEBUG[31319] chan_sip.c: Header 8 [ 46]: Supported: replaces, 100rel, timer, norefersub [Jan 21 17:16:58] DEBUG[31319] chan_sip.c: Header 9 [ 29]: Content-Type: application/sdp [Jan 21 17:16:58] DEBUG[31319] chan_sip.c: Header 10 [ 19]: Content-Length: 218 [Jan 21 17:16:58] DEBUG[31319] chan_sip.c: Header 11 [ 0]: [Jan 21 17:16:58] DEBUG[31319] chan_sip.c: Body 0 [ 3]: v=0 [Jan 21 17:16:58] DEBUG[31319] chan_sip.c: Body 1 [ 42]: o=- 148789504 148789505 IN IP4 10.24.18.16 [Jan 21 17:16:58] DEBUG[31319] chan_sip.c: Body 2 [ 8]: s=digphn [Jan 21 17:16:58] DEBUG[31319] chan_sip.c: Body 3 [ 20]: c=IN IP4 10.24.18.16 [Jan 21 17:16:58] DEBUG[31319] chan_sip.c: Body 4 [ 5]: t=0 0 [Jan 21 17:16:58] DEBUG[31319] chan_sip.c: Body 5 [ 9]: a=X-nat:0 [Jan 21 17:16:58] DEBUG[31319] chan_sip.c: Body 6 [ 23]: m=audio 4036 RTP/AVP 96 [Jan 21 17:16:58] DEBUG[31319] chan_sip.c: Body 7 [ 30]: a=rtcp:4037 IN IP4 10.24.18.16 [Jan 21 17:16:58] DEBUG[31319] chan_sip.c: Body 8 [ 10]: a=sendrecv [Jan 21 17:16:58] DEBUG[31319] chan_sip.c: Body 9 [ 32]: a=rtpmap:96 telephone-event/8000 [Jan 21 17:16:58] DEBUG[31319] chan_sip.c: Body 10 [ 14]: a=fmtp:96 0-15 [Jan 21 17:16:58] DEBUG[31319] chan_sip.c: = Looking for Call ID: yjo08nkBGlkgv1gORGcfATVEbAUmGzZb (Checking To) --From tag as698e1ba6 --To-tag Q9smPlsbBZ-xV3mr6py9Q.7x8CQidAcU [Jan 21 17:16:58] DEBUG[31319][C-00000000] chan_sip.c: Stopping retransmission on 'yjo08nkBGlkgv1gORGcfATVEbAUmGzZb' of Request 102: Match Not Found [Jan 21 17:16:58] DEBUG[31319][C-00000000] chan_sip.c: Strict routing enforced for session yjo08nkBGlkgv1gORGcfATVEbAUmGzZb [Jan 21 17:16:58] DEBUG[31319][C-00000000] netsock2.c: Splitting '10.24.18.16:5060' into... [Jan 21 17:16:58] DEBUG[31319][C-00000000] netsock2.c: ...host '10.24.18.16' and port '5060'. [Jan 21 17:16:58] DEBUG[31319][C-00000000] chan_sip.c: Trying to put 'ACK sip:333' onto UDP socket destined for 10.24.18.16:5060 [Jan 21 17:17:02] DEBUG[31319] chan_sip.c: Header 0 [ 14]: SIP/2.0 200 OK [Jan 21 17:17:02] DEBUG[31319] chan_sip.c: Header 1 [ 90]: Via: SIP/2.0/UDP 10.24.17.195:5060;rport=5060;received=10.24.17.195;branch=z9hG4bK646a96bf [Jan 21 17:17:02] DEBUG[31319] chan_sip.c: Header 2 [ 41]: Call-ID: yjo08nkBGlkgv1gORGcfATVEbAUmGzZb [Jan 21 17:17:02] DEBUG[31319] chan_sip.c: Header 3 [ 43]: From: ;tag=as698e1ba6 [Jan 21 17:17:02] DEBUG[31319] chan_sip.c: Header 4 [ 69]: To: "333" ;tag=Q9smPlsbBZ-xV3mr6py9Q.7x8CQidAcU [Jan 21 17:17:02] DEBUG[31319] chan_sip.c: Header 5 [ 16]: CSeq: 102 INVITE [Jan 21 17:17:02] DEBUG[31319] chan_sip.c: Header 6 [ 44]: Contact: "333" [Jan 21 17:17:02] DEBUG[31319] chan_sip.c: Header 7 [ 90]: Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, SUBSCRIBE, NOTIFY, REFER, MESSAGE, OPTIONS [Jan 21 17:17:02] DEBUG[31319] chan_sip.c: Header 8 [ 46]: Supported: replaces, 100rel, timer, norefersub [Jan 21 17:17:02] DEBUG[31319] chan_sip.c: Header 9 [ 29]: Content-Type: application/sdp [Jan 21 17:17:02] DEBUG[31319] chan_sip.c: Header 10 [ 19]: Content-Length: 218 [Jan 21 17:17:02] DEBUG[31319] chan_sip.c: Header 11 [ 0]: [Jan 21 17:17:02] DEBUG[31319] chan_sip.c: Body 0 [ 3]: v=0 [Jan 21 17:17:02] DEBUG[31319] chan_sip.c: Body 1 [ 42]: o=- 148789504 148789505 IN IP4 10.24.18.16 [Jan 21 17:17:02] DEBUG[31319] chan_sip.c: Body 2 [ 8]: s=digphn [Jan 21 17:17:02] DEBUG[31319] chan_sip.c: Body 3 [ 20]: c=IN IP4 10.24.18.16 [Jan 21 17:17:02] DEBUG[31319] chan_sip.c: Body 4 [ 5]: t=0 0 [Jan 21 17:17:02] DEBUG[31319] chan_sip.c: Body 5 [ 9]: a=X-nat:0 [Jan 21 17:17:02] DEBUG[31319] chan_sip.c: Body 6 [ 23]: m=audio 4036 RTP/AVP 96 [Jan 21 17:17:02] DEBUG[31319] chan_sip.c: Body 7 [ 30]: a=rtcp:4037 IN IP4 10.24.18.16 [Jan 21 17:17:02] DEBUG[31319] chan_sip.c: Body 8 [ 10]: a=sendrecv [Jan 21 17:17:02] DEBUG[31319] chan_sip.c: Body 9 [ 32]: a=rtpmap:96 telephone-event/8000 [Jan 21 17:17:02] DEBUG[31319] chan_sip.c: Body 10 [ 14]: a=fmtp:96 0-15 [Jan 21 17:17:02] DEBUG[31319] chan_sip.c: = Looking for Call ID: yjo08nkBGlkgv1gORGcfATVEbAUmGzZb (Checking To) --From tag as698e1ba6 --To-tag Q9smPlsbBZ-xV3mr6py9Q.7x8CQidAcU [Jan 21 17:17:02] DEBUG[31319][C-00000000] chan_sip.c: Stopping retransmission on 'yjo08nkBGlkgv1gORGcfATVEbAUmGzZb' of Request 102: Match Not Found [Jan 21 17:17:02] DEBUG[31319][C-00000000] chan_sip.c: Strict routing enforced for session yjo08nkBGlkgv1gORGcfATVEbAUmGzZb [Jan 21 17:17:02] DEBUG[31319][C-00000000] netsock2.c: Splitting '10.24.18.16:5060' into... [Jan 21 17:17:02] DEBUG[31319][C-00000000] netsock2.c: ...host '10.24.18.16' and port '5060'. [Jan 21 17:17:02] DEBUG[31319][C-00000000] chan_sip.c: Trying to put 'ACK sip:333' onto UDP socket destined for 10.24.18.16:5060 [Jan 21 17:17:06] DEBUG[31319] chan_sip.c: Header 0 [ 14]: SIP/2.0 200 OK [Jan 21 17:17:06] DEBUG[31319] chan_sip.c: Header 1 [ 90]: Via: SIP/2.0/UDP 10.24.17.195:5060;rport=5060;received=10.24.17.195;branch=z9hG4bK646a96bf [Jan 21 17:17:06] DEBUG[31319] chan_sip.c: Header 2 [ 41]: Call-ID: yjo08nkBGlkgv1gORGcfATVEbAUmGzZb [Jan 21 17:17:06] DEBUG[31319] chan_sip.c: Header 3 [ 43]: From: ;tag=as698e1ba6 [Jan 21 17:17:06] DEBUG[31319] chan_sip.c: Header 4 [ 69]: To: "333" ;tag=Q9smPlsbBZ-xV3mr6py9Q.7x8CQidAcU [Jan 21 17:17:06] DEBUG[31319] chan_sip.c: Header 5 [ 16]: CSeq: 102 INVITE [Jan 21 17:17:06] DEBUG[31319] chan_sip.c: Header 6 [ 44]: Contact: "333" [Jan 21 17:17:06] DEBUG[31319] chan_sip.c: Header 7 [ 90]: Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, SUBSCRIBE, NOTIFY, REFER, MESSAGE, OPTIONS [Jan 21 17:17:06] DEBUG[31319] chan_sip.c: Header 8 [ 46]: Supported: replaces, 100rel, timer, norefersub [Jan 21 17:17:06] DEBUG[31319] chan_sip.c: Header 9 [ 29]: Content-Type: application/sdp [Jan 21 17:17:06] DEBUG[31319] chan_sip.c: Header 10 [ 19]: Content-Length: 218 [Jan 21 17:17:06] DEBUG[31319] chan_sip.c: Header 11 [ 0]: [Jan 21 17:17:06] DEBUG[31319] chan_sip.c: Body 0 [ 3]: v=0 [Jan 21 17:17:06] DEBUG[31319] chan_sip.c: Body 1 [ 42]: o=- 148789504 148789505 IN IP4 10.24.18.16 [Jan 21 17:17:06] DEBUG[31319] chan_sip.c: Body 2 [ 8]: s=digphn [Jan 21 17:17:06] DEBUG[31319] chan_sip.c: Body 3 [ 20]: c=IN IP4 10.24.18.16 [Jan 21 17:17:06] DEBUG[31319] chan_sip.c: Body 4 [ 5]: t=0 0 [Jan 21 17:17:06] DEBUG[31319] chan_sip.c: Body 5 [ 9]: a=X-nat:0 [Jan 21 17:17:06] DEBUG[31319] chan_sip.c: Body 6 [ 23]: m=audio 4036 RTP/AVP 96 [Jan 21 17:17:06] DEBUG[31319] chan_sip.c: Body 7 [ 30]: a=rtcp:4037 IN IP4 10.24.18.16 [Jan 21 17:17:06] DEBUG[31319] chan_sip.c: Body 8 [ 10]: a=sendrecv [Jan 21 17:17:06] DEBUG[31319] chan_sip.c: Body 9 [ 32]: a=rtpmap:96 telephone-event/8000 [Jan 21 17:17:06] DEBUG[31319] chan_sip.c: Body 10 [ 14]: a=fmtp:96 0-15 [Jan 21 17:17:06] DEBUG[31319] chan_sip.c: = Looking for Call ID: yjo08nkBGlkgv1gORGcfATVEbAUmGzZb (Checking To) --From tag as698e1ba6 --To-tag Q9smPlsbBZ-xV3mr6py9Q.7x8CQidAcU [Jan 21 17:17:06] DEBUG[31319][C-00000000] chan_sip.c: Stopping retransmission on 'yjo08nkBGlkgv1gORGcfATVEbAUmGzZb' of Request 102: Match Not Found [Jan 21 17:17:06] DEBUG[31319][C-00000000] chan_sip.c: Strict routing enforced for session yjo08nkBGlkgv1gORGcfATVEbAUmGzZb [Jan 21 17:17:06] DEBUG[31319][C-00000000] netsock2.c: Splitting '10.24.18.16:5060' into... [Jan 21 17:17:06] DEBUG[31319][C-00000000] netsock2.c: ...host '10.24.18.16' and port '5060'. [Jan 21 17:17:06] DEBUG[31319][C-00000000] chan_sip.c: Trying to put 'ACK sip:333' onto UDP socket destined for 10.24.18.16:5060 [Jan 21 17:17:06] DEBUG[31319] chan_sip.c: Auto destroying SIP dialog 'fvCAfxQoDLDfUaFxoo-SDdrQJjEkcsKA' [Jan 21 17:17:06] DEBUG[31319] chan_sip.c: Destroying SIP dialog fvCAfxQoDLDfUaFxoo-SDdrQJjEkcsKA [Jan 21 17:17:10] DEBUG[31319] chan_sip.c: Header 0 [ 14]: SIP/2.0 200 OK [Jan 21 17:17:10] DEBUG[31319] chan_sip.c: Header 1 [ 90]: Via: SIP/2.0/UDP 10.24.17.195:5060;rport=5060;received=10.24.17.195;branch=z9hG4bK646a96bf [Jan 21 17:17:10] DEBUG[31319] chan_sip.c: Header 2 [ 41]: Call-ID: yjo08nkBGlkgv1gORGcfATVEbAUmGzZb [Jan 21 17:17:10] DEBUG[31319] chan_sip.c: Header 3 [ 43]: From: ;tag=as698e1ba6 [Jan 21 17:17:10] DEBUG[31319] chan_sip.c: Header 4 [ 69]: To: "333" ;tag=Q9smPlsbBZ-xV3mr6py9Q.7x8CQidAcU [Jan 21 17:17:10] DEBUG[31319] chan_sip.c: Header 5 [ 16]: CSeq: 102 INVITE [Jan 21 17:17:10] DEBUG[31319] chan_sip.c: Header 6 [ 44]: Contact: "333" [Jan 21 17:17:10] DEBUG[31319] chan_sip.c: Header 7 [ 90]: Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, SUBSCRIBE, NOTIFY, REFER, MESSAGE, OPTIONS [Jan 21 17:17:10] DEBUG[31319] chan_sip.c: Header 8 [ 46]: Supported: replaces, 100rel, timer, norefersub [Jan 21 17:17:10] DEBUG[31319] chan_sip.c: Header 9 [ 29]: Content-Type: application/sdp [Jan 21 17:17:10] DEBUG[31319] chan_sip.c: Header 10 [ 19]: Content-Length: 218 [Jan 21 17:17:10] DEBUG[31319] chan_sip.c: Header 11 [ 0]: [Jan 21 17:17:10] DEBUG[31319] chan_sip.c: Body 0 [ 3]: v=0 [Jan 21 17:17:10] DEBUG[31319] chan_sip.c: Body 1 [ 42]: o=- 148789504 148789505 IN IP4 10.24.18.16 [Jan 21 17:17:10] DEBUG[31319] chan_sip.c: Body 2 [ 8]: s=digphn [Jan 21 17:17:10] DEBUG[31319] chan_sip.c: Body 3 [ 20]: c=IN IP4 10.24.18.16 [Jan 21 17:17:10] DEBUG[31319] chan_sip.c: Body 4 [ 5]: t=0 0 [Jan 21 17:17:10] DEBUG[31319] chan_sip.c: Body 5 [ 9]: a=X-nat:0 [Jan 21 17:17:10] DEBUG[31319] chan_sip.c: Body 6 [ 23]: m=audio 4036 RTP/AVP 96 [Jan 21 17:17:10] DEBUG[31319] chan_sip.c: Body 7 [ 30]: a=rtcp:4037 IN IP4 10.24.18.16 [Jan 21 17:17:10] DEBUG[31319] chan_sip.c: Body 8 [ 10]: a=sendrecv [Jan 21 17:17:10] DEBUG[31319] chan_sip.c: Body 9 [ 32]: a=rtpmap:96 telephone-event/8000 [Jan 21 17:17:10] DEBUG[31319] chan_sip.c: Body 10 [ 14]: a=fmtp:96 0-15 [Jan 21 17:17:10] DEBUG[31319] chan_sip.c: = Looking for Call ID: yjo08nkBGlkgv1gORGcfATVEbAUmGzZb (Checking To) --From tag as698e1ba6 --To-tag Q9smPlsbBZ-xV3mr6py9Q.7x8CQidAcU [Jan 21 17:17:10] DEBUG[31319][C-00000000] chan_sip.c: Stopping retransmission on 'yjo08nkBGlkgv1gORGcfATVEbAUmGzZb' of Request 102: Match Not Found [Jan 21 17:17:10] DEBUG[31319][C-00000000] chan_sip.c: Strict routing enforced for session yjo08nkBGlkgv1gORGcfATVEbAUmGzZb [Jan 21 17:17:10] DEBUG[31319][C-00000000] netsock2.c: Splitting '10.24.18.16:5060' into... [Jan 21 17:17:10] DEBUG[31319][C-00000000] netsock2.c: ...host '10.24.18.16' and port '5060'. [Jan 21 17:17:10] DEBUG[31319][C-00000000] chan_sip.c: Trying to put 'ACK sip:333' onto UDP socket destined for 10.24.18.16:5060 [Jan 21 17:17:14] DEBUG[31319] chan_sip.c: Header 0 [ 14]: SIP/2.0 200 OK [Jan 21 17:17:14] DEBUG[31319] chan_sip.c: Header 1 [ 90]: Via: SIP/2.0/UDP 10.24.17.195:5060;rport=5060;received=10.24.17.195;branch=z9hG4bK646a96bf [Jan 21 17:17:14] DEBUG[31319] chan_sip.c: Header 2 [ 41]: Call-ID: yjo08nkBGlkgv1gORGcfATVEbAUmGzZb [Jan 21 17:17:14] DEBUG[31319] chan_sip.c: Header 3 [ 43]: From: ;tag=as698e1ba6 [Jan 21 17:17:14] DEBUG[31319] chan_sip.c: Header 4 [ 69]: To: "333" ;tag=Q9smPlsbBZ-xV3mr6py9Q.7x8CQidAcU [Jan 21 17:17:14] DEBUG[31319] chan_sip.c: Header 5 [ 16]: CSeq: 102 INVITE [Jan 21 17:17:14] DEBUG[31319] chan_sip.c: Header 6 [ 44]: Contact: "333" [Jan 21 17:17:14] DEBUG[31319] chan_sip.c: Header 7 [ 90]: Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, SUBSCRIBE, NOTIFY, REFER, MESSAGE, OPTIONS [Jan 21 17:17:14] DEBUG[31319] chan_sip.c: Header 8 [ 46]: Supported: replaces, 100rel, timer, norefersub [Jan 21 17:17:14] DEBUG[31319] chan_sip.c: Header 9 [ 29]: Content-Type: application/sdp [Jan 21 17:17:14] DEBUG[31319] chan_sip.c: Header 10 [ 19]: Content-Length: 218 [Jan 21 17:17:14] DEBUG[31319] chan_sip.c: Header 11 [ 0]: [Jan 21 17:17:14] DEBUG[31319] chan_sip.c: Body 0 [ 3]: v=0 [Jan 21 17:17:14] DEBUG[31319] chan_sip.c: Body 1 [ 42]: o=- 148789504 148789505 IN IP4 10.24.18.16 [Jan 21 17:17:14] DEBUG[31319] chan_sip.c: Body 2 [ 8]: s=digphn [Jan 21 17:17:14] DEBUG[31319] chan_sip.c: Body 3 [ 20]: c=IN IP4 10.24.18.16 [Jan 21 17:17:14] DEBUG[31319] chan_sip.c: Body 4 [ 5]: t=0 0 [Jan 21 17:17:14] DEBUG[31319] chan_sip.c: Body 5 [ 9]: a=X-nat:0 [Jan 21 17:17:14] DEBUG[31319] chan_sip.c: Body 6 [ 23]: m=audio 4036 RTP/AVP 96 [Jan 21 17:17:14] DEBUG[31319] chan_sip.c: Body 7 [ 30]: a=rtcp:4037 IN IP4 10.24.18.16 [Jan 21 17:17:14] DEBUG[31319] chan_sip.c: Body 8 [ 10]: a=sendrecv [Jan 21 17:17:14] DEBUG[31319] chan_sip.c: Body 9 [ 32]: a=rtpmap:96 telephone-event/8000 [Jan 21 17:17:14] DEBUG[31319] chan_sip.c: Body 10 [ 14]: a=fmtp:96 0-15 [Jan 21 17:17:14] DEBUG[31319] chan_sip.c: = Looking for Call ID: yjo08nkBGlkgv1gORGcfATVEbAUmGzZb (Checking To) --From tag as698e1ba6 --To-tag Q9smPlsbBZ-xV3mr6py9Q.7x8CQidAcU [Jan 21 17:17:14] DEBUG[31319][C-00000000] chan_sip.c: Stopping retransmission on 'yjo08nkBGlkgv1gORGcfATVEbAUmGzZb' of Request 102: Match Not Found [Jan 21 17:17:14] DEBUG[31319][C-00000000] chan_sip.c: Strict routing enforced for session yjo08nkBGlkgv1gORGcfATVEbAUmGzZb [Jan 21 17:17:14] DEBUG[31319][C-00000000] netsock2.c: Splitting '10.24.18.16:5060' into... [Jan 21 17:17:14] DEBUG[31319][C-00000000] netsock2.c: ...host '10.24.18.16' and port '5060'. [Jan 21 17:17:14] DEBUG[31319][C-00000000] chan_sip.c: Trying to put 'ACK sip:333' onto UDP socket destined for 10.24.18.16:5060 [Jan 21 17:17:14] DEBUG[31339] threadpool.c: Worker thread idle timeout reached. Dying. [Jan 21 17:17:14] DEBUG[31275] threadpool.c: Destroying worker thread 5 [Jan 21 17:17:14] DEBUG[31340] threadpool.c: Worker thread idle timeout reached. Dying. [Jan 21 17:17:14] DEBUG[31275] threadpool.c: Destroying worker thread 6 [Jan 21 17:17:14] DEBUG[31281] threadpool.c: Worker thread idle timeout reached. Dying. [Jan 21 17:17:14] DEBUG[31275] threadpool.c: Destroying worker thread 0 [Jan 21 17:17:18] DEBUG[31319] chan_sip.c: Header 0 [ 14]: SIP/2.0 200 OK [Jan 21 17:17:18] DEBUG[31319] chan_sip.c: Header 1 [ 90]: Via: SIP/2.0/UDP 10.24.17.195:5060;rport=5060;received=10.24.17.195;branch=z9hG4bK646a96bf [Jan 21 17:17:18] DEBUG[31319] chan_sip.c: Header 2 [ 41]: Call-ID: yjo08nkBGlkgv1gORGcfATVEbAUmGzZb [Jan 21 17:17:18] DEBUG[31319] chan_sip.c: Header 3 [ 43]: From: ;tag=as698e1ba6 [Jan 21 17:17:18] DEBUG[31319] chan_sip.c: Header 4 [ 69]: To: "333" ;tag=Q9smPlsbBZ-xV3mr6py9Q.7x8CQidAcU [Jan 21 17:17:18] DEBUG[31319] chan_sip.c: Header 5 [ 16]: CSeq: 102 INVITE [Jan 21 17:17:18] DEBUG[31319] chan_sip.c: Header 6 [ 44]: Contact: "333" [Jan 21 17:17:18] DEBUG[31319] chan_sip.c: Header 7 [ 90]: Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, SUBSCRIBE, NOTIFY, REFER, MESSAGE, OPTIONS [Jan 21 17:17:18] DEBUG[31319] chan_sip.c: Header 8 [ 46]: Supported: replaces, 100rel, timer, norefersub [Jan 21 17:17:18] DEBUG[31319] chan_sip.c: Header 9 [ 29]: Content-Type: application/sdp [Jan 21 17:17:18] DEBUG[31319] chan_sip.c: Header 10 [ 19]: Content-Length: 218 [Jan 21 17:17:18] DEBUG[31319] chan_sip.c: Header 11 [ 0]: [Jan 21 17:17:18] DEBUG[31319] chan_sip.c: Body 0 [ 3]: v=0 [Jan 21 17:17:18] DEBUG[31319] chan_sip.c: Body 1 [ 42]: o=- 148789504 148789505 IN IP4 10.24.18.16 [Jan 21 17:17:18] DEBUG[31319] chan_sip.c: Body 2 [ 8]: s=digphn [Jan 21 17:17:18] DEBUG[31319] chan_sip.c: Body 3 [ 20]: c=IN IP4 10.24.18.16 [Jan 21 17:17:18] DEBUG[31319] chan_sip.c: Body 4 [ 5]: t=0 0 [Jan 21 17:17:18] DEBUG[31319] chan_sip.c: Body 5 [ 9]: a=X-nat:0 [Jan 21 17:17:18] DEBUG[31319] chan_sip.c: Body 6 [ 23]: m=audio 4036 RTP/AVP 96 [Jan 21 17:17:18] DEBUG[31319] chan_sip.c: Body 7 [ 30]: a=rtcp:4037 IN IP4 10.24.18.16 [Jan 21 17:17:18] DEBUG[31319] chan_sip.c: Body 8 [ 10]: a=sendrecv [Jan 21 17:17:18] DEBUG[31319] chan_sip.c: Body 9 [ 32]: a=rtpmap:96 telephone-event/8000 [Jan 21 17:17:18] DEBUG[31319] chan_sip.c: Body 10 [ 14]: a=fmtp:96 0-15 [Jan 21 17:17:18] DEBUG[31319] chan_sip.c: = Looking for Call ID: yjo08nkBGlkgv1gORGcfATVEbAUmGzZb (Checking To) --From tag as698e1ba6 --To-tag Q9smPlsbBZ-xV3mr6py9Q.7x8CQidAcU [Jan 21 17:17:18] DEBUG[31319][C-00000000] chan_sip.c: Stopping retransmission on 'yjo08nkBGlkgv1gORGcfATVEbAUmGzZb' of Request 102: Match Not Found [Jan 21 17:17:18] DEBUG[31319][C-00000000] chan_sip.c: Strict routing enforced for session yjo08nkBGlkgv1gORGcfATVEbAUmGzZb [Jan 21 17:17:18] DEBUG[31319][C-00000000] netsock2.c: Splitting '10.24.18.16:5060' into... [Jan 21 17:17:18] DEBUG[31319][C-00000000] netsock2.c: ...host '10.24.18.16' and port '5060'. [Jan 21 17:17:18] DEBUG[31319][C-00000000] chan_sip.c: Trying to put 'ACK sip:333' onto UDP socket destined for 10.24.18.16:5060 [Jan 21 17:17:22] DEBUG[31319] chan_sip.c: Header 0 [ 14]: SIP/2.0 200 OK [Jan 21 17:17:22] DEBUG[31319] chan_sip.c: Header 1 [ 90]: Via: SIP/2.0/UDP 10.24.17.195:5060;rport=5060;received=10.24.17.195;branch=z9hG4bK646a96bf [Jan 21 17:17:22] DEBUG[31319] chan_sip.c: Header 2 [ 41]: Call-ID: yjo08nkBGlkgv1gORGcfATVEbAUmGzZb [Jan 21 17:17:22] DEBUG[31319] chan_sip.c: Header 3 [ 43]: From: ;tag=as698e1ba6 [Jan 21 17:17:22] DEBUG[31319] chan_sip.c: Header 4 [ 69]: To: "333" ;tag=Q9smPlsbBZ-xV3mr6py9Q.7x8CQidAcU [Jan 21 17:17:22] DEBUG[31319] chan_sip.c: Header 5 [ 16]: CSeq: 102 INVITE [Jan 21 17:17:22] DEBUG[31319] chan_sip.c: Header 6 [ 44]: Contact: "333" [Jan 21 17:17:22] DEBUG[31319] chan_sip.c: Header 7 [ 90]: Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, SUBSCRIBE, NOTIFY, REFER, MESSAGE, OPTIONS [Jan 21 17:17:22] DEBUG[31319] chan_sip.c: Header 8 [ 46]: Supported: replaces, 100rel, timer, norefersub [Jan 21 17:17:22] DEBUG[31319] chan_sip.c: Header 9 [ 29]: Content-Type: application/sdp [Jan 21 17:17:22] DEBUG[31319] chan_sip.c: Header 10 [ 19]: Content-Length: 218 [Jan 21 17:17:22] DEBUG[31319] chan_sip.c: Header 11 [ 0]: [Jan 21 17:17:22] DEBUG[31319] chan_sip.c: Body 0 [ 3]: v=0 [Jan 21 17:17:22] DEBUG[31319] chan_sip.c: Body 1 [ 42]: o=- 148789504 148789505 IN IP4 10.24.18.16 [Jan 21 17:17:22] DEBUG[31319] chan_sip.c: Body 2 [ 8]: s=digphn [Jan 21 17:17:22] DEBUG[31319] chan_sip.c: Body 3 [ 20]: c=IN IP4 10.24.18.16 [Jan 21 17:17:22] DEBUG[31319] chan_sip.c: Body 4 [ 5]: t=0 0 [Jan 21 17:17:22] DEBUG[31319] chan_sip.c: Body 5 [ 9]: a=X-nat:0 [Jan 21 17:17:22] DEBUG[31319] chan_sip.c: Body 6 [ 23]: m=audio 4036 RTP/AVP 96 [Jan 21 17:17:22] DEBUG[31319] chan_sip.c: Body 7 [ 30]: a=rtcp:4037 IN IP4 10.24.18.16 [Jan 21 17:17:22] DEBUG[31319] chan_sip.c: Body 8 [ 10]: a=sendrecv [Jan 21 17:17:22] DEBUG[31319] chan_sip.c: Body 9 [ 32]: a=rtpmap:96 telephone-event/8000 [Jan 21 17:17:22] DEBUG[31319] chan_sip.c: Body 10 [ 14]: a=fmtp:96 0-15 [Jan 21 17:17:22] DEBUG[31319] chan_sip.c: = Looking for Call ID: yjo08nkBGlkgv1gORGcfATVEbAUmGzZb (Checking To) --From tag as698e1ba6 --To-tag Q9smPlsbBZ-xV3mr6py9Q.7x8CQidAcU [Jan 21 17:17:22] DEBUG[31319][C-00000000] chan_sip.c: Stopping retransmission on 'yjo08nkBGlkgv1gORGcfATVEbAUmGzZb' of Request 102: Match Not Found [Jan 21 17:17:22] DEBUG[31319][C-00000000] chan_sip.c: Strict routing enforced for session yjo08nkBGlkgv1gORGcfATVEbAUmGzZb [Jan 21 17:17:22] DEBUG[31319][C-00000000] netsock2.c: Splitting '10.24.18.16:5060' into... [Jan 21 17:17:22] DEBUG[31319][C-00000000] netsock2.c: ...host '10.24.18.16' and port '5060'. [Jan 21 17:17:22] DEBUG[31319][C-00000000] chan_sip.c: Trying to put 'ACK sip:333' onto UDP socket destined for 10.24.18.16:5060 [Jan 21 17:17:26] DEBUG[31319] chan_sip.c: Header 0 [ 14]: SIP/2.0 200 OK [Jan 21 17:17:26] DEBUG[31319] chan_sip.c: Header 1 [ 90]: Via: SIP/2.0/UDP 10.24.17.195:5060;rport=5060;received=10.24.17.195;branch=z9hG4bK646a96bf [Jan 21 17:17:26] DEBUG[31319] chan_sip.c: Header 2 [ 41]: Call-ID: yjo08nkBGlkgv1gORGcfATVEbAUmGzZb [Jan 21 17:17:26] DEBUG[31319] chan_sip.c: Header 3 [ 43]: From: ;tag=as698e1ba6 [Jan 21 17:17:26] DEBUG[31319] chan_sip.c: Header 4 [ 69]: To: "333" ;tag=Q9smPlsbBZ-xV3mr6py9Q.7x8CQidAcU [Jan 21 17:17:26] DEBUG[31319] chan_sip.c: Header 5 [ 16]: CSeq: 102 INVITE [Jan 21 17:17:26] DEBUG[31319] chan_sip.c: Header 6 [ 44]: Contact: "333" [Jan 21 17:17:26] DEBUG[31319] chan_sip.c: Header 7 [ 90]: Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, SUBSCRIBE, NOTIFY, REFER, MESSAGE, OPTIONS [Jan 21 17:17:26] DEBUG[31319] chan_sip.c: Header 8 [ 46]: Supported: replaces, 100rel, timer, norefersub [Jan 21 17:17:26] DEBUG[31319] chan_sip.c: Header 9 [ 29]: Content-Type: application/sdp [Jan 21 17:17:26] DEBUG[31319] chan_sip.c: Header 10 [ 19]: Content-Length: 218 [Jan 21 17:17:26] DEBUG[31319] chan_sip.c: Header 11 [ 0]: [Jan 21 17:17:26] DEBUG[31319] chan_sip.c: Body 0 [ 3]: v=0 [Jan 21 17:17:26] DEBUG[31319] chan_sip.c: Body 1 [ 42]: o=- 148789504 148789505 IN IP4 10.24.18.16 [Jan 21 17:17:26] DEBUG[31319] chan_sip.c: Body 2 [ 8]: s=digphn [Jan 21 17:17:26] DEBUG[31319] chan_sip.c: Body 3 [ 20]: c=IN IP4 10.24.18.16 [Jan 21 17:17:26] DEBUG[31319] chan_sip.c: Body 4 [ 5]: t=0 0 [Jan 21 17:17:26] DEBUG[31319] chan_sip.c: Body 5 [ 9]: a=X-nat:0 [Jan 21 17:17:26] DEBUG[31319] chan_sip.c: Body 6 [ 23]: m=audio 4036 RTP/AVP 96 [Jan 21 17:17:26] DEBUG[31319] chan_sip.c: Body 7 [ 30]: a=rtcp:4037 IN IP4 10.24.18.16 [Jan 21 17:17:26] DEBUG[31319] chan_sip.c: Body 8 [ 10]: a=sendrecv [Jan 21 17:17:26] DEBUG[31319] chan_sip.c: Body 9 [ 32]: a=rtpmap:96 telephone-event/8000 [Jan 21 17:17:26] DEBUG[31319] chan_sip.c: Body 10 [ 14]: a=fmtp:96 0-15 [Jan 21 17:17:26] DEBUG[31319] chan_sip.c: = Looking for Call ID: yjo08nkBGlkgv1gORGcfATVEbAUmGzZb (Checking To) --From tag as698e1ba6 --To-tag Q9smPlsbBZ-xV3mr6py9Q.7x8CQidAcU [Jan 21 17:17:26] DEBUG[31319][C-00000000] chan_sip.c: Stopping retransmission on 'yjo08nkBGlkgv1gORGcfATVEbAUmGzZb' of Request 102: Match Not Found [Jan 21 17:17:26] DEBUG[31319][C-00000000] chan_sip.c: Strict routing enforced for session yjo08nkBGlkgv1gORGcfATVEbAUmGzZb [Jan 21 17:17:26] DEBUG[31319][C-00000000] netsock2.c: Splitting '10.24.18.16:5060' into... [Jan 21 17:17:26] DEBUG[31319][C-00000000] netsock2.c: ...host '10.24.18.16' and port '5060'. [Jan 21 17:17:26] DEBUG[31319][C-00000000] chan_sip.c: Trying to put 'ACK sip:333' onto UDP socket destined for 10.24.18.16:5060 [Jan 21 17:17:26] DEBUG[31319] chan_sip.c: Auto destroying SIP dialog 'yjo08nkBGlkgv1gORGcfATVEbAUmGzZb' [Jan 21 17:17:26] DEBUG[31319] chan_sip.c: Destroying SIP dialog yjo08nkBGlkgv1gORGcfATVEbAUmGzZb [Jan 21 17:17:26] DEBUG[31319] rtp_engine.c: Destroyed RTP instance '0x8d2e55c' [Jan 21 17:17:27] DEBUG[31336] cdr.c: CDR Engine termination request received; waiting on messages... [Jan 21 17:17:27] DEBUG[31336] taskprocessor.c: destroying taskprocessor 'ast_msg_queue' [Jan 21 17:17:27] DEBUG[31336] res_musiconhold.c: Destroying MOH class 'default' [Jan 21 17:17:27] DEBUG[31336] taskprocessor.c: destroying taskprocessor '26e661a1-290e-4690-b54e-efe3262f2ceb' [Jan 21 17:17:27] DEBUG[31336] asterisk.c: Asterisk ending (0).