vdr: [LOG514]2013/10/04 18:43:09  148DEC0 c [DVBAPI] new events occurred on 1 of 1 handlers after 91661 ms inactivity
vdr: [LOG515]2013/10/04 18:43:09  148DEC0 c [DVBAPI] now handling fd 12 that reported event 1
vdr: [LOG516]2013/10/04 18:43:09  148DEC0 c new socket connection fd: 8
vdr: [LOG517]2013/10/04 18:43:09  148DEC0 c capmt:
vdr: [LOG518]2013/10/04 18:43:09  148DEC0    03 08 34 00 00 05 01 82 02 00 02 02 08 35 00 0D
vdr: [LOG519]2013/10/04 18:43:09  148DEC0    01 09 04 09 0F E8 3A 09 04 09 3E E0 99 04 08 36
vdr: [LOG520]2013/10/04 18:43:09  148DEC0    00 0D 01 09 04 09 0F E8 3A 09 04 09 3E E0 99 04
vdr: [LOG521]2013/10/04 18:43:09  148DEC0    08 37 00 00
vdr: [LOG522]2013/10/04 18:43:09  148DEC0 c [DVBAPI] Receiver sends PMT command 3 for channel 0834
vdr: [LOG523]2013/10/04 18:43:09  148DEC0 c [pmt] type: 82 length: 2
vdr: [LOG524]2013/10/04 18:43:09  148DEC0 c [pmt] stream_type: 02 pid: 0835 length: 13
vdr: [LOG525]2013/10/04 18:43:09  148DEC0 c [pmt] type: 09 length: 4
vdr: [LOG526]2013/10/04 18:43:09  148DEC0 c [ADD PID 0] CAID: 090F ECM_PID: 083A PROVID: 000000
vdr: [LOG527]2013/10/04 18:43:09  148DEC0 c [pmt] type: 09 length: 4
vdr: [LOG528]2013/10/04 18:43:09  148DEC0 c [ADD PID 1] CAID: 093E ECM_PID: 0099 PROVID: 000000
vdr: [LOG529]2013/10/04 18:43:09  148DEC0 c [pmt] stream_type: 04 pid: 0836 length: 13
vdr: [LOG530]2013/10/04 18:43:09  148DEC0 c [pmt] type: 09 length: 4
vdr: [LOG531]2013/10/04 18:43:09  148DEC0 c [ADD STREAM 0] CAID: 090F ECM_PID: 083A PROVID: 000000
vdr: [LOG532]2013/10/04 18:43:09  148DEC0 c [pmt] type: 09 length: 4
vdr: [LOG533]2013/10/04 18:43:09  148DEC0 c [ADD STREAM 1] CAID: 093E ECM_PID: 0099 PROVID: 000000
vdr: [LOG534]2013/10/04 18:43:09  148DEC0 c [pmt] stream_type: 04 pid: 0837 length: 0
vdr: [LOG535]2013/10/04 18:43:09  148DEC0 c Found 2 ECMpids and 3 STREAMpids in PMT
vdr: [LOG536]2013/10/04 18:43:09  148DEC0 c [DVBAPI] Receiver wants to demux srvid 0834 on adapter 0002 camask 0004 index 0000
vdr: [LOG537]2013/10/04 18:43:09  148DEC0 c New program number: 0834 (090F:0834 unknown) [pmt_list_management 3]
vdr: [LOG538]2013/10/04 18:43:09  148DEC0 c [DVBAPI] Demuxer #0 demux_index:  0 ca_mask: 04 program_info_length:   5 ca_pmt_list_management 03
vdr: [LOG539]2013/10/04 18:43:09  148DEC0 c [DVBAPI] found in channel cache: 093E&000000/0000/0834/00:(null)
vdr: [LOG540]2013/10/04 18:43:09  148DEC0 c [DVBAPI] Found channel in cache, start descrambling pid 1
vdr: [LOG541]2013/10/04 18:43:09  148DEC0 c trying reader 'Viasat_Nordic_5E_(fin+dk)' filter 093E:000000
vdr: [LOG542]2013/10/04 18:43:09  148DEC0 c 093E:000000 allowed by reader 'Viasat_Nordic_5E_(fin+dk)' filter 093E:000000
vdr: [LOG543]2013/10/04 18:43:09  148DEC0 c [DVBAPI] Demuxer #0 trying to descramble PID #1 CAID 093E PROVID 000000 ECMPID 0099 ANY CHID VPID 0835
vdr: [LOG544]2013/10/04 18:43:09  148DEC0 c [DVBAPI] Demuxer #0 try to start new filter for caid: 093E, provid: 000000, pid: 0099
vdr: [LOG545]2013/10/04 18:43:09  148DEC0 c DEVICE open (/dev/dvb/adapter2/demux0) fd 14
vdr: [LOG546]2013/10/04 18:43:09  148DEC0 c [DVBAPI] Demuxer #0 Filter #1 started succesfully (caid 093E provid 000000 pid 0099)
vdr: [LOG547]2013/10/04 18:43:09  148DEC0 c [DVBAPI] Demuxer #0 has 2 ecmpids, 3 streampids, 1 ecmfilters and 0 emmfilters
vdr: [LOG548]2013/10/04 18:43:09        0 s [OSCAM] new event 1 occurred on fd 6 after 1029 ms inactivity
vdr: [LOG549]2013/10/04 18:43:09        0 s got 21 bytes on port XXXX from ip X.X.X.X:XXXX client MYUSERNAME
vdr: [LOG550]2013/10/04 18:43:09        0 s start client thread action 22
vdr: [LOG551]2013/10/04 18:43:09  14A8020 m data from add_job action=22 client m MYUSERNAME
vdr: [LOG552]2013/10/04 18:43:09  148DEC0 c [DVBAPI] new events occurred on 1 of 3 handlers after 354 ms inactivity
vdr: [LOG553]2013/10/04 18:43:09  148DEC0 c [DVBAPI] now handling fd 14 that reported event 3
vdr: [LOG554]2013/10/04 18:43:09  148DEC0 c [DVBAPI] Readed:
vdr: [LOG555]2013/10/04 18:43:09  148DEC0    81 70 A7 00 00 01 11 A5 04 7D 63 00 AC AA 55 15
vdr: [LOG556]2013/10/04 18:43:09  148DEC0    21 80 01 00 01 03 00 01 91 7E 0A 08 1D A7 B4 3C
vdr: [LOG557]2013/10/04 18:43:09  148DEC0    57 B6 6A 00 00 47 08 DD B7 D6 D6 81 D0 DE 56 90
vdr: [LOG558]2013/10/04 18:43:09  148DEC0    79 C0 01 E5 64 D2 79 87 AB 26 88 50 F3 D7 F6 F8
vdr: [LOG559]2013/10/04 18:43:09  148DEC0    76 C9 0D 33 D0 5E 42 17 BD 5A BF 89 64 03 D4 46
vdr: [LOG560]2013/10/04 18:43:09  148DEC0    C3 3B E8 CA EF AB 86 FF 7C 38 49 D7 F3 75 26 C3
vdr: [LOG561]2013/10/04 18:43:09  148DEC0    CB A5 B1 7C 0A 9B 7E 8D A4 9F 09 99 6B 53 93 40
vdr: [LOG562]2013/10/04 18:43:09  148DEC0    7F CF 07 A4 A9 6C E6 0F DA DE 14 F6 26 B1 E4 22
vdr: [LOG563]2013/10/04 18:43:09  148DEC0    CA A5 7D CD 05 1C 27 D3 DD E7 55 80 64 AE BC B2
vdr: [LOG564]2013/10/04 18:43:09  148DEC0    C1 2E 01 8C E9 BC BF 99 FF A2 8D D9 01 69 D4 1C
vdr: [LOG565]2013/10/04 18:43:09  148DEC0    57 83 F3 BF 65 63 6D E8 CB 94
vdr: [LOG566]2013/10/04 18:43:09  148DEC0 c [DVBAPI] Demuxer #0 ECMTYPE 81 CAID 093E PROVID 000000 ECMPID 0099 FAKECHID 00AC (unique part in ecm)
vdr: [LOG567]2013/10/04 18:43:09  148DEC0 c [DVBAPI] Demuxer #0 Filter #1 set ecmtable to EVEN (CAID 093E PROVID 000000 FD 14)
vdr: [LOG568]2013/10/04 18:43:09  148DEC0 c [DVBAPI] Demuxer #0 Filter #1 set chid to ANY CHID on fd 14
vdr: [LOG569]2013/10/04 18:43:09  148DEC0 c trying reader 'Viasat_Nordic_5E_(fin+dk)' filter 093E:000000
vdr: [LOG570]2013/10/04 18:43:09  148DEC0 c 093E:000000 allowed by reader 'Viasat_Nordic_5E_(fin+dk)' filter 093E:000000
vdr: [LOG571]2013/10/04 18:43:09  148DEC0 c [GET_CW] wait_time 0 caid 093E prov 000000 srvid 0834 rc 100 cacheex cl mode 0 ex1rdr 0
vdr: [LOG572]2013/10/04 18:43:09  148DEC0 c request_cw stage=3 to reader Viasat_Nordic_5E_(fin+dk) ecm hash=BB3FE0AEC37CC5A134FD1AAC5C9A0FC9
vdr: [LOG573]2013/10/04 18:43:09  148DEC0 c add reader job action 5 queue length 1 Viasat_Nordic_5E_(fin+dk)
vdr: [LOG574]2013/10/04 18:43:09  148DEC0 c [DVBAPI] Demuxer #0 request cw for ecm 093E&000000/00AC/0834/AA:BB3FE0AEC37CC5A134FD1AAC5C9A0FC9
vdr: [LOG575]2013/10/04 18:43:09 28002C50 p ---- ecm_task 0, idx 34, sflag=1
vdr: [LOG576]2013/10/04 18:43:09 28002C50 p casc ecm (Viasat_Nordic_5E_(fin+dk)):
vdr: [LOG577]2013/10/04 18:43:09 28002C50    81 70 A7 00 00 01 11 A5 04 7D 63 00 AC AA 55 15
vdr: [LOG578]2013/10/04 18:43:09 28002C50    21 80 01 00 01 03 00 01 91 7E 0A 08 1D A7 B4 3C
vdr: [LOG579]2013/10/04 18:43:09 28002C50    57 B6 6A 00 00 47 08 DD B7 D6 D6 81 D0 DE 56 90
vdr: [LOG580]2013/10/04 18:43:09 28002C50    79 C0 01 E5 64 D2 79 87 AB 26 88 50 F3 D7 F6 F8
vdr: [LOG581]2013/10/04 18:43:09 28002C50    76 C9 0D 33 D0 5E 42 17 BD 5A BF 89 64 03 D4 46
vdr: [LOG582]2013/10/04 18:43:09 28002C50    C3 3B E8 CA EF AB 86 FF 7C 38 49 D7 F3 75 26 C3
vdr: [LOG583]2013/10/04 18:43:09 28002C50    CB A5 B1 7C 0A 9B 7E 8D A4 9F 09 99 6B 53 93 40
vdr: [LOG584]2013/10/04 18:43:09 28002C50    7F CF 07 A4 A9 6C E6 0F DA DE 14 F6 26 B1 E4 22
vdr: [LOG585]2013/10/04 18:43:09 28002C50    CA A5 7D CD 05 1C 27 D3 DD E7 55 80 64 AE BC B2
vdr: [LOG586]2013/10/04 18:43:09 28002C50    C1 2E 01 8C E9 BC BF 99 FF A2 8D D9 01 69 D4 1C
vdr: [LOG587]2013/10/04 18:43:09 28002C50    57 83 F3 BF 65 63 6D E8 CB 94
vdr: [LOG588]2013/10/04 18:43:09 28002C50 p trying server 'server IP' filter 093E:000000
vdr: [LOG589]2013/10/04 18:43:09 28002C50 p 093E:000000 allowed by server 'server IP' filter 093E:000000
vdr: [LOG590]2013/10/04 18:43:09 28002C50 p send 182 bytes to remote server
vdr: [LOG591]2013/10/04 18:43:09 28002C50    00 B4 00 22 08 34 00 00 00 00 00 00 81 70 A7 00
vdr: [LOG592]2013/10/04 18:43:09 28002C50    00 01 11 A5 04 7D 63 00 AC AA 55 15 21 80 01 00
vdr: [LOG593]2013/10/04 18:43:09 28002C50    01 03 00 01 91 7E 0A 08 1D A7 B4 3C 57 B6 6A 00
vdr: [LOG594]2013/10/04 18:43:09 28002C50    00 47 08 DD B7 D6 D6 81 D0 DE 56 90 79 C0 01 E5
vdr: [LOG595]2013/10/04 18:43:09 28002C50    64 D2 79 87 AB 26 88 50 F3 D7 F6 F8 76 C9 0D 33
vdr: [LOG596]2013/10/04 18:43:09 28002C50    D0 5E 42 17 BD 5A BF 89 64 03 D4 46 C3 3B E8 CA
vdr: [LOG597]2013/10/04 18:43:09 28002C50    EF AB 86 FF 7C 38 49 D7 F3 75 26 C3 CB A5 B1 7C
vdr: [LOG598]2013/10/04 18:43:09 28002C50    0A 9B 7E 8D A4 9F 09 99 6B 53 93 40 7F CF 07 A4
vdr: [LOG599]2013/10/04 18:43:09 28002C50    A9 6C E6 0F DA DE 14 F6 26 B1 E4 22 CA A5 7D CD
vdr: [LOG600]2013/10/04 18:43:09 28002C50    05 1C 27 D3 DD E7 55 80 64 AE BC B2 C1 2E 01 8C
vdr: [LOG601]2013/10/04 18:43:09 28002C50    E9 BC BF 99 FF A2 8D D9 01 69 D4 1C 57 83 F3 BF
vdr: [LOG602]2013/10/04 18:43:09 28002C50    65 63 6D E8 CB 94
vdr: [LOG603]2013/10/04 18:43:09        0   >> OSCam <<  cardserver log switched, version 1.20-unstable_svn, build r0 (x86_64-linux-gnu)
vdr: [LOG604]2013/10/04 18:43:09 28002C50 p [OSCAM-WORK] new event 1 occurred on fd 16 after 100812 ms inactivity
vdr: [LOG605]2013/10/04 18:43:09 28002C50 p nmr(): len=2, errno=0
vdr: [LOG606]2013/10/04 18:43:09 28002C50 p nmr: netMsgId=34, from server=34,
vdr: [LOG607]2013/10/04 18:43:09 28002C50 p received 21 bytes from remote server
vdr: [LOG608]2013/10/04 18:43:09 28002C50    00 22 80 10 10 13 22 F4 29 55 33 BA 42 F0 81 2D
vdr: [LOG609]2013/10/04 18:43:09 28002C50    9E A6 73 79 92
vdr: [LOG610]2013/10/04 18:43:09 28002C50 p start client thread action 32
vdr: [LOG611]2013/10/04 18:43:09 28002C50 p Viasat_Nordic_5E_(fin+dk) [newcamd] TRACE: ecm answer for ecm hash BB3FE0AEC37CC5A134FD1AAC5C9A0FC9 rc=0
vdr: [LOG612]2013/10/04 18:43:09  148DEC0 c data from add_job action=32 client c anonymous
vdr: [LOG613]2013/10/04 18:43:09  148DEC0 c [DVBAPI] Demuxer #0 controlword received for PID #1 CAID 093E PROVID 000000 ECMPID 0099 CHID 00AC VPID 0835
vdr: [LOG614]2013/10/04 18:43:09  148DEC0 c [DVBAPI] Demuxer #0 requested controlword for ecm BB3FE0AEC37CC5A134FD1AAC5C9A0FC9 on fd 14
vdr: [LOG615]2013/10/04 18:43:09  148DEC0 c [DVBAPI] Demuxer #0 descrambling PID #1 CAID 093E PROVID 000000 ECMPID 0099 CHID AC VPID 0835
vdr: [LOG616]2013/10/04 18:43:09  148DEC0 c [DVBAPI] Demuxer #0 PID: #1 CAID: 093E ECMPID: 0099 is using index 0
vdr: [LOG617]2013/10/04 18:43:09  148DEC0 c [DVBAPI] Demuxer #0 writing odd part (1322F4295533BA42) of controlword, replacing expired (0000000000000000)
vdr: [LOG618]2013/10/04 18:43:09  148DEC0 c [DVBAPI] Demuxer #0 write cw0 index: 0 (ca2)
vdr: [LOG619]2013/10/04 18:43:09  148DEC0 c [DVBAPI] Demuxer #0 writing even part (F0812D9EA6737992) of controlword, replacing expired (0000000000000000)
vdr: [LOG620]2013/10/04 18:43:09  148DEC0 c [DVBAPI] Demuxer #0 write cw1 index: 0 (ca2)
vdr: [LOG621]2013/10/04 18:43:09  148DEC0 c anonymous (093E&000000/00AC/0834/AA:BB3FE0AEC37CC5A134FD1AAC5C9A0FC9): found (89 ms) by Viasat_Nordic_5E_(fin+dk)
vdr: [LOG622]2013/10/04 18:43:09  148DEC0 c cw:
vdr: [LOG623]2013/10/04 18:43:09  148DEC0    13 22 F4 29 55 33 BA 42 F0 81 2D 9E A6 73 79 92
vdr: [LOG624]2013/10/04 18:43:10        0 s [OSCAM] new event 1 occurred on fd 6 after 999 ms inactivity
vdr: [LOG625]2013/10/04 18:43:10        0 s got 21 bytes on port XXXX from ip X.X.X.X:XXXX client MYUSERNAME
vdr: [LOG626]2013/10/04 18:43:10        0 s start client thread action 22
vdr: [LOG627]2013/10/04 18:43:10  14A8020 m data from add_job action=22 client m MYUSERNAME
vdr: [LOG628]2013/10/04 18:43:11        0 s [OSCAM] new event 1 occurred on fd 6 after 1013 ms inactivity
vdr: [LOG629]2013/10/04 18:43:11        0 s got 21 bytes on port XXXX from ip X.X.X.X:XXXX client MYUSERNAME
vdr: [LOG630]2013/10/04 18:43:11        0 s start client thread action 22
vdr: [LOG631]2013/10/04 18:43:11  14A8020 m data from add_job action=22 client m MYUSERNAME
vdr: [LOG632]2013/10/04 18:43:12        0 s [OSCAM] new event 1 occurred on fd 6 after 1015 ms inactivity
vdr: [LOG633]2013/10/04 18:43:12        0 s got 21 bytes on port XXXX from ip X.X.X.X:XXXX client MYUSERNAME
vdr: [LOG634]2013/10/04 18:43:12        0 s start client thread action 22
vdr: [LOG635]2013/10/04 18:43:12  14A8020 m data from add_job action=22 client m MYUSERNAME
vdr: [LOG636]2013/10/04 18:43:13        0 s [OSCAM] new event 1 occurred on fd 6 after 1014 ms inactivity
vdr: [LOG637]2013/10/04 18:43:13        0 s got 21 bytes on port XXXX from ip X.X.X.X:XXXX client MYUSERNAME
vdr: [LOG638]2013/10/04 18:43:13        0 s start client thread action 22
vdr: [LOG639]2013/10/04 18:43:13  14A8020 m data from add_job action=22 client m MYUSERNAME
vdr: [LOG640]2013/10/04 18:43:14        0 s [OSCAM] new event 1 occurred on fd 6 after 1014 ms inactivity
vdr: [LOG641]2013/10/04 18:43:14        0 s got 21 bytes on port XXXX from ip X.X.X.X:XXXX client MYUSERNAME
vdr: [LOG642]2013/10/04 18:43:14        0 s start client thread action 22
vdr: [LOG643]2013/10/04 18:43:14  14A8020 m data from add_job action=22 client m MYUSERNAME
vdr: [LOG644]2013/10/04 18:43:14        0   start client thread action 31
vdr: [LOG645]2013/10/04 18:43:14  148DEC0 c data from add_job action=31 client c anonymous
vdr: [LOG646]2013/10/04 18:43:15        0 s [OSCAM] new event 1 occurred on fd 6 after 1014 ms inactivity
vdr: [LOG647]2013/10/04 18:43:15        0 s got 21 bytes on port XXXX from ip X.X.X.X:XXXX client MYUSERNAME
vdr: [LOG648]2013/10/04 18:43:15        0 s start client thread action 22
vdr: [LOG649]2013/10/04 18:43:15  14A8020 m data from add_job action=22 client m MYUSERNAME
vdr: [LOG650]2013/10/04 18:43:16        0 s [OSCAM] new event 1 occurred on fd 6 after 1014 ms inactivity
vdr: [LOG651]2013/10/04 18:43:16        0 s got 21 bytes on port XXXX from ip X.X.X.X:XXXX client MYUSERNAME
vdr: [LOG652]2013/10/04 18:43:16        0 s start client thread action 22
vdr: [LOG653]2013/10/04 18:43:16  14A8020 m data from add_job action=22 client m MYUSERNAME
vdr: [LOG654]2013/10/04 18:43:17        0 s [OSCAM] new event 1 occurred on fd 6 after 1014 ms inactivity
vdr: [LOG655]2013/10/04 18:43:17        0 s got 21 bytes on port XXXX from ip X.X.X.X:XXXX client MYUSERNAME
vdr: [LOG656]2013/10/04 18:43:17        0 s start client thread action 22
vdr: [LOG657]2013/10/04 18:43:17  14A8020 m data from add_job action=22 client m MYUSERNAME
vdr: [LOG658]2013/10/04 18:43:18        0 s [OSCAM] new event 1 occurred on fd 6 after 1014 ms inactivity
vdr: [LOG659]2013/10/04 18:43:18        0 s got 21 bytes on port XXXX from ip X.X.X.X:XXXX client MYUSERNAME
vdr: [LOG660]2013/10/04 18:43:18        0 s start client thread action 22
vdr: [LOG661]2013/10/04 18:43:18  14A8020 m data from add_job action=22 client m MYUSERNAME
vdr: [LOG662]2013/10/04 18:43:18  148DEC0 c [DVBAPI] new events occurred on 1 of 3 handlers after 9008 ms inactivity
vdr: [LOG663]2013/10/04 18:43:18  148DEC0 c [DVBAPI] now handling fd 14 that reported event 3
vdr: [LOG664]2013/10/04 18:43:18  148DEC0 c [DVBAPI] Readed:
vdr: [LOG665]2013/10/04 18:43:18  148DEC0    80 70 A7 00 00 01 11 A5 04 7D 68 00 AC AA 55 15
vdr: [LOG666]2013/10/04 18:43:18  148DEC0    21 80 01 00 01 03 00 06 91 7E 0A 92 54 7B B0 80
vdr: [LOG667]2013/10/04 18:43:18  148DEC0    44 A4 5A 00 00 47 08 DB 7D 90 4E BE C4 0F FA 90
vdr: [LOG668]2013/10/04 18:43:18  148DEC0    79 C0 01 B0 73 2C 08 E0 1B 82 FF 7C 94 EB 1B 3C
vdr: [LOG669]2013/10/04 18:43:18  148DEC0    A3 B4 69 6F 3F 0D 9D 63 D0 19 F0 94 A6 C7 D2 92
vdr: [LOG670]2013/10/04 18:43:18  148DEC0    74 CC CA 4B DD 00 28 9A AD 66 C6 95 0F 7D 8F 9F
vdr: [LOG671]2013/10/04 18:43:18  148DEC0    FC 66 26 A2 1F 5B 3E D0 4A 0E 11 0D 44 0D 4B 2A
vdr: [LOG672]2013/10/04 18:43:18  148DEC0    B7 42 3D AD 4A 6F 76 9C 10 7F 8C 8F 94 A7 98 9B
vdr: [LOG673]2013/10/04 18:43:18  148DEC0    4E FE D6 29 35 8D 72 81 7E 70 AF 40 5D 84 C2 EE
vdr: [LOG674]2013/10/04 18:43:18  148DEC0    CC 3F D7 54 20 A3 1C 4D B4 1C 87 4C AC C4 B4 11
vdr: [LOG675]2013/10/04 18:43:18  148DEC0    94 EB B0 AA 90 D8 C8 61 01 68
vdr: [LOG676]2013/10/04 18:43:18  148DEC0 c [DVBAPI] Demuxer #0 ECMTYPE 80 CAID 093E PROVID 000000 ECMPID 0099 FAKECHID 00AC (unique part in ecm)
vdr: [LOG677]2013/10/04 18:43:18  148DEC0 c [DVBAPI] Demuxer #0 Filter #1 set ecmtable to ODD (CAID 093E PROVID 000000 FD 14)
vdr: [LOG678]2013/10/04 18:43:18  148DEC0 c [DVBAPI] Demuxer #0 Filter #1 set chid to 00AC on fd 14
vdr: [LOG679]2013/10/04 18:43:18  148DEC0 c trying reader 'Viasat_Nordic_5E_(fin+dk)' filter 093E:000000
vdr: [LOG680]2013/10/04 18:43:18  148DEC0 c 093E:000000 allowed by reader 'Viasat_Nordic_5E_(fin+dk)' filter 093E:000000
vdr: [LOG681]2013/10/04 18:43:18  148DEC0 c [GET_CW] wait_time 0 caid 093E prov 000000 srvid 0834 rc 100 cacheex cl mode 0 ex1rdr 0
vdr: [LOG682]2013/10/04 18:43:18  148DEC0 c request_cw stage=3 to reader Viasat_Nordic_5E_(fin+dk) ecm hash=287402F2DB4225A69BB79CE62E05193F
vdr: [LOG683]2013/10/04 18:43:18  148DEC0 c add reader job action 5 queue length 1 Viasat_Nordic_5E_(fin+dk)
vdr: [LOG684]2013/10/04 18:43:18  148DEC0 c [DVBAPI] Demuxer #0 request cw for ecm 093E&000000/00AC/0834/AA:287402F2DB4225A69BB79CE62E05193F
vdr: [LOG685]2013/10/04 18:43:18 28002C50 p ---- ecm_task 0, idx 35, sflag=1
vdr: [LOG686]2013/10/04 18:43:18 28002C50 p casc ecm (Viasat_Nordic_5E_(fin+dk)):
vdr: [LOG687]2013/10/04 18:43:18 28002C50    80 70 A7 00 00 01 11 A5 04 7D 68 00 AC AA 55 15
vdr: [LOG688]2013/10/04 18:43:18 28002C50    21 80 01 00 01 03 00 06 91 7E 0A 92 54 7B B0 80
vdr: [LOG689]2013/10/04 18:43:18 28002C50    44 A4 5A 00 00 47 08 DB 7D 90 4E BE C4 0F FA 90
vdr: [LOG690]2013/10/04 18:43:18 28002C50    79 C0 01 B0 73 2C 08 E0 1B 82 FF 7C 94 EB 1B 3C
vdr: [LOG691]2013/10/04 18:43:18 28002C50    A3 B4 69 6F 3F 0D 9D 63 D0 19 F0 94 A6 C7 D2 92
vdr: [LOG692]2013/10/04 18:43:18 28002C50    74 CC CA 4B DD 00 28 9A AD 66 C6 95 0F 7D 8F 9F
vdr: [LOG693]2013/10/04 18:43:18 28002C50    FC 66 26 A2 1F 5B 3E D0 4A 0E 11 0D 44 0D 4B 2A
vdr: [LOG694]2013/10/04 18:43:18 28002C50    B7 42 3D AD 4A 6F 76 9C 10 7F 8C 8F 94 A7 98 9B
vdr: [LOG695]2013/10/04 18:43:18 28002C50    4E FE D6 29 35 8D 72 81 7E 70 AF 40 5D 84 C2 EE
vdr: [LOG696]2013/10/04 18:43:18 28002C50    CC 3F D7 54 20 A3 1C 4D B4 1C 87 4C AC C4 B4 11
vdr: [LOG697]2013/10/04 18:43:18 28002C50    94 EB B0 AA 90 D8 C8 61 01 68
vdr: [LOG698]2013/10/04 18:43:18 28002C50 p trying server 'server IP' filter 093E:000000
vdr: [LOG699]2013/10/04 18:43:18 28002C50 p 093E:000000 allowed by server 'server IP' filter 093E:000000
vdr: [LOG700]2013/10/04 18:43:18 28002C50 p send 182 bytes to remote server
vdr: [LOG701]2013/10/04 18:43:18 28002C50    00 B4 00 23 08 34 00 00 00 00 00 00 80 70 A7 00
vdr: [LOG702]2013/10/04 18:43:18 28002C50    00 01 11 A5 04 7D 68 00 AC AA 55 15 21 80 01 00
vdr: [LOG703]2013/10/04 18:43:18 28002C50    01 03 00 06 91 7E 0A 92 54 7B B0 80 44 A4 5A 00
vdr: [LOG704]2013/10/04 18:43:18 28002C50    00 47 08 DB 7D 90 4E BE C4 0F FA 90 79 C0 01 B0
vdr: [LOG705]2013/10/04 18:43:18 28002C50    73 2C 08 E0 1B 82 FF 7C 94 EB 1B 3C A3 B4 69 6F
vdr: [LOG706]2013/10/04 18:43:18 28002C50    3F 0D 9D 63 D0 19 F0 94 A6 C7 D2 92 74 CC CA 4B
vdr: [LOG707]2013/10/04 18:43:18 28002C50    DD 00 28 9A AD 66 C6 95 0F 7D 8F 9F FC 66 26 A2
vdr: [LOG708]2013/10/04 18:43:18 28002C50    1F 5B 3E D0 4A 0E 11 0D 44 0D 4B 2A B7 42 3D AD
vdr: [LOG709]2013/10/04 18:43:18 28002C50    4A 6F 76 9C 10 7F 8C 8F 94 A7 98 9B 4E FE D6 29
vdr: [LOG710]2013/10/04 18:43:18 28002C50    35 8D 72 81 7E 70 AF 40 5D 84 C2 EE CC 3F D7 54
vdr: [LOG711]2013/10/04 18:43:18 28002C50    20 A3 1C 4D B4 1C 87 4C AC C4 B4 11 94 EB B0 AA
vdr: [LOG712]2013/10/04 18:43:18 28002C50    90 D8 C8 61 01 68
vdr: [LOG713]2013/10/04 18:43:18        0   >> OSCam <<  cardserver log switched, version 1.20-unstable_svn, build r0 (x86_64-linux-gnu)
vdr: [LOG714]2013/10/04 18:43:19 28002C50 p [OSCAM-WORK] new event 1 occurred on fd 16 after 9588 ms inactivity
vdr: [LOG715]2013/10/04 18:43:19 28002C50 p nmr(): len=2, errno=0
vdr: [LOG716]2013/10/04 18:43:19 28002C50 p nmr: netMsgId=35, from server=35,
vdr: [LOG717]2013/10/04 18:43:19 28002C50 p received 21 bytes from remote server
vdr: [LOG718]2013/10/04 18:43:19 28002C50    00 23 80 10 10 4C 88 70 44 F8 31 8F B8 F0 81 2D
vdr: [LOG719]2013/10/04 18:43:19 28002C50    9E A6 73 79 92
vdr: [LOG720]2013/10/04 18:43:19 28002C50 p start client thread action 32
vdr: [LOG721]2013/10/04 18:43:19 28002C50 p Viasat_Nordic_5E_(fin+dk) [newcamd] TRACE: ecm answer for ecm hash 287402F2DB4225A69BB79CE62E05193F rc=0
vdr: [LOG722]2013/10/04 18:43:19  148DEC0 c data from add_job action=32 client c anonymous
vdr: [LOG723]2013/10/04 18:43:19  148DEC0 c [DVBAPI] Demuxer #0 controlword received for PID #1 CAID 093E PROVID 000000 ECMPID 0099 CHID 00AC VPID 0835
vdr: [LOG724]2013/10/04 18:43:19  148DEC0 c [DVBAPI] Demuxer #0 requested controlword for ecm 287402F2DB4225A69BB79CE62E05193F on fd 14
vdr: [LOG725]2013/10/04 18:43:19  148DEC0 c [DVBAPI] Demuxer #0 writing odd part (4C887044F8318FB8) of controlword, replacing expired (1322F4295533BA42)
vdr: [LOG726]2013/10/04 18:43:19  148DEC0 c [DVBAPI] Demuxer #0 write cw0 index: 0 (ca2)
vdr: [LOG727]2013/10/04 18:43:19  148DEC0 c anonymous (093E&000000/00AC/0834/AA:287402F2DB4225A69BB79CE62E05193F): found (668 ms) by Viasat_Nordic_5E_(fin+dk)
vdr: [LOG728]2013/10/04 18:43:19  148DEC0 c cw:
vdr: [LOG729]2013/10/04 18:43:19  148DEC0    4C 88 70 44 F8 31 8F B8 F0 81 2D 9E A6 73 79 92
vdr: [LOG730]2013/10/04 18:43:19        0 s [OSCAM] new event 1 occurred on fd 6 after 1014 ms inactivity
vdr: [LOG731]2013/10/04 18:43:19        0 s got 21 bytes on port XXXX from ip X.X.X.X:XXXX client MYUSERNAME
vdr: [LOG732]2013/10/04 18:43:19        0 s start client thread action 22
vdr: [LOG733]2013/10/04 18:43:19  14A8020 m data from add_job action=22 client m MYUSERNAME
vdr: [LOG734]2013/10/04 18:43:20        0 s [OSCAM] new event 1 occurred on fd 6 after 1014 ms inactivity
vdr: [LOG735]2013/10/04 18:43:20        0 s got 21 bytes on port XXXX from ip X.X.X.X:XXXX client MYUSERNAME
vdr: [LOG736]2013/10/04 18:43:20        0 s start client thread action 22
vdr: [LOG737]2013/10/04 18:43:20  14A8020 m data from add_job action=22 client m MYUSERNAME
vdr: [LOG738]2013/10/04 18:43:21        0 s [OSCAM] new event 1 occurred on fd 6 after 1014 ms inactivity
vdr: [LOG739]2013/10/04 18:43:21        0 s got 21 bytes on port XXXX from ip X.X.X.X:XXXX client MYUSERNAME
vdr: [LOG740]2013/10/04 18:43:21        0 s start client thread action 22
vdr: [LOG741]2013/10/04 18:43:21  14A8020 m data from add_job action=22 client m MYUSERNAME
vdr: [LOG742]2013/10/04 18:43:22        0 s [OSCAM] new event 1 occurred on fd 6 after 1014 ms inactivity
vdr: [LOG743]2013/10/04 18:43:22        0 s got 21 bytes on port XXXX from ip X.X.X.X:XXXX client MYUSERNAME
vdr: [LOG744]2013/10/04 18:43:22        0 s start client thread action 22
vdr: [LOG745]2013/10/04 18:43:22  14A8020 m data from add_job action=22 client m MYUSERNAME
vdr: [LOG746]2013/10/04 18:43:23        0 s [OSCAM] new event 1 occurred on fd 6 after 1014 ms inactivity
vdr: [LOG747]2013/10/04 18:43:23        0 s got 21 bytes on port XXXX from ip X.X.X.X:XXXX client MYUSERNAME
vdr: [LOG748]2013/10/04 18:43:23        0 s start client thread action 22
vdr: [LOG749]2013/10/04 18:43:23  14A8020 m data from add_job action=22 client m MYUSERNAME
vdr: [LOG750]2013/10/04 18:43:23        0   start client thread action 31
vdr: [LOG751]2013/10/04 18:43:23  148DEC0 c data from add_job action=31 client c anonymous
vdr: [LOG752]2013/10/04 18:43:24        0 s [OSCAM] new event 1 occurred on fd 6 after 1012 ms inactivity
vdr: [LOG753]2013/10/04 18:43:24        0 s got 21 bytes on port XXXX from ip X.X.X.X:XXXX client MYUSERNAME
vdr: [LOG754]2013/10/04 18:43:24        0 s start client thread action 22
vdr: [LOG755]2013/10/04 18:43:24  14A8020 m data from add_job action=22 client m MYUSERNAME
vdr: [LOG756]2013/10/04 18:43:25        0 s [OSCAM] new event 1 occurred on fd 6 after 1014 ms inactivity
vdr: [LOG757]2013/10/04 18:43:25        0 s got 21 bytes on port XXXX from ip X.X.X.X:XXXX client MYUSERNAME
vdr: [LOG758]2013/10/04 18:43:25        0 s start client thread action 22
vdr: [LOG759]2013/10/04 18:43:25  14A8020 m data from add_job action=22 client m MYUSERNAME
vdr: [LOG760]2013/10/04 18:43:26        0 s [OSCAM] new event 1 occurred on fd 6 after 1013 ms inactivity
vdr: [LOG761]2013/10/04 18:43:26        0 s got 21 bytes on port XXXX from ip X.X.X.X:XXXX client MYUSERNAME
vdr: [LOG762]2013/10/04 18:43:26        0 s start client thread action 22
vdr: [LOG763]2013/10/04 18:43:26  14A8020 m data from add_job action=22 client m MYUSERNAME
vdr: [LOG764]2013/10/04 18:43:27        0 s [OSCAM] new event 1 occurred on fd 6 after 1013 ms inactivity
vdr: [LOG765]2013/10/04 18:43:27        0 s got 21 bytes on port XXXX from ip X.X.X.X:XXXX client MYUSERNAME
vdr: [LOG766]2013/10/04 18:43:27        0 s start client thread action 22
vdr: [LOG767]2013/10/04 18:43:27  14A8020 m data from add_job action=22 client m MYUSERNAME
vdr: [LOG768]2013/10/04 18:43:28  148DEC0 c [DVBAPI] new events occurred on 1 of 3 handlers after 9977 ms inactivity
vdr: [LOG769]2013/10/04 18:43:28  148DEC0 c [DVBAPI] now handling fd 14 that reported event 3
vdr: [LOG770]2013/10/04 18:43:28  148DEC0 c [DVBAPI] Readed:
vdr: [LOG771]2013/10/04 18:43:28  148DEC0    81 70 A7 00 00 01 11 A5 04 7D 6D 00 AC AA 55 15
vdr: [LOG772]2013/10/04 18:43:28  148DEC0    21 80 01 00 01 03 00 0B 91 7E 0A 09 F4 88 41 39
vdr: [LOG773]2013/10/04 18:43:28  148DEC0    E2 3C CD 00 00 47 08 92 1B 81 A8 47 5F 19 A7 90
vdr: [LOG774]2013/10/04 18:43:28  148DEC0    79 C0 01 12 0E A6 47 6C 87 7B A1 C7 0B 4F 1B 16
vdr: [LOG775]2013/10/04 18:43:28  148DEC0    DE 64 C3 35 38 60 70 CD 01 1D 07 C6 21 2A DA F4
vdr: [LOG776]2013/10/04 18:43:28  148DEC0    64 E9 62 0F F4 AD A9 8D 93 04 91 F2 50 6F 9B DF
vdr: [LOG777]2013/10/04 18:43:28  148DEC0    60 60 2B 83 F7 38 0E 95 67 1C 7B B6 AE FA 3F 2F
vdr: [LOG778]2013/10/04 18:43:28  148DEC0    6D D3 E4 DF 41 41 06 E9 6B A4 B4 2F C5 71 32 38
vdr: [LOG779]2013/10/04 18:43:28  148DEC0    D5 04 B7 76 73 38 59 F4 7C 30 EE A4 8D 38 32 20
vdr: [LOG780]2013/10/04 18:43:28  148DEC0    82 AB 05 8C 97 54 C8 A2 17 72 34 80 CC CA 9F BC
vdr: [LOG781]2013/10/04 18:43:28  148DEC0    76 4D A4 23 07 AB 4D 8C 3B CC
vdr: [LOG782]2013/10/04 18:43:28  148DEC0 c [DVBAPI] Demuxer #0 ECMTYPE 81 CAID 093E PROVID 000000 ECMPID 0099 FAKECHID 00AC (unique part in ecm)
vdr: [LOG783]2013/10/04 18:43:28  148DEC0 c [DVBAPI] Demuxer #0 Filter #1 set ecmtable to EVEN (CAID 093E PROVID 000000 FD 14)
vdr: [LOG784]2013/10/04 18:43:28  148DEC0 c [DVBAPI] Demuxer #0 Filter #1 set chid to 00AC on fd 14
vdr: [LOG785]2013/10/04 18:43:28  148DEC0 c trying reader 'Viasat_Nordic_5E_(fin+dk)' filter 093E:000000
vdr: [LOG786]2013/10/04 18:43:28  148DEC0 c 093E:000000 allowed by reader 'Viasat_Nordic_5E_(fin+dk)' filter 093E:000000
vdr: [LOG787]2013/10/04 18:43:28  148DEC0 c [GET_CW] wait_time 0 caid 093E prov 000000 srvid 0834 rc 100 cacheex cl mode 0 ex1rdr 0
vdr: [LOG788]2013/10/04 18:43:28  148DEC0 c request_cw stage=3 to reader Viasat_Nordic_5E_(fin+dk) ecm hash=B2411C11C3D7A0A4F7320530DACB050A
vdr: [LOG789]2013/10/04 18:43:28  148DEC0 c add reader job action 5 queue length 1 Viasat_Nordic_5E_(fin+dk)
vdr: [LOG790]2013/10/04 18:43:28  148DEC0 c [DVBAPI] Demuxer #0 request cw for ecm 093E&000000/00AC/0834/AA:B2411C11C3D7A0A4F7320530DACB050A
vdr: [LOG791]2013/10/04 18:43:28 28002C50 p ---- ecm_task 0, idx 36, sflag=1
vdr: [LOG792]2013/10/04 18:43:28 28002C50 p casc ecm (Viasat_Nordic_5E_(fin+dk)):
vdr: [LOG793]2013/10/04 18:43:28 28002C50    81 70 A7 00 00 01 11 A5 04 7D 6D 00 AC AA 55 15
vdr: [LOG794]2013/10/04 18:43:28 28002C50    21 80 01 00 01 03 00 0B 91 7E 0A 09 F4 88 41 39
vdr: [LOG795]2013/10/04 18:43:28 28002C50    E2 3C CD 00 00 47 08 92 1B 81 A8 47 5F 19 A7 90
vdr: [LOG796]2013/10/04 18:43:28 28002C50    79 C0 01 12 0E A6 47 6C 87 7B A1 C7 0B 4F 1B 16
vdr: [LOG797]2013/10/04 18:43:28 28002C50    DE 64 C3 35 38 60 70 CD 01 1D 07 C6 21 2A DA F4
vdr: [LOG798]2013/10/04 18:43:28 28002C50    64 E9 62 0F F4 AD A9 8D 93 04 91 F2 50 6F 9B DF
vdr: [LOG799]2013/10/04 18:43:28 28002C50    60 60 2B 83 F7 38 0E 95 67 1C 7B B6 AE FA 3F 2F
vdr: [LOG800]2013/10/04 18:43:28 28002C50    6D D3 E4 DF 41 41 06 E9 6B A4 B4 2F C5 71 32 38
vdr: [LOG801]2013/10/04 18:43:28 28002C50    D5 04 B7 76 73 38 59 F4 7C 30 EE A4 8D 38 32 20
vdr: [LOG802]2013/10/04 18:43:28 28002C50    82 AB 05 8C 97 54 C8 A2 17 72 34 80 CC CA 9F BC
vdr: [LOG803]2013/10/04 18:43:28 28002C50    76 4D A4 23 07 AB 4D 8C 3B CC
vdr: [LOG804]2013/10/04 18:43:28 28002C50 p trying server 'server IP' filter 093E:000000
vdr: [LOG805]2013/10/04 18:43:28 28002C50 p 093E:000000 allowed by server 'server IP' filter 093E:000000
vdr: [LOG806]2013/10/04 18:43:28 28002C50 p send 182 bytes to remote server
vdr: [LOG807]2013/10/04 18:43:28 28002C50    00 B4 00 24 08 34 00 00 00 00 00 00 81 70 A7 00
vdr: [LOG808]2013/10/04 18:43:28 28002C50    00 01 11 A5 04 7D 6D 00 AC AA 55 15 21 80 01 00
vdr: [LOG809]2013/10/04 18:43:28 28002C50    01 03 00 0B 91 7E 0A 09 F4 88 41 39 E2 3C CD 00
vdr: [LOG810]2013/10/04 18:43:28 28002C50    00 47 08 92 1B 81 A8 47 5F 19 A7 90 79 C0 01 12
vdr: [LOG811]2013/10/04 18:43:28 28002C50    0E A6 47 6C 87 7B A1 C7 0B 4F 1B 16 DE 64 C3 35
vdr: [LOG812]2013/10/04 18:43:28 28002C50    38 60 70 CD 01 1D 07 C6 21 2A DA F4 64 E9 62 0F
vdr: [LOG813]2013/10/04 18:43:28 28002C50    F4 AD A9 8D 93 04 91 F2 50 6F 9B DF 60 60 2B 83
vdr: [LOG814]2013/10/04 18:43:28 28002C50    F7 38 0E 95 67 1C 7B B6 AE FA 3F 2F 6D D3 E4 DF
vdr: [LOG815]2013/10/04 18:43:28 28002C50    41 41 06 E9 6B A4 B4 2F C5 71 32 38 D5 04 B7 76
vdr: [LOG816]2013/10/04 18:43:28 28002C50    73 38 59 F4 7C 30 EE A4 8D 38 32 20 82 AB 05 8C
vdr: [LOG817]2013/10/04 18:43:28 28002C50    97 54 C8 A2 17 72 34 80 CC CA 9F BC 76 4D A4 23
vdr: [LOG818]2013/10/04 18:43:28 28002C50    07 AB 4D 8C 3B CC
vdr: [LOG819]2013/10/04 18:43:28        0 s [OSCAM] new event 1 occurred on fd 6 after 1032 ms inactivity
vdr: [LOG820]2013/10/04 18:43:28        0 s got 21 bytes on port XXXX from ip X.X.X.X:XXXX client MYUSERNAME
vdr: [LOG821]2013/10/04 18:43:28        0 s start client thread action 22
vdr: [LOG822]2013/10/04 18:43:28  14A8020 m data from add_job action=22 client m MYUSERNAME
vdr: [LOG823]2013/10/04 18:43:29 28002C50 p [OSCAM-WORK] new event 1 occurred on fd 16 after 9812 ms inactivity
vdr: [LOG824]2013/10/04 18:43:29 28002C50 p nmr(): len=2, errno=0
vdr: [LOG825]2013/10/04 18:43:29 28002C50 p nmr: netMsgId=36, from server=36,
vdr: [LOG826]2013/10/04 18:43:29 28002C50 p received 21 bytes from remote server
vdr: [LOG827]2013/10/04 18:43:29 28002C50    00 24 80 10 10 4C 88 70 44 F8 31 8F B8 19 80 9B
vdr: [LOG828]2013/10/04 18:43:29 28002C50    34 AA 12 48 04
vdr: [LOG829]2013/10/04 18:43:29 28002C50 p start client thread action 32
vdr: [LOG830]2013/10/04 18:43:29 28002C50 p Viasat_Nordic_5E_(fin+dk) [newcamd] TRACE: ecm answer for ecm hash B2411C11C3D7A0A4F7320530DACB050A rc=0
vdr: [LOG831]2013/10/04 18:43:29  148DEC0 c data from add_job action=32 client c anonymous
vdr: [LOG832]2013/10/04 18:43:29  148DEC0 c [DVBAPI] Demuxer #0 controlword received for PID #1 CAID 093E PROVID 000000 ECMPID 0099 CHID 00AC VPID 0835
vdr: [LOG833]2013/10/04 18:43:29  148DEC0 c [DVBAPI] Demuxer #0 requested controlword for ecm B2411C11C3D7A0A4F7320530DACB050A on fd 14
vdr: [LOG834]2013/10/04 18:43:29  148DEC0 c [DVBAPI] Demuxer #0 writing even part (19809B34AA124804) of controlword, replacing expired (F0812D9EA6737992)
vdr: [LOG835]2013/10/04 18:43:29  148DEC0 c [DVBAPI] Demuxer #0 write cw1 index: 0 (ca2)
vdr: [LOG836]2013/10/04 18:43:29  148DEC0 c anonymous (093E&000000/00AC/0834/AA:B2411C11C3D7A0A4F7320530DACB050A): found (503 ms) by Viasat_Nordic_5E_(fin+dk)
vdr: [LOG837]2013/10/04 18:43:29  148DEC0 c cw:
vdr: [LOG838]2013/10/04 18:43:29  148DEC0    4C 88 70 44 F8 31 8F B8 19 80 9B 34 AA 12 48 04
vdr: [LOG839]2013/10/04 18:43:29        0   >> OSCam <<  cardserver log switched, version 1.20-unstable_svn, build r0 (x86_64-linux-gnu)
vdr: [LOG845]2013/10/04 18:43:30        0 s got 21 bytes on port XXXX from ip X.X.X.X:XXXX client MYUSERNAME
vdr: [LOG846]2013/10/04 18:43:30        0 s start client thread action 22
vdr: [LOG847]2013/10/04 18:43:30  14A8020 m data from add_job action=22 client m MYUSERNAME
vdr: [LOG848]2013/10/04 18:43:31        0 s [OSCAM] new event 1 occurred on fd 6 after 1014 ms inactivity
vdr: [LOG849]2013/10/04 18:43:31        0 s got 21 bytes on port XXXX from ip X.X.X.X:XXXX client MYUSERNAME
vdr: [LOG850]2013/10/04 18:43:31        0 s start client thread action 22
vdr: [LOG851]2013/10/04 18:43:31  14A8020 m data from add_job action=22 client m MYUSERNAME
vdr: [LOG852]2013/10/04 18:43:32        0 s [OSCAM] new event 1 occurred on fd 6 after 1014 ms inactivity
vdr: [LOG853]2013/10/04 18:43:32        0 s got 21 bytes on port XXXX from ip X.X.X.X:XXXX client MYUSERNAME
vdr: [LOG854]2013/10/04 18:43:32        0 s start client thread action 22
vdr: [LOG855]2013/10/04 18:43:32  14A8020 m data from add_job action=22 client m MYUSERNAME
vdr: [LOG856]2013/10/04 18:43:33        0   start client thread action 31
vdr: [LOG857]2013/10/04 18:43:33  148DEC0 c data from add_job action=31 client c anonymous
vdr: [LOG858]2013/10/04 18:43:33        0 s [OSCAM] new event 1 occurred on fd 6 after 1014 ms inactivity
vdr: [LOG859]2013/10/04 18:43:33        0 s got 21 bytes on port XXXX from ip X.X.X.X:XXXX client MYUSERNAME
vdr: [LOG860]2013/10/04 18:43:33        0 s start client thread action 22
vdr: [LOG861]2013/10/04 18:43:33  14A8020 m data from add_job action=22 client m MYUSERNAME
vdr: [LOG862]2013/10/04 18:43:34  148DEC0 c [DVBAPI] new events occurred on 1 of 3 handlers after 5442 ms inactivity
vdr: [LOG863]2013/10/04 18:43:34  148DEC0 c [DVBAPI] now handling fd 8 that reported event 17
vdr: [LOG864]2013/10/04 18:43:34  148DEC0 c [DVBAPI] Demuxer #0 stop descrambling program number 0834 (093E:0834 unknown)
vdr: [LOG865]2013/10/04 18:43:34  148DEC0 c [DVBAPI] Demuxer #0 stop Filter #1 (fd: 14 api: 0, caid: 093E, provid: 000000, ecmpid: 0099)
vdr: [LOG866]2013/10/04 18:43:34        0 s [OSCAM] new event 1 occurred on fd 6 after 1014 ms inactivity
