[Dec 31 10:13:26] DEBUG[18913] chan_sip.c: Header 0 [ 56]: INVITE sip:echo@212.230.19.26:6060;transport=tcp SIP/2.0 [Dec 31 10:13:26] DEBUG[18913] chan_sip.c: Header 1 [ 69]: Record-Route: [Dec 31 10:13:26] DEBUG[18913] chan_sip.c: Header 2 [ 80]: Record-Route: [Dec 31 10:13:26] DEBUG[18913] chan_sip.c: Header 3 [ 96]: Via: SIP/2.0/TCP 91.121.79.216:9090;branch=z9hG4bKd0b6be9e8c2be3d60d9ccabaaf18e9180bf6dd96;rport [Dec 31 10:13:26] DEBUG[18913] chan_sip.c: Header 4 [ 52]: Via: SIP/2.0/TCP 185.83.253.25;branch=z9hG4bK7761734 [Dec 31 10:13:26] DEBUG[18913] chan_sip.c: Header 5 [ 16]: Max-Forwards: 10 [Dec 31 10:13:26] DEBUG[18913] chan_sip.c: Header 6 [ 22]: To: sip:echo@jssip.net [Dec 31 10:13:26] DEBUG[18913] chan_sip.c: Header 7 [ 42]: From: sip:jmillan@jssip.net;tag=0cgyhltps3 [Dec 31 10:13:26] DEBUG[18913] chan_sip.c: Header 8 [ 29]: Call-ID: cxfj1uoj8f3z9jtc5wmi [Dec 31 10:13:26] DEBUG[18913] chan_sip.c: Header 9 [ 17]: CSeq: 4013 INVITE [Dec 31 10:13:26] DEBUG[18913] chan_sip.c: Header 10 [ 84]: Contact: [Dec 31 10:13:26] DEBUG[18913] chan_sip.c: Header 11 [ 60]: Allow: INVITE, ACK, CANCEL, BYE, OPTIONS, MESSAGE, SUBSCRIBE [Dec 31 10:13:26] DEBUG[18913] chan_sip.c: Header 12 [ 29]: Content-Type: application/sdp [Dec 31 10:13:26] DEBUG[18913] chan_sip.c: Header 13 [ 31]: Supported: path, outbound, gruu [Dec 31 10:13:26] DEBUG[18913] chan_sip.c: Header 14 [ 23]: User-Agent: JsSIP 0.2.1 [Dec 31 10:13:26] DEBUG[18913] chan_sip.c: Header 15 [ 20]: Content-Length: 2167 [Dec 31 10:13:26] DEBUG[18913] chan_sip.c: Header 16 [ 44]: P-Asserted-Identity: [Dec 31 10:13:26] DEBUG[18913] chan_sip.c: Header 17 [ 0]: [Dec 31 10:13:26] VERBOSE[18913] chan_sip.c: <--- SIP read from TCP:91.121.79.216:44240 ---> INVITE sip:echo@212.230.19.26:6060;transport=tcp SIP/2.0 Record-Route: Record-Route: Via: SIP/2.0/TCP 91.121.79.216:9090;branch=z9hG4bKd0b6be9e8c2be3d60d9ccabaaf18e9180bf6dd96;rport Via: SIP/2.0/TCP 185.83.253.25;branch=z9hG4bK7761734 Max-Forwards: 10 To: sip:echo@jssip.net From: sip:jmillan@jssip.net;tag=0cgyhltps3 Call-ID: cxfj1uoj8f3z9jtc5wmi CSeq: 4013 INVITE Contact: Allow: INVITE, ACK, CANCEL, BYE, OPTIONS, MESSAGE, SUBSCRIBE Content-Type: application/sdp Supported: path, outbound, gruu User-Agent: JsSIP 0.2.1 Content-Length: 2167 P-Asserted-Identity: v=0 o=- 3521865287 2 IN IP4 127.0.0.1 s=- t=0 0 a=group:BUNDLE audio a=msid-semantic: WMS uIIYsO2dH7OKhrMVm2Djt4lWB8mLjcYLxoqq m=audio 59663 RTP/SAVPF 103 104 111 0 8 107 106 105 13 126 c=IN IP4 77.229.25.234 a=rtcp:59663 IN IP4 77.229.25.234 a=candidate:4093981320 1 udp 2113937151 192.168.1.130 46656 typ host generation 0 a=candidate:4093981320 2 udp 2113937151 192.168.1.130 46656 typ host generation 0 a=candidate:3728587736 1 udp 2113937151 192.168.90.8 47146 typ host generation 0 a=candidate:3728587736 2 udp 2113937151 192.168.90.8 47146 typ host generation 0 a=candidate:3081437784 1 udp 2113937151 192.168.0.198 59663 typ host generation 0 a=candidate:3081437784 2 udp 2113937151 192.168.0.198 59663 typ host generation 0 a=candidate:920847596 1 udp 1845501695 77.229.25.234 59663 typ srflx raddr 192.168.0.198 rport 59663 generation 0 a=candidate:920847596 2 udp 1845501695 77.229.25.234 59663 typ srflx raddr 192.168.0.198 rport 59663 generation 0 a=candidate:3129396856 1 tcp 1509957375 192.168.1.130 46112 typ host generation 0 a=candidate:3129396856 2 tcp 1509957375 192.168.1.130 46112 typ host generation 0 a=candidate:2428389160 1 tcp 1509957375 192.168.90.8 45034 typ host generation 0 a=candidate:2428389160 2 tcp 1509957375 192.168.90.8 45034 typ host generation 0 a=candidate:4180213416 1 tcp 1509957375 192.168.0.198 50299 typ host generation 0 a=candidate:4180213416 2 tcp 1509957375 192.168.0.198 50299 typ host generation 0 a=ice-ufrag:ObKDzUwWSkvekbZN a=ice-pwd:5n7nLQW7XLGrvIzjrA9vv28W a=ice-options:google-ice a=sendrecv a=mid:audio a=rtcp-mux a=crypto:0 AES_CM_128_HMAC_SHA1_32 inline:HqxgWh4M0amLPmSIuqwWeDmL1K0Ad93YBszrGv8b a=rtpmap:103 ISAC/16000 a=rtpmap:104 ISAC/32000 a=rtpmap:111 opus/48000/2 a=rtpmap:0 PCMU/8000 a=rtpmap:8 PCMA/8000 a=rtpmap:107 CN/48000 a=rtpmap:106 CN/32000 a=rtpmap:105 CN/16000 a=rtpmap:13 CN/8000 a=rtpmap:126 telephone-event/8000 a=ssrc:3829437514 cname:tQ/kidikog8Z3lzz a=ssrc:3829437514 msid:uIIYsO2dH7OKhrMVm2Djt4lWB8mLjcYLxoqq a0 a=ssrc:3829437514 mslabel:uIIYsO2dH7OKhrMVm2Djt4lWB8mLjcYLxoqq a=ssrc:3829437514 label:uIIYsO2dH7OKhrMVm2Djt4lWB8mLjcYLxoqqa0 <-------------> [Dec 31 10:13:26] DEBUG[18913] chan_sip.c: Header 0 [ 56]: INVITE sip:echo@212.230.19.26:6060;transport=tcp SIP/2.0 [Dec 31 10:13:26] DEBUG[18913] chan_sip.c: Header 1 [ 69]: Record-Route: [Dec 31 10:13:26] DEBUG[18913] chan_sip.c: Header 2 [ 80]: Record-Route: [Dec 31 10:13:26] DEBUG[18913] chan_sip.c: Header 3 [ 96]: Via: SIP/2.0/TCP 91.121.79.216:9090;branch=z9hG4bKd0b6be9e8c2be3d60d9ccabaaf18e9180bf6dd96;rport [Dec 31 10:13:26] DEBUG[18913] chan_sip.c: Header 4 [ 52]: Via: SIP/2.0/TCP 185.83.253.25;branch=z9hG4bK7761734 [Dec 31 10:13:26] DEBUG[18913] chan_sip.c: Header 5 [ 16]: Max-Forwards: 10 [Dec 31 10:13:26] DEBUG[18913] chan_sip.c: Header 6 [ 22]: To: sip:echo@jssip.net [Dec 31 10:13:26] DEBUG[18913] chan_sip.c: Header 7 [ 42]: From: sip:jmillan@jssip.net;tag=0cgyhltps3 [Dec 31 10:13:26] DEBUG[18913] chan_sip.c: Header 8 [ 29]: Call-ID: cxfj1uoj8f3z9jtc5wmi [Dec 31 10:13:26] DEBUG[18913] chan_sip.c: Header 9 [ 17]: CSeq: 4013 INVITE [Dec 31 10:13:26] DEBUG[18913] chan_sip.c: Header 10 [ 84]: Contact: [Dec 31 10:13:26] DEBUG[18913] chan_sip.c: Header 11 [ 60]: Allow: INVITE, ACK, CANCEL, BYE, OPTIONS, MESSAGE, SUBSCRIBE [Dec 31 10:13:26] DEBUG[18913] chan_sip.c: Header 12 [ 29]: Content-Type: application/sdp [Dec 31 10:13:26] DEBUG[18913] chan_sip.c: Header 13 [ 31]: Supported: path, outbound, gruu [Dec 31 10:13:26] DEBUG[18913] chan_sip.c: Header 14 [ 23]: User-Agent: JsSIP 0.2.1 [Dec 31 10:13:26] DEBUG[18913] chan_sip.c: Header 15 [ 20]: Content-Length: 2167 [Dec 31 10:13:26] DEBUG[18913] chan_sip.c: Header 16 [ 44]: P-Asserted-Identity: [Dec 31 10:13:26] DEBUG[18913] chan_sip.c: Header 17 [ 0]: [Dec 31 10:13:26] DEBUG[18913] chan_sip.c: Body 0 [ 3]: v=0 [Dec 31 10:13:26] DEBUG[18913] chan_sip.c: Body 1 [ 33]: o=- 3521865287 2 IN IP4 127.0.0.1 [Dec 31 10:13:26] DEBUG[18913] chan_sip.c: Body 2 [ 3]: s=- [Dec 31 10:13:26] DEBUG[18913] chan_sip.c: Body 3 [ 5]: t=0 0 [Dec 31 10:13:26] DEBUG[18913] chan_sip.c: Body 4 [ 20]: a=group:BUNDLE audio [Dec 31 10:13:26] DEBUG[18913] chan_sip.c: Body 5 [ 57]: a=msid-semantic: WMS uIIYsO2dH7OKhrMVm2Djt4lWB8mLjcYLxoqq [Dec 31 10:13:26] DEBUG[18913] chan_sip.c: Body 6 [ 58]: m=audio 59663 RTP/SAVPF 103 104 111 0 8 107 106 105 13 126 [Dec 31 10:13:26] DEBUG[18913] chan_sip.c: Body 7 [ 22]: c=IN IP4 77.229.25.234 [Dec 31 10:13:26] DEBUG[18913] chan_sip.c: Body 8 [ 33]: a=rtcp:59663 IN IP4 77.229.25.234 [Dec 31 10:13:26] DEBUG[18913] chan_sip.c: Body 9 [ 81]: a=candidate:4093981320 1 udp 2113937151 192.168.1.130 46656 typ host generation 0 [Dec 31 10:13:26] DEBUG[18913] chan_sip.c: Body 10 [ 81]: a=candidate:4093981320 2 udp 2113937151 192.168.1.130 46656 typ host generation 0 [Dec 31 10:13:26] DEBUG[18913] chan_sip.c: Body 11 [ 80]: a=candidate:3728587736 1 udp 2113937151 192.168.90.8 47146 typ host generation 0 [Dec 31 10:13:26] DEBUG[18913] chan_sip.c: Body 12 [ 80]: a=candidate:3728587736 2 udp 2113937151 192.168.90.8 47146 typ host generation 0 [Dec 31 10:13:26] DEBUG[18913] chan_sip.c: Body 13 [ 81]: a=candidate:3081437784 1 udp 2113937151 192.168.0.198 59663 typ host generation 0 [Dec 31 10:13:26] DEBUG[18913] chan_sip.c: Body 14 [ 81]: a=candidate:3081437784 2 udp 2113937151 192.168.0.198 59663 typ host generation 0 [Dec 31 10:13:26] DEBUG[18913] chan_sip.c: Body 15 [113]: a=candidate:920847596 1 udp 1845501695 77.229.25.234 59663 typ srflx raddr 192.168.0.198 rport 59663 generation 0 [Dec 31 10:13:26] DEBUG[18913] chan_sip.c: Body 16 [113]: a=candidate:920847596 2 udp 1845501695 77.229.25.234 59663 typ srflx raddr 192.168.0.198 rport 59663 generation 0 [Dec 31 10:13:26] DEBUG[18913] chan_sip.c: Body 17 [ 81]: a=candidate:3129396856 1 tcp 1509957375 192.168.1.130 46112 typ host generation 0 [Dec 31 10:13:26] DEBUG[18913] chan_sip.c: Body 18 [ 81]: a=candidate:3129396856 2 tcp 1509957375 192.168.1.130 46112 typ host generation 0 [Dec 31 10:13:26] DEBUG[18913] chan_sip.c: Body 19 [ 80]: a=candidate:2428389160 1 tcp 1509957375 192.168.90.8 45034 typ host generation 0 [Dec 31 10:13:26] DEBUG[18913] chan_sip.c: Body 20 [ 80]: a=candidate:2428389160 2 tcp 1509957375 192.168.90.8 45034 typ host generation 0 [Dec 31 10:13:26] DEBUG[18913] chan_sip.c: Body 21 [ 81]: a=candidate:4180213416 1 tcp 1509957375 192.168.0.198 50299 typ host generation 0 [Dec 31 10:13:26] DEBUG[18913] chan_sip.c: Body 22 [ 81]: a=candidate:4180213416 2 tcp 1509957375 192.168.0.198 50299 typ host generation 0 [Dec 31 10:13:26] DEBUG[18913] chan_sip.c: Body 23 [ 28]: a=ice-ufrag:ObKDzUwWSkvekbZN [Dec 31 10:13:26] DEBUG[18913] chan_sip.c: Body 24 [ 34]: a=ice-pwd:5n7nLQW7XLGrvIzjrA9vv28W [Dec 31 10:13:26] DEBUG[18913] chan_sip.c: Body 25 [ 24]: a=ice-options:google-ice [Dec 31 10:13:26] DEBUG[18913] chan_sip.c: Body 26 [ 10]: a=sendrecv [Dec 31 10:13:26] DEBUG[18913] chan_sip.c: Body 27 [ 11]: a=mid:audio [Dec 31 10:13:26] DEBUG[18913] chan_sip.c: Body 28 [ 10]: a=rtcp-mux [Dec 31 10:13:26] DEBUG[18913] chan_sip.c: Body 29 [ 82]: a=crypto:0 AES_CM_128_HMAC_SHA1_32 inline:HqxgWh4M0amLPmSIuqwWeDmL1K0Ad93YBszrGv8b [Dec 31 10:13:26] DEBUG[18913] chan_sip.c: Body 30 [ 23]: a=rtpmap:103 ISAC/16000 [Dec 31 10:13:26] DEBUG[18913] chan_sip.c: Body 31 [ 23]: a=rtpmap:104 ISAC/32000 [Dec 31 10:13:26] DEBUG[18913] chan_sip.c: Body 32 [ 25]: a=rtpmap:111 opus/48000/2 [Dec 31 10:13:26] DEBUG[18913] chan_sip.c: Body 33 [ 20]: a=rtpmap:0 PCMU/8000 [Dec 31 10:13:26] DEBUG[18913] chan_sip.c: Body 34 [ 20]: a=rtpmap:8 PCMA/8000 [Dec 31 10:13:26] DEBUG[18913] chan_sip.c: Body 35 [ 21]: a=rtpmap:107 CN/48000 [Dec 31 10:13:26] DEBUG[18913] chan_sip.c: Body 36 [ 21]: a=rtpmap:106 CN/32000 [Dec 31 10:13:26] DEBUG[18913] chan_sip.c: Body 37 [ 21]: a=rtpmap:105 CN/16000 [Dec 31 10:13:26] DEBUG[18913] chan_sip.c: Body 38 [ 19]: a=rtpmap:13 CN/8000 [Dec 31 10:13:26] DEBUG[18913] chan_sip.c: Body 39 [ 33]: a=rtpmap:126 telephone-event/8000 [Dec 31 10:13:26] DEBUG[18913] chan_sip.c: Body 40 [ 40]: a=ssrc:3829437514 cname:tQ/kidikog8Z3lzz [Dec 31 10:13:26] DEBUG[18913] chan_sip.c: Body 41 [ 62]: a=ssrc:3829437514 msid:uIIYsO2dH7OKhrMVm2Djt4lWB8mLjcYLxoqq a0 [Dec 31 10:13:26] DEBUG[18913] chan_sip.c: Body 42 [ 62]: a=ssrc:3829437514 mslabel:uIIYsO2dH7OKhrMVm2Djt4lWB8mLjcYLxoqq [Dec 31 10:13:26] DEBUG[18913] chan_sip.c: Body 43 [ 62]: a=ssrc:3829437514 label:uIIYsO2dH7OKhrMVm2Djt4lWB8mLjcYLxoqqa0 [Dec 31 10:13:26] VERBOSE[18913] chan_sip.c: --- (17 headers 44 lines) --- [Dec 31 10:13:26] DEBUG[18913] chan_sip.c: = Looking for Call ID: cxfj1uoj8f3z9jtc5wmi (Checking From) --From tag 0cgyhltps3 --To-tag [Dec 31 10:13:26] DEBUG[18913] logger.c: CALL_ID [C-00000011] created by thread. [Dec 31 10:13:26] DEBUG[18913] acl.c: For destination '91.121.79.216', our source address is '212.230.19.26'. [Dec 31 10:13:26] DEBUG[18913] chan_sip.c: Setting SIP_TRANSPORT_TCP with address 212.230.19.26:6060 [Dec 31 10:13:26] DEBUG[18913] chan_sip.c: Allocating new SIP dialog for cxfj1uoj8f3z9jtc5wmi - INVITE (No RTP) [Dec 31 10:13:26] DEBUG[18913][C-00000011] logger.c: CALL_ID [C-00000011] bound to thread. [Dec 31 10:13:26] DEBUG[18913][C-00000011] chan_sip.c: **** Received INVITE (5) - Command in SIP INVITE [Dec 31 10:13:26] DEBUG[18913][C-00000011] sip/reqresp_parser.c: Begin: parsing SIP "Supported: path, outbound, gruu" [Dec 31 10:13:26] DEBUG[18913][C-00000011] sip/reqresp_parser.c: Found SIP option: -path- [Dec 31 10:13:26] DEBUG[18913][C-00000011] sip/reqresp_parser.c: Matched SIP option: path [Dec 31 10:13:26] DEBUG[18913][C-00000011] sip/reqresp_parser.c: Found SIP option: -outbound- [Dec 31 10:13:26] DEBUG[18913][C-00000011] sip/reqresp_parser.c: Matched SIP option: outbound [Dec 31 10:13:26] DEBUG[18913][C-00000011] sip/reqresp_parser.c: Found SIP option: -gruu- [Dec 31 10:13:26] DEBUG[18913][C-00000011] sip/reqresp_parser.c: Matched SIP option: gruu [Dec 31 10:13:26] DEBUG[18913][C-00000011] netsock2.c: Splitting '91.121.79.216:9090' into... [Dec 31 10:13:26] DEBUG[18913][C-00000011] netsock2.c: ...host '91.121.79.216' and port '9090'. [Dec 31 10:13:26] DEBUG[18913][C-00000011] chan_sip.c: NAT detected for 91.121.79.216:9090 / 91.121.79.216:44240 [Dec 31 10:13:26] VERBOSE[18913][C-00000011] chan_sip.c: Sending to 91.121.79.216:44240 (NAT) [Dec 31 10:13:26] DEBUG[18913][C-00000011] chan_sip.c: Initializing initreq for method INVITE - callid cxfj1uoj8f3z9jtc5wmi [Dec 31 10:13:26] VERBOSE[18913][C-00000011] chan_sip.c: Using INVITE request as basis request - cxfj1uoj8f3z9jtc5wmi [Dec 31 10:13:26] DEBUG[18913][C-00000011] netsock2.c: Splitting 'jssip.net' into... [Dec 31 10:13:26] DEBUG[18913][C-00000011] netsock2.c: ...host 'jssip.net' and port ''. [Dec 31 10:13:26] VERBOSE[18913][C-00000011] chan_sip.c: Found peer 'kamailio-aliax' for 'jmillan' from 91.121.79.216:44240 [Dec 31 10:13:26] DEBUG[18913][C-00000011] rtp_engine.c: Using engine 'asterisk' for RTP instance '0x15aadf8' [Dec 31 10:13:26] DEBUG[18913][C-00000011] res_rtp_asterisk.c: Allocated port 15490 for RTP instance '0x15aadf8' [Dec 31 10:13:26] DEBUG[18913][C-00000011] netsock2.c: Splitting '212.230.19.26' into... [Dec 31 10:13:26] DEBUG[18913][C-00000011] netsock2.c: ...host '212.230.19.26' and port ''. [Dec 31 10:13:26] DEBUG[18913][C-00000011] rtp_engine.c: RTP instance '0x15aadf8' is setup and ready to go [Dec 31 10:13:26] DEBUG[18913][C-00000011] res_rtp_asterisk.c: Setup RTCP on RTP instance '0x15aadf8' [Dec 31 10:13:26] DEBUG[18913][C-00000011] netsock2.c: Splitting '212.230.19.26' into... [Dec 31 10:13:26] DEBUG[18913][C-00000011] netsock2.c: ...host '212.230.19.26' and port ''. [Dec 31 10:13:26] VERBOSE[18913][C-00000011] netsock2.c: == Using SIP RTP CoS mark 5 [Dec 31 10:13:26] DEBUG[18913][C-00000011] chan_sip.c: Setting NAT on RTP to On [Dec 31 10:13:26] DEBUG[18913][C-00000011] chan_sip.c: Processing session-level SDP v=0... UNSUPPORTED OR FAILED. [Dec 31 10:13:26] DEBUG[18913][C-00000011] chan_sip.c: Processing session-level SDP o=- 3521865287 2 IN IP4 127.0.0.1... UNSUPPORTED OR FAILED. [Dec 31 10:13:26] DEBUG[18913][C-00000011] chan_sip.c: Processing session-level SDP s=-... UNSUPPORTED OR FAILED. [Dec 31 10:13:26] DEBUG[18913][C-00000011] chan_sip.c: Processing session-level SDP t=0 0... UNSUPPORTED OR FAILED. [Dec 31 10:13:26] DEBUG[18913][C-00000011] chan_sip.c: Processing session-level SDP a=group:BUNDLE audio... UNSUPPORTED OR FAILED. [Dec 31 10:13:26] DEBUG[18913][C-00000011] chan_sip.c: Processing session-level SDP a=msid-semantic: WMS uIIYsO2dH7OKhrMVm2Djt4lWB8mLjcYLxoqq... UNSUPPORTED OR FAILED. [Dec 31 10:13:26] VERBOSE[18913][C-00000011] chan_sip.c: Found RTP audio format 103 [Dec 31 10:13:26] DEBUG[18913][C-00000011] rtp_engine.c: Setting payload 103 based on m type on 0x7f9204133db0 [Dec 31 10:13:26] VERBOSE[18913][C-00000011] chan_sip.c: Found RTP audio format 104 [Dec 31 10:13:26] DEBUG[18913][C-00000011] rtp_engine.c: Setting payload 104 based on m type on 0x7f9204133db0 [Dec 31 10:13:26] VERBOSE[18913][C-00000011] chan_sip.c: Found RTP audio format 111 [Dec 31 10:13:26] DEBUG[18913][C-00000011] rtp_engine.c: Setting payload 111 based on m type on 0x7f9204133db0 [Dec 31 10:13:26] VERBOSE[18913][C-00000011] chan_sip.c: Found RTP audio format 0 [Dec 31 10:13:26] DEBUG[18913][C-00000011] rtp_engine.c: Setting payload 0 based on m type on 0x7f9204133db0 [Dec 31 10:13:26] VERBOSE[18913][C-00000011] chan_sip.c: Found RTP audio format 8 [Dec 31 10:13:26] DEBUG[18913][C-00000011] rtp_engine.c: Setting payload 8 based on m type on 0x7f9204133db0 [Dec 31 10:13:26] VERBOSE[18913][C-00000011] chan_sip.c: Found RTP audio format 107 [Dec 31 10:13:26] DEBUG[18913][C-00000011] rtp_engine.c: Setting payload 107 based on m type on 0x7f9204133db0 [Dec 31 10:13:26] VERBOSE[18913][C-00000011] chan_sip.c: Found RTP audio format 106 [Dec 31 10:13:26] DEBUG[18913][C-00000011] rtp_engine.c: Setting payload 106 based on m type on 0x7f9204133db0 [Dec 31 10:13:26] VERBOSE[18913][C-00000011] chan_sip.c: Found RTP audio format 105 [Dec 31 10:13:26] DEBUG[18913][C-00000011] rtp_engine.c: Setting payload 105 based on m type on 0x7f9204133db0 [Dec 31 10:13:26] VERBOSE[18913][C-00000011] chan_sip.c: Found RTP audio format 13 [Dec 31 10:13:26] DEBUG[18913][C-00000011] rtp_engine.c: Setting payload 13 based on m type on 0x7f9204133db0 [Dec 31 10:13:26] VERBOSE[18913][C-00000011] chan_sip.c: Found RTP audio format 126 [Dec 31 10:13:26] DEBUG[18913][C-00000011] rtp_engine.c: Setting payload 126 based on m type on 0x7f9204133db0 [Dec 31 10:13:26] DEBUG[18913][C-00000011] netsock2.c: Splitting '77.229.25.234' into... [Dec 31 10:13:26] DEBUG[18913][C-00000011] netsock2.c: ...host '77.229.25.234' and port ''. [Dec 31 10:13:26] DEBUG[18913][C-00000011] chan_sip.c: Processing media-level (audio) SDP c=IN IP4 77.229.25.234... OK. [Dec 31 10:13:26] DEBUG[18913][C-00000011] chan_sip.c: Processing media-level (audio) SDP a=rtcp:59663 IN IP4 77.229.25.234... UNSUPPORTED OR FAILED. [Dec 31 10:13:26] DEBUG[18913][C-00000011] netsock2.c: Splitting '192.168.1.130' into... [Dec 31 10:13:26] DEBUG[18913][C-00000011] netsock2.c: ...host '192.168.1.130' and port ''. [Dec 31 10:13:26] DEBUG[18913][C-00000011] netsock2.c: Splitting '0' into... [Dec 31 10:13:26] DEBUG[18913][C-00000011] netsock2.c: ...host '0' and port ''. [Dec 31 10:13:26] DEBUG[18913][C-00000011] chan_sip.c: Processing media-level (audio) SDP a=candidate:4093981320 1 udp 2113937151 192.168.1.130 46656 typ host generation 0... OK. [Dec 31 10:13:26] DEBUG[18913][C-00000011] netsock2.c: Splitting '192.168.1.130' into... [Dec 31 10:13:26] DEBUG[18913][C-00000011] netsock2.c: ...host '192.168.1.130' and port ''. [Dec 31 10:13:26] DEBUG[18913][C-00000011] netsock2.c: Splitting '0' into... [Dec 31 10:13:26] DEBUG[18913][C-00000011] netsock2.c: ...host '0' and port ''. [Dec 31 10:13:26] DEBUG[18913][C-00000011] chan_sip.c: Processing media-level (audio) SDP a=candidate:4093981320 2 udp 2113937151 192.168.1.130 46656 typ host generation 0... OK. [Dec 31 10:13:26] DEBUG[18913][C-00000011] netsock2.c: Splitting '192.168.90.8' into... [Dec 31 10:13:26] DEBUG[18913][C-00000011] netsock2.c: ...host '192.168.90.8' and port ''. [Dec 31 10:13:26] DEBUG[18913][C-00000011] netsock2.c: Splitting '0' into... [Dec 31 10:13:26] DEBUG[18913][C-00000011] netsock2.c: ...host '0' and port ''. [Dec 31 10:13:26] DEBUG[18913][C-00000011] chan_sip.c: Processing media-level (audio) SDP a=candidate:3728587736 1 udp 2113937151 192.168.90.8 47146 typ host generation 0... OK. [Dec 31 10:13:26] DEBUG[18913][C-00000011] netsock2.c: Splitting '192.168.90.8' into... [Dec 31 10:13:26] DEBUG[18913][C-00000011] netsock2.c: ...host '192.168.90.8' and port ''. [Dec 31 10:13:26] DEBUG[18913][C-00000011] netsock2.c: Splitting '0' into... [Dec 31 10:13:26] DEBUG[18913][C-00000011] netsock2.c: ...host '0' and port ''. [Dec 31 10:13:26] DEBUG[18913][C-00000011] chan_sip.c: Processing media-level (audio) SDP a=candidate:3728587736 2 udp 2113937151 192.168.90.8 47146 typ host generation 0... OK. [Dec 31 10:13:26] DEBUG[18913][C-00000011] netsock2.c: Splitting '192.168.0.198' into... [Dec 31 10:13:26] DEBUG[18913][C-00000011] netsock2.c: ...host '192.168.0.198' and port ''. [Dec 31 10:13:26] DEBUG[18913][C-00000011] netsock2.c: Splitting '0' into... [Dec 31 10:13:26] DEBUG[18913][C-00000011] netsock2.c: ...host '0' and port ''. [Dec 31 10:13:26] DEBUG[18913][C-00000011] chan_sip.c: Processing media-level (audio) SDP a=candidate:3081437784 1 udp 2113937151 192.168.0.198 59663 typ host generation 0... OK. [Dec 31 10:13:26] DEBUG[18913][C-00000011] netsock2.c: Splitting '192.168.0.198' into... [Dec 31 10:13:26] DEBUG[18913][C-00000011] netsock2.c: ...host '192.168.0.198' and port ''. [Dec 31 10:13:26] DEBUG[18913][C-00000011] netsock2.c: Splitting '0' into... [Dec 31 10:13:26] DEBUG[18913][C-00000011] netsock2.c: ...host '0' and port ''. [Dec 31 10:13:26] DEBUG[18913][C-00000011] chan_sip.c: Processing media-level (audio) SDP a=candidate:3081437784 2 udp 2113937151 192.168.0.198 59663 typ host generation 0... OK. [Dec 31 10:13:26] DEBUG[18913][C-00000011] netsock2.c: Splitting '77.229.25.234' into... [Dec 31 10:13:26] DEBUG[18913][C-00000011] netsock2.c: ...host '77.229.25.234' and port ''. [Dec 31 10:13:26] DEBUG[18913][C-00000011] netsock2.c: Splitting '192.168.0.198' into... [Dec 31 10:13:26] DEBUG[18913][C-00000011] netsock2.c: ...host '192.168.0.198' and port ''. [Dec 31 10:13:26] DEBUG[18913][C-00000011] chan_sip.c: Processing media-level (audio) SDP a=candidate:920847596 1 udp 1845501695 77.229.25.234 59663 typ srflx raddr 192.168.0.198 rport 59663 generation 0... OK. [Dec 31 10:13:26] DEBUG[18913][C-00000011] netsock2.c: Splitting '77.229.25.234' into... [Dec 31 10:13:26] DEBUG[18913][C-00000011] netsock2.c: ...host '77.229.25.234' and port ''. [Dec 31 10:13:26] DEBUG[18913][C-00000011] netsock2.c: Splitting '192.168.0.198' into... [Dec 31 10:13:26] DEBUG[18913][C-00000011] netsock2.c: ...host '192.168.0.198' and port ''. [Dec 31 10:13:26] DEBUG[18913][C-00000011] chan_sip.c: Processing media-level (audio) SDP a=candidate:920847596 2 udp 1845501695 77.229.25.234 59663 typ srflx raddr 192.168.0.198 rport 59663 generation 0... OK. [Dec 31 10:13:26] DEBUG[18913][C-00000011] netsock2.c: Splitting '192.168.1.130' into... [Dec 31 10:13:26] DEBUG[18913][C-00000011] netsock2.c: ...host '192.168.1.130' and port ''. [Dec 31 10:13:26] DEBUG[18913][C-00000011] netsock2.c: Splitting '0' into... [Dec 31 10:13:26] DEBUG[18913][C-00000011] netsock2.c: ...host '0' and port ''. [Dec 31 10:13:26] DEBUG[18913][C-00000011] chan_sip.c: Processing media-level (audio) SDP a=candidate:3129396856 1 tcp 1509957375 192.168.1.130 46112 typ host generation 0... OK. [Dec 31 10:13:26] DEBUG[18913][C-00000011] netsock2.c: Splitting '192.168.1.130' into... [Dec 31 10:13:26] DEBUG[18913][C-00000011] netsock2.c: ...host '192.168.1.130' and port ''. [Dec 31 10:13:26] DEBUG[18913][C-00000011] netsock2.c: Splitting '0' into... [Dec 31 10:13:26] DEBUG[18913][C-00000011] netsock2.c: ...host '0' and port ''. [Dec 31 10:13:26] DEBUG[18913][C-00000011] chan_sip.c: Processing media-level (audio) SDP a=candidate:3129396856 2 tcp 1509957375 192.168.1.130 46112 typ host generation 0... OK. [Dec 31 10:13:26] DEBUG[18913][C-00000011] netsock2.c: Splitting '192.168.90.8' into... [Dec 31 10:13:26] DEBUG[18913][C-00000011] netsock2.c: ...host '192.168.90.8' and port ''. [Dec 31 10:13:26] DEBUG[18913][C-00000011] netsock2.c: Splitting '0' into... [Dec 31 10:13:26] DEBUG[18913][C-00000011] netsock2.c: ...host '0' and port ''. [Dec 31 10:13:26] DEBUG[18913][C-00000011] chan_sip.c: Processing media-level (audio) SDP a=candidate:2428389160 1 tcp 1509957375 192.168.90.8 45034 typ host generation 0... OK. [Dec 31 10:13:26] DEBUG[18913][C-00000011] netsock2.c: Splitting '192.168.90.8' into... [Dec 31 10:13:26] DEBUG[18913][C-00000011] netsock2.c: ...host '192.168.90.8' and port ''. [Dec 31 10:13:26] DEBUG[18913][C-00000011] netsock2.c: Splitting '0' into... [Dec 31 10:13:26] DEBUG[18913][C-00000011] netsock2.c: ...host '0' and port ''. [Dec 31 10:13:26] DEBUG[18913][C-00000011] chan_sip.c: Processing media-level (audio) SDP a=candidate:2428389160 2 tcp 1509957375 192.168.90.8 45034 typ host generation 0... OK. [Dec 31 10:13:26] DEBUG[18913][C-00000011] netsock2.c: Splitting '192.168.0.198' into... [Dec 31 10:13:26] DEBUG[18913][C-00000011] netsock2.c: ...host '192.168.0.198' and port ''. [Dec 31 10:13:26] DEBUG[18913][C-00000011] netsock2.c: Splitting '0' into... [Dec 31 10:13:26] DEBUG[18913][C-00000011] netsock2.c: ...host '0' and port ''. [Dec 31 10:13:26] DEBUG[18913][C-00000011] chan_sip.c: Processing media-level (audio) SDP a=candidate:4180213416 1 tcp 1509957375 192.168.0.198 50299 typ host generation 0... OK. [Dec 31 10:13:26] DEBUG[18913][C-00000011] netsock2.c: Splitting '192.168.0.198' into... [Dec 31 10:13:26] DEBUG[18913][C-00000011] netsock2.c: ...host '192.168.0.198' and port ''. [Dec 31 10:13:26] DEBUG[18913][C-00000011] netsock2.c: Splitting '0' into... [Dec 31 10:13:26] DEBUG[18913][C-00000011] netsock2.c: ...host '0' and port ''. [Dec 31 10:13:26] DEBUG[18913][C-00000011] chan_sip.c: Processing media-level (audio) SDP a=candidate:4180213416 2 tcp 1509957375 192.168.0.198 50299 typ host generation 0... OK. [Dec 31 10:13:26] DEBUG[18913][C-00000011] chan_sip.c: Processing media-level (audio) SDP a=ice-ufrag:ObKDzUwWSkvekbZN... OK. [Dec 31 10:13:26] DEBUG[18913][C-00000011] chan_sip.c: Processing media-level (audio) SDP a=ice-pwd:5n7nLQW7XLGrvIzjrA9vv28W... OK. [Dec 31 10:13:26] DEBUG[18913][C-00000011] chan_sip.c: Processing media-level (audio) SDP a=ice-options:google-ice... UNSUPPORTED OR FAILED. [Dec 31 10:13:26] DEBUG[18913][C-00000011] chan_sip.c: Processing media-level (audio) SDP a=sendrecv... OK. [Dec 31 10:13:26] DEBUG[18913][C-00000011] chan_sip.c: Processing media-level (audio) SDP a=mid:audio... UNSUPPORTED OR FAILED. [Dec 31 10:13:26] DEBUG[18913][C-00000011] chan_sip.c: Processing media-level (audio) SDP a=rtcp-mux... UNSUPPORTED OR FAILED. [Dec 31 10:13:26] DEBUG[18913][C-00000011] sip/sdp_crypto.c: local_key64 5556vYJkh9HdCAEiGcaucMb0DPUA4FjES1PHFDnM len 40 [Dec 31 10:13:26] DEBUG[18913][C-00000011] res_srtp.c: Adding new policy for SSRC 604491810 [Dec 31 10:13:26] DEBUG[18913][C-00000011] sip/sdp_crypto.c: SRTP policy activated [Dec 31 10:13:26] DEBUG[18913][C-00000011] chan_sip.c: Processing media-level (audio) SDP a=crypto:0 AES_CM_128_HMAC_SHA1_32 inline:HqxgWh4M0amLPmSIuqwWeDmL1K0Ad93YBszrGv8b... OK. [Dec 31 10:13:26] DEBUG[18913][C-00000011] rtp_engine.c: Unsetting payload 103 on 0x7f9204133db0 [Dec 31 10:13:26] VERBOSE[18913][C-00000011] chan_sip.c: Found unknown media description format ISAC for ID 103 [Dec 31 10:13:26] DEBUG[18913][C-00000011] chan_sip.c: Processing media-level (audio) SDP a=rtpmap:103 ISAC/16000... UNSUPPORTED OR FAILED. [Dec 31 10:13:26] DEBUG[18913][C-00000011] rtp_engine.c: Unsetting payload 104 on 0x7f9204133db0 [Dec 31 10:13:26] VERBOSE[18913][C-00000011] chan_sip.c: Found unknown media description format ISAC for ID 104 [Dec 31 10:13:26] DEBUG[18913][C-00000011] chan_sip.c: Processing media-level (audio) SDP a=rtpmap:104 ISAC/32000... UNSUPPORTED OR FAILED. [Dec 31 10:13:26] DEBUG[18913][C-00000011] rtp_engine.c: Unsetting payload 111 on 0x7f9204133db0 [Dec 31 10:13:26] VERBOSE[18913][C-00000011] chan_sip.c: Found unknown media description format opus for ID 111 [Dec 31 10:13:26] DEBUG[18913][C-00000011] chan_sip.c: Processing media-level (audio) SDP a=rtpmap:111 opus/48000/2... UNSUPPORTED OR FAILED. [Dec 31 10:13:26] VERBOSE[18913][C-00000011] chan_sip.c: Found audio description format PCMU for ID 0 [Dec 31 10:13:26] DEBUG[18913][C-00000011] chan_sip.c: Processing media-level (audio) SDP a=rtpmap:0 PCMU/8000... OK. [Dec 31 10:13:26] VERBOSE[18913][C-00000011] chan_sip.c: Found audio description format PCMA for ID 8 [Dec 31 10:13:26] DEBUG[18913][C-00000011] chan_sip.c: Processing media-level (audio) SDP a=rtpmap:8 PCMA/8000... OK. [Dec 31 10:13:26] DEBUG[18913][C-00000011] rtp_engine.c: Unsetting payload 107 on 0x7f9204133db0 [Dec 31 10:13:26] VERBOSE[18913][C-00000011] chan_sip.c: Found unknown media description format CN for ID 107 [Dec 31 10:13:26] DEBUG[18913][C-00000011] chan_sip.c: Processing media-level (audio) SDP a=rtpmap:107 CN/48000... UNSUPPORTED OR FAILED. [Dec 31 10:13:26] DEBUG[18913][C-00000011] rtp_engine.c: Unsetting payload 106 on 0x7f9204133db0 [Dec 31 10:13:26] VERBOSE[18913][C-00000011] chan_sip.c: Found unknown media description format CN for ID 106 [Dec 31 10:13:26] DEBUG[18913][C-00000011] chan_sip.c: Processing media-level (audio) SDP a=rtpmap:106 CN/32000... UNSUPPORTED OR FAILED. [Dec 31 10:13:26] DEBUG[18913][C-00000011] rtp_engine.c: Unsetting payload 105 on 0x7f9204133db0 [Dec 31 10:13:26] VERBOSE[18913][C-00000011] chan_sip.c: Found unknown media description format CN for ID 105 [Dec 31 10:13:26] DEBUG[18913][C-00000011] chan_sip.c: Processing media-level (audio) SDP a=rtpmap:105 CN/16000... UNSUPPORTED OR FAILED. [Dec 31 10:13:26] VERBOSE[18913][C-00000011] chan_sip.c: Found audio description format CN for ID 13 [Dec 31 10:13:26] DEBUG[18913][C-00000011] chan_sip.c: Processing media-level (audio) SDP a=rtpmap:13 CN/8000... OK. [Dec 31 10:13:26] VERBOSE[18913][C-00000011] chan_sip.c: Found audio description format telephone-event for ID 126 [Dec 31 10:13:26] DEBUG[18913][C-00000011] chan_sip.c: Processing media-level (audio) SDP a=rtpmap:126 telephone-event/8000... OK. [Dec 31 10:13:26] DEBUG[18913][C-00000011] chan_sip.c: Processing media-level (audio) SDP a=ssrc:3829437514 cname:tQ/kidikog8Z3lzz... UNSUPPORTED OR FAILED. [Dec 31 10:13:26] DEBUG[18913][C-00000011] chan_sip.c: Processing media-level (audio) SDP a=ssrc:3829437514 msid:uIIYsO2dH7OKhrMVm2Djt4lWB8mLjcYLxoqq a0... UNSUPPORTED OR FAILED. [Dec 31 10:13:26] DEBUG[18913][C-00000011] chan_sip.c: Processing media-level (audio) SDP a=ssrc:3829437514 mslabel:uIIYsO2dH7OKhrMVm2Djt4lWB8mLjcYLxoqq... UNSUPPORTED OR FAILED. [Dec 31 10:13:26] DEBUG[18913][C-00000011] chan_sip.c: Processing media-level (audio) SDP a=ssrc:3829437514 label:uIIYsO2dH7OKhrMVm2Djt4lWB8mLjcYLxoqqa0... UNSUPPORTED OR FAILED. [Dec 31 10:13:26] VERBOSE[18913][C-00000011] chan_sip.c: Capabilities: us - (gsm|ulaw|alaw|h263|testlaw), peer - audio=(ulaw|alaw)/video=(nothing)/text=(nothing), combined - (ulaw|alaw) [Dec 31 10:13:26] VERBOSE[18913][C-00000011] chan_sip.c: Non-codec capabilities (dtmf): us - 0x1 (telephone-event|), peer - 0x3 (telephone-event|CN|), combined - 0x1 (telephone-event|) [Dec 31 10:13:26] DEBUG[18913][C-00000011] res_rtp_asterisk.c: Setting RTCP address on RTP instance '0x15aadf8' [Dec 31 10:13:26] VERBOSE[18913][C-00000011] chan_sip.c: Peer audio RTP is at port 77.229.25.234:59663 [Dec 31 10:13:26] DEBUG[18913][C-00000011] rtp_engine.c: Copying payload 0 from 0x7f9204133db0 to 0x15aafc0 [Dec 31 10:13:26] DEBUG[18913][C-00000011] rtp_engine.c: Copying payload 8 from 0x7f9204133db0 to 0x15aafc0 [Dec 31 10:13:26] DEBUG[18913][C-00000011] rtp_engine.c: Copying payload 13 from 0x7f9204133db0 to 0x15aafc0 [Dec 31 10:13:26] DEBUG[18913][C-00000011] rtp_engine.c: Copying payload 126 from 0x7f9204133db0 to 0x15aafc0 [Dec 31 10:13:26] DEBUG[18913][C-00000011] res_rtp_asterisk.c: Ignoring duplicate RTCP property on RTP instance '0x15aadf8' [Dec 31 10:13:26] DEBUG[18913][C-00000011] chan_sip.c: We're settling with these formats: (ulaw|alaw) [Dec 31 10:13:26] DEBUG[18913][C-00000011] chan_sip.c: Checking SIP call limits for device [Dec 31 10:13:26] DEBUG[18913][C-00000011] chan_sip.c: Updating call counter for incoming call [Dec 31 10:13:26] DEBUG[18913][C-00000011] netsock2.c: Splitting '212.230.19.26:6060' into... [Dec 31 10:13:26] DEBUG[18913][C-00000011] netsock2.c: ...host '212.230.19.26' and port ''. [Dec 31 10:13:26] DEBUG[18913][C-00000011] netsock2.c: Splitting 'jssip.net' into... [Dec 31 10:13:26] DEBUG[18913][C-00000011] netsock2.c: ...host 'jssip.net' and port ''. [Dec 31 10:13:26] VERBOSE[18913][C-00000011] chan_sip.c: Looking for echo in from-versatica (domain 212.230.19.26) [Dec 31 10:13:26] DEBUG[18913][C-00000011] format_pref.c: Could not find preferred codec - Going for the best codec [Dec 31 10:13:26] DEBUG[18913][C-00000011] chan_sip.c: *** Our native formats are (ulaw) [Dec 31 10:13:26] DEBUG[18913][C-00000011] chan_sip.c: *** Joint capabilities are (ulaw|alaw) [Dec 31 10:13:26] DEBUG[18913][C-00000011] chan_sip.c: *** Our capabilities are (gsm|ulaw|alaw|h263|testlaw) [Dec 31 10:13:26] DEBUG[18913][C-00000011] chan_sip.c: *** AST_CODEC_CHOOSE formats are ulaw [Dec 31 10:13:26] DEBUG[18913][C-00000011] chan_sip.c: This channel will not be able to handle video. [Dec 31 10:13:26] DEBUG[18913][C-00000011] chan_sip.c: build_route: Record-Route hop: [Dec 31 10:13:26] DEBUG[18913][C-00000011] chan_sip.c: build_route: Record-Route hop: [Dec 31 10:13:26] VERBOSE[18913][C-00000011] chan_sip.c: list_route: hop: [Dec 31 10:13:26] VERBOSE[18913][C-00000011] chan_sip.c: list_route: hop: [Dec 31 10:13:26] DEBUG[18913][C-00000011] chan_sip.c: SIP/kamailio-aliax-0000001b: New call is still down.... Trying... [Dec 31 10:13:26] VERBOSE[18913][C-00000011] chan_sip.c: <--- Transmitting (NAT) to 91.121.79.216:44240 ---> SIP/2.0 100 Trying Via: SIP/2.0/TCP 91.121.79.216:9090;branch=z9hG4bKd0b6be9e8c2be3d60d9ccabaaf18e9180bf6dd96;received=91.121.79.216;rport=44240 Via: SIP/2.0/TCP 185.83.253.25;branch=z9hG4bK7761734 Record-Route: Record-Route: From: sip:jmillan@jssip.net;tag=0cgyhltps3 To: sip:echo@jssip.net Call-ID: cxfj1uoj8f3z9jtc5wmi CSeq: 4013 INVITE Server: Asterisk PBX SVN-branch-11-r374642 Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH Supported: replaces, timer Contact: Content-Length: 0 <------------> [Dec 31 10:13:26] DEBUG[18913][C-00000011] chan_sip.c: Trying to put 'SIP/2.0 100' onto TCP socket destined for 91.121.79.216:44240 [Dec 31 10:13:26] DEBUG[14684][C-00000011] logger.c: CALL_ID [C-00000011] bound to thread. [Dec 31 10:13:26] DEBUG[18871] devicestate.c: No provider found, checking channel drivers for SIP - kamailio-aliax [Dec 31 10:13:26] DEBUG[18871] chan_sip.c: Checking device state for peer kamailio-aliax [Dec 31 10:13:26] DEBUG[18871] devicestate.c: Changing state for SIP/kamailio-aliax - state 1 (Not in use) [Dec 31 10:13:26] DEBUG[18871] devicestate.c: device 'SIP/kamailio-aliax' state '1' [Dec 31 10:13:26] DEBUG[18907] app_queue.c: Device 'SIP/kamailio-aliax' changed to state '1' (Not in use) but we don't care because they're not a member of any queue. [Dec 31 10:13:26] DEBUG[18913][C-00000011] logger.c: Call_ID [C-00000011] being removed from thread. [Dec 31 10:13:26] DEBUG[14684][C-00000011] pbx.c: Launching 'NoOp' [Dec 31 10:13:26] VERBOSE[14684][C-00000011] pbx.c: -- Executing [echo@from-versatica:1] NoOp("SIP/kamailio-aliax-0000001b", "Echo Test") in new stack [Dec 31 10:13:26] DEBUG[14684][C-00000011] pbx.c: Launching 'Answer' [Dec 31 10:13:26] VERBOSE[14684][C-00000011] pbx.c: -- Executing [echo@from-versatica:2] Answer("SIP/kamailio-aliax-0000001b", "") in new stack [Dec 31 10:13:26] DEBUG[14684][C-00000011] chan_sip.c: SIP answering channel: SIP/kamailio-aliax-0000001b [Dec 31 10:13:26] DEBUG[14684][C-00000011] res_rtp_asterisk.c: Setting the marker bit due to a source update [Dec 31 10:13:26] DEBUG[14684][C-00000011] chan_sip.c: Setting framing from config on incoming call [Dec 31 10:13:26] DEBUG[14684][C-00000011] chan_sip.c: ** Our capability: (ulaw|alaw) Video flag: True Text flag: True [Dec 31 10:13:26] DEBUG[14684][C-00000011] chan_sip.c: ** Our prefcodec: (nothing) [Dec 31 10:13:26] VERBOSE[14684][C-00000011] chan_sip.c: Audio is at 15490 [Dec 31 10:13:26] VERBOSE[14684][C-00000011] chan_sip.c: Adding codec 100003 (ulaw) to SDP [Dec 31 10:13:26] VERBOSE[14684][C-00000011] chan_sip.c: Adding codec 100004 (alaw) to SDP [Dec 31 10:13:26] VERBOSE[14684][C-00000011] chan_sip.c: Adding non-codec 0x1 (telephone-event) to SDP [Dec 31 10:13:26] DEBUG[14684][C-00000011] chan_sip.c: -- Done with adding codecs to SDP [Dec 31 10:13:26] DEBUG[14684][C-00000011] chan_sip.c: Done building SDP. Settling with this capability: (ulaw|alaw) [Dec 31 10:13:26] VERBOSE[14684][C-00000011] chan_sip.c: <--- Reliably Transmitting (NAT) to 91.121.79.216:44240 ---> SIP/2.0 200 OK Via: SIP/2.0/TCP 91.121.79.216:9090;branch=z9hG4bKd0b6be9e8c2be3d60d9ccabaaf18e9180bf6dd96;received=91.121.79.216;rport=44240 Via: SIP/2.0/TCP 185.83.253.25;branch=z9hG4bK7761734 Record-Route: Record-Route: From: sip:jmillan@jssip.net;tag=0cgyhltps3 To: sip:echo@jssip.net;tag=as37e58944 Call-ID: cxfj1uoj8f3z9jtc5wmi CSeq: 4013 INVITE Server: Asterisk PBX SVN-branch-11-r374642 Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH Supported: replaces, timer Contact: Content-Type: application/sdp Content-Length: 584 v=0 o=root 2129529664 2129529664 IN IP4 212.230.19.26 s=- c=IN IP4 212.230.19.26 t=0 0 m=audio 15490 RTP/SAVPF 0 8 101 a=rtpmap:0 PCMU/8000 a=rtpmap:8 PCMA/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=silenceSupp:off - - - - a=ptime:20 a=ice-ufrag:10d897270422e65d5235d4bb768c115e a=ice-pwd:1fd5b90d19b0e82c473679bc25f1c5b8 a=candidate:Hd4e6131a 1 UDP 2130706431 212.230.19.26 15490 typ host a=candidate:Hd4e6131a 2 UDP 2130706430 212.230.19.26 15491 typ host a=sendrecv a=crypto:1 AES_CM_128_HMAC_SHA1_32 inline:5556vYJkh9HdCAEiGcaucMb0DPUA4FjES1PHFDnM <------------> [Dec 31 10:13:26] DEBUG[14684][C-00000011] chan_sip.c: Trying to put 'SIP/2.0 200' onto TCP socket destined for 91.121.79.216:44240 [Dec 31 10:13:26] DEBUG[18871] devicestate.c: No provider found, checking channel drivers for SIP - kamailio-aliax [Dec 31 10:13:26] DEBUG[18871] chan_sip.c: Checking device state for peer kamailio-aliax [Dec 31 10:13:26] DEBUG[18871] devicestate.c: Changing state for SIP/kamailio-aliax - state 1 (Not in use) [Dec 31 10:13:26] DEBUG[18871] devicestate.c: device 'SIP/kamailio-aliax' state '1' [Dec 31 10:13:26] DEBUG[18907] app_queue.c: Device 'SIP/kamailio-aliax' changed to state '1' (Not in use) but we don't care because they're not a member of any queue. [Dec 31 10:13:26] DEBUG[18913] chan_sip.c: Header 0 [ 53]: ACK sip:echo@212.230.19.26:6060;transport=TCP SIP/2.0 [Dec 31 10:13:26] DEBUG[18913] chan_sip.c: Header 1 [ 90]: Via: SIP/2.0/TCP 91.121.79.216:9090;branch=z9hG4bK14b26ec9b77866455e03dec96f8f15a4c6c9a15e [Dec 31 10:13:26] DEBUG[18913] chan_sip.c: Header 2 [ 52]: Via: SIP/2.0/TCP 185.83.253.25;branch=z9hG4bK3886718 [Dec 31 10:13:26] DEBUG[18913] chan_sip.c: Header 3 [ 16]: Max-Forwards: 10 [Dec 31 10:13:26] DEBUG[18913] chan_sip.c: Header 4 [ 37]: To: sip:echo@jssip.net;tag=as37e58944 [Dec 31 10:13:26] DEBUG[18913] chan_sip.c: Header 5 [ 42]: From: sip:jmillan@jssip.net;tag=0cgyhltps3 [Dec 31 10:13:26] DEBUG[18913] chan_sip.c: Header 6 [ 29]: Call-ID: cxfj1uoj8f3z9jtc5wmi [Dec 31 10:13:26] DEBUG[18913] chan_sip.c: Header 7 [ 14]: CSeq: 4013 ACK [Dec 31 10:13:26] DEBUG[18913] chan_sip.c: Header 8 [ 31]: Supported: path, outbound, gruu [Dec 31 10:13:26] DEBUG[18913] chan_sip.c: Header 9 [ 23]: User-Agent: JsSIP 0.2.1 [Dec 31 10:13:26] DEBUG[18913] chan_sip.c: Header 10 [ 17]: Content-Length: 0 [Dec 31 10:13:26] DEBUG[18913] chan_sip.c: Header 11 [ 0]: [Dec 31 10:13:26] VERBOSE[18913] chan_sip.c: <--- SIP read from TCP:91.121.79.216:44240 ---> ACK sip:echo@212.230.19.26:6060;transport=TCP SIP/2.0 Via: SIP/2.0/TCP 91.121.79.216:9090;branch=z9hG4bK14b26ec9b77866455e03dec96f8f15a4c6c9a15e Via: SIP/2.0/TCP 185.83.253.25;branch=z9hG4bK3886718 Max-Forwards: 10 To: sip:echo@jssip.net;tag=as37e58944 From: sip:jmillan@jssip.net;tag=0cgyhltps3 Call-ID: cxfj1uoj8f3z9jtc5wmi CSeq: 4013 ACK Supported: path, outbound, gruu User-Agent: JsSIP 0.2.1 Content-Length: 0 <-------------> [Dec 31 10:13:26] DEBUG[18913] chan_sip.c: Header 0 [ 53]: ACK sip:echo@212.230.19.26:6060;transport=TCP SIP/2.0 [Dec 31 10:13:26] DEBUG[18913] chan_sip.c: Header 1 [ 90]: Via: SIP/2.0/TCP 91.121.79.216:9090;branch=z9hG4bK14b26ec9b77866455e03dec96f8f15a4c6c9a15e [Dec 31 10:13:26] DEBUG[18913] chan_sip.c: Header 2 [ 52]: Via: SIP/2.0/TCP 185.83.253.25;branch=z9hG4bK3886718 [Dec 31 10:13:26] DEBUG[18913] chan_sip.c: Header 3 [ 16]: Max-Forwards: 10 [Dec 31 10:13:26] DEBUG[18913] chan_sip.c: Header 4 [ 37]: To: sip:echo@jssip.net;tag=as37e58944 [Dec 31 10:13:26] DEBUG[18913] chan_sip.c: Header 5 [ 42]: From: sip:jmillan@jssip.net;tag=0cgyhltps3 [Dec 31 10:13:26] DEBUG[18913] chan_sip.c: Header 6 [ 29]: Call-ID: cxfj1uoj8f3z9jtc5wmi [Dec 31 10:13:26] DEBUG[18913] chan_sip.c: Header 7 [ 14]: CSeq: 4013 ACK [Dec 31 10:13:26] DEBUG[18913] chan_sip.c: Header 8 [ 31]: Supported: path, outbound, gruu [Dec 31 10:13:26] DEBUG[18913] chan_sip.c: Header 9 [ 23]: User-Agent: JsSIP 0.2.1 [Dec 31 10:13:26] DEBUG[18913] chan_sip.c: Header 10 [ 17]: Content-Length: 0 [Dec 31 10:13:26] VERBOSE[18913] chan_sip.c: --- (11 headers 0 lines) --- [Dec 31 10:13:26] DEBUG[18913] chan_sip.c: = Looking for Call ID: cxfj1uoj8f3z9jtc5wmi (Checking From) --From tag 0cgyhltps3 --To-tag as37e58944 [Dec 31 10:13:26] DEBUG[18913][C-00000011] logger.c: CALL_ID [C-00000011] bound to thread. [Dec 31 10:13:26] DEBUG[18913][C-00000011] chan_sip.c: **** Received ACK (6) - Command in SIP ACK [Dec 31 10:13:26] DEBUG[18913][C-00000011] chan_sip.c: Stopping retransmission on 'cxfj1uoj8f3z9jtc5wmi' of Response 4013: Match Not Found [Dec 31 10:13:26] DEBUG[18913][C-00000011] logger.c: Call_ID [C-00000011] being removed from thread. [Dec 31 10:13:26] DEBUG[18913] chan_sip.c: Header 0 [ 53]: BYE sip:echo@212.230.19.26:6060;transport=TCP SIP/2.0 [Dec 31 10:13:26] DEBUG[18913] chan_sip.c: Header 1 [ 96]: Via: SIP/2.0/TCP 91.121.79.216:9090;branch=z9hG4bK24b5ef60b77866455e03dec96f8f15a4c6c9a15e;rport [Dec 31 10:13:26] DEBUG[18913] chan_sip.c: Header 2 [ 51]: Via: SIP/2.0/TCP 185.83.253.25;branch=z9hG4bK522775 [Dec 31 10:13:26] DEBUG[18913] chan_sip.c: Header 3 [ 16]: Max-Forwards: 10 [Dec 31 10:13:26] DEBUG[18913] chan_sip.c: Header 4 [ 37]: To: sip:echo@jssip.net;tag=as37e58944 [Dec 31 10:13:26] DEBUG[18913] chan_sip.c: Header 5 [ 42]: From: sip:jmillan@jssip.net;tag=0cgyhltps3 [Dec 31 10:13:26] DEBUG[18913] chan_sip.c: Header 6 [ 29]: Call-ID: cxfj1uoj8f3z9jtc5wmi [Dec 31 10:13:26] DEBUG[18913] chan_sip.c: Header 7 [ 14]: CSeq: 4014 BYE [Dec 31 10:13:26] DEBUG[18913] chan_sip.c: Header 8 [ 50]: Reason: SIP ;cause=488 ;text="Not Acceptable Here" [Dec 31 10:13:26] DEBUG[18913] chan_sip.c: Header 9 [ 31]: Supported: path, outbound, gruu [Dec 31 10:13:26] DEBUG[18913] chan_sip.c: Header 10 [ 23]: User-Agent: JsSIP 0.2.1 [Dec 31 10:13:26] DEBUG[18913] chan_sip.c: Header 11 [ 17]: Content-Length: 0 [Dec 31 10:13:26] DEBUG[18913] chan_sip.c: Header 12 [ 0]: [Dec 31 10:13:26] VERBOSE[18913] chan_sip.c: <--- SIP read from TCP:91.121.79.216:44240 ---> BYE sip:echo@212.230.19.26:6060;transport=TCP SIP/2.0 Via: SIP/2.0/TCP 91.121.79.216:9090;branch=z9hG4bK24b5ef60b77866455e03dec96f8f15a4c6c9a15e;rport Via: SIP/2.0/TCP 185.83.253.25;branch=z9hG4bK522775 Max-Forwards: 10 To: sip:echo@jssip.net;tag=as37e58944 From: sip:jmillan@jssip.net;tag=0cgyhltps3 Call-ID: cxfj1uoj8f3z9jtc5wmi CSeq: 4014 BYE Reason: SIP ;cause=488 ;text="Not Acceptable Here" Supported: path, outbound, gruu User-Agent: JsSIP 0.2.1 Content-Length: 0 <-------------> [Dec 31 10:13:26] DEBUG[18913] chan_sip.c: Header 0 [ 53]: BYE sip:echo@212.230.19.26:6060;transport=TCP SIP/2.0 [Dec 31 10:13:26] DEBUG[18913] chan_sip.c: Header 1 [ 96]: Via: SIP/2.0/TCP 91.121.79.216:9090;branch=z9hG4bK24b5ef60b77866455e03dec96f8f15a4c6c9a15e;rport [Dec 31 10:13:26] DEBUG[18913] chan_sip.c: Header 2 [ 51]: Via: SIP/2.0/TCP 185.83.253.25;branch=z9hG4bK522775 [Dec 31 10:13:26] DEBUG[18913] chan_sip.c: Header 3 [ 16]: Max-Forwards: 10 [Dec 31 10:13:26] DEBUG[18913] chan_sip.c: Header 4 [ 37]: To: sip:echo@jssip.net;tag=as37e58944 [Dec 31 10:13:26] DEBUG[18913] chan_sip.c: Header 5 [ 42]: From: sip:jmillan@jssip.net;tag=0cgyhltps3 [Dec 31 10:13:26] DEBUG[18913] chan_sip.c: Header 6 [ 29]: Call-ID: cxfj1uoj8f3z9jtc5wmi [Dec 31 10:13:26] DEBUG[18913] chan_sip.c: Header 7 [ 14]: CSeq: 4014 BYE [Dec 31 10:13:26] DEBUG[18913] chan_sip.c: Header 8 [ 50]: Reason: SIP ;cause=488 ;text="Not Acceptable Here" [Dec 31 10:13:26] DEBUG[18913] chan_sip.c: Header 9 [ 31]: Supported: path, outbound, gruu [Dec 31 10:13:26] DEBUG[18913] chan_sip.c: Header 10 [ 23]: User-Agent: JsSIP 0.2.1 [Dec 31 10:13:26] DEBUG[18913] chan_sip.c: Header 11 [ 17]: Content-Length: 0 [Dec 31 10:13:26] VERBOSE[18913] chan_sip.c: --- (12 headers 0 lines) --- [Dec 31 10:13:26] DEBUG[18913] chan_sip.c: = Looking for Call ID: cxfj1uoj8f3z9jtc5wmi (Checking From) --From tag 0cgyhltps3 --To-tag as37e58944 [Dec 31 10:13:26] DEBUG[18913][C-00000011] logger.c: CALL_ID [C-00000011] bound to thread. [Dec 31 10:13:26] DEBUG[18913][C-00000011] chan_sip.c: **** Received BYE (8) - Command in SIP BYE [Dec 31 10:13:26] DEBUG[18913][C-00000011] chan_sip.c: Initializing initreq for method BYE - callid cxfj1uoj8f3z9jtc5wmi [Dec 31 10:13:26] DEBUG[18913][C-00000011] netsock2.c: Splitting '91.121.79.216:9090' into... [Dec 31 10:13:26] DEBUG[18913][C-00000011] netsock2.c: ...host '91.121.79.216' and port '9090'. [Dec 31 10:13:26] DEBUG[18913][C-00000011] chan_sip.c: NAT detected for 91.121.79.216:9090 / 91.121.79.216:44240 [Dec 31 10:13:26] VERBOSE[18913][C-00000011] chan_sip.c: Sending to 91.121.79.216:44240 (NAT) [Dec 31 10:13:26] DEBUG[18913][C-00000011] chan_sip.c: Setting SIP_ALREADYGONE on dialog cxfj1uoj8f3z9jtc5wmi [Dec 31 10:13:26] DEBUG[18913][C-00000011] res_rtp_asterisk.c: Setting RTCP address on RTP instance '0x15aadf8' [Dec 31 10:13:26] VERBOSE[18913][C-00000011] chan_sip.c: Scheduling destruction of SIP dialog 'cxfj1uoj8f3z9jtc5wmi' in 32000 ms (Method: BYE) [Dec 31 10:13:26] DEBUG[18913][C-00000011] chan_sip.c: Received bye, issuing owner hangup [Dec 31 10:13:26] VERBOSE[18913][C-00000011] chan_sip.c: <--- Transmitting (NAT) to 91.121.79.216:44240 ---> SIP/2.0 200 OK Via: SIP/2.0/TCP 91.121.79.216:9090;branch=z9hG4bK24b5ef60b77866455e03dec96f8f15a4c6c9a15e;received=91.121.79.216;rport=44240 Via: SIP/2.0/TCP 185.83.253.25;branch=z9hG4bK522775 From: sip:jmillan@jssip.net;tag=0cgyhltps3 To: sip:echo@jssip.net;tag=as37e58944 Call-ID: cxfj1uoj8f3z9jtc5wmi CSeq: 4014 BYE Server: Asterisk PBX SVN-branch-11-r374642 Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH Supported: replaces, timer Content-Length: 0 <------------> [Dec 31 10:13:26] DEBUG[18913][C-00000011] chan_sip.c: Trying to put 'SIP/2.0 200' onto TCP socket destined for 91.121.79.216:44240 [Dec 31 10:13:26] DEBUG[18913][C-00000011] logger.c: Call_ID [C-00000011] being removed from thread. [Dec 31 10:13:26] DEBUG[14684][C-00000011] channel.c: Hangup of channel SIP/kamailio-aliax-0000001b detected in answer routine [Dec 31 10:13:26] DEBUG[14684][C-00000011] pbx.c: Spawn extension (from-versatica,echo,2) exited non-zero on 'SIP/kamailio-aliax-0000001b' [Dec 31 10:13:26] VERBOSE[14684][C-00000011] pbx.c: == Spawn extension (from-versatica, echo, 2) exited non-zero on 'SIP/kamailio-aliax-0000001b' [Dec 31 10:13:26] DEBUG[14684][C-00000011] channel.c: Soft-Hanging up channel 'SIP/kamailio-aliax-0000001b' [Dec 31 10:13:26] DEBUG[14684][C-00000011] channel.c: Hanging up channel 'SIP/kamailio-aliax-0000001b' [Dec 31 10:13:26] DEBUG[14684][C-00000011] chan_sip.c: Hangup call SIP/kamailio-aliax-0000001b, SIP callid cxfj1uoj8f3z9jtc5wmi [Dec 31 10:13:26] DEBUG[14684][C-00000011] res_rtp_asterisk.c: Setting RTCP address on RTP instance '0x15aadf8' [Dec 31 10:13:26] DEBUG[14684][C-00000011] cdr_radius.c: Unable to create RADIUS record. CDR not recorded! [Dec 31 10:13:26] DEBUG[18871] devicestate.c: No provider found, checking channel drivers for SIP - kamailio-aliax [Dec 31 10:13:26] DEBUG[18871] chan_sip.c: Checking device state for peer kamailio-aliax [Dec 31 10:13:26] DEBUG[18871] devicestate.c: Changing state for SIP/kamailio-aliax - state 1 (Not in use) [Dec 31 10:13:26] DEBUG[18871] devicestate.c: device 'SIP/kamailio-aliax' state '1' [Dec 31 10:13:26] DEBUG[18907] app_queue.c: Device 'SIP/kamailio-aliax' changed to state '1' (Not in use) but we don't care because they're not a member of any queue.