( gelöst ) HD02 & OScam plötzlich "not found"

[setti]

Ich finde ums verrecken meinen Fehler nicht mit meiner HD02, welche sich im Gratisjahr befindet. Dazu sei gesagt, es lief alles mal!
Vielleicht kann mir jemand von euch helfen:

Spoiler: Log

2019/02/06 12:57:22 7DF74C0E c      (ecm) cw:
2019/02/06 12:57:22 7DF74C0E c      (ecm)   00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
2019/02/06 12:57:22 7DF74C0E c   (dvbapi) Demuxer 0 request controlword for ecm 186A@000000/0000/EF10/8F:D8C2F5EC4AAF30C9D62057FF943403E5
2019/02/06 12:57:22 0A00B0D3 r   (reader) smargo [nagra] IO: SR: Receive:
2019/02/06 12:57:22 0A00B0D3 r   (reader)   00 00 04 87
2019/02/06 12:57:22 0A00B0D3 r   (reader) smargo [nagra] IFD:  used timeout by smartreader 1070.08 ms
2019/02/06 12:57:22 0A00B0D3 r   (reader) smargo [nagra] IFD: Receive succesful
2019/02/06 12:57:22 0A00B0D3 r   (reader) smargo [nagra] IFD: Receive size 4 bytes, delay 0 us, timeout=1070082 us
2019/02/06 12:57:22 0A00B0D3 r   (reader) smargo [nagra] IO: SR: Receive:
2019/02/06 12:57:22 0A00B0D3 r   (reader)   00 90 00 13
2019/02/06 12:57:22 0A00B0D3 r   (reader) smargo [nagra] IFD:  used timeout by smartreader 1070.08 ms
2019/02/06 12:57:22 0A00B0D3 r   (reader) smargo [nagra] IFD: Receive succesful
2019/02/06 12:57:22 0A00B0D3 r   (reader) smargo [nagra] IFD: Protocol: Received block I(0,0)
2019/02/06 12:57:22 0A00B0D3 r   (reader) smargo [nagra] Answer from cardreader:
2019/02/06 12:57:22 0A00B0D3 r   (reader)   87 00 90 00
2019/02/06 12:57:22 0A00B0D3 r   (reader) smargo [nagra] write to cardreader
2019/02/06 12:57:22 0A00B0D3 r   (reader)   A0 CA 00 00 02 C0 00 06
2019/02/06 12:57:22 0A00B0D3 r   (reader) smargo [nagra] IFD: Setting timeout to 139 ETU (832 us)
2019/02/06 12:57:22 0A00B0D3 r   (reader) smargo [nagra] IFD: Transmit size 12 bytes, delay 0 us, timeout=832 us
2019/02/06 12:57:22 0A00B0D3 r   (reader) smargo [nagra] IFD: Transmit:
2019/02/06 12:57:22 0A00B0D3 r   (reader)   00 40 08 A0 CA 00 00 02 C0 00 06 E6
2019/02/06 12:57:22 0A00B0D3 r   (reader) smargo [nagra] IO: SR: Transmit:
2019/02/06 12:57:22 0A00B0D3 r   (reader)   00 40 08 A0 CA 00 00 02 C0 00 06 E6
2019/02/06 12:57:22 0A00B0D3 r   (reader) smargo [nagra] IFD: Transmit succesful
2019/02/06 12:57:22 0A00B0D3 r   (reader) smargo [nagra] IFD: Sending block I(1,0)
2019/02/06 12:57:22 0A00B0D3 r   (reader) smargo [nagra] IFD: Setting timeout to 178570 ETU (1070082 us)
2019/02/06 12:57:22 0A00B0D3 r   (reader) smargo [nagra] IFD: Receive size 4 bytes, delay 0 us, timeout=1070082 us
2019/02/06 12:57:22 0A00B0D3 r   (reader) smargo [nagra] IO: SR: Receive:
2019/02/06 12:57:22 0A00B0D3 r   (reader)   00 40 08 B0
2019/02/06 12:57:22 0A00B0D3 r   (reader) smargo [nagra] IFD:  used timeout by smartreader 1070.08 ms
2019/02/06 12:57:22 0A00B0D3 r   (reader) smargo [nagra] IFD: Receive succesful
2019/02/06 12:57:22 0A00B0D3 r   (reader) smargo [nagra] IFD: Receive size 8 bytes, delay 0 us, timeout=1070082 us
2019/02/06 12:57:22 0A00B0D3 r   (reader) smargo [nagra] IO: SR: Receive:
2019/02/06 12:57:22 0A00B0D3 r   (reader)   04 00 11 00 28 90 00 55
2019/02/06 12:57:22 0A00B0D3 r   (reader) smargo [nagra] IFD:  used timeout by smartreader 1070.08 ms
2019/02/06 12:57:22 0A00B0D3 r   (reader) smargo [nagra] IFD: Receive succesful
2019/02/06 12:57:22 0A00B0D3 r   (reader) smargo [nagra] IFD: Protocol: Received block I(1,0)
2019/02/06 12:57:22 0A00B0D3 r   (reader) smargo [nagra] Answer from cardreader:
2019/02/06 12:57:22 0A00B0D3 r   (reader)   B0 04 00 11 00 28 90 00
2019/02/06 12:57:22 0A00B0D3 r   (reader) smargo [nagra] Camstate: 11 00 28
2019/02/06 12:57:22 0A00B0D3 r   (reader) smargo [nagra] cardreader_do_ecm: after csystem->do_ecm rc=0
2019/02/06 12:57:22 0A00B0D3 r   (reader) smargo [nagra] cardreader_do_ecm: ret rc=0
2019/02/06 12:57:22 0A00B0D3 r   (reader) smargo [nagra] cardreader_process_ecm: cardreader_do_ecm returned rc=0 (ERROR=0)
2019/02/06 12:57:22 0A00B0D3 r   (reader) smargo [nagra] Error processing ecm for caid 1843, provid 000000, srvid EF10, servicename: RTL HD
2019/02/06 12:57:22 0A00B0D3 r      (ecm) {client dvbapi, caid 1843, srvid EF10} [write_ecm_answer] cyclecheck passed! Reader: smargo rc: 4
2019/02/06 12:57:22 0A00B0D3 r      (ecm) {client dvbapi, caid 1843, prid 000000, srvid EF10} [write_ecm_answer] reader smargo rc 4, ecm time 340 ms (348 ms)
2019/02/06 12:57:22 0A00B0D3 r     (work) start client thread action 32
2019/02/06 12:57:22 0A00B0D3 r     (main) starting thread client work
2019/02/06 12:57:22 0A00B0D3 r     (main) client work thread started
2019/02/06 12:57:22 0A00B0D3 r     (stat) loadbalancer: adding stat for reader smargo: rc 4 1843@000000/0000/EF10/89:3D48C2C76F42EB4C9B7D181CB7DF180F time 340 ms fail 1
2019/02/06 12:57:22 0A00B0D3 r   (reader) smargo [nagra] TRACE: ecm answer for ecm hash 3D48C2C76F42EB4C9B7D181CB7DF180F rc=4
2019/02/06 12:57:22 0A00B0D3 r   (reader) smargo [nagra] ecm hash: 3D48C2C76F42EB4C9B7D181CB7DF180F real time: 332 ms
2019/02/06 12:57:22 0A00B0D3 r   (reader) smargo [nagra] write to cardreader
2019/02/06 12:57:22 0A00B0D3 r   (reader)   A0 CA 00 00 02 C0 00 06
2019/02/06 12:57:22 0A00B0D3 r   (reader) smargo [nagra] IFD: Setting timeout to 139 ETU (832 us)
2019/02/06 12:57:22 0A00B0D3 r   (reader) smargo [nagra] IFD: Transmit size 12 bytes, delay 0 us, timeout=832 us
2019/02/06 12:57:22 0A00B0D3 r   (reader) smargo [nagra] IFD: Transmit:
2019/02/06 12:57:22 0A00B0D3 r   (reader)   00 00 08 A0 CA 00 00 02 C0 00 06 A6
2019/02/06 12:57:22 0A00B0D3 r   (reader) smargo [nagra] IO: SR: Transmit:
2019/02/06 12:57:22 0A00B0D3 r   (reader)   00 00 08 A0 CA 00 00 02 C0 00 06 A6
2019/02/06 12:57:22 0A00B0D3 r   (reader) smargo [nagra] IFD: Transmit succesful
2019/02/06 12:57:22 0A00B0D3 r   (reader) smargo [nagra] IFD: Sending block I(0,0)
2019/02/06 12:57:22 0A00B0D3 r   (reader) smargo [nagra] IFD: Setting timeout to 178570 ETU (1070082 us)
2019/02/06 12:57:22 0A00B0D3 r   (reader) smargo [nagra] IFD: Receive size 4 bytes, delay 0 us, timeout=1070082 us
2019/02/06 12:57:22 7DF74C0E c     (work) data from add_job action=32 client c dvbapi
2019/02/06 12:57:22 7DF74C0E c      (ecm) {client dvbapi, caid 1843, prid 000000, srvid EF10} [send_dcw] rc 4 from reader smargo
2019/02/06 12:57:22 7DF74C0E c      (ecm) Client dvbapi csphash 6D9A871D cw 00000000000000000000000000000000 rc 4 1843@000000/0000/EF10/89:3D48C2C76F42EB4C9B7D181CB7DF180F
2019/02/06 12:57:22 7DF74C0E c   (dvbapi) Demuxer 0 no controlword received for PID 5 CAID 1843 PROVID 000000 ECMPID 19CC CHID 0000 VPID 00FF
2019/02/06 12:57:22 7DF74C0E c   (dvbapi) Demuxer 0 requested controlword for ecm 3D48C2C76F42EB4C9B7D181CB7DF180F on fd 65535
2019/02/06 12:57:22 7DF74C0E c   (dvbapi) Demuxer 0 not interested in response ecmhash 3D48C2C76F42EB4C9B7D181CB7DF180F (delivered cw is empty!)
2019/02/06 12:57:22 7DF74C0E c   (dvbapi) Demuxer 0 stop filter 1 try 1 (fd: 65535 api: 0, caid: 1843, provid: 000000, ecmpid: 19CC)
2019/02/06 12:57:22 7DF74C0E c   (dvbapi) Sending packet to dvbapi client (fd=29):
2019/02/06 12:57:22 7DF74C0E c   (dvbapi)   00 00 6F 2A 00 00 00 19 CC
2019/02/06 12:57:22 7DF74C0E c      (ecm) dvbapi (1843@000000/0000/EF10/89:3D48C2C76F42EB4C9B7D181CB7DF180F): not found (354 ms) by smargo - RTL HD

