View Source

The following samples show troubleshooting output.

Q931, ISDN, TEL, and SIP level info 2 debug turned on.

node-225-113# trace q931 l info

Trace level set to info 2.

node-225-113# trace isdn l info

Trace level set to info 2.

node-225-113# trace tel l info

Trace level set to info 2.

node-225-113# trace sip l info

Trace level set to info 2.

Call set up:

[ 484][20071120.140732.718:q931.i2] Q931Machine::ProcessRequestOrMessage: 0x1498E570 Processing message 'SETUP' in state user Null

[ 2d0][20071120.140732.718:isdn.i3] 1:2:1:1: ISDN: (0x7a32): ISDNChannel::DoSetupStuff: going to SHOUTwatch state csWAITING_FOR_ROUTE

[  e4][20071120.140732.718:tel.i2]  RouteRequest: Routing table entry order is: 1

[  e4][20071120.140732.718:tel.i2]  RouteRequest: Local termination call...

[  e4][20071120.140732.718:tel.i2]  RouteRequest: preferred  media is now [G.711mu-law/sil T.38Fax STU ]

[  e4][20071120.140732.718:tel.i2]  RouteRequest: Chose channel 1:15:1:1 for outgoing call

[  e4][20071120.140732.718:tel.i2]  RouteRequest: outbound channel: 1:15:1:1 selected

[ e4][20071120.140732.718:tel.i2] RouteRequest: CSN 0x7a32: Setting outbound Media DiffServ level to 0x00 and Signaling Diffserv to 0x00

[ 2cc][20071120.140732.718:isdn.i3] 1:2:1:1: ISDN: (0x7a32): ISDNChannel::route: going to SHOUTwatch state csPEER_SETUP

[  e4][20071120.140732.718:tel.i2]  RouteRequest: Routing done for local termination.

[ 2cc][20071120.140732.718:q931.i2] Q931Machine::ProcessRequestOrMessage: 0x1498E570 Processing request 'MoreInfoRequest' in state user Call Present

[ 2cc][20071120.140732.718:tel.i2] 1:15:1:1: SIP: (0x7a32): Channel::CheckAndHandleOverlap: Called Party Number not complete, entering overlap mode.

[ 484][20071120.140736.640:q931.i2] Q931Machine::ProcessRequestOrMessage: 0x1498E570 Processing message 'SETUP' in state user Overlap Receiving

[ 2d0][20071120.140736.718:tel.i2] 1:15:1:1: SIP: (0x7a32): Channel::HandleOutboundIDDExpiry: Outbound IDD Timer expired, completing call if in overlap mode.

[ 2d0][20071120.140736.718:sip.i2]  1:15:1:1: SIP: (0x7a32): SIPChannel::HandleSetup: Handling Setup

[ 2d0][20071120.140736.718:sip.i2]  1:15:1:1: SIP: (0x7a32): SIPChannel::HandleSetup: Translated calling number is 1234567890

[ 2d0][20071120.140736.718:tel.i2]  1:15:1:1: SIP: (0x7a32): Channel::SendNatFlagToSPM: nat flag: 0

[ 2d0][20071120.140736.718:sip.i2] 1:15:1:1: SIP: (0x7a32): SIPChannel::HandleSetup: Address [8638e172] is resolved immediately.

[ 2d0][20071120.140736.718:sip.i2]  1:15:1:1: SIP: (0x7a32): SIPChannel::HandleInitialDNS: Handling InitialDNS response

[ 2d0][20071120.140737.031:sip.i2]  1:15:1:1: SIP: (0x7a32): SIPChannel::HandleRingingResponse: Handling Ringing Response

[ 2d0][20071120.140737.046:sip.i2]  1:15:1:1: SIP: (0x7a32): SIPChannel::HandleOkOnPRACK: Handling Ok On PRACK

[ 2cc][20071120.140737.046:q931.i2] Q931Machine::ProcessRequestOrMessage: 0x1498E570 Processing request 'AlertingRequest' in state user Overlap Receiving

