Project

General

Profile

Bug #4081

Selecting an scrambled channel (using HW CAM) causes crash

Added by Bengt Madeberg about 8 years ago. Updated about 8 years ago.

Status:
Fixed
Priority:
Normal
Assignee:
-
Category:
Descrambling
Target version:
-
Start date:
2016-11-15
Due date:
% Done:

100%

Estimated time:
Found in version:
HTS Tvheadend 4.1-2322~g879d532
Affected Versions:

Description

I have not used my HW CAM for a while but decided to give it a try. Updated to latest version but when I select an scrambled channel, tvh crash. The crash happends every time. The HW CAM tvh server acts as a SAT>IP server for another tvh server. Channels not using HW CAM works perfect. If any additional information is needed I can hopefully supply it.

2016-11-15 18:58:55.798 [   INFO]:subscription: 0003: "SAT>IP Slave/Teracom/570MHz/TV4 HD Göteborg" subscribing to service "Teracom/570MHz/TV4 HD Göteborg", weight: 100, adapter: "TurboSight TBS 5881 DVBT/T2 frontend : DVB-T #0", network: "Teracom", mux: "570MHz", provider: "TV4 AB", client="SAT>IP Slave/Teracom/570MHz/TV4 HD Göteborg" 
2016-11-15 18:58:55.829 [  ALERT]:CRASH: Signal: 11 in PRG: tvheadend (4.1-2322~g879d532) [736d1352ed61291565ac8fa4e052b01978dc9e35] CWD: /  
2016-11-15 18:58:55.829 [  ALERT]:CRASH: Fault address 0x10 (Address not mapped)
2016-11-15 18:58:55.829 [  ALERT]:CRASH: Loaded libraries: /usr/lib/libdvben50221.so /usr/lib/libdvbapi.so /usr/lib/libucsi.so /lib/x86_64-linux-gnu/libssl.so.1.0.0 /lib/x86_64-linux-gnu/libcrypto.so.1.0.0 /lib/x86_64-linux-gnu/libz.so.1 /usr/lib/liburiparser.so.1 /usr/lib/x86_64-linux-gnu/libavahi-common.so.3 /usr/lib/x86_64-linux-gnu/libavahi-client.so.3 /lib/x86_64-linux-gnu/libdbus-1.so.3 /lib/x86_64-linux-gnu/libdl.so.2 /lib/x86_64-linux-gnu/libpthread.so.0 /lib/x86_64-linux-gnu/libm.so.6 /lib/x86_64-linux-gnu/librt.so.1 /usr/lib/x86_64-linux-gnu/libstdc++.so.6 /lib/x86_64-linux-gnu/libc.so.6 /lib64/ld-linux-x86-64.so.2 /lib/x86_64-linux-gnu/libgcc_s.so.1 /lib/x86_64-linux-gnu/libnss_compat.so.2 /lib/x86_64-linux-gnu/libnsl.so.1 /lib/x86_64-linux-gnu/libnss_nis.so.2 /lib/x86_64-linux-gnu/libnss_files.so.2  
2016-11-15 18:58:55.829 [  ALERT]:CRASH: Register dump [23]: 00007fc74400183000007fc74402ec90000000005000709100000000000019db00007fc750006da100000000000002f000007fc74400165000007fc7440018300000000000000000000000000000005900007fc79780b7200000000000000001000000000000000000000000000000db0000000090db194700007fc76dffa6b000007fc796388e1d000000000001024600000000000000330000000000000004000000000000000efffffffe7ffbba110000000000000010
2016-11-15 18:58:55.829 [  ALERT]:CRASH: STACKTRACE
2016-11-15 18:58:55.850 [  ALERT]:CRASH: ??:0 0x7fc79631984d
2016-11-15 18:58:55.865 [  ALERT]:CRASH: ??:0 0x7fc794410330
2016-11-15 18:58:55.880 [  ALERT]:CRASH: ??:0 0x7fc796388e1d
2016-11-15 18:58:55.896 [  ALERT]:CRASH: ??:0 0x7fc7963808d9
2016-11-15 18:58:55.910 [  ALERT]:CRASH: ??:0 0x7fc796388ba5
2016-11-15 18:58:55.924 [  ALERT]:CRASH: ??:0 0x7fc79638678e
2016-11-15 18:58:55.939 [  ALERT]:CRASH: ??:0 0x7fc7962e20b2
2016-11-15 18:58:56.250 [  ERROR]:config: uknown subsystem 'pmt'
2016-11-15 18:58:56.250 [   INFO]:main: Log started
2016-11-15 18:58:56.252 [   INFO]:http: Starting HTTP server 0.0.0.0:9981
2016-11-15 18:58:56.252 [   INFO]:htsp: Starting HTSP server 0.0.0.0:9982
2016-11-15 18:58:56.252 [  ERROR]:satips: use --satip_bindaddr parameter to select the local IP for SAT>IP
2016-11-15 18:58:56.252 [  ERROR]:satips: using Google lookup (might block the task until timeout)
2016-11-15 18:58:56.269 [   INFO]:satips: Starting SAT>IP RTSP server 192.168.0.93:9983
2016-11-15 18:58:56.269 [   INFO]:satips: SAT>IP Server initialized
2016-11-15 18:58:56.269 [   INFO]:satips:   HTTP 192.168.0.93:9981, RTSP 192.168.0.93:9983
2016-11-15 18:58:56.269 [   INFO]:satips:   descramble 3, muxcnf 0
2016-11-15 18:58:56.269 [   INFO]:satips:   tuner[fe=1]: DVB-T2 #1
2016-11-15 18:58:56.514 [   INFO]:config: loaded
2016-11-15 18:58:56.514 [   INFO]:config: scanfile (re)initialization with path <none>
2016-11-15 18:58:56.523 [   INFO]:linuxdvb: adapter added /dev/dvb/adapter0
2016-11-15 18:58:56.526 [   INFO]:dvr: Creating new configuration ''
2016-11-15 18:58:56.528 [   INFO]:csa: Using SSE2 128bit parallel descrambling
2016-11-15 18:58:56.528 [   INFO]:descrambler: adding CAID 2600 as constant crypto-word (BISS)
2016-11-15 18:58:56.528 [   INFO]:epggrab: module eit created
2016-11-15 18:58:56.528 [   INFO]:epggrab: module uk_freesat created
2016-11-15 18:58:56.528 [   INFO]:epggrab: module uk_freeview created
2016-11-15 18:58:56.528 [   INFO]:epggrab: module viasat_baltic created
2016-11-15 18:58:56.528 [   INFO]:epggrab: module Bulsatcom_39E created
2016-11-15 18:58:56.528 [   INFO]:epggrab: module psip created
2016-11-15 18:58:56.533 [   INFO]:epggrab: module opentv-skyit created
2016-11-15 18:58:56.534 [   INFO]:epggrab: module opentv-skynz created
2016-11-15 18:58:56.534 [   INFO]:epggrab: module opentv-ausat created
2016-11-15 18:58:56.534 [   INFO]:epggrab: module opentv-skyuk created
2016-11-15 18:58:56.536 [   INFO]:epggrab: module pyepg created
2016-11-15 18:58:56.536 [   INFO]:epggrab: module xmltv created
2016-11-15 18:58:56.539 [   INFO]:epgdb: gzip format detected, inflating (ratio 12.8% deflated size 110804)
2016-11-15 18:58:56.543 [   INFO]:epgdb: parsing 864579 bytes
2016-11-15 18:58:56.586 [   INFO]:epgdb: loaded v2
2016-11-15 18:58:56.586 [   INFO]:epgdb:   config     1
2016-11-15 18:58:56.586 [   INFO]:epgdb:   brands     0
2016-11-15 18:58:56.586 [   INFO]:epgdb:   seasons    348
2016-11-15 18:58:56.586 [   INFO]:epgdb:   episodes   1335
2016-11-15 18:58:56.586 [   INFO]:epgdb:   broadcasts 1474
2016-11-15 18:58:56.588 [ NOTICE]:START: HTS Tvheadend version 4.1-2322~g879d532 started, running as PID:10377 UID:105 GID:44, CWD:/ CNF:/home/hts/.hts/tvheadend
2016-11-15 18:58:56.764 [ NOTICE]:linuxdvb: CAM slot 0 status changed to module present
2016-11-15 18:58:56.790 [   INFO]:scanfile: DVB-S - loaded 1 regions with 112 networks
2016-11-15 18:58:56.790 [   INFO]:scanfile: DVB-T - loaded 43 regions with 1106 networks
2016-11-15 18:58:56.790 [   INFO]:scanfile: DVB-C - loaded 17 regions with 56 networks
2016-11-15 18:58:56.790 [   INFO]:scanfile: ATSC-T - loaded 2 regions with 9 networks
2016-11-15 18:58:56.790 [   INFO]:scanfile: ATSC-C - loaded 1 regions with 5 networks
2016-11-15 18:58:56.790 [   INFO]:scanfile: ISDB-T - loaded 2 regions with 1297 networks
2016-11-15 18:58:57.515 [   INFO]:mpegts: 570MHz in Teracom - tuning on TurboSight TBS 5881 DVBT/T2 frontend : DVB-T #0
2016-11-15 18:59:00.411 [   INFO]:subscription: 0001: "epggrab" subscribing to mux "570MHz", weight: 4, adapter: "TurboSight TBS 5881 DVBT/T2 frontend : DVB-T #0", network: "Teracom", service: "Raw PID Subscription" 
2016-11-15 18:59:00.411 [ NOTICE]:linuxdvb: CAM slot 0 status changed to module ready
2016-11-15 18:59:00.494 [  TRACE]:en50221: 0x00000000 session 1 tc connect
2016-11-15 18:59:00.695 [  TRACE]:en50221: 0x00010041 session 1 connecting
2016-11-15 18:59:00.696 [  TRACE]:en50221: 0x00010041 session 1 connected
2016-11-15 18:59:01.100 [  TRACE]:en50221: rm reply cb received for slot 0, count 0
2016-11-15 18:59:01.408 [  TRACE]:en50221: rm enq callback received for slot 0
2016-11-15 18:59:01.712 [  TRACE]:en50221: 0x00020043 session 2 connecting
2016-11-15 18:59:01.712 [  TRACE]:en50221: 0x00020043 session 2 connected
2016-11-15 18:59:01.712 [   INFO]:en50221: setting CI+ CAM data rate to 72 Mbps
2016-11-15 18:59:01.974 [WARNING]:linuxdvb: TurboSight TBS 5881 DVBT/T2 frontend : DVB-T #0 - poll TIMEOUT
2016-11-15 18:59:02.121 [   INFO]:en50221: CAM slot 0: Application type: 01, manufacturer: cafe, Manufacturer code: babe
2016-11-15 18:59:02.121 [   INFO]:en50221: CAM slot 0: Menu string: Viaccess Access
2016-11-15 18:59:07.124 [  TRACE]:en50221: 0x00240041 session 3 connecting
2016-11-15 18:59:07.124 [  TRACE]:en50221: 0x00240041 session 3 connected
2016-11-15 18:59:07.429 [  TRACE]:en50221: datetime enquiry cb received for slot 0
2016-11-15 18:59:07.732 [  TRACE]:en50221: 0x00240041 session 3 close
2016-11-15 18:59:07.934 [  TRACE]:en50221: 0x00030041 session 3 connecting
2016-11-15 18:59:07.934 [  TRACE]:en50221: 0x00030041 session 3 connected
2016-11-15 18:59:08.339 [  TRACE]:dvbcam: unregister cam lca 0x7faca6aefdb0 slot 0
2016-11-15 18:59:08.340 [  TRACE]:dvbcam: register cam ca 0x7faca6aefdb0 slot 0 num_caids 2
2016-11-15 18:59:08.340 [   INFO]:en50221: CAM slot 0 supported CAIDs:  0500 (Viaccess) 0502 (Viaccess)