Spoiler: oscam.server

[reader]
label = smargo
description = HD02
protocol = smartreader
device = 004:002
boxkey = XXXX
rsakey = XXXXXXXXX
fix07 = 0
detect = cd
mhz = 534
cardmhz = 369
group = 1
blockemm-unknown = 1
blockemm-g = 1
saveemm-u = 1
saveemm-s = 1
lb_weight = 500

Spoiler: oscam.user

[account]
user = dvbapi
pwd = dvbapi
au = smargo
group = 1

Spoiler: oscam.dvbapi

P: 1843
I: 1830
I: 09c4

Spoiler: Entitlements

Type Caid Provid ID Class Start Date Expire Date Name
tier 1843 008011 0000000000000066 00000000 2018-09-03 2019-09-04 HD+ Gratisjahr (HD02)
tier 1843 008011 0000000000000067 00000000 2010-01-19 2010-01-20 HD+ ungenutzt

 

[pehedima]

Setze bitte den debug_level = 0
Starte oscam komplett neu und poste erneut die oscam.log vom oscam start an bis zum Fehler.

Poste bitte noch die oscam.conf.

 

[setti]

Das ging ja schnell. Damit habe ich gar nicht gerechnet:

Spoiler: Log

2019/02/06 13:25:18 00000000 s     (main) System name    = Linux
2019/02/06 13:25:18 00000000 s     (main) Host name      = satip-axe
2019/02/06 13:25:18 00000000 s     (main) Release        = 2.6.32.42_stm24_0208-idl4k_7108
2019/02/06 13:25:18 00000000 s     (main) Version        = #134 PREEMPT Sun Oct 21 15:49:45 CEST 2018
2019/02/06 13:25:18 00000000 s     (main) Machine        = sh4
2019/02/06 13:25:18 00000000 s     (main) creating pidfile /tmp/.oscam/oscam.pid with pid 2513
2019/02/06 13:25:18 00000000 s   (config) services reloaded: 0 services freed, 5 services loaded, rejected 0
2019/02/06 13:25:18 00000000 s   (config) userdb reloaded: 4 accounts loaded, 0 expired, 0 disabled
2019/02/06 13:25:18 00000000 s     (main) signal handling initialized
2019/02/06 13:25:18 00000000 s   (config) 14 provid's loaded
2019/02/06 13:25:18 00000000 s   (config) 350 service-id's loaded in 17 ms
2019/02/06 13:25:18 00000000 s   (config) 2 tier-id's loaded
2019/02/06 13:25:18 00000000 s      (net) cs378x: initialized (fd=5, port=34043, ip=192.168.0.11)
2019/02/06 13:25:18 00000000 s      (net) cccam: initialized (fd=6, port=34044)
2019/02/06 13:25:18 00000000 s      (net) scam: initialized (fd=7, port=34045)
2019/02/06 13:25:18 00000000 s   (reader) smargo [smartreader] creating thread for device 004:002
2019/02/06 13:25:18 16A7E9E5 r   (reader) smargo [smartreader] Reader initialized (device=004:002, detect=cd, mhz= AUTO, cardmhz=369)
2019/02/06 13:25:18 00000000 s (anticasc) anti cascading disabled
2019/02/06 13:25:18 6A4D5F0A c   (client) plain dvbapi-client granted (dvbapi, au=on (1 reader))
2019/02/06 13:25:18 6A4D5F0A c   (dvbapi) dvbapi channelcache loaded from /etc/sysconfig/oscam/oscam.ccache
2019/02/06 13:25:18 6A4D5F0A c   (dvbapi) Using TCP listen socket, API forced to DVBAPIv3 (0), userconfig boxtype: 12
2019/02/06 13:25:18 2CBBFB86 h    (webif) webif: decompressed 178708 bytes back into 440160 bytes
2019/02/06 13:25:18 2CBBFB86 h    (webif) HTTP Server running. ip=0.0.0.0 port=8888
2019/02/06 13:25:19 16A7E9E5 r   (reader) smargo [smartreader] card detected
2019/02/06 13:25:22 16A7E9E5 r   (reader) smargo [smartreader] ATR: 3F FF 95 00 FF 91 81 71 A0 47 00 44 4E 41 53 50 31 38 30 20 4D 65 72 30 30 30 28
2019/02/06 13:25:22 16A7E9E5 r   (reader) smargo [smartreader] HD-Plus 02 recognized
2019/02/06 13:25:22 16A7E9E5 r   (reader) smargo [smartreader] Effective reader settings mhz =534 F= 512 D= 16 N=0 T=1 inv=1 parity=ODD
2019/02/06 13:25:24 16A7E9E5 r   (reader) smargo [smartreader] detect native nagra card
2019/02/06 13:25:26 16A7E9E5 r   (reader) smargo [smartreader] ready for requests
2019/02/06 13:25:26 16A7E9E5 r   (reader) smargo [smartreader] found card system nagra
2019/02/06 13:25:26 16A7E9E5 r   (reader) smargo [nagra] THIS WAS A SUCCESSFUL START ATTEMPT No  1 out of max alloted of 1
2019/02/06 13:25:26 16A7E9E5 r   (reader) smargo [nagra] ROM:    D N A S P 1 8 0
2019/02/06 13:25:26 16A7E9E5 r   (reader) smargo [nagra] REV:    M e r 0 0 0
2019/02/06 13:25:26 16A7E9E5 r   (reader) smargo [nagra] SER:    ############
2019/02/06 13:25:26 16A7E9E5 r   (reader) smargo [nagra] CAID:   1843
2019/02/06 13:25:26 16A7E9E5 r   (reader) smargo [nagra] Prv.ID: 00 00 34 11 (sysid)
2019/02/06 13:25:26 16A7E9E5 r   (reader) smargo [nagra] Prv.ID: 00 00 00 00
2019/02/06 13:25:27 16A7E9E5 r   (reader) smargo [nagra] IRD ID: ############
2019/02/06 13:25:27 16A7E9E5 r   (reader) smargo [nagra] active to: 2018/11/18 11:59
2019/02/06 13:25:27 16A7E9E5 r   (reader) smargo [nagra] -----------------------------------------
2019/02/06 13:25:27 16A7E9E5 r   (reader) smargo [nagra] |id  |tier    |valid from  |valid to    |
2019/02/06 13:25:27 16A7E9E5 r   (reader) smargo [nagra] +----+--------+------------+------------+
2019/02/06 13:25:27 16A7E9E5 r   (reader) smargo [nagra] |8011|0066    |2018/09/03  |2019/09/04  |
2019/02/06 13:25:27 16A7E9E5 r   (reader) smargo [nagra] |8011|0067    |2010/01/19  |2010/01/20  |
2019/02/06 13:25:27 16A7E9E5 r   (reader) smargo [nagra] -----------------------------------------
2019/02/06 13:25:35 6A4D5F0A c   (dvbapi) Client connected: 'Tvheadend 4.3-1733~g7d3aa1194' (protocol version = 2)
2019/02/06 13:25:35 6A4D5F0A c   (dvbapi) Demuxer 0 ecmpid 0 CAID: 06CB ECM_PID: 0078 PROVID: 000000
2019/02/06 13:25:35 6A4D5F0A c   (dvbapi) Demuxer 0 ecmpid 1 CAID: 098D ECM_PID: 0108 PROVID: 000000
2019/02/06 13:25:35 6A4D5F0A c   (dvbapi) Demuxer 0 ecmpid 2 CAID: 09C4 ECM_PID: 1BCC PROVID: 000000
2019/02/06 13:25:35 6A4D5F0A c   (dvbapi) Demuxer 0 ecmpid 3 CAID: 1860 ECM_PID: 1ECC PROVID: 000000
2019/02/06 13:25:35 6A4D5F0A c   (dvbapi) Demuxer 0 ecmpid 4 CAID: 1830 ECM_PID: 1CCC PROVID: 000000
2019/02/06 13:25:35 6A4D5F0A c   (dvbapi) Demuxer 0 ecmpid 5 CAID: 1843 ECM_PID: 19CC PROVID: 000000
2019/02/06 13:25:35 6A4D5F0A c   (dvbapi) Demuxer 0 ecmpid 6 CAID: 186D ECM_PID: 1FCD PROVID: 000000
2019/02/06 13:25:35 6A4D5F0A c   (dvbapi) Demuxer 0 ecmpid 7 CAID: 186A ECM_PID: 1FCC PROVID: 000000
2019/02/06 13:25:35 6A4D5F0A c   (dvbapi) Demuxer 0 ecmpid 8 CAID: 0500 ECM_PID: 0109 PROVID: 051900
2019/02/06 13:25:35 6A4D5F0A c   (dvbapi) Demuxer 0 ecmpid 9 CAID: 098C ECM_PID: 1ACC PROVID: 000000
2019/02/06 13:25:35 6A4D5F0A c   (dvbapi) Demuxer 0 found 10 ECMpids and 4 STREAMpids in caPMT
2019/02/06 13:25:35 6A4D5F0A c   (dvbapi) Demuxer 0 trying to descramble PID 5 CAID 1843 PROVID 000000 ECMPID 19CC ANY CHID PMTPID 0060 VPID 00FF
2019/02/06 13:25:35 6A4D5F0A c   (dvbapi) Demuxer 0 trying to descramble PID 3 CAID 1860 PROVID 000000 ECMPID 1ECC ANY CHID PMTPID 0060 VPID 00FF
2019/02/06 13:25:35 6A4D5F0A c   (dvbapi) Demuxer 0 trying to descramble PID 4 CAID 1830 PROVID 000000 ECMPID 1CCC ANY CHID PMTPID 0060 VPID 00FF
2019/02/06 13:25:35 6A4D5F0A c   (dvbapi) Demuxer 0 trying to descramble PID 6 CAID 186D PROVID 000000 ECMPID 1FCD ANY CHID PMTPID 0060 VPID 00FF
2019/02/06 13:25:35 6A4D5F0A c   (dvbapi) Demuxer 0 trying to descramble PID 7 CAID 186A PROVID 000000 ECMPID 1FCC ANY CHID PMTPID 0060 VPID 00FF
2019/02/06 13:25:35 6A4D5F0A c   (dvbapi) Demuxer 0 continue decoding of SRVID EF10
2019/02/06 13:25:35 6A4D5F0A c   (dvbapi) Demuxer 0 found 10 ECMpids and 4 STREAMpids in caPMT
2019/02/06 13:25:35 6A4D5F0A c   (dvbapi) Demuxer 0 found 10 ECMpids and 7 STREAMpids in PMT
2019/02/06 13:25:36 6A4D5F0A c      (ecm) dvbapi (1843@000000/0000/EF10/89:8E5AC7D7B1B9EBE82D246FFA10ADBEE2): not found (334 ms) by smargo - RTL HD