[ 2cc][20071120.140737.046:isdn.i4] 1:2:1:1: ISDN: (0x7a32): ISDNChannel::receiveMessage: Not playing Ringback because Service Tones are disabled in the profile.

[ 2d0][20071120.140737.234:sip.i2]  1:15:1:1: SIP: (0x7a32): SIPChannel::HandleOkOnInvite: Handling OkOnInvite

[ 2d0][20071120.140737.234:sip.i2] 1:15:1:1: SIP: (0x7a32): SIPChannel::HandleOkOnInvite: negotiated media is now [G.711mu-law/sil ] stream(0)

[ 2d0][20071120.140737.234:isdn.i3] 1:2:1:1: ISDN: (0x7a32): ISDNChannel::msgConnect: going to SHOUTwatch state csCONNECTED

[ 2d0][20071120.140737.234:q931.i2] Q931Machine::ProcessRequestOrMessage: 0x1498E570 Processing request 'SetupResponse' in state user Call Received

[ 2d0][20071120.140737.234:sip.i2] 1:15:1:1: SIP: (0x7a32): SIPChannel::HandleTxStreamStarted: Handling Tx Stream Started message

[ 2d0][20071120.140737.234:sip.i2] 1:15:1:1: SIP: (0x7a32): SIPChannel::HandleRxStreamStarted: Handling Rx Stream Started message

[ 484][20071120.140737.328:q931.i2] Q931Machine::ProcessRequestOrMessage: 0x1498E570 Processing message 'CONNECT_ACKNOWLEDGE' in state user Connect Request

[ 2cc][20071120.140737.328:isdn.i3] 1:2:1:1: ISDN: (0x7a32): ISDNChannel::SMSetupCompleteIndication: going to SHOUTwatch state csCONNECTED

Call teardown:

[ 484][20071120.140937.937:q931.i2] Q931Machine::ProcessRequestOrMessage: 0x1498E570 Processing message 'DISCONNECT' in state user Active

[ 2d0][20071120.140937.937:isdn.i3] 1:2:1:1: ISDN: (0x7a32): ISDNChannel::SMDisconnectIndication: going to SHOUTwatch state csRELEASING

[ 2d0][20071120.140937.937:isdn.i3] 1:2:1:1: ISDN: (0x7a32): ISDNChannel::doReleaseCall: going to SHOUTwatch state csRELEASING

[ 2d0][20071120.140937.937:q931.i2] Q931Machine::ProcessRequestOrMessage: 0x1498E570 Processing request 'ReleaseRequest' in state user Disconnect Indication

[ 2cc][20071120.140937.937:sip.i2] 1:15:1:1: SIP: (0x7a32): SIPChannel::ReleaseAll: Attempting to release and reset channel ... with cause code 0x10

[ 2cc][20071120.140937.937:sip.i2]  1:15:1:1: SIP: (0x7a32): SIPChannel::UnMuteAudio: UN-Muting 0x03027a32

[ 2d0][20071120.140937.953:sip.m0] CSipSession::SipMsgIn: Session is not open! -- CallID [31282-221a20071120140736718@10.56.225.113], ChannelID [OUTBOUND 1:15:1:1]

[ 2cc][20071120.140937.953:sip.i2] 1:15:1:1: SIP: (0x7a32): SIPChannel::HandleRxStreamStopped: Handling Rx Stream Stopped message

[ 2cc][20071120.140937.953:sip.i2] 1:15:1:1: SIP: (0x7a32): SIPChannel::ReleaseAll: Attempting to release and reset channel ... with cause code 0x10

[ 2d0][20071120.140937.953:sip.i2] 1:15:1:1: SIP: (0x7a32): SIPChannel::HandleTxStreamStopped: Handling Tx Stream Stopped message

[ 2d0][20071120.140937.953:sip.i2] 1:15:1:1: SIP: (0x7a32): SIPChannel::ReleaseAll: Attempting to release and reset channel ... with cause code 0x10

