>> OSCam << cardserver started at Mon May 19 12:44:23 2014
-------------------------------------------------------------------------------
2014/05/19 12:44:23 0 s >> OSCam << cardserver started, version 1.20-unstable_svn, build r9665 (mipsel-oe-linux)
2014/05/19 12:44:23 0 s creating pidfile /tmp/.oscam/oscam.pid with pid 22004
2014/05/19 12:44:23 0 s services reloaded: 0 services freed, 33 services loaded, rejected 0
2014/05/19 12:44:23 0 s userdb reloaded: 5 accounts loaded, 0 expired, 0 disabled
2014/05/19 12:44:23 0 s signal handling initialized
2014/05/19 12:44:23 0 s 481 service-id's loaded in 12ms
2014/05/19 12:44:23 0 s cccam: initialized (fd=5, port=33333)
2014/05/19 12:44:23 0 s V14 [internal] creating thread for device /dev/sci0
2014/05/19 12:44:23 0 s loadbalancer: can't read from file /tmp/.oscam/stat
2014/05/19 12:44:23 0 s waiting for local card init
2014/05/19 12:44:23 69BC40 r V14 [internal] Reader initialized (device=/dev/sci0, detect=cd, pll max=27.00 MHz, wanted mhz=0.01 MHz
2014/05/19 12:44:24 69D940 h webif: decompressed 150079 bytes back into 364664 bytes
2014/05/19 12:44:24 69D940 h HTTP Server running. ip=0.0.0.0 port=17000
2014/05/19 12:44:24 69BC40 r V14 [internal] card detected
2014/05/19 12:44:26 69BC40 r V14 [internal] ATR: xx xx xx xx xx xx xx xx xx xx xx weg gemacht
2014/05/19 12:44:26 69BC40 r V14 [internal] Init card protocol T0, FI=1, F=372, D=4, N=2
2014/05/19 12:44:26 69BC40 r V14 [internal] TEST tempo mhz check = 500 mhz
2014/05/19 12:44:26 69BC40 r V14 [internal] Calculated work ETU is 20.67 us reader mhz = 450
2014/05/19 12:44:26 69BC40 r V14 [internal] PLL Reader: ATR Fsmax is 5 MHz, clocking card to 4.50 Mhz (nearest possible mhz specified reader->cardmhz)
2014/05/19 12:44:27 69BC40 r V14 [internal] Card type: P1TV
2014/05/19 12:44:27 69BC40 r V14 [internal] Rom version: 20AA
2014/05/19 12:44:27 69BC40 r V14 [internal] Credit available on card: 0 euro
2014/05/19 12:44:27 69BC40 r V14 [internal] Extended 4C detected
2014/05/19 12:44:27 69BC40 r V14 [internal] Region Code: 00BO0102
2014/05/19 12:44:27 69BC40 r V14 [internal] Pincode read: xxxx weg gemacht
2014/05/19 12:44:27 69BC40 r V14 [internal] PCB settings: FF FF FF FF
2014/05/19 12:44:27 69BC40 r V14 [internal] type: VideoGuard Sky Austria/Germany (098C), caid: 098C
2014/05/19 12:44:27 69BC40 r V14 [internal] serial: ########, BoxID: ########, baseyear: 2004
2014/05/19 12:44:27 69BC40 r V14 [internal] ready for requests
2014/05/19 12:44:27 69BC40 r V14 [internal] found card system videoguard2
2014/05/19 12:44:27 69BC40 r V14 [videoguard2] THIS WAS A SUCCESSFUL START ATTEMPT No 1 out of max alloted of 1
2014/05/19 12:44:27 69BC40 r V14 [videoguard2] card detected
2014/05/19 12:44:27 69BC40 r V14 [videoguard2] type: VideoGuard Sky Austria/Germany (098C)
2014/05/19 12:44:27 69BC40 r V14 [videoguard2] tier: 0046, expiry date: 2014/09/16-01:00:00
2014/05/19 12:44:27 69BC40 r V14 [videoguard2] tier: 005d, expiry date: 2014/09/16-01:00:00
2014/05/19 12:44:27 69BC40 r V14 [videoguard2] tier: 005e, expiry date: 2014/09/16-01:00:00
2014/05/19 12:44:27 69BC40 r V14 [videoguard2] tier: 0065, expiry date: 2014/09/16-01:00:00
2014/05/19 12:44:27 69BC40 r V14 [videoguard2] tier: 0066, expiry date: 2014/09/16-01:00:00
2014/05/19 12:44:27 69BC40 r V14 [videoguard2] tier: 0067, expiry date: 2014/09/16-01:00:00
2014/05/19 12:44:27 69BC40 r V14 [videoguard2] tier: 0068, expiry date: 2014/09/16-01:00:00
2014/05/19 12:44:27 69BC40 r V14 [videoguard2] tier: 0069, expiry date: 2014/09/16-01:00:00
2014/05/19 12:44:27 69BC40 r V14 [videoguard2] tier: 006a, expiry date: 2014/09/16-01:00:00
2014/05/19 12:44:27 69BC40 r V14 [videoguard2] tier: 006b, expiry date: 2014/09/16-01:00:00
2014/05/19 12:44:27 69BC40 r V14 [videoguard2] tier: 006f, expiry date: 2014/09/16-01:00:00
2014/05/19 12:44:27 69BC40 r V14 [videoguard2] tier: 007b, expiry date: 2014/09/16-01:00:00
2014/05/19 12:44:27 69BC40 r V14 [videoguard2] tier: 007d, expiry date: 2014/09/16-01:00:00
2014/05/19 12:44:27 69BC40 r V14 [videoguard2] tier: 0081, expiry date: 2014/09/16-01:00:00
2014/05/19 12:44:27 69BC40 r V14 [videoguard2] tier: 008e, expiry date: 2014/09/16-01:00:00
2014/05/19 12:44:27 69BC40 r V14 [videoguard2] tier: 00c0, expiry date: 2014/09/16-01:00:00
2014/05/19 12:44:27 69BC40 r V14 [videoguard2] tier: 00c7, expiry date: 2014/09/16-01:00:00
2014/05/19 12:44:27 69BC40 r V14 [videoguard2] tier: 00c8, expiry date: 2014/09/16-01:00:00
2014/05/19 12:44:27 69BC40 r V14 [videoguard2] tier: 00c9, expiry date: 2014/09/16-01:00:00
2014/05/19 12:44:27 69BC40 r V14 [videoguard2] tier: 00ca, expiry date: 2014/09/16-01:00:00
2014/05/19 12:44:27 69BC40 r V14 [videoguard2] tier: 00e4, expiry date: 2014/09/16-01:00:00
2014/05/19 12:44:27 69BC40 r V14 [videoguard2] tier: 00e5, expiry date: 2014/09/16-01:00:00
2014/05/19 12:44:27 69BC40 r V14 [videoguard2] tier: 00f3, expiry date: 2014/09/16-01:00:00
2014/05/19 12:44:27 69BC40 r V14 [videoguard2] tier: 00fa, expiry date: 2014/09/16-01:00:00
2014/05/19 12:44:27 69BC40 r V14 [videoguard2] tier: 00ff, expiry date: 2014/09/16-01:00:00
2014/05/19 12:44:31 0 s init for all local cards done
2014/05/19 12:44:31 0 s anti cascading disabled
2014/05/19 12:44:31 6BD8F8 c plain dvbapi-client granted (serverbox, au=on (1 reader))
2014/05/19 12:44:31 6BD8F8 c [DVBAPI] Detected /dev/dvb/adapter0/demux0 Api: 0, userconfig boxtype: 1
2014/05/19 12:44:33 6BD8F8 c [DVBAPI] Receiver sends PMT command 3 for channel 006C
2014/05/19 12:44:33 6BD8F8 c [DVBAPI] Receiver wants to demux srvid 006C on adapter 0000 camask 0008 index 0003 pmtpid 0063
2014/05/19 12:44:33 6BD8F8 c [ADD PID 0] CAID: 1833 ECM_PID: 180F PROVID: 000000
2014/05/19 12:44:33 6BD8F8 c [ADD PID 1] CAID: 09C4 ECM_PID: 1B0F PROVID: 000000
2014/05/19 12:44:33 6BD8F8 c [ADD PID 2] CAID: 09AF ECM_PID: 1F0F PROVID: 000000
2014/05/19 12:44:33 6BD8F8 c [ADD PID 3] CAID: 098C ECM_PID: 1A0F PROVID: 000000
2014/05/19 12:44:33 6BD8F8 c Found 4 ECMpids and 3 STREAMpids in PMT
2014/05/19 12:44:33 6BD8F8 c [DVBAPI] Receiver wants to demux srvid 006C on adapter 0000 camask 0008 index 0003 pmtpid 0063
2014/05/19 12:44:33 6BD8F8 c New program number: 006C (Sky Sport News HD) [pmt_list_management 3]
2014/05/19 12:44:33 6BD8F8 c [DVBAPI] Demuxer #0 trying to descramble PID #3 CAID 098C PROVID 000000 ECMPID 1A0F ANY CHID PMTPID 0063 VPID 03FF
2014/05/19 12:44:34 6BD8F8 c ERROR: Can't open device /dev/dvb/adapter0/ca3 (errno=16 Device or resource busy)
2014/05/19 12:44:34 0 --- Skipped 3 duplicated log lines ---
2014/05/19 12:44:34 6BD8F8 c serverbox (098C/006C/98): found (143 ms) by V14 - Sky Sport News HD
2014/05/19 12:44:40 6BD8F8 c ERROR: Can't open device /dev/dvb/adapter0/ca3 (errno=16 Device or resource busy)
2014/05/19 12:44:40 6BD8F8 c serverbox (098C/006C/98): found (122 ms) by V14 - Sky Sport News HD
2014/05/19 12:44:47 6BD8F8 c ERROR: Can't open device /dev/dvb/adapter0/ca3 (errno=16 Device or resource busy)
2014/05/19 12:44:47 6BD8F8 c serverbox (098C/006C/98): found (122 ms) by V14 - Sky Sport News HD
2014/05/19 12:44:48 69D940 h all debug_level=65535
2014/05/19 12:44:48 69D940 h WebIf: Origin checked. Result: access from 192.168.2.101 => allowed
2014/05/19 12:44:48 0 share-updater mode=initfast t=1s i=12
2014/05/19 12:44:49 69BC40 r V14 [videoguard2] cardreader_do_checkhealth: reader->card_status = 2, ret = 1
2014/05/19 12:44:49 0 share-updater mode=initfast t=1s i=11
2014/05/19 12:44:50 69BC40 r V14 [videoguard2] cardreader_do_checkhealth: reader->card_status = 2, ret = 1
2014/05/19 12:44:50 69D940 h WebIf: Origin checked. Result: access from 192.168.2.101 => allowed
2014/05/19 12:44:50 0 share-updater mode=initfast t=1s i=10
2014/05/19 12:44:51 69BC40 r V14 [videoguard2] cardreader_do_checkhealth: reader->card_status = 2, ret = 1
2014/05/19 12:44:51 69D940 h WebIf: Origin checked. Result: access from 192.168.2.101 => allowed
2014/05/19 12:44:51 0 share-updater mode=initfast t=1s i=9
2014/05/19 12:44:52 69BC40 r V14 [videoguard2] cardreader_do_checkhealth: reader->card_status = 2, ret = 1
2014/05/19 12:44:52 0 start client thread action 31
2014/05/19 12:44:52 6BD8F8 c data from add_job action=31 client c serverbox
2014/05/19 12:44:52 69D940 h WebIf: Origin checked. Result: access from 192.168.2.101 => allowed
2014/05/19 12:44:52 0 share-updater mode=initfast t=1s i=8
2014/05/19 12:44:53 69BC40 r V14 [videoguard2] cardreader_do_checkhealth: reader->card_status = 2, ret = 1
2014/05/19 12:44:53 69D940 h WebIf: Origin checked. Result: access from 192.168.2.101 => allowed
2014/05/19 12:44:53 0 share-updater mode=initfast t=1s i=7
2014/05/19 12:44:54 69BC40 r V14 [videoguard2] cardreader_do_checkhealth: reader->card_status = 2, ret = 1
2014/05/19 12:44:54 6BD8F8 c [DVBAPI] new events occurred on 1 of 3 handlers after 7000 ms inactivity
2014/05/19 12:44:54 6BD8F8 c [DVBAPI] now handling fd 15 that reported event 3
2014/05/19 12:44:54 6BD8F8 c [DVBAPI] Readed:
2014/05/19 12:44:54 6BD8F8 81 70 95 00 00 01 1D 7C 13 55 97 0C 20 AA 55 05
2014/05/19 12:44:54 6BD8F8 20 01 00 00 80 40 3C D2 67 BC 50 0E BA E9 89 03
2014/05/19 12:44:54 6BD8F8 02 06 02 72 73 7D 0B 0C 1D 7E 32 01 48 9C FD BD
2014/05/19 12:44:54 6BD8F8 00 00 90 64 C1 01 9A 16 3F DC 70 20 6A FE 3E A7
2014/05/19 12:44:54 6BD8F8 21 D8 F1 48 FE FE 35 50 56 AB E3 B9 ED 82 9A 7A
2014/05/19 12:44:54 6BD8F8 69 0C 6E CF 35 19 32 1F 1B 73 01 49 6A FA F4 72
2014/05/19 12:44:54 6BD8F8 A4 A2 21 4C 6D 51 BF CA 07 16 CD 8D 91 34 EB CF
2014/05/19 12:44:54 6BD8F8 E2 4D 52 08 85 F0 10 CC 43 F6 F1 09 AE 22 58 43
2014/05/19 12:44:54 6BD8F8 59 59 E6 93 A0 3D E4 06 41 1B 3B 34 74 98 A8 4F
2014/05/19 12:44:54 6BD8F8 2F 99 B6 45 ED 4A EE 46
2014/05/19 12:44:54 6BD8F8 c [DVBAPI] Demuxer #0 Filter #1 fetched ecm data
2014/05/19 12:44:54 6BD8F8 c [DVBAPI] Demuxer #0 ECMTYPE 81 CAID 098C PROVID 000000 ECMPID 1A0F FAKECHID 0C20 (unique part in ecm)
2014/05/19 12:44:54 6BD8F8 c [DVBAPI] Demuxer #0 Filter #1 set ecmtable to EVEN (CAID 098C PROVID 000000 FD 15)
2014/05/19 12:44:54 6BD8F8 c [DVBAPI] Demuxer #0 Filter #1 set chid to 0C20 on fd 15
2014/05/19 12:44:54 6BD8F8 c {client serverbox, caid 098C, prid 000000, srvid 006C} [get_cw] NEW REQUEST!
2014/05/19 12:44:54 6BD8F8 c [GET_CW] wait_time 0 caid 098C prov 000000 srvid 006C rc 100 cacheex cl mode 0 ex1rdr 0
2014/05/19 12:44:54 6BD8F8 c {client serverbox, caid 098C, prid 000000, srvid 006C} [get_cw] wait_time 0 - client cacheex mode 0, reader avail for ecm 1, hitcache 0
2014/05/19 12:44:54 6BD8F8 c request_cw stage=2 to reader V14 ecm hash=5B0910538655715ABC87D82ACDAFED51
2014/05/19 12:44:54 6BD8F8 c start reader thread action 5
2014/05/19 12:44:54 6BD8F8 c {client serverbox, caid 098C, prid 000000, srvid 006C} [write_ecm_request] reader V14 --> SENT 1
2014/05/19 12:44:54 6BD8F8 c Client serverbox ECM dump 098C/006C/98
2014/05/19 12:44:54 6BD8F8 81 70 95 00 00 01 1D 7C 13 55 97 0C 20 AA 55 05
2014/05/19 12:44:54 6BD8F8 20 01 00 00 80 40 3C D2 67 BC 50 0E BA E9 89 03
2014/05/19 12:44:54 6BD8F8 02 06 02 72 73 7D 0B 0C 1D 7E 32 01 48 9C FD BD
2014/05/19 12:44:54 6BD8F8 00 00 90 64 C1 01 9A 16 3F DC 70 20 6A FE 3E A7
2014/05/19 12:44:54 6BD8F8 21 D8 F1 48 FE FE 35 50 56 AB E3 B9 ED 82 9A 7A
2014/05/19 12:44:54 6BD8F8 69 0C 6E CF 35 19 32 1F 1B 73 01 49 6A FA F4 72
2014/05/19 12:44:54 6BD8F8 A4 A2 21 4C 6D 51 BF CA 07 16 CD 8D 91 34 EB CF
2014/05/19 12:44:54 6BD8F8 E2 4D 52 08 85 F0 10 CC 43 F6 F1 09 AE 22 58 43
2014/05/19 12:44:54 6BD8F8 59 59 E6 93 A0 3D E4 06 41 1B 3B 34 74 98 A8 4F
2014/05/19 12:44:54 6BD8F8 2F 99 B6 45 ED 4A EE 46
2014/05/19 12:44:54 6BD8F8 c [DVBAPI] Demuxer #0 request cw for ecm 098C/006C/98
2014/05/19 12:44:54 69BC40 r data from add_job action=5 client r V14
2014/05/19 12:44:54 69BC40 r ecm:
2014/05/19 12:44:54 69BC40 81 70 95 00 00 01 1D 7C 13 55 97 0C 20 AA 55 05
2014/05/19 12:44:54 69BC40 20 01 00 00 80 40 3C D2 67 BC 50 0E BA E9 89 03
2014/05/19 12:44:54 69BC40 02 06 02 72 73 7D 0B 0C 1D 7E 32 01 48 9C FD BD
2014/05/19 12:44:54 69BC40 00 00 90 64 C1 01 9A 16 3F DC 70 20 6A FE 3E A7
2014/05/19 12:44:54 69BC40 21 D8 F1 48 FE FE 35 50 56 AB E3 B9 ED 82 9A 7A
2014/05/19 12:44:54 69BC40 69 0C 6E CF 35 19 32 1F 1B 73 01 49 6A FA F4 72
2014/05/19 12:44:54 69BC40 A4 A2 21 4C 6D 51 BF CA 07 16 CD 8D 91 34 EB CF
2014/05/19 12:44:54 69BC40 E2 4D 52 08 85 F0 10 CC 43 F6 F1 09 AE 22 58 43
2014/05/19 12:44:54 69BC40 59 59 E6 93 A0 3D E4 06 41 1B 3B 34 74 98 A8 4F
2014/05/19 12:44:54 69BC40 2F 99 B6 45 ED 4A EE 46
2014/05/19 12:44:54 69BC40 r V14 [videoguard2] cardreader_do_checkhealth: reader->card_status = 2, ret = 1
2014/05/19 12:44:54 69BC40 r V14 [videoguard2] cardreader_do_ecm: cardreader_do_checkhealth returned rc=1
2014/05/19 12:44:54 69BC40 r V14 [videoguard2] write to cardreader
2014/05/19 12:44:54 69BC40 D1 40 00 80 74 00 7D 0B 0C 1D 7E 32 01 48 9C FD
2014/05/19 12:44:54 69BC40 BD 00 00 90 64 C1 01 9A 16 3F DC 70 20 6A FE 3E
2014/05/19 12:44:54 69BC40 A7 21 D8 F1 48 FE FE 35 50 56 AB E3 B9 ED 82 9A
2014/05/19 12:44:54 69BC40 7A 69 0C 6E CF 35 19 32 1F 1B 73 01 49 6A FA F4
2014/05/19 12:44:54 69BC40 72 A4 A2 21 4C 6D 51 BF CA 07 16 CD 8D 91 34 EB
2014/05/19 12:44:54 69BC40 CF E2 4D 52 08 85 F0 10 CC 43 F6 F1 09 AE 22 58
2014/05/19 12:44:54 69BC40 43 59 59 E6 93 A0 3D E4 06 41 1B 3B 34 74 98 A8
2014/05/19 12:44:54 69BC40 4F 2F 99 B6 45 ED 4A EE 46
2014/05/19 12:44:54 69BC40 r V14 [videoguard2] IFD: Setting timeout to 14 ETU (289 us)
2014/05/19 12:44:54 69BC40 r V14 [videoguard2] IFD: Transmit size 5 bytes, expected len 1 bytes, delay 0 us, timeout=289 us
2014/05/19 12:44:54 69BC40 r V14 [videoguard2] IFD: Transmit:
2014/05/19 12:44:54 69BC40 D1 40 00 80 74
2014/05/19 12:44:54 69BC40 r V14 [videoguard2] IO: Write timeout 289 us, write delay 0 us, to send 5 char(s), chunksize 5 char(s)
2014/05/19 12:44:54 69BC40 r V14 [videoguard2] IO: Sending:
2014/05/19 12:44:54 69BC40 D1 40 00 80 74
2014/05/19 12:44:54 69BC40 r V14 [videoguard2] IFD: Transmit succesful
2014/05/19 12:44:54 69BC40 r V14 [videoguard2] IFD: Setting timeout to 57600 ETU (1190400 us)
2014/05/19 12:44:54 69BC40 r V14 [videoguard2] IFD: Receive size 1 bytes, delay 0 us, timeout=1190400 us
2014/05/19 12:44:54 69BC40 r V14 [videoguard2] IO: Read timeout 1190400 us, read delay 0 us, to read 1 char(s), chunksize 1 char(s)
2014/05/19 12:44:54 69BC40 r V14 [videoguard2] IO: Receiving:
2014/05/19 12:44:54 69BC40 40
2014/05/19 12:44:54 69BC40 r V14 [videoguard2] IFD: Receive succesful
2014/05/19 12:44:54 69BC40 r V14 [videoguard2] IFD: Setting timeout to 14 ETU (289 us)
2014/05/19 12:44:54 69BC40 r V14 [videoguard2] IFD: Transmit size 116 bytes, expected len 2 bytes, delay 0 us, timeout=289 us
2014/05/19 12:44:54 69BC40 r V14 [videoguard2] IFD: Transmit:
2014/05/19 12:44:54 69BC40 00 7D 0B 0C 1D 7E 32 01 48 9C FD BD 00 00 90 64
2014/05/19 12:44:54 69BC40 C1 01 9A 16 3F DC 70 20 6A FE 3E A7 21 D8 F1 48
2014/05/19 12:44:54 69BC40 FE FE 35 50 56 AB E3 B9 ED 82 9A 7A 69 0C 6E CF
2014/05/19 12:44:54 69BC40 35 19 32 1F 1B 73 01 49 6A FA F4 72 A4 A2 21 4C
2014/05/19 12:44:54 69BC40 6D 51 BF CA 07 16 CD 8D 91 34 EB CF E2 4D 52 08
2014/05/19 12:44:54 69BC40 85 F0 10 CC 43 F6 F1 09 AE 22 58 43 59 59 E6 93
2014/05/19 12:44:54 69BC40 A0 3D E4 06 41 1B 3B 34 74 98 A8 4F 2F 99 B6 45
2014/05/19 12:44:54 69BC40 ED 4A EE 46
2014/05/19 12:44:54 69BC40 r V14 [videoguard2] IO: Write timeout 289 us, write delay 0 us, to send 116 char(s), chunksize 116 char(s)
2014/05/19 12:44:54 69BC40 r V14 [videoguard2] IO: Sending:
2014/05/19 12:44:54 69BC40 00 7D 0B 0C 1D 7E 32 01 48 9C FD BD 00 00 90 64
2014/05/19 12:44:54 69BC40 C1 01 9A 16 3F DC 70 20 6A FE 3E A7 21 D8 F1 48
2014/05/19 12:44:54 69BC40 FE FE 35 50 56 AB E3 B9 ED 82 9A 7A 69 0C 6E CF
2014/05/19 12:44:54 69BC40 35 19 32 1F 1B 73 01 49 6A FA F4 72 A4 A2 21 4C
2014/05/19 12:44:54 69BC40 6D 51 BF CA 07 16 CD 8D 91 34 EB CF E2 4D 52 08
2014/05/19 12:44:54 69BC40 85 F0 10 CC 43 F6 F1 09 AE 22 58 43 59 59 E6 93
2014/05/19 12:44:54 69BC40 A0 3D E4 06 41 1B 3B 34 74 98 A8 4F 2F 99 B6 45
2014/05/19 12:44:54 69BC40 ED 4A EE 46
2014/05/19 12:44:54 69BC40 r V14 [videoguard2] IFD: Transmit succesful
2014/05/19 12:44:54 69BC40 r V14 [videoguard2] IFD: Setting timeout to 57600 ETU (1190400 us)
2014/05/19 12:44:54 69BC40 r V14 [videoguard2] IFD: Receive size 1 bytes, delay 0 us, timeout=1190400 us
2014/05/19 12:44:54 69BC40 r V14 [videoguard2] IO: Read timeout 1190400 us, read delay 0 us, to read 1 char(s), chunksize 1 char(s)
2014/05/19 12:44:54 69BC40 r V14 [videoguard2] IO: Receiving:
2014/05/19 12:44:54 69BC40 90
2014/05/19 12:44:54 69BC40 r V14 [videoguard2] IFD: Receive succesful
2014/05/19 12:44:54 69BC40 r V14 [videoguard2] TRACE: SW1: 90
2014/05/19 12:44:54 69BC40 r V14 [videoguard2] IFD: Setting timeout to 57600 ETU (1190400 us)
2014/05/19 12:44:54 69BC40 r V14 [videoguard2] IFD: Receive size 1 bytes, delay 0 us, timeout=1190400 us
2014/05/19 12:44:54 69BC40 r V14 [videoguard2] IO: Read timeout 1190400 us, read delay 0 us, to read 1 char(s), chunksize 1 char(s)
2014/05/19 12:44:54 69BC40 r V14 [videoguard2] IO: Receiving:
2014/05/19 12:44:54 69BC40 00
2014/05/19 12:44:54 69BC40 r V14 [videoguard2] IFD: Receive succesful
2014/05/19 12:44:54 69BC40 r V14 [videoguard2] TRACE: SW2: 00
2014/05/19 12:44:54 69BC40 r V14 [videoguard2] Answer from cardreader:
2014/05/19 12:44:54 69BC40 90 00
2014/05/19 12:44:54 69BC40 r V14 [videoguard2] write to cardreader
2014/05/19 12:44:54 69BC40 D3 54 00 00 5E
2014/05/19 12:44:54 69BC40 r V14 [videoguard2] IFD: Setting timeout to 14 ETU (289 us)
2014/05/19 12:44:54 69BC40 r V14 [videoguard2] IFD: Transmit size 5 bytes, expected len 97 bytes, delay 0 us, timeout=289 us
2014/05/19 12:44:54 69BC40 r V14 [videoguard2] IFD: Transmit:
2014/05/19 12:44:54 69BC40 D3 54 00 00 5E
2014/05/19 12:44:54 69BC40 r V14 [videoguard2] IO: Write timeout 289 us, write delay 0 us, to send 5 char(s), chunksize 5 char(s)
2014/05/19 12:44:54 69BC40 r V14 [videoguard2] IO: Sending:
2014/05/19 12:44:54 69BC40 D3 54 00 00 5E
2014/05/19 12:44:54 69BC40 r V14 [videoguard2] IFD: Transmit succesful
2014/05/19 12:44:54 69BC40 r V14 [videoguard2] IFD: Setting timeout to 57600 ETU (1190400 us)
2014/05/19 12:44:54 69BC40 r V14 [videoguard2] IFD: Receive size 1 bytes, delay 0 us, timeout=1190400 us
2014/05/19 12:44:54 69BC40 r V14 [videoguard2] IO: Read timeout 1190400 us, read delay 0 us, to read 1 char(s), chunksize 1 char(s)
2014/05/19 12:44:54 69BC40 r V14 [videoguard2] IO: Receiving:
2014/05/19 12:44:54 69BC40 54
2014/05/19 12:44:54 69BC40 r V14 [videoguard2] IFD: Receive succesful
2014/05/19 12:44:54 69BC40 r V14 [videoguard2] IFD: Setting timeout to 57600 ETU (1190400 us)
2014/05/19 12:44:54 69BC40 r V14 [videoguard2] IFD: Receive size 94 bytes, delay 0 us, timeout=1190400 us
2014/05/19 12:44:54 69BC40 r V14 [videoguard2] IO: Read timeout 1190400 us, read delay 0 us, to read 94 char(s), chunksize 94 char(s)
2014/05/19 12:44:54 69BC40 r V14 [videoguard2] IO: Receiving:
2014/05/19 12:44:54 69BC40 1D FA 20 93 B4 38 3B 96 0F 0A B1 0C 32 23 1A F4
2014/05/19 12:44:54 69BC40 D6 03 31 45 2B A0 92 6F 58 99 46 C6 63 A1 68 CB
2014/05/19 12:44:54 69BC40 B8 17 49 8E 22 7C 86 AE 78 F0 CE 79 A4 9C 8D 72
2014/05/19 12:44:54 69BC40 F6 74 45 E6 8B 3C 82 F2 80 10 17 A3 D5 E9 51 EB
2014/05/19 12:44:54 69BC40 54 D3 CC C2 98 D7 1E B8 DA 13 2D 12 0F 1F 33 F2
2014/05/19 12:44:54 69BC40 D9 06 5C 64 E6 0C E1 D4 A9 D5 FB 78 38 1B
2014/05/19 12:44:54 69BC40 r V14 [videoguard2] IFD: Receive succesful
2014/05/19 12:44:54 69BC40 r V14 [videoguard2] IFD: Setting timeout to 57600 ETU (1190400 us)
2014/05/19 12:44:54 69BC40 r V14 [videoguard2] IFD: Receive size 1 bytes, delay 0 us, timeout=1190400 us
2014/05/19 12:44:54 69BC40 r V14 [videoguard2] IO: Read timeout 1190400 us, read delay 0 us, to read 1 char(s), chunksize 1 char(s)
2014/05/19 12:44:54 69BC40 r V14 [videoguard2] IO: Receiving:
2014/05/19 12:44:54 69BC40 90
2014/05/19 12:44:54 69BC40 r V14 [videoguard2] IFD: Receive succesful
2014/05/19 12:44:54 69BC40 r V14 [videoguard2] TRACE: SW1: 90
2014/05/19 12:44:54 69BC40 r V14 [videoguard2] IFD: Setting timeout to 57600 ETU (1190400 us)
2014/05/19 12:44:54 69BC40 r V14 [videoguard2] IFD: Receive size 1 bytes, delay 0 us, timeout=1190400 us
2014/05/19 12:44:54 69BC40 r V14 [videoguard2] IO: Read timeout 1190400 us, read delay 0 us, to read 1 char(s), chunksize 1 char(s)
2014/05/19 12:44:54 69BC40 r V14 [videoguard2] IO: Receiving:
2014/05/19 12:44:54 69BC40 00
2014/05/19 12:44:54 69BC40 r V14 [videoguard2] IFD: Receive succesful
2014/05/19 12:44:54 69BC40 r V14 [videoguard2] TRACE: SW2: 00
2014/05/19 12:44:54 69BC40 r V14 [videoguard2] Answer from cardreader:
2014/05/19 12:44:54 69BC40 1D FA 20 93 B4 38 3B 96 0F 0A B1 0C 32 23 1A F4
2014/05/19 12:44:54 69BC40 D6 03 31 45 2B A0 92 6F 58 99 46 C6 63 A1 68 CB
2014/05/19 12:44:54 69BC40 B8 17 49 8E 22 7C 86 AE 78 F0 CE 79 A4 9C 8D 72
2014/05/19 12:44:54 69BC40 F6 74 45 E6 8B 3C 82 F2 80 10 17 A3 D5 E9 51 EB
2014/05/19 12:44:54 69BC40 54 D3 CC C2 98 D7 1E B8 DA 13 2D 12 0F 1F 33 F2
2014/05/19 12:44:54 69BC40 D9 06 5C 64 E6 0C E1 D4 A9 D5 FB 78 38 1B 90 00
2014/05/19 12:44:54 69BC40 r V14 [videoguard2] INS54:
2014/05/19 12:44:54 69BC40 D3 54 00 00 4E
2014/05/19 12:44:54 69BC40 r V14 [videoguard2] Decrypted payload
2014/05/19 12:44:54 69BC40 E9 BB C8 6C 5B CF B2 DC 00 00 00 C9 00 01 22 02
2014/05/19 12:44:54 69BC40 00 00 0E 02 03 00 0F 06 00 00 00 00 00 03 20 04
2014/05/19 12:44:54 69BC40 00 00 00 00 25 11 00 00 00 00 00 00 00 00 00 00
2014/05/19 12:44:54 69BC40 00 00 00 00 00 00 00 2A 04 00 C9 00 00 55 01 28
2014/05/19 12:44:54 69BC40 56 08 00 00 00 00 00 00 00 00 2B 02 00 00
2014/05/19 12:44:54 69BC40 r V14 [videoguard2] cardreader_do_ecm: after csystem.do_ecm rc=1
2014/05/19 12:44:54 69BC40 r V14 [videoguard2] cardreader_do_ecm: ret rc=1
2014/05/19 12:44:54 69BC40 r V14 [videoguard2] cardreader_process_ecm: cardreader_do_ecm returned rc=1 (ERROR=0)
2014/05/19 12:44:54 69BC40 r {client serverbox, caid 098C, srvid 006C} [write_ecm_answer] cyclecheck passed! Reader: V14 rc: 0
2014/05/19 12:44:54 69BC40 r {client serverbox, caid 098C, prid 000000, srvid 006C} [write_ecm_answer] reader V14 rc 0, ecm time 135 ms (137 ms)
2014/05/19 12:44:54 69BC40 r start client thread action 32
2014/05/19 12:44:54 69BC40 r V14 [videoguard2] TRACE: ecm answer for ecm hash 5B0910538655715ABC87D82ACDAFED51 rc=0
2014/05/19 12:44:54 69BC40 r V14 [videoguard2] ecm hash: 5B0910538655715ABC87D82ACDAFED51 real time: 131 ms
2014/05/19 12:44:54 6BD8F8 c data from add_job action=32 client c serverbox
2014/05/19 12:44:54 6BD8F8 c {client serverbox, caid 098C, prid 000000, srvid 006C} [send_dcw] rc 0 from reader V14
2014/05/19 12:44:54 6BD8F8 c Client serverbox csphash 45F3FD0F cw 0000000000000000E9BBC86C5BCFB2DC rc 0 098C/006C/98
2014/05/19 12:44:54 6BD8F8 c [DVBAPI] Demuxer #0 controlword received for PID #3 CAID 098C PROVID 000000 ECMPID 1A0F CHID 0C20 VPID 03FF
2014/05/19 12:44:54 6BD8F8 c [DVBAPI] Demuxer #0 requested controlword for ecm 5B0910538655715ABC87D82ACDAFED51 on fd 15
2014/05/19 12:44:54 6BD8F8 c [DVBAPI] Demuxer #0 writing even part (E9BBC86C5BCFB2DC) of controlword, replacing expired (46175EBBD48561BA)
2014/05/19 12:44:54 6BD8F8 c [DVBAPI] Demuxer #0 write cw1 index: 0 (ca3)
2014/05/19 12:44:54 6BD8F8 c ERROR: Can't open device /dev/dvb/adapter0/ca3 (errno=16 Device or resource busy)
2014/05/19 12:44:54 6BD8F8 c serverbox (098C/006C/98): found (144 ms) by V14 - Sky Sport News HD
2014/05/19 12:44:54 6BD8F8 c cw:
2014/05/19 12:44:54 6BD8F8 00 00 00 00 00 00 00 00 E9 BB C8 6C 5B CF B2 DC
2014/05/19 12:44:54 69D940 h WebIf: Origin checked. Result: access from 192.168.2.101 => allowed
2014/05/19 12:44:54 0 share-updater mode=initfast t=1s i=6
2014/05/19 12:44:55 69BC40 r V14 [videoguard2] cardreader_do_checkhealth: reader->card_status = 2, ret = 1
2014/05/19 12:44:55 69D940 h WebIf: Origin checked. Result: access from 192.168.2.101 => allowed
2014/05/19 12:44:55 0 share-updater mode=initfast t=1s i=5
2014/05/19 12:44:56 69BC40 r V14 [videoguard2] cardreader_do_checkhealth: reader->card_status = 2, ret = 1
2014/05/19 12:44:56 69D940 h WebIf: Origin checked. Result: access from 192.168.2.101 => allowed
2014/05/19 12:44:56 0 share-updater mode=initfast t=1s i=4
2014/05/19 12:44:57 69BC40 r V14 [videoguard2] cardreader_do_checkhealth: reader->card_status = 2, ret = 1
2014/05/19 12:44:57 0 share-updater mode=initfast t=1s i=3
2014/05/19 12:44:57 69D940 h WebIf: Origin checked. Result: access from 192.168.2.101 => allowed
2014/05/19 12:44:58 69BC40 r V14 [videoguard2] cardreader_do_checkhealth: reader->card_status = 2, ret = 1
2014/05/19 12:44:58 0 share-updater mode=initfast t=1s i=2
2014/05/19 12:44:59 69BC40 r V14 [videoguard2] cardreader_do_checkhealth: reader->card_status = 2, ret = 1
2014/05/19 12:44:59 0 start client thread action 31
2014/05/19 12:44:59 6BD8F8 c data from add_job action=31 client c serverbox
2014/05/19 12:44:59 69D940 h WebIf: Origin checked. Result: access from 192.168.2.101 => allowed
2014/05/19 12:44:59 0 share-updater mode=initfast t=1s i=1
2014/05/19 12:45:00 69BC40 r V14 [videoguard2] cardreader_do_checkhealth: reader->card_status = 2, ret = 1
2014/05/19 12:45:00 69D940 h WebIf: Origin checked. Result: access from 192.168.2.101 => allowed
2014/05/19 12:45:00 0 share-updater mode=interval t=240s
2014/05/19 12:45:01 69BC40 r V14 [videoguard2] cardreader_do_checkhealth: reader->card_status = 2, ret = 1
2014/05/19 12:45:01 6BD8F8 c [DVBAPI] new events occurred on 1 of 3 handlers after 7000 ms inactivity
2014/05/19 12:45:01 6BD8F8 c [DVBAPI] now handling fd 15 that reported event 3
2014/05/19 12:45:01 6BD8F8 c [DVBAPI] Readed:
2014/05/19 12:45:01 6BD8F8 80 70 95 00 00 01 1D 7C 13 55 9B 0C 20 AA 55 05
2014/05/19 12:45:01 6BD8F8 20 01 00 00 80 40 B0 55 E1 9D 57 83 43 0A F7 03
2014/05/19 12:45:01 6BD8F8 02 06 02 5C 73 7D 0B 0C 86 79 93 05 C0 DB 2C D0
2014/05/19 12:45:01 6BD8F8 00 00 90 64 C1 01 4C 5D 14 8D DD A4 EA A9 93 8E
2014/05/19 12:45:01 6BD8F8 25 A8 09 F8 F3 7D 3A 3F 53 5E 2C E5 44 B3 35 2E
2014/05/19 12:45:01 6BD8F8 50 89 5D 8C 9C 94 5A 88 83 4B F2 2F 6F B3 85 19
2014/05/19 12:45:01 6BD8F8 DC 64 5F 60 B4 BE E9 DD 75 7D 13 F0 9B 5E C7 BF
2014/05/19 12:45:01 6BD8F8 09 B8 A7 1E 40 EB B3 16 7F 50 04 F3 63 1A CD B7
2014/05/19 12:45:01 6BD8F8 A3 BF 20 4B 17 26 2C 43 F2 C2 0B 15 64 43 EA FA
2014/05/19 12:45:01 6BD8F8 DD D5 BA 41 1B 8B 59 AC
2014/05/19 12:45:01 6BD8F8 c [DVBAPI] Demuxer #0 Filter #1 fetched ecm data
2014/05/19 12:45:01 6BD8F8 c [DVBAPI] Demuxer #0 ECMTYPE 80 CAID 098C PROVID 000000 ECMPID 1A0F FAKECHID 0C20 (unique part in ecm)
2014/05/19 12:45:01 6BD8F8 c [DVBAPI] Demuxer #0 Filter #1 set ecmtable to ODD (CAID 098C PROVID 000000 FD 15)
2014/05/19 12:45:01 6BD8F8 c [DVBAPI] Demuxer #0 Filter #1 set chid to 0C20 on fd 15
2014/05/19 12:45:01 6BD8F8 c {client serverbox, caid 098C, prid 000000, srvid 006C} [get_cw] NEW REQUEST!
2014/05/19 12:45:01 6BD8F8 c [GET_CW] wait_time 0 caid 098C prov 000000 srvid 006C rc 100 cacheex cl mode 0 ex1rdr 0
2014/05/19 12:45:01 6BD8F8 c {client serverbox, caid 098C, prid 000000, srvid 006C} [get_cw] wait_time 0 - client cacheex mode 0, reader avail for ecm 1, hitcache 0
2014/05/19 12:45:01 6BD8F8 c request_cw stage=2 to reader V14 ecm hash=180D5A35CC8A334D54299A27811B958F
2014/05/19 12:45:01 6BD8F8 c start reader thread action 5
2014/05/19 12:45:01 6BD8F8 c {client serverbox, caid 098C, prid 000000, srvid 006C} [write_ecm_request] reader V14 --> SENT 1
2014/05/19 12:45:01 6BD8F8 c Client serverbox ECM dump 098C/006C/98
2014/05/19 12:45:01 6BD8F8 80 70 95 00 00 01 1D 7C 13 55 9B 0C 20 AA 55 05
2014/05/19 12:45:01 6BD8F8 20 01 00 00 80 40 B0 55 E1 9D 57 83 43 0A F7 03
2014/05/19 12:45:01 6BD8F8 02 06 02 5C 73 7D 0B 0C 86 79 93 05 C0 DB 2C D0
2014/05/19 12:45:01 6BD8F8 00 00 90 64 C1 01 4C 5D 14 8D DD A4 EA A9 93 8E
2014/05/19 12:45:01 6BD8F8 25 A8 09 F8 F3 7D 3A 3F 53 5E 2C E5 44 B3 35 2E
2014/05/19 12:45:01 6BD8F8 50 89 5D 8C 9C 94 5A 88 83 4B F2 2F 6F B3 85 19
2014/05/19 12:45:01 6BD8F8 DC 64 5F 60 B4 BE E9 DD 75 7D 13 F0 9B 5E C7 BF
2014/05/19 12:45:01 6BD8F8 09 B8 A7 1E 40 EB B3 16 7F 50 04 F3 63 1A CD B7
2014/05/19 12:45:01 6BD8F8 A3 BF 20 4B 17 26 2C 43 F2 C2 0B 15 64 43 EA FA
2014/05/19 12:45:01 6BD8F8 DD D5 BA 41 1B 8B 59 AC
2014/05/19 12:45:01 6BD8F8 c [DVBAPI] Demuxer #0 request cw for ecm 098C/006C/98
2014/05/19 12:45:01 69BC40 r data from add_job action=5 client r V14
2014/05/19 12:45:01 69BC40 r ecm:
2014/05/19 12:45:01 69BC40 80 70 95 00 00 01 1D 7C 13 55 9B 0C 20 AA 55 05
2014/05/19 12:45:01 69BC40 20 01 00 00 80 40 B0 55 E1 9D 57 83 43 0A F7 03
2014/05/19 12:45:01 69BC40 02 06 02 5C 73 7D 0B 0C 86 79 93 05 C0 DB 2C D0
2014/05/19 12:45:01 69BC40 00 00 90 64 C1 01 4C 5D 14 8D DD A4 EA A9 93 8E
2014/05/19 12:45:01 69BC40 25 A8 09 F8 F3 7D 3A 3F 53 5E 2C E5 44 B3 35 2E
2014/05/19 12:45:01 69BC40 50 89 5D 8C 9C 94 5A 88 83 4B F2 2F 6F B3 85 19
2014/05/19 12:45:01 69BC40 DC 64 5F 60 B4 BE E9 DD 75 7D 13 F0 9B 5E C7 BF
2014/05/19 12:45:01 69BC40 09 B8 A7 1E 40 EB B3 16 7F 50 04 F3 63 1A CD B7
2014/05/19 12:45:01 69BC40 A3 BF 20 4B 17 26 2C 43 F2 C2 0B 15 64 43 EA FA
2014/05/19 12:45:01 69BC40 DD D5 BA 41 1B 8B 59 AC
2014/05/19 12:45:01 69BC40 r V14 [videoguard2] cardreader_do_checkhealth: reader->card_status = 2, ret = 1
2014/05/19 12:45:01 69BC40 r V14 [videoguard2] cardreader_do_ecm: cardreader_do_checkhealth returned rc=1
2014/05/19 12:45:01 69BC40 r V14 [videoguard2] write to cardreader
2014/05/19 12:45:01 69BC40 D1 40 00 80 74 00 7D 0B 0C 86 79 93 05 C0 DB 2C
2014/05/19 12:45:01 69BC40 D0 00 00 90 64 C1 01 4C 5D 14 8D DD A4 EA A9 93
2014/05/19 12:45:01 69BC40 8E 25 A8 09 F8 F3 7D 3A 3F 53 5E 2C E5 44 B3 35
2014/05/19 12:45:01 69BC40 2E 50 89 5D 8C 9C 94 5A 88 83 4B F2 2F 6F B3 85
2014/05/19 12:45:01 69BC40 19 DC 64 5F 60 B4 BE E9 DD 75 7D 13 F0 9B 5E C7
2014/05/19 12:45:01 69BC40 BF 09 B8 A7 1E 40 EB B3 16 7F 50 04 F3 63 1A CD
2014/05/19 12:45:01 69BC40 B7 A3 BF 20 4B 17 26 2C 43 F2 C2 0B 15 64 43 EA
2014/05/19 12:45:01 69BC40 FA DD D5 BA 41 1B 8B 59 AC
2014/05/19 12:45:01 69BC40 r V14 [videoguard2] IFD: Setting timeout to 14 ETU (289 us)
2014/05/19 12:45:01 69BC40 r V14 [videoguard2] IFD: Transmit size 5 bytes, expected len 1 bytes, delay 0 us, timeout=289 us
2014/05/19 12:45:01 69BC40 r V14 [videoguard2] IFD: Transmit:
2014/05/19 12:45:01 69BC40 D1 40 00 80 74
2014/05/19 12:45:01 69BC40 r V14 [videoguard2] IO: Write timeout 289 us, write delay 0 us, to send 5 char(s), chunksize 5 char(s)
2014/05/19 12:45:01 69BC40 r V14 [videoguard2] IO: Sending:
2014/05/19 12:45:01 69BC40 D1 40 00 80 74
2014/05/19 12:45:01 69BC40 r V14 [videoguard2] IFD: Transmit succesful
2014/05/19 12:45:01 69BC40 r V14 [videoguard2] IFD: Setting timeout to 57600 ETU (1190400 us)
2014/05/19 12:45:01 69BC40 r V14 [videoguard2] IFD: Receive size 1 bytes, delay 0 us, timeout=1190400 us
2014/05/19 12:45:01 69BC40 r V14 [videoguard2] IO: Read timeout 1190400 us, read delay 0 us, to read 1 char(s), chunksize 1 char(s)
2014/05/19 12:45:01 69BC40 r V14 [videoguard2] IO: Receiving:
2014/05/19 12:45:01 69BC40 40
2014/05/19 12:45:01 69BC40 r V14 [videoguard2] IFD: Receive succesful
2014/05/19 12:45:01 69BC40 r V14 [videoguard2] IFD: Setting timeout to 14 ETU (289 us)
2014/05/19 12:45:01 69BC40 r V14 [videoguard2] IFD: Transmit size 116 bytes, expected len 2 bytes, delay 0 us, timeout=289 us
2014/05/19 12:45:01 69BC40 r V14 [videoguard2] IFD: Transmit:
2014/05/19 12:45:01 69BC40 00 7D 0B 0C 86 79 93 05 C0 DB 2C D0 00 00 90 64
2014/05/19 12:45:01 69BC40 C1 01 4C 5D 14 8D DD A4 EA A9 93 8E 25 A8 09 F8
2014/05/19 12:45:01 69BC40 F3 7D 3A 3F 53 5E 2C E5 44 B3 35 2E 50 89 5D 8C
2014/05/19 12:45:01 69BC40 9C 94 5A 88 83 4B F2 2F 6F B3 85 19 DC 64 5F 60
2014/05/19 12:45:01 69BC40 B4 BE E9 DD 75 7D 13 F0 9B 5E C7 BF 09 B8 A7 1E
2014/05/19 12:45:01 69BC40 40 EB B3 16 7F 50 04 F3 63 1A CD B7 A3 BF 20 4B
2014/05/19 12:45:01 69BC40 17 26 2C 43 F2 C2 0B 15 64 43 EA FA DD D5 BA 41
2014/05/19 12:45:01 69BC40 1B 8B 59 AC
2014/05/19 12:45:01 69BC40 r V14 [videoguard2] IO: Write timeout 289 us, write delay 0 us, to send 116 char(s), chunksize 116 char(s)
2014/05/19 12:45:01 69BC40 r V14 [videoguard2] IO: Sending:
2014/05/19 12:45:01 69BC40 00 7D 0B 0C 86 79 93 05 C0 DB 2C D0 00 00 90 64
2014/05/19 12:45:01 69BC40 C1 01 4C 5D 14 8D DD A4 EA A9 93 8E 25 A8 09 F8
2014/05/19 12:45:01 69BC40 F3 7D 3A 3F 53 5E 2C E5 44 B3 35 2E 50 89 5D 8C
2014/05/19 12:45:01 69BC40 9C 94 5A 88 83 4B F2 2F 6F B3 85 19 DC 64 5F 60
2014/05/19 12:45:01 69BC40 B4 BE E9 DD 75 7D 13 F0 9B 5E C7 BF 09 B8 A7 1E
2014/05/19 12:45:01 69BC40 40 EB B3 16 7F 50 04 F3 63 1A CD B7 A3 BF 20 4B
2014/05/19 12:45:01 69BC40 17 26 2C 43 F2 C2 0B 15 64 43 EA FA DD D5 BA 41
2014/05/19 12:45:01 69BC40 1B 8B 59 AC
2014/05/19 12:45:01 69BC40 r V14 [videoguard2] IFD: Transmit succesful
2014/05/19 12:45:01 69BC40 r V14 [videoguard2] IFD: Setting timeout to 57600 ETU (1190400 us)
2014/05/19 12:45:01 69BC40 r V14 [videoguard2] IFD: Receive size 1 bytes, delay 0 us, timeout=1190400 us
2014/05/19 12:45:01 69BC40 r V14 [videoguard2] IO: Read timeout 1190400 us, read delay 0 us, to read 1 char(s), chunksize 1 char(s)
2014/05/19 12:45:01 69BC40 r V14 [videoguard2] IO: Receiving:
2014/05/19 12:45:01 69BC40 90
2014/05/19 12:45:01 69BC40 r V14 [videoguard2] IFD: Receive succesful
2014/05/19 12:45:01 69BC40 r V14 [videoguard2] TRACE: SW1: 90
2014/05/19 12:45:01 69BC40 r V14 [videoguard2] IFD: Setting timeout to 57600 ETU (1190400 us)
2014/05/19 12:45:01 69BC40 r V14 [videoguard2] IFD: Receive size 1 bytes, delay 0 us, timeout=1190400 us
2014/05/19 12:45:01 69BC40 r V14 [videoguard2] IO: Read timeout 1190400 us, read delay 0 us, to read 1 char(s), chunksize 1 char(s)
2014/05/19 12:45:01 69BC40 r V14 [videoguard2] IO: Receiving:
2014/05/19 12:45:01 69BC40 00
2014/05/19 12:45:01 69BC40 r V14 [videoguard2] IFD: Receive succesful
2014/05/19 12:45:01 69BC40 r V14 [videoguard2] TRACE: SW2: 00
2014/05/19 12:45:01 69BC40 r V14 [videoguard2] Answer from cardreader:
2014/05/19 12:45:01 69BC40 90 00
2014/05/19 12:45:01 69BC40 r V14 [videoguard2] write to cardreader
2014/05/19 12:45:01 69BC40 D3 54 00 00 5E
2014/05/19 12:45:01 69BC40 r V14 [videoguard2] IFD: Setting timeout to 14 ETU (289 us)
2014/05/19 12:45:01 69BC40 r V14 [videoguard2] IFD: Transmit size 5 bytes, expected len 97 bytes, delay 0 us, timeout=289 us
2014/05/19 12:45:01 69BC40 r V14 [videoguard2] IFD: Transmit:
2014/05/19 12:45:01 69BC40 D3 54 00 00 5E
2014/05/19 12:45:01 69BC40 r V14 [videoguard2] IO: Write timeout 289 us, write delay 0 us, to send 5 char(s), chunksize 5 char(s)
2014/05/19 12:45:01 69BC40 r V14 [videoguard2] IO: Sending:
2014/05/19 12:45:01 69BC40 D3 54 00 00 5E
2014/05/19 12:45:01 69BC40 r V14 [videoguard2] IFD: Transmit succesful
2014/05/19 12:45:01 69BC40 r V14 [videoguard2] IFD: Setting timeout to 57600 ETU (1190400 us)
2014/05/19 12:45:01 69BC40 r V14 [videoguard2] IFD: Receive size 1 bytes, delay 0 us, timeout=1190400 us
2014/05/19 12:45:01 69BC40 r V14 [videoguard2] IO: Read timeout 1190400 us, read delay 0 us, to read 1 char(s), chunksize 1 char(s)
2014/05/19 12:45:01 69BC40 r V14 [videoguard2] IO: Receiving:
2014/05/19 12:45:01 69BC40 54
2014/05/19 12:45:01 69BC40 r V14 [videoguard2] IFD: Receive succesful
2014/05/19 12:45:01 69BC40 r V14 [videoguard2] IFD: Setting timeout to 57600 ETU (1190400 us)
2014/05/19 12:45:01 69BC40 r V14 [videoguard2] IFD: Receive size 94 bytes, delay 0 us, timeout=1190400 us
2014/05/19 12:45:01 69BC40 r V14 [videoguard2] IO: Read timeout 1190400 us, read delay 0 us, to read 94 char(s), chunksize 94 char(s)
2014/05/19 12:45:01 69BC40 r V14 [videoguard2] IO: Receiving:
2014/05/19 12:45:01 69BC40 5A 45 BB 70 66 F4 06 8A 7F F0 FA 27 29 54 44 C3
2014/05/19 12:45:01 69BC40 62 68 D5 51 83 2D 88 02 3D 7C AB CF 4D 57 58 97
2014/05/19 12:45:01 69BC40 97 5C A1 CE F1 09 36 A0 4E F5 D7 AC B6 0D 85 62
2014/05/19 12:45:01 69BC40 14 BE 0D EC D0 52 2C 0D 64 BC 5E B8 4E 76 BC 12
2014/05/19 12:45:01 69BC40 D8 8B 89 1A 24 94 D9 F2 97 17 9C 42 C1 0A CF 4F
2014/05/19 12:45:01 69BC40 22 F6 95 71 F8 F2 39 E4 3F CA 2A E5 F3 B5
2014/05/19 12:45:01 69BC40 r V14 [videoguard2] IFD: Receive succesful
2014/05/19 12:45:01 69BC40 r V14 [videoguard2] IFD: Setting timeout to 57600 ETU (1190400 us)
2014/05/19 12:45:01 69BC40 r V14 [videoguard2] IFD: Receive size 1 bytes, delay 0 us, timeout=1190400 us
2014/05/19 12:45:01 69BC40 r V14 [videoguard2] IO: Read timeout 1190400 us, read delay 0 us, to read 1 char(s), chunksize 1 char(s)
2014/05/19 12:45:01 69BC40 r V14 [videoguard2] IO: Receiving:
2014/05/19 12:45:01 69BC40 90
2014/05/19 12:45:01 69BC40 r V14 [videoguard2] IFD: Receive succesful
2014/05/19 12:45:01 69BC40 r V14 [videoguard2] TRACE: SW1: 90
2014/05/19 12:45:01 69BC40 r V14 [videoguard2] IFD: Setting timeout to 57600 ETU (1190400 us)
2014/05/19 12:45:01 69BC40 r V14 [videoguard2] IFD: Receive size 1 bytes, delay 0 us, timeout=1190400 us
2014/05/19 12:45:01 69BC40 r V14 [videoguard2] IO: Read timeout 1190400 us, read delay 0 us, to read 1 char(s), chunksize 1 char(s)
2014/05/19 12:45:01 69BC40 r V14 [videoguard2] IO: Receiving:
2014/05/19 12:45:01 69BC40 00
2014/05/19 12:45:01 69BC40 r V14 [videoguard2] IFD: Receive succesful
2014/05/19 12:45:01 69BC40 r V14 [videoguard2] TRACE: SW2: 00
2014/05/19 12:45:01 69BC40 r V14 [videoguard2] Answer from cardreader:
2014/05/19 12:45:01 69BC40 5A 45 BB 70 66 F4 06 8A 7F F0 FA 27 29 54 44 C3
2014/05/19 12:45:01 69BC40 62 68 D5 51 83 2D 88 02 3D 7C AB CF 4D 57 58 97
2014/05/19 12:45:01 69BC40 97 5C A1 CE F1 09 36 A0 4E F5 D7 AC B6 0D 85 62
2014/05/19 12:45:01 69BC40 14 BE 0D EC D0 52 2C 0D 64 BC 5E B8 4E 76 BC 12
2014/05/19 12:45:01 69BC40 D8 8B 89 1A 24 94 D9 F2 97 17 9C 42 C1 0A CF 4F
2014/05/19 12:45:01 69BC40 22 F6 95 71 F8 F2 39 E4 3F CA 2A E5 F3 B5 90 00
2014/05/19 12:45:01 69BC40 r V14 [videoguard2] INS54:
2014/05/19 12:45:01 69BC40 D3 54 00 00 4E
2014/05/19 12:45:01 69BC40 r V14 [videoguard2] Decrypted payload
2014/05/19 12:45:01 69BC40 3C 13 45 94 00 6C BF 2B 00 00 00 C9 00 01 22 02
2014/05/19 12:45:01 69BC40 00 00 0E 02 03 00 0F 06 00 00 00 00 00 03 20 04
2014/05/19 12:45:01 69BC40 00 00 00 00 25 11 00 00 00 00 00 00 00 00 00 00
2014/05/19 12:45:01 69BC40 00 00 00 00 00 00 00 2A 04 00 C9 00 00 55 01 28
2014/05/19 12:45:01 69BC40 56 08 00 00 00 00 00 00 00 00 2B 02 00 00
2014/05/19 12:45:01 69BC40 r V14 [videoguard2] cardreader_do_ecm: after csystem.do_ecm rc=1
2014/05/19 12:45:01 69BC40 r V14 [videoguard2] cardreader_do_ecm: ret rc=1
2014/05/19 12:45:01 69BC40 r V14 [videoguard2] cardreader_process_ecm: cardreader_do_ecm returned rc=1 (ERROR=0)
2014/05/19 12:45:01 69BC40 r {client serverbox, caid 098C, srvid 006C} [write_ecm_answer] cyclecheck passed! Reader: V14 rc: 0
2014/05/19 12:45:01 69BC40 r {client serverbox, caid 098C, prid 000000, srvid 006C} [write_ecm_answer] reader V14 rc 0, ecm time 135 ms (137 ms)
2014/05/19 12:45:01 69BC40 r start client thread action 32
2014/05/19 12:45:01 69BC40 r V14 [videoguard2] TRACE: ecm answer for ecm hash 180D5A35CC8A334D54299A27811B958F rc=0
2014/05/19 12:45:01 69BC40 r V14 [videoguard2] ecm hash: 180D5A35CC8A334D54299A27811B958F real time: 131 ms
2014/05/19 12:45:01 6BD8F8 c data from add_job action=32 client c serverbox
2014/05/19 12:45:01 6BD8F8 c {client serverbox, caid 098C, prid 000000, srvid 006C} [send_dcw] rc 0 from reader V14
2014/05/19 12:45:01 6BD8F8 c Client serverbox csphash 26F7D6FF cw 3C134594006CBF2B0000000000000000 rc 0 098C/006C/98
2014/05/19 12:45:01 6BD8F8 c [DVBAPI] Demuxer #0 controlword received for PID #3 CAID 098C PROVID 000000 ECMPID 1A0F CHID 0C20 VPID 03FF
2014/05/19 12:45:01 6BD8F8 c [DVBAPI] Demuxer #0 requested controlword for ecm 180D5A35CC8A334D54299A27811B958F on fd 15
2014/05/19 12:45:01 6BD8F8 c [DVBAPI] Demuxer #0 writing odd part (3C134594006CBF2B) of controlword, replacing expired (1F35B60A22B5F9D0)
2014/05/19 12:45:01 6BD8F8 c [DVBAPI] Demuxer #0 write cw0 index: 0 (ca3)
2014/05/19 12:45:01 6BD8F8 c ERROR: Can't open device /dev/dvb/adapter0/ca3 (errno=16 Device or resource busy)
2014/05/19 12:45:01 6BD8F8 c serverbox (098C/006C/98): found (144 ms) by V14 - Sky Sport News HD
2014/05/19 12:45:01 6BD8F8 c cw:
2014/05/19 12:45:01 6BD8F8 3C 13 45 94 00 6C BF 2B 00 00 00 00 00 00 00 00
2014/05/19 12:45:01 69D940 h WebIf: Origin checked. Result: access from 192.168.2.101 => allowed
2014/05/19 12:45:02 69BC40 r V14 [videoguard2] cardreader_do_checkhealth: reader->card_status = 2, ret = 1
2014/05/19 12:45:02 69D940 h WebIf: Origin checked. Result: access from 192.168.2.101 => allowed
2014/05/19 12:45:03 69BC40 r V14 [videoguard2] cardreader_do_checkhealth: reader->card_status = 2, ret = 1
2014/05/19 12:45:03 69D940 h WebIf: Origin checked. Result: access from 192.168.2.101 => allowed
2014/05/19 12:45:03 6BD8F8 c [DVBAPI] Demuxer #0 try to start new filter for caid: 0001, provid: 000001, pid: 0001
2014/05/19 12:45:03 6BD8F8 c DEVICE open (/dev/dvb/adapter0/demux3) fd 16
2014/05/19 12:45:03 6BD8F8 c [DVBAPI] Demuxer #0 Filter #2 started successfully (caid 0001 provid 000001 pid 0001)
2014/05/19 12:45:04 69BC40 r V14 [videoguard2] cardreader_do_checkhealth: reader->card_status = 2, ret = 1
2014/05/19 12:45:04 6BD8F8 c [DVBAPI] Demuxer #0 has 4 ecmpids, 3 streampids, 1 ecmfilters and 1 emmfilters
2014/05/19 12:45:04 6BD8F8 c [DVBAPI] new events occurred on 1 of 4 handlers after 3153 ms inactivity
2014/05/19 12:45:04 6BD8F8 c [DVBAPI] now handling fd 16 that reported event 3
2014/05/19 12:45:04 6BD8F8 c [DVBAPI] Readed:
2014/05/19 12:45:04 6BD8F8 01 B0 21 FF FF E1 00 00 09 04 18 33 F0 02 09 04
2014/05/19 12:45:04 6BD8F8 09 C4 F0 08 09 04 09 AF F0 09 09 04 09 8C F0 08
2014/05/19 12:45:04 6BD8F8 94 49 D3 6A
2014/05/19 12:45:04 6BD8F8 c receiving cat
2014/05/19 12:45:04 6BD8F8 c cat:
2014/05/19 12:45:04 6BD8F8 01 B0 21 FF FF E1 00 00 09 04 18 33 F0 02 09 04
2014/05/19 12:45:04 6BD8F8 09 C4 F0 08 09 04 09 AF F0 09 09 04 09 8C F0 08
2014/05/19 12:45:04 6BD8F8 94 49 D3 6A
2014/05/19 12:45:04 6BD8F8 c Reader V14 au enabled -> parsing cat for emm pids!
2014/05/19 12:45:04 6BD8F8 c V14 [videoguard2] EMM: reader_caid 098C != caid 1833
2014/05/19 12:45:04 6BD8F8 c [IGNORE EMMPID] CAID: 1833 EMM_PID: 1002 PROVID: 000000 TYPE :UNIQUE:SHARED:GLOBAL: (no match)
2014/05/19 12:45:04 6BD8F8 c V14 [videoguard2] EMM: reader_caid 098C != caid 09C4
2014/05/19 12:45:04 6BD8F8 c [IGNORE EMMPID] CAID: 09C4 EMM_PID: 1008 PROVID: 000000 TYPE :UNIQUE:SHARED:GLOBAL: (no match)
2014/05/19 12:45:04 6BD8F8 c V14 [videoguard2] EMM: reader_caid 098C != caid 09AF
2014/05/19 12:45:04 6BD8F8 c [IGNORE EMMPID] CAID: 09AF EMM_PID: 1009 PROVID: 000000 TYPE :UNIQUE:SHARED:GLOBAL: (no match)
2014/05/19 12:45:04 6BD8F8 c V14 [videoguard2] EMM: caid 098C has no provider
2014/05/19 12:45:04 6BD8F8 c [ADD EMMPID #0] CAID: 098C EMM_PID: 1008 PROVID: 000000 TYPE :UNIQUE:SHARED:GLOBAL:
2014/05/19 12:45:04 6BD8F8 c [DVBAPI] Demuxer #0 stop Filter #2 (fd: 16 api: 0, caid: 0001, provid: 000001, emmpid: 0001)
2014/05/19 12:45:04 6BD8F8 c [DVBAPI] Demuxer #0 has 4 ecmpids, 3 streampids, 1 ecmfilters and 0 emmfilters
2014/05/19 12:45:04 6BD8F8 c [EMM Filter] starting emm filter type UNIQUE, pid: 0x1008
2014/05/19 12:45:04 6BD8F8 82 40 00 88 56 1C 00 00 00 00 00 00 00 00 00 00
2014/05/19 12:45:04 6BD8F8 FF C0 FF FF FF FF 00 00 00 00 00 00 00 00 00 00
2014/05/19 12:45:04 6BD8F8 c DEVICE open (/dev/dvb/adapter0/demux3) fd 16
2014/05/19 12:45:04 6BD8F8 c [DVBAPI] Demuxer #0 Filter #2 started successfully (caid 098C provid 000000 pid 1008)
2014/05/19 12:45:04 6BD8F8 c [DVBAPI] Demuxer #0 Filter #2 added to active emmfilters (CAID 098C PROVID 000000 EMMPID 1008)
2014/05/19 12:45:04 6BD8F8 c [EMM Filter] starting emm filter type UNIQUE, pid: 0x1008
2014/05/19 12:45:04 6BD8F8 82 40 00 00 00 00 00 88 56 1C 00 00 00 00 00 00
2014/05/19 12:45:04 6BD8F8 FF C0 00 00 00 00 FF FF FF FF 00 00 00 00 00 00
2014/05/19 12:45:04 6BD8F8 c DEVICE open (/dev/dvb/adapter0/demux3) fd 17
2014/05/19 12:45:04 6BD8F8 c [DVBAPI] Demuxer #0 Filter #3 started successfully (caid 098C provid 000000 pid 1008)
2014/05/19 12:45:04 6BD8F8 c [DVBAPI] Demuxer #0 Filter #3 added to active emmfilters (CAID 098C PROVID 000000 EMMPID 1008)
2014/05/19 12:45:04 6BD8F8 c [EMM Filter] starting emm filter type UNIQUE, pid: 0x1008
2014/05/19 12:45:04 6BD8F8 82 40 00 00 00 00 00 00 00 00 00 88 56 1C 00 00
2014/05/19 12:45:04 6BD8F8 FF C0 00 00 00 00 00 00 00 00 FF FF FF FF 00 00
2014/05/19 12:45:04 6BD8F8 c DEVICE open (/dev/dvb/adapter0/demux3) fd 18
2014/05/19 12:45:04 6BD8F8 c [DVBAPI] Demuxer #0 Filter #4 started successfully (caid 098C provid 000000 pid 1008)
2014/05/19 12:45:04 6BD8F8 c [DVBAPI] Demuxer #0 Filter #4 added to active emmfilters (CAID 098C PROVID 000000 EMMPID 1008)
2014/05/19 12:45:04 6BD8F8 c [EMM Filter] 3 matching emm filter found
2014/05/19 12:45:04 6BD8F8 c [DVBAPI] Demuxer #0 has 4 ecmpids, 3 streampids, 1 ecmfilters and 3 emmfilters
2014/05/19 12:45:04 69D940 h WebIf: Received stale header from 192.168.2.101 (nonce=149137189713adf3d0a4cddc63ad01a8, expectednonce=e64b4cb20d305971f00cbb0f37fbdbc9, opaque=2e9b7bd3f76489d5dd8c69f6a6db0aac).
2014/05/19 12:45:04 69D940 h WebIf: Origin checked. Result: access from 192.168.2.101 => allowed
2014/05/19 12:45:05 69BC40 r V14 [videoguard2] cardreader_do_checkhealth: reader->card_status = 2, ret = 1
2014/05/19 12:45:05 69D940 h WebIf: Origin checked. Result: access from 192.168.2.101 => allowed
2014/05/19 12:45:06 69BC40 r V14 [videoguard2] cardreader_do_checkhealth: reader->card_status = 2, ret = 1
2014/05/19 12:45:06 0 start client thread action 31
2014/05/19 12:45:06 6BD8F8 c data from add_job action=31 client c serverbox
2014/05/19 12:45:06 69D940 h WebIf: Origin checked. Result: access from 192.168.2.101 => allowed
2014/05/19 12:45:07 69BC40 r V14 [videoguard2] cardreader_do_checkhealth: reader->card_status = 2, ret = 1