Explanation of T3 traces

[wallacezammit]

Hi,

I would like to know from where I can get detailed explanation of the t3 tracing so I can follow exactly what the values mean.

My problem is I am experiencing intermitent dropped calls between OXE and OXO connected with H323 trunk. You would be speaking and suddenly the line drops. But this doesn't happen always. It happens only a few times. SO I am running a t3 trace on the voip card of the oxe to check what is happening but cannot find any wrong content.

What I can notice is that if I release a call from oxo side the t3 states TEMPORARY FAILURE and when I release a call from oxe side it says NORMAL CALL CLEARING. I don't know if this is normal or if it indicates something wrong.

Below are 2 calls - first one released normally from oxe side - second one released from oxo side.

Apart from these type of traces I am not finding anything else, even when I would know that there have been dropped calls.

So if I can have a detailed explanation of t3 tracing it would help me understand more what is really going on.

Thanks and Regards
Wallace

t3 C=0 c=16
--> Cleaning mtracer...
--> Positionning t3 filters...
+--------+-------+--------+--------+---------+---------+----------+------+
| filter | desti | src_id | cr_nbr | cpl_nbr | us_term | term_nbr | type |
+--------+-------+--------+--------+---------+---------+----------+------+
| 0 | ** | ** | 00 | 16 | * | *** | 165 |
| 1 | ** | ** | 00 | 16 | * | *** | 166 |
| 2 | ** | ** | 00 | 16 | * | *** | 167 |
| 3 | | | | | | | |
| 4 | | | | | | | |
| 5 | | | | | | | |
| 6 | | | | | | | |
| 7 | | | | | | | |
+--------+-------+--------+--------+---------+---------+----------+------+
Traces Analyser activated

mtracer started ...
(601991:000001) MTRACER host (192.168.60.100, Farsons_m), version: R7.1-f5.401-36-d-mt-c6s2
(601991:000001) MTRACER num: 011, time: 2012/03/09 16:09:43, loss: 0%
______________________________________________________________________________
| (602407:000002) 1253: Send_IO1 (link-nbr=16, sapi=0, tei=0) :
| long: 117 desti: 0 source: 15 cryst: 0 cpl: 16 us: 8 term: 0 type a5
| tei: 0 <<<< message sent : SETUP [05] Call ref : 36 c2
|______________________________________________________________________________
|
| [9f] Non-locking shift. codeset : 7
| [a2] EI_IP_TYPE_TERM
| [9f] Non-locking shift. codeset : 7
| IE:[05] EI_IP_INFOS (l=9) -> G723 Ece 1 Vad 0 Profil 1, IPv4 :172.16.162.100.
| [9f] Non-locking shift. codeset : 7
| IE:[06] EI_IP_PAYLOADS (l=1) -> G729 Ece 1 Vad 0
| [9f] Non-locking shift. codeset : 7
| IE:[07] EI_IP_QOS (l=3) b8 e0 00
| [9f] Non-locking shift. codeset : 7
| IE:[0a] EI_RTP_INFO (l=30)
| -> stop_packet=0 stop_rtp=0 h323=0 wc=0 rf=0 udp=1 rqm=0
| -> Transm_Bande=1 detection_Q23=1 dtmf_payload=96
| -> Port RTP = 32136, IPv4 : 192. 168. 60. 101.
| -> Port RTCP SR = 32137, IPv4 : 192. 168. 60. 101.
| -> Port RTCP RR = 32137, IPv4 : 192. 168. 60. 101.
| -> Port Fax = 32139, IPv4 : 192. 168. 60. 101.
| IE:[04] BEARER_CAPABILITY (l=3) 80 90 a3
| IE:[18] CHANNEL (l=3) a8 83 81 -> T2 : No B channel
| IE:[1e] PROGRESS_ID (l=2) 80 83
| IE:[28] DISPLAY (l=15) `YEOMANS BERNICE`
| IE:[6c] CALLING_NUMBER (l=5) -> 01 81 Num : 384
| IE:[7d] HLC (l=2) 91 81
|______________________________________________________________________________

______________________________________________________________________________
| (602407:000005) Physical-Event :
| long: 21 desti: 0 source: 0 cryst: 0 cpl: 16 us: 0 term: 0 type a5
| tei: 0 >>>> message received : SETUP ACK [0d] Call ref : b6 c2
|______________________________________________________________________________
|
| IE:[18] CHANNEL (l=1) a8 -> T2 : No B channel
|______________________________________________________________________________

______________________________________________________________________________
| (602407:000006) 1253: Send_IO1 (link-nbr=16, sapi=0, tei=0) :
| long: 26 desti: 0 source: 15 cryst: 0 cpl: 16 us: 8 term: 0 type a5
| tei: 0 <<<< message sent : INFORMATION [7b] Call ref : 36 c2
|______________________________________________________________________________
|
| IE:[70] CALLED_NUMBER (l=5) -> 81 Num : 4729
| [a1] Sending complete
|______________________________________________________________________________

