Oct 16 21:05:38 ctvdr vdr: [10562] switching to channel 2
Oct 16 21:05:38 ctvdr vdr: [10562] [core.pids] 0: tune to same source/transponder
Oct 16 21:05:38 ctvdr vdr: [10562] [core.pids] 0: livepids video=00a0 audio=0000
Oct 16 21:05:38 ctvdr vdr: [10562] [core.pids] 0: livepids video=0000 audio=0000
Oct 16 21:05:39 ctvdr vdr: [10562] [core.pids] 0: add SID 13851 (zero=1 noshift=0)
Oct 16 21:05:39 ctvdr vdr: [10562] [core.pids] 0: pids VIDEO=00a0 AUDIO=0050 AUDIO=0051
Oct 16 21:05:39 ctvdr vdr: [10562] [core.pids] 0: SID=13851 zero=1 | sid=-/-/- zero=0/-1/1 idle=0/-1/1
Oct 16 21:05:39 ctvdr vdr: [10562] [core.pids] 0: found handler for SID 13851 (0.0 idle=1 idx=0)
Oct 16 21:05:39 ctvdr vdr: [10562] [core.ecm] 0.0: setting new SID 13851
Oct 16 21:05:39 ctvdr vdr: [10562] [core.pids] 0.0: pids on entry
Oct 16 21:05:39 ctvdr vdr: [10562] [core.pids] 0.0: pids after delete
Oct 16 21:05:39 ctvdr vdr: [10562] [core.pids] 0: descrambling pid 00a0 on index 0
Oct 16 21:05:39 ctvdr vdr: [10562] [core.pids] 0: descrambling pid 0050 on index 0
Oct 16 21:05:39 ctvdr vdr: [10562] [core.pids] 0: descrambling pid 0051 on index 0
Oct 16 21:05:39 ctvdr vdr: [10562] [core.pids] 0.0: pids after add VIDEO=00a0 AUDIO=0050 AUDIO=0051
Oct 16 21:05:39 ctvdr vdr: [10562] [core.ecm] 0.0: is no longer idle
Oct 16 21:05:39 ctvdr vdr: [10863] transfer thread started (pid=10562, tid=10863)
Oct 16 21:05:39 ctvdr vdr: [10864] receiver on device 1 thread started (pid=10562, tid=10864)
Oct 16 21:05:39 ctvdr vdr: [10600] [core.ecm] 0.0: triggered SID -1/13851 idx 0/0 mode -1/0 -
Oct 16 21:05:39 ctvdr vdr: [10865] TS buffer on device 1 thread started (pid=10562, tid=10865)
Oct 16 21:05:39 ctvdr vdr: [10562] creating directory /var/cache/vdr/vtx/S13.0E-318-200-13851
Oct 16 21:05:39 ctvdr vdr: [10600] [core.ecm] 0.0: got CaDescriptors for SID 13851 (len=17)
Oct 16 21:05:39 ctvdr vdr: [10600] [core.ecm] 0.0: descriptor 05 00 e5 de 10 01 01 13 01 20 14 03 02 26 10
Oct 16 21:05:39 ctvdr vdr: [10600] [core.ecm] 0.0: found 0500 (Viaccess) id 22610 with ecm 5de (new)
Oct 16 21:05:39 ctvdr vdr: [10600] [core.ecm] 0.0: try system Viaccess (0500) id 22610 with ecm 5de (pri=-10)
Oct 16 21:05:39 ctvdr vdr: [10600] [core.ecm] system: no key found for V 22610 08
Oct 16 21:05:39 ctvdr vdr: [10600] [core.ecm] 0.0: try system ConstCW (0500) id 22610 with ecm 5de (pri=-20)
Oct 16 21:05:39 ctvdr vdr: [10600] [core.ecm] system: no key found for X 500 00
Oct 16 21:05:39 ctvdr vdr: [10604] [core.au] 0: chain caid 0100 -> Seca(-10) [0b2e-82/ff/00] [0b2e-84/ff/00]
Oct 16 21:05:39 ctvdr vdr: [10604] [core.au] 0: chain caid 0100 ++ [0b2e-82/ff/00] [0b2e-84/ff/00] [0b40-82/ff/00]
Oct 16 21:05:39 ctvdr vdr: [10604] [core.au] 0: chain caid 0500 -> Viaccess(-10) [0304-8b/fe/07]
Oct 16 21:05:39 ctvdr vdr: [10604] [core.au] 0: chain caid 0500 ++ [0304-8b/fe/07] [0b25-8b/fe/07]
Oct 16 21:05:39 ctvdr vdr: [10604] [core.au] 0: chain caid 0604 -> Irdeto(-10) [0b39-82/ff/00]
Oct 16 21:05:39 ctvdr vdr: [10604] [core.au] 0: chain caid 1801 -> Nagra2(-10) [0b42-82/fe/00]
Oct 16 21:05:39 ctvdr vdr: [10604] [core.au] 0: starting chain 0100
Oct 16 21:05:39 ctvdr vdr: [10604] [core.au] 0: starting chain 0500
Oct 16 21:05:40 ctvdr vdr: [10604] [core.au] 0: starting chain 0604
Oct 16 21:05:40 ctvdr vdr: [10604] [core.au] 0: starting chain 1801
Oct 16 21:05:40 ctvdr vdr: [10600] [core.ecm] 0.0: got CaDescriptors for SID 13851 (len=17)
Oct 16 21:05:40 ctvdr vdr: [10600] [core.ecm] 0.0: descriptor 05 00 e5 de 10 01 01 13 01 20 14 03 02 26 10
Oct 16 21:05:40 ctvdr vdr: [10600] [core.ecm] 0.0: found 0500 (Viaccess) id 22610 with ecm 5de (already present)
Oct 16 21:05:40 ctvdr vdr: [10600] [core.ecm] 0.0: try system Viaccess (0500) id 22610 with ecm 5de (pri=-10)
Oct 16 21:05:40 ctvdr vdr: [10600] [core.ecm] system: no key found for V 22610 08
Oct 16 21:05:40 ctvdr vdr: [10600] [core.ecm] 0.0: try system ConstCW (0500) id 22610 with ecm 5de (pri=-20)
Oct 16 21:05:40 ctvdr vdr: [10600] [core.ecm] system: no key found for X 500 00
Oct 16 21:05:41 ctvdr vdr: [10600] [core.ecm] 0.0: got CaDescriptors for SID 13851 (len=17)
Oct 16 21:05:41 ctvdr vdr: [10600] [core.ecm] 0.0: descriptor 05 00 e5 de 10 01 01 13 01 20 14 03 02 26 10
Oct 16 21:05:41 ctvdr vdr: [10600] [core.ecm] 0.0: found 0500 (Viaccess) id 22610 with ecm 5de (already present)
Oct 16 21:05:41 ctvdr vdr: [10600] [core.ecm] 0.0: try system Viaccess (0500) id 22610 with ecm 5de (pri=-10)
Oct 16 21:05:46 ctvdr vdr: [10600] [core.ecm] system: no key found for V 22610 08
Oct 16 21:05:46 ctvdr vdr: [10600] [core.ecm] 0.0: try system ConstCW (0500) id 22610 with ecm 5de (pri=-20)
Oct 16 21:05:46 ctvdr vdr: [10600] [core.ecm] system: no key found for X 500 00
Oct 16 21:05:47 ctvdr vdr: [10600] [core.ecm] 0.0: got CaDescriptors for SID 13851 (len=17)
Oct 16 21:05:47 ctvdr vdr: [10600] [core.ecm] 0.0: descriptor 05 00 e5 de 10 01 01 13 01 20 14 03 02 26 10
Oct 16 21:05:47 ctvdr vdr: [10600] [core.ecm] 0.0: found 0500 (Viaccess) id 22610 with ecm 5de (already present)
Oct 16 21:05:47 ctvdr vdr: [10600] [core.ecm] 0.0: try system Viaccess (0500) id 22610 with ecm 5de (pri=-10)
Oct 16 21:05:47 ctvdr vdr: [10600] [core.ecm] 0.0: stopping message log until valid key is found
Oct 16 21:05:47 ctvdr vdr: [10603] [core.auStats] EMM packet load average (1/4/10min)  573  143   57 pks/s
Oct 16 21:05:51 ctvdr vdr: [10568] changing pids of channel 4 from 161+161:84=fra,85=eng:0 to 161+161:84=fra,85=eng:38
Oct 16 21:05:51 ctvdr vdr: [10568] changing caids of channel 4 from 0 to 500
Oct 16 21:06:08 ctvdr vdr: [10562] connect from 127.0.0.1, port 55132 - accepted
Oct 16 21:06:11 ctvdr vdr: [10863] ERROR: no useful data seen within 10525180 byte of video stream
Oct 16 21:06:17 ctvdr vdr: [10562] ERROR: can't open XPM file '/var/lib/vdr/plugins/skinelchi/logos/Canal+ Cinema.xpm'
Oct 16 21:06:17 ctvdr vdr: [10562] switching to channel 3
Oct 16 21:06:17 ctvdr vdr: [10863] transfer thread ended (pid=10562, tid=10863)
Oct 16 21:06:18 ctvdr vdr: [10562] [core.pids] 0: update SID 13851 (zero=1 noshift=1)
Oct 16 21:06:18 ctvdr vdr: [10562] [core.pids] 0: SID=13851 zero=1 | sid=0/13851/0 zero=0/13851/0 idle=-/-/-
Oct 16 21:06:18 ctvdr vdr: [10562] [core.pids] 0: found handler for SID 13851 (0.0 idle=0 idx=0)
Oct 16 21:06:18 ctvdr vdr: [10562] [core.pids] 0.0: pids on entry VIDEO=00a0 AUDIO=0050 AUDIO=0051
Oct 16 21:06:18 ctvdr vdr: [10562] [core.pids] 0.0: pids after delete
Oct 16 21:06:18 ctvdr vdr: [10562] [core.pids] 0.0: pids after add
Oct 16 21:06:18 ctvdr vdr: [10562] [core.ecm] 0.0: is idle now
Oct 16 21:06:18 ctvdr vdr: [10562] buffer stats: 71816 (3%) used
Oct 16 21:06:18 ctvdr vdr: [10865] TS buffer on device 1 thread ended (pid=10562, tid=10865)
Oct 16 21:06:18 ctvdr vdr: [10864] buffer stats: 44556 (2%) used
Oct 16 21:06:18 ctvdr vdr: [10864] receiver on device 1 thread ended (pid=10562, tid=10864)
Oct 16 21:06:18 ctvdr vdr: [10562] buffer stats: 0 (0%) used
Oct 16 21:06:18 ctvdr vdr: [10562] [core.pids] 0: tune to same source/transponder
Oct 16 21:06:18 ctvdr vdr: [10562] [core.pids] 0: livepids video=00a2 audio=0000
Oct 16 21:06:18 ctvdr vdr: [10562] [core.pids] 0: livepids video=00a2 audio=0058
Oct 16 21:06:18 ctvdr vdr: [10562] [core.pids] 0: add SID 13853 (zero=1 noshift=0)
Oct 16 21:06:18 ctvdr vdr: [10562] [core.pids] 0: pids VIDEO=00a2 AUDIO=0058
Oct 16 21:06:18 ctvdr vdr: [10562] [core.pids] 0: SID=13853 zero=1 | sid=-/-/- zero=0/13851/1 idle=0/13851/1
Oct 16 21:06:18 ctvdr vdr: [10562] [core.pids] 0: found handler for SID 13853 (0.0 idle=1 idx=0)
Oct 16 21:06:18 ctvdr vdr: [10562] [core.ecm] 0.0: setting new SID 13853
Oct 16 21:06:18 ctvdr vdr: [10562] [core.pids] 0.0: pids on entry
Oct 16 21:06:18 ctvdr vdr: [10562] [core.pids] 0.0: pids after delete
Oct 16 21:06:18 ctvdr vdr: [10562] [core.pids] 0: descrambling pid 00a2 on index 0
Oct 16 21:06:18 ctvdr vdr: [10562] [core.pids] 0: descrambling pid 0058 on index 0
Oct 16 21:06:18 ctvdr vdr: [10562] [core.pids] 0.0: pids after add VIDEO=00a2 AUDIO=0058
Oct 16 21:06:18 ctvdr vdr: [10562] [core.ecm] 0.0: is no longer idle
Oct 16 21:06:18 ctvdr vdr: [10562] [core.pids] 0: livepids video=00a2 audio=0059
Oct 16 21:06:18 ctvdr vdr: [10562] creating directory /var/cache/vdr/vtx/S13.0E-318-200-13853
Oct 16 21:06:18 ctvdr vdr: [10600] [core.ecm] 0.0: triggered SID 13851/13853 idx 0/0 mode 4/0 -
Oct 16 21:06:18 ctvdr vdr: [11072] receiver on device 1 thread started (pid=10562, tid=11072)
Oct 16 21:06:18 ctvdr vdr: [10600] [core.ecm] 0.0: got CaDescriptors for SID 13853 (len=17)
Oct 16 21:06:18 ctvdr vdr: [10600] [core.ecm] 0.0: descriptor 05 00 e6 06 10 01 01 13 01 20 14 03 02 26 10
Oct 16 21:06:18 ctvdr vdr: [10600] [core.ecm] 0.0: found 0500 (Viaccess) id 22610 with ecm 606 (new)
Oct 16 21:06:18 ctvdr vdr: [10600] [core.ecm] 0.0: try system Viaccess (0500) id 22610 with ecm 606 (pri=-10)
Oct 16 21:06:18 ctvdr vdr: [11073] TS buffer on device 1 thread started (pid=10562, tid=11073)
Oct 16 21:06:18 ctvdr vdr: [10600] [core.ecm] system: no key found for V 22610 08
Oct 16 21:06:18 ctvdr vdr: [10600] [core.ecm] 0.0: try system ConstCW (0500) id 22610 with ecm 606 (pri=-20)
Oct 16 21:06:19 ctvdr vdr: [10600] [core.ecm] system: no key found for X 500 00
Oct 16 21:06:19 ctvdr vdr: [10600] [core.ecm] 0.0: got CaDescriptors for SID 13853 (len=17)
Oct 16 21:06:19 ctvdr vdr: [10600] [core.ecm] 0.0: descriptor 05 00 e6 06 10 01 01 13 01 20 14 03 02 26 10
Oct 16 21:06:19 ctvdr vdr: [10600] [core.ecm] 0.0: found 0500 (Viaccess) id 22610 with ecm 606 (already present)
Oct 16 21:06:19 ctvdr vdr: [10600] [core.ecm] 0.0: try system Viaccess (0500) id 22610 with ecm 606 (pri=-10)
Oct 16 21:06:19 ctvdr vdr: [10600] [core.ecm] system: no key found for V 22610 08
Oct 16 21:06:19 ctvdr vdr: [10600] [core.ecm] 0.0: try system ConstCW (0500) id 22610 with ecm 606 (pri=-20)
Oct 16 21:06:20 ctvdr vdr: [10600] [core.ecm] system: no key found for X 500 00
Oct 16 21:06:20 ctvdr vdr: [10600] [core.ecm] 0.0: got CaDescriptors for SID 13853 (len=17)
Oct 16 21:06:20 ctvdr vdr: [10600] [core.ecm] 0.0: descriptor 05 00 e6 06 10 01 01 13 01 20 14 03 02 26 10
Oct 16 21:06:20 ctvdr vdr: [10600] [core.ecm] 0.0: found 0500 (Viaccess) id 22610 with ecm 606 (already present)
Oct 16 21:06:20 ctvdr vdr: [10600] [core.ecm] 0.0: try system Viaccess (0500) id 22610 with ecm 606 (pri=-10)
Oct 16 21:06:28 ctvdr vdr: [10600] [core.ecm] system: no key found for V 22610 08
Oct 16 21:06:28 ctvdr vdr: [10600] [core.ecm] 0.0: try system ConstCW (0500) id 22610 with ecm 606 (pri=-20)
Oct 16 21:06:28 ctvdr vdr: [10600] [core.ecm] system: no key found for X 500 00
Oct 16 21:06:29 ctvdr vdr: [10600] [core.ecm] 0.0: got CaDescriptors for SID 13853 (len=17)
Oct 16 21:06:29 ctvdr vdr: [10600] [core.ecm] 0.0: descriptor 05 00 e6 06 10 01 01 13 01 20 14 03 02 26 10
Oct 16 21:06:29 ctvdr vdr: [10600] [core.ecm] 0.0: found 0500 (Viaccess) id 22610 with ecm 606 (already present)
Oct 16 21:06:29 ctvdr vdr: [10600] [core.ecm] 0.0: try system Viaccess (0500) id 22610 with ecm 606 (pri=-10)
Oct 16 21:06:29 ctvdr vdr: [10600] [core.ecm] 0.0: stopping message log until valid key is found
Oct 16 21:06:40 ctvdr vdr: [10562] ERROR: can't open XPM file '/var/lib/vdr/plugins/skinelchi/logos/Canal+ Decale.xpm'
Oct 16 21:06:40 ctvdr vdr: [10562] switching to channel 4