Files

_usr_bin_tvheadend.105.crash (1.8 MB) _usr_bin_tvheadend.105.crash Bengt Madeberg, 2016-11-16 00:26
bild1.png (58 KB) bild1.png Bengt Madeberg, 2016-11-17 11:12
bild2.png (36.8 KB) bild2.png Bengt Madeberg, 2016-11-17 11:12

History

#1

Updated by Jaroslav Kysela about 8 years ago

https://tvheadend.org/projects/tvheadend/wiki/Debugging - provide the backtrace of the crash...

#2

Updated by Bengt Madeberg about 8 years ago

Well, it seems that I have som issues to do the backtrace.

GNU gdb (Ubuntu 7.7.1-0ubuntu5~14.04.2) 7.7.1
Copyright (C) 2014 Free Software Foundation, Inc.
License GPLv3+: GNU GPL version 3 or later <http://gnu.org/licenses/gpl.html>
This is free software: you are free to change and redistribute it.
There is NO WARRANTY, to the extent permitted by law. Type "show copying"
and "show warranty" for details.
This GDB was configured as "x86_64-linux-gnu".
Type "show configuration" for configuration details.
For bug reporting instructions, please see:
<http://www.gnu.org/software/gdb/bugs/&gt;.
Find the GDB manual and other documentation resources online at:
<http://www.gnu.org/software/gdb/documentation/&gt;.
For help, type "help".
Type "apropos word" to search for commands related to "word"...
Reading symbols from /usr/bin/tvheadend...done.
"/home/muy/_usr_bin_tvheadend.105.crash" is not a core dump: File format not recognized
(gdb)

#3

Updated by Mark Clarkstone about 8 years ago

Bengt Madeberg wrote:

Well, it seems that I have som issues to do the backtrace.

GNU gdb (Ubuntu 7.7.1-0ubuntu5~14.04.2) 7.7.1
Copyright (C) 2014 Free Software Foundation, Inc.
License GPLv3+: GNU GPL version 3 or later <http://gnu.org/licenses/gpl.html>
This is free software: you are free to change and redistribute it.
There is NO WARRANTY, to the extent permitted by law. Type "show copying"
and "show warranty" for details.
This GDB was configured as "x86_64-linux-gnu".
Type "show configuration" for configuration details.
For bug reporting instructions, please see:
<http://www.gnu.org/software/gdb/bugs/&gt;.
Find the GDB manual and other documentation resources online at:
<http://www.gnu.org/software/gdb/documentation/&gt;.
For help, type "help".
Type "apropos word" to search for commands related to "word"...
Reading symbols from /usr/bin/tvheadend...done.
"/home/muy/_usr_bin_tvheadend.105.crash" is not a core dump: File format not recognized
(gdb)

The file attached is a ubuntu Apport log & not a core dump but it does contain (base64 encoded) one. Some info on debugging can be found here: https://wiki.ubuntu.com/DebuggingProgramCrash

If you're still stuck disable Apport and install corekeeper (dumps are in /var/crash).

#4

Updated by Jaroslav Kysela about 8 years ago

Just run tvheadend from command line but add 'gdb --args ' before the standard command line text. Then type 'r' (run) and wait for the crash. Then type 'bt' (backtrace).

#5

Updated by Bengt Madeberg about 8 years ago

Ok, thanks. I hope I got it right. 'bt' in the end...

hts@server3:~$ gdb --args /usr/bin/tvheadend -l /tmp/tvh.log --trace en50221,dvbcam
GNU gdb (Ubuntu 7.7.1-0ubuntu5~14.04.2) 7.7.1
Copyright (C) 2014 Free Software Foundation, Inc.
License GPLv3+: GNU GPL version 3 or later <http://gnu.org/licenses/gpl.html>
This is free software: you are free to change and redistribute it.
There is NO WARRANTY, to the extent permitted by law.  Type "show copying" 
and "show warranty" for details.
This GDB was configured as "x86_64-linux-gnu".
Type "show configuration" for configuration details.
For bug reporting instructions, please see:
<http://www.gnu.org/software/gdb/bugs/>.
Find the GDB manual and other documentation resources online at:
<http://www.gnu.org/software/gdb/documentation/>.
For help, type "help".
Type "apropos word" to search for commands related to "word"...
Reading symbols from /usr/bin/tvheadend...done.
(gdb) r
Starting program: /usr/bin/tvheadend -l /tmp/tvh.log --trace en50221,dvbcam
[Thread debugging using libthread_db enabled]
Using host libthread_db library "/lib/x86_64-linux-gnu/libthread_db.so.1".
[New Thread 0x7ffff5204700 (LWP 29457)]
2016-11-16 16:09:53.262 [   INFO] main: Log started
2016-11-16 16:09:53.262 [   INFO] http: Starting HTTP server 0.0.0.0:9981
2016-11-16 16:09:53.262 [   INFO] htsp: Starting HTSP server 0.0.0.0:9982
2016-11-16 16:09:53.262 [  ERROR] satips: use --satip_bindaddr parameter to select the local IP for SAT>IP
2016-11-16 16:09:53.262 [  ERROR] satips: using Google lookup (might block the task until timeout)
2016-11-16 16:09:53.277 [   INFO] satips: Starting SAT>IP RTSP server 192.168.0.93:9983
2016-11-16 16:09:53.277 [   INFO] satips: SAT>IP Server initialized
2016-11-16 16:09:53.277 [   INFO] satips:   HTTP 192.168.0.93:9981, RTSP 192.168.0.93:9983
2016-11-16 16:09:53.277 [   INFO] satips:   descramble 3, muxcnf 0
2016-11-16 16:09:53.277 [   INFO] satips:   tuner[fe=1]: DVB-T2 #1
[New Thread 0x7ffff4a03700 (LWP 29458)]
[New Thread 0x7ffff4202700 (LWP 29459)]
[New Thread 0x7ffff3a01700 (LWP 29460)]
2016-11-16 16:09:53.542 [   INFO] config: loaded
[New Thread 0x7ffff3200700 (LWP 29461)]
[New Thread 0x7ffff29ff700 (LWP 29462)]
2016-11-16 16:09:53.549 [   INFO] config: scanfile (re)initialization with path <none>
[New Thread 0x7ffff21fe700 (LWP 29463)]
[New Thread 0x7ffff19fd700 (LWP 29464)]
[New Thread 0x7ffff11fc700 (LWP 29465)]
[New Thread 0x7ffff09fb700 (LWP 29466)]
[New Thread 0x7fffe3fff700 (LWP 29467)]
2016-11-16 16:09:53.876 [   INFO] scanfile: DVB-S - loaded 1 regions with 112 networks
2016-11-16 16:09:53.876 [   INFO] scanfile: DVB-T - loaded 43 regions with 1106 networks
2016-11-16 16:09:53.876 [   INFO] scanfile: DVB-C - loaded 17 regions with 56 networks
2016-11-16 16:09:53.876 [   INFO] scanfile: ATSC-T - loaded 2 regions with 9 networks
2016-11-16 16:09:53.876 [   INFO] scanfile: ATSC-C - loaded 1 regions with 5 networks
2016-11-16 16:09:53.877 [   INFO] scanfile: ISDB-T - loaded 2 regions with 1297 networks
[New Thread 0x7fffe37fe700 (LWP 29470)]
[New Thread 0x7fffe2ffd700 (LWP 29471)]
2016-11-16 16:09:55.586 [   INFO] dvr: Creating new configuration ''
[New Thread 0x7fffe27fc700 (LWP 29472)]
[New Thread 0x7fffe1ffb700 (LWP 29473)]
[New Thread 0x7fffe17fa700 (LWP 29474)]
2016-11-16 16:09:55.592 [  ERROR] tcp: tcp_server_loop: tvhpoll_wait: Interrupted system call
[New Thread 0x7fffe0ff9700 (LWP 29475)]
2016-11-16 16:09:55.594 [   INFO] csa: Using SSE2 128bit parallel descrambling
2016-11-16 16:09:55.594 [   INFO] descrambler: adding CAID 2600 as constant crypto-word (BISS)
2016-11-16 16:09:55.594 [   INFO] epggrab: module eit created
2016-11-16 16:09:55.594 [   INFO] epggrab: module uk_freesat created
2016-11-16 16:09:55.595 [   INFO] epggrab: module uk_freeview created
2016-11-16 16:09:55.595 [   INFO] epggrab: module viasat_baltic created
2016-11-16 16:09:55.595 [   INFO] epggrab: module Bulsatcom_39E created
2016-11-16 16:09:55.595 [   INFO] epggrab: module psip created
2016-11-16 16:09:55.604 [   INFO] epggrab: module opentv-skyit created
2016-11-16 16:09:55.606 [   INFO] epggrab: module opentv-skynz created
2016-11-16 16:09:55.606 [   INFO] epggrab: module opentv-ausat created
2016-11-16 16:09:55.606 [   INFO] epggrab: module opentv-skyuk created
2016-11-16 16:09:55.608 [   INFO] epggrab: module pyepg created
2016-11-16 16:09:55.608 [   INFO] epggrab: module xmltv created
[New Thread 0x7fffd3fff700 (LWP 29477)]
2016-11-16 16:09:55.613 [  ERROR] tcp: tcp_server_loop: tvhpoll_wait: Interrupted system call
2016-11-16 16:09:55.613 [   INFO] epgdb: gzip format detected, inflating (ratio 13.2% deflated size 117304)
2016-11-16 16:09:55.618 [   INFO] epgdb: parsing 886343 bytes
2016-11-16 16:09:55.675 [   INFO] epgdb: loaded v2
[New Thread 0x7fffd37fe700 (LWP 29478)]
2016-11-16 16:09:55.675 [   INFO] epgdb:   config     1
2016-11-16 16:09:55.675 [   INFO] epgdb:   brands     0
2016-11-16 16:09:55.675 [   INFO] epgdb:   seasons    365
2016-11-16 16:09:55.675 [   INFO] epgdb:   episodes   1382
2016-11-16 16:09:55.675 [   INFO] epgdb:   broadcasts 1734
2016-11-16 16:09:55.677 [  ERROR] tcp: tcp_server_loop: tvhpoll_wait: Interrupted system call
[New Thread 0x7fffd2ffd700 (LWP 29479)]
[New Thread 0x7fffd27fc700 (LWP 29480)]
2016-11-16 16:09:55.682 [ NOTICE] START: HTS Tvheadend version 4.1-2322~g879d532 started, running as PID:29453 UID:105 GID:112, CWD:/home/hts CNF:/home/hts/.hts/tvheadend
[New Thread 0x7fffd1ffb700 (LWP 29482)]
[New Thread 0x7fffd17fa700 (LWP 29481)]
2016-11-16 16:09:55.686 [  ERROR] tcp: tcp_server_loop: tvhpoll_wait: Interrupted system call
[New Thread 0x7fffd0ff9700 (LWP 29483)]
2016-11-16 16:09:55.688 [  ERROR] tcp: tcp_server_loop: tvhpoll_wait: Interrupted system call
[New Thread 0x7fffc7fff700 (LWP 29484)]
2016-11-16 16:09:55.691 [  ERROR] tcp: tcp_server_loop: tvhpoll_wait: Interrupted system call
[Thread 0x7fffd1ffb700 (LWP 29482) exited]
[New Thread 0x7fffd1ffb700 (LWP 29498)]
[New Thread 0x7fffc77fe700 (LWP 29499)]
2016-11-16 16:10:08.663 [  ERROR] tcp: tcp_server_loop: tvhpoll_wait: Interrupted system call
2016-11-16 16:10:10.664 [ NOTICE] subscription: 0002: No input source available for subscription "SAT>IP" to mux "570MHz in Teracom" 
2016-11-16 16:10:10.664 [ NOTICE] subscription: 0003: No input source available for subscription "SAT>IP Slave/Teracom/570MHz/TV4 HD Göteborg" to service "Teracom/570MHz/TV4 HD Göteborg" in mux "570MHz in Teracom" 
2016-11-16 16:10:12.664 [ NOTICE] subscription: 0002: No input source available for subscription "SAT>IP" to mux "570MHz in Teracom" 
2016-11-16 16:10:12.664 [ NOTICE] subscription: 0003: No input source available for subscription "SAT>IP Slave/Teracom/570MHz/TV4 HD Göteborg" to service "Teracom/570MHz/TV4 HD Göteborg" in mux "570MHz in Teracom" 
2016-11-16 16:10:14.664 [ NOTICE] subscription: 0002: No input source available for subscription "SAT>IP" to mux "570MHz in Teracom" 
2016-11-16 16:10:14.664 [ NOTICE] subscription: 0003: No input source available for subscription "SAT>IP Slave/Teracom/570MHz/TV4 HD Göteborg" to service "Teracom/570MHz/TV4 HD Göteborg" in mux "570MHz in Teracom" 
2016-11-16 16:10:14.711 [  ERROR] tcp: tcp_server_loop: tvhpoll_wait: Interrupted system call
[Thread 0x7fffc77fe700 (LWP 29499) exited]
2016-11-16 16:10:14.712 [   INFO] subscription: 0003: "SAT>IP Slave/Teracom/570MHz/TV4 HD Göteborg" unsubscribing, client="SAT>IP Slave/Teracom/570MHz/TV4 HD Göteborg" 
2016-11-16 16:10:14.712 [   INFO] subscription: 0002: "SAT>IP" unsubscribing, hostname="192.168.0.180" 
[Thread 0x7fffd1ffb700 (LWP 29498) exited]