______________________________________________________________________________
| (602408:000007) Physical-Event :
| long: 21 desti: 0 source: 0 cryst: 0 cpl: 16 us: 0 term: 0 type a5
| tei: 0 >>>> message received : CALL PROC (02) Call ref : b6 c2
|______________________________________________________________________________
|
| IE:[18] CHANNEL (l=1) a8 -> T2 : No B channel
|______________________________________________________________________________

______________________________________________________________________________
| (602411:000008) Physical-Event :
| long: 23 desti: 0 source: 0 cryst: 0 cpl: 16 us: 0 term: 0 type a5
| tei: 0 >>>> message received : ALERT (01) Call ref : b6 c2
|______________________________________________________________________________
|
| [97] Locking shift. codeset : 7
| IE:[0a] EI_RTP_INFO (l=2)
| -> stop_packet=0 stop_rtp=0 h323=1 wc=0 rf=0 udp=0 rqm=0
| -> Transm_Bande=0 detection_Q23=1
|______________________________________________________________________________

______________________________________________________________________________
| (602424:000009) 1253: Send_IO1 (link-nbr=16, sapi=0, tei=0) :
| long: 22 desti: 0 source: 15 cryst: 0 cpl: 16 us: 8 term: 0 type a5
| tei: 0 <<<< message sent : DISCONNECT [45] Call ref : 36 c2
|______________________________________________________________________________
|
| IE:[08] CAUSE (l=2) 81 90 -> [90] NORMAL CALL CLEARING
|______________________________________________________________________________

______________________________________________________________________________
| (602424:000010) Physical-Event :
| long: 22 desti: 0 source: 0 cryst: 0 cpl: 16 us: 0 term: 0 type a5
| tei: 0 >>>> message received : RELEASE [4d] Call ref : b6 c2
|______________________________________________________________________________
|
| IE:[08] CAUSE (l=2) 81 90 -> [90] NORMAL CALL CLEARING
|______________________________________________________________________________

______________________________________________________________________________
| (602424:000011) 1253: Send_IO1 (link-nbr=16, sapi=0, tei=0) :
| long: 22 desti: 0 source: 15 cryst: 0 cpl: 16 us: 8 term: 0 type a5
| tei: 0 <<<< message sent : REL COMP [5a] Call ref : 36 c2
|______________________________________________________________________________
|
| IE:[08] CAUSE (l=2) 81 90 -> [90] NORMAL CALL CLEARING
|______________________________________________________________________________

______________________________________________________________________________
| (602490:000012) 1253: Send_IO1 (link-nbr=16, sapi=0, tei=0) :
| long: 117 desti: 0 source: 15 cryst: 0 cpl: 16 us: 8 term: 0 type a5
| tei: 0 <<<< message sent : SETUP [05] Call ref : 36 c3
|______________________________________________________________________________
|
| [9f] Non-locking shift. codeset : 7
| [a2] EI_IP_TYPE_TERM
| [9f] Non-locking shift. codeset : 7
| IE:[05] EI_IP_INFOS (l=9) -> G723 Ece 1 Vad 0 Profil 1, IPv4 :172.16.162.100.
| [9f] Non-locking shift. codeset : 7
| IE:[06] EI_IP_PAYLOADS (l=1) -> G729 Ece 1 Vad 0
| [9f] Non-locking shift. codeset : 7
| IE:[07] EI_IP_QOS (l=3) b8 e0 00
| [9f] Non-locking shift. codeset : 7
| IE:[0a] EI_RTP_INFO (l=30)
| -> stop_packet=0 stop_rtp=0 h323=0 wc=0 rf=0 udp=1 rqm=0
| -> Transm_Bande=1 detection_Q23=1 dtmf_payload=96
| -> Port RTP = 32012, IPv4 : 192. 168. 60. 101.
| -> Port RTCP SR = 32013, IPv4 : 192. 168. 60. 101.
| -> Port RTCP RR = 32013, IPv4 : 192. 168. 60. 101.
| -> Port Fax = 32015, IPv4 : 192. 168. 60. 101.
| IE:[04] BEARER_CAPABILITY (l=3) 80 90 a3
| IE:[18] CHANNEL (l=3) a8 83 81 -> T2 : No B channel
| IE:[1e] PROGRESS_ID (l=2) 80 83
| IE:[28] DISPLAY (l=15) `YEOMANS BERNICE`
| IE:[6c] CALLING_NUMBER (l=5) -> 01 81 Num : 384
| IE:[7d] HLC (l=2) 91 81
|______________________________________________________________________________

______________________________________________________________________________
| (602490:000015) Physical-Event :
| long: 21 desti: 0 source: 0 cryst: 0 cpl: 16 us: 0 term: 0 type a5
| tei: 0 >>>> message received : SETUP ACK [0d] Call ref : b6 c3
|______________________________________________________________________________
|
| IE:[18] CHANNEL (l=1) a8 -> T2 : No B channel
|______________________________________________________________________________