[ 484][20071120.140937.953:q931.i2] Q931Machine::ProcessRequestOrMessage: 0x1498E570 Processing message 'RELEASE_COMPLETE' in state user Release Request

[ 2cc][20071120.140937.953:isdn.i3] 1:2:1:1: ISDN: (0x7a32): ISDNChannel::ReleaseCallResources: Waiting on RB to free the DSP

[ 2cc][20071120.140937.953:isdn.i3] 1:2:1:1: ISDN: (0x7a32): ISDNChannel::ReleaseCallResources: going to SHOUTwatch state csRELEASING

[ 2cc][20071120.140937.953:isdn.i3] 1:2:1:1: ISDN: (0x7a32): ISDNChannel::ReleaseCallResources: Waiting on RB to free the DSP

[ 2cc][20071120.140937.953:isdn.i3] 1:2:1:1: ISDN: (0x7a32): ISDNChannel::ReleaseCallResources: going to SHOUTwatch state csRELEASING

[ 2cc][20071120.140937.953:isdn.i3] 1:2:1:1: ISDN: (0x7a32): ISDNChannel::ReleaseCallResources: Waiting on RB to free the DSP

[ 2cc][20071120.140937.953:isdn.i3] 1:2:1:1: ISDN: (0x7a32): ISDNChannel::ReleaseCallResources: going to SHOUTwatch state csRELEASING

[ 2cc][20071120.140937.953:isdn.i3] 1:2:1:1: ISDN: (No CSN): ISDNChannel::ReleaseCallResources: going to SHOUTwatch state csIDLE

[  6c][20071120.140937.968:tel.i4]  RecordCDR: CDR Logging not enabled. CDR has been discarded.

node-225-113# trace no

All possible tracing has been turned off.

All debug trace enabled at info level 2.

node-225-113# trace all l info

Trace level set to info 2.

Call set up:

[ 484][20071120.140732.718:q931.i2] Q931Machine::ProcessRequestOrMessage: 0x1498E570 Processing message 'SETUP' in state user Null

[ 2d0][20071120.140732.718:isdn.i3] 1:2:1:1: ISDN: (0x7a32): ISDNChannel::DoSetupStuff: going to SHOUTwatch state csWAITING_FOR_ROUTE

[  e4][20071120.140732.718:tel.i2]  RouteRequest: Routing table entry order is: 1

[  e4][20071120.140732.718:tel.i2]  RouteRequest: Local termination call...

[  e4][20071120.140732.718:tel.i2]  RouteRequest: preferred  media is now [G.711mu-law/sil T.38Fax STU ]

[  e4][20071120.140732.718:tel.i2]  RouteRequest: Chose channel 1:15:1:1 for outgoing call

[  e4][20071120.140732.718:tel.i2]  RouteRequest: outbound channel: 1:15:1:1 selected

[ e4][20071120.140732.718:tel.i2] RouteRequest: CSN 0x7a32: Setting outbound Media DiffServ level to 0x00 and Signaling Diffserv to 0x00

[ 2cc][20071120.140732.718:isdn.i3] 1:2:1:1: ISDN: (0x7a32): ISDNChannel::route: going to SHOUTwatch state csPEER_SETUP

[ 2cc][20071120.140732.718:tdm.i2]  1:2:1:1: ISDN: (0x7a32): Channel::TDMProcessTxSetup: negotiated media is now [] stream(0)

[  e4][20071120.140732.718:tel.i2]  RouteRequest: Routing done for local termination.

[ 2cc][20071120.140732.718:q931.i2] Q931Machine::ProcessRequestOrMessage: 0x1498E570 Processing request 'MoreInfoRequest' in state user Call Present

[ 2cc][20071120.140732.718:tel.i2] 1:15:1:1: SIP: (0x7a32): Channel::CheckAndHandleOverlap: Called Party Number not complete, entering overlap mode.

[ 484][20071120.140736.640:q931.i2] Q931Machine::ProcessRequestOrMessage: 0x1498E570 Processing message 'SETUP' in state user Overlap Receiving

