[Sep 20 11:19:50] VERBOSE[16400] logger.c: Asterisk Event Logger restarted [Sep 20 11:19:50] VERBOSE[16400] logger.c: Asterisk Queue Logger restarted [Sep 20 11:20:41] VERBOSE[16389] logger.c: <-- SIP read from 63.77.68.165:5060: INVITE sip:1002@proxy-test.sip.nuvio.net;user=phone;transport=udp SIP/2.0 Record-Route: Via: SIP/2.0/UDP 63.77.68.165;branch=z9hG4bK3af3.55f33894.0 Via: SIP/2.0/UDP 63.77.68.74;branch=z9hG4bK15b0882039E5B563 From: "Sally Baker" ;tag=5D56B5AD-B325DB0A To: CSeq: 2 INVITE Call-ID: ec336c01-9a146537-d23e9134@63.77.68.74 Contact: Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, INFO, MESSAGE, SUBSCRIBE, NOTIFY, PRACK, UPDATE, REFER User-Agent: PolycomSoundPointIP-SPIP_500-UA/2.0.1.0291 Supported: 100rel,replaces Allow-Events: talk,hold,conference Max-Forwards: 16 Content-Type: application/sdp Content-Length: 247 v=0 o=- 1158769236 1158769236 IN IP4 63.77.68.74 s=Polycom IP Phone c=IN IP4 63.77.68.74 t=0 0 a=sendrecv m=audio 2232 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 [Sep 20 11:20:41] DEBUG[16389] chan_sip.c: Header 0: INVITE sip:1002@proxy-test.sip.nuvio.net;user=phone;transport=udp SIP/2.0 (73) [Sep 20 11:20:41] DEBUG[16389] chan_sip.c: Header 1: Record-Route: (58) [Sep 20 11:20:41] DEBUG[16389] chan_sip.c: Header 2: Via: SIP/2.0/UDP 63.77.68.165;branch=z9hG4bK3af3.55f33894.0 (59) [Sep 20 11:20:41] DEBUG[16389] chan_sip.c: Header 3: Via: SIP/2.0/UDP 63.77.68.74;branch=z9hG4bK15b0882039E5B563 (59) [Sep 20 11:20:41] DEBUG[16389] chan_sip.c: Header 4: From: "Sally Baker" ;tag=5D56B5AD-B325DB0A (81) [Sep 20 11:20:41] DEBUG[16389] chan_sip.c: Header 5: To: (50) [Sep 20 11:20:41] DEBUG[16389] chan_sip.c: Header 6: CSeq: 2 INVITE (14) [Sep 20 11:20:41] DEBUG[16389] chan_sip.c: Header 7: Call-ID: ec336c01-9a146537-d23e9134@63.77.68.74 (47) [Sep 20 11:20:41] DEBUG[16389] chan_sip.c: Header 8: Contact: (49) [Sep 20 11:20:41] DEBUG[16389] chan_sip.c: Header 9: Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, INFO, MESSAGE, SUBSCRIBE, NOTIFY, PRACK, UPDATE, REFER (96) [Sep 20 11:20:41] DEBUG[16389] chan_sip.c: Header 10: User-Agent: PolycomSoundPointIP-SPIP_500-UA/2.0.1.0291 (54) [Sep 20 11:20:41] DEBUG[16389] chan_sip.c: Header 11: Supported: 100rel,replaces (26) [Sep 20 11:20:41] DEBUG[16389] chan_sip.c: Header 12: Allow-Events: talk,hold,conference (34) [Sep 20 11:20:41] DEBUG[16389] chan_sip.c: Header 13: Max-Forwards: 16 (16) [Sep 20 11:20:41] DEBUG[16389] chan_sip.c: Header 14: Content-Type: application/sdp (29) [Sep 20 11:20:41] DEBUG[16389] chan_sip.c: Header 15: Content-Length: 247 (19) [Sep 20 11:20:41] DEBUG[16389] chan_sip.c: Header 16: (0) [Sep 20 11:20:41] DEBUG[16389] chan_sip.c: Line: v=0 (3) [Sep 20 11:20:41] DEBUG[16389] chan_sip.c: Line: o=- 1158769236 1158769236 IN IP4 63.77.68.74 (44) [Sep 20 11:20:41] DEBUG[16389] chan_sip.c: Line: s=Polycom IP Phone (18) [Sep 20 11:20:41] DEBUG[16389] chan_sip.c: Line: c=IN IP4 63.77.68.74 (20) [Sep 20 11:20:41] DEBUG[16389] chan_sip.c: Line: t=0 0 (5) [Sep 20 11:20:41] DEBUG[16389] chan_sip.c: Line: a=sendrecv (10) [Sep 20 11:20:41] DEBUG[16389] chan_sip.c: Line: m=audio 2232 RTP/AVP 18 0 8 101 (31) [Sep 20 11:20:41] DEBUG[16389] chan_sip.c: Line: a=rtpmap:18 G729/8000 (21) [Sep 20 11:20:41] DEBUG[16389] chan_sip.c: Line: a=rtpmap:0 PCMU/8000 (20) [Sep 20 11:20:41] DEBUG[16389] chan_sip.c: Line: a=rtpmap:8 PCMA/8000 (20) [Sep 20 11:20:41] DEBUG[16389] chan_sip.c: Line: a=rtpmap:101 telephone-event/8000 (33) [Sep 20 11:20:41] VERBOSE[16389] logger.c: --- (16 headers 11 lines)[Sep 20 11:20:41] VERBOSE[16389] logger.c: --- [Sep 20 11:20:41] DEBUG[16389] chan_sip.c: Allocating new SIP dialog for ec336c01-9a146537-d23e9134@63.77.68.74 - INVITE (With RTP) [Sep 20 11:20:41] DEBUG[16389] chan_sip.c: **** Received INVITE (5) - Command in SIP INVITE [Sep 20 11:20:41] DEBUG[16389] chan_sip.c: Begin: parsing SIP "Supported: 100rel,replaces" [Sep 20 11:20:41] DEBUG[16389] chan_sip.c: Found SIP option: -100rel- [Sep 20 11:20:41] DEBUG[16389] chan_sip.c: Matched SIP option: 100rel [Sep 20 11:20:41] DEBUG[16389] chan_sip.c: Found SIP option: -replaces- [Sep 20 11:20:41] DEBUG[16389] chan_sip.c: Matched SIP option: replaces [Sep 20 11:20:41] VERBOSE[16389] logger.c: Sending to 63.77.68.165 : 5060 (no NAT) [Sep 20 11:20:41] VERBOSE[16389] logger.c: Using INVITE request as basis request - ec336c01-9a146537-d23e9134@63.77.68.74 [Sep 20 11:20:41] DEBUG[16389] chan_sip.c: Setting NAT on RTP to Off [Sep 20 11:20:41] VERBOSE[16389] logger.c: Found user '714nv100' [Sep 20 11:20:41] VERBOSE[16389] logger.c: Found RTP audio format 18 [Sep 20 11:20:41] VERBOSE[16389] logger.c: Found RTP audio format 0 [Sep 20 11:20:41] VERBOSE[16389] logger.c: Found RTP audio format 8 [Sep 20 11:20:41] VERBOSE[16389] logger.c: Found RTP audio format 101 [Sep 20 11:20:41] VERBOSE[16389] logger.c: Peer audio RTP is at port 63.77.68.74:2232 [Sep 20 11:20:41] VERBOSE[16389] logger.c: Found description format G729 for ID 18 [Sep 20 11:20:41] VERBOSE[16389] logger.c: Found description format PCMU for ID 0 [Sep 20 11:20:41] VERBOSE[16389] logger.c: Found description format PCMA for ID 8 [Sep 20 11:20:41] VERBOSE[16389] logger.c: Found description format telephone-event for ID 101 [Sep 20 11:20:41] DEBUG[16389] chan_sip.c: T38 state changed to 0 on channel [Sep 20 11:20:41] VERBOSE[16389] logger.c: Capabilities: us - 0x4 (ulaw), peer - audio=0x10c (ulaw|alaw|g729)/video=0x0 (nothing), combined - 0x4 (ulaw) [Sep 20 11:20:41] VERBOSE[16389] logger.c: Non-codec capabilities (dtmf): us - 0x1 (telephone-event), peer - 0x1 (telephone-event), combined - 0x1 (telephone-event) [Sep 20 11:20:41] VERBOSE[16389] logger.c: Peer audio RTP is at port 63.77.68.74:2232 [Sep 20 11:20:41] DEBUG[16389] chan_sip.c: We're settling with these formats: 0x4 (ulaw) [Sep 20 11:20:41] DEBUG[16389] chan_sip.c: Checking SIP call limits for device 714nv100 [Sep 20 11:20:41] DEBUG[16389] chan_sip.c: Updating call counter for incoming call [Sep 20 11:20:41] VERBOSE[16389] logger.c: Looking for 1002 in default (domain proxy-test.sip.nuvio.net) [Sep 20 11:20:41] DEBUG[16389] chan_sip.c: *** Our native formats are 0x4 (ulaw) [Sep 20 11:20:41] DEBUG[16389] chan_sip.c: *** Joint capabilities are 0x4 (ulaw) [Sep 20 11:20:41] DEBUG[16389] chan_sip.c: *** Our capabilities are 0x4 (ulaw) [Sep 20 11:20:41] DEBUG[16389] chan_sip.c: *** AST_CODEC_CHOOSE formats are 0x4 (ulaw) [Sep 20 11:20:41] DEBUG[16389] chan_sip.c: This channel will not be able to handle video. [Sep 20 11:20:41] DEBUG[16389] chan_sip.c: build_route: Record-Route hop: [Sep 20 11:20:41] VERBOSE[16389] logger.c: list_route: hop: [Sep 20 11:20:41] DEBUG[16389] chan_sip.c: SIP/714nv100-082496a0: New call is still down.... Trying... [Sep 20 11:20:41] VERBOSE[16389] 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=z9hG4bK3af3.55f33894.0;received=63.77.68.165 Via: SIP/2.0/UDP 63.77.68.74;branch=z9hG4bK15b0882039E5B563 From: "Sally Baker" ;tag=5D56B5AD-B325DB0A To: Call-ID: ec336c01-9a146537-d23e9134@63.77.68.74 CSeq: 2 INVITE User-Agent: NuvioSoftSwitch Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Supported: replaces Contact: Content-Length: 0 --- [Sep 20 11:20:41] DEBUG[16389] chan_sip.c: Header 0: SIP/2.0 100 Trying (18) [Sep 20 11:20:41] DEBUG[16389] chan_sip.c: Header 1: Via: SIP/2.0/UDP 63.77.68.165;branch=z9hG4bK3af3.55f33894.0;received=63.77.68.165 (81) [Sep 20 11:20:41] DEBUG[16389] chan_sip.c: Header 2: Via: SIP/2.0/UDP 63.77.68.74;branch=z9hG4bK15b0882039E5B563 (59) [Sep 20 11:20:41] DEBUG[16389] chan_sip.c: Header 3: From: "Sally Baker" ;tag=5D56B5AD-B325DB0A (81) [Sep 20 11:20:41] DEBUG[16389] chan_sip.c: Header 4: To: (50) [Sep 20 11:20:41] DEBUG[16389] chan_sip.c: Header 5: Call-ID: ec336c01-9a146537-d23e9134@63.77.68.74 (47) [Sep 20 11:20:41] DEBUG[16389] chan_sip.c: Header 6: CSeq: 2 INVITE (14) [Sep 20 11:20:41] DEBUG[16389] chan_sip.c: Header 7: User-Agent: NuvioSoftSwitch (27) [Sep 20 11:20:41] DEBUG[16389] chan_sip.c: Header 8: Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY (66) [Sep 20 11:20:41] DEBUG[16389] chan_sip.c: Header 9: Supported: replaces (19) [Sep 20 11:20:41] DEBUG[16389] chan_sip.c: Header 10: Contact: (32) [Sep 20 11:20:41] DEBUG[16389] chan_sip.c: Header 11: Content-Length: 0 (17) [Sep 20 11:20:41] DEBUG[16389] chan_sip.c: Header 12: (0) [Sep 20 11:20:41] DEBUG[16389] devicestate.c: Notification of state change to be queued on device/channel SIP/714nv100-082496a0 [Sep 20 11:20:41] DEBUG[16368] devicestate.c: No provider found, checking channel drivers for SIP - 714nv100 [Sep 20 11:20:41] DEBUG[16368] chan_sip.c: Checking device state for peer 714nv100 [Sep 20 11:20:41] DEBUG[16368] devicestate.c: Changing state for SIP/714nv100 - state 1 (Not in use) [Sep 20 11:20:41] DEBUG[16368] devicestate.c: No provider found, checking channel drivers for SIP - 714nv100 [Sep 20 11:20:41] DEBUG[16368] chan_sip.c: Checking device state for peer 714nv100 [Sep 20 11:20:41] DEBUG[16402] app_queue.c: Device 'SIP/714nv100' changed to state '1' (Not in use) but we don't care because they're not a member of any queue. [Sep 20 11:20:41] DEBUG[16401] pbx.c: Launching 'Dial' [Sep 20 11:20:41] VERBOSE[16401] logger.c: -- Executing [1002@default:1] Dial("SIP/714nv100-082496a0", "SIP/751nv105") in new stack [Sep 20 11:20:41] DEBUG[16401] chan_sip.c: Asked to create a SIP channel with formats: 0x4 (ulaw) [Sep 20 11:20:41] DEBUG[16401] chan_sip.c: Allocating new SIP dialog for (No Call-ID) - INVITE (With RTP) [Sep 20 11:20:41] DEBUG[16401] chan_sip.c: Setting NAT on RTP to Off [Sep 20 11:20:41] DEBUG[16401] chan_sip.c: *** Our native formats are 0x4 (ulaw) [Sep 20 11:20:41] DEBUG[16401] chan_sip.c: *** Joint capabilities are 0x0 (nothing) [Sep 20 11:20:41] DEBUG[16401] chan_sip.c: *** Our capabilities are 0x4 (ulaw) [Sep 20 11:20:41] DEBUG[16401] chan_sip.c: *** AST_CODEC_CHOOSE formats are 0x4 (ulaw) [Sep 20 11:20:41] DEBUG[16401] chan_sip.c: *** Our preferred formats from the incoming channel are 0x4 (ulaw) [Sep 20 11:20:41] DEBUG[16401] chan_sip.c: This channel will not be able to handle video. [Sep 20 11:20:41] DEBUG[16401] channel.c: Not copying variable STACK-default-1002-1. [Sep 20 11:20:41] DEBUG[16401] channel.c: Not copying variable SIPCALLID. [Sep 20 11:20:41] DEBUG[16401] channel.c: Not copying variable SIPUSERAGENT. [Sep 20 11:20:41] DEBUG[16401] channel.c: Not copying variable SIPDOMAIN. [Sep 20 11:20:41] DEBUG[16401] channel.c: Not copying variable SIPURI. [Sep 20 11:20:41] DEBUG[16401] chan_sip.c: Outgoing Call for 751nv105 [Sep 20 11:20:41] DEBUG[16401] chan_sip.c: Updating call counter for outgoing call [Sep 20 11:20:41] DEBUG[16401] chan_sip.c: Our T38 capability (0), joint T38 capability (0) [Sep 20 11:20:41] DEBUG[16401] chan_sip.c: ** Our capability: 0x4 (ulaw) Video flag: False [Sep 20 11:20:41] DEBUG[16401] chan_sip.c: ** Our prefcodec: 0x4 (ulaw) [Sep 20 11:20:41] VERBOSE[16401] logger.c: Audio is at 63.77.68.166 port 16786 [Sep 20 11:20:41] VERBOSE[16401] logger.c: Adding codec 0x4 (ulaw) to SDP [Sep 20 11:20:41] VERBOSE[16401] logger.c: Adding non-codec 0x1 (telephone-event) to SDP [Sep 20 11:20:41] DEBUG[16401] chan_sip.c: -- Done with adding codecs to SDP [Sep 20 11:20:41] DEBUG[16401] chan_sip.c: Done building SDP. Settling with this capability: 0x4 (ulaw) [Sep 20 11:20:41] DEBUG[16401] chan_sip.c: Header 0: INVITE sip:751nv105@63.77.68.165 SIP/2.0 (40) [Sep 20 11:20:41] DEBUG[16401] chan_sip.c: Header 1: Via: SIP/2.0/UDP 63.77.68.166:5060;branch=z9hG4bK28a7b077;rport (63) [Sep 20 11:20:41] DEBUG[16401] chan_sip.c: Header 2: From: "Sally Baker" ;tag=as34aa4a8c (62) [Sep 20 11:20:41] DEBUG[16401] chan_sip.c: Header 3: To: (31) [Sep 20 11:20:41] DEBUG[16401] chan_sip.c: Header 4: Contact: (36) [Sep 20 11:20:41] DEBUG[16401] chan_sip.c: Header 5: Call-ID: 36d029045ccd063e08e6b6587c208c77@63.77.68.166 (54) [Sep 20 11:20:41] DEBUG[16401] chan_sip.c: Header 6: CSeq: 102 INVITE (16) [Sep 20 11:20:41] DEBUG[16401] chan_sip.c: Header 7: User-Agent: NuvioSoftSwitch (27) [Sep 20 11:20:41] DEBUG[16401] chan_sip.c: Header 8: Max-Forwards: 70 (16) [Sep 20 11:20:41] DEBUG[16401] chan_sip.c: Header 9: Date: Wed, 20 Sep 2006 16:20:41 GMT (35) [Sep 20 11:20:41] DEBUG[16401] chan_sip.c: Header 10: Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY (66) [Sep 20 11:20:41] DEBUG[16401] chan_sip.c: Header 11: Supported: replaces (19) [Sep 20 11:20:41] DEBUG[16401] chan_sip.c: Header 12: Content-Type: application/sdp (29) [Sep 20 11:20:41] DEBUG[16401] chan_sip.c: Header 13: Content-Length: 240 (19) [Sep 20 11:20:41] DEBUG[16401] chan_sip.c: Header 14: (0) [Sep 20 11:20:41] DEBUG[16401] chan_sip.c: Line: v=0 (3) [Sep 20 11:20:41] DEBUG[16401] chan_sip.c: Line: o=root 16363 16363 IN IP4 63.77.68.166 (38) [Sep 20 11:20:41] DEBUG[16401] chan_sip.c: Line: s=session (9) [Sep 20 11:20:41] DEBUG[16401] chan_sip.c: Line: c=IN IP4 63.77.68.166 (21) [Sep 20 11:20:41] DEBUG[16401] chan_sip.c: Line: t=0 0 (5) [Sep 20 11:20:41] DEBUG[16401] chan_sip.c: Line: m=audio 16786 RTP/AVP 0 101 (27) [Sep 20 11:20:41] DEBUG[16401] chan_sip.c: Line: a=rtpmap:0 PCMU/8000 (20) [Sep 20 11:20:41] DEBUG[16401] chan_sip.c: Line: a=ptime:20 (10) [Sep 20 11:20:41] DEBUG[16401] chan_sip.c: Line: a=rtpmap:101 telephone-event/8000 (33) [Sep 20 11:20:41] DEBUG[16401] chan_sip.c: Line: a=fmtp:101 0-16 (15) [Sep 20 11:20:41] DEBUG[16401] chan_sip.c: Line: a=silenceSupp:off - - - - (25) [Sep 20 11:20:41] DEBUG[16401] chan_sip.c: Line: a=sendrecv (10) [Sep 20 11:20:41] VERBOSE[16401] logger.c: Reliably Transmitting (no NAT) to 63.77.68.165:5060: INVITE sip:751nv105@63.77.68.165 SIP/2.0 Via: SIP/2.0/UDP 63.77.68.166:5060;branch=z9hG4bK28a7b077;rport From: "Sally Baker" ;tag=as34aa4a8c To: Contact: Call-ID: 36d029045ccd063e08e6b6587c208c77@63.77.68.166 CSeq: 102 INVITE User-Agent: NuvioSoftSwitch Max-Forwards: 70 Date: Wed, 20 Sep 2006 16:20:41 GMT Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Supported: replaces Content-Type: application/sdp Content-Length: 240 v=0 o=root 16363 16363 IN IP4 63.77.68.166 s=session c=IN IP4 63.77.68.166 t=0 0 m=audio 16786 RTP/AVP 0 101 a=rtpmap:0 PCMU/8000 a=ptime:20 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=silenceSupp:off - - - - a=sendrecv --- [Sep 20 11:20:41] DEBUG[16401] chan_sip.c: Header 0: INVITE sip:751nv105@63.77.68.165 SIP/2.0 (40) [Sep 20 11:20:41] DEBUG[16401] chan_sip.c: Header 1: Via: SIP/2.0/UDP 63.77.68.166:5060;branch=z9hG4bK28a7b077;rport (63) [Sep 20 11:20:41] DEBUG[16401] chan_sip.c: Header 2: From: "Sally Baker" ;tag=as34aa4a8c (62) [Sep 20 11:20:41] DEBUG[16401] chan_sip.c: Header 3: To: (31) [Sep 20 11:20:41] DEBUG[16401] chan_sip.c: Header 4: Contact: (36) [Sep 20 11:20:41] DEBUG[16401] chan_sip.c: Header 5: Call-ID: 36d029045ccd063e08e6b6587c208c77@63.77.68.166 (54) [Sep 20 11:20:41] DEBUG[16401] chan_sip.c: Header 6: CSeq: 102 INVITE (16) [Sep 20 11:20:41] DEBUG[16401] chan_sip.c: Header 7: User-Agent: NuvioSoftSwitch (27) [Sep 20 11:20:41] DEBUG[16401] chan_sip.c: Header 8: Max-Forwards: 70 (16) [Sep 20 11:20:41] DEBUG[16401] chan_sip.c: Header 9: Date: Wed, 20 Sep 2006 16:20:41 GMT (35) [Sep 20 11:20:41] DEBUG[16401] chan_sip.c: Header 10: Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY (66) [Sep 20 11:20:41] DEBUG[16401] chan_sip.c: Header 11: Supported: replaces (19) [Sep 20 11:20:41] DEBUG[16401] chan_sip.c: Header 12: Content-Type: application/sdp (29) [Sep 20 11:20:41] DEBUG[16401] chan_sip.c: Header 13: Content-Length: 240 (19) [Sep 20 11:20:41] DEBUG[16401] chan_sip.c: Header 14: (0) [Sep 20 11:20:41] DEBUG[16401] chan_sip.c: Line: v=0 (3) [Sep 20 11:20:41] DEBUG[16401] chan_sip.c: Line: o=root 16363 16363 IN IP4 63.77.68.166 (38) [Sep 20 11:20:41] DEBUG[16401] chan_sip.c: Line: s=session (9) [Sep 20 11:20:41] DEBUG[16401] chan_sip.c: Line: c=IN IP4 63.77.68.166 (21) [Sep 20 11:20:41] DEBUG[16401] chan_sip.c: Line: t=0 0 (5) [Sep 20 11:20:41] DEBUG[16401] chan_sip.c: Line: m=audio 16786 RTP/AVP 0 101 (27) [Sep 20 11:20:41] DEBUG[16401] chan_sip.c: Line: a=rtpmap:0 PCMU/8000 (20) [Sep 20 11:20:41] DEBUG[16401] chan_sip.c: Line: a=ptime:20 (10) [Sep 20 11:20:41] DEBUG[16401] chan_sip.c: Line: a=rtpmap:101 telephone-event/8000 (33) [Sep 20 11:20:41] DEBUG[16401] chan_sip.c: Line: a=fmtp:101 0-16 (15) [Sep 20 11:20:41] DEBUG[16401] chan_sip.c: Line: a=silenceSupp:off - - - - (25) [Sep 20 11:20:41] DEBUG[16401] chan_sip.c: Line: a=sendrecv (10) [Sep 20 11:20:41] DEBUG[16401] chan_sip.c: *** SIP TIMER: Initalizing retransmit timer on packet: Id #21 [Sep 20 11:20:41] VERBOSE[16401] logger.c: -- Called 751nv105 [Sep 20 11:20:41] VERBOSE[16389] 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.166:5060;branch=z9hG4bK28a7b077;rport=5060 From: "Sally Baker" ;tag=as34aa4a8c To: Call-ID: 36d029045ccd063e08e6b6587c208c77@63.77.68.166 CSeq: 102 INVITE Content-Length: 0 [Sep 20 11:20:41] DEBUG[16389] chan_sip.c: Header 0: SIP/2.0 100 trying -- your call is important to us (50) [Sep 20 11:20:41] DEBUG[16389] chan_sip.c: Header 1: Via: SIP/2.0/UDP 63.77.68.166:5060;branch=z9hG4bK28a7b077;rport=5060 (68) [Sep 20 11:20:41] DEBUG[16389] chan_sip.c: Header 2: From: "Sally Baker" ;tag=as34aa4a8c (62) [Sep 20 11:20:41] DEBUG[16389] chan_sip.c: Header 3: To: (31) [Sep 20 11:20:41] DEBUG[16389] chan_sip.c: Header 4: Call-ID: 36d029045ccd063e08e6b6587c208c77@63.77.68.166 (54) [Sep 20 11:20:41] DEBUG[16389] chan_sip.c: Header 5: CSeq: 102 INVITE (16) [Sep 20 11:20:41] DEBUG[16389] chan_sip.c: Header 6: Content-Length: 0 (17) [Sep 20 11:20:41] DEBUG[16389] chan_sip.c: Header 7: (0) [Sep 20 11:20:41] VERBOSE[16389] logger.c: --- (7 headers 0 lines)[Sep 20 11:20:41] VERBOSE[16389] logger.c: --- [Sep 20 11:20:41] DEBUG[16389] chan_sip.c: *** SIP TIMER: Cancelling retransmission #21 - INVITE (got response) [Sep 20 11:20:41] DEBUG[16389] chan_sip.c: (Provisional) Stopping retransmission (but retaining packet) on '36d029045ccd063e08e6b6587c208c77@63.77.68.166' Request 102: Found [Sep 20 11:20:41] DEBUG[16389] chan_sip.c: SIP response 100 to standard invite [Sep 20 11:20:42] VERBOSE[16389] logger.c: <-- SIP read from 63.77.68.165:5060: SIP/2.0 180 Ringing Via: SIP/2.0/UDP 63.77.68.166:5060;branch=z9hG4bK28a7b077;rport=5060 From: "Sally Baker" ;tag=as34aa4a8c To: ;tag=0003e373741706d613ec3567-2d82e847 Call-ID: 36d029045ccd063e08e6b6587c208c77@63.77.68.166 Date: Wed, 20 Sep 2006 16:20:47 GMT CSeq: 102 INVITE Server: Cisco-CP7960G/7.5 Contact: Record-Route: Allow: ACK,BYE,CANCEL,INVITE,NOTIFY,OPTIONS,REFER,REGISTER,UPDATE Remote-Party-ID: "Bob Charles" ;party=called;id-type=subscriber;privacy=off;screen=yes Content-Length: 0 [Sep 20 11:20:42] DEBUG[16389] chan_sip.c: Header 0: SIP/2.0 180 Ringing (19) [Sep 20 11:20:42] DEBUG[16389] chan_sip.c: Header 1: Via: SIP/2.0/UDP 63.77.68.166:5060;branch=z9hG4bK28a7b077;rport=5060 (68) [Sep 20 11:20:42] DEBUG[16389] chan_sip.c: Header 2: From: "Sally Baker" ;tag=as34aa4a8c (62) [Sep 20 11:20:42] DEBUG[16389] chan_sip.c: Header 3: To: ;tag=0003e373741706d613ec3567-2d82e847 (69) [Sep 20 11:20:42] DEBUG[16389] chan_sip.c: Header 4: Call-ID: 36d029045ccd063e08e6b6587c208c77@63.77.68.166 (54) [Sep 20 11:20:42] DEBUG[16389] chan_sip.c: Header 5: Date: Wed, 20 Sep 2006 16:20:47 GMT (35) [Sep 20 11:20:42] DEBUG[16389] chan_sip.c: Header 6: CSeq: 102 INVITE (16) [Sep 20 11:20:42] DEBUG[16389] chan_sip.c: Header 7: Server: Cisco-CP7960G/7.5 (25) [Sep 20 11:20:42] DEBUG[16389] chan_sip.c: Header 8: Contact: (40) [Sep 20 11:20:42] DEBUG[16389] chan_sip.c: Header 9: Record-Route: (51) [Sep 20 11:20:42] DEBUG[16389] chan_sip.c: Header 10: Allow: ACK,BYE,CANCEL,INVITE,NOTIFY,OPTIONS,REFER,REGISTER,UPDATE (65) [Sep 20 11:20:42] DEBUG[16389] chan_sip.c: Header 11: Remote-Party-ID: "Bob Charles" ;party=called;id-type=subscriber;privacy=off;screen=yes (112) [Sep 20 11:20:42] DEBUG[16389] chan_sip.c: Header 12: Content-Length: 0 (17) [Sep 20 11:20:42] DEBUG[16389] chan_sip.c: Header 13: (0) [Sep 20 11:20:42] VERBOSE[16389] logger.c: --- (13 headers 0 lines)[Sep 20 11:20:42] VERBOSE[16389] logger.c: --- [Sep 20 11:20:42] DEBUG[16389] chan_sip.c: (Provisional) Stopping retransmission (but retaining packet) on '36d029045ccd063e08e6b6587c208c77@63.77.68.166' Request 102: Found [Sep 20 11:20:42] DEBUG[16389] chan_sip.c: SIP response 180 to standard invite [Sep 20 11:20:42] DEBUG[16389] devicestate.c: Notification of state change to be queued on device/channel SIP/751nv105-0824e2d8 [Sep 20 11:20:42] VERBOSE[16401] logger.c: -- SIP/751nv105-0824e2d8 is ringing [Sep 20 11:20:42] VERBOSE[16401] 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=z9hG4bK3af3.55f33894.0;received=63.77.68.165 Via: SIP/2.0/UDP 63.77.68.74;branch=z9hG4bK15b0882039E5B563 From: "Sally Baker" ;tag=5D56B5AD-B325DB0A To: ;tag=as3584d546 Call-ID: ec336c01-9a146537-d23e9134@63.77.68.74 CSeq: 2 INVITE User-Agent: NuvioSoftSwitch Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Supported: replaces Contact: Content-Length: 0 --- [Sep 20 11:20:42] DEBUG[16401] chan_sip.c: Header 0: SIP/2.0 180 Ringing (19) [Sep 20 11:20:42] DEBUG[16401] chan_sip.c: Header 1: Via: SIP/2.0/UDP 63.77.68.165;branch=z9hG4bK3af3.55f33894.0;received=63.77.68.165 (81) [Sep 20 11:20:42] DEBUG[16401] chan_sip.c: Header 2: Via: SIP/2.0/UDP 63.77.68.74;branch=z9hG4bK15b0882039E5B563 (59) [Sep 20 11:20:42] DEBUG[16401] chan_sip.c: Header 3: From: "Sally Baker" ;tag=5D56B5AD-B325DB0A (81) [Sep 20 11:20:42] DEBUG[16401] chan_sip.c: Header 4: To: ;tag=as3584d546 (65) [Sep 20 11:20:42] DEBUG[16401] chan_sip.c: Header 5: Call-ID: ec336c01-9a146537-d23e9134@63.77.68.74 (47) [Sep 20 11:20:42] DEBUG[16401] chan_sip.c: Header 6: CSeq: 2 INVITE (14) [Sep 20 11:20:42] DEBUG[16401] chan_sip.c: Header 7: User-Agent: NuvioSoftSwitch (27) [Sep 20 11:20:42] DEBUG[16401] chan_sip.c: Header 8: Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY (66) [Sep 20 11:20:42] DEBUG[16401] chan_sip.c: Header 9: Supported: replaces (19) [Sep 20 11:20:42] DEBUG[16401] chan_sip.c: Header 10: Contact: (32) [Sep 20 11:20:42] DEBUG[16401] chan_sip.c: Header 11: Content-Length: 0 (17) [Sep 20 11:20:42] DEBUG[16401] chan_sip.c: Header 12: (0) [Sep 20 11:20:42] DEBUG[16401] channel.c: Driver for channel 'SIP/714nv100-082496a0' does not support indication 3, emulating it [Sep 20 11:20:42] DEBUG[16401] channel.c: Prodding channel 'SIP/714nv100-082496a0' [Sep 20 11:20:42] DEBUG[16401] chan_sip.c: Setting framing from config on incoming call [Sep 20 11:20:42] DEBUG[16401] chan_sip.c: ** Our capability: 0x4 (ulaw) Video flag: True [Sep 20 11:20:42] DEBUG[16401] chan_sip.c: ** Our prefcodec: 0x0 (nothing) [Sep 20 11:20:42] VERBOSE[16401] logger.c: Audio is at 63.77.68.166 port 14460 [Sep 20 11:20:42] VERBOSE[16401] logger.c: Adding codec 0x4 (ulaw) to SDP [Sep 20 11:20:42] VERBOSE[16401] logger.c: Adding non-codec 0x1 (telephone-event) to SDP [Sep 20 11:20:42] DEBUG[16401] chan_sip.c: -- Done with adding codecs to SDP [Sep 20 11:20:42] DEBUG[16401] chan_sip.c: Done building SDP. Settling with this capability: 0x4 (ulaw) [Sep 20 11:20:42] VERBOSE[16401] 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=z9hG4bK3af3.55f33894.0;received=63.77.68.165 Via: SIP/2.0/UDP 63.77.68.74;branch=z9hG4bK15b0882039E5B563 From: "Sally Baker" ;tag=5D56B5AD-B325DB0A To: ;tag=as3584d546 Call-ID: ec336c01-9a146537-d23e9134@63.77.68.74 CSeq: 2 INVITE User-Agent: NuvioSoftSwitch Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Supported: replaces Contact: Content-Type: application/sdp Content-Length: 240 v=0 o=root 16363 16363 IN IP4 63.77.68.166 s=session c=IN IP4 63.77.68.166 t=0 0 m=audio 14460 RTP/AVP 0 101 a=rtpmap:0 PCMU/8000 a=ptime:20 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=silenceSupp:off - - - - a=sendrecv --- [Sep 20 11:20:42] DEBUG[16401] chan_sip.c: Header 0: SIP/2.0 183 Session Progress (28) [Sep 20 11:20:42] DEBUG[16401] chan_sip.c: Header 1: Via: SIP/2.0/UDP 63.77.68.165;branch=z9hG4bK3af3.55f33894.0;received=63.77.68.165 (81) [Sep 20 11:20:42] DEBUG[16401] chan_sip.c: Header 2: Via: SIP/2.0/UDP 63.77.68.74;branch=z9hG4bK15b0882039E5B563 (59) [Sep 20 11:20:42] DEBUG[16401] chan_sip.c: Header 3: From: "Sally Baker" ;tag=5D56B5AD-B325DB0A (81) [Sep 20 11:20:42] DEBUG[16401] chan_sip.c: Header 4: To: ;tag=as3584d546 (65) [Sep 20 11:20:42] DEBUG[16401] chan_sip.c: Header 5: Call-ID: ec336c01-9a146537-d23e9134@63.77.68.74 (47) [Sep 20 11:20:42] DEBUG[16401] chan_sip.c: Header 6: CSeq: 2 INVITE (14) [Sep 20 11:20:42] DEBUG[16401] chan_sip.c: Header 7: User-Agent: NuvioSoftSwitch (27) [Sep 20 11:20:42] DEBUG[16401] chan_sip.c: Header 8: Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY (66) [Sep 20 11:20:42] DEBUG[16401] chan_sip.c: Header 9: Supported: replaces (19) [Sep 20 11:20:42] DEBUG[16401] chan_sip.c: Header 10: Contact: (32) [Sep 20 11:20:42] DEBUG[16401] chan_sip.c: Header 11: Content-Type: application/sdp (29) [Sep 20 11:20:42] DEBUG[16401] chan_sip.c: Header 12: Content-Length: 240 (19) [Sep 20 11:20:42] DEBUG[16401] chan_sip.c: Header 13: (0) [Sep 20 11:20:42] DEBUG[16401] chan_sip.c: Line: v=0 (3) [Sep 20 11:20:42] DEBUG[16401] chan_sip.c: Line: o=root 16363 16363 IN IP4 63.77.68.166 (38) [Sep 20 11:20:42] DEBUG[16401] chan_sip.c: Line: s=session (9) [Sep 20 11:20:42] DEBUG[16401] chan_sip.c: Line: c=IN IP4 63.77.68.166 (21) [Sep 20 11:20:42] DEBUG[16401] chan_sip.c: Line: t=0 0 (5) [Sep 20 11:20:42] DEBUG[16401] chan_sip.c: Line: m=audio 14460 RTP/AVP 0 101 (27) [Sep 20 11:20:42] DEBUG[16401] chan_sip.c: Line: a=rtpmap:0 PCMU/8000 (20) [Sep 20 11:20:42] DEBUG[16401] chan_sip.c: Line: a=ptime:20 (10) [Sep 20 11:20:42] DEBUG[16401] chan_sip.c: Line: a=rtpmap:101 telephone-event/8000 (33) [Sep 20 11:20:42] DEBUG[16401] chan_sip.c: Line: a=fmtp:101 0-16 (15) [Sep 20 11:20:42] DEBUG[16401] chan_sip.c: Line: a=silenceSupp:off - - - - (25) [Sep 20 11:20:42] DEBUG[16401] chan_sip.c: Line: a=sendrecv (10) [Sep 20 11:20:42] DEBUG[16401] channel.c: Set channel SIP/714nv100-082496a0 to write format slin [Sep 20 11:20:42] DEBUG[16368] devicestate.c: No provider found, checking channel drivers for SIP - 751nv105 [Sep 20 11:20:42] DEBUG[16368] chan_sip.c: Checking device state for peer 751nv105 [Sep 20 11:20:42] DEBUG[16368] devicestate.c: Changing state for SIP/751nv105 - state 1 (Not in use) [Sep 20 11:20:42] DEBUG[16368] devicestate.c: No provider found, checking channel drivers for SIP - 751nv105 [Sep 20 11:20:42] DEBUG[16368] chan_sip.c: Checking device state for peer 751nv105 [Sep 20 11:20:42] DEBUG[16403] app_queue.c: Device 'SIP/751nv105' changed to state '1' (Not in use) but we don't care because they're not a member of any queue. [Sep 20 11:20:42] DEBUG[16401] rtp.c: Ooh, format changed from unknown to ulaw [Sep 20 11:20:42] DEBUG[16401] rtp.c: Created smoother: format: 4 ms: 20 len: 160 [Sep 20 11:20:44] VERBOSE[16389] logger.c: <-- SIP read from 63.77.68.165:5060: SIP/2.0 200 OK Via: SIP/2.0/UDP 63.77.68.166:5060;branch=z9hG4bK28a7b077;rport=5060 From: "Sally Baker" ;tag=as34aa4a8c To: ;tag=0003e373741706d613ec3567-2d82e847 Call-ID: 36d029045ccd063e08e6b6587c208c77@63.77.68.166 Date: Wed, 20 Sep 2006 16:20:49 GMT CSeq: 102 INVITE Server: Cisco-CP7960G/7.5 Contact: Record-Route: Allow: ACK,BYE,CANCEL,INVITE,NOTIFY,OPTIONS,REFER,REGISTER,UPDATE Remote-Party-ID: "Bob Charles" ;party=called;id-type=subscriber;privacy=off;screen=yes Supported: replaces Content-Length: 203 Content-Type: application/sdp Content-Disposition: session;handling=optional v=0 o=Cisco-SIPUA 10324 0 IN IP4 63.77.68.92 s=SIP Call t=0 0 m=audio 28788 RTP/AVP 0 101 c=IN IP4 63.77.68.92 a=rtpmap:0 PCMU/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-15 a=sendrecv [Sep 20 11:20:44] DEBUG[16389] chan_sip.c: Header 0: SIP/2.0 200 OK (14) [Sep 20 11:20:44] DEBUG[16389] chan_sip.c: Header 1: Via: SIP/2.0/UDP 63.77.68.166:5060;branch=z9hG4bK28a7b077;rport=5060 (68) [Sep 20 11:20:44] DEBUG[16389] chan_sip.c: Header 2: From: "Sally Baker" ;tag=as34aa4a8c (62) [Sep 20 11:20:44] DEBUG[16389] chan_sip.c: Header 3: To: ;tag=0003e373741706d613ec3567-2d82e847 (69) [Sep 20 11:20:44] DEBUG[16389] chan_sip.c: Header 4: Call-ID: 36d029045ccd063e08e6b6587c208c77@63.77.68.166 (54) [Sep 20 11:20:44] DEBUG[16389] chan_sip.c: Header 5: Date: Wed, 20 Sep 2006 16:20:49 GMT (35) [Sep 20 11:20:44] DEBUG[16389] chan_sip.c: Header 6: CSeq: 102 INVITE (16) [Sep 20 11:20:44] DEBUG[16389] chan_sip.c: Header 7: Server: Cisco-CP7960G/7.5 (25) [Sep 20 11:20:44] DEBUG[16389] chan_sip.c: Header 8: Contact: (40) [Sep 20 11:20:44] DEBUG[16389] chan_sip.c: Header 9: Record-Route: (51) [Sep 20 11:20:44] DEBUG[16389] chan_sip.c: Header 10: Allow: ACK,BYE,CANCEL,INVITE,NOTIFY,OPTIONS,REFER,REGISTER,UPDATE (65) [Sep 20 11:20:44] DEBUG[16389] chan_sip.c: Header 11: Remote-Party-ID: "Bob Charles" ;party=called;id-type=subscriber;privacy=off;screen=yes (112) [Sep 20 11:20:44] DEBUG[16389] chan_sip.c: Header 12: Supported: replaces (19) [Sep 20 11:20:44] DEBUG[16389] chan_sip.c: Header 13: Content-Length: 203 (19) [Sep 20 11:20:44] DEBUG[16389] chan_sip.c: Header 14: Content-Type: application/sdp (29) [Sep 20 11:20:44] DEBUG[16389] chan_sip.c: Header 15: Content-Disposition: session;handling=optional (46) [Sep 20 11:20:44] DEBUG[16389] chan_sip.c: Header 16: (0) [Sep 20 11:20:44] DEBUG[16389] chan_sip.c: Line: v=0 (3) [Sep 20 11:20:44] DEBUG[16389] chan_sip.c: Line: o=Cisco-SIPUA 10324 0 IN IP4 63.77.68.92 (40) [Sep 20 11:20:44] DEBUG[16389] chan_sip.c: Line: s=SIP Call (10) [Sep 20 11:20:44] DEBUG[16389] chan_sip.c: Line: t=0 0 (5) [Sep 20 11:20:44] DEBUG[16389] chan_sip.c: Line: m=audio 28788 RTP/AVP 0 101 (27) [Sep 20 11:20:44] DEBUG[16389] chan_sip.c: Line: c=IN IP4 63.77.68.92 (20) [Sep 20 11:20:44] DEBUG[16389] chan_sip.c: Line: a=rtpmap:0 PCMU/8000 (20) [Sep 20 11:20:44] DEBUG[16389] chan_sip.c: Line: a=rtpmap:101 telephone-event/8000 (33) [Sep 20 11:20:44] DEBUG[16389] chan_sip.c: Line: a=fmtp:101 0-15 (15) [Sep 20 11:20:44] DEBUG[16389] chan_sip.c: Line: a=sendrecv (10) [Sep 20 11:20:44] VERBOSE[16389] logger.c: --- (16 headers 10 lines)[Sep 20 11:20:44] VERBOSE[16389] logger.c: --- [Sep 20 11:20:44] DEBUG[16389] chan_sip.c: Acked pending invite 102 [Sep 20 11:20:44] DEBUG[16389] chan_sip.c: Stopping retransmission on '36d029045ccd063e08e6b6587c208c77@63.77.68.166' of Request 102: Match Not Found [Sep 20 11:20:44] DEBUG[16389] chan_sip.c: SIP response 200 to standard invite [Sep 20 11:20:44] VERBOSE[16389] logger.c: Found RTP audio format 0 [Sep 20 11:20:44] VERBOSE[16389] logger.c: Found RTP audio format 101 [Sep 20 11:20:44] VERBOSE[16389] logger.c: Peer audio RTP is at port 63.77.68.92:28788 [Sep 20 11:20:44] VERBOSE[16389] logger.c: Found description format PCMU for ID 0 [Sep 20 11:20:44] VERBOSE[16389] logger.c: Found description format telephone-event for ID 101 [Sep 20 11:20:44] VERBOSE[16389] logger.c: Got unsupported a:fmtp in SDP offer [Sep 20 11:20:44] DEBUG[16389] chan_sip.c: T38 state changed to 0 on channel SIP/751nv105-0824e2d8 [Sep 20 11:20:44] VERBOSE[16389] logger.c: Capabilities: us - 0x4 (ulaw), peer - audio=0x4 (ulaw)/video=0x0 (nothing), combined - 0x4 (ulaw) [Sep 20 11:20:44] VERBOSE[16389] logger.c: Non-codec capabilities (dtmf): us - 0x1 (telephone-event), peer - 0x1 (telephone-event), combined - 0x1 (telephone-event) [Sep 20 11:20:44] VERBOSE[16389] logger.c: Peer audio RTP is at port 63.77.68.92:28788 [Sep 20 11:20:44] DEBUG[16389] chan_sip.c: We're settling with these formats: 0x4 (ulaw) [Sep 20 11:20:44] DEBUG[16389] chan_sip.c: We have an owner, now see if we need to change this call [Sep 20 11:20:44] DEBUG[16389] chan_sip.c: Updating call counter for outgoing call [Sep 20 11:20:44] DEBUG[16389] chan_sip.c: build_route: Record-Route hop: [Sep 20 11:20:44] VERBOSE[16389] logger.c: list_route: hop: [Sep 20 11:20:44] VERBOSE[16389] logger.c: set_destination: Parsing for address/port to send to [Sep 20 11:20:44] VERBOSE[16389] logger.c: set_destination: set destination to 63.77.68.165, port 5060 [Sep 20 11:20:44] VERBOSE[16389] logger.c: Transmitting (no NAT) to 63.77.68.165:5060: ACK sip:751nv105@63.77.68.92:5060 SIP/2.0 Via: SIP/2.0/UDP 63.77.68.166:5060;branch=z9hG4bK1ba17190;rport Route: From: "Sally Baker" ;tag=as34aa4a8c To: ;tag=0003e373741706d613ec3567-2d82e847 Contact: Call-ID: 36d029045ccd063e08e6b6587c208c77@63.77.68.166 CSeq: 102 ACK User-Agent: NuvioSoftSwitch Max-Forwards: 70 Content-Length: 0 --- [Sep 20 11:20:44] DEBUG[16389] chan_sip.c: Header 0: ACK sip:751nv105@63.77.68.92:5060 SIP/2.0 (41) [Sep 20 11:20:44] DEBUG[16389] chan_sip.c: Header 1: Via: SIP/2.0/UDP 63.77.68.166:5060;branch=z9hG4bK1ba17190;rport (63) [Sep 20 11:20:44] DEBUG[16389] chan_sip.c: Header 2: Route: (44) [Sep 20 11:20:44] DEBUG[16389] chan_sip.c: Header 3: From: "Sally Baker" ;tag=as34aa4a8c (62) [Sep 20 11:20:44] DEBUG[16389] chan_sip.c: Header 4: To: ;tag=0003e373741706d613ec3567-2d82e847 (69) [Sep 20 11:20:44] DEBUG[16389] chan_sip.c: Header 5: Contact: (36) [Sep 20 11:20:44] DEBUG[16389] chan_sip.c: Header 6: Call-ID: 36d029045ccd063e08e6b6587c208c77@63.77.68.166 (54) [Sep 20 11:20:44] DEBUG[16389] chan_sip.c: Header 7: CSeq: 102 ACK (13) [Sep 20 11:20:44] DEBUG[16389] chan_sip.c: Header 8: User-Agent: NuvioSoftSwitch (27) [Sep 20 11:20:44] DEBUG[16389] chan_sip.c: Header 9: Max-Forwards: 70 (16) [Sep 20 11:20:44] DEBUG[16389] chan_sip.c: Header 10: Content-Length: 0 (17) [Sep 20 11:20:44] DEBUG[16389] chan_sip.c: Header 11: (0) [Sep 20 11:20:44] DEBUG[16401] devicestate.c: Notification of state change to be queued on device/channel SIP/751nv105-0824e2d8 [Sep 20 11:20:44] VERBOSE[16401] logger.c: -- SIP/751nv105-0824e2d8 answered SIP/714nv100-082496a0 [Sep 20 11:20:44] DEBUG[16401] channel.c: Set channel SIP/714nv100-082496a0 to write format ulaw [Sep 20 11:20:44] DEBUG[16401] devicestate.c: Notification of state change to be queued on device/channel SIP/714nv100-082496a0 [Sep 20 11:20:44] DEBUG[16401] chan_sip.c: SIP answering channel: SIP/714nv100-082496a0 [Sep 20 11:20:44] DEBUG[16401] chan_sip.c: Setting framing from config on incoming call [Sep 20 11:20:44] DEBUG[16401] chan_sip.c: ** Our capability: 0x4 (ulaw) Video flag: True [Sep 20 11:20:44] DEBUG[16401] chan_sip.c: ** Our prefcodec: 0x0 (nothing) [Sep 20 11:20:44] VERBOSE[16401] logger.c: Audio is at 63.77.68.166 port 14460 [Sep 20 11:20:44] VERBOSE[16401] logger.c: Adding codec 0x4 (ulaw) to SDP [Sep 20 11:20:44] VERBOSE[16401] logger.c: Adding non-codec 0x1 (telephone-event) to SDP [Sep 20 11:20:44] DEBUG[16401] chan_sip.c: -- Done with adding codecs to SDP [Sep 20 11:20:44] DEBUG[16401] chan_sip.c: Done building SDP. Settling with this capability: 0x4 (ulaw) [Sep 20 11:20:44] VERBOSE[16401] logger.c: Reliably Transmitting (no NAT) to 63.77.68.165:5060: SIP/2.0 200 OK Via: SIP/2.0/UDP 63.77.68.165;branch=z9hG4bK3af3.55f33894.0;received=63.77.68.165 Via: SIP/2.0/UDP 63.77.68.74;branch=z9hG4bK15b0882039E5B563 Record-Route: From: "Sally Baker" ;tag=5D56B5AD-B325DB0A To: ;tag=as3584d546 Call-ID: ec336c01-9a146537-d23e9134@63.77.68.74 CSeq: 2 INVITE User-Agent: NuvioSoftSwitch Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Supported: replaces Contact: Content-Type: application/sdp Content-Length: 240 v=0 o=root 16363 16364 IN IP4 63.77.68.166 s=session c=IN IP4 63.77.68.166 t=0 0 m=audio 14460 RTP/AVP 0 101 a=rtpmap:0 PCMU/8000 a=ptime:20 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=silenceSupp:off - - - - a=sendrecv --- [Sep 20 11:20:44] DEBUG[16401] chan_sip.c: Header 0: SIP/2.0 200 OK (14) [Sep 20 11:20:44] DEBUG[16401] chan_sip.c: Header 1: Via: SIP/2.0/UDP 63.77.68.165;branch=z9hG4bK3af3.55f33894.0;received=63.77.68.165 (81) [Sep 20 11:20:44] DEBUG[16401] chan_sip.c: Header 2: Via: SIP/2.0/UDP 63.77.68.74;branch=z9hG4bK15b0882039E5B563 (59) [Sep 20 11:20:44] DEBUG[16401] chan_sip.c: Header 3: Record-Route: (58) [Sep 20 11:20:44] DEBUG[16401] chan_sip.c: Header 4: From: "Sally Baker" ;tag=5D56B5AD-B325DB0A (81) [Sep 20 11:20:44] DEBUG[16401] chan_sip.c: Header 5: To: ;tag=as3584d546 (65) [Sep 20 11:20:44] DEBUG[16401] chan_sip.c: Header 6: Call-ID: ec336c01-9a146537-d23e9134@63.77.68.74 (47) [Sep 20 11:20:44] DEBUG[16401] chan_sip.c: Header 7: CSeq: 2 INVITE (14) [Sep 20 11:20:44] DEBUG[16401] chan_sip.c: Header 8: User-Agent: NuvioSoftSwitch (27) [Sep 20 11:20:44] DEBUG[16401] chan_sip.c: Header 9: Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY (66) [Sep 20 11:20:44] DEBUG[16401] chan_sip.c: Header 10: Supported: replaces (19) [Sep 20 11:20:44] DEBUG[16401] chan_sip.c: Header 11: Contact: (32) [Sep 20 11:20:44] DEBUG[16401] chan_sip.c: Header 12: Content-Type: application/sdp (29) [Sep 20 11:20:44] DEBUG[16401] chan_sip.c: Header 13: Content-Length: 240 (19) [Sep 20 11:20:44] DEBUG[16401] chan_sip.c: Header 14: (0) [Sep 20 11:20:44] DEBUG[16401] chan_sip.c: Line: v=0 (3) [Sep 20 11:20:44] DEBUG[16401] chan_sip.c: Line: o=root 16363 16364 IN IP4 63.77.68.166 (38) [Sep 20 11:20:44] DEBUG[16401] chan_sip.c: Line: s=session (9) [Sep 20 11:20:44] DEBUG[16401] chan_sip.c: Line: c=IN IP4 63.77.68.166 (21) [Sep 20 11:20:44] DEBUG[16401] chan_sip.c: Line: t=0 0 (5) [Sep 20 11:20:44] DEBUG[16401] chan_sip.c: Line: m=audio 14460 RTP/AVP 0 101 (27) [Sep 20 11:20:44] DEBUG[16401] chan_sip.c: Line: a=rtpmap:0 PCMU/8000 (20) [Sep 20 11:20:44] DEBUG[16401] chan_sip.c: Line: a=ptime:20 (10) [Sep 20 11:20:44] DEBUG[16401] chan_sip.c: Line: a=rtpmap:101 telephone-event/8000 (33) [Sep 20 11:20:44] DEBUG[16401] chan_sip.c: Line: a=fmtp:101 0-16 (15) [Sep 20 11:20:44] DEBUG[16401] chan_sip.c: Line: a=silenceSupp:off - - - - (25) [Sep 20 11:20:44] DEBUG[16401] chan_sip.c: Line: a=sendrecv (10) [Sep 20 11:20:44] DEBUG[16401] chan_sip.c: *** SIP TIMER: Initalizing retransmit timer on packet: Id #24 [Sep 20 11:20:44] VERBOSE[16401] logger.c: -- Packet2Packet bridging SIP/714nv100-082496a0 and SIP/751nv105-0824e2d8 [Sep 20 11:20:44] DEBUG[16368] devicestate.c: No provider found, checking channel drivers for SIP - 751nv105 [Sep 20 11:20:44] DEBUG[16368] chan_sip.c: Checking device state for peer 751nv105 [Sep 20 11:20:44] DEBUG[16368] devicestate.c: Changing state for SIP/751nv105 - state 1 (Not in use) [Sep 20 11:20:44] DEBUG[16368] devicestate.c: No provider found, checking channel drivers for SIP - 751nv105 [Sep 20 11:20:44] DEBUG[16368] chan_sip.c: Checking device state for peer 751nv105 [Sep 20 11:20:44] DEBUG[16368] devicestate.c: No provider found, checking channel drivers for SIP - 714nv100 [Sep 20 11:20:44] DEBUG[16368] chan_sip.c: Checking device state for peer 714nv100 [Sep 20 11:20:44] DEBUG[16368] devicestate.c: Changing state for SIP/714nv100 - state 1 (Not in use) [Sep 20 11:20:44] DEBUG[16368] devicestate.c: No provider found, checking channel drivers for SIP - 714nv100 [Sep 20 11:20:44] DEBUG[16368] chan_sip.c: Checking device state for peer 714nv100 [Sep 20 11:20:44] DEBUG[16404] app_queue.c: Device 'SIP/751nv105' changed to state '1' (Not in use) but we don't care because they're not a member of any queue. [Sep 20 11:20:44] DEBUG[16405] app_queue.c: Device 'SIP/714nv100' changed to state '1' (Not in use) but we don't care because they're not a member of any queue. [Sep 20 11:20:45] VERBOSE[16389] logger.c: <-- SIP read from 63.77.68.165:5060: ACK sip:1002@63.77.68.166 SIP/2.0 Record-Route: Via: SIP/2.0/UDP 63.77.68.165;branch=0 Via: SIP/2.0/UDP 63.77.68.74;branch=z9hG4bK4338228f3D7052E2 From: "Sally Baker" ;tag=5D56B5AD-B325DB0A To: ;tag=as3584d546 CSeq: 2 ACK Call-ID: ec336c01-9a146537-d23e9134@63.77.68.74 Contact: Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, INFO, MESSAGE, SUBSCRIBE, NOTIFY, PRACK, UPDATE, REFER User-Agent: PolycomSoundPointIP-SPIP_500-UA/2.0.1.0291 Proxy-Authorization: Digest username="714nv100", realm="nuvio.com", nonce="45116b85ae460a9b5e1a73bbdd3a3dd8f8ba8ef6", uri="sip:1002@proxy-test.sip.nuvio.net;user=phone;transport=udp", response="d6e457d84ab86acdb4e8a205bd82520d", algorithm=MD5 Max-Forwards: 16 Content-Length: 0 P-hint: lr enforced [Sep 20 11:20:45] DEBUG[16389] chan_sip.c: Header 0: ACK sip:1002@63.77.68.166 SIP/2.0 (33) [Sep 20 11:20:45] DEBUG[16389] chan_sip.c: Header 1: Record-Route: (58) [Sep 20 11:20:45] DEBUG[16389] chan_sip.c: Header 2: Via: SIP/2.0/UDP 63.77.68.165;branch=0 (38) [Sep 20 11:20:45] DEBUG[16389] chan_sip.c: Header 3: Via: SIP/2.0/UDP 63.77.68.74;branch=z9hG4bK4338228f3D7052E2 (59) [Sep 20 11:20:45] DEBUG[16389] chan_sip.c: Header 4: From: "Sally Baker" ;tag=5D56B5AD-B325DB0A (81) [Sep 20 11:20:45] DEBUG[16389] chan_sip.c: Header 5: To: ;tag=as3584d546 (65) [Sep 20 11:20:45] DEBUG[16389] chan_sip.c: Header 6: CSeq: 2 ACK (11) [Sep 20 11:20:45] DEBUG[16389] chan_sip.c: Header 7: Call-ID: ec336c01-9a146537-d23e9134@63.77.68.74 (47) [Sep 20 11:20:45] DEBUG[16389] chan_sip.c: Header 8: Contact: (49) [Sep 20 11:20:45] DEBUG[16389] chan_sip.c: Header 9: Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, INFO, MESSAGE, SUBSCRIBE, NOTIFY, PRACK, UPDATE, REFER (96) [Sep 20 11:20:45] DEBUG[16389] chan_sip.c: Header 10: User-Agent: PolycomSoundPointIP-SPIP_500-UA/2.0.1.0291 (54) [Sep 20 11:20:45] DEBUG[16389] chan_sip.c: Header 11: Proxy-Authorization: Digest username="714nv100", realm="nuvio.com", nonce="45116b85ae460a9b5e1a73bbdd3a3dd8f8ba8ef6", uri="sip:1002@proxy-test.sip.nuvio.net;user=phone;transport=udp", response="d6e457d84ab86acdb4e8a205bd82520d", algorithm=MD5 (242) [Sep 20 11:20:45] DEBUG[16389] chan_sip.c: Header 12: Max-Forwards: 16 (16) [Sep 20 11:20:45] DEBUG[16389] chan_sip.c: Header 13: Content-Length: 0 (17) [Sep 20 11:20:45] DEBUG[16389] chan_sip.c: Header 14: P-hint: lr enforced (19) [Sep 20 11:20:45] DEBUG[16389] chan_sip.c: Header 15: (0) [Sep 20 11:20:45] VERBOSE[16389] logger.c: --- (15 headers 0 lines)[Sep 20 11:20:45] VERBOSE[16389] logger.c: --- [Sep 20 11:20:45] DEBUG[16389] chan_sip.c: **** Received ACK (6) - Command in SIP ACK [Sep 20 11:20:45] DEBUG[16389] chan_sip.c: ** SIP TIMER: Cancelling retransmit of packet (reply received) Retransid #24 [Sep 20 11:20:45] DEBUG[16389] chan_sip.c: Stopping retransmission on 'ec336c01-9a146537-d23e9134@63.77.68.74' of Response 2: Match Not Found [Sep 20 11:20:48] VERBOSE[16389] logger.c: <-- SIP read from 63.77.68.165:5060: INVITE sip:751nv105@63.77.68.92:5060 SIP/2.0 Record-Route: Via: SIP/2.0/UDP 63.77.68.165;branch=z9hG4bK8365.bd275fa6.0 Via: SIP/2.0/UDP 63.77.68.164:5060;branch=z9hG4bK16800c14;rport=5060 From: "Jim Davis" ;tag=as6d8401c9 To: Contact: Call-ID: 2a9c88374b5aa0a137682c7d76fced0b@63.77.68.164 CSeq: 102 INVITE User-Agent: NuvioSoftSwitch Max-Forwards: 16 Date: Wed, 20 Sep 2006 16:20:48 GMT Replaces: ec336c01-9a146537-d23e9134%4063.77.68.74%3bto-tag%3das3584d546%3bfrom-tag%3d5D56B5AD-B325DB0A Require: replaces Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Supported: replaces Content-Type: application/sdp Content-Length: 240 v=0 o=root 17512 17512 IN IP4 63.77.68.164 s=session c=IN IP4 63.77.68.164 t=0 0 m=audio 16940 RTP/AVP 0 101 a=rtpmap:0 PCMU/8000 a=ptime:20 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=silenceSupp:off - - - - a=sendrecv [Sep 20 11:20:48] DEBUG[16389] chan_sip.c: Header 0: INVITE sip:751nv105@63.77.68.92:5060 SIP/2.0 (44) [Sep 20 11:20:48] DEBUG[16389] chan_sip.c: Header 1: Record-Route: (51) [Sep 20 11:20:48] DEBUG[16389] chan_sip.c: Header 2: Via: SIP/2.0/UDP 63.77.68.165;branch=z9hG4bK8365.bd275fa6.0 (59) [Sep 20 11:20:48] DEBUG[16389] chan_sip.c: Header 3: Via: SIP/2.0/UDP 63.77.68.164:5060;branch=z9hG4bK16800c14;rport=5060 (68) [Sep 20 11:20:48] DEBUG[16389] chan_sip.c: Header 4: From: "Jim Davis" ;tag=as6d8401c9 (68) [Sep 20 11:20:48] DEBUG[16389] chan_sip.c: Header 5: To: (31) [Sep 20 11:20:48] DEBUG[16389] chan_sip.c: Header 6: Contact: (44) [Sep 20 11:20:48] DEBUG[16389] chan_sip.c: Header 7: Call-ID: 2a9c88374b5aa0a137682c7d76fced0b@63.77.68.164 (54) [Sep 20 11:20:48] DEBUG[16389] chan_sip.c: Header 8: CSeq: 102 INVITE (16) [Sep 20 11:20:48] DEBUG[16389] chan_sip.c: Header 9: User-Agent: NuvioSoftSwitch (27) [Sep 20 11:20:48] DEBUG[16389] chan_sip.c: Header 10: Max-Forwards: 16 (16) [Sep 20 11:20:48] DEBUG[16389] chan_sip.c: Header 11: Date: Wed, 20 Sep 2006 16:20:48 GMT (35) [Sep 20 11:20:48] DEBUG[16389] chan_sip.c: Header 12: Replaces: ec336c01-9a146537-d23e9134%4063.77.68.74%3bto-tag%3das3584d546%3bfrom-tag%3d5D56B5AD-B325DB0A (103) [Sep 20 11:20:48] DEBUG[16389] chan_sip.c: Header 13: Require: replaces (17) [Sep 20 11:20:48] DEBUG[16389] chan_sip.c: Header 14: Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY (66) [Sep 20 11:20:48] DEBUG[16389] chan_sip.c: Header 15: Supported: replaces (19) [Sep 20 11:20:48] DEBUG[16389] chan_sip.c: Header 16: Content-Type: application/sdp (29) [Sep 20 11:20:48] DEBUG[16389] chan_sip.c: Header 17: Content-Length: 240 (19) [Sep 20 11:20:48] DEBUG[16389] chan_sip.c: Header 18: (0) [Sep 20 11:20:48] DEBUG[16389] chan_sip.c: Line: v=0 (3) [Sep 20 11:20:48] DEBUG[16389] chan_sip.c: Line: o=root 17512 17512 IN IP4 63.77.68.164 (38) [Sep 20 11:20:48] DEBUG[16389] chan_sip.c: Line: s=session (9) [Sep 20 11:20:48] DEBUG[16389] chan_sip.c: Line: c=IN IP4 63.77.68.164 (21) [Sep 20 11:20:48] DEBUG[16389] chan_sip.c: Line: t=0 0 (5) [Sep 20 11:20:48] DEBUG[16389] chan_sip.c: Line: m=audio 16940 RTP/AVP 0 101 (27) [Sep 20 11:20:48] DEBUG[16389] chan_sip.c: Line: a=rtpmap:0 PCMU/8000 (20) [Sep 20 11:20:48] DEBUG[16389] chan_sip.c: Line: a=ptime:20 (10) [Sep 20 11:20:48] DEBUG[16389] chan_sip.c: Line: a=rtpmap:101 telephone-event/8000 (33) [Sep 20 11:20:48] DEBUG[16389] chan_sip.c: Line: a=fmtp:101 0-16 (15) [Sep 20 11:20:48] DEBUG[16389] chan_sip.c: Line: a=silenceSupp:off - - - - (25) [Sep 20 11:20:48] DEBUG[16389] chan_sip.c: Line: a=sendrecv (10) [Sep 20 11:20:48] VERBOSE[16389] logger.c: --- (18 headers 12 lines)[Sep 20 11:20:48] VERBOSE[16389] logger.c: --- [Sep 20 11:20:48] DEBUG[16389] chan_sip.c: Allocating new SIP dialog for 2a9c88374b5aa0a137682c7d76fced0b@63.77.68.164 - INVITE (With RTP) [Sep 20 11:20:48] DEBUG[16389] chan_sip.c: **** Received INVITE (5) - Command in SIP INVITE [Sep 20 11:20:48] DEBUG[16389] chan_sip.c: Begin: parsing SIP "Supported: replaces" [Sep 20 11:20:48] DEBUG[16389] chan_sip.c: Found SIP option: -replaces- [Sep 20 11:20:48] DEBUG[16389] chan_sip.c: Matched SIP option: replaces [Sep 20 11:20:48] DEBUG[16389] chan_sip.c: Begin: parsing SIP "Supported: replaces" [Sep 20 11:20:48] DEBUG[16389] chan_sip.c: Found SIP option: -replaces- [Sep 20 11:20:48] DEBUG[16389] chan_sip.c: Matched SIP option: replaces [Sep 20 11:20:48] DEBUG[16389] chan_sip.c: INVITE part of call transfer. Replaces [ec336c01-9a146537-d23e9134%4063.77.68.74%3bto-tag%3das3584d546%3bfrom-tag%3d5D56B5AD-B325DB0A] [Sep 20 11:20:48] DEBUG[16389] chan_sip.c: Invite/replaces: Will use Replace-Call-ID : ec336c01-9a146537-d23e9134@63.77.68.74 Fromtag: 5D56B5AD-B325DB0A Totag: as3584d546 [Sep 20 11:20:48] DEBUG[16389] chan_sip.c: Looking for callid ec336c01-9a146537-d23e9134@63.77.68.74 (fromtag 5D56B5AD-B325DB0A totag as3584d546) [Sep 20 11:20:48] DEBUG[16389] chan_sip.c: Matched INCOMING call - their tag is 5D56B5AD-B325DB0A Our tag is as3584d546 [Sep 20 11:20:48] VERBOSE[16389] logger.c: Sending to 63.77.68.165 : 5060 (no NAT) [Sep 20 11:20:48] VERBOSE[16389] logger.c: Using INVITE request as basis request - 2a9c88374b5aa0a137682c7d76fced0b@63.77.68.164 [Sep 20 11:20:48] DEBUG[16389] chan_sip.c: Setting NAT on RTP to Off [Sep 20 11:20:48] VERBOSE[16389] logger.c: Found user '3c68bcix3r0fw8xw' [Sep 20 11:20:48] VERBOSE[16389] logger.c: Found RTP audio format 0 [Sep 20 11:20:48] VERBOSE[16389] logger.c: Found RTP audio format 101 [Sep 20 11:20:48] VERBOSE[16389] logger.c: Peer audio RTP is at port 63.77.68.164:16940 [Sep 20 11:20:48] VERBOSE[16389] logger.c: Found description format PCMU for ID 0 [Sep 20 11:20:48] VERBOSE[16389] logger.c: Got unsupported a:ptime in SDP offer [Sep 20 11:20:48] VERBOSE[16389] logger.c: Found description format telephone-event for ID 101 [Sep 20 11:20:48] VERBOSE[16389] logger.c: Got unsupported a:fmtp in SDP offer [Sep 20 11:20:48] DEBUG[16389] chan_sip.c: T38 state changed to 0 on channel [Sep 20 11:20:48] VERBOSE[16389] logger.c: Capabilities: us - 0x4 (ulaw), peer - audio=0x4 (ulaw)/video=0x0 (nothing), combined - 0x4 (ulaw) [Sep 20 11:20:48] VERBOSE[16389] logger.c: Non-codec capabilities (dtmf): us - 0x1 (telephone-event), peer - 0x1 (telephone-event), combined - 0x1 (telephone-event) [Sep 20 11:20:48] VERBOSE[16389] logger.c: Peer audio RTP is at port 63.77.68.164:16940 [Sep 20 11:20:48] DEBUG[16389] chan_sip.c: We're settling with these formats: 0x4 (ulaw) [Sep 20 11:20:48] DEBUG[16389] chan_sip.c: Checking SIP call limits for device 3c68bcix3r0fw8xw [Sep 20 11:20:48] DEBUG[16389] chan_sip.c: Updating call counter for incoming call [Sep 20 11:20:48] VERBOSE[16389] logger.c: Looking for 751nv105 in default (domain 63.77.68.92) [Sep 20 11:20:48] DEBUG[16389] chan_sip.c: *** Our native formats are 0x4 (ulaw) [Sep 20 11:20:48] DEBUG[16389] chan_sip.c: *** Joint capabilities are 0x4 (ulaw) [Sep 20 11:20:48] DEBUG[16389] chan_sip.c: *** Our capabilities are 0x4 (ulaw) [Sep 20 11:20:48] DEBUG[16389] chan_sip.c: *** AST_CODEC_CHOOSE formats are 0x4 (ulaw) [Sep 20 11:20:48] DEBUG[16389] chan_sip.c: This channel will not be able to handle video. [Sep 20 11:20:48] DEBUG[16389] chan_sip.c: build_route: Record-Route hop: [Sep 20 11:20:48] VERBOSE[16389] logger.c: list_route: hop: [Sep 20 11:20:48] DEBUG[16389] chan_sip.c: Sending this call to the invite/replcaes handler 2a9c88374b5aa0a137682c7d76fced0b@63.77.68.164 [Sep 20 11:20:48] DEBUG[16389] chan_sip.c: SIP transfer: Invite Replace incoming channel should bridge to channel SIP/751nv105-0824e2d8 while hanging up channel SIP/714nv100-082496a0 [Sep 20 11:20:48] VERBOSE[16389] 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=z9hG4bK8365.bd275fa6.0;received=63.77.68.165 Via: SIP/2.0/UDP 63.77.68.164:5060;branch=z9hG4bK16800c14;rport=5060 From: "Jim Davis" ;tag=as6d8401c9 To: Call-ID: 2a9c88374b5aa0a137682c7d76fced0b@63.77.68.164 CSeq: 102 INVITE User-Agent: NuvioSoftSwitch Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Supported: replaces Contact: Content-Length: 0 --- [Sep 20 11:20:48] DEBUG[16389] chan_sip.c: Header 0: SIP/2.0 100 Trying (18) [Sep 20 11:20:48] DEBUG[16389] chan_sip.c: Header 1: Via: SIP/2.0/UDP 63.77.68.165;branch=z9hG4bK8365.bd275fa6.0;received=63.77.68.165 (81) [Sep 20 11:20:48] DEBUG[16389] chan_sip.c: Header 2: Via: SIP/2.0/UDP 63.77.68.164:5060;branch=z9hG4bK16800c14;rport=5060 (68) [Sep 20 11:20:48] DEBUG[16389] chan_sip.c: Header 3: From: "Jim Davis" ;tag=as6d8401c9 (68) [Sep 20 11:20:48] DEBUG[16389] chan_sip.c: Header 4: To: (31) [Sep 20 11:20:48] DEBUG[16389] chan_sip.c: Header 5: Call-ID: 2a9c88374b5aa0a137682c7d76fced0b@63.77.68.164 (54) [Sep 20 11:20:48] DEBUG[16389] chan_sip.c: Header 6: CSeq: 102 INVITE (16) [Sep 20 11:20:48] DEBUG[16389] chan_sip.c: Header 7: User-Agent: NuvioSoftSwitch (27) [Sep 20 11:20:48] DEBUG[16389] chan_sip.c: Header 8: Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY (66) [Sep 20 11:20:48] DEBUG[16389] chan_sip.c: Header 9: Supported: replaces (19) [Sep 20 11:20:48] DEBUG[16389] chan_sip.c: Header 10: Contact: (36) [Sep 20 11:20:48] DEBUG[16389] chan_sip.c: Header 11: Content-Length: 0 (17) [Sep 20 11:20:48] DEBUG[16389] chan_sip.c: Header 12: (0) [Sep 20 11:20:48] DEBUG[16389] devicestate.c: Notification of state change to be queued on device/channel SIP/3c68bcix3r0fw8xw-08254800 [Sep 20 11:20:48] DEBUG[16389] chan_sip.c: Setting framing from config on incoming call [Sep 20 11:20:48] DEBUG[16389] chan_sip.c: ** Our capability: 0x4 (ulaw) Video flag: True [Sep 20 11:20:48] DEBUG[16389] chan_sip.c: ** Our prefcodec: 0x0 (nothing) [Sep 20 11:20:48] VERBOSE[16389] logger.c: Audio is at 63.77.68.166 port 18902 [Sep 20 11:20:48] VERBOSE[16389] logger.c: Adding codec 0x4 (ulaw) to SDP [Sep 20 11:20:48] VERBOSE[16389] logger.c: Adding non-codec 0x1 (telephone-event) to SDP [Sep 20 11:20:48] DEBUG[16389] chan_sip.c: -- Done with adding codecs to SDP [Sep 20 11:20:48] DEBUG[16389] chan_sip.c: Done building SDP. Settling with this capability: 0x4 (ulaw) [Sep 20 11:20:48] VERBOSE[16389] logger.c: Reliably Transmitting (no NAT) to 63.77.68.165:5060: SIP/2.0 200 OK Via: SIP/2.0/UDP 63.77.68.165;branch=z9hG4bK8365.bd275fa6.0;received=63.77.68.165 Via: SIP/2.0/UDP 63.77.68.164:5060;branch=z9hG4bK16800c14;rport=5060 Record-Route: From: "Jim Davis" ;tag=as6d8401c9 To: ;tag=as15199f86 Call-ID: 2a9c88374b5aa0a137682c7d76fced0b@63.77.68.164 CSeq: 102 INVITE User-Agent: NuvioSoftSwitch Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Supported: replaces Contact: Content-Type: application/sdp Content-Length: 240 v=0 o=root 16363 16363 IN IP4 63.77.68.166 s=session c=IN IP4 63.77.68.166 t=0 0 m=audio 18902 RTP/AVP 0 101 a=rtpmap:0 PCMU/8000 a=ptime:20 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=silenceSupp:off - - - - a=sendrecv --- [Sep 20 11:20:48] DEBUG[16389] chan_sip.c: Header 0: SIP/2.0 200 OK (14) [Sep 20 11:20:48] DEBUG[16389] chan_sip.c: Header 1: Via: SIP/2.0/UDP 63.77.68.165;branch=z9hG4bK8365.bd275fa6.0;received=63.77.68.165 (81) [Sep 20 11:20:48] DEBUG[16389] chan_sip.c: Header 2: Via: SIP/2.0/UDP 63.77.68.164:5060;branch=z9hG4bK16800c14;rport=5060 (68) [Sep 20 11:20:48] DEBUG[16389] chan_sip.c: Header 3: Record-Route: (51) [Sep 20 11:20:48] DEBUG[16389] chan_sip.c: Header 4: From: "Jim Davis" ;tag=as6d8401c9 (68) [Sep 20 11:20:48] DEBUG[16389] chan_sip.c: Header 5: To: ;tag=as15199f86 (46) [Sep 20 11:20:48] DEBUG[16389] chan_sip.c: Header 6: Call-ID: 2a9c88374b5aa0a137682c7d76fced0b@63.77.68.164 (54) [Sep 20 11:20:48] DEBUG[16389] chan_sip.c: Header 7: CSeq: 102 INVITE (16) [Sep 20 11:20:48] DEBUG[16389] chan_sip.c: Header 8: User-Agent: NuvioSoftSwitch (27) [Sep 20 11:20:48] DEBUG[16389] chan_sip.c: Header 9: Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY (66) [Sep 20 11:20:48] DEBUG[16389] chan_sip.c: Header 10: Supported: replaces (19) [Sep 20 11:20:48] DEBUG[16389] chan_sip.c: Header 11: Contact: (36) [Sep 20 11:20:48] DEBUG[16389] chan_sip.c: Header 12: Content-Type: application/sdp (29) [Sep 20 11:20:48] DEBUG[16389] chan_sip.c: Header 13: Content-Length: 240 (19) [Sep 20 11:20:48] DEBUG[16389] chan_sip.c: Header 14: (0) [Sep 20 11:20:48] DEBUG[16389] chan_sip.c: Line: v=0 (3) [Sep 20 11:20:48] DEBUG[16389] chan_sip.c: Line: o=root 16363 16363 IN IP4 63.77.68.166 (38) [Sep 20 11:20:48] DEBUG[16389] chan_sip.c: Line: s=session (9) [Sep 20 11:20:48] DEBUG[16389] chan_sip.c: Line: c=IN IP4 63.77.68.166 (21) [Sep 20 11:20:48] DEBUG[16389] chan_sip.c: Line: t=0 0 (5) [Sep 20 11:20:48] DEBUG[16389] chan_sip.c: Line: m=audio 18902 RTP/AVP 0 101 (27) [Sep 20 11:20:48] DEBUG[16389] chan_sip.c: Line: a=rtpmap:0 PCMU/8000 (20) [Sep 20 11:20:48] DEBUG[16389] chan_sip.c: Line: a=ptime:20 (10) [Sep 20 11:20:48] DEBUG[16389] chan_sip.c: Line: a=rtpmap:101 telephone-event/8000 (33) [Sep 20 11:20:48] DEBUG[16389] chan_sip.c: Line: a=fmtp:101 0-16 (15) [Sep 20 11:20:48] DEBUG[16389] chan_sip.c: Line: a=silenceSupp:off - - - - (25) [Sep 20 11:20:48] DEBUG[16389] chan_sip.c: Line: a=sendrecv (10) [Sep 20 11:20:48] DEBUG[16389] chan_sip.c: *** SIP TIMER: Initalizing retransmit timer on packet: Id #25 [Sep 20 11:20:48] DEBUG[16389] devicestate.c: Notification of state change to be queued on device/channel SIP/3c68bcix3r0fw8xw-08254800 [Sep 20 11:20:48] DEBUG[16389] chan_sip.c: Invite/Replaces: preparing to masquerade SIP/3c68bcix3r0fw8xw-08254800 into SIP/714nv100-082496a0 [Sep 20 11:20:48] DEBUG[16389] channel.c: Planning to masquerade channel SIP/3c68bcix3r0fw8xw-08254800 into the structure of SIP/714nv100-082496a0 [Sep 20 11:20:48] DEBUG[16389] channel.c: Done planning to masquerade channel SIP/3c68bcix3r0fw8xw-08254800 into the structure of SIP/714nv100-082496a0 [Sep 20 11:20:48] DEBUG[16389] chan_sip.c: Invite/Replaces: Going to masquerade SIP/3c68bcix3r0fw8xw-08254800 into SIP/714nv100-082496a0 [Sep 20 11:20:48] DEBUG[16389] channel.c: Actually Masquerading SIP/3c68bcix3r0fw8xw-08254800(6) into the structure of SIP/714nv100-082496a0(6) [Sep 20 11:20:48] DEBUG[16389] channel.c: Got clone lock for masquerade on 'SIP/3c68bcix3r0fw8xw-08254800' at 0x825ca00 [Sep 20 11:20:48] DEBUG[16389] chan_sip.c: SIP Fixup: New owner for dialogue ec336c01-9a146537-d23e9134@63.77.68.74: SIP/3c68bcix3r0fw8xw-08254800 (Old parent: SIP/3c68bcix3r0fw8xw-08254800) [Sep 20 11:20:48] DEBUG[16389] chan_sip.c: SIP Transfer: Not hanging up right now... Rescheduling hangup for ec336c01-9a146537-d23e9134@63.77.68.74. [Sep 20 11:20:48] VERBOSE[16389] logger.c: Scheduling destruction of SIP dialog 'ec336c01-9a146537-d23e9134@63.77.68.74' in 32000 ms (Method: ACK) [Sep 20 11:20:48] DEBUG[16389] channel.c: Putting channel SIP/3c68bcix3r0fw8xw-08254800 in 4/4 formats [Sep 20 11:20:48] DEBUG[16389] chan_sip.c: SIP Fixup: New owner for dialogue 2a9c88374b5aa0a137682c7d76fced0b@63.77.68.164: SIP/3c68bcix3r0fw8xw-08254800 (Old parent: SIP/714nv100-082496a0) [Sep 20 11:20:48] DEBUG[16389] channel.c: Released clone lock on 'SIP/714nv100-082496a0' [Sep 20 11:20:48] DEBUG[16389] channel.c: Done Masquerading SIP/3c68bcix3r0fw8xw-08254800 (6) [Sep 20 11:20:48] DEBUG[16389] chan_sip.c: Invite/Replace: Could successfully read frame from channel! Masq done. [Sep 20 11:20:48] DEBUG[16389] devicestate.c: Notification of state change to be queued on device/channel SIP/714nv100-082496a0 [Sep 20 11:20:48] DEBUG[16389] chan_sip.c: After transfer:---------------------------- [Sep 20 11:20:48] DEBUG[16389] chan_sip.c: -- C: SIP/714nv100-082496a0 State Down [Sep 20 11:20:48] DEBUG[16389] chan_sip.c: -- replacecall: SIP/3c68bcix3r0fw8xw-08254800 State Up [Sep 20 11:20:48] DEBUG[16389] chan_sip.c: -- P->owner: SIP/3c68bcix3r0fw8xw-08254800 State Up [Sep 20 11:20:48] DEBUG[16389] chan_sip.c: -- Call bridged to P->owner: SIP/751nv105-0824e2d8 State Up [Sep 20 11:20:48] DEBUG[16389] chan_sip.c: End After transfer:---------------------------- [Sep 20 11:20:48] DEBUG[16389] channel.c: Hanging up zombie 'SIP/714nv100-082496a0' [Sep 20 11:20:48] DEBUG[16389] devicestate.c: Notification of state change to be queued on device/channel SIP/714nv100-082496a0 [Sep 20 11:20:48] DEBUG[16368] devicestate.c: No provider found, checking channel drivers for SIP - 3c68bcix3r0fw8xw [Sep 20 11:20:48] DEBUG[16368] chan_sip.c: Checking device state for peer 3c68bcix3r0fw8xw [Sep 20 11:20:48] DEBUG[16368] devicestate.c: Changing state for SIP/3c68bcix3r0fw8xw - state 1 (Not in use) [Sep 20 11:20:48] DEBUG[16368] devicestate.c: No provider found, checking channel drivers for SIP - 3c68bcix3r0fw8xw [Sep 20 11:20:48] DEBUG[16368] chan_sip.c: Checking device state for peer 3c68bcix3r0fw8xw [Sep 20 11:20:48] DEBUG[16368] devicestate.c: No provider found, checking channel drivers for SIP - 3c68bcix3r0fw8xw [Sep 20 11:20:48] DEBUG[16368] chan_sip.c: Checking device state for peer 3c68bcix3r0fw8xw [Sep 20 11:20:48] DEBUG[16368] devicestate.c: Changing state for SIP/3c68bcix3r0fw8xw - state 1 (Not in use) [Sep 20 11:20:48] DEBUG[16368] devicestate.c: No provider found, checking channel drivers for SIP - 3c68bcix3r0fw8xw [Sep 20 11:20:48] DEBUG[16368] chan_sip.c: Checking device state for peer 3c68bcix3r0fw8xw [Sep 20 11:20:48] DEBUG[16368] devicestate.c: No provider found, checking channel drivers for SIP - 714nv100 [Sep 20 11:20:48] DEBUG[16368] chan_sip.c: Checking device state for peer 714nv100 [Sep 20 11:20:48] DEBUG[16368] devicestate.c: Changing state for SIP/714nv100 - state 1 (Not in use) [Sep 20 11:20:48] DEBUG[16368] devicestate.c: No provider found, checking channel drivers for SIP - 714nv100 [Sep 20 11:20:48] DEBUG[16368] chan_sip.c: Checking device state for peer 714nv100 [Sep 20 11:20:48] DEBUG[16368] devicestate.c: No provider found, checking channel drivers for SIP - 714nv100 [Sep 20 11:20:48] DEBUG[16368] chan_sip.c: Checking device state for peer 714nv100 [Sep 20 11:20:48] DEBUG[16368] devicestate.c: Changing state for SIP/714nv100 - state 1 (Not in use) [Sep 20 11:20:48] DEBUG[16368] devicestate.c: No provider found, checking channel drivers for SIP - 714nv100 [Sep 20 11:20:48] DEBUG[16368] chan_sip.c: Checking device state for peer 714nv100 [Sep 20 11:20:48] DEBUG[16401] rtp.c: Oooh, something is weird, backing out [Sep 20 11:20:48] VERBOSE[16401] logger.c: -- Packet2Packet bridging SIP/3c68bcix3r0fw8xw-08254800 and SIP/751nv105-0824e2d8 [Sep 20 11:20:48] DEBUG[16406] app_queue.c: Device 'SIP/3c68bcix3r0fw8xw' changed to state '1' (Not in use) but we don't care because they're not a member of any queue. [Sep 20 11:20:48] VERBOSE[16389] logger.c: <-- SIP read from 63.77.68.165:5060: ACK sip:751nv105@63.77.68.166 SIP/2.0 Record-Route: Via: SIP/2.0/UDP 63.77.68.165;branch=0 Via: SIP/2.0/UDP 63.77.68.164:5060;branch=z9hG4bK024650bf;rport=5060 From: "Jim Davis" ;tag=as6d8401c9 To: ;tag=as15199f86 Contact: Call-ID: 2a9c88374b5aa0a137682c7d76fced0b@63.77.68.164 CSeq: 102 ACK User-Agent: NuvioSoftSwitch Max-Forwards: 16 Content-Length: 0 P-hint: lr enforced [Sep 20 11:20:48] DEBUG[16389] chan_sip.c: Header 0: ACK sip:751nv105@63.77.68.166 SIP/2.0 (37) [Sep 20 11:20:48] DEBUG[16389] chan_sip.c: Header 1: Record-Route: (51) [Sep 20 11:20:48] DEBUG[16389] chan_sip.c: Header 2: Via: SIP/2.0/UDP 63.77.68.165;branch=0 (38) [Sep 20 11:20:48] DEBUG[16389] chan_sip.c: Header 3: Via: SIP/2.0/UDP 63.77.68.164:5060;branch=z9hG4bK024650bf;rport=5060 (68) [Sep 20 11:20:48] DEBUG[16389] chan_sip.c: Header 4: From: "Jim Davis" ;tag=as6d8401c9 (68) [Sep 20 11:20:48] DEBUG[16389] chan_sip.c: Header 5: To: ;tag=as15199f86 (46) [Sep 20 11:20:48] DEBUG[16389] chan_sip.c: Header 6: Contact: (44) [Sep 20 11:20:48] DEBUG[16389] chan_sip.c: Header 7: Call-ID: 2a9c88374b5aa0a137682c7d76fced0b@63.77.68.164 (54) [Sep 20 11:20:48] DEBUG[16389] chan_sip.c: Header 8: CSeq: 102 ACK (13) [Sep 20 11:20:48] DEBUG[16389] chan_sip.c: Header 9: User-Agent: NuvioSoftSwitch (27) [Sep 20 11:20:48] DEBUG[16389] chan_sip.c: Header 10: Max-Forwards: 16 (16) [Sep 20 11:20:48] DEBUG[16389] chan_sip.c: Header 11: Content-Length: 0 (17) [Sep 20 11:20:48] DEBUG[16389] chan_sip.c: Header 12: P-hint: lr enforced (19) [Sep 20 11:20:48] DEBUG[16389] chan_sip.c: Header 13: (0) [Sep 20 11:20:48] VERBOSE[16389] logger.c: --- (13 headers 0 lines)[Sep 20 11:20:48] VERBOSE[16389] logger.c: --- [Sep 20 11:20:48] DEBUG[16389] chan_sip.c: **** Received ACK (6) - Command in SIP ACK [Sep 20 11:20:48] DEBUG[16389] chan_sip.c: ** SIP TIMER: Cancelling retransmit of packet (reply received) Retransid #25 [Sep 20 11:20:48] DEBUG[16389] chan_sip.c: Stopping retransmission on '2a9c88374b5aa0a137682c7d76fced0b@63.77.68.164' of Response 102: Match Not Found [Sep 20 11:20:48] DEBUG[16407] app_queue.c: Device 'SIP/3c68bcix3r0fw8xw' changed to state '1' (Not in use) but we don't care because they're not a member of any queue. [Sep 20 11:20:48] DEBUG[16408] app_queue.c: Device 'SIP/714nv100' changed to state '1' (Not in use) but we don't care because they're not a member of any queue. [Sep 20 11:20:48] DEBUG[16409] app_queue.c: Device 'SIP/714nv100' changed to state '1' (Not in use) but we don't care because they're not a member of any queue. [Sep 20 11:20:54] VERBOSE[16389] logger.c: <-- SIP read from 63.77.68.165:5060: BYE sip:714nv100@63.77.68.166:5060;ftag=as34aa4a8c SIP/2.0 Record-Route: Via: SIP/2.0/UDP 63.77.68.165;branch=z9hG4bK4f9.9dbe4562.0 Via: SIP/2.0/UDP 63.77.68.92:5060;branch=z9hG4bK4c7f0b38 From: ;tag=0003e373741706d613ec3567-2d82e847 To: "Sally Baker" ;tag=as34aa4a8c Call-ID: 36d029045ccd063e08e6b6587c208c77@63.77.68.166 Max-Forwards: 16 Date: Wed, 20 Sep 2006 16:20:59 GMT CSeq: 101 BYE User-Agent: Cisco-CP7960G/7.5 Content-Length: 0 P-hint: lr enforced [Sep 20 11:20:54] DEBUG[16389] chan_sip.c: Header 0: BYE sip:714nv100@63.77.68.166:5060;ftag=as34aa4a8c SIP/2.0 (58) [Sep 20 11:20:54] DEBUG[16389] chan_sip.c: Header 1: Record-Route: (74) [Sep 20 11:20:54] DEBUG[16389] chan_sip.c: Header 2: Via: SIP/2.0/UDP 63.77.68.165;branch=z9hG4bK4f9.9dbe4562.0 (58) [Sep 20 11:20:54] DEBUG[16389] chan_sip.c: Header 3: Via: SIP/2.0/UDP 63.77.68.92:5060;branch=z9hG4bK4c7f0b38 (56) [Sep 20 11:20:54] DEBUG[16389] chan_sip.c: Header 4: From: ;tag=0003e373741706d613ec3567-2d82e847 (71) [Sep 20 11:20:54] DEBUG[16389] chan_sip.c: Header 5: To: "Sally Baker" ;tag=as34aa4a8c (60) [Sep 20 11:20:54] DEBUG[16389] chan_sip.c: Header 6: Call-ID: 36d029045ccd063e08e6b6587c208c77@63.77.68.166 (54) [Sep 20 11:20:54] DEBUG[16389] chan_sip.c: Header 7: Max-Forwards: 16 (16) [Sep 20 11:20:54] DEBUG[16389] chan_sip.c: Header 8: Date: Wed, 20 Sep 2006 16:20:59 GMT (35) [Sep 20 11:20:54] DEBUG[16389] chan_sip.c: Header 9: CSeq: 101 BYE (13) [Sep 20 11:20:54] DEBUG[16389] chan_sip.c: Header 10: User-Agent: Cisco-CP7960G/7.5 (29) [Sep 20 11:20:54] DEBUG[16389] chan_sip.c: Header 11: Content-Length: 0 (17) [Sep 20 11:20:54] DEBUG[16389] chan_sip.c: Header 12: P-hint: lr enforced (19) [Sep 20 11:20:54] DEBUG[16389] chan_sip.c: Header 13: (0) [Sep 20 11:20:54] VERBOSE[16389] logger.c: --- (13 headers 0 lines)[Sep 20 11:20:54] VERBOSE[16389] logger.c: --- [Sep 20 11:20:54] DEBUG[16389] chan_sip.c: **** Received BYE (8) - Command in SIP BYE [Sep 20 11:20:54] VERBOSE[16389] logger.c: Sending to 63.77.68.165 : 5060 (no NAT) [Sep 20 11:20:54] DEBUG[16389] chan_sip.c: Received bye, issuing owner hangup .[Sep 20 11:20:54] VERBOSE[16389] 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=z9hG4bK4f9.9dbe4562.0;received=63.77.68.165 Via: SIP/2.0/UDP 63.77.68.92:5060;branch=z9hG4bK4c7f0b38 Record-Route: From: ;tag=0003e373741706d613ec3567-2d82e847 To: "Sally Baker" ;tag=as34aa4a8c Call-ID: 36d029045ccd063e08e6b6587c208c77@63.77.68.166 CSeq: 101 BYE User-Agent: NuvioSoftSwitch Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Supported: replaces Contact: Content-Length: 0 --- [Sep 20 11:20:54] DEBUG[16389] chan_sip.c: Header 0: SIP/2.0 200 OK (14) [Sep 20 11:20:54] DEBUG[16389] chan_sip.c: Header 1: Via: SIP/2.0/UDP 63.77.68.165;branch=z9hG4bK4f9.9dbe4562.0;received=63.77.68.165 (80) [Sep 20 11:20:54] DEBUG[16389] chan_sip.c: Header 2: Via: SIP/2.0/UDP 63.77.68.92:5060;branch=z9hG4bK4c7f0b38 (56) [Sep 20 11:20:54] DEBUG[16389] chan_sip.c: Header 3: Record-Route: (74) [Sep 20 11:20:54] DEBUG[16389] chan_sip.c: Header 4: From: ;tag=0003e373741706d613ec3567-2d82e847 (71) [Sep 20 11:20:54] DEBUG[16389] chan_sip.c: Header 5: To: "Sally Baker" ;tag=as34aa4a8c (60) [Sep 20 11:20:54] DEBUG[16389] chan_sip.c: Header 6: Call-ID: 36d029045ccd063e08e6b6587c208c77@63.77.68.166 (54) [Sep 20 11:20:54] DEBUG[16389] chan_sip.c: Header 7: CSeq: 101 BYE (13) [Sep 20 11:20:54] DEBUG[16389] chan_sip.c: Header 8: User-Agent: NuvioSoftSwitch (27) [Sep 20 11:20:54] DEBUG[16389] chan_sip.c: Header 9: Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY (66) [Sep 20 11:20:54] DEBUG[16389] chan_sip.c: Header 10: Supported: replaces (19) [Sep 20 11:20:54] DEBUG[16389] chan_sip.c: Header 11: Contact: (36) [Sep 20 11:20:54] DEBUG[16389] chan_sip.c: Header 12: Content-Length: 0 (17) [Sep 20 11:20:54] DEBUG[16389] chan_sip.c: Header 13: (0) [Sep 20 11:20:54] DEBUG[16401] rtp.c: Oooh, got a hangup [Sep 20 11:20:54] DEBUG[16401] channel.c: Returning from native bridge, channels: SIP/3c68bcix3r0fw8xw-08254800, SIP/751nv105-0824e2d8 [Sep 20 11:20:54] DEBUG[16401] channel.c: Hanging up channel 'SIP/751nv105-0824e2d8' [Sep 20 11:20:54] DEBUG[16401] chan_sip.c: Hangup call SIP/751nv105-0824e2d8, SIP callid 36d029045ccd063e08e6b6587c208c77@63.77.68.166) [Sep 20 11:20:54] DEBUG[16401] chan_sip.c: update_call_counter(751nv105) - decrement call limit counter on hangup [Sep 20 11:20:54] DEBUG[16401] chan_sip.c: Updating call counter for outgoing call [Sep 20 11:20:54] DEBUG[16401] devicestate.c: Notification of state change to be queued on device/channel SIP/751nv105-0824e2d8 [Sep 20 11:20:54] DEBUG[16401] rtp.c: Channel '' has no RTP, not doing anything [Sep 20 11:20:54] DEBUG[16401] app_dial.c: Exiting with DIALSTATUS=ANSWER. [Sep 20 11:20:54] DEBUG[16401] pbx.c: Spawn extension (default,1002,1) exited non-zero on 'SIP/3c68bcix3r0fw8xw-08254800' [Sep 20 11:20:54] VERBOSE[16401] logger.c: == Spawn extension (default, 1002, 1) exited non-zero on 'SIP/3c68bcix3r0fw8xw-08254800' [Sep 20 11:20:54] DEBUG[16401] pbx.c: Function result is '"Jim Davis" <3c68bcix3r0fw8xw>' [Sep 20 11:20:54] DEBUG[16401] pbx.c: Function result is '3c68bcix3r0fw8xw' [Sep 20 11:20:54] DEBUG[16401] pbx.c: Function result is '1002' [Sep 20 11:20:54] DEBUG[16401] pbx.c: Function result is 'default' [Sep 20 11:20:54] DEBUG[16401] pbx.c: Function result is 'SIP/714nv100-082496a0' [Sep 20 11:20:54] DEBUG[16401] pbx.c: Function result is 'SIP/751nv105-0824e2d8' [Sep 20 11:20:54] DEBUG[16401] pbx.c: Function result is 'Dial' [Sep 20 11:20:54] DEBUG[16401] pbx.c: Function result is 'SIP/751nv105' [Sep 20 11:20:54] DEBUG[16401] pbx.c: Function result is '2006-09-20 11:20:41' [Sep 20 11:20:54] DEBUG[16401] pbx.c: Function result is '2006-09-20 11:20:44' [Sep 20 11:20:54] DEBUG[16401] pbx.c: Function result is '2006-09-20 11:20:54' [Sep 20 11:20:54] DEBUG[16401] pbx.c: Function result is '13' [Sep 20 11:20:54] DEBUG[16401] pbx.c: Function result is '10' [Sep 20 11:20:54] DEBUG[16401] pbx.c: Function result is 'ANSWERED' [Sep 20 11:20:54] DEBUG[16401] pbx.c: Function result is 'DOCUMENTATION' [Sep 20 11:20:54] DEBUG[16401] pbx.c: Function result is '' [Sep 20 11:20:54] DEBUG[16401] pbx.c: Function result is '1158769241.2' [Sep 20 11:20:54] DEBUG[16401] pbx.c: Function result is '' [Sep 20 11:20:54] DEBUG[16401] channel.c: Hanging up channel 'SIP/3c68bcix3r0fw8xw-08254800' [Sep 20 11:20:54] DEBUG[16401] chan_sip.c: Hangup call SIP/3c68bcix3r0fw8xw-08254800, SIP callid 2a9c88374b5aa0a137682c7d76fced0b@63.77.68.164) [Sep 20 11:20:54] DEBUG[16401] chan_sip.c: update_call_counter(3c68bcix3r0fw8xw) - decrement call limit counter on hangup [Sep 20 11:20:54] DEBUG[16401] chan_sip.c: Updating call counter for incoming call [Sep 20 11:20:54] VERBOSE[16401] logger.c: Scheduling destruction of SIP dialog '2a9c88374b5aa0a137682c7d76fced0b@63.77.68.164' in 32000 ms (Method: ACK) [Sep 20 11:20:54] VERBOSE[16401] logger.c: set_destination: Parsing for address/port to send to [Sep 20 11:20:54] VERBOSE[16401] logger.c: set_destination: set destination to 63.77.68.165, port 5060 [Sep 20 11:20:54] VERBOSE[16401] logger.c: Reliably Transmitting (no NAT) to 63.77.68.165:5060: BYE sip:3c68bcix3r0fw8xw@63.77.68.164 SIP/2.0 Via: SIP/2.0/UDP 63.77.68.166:5060;branch=z9hG4bK78fa5620;rport Route: From: ;tag=as15199f86 To: "Jim Davis" ;tag=as6d8401c9 Contact: Call-ID: 2a9c88374b5aa0a137682c7d76fced0b@63.77.68.164 CSeq: 102 BYE User-Agent: NuvioSoftSwitch Max-Forwards: 70 Content-Length: 0 --- [Sep 20 11:20:54] DEBUG[16401] chan_sip.c: Header 0: BYE sip:3c68bcix3r0fw8xw@63.77.68.164 SIP/2.0 (45) [Sep 20 11:20:54] DEBUG[16401] chan_sip.c: Header 1: Via: SIP/2.0/UDP 63.77.68.166:5060;branch=z9hG4bK78fa5620;rport (63) [Sep 20 11:20:54] DEBUG[16401] chan_sip.c: Header 2: Route: (44) [Sep 20 11:20:54] DEBUG[16401] chan_sip.c: Header 3: From: ;tag=as15199f86 (48) [Sep 20 11:20:54] DEBUG[16401] chan_sip.c: Header 4: To: "Jim Davis" ;tag=as6d8401c9 (66) [Sep 20 11:20:54] DEBUG[16401] chan_sip.c: Header 5: Contact: (36) [Sep 20 11:20:54] DEBUG[16401] chan_sip.c: Header 6: Call-ID: 2a9c88374b5aa0a137682c7d76fced0b@63.77.68.164 (54) [Sep 20 11:20:54] DEBUG[16401] chan_sip.c: Header 7: CSeq: 102 BYE (13) [Sep 20 11:20:54] DEBUG[16401] chan_sip.c: Header 8: User-Agent: NuvioSoftSwitch (27) [Sep 20 11:20:54] DEBUG[16401] chan_sip.c: Header 9: Max-Forwards: 70 (16) [Sep 20 11:20:54] DEBUG[16401] chan_sip.c: Header 10: Content-Length: 0 (17) [Sep 20 11:20:54] DEBUG[16401] chan_sip.c: Header 11: (0) [Sep 20 11:20:54] DEBUG[16401] chan_sip.c: *** SIP TIMER: Initalizing retransmit timer on packet: Id #28 [Sep 20 11:20:54] DEBUG[16401] devicestate.c: Notification of state change to be queued on device/channel SIP/3c68bcix3r0fw8xw-08254800 [Sep 20 11:20:54] DEBUG[16368] devicestate.c: No provider found, checking channel drivers for SIP - 751nv105 [Sep 20 11:20:54] DEBUG[16368] chan_sip.c: Checking device state for peer 751nv105 [Sep 20 11:20:54] DEBUG[16368] devicestate.c: Changing state for SIP/751nv105 - state 1 (Not in use) [Sep 20 11:20:54] DEBUG[16368] devicestate.c: No provider found, checking channel drivers for SIP - 751nv105 [Sep 20 11:20:54] DEBUG[16368] chan_sip.c: Checking device state for peer 751nv105 [Sep 20 11:20:54] DEBUG[16368] devicestate.c: No provider found, checking channel drivers for SIP - 3c68bcix3r0fw8xw [Sep 20 11:20:54] DEBUG[16368] chan_sip.c: Checking device state for peer 3c68bcix3r0fw8xw [Sep 20 11:20:54] DEBUG[16368] devicestate.c: Changing state for SIP/3c68bcix3r0fw8xw - state 1 (Not in use) [Sep 20 11:20:54] DEBUG[16410] app_queue.c: Device 'SIP/751nv105' changed to state '1' (Not in use) but we don't care because they're not a member of any queue. [Sep 20 11:20:54] VERBOSE[16389] logger.c: <-- SIP read from 63.77.68.165:5060: SIP/2.0 200 OK Via: SIP/2.0/UDP 63.77.68.166:5060;branch=z9hG4bK78fa5620;rport=5060 Record-Route: From: ;tag=as15199f86 To: "Jim Davis" ;tag=as6d8401c9 Call-ID: 2a9c88374b5aa0a137682c7d76fced0b@63.77.68.164 CSeq: 102 BYE User-Agent: NuvioSoftSwitch Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Supported: replaces Contact: Content-Length: 0 [Sep 20 11:20:54] DEBUG[16389] chan_sip.c: Header 0: SIP/2.0 200 OK (14) [Sep 20 11:20:54] DEBUG[16389] chan_sip.c: Header 1: Via: SIP/2.0/UDP 63.77.68.166:5060;branch=z9hG4bK78fa5620;rport=5060 (68) [Sep 20 11:20:54] DEBUG[16389] chan_sip.c: Header 2: Record-Route: (51) [Sep 20 11:20:54] DEBUG[16389] chan_sip.c: Header 3: From: ;tag=as15199f86 (48) [Sep 20 11:20:54] DEBUG[16389] chan_sip.c: Header 4: To: "Jim Davis" ;tag=as6d8401c9 (66) [Sep 20 11:20:54] DEBUG[16389] chan_sip.c: Header 5: Call-ID: 2a9c88374b5aa0a137682c7d76fced0b@63.77.68.164 (54) [Sep 20 11:20:54] DEBUG[16389] chan_sip.c: Header 6: CSeq: 102 BYE (13) [Sep 20 11:20:54] DEBUG[16389] chan_sip.c: Header 7: User-Agent: NuvioSoftSwitch (27) [Sep 20 11:20:54] DEBUG[16389] chan_sip.c: Header 8: Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY (66) [Sep 20 11:20:54] DEBUG[16389] chan_sip.c: Header 9: Supported: replaces (19) [Sep 20 11:20:54] DEBUG[16389] chan_sip.c: Header 10: Contact: (44) [Sep 20 11:20:54] DEBUG[16389] chan_sip.c: Header 11: Content-Length: 0 (17) [Sep 20 11:20:54] DEBUG[16389] chan_sip.c: Header 12: (0) [Sep 20 11:20:54] VERBOSE[16389] logger.c: --- (12 headers 0 lines)[Sep 20 11:20:54] VERBOSE[16389] logger.c: --- [Sep 20 11:20:54] DEBUG[16389] chan_sip.c: ** SIP TIMER: Cancelling retransmit of packet (reply received) Retransid #28 [Sep 20 11:20:54] DEBUG[16389] chan_sip.c: Stopping retransmission on '2a9c88374b5aa0a137682c7d76fced0b@63.77.68.164' of Request 102: Match Not Found [Sep 20 11:20:54] VERBOSE[16389] logger.c: SIP Response message for INCOMING dialog BYE arrived [Sep 20 11:20:54] VERBOSE[16389] logger.c: Really destroying SIP dialog '2a9c88374b5aa0a137682c7d76fced0b@63.77.68.164' Method: ACK [Sep 20 11:20:54] DEBUG[16389] chan_sip.c: ---------- SIP HISTORY for '2a9c88374b5aa0a137682c7d76fced0b@63.77.68.164' [Sep 20 11:20:54] DEBUG[16389] chan_sip.c: * SIP Call [Sep 20 11:20:54] DEBUG[16389] chan_sip.c: 001. Rx INVITE / 102 INVITE / sip:751nv105@63.77.68.92:5060 [Sep 20 11:20:54] DEBUG[16389] chan_sip.c: 002. Invite New call: 2a9c88374b5aa0a137682c7d76fced0b@63.77.68.164 [Sep 20 11:20:54] DEBUG[16389] chan_sip.c: 003. NewChan Channel SIP/3c68bcix3r0fw8xw-08254800 - from 2a9c88374b5aa0a137 [Sep 20 11:20:54] DEBUG[16389] chan_sip.c: 004. Xfer INVITE/Replace received [Sep 20 11:20:54] DEBUG[16389] chan_sip.c: 005. TxResp SIP/2.0 / 102 INVITE - 100 Trying [Sep 20 11:20:54] DEBUG[16389] chan_sip.c: 006. TxRespRel SIP/2.0 / 102 INVITE - 200 OK [Sep 20 11:20:54] DEBUG[16389] chan_sip.c: 007. Masq Old channel: SIP/714nv100-082496a0 [Sep 20 11:20:54] DEBUG[16389] chan_sip.c: 008. Masq (cont) ...new owner: SIP/3c68bcix3r0fw8xw-08254800 [Sep 20 11:20:54] DEBUG[16389] chan_sip.c: 009. Rx ACK / 102 ACK / sip:751nv105@63.77.68.166 [Sep 20 11:20:54] DEBUG[16389] chan_sip.c: 010. SchedDestroy 32000 ms [Sep 20 11:20:54] DEBUG[16389] chan_sip.c: 011. TxReqRel BYE / 102 BYE - -UNKNOWN- [Sep 20 11:20:54] DEBUG[16389] chan_sip.c: 012. RTCPaudio Quality:ssrc=595299121;themssrc=0;lp=0;rxjitter=0.000000;rxcoun [Sep 20 11:20:54] DEBUG[16389] chan_sip.c: 013. Rx SIP/2.0 / 102 BYE / 200 OK [Sep 20 11:20:54] DEBUG[16389] chan_sip.c: ---------- END SIP HISTORY for '2a9c88374b5aa0a137682c7d76fced0b@63.77.68.164' [Sep 20 11:20:54] VERBOSE[16389] logger.c: Really destroying SIP dialog '36d029045ccd063e08e6b6587c208c77@63.77.68.166' Method: BYE [Sep 20 11:20:54] DEBUG[16389] chan_sip.c: ---------- SIP HISTORY for '36d029045ccd063e08e6b6587c208c77@63.77.68.166' [Sep 20 11:20:54] DEBUG[16389] chan_sip.c: * SIP Call [Sep 20 11:20:54] DEBUG[16389] chan_sip.c: 001. NewChan Channel SIP/751nv105-0824e2d8 - from 36d029045ccd063e08e6b6587c [Sep 20 11:20:54] DEBUG[16389] chan_sip.c: 002. TxReqRel INVITE / 102 INVITE - -UNKNOWN- [Sep 20 11:20:54] DEBUG[16389] chan_sip.c: 003. Rx SIP/2.0 / 102 INVITE / 100 trying -- your call is important to [Sep 20 11:20:54] DEBUG[16389] chan_sip.c: 004. Rx SIP/2.0 / 102 INVITE / 180 Ringing [Sep 20 11:20:54] DEBUG[16389] chan_sip.c: 005. Rx SIP/2.0 / 102 INVITE / 200 OK [Sep 20 11:20:54] DEBUG[16389] chan_sip.c: 006. TxReq ACK / 102 ACK - -UNKNOWN- [Sep 20 11:20:54] DEBUG[16389] chan_sip.c: 007. Rx BYE / 101 BYE / sip:714nv100@63.77.68.166:5060;ftag=as34aa4a8c [Sep 20 11:20:54] DEBUG[16389] chan_sip.c: 008. RTCPaudio Quality:ssrc=1615682878;themssrc=0;lp=0;rxjitter=0.000000;rxcou [Sep 20 11:20:54] DEBUG[16389] chan_sip.c: 009. TxResp SIP/2.0 / 101 BYE - 200 OK [Sep 20 11:20:54] DEBUG[16389] chan_sip.c: ---------- END SIP HISTORY for '36d029045ccd063e08e6b6587c208c77@63.77.68.166' [Sep 20 11:20:54] DEBUG[16368] devicestate.c: No provider found, checking channel drivers for SIP - 3c68bcix3r0fw8xw [Sep 20 11:20:54] DEBUG[16368] chan_sip.c: Checking device state for peer 3c68bcix3r0fw8xw [Sep 20 11:20:54] DEBUG[16411] app_queue.c: Device 'SIP/3c68bcix3r0fw8xw' changed to state '1' (Not in use) but we don't care because they're not a member of any queue. [Sep 20 11:20:56] VERBOSE[16389] logger.c: <-- SIP read from 63.77.68.165:5060: BYE sip:1002@63.77.68.166 SIP/2.0 Record-Route: Via: SIP/2.0/UDP 63.77.68.165;branch=z9hG4bK4af3.60696e44.0 Via: SIP/2.0/UDP 63.77.68.74;branch=z9hG4bK13ea00b1AC4CFAE4 From: "Sally Baker" ;tag=5D56B5AD-B325DB0A To: ;tag=as3584d546 CSeq: 3 BYE Call-ID: ec336c01-9a146537-d23e9134@63.77.68.74 Contact: User-Agent: PolycomSoundPointIP-SPIP_500-UA/2.0.1.0291 Proxy-Authorization: Digest username="714nv100", realm="nuvio.com", nonce="45116b85ae460a9b5e1a73bbdd3a3dd8f8ba8ef6", uri="sip:1002@proxy-test.sip.nuvio.net;user=phone;transport=udp", response="1c18bfa596451ecc2d923b0fcd869783", algorithm=MD5 Max-Forwards: 16 Content-Length: 0 P-hint: lr enforced [Sep 20 11:20:56] DEBUG[16389] chan_sip.c: Header 0: BYE sip:1002@63.77.68.166 SIP/2.0 (33) [Sep 20 11:20:56] DEBUG[16389] chan_sip.c: Header 1: Record-Route: (58) [Sep 20 11:20:56] DEBUG[16389] chan_sip.c: Header 2: Via: SIP/2.0/UDP 63.77.68.165;branch=z9hG4bK4af3.60696e44.0 (59) [Sep 20 11:20:56] DEBUG[16389] chan_sip.c: Header 3: Via: SIP/2.0/UDP 63.77.68.74;branch=z9hG4bK13ea00b1AC4CFAE4 (59) [Sep 20 11:20:56] DEBUG[16389] chan_sip.c: Header 4: From: "Sally Baker" ;tag=5D56B5AD-B325DB0A (81) [Sep 20 11:20:56] DEBUG[16389] chan_sip.c: Header 5: To: ;tag=as3584d546 (65) [Sep 20 11:20:56] DEBUG[16389] chan_sip.c: Header 6: CSeq: 3 BYE (11) [Sep 20 11:20:56] DEBUG[16389] chan_sip.c: Header 7: Call-ID: ec336c01-9a146537-d23e9134@63.77.68.74 (47) [Sep 20 11:20:56] DEBUG[16389] chan_sip.c: Header 8: Contact: (35) [Sep 20 11:20:56] DEBUG[16389] chan_sip.c: Header 9: User-Agent: PolycomSoundPointIP-SPIP_500-UA/2.0.1.0291 (54) [Sep 20 11:20:56] DEBUG[16389] chan_sip.c: Header 10: Proxy-Authorization: Digest username="714nv100", realm="nuvio.com", nonce="45116b85ae460a9b5e1a73bbdd3a3dd8f8ba8ef6", uri="sip:1002@proxy-test.sip.nuvio.net;user=phone;transport=udp", response="1c18bfa596451ecc2d923b0fcd869783", algorithm=MD5 (242) [Sep 20 11:20:56] DEBUG[16389] chan_sip.c: Header 11: Max-Forwards: 16 (16) [Sep 20 11:20:56] DEBUG[16389] chan_sip.c: Header 12: Content-Length: 0 (17) [Sep 20 11:20:56] DEBUG[16389] chan_sip.c: Header 13: P-hint: lr enforced (19) [Sep 20 11:20:56] DEBUG[16389] chan_sip.c: Header 14: (0) [Sep 20 11:20:56] VERBOSE[16389] logger.c: --- (14 headers 0 lines)[Sep 20 11:20:56] VERBOSE[16389] logger.c: --- [Sep 20 11:20:56] DEBUG[16389] chan_sip.c: **** Received BYE (8) - Command in SIP BYE [Sep 20 11:20:56] VERBOSE[16389] logger.c: Sending to 63.77.68.165 : 5060 (no NAT) [Sep 20 11:20:56] VERBOSE[16389] logger.c: Scheduling destruction of SIP dialog 'ec336c01-9a146537-d23e9134@63.77.68.74' in 32000 ms (Method: BYE) [Sep 20 11:20:56] DEBUG[16389] chan_sip.c: Received bye, no owner, selfdestruct soon. .[Sep 20 11:20:56] VERBOSE[16389] 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=z9hG4bK4af3.60696e44.0;received=63.77.68.165 Via: SIP/2.0/UDP 63.77.68.74;branch=z9hG4bK13ea00b1AC4CFAE4 Record-Route: From: "Sally Baker" ;tag=5D56B5AD-B325DB0A To: ;tag=as3584d546 Call-ID: ec336c01-9a146537-d23e9134@63.77.68.74 CSeq: 3 BYE User-Agent: NuvioSoftSwitch Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Supported: replaces Contact: Content-Length: 0 --- [Sep 20 11:20:56] DEBUG[16389] chan_sip.c: Header 0: SIP/2.0 200 OK (14) [Sep 20 11:20:56] DEBUG[16389] chan_sip.c: Header 1: Via: SIP/2.0/UDP 63.77.68.165;branch=z9hG4bK4af3.60696e44.0;received=63.77.68.165 (81) [Sep 20 11:20:56] DEBUG[16389] chan_sip.c: Header 2: Via: SIP/2.0/UDP 63.77.68.74;branch=z9hG4bK13ea00b1AC4CFAE4 (59) [Sep 20 11:20:56] DEBUG[16389] chan_sip.c: Header 3: Record-Route: (58) [Sep 20 11:20:56] DEBUG[16389] chan_sip.c: Header 4: From: "Sally Baker" ;tag=5D56B5AD-B325DB0A (81) [Sep 20 11:20:56] DEBUG[16389] chan_sip.c: Header 5: To: ;tag=as3584d546 (65) [Sep 20 11:20:56] DEBUG[16389] chan_sip.c: Header 6: Call-ID: ec336c01-9a146537-d23e9134@63.77.68.74 (47) [Sep 20 11:20:56] DEBUG[16389] chan_sip.c: Header 7: CSeq: 3 BYE (11) [Sep 20 11:20:56] DEBUG[16389] chan_sip.c: Header 8: User-Agent: NuvioSoftSwitch (27) [Sep 20 11:20:56] DEBUG[16389] chan_sip.c: Header 9: Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY (66) [Sep 20 11:20:56] DEBUG[16389] chan_sip.c: Header 10: Supported: replaces (19) [Sep 20 11:20:56] DEBUG[16389] chan_sip.c: Header 11: Contact: (32) [Sep 20 11:20:56] DEBUG[16389] chan_sip.c: Header 12: Content-Length: 0 (17) [Sep 20 11:20:56] DEBUG[16389] chan_sip.c: Header 13: (0) [Sep 20 11:21:17] VERBOSE[16400] logger.c: -- Remote UNIX connection disconnected [Sep 20 11:21:28] DEBUG[16389] chan_sip.c: Auto destroying SIP dialog 'ec336c01-9a146537-d23e9134@63.77.68.74' [Sep 20 11:21:28] DEBUG[16389] chan_sip.c: Destroying SIP dialog ec336c01-9a146537-d23e9134@63.77.68.74 [Sep 20 11:21:28] VERBOSE[16389] logger.c: Really destroying SIP dialog 'ec336c01-9a146537-d23e9134@63.77.68.74' Method: BYE [Sep 20 11:21:28] DEBUG[16389] chan_sip.c: ---------- SIP HISTORY for 'ec336c01-9a146537-d23e9134@63.77.68.74' [Sep 20 11:21:28] DEBUG[16389] chan_sip.c: * SIP Call [Sep 20 11:21:28] DEBUG[16389] chan_sip.c: 001. Rx INVITE / 2 INVITE / sip:1002@proxy-test.sip.nuvio.net;user=phon [Sep 20 11:21:28] DEBUG[16389] chan_sip.c: 002. Invite New call: ec336c01-9a146537-d23e9134@63.77.68.74 [Sep 20 11:21:28] DEBUG[16389] chan_sip.c: 003. NewChan Channel SIP/714nv100-082496a0 - from ec336c01-9a146537-d23e9134 [Sep 20 11:21:28] DEBUG[16389] chan_sip.c: 004. TxResp SIP/2.0 / 2 INVITE - 100 Trying [Sep 20 11:21:28] DEBUG[16389] chan_sip.c: 005. TxResp SIP/2.0 / 2 INVITE - 180 Ringing [Sep 20 11:21:28] DEBUG[16389] chan_sip.c: 006. TxResp SIP/2.0 / 2 INVITE - 183 Session Progress [Sep 20 11:21:28] DEBUG[16389] chan_sip.c: 007. TxRespRel SIP/2.0 / 2 INVITE - 200 OK [Sep 20 11:21:28] DEBUG[16389] chan_sip.c: 008. Rx ACK / 2 ACK / sip:1002@63.77.68.166 [Sep 20 11:21:28] DEBUG[16389] chan_sip.c: 009. Masq Old channel: SIP/3c68bcix3r0fw8xw-08254800 [Sep 20 11:21:28] DEBUG[16389] chan_sip.c: 010. Masq (cont) ...new owner: SIP/3c68bcix3r0fw8xw-08254800 [Sep 20 11:21:28] DEBUG[16389] chan_sip.c: 011. SchedDestroy 32000 ms [Sep 20 11:21:28] DEBUG[16389] chan_sip.c: 012. Rx BYE / 3 BYE / sip:1002@63.77.68.166 [Sep 20 11:21:28] DEBUG[16389] chan_sip.c: 013. RTCPaudio Quality:ssrc=1195072209;themssrc=779813711;lp=0;rxjitter=0.0002 [Sep 20 11:21:28] DEBUG[16389] chan_sip.c: 014. SchedDestroy 32000 ms [Sep 20 11:21:28] DEBUG[16389] chan_sip.c: 015. TxResp SIP/2.0 / 3 BYE - 200 OK [Sep 20 11:21:28] DEBUG[16389] chan_sip.c: 016. AutoDestroy ec336c01-9a146537-d23e9134@63.77.68.74 [Sep 20 11:21:28] DEBUG[16389] chan_sip.c: ---------- END SIP HISTORY for 'ec336c01-9a146537-d23e9134@63.77.68.74'