[Jan 22 09:10:08] Asterisk 11.7.0 built by root @ vm2233.cloud.seeweb.it on a x86_64 running Linux on 2014-01-22 07:39:42 UTC [Jan 22 09:10:08] DEBUG[14811] config.c: Parsing /etc/asterisk/logger.conf [Jan 22 09:10:08] VERBOSE[14811] config.c: == Parsing '/etc/asterisk/logger.conf': Found [Jan 22 09:10:08] VERBOSE[14811] logger.c: Asterisk Queue Logger restarted [Jan 22 09:10:12] DEBUG[14782] chan_sip.c: Auto destroying SIP dialog '30c5077e1cf185671628283973d4824e@5.144.170.162:5060' [Jan 22 09:10:12] DEBUG[14782] chan_sip.c: Destroying SIP dialog 30c5077e1cf185671628283973d4824e@5.144.170.162:5060 [Jan 22 09:10:12] VERBOSE[14782] chan_sip.c: Really destroying SIP dialog '30c5077e1cf185671628283973d4824e@5.144.170.162:5060' Method: OPTIONS [Jan 22 09:10:17] DEBUG[14782] chan_sip.c: Auto destroying SIP dialog '3008b05800f1ab665846902074f49749@5.144.170.162:5060' [Jan 22 09:10:17] DEBUG[14782] chan_sip.c: Destroying SIP dialog 3008b05800f1ab665846902074f49749@5.144.170.162:5060 [Jan 22 09:10:17] VERBOSE[14782] chan_sip.c: Really destroying SIP dialog '3008b05800f1ab665846902074f49749@5.144.170.162:5060' Method: OPTIONS [Jan 22 09:10:40] VERBOSE[14782] chan_sip.c: <--- SIP read from UDP:5.144.170.162:5060 ---> OPTIONS sip:5.144.163.122 SIP/2.0 Via: SIP/2.0/UDP 5.144.170.162:5060;branch=z9hG4bK4a55ffde;rport Max-Forwards: 70 From: "Unknown" ;tag=as37d69357 To: Contact: Call-ID: 1ef04757213814e60cc895986c1b12d1@5.144.170.162:5060 CSeq: 102 OPTIONS User-Agent: Asterisk 1.8 Date: Wed, 22 Jan 2014 08:10:40 GMT Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH Supported: replaces, timer Content-Length: 0 <-------------> [Jan 22 09:10:40] DEBUG[14782] chan_sip.c: Header 0 [ 33]: OPTIONS sip:5.144.163.122 SIP/2.0 [Jan 22 09:10:40] DEBUG[14782] chan_sip.c: Header 1 [ 64]: Via: SIP/2.0/UDP 5.144.170.162:5060;branch=z9hG4bK4a55ffde;rport [Jan 22 09:10:40] DEBUG[14782] chan_sip.c: Header 2 [ 16]: Max-Forwards: 70 [Jan 22 09:10:40] DEBUG[14782] chan_sip.c: Header 3 [ 58]: From: "Unknown" ;tag=as37d69357 [Jan 22 09:10:40] DEBUG[14782] chan_sip.c: Header 4 [ 23]: To: [Jan 22 09:10:40] DEBUG[14782] chan_sip.c: Header 5 [ 41]: Contact: [Jan 22 09:10:40] DEBUG[14782] chan_sip.c: Header 6 [ 60]: Call-ID: 1ef04757213814e60cc895986c1b12d1@5.144.170.162:5060 [Jan 22 09:10:40] DEBUG[14782] chan_sip.c: Header 7 [ 17]: CSeq: 102 OPTIONS [Jan 22 09:10:40] DEBUG[14782] chan_sip.c: Header 8 [ 24]: User-Agent: Asterisk 1.8 [Jan 22 09:10:40] DEBUG[14782] chan_sip.c: Header 9 [ 35]: Date: Wed, 22 Jan 2014 08:10:40 GMT [Jan 22 09:10:40] DEBUG[14782] chan_sip.c: Header 10 [ 81]: Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH [Jan 22 09:10:40] DEBUG[14782] chan_sip.c: Header 11 [ 26]: Supported: replaces, timer [Jan 22 09:10:40] DEBUG[14782] chan_sip.c: Header 12 [ 17]: Content-Length: 0 [Jan 22 09:10:40] VERBOSE[14782] chan_sip.c: --- (13 headers 0 lines) --- [Jan 22 09:10:40] DEBUG[14782] chan_sip.c: = Looking for Call ID: 1ef04757213814e60cc895986c1b12d1@5.144.170.162:5060 (Checking From) --From tag as37d69357 --To-tag [Jan 22 09:10:40] DEBUG[14782] acl.c: For destination '5.144.170.162', our source address is '5.144.163.122'. [Jan 22 09:10:40] DEBUG[14782] chan_sip.c: Setting SIP_TRANSPORT_UDP with address 5.144.163.122:5060 [Jan 22 09:10:40] DEBUG[14782] netsock2.c: Splitting '5.144.170.162:5060' into... [Jan 22 09:10:40] DEBUG[14782] netsock2.c: ...host '5.144.170.162' and port '5060'. [Jan 22 09:10:40] VERBOSE[14782] chan_sip.c: Sending to 5.144.170.162:5060 (no NAT) [Jan 22 09:10:40] DEBUG[14782] chan_sip.c: Allocating new SIP dialog for 1ef04757213814e60cc895986c1b12d1@5.144.170.162:5060 - OPTIONS (No RTP) [Jan 22 09:10:40] DEBUG[14782] chan_sip.c: **** Received OPTIONS (3) - Command in SIP OPTIONS [Jan 22 09:10:40] DEBUG[14782] netsock2.c: Splitting '5.144.163.122' into... [Jan 22 09:10:40] DEBUG[14782] netsock2.c: ...host '5.144.163.122' and port ''. [Jan 22 09:10:40] DEBUG[14782] netsock2.c: Splitting '5.144.170.162' into... [Jan 22 09:10:40] DEBUG[14782] netsock2.c: ...host '5.144.170.162' and port ''. [Jan 22 09:10:40] VERBOSE[14782] chan_sip.c: Looking for s in from-sip-external (domain 5.144.163.122) [Jan 22 09:10:40] VERBOSE[14782] chan_sip.c: <--- Transmitting (no NAT) to 5.144.170.162:5060 ---> SIP/2.0 404 Not Found Via: SIP/2.0/UDP 5.144.170.162:5060;branch=z9hG4bK4a55ffde;received=5.144.170.162;rport=5060 From: "Unknown" ;tag=as37d69357 To: ;tag=as01fb561b Call-ID: 1ef04757213814e60cc895986c1b12d1@5.144.170.162:5060 CSeq: 102 OPTIONS Server: Asterisk 1.8 Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH Supported: replaces, timer Accept: application/sdp Content-Length: 0 <------------> [Jan 22 09:10:40] DEBUG[14782] chan_sip.c: Trying to put 'SIP/2.0 404' onto UDP socket destined for 5.144.170.162:5060 [Jan 22 09:10:40] VERBOSE[14782] chan_sip.c: Scheduling destruction of SIP dialog '1ef04757213814e60cc895986c1b12d1@5.144.170.162:5060' in 32000 ms (Method: OPTIONS) [Jan 22 09:10:44] VERBOSE[14767] asterisk.c: -- Remote UNIX connection [Jan 22 09:10:44] VERBOSE[14815] asterisk.c: -- Remote UNIX connection disconnected [Jan 22 09:10:44] VERBOSE[14767] asterisk.c: -- Remote UNIX connection [Jan 22 09:10:44] VERBOSE[14817] asterisk.c: -- Remote UNIX connection disconnected [Jan 22 09:10:44] VERBOSE[14767] asterisk.c: -- Remote UNIX connection [Jan 22 09:10:44] DEBUG[14819] app_queue.c: Queue 507 has no realtime members defined. No need for update [Jan 22 09:10:44] DEBUG[14819] devicestate.c: No provider found, checking channel drivers for Agent - 208 [Jan 22 09:10:44] VERBOSE[14819] asterisk.c: -- Remote UNIX connection disconnected [Jan 22 09:10:45] VERBOSE[14782] chan_sip.c: <--- SIP read from UDP:5.144.170.162:5060 ---> OPTIONS sip:5.144.163.122 SIP/2.0 Via: SIP/2.0/UDP 5.144.170.162:5060;branch=z9hG4bK631e24a7;rport Max-Forwards: 70 From: "Unknown" ;tag=as5a54c116 To: Contact: Call-ID: 1940929331d563d243ad762461cfbd66@5.144.170.162:5060 CSeq: 102 OPTIONS User-Agent: Asterisk 1.8 Date: Wed, 22 Jan 2014 08:10:45 GMT Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH Supported: replaces, timer Content-Length: 0 <-------------> [Jan 22 09:10:45] DEBUG[14782] chan_sip.c: Header 0 [ 33]: OPTIONS sip:5.144.163.122 SIP/2.0 [Jan 22 09:10:45] DEBUG[14782] chan_sip.c: Header 1 [ 64]: Via: SIP/2.0/UDP 5.144.170.162:5060;branch=z9hG4bK631e24a7;rport [Jan 22 09:10:45] DEBUG[14782] chan_sip.c: Header 2 [ 16]: Max-Forwards: 70 [Jan 22 09:10:45] DEBUG[14782] chan_sip.c: Header 3 [ 57]: From: "Unknown" ;tag=as5a54c116 [Jan 22 09:10:45] DEBUG[14782] chan_sip.c: Header 4 [ 23]: To: [Jan 22 09:10:45] DEBUG[14782] chan_sip.c: Header 5 [ 40]: Contact: [Jan 22 09:10:45] DEBUG[14782] chan_sip.c: Header 6 [ 60]: Call-ID: 1940929331d563d243ad762461cfbd66@5.144.170.162:5060 [Jan 22 09:10:45] DEBUG[14782] chan_sip.c: Header 7 [ 17]: CSeq: 102 OPTIONS [Jan 22 09:10:45] DEBUG[14782] chan_sip.c: Header 8 [ 24]: User-Agent: Asterisk 1.8 [Jan 22 09:10:45] DEBUG[14782] chan_sip.c: Header 9 [ 35]: Date: Wed, 22 Jan 2014 08:10:45 GMT [Jan 22 09:10:45] DEBUG[14782] chan_sip.c: Header 10 [ 81]: Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH [Jan 22 09:10:45] DEBUG[14782] chan_sip.c: Header 11 [ 26]: Supported: replaces, timer [Jan 22 09:10:45] DEBUG[14782] chan_sip.c: Header 12 [ 17]: Content-Length: 0 [Jan 22 09:10:45] VERBOSE[14782] chan_sip.c: --- (13 headers 0 lines) --- [Jan 22 09:10:45] DEBUG[14782] chan_sip.c: = Looking for Call ID: 1940929331d563d243ad762461cfbd66@5.144.170.162:5060 (Checking From) --From tag as5a54c116 --To-tag [Jan 22 09:10:45] DEBUG[14782] acl.c: For destination '5.144.170.162', our source address is '5.144.163.122'. [Jan 22 09:10:45] DEBUG[14782] chan_sip.c: Setting SIP_TRANSPORT_UDP with address 5.144.163.122:5060 [Jan 22 09:10:45] DEBUG[14782] netsock2.c: Splitting '5.144.170.162:5060' into... [Jan 22 09:10:45] DEBUG[14782] netsock2.c: ...host '5.144.170.162' and port '5060'. [Jan 22 09:10:45] VERBOSE[14782] chan_sip.c: Sending to 5.144.170.162:5060 (no NAT) [Jan 22 09:10:45] DEBUG[14782] chan_sip.c: Allocating new SIP dialog for 1940929331d563d243ad762461cfbd66@5.144.170.162:5060 - OPTIONS (No RTP) [Jan 22 09:10:45] DEBUG[14782] chan_sip.c: **** Received OPTIONS (3) - Command in SIP OPTIONS [Jan 22 09:10:45] DEBUG[14782] netsock2.c: Splitting '5.144.163.122' into... [Jan 22 09:10:45] DEBUG[14782] netsock2.c: ...host '5.144.163.122' and port ''. [Jan 22 09:10:45] DEBUG[14782] netsock2.c: Splitting '5.144.170.162' into... [Jan 22 09:10:45] DEBUG[14782] netsock2.c: ...host '5.144.170.162' and port ''. [Jan 22 09:10:45] VERBOSE[14782] chan_sip.c: Looking for s in from-sip-external (domain 5.144.163.122) [Jan 22 09:10:45] VERBOSE[14782] chan_sip.c: <--- Transmitting (no NAT) to 5.144.170.162:5060 ---> SIP/2.0 404 Not Found Via: SIP/2.0/UDP 5.144.170.162:5060;branch=z9hG4bK631e24a7;received=5.144.170.162;rport=5060 From: "Unknown" ;tag=as5a54c116 To: ;tag=as124fd951 Call-ID: 1940929331d563d243ad762461cfbd66@5.144.170.162:5060 CSeq: 102 OPTIONS Server: Asterisk 1.8 Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH Supported: replaces, timer Accept: application/sdp Content-Length: 0 <------------> [Jan 22 09:10:45] DEBUG[14782] chan_sip.c: Trying to put 'SIP/2.0 404' onto UDP socket destined for 5.144.170.162:5060 [Jan 22 09:10:45] VERBOSE[14782] chan_sip.c: Scheduling destruction of SIP dialog '1940929331d563d243ad762461cfbd66@5.144.170.162:5060' in 32000 ms (Method: OPTIONS) [Jan 22 09:10:46] DEBUG[14820] http.c: HTTP Request URI is /ws [Jan 22 09:10:46] DEBUG[14820] http.c: match request [ws] with handler [httpstatus] len 0 [Jan 22 09:10:46] DEBUG[14820] http.c: match request [ws] with handler [phoneprov] len 10 [Jan 22 09:10:46] DEBUG[14820] http.c: match request [ws] with handler [static] len 9 [Jan 22 09:10:46] DEBUG[14820] http.c: match request [ws] with handler [ws] len 6 [Jan 22 09:10:46] VERBOSE[14820] res_http_websocket.c: == WebSocket connection from '79.45.181.142:35219' for protocol 'sip' accepted using version '13' [Jan 22 09:10:46] VERBOSE[14820] chan_sip.c: <--- SIP read from WS:79.45.181.142:35219 ---> REGISTER sip:vm2233.cloud.seeweb.it SIP/2.0 Via: SIP/2.0/WS t9dsk06quc33.invalid;branch=z9hG4bK4805102 Max-Forwards: 69 To: From: ;tag=1dfct204jt Call-ID: o0tq8jcr86318rar8pb2ff CSeq: 81 REGISTER Contact: ;reg-id=1;+sip.instance="";expires=600 Allow: ACK,CANCEL,BYE,OPTIONS,INVITE,MESSAGE Supported: path, outbound, gruu User-Agent: JsSIP 0.3.0 Content-Length: 0 <-------------> [Jan 22 09:10:46] DEBUG[14820] chan_sip.c: Header 0 [ 43]: REGISTER sip:vm2233.cloud.seeweb.it SIP/2.0 [Jan 22 09:10:46] DEBUG[14820] chan_sip.c: Header 1 [ 58]: Via: SIP/2.0/WS t9dsk06quc33.invalid;branch=z9hG4bK4805102 [Jan 22 09:10:46] DEBUG[14820] chan_sip.c: Header 2 [ 16]: Max-Forwards: 69 [Jan 22 09:10:46] DEBUG[14820] chan_sip.c: Header 3 [ 36]: To: [Jan 22 09:10:46] DEBUG[14820] chan_sip.c: Header 4 [ 53]: From: ;tag=1dfct204jt [Jan 22 09:10:46] DEBUG[14820] chan_sip.c: Header 5 [ 31]: Call-ID: o0tq8jcr86318rar8pb2ff [Jan 22 09:10:46] DEBUG[14820] chan_sip.c: Header 6 [ 17]: CSeq: 81 REGISTER [Jan 22 09:10:46] DEBUG[14820] chan_sip.c: Header 7 [142]: Contact: ;reg-id=1;+sip.instance="";expires=600 [Jan 22 09:10:46] DEBUG[14820] chan_sip.c: Header 8 [ 44]: Allow: ACK,CANCEL,BYE,OPTIONS,INVITE,MESSAGE [Jan 22 09:10:46] DEBUG[14820] chan_sip.c: Header 9 [ 31]: Supported: path, outbound, gruu [Jan 22 09:10:46] DEBUG[14820] chan_sip.c: Header 10 [ 23]: User-Agent: JsSIP 0.3.0 [Jan 22 09:10:46] DEBUG[14820] chan_sip.c: Header 11 [ 17]: Content-Length: 0 [Jan 22 09:10:46] VERBOSE[14820] chan_sip.c: --- (12 headers 0 lines) --- [Jan 22 09:10:46] DEBUG[14820] chan_sip.c: = Looking for Call ID: o0tq8jcr86318rar8pb2ff (Checking From) --From tag 1dfct204jt --To-tag [Jan 22 09:10:46] DEBUG[14820] acl.c: For destination '79.45.181.142', our source address is '5.144.163.122'. [Jan 22 09:10:46] DEBUG[14820] chan_sip.c: Setting SIP_TRANSPORT_WS with address 5.144.163.122:5060 [Jan 22 09:10:46] DEBUG[14820] chan_sip.c: Allocating new SIP dialog for o0tq8jcr86318rar8pb2ff - REGISTER (No RTP) [Jan 22 09:10:46] DEBUG[14820] chan_sip.c: **** Received REGISTER (2) - Command in SIP REGISTER [Jan 22 09:10:46] DEBUG[14820] chan_sip.c: Initializing initreq for method REGISTER - callid o0tq8jcr86318rar8pb2ff [Jan 22 09:10:46] DEBUG[14820] netsock2.c: Splitting 'vm2233.cloud.seeweb.it' into... [Jan 22 09:10:46] DEBUG[14820] netsock2.c: ...host 'vm2233.cloud.seeweb.it' and port ''. [Jan 22 09:10:46] VERBOSE[14820] chan_sip.c: <--- Transmitting (NAT) to 79.45.181.142:35219 ---> SIP/2.0 401 Unauthorized Via: SIP/2.0/WS t9dsk06quc33.invalid;branch=z9hG4bK4805102;received=79.45.181.142;rport=35219 From: ;tag=1dfct204jt To: ;tag=as108d097d Call-ID: o0tq8jcr86318rar8pb2ff CSeq: 81 REGISTER Server: Asterisk 1.8 Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH Supported: replaces, timer WWW-Authenticate: Digest algorithm=MD5, realm="asterisk", nonce="225a8955" Content-Length: 0 <------------> [Jan 22 09:10:46] DEBUG[14820] chan_sip.c: Trying to put 'SIP/2.0 401' onto WS socket destined for 79.45.181.142:35219 [Jan 22 09:10:46] VERBOSE[14820] chan_sip.c: Scheduling destruction of SIP dialog 'o0tq8jcr86318rar8pb2ff' in 32000 ms (Method: REGISTER) [Jan 22 09:10:46] VERBOSE[14820] chan_sip.c: <--- SIP read from WS:79.45.181.142:35219 ---> REGISTER sip:vm2233.cloud.seeweb.it SIP/2.0 Via: SIP/2.0/WS t9dsk06quc33.invalid;branch=z9hG4bK5349734 Max-Forwards: 69 To: From: ;tag=1dfct204jt Call-ID: o0tq8jcr86318rar8pb2ff CSeq: 82 REGISTER Authorization: Digest algorithm=MD5, username="208", realm="asterisk", nonce="225a8955", uri="sip:vm2233.cloud.seeweb.it", response="4e4527c3c85fe8abf40390828822d0f8" Contact: ;reg-id=1;+sip.instance="";expires=600 Allow: ACK,CANCEL,BYE,OPTIONS,INVITE,MESSAGE Supported: path, outbound, gruu User-Agent: JsSIP 0.3.0 Content-Length: 0 <-------------> [Jan 22 09:10:46] DEBUG[14820] chan_sip.c: Header 0 [ 43]: REGISTER sip:vm2233.cloud.seeweb.it SIP/2.0 [Jan 22 09:10:46] DEBUG[14820] chan_sip.c: Header 1 [ 58]: Via: SIP/2.0/WS t9dsk06quc33.invalid;branch=z9hG4bK5349734 [Jan 22 09:10:46] DEBUG[14820] chan_sip.c: Header 2 [ 16]: Max-Forwards: 69 [Jan 22 09:10:46] DEBUG[14820] chan_sip.c: Header 3 [ 36]: To: [Jan 22 09:10:46] DEBUG[14820] chan_sip.c: Header 4 [ 53]: From: ;tag=1dfct204jt [Jan 22 09:10:46] DEBUG[14820] chan_sip.c: Header 5 [ 31]: Call-ID: o0tq8jcr86318rar8pb2ff [Jan 22 09:10:46] DEBUG[14820] chan_sip.c: Header 6 [ 17]: CSeq: 82 REGISTER [Jan 22 09:10:46] DEBUG[14820] chan_sip.c: Header 7 [166]: Authorization: Digest algorithm=MD5, username="208", realm="asterisk", nonce="225a8955", uri="sip:vm2233.cloud.seeweb.it", response="4e4527c3c85fe8abf40390828822d0f8" [Jan 22 09:10:46] DEBUG[14820] chan_sip.c: Header 8 [142]: Contact: ;reg-id=1;+sip.instance="";expires=600 [Jan 22 09:10:46] DEBUG[14820] chan_sip.c: Header 9 [ 44]: Allow: ACK,CANCEL,BYE,OPTIONS,INVITE,MESSAGE [Jan 22 09:10:46] DEBUG[14820] chan_sip.c: Header 10 [ 31]: Supported: path, outbound, gruu [Jan 22 09:10:46] DEBUG[14820] chan_sip.c: Header 11 [ 23]: User-Agent: JsSIP 0.3.0 [Jan 22 09:10:46] DEBUG[14820] chan_sip.c: Header 12 [ 17]: Content-Length: 0 [Jan 22 09:10:46] VERBOSE[14820] chan_sip.c: --- (13 headers 0 lines) --- [Jan 22 09:10:46] DEBUG[14820] chan_sip.c: = Looking for Call ID: o0tq8jcr86318rar8pb2ff (Checking From) --From tag 1dfct204jt --To-tag [Jan 22 09:10:46] DEBUG[14820] netsock2.c: Splitting 'vm2233.cloud.seeweb.it' into... [Jan 22 09:10:46] DEBUG[14820] netsock2.c: ...host 'vm2233.cloud.seeweb.it' and port ''. [Jan 22 09:10:46] DEBUG[14820] netsock2.c: Splitting 'vm2233.cloud.seeweb.it' into... [Jan 22 09:10:46] DEBUG[14820] netsock2.c: ...host 'vm2233.cloud.seeweb.it' and port ''. [Jan 22 09:10:46] DEBUG[14820] chan_sip.c: **** Received REGISTER (2) - Command in SIP REGISTER [Jan 22 09:10:46] DEBUG[14820] chan_sip.c: Initializing initreq for method REGISTER - callid o0tq8jcr86318rar8pb2ff [Jan 22 09:10:46] DEBUG[14820] netsock2.c: Splitting 'vm2233.cloud.seeweb.it' into... [Jan 22 09:10:46] DEBUG[14820] netsock2.c: ...host 'vm2233.cloud.seeweb.it' and port ''. [Jan 22 09:10:46] DEBUG[14820] chan_sip.c: Store REGISTER's src-IP:port for call routing. [Jan 22 09:10:46] VERBOSE[14820] chan_sip.c: -- Registered SIP '208' at 79.45.181.142:35219 [Jan 22 09:10:46] DEBUG[14820] chan_sip.c: Allocating new SIP dialog for 50eb49de109beb940ada72e93aee099f@5.144.163.122:5060 - OPTIONS (No RTP) [Jan 22 09:10:46] DEBUG[14820] acl.c: For destination '79.45.181.142', our source address is '5.144.163.122'. [Jan 22 09:10:46] DEBUG[14820] chan_sip.c: Setting SIP_TRANSPORT_WS with address 5.144.163.122:5060 [Jan 22 09:10:46] DEBUG[14820] chan_sip.c: SIP call-id changed from '50eb49de109beb940ada72e93aee099f@5.144.163.122:5060' to '731b1e106db4fcab3970edd61f4988c8@5.144.163.122:5060' [Jan 22 09:10:46] DEBUG[14820] chan_sip.c: Initializing initreq for method OPTIONS - callid 731b1e106db4fcab3970edd61f4988c8@5.144.163.122:5060 [Jan 22 09:10:46] DEBUG[14820] chan_sip.c: Header 0 [ 62]: OPTIONS sip:8j6gcuk9@t9dsk06quc33.invalid;transport=ws SIP/2.0 [Jan 22 09:10:46] DEBUG[14820] chan_sip.c: Header 1 [ 63]: Via: SIP/2.0/WS 5.144.163.122:5060;branch=z9hG4bK46c1f76e;rport [Jan 22 09:10:46] DEBUG[14820] chan_sip.c: Header 2 [ 16]: Max-Forwards: 70 [Jan 22 09:10:46] DEBUG[14820] chan_sip.c: Header 3 [ 58]: From: "Unknown" ;tag=as1ab78d23 [Jan 22 09:10:46] DEBUG[14820] chan_sip.c: Header 4 [ 52]: To: [Jan 22 09:10:46] DEBUG[14820] chan_sip.c: Header 5 [ 54]: Contact: [Jan 22 09:10:46] DEBUG[14820] chan_sip.c: Header 6 [ 60]: Call-ID: 731b1e106db4fcab3970edd61f4988c8@5.144.163.122:5060 [Jan 22 09:10:46] DEBUG[14820] chan_sip.c: Header 7 [ 17]: CSeq: 102 OPTIONS [Jan 22 09:10:46] DEBUG[14820] chan_sip.c: Header 8 [ 24]: User-Agent: Asterisk 1.8 [Jan 22 09:10:46] DEBUG[14820] chan_sip.c: Header 9 [ 35]: Date: Wed, 22 Jan 2014 08:10:46 GMT [Jan 22 09:10:46] DEBUG[14820] chan_sip.c: Header 10 [ 81]: Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH [Jan 22 09:10:46] DEBUG[14820] chan_sip.c: Header 11 [ 26]: Supported: replaces, timer [Jan 22 09:10:46] VERBOSE[14820] chan_sip.c: Reliably Transmitting (NAT) to 79.45.181.142:35219: OPTIONS sip:8j6gcuk9@t9dsk06quc33.invalid;transport=ws SIP/2.0 Via: SIP/2.0/WS 5.144.163.122:5060;branch=z9hG4bK46c1f76e;rport Max-Forwards: 70 From: "Unknown" ;tag=as1ab78d23 To: Contact: Call-ID: 731b1e106db4fcab3970edd61f4988c8@5.144.163.122:5060 CSeq: 102 OPTIONS User-Agent: Asterisk 1.8 Date: Wed, 22 Jan 2014 08:10:46 GMT Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH Supported: replaces, timer Content-Length: 0 --- [Jan 22 09:10:46] DEBUG[14820] chan_sip.c: Trying to put 'OPTIONS sip' onto WS socket destined for 79.45.181.142:35219 [Jan 22 09:10:46] VERBOSE[14820] chan_sip.c: <--- Transmitting (NAT) to 79.45.181.142:35219 ---> SIP/2.0 200 OK Via: SIP/2.0/WS t9dsk06quc33.invalid;branch=z9hG4bK5349734;received=79.45.181.142;rport=35219 From: ;tag=1dfct204jt To: ;tag=as108d097d Call-ID: o0tq8jcr86318rar8pb2ff CSeq: 82 REGISTER Server: Asterisk 1.8 Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH Supported: replaces, timer Expires: 600 Contact: ;expires=600 Date: Wed, 22 Jan 2014 08:10:46 GMT Content-Length: 0 <------------> [Jan 22 09:10:46] DEBUG[14820] chan_sip.c: Trying to put 'SIP/2.0 200' onto WS socket destined for 79.45.181.142:35219 [Jan 22 09:10:46] VERBOSE[14820] chan_sip.c: Scheduling destruction of SIP dialog 'o0tq8jcr86318rar8pb2ff' in 32000 ms (Method: REGISTER) [Jan 22 09:10:46] DEBUG[14773] devicestate.c: No provider found, checking channel drivers for SIP - 208 [Jan 22 09:10:46] DEBUG[14773] chan_sip.c: Checking device state for peer 208 [Jan 22 09:10:46] DEBUG[14773] devicestate.c: Changing state for SIP/208 - state 1 (Not in use) [Jan 22 09:10:46] DEBUG[14773] devicestate.c: device 'SIP/208' state '1' [Jan 22 09:10:46] DEBUG[14808] app_queue.c: Device 'SIP/208' changed to state '1' (Not in use) but we don't care because they're not a member of any queue. [Jan 22 09:10:46] VERBOSE[14820] chan_sip.c: <--- SIP read from WS:79.45.181.142:35219 ---> SIP/2.0 200 OK Via: SIP/2.0/WS 5.144.163.122:5060;branch=z9hG4bK46c1f76e;rport To: ;tag=3j96p9ls2b From: "Unknown" ;tag=as1ab78d23 Call-ID: 731b1e106db4fcab3970edd61f4988c8@5.144.163.122:5060 CSeq: 102 OPTIONS Allow: ACK,CANCEL,BYE,OPTIONS,INVITE,MESSAGE Accept: application/sdp,application/dtmf-relay Content-Length: 0 <-------------> [Jan 22 09:10:46] DEBUG[14820] chan_sip.c: Header 0 [ 14]: SIP/2.0 200 OK [Jan 22 09:10:46] DEBUG[14820] chan_sip.c: Header 1 [ 63]: Via: SIP/2.0/WS 5.144.163.122:5060;branch=z9hG4bK46c1f76e;rport [Jan 22 09:10:46] DEBUG[14820] chan_sip.c: Header 2 [ 67]: To: ;tag=3j96p9ls2b [Jan 22 09:10:46] DEBUG[14820] chan_sip.c: Header 3 [ 58]: From: "Unknown" ;tag=as1ab78d23 [Jan 22 09:10:46] DEBUG[14820] chan_sip.c: Header 4 [ 60]: Call-ID: 731b1e106db4fcab3970edd61f4988c8@5.144.163.122:5060 [Jan 22 09:10:46] DEBUG[14820] chan_sip.c: Header 5 [ 17]: CSeq: 102 OPTIONS [Jan 22 09:10:46] DEBUG[14820] chan_sip.c: Header 6 [ 44]: Allow: ACK,CANCEL,BYE,OPTIONS,INVITE,MESSAGE [Jan 22 09:10:46] DEBUG[14820] chan_sip.c: Header 7 [ 46]: Accept: application/sdp,application/dtmf-relay [Jan 22 09:10:46] DEBUG[14820] chan_sip.c: Header 8 [ 17]: Content-Length: 0 [Jan 22 09:10:46] VERBOSE[14820] chan_sip.c: --- (9 headers 0 lines) --- [Jan 22 09:10:46] DEBUG[14820] chan_sip.c: = Looking for Call ID: 731b1e106db4fcab3970edd61f4988c8@5.144.163.122:5060 (Checking To) --From tag as1ab78d23 --To-tag 3j96p9ls2b [Jan 22 09:10:46] NOTICE[14820] chan_sip.c: Peer '208' is now Reachable. (120ms / 2000ms) [Jan 22 09:10:46] DEBUG[14773] devicestate.c: No provider found, checking channel drivers for SIP - 208 [Jan 22 09:10:46] DEBUG[14773] chan_sip.c: Checking device state for peer 208 [Jan 22 09:10:46] DEBUG[14773] devicestate.c: Changing state for SIP/208 - state 1 (Not in use) [Jan 22 09:10:46] DEBUG[14773] devicestate.c: device 'SIP/208' state '1' [Jan 22 09:10:46] DEBUG[14808] app_queue.c: Device 'SIP/208' changed to state '1' (Not in use) but we don't care because they're not a member of any queue. [Jan 22 09:10:46] DEBUG[14782] chan_sip.c: Destroying SIP dialog 731b1e106db4fcab3970edd61f4988c8@5.144.163.122:5060 [Jan 22 09:10:46] VERBOSE[14782] chan_sip.c: Really destroying SIP dialog '731b1e106db4fcab3970edd61f4988c8@5.144.163.122:5060' Method: OPTIONS [Jan 22 09:10:58] VERBOSE[14820] chan_sip.c: <--- SIP read from WS:79.45.181.142:35219 ---> INVITE sip:*43@vm2233.cloud.seeweb.it SIP/2.0 Via: SIP/2.0/WS t9dsk06quc33.invalid;branch=z9hG4bK8110683 Max-Forwards: 69 To: From: ;tag=sat1k7pbps Call-ID: 35gb3h0ak3fnri36uj54 CSeq: 2262 INVITE X-Foo: foo X-Bar: bar Contact: Allow: ACK,CANCEL,BYE,OPTIONS,INVITE,MESSAGE Content-Type: application/sdp Supported: path, outbound, gruu User-Agent: JsSIP 0.3.0 Content-Length: 1720 v=0 o=- 3978281942725680505 2 IN IP4 127.0.0.1 s=- t=0 0 a=group:BUNDLE audio a=msid-semantic: WMS PaJcqDfI2Yfzas02ZHP3XEsmTz2GsTMPCZAk m=audio 41871 RTP/SAVPF 111 103 104 0 8 106 105 13 126 c=IN IP4 192.168.1.16 a=rtcp:41871 IN IP4 192.168.1.16 a=candidate:3596738868 1 udp 2113937151 192.168.1.16 41871 typ host generation 0 a=candidate:3596738868 2 udp 2113937151 192.168.1.16 41871 typ host generation 0 a=candidate:4242042849 1 udp 2113937151 192.168.153.1 53549 typ host generation 0 a=candidate:4242042849 2 udp 2113937151 192.168.153.1 53549 typ host generation 0 a=candidate:830702851 1 udp 2113937151 172.16.71.1 44048 typ host generation 0 a=candidate:830702851 2 udp 2113937151 172.16.71.1 44048 typ host generation 0 a=ice-ufrag:25k/NW2BgovzIeiw a=ice-pwd:s6QRIz7UsycDvXE+S4pE1Wnz a=ice-options:google-ice a=fingerprint:sha-1 5E:EB:B0:0E:AC:BC:DE:60:5D:8E:21:4B:06:D4:17:4A:F3:35:FE:46 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:tJ7eMMKHe56ZElgUKHz3Joe+UZqSH8tPbBX4G+fZ a=crypto:1 AES_CM_128_HMAC_SHA1_80 inline:N0VLE1SWDHFnglGEYJtvyc4sn7yslfQ3suOUWaYE 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:828613202 cname:BFDAYq6rRJK4dFS4 a=ssrc:828613202 msid:PaJcqDfI2Yfzas02ZHP3XEsmTz2GsTMPCZAk 4d84d77f-ea36-4f9d-8f83-f50708d2a2af a=ssrc:828613202 mslabel:PaJcqDfI2Yfzas02ZHP3XEsmTz2GsTMPCZAk a=ssrc:828613202 label:4d84d77f-ea36-4f9d-8f83-f50708d2a2af <-------------> [Jan 22 09:10:58] DEBUG[14820] chan_sip.c: Header 0 [ 45]: INVITE sip:*43@vm2233.cloud.seeweb.it SIP/2.0 [Jan 22 09:10:58] DEBUG[14820] chan_sip.c: Header 1 [ 58]: Via: SIP/2.0/WS t9dsk06quc33.invalid;branch=z9hG4bK8110683 [Jan 22 09:10:58] DEBUG[14820] chan_sip.c: Header 2 [ 16]: Max-Forwards: 69 [Jan 22 09:10:58] DEBUG[14820] chan_sip.c: Header 3 [ 36]: To: [Jan 22 09:10:58] DEBUG[14820] chan_sip.c: Header 4 [ 53]: From: ;tag=sat1k7pbps [Jan 22 09:10:58] DEBUG[14820] chan_sip.c: Header 5 [ 29]: Call-ID: 35gb3h0ak3fnri36uj54 [Jan 22 09:10:58] DEBUG[14820] chan_sip.c: Header 6 [ 17]: CSeq: 2262 INVITE [Jan 22 09:10:58] DEBUG[14820] chan_sip.c: Header 7 [ 10]: X-Foo: foo [Jan 22 09:10:58] DEBUG[14820] chan_sip.c: Header 8 [ 10]: X-Bar: bar [Jan 22 09:10:58] DEBUG[14820] chan_sip.c: Header 9 [ 60]: Contact: [Jan 22 09:10:58] DEBUG[14820] chan_sip.c: Header 10 [ 44]: Allow: ACK,CANCEL,BYE,OPTIONS,INVITE,MESSAGE [Jan 22 09:10:58] DEBUG[14820] chan_sip.c: Header 11 [ 29]: Content-Type: application/sdp [Jan 22 09:10:58] DEBUG[14820] chan_sip.c: Header 12 [ 31]: Supported: path, outbound, gruu [Jan 22 09:10:58] DEBUG[14820] chan_sip.c: Header 13 [ 23]: User-Agent: JsSIP 0.3.0 [Jan 22 09:10:58] DEBUG[14820] chan_sip.c: Header 14 [ 20]: Content-Length: 1720 [Jan 22 09:10:58] DEBUG[14820] chan_sip.c: Header 15 [ 0]: [Jan 22 09:10:58] DEBUG[14820] chan_sip.c: Body 0 [ 3]: v=0 [Jan 22 09:10:58] DEBUG[14820] chan_sip.c: Body 1 [ 42]: o=- 3978281942725680505 2 IN IP4 127.0.0.1 [Jan 22 09:10:58] DEBUG[14820] chan_sip.c: Body 2 [ 3]: s=- [Jan 22 09:10:58] DEBUG[14820] chan_sip.c: Body 3 [ 5]: t=0 0 [Jan 22 09:10:58] DEBUG[14820] chan_sip.c: Body 4 [ 20]: a=group:BUNDLE audio [Jan 22 09:10:58] DEBUG[14820] chan_sip.c: Body 5 [ 57]: a=msid-semantic: WMS PaJcqDfI2Yfzas02ZHP3XEsmTz2GsTMPCZAk [Jan 22 09:10:58] DEBUG[14820] chan_sip.c: Body 6 [ 54]: m=audio 41871 RTP/SAVPF 111 103 104 0 8 106 105 13 126 [Jan 22 09:10:58] DEBUG[14820] chan_sip.c: Body 7 [ 21]: c=IN IP4 192.168.1.16 [Jan 22 09:10:58] DEBUG[14820] chan_sip.c: Body 8 [ 32]: a=rtcp:41871 IN IP4 192.168.1.16 [Jan 22 09:10:58] DEBUG[14820] chan_sip.c: Body 9 [ 80]: a=candidate:3596738868 1 udp 2113937151 192.168.1.16 41871 typ host generation 0 [Jan 22 09:10:58] DEBUG[14820] chan_sip.c: Body 10 [ 80]: a=candidate:3596738868 2 udp 2113937151 192.168.1.16 41871 typ host generation 0 [Jan 22 09:10:58] DEBUG[14820] chan_sip.c: Body 11 [ 81]: a=candidate:4242042849 1 udp 2113937151 192.168.153.1 53549 typ host generation 0 [Jan 22 09:10:58] DEBUG[14820] chan_sip.c: Body 12 [ 81]: a=candidate:4242042849 2 udp 2113937151 192.168.153.1 53549 typ host generation 0 [Jan 22 09:10:58] DEBUG[14820] chan_sip.c: Body 13 [ 78]: a=candidate:830702851 1 udp 2113937151 172.16.71.1 44048 typ host generation 0 [Jan 22 09:10:58] DEBUG[14820] chan_sip.c: Body 14 [ 78]: a=candidate:830702851 2 udp 2113937151 172.16.71.1 44048 typ host generation 0 [Jan 22 09:10:58] DEBUG[14820] chan_sip.c: Body 15 [ 28]: a=ice-ufrag:25k/NW2BgovzIeiw [Jan 22 09:10:58] DEBUG[14820] chan_sip.c: Body 16 [ 34]: a=ice-pwd:s6QRIz7UsycDvXE+S4pE1Wnz [Jan 22 09:10:58] DEBUG[14820] chan_sip.c: Body 17 [ 24]: a=ice-options:google-ice [Jan 22 09:10:58] DEBUG[14820] chan_sip.c: Body 18 [ 79]: a=fingerprint:sha-1 5E:EB:B0:0E:AC:BC:DE:60:5D:8E:21:4B:06:D4:17:4A:F3:35:FE:46 [Jan 22 09:10:58] DEBUG[14820] chan_sip.c: Body 19 [ 15]: a=setup:actpass [Jan 22 09:10:58] DEBUG[14820] chan_sip.c: Body 20 [ 11]: a=mid:audio [Jan 22 09:10:58] DEBUG[14820] chan_sip.c: Body 21 [ 54]: a=extmap:1 urn:ietf:params:rtp-hdrext:ssrc-audio-level [Jan 22 09:10:58] DEBUG[14820] chan_sip.c: Body 22 [ 10]: a=sendrecv [Jan 22 09:10:58] DEBUG[14820] chan_sip.c: Body 23 [ 10]: a=rtcp-mux [Jan 22 09:10:58] DEBUG[14820] chan_sip.c: Body 24 [ 82]: a=crypto:0 AES_CM_128_HMAC_SHA1_32 inline:tJ7eMMKHe56ZElgUKHz3Joe+UZqSH8tPbBX4G+fZ [Jan 22 09:10:58] DEBUG[14820] chan_sip.c: Body 25 [ 82]: a=crypto:1 AES_CM_128_HMAC_SHA1_80 inline:N0VLE1SWDHFnglGEYJtvyc4sn7yslfQ3suOUWaYE [Jan 22 09:10:58] DEBUG[14820] chan_sip.c: Body 26 [ 25]: a=rtpmap:111 opus/48000/2 [Jan 22 09:10:58] DEBUG[14820] chan_sip.c: Body 27 [ 22]: a=fmtp:111 minptime=10 [Jan 22 09:10:58] DEBUG[14820] chan_sip.c: Body 28 [ 23]: a=rtpmap:103 ISAC/16000 [Jan 22 09:10:58] DEBUG[14820] chan_sip.c: Body 29 [ 23]: a=rtpmap:104 ISAC/32000 [Jan 22 09:10:58] DEBUG[14820] chan_sip.c: Body 30 [ 20]: a=rtpmap:0 PCMU/8000 [Jan 22 09:10:58] DEBUG[14820] chan_sip.c: Body 31 [ 20]: a=rtpmap:8 PCMA/8000 [Jan 22 09:10:58] DEBUG[14820] chan_sip.c: Body 32 [ 21]: a=rtpmap:106 CN/32000 [Jan 22 09:10:58] DEBUG[14820] chan_sip.c: Body 33 [ 21]: a=rtpmap:105 CN/16000 [Jan 22 09:10:58] DEBUG[14820] chan_sip.c: Body 34 [ 19]: a=rtpmap:13 CN/8000 [Jan 22 09:10:58] DEBUG[14820] chan_sip.c: Body 35 [ 33]: a=rtpmap:126 telephone-event/8000 [Jan 22 09:10:58] DEBUG[14820] chan_sip.c: Body 36 [ 13]: a=maxptime:60 [Jan 22 09:10:58] DEBUG[14820] chan_sip.c: Body 37 [ 39]: a=ssrc:828613202 cname:BFDAYq6rRJK4dFS4 [Jan 22 09:10:58] DEBUG[14820] chan_sip.c: Body 38 [ 95]: a=ssrc:828613202 msid:PaJcqDfI2Yfzas02ZHP3XEsmTz2GsTMPCZAk 4d84d77f-ea36-4f9d-8f83-f50708d2a2af [Jan 22 09:10:58] DEBUG[14820] chan_sip.c: Body 39 [ 61]: a=ssrc:828613202 mslabel:PaJcqDfI2Yfzas02ZHP3XEsmTz2GsTMPCZAk [Jan 22 09:10:58] DEBUG[14820] chan_sip.c: Body 40 [ 59]: a=ssrc:828613202 label:4d84d77f-ea36-4f9d-8f83-f50708d2a2af [Jan 22 09:10:58] VERBOSE[14820] chan_sip.c: --- (15 headers 41 lines) --- [Jan 22 09:10:58] DEBUG[14820] chan_sip.c: = Looking for Call ID: 35gb3h0ak3fnri36uj54 (Checking From) --From tag sat1k7pbps --To-tag [Jan 22 09:10:58] DEBUG[14820] acl.c: For destination '79.45.181.142', our source address is '5.144.163.122'. [Jan 22 09:10:58] DEBUG[14820] chan_sip.c: Setting SIP_TRANSPORT_WS with address 5.144.163.122:5060 [Jan 22 09:10:58] DEBUG[14820] chan_sip.c: Allocating new SIP dialog for 35gb3h0ak3fnri36uj54 - INVITE (No RTP) [Jan 22 09:10:58] DEBUG[14820][C-00000000] chan_sip.c: **** Received INVITE (5) - Command in SIP INVITE [Jan 22 09:10:58] DEBUG[14820][C-00000000] sip/reqresp_parser.c: Begin: parsing SIP "Supported: path, outbound, gruu" [Jan 22 09:10:58] DEBUG[14820][C-00000000] sip/reqresp_parser.c: Found SIP option: -path- [Jan 22 09:10:58] DEBUG[14820][C-00000000] sip/reqresp_parser.c: Matched SIP option: path [Jan 22 09:10:58] DEBUG[14820][C-00000000] sip/reqresp_parser.c: Found SIP option: -outbound- [Jan 22 09:10:58] DEBUG[14820][C-00000000] sip/reqresp_parser.c: Matched SIP option: outbound [Jan 22 09:10:58] DEBUG[14820][C-00000000] sip/reqresp_parser.c: Found SIP option: -gruu- [Jan 22 09:10:58] DEBUG[14820][C-00000000] sip/reqresp_parser.c: Matched SIP option: gruu [Jan 22 09:10:58] DEBUG[14820][C-00000000] chan_sip.c: Initializing initreq for method INVITE - callid 35gb3h0ak3fnri36uj54 [Jan 22 09:10:58] VERBOSE[14820][C-00000000] chan_sip.c: Using INVITE request as basis request - 35gb3h0ak3fnri36uj54 [Jan 22 09:10:58] DEBUG[14820][C-00000000] netsock2.c: Splitting 'vm2233.cloud.seeweb.it' into... [Jan 22 09:10:58] DEBUG[14820][C-00000000] netsock2.c: ...host 'vm2233.cloud.seeweb.it' and port ''. [Jan 22 09:10:58] VERBOSE[14820][C-00000000] chan_sip.c: Found peer '208' for '208' from 79.45.181.142:35219 [Jan 22 09:10:58] VERBOSE[14820][C-00000000] chan_sip.c: <--- Reliably Transmitting (NAT) to 79.45.181.142:35219 ---> SIP/2.0 401 Unauthorized Via: SIP/2.0/WS t9dsk06quc33.invalid;branch=z9hG4bK8110683;received=79.45.181.142;rport=35219 From: ;tag=sat1k7pbps To: ;tag=as545800e2 Call-ID: 35gb3h0ak3fnri36uj54 CSeq: 2262 INVITE Server: Asterisk 1.8 Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH Supported: replaces, timer WWW-Authenticate: Digest algorithm=MD5, realm="asterisk", nonce="6a813491" Content-Length: 0 <------------> [Jan 22 09:10:58] DEBUG[14820][C-00000000] chan_sip.c: Trying to put 'SIP/2.0 401' onto WS socket destined for 79.45.181.142:35219 [Jan 22 09:10:58] VERBOSE[14820][C-00000000] chan_sip.c: Scheduling destruction of SIP dialog '35gb3h0ak3fnri36uj54' in 7680 ms (Method: INVITE) [Jan 22 09:10:58] VERBOSE[14820] chan_sip.c: <--- SIP read from WS:79.45.181.142:35219 ---> ACK sip:*43@vm2233.cloud.seeweb.it SIP/2.0 Via: SIP/2.0/WS t9dsk06quc33.invalid;branch=z9hG4bK8110683 To: ;tag=as545800e2 From: ;tag=sat1k7pbps Call-ID: 35gb3h0ak3fnri36uj54 CSeq: 2262 ACK <-------------> [Jan 22 09:10:58] DEBUG[14820] chan_sip.c: Header 0 [ 42]: ACK sip:*43@vm2233.cloud.seeweb.it SIP/2.0 [Jan 22 09:10:58] DEBUG[14820] chan_sip.c: Header 1 [ 58]: Via: SIP/2.0/WS t9dsk06quc33.invalid;branch=z9hG4bK8110683 [Jan 22 09:10:58] DEBUG[14820] chan_sip.c: Header 2 [ 51]: To: ;tag=as545800e2 [Jan 22 09:10:58] DEBUG[14820] chan_sip.c: Header 3 [ 53]: From: ;tag=sat1k7pbps [Jan 22 09:10:58] DEBUG[14820] chan_sip.c: Header 4 [ 29]: Call-ID: 35gb3h0ak3fnri36uj54 [Jan 22 09:10:58] DEBUG[14820] chan_sip.c: Header 5 [ 14]: CSeq: 2262 ACK [Jan 22 09:10:58] VERBOSE[14820] chan_sip.c: --- (6 headers 0 lines) --- [Jan 22 09:10:58] DEBUG[14820] chan_sip.c: = Looking for Call ID: 35gb3h0ak3fnri36uj54 (Checking From) --From tag sat1k7pbps --To-tag as545800e2 [Jan 22 09:10:58] DEBUG[14820][C-00000000] chan_sip.c: **** Received ACK (6) - Command in SIP ACK [Jan 22 09:10:58] DEBUG[14820][C-00000000] chan_sip.c: Stopping retransmission on '35gb3h0ak3fnri36uj54' of Response 2262: Match Not Found [Jan 22 09:10:58] VERBOSE[14820] chan_sip.c: <--- SIP read from WS:79.45.181.142:35219 ---> INVITE sip:*43@vm2233.cloud.seeweb.it SIP/2.0 Via: SIP/2.0/WS t9dsk06quc33.invalid;branch=z9hG4bK9318747 Max-Forwards: 69 To: From: ;tag=sat1k7pbps Call-ID: 35gb3h0ak3fnri36uj54 CSeq: 2263 INVITE Authorization: Digest algorithm=MD5, username="208", realm="asterisk", nonce="6a813491", uri="sip:*43@vm2233.cloud.seeweb.it", response="92f30c3292f9488c0b9c6c33e3cc9269" X-Foo: foo X-Bar: bar Contact: Allow: ACK,CANCEL,BYE,OPTIONS,INVITE,MESSAGE Content-Type: application/sdp Supported: path, outbound, gruu User-Agent: JsSIP 0.3.0 Content-Length: 1720 v=0 o=- 3978281942725680505 2 IN IP4 127.0.0.1 s=- t=0 0 a=group:BUNDLE audio a=msid-semantic: WMS PaJcqDfI2Yfzas02ZHP3XEsmTz2GsTMPCZAk m=audio 41871 RTP/SAVPF 111 103 104 0 8 106 105 13 126 c=IN IP4 192.168.1.16 a=rtcp:41871 IN IP4 192.168.1.16 a=candidate:3596738868 1 udp 2113937151 192.168.1.16 41871 typ host generation 0 a=candidate:3596738868 2 udp 2113937151 192.168.1.16 41871 typ host generation 0 a=candidate:4242042849 1 udp 2113937151 192.168.153.1 53549 typ host generation 0 a=candidate:4242042849 2 udp 2113937151 192.168.153.1 53549 typ host generation 0 a=candidate:830702851 1 udp 2113937151 172.16.71.1 44048 typ host generation 0 a=candidate:830702851 2 udp 2113937151 172.16.71.1 44048 typ host generation 0 a=ice-ufrag:25k/NW2BgovzIeiw a=ice-pwd:s6QRIz7UsycDvXE+S4pE1Wnz a=ice-options:google-ice a=fingerprint:sha-1 5E:EB:B0:0E:AC:BC:DE:60:5D:8E:21:4B:06:D4:17:4A:F3:35:FE:46 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:tJ7eMMKHe56ZElgUKHz3Joe+UZqSH8tPbBX4G+fZ a=crypto:1 AES_CM_128_HMAC_SHA1_80 inline:N0VLE1SWDHFnglGEYJtvyc4sn7yslfQ3suOUWaYE 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:828613202 cname:BFDAYq6rRJK4dFS4 a=ssrc:828613202 msid:PaJcqDfI2Yfzas02ZHP3XEsmTz2GsTMPCZAk 4d84d77f-ea36-4f9d-8f83-f50708d2a2af a=ssrc:828613202 mslabel:PaJcqDfI2Yfzas02ZHP3XEsmTz2GsTMPCZAk a=ssrc:828613202 label:4d84d77f-ea36-4f9d-8f83-f50708d2a2af <-------------> [Jan 22 09:10:58] DEBUG[14820] chan_sip.c: Header 0 [ 45]: INVITE sip:*43@vm2233.cloud.seeweb.it SIP/2.0 [Jan 22 09:10:58] DEBUG[14820] chan_sip.c: Header 1 [ 58]: Via: SIP/2.0/WS t9dsk06quc33.invalid;branch=z9hG4bK9318747 [Jan 22 09:10:58] DEBUG[14820] chan_sip.c: Header 2 [ 16]: Max-Forwards: 69 [Jan 22 09:10:58] DEBUG[14820] chan_sip.c: Header 3 [ 36]: To: [Jan 22 09:10:58] DEBUG[14820] chan_sip.c: Header 4 [ 53]: From: ;tag=sat1k7pbps [Jan 22 09:10:58] DEBUG[14820] chan_sip.c: Header 5 [ 29]: Call-ID: 35gb3h0ak3fnri36uj54 [Jan 22 09:10:58] DEBUG[14820] chan_sip.c: Header 6 [ 17]: CSeq: 2263 INVITE [Jan 22 09:10:58] DEBUG[14820] chan_sip.c: Header 7 [170]: Authorization: Digest algorithm=MD5, username="208", realm="asterisk", nonce="6a813491", uri="sip:*43@vm2233.cloud.seeweb.it", response="92f30c3292f9488c0b9c6c33e3cc9269" [Jan 22 09:10:58] DEBUG[14820] chan_sip.c: Header 8 [ 10]: X-Foo: foo [Jan 22 09:10:58] DEBUG[14820] chan_sip.c: Header 9 [ 10]: X-Bar: bar [Jan 22 09:10:58] DEBUG[14820] chan_sip.c: Header 10 [ 60]: Contact: [Jan 22 09:10:58] DEBUG[14820] chan_sip.c: Header 11 [ 44]: Allow: ACK,CANCEL,BYE,OPTIONS,INVITE,MESSAGE [Jan 22 09:10:58] DEBUG[14820] chan_sip.c: Header 12 [ 29]: Content-Type: application/sdp [Jan 22 09:10:58] DEBUG[14820] chan_sip.c: Header 13 [ 31]: Supported: path, outbound, gruu [Jan 22 09:10:58] DEBUG[14820] chan_sip.c: Header 14 [ 23]: User-Agent: JsSIP 0.3.0 [Jan 22 09:10:58] DEBUG[14820] chan_sip.c: Header 15 [ 20]: Content-Length: 1720 [Jan 22 09:10:58] DEBUG[14820] chan_sip.c: Header 16 [ 0]: [Jan 22 09:10:58] DEBUG[14820] chan_sip.c: Body 0 [ 3]: v=0 [Jan 22 09:10:58] DEBUG[14820] chan_sip.c: Body 1 [ 42]: o=- 3978281942725680505 2 IN IP4 127.0.0.1 [Jan 22 09:10:58] DEBUG[14820] chan_sip.c: Body 2 [ 3]: s=- [Jan 22 09:10:58] DEBUG[14820] chan_sip.c: Body 3 [ 5]: t=0 0 [Jan 22 09:10:58] DEBUG[14820] chan_sip.c: Body 4 [ 20]: a=group:BUNDLE audio [Jan 22 09:10:58] DEBUG[14820] chan_sip.c: Body 5 [ 57]: a=msid-semantic: WMS PaJcqDfI2Yfzas02ZHP3XEsmTz2GsTMPCZAk [Jan 22 09:10:58] DEBUG[14820] chan_sip.c: Body 6 [ 54]: m=audio 41871 RTP/SAVPF 111 103 104 0 8 106 105 13 126 [Jan 22 09:10:58] DEBUG[14820] chan_sip.c: Body 7 [ 21]: c=IN IP4 192.168.1.16 [Jan 22 09:10:58] DEBUG[14820] chan_sip.c: Body 8 [ 32]: a=rtcp:41871 IN IP4 192.168.1.16 [Jan 22 09:10:58] DEBUG[14820] chan_sip.c: Body 9 [ 80]: a=candidate:3596738868 1 udp 2113937151 192.168.1.16 41871 typ host generation 0 [Jan 22 09:10:58] DEBUG[14820] chan_sip.c: Body 10 [ 80]: a=candidate:3596738868 2 udp 2113937151 192.168.1.16 41871 typ host generation 0 [Jan 22 09:10:58] DEBUG[14820] chan_sip.c: Body 11 [ 81]: a=candidate:4242042849 1 udp 2113937151 192.168.153.1 53549 typ host generation 0 [Jan 22 09:10:58] DEBUG[14820] chan_sip.c: Body 12 [ 81]: a=candidate:4242042849 2 udp 2113937151 192.168.153.1 53549 typ host generation 0 [Jan 22 09:10:58] DEBUG[14820] chan_sip.c: Body 13 [ 78]: a=candidate:830702851 1 udp 2113937151 172.16.71.1 44048 typ host generation 0 [Jan 22 09:10:58] DEBUG[14820] chan_sip.c: Body 14 [ 78]: a=candidate:830702851 2 udp 2113937151 172.16.71.1 44048 typ host generation 0 [Jan 22 09:10:58] DEBUG[14820] chan_sip.c: Body 15 [ 28]: a=ice-ufrag:25k/NW2BgovzIeiw [Jan 22 09:10:58] DEBUG[14820] chan_sip.c: Body 16 [ 34]: a=ice-pwd:s6QRIz7UsycDvXE+S4pE1Wnz [Jan 22 09:10:58] DEBUG[14820] chan_sip.c: Body 17 [ 24]: a=ice-options:google-ice [Jan 22 09:10:58] DEBUG[14820] chan_sip.c: Body 18 [ 79]: a=fingerprint:sha-1 5E:EB:B0:0E:AC:BC:DE:60:5D:8E:21:4B:06:D4:17:4A:F3:35:FE:46 [Jan 22 09:10:58] DEBUG[14820] chan_sip.c: Body 19 [ 15]: a=setup:actpass [Jan 22 09:10:58] DEBUG[14820] chan_sip.c: Body 20 [ 11]: a=mid:audio [Jan 22 09:10:58] DEBUG[14820] chan_sip.c: Body 21 [ 54]: a=extmap:1 urn:ietf:params:rtp-hdrext:ssrc-audio-level [Jan 22 09:10:58] DEBUG[14820] chan_sip.c: Body 22 [ 10]: a=sendrecv [Jan 22 09:10:58] DEBUG[14820] chan_sip.c: Body 23 [ 10]: a=rtcp-mux [Jan 22 09:10:58] DEBUG[14820] chan_sip.c: Body 24 [ 82]: a=crypto:0 AES_CM_128_HMAC_SHA1_32 inline:tJ7eMMKHe56ZElgUKHz3Joe+UZqSH8tPbBX4G+fZ [Jan 22 09:10:58] DEBUG[14820] chan_sip.c: Body 25 [ 82]: a=crypto:1 AES_CM_128_HMAC_SHA1_80 inline:N0VLE1SWDHFnglGEYJtvyc4sn7yslfQ3suOUWaYE [Jan 22 09:10:58] DEBUG[14820] chan_sip.c: Body 26 [ 25]: a=rtpmap:111 opus/48000/2 [Jan 22 09:10:58] DEBUG[14820] chan_sip.c: Body 27 [ 22]: a=fmtp:111 minptime=10 [Jan 22 09:10:58] DEBUG[14820] chan_sip.c: Body 28 [ 23]: a=rtpmap:103 ISAC/16000 [Jan 22 09:10:58] DEBUG[14820] chan_sip.c: Body 29 [ 23]: a=rtpmap:104 ISAC/32000 [Jan 22 09:10:58] DEBUG[14820] chan_sip.c: Body 30 [ 20]: a=rtpmap:0 PCMU/8000 [Jan 22 09:10:58] DEBUG[14820] chan_sip.c: Body 31 [ 20]: a=rtpmap:8 PCMA/8000 [Jan 22 09:10:58] DEBUG[14820] chan_sip.c: Body 32 [ 21]: a=rtpmap:106 CN/32000 [Jan 22 09:10:58] DEBUG[14820] chan_sip.c: Body 33 [ 21]: a=rtpmap:105 CN/16000 [Jan 22 09:10:58] DEBUG[14820] chan_sip.c: Body 34 [ 19]: a=rtpmap:13 CN/8000 [Jan 22 09:10:58] DEBUG[14820] chan_sip.c: Body 35 [ 33]: a=rtpmap:126 telephone-event/8000 [Jan 22 09:10:58] DEBUG[14820] chan_sip.c: Body 36 [ 13]: a=maxptime:60 [Jan 22 09:10:58] DEBUG[14820] chan_sip.c: Body 37 [ 39]: a=ssrc:828613202 cname:BFDAYq6rRJK4dFS4 [Jan 22 09:10:58] DEBUG[14820] chan_sip.c: Body 38 [ 95]: a=ssrc:828613202 msid:PaJcqDfI2Yfzas02ZHP3XEsmTz2GsTMPCZAk 4d84d77f-ea36-4f9d-8f83-f50708d2a2af [Jan 22 09:10:58] DEBUG[14820] chan_sip.c: Body 39 [ 61]: a=ssrc:828613202 mslabel:PaJcqDfI2Yfzas02ZHP3XEsmTz2GsTMPCZAk [Jan 22 09:10:58] DEBUG[14820] chan_sip.c: Body 40 [ 59]: a=ssrc:828613202 label:4d84d77f-ea36-4f9d-8f83-f50708d2a2af [Jan 22 09:10:58] VERBOSE[14820] chan_sip.c: --- (16 headers 41 lines) --- [Jan 22 09:10:58] DEBUG[14820] chan_sip.c: = Looking for Call ID: 35gb3h0ak3fnri36uj54 (Checking From) --From tag sat1k7pbps --To-tag [Jan 22 09:10:58] DEBUG[14820] netsock2.c: Splitting 'vm2233.cloud.seeweb.it' into... [Jan 22 09:10:58] DEBUG[14820] netsock2.c: ...host 'vm2233.cloud.seeweb.it' and port ''. [Jan 22 09:10:58] DEBUG[14820] netsock2.c: Splitting 'vm2233.cloud.seeweb.it' into... [Jan 22 09:10:58] DEBUG[14820] netsock2.c: ...host 'vm2233.cloud.seeweb.it' and port ''. [Jan 22 09:10:58] DEBUG[14820][C-00000000] chan_sip.c: **** Received INVITE (5) - Command in SIP INVITE [Jan 22 09:10:58] DEBUG[14820][C-00000000] chan_sip.c: Initializing initreq for method INVITE - callid 35gb3h0ak3fnri36uj54 [Jan 22 09:10:58] VERBOSE[14820][C-00000000] chan_sip.c: Using INVITE request as basis request - 35gb3h0ak3fnri36uj54 [Jan 22 09:10:58] DEBUG[14820][C-00000000] netsock2.c: Splitting 'vm2233.cloud.seeweb.it' into... [Jan 22 09:10:58] DEBUG[14820][C-00000000] netsock2.c: ...host 'vm2233.cloud.seeweb.it' and port ''. [Jan 22 09:10:58] VERBOSE[14820][C-00000000] chan_sip.c: Found peer '208' for '208' from 79.45.181.142:35219 [Jan 22 09:10:58] DEBUG[14820][C-00000000] rtp_engine.c: Using engine 'asterisk' for RTP instance '0x7f6ea00324b8' [Jan 22 09:10:58] DEBUG[14820][C-00000000] res_rtp_asterisk.c: Allocated port 18480 for RTP instance '0x7f6ea00324b8' [Jan 22 09:10:58] DEBUG[14820][C-00000000] rtp_engine.c: RTP instance '0x7f6ea00324b8' is setup and ready to go [Jan 22 09:10:58] DEBUG[14820][C-00000000] res_rtp_asterisk.c: Setup RTCP on RTP instance '0x7f6ea00324b8' [Jan 22 09:10:58] VERBOSE[14820][C-00000000] netsock2.c: == Using SIP RTP TOS bits 184 [Jan 22 09:10:58] VERBOSE[14820][C-00000000] netsock2.c: == Using SIP RTP CoS mark 5 [Jan 22 09:10:58] DEBUG[14820][C-00000000] chan_sip.c: Setting NAT on RTP to On [Jan 22 09:10:58] DEBUG[14820][C-00000000] chan_sip.c: Processing session-level SDP v=0... UNSUPPORTED OR FAILED. [Jan 22 09:10:58] DEBUG[14820][C-00000000] chan_sip.c: Processing session-level SDP o=- 3978281942725680505 2 IN IP4 127.0.0.1... OK. [Jan 22 09:10:58] DEBUG[14820][C-00000000] chan_sip.c: Processing session-level SDP s=-... UNSUPPORTED OR FAILED. [Jan 22 09:10:58] DEBUG[14820][C-00000000] chan_sip.c: Processing session-level SDP t=0 0... UNSUPPORTED OR FAILED. [Jan 22 09:10:58] DEBUG[14820][C-00000000] chan_sip.c: Processing session-level SDP a=group:BUNDLE audio... UNSUPPORTED OR FAILED. [Jan 22 09:10:58] DEBUG[14820][C-00000000] chan_sip.c: Processing session-level SDP a=msid-semantic: WMS PaJcqDfI2Yfzas02ZHP3XEsmTz2GsTMPCZAk... UNSUPPORTED OR FAILED. [Jan 22 09:10:58] VERBOSE[14820][C-00000000] chan_sip.c: Found RTP audio format 111 [Jan 22 09:10:58] DEBUG[14820][C-00000000] rtp_engine.c: Setting payload 111 based on m type on 0x7f6e4caf8d60 [Jan 22 09:10:58] VERBOSE[14820][C-00000000] chan_sip.c: Found RTP audio format 103 [Jan 22 09:10:58] DEBUG[14820][C-00000000] rtp_engine.c: Setting payload 103 based on m type on 0x7f6e4caf8d60 [Jan 22 09:10:58] VERBOSE[14820][C-00000000] chan_sip.c: Found RTP audio format 104 [Jan 22 09:10:58] DEBUG[14820][C-00000000] rtp_engine.c: Setting payload 104 based on m type on 0x7f6e4caf8d60 [Jan 22 09:10:58] VERBOSE[14820][C-00000000] chan_sip.c: Found RTP audio format 0 [Jan 22 09:10:58] DEBUG[14820][C-00000000] rtp_engine.c: Setting payload 0 based on m type on 0x7f6e4caf8d60 [Jan 22 09:10:58] VERBOSE[14820][C-00000000] chan_sip.c: Found RTP audio format 8 [Jan 22 09:10:58] DEBUG[14820][C-00000000] rtp_engine.c: Setting payload 8 based on m type on 0x7f6e4caf8d60 [Jan 22 09:10:58] VERBOSE[14820][C-00000000] chan_sip.c: Found RTP audio format 106 [Jan 22 09:10:58] DEBUG[14820][C-00000000] rtp_engine.c: Setting payload 106 based on m type on 0x7f6e4caf8d60 [Jan 22 09:10:58] VERBOSE[14820][C-00000000] chan_sip.c: Found RTP audio format 105 [Jan 22 09:10:58] DEBUG[14820][C-00000000] rtp_engine.c: Setting payload 105 based on m type on 0x7f6e4caf8d60 [Jan 22 09:10:58] VERBOSE[14820][C-00000000] chan_sip.c: Found RTP audio format 13 [Jan 22 09:10:58] DEBUG[14820][C-00000000] rtp_engine.c: Setting payload 13 based on m type on 0x7f6e4caf8d60 [Jan 22 09:10:58] VERBOSE[14820][C-00000000] chan_sip.c: Found RTP audio format 126 [Jan 22 09:10:58] DEBUG[14820][C-00000000] rtp_engine.c: Setting payload 126 based on m type on 0x7f6e4caf8d60 [Jan 22 09:10:58] DEBUG[14820][C-00000000] netsock2.c: Splitting '192.168.1.16' into... [Jan 22 09:10:58] DEBUG[14820][C-00000000] netsock2.c: ...host '192.168.1.16' and port ''. [Jan 22 09:10:58] DEBUG[14820][C-00000000] chan_sip.c: Processing media-level (audio) SDP c=IN IP4 192.168.1.16... OK. [Jan 22 09:10:58] DEBUG[14820][C-00000000] chan_sip.c: Processing media-level (audio) SDP a=rtcp:41871 IN IP4 192.168.1.16... UNSUPPORTED OR FAILED. [Jan 22 09:10:58] DEBUG[14820][C-00000000] chan_sip.c: Processing media-level (audio) SDP a=candidate:3596738868 1 udp 2113937151 192.168.1.16 41871 typ host generation 0... UNSUPPORTED OR FAILED. [Jan 22 09:10:58] DEBUG[14820][C-00000000] chan_sip.c: Processing media-level (audio) SDP a=candidate:3596738868 2 udp 2113937151 192.168.1.16 41871 typ host generation 0... UNSUPPORTED OR FAILED. [Jan 22 09:10:58] DEBUG[14820][C-00000000] chan_sip.c: Processing media-level (audio) SDP a=candidate:4242042849 1 udp 2113937151 192.168.153.1 53549 typ host generation 0... UNSUPPORTED OR FAILED. [Jan 22 09:10:58] DEBUG[14820][C-00000000] chan_sip.c: Processing media-level (audio) SDP a=candidate:4242042849 2 udp 2113937151 192.168.153.1 53549 typ host generation 0... UNSUPPORTED OR FAILED. [Jan 22 09:10:58] DEBUG[14820][C-00000000] chan_sip.c: Processing media-level (audio) SDP a=candidate:830702851 1 udp 2113937151 172.16.71.1 44048 typ host generation 0... UNSUPPORTED OR FAILED. [Jan 22 09:10:58] DEBUG[14820][C-00000000] chan_sip.c: Processing media-level (audio) SDP a=candidate:830702851 2 udp 2113937151 172.16.71.1 44048 typ host generation 0... UNSUPPORTED OR FAILED. [Jan 22 09:10:58] DEBUG[14820][C-00000000] chan_sip.c: Processing media-level (audio) SDP a=ice-ufrag:25k/NW2BgovzIeiw... UNSUPPORTED OR FAILED. [Jan 22 09:10:58] DEBUG[14820][C-00000000] chan_sip.c: Processing media-level (audio) SDP a=ice-pwd:s6QRIz7UsycDvXE+S4pE1Wnz... UNSUPPORTED OR FAILED. [Jan 22 09:10:58] DEBUG[14820][C-00000000] chan_sip.c: Processing media-level (audio) SDP a=ice-options:google-ice... UNSUPPORTED OR FAILED. [Jan 22 09:10:58] DEBUG[14820][C-00000000] chan_sip.c: Processing media-level (audio) SDP a=fingerprint:sha-1 5E:EB:B0:0E:AC:BC:DE:60:5D:8E:21:4B:06:D4:17:4A:F3:35:FE:46... UNSUPPORTED OR FAILED. [Jan 22 09:10:58] DEBUG[14820][C-00000000] chan_sip.c: Processing media-level (audio) SDP a=setup:actpass... UNSUPPORTED OR FAILED. [Jan 22 09:10:58] DEBUG[14820][C-00000000] chan_sip.c: Processing media-level (audio) SDP a=mid:audio... UNSUPPORTED OR FAILED. [Jan 22 09:10:58] DEBUG[14820][C-00000000] chan_sip.c: Processing media-level (audio) SDP a=extmap:1 urn:ietf:params:rtp-hdrext:ssrc-audio-level... UNSUPPORTED OR FAILED. [Jan 22 09:10:58] DEBUG[14820][C-00000000] chan_sip.c: Processing media-level (audio) SDP a=sendrecv... OK. [Jan 22 09:10:58] DEBUG[14820][C-00000000] chan_sip.c: Processing media-level (audio) SDP a=rtcp-mux... UNSUPPORTED OR FAILED. [Jan 22 09:10:58] DEBUG[14820][C-00000000] sip/sdp_crypto.c: local_key64 rXRAamVXi02Phu/WMOcS1V9Px0PNf32Hk+OhTbhb len 40 [Jan 22 09:10:58] DEBUG[14820][C-00000000] res_srtp.c: Adding new policy for SSRC 1358383381 [Jan 22 09:10:58] DEBUG[14820][C-00000000] sip/sdp_crypto.c: SRTP policy activated [Jan 22 09:10:58] DEBUG[14820][C-00000000] sip/sdp_crypto.c: Accepting crypto tag 0 [Jan 22 09:10:58] DEBUG[14820][C-00000000] sip/sdp_crypto.c: Crypto line: a=crypto:0 AES_CM_128_HMAC_SHA1_32 inline:rXRAamVXi02Phu/WMOcS1V9Px0PNf32Hk+OhTbhb [Jan 22 09:10:58] DEBUG[14820][C-00000000] chan_sip.c: Processing media-level (audio) SDP a=crypto:0 AES_CM_128_HMAC_SHA1_32 inline:tJ7eMMKHe56ZElgUKHz3Joe+UZqSH8tPbBX4G+fZ... OK. [Jan 22 09:10:58] DEBUG[14820][C-00000000] chan_sip.c: Processing media-level (audio) SDP a=crypto:1 AES_CM_128_HMAC_SHA1_80 inline:N0VLE1SWDHFnglGEYJtvyc4sn7yslfQ3suOUWaYE... UNSUPPORTED OR FAILED. [Jan 22 09:10:58] VERBOSE[14820][C-00000000] chan_sip.c: Found audio description format opus for ID 111 [Jan 22 09:10:58] DEBUG[14820][C-00000000] chan_sip.c: Processing media-level (audio) SDP a=rtpmap:111 opus/48000/2... OK. [Jan 22 09:10:58] WARNING[14820][C-00000000] chan_sip.c: Got Opus minptime=10 [Jan 22 09:10:58] DEBUG[14820][C-00000000] chan_sip.c: Processing media-level (audio) SDP a=fmtp:111 minptime=10... OK. [Jan 22 09:10:58] DEBUG[14820][C-00000000] rtp_engine.c: Unsetting payload 103 on 0x7f6e4caf8d60 [Jan 22 09:10:58] VERBOSE[14820][C-00000000] chan_sip.c: Found unknown media description format ISAC for ID 103 [Jan 22 09:10:58] DEBUG[14820][C-00000000] chan_sip.c: Processing media-level (audio) SDP a=rtpmap:103 ISAC/16000... UNSUPPORTED OR FAILED. [Jan 22 09:10:58] DEBUG[14820][C-00000000] rtp_engine.c: Unsetting payload 104 on 0x7f6e4caf8d60 [Jan 22 09:10:58] VERBOSE[14820][C-00000000] chan_sip.c: Found unknown media description format ISAC for ID 104 [Jan 22 09:10:58] DEBUG[14820][C-00000000] chan_sip.c: Processing media-level (audio) SDP a=rtpmap:104 ISAC/32000... UNSUPPORTED OR FAILED. [Jan 22 09:10:58] VERBOSE[14820][C-00000000] chan_sip.c: Found audio description format PCMU for ID 0 [Jan 22 09:10:58] DEBUG[14820][C-00000000] chan_sip.c: Processing media-level (audio) SDP a=rtpmap:0 PCMU/8000... OK. [Jan 22 09:10:58] VERBOSE[14820][C-00000000] chan_sip.c: Found audio description format PCMA for ID 8 [Jan 22 09:10:58] DEBUG[14820][C-00000000] chan_sip.c: Processing media-level (audio) SDP a=rtpmap:8 PCMA/8000... OK. [Jan 22 09:10:58] DEBUG[14820][C-00000000] rtp_engine.c: Unsetting payload 106 on 0x7f6e4caf8d60 [Jan 22 09:10:58] VERBOSE[14820][C-00000000] chan_sip.c: Found unknown media description format CN for ID 106 [Jan 22 09:10:58] DEBUG[14820][C-00000000] chan_sip.c: Processing media-level (audio) SDP a=rtpmap:106 CN/32000... UNSUPPORTED OR FAILED. [Jan 22 09:10:58] DEBUG[14820][C-00000000] rtp_engine.c: Unsetting payload 105 on 0x7f6e4caf8d60 [Jan 22 09:10:58] VERBOSE[14820][C-00000000] chan_sip.c: Found unknown media description format CN for ID 105 [Jan 22 09:10:58] DEBUG[14820][C-00000000] chan_sip.c: Processing media-level (audio) SDP a=rtpmap:105 CN/16000... UNSUPPORTED OR FAILED. [Jan 22 09:10:58] VERBOSE[14820][C-00000000] chan_sip.c: Found audio description format CN for ID 13 [Jan 22 09:10:58] DEBUG[14820][C-00000000] chan_sip.c: Processing media-level (audio) SDP a=rtpmap:13 CN/8000... OK. [Jan 22 09:10:58] VERBOSE[14820][C-00000000] chan_sip.c: Found audio description format telephone-event for ID 126 [Jan 22 09:10:58] DEBUG[14820][C-00000000] chan_sip.c: Processing media-level (audio) SDP a=rtpmap:126 telephone-event/8000... OK. [Jan 22 09:10:58] DEBUG[14820][C-00000000] chan_sip.c: Processing media-level (audio) SDP a=maxptime:60... UNSUPPORTED OR FAILED. [Jan 22 09:10:58] DEBUG[14820][C-00000000] chan_sip.c: Processing media-level (audio) SDP a=ssrc:828613202 cname:BFDAYq6rRJK4dFS4... UNSUPPORTED OR FAILED. [Jan 22 09:10:58] DEBUG[14820][C-00000000] chan_sip.c: Processing media-level (audio) SDP a=ssrc:828613202 msid:PaJcqDfI2Yfzas02ZHP3XEsmTz2GsTMPCZAk 4d84d77f-ea36-4f9d-8f83-f50708d2a2af... UNSUPPORTED OR FAILED. [Jan 22 09:10:58] DEBUG[14820][C-00000000] chan_sip.c: Processing media-level (audio) SDP a=ssrc:828613202 mslabel:PaJcqDfI2Yfzas02ZHP3XEsmTz2GsTMPCZAk... UNSUPPORTED OR FAILED. [Jan 22 09:10:58] DEBUG[14820][C-00000000] chan_sip.c: Processing media-level (audio) SDP a=ssrc:828613202 label:4d84d77f-ea36-4f9d-8f83-f50708d2a2af... UNSUPPORTED OR FAILED. [Jan 22 09:10:58] VERBOSE[14820][C-00000000] chan_sip.c: Capabilities: us - (opus), peer - audio=(ulaw|alaw|opus)/video=(nothing)/text=(nothing), combined - (opus) [Jan 22 09:10:58] VERBOSE[14820][C-00000000] chan_sip.c: Non-codec capabilities (dtmf): us - 0x1 (telephone-event|), peer - 0x3 (telephone-event|CN|), combined - 0x1 (telephone-event|) [Jan 22 09:10:58] DEBUG[14820][C-00000000] res_rtp_asterisk.c: Setting RTCP address on RTP instance '0x7f6ea00324b8' [Jan 22 09:10:58] VERBOSE[14820][C-00000000] chan_sip.c: Peer audio RTP is at port 192.168.1.16:41871 [Jan 22 09:10:58] DEBUG[14820][C-00000000] rtp_engine.c: Copying payload 0 from 0x7f6e4caf8d60 to 0x7f6ea0032680 [Jan 22 09:10:58] DEBUG[14820][C-00000000] rtp_engine.c: Copying payload 8 from 0x7f6e4caf8d60 to 0x7f6ea0032680 [Jan 22 09:10:58] DEBUG[14820][C-00000000] rtp_engine.c: Copying payload 13 from 0x7f6e4caf8d60 to 0x7f6ea0032680 [Jan 22 09:10:58] DEBUG[14820][C-00000000] rtp_engine.c: Copying payload 111 from 0x7f6e4caf8d60 to 0x7f6ea0032680 [Jan 22 09:10:58] DEBUG[14820][C-00000000] rtp_engine.c: Copying payload 126 from 0x7f6e4caf8d60 to 0x7f6ea0032680 [Jan 22 09:10:58] DEBUG[14820][C-00000000] res_rtp_asterisk.c: Ignoring duplicate RTCP property on RTP instance '0x7f6ea00324b8' [Jan 22 09:10:58] DEBUG[14820][C-00000000] chan_sip.c: We're settling with these formats: (opus) [Jan 22 09:10:58] DEBUG[14820][C-00000000] chan_sip.c: Checking SIP call limits for device 8j6gcuk9 [Jan 22 09:10:58] DEBUG[14820][C-00000000] chan_sip.c: Updating call counter for incoming call [Jan 22 09:10:58] DEBUG[14820][C-00000000] chan_sip.c: Call from peer '208' is 1 out of 50 [Jan 22 09:10:58] DEBUG[14773] devicestate.c: No provider found, checking channel drivers for SIP - 208 [Jan 22 09:10:58] DEBUG[14773] chan_sip.c: Checking device state for peer 208 [Jan 22 09:10:58] DEBUG[14773] devicestate.c: Changing state for SIP/208 - state 2 (In use) [Jan 22 09:10:58] DEBUG[14773] devicestate.c: device 'SIP/208' state '2' [Jan 22 09:10:58] DEBUG[14808] app_queue.c: Device 'SIP/208' changed to state '2' (In use) but we don't care because they're not a member of any queue. [Jan 22 09:10:58] DEBUG[14820][C-00000000] netsock2.c: Splitting 'vm2233.cloud.seeweb.it' into... [Jan 22 09:10:58] DEBUG[14820][C-00000000] netsock2.c: ...host 'vm2233.cloud.seeweb.it' and port ''. [Jan 22 09:10:58] DEBUG[14820][C-00000000] netsock2.c: Splitting 'vm2233.cloud.seeweb.it' into... [Jan 22 09:10:58] DEBUG[14820][C-00000000] netsock2.c: ...host 'vm2233.cloud.seeweb.it' and port ''. [Jan 22 09:10:58] VERBOSE[14820][C-00000000] chan_sip.c: Looking for *43 in from-int-beppo-web (domain vm2233.cloud.seeweb.it) [Jan 22 09:10:58] DEBUG[14820][C-00000000] chan_sip.c: *** Our native formats are (opus) [Jan 22 09:10:58] DEBUG[14820][C-00000000] chan_sip.c: *** Joint capabilities are (opus) [Jan 22 09:10:58] DEBUG[14820][C-00000000] chan_sip.c: *** Our capabilities are (opus) [Jan 22 09:10:58] DEBUG[14820][C-00000000] chan_sip.c: *** AST_CODEC_CHOOSE formats are opus [Jan 22 09:10:58] DEBUG[14820][C-00000000] chan_sip.c: This channel will not be able to handle video. [Jan 22 09:10:58] DEBUG[14820][C-00000000] chan_sip.c: build_route: Contact hop: [Jan 22 09:10:58] VERBOSE[14820][C-00000000] chan_sip.c: list_route: hop: [Jan 22 09:10:58] DEBUG[14820][C-00000000] chan_sip.c: SIP/208-00000000: New call is still down.... Trying... [Jan 22 09:10:58] VERBOSE[14820][C-00000000] chan_sip.c: <--- Transmitting (NAT) to 79.45.181.142:35219 ---> SIP/2.0 100 Trying Via: SIP/2.0/WS t9dsk06quc33.invalid;branch=z9hG4bK9318747;received=79.45.181.142;rport=35219 From: ;tag=sat1k7pbps To: Call-ID: 35gb3h0ak3fnri36uj54 CSeq: 2263 INVITE Server: Asterisk 1.8 Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH Supported: replaces, timer Contact: Content-Length: 0 <------------> [Jan 22 09:10:58] DEBUG[14820][C-00000000] chan_sip.c: Trying to put 'SIP/2.0 100' onto WS socket destined for 79.45.181.142:35219 [Jan 22 09:10:58] DEBUG[14773] devicestate.c: No provider found, checking channel drivers for SIP - 208 [Jan 22 09:10:58] DEBUG[14773] chan_sip.c: Checking device state for peer 208 [Jan 22 09:10:58] DEBUG[14773] devicestate.c: Changing state for SIP/208 - state 2 (In use) [Jan 22 09:10:58] DEBUG[14773] devicestate.c: device 'SIP/208' state '2' [Jan 22 09:10:58] DEBUG[14808] app_queue.c: Device 'SIP/208' changed to state '2' (In use) but we don't care because they're not a member of any queue. [Jan 22 09:10:58] DEBUG[14821][C-00000000] pbx.c: Launching 'Answer' [Jan 22 09:10:58] VERBOSE[14821][C-00000000] pbx.c: -- Executing [*43@from-int-beppo-web:1] Answer("SIP/208-00000000", "") in new stack [Jan 22 09:10:58] DEBUG[14773] devicestate.c: No provider found, checking channel drivers for SIP - 208 [Jan 22 09:10:58] DEBUG[14773] chan_sip.c: Checking device state for peer 208 [Jan 22 09:10:58] DEBUG[14773] devicestate.c: Changing state for SIP/208 - state 2 (In use) [Jan 22 09:10:58] DEBUG[14773] devicestate.c: device 'SIP/208' state '2' [Jan 22 09:10:58] DEBUG[14808] app_queue.c: Device 'SIP/208' changed to state '2' (In use) but we don't care because they're not a member of any queue. [Jan 22 09:10:58] DEBUG[14821][C-00000000] chan_sip.c: SIP answering channel: SIP/208-00000000 [Jan 22 09:10:58] DEBUG[14821][C-00000000] res_rtp_asterisk.c: Setting the marker bit due to a source update [Jan 22 09:10:58] DEBUG[14821][C-00000000] chan_sip.c: Setting framing from config on incoming call [Jan 22 09:10:58] DEBUG[14821][C-00000000] chan_sip.c: ** Our capability: (opus) Video flag: True Text flag: True [Jan 22 09:10:58] DEBUG[14821][C-00000000] chan_sip.c: ** Our prefcodec: (nothing) [Jan 22 09:10:58] VERBOSE[14821][C-00000000] chan_sip.c: Audio is at 18480 [Jan 22 09:10:58] DEBUG[14821][C-00000000] sip/sdp_crypto.c: Crypto line: a=crypto:0 AES_CM_128_HMAC_SHA1_32 inline:rXRAamVXi02Phu/WMOcS1V9Px0PNf32Hk+OhTbhb [Jan 22 09:10:58] VERBOSE[14821][C-00000000] chan_sip.c: Adding codec 100030 (opus) to SDP [Jan 22 09:10:58] VERBOSE[14821][C-00000000] chan_sip.c: Adding non-codec 0x1 (telephone-event) to SDP [Jan 22 09:10:58] DEBUG[14821][C-00000000] chan_sip.c: -- Done with adding codecs to SDP [Jan 22 09:10:58] DEBUG[14821][C-00000000] chan_sip.c: Done building SDP. Settling with this capability: (opus) [Jan 22 09:10:58] VERBOSE[14821][C-00000000] chan_sip.c: <--- Reliably Transmitting (NAT) to 79.45.181.142:35219 ---> SIP/2.0 200 OK Via: SIP/2.0/WS t9dsk06quc33.invalid;branch=z9hG4bK9318747;received=79.45.181.142;rport=35219 From: ;tag=sat1k7pbps To: ;tag=as0a0f34f3 Call-ID: 35gb3h0ak3fnri36uj54 CSeq: 2263 INVITE Server: Asterisk 1.8 Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH Supported: replaces, timer Contact: Content-Type: application/sdp Content-Length: 419 v=0 o=root 562665920 562665920 IN IP4 5.144.163.122 s=Asterisk PBX 11.7.0 c=IN IP4 5.144.163.122 t=0 0 m=audio 18480 RTP/SAVPF 111 101 a=rtpmap:111 opus/48000/2 a=maxptime:60 a=fmtp:111 maxplaybackrate=16000; stereo=0; sprop-stereo=0; useinbandfec=0 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:rXRAamVXi02Phu/WMOcS1V9Px0PNf32Hk+OhTbhb <------------> [Jan 22 09:10:58] DEBUG[14821][C-00000000] chan_sip.c: Trying to put 'SIP/2.0 200' onto WS socket destined for 79.45.181.142:35219 [Jan 22 09:10:58] VERBOSE[14820] chan_sip.c: <--- SIP read from WS:79.45.181.142:35219 ---> ACK sip:*43@5.144.163.122:5060;transport=ws SIP/2.0 Via: SIP/2.0/WS t9dsk06quc33.invalid;branch=z9hG4bK4918360 Max-Forwards: 69 To: ;tag=as0a0f34f3 From: ;tag=sat1k7pbps Call-ID: 35gb3h0ak3fnri36uj54 CSeq: 2263 ACK Supported: path, outbound, gruu User-Agent: JsSIP 0.3.0 Content-Length: 0 <-------------> [Jan 22 09:10:58] DEBUG[14820] chan_sip.c: Header 0 [ 51]: ACK sip:*43@5.144.163.122:5060;transport=ws SIP/2.0 [Jan 22 09:10:58] DEBUG[14820] chan_sip.c: Header 1 [ 58]: Via: SIP/2.0/WS t9dsk06quc33.invalid;branch=z9hG4bK4918360 [Jan 22 09:10:58] DEBUG[14820] chan_sip.c: Header 2 [ 16]: Max-Forwards: 69 [Jan 22 09:10:58] DEBUG[14820] chan_sip.c: Header 3 [ 51]: To: ;tag=as0a0f34f3 [Jan 22 09:10:58] DEBUG[14820] chan_sip.c: Header 4 [ 53]: From: ;tag=sat1k7pbps [Jan 22 09:10:58] DEBUG[14820] chan_sip.c: Header 5 [ 29]: Call-ID: 35gb3h0ak3fnri36uj54 [Jan 22 09:10:58] DEBUG[14820] chan_sip.c: Header 6 [ 14]: CSeq: 2263 ACK [Jan 22 09:10:58] DEBUG[14820] chan_sip.c: Header 7 [ 31]: Supported: path, outbound, gruu [Jan 22 09:10:58] DEBUG[14820] chan_sip.c: Header 8 [ 23]: User-Agent: JsSIP 0.3.0 [Jan 22 09:10:58] DEBUG[14820] chan_sip.c: Header 9 [ 17]: Content-Length: 0 [Jan 22 09:10:58] VERBOSE[14820] chan_sip.c: --- (10 headers 0 lines) --- [Jan 22 09:10:58] DEBUG[14820] chan_sip.c: = Looking for Call ID: 35gb3h0ak3fnri36uj54 (Checking From) --From tag sat1k7pbps --To-tag as0a0f34f3 [Jan 22 09:10:58] DEBUG[14820][C-00000000] chan_sip.c: **** Received ACK (6) - Command in SIP ACK [Jan 22 09:10:58] DEBUG[14820][C-00000000] chan_sip.c: Stopping retransmission on '35gb3h0ak3fnri36uj54' of Response 2263: Match Not Found [Jan 22 09:10:58] VERBOSE[14820] chan_sip.c: <--- SIP read from WS:79.45.181.142:35219 ---> BYE sip:*43@5.144.163.122:5060;transport=ws SIP/2.0 Via: SIP/2.0/WS t9dsk06quc33.invalid;branch=z9hG4bK5026230 Max-Forwards: 69 To: ;tag=as0a0f34f3 From: ;tag=sat1k7pbps Call-ID: 35gb3h0ak3fnri36uj54 CSeq: 2264 BYE Reason: SIP ;cause=488; text="Not Acceptable Here" Supported: path, outbound, gruu User-Agent: JsSIP 0.3.0 Content-Length: 0 <-------------> [Jan 22 09:10:58] DEBUG[14820] chan_sip.c: Header 0 [ 51]: BYE sip:*43@5.144.163.122:5060;transport=ws SIP/2.0 [Jan 22 09:10:58] DEBUG[14820] chan_sip.c: Header 1 [ 58]: Via: SIP/2.0/WS t9dsk06quc33.invalid;branch=z9hG4bK5026230 [Jan 22 09:10:58] DEBUG[14820] chan_sip.c: Header 2 [ 16]: Max-Forwards: 69 [Jan 22 09:10:58] DEBUG[14820] chan_sip.c: Header 3 [ 51]: To: ;tag=as0a0f34f3 [Jan 22 09:10:58] DEBUG[14820] chan_sip.c: Header 4 [ 53]: From: ;tag=sat1k7pbps [Jan 22 09:10:58] DEBUG[14820] chan_sip.c: Header 5 [ 29]: Call-ID: 35gb3h0ak3fnri36uj54 [Jan 22 09:10:58] DEBUG[14820] chan_sip.c: Header 6 [ 14]: CSeq: 2264 BYE [Jan 22 09:10:58] DEBUG[14820] chan_sip.c: Header 7 [ 50]: Reason: SIP ;cause=488; text="Not Acceptable Here" [Jan 22 09:10:58] DEBUG[14820] chan_sip.c: Header 8 [ 31]: Supported: path, outbound, gruu [Jan 22 09:10:58] DEBUG[14820] chan_sip.c: Header 9 [ 23]: User-Agent: JsSIP 0.3.0 [Jan 22 09:10:58] DEBUG[14820] chan_sip.c: Header 10 [ 17]: Content-Length: 0 [Jan 22 09:10:58] VERBOSE[14820] chan_sip.c: --- (11 headers 0 lines) --- [Jan 22 09:10:58] DEBUG[14820] chan_sip.c: = Looking for Call ID: 35gb3h0ak3fnri36uj54 (Checking From) --From tag sat1k7pbps --To-tag as0a0f34f3 [Jan 22 09:10:58] DEBUG[14820][C-00000000] chan_sip.c: **** Received BYE (8) - Command in SIP BYE [Jan 22 09:10:58] DEBUG[14820][C-00000000] chan_sip.c: Initializing initreq for method BYE - callid 35gb3h0ak3fnri36uj54 [Jan 22 09:10:58] DEBUG[14820][C-00000000] chan_sip.c: Setting SIP_ALREADYGONE on dialog 35gb3h0ak3fnri36uj54 [Jan 22 09:10:58] DEBUG[14820][C-00000000] res_rtp_asterisk.c: Setting RTCP address on RTP instance '0x7f6ea00324b8' [Jan 22 09:10:58] VERBOSE[14820][C-00000000] chan_sip.c: Scheduling destruction of SIP dialog '35gb3h0ak3fnri36uj54' in 7680 ms (Method: BYE) [Jan 22 09:10:58] DEBUG[14820][C-00000000] chan_sip.c: Received bye, issuing owner hangup [Jan 22 09:10:58] VERBOSE[14820][C-00000000] chan_sip.c: <--- Transmitting (NAT) to 79.45.181.142:35219 ---> SIP/2.0 200 OK Via: SIP/2.0/WS t9dsk06quc33.invalid;branch=z9hG4bK5026230;received=79.45.181.142;rport=35219 From: ;tag=sat1k7pbps To: ;tag=as0a0f34f3 Call-ID: 35gb3h0ak3fnri36uj54 CSeq: 2264 BYE Server: Asterisk 1.8 Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH Supported: replaces, timer Content-Length: 0 <------------> [Jan 22 09:10:58] DEBUG[14820][C-00000000] chan_sip.c: Trying to put 'SIP/2.0 200' onto WS socket destined for 79.45.181.142:35219 [Jan 22 09:10:58] DEBUG[14821][C-00000000] channel.c: Hangup of channel SIP/208-00000000 detected in answer routine [Jan 22 09:10:58] DEBUG[14821][C-00000000] pbx.c: Spawn extension (from-int-beppo-web,*43,1) exited non-zero on 'SIP/208-00000000' [Jan 22 09:10:58] VERBOSE[14821][C-00000000] pbx.c: == Spawn extension (from-int-beppo-web, *43, 1) exited non-zero on 'SIP/208-00000000' [Jan 22 09:10:58] DEBUG[14821][C-00000000] channel.c: Soft-Hanging up channel 'SIP/208-00000000' [Jan 22 09:10:58] DEBUG[14821][C-00000000] channel.c: Soft-Hanging up channel 'SIP/208-00000000' [Jan 22 09:10:58] DEBUG[14821][C-00000000] pbx.c: Launching 'Hangup' [Jan 22 09:10:58] VERBOSE[14821][C-00000000] pbx.c: -- Executing [h@from-int-beppo-web:1] Hangup("SIP/208-00000000", "") in new stack [Jan 22 09:10:58] DEBUG[14821][C-00000000] channel.c: Soft-Hanging up channel 'SIP/208-00000000' [Jan 22 09:10:58] DEBUG[14821][C-00000000] pbx.c: Spawn extension (from-int-beppo-web,h,1) exited non-zero on 'SIP/208-00000000' [Jan 22 09:10:58] VERBOSE[14821][C-00000000] pbx.c: == Spawn extension (from-int-beppo-web, h, 1) exited non-zero on 'SIP/208-00000000' [Jan 22 09:10:58] DEBUG[14821][C-00000000] channel.c: Hanging up channel 'SIP/208-00000000' [Jan 22 09:10:58] DEBUG[14821][C-00000000] chan_sip.c: Hangup call SIP/208-00000000, SIP callid 35gb3h0ak3fnri36uj54 [Jan 22 09:10:58] DEBUG[14821][C-00000000] chan_sip.c: update_call_counter(8j6gcuk9) - decrement call limit counter on hangup [Jan 22 09:10:58] DEBUG[14821][C-00000000] chan_sip.c: Updating call counter for incoming call [Jan 22 09:10:58] DEBUG[14821][C-00000000] chan_sip.c: Call from peer '208' removed from call limit 50 [Jan 22 09:10:58] DEBUG[14773] devicestate.c: No provider found, checking channel drivers for SIP - 208 [Jan 22 09:10:58] DEBUG[14773] chan_sip.c: Checking device state for peer 208 [Jan 22 09:10:58] DEBUG[14773] devicestate.c: Changing state for SIP/208 - state 1 (Not in use) [Jan 22 09:10:58] DEBUG[14773] devicestate.c: device 'SIP/208' state '1' [Jan 22 09:10:58] DEBUG[14808] app_queue.c: Device 'SIP/208' changed to state '1' (Not in use) but we don't care because they're not a member of any queue. [Jan 22 09:10:58] DEBUG[14821][C-00000000] res_rtp_asterisk.c: Setting RTCP address on RTP instance '0x7f6ea00324b8' [Jan 22 09:10:58] DEBUG[14773] devicestate.c: No provider found, checking channel drivers for SIP - 208 [Jan 22 09:10:58] DEBUG[14773] chan_sip.c: Checking device state for peer 208 [Jan 22 09:10:58] DEBUG[14773] devicestate.c: Changing state for SIP/208 - state 1 (Not in use) [Jan 22 09:10:58] DEBUG[14773] devicestate.c: device 'SIP/208' state '1' [Jan 22 09:10:58] DEBUG[14808] app_queue.c: Device 'SIP/208' changed to state '1' (Not in use) but we don't care because they're not a member of any queue. [Jan 22 09:11:06] DEBUG[14782] chan_sip.c: Auto destroying SIP dialog '35gb3h0ak3fnri36uj54' [Jan 22 09:11:06] DEBUG[14782] chan_sip.c: Destroying SIP dialog 35gb3h0ak3fnri36uj54 [Jan 22 09:11:06] VERBOSE[14782] chan_sip.c: Really destroying SIP dialog '35gb3h0ak3fnri36uj54' Method: BYE [Jan 22 09:11:06] DEBUG[14782] rtp_engine.c: Destroyed RTP instance '0x7f6ea00324b8' [Jan 22 09:11:09] DEBUG[14796] res_timing_timerfd.c: Expected to acknowledge 1 ticks but got 2 instead [Jan 22 09:11:12] DEBUG[14782] chan_sip.c: Auto destroying SIP dialog '1ef04757213814e60cc895986c1b12d1@5.144.170.162:5060' [Jan 22 09:11:12] DEBUG[14782] chan_sip.c: Destroying SIP dialog 1ef04757213814e60cc895986c1b12d1@5.144.170.162:5060