<-- SIP read from 10.2.1.1:5060: INVITE sip:8005551212@sip1.networkbox.com;user=phone SIP/2.0 Via: SIP/2.0/UDP 10.2.1.1:5060;branch=z9hG4bK61424d63;rport From: "Jeff Pyle" ;tag=as07e87166 To: Contact: Call-ID: 074d7ae656a856146ce5540672e1be87@10.2.1.1 CSeq: 102 INVITE User-Agent: Asterisk PBX brns Max-Forwards: 70 Date: Tue, 26 Sep 2006 17:41:31 GMT Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Content-Type: application/sdp Content-Length: 217 v=0 o=root 2460 2460 IN IP4 10.2.1.1 s=session c=IN IP4 10.2.1.1 t=0 0 m=audio 16394 RTP/AVP 111 101 a=rtpmap:111 G726-32/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=silenceSupp:off - - - - Sep 26 13:41:31 DEBUG[2934]: chan_sip.c:3382 parse_request: Header 0: INVITE sip:8005551212@sip1.networkbox.com;user=phone SIP/2.0 (63) Sep 26 13:41:31 DEBUG[2934]: chan_sip.c:3382 parse_request: Header 1: Via: SIP/2.0/UDP 10.2.1.1:5060;branch=z9hG4bK61424d63;rport (61) Sep 26 13:41:31 DEBUG[2934]: chan_sip.c:3382 parse_request: Header 2: From: "Jeff Pyle" ;tag=as07e87166 (60) Sep 26 13:41:31 DEBUG[2934]: chan_sip.c:3382 parse_request: Header 3: To: (54) Sep 26 13:41:31 DEBUG[2934]: chan_sip.c:3382 parse_request: Header 4: Contact: (36) Sep 26 13:41:31 DEBUG[2934]: chan_sip.c:3382 parse_request: Header 5: Call-ID: 074d7ae656a856146ce5540672e1be87@10.2.1.1 (52) Sep 26 13:41:31 DEBUG[2934]: chan_sip.c:3382 parse_request: Header 6: CSeq: 102 INVITE (16) Sep 26 13:41:31 DEBUG[2934]: chan_sip.c:3382 parse_request: Header 7: User-Agent: Asterisk PBX brns (29) Sep 26 13:41:31 DEBUG[2934]: chan_sip.c:3382 parse_request: Header 8: Max-Forwards: 70 (16) Sep 26 13:41:31 DEBUG[2934]: chan_sip.c:3382 parse_request: Header 9: Date: Tue, 26 Sep 2006 17:41:31 GMT (35) Sep 26 13:41:31 DEBUG[2934]: chan_sip.c:3382 parse_request: Header 10: Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY (66) Sep 26 13:41:31 DEBUG[2934]: chan_sip.c:3382 parse_request: Header 11: Content-Type: application/sdp (29) Sep 26 13:41:31 DEBUG[2934]: chan_sip.c:3382 parse_request: Header 12: Content-Length: 217 (19) Sep 26 13:41:31 DEBUG[2934]: chan_sip.c:3382 parse_request: Header 13: (0) Sep 26 13:41:31 DEBUG[2934]: chan_sip.c:3414 parse_request: Line: v=0 (3) Sep 26 13:41:31 DEBUG[2934]: chan_sip.c:3414 parse_request: Line: o=root 2460 2460 IN IP4 10.2.1.1 (34) Sep 26 13:41:31 DEBUG[2934]: chan_sip.c:3414 parse_request: Line: s=session (9) Sep 26 13:41:31 DEBUG[2934]: chan_sip.c:3414 parse_request: Line: c=IN IP4 10.2.1.1 (19) Sep 26 13:41:31 DEBUG[2934]: chan_sip.c:3414 parse_request: Line: t=0 0 (5) Sep 26 13:41:31 DEBUG[2934]: chan_sip.c:3414 parse_request: Line: m=audio 16394 RTP/AVP 111 101 (29) Sep 26 13:41:31 DEBUG[2934]: chan_sip.c:3414 parse_request: Line: a=rtpmap:111 G726-32/8000 (25) Sep 26 13:41:31 DEBUG[2934]: chan_sip.c:3414 parse_request: Line: a=rtpmap:101 telephone-event/8000 (33) Sep 26 13:41:31 DEBUG[2934]: chan_sip.c:3414 parse_request: Line: a=fmtp:101 0-16 (15) Sep 26 13:41:31 DEBUG[2934]: chan_sip.c:3414 parse_request: Line: a=silenceSupp:off - - - - (25) --- (13 headers 10 lines)--- Sep 26 13:41:31 DEBUG[2934]: chan_sip.c:3166 sip_alloc: Allocating new SIP dialog for 074d7ae656a856146ce5540672e1be87@10.2.1.1 - INVITE (With RTP) Sep 26 13:41:31 DEBUG[2934]: chan_sip.c:11178 handle_request: **** Received INVITE (5) - Command in SIP INVITE Using INVITE request as basis request - 074d7ae656a856146ce5540672e1be87@10.2.1.1 Sending to 10.2.1.1 : 5060 (NAT) -- SIP Seeding peer from astdb: 'customerbox' at customerbox@10.2.1.1:5060 for 1800 Found peer 'customerbox' Sep 26 13:41:31 DEBUG[2934]: chan_sip.c:7261 check_user_full: Setting NAT on RTP to 0 Reliably Transmitting (no NAT) to 10.2.1.1:5060: SIP/2.0 407 Proxy Authentication Required Via: SIP/2.0/UDP 10.2.1.1:5060;branch=z9hG4bK61424d63;rport;received=10.2.1.1 From: "Jeff Pyle" ;tag=as07e87166 To: ;tag=as7f6204fd Call-ID: 074d7ae656a856146ce5540672e1be87@10.2.1.1 CSeq: 102 INVITE User-Agent: Asterisk PBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Contact: Proxy-Authenticate: Digest algorithm=MD5, realm="funhouse", nonce="72a72bba" Content-Length: 0 --- Sep 26 13:41:31 DEBUG[2934]: chan_sip.c:3382 parse_request: Header 0: SIP/2.0 407 Proxy Authentication Required (41) Sep 26 13:41:31 DEBUG[2934]: chan_sip.c:3382 parse_request: Header 1: Via: SIP/2.0/UDP 10.2.1.1:5060;branch=z9hG4bK61424d63;rport;received=10.2.1.1 (81) Sep 26 13:41:31 DEBUG[2934]: chan_sip.c:3382 parse_request: Header 2: From: "Jeff Pyle" ;tag=as07e87166 (60) Sep 26 13:41:31 DEBUG[2934]: chan_sip.c:3382 parse_request: Header 3: To: ;tag=as7f6204fd (69) Sep 26 13:41:31 DEBUG[2934]: chan_sip.c:3382 parse_request: Header 4: Call-ID: 074d7ae656a856146ce5540672e1be87@10.2.1.1 (52) Sep 26 13:41:31 DEBUG[2934]: chan_sip.c:3382 parse_request: Header 5: CSeq: 102 INVITE (16) Sep 26 13:41:31 DEBUG[2934]: chan_sip.c:3382 parse_request: Header 6: User-Agent: Asterisk PBX (24) Sep 26 13:41:31 DEBUG[2934]: chan_sip.c:3382 parse_request: Header 7: Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY (66) Sep 26 13:41:31 DEBUG[2934]: chan_sip.c:3382 parse_request: Header 8: Contact: (39) Sep 26 13:41:31 DEBUG[2934]: chan_sip.c:3382 parse_request: Header 9: Proxy-Authenticate: Digest algorithm=MD5, realm="funhouse", nonce="72a72bba" (81) Sep 26 13:41:31 DEBUG[2934]: chan_sip.c:3382 parse_request: Header 10: Content-Length: 0 (17) Sep 26 13:41:31 DEBUG[2934]: chan_sip.c:3382 parse_request: Header 11: (0) Sep 26 13:41:31 DEBUG[2934]: chan_sip.c:1302 __sip_reliable_xmit: *** SIP TIMER: Initalizing retransmit timer on packet: Id #170162 Scheduling destruction of call '074d7ae656a856146ce5540672e1be87@10.2.1.1' in 15000 ms rainman*CLI> <-- SIP read from 10.2.1.1:5060: ACK sip:8005551212@sip1.networkbox.com;user=phone SIP/2.0 Via: SIP/2.0/UDP 10.2.1.1:5060;branch=z9hG4bK61424d63;rport From: "Jeff Pyle" ;tag=as07e87166 To: ;tag=as7f6204fd Contact: Call-ID: 074d7ae656a856146ce5540672e1be87@10.2.1.1 CSeq: 102 ACK User-Agent: Asterisk PBX brns Max-Forwards: 70 Content-Length: 0 Sep 26 13:41:31 DEBUG[2934]: chan_sip.c:3382 parse_request: Header 0: ACK sip:8005551212@sip1.networkbox.com;user=phone SIP/2.0 (60) Sep 26 13:41:31 DEBUG[2934]: chan_sip.c:3382 parse_request: Header 1: Via: SIP/2.0/UDP 10.2.1.1:5060;branch=z9hG4bK61424d63;rport (61) Sep 26 13:41:31 DEBUG[2934]: chan_sip.c:3382 parse_request: Header 2: From: "Jeff Pyle" ;tag=as07e87166 (60) Sep 26 13:41:31 DEBUG[2934]: chan_sip.c:3382 parse_request: Header 3: To: ;tag=as7f6204fd (69) Sep 26 13:41:31 DEBUG[2934]: chan_sip.c:3382 parse_request: Header 4: Contact: (36) Sep 26 13:41:31 DEBUG[2934]: chan_sip.c:3382 parse_request: Header 5: Call-ID: 074d7ae656a856146ce5540672e1be87@10.2.1.1 (52) Sep 26 13:41:31 DEBUG[2934]: chan_sip.c:3382 parse_request: Header 6: CSeq: 102 ACK (13) Sep 26 13:41:31 DEBUG[2934]: chan_sip.c:3382 parse_request: Header 7: User-Agent: Asterisk PBX brns (29) Sep 26 13:41:31 DEBUG[2934]: chan_sip.c:3382 parse_request: Header 8: Max-Forwards: 70 (16) Sep 26 13:41:31 DEBUG[2934]: chan_sip.c:3382 parse_request: Header 9: Content-Length: 0 (17) Sep 26 13:41:31 DEBUG[2934]: chan_sip.c:3382 parse_request: Header 10: (0) --- (10 headers 0 lines)--- Sep 26 13:41:31 DEBUG[2934]: chan_sip.c:11178 handle_request: **** Received ACK (6) - Command in SIP ACK Sep 26 13:41:31 DEBUG[2934]: chan_sip.c:1399 __sip_ack: ** SIP TIMER: Cancelling retransmit of packet (reply received) Retransid #170162 Sep 26 13:41:31 DEBUG[2934]: chan_sip.c:1410 __sip_ack: Stopping retransmission on '074d7ae656a856146ce5540672e1be87@10.2.1.1' of Response 102: Match Found rainman*CLI> <-- SIP read from 10.2.1.1:5060: INVITE sip:8005551212@sip1.networkbox.com;user=phone SIP/2.0 Via: SIP/2.0/UDP 10.2.1.1:5060;branch=z9hG4bK5426e3c7;rport From: "Jeff Pyle" ;tag=as07e87166 To: Contact: Call-ID: 074d7ae656a856146ce5540672e1be87@10.2.1.1 CSeq: 103 INVITE User-Agent: Asterisk PBX brns Max-Forwards: 70 Proxy-Authorization: Digest username="customerbox", realm="funhouse", algorithm=MD5, uri="sip:8005551212@sip1.networkbox.com;user=phone", nonce="72a72bba", response="689cc951021544d3462098e2f0bc6a5b", opaque="" Date: Tue, 26 Sep 2006 17:41:31 GMT Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Content-Type: application/sdp Content-Length: 217 v=0 o=root 2460 2461 IN IP4 10.2.1.1 s=session c=IN IP4 10.2.1.1 t=0 0 m=audio 16394 RTP/AVP 111 101 a=rtpmap:111 G726-32/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=silenceSupp:off - - - - Sep 26 13:41:31 DEBUG[2934]: chan_sip.c:3382 parse_request: Header 0: INVITE sip:8005551212@sip1.networkbox.com;user=phone SIP/2.0 (63) Sep 26 13:41:31 DEBUG[2934]: chan_sip.c:3382 parse_request: Header 1: Via: SIP/2.0/UDP 10.2.1.1:5060;branch=z9hG4bK5426e3c7;rport (61) Sep 26 13:41:31 DEBUG[2934]: chan_sip.c:3382 parse_request: Header 2: From: "Jeff Pyle" ;tag=as07e87166 (60) Sep 26 13:41:31 DEBUG[2934]: chan_sip.c:3382 parse_request: Header 3: To: (54) Sep 26 13:41:31 DEBUG[2934]: chan_sip.c:3382 parse_request: Header 4: Contact: (36) Sep 26 13:41:31 DEBUG[2934]: chan_sip.c:3382 parse_request: Header 5: Call-ID: 074d7ae656a856146ce5540672e1be87@10.2.1.1 (52) Sep 26 13:41:31 DEBUG[2934]: chan_sip.c:3382 parse_request: Header 6: CSeq: 103 INVITE (16) Sep 26 13:41:31 DEBUG[2934]: chan_sip.c:3382 parse_request: Header 7: User-Agent: Asterisk PBX brns (29) Sep 26 13:41:31 DEBUG[2934]: chan_sip.c:3382 parse_request: Header 8: Max-Forwards: 70 (16) Sep 26 13:41:31 DEBUG[2934]: chan_sip.c:3382 parse_request: Header 9: Proxy-Authorization: Digest username="customerbox", realm="funhouse", algorithm=MD5, uri="sip:8005551212@sip1.networkbox.com;user=phone", nonce="72a72bba", response="689cc951021544d3462098e2f0bc6a5b", opaque="" (214) Sep 26 13:41:31 DEBUG[2934]: chan_sip.c:3382 parse_request: Header 10: Date: Tue, 26 Sep 2006 17:41:31 GMT (35) Sep 26 13:41:31 DEBUG[2934]: chan_sip.c:3382 parse_request: Header 11: Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY (66) Sep 26 13:41:31 DEBUG[2934]: chan_sip.c:3382 parse_request: Header 12: Content-Type: application/sdp (29) Sep 26 13:41:31 DEBUG[2934]: chan_sip.c:3382 parse_request: Header 13: Content-Length: 217 (19) Sep 26 13:41:31 DEBUG[2934]: chan_sip.c:3382 parse_request: Header 14: (0) Sep 26 13:41:31 DEBUG[2934]: chan_sip.c:3414 parse_request: Line: v=0 (3) Sep 26 13:41:31 DEBUG[2934]: chan_sip.c:3414 parse_request: Line: o=root 2460 2461 IN IP4 10.2.1.1 (34) Sep 26 13:41:31 DEBUG[2934]: chan_sip.c:3414 parse_request: Line: s=session (9) Sep 26 13:41:31 DEBUG[2934]: chan_sip.c:3414 parse_request: Line: c=IN IP4 10.2.1.1 (19) Sep 26 13:41:31 DEBUG[2934]: chan_sip.c:3414 parse_request: Line: t=0 0 (5) Sep 26 13:41:31 DEBUG[2934]: chan_sip.c:3414 parse_request: Line: m=audio 16394 RTP/AVP 111 101 (29) Sep 26 13:41:31 DEBUG[2934]: chan_sip.c:3414 parse_request: Line: a=rtpmap:111 G726-32/8000 (25) Sep 26 13:41:31 DEBUG[2934]: chan_sip.c:3414 parse_request: Line: a=rtpmap:101 telephone-event/8000 (33) Sep 26 13:41:31 DEBUG[2934]: chan_sip.c:3414 parse_request: Line: a=fmtp:101 0-16 (15) Sep 26 13:41:31 DEBUG[2934]: chan_sip.c:3414 parse_request: Line: a=silenceSupp:off - - - - (25) --- (14 headers 10 lines)--- Sep 26 13:41:31 DEBUG[2934]: chan_sip.c:11178 handle_request: **** Received INVITE (5) - Command in SIP INVITE Using INVITE request as basis request - 074d7ae656a856146ce5540672e1be87@10.2.1.1 Sending to 10.2.1.1 : 5060 (NAT) -- SIP Seeding peer from astdb: 'customerbox' at customerbox@10.2.1.1:5060 for 1800 Found peer 'customerbox' Sep 26 13:41:31 DEBUG[2934]: chan_sip.c:7261 check_user_full: Setting NAT on RTP to 0 Found RTP audio format 111 Found RTP audio format 101 Peer audio RTP is at port 10.2.1.1:16394 Sep 26 13:41:31 DEBUG[2934]: chan_sip.c:3621 process_sdp: Peer audio RTP is at port 10.2.1.1:16394 Found description format G726-32 Found description format telephone-event Capabilities: us - 0x41e (gsm|ulaw|alaw|g726|ilbc), peer - audio=0x10 (g726)/video=0x0 (nothing), combined - 0x10 (g726) Non-codec capabilities: us - 0x1 (telephone-event), peer - 0x1 (telephone-event), combined - 0x1 (telephone-event) Sep 26 13:41:31 DEBUG[2934]: chan_sip.c:10530 handle_request_invite: Checking SIP call limits for device customerbox Sep 26 13:41:31 DEBUG[2934]: chan_sip.c:2215 update_call_counter: Updating call counter for incoming call -- SIP Seeding peer from astdb: 'customerbox' at customerbox@10.2.1.1:5060 for 1800 Sep 26 13:41:31 DEBUG[2934]: chan_sip.c:2270 update_call_counter: Call from peer 'customerbox' is 1 out of 1 Looking for 8005551212 in from-ugnd (domain sip1.networkbox.com;user=phone) Sep 26 13:41:31 DEBUG[2934]: chan_sip.c:6152 build_route: build_route: Contact hop: list_route: hop: Transmitting (no NAT) to 10.2.1.1:5060: SIP/2.0 100 Trying Via: SIP/2.0/UDP 10.2.1.1:5060;branch=z9hG4bK5426e3c7;rport;received=10.2.1.1 From: "Jeff Pyle" ;tag=as07e87166 To: Call-ID: 074d7ae656a856146ce5540672e1be87@10.2.1.1 CSeq: 103 INVITE User-Agent: Asterisk PBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Contact: Content-Length: 0 --- Sep 26 13:41:31 DEBUG[2934]: chan_sip.c:3382 parse_request: Header 0: SIP/2.0 100 Trying (18) Sep 26 13:41:31 DEBUG[2934]: chan_sip.c:3382 parse_request: Header 1: Via: SIP/2.0/UDP 10.2.1.1:5060;branch=z9hG4bK5426e3c7;rport;received=10.2.1.1 (81) Sep 26 13:41:31 DEBUG[2934]: chan_sip.c:3382 parse_request: Header 2: From: "Jeff Pyle" ;tag=as07e87166 (60) Sep 26 13:41:31 DEBUG[2934]: chan_sip.c:3382 parse_request: Header 3: To: (54) Sep 26 13:41:31 DEBUG[2934]: chan_sip.c:3382 parse_request: Header 4: Call-ID: 074d7ae656a856146ce5540672e1be87@10.2.1.1 (52) Sep 26 13:41:31 DEBUG[2934]: chan_sip.c:3382 parse_request: Header 5: CSeq: 103 INVITE (16) Sep 26 13:41:31 DEBUG[2934]: chan_sip.c:3382 parse_request: Header 6: User-Agent: Asterisk PBX (24) Sep 26 13:41:31 DEBUG[2934]: chan_sip.c:3382 parse_request: Header 7: Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY (66) Sep 26 13:41:31 DEBUG[2934]: chan_sip.c:3382 parse_request: Header 8: Contact: (39) Sep 26 13:41:31 DEBUG[2934]: chan_sip.c:3382 parse_request: Header 9: Content-Length: 0 (17) Sep 26 13:41:31 DEBUG[2934]: chan_sip.c:3382 parse_request: Header 10: (0) Sep 26 13:41:31 DEBUG[2923]: chan_sip.c:11719 sip_devicestate: Checking device state for peer customerbox -- SIP Seeding peer from astdb: 'customerbox' at customerbox@10.2.1.1:5060 for 1800 Sep 26 13:41:31 DEBUG[2923]: devicestate.c:187 do_state_change: Changing state for SIP/customerbox - state 1 (Not in use) Sep 26 13:41:31 DEBUG[4071]: chan_sip.c:3166 sip_alloc: Allocating new SIP dialog for (No Call-ID) - INVITE (With RTP) Sep 26 13:41:31 DEBUG[4071]: chan_sip.c:1883 create_addr_from_peer: Setting NAT on RTP to 0 Sep 26 13:41:31 DEBUG[4071]: channel.c:2902 ast_channel_inherit_variables: Not copying variable STACK-macro-dialout-s-1. Sep 26 13:41:31 DEBUG[4071]: channel.c:2902 ast_channel_inherit_variables: Not copying variable MACRO_DEPTH. Sep 26 13:41:31 DEBUG[4071]: channel.c:2902 ast_channel_inherit_variables: Not copying variable ARG1. Sep 26 13:41:31 DEBUG[4071]: channel.c:2902 ast_channel_inherit_variables: Not copying variable MACRO_PRIORITY. Sep 26 13:41:31 DEBUG[4071]: channel.c:2902 ast_channel_inherit_variables: Not copying variable MACRO_CONTEXT. Sep 26 13:41:31 DEBUG[4071]: channel.c:2902 ast_channel_inherit_variables: Not copying variable MACRO_EXTEN. Sep 26 13:41:31 DEBUG[4071]: channel.c:2902 ast_channel_inherit_variables: Not copying variable STACK-from-ugnd-8005551212-4. Sep 26 13:41:31 DEBUG[4071]: channel.c:2902 ast_channel_inherit_variables: Not copying variable STACK-from-ugnd-8005551212-3. Sep 26 13:41:31 DEBUG[4071]: channel.c:2902 ast_channel_inherit_variables: Not copying variable STACK-macro-namelookup-s-90. Sep 26 13:41:31 DEBUG[4071]: channel.c:2902 ast_channel_inherit_variables: Not copying variable STACK-macro-namelookup-s-30. Sep 26 13:41:31 DEBUG[4071]: channel.c:2902 ast_channel_inherit_variables: Not copying variable STACK-macro-namelookup-s-20. Sep 26 13:41:31 DEBUG[4071]: channel.c:2902 ast_channel_inherit_variables: Not copying variable STACK-macro-namelookup-s-7. Sep 26 13:41:31 DEBUG[4071]: channel.c:2902 ast_channel_inherit_variables: Not copying variable MYSQL_STATUS. Sep 26 13:41:31 DEBUG[4071]: channel.c:2902 ast_channel_inherit_variables: Not copying variable STACK-macro-namelookup-s-6. Sep 26 13:41:31 DEBUG[4071]: channel.c:2902 ast_channel_inherit_variables: Not copying variable STACK-macro-namelookup-s-5. Sep 26 13:41:31 DEBUG[4071]: channel.c:2902 ast_channel_inherit_variables: Not copying variable fetchid1. Sep 26 13:41:31 DEBUG[4071]: channel.c:2902 ast_channel_inherit_variables: Not copying variable PTHRU. Sep 26 13:41:31 DEBUG[4071]: channel.c:2902 ast_channel_inherit_variables: Not copying variable RESULTNUMBER. Sep 26 13:41:31 DEBUG[4071]: channel.c:2902 ast_channel_inherit_variables: Not copying variable RESULTNAME. Sep 26 13:41:31 DEBUG[4071]: channel.c:2902 ast_channel_inherit_variables: Not copying variable STACK-macro-namelookup-s-4. Sep 26 13:41:31 DEBUG[4071]: channel.c:2902 ast_channel_inherit_variables: Not copying variable resultid1. Sep 26 13:41:31 DEBUG[4071]: channel.c:2902 ast_channel_inherit_variables: Not copying variable STACK-macro-namelookup-s-3. Sep 26 13:41:31 DEBUG[4071]: channel.c:2902 ast_channel_inherit_variables: Not copying variable connid. Sep 26 13:41:31 DEBUG[4071]: channel.c:2902 ast_channel_inherit_variables: Not copying variable STACK-macro-namelookup-s-2. Sep 26 13:41:31 DEBUG[4071]: channel.c:2902 ast_channel_inherit_variables: Not copying variable STACK-macro-namelookup-s-1. Sep 26 13:41:31 DEBUG[4071]: channel.c:2902 ast_channel_inherit_variables: Not copying variable STACK-from-ugnd-8005551212-2. Sep 26 13:41:31 DEBUG[4071]: channel.c:2902 ast_channel_inherit_variables: Not copying variable STACK-macro-checkacct-s-35. Sep 26 13:41:31 DEBUG[4071]: channel.c:2902 ast_channel_inherit_variables: Not copying variable STACK-macro-checkacct-s-30. Sep 26 13:41:31 DEBUG[4071]: channel.c:2902 ast_channel_inherit_variables: Not copying variable STACK-macro-checkacct-s-1. Sep 26 13:41:31 DEBUG[4071]: channel.c:2902 ast_channel_inherit_variables: Not copying variable STACK-from-ugnd-8005551212-1. Sep 26 13:41:31 DEBUG[4071]: channel.c:2902 ast_channel_inherit_variables: Not copying variable SIPCALLID. Sep 26 13:41:31 DEBUG[4071]: channel.c:2902 ast_channel_inherit_variables: Not copying variable SIPUSERAGENT. Sep 26 13:41:31 DEBUG[4071]: channel.c:2902 ast_channel_inherit_variables: Not copying variable SIPDOMAIN. Sep 26 13:41:31 DEBUG[4071]: channel.c:2902 ast_channel_inherit_variables: Not copying variable SIPURI. Sep 26 13:41:31 DEBUG[4071]: chan_sip.c:2077 sip_call: Outgoing Call for 8005551212 Sep 26 13:41:31 DEBUG[4071]: chan_sip.c:2215 update_call_counter: Updating call counter for outgoing call Sep 26 13:41:31 DEBUG[4071]: chan_sip.c:3382 parse_request: Header 0: INVITE sip:8005551212@172.31.32.33;user=phone SIP/2.0 (54) Sep 26 13:41:31 DEBUG[4071]: chan_sip.c:3382 parse_request: Header 1: Via: SIP/2.0/UDP 10.1.1.1:5060;branch=z9hG4bK553d5232;rport (64) Sep 26 13:41:31 DEBUG[4071]: chan_sip.c:3382 parse_request: Header 2: From: "Jeff Pyle" ;tag=as0193c615 (63) Sep 26 13:41:31 DEBUG[4071]: chan_sip.c:3382 parse_request: Header 3: To: (45) Sep 26 13:41:31 DEBUG[4071]: chan_sip.c:3382 parse_request: Header 4: Contact: (39) Sep 26 13:41:31 DEBUG[4071]: chan_sip.c:3382 parse_request: Header 5: Call-ID: 7b884fd81d7eec2e4b236c6038994e44@10.1.1.1 (55) Sep 26 13:41:31 DEBUG[4071]: chan_sip.c:3382 parse_request: Header 6: CSeq: 102 INVITE (16) Sep 26 13:41:31 DEBUG[4071]: chan_sip.c:3382 parse_request: Header 7: User-Agent: Asterisk PBX (24) Sep 26 13:41:31 DEBUG[4071]: chan_sip.c:3382 parse_request: Header 8: Max-Forwards: 70 (16) Sep 26 13:41:31 DEBUG[4071]: chan_sip.c:3382 parse_request: Header 9: Remote-Party-ID: "Jeff Pyle" ;privacy=off;screen=no (81) Sep 26 13:41:31 DEBUG[4071]: chan_sip.c:3382 parse_request: Header 10: Date: Tue, 26 Sep 2006 17:41:31 GMT (35) Sep 26 13:41:31 DEBUG[4071]: chan_sip.c:3382 parse_request: Header 11: Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY (66) Sep 26 13:41:31 DEBUG[4071]: chan_sip.c:3382 parse_request: Header 12: Content-Type: application/sdp (29) Sep 26 13:41:31 DEBUG[4071]: chan_sip.c:3382 parse_request: Header 13: Content-Length: 162 (19) Sep 26 13:41:31 DEBUG[4071]: chan_sip.c:3382 parse_request: Header 14: (0) Sep 26 13:41:31 DEBUG[4071]: chan_sip.c:3414 parse_request: Line: v=0 (3) Sep 26 13:41:31 DEBUG[4071]: chan_sip.c:3414 parse_request: Line: o=root 16674 16674 IN IP4 10.1.1.1 (39) Sep 26 13:41:31 DEBUG[4071]: chan_sip.c:3414 parse_request: Line: s=session (9) Sep 26 13:41:31 DEBUG[4071]: chan_sip.c:3414 parse_request: Line: c=IN IP4 10.1.1.1 (22) Sep 26 13:41:31 DEBUG[4071]: chan_sip.c:3414 parse_request: Line: t=0 0 (5) Sep 26 13:41:31 DEBUG[4071]: chan_sip.c:3414 parse_request: Line: m=audio 42446 RTP/AVP 0 (23) Sep 26 13:41:31 DEBUG[4071]: chan_sip.c:3414 parse_request: Line: a=rtpmap:0 PCMU/8000 (20) Sep 26 13:41:31 DEBUG[4071]: chan_sip.c:3414 parse_request: Line: a=silenceSupp:off - - - - (25) Sep 26 13:41:31 DEBUG[4071]: chan_sip.c:3382 parse_request: Header 0: INVITE sip:8005551212@172.31.32.33;user=phone SIP/2.0 (54) Sep 26 13:41:31 DEBUG[4071]: chan_sip.c:3382 parse_request: Header 1: Via: SIP/2.0/UDP 10.1.1.1:5060;branch=z9hG4bK553d5232;rport (64) Sep 26 13:41:31 DEBUG[4071]: chan_sip.c:3382 parse_request: Header 2: From: "Jeff Pyle" ;tag=as0193c615 (63) Sep 26 13:41:31 DEBUG[4071]: chan_sip.c:3382 parse_request: Header 3: To: (45) Sep 26 13:41:31 DEBUG[4071]: chan_sip.c:3382 parse_request: Header 4: Contact: (39) Sep 26 13:41:31 DEBUG[4071]: chan_sip.c:3382 parse_request: Header 5: Call-ID: 7b884fd81d7eec2e4b236c6038994e44@10.1.1.1 (55) Sep 26 13:41:31 DEBUG[4071]: chan_sip.c:3382 parse_request: Header 6: CSeq: 102 INVITE (16) Sep 26 13:41:31 DEBUG[4071]: chan_sip.c:3382 parse_request: Header 7: User-Agent: Asterisk PBX (24) Sep 26 13:41:31 DEBUG[4071]: chan_sip.c:3382 parse_request: Header 8: Max-Forwards: 70 (16) Sep 26 13:41:31 DEBUG[4071]: chan_sip.c:3382 parse_request: Header 9: Remote-Party-ID: "Jeff Pyle" ;privacy=off;screen=no (81) Sep 26 13:41:31 DEBUG[4071]: chan_sip.c:3382 parse_request: Header 10: Date: Tue, 26 Sep 2006 17:41:31 GMT (35) Sep 26 13:41:31 DEBUG[4071]: chan_sip.c:3382 parse_request: Header 11: Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY (66) Sep 26 13:41:31 DEBUG[4071]: chan_sip.c:3382 parse_request: Header 12: Content-Type: application/sdp (29) Sep 26 13:41:31 DEBUG[4071]: chan_sip.c:3382 parse_request: Header 13: Content-Length: 162 (19) Sep 26 13:41:31 DEBUG[4071]: chan_sip.c:3382 parse_request: Header 14: (0) Sep 26 13:41:31 DEBUG[4071]: chan_sip.c:3414 parse_request: Line: v=0 (3) Sep 26 13:41:31 DEBUG[4071]: chan_sip.c:3414 parse_request: Line: o=root 16674 16674 IN IP4 10.1.1.1 (39) Sep 26 13:41:31 DEBUG[4071]: chan_sip.c:3414 parse_request: Line: s=session (9) Sep 26 13:41:31 DEBUG[4071]: chan_sip.c:3414 parse_request: Line: c=IN IP4 10.1.1.1 (22) Sep 26 13:41:31 DEBUG[4071]: chan_sip.c:3414 parse_request: Line: t=0 0 (5) Sep 26 13:41:31 DEBUG[4071]: chan_sip.c:3414 parse_request: Line: m=audio 42446 RTP/AVP 0 (23) Sep 26 13:41:31 DEBUG[4071]: chan_sip.c:3414 parse_request: Line: a=rtpmap:0 PCMU/8000 (20) Sep 26 13:41:31 DEBUG[4071]: chan_sip.c:3414 parse_request: Line: a=silenceSupp:off - - - - (25) Sep 26 13:41:31 DEBUG[4071]: chan_sip.c:1302 __sip_reliable_xmit: *** SIP TIMER: Initalizing retransmit timer on packet: Id #170167 -- Called 8005551212@carrier-out Sep 26 13:41:31 DEBUG[4071]: channel.c:2409 set_format: Set channel SIP/carrier-out-0927fbe8 to read format slin Sep 26 13:41:31 DEBUG[4071]: channel.c:2409 set_format: Set channel SIP/customerbox-b7a0a228 to write format slin Sep 26 13:41:31 DEBUG[4071]: channel.c:2409 set_format: Set channel SIP/customerbox-b7a0a228 to read format slin Sep 26 13:41:31 DEBUG[4071]: channel.c:2409 set_format: Set channel SIP/carrier-out-0927fbe8 to write format slin Sep 26 13:41:31 DEBUG[2934]: chan_sip.c:3382 parse_request: Header 0: SIP/2.0 100 Trying (18) Sep 26 13:41:31 DEBUG[2934]: chan_sip.c:3382 parse_request: Header 1: Via: SIP/2.0/UDP 10.1.1.1:5060;branch=z9hG4bK553d5232;rport=5060 (69) Sep 26 13:41:31 DEBUG[2934]: chan_sip.c:3382 parse_request: Header 2: From: "Jeff Pyle" ;tag=as0193c615 (63) Sep 26 13:41:31 DEBUG[2934]: chan_sip.c:3382 parse_request: Header 3: To: (45) Sep 26 13:41:31 DEBUG[2934]: chan_sip.c:3382 parse_request: Header 4: Call-ID: 7b884fd81d7eec2e4b236c6038994e44@10.1.1.1 (55) Sep 26 13:41:31 DEBUG[2934]: chan_sip.c:3382 parse_request: Header 5: CSeq: 102 INVITE (16) Sep 26 13:41:31 DEBUG[2934]: chan_sip.c:3382 parse_request: Header 6: (0) Sep 26 13:41:31 DEBUG[2934]: chan_sip.c:1454 __sip_semi_ack: *** SIP TIMER: Cancelling retransmission #170167 - INVITE (got response) Sep 26 13:41:31 DEBUG[2934]: chan_sip.c:1463 __sip_semi_ack: (Provisional) Stopping retransmission (but retaining packet) on '7b884fd81d7eec2e4b236c6038994e44@10.1.1.1' Request 102: Found Sep 26 13:41:31 DEBUG[2934]: chan_sip.c:9601 handle_response_invite: SIP response 100 to standard invite Sep 26 13:41:32 DEBUG[2934]: chan_sip.c:3382 parse_request: Header 0: SIP/2.0 180 Ringing (19) Sep 26 13:41:32 DEBUG[2934]: chan_sip.c:3382 parse_request: Header 1: Via: SIP/2.0/UDP 10.1.1.1:5060;branch=z9hG4bK553d5232;rport=5060 (69) Sep 26 13:41:32 DEBUG[2934]: chan_sip.c:3382 parse_request: Header 2: From: "Jeff Pyle" ;tag=as0193c615 (63) Sep 26 13:41:32 DEBUG[2934]: chan_sip.c:3382 parse_request: Header 3: To: ;tag=SDd5emf99-gK08f7bedf (70) Sep 26 13:41:32 DEBUG[2934]: chan_sip.c:3382 parse_request: Header 4: Call-ID: 7b884fd81d7eec2e4b236c6038994e44@10.1.1.1 (55) Sep 26 13:41:32 DEBUG[2934]: chan_sip.c:3382 parse_request: Header 5: CSeq: 102 INVITE (16) Sep 26 13:41:32 DEBUG[2934]: chan_sip.c:3382 parse_request: Header 6: Contact: (89) Sep 26 13:41:32 DEBUG[2934]: chan_sip.c:3382 parse_request: Header 7: Allow: OPTIONS,INVITE,ACK,CANCEL,BYE,REFER,INFO,SUBSCRIBE,NOTIFY,PRACK,UPDATE (77) Sep 26 13:41:32 DEBUG[2934]: chan_sip.c:3382 parse_request: Header 8: Content-Length: 181 (19) Sep 26 13:41:32 DEBUG[2934]: chan_sip.c:3382 parse_request: Header 9: Content-Disposition: session; handling=required (47) Sep 26 13:41:32 DEBUG[2934]: chan_sip.c:3382 parse_request: Header 10: Content-Type: application/sdp (29) Sep 26 13:41:32 DEBUG[2934]: chan_sip.c:3382 parse_request: Header 11: (0) Sep 26 13:41:32 DEBUG[2934]: chan_sip.c:3414 parse_request: Line: v=0 (3) Sep 26 13:41:32 DEBUG[2934]: chan_sip.c:3414 parse_request: Line: o=Sonus_UAC 20128 6597 IN IP4 172.31.32.33 (43) Sep 26 13:41:32 DEBUG[2934]: chan_sip.c:3414 parse_request: Line: s=SIP Media Capabilities (24) Sep 26 13:41:32 DEBUG[2934]: chan_sip.c:3414 parse_request: Line: c=IN IP4 172.31.32.33 (22) Sep 26 13:41:32 DEBUG[2934]: chan_sip.c:3414 parse_request: Line: t=0 0 (5) Sep 26 13:41:32 DEBUG[2934]: chan_sip.c:3414 parse_request: Line: m=audio 50630 RTP/AVP 0 (23) Sep 26 13:41:32 DEBUG[2934]: chan_sip.c:3414 parse_request: Line: a=rtpmap:0 PCMU/8000 (20) Sep 26 13:41:32 DEBUG[2934]: chan_sip.c:3414 parse_request: Line: a=sendrecv (10) Sep 26 13:41:32 DEBUG[2934]: chan_sip.c:3414 parse_request: Line: a=maxptime:20 (13) Sep 26 13:41:32 DEBUG[2934]: chan_sip.c:1463 __sip_semi_ack: (Provisional) Stopping retransmission (but retaining packet) on '7b884fd81d7eec2e4b236c6038994e44@10.1.1.1' Request 102: Found Sep 26 13:41:32 DEBUG[2934]: chan_sip.c:9601 handle_response_invite: SIP response 180 to standard invite -- SIP/carrier-out-0927fbe8 is ringing Transmitting (no NAT) to 10.2.1.1:5060: SIP/2.0 180 Ringing Via: SIP/2.0/UDP 10.2.1.1:5060;branch=z9hG4bK5426e3c7;rport;received=10.2.1.1 From: "Jeff Pyle" ;tag=as07e87166 To: ;tag=as64782458 Call-ID: 074d7ae656a856146ce5540672e1be87@10.2.1.1 CSeq: 103 INVITE User-Agent: Asterisk PBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Contact: Content-Length: 0 --- Sep 26 13:41:32 DEBUG[4071]: chan_sip.c:3382 parse_request: Header 0: SIP/2.0 180 Ringing (19) Sep 26 13:41:32 DEBUG[4071]: chan_sip.c:3382 parse_request: Header 1: Via: SIP/2.0/UDP 10.2.1.1:5060;branch=z9hG4bK5426e3c7;rport;received=10.2.1.1 (81) Sep 26 13:41:32 DEBUG[4071]: chan_sip.c:3382 parse_request: Header 2: From: "Jeff Pyle" ;tag=as07e87166 (60) Sep 26 13:41:32 DEBUG[4071]: chan_sip.c:3382 parse_request: Header 3: To: ;tag=as64782458 (69) Sep 26 13:41:32 DEBUG[4071]: chan_sip.c:3382 parse_request: Header 4: Call-ID: 074d7ae656a856146ce5540672e1be87@10.2.1.1 (52) Sep 26 13:41:32 DEBUG[4071]: chan_sip.c:3382 parse_request: Header 5: CSeq: 103 INVITE (16) Sep 26 13:41:32 DEBUG[4071]: chan_sip.c:3382 parse_request: Header 6: User-Agent: Asterisk PBX (24) Sep 26 13:41:32 DEBUG[4071]: chan_sip.c:3382 parse_request: Header 7: Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY (66) Sep 26 13:41:32 DEBUG[4071]: chan_sip.c:3382 parse_request: Header 8: Contact: (39) Sep 26 13:41:32 DEBUG[4071]: chan_sip.c:3382 parse_request: Header 9: Content-Length: 0 (17) Sep 26 13:41:32 DEBUG[4071]: chan_sip.c:3382 parse_request: Header 10: (0) Sep 26 13:41:32 DEBUG[2923]: chan_sip.c:11719 sip_devicestate: Checking device state for peer carrier-out Sep 26 13:41:32 DEBUG[2923]: devicestate.c:187 do_state_change: Changing state for SIP/carrier-out - state 6 (Ringing) -- SIP/carrier-out-0927fbe8 is making progress passing it to SIP/customerbox-b7a0a228 We're at 10.1.1.1 port 33166 Adding codec 0x10 (g726) to SDP Adding non-codec 0x1 (telephone-event) to SDP Transmitting (no NAT) to 10.2.1.1:5060: SIP/2.0 183 Session Progress Via: SIP/2.0/UDP 10.2.1.1:5060;branch=z9hG4bK5426e3c7;rport;received=10.2.1.1 From: "Jeff Pyle" ;tag=as07e87166 To: ;tag=as64782458 Call-ID: 074d7ae656a856146ce5540672e1be87@10.2.1.1 CSeq: 103 INVITE User-Agent: Asterisk PBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Contact: Content-Type: application/sdp Content-Length: 225 v=0 o=root 16674 16674 IN IP4 10.1.1.1 s=session c=IN IP4 10.1.1.1 t=0 0 m=audio 33166 RTP/AVP 111 101 a=rtpmap:111 G726-32/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=silenceSupp:off - - - - --- Sep 26 13:41:32 DEBUG[4071]: chan_sip.c:3382 parse_request: Header 0: SIP/2.0 183 Session Progress (28) Sep 26 13:41:32 DEBUG[4071]: chan_sip.c:3382 parse_request: Header 1: Via: SIP/2.0/UDP 10.2.1.1:5060;branch=z9hG4bK5426e3c7;rport;received=10.2.1.1 (81) Sep 26 13:41:32 DEBUG[4071]: chan_sip.c:3382 parse_request: Header 2: From: "Jeff Pyle" ;tag=as07e87166 (60) Sep 26 13:41:32 DEBUG[4071]: chan_sip.c:3382 parse_request: Header 3: To: ;tag=as64782458 (69) Sep 26 13:41:32 DEBUG[4071]: chan_sip.c:3382 parse_request: Header 4: Call-ID: 074d7ae656a856146ce5540672e1be87@10.2.1.1 (52) Sep 26 13:41:32 DEBUG[4071]: chan_sip.c:3382 parse_request: Header 5: CSeq: 103 INVITE (16) Sep 26 13:41:32 DEBUG[4071]: chan_sip.c:3382 parse_request: Header 6: User-Agent: Asterisk PBX (24) Sep 26 13:41:32 DEBUG[4071]: chan_sip.c:3382 parse_request: Header 7: Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY (66) Sep 26 13:41:32 DEBUG[4071]: chan_sip.c:3382 parse_request: Header 8: Contact: (39) Sep 26 13:41:32 DEBUG[4071]: chan_sip.c:3382 parse_request: Header 9: Content-Type: application/sdp (29) Sep 26 13:41:32 DEBUG[4071]: chan_sip.c:3382 parse_request: Header 10: Content-Length: 225 (19) Sep 26 13:41:32 DEBUG[4071]: chan_sip.c:3382 parse_request: Header 11: (0) Sep 26 13:41:32 DEBUG[4071]: chan_sip.c:3414 parse_request: Line: v=0 (3) Sep 26 13:41:32 DEBUG[4071]: chan_sip.c:3414 parse_request: Line: o=root 16674 16674 IN IP4 10.1.1.1 (39) Sep 26 13:41:32 DEBUG[4071]: chan_sip.c:3414 parse_request: Line: s=session (9) Sep 26 13:41:32 DEBUG[4071]: chan_sip.c:3414 parse_request: Line: c=IN IP4 10.1.1.1 (22) Sep 26 13:41:32 DEBUG[4071]: chan_sip.c:3414 parse_request: Line: t=0 0 (5) Sep 26 13:41:32 DEBUG[4071]: chan_sip.c:3414 parse_request: Line: m=audio 33166 RTP/AVP 111 101 (29) Sep 26 13:41:32 DEBUG[4071]: chan_sip.c:3414 parse_request: Line: a=rtpmap:111 G726-32/8000 (25) Sep 26 13:41:32 DEBUG[4071]: chan_sip.c:3414 parse_request: Line: a=rtpmap:101 telephone-event/8000 (33) Sep 26 13:41:32 DEBUG[4071]: chan_sip.c:3414 parse_request: Line: a=fmtp:101 0-16 (15) Sep 26 13:41:32 DEBUG[4071]: chan_sip.c:3414 parse_request: Line: a=silenceSupp:off - - - - (25) Sep 26 13:41:32 DEBUG[4071]: rtp.c:1359 ast_rtp_write: Ooh, format changed from unknown to g726 rainman*CLI> <-- SIP read from 10.2.1.1:5060: BYE sip:8005551212@sip1.networkbox.com;user=phone SIP/2.0 Via: SIP/2.0/UDP 10.2.1.1:5060;branch=z9hG4bK7917373c;rport From: "Jeff Pyle" ;tag=as07e87166 To: ;tag=as64782458 Contact: Call-ID: 074d7ae656a856146ce5540672e1be87@10.2.1.1 CSeq: 104 BYE User-Agent: Asterisk PBX brns Max-Forwards: 70 Proxy-Authorization: Digest username="customerbox", realm="funhouse", algorithm=MD5, uri="sip:8005551212@sip1.networkbox.com;user=phone", nonce="72a72bba", response="53880904156ebf775334c5db7b4c1822", opaque="" Content-Length: 0 Sep 26 13:41:32 DEBUG[2934]: chan_sip.c:3382 parse_request: Header 0: BYE sip:8005551212@sip1.networkbox.com;user=phone SIP/2.0 (60) Sep 26 13:41:32 DEBUG[2934]: chan_sip.c:3382 parse_request: Header 1: Via: SIP/2.0/UDP 10.2.1.1:5060;branch=z9hG4bK7917373c;rport (61) Sep 26 13:41:32 DEBUG[2934]: chan_sip.c:3382 parse_request: Header 2: From: "Jeff Pyle" ;tag=as07e87166 (60) Sep 26 13:41:32 DEBUG[2934]: chan_sip.c:3382 parse_request: Header 3: To: ;tag=as64782458 (69) Sep 26 13:41:32 DEBUG[2934]: chan_sip.c:3382 parse_request: Header 4: Contact: (36) Sep 26 13:41:32 DEBUG[2934]: chan_sip.c:3382 parse_request: Header 5: Call-ID: 074d7ae656a856146ce5540672e1be87@10.2.1.1 (52) Sep 26 13:41:32 DEBUG[2934]: chan_sip.c:3382 parse_request: Header 6: CSeq: 104 BYE (13) Sep 26 13:41:32 DEBUG[2934]: chan_sip.c:3382 parse_request: Header 7: User-Agent: Asterisk PBX brns (29) Sep 26 13:41:32 DEBUG[2934]: chan_sip.c:3382 parse_request: Header 8: Max-Forwards: 70 (16) Sep 26 13:41:32 DEBUG[2934]: chan_sip.c:3382 parse_request: Header 9: Proxy-Authorization: Digest username="customerbox", realm="funhouse", algorithm=MD5, uri="sip:8005551212@sip1.networkbox.com;user=phone", nonce="72a72bba", response="53880904156ebf775334c5db7b4c1822", opaque="" (214) Sep 26 13:41:32 DEBUG[2934]: chan_sip.c:3382 parse_request: Header 10: Content-Length: 0 (17) Sep 26 13:41:32 DEBUG[2934]: chan_sip.c:3382 parse_request: Header 11: (0) --- (11 headers 0 lines)--- Sep 26 13:41:32 DEBUG[2934]: chan_sip.c:11178 handle_request: **** Received BYE (8) - Command in SIP BYE Reliably Transmitting (no NAT) to 10.2.1.1:5060: SIP/2.0 487 Request Terminated Via: SIP/2.0/UDP 10.2.1.1:5060;branch=z9hG4bK5426e3c7;rport;received=10.2.1.1 From: "Jeff Pyle" ;tag=as07e87166 To: ;tag=as64782458 Call-ID: 074d7ae656a856146ce5540672e1be87@10.2.1.1 CSeq: 103 INVITE User-Agent: Asterisk PBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Contact: Content-Length: 0 --- Sep 26 13:41:32 DEBUG[2934]: chan_sip.c:3382 parse_request: Header 0: SIP/2.0 487 Request Terminated (30) Sep 26 13:41:32 DEBUG[2934]: chan_sip.c:3382 parse_request: Header 1: Via: SIP/2.0/UDP 10.2.1.1:5060;branch=z9hG4bK5426e3c7;rport;received=10.2.1.1 (81) Sep 26 13:41:32 DEBUG[2934]: chan_sip.c:3382 parse_request: Header 2: From: "Jeff Pyle" ;tag=as07e87166 (60) Sep 26 13:41:32 DEBUG[2934]: chan_sip.c:3382 parse_request: Header 3: To: ;tag=as64782458 (69) Sep 26 13:41:32 DEBUG[2934]: chan_sip.c:3382 parse_request: Header 4: Call-ID: 074d7ae656a856146ce5540672e1be87@10.2.1.1 (52) Sep 26 13:41:32 DEBUG[2934]: chan_sip.c:3382 parse_request: Header 5: CSeq: 103 INVITE (16) Sep 26 13:41:32 DEBUG[2934]: chan_sip.c:3382 parse_request: Header 6: User-Agent: Asterisk PBX (24) Sep 26 13:41:32 DEBUG[2934]: chan_sip.c:3382 parse_request: Header 7: Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY (66) Sep 26 13:41:32 DEBUG[2934]: chan_sip.c:3382 parse_request: Header 8: Contact: (39) Sep 26 13:41:32 DEBUG[2934]: chan_sip.c:3382 parse_request: Header 9: Content-Length: 0 (17) Sep 26 13:41:32 DEBUG[2934]: chan_sip.c:3382 parse_request: Header 10: (0) Sep 26 13:41:32 DEBUG[2934]: chan_sip.c:1302 __sip_reliable_xmit: *** SIP TIMER: Initalizing retransmit timer on packet: Id #170168 Sending to 10.2.1.1 : 5060 (NAT) Sep 26 13:41:32 DEBUG[2934]: chan_sip.c:10832 handle_request_bye: Received bye, issuing owner hangup .Transmitting (NAT) to 10.2.1.1:5060: SIP/2.0 200 OK Via: SIP/2.0/UDP 10.2.1.1:5060;branch=z9hG4bK7917373c;received=10.2.1.1;rport=5060 From: "Jeff Pyle" ;tag=as07e87166 To: ;tag=as64782458 Call-ID: 074d7ae656a856146ce5540672e1be87@10.2.1.1 CSeq: 104 BYE User-Agent: Asterisk PBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Contact: Content-Length: 0 --- Sep 26 13:41:32 DEBUG[2934]: chan_sip.c:3382 parse_request: Header 0: SIP/2.0 200 OK (14) Sep 26 13:41:32 DEBUG[2934]: chan_sip.c:3382 parse_request: Header 1: Via: SIP/2.0/UDP 10.2.1.1:5060;branch=z9hG4bK7917373c;received=10.2.1.1;rport=5060 (86) Sep 26 13:41:32 DEBUG[2934]: chan_sip.c:3382 parse_request: Header 2: From: "Jeff Pyle" ;tag=as07e87166 (60) Sep 26 13:41:32 DEBUG[2934]: chan_sip.c:3382 parse_request: Header 3: To: ;tag=as64782458 (69) Sep 26 13:41:32 DEBUG[2934]: chan_sip.c:3382 parse_request: Header 4: Call-ID: 074d7ae656a856146ce5540672e1be87@10.2.1.1 (52) Sep 26 13:41:32 DEBUG[2934]: chan_sip.c:3382 parse_request: Header 5: CSeq: 104 BYE (13) Sep 26 13:41:32 DEBUG[2934]: chan_sip.c:3382 parse_request: Header 6: User-Agent: Asterisk PBX (24) Sep 26 13:41:32 DEBUG[2934]: chan_sip.c:3382 parse_request: Header 7: Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY (66) Sep 26 13:41:32 DEBUG[2934]: chan_sip.c:3382 parse_request: Header 8: Contact: (39) Sep 26 13:41:32 DEBUG[2934]: chan_sip.c:3382 parse_request: Header 9: Content-Length: 0 (17) Sep 26 13:41:32 DEBUG[2934]: chan_sip.c:3382 parse_request: Header 10: (0) Sep 26 13:41:32 DEBUG[4071]: channel.c:1373 ast_hangup: Hanging up channel 'SIP/carrier-out-0927fbe8' Sep 26 13:41:32 DEBUG[4071]: chan_sip.c:2425 sip_hangup: Hangup call SIP/carrier-out-0927fbe8, SIP callid 7b884fd81d7eec2e4b236c6038994e44@10.1.1.1) Sep 26 13:41:32 DEBUG[4071]: chan_sip.c:2433 sip_hangup: update_call_counter(8005551212) - decrement call limit counter Sep 26 13:41:32 DEBUG[4071]: chan_sip.c:2215 update_call_counter: Updating call counter for outgoing call Sep 26 13:41:32 DEBUG[4071]: chan_sip.c:1388 __sip_ack: Acked pending invite 102 Sep 26 13:41:32 DEBUG[4071]: chan_sip.c:1410 __sip_ack: Stopping retransmission on '7b884fd81d7eec2e4b236c6038994e44@10.1.1.1' of Request 102: Match Found Sep 26 13:41:32 DEBUG[4071]: chan_sip.c:3382 parse_request: Header 0: CANCEL sip:8005551212@172.31.32.33;user=phone SIP/2.0 (54) Sep 26 13:41:32 DEBUG[4071]: chan_sip.c:3382 parse_request: Header 1: Via: SIP/2.0/UDP 10.1.1.1:5060;branch=z9hG4bK553d5232;rport (64) Sep 26 13:41:32 DEBUG[4071]: chan_sip.c:3382 parse_request: Header 2: From: "Jeff Pyle" ;tag=as0193c615 (63) Sep 26 13:41:32 DEBUG[4071]: chan_sip.c:3382 parse_request: Header 3: To: (45) Sep 26 13:41:32 DEBUG[4071]: chan_sip.c:3382 parse_request: Header 4: Contact: (39) Sep 26 13:41:32 DEBUG[4071]: chan_sip.c:3382 parse_request: Header 5: Call-ID: 7b884fd81d7eec2e4b236c6038994e44@10.1.1.1 (55) Sep 26 13:41:32 DEBUG[4071]: chan_sip.c:3382 parse_request: Header 6: CSeq: 102 CANCEL (16) Sep 26 13:41:32 DEBUG[4071]: chan_sip.c:3382 parse_request: Header 7: User-Agent: Asterisk PBX (24) Sep 26 13:41:32 DEBUG[4071]: chan_sip.c:3382 parse_request: Header 8: Max-Forwards: 70 (16) Sep 26 13:41:32 DEBUG[4071]: chan_sip.c:3382 parse_request: Header 9: Remote-Party-ID: "Jeff Pyle" ;privacy=off;screen=no (81) Sep 26 13:41:32 DEBUG[4071]: chan_sip.c:3382 parse_request: Header 10: Content-Length: 0 (17) Sep 26 13:41:32 DEBUG[4071]: chan_sip.c:3382 parse_request: Header 11: (0) Sep 26 13:41:32 DEBUG[4071]: chan_sip.c:1302 __sip_reliable_xmit: *** SIP TIMER: Initalizing retransmit timer on packet: Id #170170 Sep 26 13:41:32 DEBUG[4071]: app_dial.c:1635 dial_exec_full: Exiting with DIALSTATUS=CANCEL. Sep 26 13:41:32 DEBUG[4071]: app_macro.c:236 macro_exec: Spawn extension (macro-dialout,s,1) exited non-zero on 'SIP/customerbox-b7a0a228' in macro 'dialout' Sep 26 13:41:32 DEBUG[2923]: chan_sip.c:11719 sip_devicestate: Checking device state for peer carrier-out Sep 26 13:41:32 DEBUG[2923]: devicestate.c:187 do_state_change: Changing state for SIP/carrier-out - state 1 (Not in use) Sep 26 13:41:32 DEBUG[4071]: channel.c:1373 ast_hangup: Hanging up channel 'SIP/customerbox-b7a0a228' Sep 26 13:41:32 DEBUG[4071]: chan_sip.c:2425 sip_hangup: Hangup call SIP/customerbox-b7a0a228, SIP callid 074d7ae656a856146ce5540672e1be87@10.2.1.1) Sep 26 13:41:32 DEBUG[4071]: chan_sip.c:2433 sip_hangup: update_call_counter(customerbox) - decrement call limit counter Sep 26 13:41:32 DEBUG[4071]: chan_sip.c:2215 update_call_counter: Updating call counter for incoming call -- SIP Seeding peer from astdb: 'customerbox' at customerbox@10.2.1.1:5060 for 1800 Sep 26 13:41:32 DEBUG[4071]: chan_sip.c:2253 update_call_counter: Call from peer 'customerbox' removed from call limit 1 Sep 26 13:41:32 DEBUG[2923]: chan_sip.c:11719 sip_devicestate: Checking device state for peer customerbox -- SIP Seeding peer from astdb: 'customerbox' at customerbox@10.2.1.1:5060 for 1800 Sep 26 13:41:32 DEBUG[2923]: devicestate.c:187 do_state_change: Changing state for SIP/customerbox - state 1 (Not in use) Sep 26 13:41:32 DEBUG[2934]: chan_sip.c:3382 parse_request: Header 0: SIP/2.0 200 OK (14) Sep 26 13:41:32 DEBUG[2934]: chan_sip.c:3382 parse_request: Header 1: Via: SIP/2.0/UDP 10.1.1.1:5060;branch=z9hG4bK553d5232;rport=5060 (69) Sep 26 13:41:32 DEBUG[2934]: chan_sip.c:3382 parse_request: Header 2: From: "Jeff Pyle" ;tag=as0193c615 (63) Sep 26 13:41:32 DEBUG[2934]: chan_sip.c:3382 parse_request: Header 3: To: ;tag=SDd5emf99-gK08f7bedf (70) Sep 26 13:41:32 DEBUG[2934]: chan_sip.c:3382 parse_request: Header 4: Call-ID: 7b884fd81d7eec2e4b236c6038994e44@10.1.1.1 (55) Sep 26 13:41:32 DEBUG[2934]: chan_sip.c:3382 parse_request: Header 5: CSeq: 102 CANCEL (16) Sep 26 13:41:32 DEBUG[2934]: chan_sip.c:3382 parse_request: Header 6: (0) Sep 26 13:41:32 DEBUG[2934]: chan_sip.c:1399 __sip_ack: ** SIP TIMER: Cancelling retransmit of packet (reply received) Retransid #170170 Sep 26 13:41:32 DEBUG[2934]: chan_sip.c:1410 __sip_ack: Stopping retransmission on '7b884fd81d7eec2e4b236c6038994e44@10.1.1.1' of Request 102: Match Found Sep 26 13:41:32 DEBUG[2934]: chan_sip.c:3382 parse_request: Header 0: SIP/2.0 487 Request Terminated (30) Sep 26 13:41:32 DEBUG[2934]: chan_sip.c:3382 parse_request: Header 1: Via: SIP/2.0/UDP 10.1.1.1:5060;branch=z9hG4bK553d5232;rport=5060 (69) Sep 26 13:41:32 DEBUG[2934]: chan_sip.c:3382 parse_request: Header 2: From: "Jeff Pyle" ;tag=as0193c615 (63) Sep 26 13:41:32 DEBUG[2934]: chan_sip.c:3382 parse_request: Header 3: To: ;tag=SDd5emf99-gK08f7bedf (70) Sep 26 13:41:32 DEBUG[2934]: chan_sip.c:3382 parse_request: Header 4: Call-ID: 7b884fd81d7eec2e4b236c6038994e44@10.1.1.1 (55) Sep 26 13:41:32 DEBUG[2934]: chan_sip.c:3382 parse_request: Header 5: CSeq: 102 INVITE (16) Sep 26 13:41:32 DEBUG[2934]: chan_sip.c:3382 parse_request: Header 6: Content-Length: 0 (17) Sep 26 13:41:32 DEBUG[2934]: chan_sip.c:3382 parse_request: Header 7: (0) Sep 26 13:41:32 DEBUG[2934]: chan_sip.c:1410 __sip_ack: Stopping retransmission on '7b884fd81d7eec2e4b236c6038994e44@10.1.1.1' of Request 102: Match Not Found Sep 26 13:41:32 DEBUG[2934]: chan_sip.c:2215 update_call_counter: Updating call counter for outgoing call Sep 26 13:41:32 DEBUG[2934]: chan_sip.c:3382 parse_request: Header 0: ACK sip:8005551212@172.31.32.33;user=phone SIP/2.0 (51) Sep 26 13:41:32 DEBUG[2934]: chan_sip.c:3382 parse_request: Header 1: Via: SIP/2.0/UDP 10.1.1.1:5060;branch=z9hG4bK553d5232;rport (64) Sep 26 13:41:32 DEBUG[2934]: chan_sip.c:3382 parse_request: Header 2: From: "Jeff Pyle" ;tag=as0193c615 (63) Sep 26 13:41:32 DEBUG[2934]: chan_sip.c:3382 parse_request: Header 3: To: ;tag=SDd5emf99-gK08f7bedf (70) Sep 26 13:41:32 DEBUG[2934]: chan_sip.c:3382 parse_request: Header 4: Contact: (39) Sep 26 13:41:32 DEBUG[2934]: chan_sip.c:3382 parse_request: Header 5: Call-ID: 7b884fd81d7eec2e4b236c6038994e44@10.1.1.1 (55) Sep 26 13:41:32 DEBUG[2934]: chan_sip.c:3382 parse_request: Header 6: CSeq: 102 ACK (13) Sep 26 13:41:32 DEBUG[2934]: chan_sip.c:3382 parse_request: Header 7: User-Agent: Asterisk PBX (24) Sep 26 13:41:32 DEBUG[2934]: chan_sip.c:3382 parse_request: Header 8: Max-Forwards: 70 (16) Sep 26 13:41:32 DEBUG[2934]: chan_sip.c:3382 parse_request: Header 9: Remote-Party-ID: "Jeff Pyle" ;privacy=off;screen=no (81) Sep 26 13:41:32 DEBUG[2934]: chan_sip.c:3382 parse_request: Header 10: Content-Length: 0 (17) Sep 26 13:41:32 DEBUG[2934]: chan_sip.c:3382 parse_request: Header 11: (0) Sep 26 13:41:32 DEBUG[2934]: chan_sip.c:8721 sip_dump_history: ---------- SIP HISTORY for '7b884fd81d7eec2e4b236c6038994e44@10.1.1.1' Sep 26 13:41:32 DEBUG[2934]: chan_sip.c:8725 sip_dump_history: * SIP Call Sep 26 13:41:32 DEBUG[2934]: chan_sip.c:8730 sip_dump_history: 1. TxReqRel INVITE / 102 INVITE Sep 26 13:41:32 DEBUG[2934]: chan_sip.c:8730 sip_dump_history: 2. Rx SIP/2.0 / 102 INVITE /100 Trying Sep 26 13:41:32 DEBUG[2934]: chan_sip.c:8730 sip_dump_history: 3. CancelDestroy Sep 26 13:41:32 DEBUG[2934]: chan_sip.c:8730 sip_dump_history: 4. Rx SIP/2.0 / 102 INVITE /180 Ringing Sep 26 13:41:32 DEBUG[2934]: chan_sip.c:8730 sip_dump_history: 5. CancelDestroy Sep 26 13:41:32 DEBUG[2934]: chan_sip.c:8730 sip_dump_history: 6. Unhold SIP/2.0 Sep 26 13:41:32 DEBUG[2934]: chan_sip.c:8730 sip_dump_history: 7. SchedDestroy 32000 ms Sep 26 13:41:32 DEBUG[2934]: chan_sip.c:8730 sip_dump_history: 8. TxReqRel CANCEL / 102 CANCEL Sep 26 13:41:32 DEBUG[2934]: chan_sip.c:8730 sip_dump_history: 9. Rx SIP/2.0 / 102 CANCEL /200 OK Sep 26 13:41:32 DEBUG[2934]: chan_sip.c:8730 sip_dump_history: 10. Rx SIP/2.0 / 102 INVITE /487 Request Terminated Sep 26 13:41:32 DEBUG[2934]: chan_sip.c:8730 sip_dump_history: 11. TxReq ACK / 102 ACK Sep 26 13:41:32 DEBUG[2934]: chan_sip.c:8735 sip_dump_history: ---------- END SIP HISTORY for '7b884fd81d7eec2e4b236c6038994e44@10.1.1.1' rainman*CLI> <-- SIP read from 10.2.1.1:5060: ACK sip:8005551212@sip1.networkbox.com;user=phone SIP/2.0 Via: SIP/2.0/UDP 10.2.1.1:5060;branch=z9hG4bK7917373c;rport From: "Jeff Pyle" ;tag=as07e87166 To: ;tag=as64782458 Contact: Call-ID: 074d7ae656a856146ce5540672e1be87@10.2.1.1 CSeq: 103 ACK User-Agent: Asterisk PBX brns Max-Forwards: 70 Content-Length: 0 Sep 26 13:41:33 DEBUG[2934]: chan_sip.c:3382 parse_request: Header 0: ACK sip:8005551212@sip1.networkbox.com;user=phone SIP/2.0 (60) Sep 26 13:41:33 DEBUG[2934]: chan_sip.c:3382 parse_request: Header 1: Via: SIP/2.0/UDP 10.2.1.1:5060;branch=z9hG4bK7917373c;rport (61) Sep 26 13:41:33 DEBUG[2934]: chan_sip.c:3382 parse_request: Header 2: From: "Jeff Pyle" ;tag=as07e87166 (60) Sep 26 13:41:33 DEBUG[2934]: chan_sip.c:3382 parse_request: Header 3: To: ;tag=as64782458 (69) Sep 26 13:41:33 DEBUG[2934]: chan_sip.c:3382 parse_request: Header 4: Contact: (36) Sep 26 13:41:33 DEBUG[2934]: chan_sip.c:3382 parse_request: Header 5: Call-ID: 074d7ae656a856146ce5540672e1be87@10.2.1.1 (52) Sep 26 13:41:33 DEBUG[2934]: chan_sip.c:3382 parse_request: Header 6: CSeq: 103 ACK (13) Sep 26 13:41:33 DEBUG[2934]: chan_sip.c:3382 parse_request: Header 7: User-Agent: Asterisk PBX brns (29) Sep 26 13:41:33 DEBUG[2934]: chan_sip.c:3382 parse_request: Header 8: Max-Forwards: 70 (16) Sep 26 13:41:33 DEBUG[2934]: chan_sip.c:3382 parse_request: Header 9: Content-Length: 0 (17) Sep 26 13:41:33 DEBUG[2934]: chan_sip.c:3382 parse_request: Header 10: (0) --- (10 headers 0 lines)--- Sep 26 13:41:33 DEBUG[2934]: chan_sip.c:11178 handle_request: **** Received ACK (6) - Command in SIP ACK Sep 26 13:41:33 DEBUG[2934]: chan_sip.c:11182 handle_request: Ignoring too old SIP packet packet 103 (expecting >= 104) Sep 26 13:41:33 DEBUG[2934]: chan_sip.c:11379 sipsock_read: SIP message could not be handled, bad request: 074d7ae656a856146ce5540672e1be87@10.2.1.1 Sep 26 13:41:33 DEBUG[2934]: chan_sip.c:1193 retrans_pkt: SIP TIMER: Rescheduling retransmission #170168 (1) SIP/2.0 - 1 Sep 26 13:41:33 DEBUG[2934]: chan_sip.c:1207 retrans_pkt: ** SIP timers: Rescheduling retransmission 2 to 1000 ms (t1 500 ms (Retrans id #170168)) Retransmitting #1 (NAT) to 10.2.1.1:5060: SIP/2.0 487 Request Terminated Via: SIP/2.0/UDP 10.2.1.1:5060;branch=z9hG4bK5426e3c7;rport;received=10.2.1.1 From: "Jeff Pyle" ;tag=as07e87166 To: ;tag=as64782458 Call-ID: 074d7ae656a856146ce5540672e1be87@10.2.1.1 CSeq: 103 INVITE User-Agent: Asterisk PBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Contact: Content-Length: 0 --- == Refreshing DNS lookups. Sep 26 13:41:34 DEBUG[2934]: chan_sip.c:1193 retrans_pkt: SIP TIMER: Rescheduling retransmission #170168 (2) SIP/2.0 - 1 Sep 26 13:41:34 DEBUG[2934]: chan_sip.c:1207 retrans_pkt: ** SIP timers: Rescheduling retransmission 3 to 2000 ms (t1 500 ms (Retrans id #170168)) Retransmitting #2 (NAT) to 10.2.1.1:5060: SIP/2.0 487 Request Terminated Via: SIP/2.0/UDP 10.2.1.1:5060;branch=z9hG4bK5426e3c7;rport;received=10.2.1.1 From: "Jeff Pyle" ;tag=as07e87166 To: ;tag=as64782458 Call-ID: 074d7ae656a856146ce5540672e1be87@10.2.1.1 CSeq: 103 INVITE User-Agent: Asterisk PBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Contact: Content-Length: 0 --- Sep 26 13:41:36 DEBUG[2934]: chan_sip.c:1193 retrans_pkt: SIP TIMER: Rescheduling retransmission #170168 (3) SIP/2.0 - 1 Sep 26 13:41:36 DEBUG[2934]: chan_sip.c:1207 retrans_pkt: ** SIP timers: Rescheduling retransmission 4 to 4000 ms (t1 500 ms (Retrans id #170168)) Retransmitting #3 (NAT) to 10.2.1.1:5060: SIP/2.0 487 Request Terminated Via: SIP/2.0/UDP 10.2.1.1:5060;branch=z9hG4bK5426e3c7;rport;received=10.2.1.1 From: "Jeff Pyle" ;tag=as07e87166 To: ;tag=as64782458 Call-ID: 074d7ae656a856146ce5540672e1be87@10.2.1.1 CSeq: 103 INVITE User-Agent: Asterisk PBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Contact: Content-Length: 0 --- Sep 26 13:41:40 DEBUG[2934]: chan_sip.c:1193 retrans_pkt: SIP TIMER: Rescheduling retransmission #170168 (4) SIP/2.0 - 1 Sep 26 13:41:40 DEBUG[2934]: chan_sip.c:1207 retrans_pkt: ** SIP timers: Rescheduling retransmission 5 to 4000 ms (t1 500 ms (Retrans id #170168)) Retransmitting #4 (NAT) to 10.2.1.1:5060: SIP/2.0 487 Request Terminated Via: SIP/2.0/UDP 10.2.1.1:5060;branch=z9hG4bK5426e3c7;rport;received=10.2.1.1 From: "Jeff Pyle" ;tag=as07e87166 To: ;tag=as64782458 Call-ID: 074d7ae656a856146ce5540672e1be87@10.2.1.1 CSeq: 103 INVITE User-Agent: Asterisk PBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Contact: Content-Length: 0 --- Sep 26 13:41:44 DEBUG[2934]: chan_sip.c:1193 retrans_pkt: SIP TIMER: Rescheduling retransmission #170168 (5) SIP/2.0 - 1 Sep 26 13:41:44 DEBUG[2934]: chan_sip.c:1207 retrans_pkt: ** SIP timers: Rescheduling retransmission 6 to 4000 ms (t1 500 ms (Retrans id #170168)) Retransmitting #5 (NAT) to 10.2.1.1:5060: SIP/2.0 487 Request Terminated Via: SIP/2.0/UDP 10.2.1.1:5060;branch=z9hG4bK5426e3c7;rport;received=10.2.1.1 From: "Jeff Pyle" ;tag=as07e87166 To: ;tag=as64782458 Call-ID: 074d7ae656a856146ce5540672e1be87@10.2.1.1 CSeq: 103 INVITE User-Agent: Asterisk PBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Contact: Content-Length: 0 ---