^C
Program received signal SIGINT, Interrupt.
pthread_cond_timedwait@@GLIBC_2.3.2 () at ../nptl/sysdeps/unix/sysv/linux/x86_64/pthread_cond_timedwait.S:238
238     ../nptl/sysdeps/unix/sysv/linux/x86_64/pthread_cond_timedwait.S: No such file or directory.
(gdb) bt
#0  pthread_cond_timedwait@@GLIBC_2.3.2 () at ../nptl/sysdeps/unix/sysv/linux/x86_64/pthread_cond_timedwait.S:238
#1  0x00005555556e5497 in mainloop () at src/main.c:715
#2  main (argc=<optimized out>, argv=<optimized out>) at src/main.c:1286

#6

Updated by Jaroslav Kysela about 8 years ago

The only problem is that tvh didn't crash in the session above :-(

#7

Updated by Bengt Madeberg about 8 years ago

Ok, I did some mistakes in the startup...ok now

muy@server3:~$ sudo gdb --args /usr/bin/tvheadend -u hts -g video
GNU gdb (Ubuntu 7.7.1-0ubuntu5~14.04.2) 7.7.1
Copyright (C) 2014 Free Software Foundation, Inc.
License GPLv3+: GNU GPL version 3 or later <http://gnu.org/licenses/gpl.html>
This is free software: you are free to change and redistribute it.
There is NO WARRANTY, to the extent permitted by law.  Type "show copying" 
and "show warranty" for details.
This GDB was configured as "x86_64-linux-gnu".
Type "show configuration" for configuration details.
For bug reporting instructions, please see:
<http://www.gnu.org/software/gdb/bugs/>.
Find the GDB manual and other documentation resources online at:
<http://www.gnu.org/software/gdb/documentation/>.
For help, type "help".
Type "apropos word" to search for commands related to "word"...
Reading symbols from /usr/bin/tvheadend...done.
(gdb) r
Starting program: /usr/bin/tvheadend -u hts -g video
[Thread debugging using libthread_db enabled]
Using host libthread_db library "/lib/x86_64-linux-gnu/libthread_db.so.1".
[New Thread 0x7ffff49c9700 (LWP 30715)]
2016-11-16 16:25:37.698 [   INFO] main: Log started
2016-11-16 16:25:37.741 [   INFO] http: Starting HTTP server 0.0.0.0:9981
2016-11-16 16:25:37.742 [   INFO] htsp: Starting HTSP server 0.0.0.0:9982
2016-11-16 16:25:37.742 [  ERROR] satips: use --satip_bindaddr parameter to select the local IP for SAT>IP
2016-11-16 16:25:37.742 [  ERROR] satips: using Google lookup (might block the task until timeout)
2016-11-16 16:25:37.758 [   INFO] satips: Starting SAT>IP RTSP server 192.168.0.93:9983
2016-11-16 16:25:37.758 [   INFO] satips: SAT>IP Server initialized
2016-11-16 16:25:37.758 [   INFO] satips:   HTTP 192.168.0.93:9981, RTSP 192.168.0.93:9983
2016-11-16 16:25:37.758 [   INFO] satips:   descramble 3, muxcnf 0
2016-11-16 16:25:37.758 [   INFO] satips:   tuner[fe=1]: DVB-T2 #1
[New Thread 0x7ffff41c8700 (LWP 30717)]
[New Thread 0x7ffff39c7700 (LWP 30718)]
[New Thread 0x7ffff31c6700 (LWP 30719)]
2016-11-16 16:25:38.020 [   INFO] config: loaded
[New Thread 0x7ffff29c5700 (LWP 30720)]
[New Thread 0x7ffff21c4700 (LWP 30721)]
2016-11-16 16:25:38.024 [   INFO] config: scanfile (re)initialization with path <none>
[New Thread 0x7ffff19c3700 (LWP 30722)]
[New Thread 0x7ffff11c2700 (LWP 30723)]
[New Thread 0x7ffff09c1700 (LWP 30724)]
[New Thread 0x7fffe3fff700 (LWP 30725)]
[New Thread 0x7fffe37fe700 (LWP 30726)]
2016-11-16 16:25:38.046 [   INFO] linuxdvb: adapter added /dev/dvb/adapter0
[New Thread 0x7fffe2ffd700 (LWP 30728)]
[New Thread 0x7fffe27fc700 (LWP 30729)]
2016-11-16 16:25:38.055 [   INFO] dvr: Creating new configuration ''
[New Thread 0x7fffe1ffb700 (LWP 30730)]
[New Thread 0x7fffe17fa700 (LWP 30731)]
[New Thread 0x7fffe0ff9700 (LWP 30732)]
[New Thread 0x7fffd3fff700 (LWP 30733)]
2016-11-16 16:25:38.066 [   INFO] csa: Using SSE2 128bit parallel descrambling
2016-11-16 16:25:38.066 [  ERROR] tcp: tcp_server_loop: tvhpoll_wait: Interrupted system call
2016-11-16 16:25:38.067 [   INFO] descrambler: adding CAID 2600 as constant crypto-word (BISS)
2016-11-16 16:25:38.067 [   INFO] epggrab: module eit created
2016-11-16 16:25:38.067 [   INFO] epggrab: module uk_freesat created
2016-11-16 16:25:38.067 [   INFO] epggrab: module uk_freeview created
2016-11-16 16:25:38.067 [   INFO] epggrab: module viasat_baltic created
2016-11-16 16:25:38.067 [   INFO] epggrab: module Bulsatcom_39E created
2016-11-16 16:25:38.067 [   INFO] epggrab: module psip created
2016-11-16 16:25:38.074 [   INFO] epggrab: module opentv-skyit created
2016-11-16 16:25:38.076 [   INFO] epggrab: module opentv-skynz created
2016-11-16 16:25:38.076 [   INFO] epggrab: module opentv-ausat created
2016-11-16 16:25:38.076 [   INFO] epggrab: module opentv-skyuk created
2016-11-16 16:25:38.078 [   INFO] epggrab: module pyepg created
2016-11-16 16:25:38.078 [   INFO] epggrab: module xmltv created
[New Thread 0x7fffd37fe700 (LWP 30734)]
2016-11-16 16:25:38.082 [  ERROR] tcp: tcp_server_loop: tvhpoll_wait: Interrupted system call
2016-11-16 16:25:38.086 [   INFO] epgdb: gzip format detected, inflating (ratio 13.3% deflated size 117270)
2016-11-16 16:25:38.092 [   INFO] epgdb: parsing 883940 bytes
2016-11-16 16:25:38.144 [   INFO] epgdb: loaded v2
2016-11-16 16:25:38.145 [   INFO] epgdb:   config     1
2016-11-16 16:25:38.145 [   INFO] epgdb:   brands     0
2016-11-16 16:25:38.145 [   INFO] epgdb:   seasons    363
2016-11-16 16:25:38.145 [   INFO] epgdb:   episodes   1379
2016-11-16 16:25:38.145 [   INFO] epgdb:   broadcasts 1738
[New Thread 0x7fffd2ffd700 (LWP 30735)]
2016-11-16 16:25:38.148 [  ERROR] tcp: tcp_server_loop: tvhpoll_wait: Interrupted system call
[New Thread 0x7fffd27fc700 (LWP 30736)]
2016-11-16 16:25:38.149 [  ERROR] tcp: tcp_server_loop: tvhpoll_wait: Interrupted system call
[New Thread 0x7fffd1ffb700 (LWP 30737)]
2016-11-16 16:25:38.151 [  ERROR] tcp: tcp_server_loop: tvhpoll_wait: Interrupted system call
2016-11-16 16:25:38.153 [ NOTICE] START: HTS Tvheadend version 4.1-2322~g879d532 started, running as PID:30711 UID:105 GID:44, CWD:/home/muy CNF:/home/hts/.hts/tvheadend
[New Thread 0x7fffd17fa700 (LWP 30738)]
2016-11-16 16:25:38.155 [  ERROR] tcp: tcp_server_loop: tvhpoll_wait: Interrupted system call
[New Thread 0x7fffd0ff9700 (LWP 30739)]
2016-11-16 16:25:38.157 [  ERROR] tcp: tcp_server_loop: tvhpoll_wait: Interrupted system call
[New Thread 0x7fffc3fff700 (LWP 30740)]
2016-11-16 16:25:38.160 [  ERROR] tcp: tcp_server_loop: tvhpoll_wait: Interrupted system call
[New Thread 0x7fffc37fe700 (LWP 30741)]
2016-11-16 16:25:38.162 [  ERROR] tcp: tcp_server_loop: tvhpoll_wait: Interrupted system call
[New Thread 0x7fffc2ffd700 (LWP 30742)]
2016-11-16 16:25:38.164 [  ERROR] tcp: tcp_server_loop: tvhpoll_wait: Interrupted system call
2016-11-16 16:25:38.272 [ NOTICE] linuxdvb: CAM slot 0 status changed to module present
2016-11-16 16:25:38.388 [   INFO] scanfile: DVB-S - loaded 1 regions with 112 networks
2016-11-16 16:25:38.388 [   INFO] scanfile: DVB-T - loaded 43 regions with 1106 networks
2016-11-16 16:25:38.388 [   INFO] scanfile: DVB-C - loaded 17 regions with 56 networks
2016-11-16 16:25:38.388 [   INFO] scanfile: ATSC-T - loaded 2 regions with 9 networks
2016-11-16 16:25:38.388 [   INFO] scanfile: ATSC-C - loaded 1 regions with 5 networks
2016-11-16 16:25:38.388 [   INFO] scanfile: ISDB-T - loaded 2 regions with 1297 networks
[New Thread 0x7fffc27fc700 (LWP 30743)]
[Thread 0x7fffc27fc700 (LWP 30743) exited]
2016-11-16 16:25:39.023 [   INFO] mpegts: 570MHz in Teracom - tuning on TurboSight TBS 5881 DVBT/T2 frontend : DVB-T #0
2016-11-16 16:25:41.687 [   INFO] subscription: 0001: "epggrab" subscribing to mux "570MHz", weight: 4, adapter: "TurboSight TBS 5881 DVBT/T2 frontend : DVB-T #0", network: "Teracom", service: "Raw PID Subscription" 
2016-11-16 16:25:41.687 [ NOTICE] linuxdvb: CAM slot 0 status changed to module ready
[New Thread 0x7fffc27fc700 (LWP 30747)]
2016-11-16 16:25:41.692 [  ERROR] tcp: tcp_server_loop: tvhpoll_wait: Interrupted system call
[New Thread 0x7fffc1ffb700 (LWP 30749)]
2016-11-16 16:25:42.360 [  ERROR] tcp: tcp_server_loop: tvhpoll_wait: Interrupted system call
2016-11-16 16:25:42.935 [   INFO] en50221: setting CI+ CAM data rate to 72 Mbps
2016-11-16 16:25:43.254 [WARNING] linuxdvb: TurboSight TBS 5881 DVBT/T2 frontend : DVB-T #0 - poll TIMEOUT
2016-11-16 16:25:43.345 [   INFO] en50221: CAM slot 0: Application type: 01, manufacturer: cafe, Manufacturer code: babe
2016-11-16 16:25:43.345 [   INFO] en50221: CAM slot 0: Menu string: Viaccess Access
[New Thread 0x7fffc17fa700 (LWP 30756)]
2016-11-16 16:25:48.004 [   INFO] subscription: 0002: "SAT>IP" subscribing to mux "570MHz", weight: 150, adapter: "TurboSight TBS 5881 DVBT/T2 frontend : DVB-T #0", network: "Teracom", service: "Raw PID Subscription", hostname="192.168.0.180" 
[New Thread 0x7fffc0ff9700 (LWP 30757)]
2016-11-16 16:25:48.009 [  ERROR] tcp: tcp_server_loop: tvhpoll_wait: Interrupted system call
2016-11-16 16:25:49.565 [   INFO] en50221: CAM slot 0 supported CAIDs:  0500 (Viaccess) 0502 (Viaccess)
2016-11-16 16:25:53.356 [  ERROR] tcp: tcp_server_loop: tvhpoll_wait: Interrupted system call
[Thread 0x7fffc0ff9700 (LWP 30757) exited]
2016-11-16 16:25:53.357 [   INFO] subscription: 0002: "SAT>IP" unsubscribing, hostname="192.168.0.180" 
[New Thread 0x7fffc0ff9700 (LWP 30763)]
2016-11-16 16:25:53.362 [  ERROR] tcp: tcp_server_loop: tvhpoll_wait: Interrupted system call
2016-11-16 16:25:53.363 [   INFO] subscription: 0003: "SAT>IP" subscribing to mux "570MHz", weight: 150, adapter: "TurboSight TBS 5881 DVBT/T2 frontend : DVB-T #0", network: "Teracom", service: "Raw PID Subscription", hostname="192.168.0.180" 
2016-11-16 16:25:53.363 [   INFO] subscription: 0004: "SAT>IP Slave/Teracom/570MHz/TV4 HD Göteborg" subscribing to service "Teracom/570MHz/TV4 HD Göteborg", weight: 100, adapter: "TurboSight TBS 5881 DVBT/T2 frontend : DVB-T #0", network: "Teracom", mux: "570MHz", provider: "TV4 AB", client="SAT>IP Slave/Teracom/570MHz/TV4 HD Göteborg" 