[ 2d0][20071120.140736.718:tel.i2] 1:15:1:1: SIP: (0x7a32): Channel::HandleOutboundIDDExpiry: Outbound IDD Timer expired, completing call if in overlap mode.

[ 2d0][20071120.140736.718:sip.i2]  1:15:1:1: SIP: (0x7a32): SIPChannel::HandleSetup: Handling Setup

[ 2d0][20071120.140736.718:sip.i2]  1:15:1:1: SIP: (0x7a32): SIPChannel::HandleSetup: Translated calling number is 1234567890

[ 2d0][20071120.140736.718:tel.i2]  1:15:1:1: SIP: (0x7a32): Channel::SendNatFlagToSPM: nat flag: 0

[ 2d0][20071120.140736.718:sip.i2] 1:15:1:1: SIP: (0x7a32): SIPChannel::HandleSetup: Address [8638e172] is resolved immediately.

[ 2d0][20071120.140736.718:sip.i2]  1:15:1:1: SIP: (0x7a32): SIPChannel::HandleInitialDNS: Handling InitialDNS response

[ 2dc][20071120.140736.718:rb.i2]   BaseRB::RBConnect: RTP transport set local IP/port 10.56.225.113/0, remote IP/port 0.0.0.0/0

[ 2dc][20071120.140736.718:rb.i2]   BaseRB::RBConnect: 1:15:1:1 to 1:2:1:1 not connected

[ 2dc][20071120.140736.718:rb.i2] BaseRB::RBConnect: RTP transport set local IP/port 10.56.225.113/16384, remote IP/port 0.0.0.0/0

[ 54c][20071120.140736.718:ip.i2] Reliable_Connection::ConnectResult: Connect SUCCESSFUL Remote address = 10.56.225.114, Remote port = 5060, local port= 2124, Connection ID 0xffff873c

[ 2d0][20071120.140736.718:ip.i2] Reliable_Connection::OpenConnection: connecting to: 10.56.225.114 Remote port 5060 Local Address 0x00000000 and Local Port 2124 Connection ID = 0xffff873c

[ 54c][20071120.140736.718:ip.i2] Reliable_Connection::Transmit: Transmitting 1003 bytes from address 0x00000000:2124 to 10.56.225.114:5060 on connection 0xffff873c

[ 2dc][20071120.140736.718:rb.i2]   BaseRB::RBConnect: 1:15:1:1 from 1:2:1:1 not connected

[ 704][20071120.140736.734:stel.m0] ProcessRTPPacket: Destination unknown, dropping frame [CSN 0xfa32, payload G711_ulaw, 160 bytes]

[ 54c][20071120.140737.031:ip.i2] Reliable_Connection::Receive: Received packet on Conn ID = 0xffff873c Connected 1 Read returned: 642

[ 54c][20071120.140737.031:ip.i2] Reliable_Connection::Receive: Received packet on Conn ID = 0xffff873c Connected 1 Read returned: -1

[ 2d0][20071120.140737.031:sip.i2]  1:15:1:1: SIP: (0x7a32): SIPChannel::HandleRingingResponse: Handling Ringing Response

[ 54c][20071120.140737.031:ip.i2] Reliable_Connection::ConnectResult: Connect SUCCESSFUL Remote address = 10.56.225.114, Remote port = 5060, local port= 2125, Connection ID 0xffff873b

[ 2d0][20071120.140737.031:ip.i2] Reliable_Connection::OpenConnection: connecting to: 10.56.225.114 Remote port 5060 Local Address 0x00000000 and Local Port 2125 Connection ID = 0xffff873b

[ 54c][20071120.140737.031:ip.i2] Reliable_Connection::Transmit: Transmitting 722 bytes from address 0x00000000:2125 to 10.56.225.114:5060 on connection 0xffff873b

[ 2cc][20071120.140737.031:rb.i2]   FindRS: 1;2;128 found for hRef 0x5de, cap: 0xC660970000A01B

