Aug 29 19:28:33 VERBOSE[21375] logger.c: Asterisk Event Logger restarted Aug 29 19:28:43 VERBOSE[21375] logger.c: <-- SIP read from 10.1.1.200:5060: SUBSCRIBE sip:2000@10.1.1.48:5060 SIP/2.0 Via: SIP/2.0/UDP 10.1.1.200;branch=z9hG4bKf84f41b48C702CEB From: "Wilson" ;tag=8BA3B32A-DF94042F To: CSeq: 1 SUBSCRIBE Call-ID: f0c2fcb6-62cd1908-1d0cd005@10.1.1.200 Contact: Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, INFO, MESSAGE, SUBSCRIBE, NOTIFY, PRACK, UPDATE, REFER Event: presence User-Agent: PolycomSoundPointIP-SPIP_600-UA/1.5.2.0054 Max-Forwards: 70 Expires: 3600 Content-Length: 0 Aug 29 19:28:43 VERBOSE[21375] logger.c: --- (13 headers 0 lines)Aug 29 19:28:43 VERBOSE[21375] logger.c: --- (13 headers 0 lines)--- Aug 29 19:28:43 VERBOSE[21375] logger.c: Using latest SUBSCRIBE request as basis request Aug 29 19:28:43 VERBOSE[21375] logger.c: Sending to 10.1.1.200 : 5060 (non-NAT) Aug 29 19:28:43 VERBOSE[21375] logger.c: Found peer 'wilson-polycom' Aug 29 19:28:43 VERBOSE[21375] logger.c: Transmitting (no NAT) to 10.1.1.200:5060: SIP/2.0 401 Unauthorized Via: SIP/2.0/UDP 10.1.1.200;branch=z9hG4bKf84f41b48C702CEB From: "Wilson" ;tag=8BA3B32A-DF94042F To: ;tag=as1a6c69a7 Call-ID: f0c2fcb6-62cd1908-1d0cd005@10.1.1.200 CSeq: 1 SUBSCRIBE User-Agent: Asterisk PBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Contact: WWW-Authenticate: Digest realm="asterisk", nonce="36c06f25" Content-Length: 0 --- Aug 29 19:28:43 VERBOSE[21375] logger.c: Scheduling destruction of call 'f0c2fcb6-62cd1908-1d0cd005@10.1.1.200' in 15000 ms Aug 29 19:28:43 VERBOSE[21375] logger.c: <-- SIP read from 10.1.1.200:5060: SUBSCRIBE sip:2000@10.1.1.48:5060 SIP/2.0 Via: SIP/2.0/UDP 10.1.1.200;branch=z9hG4bK97463be9E9199B9C From: "Wilson" ;tag=8BA3B32A-DF94042F To: CSeq: 2 SUBSCRIBE Call-ID: f0c2fcb6-62cd1908-1d0cd005@10.1.1.200 Contact: Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, INFO, MESSAGE, SUBSCRIBE, NOTIFY, PRACK, UPDATE, REFER Event: presence User-Agent: PolycomSoundPointIP-SPIP_600-UA/1.5.2.0054 Authorization: Digest username="wilson-polycom", realm="asterisk", nonce="36c06f25", uri="sip:2000@10.1.1.48:5060", response="b41cc713aedd140580c78b9695e9dbbc", algorithm=MD5 Max-Forwards: 70 Expires: 3600 Content-Length: 0 Aug 29 19:28:43 VERBOSE[21375] logger.c: --- (14 headers 0 lines)Aug 29 19:28:43 VERBOSE[21375] logger.c: --- (14 headers 0 lines)--- Aug 29 19:28:43 DEBUG[21375] chan_sip.c: Got a re-subscribe on existing subscription f0c2fcb6-62cd1908-1d0cd005@10.1.1.200 Aug 29 19:28:43 VERBOSE[21375] logger.c: Using latest SUBSCRIBE request as basis request Aug 29 19:28:43 VERBOSE[21375] logger.c: Sending to 10.1.1.200 : 5060 (non-NAT) Aug 29 19:28:43 VERBOSE[21375] logger.c: Found peer 'wilson-polycom' Aug 29 19:28:43 VERBOSE[21375] logger.c: Looking for 2000 in from-sip Aug 29 19:28:43 DEBUG[21375] chan_sip.c: Adding subscription for extension 2000 context from-sip for peer wilson-polycom Aug 29 19:28:43 VERBOSE[21375] logger.c: Transmitting (no NAT) to 10.1.1.200:5060: SIP/2.0 200 OK Via: SIP/2.0/UDP 10.1.1.200;branch=z9hG4bK97463be9E9199B9C From: "Wilson" ;tag=8BA3B32A-DF94042F To: ;tag=as67de5961 Call-ID: f0c2fcb6-62cd1908-1d0cd005@10.1.1.200 CSeq: 2 SUBSCRIBE User-Agent: Asterisk PBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Expires: 3600 Contact: ;expires=3600 Content-Length: 0 --- Aug 29 19:28:43 VERBOSE[21375] logger.c: Scheduling destruction of call 'f0c2fcb6-62cd1908-1d0cd005@10.1.1.200' in 3610000 ms Aug 29 19:28:43 VERBOSE[21375] logger.c: Reliably Transmitting (no NAT) to 10.1.1.200:5060: NOTIFY sip:wilson-polycom@10.1.1.48 SIP/2.0 Via: SIP/2.0/UDP 10.1.1.48:5060;branch=z9hG4bK31dc74c9 From: ;tag=as67de5961 To: "Wilson" ;tag=8BA3B32A-DF94042F Contact: Call-ID: f0c2fcb6-62cd1908-1d0cd005@10.1.1.200 CSeq: 102 NOTIFY User-Agent: Asterisk PBX Event: - Content-Type: unknown Subscription-State: terminated;reason=timeout Content-Length: 1 ã --- Aug 29 19:28:43 VERBOSE[21375] logger.c: Reliably Transmitting (no NAT) to 10.1.1.200:5060: NOTIFY sip:wilson-polycom@10.1.1.48 SIP/2.0 Via: SIP/2.0/UDP 10.1.1.48:5060;branch=z9hG4bK2eef3e92 From: ;tag=as67de5961 To: "Wilson" ;tag=8BA3B32A-DF94042F Contact: Call-ID: f0c2fcb6-62cd1908-1d0cd005@10.1.1.200 CSeq: 103 NOTIFY User-Agent: Asterisk PBX Event: - Content-Type: unknown Subscription-State: active Content-Length: 26 Û·eâÛ·âÛ·ÃâÛ·ÒâÛ·þâÛ·ãÛ· --- Aug 29 19:28:43 VERBOSE[21375] logger.c: <-- SIP read from 10.1.1.200:5060: SIP/2.0 200 OK Via: SIP/2.0/UDP 10.1.1.48:5060;branch=z9hG4bK31dc74c9 From: ;tag=as67de5961 To: "Wilson" ;tag=8BA3B32A-DF94042F CSeq: 102 NOTIFY Call-ID: f0c2fcb6-62cd1908-1d0cd005@10.1.1.200 Contact: Event: - User-Agent: PolycomSoundPointIP-SPIP_600-UA/1.5.2.0054 Content-Length: 0 Aug 29 19:28:43 VERBOSE[21375] logger.c: --- (10 headers 0 lines)Aug 29 19:28:43 VERBOSE[21375] logger.c: --- (10 headers 0 lines)--- Aug 29 19:28:43 DEBUG[21375] chan_sip.c: Stopping retransmission on 'f0c2fcb6-62cd1908-1d0cd005@10.1.1.200' of Request 102: Match Found Aug 29 19:28:43 VERBOSE[21375] logger.c: Response message NOTIFY arrived Aug 29 19:28:43 VERBOSE[21375] logger.c: <-- SIP read from 10.1.1.200:5060: SIP/2.0 200 OK Via: SIP/2.0/UDP 10.1.1.48:5060;branch=z9hG4bK2eef3e92 From: ;tag=as67de5961 To: "Wilson" ;tag=8BA3B32A-DF94042F CSeq: 103 NOTIFY Call-ID: f0c2fcb6-62cd1908-1d0cd005@10.1.1.200 Contact: Event: - User-Agent: PolycomSoundPointIP-SPIP_600-UA/1.5.2.0054 Content-Length: 0 Aug 29 19:28:43 VERBOSE[21375] logger.c: --- (10 headers 0 lines)Aug 29 19:28:43 VERBOSE[21375] logger.c: --- (10 headers 0 lines)--- Aug 29 19:28:43 DEBUG[21375] chan_sip.c: Stopping retransmission on 'f0c2fcb6-62cd1908-1d0cd005@10.1.1.200' of Request 103: Match Found Aug 29 19:28:43 VERBOSE[21375] logger.c: Response message NOTIFY arrived