Skip to end of metadata
Go to start of metadata

This section contains information about the commands that can be used with the CLI to trace ISDN, SIP and Telephony information.

Command Line Interface Access

Access the Command Line Interface from the Windows Start > Programs > Command Prompt and entering the command: telnet <VX node IP address>.

You can also use a telnet tool such as Microsoft HyperTerminal, PuTTY or SecureCRT.

Connecting to the VXNode

Debug Logs

 

By default, debug logging is enabled on the VX node as shown below. For further information, see the topics under Logging Information in the VXbuilder Installation and Configuration Gude.

Debug Logging

Debug Logging

 

Debug logs are stored locally in the VX node which can either be obtained by using any FTP utility, or by accessing the VX node remotely. The following example explains how to view and obtain the debug log from a VX node using the FileZilla freeware FTP utility.

Accessing Debug Logs

Sniffing

 

VX sniffing provides VX network protocol analysis files in *.pcap format for troubleshooting and analysis. Sniffing can be enabled / disabled in the Command Line Interface. A simple use case for sniffing is:

  • Enable sniffing in CLI
  • Run a test case
  • Disable sniffing in CLI

Sniff files are saved in the Sniff directory of the VX node which can be collected in the same way as the debug logs via FTP. They can be viewed by using network protocol analyzers like Ethereal or Wireshark.

Controlling Sniffing

Tracing

VX tracing has various numbers of tokens that can be run in the CLI. The list of available tokens can be viewed by running the command trace ? in enable mode in CLI. Commonly used tokens are listed below. Once the tracing is enabled and the particular test case is run, tracing can be disabled on CLI by running the command trace no.

Commonly Used Tokens

Token

Purpose

List Items

Trace isdn level info

ISDN (TDM Side) Troubleshooting: This command provide ISDN channel related information as they come to and from the VX

  • Waiting for route
  • Peer Setup
  • Connected
  • Releasing
  • Channel idle

Trace q931 level info

This command provide Q931 state machine related information as they come to and from the VX

  • Setup
  • Alerting
  • Setup Response
  • Connect Acknowledgement
  • Disconnect
  • Release
  • Release Complete

Trace sip level info

SIP (IP side) Troubleshooting: This debug command allows for troubleshooting SIP related issue by outputting all SIP related messages as they come to and from the VX.

  • INVITE
  • Ok / Answer
  • Ringing / Call progress
  • Tx/Rx stream start
  • Release/reset channel
  • Tx/Rx stream stop

Trace tel level info

Telephony Troubleshooting: This debug command allows for troubleshooting of general call route request related issues.

  • Translating number
  • Route table used
  • Outbound channel type and number
  • Preferred media i.e. G.711, T.38 Fax
  • Call serial number information
  • Tone detection
  • NAT flag
  • CDR recording

Troubleshooting Output Examples

 

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.