[ 2cc][20071120.140737.031:rb.i2]   Connect: Connecting 1:2:1:1 to 1;2;128

[ 2cc][20071120.140737.031:rs.i2]   [1:2:1:1](O)[0x7a32]EndPoint::ConnectTo: Connecting to 1;2;128

[ 2cc][20071120.140737.031:rs.i2]   [1;2;128-TDMRx](O)[P1:2:1:1 A]PIN_TDM_RX::Connect:Connecting succeeded

[ 2cc][20071120.140737.031:rb.i2]   Connect: Connecting 1;2;128 to 1:2:1:1

[ 2cc][20071120.140737.031:rs.i2]   [1:2:1:1](O)[0x7a32][PT=1;2;128]EndPoint::ConnectFrom: Connecting from 1;2;128

[ 2d0][20071120.140737.031:sys.i3] DispatchMessages: recipient mailbox vanished for sender 0x00000001, recip 0xffffffff, msg 0x000aff01 [MSG_THW_DSP_OPEN_DONE], data 0x00007a32

[ 2cc][20071120.140737.031:rs.i2]   [1:2:1:1-TDMRx](O)[P1;2;128 A]PIN_TDM_RX::Connect:Connecting succeeded

[ 54c][20071120.140737.046:ip.i2] Reliable_Connection::Receive: Received packet on Conn ID = 0xffff873b Connected 1 Read returned: 661

[ 54c][20071120.140737.046:ip.i2] Reliable_Connection::Receive: Received packet on Conn ID = 0xffff873b Connected 1 Read returned: -1

[ 2d0][20071120.140737.046:sip.i2]  1:15:1:1: SIP: (0x7a32): SIPChannel::HandleOkOnPRACK: Handling Ok On PRACK

[ 2cc][20071120.140737.046:q931.i2] Q931Machine::ProcessRequestOrMessage: 0x1498E570 Processing request 'AlertingRequest' in state user Overlap Receiving

[ 2cc][20071120.140737.046:isdn.i4] 1:2:1:1: ISDN: (0x7a32): ISDNChannel::receiveMessage: Not playing Ringback because Service Tones are disabled in the profile.

[ 54c][20071120.140737.234:ip.i2] Reliable_Connection::Receive: Received packet on Conn ID = 0xffff873c Connected 1 Read returned: 895

[ 54c][20071120.140737.234:ip.i2] Reliable_Connection::Receive: Received packet on Conn ID = 0xffff873c Connected 1 Read returned: -1

[ 2d0][20071120.140737.234:sip.i2]  1:15:1:1: SIP: (0x7a32): SIPChannel::HandleOkOnInvite: Handling OkOnInvite

[ 2d0][20071120.140737.234:sip.i2] 1:15:1:1: SIP: (0x7a32): SIPChannel::HandleOkOnInvite: negotiated media is now [G.711mu-law/sil ] stream(0)

[ 2d0][20071120.140737.234:rs.i2] [1:15:1:1](C)[0x7a32]RTPEndPoint::Reconnect: Changing destination from IP=0.0.0.0/Port=0 to IP=IP=10.56.225.114/Port=16384

[ 54c][20071120.140737.234:ip.i2] Reliable_Connection::Transmit: Transmitting 649 bytes from address 0x00000000:2124 to 10.56.225.114:5060 on connection 0xffff873c

[ 2d0][20071120.140737.234:vox.i2]  [1;2;128](O)[0x7a32][idle]baseDSP::Stop: Stopping DSP. Status = idle

[ 2d0][20071120.140737.234:isdn.i3] 1:2:1:1: ISDN: (0x7a32): ISDNChannel::msgConnect: going to SHOUTwatch state csCONNECTED

[ 2d0][20071120.140737.234:q931.i2] Q931Machine::ProcessRequestOrMessage: 0x1498E570 Processing request 'SetupResponse' in state user Call Received

[ 2dc][20071120.140737.234:rb.i2]   BaseRB::GetTDMNeighbor: DSP 1;2;128 found for 1:2:1:1

