Wowza Community

RTMP output packet time debugging & ingestion packet timecode debugging

Hi,

Wowza Media Server 3.0.5 build1220 (16 February 2012) introduces:

RTMP output packet time debugging (Streams/Property debugOutgoingRTMPPacketTimes)

ingestion packet timecode debugging (Streams/Property debugIncomingPacketTimes)

Can You explain how to utilize this debugging features ?

Hi

At a guess i would try to utilise it like this,

Under the section /Streams/Properties/ add this property:

debugOutgoingRTMPPacketTimes

true

Boolean

debugIncomingPacketTimes

true

Boolean

Let me know if that works.

Jason

These flags are for our own internal use. Quickly the fields are:

hdr:0x27 sz:00873 tc2200/40 rt:1331741019516/-343

hdr: first byte of package

sz: size of packate

tc: packet absolute timecode

rt: realtime timecode for packet in milliseconds

[second-number]: calculated offset of packet timecode from the realtime clock

Again, this is for our own internal use.

Charlie

Hi

You would have to post your logs to show me what you mean.

Are you seeing that when you see the new debug output in logs?

Jason

This is great :slight_smile: I can see debug output in logs.

Can You explain in more detail what is meaning of logged fields ?

It looks like this:

2012-03-14 17:03:38 INFO server comment - LiveReceiver.rsend[live/_definst_/composite_0]: notify:false tsz:1 dsz:29 toff:577 rt:1331741018013/1492 seq:29
2012-03-14 17:03:38 INFO server comment - LiveReceiver.video[live/_definst_/composite_0]: hdr:0x27 sz:00557 tc1080/40 rt:1331741018013/ 40
2012-03-14 17:03:38 INFO server comment - LiveReceiver.rsend[live/_definst_/composite_0]: notify:false tsz:2 dsz:31 toff:498 rt:1331741018014/1 seq:31
2012-03-14 17:03:38 INFO server comment - LiveReceiver.video[live/_definst_/composite_0]: hdr:0x27 sz:00895 tc1120/40 rt:1331741018014/ 79
2012-03-14 17:03:38 INFO server comment - LiveReceiver.video[live/_definst_/composite_0]: hdr:0x27 sz:00531 tc1160/40 rt:1331741018014/ 119
2012-03-14 17:03:38 INFO server comment - LiveReceiver.rsend[live/_definst_/composite_0]: notify:false tsz:2 dsz:33 toff:418 rt:1331741018014/0 seq:33
2012-03-14 17:03:38 INFO server comment - LiveReceiver.video[live/_definst_/composite_0]: hdr:0x27 sz:00863 tc1200/40 rt:1331741018014/ 159
2012-03-14 17:03:38 INFO server comment - LiveReceiver.video[live/_definst_/composite_0]: hdr:0x27 sz:00532 tc1240/40 rt:1331741018015/ 198
2012-03-14 17:03:38 INFO server comment - LiveReceiver.rsend[live/_definst_/composite_0]: notify:false tsz:2 dsz:35 toff:339 rt:1331741018015/1 seq:35
2012-03-14 17:03:38 INFO server comment - LiveReceiver.video[live/_definst_/composite_0]: hdr:0x27 sz:00852 tc1280/40 rt:1331741018015/ 238
2012-03-14 17:03:38 INFO server comment - LiveReceiver.video[live/_definst_/composite_0]: hdr:0x27 sz:00531 tc1320/40 rt:1331741018015/ 278
2012-03-14 17:03:38 INFO server comment - LiveReceiver.rsend[live/_definst_/composite_0]: notify:false tsz:2 dsz:37 toff:260 rt:1331741018016/1 seq:37
2012-03-14 17:03:38 INFO server comment - LiveReceiver.video[live/_definst_/composite_0]: hdr:0x27 sz:00852 tc1360/40 rt:1331741018016/ 317
2012-03-14 17:03:38 INFO server comment - LiveReceiver.video[live/_definst_/composite_0]: hdr:0x27 sz:00531 tc1400/40 rt:1331741018016/ 357
2012-03-14 17:03:38 INFO server comment - LiveReceiver.rsend[live/_definst_/composite_0]: notify:false tsz:2 dsz:39 toff:180 rt:1331741018016/0 seq:39
2012-03-14 17:03:38 INFO server comment - LiveReceiver.video[live/_definst_/composite_0]: hdr:0x27 sz:00852 tc1440/40 rt:1331741018016/ 397
2012-03-14 17:03:38 INFO server comment - LiveReceiver.video[live/_definst_/composite_0]: hdr:0x27 sz:00531 tc1480/40 rt:1331741018017/ 436
2012-03-14 17:03:38 INFO server comment - LiveReceiver.rsend[live/_definst_/composite_0]: notify:false tsz:2 dsz:41 toff:101 rt:1331741018017/1 seq:41
2012-03-14 17:03:38 INFO server comment - LiveReceiver.video[live/_definst_/composite_0]: hdr:0x27 sz:00852 tc1520/40 rt:1331741018017/ 476
2012-03-14 17:03:38 INFO server comment - LiveReceiver.video[live/_definst_/composite_0]: hdr:0x27 sz:00531 tc1560/40 rt:1331741018017/ 516
2012-03-14 17:03:38 INFO server comment - LiveReceiver.rsend[live/_definst_/composite_0]: notify:false tsz:2 dsz:43 toff:22 rt:1331741018018/1 seq:43
2012-03-14 17:03:38 INFO server comment - LiveReceiver.video[live/_definst_/composite_0]: hdr:0x27 sz:00852 tc1600/40 rt:1331741018018/ 555
2012-03-14 17:03:38 INFO server comment - LiveReceiver.video[live/_definst_/composite_0]: hdr:0x27 sz:00531 tc1640/40 rt:1331741018018/ 595
2012-03-14 17:03:38 INFO server comment - LiveReceiver.rsend[live/_definst_/composite_0]: notify:false tsz:2 dsz:45 toff:-58 rt:1331741018018/0 seq:45
2012-03-14 17:03:38 INFO server comment - LiveReceiver.video[live/_definst_/composite_0]: hdr:0x27 sz:00852 tc1680/40 rt:1331741018018/ 635
2012-03-14 17:03:38 INFO server comment - LiveReceiver.video[live/_definst_/composite_0]: hdr:0x27 sz:00531 tc1720/40 rt:1331741018019/ 674
2012-03-14 17:03:38 INFO server comment - LiveReceiver.rsend[live/_definst_/composite_0]: notify:false tsz:2 dsz:47 toff:-137 rt:1331741018019/1 seq:47
2012-03-14 17:03:38 INFO server comment - LiveReceiver.video[live/_definst_/composite_0]: hdr:0x27 sz:00852 tc1760/40 rt:1331741018019/ 714
2012-03-14 17:03:38 INFO server comment - LiveReceiver.video[live/_definst_/composite_0]: hdr:0x27 sz:00531 tc1800/40 rt:1331741018019/ 754
2012-03-14 17:03:38 INFO server comment - LiveReceiver.rsend[live/_definst_/composite_0]: notify:false tsz:2 dsz:49 toff:-216 rt:1331741018020/1 seq:49
2012-03-14 17:03:38 INFO server comment - LiveReceiver.video[live/_definst_/composite_0]: hdr:0x27 sz:00852 tc1840/40 rt:1331741018020/ 793
2012-03-14 17:03:38 INFO server comment - LiveReceiver.video[live/_definst_/composite_0]: hdr:0x27 sz:00531 tc1880/40 rt:1331741018020/ 833
2012-03-14 17:03:38 INFO server comment - LiveReceiver.rsend[live/_definst_/composite_0]: notify:false tsz:2 dsz:51 toff:-296 rt:1331741018020/0 seq:51
2012-03-14 17:03:38 INFO server comment - LiveReceiver.video[live/_definst_/composite_0]: hdr:0x27 sz:00852 tc1920/40 rt:1331741018021/ 872
2012-03-14 17:03:38 INFO server comment - LiveReceiver.video[live/_definst_/composite_0]: hdr:0x27 sz:00531 tc1960/40 rt:1331741018021/ 912
2012-03-14 17:03:38 INFO server comment - LiveReceiver.rsend[live/_definst_/composite_0]: notify:false tsz:2 dsz:53 toff:-375 rt:1331741018021/1 seq:53

