Sep 9 10:07:21 DEBUG[16644] chan_sip.c: **** Received SUBSCRIBE (10) - Command in SIP SUBSCRIBE Sep 9 10:08:08 VERBOSE[16644] logger.c: <-- SIP read from 10.1.1.200:5060: SUBSCRIBE sip:2000@10.1.1.48:5060 SIP/2.0Via: SIP/2.0/UDP 10.1.1.200;branch=z9hG4bK92d42fca35BD5BA1From: "Wilson" ;tag=3E850BCC-20B703CDTo: CSeq: 1 SUBSCRIBECall-ID: 7928dc00-5949e356-88e662a7@10.1.1.200Contact: Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, INFO, MESSAGE, SUBSCRIBE, NOTIFY, PRACK, UPDATE, REFEREvent: presenceUser-Agent: PolycomSoundPointIP-SPIP_600-UA/1.5.2.0054Max-Forwards: 70Expires: 3600Content-Length: 0 Sep 9 10:08:08 DEBUG[16644] chan_sip.c: Header: SUBSCRIBE sip:2000@10.1.1.48:5060 SIP/2.0 (41) Sep 9 10:08:08 DEBUG[16644] chan_sip.c: Header: Via: SIP/2.0/UDP 10.1.1.200;branch=z9hG4bK92d42fca35BD5BA1 (58) Sep 9 10:08:08 DEBUG[16644] chan_sip.c: Header: From: "Wilson" ;tag=3E850BCC-20B703CD (67) Sep 9 10:08:08 DEBUG[16644] chan_sip.c: Header: To: (24) Sep 9 10:08:08 DEBUG[16644] chan_sip.c: Header: CSeq: 1 SUBSCRIBE (17) Sep 9 10:08:08 DEBUG[16644] chan_sip.c: Header: Call-ID: 7928dc00-5949e356-88e662a7@10.1.1.200 (46) Sep 9 10:08:08 DEBUG[16644] chan_sip.c: Header: Contact: (40) Sep 9 10:08:08 DEBUG[16644] chan_sip.c: Header: Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, INFO, MESSAGE, SUBSCRIBE, NOTIFY, PRACK, UPDATE, REFER (96) Sep 9 10:08:08 DEBUG[16644] chan_sip.c: Header: Event: presence (15) Sep 9 10:08:08 DEBUG[16644] chan_sip.c: Header: User-Agent: PolycomSoundPointIP-SPIP_600-UA/1.5.2.0054 (54) Sep 9 10:08:08 DEBUG[16644] chan_sip.c: Header: Max-Forwards: 70 (16) Sep 9 10:08:08 DEBUG[16644] chan_sip.c: Header: Expires: 3600 (13) Sep 9 10:08:08 DEBUG[16644] chan_sip.c: Header: Content-Length: 0 (17) Sep 9 10:08:08 DEBUG[16644] chan_sip.c: Header: (0) Sep 9 10:08:08 VERBOSE[16644] logger.c: --- (13 headers 0 lines)Sep 9 10:08:08 VERBOSE[16644] logger.c: --- (13 headers 0 lines)--- Sep 9 10:08:08 DEBUG[16644] chan_sip.c: Allocating new SIP dialog for 7928dc00-5949e356-88e662a7@10.1.1.200 - SUBSCRIBE (No RTP) Sep 9 10:08:08 DEBUG[16644] chan_sip.c: **** Received SUBSCRIBE (10) - Command in SIP SUBSCRIBE Sep 9 10:08:08 VERBOSE[16644] logger.c: Using latest SUBSCRIBE request as basis request Sep 9 10:08:08 VERBOSE[16644] logger.c: Sending to 10.1.1.200 : 5060 (non-NAT) Sep 9 10:08:08 VERBOSE[16644] logger.c: Found peer 'wilson-polycom' Sep 9 10:08:08 VERBOSE[16644] logger.c: Transmitting (no NAT) to 10.1.1.200:5060: SIP/2.0 401 UnauthorizedVia: SIP/2.0/UDP 10.1.1.200;branch=z9hG4bK92d42fca35BD5BA1From: "Wilson" ;tag=3E850BCC-20B703CDTo: ;tag=as211d38f3Call-ID: 7928dc00-5949e356-88e662a7@10.1.1.200CSeq: 1 SUBSCRIBEUser-Agent: Asterisk PBXAllow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFYContact: WWW-Authenticate: Digest realm="asterisk", nonce="0aea62af" Content-Length: 0 --- Sep 9 10:08:08 VERBOSE[16644] logger.c: Scheduling destruction of call '7928dc00-5949e356-88e662a7@10.1.1.200' in 15000 ms Sep 9 10:08:08 VERBOSE[16644] logger.c: <-- SIP read from 10.1.1.200:5060: SUBSCRIBE sip:2000@10.1.1.48:5060 SIP/2.0Via: SIP/2.0/UDP 10.1.1.200;branch=z9hG4bK35d75513EB263B62From: "Wilson" ;tag=3E850BCC-20B703CDTo: CSeq: 2 SUBSCRIBECall-ID: 7928dc00-5949e356-88e662a7@10.1.1.200Contact: Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, INFO, MESSAGE, SUBSCRIBE, NOTIFY, PRACK, UPDATE, REFEREvent: presenceUser-Agent: PolycomSoundPointIP-SPIP_600-UA/1.5.2.0054Authorization: Digest username="wilson-polycom", realm="asterisk", nonce="0aea62af", uri="sip:2000@10.1.1.48:5060", response="adfc6e097dec38a19ac7321cb0be0fac", algorithm=MD5Max-Forwards: 70Expires: 3600Content-Length: 0 Sep 9 10:08:08 DEBUG[16644] chan_sip.c: Header: SUBSCRIBE sip:2000@10.1.1.48:5060 SIP/2.0 (41) Sep 9 10:08:08 DEBUG[16644] chan_sip.c: Header: Via: SIP/2.0/UDP 10.1.1.200;branch=z9hG4bK35d75513EB263B62 (58) Sep 9 10:08:08 DEBUG[16644] chan_sip.c: Header: From: "Wilson" ;tag=3E850BCC-20B703CD (67) Sep 9 10:08:08 DEBUG[16644] chan_sip.c: Header: To: (24) Sep 9 10:08:08 DEBUG[16644] chan_sip.c: Header: CSeq: 2 SUBSCRIBE (17) Sep 9 10:08:08 DEBUG[16644] chan_sip.c: Header: Call-ID: 7928dc00-5949e356-88e662a7@10.1.1.200 (46) Sep 9 10:08:08 DEBUG[16644] chan_sip.c: Header: Contact: (40) Sep 9 10:08:08 DEBUG[16644] chan_sip.c: Header: Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, INFO, MESSAGE, SUBSCRIBE, NOTIFY, PRACK, UPDATE, REFER (96) Sep 9 10:08:08 DEBUG[16644] chan_sip.c: Header: Event: presence (15) Sep 9 10:08:08 DEBUG[16644] chan_sip.c: Header: User-Agent: PolycomSoundPointIP-SPIP_600-UA/1.5.2.0054 (54) Sep 9 10:08:08 DEBUG[16644] chan_sip.c: Header: Authorization: Digest username="wilson-polycom", realm="asterisk", nonce="0aea62af", uri="sip:2000@10.1.1.48:5060", response="adfc6e097dec38a19ac7321cb0be0fac", algorithm=MD5 (174) Sep 9 10:08:08 DEBUG[16644] chan_sip.c: Header: Max-Forwards: 70 (16) Sep 9 10:08:08 DEBUG[16644] chan_sip.c: Header: Expires: 3600 (13) Sep 9 10:08:08 DEBUG[16644] chan_sip.c: Header: Content-Length: 0 (17) Sep 9 10:08:08 DEBUG[16644] chan_sip.c: Header: (0) Sep 9 10:08:08 VERBOSE[16644] logger.c: --- (14 headers 0 lines)Sep 9 10:08:08 VERBOSE[16644] logger.c: --- (14 headers 0 lines)--- Sep 9 10:08:08 DEBUG[16644] chan_sip.c: **** Received SUBSCRIBE (10) - Command in SIP SUBSCRIBE Sep 9 10:08:08 DEBUG[16644] chan_sip.c: Got a re-subscribe on existing subscription 7928dc00-5949e356-88e662a7@10.1.1.200 Sep 9 10:08:08 VERBOSE[16644] logger.c: Found peer 'wilson-polycom' Sep 9 10:08:08 VERBOSE[16644] logger.c: Looking for 2000 in from-sip Sep 9 10:08:08 DEBUG[16644] chan_sip.c: Adding subscription for extension 2000 context from-sip for peer wilson-polycom Sep 9 10:08:08 VERBOSE[16644] logger.c: Transmitting (no NAT) to 10.1.1.200:5060: SIP/2.0 200 OKVia: SIP/2.0/UDP 10.1.1.200;branch=z9hG4bK35d75513EB263B62From: "Wilson" ;tag=3E850BCC-20B703CDTo: ;tag=as211d38f3Call-ID: 7928dc00-5949e356-88e662a7@10.1.1.200CSeq: 2 SUBSCRIBEUser-Agent: Asterisk PBXAllow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFYExpires: 3600Contact: ;expires=3600Content-Length: 0 --- Sep 9 10:08:08 VERBOSE[16644] logger.c: Scheduling destruction of call '7928dc00-5949e356-88e662a7@10.1.1.200' in 3610000 ms Sep 9 10:08:08 DEBUG[16644] chan_sip.c: Checking device state for peer scott-polycom Sep 9 10:08:08 DEBUG[16644] chan_sip.c: Checking device state for peer scott-polycom Sep 9 10:08:08 VERBOSE[16644] logger.c: Reliably Transmitting (no NAT) to 10.1.1.200:5060: NOTIFY sip:wilson-polycom@10.1.1.48 SIP/2.0Via: SIP/2.0/UDP 10.1.1.48:5060;branch=z9hG4bK0d23c0fdFrom: ;tag=as211d38f3To: "Wilson" ;tag=3E850BCC-20B703CDContact: Call-ID: 7928dc00-5949e356-88e662a7@10.1.1.200CSeq: 102 NOTIFYUser-Agent: Asterisk PBXEvent: presenceContent-Type: application/xpidf+xmlSubscription-State: activeContent-Length: 348
--- Sep 9 10:08:08 DEBUG[16644] chan_sip.c: *** SIP TIMER: Initalizing retransmit timer on packet: Id #1028 Sep 9 10:08:08 VERBOSE[16644] logger.c: <-- SIP read from 10.1.1.200:5060: SIP/2.0 200 OKVia: SIP/2.0/UDP 10.1.1.48:5060;branch=z9hG4bK0d23c0fdFrom: ;tag=as211d38f3To: "Wilson" ;tag=3E850BCC-20B703CDCSeq: 102 NOTIFYCall-ID: 7928dc00-5949e356-88e662a7@10.1.1.200Contact: Event: presenceUser-Agent: PolycomSoundPointIP-SPIP_600-UA/1.5.2.0054Content-Length: 0 Sep 9 10:08:08 DEBUG[16644] chan_sip.c: Header: SIP/2.0 200 OK (14) Sep 9 10:08:08 DEBUG[16644] chan_sip.c: Header: Via: SIP/2.0/UDP 10.1.1.48:5060;branch=z9hG4bK0d23c0fd (54) Sep 9 10:08:08 DEBUG[16644] chan_sip.c: Header: From: ;tag=as211d38f3 (41) Sep 9 10:08:08 DEBUG[16644] chan_sip.c: Header: To: "Wilson" ;tag=3E850BCC-20B703CD (65) Sep 9 10:08:08 DEBUG[16644] chan_sip.c: Header: CSeq: 102 NOTIFY (16) Sep 9 10:08:08 DEBUG[16644] chan_sip.c: Header: Call-ID: 7928dc00-5949e356-88e662a7@10.1.1.200 (46) Sep 9 10:08:08 DEBUG[16644] chan_sip.c: Header: Contact: (40) Sep 9 10:08:08 DEBUG[16644] chan_sip.c: Header: Event: presence (15) Sep 9 10:08:08 DEBUG[16644] chan_sip.c: Header: User-Agent: PolycomSoundPointIP-SPIP_600-UA/1.5.2.0054 (54) Sep 9 10:08:08 DEBUG[16644] chan_sip.c: Header: Content-Length: 0 (17) Sep 9 10:08:08 DEBUG[16644] chan_sip.c: Header: (0) Sep 9 10:08:08 VERBOSE[16644] logger.c: --- (10 headers 0 lines)Sep 9 10:08:08 VERBOSE[16644] logger.c: --- (10 headers 0 lines)--- Sep 9 10:08:08 DEBUG[16644] chan_sip.c: ** SIP TIMER: Cancelling retransmit of packet (reply received) Retransid #1028 Sep 9 10:08:08 DEBUG[16644] chan_sip.c: Stopping retransmission on '7928dc00-5949e356-88e662a7@10.1.1.200' of Request 102: Match Found Sep 9 10:08:08 VERBOSE[16644] logger.c: SIP Response message for INCOMING dialog NOTIFY arrived Sep 9 10:11:21 VERBOSE[16644] logger.c: <-- SIP read from 10.1.1.200:5060: REGISTER sip:10.1.1.48:5060 SIP/2.0Via: SIP/2.0/UDP 10.1.1.200;branch=z9hG4bK72548c79F94D7818From: "Wilson" ;tag=637E8B9F-FF6C90B8To: CSeq: 67 REGISTERCall-ID: 56c784b3-15d91a19-2fc96602@10.1.1.200Contact: ;methods="INVITE, ACK, BYE, CANCEL, OPTIONS, INFO, MESSAGE, SUBSCRIBE, NOTIFY, PRACK, UPDATE, REFER"User-Agent: PolycomSoundPointIP-SPIP_600-UA/1.5.2.0054Authorization: Digest username="wilson-polycom", realm="asterisk", nonce="6e9f5795", uri="sip:10.1.1.48:5060", response="83b2383580a9431b7c25bcec31a60e9c", algorithm=MD5Max-Forwards: 70Expires: 3600Content-Length: 0 Sep 9 10:11:21 DEBUG[16644] chan_sip.c: Header: REGISTER sip:10.1.1.48:5060 SIP/2.0 (35) Sep 9 10:11:21 DEBUG[16644] chan_sip.c: Header: Via: SIP/2.0/UDP 10.1.1.200;branch=z9hG4bK72548c79F94D7818 (58) Sep 9 10:11:21 DEBUG[16644] chan_sip.c: Header: From: "Wilson" ;tag=637E8B9F-FF6C90B8 (67) Sep 9 10:11:21 DEBUG[16644] chan_sip.c: Header: To: (34) Sep 9 10:11:21 DEBUG[16644] chan_sip.c: Header: CSeq: 67 REGISTER (17) Sep 9 10:11:21 DEBUG[16644] chan_sip.c: Header: Call-ID: 56c784b3-15d91a19-2fc96602@10.1.1.200 (46) Sep 9 10:11:21 DEBUG[16644] chan_sip.c: Header: Contact: ;methods="INVITE, ACK, BYE, CANCEL, OPTIONS, INFO, MESSAGE, SUBSCRIBE, NOTIFY, PRACK, UPDATE, REFER" (140) Sep 9 10:11:21 DEBUG[16644] chan_sip.c: Header: User-Agent: PolycomSoundPointIP-SPIP_600-UA/1.5.2.0054 (54) Sep 9 10:11:21 DEBUG[16644] chan_sip.c: Header: Authorization: Digest username="wilson-polycom", realm="asterisk", nonce="6e9f5795", uri="sip:10.1.1.48:5060", response="83b2383580a9431b7c25bcec31a60e9c", algorithm=MD5 (169) Sep 9 10:11:21 DEBUG[16644] chan_sip.c: Header: Max-Forwards: 70 (16) Sep 9 10:11:21 DEBUG[16644] chan_sip.c: Header: Expires: 3600 (13) Sep 9 10:11:21 DEBUG[16644] chan_sip.c: Header: Content-Length: 0 (17) Sep 9 10:11:21 DEBUG[16644] chan_sip.c: Header: (0) Sep 9 10:11:21 VERBOSE[16644] logger.c: --- (12 headers 0 lines)Sep 9 10:11:21 VERBOSE[16644] logger.c: --- (12 headers 0 lines)--- Sep 9 10:11:21 DEBUG[16644] chan_sip.c: Allocating new SIP dialog for 56c784b3-15d91a19-2fc96602@10.1.1.200 - REGISTER (No RTP) Sep 9 10:11:21 DEBUG[16644] chan_sip.c: **** Received REGISTER (2) - Command in SIP REGISTER Sep 9 10:11:21 VERBOSE[16644] logger.c: Using latest REGISTER request as basis request Sep 9 10:11:21 VERBOSE[16644] logger.c: Sending to 10.1.1.200 : 5060 (non-NAT) Sep 9 10:11:21 VERBOSE[16644] logger.c: Transmitting (no NAT) to 10.1.1.200:5060: SIP/2.0 100 TryingVia: SIP/2.0/UDP 10.1.1.200;branch=z9hG4bK72548c79F94D7818From: "Wilson" ;tag=637E8B9F-FF6C90B8To: Call-ID: 56c784b3-15d91a19-2fc96602@10.1.1.200CSeq: 67 REGISTERUser-Agent: Asterisk PBXAllow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFYContact: Content-Length: 0 --- Sep 9 10:11:21 VERBOSE[16644] logger.c: Transmitting (no NAT) to 10.1.1.200:5060: SIP/2.0 401 UnauthorizedVia: SIP/2.0/UDP 10.1.1.200;branch=z9hG4bK72548c79F94D7818From: "Wilson" ;tag=637E8B9F-FF6C90B8To: ;tag=as44927bf8Call-ID: 56c784b3-15d91a19-2fc96602@10.1.1.200CSeq: 67 REGISTERUser-Agent: Asterisk PBXAllow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFYContact: WWW-Authenticate: Digest realm="asterisk", nonce="44377a59" Content-Length: 0 --- Sep 9 10:11:21 VERBOSE[16644] logger.c: Scheduling destruction of call '56c784b3-15d91a19-2fc96602@10.1.1.200' in 15000 ms Sep 9 10:11:21 VERBOSE[16644] logger.c: <-- SIP read from 10.1.1.200:5060: REGISTER sip:10.1.1.48:5060 SIP/2.0Via: SIP/2.0/UDP 10.1.1.200;branch=z9hG4bK46ffff24E4E7EEFrom: "Wilson" ;tag=637E8B9F-FF6C90B8To: CSeq: 68 REGISTERCall-ID: 56c784b3-15d91a19-2fc96602@10.1.1.200Contact: ;methods="INVITE, ACK, BYE, CANCEL, OPTIONS, INFO, MESSAGE, SUBSCRIBE, NOTIFY, PRACK, UPDATE, REFER"User-Agent: PolycomSoundPointIP-SPIP_600-UA/1.5.2.0054Authorization: Digest username="wilson-polycom", realm="asterisk", nonce="44377a59", uri="sip:10.1.1.48:5060", response="85c50fe69be0ec277c4c8bdbe4b421a5", algorithm=MD5Max-Forwards: 70Expires: 3600Content-Length: 0 Sep 9 10:11:21 DEBUG[16644] chan_sip.c: Header: REGISTER sip:10.1.1.48:5060 SIP/2.0 (35) Sep 9 10:11:21 DEBUG[16644] chan_sip.c: Header: Via: SIP/2.0/UDP 10.1.1.200;branch=z9hG4bK46ffff24E4E7EE (56) Sep 9 10:11:21 DEBUG[16644] chan_sip.c: Header: From: "Wilson" ;tag=637E8B9F-FF6C90B8 (67) Sep 9 10:11:21 DEBUG[16644] chan_sip.c: Header: To: (34) Sep 9 10:11:21 DEBUG[16644] chan_sip.c: Header: CSeq: 68 REGISTER (17) Sep 9 10:11:21 DEBUG[16644] chan_sip.c: Header: Call-ID: 56c784b3-15d91a19-2fc96602@10.1.1.200 (46) Sep 9 10:11:21 DEBUG[16644] chan_sip.c: Header: Contact: ;methods="INVITE, ACK, BYE, CANCEL, OPTIONS, INFO, MESSAGE, SUBSCRIBE, NOTIFY, PRACK, UPDATE, REFER" (140) Sep 9 10:11:21 DEBUG[16644] chan_sip.c: Header: User-Agent: PolycomSoundPointIP-SPIP_600-UA/1.5.2.0054 (54) Sep 9 10:11:21 DEBUG[16644] chan_sip.c: Header: Authorization: Digest username="wilson-polycom", realm="asterisk", nonce="44377a59", uri="sip:10.1.1.48:5060", response="85c50fe69be0ec277c4c8bdbe4b421a5", algorithm=MD5 (169) Sep 9 10:11:21 DEBUG[16644] chan_sip.c: Header: Max-Forwards: 70 (16) Sep 9 10:11:21 DEBUG[16644] chan_sip.c: Header: Expires: 3600 (13) Sep 9 10:11:21 DEBUG[16644] chan_sip.c: Header: Content-Length: 0 (17) Sep 9 10:11:21 DEBUG[16644] chan_sip.c: Header: (0) Sep 9 10:11:21 VERBOSE[16644] logger.c: --- (12 headers 0 lines)Sep 9 10:11:21 VERBOSE[16644] logger.c: --- (12 headers 0 lines)--- Sep 9 10:11:21 DEBUG[16644] chan_sip.c: **** Received REGISTER (2) - Command in SIP REGISTER Sep 9 10:11:21 VERBOSE[16644] logger.c: Using latest REGISTER request as basis request Sep 9 10:11:21 VERBOSE[16644] logger.c: Sending to 10.1.1.200 : 5060 (non-NAT) Sep 9 10:11:21 VERBOSE[16644] logger.c: Transmitting (no NAT) to 10.1.1.200:5060: SIP/2.0 100 TryingVia: SIP/2.0/UDP 10.1.1.200;branch=z9hG4bK46ffff24E4E7EEFrom: "Wilson" ;tag=637E8B9F-FF6C90B8To: Call-ID: 56c784b3-15d91a19-2fc96602@10.1.1.200CSeq: 68 REGISTERUser-Agent: Asterisk PBXAllow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFYContact: Content-Length: 0 --- Sep 9 10:11:21 VERBOSE[16644] logger.c: Transmitting (no NAT) to 10.1.1.200:5060: SIP/2.0 200 OKVia: SIP/2.0/UDP 10.1.1.200;branch=z9hG4bK46ffff24E4E7EEFrom: "Wilson" ;tag=637E8B9F-FF6C90B8To: ;tag=as44927bf8Call-ID: 56c784b3-15d91a19-2fc96602@10.1.1.200CSeq: 68 REGISTERUser-Agent: Asterisk PBXAllow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFYExpires: 3600Contact: ;expires=3600Date: Fri, 09 Sep 2005 17:11:21 GMTContent-Length: 0 --- Sep 9 10:11:21 VERBOSE[16644] logger.c: Scheduling destruction of call '56c784b3-15d91a19-2fc96602@10.1.1.200' in 15000 ms Sep 9 10:11:21 DEBUG[16644] chan_sip.c: Checking device state for peer wilson Sep 9 10:11:21 DEBUG[16644] devicestate.c: Changing state for SIP/wilson - state 5 (Unavailable) Sep 9 10:11:25 DEBUG[16644] chan_sip.c: Allocating new SIP dialog for (No Call-ID) - NOTIFY (No RTP) Sep 9 10:11:25 DEBUG[16644] chan_sip.c: Header: NOTIFY sip:wilson-polycom@10.1.1.200 SIP/2.0 (44) Sep 9 10:11:25 DEBUG[16644] chan_sip.c: Header: Via: SIP/2.0/UDP 10.1.1.48:5060;branch=z9hG4bK686a07ea (54) Sep 9 10:11:25 DEBUG[16644] chan_sip.c: Header: From: "asterisk" ;tag=as308725f2 (56) Sep 9 10:11:25 DEBUG[16644] chan_sip.c: Header: To: (35) Sep 9 10:11:25 DEBUG[16644] chan_sip.c: Header: Contact: (33) Sep 9 10:11:25 DEBUG[16644] chan_sip.c: Header: Call-ID: 30db2dbd6847e9555e8864d93491dff4@10.1.1.48 (51) Sep 9 10:11:25 DEBUG[16644] chan_sip.c: Header: CSeq: 102 NOTIFY (16) Sep 9 10:11:25 DEBUG[16644] chan_sip.c: Header: User-Agent: Asterisk PBX (24) Sep 9 10:11:25 DEBUG[16644] chan_sip.c: Header: Event: message-summary (22) Sep 9 10:11:25 DEBUG[16644] chan_sip.c: Header: Content-Type: application/simple-message-summary (48) Sep 9 10:11:25 DEBUG[16644] chan_sip.c: Header: Content-Length: 80 (18) Sep 9 10:11:25 DEBUG[16644] chan_sip.c: Header: (0) Sep 9 10:11:25 DEBUG[16644] chan_sip.c: Line: Message-Account: sip:asterisk@ (30) Sep 9 10:11:25 DEBUG[16644] chan_sip.c: Line: Messages-Waiting: no (20) Sep 9 10:11:25 DEBUG[16644] chan_sip.c: Line: Voice-Message: 0/0 (0/0) (24) Sep 9 10:11:25 VERBOSE[16644] logger.c: 11 headers, 3 lines Sep 9 10:11:25 VERBOSE[16644] logger.c: Reliably Transmitting (no NAT) to 10.1.1.200:5060: NOTIFY sip:wilson-polycom@10.1.1.200 SIP/2.0Via: SIP/2.0/UDP 10.1.1.48:5060;branch=z9hG4bK686a07eaFrom: "asterisk" ;tag=as308725f2To: Contact: Call-ID: 30db2dbd6847e9555e8864d93491dff4@10.1.1.48CSeq: 102 NOTIFYUser-Agent: Asterisk PBXEvent: message-summaryContent-Type: application/simple-message-summaryContent-Length: 80Message-Account: sip:asterisk@Messages-Waiting: noVoice-Message: 0/0 (0/0) --- Sep 9 10:11:25 DEBUG[16644] chan_sip.c: *** SIP TIMER: Initalizing retransmit timer on packet: Id #1032 Sep 9 10:11:25 VERBOSE[16644] logger.c: Scheduling destruction of call '30db2dbd6847e9555e8864d93491dff4@10.1.1.48' in 15000 ms Sep 9 10:11:25 VERBOSE[16644] logger.c: <-- SIP read from 10.1.1.200:5060: SIP/2.0 200 OKVia: SIP/2.0/UDP 10.1.1.48:5060;branch=z9hG4bK686a07eaFrom: "asterisk" ;tag=as308725f2To: ;tag=24B6D0E4-109825A5CSeq: 102 NOTIFYCall-ID: 30db2dbd6847e9555e8864d93491dff4@10.1.1.48Contact: Event: message-summaryUser-Agent: PolycomSoundPointIP-SPIP_600-UA/1.5.2.0054Content-Length: 0 Sep 9 10:11:25 DEBUG[16644] chan_sip.c: Header: SIP/2.0 200 OK (14) Sep 9 10:11:25 DEBUG[16644] chan_sip.c: Header: Via: SIP/2.0/UDP 10.1.1.48:5060;branch=z9hG4bK686a07ea (54) Sep 9 10:11:25 DEBUG[16644] chan_sip.c: Header: From: "asterisk" ;tag=as308725f2 (56) Sep 9 10:11:25 DEBUG[16644] chan_sip.c: Header: To: ;tag=24B6D0E4-109825A5 (57) Sep 9 10:11:25 DEBUG[16644] chan_sip.c: Header: CSeq: 102 NOTIFY (16) Sep 9 10:11:25 DEBUG[16644] chan_sip.c: Header: Call-ID: 30db2dbd6847e9555e8864d93491dff4@10.1.1.48 (51) Sep 9 10:11:25 DEBUG[16644] chan_sip.c: Header: Contact: (40) Sep 9 10:11:25 DEBUG[16644] chan_sip.c: Header: Event: message-summary (22) Sep 9 10:11:25 DEBUG[16644] chan_sip.c: Header: User-Agent: PolycomSoundPointIP-SPIP_600-UA/1.5.2.0054 (54) Sep 9 10:11:25 DEBUG[16644] chan_sip.c: Header: Content-Length: 0 (17) Sep 9 10:11:25 DEBUG[16644] chan_sip.c: Header: (0) Sep 9 10:11:25 VERBOSE[16644] logger.c: --- (10 headers 0 lines)Sep 9 10:11:25 VERBOSE[16644] logger.c: --- (10 headers 0 lines)--- Sep 9 10:11:25 DEBUG[16644] chan_sip.c: ** SIP TIMER: Cancelling retransmit of packet (reply received) Retransid #1032 Sep 9 10:11:25 DEBUG[16644] chan_sip.c: Stopping retransmission on '30db2dbd6847e9555e8864d93491dff4@10.1.1.48' of Request 102: Match Found Sep 9 10:11:25 VERBOSE[16644] logger.c: Destroying call '30db2dbd6847e9555e8864d93491dff4@10.1.1.48' Sep 9 10:11:36 DEBUG[16644] chan_sip.c: Auto destroying call '56c784b3-15d91a19-2fc96602@10.1.1.200' Sep 9 10:11:36 VERBOSE[16644] logger.c: Destroying call '56c784b3-15d91a19-2fc96602@10.1.1.200' Sep 9 10:22:36 DEBUG[16644] chan_sip.c: Header: REGISTER sip:10.1.1.48:5060 SIP/2.0 (35) Sep 9 10:22:36 DEBUG[16644] chan_sip.c: Header: Via: SIP/2.0/UDP 10.1.1.201;branch=z9hG4bKde62e13cF8279FF (57) Sep 9 10:22:36 DEBUG[16644] chan_sip.c: Header: From: "Scott" ;tag=EDE85FF6-86E06D93 (65) Sep 9 10:22:36 DEBUG[16644] chan_sip.c: Header: To: (33) Sep 9 10:22:36 DEBUG[16644] chan_sip.c: Header: CSeq: 1047 REGISTER (19) Sep 9 10:22:36 DEBUG[16644] chan_sip.c: Header: Call-ID: 53a44b2a-7d482150-a3adcf2d@10.1.1.201 (46) Sep 9 10:22:36 DEBUG[16644] chan_sip.c: Header: Contact: ;methods="INVITE, ACK, BYE, CANCEL, OPTIONS, INFO, MESSAGE, SUBSCRIBE, NOTIFY, PRACK, UPDATE, REFER" (139) Sep 9 10:22:36 DEBUG[16644] chan_sip.c: Header: User-Agent: PolycomSoundPointIP-SPIP_600-UA/1.5.2.0054 (54) Sep 9 10:22:36 DEBUG[16644] chan_sip.c: Header: Authorization: Digest username="scott-polycom", realm="asterisk", nonce="1acb9312", uri="sip:10.1.1.48:5060", response="fdef31c1d50404bbe4cf269ff41639f0", algorithm=MD5 (168) Sep 9 10:22:36 DEBUG[16644] chan_sip.c: Header: Max-Forwards: 70 (16) Sep 9 10:22:36 DEBUG[16644] chan_sip.c: Header: Expires: 3600 (13) Sep 9 10:22:36 DEBUG[16644] chan_sip.c: Header: Content-Length: 0 (17) Sep 9 10:22:36 DEBUG[16644] chan_sip.c: Header: (0) Sep 9 10:22:36 DEBUG[16644] chan_sip.c: Allocating new SIP dialog for 53a44b2a-7d482150-a3adcf2d@10.1.1.201 - REGISTER (No RTP) Sep 9 10:22:36 DEBUG[16644] chan_sip.c: **** Received REGISTER (2) - Command in SIP REGISTER Sep 9 10:22:36 DEBUG[16644] chan_sip.c: Header: REGISTER sip:10.1.1.48:5060 SIP/2.0 (35) Sep 9 10:22:36 DEBUG[16644] chan_sip.c: Header: Via: SIP/2.0/UDP 10.1.1.201;branch=z9hG4bKedf9e8e2875159E5 (58) Sep 9 10:22:36 DEBUG[16644] chan_sip.c: Header: From: "Scott" ;tag=EDE85FF6-86E06D93 (65) Sep 9 10:22:36 DEBUG[16644] chan_sip.c: Header: To: (33) Sep 9 10:22:36 DEBUG[16644] chan_sip.c: Header: CSeq: 1048 REGISTER (19) Sep 9 10:22:36 DEBUG[16644] chan_sip.c: Header: Call-ID: 53a44b2a-7d482150-a3adcf2d@10.1.1.201 (46) Sep 9 10:22:36 DEBUG[16644] chan_sip.c: Header: Contact: ;methods="INVITE, ACK, BYE, CANCEL, OPTIONS, INFO, MESSAGE, SUBSCRIBE, NOTIFY, PRACK, UPDATE, REFER" (139) Sep 9 10:22:36 DEBUG[16644] chan_sip.c: Header: User-Agent: PolycomSoundPointIP-SPIP_600-UA/1.5.2.0054 (54) Sep 9 10:22:36 DEBUG[16644] chan_sip.c: Header: Authorization: Digest username="scott-polycom", realm="asterisk", nonce="416491e5", uri="sip:10.1.1.48:5060", response="a63364e82170a5eb5ddf398557f56849", algorithm=MD5 (168) Sep 9 10:22:36 DEBUG[16644] chan_sip.c: Header: Max-Forwards: 70 (16) Sep 9 10:22:36 DEBUG[16644] chan_sip.c: Header: Expires: 3600 (13) Sep 9 10:22:36 DEBUG[16644] chan_sip.c: Header: Content-Length: 0 (17) Sep 9 10:22:36 DEBUG[16644] chan_sip.c: Header: (0) Sep 9 10:22:36 DEBUG[16644] chan_sip.c: **** Received REGISTER (2) - Command in SIP REGISTER Sep 9 10:22:36 DEBUG[16644] chan_sip.c: Checking device state for DNS host scott Sep 9 10:22:36 DEBUG[16644] devicestate.c: Changing state for SIP/scott - state 4 (Invalid) Sep 9 10:22:46 DEBUG[16644] chan_sip.c: Allocating new SIP dialog for (No Call-ID) - NOTIFY (No RTP) Sep 9 10:22:46 DEBUG[16644] chan_sip.c: Header: NOTIFY sip:scott-polycom@10.1.1.201 SIP/2.0 (43) Sep 9 10:22:46 DEBUG[16644] chan_sip.c: Header: Via: SIP/2.0/UDP 10.1.1.48:5060;branch=z9hG4bK7f7351e6 (54) Sep 9 10:22:46 DEBUG[16644] chan_sip.c: Header: From: "asterisk" ;tag=as50bd6606 (56) Sep 9 10:22:46 DEBUG[16644] chan_sip.c: Header: To: (34) Sep 9 10:22:46 DEBUG[16644] chan_sip.c: Header: Contact: (33) Sep 9 10:22:46 DEBUG[16644] chan_sip.c: Header: Call-ID: 25a5f5f1697efa585b39d6ce40942437@10.1.1.48 (51) Sep 9 10:22:46 DEBUG[16644] chan_sip.c: Header: CSeq: 102 NOTIFY (16) Sep 9 10:22:46 DEBUG[16644] chan_sip.c: Header: User-Agent: Asterisk PBX (24) Sep 9 10:22:46 DEBUG[16644] chan_sip.c: Header: Event: message-summary (22) Sep 9 10:22:46 DEBUG[16644] chan_sip.c: Header: Content-Type: application/simple-message-summary (48) Sep 9 10:22:46 DEBUG[16644] chan_sip.c: Header: Content-Length: 80 (18) Sep 9 10:22:46 DEBUG[16644] chan_sip.c: Header: (0) Sep 9 10:22:46 DEBUG[16644] chan_sip.c: Line: Message-Account: sip:asterisk@ (30) Sep 9 10:22:46 DEBUG[16644] chan_sip.c: Line: Messages-Waiting: no (20) Sep 9 10:22:46 DEBUG[16644] chan_sip.c: Line: Voice-Message: 0/0 (0/0) (24) Sep 9 10:22:46 DEBUG[16644] chan_sip.c: *** SIP TIMER: Initalizing retransmit timer on packet: Id #1037 Sep 9 10:22:46 DEBUG[16644] chan_sip.c: Header: SIP/2.0 200 OK (14) Sep 9 10:22:46 DEBUG[16644] chan_sip.c: Header: Via: SIP/2.0/UDP 10.1.1.48:5060;branch=z9hG4bK7f7351e6 (54) Sep 9 10:22:46 DEBUG[16644] chan_sip.c: Header: From: "asterisk" ;tag=as50bd6606 (56) Sep 9 10:22:46 DEBUG[16644] chan_sip.c: Header: To: ;tag=C0A7724B-CF357908 (56) Sep 9 10:22:46 DEBUG[16644] chan_sip.c: Header: CSeq: 102 NOTIFY (16) Sep 9 10:22:46 DEBUG[16644] chan_sip.c: Header: Call-ID: 25a5f5f1697efa585b39d6ce40942437@10.1.1.48 (51) Sep 9 10:22:46 DEBUG[16644] chan_sip.c: Header: Contact: (39) Sep 9 10:22:46 DEBUG[16644] chan_sip.c: Header: Event: message-summary (22) Sep 9 10:22:46 DEBUG[16644] chan_sip.c: Header: User-Agent: PolycomSoundPointIP-SPIP_600-UA/1.5.2.0054 (54) Sep 9 10:22:46 DEBUG[16644] chan_sip.c: Header: Content-Length: 0 (17) Sep 9 10:22:46 DEBUG[16644] chan_sip.c: Header: (0) Sep 9 10:22:46 DEBUG[16644] chan_sip.c: ** SIP TIMER: Cancelling retransmit of packet (reply received) Retransid #1037 Sep 9 10:22:46 DEBUG[16644] chan_sip.c: Stopping retransmission on '25a5f5f1697efa585b39d6ce40942437@10.1.1.48' of Request 102: Match Found Sep 9 10:22:51 DEBUG[16644] chan_sip.c: Auto destroying call '53a44b2a-7d482150-a3adcf2d@10.1.1.201' Sep 9 10:31:17 DEBUG[16644] chan_sip.c: Header: REGISTER sip:10.1.1.48:5060 SIP/2.0 (35) Sep 9 10:31:17 DEBUG[16644] chan_sip.c: Header: Via: SIP/2.0/UDP 10.1.1.204;branch=z9hG4bK41573fb09368F0ED (58) Sep 9 10:31:17 DEBUG[16644] chan_sip.c: Header: From: "Kitchen" ;tag=8F90E744-474916F3 (69) Sep 9 10:31:17 DEBUG[16644] chan_sip.c: Header: To: (35) Sep 9 10:31:17 DEBUG[16644] chan_sip.c: Header: CSeq: 1049 REGISTER (19) Sep 9 10:31:17 DEBUG[16644] chan_sip.c: Header: Call-ID: d1897748-f95ebf6e-ec4abb25@10.1.1.204 (46) Sep 9 10:31:17 DEBUG[16644] chan_sip.c: Header: Contact: ;methods="INVITE, ACK, BYE, CANCEL, OPTIONS, INFO, MESSAGE, SUBSCRIBE, NOTIFY, PRACK, UPDATE, REFER" (141) Sep 9 10:31:17 DEBUG[16644] chan_sip.c: Header: User-Agent: PolycomSoundPointIP-SPIP_600-UA/1.5.2.0054 (54) Sep 9 10:31:17 DEBUG[16644] chan_sip.c: Header: Authorization: Digest username="kitchen-polycom", realm="asterisk", nonce="05d903ec", uri="sip:10.1.1.48:5060", response="4e93118b67482b35d0f173788586b2d3", algorithm=MD5 (170) Sep 9 10:31:17 DEBUG[16644] chan_sip.c: Header: Max-Forwards: 70 (16) Sep 9 10:31:17 DEBUG[16644] chan_sip.c: Header: Expires: 3600 (13) Sep 9 10:31:17 DEBUG[16644] chan_sip.c: Header: Content-Length: 0 (17) Sep 9 10:31:17 DEBUG[16644] chan_sip.c: Header: (0) Sep 9 10:31:17 DEBUG[16644] chan_sip.c: Allocating new SIP dialog for d1897748-f95ebf6e-ec4abb25@10.1.1.204 - REGISTER (No RTP) Sep 9 10:31:17 DEBUG[16644] chan_sip.c: **** Received REGISTER (2) - Command in SIP REGISTER Sep 9 10:31:18 DEBUG[16644] chan_sip.c: Header: REGISTER sip:10.1.1.48:5060 SIP/2.0 (35) Sep 9 10:31:18 DEBUG[16644] chan_sip.c: Header: Via: SIP/2.0/UDP 10.1.1.204;branch=z9hG4bK6521116523368FB (57) Sep 9 10:31:18 DEBUG[16644] chan_sip.c: Header: From: "Kitchen" ;tag=8F90E744-474916F3 (69) Sep 9 10:31:18 DEBUG[16644] chan_sip.c: Header: To: (35) Sep 9 10:31:18 DEBUG[16644] chan_sip.c: Header: CSeq: 1050 REGISTER (19) Sep 9 10:31:18 DEBUG[16644] chan_sip.c: Header: Call-ID: d1897748-f95ebf6e-ec4abb25@10.1.1.204 (46) Sep 9 10:31:18 DEBUG[16644] chan_sip.c: Header: Contact: ;methods="INVITE, ACK, BYE, CANCEL, OPTIONS, INFO, MESSAGE, SUBSCRIBE, NOTIFY, PRACK, UPDATE, REFER" (141) Sep 9 10:31:18 DEBUG[16644] chan_sip.c: Header: User-Agent: PolycomSoundPointIP-SPIP_600-UA/1.5.2.0054 (54) Sep 9 10:31:18 DEBUG[16644] chan_sip.c: Header: Authorization: Digest username="kitchen-polycom", realm="asterisk", nonce="145ccc7c", uri="sip:10.1.1.48:5060", response="c3848a14ea06ff672ad8434c4729f9be", algorithm=MD5 (170) Sep 9 10:31:18 DEBUG[16644] chan_sip.c: Header: Max-Forwards: 70 (16) Sep 9 10:31:18 DEBUG[16644] chan_sip.c: Header: Expires: 3600 (13) Sep 9 10:31:18 DEBUG[16644] chan_sip.c: Header: Content-Length: 0 (17) Sep 9 10:31:18 DEBUG[16644] chan_sip.c: Header: (0) Sep 9 10:31:18 DEBUG[16644] chan_sip.c: **** Received REGISTER (2) - Command in SIP REGISTER Sep 9 10:31:18 DEBUG[16644] chan_sip.c: Checking device state for DNS host kitchen Sep 9 10:31:18 DEBUG[16644] devicestate.c: Changing state for SIP/kitchen - state 4 (Invalid) Sep 9 10:31:24 DEBUG[16644] chan_sip.c: Header: REGISTER sip:10.1.1.48:5060 SIP/2.0 (35) Sep 9 10:31:24 DEBUG[16644] chan_sip.c: Header: Via: SIP/2.0/UDP 10.1.1.202;branch=z9hG4bKd470701b900C2758 (58) Sep 9 10:31:24 DEBUG[16644] chan_sip.c: Header: From: "Guest Room" ;tag=CCF2567D-4372788C (75) Sep 9 10:31:24 DEBUG[16644] chan_sip.c: Header: To: (38) Sep 9 10:31:24 DEBUG[16644] chan_sip.c: Header: CSeq: 1049 REGISTER (19) Sep 9 10:31:24 DEBUG[16644] chan_sip.c: Header: Call-ID: 3b501911-4c048fff-3eeaecc6@10.1.1.202 (46) Sep 9 10:31:24 DEBUG[16644] chan_sip.c: Header: Contact: ;methods="INVITE, ACK, BYE, CANCEL, OPTIONS, INFO, MESSAGE, SUBSCRIBE, NOTIFY, PRACK, UPDATE, REFER" (144) Sep 9 10:31:24 DEBUG[16644] chan_sip.c: Header: User-Agent: PolycomSoundPointIP-SPIP_600-UA/1.5.2.0054 (54) Sep 9 10:31:24 DEBUG[16644] chan_sip.c: Header: Authorization: Digest username="guest-room-polycom", realm="asterisk", nonce="546c7c8e", uri="sip:10.1.1.48:5060", response="dd5fc219dd940ee741e19f5e1903b77a", algorithm=MD5 (173) Sep 9 10:31:24 DEBUG[16644] chan_sip.c: Header: Max-Forwards: 70 (16) Sep 9 10:31:24 DEBUG[16644] chan_sip.c: Header: Expires: 3600 (13) Sep 9 10:31:24 DEBUG[16644] chan_sip.c: Header: Content-Length: 0 (17) Sep 9 10:31:24 DEBUG[16644] chan_sip.c: Header: (0) Sep 9 10:31:24 DEBUG[16644] chan_sip.c: Allocating new SIP dialog for 3b501911-4c048fff-3eeaecc6@10.1.1.202 - REGISTER (No RTP) Sep 9 10:31:24 DEBUG[16644] chan_sip.c: **** Received REGISTER (2) - Command in SIP REGISTER Sep 9 10:31:24 DEBUG[16644] chan_sip.c: Header: REGISTER sip:10.1.1.48:5060 SIP/2.0 (35) Sep 9 10:31:24 DEBUG[16644] chan_sip.c: Header: Via: SIP/2.0/UDP 10.1.1.202;branch=z9hG4bK148063b9BE39978E (58) Sep 9 10:31:24 DEBUG[16644] chan_sip.c: Header: From: "Guest Room" ;tag=CCF2567D-4372788C (75) Sep 9 10:31:24 DEBUG[16644] chan_sip.c: Header: To: (38) Sep 9 10:31:24 DEBUG[16644] chan_sip.c: Header: CSeq: 1050 REGISTER (19) Sep 9 10:31:24 DEBUG[16644] chan_sip.c: Header: Call-ID: 3b501911-4c048fff-3eeaecc6@10.1.1.202 (46) Sep 9 10:31:24 DEBUG[16644] chan_sip.c: Header: Contact: ;methods="INVITE, ACK, BYE, CANCEL, OPTIONS, INFO, MESSAGE, SUBSCRIBE, NOTIFY, PRACK, UPDATE, REFER" (144) Sep 9 10:31:24 DEBUG[16644] chan_sip.c: Header: User-Agent: PolycomSoundPointIP-SPIP_600-UA/1.5.2.0054 (54) Sep 9 10:31:24 DEBUG[16644] chan_sip.c: Header: Authorization: Digest username="guest-room-polycom", realm="asterisk", nonce="36b0ef60", uri="sip:10.1.1.48:5060", response="e3bc25f5c9e9c6eae867997006133f25", algorithm=MD5 (173) Sep 9 10:31:24 DEBUG[16644] chan_sip.c: Header: Max-Forwards: 70 (16) Sep 9 10:31:24 DEBUG[16644] chan_sip.c: Header: Expires: 3600 (13) Sep 9 10:31:24 DEBUG[16644] chan_sip.c: Header: Content-Length: 0 (17) Sep 9 10:31:24 DEBUG[16644] chan_sip.c: Header: (0) Sep 9 10:31:24 DEBUG[16644] chan_sip.c: **** Received REGISTER (2) - Command in SIP REGISTER Sep 9 10:31:24 DEBUG[16644] chan_sip.c: Checking device state for DNS host guest Sep 9 10:31:25 DEBUG[16644] devicestate.c: Changing state for SIP/guest - state 4 (Invalid) Sep 9 10:31:30 DEBUG[16644] chan_sip.c: Header: REGISTER sip:10.1.1.48:5060 SIP/2.0 (35) Sep 9 10:31:30 DEBUG[16644] chan_sip.c: Header: Via: SIP/2.0/UDP 10.1.1.203;branch=z9hG4bK68c38f8bF04C94E4 (58) Sep 9 10:31:30 DEBUG[16644] chan_sip.c: Header: From: "Theater" ;tag=E315B29C-EC409443 (69) Sep 9 10:31:30 DEBUG[16644] chan_sip.c: Header: To: (35) Sep 9 10:31:30 DEBUG[16644] chan_sip.c: Header: CSeq: 963 REGISTER (18) Sep 9 10:31:30 DEBUG[16644] chan_sip.c: Header: Call-ID: 235aca78-dd4310ca-9b394431@10.1.1.203 (46) Sep 9 10:31:30 DEBUG[16644] chan_sip.c: Header: Contact: ;methods="INVITE, ACK, BYE, CANCEL, OPTIONS, INFO, MESSAGE, SUBSCRIBE, NOTIFY, PRACK, UPDATE, REFER" (141) Sep 9 10:31:30 DEBUG[16644] chan_sip.c: Header: User-Agent: PolycomSoundPointIP-SPIP_600-UA/1.5.2.0054 (54) Sep 9 10:31:30 DEBUG[16644] chan_sip.c: Header: Authorization: Digest username="theater-polycom", realm="asterisk", nonce="1c59db25", uri="sip:10.1.1.48:5060", response="1d1897085b0b03ede38ccea3cdd4ec72", algorithm=MD5 (170) Sep 9 10:31:30 DEBUG[16644] chan_sip.c: Header: Max-Forwards: 70 (16) Sep 9 10:31:30 DEBUG[16644] chan_sip.c: Header: Expires: 3600 (13) Sep 9 10:31:30 DEBUG[16644] chan_sip.c: Header: Content-Length: 0 (17) Sep 9 10:31:30 DEBUG[16644] chan_sip.c: Header: (0) Sep 9 10:31:30 DEBUG[16644] chan_sip.c: Allocating new SIP dialog for 235aca78-dd4310ca-9b394431@10.1.1.203 - REGISTER (No RTP) Sep 9 10:31:30 DEBUG[16644] chan_sip.c: **** Received REGISTER (2) - Command in SIP REGISTER Sep 9 10:31:30 DEBUG[16644] chan_sip.c: Header: REGISTER sip:10.1.1.48:5060 SIP/2.0 (35) Sep 9 10:31:30 DEBUG[16644] chan_sip.c: Header: Via: SIP/2.0/UDP 10.1.1.203;branch=z9hG4bK2375b91d9025E036 (58) Sep 9 10:31:30 DEBUG[16644] chan_sip.c: Header: From: "Theater" ;tag=E315B29C-EC409443 (69) Sep 9 10:31:30 DEBUG[16644] chan_sip.c: Header: To: (35) Sep 9 10:31:30 DEBUG[16644] chan_sip.c: Header: CSeq: 964 REGISTER (18) Sep 9 10:31:30 DEBUG[16644] chan_sip.c: Header: Call-ID: 235aca78-dd4310ca-9b394431@10.1.1.203 (46) Sep 9 10:31:30 DEBUG[16644] chan_sip.c: Header: Contact: ;methods="INVITE, ACK, BYE, CANCEL, OPTIONS, INFO, MESSAGE, SUBSCRIBE, NOTIFY, PRACK, UPDATE, REFER" (141) Sep 9 10:31:30 DEBUG[16644] chan_sip.c: Header: User-Agent: PolycomSoundPointIP-SPIP_600-UA/1.5.2.0054 (54) Sep 9 10:31:30 DEBUG[16644] chan_sip.c: Header: Authorization: Digest username="theater-polycom", realm="asterisk", nonce="1700801a", uri="sip:10.1.1.48:5060", response="d14aa90684f3149cea6e692c366e094b", algorithm=MD5 (170) Sep 9 10:31:30 DEBUG[16644] chan_sip.c: Header: Max-Forwards: 70 (16) Sep 9 10:31:30 DEBUG[16644] chan_sip.c: Header: Expires: 3600 (13) Sep 9 10:31:30 DEBUG[16644] chan_sip.c: Header: Content-Length: 0 (17) Sep 9 10:31:30 DEBUG[16644] chan_sip.c: Header: (0) Sep 9 10:31:30 DEBUG[16644] chan_sip.c: **** Received REGISTER (2) - Command in SIP REGISTER Sep 9 10:31:30 DEBUG[16644] chan_sip.c: Checking device state for DNS host theater Sep 9 10:31:30 DEBUG[16644] devicestate.c: Changing state for SIP/theater - state 4 (Invalid) Sep 9 10:31:33 DEBUG[16644] chan_sip.c: Auto destroying call 'd1897748-f95ebf6e-ec4abb25@10.1.1.204' Sep 9 10:31:39 DEBUG[16644] chan_sip.c: Auto destroying call '3b501911-4c048fff-3eeaecc6@10.1.1.202' Sep 9 10:31:45 DEBUG[16644] chan_sip.c: Auto destroying call '235aca78-dd4310ca-9b394431@10.1.1.203' Sep 9 10:38:08 VERBOSE[16644] logger.c: <-- SIP read from 10.1.1.200:5060: SUBSCRIBE sip:2000@10.1.1.48 SIP/2.0Via: SIP/2.0/UDP 10.1.1.200;branch=z9hG4bK8fe8936b98A398FAFrom: "Wilson" ;tag=3E850BCC-20B703CDTo: ;tag=as211d38f3CSeq: 3 SUBSCRIBECall-ID: 7928dc00-5949e356-88e662a7@10.1.1.200Contact: Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, INFO, MESSAGE, SUBSCRIBE, NOTIFY, PRACK, UPDATE, REFEREvent: presenceUser-Agent: PolycomSoundPointIP-SPIP_600-UA/1.5.2.0054Authorization: Digest username="wilson-polycom", realm="asterisk", nonce="0aea62af", uri="sip:2000@10.1.1.48:5060", response="adfc6e097dec38a19ac7321cb0be0fac", algorithm=MD5Max-Forwards: 70Expires: 3600Content-Length: 0 Sep 9 10:38:08 DEBUG[16644] chan_sip.c: Header: SUBSCRIBE sip:2000@10.1.1.48 SIP/2.0 (36) Sep 9 10:38:08 DEBUG[16644] chan_sip.c: Header: Via: SIP/2.0/UDP 10.1.1.200;branch=z9hG4bK8fe8936b98A398FA (58) Sep 9 10:38:08 DEBUG[16644] chan_sip.c: Header: From: "Wilson" ;tag=3E850BCC-20B703CD (67) Sep 9 10:38:08 DEBUG[16644] chan_sip.c: Header: To: ;tag=as211d38f3 (39) Sep 9 10:38:08 DEBUG[16644] chan_sip.c: Header: CSeq: 3 SUBSCRIBE (17) Sep 9 10:38:08 DEBUG[16644] chan_sip.c: Header: Call-ID: 7928dc00-5949e356-88e662a7@10.1.1.200 (46) Sep 9 10:38:08 DEBUG[16644] chan_sip.c: Header: Contact: (40) Sep 9 10:38:08 DEBUG[16644] chan_sip.c: Header: Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, INFO, MESSAGE, SUBSCRIBE, NOTIFY, PRACK, UPDATE, REFER (96) Sep 9 10:38:08 DEBUG[16644] chan_sip.c: Header: Event: presence (15) Sep 9 10:38:08 DEBUG[16644] chan_sip.c: Header: User-Agent: PolycomSoundPointIP-SPIP_600-UA/1.5.2.0054 (54) Sep 9 10:38:08 DEBUG[16644] chan_sip.c: Header: Authorization: Digest username="wilson-polycom", realm="asterisk", nonce="0aea62af", uri="sip:2000@10.1.1.48:5060", response="adfc6e097dec38a19ac7321cb0be0fac", algorithm=MD5 (174) Sep 9 10:38:08 DEBUG[16644] chan_sip.c: Header: Max-Forwards: 70 (16) Sep 9 10:38:08 DEBUG[16644] chan_sip.c: Header: Expires: 3600 (13) Sep 9 10:38:08 DEBUG[16644] chan_sip.c: Header: Content-Length: 0 (17) Sep 9 10:38:08 DEBUG[16644] chan_sip.c: Header: (0) Sep 9 10:38:08 VERBOSE[16644] logger.c: --- (14 headers 0 lines)Sep 9 10:38:08 VERBOSE[16644] logger.c: --- (14 headers 0 lines)--- Sep 9 10:38:08 DEBUG[16644] chan_sip.c: **** Received SUBSCRIBE (10) - Command in SIP SUBSCRIBE Sep 9 10:38:08 DEBUG[16644] chan_sip.c: Got a re-subscribe on existing subscription 7928dc00-5949e356-88e662a7@10.1.1.200 Sep 9 10:38:08 DEBUG[16644] chan_sip.c: Adding subscription for extension 2000 context from-sip for peer wilson-polycom Sep 9 10:38:08 VERBOSE[16644] logger.c: Transmitting (no NAT) to 10.1.1.200:5060: SIP/2.0 200 OKVia: SIP/2.0/UDP 10.1.1.200;branch=z9hG4bK8fe8936b98A398FAFrom: "Wilson" ;tag=3E850BCC-20B703CDTo: ;tag=as211d38f3Call-ID: 7928dc00-5949e356-88e662a7@10.1.1.200CSeq: 3 SUBSCRIBEUser-Agent: Asterisk PBXAllow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFYExpires: 3600Contact: ;expires=3600Content-Length: 0 --- Sep 9 10:38:08 VERBOSE[16644] logger.c: Scheduling destruction of call '7928dc00-5949e356-88e662a7@10.1.1.200' in 3610000 ms Sep 9 10:38:08 DEBUG[16644] chan_sip.c: Checking device state for peer scott-polycom Sep 9 10:38:08 DEBUG[16644] chan_sip.c: Checking device state for peer scott-polycom Sep 9 10:38:08 VERBOSE[16644] logger.c: Reliably Transmitting (no NAT) to 10.1.1.200:5060: NOTIFY sip:wilson-polycom@10.1.1.200 SIP/2.0Via: SIP/2.0/UDP 10.1.1.48:5060;branch=z9hG4bK3cefd3d0From: ;tag=as211d38f3To: "Wilson" ;tag=3E850BCC-20B703CDContact: Call-ID: 7928dc00-5949e356-88e662a7@10.1.1.200CSeq: 103 NOTIFYUser-Agent: Asterisk PBXEvent: presenceContent-Type: application/xpidf+xmlSubscription-State: activeContent-Length: 348
--- Sep 9 10:38:08 DEBUG[16644] chan_sip.c: *** SIP TIMER: Initalizing retransmit timer on packet: Id #1049 Sep 9 10:38:08 VERBOSE[16644] logger.c: <-- SIP read from 10.1.1.200:5060: SIP/2.0 200 OKVia: SIP/2.0/UDP 10.1.1.48:5060;branch=z9hG4bK3cefd3d0From: ;tag=as211d38f3To: "Wilson" ;tag=3E850BCC-20B703CDCSeq: 103 NOTIFYCall-ID: 7928dc00-5949e356-88e662a7@10.1.1.200Contact: Event: presenceUser-Agent: PolycomSoundPointIP-SPIP_600-UA/1.5.2.0054Content-Length: 0 Sep 9 10:38:08 DEBUG[16644] chan_sip.c: Header: SIP/2.0 200 OK (14) Sep 9 10:38:08 DEBUG[16644] chan_sip.c: Header: Via: SIP/2.0/UDP 10.1.1.48:5060;branch=z9hG4bK3cefd3d0 (54) Sep 9 10:38:08 DEBUG[16644] chan_sip.c: Header: From: ;tag=as211d38f3 (41) Sep 9 10:38:08 DEBUG[16644] chan_sip.c: Header: To: "Wilson" ;tag=3E850BCC-20B703CD (65) Sep 9 10:38:08 DEBUG[16644] chan_sip.c: Header: CSeq: 103 NOTIFY (16) Sep 9 10:38:08 DEBUG[16644] chan_sip.c: Header: Call-ID: 7928dc00-5949e356-88e662a7@10.1.1.200 (46) Sep 9 10:38:08 DEBUG[16644] chan_sip.c: Header: Contact: (40) Sep 9 10:38:08 DEBUG[16644] chan_sip.c: Header: Event: presence (15) Sep 9 10:38:08 DEBUG[16644] chan_sip.c: Header: User-Agent: PolycomSoundPointIP-SPIP_600-UA/1.5.2.0054 (54) Sep 9 10:38:08 DEBUG[16644] chan_sip.c: Header: Content-Length: 0 (17) Sep 9 10:38:08 DEBUG[16644] chan_sip.c: Header: (0) Sep 9 10:38:08 VERBOSE[16644] logger.c: --- (10 headers 0 lines)Sep 9 10:38:08 VERBOSE[16644] logger.c: --- (10 headers 0 lines)--- Sep 9 10:38:08 DEBUG[16644] chan_sip.c: ** SIP TIMER: Cancelling retransmit of packet (reply received) Retransid #1049 Sep 9 10:38:08 DEBUG[16644] chan_sip.c: Stopping retransmission on '7928dc00-5949e356-88e662a7@10.1.1.200' of Request 103: Match Found Sep 9 10:38:08 VERBOSE[16644] logger.c: SIP Response message for INCOMING dialog NOTIFY arrived Sep 9 10:41:21 VERBOSE[16644] logger.c: <-- SIP read from 10.1.1.200:5060: REGISTER sip:10.1.1.48:5060 SIP/2.0Via: SIP/2.0/UDP 10.1.1.200;branch=z9hG4bKdf95ff51B2F7C630From: "Wilson" ;tag=637E8B9F-FF6C90B8To: CSeq: 69 REGISTERCall-ID: 56c784b3-15d91a19-2fc96602@10.1.1.200Contact: ;methods="INVITE, ACK, BYE, CANCEL, OPTIONS, INFO, MESSAGE, SUBSCRIBE, NOTIFY, PRACK, UPDATE, REFER"User-Agent: PolycomSoundPointIP-SPIP_600-UA/1.5.2.0054Authorization: Digest username="wilson-polycom", realm="asterisk", nonce="44377a59", uri="sip:10.1.1.48:5060", response="85c50fe69be0ec277c4c8bdbe4b421a5", algorithm=MD5Max-Forwards: 70Expires: 3600Content-Length: 0 Sep 9 10:41:21 DEBUG[16644] chan_sip.c: Header: REGISTER sip:10.1.1.48:5060 SIP/2.0 (35) Sep 9 10:41:21 DEBUG[16644] chan_sip.c: Header: Via: SIP/2.0/UDP 10.1.1.200;branch=z9hG4bKdf95ff51B2F7C630 (58) Sep 9 10:41:21 DEBUG[16644] chan_sip.c: Header: From: "Wilson" ;tag=637E8B9F-FF6C90B8 (67) Sep 9 10:41:21 DEBUG[16644] chan_sip.c: Header: To: (34) Sep 9 10:41:21 DEBUG[16644] chan_sip.c: Header: CSeq: 69 REGISTER (17) Sep 9 10:41:21 DEBUG[16644] chan_sip.c: Header: Call-ID: 56c784b3-15d91a19-2fc96602@10.1.1.200 (46) Sep 9 10:41:21 DEBUG[16644] chan_sip.c: Header: Contact: ;methods="INVITE, ACK, BYE, CANCEL, OPTIONS, INFO, MESSAGE, SUBSCRIBE, NOTIFY, PRACK, UPDATE, REFER" (140) Sep 9 10:41:21 DEBUG[16644] chan_sip.c: Header: User-Agent: PolycomSoundPointIP-SPIP_600-UA/1.5.2.0054 (54) Sep 9 10:41:21 DEBUG[16644] chan_sip.c: Header: Authorization: Digest username="wilson-polycom", realm="asterisk", nonce="44377a59", uri="sip:10.1.1.48:5060", response="85c50fe69be0ec277c4c8bdbe4b421a5", algorithm=MD5 (169) Sep 9 10:41:21 DEBUG[16644] chan_sip.c: Header: Max-Forwards: 70 (16) Sep 9 10:41:21 DEBUG[16644] chan_sip.c: Header: Expires: 3600 (13) Sep 9 10:41:21 DEBUG[16644] chan_sip.c: Header: Content-Length: 0 (17) Sep 9 10:41:21 DEBUG[16644] chan_sip.c: Header: (0) Sep 9 10:41:21 VERBOSE[16644] logger.c: --- (12 headers 0 lines)Sep 9 10:41:21 VERBOSE[16644] logger.c: --- (12 headers 0 lines)--- Sep 9 10:41:21 DEBUG[16644] chan_sip.c: Allocating new SIP dialog for 56c784b3-15d91a19-2fc96602@10.1.1.200 - REGISTER (No RTP) Sep 9 10:41:21 DEBUG[16644] chan_sip.c: **** Received REGISTER (2) - Command in SIP REGISTER Sep 9 10:41:21 VERBOSE[16644] logger.c: Using latest REGISTER request as basis request Sep 9 10:41:21 VERBOSE[16644] logger.c: Sending to 10.1.1.200 : 5060 (non-NAT) Sep 9 10:41:21 VERBOSE[16644] logger.c: Transmitting (no NAT) to 10.1.1.200:5060: SIP/2.0 100 TryingVia: SIP/2.0/UDP 10.1.1.200;branch=z9hG4bKdf95ff51B2F7C630From: "Wilson" ;tag=637E8B9F-FF6C90B8To: Call-ID: 56c784b3-15d91a19-2fc96602@10.1.1.200CSeq: 69 REGISTERUser-Agent: Asterisk PBXAllow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFYContact: Content-Length: 0 --- Sep 9 10:41:21 VERBOSE[16644] logger.c: Transmitting (no NAT) to 10.1.1.200:5060: SIP/2.0 401 UnauthorizedVia: SIP/2.0/UDP 10.1.1.200;branch=z9hG4bKdf95ff51B2F7C630From: "Wilson" ;tag=637E8B9F-FF6C90B8To: ;tag=as4b8f0c24Call-ID: 56c784b3-15d91a19-2fc96602@10.1.1.200CSeq: 69 REGISTERUser-Agent: Asterisk PBXAllow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFYContact: WWW-Authenticate: Digest realm="asterisk", nonce="23033613" Content-Length: 0 --- Sep 9 10:41:21 VERBOSE[16644] logger.c: Scheduling destruction of call '56c784b3-15d91a19-2fc96602@10.1.1.200' in 15000 ms Sep 9 10:41:21 VERBOSE[16644] logger.c: <-- SIP read from 10.1.1.200:5060: REGISTER sip:10.1.1.48:5060 SIP/2.0Via: SIP/2.0/UDP 10.1.1.200;branch=z9hG4bK88e13f5762E1FE86From: "Wilson" ;tag=637E8B9F-FF6C90B8To: CSeq: 70 REGISTERCall-ID: 56c784b3-15d91a19-2fc96602@10.1.1.200Contact: ;methods="INVITE, ACK, BYE, CANCEL, OPTIONS, INFO, MESSAGE, SUBSCRIBE, NOTIFY, PRACK, UPDATE, REFER"User-Agent: PolycomSoundPointIP-SPIP_600-UA/1.5.2.0054Authorization: Digest username="wilson-polycom", realm="asterisk", nonce="23033613", uri="sip:10.1.1.48:5060", response="9a9a168df20ca9c2c0ffa6dbb54d8c3b", algorithm=MD5Max-Forwards: 70Expires: 3600Content-Length: 0 Sep 9 10:41:21 DEBUG[16644] chan_sip.c: Header: REGISTER sip:10.1.1.48:5060 SIP/2.0 (35) Sep 9 10:41:21 DEBUG[16644] chan_sip.c: Header: Via: SIP/2.0/UDP 10.1.1.200;branch=z9hG4bK88e13f5762E1FE86 (58) Sep 9 10:41:21 DEBUG[16644] chan_sip.c: Header: From: "Wilson" ;tag=637E8B9F-FF6C90B8 (67) Sep 9 10:41:21 DEBUG[16644] chan_sip.c: Header: To: (34) Sep 9 10:41:21 DEBUG[16644] chan_sip.c: Header: CSeq: 70 REGISTER (17) Sep 9 10:41:21 DEBUG[16644] chan_sip.c: Header: Call-ID: 56c784b3-15d91a19-2fc96602@10.1.1.200 (46) Sep 9 10:41:21 DEBUG[16644] chan_sip.c: Header: Contact: ;methods="INVITE, ACK, BYE, CANCEL, OPTIONS, INFO, MESSAGE, SUBSCRIBE, NOTIFY, PRACK, UPDATE, REFER" (140) Sep 9 10:41:21 DEBUG[16644] chan_sip.c: Header: User-Agent: PolycomSoundPointIP-SPIP_600-UA/1.5.2.0054 (54) Sep 9 10:41:21 DEBUG[16644] chan_sip.c: Header: Authorization: Digest username="wilson-polycom", realm="asterisk", nonce="23033613", uri="sip:10.1.1.48:5060", response="9a9a168df20ca9c2c0ffa6dbb54d8c3b", algorithm=MD5 (169) Sep 9 10:41:21 DEBUG[16644] chan_sip.c: Header: Max-Forwards: 70 (16) Sep 9 10:41:21 DEBUG[16644] chan_sip.c: Header: Expires: 3600 (13) Sep 9 10:41:21 DEBUG[16644] chan_sip.c: Header: Content-Length: 0 (17) Sep 9 10:41:21 DEBUG[16644] chan_sip.c: Header: (0) Sep 9 10:41:21 VERBOSE[16644] logger.c: --- (12 headers 0 lines)Sep 9 10:41:21 VERBOSE[16644] logger.c: --- (12 headers 0 lines)--- Sep 9 10:41:21 DEBUG[16644] chan_sip.c: **** Received REGISTER (2) - Command in SIP REGISTER Sep 9 10:41:21 VERBOSE[16644] logger.c: Using latest REGISTER request as basis request Sep 9 10:41:21 VERBOSE[16644] logger.c: Sending to 10.1.1.200 : 5060 (non-NAT) Sep 9 10:41:21 VERBOSE[16644] logger.c: Transmitting (no NAT) to 10.1.1.200:5060: SIP/2.0 100 TryingVia: SIP/2.0/UDP 10.1.1.200;branch=z9hG4bK88e13f5762E1FE86From: "Wilson" ;tag=637E8B9F-FF6C90B8To: Call-ID: 56c784b3-15d91a19-2fc96602@10.1.1.200CSeq: 70 REGISTERUser-Agent: Asterisk PBXAllow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFYContact: Content-Length: 0 --- Sep 9 10:41:21 VERBOSE[16644] logger.c: Transmitting (no NAT) to 10.1.1.200:5060: SIP/2.0 200 OKVia: SIP/2.0/UDP 10.1.1.200;branch=z9hG4bK88e13f5762E1FE86From: "Wilson" ;tag=637E8B9F-FF6C90B8To: ;tag=as4b8f0c24Call-ID: 56c784b3-15d91a19-2fc96602@10.1.1.200CSeq: 70 REGISTERUser-Agent: Asterisk PBXAllow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFYExpires: 3600Contact: ;expires=3600Date: Fri, 09 Sep 2005 17:41:21 GMTContent-Length: 0 --- Sep 9 10:41:21 VERBOSE[16644] logger.c: Scheduling destruction of call '56c784b3-15d91a19-2fc96602@10.1.1.200' in 15000 ms Sep 9 10:41:21 DEBUG[16644] chan_sip.c: Checking device state for peer wilson Sep 9 10:41:21 DEBUG[16644] devicestate.c: Changing state for SIP/wilson - state 5 (Unavailable) Sep 9 10:41:29 DEBUG[16644] chan_sip.c: Allocating new SIP dialog for (No Call-ID) - NOTIFY (No RTP) Sep 9 10:41:29 DEBUG[16644] chan_sip.c: Header: NOTIFY sip:wilson-polycom@10.1.1.200 SIP/2.0 (44) Sep 9 10:41:29 DEBUG[16644] chan_sip.c: Header: Via: SIP/2.0/UDP 10.1.1.48:5060;branch=z9hG4bK067c170c (54) Sep 9 10:41:29 DEBUG[16644] chan_sip.c: Header: From: "asterisk" ;tag=as2f6bab37 (56) Sep 9 10:41:29 DEBUG[16644] chan_sip.c: Header: To: (35) Sep 9 10:41:29 DEBUG[16644] chan_sip.c: Header: Contact: (33) Sep 9 10:41:29 DEBUG[16644] chan_sip.c: Header: Call-ID: 19103ede56a80a556bb71bbf71901fac@10.1.1.48 (51) Sep 9 10:41:29 DEBUG[16644] chan_sip.c: Header: CSeq: 102 NOTIFY (16) Sep 9 10:41:29 DEBUG[16644] chan_sip.c: Header: User-Agent: Asterisk PBX (24) Sep 9 10:41:29 DEBUG[16644] chan_sip.c: Header: Event: message-summary (22) Sep 9 10:41:29 DEBUG[16644] chan_sip.c: Header: Content-Type: application/simple-message-summary (48) Sep 9 10:41:29 DEBUG[16644] chan_sip.c: Header: Content-Length: 80 (18) Sep 9 10:41:29 DEBUG[16644] chan_sip.c: Header: (0) Sep 9 10:41:29 DEBUG[16644] chan_sip.c: Line: Message-Account: sip:asterisk@ (30) Sep 9 10:41:29 DEBUG[16644] chan_sip.c: Line: Messages-Waiting: no (20) Sep 9 10:41:29 DEBUG[16644] chan_sip.c: Line: Voice-Message: 0/0 (0/0) (24) Sep 9 10:41:29 VERBOSE[16644] logger.c: 11 headers, 3 lines Sep 9 10:41:29 VERBOSE[16644] logger.c: Reliably Transmitting (no NAT) to 10.1.1.200:5060: NOTIFY sip:wilson-polycom@10.1.1.200 SIP/2.0Via: SIP/2.0/UDP 10.1.1.48:5060;branch=z9hG4bK067c170cFrom: "asterisk" ;tag=as2f6bab37To: Contact: Call-ID: 19103ede56a80a556bb71bbf71901fac@10.1.1.48CSeq: 102 NOTIFYUser-Agent: Asterisk PBXEvent: message-summaryContent-Type: application/simple-message-summaryContent-Length: 80Message-Account: sip:asterisk@Messages-Waiting: noVoice-Message: 0/0 (0/0) --- Sep 9 10:41:29 DEBUG[16644] chan_sip.c: *** SIP TIMER: Initalizing retransmit timer on packet: Id #1053 Sep 9 10:41:29 VERBOSE[16644] logger.c: Scheduling destruction of call '19103ede56a80a556bb71bbf71901fac@10.1.1.48' in 15000 ms Sep 9 10:41:29 VERBOSE[16644] logger.c: <-- SIP read from 10.1.1.200:5060: SIP/2.0 200 OKVia: SIP/2.0/UDP 10.1.1.48:5060;branch=z9hG4bK067c170cFrom: "asterisk" ;tag=as2f6bab37To: ;tag=E60907FC-34497DCSeq: 102 NOTIFYCall-ID: 19103ede56a80a556bb71bbf71901fac@10.1.1.48Contact: Event: message-summaryUser-Agent: PolycomSoundPointIP-SPIP_600-UA/1.5.2.0054Content-Length: 0 Sep 9 10:41:29 DEBUG[16644] chan_sip.c: Header: SIP/2.0 200 OK (14) Sep 9 10:41:29 DEBUG[16644] chan_sip.c: Header: Via: SIP/2.0/UDP 10.1.1.48:5060;branch=z9hG4bK067c170c (54) Sep 9 10:41:29 DEBUG[16644] chan_sip.c: Header: From: "asterisk" ;tag=as2f6bab37 (56) Sep 9 10:41:29 DEBUG[16644] chan_sip.c: Header: To: ;tag=E60907FC-34497D (55) Sep 9 10:41:29 DEBUG[16644] chan_sip.c: Header: CSeq: 102 NOTIFY (16) Sep 9 10:41:29 DEBUG[16644] chan_sip.c: Header: Call-ID: 19103ede56a80a556bb71bbf71901fac@10.1.1.48 (51) Sep 9 10:41:29 DEBUG[16644] chan_sip.c: Header: Contact: (40) Sep 9 10:41:29 DEBUG[16644] chan_sip.c: Header: Event: message-summary (22) Sep 9 10:41:29 DEBUG[16644] chan_sip.c: Header: User-Agent: PolycomSoundPointIP-SPIP_600-UA/1.5.2.0054 (54) Sep 9 10:41:29 DEBUG[16644] chan_sip.c: Header: Content-Length: 0 (17) Sep 9 10:41:29 DEBUG[16644] chan_sip.c: Header: (0) Sep 9 10:41:29 VERBOSE[16644] logger.c: --- (10 headers 0 lines)Sep 9 10:41:29 VERBOSE[16644] logger.c: --- (10 headers 0 lines)--- Sep 9 10:41:29 DEBUG[16644] chan_sip.c: ** SIP TIMER: Cancelling retransmit of packet (reply received) Retransid #1053 Sep 9 10:41:29 DEBUG[16644] chan_sip.c: Stopping retransmission on '19103ede56a80a556bb71bbf71901fac@10.1.1.48' of Request 102: Match Found Sep 9 10:41:29 VERBOSE[16644] logger.c: Destroying call '19103ede56a80a556bb71bbf71901fac@10.1.1.48' Sep 9 10:41:36 DEBUG[16644] chan_sip.c: Auto destroying call '56c784b3-15d91a19-2fc96602@10.1.1.200' Sep 9 10:41:36 VERBOSE[16644] logger.c: Destroying call '56c784b3-15d91a19-2fc96602@10.1.1.200' Sep 9 10:52:36 DEBUG[16644] chan_sip.c: Header: REGISTER sip:10.1.1.48:5060 SIP/2.0 (35) Sep 9 10:52:36 DEBUG[16644] chan_sip.c: Header: Via: SIP/2.0/UDP 10.1.1.201;branch=z9hG4bKc12cf1ae6002331 (57) Sep 9 10:52:36 DEBUG[16644] chan_sip.c: Header: From: "Scott" ;tag=EDE85FF6-86E06D93 (65) Sep 9 10:52:36 DEBUG[16644] chan_sip.c: Header: To: (33) Sep 9 10:52:36 DEBUG[16644] chan_sip.c: Header: CSeq: 1049 REGISTER (19) Sep 9 10:52:36 DEBUG[16644] chan_sip.c: Header: Call-ID: 53a44b2a-7d482150-a3adcf2d@10.1.1.201 (46) Sep 9 10:52:36 DEBUG[16644] chan_sip.c: Header: Contact: ;methods="INVITE, ACK, BYE, CANCEL, OPTIONS, INFO, MESSAGE, SUBSCRIBE, NOTIFY, PRACK, UPDATE, REFER" (139) Sep 9 10:52:36 DEBUG[16644] chan_sip.c: Header: User-Agent: PolycomSoundPointIP-SPIP_600-UA/1.5.2.0054 (54) Sep 9 10:52:36 DEBUG[16644] chan_sip.c: Header: Authorization: Digest username="scott-polycom", realm="asterisk", nonce="416491e5", uri="sip:10.1.1.48:5060", response="a63364e82170a5eb5ddf398557f56849", algorithm=MD5 (168) Sep 9 10:52:36 DEBUG[16644] chan_sip.c: Header: Max-Forwards: 70 (16) Sep 9 10:52:36 DEBUG[16644] chan_sip.c: Header: Expires: 3600 (13) Sep 9 10:52:36 DEBUG[16644] chan_sip.c: Header: Content-Length: 0 (17) Sep 9 10:52:36 DEBUG[16644] chan_sip.c: Header: (0) Sep 9 10:52:36 DEBUG[16644] chan_sip.c: Allocating new SIP dialog for 53a44b2a-7d482150-a3adcf2d@10.1.1.201 - REGISTER (No RTP) Sep 9 10:52:36 DEBUG[16644] chan_sip.c: **** Received REGISTER (2) - Command in SIP REGISTER Sep 9 10:52:36 DEBUG[16644] chan_sip.c: Header: REGISTER sip:10.1.1.48:5060 SIP/2.0 (35) Sep 9 10:52:36 DEBUG[16644] chan_sip.c: Header: Via: SIP/2.0/UDP 10.1.1.201;branch=z9hG4bKbb3fb2d4AD1ECC97 (58) Sep 9 10:52:36 DEBUG[16644] chan_sip.c: Header: From: "Scott" ;tag=EDE85FF6-86E06D93 (65) Sep 9 10:52:36 DEBUG[16644] chan_sip.c: Header: To: (33) Sep 9 10:52:36 DEBUG[16644] chan_sip.c: Header: CSeq: 1050 REGISTER (19) Sep 9 10:52:36 DEBUG[16644] chan_sip.c: Header: Call-ID: 53a44b2a-7d482150-a3adcf2d@10.1.1.201 (46) Sep 9 10:52:36 DEBUG[16644] chan_sip.c: Header: Contact: ;methods="INVITE, ACK, BYE, CANCEL, OPTIONS, INFO, MESSAGE, SUBSCRIBE, NOTIFY, PRACK, UPDATE, REFER" (139) Sep 9 10:52:36 DEBUG[16644] chan_sip.c: Header: User-Agent: PolycomSoundPointIP-SPIP_600-UA/1.5.2.0054 (54) Sep 9 10:52:36 DEBUG[16644] chan_sip.c: Header: Authorization: Digest username="scott-polycom", realm="asterisk", nonce="4bed59bc", uri="sip:10.1.1.48:5060", response="e28c096ca4f9e7ad9976b0170d1253d0", algorithm=MD5 (168) Sep 9 10:52:36 DEBUG[16644] chan_sip.c: Header: Max-Forwards: 70 (16) Sep 9 10:52:36 DEBUG[16644] chan_sip.c: Header: Expires: 3600 (13) Sep 9 10:52:36 DEBUG[16644] chan_sip.c: Header: Content-Length: 0 (17) Sep 9 10:52:36 DEBUG[16644] chan_sip.c: Header: (0) Sep 9 10:52:36 DEBUG[16644] chan_sip.c: **** Received REGISTER (2) - Command in SIP REGISTER Sep 9 10:52:36 DEBUG[16644] chan_sip.c: Checking device state for DNS host scott Sep 9 10:52:36 DEBUG[16644] devicestate.c: Changing state for SIP/scott - state 4 (Invalid) Sep 9 10:52:40 DEBUG[16644] chan_sip.c: Allocating new SIP dialog for (No Call-ID) - NOTIFY (No RTP) Sep 9 10:52:40 DEBUG[16644] chan_sip.c: Header: NOTIFY sip:scott-polycom@10.1.1.201 SIP/2.0 (43) Sep 9 10:52:40 DEBUG[16644] chan_sip.c: Header: Via: SIP/2.0/UDP 10.1.1.48:5060;branch=z9hG4bK15ed8019 (54) Sep 9 10:52:40 DEBUG[16644] chan_sip.c: Header: From: "asterisk" ;tag=as63c81d2c (56) Sep 9 10:52:40 DEBUG[16644] chan_sip.c: Header: To: (34) Sep 9 10:52:40 DEBUG[16644] chan_sip.c: Header: Contact: (33) Sep 9 10:52:40 DEBUG[16644] chan_sip.c: Header: Call-ID: 3d65c8f1135dc93b57f045341c27bf8d@10.1.1.48 (51) Sep 9 10:52:40 DEBUG[16644] chan_sip.c: Header: CSeq: 102 NOTIFY (16) Sep 9 10:52:40 DEBUG[16644] chan_sip.c: Header: User-Agent: Asterisk PBX (24) Sep 9 10:52:40 DEBUG[16644] chan_sip.c: Header: Event: message-summary (22) Sep 9 10:52:40 DEBUG[16644] chan_sip.c: Header: Content-Type: application/simple-message-summary (48) Sep 9 10:52:40 DEBUG[16644] chan_sip.c: Header: Content-Length: 80 (18) Sep 9 10:52:40 DEBUG[16644] chan_sip.c: Header: (0) Sep 9 10:52:40 DEBUG[16644] chan_sip.c: Line: Message-Account: sip:asterisk@ (30) Sep 9 10:52:40 DEBUG[16644] chan_sip.c: Line: Messages-Waiting: no (20) Sep 9 10:52:40 DEBUG[16644] chan_sip.c: Line: Voice-Message: 0/0 (0/0) (24) Sep 9 10:52:40 DEBUG[16644] chan_sip.c: *** SIP TIMER: Initalizing retransmit timer on packet: Id #1058 Sep 9 10:52:40 DEBUG[16644] chan_sip.c: Header: SIP/2.0 200 OK (14) Sep 9 10:52:40 DEBUG[16644] chan_sip.c: Header: Via: SIP/2.0/UDP 10.1.1.48:5060;branch=z9hG4bK15ed8019 (54) Sep 9 10:52:40 DEBUG[16644] chan_sip.c: Header: From: "asterisk" ;tag=as63c81d2c (56) Sep 9 10:52:40 DEBUG[16644] chan_sip.c: Header: To: ;tag=1D0ECE7D-33151C7A (56) Sep 9 10:52:40 DEBUG[16644] chan_sip.c: Header: CSeq: 102 NOTIFY (16) Sep 9 10:52:40 DEBUG[16644] chan_sip.c: Header: Call-ID: 3d65c8f1135dc93b57f045341c27bf8d@10.1.1.48 (51) Sep 9 10:52:40 DEBUG[16644] chan_sip.c: Header: Contact: (39) Sep 9 10:52:40 DEBUG[16644] chan_sip.c: Header: Event: message-summary (22) Sep 9 10:52:40 DEBUG[16644] chan_sip.c: Header: User-Agent: PolycomSoundPointIP-SPIP_600-UA/1.5.2.0054 (54) Sep 9 10:52:40 DEBUG[16644] chan_sip.c: Header: Content-Length: 0 (17) Sep 9 10:52:40 DEBUG[16644] chan_sip.c: Header: (0) Sep 9 10:52:40 DEBUG[16644] chan_sip.c: ** SIP TIMER: Cancelling retransmit of packet (reply received) Retransid #1058 Sep 9 10:52:40 DEBUG[16644] chan_sip.c: Stopping retransmission on '3d65c8f1135dc93b57f045341c27bf8d@10.1.1.48' of Request 102: Match Found Sep 9 10:52:51 DEBUG[16644] chan_sip.c: Auto destroying call '53a44b2a-7d482150-a3adcf2d@10.1.1.201' Sep 9 11:01:17 DEBUG[16644] chan_sip.c: Header: REGISTER sip:10.1.1.48:5060 SIP/2.0 (35) Sep 9 11:01:17 DEBUG[16644] chan_sip.c: Header: Via: SIP/2.0/UDP 10.1.1.204;branch=z9hG4bKaee6bc2cFDD3A5F9 (58) Sep 9 11:01:17 DEBUG[16644] chan_sip.c: Header: From: "Kitchen" ;tag=8F90E744-474916F3 (69) Sep 9 11:01:17 DEBUG[16644] chan_sip.c: Header: To: (35) Sep 9 11:01:17 DEBUG[16644] chan_sip.c: Header: CSeq: 1051 REGISTER (19) Sep 9 11:01:17 DEBUG[16644] chan_sip.c: Header: Call-ID: d1897748-f95ebf6e-ec4abb25@10.1.1.204 (46) Sep 9 11:01:17 DEBUG[16644] chan_sip.c: Header: Contact: ;methods="INVITE, ACK, BYE, CANCEL, OPTIONS, INFO, MESSAGE, SUBSCRIBE, NOTIFY, PRACK, UPDATE, REFER" (141) Sep 9 11:01:17 DEBUG[16644] chan_sip.c: Header: User-Agent: PolycomSoundPointIP-SPIP_600-UA/1.5.2.0054 (54) Sep 9 11:01:17 DEBUG[16644] chan_sip.c: Header: Authorization: Digest username="kitchen-polycom", realm="asterisk", nonce="145ccc7c", uri="sip:10.1.1.48:5060", response="c3848a14ea06ff672ad8434c4729f9be", algorithm=MD5 (170) Sep 9 11:01:17 DEBUG[16644] chan_sip.c: Header: Max-Forwards: 70 (16) Sep 9 11:01:17 DEBUG[16644] chan_sip.c: Header: Expires: 3600 (13) Sep 9 11:01:17 DEBUG[16644] chan_sip.c: Header: Content-Length: 0 (17) Sep 9 11:01:17 DEBUG[16644] chan_sip.c: Header: (0) Sep 9 11:01:17 DEBUG[16644] chan_sip.c: Allocating new SIP dialog for d1897748-f95ebf6e-ec4abb25@10.1.1.204 - REGISTER (No RTP) Sep 9 11:01:17 DEBUG[16644] chan_sip.c: **** Received REGISTER (2) - Command in SIP REGISTER Sep 9 11:01:18 DEBUG[16644] chan_sip.c: Header: REGISTER sip:10.1.1.48:5060 SIP/2.0 (35) Sep 9 11:01:18 DEBUG[16644] chan_sip.c: Header: Via: SIP/2.0/UDP 10.1.1.204;branch=z9hG4bKeba0e272FF64D367 (58) Sep 9 11:01:18 DEBUG[16644] chan_sip.c: Header: From: "Kitchen" ;tag=8F90E744-474916F3 (69) Sep 9 11:01:18 DEBUG[16644] chan_sip.c: Header: To: (35) Sep 9 11:01:18 DEBUG[16644] chan_sip.c: Header: CSeq: 1052 REGISTER (19) Sep 9 11:01:18 DEBUG[16644] chan_sip.c: Header: Call-ID: d1897748-f95ebf6e-ec4abb25@10.1.1.204 (46) Sep 9 11:01:18 DEBUG[16644] chan_sip.c: Header: Contact: ;methods="INVITE, ACK, BYE, CANCEL, OPTIONS, INFO, MESSAGE, SUBSCRIBE, NOTIFY, PRACK, UPDATE, REFER" (141) Sep 9 11:01:18 DEBUG[16644] chan_sip.c: Header: User-Agent: PolycomSoundPointIP-SPIP_600-UA/1.5.2.0054 (54) Sep 9 11:01:18 DEBUG[16644] chan_sip.c: Header: Authorization: Digest username="kitchen-polycom", realm="asterisk", nonce="50db683b", uri="sip:10.1.1.48:5060", response="b8ebdbaf38f03eee28c77305b3560438", algorithm=MD5 (170) Sep 9 11:01:18 DEBUG[16644] chan_sip.c: Header: Max-Forwards: 70 (16) Sep 9 11:01:18 DEBUG[16644] chan_sip.c: Header: Expires: 3600 (13) Sep 9 11:01:18 DEBUG[16644] chan_sip.c: Header: Content-Length: 0 (17) Sep 9 11:01:18 DEBUG[16644] chan_sip.c: Header: (0) Sep 9 11:01:18 DEBUG[16644] chan_sip.c: **** Received REGISTER (2) - Command in SIP REGISTER Sep 9 11:01:18 DEBUG[16644] chan_sip.c: Checking device state for DNS host kitchen Sep 9 11:01:18 DEBUG[16644] devicestate.c: Changing state for SIP/kitchen - state 4 (Invalid) Sep 9 11:01:24 DEBUG[16644] chan_sip.c: Header: REGISTER sip:10.1.1.48:5060 SIP/2.0 (35) Sep 9 11:01:24 DEBUG[16644] chan_sip.c: Header: Via: SIP/2.0/UDP 10.1.1.202;branch=z9hG4bK61ee9967B5A6F114 (58) Sep 9 11:01:24 DEBUG[16644] chan_sip.c: Header: From: "Guest Room" ;tag=CCF2567D-4372788C (75) Sep 9 11:01:24 DEBUG[16644] chan_sip.c: Header: To: (38) Sep 9 11:01:24 DEBUG[16644] chan_sip.c: Header: CSeq: 1051 REGISTER (19) Sep 9 11:01:24 DEBUG[16644] chan_sip.c: Header: Call-ID: 3b501911-4c048fff-3eeaecc6@10.1.1.202 (46) Sep 9 11:01:24 DEBUG[16644] chan_sip.c: Header: Contact: ;methods="INVITE, ACK, BYE, CANCEL, OPTIONS, INFO, MESSAGE, SUBSCRIBE, NOTIFY, PRACK, UPDATE, REFER" (144) Sep 9 11:01:24 DEBUG[16644] chan_sip.c: Header: User-Agent: PolycomSoundPointIP-SPIP_600-UA/1.5.2.0054 (54) Sep 9 11:01:24 DEBUG[16644] chan_sip.c: Header: Authorization: Digest username="guest-room-polycom", realm="asterisk", nonce="36b0ef60", uri="sip:10.1.1.48:5060", response="e3bc25f5c9e9c6eae867997006133f25", algorithm=MD5 (173) Sep 9 11:01:24 DEBUG[16644] chan_sip.c: Header: Max-Forwards: 70 (16) Sep 9 11:01:24 DEBUG[16644] chan_sip.c: Header: Expires: 3600 (13) Sep 9 11:01:24 DEBUG[16644] chan_sip.c: Header: Content-Length: 0 (17) Sep 9 11:01:24 DEBUG[16644] chan_sip.c: Header: (0) Sep 9 11:01:24 DEBUG[16644] chan_sip.c: Allocating new SIP dialog for 3b501911-4c048fff-3eeaecc6@10.1.1.202 - REGISTER (No RTP) Sep 9 11:01:24 DEBUG[16644] chan_sip.c: **** Received REGISTER (2) - Command in SIP REGISTER Sep 9 11:01:24 DEBUG[16644] chan_sip.c: Header: REGISTER sip:10.1.1.48:5060 SIP/2.0 (35) Sep 9 11:01:24 DEBUG[16644] chan_sip.c: Header: Via: SIP/2.0/UDP 10.1.1.202;branch=z9hG4bK1b5188a5E3D7116A (58) Sep 9 11:01:24 DEBUG[16644] chan_sip.c: Header: From: "Guest Room" ;tag=CCF2567D-4372788C (75) Sep 9 11:01:24 DEBUG[16644] chan_sip.c: Header: To: (38) Sep 9 11:01:24 DEBUG[16644] chan_sip.c: Header: CSeq: 1052 REGISTER (19) Sep 9 11:01:24 DEBUG[16644] chan_sip.c: Header: Call-ID: 3b501911-4c048fff-3eeaecc6@10.1.1.202 (46) Sep 9 11:01:24 DEBUG[16644] chan_sip.c: Header: Contact: ;methods="INVITE, ACK, BYE, CANCEL, OPTIONS, INFO, MESSAGE, SUBSCRIBE, NOTIFY, PRACK, UPDATE, REFER" (144) Sep 9 11:01:24 DEBUG[16644] chan_sip.c: Header: User-Agent: PolycomSoundPointIP-SPIP_600-UA/1.5.2.0054 (54) Sep 9 11:01:24 DEBUG[16644] chan_sip.c: Header: Authorization: Digest username="guest-room-polycom", realm="asterisk", nonce="4d89595c", uri="sip:10.1.1.48:5060", response="c4ce946b166a7b1bef78e771b99bb5e8", algorithm=MD5 (173) Sep 9 11:01:24 DEBUG[16644] chan_sip.c: Header: Max-Forwards: 70 (16) Sep 9 11:01:24 DEBUG[16644] chan_sip.c: Header: Expires: 3600 (13) Sep 9 11:01:24 DEBUG[16644] chan_sip.c: Header: Content-Length: 0 (17) Sep 9 11:01:24 DEBUG[16644] chan_sip.c: Header: (0) Sep 9 11:01:24 DEBUG[16644] chan_sip.c: **** Received REGISTER (2) - Command in SIP REGISTER Sep 9 11:01:24 DEBUG[16644] chan_sip.c: Checking device state for DNS host guest Sep 9 11:01:25 DEBUG[16644] devicestate.c: Changing state for SIP/guest - state 4 (Invalid) Sep 9 11:01:30 DEBUG[16644] chan_sip.c: Header: REGISTER sip:10.1.1.48:5060 SIP/2.0 (35) Sep 9 11:01:30 DEBUG[16644] chan_sip.c: Header: Via: SIP/2.0/UDP 10.1.1.203;branch=z9hG4bKf18f6e2f51C04708 (58) Sep 9 11:01:30 DEBUG[16644] chan_sip.c: Header: From: "Theater" ;tag=E315B29C-EC409443 (69) Sep 9 11:01:30 DEBUG[16644] chan_sip.c: Header: To: (35) Sep 9 11:01:30 DEBUG[16644] chan_sip.c: Header: CSeq: 965 REGISTER (18) Sep 9 11:01:30 DEBUG[16644] chan_sip.c: Header: Call-ID: 235aca78-dd4310ca-9b394431@10.1.1.203 (46) Sep 9 11:01:30 DEBUG[16644] chan_sip.c: Header: Contact: ;methods="INVITE, ACK, BYE, CANCEL, OPTIONS, INFO, MESSAGE, SUBSCRIBE, NOTIFY, PRACK, UPDATE, REFER" (141) Sep 9 11:01:30 DEBUG[16644] chan_sip.c: Header: User-Agent: PolycomSoundPointIP-SPIP_600-UA/1.5.2.0054 (54) Sep 9 11:01:30 DEBUG[16644] chan_sip.c: Header: Authorization: Digest username="theater-polycom", realm="asterisk", nonce="1700801a", uri="sip:10.1.1.48:5060", response="d14aa90684f3149cea6e692c366e094b", algorithm=MD5 (170) Sep 9 11:01:30 DEBUG[16644] chan_sip.c: Header: Max-Forwards: 70 (16) Sep 9 11:01:30 DEBUG[16644] chan_sip.c: Header: Expires: 3600 (13) Sep 9 11:01:30 DEBUG[16644] chan_sip.c: Header: Content-Length: 0 (17) Sep 9 11:01:30 DEBUG[16644] chan_sip.c: Header: (0) Sep 9 11:01:30 DEBUG[16644] chan_sip.c: Allocating new SIP dialog for 235aca78-dd4310ca-9b394431@10.1.1.203 - REGISTER (No RTP) Sep 9 11:01:30 DEBUG[16644] chan_sip.c: **** Received REGISTER (2) - Command in SIP REGISTER Sep 9 11:01:30 DEBUG[16644] chan_sip.c: Header: REGISTER sip:10.1.1.48:5060 SIP/2.0 (35) Sep 9 11:01:30 DEBUG[16644] chan_sip.c: Header: Via: SIP/2.0/UDP 10.1.1.203;branch=z9hG4bK9931cec1CC58E95A (58) Sep 9 11:01:30 DEBUG[16644] chan_sip.c: Header: From: "Theater" ;tag=E315B29C-EC409443 (69) Sep 9 11:01:30 DEBUG[16644] chan_sip.c: Header: To: (35) Sep 9 11:01:30 DEBUG[16644] chan_sip.c: Header: CSeq: 966 REGISTER (18) Sep 9 11:01:30 DEBUG[16644] chan_sip.c: Header: Call-ID: 235aca78-dd4310ca-9b394431@10.1.1.203 (46) Sep 9 11:01:30 DEBUG[16644] chan_sip.c: Header: Contact: ;methods="INVITE, ACK, BYE, CANCEL, OPTIONS, INFO, MESSAGE, SUBSCRIBE, NOTIFY, PRACK, UPDATE, REFER" (141) Sep 9 11:01:30 DEBUG[16644] chan_sip.c: Header: User-Agent: PolycomSoundPointIP-SPIP_600-UA/1.5.2.0054 (54) Sep 9 11:01:30 DEBUG[16644] chan_sip.c: Header: Authorization: Digest username="theater-polycom", realm="asterisk", nonce="4534d547", uri="sip:10.1.1.48:5060", response="43e93f8d125ab1e8aceca84ad9eeb79d", algorithm=MD5 (170) Sep 9 11:01:30 DEBUG[16644] chan_sip.c: Header: Max-Forwards: 70 (16) Sep 9 11:01:30 DEBUG[16644] chan_sip.c: Header: Expires: 3600 (13) Sep 9 11:01:30 DEBUG[16644] chan_sip.c: Header: Content-Length: 0 (17) Sep 9 11:01:30 DEBUG[16644] chan_sip.c: Header: (0) Sep 9 11:01:30 DEBUG[16644] chan_sip.c: **** Received REGISTER (2) - Command in SIP REGISTER Sep 9 11:01:30 DEBUG[16644] chan_sip.c: Checking device state for DNS host theater Sep 9 11:01:30 DEBUG[16644] devicestate.c: Changing state for SIP/theater - state 4 (Invalid) Sep 9 11:01:33 DEBUG[16644] chan_sip.c: Auto destroying call 'd1897748-f95ebf6e-ec4abb25@10.1.1.204' Sep 9 11:01:39 DEBUG[16644] chan_sip.c: Auto destroying call '3b501911-4c048fff-3eeaecc6@10.1.1.202' Sep 9 11:01:45 DEBUG[16644] chan_sip.c: Auto destroying call '235aca78-dd4310ca-9b394431@10.1.1.203' Sep 9 11:08:08 VERBOSE[16644] logger.c: <-- SIP read from 10.1.1.200:5060: SUBSCRIBE sip:2000@10.1.1.48 SIP/2.0Via: SIP/2.0/UDP 10.1.1.200;branch=z9hG4bKae8833c3C741C892From: "Wilson" ;tag=3E850BCC-20B703CDTo: ;tag=as211d38f3CSeq: 4 SUBSCRIBECall-ID: 7928dc00-5949e356-88e662a7@10.1.1.200Contact: Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, INFO, MESSAGE, SUBSCRIBE, NOTIFY, PRACK, UPDATE, REFEREvent: presenceUser-Agent: PolycomSoundPointIP-SPIP_600-UA/1.5.2.0054Authorization: Digest username="wilson-polycom", realm="asterisk", nonce="0aea62af", uri="sip:2000@10.1.1.48:5060", response="adfc6e097dec38a19ac7321cb0be0fac", algorithm=MD5Max-Forwards: 70Expires: 3600Content-Length: 0 Sep 9 11:08:08 DEBUG[16644] chan_sip.c: Header: SUBSCRIBE sip:2000@10.1.1.48 SIP/2.0 (36) Sep 9 11:08:08 DEBUG[16644] chan_sip.c: Header: Via: SIP/2.0/UDP 10.1.1.200;branch=z9hG4bKae8833c3C741C892 (58) Sep 9 11:08:08 DEBUG[16644] chan_sip.c: Header: From: "Wilson" ;tag=3E850BCC-20B703CD (67) Sep 9 11:08:08 DEBUG[16644] chan_sip.c: Header: To: ;tag=as211d38f3 (39) Sep 9 11:08:08 DEBUG[16644] chan_sip.c: Header: CSeq: 4 SUBSCRIBE (17) Sep 9 11:08:08 DEBUG[16644] chan_sip.c: Header: Call-ID: 7928dc00-5949e356-88e662a7@10.1.1.200 (46) Sep 9 11:08:08 DEBUG[16644] chan_sip.c: Header: Contact: (40) Sep 9 11:08:08 DEBUG[16644] chan_sip.c: Header: Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, INFO, MESSAGE, SUBSCRIBE, NOTIFY, PRACK, UPDATE, REFER (96) Sep 9 11:08:08 DEBUG[16644] chan_sip.c: Header: Event: presence (15) Sep 9 11:08:08 DEBUG[16644] chan_sip.c: Header: User-Agent: PolycomSoundPointIP-SPIP_600-UA/1.5.2.0054 (54) Sep 9 11:08:08 DEBUG[16644] chan_sip.c: Header: Authorization: Digest username="wilson-polycom", realm="asterisk", nonce="0aea62af", uri="sip:2000@10.1.1.48:5060", response="adfc6e097dec38a19ac7321cb0be0fac", algorithm=MD5 (174) Sep 9 11:08:08 DEBUG[16644] chan_sip.c: Header: Max-Forwards: 70 (16) Sep 9 11:08:08 DEBUG[16644] chan_sip.c: Header: Expires: 3600 (13) Sep 9 11:08:08 DEBUG[16644] chan_sip.c: Header: Content-Length: 0 (17) Sep 9 11:08:08 DEBUG[16644] chan_sip.c: Header: (0) Sep 9 11:08:08 VERBOSE[16644] logger.c: --- (14 headers 0 lines)Sep 9 11:08:08 VERBOSE[16644] logger.c: --- (14 headers 0 lines)--- Sep 9 11:08:08 DEBUG[16644] chan_sip.c: **** Received SUBSCRIBE (10) - Command in SIP SUBSCRIBE Sep 9 11:08:08 DEBUG[16644] chan_sip.c: Got a re-subscribe on existing subscription 7928dc00-5949e356-88e662a7@10.1.1.200 Sep 9 11:08:08 DEBUG[16644] chan_sip.c: Adding subscription for extension 2000 context from-sip for peer wilson-polycom Sep 9 11:08:08 VERBOSE[16644] logger.c: Transmitting (no NAT) to 10.1.1.200:5060: SIP/2.0 200 OKVia: SIP/2.0/UDP 10.1.1.200;branch=z9hG4bKae8833c3C741C892From: "Wilson" ;tag=3E850BCC-20B703CDTo: ;tag=as211d38f3Call-ID: 7928dc00-5949e356-88e662a7@10.1.1.200CSeq: 4 SUBSCRIBEUser-Agent: Asterisk PBXAllow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFYExpires: 3600Contact: ;expires=3600Content-Length: 0 --- Sep 9 11:08:08 VERBOSE[16644] logger.c: Scheduling destruction of call '7928dc00-5949e356-88e662a7@10.1.1.200' in 3610000 ms Sep 9 11:08:08 DEBUG[16644] chan_sip.c: Checking device state for peer scott-polycom Sep 9 11:08:08 DEBUG[16644] chan_sip.c: Checking device state for peer scott-polycom Sep 9 11:08:08 VERBOSE[16644] logger.c: Reliably Transmitting (no NAT) to 10.1.1.200:5060: NOTIFY sip:wilson-polycom@10.1.1.200 SIP/2.0Via: SIP/2.0/UDP 10.1.1.48:5060;branch=z9hG4bK452966ebFrom: ;tag=as211d38f3To: "Wilson" ;tag=3E850BCC-20B703CDContact: Call-ID: 7928dc00-5949e356-88e662a7@10.1.1.200CSeq: 104 NOTIFYUser-Agent: Asterisk PBXEvent: presenceContent-Type: application/xpidf+xmlSubscription-State: activeContent-Length: 348
--- Sep 9 11:08:08 DEBUG[16644] chan_sip.c: *** SIP TIMER: Initalizing retransmit timer on packet: Id #1070 Sep 9 11:08:08 VERBOSE[16644] logger.c: <-- SIP read from 10.1.1.200:5060: SIP/2.0 200 OKVia: SIP/2.0/UDP 10.1.1.48:5060;branch=z9hG4bK452966ebFrom: ;tag=as211d38f3To: "Wilson" ;tag=3E850BCC-20B703CDCSeq: 104 NOTIFYCall-ID: 7928dc00-5949e356-88e662a7@10.1.1.200Contact: Event: presenceUser-Agent: PolycomSoundPointIP-SPIP_600-UA/1.5.2.0054Content-Length: 0 Sep 9 11:08:08 DEBUG[16644] chan_sip.c: Header: SIP/2.0 200 OK (14) Sep 9 11:08:08 DEBUG[16644] chan_sip.c: Header: Via: SIP/2.0/UDP 10.1.1.48:5060;branch=z9hG4bK452966eb (54) Sep 9 11:08:08 DEBUG[16644] chan_sip.c: Header: From: ;tag=as211d38f3 (41) Sep 9 11:08:08 DEBUG[16644] chan_sip.c: Header: To: "Wilson" ;tag=3E850BCC-20B703CD (65) Sep 9 11:08:08 DEBUG[16644] chan_sip.c: Header: CSeq: 104 NOTIFY (16) Sep 9 11:08:08 DEBUG[16644] chan_sip.c: Header: Call-ID: 7928dc00-5949e356-88e662a7@10.1.1.200 (46) Sep 9 11:08:08 DEBUG[16644] chan_sip.c: Header: Contact: (40) Sep 9 11:08:08 DEBUG[16644] chan_sip.c: Header: Event: presence (15) Sep 9 11:08:08 DEBUG[16644] chan_sip.c: Header: User-Agent: PolycomSoundPointIP-SPIP_600-UA/1.5.2.0054 (54) Sep 9 11:08:08 DEBUG[16644] chan_sip.c: Header: Content-Length: 0 (17) Sep 9 11:08:08 DEBUG[16644] chan_sip.c: Header: (0) Sep 9 11:08:08 VERBOSE[16644] logger.c: --- (10 headers 0 lines)Sep 9 11:08:08 VERBOSE[16644] logger.c: --- (10 headers 0 lines)--- Sep 9 11:08:08 DEBUG[16644] chan_sip.c: ** SIP TIMER: Cancelling retransmit of packet (reply received) Retransid #1070 Sep 9 11:08:08 DEBUG[16644] chan_sip.c: Stopping retransmission on '7928dc00-5949e356-88e662a7@10.1.1.200' of Request 104: Match Found Sep 9 11:08:08 VERBOSE[16644] logger.c: SIP Response message for INCOMING dialog NOTIFY arrived Sep 9 11:11:21 VERBOSE[16644] logger.c: <-- SIP read from 10.1.1.200:5060: REGISTER sip:10.1.1.48:5060 SIP/2.0Via: SIP/2.0/UDP 10.1.1.200;branch=z9hG4bK96eb342970654648From: "Wilson" ;tag=637E8B9F-FF6C90B8To: CSeq: 71 REGISTERCall-ID: 56c784b3-15d91a19-2fc96602@10.1.1.200Contact: ;methods="INVITE, ACK, BYE, CANCEL, OPTIONS, INFO, MESSAGE, SUBSCRIBE, NOTIFY, PRACK, UPDATE, REFER"User-Agent: PolycomSoundPointIP-SPIP_600-UA/1.5.2.0054Authorization: Digest username="wilson-polycom", realm="asterisk", nonce="23033613", uri="sip:10.1.1.48:5060", response="9a9a168df20ca9c2c0ffa6dbb54d8c3b", algorithm=MD5Max-Forwards: 70Expires: 3600Content-Length: 0 Sep 9 11:11:21 DEBUG[16644] chan_sip.c: Header: REGISTER sip:10.1.1.48:5060 SIP/2.0 (35) Sep 9 11:11:21 DEBUG[16644] chan_sip.c: Header: Via: SIP/2.0/UDP 10.1.1.200;branch=z9hG4bK96eb342970654648 (58) Sep 9 11:11:21 DEBUG[16644] chan_sip.c: Header: From: "Wilson" ;tag=637E8B9F-FF6C90B8 (67) Sep 9 11:11:21 DEBUG[16644] chan_sip.c: Header: To: (34) Sep 9 11:11:21 DEBUG[16644] chan_sip.c: Header: CSeq: 71 REGISTER (17) Sep 9 11:11:21 DEBUG[16644] chan_sip.c: Header: Call-ID: 56c784b3-15d91a19-2fc96602@10.1.1.200 (46) Sep 9 11:11:21 DEBUG[16644] chan_sip.c: Header: Contact: ;methods="INVITE, ACK, BYE, CANCEL, OPTIONS, INFO, MESSAGE, SUBSCRIBE, NOTIFY, PRACK, UPDATE, REFER" (140) Sep 9 11:11:21 DEBUG[16644] chan_sip.c: Header: User-Agent: PolycomSoundPointIP-SPIP_600-UA/1.5.2.0054 (54) Sep 9 11:11:21 DEBUG[16644] chan_sip.c: Header: Authorization: Digest username="wilson-polycom", realm="asterisk", nonce="23033613", uri="sip:10.1.1.48:5060", response="9a9a168df20ca9c2c0ffa6dbb54d8c3b", algorithm=MD5 (169) Sep 9 11:11:21 DEBUG[16644] chan_sip.c: Header: Max-Forwards: 70 (16) Sep 9 11:11:21 DEBUG[16644] chan_sip.c: Header: Expires: 3600 (13) Sep 9 11:11:21 DEBUG[16644] chan_sip.c: Header: Content-Length: 0 (17) Sep 9 11:11:21 DEBUG[16644] chan_sip.c: Header: (0) Sep 9 11:11:21 VERBOSE[16644] logger.c: --- (12 headers 0 lines)Sep 9 11:11:21 VERBOSE[16644] logger.c: --- (12 headers 0 lines)--- Sep 9 11:11:21 DEBUG[16644] chan_sip.c: Allocating new SIP dialog for 56c784b3-15d91a19-2fc96602@10.1.1.200 - REGISTER (No RTP) Sep 9 11:11:21 DEBUG[16644] chan_sip.c: **** Received REGISTER (2) - Command in SIP REGISTER Sep 9 11:11:21 VERBOSE[16644] logger.c: Using latest REGISTER request as basis request Sep 9 11:11:21 VERBOSE[16644] logger.c: Sending to 10.1.1.200 : 5060 (non-NAT) Sep 9 11:11:21 VERBOSE[16644] logger.c: Transmitting (no NAT) to 10.1.1.200:5060: SIP/2.0 100 TryingVia: SIP/2.0/UDP 10.1.1.200;branch=z9hG4bK96eb342970654648From: "Wilson" ;tag=637E8B9F-FF6C90B8To: Call-ID: 56c784b3-15d91a19-2fc96602@10.1.1.200CSeq: 71 REGISTERUser-Agent: Asterisk PBXAllow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFYContact: Content-Length: 0 --- Sep 9 11:11:21 VERBOSE[16644] logger.c: Transmitting (no NAT) to 10.1.1.200:5060: SIP/2.0 401 UnauthorizedVia: SIP/2.0/UDP 10.1.1.200;branch=z9hG4bK96eb342970654648From: "Wilson" ;tag=637E8B9F-FF6C90B8To: ;tag=as255b0bb8Call-ID: 56c784b3-15d91a19-2fc96602@10.1.1.200CSeq: 71 REGISTERUser-Agent: Asterisk PBXAllow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFYContact: WWW-Authenticate: Digest realm="asterisk", nonce="3e4e73fe" Content-Length: 0 --- Sep 9 11:11:21 VERBOSE[16644] logger.c: Scheduling destruction of call '56c784b3-15d91a19-2fc96602@10.1.1.200' in 15000 ms Sep 9 11:11:21 VERBOSE[16644] logger.c: <-- SIP read from 10.1.1.200:5060: REGISTER sip:10.1.1.48:5060 SIP/2.0Via: SIP/2.0/UDP 10.1.1.200;branch=z9hG4bKe26a0af9546A71EFrom: "Wilson" ;tag=637E8B9F-FF6C90B8To: CSeq: 72 REGISTERCall-ID: 56c784b3-15d91a19-2fc96602@10.1.1.200Contact: ;methods="INVITE, ACK, BYE, CANCEL, OPTIONS, INFO, MESSAGE, SUBSCRIBE, NOTIFY, PRACK, UPDATE, REFER"User-Agent: PolycomSoundPointIP-SPIP_600-UA/1.5.2.0054Authorization: Digest username="wilson-polycom", realm="asterisk", nonce="3e4e73fe", uri="sip:10.1.1.48:5060", response="1a3a95a0798b4d9328e8a437f4d86298", algorithm=MD5Max-Forwards: 70Expires: 3600Content-Length: 0 Sep 9 11:11:21 DEBUG[16644] chan_sip.c: Header: REGISTER sip:10.1.1.48:5060 SIP/2.0 (35) Sep 9 11:11:21 DEBUG[16644] chan_sip.c: Header: Via: SIP/2.0/UDP 10.1.1.200;branch=z9hG4bKe26a0af9546A71E (57) Sep 9 11:11:21 DEBUG[16644] chan_sip.c: Header: From: "Wilson" ;tag=637E8B9F-FF6C90B8 (67) Sep 9 11:11:21 DEBUG[16644] chan_sip.c: Header: To: (34) Sep 9 11:11:21 DEBUG[16644] chan_sip.c: Header: CSeq: 72 REGISTER (17) Sep 9 11:11:21 DEBUG[16644] chan_sip.c: Header: Call-ID: 56c784b3-15d91a19-2fc96602@10.1.1.200 (46) Sep 9 11:11:21 DEBUG[16644] chan_sip.c: Header: Contact: ;methods="INVITE, ACK, BYE, CANCEL, OPTIONS, INFO, MESSAGE, SUBSCRIBE, NOTIFY, PRACK, UPDATE, REFER" (140) Sep 9 11:11:21 DEBUG[16644] chan_sip.c: Header: User-Agent: PolycomSoundPointIP-SPIP_600-UA/1.5.2.0054 (54) Sep 9 11:11:21 DEBUG[16644] chan_sip.c: Header: Authorization: Digest username="wilson-polycom", realm="asterisk", nonce="3e4e73fe", uri="sip:10.1.1.48:5060", response="1a3a95a0798b4d9328e8a437f4d86298", algorithm=MD5 (169) Sep 9 11:11:21 DEBUG[16644] chan_sip.c: Header: Max-Forwards: 70 (16) Sep 9 11:11:21 DEBUG[16644] chan_sip.c: Header: Expires: 3600 (13) Sep 9 11:11:21 DEBUG[16644] chan_sip.c: Header: Content-Length: 0 (17) Sep 9 11:11:21 DEBUG[16644] chan_sip.c: Header: (0) Sep 9 11:11:21 VERBOSE[16644] logger.c: --- (12 headers 0 lines)Sep 9 11:11:21 VERBOSE[16644] logger.c: --- (12 headers 0 lines)--- Sep 9 11:11:21 DEBUG[16644] chan_sip.c: **** Received REGISTER (2) - Command in SIP REGISTER Sep 9 11:11:21 VERBOSE[16644] logger.c: Using latest REGISTER request as basis request Sep 9 11:11:21 VERBOSE[16644] logger.c: Sending to 10.1.1.200 : 5060 (non-NAT) Sep 9 11:11:21 VERBOSE[16644] logger.c: Transmitting (no NAT) to 10.1.1.200:5060: SIP/2.0 100 TryingVia: SIP/2.0/UDP 10.1.1.200;branch=z9hG4bKe26a0af9546A71EFrom: "Wilson" ;tag=637E8B9F-FF6C90B8To: Call-ID: 56c784b3-15d91a19-2fc96602@10.1.1.200CSeq: 72 REGISTERUser-Agent: Asterisk PBXAllow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFYContact: Content-Length: 0 --- Sep 9 11:11:21 VERBOSE[16644] logger.c: Transmitting (no NAT) to 10.1.1.200:5060: SIP/2.0 200 OKVia: SIP/2.0/UDP 10.1.1.200;branch=z9hG4bKe26a0af9546A71EFrom: "Wilson" ;tag=637E8B9F-FF6C90B8To: ;tag=as255b0bb8Call-ID: 56c784b3-15d91a19-2fc96602@10.1.1.200CSeq: 72 REGISTERUser-Agent: Asterisk PBXAllow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFYExpires: 3600Contact: ;expires=3600Date: Fri, 09 Sep 2005 18:11:21 GMTContent-Length: 0 --- Sep 9 11:11:21 VERBOSE[16644] logger.c: Scheduling destruction of call '56c784b3-15d91a19-2fc96602@10.1.1.200' in 15000 ms Sep 9 11:11:21 DEBUG[16644] chan_sip.c: Checking device state for peer wilson Sep 9 11:11:21 DEBUG[16644] devicestate.c: Changing state for SIP/wilson - state 5 (Unavailable) Sep 9 11:11:23 DEBUG[16644] chan_sip.c: Allocating new SIP dialog for (No Call-ID) - NOTIFY (No RTP) Sep 9 11:11:23 DEBUG[16644] chan_sip.c: Header: NOTIFY sip:wilson-polycom@10.1.1.200 SIP/2.0 (44) Sep 9 11:11:23 DEBUG[16644] chan_sip.c: Header: Via: SIP/2.0/UDP 10.1.1.48:5060;branch=z9hG4bK7f1e7143 (54) Sep 9 11:11:23 DEBUG[16644] chan_sip.c: Header: From: "asterisk" ;tag=as21d14299 (56) Sep 9 11:11:23 DEBUG[16644] chan_sip.c: Header: To: (35) Sep 9 11:11:23 DEBUG[16644] chan_sip.c: Header: Contact: (33) Sep 9 11:11:23 DEBUG[16644] chan_sip.c: Header: Call-ID: 7ac378682283eeaa449aed595551439d@10.1.1.48 (51) Sep 9 11:11:23 DEBUG[16644] chan_sip.c: Header: CSeq: 102 NOTIFY (16) Sep 9 11:11:23 DEBUG[16644] chan_sip.c: Header: User-Agent: Asterisk PBX (24) Sep 9 11:11:23 DEBUG[16644] chan_sip.c: Header: Event: message-summary (22) Sep 9 11:11:23 DEBUG[16644] chan_sip.c: Header: Content-Type: application/simple-message-summary (48) Sep 9 11:11:23 DEBUG[16644] chan_sip.c: Header: Content-Length: 80 (18) Sep 9 11:11:23 DEBUG[16644] chan_sip.c: Header: (0) Sep 9 11:11:23 DEBUG[16644] chan_sip.c: Line: Message-Account: sip:asterisk@ (30) Sep 9 11:11:23 DEBUG[16644] chan_sip.c: Line: Messages-Waiting: no (20) Sep 9 11:11:23 DEBUG[16644] chan_sip.c: Line: Voice-Message: 0/0 (0/0) (24) Sep 9 11:11:23 VERBOSE[16644] logger.c: 11 headers, 3 lines Sep 9 11:11:23 VERBOSE[16644] logger.c: Reliably Transmitting (no NAT) to 10.1.1.200:5060: NOTIFY sip:wilson-polycom@10.1.1.200 SIP/2.0Via: SIP/2.0/UDP 10.1.1.48:5060;branch=z9hG4bK7f1e7143From: "asterisk" ;tag=as21d14299To: Contact: Call-ID: 7ac378682283eeaa449aed595551439d@10.1.1.48CSeq: 102 NOTIFYUser-Agent: Asterisk PBXEvent: message-summaryContent-Type: application/simple-message-summaryContent-Length: 80Message-Account: sip:asterisk@Messages-Waiting: noVoice-Message: 0/0 (0/0) --- Sep 9 11:11:23 DEBUG[16644] chan_sip.c: *** SIP TIMER: Initalizing retransmit timer on packet: Id #1074 Sep 9 11:11:23 VERBOSE[16644] logger.c: Scheduling destruction of call '7ac378682283eeaa449aed595551439d@10.1.1.48' in 15000 ms Sep 9 11:11:23 VERBOSE[16644] logger.c: <-- SIP read from 10.1.1.200:5060: SIP/2.0 200 OKVia: SIP/2.0/UDP 10.1.1.48:5060;branch=z9hG4bK7f1e7143From: "asterisk" ;tag=as21d14299To: ;tag=DDC93114-1A84EF55CSeq: 102 NOTIFYCall-ID: 7ac378682283eeaa449aed595551439d@10.1.1.48Contact: Event: message-summaryUser-Agent: PolycomSoundPointIP-SPIP_600-UA/1.5.2.0054Content-Length: 0 Sep 9 11:11:23 DEBUG[16644] chan_sip.c: Header: SIP/2.0 200 OK (14) Sep 9 11:11:23 DEBUG[16644] chan_sip.c: Header: Via: SIP/2.0/UDP 10.1.1.48:5060;branch=z9hG4bK7f1e7143 (54) Sep 9 11:11:23 DEBUG[16644] chan_sip.c: Header: From: "asterisk" ;tag=as21d14299 (56) Sep 9 11:11:23 DEBUG[16644] chan_sip.c: Header: To: ;tag=DDC93114-1A84EF55 (57) Sep 9 11:11:23 DEBUG[16644] chan_sip.c: Header: CSeq: 102 NOTIFY (16) Sep 9 11:11:23 DEBUG[16644] chan_sip.c: Header: Call-ID: 7ac378682283eeaa449aed595551439d@10.1.1.48 (51) Sep 9 11:11:23 DEBUG[16644] chan_sip.c: Header: Contact: (40) Sep 9 11:11:23 DEBUG[16644] chan_sip.c: Header: Event: message-summary (22) Sep 9 11:11:23 DEBUG[16644] chan_sip.c: Header: User-Agent: PolycomSoundPointIP-SPIP_600-UA/1.5.2.0054 (54) Sep 9 11:11:23 DEBUG[16644] chan_sip.c: Header: Content-Length: 0 (17) Sep 9 11:11:23 DEBUG[16644] chan_sip.c: Header: (0) Sep 9 11:11:23 VERBOSE[16644] logger.c: --- (10 headers 0 lines)Sep 9 11:11:23 VERBOSE[16644] logger.c: --- (10 headers 0 lines)--- Sep 9 11:11:23 DEBUG[16644] chan_sip.c: ** SIP TIMER: Cancelling retransmit of packet (reply received) Retransid #1074 Sep 9 11:11:23 DEBUG[16644] chan_sip.c: Stopping retransmission on '7ac378682283eeaa449aed595551439d@10.1.1.48' of Request 102: Match Found Sep 9 11:11:23 VERBOSE[16644] logger.c: Destroying call '7ac378682283eeaa449aed595551439d@10.1.1.48' Sep 9 11:11:36 DEBUG[16644] chan_sip.c: Auto destroying call '56c784b3-15d91a19-2fc96602@10.1.1.200' Sep 9 11:11:36 VERBOSE[16644] logger.c: Destroying call '56c784b3-15d91a19-2fc96602@10.1.1.200' Sep 9 11:22:36 DEBUG[16644] chan_sip.c: Header: REGISTER sip:10.1.1.48:5060 SIP/2.0 (35) Sep 9 11:22:36 DEBUG[16644] chan_sip.c: Header: Via: SIP/2.0/UDP 10.1.1.201;branch=z9hG4bK629c8ea0542388E3 (58) Sep 9 11:22:36 DEBUG[16644] chan_sip.c: Header: From: "Scott" ;tag=EDE85FF6-86E06D93 (65) Sep 9 11:22:36 DEBUG[16644] chan_sip.c: Header: To: (33) Sep 9 11:22:36 DEBUG[16644] chan_sip.c: Header: CSeq: 1051 REGISTER (19) Sep 9 11:22:36 DEBUG[16644] chan_sip.c: Header: Call-ID: 53a44b2a-7d482150-a3adcf2d@10.1.1.201 (46) Sep 9 11:22:36 DEBUG[16644] chan_sip.c: Header: Contact: ;methods="INVITE, ACK, BYE, CANCEL, OPTIONS, INFO, MESSAGE, SUBSCRIBE, NOTIFY, PRACK, UPDATE, REFER" (139) Sep 9 11:22:36 DEBUG[16644] chan_sip.c: Header: User-Agent: PolycomSoundPointIP-SPIP_600-UA/1.5.2.0054 (54) Sep 9 11:22:36 DEBUG[16644] chan_sip.c: Header: Authorization: Digest username="scott-polycom", realm="asterisk", nonce="4bed59bc", uri="sip:10.1.1.48:5060", response="e28c096ca4f9e7ad9976b0170d1253d0", algorithm=MD5 (168) Sep 9 11:22:36 DEBUG[16644] chan_sip.c: Header: Max-Forwards: 70 (16) Sep 9 11:22:36 DEBUG[16644] chan_sip.c: Header: Expires: 3600 (13) Sep 9 11:22:36 DEBUG[16644] chan_sip.c: Header: Content-Length: 0 (17) Sep 9 11:22:36 DEBUG[16644] chan_sip.c: Header: (0) Sep 9 11:22:36 DEBUG[16644] chan_sip.c: Allocating new SIP dialog for 53a44b2a-7d482150-a3adcf2d@10.1.1.201 - REGISTER (No RTP) Sep 9 11:22:36 DEBUG[16644] chan_sip.c: **** Received REGISTER (2) - Command in SIP REGISTER Sep 9 11:22:36 DEBUG[16644] chan_sip.c: Header: REGISTER sip:10.1.1.48:5060 SIP/2.0 (35) Sep 9 11:22:36 DEBUG[16644] chan_sip.c: Header: Via: SIP/2.0/UDP 10.1.1.201;branch=z9hG4bKe0d6946EDF85BC9 (57) Sep 9 11:22:36 DEBUG[16644] chan_sip.c: Header: From: "Scott" ;tag=EDE85FF6-86E06D93 (65) Sep 9 11:22:36 DEBUG[16644] chan_sip.c: Header: To: (33) Sep 9 11:22:36 DEBUG[16644] chan_sip.c: Header: CSeq: 1052 REGISTER (19) Sep 9 11:22:36 DEBUG[16644] chan_sip.c: Header: Call-ID: 53a44b2a-7d482150-a3adcf2d@10.1.1.201 (46) Sep 9 11:22:36 DEBUG[16644] chan_sip.c: Header: Contact: ;methods="INVITE, ACK, BYE, CANCEL, OPTIONS, INFO, MESSAGE, SUBSCRIBE, NOTIFY, PRACK, UPDATE, REFER" (139) Sep 9 11:22:36 DEBUG[16644] chan_sip.c: Header: User-Agent: PolycomSoundPointIP-SPIP_600-UA/1.5.2.0054 (54) Sep 9 11:22:36 DEBUG[16644] chan_sip.c: Header: Authorization: Digest username="scott-polycom", realm="asterisk", nonce="6de0ad51", uri="sip:10.1.1.48:5060", response="c7b9376def153643cd8302d479fd2541", algorithm=MD5 (168) Sep 9 11:22:36 DEBUG[16644] chan_sip.c: Header: Max-Forwards: 70 (16) Sep 9 11:22:36 DEBUG[16644] chan_sip.c: Header: Expires: 3600 (13) Sep 9 11:22:36 DEBUG[16644] chan_sip.c: Header: Content-Length: 0 (17) Sep 9 11:22:36 DEBUG[16644] chan_sip.c: Header: (0) Sep 9 11:22:36 DEBUG[16644] chan_sip.c: **** Received REGISTER (2) - Command in SIP REGISTER Sep 9 11:22:36 DEBUG[16644] chan_sip.c: Checking device state for DNS host scott Sep 9 11:22:36 DEBUG[16644] devicestate.c: Changing state for SIP/scott - state 4 (Invalid) Sep 9 11:22:44 DEBUG[16644] chan_sip.c: Allocating new SIP dialog for (No Call-ID) - NOTIFY (No RTP) Sep 9 11:22:44 DEBUG[16644] chan_sip.c: Header: NOTIFY sip:scott-polycom@10.1.1.201 SIP/2.0 (43) Sep 9 11:22:44 DEBUG[16644] chan_sip.c: Header: Via: SIP/2.0/UDP 10.1.1.48:5060;branch=z9hG4bK21d811a1 (54) Sep 9 11:22:44 DEBUG[16644] chan_sip.c: Header: From: "asterisk" ;tag=as45685cdb (56) Sep 9 11:22:44 DEBUG[16644] chan_sip.c: Header: To: (34) Sep 9 11:22:44 DEBUG[16644] chan_sip.c: Header: Contact: (33) Sep 9 11:22:44 DEBUG[16644] chan_sip.c: Header: Call-ID: 664a604429d6fdb6756609db18693fee@10.1.1.48 (51) Sep 9 11:22:44 DEBUG[16644] chan_sip.c: Header: CSeq: 102 NOTIFY (16) Sep 9 11:22:44 DEBUG[16644] chan_sip.c: Header: User-Agent: Asterisk PBX (24) Sep 9 11:22:44 DEBUG[16644] chan_sip.c: Header: Event: message-summary (22) Sep 9 11:22:44 DEBUG[16644] chan_sip.c: Header: Content-Type: application/simple-message-summary (48) Sep 9 11:22:44 DEBUG[16644] chan_sip.c: Header: Content-Length: 80 (18) Sep 9 11:22:44 DEBUG[16644] chan_sip.c: Header: (0) Sep 9 11:22:44 DEBUG[16644] chan_sip.c: Line: Message-Account: sip:asterisk@ (30) Sep 9 11:22:44 DEBUG[16644] chan_sip.c: Line: Messages-Waiting: no (20) Sep 9 11:22:44 DEBUG[16644] chan_sip.c: Line: Voice-Message: 0/0 (0/0) (24) Sep 9 11:22:44 DEBUG[16644] chan_sip.c: *** SIP TIMER: Initalizing retransmit timer on packet: Id #1079 Sep 9 11:22:44 DEBUG[16644] chan_sip.c: Header: SIP/2.0 200 OK (14) Sep 9 11:22:44 DEBUG[16644] chan_sip.c: Header: Via: SIP/2.0/UDP 10.1.1.48:5060;branch=z9hG4bK21d811a1 (54) Sep 9 11:22:44 DEBUG[16644] chan_sip.c: Header: From: "asterisk" ;tag=as45685cdb (56) Sep 9 11:22:44 DEBUG[16644] chan_sip.c: Header: To: ;tag=A970A72F-C9E70C6C (56) Sep 9 11:22:44 DEBUG[16644] chan_sip.c: Header: CSeq: 102 NOTIFY (16) Sep 9 11:22:44 DEBUG[16644] chan_sip.c: Header: Call-ID: 664a604429d6fdb6756609db18693fee@10.1.1.48 (51) Sep 9 11:22:44 DEBUG[16644] chan_sip.c: Header: Contact: (39) Sep 9 11:22:44 DEBUG[16644] chan_sip.c: Header: Event: message-summary (22) Sep 9 11:22:44 DEBUG[16644] chan_sip.c: Header: User-Agent: PolycomSoundPointIP-SPIP_600-UA/1.5.2.0054 (54) Sep 9 11:22:44 DEBUG[16644] chan_sip.c: Header: Content-Length: 0 (17) Sep 9 11:22:44 DEBUG[16644] chan_sip.c: Header: (0) Sep 9 11:22:44 DEBUG[16644] chan_sip.c: ** SIP TIMER: Cancelling retransmit of packet (reply received) Retransid #1079 Sep 9 11:22:44 DEBUG[16644] chan_sip.c: Stopping retransmission on '664a604429d6fdb6756609db18693fee@10.1.1.48' of Request 102: Match Found Sep 9 11:22:51 DEBUG[16644] chan_sip.c: Auto destroying call '53a44b2a-7d482150-a3adcf2d@10.1.1.201' Sep 9 11:31:17 DEBUG[16644] chan_sip.c: Header: REGISTER sip:10.1.1.48:5060 SIP/2.0 (35) Sep 9 11:31:17 DEBUG[16644] chan_sip.c: Header: Via: SIP/2.0/UDP 10.1.1.204;branch=z9hG4bK9e4ad16887298C5 (57) Sep 9 11:31:17 DEBUG[16644] chan_sip.c: Header: From: "Kitchen" ;tag=8F90E744-474916F3 (69) Sep 9 11:31:17 DEBUG[16644] chan_sip.c: Header: To: (35) Sep 9 11:31:17 DEBUG[16644] chan_sip.c: Header: CSeq: 1053 REGISTER (19) Sep 9 11:31:17 DEBUG[16644] chan_sip.c: Header: Call-ID: d1897748-f95ebf6e-ec4abb25@10.1.1.204 (46) Sep 9 11:31:17 DEBUG[16644] chan_sip.c: Header: Contact: ;methods="INVITE, ACK, BYE, CANCEL, OPTIONS, INFO, MESSAGE, SUBSCRIBE, NOTIFY, PRACK, UPDATE, REFER" (141) Sep 9 11:31:17 DEBUG[16644] chan_sip.c: Header: User-Agent: PolycomSoundPointIP-SPIP_600-UA/1.5.2.0054 (54) Sep 9 11:31:17 DEBUG[16644] chan_sip.c: Header: Authorization: Digest username="kitchen-polycom", realm="asterisk", nonce="50db683b", uri="sip:10.1.1.48:5060", response="b8ebdbaf38f03eee28c77305b3560438", algorithm=MD5 (170) Sep 9 11:31:17 DEBUG[16644] chan_sip.c: Header: Max-Forwards: 70 (16) Sep 9 11:31:17 DEBUG[16644] chan_sip.c: Header: Expires: 3600 (13) Sep 9 11:31:17 DEBUG[16644] chan_sip.c: Header: Content-Length: 0 (17) Sep 9 11:31:17 DEBUG[16644] chan_sip.c: Header: (0) Sep 9 11:31:17 DEBUG[16644] chan_sip.c: Allocating new SIP dialog for d1897748-f95ebf6e-ec4abb25@10.1.1.204 - REGISTER (No RTP) Sep 9 11:31:17 DEBUG[16644] chan_sip.c: **** Received REGISTER (2) - Command in SIP REGISTER Sep 9 11:31:18 DEBUG[16644] chan_sip.c: Header: REGISTER sip:10.1.1.48:5060 SIP/2.0 (35) Sep 9 11:31:18 DEBUG[16644] chan_sip.c: Header: Via: SIP/2.0/UDP 10.1.1.204;branch=z9hG4bK3807e28eCEDF993 (57) Sep 9 11:31:18 DEBUG[16644] chan_sip.c: Header: From: "Kitchen" ;tag=8F90E744-474916F3 (69) Sep 9 11:31:18 DEBUG[16644] chan_sip.c: Header: To: (35) Sep 9 11:31:18 DEBUG[16644] chan_sip.c: Header: CSeq: 1054 REGISTER (19) Sep 9 11:31:18 DEBUG[16644] chan_sip.c: Header: Call-ID: d1897748-f95ebf6e-ec4abb25@10.1.1.204 (46) Sep 9 11:31:18 DEBUG[16644] chan_sip.c: Header: Contact: ;methods="INVITE, ACK, BYE, CANCEL, OPTIONS, INFO, MESSAGE, SUBSCRIBE, NOTIFY, PRACK, UPDATE, REFER" (141) Sep 9 11:31:18 DEBUG[16644] chan_sip.c: Header: User-Agent: PolycomSoundPointIP-SPIP_600-UA/1.5.2.0054 (54) Sep 9 11:31:18 DEBUG[16644] chan_sip.c: Header: Authorization: Digest username="kitchen-polycom", realm="asterisk", nonce="766ac57c", uri="sip:10.1.1.48:5060", response="a00d3a1e9b18b83d7c047f6db9d45ca3", algorithm=MD5 (170) Sep 9 11:31:18 DEBUG[16644] chan_sip.c: Header: Max-Forwards: 70 (16) Sep 9 11:31:18 DEBUG[16644] chan_sip.c: Header: Expires: 3600 (13) Sep 9 11:31:18 DEBUG[16644] chan_sip.c: Header: Content-Length: 0 (17) Sep 9 11:31:18 DEBUG[16644] chan_sip.c: Header: (0) Sep 9 11:31:18 DEBUG[16644] chan_sip.c: **** Received REGISTER (2) - Command in SIP REGISTER Sep 9 11:31:18 DEBUG[16644] chan_sip.c: Checking device state for DNS host kitchen Sep 9 11:31:18 DEBUG[16644] devicestate.c: Changing state for SIP/kitchen - state 4 (Invalid) Sep 9 11:31:24 DEBUG[16644] chan_sip.c: Header: REGISTER sip:10.1.1.48:5060 SIP/2.0 (35) Sep 9 11:31:24 DEBUG[16644] chan_sip.c: Header: Via: SIP/2.0/UDP 10.1.1.202;branch=z9hG4bK5a4bacf3E9F8AA10 (58) Sep 9 11:31:24 DEBUG[16644] chan_sip.c: Header: From: "Guest Room" ;tag=CCF2567D-4372788C (75) Sep 9 11:31:24 DEBUG[16644] chan_sip.c: Header: To: (38) Sep 9 11:31:24 DEBUG[16644] chan_sip.c: Header: CSeq: 1053 REGISTER (19) Sep 9 11:31:24 DEBUG[16644] chan_sip.c: Header: Call-ID: 3b501911-4c048fff-3eeaecc6@10.1.1.202 (46) Sep 9 11:31:24 DEBUG[16644] chan_sip.c: Header: Contact: ;methods="INVITE, ACK, BYE, CANCEL, OPTIONS, INFO, MESSAGE, SUBSCRIBE, NOTIFY, PRACK, UPDATE, REFER" (144) Sep 9 11:31:24 DEBUG[16644] chan_sip.c: Header: User-Agent: PolycomSoundPointIP-SPIP_600-UA/1.5.2.0054 (54) Sep 9 11:31:24 DEBUG[16644] chan_sip.c: Header: Authorization: Digest username="guest-room-polycom", realm="asterisk", nonce="4d89595c", uri="sip:10.1.1.48:5060", response="c4ce946b166a7b1bef78e771b99bb5e8", algorithm=MD5 (173) Sep 9 11:31:24 DEBUG[16644] chan_sip.c: Header: Max-Forwards: 70 (16) Sep 9 11:31:24 DEBUG[16644] chan_sip.c: Header: Expires: 3600 (13) Sep 9 11:31:24 DEBUG[16644] chan_sip.c: Header: Content-Length: 0 (17) Sep 9 11:31:24 DEBUG[16644] chan_sip.c: Header: (0) Sep 9 11:31:24 DEBUG[16644] chan_sip.c: Allocating new SIP dialog for 3b501911-4c048fff-3eeaecc6@10.1.1.202 - REGISTER (No RTP) Sep 9 11:31:24 DEBUG[16644] chan_sip.c: **** Received REGISTER (2) - Command in SIP REGISTER Sep 9 11:31:24 DEBUG[16644] chan_sip.c: Header: REGISTER sip:10.1.1.48:5060 SIP/2.0 (35) Sep 9 11:31:24 DEBUG[16644] chan_sip.c: Header: Via: SIP/2.0/UDP 10.1.1.202;branch=z9hG4bK9f0d65d16CD1A086 (58) Sep 9 11:31:24 DEBUG[16644] chan_sip.c: Header: From: "Guest Room" ;tag=CCF2567D-4372788C (75) Sep 9 11:31:24 DEBUG[16644] chan_sip.c: Header: To: (38) Sep 9 11:31:24 DEBUG[16644] chan_sip.c: Header: CSeq: 1054 REGISTER (19) Sep 9 11:31:24 DEBUG[16644] chan_sip.c: Header: Call-ID: 3b501911-4c048fff-3eeaecc6@10.1.1.202 (46) Sep 9 11:31:24 DEBUG[16644] chan_sip.c: Header: Contact: ;methods="INVITE, ACK, BYE, CANCEL, OPTIONS, INFO, MESSAGE, SUBSCRIBE, NOTIFY, PRACK, UPDATE, REFER" (144) Sep 9 11:31:24 DEBUG[16644] chan_sip.c: Header: User-Agent: PolycomSoundPointIP-SPIP_600-UA/1.5.2.0054 (54) Sep 9 11:31:24 DEBUG[16644] chan_sip.c: Header: Authorization: Digest username="guest-room-polycom", realm="asterisk", nonce="1645f1b1", uri="sip:10.1.1.48:5060", response="d32d91ad819edeb3b88faa6855831d8f", algorithm=MD5 (173) Sep 9 11:31:24 DEBUG[16644] chan_sip.c: Header: Max-Forwards: 70 (16) Sep 9 11:31:24 DEBUG[16644] chan_sip.c: Header: Expires: 3600 (13) Sep 9 11:31:24 DEBUG[16644] chan_sip.c: Header: Content-Length: 0 (17) Sep 9 11:31:24 DEBUG[16644] chan_sip.c: Header: (0) Sep 9 11:31:24 DEBUG[16644] chan_sip.c: **** Received REGISTER (2) - Command in SIP REGISTER Sep 9 11:31:24 DEBUG[16644] chan_sip.c: Checking device state for DNS host guest Sep 9 11:31:25 DEBUG[16644] devicestate.c: Changing state for SIP/guest - state 4 (Invalid) Sep 9 11:31:30 DEBUG[16644] chan_sip.c: Header: REGISTER sip:10.1.1.48:5060 SIP/2.0 (35) Sep 9 11:31:30 DEBUG[16644] chan_sip.c: Header: Via: SIP/2.0/UDP 10.1.1.203;branch=z9hG4bK3735fad3C6E4E72C (58) Sep 9 11:31:30 DEBUG[16644] chan_sip.c: Header: From: "Theater" ;tag=E315B29C-EC409443 (69) Sep 9 11:31:30 DEBUG[16644] chan_sip.c: Header: To: (35) Sep 9 11:31:30 DEBUG[16644] chan_sip.c: Header: CSeq: 967 REGISTER (18) Sep 9 11:31:30 DEBUG[16644] chan_sip.c: Header: Call-ID: 235aca78-dd4310ca-9b394431@10.1.1.203 (46) Sep 9 11:31:30 DEBUG[16644] chan_sip.c: Header: Contact: ;methods="INVITE, ACK, BYE, CANCEL, OPTIONS, INFO, MESSAGE, SUBSCRIBE, NOTIFY, PRACK, UPDATE, REFER" (141) Sep 9 11:31:30 DEBUG[16644] chan_sip.c: Header: User-Agent: PolycomSoundPointIP-SPIP_600-UA/1.5.2.0054 (54) Sep 9 11:31:30 DEBUG[16644] chan_sip.c: Header: Authorization: Digest username="theater-polycom", realm="asterisk", nonce="4534d547", uri="sip:10.1.1.48:5060", response="43e93f8d125ab1e8aceca84ad9eeb79d", algorithm=MD5 (170) Sep 9 11:31:30 DEBUG[16644] chan_sip.c: Header: Max-Forwards: 70 (16) Sep 9 11:31:30 DEBUG[16644] chan_sip.c: Header: Expires: 3600 (13) Sep 9 11:31:30 DEBUG[16644] chan_sip.c: Header: Content-Length: 0 (17) Sep 9 11:31:30 DEBUG[16644] chan_sip.c: Header: (0) Sep 9 11:31:30 DEBUG[16644] chan_sip.c: Allocating new SIP dialog for 235aca78-dd4310ca-9b394431@10.1.1.203 - REGISTER (No RTP) Sep 9 11:31:30 DEBUG[16644] chan_sip.c: **** Received REGISTER (2) - Command in SIP REGISTER Sep 9 11:31:30 DEBUG[16644] chan_sip.c: Header: REGISTER sip:10.1.1.48:5060 SIP/2.0 (35) Sep 9 11:31:30 DEBUG[16644] chan_sip.c: Header: Via: SIP/2.0/UDP 10.1.1.203;branch=z9hG4bKd12d12658A11607E (58) Sep 9 11:31:30 DEBUG[16644] chan_sip.c: Header: From: "Theater" ;tag=E315B29C-EC409443 (69) Sep 9 11:31:30 DEBUG[16644] chan_sip.c: Header: To: (35) Sep 9 11:31:30 DEBUG[16644] chan_sip.c: Header: CSeq: 968 REGISTER (18) Sep 9 11:31:30 DEBUG[16644] chan_sip.c: Header: Call-ID: 235aca78-dd4310ca-9b394431@10.1.1.203 (46) Sep 9 11:31:30 DEBUG[16644] chan_sip.c: Header: Contact: ;methods="INVITE, ACK, BYE, CANCEL, OPTIONS, INFO, MESSAGE, SUBSCRIBE, NOTIFY, PRACK, UPDATE, REFER" (141) Sep 9 11:31:30 DEBUG[16644] chan_sip.c: Header: User-Agent: PolycomSoundPointIP-SPIP_600-UA/1.5.2.0054 (54) Sep 9 11:31:30 DEBUG[16644] chan_sip.c: Header: Authorization: Digest username="theater-polycom", realm="asterisk", nonce="71b556a4", uri="sip:10.1.1.48:5060", response="a13fd9c02f4048436905202e709a980f", algorithm=MD5 (170) Sep 9 11:31:30 DEBUG[16644] chan_sip.c: Header: Max-Forwards: 70 (16) Sep 9 11:31:30 DEBUG[16644] chan_sip.c: Header: Expires: 3600 (13) Sep 9 11:31:30 DEBUG[16644] chan_sip.c: Header: Content-Length: 0 (17) Sep 9 11:31:30 DEBUG[16644] chan_sip.c: Header: (0) Sep 9 11:31:30 DEBUG[16644] chan_sip.c: **** Received REGISTER (2) - Command in SIP REGISTER Sep 9 11:31:30 DEBUG[16644] chan_sip.c: Checking device state for DNS host theater Sep 9 11:31:30 DEBUG[16644] devicestate.c: Changing state for SIP/theater - state 4 (Invalid) Sep 9 11:31:33 DEBUG[16644] chan_sip.c: Auto destroying call 'd1897748-f95ebf6e-ec4abb25@10.1.1.204' Sep 9 11:31:39 DEBUG[16644] chan_sip.c: Auto destroying call '3b501911-4c048fff-3eeaecc6@10.1.1.202' Sep 9 11:31:45 DEBUG[16644] chan_sip.c: Auto destroying call '235aca78-dd4310ca-9b394431@10.1.1.203' Sep 9 11:38:08 VERBOSE[16644] logger.c: <-- SIP read from 10.1.1.200:5060: SUBSCRIBE sip:2000@10.1.1.48 SIP/2.0Via: SIP/2.0/UDP 10.1.1.200;branch=z9hG4bK31b7b61b55164A2AFrom: "Wilson" ;tag=3E850BCC-20B703CDTo: ;tag=as211d38f3CSeq: 5 SUBSCRIBECall-ID: 7928dc00-5949e356-88e662a7@10.1.1.200Contact: Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, INFO, MESSAGE, SUBSCRIBE, NOTIFY, PRACK, UPDATE, REFEREvent: presenceUser-Agent: PolycomSoundPointIP-SPIP_600-UA/1.5.2.0054Authorization: Digest username="wilson-polycom", realm="asterisk", nonce="0aea62af", uri="sip:2000@10.1.1.48:5060", response="adfc6e097dec38a19ac7321cb0be0fac", algorithm=MD5Max-Forwards: 70Expires: 3600Content-Length: 0 Sep 9 11:38:08 DEBUG[16644] chan_sip.c: Header: SUBSCRIBE sip:2000@10.1.1.48 SIP/2.0 (36) Sep 9 11:38:08 DEBUG[16644] chan_sip.c: Header: Via: SIP/2.0/UDP 10.1.1.200;branch=z9hG4bK31b7b61b55164A2A (58) Sep 9 11:38:08 DEBUG[16644] chan_sip.c: Header: From: "Wilson" ;tag=3E850BCC-20B703CD (67) Sep 9 11:38:08 DEBUG[16644] chan_sip.c: Header: To: ;tag=as211d38f3 (39) Sep 9 11:38:08 DEBUG[16644] chan_sip.c: Header: CSeq: 5 SUBSCRIBE (17) Sep 9 11:38:08 DEBUG[16644] chan_sip.c: Header: Call-ID: 7928dc00-5949e356-88e662a7@10.1.1.200 (46) Sep 9 11:38:08 DEBUG[16644] chan_sip.c: Header: Contact: (40) Sep 9 11:38:08 DEBUG[16644] chan_sip.c: Header: Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, INFO, MESSAGE, SUBSCRIBE, NOTIFY, PRACK, UPDATE, REFER (96) Sep 9 11:38:08 DEBUG[16644] chan_sip.c: Header: Event: presence (15) Sep 9 11:38:08 DEBUG[16644] chan_sip.c: Header: User-Agent: PolycomSoundPointIP-SPIP_600-UA/1.5.2.0054 (54) Sep 9 11:38:08 DEBUG[16644] chan_sip.c: Header: Authorization: Digest username="wilson-polycom", realm="asterisk", nonce="0aea62af", uri="sip:2000@10.1.1.48:5060", response="adfc6e097dec38a19ac7321cb0be0fac", algorithm=MD5 (174) Sep 9 11:38:08 DEBUG[16644] chan_sip.c: Header: Max-Forwards: 70 (16) Sep 9 11:38:08 DEBUG[16644] chan_sip.c: Header: Expires: 3600 (13) Sep 9 11:38:08 DEBUG[16644] chan_sip.c: Header: Content-Length: 0 (17) Sep 9 11:38:08 DEBUG[16644] chan_sip.c: Header: (0) Sep 9 11:38:08 VERBOSE[16644] logger.c: --- (14 headers 0 lines)Sep 9 11:38:08 VERBOSE[16644] logger.c: --- (14 headers 0 lines)--- Sep 9 11:38:08 DEBUG[16644] chan_sip.c: **** Received SUBSCRIBE (10) - Command in SIP SUBSCRIBE Sep 9 11:38:08 DEBUG[16644] chan_sip.c: Got a re-subscribe on existing subscription 7928dc00-5949e356-88e662a7@10.1.1.200 Sep 9 11:38:08 DEBUG[16644] chan_sip.c: Adding subscription for extension 2000 context from-sip for peer wilson-polycom Sep 9 11:38:08 VERBOSE[16644] logger.c: Transmitting (no NAT) to 10.1.1.200:5060: SIP/2.0 200 OKVia: SIP/2.0/UDP 10.1.1.200;branch=z9hG4bK31b7b61b55164A2AFrom: "Wilson" ;tag=3E850BCC-20B703CDTo: ;tag=as211d38f3Call-ID: 7928dc00-5949e356-88e662a7@10.1.1.200CSeq: 5 SUBSCRIBEUser-Agent: Asterisk PBXAllow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFYExpires: 3600Contact: ;expires=3600Content-Length: 0 --- Sep 9 11:38:08 VERBOSE[16644] logger.c: Scheduling destruction of call '7928dc00-5949e356-88e662a7@10.1.1.200' in 3610000 ms Sep 9 11:38:08 DEBUG[16644] chan_sip.c: Checking device state for peer scott-polycom Sep 9 11:38:08 DEBUG[16644] chan_sip.c: Checking device state for peer scott-polycom Sep 9 11:38:08 VERBOSE[16644] logger.c: Reliably Transmitting (no NAT) to 10.1.1.200:5060: NOTIFY sip:wilson-polycom@10.1.1.200 SIP/2.0Via: SIP/2.0/UDP 10.1.1.48:5060;branch=z9hG4bK266c10bbFrom: ;tag=as211d38f3To: "Wilson" ;tag=3E850BCC-20B703CDContact: Call-ID: 7928dc00-5949e356-88e662a7@10.1.1.200CSeq: 105 NOTIFYUser-Agent: Asterisk PBXEvent: presenceContent-Type: application/xpidf+xmlSubscription-State: activeContent-Length: 348
--- Sep 9 11:38:08 DEBUG[16644] chan_sip.c: *** SIP TIMER: Initalizing retransmit timer on packet: Id #1091 Sep 9 11:38:08 VERBOSE[16644] logger.c: <-- SIP read from 10.1.1.200:5060: SIP/2.0 200 OKVia: SIP/2.0/UDP 10.1.1.48:5060;branch=z9hG4bK266c10bbFrom: ;tag=as211d38f3To: "Wilson" ;tag=3E850BCC-20B703CDCSeq: 105 NOTIFYCall-ID: 7928dc00-5949e356-88e662a7@10.1.1.200Contact: Event: presenceUser-Agent: PolycomSoundPointIP-SPIP_600-UA/1.5.2.0054Content-Length: 0 Sep 9 11:38:08 DEBUG[16644] chan_sip.c: Header: SIP/2.0 200 OK (14) Sep 9 11:38:08 DEBUG[16644] chan_sip.c: Header: Via: SIP/2.0/UDP 10.1.1.48:5060;branch=z9hG4bK266c10bb (54) Sep 9 11:38:08 DEBUG[16644] chan_sip.c: Header: From: ;tag=as211d38f3 (41) Sep 9 11:38:08 DEBUG[16644] chan_sip.c: Header: To: "Wilson" ;tag=3E850BCC-20B703CD (65) Sep 9 11:38:08 DEBUG[16644] chan_sip.c: Header: CSeq: 105 NOTIFY (16) Sep 9 11:38:08 DEBUG[16644] chan_sip.c: Header: Call-ID: 7928dc00-5949e356-88e662a7@10.1.1.200 (46) Sep 9 11:38:08 DEBUG[16644] chan_sip.c: Header: Contact: (40) Sep 9 11:38:08 DEBUG[16644] chan_sip.c: Header: Event: presence (15) Sep 9 11:38:08 DEBUG[16644] chan_sip.c: Header: User-Agent: PolycomSoundPointIP-SPIP_600-UA/1.5.2.0054 (54) Sep 9 11:38:08 DEBUG[16644] chan_sip.c: Header: Content-Length: 0 (17) Sep 9 11:38:08 DEBUG[16644] chan_sip.c: Header: (0) Sep 9 11:38:08 VERBOSE[16644] logger.c: --- (10 headers 0 lines)Sep 9 11:38:08 VERBOSE[16644] logger.c: --- (10 headers 0 lines)--- Sep 9 11:38:08 DEBUG[16644] chan_sip.c: ** SIP TIMER: Cancelling retransmit of packet (reply received) Retransid #1091 Sep 9 11:38:08 DEBUG[16644] chan_sip.c: Stopping retransmission on '7928dc00-5949e356-88e662a7@10.1.1.200' of Request 105: Match Found Sep 9 11:38:08 VERBOSE[16644] logger.c: SIP Response message for INCOMING dialog NOTIFY arrived Sep 9 11:41:21 VERBOSE[16644] logger.c: <-- SIP read from 10.1.1.200:5060: REGISTER sip:10.1.1.48:5060 SIP/2.0Via: SIP/2.0/UDP 10.1.1.200;branch=z9hG4bK2addab0143F37860From: "Wilson" ;tag=637E8B9F-FF6C90B8To: CSeq: 73 REGISTERCall-ID: 56c784b3-15d91a19-2fc96602@10.1.1.200Contact: ;methods="INVITE, ACK, BYE, CANCEL, OPTIONS, INFO, MESSAGE, SUBSCRIBE, NOTIFY, PRACK, UPDATE, REFER"User-Agent: PolycomSoundPointIP-SPIP_600-UA/1.5.2.0054Authorization: Digest username="wilson-polycom", realm="asterisk", nonce="3e4e73fe", uri="sip:10.1.1.48:5060", response="1a3a95a0798b4d9328e8a437f4d86298", algorithm=MD5Max-Forwards: 70Expires: 3600Content-Length: 0 Sep 9 11:41:21 DEBUG[16644] chan_sip.c: Header: REGISTER sip:10.1.1.48:5060 SIP/2.0 (35) Sep 9 11:41:21 DEBUG[16644] chan_sip.c: Header: Via: SIP/2.0/UDP 10.1.1.200;branch=z9hG4bK2addab0143F37860 (58) Sep 9 11:41:21 DEBUG[16644] chan_sip.c: Header: From: "Wilson" ;tag=637E8B9F-FF6C90B8 (67) Sep 9 11:41:21 DEBUG[16644] chan_sip.c: Header: To: (34) Sep 9 11:41:21 DEBUG[16644] chan_sip.c: Header: CSeq: 73 REGISTER (17) Sep 9 11:41:21 DEBUG[16644] chan_sip.c: Header: Call-ID: 56c784b3-15d91a19-2fc96602@10.1.1.200 (46) Sep 9 11:41:21 DEBUG[16644] chan_sip.c: Header: Contact: ;methods="INVITE, ACK, BYE, CANCEL, OPTIONS, INFO, MESSAGE, SUBSCRIBE, NOTIFY, PRACK, UPDATE, REFER" (140) Sep 9 11:41:21 DEBUG[16644] chan_sip.c: Header: User-Agent: PolycomSoundPointIP-SPIP_600-UA/1.5.2.0054 (54) Sep 9 11:41:21 DEBUG[16644] chan_sip.c: Header: Authorization: Digest username="wilson-polycom", realm="asterisk", nonce="3e4e73fe", uri="sip:10.1.1.48:5060", response="1a3a95a0798b4d9328e8a437f4d86298", algorithm=MD5 (169) Sep 9 11:41:21 DEBUG[16644] chan_sip.c: Header: Max-Forwards: 70 (16) Sep 9 11:41:21 DEBUG[16644] chan_sip.c: Header: Expires: 3600 (13) Sep 9 11:41:21 DEBUG[16644] chan_sip.c: Header: Content-Length: 0 (17) Sep 9 11:41:21 DEBUG[16644] chan_sip.c: Header: (0) Sep 9 11:41:21 VERBOSE[16644] logger.c: --- (12 headers 0 lines)Sep 9 11:41:21 VERBOSE[16644] logger.c: --- (12 headers 0 lines)--- Sep 9 11:41:21 DEBUG[16644] chan_sip.c: Allocating new SIP dialog for 56c784b3-15d91a19-2fc96602@10.1.1.200 - REGISTER (No RTP) Sep 9 11:41:21 DEBUG[16644] chan_sip.c: **** Received REGISTER (2) - Command in SIP REGISTER Sep 9 11:41:21 VERBOSE[16644] logger.c: Using latest REGISTER request as basis request Sep 9 11:41:21 VERBOSE[16644] logger.c: Sending to 10.1.1.200 : 5060 (non-NAT) Sep 9 11:41:21 VERBOSE[16644] logger.c: Transmitting (no NAT) to 10.1.1.200:5060: SIP/2.0 100 TryingVia: SIP/2.0/UDP 10.1.1.200;branch=z9hG4bK2addab0143F37860From: "Wilson" ;tag=637E8B9F-FF6C90B8To: Call-ID: 56c784b3-15d91a19-2fc96602@10.1.1.200CSeq: 73 REGISTERUser-Agent: Asterisk PBXAllow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFYContact: Content-Length: 0 --- Sep 9 11:41:21 VERBOSE[16644] logger.c: Transmitting (no NAT) to 10.1.1.200:5060: SIP/2.0 401 UnauthorizedVia: SIP/2.0/UDP 10.1.1.200;branch=z9hG4bK2addab0143F37860From: "Wilson" ;tag=637E8B9F-FF6C90B8To: ;tag=as18ffe0e5Call-ID: 56c784b3-15d91a19-2fc96602@10.1.1.200CSeq: 73 REGISTERUser-Agent: Asterisk PBXAllow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFYContact: WWW-Authenticate: Digest realm="asterisk", nonce="64ed3aa1" Content-Length: 0 --- Sep 9 11:41:21 VERBOSE[16644] logger.c: Scheduling destruction of call '56c784b3-15d91a19-2fc96602@10.1.1.200' in 15000 ms Sep 9 11:41:21 VERBOSE[16644] logger.c: <-- SIP read from 10.1.1.200:5060: REGISTER sip:10.1.1.48:5060 SIP/2.0Via: SIP/2.0/UDP 10.1.1.200;branch=z9hG4bK5aa8a4073C3861B6From: "Wilson" ;tag=637E8B9F-FF6C90B8To: CSeq: 74 REGISTERCall-ID: 56c784b3-15d91a19-2fc96602@10.1.1.200Contact: ;methods="INVITE, ACK, BYE, CANCEL, OPTIONS, INFO, MESSAGE, SUBSCRIBE, NOTIFY, PRACK, UPDATE, REFER"User-Agent: PolycomSoundPointIP-SPIP_600-UA/1.5.2.0054Authorization: Digest username="wilson-polycom", realm="asterisk", nonce="64ed3aa1", uri="sip:10.1.1.48:5060", response="e42e46a905c069f564bd2bac61207f63", algorithm=MD5Max-Forwards: 70Expires: 3600Content-Length: 0 Sep 9 11:41:21 DEBUG[16644] chan_sip.c: Header: REGISTER sip:10.1.1.48:5060 SIP/2.0 (35) Sep 9 11:41:21 DEBUG[16644] chan_sip.c: Header: Via: SIP/2.0/UDP 10.1.1.200;branch=z9hG4bK5aa8a4073C3861B6 (58) Sep 9 11:41:21 DEBUG[16644] chan_sip.c: Header: From: "Wilson" ;tag=637E8B9F-FF6C90B8 (67) Sep 9 11:41:21 DEBUG[16644] chan_sip.c: Header: To: (34) Sep 9 11:41:21 DEBUG[16644] chan_sip.c: Header: CSeq: 74 REGISTER (17) Sep 9 11:41:21 DEBUG[16644] chan_sip.c: Header: Call-ID: 56c784b3-15d91a19-2fc96602@10.1.1.200 (46) Sep 9 11:41:21 DEBUG[16644] chan_sip.c: Header: Contact: ;methods="INVITE, ACK, BYE, CANCEL, OPTIONS, INFO, MESSAGE, SUBSCRIBE, NOTIFY, PRACK, UPDATE, REFER" (140) Sep 9 11:41:21 DEBUG[16644] chan_sip.c: Header: User-Agent: PolycomSoundPointIP-SPIP_600-UA/1.5.2.0054 (54) Sep 9 11:41:21 DEBUG[16644] chan_sip.c: Header: Authorization: Digest username="wilson-polycom", realm="asterisk", nonce="64ed3aa1", uri="sip:10.1.1.48:5060", response="e42e46a905c069f564bd2bac61207f63", algorithm=MD5 (169) Sep 9 11:41:21 DEBUG[16644] chan_sip.c: Header: Max-Forwards: 70 (16) Sep 9 11:41:21 DEBUG[16644] chan_sip.c: Header: Expires: 3600 (13) Sep 9 11:41:21 DEBUG[16644] chan_sip.c: Header: Content-Length: 0 (17) Sep 9 11:41:21 DEBUG[16644] chan_sip.c: Header: (0) Sep 9 11:41:21 VERBOSE[16644] logger.c: --- (12 headers 0 lines)Sep 9 11:41:21 VERBOSE[16644] logger.c: --- (12 headers 0 lines)--- Sep 9 11:41:21 DEBUG[16644] chan_sip.c: **** Received REGISTER (2) - Command in SIP REGISTER Sep 9 11:41:21 VERBOSE[16644] logger.c: Using latest REGISTER request as basis request Sep 9 11:41:21 VERBOSE[16644] logger.c: Sending to 10.1.1.200 : 5060 (non-NAT) Sep 9 11:41:21 VERBOSE[16644] logger.c: Transmitting (no NAT) to 10.1.1.200:5060: SIP/2.0 100 TryingVia: SIP/2.0/UDP 10.1.1.200;branch=z9hG4bK5aa8a4073C3861B6From: "Wilson" ;tag=637E8B9F-FF6C90B8To: Call-ID: 56c784b3-15d91a19-2fc96602@10.1.1.200CSeq: 74 REGISTERUser-Agent: Asterisk PBXAllow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFYContact: Content-Length: 0 --- Sep 9 11:41:21 VERBOSE[16644] logger.c: Transmitting (no NAT) to 10.1.1.200:5060: SIP/2.0 200 OKVia: SIP/2.0/UDP 10.1.1.200;branch=z9hG4bK5aa8a4073C3861B6From: "Wilson" ;tag=637E8B9F-FF6C90B8To: ;tag=as18ffe0e5Call-ID: 56c784b3-15d91a19-2fc96602@10.1.1.200CSeq: 74 REGISTERUser-Agent: Asterisk PBXAllow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFYExpires: 3600Contact: ;expires=3600Date: Fri, 09 Sep 2005 18:41:21 GMTContent-Length: 0 --- Sep 9 11:41:21 VERBOSE[16644] logger.c: Scheduling destruction of call '56c784b3-15d91a19-2fc96602@10.1.1.200' in 15000 ms Sep 9 11:41:21 DEBUG[16644] chan_sip.c: Checking device state for peer wilson Sep 9 11:41:21 DEBUG[16644] devicestate.c: Changing state for SIP/wilson - state 5 (Unavailable) Sep 9 11:41:27 DEBUG[16644] chan_sip.c: Allocating new SIP dialog for (No Call-ID) - NOTIFY (No RTP) Sep 9 11:41:27 DEBUG[16644] chan_sip.c: Header: NOTIFY sip:wilson-polycom@10.1.1.200 SIP/2.0 (44) Sep 9 11:41:27 DEBUG[16644] chan_sip.c: Header: Via: SIP/2.0/UDP 10.1.1.48:5060;branch=z9hG4bK7adababa (54) Sep 9 11:41:27 DEBUG[16644] chan_sip.c: Header: From: "asterisk" ;tag=as5ea2d7e6 (56) Sep 9 11:41:27 DEBUG[16644] chan_sip.c: Header: To: (35) Sep 9 11:41:27 DEBUG[16644] chan_sip.c: Header: Contact: (33) Sep 9 11:41:27 DEBUG[16644] chan_sip.c: Header: Call-ID: 6fdc464d033a0f885b2a54bc7752144a@10.1.1.48 (51) Sep 9 11:41:27 DEBUG[16644] chan_sip.c: Header: CSeq: 102 NOTIFY (16) Sep 9 11:41:27 DEBUG[16644] chan_sip.c: Header: User-Agent: Asterisk PBX (24) Sep 9 11:41:27 DEBUG[16644] chan_sip.c: Header: Event: message-summary (22) Sep 9 11:41:27 DEBUG[16644] chan_sip.c: Header: Content-Type: application/simple-message-summary (48) Sep 9 11:41:27 DEBUG[16644] chan_sip.c: Header: Content-Length: 80 (18) Sep 9 11:41:27 DEBUG[16644] chan_sip.c: Header: (0) Sep 9 11:41:27 DEBUG[16644] chan_sip.c: Line: Message-Account: sip:asterisk@ (30) Sep 9 11:41:27 DEBUG[16644] chan_sip.c: Line: Messages-Waiting: no (20) Sep 9 11:41:27 DEBUG[16644] chan_sip.c: Line: Voice-Message: 0/0 (0/0) (24) Sep 9 11:41:27 VERBOSE[16644] logger.c: 11 headers, 3 lines Sep 9 11:41:27 VERBOSE[16644] logger.c: Reliably Transmitting (no NAT) to 10.1.1.200:5060: NOTIFY sip:wilson-polycom@10.1.1.200 SIP/2.0Via: SIP/2.0/UDP 10.1.1.48:5060;branch=z9hG4bK7adababaFrom: "asterisk" ;tag=as5ea2d7e6To: Contact: Call-ID: 6fdc464d033a0f885b2a54bc7752144a@10.1.1.48CSeq: 102 NOTIFYUser-Agent: Asterisk PBXEvent: message-summaryContent-Type: application/simple-message-summaryContent-Length: 80Message-Account: sip:asterisk@Messages-Waiting: noVoice-Message: 0/0 (0/0) --- Sep 9 11:41:27 DEBUG[16644] chan_sip.c: *** SIP TIMER: Initalizing retransmit timer on packet: Id #1095 Sep 9 11:41:27 VERBOSE[16644] logger.c: Scheduling destruction of call '6fdc464d033a0f885b2a54bc7752144a@10.1.1.48' in 15000 ms Sep 9 11:41:27 VERBOSE[16644] logger.c: <-- SIP read from 10.1.1.200:5060: SIP/2.0 200 OKVia: SIP/2.0/UDP 10.1.1.48:5060;branch=z9hG4bK7adababaFrom: "asterisk" ;tag=as5ea2d7e6To: ;tag=3B64CC2C-EFA3972DCSeq: 102 NOTIFYCall-ID: 6fdc464d033a0f885b2a54bc7752144a@10.1.1.48Contact: Event: message-summaryUser-Agent: PolycomSoundPointIP-SPIP_600-UA/1.5.2.0054Content-Length: 0 Sep 9 11:41:27 DEBUG[16644] chan_sip.c: Header: SIP/2.0 200 OK (14) Sep 9 11:41:27 DEBUG[16644] chan_sip.c: Header: Via: SIP/2.0/UDP 10.1.1.48:5060;branch=z9hG4bK7adababa (54) Sep 9 11:41:27 DEBUG[16644] chan_sip.c: Header: From: "asterisk" ;tag=as5ea2d7e6 (56) Sep 9 11:41:27 DEBUG[16644] chan_sip.c: Header: To: ;tag=3B64CC2C-EFA3972D (57) Sep 9 11:41:27 DEBUG[16644] chan_sip.c: Header: CSeq: 102 NOTIFY (16) Sep 9 11:41:27 DEBUG[16644] chan_sip.c: Header: Call-ID: 6fdc464d033a0f885b2a54bc7752144a@10.1.1.48 (51) Sep 9 11:41:27 DEBUG[16644] chan_sip.c: Header: Contact: (40) Sep 9 11:41:27 DEBUG[16644] chan_sip.c: Header: Event: message-summary (22) Sep 9 11:41:27 DEBUG[16644] chan_sip.c: Header: User-Agent: PolycomSoundPointIP-SPIP_600-UA/1.5.2.0054 (54) Sep 9 11:41:27 DEBUG[16644] chan_sip.c: Header: Content-Length: 0 (17) Sep 9 11:41:27 DEBUG[16644] chan_sip.c: Header: (0) Sep 9 11:41:27 VERBOSE[16644] logger.c: --- (10 headers 0 lines)Sep 9 11:41:27 VERBOSE[16644] logger.c: --- (10 headers 0 lines)--- Sep 9 11:41:27 DEBUG[16644] chan_sip.c: ** SIP TIMER: Cancelling retransmit of packet (reply received) Retransid #1095 Sep 9 11:41:27 DEBUG[16644] chan_sip.c: Stopping retransmission on '6fdc464d033a0f885b2a54bc7752144a@10.1.1.48' of Request 102: Match Found Sep 9 11:41:27 VERBOSE[16644] logger.c: Destroying call '6fdc464d033a0f885b2a54bc7752144a@10.1.1.48' Sep 9 11:41:36 DEBUG[16644] chan_sip.c: Auto destroying call '56c784b3-15d91a19-2fc96602@10.1.1.200' Sep 9 11:41:36 VERBOSE[16644] logger.c: Destroying call '56c784b3-15d91a19-2fc96602@10.1.1.200' Sep 9 11:52:36 DEBUG[16644] chan_sip.c: Header: REGISTER sip:10.1.1.48:5060 SIP/2.0 (35) Sep 9 11:52:36 DEBUG[16644] chan_sip.c: Header: Via: SIP/2.0/UDP 10.1.1.201;branch=z9hG4bKc0f0d8126EB7CB15 (58) Sep 9 11:52:36 DEBUG[16644] chan_sip.c: Header: From: "Scott" ;tag=EDE85FF6-86E06D93 (65) Sep 9 11:52:36 DEBUG[16644] chan_sip.c: Header: To: (33) Sep 9 11:52:36 DEBUG[16644] chan_sip.c: Header: CSeq: 1053 REGISTER (19) Sep 9 11:52:36 DEBUG[16644] chan_sip.c: Header: Call-ID: 53a44b2a-7d482150-a3adcf2d@10.1.1.201 (46) Sep 9 11:52:36 DEBUG[16644] chan_sip.c: Header: Contact: ;methods="INVITE, ACK, BYE, CANCEL, OPTIONS, INFO, MESSAGE, SUBSCRIBE, NOTIFY, PRACK, UPDATE, REFER" (139) Sep 9 11:52:36 DEBUG[16644] chan_sip.c: Header: User-Agent: PolycomSoundPointIP-SPIP_600-UA/1.5.2.0054 (54) Sep 9 11:52:36 DEBUG[16644] chan_sip.c: Header: Authorization: Digest username="scott-polycom", realm="asterisk", nonce="6de0ad51", uri="sip:10.1.1.48:5060", response="c7b9376def153643cd8302d479fd2541", algorithm=MD5 (168) Sep 9 11:52:36 DEBUG[16644] chan_sip.c: Header: Max-Forwards: 70 (16) Sep 9 11:52:36 DEBUG[16644] chan_sip.c: Header: Expires: 3600 (13) Sep 9 11:52:36 DEBUG[16644] chan_sip.c: Header: Content-Length: 0 (17) Sep 9 11:52:36 DEBUG[16644] chan_sip.c: Header: (0) Sep 9 11:52:36 DEBUG[16644] chan_sip.c: Allocating new SIP dialog for 53a44b2a-7d482150-a3adcf2d@10.1.1.201 - REGISTER (No RTP) Sep 9 11:52:36 DEBUG[16644] chan_sip.c: **** Received REGISTER (2) - Command in SIP REGISTER Sep 9 11:52:36 DEBUG[16644] chan_sip.c: Header: REGISTER sip:10.1.1.48:5060 SIP/2.0 (35) Sep 9 11:52:36 DEBUG[16644] chan_sip.c: Header: Via: SIP/2.0/UDP 10.1.1.201;branch=z9hG4bKfa3a2c38D541277B (58) Sep 9 11:52:36 DEBUG[16644] chan_sip.c: Header: From: "Scott" ;tag=EDE85FF6-86E06D93 (65) Sep 9 11:52:36 DEBUG[16644] chan_sip.c: Header: To: (33) Sep 9 11:52:36 DEBUG[16644] chan_sip.c: Header: CSeq: 1054 REGISTER (19) Sep 9 11:52:36 DEBUG[16644] chan_sip.c: Header: Call-ID: 53a44b2a-7d482150-a3adcf2d@10.1.1.201 (46) Sep 9 11:52:36 DEBUG[16644] chan_sip.c: Header: Contact: ;methods="INVITE, ACK, BYE, CANCEL, OPTIONS, INFO, MESSAGE, SUBSCRIBE, NOTIFY, PRACK, UPDATE, REFER" (139) Sep 9 11:52:36 DEBUG[16644] chan_sip.c: Header: User-Agent: PolycomSoundPointIP-SPIP_600-UA/1.5.2.0054 (54) Sep 9 11:52:36 DEBUG[16644] chan_sip.c: Header: Authorization: Digest username="scott-polycom", realm="asterisk", nonce="01d83167", uri="sip:10.1.1.48:5060", response="e1917754f0933b31094a5375d35e7fd6", algorithm=MD5 (168) Sep 9 11:52:36 DEBUG[16644] chan_sip.c: Header: Max-Forwards: 70 (16) Sep 9 11:52:36 DEBUG[16644] chan_sip.c: Header: Expires: 3600 (13) Sep 9 11:52:36 DEBUG[16644] chan_sip.c: Header: Content-Length: 0 (17) Sep 9 11:52:36 DEBUG[16644] chan_sip.c: Header: (0) Sep 9 11:52:36 DEBUG[16644] chan_sip.c: **** Received REGISTER (2) - Command in SIP REGISTER Sep 9 11:52:36 DEBUG[16644] chan_sip.c: Checking device state for DNS host scott Sep 9 11:52:36 DEBUG[16644] devicestate.c: Changing state for SIP/scott - state 4 (Invalid) Sep 9 11:52:37 DEBUG[16644] chan_sip.c: Allocating new SIP dialog for (No Call-ID) - NOTIFY (No RTP) Sep 9 11:52:37 DEBUG[16644] chan_sip.c: Header: NOTIFY sip:scott-polycom@10.1.1.201 SIP/2.0 (43) Sep 9 11:52:37 DEBUG[16644] chan_sip.c: Header: Via: SIP/2.0/UDP 10.1.1.48:5060;branch=z9hG4bK57edc4a5 (54) Sep 9 11:52:37 DEBUG[16644] chan_sip.c: Header: From: "asterisk" ;tag=as111ceb3c (56) Sep 9 11:52:37 DEBUG[16644] chan_sip.c: Header: To: (34) Sep 9 11:52:37 DEBUG[16644] chan_sip.c: Header: Contact: (33) Sep 9 11:52:37 DEBUG[16644] chan_sip.c: Header: Call-ID: 02b2c6a217ea0bfb3d4517b37b938bb2@10.1.1.48 (51) Sep 9 11:52:37 DEBUG[16644] chan_sip.c: Header: CSeq: 102 NOTIFY (16) Sep 9 11:52:37 DEBUG[16644] chan_sip.c: Header: User-Agent: Asterisk PBX (24) Sep 9 11:52:37 DEBUG[16644] chan_sip.c: Header: Event: message-summary (22) Sep 9 11:52:37 DEBUG[16644] chan_sip.c: Header: Content-Type: application/simple-message-summary (48) Sep 9 11:52:37 DEBUG[16644] chan_sip.c: Header: Content-Length: 80 (18) Sep 9 11:52:37 DEBUG[16644] chan_sip.c: Header: (0) Sep 9 11:52:37 DEBUG[16644] chan_sip.c: Line: Message-Account: sip:asterisk@ (30) Sep 9 11:52:37 DEBUG[16644] chan_sip.c: Line: Messages-Waiting: no (20) Sep 9 11:52:37 DEBUG[16644] chan_sip.c: Line: Voice-Message: 0/0 (0/0) (24) Sep 9 11:52:37 DEBUG[16644] chan_sip.c: *** SIP TIMER: Initalizing retransmit timer on packet: Id #1100 Sep 9 11:52:37 DEBUG[16644] chan_sip.c: Header: SIP/2.0 200 OK (14) Sep 9 11:52:37 DEBUG[16644] chan_sip.c: Header: Via: SIP/2.0/UDP 10.1.1.48:5060;branch=z9hG4bK57edc4a5 (54) Sep 9 11:52:37 DEBUG[16644] chan_sip.c: Header: From: "asterisk" ;tag=as111ceb3c (56) Sep 9 11:52:37 DEBUG[16644] chan_sip.c: Header: To: ;tag=29C81C61-1F1A68DE (56) Sep 9 11:52:37 DEBUG[16644] chan_sip.c: Header: CSeq: 102 NOTIFY (16) Sep 9 11:52:37 DEBUG[16644] chan_sip.c: Header: Call-ID: 02b2c6a217ea0bfb3d4517b37b938bb2@10.1.1.48 (51) Sep 9 11:52:37 DEBUG[16644] chan_sip.c: Header: Contact: (39) Sep 9 11:52:37 DEBUG[16644] chan_sip.c: Header: Event: message-summary (22) Sep 9 11:52:37 DEBUG[16644] chan_sip.c: Header: User-Agent: PolycomSoundPointIP-SPIP_600-UA/1.5.2.0054 (54) Sep 9 11:52:37 DEBUG[16644] chan_sip.c: Header: Content-Length: 0 (17) Sep 9 11:52:37 DEBUG[16644] chan_sip.c: Header: (0) Sep 9 11:52:37 DEBUG[16644] chan_sip.c: ** SIP TIMER: Cancelling retransmit of packet (reply received) Retransid #1100 Sep 9 11:52:37 DEBUG[16644] chan_sip.c: Stopping retransmission on '02b2c6a217ea0bfb3d4517b37b938bb2@10.1.1.48' of Request 102: Match Found Sep 9 11:52:51 DEBUG[16644] chan_sip.c: Auto destroying call '53a44b2a-7d482150-a3adcf2d@10.1.1.201' Sep 9 12:01:17 DEBUG[16644] chan_sip.c: Header: REGISTER sip:10.1.1.48:5060 SIP/2.0 (35) Sep 9 12:01:17 DEBUG[16644] chan_sip.c: Header: Via: SIP/2.0/UDP 10.1.1.204;branch=z9hG4bK6c29db6440113551 (58) Sep 9 12:01:17 DEBUG[16644] chan_sip.c: Header: From: "Kitchen" ;tag=8F90E744-474916F3 (69) Sep 9 12:01:17 DEBUG[16644] chan_sip.c: Header: To: (35) Sep 9 12:01:17 DEBUG[16644] chan_sip.c: Header: CSeq: 1055 REGISTER (19) Sep 9 12:01:17 DEBUG[16644] chan_sip.c: Header: Call-ID: d1897748-f95ebf6e-ec4abb25@10.1.1.204 (46) Sep 9 12:01:17 DEBUG[16644] chan_sip.c: Header: Contact: ;methods="INVITE, ACK, BYE, CANCEL, OPTIONS, INFO, MESSAGE, SUBSCRIBE, NOTIFY, PRACK, UPDATE, REFER" (141) Sep 9 12:01:17 DEBUG[16644] chan_sip.c: Header: User-Agent: PolycomSoundPointIP-SPIP_600-UA/1.5.2.0054 (54) Sep 9 12:01:17 DEBUG[16644] chan_sip.c: Header: Authorization: Digest username="kitchen-polycom", realm="asterisk", nonce="766ac57c", uri="sip:10.1.1.48:5060", response="a00d3a1e9b18b83d7c047f6db9d45ca3", algorithm=MD5 (170) Sep 9 12:01:17 DEBUG[16644] chan_sip.c: Header: Max-Forwards: 70 (16) Sep 9 12:01:17 DEBUG[16644] chan_sip.c: Header: Expires: 3600 (13) Sep 9 12:01:17 DEBUG[16644] chan_sip.c: Header: Content-Length: 0 (17) Sep 9 12:01:17 DEBUG[16644] chan_sip.c: Header: (0) Sep 9 12:01:17 DEBUG[16644] chan_sip.c: Allocating new SIP dialog for d1897748-f95ebf6e-ec4abb25@10.1.1.204 - REGISTER (No RTP) Sep 9 12:01:17 DEBUG[16644] chan_sip.c: **** Received REGISTER (2) - Command in SIP REGISTER Sep 9 12:01:18 DEBUG[16644] chan_sip.c: Header: REGISTER sip:10.1.1.48:5060 SIP/2.0 (35) Sep 9 12:01:18 DEBUG[16644] chan_sip.c: Header: Via: SIP/2.0/UDP 10.1.1.204;branch=z9hG4bK62d94d6aD3EAA77F (58) Sep 9 12:01:18 DEBUG[16644] chan_sip.c: Header: From: "Kitchen" ;tag=8F90E744-474916F3 (69) Sep 9 12:01:18 DEBUG[16644] chan_sip.c: Header: To: (35) Sep 9 12:01:18 DEBUG[16644] chan_sip.c: Header: CSeq: 1056 REGISTER (19) Sep 9 12:01:18 DEBUG[16644] chan_sip.c: Header: Call-ID: d1897748-f95ebf6e-ec4abb25@10.1.1.204 (46) Sep 9 12:01:18 DEBUG[16644] chan_sip.c: Header: Contact: ;methods="INVITE, ACK, BYE, CANCEL, OPTIONS, INFO, MESSAGE, SUBSCRIBE, NOTIFY, PRACK, UPDATE, REFER" (141) Sep 9 12:01:18 DEBUG[16644] chan_sip.c: Header: User-Agent: PolycomSoundPointIP-SPIP_600-UA/1.5.2.0054 (54) Sep 9 12:01:18 DEBUG[16644] chan_sip.c: Header: Authorization: Digest username="kitchen-polycom", realm="asterisk", nonce="6111b592", uri="sip:10.1.1.48:5060", response="3b24cba13340f99d0645c87529e2349f", algorithm=MD5 (170) Sep 9 12:01:18 DEBUG[16644] chan_sip.c: Header: Max-Forwards: 70 (16) Sep 9 12:01:18 DEBUG[16644] chan_sip.c: Header: Expires: 3600 (13) Sep 9 12:01:18 DEBUG[16644] chan_sip.c: Header: Content-Length: 0 (17) Sep 9 12:01:18 DEBUG[16644] chan_sip.c: Header: (0) Sep 9 12:01:18 DEBUG[16644] chan_sip.c: **** Received REGISTER (2) - Command in SIP REGISTER Sep 9 12:01:18 DEBUG[16644] chan_sip.c: Checking device state for DNS host kitchen Sep 9 12:01:18 DEBUG[16644] devicestate.c: Changing state for SIP/kitchen - state 4 (Invalid) Sep 9 12:01:24 DEBUG[16644] chan_sip.c: Header: REGISTER sip:10.1.1.48:5060 SIP/2.0 (35) Sep 9 12:01:24 DEBUG[16644] chan_sip.c: Header: Via: SIP/2.0/UDP 10.1.1.202;branch=z9hG4bKe1b7d6bf4FFF6E4C (58) Sep 9 12:01:24 DEBUG[16644] chan_sip.c: Header: From: "Guest Room" ;tag=CCF2567D-4372788C (75) Sep 9 12:01:24 DEBUG[16644] chan_sip.c: Header: To: (38) Sep 9 12:01:24 DEBUG[16644] chan_sip.c: Header: CSeq: 1055 REGISTER (19) Sep 9 12:01:24 DEBUG[16644] chan_sip.c: Header: Call-ID: 3b501911-4c048fff-3eeaecc6@10.1.1.202 (46) Sep 9 12:01:24 DEBUG[16644] chan_sip.c: Header: Contact: ;methods="INVITE, ACK, BYE, CANCEL, OPTIONS, INFO, MESSAGE, SUBSCRIBE, NOTIFY, PRACK, UPDATE, REFER" (144) Sep 9 12:01:24 DEBUG[16644] chan_sip.c: Header: User-Agent: PolycomSoundPointIP-SPIP_600-UA/1.5.2.0054 (54) Sep 9 12:01:24 DEBUG[16644] chan_sip.c: Header: Authorization: Digest username="guest-room-polycom", realm="asterisk", nonce="1645f1b1", uri="sip:10.1.1.48:5060", response="d32d91ad819edeb3b88faa6855831d8f", algorithm=MD5 (173) Sep 9 12:01:24 DEBUG[16644] chan_sip.c: Header: Max-Forwards: 70 (16) Sep 9 12:01:24 DEBUG[16644] chan_sip.c: Header: Expires: 3600 (13) Sep 9 12:01:24 DEBUG[16644] chan_sip.c: Header: Content-Length: 0 (17) Sep 9 12:01:24 DEBUG[16644] chan_sip.c: Header: (0) Sep 9 12:01:24 DEBUG[16644] chan_sip.c: Allocating new SIP dialog for 3b501911-4c048fff-3eeaecc6@10.1.1.202 - REGISTER (No RTP) Sep 9 12:01:24 DEBUG[16644] chan_sip.c: **** Received REGISTER (2) - Command in SIP REGISTER Sep 9 12:01:24 DEBUG[16644] chan_sip.c: Header: REGISTER sip:10.1.1.48:5060 SIP/2.0 (35) Sep 9 12:01:24 DEBUG[16644] chan_sip.c: Header: Via: SIP/2.0/UDP 10.1.1.202;branch=z9hG4bK4318c73d846480E2 (58) Sep 9 12:01:24 DEBUG[16644] chan_sip.c: Header: From: "Guest Room" ;tag=CCF2567D-4372788C (75) Sep 9 12:01:24 DEBUG[16644] chan_sip.c: Header: To: (38) Sep 9 12:01:24 DEBUG[16644] chan_sip.c: Header: CSeq: 1056 REGISTER (19) Sep 9 12:01:24 DEBUG[16644] chan_sip.c: Header: Call-ID: 3b501911-4c048fff-3eeaecc6@10.1.1.202 (46) Sep 9 12:01:24 DEBUG[16644] chan_sip.c: Header: Contact: ;methods="INVITE, ACK, BYE, CANCEL, OPTIONS, INFO, MESSAGE, SUBSCRIBE, NOTIFY, PRACK, UPDATE, REFER" (144) Sep 9 12:01:24 DEBUG[16644] chan_sip.c: Header: User-Agent: PolycomSoundPointIP-SPIP_600-UA/1.5.2.0054 (54) Sep 9 12:01:24 DEBUG[16644] chan_sip.c: Header: Authorization: Digest username="guest-room-polycom", realm="asterisk", nonce="428ed71f", uri="sip:10.1.1.48:5060", response="2e2461d95045e01ebabd49f3d01c17b0", algorithm=MD5 (173) Sep 9 12:01:24 DEBUG[16644] chan_sip.c: Header: Max-Forwards: 70 (16) Sep 9 12:01:24 DEBUG[16644] chan_sip.c: Header: Expires: 3600 (13) Sep 9 12:01:24 DEBUG[16644] chan_sip.c: Header: Content-Length: 0 (17) Sep 9 12:01:24 DEBUG[16644] chan_sip.c: Header: (0) Sep 9 12:01:24 DEBUG[16644] chan_sip.c: **** Received REGISTER (2) - Command in SIP REGISTER Sep 9 12:01:24 DEBUG[16644] chan_sip.c: Checking device state for DNS host guest Sep 9 12:01:25 DEBUG[16644] devicestate.c: Changing state for SIP/guest - state 4 (Invalid) Sep 9 12:01:30 DEBUG[16644] chan_sip.c: Header: REGISTER sip:10.1.1.48:5060 SIP/2.0 (35) Sep 9 12:01:30 DEBUG[16644] chan_sip.c: Header: Via: SIP/2.0/UDP 10.1.1.203;branch=z9hG4bK67603577B2437550 (58) Sep 9 12:01:30 DEBUG[16644] chan_sip.c: Header: From: "Theater" ;tag=E315B29C-EC409443 (69) Sep 9 12:01:30 DEBUG[16644] chan_sip.c: Header: To: (35) Sep 9 12:01:30 DEBUG[16644] chan_sip.c: Header: CSeq: 969 REGISTER (18) Sep 9 12:01:30 DEBUG[16644] chan_sip.c: Header: Call-ID: 235aca78-dd4310ca-9b394431@10.1.1.203 (46) Sep 9 12:01:30 DEBUG[16644] chan_sip.c: Header: Contact: ;methods="INVITE, ACK, BYE, CANCEL, OPTIONS, INFO, MESSAGE, SUBSCRIBE, NOTIFY, PRACK, UPDATE, REFER" (141) Sep 9 12:01:30 DEBUG[16644] chan_sip.c: Header: User-Agent: PolycomSoundPointIP-SPIP_600-UA/1.5.2.0054 (54) Sep 9 12:01:30 DEBUG[16644] chan_sip.c: Header: Authorization: Digest username="theater-polycom", realm="asterisk", nonce="71b556a4", uri="sip:10.1.1.48:5060", response="a13fd9c02f4048436905202e709a980f", algorithm=MD5 (170) Sep 9 12:01:30 DEBUG[16644] chan_sip.c: Header: Max-Forwards: 70 (16) Sep 9 12:01:30 DEBUG[16644] chan_sip.c: Header: Expires: 3600 (13) Sep 9 12:01:30 DEBUG[16644] chan_sip.c: Header: Content-Length: 0 (17) Sep 9 12:01:30 DEBUG[16644] chan_sip.c: Header: (0) Sep 9 12:01:30 DEBUG[16644] chan_sip.c: Allocating new SIP dialog for 235aca78-dd4310ca-9b394431@10.1.1.203 - REGISTER (No RTP) Sep 9 12:01:30 DEBUG[16644] chan_sip.c: **** Received REGISTER (2) - Command in SIP REGISTER Sep 9 12:01:30 DEBUG[16644] chan_sip.c: Header: REGISTER sip:10.1.1.48:5060 SIP/2.0 (35) Sep 9 12:01:30 DEBUG[16644] chan_sip.c: Header: Via: SIP/2.0/UDP 10.1.1.203;branch=z9hG4bK86d08409819845A2 (58) Sep 9 12:01:30 DEBUG[16644] chan_sip.c: Header: From: "Theater" ;tag=E315B29C-EC409443 (69) Sep 9 12:01:30 DEBUG[16644] chan_sip.c: Header: To: (35) Sep 9 12:01:30 DEBUG[16644] chan_sip.c: Header: CSeq: 970 REGISTER (18) Sep 9 12:01:30 DEBUG[16644] chan_sip.c: Header: Call-ID: 235aca78-dd4310ca-9b394431@10.1.1.203 (46) Sep 9 12:01:30 DEBUG[16644] chan_sip.c: Header: Contact: ;methods="INVITE, ACK, BYE, CANCEL, OPTIONS, INFO, MESSAGE, SUBSCRIBE, NOTIFY, PRACK, UPDATE, REFER" (141) Sep 9 12:01:30 DEBUG[16644] chan_sip.c: Header: User-Agent: PolycomSoundPointIP-SPIP_600-UA/1.5.2.0054 (54) Sep 9 12:01:30 DEBUG[16644] chan_sip.c: Header: Authorization: Digest username="theater-polycom", realm="asterisk", nonce="24712b8b", uri="sip:10.1.1.48:5060", response="400949bc8114eaf9d83b6ac7b9810d62", algorithm=MD5 (170) Sep 9 12:01:30 DEBUG[16644] chan_sip.c: Header: Max-Forwards: 70 (16) Sep 9 12:01:30 DEBUG[16644] chan_sip.c: Header: Expires: 3600 (13) Sep 9 12:01:30 DEBUG[16644] chan_sip.c: Header: Content-Length: 0 (17) Sep 9 12:01:30 DEBUG[16644] chan_sip.c: Header: (0) Sep 9 12:01:30 DEBUG[16644] chan_sip.c: **** Received REGISTER (2) - Command in SIP REGISTER Sep 9 12:01:30 DEBUG[16644] chan_sip.c: Checking device state for DNS host theater Sep 9 12:01:30 DEBUG[16644] devicestate.c: Changing state for SIP/theater - state 4 (Invalid) Sep 9 12:01:33 DEBUG[16644] chan_sip.c: Auto destroying call 'd1897748-f95ebf6e-ec4abb25@10.1.1.204' Sep 9 12:01:39 DEBUG[16644] chan_sip.c: Auto destroying call '3b501911-4c048fff-3eeaecc6@10.1.1.202' Sep 9 12:01:45 DEBUG[16644] chan_sip.c: Auto destroying call '235aca78-dd4310ca-9b394431@10.1.1.203' Sep 9 12:08:08 VERBOSE[16644] logger.c: <-- SIP read from 10.1.1.200:5060: SUBSCRIBE sip:2000@10.1.1.48 SIP/2.0Via: SIP/2.0/UDP 10.1.1.200;branch=z9hG4bKc4989a73EA569DC2From: "Wilson" ;tag=3E850BCC-20B703CDTo: ;tag=as211d38f3CSeq: 6 SUBSCRIBECall-ID: 7928dc00-5949e356-88e662a7@10.1.1.200Contact: Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, INFO, MESSAGE, SUBSCRIBE, NOTIFY, PRACK, UPDATE, REFEREvent: presenceUser-Agent: PolycomSoundPointIP-SPIP_600-UA/1.5.2.0054Authorization: Digest username="wilson-polycom", realm="asterisk", nonce="0aea62af", uri="sip:2000@10.1.1.48:5060", response="adfc6e097dec38a19ac7321cb0be0fac", algorithm=MD5Max-Forwards: 70Expires: 3600Content-Length: 0 Sep 9 12:08:08 DEBUG[16644] chan_sip.c: Header: SUBSCRIBE sip:2000@10.1.1.48 SIP/2.0 (36) Sep 9 12:08:08 DEBUG[16644] chan_sip.c: Header: Via: SIP/2.0/UDP 10.1.1.200;branch=z9hG4bKc4989a73EA569DC2 (58) Sep 9 12:08:08 DEBUG[16644] chan_sip.c: Header: From: "Wilson" ;tag=3E850BCC-20B703CD (67) Sep 9 12:08:08 DEBUG[16644] chan_sip.c: Header: To: ;tag=as211d38f3 (39) Sep 9 12:08:08 DEBUG[16644] chan_sip.c: Header: CSeq: 6 SUBSCRIBE (17) Sep 9 12:08:08 DEBUG[16644] chan_sip.c: Header: Call-ID: 7928dc00-5949e356-88e662a7@10.1.1.200 (46) Sep 9 12:08:08 DEBUG[16644] chan_sip.c: Header: Contact: (40) Sep 9 12:08:08 DEBUG[16644] chan_sip.c: Header: Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, INFO, MESSAGE, SUBSCRIBE, NOTIFY, PRACK, UPDATE, REFER (96) Sep 9 12:08:08 DEBUG[16644] chan_sip.c: Header: Event: presence (15) Sep 9 12:08:08 DEBUG[16644] chan_sip.c: Header: User-Agent: PolycomSoundPointIP-SPIP_600-UA/1.5.2.0054 (54) Sep 9 12:08:08 DEBUG[16644] chan_sip.c: Header: Authorization: Digest username="wilson-polycom", realm="asterisk", nonce="0aea62af", uri="sip:2000@10.1.1.48:5060", response="adfc6e097dec38a19ac7321cb0be0fac", algorithm=MD5 (174) Sep 9 12:08:08 DEBUG[16644] chan_sip.c: Header: Max-Forwards: 70 (16) Sep 9 12:08:08 DEBUG[16644] chan_sip.c: Header: Expires: 3600 (13) Sep 9 12:08:08 DEBUG[16644] chan_sip.c: Header: Content-Length: 0 (17) Sep 9 12:08:08 DEBUG[16644] chan_sip.c: Header: (0) Sep 9 12:08:08 VERBOSE[16644] logger.c: --- (14 headers 0 lines)Sep 9 12:08:08 VERBOSE[16644] logger.c: --- (14 headers 0 lines)--- Sep 9 12:08:08 DEBUG[16644] chan_sip.c: **** Received SUBSCRIBE (10) - Command in SIP SUBSCRIBE Sep 9 12:08:08 DEBUG[16644] chan_sip.c: Got a re-subscribe on existing subscription 7928dc00-5949e356-88e662a7@10.1.1.200 Sep 9 12:08:08 DEBUG[16644] chan_sip.c: Adding subscription for extension 2000 context from-sip for peer wilson-polycom Sep 9 12:08:08 VERBOSE[16644] logger.c: Transmitting (no NAT) to 10.1.1.200:5060: SIP/2.0 200 OKVia: SIP/2.0/UDP 10.1.1.200;branch=z9hG4bKc4989a73EA569DC2From: "Wilson" ;tag=3E850BCC-20B703CDTo: ;tag=as211d38f3Call-ID: 7928dc00-5949e356-88e662a7@10.1.1.200CSeq: 6 SUBSCRIBEUser-Agent: Asterisk PBXAllow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFYExpires: 3600Contact: ;expires=3600Content-Length: 0 --- Sep 9 12:08:08 VERBOSE[16644] logger.c: Scheduling destruction of call '7928dc00-5949e356-88e662a7@10.1.1.200' in 3610000 ms Sep 9 12:08:08 DEBUG[16644] chan_sip.c: Checking device state for peer scott-polycom Sep 9 12:08:08 DEBUG[16644] chan_sip.c: Checking device state for peer scott-polycom Sep 9 12:08:08 VERBOSE[16644] logger.c: Reliably Transmitting (no NAT) to 10.1.1.200:5060: NOTIFY sip:wilson-polycom@10.1.1.200 SIP/2.0Via: SIP/2.0/UDP 10.1.1.48:5060;branch=z9hG4bK5fae7f93From: ;tag=as211d38f3To: "Wilson" ;tag=3E850BCC-20B703CDContact: Call-ID: 7928dc00-5949e356-88e662a7@10.1.1.200CSeq: 106 NOTIFYUser-Agent: Asterisk PBXEvent: presenceContent-Type: application/xpidf+xmlSubscription-State: activeContent-Length: 348
--- Sep 9 12:08:08 DEBUG[16644] chan_sip.c: *** SIP TIMER: Initalizing retransmit timer on packet: Id #1112 Sep 9 12:08:08 VERBOSE[16644] logger.c: <-- SIP read from 10.1.1.200:5060: SIP/2.0 200 OKVia: SIP/2.0/UDP 10.1.1.48:5060;branch=z9hG4bK5fae7f93From: ;tag=as211d38f3To: "Wilson" ;tag=3E850BCC-20B703CDCSeq: 106 NOTIFYCall-ID: 7928dc00-5949e356-88e662a7@10.1.1.200Contact: Event: presenceUser-Agent: PolycomSoundPointIP-SPIP_600-UA/1.5.2.0054Content-Length: 0 Sep 9 12:08:08 DEBUG[16644] chan_sip.c: Header: SIP/2.0 200 OK (14) Sep 9 12:08:08 DEBUG[16644] chan_sip.c: Header: Via: SIP/2.0/UDP 10.1.1.48:5060;branch=z9hG4bK5fae7f93 (54) Sep 9 12:08:08 DEBUG[16644] chan_sip.c: Header: From: ;tag=as211d38f3 (41) Sep 9 12:08:08 DEBUG[16644] chan_sip.c: Header: To: "Wilson" ;tag=3E850BCC-20B703CD (65) Sep 9 12:08:08 DEBUG[16644] chan_sip.c: Header: CSeq: 106 NOTIFY (16) Sep 9 12:08:08 DEBUG[16644] chan_sip.c: Header: Call-ID: 7928dc00-5949e356-88e662a7@10.1.1.200 (46) Sep 9 12:08:08 DEBUG[16644] chan_sip.c: Header: Contact: (40) Sep 9 12:08:08 DEBUG[16644] chan_sip.c: Header: Event: presence (15) Sep 9 12:08:08 DEBUG[16644] chan_sip.c: Header: User-Agent: PolycomSoundPointIP-SPIP_600-UA/1.5.2.0054 (54) Sep 9 12:08:08 DEBUG[16644] chan_sip.c: Header: Content-Length: 0 (17) Sep 9 12:08:08 DEBUG[16644] chan_sip.c: Header: (0) Sep 9 12:08:08 VERBOSE[16644] logger.c: --- (10 headers 0 lines)Sep 9 12:08:08 VERBOSE[16644] logger.c: --- (10 headers 0 lines)--- Sep 9 12:08:08 DEBUG[16644] chan_sip.c: ** SIP TIMER: Cancelling retransmit of packet (reply received) Retransid #1112 Sep 9 12:08:08 DEBUG[16644] chan_sip.c: Stopping retransmission on '7928dc00-5949e356-88e662a7@10.1.1.200' of Request 106: Match Found Sep 9 12:08:08 VERBOSE[16644] logger.c: SIP Response message for INCOMING dialog NOTIFY arrived