674 kbit/s)
Jul 18 12:45:59 easyVDR vdr: [3105] [xine..put] OSD bandwidth: 214299 bytes/s (1674 kbit/s)
Jul 18 12:45:59 easyVDR vdr: [3105] Text2Skin: channelInfo display update thread ended (pid=2799, tid=3105)
Jul 18 12:46:01 easyVDR vdr: [2836] [core.ci] card 0/0, slot 0 (v= 2) caids: 1818 1817 1811 1819 0d00 1863 1833 1702 0d05 1810 0d95 1834 1861 0d03 0d70 0d96 0d02 1812 1836 1837
Jul 18 12:46:01 easyVDR vdr: [2836] [core.ci] 0/0.0: now using CAIDs version 2
Jul 18 12:46:01 easyVDR vdr: [2836] [core.ci] 0/0.0 sending CA info
Jul 18 12:46:01 easyVDR vdr: [2836] [core.ci] 0/0.0 -> 00 01 81 01 01
Jul 18 12:46:01 easyVDR vdr: [2836] [core.ci] 0/0.0 <- 00 01 a0 31 01 90 02 00 01 9f 80 31 28 18 18 18 17 18 11 18 19 0d 00 18 63 18 33 17 02 0d 05 18 10 0d 95 18 34 18 61 0d 03 0d 70 0d 96 0d 02 18 12 18 36 18 37 80 02 01 00
Jul 18 12:46:01 easyVDR vdr: [2835] [core.ci] card 1/0, slot 0 (v= 2) caids: 1818 1817 1811 1819 0d00 1863 1833 1702 0d05 1810 0d95 1834 1861 0d03 0d70 0d96 0d02 1812 1836 1837
Jul 18 12:46:01 easyVDR vdr: [2835] [core.ci] 1/0.0: now using CAIDs version 2
Jul 18 12:46:01 easyVDR vdr: [2835] [core.ci] 1/0.0 sending CA info
Jul 18 12:46:01 easyVDR vdr: [2835] [core.ci] 1/0.0 -> 00 01 81 01 01
Jul 18 12:46:01 easyVDR vdr: [2835] [core.ci] 1/0.0 <- 00 01 a0 31 01 90 02 00 01 9f 80 31 28 18 18 18 17 18 11 18 19 0d 00 18 63 18 33 17 02 0d 05 18 10 0d 95 18 34 18 61 0d 03 0d 70 0d 96 0d 02 18 12 18 36 18 37 80 02 01 00
Jul 18 12:46:03 easyVDR vdr: [3131] Text2Skin: channelInfo display update thread started (pid=2799, tid=3131)
Jul 18 12:46:04 easyVDR vdr: [3131] [xine..put] OSD bandwidth: 214648 bytes/s (1676 kbit/s)
Jul 18 12:46:05 easyVDR vdr: [3131] [xine..put] OSD bandwidth: 290565 bytes/s (2270 kbit/s)
Jul 18 12:46:06 easyVDR vdr: [3131] [xine..put] OSD bandwidth: 193196 bytes/s (1509 kbit/s)
Jul 18 12:46:08 easyVDR vdr: [3131] [xine..put] OSD bandwidth: 192595 bytes/s (1504 kbit/s)
Jul 18 12:46:09 easyVDR vdr: [3131] [xine..put] OSD bandwidth: 193073 bytes/s (1508 kbit/s)
Jul 18 12:46:11 easyVDR vdr: [3131] [xine..put] OSD bandwidth: 192872 bytes/s (1506 kbit/s)
Jul 18 12:46:11 easyVDR vdr: [2799] switching to channel 173
Jul 18 12:46:12 easyVDR vdr: [2799] [core.pids] 0/0: now tuned to source 5300ff40(S19.2E) transponder 1b5a0
Jul 18 12:46:12 easyVDR vdr-sxfe[2927]: [3032] [input_vdr] wait_stream_sync: discard_index 13848456 != curpos 13750696 ! (diff 97760)
Jul 18 12:46:12 easyVDR vdr: [3106] TS buffer on device 1 thread ended (pid=2799, tid=3106)
Jul 18 12:46:12 easyVDR vdr: [3104] buffer stats: 122012 (2%) used
Jul 18 12:46:12 easyVDR vdr: [3104] receiver on device 1 thread ended (pid=2799, tid=3104)
Jul 18 12:46:12 easyVDR vdr: [3132] receiver on device 1 thread started (pid=2799, tid=3132)
Jul 18 12:46:12 easyVDR vdr: [3133] TS buffer on device 1 thread started (pid=2799, tid=3133)
Jul 18 12:46:12 easyVDR vdr: [3102] [core.au] 0/0: chain caid 1702 -> Cardclient(-15) [1000-82/ff]
Jul 18 12:46:12 easyVDR vdr: [3102] [core.au] 0/0: chain caid 1722 -> none available
Jul 18 12:46:12 easyVDR vdr: [3102] [core.au] 0/0: chain caid 1833 -> Nagra2(-10) [1002-82/fe]
Jul 18 12:46:12 easyVDR vdr: [3102] [core.au] 0/0: chain caid 1836 -> Nagra2(-10) [1002-82/fe]
Jul 18 12:46:12 easyVDR vdr: [3102] [core.au] 0/0: chain caid 09c4 -> none available
Jul 18 12:46:12 easyVDR vdr: [3102] [core.au] 0/0: chain caid 09af -> none available
Jul 18 12:46:12 easyVDR vdr: [3102] [core.au] 0/0: chain caid 098c -> none available
Jul 18 12:46:12 easyVDR vdr: [2811] changing portal name of channel 180 from '' to 'Info Tafel'
Jul 18 12:46:12 easyVDR vdr: [3132] [xine..put] Detected video size 720x576
Jul 18 12:46:12 easyVDR vdr: [3131] [xine..put] OSD bandwidth: 192293 bytes/s (1502 kbit/s)
Jul 18 12:46:12 easyVDR vdr: [2811] changing portal name of channel 173 from '' to 'Sky Select'
Jul 18 12:46:13 easyVDR vdr: [2799] CAM 3: assigned to device 2
Jul 18 12:46:13 easyVDR vdr: [2799] switching to channel 174
Jul 18 12:46:13 easyVDR vdr-sxfe[2927]: [2992] [input_vdr] vdr_flush_engine: playback is paused <0>
Jul 18 12:46:13 easyVDR vdr-sxfe[2927]: [3032] [input_vdr] wait_stream_sync: discard_index 14224832 != curpos 14187232 ! (diff 37600)
Jul 18 12:46:13 easyVDR vdr: [3133] TS buffer on device 1 thread ended (pid=2799, tid=3133)
Jul 18 12:46:13 easyVDR vdr: [2799] [core.pids] 1/0: now tuned to source 5300ff40(S19.2E) transponder 1b5c6
Jul 18 12:46:13 easyVDR vdr: [3132] buffer stats: 52452 (1%) used
Jul 18 12:46:13 easyVDR vdr: [3132] receiver on device 1 thread ended (pid=2799, tid=3132)
Jul 18 12:46:13 easyVDR vdr: [3134] logger 1/0 filter thread started (pid=2799, tid=3134)
Jul 18 12:46:13 easyVDR vdr: [2799] [core.ci] 1/0.0 -> 00 01 a0 1e 01 90 02 00 01 9f 80 32 15 03 00 2a 01 00 00 02 02 ff 00 00 04 03 00 00 00 04 03 01 00 00
Jul 18 12:46:13 easyVDR vdr: [2799] [core.ci] 1/0.0 CA_PMT decoding len=15 lm=3 prg=42 len=0/f pid=2,2ff len=0/a pid=4,300 len=0/5 pid=4,301 len=0/0
Jul 18 12:46:13 easyVDR vdr: [2799] [core.ci] 1/0.0 got CA pmt ciCmd=-1 caLm=3
Jul 18 12:46:13 easyVDR vdr: [2799] [core.ci] 1/0.0 stop decrypt
Jul 18 12:46:13 easyVDR vdr: [3135] receiver on device 2 thread started (pid=2799, tid=3135)
Jul 18 12:46:13 easyVDR vdr: [3136] TS buffer on device 2 thread started (pid=2799, tid=3136)
Jul 18 12:46:13 easyVDR vdr: [3131] [xine..put] OSD bandwidth: 310035 bytes/s (2422 kbit/s)
Jul 18 12:46:14 easyVDR vdr: [3102] [core.au] 0/0: starting chain 1702
Jul 18 12:46:14 easyVDR vdr: [3102] [core.au] 0/0: starting chain 1833
Jul 18 12:46:14 easyVDR vdr: [3102] [core.au] 0/0: starting chain 1836
Jul 18 12:46:14 easyVDR vdr: [2799] retuning due to modification of channel 174
Jul 18 12:46:14 easyVDR vdr: [2799] switching to channel 174
Jul 18 12:46:14 easyVDR vdr-sxfe[2927]: [2992] [input_vdr] vdr_flush_engine: playback is paused <0>
Jul 18 12:46:14 easyVDR vdr-sxfe[2927]: [3032] [input_vdr] wait_stream_sync: discard_index 14448928 != curpos 14447048 ! (diff 1880)
Jul 18 12:46:14 easyVDR vdr: [2799] [core.pids] 1/0: tune to same source/transponder
Jul 18 12:46:14 easyVDR vdr: [3131] [xine..put] OSD bandwidth: 214300 bytes/s (1674 kbit/s)
Jul 18 12:46:14 easyVDR vdr: [3134] [core.au] 1/0: chain caid 1702 -> Cardclient(-15) [1000-82/ff]
Jul 18 12:46:14 easyVDR vdr: [3134] [core.au] 1/0: chain caid 1722 -> none available
Jul 18 12:46:14 easyVDR vdr: [3134] [core.au] 1/0: chain caid 1837 -> Nagra2(-10) [1004-82/fe]
Jul 18 12:46:14 easyVDR vdr: [3134] [core.au] 1/0: chain caid 1833 -> Nagra2(-10) [1002-82/fe]
Jul 18 12:46:14 easyVDR vdr: [3134] [core.au] 1/0: chain caid 1836 -> Nagra2(-10) [1002-82/fe]
Jul 18 12:46:14 easyVDR vdr: [3134] [core.au] 1/0: chain caid 09c4 -> none available
Jul 18 12:46:14 easyVDR vdr: [3134] [core.au] 1/0: chain caid 09af -> none available
Jul 18 12:46:14 easyVDR vdr: [3134] [core.au] 1/0: chain caid 098c -> none available
Jul 18 12:46:14 easyVDR vdr: [3136] TS buffer on device 2 thread ended (pid=2799, tid=3136)
Jul 18 12:46:14 easyVDR vdr: [3135] buffer stats: 19552 (0%) used
Jul 18 12:46:14 easyVDR vdr: [2799] [core.ci] 1/0.0 -> 00 01 a0 39 01 90 02 00 01 9f 80 32 30 03 00 2a 01 00 1b 01 09 06 17 02 f7 5e 00 5e 09 04 18 33 f8 5e 09 04 18 34 fc 5e 09 04 18 61 f9 5e 02 02 ff 00 00 04 03 00 00 00 04 03 01 00 00
Jul 18 12:46:14 easyVDR vdr: [2799] [core.ci] 1/0.0 CA_PMT decoding len=30 lm=3 prg=42 len=1b/2a ci_cmd(G)=01 pid=2,2ff len=0/a pid=4,300 len=0/5 pid=4,301 len=0/0
Jul 18 12:46:14 easyVDR vdr: [2799] [core.ci] 1/0.0 got CA pmt ciCmd=1 caLm=3
Jul 18 12:46:14 easyVDR vdr: [2799] [core.ci] 1/0.0 set CAM decrypt (prg 42)
Jul 18 12:46:14 easyVDR vdr: [2799] [core.pids] 1/0: add SID 42 (
zero=1 noshift=0)
Jul 18 12:46:14 easyVDR vdr: [2799] [core.pids] 1/0: pids VIDEO=02ff AUDIO=0300 AUDIO=0301
Jul 18 12:46:14 easyVDR vdr: [2799] [core.pids] Ca descriptors after simplify (pidCa=0)
Jul 18 12:46:14 easyVDR vdr: [2799] [core.pids] prgca: 09 06 17 02 F7 5E 00 5E 09 04 18 33 F8 5E 09 04 18 34 FC 5E 09 04 18 61 F9 5E
Jul 18 12:46:14 easyVDR vdr: [2799] [core.pids] pidca 02ff: <empty>
Jul 18 12:46:14 easyVDR vdr: [2799] [core.pids] pidca 0300: <empty>
Jul 18 12:46:14 easyVDR vdr: [2799] [core.pids] pidca 0301: <empty>
Jul 18 12:46:14 easyVDR vdr: [2799] [core.pids] 1/0: SID=42
zero=1 | sid=-/-/-
zero=-/-/- idle=-/-/-
Jul 18 12:46:14 easyVDR vdr: [2799] [core.pids] 1/0: found handler for SID 42 (1/0.0 idle=1 idx=0)
Jul 18 12:46:14 easyVDR vdr: [2799] [core.ecm] 1/0.0: setting new SID 42
Jul 18 12:46:14 easyVDR vdr: [2799] [core.pids] 1/0.0: pids on entry
Jul 18 12:46:14 easyVDR vdr: [2799] [core.pids] 1/0.0: pids after delete
Jul 18 12:46:14 easyVDR vdr: [2799] [core.pids] 1/0: descrambling pid 02ff on index 0
Jul 18 12:46:14 easyVDR vdr: [2799] [core.pids] 1/0: descrambling pid 0300 on index 0
Jul 18 12:46:14 easyVDR vdr: [2799] [core.pids] 1/0: descrambling pid 0301 on index 0
Jul 18 12:46:14 easyVDR vdr: [2799] [core.pids] 1/0.0: pids after add VIDEO=02ff AUDIO=0300 AUDIO=0301
Jul 18 12:46:14 easyVDR vdr: [2799] [core.ecm] 1/0.0: is no longer idle
Jul 18 12:46:14 easyVDR vdr: [3135] receiver on device 2 thread ended (pid=2799, tid=3135)
Jul 18 12:46:14 easyVDR vdr: [3137] ecmhandler 1/0 filter thread started (pid=2799, tid=3137)
Jul 18 12:46:14 easyVDR vdr: [3138] receiver on device 2 thread started (pid=2799, tid=3138)
Jul 18 12:46:15 easyVDR vdr: [3139] TS buffer on device 2 thread started (pid=2799, tid=3139)
Jul 18 12:46:15 easyVDR vdr: [3137] [core.ecm] 1/0.0: triggered SID -1/42 idx -1/0 mode -1/0 -
Jul 18 12:46:15 easyVDR vdr: [3137] [core.ecm] 1/0.0: new caDescr: 09 06 17 02 F7 5E 00 5E 09 04 18 33 F8 5E 09 04 18 34 FC 5E 09 04 18 61 F9 5E
Jul 18 12:46:15 easyVDR vdr: [3137] [core.ecm] from cache: system Cardclient (1702) id 0000 with ecm 175e/80
Jul 18 12:46:15 easyVDR vdr: [3137] [core.ecm] 1/0.0: CA descriptors for SID 42 (len=26)
Jul 18 12:46:15 easyVDR vdr: [3137] [core.ecm] 1/0.0: descriptor 17 02 f7 5e 00 5e
Jul 18 12:46:15 easyVDR vdr: [3137] [core.ecm] 1/0.0: found 1702(0000) (Cardclient) id 0000 with ecm 175e/80 (already present)
Jul 18 12:46:15 easyVDR vdr: [3137] [core.ecm] 1/0.0: descriptor 18 33 f8 5e
Jul 18 12:46:15 easyVDR vdr: [3137] [core.ecm] 1/0.0: found 1833(0000) (Nagra2) id 0000 with ecm 185e/80 (new)
Jul 18 12:46:15 easyVDR vdr: [3137] [core.ecm] 1/0.0: descriptor 18 34 fc 5e
Jul 18 12:46:15 easyVDR vdr: [3137] [core.ecm] 1/0.0: found 1834(0000) (Nagra2) id 0000 with ecm 1c5e/80 (new)
Jul 18 12:46:15 easyVDR vdr: [3137] [core.ecm] 1/0.0: descriptor 18 61 f9 5e
Jul 18 12:46:15 easyVDR vdr: [3137] [core.ecm] 1/0.0: found 1861(0000) (Nagra2) id 0000 with ecm 195e/80 (new)
Jul 18 12:46:15 easyVDR vdr: [3137] [core.au] 1/0: starting chain 1702
Jul 18 12:46:15 easyVDR vdr: [3137] [core.ecm] 1/0.0: try system Cardclient (1702) id 0000 with ecm 175e (cached) (pri=-15)
Jul 18 12:46:15 easyVDR vdr: [3137] [cardclient.core] cc-loop
Jul 18 12:46:15 easyVDR vdr: [3137] [cardclient.core] now trying client cccam2 (192.168.27.3:12000)
Jul 18 12:46:15 easyVDR vdr: [3137] [cardclient.cccam2] ECM caid 1702 prov 0000 sid 42 pid 175e
Jul 18 12:46:15 easyVDR vdr: [3137] [cardclient.cccam2shares] share try list for caid 1702 prov 000000 pid 175e
Jul 18 12:46:15 easyVDR vdr: [3137] [cardclient.cccam2shares] shareid 00000064 hops 0 lag 1000
Jul 18 12:46:15 easyVDR vdr: [3137] [cardclient.cccam2extra] now try shareid 00000064
Jul 18 12:46:15 easyVDR vdr: [2833] [cardclient.cccam2] got CW, current shareid 00000064 (pending 0, EMM 0, maxecmcount=0)
Jul 18 12:46:15 easyVDR vdr: [3137] [cardclient.cccam2extra] wait returned after 571
Jul 18 12:46:15 easyVDR vdr: [3137] [cardclient.cccam2shares] added shareid 00000064 for 175e/5300ff40/1b5c6 status 1
Jul 18 12:46:15 easyVDR vdr: [3137] [cardclient.cccam2] got CW
Jul 18 12:46:15 easyVDR vdr: [3137] [general.error] CA_SET_DESCR failed (Erfolg). Expect a black screen.
Jul 18 12:46:15 easyVDR vdr: [3137] [core.ecm] 1/0.0: correct key found
Jul 18 12:46:16 easyVDR vdr: [3131] [xine..put] OSD bandwidth: 214300 bytes/s (1674 kbit/s)
Jul 18 12:46:17 easyVDR vdr: [3131] [xine..put] OSD bandwidth: 214290 bytes/s (1674 kbit/s)