Oscam Client timeout (5000 ms) by .........

[Altair007]

Hallo Zusammen

Meine Clients bekommen immer wieder einmal diesen Timeout
[cs378x] disconnected: reason rto
timeout (5000 ms) by .........
[DVBAPI] Demuxer #0 trying to descramble PID #3 CAID 098C PROVID 000000 ECMPID 1A11 ANY CHID PMTPID 0063 VPID 03FF
Natürlich steht bei den Clients dann das Bild für einige Zeit. Teilweise Minuten lang.
Die Verbindung ist ein reines LAN für HS und LAN und Internet 16000+ für CS.
Welche Möglichkeiten gibt es dieses zu verhindern?
Ich benutzte die version 1.20-modern, build r1131 und setzte momentan cs378x und cccam ein.
Benutzte den [Cache] delay und keepconnected + Forward_origin_Card in den configs. keepalive und emmcache im Server.

 

[dodo83]

AW: Oscam Client timeout (5000 ms) by .........

hi
kommt es zu den Verbindungs abrrüchen im HS oder beim Inet CS?
bei beiden Protokolen?
poste mal ein Vernünftiges log dazu wenn es zum Timeout kommt

 

[Altair007]

AW: Oscam Client timeout (5000 ms) by .........

Es kommt sowohl als auch vor. Hs ist aber weniger davon betroffen . Im CS kommt es immer wieder mal vor.
2 Karten im Einsatz. Am Server fogende Timings der V14 mit 078ms und HD02 mit 380ms.
Server und Client ist ne Dream mit internen Schacht in use.

Spoiler

-------------------------------------------------------------------------------
>> OSCam << cardserver started at Thu Jan 1 01:01:01 1970
-------------------------------------------------------------------------------
1970/01/01 01:01:01 0 s >> OSCam << cardserver started, version 1.20-modern, build r1131 (mipsel-unknown-linux-gnu)
1970/01/01 01:01:01 0 s creating pidfile /tmp/.oscam/oscam.pid with pid 809
1970/01/01 01:01:01 0 s The current system time is smaller than the build date (Nov 5 2014). Waiting up to 60 seconds for time to correct
2014/11/12 19:49:24 0 s services reloaded: 0 services freed, 14 services loaded, rejected 0
2014/11/12 19:49:24 0 s [Emu] OSEmu (built-in) version 706
2014/11/12 19:49:24 0 s userdb reloaded: 1 accounts loaded, 0 expired, 0 disabled
2014/11/12 19:49:24 0 s signal handling initialized
2014/11/12 19:49:24 0 s 512 service-id's loaded in 25 ms
2014/11/12 19:49:24 0 s 36 tier-id's loaded
2014/11/12 19:49:24 0 s Server [cs378x] creating thread for device xxxxxxxxxxxxx.xxxxx.net
2014/11/12 19:49:24 0 s ORF [emu] creating thread for device emulator
2014/11/12 19:49:24 0 s loadbalancer: can't read from file /tmp/.oscam/stat
2014/11/12 19:49:24 0 s waiting for local card init
2014/11/12 19:49:24 A4AE70 p cs378x proxy xxxxxxxxxxxxx.xxxxx.net:xxxxx
2014/11/12 19:49:24 A2EB78 h webif: decompressed 168289 bytes back into 385560 bytes
2014/11/12 19:49:24 A2EB78 h HTTP Server running. ip=0.0.0.0 port=1001
2014/11/12 19:49:24 A4AE70 p xxxxxxxxxxxxx.xxxxx.net: resolved ip=217.94.132.141
2014/11/12 19:49:24 A4AE70 p Server [cs378x] connecting to xxxxxxxxxxxxx.xxxxx.net:xxxxx
2014/11/12 19:49:24 A4AE70 p Server [cs378x] proxy initialized, server xxxxxxxxxxxxx.xxxxx.net:xxxxx
2014/11/12 19:49:24 A4CB40 r ORF [emu] Reader initialized (device=emulator, detect=cd, mhz=357, cardmhz=357)
2014/11/12 19:49:25 A4CB40 r ORF [emu] card detected
2014/11/12 19:49:26 A4CB40 r ORF [emu] found card system emu
2014/11/12 19:49:26 A4CB40 r ORF [emu] THIS WAS A SUCCESSFUL START ATTEMPT No 1 out of max alloted of 1
2014/11/12 19:49:26 0 s init for all local cards done
2014/11/12 19:49:26 775078D0 c plain dvbapi-client granted (local, au=on (2 reader))
2014/11/12 19:49:26 775078D0 c [DVBAPI] Detected /dev/dvb/adapter0/demux0 Api: 0, userconfig boxtype: 1
2014/11/12 19:49:28 775078D0 c [ADD PID 0] CAID: 1833 ECM_PID: 1811 PROVID: 000000
2014/11/12 19:49:28 775078D0 c [ADD PID 1] CAID: 09C4 ECM_PID: 1B11 PROVID: 000000
2014/11/12 19:49:28 775078D0 c [ADD PID 2] CAID: 09AF ECM_PID: 1F11 PROVID: 000000
2014/11/12 19:49:28 775078D0 c [ADD PID 3] CAID: 098C ECM_PID: 1A11 PROVID: 000000
2014/11/12 19:49:28 775078D0 c Found 4 ECMpids and 3 STREAMpids in PMT
2014/11/12 19:49:28 775078D0 c [DVBAPI] Receiver wants to demux srvid 0074 on adapter 0000 camask 0008 index 0003 pmtpid 0063
2014/11/12 19:49:28 775078D0 c New program number: 0074 (Sky Action HD) [pmt_list_management 3]
2014/11/12 19:49:28 775078D0 c [DVBAPI] Demuxer #0 trying to descramble PID #3 CAID 098C PROVID 000000 ECMPID 1A11 ANY CHID PMTPID 0063 VPID 03FF
2014/11/12 19:49:28 775078D0 c local (098C/0074/98): found (110 ms) by Server - Sky Action HD
2014/11/12 19:49:43 0 --- Skipped 2 duplicated log lines ---
2014/11/12 19:49:43 775078D0 c local (098C/0074/98): found (109 ms) by Server - Sky Action HD
2014/11/12 19:49:57 0 --- Skipped 1 duplicated log lines ---
2014/11/12 19:49:57 775078D0 c local (098C/0074/98): found (110 ms) by Server - Sky Action HD
2014/11/12 19:50:11 0 --- Skipped 1 duplicated log lines ---
2014/11/12 19:50:11 775078D0 c local (098C/0074/98): found (109 ms) by Server - Sky Action HD
2014/11/12 19:50:18 775078D0 c local (098C/0074/98): found (110 ms) by Server - Sky Action HD
2014/11/12 19:50:39 0 --- Skipped 2 duplicated log lines ---
2014/11/12 19:50:39 775078D0 c local (098C/0074/98): found (108 ms) by Server - Sky Action HD
2014/11/12 19:50:46 775078D0 c local (098C/0074/98): found (110 ms) by Server - Sky Action HD
2014/11/12 19:51:07 0 --- Skipped 2 duplicated log lines ---
2014/11/12 19:51:07 775078D0 c local (098C/0074/98): found (109 ms) by Server - Sky Action HD
2014/11/12 19:51:21 0 --- Skipped 1 duplicated log lines ---
2014/11/12 19:51:21 775078D0 c local (098C/0074/98): found (110 ms) by Server - Sky Action HD
2014/11/12 19:51:35 0 --- Skipped 1 duplicated log lines ---
2014/11/12 19:51:35 775078D0 c local (098C/0074/98): found (109 ms) by Server - Sky Action HD
2014/11/12 19:51:49 0 --- Skipped 1 duplicated log lines ---
2014/11/12 19:51:49 775078D0 c local (098C/0074/98): found (110 ms) by Server - Sky Action HD
2014/11/12 19:52:03 0 --- Skipped 1 duplicated log lines ---
2014/11/12 19:52:03 775078D0 c local (098C/0074/98): found (109 ms) by Server - Sky Action HD
2014/11/12 19:52:24 0 --- Skipped 2 duplicated log lines ---
2014/11/12 19:52:24 775078D0 c local (098C/0074/98): found (110 ms) by Server - Sky Action HD
2014/11/12 19:52:45 0 --- Skipped 2 duplicated log lines ---
2014/11/12 19:52:45 775078D0 c local (098C/0074/98): found (109 ms) by Server - Sky Action HD
2014/11/12 19:52:52 775078D0 c local (098C/0074/98): found (110 ms) by Server - Sky Action HD
2014/11/12 19:52:59 775078D0 c local (098C/0074/98): found (109 ms) by Server - Sky Action HD
2014/11/12 19:53:13 0 --- Skipped 1 duplicated log lines ---
2014/11/12 19:53:13 775078D0 c local (098C/0074/98): found (110 ms) by Server - Sky Action HD
2014/11/12 19:53:20 775078D0 c local (098C/0074/98): found (115 ms) by Server - Sky Action HD
2014/11/12 19:53:27 775078D0 c local (098C/0074/98): found (110 ms) by Server - Sky Action HD
2014/11/12 19:53:34 775078D0 c local (098C/0074/98): found (109 ms) by Server - Sky Action HD
2014/11/12 19:53:36 775078D0 c Found 0 ECMpids and 9 STREAMpids in PMT
2014/11/12 19:53:36 775078D0 c [DVBAPI] Receiver wants to demux srvid 283D on adapter 0000 camask 0008 index 0003 pmtpid 13EC
2014/11/12 19:53:36 775078D0 c New program number: 283D (Das Erste HD) [pmt_list_management 3]
2014/11/12 20:15:37 775078D0 c Found 0 ECMpids and 8 STREAMpids in PMT
2014/11/12 20:15:37 775078D0 c [DVBAPI] Receiver wants to demux srvid 2B66 on adapter 0000 camask 0008 index 0003 pmtpid 17D4
2014/11/12 20:15:37 775078D0 c New program number: 2B66 (ZDF HD) [pmt_list_management 3]
2014/11/12 21:45:04 775078D0 c Found 0 ECMpids and 9 STREAMpids in PMT
2014/11/12 21:45:04 775078D0 c [DVBAPI] Receiver wants to demux srvid 283D on adapter 0000 camask 0008 index 0003 pmtpid 13EC
2014/11/12 21:45:04 775078D0 c New program number: 283D (Das Erste HD) [pmt_list_management 3]
2014/11/12 21:45:33 775078D0 c [ADD PID 0] CAID: 1833 ECM_PID: 18B6 PROVID: 000000
2014/11/12 21:45:33 775078D0 c [ADD PID 1] CAID: 09C4 ECM_PID: 1BB6 PROVID: 000000
2014/11/12 21:45:33 775078D0 c [ADD PID 2] CAID: 09AF ECM_PID: 1FB6 PROVID: 000000
2014/11/12 21:45:33 775078D0 c [ADD PID 3] CAID: 098C ECM_PID: 1AB6 PROVID: 000000
2014/11/12 21:45:33 775078D0 c Found 4 ECMpids and 3 STREAMpids in PMT
2014/11/12 21:45:33 775078D0 c [DVBAPI] Receiver wants to demux srvid 0083 on adapter 0000 camask 0008 index 0003 pmtpid 0064
2014/11/12 21:45:33 775078D0 c New program number: 0083 (Sky Cinema HD) [pmt_list_management 3]
2014/11/12 21:45:33 775078D0 c [DVBAPI] Demuxer #0 trying to descramble PID #3 CAID 098C PROVID 000000 ECMPID 1AB6 ANY CHID PMTPID 0064 VPID 04FF
2014/11/12 21:45:33 775078D0 c local (098C/0083/98): found (110 ms) by Server - Sky Cinema HD
2014/11/12 21:45:43 0 --- Skipped 1 duplicated log lines ---
2014/11/12 21:45:50 0 --- Skipped 1 duplicated log lines ---
2014/11/12 21:45:50 775078D0 c local (098C/0083/98): found (109 ms) by Server - Sky Cinema HD
2014/11/12 21:45:57 775078D0 c local (098C/0083/98): found (110 ms) by Server - Sky Cinema HD
2014/11/12 21:46:11 0 --- Skipped 1 duplicated log lines ---
2014/11/12 21:48:31 775078D0 c local (098C/0083/98): found (116 ms) by Server - Sky Cinema HD
2014/11/12 21:48:38 775078D0 c local (098C/0083/98): found (113 ms) by Server - Sky Cinema HD
2014/11/12 21:48:44 775078D0 c [ADD PID 0] CAID: 1833 ECM_PID: 1811 PROVID: 000000
2014/11/12 21:48:44 775078D0 c [ADD PID 1] CAID: 09C4 ECM_PID: 1B11 PROVID: 000000
2014/11/12 21:48:44 775078D0 c [ADD PID 2] CAID: 09AF ECM_PID: 1F11 PROVID: 000000
2014/11/12 21:48:44 775078D0 c [ADD PID 3] CAID: 098C ECM_PID: 1A11 PROVID: 000000
2014/11/12 21:48:44 775078D0 c Found 4 ECMpids and 3 STREAMpids in PMT
2014/11/12 21:48:44 775078D0 c [DVBAPI] Receiver wants to demux srvid 0074 on adapter 0000 camask 0008 index 0003 pmtpid 0063
2014/11/12 21:48:44 775078D0 c New program number: 0074 (Sky Action HD) [pmt_list_management 3]
2014/11/12 21:48:44 775078D0 c [DVBAPI] Demuxer #0 trying to descramble PID #3 CAID 098C PROVID 000000 ECMPID 1A11 ANY CHID PMTPID 0063 VPID 03FF
2014/11/12 21:48:44 775078D0 c local (098C/0074/98): found (113 ms) by Server - Sky Action HD
2014/11/12 21:48:46 775078D0 c local (098C/0074/98): found (114 ms) by Server - Sky Action HD
2014/11/12 21:48:53 775078D0 c local (098C/0074/98): found (110 ms) by Server - Sky Action HD
2014/11/12 22:33:07 775078D0 c local (098C/0074/98): found (112 ms) by Server - Sky Action HD
2014/11/12 22:33:19 775078D0 c local (098C/0074/98): timeout (5000 ms) by Server - Sky Action HD
2014/11/12 22:33:20 A4AE70 p Server [cs378x] disconnected: reason disconnect
2014/11/12 22:33:20 A4AE70 p Server [cs378x] connecting to xxxxxxxxxxxxx.xxxxx.net:xxxxx
2014/11/12 22:33:23 A4AE70 p Server [cs378x] connect failed: Connection timed out
2014/11/12 22:33:23 775078D0 c local (098C/0074/98): not found (2220 ms) by Server - Sky Action HD
2014/11/12 22:33:24 775078D0 c [DVBAPI] Demuxer #0 (re)starting decodingrequests on all 4 ecmpids!
2014/11/12 22:33:24 775078D0 c [DVBAPI] Demuxer #0 trying to descramble PID #3 CAID 098C PROVID 000000 ECMPID 1A11 ANY CHID PMTPID 0063 VPID 03FF
2014/11/12 22:33:24 775078D0 c local (098C/0074/98): not found (2 ms) by Server - Sky Action HD
2014/11/12 22:33:24 775078D0 c local (098C/0074/98): timeout (5001 ms) by Server - Sky Action HD
2014/11/12 22:33:24 775078D0 c [DVBAPI] Demuxer #0 (re)starting decodingrequests on all 4 ecmpids!
2014/11/12 22:33:25 775078D0 c [DVBAPI] Demuxer #0 trying to descramble PID #3 CAID 098C PROVID 000000 ECMPID 1A11 ANY CHID PMTPID 0063 VPID 03FF
2014/11/12 22:33:25 775078D0 c local (098C/0074/98): not found (2 ms) by Server - Sky Action HD
2014/11/12 22:33:25 775078D0 c [DVBAPI] Demuxer #0 (re)starting decodingrequests on all 4 ecmpids!
2014/11/12 22:33:26 775078D0 c [DVBAPI] Demuxer #0 trying to descramble PID #3 CAID 098C PROVID 000000 ECMPID 1A11 ANY CHID PMTPID 0063 VPID 03FF
2014/11/12 22:33:26 775078D0 c local (098C/0074/98): not found (1 ms) by Server - Sky Action HD
2014/11/12 22:33:26 775078D0 c [DVBAPI] Demuxer #0 (re)starting decodingrequests on all 4 ecmpids!
2014/11/12 22:33:27 775078D0 c [DVBAPI] Demuxer #0 trying to descramble PID #3 CAID 098C PROVID 000000 ECMPID 1A11 ANY CHID PMTPID 0063 VPID 03FF
2014/11/12 22:33:27 A4AE70 p Server [cs378x] connecting to xxxxxxxxxxxxx.xxxxx.net:xxxxx
2014/11/12 22:33:27 775078D0 c local (098C/0074/98): found (164 ms) by Server - Sky Action HD
2014/11/12 22:33:28 775078D0 c local (098C/0074/98): found (109 ms) by Server - Sky Action HD

 