[ 2dc][20071120.140737.234:rb.i2]   Connect: Connecting 1:2:1:1 to 1;2;128

[ 2dc][20071120.140737.234:rs.i2]   [1:2:1:1](O)[0x7a32][PT=1;2;128][PR=1;2;128]EndPoint::ConnectTo: Connecting to 1;2;128

[ 2dc][20071120.140737.234:rs.i2]   [1:2:1:1-TDMTx](O)[P1;2;128 A]PIN_TDM_TX::Connect:Connecting Succeed

[ 2dc][20071120.140737.234:rs.i2]   [1;2;128-TDMRx](O)[P1:2:1:1 A]PIN_TDM_RX::Connect:Connecting succeeded

[ 2dc][20071120.140737.234:rb.i2]   Connect: Connecting 1;2;128 to 1:15:1:1

[ 2dc][20071120.140737.234:rs.i2]   [1:15:1:1](C)[0x7a32]EndPoint::ConnectFrom: Connecting from 1;2;128

[ 2d0][20071120.140737.234:sip.i2] 1:15:1:1: SIP: (0x7a32): SIPChannel::HandleTxStreamStarted: Handling Tx Stream Started message

[ 2dc][20071120.140737.234:rb.i2]   BaseRB::RBTraceConnections: Connection is 1:2:1:1=>1;2;128=>1:15:1:1

[ 2dc][20071120.140737.234:rb.i2]   TI_RB::RBMakeTdmPktConnect: Started tone detection on TI DSP 1;2;129

[ 2dc][20071120.140737.234:rb.i2]   BaseRB::RBConnect: 1:2:1:1 to 1:15:1:1  connected

[ 2dc][20071120.140737.234:rb.i2]   BaseRB::GetTDMNeighbor: DSP 1;2;128 found for 1:2:1:1

[ 2dc][20071120.140737.234:rb.i2]   Connect: Connecting 1:2:1:1 from 1;2;128

[ 2dc][20071120.140737.234:rs.i2]   [1;2;128-TDMTx](O)[P1:2:1:1 A]PIN_TDM_TX::Connect:Connecting Succeed

[ 2dc][20071120.140737.234:rs.i2]   [1:2:1:1](O)[0x7a32][PT=1;2;128][PR=1;2;128]EndPoint::ConnectFrom: Connecting from 1;2;128

[ 2dc][20071120.140737.234:rs.i2]   [1:2:1:1-TDMRx](O)[P1;2;128 A]PIN_TDM_RX::Connect:Connecting succeeded

[ 2dc][20071120.140737.234:rb.i2]   Connect: Connecting 1;2;128 from 1:15:1:1

[ 2dc][20071120.140737.234:rs.i2]   [1:15:1:1](O)[0x7a32][PR=1;2;128]EndPoint::ConnectTo: Connecting to 1;2;128

[ 2d0][20071120.140737.234:sip.i2] 1:15:1:1: SIP: (0x7a32): SIPChannel::HandleRxStreamStarted: Handling Rx Stream Started message

[ 2dc][20071120.140737.234:rb.i2]   BaseRB::RBTraceConnections: Connection is 1:2:1:1<=1;2;128<=1:15:1:1

[ 2dc][20071120.140737.234:rb.i2]   TI_RB::RBMakeTdmPktConnect: Started tone detection on TI DSP 1;2;129

[ 2dc][20071120.140737.234:rb.i2]   BaseRB::RBConnect: 1:2:1:1 from 1:15:1:1  connected

[ 4a0][20071120.140737.250:stu.i4]  1:2:129: start_tone_detection: Law: 1 DC Filter: Disabled

[ 4a0][20071120.140737.250:stu.i4]  1:2:129: start_tone_detection: Law: 1 DC Filter: Disabled

[ 484][20071120.140737.328:q931.i2] Q931Machine::ProcessRequestOrMessage: 0x1498E570 Processing message 'CONNECT_ACKNOWLEDGE' in state user Connect Request