Program received signal SIGSEGV, Segmentation fault.
[Switching to Thread 0x7fffc3fff700 (LWP 30740)]
ts_recv_skipped0 (len=<optimized out>, tsb=<optimized out>, st=<optimized out>, t=<optimized out>) at src/input/mpegts/tsdemux.c:167
167           if (mpegts_pid_rexists(t->s_slaves_pids, pid))
(gdb) bt
#0  ts_recv_skipped0 (len=<optimized out>, tsb=<optimized out>, st=<optimized out>, t=<optimized out>) at src/input/mpegts/tsdemux.c:167
#1  ts_skip_packet2 (t=0x555556b25300,
    tsb=tsb@entry=0x7fffb4008ead "G\031ۖ%\215\061\030\356\232L\250ۘ\a\223\256\037\253\231\036-\032\065\033\342gA\225Mspx\v\247\036\370\204\260\357D\"y`W\224\365,\320Q#S\227\017\371\211y\333W\376?a\320?\030\325,\270\065W\243\246M\345\345\026\355vLE'\021\036\037\326ޣM\216L\340WcZ\343\032\301\366?\226\322\351\204\346\272\345\323\001\351\023\262\215\206* W\347t0\351g\367\224\304iA\357hL\001Ӈ&\t\036\224\246\350'\317\302\212\276\247\020\326v\262\322\335", <incomplete sequence \355>, len=len@entry=1128) at src/input/mpegts/tsdemux.c:284
#2  0x00005555557908d9 in descrambler_descramble (t=t@entry=0x555556b25300, st=st@entry=0x555556b25760,
    tsb=tsb@entry=0x7fffb4008ead "G\031ۖ%\215\061\030\356\232L\250ۘ\a\223\256\037\253\231\036-\032\065\033\342gA\225Mspx\v\247\036\370\204\260\357D\"y`W\224\365,\320Q#S\227\017\371\211y\333W\376?a\320?\030\325,\270\065W\243\246M\345\345\026\355vLE'\021\036\037\326ޣM\216L\340WcZ\343\032\301\366?\226\322\351\204\346\272\345\323\001\351\023\262\215\206* W\347t0\351g\367\224\304iA\357hL\001Ӈ&\t\036\224\246\350'\317\302\212\276\247\020\326v\262\322\335", <incomplete sequence \355>, len=len@entry=1128) at src/descrambler/descrambler.c:842
#3  0x0000555555798ba5 in ts_recv_packet1 (t=0x555556b25300,
    tsb=tsb@entry=0x7fffb4008ead "G\031ۖ%\215\061\030\356\232L\250ۘ\a\223\256\037\253\231\036-\032\065\033\342gA\225Mspx\v\247\036\370\204\260\357D\"y`W\224\365,\320Q#S\227\017\371\211y\333W\376?a\320?\030\325,\270\065W\243\246M\345\345\026\355vLE'\021\036\037\326ޣM\216L\340WcZ\343\032\301\366?\226\322\351\204\346\272\345\323\001\351\023\262\215\206* W\347t0\351g\367\224\304iA\357hL\001Ӈ&\t\036\224\246\350'\317\302\212\276\247\020\326v\262\322\335", <incomplete sequence \355>, len=len@entry=1128, table=1) at src/input/mpegts/tsdemux.c:232
#4  0x000055555579678e in mpegts_input_process (mpkt=<optimized out>, mi=<optimized out>) at src/input/mpegts/mpegts_input.c:1365
#5  mpegts_input_thread (p=<optimized out>) at src/input/mpegts/mpegts_input.c:1506
#6  0x00005555556f20b2 in thread_wrapper (p=0x7fffc8000a30) at src/wrappers.c:159
#7  0x00007ffff62c3184 in start_thread (arg=0x7fffc3fff700) at pthread_create.c:312
#8  0x00007ffff57de37d in clone () at ../sysdeps/unix/sysv/linux/x86_64/clone.S:111
(gdb)

#8

Updated by Jaroslav Kysela about 8 years ago

Could you compile tvh binary with --enable-ccdebug and provide another backtrace like this one ?

#9

Updated by Bengt Madeberg about 8 years ago

Hop I got it ok ? I changed in the configre file and did a new autobild.

