vdr: [LOG768]2013/10/04 18:29:00  148DEC0 c [DVBAPI] Receiver wants to demux srvid 0834 on adapter 0002 camask 0004 index 0000
vdr: [LOG769]2013/10/04 18:29:00  148DEC0 c New program number: 0834 (090F:0834 unknown) [pmt_list_management 3]
vdr: [LOG770]2013/10/04 18:29:00  148DEC0 c [DVBAPI] Demuxer #0 demux_index:  0 ca_mask: 04 program_info_length:   5 ca_pmt_list_management 03
vdr: [LOG771]2013/10/04 18:29:00  148DEC0 c [DVBAPI] Demuxer #0 no prio rules in oscam.dvbapi matches!
vdr: [LOG772]2013/10/04 18:29:00  148DEC0 c caid 090F not found in caidlist reader Viasat_Nordic_5E_(fin+dk)
vdr: [LOG773]2013/10/04 18:29:00  148DEC0 c [IGNORE PID 0] 090F:000000:083A (no matching reader)
vdr: [LOG774]2013/10/04 18:29:00  148DEC0 c trying reader 'Viasat_Nordic_5E_(fin+dk)' filter 093E:000000
vdr: [LOG775]2013/10/04 18:29:00  148DEC0 c 093E:000000 allowed by reader 'Viasat_Nordic_5E_(fin+dk)' filter 093E:000000
vdr: [LOG776]2013/10/04 18:29:00  148DEC0 c [PRIORITIZE PID 1] 093E:000000:0099 (rdr: Viasat_Nordic_5E_(fin+dk) weight: 1)
vdr: [LOG777]2013/10/04 18:29:00  148DEC0 c trying reader 'Viasat_Nordic_5E_(fin+dk)' filter 093E:000000
vdr: [LOG778]2013/10/04 18:29:00  148DEC0 c 093E:000000 allowed by reader 'Viasat_Nordic_5E_(fin+dk)' filter 093E:000000
vdr: [LOG779]2013/10/04 18:29:00  148DEC0 c [DVBAPI] Demuxer #0 trying to descramble PID #1 CAID 093E PROVID 000000 ECMPID 0099 ANY CHID VPID 0835
vdr: [LOG780]2013/10/04 18:29:00  148DEC0 c [DVBAPI] Demuxer #0 try to start new filter for caid: 093E, provid: 000000, pid: 0099
vdr: [LOG781]2013/10/04 18:29:00  148DEC0 c DEVICE open (/dev/dvb/adapter2/demux0) fd 14
vdr: [LOG782]2013/10/04 18:29:00  148DEC0 c [DVBAPI] Demuxer #0 Filter #1 started succesfully (caid 093E provid 000000 pid 0099)
vdr: [LOG783]2013/10/04 18:29:00  148DEC0 c [DVBAPI] Demuxer #0 has 2 ecmpids, 3 streampids, 1 ecmfilters and 0 emmfilters
vdr: [LOG784]2013/10/04 18:29:00  148DEC0 c [DVBAPI] new events occurred on 1 of 3 handlers after 55 ms inactivity
vdr: [LOG785]2013/10/04 18:29:00  148DEC0 c [DVBAPI] now handling fd 14 that reported event 3
vdr: [LOG786]2013/10/04 18:29:00  148DEC0 c [DVBAPI] Readed:
vdr: [LOG787]2013/10/04 18:29:00  148DEC0    80 70 A7 00 00 01 11 A5 04 7B 9C 00 AC AA 55 15
vdr: [LOG788]2013/10/04 18:29:00  148DEC0    21 80 01 00 01 03 00 38 91 7E 0A 2F 7C 47 29 C5
vdr: [LOG789]2013/10/04 18:29:00  148DEC0    97 C7 BA 00 00 47 08 23 56 07 B8 08 28 4D BC 90
vdr: [LOG790]2013/10/04 18:29:00  148DEC0    79 C0 01 3B 3D B9 75 0D 09 E5 07 CC C0 F9 8E 74
vdr: [LOG791]2013/10/04 18:29:00  148DEC0    E9 9F 97 DF 61 38 88 C6 40 A9 76 DE B3 E0 B1 DB
vdr: [LOG792]2013/10/04 18:29:00  148DEC0    DA 61 2E 17 17 B6 5B E4 91 68 A7 9A A4 8C CB 96
vdr: [LOG793]2013/10/04 18:29:00  148DEC0    69 CE C1 3F 90 9F 03 49 92 96 26 F4 9A 5B B1 D0
vdr: [LOG794]2013/10/04 18:29:00  148DEC0    4E 5A A1 22 05 43 2D 59 B2 8B A8 C8 AF EB 44 1E
vdr: [LOG795]2013/10/04 18:29:00  148DEC0    C0 B8 12 56 25 10 2C AC A3 A5 AE 00 4F 5C 72 1A
vdr: [LOG796]2013/10/04 18:29:00  148DEC0    FA 3B 3B 6E 16 C0 93 54 3F 76 47 BB A1 6D 78 DB
vdr: [LOG797]2013/10/04 18:29:00  148DEC0    C0 93 8B C4 65 4B D1 79 D1 25
vdr: [LOG798]2013/10/04 18:29:00  148DEC0 c [DVBAPI] Demuxer #0 ECMTYPE 80 CAID 093E PROVID 000000 ECMPID 0099 FAKECHID 00AC (unique part in ecm)
vdr: [LOG799]2013/10/04 18:29:00  148DEC0 c [DVBAPI] Demuxer #0 Filter #1 set ecmtable to ODD (CAID 093E PROVID 000000 FD 14)
vdr: [LOG800]2013/10/04 18:29:00  148DEC0 c [DVBAPI] Demuxer #0 Filter #1 set chid to ANY CHID on fd 14
vdr: [LOG801]2013/10/04 18:29:00  148DEC0 c trying reader 'Viasat_Nordic_5E_(fin+dk)' filter 093E:000000
vdr: [LOG802]2013/10/04 18:29:00  148DEC0 c 093E:000000 allowed by reader 'Viasat_Nordic_5E_(fin+dk)' filter 093E:000000
vdr: [LOG803]2013/10/04 18:29:00  148DEC0 c [GET_CW] wait_time 0 caid 093E prov 000000 srvid 0834 rc 100 cacheex cl mode 0 ex1rdr 0
vdr: [LOG804]2013/10/04 18:29:00  148DEC0 c request_cw stage=3 to reader Viasat_Nordic_5E_(fin+dk) ecm hash=BCFAD5965A19354C9EE6DA694036A8AA
vdr: [LOG805]2013/10/04 18:29:00  148DEC0 c add reader job action 5 queue length 1 Viasat_Nordic_5E_(fin+dk)
vdr: [LOG806]2013/10/04 18:29:00  148DEC0 c [DVBAPI] Demuxer #0 request cw for ecm 093E&000000/00AC/0834/AA:BCFAD5965A19354C9EE6DA694036A8AA
vdr: [LOG807]2013/10/04 18:29:00 28002C50 p ---- ecm_task 0, idx 12, sflag=1
vdr: [LOG808]2013/10/04 18:29:00 28002C50 p casc ecm (Viasat_Nordic_5E_(fin+dk)):
vdr: [LOG809]2013/10/04 18:29:00 28002C50    80 70 A7 00 00 01 11 A5 04 7B 9C 00 AC AA 55 15
vdr: [LOG810]2013/10/04 18:29:00 28002C50    21 80 01 00 01 03 00 38 91 7E 0A 2F 7C 47 29 C5
vdr: [LOG811]2013/10/04 18:29:00 28002C50    97 C7 BA 00 00 47 08 23 56 07 B8 08 28 4D BC 90
vdr: [LOG812]2013/10/04 18:29:00 28002C50    79 C0 01 3B 3D B9 75 0D 09 E5 07 CC C0 F9 8E 74
vdr: [LOG813]2013/10/04 18:29:00 28002C50    E9 9F 97 DF 61 38 88 C6 40 A9 76 DE B3 E0 B1 DB
vdr: [LOG814]2013/10/04 18:29:00 28002C50    DA 61 2E 17 17 B6 5B E4 91 68 A7 9A A4 8C CB 96
vdr: [LOG815]2013/10/04 18:29:00 28002C50    69 CE C1 3F 90 9F 03 49 92 96 26 F4 9A 5B B1 D0
vdr: [LOG816]2013/10/04 18:29:00 28002C50    4E 5A A1 22 05 43 2D 59 B2 8B A8 C8 AF EB 44 1E
vdr: [LOG817]2013/10/04 18:29:00 28002C50    C0 B8 12 56 25 10 2C AC A3 A5 AE 00 4F 5C 72 1A
vdr: [LOG818]2013/10/04 18:29:00 28002C50    FA 3B 3B 6E 16 C0 93 54 3F 76 47 BB A1 6D 78 DB
vdr: [LOG819]2013/10/04 18:29:00 28002C50    C0 93 8B C4 65 4B D1 79 D1 25
vdr: [LOG820]2013/10/04 18:29:00 28002C50 p trying server 'server IP.port' filter 093E:000000
vdr: [LOG821]2013/10/04 18:29:00 28002C50 p 093E:000000 allowed by server 'server IP.port' filter 093E:000000
vdr: [LOG822]2013/10/04 18:29:00 28002C50 p send 182 bytes to remote server
vdr: [LOG823]2013/10/04 18:29:00 28002C50    00 B4 00 0C 08 34 00 00 00 00 00 00 80 70 A7 00
vdr: [LOG824]2013/10/04 18:29:00 28002C50    00 01 11 A5 04 7B 9C 00 AC AA 55 15 21 80 01 00
vdr: [LOG825]2013/10/04 18:29:00 28002C50    01 03 00 38 91 7E 0A 2F 7C 47 29 C5 97 C7 BA 00
vdr: [LOG826]2013/10/04 18:29:00 28002C50    00 47 08 23 56 07 B8 08 28 4D BC 90 79 C0 01 3B
vdr: [LOG827]2013/10/04 18:29:00 28002C50    3D B9 75 0D 09 E5 07 CC C0 F9 8E 74 E9 9F 97 DF
vdr: [LOG828]2013/10/04 18:29:00 28002C50    61 38 88 C6 40 A9 76 DE B3 E0 B1 DB DA 61 2E 17
vdr: [LOG829]2013/10/04 18:29:00 28002C50    17 B6 5B E4 91 68 A7 9A A4 8C CB 96 69 CE C1 3F
vdr: [LOG830]2013/10/04 18:29:00 28002C50    90 9F 03 49 92 96 26 F4 9A 5B B1 D0 4E 5A A1 22
vdr: [LOG831]2013/10/04 18:29:00 28002C50    05 43 2D 59 B2 8B A8 C8 AF EB 44 1E C0 B8 12 56
vdr: [LOG832]2013/10/04 18:29:00 28002C50    25 10 2C AC A3 A5 AE 00 4F 5C 72 1A FA 3B 3B 6E
vdr: [LOG833]2013/10/04 18:29:00 28002C50    16 C0 93 54 3F 76 47 BB A1 6D 78 DB C0 93 8B C4
vdr: [LOG835]2013/10/04 18:29:00        0   >> OSCam <<  cardserver log switched, version 1.20-unstable_svn, build r0 (x86_64-linux-gnu)
vdr: [LOG836]2013/10/04 18:29:00        0 s [OSCAM] new event 1 occurred on fd 6 after 1013 ms inactivity
vdr: [LOG837]2013/10/04 18:29:00        0 s got 21 bytes on port XXXX from ip X.X.X.X:XXXX client MYUSERNAME
vdr: [LOG838]2013/10/04 18:29:00        0 s start client thread action 22
vdr: [LOG839]2013/10/04 18:29:00  14A8020 m data from add_job action=22 client m MYUSERNAME
vdr: [LOG840]2013/10/04 18:29:01        0 s [OSCAM] new event 1 occurred on fd 6 after 1015 ms inactivity
vdr: [LOG841]2013/10/04 18:29:01        0 s got 21 bytes on port XXX from ip X.X.X.X:XXXX client MYUSERNAME
vdr: [LOG842]2013/10/04 18:29:01        0 s start client thread action 22
vdr: [LOG843]2013/10/04 18:29:01  14A8020 m data from add_job action=22 client m MYUSERNAME
vdr: [LOG844]2013/10/04 18:29:02        0 s [OSCAM] new event 1 occurred on fd 6 after 1013 ms inactivity
vdr: [LOG845]2013/10/04 18:29:02        0 s got 21 bytes on port XXXX from ip X.X.X.X:XXXX client MYUSERNAME
vdr: [LOG846]2013/10/04 18:29:02        0 s start client thread action 22
vdr: [LOG847]2013/10/04 18:29:02  14A8020 m data from add_job action=22 client m MYUSERNAME
vdr: [LOG848]2013/10/04 18:29:02        0   start client thread action 30
vdr: [LOG849]2013/10/04 18:29:02  148DEC0 c data from add_job action=30 client c anonymous
vdr: [LOG850]2013/10/04 18:29:02  148DEC0 c fallback for anonymous 093E&000000/00AC/0834/AA:BCFAD5965A19354C9EE6DA694036A8AA
vdr: [LOG851]2013/10/04 18:29:03        0   start client thread action 31
vdr: [LOG852]2013/10/04 18:29:03  148DEC0 c data from add_job action=31 client c anonymous
vdr: [LOG853]2013/10/04 18:29:03        0 s [OSCAM] new event 1 occurred on fd 6 after 1014 ms inactivity
vdr: [LOG854]2013/10/04 18:29:03        0 s got 21 bytes on port XXX from ip X.X.X.X:XXXX client MYUSERNAME
vdr: [LOG855]2013/10/04 18:29:03        0 s start client thread action 22
vdr: [LOG856]2013/10/04 18:29:03  14A8020 m data from add_job action=22 client m MYUSERNAME
vdr: [LOG857]2013/10/04 18:29:04        0 s [OSCAM] new event 1 occurred on fd 6 after 1014 ms inactivity
vdr: [LOG858]2013/10/04 18:29:04        0 s got 21 bytes on port XXX from ip X.X.X.X:XXXX client MYUSERNAME
vdr: [LOG859]2013/10/04 18:29:04        0 s start client thread action 22
vdr: [LOG860]2013/10/04 18:29:04  14A8020 m data from add_job action=22 client m MYUSERNAME
vdr: [LOG861]2013/10/04 18:29:05        0   start client thread action 31
vdr: [LOG862]2013/10/04 18:29:05  148DEC0 c data from add_job action=31 client c anonymous
vdr: [LOG863]2013/10/04 18:29:05  148DEC0 c timeout for anonymous 093E&000000/00AC/0834/AA:BCFAD5965A19354C9EE6DA694036A8AA
vdr: [LOG864]2013/10/04 18:29:05  148DEC0 c add client job action 32 queue length 1 anonymous
vdr: [LOG865]2013/10/04 18:29:05  148DEC0 c Viasat_Nordic_5E_(fin+dk) [newcamd] TRACE: ecm answer for ecm hash BCFAD5965A19354C9EE6DA694036A8AA rc=5
vdr: [LOG866]2013/10/04 18:29:05  148DEC0 c [DVBAPI] Demuxer #0 no controlword received for PID #1 CAID 093E PROVID 000000 ECMPID 0099 CHID 00AC VPID 0835
vdr: [LOG867]2013/10/04 18:29:05  148DEC0 c [DVBAPI] Demuxer #0 Filter #1 set ecmtable to ODD+EVEN (CAID 093E PROVID 000000 FD 14)
vdr: [LOG868]2013/10/04 18:29:05  148DEC0 c [DVBAPI] Demuxer #0 Filter #1 set chid to ANY CHID on fd 14
vdr: [LOG869]2013/10/04 18:29:05  148DEC0 c anonymous (093E&000000/00AC/0834/AA:BCFAD5965A19354C9EE6DA694036A8AA): timeout (5000 ms) by Viasat_Nordic_5E_(fin+dk)
vdr: [LOG870]2013/10/04 18:29:05  148DEC0 c cw:
vdr: [LOG871]2013/10/04 18:29:05  148DEC0    00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
vdr: [LOG872]2013/10/04 18:29:05  148DEC0 c [DVBAPI] new events occurred on 1 of 3 handlers after 5099 ms inactivity
vdr: [LOG873]2013/10/04 18:29:05  148DEC0 c [DVBAPI] now handling fd 14 that reported event 3
vdr: [LOG874]2013/10/04 18:29:05  148DEC0 c [DVBAPI] Readed:
vdr: [LOG875]2013/10/04 18:29:05  148DEC0    80 70 A7 00 00 01 11 A5 04 7B 9C 00 AC AA 55 15
vdr: [LOG876]2013/10/04 18:29:05  148DEC0    21 80 01 00 01 03 00 38 91 7E 0A 2F 7C 47 29 C5
vdr: [LOG877]2013/10/04 18:29:05  148DEC0    97 C7 BA 00 00 47 08 23 56 07 B8 08 28 4D BC 90
vdr: [LOG878]2013/10/04 18:29:05  148DEC0    79 C0 01 3B 3D B9 75 0D 09 E5 07 CC C0 F9 8E 74
vdr: [LOG879]2013/10/04 18:29:05  148DEC0    E9 9F 97 DF 61 38 88 C6 40 A9 76 DE B3 E0 B1 DB
vdr: [LOG880]2013/10/04 18:29:05  148DEC0    DA 61 2E 17 17 B6 5B E4 91 68 A7 9A A4 8C CB 96
vdr: [LOG881]2013/10/04 18:29:05  148DEC0    69 CE C1 3F 90 9F 03 49 92 96 26 F4 9A 5B B1 D0
vdr: [LOG882]2013/10/04 18:29:05  148DEC0    4E 5A A1 22 05 43 2D 59 B2 8B A8 C8 AF EB 44 1E
vdr: [LOG883]2013/10/04 18:29:05  148DEC0    C0 B8 12 56 25 10 2C AC A3 A5 AE 00 4F 5C 72 1A
vdr: [LOG884]2013/10/04 18:29:05  148DEC0    FA 3B 3B 6E 16 C0 93 54 3F 76 47 BB A1 6D 78 DB
vdr: [LOG885]2013/10/04 18:29:05  148DEC0    C0 93 8B C4 65 4B D1 79 D1 25
vdr: [LOG886]2013/10/04 18:29:05  148DEC0 c [DVBAPI] Demuxer #0 ECMTYPE 80 CAID 093E PROVID 000000 ECMPID 0099 FAKECHID 00AC (unique part in ecm)
vdr: [LOG887]2013/10/04 18:29:05  148DEC0 c [DVBAPI] Demuxer #0 Filter #1 set ecmtable to ODD (CAID 093E PROVID 000000 FD 14)
vdr: [LOG888]2013/10/04 18:29:05  148DEC0 c [DVBAPI] Demuxer #0 Filter #1 set chid to ANY CHID on fd 14
vdr: [LOG889]2013/10/04 18:29:05  148DEC0 c trying reader 'Viasat_Nordic_5E_(fin+dk)' filter 093E:000000
vdr: [LOG890]2013/10/04 18:29:05  148DEC0 c 093E:000000 allowed by reader 'Viasat_Nordic_5E_(fin+dk)' filter 093E:000000
vdr: [LOG891]2013/10/04 18:29:05  148DEC0 c [GET_CW] wait_time 0 caid 093E prov 000000 srvid 0834 rc 100 cacheex cl mode 0 ex1rdr 0
vdr: [LOG892]2013/10/04 18:29:05  148DEC0 c request_cw stage=3 to reader Viasat_Nordic_5E_(fin+dk) ecm hash=BCFAD5965A19354C9EE6DA694036A8AA
vdr: [LOG893]2013/10/04 18:29:05  148DEC0 c add reader job action 5 queue length 1 Viasat_Nordic_5E_(fin+dk)
vdr: [LOG894]2013/10/04 18:29:05  148DEC0 c [DVBAPI] Demuxer #0 request cw for ecm 093E&000000/00AC/0834/AA:BCFAD5965A19354C9EE6DA694036A8AA
vdr: [LOG895]2013/10/04 18:29:05 28002C50 p start client thread action 32
vdr: [LOG896]2013/10/04 18:29:05  148DEC0 c data from add_job action=32 client c anonymous
vdr: [LOG897]2013/10/04 18:29:05  148DEC0 c [DVBAPI] Demuxer #0 no controlword received for PID #1 CAID 093E PROVID 000000 ECMPID 0099 CHID 00AC VPID 0835
vdr: [LOG898]2013/10/04 18:29:05  148DEC0 c [DVBAPI] Demuxer #0 stop Filter #1 (fd: 14 api: 0, caid: 093E, provid: 000000, ecmpid: 0099)
vdr: [LOG899]2013/10/04 18:29:05  148DEC0 c anonymous (093E&000000/00AC/0834/AA:BCFAD5965A19354C9EE6DA694036A8AA): timeout (1 ms) by Viasat_Nordic_5E_(fin+dk)
vdr: [LOG900]2013/10/04 18:29:05  148DEC0 c cw:
vdr: [LOG901]2013/10/04 18:29:05  148DEC0    00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
vdr: [LOG902]2013/10/04 18:29:05  148DEC0 c [DVBAPI] new events occurred on 1 of 3 handlers after 301 ms inactivity
vdr: [LOG903]2013/10/04 18:29:05  148DEC0 c [DVBAPI] now handling fd 14 that reported event 32
vdr: [LOG904]2013/10/04 18:29:05  148DEC0 c [DVBAPI] Demuxer #0 has 2 ecmpids, 3 streampids, 0 ecmfilters and 0 emmfilters
vdr: [LOG905]2013/10/04 18:29:05  148DEC0 c [DVBAPI] Demuxer #0 (re)starting decodingrequests on all 2 ecmpids!
vdr: [LOG906]2013/10/04 18:29:05        0 s [OSCAM] new event 1 occurred on fd 6 after 1014 ms inactivity
vdr: [LOG907]2013/10/04 18:29:05        0 s got 21 bytes on port 988 from ip 192.168.0.4:44013 client MYUSERNAME
vdr: [LOG908]2013/10/04 18:29:05        0 s start client thread action 22
vdr: [LOG909]2013/10/04 18:29:05  14A8020 m data from add_job action=22 client m MYUSERNAME
vdr: [LOG910]2013/10/04 18:29:06  148DEC0 c [DVBAPI] Demuxer #0 no prio rules in oscam.dvbapi matches!
vdr: [LOG911]2013/10/04 18:29:06  148DEC0 c caid 090F not found in caidlist reader Viasat_Nordic_5E_(fin+dk)
vdr: [LOG912]2013/10/04 18:29:06  148DEC0 c [IGNORE PID 0] 090F:000000:083A (no matching reader)
vdr: [LOG913]2013/10/04 18:29:06  148DEC0 c trying reader 'Viasat_Nordic_5E_(fin+dk)' filter 093E:000000
vdr: [LOG914]2013/10/04 18:29:06  148DEC0 c 093E:000000 allowed by reader 'Viasat_Nordic_5E_(fin+dk)' filter 093E:000000
vdr: [LOG915]2013/10/04 18:29:06  148DEC0 c [PRIORITIZE PID 1] 093E:000000:0099 (rdr: Viasat_Nordic_5E_(fin+dk) weight: 1)
vdr: [LOG916]2013/10/04 18:29:06  148DEC0 c trying reader 'Viasat_Nordic_5E_(fin+dk)' filter 093E:000000
vdr: [LOG917]2013/10/04 18:29:06  148DEC0 c 093E:000000 allowed by reader 'Viasat_Nordic_5E_(fin+dk)' filter 093E:000000
vdr: [LOG918]2013/10/04 18:29:06  148DEC0 c [DVBAPI] Demuxer #0 trying to descramble PID #1 CAID 093E PROVID 000000 ECMPID 0099 ANY CHID VPID 0835
vdr: [LOG919]2013/10/04 18:29:06  148DEC0 c [DVBAPI] Demuxer #0 try to start new filter for caid: 093E, provid: 000000, pid: 0099
vdr: [LOG920]2013/10/04 18:29:06  148DEC0 c DEVICE open (/dev/dvb/adapter2/demux0) fd 14
vdr: [LOG921]2013/10/04 18:29:06  148DEC0 c [DVBAPI] Demuxer #0 Filter #1 started succesfully (caid 093E provid 000000 pid 0099)
vdr: [LOG922]2013/10/04 18:29:06  148DEC0 c [DVBAPI] Demuxer #0 has 2 ecmpids, 3 streampids, 1 ecmfilters and 0 emmfilters
vdr: [LOG923]2013/10/04 18:29:06  148DEC0 c [DVBAPI] new events occurred on 1 of 3 handlers after 600 ms inactivity
vdr: [LOG924]2013/10/04 18:29:06  148DEC0 c [DVBAPI] now handling fd 14 that reported event 3
vdr: [LOG925]2013/10/04 18:29:06  148DEC0 c [DVBAPI] Readed:
vdr: [LOG926]2013/10/04 18:29:06  148DEC0    80 70 A7 00 00 01 11 A5 04 7B 9C 00 AC AA 55 15
vdr: [LOG927]2013/10/04 18:29:06  148DEC0    21 80 01 00 01 03 00 38 91 7E 0A 2F 7C 47 29 C5
vdr: [LOG928]2013/10/04 18:29:06  148DEC0    97 C7 BA 00 00 47 08 23 56 07 B8 08 28 4D BC 90
vdr: [LOG929]2013/10/04 18:29:06  148DEC0    79 C0 01 3B 3D B9 75 0D 09 E5 07 CC C0 F9 8E 74
vdr: [LOG930]2013/10/04 18:29:06  148DEC0    E9 9F 97 DF 61 38 88 C6 40 A9 76 DE B3 E0 B1 DB
vdr: [LOG931]2013/10/04 18:29:06  148DEC0    DA 61 2E 17 17 B6 5B E4 91 68 A7 9A A4 8C CB 96
vdr: [LOG932]2013/10/04 18:29:06  148DEC0    69 CE C1 3F 90 9F 03 49 92 96 26 F4 9A 5B B1 D0
vdr: [LOG933]2013/10/04 18:29:06  148DEC0    4E 5A A1 22 05 43 2D 59 B2 8B A8 C8 AF EB 44 1E
vdr: [LOG934]2013/10/04 18:29:06  148DEC0    C0 B8 12 56 25 10 2C AC A3 A5 AE 00 4F 5C 72 1A
vdr: [LOG935]2013/10/04 18:29:06  148DEC0    FA 3B 3B 6E 16 C0 93 54 3F 76 47 BB A1 6D 78 DB
vdr: [LOG936]2013/10/04 18:29:06  148DEC0    C0 93 8B C4 65 4B D1 79 D1 25
vdr: [LOG937]2013/10/04 18:29:06  148DEC0 c [DVBAPI] Demuxer #0 ECMTYPE 80 CAID 093E PROVID 000000 ECMPID 0099 FAKECHID 00AC (unique part in ecm)
vdr: [LOG938]2013/10/04 18:29:06  148DEC0 c [DVBAPI] Demuxer #0 Filter #1 set ecmtable to ODD (CAID 093E PROVID 000000 FD 14)
vdr: [LOG939]2013/10/04 18:29:06  148DEC0 c [DVBAPI] Demuxer #0 Filter #1 set chid to ANY CHID on fd 14
vdr: [LOG940]2013/10/04 18:29:06  148DEC0 c trying reader 'Viasat_Nordic_5E_(fin+dk)' filter 093E:000000
vdr: [LOG941]2013/10/04 18:29:06  148DEC0 c 093E:000000 allowed by reader 'Viasat_Nordic_5E_(fin+dk)' filter 093E:000000
vdr: [LOG942]2013/10/04 18:29:06  148DEC0 c [GET_CW] wait_time 0 caid 093E prov 000000 srvid 0834 rc 100 cacheex cl mode 0 ex1rdr 0
vdr: [LOG943]2013/10/04 18:29:06  148DEC0 c request_cw stage=3 to reader Viasat_Nordic_5E_(fin+dk) ecm hash=BCFAD5965A19354C9EE6DA694036A8AA
vdr: [LOG944]2013/10/04 18:29:06  148DEC0 c add reader job action 5 queue length 1 Viasat_Nordic_5E_(fin+dk)
vdr: [LOG945]2013/10/04 18:29:06  148DEC0 c [DVBAPI] Demuxer #0 request cw for ecm 093E&000000/00AC/0834/AA:BCFAD5965A19354C9EE6DA694036A8AA
vdr: [LOG946]2013/10/04 18:29:06 28002C50 p start client thread action 32
vdr: [LOG947]2013/10/04 18:29:06  148DEC0 c data from add_job action=32 client c anonymous
vdr: [LOG948]2013/10/04 18:29:06  148DEC0 c [DVBAPI] Demuxer #0 no controlword received for PID #1 CAID 093E PROVID 000000 ECMPID 0099 CHID 00AC VPID 0835
vdr: [LOG949]2013/10/04 18:29:06  148DEC0 c [DVBAPI] Demuxer #0 Filter #1 set ecmtable to ODD+EVEN (CAID 093E PROVID 000000 FD 14)
vdr: [LOG950]2013/10/04 18:29:06  148DEC0 c [DVBAPI] Demuxer #0 Filter #1 set chid to ANY CHID on fd 14
vdr: [LOG951]2013/10/04 18:29:06  148DEC0 c anonymous (093E&000000/00AC/0834/AA:BCFAD5965A19354C9EE6DA694036A8AA): timeout (0 ms) by Viasat_Nordic_5E_(fin+dk)
vdr: [LOG952]2013/10/04 18:29:06  148DEC0 c cw:
vdr: [LOG953]2013/10/04 18:29:06  148DEC0    00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
vdr: [LOG954]2013/10/04 18:29:06  148DEC0 c [DVBAPI] new events occurred on 1 of 3 handlers after 1 ms inactivity
vdr: [LOG955]2013/10/04 18:29:06  148DEC0 c [DVBAPI] now handling fd 14 that reported event 3
vdr: [LOG956]2013/10/04 18:29:06  148DEC0 c [DVBAPI] Readed:
vdr: [LOG957]2013/10/04 18:29:06  148DEC0    80 70 A7 00 00 01 11 A5 04 7B 9C 00 AC AA 55 15
vdr: [LOG958]2013/10/04 18:29:06  148DEC0    21 80 01 00 01 03 00 38 91 7E 0A 2F 7C 47 29 C5
vdr: [LOG959]2013/10/04 18:29:06  148DEC0    97 C7 BA 00 00 47 08 23 56 07 B8 08 28 4D BC 90
vdr: [LOG960]2013/10/04 18:29:06  148DEC0    79 C0 01 3B 3D B9 75 0D 09 E5 07 CC C0 F9 8E 74
vdr: [LOG961]2013/10/04 18:29:06  148DEC0    E9 9F 97 DF 61 38 88 C6 40 A9 76 DE B3 E0 B1 DB
vdr: [LOG962]2013/10/04 18:29:06  148DEC0    DA 61 2E 17 17 B6 5B E4 91 68 A7 9A A4 8C CB 96
vdr: [LOG963]2013/10/04 18:29:06  148DEC0    69 CE C1 3F 90 9F 03 49 92 96 26 F4 9A 5B B1 D0
vdr: [LOG964]2013/10/04 18:29:06  148DEC0    4E 5A A1 22 05 43 2D 59 B2 8B A8 C8 AF EB 44 1E
vdr: [LOG965]2013/10/04 18:29:06  148DEC0    C0 B8 12 56 25 10 2C AC A3 A5 AE 00 4F 5C 72 1A
vdr: [LOG966]2013/10/04 18:29:06  148DEC0    FA 3B 3B 6E 16 C0 93 54 3F 76 47 BB A1 6D 78 DB
vdr: [LOG967]2013/10/04 18:29:06  148DEC0    C0 93 8B C4 65 4B D1 79 D1 25
vdr: [LOG968]2013/10/04 18:29:06  148DEC0 c [DVBAPI] Demuxer #0 ECMTYPE 80 CAID 093E PROVID 000000 ECMPID 0099 FAKECHID 00AC (unique part in ecm)
vdr: [LOG969]2013/10/04 18:29:06  148DEC0 c [DVBAPI] Demuxer #0 Filter #1 set ecmtable to ODD (CAID 093E PROVID 000000 FD 14)
vdr: [LOG970]2013/10/04 18:29:06  148DEC0 c [DVBAPI] Demuxer #0 Filter #1 set chid to ANY CHID on fd 14
vdr: [LOG971]2013/10/04 18:29:06  148DEC0 c trying reader 'Viasat_Nordic_5E_(fin+dk)' filter 093E:000000
vdr: [LOG972]2013/10/04 18:29:06  148DEC0 c 093E:000000 allowed by reader 'Viasat_Nordic_5E_(fin+dk)' filter 093E:000000
vdr: [LOG973]2013/10/04 18:29:06  148DEC0 c [GET_CW] wait_time 0 caid 093E prov 000000 srvid 0834 rc 100 cacheex cl mode 0 ex1rdr 0
vdr: [LOG974]2013/10/04 18:29:06  148DEC0 c request_cw stage=3 to reader Viasat_Nordic_5E_(fin+dk) ecm hash=BCFAD5965A19354C9EE6DA694036A8AA
vdr: [LOG975]2013/10/04 18:29:06  148DEC0 c add reader job action 5 queue length 1 Viasat_Nordic_5E_(fin+dk)
vdr: [LOG976]2013/10/04 18:29:06  148DEC0 c [DVBAPI] Demuxer #0 request cw for ecm 093E&000000/00AC/0834/AA:BCFAD5965A19354C9EE6DA694036A8AA
vdr: [LOG977]2013/10/04 18:29:06 28002C50 p start client thread action 32
vdr: [LOG978]2013/10/04 18:29:06  148DEC0 c data from add_job action=32 client c anonymous
vdr: [LOG979]2013/10/04 18:29:06  148DEC0 c [DVBAPI] Demuxer #0 no controlword received for PID #1 CAID 093E PROVID 000000 ECMPID 0099 CHID 00AC VPID 0835
vdr: [LOG980]2013/10/04 18:29:06  148DEC0 c [DVBAPI] Demuxer #0 stop Filter #1 (fd: 14 api: 0, caid: 093E, provid: 000000, ecmpid: 0099)
vdr: [LOG981]2013/10/04 18:29:06  148DEC0 c anonymous (093E&000000/00AC/0834/AA:BCFAD5965A19354C9EE6DA694036A8AA): timeout (0 ms) by Viasat_Nordic_5E_(fin+dk)
vdr: [LOG982]2013/10/04 18:29:06  148DEC0 c cw:
vdr: [LOG983]2013/10/04 18:29:06  148DEC0    00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
vdr: [LOG984]2013/10/04 18:29:06        0   >> OSCam <<  cardserver log switched, version 1.20-unstable_svn, build r0 (x86_64-linux-gnu)
vdr: [LOG985]2013/10/04 18:29:06  148DEC0 c [DVBAPI] new events occurred on 1 of 3 handlers after 300 ms inactivity
vdr: [LOG986]2013/10/04 18:29:06  148DEC0 c [DVBAPI] now handling fd 14 that reported event 32
vdr: [LOG987]2013/10/04 18:29:06  148DEC0 c [DVBAPI] Demuxer #0 has 2 ecmpids, 3 streampids, 0 ecmfilters and 0 emmfilters
vdr: [LOG988]2013/10/04 18:29:06  148DEC0 c [DVBAPI] Demuxer #0 (re)starting decodingrequests on all 2 ecmpids!
vdr: [LOG989]2013/10/04 18:29:06        0 s [OSCAM] new event 1 occurred on fd 6 after 1014 ms inactivity
vdr: [LOG990]2013/10/04 18:29:06        0 s got 21 bytes on port XXX from ip X.X.X.X:XXXX client MYUSERNAME
vdr: [LOG991]2013/10/04 18:29:06        0 s start client thread action 22
vdr: [LOG992]2013/10/04 18:29:06  14A8020 m data from add_job action=22 client m MYUSERNAME
vdr: [LOG993]2013/10/04 18:29:07  148DEC0 c [DVBAPI] Demuxer #0 no prio rules in oscam.dvbapi matches!
vdr: [LOG994]2013/10/04 18:29:07  148DEC0 c caid 090F not found in caidlist reader Viasat_Nordic_5E_(fin+dk)
vdr: [LOG995]2013/10/04 18:29:07  148DEC0 c [IGNORE PID 0] 090F:000000:083A (no matching reader)
vdr: [LOG996]2013/10/04 18:29:07  148DEC0 c trying reader 'Viasat_Nordic_5E_(fin+dk)' filter 093E:000000
vdr: [LOG997]2013/10/04 18:29:07  148DEC0 c 093E:000000 allowed by reader 'Viasat_Nordic_5E_(fin+dk)' filter 093E:000000
vdr: [LOG998]2013/10/04 18:29:07  148DEC0 c [PRIORITIZE PID 1] 093E:000000:0099 (rdr: Viasat_Nordic_5E_(fin+dk) weight: 1)
vdr: [LOG999]2013/10/04 18:29:07  148DEC0 c trying reader 'Viasat_Nordic_5E_(fin+dk)' filter 093E:000000
vdr: [LOG000]2013/10/04 18:29:07  148DEC0 c 093E:000000 allowed by reader 'Viasat_Nordic_5E_(fin+dk)' filter 093E:000000
vdr: [LOG001]2013/10/04 18:29:07  148DEC0 c [DVBAPI] Demuxer #0 trying to descramble PID #1 CAID 093E PROVID 000000 ECMPID 0099 ANY CHID VPID 0835
vdr: [LOG002]2013/10/04 18:29:07  148DEC0 c [DVBAPI] Demuxer #0 try to start new filter for caid: 093E, provid: 000000, pid: 0099
vdr: [LOG003]2013/10/04 18:29:07  148DEC0 c DEVICE open (/dev/dvb/adapter2/demux0) fd 14
vdr: [LOG004]2013/10/04 18:29:07  148DEC0 c [DVBAPI] Demuxer #0 Filter #1 started succesfully (caid 093E provid 000000 pid 0099)
vdr: [LOG005]2013/10/04 18:29:07  148DEC0 c [DVBAPI] Demuxer #0 has 2 ecmpids, 3 streampids, 1 ecmfilters and 0 emmfilters
vdr: [LOG006]2013/10/04 18:29:07  148DEC0 c [DVBAPI] new events occurred on 1 of 3 handlers after 601 ms inactivity
vdr: [LOG007]2013/10/04 18:29:07  148DEC0 c [DVBAPI] now handling fd 14 that reported event 3
vdr: [LOG008]2013/10/04 18:29:07  148DEC0 c [DVBAPI] Readed:
vdr: [LOG009]2013/10/04 18:29:07  148DEC0    80 70 A7 00 00 01 11 A5 04 7B 9C 00 AC AA 55 15
vdr: [LOG010]2013/10/04 18:29:07  148DEC0    21 80 01 00 01 03 00 38 91 7E 0A 2F 7C 47 29 C5
vdr: [LOG011]2013/10/04 18:29:07  148DEC0    97 C7 BA 00 00 47 08 23 56 07 B8 08 28 4D BC 90
vdr: [LOG012]2013/10/04 18:29:07  148DEC0    79 C0 01 3B 3D B9 75 0D 09 E5 07 CC C0 F9 8E 74
vdr: [LOG013]2013/10/04 18:29:07  148DEC0    E9 9F 97 DF 61 38 88 C6 40 A9 76 DE B3 E0 B1 DB
vdr: [LOG014]2013/10/04 18:29:07  148DEC0    DA 61 2E 17 17 B6 5B E4 91 68 A7 9A A4 8C CB 96
vdr: [LOG015]2013/10/04 18:29:07  148DEC0    69 CE C1 3F 90 9F 03 49 92 96 26 F4 9A 5B B1 D0
vdr: [LOG016]2013/10/04 18:29:07  148DEC0    4E 5A A1 22 05 43 2D 59 B2 8B A8 C8 AF EB 44 1E
vdr: [LOG017]2013/10/04 18:29:07  148DEC0    C0 B8 12 56 25 10 2C AC A3 A5 AE 00 4F 5C 72 1A
vdr: [LOG018]2013/10/04 18:29:07  148DEC0    FA 3B 3B 6E 16 C0 93 54 3F 76 47 BB A1 6D 78 DB
vdr: [LOG019]2013/10/04 18:29:07  148DEC0    C0 93 8B C4 65 4B D1 79 D1 25
vdr: [LOG020]2013/10/04 18:29:07  148DEC0 c [DVBAPI] Demuxer #0 ECMTYPE 80 CAID 093E PROVID 000000 ECMPID 0099 FAKECHID 00AC (unique part in ecm)
vdr: [LOG021]2013/10/04 18:29:07  148DEC0 c [DVBAPI] Demuxer #0 Filter #1 set ecmtable to ODD (CAID 093E PROVID 000000 FD 14)
vdr: [LOG022]2013/10/04 18:29:07  148DEC0 c [DVBAPI] Demuxer #0 Filter #1 set chid to ANY CHID on fd 14
vdr: [LOG023]2013/10/04 18:29:07  148DEC0 c trying reader 'Viasat_Nordic_5E_(fin+dk)' filter 093E:000000
vdr: [LOG024]2013/10/04 18:29:07  148DEC0 c 093E:000000 allowed by reader 'Viasat_Nordic_5E_(fin+dk)' filter 093E:000000
vdr: [LOG025]2013/10/04 18:29:07  148DEC0 c [GET_CW] wait_time 0 caid 093E prov 000000 srvid 0834 rc 100 cacheex cl mode 0 ex1rdr 0
vdr: [LOG026]2013/10/04 18:29:07  148DEC0 c request_cw stage=3 to reader Viasat_Nordic_5E_(fin+dk) ecm hash=BCFAD5965A19354C9EE6DA694036A8AA
vdr: [LOG027]2013/10/04 18:29:07  148DEC0 c add reader job action 5 queue length 1 Viasat_Nordic_5E_(fin+dk)
vdr: [LOG028]2013/10/04 18:29:07  148DEC0 c [DVBAPI] Demuxer #0 request cw for ecm 093E&000000/00AC/0834/AA:BCFAD5965A19354C9EE6DA694036A8AA
vdr: [LOG029]2013/10/04 18:29:07 28002C50 p start client thread action 32
vdr: [LOG030]2013/10/04 18:29:07  148DEC0 c data from add_job action=32 client c anonymous
vdr: [LOG031]2013/10/04 18:29:07  148DEC0 c [DVBAPI] Demuxer #0 no controlword received for PID #1 CAID 093E PROVID 000000 ECMPID 0099 CHID 00AC VPID 0835
vdr: [LOG032]2013/10/04 18:29:07  148DEC0 c [DVBAPI] Demuxer #0 Filter #1 set ecmtable to ODD+EVEN (CAID 093E PROVID 000000 FD 14)
vdr: [LOG033]2013/10/04 18:29:07  148DEC0 c [DVBAPI] Demuxer #0 Filter #1 set chid to ANY CHID on fd 14
vdr: [LOG034]2013/10/04 18:29:07  148DEC0 c anonymous (093E&000000/00AC/0834/AA:BCFAD5965A19354C9EE6DA694036A8AA): timeout (0 ms) by Viasat_Nordic_5E_(fin+dk)
vdr: [LOG035]2013/10/04 18:29:07  148DEC0 c cw:
vdr: [LOG036]2013/10/04 18:29:07  148DEC0    00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
vdr: [LOG037]2013/10/04 18:29:07        0   >> OSCam <<  cardserver log switched, version 1.20-unstable_svn, build r0 (x86_64-linux-gnu)
vdr: [LOG038]2013/10/04 18:29:07  148DEC0 c [DVBAPI] new events occurred on 1 of 3 handlers after 96 ms inactivity
vdr: [LOG039]2013/10/04 18:29:07  148DEC0 c [DVBAPI] now handling fd 14 that reported event 3
vdr: [LOG040]2013/10/04 18:29:07  148DEC0 c [DVBAPI] Readed:
vdr: [LOG041]2013/10/04 18:29:07  148DEC0    80 70 A7 00 00 01 11 A5 04 7B 9C 00 AC AA 55 15
vdr: [LOG042]2013/10/04 18:29:07  148DEC0    21 80 01 00 01 03 00 38 91 7E 0A 2F 7C 47 29 C5
vdr: [LOG043]2013/10/04 18:29:07  148DEC0    97 C7 BA 00 00 47 08 23 56 07 B8 08 28 4D BC 90
vdr: [LOG044]2013/10/04 18:29:07  148DEC0    79 C0 01 3B 3D B9 75 0D 09 E5 07 CC C0 F9 8E 74
vdr: [LOG045]2013/10/04 18:29:07  148DEC0    E9 9F 97 DF 61 38 88 C6 40 A9 76 DE B3 E0 B1 DB
vdr: [LOG046]2013/10/04 18:29:07  148DEC0    DA 61 2E 17 17 B6 5B E4 91 68 A7 9A A4 8C CB 96
vdr: [LOG047]2013/10/04 18:29:07  148DEC0    69 CE C1 3F 90 9F 03 49 92 96 26 F4 9A 5B B1 D0
vdr: [LOG048]2013/10/04 18:29:07  148DEC0    4E 5A A1 22 05 43 2D 59 B2 8B A8 C8 AF EB 44 1E
vdr: [LOG049]2013/10/04 18:29:07  148DEC0    C0 B8 12 56 25 10 2C AC A3 A5 AE 00 4F 5C 72 1A
vdr: [LOG050]2013/10/04 18:29:07  148DEC0    FA 3B 3B 6E 16 C0 93 54 3F 76 47 BB A1 6D 78 DB
vdr: [LOG051]2013/10/04 18:29:07  148DEC0    C0 93 8B C4 65 4B D1 79 D1 25
vdr: [LOG052]2013/10/04 18:29:07  148DEC0 c [DVBAPI] Demuxer #0 ECMTYPE 80 CAID 093E PROVID 000000 ECMPID 0099 FAKECHID 00AC (unique part in ecm)
vdr: [LOG053]2013/10/04 18:29:07  148DEC0 c [DVBAPI] Demuxer #0 Filter #1 set ecmtable to ODD (CAID 093E PROVID 000000 FD 14)
vdr: [LOG054]2013/10/04 18:29:07  148DEC0 c [DVBAPI] Demuxer #0 Filter #1 set chid to ANY CHID on fd 14
vdr: [LOG055]2013/10/04 18:29:07  148DEC0 c trying reader 'Viasat_Nordic_5E_(fin+dk)' filter 093E:000000
vdr: [LOG056]2013/10/04 18:29:07  148DEC0 c 093E:000000 allowed by reader 'Viasat_Nordic_5E_(fin+dk)' filter 093E:000000
vdr: [LOG057]2013/10/04 18:29:07  148DEC0 c [GET_CW] wait_time 0 caid 093E prov 000000 srvid 0834 rc 100 cacheex cl mode 0 ex1rdr 0
vdr: [LOG058]2013/10/04 18:29:07  148DEC0 c request_cw stage=3 to reader Viasat_Nordic_5E_(fin+dk) ecm hash=BCFAD5965A19354C9EE6DA694036A8AA
vdr: [LOG059]2013/10/04 18:29:07  148DEC0 c add reader job action 5 queue length 1 Viasat_Nordic_5E_(fin+dk)
vdr: [LOG060]2013/10/04 18:29:07  148DEC0 c [DVBAPI] Demuxer #0 request cw for ecm 093E&000000/00AC/0834/AA:BCFAD5965A19354C9EE6DA694036A8AA
vdr: [LOG061]2013/10/04 18:29:07 28002C50 p start client thread action 32
vdr: [LOG062]2013/10/04 18:29:07  148DEC0 c data from add_job action=32 client c anonymous
vdr: [LOG063]2013/10/04 18:29:07  148DEC0 c [DVBAPI] Demuxer #0 no controlword received for PID #1 CAID 093E PROVID 000000 ECMPID 0099 CHID 00AC VPID 0835
vdr: [LOG064]2013/10/04 18:29:07  148DEC0 c [DVBAPI] Demuxer #0 stop Filter #1 (fd: 14 api: 0, caid: 093E, provid: 000000, ecmpid: 0099)
vdr: [LOG065]2013/10/04 18:29:07  148DEC0 c anonymous (093E&000000/00AC/0834/AA:BCFAD5965A19354C9EE6DA694036A8AA): timeout (1 ms) by Viasat_Nordic_5E_(fin+dk)
vdr: [LOG066]2013/10/04 18:29:07  148DEC0 c cw:
vdr: [LOG067]2013/10/04 18:29:07  148DEC0    00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
vdr: [LOG068]2013/10/04 18:29:07  148DEC0 c [DVBAPI] new events occurred on 1 of 3 handlers after 301 ms inactivity
vdr: [LOG069]2013/10/04 18:29:07  148DEC0 c [DVBAPI] now handling fd 14 that reported event 32
vdr: [LOG070]2013/10/04 18:29:07  148DEC0 c [DVBAPI] Demuxer #0 has 2 ecmpids, 3 streampids, 0 ecmfilters and 0 emmfilters
vdr: [LOG071]2013/10/04 18:29:07  148DEC0 c [DVBAPI] Demuxer #0 (re)starting decodingrequests on all 2 ecmpids!
vdr: [LOG072]2013/10/04 18:29:07        0 s [OSCAM] new event 1 occurred on fd 6 after 1013 ms inactivity
vdr: [LOG073]2013/10/04 18:29:07        0 s got 21 bytes on port XXX from ip X.X.X.X:XXXX client MYUSERNAME
vdr: [LOG074]2013/10/04 18:29:07        0 s start client thread action 22
vdr: [LOG075]2013/10/04 18:29:07  14A8020 m data from add_job action=22 client m MYUSERNAME
vdr: [LOG076]2013/10/04 18:29:08  148DEC0 c [DVBAPI] Demuxer #0 no prio rules in oscam.dvbapi matches!
vdr: [LOG077]2013/10/04 18:29:08  148DEC0 c caid 090F not found in caidlist reader Viasat_Nordic_5E_(fin+dk)
vdr: [LOG078]2013/10/04 18:29:08  148DEC0 c [IGNORE PID 0] 090F:000000:083A (no matching reader)
vdr: [LOG079]2013/10/04 18:29:08  148DEC0 c trying reader 'Viasat_Nordic_5E_(fin+dk)' filter 093E:000000
vdr: [LOG080]2013/10/04 18:29:08  148DEC0 c 093E:000000 allowed by reader 'Viasat_Nordic_5E_(fin+dk)' filter 093E:000000
vdr: [LOG081]2013/10/04 18:29:08  148DEC0 c [PRIORITIZE PID 1] 093E:000000:0099 (rdr: Viasat_Nordic_5E_(fin+dk) weight: 1)
vdr: [LOG082]2013/10/04 18:29:08  148DEC0 c trying reader 'Viasat_Nordic_5E_(fin+dk)' filter 093E:000000
vdr: [LOG083]2013/10/04 18:29:08  148DEC0 c 093E:000000 allowed by reader 'Viasat_Nordic_5E_(fin+dk)' filter 093E:000000
vdr: [LOG084]2013/10/04 18:29:08  148DEC0 c [DVBAPI] Demuxer #0 trying to descramble PID #1 CAID 093E PROVID 000000 ECMPID 0099 ANY CHID VPID 0835
vdr: [LOG085]2013/10/04 18:29:08  148DEC0 c [DVBAPI] Demuxer #0 try to start new filter for caid: 093E, provid: 000000, pid: 0099
vdr: [LOG086]2013/10/04 18:29:08  148DEC0 c DEVICE open (/dev/dvb/adapter2/demux0) fd 14
vdr: [LOG087]2013/10/04 18:29:08  148DEC0 c [DVBAPI] Demuxer #0 Filter #1 started succesfully (caid 093E provid 000000 pid 0099)
vdr: [LOG088]2013/10/04 18:29:08  148DEC0 c [DVBAPI] Demuxer #0 has 2 ecmpids, 3 streampids, 1 ecmfilters and 0 emmfilters
vdr: [LOG089]2013/10/04 18:29:08  148DEC0 c [DVBAPI] new events occurred on 1 of 3 handlers after 600 ms inactivity
vdr: [LOG090]2013/10/04 18:29:08  148DEC0 c [DVBAPI] now handling fd 14 that reported event 3
vdr: [LOG091]2013/10/04 18:29:08  148DEC0 c [DVBAPI] Readed:
vdr: [LOG092]2013/10/04 18:29:08  148DEC0    80 70 A7 00 00 01 11 A5 04 7B 9C 00 AC AA 55 15
vdr: [LOG093]2013/10/04 18:29:08  148DEC0    21 80 01 00 01 03 00 38 91 7E 0A 2F 7C 47 29 C5
vdr: [LOG094]2013/10/04 18:29:08  148DEC0    97 C7 BA 00 00 47 08 23 56 07 B8 08 28 4D BC 90
vdr: [LOG095]2013/10/04 18:29:08  148DEC0    79 C0 01 3B 3D B9 75 0D 09 E5 07 CC C0 F9 8E 74
vdr: [LOG096]2013/10/04 18:29:08  148DEC0    E9 9F 97 DF 61 38 88 C6 40 A9 76 DE B3 E0 B1 DB
vdr: [LOG097]2013/10/04 18:29:08  148DEC0    DA 61 2E 17 17 B6 5B E4 91 68 A7 9A A4 8C CB 96
vdr: [LOG098]2013/10/04 18:29:08  148DEC0    69 CE C1 3F 90 9F 03 49 92 96 26 F4 9A 5B B1 D0
vdr: [LOG099]2013/10/04 18:29:08  148DEC0    4E 5A A1 22 05 43 2D 59 B2 8B A8 C8 AF EB 44 1E
vdr: [LOG100]2013/10/04 18:29:08  148DEC0    C0 B8 12 56 25 10 2C AC A3 A5 AE 00 4F 5C 72 1A
vdr: [LOG101]2013/10/04 18:29:08  148DEC0    FA 3B 3B 6E 16 C0 93 54 3F 76 47 BB A1 6D 78 DB
vdr: [LOG102]2013/10/04 18:29:08  148DEC0    C0 93 8B C4 65 4B D1 79 D1 25
vdr: [LOG103]2013/10/04 18:29:08  148DEC0 c [DVBAPI] Demuxer #0 ECMTYPE 80 CAID 093E PROVID 000000 ECMPID 0099 FAKECHID 00AC (unique part in ecm)
vdr: [LOG104]2013/10/04 18:29:08  148DEC0 c [DVBAPI] Demuxer #0 Filter #1 set ecmtable to ODD (CAID 093E PROVID 000000 FD 14)
vdr: [LOG105]2013/10/04 18:29:08  148DEC0 c [DVBAPI] Demuxer #0 Filter #1 set chid to ANY CHID on fd 14
vdr: [LOG106]2013/10/04 18:29:08  148DEC0 c trying reader 'Viasat_Nordic_5E_(fin+dk)' filter 093E:000000
vdr: [LOG107]2013/10/04 18:29:08  148DEC0 c 093E:000000 allowed by reader 'Viasat_Nordic_5E_(fin+dk)' filter 093E:000000
vdr: [LOG108]2013/10/04 18:29:08  148DEC0 c [GET_CW] wait_time 0 caid 093E prov 000000 srvid 0834 rc 100 cacheex cl mode 0 ex1rdr 0
vdr: [LOG109]2013/10/04 18:29:08  148DEC0 c request_cw stage=3 to reader Viasat_Nordic_5E_(fin+dk) ecm hash=BCFAD5965A19354C9EE6DA694036A8AA
vdr: [LOG110]2013/10/04 18:29:08  148DEC0 c add reader job action 5 queue length 1 Viasat_Nordic_5E_(fin+dk)
vdr: [LOG111]2013/10/04 18:29:08  148DEC0 c [DVBAPI] Demuxer #0 request cw for ecm 093E&000000/00AC/0834/AA:BCFAD5965A19354C9EE6DA694036A8AA
vdr: [LOG112]2013/10/04 18:29:08 28002C50 p start client thread action 32
vdr: [LOG113]2013/10/04 18:29:08  148DEC0 c data from add_job action=32 client c anonymous
vdr: [LOG114]2013/10/04 18:29:08  148DEC0 c [DVBAPI] Demuxer #0 no controlword received for PID #1 CAID 093E PROVID 000000 ECMPID 0099 CHID 00AC VPID 0835
vdr: [LOG115]2013/10/04 18:29:08  148DEC0 c [DVBAPI] Demuxer #0 Filter #1 set ecmtable to ODD+EVEN (CAID 093E PROVID 000000 FD 14)
vdr: [LOG116]2013/10/04 18:29:08  148DEC0 c [DVBAPI] Demuxer #0 Filter #1 set chid to ANY CHID on fd 14
vdr: [LOG117]2013/10/04 18:29:08  148DEC0 c anonymous (093E&000000/00AC/0834/AA:BCFAD5965A19354C9EE6DA694036A8AA): timeout (1 ms) by Viasat_Nordic_5E_(fin+dk)
vdr: [LOG118]2013/10/04 18:29:08  148DEC0 c cw:
vdr: [LOG119]2013/10/04 18:29:08  148DEC0    00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
vdr: [LOG120]2013/10/04 18:29:08  148DEC0 c [DVBAPI] new events occurred on 1 of 3 handlers after 1 ms inactivity
vdr: [LOG121]2013/10/04 18:29:08  148DEC0 c [DVBAPI] now handling fd 14 that reported event 3
vdr: [LOG122]2013/10/04 18:29:08  148DEC0 c [DVBAPI] Readed:
vdr: [LOG123]2013/10/04 18:29:08  148DEC0    80 70 A7 00 00 01 11 A5 04 7B 9C 00 AC AA 55 15
vdr: [LOG124]2013/10/04 18:29:08  148DEC0    21 80 01 00 01 03 00 38 91 7E 0A 2F 7C 47 29 C5
vdr: [LOG125]2013/10/04 18:29:08  148DEC0    97 C7 BA 00 00 47 08 23 56 07 B8 08 28 4D BC 90
vdr: [LOG126]2013/10/04 18:29:08  148DEC0    79 C0 01 3B 3D B9 75 0D 09 E5 07 CC C0 F9 8E 74
vdr: [LOG127]2013/10/04 18:29:08  148DEC0    E9 9F 97 DF 61 38 88 C6 40 A9 76 DE B3 E0 B1 DB
vdr: [LOG128]2013/10/04 18:29:08  148DEC0    DA 61 2E 17 17 B6 5B E4 91 68 A7 9A A4 8C CB 96
vdr: [LOG129]2013/10/04 18:29:08  148DEC0    69 CE C1 3F 90 9F 03 49 92 96 26 F4 9A 5B B1 D0
vdr: [LOG130]2013/10/04 18:29:08  148DEC0    4E 5A A1 22 05 43 2D 59 B2 8B A8 C8 AF EB 44 1E
vdr: [LOG131]2013/10/04 18:29:08  148DEC0    C0 B8 12 56 25 10 2C AC A3 A5 AE 00 4F 5C 72 1A
vdr: [LOG132]2013/10/04 18:29:08  148DEC0    FA 3B 3B 6E 16 C0 93 54 3F 76 47 BB A1 6D 78 DB
vdr: [LOG133]2013/10/04 18:29:08  148DEC0    C0 93 8B C4 65 4B D1 79 D1 25
vdr: [LOG134]2013/10/04 18:29:08  148DEC0 c [DVBAPI] Demuxer #0 ECMTYPE 80 CAID 093E PROVID 000000 ECMPID 0099 FAKECHID 00AC (unique part in ecm)
vdr: [LOG135]2013/10/04 18:29:08  148DEC0 c [DVBAPI] Demuxer #0 Filter #1 set ecmtable to ODD (CAID 093E PROVID 000000 FD 14)
vdr: [LOG136]2013/10/04 18:29:08  148DEC0 c [DVBAPI] Demuxer #0 Filter #1 set chid to ANY CHID on fd 14
vdr: [LOG137]2013/10/04 18:29:08  148DEC0 c trying reader 'Viasat_Nordic_5E_(fin+dk)' filter 093E:000000
vdr: [LOG138]2013/10/04 18:29:08  148DEC0 c 093E:000000 allowed by reader 'Viasat_Nordic_5E_(fin+dk)' filter 093E:000000
vdr: [LOG139]2013/10/04 18:29:08  148DEC0 c [GET_CW] wait_time 0 caid 093E prov 000000 srvid 0834 rc 100 cacheex cl mode 0 ex1rdr 0
vdr: [LOG140]2013/10/04 18:29:08  148DEC0 c request_cw stage=3 to reader Viasat_Nordic_5E_(fin+dk) ecm hash=BCFAD5965A19354C9EE6DA694036A8AA
vdr: [LOG141]2013/10/04 18:29:08  148DEC0 c add reader job action 5 queue length 1 Viasat_Nordic_5E_(fin+dk)
vdr: [LOG142]2013/10/04 18:29:08 28002C50 p start client thread action 32
vdr: [LOG143]2013/10/04 18:29:08  148DEC0 c [DVBAPI] Demuxer #0 request cw for ecm 093E&000000/00AC/0834/AA:BCFAD5965A19354C9EE6DA694036A8AA
vdr: [LOG144]2013/10/04 18:29:08  148DEC0 c data from add_job action=32 client c anonymous
vdr: [LOG145]2013/10/04 18:29:08  148DEC0 c [DVBAPI] Demuxer #0 no controlword received for PID #1 CAID 093E PROVID 000000 ECMPID 0099 CHID 00AC VPID 0835
vdr: [LOG146]2013/10/04 18:29:08  148DEC0 c [DVBAPI] Demuxer #0 stop Filter #1 (fd: 14 api: 0, caid: 093E, provid: 000000, ecmpid: 0099)
vdr: [LOG147]2013/10/04 18:29:08  148DEC0 c anonymous (093E&000000/00AC/0834/AA:BCFAD5965A19354C9EE6DA694036A8AA): timeout (0 ms) by Viasat_Nordic_5E_(fin+dk)
vdr: [LOG148]2013/10/04 18:29:08  148DEC0 c cw:
vdr: [LOG149]2013/10/04 18:29:08  148DEC0    00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
vdr: [LOG150]2013/10/04 18:29:08        0   >> OSCam <<  cardserver log switched, version 1.20-unstable_svn, build r0 (x86_64-linux-gnu)
vdr: [LOG151]2013/10/04 18:29:08 28002C50 p [OSCAM-WORK] new event 1 occurred on fd 16 after 9207 ms inactivity
vdr: [LOG152]2013/10/04 18:29:08 28002C50 p nmr(): len=2, errno=0
vdr: [LOG153]2013/10/04 18:29:08 28002C50 p nmr: netMsgId=12, from server=12,
vdr: [LOG154]2013/10/04 18:29:08 28002C50 p received 5 bytes from remote server
vdr: [LOG155]2013/10/04 18:29:08 28002C50    00 0C 80 00 00
vdr: [LOG156]2013/10/04 18:29:08  148DEC0 c [DVBAPI] new events occurred on 1 of 3 handlers after 300 ms inactivity
vdr: [LOG157]2013/10/04 18:29:08  148DEC0 c [DVBAPI] now handling fd 14 that reported event 32
vdr: [LOG158]2013/10/04 18:29:08  148DEC0 c [DVBAPI] Demuxer #0 has 2 ecmpids, 3 streampids, 0 ecmfilters and 0 emmfilters
vdr: [LOG159]2013/10/04 18:29:08  148DEC0 c [DVBAPI] Demuxer #0 (re)starting decodingrequests on all 2 ecmpids!
vdr: [LOG160]2013/10/04 18:29:08  148DEC0 c [DVBAPI] Demuxer #0 no prio rules in oscam.dvbapi matches!
vdr: [LOG161]2013/10/04 18:29:08  148DEC0 c caid 090F not found in caidlist reader Viasat_Nordic_5E_(fin+dk)
vdr: [LOG162]2013/10/04 18:29:08  148DEC0 c [IGNORE PID 0] 090F:000000:083A (no matching reader)
vdr: [LOG163]2013/10/04 18:29:08  148DEC0 c trying reader 'Viasat_Nordic_5E_(fin+dk)' filter 093E:000000
vdr: [LOG164]2013/10/04 18:29:08  148DEC0 c 093E:000000 allowed by reader 'Viasat_Nordic_5E_(fin+dk)' filter 093E:000000
vdr: [LOG165]2013/10/04 18:29:08  148DEC0 c [PRIORITIZE PID 1] 093E:000000:0099 (rdr: Viasat_Nordic_5E_(fin+dk) weight: 1)
vdr: [LOG166]2013/10/04 18:29:08  148DEC0 c trying reader 'Viasat_Nordic_5E_(fin+dk)' filter 093E:000000
vdr: [LOG167]2013/10/04 18:29:08  148DEC0 c 093E:000000 allowed by reader 'Viasat_Nordic_5E_(fin+dk)' filter 093E:000000
vdr: [LOG168]2013/10/04 18:29:08  148DEC0 c [DVBAPI] Demuxer #0 trying to descramble PID #1 CAID 093E PROVID 000000 ECMPID 0099 ANY CHID VPID 0835
vdr: [LOG169]2013/10/04 18:29:08  148DEC0 c [DVBAPI] Demuxer #0 try to start new filter for caid: 093E, provid: 000000, pid: 0099
vdr: [LOG170]2013/10/04 18:29:08  148DEC0 c DEVICE open (/dev/dvb/adapter2/demux0) fd 14
vdr: [LOG171]2013/10/04 18:29:08  148DEC0 c [DVBAPI] Demuxer #0 Filter #1 started succesfully (caid 093E provid 000000 pid 0099)
vdr: [LOG172]2013/10/04 18:29:08        0 s [OSCAM] new event 1 occurred on fd 6 after 1021 ms inactivity
vdr: [LOG173]2013/10/04 18:29:08        0 s got 21 bytes on port 988 from ip 192.168.0.4:44013 client MYUSERNAME
vdr: [LOG174]2013/10/04 18:29:08        0 s start client thread action 22
vdr: [LOG175]2013/10/04 18:29:08  14A8020 m data from add_job action=22 client m MYUSERNAME
vdr: [LOG176]2013/10/04 18:29:09  148DEC0 c [DVBAPI] Demuxer #0 has 2 ecmpids, 3 streampids, 1 ecmfilters and 0 emmfilters
vdr: [LOG177]2013/10/04 18:29:09  148DEC0 c [DVBAPI] new events occurred on 1 of 3 handlers after 601 ms inactivity
vdr: [LOG178]2013/10/04 18:29:09  148DEC0 c [DVBAPI] now handling fd 14 that reported event 3
vdr: [LOG179]2013/10/04 18:29:09  148DEC0 c [DVBAPI] Readed:
vdr: [LOG180]2013/10/04 18:29:09  148DEC0    81 70 A7 00 00 01 11 A5 04 7B A3 00 AC AA 55 15
vdr: [LOG181]2013/10/04 18:29:09  148DEC0    21 80 01 00 01 03 00 3F 91 7E 0A 8B 8C 80 F2 BD
vdr: [LOG182]2013/10/04 18:29:09  148DEC0    53 4A 43 00 00 47 08 89 4D 1F 81 C3 1C 22 CB 90
vdr: [LOG183]2013/10/04 18:29:09  148DEC0    79 C0 01 B6 4D DE 1D B7 78 B9 3B 00 0F 24 C6 77
vdr: [LOG184]2013/10/04 18:29:09  148DEC0    E2 E0 B8 26 C1 C0 D9 2E E9 C7 B9 C7 FA 3C 41 9C
vdr: [LOG185]2013/10/04 18:29:09  148DEC0    A9 24 8C C8 0D CD F0 BF 73 01 C3 A7 4A 47 CA 4D
vdr: [LOG186]2013/10/04 18:29:09  148DEC0    35 8C 2A 31 3E A1 63 E1 02 0F 3F CB E0 22 D3 49
vdr: [LOG187]2013/10/04 18:29:09  148DEC0    60 B6 A1 ED 72 7B 11 91 E8 71 4F 45 01 1F 69 CC
vdr: [LOG188]2013/10/04 18:29:09  148DEC0    C4 1F AB 66 E2 4F 00 BA 29 9C 66 02 45 8E B8 8E
vdr: [LOG189]2013/10/04 18:29:09  148DEC0    42 BA A6 9D 3F A4 11 76 08 92 4C B2 D7 6F 2C A4
vdr: [LOG190]2013/10/04 18:29:09  148DEC0    9F 12 8D 63 25 AC 69 38 31 58
vdr: [LOG191]2013/10/04 18:29:09  148DEC0 c [DVBAPI] Demuxer #0 ECMTYPE 81 CAID 093E PROVID 000000 ECMPID 0099 FAKECHID 00AC (unique part in ecm)
vdr: [LOG192]2013/10/04 18:29:09  148DEC0 c [DVBAPI] Demuxer #0 Filter #1 set ecmtable to EVEN (CAID 093E PROVID 000000 FD 14)
vdr: [LOG193]2013/10/04 18:29:09  148DEC0 c [DVBAPI] Demuxer #0 Filter #1 set chid to ANY CHID on fd 14
vdr: [LOG194]2013/10/04 18:29:09  148DEC0 c trying reader 'Viasat_Nordic_5E_(fin+dk)' filter 093E:000000
vdr: [LOG195]2013/10/04 18:29:09  148DEC0 c 093E:000000 allowed by reader 'Viasat_Nordic_5E_(fin+dk)' filter 093E:000000
vdr: [LOG196]2013/10/04 18:29:09  148DEC0 c [GET_CW] wait_time 0 caid 093E prov 000000 srvid 0834 rc 100 cacheex cl mode 0 ex1rdr 0
vdr: [LOG197]2013/10/04 18:29:09  148DEC0 c request_cw stage=3 to reader Viasat_Nordic_5E_(fin+dk) ecm hash=0F09CAE2FD2774925A77994EC821616A
vdr: [LOG198]2013/10/04 18:29:09  148DEC0 c add reader job action 5 queue length 1 Viasat_Nordic_5E_(fin+dk)
vdr: [LOG199]2013/10/04 18:29:09  148DEC0 c [DVBAPI] Demuxer #0 request cw for ecm 093E&000000/00AC/0834/AA:0F09CAE2FD2774925A77994EC821616A
vdr: [LOG200]2013/10/04 18:29:09 28002C50 p ---- ecm_task 0, idx 13, sflag=1
vdr: [LOG201]2013/10/04 18:29:09 28002C50 p casc ecm (Viasat_Nordic_5E_(fin+dk)):
vdr: [LOG202]2013/10/04 18:29:09 28002C50    81 70 A7 00 00 01 11 A5 04 7B A3 00 AC AA 55 15
vdr: [LOG203]2013/10/04 18:29:09 28002C50    21 80 01 00 01 03 00 3F 91 7E 0A 8B 8C 80 F2 BD
vdr: [LOG204]2013/10/04 18:29:09 28002C50    53 4A 43 00 00 47 08 89 4D 1F 81 C3 1C 22 CB 90
vdr: [LOG205]2013/10/04 18:29:09 28002C50    79 C0 01 B6 4D DE 1D B7 78 B9 3B 00 0F 24 C6 77
vdr: [LOG206]2013/10/04 18:29:09 28002C50    E2 E0 B8 26 C1 C0 D9 2E E9 C7 B9 C7 FA 3C 41 9C
vdr: [LOG207]2013/10/04 18:29:09 28002C50    A9 24 8C C8 0D CD F0 BF 73 01 C3 A7 4A 47 CA 4D
vdr: [LOG208]2013/10/04 18:29:09 28002C50    35 8C 2A 31 3E A1 63 E1 02 0F 3F CB E0 22 D3 49
vdr: [LOG209]2013/10/04 18:29:09 28002C50    60 B6 A1 ED 72 7B 11 91 E8 71 4F 45 01 1F 69 CC
vdr: [LOG210]2013/10/04 18:29:09 28002C50    C4 1F AB 66 E2 4F 00 BA 29 9C 66 02 45 8E B8 8E
vdr: [LOG211]2013/10/04 18:29:09 28002C50    42 BA A6 9D 3F A4 11 76 08 92 4C B2 D7 6F 2C A4
vdr: [LOG212]2013/10/04 18:29:09 28002C50    9F 12 8D 63 25 AC 69 38 31 58
vdr: [LOG213]2013/10/04 18:29:09 28002C50 p trying server 'server IP.port' filter 093E:000000
vdr: [LOG214]2013/10/04 18:29:09 28002C50 p 093E:000000 allowed by server 'server IP.port' filter 093E:000000
vdr: [LOG215]2013/10/04 18:29:09 28002C50 p send 182 bytes to remote server
vdr: [LOG216]2013/10/04 18:29:09 28002C50    00 B4 00 0D 08 34 00 00 00 00 00 00 81 70 A7 00
vdr: [LOG217]2013/10/04 18:29:09 28002C50    00 01 11 A5 04 7B A3 00 AC AA 55 15 21 80 01 00
vdr: [LOG218]2013/10/04 18:29:09 28002C50    01 03 00 3F 91 7E 0A 8B 8C 80 F2 BD 53 4A 43 00
vdr: [LOG219]2013/10/04 18:29:09 28002C50    00 47 08 89 4D 1F 81 C3 1C 22 CB 90 79 C0 01 B6
vdr: [LOG220]2013/10/04 18:29:09 28002C50    4D DE 1D B7 78 B9 3B 00 0F 24 C6 77 E2 E0 B8 26
vdr: [LOG221]2013/10/04 18:29:09 28002C50    C1 C0 D9 2E E9 C7 B9 C7 FA 3C 41 9C A9 24 8C C8
vdr: [LOG222]2013/10/04 18:29:09 28002C50    0D CD F0 BF 73 01 C3 A7 4A 47 CA 4D 35 8C 2A 31
vdr: [LOG223]2013/10/04 18:29:09 28002C50    3E A1 63 E1 02 0F 3F CB E0 22 D3 49 60 B6 A1 ED
vdr: [LOG224]2013/10/04 18:29:09 28002C50    72 7B 11 91 E8 71 4F 45 01 1F 69 CC C4 1F AB 66
vdr: [LOG225]2013/10/04 18:29:09 28002C50    E2 4F 00 BA 29 9C 66 02 45 8E B8 8E 42 BA A6 9D
vdr: [LOG226]2013/10/04 18:29:09 28002C50    3F A4 11 76 08 92 4C B2 D7 6F 2C A4 9F 12 8D 63
vdr: [LOG227]2013/10/04 18:29:09 28002C50    25 AC 69 38 31 58
vdr: [LOG228]2013/10/04 18:29:09        0 s [OSCAM] new event 1 occurred on fd 6 after 1007 ms inactivity
vdr: [LOG229]2013/10/04 18:29:09        0 s got 21 bytes on port XXX from ip X.X.X.X:XXXX client MYUSERNAME
vdr: [LOG230]2013/10/04 18:29:09        0 s start client thread action 22
vdr: [LOG231]2013/10/04 18:29:09  14A8020 m data from add_job action=22 client m MYUSERNAME
vdr: [LOG232]2013/10/04 18:29:10        0   start client thread action 31
vdr: [LOG233]2013/10/04 18:29:10  148DEC0 c data from add_job action=31 client c anonymous
vdr: [LOG234]2013/10/04 18:29:10        0 s [OSCAM] new event 1 occurred on fd 6 after 1014 ms inactivity
vdr: [LOG235]2013/10/04 18:29:10        0 s got 21 bytes on port XXX from ip X.X.X.X:XXXX client MYUSERNAME
vdr: [LOG236]2013/10/04 18:29:10        0 s start client thread action 22
vdr: [LOG237]2013/10/04 18:29:10  14A8020 m data from add_job action=22 client m MYUSERNAME
vdr: [LOG238]2013/10/04 18:29:11        0   start client thread action 31
vdr: [LOG239]2013/10/04 18:29:11  148DEC0 c data from add_job action=31 client c anonymous
vdr: [LOG240]2013/10/04 18:29:11        0   start client thread action 31
vdr: [LOG241]2013/10/04 18:29:11  148DEC0 c data from add_job action=31 client c anonymous
vdr: [LOG242]2013/10/04 18:29:11        0   start client thread action 30
vdr: [LOG243]2013/10/04 18:29:11  148DEC0 c data from add_job action=30 client c anonymous
vdr: [LOG244]2013/10/04 18:29:11  148DEC0 c fallback for anonymous 093E&000000/00AC/0834/AA:0F09CAE2FD2774925A77994EC821616A
vdr: [LOG245]2013/10/04 18:29:12        0 s [OSCAM] new event 1 occurred on fd 6 after 1015 ms inactivity
vdr: [LOG246]2013/10/04 18:29:12        0 s got 21 bytes on port XXX from ip X.X.X.X:XXXX client MYUSERNAME
vdr: [LOG247]2013/10/04 18:29:12        0 s start client thread action 22
vdr: [LOG248]2013/10/04 18:29:12  14A8020 m data from add_job action=22 client m MYUSERNAME
vdr: [LOG249]2013/10/04 18:29:12        0   start client thread action 31
vdr: [LOG250]2013/10/04 18:29:12  148DEC0 c data from add_job action=31 client c anonymous
vdr: [LOG251]2013/10/04 18:29:12        0   start client thread action 31
vdr: [LOG252]2013/10/04 18:29:12  148DEC0 c data from add_job action=31 client c anonymous
vdr: [LOG253]2013/10/04 18:29:13        0 s [OSCAM] new event 1 occurred on fd 6 after 1013 ms inactivity
vdr: [LOG254]2013/10/04 18:29:13        0 s got 21 bytes on port XXX from ip X.X.X.X:XXXX client MYUSERNAME
vdr: [LOG255]2013/10/04 18:29:13        0 s start client thread action 22
vdr: [LOG256]2013/10/04 18:29:13  14A8020 m data from add_job action=22 client m MYUSERNAME
vdr: [LOG257]2013/10/04 18:29:13        0   start client thread action 31
vdr: [LOG258]2013/10/04 18:29:13  148DEC0 c data from add_job action=31 client c anonymous
vdr: [LOG259]2013/10/04 18:29:13        0   >> OSCam <<  cardserver log switched, version 1.20-unstable_svn, build r0 (x86_64-linux-gnu)
vdr: [LOG260]2013/10/04 18:29:13        0   start client thread action 31
vdr: [LOG261]2013/10/04 18:29:13  148DEC0 c data from add_job action=31 client c anonymous
vdr: [LOG262]2013/10/04 18:29:14        0 s [OSCAM] new event 1 occurred on fd 6 after 1014 ms inactivity
vdr: [LOG263]2013/10/04 18:29:14        0 s got 21 bytes on port XXX from ip X.X.X.X:XXXX client MYUSERNAME
vdr: [LOG264]2013/10/04 18:29:14        0 s start client thread action 22
vdr: [LOG265]2013/10/04 18:29:14  14A8020 m data from add_job action=22 client m MYUSERNAME
vdr: [LOG266]2013/10/04 18:29:14        0   start client thread action 31
vdr: [LOG267]2013/10/04 18:29:14  148DEC0 c data from add_job action=31 client c anonymous
vdr: [LOG268]2013/10/04 18:29:14  148DEC0 c timeout for anonymous 093E&000000/00AC/0834/AA:0F09CAE2FD2774925A77994EC821616A
vdr: [LOG269]2013/10/04 18:29:14  148DEC0 c add client job action 32 queue length 1 anonymous
vdr: [LOG270]2013/10/04 18:29:14  148DEC0 c Viasat_Nordic_5E_(fin+dk) [newcamd] TRACE: ecm answer for ecm hash 0F09CAE2FD2774925A77994EC821616A rc=5
vdr: [LOG271]2013/10/04 18:29:14  148DEC0 c [DVBAPI] Demuxer #0 no controlword received for PID #1 CAID 093E PROVID 000000 ECMPID 0099 CHID 00AC VPID 0835
vdr: [LOG272]2013/10/04 18:29:14  148DEC0 c [DVBAPI] Demuxer #0 Filter #1 set ecmtable to ODD+EVEN (CAID 093E PROVID 000000 FD 14)
vdr: [LOG273]2013/10/04 18:29:14  148DEC0 c [DVBAPI] Demuxer #0 Filter #1 set chid to ANY CHID on fd 14
vdr: [LOG274]2013/10/04 18:29:14  148DEC0 c anonymous (093E&000000/00AC/0834/AA:0F09CAE2FD2774925A77994EC821616A): timeout (5000 ms) by Viasat_Nordic_5E_(fin+dk)
vdr: [LOG275]2013/10/04 18:29:14  148DEC0 c cw:
vdr: [LOG276]2013/10/04 18:29:14  148DEC0    00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
vdr: [LOG277]2013/10/04 18:29:14  148DEC0 c [DVBAPI] new events occurred on 1 of 3 handlers after 5090 ms inactivity
vdr: [LOG278]2013/10/04 18:29:14  148DEC0 c [DVBAPI] now handling fd 14 that reported event 3
vdr: [LOG279]2013/10/04 18:29:14  148DEC0 c [DVBAPI] Readed:
vdr: [LOG280]2013/10/04 18:29:14  148DEC0    81 70 A7 00 00 01 11 A5 04 7B A3 00 AC AA 55 15
vdr: [LOG281]2013/10/04 18:29:14  148DEC0    21 80 01 00 01 03 00 3F 91 7E 0A 8B 8C 80 F2 BD
vdr: [LOG282]2013/10/04 18:29:14  148DEC0    53 4A 43 00 00 47 08 89 4D 1F 81 C3 1C 22 CB 90
vdr: [LOG283]2013/10/04 18:29:14  148DEC0    79 C0 01 B6 4D DE 1D B7 78 B9 3B 00 0F 24 C6 77
vdr: [LOG284]2013/10/04 18:29:14  148DEC0    E2 E0 B8 26 C1 C0 D9 2E E9 C7 B9 C7 FA 3C 41 9C
vdr: [LOG285]2013/10/04 18:29:14  148DEC0    A9 24 8C C8 0D CD F0 BF 73 01 C3 A7 4A 47 CA 4D
vdr: [LOG286]2013/10/04 18:29:14  148DEC0    35 8C 2A 31 3E A1 63 E1 02 0F 3F CB E0 22 D3 49
vdr: [LOG287]2013/10/04 18:29:14  148DEC0    60 B6 A1 ED 72 7B 11 91 E8 71 4F 45 01 1F 69 CC
vdr: [LOG288]2013/10/04 18:29:14  148DEC0    C4 1F AB 66 E2 4F 00 BA 29 9C 66 02 45 8E B8 8E
vdr: [LOG289]2013/10/04 18:29:14  148DEC0    42 BA A6 9D 3F A4 11 76 08 92 4C B2 D7 6F 2C A4
vdr: [LOG290]2013/10/04 18:29:14  148DEC0    9F 12 8D 63 25 AC 69 38 31 58
vdr: [LOG291]2013/10/04 18:29:14  148DEC0 c [DVBAPI] Demuxer #0 ECMTYPE 81 CAID 093E PROVID 000000 ECMPID 0099 FAKECHID 00AC (unique part in ecm)
vdr: [LOG292]2013/10/04 18:29:14  148DEC0 c [DVBAPI] Demuxer #0 Filter #1 set ecmtable to EVEN (CAID 093E PROVID 000000 FD 14)
vdr: [LOG293]2013/10/04 18:29:14  148DEC0 c [DVBAPI] Demuxer #0 Filter #1 set chid to ANY CHID on fd 14
vdr: [LOG294]2013/10/04 18:29:14  148DEC0 c trying reader 'Viasat_Nordic_5E_(fin+dk)' filter 093E:000000
vdr: [LOG295]2013/10/04 18:29:14  148DEC0 c 093E:000000 allowed by reader 'Viasat_Nordic_5E_(fin+dk)' filter 093E:000000
vdr: [LOG296]2013/10/04 18:29:14  148DEC0 c [GET_CW] wait_time 0 caid 093E prov 000000 srvid 0834 rc 100 cacheex cl mode 0 ex1rdr 0
vdr: [LOG297]2013/10/04 18:29:14  148DEC0 c request_cw stage=3 to reader Viasat_Nordic_5E_(fin+dk) ecm hash=0F09CAE2FD2774925A77994EC821616A
vdr: [LOG298]2013/10/04 18:29:14  148DEC0 c add reader job action 5 queue length 1 Viasat_Nordic_5E_(fin+dk)
vdr: [LOG299]2013/10/04 18:29:14  148DEC0 c [DVBAPI] Demuxer #0 request cw for ecm 093E&000000/00AC/0834/AA:0F09CAE2FD2774925A77994EC821616A
vdr: [LOG300]2013/10/04 18:29:14 28002C50 p start client thread action 32
vdr: [LOG301]2013/10/04 18:29:14  148DEC0 c data from add_job action=32 client c anonymous
vdr: [LOG302]2013/10/04 18:29:14  148DEC0 c [DVBAPI] Demuxer #0 no controlword received for PID #1 CAID 093E PROVID 000000 ECMPID 0099 CHID 00AC VPID 0835
vdr: [LOG303]2013/10/04 18:29:14  148DEC0 c [DVBAPI] Demuxer #0 stop Filter #1 (fd: 14 api: 0, caid: 093E, provid: 000000, ecmpid: 0099)
vdr: [LOG304]2013/10/04 18:29:14  148DEC0 c anonymous (093E&000000/00AC/0834/AA:0F09CAE2FD2774925A77994EC821616A): timeout (0 ms) by Viasat_Nordic_5E_(fin+dk)
vdr: [LOG305]2013/10/04 18:29:14  148DEC0 c cw:
vdr: [LOG306]2013/10/04 18:29:14  148DEC0    00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
vdr: [LOG307]2013/10/04 18:29:14  148DEC0 c [DVBAPI] new events occurred on 1 of 3 handlers after 300 ms inactivity
vdr: [LOG308]2013/10/04 18:29:14  148DEC0 c [DVBAPI] now handling fd 14 that reported event 32
vdr: [LOG309]2013/10/04 18:29:14  148DEC0 c [DVBAPI] Demuxer #0 has 2 ecmpids, 3 streampids, 0 ecmfilters and 0 emmfilters
vdr: [LOG310]2013/10/04 18:29:14  148DEC0 c [DVBAPI] Demuxer #0 (re)starting decodingrequests on all 2 ecmpids!
vdr: [LOG311]2013/10/04 18:29:14  148DEC0 c [DVBAPI] Demuxer #0 no prio rules in oscam.dvbapi matches!
vdr: [LOG312]2013/10/04 18:29:14  148DEC0 c caid 090F not found in caidlist reader Viasat_Nordic_5E_(fin+dk)
vdr: [LOG313]2013/10/04 18:29:14  148DEC0 c [IGNORE PID 0] 090F:000000:083A (no matching reader)
vdr: [LOG314]2013/10/04 18:29:14  148DEC0 c trying reader 'Viasat_Nordic_5E_(fin+dk)' filter 093E:000000
vdr: [LOG315]2013/10/04 18:29:14  148DEC0 c 093E:000000 allowed by reader 'Viasat_Nordic_5E_(fin+dk)' filter 093E:000000
vdr: [LOG316]2013/10/04 18:29:14  148DEC0 c [PRIORITIZE PID 1] 093E:000000:0099 (rdr: Viasat_Nordic_5E_(fin+dk) weight: 1)
vdr: [LOG317]2013/10/04 18:29:14  148DEC0 c trying reader 'Viasat_Nordic_5E_(fin+dk)' filter 093E:000000
vdr: [LOG318]2013/10/04 18:29:14  148DEC0 c 093E:000000 allowed by reader 'Viasat_Nordic_5E_(fin+dk)' filter 093E:000000
vdr: [LOG319]2013/10/04 18:29:14  148DEC0 c [DVBAPI] Demuxer #0 trying to descramble PID #1 CAID 093E PROVID 000000 ECMPID 0099 ANY CHID VPID 0835
vdr: [LOG320]2013/10/04 18:29:14  148DEC0 c [DVBAPI] Demuxer #0 try to start new filter for caid: 093E, provid: 000000, pid: 0099
vdr: [LOG321]2013/10/04 18:29:14  148DEC0 c DEVICE open (/dev/dvb/adapter2/demux0) fd 14
vdr: [LOG322]2013/10/04 18:29:14  148DEC0 c [DVBAPI] Demuxer #0 Filter #1 started succesfully (caid 093E provid 000000 pid 0099)
vdr: [LOG323]2013/10/04 18:29:15        0 s [OSCAM] new event 1 occurred on fd 6 after 1015 ms inactivity
vdr: [LOG324]2013/10/04 18:29:15        0 s got 21 bytes on port XXX from ip X.X.X.X:XXXX client MYUSERNAME
vdr: [LOG325]2013/10/04 18:29:15        0 s start client thread action 22
vdr: [LOG326]2013/10/04 18:29:15  14A8020 m data from add_job action=22 client m MYUSERNAME
vdr: [LOG327]2013/10/04 18:29:15  148DEC0 c [DVBAPI] Demuxer #0 has 2 ecmpids, 3 streampids, 1 ecmfilters and 0 emmfilters
vdr: [LOG328]2013/10/04 18:29:15  148DEC0 c [DVBAPI] new events occurred on 1 of 3 handlers after 600 ms inactivity
vdr: [LOG329]2013/10/04 18:29:15  148DEC0 c [DVBAPI] now handling fd 14 that reported event 3
vdr: [LOG330]2013/10/04 18:29:15  148DEC0 c [DVBAPI] Readed:
vdr: [LOG331]2013/10/04 18:29:15  148DEC0    81 70 A7 00 00 01 11 A5 04 7B A3 00 AC AA 55 15
vdr: [LOG332]2013/10/04 18:29:15  148DEC0    21 80 01 00 01 03 00 3F 91 7E 0A 8B 8C 80 F2 BD
vdr: [LOG333]2013/10/04 18:29:15  148DEC0    53 4A 43 00 00 47 08 89 4D 1F 81 C3 1C 22 CB 90
vdr: [LOG334]2013/10/04 18:29:15  148DEC0    79 C0 01 B6 4D DE 1D B7 78 B9 3B 00 0F 24 C6 77
vdr: [LOG335]2013/10/04 18:29:15  148DEC0    E2 E0 B8 26 C1 C0 D9 2E E9 C7 B9 C7 FA 3C 41 9C
vdr: [LOG336]2013/10/04 18:29:15  148DEC0    A9 24 8C C8 0D CD F0 BF 73 01 C3 A7 4A 47 CA 4D
vdr: [LOG337]2013/10/04 18:29:15  148DEC0    35 8C 2A 31 3E A1 63 E1 02 0F 3F CB E0 22 D3 49
vdr: [LOG338]2013/10/04 18:29:15  148DEC0    60 B6 A1 ED 72 7B 11 91 E8 71 4F 45 01 1F 69 CC
vdr: [LOG339]2013/10/04 18:29:15  148DEC0    C4 1F AB 66 E2 4F 00 BA 29 9C 66 02 45 8E B8 8E
vdr: [LOG340]2013/10/04 18:29:15  148DEC0    42 BA A6 9D 3F A4 11 76 08 92 4C B2 D7 6F 2C A4
vdr: [LOG341]2013/10/04 18:29:15  148DEC0    9F 12 8D 63 25 AC 69 38 31 58
vdr: [LOG342]2013/10/04 18:29:15  148DEC0 c [DVBAPI] Demuxer #0 ECMTYPE 81 CAID 093E PROVID 000000 ECMPID 0099 FAKECHID 00AC (unique part in ecm)
vdr: [LOG343]2013/10/04 18:29:15  148DEC0 c [DVBAPI] Demuxer #0 Filter #1 set ecmtable to EVEN (CAID 093E PROVID 000000 FD 14)
vdr: [LOG344]2013/10/04 18:29:15  148DEC0 c [DVBAPI] Demuxer #0 Filter #1 set chid to ANY CHID on fd 14
vdr: [LOG345]2013/10/04 18:29:15  148DEC0 c trying reader 'Viasat_Nordic_5E_(fin+dk)' filter 093E:000000
vdr: [LOG346]2013/10/04 18:29:15  148DEC0 c 093E:000000 allowed by reader 'Viasat_Nordic_5E_(fin+dk)' filter 093E:000000
vdr: [LOG347]2013/10/04 18:29:15  148DEC0 c [GET_CW] wait_time 0 caid 093E prov 000000 srvid 0834 rc 100 cacheex cl mode 0 ex1rdr 0
vdr: [LOG348]2013/10/04 18:29:15  148DEC0 c request_cw stage=3 to reader Viasat_Nordic_5E_(fin+dk) ecm hash=0F09CAE2FD2774925A77994EC821616A
vdr: [LOG349]2013/10/04 18:29:15  148DEC0 c add reader job action 5 queue length 1 Viasat_Nordic_5E_(fin+dk)
vdr: [LOG350]2013/10/04 18:29:15  148DEC0 c [DVBAPI] Demuxer #0 request cw for ecm 093E&000000/00AC/0834/AA:0F09CAE2FD2774925A77994EC821616A
vdr: [LOG351]2013/10/04 18:29:15 28002C50 p start client thread action 32
vdr: [LOG352]2013/10/04 18:29:15  148DEC0 c data from add_job action=32 client c anonymous
vdr: [LOG353]2013/10/04 18:29:15  148DEC0 c [DVBAPI] Demuxer #0 no controlword received for PID #1 CAID 093E PROVID 000000 ECMPID 0099 CHID 00AC VPID 0835
vdr: [LOG354]2013/10/04 18:29:15  148DEC0 c [DVBAPI] Demuxer #0 Filter #1 set ecmtable to ODD+EVEN (CAID 093E PROVID 000000 FD 14)
vdr: [LOG355]2013/10/04 18:29:15  148DEC0 c [DVBAPI] Demuxer #0 Filter #1 set chid to ANY CHID on fd 14
vdr: [LOG356]2013/10/04 18:29:15  148DEC0 c anonymous (093E&000000/00AC/0834/AA:0F09CAE2FD2774925A77994EC821616A): timeout (1 ms) by Viasat_Nordic_5E_(fin+dk)
vdr: [LOG357]2013/10/04 18:29:15  148DEC0 c cw:
vdr: [LOG358]2013/10/04 18:29:15  148DEC0    00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
vdr: [LOG359]2013/10/04 18:29:15  148DEC0 c [DVBAPI] new events occurred on 1 of 3 handlers after 1 ms inactivity
vdr: [LOG360]2013/10/04 18:29:15  148DEC0 c [DVBAPI] now handling fd 14 that reported event 3
vdr: [LOG361]2013/10/04 18:29:15  148DEC0 c [DVBAPI] Readed:
vdr: [LOG362]2013/10/04 18:29:15  148DEC0    81 70 A7 00 00 01 11 A5 04 7B A3 00 AC AA 55 15
vdr: [LOG363]2013/10/04 18:29:15  148DEC0    21 80 01 00 01 03 00 3F 91 7E 0A 8B 8C 80 F2 BD
vdr: [LOG364]2013/10/04 18:29:15  148DEC0    53 4A 43 00 00 47 08 89 4D 1F 81 C3 1C 22 CB 90
vdr: [LOG365]2013/10/04 18:29:15  148DEC0    79 C0 01 B6 4D DE 1D B7 78 B9 3B 00 0F 24 C6 77
vdr: [LOG366]2013/10/04 18:29:15  148DEC0    E2 E0 B8 26 C1 C0 D9 2E E9 C7 B9 C7 FA 3C 41 9C
vdr: [LOG367]2013/10/04 18:29:15  148DEC0    A9 24 8C C8 0D CD F0 BF 73 01 C3 A7 4A 47 CA 4D
vdr: [LOG368]2013/10/04 18:29:15  148DEC0    35 8C 2A 31 3E A1 63 E1 02 0F 3F CB E0 22 D3 49
vdr: [LOG369]2013/10/04 18:29:15  148DEC0    60 B6 A1 ED 72 7B 11 91 E8 71 4F 45 01 1F 69 CC
vdr: [LOG370]2013/10/04 18:29:15  148DEC0    C4 1F AB 66 E2 4F 00 BA 29 9C 66 02 45 8E B8 8E
vdr: [LOG371]2013/10/04 18:29:15  148DEC0    42 BA A6 9D 3F A4 11 76 08 92 4C B2 D7 6F 2C A4
vdr: [LOG372]2013/10/04 18:29:15  148DEC0    9F 12 8D 63 25 AC 69 38 31 58
vdr: [LOG373]2013/10/04 18:29:15  148DEC0 c [DVBAPI] Demuxer #0 ECMTYPE 81 CAID 093E PROVID 000000 ECMPID 0099 FAKECHID 00AC (unique part in ecm)
vdr: [LOG374]2013/10/04 18:29:15  148DEC0 c [DVBAPI] Demuxer #0 Filter #1 set ecmtable to EVEN (CAID 093E PROVID 000000 FD 14)
vdr: [LOG375]2013/10/04 18:29:15  148DEC0 c [DVBAPI] Demuxer #0 Filter #1 set chid to ANY CHID on fd 14
vdr: [LOG376]2013/10/04 18:29:15  148DEC0 c trying reader 'Viasat_Nordic_5E_(fin+dk)' filter 093E:000000
vdr: [LOG377]2013/10/04 18:29:15  148DEC0 c 093E:000000 allowed by reader 'Viasat_Nordic_5E_(fin+dk)' filter 093E:000000
vdr: [LOG378]2013/10/04 18:29:15  148DEC0 c [GET_CW] wait_time 0 caid 093E prov 000000 srvid 0834 rc 100 cacheex cl mode 0 ex1rdr 0
vdr: [LOG379]2013/10/04 18:29:15  148DEC0 c request_cw stage=3 to reader Viasat_Nordic_5E_(fin+dk) ecm hash=0F09CAE2FD2774925A77994EC821616A
vdr: [LOG380]2013/10/04 18:29:15  148DEC0 c add reader job action 5 queue length 1 Viasat_Nordic_5E_(fin+dk)
vdr: [LOG381]2013/10/04 18:29:15  148DEC0 c [DVBAPI] Demuxer #0 request cw for ecm 093E&000000/00AC/0834/AA:0F09CAE2FD2774925A77994EC821616A
vdr: [LOG382]2013/10/04 18:29:15 28002C50 p start client thread action 32
vdr: [LOG383]2013/10/04 18:29:15  148DEC0 c data from add_job action=32 client c anonymous
vdr: [LOG384]2013/10/04 18:29:15  148DEC0 c [DVBAPI] Demuxer #0 no controlword received for PID #1 CAID 093E PROVID 000000 ECMPID 0099 CHID 00AC VPID 0835
vdr: [LOG385]2013/10/04 18:29:15  148DEC0 c [DVBAPI] Demuxer #0 stop Filter #1 (fd: 14 api: 0, caid: 093E, provid: 000000, ecmpid: 0099)
vdr: [LOG386]2013/10/04 18:29:15  148DEC0 c anonymous (093E&000000/00AC/0834/AA:0F09CAE2FD2774925A77994EC821616A): timeout (0 ms) by Viasat_Nordic_5E_(fin+dk)
vdr: [LOG387]2013/10/04 18:29:15  148DEC0 c cw:
vdr: [LOG388]2013/10/04 18:29:15  148DEC0    00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
vdr: [LOG389]2013/10/04 18:29:15        0   >> OSCam <<  cardserver log switched, version 1.20-unstable_svn, build r0 (x86_64-linux-gnu)
vdr: [LOG390]2013/10/04 18:29:15  148DEC0 c [DVBAPI] new events occurred on 1 of 3 handlers after 300 ms inactivity
vdr: [LOG391]2013/10/04 18:29:15  148DEC0 c [DVBAPI] now handling fd 14 that reported event 32
vdr: [LOG392]2013/10/04 18:29:15  148DEC0 c [DVBAPI] Demuxer #0 has 2 ecmpids, 3 streampids, 0 ecmfilters and 0 emmfilters
vdr: [LOG393]2013/10/04 18:29:15  148DEC0 c [DVBAPI] Demuxer #0 (re)starting decodingrequests on all 2 ecmpids!
vdr: [LOG394]2013/10/04 18:29:15  148DEC0 c [DVBAPI] Demuxer #0 no prio rules in oscam.dvbapi matches!
vdr: [LOG395]2013/10/04 18:29:15  148DEC0 c caid 090F not found in caidlist reader Viasat_Nordic_5E_(fin+dk)
vdr: [LOG396]2013/10/04 18:29:15  148DEC0 c [IGNORE PID 0] 090F:000000:083A (no matching reader)
vdr: [LOG397]2013/10/04 18:29:15  148DEC0 c trying reader 'Viasat_Nordic_5E_(fin+dk)' filter 093E:000000
vdr: [LOG398]2013/10/04 18:29:15  148DEC0 c 093E:000000 allowed by reader 'Viasat_Nordic_5E_(fin+dk)' filter 093E:000000
vdr: [LOG399]2013/10/04 18:29:15  148DEC0 c [PRIORITIZE PID 1] 093E:000000:0099 (rdr: Viasat_Nordic_5E_(fin+dk) weight: 1)
vdr: [LOG400]2013/10/04 18:29:15  148DEC0 c trying reader 'Viasat_Nordic_5E_(fin+dk)' filter 093E:000000
vdr: [LOG401]2013/10/04 18:29:15  148DEC0 c 093E:000000 allowed by reader 'Viasat_Nordic_5E_(fin+dk)' filter 093E:000000
vdr: [LOG402]2013/10/04 18:29:15  148DEC0 c [DVBAPI] Demuxer #0 trying to descramble PID #1 CAID 093E PROVID 000000 ECMPID 0099 ANY CHID VPID 0835
vdr: [LOG403]2013/10/04 18:29:15  148DEC0 c [DVBAPI] Demuxer #0 try to start new filter for caid: 093E, provid: 000000, pid: 0099
vdr: [LOG404]2013/10/04 18:29:15  148DEC0 c DEVICE open (/dev/dvb/adapter2/demux0) fd 14
vdr: [LOG405]2013/10/04 18:29:15  148DEC0 c [DVBAPI] Demuxer #0 Filter #1 started succesfully (caid 093E provid 000000 pid 0099)
vdr: [LOG406]2013/10/04 18:29:16        0 s [OSCAM] new event 1 occurred on fd 6 after 1013 ms inactivity
vdr: [LOG407]2013/10/04 18:29:16        0 s got 21 bytes on port XXX from ip X.X.X.X:XXXX client MYUSERNAME
vdr: [LOG408]2013/10/04 18:29:16        0 s start client thread action 22
vdr: [LOG409]2013/10/04 18:29:16  14A8020 m data from add_job action=22 client m MYUSERNAME
vdr: [LOG410]2013/10/04 18:29:16  148DEC0 c [DVBAPI] Demuxer #0 has 2 ecmpids, 3 streampids, 1 ecmfilters and 0 emmfilters
vdr: [LOG411]2013/10/04 18:29:16  148DEC0 c [DVBAPI] new events occurred on 1 of 3 handlers after 601 ms inactivity
vdr: [LOG412]2013/10/04 18:29:16  148DEC0 c [DVBAPI] now handling fd 14 that reported event 3
vdr: [LOG413]2013/10/04 18:29:16  148DEC0 c [DVBAPI] Readed:
vdr: [LOG414]2013/10/04 18:29:16  148DEC0    81 70 A7 00 00 01 11 A5 04 7B A3 00 AC AA 55 15
vdr: [LOG415]2013/10/04 18:29:16  148DEC0    21 80 01 00 01 03 00 3F 91 7E 0A 8B 8C 80 F2 BD
vdr: [LOG416]2013/10/04 18:29:16  148DEC0    53 4A 43 00 00 47 08 89 4D 1F 81 C3 1C 22 CB 90
vdr: [LOG417]2013/10/04 18:29:16  148DEC0    79 C0 01 B6 4D DE 1D B7 78 B9 3B 00 0F 24 C6 77
vdr: [LOG418]2013/10/04 18:29:16  148DEC0    E2 E0 B8 26 C1 C0 D9 2E E9 C7 B9 C7 FA 3C 41 9C
vdr: [LOG419]2013/10/04 18:29:16  148DEC0    A9 24 8C C8 0D CD F0 BF 73 01 C3 A7 4A 47 CA 4D
vdr: [LOG420]2013/10/04 18:29:16  148DEC0    35 8C 2A 31 3E A1 63 E1 02 0F 3F CB E0 22 D3 49
vdr: [LOG421]2013/10/04 18:29:16  148DEC0    60 B6 A1 ED 72 7B 11 91 E8 71 4F 45 01 1F 69 CC
vdr: [LOG422]2013/10/04 18:29:16  148DEC0    C4 1F AB 66 E2 4F 00 BA 29 9C 66 02 45 8E B8 8E
vdr: [LOG423]2013/10/04 18:29:16  148DEC0    42 BA A6 9D 3F A4 11 76 08 92 4C B2 D7 6F 2C A4
vdr: [LOG424]2013/10/04 18:29:16  148DEC0    9F 12 8D 63 25 AC 69 38 31 58
vdr: [LOG425]2013/10/04 18:29:16  148DEC0 c [DVBAPI] Demuxer #0 ECMTYPE 81 CAID 093E PROVID 000000 ECMPID 0099 FAKECHID 00AC (unique part in ecm)
vdr: [LOG426]2013/10/04 18:29:16  148DEC0 c [DVBAPI] Demuxer #0 Filter #1 set ecmtable to EVEN (CAID 093E PROVID 000000 FD 14)
vdr: [LOG427]2013/10/04 18:29:16  148DEC0 c [DVBAPI] Demuxer #0 Filter #1 set chid to ANY CHID on fd 14
vdr: [LOG428]2013/10/04 18:29:16  148DEC0 c trying reader 'Viasat_Nordic_5E_(fin+dk)' filter 093E:000000
vdr: [LOG429]2013/10/04 18:29:16  148DEC0 c 093E:000000 allowed by reader 'Viasat_Nordic_5E_(fin+dk)' filter 093E:000000
vdr: [LOG430]2013/10/04 18:29:16  148DEC0 c [GET_CW] wait_time 0 caid 093E prov 000000 srvid 0834 rc 100 cacheex cl mode 0 ex1rdr 0
vdr: [LOG431]2013/10/04 18:29:16  148DEC0 c request_cw stage=3 to reader Viasat_Nordic_5E_(fin+dk) ecm hash=0F09CAE2FD2774925A77994EC821616A
vdr: [LOG432]2013/10/04 18:29:16  148DEC0 c add reader job action 5 queue length 1 Viasat_Nordic_5E_(fin+dk)
vdr: [LOG433]2013/10/04 18:29:16  148DEC0 c [DVBAPI] Demuxer #0 request cw for ecm 093E&000000/00AC/0834/AA:0F09CAE2FD2774925A77994EC821616A
vdr: [LOG434]2013/10/04 18:29:16 28002C50 p start client thread action 32
vdr: [LOG435]2013/10/04 18:29:16  148DEC0 c data from add_job action=32 client c anonymous
vdr: [LOG436]2013/10/04 18:29:16  148DEC0 c [DVBAPI] Demuxer #0 no controlword received for PID #1 CAID 093E PROVID 000000 ECMPID 0099 CHID 00AC VPID 0835
vdr: [LOG437]2013/10/04 18:29:16  148DEC0 c [DVBAPI] Demuxer #0 Filter #1 set ecmtable to ODD+EVEN (CAID 093E PROVID 000000 FD 14)
vdr: [LOG438]2013/10/04 18:29:16  148DEC0 c [DVBAPI] Demuxer #0 Filter #1 set chid to ANY CHID on fd 14
vdr: [LOG439]2013/10/04 18:29:16  148DEC0 c anonymous (093E&000000/00AC/0834/AA:0F09CAE2FD2774925A77994EC821616A): timeout (0 ms) by Viasat_Nordic_5E_(fin+dk)
vdr: [LOG440]2013/10/04 18:29:16  148DEC0 c cw:
vdr: [LOG441]2013/10/04 18:29:16  148DEC0    00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
vdr: [LOG442]2013/10/04 18:29:16  148DEC0 c [DVBAPI] new events occurred on 1 of 3 handlers after 97 ms inactivity
vdr: [LOG443]2013/10/04 18:29:16  148DEC0 c [DVBAPI] now handling fd 14 that reported event 3
vdr: [LOG444]2013/10/04 18:29:16  148DEC0 c [DVBAPI] Readed:
vdr: [LOG445]2013/10/04 18:29:16  148DEC0    81 70 A7 00 00 01 11 A5 04 7B A3 00 AC AA 55 15
vdr: [LOG446]2013/10/04 18:29:16  148DEC0    21 80 01 00 01 03 00 3F 91 7E 0A 8B 8C 80 F2 BD
vdr: [LOG447]2013/10/04 18:29:16  148DEC0    53 4A 43 00 00 47 08 89 4D 1F 81 C3 1C 22 CB 90
vdr: [LOG448]2013/10/04 18:29:16  148DEC0    79 C0 01 B6 4D DE 1D B7 78 B9 3B 00 0F 24 C6 77
vdr: [LOG449]2013/10/04 18:29:16  148DEC0    E2 E0 B8 26 C1 C0 D9 2E E9 C7 B9 C7 FA 3C 41 9C
vdr: [LOG450]2013/10/04 18:29:16  148DEC0    A9 24 8C C8 0D CD F0 BF 73 01 C3 A7 4A 47 CA 4D
vdr: [LOG451]2013/10/04 18:29:16  148DEC0    35 8C 2A 31 3E A1 63 E1 02 0F 3F CB E0 22 D3 49
vdr: [LOG452]2013/10/04 18:29:16  148DEC0    60 B6 A1 ED 72 7B 11 91 E8 71 4F 45 01 1F 69 CC
vdr: [LOG453]2013/10/04 18:29:16  148DEC0    C4 1F AB 66 E2 4F 00 BA 29 9C 66 02 45 8E B8 8E
vdr: [LOG454]2013/10/04 18:29:16  148DEC0    42 BA A6 9D 3F A4 11 76 08 92 4C B2 D7 6F 2C A4
vdr: [LOG455]2013/10/04 18:29:16  148DEC0    9F 12 8D 63 25 AC 69 38 31 58
vdr: [LOG456]2013/10/04 18:29:16  148DEC0 c [DVBAPI] Demuxer #0 ECMTYPE 81 CAID 093E PROVID 000000 ECMPID 0099 FAKECHID 00AC (unique part in ecm)
vdr: [LOG457]2013/10/04 18:29:16  148DEC0 c [DVBAPI] Demuxer #0 Filter #1 set ecmtable to EVEN (CAID 093E PROVID 000000 FD 14)
vdr: [LOG458]2013/10/04 18:29:16  148DEC0 c [DVBAPI] Demuxer #0 Filter #1 set chid to ANY CHID on fd 14
vdr: [LOG459]2013/10/04 18:29:16  148DEC0 c trying reader 'Viasat_Nordic_5E_(fin+dk)' filter 093E:000000
vdr: [LOG460]2013/10/04 18:29:16  148DEC0 c 093E:000000 allowed by reader 'Viasat_Nordic_5E_(fin+dk)' filter 093E:000000
vdr: [LOG461]2013/10/04 18:29:16  148DEC0 c [GET_CW] wait_time 0 caid 093E prov 000000 srvid 0834 rc 100 cacheex cl mode 0 ex1rdr 0
vdr: [LOG462]2013/10/04 18:29:16  148DEC0 c request_cw stage=3 to reader Viasat_Nordic_5E_(fin+dk) ecm hash=0F09CAE2FD2774925A77994EC821616A
vdr: [LOG463]2013/10/04 18:29:16  148DEC0 c add reader job action 5 queue length 1 Viasat_Nordic_5E_(fin+dk)
vdr: [LOG464]2013/10/04 18:29:16  148DEC0 c [DVBAPI] Demuxer #0 request cw for ecm 093E&000000/00AC/0834/AA:0F09CAE2FD2774925A77994EC821616A
vdr: [LOG465]2013/10/04 18:29:16 28002C50 p start client thread action 32
vdr: [LOG466]2013/10/04 18:29:16  148DEC0 c data from add_job action=32 client c anonymous
vdr: [LOG467]2013/10/04 18:29:16  148DEC0 c [DVBAPI] Demuxer #0 no controlword received for PID #1 CAID 093E PROVID 000000 ECMPID 0099 CHID 00AC VPID 0835
vdr: [LOG468]2013/10/04 18:29:16  148DEC0 c [DVBAPI] Demuxer #0 stop Filter #1 (fd: 14 api: 0, caid: 093E, provid: 000000, ecmpid: 0099)
vdr: [LOG469]2013/10/04 18:29:16  148DEC0 c anonymous (093E&000000/00AC/0834/AA:0F09CAE2FD2774925A77994EC821616A): timeout (0 ms) by Viasat_Nordic_5E_(fin+dk)
vdr: [LOG470]2013/10/04 18:29:16  148DEC0 c cw:
vdr: [LOG471]2013/10/04 18:29:16  148DEC0    00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
vdr: [LOG472]2013/10/04 18:29:16  148DEC0 c [DVBAPI] new events occurred on 1 of 3 handlers after 300 ms inactivity
vdr: [LOG473]2013/10/04 18:29:16  148DEC0 c [DVBAPI] now handling fd 14 that reported event 32
vdr: [LOG474]2013/10/04 18:29:16  148DEC0 c [DVBAPI] Demuxer #0 has 2 ecmpids, 3 streampids, 0 ecmfilters and 0 emmfilters
vdr: [LOG475]2013/10/04 18:29:16  148DEC0 c [DVBAPI] Demuxer #0 (re)starting decodingrequests on all 2 ecmpids!
vdr: [LOG476]2013/10/04 18:29:16        0   >> OSCam <<  cardserver log switched, version 1.20-unstable_svn, build r0 (x86_64-linux-gnu)
vdr: [LOG477]2013/10/04 18:29:16  148DEC0 c [DVBAPI] Demuxer #0 no prio rules in oscam.dvbapi matches!
vdr: [LOG478]2013/10/04 18:29:16  148DEC0 c caid 090F not found in caidlist reader Viasat_Nordic_5E_(fin+dk)
vdr: [LOG479]2013/10/04 18:29:16  148DEC0 c [IGNORE PID 0] 090F:000000:083A (no matching reader)
vdr: [LOG480]2013/10/04 18:29:16  148DEC0 c trying reader 'Viasat_Nordic_5E_(fin+dk)' filter 093E:000000
vdr: [LOG481]2013/10/04 18:29:16  148DEC0 c 093E:000000 allowed by reader 'Viasat_Nordic_5E_(fin+dk)' filter 093E:000000
vdr: [LOG482]2013/10/04 18:29:16  148DEC0 c [PRIORITIZE PID 1] 093E:000000:0099 (rdr: Viasat_Nordic_5E_(fin+dk) weight: 1)
vdr: [LOG483]2013/10/04 18:29:16  148DEC0 c trying reader 'Viasat_Nordic_5E_(fin+dk)' filter 093E:000000
vdr: [LOG484]2013/10/04 18:29:16  148DEC0 c 093E:000000 allowed by reader 'Viasat_Nordic_5E_(fin+dk)' filter 093E:000000
vdr: [LOG485]2013/10/04 18:29:16  148DEC0 c [DVBAPI] Demuxer #0 trying to descramble PID #1 CAID 093E PROVID 000000 ECMPID 0099 ANY CHID VPID 0835
vdr: [LOG486]2013/10/04 18:29:16  148DEC0 c [DVBAPI] Demuxer #0 try to start new filter for caid: 093E, provid: 000000, pid: 0099
vdr: [LOG487]2013/10/04 18:29:16  148DEC0 c DEVICE open (/dev/dvb/adapter2/demux0) fd 14
vdr: [LOG488]2013/10/04 18:29:16  148DEC0 c [DVBAPI] Demuxer #0 Filter #1 started succesfully (caid 093E provid 000000 pid 0099)
vdr: [LOG489]2013/10/04 18:29:17        0 s [OSCAM] new event 1 occurred on fd 6 after 1015 ms inactivity
vdr: [LOG490]2013/10/04 18:29:17        0 s got 21 bytes on port XXX from ip X.X.X.X:XXXX client MYUSERNAME
vdr: [LOG491]2013/10/04 18:29:17        0 s start client thread action 22
vdr: [LOG492]2013/10/04 18:29:17  14A8020 m data from add_job action=22 client m MYUSERNAME
vdr: [LOG493]2013/10/04 18:29:17  148DEC0 c [DVBAPI] Demuxer #0 has 2 ecmpids, 3 streampids, 1 ecmfilters and 0 emmfilters
vdr: [LOG494]2013/10/04 18:29:17  148DEC0 c [DVBAPI] new events occurred on 1 of 3 handlers after 601 ms inactivity
vdr: [LOG495]2013/10/04 18:29:17  148DEC0 c [DVBAPI] now handling fd 14 that reported event 3
vdr: [LOG496]2013/10/04 18:29:17  148DEC0 c [DVBAPI] Readed:
vdr: [LOG497]2013/10/04 18:29:17  148DEC0    81 70 A7 00 00 01 11 A5 04 7B A3 00 AC AA 55 15
vdr: [LOG498]2013/10/04 18:29:17  148DEC0    21 80 01 00 01 03 00 3F 91 7E 0A 8B 8C 80 F2 BD
vdr: [LOG499]2013/10/04 18:29:17  148DEC0    53 4A 43 00 00 47 08 89 4D 1F 81 C3 1C 22 CB 90
vdr: [LOG500]2013/10/04 18:29:17  148DEC0    79 C0 01 B6 4D DE 1D B7 78 B9 3B 00 0F 24 C6 77
vdr: [LOG501]2013/10/04 18:29:17  148DEC0    E2 E0 B8 26 C1 C0 D9 2E E9 C7 B9 C7 FA 3C 41 9C
vdr: [LOG502]2013/10/04 18:29:17  148DEC0    A9 24 8C C8 0D CD F0 BF 73 01 C3 A7 4A 47 CA 4D
vdr: [LOG503]2013/10/04 18:29:17  148DEC0    35 8C 2A 31 3E A1 63 E1 02 0F 3F CB E0 22 D3 49
vdr: [LOG504]2013/10/04 18:29:17  148DEC0    60 B6 A1 ED 72 7B 11 91 E8 71 4F 45 01 1F 69 CC
vdr: [LOG505]2013/10/04 18:29:17  148DEC0    C4 1F AB 66 E2 4F 00 BA 29 9C 66 02 45 8E B8 8E
vdr: [LOG506]2013/10/04 18:29:17  148DEC0    42 BA A6 9D 3F A4 11 76 08 92 4C B2 D7 6F 2C A4
vdr: [LOG507]2013/10/04 18:29:17  148DEC0    9F 12 8D 63 25 AC 69 38 31 58
vdr: [LOG508]2013/10/04 18:29:17  148DEC0 c [DVBAPI] Demuxer #0 ECMTYPE 81 CAID 093E PROVID 000000 ECMPID 0099 FAKECHID 00AC (unique part in ecm)
vdr: [LOG509]2013/10/04 18:29:17  148DEC0 c [DVBAPI] Demuxer #0 Filter #1 set ecmtable to EVEN (CAID 093E PROVID 000000 FD 14)
vdr: [LOG510]2013/10/04 18:29:17  148DEC0 c [DVBAPI] Demuxer #0 Filter #1 set chid to ANY CHID on fd 14
vdr: [LOG511]2013/10/04 18:29:17  148DEC0 c trying reader 'Viasat_Nordic_5E_(fin+dk)' filter 093E:000000
vdr: [LOG512]2013/10/04 18:29:17  148DEC0 c 093E:000000 allowed by reader 'Viasat_Nordic_5E_(fin+dk)' filter 093E:000000
vdr: [LOG513]2013/10/04 18:29:17  148DEC0 c [GET_CW] wait_time 0 caid 093E prov 000000 srvid 0834 rc 100 cacheex cl mode 0 ex1rdr 0
vdr: [LOG514]2013/10/04 18:29:17  148DEC0 c request_cw stage=3 to reader Viasat_Nordic_5E_(fin+dk) ecm hash=0F09CAE2FD2774925A77994EC821616A
vdr: [LOG515]2013/10/04 18:29:17  148DEC0 c add reader job action 5 queue length 1 Viasat_Nordic_5E_(fin+dk)
vdr: [LOG516]2013/10/04 18:29:17  148DEC0 c [DVBAPI] Demuxer #0 request cw for ecm 093E&000000/00AC/0834/AA:0F09CAE2FD2774925A77994EC821616A
vdr: [LOG517]2013/10/04 18:29:17 28002C50 p start client thread action 32
vdr: [LOG518]2013/10/04 18:29:17  148DEC0 c data from add_job action=32 client c anonymous
vdr: [LOG519]2013/10/04 18:29:17  148DEC0 c [DVBAPI] Demuxer #0 no controlword received for PID #1 CAID 093E PROVID 000000 ECMPID 0099 CHID 00AC VPID 0835
vdr: [LOG520]2013/10/04 18:29:17  148DEC0 c [DVBAPI] Demuxer #0 Filter #1 set ecmtable to ODD+EVEN (CAID 093E PROVID 000000 FD 14)
vdr: [LOG521]2013/10/04 18:29:17  148DEC0 c [DVBAPI] Demuxer #0 Filter #1 set chid to ANY CHID on fd 14
vdr: [LOG522]2013/10/04 18:29:17  148DEC0 c anonymous (093E&000000/00AC/0834/AA:0F09CAE2FD2774925A77994EC821616A): timeout (0 ms) by Viasat_Nordic_5E_(fin+dk)
vdr: [LOG523]2013/10/04 18:29:17  148DEC0 c cw:
vdr: [LOG524]2013/10/04 18:29:17  148DEC0    00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
vdr: [LOG525]2013/10/04 18:29:17  148DEC0 c [DVBAPI] new events occurred on 1 of 3 handlers after 0 ms inactivity
vdr: [LOG526]2013/10/04 18:29:17  148DEC0 c [DVBAPI] now handling fd 14 that reported event 3
vdr: [LOG527]2013/10/04 18:29:17  148DEC0 c [DVBAPI] Readed:
vdr: [LOG528]2013/10/04 18:29:17  148DEC0    81 70 A7 00 00 01 11 A5 04 7B A3 00 AC AA 55 15
vdr: [LOG529]2013/10/04 18:29:17  148DEC0    21 80 01 00 01 03 00 3F 91 7E 0A 8B 8C 80 F2 BD
vdr: [LOG530]2013/10/04 18:29:17  148DEC0    53 4A 43 00 00 47 08 89 4D 1F 81 C3 1C 22 CB 90
vdr: [LOG531]2013/10/04 18:29:17  148DEC0    79 C0 01 B6 4D DE 1D B7 78 B9 3B 00 0F 24 C6 77
vdr: [LOG532]2013/10/04 18:29:17  148DEC0    E2 E0 B8 26 C1 C0 D9 2E E9 C7 B9 C7 FA 3C 41 9C
vdr: [LOG533]2013/10/04 18:29:17  148DEC0    A9 24 8C C8 0D CD F0 BF 73 01 C3 A7 4A 47 CA 4D
vdr: [LOG534]2013/10/04 18:29:17  148DEC0    35 8C 2A 31 3E A1 63 E1 02 0F 3F CB E0 22 D3 49
vdr: [LOG535]2013/10/04 18:29:17  148DEC0    60 B6 A1 ED 72 7B 11 91 E8 71 4F 45 01 1F 69 CC
vdr: [LOG536]2013/10/04 18:29:17  148DEC0    C4 1F AB 66 E2 4F 00 BA 29 9C 66 02 45 8E B8 8E
vdr: [LOG537]2013/10/04 18:29:17  148DEC0    42 BA A6 9D 3F A4 11 76 08 92 4C B2 D7 6F 2C A4
vdr: [LOG538]2013/10/04 18:29:17  148DEC0    9F 12 8D 63 25 AC 69 38 31 58
vdr: [LOG539]2013/10/04 18:29:17  148DEC0 c [DVBAPI] Demuxer #0 ECMTYPE 81 CAID 093E PROVID 000000 ECMPID 0099 FAKECHID 00AC (unique part in ecm)
vdr: [LOG540]2013/10/04 18:29:17  148DEC0 c [DVBAPI] Demuxer #0 Filter #1 set ecmtable to EVEN (CAID 093E PROVID 000000 FD 14)
vdr: [LOG541]2013/10/04 18:29:17  148DEC0 c [DVBAPI] Demuxer #0 Filter #1 set chid to ANY CHID on fd 14
vdr: [LOG542]2013/10/04 18:29:17  148DEC0 c trying reader 'Viasat_Nordic_5E_(fin+dk)' filter 093E:000000
vdr: [LOG543]2013/10/04 18:29:17  148DEC0 c 093E:000000 allowed by reader 'Viasat_Nordic_5E_(fin+dk)' filter 093E:000000
vdr: [LOG544]2013/10/04 18:29:17  148DEC0 c [GET_CW] wait_time 0 caid 093E prov 000000 srvid 0834 rc 100 cacheex cl mode 0 ex1rdr 0
vdr: [LOG545]2013/10/04 18:29:17  148DEC0 c request_cw stage=3 to reader Viasat_Nordic_5E_(fin+dk) ecm hash=0F09CAE2FD2774925A77994EC821616A
vdr: [LOG546]2013/10/04 18:29:17  148DEC0 c add reader job action 5 queue length 1 Viasat_Nordic_5E_(fin+dk)
vdr: [LOG547]2013/10/04 18:29:17 28002C50 p start client thread action 32
vdr: [LOG548]2013/10/04 18:29:17  148DEC0 c [DVBAPI] Demuxer #0 request cw for ecm 093E&000000/00AC/0834/AA:0F09CAE2FD2774925A77994EC821616A
vdr: [LOG549]2013/10/04 18:29:17  148DEC0 c data from add_job action=32 client c anonymous
vdr: [LOG550]2013/10/04 18:29:17  148DEC0 c [DVBAPI] Demuxer #0 no controlword received for PID #1 CAID 093E PROVID 000000 ECMPID 0099 CHID 00AC VPID 0835
vdr: [LOG551]2013/10/04 18:29:17  148DEC0 c [DVBAPI] Demuxer #0 stop Filter #1 (fd: 14 api: 0, caid: 093E, provid: 000000, ecmpid: 0099)
vdr: [LOG552]2013/10/04 18:29:17  148DEC0 c anonymous (093E&000000/00AC/0834/AA:0F09CAE2FD2774925A77994EC821616A): timeout (0 ms) by Viasat_Nordic_5E_(fin+dk)
vdr: [LOG553]2013/10/04 18:29:17  148DEC0 c cw:
vdr: [LOG554]2013/10/04 18:29:17  148DEC0    00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
vdr: [LOG555]2013/10/04 18:29:17 28002C50 p [OSCAM-WORK] new event 1 occurred on fd 16 after 8801 ms inactivity
vdr: [LOG556]2013/10/04 18:29:17 28002C50 p nmr(): len=2, errno=0
vdr: [LOG557]2013/10/04 18:29:17 28002C50 p nmr: netMsgId=13, from server=13,
vdr: [LOG558]2013/10/04 18:29:17 28002C50 p received 5 bytes from remote server
vdr: [LOG559]2013/10/04 18:29:17 28002C50    00 0D 80 00 00
vdr: [LOG560]2013/10/04 18:29:17  148DEC0 c [DVBAPI] new events occurred on 1 of 3 handlers after 300 ms inactivity
vdr: [LOG561]2013/10/04 18:29:17  148DEC0 c [DVBAPI] now handling fd 14 that reported event 32
vdr: [LOG562]2013/10/04 18:29:17  148DEC0 c [DVBAPI] Demuxer #0 has 2 ecmpids, 3 streampids, 0 ecmfilters and 0 emmfilters
vdr: [LOG563]2013/10/04 18:29:17  148DEC0 c [DVBAPI] Demuxer #0 (re)starting decodingrequests on all 2 ecmpids!
vdr: [LOG564]2013/10/04 18:29:17  148DEC0 c [DVBAPI] Demuxer #0 no prio rules in oscam.dvbapi matches!
vdr: [LOG565]2013/10/04 18:29:17  148DEC0 c caid 090F not found in caidlist reader Viasat_Nordic_5E_(fin+dk)
vdr: [LOG566]2013/10/04 18:29:17  148DEC0 c [IGNORE PID 0] 090F:000000:083A (no matching reader)
vdr: [LOG567]2013/10/04 18:29:17  148DEC0 c trying reader 'Viasat_Nordic_5E_(fin+dk)' filter 093E:000000
vdr: [LOG568]2013/10/04 18:29:17  148DEC0 c 093E:000000 allowed by reader 'Viasat_Nordic_5E_(fin+dk)' filter 093E:000000
vdr: [LOG569]2013/10/04 18:29:17  148DEC0 c [PRIORITIZE PID 1] 093E:000000:0099 (rdr: Viasat_Nordic_5E_(fin+dk) weight: 1)
vdr: [LOG570]2013/10/04 18:29:17  148DEC0 c trying reader 'Viasat_Nordic_5E_(fin+dk)' filter 093E:000000
vdr: [LOG571]2013/10/04 18:29:17  148DEC0 c 093E:000000 allowed by reader 'Viasat_Nordic_5E_(fin+dk)' filter 093E:000000
vdr: [LOG572]2013/10/04 18:29:17  148DEC0 c [DVBAPI] Demuxer #0 trying to descramble PID #1 CAID 093E PROVID 000000 ECMPID 0099 ANY CHID VPID 0835
vdr: [LOG573]2013/10/04 18:29:17  148DEC0 c [DVBAPI] Demuxer #0 try to start new filter for caid: 093E, provid: 000000, pid: 0099
vdr: [LOG574]2013/10/04 18:29:17  148DEC0 c DEVICE open (/dev/dvb/adapter2/demux0) fd 14
vdr: [LOG575]2013/10/04 18:29:17  148DEC0 c [DVBAPI] Demuxer #0 Filter #1 started succesfully (caid 093E provid 000000 pid 0099)
vdr: [LOG576]2013/10/04 18:29:18  148DEC0 c [DVBAPI] Demuxer #0 has 2 ecmpids, 3 streampids, 1 ecmfilters and 0 emmfilters
vdr: [LOG577]2013/10/04 18:29:18  148DEC0 c [DVBAPI] new events occurred on 1 of 3 handlers after 601 ms inactivity
vdr: [LOG578]2013/10/04 18:29:18  148DEC0 c [DVBAPI] now handling fd 14 that reported event 3
vdr: [LOG579]2013/10/04 18:29:18  148DEC0 c [DVBAPI] Readed:
vdr: [LOG580]2013/10/04 18:29:18  148DEC0    81 70 A7 00 00 01 11 A5 04 7B A3 00 AC AA 55 15
vdr: [LOG581]2013/10/04 18:29:18  148DEC0    21 80 01 00 01 03 00 3F 91 7E 0A 8B 8C 80 F2 BD
vdr: [LOG582]2013/10/04 18:29:18  148DEC0    53 4A 43 00 00 47 08 89 4D 1F 81 C3 1C 22 CB 90
vdr: [LOG583]2013/10/04 18:29:18  148DEC0    79 C0 01 B6 4D DE 1D B7 78 B9 3B 00 0F 24 C6 77
vdr: [LOG584]2013/10/04 18:29:18  148DEC0    E2 E0 B8 26 C1 C0 D9 2E E9 C7 B9 C7 FA 3C 41 9C
vdr: [LOG585]2013/10/04 18:29:18  148DEC0    A9 24 8C C8 0D CD F0 BF 73 01 C3 A7 4A 47 CA 4D
vdr: [LOG586]2013/10/04 18:29:18  148DEC0    35 8C 2A 31 3E A1 63 E1 02 0F 3F CB E0 22 D3 49
vdr: [LOG587]2013/10/04 18:29:18  148DEC0    60 B6 A1 ED 72 7B 11 91 E8 71 4F 45 01 1F 69 CC
vdr: [LOG588]2013/10/04 18:29:18  148DEC0    C4 1F AB 66 E2 4F 00 BA 29 9C 66 02 45 8E B8 8E
vdr: [LOG589]2013/10/04 18:29:18  148DEC0    42 BA A6 9D 3F A4 11 76 08 92 4C B2 D7 6F 2C A4
vdr: [LOG590]2013/10/04 18:29:18  148DEC0    9F 12 8D 63 25 AC 69 38 31 58
vdr: [LOG591]2013/10/04 18:29:18  148DEC0 c [DVBAPI] Demuxer #0 ECMTYPE 81 CAID 093E PROVID 000000 ECMPID 0099 FAKECHID 00AC (unique part in ecm)
vdr: [LOG592]2013/10/04 18:29:18  148DEC0 c [DVBAPI] Demuxer #0 Filter #1 set ecmtable to EVEN (CAID 093E PROVID 000000 FD 14)
vdr: [LOG593]2013/10/04 18:29:18  148DEC0 c [DVBAPI] Demuxer #0 Filter #1 set chid to ANY CHID on fd 14
vdr: [LOG594]2013/10/04 18:29:18  148DEC0 c trying reader 'Viasat_Nordic_5E_(fin+dk)' filter 093E:000000
vdr: [LOG595]2013/10/04 18:29:18  148DEC0 c 093E:000000 allowed by reader 'Viasat_Nordic_5E_(fin+dk)' filter 093E:000000
vdr: [LOG596]2013/10/04 18:29:18  148DEC0 c [GET_CW] wait_time 0 caid 093E prov 000000 srvid 0834 rc 100 cacheex cl mode 0 ex1rdr 0
vdr: [LOG597]2013/10/04 18:29:18  148DEC0 c request_cw stage=3 to reader Viasat_Nordic_5E_(fin+dk) ecm hash=0F09CAE2FD2774925A77994EC821616A
vdr: [LOG598]2013/10/04 18:29:18  148DEC0 c add reader job action 5 queue length 1 Viasat_Nordic_5E_(fin+dk)
vdr: [LOG599]2013/10/04 18:29:18  148DEC0 c [DVBAPI] Demuxer #0 request cw for ecm 093E&000000/00AC/0834/AA:0F09CAE2FD2774925A77994EC821616A
vdr: [LOG600]2013/10/04 18:29:18 28002C50 p start client thread action 32
vdr: [LOG601]2013/10/04 18:29:18  148DEC0 c data from add_job action=32 client c anonymous
vdr: [LOG602]2013/10/04 18:29:18  148DEC0 c [DVBAPI] Demuxer #0 no controlword received for PID #1 CAID 093E PROVID 000000 ECMPID 0099 CHID 00AC VPID 0835
vdr: [LOG603]2013/10/04 18:29:18  148DEC0 c [DVBAPI] Demuxer #0 Filter #1 set ecmtable to ODD+EVEN (CAID 093E PROVID 000000 FD 14)
vdr: [LOG604]2013/10/04 18:29:18  148DEC0 c [DVBAPI] Demuxer #0 Filter #1 set chid to ANY CHID on fd 14
vdr: [LOG605]2013/10/04 18:29:18  148DEC0 c anonymous (093E&000000/00AC/0834/AA:0F09CAE2FD2774925A77994EC821616A): timeout (0 ms) by Viasat_Nordic_5E_(fin+dk)
vdr: [LOG606]2013/10/04 18:29:18  148DEC0 c cw:
vdr: [LOG607]2013/10/04 18:29:18  148DEC0    00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
vdr: [LOG608]2013/10/04 18:29:18        0   >> OSCam <<  cardserver log switched, version 1.20-unstable_svn, build r0 (x86_64-linux-gnu)
vdr: [LOG609]2013/10/04 18:29:18        0 s [OSCAM] new event 1 occurred on fd 6 after 1020 ms inactivity
vdr: [LOG610]2013/10/04 18:29:18        0 s got 21 bytes on port XXX from ip X.X.X.X:XXXX client MYUSERNAME
vdr: [LOG611]2013/10/04 18:29:18        0 s start client thread action 22
vdr: [LOG612]2013/10/04 18:29:18  14A8020 m data from add_job action=22 client m MYUSERNAME
vdr: [LOG613]2013/10/04 18:29:18  148DEC0 c [DVBAPI] new events occurred on 1 of 3 handlers after 97 ms inactivity
vdr: [LOG614]2013/10/04 18:29:18  148DEC0 c [DVBAPI] now handling fd 14 that reported event 3
vdr: [LOG615]2013/10/04 18:29:18  148DEC0 c [DVBAPI] Readed:
vdr: [LOG616]2013/10/04 18:29:18  148DEC0    81 70 A7 00 00 01 11 A5 04 7B A3 00 AC AA 55 15
vdr: [LOG617]2013/10/04 18:29:18  148DEC0    21 80 01 00 01 03 00 3F 91 7E 0A 8B 8C 80 F2 BD
vdr: [LOG618]2013/10/04 18:29:18  148DEC0    53 4A 43 00 00 47 08 89 4D 1F 81 C3 1C 22 CB 90
vdr: [LOG619]2013/10/04 18:29:18  148DEC0    79 C0 01 B6 4D DE 1D B7 78 B9 3B 00 0F 24 C6 77
vdr: [LOG620]2013/10/04 18:29:18  148DEC0    E2 E0 B8 26 C1 C0 D9 2E E9 C7 B9 C7 FA 3C 41 9C
vdr: [LOG621]2013/10/04 18:29:18  148DEC0    A9 24 8C C8 0D CD F0 BF 73 01 C3 A7 4A 47 CA 4D
vdr: [LOG622]2013/10/04 18:29:18  148DEC0    35 8C 2A 31 3E A1 63 E1 02 0F 3F CB E0 22 D3 49
vdr: [LOG623]2013/10/04 18:29:18  148DEC0    60 B6 A1 ED 72 7B 11 91 E8 71 4F 45 01 1F 69 CC
vdr: [LOG624]2013/10/04 18:29:18  148DEC0    C4 1F AB 66 E2 4F 00 BA 29 9C 66 02 45 8E B8 8E
vdr: [LOG625]2013/10/04 18:29:18  148DEC0    42 BA A6 9D 3F A4 11 76 08 92 4C B2 D7 6F 2C A4
vdr: [LOG626]2013/10/04 18:29:18  148DEC0    9F 12 8D 63 25 AC 69 38 31 58
vdr: [LOG627]2013/10/04 18:29:18  148DEC0 c [DVBAPI] Demuxer #0 ECMTYPE 81 CAID 093E PROVID 000000 ECMPID 0099 FAKECHID 00AC (unique part in ecm)
vdr: [LOG628]2013/10/04 18:29:18  148DEC0 c [DVBAPI] Demuxer #0 Filter #1 set ecmtable to EVEN (CAID 093E PROVID 000000 FD 14)
vdr: [LOG629]2013/10/04 18:29:18  148DEC0 c [DVBAPI] Demuxer #0 Filter #1 set chid to ANY CHID on fd 14
vdr: [LOG630]2013/10/04 18:29:18  148DEC0 c trying reader 'Viasat_Nordic_5E_(fin+dk)' filter 093E:000000
vdr: [LOG631]2013/10/04 18:29:18  148DEC0 c 093E:000000 allowed by reader 'Viasat_Nordic_5E_(fin+dk)' filter 093E:000000
vdr: [LOG632]2013/10/04 18:29:18  148DEC0 c [GET_CW] wait_time 0 caid 093E prov 000000 srvid 0834 rc 100 cacheex cl mode 0 ex1rdr 0
vdr: [LOG633]2013/10/04 18:29:18  148DEC0 c request_cw stage=3 to reader Viasat_Nordic_5E_(fin+dk) ecm hash=0F09CAE2FD2774925A77994EC821616A
vdr: [LOG634]2013/10/04 18:29:18  148DEC0 c add reader job action 5 queue length 1 Viasat_Nordic_5E_(fin+dk)
vdr: [LOG635]2013/10/04 18:29:18  148DEC0 c [DVBAPI] Demuxer #0 request cw for ecm 093E&000000/00AC/0834/AA:0F09CAE2FD2774925A77994EC821616A
vdr: [LOG636]2013/10/04 18:29:18 28002C50 p start client thread action 32
vdr: [LOG637]2013/10/04 18:29:18  148DEC0 c data from add_job action=32 client c anonymous
vdr: [LOG638]2013/10/04 18:29:18  148DEC0 c [DVBAPI] Demuxer #0 no controlword received for PID #1 CAID 093E PROVID 000000 ECMPID 0099 CHID 00AC VPID 0835
vdr: [LOG639]2013/10/04 18:29:18  148DEC0 c [DVBAPI] Demuxer #0 stop Filter #1 (fd: 14 api: 0, caid: 093E, provid: 000000, ecmpid: 0099)
vdr: [LOG640]2013/10/04 18:29:18  148DEC0 c anonymous (093E&000000/00AC/0834/AA:0F09CAE2FD2774925A77994EC821616A): timeout (0 ms) by Viasat_Nordic_5E_(fin+dk)
vdr: [LOG641]2013/10/04 18:29:18  148DEC0 c cw:
vdr: [LOG642]2013/10/04 18:29:18  148DEC0    00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
vdr: [LOG643]2013/10/04 18:29:18  148DEC0 c [DVBAPI] new events occurred on 1 of 3 handlers after 300 ms inactivity
vdr: [LOG644]2013/10/04 18:29:18  148DEC0 c [DVBAPI] now handling fd 14 that reported event 32
vdr: [LOG645]2013/10/04 18:29:18  148DEC0 c [DVBAPI] Demuxer #0 has 2 ecmpids, 3 streampids, 0 ecmfilters and 0 emmfilters
vdr: [LOG646]2013/10/04 18:29:18  148DEC0 c [DVBAPI] Demuxer #0 (re)starting decodingrequests on all 2 ecmpids!
vdr: [LOG647]2013/10/04 18:29:18  148DEC0 c [DVBAPI] Demuxer #0 no prio rules in oscam.dvbapi matches!
vdr: [LOG648]2013/10/04 18:29:18  148DEC0 c caid 090F not found in caidlist reader Viasat_Nordic_5E_(fin+dk)
vdr: [LOG649]2013/10/04 18:29:18  148DEC0 c [IGNORE PID 0] 090F:000000:083A (no matching reader)
vdr: [LOG650]2013/10/04 18:29:18  148DEC0 c trying reader 'Viasat_Nordic_5E_(fin+dk)' filter 093E:000000
vdr: [LOG651]2013/10/04 18:29:18  148DEC0 c 093E:000000 allowed by reader 'Viasat_Nordic_5E_(fin+dk)' filter 093E:000000
vdr: [LOG652]2013/10/04 18:29:18  148DEC0 c [PRIORITIZE PID 1] 093E:000000:0099 (rdr: Viasat_Nordic_5E_(fin+dk) weight: 1)
vdr: [LOG653]2013/10/04 18:29:18  148DEC0 c trying reader 'Viasat_Nordic_5E_(fin+dk)' filter 093E:000000
vdr: [LOG654]2013/10/04 18:29:18  148DEC0 c 093E:000000 allowed by reader 'Viasat_Nordic_5E_(fin+dk)' filter 093E:000000
vdr: [LOG655]2013/10/04 18:29:18  148DEC0 c [DVBAPI] Demuxer #0 trying to descramble PID #1 CAID 093E PROVID 000000 ECMPID 0099 ANY CHID VPID 0835
vdr: [LOG656]2013/10/04 18:29:18  148DEC0 c [DVBAPI] Demuxer #0 try to start new filter for caid: 093E, provid: 000000, pid: 0099
vdr: [LOG657]2013/10/04 18:29:18  148DEC0 c DEVICE open (/dev/dvb/adapter2/demux0) fd 14
vdr: [LOG658]2013/10/04 18:29:18  148DEC0 c [DVBAPI] Demuxer #0 Filter #1 started succesfully (caid 093E provid 000000 pid 0099)
vdr: [LOG659]2013/10/04 18:29:19  148DEC0 c [DVBAPI] Demuxer #0 has 2 ecmpids, 3 streampids, 1 ecmfilters and 0 emmfilters
vdr: [LOG660]2013/10/04 18:29:19  148DEC0 c [DVBAPI] new events occurred on 1 of 3 handlers after 601 ms inactivity
vdr: [LOG661]2013/10/04 18:29:19  148DEC0 c [DVBAPI] now handling fd 14 that reported event 3
vdr: [LOG662]2013/10/04 18:29:19  148DEC0 c [DVBAPI] Readed:
vdr: [LOG663]2013/10/04 18:29:19  148DEC0    80 70 A7 00 00 01 11 A5 04 7B A8 00 AC AA 55 15
vdr: [LOG664]2013/10/04 18:29:19  148DEC0    21 80 01 00 01 03 00 44 91 7E 0A C6 B5 E4 8B 61
vdr: [LOG665]2013/10/04 18:29:19  148DEC0    10 1A C8 00 00 47 08 28 73 51 3A 27 29 2F 15 90
vdr: [LOG666]2013/10/04 18:29:19  148DEC0    79 C0 01 E1 9A BC 79 21 03 56 36 97 A2 2C 8D 56
vdr: [LOG667]2013/10/04 18:29:19  148DEC0    8C D9 69 42 EF 68 88 0C 1A 6F 1D FC B6 15 24 C3
vdr: [LOG668]2013/10/04 18:29:19  148DEC0    EF D5 77 26 36 2D 65 5B 81 01 CC E9 BE 26 1C 6A
vdr: [LOG669]2013/10/04 18:29:19  148DEC0    3E 10 B4 74 3A 9C 74 9B B9 93 E7 8F 66 34 BA 14
vdr: [LOG670]2013/10/04 18:29:19  148DEC0    A4 14 2D AE 0D 54 FA 21 B0 F8 06 50 6B 7F B7 67
vdr: [LOG671]2013/10/04 18:29:19  148DEC0    A2 89 13 7E D5 67 B1 93 F8 29 15 CD CA 3B 10 89
vdr: [LOG672]2013/10/04 18:29:19  148DEC0    76 E2 FA 44 A4 8B EB D7 D0 B1 8C 3C 25 72 6D FD
vdr: [LOG673]2013/10/04 18:29:19  148DEC0    85 5C 59 D8 73 CE 97 42 30 51
vdr: [LOG674]2013/10/04 18:29:19  148DEC0 c [DVBAPI] Demuxer #0 ECMTYPE 80 CAID 093E PROVID 000000 ECMPID 0099 FAKECHID 00AC (unique part in ecm)
vdr: [LOG675]2013/10/04 18:29:19  148DEC0 c [DVBAPI] Demuxer #0 Filter #1 set ecmtable to ODD (CAID 093E PROVID 000000 FD 14)
vdr: [LOG676]2013/10/04 18:29:19  148DEC0 c [DVBAPI] Demuxer #0 Filter #1 set chid to ANY CHID on fd 14
vdr: [LOG677]2013/10/04 18:29:19  148DEC0 c trying reader 'Viasat_Nordic_5E_(fin+dk)' filter 093E:000000
vdr: [LOG678]2013/10/04 18:29:19  148DEC0 c 093E:000000 allowed by reader 'Viasat_Nordic_5E_(fin+dk)' filter 093E:000000
vdr: [LOG679]2013/10/04 18:29:19  148DEC0 c [GET_CW] wait_time 0 caid 093E prov 000000 srvid 0834 rc 100 cacheex cl mode 0 ex1rdr 0
vdr: [LOG680]2013/10/04 18:29:19  148DEC0 c request_cw stage=3 to reader Viasat_Nordic_5E_(fin+dk) ecm hash=7A6FE985C281A98A12B1EDA55DFBE4E6
vdr: [LOG681]2013/10/04 18:29:19  148DEC0 c add reader job action 5 queue length 1 Viasat_Nordic_5E_(fin+dk)
vdr: [LOG682]2013/10/04 18:29:19  148DEC0 c [DVBAPI] Demuxer #0 request cw for ecm 093E&000000/00AC/0834/AA:7A6FE985C281A98A12B1EDA55DFBE4E6
vdr: [LOG683]2013/10/04 18:29:19 28002C50 p ---- ecm_task 0, idx 14, sflag=1
vdr: [LOG684]2013/10/04 18:29:19 28002C50 p casc ecm (Viasat_Nordic_5E_(fin+dk)):
vdr: [LOG685]2013/10/04 18:29:19 28002C50    80 70 A7 00 00 01 11 A5 04 7B A8 00 AC AA 55 15
vdr: [LOG686]2013/10/04 18:29:19 28002C50    21 80 01 00 01 03 00 44 91 7E 0A C6 B5 E4 8B 61
vdr: [LOG687]2013/10/04 18:29:19 28002C50    10 1A C8 00 00 47 08 28 73 51 3A 27 29 2F 15 90
vdr: [LOG688]2013/10/04 18:29:19 28002C50    79 C0 01 E1 9A BC 79 21 03 56 36 97 A2 2C 8D 56
vdr: [LOG689]2013/10/04 18:29:19 28002C50    8C D9 69 42 EF 68 88 0C 1A 6F 1D FC B6 15 24 C3
vdr: [LOG690]2013/10/04 18:29:19 28002C50    EF D5 77 26 36 2D 65 5B 81 01 CC E9 BE 26 1C 6A
vdr: [LOG691]2013/10/04 18:29:19 28002C50    3E 10 B4 74 3A 9C 74 9B B9 93 E7 8F 66 34 BA 14
vdr: [LOG692]2013/10/04 18:29:19 28002C50    A4 14 2D AE 0D 54 FA 21 B0 F8 06 50 6B 7F B7 67
vdr: [LOG693]2013/10/04 18:29:19 28002C50    A2 89 13 7E D5 67 B1 93 F8 29 15 CD CA 3B 10 89
vdr: [LOG694]2013/10/04 18:29:19 28002C50    76 E2 FA 44 A4 8B EB D7 D0 B1 8C 3C 25 72 6D FD
vdr: [LOG695]2013/10/04 18:29:19 28002C50    85 5C 59 D8 73 CE 97 42 30 51
vdr: [LOG696]2013/10/04 18:29:19 28002C50 p trying server 'server IP.port' filter 093E:000000
vdr: [LOG697]2013/10/04 18:29:19 28002C50 p 093E:000000 allowed by server 'server IP.port' filter 093E:000000
vdr: [LOG698]2013/10/04 18:29:19 28002C50 p send 182 bytes to remote server
vdr: [LOG699]2013/10/04 18:29:19 28002C50    00 B4 00 0E 08 34 00 00 00 00 00 00 80 70 A7 00
vdr: [LOG700]2013/10/04 18:29:19 28002C50    00 01 11 A5 04 7B A8 00 AC AA 55 15 21 80 01 00
vdr: [LOG701]2013/10/04 18:29:19 28002C50    01 03 00 44 91 7E 0A C6 B5 E4 8B 61 10 1A C8 00
vdr: [LOG702]2013/10/04 18:29:19 28002C50    00 47 08 28 73 51 3A 27 29 2F 15 90 79 C0 01 E1
vdr: [LOG703]2013/10/04 18:29:19 28002C50    9A BC 79 21 03 56 36 97 A2 2C 8D 56 8C D9 69 42
vdr: [LOG704]2013/10/04 18:29:19 28002C50    EF 68 88 0C 1A 6F 1D FC B6 15 24 C3 EF D5 77 26
vdr: [LOG705]2013/10/04 18:29:19 28002C50    36 2D 65 5B 81 01 CC E9 BE 26 1C 6A 3E 10 B4 74
vdr: [LOG706]2013/10/04 18:29:19 28002C50    3A 9C 74 9B B9 93 E7 8F 66 34 BA 14 A4 14 2D AE
vdr: [LOG707]2013/10/04 18:29:19 28002C50    0D 54 FA 21 B0 F8 06 50 6B 7F B7 67 A2 89 13 7E
vdr: [LOG708]2013/10/04 18:29:19 28002C50    D5 67 B1 93 F8 29 15 CD CA 3B 10 89 76 E2 FA 44
vdr: [LOG709]2013/10/04 18:29:19 28002C50    A4 8B EB D7 D0 B1 8C 3C 25 72 6D FD 85 5C 59 D8
vdr: [LOG710]2013/10/04 18:29:19 28002C50    73 CE 97 42 30 51
vdr: [LOG711]2013/10/04 18:29:19        0   >> OSCam <<  cardserver log switched, version 1.20-unstable_svn, build r0 (x86_64-linux-gnu)
vdr: [LOG712]2013/10/04 18:29:19        0 s [OSCAM] new event 1 occurred on fd 6 after 1015 ms inactivity
vdr: [LOG713]2013/10/04 18:29:19        0 s got 21 bytes on port XXX from ip X.X.X.X:XXXX client MYUSERNAME
vdr: [LOG714]2013/10/04 18:29:19        0 s start client thread action 22
vdr: [LOG715]2013/10/04 18:29:19  14A8020 m data from add_job action=22 client m MYUSERNAME
vdr: [LOG716]2013/10/04 18:29:19        0   start client thread action 31
vdr: [LOG717]2013/10/04 18:29:19  148DEC0 c data from add_job action=31 client c anonymous
vdr: [LOG718]2013/10/04 18:29:20        0 s [OSCAM] new event 1 occurred on fd 6 after 1005 ms inactivity
vdr: [LOG719]2013/10/04 18:29:20        0 s got 21 bytes on port XXX from ip X.X.X.X:XXXX client MYUSERNAME
vdr: [LOG720]2013/10/04 18:29:20        0 s start client thread action 22
vdr: [LOG721]2013/10/04 18:29:20  14A8020 m data from add_job action=22 client m MYUSERNAME
vdr: [LOG722]2013/10/04 18:29:20        0   start client thread action 31
vdr: [LOG723]2013/10/04 18:29:20  148DEC0 c data from add_job action=31 client c anonymous
vdr: [LOG724]2013/10/04 18:29:20        0   start client thread action 31
vdr: [LOG725]2013/10/04 18:29:20  148DEC0 c data from add_job action=31 client c anonymous
vdr: [LOG726]2013/10/04 18:29:21        0   start client thread action 31
vdr: [LOG727]2013/10/04 18:29:21  148DEC0 c data from add_job action=31 client c anonymous
vdr: [LOG728]2013/10/04 18:29:21        0 s [OSCAM] new event 1 occurred on fd 6 after 1039 ms inactivity
vdr: [LOG729]2013/10/04 18:29:21        0 s got 21 bytes on port XXX from ip X.X.X.X:XXXX client MYUSERNAME
vdr: [LOG730]2013/10/04 18:29:21        0 s start client thread action 22
vdr: [LOG731]2013/10/04 18:29:21  14A8020 m data from add_job action=22 client m MYUSERNAME
vdr: [LOG732]2013/10/04 18:29:21        0   start client thread action 31
vdr: [LOG733]2013/10/04 18:29:21  148DEC0 c data from add_job action=31 client c anonymous
vdr: [LOG734]2013/10/04 18:29:21        0   start client thread action 30
vdr: [LOG735]2013/10/04 18:29:21  148DEC0 c data from add_job action=30 client c anonymous
vdr: [LOG736]2013/10/04 18:29:21  148DEC0 c fallback for anonymous 093E&000000/00AC/0834/AA:7A6FE985C281A98A12B1EDA55DFBE4E6
vdr: [LOG737]2013/10/04 18:29:22        0   start client thread action 31
vdr: [LOG738]2013/10/04 18:29:22        0   add client job action 31 queue length 1 anonymous
vdr: [LOG739]2013/10/04 18:29:22  148DEC0 c data from add_job action=31 client c anonymous
vdr: [LOG740]2013/10/04 18:29:22        0 s [OSCAM] new event 1 occurred on fd 6 after 1005 ms inactivity
vdr: [LOG741]2013/10/04 18:29:22        0 s got 21 bytes on port XXX from ip X.X.X.X:XXXX client MYUSERNAME
vdr: [LOG742]2013/10/04 18:29:22        0 s start client thread action 22
vdr: [LOG743]2013/10/04 18:29:22  14A8020 m data from add_job action=22 client m MYUSERNAME
vdr: [LOG744]2013/10/04 18:29:23        0   start client thread action 31
vdr: [LOG745]2013/10/04 18:29:23  148DEC0 c data from add_job action=31 client c anonymous
vdr: [LOG746]2013/10/04 18:29:23        0   start client thread action 31
vdr: [LOG747]2013/10/04 18:29:23  148DEC0 c data from add_job action=31 client c anonymous
vdr: [LOG748]2013/10/04 18:29:23        0 s [OSCAM] new event 1 occurred on fd 6 after 998 ms inactivity
vdr: [LOG749]2013/10/04 18:29:23        0 s got 21 bytes on port XXX from ip X.X.X.X:XXXX client MYUSERNAME
vdr: [LOG750]2013/10/04 18:29:23        0 s start client thread action 22
vdr: [LOG751]2013/10/04 18:29:23  14A8020 m data from add_job action=22 client m MYUSERNAME
vdr: [LOG752]2013/10/04 18:29:24        0   start client thread action 31
vdr: [LOG753]2013/10/04 18:29:24  148DEC0 c data from add_job action=31 client c anonymous
vdr: [LOG754]2013/10/04 18:29:24  148DEC0 c timeout for anonymous 093E&000000/00AC/0834/AA:7A6FE985C281A98A12B1EDA55DFBE4E6
vdr: [LOG755]2013/10/04 18:29:24  148DEC0 c add client job action 32 queue length 1 anonymous
vdr: [LOG756]2013/10/04 18:29:24  148DEC0 c Viasat_Nordic_5E_(fin+dk) [newcamd] TRACE: ecm answer for ecm hash 7A6FE985C281A98A12B1EDA55DFBE4E6 rc=5
vdr: [LOG757]2013/10/04 18:29:24  148DEC0 c [DVBAPI] Demuxer #0 no controlword received for PID #1 CAID 093E PROVID 000000 ECMPID 0099 CHID 00AC VPID 0835
vdr: [LOG758]2013/10/04 18:29:24  148DEC0 c [DVBAPI] Demuxer #0 Filter #1 set ecmtable to ODD+EVEN (CAID 093E PROVID 000000 FD 14)
vdr: [LOG759]2013/10/04 18:29:24  148DEC0 c [DVBAPI] Demuxer #0 Filter #1 set chid to ANY CHID on fd 14
vdr: [LOG760]2013/10/04 18:29:24  148DEC0 c anonymous (093E&000000/00AC/0834/AA:7A6FE985C281A98A12B1EDA55DFBE4E6): timeout (5000 ms) by Viasat_Nordic_5E_(fin+dk)
vdr: [LOG761]2013/10/04 18:29:24  148DEC0 c cw:
vdr: [LOG762]2013/10/04 18:29:24  148DEC0    00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
vdr: [LOG763]2013/10/04 18:29:24  148DEC0 c [DVBAPI] new events occurred on 1 of 3 handlers after 5039 ms inactivity
vdr: [LOG764]2013/10/04 18:29:24  148DEC0 c [DVBAPI] now handling fd 14 that reported event 3
vdr: [LOG765]2013/10/04 18:29:24  148DEC0 c [DVBAPI] Readed:
vdr: [LOG766]2013/10/04 18:29:24  148DEC0    80 70 A7 00 00 01 11 A5 04 7B A8 00 AC AA 55 15
vdr: [LOG767]2013/10/04 18:29:24  148DEC0    21 80 01 00 01 03 00 44 91 7E 0A C6 B5 E4 8B 61
vdr: [LOG768]2013/10/04 18:29:24  148DEC0    10 1A C8 00 00 47 08 28 73 51 3A 27 29 2F 15 90
vdr: [LOG769]2013/10/04 18:29:24  148DEC0    79 C0 01 E1 9A BC 79 21 03 56 36 97 A2 2C 8D 56
vdr: [LOG770]2013/10/04 18:29:24  148DEC0    8C D9 69 42 EF 68 88 0C 1A 6F 1D FC B6 15 24 C3
vdr: [LOG771]2013/10/04 18:29:24  148DEC0    EF D5 77 26 36 2D 65 5B 81 01 CC E9 BE 26 1C 6A
vdr: [LOG772]2013/10/04 18:29:24  148DEC0    3E 10 B4 74 3A 9C 74 9B B9 93 E7 8F 66 34 BA 14
vdr: [LOG773]2013/10/04 18:29:24  148DEC0    A4 14 2D AE 0D 54 FA 21 B0 F8 06 50 6B 7F B7 67
vdr: [LOG774]2013/10/04 18:29:24  148DEC0    A2 89 13 7E D5 67 B1 93 F8 29 15 CD CA 3B 10 89
vdr: [LOG775]2013/10/04 18:29:24  148DEC0    76 E2 FA 44 A4 8B EB D7 D0 B1 8C 3C 25 72 6D FD
vdr: [LOG776]2013/10/04 18:29:24  148DEC0    85 5C 59 D8 73 CE 97 42 30 51
vdr: [LOG777]2013/10/04 18:29:24  148DEC0 c [DVBAPI] Demuxer #0 ECMTYPE 80 CAID 093E PROVID 000000 ECMPID 0099 FAKECHID 00AC (unique part in ecm)
vdr: [LOG778]2013/10/04 18:29:24  148DEC0 c [DVBAPI] Demuxer #0 Filter #1 set ecmtable to ODD (CAID 093E PROVID 000000 FD 14)
vdr: [LOG779]2013/10/04 18:29:24  148DEC0 c [DVBAPI] Demuxer #0 Filter #1 set chid to ANY CHID on fd 14
vdr: [LOG780]2013/10/04 18:29:24  148DEC0 c trying reader 'Viasat_Nordic_5E_(fin+dk)' filter 093E:000000
vdr: [LOG781]2013/10/04 18:29:24  148DEC0 c 093E:000000 allowed by reader 'Viasat_Nordic_5E_(fin+dk)' filter 093E:000000
vdr: [LOG782]2013/10/04 18:29:24  148DEC0 c [GET_CW] wait_time 0 caid 093E prov 000000 srvid 0834 rc 100 cacheex cl mode 0 ex1rdr 0
vdr: [LOG783]2013/10/04 18:29:24  148DEC0 c request_cw stage=3 to reader Viasat_Nordic_5E_(fin+dk) ecm hash=7A6FE985C281A98A12B1EDA55DFBE4E6
vdr: [LOG784]2013/10/04 18:29:24  148DEC0 c add reader job action 5 queue length 1 Viasat_Nordic_5E_(fin+dk)
vdr: [LOG785]2013/10/04 18:29:24  148DEC0 c [DVBAPI] Demuxer #0 request cw for ecm 093E&000000/00AC/0834/AA:7A6FE985C281A98A12B1EDA55DFBE4E6
vdr: [LOG786]2013/10/04 18:29:24 28002C50 p start client thread action 32
vdr: [LOG787]2013/10/04 18:29:24  148DEC0 c data from add_job action=32 client c anonymous
vdr: [LOG788]2013/10/04 18:29:24  148DEC0 c [DVBAPI] Demuxer #0 no controlword received for PID #1 CAID 093E PROVID 000000 ECMPID 0099 CHID 00AC VPID 0835
vdr: [LOG789]2013/10/04 18:29:24  148DEC0 c [DVBAPI] Demuxer #0 stop Filter #1 (fd: 14 api: 0, caid: 093E, provid: 000000, ecmpid: 0099)
vdr: [LOG790]2013/10/04 18:29:24  148DEC0 c anonymous (093E&000000/00AC/0834/AA:7A6FE985C281A98A12B1EDA55DFBE4E6): timeout (0 ms) by Viasat_Nordic_5E_(fin+dk)
vdr: [LOG791]2013/10/04 18:29:24  148DEC0 c cw:
vdr: [LOG792]2013/10/04 18:29:24  148DEC0    00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
vdr: [LOG793]2013/10/04 18:29:24        0 s [OSCAM] new event 1 occurred on fd 6 after 1023 ms inactivity
vdr: [LOG794]2013/10/04 18:29:24        0 s got 21 bytes on port XXX from ip X.X.X.X:XXXX client MYUSERNAME
vdr: [LOG795]2013/10/04 18:29:24        0 s start client thread action 22
vdr: [LOG796]2013/10/04 18:29:24  14A8020 m data from add_job action=22 client m MYUSERNAME
vdr: [LOG797]2013/10/04 18:29:24  148DEC0 c [DVBAPI] new events occurred on 1 of 3 handlers after 300 ms inactivity
vdr: [LOG798]2013/10/04 18:29:24  148DEC0 c [DVBAPI] now handling fd 14 that reported event 32
vdr: [LOG799]2013/10/04 18:29:24  148DEC0 c [DVBAPI] Demuxer #0 has 2 ecmpids, 3 streampids, 0 ecmfilters and 0 emmfilters
vdr: [LOG800]2013/10/04 18:29:24  148DEC0 c [DVBAPI] Demuxer #0 (re)starting decodingrequests on all 2 ecmpids!
vdr: [LOG801]2013/10/04 18:29:24  148DEC0 c [DVBAPI] Demuxer #0 no prio rules in oscam.dvbapi matches!
vdr: [LOG802]2013/10/04 18:29:24  148DEC0 c caid 090F not found in caidlist reader Viasat_Nordic_5E_(fin+dk)
vdr: [LOG803]2013/10/04 18:29:24  148DEC0 c [IGNORE PID 0] 090F:000000:083A (no matching reader)
vdr: [LOG804]2013/10/04 18:29:24  148DEC0 c trying reader 'Viasat_Nordic_5E_(fin+dk)' filter 093E:000000
vdr: [LOG805]2013/10/04 18:29:24  148DEC0 c 093E:000000 allowed by reader 'Viasat_Nordic_5E_(fin+dk)' filter 093E:000000
vdr: [LOG806]2013/10/04 18:29:24  148DEC0 c [PRIORITIZE PID 1] 093E:000000:0099 (rdr: Viasat_Nordic_5E_(fin+dk) weight: 1)
vdr: [LOG807]2013/10/04 18:29:24  148DEC0 c trying reader 'Viasat_Nordic_5E_(fin+dk)' filter 093E:000000
vdr: [LOG808]2013/10/04 18:29:24  148DEC0 c 093E:000000 allowed by reader 'Viasat_Nordic_5E_(fin+dk)' filter 093E:000000
vdr: [LOG809]2013/10/04 18:29:24  148DEC0 c [DVBAPI] Demuxer #0 trying to descramble PID #1 CAID 093E PROVID 000000 ECMPID 0099 ANY CHID VPID 0835
vdr: [LOG810]2013/10/04 18:29:24  148DEC0 c [DVBAPI] Demuxer #0 try to start new filter for caid: 093E, provid: 000000, pid: 0099
vdr: [LOG811]2013/10/04 18:29:24  148DEC0 c DEVICE open (/dev/dvb/adapter2/demux0) fd 14
vdr: [LOG812]2013/10/04 18:29:24  148DEC0 c [DVBAPI] Demuxer #0 Filter #1 started succesfully (caid 093E provid 000000 pid 0099)
vdr: [LOG813]2013/10/04 18:29:24        0   >> OSCam <<  cardserver log switched, version 1.20-unstable_svn, build r0 (x86_64-linux-gnu)
vdr: [LOG814]2013/10/04 18:29:24  148DEC0 c [DVBAPI] Demuxer #0 has 2 ecmpids, 3 streampids, 1 ecmfilters and 0 emmfilters
vdr: [LOG815]2013/10/04 18:29:24  148DEC0 c [DVBAPI] new events occurred on 1 of 3 handlers after 601 ms inactivity
vdr: [LOG816]2013/10/04 18:29:24  148DEC0 c [DVBAPI] now handling fd 14 that reported event 3
vdr: [LOG817]2013/10/04 18:29:24  148DEC0 c [DVBAPI] Readed:
vdr: [LOG818]2013/10/04 18:29:24  148DEC0    80 70 A7 00 00 01 11 A5 04 7B A8 00 AC AA 55 15
vdr: [LOG819]2013/10/04 18:29:24  148DEC0    21 80 01 00 01 03 00 44 91 7E 0A C6 B5 E4 8B 61
vdr: [LOG820]2013/10/04 18:29:24  148DEC0    10 1A C8 00 00 47 08 28 73 51 3A 27 29 2F 15 90
vdr: [LOG821]2013/10/04 18:29:24  148DEC0    79 C0 01 E1 9A BC 79 21 03 56 36 97 A2 2C 8D 56
vdr: [LOG822]2013/10/04 18:29:24  148DEC0    8C D9 69 42 EF 68 88 0C 1A 6F 1D FC B6 15 24 C3
vdr: [LOG823]2013/10/04 18:29:24  148DEC0    EF D5 77 26 36 2D 65 5B 81 01 CC E9 BE 26 1C 6A
vdr: [LOG824]2013/10/04 18:29:24  148DEC0    3E 10 B4 74 3A 9C 74 9B B9 93 E7 8F 66 34 BA 14
vdr: [LOG825]2013/10/04 18:29:24  148DEC0    A4 14 2D AE 0D 54 FA 21 B0 F8 06 50 6B 7F B7 67
vdr: [LOG826]2013/10/04 18:29:24  148DEC0    A2 89 13 7E D5 67 B1 93 F8 29 15 CD CA 3B 10 89
vdr: [LOG827]2013/10/04 18:29:24  148DEC0    76 E2 FA 44 A4 8B EB D7 D0 B1 8C 3C 25 72 6D FD
vdr: [LOG828]2013/10/04 18:29:24  148DEC0    85 5C 59 D8 73 CE 97 42 30 51
vdr: [LOG829]2013/10/04 18:29:24  148DEC0 c [DVBAPI] Demuxer #0 ECMTYPE 80 CAID 093E PROVID 000000 ECMPID 0099 FAKECHID 00AC (unique part in ecm)
vdr: [LOG830]2013/10/04 18:29:24  148DEC0 c [DVBAPI] Demuxer #0 Filter #1 set ecmtable to ODD (CAID 093E PROVID 000000 FD 14)
vdr: [LOG831]2013/10/04 18:29:24  148DEC0 c [DVBAPI] Demuxer #0 Filter #1 set chid to ANY CHID on fd 14
vdr: [LOG832]2013/10/04 18:29:24  148DEC0 c trying reader 'Viasat_Nordic_5E_(fin+dk)' filter 093E:000000
vdr: [LOG833]2013/10/04 18:29:24  148DEC0 c 093E:000000 allowed by reader 'Viasat_Nordic_5E_(fin+dk)' filter 093E:000000
vdr: [LOG834]2013/10/04 18:29:24  148DEC0 c [GET_CW] wait_time 0 caid 093E prov 000000 srvid 0834 rc 100 cacheex cl mode 0 ex1rdr 0
vdr: [LOG835]2013/10/04 18:29:24  148DEC0 c request_cw stage=3 to reader Viasat_Nordic_5E_(fin+dk) ecm hash=7A6FE985C281A98A12B1EDA55DFBE4E6
vdr: [LOG836]2013/10/04 18:29:24  148DEC0 c add reader job action 5 queue length 1 Viasat_Nordic_5E_(fin+dk)
vdr: [LOG837]2013/10/04 18:29:24  148DEC0 c [DVBAPI] Demuxer #0 request cw for ecm 093E&000000/00AC/0834/AA:7A6FE985C281A98A12B1EDA55DFBE4E6
vdr: [LOG838]2013/10/04 18:29:24 28002C50 p start client thread action 32
vdr: [LOG839]2013/10/04 18:29:24  148DEC0 c data from add_job action=32 client c anonymous
vdr: [LOG840]2013/10/04 18:29:24  148DEC0 c [DVBAPI] Demuxer #0 no controlword received for PID #1 CAID 093E PROVID 000000 ECMPID 0099 CHID 00AC VPID 0835
vdr: [LOG841]2013/10/04 18:29:24  148DEC0 c [DVBAPI] Demuxer #0 Filter #1 set ecmtable to ODD+EVEN (CAID 093E PROVID 000000 FD 14)
vdr: [LOG842]2013/10/04 18:29:24  148DEC0 c [DVBAPI] Demuxer #0 Filter #1 set chid to ANY CHID on fd 14
vdr: [LOG843]2013/10/04 18:29:24  148DEC0 c anonymous (093E&000000/00AC/0834/AA:7A6FE985C281A98A12B1EDA55DFBE4E6): timeout (0 ms) by Viasat_Nordic_5E_(fin+dk)
vdr: [LOG844]2013/10/04 18:29:24  148DEC0 c cw:
vdr: [LOG845]2013/10/04 18:29:24  148DEC0    00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
vdr: [LOG846]2013/10/04 18:29:24  148DEC0 c [DVBAPI] new events occurred on 1 of 3 handlers after 0 ms inactivity
vdr: [LOG847]2013/10/04 18:29:24  148DEC0 c [DVBAPI] now handling fd 14 that reported event 3
vdr: [LOG848]2013/10/04 18:29:24  148DEC0 c [DVBAPI] Readed:
vdr: [LOG849]2013/10/04 18:29:24  148DEC0    80 70 A7 00 00 01 11 A5 04 7B A8 00 AC AA 55 15
vdr: [LOG850]2013/10/04 18:29:24  148DEC0    21 80 01 00 01 03 00 44 91 7E 0A C6 B5 E4 8B 61
vdr: [LOG851]2013/10/04 18:29:24  148DEC0    10 1A C8 00 00 47 08 28 73 51 3A 27 29 2F 15 90
vdr: [LOG852]2013/10/04 18:29:24  148DEC0    79 C0 01 E1 9A BC 79 21 03 56 36 97 A2 2C 8D 56
vdr: [LOG853]2013/10/04 18:29:24  148DEC0    8C D9 69 42 EF 68 88 0C 1A 6F 1D FC B6 15 24 C3
vdr: [LOG854]2013/10/04 18:29:24  148DEC0    EF D5 77 26 36 2D 65 5B 81 01 CC E9 BE 26 1C 6A
vdr: [LOG855]2013/10/04 18:29:24  148DEC0    3E 10 B4 74 3A 9C 74 9B B9 93 E7 8F 66 34 BA 14
vdr: [LOG856]2013/10/04 18:29:24  148DEC0    A4 14 2D AE 0D 54 FA 21 B0 F8 06 50 6B 7F B7 67
vdr: [LOG857]2013/10/04 18:29:24  148DEC0    A2 89 13 7E D5 67 B1 93 F8 29 15 CD CA 3B 10 89
vdr: [LOG858]2013/10/04 18:29:24  148DEC0    76 E2 FA 44 A4 8B EB D7 D0 B1 8C 3C 25 72 6D FD
vdr: [LOG859]2013/10/04 18:29:24  148DEC0    85 5C 59 D8 73 CE 97 42 30 51
vdr: [LOG860]2013/10/04 18:29:24  148DEC0 c [DVBAPI] Demuxer #0 ECMTYPE 80 CAID 093E PROVID 000000 ECMPID 0099 FAKECHID 00AC (unique part in ecm)
vdr: [LOG861]2013/10/04 18:29:24  148DEC0 c [DVBAPI] Demuxer #0 Filter #1 set ecmtable to ODD (CAID 093E PROVID 000000 FD 14)
vdr: [LOG862]2013/10/04 18:29:24  148DEC0 c [DVBAPI] Demuxer #0 Filter #1 set chid to ANY CHID on fd 14
vdr: [LOG863]2013/10/04 18:29:24  148DEC0 c trying reader 'Viasat_Nordic_5E_(fin+dk)' filter 093E:000000
vdr: [LOG864]2013/10/04 18:29:24  148DEC0 c 093E:000000 allowed by reader 'Viasat_Nordic_5E_(fin+dk)' filter 093E:000000
vdr: [LOG865]2013/10/04 18:29:24  148DEC0 c [GET_CW] wait_time 0 caid 093E prov 000000 srvid 0834 rc 100 cacheex cl mode 0 ex1rdr 0
vdr: [LOG866]2013/10/04 18:29:24  148DEC0 c request_cw stage=3 to reader Viasat_Nordic_5E_(fin+dk) ecm hash=7A6FE985C281A98A12B1EDA55DFBE4E6
vdr: [LOG867]2013/10/04 18:29:24  148DEC0 c add reader job action 5 queue length 1 Viasat_Nordic_5E_(fin+dk)
vdr: [LOG868]2013/10/04 18:29:24 28002C50 p start client thread action 32
vdr: [LOG869]2013/10/04 18:29:24  148DEC0 c [DVBAPI] Demuxer #0 request cw for ecm 093E&000000/00AC/0834/AA:7A6FE985C281A98A12B1EDA55DFBE4E6
vdr: [LOG870]2013/10/04 18:29:24  148DEC0 c data from add_job action=32 client c anonymous
vdr: [LOG871]2013/10/04 18:29:24  148DEC0 c [DVBAPI] Demuxer #0 no controlword received for PID #1 CAID 093E PROVID 000000 ECMPID 0099 CHID 00AC VPID 0835
vdr: [LOG872]2013/10/04 18:29:24  148DEC0 c [DVBAPI] Demuxer #0 stop Filter #1 (fd: 14 api: 0, caid: 093E, provid: 000000, ecmpid: 0099)
vdr: [LOG873]2013/10/04 18:29:24  148DEC0 c anonymous (093E&000000/00AC/0834/AA:7A6FE985C281A98A12B1EDA55DFBE4E6): timeout (0 ms) by Viasat_Nordic_5E_(fin+dk)
vdr: [LOG874]2013/10/04 18:29:24  148DEC0 c cw:
vdr: [LOG875]2013/10/04 18:29:24  148DEC0    00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
vdr: [LOG876]2013/10/04 18:29:25        0 s [OSCAM] new event 1 occurred on fd 6 after 1018 ms inactivity
vdr: [LOG877]2013/10/04 18:29:25        0 s got 21 bytes on port XXX from ip X.X.X.X:XXXX client MYUSERNAME
vdr: [LOG878]2013/10/04 18:29:25        0 s start client thread action 22
vdr: [LOG879]2013/10/04 18:29:25  14A8020 m data from add_job action=22 client m MYUSERNAME
vdr: [LOG880]2013/10/04 18:29:25        0 s [OSCAM] new event 1 occurred on fd 6 after 43 ms inactivity
vdr: [LOG881]2013/10/04 18:29:25        0 s got 21 bytes on port XXX from ip X.X.X.X:XXXX client MYUSERNAME
vdr: [LOG882]2013/10/04 18:29:25        0 s start client thread action 22
vdr: [LOG883]2013/10/04 18:29:25  14A8020 m data from add_job action=22 client m MYUSERNAME
vdr: [LOG884]2013/10/04 18:29:25  14A8020 m all debug_level=0
vdr: [LOG885]2013/10/04 18:29:25  148DEC0 c [DVBAPI] Demuxer #0 (re)starting decodingrequests on all 2 ecmpids!
vdr: [LOG886]2013/10/04 18:29:25  148DEC0 c [DVBAPI] Demuxer #0 trying to descramble PID #1 CAID 093E PROVID 000000 ECMPID 0099 ANY CHID VPID 0835
vdr: [LOG887]2013/10/04 18:29:25  148DEC0 c anonymous (093E&000000/00AC/0834/AA:7A6FE985C281A98A12B1EDA55DFBE4E6): timeout (0 ms) by Viasat_Nordic_5E_(fin+dk)
vdr: [LOG888]2013/10/04 18:29:26        0   --- Skipped 1 duplicated log lines ---
vdr: [LOG889]2013/10/04 18:29:26  148DEC0 c [DVBAPI] Demuxer #0 (re)starting decodingrequests on all 2 ecmpids!
vdr: [LOG890]2013/10/04 18:29:26  148DEC0 c [DVBAPI] Demuxer #0 trying to descramble PID #1 CAID 093E PROVID 000000 ECMPID 0099 ANY CHID VPID 0835
vdr: [LOG891]2013/10/04 18:29:26  148DEC0 c anonymous (093E&000000/00AC/0834/AA:7A6FE985C281A98A12B1EDA55DFBE4E6): timeout (0 ms) by Viasat_Nordic_5E_(fin+dk)
vdr: [LOG892]2013/10/04 18:29:27        0   --- Skipped 1 duplicated log lines ---
vdr: [LOG893]2013/10/04 18:29:27  148DEC0 c [DVBAPI] Demuxer #0 (re)starting decodingrequests on all 2 ecmpids!
vdr: [LOG894]2013/10/04 18:29:27  148DEC0 c [DVBAPI] Demuxer #0 trying to descramble PID #1 CAID 093E PROVID 000000 ECMPID 0099 ANY CHID VPID 0835
vdr: [LOG895]2013/10/04 18:29:27  148DEC0 c anonymous (093E&000000/00AC/0834/AA:7A6FE985C281A98A12B1EDA55DFBE4E6): timeout (0 ms) by Viasat_Nordic_5E_(fin+dk)
vdr: [LOG896]2013/10/04 18:29:28        0   --- Skipped 1 duplicated log lines ---
vdr: [LOG897]2013/10/04 18:29:28  148DEC0 c [DVBAPI] Demuxer #0 (re)starting decodingrequests on all 2 ecmpids!
vdr: [LOG898]2013/10/04 18:29:28  148DEC0 c [DVBAPI] Demuxer #0 trying to descramble PID #1 CAID 093E PROVID 000000 ECMPID 0099 ANY CHID VPID 0835
vdr: [LOG899]2013/10/04 18:29:28  148DEC0 c anonymous (093E&000000/00AC/0834/AA:7A6FE985C281A98A12B1EDA55DFBE4E6): timeout (0 ms) by Viasat_Nordic_5E_(fin+dk)
vdr: [LOG900]2013/10/04 18:29:28        0   --- Skipped 1 duplicated log lines ---
vdr: [LOG901]2013/10/04 18:29:28  148DEC0 c [DVBAPI] Demuxer #0 (re)starting decodingrequests on all 2 ecmpids!
vdr: [LOG902]2013/10/04 18:29:29  148DEC0 c [DVBAPI] Demuxer #0 trying to descramble PID #1 CAID 093E PROVID 000000 ECMPID 0099 ANY CHID VPID 0835
vdr: [LOG903]2013/10/04 18:29:34  148DEC0 c anonymous (093E&000000/00AC/0834/AA:8D470A0EDF07F570F4FCE93FD0301808): timeout (5000 ms) by Viasat_Nordic_5E_(fin+dk)
vdr: [LOG904]2013/10/04 18:29:34  148DEC0 c anonymous (093E&000000/00AC/0834/AA:8D470A0EDF07F570F4FCE93FD0301808): timeout (0 ms) by Viasat_Nordic_5E_(fin+dk)
vdr: [LOG905]2013/10/04 18:29:34  148DEC0 c [DVBAPI] Demuxer #0 (re)starting decodingrequests on all 2 ecmpids!
vdr: [LOG906]2013/10/04 18:29:35  148DEC0 c [DVBAPI] Demuxer #0 trying to descramble PID #1 CAID 093E PROVID 000000 ECMPID 0099 ANY CHID VPID 0835
vdr: [LOG907]2013/10/04 18:29:35  148DEC0 c anonymous (093E&000000/00AC/0834/AA:8D470A0EDF07F570F4FCE93FD0301808): timeout (1 ms) by Viasat_Nordic_5E_(fin+dk)
vdr: [LOG908]2013/10/04 18:29:35  148DEC0 c anonymous (093E&000000/00AC/0834/AA:8D470A0EDF07F570F4FCE93FD0301808): timeout (0 ms) by Viasat_Nordic_5E_(fin+dk)
vdr: [LOG909]2013/10/04 18:29:35        0   >> OSCam <<  cardserver log switched, version 1.20-unstable_svn, build r0 (x86_64-linux-gnu)
vdr: [LOG910]2013/10/04 18:29:35  148DEC0 c [DVBAPI] Demuxer #0 (re)starting decodingrequests on all 2 ecmpids!
vdr: [LOG911]2013/10/04 18:29:36  148DEC0 c [DVBAPI] Demuxer #0 trying to descramble PID #1 CAID 093E PROVID 000000 ECMPID 0099 ANY CHID VPID 0835
vdr: [LOG912]2013/10/04 18:29:36  148DEC0 c anonymous (093E&000000/00AC/0834/AA:8D470A0EDF07F570F4FCE93FD0301808): timeout (0 ms) by Viasat_Nordic_5E_(fin+dk)
vdr: [LOG913]2013/10/04 18:29:36        0   --- Skipped 1 duplicated log lines ---
vdr: [LOG914]2013/10/04 18:29:36  148DEC0 c [DVBAPI] Demuxer #0 (re)starting decodingrequests on all 2 ecmpids!
vdr: [LOG915]2013/10/04 18:29:37  148DEC0 c [DVBAPI] Demuxer #0 trying to descramble PID #1 CAID 093E PROVID 000000 ECMPID 0099 ANY CHID VPID 0835
vdr: [LOG916]2013/10/04 18:29:37  148DEC0 c anonymous (093E&000000/00AC/0834/AA:8D470A0EDF07F570F4FCE93FD0301808): timeout (0 ms) by Viasat_Nordic_5E_(fin+dk)
vdr: [LOG917]2013/10/04 18:29:37        0   --- Skipped 1 duplicated log lines ---
vdr: [LOG918]2013/10/04 18:29:37  148DEC0 c [DVBAPI] Demuxer #0 (re)starting decodingrequests on all 2 ecmpids!
vdr: [LOG919]2013/10/04 18:29:38  148DEC0 c [DVBAPI] Demuxer #0 trying to descramble PID #1 CAID 093E PROVID 000000 ECMPID 0099 ANY CHID VPID 0835
vdr: [LOG920]2013/10/04 18:29:38  148DEC0 c anonymous (093E&000000/00AC/0834/AA:8D470A0EDF07F570F4FCE93FD0301808): timeout (0 ms) by Viasat_Nordic_5E_(fin+dk)
vdr: [LOG921]2013/10/04 18:29:38        0   --- Skipped 1 duplicated log lines ---
vdr: [LOG922]2013/10/04 18:29:38  148DEC0 c [DVBAPI] Demuxer #0 (re)starting decodingrequests on all 2 ecmpids!
vdr: [LOG923]2013/10/04 18:29:38  148DEC0 c [DVBAPI] Demuxer #0 trying to descramble PID #1 CAID 093E PROVID 000000 ECMPID 0099 ANY CHID VPID 0835
vdr: [LOG924]2013/10/04 18:29:44  148DEC0 c anonymous (093E&000000/00AC/0834/AA:BE30BF8CD72960D2DBFBCEB0D17EDFF8): timeout (5001 ms) by Viasat_Nordic_5E_(fin+dk)
vdr: [LOG925]2013/10/04 18:29:44  148DEC0 c anonymous (093E&000000/00AC/0834/AA:BE30BF8CD72960D2DBFBCEB0D17EDFF8): timeout (0 ms) by Viasat_Nordic_5E_(fin+dk)
vdr: [LOG926]2013/10/04 18:29:44  148DEC0 c [DVBAPI] Demuxer #0 (re)starting decodingrequests on all 2 ecmpids!
vdr: [LOG927]2013/10/04 18:29:44  148DEC0 c [DVBAPI] Demuxer #0 trying to descramble PID #1 CAID 093E PROVID 000000 ECMPID 0099 ANY CHID VPID 0835
vdr: [LOG928]2013/10/04 18:29:45  148DEC0 c anonymous (093E&000000/00AC/0834/AA:BE30BF8CD72960D2DBFBCEB0D17EDFF8): timeout (0 ms) by Viasat_Nordic_5E_(fin+dk)
vdr: [LOG929]2013/10/04 18:29:45  148DEC0 c anonymous (093E&000000/00AC/0834/AA:BE30BF8CD72960D2DBFBCEB0D17EDFF8): timeout (1 ms) by Viasat_Nordic_5E_(fin+dk)
vdr: [LOG930]2013/10/04 18:29:45  148DEC0 c [DVBAPI] Demuxer #0 (re)starting decodingrequests on all 2 ecmpids!
vdr: [LOG931]2013/10/04 18:29:45  148DEC0 c [DVBAPI] Demuxer #0 trying to descramble PID #1 CAID 093E PROVID 000000 ECMPID 0099 ANY CHID VPID 0835
vdr: [LOG932]2013/10/04 18:29:46  148DEC0 c anonymous (093E&000000/00AC/0834/AA:BE30BF8CD72960D2DBFBCEB0D17EDFF8): timeout (0 ms) by Viasat_Nordic_5E_(fin+dk)
vdr: [LOG933]2013/10/04 18:29:46        0   --- Skipped 1 duplicated log lines ---
vdr: [LOG934]2013/10/04 18:29:46  148DEC0 c [DVBAPI] Demuxer #0 (re)starting decodingrequests on all 2 ecmpids!
vdr: [LOG935]2013/10/04 18:29:46  148DEC0 c [DVBAPI] Demuxer #0 trying to descramble PID #1 CAID 093E PROVID 000000 ECMPID 0099 ANY CHID VPID 0835
vdr: [LOG936]2013/10/04 18:29:47  148DEC0 c anonymous (093E&000000/00AC/0834/AA:BE30BF8CD72960D2DBFBCEB0D17EDFF8): timeout (0 ms) by Viasat_Nordic_5E_(fin+dk)
vdr: [LOG937]2013/10/04 18:29:47        0   --- Skipped 1 duplicated log lines ---
vdr: [LOG938]2013/10/04 18:29:47  148DEC0 c [DVBAPI] Demuxer #0 (re)starting decodingrequests on all 2 ecmpids!
vdr: [LOG939]2013/10/04 18:29:47  148DEC0 c [DVBAPI] Demuxer #0 trying to descramble PID #1 CAID 093E PROVID 000000 ECMPID 0099 ANY CHID VPID 0835
vdr: [LOG940]2013/10/04 18:29:48  148DEC0 c anonymous (093E&000000/00AC/0834/AA:BE30BF8CD72960D2DBFBCEB0D17EDFF8): timeout (1 ms) by Viasat_Nordic_5E_(fin+dk)
vdr: [LOG941]2013/10/04 18:29:48  148DEC0 c anonymous (093E&000000/00AC/0834/AA:BE30BF8CD72960D2DBFBCEB0D17EDFF8): timeout (0 ms) by Viasat_Nordic_5E_(fin+dk)
vdr: [LOG942]2013/10/04 18:29:48  148DEC0 c [DVBAPI] Demuxer #0 (re)starting decodingrequests on all 2 ecmpids!
vdr: [LOG943]2013/10/04 18:29:48  148DEC0 c [DVBAPI] Demuxer #0 trying to descramble PID #1 CAID 093E PROVID 000000 ECMPID 0099 ANY CHID VPID 0835
vdr: [LOG944]2013/10/04 18:29:53  148DEC0 c anonymous (093E&000000/00AC/0834/AA:FFF42323A6176BDF4D8624F1970B5BC3): timeout (5000 ms) by Viasat_Nordic_5E_(fin+dk)
vdr: [LOG945]2013/10/04 18:29:53  148DEC0 c anonymous (093E&000000/00AC/0834/AA:FFF42323A6176BDF4D8624F1970B5BC3): timeout (0 ms) by Viasat_Nordic_5E_(fin+dk)
vdr: [LOG946]2013/10/04 18:29:54  148DEC0 c [DVBAPI] Demuxer #0 (re)starting decodingrequests on all 2 ecmpids!
vdr: [LOG947]2013/10/04 18:29:54  148DEC0 c [DVBAPI] Demuxer #0 trying to descramble PID #1 CAID 093E PROVID 000000 ECMPID 0099 ANY CHID VPID 0835
vdr: [LOG948]2013/10/04 18:29:54  148DEC0 c anonymous (093E&000000/00AC/0834/AA:FFF42323A6176BDF4D8624F1970B5BC3): timeout (0 ms) by Viasat_Nordic_5E_(fin+dk)
vdr: [LOG949]2013/10/04 18:29:55        0   --- Skipped 1 duplicated log lines ---
vdr: [LOG950]2013/10/04 18:29:55  148DEC0 c [DVBAPI] Demuxer #0 (re)starting decodingrequests on all 2 ecmpids!
vdr: [LOG951]2013/10/04 18:29:55  148DEC0 c [DVBAPI] Demuxer #0 trying to descramble PID #1 CAID 093E PROVID 000000 ECMPID 0099 ANY CHID VPID 0835
vdr: [LOG952]2013/10/04 18:29:55  148DEC0 c anonymous (093E&000000/00AC/0834/AA:FFF42323A6176BDF4D8624F1970B5BC3): timeout (0 ms) by Viasat_Nordic_5E_(fin+dk)
vdr: [LOG953]2013/10/04 18:29:56        0   --- Skipped 1 duplicated log lines ---
vdr: [LOG954]2013/10/04 18:29:56  148DEC0 c [DVBAPI] Demuxer #0 (re)starting decodingrequests on all 2 ecmpids!
vdr: [LOG955]2013/10/04 18:29:56  148DEC0 c [DVBAPI] Demuxer #0 trying to descramble PID #1 CAID 093E PROVID 000000 ECMPID 0099 ANY CHID VPID 0835
vdr: [LOG956]2013/10/04 18:29:56  148DEC0 c anonymous (093E&000000/00AC/0834/AA:FFF42323A6176BDF4D8624F1970B5BC3): timeout (0 ms) by Viasat_Nordic_5E_(fin+dk)
vdr: [LOG957]2013/10/04 18:29:57        0   --- Skipped 1 duplicated log lines ---
vdr: [LOG958]2013/10/04 18:29:57  148DEC0 c [DVBAPI] Demuxer #0 (re)starting decodingrequests on all 2 ecmpids!
vdr: [LOG959]2013/10/04 18:29:57  148DEC0 c [DVBAPI] Demuxer #0 trying to descramble PID #1 CAID 093E PROVID 000000 ECMPID 0099 ANY CHID VPID 0835
vdr: [LOG960]2013/10/04 18:29:57  148DEC0 c anonymous (093E&000000/00AC/0834/AA:FFF42323A6176BDF4D8624F1970B5BC3): timeout (0 ms) by Viasat_Nordic_5E_(fin+dk)
vdr: [LOG961]2013/10/04 18:29:57        0   --- Skipped 1 duplicated log lines ---
vdr: [LOG962]2013/10/04 18:29:57  148DEC0 c [DVBAPI] Demuxer #0 (re)starting decodingrequests on all 2 ecmpids!
vdr: [LOG963]2013/10/04 18:29:58  148DEC0 c [DVBAPI] Demuxer #0 trying to descramble PID #1 CAID 093E PROVID 000000 ECMPID 0099 ANY CHID VPID 0835
vdr: [LOG964]2013/10/04 18:29:58  148DEC0 c anonymous (093E&000000/00AC/0834/AA:FFF42323A6176BDF4D8624F1970B5BC3): timeout (0 ms) by Viasat_Nordic_5E_(fin+dk)
vdr: [LOG965]2013/10/04 18:29:58        0   --- Skipped 1 duplicated log lines ---
vdr: [LOG966]2013/10/04 18:29:58  148DEC0 c [DVBAPI] Demuxer #0 (re)starting decodingrequests on all 2 ecmpids!
vdr: [LOG967]2013/10/04 18:29:59  148DEC0 c [DVBAPI] Demuxer #0 trying to descramble PID #1 CAID 093E PROVID 000000 ECMPID 0099 ANY CHID VPID 0835
vdr: [LOG968]2013/10/04 18:30:04  148DEC0 c anonymous (093E&000000/00AC/0834/AA:614EEB7879EE9197ADB8DBA97F390468): timeout (5001 ms) by Viasat_Nordic_5E_(fin+dk)
vdr: [LOG969]2013/10/04 18:30:04  148DEC0 c anonymous (093E&000000/00AC/0834/AA:614EEB7879EE9197ADB8DBA97F390468): timeout (0 ms) by Viasat_Nordic_5E_(fin+dk)
vdr: [LOG970]2013/10/04 18:30:04  148DEC0 c [DVBAPI] Demuxer #0 (re)starting decodingrequests on all 2 ecmpids!
vdr: [LOG971]2013/10/04 18:30:05  148DEC0 c [DVBAPI] Demuxer #0 trying to descramble PID #1 CAID 093E PROVID 000000 ECMPID 0099 ANY CHID VPID 0835
vdr: [LOG972]2013/10/04 18:30:05  148DEC0 c anonymous (093E&000000/00AC/0834/AA:614EEB7879EE9197ADB8DBA97F390468): timeout (0 ms) by Viasat_Nordic_5E_(fin+dk)
vdr: [LOG973]2013/10/04 18:30:05        0   --- Skipped 1 duplicated log lines ---
vdr: [LOG974]2013/10/04 18:30:05  14A8020 m all debug_level=1
vdr: [LOG975]2013/10/04 18:30:05        0 s [OSCAM] new event 1 occurred on fd 6 after 91 ms inactivity
vdr: [LOG976]2013/10/04 18:30:05        0 s got 21 bytes on port XXX from ip X.X.X.X:XXXX client MYUSERNAME
vdr: [LOG977]2013/10/04 18:30:05        0 s start client thread action 22
vdr: [LOG978]2013/10/04 18:30:05  14A8020 m data from add_job action=22 client m MYUSERNAME
vdr: [LOG979]2013/10/04 18:30:05  148DEC0 c [DVBAPI] new events occurred on 1 of 3 handlers after 301 ms inactivity
vdr: [LOG980]2013/10/04 18:30:05  148DEC0 c [DVBAPI] now handling fd 14 that reported event 32
vdr: [LOG981]2013/10/04 18:30:05  148DEC0 c [DVBAPI] Demuxer #0 (re)starting decodingrequests on all 2 ecmpids!
vdr: [LOG982]2013/10/04 18:30:06  148DEC0 c caid 090F not found in caidlist reader Viasat_Nordic_5E_(fin+dk)
vdr: [LOG983]2013/10/04 18:30:06  148DEC0 c [DVBAPI] Demuxer #0 trying to descramble PID #1 CAID 093E PROVID 000000 ECMPID 0099 ANY CHID VPID 0835
vdr: [LOG984]2013/10/04 18:30:06  148DEC0 c [DVBAPI] new events occurred on 1 of 3 handlers after 600 ms inactivity
vdr: [LOG985]2013/10/04 18:30:06  148DEC0 c [DVBAPI] now handling fd 14 that reported event 3
vdr: [LOG986]2013/10/04 18:30:06  148DEC0 c [DVBAPI] Readed:
vdr: [LOG987]2013/10/04 18:30:06  148DEC0    80 70 A7 00 00 01 11 A5 04 7B BC 00 AC AA 55 15
vdr: [LOG988]2013/10/04 18:30:06  148DEC0    21 80 01 00 01 03 00 58 91 7E 0A 53 06 F8 83 E7
vdr: [LOG989]2013/10/04 18:30:06  148DEC0    90 B4 E0 00 00 47 08 2A C7 C3 CB 68 10 AB 06 90
vdr: [LOG990]2013/10/04 18:30:06  148DEC0    79 C0 01 91 F7 C2 30 CF B1 4F 2B 9F 1F D1 A5 A9
vdr: [LOG991]2013/10/04 18:30:06  148DEC0    D1 10 40 F2 0D DC 02 9D 90 CD 70 9F 49 6B 71 45
vdr: [LOG992]2013/10/04 18:30:06  148DEC0    6F 5D 7F 48 E2 C2 E0 39 61 49 42 81 AF 0B 23 1B
vdr: [LOG993]2013/10/04 18:30:06  148DEC0    D1 02 8C 8E CD B8 CD 73 6C 45 CC 9E DB C6 40 F8
vdr: [LOG994]2013/10/04 18:30:06  148DEC0    8F BF 8F 32 00 6C FF 25 76 AC F7 56 EF 22 2C B1
vdr: [LOG995]2013/10/04 18:30:06  148DEC0    F1 4B C6 BA 2B 10 00 D0 D2 7D 77 72 51 7B 16 FA
vdr: [LOG996]2013/10/04 18:30:06  148DEC0    26 50 05 A9 48 D0 38 C7 8A CC AF A0 FD DE 35 C0
vdr: [LOG997]2013/10/04 18:30:06  148DEC0    A5 AB AD 39 70 56 5E 83 3B F0
vdr: [LOG998]2013/10/04 18:30:06  148DEC0 c [GET_CW] wait_time 0 caid 093E prov 000000 srvid 0834 rc 100 cacheex cl mode 0 ex1rdr 0
vdr: [LOG999]2013/10/04 18:30:06  148DEC0 c request_cw stage=3 to reader Viasat_Nordic_5E_(fin+dk) ecm hash=614EEB7879EE9197ADB8DBA97F390468
vdr: [LOG000]2013/10/04 18:30:06  148DEC0 c add reader job action 5 queue length 1 Viasat_Nordic_5E_(fin+dk)
vdr: [LOG001]2013/10/04 18:30:06 28002C50 p start client thread action 32
vdr: [LOG002]2013/10/04 18:30:06  148DEC0 c data from add_job action=32 client c anonymous
vdr: [LOG003]2013/10/04 18:30:06  148DEC0 c anonymous (093E&000000/00AC/0834/AA:614EEB7879EE9197ADB8DBA97F390468): timeout (0 ms) by Viasat_Nordic_5E_(fin+dk)
vdr: [LOG004]2013/10/04 18:30:06  148DEC0 c [DVBAPI] new events occurred on 1 of 3 handlers after 1 ms inactivity
vdr: [LOG005]2013/10/04 18:30:06  148DEC0 c [DVBAPI] now handling fd 14 that reported event 3
vdr: [LOG006]2013/10/04 18:30:06  148DEC0 c [DVBAPI] Readed:
vdr: [LOG007]2013/10/04 18:30:06  148DEC0    80 70 A7 00 00 01 11 A5 04 7B BC 00 AC AA 55 15
vdr: [LOG008]2013/10/04 18:30:06  148DEC0    21 80 01 00 01 03 00 58 91 7E 0A 53 06 F8 83 E7
vdr: [LOG009]2013/10/04 18:30:06  148DEC0    90 B4 E0 00 00 47 08 2A C7 C3 CB 68 10 AB 06 90
vdr: [LOG010]2013/10/04 18:30:06  148DEC0    79 C0 01 91 F7 C2 30 CF B1 4F 2B 9F 1F D1 A5 A9
vdr: [LOG011]2013/10/04 18:30:06  148DEC0    D1 10 40 F2 0D DC 02 9D 90 CD 70 9F 49 6B 71 45
vdr: [LOG012]2013/10/04 18:30:06  148DEC0    6F 5D 7F 48 E2 C2 E0 39 61 49 42 81 AF 0B 23 1B
vdr: [LOG013]2013/10/04 18:30:06  148DEC0    D1 02 8C 8E CD B8 CD 73 6C 45 CC 9E DB C6 40 F8
vdr: [LOG014]2013/10/04 18:30:06  148DEC0    8F BF 8F 32 00 6C FF 25 76 AC F7 56 EF 22 2C B1
vdr: [LOG015]2013/10/04 18:30:06  148DEC0    F1 4B C6 BA 2B 10 00 D0 D2 7D 77 72 51 7B 16 FA
vdr: [LOG016]2013/10/04 18:30:06  148DEC0    26 50 05 A9 48 D0 38 C7 8A CC AF A0 FD DE 35 C0
vdr: [LOG017]2013/10/04 18:30:06  148DEC0    A5 AB AD 39 70 56 5E 83 3B F0
vdr: [LOG018]2013/10/04 18:30:06  148DEC0 c [GET_CW] wait_time 0 caid 093E prov 000000 srvid 0834 rc 100 cacheex cl mode 0 ex1rdr 0
vdr: [LOG019]2013/10/04 18:30:06  148DEC0 c request_cw stage=3 to reader Viasat_Nordic_5E_(fin+dk) ecm hash=614EEB7879EE9197ADB8DBA97F390468
vdr: [LOG020]2013/10/04 18:30:06  148DEC0 c add reader job action 5 queue length 1 Viasat_Nordic_5E_(fin+dk)
vdr: [LOG021]2013/10/04 18:30:06 28002C50 p start client thread action 32
vdr: [LOG022]2013/10/04 18:30:06  148DEC0 c data from add_job action=32 client c anonymous
vdr: [LOG023]2013/10/04 18:30:06  148DEC0 c anonymous (093E&000000/00AC/0834/AA:614EEB7879EE9197ADB8DBA97F390468): timeout (0 ms) by Viasat_Nordic_5E_(fin+dk)
vdr: [LOG024]2013/10/04 18:30:06        0   >> OSCam <<  cardserver log switched, version 1.20-unstable_svn, build r0 (x86_64-linux-gnu)
vdr: [LOG025]2013/10/04 18:30:06        0 s [OSCAM] new event 1 occurred on fd 6 after 957 ms inactivity
vdr: [LOG026]2013/10/04 18:30:06        0 s got 21 bytes on port XXX from ip X.X.X.X:XXXX client MYUSERNAME
vdr: [LOG027]2013/10/04 18:30:06        0 s start client thread action 22
vdr: [LOG028]2013/10/04 18:30:06  14A8020 m data from add_job action=22 client m MYUSERNAME
vdr: [LOG029]2013/10/04 18:30:06  14A8020 m all debug_level=0
vdr: [LOG030]2013/10/04 18:30:06  148DEC0 c [DVBAPI] Demuxer #0 (re)starting decodingrequests on all 2 ecmpids!
vdr: [LOG031]2013/10/04 18:30:07  148DEC0 c [DVBAPI] Demuxer #0 trying to descramble PID #1 CAID 093E PROVID 000000 ECMPID 0099 ANY CHID VPID 0835
vdr: [LOG032]2013/10/04 18:30:07  148DEC0 c anonymous (093E&000000/00AC/0834/AA:614EEB7879EE9197ADB8DBA97F390468): timeout (0 ms) by Viasat_Nordic_5E_(fin+dk)
vdr: [LOG033]2013/10/04 18:30:07        0   --- Skipped 1 duplicated log lines ---
vdr: [LOG034]2013/10/04 18:30:07  148DEC0 c [DVBAPI] Demuxer #0 (re)starting decodingrequests on all 2 ecmpids!
vdr: [LOG035]2013/10/04 18:30:07  148DEC0 c [DVBAPI] Demuxer #0 trying to descramble PID #1 CAID 093E PROVID 000000 ECMPID 0099 ANY CHID VPID 0835
vdr: [LOG036]2013/10/04 18:30:08  148DEC0 c anonymous (093E&000000/00AC/0834/AA:614EEB7879EE9197ADB8DBA97F390468): timeout (0 ms) by Viasat_Nordic_5E_(fin+dk)
vdr: [LOG037]2013/10/04 18:30:08        0   --- Skipped 1 duplicated log lines ---
vdr: [LOG038]2013/10/04 18:30:08  148DEC0 c [DVBAPI] Demuxer #0 (re)starting decodingrequests on all 2 ecmpids!
vdr: [LOG039]2013/10/04 18:30:08  148DEC0 c [DVBAPI] Demuxer #0 trying to descramble PID #1 CAID 093E PROVID 000000 ECMPID 0099 ANY CHID VPID 0835
vdr: [LOG040]2013/10/04 18:30:14  148DEC0 c anonymous (093E&000000/00AC/0834/AA:9CB50814DD9FED6446F0C13E81A1D03B): timeout (5000 ms) by Viasat_Nordic_5E_(fin+dk)