Spoiler: oscam.conf

# oscam.conf generated automatically by Streamboard OSCAM 1.20_svn SVN r11494
# Read more:

Sie müssen registriert sein, um Links zu sehen.

[global]
logfile = /tmp/oscam.log
maxlogsize = 1024
waitforcards = 0
preferlocalcards = 2
block_same_ip = 0
block_same_name = 0
lb_mode = 1
lb_save = 100
lb_force_reopen_always = 1

[cache]

[cs378x]
port = 34043
serverip = 192.168.0.11
suppresscmd08 = 1

[cccam]
port = 34044
nodeid = 084C177D109413C0
version = 2.3.2
reshare = 2
ignorereshare = 1
updateinterval = 30
recv_timeout = 7000

[scam]
port = 34045

[dvbapi]
enabled = 1
au = 1
pmt_mode = 4
request_mode = 1
listen_port = 9000
user = dvbapi
read_sdt = 2
boxtype = pc-nodmx

[webif]
httpport = 8888
httpallowed = 127.0.0.1,0.0.0.0-255.255.255.255
httpemmsclean = 256
httpemmgclean = 256

Ich sehe gerade in der Log:
smargo [nagra] active to: 2018/11/18 11:59

Woher rührt das, wenn ich doch im Gratisjahr bin und entsprechendes Tier auch habe?!

 

