proxy5*CLI> sip debug SIP Debugging enabled proxy5*CLI> <-- SIP read from 67.58.10.169:5061: INVITE sip:17025770986@207.15.15.3 SIP/2.0 Via: SIP/2.0/UDP 67.58.10.169:5061;branch=z9hG4bK-5564369c From: 2142700823 ;tag=aed577cc5b1357d8o1 To: Remote-Party-ID: 2142700823 ;screen=yes;party=calling Call-ID: 73ccdddc-9bcca908@67.58.10.169 CSeq: 101 INVITE Max-Forwards: 70 Contact: 2142700823 Expires: 240 User-Agent: Sipura/SPA2100-3.2.5(d) Content-Length: 249 Allow: ACK, BYE, CANCEL, INFO, INVITE, NOTIFY, OPTIONS, REFER Supported: x-sipura Content-Type: application/sdp v=0 o=- 6837 6837 IN IP4 67.58.10.169 s=- c=IN IP4 67.58.10.169 t=0 0 m=audio 16414 RTP/AVP 0 100 101 a=rtpmap:0 PCMU/8000 a=rtpmap:100 NSE/8000 a=fmtp:100 192-193 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-15 a=ptime:30 a=sendrecv Jun 8 16:19:59 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 0: INVITE sip:17025770986@207.15.15.3 SIP/2.0 (44) Jun 8 16:19:59 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 1: Via: SIP/2.0/UDP 67.58.10.169:5061;branch=z9hG4bK-5564369c (58) Jun 8 16:19:59 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 2: From: 2142700823 ;tag=aed577cc5b1357d8o1 (65) Jun 8 16:19:59 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 3: To: (35) Jun 8 16:19:59 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 4: Remote-Party-ID: 2142700823 ;screen=yes;party=calling (78) Jun 8 16:19:59 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 5: Call-ID: 73ccdddc-9bcca908@67.58.10.169 (39) Jun 8 16:19:59 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 6: CSeq: 101 INVITE (16) Jun 8 16:19:59 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 7: Max-Forwards: 70 (16) Jun 8 16:19:59 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 8: Contact: 2142700823 (49) Jun 8 16:19:59 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 9: Expires: 240 (12) Jun 8 16:19:59 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 10: User-Agent: Sipura/SPA2100-3.2.5(d) (35) Jun 8 16:19:59 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 11: Content-Length: 249 (19) Jun 8 16:19:59 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 12: Allow: ACK, BYE, CANCEL, INFO, INVITE, NOTIFY, OPTIONS, REFER (61) Jun 8 16:19:59 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 13: Supported: x-sipura (19) Jun 8 16:19:59 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 14: Content-Type: application/sdp (29) Jun 8 16:19:59 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 15: (0) Jun 8 16:19:59 DEBUG[31477]: chan_sip.c:4170 parse_request: Line: v=0 (3) Jun 8 16:19:59 DEBUG[31477]: chan_sip.c:4170 parse_request: Line: o=- 6837 6837 IN IP4 67.58.10.169 (33) Jun 8 16:19:59 DEBUG[31477]: chan_sip.c:4170 parse_request: Line: s=- (3) Jun 8 16:19:59 DEBUG[31477]: chan_sip.c:4170 parse_request: Line: c=IN IP4 67.58.10.169 (21) Jun 8 16:19:59 DEBUG[31477]: chan_sip.c:4170 parse_request: Line: t=0 0 (5) Jun 8 16:19:59 DEBUG[31477]: chan_sip.c:4170 parse_request: Line: m=audio 16414 RTP/AVP 0 100 101 (31) Jun 8 16:19:59 DEBUG[31477]: chan_sip.c:4170 parse_request: Line: a=rtpmap:0 PCMU/8000 (20) Jun 8 16:19:59 DEBUG[31477]: chan_sip.c:4170 parse_request: Line: a=rtpmap:100 NSE/8000 (21) Jun 8 16:19:59 DEBUG[31477]: chan_sip.c:4170 parse_request: Line: a=fmtp:100 192-193 (18) Jun 8 16:19:59 DEBUG[31477]: chan_sip.c:4170 parse_request: Line: a=rtpmap:101 telephone-event/8000 (33) Jun 8 16:19:59 DEBUG[31477]: chan_sip.c:4170 parse_request: Line: a=fmtp:101 0-15 (15) Jun 8 16:19:59 DEBUG[31477]: chan_sip.c:4170 parse_request: Line: a=ptime:30 (10) Jun 8 16:19:59 DEBUG[31477]: chan_sip.c:4170 parse_request: Line: a=sendrecv (10) --- (15 headers 13 lines)--- Jun 8 16:19:59 DEBUG[31477]: chan_sip.c:3918 sip_alloc: Allocating new SIP dialog for 73ccdddc-9bcca908@67.58.10.169 - INVITE (With RTP) Jun 8 16:19:59 DEBUG[31477]: chan_sip.c:13633 handle_request: **** Received INVITE (5) - Command in SIP INVITE Jun 8 16:19:59 DEBUG[31477]: chan_sip.c:1453 parse_sip_options: Begin: parsing SIP "Supported: x-sipura" Jun 8 16:19:59 DEBUG[31477]: chan_sip.c:1461 parse_sip_options: Found SIP option: -x-sipura- Jun 8 16:19:59 DEBUG[31477]: chan_sip.c:1472 parse_sip_options: Found no match for SIP option: x-sipura (Please file bug report!) Sending to 67.58.10.169 : 5061 (no NAT) Using INVITE request as basis request - 73ccdddc-9bcca908@67.58.10.169 Jun 8 16:19:59 DEBUG[31477]: chan_sip.c:8302 check_user_full: Setting NAT on RTP to Off Reliably Transmitting (no NAT) to 67.58.10.169:5061: SIP/2.0 407 Proxy Authentication Required Via: SIP/2.0/UDP 67.58.10.169:5061;branch=z9hG4bK-5564369c;received=67.58.10.169 From: 2142700823 ;tag=aed577cc5b1357d8o1 To: ;tag=as4f8a6fca Call-ID: 73ccdddc-9bcca908@67.58.10.169 CSeq: 101 INVITE User-Agent: Lucent-Universal-Gateway Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Supported: replaces Contact: Proxy-Authenticate: Digest realm="asterisk", nonce="541d4006" Content-Length: 0 --- Jun 8 16:19:59 DEBUG[31477]: chan_sip.c:1746 __sip_reliable_xmit: *** SIP TIMER: Initalizing retransmit timer on packet: Id #4 Scheduling destruction of SIP dialog '73ccdddc-9bcca908@67.58.10.169' in 32000 ms (Method: INVITE) Found user 'test2' proxy5*CLI> <-- SIP read from 67.58.10.169:5061: ACK sip:17025770986@207.15.15.3 SIP/2.0 Via: SIP/2.0/UDP 67.58.10.169:5061;branch=z9hG4bK-5564369c From: 2142700823 ;tag=aed577cc5b1357d8o1 To: ;tag=as4f8a6fca Call-ID: 73ccdddc-9bcca908@67.58.10.169 CSeq: 101 ACK Max-Forwards: 70 Contact: 2142700823 User-Agent: Sipura/SPA2100-3.2.5(d) Content-Length: 0 Jun 8 16:19:59 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 0: ACK sip:17025770986@207.15.15.3 SIP/2.0 (41) Jun 8 16:19:59 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 1: Via: SIP/2.0/UDP 67.58.10.169:5061;branch=z9hG4bK-5564369c (58) Jun 8 16:19:59 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 2: From: 2142700823 ;tag=aed577cc5b1357d8o1 (65) Jun 8 16:19:59 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 3: To: ;tag=as4f8a6fca (50) Jun 8 16:19:59 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 4: Call-ID: 73ccdddc-9bcca908@67.58.10.169 (39) Jun 8 16:19:59 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 5: CSeq: 101 ACK (13) Jun 8 16:19:59 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 6: Max-Forwards: 70 (16) Jun 8 16:19:59 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 7: Contact: 2142700823 (49) Jun 8 16:19:59 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 8: User-Agent: Sipura/SPA2100-3.2.5(d) (35) Jun 8 16:19:59 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 9: Content-Length: 0 (17) Jun 8 16:19:59 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 10: (0) --- (10 headers 0 lines)--- Jun 8 16:19:59 DEBUG[31477]: chan_sip.c:13633 handle_request: **** Received ACK (6) - Command in SIP ACK Jun 8 16:19:59 DEBUG[31477]: chan_sip.c:1838 __sip_ack: ** SIP TIMER: Cancelling retransmit of packet (reply received) Retransid #4 Jun 8 16:19:59 DEBUG[31477]: chan_sip.c:1849 __sip_ack: Stopping retransmission on '73ccdddc-9bcca908@67.58.10.169' of Response 101: Match Found proxy5*CLI> <-- SIP read from 67.58.10.169:5061: INVITE sip:17025770986@207.15.15.3 SIP/2.0 Via: SIP/2.0/UDP 67.58.10.169:5061;branch=z9hG4bK-8ed6a680 From: 2142700823 ;tag=aed577cc5b1357d8o1 To: Remote-Party-ID: 2142700823 ;screen=yes;party=calling Call-ID: 73ccdddc-9bcca908@67.58.10.169 CSeq: 102 INVITE Max-Forwards: 70 Proxy-Authorization: Digest username="test2",realm="asterisk",nonce="541d4006",uri="sip:17025770986@207.15.15.3",algorithm=MD5,response="04a4cc7f72ca22a21e7e46530ad9d06e" Contact: 2142700823 Expires: 240 User-Agent: Sipura/SPA2100-3.2.5(d) Content-Length: 249 Allow: ACK, BYE, CANCEL, INFO, INVITE, NOTIFY, OPTIONS, REFER Supported: x-sipura Content-Type: application/sdp v=0 o=- 6837 6837 IN IP4 67.58.10.169 s=- c=IN IP4 67.58.10.169 t=0 0 m=audio 16414 RTP/AVP 0 100 101 a=rtpmap:0 PCMU/8000 a=rtpmap:100 NSE/8000 a=fmtp:100 192-193 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-15 a=ptime:30 a=sendrecv Jun 8 16:19:59 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 0: INVITE sip:17025770986@207.15.15.3 SIP/2.0 (44) Jun 8 16:19:59 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 1: Via: SIP/2.0/UDP 67.58.10.169:5061;branch=z9hG4bK-8ed6a680 (58) Jun 8 16:19:59 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 2: From: 2142700823 ;tag=aed577cc5b1357d8o1 (65) Jun 8 16:19:59 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 3: To: (35) Jun 8 16:19:59 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 4: Remote-Party-ID: 2142700823 ;screen=yes;party=calling (78) Jun 8 16:19:59 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 5: Call-ID: 73ccdddc-9bcca908@67.58.10.169 (39) Jun 8 16:19:59 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 6: CSeq: 102 INVITE (16) Jun 8 16:19:59 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 7: Max-Forwards: 70 (16) Jun 8 16:19:59 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 8: Proxy-Authorization: Digest username="test2",realm="asterisk",nonce="541d4006",uri="sip:17025770986@207.15.15.3",algorithm=MD5,response="04a4cc7f72ca22a21e7e46530ad9d06e" (172) Jun 8 16:19:59 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 9: Contact: 2142700823 (49) Jun 8 16:19:59 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 10: Expires: 240 (12) Jun 8 16:19:59 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 11: User-Agent: Sipura/SPA2100-3.2.5(d) (35) Jun 8 16:19:59 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 12: Content-Length: 249 (19) Jun 8 16:19:59 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 13: Allow: ACK, BYE, CANCEL, INFO, INVITE, NOTIFY, OPTIONS, REFER (61) Jun 8 16:19:59 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 14: Supported: x-sipura (19) Jun 8 16:19:59 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 15: Content-Type: application/sdp (29) Jun 8 16:19:59 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 16: (0) Jun 8 16:19:59 DEBUG[31477]: chan_sip.c:4170 parse_request: Line: v=0 (3) Jun 8 16:19:59 DEBUG[31477]: chan_sip.c:4170 parse_request: Line: o=- 6837 6837 IN IP4 67.58.10.169 (33) Jun 8 16:19:59 DEBUG[31477]: chan_sip.c:4170 parse_request: Line: s=- (3) Jun 8 16:19:59 DEBUG[31477]: chan_sip.c:4170 parse_request: Line: c=IN IP4 67.58.10.169 (21) Jun 8 16:19:59 DEBUG[31477]: chan_sip.c:4170 parse_request: Line: t=0 0 (5) Jun 8 16:19:59 DEBUG[31477]: chan_sip.c:4170 parse_request: Line: m=audio 16414 RTP/AVP 0 100 101 (31) Jun 8 16:19:59 DEBUG[31477]: chan_sip.c:4170 parse_request: Line: a=rtpmap:0 PCMU/8000 (20) Jun 8 16:19:59 DEBUG[31477]: chan_sip.c:4170 parse_request: Line: a=rtpmap:100 NSE/8000 (21) Jun 8 16:19:59 DEBUG[31477]: chan_sip.c:4170 parse_request: Line: a=fmtp:100 192-193 (18) Jun 8 16:19:59 DEBUG[31477]: chan_sip.c:4170 parse_request: Line: a=rtpmap:101 telephone-event/8000 (33) Jun 8 16:19:59 DEBUG[31477]: chan_sip.c:4170 parse_request: Line: a=fmtp:101 0-15 (15) Jun 8 16:19:59 DEBUG[31477]: chan_sip.c:4170 parse_request: Line: a=ptime:30 (10) Jun 8 16:19:59 DEBUG[31477]: chan_sip.c:4170 parse_request: Line: a=sendrecv (10) --- (16 headers 13 lines)--- Jun 8 16:19:59 DEBUG[31477]: chan_sip.c:13633 handle_request: **** Received INVITE (5) - Command in SIP INVITE Jun 8 16:19:59 DEBUG[31477]: chan_sip.c:1453 parse_sip_options: Begin: parsing SIP "Supported: x-sipura" Jun 8 16:19:59 DEBUG[31477]: chan_sip.c:1461 parse_sip_options: Found SIP option: -x-sipura- Jun 8 16:19:59 DEBUG[31477]: chan_sip.c:1472 parse_sip_options: Found no match for SIP option: x-sipura (Please file bug report!) Sending to 67.58.10.169 : 5061 (no NAT) Using INVITE request as basis request - 73ccdddc-9bcca908@67.58.10.169 Jun 8 16:19:59 DEBUG[31477]: chan_sip.c:8302 check_user_full: Setting NAT on RTP to Off Found user 'test2' Found RTP audio format 0 Found RTP audio format 100 Found RTP audio format 101 Peer audio RTP is at port 67.58.10.169:16414 Found description format PCMU Found description format NSE Found description format telephone-event Jun 8 16:19:59 DEBUG[31477]: chan_sip.c:4593 process_sdp: T38 state changed to 0 on channel Capabilities: us - 0x4 (ulaw), peer - audio=0x4 (ulaw)/video=0x0 (nothing), combined - 0x4 (ulaw) Non-codec capabilities: us - 0x1 (telephone-event), peer - 0x1 (telephone-event), combined - 0x1 (telephone-event) Jun 8 16:19:59 DEBUG[31477]: chan_sip.c:12472 handle_request_invite: Checking SIP call limits for device test2 Jun 8 16:19:59 DEBUG[31477]: chan_sip.c:2711 update_call_counter: Updating call counter for incoming call Looking for 17025770986 in customer (domain 207.15.15.3) Jun 8 16:19:59 DEBUG[31477]: chan_sip.c:7260 build_route: build_route: Contact hop: 2142700823 list_route: hop: Jun 8 16:19:59 DEBUG[31477]: chan_sip.c:12542 handle_request_invite: SIP/test2-3b96: New call is still down.... Trying... Transmitting (no NAT) to 67.58.10.169:5061: SIP/2.0 100 Trying Via: SIP/2.0/UDP 67.58.10.169:5061;branch=z9hG4bK-8ed6a680;received=67.58.10.169 From: 2142700823 ;tag=aed577cc5b1357d8o1 To: Call-ID: 73ccdddc-9bcca908@67.58.10.169 CSeq: 102 INVITE User-Agent: Lucent-Universal-Gateway Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Supported: replaces Contact: Content-Length: 0 --- Jun 8 16:19:59 DEBUG[31458]: chan_sip.c:14196 sip_devicestate: Checking device state for peer test2 Jun 8 16:19:59 DEBUG[31458]: devicestate.c:189 do_state_change: Changing state for SIP/test2 - state 1 (Not in use) Jun 8 16:19:59 DEBUG[31486]: pbx.c:1676 pbx_extension_helper: Launching 'Goto' -- Executing [customer:1] Goto("SIP/test2-3b96", "dialplan|7025770986|1") in new stack -- Goto (dialplan,7025770986,1) Jun 8 16:19:59 DEBUG[31486]: pbx.c:1676 pbx_extension_helper: Launching 'Dial' -- Executing [dialplan:1] Dial("SIP/test2-3b96", "SIP/apxtrunk/17025770986") in new stack Jun 8 16:19:59 DEBUG[31486]: chan_sip.c:3918 sip_alloc: Allocating new SIP dialog for (No Call-ID) - INVITE (With RTP) Jun 8 16:19:59 DEBUG[31486]: chan_sip.c:2345 create_addr_from_peer: Setting NAT on RTP to Off Jun 8 16:19:59 DEBUG[31486]: chan_sip.c:15551 sip_set_rtp_peer: Early media setting SIP '7f0ccc8241ff1f182bfa44c3439585ad@207.15.15.3' - Sending early media to 67.58.10.169 Jun 8 16:19:59 DEBUG[31486]: rtp.c:1144 ast_rtp_make_compatible: Seeded SDP of 'SIP/apxtrunk-3518' with that of 'SIP/test2-3b96' Jun 8 16:19:59 DEBUG[31486]: channel.c:2920 ast_channel_inherit_variables: Not copying variable STACK-dialplan-7025770986-1. Jun 8 16:19:59 DEBUG[31486]: channel.c:2920 ast_channel_inherit_variables: Not copying variable STACK-customer-17025770986-1. Jun 8 16:19:59 DEBUG[31486]: channel.c:2920 ast_channel_inherit_variables: Not copying variable SIPCALLID. Jun 8 16:19:59 DEBUG[31486]: channel.c:2920 ast_channel_inherit_variables: Not copying variable SIPUSERAGENT. Jun 8 16:19:59 DEBUG[31486]: channel.c:2920 ast_channel_inherit_variables: Not copying variable SIPDOMAIN. Jun 8 16:19:59 DEBUG[31486]: channel.c:2920 ast_channel_inherit_variables: Not copying variable SIPURI. Jun 8 16:19:59 DEBUG[31486]: chan_sip.c:2547 sip_call: Outgoing Call for 17025770986 Jun 8 16:19:59 DEBUG[31486]: chan_sip.c:2711 update_call_counter: Updating call counter for outgoing call Jun 8 16:19:59 DEBUG[31486]: chan_sip.c:2555 sip_call: Our T38 capability (3840), joint T38 capability (3840) We're at 207.15.15.3 port 17778 Adding codec 0x4 (ulaw) to SDP Adding non-codec 0x1 (telephone-event) to SDP Jun 8 16:19:59 DEBUG[31486]: channel.c:2101 ast_internal_timing_enabled: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1) Jun 8 16:19:59 DEBUG[31486]: chan_sip.c:4138 parse_request: Header 0: INVITE sip:17025770986@205.17.1.5 SIP/2.0 (45) Jun 8 16:19:59 DEBUG[31486]: chan_sip.c:4138 parse_request: Header 1: Via: SIP/2.0/UDP 207.15.15.3:5060;branch=z9hG4bK7f4ea0ff;rport (64) Jun 8 16:19:59 DEBUG[31486]: chan_sip.c:4138 parse_request: Header 2: From: "2142700823" ;tag=as75206b4b (59) Jun 8 16:19:59 DEBUG[31486]: chan_sip.c:4138 parse_request: Header 3: To: (36) Jun 8 16:19:59 DEBUG[31486]: chan_sip.c:4138 parse_request: Header 4: Contact: (34) Jun 8 16:19:59 DEBUG[31486]: chan_sip.c:4138 parse_request: Header 5: Call-ID: 7f0ccc8241ff1f182bfa44c3439585ad@207.15.15.3 (55) Jun 8 16:19:59 DEBUG[31486]: chan_sip.c:4138 parse_request: Header 6: CSeq: 102 INVITE (16) Jun 8 16:19:59 DEBUG[31486]: chan_sip.c:4138 parse_request: Header 7: User-Agent: Lucent-Universal-Gateway (36) Jun 8 16:19:59 DEBUG[31486]: chan_sip.c:4138 parse_request: Header 8: Max-Forwards: 70 (16) Jun 8 16:19:59 DEBUG[31486]: chan_sip.c:4138 parse_request: Header 9: Date: Thu, 08 Jun 2006 21:19:59 GMT (35) Jun 8 16:19:59 DEBUG[31486]: chan_sip.c:4138 parse_request: Header 10: Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY (66) Jun 8 16:19:59 DEBUG[31486]: chan_sip.c:4138 parse_request: Header 11: Supported: replaces (19) Jun 8 16:19:59 DEBUG[31486]: chan_sip.c:4138 parse_request: Header 12: Content-Type: application/sdp (29) Jun 8 16:19:59 DEBUG[31486]: chan_sip.c:4138 parse_request: Header 13: Content-Length: 228 (19) Jun 8 16:19:59 DEBUG[31486]: chan_sip.c:4138 parse_request: Header 14: (0) Jun 8 16:19:59 DEBUG[31486]: chan_sip.c:4170 parse_request: Line: v=0 (3) Jun 8 16:19:59 DEBUG[31486]: chan_sip.c:4170 parse_request: Line: o=root 31486 31486 IN IP4 67.58.10.169 (38) Jun 8 16:19:59 DEBUG[31486]: chan_sip.c:4170 parse_request: Line: s=session (9) Jun 8 16:19:59 DEBUG[31486]: chan_sip.c:4170 parse_request: Line: c=IN IP4 67.58.10.169 (21) Jun 8 16:19:59 DEBUG[31486]: chan_sip.c:4170 parse_request: Line: t=0 0 (5) Jun 8 16:19:59 DEBUG[31486]: chan_sip.c:4170 parse_request: Line: m=audio 16414 RTP/AVP 0 101 (27) Jun 8 16:19:59 DEBUG[31486]: chan_sip.c:4170 parse_request: Line: a=rtpmap:0 PCMU/8000 (20) Jun 8 16:19:59 DEBUG[31486]: chan_sip.c:4170 parse_request: Line: a=rtpmap:101 telephone-event/8000 (33) Jun 8 16:19:59 DEBUG[31486]: chan_sip.c:4170 parse_request: Line: a=fmtp:101 0-16 (15) Jun 8 16:19:59 DEBUG[31486]: chan_sip.c:4170 parse_request: Line: a=silenceSupp:off - - - - (25) Jun 8 16:19:59 DEBUG[31486]: chan_sip.c:4170 parse_request: Line: a=sendrecv (10) Reliably Transmitting (no NAT) to 205.17.1.5:5060: INVITE sip:17025770986@205.17.1.5 SIP/2.0 Via: SIP/2.0/UDP 207.15.15.3:5060;branch=z9hG4bK7f4ea0ff;rport From: "2142700823" ;tag=as75206b4b To: Contact: Call-ID: 7f0ccc8241ff1f182bfa44c3439585ad@207.15.15.3 CSeq: 102 INVITE User-Agent: Lucent-Universal-Gateway Max-Forwards: 70 Date: Thu, 08 Jun 2006 21:19:59 GMT Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Supported: replaces Content-Type: application/sdp Content-Length: 228 v=0 o=root 31486 31486 IN IP4 67.58.10.169 s=session c=IN IP4 67.58.10.169 t=0 0 m=audio 16414 RTP/AVP 0 101 a=rtpmap:0 PCMU/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=silenceSupp:off - - - - a=sendrecv --- Jun 8 16:19:59 DEBUG[31486]: chan_sip.c:1746 __sip_reliable_xmit: *** SIP TIMER: Initalizing retransmit timer on packet: Id #6 -- Called apxtrunk/17025770986 Jun 8 16:19:59 DEBUG[31486]: channel.c:2476 set_format: Set channel SIP/apxtrunk-3518 to read format ulaw Jun 8 16:19:59 DEBUG[31486]: channel.c:2476 set_format: Set channel SIP/test2-3b96 to write format ulaw Jun 8 16:19:59 DEBUG[31486]: channel.c:2476 set_format: Set channel SIP/test2-3b96 to read format ulaw Jun 8 16:19:59 DEBUG[31486]: channel.c:2476 set_format: Set channel SIP/apxtrunk-3518 to write format ulaw Jun 8 16:19:59 DEBUG[31487]: app_queue.c:557 changethread: Device 'SIP/test2' changed to state '1' (Not in use) but we don't care because they're not a member of any queue. proxy5*CLI> <-- SIP read from 205.17.1.5:5060: SIP/2.0 100 Trying Via: SIP/2.0/UDP 207.15.15.3:5060;branch=z9hG4bK7f4ea0ff;rport To: ;tag=64a64e52-1ee9ae62-26e80dcc From: "2142700823" ;tag=as75206b4b Call-ID: 7f0ccc8241ff1f182bfa44c3439585ad@207.15.15.3 CSeq: 102 INVITE Server: Lucent-Universal-Gateway User-Agent: Lucent-Universal-Gateway Content-Length: 0 Jun 8 16:19:59 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 0: SIP/2.0 100 Trying (18) Jun 8 16:19:59 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 1: Via: SIP/2.0/UDP 207.15.15.3:5060;branch=z9hG4bK7f4ea0ff;rport (64) Jun 8 16:19:59 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 2: To: ;tag=64a64e52-1ee9ae62-26e80dcc (67) Jun 8 16:19:59 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 3: From: "2142700823" ;tag=as75206b4b (59) Jun 8 16:19:59 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 4: Call-ID: 7f0ccc8241ff1f182bfa44c3439585ad@207.15.15.3 (55) Jun 8 16:19:59 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 5: CSeq: 102 INVITE (16) Jun 8 16:19:59 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 6: Server: Lucent-Universal-Gateway (32) Jun 8 16:19:59 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 7: User-Agent: Lucent-Universal-Gateway (36) Jun 8 16:19:59 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 8: Content-Length: 0 (17) Jun 8 16:19:59 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 9: (0) --- (9 headers 0 lines)--- Jun 8 16:19:59 DEBUG[31477]: chan_sip.c:1884 __sip_semi_ack: *** SIP TIMER: Cancelling retransmission #6 - INVITE (got response) Jun 8 16:19:59 DEBUG[31477]: chan_sip.c:1893 __sip_semi_ack: (Provisional) Stopping retransmission (but retaining packet) on '7f0ccc8241ff1f182bfa44c3439585ad@207.15.15.3' Request 102: Found Jun 8 16:19:59 DEBUG[31477]: chan_sip.c:10849 handle_response_invite: SIP response 100 to standard invite proxy5*CLI> <-- SIP read from 205.17.1.5:5060: SIP/2.0 183 Session Progress Via: SIP/2.0/UDP 207.15.15.3:5060;branch=z9hG4bK7f4ea0ff;rport To: ;tag=64a64e52-1ee9ae62-26e80dcc From: "2142700823" ;tag=as75206b4b Call-ID: 7f0ccc8241ff1f182bfa44c3439585ad@207.15.15.3 CSeq: 102 INVITE Contact: Content-Type: application/sdp Server: Lucent-Universal-Gateway User-Agent: Lucent-Universal-Gateway Content-Length: 235 v=0 o=APX1 518631010 518631010 IN IP4 205.17.1.5 s=Session SDP c=IN IP4 205.17.1.5 t=0 0 m=audio 47710 RTP/AVP 0 101 a=silenceSupp:off a=ecan:b on g168 a=ptime:20 a=rtpmap:101 telephone-event/8000 a=rtpmap:0 PCMU/8000 Jun 8 16:20:01 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 0: SIP/2.0 183 Session Progress (28) Jun 8 16:20:01 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 1: Via: SIP/2.0/UDP 207.15.15.3:5060;branch=z9hG4bK7f4ea0ff;rport (64) Jun 8 16:20:01 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 2: To: ;tag=64a64e52-1ee9ae62-26e80dcc (67) Jun 8 16:20:01 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 3: From: "2142700823" ;tag=as75206b4b (59) Jun 8 16:20:01 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 4: Call-ID: 7f0ccc8241ff1f182bfa44c3439585ad@207.15.15.3 (55) Jun 8 16:20:01 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 5: CSeq: 102 INVITE (16) Jun 8 16:20:01 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 6: Contact: (55) Jun 8 16:20:01 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 7: Content-Type: application/sdp (29) Jun 8 16:20:01 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 8: Server: Lucent-Universal-Gateway (32) Jun 8 16:20:01 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 9: User-Agent: Lucent-Universal-Gateway (36) Jun 8 16:20:01 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 10: Content-Length: 235 (19) Jun 8 16:20:01 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 11: (0) Jun 8 16:20:01 DEBUG[31477]: chan_sip.c:4170 parse_request: Line: v=0 (3) Jun 8 16:20:01 DEBUG[31477]: chan_sip.c:4170 parse_request: Line: o=APX1 518631010 518631010 IN IP4 205.17.1.5 (47) Jun 8 16:20:01 DEBUG[31477]: chan_sip.c:4170 parse_request: Line: s=Session SDP (13) Jun 8 16:20:01 DEBUG[31477]: chan_sip.c:4170 parse_request: Line: c=IN IP4 205.17.1.5 (22) Jun 8 16:20:01 DEBUG[31477]: chan_sip.c:4170 parse_request: Line: t=0 0 (5) Jun 8 16:20:01 DEBUG[31477]: chan_sip.c:4170 parse_request: Line: m=audio 47710 RTP/AVP 0 101 (27) Jun 8 16:20:01 DEBUG[31477]: chan_sip.c:4170 parse_request: Line: a=silenceSupp:off (17) Jun 8 16:20:01 DEBUG[31477]: chan_sip.c:4170 parse_request: Line: a=ecan:b on g168 (16) Jun 8 16:20:01 DEBUG[31477]: chan_sip.c:4170 parse_request: Line: a=ptime:20 (10) Jun 8 16:20:01 DEBUG[31477]: chan_sip.c:4170 parse_request: Line: a=rtpmap:101 telephone-event/8000 (33) Jun 8 16:20:01 DEBUG[31477]: chan_sip.c:4170 parse_request: Line: a=rtpmap:0 PCMU/8000 (20) --- (11 headers 11 lines)--- Jun 8 16:20:01 DEBUG[31477]: chan_sip.c:1893 __sip_semi_ack: (Provisional) Stopping retransmission (but retaining packet) on '7f0ccc8241ff1f182bfa44c3439585ad@207.15.15.3' Request 102: Found Jun 8 16:20:01 DEBUG[31477]: chan_sip.c:10849 handle_response_invite: SIP response 183 to standard invite Found RTP audio format 0 Found RTP audio format 101 Peer audio RTP is at port 205.17.1.5:47710 Found description format telephone-event Found description format PCMU Jun 8 16:20:01 DEBUG[31477]: chan_sip.c:4593 process_sdp: T38 state changed to 0 on channel SIP/apxtrunk-3518 Capabilities: us - 0x104 (ulaw|g729), peer - audio=0x4 (ulaw)/video=0x0 (nothing), combined - 0x4 (ulaw) Non-codec capabilities: us - 0x1 (telephone-event), peer - 0x1 (telephone-event), combined - 0x1 (telephone-event) -- SIP/apxtrunk-3518 is making progress passing it to SIP/test2-3b96 Jun 8 16:20:01 DEBUG[31486]: chan_sip.c:15551 sip_set_rtp_peer: Early media setting SIP '73ccdddc-9bcca908@67.58.10.169' - Sending early media to 205.17.1.5 Jun 8 16:20:01 DEBUG[31486]: rtp.c:1080 ast_rtp_early_media: Setting early media SDP of 'SIP/test2-3b96' with that of 'SIP/apxtrunk-3518' We're at 207.15.15.3 port 17830 Adding codec 0x4 (ulaw) to SDP Adding non-codec 0x1 (telephone-event) to SDP Jun 8 16:20:01 DEBUG[31486]: channel.c:2101 ast_internal_timing_enabled: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1) Transmitting (no NAT) to 67.58.10.169:5061: SIP/2.0 183 Session Progress Via: SIP/2.0/UDP 67.58.10.169:5061;branch=z9hG4bK-8ed6a680;received=67.58.10.169 From: 2142700823 ;tag=aed577cc5b1357d8o1 To: ;tag=as3c3e6b3f Call-ID: 73ccdddc-9bcca908@67.58.10.169 CSeq: 102 INVITE User-Agent: Lucent-Universal-Gateway Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Supported: replaces Contact: Content-Type: application/sdp Content-Length: 230 v=0 o=root 31486 31486 IN IP4 205.17.1.5 s=session c=IN IP4 205.17.1.5 t=0 0 m=audio 47710 RTP/AVP 0 101 a=rtpmap:0 PCMU/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=silenceSupp:off - - - - a=sendrecv --- proxy5*CLI> <-- SIP read from 205.17.1.5:5060: SIP/2.0 200 OK Via: SIP/2.0/UDP 207.15.15.3:5060;branch=z9hG4bK7f4ea0ff;rport To: ;tag=64a64e52-1ee9ae62-26e80dcc From: "2142700823" ;tag=as75206b4b Call-ID: 7f0ccc8241ff1f182bfa44c3439585ad@207.15.15.3 CSeq: 102 INVITE Contact: Accept: application/sdp Accept-Encoding: Accept-Language: en Content-Type: application/sdp Server: Lucent-Universal-Gateway User-Agent: Lucent-Universal-Gateway Content-Length: 235 v=0 o=APX1 518631010 518631010 IN IP4 205.17.1.5 s=Session SDP c=IN IP4 205.17.1.5 t=0 0 m=audio 47710 RTP/AVP 0 101 a=silenceSupp:off a=ecan:b on g168 a=ptime:20 a=rtpmap:101 telephone-event/8000 a=rtpmap:0 PCMU/8000 Jun 8 16:20:04 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 0: SIP/2.0 200 OK (14) Jun 8 16:20:04 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 1: Via: SIP/2.0/UDP 207.15.15.3:5060;branch=z9hG4bK7f4ea0ff;rport (64) Jun 8 16:20:04 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 2: To: ;tag=64a64e52-1ee9ae62-26e80dcc (67) Jun 8 16:20:04 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 3: From: "2142700823" ;tag=as75206b4b (59) Jun 8 16:20:04 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 4: Call-ID: 7f0ccc8241ff1f182bfa44c3439585ad@207.15.15.3 (55) Jun 8 16:20:04 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 5: CSeq: 102 INVITE (16) Jun 8 16:20:04 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 6: Contact: (55) Jun 8 16:20:04 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 7: Accept: application/sdp (23) Jun 8 16:20:04 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 8: Accept-Encoding: (17) Jun 8 16:20:04 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 9: Accept-Language: en (19) Jun 8 16:20:04 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 10: Content-Type: application/sdp (29) Jun 8 16:20:04 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 11: Server: Lucent-Universal-Gateway (32) Jun 8 16:20:04 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 12: User-Agent: Lucent-Universal-Gateway (36) Jun 8 16:20:04 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 13: Content-Length: 235 (19) Jun 8 16:20:04 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 14: (0) Jun 8 16:20:04 DEBUG[31477]: chan_sip.c:4170 parse_request: Line: v=0 (3) Jun 8 16:20:04 DEBUG[31477]: chan_sip.c:4170 parse_request: Line: o=APX1 518631010 518631010 IN IP4 205.17.1.5 (47) Jun 8 16:20:04 DEBUG[31477]: chan_sip.c:4170 parse_request: Line: s=Session SDP (13) Jun 8 16:20:04 DEBUG[31477]: chan_sip.c:4170 parse_request: Line: c=IN IP4 205.17.1.5 (22) Jun 8 16:20:04 DEBUG[31477]: chan_sip.c:4170 parse_request: Line: t=0 0 (5) Jun 8 16:20:04 DEBUG[31477]: chan_sip.c:4170 parse_request: Line: m=audio 47710 RTP/AVP 0 101 (27) Jun 8 16:20:04 DEBUG[31477]: chan_sip.c:4170 parse_request: Line: a=silenceSupp:off (17) Jun 8 16:20:04 DEBUG[31477]: chan_sip.c:4170 parse_request: Line: a=ecan:b on g168 (16) Jun 8 16:20:04 DEBUG[31477]: chan_sip.c:4170 parse_request: Line: a=ptime:20 (10) Jun 8 16:20:04 DEBUG[31477]: chan_sip.c:4170 parse_request: Line: a=rtpmap:101 telephone-event/8000 (33) Jun 8 16:20:04 DEBUG[31477]: chan_sip.c:4170 parse_request: Line: a=rtpmap:0 PCMU/8000 (20) --- (14 headers 11 lines)--- Jun 8 16:20:04 DEBUG[31477]: chan_sip.c:1831 __sip_ack: Acked pending invite 102 Jun 8 16:20:04 DEBUG[31477]: chan_sip.c:1849 __sip_ack: Stopping retransmission on '7f0ccc8241ff1f182bfa44c3439585ad@207.15.15.3' of Request 102: Match Found Jun 8 16:20:04 DEBUG[31477]: chan_sip.c:10849 handle_response_invite: SIP response 200 to standard invite Found RTP audio format 0 Found RTP audio format 101 Peer audio RTP is at port 205.17.1.5:47710 Found description format telephone-event Found description format PCMU Jun 8 16:20:04 DEBUG[31477]: chan_sip.c:4593 process_sdp: T38 state changed to 0 on channel SIP/apxtrunk-3518 Capabilities: us - 0x104 (ulaw|g729), peer - audio=0x4 (ulaw)/video=0x0 (nothing), combined - 0x4 (ulaw) Non-codec capabilities: us - 0x1 (telephone-event), peer - 0x1 (telephone-event), combined - 0x1 (telephone-event) Jun 8 16:20:04 DEBUG[31477]: chan_sip.c:2711 update_call_counter: Updating call counter for outgoing call Jun 8 16:20:04 DEBUG[31477]: chan_sip.c:7260 build_route: build_route: Contact hop: list_route: hop: Jun 8 16:20:04 DEBUG[31477]: chan_sip.c:5058 reqprep: Strict routing enforced for session 7f0ccc8241ff1f182bfa44c3439585ad@207.15.15.3 set_destination: Parsing for address/port to send to set_destination: set destination to 205.17.1.5, port 5060 Transmitting (no NAT) to 205.17.1.5:5060: ACK sip:7025770986@205.17.1.5:5060;user=phone SIP/2.0 Via: SIP/2.0/UDP 207.15.15.3:5060;branch=z9hG4bK143633d0;rport From: "2142700823" ;tag=as75206b4b To: ;tag=64a64e52-1ee9ae62-26e80dcc Contact: Call-ID: 7f0ccc8241ff1f182bfa44c3439585ad@207.15.15.3 CSeq: 102 ACK User-Agent: Lucent-Universal-Gateway Max-Forwards: 70 Content-Length: 0 --- -- SIP/apxtrunk-3518 answered SIP/test2-3b96 Jun 8 16:20:04 DEBUG[31486]: rtp.c:1080 ast_rtp_early_media: Setting early media SDP of 'SIP/test2-3b96' with that of 'SIP/apxtrunk-3518' Jun 8 16:20:04 DEBUG[31486]: channel.c:2476 set_format: Set channel SIP/test2-3b96 to read format ulaw Jun 8 16:20:04 DEBUG[31486]: channel.c:2476 set_format: Set channel SIP/apxtrunk-3518 to write format ulaw Jun 8 16:20:04 DEBUG[31486]: channel.c:2476 set_format: Set channel SIP/apxtrunk-3518 to read format ulaw Jun 8 16:20:04 DEBUG[31486]: channel.c:2476 set_format: Set channel SIP/test2-3b96 to write format ulaw Jun 8 16:20:04 DEBUG[31486]: chan_sip.c:3129 sip_answer: SIP answering channel: SIP/test2-3b96 We're at 207.15.15.3 port 17830 Adding codec 0x4 (ulaw) to SDP Adding non-codec 0x1 (telephone-event) to SDP Jun 8 16:20:04 DEBUG[31486]: channel.c:2101 ast_internal_timing_enabled: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1) Reliably Transmitting (no NAT) to 67.58.10.169:5061: SIP/2.0 200 OK Via: SIP/2.0/UDP 67.58.10.169:5061;branch=z9hG4bK-8ed6a680;received=67.58.10.169 From: 2142700823 ;tag=aed577cc5b1357d8o1 To: ;tag=as3c3e6b3f Call-ID: 73ccdddc-9bcca908@67.58.10.169 CSeq: 102 INVITE User-Agent: Lucent-Universal-Gateway Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Supported: replaces Contact: Content-Type: application/sdp Content-Length: 230 v=0 o=root 31486 31487 IN IP4 205.17.1.5 s=session c=IN IP4 205.17.1.5 t=0 0 m=audio 47710 RTP/AVP 0 101 a=rtpmap:0 PCMU/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=silenceSupp:off - - - - a=sendrecv --- Jun 8 16:20:04 DEBUG[31486]: chan_sip.c:1746 __sip_reliable_xmit: *** SIP TIMER: Initalizing retransmit timer on packet: Id #8 -- Native bridging SIP/test2-3b96 and SIP/apxtrunk-3518 Jun 8 16:20:04 DEBUG[31458]: chan_sip.c:14196 sip_devicestate: Checking device state for peer apxtrunk Jun 8 16:20:04 DEBUG[31458]: devicestate.c:189 do_state_change: Changing state for SIP/apxtrunk - state 1 (Not in use) Jun 8 16:20:04 DEBUG[31458]: chan_sip.c:14196 sip_devicestate: Checking device state for peer test2 Jun 8 16:20:04 DEBUG[31458]: devicestate.c:189 do_state_change: Changing state for SIP/test2 - state 1 (Not in use) Jun 8 16:20:04 DEBUG[31488]: app_queue.c:557 changethread: Device 'SIP/apxtrunk' changed to state '1' (Not in use) but we don't care because they're not a member of any queue. Jun 8 16:20:04 DEBUG[31489]: app_queue.c:557 changethread: Device 'SIP/test2' changed to state '1' (Not in use) but we don't care because they're not a member of any queue. proxy5*CLI> <-- SIP read from 67.58.10.169:5061: ACK sip:17025770986@207.15.15.3 SIP/2.0 Via: SIP/2.0/UDP 67.58.10.169:5061;branch=z9hG4bK-fcfadaad From: 2142700823 ;tag=aed577cc5b1357d8o1 To: ;tag=as3c3e6b3f Call-ID: 73ccdddc-9bcca908@67.58.10.169 CSeq: 102 ACK Max-Forwards: 70 Proxy-Authorization: Digest username="test2",realm="asterisk",nonce="541d4006",uri="sip:17025770986@207.15.15.3",algorithm=MD5,response="bdb103f0a530c4de30f39abaecab1a89" Contact: 2142700823 User-Agent: Sipura/SPA2100-3.2.5(d) Content-Length: 0 Jun 8 16:20:04 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 0: ACK sip:17025770986@207.15.15.3 SIP/2.0 (41) Jun 8 16:20:04 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 1: Via: SIP/2.0/UDP 67.58.10.169:5061;branch=z9hG4bK-fcfadaad (58) Jun 8 16:20:04 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 2: From: 2142700823 ;tag=aed577cc5b1357d8o1 (65) Jun 8 16:20:04 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 3: To: ;tag=as3c3e6b3f (50) Jun 8 16:20:04 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 4: Call-ID: 73ccdddc-9bcca908@67.58.10.169 (39) Jun 8 16:20:04 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 5: CSeq: 102 ACK (13) Jun 8 16:20:04 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 6: Max-Forwards: 70 (16) Jun 8 16:20:04 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 7: Proxy-Authorization: Digest username="test2",realm="asterisk",nonce="541d4006",uri="sip:17025770986@207.15.15.3",algorithm=MD5,response="bdb103f0a530c4de30f39abaecab1a89" (172) Jun 8 16:20:04 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 8: Contact: 2142700823 (49) Jun 8 16:20:04 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 9: User-Agent: Sipura/SPA2100-3.2.5(d) (35) Jun 8 16:20:04 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 10: Content-Length: 0 (17) Jun 8 16:20:04 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 11: (0) --- (11 headers 0 lines)--- Jun 8 16:20:04 DEBUG[31477]: chan_sip.c:13633 handle_request: **** Received ACK (6) - Command in SIP ACK Jun 8 16:20:04 DEBUG[31477]: chan_sip.c:1838 __sip_ack: ** SIP TIMER: Cancelling retransmit of packet (reply received) Retransid #8 Jun 8 16:20:04 DEBUG[31477]: chan_sip.c:1849 __sip_ack: Stopping retransmission on '73ccdddc-9bcca908@67.58.10.169' of Response 102: Match Found proxy5*CLI> <-- SIP read from 67.58.10.169:5061: INVITE sip:17025770986@207.15.15.3 SIP/2.0 Via: SIP/2.0/UDP 67.58.10.169:5061;branch=z9hG4bK-c52dec65 From: 2142700823 ;tag=aed577cc5b1357d8o1 To: ;tag=as3c3e6b3f Remote-Party-ID: 2142700823 ;screen=yes;party=calling Call-ID: 73ccdddc-9bcca908@67.58.10.169 CSeq: 103 INVITE Max-Forwards: 70 Proxy-Authorization: Digest username="test2",realm="asterisk",nonce="541d4006",uri="sip:17025770986@207.15.15.3",algorithm=MD5,response="04a4cc7f72ca22a21e7e46530ad9d06e" Contact: 2142700823 Expires: 30 User-Agent: Sipura/SPA2100-3.2.5(d) Content-Length: 339 Content-Type: application/sdp v=0 o=- 7343 7343 IN IP4 67.58.10.169 s=- c=IN IP4 67.58.10.169 t=0 0 m=image 16414 udptl t38 a=T38FaxVersion:0 a=T38MaxBitRate:14400 a=T38FaxFillBitRemoval:0 a=T38FaxTranscodingMMR:0 a=T38FaxTranscodingJBIG:0 a=T38FaxRateManagement:transferredTCF a=T38FaxMaxBuffer:200 a=T38FaxMaxDatagram:72 a=T38FaxUdpEC:t38UDPRedundancy Jun 8 16:20:04 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 0: INVITE sip:17025770986@207.15.15.3 SIP/2.0 (44) Jun 8 16:20:04 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 1: Via: SIP/2.0/UDP 67.58.10.169:5061;branch=z9hG4bK-c52dec65 (58) Jun 8 16:20:04 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 2: From: 2142700823 ;tag=aed577cc5b1357d8o1 (65) Jun 8 16:20:04 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 3: To: ;tag=as3c3e6b3f (50) Jun 8 16:20:04 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 4: Remote-Party-ID: 2142700823 ;screen=yes;party=calling (78) Jun 8 16:20:04 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 5: Call-ID: 73ccdddc-9bcca908@67.58.10.169 (39) Jun 8 16:20:04 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 6: CSeq: 103 INVITE (16) Jun 8 16:20:04 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 7: Max-Forwards: 70 (16) Jun 8 16:20:04 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 8: Proxy-Authorization: Digest username="test2",realm="asterisk",nonce="541d4006",uri="sip:17025770986@207.15.15.3",algorithm=MD5,response="04a4cc7f72ca22a21e7e46530ad9d06e" (172) Jun 8 16:20:04 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 9: Contact: 2142700823 (49) Jun 8 16:20:04 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 10: Expires: 30 (11) Jun 8 16:20:04 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 11: User-Agent: Sipura/SPA2100-3.2.5(d) (35) Jun 8 16:20:04 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 12: Content-Length: 339 (19) Jun 8 16:20:04 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 13: Content-Type: application/sdp (29) Jun 8 16:20:04 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 14: (0) Jun 8 16:20:04 DEBUG[31477]: chan_sip.c:4170 parse_request: Line: v=0 (3) Jun 8 16:20:04 DEBUG[31477]: chan_sip.c:4170 parse_request: Line: o=- 7343 7343 IN IP4 67.58.10.169 (33) Jun 8 16:20:04 DEBUG[31477]: chan_sip.c:4170 parse_request: Line: s=- (3) Jun 8 16:20:04 DEBUG[31477]: chan_sip.c:4170 parse_request: Line: c=IN IP4 67.58.10.169 (21) Jun 8 16:20:04 DEBUG[31477]: chan_sip.c:4170 parse_request: Line: t=0 0 (5) Jun 8 16:20:04 DEBUG[31477]: chan_sip.c:4170 parse_request: Line: m=image 16414 udptl t38 (23) Jun 8 16:20:04 DEBUG[31477]: chan_sip.c:4170 parse_request: Line: a=T38FaxVersion:0 (17) Jun 8 16:20:04 DEBUG[31477]: chan_sip.c:4170 parse_request: Line: a=T38MaxBitRate:14400 (21) Jun 8 16:20:04 DEBUG[31477]: chan_sip.c:4170 parse_request: Line: a=T38FaxFillBitRemoval:0 (24) Jun 8 16:20:04 DEBUG[31477]: chan_sip.c:4170 parse_request: Line: a=T38FaxTranscodingMMR:0 (24) Jun 8 16:20:04 DEBUG[31477]: chan_sip.c:4170 parse_request: Line: a=T38FaxTranscodingJBIG:0 (25) Jun 8 16:20:04 DEBUG[31477]: chan_sip.c:4170 parse_request: Line: a=T38FaxRateManagement:transferredTCF (37) Jun 8 16:20:04 DEBUG[31477]: chan_sip.c:4170 parse_request: Line: a=T38FaxMaxBuffer:200 (21) Jun 8 16:20:04 DEBUG[31477]: chan_sip.c:4170 parse_request: Line: a=T38FaxMaxDatagram:72 (22) Jun 8 16:20:04 DEBUG[31477]: chan_sip.c:4170 parse_request: Line: a=T38FaxUdpEC:t38UDPRedundancy (30) --- (14 headers 15 lines)--- Jun 8 16:20:04 DEBUG[31477]: chan_sip.c:13633 handle_request: **** Received INVITE (5) - Command in SIP INVITE Sending to 67.58.10.169 : 5061 (no NAT) Jun 8 16:20:04 WARNING[31477]: chan_sip.c:4368 process_sdp: Unknown SDP media type in offer: image 16414 udptl t38 Transmitting (no NAT) to 67.58.10.169:5061: SIP/2.0 488 Not acceptable here Via: SIP/2.0/UDP 67.58.10.169:5061;branch=z9hG4bK-c52dec65;received=67.58.10.169 From: 2142700823 ;tag=aed577cc5b1357d8o1 To: ;tag=as3c3e6b3f Call-ID: 73ccdddc-9bcca908@67.58.10.169 CSeq: 103 INVITE User-Agent: Lucent-Universal-Gateway Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Supported: replaces Contact: Content-Length: 0 X-Asterisk-HangupCause: Normal Clearing X-Asterisk-HangupCauseCode: 16 --- Jun 8 16:20:04 DEBUG[31477]: chan_sip.c:13841 sipsock_read: SIP message could not be handled, bad request: 73ccdddc-9bcca908@67.58.10.169 proxy5*CLI> <-- SIP read from 67.58.10.169:5061: ACK sip:17025770986@207.15.15.3 SIP/2.0 Via: SIP/2.0/UDP 67.58.10.169:5061;branch=z9hG4bK-c52dec65 From: 2142700823 ;tag=aed577cc5b1357d8o1 To: ;tag=as3c3e6b3f Call-ID: 73ccdddc-9bcca908@67.58.10.169 CSeq: 103 ACK Max-Forwards: 70 Proxy-Authorization: Digest username="test2",realm="asterisk",nonce="541d4006",uri="sip:17025770986@207.15.15.3",algorithm=MD5,response="bdb103f0a530c4de30f39abaecab1a89" Contact: 2142700823 User-Agent: Sipura/SPA2100-3.2.5(d) Content-Length: 0 Jun 8 16:20:04 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 0: ACK sip:17025770986@207.15.15.3 SIP/2.0 (41) Jun 8 16:20:04 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 1: Via: SIP/2.0/UDP 67.58.10.169:5061;branch=z9hG4bK-c52dec65 (58) Jun 8 16:20:04 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 2: From: 2142700823 ;tag=aed577cc5b1357d8o1 (65) Jun 8 16:20:04 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 3: To: ;tag=as3c3e6b3f (50) Jun 8 16:20:04 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 4: Call-ID: 73ccdddc-9bcca908@67.58.10.169 (39) Jun 8 16:20:04 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 5: CSeq: 103 ACK (13) Jun 8 16:20:04 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 6: Max-Forwards: 70 (16) Jun 8 16:20:04 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 7: Proxy-Authorization: Digest username="test2",realm="asterisk",nonce="541d4006",uri="sip:17025770986@207.15.15.3",algorithm=MD5,response="bdb103f0a530c4de30f39abaecab1a89" (172) Jun 8 16:20:04 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 8: Contact: 2142700823 (49) Jun 8 16:20:04 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 9: User-Agent: Sipura/SPA2100-3.2.5(d) (35) Jun 8 16:20:04 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 10: Content-Length: 0 (17) Jun 8 16:20:04 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 11: (0) --- (11 headers 0 lines)--- Jun 8 16:20:04 DEBUG[31477]: chan_sip.c:13633 handle_request: **** Received ACK (6) - Command in SIP ACK Jun 8 16:20:04 DEBUG[31477]: chan_sip.c:1849 __sip_ack: Stopping retransmission on '73ccdddc-9bcca908@67.58.10.169' of Response 103: Match Not Found proxy5*CLI> <-- SIP read from 67.58.10.169:5061: INVITE sip:17025770986@207.15.15.3 SIP/2.0 Via: SIP/2.0/UDP 67.58.10.169:5061;branch=z9hG4bK-eb03460e From: 2142700823 ;tag=aed577cc5b1357d8o1 To: ;tag=as3c3e6b3f Remote-Party-ID: 2142700823 ;screen=yes;party=calling Call-ID: 73ccdddc-9bcca908@67.58.10.169 CSeq: 104 INVITE Max-Forwards: 70 Proxy-Authorization: Digest username="test2",realm="asterisk",nonce="541d4006",uri="sip:17025770986@207.15.15.3",algorithm=MD5,response="04a4cc7f72ca22a21e7e46530ad9d06e" Contact: 2142700823 Expires: 30 User-Agent: Sipura/SPA2100-3.2.5(d) Content-Length: 146 Content-Type: application/sdp v=0 o=- 7344 7344 IN IP4 67.58.10.169 s=- c=IN IP4 67.58.10.169 t=0 0 m=audio 16414 RTP/AVP 0 a=rtpmap:0 PCMU/8000 a=ptime:30 a=sendrecv Jun 8 16:20:04 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 0: INVITE sip:17025770986@207.15.15.3 SIP/2.0 (44) Jun 8 16:20:04 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 1: Via: SIP/2.0/UDP 67.58.10.169:5061;branch=z9hG4bK-eb03460e (58) Jun 8 16:20:04 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 2: From: 2142700823 ;tag=aed577cc5b1357d8o1 (65) Jun 8 16:20:04 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 3: To: ;tag=as3c3e6b3f (50) Jun 8 16:20:04 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 4: Remote-Party-ID: 2142700823 ;screen=yes;party=calling (78) Jun 8 16:20:04 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 5: Call-ID: 73ccdddc-9bcca908@67.58.10.169 (39) Jun 8 16:20:04 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 6: CSeq: 104 INVITE (16) Jun 8 16:20:04 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 7: Max-Forwards: 70 (16) Jun 8 16:20:04 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 8: Proxy-Authorization: Digest username="test2",realm="asterisk",nonce="541d4006",uri="sip:17025770986@207.15.15.3",algorithm=MD5,response="04a4cc7f72ca22a21e7e46530ad9d06e" (172) Jun 8 16:20:04 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 9: Contact: 2142700823 (49) Jun 8 16:20:04 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 10: Expires: 30 (11) Jun 8 16:20:04 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 11: User-Agent: Sipura/SPA2100-3.2.5(d) (35) Jun 8 16:20:04 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 12: Content-Length: 146 (19) Jun 8 16:20:04 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 13: Content-Type: application/sdp (29) Jun 8 16:20:04 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 14: (0) Jun 8 16:20:04 DEBUG[31477]: chan_sip.c:4170 parse_request: Line: v=0 (3) Jun 8 16:20:04 DEBUG[31477]: chan_sip.c:4170 parse_request: Line: o=- 7344 7344 IN IP4 67.58.10.169 (33) Jun 8 16:20:04 DEBUG[31477]: chan_sip.c:4170 parse_request: Line: s=- (3) Jun 8 16:20:04 DEBUG[31477]: chan_sip.c:4170 parse_request: Line: c=IN IP4 67.58.10.169 (21) Jun 8 16:20:04 DEBUG[31477]: chan_sip.c:4170 parse_request: Line: t=0 0 (5) Jun 8 16:20:04 DEBUG[31477]: chan_sip.c:4170 parse_request: Line: m=audio 16414 RTP/AVP 0 (23) Jun 8 16:20:04 DEBUG[31477]: chan_sip.c:4170 parse_request: Line: a=rtpmap:0 PCMU/8000 (20) Jun 8 16:20:04 DEBUG[31477]: chan_sip.c:4170 parse_request: Line: a=ptime:30 (10) Jun 8 16:20:04 DEBUG[31477]: chan_sip.c:4170 parse_request: Line: a=sendrecv (10) --- (14 headers 9 lines)--- Jun 8 16:20:04 DEBUG[31477]: chan_sip.c:13633 handle_request: **** Received INVITE (5) - Command in SIP INVITE Sending to 67.58.10.169 : 5061 (no NAT) Found RTP audio format 0 Peer audio RTP is at port 67.58.10.169:16414 Found description format PCMU Jun 8 16:20:04 DEBUG[31477]: chan_sip.c:4593 process_sdp: T38 state changed to 0 on channel SIP/test2-3b96 Capabilities: us - 0x4 (ulaw), peer - audio=0x4 (ulaw)/video=0x0 (nothing), combined - 0x4 (ulaw) Non-codec capabilities: us - 0x1 (telephone-event), peer - 0x0 (nothing), combined - 0x0 (nothing) Jun 8 16:20:04 DEBUG[31477]: chan_sip.c:12520 handle_request_invite: Got a SIP re-invite for call 73ccdddc-9bcca908@67.58.10.169 We're at 207.15.15.3 port 17830 Adding codec 0x4 (ulaw) to SDP Jun 8 16:20:04 DEBUG[31477]: channel.c:2101 ast_internal_timing_enabled: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1) Reliably Transmitting (no NAT) to 67.58.10.169:5061: SIP/2.0 200 OK Via: SIP/2.0/UDP 67.58.10.169:5061;branch=z9hG4bK-eb03460e;received=67.58.10.169 From: 2142700823 ;tag=aed577cc5b1357d8o1 To: ;tag=as3c3e6b3f Call-ID: 73ccdddc-9bcca908@67.58.10.169 CSeq: 104 INVITE User-Agent: Lucent-Universal-Gateway Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Supported: replaces Contact: Content-Type: application/sdp Content-Length: 174 v=0 o=root 31486 31488 IN IP4 205.17.1.5 s=session c=IN IP4 205.17.1.5 t=0 0 m=audio 47710 RTP/AVP 0 a=rtpmap:0 PCMU/8000 a=silenceSupp:off - - - - a=sendrecv --- Jun 8 16:20:04 DEBUG[31477]: chan_sip.c:1746 __sip_reliable_xmit: *** SIP TIMER: Initalizing retransmit timer on packet: Id #9 Jun 8 16:20:04 DEBUG[31477]: chan_sip.c:12677 handle_request_invite: SIP/test2-3b96: New call is UP.... proxy5*CLI> <-- SIP read from 67.58.10.169:5061: ACK sip:17025770986@207.15.15.3 SIP/2.0 Via: SIP/2.0/UDP 67.58.10.169:5061;branch=z9hG4bK-b4d107b From: 2142700823 ;tag=aed577cc5b1357d8o1 To: ;tag=as3c3e6b3f Call-ID: 73ccdddc-9bcca908@67.58.10.169 CSeq: 104 ACK Max-Forwards: 70 Proxy-Authorization: Digest username="test2",realm="asterisk",nonce="541d4006",uri="sip:17025770986@207.15.15.3",algorithm=MD5,response="bdb103f0a530c4de30f39abaecab1a89" Contact: 2142700823 User-Agent: Sipura/SPA2100-3.2.5(d) Content-Length: 0 Jun 8 16:20:04 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 0: ACK sip:17025770986@207.15.15.3 SIP/2.0 (41) Jun 8 16:20:04 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 1: Via: SIP/2.0/UDP 67.58.10.169:5061;branch=z9hG4bK-b4d107b (57) Jun 8 16:20:04 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 2: From: 2142700823 ;tag=aed577cc5b1357d8o1 (65) Jun 8 16:20:04 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 3: To: ;tag=as3c3e6b3f (50) Jun 8 16:20:04 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 4: Call-ID: 73ccdddc-9bcca908@67.58.10.169 (39) Jun 8 16:20:04 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 5: CSeq: 104 ACK (13) Jun 8 16:20:04 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 6: Max-Forwards: 70 (16) Jun 8 16:20:04 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 7: Proxy-Authorization: Digest username="test2",realm="asterisk",nonce="541d4006",uri="sip:17025770986@207.15.15.3",algorithm=MD5,response="bdb103f0a530c4de30f39abaecab1a89" (172) Jun 8 16:20:04 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 8: Contact: 2142700823 (49) Jun 8 16:20:04 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 9: User-Agent: Sipura/SPA2100-3.2.5(d) (35) Jun 8 16:20:04 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 10: Content-Length: 0 (17) Jun 8 16:20:04 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 11: (0) --- (11 headers 0 lines)--- Jun 8 16:20:04 DEBUG[31477]: chan_sip.c:13633 handle_request: **** Received ACK (6) - Command in SIP ACK Jun 8 16:20:04 DEBUG[31477]: chan_sip.c:1838 __sip_ack: ** SIP TIMER: Cancelling retransmit of packet (reply received) Retransid #9 Jun 8 16:20:04 DEBUG[31477]: chan_sip.c:1849 __sip_ack: Stopping retransmission on '73ccdddc-9bcca908@67.58.10.169' of Response 104: Match Found proxy5*CLI> <-- SIP read from 205.17.1.5:5060: INVITE sip:test2@207.15.15.3 SIP/2.0 To: "2142700823" ;tag=as75206b4b From: ;tag=64a64e52-1ee9ae62-26e80dcc Call-ID: 7f0ccc8241ff1f182bfa44c3439585ad@207.15.15.3 CSeq: 133845598 INVITE Via: SIP/2.0/UDP 205.17.1.5:5060 Max-Forwards: 70 Contact: sip:205.17.1.5:5060 Content-Type: application/sdp Accept: application/sdp Accept-Encoding: Accept-Language: en User-Agent: Lucent-Universal-Gateway Content-Length: 364 v=0 o=APX1 518631010 518631011 IN IP4 205.17.1.5 s=Session SDP c=IN IP4 205.17.1.5 t=0 0 m=image 47710 udptl t38 a=T38FaxMaxDatagram:316 a=T38FaxMaxBuffer:72 a=T38FaxRateManagement:transferredTCF a=T38FaxUdpEC:t38UDPRedundancy a=T38FaxVersion:0 a=T38FaxTranscodingJBIG:0 a=T38FaxTranscodingMMR:0 a=T38FaxFillBitRemoval:0 a=T38MaxBitRate:14400 Jun 8 16:20:05 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 0: INVITE sip:test2@207.15.15.3 SIP/2.0 (38) Jun 8 16:20:05 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 1: To: "2142700823" ;tag=as75206b4b (57) Jun 8 16:20:05 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 2: From: ;tag=64a64e52-1ee9ae62-26e80dcc (69) Jun 8 16:20:05 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 3: Call-ID: 7f0ccc8241ff1f182bfa44c3439585ad@207.15.15.3 (55) Jun 8 16:20:05 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 4: CSeq: 133845598 INVITE (22) Jun 8 16:20:05 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 5: Via: SIP/2.0/UDP 205.17.1.5:5060 (35) Jun 8 16:20:05 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 6: Max-Forwards: 70 (16) Jun 8 16:20:05 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 7: Contact: sip:205.17.1.5:5060 (31) Jun 8 16:20:05 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 8: Content-Type: application/sdp (29) Jun 8 16:20:05 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 9: Accept: application/sdp (23) Jun 8 16:20:05 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 10: Accept-Encoding: (17) Jun 8 16:20:05 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 11: Accept-Language: en (19) Jun 8 16:20:05 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 12: User-Agent: Lucent-Universal-Gateway (36) Jun 8 16:20:05 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 13: Content-Length: 364 (19) Jun 8 16:20:05 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 14: (0) Jun 8 16:20:05 DEBUG[31477]: chan_sip.c:4170 parse_request: Line: v=0 (3) Jun 8 16:20:05 DEBUG[31477]: chan_sip.c:4170 parse_request: Line: o=APX1 518631010 518631011 IN IP4 205.17.1.5 (47) Jun 8 16:20:05 DEBUG[31477]: chan_sip.c:4170 parse_request: Line: s=Session SDP (13) Jun 8 16:20:05 DEBUG[31477]: chan_sip.c:4170 parse_request: Line: c=IN IP4 205.17.1.5 (22) Jun 8 16:20:05 DEBUG[31477]: chan_sip.c:4170 parse_request: Line: t=0 0 (5) Jun 8 16:20:05 DEBUG[31477]: chan_sip.c:4170 parse_request: Line: m=image 47710 udptl t38 (23) Jun 8 16:20:05 DEBUG[31477]: chan_sip.c:4170 parse_request: Line: a=T38FaxMaxDatagram:316 (23) Jun 8 16:20:05 DEBUG[31477]: chan_sip.c:4170 parse_request: Line: a=T38FaxMaxBuffer:72 (20) Jun 8 16:20:05 DEBUG[31477]: chan_sip.c:4170 parse_request: Line: a=T38FaxRateManagement:transferredTCF (37) Jun 8 16:20:05 DEBUG[31477]: chan_sip.c:4170 parse_request: Line: a=T38FaxUdpEC:t38UDPRedundancy (30) Jun 8 16:20:05 DEBUG[31477]: chan_sip.c:4170 parse_request: Line: a=T38FaxVersion:0 (17) Jun 8 16:20:05 DEBUG[31477]: chan_sip.c:4170 parse_request: Line: a=T38FaxTranscodingJBIG:0 (25) Jun 8 16:20:05 DEBUG[31477]: chan_sip.c:4170 parse_request: Line: a=T38FaxTranscodingMMR:0 (24) Jun 8 16:20:05 DEBUG[31477]: chan_sip.c:4170 parse_request: Line: a=T38FaxFillBitRemoval:0 (24) Jun 8 16:20:05 DEBUG[31477]: chan_sip.c:4170 parse_request: Line: a=T38MaxBitRate:14400 (21) --- (14 headers 15 lines)--- Jun 8 16:20:05 DEBUG[31477]: chan_sip.c:13633 handle_request: **** Received INVITE (5) - Command in SIP INVITE Sending to 205.17.1.5 : 5060 (no NAT) Jun 8 16:20:05 WARNING[31477]: chan_sip.c:4368 process_sdp: Unknown SDP media type in offer: image 47710 udptl t38 Transmitting (no NAT) to 205.17.1.5:5060: SIP/2.0 488 Not acceptable here Via: SIP/2.0/UDP 205.17.1.5:5060;received=205.17.1.5 From: ;tag=64a64e52-1ee9ae62-26e80dcc To: "2142700823" ;tag=as75206b4b Call-ID: 7f0ccc8241ff1f182bfa44c3439585ad@207.15.15.3 CSeq: 133845598 INVITE User-Agent: Lucent-Universal-Gateway Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Supported: replaces Contact: Content-Length: 0 X-Asterisk-HangupCause: Normal Clearing X-Asterisk-HangupCauseCode: 16 --- Jun 8 16:20:05 DEBUG[31477]: chan_sip.c:13841 sipsock_read: SIP message could not be handled, bad request: 7f0ccc8241ff1f182bfa44c3439585ad@207.15.15.3 proxy5*CLI> <-- SIP read from 205.17.1.5:5060: ACK sip:test2@207.15.15.3 SIP/2.0 To: "2142700823" ;tag=as75206b4b From: ;tag=64a64e52-1ee9ae62-26e80dcc Call-ID: 7f0ccc8241ff1f182bfa44c3439585ad@207.15.15.3 CSeq: 133845598 ACK Via: SIP/2.0/UDP 205.17.1.5:5060 Max-Forwards: 70 User-Agent: Lucent-Universal-Gateway Content-Length: 0 Jun 8 16:20:05 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 0: ACK sip:test2@207.15.15.3 SIP/2.0 (35) Jun 8 16:20:05 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 1: To: "2142700823" ;tag=as75206b4b (57) Jun 8 16:20:05 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 2: From: ;tag=64a64e52-1ee9ae62-26e80dcc (69) Jun 8 16:20:05 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 3: Call-ID: 7f0ccc8241ff1f182bfa44c3439585ad@207.15.15.3 (55) Jun 8 16:20:05 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 4: CSeq: 133845598 ACK (19) Jun 8 16:20:05 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 5: Via: SIP/2.0/UDP 205.17.1.5:5060 (35) Jun 8 16:20:05 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 6: Max-Forwards: 70 (16) Jun 8 16:20:05 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 7: User-Agent: Lucent-Universal-Gateway (36) Jun 8 16:20:05 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 8: Content-Length: 0 (17) Jun 8 16:20:05 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 9: (0) --- (9 headers 0 lines)--- Jun 8 16:20:05 DEBUG[31477]: chan_sip.c:13633 handle_request: **** Received ACK (6) - Command in SIP ACK Jun 8 16:20:05 DEBUG[31477]: chan_sip.c:1849 __sip_ack: Stopping retransmission on '7f0ccc8241ff1f182bfa44c3439585ad@207.15.15.3' of Response 133845598: Match Not Found proxy5*CLI> show channels Channel Location State Application(Data) SIP/apxtrunk-3518 (None) Up Bridged Call(SIP/test2-3b96) SIP/test2-3b96 7025770986@dialplan: Up Dial(SIP/apxtrunk/17025770986 2 active channels 1 active call proxy5*CLI> <-- SIP read from 67.58.10.169:5061: BYE sip:17025770986@207.15.15.3 SIP/2.0 Via: SIP/2.0/UDP 67.58.10.169:5061;branch=z9hG4bK-358d9d71 From: 2142700823 ;tag=aed577cc5b1357d8o1 To: ;tag=as3c3e6b3f Call-ID: 73ccdddc-9bcca908@67.58.10.169 CSeq: 105 BYE Max-Forwards: 70 Proxy-Authorization: Digest username="test2",realm="asterisk",nonce="541d4006",uri="sip:17025770986@207.15.15.3",algorithm=MD5,response="1e0bc978bb20ea2df1797a29bc355461" User-Agent: Sipura/SPA2100-3.2.5(d) Content-Length: 0 Jun 8 16:21:17 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 0: BYE sip:17025770986@207.15.15.3 SIP/2.0 (41) Jun 8 16:21:17 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 1: Via: SIP/2.0/UDP 67.58.10.169:5061;branch=z9hG4bK-358d9d71 (58) Jun 8 16:21:17 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 2: From: 2142700823 ;tag=aed577cc5b1357d8o1 (65) Jun 8 16:21:17 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 3: To: ;tag=as3c3e6b3f (50) Jun 8 16:21:17 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 4: Call-ID: 73ccdddc-9bcca908@67.58.10.169 (39) Jun 8 16:21:17 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 5: CSeq: 105 BYE (13) Jun 8 16:21:17 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 6: Max-Forwards: 70 (16) Jun 8 16:21:17 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 7: Proxy-Authorization: Digest username="test2",realm="asterisk",nonce="541d4006",uri="sip:17025770986@207.15.15.3",algorithm=MD5,response="1e0bc978bb20ea2df1797a29bc355461" (172) Jun 8 16:21:17 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 8: User-Agent: Sipura/SPA2100-3.2.5(d) (35) Jun 8 16:21:17 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 9: Content-Length: 0 (17) Jun 8 16:21:17 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 10: (0) --- (10 headers 0 lines)--- Jun 8 16:21:17 DEBUG[31477]: chan_sip.c:13633 handle_request: **** Received BYE (8) - Command in SIP BYE Sending to 67.58.10.169 : 5061 (no NAT) Transmitting (no NAT) to 67.58.10.169:5061: SIP/2.0 200 OK Via: SIP/2.0/UDP 67.58.10.169:5061;branch=z9hG4bK-358d9d71;received=67.58.10.169 From: 2142700823 ;tag=aed577cc5b1357d8o1 To: ;tag=as3c3e6b3f Call-ID: 73ccdddc-9bcca908@67.58.10.169 CSeq: 105 BYE User-Agent: Lucent-Universal-Gateway Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Supported: replaces Contact: Content-Length: 0 --- Jun 8 16:21:17 DEBUG[31486]: rtp.c:2109 ast_rtp_bridge: Oooh, got a hangup Jun 8 16:21:17 DEBUG[31486]: chan_sip.c:15555 sip_set_rtp_peer: Sending reinvite on SIP '7f0ccc8241ff1f182bfa44c3439585ad@207.15.15.3' - It's audio soon redirected to IP 207.15.15.3 Jun 8 16:21:17 DEBUG[31486]: chan_sip.c:5058 reqprep: Strict routing enforced for session 7f0ccc8241ff1f182bfa44c3439585ad@207.15.15.3 set_destination: Parsing for address/port to send to set_destination: set destination to 205.17.1.5, port 5060 We're at 207.15.15.3 port 17778 Adding codec 0x4 (ulaw) to SDP Adding non-codec 0x1 (telephone-event) to SDP Jun 8 16:21:17 DEBUG[31486]: channel.c:2101 ast_internal_timing_enabled: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1) Jun 8 16:21:17 DEBUG[31486]: chan_sip.c:1402 initialize_initreq: Initializing already initialized SIP dialog 7f0ccc8241ff1f182bfa44c3439585ad@207.15.15.3 (presumably reinvite) Jun 8 16:21:17 DEBUG[31486]: chan_sip.c:4138 parse_request: Header 0: INVITE sip:7025770986@205.17.1.5:5060;user=phone SIP/2.0 (59) Jun 8 16:21:17 DEBUG[31486]: chan_sip.c:4138 parse_request: Header 1: Via: SIP/2.0/UDP 207.15.15.3:5060;branch=z9hG4bK36ae075a;rport (64) Jun 8 16:21:17 DEBUG[31486]: chan_sip.c:4138 parse_request: Header 2: From: "2142700823" ;tag=as75206b4b (59) Jun 8 16:21:17 DEBUG[31486]: chan_sip.c:4138 parse_request: Header 3: To: ;tag=64a64e52-1ee9ae62-26e80dcc (67) Jun 8 16:21:17 DEBUG[31486]: chan_sip.c:4138 parse_request: Header 4: Contact: (34) Jun 8 16:21:17 DEBUG[31486]: chan_sip.c:4138 parse_request: Header 5: Call-ID: 7f0ccc8241ff1f182bfa44c3439585ad@207.15.15.3 (55) Jun 8 16:21:17 DEBUG[31486]: chan_sip.c:4138 parse_request: Header 6: CSeq: 103 INVITE (16) Jun 8 16:21:17 DEBUG[31486]: chan_sip.c:4138 parse_request: Header 7: User-Agent: Lucent-Universal-Gateway (36) Jun 8 16:21:17 DEBUG[31486]: chan_sip.c:4138 parse_request: Header 8: Max-Forwards: 70 (16) Jun 8 16:21:17 DEBUG[31486]: chan_sip.c:4138 parse_request: Header 9: Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY (66) Jun 8 16:21:17 DEBUG[31486]: chan_sip.c:4138 parse_request: Header 10: Supported: replaces (19) Jun 8 16:21:17 DEBUG[31486]: chan_sip.c:4138 parse_request: Header 11: X-asterisk-Info: SIP re-invite (External RTP bridge) (52) Jun 8 16:21:17 DEBUG[31486]: chan_sip.c:4138 parse_request: Header 12: Content-Type: application/sdp (29) Jun 8 16:21:17 DEBUG[31486]: chan_sip.c:4138 parse_request: Header 13: Content-Length: 230 (19) Jun 8 16:21:17 DEBUG[31486]: chan_sip.c:4138 parse_request: Header 14: (0) Jun 8 16:21:17 DEBUG[31486]: chan_sip.c:4170 parse_request: Line: v=0 (3) Jun 8 16:21:17 DEBUG[31486]: chan_sip.c:4170 parse_request: Line: o=root 31486 31487 IN IP4 207.15.15.3 (39) Jun 8 16:21:17 DEBUG[31486]: chan_sip.c:4170 parse_request: Line: s=session (9) Jun 8 16:21:17 DEBUG[31486]: chan_sip.c:4170 parse_request: Line: c=IN IP4 207.15.15.3 (22) Jun 8 16:21:17 DEBUG[31486]: chan_sip.c:4170 parse_request: Line: t=0 0 (5) Jun 8 16:21:17 DEBUG[31486]: chan_sip.c:4170 parse_request: Line: m=audio 17778 RTP/AVP 0 101 (27) Jun 8 16:21:17 DEBUG[31486]: chan_sip.c:4170 parse_request: Line: a=rtpmap:0 PCMU/8000 (20) Jun 8 16:21:17 DEBUG[31486]: chan_sip.c:4170 parse_request: Line: a=rtpmap:101 telephone-event/8000 (33) Jun 8 16:21:17 DEBUG[31486]: chan_sip.c:4170 parse_request: Line: a=fmtp:101 0-16 (15) Jun 8 16:21:17 DEBUG[31486]: chan_sip.c:4170 parse_request: Line: a=silenceSupp:off - - - - (25) Jun 8 16:21:17 DEBUG[31486]: chan_sip.c:4170 parse_request: Line: a=sendrecv (10) Reliably Transmitting (no NAT) to 205.17.1.5:5060: INVITE sip:7025770986@205.17.1.5:5060;user=phone SIP/2.0 Via: SIP/2.0/UDP 207.15.15.3:5060;branch=z9hG4bK36ae075a;rport From: "2142700823" ;tag=as75206b4b To: ;tag=64a64e52-1ee9ae62-26e80dcc Contact: Call-ID: 7f0ccc8241ff1f182bfa44c3439585ad@207.15.15.3 CSeq: 103 INVITE User-Agent: Lucent-Universal-Gateway Max-Forwards: 70 Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Supported: replaces X-asterisk-Info: SIP re-invite (External RTP bridge) Content-Type: application/sdp Content-Length: 230 v=0 o=root 31486 31487 IN IP4 207.15.15.3 s=session c=IN IP4 207.15.15.3 t=0 0 m=audio 17778 RTP/AVP 0 101 a=rtpmap:0 PCMU/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=silenceSupp:off - - - - a=sendrecv --- Jun 8 16:21:17 DEBUG[31486]: chan_sip.c:1746 __sip_reliable_xmit: *** SIP TIMER: Initalizing retransmit timer on packet: Id #10 Jun 8 16:21:17 DEBUG[31486]: channel.c:3601 ast_channel_bridge: Returning from native bridge, channels: SIP/test2-3b96, SIP/apxtrunk-3518 Jun 8 16:21:17 DEBUG[31486]: channel.c:1477 ast_hangup: Hanging up channel 'SIP/apxtrunk-3518' Jun 8 16:21:17 DEBUG[31486]: chan_sip.c:2995 sip_hangup: Hangup call SIP/apxtrunk-3518, SIP callid 7f0ccc8241ff1f182bfa44c3439585ad@207.15.15.3) Jun 8 16:21:17 DEBUG[31486]: chan_sip.c:3012 sip_hangup: Hangup call SIP/apxtrunk-3518, SIP callid 7f0ccc8241ff1f182bfa44c3439585ad@207.15.15.3) Jun 8 16:21:17 DEBUG[31486]: chan_sip.c:3017 sip_hangup: update_call_counter(17025770986) - decrement call limit counter on hangup Jun 8 16:21:17 DEBUG[31486]: chan_sip.c:2711 update_call_counter: Updating call counter for outgoing call Jun 8 16:21:17 DEBUG[31486]: rtp.c:1043 ast_rtp_early_media: Channel '' has no RTP, not doing anything Jun 8 16:21:17 DEBUG[31486]: app_dial.c:1615 dial_exec_full: Exiting with DIALSTATUS=ANSWER. Jun 8 16:21:17 DEBUG[31486]: pbx.c:2259 __ast_pbx_run: Spawn extension (dialplan,7025770986,1) exited non-zero on 'SIP/test2-3b96' Jun 8 16:21:17 DEBUG[31486]: cdr.c:910 ast_cdr_detach: CDR detaching from this thread Jun 8 16:21:17 DEBUG[31486]: channel.c:1477 ast_hangup: Hanging up channel 'SIP/test2-3b96' Jun 8 16:21:17 DEBUG[31486]: chan_sip.c:2995 sip_hangup: Hangup call SIP/test2-3b96, SIP callid 73ccdddc-9bcca908@67.58.10.169) Jun 8 16:21:17 DEBUG[31486]: chan_sip.c:3012 sip_hangup: Hangup call SIP/test2-3b96, SIP callid 73ccdddc-9bcca908@67.58.10.169) Jun 8 16:21:17 DEBUG[31486]: chan_sip.c:3017 sip_hangup: update_call_counter(test2) - decrement call limit counter on hangup Jun 8 16:21:17 DEBUG[31486]: chan_sip.c:2711 update_call_counter: Updating call counter for incoming call Jun 8 16:21:17 DEBUG[31458]: chan_sip.c:14196 sip_devicestate: Checking device state for peer apxtrunk Jun 8 16:21:17 DEBUG[31458]: devicestate.c:189 do_state_change: Changing state for SIP/apxtrunk - state 1 (Not in use) Jun 8 16:21:17 DEBUG[31458]: chan_sip.c:14196 sip_devicestate: Checking device state for peer test2 Jun 8 16:21:17 DEBUG[31458]: devicestate.c:189 do_state_change: Changing state for SIP/test2 - state 1 (Not in use) Jun 8 16:21:17 DEBUG[31490]: app_queue.c:557 changethread: Device 'SIP/apxtrunk' changed to state '1' (Not in use) but we don't care because they're not a member of any queue. proxy5*CLI> <-- SIP read from 205.17.1.5:5060: SIP/2.0 200 OK Via: SIP/2.0/UDP 207.15.15.3:5060;branch=z9hG4bK36ae075a;rport To: ;tag=64a64e52-1ee9ae62-26e80dcc From: "2142700823" ;tag=as75206b4b Call-ID: 7f0ccc8241ff1f182bfa44c3439585ad@207.15.15.3 CSeq: 103 INVITE Contact: Accept: application/sdp Accept-Encoding: Accept-Language: en Content-Type: application/sdp Server: Lucent-Universal-Gateway User-Agent: Lucent-Universal-Gateway Content-Length: 235 v=0 o=APX1 518631010 518631012 IN IP4 205.17.1.5 s=Session SDP c=IN IP4 205.17.1.5 t=0 0 m=audio 47710 RTP/AVP 0 101 a=silenceSupp:off a=ecan:b on g168 a=ptime:20 a=rtpmap:101 telephone-event/8000 a=rtpmap:0 PCMU/8000 Jun 8 16:21:17 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 0: SIP/2.0 200 OK (14) Jun 8 16:21:17 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 1: Via: SIP/2.0/UDP 207.15.15.3:5060;branch=z9hG4bK36ae075a;rport (64) Jun 8 16:21:17 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 2: To: ;tag=64a64e52-1ee9ae62-26e80dcc (67) Jun 8 16:21:17 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 3: From: "2142700823" ;tag=as75206b4b (59) Jun 8 16:21:17 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 4: Call-ID: 7f0ccc8241ff1f182bfa44c3439585ad@207.15.15.3 (55) Jun 8 16:21:17 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 5: CSeq: 103 INVITE (16) Jun 8 16:21:17 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 6: Contact: (55) Jun 8 16:21:17 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 7: Accept: application/sdp (23) Jun 8 16:21:17 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 8: Accept-Encoding: (17) Jun 8 16:21:17 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 9: Accept-Language: en (19) Jun 8 16:21:17 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 10: Content-Type: application/sdp (29) Jun 8 16:21:17 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 11: Server: Lucent-Universal-Gateway (32) Jun 8 16:21:17 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 12: User-Agent: Lucent-Universal-Gateway (36) Jun 8 16:21:17 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 13: Content-Length: 235 (19) Jun 8 16:21:17 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 14: (0) Jun 8 16:21:17 DEBUG[31477]: chan_sip.c:4170 parse_request: Line: v=0 (3) Jun 8 16:21:17 DEBUG[31477]: chan_sip.c:4170 parse_request: Line: o=APX1 518631010 518631012 IN IP4 205.17.1.5 (47) Jun 8 16:21:17 DEBUG[31477]: chan_sip.c:4170 parse_request: Line: s=Session SDP (13) Jun 8 16:21:17 DEBUG[31477]: chan_sip.c:4170 parse_request: Line: c=IN IP4 205.17.1.5 (22) Jun 8 16:21:17 DEBUG[31477]: chan_sip.c:4170 parse_request: Line: t=0 0 (5) Jun 8 16:21:17 DEBUG[31477]: chan_sip.c:4170 parse_request: Line: m=audio 47710 RTP/AVP 0 101 (27) Jun 8 16:21:17 DEBUG[31477]: chan_sip.c:4170 parse_request: Line: a=silenceSupp:off (17) Jun 8 16:21:17 DEBUG[31477]: chan_sip.c:4170 parse_request: Line: a=ecan:b on g168 (16) Jun 8 16:21:17 DEBUG[31477]: chan_sip.c:4170 parse_request: Line: a=ptime:20 (10) Jun 8 16:21:17 DEBUG[31477]: chan_sip.c:4170 parse_request: Line: a=rtpmap:101 telephone-event/8000 (33) Jun 8 16:21:17 DEBUG[31477]: chan_sip.c:4170 parse_request: Line: a=rtpmap:0 PCMU/8000 (20) --- (14 headers 11 lines)--- Jun 8 16:21:17 DEBUG[31477]: chan_sip.c:1831 __sip_ack: Acked pending invite 103 Jun 8 16:21:17 DEBUG[31477]: chan_sip.c:1838 __sip_ack: ** SIP TIMER: Cancelling retransmit of packet (reply received) Retransid #10 Jun 8 16:21:17 DEBUG[31477]: chan_sip.c:1849 __sip_ack: Stopping retransmission on '7f0ccc8241ff1f182bfa44c3439585ad@207.15.15.3' of Request 103: Match Found Jun 8 16:21:17 DEBUG[31477]: chan_sip.c:10849 handle_response_invite: SIP response 200 to standard invite Found RTP audio format 0 Found RTP audio format 101 Peer audio RTP is at port 205.17.1.5:47710 Found description format telephone-event Found description format PCMU Jun 8 16:21:17 DEBUG[31477]: chan_sip.c:4593 process_sdp: T38 state changed to 0 on channel Capabilities: us - 0x104 (ulaw|g729), peer - audio=0x4 (ulaw)/video=0x0 (nothing), combined - 0x4 (ulaw) Non-codec capabilities: us - 0x1 (telephone-event), peer - 0x1 (telephone-event), combined - 0x1 (telephone-event) Jun 8 16:21:17 DEBUG[31477]: chan_sip.c:2711 update_call_counter: Updating call counter for outgoing call Jun 8 16:21:17 DEBUG[31477]: chan_sip.c:7200 build_route: build_route: Retaining previous route: Jun 8 16:21:17 DEBUG[31477]: chan_sip.c:5058 reqprep: Strict routing enforced for session 7f0ccc8241ff1f182bfa44c3439585ad@207.15.15.3 set_destination: Parsing for address/port to send to set_destination: set destination to 205.17.1.5, port 5060 Transmitting (no NAT) to 205.17.1.5:5060: ACK sip:7025770986@205.17.1.5:5060;user=phone SIP/2.0 Via: SIP/2.0/UDP 207.15.15.3:5060;branch=z9hG4bK67bf8f64;rport From: "2142700823" ;tag=as75206b4b To: ;tag=64a64e52-1ee9ae62-26e80dcc Contact: Call-ID: 7f0ccc8241ff1f182bfa44c3439585ad@207.15.15.3 CSeq: 103 ACK User-Agent: Lucent-Universal-Gateway Max-Forwards: 70 Content-Length: 0 --- Jun 8 16:21:17 DEBUG[31477]: chan_sip.c:5058 reqprep: Strict routing enforced for session 7f0ccc8241ff1f182bfa44c3439585ad@207.15.15.3 set_destination: Parsing for address/port to send to set_destination: set destination to 205.17.1.5, port 5060 Reliably Transmitting (no NAT) to 205.17.1.5:5060: BYE sip:7025770986@205.17.1.5:5060;user=phone SIP/2.0 Via: SIP/2.0/UDP 207.15.15.3:5060;branch=z9hG4bK1653a755;rport From: "2142700823" ;tag=as75206b4b To: ;tag=64a64e52-1ee9ae62-26e80dcc Contact: Call-ID: 7f0ccc8241ff1f182bfa44c3439585ad@207.15.15.3 CSeq: 104 BYE User-Agent: Lucent-Universal-Gateway Max-Forwards: 70 Content-Length: 0 --- Jun 8 16:21:17 DEBUG[31477]: chan_sip.c:1746 __sip_reliable_xmit: *** SIP TIMER: Initalizing retransmit timer on packet: Id #11 Really destroying SIP dialog '73ccdddc-9bcca908@67.58.10.169' Method: BYE Jun 8 16:21:17 DEBUG[31491]: app_queue.c:557 changethread: Device 'SIP/test2' changed to state '1' (Not in use) but we don't care because they're not a member of any queue. proxy5*CLI> <-- SIP read from 205.17.1.5:5060: INVITE sip:test2@207.15.15.3 SIP/2.0 To: "2142700823" ;tag=as75206b4b From: ;tag=64a64e52-1ee9ae62-26e80dcc Call-ID: 7f0ccc8241ff1f182bfa44c3439585ad@207.15.15.3 CSeq: 133845599 INVITE Via: SIP/2.0/UDP 205.17.1.5:5060 Max-Forwards: 70 Contact: sip:205.17.1.5:5060 Content-Type: application/sdp Accept: application/sdp Accept-Encoding: Accept-Language: en User-Agent: Lucent-Universal-Gateway Content-Length: 364 v=0 o=APX1 518631010 518631013 IN IP4 205.17.1.5 s=Session SDP c=IN IP4 205.17.1.5 t=0 0 m=image 47710 udptl t38 a=T38FaxMaxDatagram:316 a=T38FaxMaxBuffer:72 a=T38FaxRateManagement:transferredTCF a=T38FaxUdpEC:t38UDPRedundancy a=T38FaxVersion:0 a=T38FaxTranscodingJBIG:0 a=T38FaxTranscodingMMR:0 a=T38FaxFillBitRemoval:0 a=T38MaxBitRate:14400 Jun 8 16:21:17 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 0: INVITE sip:test2@207.15.15.3 SIP/2.0 (38) Jun 8 16:21:17 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 1: To: "2142700823" ;tag=as75206b4b (57) Jun 8 16:21:17 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 2: From: ;tag=64a64e52-1ee9ae62-26e80dcc (69) Jun 8 16:21:17 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 3: Call-ID: 7f0ccc8241ff1f182bfa44c3439585ad@207.15.15.3 (55) Jun 8 16:21:17 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 4: CSeq: 133845599 INVITE (22) Jun 8 16:21:17 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 5: Via: SIP/2.0/UDP 205.17.1.5:5060 (35) Jun 8 16:21:17 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 6: Max-Forwards: 70 (16) Jun 8 16:21:17 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 7: Contact: sip:205.17.1.5:5060 (31) Jun 8 16:21:17 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 8: Content-Type: application/sdp (29) Jun 8 16:21:17 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 9: Accept: application/sdp (23) Jun 8 16:21:17 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 10: Accept-Encoding: (17) Jun 8 16:21:17 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 11: Accept-Language: en (19) Jun 8 16:21:17 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 12: User-Agent: Lucent-Universal-Gateway (36) Jun 8 16:21:17 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 13: Content-Length: 364 (19) Jun 8 16:21:17 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 14: (0) Jun 8 16:21:17 DEBUG[31477]: chan_sip.c:4170 parse_request: Line: v=0 (3) Jun 8 16:21:17 DEBUG[31477]: chan_sip.c:4170 parse_request: Line: o=APX1 518631010 518631013 IN IP4 205.17.1.5 (47) Jun 8 16:21:17 DEBUG[31477]: chan_sip.c:4170 parse_request: Line: s=Session SDP (13) Jun 8 16:21:17 DEBUG[31477]: chan_sip.c:4170 parse_request: Line: c=IN IP4 205.17.1.5 (22) Jun 8 16:21:17 DEBUG[31477]: chan_sip.c:4170 parse_request: Line: t=0 0 (5) Jun 8 16:21:17 DEBUG[31477]: chan_sip.c:4170 parse_request: Line: m=image 47710 udptl t38 (23) Jun 8 16:21:17 DEBUG[31477]: chan_sip.c:4170 parse_request: Line: a=T38FaxMaxDatagram:316 (23) Jun 8 16:21:17 DEBUG[31477]: chan_sip.c:4170 parse_request: Line: a=T38FaxMaxBuffer:72 (20) Jun 8 16:21:17 DEBUG[31477]: chan_sip.c:4170 parse_request: Line: a=T38FaxRateManagement:transferredTCF (37) Jun 8 16:21:17 DEBUG[31477]: chan_sip.c:4170 parse_request: Line: a=T38FaxUdpEC:t38UDPRedundancy (30) Jun 8 16:21:17 DEBUG[31477]: chan_sip.c:4170 parse_request: Line: a=T38FaxVersion:0 (17) Jun 8 16:21:17 DEBUG[31477]: chan_sip.c:4170 parse_request: Line: a=T38FaxTranscodingJBIG:0 (25) Jun 8 16:21:17 DEBUG[31477]: chan_sip.c:4170 parse_request: Line: a=T38FaxTranscodingMMR:0 (24) Jun 8 16:21:17 DEBUG[31477]: chan_sip.c:4170 parse_request: Line: a=T38FaxFillBitRemoval:0 (24) Jun 8 16:21:17 DEBUG[31477]: chan_sip.c:4170 parse_request: Line: a=T38MaxBitRate:14400 (21) --- (14 headers 15 lines)--- Jun 8 16:21:17 DEBUG[31477]: chan_sip.c:13633 handle_request: **** Received INVITE (5) - Command in SIP INVITE Sending to 205.17.1.5 : 5060 (no NAT) Using INVITE request as basis request - 7f0ccc8241ff1f182bfa44c3439585ad@207.15.15.3 Jun 8 16:21:17 DEBUG[31477]: chan_sip.c:12520 handle_request_invite: Got a SIP re-invite for call 7f0ccc8241ff1f182bfa44c3439585ad@207.15.15.3 <-- SIP read from 205.17.1.5:5060: BYE sip:test2@207.15.15.3 SIP/2.0 To: "2142700823" ;tag=as75206b4b From: ;tag=64a64e52-1ee9ae62-26e80dcc Call-ID: 7f0ccc8241ff1f182bfa44c3439585ad@207.15.15.3 CSeq: 133845600 BYE Via: SIP/2.0/UDP 205.17.1.5:5060 Max-Forwards: 70 Accept: application/sdp Accept-Encoding: Accept-Language: en User-Agent: Lucent-Universal-Gateway Content-Length: 0 Jun 8 16:21:17 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 0: BYE sip:test2@207.15.15.3 SIP/2.0 (35) Jun 8 16:21:17 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 1: To: "2142700823" ;tag=as75206b4b (57) Jun 8 16:21:17 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 2: From: ;tag=64a64e52-1ee9ae62-26e80dcc (69) Jun 8 16:21:17 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 3: Call-ID: 7f0ccc8241ff1f182bfa44c3439585ad@207.15.15.3 (55) Jun 8 16:21:17 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 4: CSeq: 133845600 BYE (19) Jun 8 16:21:17 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 5: Via: SIP/2.0/UDP 205.17.1.5:5060 (35) Jun 8 16:21:17 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 6: Max-Forwards: 70 (16) Jun 8 16:21:17 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 7: Accept: application/sdp (23) Jun 8 16:21:17 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 8: Accept-Encoding: (17) Jun 8 16:21:17 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 9: Accept-Language: en (19) Jun 8 16:21:17 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 10: User-Agent: Lucent-Universal-Gateway (36) Jun 8 16:21:17 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 11: Content-Length: 0 (17) Jun 8 16:21:17 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 12: (0) --- (12 headers 0 lines)--- Jun 8 16:21:17 DEBUG[31477]: chan_sip.c:13633 handle_request: **** Received BYE (8) - Command in SIP BYE Sending to 205.17.1.5 : 5060 (no NAT) Transmitting (no NAT) to 205.17.1.5:5060: SIP/2.0 200 OK Via: SIP/2.0/UDP 205.17.1.5:5060;received=205.17.1.5 From: ;tag=64a64e52-1ee9ae62-26e80dcc To: "2142700823" ;tag=as75206b4b Call-ID: 7f0ccc8241ff1f182bfa44c3439585ad@207.15.15.3 CSeq: 133845600 BYE User-Agent: Lucent-Universal-Gateway Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Supported: replaces Contact: Content-Length: 0 --- proxy5*CLI> <-- SIP read from 205.17.1.5:5060: SIP/2.0 200 OK Via: SIP/2.0/UDP 207.15.15.3:5060;branch=z9hG4bK1653a755;rport To: ;tag=64a64e52-1ee9ae62-26e80dcc From: "2142700823" ;tag=as75206b4b Call-ID: 7f0ccc8241ff1f182bfa44c3439585ad@207.15.15.3 CSeq: 104 BYE Server: Lucent-Universal-Gateway User-Agent: Lucent-Universal-Gateway Content-Length: 0 Jun 8 16:21:17 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 0: SIP/2.0 200 OK (14) Jun 8 16:21:17 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 1: Via: SIP/2.0/UDP 207.15.15.3:5060;branch=z9hG4bK1653a755;rport (64) Jun 8 16:21:17 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 2: To: ;tag=64a64e52-1ee9ae62-26e80dcc (67) Jun 8 16:21:17 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 3: From: "2142700823" ;tag=as75206b4b (59) Jun 8 16:21:17 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 4: Call-ID: 7f0ccc8241ff1f182bfa44c3439585ad@207.15.15.3 (55) Jun 8 16:21:17 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 5: CSeq: 104 BYE (13) Jun 8 16:21:17 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 6: Server: Lucent-Universal-Gateway (32) Jun 8 16:21:17 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 7: User-Agent: Lucent-Universal-Gateway (36) Jun 8 16:21:17 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 8: Content-Length: 0 (17) Jun 8 16:21:17 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 9: (0) --- (9 headers 0 lines)--- Jun 8 16:21:17 DEBUG[31477]: chan_sip.c:1838 __sip_ack: ** SIP TIMER: Cancelling retransmit of packet (reply received) Retransid #11 Jun 8 16:21:17 DEBUG[31477]: chan_sip.c:1849 __sip_ack: Stopping retransmission on '7f0ccc8241ff1f182bfa44c3439585ad@207.15.15.3' of Request 104: Match Found Really destroying SIP dialog '7f0ccc8241ff1f182bfa44c3439585ad@207.15.15.3' Method: BYE proxy5*CLI> <-- SIP read from 205.17.1.5:5060: INVITE sip:test2@207.15.15.3 SIP/2.0 To: "2142700823" ;tag=as75206b4b From: ;tag=64a64e52-1ee9ae62-26e80dcc Call-ID: 7f0ccc8241ff1f182bfa44c3439585ad@207.15.15.3 CSeq: 133845599 INVITE Via: SIP/2.0/UDP 205.17.1.5:5060 Max-Forwards: 70 Contact: sip:205.17.1.5:5060 Content-Type: application/sdp Accept: application/sdp Accept-Encoding: Accept-Language: en User-Agent: Lucent-Universal-Gateway Content-Length: 364 v=0 o=APX1 518631010 518631013 IN IP4 205.17.1.5 s=Session SDP c=IN IP4 205.17.1.5 t=0 0 m=image 47710 udptl t38 a=T38FaxMaxDatagram:316 a=T38FaxMaxBuffer:72 a=T38FaxRateManagement:transferredTCF a=T38FaxUdpEC:t38UDPRedundancy a=T38FaxVersion:0 a=T38FaxTranscodingJBIG:0 a=T38FaxTranscodingMMR:0 a=T38FaxFillBitRemoval:0 a=T38MaxBitRate:14400 Jun 8 16:21:21 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 0: INVITE sip:test2@207.15.15.3 SIP/2.0 (38) Jun 8 16:21:21 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 1: To: "2142700823" ;tag=as75206b4b (57) Jun 8 16:21:21 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 2: From: ;tag=64a64e52-1ee9ae62-26e80dcc (69) Jun 8 16:21:21 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 3: Call-ID: 7f0ccc8241ff1f182bfa44c3439585ad@207.15.15.3 (55) Jun 8 16:21:21 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 4: CSeq: 133845599 INVITE (22) Jun 8 16:21:21 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 5: Via: SIP/2.0/UDP 205.17.1.5:5060 (35) Jun 8 16:21:21 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 6: Max-Forwards: 70 (16) Jun 8 16:21:21 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 7: Contact: sip:205.17.1.5:5060 (31) Jun 8 16:21:21 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 8: Content-Type: application/sdp (29) Jun 8 16:21:21 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 9: Accept: application/sdp (23) Jun 8 16:21:21 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 10: Accept-Encoding: (17) Jun 8 16:21:21 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 11: Accept-Language: en (19) Jun 8 16:21:21 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 12: User-Agent: Lucent-Universal-Gateway (36) Jun 8 16:21:21 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 13: Content-Length: 364 (19) Jun 8 16:21:21 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 14: (0) Jun 8 16:21:21 DEBUG[31477]: chan_sip.c:4170 parse_request: Line: v=0 (3) Jun 8 16:21:21 DEBUG[31477]: chan_sip.c:4170 parse_request: Line: o=APX1 518631010 518631013 IN IP4 205.17.1.5 (47) Jun 8 16:21:21 DEBUG[31477]: chan_sip.c:4170 parse_request: Line: s=Session SDP (13) Jun 8 16:21:21 DEBUG[31477]: chan_sip.c:4170 parse_request: Line: c=IN IP4 205.17.1.5 (22) Jun 8 16:21:21 DEBUG[31477]: chan_sip.c:4170 parse_request: Line: t=0 0 (5) Jun 8 16:21:21 DEBUG[31477]: chan_sip.c:4170 parse_request: Line: m=image 47710 udptl t38 (23) Jun 8 16:21:21 DEBUG[31477]: chan_sip.c:4170 parse_request: Line: a=T38FaxMaxDatagram:316 (23) Jun 8 16:21:21 DEBUG[31477]: chan_sip.c:4170 parse_request: Line: a=T38FaxMaxBuffer:72 (20) Jun 8 16:21:21 DEBUG[31477]: chan_sip.c:4170 parse_request: Line: a=T38FaxRateManagement:transferredTCF (37) Jun 8 16:21:21 DEBUG[31477]: chan_sip.c:4170 parse_request: Line: a=T38FaxUdpEC:t38UDPRedundancy (30) Jun 8 16:21:21 DEBUG[31477]: chan_sip.c:4170 parse_request: Line: a=T38FaxVersion:0 (17) Jun 8 16:21:21 DEBUG[31477]: chan_sip.c:4170 parse_request: Line: a=T38FaxTranscodingJBIG:0 (25) Jun 8 16:21:21 DEBUG[31477]: chan_sip.c:4170 parse_request: Line: a=T38FaxTranscodingMMR:0 (24) Jun 8 16:21:21 DEBUG[31477]: chan_sip.c:4170 parse_request: Line: a=T38FaxFillBitRemoval:0 (24) Jun 8 16:21:21 DEBUG[31477]: chan_sip.c:4170 parse_request: Line: a=T38MaxBitRate:14400 (21) --- (14 headers 15 lines)--- Jun 8 16:21:21 DEBUG[31477]: chan_sip.c:3918 sip_alloc: Allocating new SIP dialog for 7f0ccc8241ff1f182bfa44c3439585ad@207.15.15.3 - INVITE (With RTP) Jun 8 16:21:21 DEBUG[31477]: chan_sip.c:13633 handle_request: **** Received INVITE (5) - Command in SIP INVITE Sending to 205.17.1.5 : 5060 (no NAT) Using INVITE request as basis request - 7f0ccc8241ff1f182bfa44c3439585ad@207.15.15.3 Found peer 'apxtrunk' Jun 8 16:21:21 DEBUG[31477]: chan_sip.c:8413 check_user_full: Setting NAT on RTP to Off Jun 8 16:21:21 WARNING[31477]: chan_sip.c:4368 process_sdp: Unknown SDP media type in offer: image 47710 udptl t38 Reliably Transmitting (no NAT) to 205.17.1.5:5060: SIP/2.0 488 Not acceptable here Via: SIP/2.0/UDP 205.17.1.5:5060;received=205.17.1.5 From: ;tag=64a64e52-1ee9ae62-26e80dcc To: "2142700823" ;tag=as75206b4b Call-ID: 7f0ccc8241ff1f182bfa44c3439585ad@207.15.15.3 CSeq: 133845599 INVITE User-Agent: Lucent-Universal-Gateway Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Supported: replaces Contact: Content-Length: 0 --- Jun 8 16:21:21 DEBUG[31477]: chan_sip.c:1746 __sip_reliable_xmit: *** SIP TIMER: Initalizing retransmit timer on packet: Id #12 Jun 8 16:21:21 DEBUG[31477]: chan_sip.c:12450 handle_request_invite: No compatible codecs for this SIP call. Jun 8 16:21:21 DEBUG[31477]: chan_sip.c:13841 sipsock_read: SIP message could not be handled, bad request: 7f0ccc8241ff1f182bfa44c3439585ad@207.15.15.3 proxy5*CLI> <-- SIP read from 205.17.1.5:5060: ACK sip:test2@207.15.15.3 SIP/2.0 To: "2142700823" ;tag=as75206b4b From: ;tag=64a64e52-1ee9ae62-26e80dcc Call-ID: 7f0ccc8241ff1f182bfa44c3439585ad@207.15.15.3 CSeq: 133845599 ACK Via: SIP/2.0/UDP 205.17.1.5:5060 Max-Forwards: 70 User-Agent: Lucent-Universal-Gateway Content-Length: 0 Jun 8 16:21:21 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 0: ACK sip:test2@207.15.15.3 SIP/2.0 (35) Jun 8 16:21:21 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 1: To: "2142700823" ;tag=as75206b4b (57) Jun 8 16:21:21 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 2: From: ;tag=64a64e52-1ee9ae62-26e80dcc (69) Jun 8 16:21:21 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 3: Call-ID: 7f0ccc8241ff1f182bfa44c3439585ad@207.15.15.3 (55) Jun 8 16:21:21 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 4: CSeq: 133845599 ACK (19) Jun 8 16:21:21 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 5: Via: SIP/2.0/UDP 205.17.1.5:5060 (35) Jun 8 16:21:21 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 6: Max-Forwards: 70 (16) Jun 8 16:21:21 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 7: User-Agent: Lucent-Universal-Gateway (36) Jun 8 16:21:21 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 8: Content-Length: 0 (17) Jun 8 16:21:21 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 9: (0) --- (9 headers 0 lines)--- Jun 8 16:21:21 DEBUG[31477]: chan_sip.c:13633 handle_request: **** Received ACK (6) - Command in SIP ACK Jun 8 16:21:21 DEBUG[31477]: chan_sip.c:1838 __sip_ack: ** SIP TIMER: Cancelling retransmit of packet (reply received) Retransid #12 Jun 8 16:21:21 DEBUG[31477]: chan_sip.c:1849 __sip_ack: Stopping retransmission on '7f0ccc8241ff1f182bfa44c3439585ad@207.15.15.3' of Response 133845599: Match Found Really destroying SIP dialog '7f0ccc8241ff1f182bfa44c3439585ad@207.15.15.3' Method: ACK <-- SIP read from 205.17.1.5:5060: BYE sip:test2@207.15.15.3 SIP/2.0 To: "2142700823" ;tag=as75206b4b From: ;tag=64a64e52-1ee9ae62-26e80dcc Call-ID: 7f0ccc8241ff1f182bfa44c3439585ad@207.15.15.3 CSeq: 133845601 BYE Via: SIP/2.0/UDP 205.17.1.5:5060 Max-Forwards: 70 Accept: application/sdp Accept-Encoding: Accept-Language: en User-Agent: Lucent-Universal-Gateway Content-Length: 0 Jun 8 16:21:21 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 0: BYE sip:test2@207.15.15.3 SIP/2.0 (35) Jun 8 16:21:21 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 1: To: "2142700823" ;tag=as75206b4b (57) Jun 8 16:21:21 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 2: From: ;tag=64a64e52-1ee9ae62-26e80dcc (69) Jun 8 16:21:21 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 3: Call-ID: 7f0ccc8241ff1f182bfa44c3439585ad@207.15.15.3 (55) Jun 8 16:21:21 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 4: CSeq: 133845601 BYE (19) Jun 8 16:21:21 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 5: Via: SIP/2.0/UDP 205.17.1.5:5060 (35) Jun 8 16:21:21 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 6: Max-Forwards: 70 (16) Jun 8 16:21:21 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 7: Accept: application/sdp (23) Jun 8 16:21:21 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 8: Accept-Encoding: (17) Jun 8 16:21:21 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 9: Accept-Language: en (19) Jun 8 16:21:21 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 10: User-Agent: Lucent-Universal-Gateway (36) Jun 8 16:21:21 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 11: Content-Length: 0 (17) Jun 8 16:21:21 DEBUG[31477]: chan_sip.c:4138 parse_request: Header 12: (0) --- (12 headers 0 lines)--- Jun 8 16:21:21 DEBUG[31477]: chan_sip.c:3918 sip_alloc: Allocating new SIP dialog for 7f0ccc8241ff1f182bfa44c3439585ad@207.15.15.3 - BYE (No RTP) Jun 8 16:21:21 DEBUG[31477]: chan_sip.c:13633 handle_request: **** Received BYE (8) - Command in SIP BYE Sending to 205.17.1.5 : 5060 (no NAT) Transmitting (no NAT) to 205.17.1.5:5060: SIP/2.0 200 OK Via: SIP/2.0/UDP 205.17.1.5:5060;received=205.17.1.5 From: ;tag=64a64e52-1ee9ae62-26e80dcc To: "2142700823" ;tag=as75206b4b Call-ID: 7f0ccc8241ff1f182bfa44c3439585ad@207.15.15.3 CSeq: 133845601 BYE User-Agent: Lucent-Universal-Gateway Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Supported: replaces Content-Length: 0 --- Really destroying SIP dialog '7f0ccc8241ff1f182bfa44c3439585ad@207.15.15.3' Method: BYE proxy5*CLI> sip no debug