[dodo83]

AW: Oscam Client timeout (5000 ms) by .........

lass es mal per CCcam Protokol laufen
und Poste mal auch komplette Config vom Server und Client

 

[Altair007]

AW: Oscam Client timeout (5000 ms) by .........

Werde nachher mal wieder auf cccam umstellen. Im Server Log ist in dieser Zeit nichts zu sehen nur das der Client bei found nicht mehr aufgelistet wird.

 

[dodo83]

AW: Oscam Client timeout (5000 ms) by .........

weil er DISCONNECTED und nicht mehr verbunden ist, deswegen kommt keine Anfrage mehr
nur man muss herausfinden ob das Problem Software (OScam,Protokol,etc) oder Hardware (Kabel,etc) technisch ist

 

[Altair007]

AW: Oscam Client timeout (5000 ms) by .........

Ja das ist klar ;-)
Hier mal die logs

oscam Server
Neu sind die beiden internal open Error. Die gab es Gestern noch nicht. Allerdings siehst nicht in der Zeit der Client Aussetzer.

Spoiler

>> OSCam << cardserver started at Sat Nov 15 19:43:14 2014
-------------------------------------------------------------------------------
2014/11/15 19:43:14 0 s >> OSCam << cardserver started, version 1.20-modern, build r1131 (mipsel-unknown-linux-gnu)
2014/11/15 19:43:14 0 s creating pidfile /tmp/.oscam/oscam.pid with pid 168
2014/11/15 19:43:14 0 s services reloaded: 0 services freed, 14 services loaded, rejected 0
2014/11/15 19:43:14 0 s [Emu] OSEmu (built-in) version 706
2014/11/15 19:43:14 0 s userdb reloaded: 8 accounts loaded, 0 expired, 0 disabled
2014/11/15 19:43:14 0 s signal handling initialized
2014/11/15 19:43:14 0 s 513 service-id's loaded in 17 ms
2014/11/15 19:43:14 0 s 37 tier-id's loaded
2014/11/15 19:43:14 0 s cs378x: initialized (fd=5, port=Port)
2014/11/15 19:43:14 0 s cccam: initialized (fd=6, port=Port)
2014/11/15 19:43:14 0 s Sky [internal] creating thread for device /dev/sci0
2014/11/15 19:43:14 0 s HDPlus [internal] creating thread for device /dev/sci1
2014/11/15 19:43:14 0 s ORF [emu] creating thread for device emulator
2014/11/15 19:43:14 0 s loadbalancer: can't read from file /tmp/.oscam/stat
2014/11/15 19:43:14 0 s waiting for local card init
2014/11/15 19:43:14 7C8958 r Sky [internal] Reader initialized (device=/dev/sci0, detect=cd, pll max=27.00 MHz, wanted mhz=3.57 MHz
2014/11/15 19:43:14 76E00468 h webif: decompressed 168289 bytes back into 385560 bytes
2014/11/15 19:43:14 76E00468 h HTTP Server running. ip=0.0.0.0 port=1001
2014/11/15 19:43:14 7CA628 r HDPlus [internal] Reader initialized (device=/dev/sci1, detect=cd, pll max=27.00 MHz, wanted mhz=3.57 MHz
2014/11/15 19:43:14 7EDE28 r ORF [emu] Reader initialized (device=emulator, detect=cd, mhz=357, cardmhz=357)
2014/11/15 19:43:15 7C8958 r Sky [internal] card detected
2014/11/15 19:43:15 7EDE28 r ORF [emu] card detected
2014/11/15 19:43:15 7CA628 r HDPlus [internal] card detected
2014/11/15 19:43:16 7EDE28 r ORF [emu] found card system emu
2014/11/15 19:43:16 7EDE28 r ORF [emu] THIS WAS A SUCCESSFUL START ATTEMPT No 1 out of max alloted of 1
2014/11/15 19:43:18 7C8958 r Sky [internal] ATR: 3F FD 15 25 02 50 80 0F 41 B0 0A 69 FF 4A 50 F0 00 00 50 31 03
2014/11/15 19:43:18 7C8958 r Sky [internal] Init card protocol T0, FI=1, F=372, D=16, N=2
2014/11/15 19:43:18 7C8958 r Sky [internal] TEST tempo mhz check = 500 mhz
2014/11/15 19:43:18 7C8958 r Sky [internal] Calculated work ETU is 5.17 us reader mhz = 450
2014/11/15 19:43:18 7CA628 r HDPlus [internal] 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
2014/11/15 19:43:18 7CA628 r HDPlus [internal] Init card protocol T1, FI=9, F=512, D=16, N=255
2014/11/15 19:43:18 7CA628 r HDPlus [internal] TEST tempo mhz check = 500 mhz
2014/11/15 19:43:18 7CA628 r HDPlus [internal] Calculated work ETU is 5.93 us reader mhz = 540
2014/11/15 19:43:18 7C8958 r Sky [internal] PLL Reader: ATR Fsmax is 5 MHz, clocking card to 4.50 Mhz (nearest possible mhz specified reader->cardmhz)
2014/11/15 19:43:18 7CA628 r HDPlus [internal] PLL Reader: ATR Fsmax is 5 MHz, clocking card to 5.40 Mhz (nearest possible mhz specified reader->cardmhz)
2014/11/15 19:43:18 7CA628 r HDPlus [internal] Card responded ok for ifsd request of 160
2014/11/15 19:43:19 7C8958 r Sky [internal] Card type: P1TV
2014/11/15 19:43:19 7C8958 r Sky [internal] Rom version: 20AA
2014/11/15 19:43:19 7C8958 r Sky [internal] Credit available on card: 0 euro
2014/11/15 19:43:19 7C8958 r Sky [internal] Extended 4C detected
2014/11/15 19:43:19 7C8958 r Sky [internal] FuseByte: 25
2014/11/15 19:43:19 7C8958 r Sky [internal] Region Code: 00BO0102
2014/11/15 19:43:19 7C8958 r Sky [internal] Country Code: DEU
2014/11/15 19:43:19 7CA628 r HDPlus [internal] detect native nagra card
2014/11/15 19:43:19 7C8958 r Sky [internal] Pincode read: 5402
2014/11/15 19:43:19 7C8958 r Sky [internal] PCB settings: FF FF FF FF
2014/11/15 19:43:19 7C8958 r Sky [internal] Parental control disabled
2014/11/15 19:43:19 7C8958 r Sky [internal] type: VideoGuard Sky Austria/Germany (098C), caid: 098C
2014/11/15 19:43:19 7C8958 r Sky [internal] serial: ########, BoxID: ########, baseyear: 2004
2014/11/15 19:43:19 7C8958 r Sky [internal] ready for requests
2014/11/15 19:43:19 7C8958 r Sky [internal] found card system videoguard2
2014/11/15 19:43:19 7C8958 r Sky [videoguard2] THIS WAS A SUCCESSFUL START ATTEMPT No 1 out of max alloted of 1
2014/11/15 19:43:19 7C8958 r Sky [videoguard2] card detected
2014/11/15 19:43:19 7C8958 r Sky [videoguard2] type: VideoGuard Sky Austria/Germany (098C)
2014/11/15 19:43:19 7C8958 r Sky [videoguard2] tier: c647, expiry date: 2014/11/16-00:01:04 Sky Select
2014/11/15 19:43:19 7C8958 r Sky [videoguard2] tier: 0046, expiry date: 2015/02/15-00:00:00 HD+ (Gratisjahr)
2014/11/15 19:43:19 7C8958 r Sky [videoguard2] tier: 0056, expiry date: 2015/02/15-00:00:00 Sky 3D Welt
2014/11/15 19:43:19 7C8958 r Sky [videoguard2] tier: 0057, expiry date: 2015/02/15-00:00:00 Sky 3D Film
2014/11/15 19:43:19 7C8958 r Sky [videoguard2] tier: 0058, expiry date: 2015/02/15-00:00:00 Sky 3D Sport
2014/11/15 19:43:19 7C8958 r Sky [videoguard2] tier: 007b, expiry date: 2015/02/15-00:00:00 Sky AT/DE / 19.2E / de
2014/11/15 19:43:19 7C8958 r Sky [videoguard2] tier: 007d, expiry date: 2015/02/15-00:00:00 Sky Sport SD
2014/11/15 19:43:19 7C8958 r Sky [videoguard2] tier: 0081, expiry date: 2015/02/15-00:00:00 Sky Bundesliga SD
2014/11/15 19:43:19 7C8958 r Sky [videoguard2] tier: 008e, expiry date: 2015/02/15-00:00:00 Beate-Uhse.tv
2014/11/15 19:43:19 7C8958 r Sky [videoguard2] tier: 00c0, expiry date: 2015/02/15-00:00:00 Sky Welt Extra
2014/11/15 19:43:19 7C8958 r Sky [videoguard2] tier: 00c7, expiry date: 2015/02/15-00:00:00 Sky Welt SD
2014/11/15 19:43:19 7C8958 r Sky [videoguard2] tier: 00c8, expiry date: 2015/02/15-00:00:00 Sky Film SD
2014/11/15 19:43:19 7C8958 r Sky [videoguard2] tier: 00c9, expiry date: 2015/02/15-00:00:00 Sky Welt HD
2014/11/15 19:43:19 7C8958 r Sky [videoguard2] tier: 00ca, expiry date: 2015/02/15-00:00:00 Sky Film HD
2014/11/15 19:43:19 7C8958 r Sky [videoguard2] tier: 00e4, expiry date: 2015/02/15-00:00:00 Sky Bundesliga HD
2014/11/15 19:43:19 7C8958 r Sky [videoguard2] tier: 00e5, expiry date: 2015/02/15-00:00:00 Sky Sport HD
2014/11/15 19:43:19 7C8958 r Sky [videoguard2] tier: 00f0, expiry date: 2015/02/15-00:00:00 Sky + / Sky Anytime
2014/11/15 19:43:19 7C8958 r Sky [videoguard2] tier: 00f3, expiry date: 2015/02/15-00:00:00 Sky Blue Movie (Info, 1-3)
2014/11/15 19:43:19 7C8958 r Sky [videoguard2] tier: 00fa, expiry date: 2015/02/15-00:00:00 Sky Blue Movie HD
2014/11/15 19:43:19 7C8958 r Sky [videoguard2] tier: 00ff, expiry date: 2015/02/15-00:00:00 Sky AT/DE / 19.2E / de
2014/11/15 19:43:21 7CA628 r HDPlus [internal] ready for requests
2014/11/15 19:43:21 7CA628 r HDPlus [internal] found card system nagra
2014/11/15 19:43:21 7CA628 r HDPlus [nagra] THIS WAS A SUCCESSFUL START ATTEMPT No 1 out of max alloted of 1
2014/11/15 19:43:21 7CA628 r HDPlus [nagra] ROM: D N A S P 1 8 0
2014/11/15 19:43:21 7CA628 r HDPlus [nagra] REV: M e r 0 0 0
2014/11/15 19:43:21 7CA628 r HDPlus [nagra] SER: ############
2014/11/15 19:43:21 7CA628 r HDPlus [nagra] CAID: 1843
2014/11/15 19:43:21 7CA628 r HDPlus [nagra] Prv.ID: 00 00 34 11 (sysid)
2014/11/15 19:43:21 7CA628 r HDPlus [nagra] Prv.ID: 00 00 00 00
2014/11/15 19:43:21 7CA628 r HDPlus [nagra] IRD ID: ############
2014/11/15 19:43:21 7CA628 r HDPlus [nagra] active to: 2015/01/16 01:35
2014/11/15 19:43:21 7CA628 r HDPlus [nagra] -----------------------------------------
2014/11/15 19:43:21 7CA628 r HDPlus [nagra] |id |tier |valid from |valid to |
2014/11/15 19:43:21 7CA628 r HDPlus [nagra] +----+--------+------------+------------+
2014/11/15 19:43:21 7CA628 r HDPlus [nagra] |8011|0066 |2011/04/22 |2012/04/22 |
2014/11/15 19:43:21 7CA628 r HDPlus [nagra] |8011|0067 |2010/01/19 |2010/01/20 |
2014/11/15 19:43:21 7CA628 r HDPlus [nagra] |8011|0BEA |2014/05/20 |2015/06/25 |
2014/11/15 19:43:21 7CA628 r HDPlus [nagra] -----------------------------------------
2014/11/15 19:43:22 0 s init for all local cards done
2014/11/15 19:43:22 7EFAF8 c plain dvbapi-client granted (local, au=on (3 reader))
2014/11/15 19:43:22 7F1AF0 c encrypted cs378x-client IP granted (dm7020hd, au=on (3 reader))
2014/11/15 19:43:22 7F37C0 c encrypted cs378x-client IP granted (106tv, au=off)
2014/11/15 19:43:22 7EFAF8 c [DVBAPI] Detected /dev/dvb/adapter0/demux0 Api: 0, userconfig boxtype: 1
2014/11/15 19:43:22 7F1AF0 c dm7020hd emm-request sent (reader=Sky, caid=098C, auprovid=000000)
2014/11/15 19:43:22 7F1AF0 c dm7020hd (098C/0083/98): found (85 ms) by Sky - Sky Cinema HD
2014/11/15 19:43:24 7F1AF0 c dm7020hd (098C/0083/98): found (75 ms) by Sky - Sky Cinema HD
2014/11/15 22:35:23 5B3958 r Sky [internal] ERROR: Opening device /dev/sci0 (errno=16 Device or resource busy)
2014/11/15 22:35:23 5B3958 r Sky [internal] Cannot open device: /dev/sci0
2014/11/15 22:35:23 5B5628 r HDPlus [internal] ERROR: Opening device /dev/sci1 (errno=16 Device or resource busy)
2014/11/15 22:35:23 5B5628 r HDPlus [internal] Cannot open device: /dev/sci1
2014/11/15 22:35:26 7F1AF0 c dm7020hd (098C/0083/98): found (76 ms) by Sky - Sky Cinema HD
2014/11/15 22:35:26 7F7160 c 104tv (098C/006B/98): found (74 ms) by Sky - Sky Hits HD
2014/11/15 22:35:27 7F8E30 c 106tv (098C/0008/98): found (74 ms) by Sky - Sky Comedy
2014/11/15 22:35:33 7F1AF0 c dm7020hd (098C/0083/98): found (76 ms) by Sky - Sky Cinema HD
2014/11/15 22:35:33 7F7160 c 104tv (098C/006B/98): found (74 ms) by Sky - Sky Hits HD
2014/11/15 22:35:34 7F8E30 c 106tv (098C/0008/98): found (75 ms) by Sky - Sky Comedy
2014/11/15 22:35:40 7F1AF0 c dm7020hd (098C/0083/98): found (76 ms) by Sky - Sky Cinema HD
2014/11/15 22:35:40 7F7160 c 104tv (098C/006B/98): found (75 ms) by Sky - Sky Hits HD
2014/11/15 22:35:41 7F8E30 c 106tv (098C/0008/98): found (74 ms) by Sky - Sky Comedy
2014/11/15 22:35:47 7F1AF0 c dm7020hd (098C/0083/98): found (76 ms) by Sky - Sky Cinema HD
2014/11/15 22:35:47 7F7160 c 104tv (098C/006B/98): found (75 ms) by Sky - Sky Hits HD
2014/11/15 22:35:48 7F8E30 c 106tv (098C/0008/98): found (74 ms) by Sky - Sky Comedy
2014/11/15 22:35:54 7F1AF0 c dm7020hd (098C/0083/98): found (75 ms) by Sky - Sky Cinema HD
2014/11/15 22:35:54 7F7160 c 104tv (098C/006B/98): found (74 ms) by Sky - Sky Hits HD
2014/11/15 22:35:55 7F8E30 c 106tv (098C/0008/98): found (75 ms) by Sky - Sky Comedy
2014/11/15 22:36:01 7F1AF0 c dm7020hd (098C/0083/98): found (76 ms) by Sky - Sky Cinema HD
2014/11/15 22:36:01 7F7160 c 104tv (098C/006B/98): found (75 ms) by Sky - Sky Hits HD
2014/11/15 22:36:02 7F8E30 c 106tv (098C/0008/98): found (75 ms) by Sky - Sky Comedy
2014/11/15 22:36:08 7F1AF0 c dm7020hd (098C/0083/98): found (76 ms) by Sky - Sky Cinema HD
2014/11/15 22:36:08 7F7160 c 104tv (098C/006B/98): found (74 ms) by Sky - Sky Hits HD
2014/11/15 22:36:09 7F8E30 c 106tv (098C/0008/98): found (74 ms) by Sky - Sky Comedy
2014/11/15 22:36:15 7F1AF0 c dm7020hd (098C/0083/98): found (76 ms) by Sky - Sky Cinema HD
2014/11/15 22:36:15 7F7160 c 104tv (098C/006B/98): found (75 ms) by Sky - Sky Hits HD
2014/11/15 22:36:16 7F8E30 c 106tv (098C/0008/98): found (77 ms) by Sky - Sky Comedy
2014/11/15 22:36:22 7F1AF0 c dm7020hd (098C/0083/98): found (76 ms) by Sky - Sky Cinema HD
2014/11/15 22:36:22 7F7160 c 104tv (098C/006B/98): found (75 ms) by Sky - Sky Hits HD
2014/11/15 22:36:23 7F8E30 c 106tv (098C/0008/98): found (75 ms) by Sky - Sky Comedy
2014/11/15 22:36:23 5B3958 r Sky [internal] ERROR: Opening device /dev/sci0 (errno=16 Device or resource busy)
2014/11/15 22:36:23 5B3958 r Sky [internal] Cannot open device: /dev/sci0
2014/11/15 22:36:23 5B5628 r HDPlus [internal] ERROR: Opening device /dev/sci1 (errno=16 Device or resource busy)
2014/11/15 22:36:23 5B5628 r HDPlus [internal] Cannot open device: /dev/sci1
2014/11/15 22:36:29 7F1AF0 c dm7020hd (098C/0083/98): found (76 ms) by Sky - Sky Cinema HD
2014/11/15 22:36:29 7F7160 c 104tv (098C/006B/98): found (75 ms) by Sky - Sky Hits HD
2014/11/15 22:36:30 7F8E30 c 106tv (098C/0008/98): found (74 ms) by Sky - Sky Comedy
2014/11/15 22:36:36 7F1AF0 c dm7020hd (098C/0083/98): found (76 ms) by Sky - Sky Cinema HD
2014/11/15 22:36:36 7F7160 c 104tv (098C/006B/98): found (75 ms) by Sky - Sky Hits HD
2014/11/15 22:36:37 7F8E30 c 106tv (098C/0008/98): found (74 ms) by Sky - Sky Comedy
2014/11/15 22:36:43 7F1AF0 c dm7020hd (098C/0083/98): found (76 ms) by Sky - Sky Cinema HD
2014/11/15 22:36:43 7F7160 c 104tv (098C/006B/98): found (75 ms) by Sky - Sky Hits HD
2014/11/15 22:36:44 7F8E30 c 106tv (098C/0008/98): found (74 ms) by Sky - Sky Comedy
2014/11/15 22:36:50 7F1AF0 c dm7020hd (098C/0083/98): found (76 ms) by Sky - Sky Cinema HD
2014/11/15 22:36:51 7F7160 c 104tv (098C/006B/98): found (75 ms) by Sky - Sky Hits HD
2014/11/15 22:36:51 7F8E30 c 106tv (098C/0008/98): found (75 ms) by Sky - Sky Comedy
2014/11/15 22:36:57 7F1AF0 c dm7020hd (098C/0083/98): found (75 ms) by Sky - Sky Cinema HD
2014/11/15 22:36:58 7F7160 c 104tv (098C/006B/98): found (75 ms) by Sky - Sky Hits HD
2014/11/15 22:36:58 7F8E30 c 106tv (098C/0008/98): found (74 ms) by Sky - Sky Comedy
2014/11/15 22:37:04 7F1AF0 c dm7020hd (098C/0083/98): found (76 ms) by Sky - Sky Cinema HD
2014/11/15 22:37:05 7F7160 c 104tv (098C/006B/98): found (74 ms) by Sky - Sky Hits HD
2014/11/15 22:37:05 7F8E30 c 106tv (098C/0008/98): found (75 ms) by Sky - Sky Comedy
2014/11/15 22:37:11 7F1AF0 c dm7020hd (098C/0083/98): found (75 ms) by Sky - Sky Cinema HD
2014/11/15 22:37:12 7F7160 c 104tv (098C/006B/98): found (75 ms) by Sky - Sky Hits HD
2014/11/15 22:37:12 7F8E30 c 106tv (098C/0008/98): found (75 ms) by Sky - Sky Comedy
2014/11/15 22:37:18 7F1AF0 c dm7020hd (098C/0083/98): found (76 ms) by Sky - Sky Cinema HD
2014/11/15 22:37:19 7F7160 c 104tv (098C/006B/98): found (74 ms) by Sky - Sky Hits HD
2014/11/15 22:37:19 7F8E30 c 106tv (098C/0008/98): found (75 ms) by Sky - Sky Comedy
2014/11/15 22:37:23 5B3958 r Sky [internal] ERROR: Opening device /dev/sci0 (errno=16 Device or resource busy)
2014/11/15 22:37:23 5B3958 r Sky [internal] Cannot open device: /dev/sci0
2014/11/15 22:37:23 5B5628 r HDPlus [internal] ERROR: Opening device /dev/sci1 (errno=16 Device or resource busy)
2014/11/15 22:37:23 5B5628 r HDPlus [internal] Cannot open device: /dev/sci1
2014/11/15 22:37:25 7F1AF0 c dm7020hd (098C/0083/98): found (76 ms) by Sky - Sky Cinema HD
2014/11/15 22:37:26 7F7160 c 104tv (098C/006B/98): found (75 ms) by Sky - Sky Hits HD
2014/11/15 22:37:26 7F8E30 c 106tv (098C/0008/98): found (75 ms) by Sky - Sky Comedy
2014/11/15 22:37:32 7F1AF0 c dm7020hd (098C/0083/98): found (75 ms) by Sky - Sky Cinema HD
2014/11/15 22:37:33 7F7160 c 104tv (098C/006B/98): found (74 ms) by Sky - Sky Hits HD
2014/11/15 22:37:33 7F8E30 c 106tv (098C/0008/98): found (75 ms) by Sky - Sky Comedy
2014/11/15 22:37:39 7F1AF0 c dm7020hd (098C/0083/98): found (76 ms) by Sky - Sky Cinema HD
2014/11/15 22:37:40 7F7160 c 104tv (098C/006B/98): found (74 ms) by Sky - Sky Hits HD
2014/11/15 22:37:40 7F8E30 c 106tv (098C/0008/98): found (74 ms) by Sky - Sky Comedy
2014/11/15 22:37:46 7F1AF0 c dm7020hd (098C/0083/98): found (75 ms) by Sky - Sky Cinema HD
2014/11/15 22:37:47 7F7160 c 104tv (098C/006B/98): found (74 ms) by Sky - Sky Hits HD
2014/11/15 22:37:47 7F8E30 c 106tv (098C/0008/98): found (75 ms) by Sky - Sky Comedy
2014/11/15 22:37:53 7F1AF0 c dm7020hd (098C/0083/98): found (75 ms) by Sky - Sky Cinema HD
2014/11/15 22:37:54 7F7160 c 104tv (098C/006B/98): found (76 ms) by Sky - Sky Hits HD
2014/11/15 22:37:54 7F8E30 c 106tv (098C/0008/98): found (75 ms) by Sky - Sky Comedy
===================================================== AUSSETZER Client =====================================
2014/11/15 22:38:00 7F1AF0 c dm7020hd (098C/0083/98): found (75 ms) by Sky - Sky Cinema HD
2014/11/15 22:38:01 7F8E30 c 106tv (098C/0008/98): found (75 ms) by Sky - Sky Comedy
2014/11/15 22:38:01 7F7160 c 104tv (098C/006B/98): cache1 (100 ms) by Sky - Sky Hits HD
2014/11/15 22:38:01 7F7160 c 104tv (098C/006B/98): found (101 ms) by Sky - Sky Hits HD
2014/11/15 22:38:07 7F1AF0 c dm7020hd (098C/0083/98): found (76 ms) by Sky - Sky Cinema HD
2014/11/15 22:38:08 7F7160 c 104tv (098C/006B/98): cache1 (100 ms) by Sky - Sky Hits HD
2014/11/15 22:38:08 7F7160 c 104tv (098C/006B/98): found (102 ms) by Sky - Sky Hits HD
2014/11/15 22:38:08 7F8E30 c 106tv (098C/0008/98): found (74 ms) by Sky - Sky Comedy
===================================================== AUSSETZER =====================================
2014/11/15 22:38:14 7F1AF0 c dm7020hd (098C/0083/98): found (76 ms) by Sky - Sky Cinema HD
2014/11/15 22:38:15 7F7160 c 104tv (098C/006B/98): found (75 ms) by Sky - Sky Hits HD
2014/11/15 22:38:15 7F8E30 c 106tv (098C/0008/98): found (74 ms) by Sky - Sky Comedy
2014/11/15 22:38:21 7F1AF0 c dm7020hd (098C/0083/98): found (76 ms) by Sky - Sky Cinema HD
2014/11/15 22:38:22 7F7160 c 104tv (098C/006B/98): found (74 ms) by Sky - Sky Hits HD
2014/11/15 22:38:22 7F8E30 c 106tv (098C/0008/98): found (75 ms) by Sky - Sky Comedy
2014/11/15 22:38:23 5B3958 r Sky [internal] ERROR: Opening device /dev/sci0 (errno=16 Device or resource busy)
2014/11/15 22:38:23 5B3958 r Sky [internal] Cannot open device: /dev/sci0
2014/11/15 22:38:23 5B5628 r HDPlus [internal] ERROR: Opening device /dev/sci1 (errno=16 Device or resource busy)
2014/11/15 22:38:23 5B5628 r HDPlus [internal] Cannot open device: /dev/sci1
2014/11/15 22:38:28 7F1AF0 c dm7020hd (098C/0083/98): found (76 ms) by Sky - Sky Cinema HD
2014/11/15 22:38:29 7F7160 c 104tv (098C/006B/98): found (74 ms) by Sky - Sky Hits HD
2014/11/15 22:38:29 7F8E30 c 106tv (098C/0008/98): found (75 ms) by Sky - Sky Comedy
2014/11/15 22:38:35 7F1AF0 c dm7020hd (098C/0083/98): found (75 ms) by Sky - Sky Cinema HD
2014/11/15 22:38:36 7F7160 c 104tv (098C/006B/98): found (75 ms) by Sky - Sky Hits HD
2014/11/15 22:38:36 7F8E30 c 106tv (098C/0008/98): found (75 ms) by Sky - Sky Comedy
2014/11/15 22:38:42 7F1AF0 c dm7020hd (098C/0083/98): found (77 ms) by Sky - Sky Cinema HD
2014/11/15 22:38:43 7F7160 c 104tv (098C/006B/98): found (74 ms) by Sky - Sky Hits HD
2014/11/15 22:38:43 7F8E30 c 106tv (098C/0008/98): found (74 ms) by Sky - Sky Comedy
2014/11/15 22:38:49 7F1AF0 c dm7020hd (098C/0083/98): found (76 ms) by Sky - Sky Cinema HD
2014/11/15 22:38:50 7F7160 c 104tv (098C/006B/98): found (74 ms) by Sky - Sky Hits HD
2014/11/15 22:38:50 7F8E30 c 106tv (098C/0008/98): found (75 ms) by Sky - Sky Comedy
2014/11/15 22:38:57 7F1AF0 c dm7020hd (098C/0083/98): found (76 ms) by Sky - Sky Cinema HD
2014/11/15 22:38:57 7F7160 c 104tv (098C/006B/98): found (74 ms) by Sky - Sky Hits HD
2014/11/15 22:38:57 7F8E30 c 106tv (098C/0008/98): found (74 ms) by Sky - Sky Comedy
2014/11/15 22:39:04 7F1AF0 c dm7020hd (098C/0083/98): found (76 ms) by Sky - Sky Cinema HD
2014/11/15 22:39:04 7F7160 c 104tv (098C/006B/98): found (74 ms) by Sky - Sky Hits HD
2014/11/15 22:39:04 7F8E30 c 106tv (098C/0008/98): found (75 ms) by Sky - Sky Comedy
2014/11/15 22:39:11 7F1AF0 c dm7020hd (098C/0083/98): found (76 ms) by Sky - Sky Cinema HD
2014/11/15 22:39:11 7F7160 c 104tv (098C/006B/98): found (75 ms) by Sky - Sky Hits HD
2014/11/15 22:39:11 7F8E30 c 106tv (098C/0008/98): found (75 ms) by Sky - Sky Comedy
2014/11/15 22:39:18 7F1AF0 c dm7020hd (098C/0083/98): found (75 ms) by Sky - Sky Cinema HD
2014/11/15 22:39:18 7F7160 c 104tv (098C/006B/98): found (74 ms) by Sky - Sky Hits HD
2014/11/15 22:39:18 7F8E30 c 106tv (098C/0008/98): found (75 ms) by Sky - Sky Comedy
2014/11/15 22:39:23 5B3958 r Sky [internal] ERROR: Opening device /dev/sci0 (errno=16 Device or resource busy)
2014/11/15 22:39:23 5B3958 r Sky [internal] Cannot open device: /dev/sci0
2014/11/15 22:39:23 5B5628 r HDPlus [internal] ERROR: Opening device /dev/sci1 (errno=16 Device or resource busy)

oscam Cccam Client

Spoiler

>> OSCam << cardserver started at Thu Jan 1 01:01:01 1970
-------------------------------------------------------------------------------
1970/01/01 01:01:01 0 s >> OSCam << cardserver started, version 1.20-modern, build r1131 (mipsel-unknown-linux-gnu)
1970/01/01 01:01:01 0 s creating pidfile /tmp/.oscam/oscam.pid with pid 813
1970/01/01 01:01:01 0 s The current system time is smaller than the build date (Nov 5 2014). Waiting up to 60 seconds for time to correct
2014/11/15 18:14:28 0 s services reloaded: 0 services freed, 14 services loaded, rejected 0
2014/11/15 18:14:28 0 s [Emu] OSEmu (built-in) version 706
2014/11/15 18:14:28 0 s userdb reloaded: 1 accounts loaded, 0 expired, 0 disabled
2014/11/15 18:14:28 0 s signal handling initialized
2014/11/15 18:14:28 0 s 513 service-id's loaded in 19 ms
2014/11/15 18:14:28 0 s 36 tier-id's loaded
2014/11/15 18:14:28 0 s Server [cccam] creating thread for device ServerIP.ServerIP.net
2014/11/15 18:14:28 0 s ORF [emu] creating thread for device emulator
2014/11/15 18:14:28 0 s loadbalancer: can't read from file /tmp/.oscam/stat
2014/11/15 18:14:28 0 s waiting for local card init
2014/11/15 18:14:28 C2EB78 h webif: decompressed 168289 bytes back into 385560 bytes
2014/11/15 18:14:28 C2EB78 h HTTP Server running. ip=0.0.0.0 port=1001
2014/11/15 18:14:28 C4AEA8 p ServerIP.ServerIP.net: resolved ip=Adress
2014/11/15 18:14:28 C4AEA8 p Server [cccam] connecting to ServerIP.ServerIP.netort
2014/11/15 18:14:29 C4CB78 r ORF [emu] Reader initialized (device=emulator, detect=cd, mhz=357, cardmhz=357)
2014/11/15 18:14:29 C4AEA8 p Server [cccam] proxy initialized, server ServerIP.ServerIP.netort
2014/11/15 18:14:29 C4CB78 r ORF [emu] card detected
2014/11/15 18:14:30 C4CB78 r ORF [emu] found card system emu
2014/11/15 18:14:30 C4CB78 r ORF [emu] THIS WAS A SUCCESSFUL START ATTEMPT No 1 out of max alloted of 1
2014/11/15 18:14:31 0 s init for all local cards done
2014/11/15 18:14:31 C731E8 c plain dvbapi-client granted (local, au=on (2 reader))
2014/11/15 18:14:31 C731E8 c [DVBAPI] Detected /dev/dvb/adapter0/demux0 Api: 0, userconfig boxtype: 1
2014/11/15 18:14:33 C731E8 c Found 0 ECMpids and 8 STREAMpids in PMT
2014/11/15 18:14:33 C731E8 c [DVBAPI] Receiver wants to demux srvid 2B66 on adapter 0000 camask 0008 index 0003 pmtpid 17D4
2014/11/15 18:14:33 C731E8 c New program number: 2B66 (ZDF HD) [pmt_list_management 3]
2014/11/15 18:15:15 C731E8 c [ADD PID 0] CAID: 1833 ECM_PID: 1883 PROVID: 000000
2014/11/15 18:15:15 C731E8 c [ADD PID 1] CAID: 09C4 ECM_PID: 1B83 PROVID: 000000
2014/11/15 18:15:15 C731E8 c [ADD PID 2] CAID: 098C ECM_PID: 1A83 PROVID: 000000
2014/11/15 18:15:15 C731E8 c Found 3 ECMpids and 3 STREAMpids in PMT
2014/11/15 18:15:15 C731E8 c [DVBAPI] Receiver wants to demux srvid 0086 on adapter 0000 camask 0008 index 0003 pmtpid 0060
2014/11/15 18:15:15 C731E8 c New program number: 0086 (Sky Cinema +1 HD) [pmt_list_management 3]
2014/11/15 18:15:15 C731E8 c [DVBAPI] Demuxer #0 trying to descramble PID #2 CAID 098C PROVID 000000 ECMPID 1A83 ANY CHID PMTPID 0060 VPID 00FF
2014/11/15 18:15:15 C731E8 c local (098C/0086/98): found (110 ms) by Server - Sky Cinema +1 HD
2014/11/15 18:15:32 0 --- Skipped 2 duplicated log lines ---
2014/11/15 18:15:32 C731E8 c local (098C/0086/98): found (109 ms) by Server - Sky Cinema +1 HD
2014/11/15 18:26:16 C731E8 c local (098C/0086/98): found (109 ms) by Server - Sky Cinema +1 HD
2014/11/15 18:26:37 0 --- Skipped 2 duplicated log lines ---
2014/11/15 18:26:37 C731E8 c local (098C/0086/98): found (110 ms) by Server - Sky Cinema +1 HD
2014/11/15 18:26:49 C731E8 c local (098C/0086/98): timeout (5000 ms) by Server - Sky Cinema +1 HD
2014/11/15 18:26:52 C731E8 c local (098C/0086/98): not found (2364 ms) by Server - Sky Cinema +1 HD
2014/11/15 18:26:52 C731E8 c local (098C/0086/98): found (363 ms) by Server - Sky Cinema +1 HD
2014/11/15 18:26:52 C731E8 c [DVBAPI] Demuxer #0 (re)starting decodingrequests on all 3 ecmpids!
2014/11/15 18:26:52 C731E8 c [DVBAPI] Demuxer #0 trying to descramble PID #2 CAID 098C PROVID 000000 ECMPID 1A83 ANY CHID PMTPID 0060 VPID 00FF
2014/11/15 18:26:53 C731E8 c local (098C/0086/98): cache1 (1 ms) by Server - Sky Cinema +1 HD
2014/11/15 18:26:59 C731E8 c local (098C/0086/98): found (109 ms) by Server - Sky Cinema +1 HD
2014/11/15 18:27:06 C731E8 c local (098C/0086/98): found (110 ms) by Server - Sky Cinema +1 HD
2014/11/15 18:27:13 C731E8 c local (098C/0086/98): found (109 ms) by Server - Sky Cinema +1 HD
2014/11/15 18:27:21 C731E8 c local (098C/0086/98): found (1743 ms) by Server - Sky Cinema +1 HD
2014/11/15 18:27:27 C731E8 c local (098C/0086/98): found (110 ms) by Server - Sky Cinema +1 HD
2014/11/15 18:53:30 C731E8 c local (098C/0086/98): found (110 ms) by Server - Sky Cinema +1 HD
2014/11/15 18:53:37 C731E8 c local (098C/0086/98): found (111 ms) by Server - Sky Cinema +1 HD
2014/11/15 18:53:48 0 --- Skipped 1 duplicated log lines ---
2014/11/15 18:53:48 C731E8 c [DVBAPI] Demuxer #0 PMT update for decoding of SRVID 0086!
2014/11/15 18:53:48 C731E8 c [DVBAPI] Demuxer #0 continue decoding of SRVID 0086
2014/11/15 18:53:48 C731E8 c [SKIP STREAM 0] CAID: 1833 ECM_PID: 1883 PROVID: 000000
2014/11/15 18:53:48 C731E8 c [SKIP STREAM 1] CAID: 09C4 ECM_PID: 1B83 PROVID: 000000
2014/11/15 18:53:48 C731E8 c [SKIP STREAM 2] CAID: 098C ECM_PID: 1A83 PROVID: 000000
2014/11/15 18:53:48 C731E8 c Found 3 ECMpids and 3 STREAMpids in PMT
2014/11/15 18:53:48 C731E8 c [DVBAPI] Receiver wants to demux srvid 0086 on adapter 0000 camask 0008 index 0003 pmtpid 0060
2014/11/15 18:53:48 C731E8 c New program number: 0086 (Sky Cinema +1 HD) [pmt_list_management 5]
2014/11/15 18:53:48 C731E8 c [DVBAPI] Demuxer #0 PMT update for decoding of SRVID 0086!
2014/11/15 18:53:48 C731E8 c [DVBAPI] Demuxer #0 continue decoding of SRVID 0086
2014/11/15 18:53:48 C731E8 c [SKIP STREAM 0] CAID: 1833 ECM_PID: 1883 PROVID: 000000
2014/11/15 18:53:48 C731E8 c [SKIP STREAM 1] CAID: 09C4 ECM_PID: 1B83 PROVID: 000000
2014/11/15 18:53:48 C731E8 c [SKIP STREAM 2] CAID: 098C ECM_PID: 1A83 PROVID: 000000
2014/11/15 18:53:48 C731E8 c Found 3 ECMpids and 3 STREAMpids in PMT
2014/11/15 18:53:48 C731E8 c [DVBAPI] Receiver wants to demux srvid 0086 on adapter 0000 camask 0008 index 0003 pmtpid 0060
2014/11/15 18:53:48 C731E8 c New program number: 0086 (Sky Cinema +1 HD) [pmt_list_management 5]
2014/11/15 18:53:48 C731E8 c [DVBAPI] Demuxer #0 PMT update for decoding of SRVID 0086!
2014/11/15 18:53:48 C731E8 c [DVBAPI] Demuxer #0 continue decoding of SRVID 0086
2014/11/15 18:53:48 C731E8 c [SKIP STREAM 0] CAID: 1833 ECM_PID: 1883 PROVID: 000000
2014/11/15 18:53:48 C731E8 c [SKIP STREAM 1] CAID: 09C4 ECM_PID: 1B83 PROVID: 000000
2014/11/15 18:53:48 C731E8 c [SKIP STREAM 2] CAID: 098C ECM_PID: 1A83 PROVID: 000000
2014/11/15 18:53:48 C731E8 c Found 3 ECMpids and 2 STREAMpids in PMT
2014/11/15 18:53:48 C731E8 c [DVBAPI] Receiver wants to demux srvid 0086 on adapter 0000 camask 0008 index 0003 pmtpid 0060
2014/11/15 18:53:48 C731E8 c New program number: 0086 (Sky Cinema +1 HD) [pmt_list_management 5]
2014/11/15 18:53:49 C731E8 c [DVBAPI] Demuxer #0 PMT update for decoding of SRVID 0086!
2014/11/15 18:53:49 C731E8 c [DVBAPI] Demuxer #0 continue decoding of SRVID 0086
2014/11/15 18:53:49 C731E8 c [SKIP STREAM 0] CAID: 1833 ECM_PID: 1883 PROVID: 000000
2014/11/15 18:53:49 C731E8 c [SKIP STREAM 1] CAID: 09C4 ECM_PID: 1B83 PROVID: 000000
2014/11/15 18:53:49 C731E8 c [SKIP STREAM 2] CAID: 098C ECM_PID: 1A83 PROVID: 000000
2014/11/15 18:53:49 C731E8 c Found 3 ECMpids and 2 STREAMpids in PMT
2014/11/15 18:53:49 C731E8 c [DVBAPI] Receiver wants to demux srvid 0086 on adapter 0000 camask 0008 index 0003 pmtpid 0060
2014/11/15 18:53:49 C731E8 c New program number: 0086 (Sky Cinema +1 HD) [pmt_list_management 5]
2014/11/15 18:53:51 C731E8 c local (098C/0086/98): found (110 ms) by Server - Sky Cinema +1 HD
2014/11/15 18:53:58 C731E8 c local (098C/0086/9B): found (111 ms) by Server - Sky Cinema +1 HD
2014/11/15 18:54:05 C731E8 c local (098C/0086/98): found (111 ms) by Server - Sky Cinema +1 HD
2014/11/15 18:54:12 C731E8 c local (098C/0086/98): found (109 ms) by Server - Sky Cinema +1 HD
2014/11/15 18:54:19 C731E8 c local (098C/0086/98): found (108 ms) by Server - Sky Cinema +1 HD
2014/11/15 18:54:26 C731E8 c local (098C/0086/98): found (109 ms) by Server - Sky Cinema +1 HD
2014/11/15 18:54:33 C731E8 c local (098C/0086/98): found (110 ms) by Server - Sky Cinema +1 HD
2014/11/15 18:54:40 C731E8 c local (098C/0086/98): found (109 ms) by Server - Sky Cinema +1 HD
2014/11/15 18:54:47 C731E8 c local (098C/0086/98): found (110 ms) by Server - Sky Cinema +1 HD
2014/11/15 19:58:46 C731E8 c local (098C/0086/98): found (114 ms) by Server - Sky Cinema +1 HD
2014/11/15 19:58:53 C731E8 c local (098C/0086/98): found (109 ms) by Server - Sky Cinema +1 HD
2014/11/15 19:59:00 C731E8 c local (098C/0086/98): found (111 ms) by Server - Sky Cinema +1 HD
2014/11/15 19:59:07 C731E8 c local (098C/0086/98): found (116 ms) by Server - Sky Cinema +1 HD
2014/11/15 19:59:14 C731E8 c local (098C/0086/98): found (167 ms) by Server - Sky Cinema +1 HD
2014/11/15 19:59:21 C731E8 c local (098C/0086/98): found (110 ms) by Server - Sky Cinema +1 HD
2014/11/15 19:59:28 C731E8 c local (098C/0086/98): found (197 ms) by Server - Sky Cinema +1 HD
2014/11/15 19:59:35 C731E8 c local (098C/0086/98): found (110 ms) by Server - Sky Cinema +1 HD
2014/11/15 19:59:42 C731E8 c local (098C/0086/98): found (111 ms) by Server - Sky Cinema +1 HD
2014/11/15 19:59:49 C731E8 c local (098C/0086/98): found (110 ms) by Server - Sky Cinema +1 HD
2014/11/15 19:59:56 C731E8 c local (098C/0086/98): found (111 ms) by Server - Sky Cinema +1 HD
2014/11/15 20:00:03 C731E8 c local (098C/0086/98): found (110 ms) by Server - Sky Cinema +1 HD
2014/11/15 20:00:05 C731E8 c [DVBAPI] Demuxer #0 PMT update for decoding of SRVID 0086!
2014/11/15 20:00:05 C731E8 c [DVBAPI] Demuxer #0 continue decoding of SRVID 0086
2014/11/15 20:00:05 C731E8 c [SKIP STREAM 0] CAID: 1833 ECM_PID: 1883 PROVID: 000000
2014/11/15 20:00:05 C731E8 c [SKIP STREAM 1] CAID: 09C4 ECM_PID: 1B83 PROVID: 000000
2014/11/15 20:00:05 C731E8 c [SKIP STREAM 2] CAID: 098C ECM_PID: 1A83 PROVID: 000000
2014/11/15 20:00:05 C731E8 c Found 3 ECMpids and 3 STREAMpids in PMT
2014/11/15 20:00:05 C731E8 c [DVBAPI] Receiver wants to demux srvid 0086 on adapter 0000 camask 0008 index 0003 pmtpid 0060
2014/11/15 20:00:05 C731E8 c New program number: 0086 (Sky Cinema +1 HD) [pmt_list_management 5]
2014/11/15 20:00:10 C731E8 c local (098C/0086/98): found (197 ms) by Server - Sky Cinema +1 HD
2014/11/15 20:00:17 C731E8 c local (098C/0086/98): found (110 ms) by Server - Sky Cinema +1 HD
2014/11/15 20:00:24 C731E8 c local (098C/0086/98): found (109 ms) by Server - Sky Cinema +1 HD
2014/11/15 20:00:31 C731E8 c local (098C/0086/98): found (110 ms) by Server - Sky Cinema +1 HD
2014/11/15 20:00:33 C731E8 c [DVBAPI] Demuxer #0 PMT update for decoding of SRVID 0086!
2014/11/15 20:00:33 C731E8 c [DVBAPI] Demuxer #0 continue decoding of SRVID 0086
2014/11/15 20:00:33 C731E8 c [SKIP STREAM 0] CAID: 1833 ECM_PID: 1883 PROVID: 000000
2014/11/15 20:00:33 C731E8 c [SKIP STREAM 1] CAID: 09C4 ECM_PID: 1B83 PROVID: 000000
2014/11/15 20:00:33 C731E8 c [SKIP STREAM 2] CAID: 098C ECM_PID: 1A83 PROVID: 000000
2014/11/15 20:00:33 C731E8 c Found 3 ECMpids and 3 STREAMpids in PMT
2014/11/15 20:00:33 C731E8 c [DVBAPI] Receiver wants to demux srvid 0086 on adapter 0000 camask 0008 index 0003 pmtpid 0060
2014/11/15 20:00:33 C731E8 c New program number: 0086 (Sky Cinema +1 HD) [pmt_list_management 5]
2014/11/15 20:00:38 C731E8 c local (098C/0086/98): found (111 ms) by Server - Sky Cinema +1 HD
2014/11/15 20:00:45 C731E8 c local (098C/0086/98): found (113 ms) by Server - Sky Cinema +1 HD
2014/11/15 20:00:52 C731E8 c local (098C/0086/98): found (111 ms) by Server - Sky Cinema +1 HD
2014/11/15 20:00:59 C731E8 c local (098C/0086/98): found (110 ms) by Server - Sky Cinema +1 HD
2014/11/15 20:01:13 0 --- Skipped 1 duplicated log lines ---
2014/11/15 20:01:13 C731E8 c local (098C/0086/98): found (115 ms) by Server - Sky Cinema +1 HD
2014/11/15 20:01:20 C731E8 c local (098C/0086/98): found (110 ms) by Server - Sky Cinema +1 HD
2014/11/15 20:01:27 C731E8 c local (098C/0086/98): found (109 ms) by Server - Sky Cinema +1 HD
2014/11/15 20:01:41 0 --- Skipped 1 duplicated log lines ---
2014/11/15 20:01:41 C731E8 c local (098C/0086/98): found (111 ms) by Server - Sky Cinema +1 HD
2014/11/15 20:01:44 C731E8 c Found 0 ECMpids and 9 STREAMpids in PMT
2014/11/15 20:01:44 C731E8 c [DVBAPI] Receiver wants to demux srvid 283D on adapter 0000 camask 0008 index 0003 pmtpid 13EC
2014/11/15 20:01:44 C731E8 c New program number: 283D (Das Erste HD) [pmt_list_management 3]
2014/11/15 20:14:19 C731E8 c Found 0 ECMpids and 8 STREAMpids in PMT
2014/11/15 20:14:19 C731E8 c [DVBAPI] Receiver wants to demux srvid 2B66 on adapter 0000 camask 0008 index 0003 pmtpid 17D4
2014/11/15 20:14:19 C731E8 c New program number: 2B66 (ZDF HD) [pmt_list_management 3]
2014/11/15 21:44:21 C731E8 c [ADD PID 0] CAID: 1830 ECM_PID: 1CCC PROVID: 000000
2014/11/15 21:44:21 C731E8 c [ADD PID 1] CAID: 1843 ECM_PID: 19CC PROVID: 000000
2014/11/15 21:44:21 C731E8 c [ADD PID 2] CAID: 1860 ECM_PID: 1ECC PROVID: 000000
2014/11/15 21:44:21 C731E8 c [ADD PID 3] CAID: 098C ECM_PID: 1ACC PROVID: 000000
2014/11/15 21:44:21 C731E8 c [ADD PID 4] CAID: 09C4 ECM_PID: 1BCC PROVID: 000000
2014/11/15 21:44:21 C731E8 c Found 5 ECMpids and 6 STREAMpids in PMT
2014/11/15 21:44:21 C731E8 c [DVBAPI] Receiver wants to demux srvid EF10 on adapter 0000 camask 0008 index 0003 pmtpid 0060
2014/11/15 21:44:21 C731E8 c New program number: EF10 (RTL HD) [pmt_list_management 3]
2014/11/15 21:44:21 C731E8 c [DVBAPI] Demuxer #0 trying to descramble PID #1 CAID 1843 PROVID 000000 ECMPID 19CC ANY CHID PMTPID 0060 VPID 00FF
2014/11/15 21:44:22 C731E8 c local (1843/EF10/89): found (433 ms) by Server - RTL HD
2014/11/15 21:44:25 C731E8 c Found 0 ECMpids and 1 STREAMpids in PMT
2014/11/15 21:44:25 C731E8 c [DVBAPI] Receiver wants to demux srvid 0041 on adapter 0000 camask 0008 index 0003 pmtpid 0063
2014/11/15 21:44:25 C731E8 c New program number: 0041 (Deluxe Music SD) [pmt_list_management 3]
2014/11/15 21:44:26 C731E8 c Found 0 ECMpids and 2 STREAMpids in PMT
2014/11/15 21:44:26 C731E8 c [DVBAPI] Receiver wants to demux srvid 003E on adapter 0000 camask 0008 index 0003 pmtpid 0064
2014/11/15 21:44:26 C731E8 c New program number: 003E (EWNT katholisches TV SD) [pmt_list_management 3]
2014/11/15 21:44:27 C731E8 c Found 0 ECMpids and 1 STREAMpids in PMT
2014/11/15 21:44:27 C731E8 c [DVBAPI] Receiver wants to demux srvid 0041 on adapter 0000 camask 0008 index 0003 pmtpid 0063
2014/11/15 21:44:27 C731E8 c New program number: 0041 (Deluxe Music SD) [pmt_list_management 3]
2014/11/15 21:44:29 C731E8 c Found 0 ECMpids and 7 STREAMpids in PMT
2014/11/15 21:44:29 C731E8 c [DVBAPI] Receiver wants to demux srvid 2855 on adapter 0000 camask 0008 index 0003 pmtpid 1450
2014/11/15 21:44:29 C731E8 c New program number: 2855 (BR Süd HD) [pmt_list_management 3]
2014/11/15 21:44:39 C731E8 c Found 0 ECMpids and 8 STREAMpids in PMT
2014/11/15 21:44:39 C731E8 c [DVBAPI] Receiver wants to demux srvid 2B66 on adapter 0000 camask 0008 index 0003 pmtpid 17D4
2014/11/15 21:44:39 C731E8 c New program number: 2B66 (ZDF HD) [pmt_list_management 3]
2014/11/15 21:44:59 C731E8 c Found 0 ECMpids and 7 STREAMpids in PMT
2014/11/15 21:44:59 C731E8 c [DVBAPI] Receiver wants to demux srvid 2855 on adapter 0000 camask 0008 index 0003 pmtpid 1450
2014/11/15 21:44:59 C731E8 c New program number: 2855 (BR Süd HD) [pmt_list_management 3]
2014/11/15 21:45:47 C731E8 c [ADD PID 0] CAID: 1833 ECM_PID: 1811 PROVID: 000000
2014/11/15 21:45:47 C731E8 c [ADD PID 1] CAID: 09C4 ECM_PID: 1B11 PROVID: 000000
2014/11/15 21:45:47 C731E8 c [ADD PID 2] CAID: 09AF ECM_PID: 1F11 PROVID: 000000
2014/11/15 21:45:47 C731E8 c [ADD PID 3] CAID: 098C ECM_PID: 1A11 PROVID: 000000
2014/11/15 21:45:47 C731E8 c Found 4 ECMpids and 3 STREAMpids in PMT
2014/11/15 21:45:47 C731E8 c [DVBAPI] Receiver wants to demux srvid 0074 on adapter 0000 camask 0008 index 0003 pmtpid 0063
2014/11/15 21:45:47 C731E8 c New program number: 0074 (Sky Action HD) [pmt_list_management 3]
2014/11/15 21:45:47 C731E8 c [DVBAPI] Demuxer #0 trying to descramble PID #3 CAID 098C PROVID 000000 ECMPID 1A11 ANY CHID PMTPID 0063 VPID 03FF
2014/11/15 21:45:47 C731E8 c local (098C/0074/98): found (110 ms) by Server - Sky Action HD
2014/11/15 21:46:00 0 --- Skipped 1 duplicated log lines ---
2014/11/15 21:46:31 0 --- Skipped 5 duplicated log lines ---
2014/11/15 21:46:31 C731E8 c [ADD PID 0] CAID: 1833 ECM_PID: 180B PROVID: 000000
2014/11/15 21:46:31 C731E8 c [ADD PID 1] CAID: 09C4 ECM_PID: 1B0B PROVID: 000000
2014/11/15 21:46:31 C731E8 c [ADD PID 2] CAID: 09AF ECM_PID: 1F0B PROVID: 000000
2014/11/15 21:46:31 C731E8 c [ADD PID 3] CAID: 098C ECM_PID: 1A0B PROVID: 000000
2014/11/15 21:46:31 C731E8 c Found 4 ECMpids and 3 STREAMpids in PMT
2014/11/15 21:46:31 C731E8 c [DVBAPI] Receiver wants to demux srvid 006B on adapter 0000 camask 0008 index 0003 pmtpid 0062
2014/11/15 21:46:31 C731E8 c New program number: 006B (Sky Hits HD) [pmt_list_management 3]
2014/11/15 21:46:31 C731E8 c [DVBAPI] Demuxer #0 trying to descramble PID #3 CAID 098C PROVID 000000 ECMPID 1A0B ANY CHID PMTPID 0062 VPID 02FF
2014/11/15 21:46:32 C731E8 c local (098C/006B/98): found (110 ms) by Server - Sky Hits HD
2014/11/15 21:46:32 C731E8 c local (098C/006B/98): found (168 ms) by Server - Sky Hits HD
2014/11/15 21:46:39 C731E8 c local (098C/006B/98): found (111 ms) by Server - Sky Hits HD
2014/11/15 21:46:46 C731E8 c local (098C/006B/98): found (110 ms) by Server - Sky Hits HD
2014/11/15 22:36:36 0 --- Skipped 1 duplicated log lines ---
2014/11/15 22:36:36 C731E8 c local (098C/006B/98): found (111 ms) by Server - Sky Hits HD
2014/11/15 22:36:43 C731E8 c local (098C/006B/98): found (112 ms) by Server - Sky Hits HD
2014/11/15 22:36:50 C731E8 c local (098C/006B/98): found (110 ms) by Server - Sky Hits HD
2014/11/15 22:36:57 C731E8 c local (098C/006B/98): found (112 ms) by Server - Sky Hits HD
2014/11/15 22:37:04 C731E8 c local (098C/006B/98): found (110 ms) by Server - Sky Hits HD
2014/11/15 22:37:11 C731E8 c local (098C/006B/98): found (109 ms) by Server - Sky Hits HD
2014/11/15 22:37:18 C731E8 c local (098C/006B/98): found (113 ms) by Server - Sky Hits HD
2014/11/15 22:37:25 C731E8 c local (098C/006B/98): found (109 ms) by Server - Sky Hits HD
2014/11/15 22:37:32 C731E8 c local (098C/006B/98): found (110 ms) by Server - Sky Hits HD
2014/11/15 22:37:40 C731E8 c local (098C/006B/98): found (109 ms) by Server - Sky Hits HD
2014/11/15 22:37:47 C731E8 c local (098C/006B/98): found (110 ms) by Server - Sky Hits HD

===================================================== AUSSETZER =====================================
2014/11/15 22:37:58 C731E8 c local (098C/006B/98): timeout (5000 ms) by Server - Sky Hits HD
2014/11/15 22:38:04 0 --- Skipped 1 duplicated log lines ---
2014/11/15 22:38:04 C731E8 c [DVBAPI] Demuxer #0 (re)starting decodingrequests on all 4 ecmpids!
2014/11/15 22:38:04 C731E8 c [DVBAPI] Demuxer #0 trying to descramble PID #3 CAID 098C PROVID 000000 ECMPID 1A0B ANY CHID PMTPID 0062 VPID 02FF
2014/11/15 22:38:05 C731E8 c local (098C/006B/98): timeout (5000 ms) by Server - Sky Hits HD
2014/11/15 22:38:05 C731E8 c local (098C/006B/98): not found (1094 ms) by Server - Sky Hits HD
2014/11/15 22:38:06 C731E8 c [DVBAPI] Demuxer #0 (re)starting decodingrequests on all 4 ecmpids!
2014/11/15 22:38:06 C731E8 c [DVBAPI] Demuxer #0 trying to descramble PID #3 CAID 098C PROVID 000000 ECMPID 1A0B ANY CHID PMTPID 0062 VPID 02FF
===================================================== AUSSETZER =====================================
2014/11/15 22:38:08 C731E8 c local (098C/006B/98): found (2122 ms) by Server - Sky Hits HD
2014/11/15 22:38:08 C731E8 c local (098C/006B/98): found (826 ms) by Server - Sky Hits HD
2014/11/15 22:38:15 C731E8 c local (098C/006B/98): found (110 ms) by Server - Sky Hits HD
2014/11/15 22:38:22 C731E8 c local (098C/006B/98): found (111 ms) by Server - Sky Hits HD
2014/11/15 22:38:29 C731E8 c local (098C/006B/98): found (109 ms) by Server - Sky Hits HD
2014/11/15 22:38:36 C731E8 c local (098C/006B/98): found (111 ms) by Server - Sky Hits HD
2014/11/15 22:38:50 0 --- Skipped 1 duplicated log lines ---
2014/11/15 22:38:50 C731E8 c local (098C/006B/98): found (110 ms) by Server - Sky Hits HD
2014/11/15 22:39:11 0 --- Skipped 2 duplicated log lines ---

Hätte noch den cs378x Client log. Der Aussetzer fand scheinbar bei allen zur gleichen Zeit statt. Allerdings lief es Gestern sehr stabil. Manchmal sind alle 10 -30 Minuten Aussetzer.


Oscam cs378c Client

Spoiler

>> OSCam << cardserver started at Sat Nov 15 21:58:09 2014
-------------------------------------------------------------------------------
2014/11/15 21:58:09 0 s >> OSCam << cardserver started, version 1.20-unstable_svn, build r9977 (mipsel-unknown-linux-gnu)
2014/11/15 21:58:09 0 s creating pidfile /tmp/.oscam/oscam.pid with pid 764
2014/11/15 21:58:09 0 s services reloaded: 0 services freed, 14 services loaded, rejected 0
2014/11/15 21:58:09 0 s [Emu] OSEmu (built-in) version 706
2014/11/15 21:58:09 0 s userdb reloaded: 1 accounts loaded, 0 expired, 0 disabled
2014/11/15 21:58:09 0 s signal handling initialized
2014/11/15 21:58:09 0 s 512 service-id's loaded in 17 ms
2014/11/15 21:58:09 0 s 36 tier-id's loaded
2014/11/15 21:58:09 0 s monitor: initialized (fd=6, port=999)
2014/11/15 21:58:09 0 s Server [cs378x] creating thread for device adresse.net
2014/11/15 21:58:09 0 s ORF [emu] creating thread for device emulator
2014/11/15 21:58:09 0 s loadbalancer: can't read from file /tmp/.oscam/stat
2014/11/15 21:58:09 0 s waiting for local card init
2014/11/15 21:58:09 801D18 p cs378x proxy adresse.xxx
2014/11/15 21:58:09 81E518 h webif: decompressed 149951 bytes back into 359760 bytes
2014/11/15 21:58:09 803B50 r ORF [emu] Reader initialized (device=emulator, detect=cd, mhz=357, cardmhz=357)
2014/11/15 21:58:09 81E518 h HTTP Server running. ip=0.0.0.0 port=1001
2014/11/15 21:58:09 801D18 p adresse.xxx: resolved ip=123.456.789.111
2014/11/15 21:58:09 801D18 p Server [cs378x] connecting to adresse.xxx
2014/11/15 21:58:09 801D18 p Server [cs378x] proxy initialized, server adresse.xxx
2014/11/15 21:58:10 803B50 r ORF [emu] card detected
2014/11/15 21:58:11 803B50 r ORF [emu] found card system emu
2014/11/15 21:58:11 803B50 r ORF [emu] THIS WAS A SUCCESSFUL START ATTEMPT No 1 out of max alloted of 1
2014/11/15 21:58:12 0 s init for all local cards done
2014/11/15 21:58:12 0 s anti cascading disabled
2014/11/15 21:58:12 82DD68 c plain dvbapi-client granted (local, au=on (2 reader))
2014/11/15 21:58:12 82DD68 c [DVBAPI] Detected /dev/dvb/adapter0/demux0 Api: 0, userconfig boxtype: 1
2014/11/15 21:58:50 82DD68 c [ADD PID 0] CAID: 0D05 ECM_PID: 00DC PROVID: 000000
2014/11/15 21:58:50 82DD68 c [ADD PID 1] CAID: 0D95 ECM_PID: 010E PROVID: 000000
2014/11/15 21:58:50 82DD68 c [ADD PID 2] CAID: 0648 ECM_PID: 0078 PROVID: 000000
2014/11/15 21:58:50 82DD68 c [ADD PID 3] CAID: 1702 ECM_PID: 0140 PROVID: 000000
2014/11/15 21:58:50 82DD68 c [ADD PID 4] CAID: 1833 ECM_PID: 01A4 PROVID: 000000
2014/11/15 21:58:50 82DD68 c [ADD PID 5] CAID: 09C4 ECM_PID: 01E0 PROVID: 000000
2014/11/15 21:58:50 82DD68 c [ADD PID 6] CAID: 098C ECM_PID: 01EA PROVID: 000000
2014/11/15 21:58:50 82DD68 c [ADD PID 7] CAID: 0D98 ECM_PID: 0110 PROVID: 000000
2014/11/15 21:58:50 82DD68 c [ADD PID 8] CAID: 0650 ECM_PID: 007A PROVID: 000000
2014/11/15 21:58:50 82DD68 c Found 9 ECMpids and 5 STREAMpids in PMT
2014/11/15 21:58:50 82DD68 c [DVBAPI] Receiver wants to demux srvid 132F on adapter 0000 camask 0008 index 0003 pmtpid 006B
2014/11/15 21:58:50 82DD68 c New program number: 132F (ORF1 HD) [pmt_list_management 3]
2014/11/15 21:58:50 82DD68 c [DVBAPI] Demuxer #0 trying to descramble PID #0 CAID 0D05 PROVID 000000 ECMPID 00DC ANY CHID PMTPID 006B VPID 0780
2014/11/15 21:58:50 82DD68 c local (0D05/132F/9C): found (14 ms) by ORF (L/1/2/2) - ORF1 HD
2014/11/15 21:58:57 82DD68 c local (0D05/132F/9C): found (4 ms) by ORF (L/1/2/2) - ORF1 HD
2014/11/15 21:59:07 82DD68 c local (0D05/132F/9C): found (3 ms) by ORF (L/1/2/2) - ORF1 HD
2014/11/15 22:36:17 82DD68 c local (098C/0008/98): found (108 ms) by Server - Sky Comedy
2014/11/15 22:36:24 82DD68 c local (098C/0008/98): found (106 ms) by Server - Sky Comedy
2014/11/15 22:36:31 82DD68 c local (098C/0008/98): found (105 ms) by Server - Sky Comedy
2014/11/15 22:36:45 0 --- Skipped 1 duplicated log lines ---
2014/11/15 22:36:45 82DD68 c local (098C/0008/98): found (106 ms) by Server - Sky Comedy
2014/11/15 22:36:52 82DD68 c local (098C/0008/98): found (105 ms) by Server - Sky Comedy
2014/11/15 22:37:06 0 --- Skipped 1 duplicated log lines ---
2014/11/15 22:37:06 82DD68 c local (098C/0008/98): found (104 ms) by Server - Sky Comedy
2014/11/15 22:37:13 82DD68 c local (098C/0008/98): found (105 ms) by Server - Sky Comedy
2014/11/15 22:37:27 0 --- Skipped 1 duplicated log lines ---
2014/11/15 22:37:27 82DD68 c local (098C/0008/98): found (106 ms) by Server - Sky Comedy
2014/11/15 22:37:34 82DD68 c local (098C/0008/98): found (105 ms) by Server - Sky Comedy
2014/11/15 22:37:41 82DD68 c local (098C/0008/98): found (106 ms) by Server - Sky Comedy
2014/11/15 22:37:48 82DD68 c local (098C/0008/98): found (105 ms) by Server - Sky Comedy
===================================================== AUSSETZER =====================================
2014/11/15 22:38:00 82DD68 c local (098C/0008/98): timeout (5000 ms) by Server - Sky Comedy
2014/11/15 22:38:00 82DD68 c unexpected ecm answer rc=100.
2014/11/15 22:38:05 82DD68 c local (098C/0008/98): timeout (5000 ms) by Server - Sky Comedy
2014/11/15 22:38:05 82DD68 c unexpected ecm answer rc=100.
2014/11/15 22:38:05 82DD68 c [DVBAPI] Demuxer #0 (re)starting decodingrequests on all 5 ecmpids!
2014/11/15 22:38:06 82DD68 c [DVBAPI] Demuxer #0 trying to descramble PID #4 CAID 098C PROVID 000000 ECMPID 1A18 ANY CHID PMTPID 0069 VPID 09FF
2014/11/15 22:38:07 82DD68 c local (098C/0008/98): timeout (5001 ms) by Server - Sky Comedy
2014/11/15 22:38:07 82DD68 c unexpected ecm answer rc=100.
2014/11/15 22:38:07 82DD68 c local (098C/0008/98): not found (1095 ms) by Server - Sky Comedy
2014/11/15 22:38:07 82DD68 c [DVBAPI] Demuxer #0 (re)starting decodingrequests on all 5 ecmpids!
2014/11/15 22:38:07 82DD68 c [DVBAPI] Demuxer #0 trying to descramble PID #4 CAID 098C PROVID 000000 ECMPID 1A18 ANY CHID PMTPID 0069 VPID 09FF
2014/11/15 22:38:10 82DD68 c local (098C/0008/98): found (581 ms) by Server - Sky Comedy
2014/11/15 22:38:13 82DD68 c local (098C/0008/98): timeout (5001 ms) by Server - Sky Comedy
2014/11/15 22:38:13 82DD68 c unexpected ecm answer rc=100.
===================================================== AUSSETZER =====================================
2014/11/15 22:38:13 82DD68 c local (098C/0008/98): cache1 (1 ms) by Server - Sky Comedy
2014/11/15 22:38:16 82DD68 c local (098C/0008/98): found (104 ms) by Server - Sky Comedy
2014/11/15 22:38:23 82DD68 c local (098C/0008/98): found (106 ms) by Server - Sky Comedy
2014/11/15 22:38:30 82DD68 c local (098C/0008/98): found (104 ms) by Server - Sky Comedy
2014/11/15 22:38:37 82DD68 c local (098C/0008/98): found (105 ms) by Server - Sky Comedy

 

[dodo83]

AW: Oscam Client timeout (5000 ms) by .........

das einzige was mir auffällt ist das der Client 104tv zu der zeit zwei anfragen zur gleichen zeit stellt und beide werden beantwortet, eine aus dem Cache und eine von der Karte

2014/11/15 22:38:01 7F7160 c 104tv (098C/006B/98): cache1 (100 ms) by Sky - Sky Hits HD
2014/11/15 22:38:01 7F7160 c 104tv (098C/006B/98): found (101 ms) by Sky - Sky Hits HD

2014/11/15 22:38:08 7F7160 c 104tv (098C/006B/98): cache1 (100 ms) by Sky - Sky Hits HD
2014/11/15 22:38:08 7F7160 c 104tv (098C/006B/98): found (102 ms) by Sky - Sky Hits HD

nicht desto trotz sollte sowas nicht vorkommen

2014/11/15 22:38:23 5B3958 r Sky [internal] ERROR: Opening device /dev/sci0 (errno=16 Device or resource busy)
2014/11/15 22:38:23 5B3958 r Sky [internal] Cannot open device: /dev/sci0
2014/11/15 22:38:23 5B5628 r HDPlus [internal] ERROR: Opening device /dev/sci1 (errno=16 Device or resource busy)
2014/11/15 22:38:23 5B5628 r HDPlus [internal] Cannot open device: /dev/sci1

läuft da noch eine Cam parallel zur OScam?

 

[Altair007]

AW: Oscam Client timeout (5000 ms) by .........

Nicht am Server.
Komisch das es erst seit Gestern drin ist. Habe Gemini 3.3 installiert. werde das mal prüfen.
Kann das mit den beiden Anfragen zum Aussetzer führen , oder da es bei beiden Clients ziemlich zeitnah ist vielleicht doch an der Verbindung?

 

[dodo83]

AW: Oscam Client timeout (5000 ms) by .........

wenn die Reader kurz aussetzen kann es davon kommen
obwohl im log werden die Anfragen alle 7 sec beantwortet
das würde aber erklären wieso alle Clients zu der zeit freezer haben

bei Netzwerkproblemen würden sich die Clients Ab/Anmelden im log

 

[Altair007]

Eine Idee wie ich die Karte und den Cache am Server oder ggf. am Client anpassen kann?
Achja hab den internal Error gefunden. Start script für Blue Panel war etwas zu ausführlich. ;-)

Gibt es einen Möglichkeit z.B. den Cache beim Client so zufüllen das im Falle eines Verbindungsabbruches der sich vorübergehend aus seinem Cache weiter mit Entschlüsselungen versorgen kann?