-------------------------------------------------------------------------------
>> OSCam << cardserver started at Thu Apr 30 19:54:13 2015
-------------------------------------------------------------------------------
2015/04/30 19:54:13 0 s >> OSCam << cardserver started, version 1.20-unstable_svn, build r788 (mipsel-unknown-linux-gnu)
2015/04/30 19:54:13 0 s creating pidfile /tmp/.oscam/oscam.pid with pid 619
2015/04/30 19:54:13 0 s services reloaded: 0 services freed, 2 services loaded, rejected 0
2015/04/30 19:54:13 0 s userdb reloaded: 1 accounts loaded, 0 expired, 0 disabled
2015/04/30 19:54:13 0 s signal handling initialized
2015/04/30 19:54:13 0 s 4 provid's loaded
2015/04/30 19:54:13 0 s cccam: initialized (fd=6, port=12000, prio=1)
2015/04/30 19:54:13 0 s
HDPlus [internal] creating thread for device /dev/sci1
2015/04/30 19:54:13 0 s V13 [internal] creating thread for device /dev/sci0
2015/04/30 19:54:13 0 s loadbalancer: can't read from file /tmp/.oscam/stat
2015/04/30 19:54:13 0 s waiting for local card init
2015/04/30 19:54:13 2AF00588 r
HDPlus [internal] Reader initialized (device=/dev/sci1, detect=cd, mhz=368, cardmhz=368)
2015/04/30 19:54:13 2AF023C8 r V13 [internal] Reader initialized (device=/dev/sci0, detect=cd, pll max=27.00 MHz, wanted mhz=0.01 MHz
2015/04/30 19:54:13 540720 h webif: decompressed 69662 bytes back into 195704 bytes
2015/04/30 19:54:13 540720 h HTTP Server running. ip=0.0.0.0 port=2002
2015/04/30 19:54:14 2AF00588 r
HDPlus [internal] card detected
2015/04/30 19:54:14 2AF023C8 r V13 [internal] card detected
2015/04/30 19:54:15 2AF023C8 r V13 [internal] ATR: 3F FF 15 25 03 10 80 41 B0 07 69 FF 4A 50 70 00 00 50 31 01 00 15
2015/04/30 19:54:15 2AF023C8 r V13 [internal] Init card protocol T0, FI=1, F=372, D=16, N=3
2015/04/30 19:54:15 2AF023C8 r V13 [internal] TEST tempo mhz check = 500 mhz
2015/04/30 19:54:15 2AF023C8 r V13 [internal] Calculated work ETU is 5.17 us reader mhz = 450
2015/04/30 19:54:15 2AF023C8 r V13 [internal] PLL Reader: ATR Fsmax is 5 MHz, clocking card to 4.50 Mhz (nearest possible mhz specified reader->cardmhz)
2015/04/30 19:54:16 2AF00588 r
HDPlus [internal] ATR: 3F FF 95 00 FF 91 81 71 FE 47 00 44 4E 41 53 50 31 34 32 20 52 65 76 47 43 36 61
2015/04/30 19:54:16 2AF00588 r
HDPlus [internal] Init card protocol T1, FI=9, F=512, D=16, N=255
2015/04/30 19:54:16 2AF00588 r
HDPlus [internal] Calculated work ETU is 8.70 us reader mhz = 368
2015/04/30 19:54:16 2AF00588 r
HDPlus [internal] ATR Fsmax is 5 MHz, clocking card to 3.68 (specified in reader->mhz)
2015/04/30 19:54:16 2AF023C8 r V13 [internal] Card type: P1GV
2015/04/30 19:54:16 2AF023C8 r V13 [internal] Rom version: 10A7
2015/04/30 19:54:17 2AF023C8 r V13 [internal] parental lock setting: FF FF FF FF
2015/04/30 19:54:17 2AF023C8 r V13 [internal] type: VideoGuard Sky Austria/Germany (09C4) FastMode, caid: 09C4
2015/04/30 19:54:17 2AF023C8 r V13 [internal] serial: ########, BoxID: ########, baseyear: 2004
2015/04/30 19:54:17 2AF023C8 r V13 [internal] ready for requests
2015/04/30 19:54:17 2AF023C8 r V13 [internal] found card system videoguard2
2015/04/30 19:54:17 2AF023C8 r V13 [videoguard2] THIS WAS A SUCCESFULL START ATTEMPT No 1 out of max alloted of 1
2015/04/30 19:54:17 2AF023C8 r V13 [videoguard2] card detected
2015/04/30 19:54:17 2AF023C8 r V13 [videoguard2] type: VideoGuard Sky Austria/Germany (09C4) FastMode
2015/04/30 19:54:17 2AF023C8 r V13 [videoguard2] tier: 2712, expiry date: 2013/10/14-01:01:00
2015/04/30 19:54:17 2AF023C8 r V13 [videoguard2] tier: 2710, expiry date: 2013/08/08-01:01:00
2015/04/30 19:54:17 2AF00588 r
HDPlus [internal] Warning: Card returned error on setting ifsd value to 251
2015/04/30 19:54:17 2AF023C8 r V13 [videoguard2] tier: 0056, expiry date: 2015/06/28-01:01:00
2015/04/30 19:54:17 2AF023C8 r V13 [videoguard2] tier: 0057, expiry date: 2015/06/28-01:01:00
2015/04/30 19:54:17 2AF023C8 r V13 [videoguard2] tier: 0058, expiry date: 2015/06/28-01:01:00
2015/04/30 19:54:18 2AF023C8 r V13 [videoguard2] tier: 007b, expiry date: 2015/06/28-01:01:00
2015/04/30 19:54:18 2AF023C8 r V13 [videoguard2] tier: 007d, expiry date: 2015/06/28-01:01:00
2015/04/30 19:54:18 2AF023C8 r V13 [videoguard2] tier: 008e, expiry date: 2015/06/28-01:01:00
2015/04/30 19:54:18 2AF023C8 r V13 [videoguard2] tier: 00c0, expiry date: 2015/06/28-01:01:00
2015/04/30 19:54:18 2AF023C8 r V13 [videoguard2] tier: 00c7, expiry date: 2015/06/28-01:01:00
2015/04/30 19:54:18 2AF023C8 r V13 [videoguard2] tier: 00c8, expiry date: 2015/06/28-01:01:00
2015/04/30 19:54:18 2AF023C8 r V13 [videoguard2] tier: 00c9, expiry date: 2015/06/28-01:01:00
2015/04/30 19:54:18 2AF023C8 r V13 [videoguard2] tier: 00ca, expiry date: 2015/06/28-01:01:00
2015/04/30 19:54:18 2AF023C8 r V13 [videoguard2] tier: 00cb, expiry date: 2015/06/28-01:01:00
2015/04/30 19:54:18 2AF023C8 r V13 [videoguard2] tier: 00f0, expiry date: 2015/06/28-01:01:00
2015/04/30 19:54:18 2AF023C8 r V13 [videoguard2] tier: 00f3, expiry date: 2015/06/28-01:01:00
2015/04/30 19:54:18 2AF023C8 r V13 [videoguard2] tier: 00fa, expiry date: 2015/06/28-01:01:00
2015/04/30 19:54:18 2AF023C8 r V13 [videoguard2] tier: 00ff, expiry date: 2015/06/28-01:01:00
2015/04/30 19:54:18 2AF00588 r
HDPlus [internal] detect native nagra card
2015/04/30 19:54:20 2AF00588 r
HDPlus [internal] Resync error: readtimeouts 0/0 (max/min) us, writetimeouts 0/0 (max/min) us
2015/04/30 19:54:22 2AF00588 r
HDPlus [internal] T1 Resync command error, trying to reactivate!
2015/04/30 19:54:23 2AF00588 r
HDPlus [internal] ATR: 3F FF 95 00 FF 91 81 71 FE 47 00 44 4E 41 53 50 31 34 32 20 52 65 76 47 43 36 61
2015/04/30 19:54:23 2AF00588 r
HDPlus [internal] Init card protocol T1, FI=9, F=512, D=16, N=255
2015/04/30 19:54:23 2AF00588 r
HDPlus [internal] Calculated work ETU is 8.70 us reader mhz = 368
2015/04/30 19:54:23 2AF00588 r
HDPlus [internal] ATR Fsmax is 5 MHz, clocking card to 3.68 (specified in reader->mhz)
2015/04/30 19:54:25 2AF00588 r
HDPlus [internal] Warning: Card returned error on setting ifsd value to 251
2015/04/30 19:54:27 2AF00588 r
HDPlus [internal] Resync error: readtimeouts 0/0 (max/min) us, writetimeouts 0/0 (max/min) us
2015/04/30 19:54:28 2AF00588 r
HDPlus [internal] T1 Resync command error, trying to reactivate!
2015/04/30 19:54:30 2AF00588 r
HDPlus [internal] ATR: 3F FF 95 00 FF 91 81 71 FE 47 00 44 4E 41 53 50 31 34 32 20 52 65 76 47 43 36 61
2015/04/30 19:54:30 2AF00588 r
HDPlus [internal] Init card protocol T1, FI=9, F=512, D=16, N=255
2015/04/30 19:54:30 2AF00588 r
HDPlus [internal] Calculated work ETU is 8.70 us reader mhz = 368
2015/04/30 19:54:30 2AF00588 r
HDPlus [internal] ATR Fsmax is 5 MHz, clocking card to 3.68 (specified in reader->mhz)
2015/04/30 19:54:31 2AF00588 r
HDPlus [internal] Warning: Card returned error on setting ifsd value to 251
2015/04/30 19:54:31 2AF00588 r
HDPlus [internal] card system not supported
2015/04/30 19:54:31 2AF00588 r
HDPlus [internal] Normal mode failed, reverting to Deprecated Mode
2015/04/30 19:54:33 2AF00588 r
HDPlus [internal] ATR: 3F FF 95 00 FF 91 81 71 FE 47 00 44 4E 41 53 50 31 34 32 20 52 65 76 47 43 36 61
2015/04/30 19:54:33 2AF00588 r
HDPlus [internal] Init card protocol T1, FI=9, F=512, D=16, N=255
2015/04/30 19:54:33 2AF00588 r
HDPlus [internal] Calculated work ETU is 101.09 us reader mhz = 368
2015/04/30 19:54:33 2AF00588 r
HDPlus [internal] ATR Fsmax is 5 MHz, clocking card to 3.68 (specified in reader->mhz)
2015/04/30 19:54:35 2AF00588 r
HDPlus [internal] Warning: Card returned error on setting ifsd value to 251
2015/04/30 19:54:36 2AF00588 r
HDPlus [internal] detect native nagra card
2015/04/30 19:54:37 2AF00588 r
HDPlus [internal] Resync error: readtimeouts 0/0 (max/min) us, writetimeouts 0/0 (max/min) us
2015/04/30 19:54:39 2AF00588 r
HDPlus [internal] T1 Resync command error, trying to reactivate!
2015/04/30 19:54:40 2AF00588 r
HDPlus [internal] ATR: 3F FF 95 00 FF 91 81 71 FE 47 00 44 4E 41 53 50 31 34 32 20 52 65 76 47 43 36 61
2015/04/30 19:54:40 2AF00588 r
HDPlus [internal] Init card protocol T1, FI=9, F=512, D=16, N=255
2015/04/30 19:54:40 2AF00588 r
HDPlus [internal] Calculated work ETU is 8.70 us reader mhz = 368
2015/04/30 19:54:40 2AF00588 r
HDPlus [internal] ATR Fsmax is 5 MHz, clocking card to 3.68 (specified in reader->mhz)
2015/04/30 19:54:42 2AF00588 r
HDPlus [internal] Warning: Card returned error on setting ifsd value to 251
2015/04/30 19:54:44 2AF00588 r
HDPlus [internal] Resync error: readtimeouts 0/0 (max/min) us, writetimeouts 0/0 (max/min) us
2015/04/30 19:54:45 2AF00588 r
HDPlus [internal] T1 Resync command error, trying to reactivate!
2015/04/30 19:54:47 2AF00588 r
HDPlus [internal] ATR: 3F FF 95 00 FF 91 81 71 FE 47 00 44 4E 41 53 50 31 34 32 20 52 65 76 47 43 36 61
2015/04/30 19:54:47 2AF00588 r
HDPlus [internal] Init card protocol T1, FI=9, F=512, D=16, N=255
2015/04/30 19:54:47 2AF00588 r
HDPlus [internal] Calculated work ETU is 8.70 us reader mhz = 368
2015/04/30 19:54:47 2AF00588 r
HDPlus [internal] ATR Fsmax is 5 MHz, clocking card to 3.68 (specified in reader->mhz)
2015/04/30 19:54:49 2AF00588 r
HDPlus [internal] Warning: Card returned error on setting ifsd value to 251
2015/04/30 19:54:49 2AF00588 r
HDPlus [internal] card system not supported
2015/04/30 19:54:49 2AF00588 r
HDPlus [internal] THIS WAS A FAILED START ATTEMPT No 1 out of max alloted of 1
2015/04/30 19:54:49 2AF00588 r
HDPlus [internal] card initializing error
2015/04/30 19:54:49 0 s init for all local cards done
2015/04/30 19:54:49 0 s anti cascading disabled
2015/04/30 19:54:49 2AF041D0 c plain dvbapi-client granted (local, au=auto (2 reader))
2015/04/30 19:54:49 2AF0A2D0 c anonymous disconnected from 192.168.2.2
2015/04/30 19:54:49 2AF041D0 c [DVBAPI] Detected /dev/dvb/adapter0/demux0 Api: 0, userconfig boxtype: 1
2015/04/30 19:54:49 2AF085D0 c anonymous disconnected from 192.168.2.2
2015/04/30 19:54:53 0 --- Skipped 1 duplicated log lines ---
2015/04/30 19:54:53 2AF041D0 c [DVBAPI] Receiver sends PMT command 5 for channel 0083
2015/04/30 19:54:53 2AF041D0 c [DVBAPI] Receiver wants to demux srvid 0083 on adapter 0000 camask 0008 index 0003 pmtpid 0064
2015/04/30 19:54:53 2AF041D0 c [ADD PID 0] CAID: 1833 ECM_PID: 18B6 PROVID: 000000
2015/04/30 19:54:53 2AF041D0 c [ADD PID 1] CAID: 09C4 ECM_PID: 1BB6 PROVID: 000000
2015/04/30 19:54:53 2AF041D0 c [ADD PID 2] CAID: 09AF ECM_PID: 1FB6 PROVID: 000000
2015/04/30 19:54:53 2AF041D0 c [ADD PID 3] CAID: 098C ECM_PID: 1AB6 PROVID: 000000
2015/04/30 19:54:53 2AF041D0 c Found 4 ECMpids and 3 STREAMpids in PMT
2015/04/30 19:54:53 2AF041D0 c [DVBAPI] Receiver wants to demux srvid 0083 on adapter 0000 camask 0008 index 0003 pmtpid 0064
2015/04/30 19:54:53 2AF041D0 c New program number: 0083 (1833:0083 unknown) [pmt_list_management 5]
2015/04/30 19:54:53 2AF041D0 c [DVBAPI] Demuxer #0 trying to descramble PID #1 CAID 09C4 PROVID 000000 ECMPID 1BB6 ANY CHID PMTPID 0064 VPID 04FF
2015/04/30 19:54:53 2AF023C8 r V13 [videoguard2] classD3 ins54: status 90 00 = ok but cw=00 -> channel not subscribed
2015/04/30 19:54:53 2AF041D0 c local (09C4&000000/025B/0083/B3:EC05570629A97DC8B2C4835E5F6CB777): not found (149 ms) by V13
2015/04/30 19:54:53 2AF041D0 c [DVBAPI] Demuxer #0 (re)starting decodingrequests on all 4 ecmpids!
2015/04/30 19:54:54 2AF041D0 c [DVBAPI] Demuxer #0 trying to descramble PID #1 CAID 09C4 PROVID 000000 ECMPID 1BB6 ANY CHID PMTPID 0064 VPID 04FF
2015/04/30 19:54:54 2AF041D0 c local (09C4&000000/025B/0083/B3:EC05570629A97DC8B2C4835E5F6CB777): not found (2 ms) by V13
2015/04/30 19:54:54 2AF041D0 c [DVBAPI] Demuxer #0 (re)starting decodingrequests on all 4 ecmpids!
2015/04/30 19:54:54 2AF041D0 c [DVBAPI] Demuxer #0 trying to descramble PID #1 CAID 09C4 PROVID 000000 ECMPID 1BB6 ANY CHID PMTPID 0064 VPID 04FF
2015/04/30 19:54:54 2AF041D0 c local (09C4&000000/025B/0083/B3:EC05570629A97DC8B2C4835E5F6CB777): not found (2 ms) by V13
2015/04/30 19:54:54 2AF041D0 c [DVBAPI] Demuxer #0 (re)starting decodingrequests on all 4 ecmpids!
2015/04/30 19:54:55 2AF041D0 c [DVBAPI] Demuxer #0 trying to descramble PID #1 CAID 09C4 PROVID 000000 ECMPID 1BB6 ANY CHID PMTPID 0064 VPID 04FF
2015/04/30 19:54:55 2AF041D0 c local (09C4&000000/025B/0083/B3:EC05570629A97DC8B2C4835E5F6CB777): not found (2 ms) by V13
2015/04/30 19:54:55 2AF041D0 c [DVBAPI] Demuxer #0 (re)starting decodingrequests on all 4 ecmpids!
2015/04/30 19:54:55 2AF041D0 c [DVBAPI] Demuxer #0 trying to descramble PID #1 CAID 09C4 PROVID 000000 ECMPID 1BB6 ANY CHID PMTPID 0064 VPID 04FF
2015/04/30 19:54:56 2AF023C8 r V13 [videoguard2] classD3 ins54: status 90 00 = ok but cw=00 -> channel not subscribed
2015/04/30 19:54:56 2AF041D0 c local (09C4&000000/025B/0083/B3:2A6B3E36D01976B2821C32297BF2AB16): not found (126 ms) by V13
2015/04/30 19:54:56 2AF041D0 c [DVBAPI] Demuxer #0 (re)starting decodingrequests on all 4 ecmpids!
2015/04/30 19:54:56 2AF041D0 c [DVBAPI] Demuxer #0 trying to descramble PID #1 CAID 09C4 PROVID 000000 ECMPID 1BB6 ANY CHID PMTPID 0064 VPID 04FF
2015/04/30 19:54:56 2AF041D0 c local (09C4&000000/025B/0083/B3:2A6B3E36D01976B2821C32297BF2AB16): not found (2 ms) by V13
2015/04/30 19:54:56 2AF041D0 c [DVBAPI] Demuxer #0 (re)starting decodingrequests on all 4 ecmpids!
2015/04/30 19:54:57 2AF041D0 c [DVBAPI] Demuxer #0 trying to descramble PID #1 CAID 09C4 PROVID 000000 ECMPID 1BB6 ANY CHID PMTPID 0064 VPID 04FF
2015/04/30 19:54:57 2AF041D0 c local (09C4&000000/025B/0083/B3:2A6B3E36D01976B2821C32297BF2AB16): not found (2 ms) by V13
2015/04/30 19:54:57 2AF041D0 c [DVBAPI] Demuxer #0 (re)starting decodingrequests on all 4 ecmpids!
2015/04/30 19:54:57 2AF0E388 c encrypted cccam-client 192.168.2.2 rejected (invalid access)
2015/04/30 19:54:57 2AF0E388 c account 'david' not found!
2015/04/30 19:54:57 2AF0E388 c anonymous disconnected from 192.168.2.2
2015/04/30 19:54:57 2AF041D0 c [DVBAPI] Demuxer #0 trying to descramble PID #1 CAID 09C4 PROVID 000000 ECMPID 1BB6 ANY CHID PMTPID 0064 VPID 04FF
2015/04/30 19:54:58 2AF041D0 c local (09C4&000000/025B/0083/B3:2A6B3E36D01976B2821C32297BF2AB16): not found (2 ms) by V13
2015/04/30 19:54:58 2AF041D0 c [DVBAPI] Demuxer #0 (re)starting decodingrequests on all 4 ecmpids!
2015/04/30 19:54:58 2AF041D0 c [DVBAPI] Demuxer #0 trying to descramble PID #1 CAID 09C4 PROVID 000000 ECMPID 1BB6 ANY CHID PMTPID 0064 VPID 04FF
2015/04/30 19:54:58 2AF041D0 c local (09C4&000000/025B/0083/B3:2A6B3E36D01976B2821C32297BF2AB16): not found (2 ms) by V13
2015/04/30 19:54:58 2AF041D0 c [DVBAPI] Demuxer #0 (re)starting decodingrequests on all 4 ecmpids!
2015/04/30 19:54:58 2AF041D0 c [DVBAPI] Demuxer #0 trying to descramble PID #1 CAID 09C4 PROVID 000000 ECMPID 1BB6 ANY CHID PMTPID 0064 VPID 04FF
2015/04/30 19:54:59 2AF041D0 c local (09C4&000000/025B/0083/B3:2A6B3E36D01976B2821C32297BF2AB16): not found (2 ms) by V13
2015/04/30 19:54:59 2AF041D0 c [DVBAPI] Demuxer #0 (re)starting decodingrequests on all 4 ecmpids!
2015/04/30 19:54:59 2AF041D0 c [DVBAPI] Demuxer #0 trying to descramble PID #1 CAID 09C4 PROVID 000000 ECMPID 1BB6 ANY CHID PMTPID 0064 VPID 04FF