______________________________________________________________________________
| (602490:000016) 1253: Send_IO1 (link-nbr=16, sapi=0, tei=0) :
| long: 26 desti: 0 source: 15 cryst: 0 cpl: 16 us: 8 term: 0 type a5
| tei: 0 <<<< message sent : INFORMATION [7b] Call ref : 36 c3
|______________________________________________________________________________
|
| IE:[70] CALLED_NUMBER (l=5) -> 81 Num : 4729
| [a1] Sending complete
|______________________________________________________________________________

______________________________________________________________________________
| (602491:000017) Physical-Event :
| long: 21 desti: 0 source: 0 cryst: 0 cpl: 16 us: 0 term: 0 type a5
| tei: 0 >>>> message received : CALL PROC (02) Call ref : b6 c3
|______________________________________________________________________________
|
| IE:[18] CHANNEL (l=1) a8 -> T2 : No B channel
|______________________________________________________________________________

______________________________________________________________________________
| (602494:000018) Physical-Event :
| long: 23 desti: 0 source: 0 cryst: 0 cpl: 16 us: 0 term: 0 type a5
| tei: 0 >>>> message received : ALERT (01) Call ref : b6 c3
|______________________________________________________________________________
|
| [97] Locking shift. codeset : 7
| IE:[0a] EI_RTP_INFO (l=2)
| -> stop_packet=0 stop_rtp=0 h323=1 wc=0 rf=0 udp=0 rqm=0
| -> Transm_Bande=0 detection_Q23=1
|______________________________________________________________________________

______________________________________________________________________________
| (602530:000019) Physical-Event :
| long: 34 desti: 0 source: 0 cryst: 0 cpl: 16 us: 0 term: 0 type a5
| tei: 0 >>>> message received : CONNECT (07) Call ref : b6 c3
|______________________________________________________________________________
|
| IE:[4c] CONNECTED_NUMBER (l=6) -> 00 80 Num : 4723
| [97] Locking shift. codeset : 7
| IE:[06] EI_IP_PAYLOADS (l=1) -> G723 Ece 0 Vad 0
| IE:[0a] EI_RTP_INFO (l=2)
| -> stop_packet=0 stop_rtp=0 h323=1 wc=0 rf=0 udp=0 rqm=0
| -> Transm_Bande=0 detection_Q23=1
|______________________________________________________________________________

______________________________________________________________________________
| (602530:000020) Physical-Event :
| long: 54 desti: 0 source: 0 cryst: 0 cpl: 16 us: 0 term: 0 type a5
| tei: 0 >>>> message received : FACILITY [62] Call ref : b6 c3
|______________________________________________________________________________
|
| [97] Locking shift. codeset : 7
| IE:[06] EI_IP_PAYLOADS (l=1) -> G723 Ece 0 Vad 0
| IE:[0a] EI_RTP_INFO (l=30)
| -> stop_packet=0 stop_rtp=0 h323=1 wc=0 rf=0 udp=0 rqm=0
| -> Transm_Bande=0 detection_Q23=1
| -> Port RTP = 32160, IPv4 : 172. 16. 162. 100.
| -> Port RTCP SR = 32161, IPv4 : 172. 16. 162. 100.
| -> Port RTCP RR = 32161, IPv4 : 172. 16. 162. 100.
| -> Port Fax = 0, IPv4 : 0. 0. 0. 0.
|______________________________________________________________________________

______________________________________________________________________________
| (602530:000021) 1253: Send_IO1 (link-nbr=16, sapi=0, tei=0) :
| long: 18 desti: 0 source: 15 cryst: 0 cpl: 16 us: 8 term: 0 type a5
| tei: 0 <<<< message sent : CONNECT ACK (0f) Call ref : 36 c3
|______________________________________________________________________________

______________________________________________________________________________
| (602619:000022) Physical-Event :
| long: 22 desti: 0 source: 0 cryst: 0 cpl: 16 us: 0 term: 0 type a5
| tei: 0 >>>> message received : RELEASE [4d] Call ref : b6 c3
|______________________________________________________________________________
|
| IE:[08] CAUSE (l=2) 81 a9 -> [a9] TEMPORARY FAILURE
|______________________________________________________________________________

______________________________________________________________________________
| (602619:000023) 1253: Send_IO1 (link-nbr=16, sapi=0, tei=0) :
| long: 22 desti: 0 source: 15 cryst: 0 cpl: 16 us: 8 term: 0 type a5
| tei: 0 <<<< message sent : REL COMP [5a] Call ref : 36 c3
|______________________________________________________________________________
|
| IE:[08] CAUSE (l=2) 81 90 -> [90] NORMAL CALL CLEARING
|______________________________________________________________________________


(1)Farsons_m> first call released from mriehel / second call released from st ju
lians
-bash: first: command not found
(1)Farsons_m>

[alex]

As I pointed out many times - t3 won't help you to identify complicated problems with H.323.
Basically it shows only H.225 messages. But in H.323 you have H.245, RTP, RAS etc messages.
The best way is to run tcpdump or wireshark traces (on OXO side's port or configure mirror port for OXE CS and GD ports) and analyze them with the latest wireshark program.

[wallacezammit]

Ok, thanks I will do that.