[Mar 14 11:59:58] VERBOSE[19196] logger.c: <--- SIP read from 66.61.7.219:5060 ---> INVITE sip:2168810880@ms1.mysipstuff.com SIP/2.0 Via: SIP/2.0/UDP 66.61.7.219:5060;branch=z9hG4bK2e6fe583 From: "jpyle" ;tag=as65426ee2 To: Contact: Call-ID: 6ab1b9cb3742eedd5364ca9b58bc7462@66.61.7.219 CSeq: 103 INVITE User-Agent: Asterisk PBX brns Max-Forwards: 70 Proxy-Authorization: Digest username="ugnd_01_ms", realm="ms1.mysipstuff.com", algorithm=MD5, uri="sip:2168810880@ms1.mysipstuff.com", nonce="4eef86b3", response="15f616097adc124ee2defcb72b936093", opaque="" Date: Fri, 14 Mar 2008 15:59:58 GMT Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Supported: replaces Remote-Party-ID: "jpyle" ;party=calling;privacy=off;screen=no Content-Type: application/sdp Content-Length: 236 v=0 o=root 3402 3403 IN IP4 66.61.7.219 s=session c=IN IP4 66.61.7.219 t=0 0 m=audio 16832 RTP/AVP 0 101 a=rtpmap:0 PCMU/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=silenceSupp:off - - - - a=ptime:20 a=sendrecv <-------------> [Mar 14 11:59:58] DEBUG[19196] chan_sip.c: Header 0: INVITE sip:2168810880@ms1.mysipstuff.com SIP/2.0 (51) [Mar 14 11:59:58] DEBUG[19196] chan_sip.c: Header 1: Via: SIP/2.0/UDP 66.61.7.219:5060;branch=z9hG4bK2e6fe583 (56) [Mar 14 11:59:58] DEBUG[19196] chan_sip.c: Header 2: From: "jpyle" ;tag=as65426ee2 (61) [Mar 14 11:59:58] DEBUG[19196] chan_sip.c: Header 3: To: (42) [Mar 14 11:59:58] DEBUG[19196] chan_sip.c: Header 4: Contact: (37) [Mar 14 11:59:58] DEBUG[19196] chan_sip.c: Header 5: Call-ID: 6ab1b9cb3742eedd5364ca9b58bc7462@66.61.7.219 (53) [Mar 14 11:59:58] DEBUG[19196] chan_sip.c: Header 6: CSeq: 103 INVITE (16) [Mar 14 11:59:58] DEBUG[19196] chan_sip.c: Header 7: User-Agent: Asterisk PBX brns (29) [Mar 14 11:59:58] DEBUG[19196] chan_sip.c: Header 8: Max-Forwards: 70 (16) [Mar 14 11:59:58] DEBUG[19196] chan_sip.c: Header 9: Proxy-Authorization: Digest username="ugnd_01_ms", realm="ms1.mysipstuff.com", algorithm=MD5, uri="sip:2168810880@ms1.mysipstuff.com", nonce="4eef86b3", response="15f616097adc124ee2defcb72b936093", opaque="" (213) [Mar 14 11:59:58] DEBUG[19196] chan_sip.c: Header 10: Date: Fri, 14 Mar 2008 15:59:58 GMT (35) [Mar 14 11:59:58] DEBUG[19196] chan_sip.c: Header 11: Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY (66) [Mar 14 11:59:58] DEBUG[19196] chan_sip.c: Header 12: Supported: replaces (19) [Mar 14 11:59:58] DEBUG[19196] chan_sip.c: Header 13: Remote-Party-ID: "jpyle" ;party=calling;privacy=off;screen=no (93) [Mar 14 11:59:58] DEBUG[19196] chan_sip.c: Header 14: Content-Type: application/sdp (29) [Mar 14 11:59:58] DEBUG[19196] chan_sip.c: Header 15: Content-Length: 236 (19) [Mar 14 11:59:58] DEBUG[19196] chan_sip.c: Header 16: (0) [Mar 14 11:59:58] DEBUG[19196] chan_sip.c: Line: v=0 (3) [Mar 14 11:59:58] DEBUG[19196] chan_sip.c: Line: o=root 3402 3403 IN IP4 66.61.7.219 (35) [Mar 14 11:59:58] DEBUG[19196] chan_sip.c: Line: s=session (9) [Mar 14 11:59:58] DEBUG[19196] chan_sip.c: Line: c=IN IP4 66.61.7.219 (20) [Mar 14 11:59:58] DEBUG[19196] chan_sip.c: Line: t=0 0 (5) [Mar 14 11:59:58] DEBUG[19196] chan_sip.c: Line: m=audio 16832 RTP/AVP 0 101 (27) [Mar 14 11:59:58] DEBUG[19196] chan_sip.c: Line: a=rtpmap:0 PCMU/8000 (20) [Mar 14 11:59:58] DEBUG[19196] chan_sip.c: Line: a=rtpmap:101 telephone-event/8000 (33) [Mar 14 11:59:58] DEBUG[19196] chan_sip.c: Line: a=fmtp:101 0-16 (15) [Mar 14 11:59:58] DEBUG[19196] chan_sip.c: Line: a=silenceSupp:off - - - - (25) [Mar 14 11:59:58] DEBUG[19196] chan_sip.c: Line: a=ptime:20 (10) [Mar 14 11:59:58] VERBOSE[19196] logger.c: --- (16 headers 12 lines) --- [Mar 14 11:59:58] DEBUG[19196] chan_sip.c: **** Received INVITE (5) - Command in SIP INVITE [Mar 14 11:59:58] VERBOSE[19196] logger.c: Sending to 66.61.7.219 : 5060 (no NAT) [Mar 14 11:59:58] VERBOSE[19196] logger.c: Using INVITE request as basis request - 6ab1b9cb3742eedd5364ca9b58bc7462@66.61.7.219 [Mar 14 11:59:58] DEBUG[19196] res_config_mysql.c: MySQL RealTime: Everything is fine. [Mar 14 11:59:58] DEBUG[19196] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM sip_buddies WHERE name = '2169311212' [Mar 14 11:59:58] DEBUG[19196] res_config_mysql.c: MySQL RealTime: Everything is fine. [Mar 14 11:59:58] DEBUG[19196] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM sip_buddies WHERE host = '66.61.7.219' AND port = '5060' [Mar 14 11:59:58] DEBUG[19196] res_config_mysql.c: MySQL RealTime: Everything is fine. [Mar 14 11:59:58] DEBUG[19196] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM sip_buddies WHERE ipaddr = '66.61.7.219' AND port = '5060' [Mar 14 11:59:58] DEBUG[19196] chan_sip.c: SIP Seeding peer from astdb: 'ugnd_01_ms' at ugnd_01_ms@66.61.7.219:5060 for 3600 [Mar 14 11:59:58] VERBOSE[19196] logger.c: Found peer 'ugnd_01_ms' [Mar 14 11:59:58] DEBUG[19196] chan_sip.c: Setting NAT on RTP to Off [Mar 14 11:59:58] DEBUG[19196] chan_sip.c: Destroying SIP peer ugnd_01_ms [Mar 14 11:59:58] VERBOSE[19196] logger.c: Found RTP audio format 0 [Mar 14 11:59:58] VERBOSE[19196] logger.c: Found RTP audio format 101 [Mar 14 11:59:58] VERBOSE[19196] logger.c: Peer audio RTP is at port 66.61.7.219:16832 [Mar 14 11:59:58] VERBOSE[19196] logger.c: Found audio description format PCMU for ID 0 [Mar 14 11:59:58] VERBOSE[19196] logger.c: Found audio description format telephone-event for ID 101 [Mar 14 11:59:58] VERBOSE[19196] logger.c: Got unsupported a:fmtp in SDP offer [Mar 14 11:59:58] DEBUG[19196] chan_sip.c: T38 state changed to 0 on channel [Mar 14 11:59:58] VERBOSE[19196] logger.c: Capabilities: us - 0x216 (gsm|ulaw|speex|g726aal2), peer - audio=0x4 (ulaw)/video=0x0 (nothing), combined - 0x4 (ulaw) [Mar 14 11:59:58] VERBOSE[19196] logger.c: Non-codec capabilities (dtmf): us - 0x1 (telephone-event), peer - 0x1 (telephone-event), combined - 0x1 (telephone-event) [Mar 14 11:59:58] VERBOSE[19196] logger.c: Peer audio RTP is at port 66.61.7.219:16832 [Mar 14 11:59:58] DEBUG[19196] chan_sip.c: We're settling with these formats: 0x4 (ulaw) [Mar 14 11:59:58] DEBUG[19196] chan_sip.c: Checking SIP call limits for device ugnd_01_ms [Mar 14 11:59:58] DEBUG[19196] chan_sip.c: Updating call counter for incoming call [Mar 14 11:59:58] VERBOSE[19196] logger.c: Looking for 2168810880 in from-ugnd (domain ms1.mysipstuff.com) [Mar 14 11:59:58] DEBUG[19196] chan_sip.c: *** Our native formats are 0x4 (ulaw) [Mar 14 11:59:58] DEBUG[19196] chan_sip.c: *** Joint capabilities are 0x4 (ulaw) [Mar 14 11:59:58] DEBUG[19196] chan_sip.c: *** Our capabilities are 0x216 (gsm|ulaw|speex|g726aal2) [Mar 14 11:59:58] DEBUG[19196] chan_sip.c: *** AST_CODEC_CHOOSE formats are 0x4 (ulaw) [Mar 14 11:59:58] DEBUG[19196] chan_sip.c: This channel will not be able to handle video. [Mar 14 11:59:58] DEBUG[19196] chan_sip.c: build_route: Contact hop: [Mar 14 11:59:58] VERBOSE[19196] logger.c: list_route: hop: [Mar 14 11:59:58] DEBUG[19196] chan_sip.c: SIP/ugnd_01_ms-0a024c70: New call is still down.... Trying... [Mar 14 11:59:58] VERBOSE[19196] logger.c: <--- Transmitting (no NAT) to 66.61.7.219:5060 ---> SIP/2.0 100 Trying Via: SIP/2.0/UDP 66.61.7.219:5060;branch=z9hG4bK2e6fe583;received=66.61.7.219 From: "jpyle" ;tag=as65426ee2 To: Call-ID: 6ab1b9cb3742eedd5364ca9b58bc7462@66.61.7.219 CSeq: 103 INVITE User-Agent: Hello-MS-12.262 Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Supported: replaces Contact: Content-Length: 0 <------------> [Mar 14 11:59:58] DEBUG[19196] devicestate.c: Notification of state change to be queued on device/channel SIP/ugnd_01_ms-0a024c70 [Mar 14 11:59:58] DEBUG[19196] devicestate.c: Notification of state change to be queued on device/channel SIP/ugnd_01_ms [Mar 14 11:59:58] DEBUG[19179] devicestate.c: No provider found, checking channel drivers for SIP - ugnd_01_ms-0a024c70 [Mar 14 11:59:58] DEBUG[19179] chan_sip.c: Checking device state for peer ugnd_01_ms-0a024c70 [Mar 14 11:59:58] DEBUG[5834] pbx.c: Function result is '1000873924' [Mar 14 11:59:58] DEBUG[5834] pbx.c: Launching 'SIPAddHeader' [Mar 14 11:59:58] DEBUG[19179] res_config_mysql.c: MySQL RealTime: Everything is fine. [Mar 14 11:59:58] VERBOSE[5834] logger.c: -- Executing [2168810880@from-ugnd:1] SIPAddHeader("SIP/ugnd_01_ms-0a024c70", "X-Accountcode: 1000873924") in new stack [Mar 14 11:59:58] DEBUG[19179] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM sip_buddies WHERE name = 'ugnd_01_ms-0a024c70' AND host = 'dynamic' [Mar 14 11:59:58] DEBUG[5834] chan_sip.c: SIP Header added "X-Accountcode: 1000873924" as _SIPADDHEADER01 [Mar 14 11:59:58] DEBUG[5834] pbx.c: Launching 'Macro' [Mar 14 11:59:58] VERBOSE[5834] logger.c: -- Executing [2168810880@from-ugnd:2] Macro("SIP/ugnd_01_ms-0a024c70", "recordit|2168810880") in new stack [Mar 14 11:59:58] DEBUG[5834] pbx.c: Function result is '1000873924' [Mar 14 11:59:58] DEBUG[5834] db.c: Unable to find key '1000873924' in family 'record' [Mar 14 11:59:58] DEBUG[5834] func_db.c: DB: record/1000873924 not found in database. [Mar 14 11:59:58] DEBUG[5834] pbx.c: Function result is '' [Mar 14 11:59:58] DEBUG[5834] pbx.c: Launching 'Set' [Mar 14 11:59:58] VERBOSE[5834] logger.c: -- Executing [s@macro-recordit:1] Set("SIP/ugnd_01_ms-0a024c70", "REC=") in new stack [Mar 14 11:59:58] DEBUG[5834] app_macro.c: Executed application: Set [Mar 14 11:59:58] DEBUG[5834] pbx.c: Function result is '0' [Mar 14 11:59:58] DEBUG[5834] pbx.c: Expression result is '1' [Mar 14 11:59:58] DEBUG[5834] pbx.c: Launching 'GotoIf' [Mar 14 11:59:58] DEBUG[19179] res_config_mysql.c: MySQL RealTime: Everything is fine. [Mar 14 11:59:58] VERBOSE[5834] logger.c: -- Executing [s@macro-recordit:2] GotoIf("SIP/ugnd_01_ms-0a024c70", "1?10") in new stack [Mar 14 11:59:58] DEBUG[19179] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM sip_buddies WHERE name = 'ugnd_01_ms-0a024c70' [Mar 14 11:59:58] VERBOSE[5834] logger.c: -- Goto (macro-recordit,s,10) [Mar 14 11:59:58] DEBUG[5834] app_macro.c: Executed application: GotoIf [Mar 14 11:59:58] DEBUG[5834] pbx.c: Launching 'NoOp' [Mar 14 11:59:58] VERBOSE[5834] logger.c: -- Executing [s@macro-recordit:10] NoOp("SIP/ugnd_01_ms-0a024c70", "") in new stack [Mar 14 11:59:58] DEBUG[5834] app_macro.c: Executed application: NoOp [Mar 14 11:59:58] DEBUG[5834] pbx.c: Function result is 'jpyle' [Mar 14 11:59:58] DEBUG[5834] pbx.c: Function result is '2169311212' [Mar 14 11:59:58] DEBUG[5834] pbx.c: Launching 'Verbose' [Mar 14 11:59:58] VERBOSE[5834] logger.c: -- Executing [2168810880@from-ugnd:3] Verbose("SIP/ugnd_01_ms-0a024c70", "1| >>>> Called 2168810880 @ sip1 - jpyle <2169311212>") in new stack [Mar 14 11:59:58] VERBOSE[5834] logger.c: >>>> Called 2168810880 @ sip1 - jpyle <2169311212> [Mar 14 11:59:58] DEBUG[5834] pbx.c: Launching 'Dial' [Mar 14 11:59:58] VERBOSE[5834] logger.c: -- Executing [2168810880@from-ugnd:4] Dial("SIP/ugnd_01_ms-0a024c70", "SIP/2168810880@sip1|180|j") in new stack [Mar 14 11:59:58] DEBUG[5834] chan_sip.c: Asked to create a SIP channel with formats: 0x4 (ulaw) [Mar 14 11:59:58] DEBUG[5834] chan_sip.c: Allocating new SIP dialog for (No Call-ID) - INVITE (With RTP) [Mar 14 11:59:58] DEBUG[5834] chan_sip.c: Setting NAT on RTP to Off [Mar 14 11:59:58] DEBUG[5834] chan_sip.c: *** Our native formats are 0x4 (ulaw) [Mar 14 11:59:58] DEBUG[5834] chan_sip.c: *** Joint capabilities are 0x0 (nothing) [Mar 14 11:59:58] DEBUG[5834] chan_sip.c: *** Our capabilities are 0x4 (ulaw) [Mar 14 11:59:58] DEBUG[5834] chan_sip.c: *** AST_CODEC_CHOOSE formats are 0x4 (ulaw) [Mar 14 11:59:58] DEBUG[5834] chan_sip.c: *** Our preferred formats from the incoming channel are 0x4 (ulaw) [Mar 14 11:59:58] DEBUG[5834] chan_sip.c: This channel will not be able to handle video. [Mar 14 11:59:58] DEBUG[5834] rtp.c: Seeded SDP of 'SIP/sip1-0a029780' with that of 'SIP/ugnd_01_ms-0a024c70' [Mar 14 11:59:58] DEBUG[5834] channel.c: Not copying variable MACRO_DEPTH. [Mar 14 11:59:58] DEBUG[5834] channel.c: Not copying variable REC. [Mar 14 11:59:58] DEBUG[5834] channel.c: Copying soft-transferable variable SIPADDHEADER01. [Mar 14 11:59:58] DEBUG[5834] channel.c: Not copying variable SIPCALLID. [Mar 14 11:59:58] DEBUG[5834] channel.c: Not copying variable SIPUSERAGENT. [Mar 14 11:59:58] DEBUG[5834] channel.c: Not copying variable SIPDOMAIN. [Mar 14 11:59:58] DEBUG[5834] channel.c: Not copying variable SIPURI. [Mar 14 11:59:58] DEBUG[5834] chan_sip.c: Outgoing Call for 2168810880 [Mar 14 11:59:58] DEBUG[5834] chan_sip.c: Updating call counter for outgoing call [Mar 14 11:59:58] DEBUG[5834] chan_sip.c: Our T38 capability (0), joint T38 capability (0) [Mar 14 11:59:58] DEBUG[5834] chan_sip.c: Adding SIP Header "X-Accountcode" with content :1000873924: [Mar 14 11:59:58] DEBUG[5834] chan_sip.c: ** Our capability: 0x4 (ulaw) Video flag: False [Mar 14 11:59:58] DEBUG[5834] chan_sip.c: ** Our prefcodec: 0x4 (ulaw) [Mar 14 11:59:58] VERBOSE[5834] logger.c: Audio is at 63.216.250.91 port 22804 [Mar 14 11:59:58] VERBOSE[5834] logger.c: Adding codec 0x4 (ulaw) to SDP [Mar 14 11:59:58] DEBUG[5834] chan_sip.c: -- Done with adding codecs to SDP [Mar 14 11:59:58] DEBUG[5834] chan_sip.c: Done building SDP. Settling with this capability: 0x4 (ulaw) [Mar 14 11:59:58] DEBUG[5834] chan_sip.c: Header 0: INVITE sip:2168810880@63.216.250.82 SIP/2.0 (43) [Mar 14 11:59:58] DEBUG[5834] chan_sip.c: Header 1: Via: SIP/2.0/UDP 63.216.250.91:5060;branch=z9hG4bK65502479 (58) [Mar 14 11:59:58] DEBUG[5834] chan_sip.c: Header 2: From: "jpyle" ;tag=as5b2afe1f (71) [Mar 14 11:59:58] DEBUG[5834] chan_sip.c: Header 3: To: (34) [Mar 14 11:59:58] DEBUG[5834] chan_sip.c: Header 4: Contact: (39) [Mar 14 11:59:58] DEBUG[5834] chan_sip.c: Header 5: Call-ID: 66990b8a272fe6da314b12284a3041e1@ms1.mysipstuff.com (63) [Mar 14 11:59:58] DEBUG[5834] chan_sip.c: Header 6: CSeq: 102 INVITE (16) [Mar 14 11:59:58] DEBUG[5834] chan_sip.c: Header 7: User-Agent: Hello-MS-12.262 (25) [Mar 14 11:59:58] DEBUG[5834] chan_sip.c: Header 8: Max-Forwards: 70 (16) [Mar 14 11:59:58] DEBUG[5834] chan_sip.c: Header 9: Remote-Party-ID: "jpyle" ;privacy=off;screen=no (89) [Mar 14 11:59:58] DEBUG[5834] chan_sip.c: Header 10: Date: Fri, 14 Mar 2008 15:59:58 GMT (35) [Mar 14 11:59:58] DEBUG[5834] chan_sip.c: Header 11: Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY (66) [Mar 14 11:59:58] DEBUG[5834] chan_sip.c: Header 12: Supported: replaces (19) [Mar 14 11:59:58] DEBUG[5834] chan_sip.c: Header 13: X-Accountcode: 1000873924 (25) [Mar 14 11:59:58] DEBUG[5834] chan_sip.c: Header 14: Content-Type: application/sdp (29) [Mar 14 11:59:58] DEBUG[5834] chan_sip.c: Header 15: Content-Length: 186 (19) [Mar 14 11:59:58] DEBUG[5834] chan_sip.c: Header 16: (0) [Mar 14 11:59:58] DEBUG[5834] chan_sip.c: Line: v=0 (3) [Mar 14 11:59:58] DEBUG[5834] chan_sip.c: Line: o=root 13330 13330 IN IP4 63.216.250.91 (39) [Mar 14 11:59:58] DEBUG[5834] chan_sip.c: Line: s=session (9) [Mar 14 11:59:58] DEBUG[5834] chan_sip.c: Line: c=IN IP4 63.216.250.91 (22) [Mar 14 11:59:58] DEBUG[5834] chan_sip.c: Line: t=0 0 (5) [Mar 14 11:59:58] DEBUG[5834] chan_sip.c: Line: m=audio 22804 RTP/AVP 0 (23) [Mar 14 11:59:58] DEBUG[5834] chan_sip.c: Line: a=rtpmap:0 PCMU/8000 (20) [Mar 14 11:59:58] DEBUG[5834] chan_sip.c: Line: a=silenceSupp:off - - - - (25) [Mar 14 11:59:58] DEBUG[5834] chan_sip.c: Line: a=ptime:20 (10) [Mar 14 11:59:58] DEBUG[5834] chan_sip.c: Line: a=sendrecv (10) [Mar 14 11:59:58] VERBOSE[5834] logger.c: Reliably Transmitting (no NAT) to 63.216.250.82:5060: INVITE sip:2168810880@63.216.250.82 SIP/2.0 Via: SIP/2.0/UDP 63.216.250.91:5060;branch=z9hG4bK65502479 From: "jpyle" ;tag=as5b2afe1f To: Contact: Call-ID: 66990b8a272fe6da314b12284a3041e1@ms1.mysipstuff.com CSeq: 102 INVITE User-Agent: Hello-MS-12.262 Max-Forwards: 70 Remote-Party-ID: "jpyle" ;privacy=off;screen=no Date: Fri, 14 Mar 2008 15:59:58 GMT Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Supported: replaces X-Accountcode: 1000873924 Content-Type: application/sdp Content-Length: 186 v=0 o=root 13330 13330 IN IP4 63.216.250.91 s=session c=IN IP4 63.216.250.91 t=0 0 m=audio 22804 RTP/AVP 0 a=rtpmap:0 PCMU/8000 a=silenceSupp:off - - - - a=ptime:20 a=sendrecv --- [Mar 14 11:59:58] DEBUG[5834] chan_sip.c: *** SIP TIMER: Initializing retransmit timer on packet: Id #0 [Mar 14 11:59:58] VERBOSE[5834] logger.c: -- Called 2168810880@sip1 [Mar 14 11:59:58] VERBOSE[19196] logger.c: <--- SIP read from 63.216.250.82:5060 ---> SIP/2.0 100 Trying Via: SIP/2.0/UDP 63.216.250.91:5060;branch=z9hG4bK65502479;received=63.216.250.91 From: "jpyle" ;tag=as5b2afe1f To: Call-ID: 66990b8a272fe6da314b12284a3041e1@ms1.mysipstuff.com CSeq: 102 INVITE User-Agent: Hello-12.26 Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Contact: Content-Length: 0 <-------------> [Mar 14 11:59:58] DEBUG[19196] chan_sip.c: Header 0: SIP/2.0 100 Trying (18) [Mar 14 11:59:58] DEBUG[19196] chan_sip.c: Header 1: Via: SIP/2.0/UDP 63.216.250.91:5060;branch=z9hG4bK65502479;received=63.216.250.91 (81) [Mar 14 11:59:58] DEBUG[19196] chan_sip.c: Header 2: From: "jpyle" ;tag=as5b2afe1f (71) [Mar 14 11:59:58] DEBUG[19196] chan_sip.c: Header 3: To: (34) [Mar 14 11:59:58] DEBUG[19196] chan_sip.c: Header 4: Call-ID: 66990b8a272fe6da314b12284a3041e1@ms1.mysipstuff.com (63) [Mar 14 11:59:58] DEBUG[19196] chan_sip.c: Header 5: CSeq: 102 INVITE (16) [Mar 14 11:59:58] DEBUG[19196] chan_sip.c: Header 6: User-Agent: Hello-12.26 (21) [Mar 14 11:59:58] DEBUG[19196] chan_sip.c: Header 7: Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY (66) [Mar 14 11:59:58] DEBUG[19196] chan_sip.c: Header 8: Contact: (39) [Mar 14 11:59:58] DEBUG[19196] chan_sip.c: Header 9: Content-Length: 0 (17) [Mar 14 11:59:58] VERBOSE[19196] logger.c: --- (10 headers 0 lines) --- [Mar 14 11:59:58] DEBUG[19196] chan_sip.c: *** SIP TIMER: Cancelling retransmission #11583 - INVITE (got response) [Mar 14 11:59:58] DEBUG[19196] chan_sip.c: (Provisional) Stopping retransmission (but retaining packet) on '66990b8a272fe6da314b12284a3041e1@ms1.mysipstuff.com' Request 102: Found [Mar 14 11:59:58] DEBUG[19196] chan_sip.c: SIP response 100 to standard invite [Mar 14 11:59:58] DEBUG[19179] devicestate.c: Changing state for SIP/ugnd_01_ms-0a024c70 - state 4 (Invalid) [Mar 14 11:59:58] DEBUG[19179] devicestate.c: No provider found, checking channel drivers for SIP - ugnd_01_ms [Mar 14 11:59:58] DEBUG[19179] chan_sip.c: Checking device state for peer ugnd_01_ms [Mar 14 11:59:58] DEBUG[19179] res_config_mysql.c: MySQL RealTime: Everything is fine. [Mar 14 11:59:58] DEBUG[19179] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM sip_buddies WHERE name = 'ugnd_01_ms' AND host = 'dynamic' [Mar 14 11:59:58] DEBUG[19179] chan_sip.c: SIP Seeding peer from astdb: 'ugnd_01_ms' at ugnd_01_ms@66.61.7.219:5060 for 3600 [Mar 14 11:59:58] DEBUG[19179] chan_sip.c: Destroying SIP peer ugnd_01_ms [Mar 14 11:59:58] DEBUG[19179] devicestate.c: Changing state for SIP/ugnd_01_ms - state 1 (Not in use) [Mar 14 11:59:58] VERBOSE[19196] logger.c: <--- SIP read from 63.216.250.82:5060 ---> SIP/2.0 183 Session Progress Via: SIP/2.0/UDP 63.216.250.91:5060;branch=z9hG4bK65502479;received=63.216.250.91 From: "jpyle" ;tag=as5b2afe1f To: ;tag=as6e1f65f0 Call-ID: 66990b8a272fe6da314b12284a3041e1@ms1.mysipstuff.com CSeq: 102 INVITE User-Agent: Hello-12.26 Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Contact: Content-Type: application/sdp Content-Length: 162 v=0 o=root 14589 14589 IN IP4 63.216.250.82 s=session c=IN IP4 63.216.250.82 t=0 0 m=audio 28036 RTP/AVP 0 a=rtpmap:0 PCMU/8000 a=silenceSupp:off - - - - <-------------> [Mar 14 11:59:58] DEBUG[19196] chan_sip.c: Header 0: SIP/2.0 183 Session Progress (28) [Mar 14 11:59:58] DEBUG[19196] chan_sip.c: Header 1: Via: SIP/2.0/UDP 63.216.250.91:5060;branch=z9hG4bK65502479;received=63.216.250.91 (81) [Mar 14 11:59:58] DEBUG[19196] chan_sip.c: Header 2: From: "jpyle" ;tag=as5b2afe1f (71) [Mar 14 11:59:58] DEBUG[19196] chan_sip.c: Header 3: To: ;tag=as6e1f65f0 (49) [Mar 14 11:59:58] DEBUG[19196] chan_sip.c: Header 4: Call-ID: 66990b8a272fe6da314b12284a3041e1@ms1.mysipstuff.com (63) [Mar 14 11:59:58] DEBUG[19196] chan_sip.c: Header 5: CSeq: 102 INVITE (16) [Mar 14 11:59:58] DEBUG[19196] chan_sip.c: Header 6: User-Agent: Hello-12.26 (21) [Mar 14 11:59:58] DEBUG[19196] chan_sip.c: Header 7: Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY (66) [Mar 14 11:59:58] DEBUG[19196] chan_sip.c: Header 8: Contact: (39) [Mar 14 11:59:58] DEBUG[19196] chan_sip.c: Header 9: Content-Type: application/sdp (29) [Mar 14 11:59:58] DEBUG[19196] chan_sip.c: Header 10: Content-Length: 162 (19) [Mar 14 11:59:58] DEBUG[19196] chan_sip.c: Header 11: (0) [Mar 14 11:59:58] DEBUG[19196] chan_sip.c: Line: v=0 (3) [Mar 14 11:59:58] DEBUG[19196] chan_sip.c: Line: o=root 14589 14589 IN IP4 63.216.250.82 (39) [Mar 14 11:59:58] DEBUG[19196] chan_sip.c: Line: s=session (9) [Mar 14 11:59:58] DEBUG[19196] chan_sip.c: Line: c=IN IP4 63.216.250.82 (22) [Mar 14 11:59:58] DEBUG[19196] chan_sip.c: Line: t=0 0 (5) [Mar 14 11:59:58] DEBUG[19196] chan_sip.c: Line: m=audio 28036 RTP/AVP 0 (23) [Mar 14 11:59:58] DEBUG[19196] chan_sip.c: Line: a=rtpmap:0 PCMU/8000 (20) [Mar 14 11:59:58] VERBOSE[19196] logger.c: --- (11 headers 8 lines) --- [Mar 14 11:59:58] DEBUG[19196] chan_sip.c: (Provisional) Stopping retransmission (but retaining packet) on '66990b8a272fe6da314b12284a3041e1@ms1.mysipstuff.com' Request 102: Found [Mar 14 11:59:58] DEBUG[19196] chan_sip.c: SIP response 183 to standard invite [Mar 14 11:59:58] VERBOSE[19196] logger.c: Found RTP audio format 0 [Mar 14 11:59:58] VERBOSE[19196] logger.c: Peer audio RTP is at port 63.216.250.82:28036 [Mar 14 11:59:58] VERBOSE[19196] logger.c: Found audio description format PCMU for ID 0 [Mar 14 11:59:58] DEBUG[19196] chan_sip.c: T38 state changed to 0 on channel SIP/sip1-0a029780 [Mar 14 11:59:58] VERBOSE[19196] logger.c: Capabilities: us - 0x4 (ulaw), peer - audio=0x4 (ulaw)/video=0x0 (nothing), combined - 0x4 (ulaw) [Mar 14 11:59:58] VERBOSE[19196] logger.c: Non-codec capabilities (dtmf): us - 0x0 (nothing), peer - 0x0 (nothing), combined - 0x0 (nothing) [Mar 14 11:59:58] VERBOSE[19196] logger.c: Peer audio RTP is at port 63.216.250.82:28036 [Mar 14 11:59:58] DEBUG[19196] chan_sip.c: We're settling with these formats: 0x4 (ulaw) [Mar 14 11:59:58] DEBUG[19196] chan_sip.c: We have an owner, now see if we need to change this call [Mar 14 11:59:58] VERBOSE[5834] logger.c: -- SIP/sip1-0a029780 is making progress passing it to SIP/ugnd_01_ms-0a024c70 [Mar 14 11:59:58] DEBUG[5834] rtp.c: Setting early bridge SDP of 'SIP/ugnd_01_ms-0a024c70' with that of 'SIP/sip1-0a029780' [Mar 14 11:59:58] DEBUG[5834] chan_sip.c: Setting framing from config on incoming call [Mar 14 11:59:58] DEBUG[5834] chan_sip.c: ** Our capability: 0x4 (ulaw) Video flag: True [Mar 14 11:59:58] DEBUG[5834] chan_sip.c: ** Our prefcodec: 0x0 (nothing) [Mar 14 11:59:58] VERBOSE[5834] logger.c: Audio is at 63.216.250.91 port 31064 [Mar 14 11:59:58] VERBOSE[5834] logger.c: Adding codec 0x4 (ulaw) to SDP [Mar 14 11:59:58] VERBOSE[5834] logger.c: Adding non-codec 0x1 (telephone-event) to SDP [Mar 14 11:59:58] DEBUG[5834] chan_sip.c: -- Done with adding codecs to SDP [Mar 14 11:59:58] DEBUG[5834] chan_sip.c: Done building SDP. Settling with this capability: 0x4 (ulaw) [Mar 14 11:59:58] VERBOSE[5834] logger.c: <--- Transmitting (no NAT) to 66.61.7.219:5060 ---> SIP/2.0 183 Session Progress Via: SIP/2.0/UDP 66.61.7.219:5060;branch=z9hG4bK2e6fe583;received=66.61.7.219 From: "jpyle" ;tag=as65426ee2 To: ;tag=as68a232dd Call-ID: 6ab1b9cb3742eedd5364ca9b58bc7462@66.61.7.219 CSeq: 103 INVITE User-Agent: Hello-MS-12.262 Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Supported: replaces Contact: Content-Type: application/sdp Content-Length: 242 v=0 o=root 13330 13330 IN IP4 63.216.250.91 s=session c=IN IP4 63.216.250.91 t=0 0 m=audio 31064 RTP/AVP 0 101 a=rtpmap:0 PCMU/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=silenceSupp:off - - - - a=ptime:20 a=sendrecv <------------> [Mar 14 11:59:58] DEBUG[5834] rtp.c: Ooh, format changed from unknown to ulaw [Mar 14 11:59:58] DEBUG[5834] rtp.c: Created smoother: format: 4 ms: 20 len: 160 [Mar 14 11:59:58] DEBUG[5834] rtp.c: Ooh, format changed from unknown to ulaw [Mar 14 11:59:58] DEBUG[5834] rtp.c: Created smoother: format: 4 ms: 20 len: 160 [Mar 14 12:00:02] VERBOSE[19196] logger.c: <--- SIP read from 63.216.250.82:5060 ---> SIP/2.0 200 OK Via: SIP/2.0/UDP 63.216.250.91:5060;branch=z9hG4bK65502479;received=63.216.250.91 From: "jpyle" ;tag=as5b2afe1f To: ;tag=as6e1f65f0 Call-ID: 66990b8a272fe6da314b12284a3041e1@ms1.mysipstuff.com CSeq: 102 INVITE User-Agent: Hello-12.26 Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Contact: Content-Type: application/sdp Content-Length: 162 v=0 o=root 14589 14590 IN IP4 63.216.250.82 s=session c=IN IP4 63.216.250.82 t=0 0 m=audio 28036 RTP/AVP 0 a=rtpmap:0 PCMU/8000 a=silenceSupp:off - - - - <-------------> [Mar 14 12:00:02] DEBUG[19196] chan_sip.c: Header 0: SIP/2.0 200 OK (14) [Mar 14 12:00:02] DEBUG[19196] chan_sip.c: Header 1: Via: SIP/2.0/UDP 63.216.250.91:5060;branch=z9hG4bK65502479;received=63.216.250.91 (81) [Mar 14 12:00:02] DEBUG[19196] chan_sip.c: Header 2: From: "jpyle" ;tag=as5b2afe1f (71) [Mar 14 12:00:02] DEBUG[19196] chan_sip.c: Header 3: To: ;tag=as6e1f65f0 (49) [Mar 14 12:00:02] DEBUG[19196] chan_sip.c: Header 4: Call-ID: 66990b8a272fe6da314b12284a3041e1@ms1.mysipstuff.com (63) [Mar 14 12:00:02] DEBUG[19196] chan_sip.c: Header 5: CSeq: 102 INVITE (16) [Mar 14 12:00:02] DEBUG[19196] chan_sip.c: Header 6: User-Agent: Hello-12.26 (21) [Mar 14 12:00:02] DEBUG[19196] chan_sip.c: Header 7: Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY (66) [Mar 14 12:00:02] DEBUG[19196] chan_sip.c: Header 8: Contact: (39) [Mar 14 12:00:02] DEBUG[19196] chan_sip.c: Header 9: Content-Type: application/sdp (29) [Mar 14 12:00:02] DEBUG[19196] chan_sip.c: Header 10: Content-Length: 162 (19) [Mar 14 12:00:02] DEBUG[19196] chan_sip.c: Header 11: (0) [Mar 14 12:00:02] DEBUG[19196] chan_sip.c: Line: v=0 (3) [Mar 14 12:00:02] DEBUG[19196] chan_sip.c: Line: o=root 14589 14590 IN IP4 63.216.250.82 (39) [Mar 14 12:00:02] DEBUG[19196] chan_sip.c: Line: s=session (9) [Mar 14 12:00:02] DEBUG[19196] chan_sip.c: Line: c=IN IP4 63.216.250.82 (22) [Mar 14 12:00:02] DEBUG[19196] chan_sip.c: Line: t=0 0 (5) [Mar 14 12:00:02] DEBUG[19196] chan_sip.c: Line: m=audio 28036 RTP/AVP 0 (23) [Mar 14 12:00:02] DEBUG[19196] chan_sip.c: Line: a=rtpmap:0 PCMU/8000 (20) [Mar 14 12:00:02] VERBOSE[19196] logger.c: --- (11 headers 8 lines) --- [Mar 14 12:00:02] DEBUG[19196] chan_sip.c: Acked pending invite 102 [Mar 14 12:00:02] DEBUG[19196] chan_sip.c: Stopping retransmission on '66990b8a272fe6da314b12284a3041e1@ms1.mysipstuff.com' of Request 102: Match Not Found [Mar 14 12:00:02] DEBUG[19196] chan_sip.c: SIP response 200 to standard invite [Mar 14 12:00:02] VERBOSE[19196] logger.c: Found RTP audio format 0 [Mar 14 12:00:02] VERBOSE[19196] logger.c: Peer audio RTP is at port 63.216.250.82:28036 [Mar 14 12:00:02] VERBOSE[19196] logger.c: Found audio description format PCMU for ID 0 [Mar 14 12:00:02] DEBUG[19196] chan_sip.c: T38 state changed to 0 on channel SIP/sip1-0a029780 [Mar 14 12:00:02] VERBOSE[19196] logger.c: Capabilities: us - 0x4 (ulaw), peer - audio=0x4 (ulaw)/video=0x0 (nothing), combined - 0x4 (ulaw) [Mar 14 12:00:02] VERBOSE[19196] logger.c: Non-codec capabilities (dtmf): us - 0x0 (nothing), peer - 0x0 (nothing), combined - 0x0 (nothing) [Mar 14 12:00:02] VERBOSE[19196] logger.c: Peer audio RTP is at port 63.216.250.82:28036 [Mar 14 12:00:02] DEBUG[19196] chan_sip.c: We're settling with these formats: 0x4 (ulaw) [Mar 14 12:00:02] DEBUG[19196] chan_sip.c: We have an owner, now see if we need to change this call [Mar 14 12:00:02] DEBUG[19196] chan_sip.c: Updating call counter for outgoing call [Mar 14 12:00:02] DEBUG[19196] chan_sip.c: build_route: Contact hop: [Mar 14 12:00:02] VERBOSE[19196] logger.c: list_route: hop: [Mar 14 12:00:02] DEBUG[19196] chan_sip.c: Strict routing enforced for session 66990b8a272fe6da314b12284a3041e1@ms1.mysipstuff.com [Mar 14 12:00:02] VERBOSE[19196] logger.c: set_destination: Parsing for address/port to send to [Mar 14 12:00:02] VERBOSE[19196] logger.c: set_destination: set destination to 63.216.250.82, port 5060 [Mar 14 12:00:02] VERBOSE[19196] logger.c: Transmitting (no NAT) to 63.216.250.82:5060: ACK sip:2168810880@63.216.250.82 SIP/2.0 Via: SIP/2.0/UDP 63.216.250.91:5060;branch=z9hG4bK2ecec94a From: "jpyle" ;tag=as5b2afe1f To: ;tag=as6e1f65f0 Contact: Call-ID: 66990b8a272fe6da314b12284a3041e1@ms1.mysipstuff.com CSeq: 102 ACK User-Agent: Hello-MS-12.262 Max-Forwards: 70 Remote-Party-ID: "jpyle" ;privacy=off;screen=no Content-Length: 0 --- [Mar 14 12:00:02] VERBOSE[19196] logger.c: <--- SIP read from 63.216.250.82:5060 ---> SIP/2.0 200 OK Via: SIP/2.0/UDP 63.216.250.91:5060;branch=z9hG4bK65502479;received=63.216.250.91 From: "jpyle" ;tag=as5b2afe1f To: ;tag=as6e1f65f0 Call-ID: 66990b8a272fe6da314b12284a3041e1@ms1.mysipstuff.com CSeq: 102 INVITE User-Agent: Hello-12.26 Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Contact: Content-Type: application/sdp Content-Length: 162 v=0 o=root 14589 14590 IN IP4 63.216.250.82 s=session c=IN IP4 63.216.250.82 t=0 0 m=audio 28036 RTP/AVP 0 a=rtpmap:0 PCMU/8000 a=silenceSupp:off - - - - <-------------> [Mar 14 12:00:02] DEBUG[19196] chan_sip.c: Header 0: SIP/2.0 200 OK (14) [Mar 14 12:00:02] DEBUG[19196] chan_sip.c: Header 1: Via: SIP/2.0/UDP 63.216.250.91:5060;branch=z9hG4bK65502479;received=63.216.250.91 (81) [Mar 14 12:00:02] DEBUG[19196] chan_sip.c: Header 2: From: "jpyle" ;tag=as5b2afe1f (71) [Mar 14 12:00:02] DEBUG[19196] chan_sip.c: Header 3: To: ;tag=as6e1f65f0 (49) [Mar 14 12:00:02] DEBUG[19196] chan_sip.c: Header 4: Call-ID: 66990b8a272fe6da314b12284a3041e1@ms1.mysipstuff.com (63) [Mar 14 12:00:02] DEBUG[19196] chan_sip.c: Header 5: CSeq: 102 INVITE (16) [Mar 14 12:00:02] DEBUG[19196] chan_sip.c: Header 6: User-Agent: Hello-12.26 (21) [Mar 14 12:00:02] DEBUG[19196] chan_sip.c: Header 7: Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY (66) [Mar 14 12:00:02] DEBUG[19196] chan_sip.c: Header 8: Contact: (39) [Mar 14 12:00:02] DEBUG[19196] chan_sip.c: Header 9: Content-Type: application/sdp (29) [Mar 14 12:00:02] DEBUG[19196] chan_sip.c: Header 10: Content-Length: 162 (19) [Mar 14 12:00:02] DEBUG[19196] chan_sip.c: Header 11: (0) [Mar 14 12:00:02] DEBUG[19196] chan_sip.c: Line: v=0 (3) [Mar 14 12:00:02] DEBUG[19196] chan_sip.c: Line: o=root 14589 14590 IN IP4 63.216.250.82 (39) [Mar 14 12:00:02] DEBUG[19196] chan_sip.c: Line: s=session (9) [Mar 14 12:00:02] DEBUG[19196] chan_sip.c: Line: c=IN IP4 63.216.250.82 (22) [Mar 14 12:00:02] DEBUG[19196] chan_sip.c: Line: t=0 0 (5) [Mar 14 12:00:02] DEBUG[19196] chan_sip.c: Line: m=audio 28036 RTP/AVP 0 (23) [Mar 14 12:00:02] DEBUG[19196] chan_sip.c: Line: a=rtpmap:0 PCMU/8000 (20) [Mar 14 12:00:02] VERBOSE[19196] logger.c: --- (11 headers 8 lines) --- [Mar 14 12:00:02] DEBUG[19196] chan_sip.c: Stopping retransmission on '66990b8a272fe6da314b12284a3041e1@ms1.mysipstuff.com' of Request 102: Match Found [Mar 14 12:00:02] DEBUG[19196] chan_sip.c: SIP response 200 to standard invite [Mar 14 12:00:02] VERBOSE[19196] logger.c: Found RTP audio format 0 [Mar 14 12:00:02] VERBOSE[19196] logger.c: Peer audio RTP is at port 63.216.250.82:28036 [Mar 14 12:00:02] VERBOSE[19196] logger.c: Found audio description format PCMU for ID 0 [Mar 14 12:00:02] DEBUG[19196] chan_sip.c: T38 state changed to 0 on channel SIP/sip1-0a029780 [Mar 14 12:00:02] VERBOSE[19196] logger.c: Capabilities: us - 0x4 (ulaw), peer - audio=0x4 (ulaw)/video=0x0 (nothing), combined - 0x4 (ulaw) [Mar 14 12:00:02] VERBOSE[19196] logger.c: Non-codec capabilities (dtmf): us - 0x0 (nothing), peer - 0x0 (nothing), combined - 0x0 (nothing) [Mar 14 12:00:02] VERBOSE[19196] logger.c: Peer audio RTP is at port 63.216.250.82:28036 [Mar 14 12:00:02] DEBUG[19196] chan_sip.c: We're settling with these formats: 0x4 (ulaw) [Mar 14 12:00:02] DEBUG[19196] chan_sip.c: We have an owner, now see if we need to change this call [Mar 14 12:00:02] DEBUG[19196] chan_sip.c: Updating call counter for outgoing call [Mar 14 12:00:02] DEBUG[19196] chan_sip.c: build_route: Retaining previous route: [Mar 14 12:00:02] DEBUG[19196] chan_sip.c: Strict routing enforced for session 66990b8a272fe6da314b12284a3041e1@ms1.mysipstuff.com [Mar 14 12:00:02] VERBOSE[19196] logger.c: set_destination: Parsing for address/port to send to [Mar 14 12:00:02] VERBOSE[19196] logger.c: set_destination: set destination to 63.216.250.82, port 5060 [Mar 14 12:00:02] VERBOSE[19196] logger.c: Transmitting (no NAT) to 63.216.250.82:5060: ACK sip:2168810880@63.216.250.82 SIP/2.0 Via: SIP/2.0/UDP 63.216.250.91:5060;branch=z9hG4bK0b76fa3b From: "jpyle" ;tag=as5b2afe1f To: ;tag=as6e1f65f0 Contact: Call-ID: 66990b8a272fe6da314b12284a3041e1@ms1.mysipstuff.com CSeq: 102 ACK User-Agent: Hello-MS-12.262 Max-Forwards: 70 Remote-Party-ID: "jpyle" ;privacy=off;screen=no Content-Length: 0 --- [Mar 14 12:00:02] VERBOSE[19196] logger.c: <--- SIP read from 63.216.250.82:5060 ---> INVITE sip:2169311212@63.216.250.91 SIP/2.0 Via: SIP/2.0/UDP 63.216.250.82:5060;branch=z9hG4bK664118aa From: ;tag=as6e1f65f0 To: "jpyle" ;tag=as5b2afe1f Contact: Call-ID: 66990b8a272fe6da314b12284a3041e1@ms1.mysipstuff.com CSeq: 102 INVITE User-Agent: Hello-12.26 Max-Forwards: 70 Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Content-Type: application/sdp Content-Length: 162 v=0 o=root 14589 14591 IN IP4 207.62.58.251 s=session c=IN IP4 207.62.58.251 t=0 0 m=audio 44956 RTP/AVP 0 a=rtpmap:0 PCMU/8000 a=silenceSupp:off - - - - <-------------> [Mar 14 12:00:02] DEBUG[19196] chan_sip.c: Header 0: INVITE sip:2169311212@63.216.250.91 SIP/2.0 (43) [Mar 14 12:00:02] DEBUG[19196] chan_sip.c: Header 1: Via: SIP/2.0/UDP 63.216.250.82:5060;branch=z9hG4bK664118aa (58) [Mar 14 12:00:02] DEBUG[19196] chan_sip.c: Header 2: From: ;tag=as6e1f65f0 (51) [Mar 14 12:00:02] DEBUG[19196] chan_sip.c: Header 3: To: "jpyle" ;tag=as5b2afe1f (69) [Mar 14 12:00:02] DEBUG[19196] chan_sip.c: Header 4: Contact: (39) [Mar 14 12:00:02] DEBUG[19196] chan_sip.c: Header 5: Call-ID: 66990b8a272fe6da314b12284a3041e1@ms1.mysipstuff.com (63) [Mar 14 12:00:02] DEBUG[19196] chan_sip.c: Header 6: CSeq: 102 INVITE (16) [Mar 14 12:00:02] DEBUG[19196] chan_sip.c: Header 7: User-Agent: Hello-12.26 (21) [Mar 14 12:00:02] DEBUG[19196] chan_sip.c: Header 8: Max-Forwards: 70 (16) [Mar 14 12:00:02] DEBUG[19196] chan_sip.c: Header 9: Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY (66) [Mar 14 12:00:02] DEBUG[19196] chan_sip.c: Header 10: Content-Type: application/sdp (29) [Mar 14 12:00:02] DEBUG[19196] chan_sip.c: Header 11: Content-Length: 162 (19) [Mar 14 12:00:02] DEBUG[19196] chan_sip.c: Header 12: (0) [Mar 14 12:00:02] DEBUG[19196] chan_sip.c: Line: v=0 (3) [Mar 14 12:00:02] DEBUG[19196] chan_sip.c: Line: o=root 14589 14591 IN IP4 207.62.58.251 (39) [Mar 14 12:00:02] DEBUG[19196] chan_sip.c: Line: s=session (9) [Mar 14 12:00:02] DEBUG[19196] chan_sip.c: Line: c=IN IP4 207.62.58.251 (22) [Mar 14 12:00:02] DEBUG[19196] chan_sip.c: Line: t=0 0 (5) [Mar 14 12:00:02] DEBUG[19196] chan_sip.c: Line: m=audio 44956 RTP/AVP 0 (23) [Mar 14 12:00:02] DEBUG[19196] chan_sip.c: Line: a=rtpmap:0 PCMU/8000 (20) [Mar 14 12:00:02] VERBOSE[19196] logger.c: --- (12 headers 8 lines) --- [Mar 14 12:00:02] DEBUG[19196] chan_sip.c: **** Received INVITE (5) - Command in SIP INVITE [Mar 14 12:00:02] VERBOSE[19196] logger.c: <--- Transmitting (no NAT) to 63.216.250.82:5060 ---> SIP/2.0 482 Loop Detected Via: SIP/2.0/UDP 63.216.250.82:5060;branch=z9hG4bK664118aa;received=63.216.250.82 From: ;tag=as6e1f65f0 To: "jpyle" ;tag=as5b2afe1f Call-ID: 66990b8a272fe6da314b12284a3041e1@ms1.mysipstuff.com CSeq: 102 INVITE User-Agent: Hello-MS-12.262 Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Supported: replaces Content-Length: 0 X-Asterisk-HangupCause: Normal Clearing X-Asterisk-HangupCauseCode: 16 <------------> [Mar 14 12:00:02] VERBOSE[19196] logger.c: Scheduling destruction of SIP dialog '66990b8a272fe6da314b12284a3041e1@ms1.mysipstuff.com' in 32000 ms (Method: INVITE) [Mar 14 12:00:02] VERBOSE[19196] logger.c: <--- SIP read from 63.216.250.82:5060 ---> INVITE sip:2169311212@63.216.250.91 SIP/2.0 Via: SIP/2.0/UDP 63.216.250.82:5060;branch=z9hG4bK664118aa From: ;tag=as6e1f65f0 To: "jpyle" ;tag=as5b2afe1f Contact: Call-ID: 66990b8a272fe6da314b12284a3041e1@ms1.mysipstuff.com CSeq: 102 INVITE User-Agent: Hello-12.26 Max-Forwards: 70 Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Content-Type: application/sdp Content-Length: 162 v=0 o=root 14589 14591 IN IP4 207.62.58.251 s=session c=IN IP4 207.62.58.251 t=0 0 m=audio 44956 RTP/AVP 0 a=rtpmap:0 PCMU/8000 a=silenceSupp:off - - - - <-------------> [Mar 14 12:00:02] DEBUG[19196] chan_sip.c: Header 0: INVITE sip:2169311212@63.216.250.91 SIP/2.0 (43) [Mar 14 12:00:02] DEBUG[19196] chan_sip.c: Header 1: Via: SIP/2.0/UDP 63.216.250.82:5060;branch=z9hG4bK664118aa (58) [Mar 14 12:00:02] DEBUG[19196] chan_sip.c: Header 2: From: ;tag=as6e1f65f0 (51) [Mar 14 12:00:02] DEBUG[19196] chan_sip.c: Header 3: To: "jpyle" ;tag=as5b2afe1f (69) [Mar 14 12:00:02] DEBUG[19196] chan_sip.c: Header 4: Contact: (39) [Mar 14 12:00:02] DEBUG[19196] chan_sip.c: Header 5: Call-ID: 66990b8a272fe6da314b12284a3041e1@ms1.mysipstuff.com (63) [Mar 14 12:00:02] DEBUG[19196] chan_sip.c: Header 6: CSeq: 102 INVITE (16) [Mar 14 12:00:02] DEBUG[19196] chan_sip.c: Header 7: User-Agent: Hello-12.26 (21) [Mar 14 12:00:02] DEBUG[19196] chan_sip.c: Header 8: Max-Forwards: 70 (16) [Mar 14 12:00:02] DEBUG[19196] chan_sip.c: Header 9: Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY (66) [Mar 14 12:00:02] DEBUG[19196] chan_sip.c: Header 10: Content-Type: application/sdp (29) [Mar 14 12:00:02] DEBUG[19196] chan_sip.c: Header 11: Content-Length: 162 (19) [Mar 14 12:00:02] DEBUG[19196] chan_sip.c: Header 12: (0) [Mar 14 12:00:02] DEBUG[19196] chan_sip.c: Line: v=0 (3) [Mar 14 12:00:02] DEBUG[19196] chan_sip.c: Line: o=root 14589 14591 IN IP4 207.62.58.251 (39) [Mar 14 12:00:02] DEBUG[19196] chan_sip.c: Line: s=session (9) [Mar 14 12:00:02] DEBUG[19196] chan_sip.c: Line: c=IN IP4 207.62.58.251 (22) [Mar 14 12:00:02] DEBUG[19196] chan_sip.c: Line: t=0 0 (5) [Mar 14 12:00:02] DEBUG[19196] chan_sip.c: Line: m=audio 44956 RTP/AVP 0 (23) [Mar 14 12:00:02] DEBUG[19196] chan_sip.c: Line: a=rtpmap:0 PCMU/8000 (20) [Mar 14 12:00:02] VERBOSE[19196] logger.c: --- (12 headers 8 lines) --- [Mar 14 12:00:02] DEBUG[19196] chan_sip.c: **** Received INVITE (5) - Command in SIP INVITE [Mar 14 12:00:02] DEBUG[19196] chan_sip.c: Ignoring SIP message because of retransmit (INVITE Seqno 102, ours 102) [Mar 14 12:00:02] VERBOSE[19196] logger.c: <--- Transmitting (no NAT) to 63.216.250.82:5060 ---> SIP/2.0 482 Loop Detected Via: SIP/2.0/UDP 63.216.250.82:5060;branch=z9hG4bK664118aa;received=63.216.250.82 From: ;tag=as6e1f65f0 To: "jpyle" ;tag=as5b2afe1f Call-ID: 66990b8a272fe6da314b12284a3041e1@ms1.mysipstuff.com CSeq: 102 INVITE User-Agent: Hello-MS-12.262 Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Supported: replaces Content-Length: 0 X-Asterisk-HangupCause: Normal Clearing X-Asterisk-HangupCauseCode: 16 <------------> [Mar 14 12:00:02] VERBOSE[19196] logger.c: Scheduling destruction of SIP dialog '66990b8a272fe6da314b12284a3041e1@ms1.mysipstuff.com' in 32000 ms (Method: INVITE) [Mar 14 12:00:02] VERBOSE[19196] logger.c: <--- SIP read from 63.216.250.82:5060 ---> ACK sip:2169311212@63.216.250.91 SIP/2.0 Via: SIP/2.0/UDP 63.216.250.82:5060;branch=z9hG4bK664118aa From: ;tag=as6e1f65f0 To: "jpyle" ;tag=as5b2afe1f Contact: Call-ID: 66990b8a272fe6da314b12284a3041e1@ms1.mysipstuff.com CSeq: 102 ACK User-Agent: Hello-12.26 Max-Forwards: 70 Content-Length: 0 <-------------> [Mar 14 12:00:02] DEBUG[19196] chan_sip.c: Header 0: ACK sip:2169311212@63.216.250.91 SIP/2.0 (40) [Mar 14 12:00:02] DEBUG[19196] chan_sip.c: Header 1: Via: SIP/2.0/UDP 63.216.250.82:5060;branch=z9hG4bK664118aa (58) [Mar 14 12:00:02] DEBUG[19196] chan_sip.c: Header 2: From: ;tag=as6e1f65f0 (51) [Mar 14 12:00:02] DEBUG[19196] chan_sip.c: Header 3: To: "jpyle" ;tag=as5b2afe1f (69) [Mar 14 12:00:02] DEBUG[19196] chan_sip.c: Header 4: Contact: (39) [Mar 14 12:00:02] DEBUG[19196] chan_sip.c: Header 5: Call-ID: 66990b8a272fe6da314b12284a3041e1@ms1.mysipstuff.com (63) [Mar 14 12:00:02] DEBUG[19196] chan_sip.c: Header 6: CSeq: 102 ACK (13) [Mar 14 12:00:02] DEBUG[19196] chan_sip.c: Header 7: User-Agent: Hello-12.26 (21) [Mar 14 12:00:02] DEBUG[19196] chan_sip.c: Header 8: Max-Forwards: 70 (16) [Mar 14 12:00:02] DEBUG[19196] chan_sip.c: Header 9: Content-Length: 0 (17) [Mar 14 12:00:02] VERBOSE[19196] logger.c: --- (10 headers 0 lines) --- [Mar 14 12:00:02] DEBUG[19196] chan_sip.c: **** Received ACK (6) - Command in SIP ACK [Mar 14 12:00:02] VERBOSE[19196] logger.c: <--- SIP read from 63.216.250.82:5060 ---> ACK sip:2169311212@63.216.250.91 SIP/2.0 Via: SIP/2.0/UDP 63.216.250.82:5060;branch=z9hG4bK664118aa From: ;tag=as6e1f65f0 To: "jpyle" ;tag=as5b2afe1f Contact: Call-ID: 66990b8a272fe6da314b12284a3041e1@ms1.mysipstuff.com CSeq: 102 ACK User-Agent: Hello-12.26 Max-Forwards: 70 Content-Length: 0 <-------------> [Mar 14 12:00:02] DEBUG[19196] chan_sip.c: Header 0: ACK sip:2169311212@63.216.250.91 SIP/2.0 (40) [Mar 14 12:00:02] DEBUG[19196] chan_sip.c: Header 1: Via: SIP/2.0/UDP 63.216.250.82:5060;branch=z9hG4bK664118aa (58) [Mar 14 12:00:02] DEBUG[19196] chan_sip.c: Header 2: From: ;tag=as6e1f65f0 (51) [Mar 14 12:00:02] DEBUG[19196] chan_sip.c: Header 3: To: "jpyle" ;tag=as5b2afe1f (69) [Mar 14 12:00:02] DEBUG[19196] chan_sip.c: Header 4: Contact: (39) [Mar 14 12:00:02] DEBUG[19196] chan_sip.c: Header 5: Call-ID: 66990b8a272fe6da314b12284a3041e1@ms1.mysipstuff.com (63) [Mar 14 12:00:02] DEBUG[19196] chan_sip.c: Header 6: CSeq: 102 ACK (13) [Mar 14 12:00:02] DEBUG[19196] chan_sip.c: Header 7: User-Agent: Hello-12.26 (21) [Mar 14 12:00:02] DEBUG[19196] chan_sip.c: Header 8: Max-Forwards: 70 (16) [Mar 14 12:00:02] DEBUG[19196] chan_sip.c: Header 9: Content-Length: 0 (17) [Mar 14 12:00:02] VERBOSE[19196] logger.c: --- (10 headers 0 lines) --- [Mar 14 12:00:02] DEBUG[19196] chan_sip.c: **** Received ACK (6) - Command in SIP ACK [Mar 14 12:00:02] DEBUG[5834] devicestate.c: Notification of state change to be queued on device/channel SIP/sip1-0a029780 [Mar 14 12:00:02] DEBUG[5834] devicestate.c: Notification of state change to be queued on device/channel SIP/sip1 [Mar 14 12:00:02] DEBUG[19179] devicestate.c: No provider found, checking channel drivers for SIP - sip1-0a029780 [Mar 14 12:00:02] VERBOSE[5834] logger.c: -- SIP/sip1-0a029780 answered SIP/ugnd_01_ms-0a024c70 [Mar 14 12:00:02] DEBUG[19179] chan_sip.c: Checking device state for peer sip1-0a029780 [Mar 14 12:00:02] DEBUG[5834] rtp.c: Setting early bridge SDP of 'SIP/ugnd_01_ms-0a024c70' with that of 'SIP/sip1-0a029780' [Mar 14 12:00:02] DEBUG[5834] devicestate.c: Notification of state change to be queued on device/channel SIP/ugnd_01_ms-0a024c70 [Mar 14 12:00:02] DEBUG[5834] devicestate.c: Notification of state change to be queued on device/channel SIP/ugnd_01_ms [Mar 14 12:00:02] DEBUG[5834] chan_sip.c: SIP answering channel: SIP/ugnd_01_ms-0a024c70 [Mar 14 12:00:02] DEBUG[5834] chan_sip.c: Setting framing from config on incoming call [Mar 14 12:00:02] DEBUG[5834] chan_sip.c: ** Our capability: 0x4 (ulaw) Video flag: True [Mar 14 12:00:02] DEBUG[19179] res_config_mysql.c: MySQL RealTime: Everything is fine. [Mar 14 12:00:02] DEBUG[5834] chan_sip.c: ** Our prefcodec: 0x0 (nothing) [Mar 14 12:00:02] DEBUG[19179] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM sip_buddies WHERE name = 'sip1-0a029780' AND host = 'dynamic' [Mar 14 12:00:02] VERBOSE[5834] logger.c: Audio is at 63.216.250.91 port 31064 [Mar 14 12:00:02] VERBOSE[5834] logger.c: Adding codec 0x4 (ulaw) to SDP [Mar 14 12:00:02] VERBOSE[5834] logger.c: Adding non-codec 0x1 (telephone-event) to SDP [Mar 14 12:00:02] DEBUG[5834] chan_sip.c: -- Done with adding codecs to SDP [Mar 14 12:00:02] DEBUG[5834] chan_sip.c: Done building SDP. Settling with this capability: 0x4 (ulaw) [Mar 14 12:00:02] VERBOSE[5834] logger.c: <--- Reliably Transmitting (no NAT) to 66.61.7.219:5060 ---> SIP/2.0 200 OK Via: SIP/2.0/UDP 66.61.7.219:5060;branch=z9hG4bK2e6fe583;received=66.61.7.219 From: "jpyle" ;tag=as65426ee2 To: ;tag=as68a232dd Call-ID: 6ab1b9cb3742eedd5364ca9b58bc7462@66.61.7.219 CSeq: 103 INVITE User-Agent: Hello-MS-12.262 Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Supported: replaces Contact: Content-Type: application/sdp Content-Length: 242 v=0 o=root 13330 13331 IN IP4 63.216.250.91 s=session c=IN IP4 63.216.250.91 t=0 0 m=audio 31064 RTP/AVP 0 101 a=rtpmap:0 PCMU/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=silenceSupp:off - - - - a=ptime:20 a=sendrecv <------------> [Mar 14 12:00:02] DEBUG[5834] chan_sip.c: *** SIP TIMER: Initializing retransmit timer on packet: Id #0 [Mar 14 12:00:02] DEBUG[5834] channel.c: Dropping duplicate answer! [Mar 14 12:00:02] DEBUG[5834] rtp.c: Created smoother: format: 4 ms: 20 len: 160 [Mar 14 12:00:02] DEBUG[19179] res_config_mysql.c: MySQL RealTime: Everything is fine. [Mar 14 12:00:02] DEBUG[19179] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM sip_buddies WHERE name = 'sip1-0a029780' [Mar 14 12:00:02] VERBOSE[19196] logger.c: <--- SIP read from 66.61.7.219:5060 ---> ACK sip:2168810880@63.216.250.91 SIP/2.0 Via: SIP/2.0/UDP 66.61.7.219:5060;branch=z9hG4bK18a909ec From: "jpyle" ;tag=as65426ee2 To: ;tag=as68a232dd Contact: Call-ID: 6ab1b9cb3742eedd5364ca9b58bc7462@66.61.7.219 CSeq: 103 ACK User-Agent: Asterisk PBX brns Max-Forwards: 70 Content-Length: 0 <-------------> [Mar 14 12:00:02] DEBUG[19196] chan_sip.c: Header 0: ACK sip:2168810880@63.216.250.91 SIP/2.0 (40) [Mar 14 12:00:02] DEBUG[19196] chan_sip.c: Header 1: Via: SIP/2.0/UDP 66.61.7.219:5060;branch=z9hG4bK18a909ec (56) [Mar 14 12:00:02] DEBUG[19196] chan_sip.c: Header 2: From: "jpyle" ;tag=as65426ee2 (61) [Mar 14 12:00:02] DEBUG[19196] chan_sip.c: Header 3: To: ;tag=as68a232dd (57) [Mar 14 12:00:02] DEBUG[19196] chan_sip.c: Header 4: Contact: (37) [Mar 14 12:00:02] DEBUG[19196] chan_sip.c: Header 5: Call-ID: 6ab1b9cb3742eedd5364ca9b58bc7462@66.61.7.219 (53) [Mar 14 12:00:02] DEBUG[19196] chan_sip.c: Header 6: CSeq: 103 ACK (13) [Mar 14 12:00:02] DEBUG[19196] chan_sip.c: Header 7: User-Agent: Asterisk PBX brns (29) [Mar 14 12:00:02] DEBUG[19196] chan_sip.c: Header 8: Max-Forwards: 70 (16) [Mar 14 12:00:02] DEBUG[19196] chan_sip.c: Header 9: Content-Length: 0 (17) [Mar 14 12:00:02] VERBOSE[19196] logger.c: --- (10 headers 0 lines) --- [Mar 14 12:00:02] DEBUG[19196] chan_sip.c: **** Received ACK (6) - Command in SIP ACK [Mar 14 12:00:02] DEBUG[19196] chan_sip.c: ** SIP TIMER: Cancelling retransmit of packet (reply received) Retransid #11591 [Mar 14 12:00:02] DEBUG[19196] chan_sip.c: Stopping retransmission on '6ab1b9cb3742eedd5364ca9b58bc7462@66.61.7.219' of Response 103: Match Not Found [Mar 14 12:00:02] DEBUG[19179] devicestate.c: Changing state for SIP/sip1-0a029780 - state 4 (Invalid) [Mar 14 12:00:02] DEBUG[19179] devicestate.c: No provider found, checking channel drivers for SIP - sip1 [Mar 14 12:00:02] DEBUG[19179] chan_sip.c: Checking device state for peer sip1 [Mar 14 12:00:02] DEBUG[19179] devicestate.c: Changing state for SIP/sip1 - state 1 (Not in use) [Mar 14 12:00:02] DEBUG[19179] devicestate.c: No provider found, checking channel drivers for SIP - ugnd_01_ms-0a024c70 [Mar 14 12:00:02] DEBUG[19179] chan_sip.c: Checking device state for peer ugnd_01_ms-0a024c70 [Mar 14 12:00:02] DEBUG[19179] res_config_mysql.c: MySQL RealTime: Everything is fine. [Mar 14 12:00:02] DEBUG[19179] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM sip_buddies WHERE name = 'ugnd_01_ms-0a024c70' AND host = 'dynamic' [Mar 14 12:00:02] DEBUG[19179] res_config_mysql.c: MySQL RealTime: Everything is fine. [Mar 14 12:00:02] DEBUG[19179] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM sip_buddies WHERE name = 'ugnd_01_ms-0a024c70' [Mar 14 12:00:02] DEBUG[19179] devicestate.c: Changing state for SIP/ugnd_01_ms-0a024c70 - state 4 (Invalid) [Mar 14 12:00:02] DEBUG[19179] devicestate.c: No provider found, checking channel drivers for SIP - ugnd_01_ms [Mar 14 12:00:02] DEBUG[19179] chan_sip.c: Checking device state for peer ugnd_01_ms [Mar 14 12:00:02] DEBUG[19179] res_config_mysql.c: MySQL RealTime: Everything is fine. [Mar 14 12:00:02] DEBUG[19179] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM sip_buddies WHERE name = 'ugnd_01_ms' AND host = 'dynamic' [Mar 14 12:00:02] DEBUG[19179] chan_sip.c: SIP Seeding peer from astdb: 'ugnd_01_ms' at ugnd_01_ms@66.61.7.219:5060 for 3600 [Mar 14 12:00:02] DEBUG[19179] chan_sip.c: Destroying SIP peer ugnd_01_ms [Mar 14 12:00:02] DEBUG[19179] devicestate.c: Changing state for SIP/ugnd_01_ms - state 1 (Not in use) [Mar 14 12:00:03] DEBUG[5834] rtp.c: Got RTCP report of 64 bytes [Mar 14 12:00:07] VERBOSE[19196] logger.c: <--- SIP read from 66.61.7.219:5060 ---> BYE sip:2168810880@63.216.250.91 SIP/2.0 Via: SIP/2.0/UDP 66.61.7.219:5060;branch=z9hG4bK24d61320 From: "jpyle" ;tag=as65426ee2 To: ;tag=as68a232dd Call-ID: 6ab1b9cb3742eedd5364ca9b58bc7462@66.61.7.219 CSeq: 104 BYE User-Agent: Asterisk PBX brns Max-Forwards: 70 Proxy-Authorization: Digest username="ugnd_01_ms", realm="ms1.mysipstuff.com", algorithm=MD5, uri="sip:2168810880@63.216.250.91", nonce="4eef86b3", response="97d0c1dadbff7ada8f94e9df553a74ff", opaque="" Content-Length: 0 <-------------> [Mar 14 12:00:07] DEBUG[19196] chan_sip.c: Header 0: BYE sip:2168810880@63.216.250.91 SIP/2.0 (40) [Mar 14 12:00:07] DEBUG[19196] chan_sip.c: Header 1: Via: SIP/2.0/UDP 66.61.7.219:5060;branch=z9hG4bK24d61320 (56) [Mar 14 12:00:07] DEBUG[19196] chan_sip.c: Header 2: From: "jpyle" ;tag=as65426ee2 (61) [Mar 14 12:00:07] DEBUG[19196] chan_sip.c: Header 3: To: ;tag=as68a232dd (57) [Mar 14 12:00:07] DEBUG[19196] chan_sip.c: Header 4: Call-ID: 6ab1b9cb3742eedd5364ca9b58bc7462@66.61.7.219 (53) [Mar 14 12:00:07] DEBUG[19196] chan_sip.c: Header 5: CSeq: 104 BYE (13) [Mar 14 12:00:07] DEBUG[19196] chan_sip.c: Header 6: User-Agent: Asterisk PBX brns (29) [Mar 14 12:00:07] DEBUG[19196] chan_sip.c: Header 7: Max-Forwards: 70 (16) [Mar 14 12:00:07] DEBUG[19196] chan_sip.c: Header 8: Proxy-Authorization: Digest username="ugnd_01_ms", realm="ms1.mysipstuff.com", algorithm=MD5, uri="sip:2168810880@63.216.250.91", nonce="4eef86b3", response="97d0c1dadbff7ada8f94e9df553a74ff", opaque="" (205) [Mar 14 12:00:07] DEBUG[19196] chan_sip.c: Header 9: Content-Length: 0 (17) [Mar 14 12:00:07] VERBOSE[19196] logger.c: --- (10 headers 0 lines) --- [Mar 14 12:00:07] DEBUG[19196] chan_sip.c: **** Received BYE (8) - Command in SIP BYE [Mar 14 12:00:07] VERBOSE[19196] logger.c: Sending to 66.61.7.219 : 5060 (no NAT) [Mar 14 12:00:07] DEBUG[19196] chan_sip.c: Setting SIP_ALREADYGONE on dialog 6ab1b9cb3742eedd5364ca9b58bc7462@66.61.7.219 [Mar 14 12:00:07] DEBUG[19196] chan_sip.c: Received bye, issuing owner hangup [Mar 14 12:00:07] VERBOSE[19196] logger.c: <--- Transmitting (no NAT) to 66.61.7.219:5060 ---> SIP/2.0 200 OK Via: SIP/2.0/UDP 66.61.7.219:5060;branch=z9hG4bK24d61320;received=66.61.7.219 From: "jpyle" ;tag=as65426ee2 To: ;tag=as68a232dd Call-ID: 6ab1b9cb3742eedd5364ca9b58bc7462@66.61.7.219 CSeq: 104 BYE User-Agent: Hello-MS-12.262 Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Supported: replaces Contact: Content-Length: 0 <------------> [Mar 14 12:00:07] DEBUG[5834] channel.c: Didn't get a frame from channel: SIP/ugnd_01_ms-0a024c70 [Mar 14 12:00:07] DEBUG[5834] channel.c: Bridge stops bridging channels SIP/ugnd_01_ms-0a024c70 and SIP/sip1-0a029780 [Mar 14 12:00:07] DEBUG[5834] channel.c: Hanging up channel 'SIP/sip1-0a029780' [Mar 14 12:00:07] DEBUG[5834] chan_sip.c: Hangup call SIP/sip1-0a029780, SIP callid 66990b8a272fe6da314b12284a3041e1@ms1.mysipstuff.com) [Mar 14 12:00:07] VERBOSE[5834] logger.c: Scheduling destruction of SIP dialog '66990b8a272fe6da314b12284a3041e1@ms1.mysipstuff.com' in 32000 ms (Method: ACK) [Mar 14 12:00:07] DEBUG[5834] chan_sip.c: Strict routing enforced for session 66990b8a272fe6da314b12284a3041e1@ms1.mysipstuff.com [Mar 14 12:00:07] VERBOSE[5834] logger.c: set_destination: Parsing for address/port to send to [Mar 14 12:00:07] VERBOSE[5834] logger.c: set_destination: set destination to 63.216.250.82, port 5060 [Mar 14 12:00:07] VERBOSE[5834] logger.c: Reliably Transmitting (no NAT) to 63.216.250.82:5060: BYE sip:2168810880@63.216.250.82 SIP/2.0 Via: SIP/2.0/UDP 63.216.250.91:5060;branch=z9hG4bK451acc5c From: "jpyle" ;tag=as5b2afe1f To: ;tag=as6e1f65f0 Call-ID: 66990b8a272fe6da314b12284a3041e1@ms1.mysipstuff.com CSeq: 103 BYE User-Agent: Hello-MS-12.262 Max-Forwards: 70 Remote-Party-ID: "jpyle" ;privacy=off;screen=no Content-Length: 0 --- [Mar 14 12:00:07] DEBUG[5834] chan_sip.c: *** SIP TIMER: Initializing retransmit timer on packet: Id #0 [Mar 14 12:00:07] DEBUG[5834] devicestate.c: Notification of state change to be queued on device/channel SIP/sip1-0a029780 [Mar 14 12:00:07] DEBUG[5834] devicestate.c: Notification of state change to be queued on device/channel SIP/sip1 [Mar 14 12:00:07] DEBUG[19179] devicestate.c: No provider found, checking channel drivers for SIP - sip1-0a029780 [Mar 14 12:00:07] DEBUG[5834] rtp.c: Channel '' has no RTP, not doing anything [Mar 14 12:00:07] DEBUG[19179] chan_sip.c: Checking device state for peer sip1-0a029780 [Mar 14 12:00:07] VERBOSE[19196] logger.c: <--- SIP read from 63.216.250.82:5060 ---> SIP/2.0 481 Call leg/transaction does not exist Via: SIP/2.0/UDP 63.216.250.91:5060;branch=z9hG4bK451acc5c;received=63.216.250.91 From: "jpyle" ;tag=as5b2afe1f To: ;tag=as6e1f65f0 Call-ID: 66990b8a272fe6da314b12284a3041e1@ms1.mysipstuff.com CSeq: 103 BYE User-Agent: Hello-12.26 Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Content-Length: 0 <-------------> [Mar 14 12:00:07] DEBUG[19196] chan_sip.c: Header 0: SIP/2.0 481 Call leg/transaction does not exist (47) [Mar 14 12:00:07] DEBUG[19196] chan_sip.c: Header 1: Via: SIP/2.0/UDP 63.216.250.91:5060;branch=z9hG4bK451acc5c;received=63.216.250.91 (81) [Mar 14 12:00:07] DEBUG[19196] chan_sip.c: Header 2: From: "jpyle" ;tag=as5b2afe1f (71) [Mar 14 12:00:07] DEBUG[19196] chan_sip.c: Header 3: To: ;tag=as6e1f65f0 (49) [Mar 14 12:00:07] DEBUG[19196] chan_sip.c: Header 4: Call-ID: 66990b8a272fe6da314b12284a3041e1@ms1.mysipstuff.com (63) [Mar 14 12:00:07] DEBUG[19196] chan_sip.c: Header 5: CSeq: 103 BYE (13) [Mar 14 12:00:07] DEBUG[19179] res_config_mysql.c: MySQL RealTime: Everything is fine. [Mar 14 12:00:07] DEBUG[19196] chan_sip.c: Header 6: User-Agent: Hello-12.26 (21) [Mar 14 12:00:07] DEBUG[5834] app_dial.c: Exiting with DIALSTATUS=ANSWER. [Mar 14 12:00:07] DEBUG[19179] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM sip_buddies WHERE name = 'sip1-0a029780' AND host = 'dynamic' [Mar 14 12:00:07] DEBUG[5834] pbx.c: Spawn extension (from-ugnd,2168810880,4) exited non-zero on 'SIP/ugnd_01_ms-0a024c70' [Mar 14 12:00:07] VERBOSE[5834] logger.c: == Spawn extension (from-ugnd, 2168810880, 4) exited non-zero on 'SIP/ugnd_01_ms-0a024c70' [Mar 14 12:00:07] DEBUG[5834] channel.c: Soft-Hanging up channel 'SIP/ugnd_01_ms-0a024c70' [Mar 14 12:00:07] DEBUG[5834] pbx.c: Launching 'Hangup' [Mar 14 12:00:07] VERBOSE[5834] logger.c: -- Executing [h@from-ugnd:1] Hangup("SIP/ugnd_01_ms-0a024c70", "") in new stack [Mar 14 12:00:07] DEBUG[5834] pbx.c: Spawn extension (from-ugnd,h,1) exited non-zero on 'SIP/ugnd_01_ms-0a024c70' [Mar 14 12:00:07] VERBOSE[5834] logger.c: == Spawn extension (from-ugnd, h, 1) exited non-zero on 'SIP/ugnd_01_ms-0a024c70' [Mar 14 12:00:07] DEBUG[5834] channel.c: Hanging up channel 'SIP/ugnd_01_ms-0a024c70' [Mar 14 12:00:07] DEBUG[5834] chan_sip.c: Hangup call SIP/ugnd_01_ms-0a024c70, SIP callid 6ab1b9cb3742eedd5364ca9b58bc7462@66.61.7.219) [Mar 14 12:00:07] DEBUG[5834] devicestate.c: Notification of state change to be queued on device/channel SIP/ugnd_01_ms-0a024c70 [Mar 14 12:00:07] DEBUG[5834] devicestate.c: Notification of state change to be queued on device/channel SIP/ugnd_01_ms [Mar 14 12:00:07] DEBUG[19196] chan_sip.c: Header 7: Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY (66) [Mar 14 12:00:07] DEBUG[19196] chan_sip.c: Header 8: Content-Length: 0 (17) [Mar 14 12:00:07] DEBUG[19179] res_config_mysql.c: MySQL RealTime: Everything is fine. [Mar 14 12:00:07] VERBOSE[19196] logger.c: --- (9 headers 0 lines) --- [Mar 14 12:00:07] DEBUG[19179] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM sip_buddies WHERE name = 'sip1-0a029780' [Mar 14 12:00:07] DEBUG[19196] chan_sip.c: ** SIP TIMER: Cancelling retransmit of packet (reply received) Retransid #11594 [Mar 14 12:00:07] DEBUG[19196] chan_sip.c: Stopping retransmission on '66990b8a272fe6da314b12284a3041e1@ms1.mysipstuff.com' of Request 103: Match Not Found [Mar 14 12:00:07] WARNING[19196] chan_sip.c: Remote host can't match request BYE to call '66990b8a272fe6da314b12284a3041e1@ms1.mysipstuff.com'. Giving up. [Mar 14 12:00:07] VERBOSE[19196] logger.c: Really destroying SIP dialog '6ab1b9cb3742eedd5364ca9b58bc7462@66.61.7.219' Method: BYE [Mar 14 12:00:07] DEBUG[5834] cdr_addon_mysql.c: cdr_mysql: inserting a CDR record. [Mar 14 12:00:07] DEBUG[5834] cdr_addon_mysql.c: cdr_mysql: SQL command as follows: INSERT INTO cdr (calldate,clid,src,dst,dcontext,channel,dstchannel,lastapp,lastdata,duration,billsec,disposition,amaflags,accountcode,userfield) VALUES ('2008-03-14 11:59:58','\"jpyle\" <2169311212>','2169311212','2168810880','from-ugnd', 'SIP/ugnd_01_ms-0a024c70','SIP/sip1-0a029780','Dial','SIP/2168810880@sip1|180|j',9,5,'ANSWERED',3,'1000873924','') [Mar 14 12:00:07] DEBUG[19179] devicestate.c: Changing state for SIP/sip1-0a029780 - state 4 (Invalid) [Mar 14 12:00:07] DEBUG[19179] devicestate.c: No provider found, checking channel drivers for SIP - sip1 [Mar 14 12:00:07] DEBUG[19179] chan_sip.c: Checking device state for peer sip1 [Mar 14 12:00:07] DEBUG[19179] devicestate.c: Changing state for SIP/sip1 - state 1 (Not in use) [Mar 14 12:00:07] DEBUG[19179] devicestate.c: No provider found, checking channel drivers for SIP - ugnd_01_ms-0a024c70 [Mar 14 12:00:07] DEBUG[19179] chan_sip.c: Checking device state for peer ugnd_01_ms-0a024c70 [Mar 14 12:00:07] DEBUG[19179] res_config_mysql.c: MySQL RealTime: Everything is fine. [Mar 14 12:00:07] DEBUG[19179] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM sip_buddies WHERE name = 'ugnd_01_ms-0a024c70' AND host = 'dynamic' [Mar 14 12:00:07] DEBUG[19179] res_config_mysql.c: MySQL RealTime: Everything is fine. [Mar 14 12:00:07] DEBUG[19179] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM sip_buddies WHERE name = 'ugnd_01_ms-0a024c70' [Mar 14 12:00:07] DEBUG[19179] devicestate.c: Changing state for SIP/ugnd_01_ms-0a024c70 - state 4 (Invalid) [Mar 14 12:00:07] DEBUG[19179] devicestate.c: No provider found, checking channel drivers for SIP - ugnd_01_ms [Mar 14 12:00:07] DEBUG[19179] chan_sip.c: Checking device state for peer ugnd_01_ms [Mar 14 12:00:07] DEBUG[19179] res_config_mysql.c: MySQL RealTime: Everything is fine. [Mar 14 12:00:07] DEBUG[19179] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM sip_buddies WHERE name = 'ugnd_01_ms' AND host = 'dynamic' [Mar 14 12:00:07] DEBUG[19179] chan_sip.c: SIP Seeding peer from astdb: 'ugnd_01_ms' at ugnd_01_ms@66.61.7.219:5060 for 3600 [Mar 14 12:00:07] DEBUG[19179] chan_sip.c: Destroying SIP peer ugnd_01_ms [Mar 14 12:00:07] DEBUG[19179] devicestate.c: Changing state for SIP/ugnd_01_ms - state 1 (Not in use)