muy@server3:~$ sudo gdb --args /usr/bin/tvheadend -u hts -g video
GNU gdb (Ubuntu 7.7.1-0ubuntu5~14.04.2) 7.7.1
Copyright (C) 2014 Free Software Foundation, Inc.
License GPLv3+: GNU GPL version 3 or later <http://gnu.org/licenses/gpl.html>
This is free software: you are free to change and redistribute it.
There is NO WARRANTY, to the extent permitted by law.  Type "show copying" 
and "show warranty" for details.
This GDB was configured as "x86_64-linux-gnu".
Type "show configuration" for configuration details.
For bug reporting instructions, please see:
<http://www.gnu.org/software/gdb/bugs/>.
Find the GDB manual and other documentation resources online at:
<http://www.gnu.org/software/gdb/documentation/>.
For help, type "help".
Type "apropos word" to search for commands related to "word"...
Reading symbols from /usr/bin/tvheadend...done.
(gdb) r
Starting program: /usr/bin/tvheadend -u hts -g video
[Thread debugging using libthread_db enabled]
Using host libthread_db library "/lib/x86_64-linux-gnu/libthread_db.so.1".
[New Thread 0x7ffff49c9700 (LWP 7404)]
2016-11-16 17:15:17.529 [   INFO] main: Log started
2016-11-16 17:15:17.572 [   INFO] http: Starting HTTP server 0.0.0.0:9981
2016-11-16 17:15:17.572 [   INFO] htsp: Starting HTSP server 0.0.0.0:9982
2016-11-16 17:15:17.572 [  ERROR] satips: use --satip_bindaddr parameter to select the local IP for SAT>IP
2016-11-16 17:15:17.572 [  ERROR] satips: using Google lookup (might block the task until timeout)
2016-11-16 17:15:17.589 [   INFO] satips: Starting SAT>IP RTSP server 192.168.0.93:9983
2016-11-16 17:15:17.589 [   INFO] satips: SAT>IP Server initialized
2016-11-16 17:15:17.589 [   INFO] satips:   HTTP 192.168.0.93:9981, RTSP 192.168.0.93:9983
2016-11-16 17:15:17.589 [   INFO] satips:   descramble 3, muxcnf 0
2016-11-16 17:15:17.589 [   INFO] satips:   tuner[fe=1]: DVB-T2 #1
[New Thread 0x7ffff41c8700 (LWP 7405)]
[New Thread 0x7ffff39c7700 (LWP 7406)]
[New Thread 0x7ffff31c6700 (LWP 7407)]
2016-11-16 17:15:17.838 [   INFO] config: loaded
[New Thread 0x7ffff29c5700 (LWP 7408)]
[New Thread 0x7ffff21c4700 (LWP 7409)]
2016-11-16 17:15:17.842 [   INFO] config: scanfile (re)initialization with path <none>
[New Thread 0x7ffff19c3700 (LWP 7410)]
[New Thread 0x7ffff11c2700 (LWP 7411)]
[New Thread 0x7ffff09c1700 (LWP 7412)]
[New Thread 0x7fffe3fff700 (LWP 7413)]
[New Thread 0x7fffe37fe700 (LWP 7414)]
2016-11-16 17:15:17.865 [   INFO] linuxdvb: adapter added /dev/dvb/adapter0
[New Thread 0x7fffe2ffd700 (LWP 7415)]
[New Thread 0x7fffe27fc700 (LWP 7416)]
2016-11-16 17:15:17.875 [   INFO] dvr: Creating new configuration ''
[New Thread 0x7fffe1ffb700 (LWP 7417)]
[New Thread 0x7fffe17fa700 (LWP 7418)]
[New Thread 0x7fffe0ff9700 (LWP 7419)]
2016-11-16 17:15:17.884 [  ERROR] tcp: tcp_server_loop: tvhpoll_wait: Interrupted system call
[New Thread 0x7fffd3fff700 (LWP 7420)]
2016-11-16 17:15:17.887 [   INFO] csa: Using SSE2 128bit parallel descrambling
2016-11-16 17:15:17.887 [  ERROR] tcp: tcp_server_loop: tvhpoll_wait: Interrupted system call
2016-11-16 17:15:17.887 [   INFO] descrambler: adding CAID 2600 as constant crypto-word (BISS)
2016-11-16 17:15:17.887 [   INFO] epggrab: module eit created
2016-11-16 17:15:17.887 [   INFO] epggrab: module uk_freesat created
2016-11-16 17:15:17.887 [   INFO] epggrab: module uk_freeview created
2016-11-16 17:15:17.887 [   INFO] epggrab: module viasat_baltic created
2016-11-16 17:15:17.887 [   INFO] epggrab: module Bulsatcom_39E created
2016-11-16 17:15:17.887 [   INFO] epggrab: module psip created
2016-11-16 17:15:17.897 [   INFO] epggrab: module opentv-skyit created
2016-11-16 17:15:17.899 [   INFO] epggrab: module opentv-skynz created
2016-11-16 17:15:17.900 [   INFO] epggrab: module opentv-ausat created
2016-11-16 17:15:17.900 [   INFO] epggrab: module opentv-skyuk created
2016-11-16 17:15:17.902 [   INFO] epggrab: module pyepg created
2016-11-16 17:15:17.902 [   INFO] epggrab: module xmltv created
[New Thread 0x7fffd37fe700 (LWP 7421)]
2016-11-16 17:15:17.906 [  ERROR] tcp: tcp_server_loop: tvhpoll_wait: Interrupted system call
2016-11-16 17:15:17.906 [   INFO] epgdb: gzip format detected, inflating (ratio 13.1% deflated size 116160)
2016-11-16 17:15:17.912 [   INFO] epgdb: parsing 888921 bytes
2016-11-16 17:15:17.986 [   INFO] epgdb: loaded v2
2016-11-16 17:15:17.987 [   INFO] epgdb:   config     1
2016-11-16 17:15:17.987 [   INFO] epgdb:   brands     0
2016-11-16 17:15:17.987 [   INFO] epgdb:   seasons    365
2016-11-16 17:15:17.987 [   INFO] epgdb:   episodes   1399
2016-11-16 17:15:17.987 [   INFO] epgdb:   broadcasts 1754
[New Thread 0x7fffd2ffd700 (LWP 7422)]
2016-11-16 17:15:17.991 [  ERROR] tcp: tcp_server_loop: tvhpoll_wait: Interrupted system call
[New Thread 0x7fffd27fc700 (LWP 7423)]
2016-11-16 17:15:17.993 [  ERROR] tcp: tcp_server_loop: tvhpoll_wait: Interrupted system call
[New Thread 0x7fffd1ffb700 (LWP 7424)]
2016-11-16 17:15:17.995 [  ERROR] tcp: tcp_server_loop: tvhpoll_wait: Interrupted system call
2016-11-16 17:15:17.997 [ NOTICE] START: HTS Tvheadend version 4.1-2322~g879d532-dirty started, running as PID:7399 UID:105 GID:44, CWD:/home/muy CNF:/home/hts/.hts/tvheadend
[New Thread 0x7fffd17fa700 (LWP 7425)]
2016-11-16 17:15:17.999 [  ERROR] tcp: tcp_server_loop: tvhpoll_wait: Interrupted system call
[New Thread 0x7fffd0ff9700 (LWP 7426)]
2016-11-16 17:15:18.002 [  ERROR] tcp: tcp_server_loop: tvhpoll_wait: Interrupted system call
[New Thread 0x7fffc3fff700 (LWP 7427)]
2016-11-16 17:15:18.004 [  ERROR] tcp: tcp_server_loop: tvhpoll_wait: Interrupted system call
[New Thread 0x7fffc37fe700 (LWP 7428)]
2016-11-16 17:15:18.007 [  ERROR] tcp: tcp_server_loop: tvhpoll_wait: Interrupted system call
[New Thread 0x7fffc2ffd700 (LWP 7429)]
2016-11-16 17:15:18.009 [  ERROR] tcp: tcp_server_loop: tvhpoll_wait: Interrupted system call
2016-11-16 17:15:18.090 [ NOTICE] linuxdvb: CAM slot 0 status changed to module present
2016-11-16 17:15:18.313 [   INFO] scanfile: DVB-S - loaded 1 regions with 112 networks
2016-11-16 17:15:18.313 [   INFO] scanfile: DVB-T - loaded 43 regions with 1106 networks
2016-11-16 17:15:18.313 [   INFO] scanfile: DVB-C - loaded 17 regions with 56 networks
2016-11-16 17:15:18.313 [   INFO] scanfile: ATSC-T - loaded 2 regions with 9 networks
2016-11-16 17:15:18.313 [   INFO] scanfile: ATSC-C - loaded 1 regions with 5 networks
2016-11-16 17:15:18.313 [   INFO] scanfile: ISDB-T - loaded 2 regions with 1297 networks
2016-11-16 17:15:18.841 [   INFO] mpegts: 570MHz in Teracom - tuning on TurboSight TBS 5881 DVBT/T2 frontend : DVB-T #0
2016-11-16 17:15:21.303 [   INFO] subscription: 0001: "epggrab" subscribing to mux "570MHz", weight: 4, adapter: "TurboSight TBS 5881 DVBT/T2 frontend : DVB-T #0", network: "Teracom", service: "Raw PID Subscription" 
2016-11-16 17:15:21.303 [ NOTICE] linuxdvb: CAM slot 0 status changed to module ready
[New Thread 0x7fffc27fc700 (LWP 7434)]
2016-11-16 17:15:21.309 [  ERROR] tcp: tcp_server_loop: tvhpoll_wait: Interrupted system call
[New Thread 0x7fffc1ffb700 (LWP 7435)]
[New Thread 0x7fffc17fa700 (LWP 7436)]
2016-11-16 17:15:22.126 [   INFO] subscription: 0002: "SAT>IP" subscribing to mux "570MHz", weight: 150, adapter: "TurboSight TBS 5881 DVBT/T2 frontend : DVB-T #0", network: "Teracom", service: "Raw PID Subscription", hostname="192.168.0.180" 
[New Thread 0x7fffc0ff9700 (LWP 7437)]
2016-11-16 17:15:22.128 [  ERROR] tcp: tcp_server_loop: tvhpoll_wait: Interrupted system call
2016-11-16 17:15:22.559 [   INFO] en50221: setting CI+ CAM data rate to 72 Mbps
2016-11-16 17:15:22.880 [WARNING] linuxdvb: TurboSight TBS 5881 DVBT/T2 frontend : DVB-T #0 - poll TIMEOUT
2016-11-16 17:15:22.969 [   INFO] en50221: CAM slot 0: Application type: 01, manufacturer: cafe, Manufacturer code: babe
2016-11-16 17:15:22.969 [   INFO] en50221: CAM slot 0: Menu string: Viaccess Access
2016-11-16 17:15:28.034 [  ERROR] tcp: tcp_server_loop: tvhpoll_wait: Interrupted system call
2016-11-16 17:15:28.035 [   INFO] subscription: 0002: "SAT>IP" unsubscribing, hostname="192.168.0.180" 
[Thread 0x7fffc0ff9700 (LWP 7437) exited]
[New Thread 0x7fffc0ff9700 (LWP 7444)]
2016-11-16 17:15:28.042 [  ERROR] tcp: tcp_server_loop: tvhpoll_wait: Interrupted system call
2016-11-16 17:15:28.043 [   INFO] subscription: 0003: "SAT>IP" subscribing to mux "570MHz", weight: 150, adapter: "TurboSight TBS 5881 DVBT/T2 frontend : DVB-T #0", network: "Teracom", service: "Raw PID Subscription", hostname="192.168.0.180" 
2016-11-16 17:15:28.043 [   INFO] subscription: 0004: "SAT>IP Slave/Teracom/570MHz/TV4 HD Göteborg" subscribing to service "Teracom/570MHz/TV4 HD Göteborg", weight: 100, adapter: "TurboSight TBS 5881 DVBT/T2 frontend : DVB-T #0", network: "Teracom", mux: "570MHz", provider: "TV4 AB", client="SAT>IP Slave/Teracom/570MHz/TV4 HD Göteborg" 

Program received signal SIGSEGV, Segmentation fault.
[Switching to Thread 0x7fffc3fff700 (LWP 7427)]
0x00005555557ee273 in mpegts_pid_rexists (pids=0x0, pid=6619) at /home/muy/tvh_20161116/tvheadend/src/input/mpegts.h:111
111       { return pids->all || mpegts_pid_find_rindex(pids, pid) >= 0; }
(gdb) bt
#0  0x00005555557ee273 in mpegts_pid_rexists (pids=0x0, pid=6619) at /home/muy/tvh_20161116/tvheadend/src/input/mpegts.h:111
#1  0x00005555557ee884 in ts_recv_skipped0 (t=0x555556be5300, st=0x555556be5760,
    tsb=0x7fffb40053ed "G\031\333\334U\250}It\372\372\213l\354\254fL;s\001F\217\251\333S\377֫\361U=W\311Eh7\303\067\032g\312\346u\354n\021\220/0(\025@>(\303?,ZV\351\024;\006N\265@t}\253\v!-\311\313_wE\\\260S\033\226hZ\240\373\301Yq\237vv\r\f~\036\301\202b1V \267<\345\355e\177o\205\212\f\376\377\303\023_\026\024\206Ƚ\342\211.ߕ~\"h\223\230\257\022\362\217,,\003\273\026\017$bj\377\304R\231\360\262\025\240\067\370\306\364\317X\245\262\222:x\244\273}\031\313q6\253\023\361\064-", <incomplete sequence \337>, len=1504) at src/input/mpegts/tsdemux.c:167
#2  0x00005555557eeca8 in ts_skip_packet2 (t=0x555556be5300,
    tsb=0x7fffb40053ed "G\031\333\334U\250}It\372\372\213l\354\254fL;s\001F\217\251\333S\377֫\361U=W\311Eh7\303\067\032g\312\346u\354n\021\220/0(\025@>(\303?,ZV\351\024;\006N\265@t}\253\v!-\311\313_wE\\\260S\033\226hZ\240\373\301Yq\237vv\r\f~\036\301\202b1V \267<\345\355e\177o\205\212\f\376\377\303\023_\026\024\206Ƚ\342\211.ߕ~\"h\223\230\257\022\362\217,,\003\273\026\017$bj\377\304R\231\360\262\025\240\067\370\306\364\317X\245\262\222:x\244\273}\031\313q6\253\023\361\064-", <incomplete sequence \337>, len=1504) at src/input/mpegts/tsdemux.c:284
