[Oct 7 14:00:30] Asterisk 11.20.0-rc2 built by root @ centosclean on a i686 running Linux on 2015-10-07 14:34:06 UTC [Oct 7 14:00:30] VERBOSE[24011] config.c: == Parsing '/etc/asterisk/logger.conf': Found [Oct 7 14:00:30] VERBOSE[24011] logger.c: Asterisk Queue Logger restarted [Oct 7 14:00:39] VERBOSE[24042] chan_sip.c: <--- SIP read from UDP:192.168.1.103:52547 ---> REGISTER sip:192.168.1.110 SIP/2.0 Via: SIP/2.0/UDP 192.168.1.103:52547;rport;branch=z9hG4bKPj9ad9d0ddafb5453f873c082f861ae904 Max-Forwards: 70 From: "ALICE" ;tag=9917cba0a741481ab5120d69929f5963 To: "ALICE" Call-ID: cafb29869720429e924b3a6337b7e59b CSeq: 18933 REGISTER User-Agent: MicroSIP/3.10.5 Contact: "ALICE" Expires: 0 Content-Length: 0 <-------------> [Oct 7 14:00:39] VERBOSE[24042] chan_sip.c: --- (11 headers 0 lines) --- [Oct 7 14:00:39] VERBOSE[24042] chan_sip.c: Sending to 192.168.1.103:52547 (no NAT) [Oct 7 14:00:39] VERBOSE[24042] chan_sip.c: Sending to 192.168.1.103:52547 (no NAT) [Oct 7 14:00:39] VERBOSE[24042] chan_sip.c: <--- Transmitting (no NAT) to 192.168.1.103:52547 ---> SIP/2.0 401 Unauthorized Via: SIP/2.0/UDP 192.168.1.103:52547;branch=z9hG4bKPj9ad9d0ddafb5453f873c082f861ae904;received=192.168.1.103;rport=52547 From: "ALICE" ;tag=9917cba0a741481ab5120d69929f5963 To: "ALICE" ;tag=as7e28542e Call-ID: cafb29869720429e924b3a6337b7e59b CSeq: 18933 REGISTER Server: Asterisk PBX 11.20.0-rc2 Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE Supported: replaces, timer WWW-Authenticate: Digest algorithm=MD5, realm="asterisk", nonce="66829639" Content-Length: 0 <------------> [Oct 7 14:00:39] VERBOSE[24042] chan_sip.c: Scheduling destruction of SIP dialog 'cafb29869720429e924b3a6337b7e59b' in 32000 ms (Method: REGISTER) [Oct 7 14:00:39] VERBOSE[24042] chan_sip.c: <--- SIP read from UDP:192.168.1.103:52547 ---> REGISTER sip:192.168.1.110 SIP/2.0 Via: SIP/2.0/UDP 192.168.1.103:52547;rport;branch=z9hG4bKPj3a3cc3d2a50d414ab28c48d1eeefd069 Max-Forwards: 70 From: "ALICE" ;tag=9917cba0a741481ab5120d69929f5963 To: "ALICE" Call-ID: cafb29869720429e924b3a6337b7e59b CSeq: 18934 REGISTER User-Agent: MicroSIP/3.10.5 Contact: "ALICE" Expires: 0 Authorization: Digest username="ALICE", realm="asterisk", nonce="66829639", uri="sip:192.168.1.110", response="eb1ab9c5d301b43c280a07249e26fbbb", algorithm=MD5 Content-Length: 0 <-------------> [Oct 7 14:00:39] VERBOSE[24042] chan_sip.c: --- (12 headers 0 lines) --- [Oct 7 14:00:39] VERBOSE[24042] chan_sip.c: Sending to 192.168.1.103:52547 (no NAT) [Oct 7 14:00:39] VERBOSE[24042] chan_sip.c: -- Unregistered SIP 'ALICE' [Oct 7 14:00:39] VERBOSE[24042] chan_sip.c: <--- Transmitting (no NAT) to 192.168.1.103:52547 ---> SIP/2.0 200 OK Via: SIP/2.0/UDP 192.168.1.103:52547;branch=z9hG4bKPj3a3cc3d2a50d414ab28c48d1eeefd069;received=192.168.1.103;rport=52547 From: "ALICE" ;tag=9917cba0a741481ab5120d69929f5963 To: "ALICE" ;tag=as7e28542e Call-ID: cafb29869720429e924b3a6337b7e59b CSeq: 18934 REGISTER Server: Asterisk PBX 11.20.0-rc2 Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE Supported: replaces, timer Expires: 0 Date: Wed, 07 Oct 2015 19:00:39 GMT Content-Length: 0 <------------> [Oct 7 14:00:39] VERBOSE[24042] chan_sip.c: Scheduling destruction of SIP dialog 'cafb29869720429e924b3a6337b7e59b' in 32000 ms (Method: REGISTER) [Oct 7 14:00:43] VERBOSE[24042] chan_sip.c: <--- SIP read from UDP:192.168.1.103:52547 ---> REGISTER sip:192.168.1.110 SIP/2.0 Via: SIP/2.0/UDP 192.168.1.103:52547;rport;branch=z9hG4bKPj5e7ac3a5823f4216a230273b8aeac088 Max-Forwards: 70 From: "ALICE" ;tag=4a4bde57b7cc42e5b6620117eea63f74 To: "ALICE" Call-ID: 7e84e8590d6c4136bae9c3abca95c361 CSeq: 30595 REGISTER User-Agent: MicroSIP/3.10.5 Contact: "ALICE" Expires: 300 Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, INFO, SUBSCRIBE, NOTIFY, REFER, MESSAGE, OPTIONS Content-Length: 0 <-------------> [Oct 7 14:00:43] VERBOSE[24042] chan_sip.c: --- (12 headers 0 lines) --- [Oct 7 14:00:43] VERBOSE[24042] chan_sip.c: Sending to 192.168.1.103:52547 (no NAT) [Oct 7 14:00:43] VERBOSE[24042] chan_sip.c: Sending to 192.168.1.103:52547 (no NAT) [Oct 7 14:00:43] VERBOSE[24042] chan_sip.c: <--- Transmitting (no NAT) to 192.168.1.103:52547 ---> SIP/2.0 401 Unauthorized Via: SIP/2.0/UDP 192.168.1.103:52547;branch=z9hG4bKPj5e7ac3a5823f4216a230273b8aeac088;received=192.168.1.103;rport=52547 From: "ALICE" ;tag=4a4bde57b7cc42e5b6620117eea63f74 To: "ALICE" ;tag=as6304df45 Call-ID: 7e84e8590d6c4136bae9c3abca95c361 CSeq: 30595 REGISTER Server: Asterisk PBX 11.20.0-rc2 Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE Supported: replaces, timer WWW-Authenticate: Digest algorithm=MD5, realm="asterisk", nonce="66b922c4" Content-Length: 0 <------------> [Oct 7 14:00:43] VERBOSE[24042] chan_sip.c: Scheduling destruction of SIP dialog '7e84e8590d6c4136bae9c3abca95c361' in 32000 ms (Method: REGISTER) [Oct 7 14:00:43] VERBOSE[24042] chan_sip.c: <--- SIP read from UDP:192.168.1.103:52547 ---> REGISTER sip:192.168.1.110 SIP/2.0 Via: SIP/2.0/UDP 192.168.1.103:52547;rport;branch=z9hG4bKPj8234f4e740cd42388c54399dbd402134 Max-Forwards: 70 From: "ALICE" ;tag=4a4bde57b7cc42e5b6620117eea63f74 To: "ALICE" Call-ID: 7e84e8590d6c4136bae9c3abca95c361 CSeq: 30596 REGISTER User-Agent: MicroSIP/3.10.5 Contact: "ALICE" Expires: 300 Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, INFO, SUBSCRIBE, NOTIFY, REFER, MESSAGE, OPTIONS Authorization: Digest username="ALICE", realm="asterisk", nonce="66b922c4", uri="sip:192.168.1.110", response="44c1e70f1ada95bd4355a7ab2c10c5d3", algorithm=MD5 Content-Length: 0 <-------------> [Oct 7 14:00:43] VERBOSE[24042] chan_sip.c: --- (13 headers 0 lines) --- [Oct 7 14:00:43] VERBOSE[24042] chan_sip.c: Sending to 192.168.1.103:52547 (no NAT) [Oct 7 14:00:43] VERBOSE[24042] chan_sip.c: -- Registered SIP 'ALICE' at 192.168.1.103:52547 [Oct 7 14:00:43] VERBOSE[24042] chan_sip.c: > Saved useragent "MicroSIP/3.10.5" for peer ALICE [Oct 7 14:00:43] VERBOSE[24042] chan_sip.c: <--- Transmitting (no NAT) to 192.168.1.103:52547 ---> SIP/2.0 200 OK Via: SIP/2.0/UDP 192.168.1.103:52547;branch=z9hG4bKPj8234f4e740cd42388c54399dbd402134;received=192.168.1.103;rport=52547 From: "ALICE" ;tag=4a4bde57b7cc42e5b6620117eea63f74 To: "ALICE" ;tag=as6304df45 Call-ID: 7e84e8590d6c4136bae9c3abca95c361 CSeq: 30596 REGISTER Server: Asterisk PBX 11.20.0-rc2 Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE Supported: replaces, timer Expires: 300 Contact: ;expires=300 Date: Wed, 07 Oct 2015 19:00:43 GMT Content-Length: 0 <------------> [Oct 7 14:00:43] VERBOSE[24042] chan_sip.c: Scheduling destruction of SIP dialog '7e84e8590d6c4136bae9c3abca95c361' in 32000 ms (Method: REGISTER) [Oct 7 14:00:48] VERBOSE[24042] chan_sip.c: <--- SIP read from UDP:192.168.1.103:52547 ---> INVITE sip:6002@192.168.1.110 SIP/2.0 Via: SIP/2.0/UDP 192.168.1.103:52547;rport;branch=z9hG4bKPj9dbeb522db714ade9402e8a98053985c Max-Forwards: 70 From: "ALICE" ;tag=66b41d2e18314f308d642a0eab41dbd8 To: Contact: "ALICE" Call-ID: a63992f287c642db9e8065ecdcf8668c CSeq: 8361 INVITE Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, INFO, SUBSCRIBE, NOTIFY, REFER, MESSAGE, OPTIONS Supported: replaces, 100rel, timer, norefersub Session-Expires: 1800 Min-SE: 90 User-Agent: MicroSIP/3.10.5 Content-Type: application/sdp Content-Length: 554 v=0 o=- 3653215251 3653215251 IN IP4 192.168.1.103 s=pjmedia b=AS:84 t=0 0 a=X-nat:0 m=audio 4000 RTP/AVP 117 107 9 8 0 110 96 97 3 18 101 c=IN IP4 192.168.1.103 b=TIAS:64000 a=rtcp:4001 IN IP4 192.168.1.103 a=sendrecv a=rtpmap:117 speex/16000 a=rtpmap:107 SILK/16000 a=rtpmap:9 G722/8000 a=rtpmap:8 PCMA/8000 a=rtpmap:0 PCMU/8000 a=rtpmap:110 speex/8000 a=rtpmap:96 SILK/8000 a=rtpmap:97 iLBC/8000 a=fmtp:97 mode=30 a=rtpmap:3 GSM/8000 a=rtpmap:18 G729/8000 a=fmtp:18 annexb=no a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 <-------------> [Oct 7 14:00:48] VERBOSE[24042] chan_sip.c: --- (15 headers 25 lines) --- [Oct 7 14:00:48] VERBOSE[24042] chan_sip.c: Sending to 192.168.1.103:52547 (no NAT) [Oct 7 14:00:48] VERBOSE[24042][C-00000000] chan_sip.c: Sending to 192.168.1.103:52547 (no NAT) [Oct 7 14:00:48] VERBOSE[24042][C-00000000] chan_sip.c: Using INVITE request as basis request - a63992f287c642db9e8065ecdcf8668c [Oct 7 14:00:48] VERBOSE[24042][C-00000000] chan_sip.c: Found peer 'ALICE' for 'ALICE' from 192.168.1.103:52547 [Oct 7 14:00:48] VERBOSE[24042][C-00000000] chan_sip.c: <--- Reliably Transmitting (no NAT) to 192.168.1.103:52547 ---> SIP/2.0 401 Unauthorized Via: SIP/2.0/UDP 192.168.1.103:52547;branch=z9hG4bKPj9dbeb522db714ade9402e8a98053985c;received=192.168.1.103;rport=52547 From: "ALICE" ;tag=66b41d2e18314f308d642a0eab41dbd8 To: ;tag=as4858214f Call-ID: a63992f287c642db9e8065ecdcf8668c CSeq: 8361 INVITE Server: Asterisk PBX 11.20.0-rc2 Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE Supported: replaces, timer WWW-Authenticate: Digest algorithm=MD5, realm="asterisk", nonce="68e2f686" Content-Length: 0 <------------> [Oct 7 14:00:48] VERBOSE[24042][C-00000000] chan_sip.c: Scheduling destruction of SIP dialog 'a63992f287c642db9e8065ecdcf8668c' in 32000 ms (Method: INVITE) [Oct 7 14:00:48] VERBOSE[24042] chan_sip.c: <--- SIP read from UDP:192.168.1.103:52547 ---> ACK sip:6002@192.168.1.110 SIP/2.0 Via: SIP/2.0/UDP 192.168.1.103:52547;rport;branch=z9hG4bKPj9dbeb522db714ade9402e8a98053985c Max-Forwards: 70 From: "ALICE" ;tag=66b41d2e18314f308d642a0eab41dbd8 To: ;tag=as4858214f Call-ID: a63992f287c642db9e8065ecdcf8668c CSeq: 8361 ACK Content-Length: 0 <-------------> [Oct 7 14:00:48] VERBOSE[24042] chan_sip.c: --- (8 headers 0 lines) --- [Oct 7 14:00:48] VERBOSE[24042] chan_sip.c: <--- SIP read from UDP:192.168.1.103:52547 ---> INVITE sip:6002@192.168.1.110 SIP/2.0 Via: SIP/2.0/UDP 192.168.1.103:52547;rport;branch=z9hG4bKPjab51a8c61a134fd19f730394a2827117 Max-Forwards: 70 From: "ALICE" ;tag=66b41d2e18314f308d642a0eab41dbd8 To: Contact: "ALICE" Call-ID: a63992f287c642db9e8065ecdcf8668c CSeq: 8362 INVITE Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, INFO, SUBSCRIBE, NOTIFY, REFER, MESSAGE, OPTIONS Supported: replaces, 100rel, timer, norefersub Session-Expires: 1800 Min-SE: 90 User-Agent: MicroSIP/3.10.5 Authorization: Digest username="ALICE", realm="asterisk", nonce="68e2f686", uri="sip:6002@192.168.1.110", response="a9017a6892da7e84941b482786206e19", algorithm=MD5 Content-Type: application/sdp Content-Length: 554 v=0 o=- 3653215251 3653215251 IN IP4 192.168.1.103 s=pjmedia b=AS:84 t=0 0 a=X-nat:0 m=audio 4000 RTP/AVP 117 107 9 8 0 110 96 97 3 18 101 c=IN IP4 192.168.1.103 b=TIAS:64000 a=rtcp:4001 IN IP4 192.168.1.103 a=sendrecv a=rtpmap:117 speex/16000 a=rtpmap:107 SILK/16000 a=rtpmap:9 G722/8000 a=rtpmap:8 PCMA/8000 a=rtpmap:0 PCMU/8000 a=rtpmap:110 speex/8000 a=rtpmap:96 SILK/8000 a=rtpmap:97 iLBC/8000 a=fmtp:97 mode=30 a=rtpmap:3 GSM/8000 a=rtpmap:18 G729/8000 a=fmtp:18 annexb=no a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 <-------------> [Oct 7 14:00:48] VERBOSE[24042] chan_sip.c: --- (16 headers 25 lines) --- [Oct 7 14:00:48] VERBOSE[24042][C-00000000] chan_sip.c: Sending to 192.168.1.103:52547 (no NAT) [Oct 7 14:00:48] VERBOSE[24042][C-00000000] chan_sip.c: Using INVITE request as basis request - a63992f287c642db9e8065ecdcf8668c [Oct 7 14:00:48] VERBOSE[24042][C-00000000] chan_sip.c: Found peer 'ALICE' for 'ALICE' from 192.168.1.103:52547 [Oct 7 14:00:48] VERBOSE[24042][C-00000000] netsock2.c: == Using SIP RTP CoS mark 5 [Oct 7 14:00:48] VERBOSE[24042][C-00000000] chan_sip.c: Found RTP audio format 117 [Oct 7 14:00:48] VERBOSE[24042][C-00000000] chan_sip.c: Found RTP audio format 107 [Oct 7 14:00:48] VERBOSE[24042][C-00000000] chan_sip.c: Found RTP audio format 9 [Oct 7 14:00:48] VERBOSE[24042][C-00000000] chan_sip.c: Found RTP audio format 8 [Oct 7 14:00:48] VERBOSE[24042][C-00000000] chan_sip.c: Found RTP audio format 0 [Oct 7 14:00:48] VERBOSE[24042][C-00000000] chan_sip.c: Found RTP audio format 110 [Oct 7 14:00:48] VERBOSE[24042][C-00000000] chan_sip.c: Found RTP audio format 96 [Oct 7 14:00:48] VERBOSE[24042][C-00000000] chan_sip.c: Found RTP audio format 97 [Oct 7 14:00:48] VERBOSE[24042][C-00000000] chan_sip.c: Found RTP audio format 3 [Oct 7 14:00:48] VERBOSE[24042][C-00000000] chan_sip.c: Found RTP audio format 18 [Oct 7 14:00:48] VERBOSE[24042][C-00000000] chan_sip.c: Found RTP audio format 101 [Oct 7 14:00:48] VERBOSE[24042][C-00000000] chan_sip.c: Found audio description format speex for ID 117 [Oct 7 14:00:48] VERBOSE[24042][C-00000000] chan_sip.c: Found audio description format SILK for ID 107 [Oct 7 14:00:48] VERBOSE[24042][C-00000000] chan_sip.c: Found audio description format G722 for ID 9 [Oct 7 14:00:48] VERBOSE[24042][C-00000000] chan_sip.c: Found audio description format PCMA for ID 8 [Oct 7 14:00:48] VERBOSE[24042][C-00000000] chan_sip.c: Found audio description format PCMU for ID 0 [Oct 7 14:00:48] VERBOSE[24042][C-00000000] chan_sip.c: Found audio description format speex for ID 110 [Oct 7 14:00:48] VERBOSE[24042][C-00000000] chan_sip.c: Found audio description format SILK for ID 96 [Oct 7 14:00:48] VERBOSE[24042][C-00000000] chan_sip.c: Found audio description format iLBC for ID 97 [Oct 7 14:00:48] VERBOSE[24042][C-00000000] chan_sip.c: Found audio description format GSM for ID 3 [Oct 7 14:00:48] VERBOSE[24042][C-00000000] chan_sip.c: Found audio description format G729 for ID 18 [Oct 7 14:00:48] VERBOSE[24042][C-00000000] chan_sip.c: Found audio description format telephone-event for ID 101 [Oct 7 14:00:48] VERBOSE[24042][C-00000000] chan_sip.c: Capabilities: us - (ulaw), peer - audio=(gsm|ulaw|alaw|g729|speex|speex16|ilbc|g722|silk8|silk16)/video=(nothing)/text=(nothing), combined - (ulaw) [Oct 7 14:00:48] VERBOSE[24042][C-00000000] chan_sip.c: Non-codec capabilities (dtmf): us - 0x1 (telephone-event|), peer - 0x1 (telephone-event|), combined - 0x1 (telephone-event|) [Oct 7 14:00:48] VERBOSE[24042][C-00000000] chan_sip.c: Peer audio RTP is at port 192.168.1.103:4000 [Oct 7 14:00:48] VERBOSE[24042][C-00000000] chan_sip.c: Looking for 6002 in from-internal (domain 192.168.1.110) [Oct 7 14:00:48] VERBOSE[24042][C-00000000] chan_sip.c: list_route: hop: [Oct 7 14:00:48] VERBOSE[24042][C-00000000] chan_sip.c: <--- Transmitting (no NAT) to 192.168.1.103:52547 ---> SIP/2.0 100 Trying Via: SIP/2.0/UDP 192.168.1.103:52547;branch=z9hG4bKPjab51a8c61a134fd19f730394a2827117;received=192.168.1.103;rport=52547 From: "ALICE" ;tag=66b41d2e18314f308d642a0eab41dbd8 To: Call-ID: a63992f287c642db9e8065ecdcf8668c CSeq: 8362 INVITE Server: Asterisk PBX 11.20.0-rc2 Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE Supported: replaces, timer Session-Expires: 1800;refresher=uas Contact: Content-Length: 0 <------------> [Oct 7 14:00:48] VERBOSE[24066][C-00000000] pbx.c: -- Executing [6002@from-internal:1] Dial("SIP/ALICE-00000000", "SIP/BOB") in new stack [Oct 7 14:00:49] VERBOSE[24066][C-00000000] netsock2.c: == Using SIP RTP CoS mark 5 [Oct 7 14:00:49] VERBOSE[24066][C-00000000] chan_sip.c: Audio is at 16364 [Oct 7 14:00:49] VERBOSE[24066][C-00000000] chan_sip.c: Adding codec 100003 (ulaw) to SDP [Oct 7 14:00:49] VERBOSE[24066][C-00000000] chan_sip.c: Adding non-codec 0x1 (telephone-event) to SDP [Oct 7 14:00:49] VERBOSE[24066][C-00000000] chan_sip.c: Reliably Transmitting (no NAT) to 192.168.1.102:5060: INVITE sip:BOB@192.168.1.102:5060;transport=TCP;ob SIP/2.0 Via: SIP/2.0/TCP 192.168.1.110:5060;branch=z9hG4bK18782c09 Max-Forwards: 70 From: "ALICE" ;tag=as5889a1c6 To: Contact: Call-ID: 50a669e01554d99470c313da2d0a17cd@192.168.1.110:5060 CSeq: 102 INVITE User-Agent: Asterisk PBX 11.20.0-rc2 Date: Wed, 07 Oct 2015 19:00:48 GMT Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE Supported: replaces, timer Content-Type: application/sdp Content-Length: 242 v=0 o=root 1055786350 1055786350 IN IP4 192.168.1.110 s=Asterisk PBX 11.20.0-rc2 c=IN IP4 192.168.1.110 t=0 0 m=audio 16364 RTP/AVP 0 101 a=rtpmap:0 PCMU/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=ptime:20 a=sendrecv --- [Oct 7 14:00:49] VERBOSE[24066][C-00000000] app_dial.c: -- Called SIP/BOB [Oct 7 14:00:49] VERBOSE[24067] chan_sip.c: <--- SIP read from TCP:192.168.1.102:5060 ---> SIP/2.0 100 Trying Via: SIP/2.0/TCP 192.168.1.110:5060;received=192.168.1.101;branch=z9hG4bK18782c09 Call-ID: 50a669e01554d99470c313da2d0a17cd@192.168.1.110:5060 From: "ALICE" ;tag=as5889a1c6 To: CSeq: 102 INVITE Content-Length: 0 <-------------> [Oct 7 14:00:49] VERBOSE[24067] chan_sip.c: --- (7 headers 0 lines) --- [Oct 7 14:00:49] VERBOSE[24067] chan_sip.c: <--- SIP read from TCP:192.168.1.102:5060 ---> SIP/2.0 180 ringing Via: SIP/2.0/TCP 192.168.1.110:5060;received=192.168.1.101;branch=z9hG4bK18782c09 Call-ID: 50a669e01554d99470c313da2d0a17cd@192.168.1.110:5060 From: "ALICE" ;tag=as5889a1c6 To: ;tag=KswzhDut31.vyGMFN1SJ4PJi-EAq3xs. CSeq: 102 INVITE Contact: "BOB<6002>" Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, SUBSCRIBE, NOTIFY, REFER, MESSAGE, OPTIONS User-Agent: Digium D40 1_4_2_0_63880 Content-Length: 0 <-------------> [Oct 7 14:00:49] VERBOSE[24067] chan_sip.c: --- (10 headers 0 lines) --- [Oct 7 14:00:49] VERBOSE[24067][C-00000000] chan_sip.c: list_route: hop: [Oct 7 14:00:49] VERBOSE[24066][C-00000000] app_dial.c: -- SIP/BOB-00000001 is ringing [Oct 7 14:00:49] VERBOSE[24066][C-00000000] chan_sip.c: <--- Transmitting (no NAT) to 192.168.1.103:52547 ---> SIP/2.0 180 Ringing Via: SIP/2.0/UDP 192.168.1.103:52547;branch=z9hG4bKPjab51a8c61a134fd19f730394a2827117;received=192.168.1.103;rport=52547 From: "ALICE" ;tag=66b41d2e18314f308d642a0eab41dbd8 To: ;tag=as39dc740e Call-ID: a63992f287c642db9e8065ecdcf8668c CSeq: 8362 INVITE Server: Asterisk PBX 11.20.0-rc2 Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE Supported: replaces, timer Session-Expires: 1800;refresher=uas Contact: Content-Length: 0 <------------> [Oct 7 14:00:57] VERBOSE[24042] chan_sip.c: Really destroying SIP dialog '65UD.JL4q8pJqMDwCLy1Qp43mOeaUcXL' Method: REGISTER [Oct 7 14:00:58] VERBOSE[24042] chan_sip.c: <--- SIP read from UDP:192.168.1.103:52547 ---> <-------------> [Oct 7 14:01:06] VERBOSE[24042] chan_sip.c: <--- SIP read from UDP:192.168.1.103:52547 ---> CANCEL sip:6002@192.168.1.110 SIP/2.0 Via: SIP/2.0/UDP 192.168.1.103:52547;rport;branch=z9hG4bKPjab51a8c61a134fd19f730394a2827117 Max-Forwards: 70 From: "ALICE" ;tag=66b41d2e18314f308d642a0eab41dbd8 To: Call-ID: a63992f287c642db9e8065ecdcf8668c CSeq: 8362 CANCEL User-Agent: MicroSIP/3.10.5 Content-Length: 0 <-------------> [Oct 7 14:01:06] VERBOSE[24042] chan_sip.c: --- (9 headers 0 lines) --- [Oct 7 14:01:06] VERBOSE[24042][C-00000000] chan_sip.c: Sending to 192.168.1.103:52547 (no NAT) [Oct 7 14:01:06] VERBOSE[24042][C-00000000] chan_sip.c: <--- Reliably Transmitting (no NAT) to 192.168.1.103:52547 ---> SIP/2.0 487 Request Terminated Via: SIP/2.0/UDP 192.168.1.103:52547;branch=z9hG4bKPjab51a8c61a134fd19f730394a2827117;received=192.168.1.103;rport=52547 From: "ALICE" ;tag=66b41d2e18314f308d642a0eab41dbd8 To: ;tag=as39dc740e Call-ID: a63992f287c642db9e8065ecdcf8668c CSeq: 8362 INVITE Server: Asterisk PBX 11.20.0-rc2 Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE Supported: replaces, timer Content-Length: 0 <------------> [Oct 7 14:01:06] VERBOSE[24042][C-00000000] chan_sip.c: <--- Transmitting (no NAT) to 192.168.1.103:52547 ---> SIP/2.0 200 OK Via: SIP/2.0/UDP 192.168.1.103:52547;branch=z9hG4bKPjab51a8c61a134fd19f730394a2827117;received=192.168.1.103;rport=52547 From: "ALICE" ;tag=66b41d2e18314f308d642a0eab41dbd8 To: ;tag=as39dc740e Call-ID: a63992f287c642db9e8065ecdcf8668c CSeq: 8362 CANCEL Server: Asterisk PBX 11.20.0-rc2 Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE Supported: replaces, timer Content-Length: 0 <------------> [Oct 7 14:01:06] VERBOSE[24066][C-00000000] chan_sip.c: Scheduling destruction of SIP dialog '50a669e01554d99470c313da2d0a17cd@192.168.1.110:5060' in 32000 ms (Method: INVITE) [Oct 7 14:01:06] VERBOSE[24066][C-00000000] chan_sip.c: Reliably Transmitting (no NAT) to 192.168.1.102:5060: CANCEL sip:BOB@192.168.1.102:5060;transport=TCP;ob SIP/2.0 Via: SIP/2.0/TCP 192.168.1.110:5060;branch=z9hG4bK18782c09 Max-Forwards: 70 From: "ALICE" ;tag=as5889a1c6 To: Call-ID: 50a669e01554d99470c313da2d0a17cd@192.168.1.110:5060 CSeq: 102 CANCEL User-Agent: Asterisk PBX 11.20.0-rc2 Content-Length: 0 --- [Oct 7 14:01:06] VERBOSE[24066][C-00000000] chan_sip.c: Scheduling destruction of SIP dialog '50a669e01554d99470c313da2d0a17cd@192.168.1.110:5060' in 32000 ms (Method: INVITE) [Oct 7 14:01:06] VERBOSE[24066][C-00000000] pbx.c: == Spawn extension (from-internal, 6002, 1) exited non-zero on 'SIP/ALICE-00000000' [Oct 7 14:01:06] VERBOSE[24042] chan_sip.c: <--- SIP read from UDP:192.168.1.103:52547 ---> ACK sip:6002@192.168.1.110 SIP/2.0 Via: SIP/2.0/UDP 192.168.1.103:52547;rport;branch=z9hG4bKPjab51a8c61a134fd19f730394a2827117 Max-Forwards: 70 From: "ALICE" ;tag=66b41d2e18314f308d642a0eab41dbd8 To: ;tag=as39dc740e Call-ID: a63992f287c642db9e8065ecdcf8668c CSeq: 8362 ACK Content-Length: 0 <-------------> [Oct 7 14:01:06] VERBOSE[24042] chan_sip.c: --- (8 headers 0 lines) --- [Oct 7 14:01:06] VERBOSE[24042] chan_sip.c: Really destroying SIP dialog 'a63992f287c642db9e8065ecdcf8668c' Method: ACK [Oct 7 14:01:06] VERBOSE[24067] chan_sip.c: <--- SIP read from TCP:192.168.1.102:5060 ---> SIP/2.0 200 OK Via: SIP/2.0/TCP 192.168.1.110:5060;received=192.168.1.101;branch=z9hG4bK18782c09 Call-ID: 50a669e01554d99470c313da2d0a17cd@192.168.1.110:5060 From: "ALICE" ;tag=as5889a1c6 To: ;tag=KswzhDut31.vyGMFN1SJ4PJi-EAq3xs. CSeq: 102 CANCEL Content-Length: 0 <-------------> [Oct 7 14:01:06] VERBOSE[24067] chan_sip.c: --- (7 headers 0 lines) --- [Oct 7 14:01:06] VERBOSE[24067] chan_sip.c: <--- SIP read from TCP:192.168.1.102:5060 ---> SIP/2.0 487 Request Terminated Via: SIP/2.0/TCP 192.168.1.110:5060;received=192.168.1.101;branch=z9hG4bK18782c09 Call-ID: 50a669e01554d99470c313da2d0a17cd@192.168.1.110:5060 From: "ALICE" ;tag=as5889a1c6 To: ;tag=KswzhDut31.vyGMFN1SJ4PJi-EAq3xs. CSeq: 102 INVITE Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, SUBSCRIBE, NOTIFY, REFER, MESSAGE, OPTIONS User-Agent: Digium D40 1_4_2_0_63880 Content-Length: 0 <-------------> [Oct 7 14:01:06] VERBOSE[24067] chan_sip.c: --- (9 headers 0 lines) --- [Oct 7 14:01:06] VERBOSE[24067][C-00000000] chan_sip.c: Transmitting (no NAT) to 192.168.1.102:5060: ACK sip:BOB@192.168.1.102:5060;transport=TCP;ob SIP/2.0 Via: SIP/2.0/TCP 192.168.1.110:5060;branch=z9hG4bK18782c09 Max-Forwards: 70 From: "ALICE" ;tag=as5889a1c6 To: ;tag=KswzhDut31.vyGMFN1SJ4PJi-EAq3xs. Contact: Call-ID: 50a669e01554d99470c313da2d0a17cd@192.168.1.110:5060 CSeq: 102 ACK User-Agent: Asterisk PBX 11.20.0-rc2 Content-Length: 0 --- [Oct 7 14:01:06] VERBOSE[24067][C-00000000] chan_sip.c: Scheduling destruction of SIP dialog '50a669e01554d99470c313da2d0a17cd@192.168.1.110:5060' in 32000 ms (Method: INVITE) [Oct 7 14:01:11] VERBOSE[24042] chan_sip.c: Really destroying SIP dialog 'cafb29869720429e924b3a6337b7e59b' Method: REGISTER [Oct 7 14:01:13] VERBOSE[24042] chan_sip.c: <--- SIP read from UDP:192.168.1.103:52547 ---> <-------------> [Oct 7 14:01:14] VERBOSE[24011] asterisk.c: Beginning asterisk shutdown.... [Oct 7 14:01:14] VERBOSE[24011] asterisk.c: Asterisk cleanly ending (0). [Oct 7 14:01:14] VERBOSE[24011] asterisk.c: Executing last minute cleanups [Oct 7 14:01:14] VERBOSE[24011] res_musiconhold.c: == Destroying musiconhold processes [Oct 7 14:01:14] VERBOSE[24011] manager.c: == Manager unregistered action DBGet [Oct 7 14:01:14] VERBOSE[24011] manager.c: == Manager unregistered action DBPut [Oct 7 14:01:14] VERBOSE[24011] manager.c: == Manager unregistered action DBDel [Oct 7 14:01:14] VERBOSE[24011] manager.c: == Manager unregistered action DBDelTree