[pehedima]

active to: 2018/11/18 11:59

Die Karte braucht shared EMMs. Die läuft ja schon seid Mitte Nov. letzten Jahres nicht mehr.
Welche oscam Version läuft auf der Kiste? okay scheint 11494 zu sein.

Wenn ich das listen_port Geraffel in der oscam.conf sehe, hast du sicherlich einen Client dran.
Der sollte EMMs liefern, was er wahrscheinlich nicht tut.

Um welchen Client geht es?

 

[setti]

Alles klar, danke. Dann werde ich ihr mal welche geben. Lange nicht mit beschäftigt...

achso: OSCam r11494, gestern compiled

 

[pehedima]

Was hängt da für ein Client dran?

 

[setti]

Tvheadend über dvbapi

 

[pehedima]

Okay.
Da müssen auf jeden Fall EMMs rüber kommen, sonst wird das nichts mit der Erhellung.
Lass das mal eine zeitlang auf einen HD+ Sender laufen (z.B.: RTL HD) und schau ob EMMs ankommen.
Evtl. im Reader mal emmcache = 1,1,10,0 einstellen, dann siehtst du gleich im logfile, ob dort EMMs prokolliert werden.

 

[setti]

Jop, trudeln schon ein. Werden die auch gleich auf die Karte geschrieben, wenn ich auch saveemm-s = 1 habe?

 

[pehedima]

Ja.
Mit saveemm-s = 1 werden die nur zusätzlich gespeichert.
Da sollten ja dann auch 'written' stehen.

 

[merlin57]

Lass mal ein paar Globale drauf dann wird in 1 min hell

 

[setti]

TOP: Läuft alles. Und ab jetzt gibts regelmäßig EMMs Danke schön.

 

[merlin57]

Das valid to Datum sagt dir wann wieder dunkel wird