<--- SIP read from UDP:192.168.1.121:5060 ---> INVITE sip:1060@192.168.1.92:5062 SIP/2.0 Via: SIP/2.0/UDP 192.168.1.121;branch=z9hG4bK87d4239be Max-Forwards: 70 Content-Length: 280 To: 1060 From: 1061 ;tag=01d08f68577355e Call-ID: 7b376f39aaed66605b9424bb4d96445c@192.168.1.121 CSeq: 1043110675 INVITE Supported: timer Allow-Events: talk,hold,conference Allow:NOTIFY,REFER,OPTIONS,INVITE,ACK,CANCEL,BYE,INFO Contact: 1061 Content-Type: application/sdp Supported: replaces Authorization:Digest response="2f6d1f6dcf48708d05bcaab1641d5e12",username="1061",realm="192.168.1.92",nonce="59c7518e",algorithm=MD5,uri="sip:1060@192.168.1.92:5062" User-Agent: Aastra 9133i/1.4.0.1048 Brcm Callctrl/1.5.1.0 MxSF/v3.2.6.26 v=0 o=MxSIP 0 947318477 IN IP4 192.168.1.121 s=SIP Call c=IN IP4 192.168.1.121 t=0 0 m=audio 3000 RTP/AVP 0 8 18 101 a=rtpmap:0 PCMU/8000 a=rtpmap:8 PCMA/8000 a=rtpmap:18 G729/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-15 a=ptime:30 a=silenceSupp:on - - - - <-------------> [Feb 5 13:20:48] VERBOSE[26101] chan_sip.c: --- (16 headers 13 lines) --- [Feb 5 13:20:48] VERBOSE[26101][C-00000007] chan_sip.c: Sending to 192.168.1.121:5060 (no NAT) [Feb 5 13:20:48] VERBOSE[26101][C-00000007] chan_sip.c: Using INVITE request as basis request - 7b376f39aaed66605b9424bb4d96445c@192.168.1.121 [Feb 5 13:20:48] VERBOSE[26101][C-00000007] chan_sip.c: Found peer '1061' for '1061' from 192.168.1.121:5060 [Feb 5 13:20:48] DEBUG[26101][C-00000007] pjproject: icess0x7fc4e8026318 ICE session created, comp_cnt=2, role is Unknown agent [Feb 5 13:20:48] DEBUG[26101][C-00000007] pjproject: icess0x7fc4e8026318 Candidate 0 added: comp_id=1, type=host, foundation=Hc0a8015c, addr=192.168.1.92:12654, base=192.168.1.92:12654, prio=0x7effffff (2130706431) [Feb 5 13:20:48] DEBUG[26101][C-00000007] pjproject: icess0x7fc4e8026318 Candidate 1 added: comp_id=1, type=srflx, foundation=Sc1c89bab, addr=193.200.155.171:22794, base=192.168.1.92:12654, prio=0x64ffffff (1694498815) [Feb 5 13:20:48] DEBUG[26101][C-00000007] pjproject: icess0x7fc4e8026318 Destroying ICE session 0x7fc4e8026318 [Feb 5 13:20:48] DEBUG[26101][C-00000007] pjproject: stuse0x7fc4e8009ee0 STUN session 0x7fc4e800d308 destroy request, ref_cnt=4 [Feb 5 13:20:48] DEBUG[26101][C-00000007] pjproject: stuse0x7fc4e801ab20 STUN session 0x7fc4e8014c78 destroy request, ref_cnt=3 [Feb 5 13:20:48] DEBUG[26101][C-00000007] pjproject: ice_session.c ICE session 0x7fc4e8026318 destroyed [Feb 5 13:20:48] DEBUG[26101][C-00000007] pjproject: stun_session.c STUN session 0x7fc4e800d308 destroyed [Feb 5 13:20:48] DEBUG[26101][C-00000007] pjproject: stun_session.c STUN session 0x7fc4e8014c78 destroyed [Feb 5 13:20:48] VERBOSE[26101][C-00000007] netsock2.c: Using SIP RTP CoS mark 5 [Feb 5 13:20:48] VERBOSE[26101][C-00000007] chan_sip.c: Found RTP audio format 0 [Feb 5 13:20:48] VERBOSE[26101][C-00000007] chan_sip.c: Found RTP audio format 8 [Feb 5 13:20:48] VERBOSE[26101][C-00000007] chan_sip.c: Found RTP audio format 18 [Feb 5 13:20:48] VERBOSE[26101][C-00000007] chan_sip.c: Found RTP audio format 101 [Feb 5 13:20:48] VERBOSE[26101][C-00000007] chan_sip.c: Found audio description format PCMU for ID 0 [Feb 5 13:20:48] VERBOSE[26101][C-00000007] chan_sip.c: Found audio description format PCMA for ID 8 [Feb 5 13:20:48] VERBOSE[26101][C-00000007] chan_sip.c: Found audio description format G729 for ID 18 [Feb 5 13:20:48] VERBOSE[26101][C-00000007] chan_sip.c: Found audio description format telephone-event for ID 101 [Feb 5 13:20:48] VERBOSE[26101][C-00000007] chan_sip.c: Capabilities: us - (ulaw|alaw), peer - audio=(ulaw|alaw|g729)/video=(nothing)/text=(nothing), combined - (ulaw|alaw) [Feb 5 13:20:48] VERBOSE[26101][C-00000007] chan_sip.c: Non-codec capabilities (dtmf): us - 0x1 (telephone-event|), peer - 0x1 (telephone-event|), combined - 0x1 (telephone-event|) [Feb 5 13:20:48] VERBOSE[26101][C-00000007] res_rtp_asterisk.c: 0x7fc4e80208c0 -- Strict RTP learning after remote address set to: 192.168.1.121:3000 [Feb 5 13:20:48] VERBOSE[26101][C-00000007] chan_sip.c: Peer audio RTP is at port 192.168.1.121:3000 [Feb 5 13:20:48] VERBOSE[26101][C-00000007] chan_sip.c: Looking for 1060 in default (domain 192.168.1.92) [Feb 5 13:20:48] VERBOSE[26101][C-00000007] sip/route.c: sip_route_dump: route/path hop: [Feb 5 13:20:48] VERBOSE[26101][C-00000007] chan_sip.c: <--- Transmitting (no NAT) to 192.168.1.121:5060 ---> SIP/2.0 100 Trying Via: SIP/2.0/UDP 192.168.1.121;branch=z9hG4bK87d4239be;received=192.168.1.121 From: 1061 ;tag=01d08f68577355e To: 1060 Call-ID: 7b376f39aaed66605b9424bb4d96445c@192.168.1.121 CSeq: 1043110675 INVITE Server: Asterisk PBX 15.1.2 Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE Supported: replaces, timer Session-Expires: 1800;refresher=uas Contact: Content-Length: 0 <------------> [Feb 5 13:20:48] VERBOSE[26905][C-00000007] pbx.c: Executing [1060@default:1] Dial("SIP/1061-0000000c", "SIP/1060") in new stack [Feb 5 13:20:48] DEBUG[26905][C-00000007] pjproject: icess0x7fc4f8018728 ICE session created, comp_cnt=2, role is Unknown agent [Feb 5 13:20:48] DEBUG[26905][C-00000007] pjproject: icess0x7fc4f8018728 Candidate 0 added: comp_id=1, type=host, foundation=Hc0a8015c, addr=192.168.1.92:17434, base=192.168.1.92:17434, prio=0x7effffff (2130706431) [Feb 5 13:20:48] DEBUG[26905][C-00000007] pjproject: icess0x7fc4f8018728 Candidate 1 added: comp_id=1, type=srflx, foundation=Sc1c89bab, addr=193.200.155.171:27574, base=192.168.1.92:17434, prio=0x64ffffff (1694498815) [Feb 5 13:20:48] VERBOSE[26905][C-00000007] res_rtp_asterisk.c: DTLS ECDH initialized (secp256r1), faster PFS enabled [Feb 5 13:20:48] DEBUG[26905][C-00000007] pjproject: icess0x7fc4f8018728 Candidate 2 added: comp_id=2, type=host, foundation=Hc0a8015c, addr=192.168.1.92:17435, base=192.168.1.92:17435, prio=0x7efffffe (2130706430) [Feb 5 13:20:48] DEBUG[26905][C-00000007] pjproject: icess0x7fc4f8018728 Candidate 3 added: comp_id=2, type=srflx, foundation=Sc1c89bab, addr=193.200.155.171:27575, base=192.168.1.92:17435, prio=0x64fffffe (1694498814) [Feb 5 13:20:48] VERBOSE[26905][C-00000007] netsock2.c: Using SIP RTP CoS mark 5 [Feb 5 13:20:48] VERBOSE[26905][C-00000007] chan_sip.c: Audio is at 17434 [Feb 5 13:20:48] VERBOSE[26905][C-00000007] chan_sip.c: Adding codec ulaw to SDP [Feb 5 13:20:48] VERBOSE[26905][C-00000007] chan_sip.c: Adding codec alaw to SDP [Feb 5 13:20:48] VERBOSE[26905][C-00000007] chan_sip.c: Adding non-codec 0x1 (telephone-event) to SDP [Feb 5 13:20:48] VERBOSE[26905][C-00000007] chan_sip.c: Reliably Transmitting (no NAT) to 192.168.1.211:63470: INVITE sip:7gksgnog@192.0.2.102;transport=wss SIP/2.0 Via: SIP/2.0/WS 192.168.1.92:5062;branch=z9hG4bK02e633a3 Max-Forwards: 70 From: "1061" ;tag=as42c06b0b To: Contact: Call-ID: 3908170b275c629c6e0990f33735f99d@192.168.1.92:5062 CSeq: 102 INVITE User-Agent: Asterisk PBX 15.1.2 Date: Mon, 05 Feb 2018 13:20:48 GMT Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE Supported: replaces, timer Content-Type: application/sdp Content-Length: 859 v=0 o=root 47754391 47754391 IN IP4 192.168.1.92 s=Asterisk PBX 15.1.2 c=IN IP4 192.168.1.92 t=0 0 m=audio 17434 RTP/SAVPF 0 8 101 a=rtpmap:0 PCMU/8000 a=rtpmap:8 PCMA/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=maxptime:150 a=ice-ufrag:4430d6993ebb2f9f607f3c5d76e41f43 a=ice-pwd:7dbf942766045621584b7a1d2e4defb5 a=candidate:Hc0a8015c 1 UDP 2130706431 192.168.1.92 17434 typ host a=candidate:Sc1c89bab 1 UDP 1694498815 193.200.155.171 27574 typ srflx raddr 192.168.1.92 rport 17434 a=candidate:Hc0a8015c 2 UDP 2130706430 192.168.1.92 17435 typ host a=candidate:Sc1c89bab 2 UDP 1694498814 193.200.155.171 27575 typ srflx raddr 192.168.1.92 rport 17435 a=connection:new a=setup:actpass a=fingerprint:SHA-256 12:E5:DD:E2:5A:91:26:72:D5:F7:90:25:BF:98:5E:A2:66:7C:1D:66:88:54:AC:C8:08:04:CE:1A:96:0A:A0:F5 a=rtcp-mux a=sendrecv --- [Feb 5 13:20:48] VERBOSE[26905][C-00000007] app_dial.c: Called SIP/1060 [Feb 5 13:20:48] VERBOSE[26111] chan_sip.c: <--- SIP read from WS:192.168.1.211:63470 ---> SIP/2.0 100 Trying Via: SIP/2.0/WS 192.168.1.92:5062;branch=z9hG4bK02e633a3 To: From: "1061" ;tag=as42c06b0b Call-ID: 3908170b275c629c6e0990f33735f99d@192.168.1.92:5062 CSeq: 102 INVITE Supported: outbound User-Agent: SIP.js/0.9.2 Content-Length: 0 <-------------> [Feb 5 13:20:48] VERBOSE[26111] chan_sip.c: --- (9 headers 0 lines) --- [Feb 5 13:20:48] VERBOSE[26111] chan_sip.c: <--- SIP read from WS:192.168.1.211:63470 ---> SIP/2.0 180 Ringing Via: SIP/2.0/WS 192.168.1.92:5062;branch=z9hG4bK02e633a3 To: ;tag=vb3rbbroir From: "1061" ;tag=as42c06b0b Call-ID: 3908170b275c629c6e0990f33735f99d@192.168.1.92:5062 CSeq: 102 INVITE Contact: Supported: outbound User-Agent: SIP.js/0.9.2 Content-Length: 0 <-------------> [Feb 5 13:20:48] VERBOSE[26111] chan_sip.c: --- (10 headers 0 lines) --- [Feb 5 13:20:48] VERBOSE[26111][C-00000007] sip/route.c: sip_route_dump: route/path hop: [Feb 5 13:20:48] VERBOSE[26905][C-00000007] app_dial.c: SIP/1060-0000000d is ringing [Feb 5 13:20:48] VERBOSE[26905][C-00000007] chan_sip.c: <--- Transmitting (no NAT) to 192.168.1.121:5060 ---> SIP/2.0 180 Ringing Via: SIP/2.0/UDP 192.168.1.121;branch=z9hG4bK87d4239be;received=192.168.1.121 From: 1061 ;tag=01d08f68577355e To: 1060 ;tag=as57e92cd2 Call-ID: 7b376f39aaed66605b9424bb4d96445c@192.168.1.121 CSeq: 1043110675 INVITE Server: Asterisk PBX 15.1.2 Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE Supported: replaces, timer Session-Expires: 1800;refresher=uas Contact: Content-Length: 0 <------------> [Feb 5 13:20:59] VERBOSE[26101] chan_sip.c: Really destroying SIP dialog '0f50079bafd9ccf74cbb0c588ebc7a78@192.168.1.121' Method: BYE [Feb 5 13:21:01] VERBOSE[26101] chan_sip.c: Really destroying SIP dialog '5b87a0ca264d38f05f4bcb6e706c1518@192.168.1.194:5060' Method: OPTIONS [Feb 5 13:21:01] DEBUG[26905][C-00000007] pjproject: stuse0x7fc4f8032d30 .RX 120 bytes STUN message from 192.168.1.211:51090: --- begin STUN message --- STUN Binding request Hdr: length=100, magic=2112a442, tsx_id=484419ba3b2885fa067f8329 Attributes: USERNAME: length=41, value="4430d6993ebb2f9f607f3c5d76e41f43:e6cfcb4d" PRIORITY: length=4, value=1853817087 (0x6e7f00ff) ICE-CONTROLLED: length=8, data=14fec8641b41fb3d MESSAGE-INTEGRITY: length=20, data=c4a2eb628503dd4338b7eaf8b77a8e3e680b7db3 FINGERPRINT: length=4, value=4283775791 (0xff553b2f) --- end of STUN message --- [Feb 5 13:21:01] DEBUG[26905][C-00000007] pjproject: stuse0x7fc4f8032d30 ..TX 80 bytes STUN message to 192.168.1.211:51090: --- begin STUN message --- STUN Binding success response Hdr: length=60, magic=2112a442, tsx_id=484419ba3b2885fa067f8329 Attributes: XOR-MAPPED-ADDRESS: length=8, IPv4 addr=192.168.1.211:51090 SOFTWARE: length=12, value="pjnath-2.6.0" MESSAGE-INTEGRITY: length=20, data=1e1172d1fa4f44c89bf23380a6f7a083e9693f47 FINGERPRINT: length=4, value=2416318634 (0x900618aa) --- end of STUN message --- [Feb 5 13:21:01] DEBUG[26905][C-00000007] pjproject: icess0x7fc4f8018728 .Received an early check for comp 1 [Feb 5 13:21:01] VERBOSE[26905][C-00000007] res_rtp_asterisk.c: 0x7fc4f8015760 -- Strict RTP learning after remote address set to: 192.168.1.211:51090 [Feb 5 13:21:01] VERBOSE[26111] chan_sip.c: <--- SIP read from WS:192.168.1.211:63470 ---> SIP/2.0 200 OK Via: SIP/2.0/WS 192.168.1.92:5062;branch=z9hG4bK02e633a3 To: ;tag=vb3rbbroir From: "1061" ;tag=as42c06b0b Call-ID: 3908170b275c629c6e0990f33735f99d@192.168.1.92:5062 CSeq: 102 INVITE Contact: Allow: ACK,CANCEL,INVITE,MESSAGE,BYE,OPTIONS,INFO,NOTIFY,REFER Supported: outbound User-Agent: SIP.js/0.9.2 Content-Type: application/sdp Content-Length: 901 v=0 o=mozilla...THIS_IS_SDPARTA-57.0 104062996866113975 0 IN IP4 0.0.0.0 s=- t=0 0 a=sendrecv a=fingerprint:sha-256 B9:46:A4:09:79:32:44:2B:87:F7:24:27:C3:CA:A7:03:B2:1F:38:ED:F4:CA:D6:B6:9D:68:04:F1:58:26:7F:77 a=ice-options:trickle a=msid-semantic:WMS * m=audio 60837 RTP/SAVPF 0 101 c=IN IP4 193.200.155.171 a=candidate:0 1 UDP 2122252543 192.168.1.211 51090 typ host a=candidate:2 1 TCP 2105524479 192.168.1.211 9 typ host tcptype active a=candidate:1 1 UDP 1686052863 193.200.155.171 60837 typ srflx raddr 192.168.1.211 rport 51090 a=sendrecv a=end-of-candidates a=fmtp:101 0-15 a=ice-pwd:a44b50aebb1cdb8f68ade6bd38741788 a=ice-ufrag:e6cfcb4d a=msid:{c985d110-8b77-4a09-adf1-fe0b12d55c7d} {02a1454a-0c12-4170-af7f-4a483d06d06e} a=rtcp-mux a=rtpmap:0 PCMU/8000 a=rtpmap:101 telephone-event/8000 a=setup:active a=ssrc:2897638374 cname:{68e42542-5170-4a6c-a56d-3ab49330f2c0} <-------------> [Feb 5 13:21:01] VERBOSE[26111] chan_sip.c: --- (12 headers 24 lines) --- [Feb 5 13:21:01] VERBOSE[26111][C-00000007] chan_sip.c: Found RTP audio format 0 [Feb 5 13:21:01] VERBOSE[26111][C-00000007] chan_sip.c: Found RTP audio format 101 [Feb 5 13:21:01] VERBOSE[26111][C-00000007] chan_sip.c: Found audio description format PCMU for ID 0 [Feb 5 13:21:01] VERBOSE[26111][C-00000007] chan_sip.c: Found audio description format telephone-event for ID 101 [Feb 5 13:21:01] VERBOSE[26111][C-00000007] chan_sip.c: Capabilities: us - (ulaw|alaw), peer - audio=(ulaw)/video=(nothing)/text=(nothing), combined - (ulaw) [Feb 5 13:21:01] VERBOSE[26111][C-00000007] chan_sip.c: Non-codec capabilities (dtmf): us - 0x1 (telephone-event|), peer - 0x1 (telephone-event|), combined - 0x1 (telephone-event|) [Feb 5 13:21:01] DEBUG[26111][C-00000007] pjproject: icess0x7fc4f8018728 Check 1: [1] 192.168.1.92:17434-->192.168.1.211:51090 pruned (duplicate found) [Feb 5 13:21:01] DEBUG[26111][C-00000007] pjproject: icess0x7fc4f8018728 Check 2: [1] 192.168.1.92:17434-->193.200.155.171:60837 pruned (duplicate found) [Feb 5 13:21:01] DEBUG[26111][C-00000007] pjproject: stuse0x7fc4f8033d30 STUN session 0x7fc4f8034168 destroy request, ref_cnt=5 [Feb 5 13:21:01] DEBUG[26111][C-00000007] pjproject: icess0x7fc4f8018728 Checklist created: [Feb 5 13:21:01] DEBUG[26111][C-00000007] pjproject: icess0x7fc4f8018728 0: [1] 192.168.1.92:17434-->192.168.1.211:51090 (not nominated, state=Frozen) [Feb 5 13:21:01] DEBUG[26111][C-00000007] pjproject: icess0x7fc4f8018728 1: [1] 192.168.1.92:17434-->193.200.155.171:60837 (not nominated, state=Frozen) [Feb 5 13:21:01] DEBUG[26111][C-00000007] pjproject: icess0x7fc4f8018728 Starting ICE check.. [Feb 5 13:21:01] DEBUG[26111][C-00000007] pjproject: icess0x7fc4f8018728 .Check 0: [1] 192.168.1.92:17434-->192.168.1.211:51090: state changed from Frozen to Waiting [Feb 5 13:21:01] DEBUG[26111][C-00000007] pjproject: icess0x7fc4f8018728 .Performing delayed triggerred check for component 1 [Feb 5 13:21:01] DEBUG[26111][C-00000007] pjproject: icess0x7fc4f8018728 ..Performing triggered check for check 0 [Feb 5 13:21:01] DEBUG[26111][C-00000007] pjproject: icess0x7fc4f8018728 ...Sending connectivity check for check 0: [1] 192.168.1.92:17434-->192.168.1.211:51090 [Feb 5 13:21:01] DEBUG[26111][C-00000007] pjproject: stuse0x7fc4f8032d30 .....TX 136 bytes STUN message to 192.168.1.211:51090: --- begin STUN message --- STUN Binding request Hdr: length=116, magic=2112a442, tsx_id=000065b255197c9a779bdefb Attributes: PRIORITY: length=4, value=1862270975 (0x6effffff) ICE-CONTROLLED: length=8, data=5134c6503bbe7005 SOFTWARE: length=12, value="pjnath-2.6.0" USERNAME: length=41, value="e6cfcb4d:4430d6993ebb2f9f607f3c5d76e41f43" MESSAGE-INTEGRITY: length=20, data=f551764f946a565f2ea4ad33f3764f05b43c60b9 FINGERPRINT: length=4, value=1541952123 (0x5be8527b) --- end of STUN message --- [Feb 5 13:21:01] DEBUG[26111][C-00000007] pjproject: utsx0x7fc4ec03e498 .....STUN client transaction created [Feb 5 13:21:01] DEBUG[26111][C-00000007] pjproject: utsx0x7fc4ec03e498 .....STUN sending message (transmit count=1) [Feb 5 13:21:01] DEBUG[26111][C-00000007] pjproject: icess0x7fc4f8018728 ....Check 0: [1] 192.168.1.92:17434-->192.168.1.211:51090: state changed from Waiting to In Progress [Feb 5 13:21:01] DEBUG[26111][C-00000007] pjproject: icess0x7fc4f8018728 Checklist: state changed from Idle to Running [Feb 5 13:21:01] DEBUG[26111][C-00000007] pjproject: icess0x7fc4f8018728 Starting checklist periodic check [Feb 5 13:21:01] DEBUG[26111][C-00000007] pjproject: icess0x7fc4f8018728 .Sending connectivity check for check 1: [1] 192.168.1.92:17434-->193.200.155.171:60837 [Feb 5 13:21:01] DEBUG[26111][C-00000007] pjproject: stuse0x7fc4f8032d30 ...TX 136 bytes STUN message to 193.200.155.171:60837: --- begin STUN message --- STUN Binding request Hdr: length=116, magic=2112a442, tsx_id=000065b22b8fa661779bdefc Attributes: PRIORITY: length=4, value=1862270975 (0x6effffff) ICE-CONTROLLED: length=8, data=5134c6503bbe7005 SOFTWARE: length=12, value="pjnath-2.6.0" USERNAME: length=41, value="e6cfcb4d:4430d6993ebb2f9f607f3c5d76e41f43" MESSAGE-INTEGRITY: length=20, data=db7ac57ec1bd3614497bb5291f6dfadd134f8064 FINGERPRINT: length=4, value=893817510 (0x354692a6) --- end of STUN message --- [Feb 5 13:21:01] DEBUG[26111][C-00000007] pjproject: utsx0x7fc4ec040cd8 ...STUN client transaction created [Feb 5 13:21:01] DEBUG[26111][C-00000007] pjproject: utsx0x7fc4ec040cd8 ...STUN sending message (transmit count=1) [Feb 5 13:21:01] DEBUG[26111][C-00000007] pjproject: icess0x7fc4f8018728 ..Check 1: [1] 192.168.1.92:17434-->193.200.155.171:60837: state changed from Frozen to In Progress [Feb 5 13:21:01] VERBOSE[26111][C-00000007] chan_sip.c: Peer audio RTP is at port 193.200.155.171:60837 [Feb 5 13:21:01] VERBOSE[26111][C-00000007] sip/route.c: sip_route_dump: route/path hop: [Feb 5 13:21:01] VERBOSE[26111][C-00000007] chan_sip.c: set_destination: Parsing for address/port to send to [Feb 5 13:21:01] VERBOSE[26111][C-00000007] chan_sip.c: set_destination: URI is for WebSocket, we can't set destination [Feb 5 13:21:01] VERBOSE[26111][C-00000007] chan_sip.c: Transmitting (no NAT) to 192.0.2.102:5060: ACK sip:7gksgnog@192.0.2.102;transport=wss SIP/2.0 Via: SIP/2.0/WS 192.168.1.92:5062;branch=z9hG4bK09fabadf Max-Forwards: 70 From: "1061" ;tag=as42c06b0b To: ;tag=vb3rbbroir Contact: Call-ID: 3908170b275c629c6e0990f33735f99d@192.168.1.92:5062 CSeq: 102 ACK User-Agent: Asterisk PBX 15.1.2 Content-Length: 0 --- [Feb 5 13:21:01] VERBOSE[26905][C-00000007] app_dial.c: SIP/1060-0000000d answered SIP/1061-0000000c [Feb 5 13:21:01] VERBOSE[26905][C-00000007] chan_sip.c: Audio is at 12654 [Feb 5 13:21:01] VERBOSE[26905][C-00000007] chan_sip.c: Adding codec ulaw to SDP [Feb 5 13:21:01] VERBOSE[26905][C-00000007] chan_sip.c: Adding codec alaw to SDP [Feb 5 13:21:01] VERBOSE[26905][C-00000007] chan_sip.c: Adding non-codec 0x1 (telephone-event) to SDP [Feb 5 13:21:01] VERBOSE[26905][C-00000007] chan_sip.c: <--- Reliably Transmitting (no NAT) to 192.168.1.121:5060 ---> SIP/2.0 200 OK Via: SIP/2.0/UDP 192.168.1.121;branch=z9hG4bK87d4239be;received=192.168.1.121 From: 1061 ;tag=01d08f68577355e To: 1060 ;tag=as57e92cd2 Call-ID: 7b376f39aaed66605b9424bb4d96445c@192.168.1.121 CSeq: 1043110675 INVITE Server: Asterisk PBX 15.1.2 Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE Supported: replaces, timer Session-Expires: 1800;refresher=uas Contact: Content-Type: application/sdp Require: timer Content-Length: 263 v=0 o=root 1326908068 1326908068 IN IP4 192.168.1.92 s=Asterisk PBX 15.1.2 c=IN IP4 192.168.1.92 t=0 0 m=audio 12654 RTP/AVP 0 8 101 a=rtpmap:0 PCMU/8000 a=rtpmap:8 PCMA/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=maxptime:150 a=sendrecv <------------> [Feb 5 13:21:01] VERBOSE[26907][C-00000007] bridge_channel.c: Channel SIP/1060-0000000d joined 'simple_bridge' basic-bridge <199517c1-c728-4d59-ac91-29798c483764> [Feb 5 13:21:01] VERBOSE[26905][C-00000007] bridge_channel.c: Channel SIP/1061-0000000c joined 'simple_bridge' basic-bridge <199517c1-c728-4d59-ac91-29798c483764> [Feb 5 13:21:01] DEBUG[26907][C-00000007] pjproject: stuse0x7fc4f8032d30 .RX 88 bytes STUN message from 192.168.1.211:51090: --- begin STUN message --- STUN Binding error response Hdr: length=68, magic=2112a442, tsx_id=000065b255197c9a779bdefb Attributes: XOR-MAPPED-ADDRESS: length=8, IPv4 addr=192.168.1.92:17434 ERROR-CODE: length=17, err_code=487, reason="Role Conflict" MESSAGE-INTEGRITY: length=20, data=eade9313bef0e1389c0581419393c07bcc12e291 FINGERPRINT: length=4, value=2988417748 (0xb21fa2d4) --- end of STUN message --- [Feb 5 13:21:01] DEBUG[26907][C-00000007] pjproject: icess0x7fc4f8018728 .Changing role because of role conflict response [Feb 5 13:21:01] DEBUG[26907][C-00000007] pjproject: icess0x7fc4f8018728 .Role changed to Controlling [Feb 5 13:21:01] DEBUG[26907][C-00000007] pjproject: icess0x7fc4f8018728 .Resending check because of role conflict [Feb 5 13:21:01] DEBUG[26907][C-00000007] pjproject: icess0x7fc4f8018728 ..Check 0: [1] 192.168.1.92:17434-->192.168.1.211:51090: state changed from In Progress to Waiting [Feb 5 13:21:01] DEBUG[26907][C-00000007] pjproject: icess0x7fc4f8018728 ..Sending connectivity check for check 0: [1] 192.168.1.92:17434-->192.168.1.211:51090 [Feb 5 13:21:01] DEBUG[26907][C-00000007] pjproject: stuse0x7fc4f8032d30 ....TX 140 bytes STUN message to 192.168.1.211:51090: --- begin STUN message --- STUN Binding request Hdr: length=120, magic=2112a442, tsx_id=000065b24cc965b2779bdefd Attributes: PRIORITY: length=4, value=1862270975 (0x6effffff) USE-CANDIDATE: length=0 ICE-CONTROLLING: length=8, data=5134c6503bbe7005 SOFTWARE: length=12, value="pjnath-2.6.0" USERNAME: length=41, value="e6cfcb4d:4430d6993ebb2f9f607f3c5d76e41f43" MESSAGE-INTEGRITY: length=20, data=7b1f77c7b33d051f4ce566fe3a4e7383d861f15b FINGERPRINT: length=4, value=1762571625 (0x690eb569) --- end of STUN message --- [Feb 5 13:21:01] DEBUG[26907][C-00000007] pjproject: utsx0x24bbda0 ....STUN client transaction created [Feb 5 13:21:01] DEBUG[26907][C-00000007] pjproject: utsx0x24bbda0 ....STUN sending message (transmit count=1) [Feb 5 13:21:01] DEBUG[26907][C-00000007] pjproject: icess0x7fc4f8018728 ...Check 0: [1] 192.168.1.92:17434-->192.168.1.211:51090: state changed from Waiting to In Progress [Feb 5 13:21:01] DEBUG[26907][C-00000007] pjproject: stun_session.c .tdata 0x7fc4ec03e2b8 destroy request, force=0, tsx=0x7fc4ec03e498 [Feb 5 13:21:01] DEBUG[26907][C-00000007] pjproject: utsx0x7fc4ec03e498 .STUN transaction 0x7fc4ec03e498 schedule destroy [Feb 5 13:21:01] DEBUG[26907][C-00000007] pjproject: stuse0x7fc4f8032d30 .RX 64 bytes STUN message from 192.168.1.211:51090: --- begin STUN message --- STUN Binding success response Hdr: length=44, magic=2112a442, tsx_id=000065b24cc965b2779bdefd Attributes: XOR-MAPPED-ADDRESS: length=8, IPv4 addr=192.168.1.92:17434 MESSAGE-INTEGRITY: length=20, data=6176c2540d31b3c6f0e8865ed2288738c58aaed7 FINGERPRINT: length=4, value=3183549231 (0xbdc11b2f) --- end of STUN message --- [Feb 5 13:21:01] DEBUG[26907][C-00000007] pjproject: icess0x7fc4f8018728 .Check 0: [1] 192.168.1.92:17434-->192.168.1.211:51090 (nominated): connectivity check SUCCESS [Feb 5 13:21:01] DEBUG[26907][C-00000007] pjproject: icess0x7fc4f8018728 .Check 0: [1] 192.168.1.92:17434-->192.168.1.211:51090: state changed from In Progress to Succeeded [Feb 5 13:21:01] DEBUG[26907][C-00000007] pjproject: icess0x7fc4f8018728 .Check 0 is successful and nominated [Feb 5 13:21:01] DEBUG[26907][C-00000007] pjproject: icess0x7fc4f8018728 .Cancelling check 1: [1] 192.168.1.92:17434-->193.200.155.171:60837 (In Progress) [Feb 5 13:21:01] DEBUG[26907][C-00000007] pjproject: stun_session.c .tdata 0x7fc4ec040af8 destroy request, force=0, tsx=0x7fc4ec040cd8 [Feb 5 13:21:01] DEBUG[26907][C-00000007] pjproject: utsx0x7fc4ec040cd8 .STUN transaction 0x7fc4ec040cd8 schedule destroy [Feb 5 13:21:01] DEBUG[26907][C-00000007] pjproject: icess0x7fc4f8018728 .Check 1: [1] 192.168.1.92:17434-->193.200.155.171:60837: state changed from In Progress to Failed [Feb 5 13:21:01] DEBUG[26907][C-00000007] pjproject: icess0x7fc4f8018728 .ICE process complete, status=Success [Feb 5 13:21:01] DEBUG[26907][C-00000007] pjproject: icess0x7fc4f8018728 .Valid list [Feb 5 13:21:01] DEBUG[26907][C-00000007] pjproject: icess0x7fc4f8018728 . 0: [1] 192.168.1.92:17434-->192.168.1.211:51090 (nominated, state=Succeeded) [Feb 5 13:21:01] DEBUG[26907][C-00000007] pjproject: stun_session.c .tdata 0x24bbbb8 destroy request, force=0, tsx=0x24bbda0 [Feb 5 13:21:01] DEBUG[26907][C-00000007] pjproject: utsx0x24bbda0 .STUN transaction 0x24bbda0 schedule destroy [Feb 5 13:21:01] DEBUG[26085] pjproject: icess0x7fc4f8018728 Starting checklist periodic check [Feb 5 13:21:01] VERBOSE[26085] res_rtp_asterisk.c: 0x7fc4f8015760 -- Strict RTP learning after ICE completion [Feb 5 13:21:01] VERBOSE[26907][C-00000007] res_rtp_asterisk.c: 0x7fc4f8015760 -- Strict RTP switching to RTP target address 192.168.1.211:51090 as source [Feb 5 13:21:01] VERBOSE[26907][C-00000007] res_rtp_asterisk.c: Got RTP packet from 192.168.1.211:51090 (type 00, seq 001874, ts 3380120714, len 000160) [Feb 5 13:21:01] VERBOSE[26907][C-00000007] res_rtp_asterisk.c: Got RTP packet from 192.168.1.211:51090 (type 00, seq 001875, ts 3380120874, len 000160) [Feb 5 13:21:01] VERBOSE[26907][C-00000007] res_rtp_asterisk.c: Got RTP packet from 192.168.1.211:51090 (type 00, seq 001876, ts 3380121034, len 000160) [Feb 5 13:21:01] VERBOSE[26907][C-00000007] res_rtp_asterisk.c: Got RTP packet from 192.168.1.211:51090 (type 00, seq 001877, ts 3380121194, len 000160) [Feb 5 13:21:01] VERBOSE[26907][C-00000007] res_rtp_asterisk.c: Got RTP packet from 192.168.1.211:51090 (type 00, seq 001878, ts 3380121354, len 000160) [Feb 5 13:21:01] VERBOSE[26907][C-00000007] res_rtp_asterisk.c: Got RTP packet from 192.168.1.211:51090 (type 00, seq 001879, ts 3380121514, len 000160) [Feb 5 13:21:01] VERBOSE[26907][C-00000007] res_rtp_asterisk.c: Got RTP packet from 192.168.1.211:51090 (type 00, seq 001880, ts 3380121674, len 000160) [Feb 5 13:21:01] VERBOSE[26907][C-00000007] res_rtp_asterisk.c: Got RTP packet from 192.168.1.211:51090 (type 00, seq 001881, ts 3380121834, len 000160) [Feb 5 13:21:01] VERBOSE[26907][C-00000007] res_rtp_asterisk.c: Got RTP packet from 192.168.1.211:51090 (type 00, seq 001882, ts 3380121994, len 000160) [Feb 5 13:21:01] VERBOSE[26907][C-00000007] res_rtp_asterisk.c: Got RTP packet from 192.168.1.211:51090 (type 00, seq 001883, ts 3380122154, len 000160) [Feb 5 13:21:01] VERBOSE[26907][C-00000007] res_rtp_asterisk.c: Got RTP packet from 192.168.1.211:51090 (type 00, seq 001884, ts 3380122314, len 000160) [Feb 5 13:21:01] VERBOSE[26907][C-00000007] res_rtp_asterisk.c: Got RTP packet from 192.168.1.211:51090 (type 00, seq 001885, ts 3380122474, len 000160) [Feb 5 13:21:01] VERBOSE[26101] chan_sip.c: <--- SIP read from UDP:192.168.1.121:5060 ---> ACK sip:1060@192.168.1.92:5062 SIP/2.0 Via: SIP/2.0/UDP 192.168.1.121;branch=z9hG4bKd5e10214e Max-Forwards: 70 Content-Length: 0 To: 1060 ;tag=as57e92cd2 From: 1061 ;tag=01d08f68577355e Call-ID: 7b376f39aaed66605b9424bb4d96445c@192.168.1.121 CSeq: 1043110675 ACK Contact: 1061 Authorization:Digest response="5c2b57dde62e00672222991c5f9752f7",username="1061",realm="192.168.1.92",nonce="59c7518e",algorithm=MD5,uri="sip:1060@192.168.1.92:5062" User-Agent: Aastra 9133i/1.4.0.1048 Brcm Callctrl/1.5.1.0 MxSF/v3.2.6.26 <-------------> [Feb 5 13:21:01] VERBOSE[26101] chan_sip.c: --- (11 headers 0 lines) --- [Feb 5 13:21:01] VERBOSE[26907][C-00000007] res_rtp_asterisk.c: Got RTP packet from 192.168.1.211:51090 (type 00, seq 001886, ts 3380122634, len 000160) [Feb 5 13:21:01] VERBOSE[26905][C-00000007] res_rtp_asterisk.c: Sent RTP packet to 192.168.1.121:3000 (type 00, seq 001684, ts 3380122632, len 000160) [Feb 5 13:21:01] VERBOSE[26907][C-00000007] res_rtp_asterisk.c: Got RTP packet from 192.168.1.211:51090 (type 00, seq 001887, ts 3380122794, len 000160) [Feb 5 13:21:01] VERBOSE[26905][C-00000007] res_rtp_asterisk.c: Sent RTP packet to 192.168.1.121:3000 (type 00, seq 001685, ts 3380122792, len 000160) [Feb 5 13:21:01] DEBUG[26085] pjproject: utsx0x7fc4ec03e498 STUN client transaction 0x7fc4ec03e498 stopped, ref_cnt=7 [Feb 5 13:21:01] DEBUG[26085] pjproject: stun_session.c STUN transaction 0x7fc4ec03e498 destroyed [Feb 5 13:21:01] DEBUG[26085] pjproject: utsx0x7fc4ec040cd8 STUN client transaction 0x7fc4ec040cd8 stopped, ref_cnt=6 [Feb 5 13:21:01] DEBUG[26085] pjproject: stun_session.c STUN transaction 0x7fc4ec040cd8 destroyed [Feb 5 13:21:01] DEBUG[26085] pjproject: utsx0x24bbda0 STUN client transaction 0x24bbda0 stopped, ref_cnt=5 [Feb 5 13:21:01] DEBUG[26085] pjproject: stun_session.c STUN transaction 0x24bbda0 destroyed [Feb 5 13:21:01] VERBOSE[26907][C-00000007] res_rtp_asterisk.c: Got RTP packet from 192.168.1.211:51090 (type 00, seq 001888, ts 3380122954, len 000160) [Feb 5 13:21:01] VERBOSE[26905][C-00000007] res_rtp_asterisk.c: Sent RTP packet to 192.168.1.121:3000 (type 00, seq 001686, ts 3380122952, len 000160) [Feb 5 13:21:01] VERBOSE[26907][C-00000007] res_rtp_asterisk.c: Got RTP packet from 192.168.1.211:51090 (type 00, seq 001889, ts 3380123114, len 000160) [Feb 5 13:21:01] VERBOSE[26905][C-00000007] res_rtp_asterisk.c: Sent RTP packet to 192.168.1.121:3000 (type 00, seq 001687, ts 3380123112, len 000160) [Feb 5 13:21:01] VERBOSE[26907][C-00000007] res_rtp_asterisk.c: Got RTP packet from 192.168.1.211:51090 (type 00, seq 001890, ts 3380123274, len 000160) [Feb 5 13:21:01] VERBOSE[26905][C-00000007] res_rtp_asterisk.c: Sent RTP packet to 192.168.1.121:3000 (type 00, seq 001688, ts 3380123272, len 000160) [Feb 5 13:21:01] VERBOSE[26907][C-00000007] res_rtp_asterisk.c: Got RTP packet from 192.168.1.211:51090 (type 00, seq 001891, ts 3380123434, len 000160) [Feb 5 13:21:01] VERBOSE[26905][C-00000007] res_rtp_asterisk.c: Sent RTP packet to 192.168.1.121:3000 (type 00, seq 001689, ts 3380123432, len 000160) [Feb 5 13:21:01] VERBOSE[26907][C-00000007] res_rtp_asterisk.c: Got RTP packet from 192.168.1.211:51090 (type 00, seq 001892, ts 3380123594, len 000160) [Feb 5 13:21:01] VERBOSE[26905][C-00000007] res_rtp_asterisk.c: Sent RTP packet to 192.168.1.121:3000 (type 00, seq 001690, ts 3380123592, len 000160) [Feb 5 13:21:01] VERBOSE[26907][C-00000007] res_rtp_asterisk.c: Got RTP packet from 192.168.1.211:51090 (type 00, seq 001893, ts 3380123754, len 000160) [Feb 5 13:21:01] VERBOSE[26905][C-00000007] res_rtp_asterisk.c: Sent RTP packet to 192.168.1.121:3000 (type 00, seq 001691, ts 3380123752, len 000160) [Feb 5 13:21:01] VERBOSE[26907][C-00000007] res_rtp_asterisk.c: Got RTP packet from 192.168.1.211:51090 (type 00, seq 001894, ts 3380123914, len 000160) [Feb 5 13:21:01] VERBOSE[26905][C-00000007] res_rtp_asterisk.c: Sent RTP packet to 192.168.1.121:3000 (type 00, seq 001692, ts 3380123912, len 000160)