[ 2cc][20071120.140737.328:isdn.i3] 1:2:1:1: ISDN: (0x7a32): ISDNChannel::SMSetupCompleteIndication: going to SHOUTwatch state csCONNECTED

[ 704][20071120.140737.390:stel.i2] ProcessRTPPacket: frame [CSN 0x7a32, payload Silence, 1 bytes]: Received RFC 3389 comfort noise frame, comfort noise payload now 13

Call teardown:

[ 484][20071120.140937.937:q931.i2] Q931Machine::ProcessRequestOrMessage: 0x1498E570 Processing message 'DISCONNECT' in state user Active

[ 2d0][20071120.140937.937:isdn.i3] 1:2:1:1: ISDN: (0x7a32): ISDNChannel::SMDisconnectIndication: going to SHOUTwatch state csRELEASING

[ 2d0][20071120.140937.937:isdn.i3] 1:2:1:1: ISDN: (0x7a32): ISDNChannel::doReleaseCall: going to SHOUTwatch state csRELEASING

[ 2d0][20071120.140937.937:q931.i2] Q931Machine::ProcessRequestOrMessage: 0x1498E570 Processing request 'ReleaseRequest' in state user Disconnect Indication

[ 2cc][20071120.140937.937:sip.i2] 1:15:1:1: SIP: (0x7a32): SIPChannel::ReleaseAll: Attempting to release and reset channel ... with cause code 0x10

[ 2cc][20071120.140937.937:sip.i2]  1:15:1:1: SIP: (0x7a32): SIPChannel::UnMuteAudio: UN-Muting 0x03027a32

[ 2cc][20071120.140937.937:rs.i2]   [1:15:1:1](O)[0x7a32][PT=1;2;128][PR=1;2;128]PKTEndPoint::UnMute: Unmuting in dir Tx

[ 54c][20071120.140937.937:ip.i2] Reliable_Connection::ConnectResult: Connect SUCCESSFUL Remote address = 10.56.225.114, Remote port = 5060, local port= 2137, Connection ID 0xffff873a

[ 2cc][20071120.140937.937:ip.i2] Reliable_Connection::OpenConnection: connecting to: 10.56.225.114 Remote port 5060 Local Address 0x00000000 and Local Port 2137 Connection ID = 0xffff873a

[ 54c][20071120.140937.937:ip.i2] Reliable_Connection::Transmit: Transmitting 536 bytes from address 0x00000000:2137 to 10.56.225.114:5060 on connection 0xffff873a

[ 2dc][20071120.140937.953:rb.i2]   Disconnect: Disconnecting 1:15:1:1 to 1;2;128

[ 54c][20071120.140937.953:ip.i2] Reliable_Connection::Receive: Received packet on Conn ID = 0xffff873a Connected 1 Read returned: 477

[ 54c][20071120.140937.953:ip.i2] Reliable_Connection::Receive: Received packet on Conn ID = 0xffff873a Connected 1 Read returned: -1

[ 2d0][20071120.140937.953:sip.m0] CSipSession::SipMsgIn: Session is not open! -- CallID [31282-221a20071120140736718@10.56.225.113], ChannelID [OUTBOUND 1:15:1:1]

[ 2cc][20071120.140937.953:sip.i2] 1:15:1:1: SIP: (0x7a32): SIPChannel::HandleRxStreamStopped: Handling Rx Stream Stopped message

[ 2cc][20071120.140937.953:sip.i2] 1:15:1:1: SIP: (0x7a32): SIPChannel::ReleaseAll: Attempting to release and reset channel ... with cause code 0x10

[ 2dc][20071120.140937.953:rb.i2]   Disconnect: Disconnecting 1:15:1:1 from 1;2;128

[ 2d0][20071120.140937.953:sip.i2] 1:15:1:1: SIP: (0x7a32): SIPChannel::HandleTxStreamStopped: Handling Tx Stream Stopped message

