Project

General

Profile

Actions

Bug #3501

open

decrypting fails but restart TVH fixes it works straight away (assume to be TVH timing out with connection to oscam or something)

Added by neil mac almost 10 years ago. Updated almost 10 years ago.

Status:
New
Priority:
Normal
Assignee:
-
Category:
Descrambling
Target version:
-
Start date:
2016-01-16
Due date:
% Done:

0%

Estimated time:
Found in version:
HTS Tvheadend 4.1-1350~gdfae393
Affected Versions:

Description

This one is difficult to provide logs for as it seems to lose the ability to decode successfully after a certain period (say the next day).

I have a kodi client connected, I restart tvh and it jumps into live (I thought it was oscam not being able to decode but its seems to be the connection with TVH).

I have two snippets of logs, the first trying to connect to a encrypted channel and it failing, then a restart of TVH and it works as expected.

Let me know if I can provide more logs or info on this.

Thanks

Jan 16 10:00:07 server tvheadend7031: capmt: tvh: Starting CAPMT server for service "Sky Sports 1" on adapter 1
Jan 16 10:00:07 server tvheadend7031: capmt: tvh: New caid 0x0960:0x000000 (pid 0x051F) for service "Sky Sports 1"
Jan 16 10:00:07 server tvheadend7031: capmt: tvh: New caid 0x0961:0x000000 (pid 0x051F) for service "Sky Sports 1"
Jan 16 10:00:07 server tvheadend7031: capmt: tvh: New caid 0x0963:0x000000 (pid 0x051F) for service "Sky Sports 1"
Jan 16 10:00:07 server tvheadend7031: capmt: tvh: adding ECMPID=0x51F (1311), CAID=0x963 (2403) PROVID=0x0 (0), SID=4611, ADAPTER=1
Jan 16 10:00:07 server tvheadend7031: capmt: tvh: adding ECMPID=0x51F (1311), CAID=0x961 (2401) PROVID=0x0 (0), SID=4611, ADAPTER=1
Jan 16 10:00:07 server tvheadend7031: capmt: tvh: adding ECMPID=0x51F (1311), CAID=0x960 (2400) PROVID=0x0 (0), SID=4611, ADAPTER=1
Jan 16 10:00:07 server tvheadend7031: capmt: tvh: Trying to obtain key for service "Sky Sports 1"
Jan 16 10:00:07 server tvheadend7031: subscription: 02BA: "192.168.0.130 [ zotac-d88 | Kodi Media Center ]" subscribing on channel "Sky Sports 1", weight: 150, adapter: "SAT>IP DVB-S Tuner #1 (192.168.0.75)", network: "DVB-S2", mux: "12148H", provider: "BSkyB", service: "Sky Sports 1", profile="htsp", hostname="192.168.0.130", username="zotac-d88", client="Kodi Media Center"
Jan 16 10:00:07 server tvheadend7031: htsp: 192.168.0.130 [ zotac-d88 | Kodi Media Center ] - subscription grace 10 seconds
Jan 16 10:00:07 server tvheadend7031: htsp: 192.168.0.130 [ zotac-d88 | Kodi Media Center ] - subscription speed
Jan 16 10:00:07 server tvheadend7031: mpegts: 12148H in DVB-S2 - open PID 0000 (0) [20/0x2862360]
Jan 16 10:00:07 server tvheadend7031: mpegts: 12148H in DVB-S2 - open PID 0001 (1) [16/0x29a2f00]
Jan 16 10:00:07 server tvheadend7031: mpegts: 12148H in DVB-S2 - open PID 0010 (16) [16/0x2900710]
Jan 16 10:00:07 server tvheadend7031: mpegts: 12148H in DVB-S2 - open PID 0011 (17) [20/0x2bb5ef0]
Jan 16 10:00:07 server tvheadend7031: mpegts: 12148H in DVB-S2 - open PID 0011 (17) [16/0x2190a20]
Jan 16 10:00:07 server tvheadend7031: mpegts: 12148H in DVB-S2 - open PID 0014 (20) [20/0x23bec80]
Jan 16 10:00:07 server tvheadend7031: mpegts: 12148H in DVB-S2 - open PID 0014 (20) [20/0x2c0c720]
Jan 16 10:00:07 server tvheadend7031: satip: 192.168.0.75 #1 - new session 14b42fdb7829292 stream id 3619
Jan 16 10:00:07 server tvheadend7031: service: DVB-S2/12148H/Sky Sports 1: Status changed to [Hardware input]
Jan 16 10:00:07 server tvheadend7031: service: DVB-S2/12148H/Sky Sports 1: Status changed to [Hardware input] [Input on service]
Jan 16 10:00:07 server tvheadend7031: service: DVB-S2/12148H/Sky Sports 1: Status changed to [Hardware input] [Input on service] [Demuxed packets]
Jan 16 10:00:07 server tvheadend7031: service: DVB-S2/12148H/Sky Sports 1: Status changed to [Hardware input] [Input on service] [Demuxed packets] [No access]
Jan 16 10:00:07 server tvheadend7031: eit: 12148H in DVB-S2: PID 0012 CC error 10 != 0

Jan 16 10:01:05 server tvheadend25745: capmt: tvh: CA_SET_PID adapter 0 index -1 pid 516 (0x0204)
Jan 16 10:01:05 server tvheadend25745: capmt: tvh: CA_SET_PID adapter 0 index 0 pid 516 (0x0204)
Jan 16 10:01:05 server tvheadend25745: capmt: tvh: CA_SET_PID adapter 0 index -1 pid 644 (0x0284)
Jan 16 10:01:05 server tvheadend25745: capmt: tvh: CA_SET_PID adapter 0 index 0 pid 644 (0x0284)
Jan 16 10:01:05 server tvheadend25745: capmt: tvh: CA_SET_PID adapter 0 index -1 pid 1036 (0x040c)
Jan 16 10:01:05 server tvheadend25745: capmt: tvh: CA_SET_PID adapter 0 index 0 pid 1036 (0x040c)
Jan 16 10:01:05 server tvheadend25745: capmt: tvh, CA_SET_DESCR adapter 0 par 1 idx 0 c61900df63cee314
Jan 16 10:01:05 server tvheadend25745: descrambler: Obtained keys from capmt-127.0.0.1 for service "Sky Sports 1"
Jan 16 10:01:05 server tvheadend25745: descrambler: info - service='Sky Sports 1' caid=0963(videoguard) provid=000000 ecmtime=315 hops=2 reader='XXXX' from='XXXX' protocol='cccam'
Jan 16 10:01:05 server tvheadend25745: capmt: tvh: ECM_INFO: adapter=0 sid=4611 caid=0963(videoguard) pid=051F provid=000000 ecmtime=315 hops=2 reader=XXXX from=XXXX protocol=cccam
Jan 16 10:01:05 server tvheadend25745: service: DVB-S2/12148H/Sky Sports 1: Status changed to [Hardware input] [Input on service] [Demuxed packets] [Reassembled packets] [No access]
Jan 16 10:01:05 server tvheadend25745: htsp: 192.168.0.130 [ zotac-d88 | Kodi Media Center ] - subscription start
Jan 16 10:01:05 server tvheadend25745: service: DVB-S2/12148H/Sky Sports 1: Status changed to [Hardware input] [Input on service] [Demuxed packets] [Reassembled packets]

Actions #1

Updated by Jaroslav Kysela almost 10 years ago

Provide log from oscam - level 128 - and traces for tvh - '--trace descrambler,capmt' for working / non-working states. https://tvheadend.org/projects/tvheadend/wiki/Traces

Actions

Also available in: Atom PDF