#3  0x00005555557e1516 in descrambler_descramble (t=0x555556be5300, st=0x555556be5760,
    tsb=0x7fffb40053ed "G\031\333\334U\250}It\372\372\213l\354\254fL;s\001F\217\251\333S\377֫\361U=W\311Eh7\303\067\032g\312\346u\354n\021\220/0(\025@>(\303?,ZV\351\024;\006N\265@t}\253\v!-\311\313_wE\\\260S\033\226hZ\240\373\301Yq\237vv\r\f~\036\301\202b1V \267<\345\355e\177o\205\212\f\376\377\303\023_\026\024\206Ƚ\342\211.ߕ~\"h\223\230\257\022\362\217,,\003\273\026\017$bj\377\304R\231\360\262\025\240\067\370\306\364\317X\245\262\222:x\244\273}\031\313q6\253\023\361\064-", <incomplete sequence \337>, len=1504) at src/descrambler/descrambler.c:842
#4  0x00005555557eead8 in ts_recv_packet1 (t=0x555556be5300,
    tsb=0x7fffb40053ed "G\031\333\334U\250}It\372\372\213l\354\254fL;s\001F\217\251\333S\377֫\361U=W\311Eh7\303\067\032g\312\346u\354n\021\220/0(\025@>(\303?,ZV\351\024;\006N\265@t}\253\v!-\311\313_wE\\\260S\033\226hZ\240\373\301Yq\237vv\r\f~\036\301\202b1V \267<\345\355e\177o\205\212\f\376\377\303\023_\026\024\206Ƚ\342\211.ߕ~\"h\223\230\257\022\362\217,,\003\273\026\017$bj\377\304R\231\360\262\025\240\067\370\306\364\317X\245\262\222:x\244\273}\031\313q6\253\023\361\064-", <incomplete sequence \337>, len=1504, table=1) at src/input/mpegts/tsdemux.c:232
#5  0x00005555557ebd08 in mpegts_input_process (mi=0x555556b85ed0, mpkt=0x7fffb4005310) at src/input/mpegts/mpegts_input.c:1365
#6  0x00005555557ec4ce in mpegts_input_thread (p=0x555556b85ed0) at src/input/mpegts/mpegts_input.c:1506
#7  0x00005555556fb67c in thread_wrapper (p=0x7fffc8000a30) at src/wrappers.c:159
#8  0x00007ffff62c3184 in start_thread (arg=0x7fffc3fff700) at pthread_create.c:312
#9  0x00007ffff57de37d in clone () at ../sysdeps/unix/sysv/linux/x86_64/clone.S:111
(gdb)

#10

Updated by Jaroslav Kysela about 8 years ago

  • Status changed from New to Fixed
  • % Done changed from 0 to 100

Applied in changeset commit:tvheadend|242bee1c7670c5f3fe56a8ae7e35950e38b6bcb9.

#11

Updated by Jaroslav Kysela about 8 years ago

Fixed in v4.1-2325-g242bee1. Please, test.

#12

Updated by Bengt Madeberg about 8 years ago

Great. Many thanks. The HW CAM implemenation has never worked better than now. When doing some experiments, I can se that even if my CAM support 2 channels which I have in the configuration there are still some issues. In 50 % av the cases the first channel stops (freezes) when selecting a new one. I will check without SAT>IP to se if there is any difference.

Below 2 channels streaming perfect

Her it looks like 2 channels are streaming but only one works. (lower bitrate)

It seems that unsubscribing is sent even if I have not ordered any unsubscription

2016-11-17 11:20:49.108 subscription: 1D59: "192.168.0.67 [ muy | Kodi Media Center ]" subscribing on channel "Kanal 5 HD", weight: 150, adapter: "SAT>IP DVB-T Tuner #1 (192.168.0.93:9983)", network: "Teracom_sat_ip", mux: "570MHz", provider: "SBS Broadcasting Networks Ltd", service: "Kanal 5 HD", profile="htsp", hostname="192.168.0.67", username="muy", client="Kodi Media Center"

2016-11-17 11:20:53.162 subscription: 1D59: "192.168.0.67 [ muy | Kodi Media Center ]" unsubscribing from "Kanal 5 HD", hostname="192.168.0.67", username="muy", client="Kodi Media Center"

2016-11-17 11:20:53.163 subscription: 1D5A: "192.168.0.67 [ muy | Kodi Media Center ]" subscribing on channel "TV3 HD GÖTEBORG", weight: 150, adapter: "SAT>IP DVB-T Tuner #1 (192.168.0.93:9983)", network: "Teracom_sat_ip", mux: "570MHz", provider: "Viasat AB", service: "TV3 HD GÖTEBORG", profile="htsp", hostname="192.168.0.67", username="muy", client="Kodi Media Center"

#13

Updated by Bengt Madeberg about 8 years ago

Have I also now noticed that when running via SAT>IP it seems to work reasonble good except the stuff above. If I connect directly the channels never shows up...The log below is when I first connect kodi directly and then I change to my other tvh server (SAT>IP client) inbetween.

