*CLI> *CLI> *CLI> *CLI> *CLI> set debug 4 Core debug was 0 and is now 4 *CLI> set verbose 4 Verbosity was 0 and is now 4 *CLI> sip debug SIP Debugging enabled *CLI> *CLI> *CLI> *CLI> *CLI> *CLI> *CLI> <-- SIP read from 10.3.2.111:5060: INVITE sip:7401@10.3.3.239;user=phone SIP/2.0 Via: SIP/2.0/UDP 10.3.2.111;branch=z9hG4bK6e60d7acf5e8816f From: ;tag=147fde71546b9c6d To: Contact: Call-ID: 2a19bf3a063f8e40@10.3.2.111 CSeq: 34555 INVITE User-Agent: Grandstream BT100 1.0.5.11 Max-Forwards: 70 Allow: INVITE,ACK,CANCEL,BYE,NOTIFY,REFER,OPTIONS,INFO,SUBSCRIBE Content-Type: application/sdp Content-Length: 298 v=0 o=6111 8000 8000 IN IP4 10.3.2.111 s=SIP Call c=IN IP4 10.3.2.111 t=0 0 m=audio 5004 RTP/AVP 0 8 18 2 15 101 a=rtpmap:0 PCMU/8000 a=rtpmap:8 PCMA/8000 a=rtpmap:18 G729/8000 a=rtpmap:2 G726-32/8000 a=rtpmap:15 G728/8000 a=ptime:10 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-11 Jul 10 21:01:14 DEBUG[4544]: chan_sip.c:3408 parse_request: Header 0: INVITE sip:7401@10.3.3.239;user=phone SIP/2.0 (45) Jul 10 21:01:14 DEBUG[4544]: chan_sip.c:3408 parse_request: Header 1: Via: SIP/2.0/UDP 10.3.2.111;branch=z9hG4bK6e60d7acf5e8816f (58) Jul 10 21:01:14 DEBUG[4544]: chan_sip.c:3408 parse_request: Header 2: From: ;tag=147fde71546b9c6d (59) Jul 10 21:01:14 DEBUG[4544]: chan_sip.c:3408 parse_request: Header 3: To: (36) Jul 10 21:01:14 DEBUG[4544]: chan_sip.c:3408 parse_request: Header 4: Contact: (41) Jul 10 21:01:14 DEBUG[4544]: chan_sip.c:3408 parse_request: Header 5: Call-ID: 2a19bf3a063f8e40@10.3.2.111 (36) Jul 10 21:01:14 DEBUG[4544]: chan_sip.c:3408 parse_request: Header 6: CSeq: 34555 INVITE (18) Jul 10 21:01:14 DEBUG[4544]: chan_sip.c:3408 parse_request: Header 7: User-Agent: Grandstream BT100 1.0.5.11 (38) Jul 10 21:01:14 DEBUG[4544]: chan_sip.c:3408 parse_request: Header 8: Max-Forwards: 70 (16) Jul 10 21:01:14 DEBUG[4544]: chan_sip.c:3408 parse_request: Header 9: Allow: INVITE,ACK,CANCEL,BYE,NOTIFY,REFER,OPTIONS,INFO,SUBSCRIBE (64) Jul 10 21:01:14 DEBUG[4544]: chan_sip.c:3408 parse_request: Header 10: Content-Type: application/sdp (29) Jul 10 21:01:14 DEBUG[4544]: chan_sip.c:3408 parse_request: Header 11: Content-Length: 298 (19) Jul 10 21:01:14 DEBUG[4544]: chan_sip.c:3408 parse_request: Header 12: (0) Jul 10 21:01:14 DEBUG[4544]: chan_sip.c:3440 parse_request: Line: v=0 (3) Jul 10 21:01:14 DEBUG[4544]: chan_sip.c:3440 parse_request: Line: o=6111 8000 8000 IN IP4 10.3.2.111 (34) Jul 10 21:01:14 DEBUG[4544]: chan_sip.c:3440 parse_request: Line: s=SIP Call (10) Jul 10 21:01:15 DEBUG[4544]: chan_sip.c:3440 parse_request: Line: c=IN IP4 10.3.2.111 (19) Jul 10 21:01:15 DEBUG[4544]: chan_sip.c:3440 parse_request: Line: t=0 0 (5) Jul 10 21:01:15 DEBUG[4544]: chan_sip.c:3440 parse_request: Line: m=audio 5004 RTP/AVP 0 8 18 2 15 101 (36) Jul 10 21:01:15 DEBUG[4544]: chan_sip.c:3440 parse_request: Line: a=rtpmap:0 PCMU/8000 (20) Jul 10 21:01:15 DEBUG[4544]: chan_sip.c:3440 parse_request: Line: a=rtpmap:8 PCMA/8000 (20) Jul 10 21:01:15 DEBUG[4544]: chan_sip.c:3440 parse_request: Line: a=rtpmap:18 G729/8000 (21) Jul 10 21:01:15 DEBUG[4544]: chan_sip.c:3440 parse_request: Line: a=rtpmap:2 G726-32/8000 (23) Jul 10 21:01:15 DEBUG[4544]: chan_sip.c:3440 parse_request: Line: a=rtpmap:15 G728/8000 (21) Jul 10 21:01:15 DEBUG[4544]: chan_sip.c:3440 parse_request: Line: a=ptime:10 (10) Jul 10 21:01:15 DEBUG[4544]: chan_sip.c:3440 parse_request: Line: a=rtpmap:101 telephone-event/8000 (33) Jul 10 21:01:15 DEBUG[4544]: chan_sip.c:3440 parse_request: Line: a=fmtp:101 0-11 (15) --- (12 headers 14 lines)--- Jul 10 21:01:15 DEBUG[4544]: chan_sip.c:3192 sip_alloc: Allocating new SIP dialog for 2a19bf3a063f8e40@10.3.2.111 - INVITE (With RTP) Jul 10 21:01:15 DEBUG[4544]: chan_sip.c:11186 handle_request: **** Received INVITE (5) - Command in SIP INVITE Using INVITE request as basis request - 2a19bf3a063f8e40@10.3.2.111 Sending to 10.3.2.111 : 5060 (non-NAT) Jul 10 21:01:15 DEBUG[4544]: chan_sip.c:7204 check_user_full: Setting NAT on RTP to 0 Reliably Transmitting (no NAT) to 10.3.2.111:5060: SIP/2.0 407 Proxy Authentication Required Via: SIP/2.0/UDP 10.3.2.111;branch=z9hG4bK6e60d7acf5e8816f;received=10.3.2.111 From: ;tag=147fde71546b9c6d To: ;tag=as486f2427 Call-ID: 2a19bf3a063f8e40@10.3.2.111 CSeq: 34555 INVITE User-Agent: Asterisk PBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Contact: Proxy-Authenticate: Digest realm="asterisk", nonce="62c8d21e" Content-Length: 0 --- Jul 10 21:01:15 DEBUG[4544]: chan_sip.c:1293 __sip_reliable_xmit: *** SIP TIMER: Initalizing retransmit timer on packet: Id #8 Scheduling destruction of call '2a19bf3a063f8e40@10.3.2.111' in 15000 ms Found user '6111' <-- SIP read from 10.3.2.111:5060: ACK sip:7401@10.3.3.239;user=phone SIP/2.0 Via: SIP/2.0/UDP 10.3.2.111;branch=z9hG4bK6e60d7acf5e8816f From: ;tag=147fde71546b9c6d To: ;tag=as486f2427 Contact: Call-ID: 2a19bf3a063f8e40@10.3.2.111 CSeq: 34555 ACK User-Agent: Grandstream BT100 1.0.5.11 Max-Forwards: 70 Allow: INVITE,ACK,CANCEL,BYE,NOTIFY,REFER,OPTIONS,INFO,SUBSCRIBE Content-Length: 0 Jul 10 21:01:15 DEBUG[4544]: chan_sip.c:3408 parse_request: Header 0: ACK sip:7401@10.3.3.239;user=phone SIP/2.0 (42) Jul 10 21:01:15 DEBUG[4544]: chan_sip.c:3408 parse_request: Header 1: Via: SIP/2.0/UDP 10.3.2.111;branch=z9hG4bK6e60d7acf5e8816f (58) Jul 10 21:01:15 DEBUG[4544]: chan_sip.c:3408 parse_request: Header 2: From: ;tag=147fde71546b9c6d (59) Jul 10 21:01:15 DEBUG[4544]: chan_sip.c:3408 parse_request: Header 3: To: ;tag=as486f2427 (51) Jul 10 21:01:15 DEBUG[4544]: chan_sip.c:3408 parse_request: Header 4: Contact: (41) Jul 10 21:01:15 DEBUG[4544]: chan_sip.c:3408 parse_request: Header 5: Call-ID: 2a19bf3a063f8e40@10.3.2.111 (36) Jul 10 21:01:15 DEBUG[4544]: chan_sip.c:3408 parse_request: Header 6: CSeq: 34555 ACK (15) Jul 10 21:01:15 DEBUG[4544]: chan_sip.c:3408 parse_request: Header 7: User-Agent: Grandstream BT100 1.0.5.11 (38) Jul 10 21:01:15 DEBUG[4544]: chan_sip.c:3408 parse_request: Header 8: Max-Forwards: 70 (16) Jul 10 21:01:15 DEBUG[4544]: chan_sip.c:3408 parse_request: Header 9: Allow: INVITE,ACK,CANCEL,BYE,NOTIFY,REFER,OPTIONS,INFO,SUBSCRIBE (64) Jul 10 21:01:15 DEBUG[4544]: chan_sip.c:3408 parse_request: Header 10: Content-Length: 0 (17) Jul 10 21:01:15 DEBUG[4544]: chan_sip.c:3408 parse_request: Header 11: (0) --- (11 headers 0 lines)--- Jul 10 21:01:15 DEBUG[4544]: chan_sip.c:11186 handle_request: **** Received ACK (6) - Command in SIP ACK Jul 10 21:01:15 DEBUG[4544]: chan_sip.c:1390 __sip_ack: ** SIP TIMER: Cancelling retransmit of packet (reply received) Retransid #8 Jul 10 21:01:16 DEBUG[4544]: chan_sip.c:1401 __sip_ack: Stopping retransmission on '2a19bf3a063f8e40@10.3.2.111' of Response 34555: Match Found <-- SIP read from 10.3.2.111:5060: INVITE sip:7401@10.3.3.239;user=phone SIP/2.0 Via: SIP/2.0/UDP 10.3.2.111;branch=z9hG4bKf10d0c1e3fda7deb From: ;tag=147fde71546b9c6d To: Contact: Proxy-Authorization: DIGEST username="6111", realm="asterisk", algorithm=MD5, uri="sip:7401@10.3.3.239;user=phone", nonce="62c8d21e", response="aac9705327305dba057f06fbceb5b1ec" Call-ID: 2a19bf3a063f8e40@10.3.2.111 CSeq: 34556 INVITE User-Agent: Grandstream BT100 1.0.5.11 Max-Forwards: 70 Allow: INVITE,ACK,CANCEL,BYE,NOTIFY,REFER,OPTIONS,INFO,SUBSCRIBE Content-Type: application/sdp Content-Length: 298 v=0 o=6111 8000 8000 IN IP4 10.3.2.111 s=SIP Call c=IN IP4 10.3.2.111 t=0 0 m=audio 5004 RTP/AVP 0 8 18 2 15 101 a=rtpmap:0 PCMU/8000 a=rtpmap:8 PCMA/8000 a=rtpmap:18 G729/8000 a=rtpmap:2 G726-32/8000 a=rtpmap:15 G728/8000 a=ptime:10 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-11 Jul 10 21:01:16 DEBUG[4544]: chan_sip.c:3408 parse_request: Header 0: INVITE sip:7401@10.3.3.239;user=phone SIP/2.0 (45) Jul 10 21:01:16 DEBUG[4544]: chan_sip.c:3408 parse_request: Header 1: Via: SIP/2.0/UDP 10.3.2.111;branch=z9hG4bKf10d0c1e3fda7deb (58) Jul 10 21:01:16 DEBUG[4544]: chan_sip.c:3408 parse_request: Header 2: From: ;tag=147fde71546b9c6d (59) Jul 10 21:01:16 DEBUG[4544]: chan_sip.c:3408 parse_request: Header 3: To: (36) Jul 10 21:01:16 DEBUG[4544]: chan_sip.c:3408 parse_request: Header 4: Contact: (41) Jul 10 21:01:16 DEBUG[4544]: chan_sip.c:3408 parse_request: Header 5: Proxy-Authorization: DIGEST username="6111", realm="asterisk", algorithm=MD5, uri="sip:7401@10.3.3.239;user=phone", nonce="62c8d21e", response="aac9705327305dba057f06fbceb5b1ec" (177) Jul 10 21:01:16 DEBUG[4544]: chan_sip.c:3408 parse_request: Header 6: Call-ID: 2a19bf3a063f8e40@10.3.2.111 (36) Jul 10 21:01:16 DEBUG[4544]: chan_sip.c:3408 parse_request: Header 7: CSeq: 34556 INVITE (18) Jul 10 21:01:16 DEBUG[4544]: chan_sip.c:3408 parse_request: Header 8: User-Agent: Grandstream BT100 1.0.5.11 (38) Jul 10 21:01:16 DEBUG[4544]: chan_sip.c:3408 parse_request: Header 9: Max-Forwards: 70 (16) Jul 10 21:01:16 DEBUG[4544]: chan_sip.c:3408 parse_request: Header 10: Allow: INVITE,ACK,CANCEL,BYE,NOTIFY,REFER,OPTIONS,INFO,SUBSCRIBE (64) Jul 10 21:01:16 DEBUG[4544]: chan_sip.c:3408 parse_request: Header 11: Content-Type: application/sdp (29) Jul 10 21:01:16 DEBUG[4544]: chan_sip.c:3408 parse_request: Header 12: Content-Length: 298 (19) Jul 10 21:01:16 DEBUG[4544]: chan_sip.c:3408 parse_request: Header 13: (0) Jul 10 21:01:16 DEBUG[4544]: chan_sip.c:3440 parse_request: Line: v=0 (3) Jul 10 21:01:16 DEBUG[4544]: chan_sip.c:3440 parse_request: Line: o=6111 8000 8000 IN IP4 10.3.2.111 (34) Jul 10 21:01:16 DEBUG[4544]: chan_sip.c:3440 parse_request: Line: s=SIP Call (10) Jul 10 21:01:16 DEBUG[4544]: chan_sip.c:3440 parse_request: Line: c=IN IP4 10.3.2.111 (19) Jul 10 21:01:16 DEBUG[4544]: chan_sip.c:3440 parse_request: Line: t=0 0 (5) Jul 10 21:01:16 DEBUG[4544]: chan_sip.c:3440 parse_request: Line: m=audio 5004 RTP/AVP 0 8 18 2 15 101 (36) Jul 10 21:01:16 DEBUG[4544]: chan_sip.c:3440 parse_request: Line: a=rtpmap:0 PCMU/8000 (20) Jul 10 21:01:16 DEBUG[4544]: chan_sip.c:3440 parse_request: Line: a=rtpmap:8 PCMA/8000 (20) Jul 10 21:01:16 DEBUG[4544]: chan_sip.c:3440 parse_request: Line: a=rtpmap:18 G729/8000 (21) Jul 10 21:01:16 DEBUG[4544]: chan_sip.c:3440 parse_request: Line: a=rtpmap:2 G726-32/8000 (23) Jul 10 21:01:16 DEBUG[4544]: chan_sip.c:3440 parse_request: Line: a=rtpmap:15 G728/8000 (21) Jul 10 21:01:16 DEBUG[4544]: chan_sip.c:3440 parse_request: Line: a=ptime:10 (10) Jul 10 21:01:16 DEBUG[4544]: chan_sip.c:3440 parse_request: Line: a=rtpmap:101 telephone-event/8000 (33) Jul 10 21:01:16 DEBUG[4544]: chan_sip.c:3440 parse_request: Line: a=fmtp:101 0-11 (15) --- (13 headers 14 lines)--- Jul 10 21:01:16 DEBUG[4544]: chan_sip.c:11186 handle_request: **** Received INVITE (5) - Command in SIP INVITE Using INVITE request as basis request - 2a19bf3a063f8e40@10.3.2.111 Sending to 10.3.2.111 : 5060 (non-NAT) Jul 10 21:01:16 DEBUG[4544]: chan_sip.c:7204 check_user_full: Setting NAT on RTP to 0 Found user '6111' Found RTP audio format 0 Found RTP audio format 8 Found RTP audio format 18 Found RTP audio format 2 Found RTP audio format 15 Found RTP audio format 101 Peer audio RTP is at port 10.3.2.111:5004 Jul 10 21:01:17 DEBUG[4544]: chan_sip.c:3649 process_sdp: Peer audio RTP is at port 10.3.2.111:5004 Found description format PCMU Found description format PCMA Found description format G729 Found description format G726-32 Found description format G728 Found description format telephone-event Capabilities: us - 0x4 (ulaw), peer - audio=0x11c (ulaw|alaw|g726|g729)/video=0x0 (nothing), combined - 0x4 (ulaw) Non-codec capabilities: us - 0x1 (telephone-event), peer - 0x1 (telephone-event), combined - 0x1 (telephone-event) Jul 10 21:01:17 DEBUG[4544]: chan_sip.c:10546 handle_request_invite: Checking SIP call limits for device 6111 Jul 10 21:01:17 DEBUG[4544]: chan_sip.c:2209 update_call_counter: Updating call counter for incoming call Looking for 7401 in from-internal (domain 10.3.3.239) Jul 10 21:01:17 DEBUG[4544]: chan_sip.c:6186 build_route: build_route: Contact hop: list_route: hop: Transmitting (no NAT) to 10.3.2.111:5060: SIP/2.0 100 Trying Via: SIP/2.0/UDP 10.3.2.111;branch=z9hG4bKf10d0c1e3fda7deb;received=10.3.2.111 From: ;tag=147fde71546b9c6d To: Call-ID: 2a19bf3a063f8e40@10.3.2.111 CSeq: 34556 INVITE User-Agent: Asterisk PBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Contact: Content-Length: 0 --- Jul 10 21:01:17 DEBUG[4538]: chan_sip.c:11717 sip_devicestate: Checking device state for peer 6111 Jul 10 21:01:17 DEBUG[4538]: channel.c:777 channel_find_locked: Avoiding initial deadlock for 'SIP/6111-ba7a' Jul 10 21:01:17 DEBUG[4558]: pbx.c:1677 pbx_extension_helper: Launching 'Dial' Jul 10 21:01:17 DEBUG[4538]: channel.c:777 channel_find_locked: Avoiding initial deadlock for 'SIP/6111-ba7a' -- Executing Dial("SIP/6111-ba7a", "OOH323/ken_op|20") in new stack Jul 10 21:01:17 DEBUG[4538]: channel.c:777 channel_find_locked: Avoiding initial deadlock for 'SIP/6111-ba7a' <-- SIP read from 10.3.2.111:5060: INVITE sip:7401@10.3.3.239;user=phone SIP/2.0 Via: SIP/2.0/UDP 10.3.2.111;branch=z9hG4bKf10d0c1e3fda7deb From: ;tag=147fde71546b9c6d To: Contact: Proxy-Authorization: DIGEST username="6111", realm="asterisk", algorithm=MD5, uri="sip:7401@10.3.3.239;user=phone", nonce="62c8d21e", response="aac9705327305dba057f06fbceb5b1ec" Call-ID: 2a19bf3a063f8e40@10.3.2.111 CSeq: 34556 INVITE User-Agent: Grandstream BT100 1.0.5.11 Max-Forwards: 70 Allow: INVITE,ACK,CANCEL,BYE,NOTIFY,REFER,OPTIONS,INFO,SUBSCRIBE Content-Type: application/sdp Content-Length: 298 v=0 o=6111 8000 8000 IN IP4 10.3.2.111 s=SIP Call c=IN IP4 10.3.2.111 t=0 0 m=audio 5004 RTP/AVP 0 8 18 2 15 101 a=rtpmap:0 PCMU/8000 a=rtpmap:8 PCMA/8000 a=rtpmap:18 G729/8000 a=rtpmap:2 G726-32/8000 a=rtpmap:15 G728/8000 a=ptime:10 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-11 Jul 10 21:01:17 DEBUG[4558]: channel.c:2829 ast_channel_inherit_variables: Not copying variable STACK-from-internal-7401-1. Jul 10 21:01:17 DEBUG[4538]: devicestate.c:187 do_state_change: Changing state for SIP/6111 - state 2 (In use) Jul 10 21:01:17 DEBUG[4544]: chan_sip.c:3408 parse_request: Header 0: INVITE sip:7401@10.3.3.239;user=phone SIP/2.0 (45) Jul 10 21:01:17 DEBUG[4559]: app_queue.c:523 changethread: Device 'SIP/6111' changed to state '2' (In use) but we don't care because they're not a member of any queue. Jul 10 21:01:17 DEBUG[4538]: chan_sip.c:11717 sip_devicestate: Checking device state for peer 6111 Jul 10 21:01:17 DEBUG[4558]: channel.c:2829 ast_channel_inherit_variables: Not copying variable SIPCALLID. Jul 10 21:01:17 DEBUG[4544]: chan_sip.c:3408 parse_request: Header 1: Via: SIP/2.0/UDP 10.3.2.111;branch=z9hG4bKf10d0c1e3fda7deb (58) Jul 10 21:01:17 DEBUG[4544]: chan_sip.c:3408 parse_request: Header 2: From: ;tag=147fde71546b9c6d (59) Jul 10 21:01:17 DEBUG[4558]: channel.c:2829 ast_channel_inherit_variables: Not copying variable SIPUSERAGENT. Jul 10 21:01:18 DEBUG[4544]: chan_sip.c:3408 parse_request: Header 3: To: (36) Jul 10 21:01:18 DEBUG[4558]: channel.c:2829 ast_channel_inherit_variables: Not copying variable SIPDOMAIN. Jul 10 21:01:18 DEBUG[4558]: channel.c:2829 ast_channel_inherit_variables: Not copying variable SIPURI. -- Called ken_op -- ast_channel_make_compatible: src=0x4: dst=0x4 Jul 10 21:01:18 DEBUG[4558]: channel.c:2350 set_format: Set channel OOH323/ken_op-5aa7 to read format ulaw Jul 10 21:01:18 DEBUG[4544]: chan_sip.c:3408 parse_request: Header 4: Contact: (41) Jul 10 21:01:18 DEBUG[4544]: chan_sip.c:3408 parse_request: Header 5: Proxy-Authorization: DIGEST username="6111", realm="asterisk", algorithm=MD5, uri="sip:7401@10.3.3.239;user=phone", nonce="62c8d21e", response="aac9705327305dba057f06fbceb5b1ec" (177) Jul 10 21:01:18 DEBUG[4544]: chan_sip.c:3408 parse_request: Header 6: Call-ID: 2a19bf3a063f8e40@10.3.2.111 (36) Jul 10 21:01:18 DEBUG[4558]: channel.c:2350 set_format: Set channel SIP/6111-ba7a to write format ulaw Jul 10 21:01:18 DEBUG[4558]: channel.c:2350 set_format: Set channel SIP/6111-ba7a to read format ulaw Jul 10 21:01:18 DEBUG[4558]: channel.c:2350 set_format: Set channel OOH323/ken_op-5aa7 to write format ulaw Jul 10 21:01:18 DEBUG[4544]: chan_sip.c:3408 parse_request: Header 7: CSeq: 34556 INVITE (18) Jul 10 21:01:18 DEBUG[4544]: chan_sip.c:3408 parse_request: Header 8: User-Agent: Grandstream BT100 1.0.5.11 (38) Jul 10 21:01:18 DEBUG[4544]: chan_sip.c:3408 parse_request: Header 9: Max-Forwards: 70 (16) Jul 10 21:01:18 DEBUG[4544]: chan_sip.c:3408 parse_request: Header 10: Allow: INVITE,ACK,CANCEL,BYE,NOTIFY,REFER,OPTIONS,INFO,SUBSCRIBE (64) Jul 10 21:01:18 DEBUG[4544]: chan_sip.c:3408 parse_request: Header 11: Content-Type: application/sdp (29) Jul 10 21:01:18 DEBUG[4544]: chan_sip.c:3408 parse_request: Header 12: Content-Length: 298 (19) Jul 10 21:01:18 DEBUG[4544]: chan_sip.c:3408 parse_request: Header 13: (0) Jul 10 21:01:18 DEBUG[4544]: chan_sip.c:3440 parse_request: Line: v=0 (3) -- OOH323/ken_op-5aa7 is ringing Jul 10 21:01:18 DEBUG[4544]: chan_sip.c:3440 parse_request: Line: o=6111 8000 8000 IN IP4 10.3.2.111 (34) Jul 10 21:01:18 DEBUG[4538]: channel.c:777 channel_find_locked: Avoiding initial deadlock for 'OOH323/ken_op-5aa7' Transmitting (no NAT) to 10.3.2.111:5060: SIP/2.0 180 Ringing Via: SIP/2.0/UDP 10.3.2.111;branch=z9hG4bKf10d0c1e3fda7deb;received=10.3.2.111 From: ;tag=147fde71546b9c6d To: ;tag=as42250234 Call-ID: 2a19bf3a063f8e40@10.3.2.111 CSeq: 34556 INVITE User-Agent: Asterisk PBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Contact: Content-Length: 0 --- Jul 10 21:01:18 DEBUG[4538]: devicestate.c:187 do_state_change: Changing state for OOH323/ken_op - state 6 (Ringing) Jul 10 21:01:18 DEBUG[4544]: chan_sip.c:3440 parse_request: Line: s=SIP Call (10) Jul 10 21:01:18 DEBUG[4560]: app_queue.c:523 changethread: Device 'OOH323/ken_op' changed to state '6' (Ringing) but we don't care because they're not a member of any queue. Jul 10 21:01:18 DEBUG[4544]: chan_sip.c:3440 parse_request: Line: c=IN IP4 10.3.2.111 (19) Jul 10 21:01:18 DEBUG[4544]: chan_sip.c:3440 parse_request: Line: t=0 0 (5) Jul 10 21:01:18 DEBUG[4544]: chan_sip.c:3440 parse_request: Line: m=audio 5004 RTP/AVP 0 8 18 2 15 101 (36) Jul 10 21:01:18 DEBUG[4544]: chan_sip.c:3440 parse_request: Line: a=rtpmap:0 PCMU/8000 (20) Jul 10 21:01:18 DEBUG[4544]: chan_sip.c:3440 parse_request: Line: a=rtpmap:8 PCMA/8000 (20) Jul 10 21:01:18 DEBUG[4544]: chan_sip.c:3440 parse_request: Line: a=rtpmap:18 G729/8000 (21) Jul 10 21:01:19 DEBUG[4544]: chan_sip.c:3440 parse_request: Line: a=rtpmap:2 G726-32/8000 (23) Jul 10 21:01:19 DEBUG[4544]: chan_sip.c:3440 parse_request: Line: a=rtpmap:15 G728/8000 (21) Jul 10 21:01:19 DEBUG[4544]: chan_sip.c:3440 parse_request: Line: a=ptime:10 (10) Jul 10 21:01:19 DEBUG[4544]: chan_sip.c:3440 parse_request: Line: a=rtpmap:101 telephone-event/8000 (33) Jul 10 21:01:19 DEBUG[4544]: chan_sip.c:3440 parse_request: Line: a=fmtp:101 0-11 (15) --- (13 headers 14 lines)--- Jul 10 21:01:19 DEBUG[4544]: chan_sip.c:11186 handle_request: **** Received INVITE (5) - Command in SIP INVITE Jul 10 21:01:19 DEBUG[4544]: chan_sip.c:11200 handle_request: Ignoring SIP message because of retransmit (INVITE Seqno 34556, ours 34556) Ignoring this INVITE request Jul 10 21:01:19 DEBUG[4544]: chan_sip.c:10600 handle_request_invite: Got a SIP re-invite for call 2a19bf3a063f8e40@10.3.2.111 Transmitting (no NAT) to 10.3.2.111:5060: SIP/2.0 100 Trying Via: SIP/2.0/UDP 10.3.2.111;branch=z9hG4bKf10d0c1e3fda7deb;received=10.3.2.111 From: ;tag=147fde71546b9c6d To: Call-ID: 2a19bf3a063f8e40@10.3.2.111 CSeq: 34556 INVITE User-Agent: Asterisk PBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Contact: Content-Length: 0 --- *CLI> *CLI> *CLI> *CLI> *CLI> *CLI> *CLI> Jul 10 21:01:26 DEBUG[4538]: channel.c:777 channel_find_locked: Avoiding initial deadlock for 'OOH323/ken_op-5aa7' -- OOH323/ken_op-5aa7 answered SIP/6111-ba7a -- ast_channel_make_compatible: src=0x4: dst=0x4 Jul 10 21:01:26 DEBUG[4558]: channel.c:2350 set_format: Set channel SIP/6111-ba7a to read format ulaw Jul 10 21:01:26 DEBUG[4538]: channel.c:777 channel_find_locked: Avoiding initial deadlock for 'OOH323/ken_op-5aa7' Jul 10 21:01:26 DEBUG[4558]: channel.c:2350 set_format: Set channel OOH323/ken_op-5aa7 to write format ulaw Jul 10 21:01:26 DEBUG[4558]: channel.c:2350 set_format: Set channel OOH323/ken_op-5aa7 to read format ulaw Jul 10 21:01:26 DEBUG[4538]: devicestate.c:187 do_state_change: Changing state for OOH323/ken_op - state 2 (In use) Jul 10 21:01:26 DEBUG[4558]: channel.c:2350 set_format: Set channel SIP/6111-ba7a to write format ulaw Jul 10 21:01:26 DEBUG[4561]: app_queue.c:523 changethread: Device 'OOH323/ken_op' changed to state '2' (In use) but we don't care because they're not a member of any queue. Jul 10 21:01:26 DEBUG[4558]: chan_sip.c:2540 sip_answer: sip_answer(SIP/6111-ba7a) Jul 10 21:01:26 DEBUG[4538]: chan_sip.c:11717 sip_devicestate: Checking device state for peer 6111 We're at 10.3.3.239 port 17384hannel.c:777 channel_find_locked: Avoiding initial deadlock for 'SIP/6111-ba7a' Adding codec 0x4 (ulaw) to SDP Adding non-codec 0x1 (telephone-event) to SDP Jul 10 21:01:26 DEBUG[4538]: channel.c:777 channel_find_locked: Avoiding initial deadlock for 'SIP/6111-ba7a' Reliably Transmitting (no NAT) to 10.3.2.111:5060:_find_locked: SIP/2.0 200 OK Via: SIP/2.0/UDP 10.3.2.111;branch=z9hG4bKf10d0c1e3fda7deb;received=10.3.2.111 From: ;tag=147fde71546b9c6d To: ;tag=as42250234 Call-ID: 2a19bf3a063f8e40@10.3.2.111 CSeq: 34556 INVITE User-Agent: Asterisk PBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Contact: Content-Type: application/sdp Content-Length: 210 v=0 o=root 4558 4558 IN IP4 10.3.3.239 s=session c=IN IP4 10.3.3.239 t=0 0 m=audio 17384 RTP/AVP 0 101 a=rtpmap:0 PCMU/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=silenceSupp:off - - - - --- Avoiding initial deadlock for 'SIP/6111-ba7a' Jul 10 21:01:26 DEBUG[4558]: chan_sip.c:1293 __sip_reliable_xmit: *** SIP TIMER: Initalizing retransmit timer on packet: Id #10 -- ast_channel_bridge is being called: (1) flags=0x0 Jul 10 21:01:26 DEBUG[4538]: devicestate.c:187 do_state_change: Changing state for SIP/6111 - state 2 (In use) <-- SIP read from 10.3.2.111:5060: ACK sip:7401@10.3.3.239 SIP/2.0 Via: SIP/2.0/UDP 10.3.2.111;branch=z9hG4bKbd1c5877c9af4eb0 From: ;tag=147fde71546b9c6d To: ;tag=as42250234 Contact: Proxy-Authorization: DIGEST username="6111", realm="asterisk", algorithm=MD5, uri="sip:7401@10.3.3.239", nonce="62c8d21e", response="f178718bce5e638c5e3f6bc587e23fc6" Call-ID: 2a19bf3a063f8e40@10.3.2.111 CSeq: 34556 ACK User-Agent: Grandstream BT100 1.0.5.11 Max-Forwards: 70 Allow: INVITE,ACK,CANCEL,BYE,NOTIFY,REFER,OPTIONS,INFO,SUBSCRIBE Content-Length: 0 Jul 10 21:01:26 DEBUG[4562]: app_queue.c:523 changethread: Device 'SIP/6111' changed to state '2' (In use) but we don't care because they're not a member of any queue. Jul 10 21:01:26 DEBUG[4538]: chan_sip.c:11717 sip_devicestate: Checking device state for peer 6111 -- ast_channel_bridge is being called: (2) c0-bridge=268845316: c1-bridge=268845316 Jul 10 21:01:27 DEBUG[4544]: chan_sip.c:3408 parse_request: Header 0: ACK sip:7401@10.3.3.239 SIP/2.0 (31) -- Attempting native bridge of SIP/6111-ba7a and OOH323/ken_op-5aa7 Jul 10 21:01:27 DEBUG[4558]: chan_sip.c:12977 sip_set_rtp_peer: Deferring reinvite on SIP '2a19bf3a063f8e40@10.3.2.111' - It's audio will be redirected to IP 10.1.1.155 Jul 10 21:01:27 DEBUG[4558]: channel.c:1957 ast_read: Dropping duplicate answer! Jul 10 21:01:27 DEBUG[4558]: rtp.c:1378 ast_rtp_write: Ooh, format changed from unknown to ulaw Jul 10 21:01:27 DEBUG[4544]: chan_sip.c:3408 parse_request: Header 1: Via: SIP/2.0/UDP 10.3.2.111;branch=z9hG4bKbd1c5877c9af4eb0 (58) Jul 10 21:01:27 DEBUG[4544]: chan_sip.c:3408 parse_request: Header 2: From: ;tag=147fde71546b9c6d (59) Jul 10 21:01:27 DEBUG[4544]: chan_sip.c:3408 parse_request: Header 3: To: ;tag=as42250234 (51) Jul 10 21:01:27 DEBUG[4558]: rtp.c:1378 ast_rtp_write: Ooh, format changed from unknown to ulaw Jul 10 21:01:27 DEBUG[4544]: chan_sip.c:3408 parse_request: Header 4: Contact: (41) Jul 10 21:01:27 DEBUG[4544]: chan_sip.c:3408 parse_request: Header 5: Proxy-Authorization: DIGEST username="6111", realm="asterisk", algorithm=MD5, uri="sip:7401@10.3.3.239", nonce="62c8d21e", response="f178718bce5e638c5e3f6bc587e23fc6" (166) Jul 10 21:01:27 DEBUG[4544]: chan_sip.c:3408 parse_request: Header 6: Call-ID: 2a19bf3a063f8e40@10.3.2.111 (36) Jul 10 21:01:27 DEBUG[4544]: chan_sip.c:3408 parse_request: Header 7: CSeq: 34556 ACK (15) Jul 10 21:01:27 DEBUG[4544]: chan_sip.c:3408 parse_request: Header 8: User-Agent: Grandstream BT100 1.0.5.11 (38) Jul 10 21:01:27 DEBUG[4544]: chan_sip.c:3408 parse_request: Header 9: Max-Forwards: 70 (16) Jul 10 21:01:27 DEBUG[4544]: chan_sip.c:3408 parse_request: Header 10: Allow: INVITE,ACK,CANCEL,BYE,NOTIFY,REFER,OPTIONS,INFO,SUBSCRIBE (64) Jul 10 21:01:27 DEBUG[4544]: chan_sip.c:3408 parse_request: Header 11: Content-Length: 0 (17) Jul 10 21:01:27 DEBUG[4544]: chan_sip.c:3408 parse_request: Header 12: (0) --- (12 headers 0 lines)--- Jul 10 21:01:27 DEBUG[4544]: chan_sip.c:11186 handle_request: **** Received ACK (6) - Command in SIP ACK Jul 10 21:01:27 DEBUG[4544]: chan_sip.c:1390 __sip_ack: ** SIP TIMER: Cancelling retransmit of packet (reply received) Retransid #10 Jul 10 21:01:27 DEBUG[4544]: chan_sip.c:1401 __sip_ack: Stopping retransmission on '2a19bf3a063f8e40@10.3.2.111' of Response 34556: Match Found Jul 10 21:01:27 DEBUG[4544]: chan_sip.c:9616 check_pendings: Sending pending reinvite on '2a19bf3a063f8e40@10.3.2.111' set_destination: Parsing for address/port to send to set_destination: set destination to 10.3.2.111, port 5060 X-asterisk-info: redirect=0xa01019b: peer=0xa03026f We're at 10.3.3.239 port 17384 Adding codec 0x4 (ulaw) to SDP Adding non-codec 0x1 (telephone-event) to SDP Jul 10 21:01:27 DEBUG[4544]: chan_sip.c:3408 parse_request: Header 0: INVITE sip:6111@10.3.2.111 SIP/2.0 (34) Jul 10 21:01:27 DEBUG[4544]: chan_sip.c:3408 parse_request: Header 1: Via: SIP/2.0/UDP 10.3.3.239:5060;branch=z9hG4bK7cc62291 (55) Jul 10 21:01:27 DEBUG[4544]: chan_sip.c:3408 parse_request: Header 2: From: ;tag=as42250234 (53) Jul 10 21:01:27 DEBUG[4544]: chan_sip.c:3408 parse_request: Header 3: To: ;tag=147fde71546b9c6d (57) Jul 10 21:01:27 DEBUG[4544]: chan_sip.c:3408 parse_request: Header 4: Contact: (30) Jul 10 21:01:27 DEBUG[4544]: chan_sip.c:3408 parse_request: Header 5: Call-ID: 2a19bf3a063f8e40@10.3.2.111 (36) Jul 10 21:01:27 DEBUG[4544]: chan_sip.c:3408 parse_request: Header 6: CSeq: 102 INVITE (16) Jul 10 21:01:27 DEBUG[4544]: chan_sip.c:3408 parse_request: Header 7: User-Agent: Asterisk PBX (24) Jul 10 21:01:27 DEBUG[4544]: chan_sip.c:3408 parse_request: Header 8: Max-Forwards: 70 (16) Jul 10 21:01:27 DEBUG[4544]: chan_sip.c:3408 parse_request: Header 9: Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY (66) Jul 10 21:01:27 DEBUG[4544]: chan_sip.c:3408 parse_request: Header 10: X-asterisk-info: SIP re-invite (RTP bridge) (45) Jul 10 21:01:28 DEBUG[4544]: chan_sip.c:3408 parse_request: Header 11: Content-Type: application/sdp (29) Jul 10 21:01:28 DEBUG[4544]: chan_sip.c:3408 parse_request: Header 12: Content-Length: 209 (19) Jul 10 21:01:28 DEBUG[4544]: chan_sip.c:3408 parse_request: Header 13: (0) Jul 10 21:01:28 DEBUG[4544]: chan_sip.c:3440 parse_request: Line: v=0 (3) Jul 10 21:01:28 DEBUG[4544]: chan_sip.c:3440 parse_request: Line: o=root 4558 4559 IN IP4 10.1.1.155 (34) Jul 10 21:01:28 DEBUG[4544]: chan_sip.c:3440 parse_request: Line: s=session (9) Jul 10 21:01:28 DEBUG[4544]: chan_sip.c:3440 parse_request: Line: c=IN IP4 10.1.1.155 (19) Jul 10 21:01:28 DEBUG[4544]: chan_sip.c:3440 parse_request: Line: t=0 0 (5) Jul 10 21:01:28 DEBUG[4544]: chan_sip.c:3440 parse_request: Line: m=audio 1720 RTP/AVP 0 101 (26) Jul 10 21:01:28 DEBUG[4544]: chan_sip.c:3440 parse_request: Line: a=rtpmap:0 PCMU/8000 (20) Jul 10 21:01:28 DEBUG[4544]: chan_sip.c:3440 parse_request: Line: a=rtpmap:101 telephone-event/8000 (33) Jul 10 21:01:28 DEBUG[4544]: chan_sip.c:3440 parse_request: Line: a=fmtp:101 0-16 (15) Jul 10 21:01:28 DEBUG[4544]: chan_sip.c:3440 parse_request: Line: a=silenceSupp:off - - - - (25) 13 headers, 10 lines Reliably Transmitting (no NAT) to 10.3.2.111:5060: INVITE sip:6111@10.3.2.111 SIP/2.0 Via: SIP/2.0/UDP 10.3.3.239:5060;branch=z9hG4bK7cc62291 From: ;tag=as42250234 To: ;tag=147fde71546b9c6d Contact: Call-ID: 2a19bf3a063f8e40@10.3.2.111 CSeq: 102 INVITE User-Agent: Asterisk PBX Max-Forwards: 70 Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY X-asterisk-info: SIP re-invite (RTP bridge) Content-Type: application/sdp Content-Length: 209 v=0 o=root 4558 4559 IN IP4 10.1.1.155 s=session c=IN IP4 10.1.1.155 t=0 0 m=audio 1720 RTP/AVP 0 101 a=rtpmap:0 PCMU/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=silenceSupp:off - - - - --- Jul 10 21:01:28 DEBUG[4544]: chan_sip.c:1293 __sip_reliable_xmit: *** SIP TIMER: Initalizing retransmit timer on packet: Id #11 <-- SIP read from 10.3.2.111:5060: SIP/2.0 200 OK Via: SIP/2.0/UDP 10.3.3.239:5060;branch=z9hG4bK7cc62291 From: ;tag=as42250234 To: ;tag=147fde71546b9c6d Call-ID: 2a19bf3a063f8e40@10.3.2.111 CSeq: 102 INVITE User-Agent: Grandstream BT100 1.0.5.11 Contact: Allow: INVITE,ACK,CANCEL,BYE,NOTIFY,REFER,OPTIONS,INFO,SUBSCRIBE Content-Type: application/sdp Content-Length: 195 v=0 o=6111 8000 8000 IN IP4 10.3.2.111 s=SIP Call c=IN IP4 10.3.2.111 t=0 0 m=audio 5004 RTP/AVP 0 101 a=rtpmap:0 PCMU/8000 a=ptime:20 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-11 Jul 10 21:01:28 DEBUG[4544]: chan_sip.c:3408 parse_request: Header 0: SIP/2.0 200 OK (14) Jul 10 21:01:28 DEBUG[4544]: chan_sip.c:3408 parse_request: Header 1: Via: SIP/2.0/UDP 10.3.3.239:5060;branch=z9hG4bK7cc62291 (55) Jul 10 21:01:28 DEBUG[4544]: chan_sip.c:3408 parse_request: Header 2: From: ;tag=as42250234 (53) Jul 10 21:01:28 DEBUG[4544]: chan_sip.c:3408 parse_request: Header 3: To: ;tag=147fde71546b9c6d (57) Jul 10 21:01:28 DEBUG[4544]: chan_sip.c:3408 parse_request: Header 4: Call-ID: 2a19bf3a063f8e40@10.3.2.111 (36) Jul 10 21:01:28 DEBUG[4544]: chan_sip.c:3408 parse_request: Header 5: CSeq: 102 INVITE (16) Jul 10 21:01:28 DEBUG[4544]: chan_sip.c:3408 parse_request: Header 6: User-Agent: Grandstream BT100 1.0.5.11 (38) Jul 10 21:01:28 DEBUG[4544]: chan_sip.c:3408 parse_request: Header 7: Contact: (41) Jul 10 21:01:28 DEBUG[4544]: chan_sip.c:3408 parse_request: Header 8: Allow: INVITE,ACK,CANCEL,BYE,NOTIFY,REFER,OPTIONS,INFO,SUBSCRIBE (64) Jul 10 21:01:28 DEBUG[4544]: chan_sip.c:3408 parse_request: Header 9: Content-Type: application/sdp (29) Jul 10 21:01:28 DEBUG[4544]: chan_sip.c:3408 parse_request: Header 10: Content-Length: 195 (19) Jul 10 21:01:28 DEBUG[4544]: chan_sip.c:3408 parse_request: Header 11: (0) Jul 10 21:01:28 DEBUG[4544]: chan_sip.c:3440 parse_request: Line: v=0 (3) Jul 10 21:01:28 DEBUG[4544]: chan_sip.c:3440 parse_request: Line: o=6111 8000 8000 IN IP4 10.3.2.111 (34) Jul 10 21:01:28 DEBUG[4544]: chan_sip.c:3440 parse_request: Line: s=SIP Call (10) Jul 10 21:01:28 DEBUG[4544]: chan_sip.c:3440 parse_request: Line: c=IN IP4 10.3.2.111 (19) Jul 10 21:01:28 DEBUG[4544]: chan_sip.c:3440 parse_request: Line: t=0 0 (5) Jul 10 21:01:28 DEBUG[4544]: chan_sip.c:3440 parse_request: Line: m=audio 5004 RTP/AVP 0 101 (26) Jul 10 21:01:28 DEBUG[4544]: chan_sip.c:3440 parse_request: Line: a=rtpmap:0 PCMU/8000 (20) Jul 10 21:01:28 DEBUG[4544]: chan_sip.c:3440 parse_request: Line: a=ptime:20 (10) Jul 10 21:01:28 DEBUG[4544]: chan_sip.c:3440 parse_request: Line: a=rtpmap:101 telephone-event/8000 (33) Jul 10 21:01:28 DEBUG[4544]: chan_sip.c:3440 parse_request: Line: a=fmtp:101 0-11 (15) --- (11 headers 10 lines)--- Jul 10 21:01:29 DEBUG[4544]: chan_sip.c:1379 __sip_ack: Acked pending invite 102 Jul 10 21:01:29 DEBUG[4544]: chan_sip.c:1390 __sip_ack: ** SIP TIMER: Cancelling retransmit of packet (reply received) Retransid #11 Jul 10 21:01:29 DEBUG[4544]: chan_sip.c:1401 __sip_ack: Stopping retransmission on '2a19bf3a063f8e40@10.3.2.111' of Request 102: Match Found Jul 10 21:01:29 DEBUG[4544]: chan_sip.c:9631 handle_response_invite: SIP response 200 to RE-invite on outgoing call 2a19bf3a063f8e40@10.3.2.111 Found RTP audio format 0 Found RTP audio format 101 Peer audio RTP is at port 10.3.2.111:5004 Jul 10 21:01:29 DEBUG[4544]: chan_sip.c:3649 process_sdp: Peer audio RTP is at port 10.3.2.111:5004 Found description format PCMU Found description format telephone-event 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) Jul 10 21:01:29 DEBUG[4544]: chan_sip.c:6186 build_route: build_route: Contact hop: list_route: hop: set_destination: Parsing for address/port to send to set_destination: set destination to 10.3.2.111, port 5060 Transmitting (no NAT) to 10.3.2.111:5060: ACK sip:6111@10.3.2.111;user=phone SIP/2.0 Via: SIP/2.0/UDP 10.3.3.239:5060;branch=z9hG4bK33e095db From: ;tag=as42250234 To: ;tag=147fde71546b9c6d Contact: Call-ID: 2a19bf3a063f8e40@10.3.2.111 CSeq: 102 ACK User-Agent: Asterisk PBX Max-Forwards: 70 Content-Length: 0 --- *CLI> *CLI> *CLI> *CLI> *CLI> Jul 10 21:01:38 DEBUG[4558]: rtp.c:415 ast_rtcp_read: Got RTCP report of 96 bytes Jul 10 21:01:50 DEBUG[4558]: rtp.c:415 ast_rtcp_read: Got RTCP report of 96 bytes *CLI> *CLI> *CLI> *CLI> *CLI> *CLI> *CLI> rtp debug ip 10.3.2.Jul 10 21:02:02 DEBUG[4558]: rtp.c:415 ast_rtcp_read: Got RTCP report of 96 bytes 111 RTP Debugging Enabled for IP: 10.3.2.111 Sent RTP packet to 10.3.2.111:5004 (type 0, seq 54461, ts 296800, len 160) Sent RTP packet to 10.3.2.111:5004 (type 0, seq 54462, ts 296960, len 160) Sent RTP packet to 10.3.2.111:5004 (type 0, seq 54463, ts 297120, len 160) Sent RTP packet to 10.3.2.111:5004 (type 0, seq 54464, ts 297280, len 160) Sent RTP packet to 10.3.2.111:5004 (type 0, seq 54465, ts 297440, len 160) Sent RTP packet to 10.3.2.111:5004 (type 0, seq 54466, ts 297600, len 160) Sent RTP packet to 10.3.2.111:5004 (type 0, seq 54467, ts 297760, len 160) Sent RTP packet to 10.3.2.111:5004 (type 0, seq 54468, ts 297920, len 160) Sent RTP packet to 10.3.2.111:5004 (type 0, seq 54469, ts 298080, len 160) Sent RTP packet to 10.3.2.111:5004 (type 0, seq 54470, ts 298240, len 160) Sent RTP packet to 10.3.2.111:5004 (type 0, seq 54471, ts 298400, len 160) Sent RTP packet to 10.3.2.111:5004 (type 0, seq 54472, ts 298560, len 160) Sent RTP packet to 10.3.2.111:5004 (type 0, seq 54473, ts 298720, len 160) Sent RTP packet to 10.3.2.111:5004 (type 0, seq 54474, ts 298880, len 160) Sent RTP packet to 10.3.2.111:5004 (type 0, seq 54475, ts 299040, len 160) Sent RTP packet to 10.3.2.111:5004 (type 0, seq 54476, ts 299200, len 160) Sent RTP packet to 10.3.2.111:5004 (type 0, seq 54477, ts 299360, len 160) Sent RTP packet to 10.3.2.111:5004 (type 0, seq 54478, ts 299520, len 160) Sent RTP packet to 10.3.2.111:5004 (type 0, seq 54479, ts 299680, len 160) Sent RTP packet to 10.3.2.111:5004 (type 0, seq 54480, ts 299840, len 160) Sent RTP packet to 10.3.2.111:5004 (type 0, seq 54481, ts 300000, len 160) Sent RTP packet to 10.3.2.111:5004 (type 0, seq 54482, ts 300160, len 160) Sent RTP packet to 10.3.2.111:5004 (type 0, seq 54483, ts 300320, len 160) Sent RTP packet to 10.3.2.111:5004 (type 0, seq 54484, ts 300480, len 160) Sent RTP packet to 10.3.2.111:5004 (type 0, seq 54485, ts 300640, len 160) Sent RTP packet to 10.3.2.111:5004 (type 0, seq 54486, ts 300800, len 160) Sent RTP packet to 10.3.2.111:5004 (type 0, seq 54487, ts 300960, len 160) Sent RTP packet to 10.3.2.111:5004 (type 0, seq 54488, ts 301120, len 160) Sent RTP packet to 10.3.2.111:5004 (type 0, seq 54489, ts 301280, len 160) Sent RTP packet to 10.3.2.111:5004 (type 0, seq 54490, ts 301440, len 160) Sent RTP packet to 10.3.2.111:5004 (type 0, seq 54491, ts 301600, len 160) Sent RTP packet to 10.3.2.111:5004 (type 0, seq 54492, ts 301760, len 160) Sent RTP packet to 10.3.2.111:5004 (type 0, seq 54493, ts 301920, len 160) Sent RTP packet to 10.3.2.111:5004 (type 0, seq 54494, ts 302080, len 160) Sent RTP packet to 10.3.2.111:5004 (type 0, seq 54495, ts 302240, len 160) Sent RTP packet to 10.3.2.111:5004 (type 0, seq 54496, ts 302400, len 160) Sent RTP packet to 10.3.2.111:5004 (type 0, seq 54497, ts 302560, len 160) Sent RTP packet to 10.3.2.111:5004 (type 0, seq 54498, ts 302720, len 160) Sent RTP packet to 10.3.2.111:5004 (type 0, seq 54499, ts 302880, len 160) Sent RTP packet to 10.3.2.111:5004 (type 0, seq 54500, ts 303040, len 160) Sent RTP packet to 10.3.2.111:5004 (type 0, seq 54501, ts 303200, len 160) Sent RTP packet to 10.3.2.111:5004 (type 0, seq 54502, ts 303360, len 160) Sent RTP packet to 10.3.2.111:5004 (type 0, seq 54503, ts 303520, len 160) Sent RTP packet to 10.3.2.111:5004 (type 0, seq 54504, ts 303680, len 160) Sent RTP packet to 10.3.2.111:5004 (type 0, seq 54505, ts 303840, len 160) Sent RTP packet to 10.3.2.111:5004 (type 0, seq 54506, ts 304000, len 160) Sent RTP packet to 10.3.2.111:5004 (type 0, seq 54507, ts 304160, len 160) Sent RTP packet to 10.3.2.111:5004 (type 0, seq 54508, ts 304320, len 160) Sent RTP packet to 10.3.2.111:5004 (type 0, seq 54509, ts 304480, len 160) Sent RTP packet to 10.3.2.111:5004 (type 0, seq 54510, ts 304640, len 160) Sent RTP packet to 10.3.2.111:5004 (type 0, seq 54511, ts 304800, len 160) Sent RTP packet to 10.3.2.111:5004 (type 0, seq 54512, ts 304960, len 160) Sent RTP packet to 10.3.2.111:5004 (type 0, seq 54513, ts 305120, len 160) Sent RTP packet to 10.3.2.111:5004 (type 0, seq 54514, ts 305280, len 160) Sent RTP packet to 10.3.2.111:5004 (type 0, seq 54515, ts 305440, len 160) Sent RTP packet to 10.3.2.111:5004 (type 0, seq 54516, ts 305600, len 160) Sent RTP packet to 10.3.2.111:5004 (type 0, seq 54517, ts 305760, len 160) Sent RTP packet to 10.3.2.111:5004 (type 0, seq 54518, ts 305920, len 160) Sent RTP packet to 10.3.2.111:5004 (type 0, seq 54519, ts 306080, len 160) Sent RTP packet to 10.3.2.111:5004 (type 0, seq 54520, ts 306240, len 160) Sent RTP packet to 10.3.2.111:5004 (type 0, seq 54521, ts 306400, len 160) Sent RTP packet to 10.3.2.111:5004 (type 0, seq 54522, ts 306560, len 160) Sent RTP packet to 10.3.2.111:5004 (type 0, seq 54523, ts 306720, len 160) Sent RTP packet to 10.3.2.111:5004 (type 0, seq 54524, ts 306880, len 160) Sent RTP packet to 10.3.2.111:5004 (type 0, seq 54525, ts 307040, len 160) Sent RTP packet to 10.3.2.111:5004 (type 0, seq 54526, ts 307200, len 160) Sent RTP packet to 10.3.2.111:5004 (type 0, seq 54527, ts 307360, len 160) Sent RTP packet to 10.3.2.111:5004 (type 0, seq 54528, ts 307520, len 160) Sent RTP packet to 10.3.2.111:5004 (type 0, seq 54529, ts 307680, len 160) Sent RTP packet to 10.3.2.111:5004 (type 0, seq 54530, ts 307840, len 160) Sent RTP packet to 10.3.2.111:5004 (type 0, seq 54531, ts 308000, len 160) Sent RTP packet to 10.3.2.111:5004 (type 0, seq 54532, ts 308160, len 160) Sent RTP packet to 10.3.2.111:5004 (type 0, seq 54533, ts 308320, len 160) Sent RTP packet to 10.3.2.111:5004 (type 0, seq 54534, ts 308480, len 160) Sent RTP packet to 10.3.2.111:5004 (type 0, seq 54535, ts 308640, len 160) Sent RTP packet to 10.3.2.111:5004 (type 0, seq 54536, ts 308800, len 160) Sent RTP packet to 10.3.2.111:5004 (type 0, seq 54537, ts 308960, len 160) Sent RTP packet to 10.3.2.111:5004 (type 0, seq 54538, ts 309120, len 160) Sent RTP packet to 10.3.2.111:5004 (type 0, seq 54539, ts 309280, len 160) Sent RTP packet to 10.3.2.111:5004 (type 0, seq 54540, ts 309440, len 160) Sent RTP packet to 10.3.2.111:5004 (type 0, seq 54541, ts 309600, len 160) Sent RTP packet to 10.3.2.111:5004 (type 0, seq 54542, ts 309760, len 160) Sent RTP packet to 10.3.2.111:5004 (type 0, seq 54543, ts 309920, len 160) Sent RTP packet to 10.3.2.111:5004 (type 0, seq 54544, ts 310080, len 160) RTP Debugging Disabled *CLI> Jul 10 21:02:16 DEBUG[4558]: rtp.c:415 ast_rtcp_read: Got RTCP report of 96 bytes Jul 10 21:02:25 DEBUG[4558]: rtp.c:415 ast_rtcp_read: Got RTCP report of 96 bytes Jul 10 21:02:39 DEBUG[4558]: rtp.c:415 ast_rtcp_read: Got RTCP report of 96 bytes Jul 10 21:02:53 DEBUG[4558]: rtp.c:415 ast_rtcp_read: Got RTCP report of 96 bytes