Traces - verbose debugging

Please, read this whole information. If you don't see [TRACE] lines in the log file, you misconfigured something and we will ask you for new feedback which slows down the help process.

It is quite usual that a developer asks you to provide more verbose logs using the trace (verbose logs for development) which tvheadend includes. If you see --trace <comma_separated_list_of_subsystems> you need to follow this.

Compile tvheadend using --enable-trace option for the configure script see Building to compile tvheadend directly from sources. The traces are enabled by default, but packages - except debug versions - disable this functionality.

!!! The [TRACE] lines are written only to the log file. You will not see them on console or syslog output. Please, enter the log file. !!!

The first way to enable traces is from the command-line options (to specify the log file and all subsystems to debug), like:

./build.linux/tvheadend -l /tmp/tvheadend.log --trace htsp,subscription,pat,pmt,bat

The second way is to use the debugging tab in the configuration (this settings is not preserved):

Example

You should see [ TRACE] lines in your log file (these lines are not in syslog file or journal)!

2017-09-14 15:39:07.670 [  TRACE]:httpc: 0004: finishing
2017-09-14 15:39:07.672 [  TRACE]:mpegts: table: mux 0x283ea64430 destroy pmt 02/FF (2) pid 044C (1100)
2017-09-14 15:39:07.673 [  TRACE]:pcr: S23.5E/12343.5H/CT 1 HD: change   : 1532541106
2017-09-14 15:39:07.673 [  TRACE]:mpegts: input SAT>IP DVB-S Tuner #4 (192.168.100.237) got 68432 bytes
2017-09-14 15:39:07.673 [  DEBUG]:mpegts: 12343.5H in S23.5E - close PID 044C (1100) [16/0x7fc64400a900]
2017-09-14 15:39:07.674 [  TRACE]:mpegts: table: mux 0x283ea64430 free pmt 02/FF (2) pid 044C (1100)
2017-09-14 15:39:07.674 [  DEBUG]:mpegts: 12343.5H in S23.5E - close PID 044C (1100) [8/0x283ea64d00]
2017-09-14 15:39:07.674 [  DEBUG]:mpegts: 12343.5H in S23.5E - close PID 0456 (1110) [8/0x283ea64d00]
2017-09-14 15:39:07.674 [  DEBUG]:mpegts: 12343.5H in S23.5E - close PID 0460 (1120) [8/0x283ea64d00]
2017-09-14 15:39:07.674 [  DEBUG]:mpegts: 12343.5H in S23.5E - close PID 0461 (1121) [8/0x283ea64d00]
2017-09-14 15:39:07.674 [  DEBUG]:mpegts: 12343.5H in S23.5E - close PID 0462 (1122) [8/0x283ea64d00]
2017-09-14 15:39:07.674 [  DEBUG]:mpegts: 12343.5H in S23.5E - close PID 0463 (1123) [8/0x283ea64d00]
2017-09-14 15:39:07.674 [  DEBUG]:mpegts: 12343.5H in S23.5E - close PID 046A (1130) [8/0x283ea64d00]
2017-09-14 15:39:07.674 [  DEBUG]:mpegts: 12343.5H in S23.5E - close PID 047E (1150) [8/0x283ea64d00]
2017-09-14 15:39:07.674 [  DEBUG]:mpegts: 12343.5H in S23.5E - close PID 0488 (1160) [8/0x283ea64d00]
2017-09-14 15:39:07.674 [  DEBUG]:mpegts: 12343.5H in S23.5E - stopping mux
2017-09-14 15:39:07.674 [  DEBUG]:satip: SAT>IP DVB-S Tuner #4 (192.168.100.237) - stopping 12343.5H in S23.5E
2017-09-14 15:39:07.674 [  TRACE]:mpegts: SAT>IP DVB-S Tuner #4 (192.168.100.237) - flush subscribers
2017-09-14 15:39:07.674 [  TRACE]:mpegts: 12343.5H in S23.5E - flush tables
2017-09-14 15:39:07.674 [  TRACE]:descrambler: mux 0x283ea64430 - flush tables

debugging.png (32.3 KB) Jaroslav Kysela, 2015-06-02 10:33