Mar 27 10:37:59 asterisk asterisk[26614]: VERBOSE[26614]: -- Attempting native bridge of SIP/CPW-8be02000 and SIP/216.109.194.96-844e7000 Mar 27 10:37:59 asterisk asterisk[26614]: DEBUG[26614]: chan_sip.c:11844 in sip_devicestate: Checking device state for peer 216.109.194.96 Mar 27 10:37:59 asterisk asterisk[26614]: DEBUG[26614]: devicestate.c:187 in do_state_change: Changing state for SIP/216.109.194.96 - state 2 (In use) Mar 27 10:37:59 asterisk asterisk[26614]: DEBUG[26614]: chan_sip.c:11844 in sip_devicestate: Checking device state for peer CPW Mar 27 10:37:59 asterisk asterisk[26614]: DEBUG[26614]: devicestate.c:187 in do_state_change: Changing state for SIP/CPW - state 2 (In use) Mar 27 10:37:59 asterisk asterisk[26614]: DEBUG[26614]: app_queue.c:500 in changethread: Device 'SIP/216.109.194.96' changed to state '2' (In use) but we don' t care because they're not a member of any queue. Mar 27 10:37:59 asterisk asterisk[26614]: DEBUG[26614]: app_queue.c:500 in changethread: Device 'SIP/CPW' changed to state '2' (In use) but we don't care beca use they're not a member of any queue. Mar 27 10:37:59 asterisk asterisk[26614]: DEBUG[26614]: chan_sip.c:3442 in parse_request: Header 0: ACK sip:6989085@216.109.194.95 SIP/2.0 (38) Mar 27 10:37:59 asterisk asterisk[26614]: DEBUG[26614]: chan_sip.c:3442 in parse_request: Header 1: Via: SIP/2.0/UDP 216.109.205.115:5060;branch=z9hG4bK-92c47 -210b (63) Mar 27 10:37:59 asterisk asterisk[26614]: DEBUG[26614]: chan_sip.c:3442 in parse_request: Header 2: To: ;tag=as42802c3a (52) Mar 27 10:37:59 asterisk asterisk[26614]: DEBUG[26614]: chan_sip.c:3442 in parse_request: Header 3: From: "CPW Computer Systems, Inc." ;tag=10262-2c3e-2104 (91) Mar 27 10:37:59 asterisk asterisk[26614]: DEBUG[26614]: chan_sip.c:3442 in parse_request: Header 4: CSeq: 9784 ACK (14) Mar 27 10:37:59 asterisk asterisk[26614]: DEBUG[26614]: chan_sip.c:3442 in parse_request: Header 5: Call-ID: a11ce-10262-46092c3e-2105-216.109.205.115 (50) Mar 27 10:37:59 asterisk asterisk[26614]: DEBUG[26614]: chan_sip.c:3442 in parse_request: Header 6: Date: Tue, 27 Mar 2007 14:37:59 GMT (35) Mar 27 10:37:59 asterisk asterisk[26614]: DEBUG[26614]: chan_sip.c:3442 in parse_request: Header 7: Max-Forwards: 70 (16) Mar 27 10:37:59 asterisk asterisk[26614]: DEBUG[26614]: chan_sip.c:3442 in parse_request: Header 8: User-Agent: IST-OIS-6.5.1.3 (27) Mar 27 10:37:59 asterisk asterisk[26614]: DEBUG[26614]: chan_sip.c:3442 in parse_request: Header 9: Content-Length: 0 (17) Mar 27 10:37:59 asterisk asterisk[26614]: DEBUG[26614]: chan_sip.c:3442 in parse_request: Header 10: (0) Mar 27 10:37:59 asterisk asterisk[26614]: DEBUG[26614]: chan_sip.c:11296 in handle_request: **** Received ACK (6) - Command in SIP ACK Mar 27 10:37:59 asterisk asterisk[26614]: DEBUG[26614]: chan_sip.c:1195 in retrans_pkt: SIP TIMER: Rescheduling retransmission #4916242 (1) SIP/2.0 - 1 Mar 27 10:37:59 asterisk asterisk[26614]: DEBUG[26614]: chan_sip.c:1209 in retrans_pkt: ** SIP timers: Rescheduling retransmission 2 to 22 ms (t1 11 ms (Retra ns id #4916242)) Mar 27 10:37:59 asterisk asterisk[26614]: DEBUG[26614]: chan_sip.c:3442 in parse_request: Header 0: ACK sip:6989085@216.109.194.95 SIP/2.0 (38) Mar 27 10:37:59 asterisk asterisk[26614]: DEBUG[26614]: chan_sip.c:3442 in parse_request: Header 1: Via: SIP/2.0/UDP 216.109.205.115:5060;branch=z9hG4bK-92c47 -210b (63) Mar 27 10:37:59 asterisk asterisk[26614]: DEBUG[26614]: chan_sip.c:3442 in parse_request: Header 2: To: ;tag=as42802c3a (52) Mar 27 10:37:59 asterisk asterisk[26614]: DEBUG[26614]: chan_sip.c:3442 in parse_request: Header 3: From: "CPW Computer Systems, Inc." ;tag=10262-2c3e-2104 (91) Mar 27 10:37:59 asterisk asterisk[26614]: DEBUG[26614]: chan_sip.c:3442 in parse_request: Header 4: CSeq: 9784 ACK (14) Mar 27 10:37:59 asterisk asterisk[26614]: DEBUG[26614]: chan_sip.c:3442 in parse_request: Header 5: Call-ID: a11ce-10262-46092c3e-2105-216.109.205.115 (50) Mar 27 10:37:59 asterisk asterisk[26614]: DEBUG[26614]: chan_sip.c:3442 in parse_request: Header 6: Date: Tue, 27 Mar 2007 14:37:59 GMT (35) Mar 27 10:37:59 asterisk asterisk[26614]: DEBUG[26614]: chan_sip.c:3442 in parse_request: Header 7: Max-Forwards: 70 (16) Mar 27 10:37:59 asterisk asterisk[26614]: DEBUG[26614]: chan_sip.c:3442 in parse_request: Header 8: User-Agent: IST-OIS-6.5.1.3 (27) Mar 27 10:37:59 asterisk asterisk[26614]: DEBUG[26614]: chan_sip.c:3442 in parse_request: Header 9: Content-Length: 0 (17) Mar 27 10:37:59 asterisk asterisk[26614]: DEBUG[26614]: chan_sip.c:3442 in parse_request: Header 10: (0) Mar 27 10:37:59 asterisk asterisk[26614]: DEBUG[26614]: chan_sip.c:11296 in handle_request: **** Received ACK (6) - Command in SIP ACK Mar 27 10:37:59 asterisk asterisk[26614]: DEBUG[26614]: rtp.c:411 in ast_rtcp_read: Got RTCP report of 136 bytes Mar 27 10:37:59 asterisk asterisk[26614]: DEBUG[26614]: chan_sip.c:1195 in retrans_pkt: SIP TIMER: Rescheduling retransmission #4916242 (2) SIP/2.0 - 1 Mar 27 10:37:59 asterisk asterisk[26614]: DEBUG[26614]: chan_sip.c:1209 in retrans_pkt: ** SIP timers: Rescheduling retransmission 3 to 44 ms (t1 11 ms (Retra ns id #4916242)) Mar 27 10:37:59 asterisk asterisk[26614]: DEBUG[26614]: chan_sip.c:3442 in parse_request: Header 0: ACK sip:6989085@216.109.194.95 SIP/2.0 (38) Mar 27 10:37:59 asterisk asterisk[26614]: DEBUG[26614]: chan_sip.c:3442 in parse_request: Header 1: Via: SIP/2.0/UDP 216.109.205.115:5060;branch=z9hG4bK-92c47 -210b (63) Mar 27 10:37:59 asterisk asterisk[26614]: DEBUG[26614]: chan_sip.c:3442 in parse_request: Header 2: To: ;tag=as42802c3a (52) Mar 27 10:37:59 asterisk asterisk[26614]: DEBUG[26614]: chan_sip.c:3442 in parse_request: Header 3: From: "CPW Computer Systems, Inc." ;tag=10262-2c3e-2104 (91) Mar 27 10:37:59 asterisk asterisk[26614]: DEBUG[26614]: chan_sip.c:3442 in parse_request: Header 4: CSeq: 9784 ACK (14) Mar 27 10:37:59 asterisk asterisk[26614]: DEBUG[26614]: chan_sip.c:3442 in parse_request: Header 5: Call-ID: a11ce-10262-46092c3e-2105-216.109.205.115 (50) Mar 27 10:37:59 asterisk asterisk[26614]: DEBUG[26614]: chan_sip.c:3442 in parse_request: Header 6: Date: Tue, 27 Mar 2007 14:37:59 GMT (35) Mar 27 10:37:59 asterisk asterisk[26614]: DEBUG[26614]: chan_sip.c:3442 in parse_request: Header 7: Max-Forwards: 70 (16) Mar 27 10:37:59 asterisk asterisk[26614]: DEBUG[26614]: chan_sip.c:3442 in parse_request: Header 8: User-Agent: IST-OIS-6.5.1.3 (27) Mar 27 10:37:59 asterisk asterisk[26614]: DEBUG[26614]: chan_sip.c:3442 in parse_request: Header 9: Content-Length: 0 (17) Mar 27 10:37:59 asterisk asterisk[26614]: DEBUG[26614]: chan_sip.c:3442 in parse_request: Header 10: (0) Mar 27 10:37:59 asterisk asterisk[26614]: DEBUG[26614]: chan_sip.c:11296 in handle_request: **** Received ACK (6) - Command in SIP ACK Mar 27 10:37:59 asterisk asterisk[26614]: DEBUG[26614]: chan_sip.c:1195 in retrans_pkt: SIP TIMER: Rescheduling retransmission #4916242 (3) SIP/2.0 - 1 Mar 27 10:37:59 asterisk asterisk[26614]: DEBUG[26614]: chan_sip.c:1209 in retrans_pkt: ** SIP timers: Rescheduling retransmission 4 to 88 ms (t1 11 ms (Retra ns id #4916242)) Mar 27 10:37:59 asterisk asterisk[26614]: DEBUG[26614]: chan_sip.c:3442 in parse_request: Header 0: ACK sip:6989085@216.109.194.95 SIP/2.0 (38) Mar 27 10:37:59 asterisk asterisk[26614]: DEBUG[26614]: chan_sip.c:3442 in parse_request: Header 1: Via: SIP/2.0/UDP 216.109.205.115:5060;branch=z9hG4bK-92c47 -210b (63) Mar 27 10:37:59 asterisk asterisk[26614]: DEBUG[26614]: chan_sip.c:3442 in parse_request: Header 2: To: ;tag=as42802c3a (52) Mar 27 10:37:59 asterisk asterisk[26614]: DEBUG[26614]: chan_sip.c:3442 in parse_request: Header 3: From: "CPW Computer Systems, Inc." ;tag=10262-2c3e-2104 (91) Mar 27 10:37:59 asterisk asterisk[26614]: DEBUG[26614]: chan_sip.c:3442 in parse_request: Header 4: CSeq: 9784 ACK (14) Mar 27 10:37:59 asterisk asterisk[26614]: DEBUG[26614]: chan_sip.c:3442 in parse_request: Header 5: Call-ID: a11ce-10262-46092c3e-2105-216.109.205.115 (50) Mar 27 10:37:59 asterisk asterisk[26614]: DEBUG[26614]: chan_sip.c:3442 in parse_request: Header 6: Date: Tue, 27 Mar 2007 14:37:59 GMT (35) Mar 27 10:37:59 asterisk asterisk[26614]: DEBUG[26614]: chan_sip.c:3442 in parse_request: Header 7: Max-Forwards: 70 (16) Mar 27 10:37:59 asterisk asterisk[26614]: DEBUG[26614]: chan_sip.c:3442 in parse_request: Header 8: User-Agent: IST-OIS-6.5.1.3 (27) Mar 27 10:37:59 asterisk asterisk[26614]: DEBUG[26614]: chan_sip.c:3442 in parse_request: Header 9: Content-Length: 0 (17) Mar 27 10:37:59 asterisk asterisk[26614]: DEBUG[26614]: chan_sip.c:3442 in parse_request: Header 10: (0) Mar 27 10:37:59 asterisk asterisk[26614]: DEBUG[26614]: chan_sip.c:11296 in handle_request: **** Received ACK (6) - Command in SIP ACK Mar 27 10:37:59 asterisk asterisk[26614]: DEBUG[26614]: chan_sip.c:1195 in retrans_pkt: SIP TIMER: Rescheduling retransmission #4916242 (4) SIP/2.0 - 1 Mar 27 10:37:59 asterisk asterisk[26614]: DEBUG[26614]: chan_sip.c:1209 in retrans_pkt: ** SIP timers: Rescheduling retransmission 5 to 176 ms (t1 11 ms (Retr ans id #4916242)) Mar 27 10:37:59 asterisk asterisk[26614]: DEBUG[26614]: chan_sip.c:3442 in parse_request: Header 0: ACK sip:6989085@216.109.194.95 SIP/2.0 (38) Mar 27 10:37:59 asterisk asterisk[26614]: DEBUG[26614]: chan_sip.c:3442 in parse_request: Header 1: Via: SIP/2.0/UDP 216.109.205.115:5060;branch=z9hG4bK-92c47 -210b (63) Mar 27 10:37:59 asterisk asterisk[26614]: DEBUG[26614]: chan_sip.c:3442 in parse_request: Header 2: To: ;tag=as42802c3a (52) Mar 27 10:37:59 asterisk asterisk[26614]: DEBUG[26614]: chan_sip.c:3442 in parse_request: Header 3: From: "CPW Computer Systems, Inc." ;tag=10262-2c3e-2104 (91) Mar 27 10:37:59 asterisk asterisk[26614]: DEBUG[26614]: chan_sip.c:3442 in parse_request: Header 4: CSeq: 9784 ACK (14) Mar 27 10:37:59 asterisk asterisk[26614]: DEBUG[26614]: chan_sip.c:3442 in parse_request: Header 5: Call-ID: a11ce-10262-46092c3e-2105-216.109.205.115 (50) Mar 27 10:37:59 asterisk asterisk[26614]: DEBUG[26614]: chan_sip.c:3442 in parse_request: Header 6: Date: Tue, 27 Mar 2007 14:37:59 GMT (35) Mar 27 10:37:59 asterisk asterisk[26614]: DEBUG[26614]: chan_sip.c:3442 in parse_request: Header 7: Max-Forwards: 70 (16) Mar 27 10:37:59 asterisk asterisk[26614]: DEBUG[26614]: chan_sip.c:3442 in parse_request: Header 8: User-Agent: IST-OIS-6.5.1.3 (27) Mar 27 10:37:59 asterisk asterisk[26614]: DEBUG[26614]: chan_sip.c:3442 in parse_request: Header 9: Content-Length: 0 (17) Mar 27 10:37:59 asterisk asterisk[26614]: DEBUG[26614]: chan_sip.c:3442 in parse_request: Header 10: (0) Mar 27 10:37:59 asterisk asterisk[26614]: DEBUG[26614]: chan_sip.c:11296 in handle_request: **** Received ACK (6) - Command in SIP ACK Mar 27 10:37:59 asterisk asterisk[26614]: DEBUG[26614]: chan_sip.c:1195 in retrans_pkt: SIP TIMER: Rescheduling retransmission #4916242 (5) SIP/2.0 - 1 Mar 27 10:37:59 asterisk asterisk[26614]: DEBUG[26614]: chan_sip.c:1209 in retrans_pkt: ** SIP timers: Rescheduling retransmission 6 to 352 ms (t1 11 ms (Retr ans id #4916242)) Mar 27 10:37:59 asterisk asterisk[26614]: DEBUG[26614]: chan_sip.c:3442 in parse_request: Header 0: ACK sip:6989085@216.109.194.95 SIP/2.0 (38) Mar 27 10:37:59 asterisk asterisk[26614]: DEBUG[26614]: chan_sip.c:3442 in parse_request: Header 1: Via: SIP/2.0/UDP 216.109.205.115:5060;branch=z9hG4bK-92c47 -210b (63) Mar 27 10:37:59 asterisk asterisk[26614]: DEBUG[26614]: chan_sip.c:3442 in parse_request: Header 2: To: ;tag=as42802c3a (52) Mar 27 10:37:59 asterisk asterisk[26614]: DEBUG[26614]: chan_sip.c:3442 in parse_request: Header 3: From: "CPW Computer Systems, Inc." ;tag=10262-2c3e-2104 (91) Mar 27 10:37:59 asterisk asterisk[26614]: DEBUG[26614]: chan_sip.c:3442 in parse_request: Header 4: CSeq: 9784 ACK (14) Mar 27 10:37:59 asterisk asterisk[26614]: DEBUG[26614]: chan_sip.c:3442 in parse_request: Header 5: Call-ID: a11ce-10262-46092c3e-2105-216.109.205.115 (50) Mar 27 10:37:59 asterisk asterisk[26614]: DEBUG[26614]: chan_sip.c:3442 in parse_request: Header 6: Date: Tue, 27 Mar 2007 14:37:59 GMT (35) Mar 27 10:37:59 asterisk asterisk[26614]: DEBUG[26614]: chan_sip.c:3442 in parse_request: Header 7: Max-Forwards: 70 (16) Mar 27 10:37:59 asterisk asterisk[26614]: DEBUG[26614]: chan_sip.c:3442 in parse_request: Header 8: User-Agent: IST-OIS-6.5.1.3 (27) Mar 27 10:37:59 asterisk asterisk[26614]: DEBUG[26614]: chan_sip.c:3442 in parse_request: Header 9: Content-Length: 0 (17) Mar 27 10:37:59 asterisk asterisk[26614]: DEBUG[26614]: chan_sip.c:3442 in parse_request: Header 10: (0) Mar 27 10:37:59 asterisk asterisk[26614]: DEBUG[26614]: chan_sip.c:11296 in handle_request: **** Received ACK (6) - Command in SIP ACK Mar 27 10:37:59 asterisk asterisk[26614]: DEBUG[26614]: rtp.c:411 in ast_rtcp_read: Got RTCP report of 136 bytes Mar 27 10:38:00 asterisk asterisk[26614]: DEBUG[26614]: chan_sip.c:1195 in retrans_pkt: SIP TIMER: Rescheduling retransmission #4916242 (6) SIP/2.0 - 1 Mar 27 10:38:00 asterisk asterisk[26614]: DEBUG[26614]: chan_sip.c:1209 in retrans_pkt: ** SIP timers: Rescheduling retransmission 7 to 704 ms (t1 11 ms (Retr ans id #4916242)) Mar 27 10:38:00 asterisk asterisk[26614]: DEBUG[26614]: chan_sip.c:3442 in parse_request: Header 0: ACK sip:6989085@216.109.194.95 SIP/2.0 (38) Mar 27 10:38:00 asterisk asterisk[26614]: DEBUG[26614]: chan_sip.c:3442 in parse_request: Header 1: Via: SIP/2.0/UDP 216.109.205.115:5060;branch=z9hG4bK-92c47 -210b (63) Mar 27 10:38:00 asterisk asterisk[26614]: DEBUG[26614]: chan_sip.c:3442 in parse_request: Header 2: To: ;tag=as42802c3a (52) Mar 27 10:38:00 asterisk asterisk[26614]: DEBUG[26614]: chan_sip.c:3442 in parse_request: Header 3: From: "CPW Computer Systems, Inc." ;tag=10262-2c3e-2104 (91) Mar 27 10:38:00 asterisk asterisk[26614]: DEBUG[26614]: chan_sip.c:3442 in parse_request: Header 4: CSeq: 9784 ACK (14) Mar 27 10:38:00 asterisk asterisk[26614]: DEBUG[26614]: chan_sip.c:3442 in parse_request: Header 5: Call-ID: a11ce-10262-46092c3e-2105-216.109.205.115 (50) Mar 27 10:38:00 asterisk asterisk[26614]: DEBUG[26614]: chan_sip.c:3442 in parse_request: Header 6: Date: Tue, 27 Mar 2007 14:37:59 GMT (35) Mar 27 10:38:00 asterisk asterisk[26614]: DEBUG[26614]: chan_sip.c:3442 in parse_request: Header 7: Max-Forwards: 70 (16) Mar 27 10:38:00 asterisk asterisk[26614]: DEBUG[26614]: chan_sip.c:3442 in parse_request: Header 8: User-Agent: IST-OIS-6.5.1.3 (27) Mar 27 10:38:00 asterisk asterisk[26614]: DEBUG[26614]: chan_sip.c:3442 in parse_request: Header 9: Content-Length: 0 (17) Mar 27 10:38:00 asterisk asterisk[26614]: DEBUG[26614]: chan_sip.c:3442 in parse_request: Header 10: (0) Mar 27 10:38:00 asterisk asterisk[26614]: DEBUG[26614]: chan_sip.c:11296 in handle_request: **** Received ACK (6) - Command in SIP ACK Mar 27 10:38:00 asterisk asterisk[26614]: DEBUG[26614]: rtp.c:411 in ast_rtcp_read: Got RTCP report of 136 bytes Mar 27 10:38:00 asterisk asterisk[26614]: DEBUG[26614]: rtp.c:411 in ast_rtcp_read: Got RTCP report of 136 bytes Mar 27 10:38:00 asterisk asterisk[26614]: WARNING[26614]: chan_sip.c:1228 in retrans_pkt: Maximum retries exceeded on transmission a11ce-10262-46092c3e-2105-2 16.109.205.115 for seqno 9784 (Critical Response) Mar 27 10:38:00 asterisk asterisk[26614]: WARNING[26614]: chan_sip.c:1245 in retrans_pkt: Hanging up call a11ce-10262-46092c3e-2105-216.109.205.115 - no reply to our critical packet. Mar 27 10:38:00 asterisk asterisk[26614]: DEBUG[26614]: channel.c:3386 in ast_generic_bridge: Didn't get a frame from channel: SIP/CPW-8be02000 Mar 27 10:38:00 asterisk asterisk[26614]: DEBUG[26614]: channel.c:3675 in ast_channel_bridge: Bridge stops bridging channels SIP/CPW-8be02000 and SIP/216.109. 194.96-844e7000 Mar 27 10:38:00 asterisk asterisk[26614]: DEBUG[26614]: channel.c:1371 in ast_hangup: Hanging up channel 'SIP/216.109.194.96-844e7000' Mar 27 10:38:00 asterisk asterisk[26614]: DEBUG[26614]: chan_sip.c:2440 in sip_hangup: Hangup call SIP/216.109.194.96-844e7000, SIP callid 44076e60790b6f194c0 e1bde1a33b1bf@216.109.194.95) Mar 27 10:38:00 asterisk asterisk[26614]: DEBUG[26614]: chan_sip.c:2448 in sip_hangup: update_call_counter(16166989085) - decrement call limit counter Mar 27 10:38:00 asterisk asterisk[26614]: DEBUG[26614]: chan_sip.c:2228 in update_call_counter: Updating call counter for outgoing call Mar 27 10:38:00 asterisk asterisk[26614]: DEBUG[26614]: chan_sip.c:1307 in __sip_reliable_xmit: *** SIP TIMER: Initalizing retransmit timer on packet: Id #49 16244 Mar 27 10:38:00 asterisk asterisk[26614]: DEBUG[26614]: app_dial.c:1644 in dial_exec_full: Exiting with DIALSTATUS=ANSWER. Mar 27 10:38:00 asterisk asterisk[26614]: DEBUG[26614]: pbx.c:2336 in __ast_pbx_run: Spawn extension (outbound,16166989085,50) exited non-zero on 'SIP/CPW-8be 02000' Mar 27 10:38:00 asterisk asterisk[26614]: VERBOSE[26614]: == Spawn extension (outbound, 16166989085, 50) exited non-zero on 'SIP/CPW-8be02000'