2012-03-14 17:03:39 INFO server comment - LiveReceiver.video[live/_definst_/composite_0]: hdr:0x17 sz:25365 tc2000/40 rt:1331741019514/-541
2012-03-14 17:03:39 INFO server comment - LiveReceiver.rsend[live/_definst_/composite_0]: notify:false tsz:1 dsz:54 toff:1078 rt:1331741019514/1493 seq:54
2012-03-14 17:03:39 INFO server comment - LiveReceiver.video[live/_definst_/composite_0]: hdr:0x27 sz:01196 tc2040/40 rt:1331741019514/-501
2012-03-14 17:03:39 INFO server comment - LiveReceiver.video[live/_definst_/composite_0]: hdr:0x27 sz:00559 tc2080/40 rt:1331741019515/-462
2012-03-14 17:03:39 INFO server comment - LiveReceiver.rsend[live/_definst_/composite_0]: notify:false tsz:2 dsz:56 toff:999 rt:1331741019515/1 seq:56
2012-03-14 17:03:39 INFO server comment - LiveReceiver.video[live/_definst_/composite_0]: hdr:0x27 sz:00998 tc2120/40 rt:1331741019515/-422
2012-03-14 17:03:39 INFO server comment - LiveReceiver.video[live/_definst_/composite_0]: hdr:0x27 sz:00538 tc2160/40 rt:1331741019515/-382
2012-03-14 17:03:39 INFO server comment - LiveReceiver.rsend[live/_definst_/composite_0]: notify:false tsz:2 dsz:58 toff:920 rt:1331741019516/1 seq:58
2012-03-14 17:03:39 INFO server comment - LiveReceiver.video[live/_definst_/composite_0]: hdr:0x27 sz:00873 tc2200/40 rt:1331741019516/-343
2012-03-14 17:03:39 INFO server comment - LiveReceiver.video[live/_definst_/composite_0]: hdr:0x27 sz:00534 tc2240/40 rt:1331741019516/-303
2012-03-14 17:03:39 INFO server comment - LiveReceiver.rsend[live/_definst_/composite_0]: notify:false tsz:2 dsz:60 toff:840 rt:1331741019516/0 seq:60
2012-03-14 17:03:39 INFO server comment - LiveReceiver.video[live/_definst_/composite_0]: hdr:0x27 sz:00925 tc2280/40 rt:1331741019516/-263
2012-03-14 17:03:39 INFO server comment - LiveReceiver.video[live/_definst_/composite_0]: hdr:0x27 sz:00537 tc2320/40 rt:1331741019517/-224
2012-03-14 17:03:39 INFO server comment - LiveReceiver.rsend[live/_definst_/composite_0]: notify:false tsz:2 dsz:62 toff:761 rt:1331741019517/1 seq:62
2012-03-14 17:03:39 INFO server comment - LiveReceiver.video[live/_definst_/composite_0]: hdr:0x27 sz:00858 tc2360/40 rt:1331741019517/-184
2012-03-14 17:03:39 INFO server comment - LiveReceiver.video[live/_definst_/composite_0]: hdr:0x27 sz:00533 tc2400/40 rt:1331741019517/-144
2012-03-14 17:03:39 INFO server comment - LiveReceiver.rsend[live/_definst_/composite_0]: notify:false tsz:2 dsz:64 toff:682 rt:1331741019518/1 seq:64
2012-03-14 17:03:39 INFO server comment - LiveReceiver.video[live/_definst_/composite_0]: hdr:0x27 sz:00855 tc2440/40 rt:1331741019518/-105
2012-03-14 17:03:39 INFO server comment - LiveReceiver.video[live/_definst_/composite_0]: hdr:0x27 sz:00533 tc2480/40 rt:1331741019518/-65
2012-03-14 17:03:39 INFO server comment - LiveReceiver.rsend[live/_definst_/composite_0]: notify:false tsz:2 dsz:66 toff:602 rt:1331741019518/0 seq:66
2012-03-14 17:03:39 INFO server comment - LiveReceiver.video[live/_definst_/composite_0]: hdr:0x27 sz:00953 tc2520/40 rt:1331741019518/-25
2012-03-14 17:03:39 INFO server comment - LiveReceiver.video[live/_definst_/composite_0]: hdr:0x27 sz:00538 tc2560/40 rt:1331741019519/ 14
2012-03-14 17:03:39 INFO server comment - LiveReceiver.rsend[live/_definst_/composite_0]: notify:false tsz:2 dsz:68 toff:523 rt:1331741019519/1 seq:68
2012-03-14 17:03:39 INFO server comment - LiveReceiver.video[live/_definst_/composite_0]: hdr:0x27 sz:00857 tc2600/40 rt:1331741019519/ 54
2012-03-14 17:03:39 INFO server comment - LiveReceiver.video[live/_definst_/composite_0]: hdr:0x27 sz:00533 tc2640/40 rt:1331741019519/ 94
2012-03-14 17:03:39 INFO server comment - LiveReceiver.rsend[live/_definst_/composite_0]: notify:false tsz:2 dsz:70 toff:443 rt:1331741019519/0 seq:70
2012-03-14 17:03:39 INFO server comment - LiveReceiver.video[live/_definst_/composite_0]: hdr:0x27 sz:00855 tc2680/40 rt:1331741019520/ 133
2012-03-14 17:03:39 INFO server comment - LiveReceiver.video[live/_definst_/composite_0]: hdr:0x27 sz:00533 tc2720/40 rt:1331741019520/ 173
2012-03-14 17:03:39 INFO server comment - LiveReceiver.rsend[live/_definst_/composite_0]: notify:false tsz:2 dsz:72 toff:364 rt:1331741019520/1 seq:72
2012-03-14 17:03:39 INFO server comment - LiveReceiver.video[live/_definst_/composite_0]: hdr:0x27 sz:00855 tc2760/40 rt:1331741019520/ 213
2012-03-14 17:03:39 INFO server comment - LiveReceiver.video[live/_definst_/composite_0]: hdr:0x27 sz:00533 tc2800/40 rt:1331741019520/ 253
2012-03-14 17:03:39 INFO server comment - LiveReceiver.rsend[live/_definst_/composite_0]: notify:false tsz:2 dsz:74 toff:285 rt:1331741019521/1 seq:74
2012-03-14 17:03:39 INFO server comment - LiveReceiver.video[live/_definst_/composite_0]: hdr:0x27 sz:00855 tc2840/40 rt:1331741019521/ 292
2012-03-14 17:03:39 INFO server comment - LiveReceiver.video[live/_definst_/composite_0]: hdr:0x27 sz:00533 tc2880/40 rt:1331741019521/ 332
2012-03-14 17:03:39 INFO server comment - LiveReceiver.rsend[live/_definst_/composite_0]: notify:false tsz:2 dsz:76 toff:205 rt:1331741019521/0 seq:76
2012-03-14 17:03:39 INFO server comment - LiveReceiver.video[live/_definst_/composite_0]: hdr:0x27 sz:00855 tc2920/40 rt:1331741019521/ 372
2012-03-14 17:03:39 INFO server comment - LiveReceiver.video[live/_definst_/composite_0]: hdr:0x27 sz:00533 tc2960/40 rt:1331741019522/ 411
2012-03-14 17:03:39 INFO server comment - LiveReceiver.rsend[live/_definst_/composite_0]: notify:false tsz:2 dsz:78 toff:126 rt:1331741019522/1 seq:78
2012-03-14 17:03:39 INFO server comment - LiveReceiver.video[live/_definst_/composite_0]: hdr:0x17 sz:25359 tc3000/40 rt:1331741019522/ 451
2012-03-14 17:03:39 INFO server comment - LiveReceiver.video[live/_definst_/composite_0]: hdr:0x27 sz:01187 tc3040/40 rt:1331741019522/ 491
2012-03-14 17:03:39 INFO server comment - LiveReceiver.rsend[live/_definst_/composite_0]: notify:false tsz:2 dsz:80 toff:47 rt:1331741019523/1 seq:80
2012-03-14 17:03:39 INFO server comment - LiveReceiver.video[live/_definst_/composite_0]: hdr:0x27 sz:00557 tc3080/40 rt:1331741019523/ 530
2012-03-14 17:03:39 INFO server comment - LiveReceiver.video[live/_definst_/composite_0]: hdr:0x27 sz:00895 tc3120/40 rt:1331741019523/ 570
2012-03-14 17:03:39 INFO server comment - LiveReceiver.rsend[live/_definst_/composite_0]: notify:false tsz:2 dsz:82 toff:-33 rt:1331741019523/0 seq:82
2012-03-14 17:03:39 INFO server comment - LiveReceiver.video[live/_definst_/composite_0]: hdr:0x27 sz:00531 tc3160/40 rt:1331741019523/ 610
2012-03-14 17:03:39 INFO server comment - LiveReceiver.video[live/_definst_/composite_0]: hdr:0x27 sz:00863 tc3200/40 rt:1331741019524/ 649
2012-03-14 17:03:39 INFO server comment - LiveReceiver.rsend[live/_definst_/composite_0]: notify:false tsz:2 dsz:84 toff:-112 rt:1331741019524/1 seq:84
2012-03-14 17:03:39 INFO server comment - LiveReceiver.video[live/_definst_/composite_0]: hdr:0x27 sz:00532 tc3240/40 rt:1331741019524/ 689
2012-03-14 17:03:39 INFO server comment - LiveReceiver.video[live/_definst_/composite_0]: hdr:0x27 sz:00852 tc3280/40 rt:1331741019524/ 729
2012-03-14 17:03:39 INFO server comment - LiveReceiver.rsend[live/_definst_/composite_0]: notify:false tsz:2 dsz:86 toff:-192 rt:1331741019524/0 seq:86
2012-03-14 17:03:39 INFO server comment - LiveReceiver.video[live/_definst_/composite_0]: hdr:0x27 sz:00531 tc3320/40 rt:1331741019525/ 768
2012-03-14 17:03:39 INFO server comment - LiveReceiver.video[live/_definst_/composite_0]: hdr:0x27 sz:00852 tc3360/40 rt:1331741019525/ 808
2012-03-14 17:03:39 INFO server comment - LiveReceiver.rsend[live/_definst_/composite_0]: notify:false tsz:2 dsz:88 toff:-271 rt:1331741019525/1 seq:88
2012-03-14 17:03:39 INFO server comment - LiveReceiver.video[live/_definst_/composite_0]: hdr:0x27 sz:00531 tc3400/40 rt:1331741019525/ 848
2012-03-14 17:03:39 INFO server comment - LiveReceiver.video[live/_definst_/composite_0]: hdr:0x27 sz:00852 tc3440/40 rt:1331741019525/ 888
2012-03-14 17:03:39 INFO server comment - LiveReceiver.rsend[live/_definst_/composite_0]: notify:false tsz:2 dsz:90 toff:-350 rt:1331741019526/1 seq:90
2012-03-14 17:03:39 INFO server comment - LiveReceiver.video[live/_definst_/composite_0]: hdr:0x27 sz:00531 tc3480/40 rt:1331741019526/ 927
2012-03-14 17:03:39 INFO server comment - LiveReceiver.video[live/_definst_/composite_0]: hdr:0x27 sz:00869 tc3520/40 rt:1331741019526/ 967
2012-03-14 17:03:39 INFO server comment - LiveReceiver.rsend[live/_definst_/composite_0]: notify:false tsz:2 dsz:92 toff:-430 rt:1331741019526/0 seq:92
2012-03-14 17:03:39 INFO server comment - LiveReceiver.video[live/_definst_/composite_0]: hdr:0x27 sz:00536 tc3560/40 rt:1331741019526/ 1007
2012-03-14 17:03:39 INFO server comment - LiveReceiver.video[live/_definst_/composite_0]: hdr:0x27 sz:00886 tc3600/40 rt:1331741019527/ 1046
2012-03-14 17:03:39 INFO server comment - LiveReceiver.rsend[live/_definst_/composite_0]: notify:false tsz:2 dsz:94 toff:-509 rt:1331741019527/1 seq:94
2012-03-14 17:03:39 INFO server comment - LiveReceiver.video[live/_definst_/composite_0]: hdr:0x27 sz:00533 tc3640/40 rt:1331741019527/ 1086
2012-03-14 17:03:39 INFO server comment - LiveReceiver.video[live/_definst_/composite_0]: hdr:0x27 sz:00886 tc3680/40 rt:1331741019527/ 1126
2012-03-14 17:03:39 INFO server comment - LiveReceiver.rsend[live/_definst_/composite_0]: notify:false tsz:2 dsz:96 toff:-589 rt:1331741019527/0 seq:96
2012-03-14 17:03:39 INFO server comment - LiveReceiver.video[live/_definst_/composite_0]: hdr:0x27 sz:00531 tc3720/40 rt:1331741019528/ 1165
2012-03-14 17:03:39 INFO server comment - LiveReceiver.video[live/_definst_/composite_0]: hdr:0x27 sz:00870 tc3760/40 rt:1331741019531/ 1202
2012-03-14 17:03:39 INFO server comment - LiveReceiver.rsend[live/_definst_/composite_0]: notify:false tsz:2 dsz:98 toff:-665 rt:1331741019531/4 seq:98
2012-03-14 17:03:39 INFO server comment - LiveReceiver.video[live/_definst_/composite_0]: hdr:0x27 sz:00531 tc3800/40 rt:1331741019531/ 1242
2012-03-14 17:03:39 INFO server comment - LiveReceiver.video[live/_definst_/composite_0]: hdr:0x27 sz:00870 tc3840/40 rt:1331741019531/ 1282
2012-03-14 17:03:39 INFO server comment - LiveReceiver.rsend[live/_definst_/composite_0]: notify:false tsz:2 dsz:100 toff:-744 rt:1331741019532/1 seq:100
2012-03-14 17:03:39 INFO server comment - LiveReceiver.video[live/_definst_/composite_0]: hdr:0x27 sz:00536 tc3880/40 rt:1331741019532/ 1321
2012-03-14 17:03:39 INFO server comment - LiveReceiver.video[live/_definst_/composite_0]: hdr:0x27 sz:00864 tc3920/40 rt:1331741019532/ 1361
2012-03-14 17:03:39 INFO server comment - LiveReceiver.rsend[live/_definst_/composite_0]: notify:false tsz:2 dsz:102 toff:-824 rt:1331741019532/0 seq:102
2012-03-14 17:03:39 INFO server comment - LiveReceiver.video[live/_definst_/composite_0]: hdr:0x27 sz:00532 tc3960/40 rt:1331741019532/ 1401

Thanks

Thanks,

Can You explain what fields notify, tsz, dsz, toff means ?

notify:false tsz:2 dsz:49 toff:-216 rt:1331741018020/1 seq:49

rt would be realtime timecode for packet in milliseconds ?

seq would be packet sequence number ?