2016-11-17 12:53:52.678 [  TRACE]:dvbcam: start service 0x7f942b9fc200
2016-11-17 12:53:54.245 [WARNING]:TS: Teracom/570MHz/TV4 HD Göteborg: H264 @ #6619 Continuity counter error (total 1)
2016-11-17 12:53:54.245 [WARNING]:TS: Teracom/570MHz/TV4 HD Göteborg: AAC @ #6618 Continuity counter error (total 1)
2016-11-17 12:53:54.601 [WARNING]:subscription: 0060: service instance is bad, reason: No access
2016-11-17 12:53:54.602 [  TRACE]:dvbcam: stop service 0x7f942b9fc200
2016-11-17 12:53:54.602 [  TRACE]:en50221: not_selected CAPMT enqueued (update)
2016-11-17 12:53:54.652 [  TRACE]:en50221: not_selected CAPMT sent (update)
2016-11-17 12:53:54.652 [  TRACE]:en50221: 05 19 D2 05 00 00 1B 19 DB 00 34 04 09 0F 05 00 ..........4.....
2016-11-17 12:53:54.652 [  TRACE]:en50221: E0 BC 10 01 00 13 01 20 14 03 04 0D 08 09 0F 05 ....... ........
2016-11-17 12:53:54.652 [  TRACE]:en50221: 00 E0 41 10 01 00 13 01 20 14 03 02 05 08 09 0F ..A..... .......
2016-11-17 12:53:54.652 [  TRACE]:en50221: 05 00 E0 42 10 01 00 13 01 20 14 03 02 32 08 11 ...B..... ...2..
2016-11-17 12:53:54.652 [  TRACE]:en50221: 19 DA 00 34 04 09 0F 05 00 E0 BC 10 01 00 13 01 ...4............
2016-11-17 12:53:54.652 [  TRACE]:en50221: 20 14 03 04 0D 08 09 0F 05 00 E0 41 10 01 00 13  ..........A....
2016-11-17 12:53:54.652 [  TRACE]:en50221: 01 20 14 03 02 05 08 09 0F 05 00 E0 42 10 01 00 . ..........B...
2016-11-17 12:53:54.652 [  TRACE]:en50221: 13 01 20 14 03 02 32 08 06 04 16 00 00          .. ...2......
2016-11-17 12:53:56.602 [ NOTICE]:subscription: 0060: No input source available for subscription "192.168.0.67 [ muy | Kodi Media Center ]" to channel "TV4 HD Göteborg" 
2016-11-17 12:53:58.602 [ NOTICE]:subscription: 0060: No input source available for subscription "192.168.0.67 [ muy | Kodi Media Center ]" to channel "TV4 HD Göteborg" 
2016-11-17 12:54:00.602 [ NOTICE]:subscription: 0060: No input source available for subscription "192.168.0.67 [ muy | Kodi Media Center ]" to channel "TV4 HD Göteborg" 
2016-11-17 12:54:02.602 [ NOTICE]:subscription: 0060: No input source available for subscription "192.168.0.67 [ muy | Kodi Media Center ]" to channel "TV4 HD Göteborg" 
2016-11-17 12:54:04.602 [ NOTICE]:subscription: 0060: No input source available for subscription "192.168.0.67 [ muy | Kodi Media Center ]" to channel "TV4 HD Göteborg" 
2016-11-17 12:54:04.612 [   INFO]:subscription: 0060: "192.168.0.67 [ muy | Kodi Media Center ]" unsubscribing from "TV4 HD Göteborg", hostname="192.168.0.67", username="muy", client="Kodi Media Center" 
2016-11-17 12:54:04.612 [  TRACE]:dvbcam: stop service 0x7f942b9fc200
2016-11-17 12:54:04.613 [  TRACE]:dvbcam: start service 0x7f942b9f7af0
2016-11-17 12:54:04.613 [   INFO]:subscription: 0061: "192.168.0.67 [ muy | Kodi Media Center ]" subscribing on channel "Kanal 5 HD", weight: 150, adapter: "TurboSight TBS 5881 DVBT/T2 frontend : DVB-T #0", network: "Teracom", mux: "570MHz", provider: "SBS Broadcasting Networks Ltd", service: "Kanal 5 HD", profile="htsp", hostname="192.168.0.67", username="muy", client="Kodi Media Center" 
2016-11-17 12:54:04.719 [  TRACE]:dvbcam: found active cam entry
2016-11-17 12:54:04.719 [  TRACE]:en50221: ok_descrambling CAPMT enqueued (only)
2016-11-17 12:54:04.720 [  TRACE]:dvbcam: start service 0x7f942b9f7af0
2016-11-17 12:54:04.740 [  TRACE]:en50221: ok_descrambling CAPMT sent (only)
2016-11-17 12:54:04.740 [  TRACE]:en50221: 03 0D 7A 05 00 00 1B 0D 83 00 34 01 09 0F 05 00 ..z.......4.....
2016-11-17 12:54:04.740 [  TRACE]:en50221: E0 BD 10 01 00 13 01 20 14 03 04 0D 08 09 0F 05 ....... ........
2016-11-17 12:54:04.740 [  TRACE]:en50221: 00 E0 43 10 01 00 13 01 20 14 03 02 05 08 09 0F ..C..... .......
2016-11-17 12:54:04.740 [  TRACE]:en50221: 05 00 E0 44 10 01 00 13 01 20 14 03 02 32 08 11 ...D..... ...2..
2016-11-17 12:54:04.740 [  TRACE]:en50221: 0D 82 00 34 01 09 0F 05 00 E0 BD 10 01 00 13 01 ...4............
2016-11-17 12:54:04.740 [  TRACE]:en50221: 20 14 03 04 0D 08 09 0F 05 00 E0 43 10 01 00 13  ..........C....
2016-11-17 12:54:04.740 [  TRACE]:en50221: 01 20 14 03 02 05 08 09 0F 05 00 E0 44 10 01 00 . ..........D...
2016-11-17 12:54:04.740 [  TRACE]:en50221: 13 01 20 14 03 02 32 08 06 0D B2 00 00          .. ...2......
2016-11-17 12:54:06.383 [WARNING]:TS: Teracom/570MHz/Kanal 5 HD: H264 @ #3459 Continuity counter error (total 1)
2016-11-17 12:54:06.400 [WARNING]:TS: Teracom/570MHz/Kanal 5 HD: AAC @ #3458 Continuity counter error (total 1)
2016-11-17 12:54:06.613 [WARNING]:subscription: 0061: service instance is bad, reason: No access
2016-11-17 12:54:06.613 [  TRACE]:dvbcam: stop service 0x7f942b9f7af0
2016-11-17 12:54:06.613 [  TRACE]:en50221: not_selected CAPMT enqueued (update)
2016-11-17 12:54:06.663 [  TRACE]:en50221: not_selected CAPMT sent (update)
2016-11-17 12:54:06.663 [  TRACE]:en50221: 05 0D 7A 05 00 00 1B 0D 83 00 34 04 09 0F 05 00 ..z.......4.....
2016-11-17 12:54:06.663 [  TRACE]:en50221: E0 BD 10 01 00 13 01 20 14 03 04 0D 08 09 0F 05 ....... ........
2016-11-17 12:54:06.663 [  TRACE]:en50221: 00 E0 43 10 01 00 13 01 20 14 03 02 05 08 09 0F ..C..... .......
2016-11-17 12:54:06.663 [  TRACE]:en50221: 05 00 E0 44 10 01 00 13 01 20 14 03 02 32 08 11 ...D..... ...2..
2016-11-17 12:54:06.663 [  TRACE]:en50221: 0D 82 00 34 04 09 0F 05 00 E0 BD 10 01 00 13 01 ...4............
2016-11-17 12:54:06.663 [  TRACE]:en50221: 20 14 03 04 0D 08 09 0F 05 00 E0 43 10 01 00 13  ..........C....
2016-11-17 12:54:06.663 [  TRACE]:en50221: 01 20 14 03 02 05 08 09 0F 05 00 E0 44 10 01 00 . ..........D...
2016-11-17 12:54:06.663 [  TRACE]:en50221: 13 01 20 14 03 02 32 08 06 0D B2 00 00          .. ...2......
2016-11-17 12:54:08.613 [ NOTICE]:subscription: 0061: No input source available for subscription "192.168.0.67 [ muy | Kodi Media Center ]" to channel "Kanal 5 HD" 
2016-11-17 12:54:10.613 [ NOTICE]:subscription: 0061: No input source available for subscription "192.168.0.67 [ muy | Kodi Media Center ]" to channel "Kanal 5 HD" 
2016-11-17 12:54:11.623 [   INFO]:subscription: 0061: "192.168.0.67 [ muy | Kodi Media Center ]" unsubscribing from "Kanal 5 HD", hostname="192.168.0.67", username="muy", client="Kodi Media Center" 
2016-11-17 12:54:11.623 [  TRACE]:dvbcam: stop service 0x7f942b9f7af0
2016-11-17 12:54:11.625 [   INFO]:subscription: 0062: "192.168.0.67 [ muy | Kodi Media Center ]" subscribing on channel "SVT1 HD Väst", weight: 150, adapter: "TurboSight TBS 5881 DVBT/T2 frontend : DVB-T #0", network: "Teracom", mux: "570MHz", provider: "Sveriges Television", service: "SVT1 HD Väst", profile="htsp", hostname="192.168.0.67", username="muy", client="Kodi Media Center" 
2016-11-17 12:54:11.729 [  TRACE]:dvbcam: cannot find active service entry
2016-11-17 12:54:17.668 [   INFO]:subscription: 0062: "192.168.0.67 [ muy | Kodi Media Center ]" unsubscribing from "SVT1 HD Väst", hostname="192.168.0.67", username="muy", client="Kodi Media Center" 
2016-11-17 12:54:17.669 [  TRACE]:dvbcam: stop service 0x7f942b9f8e30
2016-11-17 12:54:36.139 [   INFO]:htsp: 192.168.0.67 [ muy | Kodi Media Center ]: Disconnected
2016-11-17 12:54:53.640 [   INFO]:subscription: 0063: "SAT>IP" subscribing to mux "570MHz", weight: 150, adapter: "TurboSight TBS 5881 DVBT/T2 frontend : DVB-T #0", network: "Teracom", service: "Raw PID Subscription", hostname="192.168.0.180" 
2016-11-17 12:54:57.202 [   INFO]:subscription: 0063: "SAT>IP" unsubscribing, hostname="192.168.0.180" 
2016-11-17 12:54:57.202 [  TRACE]:dvbcam: stop service 0x7f93ec001070
2016-11-17 12:54:57.204 [   INFO]:subscription: 0064: "SAT>IP" subscribing to mux "570MHz", weight: 150, adapter: "TurboSight TBS 5881 DVBT/T2 frontend : DVB-T #0", network: "Teracom", service: "Raw PID Subscription", hostname="192.168.0.180" 
2016-11-17 12:54:57.205 [  TRACE]:dvbcam: start service 0x7f942b9f6b20
2016-11-17 12:54:57.205 [   INFO]:subscription: 0065: "SAT>IP Slave/Teracom/570MHz/TV3 HD GÖTEBORG" subscribing to service "Teracom/570MHz/TV3 HD GÖTEBORG", weight: 100, adapter: "TurboSight TBS 5881 DVBT/T2 frontend : DVB-T #0", network: "Teracom", mux: "570MHz", provider: "Viasat AB", client="SAT>IP Slave/Teracom/570MHz/TV3 HD GÖTEBORG" 
2016-11-17 12:54:57.234 [  TRACE]:dvbcam: found active cam entry
2016-11-17 12:54:57.234 [  TRACE]:en50221: ok_descrambling CAPMT enqueued (only)
2016-11-17 12:54:57.234 [  TRACE]:dvbcam: start service 0x7f942b9f6b20
2016-11-17 12:54:57.404 [  TRACE]:en50221: ok_descrambling CAPMT sent (only)
2016-11-17 12:54:57.404 [  TRACE]:en50221: 03 0D 66 03 00 00 1B 0D 6F 00 34 01 09 0F 05 00 ..f.....o.4.....
2016-11-17 12:54:57.404 [  TRACE]:en50221: E0 BE 10 01 00 13 01 20 14 03 04 0D 08 09 0F 05 ....... ........
2016-11-17 12:54:57.404 [  TRACE]:en50221: 00 E0 3F 10 01 00 13 01 20 14 03 02 05 08 09 0F ..?..... .......
2016-11-17 12:54:57.404 [  TRACE]:en50221: 05 00 E0 40 10 01 00 13 01 20 14 03 02 32 08 11 ...@..... ...2..
2016-11-17 12:54:57.404 [  TRACE]:en50221: 0D 6E 00 34 01 09 0F 05 00 E0 BE 10 01 00 13 01 .n.4............
2016-11-17 12:54:57.404 [  TRACE]:en50221: 20 14 03 04 0D 08 09 0F 05 00 E0 3F 10 01 00 13  ..........?....
2016-11-17 12:54:57.404 [  TRACE]:en50221: 01 20 14 03 02 05 08 09 0F 05 00 E0 40 10 01 00 . ..........@...
2016-11-17 12:54:57.404 [  TRACE]:en50221: 13 01 20 14 03 02 32 08 06 0B BE 00 00          .. ...2......
2016-11-17 12:54:58.300 [WARNING]:TS: Teracom/570MHz/TV3 HD GÖTEBORG: H264 @ #3439 Continuity counter error (total 1)
2016-11-17 12:54:58.334 [WARNING]:TS: Teracom/570MHz/TV3 HD GÖTEBORG: AAC @ #3438 Continuity counter error (total 1)
2016-11-17 12:55:05.603 [   INFO]:subscription: 0065: "SAT>IP Slave/Teracom/570MHz/TV3 HD GÖTEBORG" unsubscribing, client="SAT>IP Slave/Teracom/570MHz/TV3 HD GÖTEBORG" 
2016-11-17 12:55:05.603 [  TRACE]:dvbcam: stop service 0x7f942b9f6b20
2016-11-17 12:55:05.604 [  TRACE]:en50221: not_selected CAPMT enqueued (update)
2016-11-17 12:55:05.604 [   INFO]:subscription: 0064: "SAT>IP" unsubscribing, hostname="192.168.0.180" 
2016-11-17 12:55:05.604 [  TRACE]:dvbcam: stop service 0x7f93ec038cc0
2016-11-17 12:55:05.604 [  TRACE]:en50221: not_selected CAPMT sent (update)
2016-11-17 12:55:05.604 [  TRACE]:en50221: 05 0D 66 03 00 00 1B 0D 6F 00 34 04 09 0F 05 00 ..f.....o.4.....
2016-11-17 12:55:05.604 [  TRACE]:en50221: E0 BE 10 01 00 13 01 20 14 03 04 0D 08 09 0F 05 ....... ........
2016-11-17 12:55:05.604 [  TRACE]:en50221: 00 E0 3F 10 01 00 13 01 20 14 03 02 05 08 09 0F ..?..... .......
2016-11-17 12:55:05.605 [  TRACE]:en50221: 05 00 E0 40 10 01 00 13 01 20 14 03 02 32 08 11 ...@..... ...2..
2016-11-17 12:55:05.605 [  TRACE]:en50221: 0D 6E 00 34 04 09 0F 05 00 E0 BE 10 01 00 13 01 .n.4............
2016-11-17 12:55:05.605 [  TRACE]:en50221: 20 14 03 04 0D 08 09 0F 05 00 E0 3F 10 01 00 13  ..........?....
2016-11-17 12:55:05.605 [  TRACE]:en50221: 01 20 14 03 02 05 08 09 0F 05 00 E0 40 10 01 00 . ..........@...
2016-11-17 12:55:05.605 [  TRACE]:en50221: 13 01 20 14 03 02 32 08 06 0B BE 00 00          .. ...2......
2016-11-17 12:55:05.605 [   INFO]:subscription: 0066: "SAT>IP" subscribing to mux "570MHz", weight: 150, adapter: "TurboSight TBS 5881 DVBT/T2 frontend : DVB-T #0", network: "Teracom", service: "Raw PID Subscription", hostname="192.168.0.180" 
2016-11-17 12:55:05.606 [  TRACE]:dvbcam: start service 0x7f942b9fc200
2016-11-17 12:55:05.606 [   INFO]:subscription: 0067: "SAT>IP Slave/Teracom/570MHz/TV4 HD Göteborg" subscribing to service "Teracom/570MHz/TV4 HD Göteborg", weight: 100, adapter: "TurboSight TBS 5881 DVBT/T2 frontend : DVB-T #0", network: "Teracom", mux: "570MHz", provider: "TV4 AB", client="SAT>IP Slave/Teracom/570MHz/TV4 HD Göteborg" 
2016-11-17 12:55:05.684 [  TRACE]:dvbcam: found active cam entry
2016-11-17 12:55:05.684 [  TRACE]:en50221: ok_descrambling CAPMT enqueued (only)
2016-11-17 12:55:05.687 [  TRACE]:dvbcam: start service 0x7f942b9fc200
2016-11-17 12:55:05.727 [  TRACE]:en50221: ok_descrambling CAPMT sent (only)
2016-11-17 12:55:05.727 [  TRACE]:en50221: 03 19 D2 05 00 00 1B 19 DB 00 34 01 09 0F 05 00 ..........4.....
2016-11-17 12:55:05.727 [  TRACE]:en50221: E0 BC 10 01 00 13 01 20 14 03 04 0D 08 09 0F 05 ....... ........
2016-11-17 12:55:05.727 [  TRACE]:en50221: 00 E0 41 10 01 00 13 01 20 14 03 02 05 08 09 0F ..A..... .......
2016-11-17 12:55:05.727 [  TRACE]:en50221: 05 00 E0 42 10 01 00 13 01 20 14 03 02 32 08 11 ...B..... ...2..
2016-11-17 12:55:05.727 [  TRACE]:en50221: 19 DA 00 34 01 09 0F 05 00 E0 BC 10 01 00 13 01 ...4............
2016-11-17 12:55:05.727 [  TRACE]:en50221: 20 14 03 04 0D 08 09 0F 05 00 E0 41 10 01 00 13  ..........A....
2016-11-17 12:55:05.728 [  TRACE]:en50221: 01 20 14 03 02 05 08 09 0F 05 00 E0 42 10 01 00 . ..........B...
2016-11-17 12:55:05.728 [  TRACE]:en50221: 13 01 20 14 03 02 32 08 06 04 16 00 00          .. ...2......
2016-11-17 12:55:07.230 [WARNING]:TS: Teracom/570MHz/TV4 HD Göteborg: H264 @ #6619 Continuity counter error (total 1)
2016-11-17 12:55:07.230 [WARNING]:TS: Teracom/570MHz/TV4 HD Göteborg: AAC @ #6618 Continuity counter error (total 1)
2016-11-17 12:55:33.409 [   INFO]:subscription: 0067: "SAT>IP Slave/Teracom/570MHz/TV4 HD Göteborg" unsubscribing, client="SAT>IP Slave/Teracom/570MHz/TV4 HD Göteborg" 
2016-11-17 12:55:33.409 [  TRACE]:dvbcam: stop service 0x7f942b9fc200
2016-11-17 12:55:33.409 [  TRACE]:en50221: not_selected CAPMT enqueued (update)
2016-11-17 12:55:33.410 [   INFO]:subscription: 0066: "SAT>IP" unsubscribing, hostname="192.168.0.180" 
2016-11-17 12:55:33.411 [  TRACE]:dvbcam: stop service 0x7f93ec038070
2016-11-17 12:55:33.414 [   INFO]:subscription: 0068: "SAT>IP" subscribing to mux "570MHz", weight: 150, adapter: "TurboSight TBS 5881 DVBT/T2 frontend : DVB-T #0", network: "Teracom", service: "Raw PID Subscription", hostname="192.168.0.180" 
2016-11-17 12:55:33.415 [  TRACE]:dvbcam: start service 0x7f942b9f7af0
2016-11-17 12:55:33.415 [   INFO]:subscription: 0069: "SAT>IP Slave/Teracom/570MHz/Kanal 5 HD" subscribing to service "Teracom/570MHz/Kanal 5 HD", weight: 100, adapter: "TurboSight TBS 5881 DVBT/T2 frontend : DVB-T #0", network: "Teracom", mux: "570MHz", provider: "SBS Broadcasting Networks Ltd", client="SAT>IP Slave/Teracom/570MHz/Kanal 5 HD" 
2016-11-17 12:55:33.419 [  TRACE]:en50221: not_selected CAPMT sent (update)
2016-11-17 12:55:33.419 [  TRACE]:en50221: 05 19 D2 05 00 00 1B 19 DB 00 34 04 09 0F 05 00 ..........4.....
2016-11-17 12:55:33.419 [  TRACE]:en50221: E0 BC 10 01 00 13 01 20 14 03 04 0D 08 09 0F 05 ....... ........
2016-11-17 12:55:33.419 [  TRACE]:en50221: 00 E0 41 10 01 00 13 01 20 14 03 02 05 08 09 0F ..A..... .......
2016-11-17 12:55:33.419 [  TRACE]:en50221: 05 00 E0 42 10 01 00 13 01 20 14 03 02 32 08 11 ...B..... ...2..
2016-11-17 12:55:33.419 [  TRACE]:en50221: 19 DA 00 34 04 09 0F 05 00 E0 BC 10 01 00 13 01 ...4............
2016-11-17 12:55:33.419 [  TRACE]:en50221: 20 14 03 04 0D 08 09 0F 05 00 E0 41 10 01 00 13  ..........A....
2016-11-17 12:55:33.419 [  TRACE]:en50221: 01 20 14 03 02 05 08 09 0F 05 00 E0 42 10 01 00 . ..........B...
2016-11-17 12:55:33.419 [  TRACE]:en50221: 13 01 20 14 03 02 32 08 06 04 16 00 00          .. ...2......
2016-11-17 12:55:33.521 [  TRACE]:dvbcam: found active cam entry
2016-11-17 12:55:33.521 [  TRACE]:en50221: ok_descrambling CAPMT enqueued (only)
2016-11-17 12:55:33.521 [  TRACE]:en50221: ok_descrambling CAPMT sent (only)
2016-11-17 12:55:33.522 [  TRACE]:en50221: 03 0D 7A 05 00 00 1B 0D 83 00 34 01 09 0F 05 00 ..z.......4.....
2016-11-17 12:55:33.522 [  TRACE]:en50221: E0 BD 10 01 00 13 01 20 14 03 04 0D 08 09 0F 05 ....... ........
2016-11-17 12:55:33.522 [  TRACE]:en50221: 00 E0 43 10 01 00 13 01 20 14 03 02 05 08 09 0F ..C..... .......
2016-11-17 12:55:33.522 [  TRACE]:en50221: 05 00 E0 44 10 01 00 13 01 20 14 03 02 32 08 11 ...D..... ...2..
2016-11-17 12:55:33.522 [  TRACE]:en50221: 0D 82 00 34 01 09 0F 05 00 E0 BD 10 01 00 13 01 ...4............
2016-11-17 12:55:33.522 [  TRACE]:en50221: 20 14 03 04 0D 08 09 0F 05 00 E0 43 10 01 00 13  ..........C....
2016-11-17 12:55:33.522 [  TRACE]:en50221: 01 20 14 03 02 05 08 09 0F 05 00 E0 44 10 01 00 . ..........D...
2016-11-17 12:55:33.522 [  TRACE]:en50221: 13 01 20 14 03 02 32 08 06 0D B2 00 00          .. ...2......
2016-11-17 12:55:33.523 [  TRACE]:dvbcam: start service 0x7f942b9f7af0
2016-11-17 12:55:35.224 [WARNING]:TS: Teracom/570MHz/Kanal 5 HD: H264 @ #3459 Continuity counter error (total 1)
2016-11-17 12:55:35.224 [WARNING]:TS: Teracom/570MHz/Kanal 5 HD: AAC @ #3458 Continuity counter error (total 1)
2016-11-17 12:55:44.404 [   INFO]:subscription: 0069: "SAT>IP Slave/Teracom/570MHz/Kanal 5 HD" unsubscribing, client="SAT>IP Slave/Teracom/570MHz/Kanal 5 HD" 
2016-11-17 12:55:44.404 [  TRACE]:dvbcam: stop service 0x7f942b9f7af0
2016-11-17 12:55:44.404 [  TRACE]:en50221: not_selected CAPMT enqueued (update)
2016-11-17 12:55:44.404 [   INFO]:subscription: 0068: "SAT>IP" unsubscribing, hostname="192.168.0.180" 
2016-11-17 12:55:44.405 [  TRACE]:dvbcam: stop service 0x7f93ec038070
2016-11-17 12:55:44.410 [   INFO]:subscription: 006A: "SAT>IP" subscribing to mux "570MHz", weight: 150, adapter: "TurboSight TBS 5881 DVBT/T2 frontend : DVB-T #0", network: "Teracom", service: "Raw PID Subscription", hostname="192.168.0.180" 
2016-11-17 12:55:44.410 [  TRACE]:dvbcam: start service 0x7f942b9fc200
2016-11-17 12:55:44.410 [   INFO]:subscription: 006B: "SAT>IP Slave/Teracom/570MHz/TV4 HD Göteborg" subscribing to service "Teracom/570MHz/TV4 HD Göteborg", weight: 100, adapter: "TurboSight TBS 5881 DVBT/T2 frontend : DVB-T #0", network: "Teracom", mux: "570MHz", provider: "TV4 AB", client="SAT>IP Slave/Teracom/570MHz/TV4 HD Göteborg" 
2016-11-17 12:55:44.429 [  TRACE]:en50221: not_selected CAPMT sent (update)
2016-11-17 12:55:44.429 [  TRACE]:en50221: 05 0D 7A 05 00 00 1B 0D 83 00 34 04 09 0F 05 00 ..z.......4.....
2016-11-17 12:55:44.429 [  TRACE]:en50221: E0 BD 10 01 00 13 01 20 14 03 04 0D 08 09 0F 05 ....... ........
2016-11-17 12:55:44.429 [  TRACE]:en50221: 00 E0 43 10 01 00 13 01 20 14 03 02 05 08 09 0F ..C..... .......
2016-11-17 12:55:44.429 [  TRACE]:en50221: 05 00 E0 44 10 01 00 13 01 20 14 03 02 32 08 11 ...D..... ...2..
2016-11-17 12:55:44.430 [  TRACE]:en50221: 0D 82 00 34 04 09 0F 05 00 E0 BD 10 01 00 13 01 ...4............
2016-11-17 12:55:44.430 [  TRACE]:en50221: 20 14 03 04 0D 08 09 0F 05 00 E0 43 10 01 00 13  ..........C....
2016-11-17 12:55:44.430 [  TRACE]:en50221: 01 20 14 03 02 05 08 09 0F 05 00 E0 44 10 01 00 . ..........D...
2016-11-17 12:55:44.430 [  TRACE]:en50221: 13 01 20 14 03 02 32 08 06 0D B2 00 00          .. ...2......
2016-11-17 12:55:44.466 [  TRACE]:dvbcam: found active cam entry
2016-11-17 12:55:44.466 [  TRACE]:en50221: ok_descrambling CAPMT enqueued (only)
2016-11-17 12:55:44.467 [  TRACE]:dvbcam: start service 0x7f942b9fc200
2016-11-17 12:55:44.495 [  TRACE]:en50221: ok_descrambling CAPMT sent (only)
2016-11-17 12:55:44.496 [  TRACE]:en50221: 03 19 D2 05 00 00 1B 19 DB 00 34 01 09 0F 05 00 ..........4.....
2016-11-17 12:55:44.496 [  TRACE]:en50221: E0 BC 10 01 00 13 01 20 14 03 04 0D 08 09 0F 05 ....... ........
2016-11-17 12:55:44.496 [  TRACE]:en50221: 00 E0 41 10 01 00 13 01 20 14 03 02 05 08 09 0F ..A..... .......
2016-11-17 12:55:44.496 [  TRACE]:en50221: 05 00 E0 42 10 01 00 13 01 20 14 03 02 32 08 11 ...B..... ...2..
2016-11-17 12:55:44.496 [  TRACE]:en50221: 19 DA 00 34 01 09 0F 05 00 E0 BC 10 01 00 13 01 ...4............
2016-11-17 12:55:44.496 [  TRACE]:en50221: 20 14 03 04 0D 08 09 0F 05 00 E0 41 10 01 00 13  ..........A....
2016-11-17 12:55:44.496 [  TRACE]:en50221: 01 20 14 03 02 05 08 09 0F 05 00 E0 42 10 01 00 . ..........B...
2016-11-17 12:55:44.496 [  TRACE]:en50221: 13 01 20 14 03 02 32 08 06 04 16 00 00          .. ...2......
2016-11-17 12:55:46.231 [WARNING]:TS: Teracom/570MHz/TV4 HD Göteborg: H264 @ #6619 Continuity counter error (total 1)
2016-11-17 12:55:46.231 [WARNING]:TS: Teracom/570MHz/TV4 HD Göteborg: AAC @ #6618 Continuity counter error (total 1)

Also available in: Atom PDF