[Mar 6 11:18:53] DEBUG[20266]: http.c:686 handle_uri: HTTP Request URI is /ws [Mar 6 11:18:53] DEBUG[20266]: http.c:735 handle_uri: match request [ws] with handler [httpstatus] len 0 [Mar 6 11:18:53] DEBUG[20266]: http.c:735 handle_uri: match request [ws] with handler [phoneprov] len 10 [Mar 6 11:18:53] DEBUG[20266]: http.c:735 handle_uri: match request [ws] with handler [static] len 9 [Mar 6 11:18:53] DEBUG[20266]: http.c:735 handle_uri: match request [ws] with handler [ws] len 6 <--- SIP read from WS:194.150.179.78:51172 ---> REGISTER sip:my.domain.com SIP/2.0 Via: SIP/2.0/WS df7jal23ls0d.invalid;branch=z9hG4bKd2WCMWnlqcolEThtqaUqXyKLCwFCJgp0;rport From: "4343";tag=RlIGBPzeZlvRMUTptMTF To: "4343" Contact: "4343";expires=200;click2call=no;+g.oma.sip-im;+audio;language="en,fr" Call-ID: bc073feb-7bfa-c8a6-0328-c3a1be226010 CSeq: 51063 REGISTER Content-Length: 0 Max-Forwards: 70 User-Agent: client v.1.0 Organization: Doubango Telecom Supported: path <-------------> [Mar 6 11:18:53] DEBUG[20266]: chan_sip.c:9625 parse_request: Header 0 [ 33]: REGISTER sip:my.domain.com SIP/2.0 [Mar 6 11:18:53] DEBUG[20266]: chan_sip.c:9625 parse_request: Header 1 [ 89]: Via: SIP/2.0/WS df7jal23ls0d.invalid;branch=z9hG4bKd2WCMWnlqcolEThtqaUqXyKLCwFCJgp0;rport [Mar 6 11:18:53] DEBUG[20266]: chan_sip.c:9625 parse_request: Header 2 [ 60]: From: "4343";tag=RlIGBPzeZlvRMUTptMTF [Mar 6 11:18:53] DEBUG[20266]: chan_sip.c:9625 parse_request: Header 3 [ 33]: To: "4343" [Mar 6 11:18:53] DEBUG[20266]: chan_sip.c:9625 parse_request: Header 4 [142]: Contact: "4343";expires=200;click2call=no;+g.oma.sip-im;+audio;language="en,fr" [Mar 6 11:18:53] DEBUG[20266]: chan_sip.c:9625 parse_request: Header 5 [ 45]: Call-ID: bc073feb-7bfa-c8a6-0328-c3a1be226010 [Mar 6 11:18:53] DEBUG[20266]: chan_sip.c:9625 parse_request: Header 6 [ 20]: CSeq: 51063 REGISTER [Mar 6 11:18:53] DEBUG[20266]: chan_sip.c:9625 parse_request: Header 7 [ 17]: Content-Length: 0 [Mar 6 11:18:53] DEBUG[20266]: chan_sip.c:9625 parse_request: Header 8 [ 16]: Max-Forwards: 70 [Mar 6 11:18:53] DEBUG[20266]: chan_sip.c:9625 parse_request: Header 9 [ 24]: User-Agent: client v.1.0 [Mar 6 11:18:53] DEBUG[20266]: chan_sip.c:9625 parse_request: Header 10 [ 25]: Organization: Doubango Telecom [Mar 6 11:18:53] DEBUG[20266]: chan_sip.c:9625 parse_request: Header 11 [ 15]: Supported: path --- (12 headers 0 lines) --- [Mar 6 11:18:53] DEBUG[20266]: chan_sip.c:9175 find_call: = Looking for Call ID: bc073feb-7bfa-c8a6-0328-c3a1be226010 (Checking From) --From tag RlIGBPzeZlvRMUTptMTF --To-tag [Mar 6 11:18:53] DEBUG[20266]: acl.c:979 ast_ouraddrfor: For destination '194.150.179.78', our source address is '192.168.3.202'. [Mar 6 11:18:53] DEBUG[20266]: netsock2.c:138 ast_sockaddr_split_hostport: Splitting 'my.domain.com' into... [Mar 6 11:18:53] DEBUG[20266]: netsock2.c:192 ast_sockaddr_split_hostport: ...host 'my.domain.com' and port ''. [Mar 6 11:18:53] DEBUG[20266]: chan_sip.c:4000 ast_sip_ouraddrfor: Target address 194.150.179.78:51172 is not local, substituting externaddr [Mar 6 11:18:53] DEBUG[20266]: chan_sip.c:4033 ast_sip_ouraddrfor: Setting SIP_TRANSPORT_WS with address 195.195.195.195:0 [Mar 6 11:18:53] DEBUG[20266]: chan_sip.c:8778 sip_alloc: Allocating new SIP dialog for bc073feb-7bfa-c8a6-0328-c3a1be226010 - REGISTER (No RTP) [Mar 6 11:18:53] DEBUG[20266]: chan_sip.c:28199 handle_incoming: **** Received REGISTER (2) - Command in SIP REGISTER [Mar 6 11:18:53] DEBUG[20266]: chan_sip.c:28021 handle_request_register: Initializing initreq for method REGISTER - callid bc073feb-7bfa-c8a6-0328-c3a1be226010 [Mar 6 11:18:53] DEBUG[20266]: netsock2.c:138 ast_sockaddr_split_hostport: Splitting 'my.domain.com' into... [Mar 6 11:18:53] DEBUG[20266]: netsock2.c:192 ast_sockaddr_split_hostport: ...host 'my.domain.com' and port ''. <--- Transmitting (NAT) to 194.150.179.78:51172 ---> SIP/2.0 401 Unauthorized Via: SIP/2.0/WS df7jal23ls0d.invalid;branch=z9hG4bKd2WCMWnlqcolEThtqaUqXyKLCwFCJgp0;received=194.150.179.78;rport=51172 From: "4343";tag=RlIGBPzeZlvRMUTptMTF To: "4343";tag=as14ba4a15 Call-ID: bc073feb-7bfa-c8a6-0328-c3a1be226010 CSeq: 51063 REGISTER Server: Asterisk PBX 11.8.0 Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH Supported: replaces, timer WWW-Authenticate: Digest algorithm=MD5, realm="asterisk", nonce="01d73b53" Content-Length: 0 <------------> [Mar 6 11:18:53] DEBUG[20266]: chan_sip.c:3876 __sip_xmit: Trying to put 'SIP/2.0 401' onto WS socket destined for 194.150.179.78:51172 Scheduling destruction of SIP dialog 'bc073feb-7bfa-c8a6-0328-c3a1be226010' in 32000 ms (Method: REGISTER) <--- SIP read from WS:194.150.179.78:51172 ---> REGISTER sip:my.domain.com SIP/2.0 Via: SIP/2.0/WS df7jal23ls0d.invalid;branch=z9hG4bKbNqcBHJBHLUv3RRbwCiPuNcoKmdnybq6;rport From: "4343";tag=RlIGBPzeZlvRMUTptMTF To: "4343" Contact: "4343";expires=200;click2call=no;+g.oma.sip-im;+audio;language="en,fr" Call-ID: bc073feb-7bfa-c8a6-0328-c3a1be226010 CSeq: 51064 REGISTER Content-Length: 0 Max-Forwards: 70 Authorization: Digest username="4343",realm="asterisk",nonce="01d73b53",uri="sip:my.domain.com",response="82abb07a28f1bff0ec0d648d65e2ba68",algorithm=MD5 User-Agent: client v.1.0 Organization: Doubango Telecom Supported: path <-------------> [Mar 6 11:18:53] DEBUG[20266]: chan_sip.c:9625 parse_request: Header 0 [ 33]: REGISTER sip:my.domain.com SIP/2.0 [Mar 6 11:18:53] DEBUG[20266]: chan_sip.c:9625 parse_request: Header 1 [ 89]: Via: SIP/2.0/WS df7jal23ls0d.invalid;branch=z9hG4bKbNqcBHJBHLUv3RRbwCiPuNcoKmdnybq6;rport [Mar 6 11:18:53] DEBUG[20266]: chan_sip.c:9625 parse_request: Header 2 [ 60]: From: "4343";tag=RlIGBPzeZlvRMUTptMTF [Mar 6 11:18:53] DEBUG[20266]: chan_sip.c:9625 parse_request: Header 3 [ 33]: To: "4343" [Mar 6 11:18:53] DEBUG[20266]: chan_sip.c:9625 parse_request: Header 4 [142]: Contact: "4343";expires=200;click2call=no;+g.oma.sip-im;+audio;language="en,fr" [Mar 6 11:18:53] DEBUG[20266]: chan_sip.c:9625 parse_request: Header 5 [ 45]: Call-ID: bc073feb-7bfa-c8a6-0328-c3a1be226010 [Mar 6 11:18:53] DEBUG[20266]: chan_sip.c:9625 parse_request: Header 6 [ 20]: CSeq: 51064 REGISTER [Mar 6 11:18:53] DEBUG[20266]: chan_sip.c:9625 parse_request: Header 7 [ 17]: Content-Length: 0 [Mar 6 11:18:53] DEBUG[20266]: chan_sip.c:9625 parse_request: Header 8 [ 16]: Max-Forwards: 70 [Mar 6 11:18:53] DEBUG[20266]: chan_sip.c:9625 parse_request: Header 9 [152]: Authorization: Digest username="4343",realm="asterisk",nonce="01d73b53",uri="sip:my.domain.com",response="82abb07a28f1bff0ec0d648d65e2ba68",algorithm=MD5 [Mar 6 11:18:53] DEBUG[20266]: chan_sip.c:9625 parse_request: Header 10 [ 24]: User-Agent: client v.1.0 [Mar 6 11:18:53] DEBUG[20266]: chan_sip.c:9625 parse_request: Header 11 [ 25]: Organization: Doubango Telecom [Mar 6 11:18:53] DEBUG[20266]: chan_sip.c:9625 parse_request: Header 12 [ 15]: Supported: path --- (13 headers 0 lines) --- [Mar 6 11:18:53] DEBUG[20266]: chan_sip.c:9175 find_call: = Looking for Call ID: bc073feb-7bfa-c8a6-0328-c3a1be226010 (Checking From) --From tag RlIGBPzeZlvRMUTptMTF --To-tag [Mar 6 11:18:53] DEBUG[20266]: netsock2.c:138 ast_sockaddr_split_hostport: Splitting 'my.domain.com' into... [Mar 6 11:18:53] DEBUG[20266]: netsock2.c:192 ast_sockaddr_split_hostport: ...host 'my.domain.com' and port ''. [Mar 6 11:18:53] DEBUG[20266]: netsock2.c:138 ast_sockaddr_split_hostport: Splitting 'my.domain.com' into... [Mar 6 11:18:53] DEBUG[20266]: netsock2.c:192 ast_sockaddr_split_hostport: ...host 'my.domain.com' and port ''. [Mar 6 11:18:53] DEBUG[20266]: chan_sip.c:28199 handle_incoming: **** Received REGISTER (2) - Command in SIP REGISTER [Mar 6 11:18:53] DEBUG[20266]: chan_sip.c:28021 handle_request_register: Initializing initreq for method REGISTER - callid bc073feb-7bfa-c8a6-0328-c3a1be226010 [Mar 6 11:18:53] DEBUG[20266]: netsock2.c:138 ast_sockaddr_split_hostport: Splitting 'my.domain.com' into... [Mar 6 11:18:53] DEBUG[20266]: netsock2.c:192 ast_sockaddr_split_hostport: ...host 'my.domain.com' and port ''. [Mar 6 11:18:53] DEBUG[20266]: chan_sip.c:16092 parse_register_contact: Store REGISTER's src-IP:port for call routing. <--- Transmitting (NAT) to 194.150.179.78:51172 ---> SIP/2.0 200 OK Via: SIP/2.0/WS df7jal23ls0d.invalid;branch=z9hG4bKbNqcBHJBHLUv3RRbwCiPuNcoKmdnybq6;received=194.150.179.78;rport=51172 From: "4343";tag=RlIGBPzeZlvRMUTptMTF To: "4343";tag=as14ba4a15 Call-ID: bc073feb-7bfa-c8a6-0328-c3a1be226010 CSeq: 51064 REGISTER Server: Asterisk PBX 11.8.0 Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH Supported: replaces, timer Expires: 200 Contact: ;expires=200 Date: Thu, 06 Mar 2014 07:18:53 GMT Content-Length: 0 <------------> [Mar 6 11:18:53] DEBUG[20266]: chan_sip.c:3876 __sip_xmit: Trying to put 'SIP/2.0 200' onto WS socket destined for 194.150.179.78:51172 [Mar 6 11:18:53] DEBUG[20227]: devicestate.c:344 _ast_device_state: No provider found, checking channel drivers for SIP - 4343 Scheduling destruction of SIP dialog 'bc073feb-7bfa-c8a6-0328-c3a1be226010' in 32000 ms (Method: REGISTER) [Mar 6 11:18:53] DEBUG[20227]: chan_sip.c:29655 sip_devicestate: Checking device state for peer 4343 [Mar 6 11:18:53] DEBUG[20227]: devicestate.c:467 do_state_change: Changing state for SIP/4343 - state 1 (Not in use) [Mar 6 11:18:53] DEBUG[20227]: devicestate.c:442 devstate_event: device 'SIP/4343' state '1' [Mar 6 11:18:53] DEBUG[20265]: app_queue.c:1809 handle_statechange: Device 'SIP/4343' changed to state '1' (Not in use) but we don't care because they're not a member of any queue. <--- SIP read from WS:194.150.179.78:51172 ---> INVITE sip:7@my.domain.com SIP/2.0 Via: SIP/2.0/WS df7jal23ls0d.invalid;branch=z9hG4bKrZEmNnsVyOhQpyy1mrqbXTA06ismWRKg;rport From: "4343";tag=xbCCGl9iQzag4E2lNMcp To: Contact: "4343";impi=4343;ha1=b00198c97a531d94410593fb8d4a9b44;+g.oma.sip-im;+sip.ice;language="en,fr" Call-ID: 27d88875-b3bf-1649-0ae0-bf4126e6ba4e CSeq: 22590 INVITE Content-Type: application/sdp Content-Length: 1859 Max-Forwards: 70 User-Agent: client v.1.0 Organization: Doubango Telecom v=0 o=- 6637247084114243000 2 IN IP4 127.0.0.1 s=Doubango Telecom - chrome t=0 0 a=group:BUNDLE audio a=msid-semantic: WMS nnpFRjEiRai5mgAzrcGHQS9m9mhWeDzvzCj6 m=audio 53648 RTP/SAVPF 111 103 104 0 8 106 105 13 126 c=IN IP4 194.150.179.78 b=AS:64 a=rtcp:53648 IN IP4 194.150.179.78 a=candidate:456696959 1 udp 2113937151 172.16.15.97 53648 typ host generation 0 a=candidate:456696959 2 udp 2113937151 172.16.15.97 53648 typ host generation 0 a=candidate:1438341263 1 tcp 1509957375 172.16.15.97 0 typ host generation 0 a=candidate:1438341263 2 tcp 1509957375 172.16.15.97 0 typ host generation 0 a=candidate:3978878379 1 udp 1845501695 194.150.179.78 53648 typ srflx raddr 172.16.15.97 rport 53648 generation 0 a=candidate:3978878379 2 udp 1845501695 194.150.179.78 53648 typ srflx raddr 172.16.15.97 rport 53648 generation 0 a=ice-ufrag:BB2R1zOyZPOqPVtk a=ice-pwd:rmvm6sU0puoFM1kBbETnVYWz a=ice-options:google-ice a=fingerprint:sha-256 34:09:52:1C:E0:58:E7:18:25:0F:5C:21:22:4E:70:47:62:E4:C1:9B:C1:6C:B5:C4:D8:66:A8:8D:15:04:AE:01 a=setup:actpass a=mid:audio a=extmap:1 urn:ietf:params:rtp-hdrext:ssrc-audio-level a=sendrecv a=rtcp-mux a=crypto:0 AES_CM_128_HMAC_SHA1_32 inline:abtzy8YP7f1ussNKkvVlY0c0S3NnsGhKRn+s0gAD a=crypto:1 AES_CM_128_HMAC_SHA1_80 inline:1p0Y3wGUuDK83JqAPu0z+oPwgHK5XntLcOzr1YQc a=rtpmap:111 opus/48000/2 a=fmtp:111 minptime=10 a=rtpmap:103 ISAC/16000 a=rtpmap:104 ISAC/32000 a=rtpmap:0 PCMU/8000 a=rtpmap:8 PCMA/8000 a=rtpmap:106 CN/32000 a=rtpmap:105 CN/16000 a=rtpmap:13 CN/8000 a=rtpmap:126 telephone-event/8000 a=maxptime:60 a=ssrc:3844988677 cname:ObXGGnsu+HwTtBZs a=ssrc:3844988677 msid:nnpFRjEiRai5mgAzrcGHQS9m9mhWeDzvzCj6 0c3b223b-d479-46d9-9e16-8555f36c7fba a=ssrc:3844988677 mslabel:nnpFRjEiRai5mgAzrcGHQS9m9mhWeDzvzCj6 a=ssrc:3844988677 label:0c3b223b-d479-46d9-9e16-8555f36c7fba <-------------> [Mar 6 11:18:57] DEBUG[20266]: chan_sip.c:9625 parse_request: Header 0 [ 33]: INVITE sip:7@my.domain.com SIP/2.0 [Mar 6 11:18:57] DEBUG[20266]: chan_sip.c:9625 parse_request: Header 1 [ 89]: Via: SIP/2.0/WS df7jal23ls0d.invalid;branch=z9hG4bKrZEmNnsVyOhQpyy1mrqbXTA06ismWRKg;rport [Mar 6 11:18:57] DEBUG[20266]: chan_sip.c:9625 parse_request: Header 2 [ 60]: From: "4343";tag=xbCCGl9iQzag4E2lNMcp [Mar 6 11:18:57] DEBUG[20266]: chan_sip.c:9625 parse_request: Header 3 [ 24]: To: [Mar 6 11:18:57] DEBUG[20266]: chan_sip.c:9625 parse_request: Header 4 [179]: Contact: "4343";impi=4343;ha1=b00198c97a531d94410593fb8d4a9b44;+g.oma.sip-im;+sip.ice;language="en,fr" [Mar 6 11:18:57] DEBUG[20266]: chan_sip.c:9625 parse_request: Header 5 [ 45]: Call-ID: 27d88875-b3bf-1649-0ae0-bf4126e6ba4e [Mar 6 11:18:57] DEBUG[20266]: chan_sip.c:9625 parse_request: Header 6 [ 18]: CSeq: 22590 INVITE [Mar 6 11:18:57] DEBUG[20266]: chan_sip.c:9625 parse_request: Header 7 [ 29]: Content-Type: application/sdp [Mar 6 11:18:57] DEBUG[20266]: chan_sip.c:9625 parse_request: Header 8 [ 20]: Content-Length: 1859 [Mar 6 11:18:57] DEBUG[20266]: chan_sip.c:9625 parse_request: Header 9 [ 16]: Max-Forwards: 70 [Mar 6 11:18:57] DEBUG[20266]: chan_sip.c:9625 parse_request: Header 10 [ 24]: User-Agent: client v.1.0 [Mar 6 11:18:57] DEBUG[20266]: chan_sip.c:9625 parse_request: Header 11 [ 25]: Organization: Doubango Telecom [Mar 6 11:18:57] DEBUG[20266]: chan_sip.c:9625 parse_request: Header 12 [ 0]: [Mar 6 11:18:57] DEBUG[20266]: chan_sip.c:9625 parse_request: Body 0 [ 3]: v=0 [Mar 6 11:18:57] DEBUG[20266]: chan_sip.c:9625 parse_request: Body 1 [ 42]: o=- 6637247084114243000 2 IN IP4 127.0.0.1 [Mar 6 11:18:57] DEBUG[20266]: chan_sip.c:9625 parse_request: Body 2 [ 27]: s=Doubango Telecom - chrome [Mar 6 11:18:57] DEBUG[20266]: chan_sip.c:9625 parse_request: Body 3 [ 5]: t=0 0 [Mar 6 11:18:57] DEBUG[20266]: chan_sip.c:9625 parse_request: Body 4 [ 20]: a=group:BUNDLE audio [Mar 6 11:18:57] DEBUG[20266]: chan_sip.c:9625 parse_request: Body 5 [ 57]: a=msid-semantic: WMS nnpFRjEiRai5mgAzrcGHQS9m9mhWeDzvzCj6 [Mar 6 11:18:57] DEBUG[20266]: chan_sip.c:9625 parse_request: Body 6 [ 54]: m=audio 53648 RTP/SAVPF 111 103 104 0 8 106 105 13 126 [Mar 6 11:18:57] DEBUG[20266]: chan_sip.c:9625 parse_request: Body 7 [ 23]: c=IN IP4 194.150.179.78 [Mar 6 11:18:57] DEBUG[20266]: chan_sip.c:9625 parse_request: Body 8 [ 7]: b=AS:64 [Mar 6 11:18:57] DEBUG[20266]: chan_sip.c:9625 parse_request: Body 9 [ 34]: a=rtcp:53648 IN IP4 194.150.179.78 [Mar 6 11:18:57] DEBUG[20266]: chan_sip.c:9625 parse_request: Body 10 [ 79]: a=candidate:456696959 1 udp 2113937151 172.16.15.97 53648 typ host generation 0 [Mar 6 11:18:57] DEBUG[20266]: chan_sip.c:9625 parse_request: Body 11 [ 79]: a=candidate:456696959 2 udp 2113937151 172.16.15.97 53648 typ host generation 0 [Mar 6 11:18:57] DEBUG[20266]: chan_sip.c:9625 parse_request: Body 12 [ 76]: a=candidate:1438341263 1 tcp 1509957375 172.16.15.97 0 typ host generation 0 [Mar 6 11:18:57] DEBUG[20266]: chan_sip.c:9625 parse_request: Body 13 [ 76]: a=candidate:1438341263 2 tcp 1509957375 172.16.15.97 0 typ host generation 0 [Mar 6 11:18:57] DEBUG[20266]: chan_sip.c:9625 parse_request: Body 14 [114]: a=candidate:3978878379 1 udp 1845501695 194.150.179.78 53648 typ srflx raddr 172.16.15.97 rport 53648 generation 0 [Mar 6 11:18:57] DEBUG[20266]: chan_sip.c:9625 parse_request: Body 15 [114]: a=candidate:3978878379 2 udp 1845501695 194.150.179.78 53648 typ srflx raddr 172.16.15.97 rport 53648 generation 0 [Mar 6 11:18:57] DEBUG[20266]: chan_sip.c:9625 parse_request: Body 16 [ 28]: a=ice-ufrag:BB2R1zOyZPOqPVtk [Mar 6 11:18:57] DEBUG[20266]: chan_sip.c:9625 parse_request: Body 17 [ 34]: a=ice-pwd:rmvm6sU0puoFM1kBbETnVYWz [Mar 6 11:18:57] DEBUG[20266]: chan_sip.c:9625 parse_request: Body 18 [ 24]: a=ice-options:google-ice [Mar 6 11:18:57] DEBUG[20266]: chan_sip.c:9625 parse_request: Body 19 [117]: a=fingerprint:sha-256 34:09:52:1C:E0:58:E7:18:25:0F:5C:21:22:4E:70:47:62:E4:C1:9B:C1:6C:B5:C4:D8:66:A8:8D:15:04:AE:01 [Mar 6 11:18:57] DEBUG[20266]: chan_sip.c:9625 parse_request: Body 20 [ 15]: a=setup:actpass [Mar 6 11:18:57] DEBUG[20266]: chan_sip.c:9625 parse_request: Body 21 [ 11]: a=mid:audio [Mar 6 11:18:57] DEBUG[20266]: chan_sip.c:9625 parse_request: Body 22 [ 54]: a=extmap:1 urn:ietf:params:rtp-hdrext:ssrc-audio-level [Mar 6 11:18:57] DEBUG[20266]: chan_sip.c:9625 parse_request: Body 23 [ 10]: a=sendrecv [Mar 6 11:18:57] DEBUG[20266]: chan_sip.c:9625 parse_request: Body 24 [ 10]: a=rtcp-mux [Mar 6 11:18:57] DEBUG[20266]: chan_sip.c:9625 parse_request: Body 25 [ 82]: a=crypto:0 AES_CM_128_HMAC_SHA1_32 inline:abtzy8YP7f1ussNKkvVlY0c0S3NnsGhKRn+s0gAD [Mar 6 11:18:57] DEBUG[20266]: chan_sip.c:9625 parse_request: Body 26 [ 82]: a=crypto:1 AES_CM_128_HMAC_SHA1_80 inline:1p0Y3wGUuDK83JqAPu0z+oPwgHK5XntLcOzr1YQc [Mar 6 11:18:57] DEBUG[20266]: chan_sip.c:9625 parse_request: Body 27 [ 25]: a=rtpmap:111 opus/48000/2 [Mar 6 11:18:57] DEBUG[20266]: chan_sip.c:9625 parse_request: Body 28 [ 22]: a=fmtp:111 minptime=10 [Mar 6 11:18:57] DEBUG[20266]: chan_sip.c:9625 parse_request: Body 29 [ 23]: a=rtpmap:103 ISAC/16000 [Mar 6 11:18:57] DEBUG[20266]: chan_sip.c:9625 parse_request: Body 30 [ 23]: a=rtpmap:104 ISAC/32000 [Mar 6 11:18:57] DEBUG[20266]: chan_sip.c:9625 parse_request: Body 31 [ 20]: a=rtpmap:0 PCMU/8000 [Mar 6 11:18:57] DEBUG[20266]: chan_sip.c:9625 parse_request: Body 32 [ 20]: a=rtpmap:8 PCMA/8000 [Mar 6 11:18:57] DEBUG[20266]: chan_sip.c:9625 parse_request: Body 33 [ 21]: a=rtpmap:106 CN/32000 [Mar 6 11:18:57] DEBUG[20266]: chan_sip.c:9625 parse_request: Body 34 [ 21]: a=rtpmap:105 CN/16000 [Mar 6 11:18:57] DEBUG[20266]: chan_sip.c:9625 parse_request: Body 35 [ 19]: a=rtpmap:13 CN/8000 [Mar 6 11:18:57] DEBUG[20266]: chan_sip.c:9625 parse_request: Body 36 [ 33]: a=rtpmap:126 telephone-event/8000 [Mar 6 11:18:57] DEBUG[20266]: chan_sip.c:9625 parse_request: Body 37 [ 13]: a=maxptime:60 [Mar 6 11:18:57] DEBUG[20266]: chan_sip.c:9625 parse_request: Body 38 [ 40]: a=ssrc:3844988677 cname:ObXGGnsu+HwTtBZs [Mar 6 11:18:57] DEBUG[20266]: chan_sip.c:9625 parse_request: Body 39 [ 96]: a=ssrc:3844988677 msid:nnpFRjEiRai5mgAzrcGHQS9m9mhWeDzvzCj6 0c3b223b-d479-46d9-9e16-8555f36c7fba [Mar 6 11:18:57] DEBUG[20266]: chan_sip.c:9625 parse_request: Body 40 [ 62]: a=ssrc:3844988677 mslabel:nnpFRjEiRai5mgAzrcGHQS9m9mhWeDzvzCj6 [Mar 6 11:18:57] DEBUG[20266]: chan_sip.c:9662 parse_request: Body 41 [ 60]: a=ssrc:3844988677 label:0c3b223b-d479-46d9-9e16-8555f36c7fba --- (12 headers 42 lines) --- [Mar 6 11:18:57] DEBUG[20266]: chan_sip.c:9175 find_call: = Looking for Call ID: 27d88875-b3bf-1649-0ae0-bf4126e6ba4e (Checking From) --From tag xbCCGl9iQzag4E2lNMcp --To-tag [Mar 6 11:18:57] DEBUG[20266]: acl.c:979 ast_ouraddrfor: For destination '194.150.179.78', our source address is '192.168.3.202'. [Mar 6 11:18:57] DEBUG[20266]: chan_sip.c:4000 ast_sip_ouraddrfor: Target address 194.150.179.78:51172 is not local, substituting externaddr [Mar 6 11:18:57] DEBUG[20266]: chan_sip.c:4033 ast_sip_ouraddrfor: Setting SIP_TRANSPORT_WS with address 195.195.195.195:0 [Mar 6 11:18:57] DEBUG[20266]: chan_sip.c:8778 sip_alloc: Allocating new SIP dialog for 27d88875-b3bf-1649-0ae0-bf4126e6ba4e - INVITE (No RTP) [Mar 6 11:18:57] DEBUG[20266][C-00000000]: chan_sip.c:28199 handle_incoming: **** Received INVITE (5) - Command in SIP INVITE [Mar 6 11:18:57] DEBUG[20266][C-00000000]: chan_sip.c:25323 handle_request_invite: Initializing initreq for method INVITE - callid 27d88875-b3bf-1649-0ae0-bf4126e6ba4e Using INVITE request as basis request - 27d88875-b3bf-1649-0ae0-bf4126e6ba4e [Mar 6 11:18:57] DEBUG[20266][C-00000000]: netsock2.c:138 ast_sockaddr_split_hostport: Splitting 'my.domain.com' into... [Mar 6 11:18:57] DEBUG[20266][C-00000000]: netsock2.c:192 ast_sockaddr_split_hostport: ...host 'my.domain.com' and port ''. Found peer '4343' for '4343' from 194.150.179.78:51172 <--- Reliably Transmitting (NAT) to 194.150.179.78:51172 ---> SIP/2.0 401 Unauthorized Via: SIP/2.0/WS df7jal23ls0d.invalid;branch=z9hG4bKrZEmNnsVyOhQpyy1mrqbXTA06ismWRKg;received=194.150.179.78;rport=51172 From: "4343";tag=xbCCGl9iQzag4E2lNMcp To: ;tag=as7d3a4ffd Call-ID: 27d88875-b3bf-1649-0ae0-bf4126e6ba4e CSeq: 22590 INVITE Server: Asterisk PBX 11.8.0 Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH Supported: replaces, timer WWW-Authenticate: Digest algorithm=MD5, realm="asterisk", nonce="2839cd8e" Content-Length: 0 <------------> [Mar 6 11:18:57] DEBUG[20266][C-00000000]: chan_sip.c:3876 __sip_xmit: Trying to put 'SIP/2.0 401' onto WS socket destined for 194.150.179.78:51172 Scheduling destruction of SIP dialog '27d88875-b3bf-1649-0ae0-bf4126e6ba4e' in 32000 ms (Method: INVITE) <--- SIP read from WS:194.150.179.78:51172 ---> ACK sip:7@my.domain.com SIP/2.0 Via: SIP/2.0/WS df7jal23ls0d.invalid;branch=z9hG4bKrZEmNnsVyOhQpyy1mrqbXTA06ismWRKg;rport From: "4343";tag=xbCCGl9iQzag4E2lNMcp To: ;tag=as7d3a4ffd Call-ID: 27d88875-b3bf-1649-0ae0-bf4126e6ba4e CSeq: 22590 ACK Content-Length: 0 Max-Forwards: 70 <-------------> [Mar 6 11:18:57] DEBUG[20266]: chan_sip.c:9625 parse_request: Header 0 [ 30]: ACK sip:7@my.domain.com SIP/2.0 [Mar 6 11:18:57] DEBUG[20266]: chan_sip.c:9625 parse_request: Header 1 [ 89]: Via: SIP/2.0/WS df7jal23ls0d.invalid;branch=z9hG4bKrZEmNnsVyOhQpyy1mrqbXTA06ismWRKg;rport [Mar 6 11:18:57] DEBUG[20266]: chan_sip.c:9625 parse_request: Header 2 [ 60]: From: "4343";tag=xbCCGl9iQzag4E2lNMcp [Mar 6 11:18:57] DEBUG[20266]: chan_sip.c:9625 parse_request: Header 3 [ 39]: To: ;tag=as7d3a4ffd [Mar 6 11:18:57] DEBUG[20266]: chan_sip.c:9625 parse_request: Header 4 [ 45]: Call-ID: 27d88875-b3bf-1649-0ae0-bf4126e6ba4e [Mar 6 11:18:57] DEBUG[20266]: chan_sip.c:9625 parse_request: Header 5 [ 15]: CSeq: 22590 ACK [Mar 6 11:18:57] DEBUG[20266]: chan_sip.c:9625 parse_request: Header 6 [ 17]: Content-Length: 0 [Mar 6 11:18:57] DEBUG[20266]: chan_sip.c:9625 parse_request: Header 7 [ 16]: Max-Forwards: 70 --- (8 headers 0 lines) --- [Mar 6 11:18:57] DEBUG[20266]: chan_sip.c:9175 find_call: = Looking for Call ID: 27d88875-b3bf-1649-0ae0-bf4126e6ba4e (Checking From) --From tag xbCCGl9iQzag4E2lNMcp --To-tag as7d3a4ffd [Mar 6 11:18:57] DEBUG[20266][C-00000000]: chan_sip.c:28199 handle_incoming: **** Received ACK (6) - Command in SIP ACK [Mar 6 11:18:57] DEBUG[20266][C-00000000]: chan_sip.c:4568 __sip_ack: Stopping retransmission on '27d88875-b3bf-1649-0ae0-bf4126e6ba4e' of Response 22590: Match Not Found <--- SIP read from WS:194.150.179.78:51172 ---> INVITE sip:7@my.domain.com SIP/2.0 Via: SIP/2.0/WS df7jal23ls0d.invalid;branch=z9hG4bKD9faEIeJmrAawTplwU3TGC0wg9hT8LCi;rport From: "4343";tag=xbCCGl9iQzag4E2lNMcp To: Contact: "4343";impi=4343;ha1=b00198c97a531d94410593fb8d4a9b44;+g.oma.sip-im;+sip.ice;language="en,fr" Call-ID: 27d88875-b3bf-1649-0ae0-bf4126e6ba4e CSeq: 22591 INVITE Content-Type: application/sdp Content-Length: 1859 Max-Forwards: 70 Authorization: Digest username="4343",realm="asterisk",nonce="2839cd8e",uri="sip:7@my.domain.com",response="4b349423b4ba513cfe4dd09756762768",algorithm=MD5 User-Agent: client v.1.0 Organization: Doubango Telecom v=0 o=- 6637247084114243000 2 IN IP4 127.0.0.1 s=Doubango Telecom - chrome t=0 0 a=group:BUNDLE audio a=msid-semantic: WMS nnpFRjEiRai5mgAzrcGHQS9m9mhWeDzvzCj6 m=audio 53648 RTP/SAVPF 111 103 104 0 8 106 105 13 126 c=IN IP4 194.150.179.78 b=AS:64 a=rtcp:53648 IN IP4 194.150.179.78 a=candidate:456696959 1 udp 2113937151 172.16.15.97 53648 typ host generation 0 a=candidate:456696959 2 udp 2113937151 172.16.15.97 53648 typ host generation 0 a=candidate:1438341263 1 tcp 1509957375 172.16.15.97 0 typ host generation 0 a=candidate:1438341263 2 tcp 1509957375 172.16.15.97 0 typ host generation 0 a=candidate:3978878379 1 udp 1845501695 194.150.179.78 53648 typ srflx raddr 172.16.15.97 rport 53648 generation 0 a=candidate:3978878379 2 udp 1845501695 194.150.179.78 53648 typ srflx raddr 172.16.15.97 rport 53648 generation 0 a=ice-ufrag:BB2R1zOyZPOqPVtk a=ice-pwd:rmvm6sU0puoFM1kBbETnVYWz a=ice-options:google-ice a=fingerprint:sha-256 34:09:52:1C:E0:58:E7:18:25:0F:5C:21:22:4E:70:47:62:E4:C1:9B:C1:6C:B5:C4:D8:66:A8:8D:15:04:AE:01 a=setup:actpass a=mid:audio a=extmap:1 urn:ietf:params:rtp-hdrext:ssrc-audio-level a=sendrecv a=rtcp-mux a=crypto:0 AES_CM_128_HMAC_SHA1_32 inline:abtzy8YP7f1ussNKkvVlY0c0S3NnsGhKRn+s0gAD a=crypto:1 AES_CM_128_HMAC_SHA1_80 inline:1p0Y3wGUuDK83JqAPu0z+oPwgHK5XntLcOzr1YQc a=rtpmap:111 opus/48000/2 a=fmtp:111 minptime=10 a=rtpmap:103 ISAC/16000 a=rtpmap:104 ISAC/32000 a=rtpmap:0 PCMU/8000 a=rtpmap:8 PCMA/8000 a=rtpmap:106 CN/32000 a=rtpmap:105 CN/16000 a=rtpmap:13 CN/8000 a=rtpmap:126 telephone-event/8000 a=maxptime:60 a=ssrc:3844988677 cname:ObXGGnsu+HwTtBZs a=ssrc:3844988677 msid:nnpFRjEiRai5mgAzrcGHQS9m9mhWeDzvzCj6 0c3b223b-d479-46d9-9e16-8555f36c7fba a=ssrc:3844988677 mslabel:nnpFRjEiRai5mgAzrcGHQS9m9mhWeDzvzCj6 a=ssrc:3844988677 label:0c3b223b-d479-46d9-9e16-8555f36c7fba <-------------> [Mar 6 11:18:57] DEBUG[20266]: chan_sip.c:9625 parse_request: Header 0 [ 33]: INVITE sip:7@my.domain.com SIP/2.0 [Mar 6 11:18:57] DEBUG[20266]: chan_sip.c:9625 parse_request: Header 1 [ 89]: Via: SIP/2.0/WS df7jal23ls0d.invalid;branch=z9hG4bKD9faEIeJmrAawTplwU3TGC0wg9hT8LCi;rport [Mar 6 11:18:57] DEBUG[20266]: chan_sip.c:9625 parse_request: Header 2 [ 60]: From: "4343";tag=xbCCGl9iQzag4E2lNMcp [Mar 6 11:18:57] DEBUG[20266]: chan_sip.c:9625 parse_request: Header 3 [ 24]: To: [Mar 6 11:18:57] DEBUG[20266]: chan_sip.c:9625 parse_request: Header 4 [179]: Contact: "4343";impi=4343;ha1=b00198c97a531d94410593fb8d4a9b44;+g.oma.sip-im;+sip.ice;language="en,fr" [Mar 6 11:18:57] DEBUG[20266]: chan_sip.c:9625 parse_request: Header 5 [ 45]: Call-ID: 27d88875-b3bf-1649-0ae0-bf4126e6ba4e [Mar 6 11:18:57] DEBUG[20266]: chan_sip.c:9625 parse_request: Header 6 [ 18]: CSeq: 22591 INVITE [Mar 6 11:18:57] DEBUG[20266]: chan_sip.c:9625 parse_request: Header 7 [ 29]: Content-Type: application/sdp [Mar 6 11:18:57] DEBUG[20266]: chan_sip.c:9625 parse_request: Header 8 [ 20]: Content-Length: 1859 [Mar 6 11:18:57] DEBUG[20266]: chan_sip.c:9625 parse_request: Header 9 [ 16]: Max-Forwards: 70 [Mar 6 11:18:57] DEBUG[20266]: chan_sip.c:9625 parse_request: Header 10 [154]: Authorization: Digest username="4343",realm="asterisk",nonce="2839cd8e",uri="sip:7@my.domain.com",response="4b349423b4ba513cfe4dd09756762768",algorithm=MD5 [Mar 6 11:18:57] DEBUG[20266]: chan_sip.c:9625 parse_request: Header 11 [ 24]: User-Agent: client v.1.0 [Mar 6 11:18:57] DEBUG[20266]: chan_sip.c:9625 parse_request: Header 12 [ 25]: Organization: Doubango Telecom [Mar 6 11:18:57] DEBUG[20266]: chan_sip.c:9625 parse_request: Header 13 [ 0]: [Mar 6 11:18:57] DEBUG[20266]: chan_sip.c:9625 parse_request: Body 0 [ 3]: v=0 [Mar 6 11:18:57] DEBUG[20266]: chan_sip.c:9625 parse_request: Body 1 [ 42]: o=- 6637247084114243000 2 IN IP4 127.0.0.1 [Mar 6 11:18:57] DEBUG[20266]: chan_sip.c:9625 parse_request: Body 2 [ 27]: s=Doubango Telecom - chrome [Mar 6 11:18:57] DEBUG[20266]: chan_sip.c:9625 parse_request: Body 3 [ 5]: t=0 0 [Mar 6 11:18:57] DEBUG[20266]: chan_sip.c:9625 parse_request: Body 4 [ 20]: a=group:BUNDLE audio [Mar 6 11:18:57] DEBUG[20266]: chan_sip.c:9625 parse_request: Body 5 [ 57]: a=msid-semantic: WMS nnpFRjEiRai5mgAzrcGHQS9m9mhWeDzvzCj6 [Mar 6 11:18:57] DEBUG[20266]: chan_sip.c:9625 parse_request: Body 6 [ 54]: m=audio 53648 RTP/SAVPF 111 103 104 0 8 106 105 13 126 [Mar 6 11:18:57] DEBUG[20266]: chan_sip.c:9625 parse_request: Body 7 [ 23]: c=IN IP4 194.150.179.78 [Mar 6 11:18:57] DEBUG[20266]: chan_sip.c:9625 parse_request: Body 8 [ 7]: b=AS:64 [Mar 6 11:18:57] DEBUG[20266]: chan_sip.c:9625 parse_request: Body 9 [ 34]: a=rtcp:53648 IN IP4 194.150.179.78 [Mar 6 11:18:57] DEBUG[20266]: chan_sip.c:9625 parse_request: Body 10 [ 79]: a=candidate:456696959 1 udp 2113937151 172.16.15.97 53648 typ host generation 0 [Mar 6 11:18:57] DEBUG[20266]: chan_sip.c:9625 parse_request: Body 11 [ 79]: a=candidate:456696959 2 udp 2113937151 172.16.15.97 53648 typ host generation 0 [Mar 6 11:18:57] DEBUG[20266]: chan_sip.c:9625 parse_request: Body 12 [ 76]: a=candidate:1438341263 1 tcp 1509957375 172.16.15.97 0 typ host generation 0 [Mar 6 11:18:57] DEBUG[20266]: chan_sip.c:9625 parse_request: Body 13 [ 76]: a=candidate:1438341263 2 tcp 1509957375 172.16.15.97 0 typ host generation 0 [Mar 6 11:18:57] DEBUG[20266]: chan_sip.c:9625 parse_request: Body 14 [114]: a=candidate:3978878379 1 udp 1845501695 194.150.179.78 53648 typ srflx raddr 172.16.15.97 rport 53648 generation 0 [Mar 6 11:18:57] DEBUG[20266]: chan_sip.c:9625 parse_request: Body 15 [114]: a=candidate:3978878379 2 udp 1845501695 194.150.179.78 53648 typ srflx raddr 172.16.15.97 rport 53648 generation 0 [Mar 6 11:18:57] DEBUG[20266]: chan_sip.c:9625 parse_request: Body 16 [ 28]: a=ice-ufrag:BB2R1zOyZPOqPVtk [Mar 6 11:18:57] DEBUG[20266]: chan_sip.c:9625 parse_request: Body 17 [ 34]: a=ice-pwd:rmvm6sU0puoFM1kBbETnVYWz [Mar 6 11:18:57] DEBUG[20266]: chan_sip.c:9625 parse_request: Body 18 [ 24]: a=ice-options:google-ice [Mar 6 11:18:57] DEBUG[20266]: chan_sip.c:9625 parse_request: Body 19 [117]: a=fingerprint:sha-256 34:09:52:1C:E0:58:E7:18:25:0F:5C:21:22:4E:70:47:62:E4:C1:9B:C1:6C:B5:C4:D8:66:A8:8D:15:04:AE:01 [Mar 6 11:18:57] DEBUG[20266]: chan_sip.c:9625 parse_request: Body 20 [ 15]: a=setup:actpass [Mar 6 11:18:57] DEBUG[20266]: chan_sip.c:9625 parse_request: Body 21 [ 11]: a=mid:audio [Mar 6 11:18:57] DEBUG[20266]: chan_sip.c:9625 parse_request: Body 22 [ 54]: a=extmap:1 urn:ietf:params:rtp-hdrext:ssrc-audio-level [Mar 6 11:18:57] DEBUG[20266]: chan_sip.c:9625 parse_request: Body 23 [ 10]: a=sendrecv [Mar 6 11:18:57] DEBUG[20266]: chan_sip.c:9625 parse_request: Body 24 [ 10]: a=rtcp-mux [Mar 6 11:18:57] DEBUG[20266]: chan_sip.c:9625 parse_request: Body 25 [ 82]: a=crypto:0 AES_CM_128_HMAC_SHA1_32 inline:abtzy8YP7f1ussNKkvVlY0c0S3NnsGhKRn+s0gAD [Mar 6 11:18:57] DEBUG[20266]: chan_sip.c:9625 parse_request: Body 26 [ 82]: a=crypto:1 AES_CM_128_HMAC_SHA1_80 inline:1p0Y3wGUuDK83JqAPu0z+oPwgHK5XntLcOzr1YQc [Mar 6 11:18:57] DEBUG[20266]: chan_sip.c:9625 parse_request: Body 27 [ 25]: a=rtpmap:111 opus/48000/2 [Mar 6 11:18:57] DEBUG[20266]: chan_sip.c:9625 parse_request: Body 28 [ 22]: a=fmtp:111 minptime=10 [Mar 6 11:18:57] DEBUG[20266]: chan_sip.c:9625 parse_request: Body 29 [ 23]: a=rtpmap:103 ISAC/16000 [Mar 6 11:18:57] DEBUG[20266]: chan_sip.c:9625 parse_request: Body 30 [ 23]: a=rtpmap:104 ISAC/32000 [Mar 6 11:18:57] DEBUG[20266]: chan_sip.c:9625 parse_request: Body 31 [ 20]: a=rtpmap:0 PCMU/8000 [Mar 6 11:18:57] DEBUG[20266]: chan_sip.c:9625 parse_request: Body 32 [ 20]: a=rtpmap:8 PCMA/8000 [Mar 6 11:18:57] DEBUG[20266]: chan_sip.c:9625 parse_request: Body 33 [ 21]: a=rtpmap:106 CN/32000 [Mar 6 11:18:57] DEBUG[20266]: chan_sip.c:9625 parse_request: Body 34 [ 21]: a=rtpmap:105 CN/16000 [Mar 6 11:18:57] DEBUG[20266]: chan_sip.c:9625 parse_request: Body 35 [ 19]: a=rtpmap:13 CN/8000 [Mar 6 11:18:57] DEBUG[20266]: chan_sip.c:9625 parse_request: Body 36 [ 33]: a=rtpmap:126 telephone-event/8000 [Mar 6 11:18:57] DEBUG[20266]: chan_sip.c:9625 parse_request: Body 37 [ 13]: a=maxptime:60 [Mar 6 11:18:57] DEBUG[20266]: chan_sip.c:9625 parse_request: Body 38 [ 40]: a=ssrc:3844988677 cname:ObXGGnsu+HwTtBZs [Mar 6 11:18:57] DEBUG[20266]: chan_sip.c:9625 parse_request: Body 39 [ 96]: a=ssrc:3844988677 msid:nnpFRjEiRai5mgAzrcGHQS9m9mhWeDzvzCj6 0c3b223b-d479-46d9-9e16-8555f36c7fba [Mar 6 11:18:57] DEBUG[20266]: chan_sip.c:9625 parse_request: Body 40 [ 62]: a=ssrc:3844988677 mslabel:nnpFRjEiRai5mgAzrcGHQS9m9mhWeDzvzCj6 [Mar 6 11:18:57] DEBUG[20266]: chan_sip.c:9662 parse_request: Body 41 [ 60]: a=ssrc:3844988677 label:0c3b223b-d479-46d9-9e16-8555f36c7fba --- (13 headers 42 lines) --- [Mar 6 11:18:57] DEBUG[20266]: chan_sip.c:9175 find_call: = Looking for Call ID: 27d88875-b3bf-1649-0ae0-bf4126e6ba4e (Checking From) --From tag xbCCGl9iQzag4E2lNMcp --To-tag [Mar 6 11:18:57] DEBUG[20266]: netsock2.c:138 ast_sockaddr_split_hostport: Splitting 'my.domain.com' into... [Mar 6 11:18:57] DEBUG[20266]: netsock2.c:192 ast_sockaddr_split_hostport: ...host 'my.domain.com' and port ''. [Mar 6 11:18:57] DEBUG[20266]: netsock2.c:138 ast_sockaddr_split_hostport: Splitting 'my.domain.com' into... [Mar 6 11:18:57] DEBUG[20266]: netsock2.c:192 ast_sockaddr_split_hostport: ...host 'my.domain.com' and port ''. [Mar 6 11:18:57] DEBUG[20266][C-00000000]: chan_sip.c:28199 handle_incoming: **** Received INVITE (5) - Command in SIP INVITE [Mar 6 11:18:57] DEBUG[20266][C-00000000]: chan_sip.c:25323 handle_request_invite: Initializing initreq for method INVITE - callid 27d88875-b3bf-1649-0ae0-bf4126e6ba4e Using INVITE request as basis request - 27d88875-b3bf-1649-0ae0-bf4126e6ba4e [Mar 6 11:18:57] DEBUG[20266][C-00000000]: netsock2.c:138 ast_sockaddr_split_hostport: Splitting 'my.domain.com' into... [Mar 6 11:18:57] DEBUG[20266][C-00000000]: netsock2.c:192 ast_sockaddr_split_hostport: ...host 'my.domain.com' and port ''. Found peer '4343' for '4343' from 194.150.179.78:51172 [Mar 6 11:18:57] DEBUG[20266][C-00000000]: rtp_engine.c:283 ast_rtp_instance_new: Using engine 'asterisk' for RTP instance '0x7f7948026cf8' [Mar 6 11:18:57] DEBUG[20266][C-00000000]: res_rtp_asterisk.c:1819 ast_rtp_new: Allocated port 21958 for RTP instance '0x7f7948026cf8' [Mar 6 11:18:57] DEBUG[20266][C-00000000]: rtp_engine.c:292 ast_rtp_instance_new: RTP instance '0x7f7948026cf8' is setup and ready to go [Mar 6 11:18:57] DEBUG[20266][C-00000000]: res_rtp_asterisk.c:3958 ast_rtp_prop_set: Setup RTCP on RTP instance '0x7f7948026cf8' [Mar 6 11:18:57] DEBUG[20266][C-00000000]: chan_sip.c:5737 do_setnat: Setting NAT on RTP to On [Mar 6 11:18:57] DEBUG[20266][C-00000000]: chan_sip.c:10057 process_sdp: Processing session-level SDP v=0... UNSUPPORTED OR FAILED. [Mar 6 11:18:57] DEBUG[20266][C-00000000]: chan_sip.c:10057 process_sdp: Processing session-level SDP o=- 6637247084114243000 2 IN IP4 127.0.0.1... OK. [Mar 6 11:18:57] DEBUG[20266][C-00000000]: chan_sip.c:10057 process_sdp: Processing session-level SDP s=Doubango Telecom - chrome... UNSUPPORTED OR FAILED. [Mar 6 11:18:57] DEBUG[20266][C-00000000]: chan_sip.c:10057 process_sdp: Processing session-level SDP t=0 0... UNSUPPORTED OR FAILED. [Mar 6 11:18:57] DEBUG[20266][C-00000000]: chan_sip.c:10057 process_sdp: Processing session-level SDP a=group:BUNDLE audio... UNSUPPORTED OR FAILED. [Mar 6 11:18:57] DEBUG[20266][C-00000000]: chan_sip.c:10057 process_sdp: Processing session-level SDP a=msid-semantic: WMS nnpFRjEiRai5mgAzrcGHQS9m9mhWeDzvzCj6... UNSUPPORTED OR FAILED. Found RTP audio format 111 [Mar 6 11:18:57] DEBUG[20266][C-00000000]: rtp_engine.c:557 ast_rtp_codecs_payloads_set_m_type: Setting payload 111 based on m type on 0x7f7905e0daf0 Found RTP audio format 103 [Mar 6 11:18:57] DEBUG[20266][C-00000000]: rtp_engine.c:557 ast_rtp_codecs_payloads_set_m_type: Setting payload 103 based on m type on 0x7f7905e0daf0 Found RTP audio format 104 [Mar 6 11:18:57] DEBUG[20266][C-00000000]: rtp_engine.c:557 ast_rtp_codecs_payloads_set_m_type: Setting payload 104 based on m type on 0x7f7905e0daf0 Found RTP audio format 0 [Mar 6 11:18:57] DEBUG[20266][C-00000000]: rtp_engine.c:557 ast_rtp_codecs_payloads_set_m_type: Setting payload 0 based on m type on 0x7f7905e0daf0 Found RTP audio format 8 [Mar 6 11:18:57] DEBUG[20266][C-00000000]: rtp_engine.c:557 ast_rtp_codecs_payloads_set_m_type: Setting payload 8 based on m type on 0x7f7905e0daf0 Found RTP audio format 106 [Mar 6 11:18:57] DEBUG[20266][C-00000000]: rtp_engine.c:557 ast_rtp_codecs_payloads_set_m_type: Setting payload 106 based on m type on 0x7f7905e0daf0 Found RTP audio format 105 [Mar 6 11:18:57] DEBUG[20266][C-00000000]: rtp_engine.c:557 ast_rtp_codecs_payloads_set_m_type: Setting payload 105 based on m type on 0x7f7905e0daf0 Found RTP audio format 13 [Mar 6 11:18:57] DEBUG[20266][C-00000000]: rtp_engine.c:557 ast_rtp_codecs_payloads_set_m_type: Setting payload 13 based on m type on 0x7f7905e0daf0 Found RTP audio format 126 [Mar 6 11:18:57] DEBUG[20266][C-00000000]: rtp_engine.c:557 ast_rtp_codecs_payloads_set_m_type: Setting payload 126 based on m type on 0x7f7905e0daf0 [Mar 6 11:18:57] DEBUG[20266][C-00000000]: netsock2.c:138 ast_sockaddr_split_hostport: Splitting '194.150.179.78' into... [Mar 6 11:18:57] DEBUG[20266][C-00000000]: netsock2.c:192 ast_sockaddr_split_hostport: ...host '194.150.179.78' and port ''. [Mar 6 11:18:57] DEBUG[20266][C-00000000]: chan_sip.c:10490 process_sdp: Processing media-level (audio) SDP c=IN IP4 194.150.179.78... OK. [Mar 6 11:18:57] DEBUG[20266][C-00000000]: chan_sip.c:10490 process_sdp: Processing media-level (audio) SDP b=AS:64... UNSUPPORTED OR FAILED. [Mar 6 11:18:57] DEBUG[20266][C-00000000]: chan_sip.c:10490 process_sdp: Processing media-level (audio) SDP a=rtcp:53648 IN IP4 194.150.179.78... UNSUPPORTED OR FAILED. [Mar 6 11:18:57] DEBUG[20266][C-00000000]: chan_sip.c:10490 process_sdp: Processing media-level (audio) SDP a=candidate:456696959 1 udp 2113937151 172.16.15.97 53648 typ host generation 0... UNSUPPORTED OR FAILED. [Mar 6 11:18:57] DEBUG[20266][C-00000000]: chan_sip.c:10490 process_sdp: Processing media-level (audio) SDP a=candidate:456696959 2 udp 2113937151 172.16.15.97 53648 typ host generation 0... UNSUPPORTED OR FAILED. [Mar 6 11:18:57] DEBUG[20266][C-00000000]: chan_sip.c:10490 process_sdp: Processing media-level (audio) SDP a=candidate:1438341263 1 tcp 1509957375 172.16.15.97 0 typ host generation 0... UNSUPPORTED OR FAILED. [Mar 6 11:18:57] DEBUG[20266][C-00000000]: chan_sip.c:10490 process_sdp: Processing media-level (audio) SDP a=candidate:1438341263 2 tcp 1509957375 172.16.15.97 0 typ host generation 0... UNSUPPORTED OR FAILED. [Mar 6 11:18:57] DEBUG[20266][C-00000000]: chan_sip.c:10490 process_sdp: Processing media-level (audio) SDP a=candidate:3978878379 1 udp 1845501695 194.150.179.78 53648 typ srflx raddr 172.16.15.97 rport 53648 generation 0... UNSUPPORTED OR FAILED. [Mar 6 11:18:57] DEBUG[20266][C-00000000]: chan_sip.c:10490 process_sdp: Processing media-level (audio) SDP a=candidate:3978878379 2 udp 1845501695 194.150.179.78 53648 typ srflx raddr 172.16.15.97 rport 53648 generation 0... UNSUPPORTED OR FAILED. [Mar 6 11:18:57] DEBUG[20266][C-00000000]: chan_sip.c:10490 process_sdp: Processing media-level (audio) SDP a=ice-ufrag:BB2R1zOyZPOqPVtk... UNSUPPORTED OR FAILED. [Mar 6 11:18:57] DEBUG[20266][C-00000000]: chan_sip.c:10490 process_sdp: Processing media-level (audio) SDP a=ice-pwd:rmvm6sU0puoFM1kBbETnVYWz... UNSUPPORTED OR FAILED. [Mar 6 11:18:57] DEBUG[20266][C-00000000]: chan_sip.c:10490 process_sdp: Processing media-level (audio) SDP a=ice-options:google-ice... UNSUPPORTED OR FAILED. [Mar 6 11:18:57] DEBUG[20266][C-00000000]: chan_sip.c:10490 process_sdp: Processing media-level (audio) SDP a=fingerprint:sha-256 34:09:52:1C:E0:58:E7:18:25:0F:5C:21:22:4E:70:47:62:E4:C1:9B:C1:6C:B5:C4:D8:66:A8:8D:15:04:AE:01... UNSUPPORTED OR FAILED. [Mar 6 11:18:57] DEBUG[20266][C-00000000]: chan_sip.c:10490 process_sdp: Processing media-level (audio) SDP a=setup:actpass... UNSUPPORTED OR FAILED. [Mar 6 11:18:57] DEBUG[20266][C-00000000]: chan_sip.c:10490 process_sdp: Processing media-level (audio) SDP a=mid:audio... UNSUPPORTED OR FAILED. [Mar 6 11:18:57] DEBUG[20266][C-00000000]: chan_sip.c:10490 process_sdp: Processing media-level (audio) SDP a=extmap:1 urn:ietf:params:rtp-hdrext:ssrc-audio-level... UNSUPPORTED OR FAILED. [Mar 6 11:18:57] DEBUG[20266][C-00000000]: chan_sip.c:10490 process_sdp: Processing media-level (audio) SDP a=sendrecv... OK. [Mar 6 11:18:57] DEBUG[20266][C-00000000]: chan_sip.c:10490 process_sdp: Processing media-level (audio) SDP a=rtcp-mux... UNSUPPORTED OR FAILED. [Mar 6 11:18:57] DEBUG[20266][C-00000000]: sip/sdp_crypto.c:108 sdp_crypto_setup: local_key64 GFSGvnQ5wKldNu/wYWBZjWasYPoXi4r56sBe3ILs len 40 [Mar 6 11:18:57] DEBUG[20266][C-00000000]: res_srtp.c:507 ast_srtp_add_stream: Adding new policy for SSRC 122245324 [Mar 6 11:18:57] DEBUG[20266][C-00000000]: sip/sdp_crypto.c:177 sdp_crypto_activate: SRTP policy activated [Mar 6 11:18:57] DEBUG[20266][C-00000000]: sip/sdp_crypto.c:285 sdp_crypto_process: Accepting crypto tag 0 [Mar 6 11:18:57] DEBUG[20266][C-00000000]: sip/sdp_crypto.c:310 sdp_crypto_offer: Crypto line: a=crypto:0 AES_CM_128_HMAC_SHA1_32 inline:GFSGvnQ5wKldNu/wYWBZjWasYPoXi4r56sBe3ILs [Mar 6 11:18:57] DEBUG[20266][C-00000000]: chan_sip.c:10490 process_sdp: Processing media-level (audio) SDP a=crypto:0 AES_CM_128_HMAC_SHA1_32 inline:abtzy8YP7f1ussNKkvVlY0c0S3NnsGhKRn+s0gAD... OK. [Mar 6 11:18:57] DEBUG[20266][C-00000000]: chan_sip.c:10490 process_sdp: Processing media-level (audio) SDP a=crypto:1 AES_CM_128_HMAC_SHA1_80 inline:1p0Y3wGUuDK83JqAPu0z+oPwgHK5XntLcOzr1YQc... UNSUPPORTED OR FAILED. [Mar 6 11:18:57] DEBUG[20266][C-00000000]: rtp_engine.c:641 ast_rtp_codecs_payloads_unset: Unsetting payload 111 on 0x7f7905e0daf0 Found unknown media description format opus for ID 111 [Mar 6 11:18:57] DEBUG[20266][C-00000000]: chan_sip.c:10490 process_sdp: Processing media-level (audio) SDP a=rtpmap:111 opus/48000/2... UNSUPPORTED OR FAILED. [Mar 6 11:18:57] DEBUG[20266][C-00000000]: chan_sip.c:10490 process_sdp: Processing media-level (audio) SDP a=fmtp:111 minptime=10... UNSUPPORTED OR FAILED. [Mar 6 11:18:57] DEBUG[20266][C-00000000]: rtp_engine.c:641 ast_rtp_codecs_payloads_unset: Unsetting payload 103 on 0x7f7905e0daf0 Found unknown media description format ISAC for ID 103 [Mar 6 11:18:57] DEBUG[20266][C-00000000]: chan_sip.c:10490 process_sdp: Processing media-level (audio) SDP a=rtpmap:103 ISAC/16000... UNSUPPORTED OR FAILED. [Mar 6 11:18:57] DEBUG[20266][C-00000000]: rtp_engine.c:641 ast_rtp_codecs_payloads_unset: Unsetting payload 104 on 0x7f7905e0daf0 Found unknown media description format ISAC for ID 104 [Mar 6 11:18:57] DEBUG[20266][C-00000000]: chan_sip.c:10490 process_sdp: Processing media-level (audio) SDP a=rtpmap:104 ISAC/32000... UNSUPPORTED OR FAILED. Found audio description format PCMU for ID 0 [Mar 6 11:18:57] DEBUG[20266][C-00000000]: chan_sip.c:10490 process_sdp: Processing media-level (audio) SDP a=rtpmap:0 PCMU/8000... OK. Found audio description format PCMA for ID 8 [Mar 6 11:18:57] DEBUG[20266][C-00000000]: chan_sip.c:10490 process_sdp: Processing media-level (audio) SDP a=rtpmap:8 PCMA/8000... OK. [Mar 6 11:18:57] DEBUG[20266][C-00000000]: rtp_engine.c:641 ast_rtp_codecs_payloads_unset: Unsetting payload 106 on 0x7f7905e0daf0 Found unknown media description format CN for ID 106 [Mar 6 11:18:57] DEBUG[20266][C-00000000]: chan_sip.c:10490 process_sdp: Processing media-level (audio) SDP a=rtpmap:106 CN/32000... UNSUPPORTED OR FAILED. [Mar 6 11:18:57] DEBUG[20266][C-00000000]: rtp_engine.c:641 ast_rtp_codecs_payloads_unset: Unsetting payload 105 on 0x7f7905e0daf0 Found unknown media description format CN for ID 105 [Mar 6 11:18:57] DEBUG[20266][C-00000000]: chan_sip.c:10490 process_sdp: Processing media-level (audio) SDP a=rtpmap:105 CN/16000... UNSUPPORTED OR FAILED. Found audio description format CN for ID 13 [Mar 6 11:18:57] DEBUG[20266][C-00000000]: chan_sip.c:10490 process_sdp: Processing media-level (audio) SDP a=rtpmap:13 CN/8000... OK. Found audio description format telephone-event for ID 126 [Mar 6 11:18:57] DEBUG[20266][C-00000000]: chan_sip.c:10490 process_sdp: Processing media-level (audio) SDP a=rtpmap:126 telephone-event/8000... OK. [Mar 6 11:18:57] DEBUG[20266][C-00000000]: chan_sip.c:10490 process_sdp: Processing media-level (audio) SDP a=maxptime:60... UNSUPPORTED OR FAILED. [Mar 6 11:18:57] DEBUG[20266][C-00000000]: chan_sip.c:10490 process_sdp: Processing media-level (audio) SDP a=ssrc:3844988677 cname:ObXGGnsu+HwTtBZs... UNSUPPORTED OR FAILED. [Mar 6 11:18:57] DEBUG[20266][C-00000000]: chan_sip.c:10490 process_sdp: Processing media-level (audio) SDP a=ssrc:3844988677 msid:nnpFRjEiRai5mgAzrcGHQS9m9mhWeDzvzCj6 0c3b223b-d479-46d9-9e16-8555f36c7fba... UNSUPPORTED OR FAILED. [Mar 6 11:18:57] DEBUG[20266][C-00000000]: chan_sip.c:10490 process_sdp: Processing media-level (audio) SDP a=ssrc:3844988677 mslabel:nnpFRjEiRai5mgAzrcGHQS9m9mhWeDzvzCj6... UNSUPPORTED OR FAILED. [Mar 6 11:18:57] DEBUG[20266][C-00000000]: chan_sip.c:10490 process_sdp: Processing media-level (audio) SDP a=ssrc:3844988677 label:0c3b223b-d479-46d9-9e16-8555f36c7fba... UNSUPPORTED OR FAILED. Capabilities: us - (alaw), peer - audio=(ulaw|alaw)/video=(nothing)/text=(nothing), combined - (alaw) Non-codec capabilities (dtmf): us - 0x1 (telephone-event|), peer - 0x3 (telephone-event|CN|), combined - 0x1 (telephone-event|) [Mar 6 11:18:57] DEBUG[20266][C-00000000]: res_rtp_asterisk.c:4005 ast_rtp_remote_address_set: Setting RTCP address on RTP instance '0x7f7948026cf8' Peer audio RTP is at port 194.150.179.78:53648 [Mar 6 11:18:57] DEBUG[20266][C-00000000]: rtp_engine.c:515 ast_rtp_codecs_payloads_copy: Copying payload 0 from 0x7f7905e0daf0 to 0x7f7948026ec0 [Mar 6 11:18:57] DEBUG[20266][C-00000000]: rtp_engine.c:515 ast_rtp_codecs_payloads_copy: Copying payload 8 from 0x7f7905e0daf0 to 0x7f7948026ec0 [Mar 6 11:18:57] DEBUG[20266][C-00000000]: rtp_engine.c:515 ast_rtp_codecs_payloads_copy: Copying payload 13 from 0x7f7905e0daf0 to 0x7f7948026ec0 [Mar 6 11:18:57] DEBUG[20266][C-00000000]: rtp_engine.c:515 ast_rtp_codecs_payloads_copy: Copying payload 126 from 0x7f7905e0daf0 to 0x7f7948026ec0 [Mar 6 11:18:57] DEBUG[20266][C-00000000]: res_rtp_asterisk.c:3924 ast_rtp_prop_set: Ignoring duplicate RTCP property on RTP instance '0x7f7948026cf8' [Mar 6 11:18:57] DEBUG[20266][C-00000000]: chan_sip.c:10761 process_sdp: We're settling with these formats: (alaw) [Mar 6 11:18:57] DEBUG[20266][C-00000000]: chan_sip.c:25456 handle_request_invite: Checking SIP call limits for device 4343 [Mar 6 11:18:57] DEBUG[20266][C-00000000]: chan_sip.c:6682 update_call_counter: Updating call counter for incoming call [Mar 6 11:18:57] DEBUG[20266][C-00000000]: netsock2.c:138 ast_sockaddr_split_hostport: Splitting 'my.domain.com' into... [Mar 6 11:18:57] DEBUG[20266][C-00000000]: netsock2.c:192 ast_sockaddr_split_hostport: ...host 'my.domain.com' and port ''. [Mar 6 11:18:57] DEBUG[20266][C-00000000]: netsock2.c:138 ast_sockaddr_split_hostport: Splitting 'my.domain.com' into... [Mar 6 11:18:57] DEBUG[20266][C-00000000]: netsock2.c:192 ast_sockaddr_split_hostport: ...host 'my.domain.com' and port ''. Looking for 7 in enigmacallsonly (domain my.domain.com) [Mar 6 11:18:57] DEBUG[20266][C-00000000]: chan_sip.c:7953 sip_new: *** Our native formats are (alaw) [Mar 6 11:18:57] DEBUG[20266][C-00000000]: chan_sip.c:7954 sip_new: *** Joint capabilities are (alaw) [Mar 6 11:18:57] DEBUG[20266][C-00000000]: chan_sip.c:7955 sip_new: *** Our capabilities are (alaw) [Mar 6 11:18:57] DEBUG[20266][C-00000000]: chan_sip.c:7956 sip_new: *** AST_CODEC_CHOOSE formats are alaw [Mar 6 11:18:57] DEBUG[20266][C-00000000]: chan_sip.c:7984 sip_new: This channel will not be able to handle video. [Mar 6 11:18:57] DEBUG[20266][C-00000000]: chan_sip.c:16280 build_route: build_route: Contact hop: "4343";impi=4343;ha1=b00198c97a531d94410593fb8d4a9b44;+g.oma.sip-im;+sip.ice;language="en,fr" list_route: hop: [Mar 6 11:18:57] DEBUG[20266][C-00000000]: chan_sip.c:25768 handle_request_invite: SIP/4343-00000000: New call is still down.... Trying... <--- Transmitting (NAT) to 194.150.179.78:51172 ---> SIP/2.0 100 Trying Via: SIP/2.0/WS df7jal23ls0d.invalid;branch=z9hG4bKD9faEIeJmrAawTplwU3TGC0wg9hT8LCi;received=194.150.179.78;rport=51172 From: "4343";tag=xbCCGl9iQzag4E2lNMcp To: Call-ID: 27d88875-b3bf-1649-0ae0-bf4126e6ba4e CSeq: 22591 INVITE Server: Asterisk PBX 11.8.0 Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH Supported: replaces, timer Contact: Content-Length: 0 <------------> [Mar 6 11:18:57] DEBUG[20266][C-00000000]: chan_sip.c:3876 __sip_xmit: Trying to put 'SIP/2.0 100' onto WS socket destined for 194.150.179.78:51172 [Mar 6 11:18:57] DEBUG[20227]: devicestate.c:344 _ast_device_state: No provider found, checking channel drivers for SIP - 4343 [Mar 6 11:18:57] DEBUG[20227]: chan_sip.c:29655 sip_devicestate: Checking device state for peer 4343 [Mar 6 11:18:57] DEBUG[20227]: devicestate.c:467 do_state_change: Changing state for SIP/4343 - state 1 (Not in use) [Mar 6 11:18:57] DEBUG[20227]: devicestate.c:442 devstate_event: device 'SIP/4343' state '1' [Mar 6 11:18:57] DEBUG[20265]: app_queue.c:1809 handle_statechange: Device 'SIP/4343' changed to state '1' (Not in use) but we don't care because they're not a member of any queue. [Mar 6 11:18:57] DEBUG[20267][C-00000000]: pbx.c:4886 pbx_extension_helper: Launching 'Answer' [Mar 6 11:18:57] DEBUG[20227]: devicestate.c:344 _ast_device_state: No provider found, checking channel drivers for SIP - 4343 [Mar 6 11:18:57] DEBUG[20227]: chan_sip.c:29655 sip_devicestate: Checking device state for peer 4343 [Mar 6 11:18:57] DEBUG[20227]: devicestate.c:467 do_state_change: Changing state for SIP/4343 - state 1 (Not in use) [Mar 6 11:18:57] DEBUG[20227]: devicestate.c:442 devstate_event: device 'SIP/4343' state '1' [Mar 6 11:18:57] DEBUG[20265]: app_queue.c:1809 handle_statechange: Device 'SIP/4343' changed to state '1' (Not in use) but we don't care because they're not a member of any queue. [Mar 6 11:18:57] DEBUG[20267][C-00000000]: chan_sip.c:7279 sip_answer: SIP answering channel: SIP/4343-00000000 [Mar 6 11:18:57] DEBUG[20267][C-00000000]: res_rtp_asterisk.c:2186 ast_rtp_update_source: Setting the marker bit due to a source update [Mar 6 11:18:57] DEBUG[20267][C-00000000]: chan_sip.c:13610 transmit_response_with_sdp: Setting framing from config on incoming call [Mar 6 11:18:57] DEBUG[20267][C-00000000]: chan_sip.c:13165 add_sdp: ** Our capability: (alaw) Video flag: True Text flag: True [Mar 6 11:18:57] DEBUG[20267][C-00000000]: chan_sip.c:13166 add_sdp: ** Our prefcodec: (nothing) Audio is at 21958 [Mar 6 11:18:57] DEBUG[20267][C-00000000]: sip/sdp_crypto.c:310 sdp_crypto_offer: Crypto line: a=crypto:0 AES_CM_128_HMAC_SHA1_32 inline:GFSGvnQ5wKldNu/wYWBZjWasYPoXi4r56sBe3ILs Adding codec 100004 (alaw) to SDP Adding non-codec 0x1 (telephone-event) to SDP [Mar 6 11:18:57] DEBUG[20267][C-00000000]: chan_sip.c:13302 add_sdp: -- Done with adding codecs to SDP [Mar 6 11:18:57] DEBUG[20267][C-00000000]: chan_sip.c:13496 add_sdp: Done building SDP. Settling with this capability: (alaw) <--- Reliably Transmitting (NAT) to 194.150.179.78:51172 ---> SIP/2.0 200 OK Via: SIP/2.0/WS df7jal23ls0d.invalid;branch=z9hG4bKD9faEIeJmrAawTplwU3TGC0wg9hT8LCi;received=194.150.179.78;rport=51172 From: "4343";tag=xbCCGl9iQzag4E2lNMcp To: ;tag=as6deb886a Call-ID: 27d88875-b3bf-1649-0ae0-bf4126e6ba4e CSeq: 22591 INVITE Server: Asterisk PBX 11.8.0 Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH Supported: replaces, timer Contact: Content-Type: application/sdp Content-Length: 321 v=0 o=root 1527622726 1527622726 IN IP4 195.195.195.195 s=Asterisk PBX 11.8.0 c=IN IP4 195.195.195.195 t=0 0 m=audio 21958 RTP/SAVPF 8 101 a=rtpmap:8 PCMA/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=ptime:20 a=sendrecv a=crypto:0 AES_CM_128_HMAC_SHA1_32 inline:GFSGvnQ5wKldNu/wYWBZjWasYPoXi4r56sBe3ILs <------------> [Mar 6 11:18:57] DEBUG[20267][C-00000000]: chan_sip.c:3876 __sip_xmit: Trying to put 'SIP/2.0 200' onto WS socket destined for 194.150.179.78:51172 <--- SIP read from WS:194.150.179.78:51172 ---> ACK sip:7@195.195.195.195;transport=WS SIP/2.0 Via: SIP/2.0/WS df7jal23ls0d.invalid;branch=z9hG4bKcR0AmAxHTTUbzqDwD38x;rport From: "4343";tag=xbCCGl9iQzag4E2lNMcp To: ;tag=as6deb886a Contact: "4343";+g.oma.sip-im;+sip.ice;language="en,fr" Call-ID: 27d88875-b3bf-1649-0ae0-bf4126e6ba4e CSeq: 22591 ACK Content-Length: 0 Max-Forwards: 70 Authorization: Digest username="4343",realm="asterisk",nonce="2839cd8e",uri="sip:7@195.195.195.195;transport=WS",response="165efd4e3791fc951dda813093532387",algorithm=MD5 User-Agent: client v.1.0 Organization: Doubango Telecom <-------------> [Mar 6 11:18:57] DEBUG[20266]: chan_sip.c:9625 parse_request: Header 0 [ 43]: ACK sip:7@195.195.195.195;transport=WS SIP/2.0 [Mar 6 11:18:57] DEBUG[20266]: chan_sip.c:9625 parse_request: Header 1 [ 77]: Via: SIP/2.0/WS df7jal23ls0d.invalid;branch=z9hG4bKcR0AmAxHTTUbzqDwD38x;rport [Mar 6 11:18:57] DEBUG[20266]: chan_sip.c:9625 parse_request: Header 2 [ 60]: From: "4343";tag=xbCCGl9iQzag4E2lNMcp [Mar 6 11:18:57] DEBUG[20266]: chan_sip.c:9625 parse_request: Header 3 [ 39]: To: ;tag=as6deb886a [Mar 6 11:18:57] DEBUG[20266]: chan_sip.c:9625 parse_request: Header 4 [132]: Contact: "4343";+g.oma.sip-im;+sip.ice;language="en,fr" [Mar 6 11:18:57] DEBUG[20266]: chan_sip.c:9625 parse_request: Header 5 [ 45]: Call-ID: 27d88875-b3bf-1649-0ae0-bf4126e6ba4e [Mar 6 11:18:57] DEBUG[20266]: chan_sip.c:9625 parse_request: Header 6 [ 15]: CSeq: 22591 ACK [Mar 6 11:18:57] DEBUG[20266]: chan_sip.c:9625 parse_request: Header 7 [ 17]: Content-Length: 0 [Mar 6 11:18:57] DEBUG[20266]: chan_sip.c:9625 parse_request: Header 8 [ 16]: Max-Forwards: 70 [Mar 6 11:18:57] DEBUG[20266]: chan_sip.c:9625 parse_request: Header 9 [167]: Authorization: Digest username="4343",realm="asterisk",nonce="2839cd8e",uri="sip:7@195.195.195.195;transport=WS",response="165efd4e3791fc951dda813093532387",algorithm=MD5 [Mar 6 11:18:57] DEBUG[20266]: chan_sip.c:9625 parse_request: Header 10 [ 24]: User-Agent: client v.1.0 [Mar 6 11:18:57] DEBUG[20266]: chan_sip.c:9625 parse_request: Header 11 [ 25]: Organization: Doubango Telecom --- (12 headers 0 lines) --- [Mar 6 11:18:57] DEBUG[20266]: chan_sip.c:9175 find_call: = Looking for Call ID: 27d88875-b3bf-1649-0ae0-bf4126e6ba4e (Checking From) --From tag xbCCGl9iQzag4E2lNMcp --To-tag as6deb886a [Mar 6 11:18:57] DEBUG[20266][C-00000000]: chan_sip.c:28199 handle_incoming: **** Received ACK (6) - Command in SIP ACK [Mar 6 11:18:57] DEBUG[20266][C-00000000]: chan_sip.c:4568 __sip_ack: Stopping retransmission on '27d88875-b3bf-1649-0ae0-bf4126e6ba4e' of Response 22591: Match Not Found [Mar 6 11:18:58] DEBUG[20267][C-00000000]: channel.c:2984 __ast_answer: Didn't receive a media frame from SIP/4343-00000000 within 500 ms of answering. Continuing anyway [Mar 6 11:18:58] DEBUG[20267][C-00000000]: pbx.c:4886 pbx_extension_helper: Launching 'Playback' [Mar 6 11:18:58] DEBUG[20267][C-00000000]: res_rtp_asterisk.c:2718 ast_rtp_write: Ooh, format changed from unknown to alaw [Mar 6 11:18:58] DEBUG[20267][C-00000000]: res_rtp_asterisk.c:2753 ast_rtp_write: Created smoother: format: alaw ms: 20 len: 160 [Mar 6 11:18:58] DEBUG[20267][C-00000000]: res_rtp_asterisk.c:2611 ast_rtp_raw_write: Starting RTCP transmission on RTP instance '0x7f7948026cf8' [Mar 6 11:18:58] DEBUG[20267][C-00000000]: channel.c:3577 ast_settimeout: Scheduling timer at (50 requested / 50 actual) timer ticks per second [Mar 6 11:18:59] DEBUG[20267][C-00000000]: channel.c:3577 ast_settimeout: Scheduling timer at (62 requested / 62 actual) timer ticks per second [Mar 6 11:18:59] DEBUG[20267][C-00000000]: channel.c:3577 ast_settimeout: Scheduling timer at (0 requested / 0 actual) timer ticks per second [Mar 6 11:18:59] DEBUG[20267][C-00000000]: channel.c:3577 ast_settimeout: Scheduling timer at (0 requested / 0 actual) timer ticks per second [Mar 6 11:18:59] DEBUG[20267][C-00000000]: channel.c:3577 ast_settimeout: Scheduling timer at (0 requested / 0 actual) timer ticks per second [Mar 6 11:18:59] DEBUG[20267][C-00000000]: pbx.c:4886 pbx_extension_helper: Launching 'MusicOnHold' [Mar 6 11:18:59] DEBUG[20267][C-00000000]: channel.c:3577 ast_settimeout: Scheduling timer at (50 requested / 50 actual) timer ticks per second [Mar 6 11:18:59] DEBUG[20267][C-00000000]: channel.c:5361 set_format: Set channel SIP/4343-00000000 to write format slin [Mar 6 11:18:59] DEBUG[20267][C-00000000]: res_musiconhold.c:355 ast_moh_files_next: SIP/4343-00000000 Opened file 0 '/var/lib/asterisk/moh/macroform-robot_dity' universe*CLI>