All pastes #412504 Raw Edit

Untitled

public text v1 · immutable
#412504 ·published 2007-03-27 18:57 UTC
rendered paste body
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: <sip:6989085@voice.wmis.net:5060>;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." <sip:6165346386@voice.w
mis.net:5060>;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: <sip:6989085@voice.wmis.net:5060>;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." <sip:6165346386@voice.w
mis.net:5060>;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: <sip:6989085@voice.wmis.net:5060>;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." <sip:6165346386@voice.w
mis.net:5060>;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: <sip:6989085@voice.wmis.net:5060>;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." <sip:6165346386@voice.w
mis.net:5060>;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: <sip:6989085@voice.wmis.net:5060>;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." <sip:6165346386@voice.w
mis.net:5060>;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: <sip:6989085@voice.wmis.net:5060>;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." <sip:6165346386@voice.w
mis.net:5060>;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: <sip:6989085@voice.wmis.net:5060>;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." <sip:6165346386@voice.w
mis.net:5060>;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'