>> OSCam << cardserver log switched at Fri Jan 26 07:39:05 2018
-------------------------------------------------------------------------------
2018/01/26 07:39:05 00000000 s >> OSCam << cardserver log switched, version 1.20-unstable_svn, build r11355 (arm-oe-linux-gnueabi)
2018/01/26 07:39:05 00000000 s (main) System name = Linux
2018/01/26 07:39:05 00000000 s (main) Host name = vusolo4k
2018/01/26 07:39:05 00000000 s (main) Release = 3.14.28-1.8
2018/01/26 07:39:05 00000000 s (main) Version = #1 SMP Sun Nov 27 13:58:29 CET 2016
2018/01/26 07:39:05 00000000 s (main) Machine = armv7l
2018/01/26 07:39:05 00000000 s (main) Stb model = dm8000
2018/01/26 07:39:05 00000000 s (main) Stb vumodel = vusolo4k
2018/01/26 07:39:05 00000000 s (main) Stb boxtype = vusolo4k
2018/01/26 07:39:05 00000000 s (main) creating pidfile /tmp/oscam.pid with pid 27881
2018/01/26 07:39:05 00000000 s (config) userdb reloaded: 2 accounts loaded, 0 expired, 0 disabled
2018/01/26 07:39:05 00000000 s (main) signal handling initialized
2018/01/26 07:39:05 00000000 s (config) 13 provid's loaded
2018/01/26 07:39:05 00000000 s (config) 85 service-id's loaded in 0 ms
2018/01/26 07:39:05 00000000 s (config) 15 tier-id's loaded
2018/01/26 07:39:05 00000000 s (net) newcamd: initialized (fd=6, port=34087)
2018/01/26 07:39:05 00000000 s (net) -> CAID: 0648 PROVID: 000000
2018/01/26 07:39:05 00000000 s (reader) ICE_irde [internal] creating thread for device /dev/sci1
2018/01/26 07:39:05 00000000 s (stat) loadbalancer: could not open /tmp/.oscam/stat for reading (errno=2 No such file or directory)
2018/01/26 07:39:05 00000000 s (emmcache) loaded 30 emmcache records from /etc/tuxbox/config/oscam.emmcache in 1 ms
2018/01/26 07:39:05 00000000 s (emmcache) loaded 0 emmstat records from /etc/tuxbox/config/oscam.emmstat in 0 ms
2018/01/26 07:39:05 00000000 s (anticasc) anti cascading disabled
2018/01/26 07:39:05 638BC84C r (reader) ICE_irde [internal] Reader initialized (device=/dev/sci1, detect=cd, pll max=27.00 MHz, wanted mhz=4.50 MHz)
2018/01/26 07:39:05 638BC84C r (reader) ICE_irde [internal] Reader sci internal, detected box type: vusolo4k
2018/01/26 07:39:05 29163D95 c (client) plain dvbapi-client granted (dvbapi_local, au=auto (8 reader))
2018/01/26 07:39:05 29163D95 c (dvbapi) dvbapi channelcache loaded from /etc/tuxbox/config/oscam.ccache
2018/01/26 07:39:05 29163D95 c (dvbapi) Detected /dev/dvb/adapter0/demux0 Api: 0, userconfig boxtype: 1 maximum amount of possible filters is 32 (oscam limit is 32)
2018/01/26 07:39:05 29E3E435 h (webif) webif: decompressed 176693 bytes back into 435304 bytes
2018/01/26 07:39:05 29E3E435 h (webif) HTTP Server running. ip=0.0.0.0 port=19001
2018/01/26 07:39:06 638BC84C r (reader) ICE_irde [internal] card detected
2018/01/26 07:39:06 638BC84C r (reader) ICE_irde [internal] ATR: 3B 78 12 00 00 54 C4 03 00 8F F1 90 00
2018/01/26 07:39:09 638BC84C r (reader) ICE_irde [internal] Buffers readed 0 bytes total time_us 3095668
2018/01/26 07:39:09 638BC84C r (reader) ICE_irde [internal] Init card protocol T0, FI=1, F=372, D=2, N=0
2018/01/26 07:39:09 638BC84C r (reader) ICE_irde [internal] Calculated work ETU is 41.33 us reader mhz = 450
2018/01/26 07:39:10 29163D95 c (dvbapi) Demuxer 0 ecmpid 0 CAID: 09C4 ECM_PID: 1BBE PROVID: 000000
2018/01/26 07:39:10 29163D95 c (dvbapi) Demuxer 0 ecmpid 1 CAID: 098C ECM_PID: 1ABE PROVID: 000000
2018/01/26 07:39:10 29163D95 c (dvbapi) Demuxer 0 ecmpid 2 CAID: 0648 ECM_PID: 1FBE PROVID: 000000
2018/01/26 07:39:10 29163D95 c (dvbapi) Demuxer 0 ecmpid 3 CAID: 0650 ECM_PID: 1FBF PROVID: 000000
2018/01/26 07:39:10 29163D95 c (dvbapi) Demuxer 0 ecmpid 4 CAID: 0500 ECM_PID: 1DBE PROVID: 051900
2018/01/26 07:39:10 29163D95 c (dvbapi) Demuxer 0 ecmpid 5 CAID: 06E2 ECM_PID: 1EBF PROVID: 000000
2018/01/26 07:39:10 29163D95 c (dvbapi) Demuxer 0 ecmpid 6 CAID: 098D ECM_PID: 18BE PROVID: 000000
2018/01/26 07:39:10 29163D95 c (dvbapi) Demuxer 0 found 7 ECMpids and 4 STREAMpids in caPMT
2018/01/26 07:39:10 29163D95 c (dvbapi) Demuxer 0 no suitable readers found that can be used for decoding!
2018/01/26 07:39:10 29163D95 c (dvbapi) Demuxer 1 ecmpid 0 CAID: 09C4 ECM_PID: 1BB2 PROVID: 000000
2018/01/26 07:39:10 29163D95 c (dvbapi) Demuxer 1 ecmpid 1 CAID: 098C ECM_PID: 1AB2 PROVID: 000000
2018/01/26 07:39:10 29163D95 c (dvbapi) Demuxer 1 ecmpid 2 CAID: 0648 ECM_PID: 1FB2 PROVID: 000000
2018/01/26 07:39:10 29163D95 c (dvbapi) Demuxer 1 ecmpid 3 CAID: 0650 ECM_PID: 1FB3 PROVID: 000000
2018/01/26 07:39:10 29163D95 c (dvbapi) Demuxer 1 ecmpid 4 CAID: 0500 ECM_PID: 1DB2 PROVID: 051900
2018/01/26 07:39:10 29163D95 c (dvbapi) Demuxer 1 ecmpid 5 CAID: 06E2 ECM_PID: 1EB3 PROVID: 000000
2018/01/26 07:39:10 29163D95 c (dvbapi) Demuxer 1 ecmpid 6 CAID: 098D ECM_PID: 18B2 PROVID: 000000
2018/01/26 07:39:10 29163D95 c (dvbapi) Demuxer 1 found 7 ECMpids and 4 STREAMpids in caPMT
2018/01/26 07:39:10 29163D95 c (dvbapi) Demuxer 1 no suitable readers found that can be used for decoding!
2018/01/26 07:39:10 29163D95 c (dvbapi) Demuxer 2 ecmpid 0 CAID: 0D98 ECM_PID: 09CF PROVID: 000000
2018/01/26 07:39:10 29163D95 c (dvbapi) Demuxer 2 ecmpid 1 CAID: 0650 ECM_PID: 09D0 PROVID: 000000
2018/01/26 07:39:10 29163D95 c (dvbapi) Demuxer 2 ecmpid 2 CAID: 0D95 ECM_PID: 09D1 PROVID: 000000
2018/01/26 07:39:10 29163D95 c (dvbapi) Demuxer 2 ecmpid 3 CAID: 0648 ECM_PID: 09D2 PROVID: 000000
2018/01/26 07:39:10 29163D95 c (dvbapi) Demuxer 2 ecmpid 4 CAID: 06E2 ECM_PID: 09D3 PROVID: 000000
2018/01/26 07:39:10 29163D95 c (dvbapi) Demuxer 2 ecmpid 5 CAID: 098C ECM_PID: 0A31 PROVID: 000000
2018/01/26 07:39:10 29163D95 c (dvbapi) Demuxer 2 ecmpid 6 CAID: 09C4 ECM_PID: 0A3B PROVID: 000000
2018/01/26 07:39:10 29163D95 c (dvbapi) Demuxer 2 ecmpid 7 CAID: 0500 ECM_PID: 0A48 PROVID: 051900
2018/01/26 07:39:10 29163D95 c (dvbapi) Demuxer 2 found 8 ECMpids and 6 STREAMpids in caPMT
2018/01/26 07:39:10 29163D95 c (dvbapi) Demuxer 2 no suitable readers found that can be used for decoding!
2018/01/26 07:39:10 29163D95 c (dvbapi) Demuxer 3 ecmpid 0 CAID: 09C4 ECM_PID: 1BE4 PROVID: 000000
2018/01/26 07:39:10 29163D95 c (dvbapi) Demuxer 3 ecmpid 1 CAID: 098C ECM_PID: 1AE4 PROVID: 000000
2018/01/26 07:39:10 29163D95 c (dvbapi) Demuxer 3 ecmpid 2 CAID: 0500 ECM_PID: 1FE4 PROVID: 051900
2018/01/26 07:39:10 29163D95 c (dvbapi) Demuxer 3 ecmpid 3 CAID: 06E2 ECM_PID: 1EE5 PROVID: 000000
2018/01/26 07:39:10 29163D95 c (dvbapi) Demuxer 3 ecmpid 4 CAID: 098D ECM_PID: 18E4 PROVID: 000000
2018/01/26 07:39:10 29163D95 c (dvbapi) Demuxer 3 found 5 ECMpids and 3 STREAMpids in caPMT
2018/01/26 07:39:10 29163D95 c (dvbapi) Demuxer 3 no suitable readers found that can be used for decoding!
2018/01/26 07:39:10 29163D95 c (dvbapi) Demuxer 4 ecmpid 0 CAID: 0648 ECM_PID: 0079 PROVID: 000000
2018/01/26 07:39:10 29163D95 c (dvbapi) Demuxer 4 ecmpid 1 CAID: 0650 ECM_PID: 007B PROVID: 000000
2018/01/26 07:39:10 29163D95 c (dvbapi) Demuxer 4 ecmpid 2 CAID: 0D95 ECM_PID: 010F PROVID: 000000
2018/01/26 07:39:10 29163D95 c (dvbapi) Demuxer 4 ecmpid 3 CAID: 0D98 ECM_PID: 0111 PROVID: 000000
2018/01/26 07:39:10 29163D95 c (dvbapi) Demuxer 4 ecmpid 4 CAID: 06E2 ECM_PID: 0141 PROVID: 000000
2018/01/26 07:39:10 29163D95 c (dvbapi) Demuxer 4 ecmpid 5 CAID: 0500 ECM_PID: 01CE PROVID: 051900
2018/01/26 07:39:10 29163D95 c (dvbapi) Demuxer 4 ecmpid 6 CAID: 09C4 ECM_PID: 01E1 PROVID: 000000
2018/01/26 07:39:10 29163D95 c (dvbapi) Demuxer 4 ecmpid 7 CAID: 098C ECM_PID: 01EB PROVID: 000000
2018/01/26 07:39:10 29163D95 c (dvbapi) Demuxer 4 found 8 ECMpids and 6 STREAMpids in caPMT
2018/01/26 07:39:10 29163D95 c (dvbapi) Demuxer 4 no suitable readers found that can be used for decoding!
2018/01/26 07:39:10 29163D95 c (dvbapi) Demuxer 0 found 7 ECMpids and 4 STREAMpids in PMT
2018/01/26 07:39:10 29163D95 c (dvbapi) Demuxer 1 found 7 ECMpids and 4 STREAMpids in PMT
2018/01/26 07:39:11 29163D95 c (dvbapi) Demuxer 0 no enabled matching ecmpids -> decoding is waiting for matching readers!
2018/01/26 07:39:11 29163D95 c (dvbapi) Demuxer 1 no enabled matching ecmpids -> decoding is waiting for matching readers!
2018/01/26 07:39:12 29163D95 c (dvbapi) Demuxer 2 no enabled matching ecmpids -> decoding is waiting for matching readers!
2018/01/26 07:39:12 29163D95 c (dvbapi) Demuxer 3 no enabled matching ecmpids -> decoding is waiting for matching readers!
2018/01/26 07:39:12 638BC84C r (reader) ICE_irde [internal] Buffers readed 0 bytes total time_us 3095816
2018/01/26 07:39:13 29163D95 c (dvbapi) Demuxer 4 no enabled matching ecmpids -> decoding is waiting for matching readers!
2018/01/26 07:39:13 638BC84C r (reader) ICE_irde [internal] PLL Reader: ATR Fsmax is 5 MHz, clocking card to 4.50 Mhz (nearest possible mhz specified reader->mhz)
2018/01/26 07:39:14 638BC84C r (reader) ICE_irde [internal] Hist. Bytes: TÄ
2018/01/26 07:39:14 638BC84C r (reader) ICE_irde [internal] detect irdeto card
2018/01/26 07:39:14 638BC84C r (reader) ICE_irde [internal] caid: 0648, acs: 0.05, country code: AUT
2018/01/26 07:39:14 638BC84C r (reader) ICE_irde [internal] providers: 4, ascii serial: ##########, hex serial: ######, hex base: ##
2018/01/26 07:39:14 638BC84C r (reader) ICE_irde [internal] WARNING: For Irdeto cards you will have to set 'mhz= 600' in oscam.server
2018/01/26 07:39:14 638BC84C r (reader) ICE_irde [internal] active providers: 4 (222aff,222aff,002600,222aff)
2018/01/26 07:39:14 638BC84C r (reader) ICE_irde [internal] found card system irdeto
2018/01/26 07:39:14 638BC84C r (reader) ICE_irde [irdeto] THIS WAS A SUCCESSFUL START ATTEMPT No 1 out of max alloted of 1
2018/01/26 07:39:15 638BC84C r (reader) ICE_irde [irdeto] entitlements for provider: 1, id: 222AFF
2018/01/26 07:39:15 638BC84C r (reader) ICE_irde [irdeto] chid: 000D, date: 2013/04/16 - 2013/05/16
2018/01/26 07:39:15 638BC84C r (reader) ICE_irde [irdeto] chid: 7FF7, date: 2018/01/24 - 2018/02/03
2018/01/26 07:39:15 638BC84C r (reader) ICE_irde [irdeto] chid: 0001, date: 2018/01/24 - 2018/10/01
2018/01/26 07:39:15 638BC84C r (reader) ICE_irde [irdeto] chid: 0002, date: 2018/01/24 - 2018/10/01
2018/01/26 07:39:15 638BC84C r (reader) ICE_irde [irdeto] chid: 7FFB, date: 2017/09/29 - 2017/10/29
2018/01/26 07:39:15 638BC84C r (reader) ICE_irde [irdeto] chid: FFD8, date: 2014/02/27 - 2014/03/29
2018/01/26 07:39:15 638BC84C r (reader) ICE_irde [irdeto] chid: FFE0, date: 2018/01/23 - 2018/09/30
2018/01/26 07:39:15 638BC84C r (reader) ICE_irde [irdeto] entitlements for provider: 2, id: 222AFF
2018/01/26 07:39:15 638BC84C r (reader) ICE_irde [irdeto] chid: FFE1, date: 2018/01/23 - 2018/09/30
2018/01/26 07:39:15 638BC84C r (reader) ICE_irde [irdeto] chid: 0015, date: 2018/01/24 - 2018/03/25
2018/01/26 07:39:15 638BC84C r (reader) ICE_irde [irdeto] chid: 7FFA, date: 2018/01/23 - 2018/02/22
2018/01/26 07:39:15 638BC84C r (reader) ICE_irde [irdeto] chid: 7FF6, date: 2018/01/24 - 2018/02/03
2018/01/26 07:39:15 638BC84C r (reader) ICE_irde [irdeto] chid: FFD9, date: 2018/01/23 - 2018/02/22
2018/01/26 07:39:15 638BC84C r (reader) ICE_irde [irdeto] entitlements for provider: 4, id: 222AFF
2018/01/26 07:39:15 638BC84C r (reader) ICE_irde [irdeto] chid: FFE3, date: 2018/01/24 - 2018/10/01
2018/01/26 07:39:15 638BC84C r (reader) ICE_irde [irdeto] chid: 002A, date: 2018/01/24 - 2018/03/25
2018/01/26 07:39:15 638BC84C r (reader) ICE_irde [irdeto] chid: 002B, date: 2018/01/24 - 2018/03/25
2018/01/26 07:39:15 638BC84C r (reader) ICE_irde [irdeto] chid: 002C, date: 2018/01/24 - 2018/03/25
2018/01/26 07:39:15 638BC84C r (reader) ICE_irde [irdeto] chid: 002D, date: 2018/01/24 - 2018/03/25
2018/01/26 07:39:15 638BC84C r (reader) ICE_irde [irdeto] chid: 7FF8, date: 2018/01/24 - 2018/02/23
2018/01/26 07:39:15 638BC84C r (reader) ICE_irde [irdeto] chid: 7FF4, date: 2018/01/25 - 2018/02/04
2018/01/26 07:39:15 638BC84C r (reader) ICE_irde [irdeto] chid: FFDB, date: 2018/01/23 - 2018/02/22
2018/01/26 07:39:15 638BC84C r (reader) ICE_irde [irdeto] ready for requests
2018/01/26 07:39:15 29163D95 c (dvbapi) Demuxer 0 restarting decodingrequests after 5267 ms with 1 enabled and 6 disabled ecmpids!
2018/01/26 07:39:15 29163D95 c (dvbapi) Warning reader ICE_irde received no emms for the last 1516948755 seconds -> skip, this reader needs emms first!
2018/01/26 07:39:15 29163D95 c (dvbapi) Demuxer 0 impossible to descramble PID 2 CAID 0648 PROVID 000000 ECMPID 1FBE PMTPID 0063 (NO MATCHING READER)
2018/01/26 07:39:15 29163D95 c (dvbapi) Demuxer 1 restarting decodingrequests after 5264 ms with 1 enabled and 6 disabled ecmpids!
2018/01/26 07:39:15 29163D95 c (dvbapi) Warning reader ICE_irde received no emms for the last 1516948755 seconds -> skip, this reader needs emms first!
2018/01/26 07:39:15 29163D95 c (dvbapi) Demuxer 1 impossible to descramble PID 2 CAID 0648 PROVID 000000 ECMPID 1FB2 PMTPID 0061 (NO MATCHING READER)
2018/01/26 07:39:15 29163D95 c (dvbapi) Demuxer 2 restarting decodingrequests after 5262 ms with 1 enabled and 7 disabled ecmpids!