-------------------------------------------------------------------------------
>> OSCam << cardserver log switched at Sun Jun 19 11:07:44 2016
-------------------------------------------------------------------------------
2016/06/19 11:07:44 00000000 s >> OSCam << cardserver log switched, version 1.20-unstable_svn, build r11245 (arm-linux-gnueabihf)
2016/06/19 11:07:44 00000000 s (main) System name = Linux
2016/06/19 11:07:44 00000000 s (main) Host name = raspberrypi
2016/06/19 11:07:44 00000000 s (main) Release = 4.1.12+
2016/06/19 11:07:44 00000000 s (main) Version = #825 PREEMPT Fri Nov 6 17:53:21 GMT 2015
2016/06/19 11:07:44 00000000 s (main) Machine = armv6l
2016/06/19 11:07:44 00000000 s (main) creating pidfile /tmp/.oscam/oscam.pid with pid 28109
2016/06/19 11:07:44 00000000 s (config) services reloaded: 0 services freed, 3 services loaded, rejected 0
2016/06/19 11:07:44 00000000 s [Emu] oscam-emu version 728
2016/06/19 11:07:44 00000000 s (config) userdb reloaded: 2 accounts loaded, 0 expired, 0 disabled
2016/06/19 11:07:44 00000000 s (main) signal handling initialized
2016/06/19 11:07:44 00000000 s (net) cs357x: initialized (fd=5, port=9457, prio=1)
2016/06/19 11:07:44 00000000 s (reader) emulator [emu] creating thread for device emulator
2016/06/19 11:07:44 00000000 s (reader) Do [mouse] creating thread for device /dev/Do
2016/06/19 11:07:44 00000000 s (stat) loadbalancer: could not open /tmp/.oscam/stat for reading (errno=2 No such file or directory)
2016/06/19 11:07:44 00000000 s (main) waiting for local card init
2016/06/19 11:07:44 2E26931A r key file not found in: /var/etc
2016/06/19 11:07:44 00000000 [Emu] info: FFDecsa parallel mode = 32
2016/06/19 11:07:44 2E26931A r reading key file: /var/keys/SoftCam.Key
2016/06/19 11:07:44 2E26931A r (reader) emulator [emu] Reader initialized (device=emulator, detect=cd, mhz=357, cardmhz=357)
2016/06/19 11:07:44 31B56531 h (webif) webif: decompressed 161941 bytes back into 393708 bytes
2016/06/19 11:07:44 31B56531 h (webif) HTTP Server running. ip=0.0.0.0 port=21200
2016/06/19 11:07:44 5644943E r (reader) Do [mouse] Buffers readed 0 bytes total time_us 75265
2016/06/19 11:07:44 5644943E r (reader) Do [mouse] Flush readed byte Nr 1 value 03 time_us 3926
2016/06/19 11:07:44 5644943E r (reader) Do [mouse] Flush readed byte Nr 2 value 11 time_us 1527
2016/06/19 11:07:44 5644943E r (reader) Do [mouse] Flush readed byte Nr 3 value e7 time_us 554
2016/06/19 11:07:44 5644943E r (reader) Do [mouse] Flush readed byte Nr 4 value ff time_us 530
2016/06/19 11:07:44 5644943E r (reader) Do [mouse] Flush readed byte Nr 5 value ff time_us 1557
2016/06/19 11:07:44 5644943E r (reader) Do [mouse] Flush readed byte Nr 6 value bc time_us 480
2016/06/19 11:07:44 5644943E r (reader) Do [mouse] Flush readed byte Nr 7 value 28 time_us 431
2016/06/19 11:07:44 5644943E r (reader) Do [mouse] Flush readed byte Nr 8 value 7d time_us 418
2016/06/19 11:07:44 5644943E r (reader) Do [mouse] Flush readed byte Nr 9 value bf time_us 1478
2016/06/19 11:07:44 5644943E r (reader) Do [mouse] Flush readed byte Nr 10 value c9 time_us 566
2016/06/19 11:07:44 5644943E r (reader) Do [mouse] Flush readed byte Nr 11 value f6 time_us 519
2016/06/19 11:07:44 5644943E r (reader) Do [mouse] Flush readed byte Nr 12 value ff time_us 540
2016/06/19 11:07:45 5644943E r (reader) Do [mouse] Buffers readed 12 bytes total time_us 93722
2016/06/19 11:07:45 5644943E r (reader) Do [mouse] Reader initialized (device=/dev/Do, detect=cd, mhz=369, cardmhz=368)
2016/06/19 11:07:45 2E26931A r (reader) emulator [emu] card detected
2016/06/19 11:07:45 5644943E r (reader) Do [mouse] card detected
2016/06/19 11:07:45 5644943E r (reader) Do [mouse] Buffers readed 0 bytes total time_us 75304
2016/06/19 11:07:46 2E26931A r (reader) emulator [emu] found card system emu
2016/06/19 11:07:46 2E26931A r (reader) emulator [emu] THIS WAS A SUCCESSFUL START ATTEMPT No 1 out of max alloted of 1
2016/06/19 11:07:46 2E26931A r key file not found in: /var/etc
2016/06/19 11:07:46 2E26931A r reading key file: /var/keys/SoftCam.Key
2016/06/19 11:07:47 5644943E r (reader) Do [mouse] ATR:
2016/06/19 11:07:47 5644943E r (reader) Do [mouse] Buffers readed 0 bytes total time_us 75209
2016/06/19 11:07:47 5644943E r (reader) Do [mouse] Init card protocol T0, FI=1, F=372, D=12, N=0
2016/06/19 11:07:47 5644943E r (reader) Do [mouse] Setting baudrate to 118916 bps
2016/06/19 11:07:47 5644943E r (reader) Do [mouse] Calculated work ETU is 8.40 us reader mhz = 369
2016/06/19 11:07:47 5644943E r (reader) Do [mouse] Buffers readed 0 bytes total time_us 88335
2016/06/19 11:07:47 5644943E r (reader) Do [mouse] ATR Fsmax is 5 MHz, clocking card to wanted user cardspeed off 3.69 MHz (specified in reader->mhz)
2016/06/19 11:07:48 5644943E r (reader) Do [mouse] using ecm #710 for long viaccess ecm
2016/06/19 11:07:48 5644943E r (reader) Do [mouse] type: Viaccess (non-standard atr), caid: 0500, serial: ##########
2016/06/19 11:07:48 5644943E r (reader) Do [mouse] providers: 3 (FFF8F0,043800,050F00)
2016/06/19 11:07:48 5644943E r (reader) Do [mouse] Maturity level [F]=no age limit
2016/06/19 11:07:48 5644943E r (reader) Do [mouse] Using PIN 0000!
2016/06/19 11:07:48 5644943E r (reader) Do [mouse] Parental lock disabled
2016/06/19 11:07:48 5644943E r (reader) Do [mouse] Maturity level [F]=no age limit
2016/06/19 11:07:48 5644943E r (reader) Do [mouse] ready for requests
2016/06/19 11:07:48 5644943E r (reader) Do [mouse] found card system viaccess
2016/06/19 11:07:48 5644943E r (reader) Do [viaccess] THIS WAS A SUCCESSFUL START ATTEMPT No 1 out of max alloted of 1
2016/06/19 11:07:48 5644943E r (reader) Do [viaccess] card detected
2016/06/19 11:07:48 5644943E r (reader) Do [viaccess] serial: ##########
2016/06/19 11:07:49 5644943E r (reader) Do [viaccess] provider: 1, id: ####################, sa: ########, geo: empty
2016/06/19 11:07:49 5644943E r (reader) Do [viaccess] provider: 2, id: ######################, sa: ########, geo: FF FF FF FF
2016/06/19 11:07:49 5644943E r (reader) Do [viaccess] class: 0A, expiry date: 2016/06/01 - 2017/05/31
2016/06/19 11:07:49 5644943E r (reader) Do [viaccess] provider: 3, id: ######################, sa: ########, geo: FF FF FF FF
2016/06/19 11:07:49 5644943E r (reader) Do [viaccess] class: 0A, expiry date: 2016/06/01 - 2017/05/31
2016/06/19 11:07:50 00000000 s (main) init for all local cards done
2016/06/19 11:07:50 00000000 s (emmcache) loaded 2 emmcache records from /var/etc/oscam.emmcache in 0 ms
2016/06/19 11:07:50 00000000 s (emmcache) loaded 0 emmstat records from /var/etc/oscam.emmstat in 0 ms
2016/06/19 11:07:50 00000000 s (anticasc) anti cascading disabled
2016/06/19 11:08:17 03117B56 c (client) encrypted cs357x-client 192.168.1.19 granted (UFS9132, au=auto (2 reader))
2016/06/19 11:08:17 03117B56 c (ecm) UFS9132 (09C4@000000/025F/0076/B3:B7A11AAF7C0F39CC29A61D11C30CD466): rejected group (0 ms) (no matching reader)
2016/06/19 11:08:22 03117B56 c (ecm) UFS9132 (09C4@000000/025F/0076/B3:BC7B5117CCD0E6E9E1348CD35C7C217F): rejected group (0 ms) (no matching reader)
2016/06/19 11:08:28 03117B56 c (ecm) UFS9132 (09C4@000000/025F/0076/B3:BC7B5117CCD0E6E9E1348CD35C7C217F): rejected group (1 ms) (no matching reader)
2016/06/19 11:08:29 03117B56 c (ecm) UFS9132 (09C4@000000/025F/0076/B3:CF87F9F9FCDDF866715AFF3770ED7A38): rejected group (0 ms) (no matching reader)
2016/06/19 11:08:50 2E26931A r [Emu] Key not found: V 23800 C1
2016/06/19 11:08:50 2E26931A r [Emu] ECM failed: Key not found
2016/06/19 11:08:50 03117B56 c (ecm) UFS9132 (0500@023800/08E2/4332/2A:362E31689B0EA5D37DDDBFC8653180F0): not found (3 ms) by emulator
2016/06/19 11:08:51 03117B56 c (ecm) UFS9132 (0500@040810/18E2/4332/2A:E80430989FF98FAC02F36DCE7E418621): rejected group (0 ms) (no matching reader)
2016/06/19 11:08:52 03117B56 c (ecm) UFS9132 (0500@050800/08E2/4332/2A:E18EE2D0FB6B82D9FCE1CE23DC50D4AD): rejected group (1 ms) (no matching reader)
2016/06/19 11:08:53 03117B56 c (ecm) UFS9132 (0500@060200/08DE/4332/30

A8545945708FB4DCD062D8A55D05971): rejected group (0 ms) (no matching reader)
2016/06/19 11:08:54 2E26931A r [Emu] Key not found: V 23800 C1
2016/06/19 11:08:54 2E26931A r [Emu] ECM failed: Key not found
2016/06/19 11:08:54 03117B56 c (ecm) UFS9132 (0500@023800/08E2/4332/2A:4AA83C306D59190923595DAD1BFAC01D): not found (3 ms) by emulator
2016/06/19 11:08:55 03117B56 c (ecm) UFS9132 (0500@040810/18E2/4332/2A:234AC2160C6FBBFB7015B4D5AA089173): rejected group (0 ms) (no matching reader)
2016/06/19 11:08:56 03117B56 c (ecm) UFS9132 (0500@050800/08E2/4332/2A:CB46D6E807A294D7C13F9C25B91741B9): rejected group (0 ms) (no matching reader)
2016/06/19 11:08:57 03117B56 c (ecm) UFS9132 (0500@060200/08DE/4332/30:2628689F6D2C0907B394A4519DDE1E00): rejected group (1 ms) (no matching reader)
switch log file