yavdr 0.5 - ORF hängt und braucht lange bis es hell wird

3:10:40 yavdr vdr: [1536] switching to channel 5
Nov 18 13:10:40 yavdr vdr: [1536] [core.ci] 1/0.0 -> 00 01 a0 2e 01 90 02 00 01 9f 80 32 25 05 33 a7 01 00 1f 01 09 04 0d 05 e0 e8 09 04 0d 95 e1 1a 09 04 06 48 e0 84 09 04 17 02 e1 4c 09 04 18 33 e1 b0
Nov 18 13:10:40 yavdr vdr: [1536] [core.ci] 1/0.0 CA_PMT decoding len=25 lm=5 prg=13223 len=1f/1f ci_cmd(G)=01
Nov 18 13:10:40 yavdr vdr: [1536] [core.ci] 1/0.0 got CA pmt ciCmd=1 caLm=5
Nov 18 13:10:40 yavdr vdr: [1536] [core.ci] 1/0.0 answer to query surpressed
Nov 18 13:10:40 yavdr vdr: [1536] [core.ci] 1/0.0 set CAM decrypt (prg 13223)
Nov 18 13:10:40 yavdr vdr: [1536] [core.pids] 1/0: update SID 13223 (zero=0 noshift=1)
Nov 18 13:10:40 yavdr vdr: [1536] [core.pids] 1/0: SID=13223 zero=0 | sid=1/13223/0 zero=-/-/- idle=-/-/-
Nov 18 13:10:40 yavdr vdr: [1536] [core.pids] 1/0: found handler for SID 13223 (1/0.1 idle=0 idx=1)
Nov 18 13:10:40 yavdr vdr: [1536] [core.pids] 1/0.1: pids on entry VIDEO=08b6 AUDIO=08b7
Nov 18 13:10:40 yavdr vdr: [1536] [core.pids] 1/0.1: pids after delete
Nov 18 13:10:40 yavdr vdr: [1536] [core.pids] 1/0.1: pids after add
Nov 18 13:10:40 yavdr vdr: [1536] [core.ecm] 1/0.1: is idle now
Nov 18 13:10:40 yavdr vdr: [1536] [core.pids] 1/0: tune to same source/transponder
Nov 18 13:10:40 yavdr vdr: [1536] [core.ci] 1/0.0 -> 00 01 a0 38 01 90 02 00 01 9f 80 32 2f 04 33 a5 01 00 1f 01 09 04 0d 95 e1 19 09 04 06 48 e0 83 09 04 0d 05 e0 e7 09 04 17 02 e1 4b 09 04 18 33 e1 af 02 08 a2 00 00 04 08 a3 00 00
Nov 18 13:10:40 yavdr vdr: [1536] [core.ci] 1/0.0 CA_PMT decoding len=2f lm=4 prg=13221 len=1f/29 ci_cmd(G)=01 pid=2,8a2 len=0/5 pid=4,8a3 len=0/0
Nov 18 13:10:40 yavdr vdr: [1536] [core.ci] 1/0.0 got CA pmt ciCmd=1 caLm=4
Nov 18 13:10:40 yavdr vdr: [1536] [core.ci] 1/0.0 answer to query surpressed
Nov 18 13:10:40 yavdr vdr: [1536] [core.ci] 1/0.0 set CAM decrypt (prg 13221)
Nov 18 13:10:40 yavdr vdr: [1536] [core.pids] 1/0: add SID 13221 (zero=0 noshift=1)
Nov 18 13:10:40 yavdr vdr: [1536] [core.pids] 1/0: pids VIDEO=08a2 AUDIO=08a3
Nov 18 13:10:40 yavdr vdr: [1536] [core.pids] Ca descriptors after simplify (pidCa=0)
Nov 18 13:10:40 yavdr vdr: [1536] [core.pids] prgca: 09 04 0D 95 E1 19 09 04 06 48 E0 83 09 04 0D 05 E0 E7 09 04 17 02 E1 4B 09 04 18 33 E1 AF
Nov 18 13:10:40 yavdr vdr: [1536] [core.pids] pidca 08a2: <empty>
Nov 18 13:10:40 yavdr vdr: [1536] [core.pids] pidca 08a3: <empty>
Nov 18 13:10:40 yavdr vdr: [1536] [core.pids] 1/0: SID=13221 zero=0 | sid=-/-/- zero=-/-/- idle=1/13223/1
Nov 18 13:10:40 yavdr vdr: [1536] [core.pids] 1/0: found handler for SID 13221 (1/0.1 idle=1 idx=1)
Nov 18 13:10:40 yavdr vdr: [1536] [core.ecm] 1/0.1: setting new SID 13221
Nov 18 13:10:40 yavdr vdr: [1536] [core.pids] 1/0.1: pids on entry
Nov 18 13:10:40 yavdr vdr: [1536] [core.pids] 1/0.1: pids after delete
Nov 18 13:10:40 yavdr vdr: [1536] [core.pids] 1/0: descrambling pid 08a2 on index 1
Nov 18 13:10:40 yavdr vdr: [1536] [core.pids] 1/0: descrambling pid 08a3 on index 1
Nov 18 13:10:40 yavdr vdr: [1536] [core.pids] 1/0.1: pids after add VIDEO=08a2 AUDIO=08a3
Nov 18 13:10:40 yavdr vdr: [1536] [core.ecm] 1/0.1: is no longer idle
Nov 18 13:10:41 yavdr vdr: [2546] TS buffer on device 2 thread ended (pid=1536, tid=2546)
Nov 18 13:10:41 yavdr vdr: [2545] buffer stats: 0 (0%) used
Nov 18 13:10:41 yavdr vdr: [2545] receiver on device 2 thread ended (pid=1536, tid=2545)
Nov 18 13:10:41 yavdr vdr: [1825] [core.ecm] 1/0.1: triggered SID 13223/13221 idx 1/1 mode 3/0 -
Nov 18 13:10:41 yavdr vdr: [1825] [core.ecm] 1/0.1: new caDescr: 09 04 0D 95 E1 19 09 04 06 48 E0 83 09 04 0D 05 E0 E7 09 04 17 02 E1 4B 09 04 18 33 E1 AF
Nov 18 13:10:41 yavdr vdr: [1825] [core.ecm] from cache: system Cryptoworks (0d05) id 0000 with ecm e7/80
Nov 18 13:10:41 yavdr vdr: [1825] [core.ecm] 1/0.1: CA descriptors for SID 13221 (len=30)
Nov 18 13:10:41 yavdr vdr: [1825] [core.ecm] 1/0.1: descriptor 0d 95 e1 19
Nov 18 13:10:41 yavdr vdr: [1825] [core.ecm] 1/0.1: found 0d95(0000) (Cryptoworks) id 0000 with ecm 119/80 (new)
Nov 18 13:10:41 yavdr vdr: [1825] [core.ecm] 1/0.1: descriptor 06 48 e0 83
Nov 18 13:10:41 yavdr vdr: [1825] [core.ecm] 1/0.1: found 0648(0000) (Irdeto2) id 0000 with ecm 83/80 (new)
Nov 18 13:10:41 yavdr vdr: [1825] [core.ecm] 1/0.1: descriptor 0d 05 e0 e7
Nov 18 13:10:41 yavdr vdr: [1825] [core.ecm] 1/0.1: found 0d05(0000) (Cryptoworks) id 0000 with ecm e7/80 (already present)
Nov 18 13:10:41 yavdr vdr: [1825] [core.ecm] 1/0.1: descriptor 17 02 e1 4b
Nov 18 13:10:41 yavdr vdr: [1825] [core.ecm] 1/0.1: found 1702(0000) (Irdeto) id 0000 with ecm 14b/80 (new)
Nov 18 13:10:41 yavdr vdr: [1825] [core.ecm] 1/0.1: descriptor 18 33 e1 af
Nov 18 13:10:41 yavdr vdr: [1825] [core.ecm] 1/0.1: found 1833(0000) (Nagra2) id 0000 with ecm 1af/80 (new)
Nov 18 13:10:41 yavdr vdr: [1825] [core.ecm] 1/0.1: try system Cryptoworks (0d05) id 0000 with ecm e7 (cached) (pri=-10)
Nov 18 13:10:41 yavdr vdr: [2547] receiver on device 2 thread started (pid=1536, tid=2547)
Nov 18 13:10:41 yavdr vdr: [2548] TS buffer on device 2 thread started (pid=1536, tid=2548)
Nov 18 13:10:42 yavdr vdr: [1825] [core.ecm] 1/0.1: try system Irdeto2 (0648) id 0000 with ecm 83 (pri=-8)
Nov 18 13:10:43 yavdr vdr: [1825] [core.ecm] 1/0.1: try system Cryptoworks (0d95) id 0000 with ecm 119 (pri=-10)
Nov 18 13:10:45 yavdr vdr: [1825] [core.ecm] 1/0.1: try system Irdeto (0648) id 0000 with ecm 83 (pri=-10)
Nov 18 13:10:45 yavdr vdr: [2536] Text2Skin: channelInfo display update thread ended (pid=1536, tid=2536)
Nov 18 13:10:46 yavdr vdr: [1672] frontend 1/0 timed out while tuning to channel 5, tp 111244
Nov 18 13:10:47 yavdr vdr: [1825] [core.ecm] 1/0.1: try system Irdeto (1702) id 0000 with ecm 14b (pri=-10)
Nov 18 13:10:48 yavdr vdr: [1825] [core.ecm] 1/0.1: try system Nagra2 (1833) id 0000 with ecm 1af (pri=-10)
Nov 18 13:10:50 yavdr vdr: [1825] [core.ecm] 1/0.1: CA descriptors for SID 13221 (len=30)
Nov 18 13:10:50 yavdr vdr: [1825] [core.ecm] 1/0.1: descriptor 0d 95 e1 19
Nov 18 13:10:50 yavdr vdr: [1825] [core.ecm] 1/0.1: found 0d95(0000) (Cryptoworks) id 0000 with ecm 119/80 (already present)
Nov 18 13:10:50 yavdr vdr: [1825] [core.ecm] 1/0.1: descriptor 06 48 e0 83
Nov 18 13:10:50 yavdr vdr: [1825] [core.ecm] 1/0.1: found 0648(0000) (Irdeto2) id 0000 with ecm 83/80 (already present)
Nov 18 13:10:50 yavdr vdr: [1825] [core.ecm] 1/0.1: descriptor 0d 05 e0 e7
Nov 18 13:10:50 yavdr vdr: [1825] [core.ecm] 1/0.1: found 0d05(0000) (Cryptoworks) id 0000 with ecm e7/80 (already present)
Nov 18 13:10:50 yavdr vdr: [1825] [core.ecm] 1/0.1: descriptor 17 02 e1 4b
Nov 18 13:10:50 yavdr vdr: [1825] [core.ecm] 1/0.1: found 1702(0000) (Irdeto) id 0000 with ecm 14b/80 (already present)
Nov 18 13:10:50 yavdr vdr: [1825] [core.ecm] 1/0.1: descriptor 18 33 e1 af
Nov 18 13:10:50 yavdr vdr: [1825] [core.ecm] 1/0.1: found 1833(0000) (Nagra2) id 0000 with ecm 1af/80 (already present)
Nov 18 13:10:50 yavdr vdr: [1825] [core.ecm] 1/0.1: try system Cryptoworks (0d05) id 0000 with ecm e7 (cached) (pri=-10)
Nov 18 13:10:52 yavdr vdr: [2541] [core.ecm] 0/0.1: hold timeout expired
Nov 18 13:10:52 yavdr vdr: [1825] [core.ecm] 1/0.1: try system Irdeto2 (0648) id 0000 with ecm 83 (pri=-8)
Nov 18 13:10:53 yavdr vdr: [1825] [core.ecm] 1/0.1: try system Cryptoworks (0d95) id 0000 with ecm 119 (pri=-10)
Nov 18 13:10:55 yavdr vdr: [1825] [core.ecm] 1/0.1: try system Irdeto (0648) id 0000 with ecm 83 (pri=-10)
Nov 18 13:10:57 yavdr vdr: [1825] [core.ecm] 1/0.1: try system Irdeto (1702) id 0000 with ecm 14b (pri=-10)
Nov 18 13:10:58 yavdr vdr: [1825] [core.ecm] 1/0.1: try system Nagra2 (1833) id 0000 with ecm 1af (pri=-10)
Nov 18 13:11:00 yavdr vdr: [1825] [core.ecm] 1/0.1: CA descriptors for SID 13221 (len=30)
Nov 18 13:11:00 yavdr vdr: [1825] [core.ecm] 1/0.1: descriptor 0d 95 e1 19
Nov 18 13:11:00 yavdr vdr: [1825] [core.ecm] 1/0.1: found 0d95(0000) (Cryptoworks) id 0000 with ecm 119/80 (already present)
Nov 18 13:11:00 yavdr vdr: [1825] [core.ecm] 1/0.1: descriptor 06 48 e0 83
Nov 18 13:11:00 yavdr vdr: [1825] [core.ecm] 1/0.1: found 0648(0000) (Irdeto2) id 0000 with ecm 83/80 (already present)
Nov 18 13:11:00 yavdr vdr: [1825] [core.ecm] 1/0.1: descriptor 0d 05 e0 e7
Nov 18 13:11:00 yavdr vdr: [1825] [core.ecm] 1/0.1: found 0d05(0000) (Cryptoworks) id 0000 with ecm e7/80 (already present)
Nov 18 13:11:00 yavdr vdr: [1825] [core.ecm] 1/0.1: descriptor 17 02 e1 4b
Nov 18 13:11:00 yavdr vdr: [1825] [core.ecm] 1/0.1: found 1702(0000) (Irdeto) id 0000 with ecm 14b/80 (already present)
Nov 18 13:11:00 yavdr vdr: [1825] [core.ecm] 1/0.1: descriptor 18 33 e1 af
Nov 18 13:11:00 yavdr vdr: [1825] [core.ecm] 1/0.1: found 1833(0000) (Nagra2) id 0000 with ecm 1af/80 (already present)
Nov 18 13:11:00 yavdr vdr: [1825] [core.ecm] 1/0.1: try system Cryptoworks (0d05) id 0000 with ecm e7 (cached) (pri=-10)
Nov 18 13:11:02 yavdr vdr: [1825] [core.ecm] 1/0.1: try system Irdeto2 (0648) id 0000 with ecm 83 (pri=-8)
Nov 18 13:11:04 yavdr vdr: [1825] [core.ecm] 1/0.1: try system Cryptoworks (0d95) id 0000 with ecm 119 (pri=-10)
Nov 18 13:11:05 yavdr vdr: [1825] [core.ecm] 1/0.1: try system Irdeto (0648) id 0000 with ecm 83 (pri=-10)
Nov 18 13:11:07 yavdr vdr: [1825] [core.ecm] 1/0.1: try system Irdeto (1702) id 0000 with ecm 14b (pri=-10)
Nov 18 13:11:09 yavdr vdr: [1825] [core.ecm] 1/0.1: try system Nagra2 (1833) id 0000 with ecm 1af (pri=-10)
Nov 18 13:11:10 yavdr vdr: [1825] [core.ecm] 1/0.1: CA descriptors for SID 13221 (len=30)
Nov 18 13:11:10 yavdr vdr: [1825] [core.ecm] 1/0.1: descriptor 0d 95 e1 19
Nov 18 13:11:10 yavdr vdr: [1825] [core.ecm] 1/0.1: found 0d95(0000) (Cryptoworks) id 0000 with ecm 119/80 (already present)
Nov 18 13:11:10 yavdr vdr: [1825] [core.ecm] 1/0.1: descriptor 06 48 e0 83
Nov 18 13:11:10 yavdr vdr: [1825] [core.ecm] 1/0.1: found 0648(0000) (Irdeto2) id 0000 with ecm 83/80 (already present)
Nov 18 13:11:10 yavdr vdr: [1825] [core.ecm] 1/0.1: descriptor 0d 05 e0 e7
Nov 18 13:11:10 yavdr vdr: [1825] [core.ecm] 1/0.1: found 0d05(0000) (Cryptoworks) id 0000 with ecm e7/80 (already present)
Nov 18 13:11:10 yavdr vdr: [1825] [core.ecm] 1/0.1: descriptor 17 02 e1 4b
Nov 18 13:11:10 yavdr vdr: [1825] [core.ecm] 1/0.1: found 1702(0000) (Irdeto) id 0000 with ecm 14b/80 (already present)
Nov 18 13:11:10 yavdr vdr: [1825] [core.ecm] 1/0.1: descriptor 18 33 e1 af
Nov 18 13:11:10 yavdr vdr: [1825] [core.ecm] 1/0.1: found 1833(0000) (Nagra2) id 0000 with ecm 1af/80 (already present)
Nov 18 13:11:10 yavdr vdr: [1825] [core.ecm] 1/0.1: try system Cryptoworks (0d05) id 0000 with ecm e7 (cached) (pri=-10)
Nov 18 13:11:10 yavdr vdr: [1825] [core.ecm] 1/0.1: stopping message log until valid key is found
Nov 18 13:11:41 yavdr vdr: [1536] [core.ci] 0/0.0 -> 00 01 a0 0f 01 90 02 00 01 9f 80 32 06 03 00 00 01 00 00
Nov 18 13:11:41 yavdr vdr: [1536] [core.ci] 0/0.0 CA_PMT decoding len=6 lm=3 prg=0 len=0/0
Nov 18 13:11:41 yavdr vdr: [1536] [core.ci] 0/0.0 got CA pmt ciCmd=-1 caLm=3
Nov 18 13:11:41 yavdr vdr: [1536] [core.ci] 0/0.0 answer to query surpressed
Nov 18 13:11:41 yavdr vdr: [1536] [core.ci] 0/0.0 stop decrypt
Nov 18 13:11:41 yavdr vdr: [1536] [core.ecm] 0/0.1: stop
Nov 18 13:11:41 yavdr vdr: [1536] [core.pids] 0/0: now tuned to source 5300ff40(S19.2E) transponder 1b302
Nov 18 13:11:41 yavdr vdr: [2541] [core.ecm] 0/0.1: triggered SID 13012/-1 idx 1/1 mode -1/-1 -
Nov 18 13:11:41 yavdr vdr: [2541] [core.ecm] 0/0.1: new caDescr: <empty>
Nov 18 13:11:41 yavdr vdr: [1536] [core.ci] 0/0.0 -> 00 01 a0 0f 01 90 02 00 01 9f 80 32 06 03 00 00 01 00 00
Nov 18 13:11:41 yavdr vdr: [1536] [core.ci] 0/0.0 CA_PMT decoding len=6 lm=3 prg=0 len=0/0
Nov 18 13:11:41 yavdr vdr: [1536] [core.ci] 0/0.0 got CA pmt ciCmd=-1 caLm=3
Nov 18 13:11:41 yavdr vdr: [1536] [core.ci] 0/0.0 answer to query surpressed
Nov 18 13:11:41 yavdr vdr: [1536] [core.ci] 0/0.0 stop decrypt
Nov 18 13:11:42 yavdr vdr: [1672] frontend 1/0 regained lock on channel 5, tp 111244
Nov 18 13:11:42 yavdr vdr: [1825] [core.ecm] system: using key W 0D05 04 20 00 A9D7...
Nov 18 13:11:42 yavdr vdr: [1825] [core.ecm] 1/0.1: correct key found
Nov 18 13:11:42 yavdr vdr: [2547] [xine..put] Detected video size 720x576
Nov 18 13:11:46 yavdr vdr: [1830] [core.au] 1/0: chain caid 0d95 -> none available
Nov 18 13:11:46 yavdr vdr: [1830] [core.au] 1/0: chain caid 0648 -> Irdeto2(-8) Irdeto(-10) [0258-82/ff]
Nov 18 13:11:46 yavdr vdr: [1830] [core.au] 1/0: chain caid 0d05 -> none available
Nov 18 13:11:46 yavdr vdr: [1830] [core.au] 1/0: chain caid 09c4 -> none available
Nov 18 13:11:46 yavdr vdr: [1830] [core.au] 1/0: chain caid 1833 -> Nagra2(-10) [032b-82/fe]
Nov 18 13:11:46 yavdr vdr: [1830] [core.au] 1/0: chain caid 1702 -> Irdeto(-10) [0263-82/ff]
Nov 18 13:12:02 yavdr vdr: [1536] [core.pids] 0/0: now tuned to source 5300ff40(S19.2E) transponder 1b368
Nov 18 13:12:23 yavdr vdr: [1536] [core.ci] 0/0.0 -> 00 01 a0 0f 01 90 02 00 01 9f 80 32 06 03 00 00 01 00 00
Nov 18 13:12:23 yavdr vdr: [1536] [core.ci] 0/0.0 CA_PMT decoding len=6 lm=3 prg=0 len=0/0
Nov 18 13:12:23 yavdr vdr: [1536] [core.ci] 0/0.0 got CA pmt ciCmd=-1 caLm=3
Nov 18 13:12:23 yavdr vdr: [1536] [core.ci] 0/0.0 answer to query surpressed
Nov 18 13:12:23 yavdr vdr: [1536] [core.ci] 0/0.0 stop decrypt
Nov 18 13:12:23 yavdr vdr: [1536] [core.pids] 0/0: now tuned to source 5300ff40(S19.2E) transponder 1b467
Nov 18 13:12:24 yavdr vdr: [1672] frontend 1/0 lost lock on channel 5, tp 111244
Nov 18 13:12:25 yavdr vdr: [1641] changing pids of channel 112 from 3327+3327=2:3328=deu@3,3329=deu@3:0:32 to 3327+3327=2:3328=deu@3:0:32
Nov 18 13:12:26 yavdr vdr: [1672] frontend 1/0 timed out while tuning to channel 5, tp 111244