[Sep 8 09:58:11] VERBOSE[8355] logger.c: Asterisk Event Logger restarted [Sep 8 09:58:11] VERBOSE[8355] logger.c: Asterisk Queue Logger restarted [Sep 8 09:58:13] VERBOSE[8353] logger.c: <-- SIP read from 63.77.68.165:5060: INVITE sip:1001@sdtest.nuvio.com;user=phone SIP/2.0 Record-Route: Via: SIP/2.0/UDP 63.77.68.165;branch=z9hG4bKa9d2.d6dc51b7.0 Via: SIP/2.0/UDP 192.168.15.100;rport=10060;received=63.77.68.246;branch=z9hG4bK390e2b583DED65B9 From: "Joe Allison" ;tag=14A91D2B-FD3CA6A2 To: CSeq: 2 INVITE Call-ID: 560cafff-f705a86d-fa2d077c@192.168.15.100 Contact: Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, INFO, MESSAGE, SUBSCRIBE, NOTIFY, PRACK, UPDATE, REFER User-Agent: PolycomSoundPointIP-SPIP_500-UA/1.6.7.0098 Supported: 100rel,replaces Allow-Events: talk,hold,conference Max-Forwards: 16 Content-Type: application/sdp Content-Length: 280 v=0 o=- 1157727408 1157727408 IN IP4 192.168.15.100 s=Polycom IP Phone c=IN IP4 63.77.68.246 t=0 0 a=sendrecv m=audio 2228 RTP/AVP 18 0 8 101 a=rtpmap:18 G729/8000 a=rtpmap:0 PCMU/8000 a=rtpmap:8 PCMA/8000 a=rtpmap:101 telephone-event/8000 a=oldmediaip:192.168.15.100 [Sep 8 09:58:13] DEBUG[8353] chan_sip.c: Header 0: INVITE sip:1001@sdtest.nuvio.com;user=phone SIP/2.0 (51) [Sep 8 09:58:13] DEBUG[8353] chan_sip.c: Header 1: Record-Route: (58) [Sep 8 09:58:13] DEBUG[8353] chan_sip.c: Header 2: Via: SIP/2.0/UDP 63.77.68.165;branch=z9hG4bKa9d2.d6dc51b7.0 (59) [Sep 8 09:58:13] DEBUG[8353] chan_sip.c: Header 3: Via: SIP/2.0/UDP 192.168.15.100;rport=10060;received=63.77.68.246;branch=z9hG4bK390e2b583DED65B9 (96) [Sep 8 09:58:13] DEBUG[8353] chan_sip.c: Header 4: From: "Joe Allison" ;tag=14A91D2B-FD3CA6A2 (73) [Sep 8 09:58:13] DEBUG[8353] chan_sip.c: Header 5: To: (42) [Sep 8 09:58:13] DEBUG[8353] chan_sip.c: Header 6: CSeq: 2 INVITE (14) [Sep 8 09:58:13] DEBUG[8353] chan_sip.c: Header 7: Call-ID: 560cafff-f705a86d-fa2d077c@192.168.15.100 (50) [Sep 8 09:58:13] DEBUG[8353] chan_sip.c: Header 8: Contact: (42) [Sep 8 09:58:13] DEBUG[8353] chan_sip.c: Header 9: Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, INFO, MESSAGE, SUBSCRIBE, NOTIFY, PRACK, UPDATE, REFER (96) [Sep 8 09:58:13] DEBUG[8353] chan_sip.c: Header 10: User-Agent: PolycomSoundPointIP-SPIP_500-UA/1.6.7.0098 (54) [Sep 8 09:58:13] DEBUG[8353] chan_sip.c: Header 11: Supported: 100rel,replaces (26) [Sep 8 09:58:13] DEBUG[8353] chan_sip.c: Header 12: Allow-Events: talk,hold,conference (34) [Sep 8 09:58:13] DEBUG[8353] chan_sip.c: Header 13: Max-Forwards: 16 (16) [Sep 8 09:58:13] DEBUG[8353] chan_sip.c: Header 14: Content-Type: application/sdp (29) [Sep 8 09:58:13] DEBUG[8353] chan_sip.c: Header 15: Content-Length: 280 (19) [Sep 8 09:58:13] DEBUG[8353] chan_sip.c: Header 16: (0) [Sep 8 09:58:13] DEBUG[8353] chan_sip.c: Line: v=0 (3) [Sep 8 09:58:13] DEBUG[8353] chan_sip.c: Line: o=- 1157727408 1157727408 IN IP4 192.168.15.100 (47) [Sep 8 09:58:13] DEBUG[8353] chan_sip.c: Line: s=Polycom IP Phone (18) [Sep 8 09:58:13] DEBUG[8353] chan_sip.c: Line: c=IN IP4 63.77.68.246 (21) [Sep 8 09:58:13] DEBUG[8353] chan_sip.c: Line: t=0 0 (5) [Sep 8 09:58:13] DEBUG[8353] chan_sip.c: Line: a=sendrecv (10) [Sep 8 09:58:13] DEBUG[8353] chan_sip.c: Line: m=audio 2228 RTP/AVP 18 0 8 101 (31) [Sep 8 09:58:13] DEBUG[8353] chan_sip.c: Line: a=rtpmap:18 G729/8000 (21) [Sep 8 09:58:13] DEBUG[8353] chan_sip.c: Line: a=rtpmap:0 PCMU/8000 (20) [Sep 8 09:58:13] DEBUG[8353] chan_sip.c: Line: a=rtpmap:8 PCMA/8000 (20) [Sep 8 09:58:13] DEBUG[8353] chan_sip.c: Line: a=rtpmap:101 telephone-event/8000 (33) [Sep 8 09:58:13] DEBUG[8353] chan_sip.c: Line: a=oldmediaip:192.168.15.100 (27) [Sep 8 09:58:13] VERBOSE[8353] logger.c: --- (16 headers 12 lines)[Sep 8 09:58:13] VERBOSE[8353] logger.c: --- [Sep 8 09:58:13] DEBUG[8353] chan_sip.c: Allocating new SIP dialog for 560cafff-f705a86d-fa2d077c@192.168.15.100 - INVITE (With RTP) [Sep 8 09:58:13] DEBUG[8353] chan_sip.c: **** Received INVITE (5) - Command in SIP INVITE [Sep 8 09:58:13] DEBUG[8353] chan_sip.c: Begin: parsing SIP "Supported: 100rel,replaces" [Sep 8 09:58:13] DEBUG[8353] chan_sip.c: Found SIP option: -100rel- [Sep 8 09:58:13] DEBUG[8353] chan_sip.c: Matched SIP option: 100rel [Sep 8 09:58:13] DEBUG[8353] chan_sip.c: Found SIP option: -replaces- [Sep 8 09:58:13] DEBUG[8353] chan_sip.c: Matched SIP option: replaces [Sep 8 09:58:13] VERBOSE[8353] logger.c: Sending to 63.77.68.165 : 5060 (no NAT) [Sep 8 09:58:13] VERBOSE[8353] logger.c: Using INVITE request as basis request - 560cafff-f705a86d-fa2d077c@192.168.15.100 [Sep 8 09:58:13] DEBUG[8353] chan_sip.c: Setting NAT on RTP to Off [Sep 8 09:58:13] VERBOSE[8353] logger.c: Found user '714nv101' [Sep 8 09:58:13] VERBOSE[8353] logger.c: Found RTP audio format 18 [Sep 8 09:58:13] VERBOSE[8353] logger.c: Found RTP audio format 0 [Sep 8 09:58:13] VERBOSE[8353] logger.c: Found RTP audio format 8 [Sep 8 09:58:13] VERBOSE[8353] logger.c: Found RTP audio format 101 [Sep 8 09:58:13] VERBOSE[8353] logger.c: Peer audio RTP is at port 63.77.68.246:2228 [Sep 8 09:58:13] VERBOSE[8353] logger.c: Found description format G729 for ID 18 [Sep 8 09:58:13] VERBOSE[8353] logger.c: Found description format PCMU for ID 0 [Sep 8 09:58:13] VERBOSE[8353] logger.c: Found description format PCMA for ID 8 [Sep 8 09:58:13] VERBOSE[8353] logger.c: Found description format telephone-event for ID 101 [Sep 8 09:58:13] DEBUG[8353] chan_sip.c: T38 state changed to 0 on channel [Sep 8 09:58:13] VERBOSE[8353] logger.c: Capabilities: us - 0x4 (ulaw), peer - audio=0x10c (ulaw|alaw|g729)/video=0x0 (nothing), combined - 0x4 (ulaw) [Sep 8 09:58:13] VERBOSE[8353] logger.c: Non-codec capabilities (dtmf): us - 0x1 (telephone-event), peer - 0x1 (telephone-event), combined - 0x1 (telephone-event) [Sep 8 09:58:13] VERBOSE[8353] logger.c: Peer audio RTP is at port 63.77.68.246:2228 [Sep 8 09:58:13] DEBUG[8353] chan_sip.c: We're settling with these formats: 0x4 (ulaw) [Sep 8 09:58:13] DEBUG[8353] chan_sip.c: Checking SIP call limits for device 714nv101 [Sep 8 09:58:13] DEBUG[8353] chan_sip.c: Updating call counter for incoming call [Sep 8 09:58:13] VERBOSE[8353] logger.c: Looking for 1001 in default (domain sdtest.nuvio.com) [Sep 8 09:58:13] DEBUG[8353] chan_sip.c: *** Our native formats are 0x4 (ulaw) [Sep 8 09:58:13] DEBUG[8353] chan_sip.c: *** Joint capabilities are 0x4 (ulaw) [Sep 8 09:58:13] DEBUG[8353] chan_sip.c: *** Our capabilities are 0x4 (ulaw) [Sep 8 09:58:13] DEBUG[8353] chan_sip.c: *** AST_CODEC_CHOOSE formats are 0x4 (ulaw) [Sep 8 09:58:13] DEBUG[8353] chan_sip.c: This channel will not be able to handle video. [Sep 8 09:58:13] DEBUG[8353] chan_sip.c: build_route: Record-Route hop: [Sep 8 09:58:13] VERBOSE[8353] logger.c: list_route: hop: [Sep 8 09:58:13] DEBUG[8353] chan_sip.c: SIP/714nv101-08d43518: New call is still down.... Trying... [Sep 8 09:58:13] VERBOSE[8353] logger.c: Transmitting (no NAT) to 63.77.68.165:5060: SIP/2.0 100 Trying Via: SIP/2.0/UDP 63.77.68.165;branch=z9hG4bKa9d2.d6dc51b7.0;received=63.77.68.165 Via: SIP/2.0/UDP 192.168.15.100;rport=10060;received=63.77.68.246;branch=z9hG4bK390e2b583DED65B9 From: "Joe Allison" ;tag=14A91D2B-FD3CA6A2 To: Call-ID: 560cafff-f705a86d-fa2d077c@192.168.15.100 CSeq: 2 INVITE User-Agent: NuvioSoftSwitch Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Supported: replaces Contact: Content-Length: 0 --- [Sep 8 09:58:13] DEBUG[8353] chan_sip.c: Header 0: SIP/2.0 100 Trying (18) [Sep 8 09:58:13] DEBUG[8353] chan_sip.c: Header 1: Via: SIP/2.0/UDP 63.77.68.165;branch=z9hG4bKa9d2.d6dc51b7.0;received=63.77.68.165 (81) [Sep 8 09:58:13] DEBUG[8353] chan_sip.c: Header 2: Via: SIP/2.0/UDP 192.168.15.100;rport=10060;received=63.77.68.246;branch=z9hG4bK390e2b583DED65B9 (96) [Sep 8 09:58:13] DEBUG[8353] chan_sip.c: Header 3: From: "Joe Allison" ;tag=14A91D2B-FD3CA6A2 (73) [Sep 8 09:58:13] DEBUG[8353] chan_sip.c: Header 4: To: (42) [Sep 8 09:58:13] DEBUG[8353] chan_sip.c: Header 5: Call-ID: 560cafff-f705a86d-fa2d077c@192.168.15.100 (50) [Sep 8 09:58:13] DEBUG[8353] chan_sip.c: Header 6: CSeq: 2 INVITE (14) [Sep 8 09:58:13] DEBUG[8353] chan_sip.c: Header 7: User-Agent: NuvioSoftSwitch (27) [Sep 8 09:58:13] DEBUG[8353] chan_sip.c: Header 8: Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY (66) [Sep 8 09:58:13] DEBUG[8353] chan_sip.c: Header 9: Supported: replaces (19) [Sep 8 09:58:13] DEBUG[8353] chan_sip.c: Header 10: Contact: (32) [Sep 8 09:58:13] DEBUG[8353] chan_sip.c: Header 11: Content-Length: 0 (17) [Sep 8 09:58:13] DEBUG[8353] chan_sip.c: Header 12: (0) [Sep 8 09:58:13] DEBUG[8353] devicestate.c: Notification of state change to be queued on device/channel SIP/714nv101-08d43518 [Sep 8 09:58:13] DEBUG[8350] devicestate.c: No provider found, checking channel drivers for SIP - 714nv101 [Sep 8 09:58:13] DEBUG[8350] chan_sip.c: Checking device state for peer 714nv101 [Sep 8 09:58:13] DEBUG[8350] devicestate.c: Changing state for SIP/714nv101 - state 1 (Not in use) [Sep 8 09:58:13] DEBUG[8350] devicestate.c: No provider found, checking channel drivers for SIP - 714nv101 [Sep 8 09:58:13] DEBUG[8350] chan_sip.c: Checking device state for peer 714nv101 [Sep 8 09:58:13] DEBUG[8357] pbx.c: Launching 'Dial' [Sep 8 09:58:13] VERBOSE[8357] logger.c: -- Executing [1001@default:1] Dial("SIP/714nv101-08d43518", "SIP/714nv100") in new stack [Sep 8 09:58:13] DEBUG[8357] chan_sip.c: Asked to create a SIP channel with formats: 0x4 (ulaw) [Sep 8 09:58:13] DEBUG[8357] chan_sip.c: Allocating new SIP dialog for (No Call-ID) - INVITE (With RTP) [Sep 8 09:58:13] DEBUG[8357] chan_sip.c: Setting NAT on RTP to Off [Sep 8 09:58:13] DEBUG[8357] chan_sip.c: *** Our native formats are 0x4 (ulaw) [Sep 8 09:58:13] DEBUG[8357] chan_sip.c: *** Joint capabilities are 0x0 (nothing) [Sep 8 09:58:13] DEBUG[8357] chan_sip.c: *** Our capabilities are 0x4 (ulaw) [Sep 8 09:58:13] DEBUG[8357] chan_sip.c: *** AST_CODEC_CHOOSE formats are 0x4 (ulaw) [Sep 8 09:58:13] DEBUG[8357] chan_sip.c: *** Our preferred formats from the incoming channel are 0x4 (ulaw) [Sep 8 09:58:13] DEBUG[8357] chan_sip.c: This channel will not be able to handle video. [Sep 8 09:58:13] DEBUG[8357] channel.c: Not copying variable STACK-default-1001-1. [Sep 8 09:58:13] DEBUG[8357] channel.c: Not copying variable SIPCALLID. [Sep 8 09:58:13] DEBUG[8357] channel.c: Not copying variable SIPUSERAGENT. [Sep 8 09:58:13] DEBUG[8357] channel.c: Not copying variable SIPDOMAIN. [Sep 8 09:58:13] DEBUG[8357] channel.c: Not copying variable SIPURI. [Sep 8 09:58:13] DEBUG[8357] chan_sip.c: Outgoing Call for 714nv100 [Sep 8 09:58:13] DEBUG[8357] chan_sip.c: Updating call counter for outgoing call [Sep 8 09:58:13] DEBUG[8357] chan_sip.c: Our T38 capability (0), joint T38 capability (0) [Sep 8 09:58:13] DEBUG[8357] chan_sip.c: ** Our capability: 0x4 (ulaw) Video flag: False [Sep 8 09:58:13] DEBUG[8357] chan_sip.c: ** Our prefcodec: 0x4 (ulaw) [Sep 8 09:58:13] VERBOSE[8357] logger.c: Audio is at 63.77.68.164 port 17526 [Sep 8 09:58:13] VERBOSE[8357] logger.c: Adding codec 0x4 (ulaw) to SDP [Sep 8 09:58:13] VERBOSE[8357] logger.c: Adding non-codec 0x1 (telephone-event) to SDP [Sep 8 09:58:13] DEBUG[8357] chan_sip.c: -- Done with adding codecs to SDP [Sep 8 09:58:13] DEBUG[8357] chan_sip.c: Done building SDP. Settling with this capability: 0x4 (ulaw) [Sep 8 09:58:13] DEBUG[8357] chan_sip.c: Header 0: INVITE sip:714nv100@63.77.68.165 SIP/2.0 (40) [Sep 8 09:58:13] DEBUG[8357] chan_sip.c: Header 1: Via: SIP/2.0/UDP 63.77.68.164:5060;branch=z9hG4bK0c052172;rport (63) [Sep 8 09:58:13] DEBUG[8357] chan_sip.c: Header 2: From: "Joe Allison" ;tag=as1a0c4a58 (62) [Sep 8 09:58:13] DEBUG[8357] chan_sip.c: Header 3: To: (31) [Sep 8 09:58:13] DEBUG[8357] chan_sip.c: Header 4: Contact: (36) [Sep 8 09:58:13] DEBUG[8357] chan_sip.c: Header 5: Call-ID: 7c83f98a2c1af72b2c68ef5b671452ec@63.77.68.164 (54) [Sep 8 09:58:13] DEBUG[8357] chan_sip.c: Header 6: CSeq: 102 INVITE (16) [Sep 8 09:58:13] DEBUG[8357] chan_sip.c: Header 7: User-Agent: NuvioSoftSwitch (27) [Sep 8 09:58:13] DEBUG[8357] chan_sip.c: Header 8: Max-Forwards: 70 (16) [Sep 8 09:58:13] DEBUG[8357] chan_sip.c: Header 9: Date: Fri, 08 Sep 2006 14:58:13 GMT (35) [Sep 8 09:58:13] DEBUG[8357] chan_sip.c: Header 10: Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY (66) [Sep 8 09:58:13] DEBUG[8357] chan_sip.c: Header 11: Supported: replaces (19) [Sep 8 09:58:13] DEBUG[8357] chan_sip.c: Header 12: Content-Type: application/sdp (29) [Sep 8 09:58:13] DEBUG[8357] chan_sip.c: Header 13: Content-Length: 226 (19) [Sep 8 09:58:13] DEBUG[8357] chan_sip.c: Header 14: (0) [Sep 8 09:58:13] DEBUG[8357] chan_sip.c: Line: v=0 (3) [Sep 8 09:58:13] DEBUG[8357] chan_sip.c: Line: o=root 8326 8326 IN IP4 63.77.68.164 (36) [Sep 8 09:58:13] DEBUG[8357] chan_sip.c: Line: s=session (9) [Sep 8 09:58:13] DEBUG[8357] chan_sip.c: Line: c=IN IP4 63.77.68.164 (21) [Sep 8 09:58:13] DEBUG[8357] chan_sip.c: Line: t=0 0 (5) [Sep 8 09:58:13] DEBUG[8357] chan_sip.c: Line: m=audio 17526 RTP/AVP 0 101 (27) [Sep 8 09:58:13] DEBUG[8357] chan_sip.c: Line: a=rtpmap:0 PCMU/8000 (20) [Sep 8 09:58:13] DEBUG[8357] chan_sip.c: Line: a=rtpmap:101 telephone-event/8000 (33) [Sep 8 09:58:13] DEBUG[8357] chan_sip.c: Line: a=fmtp:101 0-16 (15) [Sep 8 09:58:13] DEBUG[8357] chan_sip.c: Line: a=silenceSupp:off - - - - (25) [Sep 8 09:58:13] DEBUG[8357] chan_sip.c: Line: a=sendrecv (10) [Sep 8 09:58:13] VERBOSE[8357] logger.c: Reliably Transmitting (no NAT) to 63.77.68.165:5060: INVITE sip:714nv100@63.77.68.165 SIP/2.0 Via: SIP/2.0/UDP 63.77.68.164:5060;branch=z9hG4bK0c052172;rport From: "Joe Allison" ;tag=as1a0c4a58 To: Contact: Call-ID: 7c83f98a2c1af72b2c68ef5b671452ec@63.77.68.164 CSeq: 102 INVITE User-Agent: NuvioSoftSwitch Max-Forwards: 70 Date: Fri, 08 Sep 2006 14:58:13 GMT Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Supported: replaces Content-Type: application/sdp Content-Length: 226 v=0 o=root 8326 8326 IN IP4 63.77.68.164 s=session c=IN IP4 63.77.68.164 t=0 0 m=audio 17526 RTP/AVP 0 101 a=rtpmap:0 PCMU/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=silenceSupp:off - - - - a=sendrecv --- [Sep 8 09:58:13] DEBUG[8357] chan_sip.c: Header 0: INVITE sip:714nv100@63.77.68.165 SIP/2.0 (40) [Sep 8 09:58:13] DEBUG[8357] chan_sip.c: Header 1: Via: SIP/2.0/UDP 63.77.68.164:5060;branch=z9hG4bK0c052172;rport (63) [Sep 8 09:58:13] DEBUG[8357] chan_sip.c: Header 2: From: "Joe Allison" ;tag=as1a0c4a58 (62) [Sep 8 09:58:13] DEBUG[8357] chan_sip.c: Header 3: To: (31) [Sep 8 09:58:13] DEBUG[8357] chan_sip.c: Header 4: Contact: (36) [Sep 8 09:58:13] DEBUG[8357] chan_sip.c: Header 5: Call-ID: 7c83f98a2c1af72b2c68ef5b671452ec@63.77.68.164 (54) [Sep 8 09:58:13] DEBUG[8357] chan_sip.c: Header 6: CSeq: 102 INVITE (16) [Sep 8 09:58:13] DEBUG[8357] chan_sip.c: Header 7: User-Agent: NuvioSoftSwitch (27) [Sep 8 09:58:13] DEBUG[8357] chan_sip.c: Header 8: Max-Forwards: 70 (16) [Sep 8 09:58:13] DEBUG[8357] chan_sip.c: Header 9: Date: Fri, 08 Sep 2006 14:58:13 GMT (35) [Sep 8 09:58:13] DEBUG[8357] chan_sip.c: Header 10: Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY (66) [Sep 8 09:58:13] DEBUG[8357] chan_sip.c: Header 11: Supported: replaces (19) [Sep 8 09:58:13] DEBUG[8357] chan_sip.c: Header 12: Content-Type: application/sdp (29) [Sep 8 09:58:13] DEBUG[8357] chan_sip.c: Header 13: Content-Length: 226 (19) [Sep 8 09:58:13] DEBUG[8357] chan_sip.c: Header 14: (0) [Sep 8 09:58:13] DEBUG[8357] chan_sip.c: Line: v=0 (3) [Sep 8 09:58:13] DEBUG[8357] chan_sip.c: Line: o=root 8326 8326 IN IP4 63.77.68.164 (36) [Sep 8 09:58:13] DEBUG[8357] chan_sip.c: Line: s=session (9) [Sep 8 09:58:13] DEBUG[8357] chan_sip.c: Line: c=IN IP4 63.77.68.164 (21) [Sep 8 09:58:13] DEBUG[8357] chan_sip.c: Line: t=0 0 (5) [Sep 8 09:58:13] DEBUG[8357] chan_sip.c: Line: m=audio 17526 RTP/AVP 0 101 (27) [Sep 8 09:58:13] DEBUG[8357] chan_sip.c: Line: a=rtpmap:0 PCMU/8000 (20) [Sep 8 09:58:13] DEBUG[8357] chan_sip.c: Line: a=rtpmap:101 telephone-event/8000 (33) [Sep 8 09:58:13] DEBUG[8357] chan_sip.c: Line: a=fmtp:101 0-16 (15) [Sep 8 09:58:13] DEBUG[8357] chan_sip.c: Line: a=silenceSupp:off - - - - (25) [Sep 8 09:58:13] DEBUG[8357] chan_sip.c: Line: a=sendrecv (10) [Sep 8 09:58:13] DEBUG[8357] chan_sip.c: *** SIP TIMER: Initalizing retransmit timer on packet: Id #17 [Sep 8 09:58:13] VERBOSE[8357] logger.c: -- Called 714nv100 [Sep 8 09:58:13] VERBOSE[8353] logger.c: <-- SIP read from 63.77.68.165:5060: SIP/2.0 100 trying -- your call is important to us Via: SIP/2.0/UDP 63.77.68.164:5060;branch=z9hG4bK0c052172;rport=5060 From: "Joe Allison" ;tag=as1a0c4a58 To: Call-ID: 7c83f98a2c1af72b2c68ef5b671452ec@63.77.68.164 CSeq: 102 INVITE Content-Length: 0 [Sep 8 09:58:13] DEBUG[8353] chan_sip.c: Header 0: SIP/2.0 100 trying -- your call is important to us (50) [Sep 8 09:58:13] DEBUG[8353] chan_sip.c: Header 1: Via: SIP/2.0/UDP 63.77.68.164:5060;branch=z9hG4bK0c052172;rport=5060 (68) [Sep 8 09:58:13] DEBUG[8353] chan_sip.c: Header 2: From: "Joe Allison" ;tag=as1a0c4a58 (62) [Sep 8 09:58:13] DEBUG[8353] chan_sip.c: Header 3: To: (31) [Sep 8 09:58:13] DEBUG[8353] chan_sip.c: Header 4: Call-ID: 7c83f98a2c1af72b2c68ef5b671452ec@63.77.68.164 (54) [Sep 8 09:58:13] DEBUG[8353] chan_sip.c: Header 5: CSeq: 102 INVITE (16) [Sep 8 09:58:13] DEBUG[8353] chan_sip.c: Header 6: Content-Length: 0 (17) [Sep 8 09:58:13] DEBUG[8353] chan_sip.c: Header 7: (0) [Sep 8 09:58:13] VERBOSE[8353] logger.c: --- (7 headers 0 lines)[Sep 8 09:58:13] VERBOSE[8353] logger.c: --- [Sep 8 09:58:13] DEBUG[8353] chan_sip.c: *** SIP TIMER: Cancelling retransmission #17 - INVITE (got response) [Sep 8 09:58:13] DEBUG[8353] chan_sip.c: (Provisional) Stopping retransmission (but retaining packet) on '7c83f98a2c1af72b2c68ef5b671452ec@63.77.68.164' Request 102: Found [Sep 8 09:58:13] DEBUG[8353] chan_sip.c: SIP response 100 to standard invite [Sep 8 09:58:14] VERBOSE[8353] logger.c: <-- SIP read from 63.77.68.165:5060: SIP/2.0 180 Ringing Via: SIP/2.0/UDP 63.77.68.164:5060;branch=z9hG4bK0c052172;rport=5060 From: "Joe Allison" ;tag=as1a0c4a58 To: ;tag=3BDC5C01-E0184EE CSeq: 102 INVITE Call-ID: 7c83f98a2c1af72b2c68ef5b671452ec@63.77.68.164 Contact: Record-Route: User-Agent: PolycomSoundPointIP-SPIP_500-UA/1.6.7.0098 Allow-Events: talk,hold,conference Content-Length: 0 [Sep 8 09:58:14] DEBUG[8353] chan_sip.c: Header 0: SIP/2.0 180 Ringing (19) [Sep 8 09:58:14] DEBUG[8353] chan_sip.c: Header 1: Via: SIP/2.0/UDP 63.77.68.164:5060;branch=z9hG4bK0c052172;rport=5060 (68) [Sep 8 09:58:14] DEBUG[8353] chan_sip.c: Header 2: From: "Joe Allison" ;tag=as1a0c4a58 (62) [Sep 8 09:58:14] DEBUG[8353] chan_sip.c: Header 3: To: ;tag=3BDC5C01-E0184EE (52) [Sep 8 09:58:14] DEBUG[8353] chan_sip.c: Header 4: CSeq: 102 INVITE (16) [Sep 8 09:58:14] DEBUG[8353] chan_sip.c: Header 5: Call-ID: 7c83f98a2c1af72b2c68ef5b671452ec@63.77.68.164 (54) [Sep 8 09:58:14] DEBUG[8353] chan_sip.c: Header 6: Contact: (35) [Sep 8 09:58:14] DEBUG[8353] chan_sip.c: Header 7: Record-Route: (51) [Sep 8 09:58:14] DEBUG[8353] chan_sip.c: Header 8: User-Agent: PolycomSoundPointIP-SPIP_500-UA/1.6.7.0098 (54) [Sep 8 09:58:14] DEBUG[8353] chan_sip.c: Header 9: Allow-Events: talk,hold,conference (34) [Sep 8 09:58:14] DEBUG[8353] chan_sip.c: Header 10: Content-Length: 0 (17) [Sep 8 09:58:14] DEBUG[8353] chan_sip.c: Header 11: (0) [Sep 8 09:58:14] VERBOSE[8353] logger.c: --- (11 headers 0 lines)[Sep 8 09:58:14] VERBOSE[8353] logger.c: --- [Sep 8 09:58:14] DEBUG[8353] chan_sip.c: (Provisional) Stopping retransmission (but retaining packet) on '7c83f98a2c1af72b2c68ef5b671452ec@63.77.68.164' Request 102: Found [Sep 8 09:58:14] DEBUG[8353] chan_sip.c: SIP response 180 to standard invite [Sep 8 09:58:14] DEBUG[8353] devicestate.c: Notification of state change to be queued on device/channel SIP/714nv100-08d48778 [Sep 8 09:58:14] DEBUG[8350] devicestate.c: No provider found, checking channel drivers for SIP - 714nv100 [Sep 8 09:58:14] DEBUG[8350] chan_sip.c: Checking device state for peer 714nv100 [Sep 8 09:58:14] DEBUG[8350] devicestate.c: Changing state for SIP/714nv100 - state 1 (Not in use) [Sep 8 09:58:14] DEBUG[8350] devicestate.c: No provider found, checking channel drivers for SIP - 714nv100 [Sep 8 09:58:14] DEBUG[8350] chan_sip.c: Checking device state for peer 714nv100 [Sep 8 09:58:14] VERBOSE[8357] logger.c: -- SIP/714nv100-08d48778 is ringing [Sep 8 09:58:14] VERBOSE[8357] logger.c: Transmitting (no NAT) to 63.77.68.165:5060: SIP/2.0 180 Ringing Via: SIP/2.0/UDP 63.77.68.165;branch=z9hG4bKa9d2.d6dc51b7.0;received=63.77.68.165 Via: SIP/2.0/UDP 192.168.15.100;rport=10060;received=63.77.68.246;branch=z9hG4bK390e2b583DED65B9 From: "Joe Allison" ;tag=14A91D2B-FD3CA6A2 To: ;tag=as25a083c1 Call-ID: 560cafff-f705a86d-fa2d077c@192.168.15.100 CSeq: 2 INVITE User-Agent: NuvioSoftSwitch Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Supported: replaces Contact: Content-Length: 0 --- [Sep 8 09:58:14] DEBUG[8357] chan_sip.c: Header 0: SIP/2.0 180 Ringing (19) [Sep 8 09:58:14] DEBUG[8357] chan_sip.c: Header 1: Via: SIP/2.0/UDP 63.77.68.165;branch=z9hG4bKa9d2.d6dc51b7.0;received=63.77.68.165 (81) [Sep 8 09:58:14] DEBUG[8357] chan_sip.c: Header 2: Via: SIP/2.0/UDP 192.168.15.100;rport=10060;received=63.77.68.246;branch=z9hG4bK390e2b583DED65B9 (96) [Sep 8 09:58:14] DEBUG[8357] chan_sip.c: Header 3: From: "Joe Allison" ;tag=14A91D2B-FD3CA6A2 (73) [Sep 8 09:58:14] DEBUG[8357] chan_sip.c: Header 4: To: ;tag=as25a083c1 (57) [Sep 8 09:58:14] DEBUG[8357] chan_sip.c: Header 5: Call-ID: 560cafff-f705a86d-fa2d077c@192.168.15.100 (50) [Sep 8 09:58:14] DEBUG[8357] chan_sip.c: Header 6: CSeq: 2 INVITE (14) [Sep 8 09:58:14] DEBUG[8357] chan_sip.c: Header 7: User-Agent: NuvioSoftSwitch (27) [Sep 8 09:58:14] DEBUG[8357] chan_sip.c: Header 8: Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY (66) [Sep 8 09:58:14] DEBUG[8357] chan_sip.c: Header 9: Supported: replaces (19) [Sep 8 09:58:14] DEBUG[8357] chan_sip.c: Header 10: Contact: (32) [Sep 8 09:58:14] DEBUG[8357] chan_sip.c: Header 11: Content-Length: 0 (17) [Sep 8 09:58:14] DEBUG[8357] chan_sip.c: Header 12: (0) [Sep 8 09:58:14] DEBUG[8357] channel.c: Driver for channel 'SIP/714nv101-08d43518' does not support indication 3, emulating it [Sep 8 09:58:14] DEBUG[8357] channel.c: Prodding channel 'SIP/714nv101-08d43518' [Sep 8 09:58:14] DEBUG[8357] chan_sip.c: ** Our capability: 0x4 (ulaw) Video flag: True [Sep 8 09:58:14] DEBUG[8357] chan_sip.c: ** Our prefcodec: 0x0 (nothing) [Sep 8 09:58:14] VERBOSE[8357] logger.c: Audio is at 63.77.68.164 port 10264 [Sep 8 09:58:14] VERBOSE[8357] logger.c: Adding codec 0x4 (ulaw) to SDP [Sep 8 09:58:14] VERBOSE[8357] logger.c: Adding non-codec 0x1 (telephone-event) to SDP [Sep 8 09:58:14] DEBUG[8357] chan_sip.c: -- Done with adding codecs to SDP [Sep 8 09:58:14] DEBUG[8357] chan_sip.c: Done building SDP. Settling with this capability: 0x4 (ulaw) [Sep 8 09:58:14] VERBOSE[8357] logger.c: Transmitting (no NAT) to 63.77.68.165:5060: SIP/2.0 183 Session Progress Via: SIP/2.0/UDP 63.77.68.165;branch=z9hG4bKa9d2.d6dc51b7.0;received=63.77.68.165 Via: SIP/2.0/UDP 192.168.15.100;rport=10060;received=63.77.68.246;branch=z9hG4bK390e2b583DED65B9 From: "Joe Allison" ;tag=14A91D2B-FD3CA6A2 To: ;tag=as25a083c1 Call-ID: 560cafff-f705a86d-fa2d077c@192.168.15.100 CSeq: 2 INVITE User-Agent: NuvioSoftSwitch Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Supported: replaces Contact: Content-Type: application/sdp Content-Length: 226 v=0 o=root 8326 8326 IN IP4 63.77.68.164 s=session c=IN IP4 63.77.68.164 t=0 0 m=audio 10264 RTP/AVP 0 101 a=rtpmap:0 PCMU/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=silenceSupp:off - - - - a=sendrecv --- [Sep 8 09:58:14] DEBUG[8357] chan_sip.c: Header 0: SIP/2.0 183 Session Progress (28) [Sep 8 09:58:14] DEBUG[8357] chan_sip.c: Header 1: Via: SIP/2.0/UDP 63.77.68.165;branch=z9hG4bKa9d2.d6dc51b7.0;received=63.77.68.165 (81) [Sep 8 09:58:14] DEBUG[8357] chan_sip.c: Header 2: Via: SIP/2.0/UDP 192.168.15.100;rport=10060;received=63.77.68.246;branch=z9hG4bK390e2b583DED65B9 (96) [Sep 8 09:58:14] DEBUG[8357] chan_sip.c: Header 3: From: "Joe Allison" ;tag=14A91D2B-FD3CA6A2 (73) [Sep 8 09:58:14] DEBUG[8357] chan_sip.c: Header 4: To: ;tag=as25a083c1 (57) [Sep 8 09:58:14] DEBUG[8357] chan_sip.c: Header 5: Call-ID: 560cafff-f705a86d-fa2d077c@192.168.15.100 (50) [Sep 8 09:58:14] DEBUG[8357] chan_sip.c: Header 6: CSeq: 2 INVITE (14) [Sep 8 09:58:14] DEBUG[8357] chan_sip.c: Header 7: User-Agent: NuvioSoftSwitch (27) [Sep 8 09:58:14] DEBUG[8357] chan_sip.c: Header 8: Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY (66) [Sep 8 09:58:14] DEBUG[8357] chan_sip.c: Header 9: Supported: replaces (19) [Sep 8 09:58:14] DEBUG[8357] chan_sip.c: Header 10: Contact: (32) [Sep 8 09:58:14] DEBUG[8357] chan_sip.c: Header 11: Content-Type: application/sdp (29) [Sep 8 09:58:14] DEBUG[8357] chan_sip.c: Header 12: Content-Length: 226 (19) [Sep 8 09:58:14] DEBUG[8357] chan_sip.c: Header 13: (0) [Sep 8 09:58:14] DEBUG[8357] chan_sip.c: Line: v=0 (3) [Sep 8 09:58:14] DEBUG[8357] chan_sip.c: Line: o=root 8326 8326 IN IP4 63.77.68.164 (36) [Sep 8 09:58:14] DEBUG[8357] chan_sip.c: Line: s=session (9) [Sep 8 09:58:14] DEBUG[8357] chan_sip.c: Line: c=IN IP4 63.77.68.164 (21) [Sep 8 09:58:14] DEBUG[8357] chan_sip.c: Line: t=0 0 (5) [Sep 8 09:58:14] DEBUG[8357] chan_sip.c: Line: m=audio 10264 RTP/AVP 0 101 (27) [Sep 8 09:58:14] DEBUG[8357] chan_sip.c: Line: a=rtpmap:0 PCMU/8000 (20) [Sep 8 09:58:14] DEBUG[8357] chan_sip.c: Line: a=rtpmap:101 telephone-event/8000 (33) [Sep 8 09:58:14] DEBUG[8357] chan_sip.c: Line: a=fmtp:101 0-16 (15) [Sep 8 09:58:14] DEBUG[8357] chan_sip.c: Line: a=silenceSupp:off - - - - (25) [Sep 8 09:58:14] DEBUG[8357] chan_sip.c: Line: a=sendrecv (10) [Sep 8 09:58:14] DEBUG[8357] channel.c: Set channel SIP/714nv101-08d43518 to write format slin [Sep 8 09:58:14] DEBUG[8357] rtp.c: Ooh, format changed from unknown to ulaw [Sep 8 09:58:20] DEBUG[8357] rtp.c: Got RTCP report of 92 bytes [Sep 8 09:58:23] VERBOSE[8353] logger.c: <-- SIP read from 63.77.68.165:5060: CANCEL sip:1001@sdtest.nuvio.com;user=phone SIP/2.0 Record-Route: Via: SIP/2.0/UDP 63.77.68.165;branch=z9hG4bKa9d2.d6dc51b7.0 Via: SIP/2.0/UDP 192.168.15.100;rport=10060;received=63.77.68.246;branch=z9hG4bK390e2b583DED65B9 From: "Joe Allison" ;tag=14A91D2B-FD3CA6A2 To: CSeq: 2 CANCEL Call-ID: 560cafff-f705a86d-fa2d077c@192.168.15.100 Contact: Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, INFO, MESSAGE, SUBSCRIBE, NOTIFY, PRACK, UPDATE, REFER User-Agent: PolycomSoundPointIP-SPIP_500-UA/1.6.7.0098 Proxy-Authorization: Digest username="714nv101", realm="nuvio.com", nonce="4501863175e4a7dd3261fe77eaef4fbf89be185c", uri="sip:1001@sdtest.nuvio.com;user=phone", response="8a2d4a0c24fa73d106d5c7daf8b1f301", algorithm=MD5 Max-Forwards: 16 Content-Length: 0 [Sep 8 09:58:23] DEBUG[8353] chan_sip.c: Header 0: CANCEL sip:1001@sdtest.nuvio.com;user=phone SIP/2.0 (51) [Sep 8 09:58:23] DEBUG[8353] chan_sip.c: Header 1: Record-Route: (58) [Sep 8 09:58:23] DEBUG[8353] chan_sip.c: Header 2: Via: SIP/2.0/UDP 63.77.68.165;branch=z9hG4bKa9d2.d6dc51b7.0 (59) [Sep 8 09:58:23] DEBUG[8353] chan_sip.c: Header 3: Via: SIP/2.0/UDP 192.168.15.100;rport=10060;received=63.77.68.246;branch=z9hG4bK390e2b583DED65B9 (96) [Sep 8 09:58:23] DEBUG[8353] chan_sip.c: Header 4: From: "Joe Allison" ;tag=14A91D2B-FD3CA6A2 (73) [Sep 8 09:58:23] DEBUG[8353] chan_sip.c: Header 5: To: (42) [Sep 8 09:58:23] DEBUG[8353] chan_sip.c: Header 6: CSeq: 2 CANCEL (14) [Sep 8 09:58:23] DEBUG[8353] chan_sip.c: Header 7: Call-ID: 560cafff-f705a86d-fa2d077c@192.168.15.100 (50) [Sep 8 09:58:23] DEBUG[8353] chan_sip.c: Header 8: Contact: (42) [Sep 8 09:58:23] DEBUG[8353] chan_sip.c: Header 9: Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, INFO, MESSAGE, SUBSCRIBE, NOTIFY, PRACK, UPDATE, REFER (96) [Sep 8 09:58:23] DEBUG[8353] chan_sip.c: Header 10: User-Agent: PolycomSoundPointIP-SPIP_500-UA/1.6.7.0098 (54) [Sep 8 09:58:23] DEBUG[8353] chan_sip.c: Header 11: Proxy-Authorization: Digest username="714nv101", realm="nuvio.com", nonce="4501863175e4a7dd3261fe77eaef4fbf89be185c", uri="sip:1001@sdtest.nuvio.com;user=phone", response="8a2d4a0c24fa73d106d5c7daf8b1f301", algorithm=MD5 (220) [Sep 8 09:58:23] DEBUG[8353] chan_sip.c: Header 12: Max-Forwards: 16 (16) [Sep 8 09:58:23] DEBUG[8353] chan_sip.c: Header 13: Content-Length: 0 (17) [Sep 8 09:58:23] DEBUG[8353] chan_sip.c: Header 14: (0) [Sep 8 09:58:23] VERBOSE[8353] logger.c: --- (14 headers 0 lines)[Sep 8 09:58:23] VERBOSE[8353] logger.c: --- [Sep 8 09:58:23] DEBUG[8353] chan_sip.c: **** Received CANCEL (14) - Command in SIP CANCEL [Sep 8 09:58:23] VERBOSE[8353] logger.c: Sending to 63.77.68.165 : 5060 (no NAT) [Sep 8 09:58:23] VERBOSE[8353] logger.c: Reliably Transmitting (no NAT) to 63.77.68.165:5060: SIP/2.0 487 Request Terminated Via: SIP/2.0/UDP 63.77.68.165;branch=z9hG4bKa9d2.d6dc51b7.0;received=63.77.68.165 Via: SIP/2.0/UDP 192.168.15.100;rport=10060;received=63.77.68.246;branch=z9hG4bK390e2b583DED65B9 From: "Joe Allison" ;tag=14A91D2B-FD3CA6A2 To: ;tag=as25a083c1 Call-ID: 560cafff-f705a86d-fa2d077c@192.168.15.100 CSeq: 2 INVITE User-Agent: NuvioSoftSwitch Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Supported: replaces Contact: Content-Length: 0 --- [Sep 8 09:58:23] DEBUG[8353] chan_sip.c: Header 0: SIP/2.0 487 Request Terminated (30) [Sep 8 09:58:23] DEBUG[8353] chan_sip.c: Header 1: Via: SIP/2.0/UDP 63.77.68.165;branch=z9hG4bKa9d2.d6dc51b7.0;received=63.77.68.165 (81) [Sep 8 09:58:23] DEBUG[8353] chan_sip.c: Header 2: Via: SIP/2.0/UDP 192.168.15.100;rport=10060;received=63.77.68.246;branch=z9hG4bK390e2b583DED65B9 (96) [Sep 8 09:58:23] DEBUG[8353] chan_sip.c: Header 3: From: "Joe Allison" ;tag=14A91D2B-FD3CA6A2 (73) [Sep 8 09:58:23] DEBUG[8353] chan_sip.c: Header 4: To: ;tag=as25a083c1 (57) [Sep 8 09:58:23] DEBUG[8353] chan_sip.c: Header 5: Call-ID: 560cafff-f705a86d-fa2d077c@192.168.15.100 (50) [Sep 8 09:58:23] DEBUG[8353] chan_sip.c: Header 6: CSeq: 2 INVITE (14) [Sep 8 09:58:23] DEBUG[8353] chan_sip.c: Header 7: User-Agent: NuvioSoftSwitch (27) [Sep 8 09:58:23] DEBUG[8353] chan_sip.c: Header 8: Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY (66) [Sep 8 09:58:23] DEBUG[8353] chan_sip.c: Header 9: Supported: replaces (19) [Sep 8 09:58:23] DEBUG[8353] chan_sip.c: Header 10: Contact: (32) [Sep 8 09:58:23] DEBUG[8353] chan_sip.c: Header 11: Content-Length: 0 (17) [Sep 8 09:58:23] DEBUG[8353] chan_sip.c: Header 12: (0) [Sep 8 09:58:23] DEBUG[8353] chan_sip.c: *** SIP TIMER: Initalizing retransmit timer on packet: Id #20 [Sep 8 09:58:23] VERBOSE[8353] logger.c: Transmitting (no NAT) to 63.77.68.165:5060: SIP/2.0 200 OK Via: SIP/2.0/UDP 63.77.68.165;branch=z9hG4bKa9d2.d6dc51b7.0;received=63.77.68.165 Via: SIP/2.0/UDP 192.168.15.100;rport=10060;received=63.77.68.246;branch=z9hG4bK390e2b583DED65B9 Record-Route: From: "Joe Allison" ;tag=14A91D2B-FD3CA6A2 To: ;tag=as25a083c1 Call-ID: 560cafff-f705a86d-fa2d077c@192.168.15.100 CSeq: 2 CANCEL User-Agent: NuvioSoftSwitch Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Supported: replaces Contact: Content-Length: 0 --- [Sep 8 09:58:23] DEBUG[8353] chan_sip.c: Header 0: SIP/2.0 200 OK (14) [Sep 8 09:58:23] DEBUG[8353] chan_sip.c: Header 1: Via: SIP/2.0/UDP 63.77.68.165;branch=z9hG4bKa9d2.d6dc51b7.0;received=63.77.68.165 (81) [Sep 8 09:58:23] DEBUG[8353] chan_sip.c: Header 2: Via: SIP/2.0/UDP 192.168.15.100;rport=10060;received=63.77.68.246;branch=z9hG4bK390e2b583DED65B9 (96) [Sep 8 09:58:23] DEBUG[8353] chan_sip.c: Header 3: Record-Route: (58) [Sep 8 09:58:23] DEBUG[8353] chan_sip.c: Header 4: From: "Joe Allison" ;tag=14A91D2B-FD3CA6A2 (73) [Sep 8 09:58:23] DEBUG[8353] chan_sip.c: Header 5: To: ;tag=as25a083c1 (57) [Sep 8 09:58:23] DEBUG[8353] chan_sip.c: Header 6: Call-ID: 560cafff-f705a86d-fa2d077c@192.168.15.100 (50) [Sep 8 09:58:23] DEBUG[8353] chan_sip.c: Header 7: CSeq: 2 CANCEL (14) [Sep 8 09:58:23] DEBUG[8353] chan_sip.c: Header 8: User-Agent: NuvioSoftSwitch (27) [Sep 8 09:58:23] DEBUG[8353] chan_sip.c: Header 9: Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY (66) [Sep 8 09:58:23] DEBUG[8353] chan_sip.c: Header 10: Supported: replaces (19) [Sep 8 09:58:23] DEBUG[8353] chan_sip.c: Header 11: Contact: (32) [Sep 8 09:58:23] DEBUG[8353] chan_sip.c: Header 12: Content-Length: 0 (17) [Sep 8 09:58:23] DEBUG[8353] chan_sip.c: Header 13: (0) [Sep 8 09:58:23] VERBOSE[8353] logger.c: <-- SIP read from 63.77.68.165:5060: ACK sip:1001@sdtest.nuvio.com;user=phone SIP/2.0 Via: SIP/2.0/UDP 63.77.68.165;branch=z9hG4bKa9d2.d6dc51b7.0 From: "Joe Allison" ;tag=14A91D2B-FD3CA6A2 Call-ID: 560cafff-f705a86d-fa2d077c@192.168.15.100 To: ;tag=as25a083c1 CSeq: 2 ACK Content-Length: 0 [Sep 8 09:58:23] DEBUG[8353] chan_sip.c: Header 0: ACK sip:1001@sdtest.nuvio.com;user=phone SIP/2.0 (48) [Sep 8 09:58:23] DEBUG[8353] chan_sip.c: Header 1: Via: SIP/2.0/UDP 63.77.68.165;branch=z9hG4bKa9d2.d6dc51b7.0 (59) [Sep 8 09:58:23] DEBUG[8353] chan_sip.c: Header 2: From: "Joe Allison" ;tag=14A91D2B-FD3CA6A2 (73) [Sep 8 09:58:23] DEBUG[8353] chan_sip.c: Header 3: Call-ID: 560cafff-f705a86d-fa2d077c@192.168.15.100 (50) [Sep 8 09:58:23] DEBUG[8353] chan_sip.c: Header 4: To: ;tag=as25a083c1 (57) [Sep 8 09:58:23] DEBUG[8353] chan_sip.c: Header 5: CSeq: 2 ACK (11) [Sep 8 09:58:23] DEBUG[8353] chan_sip.c: Header 6: Content-Length: 0 (17) [Sep 8 09:58:23] DEBUG[8353] chan_sip.c: Header 7: (0) [Sep 8 09:58:23] VERBOSE[8353] logger.c: --- (7 headers 0 lines)[Sep 8 09:58:23] VERBOSE[8353] logger.c: --- [Sep 8 09:58:23] DEBUG[8353] chan_sip.c: **** Received ACK (6) - Command in SIP ACK [Sep 8 09:58:23] DEBUG[8353] chan_sip.c: ** SIP TIMER: Cancelling retransmit of packet (reply received) Retransid #20 [Sep 8 09:58:23] DEBUG[8353] chan_sip.c: Stopping retransmission on '560cafff-f705a86d-fa2d077c@192.168.15.100' of Response 2: Match Not Found [Sep 8 09:58:23] DEBUG[8357] channel.c: Set channel SIP/714nv101-08d43518 to write format ulaw [Sep 8 09:58:23] DEBUG[8357] rtp.c: Channel '' has no RTP, not doing anything [Sep 8 09:58:23] DEBUG[8357] channel.c: Hanging up channel 'SIP/714nv100-08d48778' [Sep 8 09:58:23] DEBUG[8357] chan_sip.c: Hangup call SIP/714nv100-08d48778, SIP callid 7c83f98a2c1af72b2c68ef5b671452ec@63.77.68.164) [Sep 8 09:58:23] DEBUG[8357] chan_sip.c: update_call_counter(714nv100) - decrement call limit counter on hangup [Sep 8 09:58:23] DEBUG[8357] chan_sip.c: Updating call counter for outgoing call [Sep 8 09:58:23] DEBUG[8357] chan_sip.c: Hanging up channel in state Ringing (not UP) [Sep 8 09:58:23] VERBOSE[8357] logger.c: Scheduling destruction of SIP dialog '7c83f98a2c1af72b2c68ef5b671452ec@63.77.68.164' in 32000 ms (Method: INVITE) [Sep 8 09:58:23] DEBUG[8357] chan_sip.c: Acked pending invite 102 [Sep 8 09:58:23] DEBUG[8357] chan_sip.c: Stopping retransmission on '7c83f98a2c1af72b2c68ef5b671452ec@63.77.68.164' of Request 102: Match Not Found [Sep 8 09:58:23] DEBUG[8357] devicestate.c: Notification of state change to be queued on device/channel SIP/714nv100-08d48778 [Sep 8 09:58:23] DEBUG[8357] app_dial.c: Exiting with DIALSTATUS=CANCEL. [Sep 8 09:58:23] DEBUG[8357] pbx.c: Spawn extension (default,1001,1) exited non-zero on 'SIP/714nv101-08d43518' [Sep 8 09:58:23] DEBUG[8357] channel.c: Hanging up channel 'SIP/714nv101-08d43518' [Sep 8 09:58:23] DEBUG[8357] chan_sip.c: Hangup call SIP/714nv101-08d43518, SIP callid 560cafff-f705a86d-fa2d077c@192.168.15.100) [Sep 8 09:58:23] DEBUG[8357] chan_sip.c: update_call_counter(714nv101) - decrement call limit counter on hangup [Sep 8 09:58:23] DEBUG[8357] chan_sip.c: Updating call counter for incoming call [Sep 8 09:58:23] DEBUG[8357] chan_sip.c: Hanging up channel in state Ring (not UP) [Sep 8 09:58:23] DEBUG[8357] devicestate.c: Notification of state change to be queued on device/channel SIP/714nv101-08d43518 [Sep 8 09:58:23] DEBUG[8350] devicestate.c: No provider found, checking channel drivers for SIP - 714nv100 [Sep 8 09:58:23] DEBUG[8350] chan_sip.c: Checking device state for peer 714nv100 [Sep 8 09:58:23] DEBUG[8350] devicestate.c: Changing state for SIP/714nv100 - state 1 (Not in use) [Sep 8 09:58:23] DEBUG[8350] devicestate.c: No provider found, checking channel drivers for SIP - 714nv100 [Sep 8 09:58:23] DEBUG[8350] chan_sip.c: Checking device state for peer 714nv100 [Sep 8 09:58:23] DEBUG[8350] devicestate.c: No provider found, checking channel drivers for SIP - 714nv101 [Sep 8 09:58:23] DEBUG[8350] chan_sip.c: Checking device state for peer 714nv101 [Sep 8 09:58:23] DEBUG[8350] devicestate.c: Changing state for SIP/714nv101 - state 1 (Not in use) [Sep 8 09:58:23] DEBUG[8350] devicestate.c: No provider found, checking channel drivers for SIP - 714nv101 [Sep 8 09:58:23] DEBUG[8350] chan_sip.c: Checking device state for peer 714nv101 [Sep 8 09:58:24] VERBOSE[8353] logger.c: Really destroying SIP dialog '560cafff-f705a86d-fa2d077c@192.168.15.100' Method: ACK [Sep 8 09:58:24] DEBUG[8353] chan_sip.c: ---------- SIP HISTORY for '560cafff-f705a86d-fa2d077c@192.168.15.100' [Sep 8 09:58:24] DEBUG[8353] chan_sip.c: * SIP Call [Sep 8 09:58:24] DEBUG[8353] chan_sip.c: 001. Rx INVITE / 2 INVITE / sip:1001@sdtest.nuvio.com;user=phone [Sep 8 09:58:24] DEBUG[8353] chan_sip.c: 002. Invite New call: 560cafff-f705a86d-fa2d077c@192.168.15.100 [Sep 8 09:58:24] DEBUG[8353] chan_sip.c: 003. NewChan Channel SIP/714nv101-08d43518 - from 560cafff-f705a86d-fa2d077c [Sep 8 09:58:24] DEBUG[8353] chan_sip.c: 004. TxResp SIP/2.0 / 2 INVITE - 100 Trying [Sep 8 09:58:24] DEBUG[8353] chan_sip.c: 005. TxResp SIP/2.0 / 2 INVITE - 180 Ringing [Sep 8 09:58:24] DEBUG[8353] chan_sip.c: 006. TxResp SIP/2.0 / 2 INVITE - 183 Session Progress [Sep 8 09:58:24] DEBUG[8353] chan_sip.c: 007. Rx CANCEL / 2 CANCEL / sip:1001@sdtest.nuvio.com;user=phone [Sep 8 09:58:24] DEBUG[8353] chan_sip.c: 008. TxRespRel SIP/2.0 / 2 INVITE - 487 Request Terminated [Sep 8 09:58:24] DEBUG[8353] chan_sip.c: 009. TxResp SIP/2.0 / 2 CANCEL - 200 OK [Sep 8 09:58:24] DEBUG[8353] chan_sip.c: 010. Rx ACK / 2 ACK / sip:1001@sdtest.nuvio.com;user=phone [Sep 8 09:58:24] DEBUG[8353] chan_sip.c: ---------- END SIP HISTORY for '560cafff-f705a86d-fa2d077c@192.168.15.100' [Sep 8 09:58:55] DEBUG[8353] chan_sip.c: Auto destroying call '7c83f98a2c1af72b2c68ef5b671452ec@63.77.68.164' [Sep 8 09:58:55] DEBUG[8353] chan_sip.c: Destroying SIP dialog 7c83f98a2c1af72b2c68ef5b671452ec@63.77.68.164 [Sep 8 09:58:55] VERBOSE[8353] logger.c: Really destroying SIP dialog '7c83f98a2c1af72b2c68ef5b671452ec@63.77.68.164' Method: INVITE [Sep 8 09:58:55] DEBUG[8353] chan_sip.c: ---------- SIP HISTORY for '7c83f98a2c1af72b2c68ef5b671452ec@63.77.68.164' [Sep 8 09:58:55] DEBUG[8353] chan_sip.c: * SIP Call [Sep 8 09:58:55] DEBUG[8353] chan_sip.c: 001. NewChan Channel SIP/714nv100-08d48778 - from 7c83f98a2c1af72b2c68ef5b67 [Sep 8 09:58:55] DEBUG[8353] chan_sip.c: 002. TxReqRel INVITE / 102 INVITE - -UNKNOWN- [Sep 8 09:58:55] DEBUG[8353] chan_sip.c: 003. Rx SIP/2.0 / 102 INVITE / 100 trying -- your call is important to [Sep 8 09:58:55] DEBUG[8353] chan_sip.c: 004. Rx SIP/2.0 / 102 INVITE / 180 Ringing [Sep 8 09:58:55] DEBUG[8353] chan_sip.c: 005. SchedDestroy 32000 ms [Sep 8 09:58:55] DEBUG[8353] chan_sip.c: 006. AutoDestroy 7c83f98a2c1af72b2c68ef5b671452ec@63.77.68.164 [Sep 8 09:58:55] DEBUG[8353] chan_sip.c: ---------- END SIP HISTORY for '7c83f98a2c1af72b2c68ef5b671452ec@63.77.68.164' [Sep 8 09:59:14] VERBOSE[8353] logger.c: <-- SIP read from 63.77.68.165:5060: SIP/2.0 603 Decline Via: SIP/2.0/UDP 63.77.68.164:5060;branch=z9hG4bK0c052172;rport=5060 From: "Joe Allison" ;tag=as1a0c4a58 To: ;tag=3BDC5C01-E0184EE CSeq: 102 INVITE Call-ID: 7c83f98a2c1af72b2c68ef5b671452ec@63.77.68.164 Contact: Record-Route: User-Agent: PolycomSoundPointIP-SPIP_500-UA/1.6.7.0098 Content-Length: 0 [Sep 8 09:59:14] DEBUG[8353] chan_sip.c: Header 0: SIP/2.0 603 Decline (19) [Sep 8 09:59:14] DEBUG[8353] chan_sip.c: Header 1: Via: SIP/2.0/UDP 63.77.68.164:5060;branch=z9hG4bK0c052172;rport=5060 (68) [Sep 8 09:59:14] DEBUG[8353] chan_sip.c: Header 2: From: "Joe Allison" ;tag=as1a0c4a58 (62) [Sep 8 09:59:14] DEBUG[8353] chan_sip.c: Header 3: To: ;tag=3BDC5C01-E0184EE (52) [Sep 8 09:59:14] DEBUG[8353] chan_sip.c: Header 4: CSeq: 102 INVITE (16) [Sep 8 09:59:14] DEBUG[8353] chan_sip.c: Header 5: Call-ID: 7c83f98a2c1af72b2c68ef5b671452ec@63.77.68.164 (54) [Sep 8 09:59:14] DEBUG[8353] chan_sip.c: Header 6: Contact: (35) [Sep 8 09:59:14] DEBUG[8353] chan_sip.c: Header 7: Record-Route: (51) [Sep 8 09:59:14] DEBUG[8353] chan_sip.c: Header 8: User-Agent: PolycomSoundPointIP-SPIP_500-UA/1.6.7.0098 (54) [Sep 8 09:59:14] DEBUG[8353] chan_sip.c: Header 9: Content-Length: 0 (17) [Sep 8 09:59:14] DEBUG[8353] chan_sip.c: Header 10: (0) [Sep 8 09:59:14] VERBOSE[8353] logger.c: --- (10 headers 0 lines)[Sep 8 09:59:14] VERBOSE[8353] logger.c: --- [Sep 8 09:59:14] DEBUG[8353] chan_sip.c: Allocating new SIP dialog for 7c83f98a2c1af72b2c68ef5b671452ec@63.77.68.164 - SIP/2.0 (No RTP) [Sep 8 09:59:14] DEBUG[8353] chan_sip.c: That's odd... Got a response on a call we dont know about. Cseq 102 Cmd SIP/2.0 [Sep 8 09:59:14] VERBOSE[8353] logger.c: Really destroying SIP dialog '7c83f98a2c1af72b2c68ef5b671452ec@63.77.68.164' Method: SIP/2.0 [Sep 8 09:59:14] DEBUG[8353] chan_sip.c: ---------- SIP HISTORY for '7c83f98a2c1af72b2c68ef5b671452ec@63.77.68.164' [Sep 8 09:59:14] DEBUG[8353] chan_sip.c: * SIP Call [Sep 8 09:59:14] DEBUG[8353] chan_sip.c: 001. Rx SIP/2.0 / 102 INVITE / 603 Decline [Sep 8 09:59:14] DEBUG[8353] chan_sip.c: ---------- END SIP HISTORY for '7c83f98a2c1af72b2c68ef5b671452ec@63.77.68.164' [Sep 8 09:59:14] VERBOSE[8353] logger.c: <-- SIP read from 63.77.68.165:5060: SIP/2.0 603 Decline Via: SIP/2.0/UDP 63.77.68.164:5060;branch=z9hG4bK0c052172;rport=5060 From: "Joe Allison" ;tag=as1a0c4a58 To: ;tag=3BDC5C01-E0184EE CSeq: 102 INVITE Call-ID: 7c83f98a2c1af72b2c68ef5b671452ec@63.77.68.164 Contact: Record-Route: User-Agent: PolycomSoundPointIP-SPIP_500-UA/1.6.7.0098 Content-Length: 0 [Sep 8 09:59:14] DEBUG[8353] chan_sip.c: Header 0: SIP/2.0 603 Decline (19) [Sep 8 09:59:14] DEBUG[8353] chan_sip.c: Header 1: Via: SIP/2.0/UDP 63.77.68.164:5060;branch=z9hG4bK0c052172;rport=5060 (68) [Sep 8 09:59:14] DEBUG[8353] chan_sip.c: Header 2: From: "Joe Allison" ;tag=as1a0c4a58 (62) [Sep 8 09:59:14] DEBUG[8353] chan_sip.c: Header 3: To: ;tag=3BDC5C01-E0184EE (52) [Sep 8 09:59:14] DEBUG[8353] chan_sip.c: Header 4: CSeq: 102 INVITE (16) [Sep 8 09:59:14] DEBUG[8353] chan_sip.c: Header 5: Call-ID: 7c83f98a2c1af72b2c68ef5b671452ec@63.77.68.164 (54) [Sep 8 09:59:14] DEBUG[8353] chan_sip.c: Header 6: Contact: (35) [Sep 8 09:59:14] DEBUG[8353] chan_sip.c: Header 7: Record-Route: (51) [Sep 8 09:59:14] DEBUG[8353] chan_sip.c: Header 8: User-Agent: PolycomSoundPointIP-SPIP_500-UA/1.6.7.0098 (54) [Sep 8 09:59:14] DEBUG[8353] chan_sip.c: Header 9: Content-Length: 0 (17) [Sep 8 09:59:14] DEBUG[8353] chan_sip.c: Header 10: (0) [Sep 8 09:59:14] VERBOSE[8353] logger.c: --- (10 headers 0 lines)[Sep 8 09:59:14] VERBOSE[8353] logger.c: --- [Sep 8 09:59:14] DEBUG[8353] chan_sip.c: Allocating new SIP dialog for 7c83f98a2c1af72b2c68ef5b671452ec@63.77.68.164 - SIP/2.0 (No RTP) [Sep 8 09:59:14] DEBUG[8353] chan_sip.c: That's odd... Got a response on a call we dont know about. Cseq 102 Cmd SIP/2.0 [Sep 8 09:59:14] VERBOSE[8353] logger.c: Really destroying SIP dialog '7c83f98a2c1af72b2c68ef5b671452ec@63.77.68.164' Method: SIP/2.0 [Sep 8 09:59:14] DEBUG[8353] chan_sip.c: ---------- SIP HISTORY for '7c83f98a2c1af72b2c68ef5b671452ec@63.77.68.164' [Sep 8 09:59:14] DEBUG[8353] chan_sip.c: * SIP Call [Sep 8 09:59:14] DEBUG[8353] chan_sip.c: 001. Rx SIP/2.0 / 102 INVITE / 603 Decline [Sep 8 09:59:14] DEBUG[8353] chan_sip.c: ---------- END SIP HISTORY for '7c83f98a2c1af72b2c68ef5b671452ec@63.77.68.164' [Sep 8 09:59:16] VERBOSE[8353] logger.c: <-- SIP read from 63.77.68.165:5060: SIP/2.0 603 Decline Via: SIP/2.0/UDP 63.77.68.164:5060;branch=z9hG4bK0c052172;rport=5060 From: "Joe Allison" ;tag=as1a0c4a58 To: ;tag=3BDC5C01-E0184EE CSeq: 102 INVITE Call-ID: 7c83f98a2c1af72b2c68ef5b671452ec@63.77.68.164 Contact: Record-Route: User-Agent: PolycomSoundPointIP-SPIP_500-UA/1.6.7.0098 Content-Length: 0 [Sep 8 09:59:16] DEBUG[8353] chan_sip.c: Header 0: SIP/2.0 603 Decline (19) [Sep 8 09:59:16] DEBUG[8353] chan_sip.c: Header 1: Via: SIP/2.0/UDP 63.77.68.164:5060;branch=z9hG4bK0c052172;rport=5060 (68) [Sep 8 09:59:16] DEBUG[8353] chan_sip.c: Header 2: From: "Joe Allison" ;tag=as1a0c4a58 (62) [Sep 8 09:59:16] DEBUG[8353] chan_sip.c: Header 3: To: ;tag=3BDC5C01-E0184EE (52) [Sep 8 09:59:16] DEBUG[8353] chan_sip.c: Header 4: CSeq: 102 INVITE (16) [Sep 8 09:59:16] DEBUG[8353] chan_sip.c: Header 5: Call-ID: 7c83f98a2c1af72b2c68ef5b671452ec@63.77.68.164 (54) [Sep 8 09:59:16] DEBUG[8353] chan_sip.c: Header 6: Contact: (35) [Sep 8 09:59:16] DEBUG[8353] chan_sip.c: Header 7: Record-Route: (51) [Sep 8 09:59:16] DEBUG[8353] chan_sip.c: Header 8: User-Agent: PolycomSoundPointIP-SPIP_500-UA/1.6.7.0098 (54) [Sep 8 09:59:16] DEBUG[8353] chan_sip.c: Header 9: Content-Length: 0 (17) [Sep 8 09:59:16] DEBUG[8353] chan_sip.c: Header 10: (0) [Sep 8 09:59:16] VERBOSE[8353] logger.c: --- (10 headers 0 lines)[Sep 8 09:59:16] VERBOSE[8353] logger.c: --- [Sep 8 09:59:16] DEBUG[8353] chan_sip.c: Allocating new SIP dialog for 7c83f98a2c1af72b2c68ef5b671452ec@63.77.68.164 - SIP/2.0 (No RTP) [Sep 8 09:59:16] DEBUG[8353] chan_sip.c: That's odd... Got a response on a call we dont know about. Cseq 102 Cmd SIP/2.0 [Sep 8 09:59:16] VERBOSE[8353] logger.c: Really destroying SIP dialog '7c83f98a2c1af72b2c68ef5b671452ec@63.77.68.164' Method: SIP/2.0 [Sep 8 09:59:16] DEBUG[8353] chan_sip.c: ---------- SIP HISTORY for '7c83f98a2c1af72b2c68ef5b671452ec@63.77.68.164' [Sep 8 09:59:16] DEBUG[8353] chan_sip.c: * SIP Call [Sep 8 09:59:16] DEBUG[8353] chan_sip.c: 001. Rx SIP/2.0 / 102 INVITE / 603 Decline [Sep 8 09:59:16] DEBUG[8353] chan_sip.c: ---------- END SIP HISTORY for '7c83f98a2c1af72b2c68ef5b671452ec@63.77.68.164' [Sep 8 09:59:20] VERBOSE[8353] logger.c: <-- SIP read from 63.77.68.165:5060: SIP/2.0 603 Decline Via: SIP/2.0/UDP 63.77.68.164:5060;branch=z9hG4bK0c052172;rport=5060 From: "Joe Allison" ;tag=as1a0c4a58 To: ;tag=3BDC5C01-E0184EE CSeq: 102 INVITE Call-ID: 7c83f98a2c1af72b2c68ef5b671452ec@63.77.68.164 Contact: Record-Route: User-Agent: PolycomSoundPointIP-SPIP_500-UA/1.6.7.0098 Content-Length: 0 [Sep 8 09:59:20] DEBUG[8353] chan_sip.c: Header 0: SIP/2.0 603 Decline (19) [Sep 8 09:59:20] DEBUG[8353] chan_sip.c: Header 1: Via: SIP/2.0/UDP 63.77.68.164:5060;branch=z9hG4bK0c052172;rport=5060 (68) [Sep 8 09:59:20] DEBUG[8353] chan_sip.c: Header 2: From: "Joe Allison" ;tag=as1a0c4a58 (62) [Sep 8 09:59:20] DEBUG[8353] chan_sip.c: Header 3: To: ;tag=3BDC5C01-E0184EE (52) [Sep 8 09:59:20] DEBUG[8353] chan_sip.c: Header 4: CSeq: 102 INVITE (16) [Sep 8 09:59:20] DEBUG[8353] chan_sip.c: Header 5: Call-ID: 7c83f98a2c1af72b2c68ef5b671452ec@63.77.68.164 (54) [Sep 8 09:59:20] DEBUG[8353] chan_sip.c: Header 6: Contact: (35) [Sep 8 09:59:20] DEBUG[8353] chan_sip.c: Header 7: Record-Route: (51) [Sep 8 09:59:20] DEBUG[8353] chan_sip.c: Header 8: User-Agent: PolycomSoundPointIP-SPIP_500-UA/1.6.7.0098 (54) [Sep 8 09:59:20] DEBUG[8353] chan_sip.c: Header 9: Content-Length: 0 (17) [Sep 8 09:59:20] DEBUG[8353] chan_sip.c: Header 10: (0) [Sep 8 09:59:20] VERBOSE[8353] logger.c: --- (10 headers 0 lines)[Sep 8 09:59:20] VERBOSE[8353] logger.c: --- [Sep 8 09:59:20] DEBUG[8353] chan_sip.c: Allocating new SIP dialog for 7c83f98a2c1af72b2c68ef5b671452ec@63.77.68.164 - SIP/2.0 (No RTP) [Sep 8 09:59:20] DEBUG[8353] chan_sip.c: That's odd... Got a response on a call we dont know about. Cseq 102 Cmd SIP/2.0 [Sep 8 09:59:20] VERBOSE[8353] logger.c: Really destroying SIP dialog '7c83f98a2c1af72b2c68ef5b671452ec@63.77.68.164' Method: SIP/2.0 [Sep 8 09:59:20] DEBUG[8353] chan_sip.c: ---------- SIP HISTORY for '7c83f98a2c1af72b2c68ef5b671452ec@63.77.68.164' [Sep 8 09:59:20] DEBUG[8353] chan_sip.c: * SIP Call [Sep 8 09:59:20] DEBUG[8353] chan_sip.c: 001. Rx SIP/2.0 / 102 INVITE / 603 Decline [Sep 8 09:59:20] DEBUG[8353] chan_sip.c: ---------- END SIP HISTORY for '7c83f98a2c1af72b2c68ef5b671452ec@63.77.68.164' [Sep 8 09:59:24] VERBOSE[8353] logger.c: <-- SIP read from 63.77.68.165:5060: SIP/2.0 603 Decline Via: SIP/2.0/UDP 63.77.68.164:5060;branch=z9hG4bK0c052172;rport=5060 From: "Joe Allison" ;tag=as1a0c4a58 To: ;tag=3BDC5C01-E0184EE CSeq: 102 INVITE Call-ID: 7c83f98a2c1af72b2c68ef5b671452ec@63.77.68.164 Contact: Record-Route: User-Agent: PolycomSoundPointIP-SPIP_500-UA/1.6.7.0098 Content-Length: 0 [Sep 8 09:59:24] DEBUG[8353] chan_sip.c: Header 0: SIP/2.0 603 Decline (19) [Sep 8 09:59:24] DEBUG[8353] chan_sip.c: Header 1: Via: SIP/2.0/UDP 63.77.68.164:5060;branch=z9hG4bK0c052172;rport=5060 (68) [Sep 8 09:59:24] DEBUG[8353] chan_sip.c: Header 2: From: "Joe Allison" ;tag=as1a0c4a58 (62) [Sep 8 09:59:24] DEBUG[8353] chan_sip.c: Header 3: To: ;tag=3BDC5C01-E0184EE (52) [Sep 8 09:59:24] DEBUG[8353] chan_sip.c: Header 4: CSeq: 102 INVITE (16) [Sep 8 09:59:24] DEBUG[8353] chan_sip.c: Header 5: Call-ID: 7c83f98a2c1af72b2c68ef5b671452ec@63.77.68.164 (54) [Sep 8 09:59:24] DEBUG[8353] chan_sip.c: Header 6: Contact: (35) [Sep 8 09:59:24] DEBUG[8353] chan_sip.c: Header 7: Record-Route: (51) [Sep 8 09:59:24] DEBUG[8353] chan_sip.c: Header 8: User-Agent: PolycomSoundPointIP-SPIP_500-UA/1.6.7.0098 (54) [Sep 8 09:59:24] DEBUG[8353] chan_sip.c: Header 9: Content-Length: 0 (17) [Sep 8 09:59:24] DEBUG[8353] chan_sip.c: Header 10: (0) [Sep 8 09:59:24] VERBOSE[8353] logger.c: --- (10 headers 0 lines)[Sep 8 09:59:24] VERBOSE[8353] logger.c: --- [Sep 8 09:59:24] DEBUG[8353] chan_sip.c: Allocating new SIP dialog for 7c83f98a2c1af72b2c68ef5b671452ec@63.77.68.164 - SIP/2.0 (No RTP) [Sep 8 09:59:24] DEBUG[8353] chan_sip.c: That's odd... Got a response on a call we dont know about. Cseq 102 Cmd SIP/2.0 [Sep 8 09:59:24] VERBOSE[8353] logger.c: Really destroying SIP dialog '7c83f98a2c1af72b2c68ef5b671452ec@63.77.68.164' Method: SIP/2.0 [Sep 8 09:59:24] DEBUG[8353] chan_sip.c: ---------- SIP HISTORY for '7c83f98a2c1af72b2c68ef5b671452ec@63.77.68.164' [Sep 8 09:59:24] DEBUG[8353] chan_sip.c: * SIP Call [Sep 8 09:59:24] DEBUG[8353] chan_sip.c: 001. Rx SIP/2.0 / 102 INVITE / 603 Decline [Sep 8 09:59:24] DEBUG[8353] chan_sip.c: ---------- END SIP HISTORY for '7c83f98a2c1af72b2c68ef5b671452ec@63.77.68.164' [Sep 8 09:59:24] VERBOSE[8355] logger.c: -- Remote UNIX connection disconnected [Sep 8 09:59:28] VERBOSE[8353] logger.c: <-- SIP read from 63.77.68.165:5060: SIP/2.0 603 Decline Via: SIP/2.0/UDP 63.77.68.164:5060;branch=z9hG4bK0c052172;rport=5060 From: "Joe Allison" ;tag=as1a0c4a58 To: ;tag=3BDC5C01-E0184EE CSeq: 102 INVITE Call-ID: 7c83f98a2c1af72b2c68ef5b671452ec@63.77.68.164 Contact: Record-Route: User-Agent: PolycomSoundPointIP-SPIP_500-UA/1.6.7.0098 Content-Length: 0 [Sep 8 09:59:28] DEBUG[8353] chan_sip.c: Header 0: SIP/2.0 603 Decline (19) [Sep 8 09:59:28] DEBUG[8353] chan_sip.c: Header 1: Via: SIP/2.0/UDP 63.77.68.164:5060;branch=z9hG4bK0c052172;rport=5060 (68) [Sep 8 09:59:28] DEBUG[8353] chan_sip.c: Header 2: From: "Joe Allison" ;tag=as1a0c4a58 (62) [Sep 8 09:59:28] DEBUG[8353] chan_sip.c: Header 3: To: ;tag=3BDC5C01-E0184EE (52) [Sep 8 09:59:28] DEBUG[8353] chan_sip.c: Header 4: CSeq: 102 INVITE (16) [Sep 8 09:59:28] DEBUG[8353] chan_sip.c: Header 5: Call-ID: 7c83f98a2c1af72b2c68ef5b671452ec@63.77.68.164 (54) [Sep 8 09:59:28] DEBUG[8353] chan_sip.c: Header 6: Contact: (35) [Sep 8 09:59:28] DEBUG[8353] chan_sip.c: Header 7: Record-Route: (51) [Sep 8 09:59:28] DEBUG[8353] chan_sip.c: Header 8: User-Agent: PolycomSoundPointIP-SPIP_500-UA/1.6.7.0098 (54) [Sep 8 09:59:28] DEBUG[8353] chan_sip.c: Header 9: Content-Length: 0 (17) [Sep 8 09:59:28] DEBUG[8353] chan_sip.c: Header 10: (0) [Sep 8 09:59:28] VERBOSE[8353] logger.c: --- (10 headers 0 lines)[Sep 8 09:59:28] VERBOSE[8353] logger.c: --- [Sep 8 09:59:28] DEBUG[8353] chan_sip.c: Allocating new SIP dialog for 7c83f98a2c1af72b2c68ef5b671452ec@63.77.68.164 - SIP/2.0 (No RTP) [Sep 8 09:59:28] DEBUG[8353] chan_sip.c: That's odd... Got a response on a call we dont know about. Cseq 102 Cmd SIP/2.0 [Sep 8 09:59:28] VERBOSE[8353] logger.c: Really destroying SIP dialog '7c83f98a2c1af72b2c68ef5b671452ec@63.77.68.164' Method: SIP/2.0 [Sep 8 09:59:28] DEBUG[8353] chan_sip.c: ---------- SIP HISTORY for '7c83f98a2c1af72b2c68ef5b671452ec@63.77.68.164' [Sep 8 09:59:28] DEBUG[8353] chan_sip.c: * SIP Call [Sep 8 09:59:28] DEBUG[8353] chan_sip.c: 001. Rx SIP/2.0 / 102 INVITE / 603 Decline [Sep 8 09:59:28] DEBUG[8353] chan_sip.c: ---------- END SIP HISTORY for '7c83f98a2c1af72b2c68ef5b671452ec@63.77.68.164' [Sep 8 09:59:32] VERBOSE[8353] logger.c: <-- SIP read from 63.77.68.165:5060: SIP/2.0 603 Decline Via: SIP/2.0/UDP 63.77.68.164:5060;branch=z9hG4bK0c052172;rport=5060 From: "Joe Allison" ;tag=as1a0c4a58 To: ;tag=3BDC5C01-E0184EE CSeq: 102 INVITE Call-ID: 7c83f98a2c1af72b2c68ef5b671452ec@63.77.68.164 Contact: Record-Route: User-Agent: PolycomSoundPointIP-SPIP_500-UA/1.6.7.0098 Content-Length: 0 [Sep 8 09:59:32] DEBUG[8353] chan_sip.c: Header 0: SIP/2.0 603 Decline (19) [Sep 8 09:59:32] DEBUG[8353] chan_sip.c: Header 1: Via: SIP/2.0/UDP 63.77.68.164:5060;branch=z9hG4bK0c052172;rport=5060 (68) [Sep 8 09:59:32] DEBUG[8353] chan_sip.c: Header 2: From: "Joe Allison" ;tag=as1a0c4a58 (62) [Sep 8 09:59:32] DEBUG[8353] chan_sip.c: Header 3: To: ;tag=3BDC5C01-E0184EE (52) [Sep 8 09:59:32] DEBUG[8353] chan_sip.c: Header 4: CSeq: 102 INVITE (16) [Sep 8 09:59:32] DEBUG[8353] chan_sip.c: Header 5: Call-ID: 7c83f98a2c1af72b2c68ef5b671452ec@63.77.68.164 (54) [Sep 8 09:59:32] DEBUG[8353] chan_sip.c: Header 6: Contact: (35) [Sep 8 09:59:32] DEBUG[8353] chan_sip.c: Header 7: Record-Route: (51) [Sep 8 09:59:32] DEBUG[8353] chan_sip.c: Header 8: User-Agent: PolycomSoundPointIP-SPIP_500-UA/1.6.7.0098 (54) [Sep 8 09:59:32] DEBUG[8353] chan_sip.c: Header 9: Content-Length: 0 (17) [Sep 8 09:59:32] DEBUG[8353] chan_sip.c: Header 10: (0) [Sep 8 09:59:32] VERBOSE[8353] logger.c: --- (10 headers 0 lines)[Sep 8 09:59:32] VERBOSE[8353] logger.c: --- [Sep 8 09:59:32] DEBUG[8353] chan_sip.c: Allocating new SIP dialog for 7c83f98a2c1af72b2c68ef5b671452ec@63.77.68.164 - SIP/2.0 (No RTP) [Sep 8 09:59:32] DEBUG[8353] chan_sip.c: That's odd... Got a response on a call we dont know about. Cseq 102 Cmd SIP/2.0 [Sep 8 09:59:32] VERBOSE[8353] logger.c: Really destroying SIP dialog '7c83f98a2c1af72b2c68ef5b671452ec@63.77.68.164' Method: SIP/2.0 [Sep 8 09:59:32] DEBUG[8353] chan_sip.c: ---------- SIP HISTORY for '7c83f98a2c1af72b2c68ef5b671452ec@63.77.68.164' [Sep 8 09:59:32] DEBUG[8353] chan_sip.c: * SIP Call [Sep 8 09:59:32] DEBUG[8353] chan_sip.c: 001. Rx SIP/2.0 / 102 INVITE / 603 Decline [Sep 8 09:59:32] DEBUG[8353] chan_sip.c: ---------- END SIP HISTORY for '7c83f98a2c1af72b2c68ef5b671452ec@63.77.68.164' [Sep 8 09:59:36] VERBOSE[8353] logger.c: <-- SIP read from 63.77.68.165:5060: SIP/2.0 603 Decline Via: SIP/2.0/UDP 63.77.68.164:5060;branch=z9hG4bK0c052172;rport=5060 From: "Joe Allison" ;tag=as1a0c4a58 To: ;tag=3BDC5C01-E0184EE CSeq: 102 INVITE Call-ID: 7c83f98a2c1af72b2c68ef5b671452ec@63.77.68.164 Contact: Record-Route: User-Agent: PolycomSoundPointIP-SPIP_500-UA/1.6.7.0098 Content-Length: 0 [Sep 8 09:59:36] DEBUG[8353] chan_sip.c: Header 0: SIP/2.0 603 Decline (19) [Sep 8 09:59:36] DEBUG[8353] chan_sip.c: Header 1: Via: SIP/2.0/UDP 63.77.68.164:5060;branch=z9hG4bK0c052172;rport=5060 (68) [Sep 8 09:59:36] DEBUG[8353] chan_sip.c: Header 2: From: "Joe Allison" ;tag=as1a0c4a58 (62) [Sep 8 09:59:36] DEBUG[8353] chan_sip.c: Header 3: To: ;tag=3BDC5C01-E0184EE (52) [Sep 8 09:59:36] DEBUG[8353] chan_sip.c: Header 4: CSeq: 102 INVITE (16) [Sep 8 09:59:36] DEBUG[8353] chan_sip.c: Header 5: Call-ID: 7c83f98a2c1af72b2c68ef5b671452ec@63.77.68.164 (54) [Sep 8 09:59:36] DEBUG[8353] chan_sip.c: Header 6: Contact: (35) [Sep 8 09:59:36] DEBUG[8353] chan_sip.c: Header 7: Record-Route: (51) [Sep 8 09:59:36] DEBUG[8353] chan_sip.c: Header 8: User-Agent: PolycomSoundPointIP-SPIP_500-UA/1.6.7.0098 (54) [Sep 8 09:59:36] DEBUG[8353] chan_sip.c: Header 9: Content-Length: 0 (17) [Sep 8 09:59:36] DEBUG[8353] chan_sip.c: Header 10: (0) [Sep 8 09:59:36] VERBOSE[8353] logger.c: --- (10 headers 0 lines)[Sep 8 09:59:36] VERBOSE[8353] logger.c: --- [Sep 8 09:59:36] DEBUG[8353] chan_sip.c: Allocating new SIP dialog for 7c83f98a2c1af72b2c68ef5b671452ec@63.77.68.164 - SIP/2.0 (No RTP) [Sep 8 09:59:36] DEBUG[8353] chan_sip.c: That's odd... Got a response on a call we dont know about. Cseq 102 Cmd SIP/2.0 [Sep 8 09:59:36] VERBOSE[8353] logger.c: Really destroying SIP dialog '7c83f98a2c1af72b2c68ef5b671452ec@63.77.68.164' Method: SIP/2.0 [Sep 8 09:59:36] DEBUG[8353] chan_sip.c: ---------- SIP HISTORY for '7c83f98a2c1af72b2c68ef5b671452ec@63.77.68.164' [Sep 8 09:59:36] DEBUG[8353] chan_sip.c: * SIP Call [Sep 8 09:59:36] DEBUG[8353] chan_sip.c: 001. Rx SIP/2.0 / 102 INVITE / 603 Decline [Sep 8 09:59:36] DEBUG[8353] chan_sip.c: ---------- END SIP HISTORY for '7c83f98a2c1af72b2c68ef5b671452ec@63.77.68.164' [Sep 8 09:59:40] VERBOSE[8353] logger.c: <-- SIP read from 63.77.68.165:5060: SIP/2.0 603 Decline Via: SIP/2.0/UDP 63.77.68.164:5060;branch=z9hG4bK0c052172;rport=5060 From: "Joe Allison" ;tag=as1a0c4a58 To: ;tag=3BDC5C01-E0184EE CSeq: 102 INVITE Call-ID: 7c83f98a2c1af72b2c68ef5b671452ec@63.77.68.164 Contact: Record-Route: User-Agent: PolycomSoundPointIP-SPIP_500-UA/1.6.7.0098 Content-Length: 0 [Sep 8 09:59:40] DEBUG[8353] chan_sip.c: Header 0: SIP/2.0 603 Decline (19) [Sep 8 09:59:40] DEBUG[8353] chan_sip.c: Header 1: Via: SIP/2.0/UDP 63.77.68.164:5060;branch=z9hG4bK0c052172;rport=5060 (68) [Sep 8 09:59:40] DEBUG[8353] chan_sip.c: Header 2: From: "Joe Allison" ;tag=as1a0c4a58 (62) [Sep 8 09:59:40] DEBUG[8353] chan_sip.c: Header 3: To: ;tag=3BDC5C01-E0184EE (52) [Sep 8 09:59:40] DEBUG[8353] chan_sip.c: Header 4: CSeq: 102 INVITE (16) [Sep 8 09:59:40] DEBUG[8353] chan_sip.c: Header 5: Call-ID: 7c83f98a2c1af72b2c68ef5b671452ec@63.77.68.164 (54) [Sep 8 09:59:40] DEBUG[8353] chan_sip.c: Header 6: Contact: (35) [Sep 8 09:59:40] DEBUG[8353] chan_sip.c: Header 7: Record-Route: (51) [Sep 8 09:59:40] DEBUG[8353] chan_sip.c: Header 8: User-Agent: PolycomSoundPointIP-SPIP_500-UA/1.6.7.0098 (54) [Sep 8 09:59:40] DEBUG[8353] chan_sip.c: Header 9: Content-Length: 0 (17) [Sep 8 09:59:40] DEBUG[8353] chan_sip.c: Header 10: (0) [Sep 8 09:59:40] VERBOSE[8353] logger.c: --- (10 headers 0 lines)[Sep 8 09:59:40] VERBOSE[8353] logger.c: --- [Sep 8 09:59:40] DEBUG[8353] chan_sip.c: Allocating new SIP dialog for 7c83f98a2c1af72b2c68ef5b671452ec@63.77.68.164 - SIP/2.0 (No RTP) [Sep 8 09:59:40] DEBUG[8353] chan_sip.c: That's odd... Got a response on a call we dont know about. Cseq 102 Cmd SIP/2.0 [Sep 8 09:59:40] VERBOSE[8353] logger.c: Really destroying SIP dialog '7c83f98a2c1af72b2c68ef5b671452ec@63.77.68.164' Method: SIP/2.0 [Sep 8 09:59:40] DEBUG[8353] chan_sip.c: ---------- SIP HISTORY for '7c83f98a2c1af72b2c68ef5b671452ec@63.77.68.164' [Sep 8 09:59:40] DEBUG[8353] chan_sip.c: * SIP Call [Sep 8 09:59:40] DEBUG[8353] chan_sip.c: 001. Rx SIP/2.0 / 102 INVITE / 603 Decline [Sep 8 09:59:40] DEBUG[8353] chan_sip.c: ---------- END SIP HISTORY for '7c83f98a2c1af72b2c68ef5b671452ec@63.77.68.164'