[ 2d0][20071120.140937.953:sip.i2] 1:15:1:1: SIP: (0x7a32): SIPChannel::ReleaseAll: Attempting to release and reset channel ... with cause code 0x10

[ 484][20071120.140937.953:q931.i2] Q931Machine::ProcessRequestOrMessage: 0x1498E570 Processing message 'RELEASE_COMPLETE' in state user Release Request

[ 2cc][20071120.140937.953:vox.i2]  [1;2;128](O)[0x7a32][idle]baseDSP::Stop: Stopping DSP. Status = idle

[ 2cc][20071120.140937.953:rb.i2]   Disconnect: Disconnecting 1:2:1:1 to 1;2;128

[ 2cc][20071120.140937.953:rs.i2]   [1:2:1:1](O)[0x7a32][PT=1;2;128][PR=1;2;128]TDMEndPoint::DisconnectTo: Target is 1;2;128

[ 2cc][20071120.140937.953:rs.i2]   [1:2:1:1-TDMTx](C)PIN_TDM_TX::Disconnect:Disconnect succeeded

[ 2cc][20071120.140937.953:rs.i2]   [1;2;128-TDMRx](C)PIN_TDM_RX::Disconnect:Disconnecting succeeded

[ 2cc][20071120.140937.953:rb.i2]   Disconnect: Disconnecting 1;2;128 to 1:2:1:1

[ 2cc][20071120.140937.953:rs.i2]   [1;2;128-TDMTx](C)PIN_TDM_TX::Disconnect:Disconnect succeeded

[ 2cc][20071120.140937.953:rs.i2]   [1:2:1:1-TDMRx](C)PIN_TDM_RX::Disconnect:Disconnecting succeeded

[ 2cc][20071120.140937.953:rb.i2]   Disconnect: Disconnecting 1:2:1:1 from 1;2;128

[ 2cc][20071120.140937.953:rb.i2]   Disconnect: Disconnecting 1;2;128 from 1:2:1:1

[ 2cc][20071120.140937.953:rs.i2]   [1:2:1:1](O)[0x7a32]TDMEndPoint::DisconnectTo: Target is 1;2;128

[ 2cc][20071120.140937.953:isdn.i3] 1:2:1:1: ISDN: (0x7a32): ISDNChannel::ReleaseCallResources: Waiting on RB to free the DSP

[ 2cc][20071120.140937.953:isdn.i3] 1:2:1:1: ISDN: (0x7a32): ISDNChannel::ReleaseCallResources: going to SHOUTwatch state csRELEASING

[ 2cc][20071120.140937.953:isdn.i3] 1:2:1:1: ISDN: (0x7a32): ISDNChannel::ReleaseCallResources: Waiting on RB to free the DSP

[ 2cc][20071120.140937.953:isdn.i3] 1:2:1:1: ISDN: (0x7a32): ISDNChannel::ReleaseCallResources: going to SHOUTwatch state csRELEASING

[ 4a0][20071120.140937.953:nc.i2]   1:2:NET_Card::ProcessEvent: Event: CONNECTION_BREAK_RESPONSE received with result: ERROR

[ 2cc][20071120.140937.953:isdn.i3] 1:2:1:1: ISDN: (0x7a32): ISDNChannel::ReleaseCallResources: Waiting on RB to free the DSP

[ 2cc][20071120.140937.953:isdn.i3] 1:2:1:1: ISDN: (0x7a32): ISDNChannel::ReleaseCallResources: going to SHOUTwatch state csRELEASING

[ 4a0][20071120.140937.953:nc.i2]   1:2:NET_Card::ProcessEvent: Event: CONNECTION_BREAK_RESPONSE received with result: ERROR

[ 2cc][20071120.140937.953:isdn.i3] 1:2:1:1: ISDN: (No CSN): ISDNChannel::ReleaseCallResources: going to SHOUTwatch state csIDLE

[  6c][20071120.140937.968:tel.i4]  RecordCDR: CDR Logging not enabled. CDR has been discarded.

node-225-113# trace no

All possible tracing has been turned off.