Closed EricV closed 12 years ago
There was indeed a deadlock situation. You are running pvr and your desktop resolution is not 50hz? That's a weird use case :)
I do not configure my TV display each time. I guess AMD choose 60 by default. Then, if xranrd code was a bit more evolved, once code as finished probing the media, it could switch to the right frequency :-)
Tested the fix. Started correctly after flickering for ~5s. But 25s later I was having problems with my log filed with the following lines. I had to stop the display and restart. Got again the initial 5s flickering but seems stable now.
09:34:17 T:139733148272384 NOTICE: --------------------- hurry: 1 09:34:17 T:139733365163776 WARNING: CDVDMessageQueue(audio)::Get - asked for new data packet, with nothing a vailable 09:34:17 T:139733148272384 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped de-interlacing cycle, Sle eptime: -7.806020, Bufferlevel: 2 09:34:17 T:139733148272384 NOTICE: --------------------- hurry: 1 09:34:17 T:139733148272384 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped de-interlacing cycle, Sle eptime: -7.303118, Bufferlevel: 2 09:34:17 T:139733148272384 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped de-interlacing cycle, Sle eptime: -7.362729, Bufferlevel: 2 09:34:17 T:139733148272384 WARNING: CRenderManager::WaitForBuffer - timeout waiting for buffer 09:34:17 T:139733148272384 NOTICE: --------------------- hurry: 1 09:34:17 T:139733148272384 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped de-interlacing cycle, Sle eptime: -7.462374, Bufferlevel: 1
Got a crash withs segfault:
Program terminated with signal 11, Segmentation fault.
1394 xvba->Acquire();
Thread 1 (Thread 0x7f161d298700 (LWP 13720)):
I have just fixed the one you posted last :)
Starting is now mainly OK but I have a lot of deinterlacing problems: It starts correctly but begins to fail. If I set off, image stabilize again, I reput on, it works for a while and becomes unwatchable again and does NEVER recover unless I set interlacing off manually and then ON again.
BTW: VAAPI is broken on your master branch. I wanted to make sure deinterlacing for VAAPI is not available on AMD.
There is no log under the link you have pasted. But I have an idea what is causing the problem.
What's the problem with vaapi?
Will send it bey email then. I just wanted to check non deinterlacing capabilities for a call I have friday. Got sound but only black screen. Must kill xbmc to get display back.
To be sure it was not causing subsequent problem I rebooted before testing anything else.
Even when visiually acceptable except some drops, I get that in my logs
13:26:06 T:140145936099072 WARNING: Previous line repeats 19 times. 13:26:06 T:140145936099072 DEBUG: --------------------- hurry: 1 13:26:06 T:140145655023360 DEBUG: Previous line repeats 2 times. 13:26:06 T:140145655023360 WARNING: CDVDMessageQueue(audio)::Get - asked for new data packet, with nothing available 13:26:06 T:140145936099072 DEBUG: --------------------- hurry: 1 13:26:07 T:140145655023360 DEBUG: Previous line repeats 3 times. 13:26:07 T:140145655023360 WARNING: CDVDMessageQueue(audio)::Get - asked for new data packet, with nothing available 13:26:07 T:140145936099072 DEBUG: --------------------- hurry: 1 13:26:07 T:140145655023360 WARNING: CDVDMessageQueue(audio)::Get - asked for new data packet, with nothing available 13:26:07 T:140145936099072 DEBUG: --------------------- hurry: 1 13:26:07 T:140145655023360 DEBUG: Previous line repeats 3 times. 13:26:07 T:140145655023360 WARNING: CDVDMessageQueue(audio)::Get - asked for new data packet, with nothing available 13:26:07 T:140145936099072 DEBUG: --------------------- hurry: 1 13:26:08 T:140145655023360 DEBUG: Previous line repeats 2 times. 13:26:08 T:140145655023360 WARNING: CDVDMessageQueue(audio)::Get - asked for new data packet, with nothing available 13:26:08 T:140145936099072 DEBUG: --------------------- hurry: 1 13:26:08 T:140145655023360 DEBUG: Previous line repeats 2 times. 13:26:08 T:140145655023360 WARNING: CDVDMessageQueue(audio)::Get - asked for new data packet, with nothing available 13:26:08 T:140145936099072 DEBUG: --------------------- hurry: 1 13:26:08 T:140145655023360 DEBUG: Previous line repeats 2 times. 13:26:08 T:140145655023360 WARNING: CDVDMessageQueue(audio)::Get - asked for new data packet, with nothing available 13:26:08 T:140145936099072 DEBUG: --------------------- hurry: 1 13:26:08 T:140145655023360 DEBUG: Previous line repeats 1 times. 13:26:08 T:140145655023360 WARNING: CDVDMessageQueue(audio)::Get - asked for new data packet, with nothing available 13:26:09 T:140145936099072 DEBUG: --------------------- hurry: 1 13:26:09 T:140145655023360 DEBUG: Previous line repeats 2 times. 13:26:09 T:140145655023360 WARNING: CDVDMessageQueue(audio)::Get - asked for new data packet, with nothing available 13:26:09 T:140145936099072 DEBUG: --------------------- hurry: 1 13:26:09 T:140145655023360 DEBUG: Previous line repeats 3 times. 13:26:09 T:140145655023360 WARNING: CDVDMessageQueue(audio)::Get - asked for new data packet, with nothing available 13:26:09 T:140145936099072 DEBUG: --------------------- hurry: 1 13:26:10 T:140145655023360 DEBUG: Previous line repeats 2 times. 13:26:10 T:140145655023360 WARNING: CDVDMessageQueue(audio)::Get - asked for new data packet, with nothing available 13:26:10 T:140145936099072 DEBUG: --------------------- hurry: 1 13:26:10 T:140145655023360 DEBUG: Previous line repeats 1 times. 13:26:10 T:140145655023360 WARNING: CDVDMessageQueue(audio)::Get - asked for new data packet, with nothing available 13:26:20 T:140145720268544 WARNING: Previous line repeats 36 times. 13:26:20 T:140145720268544 DEBUG: AddOnLog: Tvheadend HTSP Client: ParseChannelUpdate - id:1, name:'(null)', icon:'(null)', event:2998 13:26:20 T:140145655023360 WARNING: CDVDMessageQueue(audio)::Get - asked for new data packet, with nothing available
Here is a log capture when it started to go wrong after 2 hours:
14:21:30 T:140145596274432 DEBUG: Thread Jobworker 140145596274432 terminating (autodelete) 14:21:30 T:140145655023360 WARNING: CDVDMessageQueue(audio)::Get - asked for new data packet, with nothing available 14:22:00 T:140145596274432 WARNING: Previous line repeats 250 times. 14:22:00 T:140145596274432 ERROR: SetThreadInfo: error Permission denied 14:22:00 T:140145596274432 NOTICE: Thread Jobworker start, auto delete: true 14:22:00 T:140145655023360 WARNING: CDVDMessageQueue(audio)::Get - asked for new data packet, with nothing available 14:22:30 T:140145596274432 WARNING: Previous line repeats 248 times. 14:22:30 T:140145596274432 DEBUG: Thread Jobworker 140145596274432 terminating (autodelete) 14:22:30 T:140145655023360 WARNING: CDVDMessageQueue(audio)::Get - asked for new data packet, with nothing available 14:23:00 T:140145701365504 WARNING: Previous line repeats 249 times. 14:23:00 T:140145701365504 ERROR: SetThreadInfo: error Permission denied 14:23:00 T:140145701365504 NOTICE: Thread Jobworker start, auto delete: true 14:23:00 T:140145655023360 WARNING: CDVDMessageQueue(audio)::Get - asked for new data packet, with nothing available 14:23:30 T:140145701365504 WARNING: Previous line repeats 249 times. 14:23:30 T:140145701365504 DEBUG: Thread Jobworker 140145701365504 terminating (autodelete) 14:23:30 T:140145655023360 WARNING: CDVDMessageQueue(audio)::Get - asked for new data packet, with nothing available 14:23:43 T:140145655023360 WARNING: Previous line repeats 109 times. 14:23:43 T:140145655023360 DEBUG: CDVDPlayerAudio:: Discontinuity - was:62444920548.684013, should be:62444818132.528580, error:-102416.155434 14:23:43 T:140145655023360 WARNING: CDVDMessageQueue(audio)::Get - asked for new data packet, with nothing available 14:23:43 T:140145936099072 WARNING: Previous line repeats 1 times. 14:23:43 T:140145936099072 DEBUG: XVBA::Decode long wait: 94 14:23:43 T:140145655023360 WARNING: CDVDMessageQueue(audio)::Get - asked for new data packet, with nothing available 14:23:44 T:140145655023360 WARNING: Previous line repeats 2 times. 14:23:44 T:140145655023360 DEBUG: CDVDPlayerAudio:: Discontinuity - was:62445418476.838577, should be:62445519445.673874, error:100968.835297 14:23:44 T:140145655023360 WARNING: CDVDMessageQueue(audio)::Get - asked for new data packet, with nothing available 14:23:44 T:140145936099072 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped de-interlacing cycle, Sleeptime: -0.028831, Bufferlevel: 2 14:23:44 T:140145655023360 WARNING: CDVDMessageQueue(audio)::Get - asked for new data packet, with nothing available 14:23:44 T:140145936099072 WARNING: Previous line repeats 2 times. 14:23:44 T:140145936099072 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped de-interlacing cycle, Sleeptime: -0.009219, Bufferlevel: 3 14:23:44 T:140145655023360 WARNING: CDVDMessageQueue(audio)::Get - asked for new data packet, with nothing available 14:23:46 T:140145936099072 WARNING: Previous line repeats 20 times. 14:23:46 T:140145936099072 DEBUG: CPullupCorrection: detected pattern of length 1: 20000.00, frameduration: 20000.000000 14:23:47 T:140145655023360 WARNING: CDVDMessageQueue(audio)::Get - asked for new data packet, with nothing available 14:24:00 T:140145680201472 WARNING: Previous line repeats 109 times. 14:24:00 T:140145680201472 ERROR: SetThreadInfo: error Permission denied 14:24:00 T:140145680201472 NOTICE: Thread Jobworker start, auto delete: true 14:24:00 T:140145655023360 WARNING: CDVDMessageQueue(audio)::Get - asked for new data packet, with nothing available 14:24:30 T:140145680201472 WARNING: Previous line repeats 249 times. 14:24:30 T:140145680201472 DEBUG: Thread Jobworker 140145680201472 terminating (autodelete) 14:24:30 T:140145655023360 WARNING: CDVDMessageQueue(audio)::Get - asked for new data packet, with nothing available
De-interlacing problems shold be fixed by 21024218d14e0621dfb3e12cca80ecec5c992f3a
Thanks. Will test and report ASAP. Trying to recode Xranrd.cpp but always get interrupted...
So far so good. It at least fixes the 5 second shuttering I always had at the beginning of any video.
Note that we use gnu style for coding.
well code is more important than the way it is indented because actually its pretty bogus. My emacs has a coding policy that comes from long ago...
So far no problem. I only managed to lock something when using XBMC DMR feature to push content via my PC on the zotac using gupnp-av-cp as a remote controller and a DMS (minidlna) to publish the PVR content that is on another machine. But I only need to stop and start to get back to normal operation. This is not a deinterlace problem but rather a locking problem or a race in stop/start.
Nothing really annoying for average user. You can probably close this one if I get nothing until I leave for home.
Spoke too soon. Visually it garbled, differently than yesterday. I have squared blocks and playing with deinterlace ON/OFF doesn't change anything as If GPU decoding itself was the culprit as I have no errors in ffmpeg... STOPPING and restarting the video via gupnp-av-cp fixed the problem.
13:35:00 T:139677294094080 ERROR: SetThreadInfo: error Permission denied 13:35:00 T:139677294094080 NOTICE: Thread Jobworker start, auto delete: true 13:35:30 T:139677294094080 DEBUG: Thread Jobworker 139677294094080 terminating (autodelete) 13:35:43 T:139677369628416 DEBUG: CDVDPlayerAudio:: Discontinuity - was:1154750679.740521, should be:1154991091.880992, error:240412.140471 13:35:43 T:139677567211264 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped de-interlacing cycle, Sleeptime: -0.230410, Bufferlevel: 2 13:35:43 T:139677567211264 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped de-interlacing cycle, Sleeptime: -0.230409, Bufferlevel: 2 13:35:43 T:139677567211264 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped de-interlacing cycle, Sleeptime: -0.210409, Bufferlevel: 2 13:35:43 T:139677567211264 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped de-interlacing cycle, Sleeptime: -0.230408, Bufferlevel: 2 13:35:43 T:139677567211264 DEBUG: XVBA::FFGetBuffer - created video surface 13:35:43 T:139677567211264 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped de-interlacing cycle, Sleeptime: -0.170402, Bufferlevel: 2 13:35:43 T:139677567211264 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped de-interlacing cycle, Sleeptime: -0.150401, Bufferlevel: 2 13:35:43 T:139677567211264 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped de-interlacing cycle, Sleeptime: -0.130391, Bufferlevel: 2 13:35:43 T:139677567211264 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped de-interlacing cycle, Sleeptime: -0.110388, Bufferlevel: 2 13:35:43 T:139677567211264 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped de-interlacing cycle, Sleeptime: -0.090386, Bufferlevel: 2 13:35:44 T:139677567211264 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped de-interlacing cycle, Sleeptime: -0.050278, Bufferlevel: 3 13:35:44 T:139677567211264 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped de-interlacing cycle, Sleeptime: -0.050269, Bufferlevel: 2 13:35:46 T:139677567211264 DEBUG: CPullupCorrection: detected pattern of length 2: 0.00 40000.00, frameduration: 20000.000000 13:35:47 T:139677567211264 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped de-interlacing cycle, Sleeptime: -0.009854, Bufferlevel: 2 13:35:49 T:139677567211264 DEBUG: CPullupCorrection: detected pattern of length 2: 0.00 40000.00, frameduration: 20000.000000 13:36:00 T:139677294094080 ERROR: SetThreadInfo: error Permission denied 13:36:00 T:139677294094080 NOTICE: Thread Jobworker start, auto delete: true 13:36:30 T:139677294094080 DEBUG: Thread Jobworker 139677294094080 terminating (autodelete) 13:36:45 T:139677744498432 DEBUG: AddOnLog: Tvheadend HTSP Client: ParseChannelUpdate - id:10, name:'(null)', icon:'(null)', event:0 13:36:45 T:139677744498432 DEBUG: AddOnLog: Tvheadend HTSP Client: ParseChannelUpdate - id:19, name:'(null)', icon:'(null)', event:0 13:36:45 T:139677744498432 DEBUG: AddOnLog: Tvheadend HTSP Client: ParseChannelUpdate - id:19, name:'(null)', icon:'(null)', event:774 13:36:45 T:139677744498432 DEBUG: AddOnLog: Tvheadend HTSP Client: ParseChannelUpdate - id:3, name:'(null)', icon:'(null)', event:24618 13:36:45 T:139677744498432 DEBUG: AddOnLog: Tvheadend HTSP Client: ParseChannelUpdate - id:22, name:'(null)', icon:'(null)', event:0 13:36:45 T:139677744498432 DEBUG: AddOnLog: Tvheadend HTSP Client: ParseChannelUpdate - id:22, name:'(null)', icon:'(null)', event:1171 13:36:55 T:139677744498432 DEBUG: AddOnLog: Tvheadend HTSP Client: ParseChannelUpdate - id:10, name:'(null)', icon:'(null)', event:24347 13:37:00 T:139677294094080 ERROR: SetThreadInfo: error Permission denied 13:37:00 T:139677294094080 NOTICE: Thread Jobworker start, auto delete: true 13:37:04 T:139678394165152 NOTICE: ----------- no buffer, out: 1, current: 1, display: 4 13:37:04 T:139677567211264 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped de-interlacing cycle, Sleeptime: -0.170000, Bufferlevel: 1 13:37:04 T:139677567211264 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped de-interlacing cycle, Sleeptime: -0.150000, Bufferlevel: 3 13:37:04 T:139677567211264 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped de-interlacing cycle, Sleeptime: -0.090000, Bufferlevel: 1 13:37:04 T:139677567211264 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped de-interlacing cycle, Sleeptime: -0.050000, Bufferlevel: 2 13:37:04 T:139677567211264 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped de-interlacing cycle, Sleeptime: -0.070000, Bufferlevel: 2 13:37:05 T:139677567211264 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped de-interlacing cycle, Sleeptime: -0.010000, Bufferlevel: 2 13:37:07 T:139677567211264 DEBUG: CPullupCorrection: detected pattern of length 2: 0.00 40000.00, frameduration: 20000.000000 13:37:08 T:139677567211264 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped de-interlacing cycle, Sleeptime: -0.010000, Bufferlevel: 2 13:37:10 T:139677567211264 DEBUG: CPullupCorrection: detected pattern of length 2: 0.00 40000.00, frameduration: 20000.000000 13:37:30 T:139677294094080 DEBUG: Thread Jobworker 139677294094080 terminating (autodelete) 13:38:00 T:139677294094080 ERROR: SetThreadInfo: error Permission denied 13:38:00 T:139677294094080 NOTICE: Thread Jobworker start, auto delete: true 13:38:26 T:139678394165152 NOTICE: ----------- no buffer, out: 4, current: 4, display: 2 13:38:26 T:139677567211264 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped de-interlacing cycle, Sleeptime: -0.130000, Bufferlevel: 1 13:38:26 T:139677567211264 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped de-interlacing cycle, Sleeptime: -0.110000, Bufferlevel: 1 13:38:26 T:139677567211264 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped de-interlacing cycle, Sleeptime: -0.070000, Bufferlevel: 2 13:38:26 T:139677567211264 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped de-interlacing cycle, Sleeptime: -0.070000, Bufferlevel: 3 13:38:26 T:139677567211264 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped de-interlacing cycle, Sleeptime: -0.070000, Bufferlevel: 2 13:38:27 T:139677567211264 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped de-interlacing cycle, Sleeptime: -0.010000, Bufferlevel: 2 13:38:29 T:139677567211264 DEBUG: CPullupCorrection: detected pattern of length 2: 0.00 40000.00, frameduration: 20000.000000 13:38:30 T:139677567211264 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped de-interlacing cycle, Sleeptime: -0.010000, Bufferlevel: 3 13:38:30 T:139677294094080 DEBUG: Thread Jobworker 139677294094080 terminating (autodelete) 13:38:32 T:139677567211264 DEBUG: CPullupCorrection: detected pattern of length 2: 0.00 40000.00, frameduration: 20000.000000 13:38:52 T:139677609174784 INFO: UpdateFromScraper - updating EPG for channel 'TV RENNES 35' from client '1' 13:38:52 T:139677294094080 ERROR: SetThreadInfo: error Permission denied 13:38:52 T:139677294094080 NOTICE: Thread Jobworker start, auto delete: true 13:38:52 T:139678007994112 DEBUG: CPVRTimers - Update - updating timers 13:39:26 T:139677567211264 ERROR: ffmpeg[37C6D700]: [h264] sps_id out of range 13:39:30 T:139677294094080 DEBUG: Thread Jobworker 139677294094080 terminating (autodelete)
I don't see anything wrong in this log, other than 13:39:26 T:139677567211264 ERROR: ffmpeg[37C6D700]: [h264] sps_id out of range
We know that tvheadend sometimes delivers crap in the first packets. How often does this happen?
BTW: you should give the xbmc user some privileges to change nice level. I have create the following file /etc/security/limits.d/xbmc.conf
xbmc - nice -10
The problem was after several hours and maybe 30 min of a recording. So it is not crap in the first packet. If its a bug due to a corrupted packet (as tvheaedend does not discard them), it should happen each time I play the video. Must remember which one it was :-(
The start stop problem did occur on media that have otherwise no problem when starting.
Thanks for the tip for the priority.
can you give me more info and detailed steps to duplicate this problem?
So far I do not know how to reproduce it so far. I was playing a recorded Life episode (I have severals and I do not even remember which one it was) and after a while ~30 mins I started to get visually corrupted output.
If I find a problematic scenario or a bit of mkv that exhibit the problem, I will pass the info.
For the stop/start scenario, I guess I provided enough info in one of the comments. Start a recording via gupnp-av-cp, selecting xbmc as a renderer, select another recording and click on it, it will stop the current playing and start the new one. In rare case I got wrong display.
For the stop/start scenario, I guess I provided enough info in one of the comments. Start a recording via gupnp-av-cp, selecting xbmc as a renderer, select another recording and click on it, it will stop the current playing and start the new one. In rare case I got wrong display
Does this work ok with opdenkamp version?
I do not remember seeing similar problem on opdenkamp running on a ION or on my PC. And I tested those for much longer. But for me its really a minor annoyance and unless it show a more problematic/stop/start logic sequence, it can stay as it is for now. I get it maybe one out of 20 times when I switch fast and hitting stop from gupnp-av-cp and start again fixes the problem.
Maybe it can be reproduced by switching TV channels either with explicit stop/start of with the up/down buttons. Will test.
Bad news, I can rapidly produce problems when switching from a HD channel to the next adjacent HD channel. I got a blank frame and the normal frame producing a flashing screen. I got a lot of different error (including horizontal color lines of different colors).Got also crash between HD and SD.
16:10:58 T:140685767972608 ERROR: ffmpeg[F5399700]: [h264] hardware accelerator failed to decode picture 16:10:58 T:140685767972608 DEBUG: XVBA::Decode - ffmpeg failed 16:10:58 T:140685767972608 DEBUG: --------------------- hurry: 0 16:10:58 T:140685767972608 ERROR: ffmpeg[F5399700]: [h264] hardware accelerator failed to decode picture 16:10:58 T:140685767972608 DEBUG: XVBA::Decode - ffmpeg failed 16:10:58 T:140685767972608 DEBUG: --------------------- hurry: 0 16:10:58 T:140685767972608 ERROR: ffmpeg[F5399700]: [h264] hardware accelerator failed to decode picture 16:10:58 T:140685767972608 DEBUG: XVBA::Decode - ffmpeg failed 16:10:58 T:140685767972608 DEBUG: --------------------- hurry: 0 16:10:58 T:140685767972608 ERROR: ffmpeg[F5399700]: [h264] hardware accelerator failed to decode picture 16:10:58 T:140685767972608 DEBUG: XVBA::Decode - ffmpeg failed 16:10:58 T:140685767972608 DEBUG: --------------------- hurry: 0 16:10:58 T:140685767972608 ERROR: ffmpeg[F5399700]: [h264] mmco: unref short failure 16:10:58 T:140685767972608 ERROR: ffmpeg[F5399700]: [h264] hardware accelerator failed to decode picture 16:10:58 T:140685767972608 DEBUG: XVBA::Decode - ffmpeg failed 16:10:58 T:140685767972608 DEBUG: --------------------- hurry: 0 16:10:58 T:140685767972608 DEBUG: XVBA::FFGetBuffer - created video surface 16:10:58 T:140685767972608 ERROR: ffmpeg[F5399700]: [h264] hardware accelerator failed to decode picture 16:10:58 T:140685767972608 DEBUG: XVBA::Decode - ffmpeg failed 16:10:58 T:140685767972608 DEBUG: --------------------- hurry: 0 16:10:58 T:140685767972608 ERROR: ffmpeg[F5399700]: [h264] hardware accelerator failed to decode picture 16:10:58 T:140685767972608 DEBUG: XVBA::Decode - ffmpeg failed 16:10:58 T:140685767972608 DEBUG: --------------------- hurry: 0 16:10:58 T:140685767972608 ERROR: ffmpeg[F5399700]: [h264] hardware accelerator failed to decode picture 16:10:58 T:140685767972608 DEBUG: XVBA::Decode - ffmpeg failed 16:10:58 T:140685767972608 DEBUG: --------------------- hurry: 0 16:10:58 T:140685767972608 ERROR: ffmpeg[F5399700]: [h264] hardware accelerator failed to decode picture 16:10:58 T:140685767972608 DEBUG: XVBA::Decode - ffmpeg failed 16:10:58 T:140685767972608 DEBUG: --------------------- hurry: 0 16:10:58 T:140685767972608 ERROR: ffmpeg[F5399700]: [h264] hardware accelerator failed to decode picture 16:10:58 T:140685767972608 DEBUG: XVBA::Decode - ffmpeg failed 16:10:58 T:140685767972608 DEBUG: --------------------- hurry: 0 16:10:58 T:140685767972608 ERROR: ffmpeg[F5399700]: [h264] hardware accelerator failed to decode picture 16:10:58 T:140685767972608 DEBUG: XVBA::Decode - ffmpeg failed 16:10:58 T:140685767972608 DEBUG: --------------------- hurry: 0 16:10:58 T:140685767972608 ERROR: ffmpeg[F5399700]: [h264] hardware accelerator failed to decode picture 16:10:58 T:140685767972608 DEBUG: XVBA::Decode - ffmpeg failed 16:10:58 T:140685767972608 DEBUG: --------------------- hurry: 0 16:10:58 T:140685767972608 ERROR: ffmpeg[F5399700]: [h264] hardware accelerator failed to decode picture 16:10:58 T:140685767972608 DEBUG: XVBA::Decode - ffmpeg failed 16:10:58 T:140685767972608 DEBUG: --------------------- hurry: 0 16:10:58 T:140685767972608 ERROR: ffmpeg[F5399700]: [h264] hardware accelerator failed to decode picture 16:10:58 T:140685767972608 DEBUG: XVBA::Decode - ffmpeg failed 16:10:58 T:140685767972608 DEBUG: --------------------- hurry: 0 16:10:58 T:140685767972608 ERROR: ffmpeg[F5399700]: [h264] hardware accelerator failed to decode picture 16:10:58 T:140685767972608 DEBUG: XVBA::Decode - ffmpeg failed 16:10:58 T:140685767972608 DEBUG: --------------------- hurry: 0 16:10:58 T:140685767972608 ERROR: ffmpeg[F5399700]: [h264] hardware accelerator failed to decode picture 16:10:58 T:140685767972608 DEBUG: XVBA::Decode - ffmpeg failed 16:10:58 T:140685767972608 DEBUG: --------------------- hurry: 0 16:10:58 T:140685767972608 ERROR: ffmpeg[F5399700]: [h264] hardware accelerator failed to decode picture 16:10:58 T:140685767972608 DEBUG: XVBA::Decode - ffmpeg failed 16:10:58 T:140685767972608 DEBUG: --------------------- hurry: 0 16:10:58 T:140685767972608 ERROR: ffmpeg[F5399700]: [h264] hardware accelerator failed to decode picture 16:10:58 T:140685767972608 DEBUG: XVBA::Decode - ffmpeg failed 16:10:58 T:140685767972608 DEBUG: --------------------- hurry: 0 16:10:58 T:140685767972608 ERROR: ffmpeg[F5399700]: [h264] hardware accelerator failed to decode picture 16:10:58 T:140685767972608 DEBUG: XVBA::Decode - ffmpeg failed 16:10:58 T:140685767972608 DEBUG: --------------------- hurry: 0 16:10:58 T:140685767972608 ERROR: ffmpeg[F5399700]: [h264] hardware accelerator failed to decode picture 16:10:58 T:140685767972608 DEBUG: XVBA::Decode - ffmpeg failed 16:10:58 T:140685767972608 DEBUG: --------------------- hurry: 0 16:10:58 T:140685767972608 ERROR: ffmpeg[F5399700]: [h264] hardware accelerator failed to decode picture 16:10:58 T:140685767972608 DEBUG: XVBA::Decode - ffmpeg failed 16:10:58 T:140685767972608 DEBUG: --------------------- hurry: 0 16:10:58 T:140685767972608 ERROR: ffmpeg[F5399700]: [h264] hardware accelerator failed to decode picture 16:10:58 T:140685767972608 DEBUG: XVBA::Decode - ffmpeg failed 16:10:58 T:140685767972608 DEBUG: --------------------- hurry: 0 16:10:58 T:140685767972608 ERROR: ffmpeg[F5399700]: [h264] hardware accelerator failed to decode picture 16:10:58 T:140685767972608 DEBUG: XVBA::Decode - ffmpeg failed 16:10:58 T:140685767972608 DEBUG: --------------------- hurry: 0 16:10:58 T:140685767972608 ERROR: ffmpeg[F5399700]: [h264] hardware accelerator failed to decode picture 16:10:58 T:140685767972608 DEBUG: XVBA::Decode - ffmpeg failed 16:10:58 T:140685767972608 DEBUG: --------------------- hurry: 0 16:10:58 T:140685767972608 DEBUG: XVBA::Decode - ffmpeg failed 16:10:58 T:140685767972608 DEBUG: --------------------- hurry: 0 16:10:58 T:140685767972608 DEBUG: XVBA::Decode - ffmpeg failed 16:10:58 T:140685767972608 DEBUG: --------------------- hurry: 0 16:10:58 T:140685767972608 DEBUG: XVBA::Decode - ffmpeg failed 16:10:58 T:140685767972608 DEBUG: --------------------- hurry: 0 16:10:58 T:140685759579904 DEBUG: COutput::ProcessReturnPicture - return of invalid render pic 16:10:58 T:140685767972608 DEBUG: CPullupCorrection: pattern lost on diff -280000.000000 16:10:58 T:140685767972608 DEBUG: OutputPicture - dropped in output 16:10:58 T:140685767972608 DEBUG: --------------------- hurry: 0 16:10:58 T:140685767972608 DEBUG: XVBA::FFGetBuffer - created video surface 16:10:58 T:140685767972608 DEBUG: OutputPicture - dropped in output 16:10:58 T:140685767972608 DEBUG: --------------------- hurry: 0 16:10:58 T:140685767972608 DEBUG: OutputPicture - dropped in output 16:10:58 T:140685767972608 DEBUG: --------------------- hurry: 0 16:10:58 T:140685767972608 DEBUG: OutputPicture - dropped in output 16:10:58 T:140685767972608 DEBUG: --------------------- hurry: 0 16:10:58 T:140685767972608 DEBUG: OutputPicture - dropped in output 16:10:58 T:140685767972608 DEBUG: --------------------- hurry: 0 16:10:58 T:140685767972608 DEBUG: OutputPicture - dropped in output 16:10:58 T:140685767972608 DEBUG: --------------------- hurry: 0 16:10:58 T:140685767972608 DEBUG: OutputPicture - dropped in output 16:10:58 T:140685767972608 DEBUG: --------------------- hurry: 0 16:10:58 T:140685767972608 DEBUG: OutputPicture - dropped in output 16:10:58 T:140685767972608 DEBUG: --------------------- hurry: 0 16:10:58 T:140685767972608 DEBUG: OutputPicture - dropped in output 16:10:58 T:140685767972608 DEBUG: --------------------- hurry: 0 16:10:58 T:140685767972608 DEBUG: XVBA::FFGetBuffer - created video surface 16:10:58 T:140685767972608 DEBUG: Previous line repeats 1 times. 16:10:58 T:140685767972608 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped de-interlacing cycle, Sleeptime: -1.514875, Bufferlevel: 2 16:10:58 T:140685767972608 DEBUG: XVBA::FFGetBuffer - created video surface 16:10:58 T:140685767972608 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped de-interlacing cycle, Sleeptime: -1.491538, Bufferlevel: 2 16:10:58 T:140685767972608 DEBUG: XVBA::Decode long wait: 79 16:10:58 T:140685767972608 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped de-interlacing cycle, Sleeptime: -1.518190, Bufferlevel: 2 16:10:58 T:140685767972608 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped de-interlacing cycle, Sleeptime: -1.498190, Bufferlevel: 2 16:10:58 T:140685767972608 DEBUG: XVBA::FFGetBuffer - created video surface 16:10:58 T:140685767972608 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped de-interlacing cycle, Sleeptime: -1.478190, Bufferlevel: 2 16:10:58 T:140685767972608 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped de-interlacing cycle, Sleeptime: -1.474852, Bufferlevel: 2 16:10:58 T:140685767972608 DEBUG: XVBA::FFGetBuffer - created video surface 16:10:58 T:140685767972608 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped de-interlacing cycle, Sleeptime: -1.434852, Bufferlevel: 2 16:10:58 T:140685767972608 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped de-interlacing cycle, Sleeptime: -1.411516, Bufferlevel: 2 16:10:58 T:140685767972608 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped de-interlacing cycle, Sleeptime: -1.388177, Bufferlevel: 2 16:10:58 T:140685767972608 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped de-interlacing cycle, Sleeptime: -1.364840, Bufferlevel: 2 16:10:58 T:140685927429888 DEBUG: CDVDPlayerAudio:: Discontinuity - was:59832774842.536095, should be:59832900766.272736, error:125923.736641 16:10:58 T:140685767972608 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped de-interlacing cycle, Sleeptime: -1.341503, Bufferlevel: 2 16:10:58 T:140685767972608 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped de-interlacing cycle, Sleeptime: -1.444087, Bufferlevel: 2 16:10:58 T:140685767972608 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped de-interlacing cycle, Sleeptime: -1.420747, Bufferlevel: 2 16:10:58 T:140685767972608 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped de-interlacing cycle, Sleeptime: -1.397407, Bufferlevel: 2 16:10:58 T:140685767972608 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped de-interlacing cycle, Sleeptime: -1.374067, Bufferlevel: 2 16:10:58 T:140685767972608 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped de-interlacing cycle, Sleeptime: -1.350728, Bufferlevel: 2 16:10:58 T:140685767972608 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped de-interlacing cycle, Sleeptime: -1.327391, Bufferlevel: 2 16:10:58 T:140685767972608 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped de-interlacing cycle, Sleeptime: -1.304053, Bufferlevel: 2 16:10:58 T:140685767972608 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped de-interlacing cycle, Sleeptime: -1.280714, Bufferlevel: 2 16:10:58 T:140685767972608 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped de-interlacing cycle, Sleeptime: -1.257374, Bufferlevel: 2 16:10:58 T:140685767972608 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped de-interlacing cycle, Sleeptime: -1.234037, Bufferlevel: 2 16:10:58 T:140685767972608 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped de-interlacing cycle, Sleeptime: -1.210701, Bufferlevel: 2 16:10:58 T:140685767972608 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped de-interlacing cycle, Sleeptime: -1.187364, Bufferlevel: 2 16:10:58 T:140685767972608 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped de-interlacing cycle, Sleeptime: -1.164026, Bufferlevel: 2 16:10:58 T:140685767972608 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped de-interlacing cycle, Sleeptime: -1.140688, Bufferlevel: 2 16:10:58 T:140685767972608 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped de-interlacing cycle, Sleeptime: -1.117351, Bufferlevel: 2 16:10:58 T:140685767972608 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped de-interlacing cycle, Sleeptime: -1.094018, Bufferlevel: 2 16:10:58 T:140685870753536 DEBUG: CVideoReferenceClock: Received RandR event 118 16:10:58 T:140685870753536 DEBUG: CVideoReferenceClock: Detected refreshrate: 50 hertz 16:10:58 T:140685767972608 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped de-interlacing cycle, Sleeptime: -1.070685, Bufferlevel: 2 16:10:58 T:140685767972608 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped de-interlacing cycle, Sleeptime: -1.047352, Bufferlevel: 2 16:10:58 T:140685767972608 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped de-interlacing cycle, Sleeptime: -1.030688, Bufferlevel: 2 16:10:58 T:140685767972608 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped de-interlacing cycle, Sleeptime: -1.010692, Bufferlevel: 2 16:10:58 T:140685767972608 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped de-interlacing cycle, Sleeptime: -0.990696, Bufferlevel: 2 16:10:58 T:140685767972608 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped de-interlacing cycle, Sleeptime: -0.970698, Bufferlevel: 2 16:10:58 T:140685767972608 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped de-interlacing cycle, Sleeptime: -0.950698, Bufferlevel: 2 16:10:58 T:140685767972608 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped de-interlacing cycle, Sleeptime: -0.930700, Bufferlevel: 2 16:10:58 T:140685767972608 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped de-interlacing cycle, Sleeptime: -0.910704, Bufferlevel: 2 16:10:58 T:140685767972608 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped de-interlacing cycle, Sleeptime: -0.890707, Bufferlevel: 2 16:10:58 T:140685767972608 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped de-interlacing cycle, Sleeptime: -0.870707, Bufferlevel: 2 16:10:59 T:140685767972608 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped de-interlacing cycle, Sleeptime: -0.850708, Bufferlevel: 2 16:10:59 T:140685767972608 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped de-interlacing cycle, Sleeptime: -0.830708, Bufferlevel: 2 16:10:59 T:140685767972608 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped de-interlacing cycle, Sleeptime: -0.810709, Bufferlevel: 2 16:10:59 T:140685767972608 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped de-interlacing cycle, Sleeptime: -0.790711, Bufferlevel: 2 16:10:59 T:140685767972608 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped de-interlacing cycle, Sleeptime: -0.770714, Bufferlevel: 2 16:10:59 T:140685767972608 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped de-interlacing cycle, Sleeptime: -0.750714, Bufferlevel: 2 16:10:59 T:140685767972608 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped de-interlacing cycle, Sleeptime: -0.730717, Bufferlevel: 2 16:10:59 T:140685767972608 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped de-interlacing cycle, Sleeptime: -0.710719, Bufferlevel: 2 16:10:59 T:140685767972608 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped de-interlacing cycle, Sleeptime: -0.690718, Bufferlevel: 2 16:10:59 T:140685767972608 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped de-interlacing cycle, Sleeptime: -0.670718, Bufferlevel: 2 16:10:59 T:140685927429888 DEBUG: CDVDPlayerAudio:: Discontinuity - was:59833579792.325737, should be:59833700700.389969, error:120908.064232 16:10:59 T:140685767972608 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped de-interlacing cycle, Sleeptime: -0.771621, Bufferlevel: 2 16:10:59 T:140685767972608 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped de-interlacing cycle, Sleeptime: -0.751621, Bufferlevel: 2 16:10:59 T:140685767972608 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped de-interlacing cycle, Sleeptime: -0.731619, Bufferlevel: 2 16:10:59 T:140685767972608 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped de-interlacing cycle, Sleeptime: -0.711614, Bufferlevel: 2 16:10:59 T:140685767972608 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped de-interlacing cycle, Sleeptime: -0.691610, Bufferlevel: 2 16:10:59 T:140685767972608 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped de-interlacing cycle, Sleeptime: -0.671603, Bufferlevel: 2 16:10:59 T:140685767972608 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped de-interlacing cycle, Sleeptime: -0.651597, Bufferlevel: 2 16:10:59 T:140685767972608 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped de-interlacing cycle, Sleeptime: -0.631591, Bufferlevel: 2 16:10:59 T:140685767972608 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped de-interlacing cycle, Sleeptime: -0.611581, Bufferlevel: 2 16:10:59 T:140685767972608 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped de-interlacing cycle, Sleeptime: -0.571560, Bufferlevel: 1 16:10:59 T:140685767972608 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped de-interlacing cycle, Sleeptime: -0.531532, Bufferlevel: 2 16:10:59 T:140685767972608 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped de-interlacing cycle, Sleeptime: -0.511518, Bufferlevel: 1 16:10:59 T:140685767972608 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped de-interlacing cycle, Sleeptime: -0.491504, Bufferlevel: 2 16:10:59 T:140685767972608 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped de-interlacing cycle, Sleeptime: -0.471489, Bufferlevel: 1 16:10:59 T:140685767972608 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped de-interlacing cycle, Sleeptime: -0.451473, Bufferlevel: 2 16:10:59 T:140685767972608 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped de-interlacing cycle, Sleeptime: -0.431458, Bufferlevel: 1 16:10:59 T:140685767972608 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped de-interlacing cycle, Sleeptime: -0.411442, Bufferlevel: 2 16:10:59 T:140685767972608 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped de-interlacing cycle, Sleeptime: -0.391425, Bufferlevel: 1 16:10:59 T:140685767972608 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped de-interlacing cycle, Sleeptime: -0.371408, Bufferlevel: 2 16:10:59 T:140685767972608 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped de-interlacing cycle, Sleeptime: -0.351391, Bufferlevel: 1 16:10:59 T:140685767972608 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped de-interlacing cycle, Sleeptime: -0.331374, Bufferlevel: 2 16:10:59 T:140685767972608 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped de-interlacing cycle, Sleeptime: -0.311356, Bufferlevel: 1 16:10:59 T:140685767972608 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped de-interlacing cycle, Sleeptime: -0.291338, Bufferlevel: 2 16:10:59 T:140685767972608 DEBUG: Previous line repeats 2 times. 16:10:59 T:140685767972608 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped de-interlacing cycle, Sleeptime: -0.271319, Bufferlevel: 2 16:10:59 T:140685767972608 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped de-interlacing cycle, Sleeptime: -0.251300, Bufferlevel: 2 16:10:59 T:140685767972608 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped de-interlacing cycle, Sleeptime: -0.231281, Bufferlevel: 2 16:10:59 T:140685767972608 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped de-interlacing cycle, Sleeptime: -0.211261, Bufferlevel: 2 16:10:59 T:140685767972608 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped de-interlacing cycle, Sleeptime: -0.191241, Bufferlevel: 2 16:10:59 T:140685767972608 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped de-interlacing cycle, Sleeptime: -0.171221, Bufferlevel: 2 16:10:59 T:140685767972608 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped de-interlacing cycle, Sleeptime: -0.151201, Bufferlevel: 2 16:10:59 T:140685767972608 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped de-interlacing cycle, Sleeptime: -0.131180, Bufferlevel: 2 16:10:59 T:140685767972608 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped de-interlacing cycle, Sleeptime: -0.111160, Bufferlevel: 2 16:11:00 T:140686057785088 DEBUG: GetImageHash - unable to stat url http://telepoche.guidetele.com/medias/chaines/france5.gif 16:11:01 T:140686772033440 DEBUG: ------ Window Deinit (Pointer.xml) ------ 16:11:01 T:140686772033440 DEBUG: ------ Window Deinit (VideoOSD.xml) ------ 16:11:02 T:140685767972608 DEBUG: CPullupCorrection: detected pattern of length 1: 20000.00, frameduration: 20000.000000 16:11:05 T:140686057785088 DEBUG: GetImageHash - unable to stat url http://telepoche.guidetele.com/medias/chaines/m6_logo.gif 16:11:10 T:140686057785088 DEBUG: GetImageHash - unable to stat url http://telepoche.guidetele.com/medias/chaines/france3.gif 16:11:15 T:140686057785088 DEBUG: GetImageHash - unable to stat url http://telepoche.guidetele.com/medias/chaines/new-logo.png 16:11:20 T:140686057785088 DEBUG: GetImageHash - unable to stat url http://telepoche.guidetele.com/medias/chaines/w9.png
tail -200 ~/.xbmc/temp/xbmc.log 16:14:20 T:140015162242816 NOTICE: Creating video thread 16:14:20 T:140015137064704 NOTICE: Thread CDVDPlayerVideo start, auto delete: false 16:14:20 T:140015137064704 NOTICE: running thread: video_thread 16:14:20 T:140015137064704 DEBUG: CDVDPlayerVideo - CDVDMsg::GENERAL_RESYNC(60037342133.000000, 0) 16:14:20 T:140015137064704 INFO: CDVDPlayerVideo - Stillframe left, switching to normal playback 16:14:20 T:140015137064704 DEBUG: --------------------- hurry: 0 16:14:20 T:140015137064704 NOTICE: (XVBA::Open) opening xvba decoder, id: 4 16:14:20 T:140015137064704 NOTICE: XVBA::CreateContext - creating decoder context 16:14:20 T:140015137064704 NOTICE: XVBA::CreateContext - opening xvba version: 76 16:14:20 T:140015137064704 NOTICE: (XVBA) using decoder capability id: 256 flags: 3 16:14:20 T:140015137064704 NOTICE: (XVBA) using surface type: 3231564e 16:14:20 T:140015531628448 NOTICE: ----------- no buffer, out: 1, current: 1, display: 4 16:14:20 T:140014788486912 NOTICE: Thread XVBA Output Thread start, auto delete: false 16:14:20 T:140014788486912 NOTICE: COutput::OnStartup: Output Thread created 16:14:20 T:140014788486912 NOTICE: XVBA::COutput::CreateGlxContext - created context 16:14:20 T:140014788486912 DEBUG: XVBA::GetTexture - created shared surface 16:14:20 T:140015137064704 DEBUG: Previous line repeats 8 times. 16:14:20 T:140015137064704 DEBUG: XVBA::FFGetBuffer - created video surface 16:14:20 T:140015531628448 NOTICE: ----------- no buffer, out: 1, current: 1, display: 4 16:14:20 T:140015137064704 DEBUG: XVBA::FFGetBuffer - created video surface 16:14:20 T:140015531628448 DEBUG: Previous line repeats 3 times. 16:14:20 T:140015531628448 NOTICE: ----------- no buffer, out: 1, current: 1, display: 4 16:14:20 T:140015137064704 INFO: ffmpeg[D0820700]: [h264] Cannot parallelize deblocking type 1, decoding such frames in sequential order 16:14:20 T:140015531628448 NOTICE: ----------- no buffer, out: 1, current: 1, display: 4 16:14:20 T:140015137064704 NOTICE: Previous line repeats 2 times. 16:14:20 T:140015137064704 DEBUG: --------------------- hurry: 0 16:14:20 T:140015531628448 NOTICE: ----------- no buffer, out: 1, current: 1, display: 4 16:14:20 T:140015137064704 DEBUG: --------------------- hurry: 0 16:14:20 T:140015531628448 NOTICE: ----------- no buffer, out: 1, current: 1, display: 4 16:14:20 T:140015137064704 DEBUG: --------------------- hurry: 0 16:14:20 T:140015531628448 NOTICE: ----------- no buffer, out: 1, current: 1, display: 4 16:14:20 T:140015137064704 DEBUG: --------------------- hurry: 0 16:14:20 T:140015137064704 ERROR: ffmpeg[D0820700]: [h264] mmco: unref short failure 16:14:20 T:140015137064704 ERROR: ffmpeg[D0820700]: [h264] number of reference frames exceeds max (probably corrupt input), discarding one 16:14:20 T:140015531628448 NOTICE: ----------- no buffer, out: 1, current: 1, display: 4 16:14:20 T:140015137064704 DEBUG: --------------------- hurry: 0 16:14:20 T:140015531628448 NOTICE: ----------- no buffer, out: 1, current: 1, display: 4 16:14:20 T:140015137064704 DEBUG: --------------------- hurry: 0 16:14:20 T:140015531628448 NOTICE: ----------- no buffer, out: 1, current: 1, display: 4 16:14:20 T:140015137064704 DEBUG: --------------------- hurry: 0 16:14:20 T:140015531628448 NOTICE: ----------- no buffer, out: 1, current: 1, display: 4 16:14:20 T:140015137064704 DEBUG: --------------------- hurry: 0 16:14:20 T:140015531628448 NOTICE: ----------- no buffer, out: 1, current: 1, display: 4 16:14:21 T:140015137064704 DEBUG: --------------------- hurry: 0 16:14:21 T:140015531628448 NOTICE: ----------- no buffer, out: 1, current: 1, display: 4 16:14:21 T:140015137064704 NOTICE: fps: 0.000000, pwidth: 1920, pheight: 1080, dwidth: 1920, dheight: 1080 16:14:21 T:140015137064704 DEBUG: OutputPicture - change configuration. 1920x1080. framerate: 0.00. format: XVBA 16:14:21 T:140015531628448 NOTICE: ----------- no buffer, out: 1, current: 1, display: 4 16:14:21 T:140015531628448 NOTICE: Using GL_TEXTURE_2D 16:14:21 T:140015531628448 NOTICE: GL: Using XVBA render method 16:14:21 T:140015531628448 NOTICE: GL: NPOT texture support detected 16:14:21 T:140015531628448 NOTICE: GL: Using GL_ARB_pixel_buffer_object 16:14:21 T:140015137064704 DEBUG: CXBMCRenderManager::EnableBuffering - 1 16:14:21 T:140015162242816 DEBUG: CDVDPlayer::HandleMessages - player started 2 16:14:21 T:140015531628448 NOTICE: ----------- no buffer, out: 4, current: 4, display: 2 16:14:21 T:140015162242816 NOTICE: Previous line repeats 9 times. 16:14:21 T:140015162242816 DEBUG: set caching from pvr to done. audio (1) = 22. video (1) = 6 16:14:21 T:140015162242816 DEBUG: CDVDPlayer::SetCaching - caching state 0 16:14:21 T:140014645810944 DEBUG: CDVDPlayerAudio:: synctype set to 2: resample 16:14:21 T:140014645810944 DEBUG: CDVDPlayerResampler: resized buffers to hold 11520 frames 16:14:21 T:140015137064704 DEBUG: --------------------- hurry: 0 16:14:21 T:140015162242816 ERROR: Get - failed to get stream 16:14:21 T:140014645810944 DEBUG: CDVDPlayerAudio:: Discontinuity - was:60035567096.203003, should be:60035560849.931580, error:-6246.271423 16:14:21 T:140015137064704 DEBUG: --------------------- hurry: 0 16:14:21 T:140015137064704 DEBUG: Previous line repeats 1 times. 16:14:21 T:140015137064704 DEBUG: XVBA::FFGetBuffer - created video surface 16:14:21 T:140015137064704 DEBUG: --------------------- hurry: 0 16:14:21 T:140015137064704 DEBUG: XVBA::FFGetBuffer - created video surface 16:14:21 T:140015137064704 WARNING: CRenderManager::WaitForBuffer - timeout waiting for buffer 16:14:22 T:140014788486912 WARNING: Previous line repeats 2 times. 16:14:22 T:140014788486912 DEBUG: CVDPAU::COutput - timeout idle: decoded: 1, proc: 0, render: 3 16:14:22 T:140015137064704 WARNING: CRenderManager::WaitForBuffer - timeout waiting for buffer 16:14:22 T:140014788486912 DEBUG: CVDPAU::COutput - timeout idle: decoded: 1, proc: 0, render: 3 16:14:22 T:140015137064704 DEBUG: Previous line repeats 1 times. 16:14:22 T:140015137064704 WARNING: CRenderManager::WaitForBuffer - timeout waiting for buffer 16:14:22 T:140014788486912 DEBUG: CVDPAU::COutput - timeout idle: decoded: 1, proc: 0, render: 3 16:14:22 T:140015137064704 WARNING: CRenderManager::WaitForBuffer - timeout waiting for buffer 16:14:22 T:140015137064704 DEBUG: XVBA::FFGetBuffer - created video surface 16:14:22 T:140015137064704 WARNING: CRenderManager::WaitForBuffer - timeout waiting for buffer 16:14:23 T:140014788486912 WARNING: Previous line repeats 2 times. 16:14:23 T:140014788486912 DEBUG: CVDPAU::COutput - timeout idle: decoded: 1, proc: 0, render: 3 16:14:23 T:140015137064704 WARNING: CRenderManager::WaitForBuffer - timeout waiting for buffer 16:14:23 T:140014788486912 DEBUG: CVDPAU::COutput - timeout idle: decoded: 1, proc: 0, render: 3 16:14:23 T:140015137064704 DEBUG: Previous line repeats 1 times. 16:14:23 T:140015137064704 WARNING: CRenderManager::WaitForBuffer - timeout waiting for buffer 16:14:23 T:140014788486912 DEBUG: CVDPAU::COutput - timeout idle: decoded: 1, proc: 0, render: 3 16:14:23 T:140015137064704 WARNING: CRenderManager::WaitForBuffer - timeout waiting for buffer 16:14:23 T:140014788486912 DEBUG: CVDPAU::COutput - timeout idle: decoded: 1, proc: 0, render: 3 16:14:23 T:140015531628448 DEBUG: ------ Window Deinit (Pointer.xml) ------ 16:14:23 T:140015137064704 WARNING: CRenderManager::WaitForBuffer - timeout waiting for buffer 16:14:23 T:140015137064704 WARNING: Previous line repeats 1 times. 16:14:23 T:140015137064704 DEBUG: --------------------- hurry: 0 16:14:23 T:140015137064704 DEBUG: XVBA::FFGetBuffer - created video surface 16:14:24 T:140015531628448 DEBUG: ------ Window Deinit (VideoOSD.xml) ------ 16:14:26 T:140015137064704 DEBUG: CPullupCorrection: detected pattern of length 2: 0.00 40000.00, frameduration: 20000.000000 16:14:27 T:140015137064704 DEBUG: CalcFrameRate framerate was:25.000000 calculated:50.000000 16:14:27 T:140015137064704 NOTICE: fps: 50.000000, pwidth: 1920, pheight: 1080, dwidth: 1920, dheight: 1080 16:14:27 T:140015137064704 DEBUG: OutputPicture - change configuration. 1920x1080. framerate: 50.00. format: XVBA 16:14:27 T:140015137064704 NOTICE: Display resolution ADJUST : DFP2: 1920x1080 @ 50.00Hz (14) (weight: 0.000) 16:14:27 T:140015531628448 NOTICE: Using GL_TEXTURE_2D 16:14:27 T:140015531628448 NOTICE: GL: Using XVBA render method 16:14:27 T:140015531628448 NOTICE: GL: NPOT texture support detected 16:14:27 T:140015531628448 NOTICE: GL: Using GL_ARB_pixel_buffer_object 16:14:27 T:140015137064704 DEBUG: CPullupCorrection: pattern lost on diff 20000.000000 16:14:29 T:140015137064704 DEBUG: CPullupCorrection: detected pattern of length 1: 20000.00, frameduration: 20000.000000 16:14:37 T:140015531628448 DEBUG: ------ Window Init (VideoOSD.xml) ------ 16:14:37 T:140015531628448 INFO: Loading skin file: VideoOSD.xml 16:14:37 T:140015531628448 DEBUG: Load VideoOSD.xml: 15.66ms 16:14:37 T:140015531628448 DEBUG: Alloc resources: 116.51ms (15.80 ms skin load) 16:14:37 T:140015531628448 DEBUG: ------ Window Init (Pointer.xml) ------ 16:14:37 T:140015531628448 ERROR: Control 1 in window 10099 has been asked to focus, but it can't 16:14:37 T:140015137064704 WARNING: CRenderManager::WaitForBuffer - timeout waiting for buffer 16:14:37 T:140015137064704 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped de-interlacing cycle, Sleeptime: -0.069882, Bufferlevel: 1 16:14:37 T:140015137064704 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped de-interlacing cycle, Sleeptime: -0.069883, Bufferlevel: 2 16:14:38 T:140015531628448 DEBUG: ProcessMouse: trying mouse action leftclick 16:14:38 T:140015531628448 DEBUG: ExecuteXBMCAction : Translating PlayerControl(Stop) 16:14:38 T:140015531628448 DEBUG: ExecuteXBMCAction : To PlayerControl(Stop) 16:14:38 T:140015531628448 DEBUG: PVR - SaveCurrentChannelSettings - no custom channel settings for channel 'ARTE HD' 16:14:38 T:140015531628448 NOTICE: CDVDPlayer::CloseFile() 16:14:38 T:140015162242816 NOTICE: CDVDPlayer::OnExit() 16:14:38 T:140015162242816 NOTICE: DVDPlayer: closing audio stream 16:14:38 T:140015162242816 NOTICE: Closing audio stream 16:14:38 T:140015162242816 NOTICE: Waiting for audio thread to exit 16:14:38 T:140015531628448 NOTICE: DVDPlayer: waiting for threads to exit 16:14:38 T:140014645810944 NOTICE: thread end: CDVDPlayerAudio::OnExit() 16:14:38 T:140014645810944 DEBUG: Thread CDVDPlayerAudio 140014645810944 terminating 16:14:38 T:140015162242816 NOTICE: Closing audio device 16:14:38 T:140015162242816 DEBUG: SetActiveDevice - SetActiveDevice from 2 to 1 16:14:38 T:140015162242816 DEBUG: RemoveActiveDevice - Removing device 2 16:14:38 T:140015162242816 DEBUG: CGUIAudioManager::Initialize 16:14:38 T:140015162242816 NOTICE: Deleting audio codec 16:14:38 T:140015162242816 NOTICE: DVDPlayer: closing video stream 16:14:38 T:140015162242816 NOTICE: Closing video stream 16:14:38 T:140015162242816 NOTICE: waiting for video thread to exit 16:14:38 T:140014788486912 DEBUG: CVDPAU::COutput - timeout idle: decoded: 1, proc: 0, render: 3 16:14:38 T:140015137064704 NOTICE: thread end: video_thread 16:14:38 T:140015137064704 DEBUG: Thread CDVDPlayerVideo 140015137064704 terminating 16:14:38 T:140015162242816 NOTICE: deleting video codec 16:14:38 T:140015162242816 NOTICE: XVBA::Release pre-cleanup 16:14:38 T:140015162242816 NOTICE: DVDPlayer: closing subtitle stream 16:14:38 T:140015162242816 NOTICE: Closing subtitle stream 16:14:38 T:140015162242816 NOTICE: CDVDPlayer::OnExit() deleting demuxer 16:14:38 T:140015162242816 NOTICE: CDVDPlayer::OnExit() deleting input stream 16:14:38 T:140015162242816 DEBUG: CAnnouncementManager - Announcement: OnStop from xbmc 16:14:38 T:140015162242816 DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnStop 16:14:38 T:140015162242816 DEBUG: Thread CDVDPlayer 140015162242816 terminating 16:14:38 T:140015531628448 NOTICE: DVDPlayer: finished waiting 16:14:38 T:140015531628448 DEBUG: LinuxRendererGL: Cleaning up GL resources 16:14:38 T:140015531628448 NOTICE: XVBA::Close - closing decoder, id: 4 16:14:38 T:140014788486912 NOTICE: COutput::OnExit: Output Thread terminated 16:14:38 T:140014788486912 DEBUG: Thread XVBA Output Thread 140014788486912 terminating 16:14:38 T:140015531628448 NOTICE: XVBA::Close - closing decoder context 16:14:38 T:140015531628448 DEBUG: CGUIWindowManager::PreviousWindow: Deactivate 16:14:38 T:140015531628448 DEBUG: ------ Window Deinit (VideoFullScreen.xml) ------ 16:14:38 T:140015531628448 DEBUG: ------ Window Deinit (VideoOSD.xml) ------ 16:14:38 T:140015531628448 NOTICE: CWinSystemX11::SetFullScreen - calling xrandr 16:14:38 T:140015531628448 DEBUG: OnLostDevice - notify display change event 16:14:38 T:140015531628448 DEBUG: Flush - flushing renderer 16:14:38 T:140015531628448 INFO: XRANDR: /usr/local/lib/xbmc/xbmc-xrandr --output DFP2 --mode 0x98 16:14:39 T:140015029442304 DEBUG: CVideoReferenceClock: Vblank counter has reset 16:14:39 T:140015029442304 DEBUG: CVideoReferenceClock: Detaching glX context 16:14:39 T:140015029442304 DEBUG: CVideoReferenceClock: Attaching glX context 16:14:39 T:140015531628448 ERROR: GLX: Same window as before, refreshing context 16:14:39 T:140015531628448 INFO: GL: Maximum texture width: 16384 16:14:39 T:140015531628448 DEBUG: CRenderManager::UpdateDisplayLatency - Latency set to 0 msec 16:14:39 T:140015531628448 DEBUG: CheckDisplayEvents: Received RandR event 118 16:14:39 T:140015531628448 DEBUG: CheckDisplayEvents - notify display reset event 16:14:39 T:140015531628448 ERROR: GLX: Same window as before, refreshing context 16:14:39 T:140015531628448 INFO: GL: Maximum texture width: 16384 16:14:39 T:140015531628448 DEBUG: CRenderManager::UpdateDisplayLatency - Latency set to 0 msec 16:14:39 T:140015531628448 DEBUG: CGUIWindowManager::PreviousWindow: Activate new 16:14:39 T:140015531628448 DEBUG: ------ Window Init (MyPVR.xml) ------ 16:14:39 T:140015531628448 INFO: Loading skin file: MyPVR.xml 16:14:40 T:140015531628448 DEBUG: Load MyPVR.xml: 144.05ms 16:14:40 T:140015531628448 DEBUG: Alloc resources: 203.98ms (146.74 ms skin load) 16:14:40 T:140015531628448 DEBUG: CGUIMediaWindow::GetDirectory (pvr://channels/tv/All TV channels/) 16:14:40 T:140015531628448 DEBUG: ParentPath = [] 16:14:40 T:140015531628448 DEBUG: CPVRDirectory::GetDirectory(pvr://channels/tv/All TV channels) 16:14:40 T:140015531628448 DEBUG: CGUIWindowPVRCommon - OnMessageFocus - focus set to window 'tv' 16:14:40 T:140015531628448 DEBUG: CGUIWindowPVRChannels - UpdateData - update window 'tv'. set view to 11 16:14:40 T:140015531628448 DEBUG: CGUIMediaWindow::GetDirectory (pvr://channels/tv/All TV channels/) 16:14:40 T:140015531628448 DEBUG: ParentPath = [] 16:14:40 T:140014994319104 DEBUG: CPVRDirectory::GetDirectory(pvr://channels/tv/All TV channels) 16:14:40 T:140015153850112 DEBUG: DoWork - took 270 ms to load /home/valette/.xbmc/addons/skin.rapier.svn/media/background-default.png 16:14:40 T:140014834927360 DEBUG: DoWork - took 288 ms to load /home/valette/.xbmc/addons/skin.rapier.svn/media/background-pvr.png 16:14:40 T:140015531628448 NOTICE: CDVDPlayer::CloseFile() 16:14:40 T:140015531628448 WARNING: CDVDMessageQueue(player)::Put MSGQ_NOT_INITIALIZED 16:14:40 T:140015531628448 NOTICE: DVDPlayer: waiting for threads to exit 16:14:40 T:140015531628448 NOTICE: DVDPlayer: finished waiting 16:14:40 T:140015531628448 DEBUG: LinuxRendererGL: Cleaning up GL resources 16:14:40 T:140015029442304 DEBUG: CVideoReferenceClock: Cleaning up GLX 16:14:40 T:140015531628448 INFO: CheckIdle - Closing session to http://telepoche.guidetele.com (easy=0x7f57c0260808, multi=(nil)) 16:14:40 T:140015029442304 DEBUG: Thread CVideoReferenceClock 140015029442304 terminating 16:14:40 T:140014994319104 INFO: easy_aquire - Created session to http://telepoche.guidetele.com 16:14:44 T:140015531628448 DEBUG: ------ Window Deinit (Pointer.xml) ------ 16:14:45 T:140014994319104 DEBUG: GetImageHash - unable to stat url http://telepoche.guidetele.com/medias/chaines/tf1.gif 16:14:49 T:140015531628448 DEBUG: SECTION:UnloadDelayed(SECTION: LCODE) 16:14:49 T:140015531628448 DEBUG: SECTION:UnloadDelayed(DLL: special://xbmcbin/system/players/dvdplayer/avformat-52-x86_64-linux.so) 16:14:49 T:140015531628448 DEBUG: Unloading: avformat-52-x86_64-linux.so 16:14:50 T:140014994319104 DEBUG: GetImageHash - unable to stat url http://telepoche.guidetele.com/medias/chaines/france2.gif
can you try to revert: 49562de14b443039ffb4bcd621c464815915676e
Yep but as you pulled things and I saw commits I update my tree => I must first regenerate from bootstrap. Sorry. Takes some time.
This should fix the flickering when changing from HD to SD: Actually a problem in mainline when codecs are switched: c052b0fd52157b56652a885e766ab7718a1337b3
I tested with the revert first. I managed to crash the machine but probably when switching HD/SD. With the second fix in addition to this one, it took me more time. But switching back and forth between HD channels finish to render xbmc unusable after a while. On some switched I got the same visual effect as the deinterlacing problem you fixed as if some frame were looping, and If I insist I can get black screen no display.
First tracee:
tail -200 ~/.xbmc/temp/xbmc.log 17:23:16 T:139916880447392 DEBUG: CGUIWindowPVRChannels - UpdateData - update window 'tv'. set view to 11 17:23:16 T:139916880447392 DEBUG: CGUIMediaWindow::GetDirectory (pvr://channels/tv/All TV channels/) 17:23:16 T:139916880447392 DEBUG: ParentPath = [] 17:23:16 T:139916204488448 DEBUG: CPVRDirectory::GetDirectory(pvr://channels/tv/All TV channels) 17:23:16 T:139916086998784 NOTICE: Thread Jobworker start, auto delete: true 17:23:16 T:139916379412224 DEBUG: CVideoReferenceClock: Received RandR event 118 17:23:16 T:139916379412224 DEBUG: CVideoReferenceClock: Detected refreshrate: 60 hertz 17:23:16 T:139916511061760 NOTICE: Thread Jobworker start, auto delete: true 17:23:17 T:139916204488448 NOTICE: Previous line repeats 1 times. 17:23:17 T:139916204488448 DEBUG: DoWork - took 227 ms to load /home/valette/.xbmc/addons/skin.rapier.svn/media/background-default.png 17:23:17 T:139916086998784 DEBUG: DoWork - took 230 ms to load /home/valette/.xbmc/addons/skin.rapier.svn/media/background-pvr.png 17:23:17 T:139916880447392 NOTICE: CDVDPlayer::CloseFile() 17:23:17 T:139916880447392 WARNING: CDVDMessageQueue(player)::Put MSGQ_NOT_INITIALIZED 17:23:17 T:139916880447392 NOTICE: DVDPlayer: waiting for threads to exit 17:23:17 T:139916880447392 NOTICE: DVDPlayer: finished waiting 17:23:17 T:139916880447392 DEBUG: LinuxRendererGL: Cleaning up GL resources 17:23:17 T:139916379412224 DEBUG: CVideoReferenceClock: Cleaning up GLX 17:23:17 T:139916379412224 DEBUG: Thread CVideoReferenceClock 139916379412224 terminating 17:23:17 T:139916145747712 INFO: easy_aquire - Created session to http://telepoche.guidetele.com 17:23:19 T:139916222322432 DEBUG: AddOnLog: Tvheadend HTSP Client: ParseChannelUpdate - id:18, name:'(null)', icon:'(null)', event:0 17:23:21 T:139916880447392 DEBUG: ProcessMouse: trying mouse action leftclick 17:23:21 T:139916880447392 DEBUG: CPlayerCoreFactory::GetPlayers(pvr://channels/tv/All TV channels/5.pvr) 17:23:21 T:139916880447392 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: system rules 17:23:21 T:139916880447392 DEBUG: CPlayerSelectionRule::GetPlayers: matches rule: system rules 17:23:21 T:139916880447392 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtv 17:23:21 T:139916880447392 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: hdhomerun/myth/rtmp/mms/udp 17:23:21 T:139916880447392 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: lastfm/shout 17:23:21 T:139916880447392 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtsp 17:23:21 T:139916880447392 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: streams 17:23:21 T:139916880447392 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvd 17:23:21 T:139916880447392 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvdfile 17:23:21 T:139916880447392 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvdimage 17:23:21 T:139916880447392 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: sdp/asf 17:23:21 T:139916880447392 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: nsv 17:23:21 T:139916880447392 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: radio 17:23:21 T:139916880447392 DEBUG: CPlayerCoreFactory::GetPlayers: matched 0 rules with players 17:23:21 T:139916880447392 DEBUG: CPlayerCoreFactory::GetPlayers: adding videodefaultplayer (1) 17:23:21 T:139916880447392 DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=0 17:23:21 T:139916880447392 DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=1 17:23:21 T:139916880447392 DEBUG: CPlayerCoreFactory::GetPlayers: adding player: DVDPlayer (1) 17:23:21 T:139916880447392 DEBUG: CPlayerCoreFactory::GetPlayers: added 1 players 17:23:21 T:139916880447392 DEBUG: CGUIAudioManager::DeInitialize 17:23:21 T:139916880447392 NOTICE: DVDPlayer: Opening: pvr://channels/tv/All TV channels/5.pvr 17:23:21 T:139916880447392 WARNING: CDVDMessageQueue(player)::Put MSGQ_NOT_INITIALIZED 17:23:21 T:139916880447392 DEBUG: CRenderManager::UpdateDisplayLatency - Latency set to 0 msec 17:23:21 T:139916880447392 DEBUG: LinuxRendererGL: Cleaning up GL resources 17:23:21 T:139916061820672 NOTICE: Thread CDVDPlayer start, auto delete: false 17:23:21 T:139916061820672 NOTICE: Creating InputStream 17:23:21 T:139916061820672 DEBUG: PVRManager - OpenLiveStream - opening live stream on channel 'ARTE HD' 17:23:21 T:139916061820672 DEBUG: AddOnLog: Tvheadend HTSP Client: Connect - connecting to 'stb-atom-ion-3', port '9982' 17:23:21 T:139916061820672 DEBUG: AddOnLog: Tvheadend HTSP Client: Connect - connected to 'stb-atom-ion-3', port '9982' 17:23:21 T:139916061820672 DEBUG: AddOnLog: Tvheadend HTSP Client: CHTSPConnection - Auth - authenticating as user 'valette' with a password 17:23:21 T:139916061820672 DEBUG: PVRFile - Open - playback has started on filename pvr://channels/tv/All TV channels/5.pvr 17:23:21 T:139916061820672 NOTICE: Creating Demuxer 17:23:21 T:139916061820672 DEBUG: CDVDPlayer::SetCaching - caching state 2 17:23:21 T:139916061820672 WARNING: CDVDMessageQueue(audio)::Put MSGQ_NOT_INITIALIZED 17:23:21 T:139916061820672 WARNING: CDVDMessageQueue(video)::Put MSGQ_NOT_INITIALIZED 17:23:21 T:139916880447392 DEBUG: ------ Window Init (DialogSeekBar.xml) ------ 17:23:21 T:139916880447392 DEBUG: Alloc resources: 6.20ms (0.01 ms skin load) 17:23:21 T:139916880447392 DEBUG: CGUIInfoManager::SetCurrentMovie(pvr://channels/tv/All TV channels/5.pvr) 17:23:21 T:139916880447392 DEBUG: CAnnouncementManager - Announcement: OnPlay from xbmc 17:23:21 T:139916880447392 DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnPlay 17:23:21 T:139916061820672 DEBUG: AddOnLog: Tvheadend HTSP Client: ParseSubscriptionStart - id: 1, type: H264 17:23:21 T:139916061820672 DEBUG: AddOnLog: Tvheadend HTSP Client: ParseSubscriptionStart - id: 2, type: MPEG2AUDIO 17:23:21 T:139916061820672 DEBUG: AddOnLog: Tvheadend HTSP Client: ParseSubscriptionStart - id: 3, type: MPEG2AUDIO 17:23:21 T:139916061820672 DEBUG: AddOnLog: Tvheadend HTSP Client: ParseSubscriptionStart - id: 4, type: DVBSUB 17:23:21 T:139916061820672 DEBUG: AddOnLog: Tvheadend HTSP Client: ParseSubscriptionStart - id: 5, type: DVBSUB 17:23:21 T:139916061820672 DEBUG: AddOnLog: Tvheadend HTSP Client: ParseSubscriptionStart - id: 6, type: DVBSUB 17:23:21 T:139916061820672 DEBUG: AddOnLog: Tvheadend HTSP Client: ParseSubscriptionStart - subscription started on adapter DiBcom 7000PC, mux F: 698,000 kHz, network F, provider Multi 4, service ARTE HD 17:23:21 T:139916061820672 DEBUG: CDVDDemuxPVRClient::RequestStreams(): added stream 0:1 with codec_id 28 17:23:21 T:139916061820672 DEBUG: CDVDDemuxPVRClient::RequestStreams(): added stream 1:2 with codec_id 86016 17:23:21 T:139916061820672 DEBUG: CDVDDemuxPVRClient::RequestStreams(): added stream 2:3 with codec_id 86016 17:23:21 T:139916061820672 DEBUG: CDVDDemuxPVRClient::RequestStreams(): added stream 3:4 with codec_id 94209 17:23:21 T:139916061820672 DEBUG: CDVDDemuxPVRClient::RequestStreams(): added stream 4:5 with codec_id 94209 17:23:21 T:139916061820672 DEBUG: CDVDDemuxPVRClient::RequestStreams(): added stream 5:6 with codec_id 94209 17:23:21 T:139916061820672 NOTICE: Opening audio stream: 2 source: 256 17:23:21 T:139916061820672 NOTICE: Finding audio codec for: 86016 17:23:21 T:139916061820672 DEBUG: FactoryCodec - Audio: PassthroughFFmpeg - Opening 17:23:21 T:139916061820672 INFO: CDVDAudioCodecPassthroughFFmpeg::Open - Won't transcode for HDMI 17:23:21 T:139916061820672 DEBUG: FactoryCodec - Audio: PassthroughFFmpeg - Failed 17:23:21 T:139916061820672 DEBUG: FactoryCodec - Audio: libmad - Opening 17:23:21 T:139916061820672 DEBUG: FactoryCodec - Audio: libmad - Opened 17:23:21 T:139916061820672 NOTICE: Creating audio thread 17:23:21 T:139916379412224 NOTICE: Thread CDVDPlayerAudio start, auto delete: false 17:23:21 T:139916379412224 NOTICE: running thread: CDVDPlayerAudio::Process() 17:23:21 T:139916379412224 DEBUG: CDVDPlayerAudio - CDVDMsg::GENERALRESYNC(64178224844.000000, 1) 17:23:21 T:139916379412224 DEBUG: CDVDPlayerAudio: Sample rate changed, checking for passthrough 17:23:21 T:139916379412224 DEBUG: FactoryCodec - Audio: PassthroughFFmpeg - Opening 17:23:21 T:139916379412224 INFO: CDVDAudioCodecPassthroughFFmpeg::Open - Won't transcode for HDMI 17:23:21 T:139916379412224 DEBUG: FactoryCodec - Audio: PassthroughFFmpeg - Failed 17:23:21 T:139916379412224 DEBUG: FactoryCodec - Audio: libmad - Opening 17:23:21 T:139916379412224 DEBUG: FactoryCodec - Audio: libmad - Opened 17:23:21 T:139916379412224 NOTICE: Creating audio device with codec id: 86016, channels: 2, sample rate: 48000, no pass-through 17:23:21 T:139916379412224 INFO: CPCMRemap: Configured speaker layout: 2.0 17:23:21 T:139916379412224 INFO: CPCMRemap: I channel map: FL,FR 17:23:21 T:139916379412224 INFO: CPCMRemap: O channel map: FL,FR 17:23:21 T:139916379412224 DEBUG: CPCMRemap: Downmix normalization is disabled 17:23:21 T:139916379412224 DEBUG: CPCMRemap: FL = FL(1.000000) 17:23:21 T:139916379412224 DEBUG: CPCMRemap: FR = FR(1.000000_) 17:23:21 T:139916379412224 DEBUG: RemoveActiveDevice - Removing device 1 17:23:21 T:139916379412224 DEBUG: SetActiveDevice - SetActiveDevice from 0 to 2 17:23:21 T:139916379412224 DEBUG: RemoveActiveDevice - Removing device 0 17:23:21 T:139916379412224 DEBUG: PulseAudio: Context authorizing 17:23:21 T:139916379412224 DEBUG: PulseAudio: Context setting name 17:23:21 T:139916379412224 DEBUG: PulseAudio: Context ready 17:23:21 T:139916379412224 DEBUG: PulseAudio: Stream ready 17:23:21 T:139916379412224 DEBUG: PulseAudio: Default buffer attributes, maxlength=4194304, tlength=48000, prebuf=44164, minreq=3840 17:23:21 T:139916379412224 DEBUG: PulseAudio: Choosen buffer attributes, maxlength=4194304, tlength=48000, prebuf=44164, minreq=3840 17:23:21 T:139916379412224 DEBUG: CPulseAudioDirectSound::Initialize - Channels: 2 - SampleRate: 48000 - SampleBit: 16 - Resample false - IsMusic false - IsPassthrough 0 - audioDevice: alsa_output.pci-0000_00_01.1.hdmi-stereo@default 17:23:21 T:139916379412224 DEBUG: CDVDPlayerAudio:: synctype set to 0: clock feedback 17:23:21 T:139916061820672 NOTICE: Opening Subtitle stream: 3 source: 256 17:23:21 T:139916061820672 DEBUG: FactoryCodec - Overlay: FFmpeg Subtitle Decoder - Opening 17:23:21 T:139916061820672 DEBUG: ffmpeg[BF29C700]: [dvbsub] Invalid extradata, subtitle streams may be combined! 17:23:21 T:139916061820672 DEBUG: FactoryCodec - Overlay: FFmpeg Subtitle Decoder - Opened 17:23:21 T:139916061820672 DEBUG: CDVDPlayer::HandleMessages - player started 1 17:23:22 T:139916061820672 NOTICE: Opening video stream: 0 source: 256 17:23:22 T:139916061820672 NOTICE: Creating video codec with codec id: 28 17:23:22 T:139916061820672 DEBUG: CDVDFactoryCodec: compiled in hardware support: CrystalHD:no OpenMax:no VDPAU:no VAAPI:yes 17:23:22 T:139916061820672 DEBUG: FactoryCodec - Video: - Opening 17:23:22 T:139916061820672 NOTICE: CDVDVideoCodecFFmpeg::Open() Using codec: H.264 / AVC / MPEG-4 AVC / MPEG-4 part 10 17:23:22 T:139916061820672 DEBUG: FactoryCodec - Video: ff-h264 - Opened 17:23:22 T:139916128962304 NOTICE: Thread CVideoReferenceClock start, auto delete: false 17:23:22 T:139916128962304 DEBUG: CVideoReferenceClock: Setting up GLX 17:23:22 T:139916128962304 DEBUG: CVideoReferenceClock: GL_VENDOR: ati technologies inc., using ati workaround 17:23:22 T:139916128962304 DEBUG: CVideoReferenceClock: Detected refreshrate: 60 hertz 17:23:22 T:139916061820672 DEBUG: CXBMCRenderManager::EnableBuffering - 0 17:23:22 T:139916061820672 NOTICE: Creating video thread 17:23:22 T:139916112176896 NOTICE: Thread CDVDPlayerVideo start, auto delete: false 17:23:22 T:139916112176896 NOTICE: running thread: video_thread 17:23:22 T:139916112176896 DEBUG: CDVDPlayerVideo - CDVDMsg::GENERAL_RESYNC(64179462133.000000, 0) 17:23:22 T:139916112176896 INFO: CDVDPlayerVideo - Stillframe left, switching to normal playback 17:23:22 T:139916112176896 DEBUG: --------------------- hurry: 0 17:23:22 T:139916112176896 NOTICE: (XVBA::Open) opening xvba decoder, id: 9 17:23:22 T:139916112176896 NOTICE: (XVBA) using decoder capability id: 256 flags: 3 17:23:22 T:139916112176896 NOTICE: (XVBA) using surface type: 3231564e 17:23:22 T:139916128962304 DEBUG: CVideoReferenceClock: Received RandR event 118 17:23:22 T:139916095391488 NOTICE: Thread XVBA Output Thread start, auto delete: false 17:23:22 T:139916095391488 NOTICE: COutput::OnStartup: Output Thread created 17:23:22 T:139916128962304 DEBUG: CVideoReferenceClock: Detected refreshrate: 60 hertz 17:23:22 T:139916061820672 DEBUG: set caching from pvr to done. audio (1) = 10. video (1) = 6 17:23:22 T:139916061820672 DEBUG: CDVDPlayer::SetCaching - caching state 0 17:23:22 T:139916379412224 DEBUG: CDVDPlayerAudio:: Discontinuity - was:64179021771.627998, should be:64178224848.441582, error:-796923.186417 17:23:22 T:139916061820672 ERROR: Get - failed to get stream 17:23:22 T:139916145747712 DEBUG: GetImageHash - unable to stat url http://telepoche.guidetele.com/medias/chaines/tf1.gif 17:23:22 T:139916095391488 NOTICE: XVBA::COutput::CreateGlxContext - created context 17:23:22 T:139916095391488 DEBUG: XVBA::GetTexture - created shared surface 17:23:22 T:139916112176896 DEBUG: Previous line repeats 8 times. 17:23:22 T:139916112176896 DEBUG: XVBA::FFGetBuffer - created video surface 17:23:22 T:139916880447392 DEBUG: Previous line repeats 4 times. 17:23:22 T:139916880447392 DEBUG: ------ Window Deinit (DialogSeekBar.xml) ------ 17:23:22 T:139916112176896 INFO: ffmpeg[C22A2700]: [h264] Cannot parallelize deblocking type 1, decoding such frames in sequential order 17:23:22 T:139916112176896 DEBUG: --------------------- hurry: 0 17:23:22 T:139916112176896 DEBUG: Previous line repeats 3 times. 17:23:22 T:139916112176896 ERROR: ffmpeg[C22A2700]: [h264] mmco: unref short failure 17:23:22 T:139916112176896 ERROR: ffmpeg[C22A2700]: [h264] number of reference frames exceeds max (probably corrupt input), discarding one 17:23:22 T:139916112176896 DEBUG: --------------------- hurry: 0 17:23:22 T:139916112176896 DEBUG: Previous line repeats 4 times. 17:23:22 T:139916112176896 NOTICE: fps: 0.000000, pwidth: 1920, pheight: 1080, dwidth: 1920, dheight: 1080 17:23:22 T:139916112176896 DEBUG: OutputPicture - change configuration. 1920x1080. framerate: 0.00. format: XVBA 17:23:22 T:139916379412224 DEBUG: CDVDPlayerAudio:: synctype set to 2: resample 17:23:22 T:139916379412224 DEBUG: CDVDPlayerResampler: resized buffers to hold 11520 frames 17:23:22 T:139916880447392 NOTICE: Using GL_TEXTURE_2D 17:23:22 T:139916880447392 NOTICE: GL: Using XVBA render method 17:23:22 T:139916880447392 NOTICE: GL: NPOT texture support detected 17:23:22 T:139916880447392 NOTICE: GL: Using GL_ARB_pixel_buffer_object 17:23:22 T:139916112176896 DEBUG: CXBMCRenderManager::EnableBuffering - 1 17:23:22 T:139916061820672 DEBUG: CDVDPlayer::HandleMessages - player started 2 17:23:22 T:139916112176896 DEBUG: XVBA::FFGetBuffer - created video surface 17:23:22 T:139916112176896 DEBUG: Previous line repeats 1 times. 17:23:22 T:139916112176896 WARNING: CRenderManager::WaitForBuffer - timeout waiting for buffer 17:23:23 T:139916112176896 WARNING: Previous line repeats 8 times. 17:23:23 T:139916112176896 DEBUG: XVBA::FFGetBuffer - created video surface 17:23:23 T:139916112176896 WARNING: CRenderManager::WaitForBuffer - timeout waiting for buffer 17:23:24 T:139916112176896 WARNING: Previous line repeats 4 times. 17:23:24 T:139916112176896 DEBUG: XVBA::FFGetBuffer - created video surface 17:23:26 T:139916880447392 DEBUG: Previous line repeats 1 times. 17:23:26 T:139916880447392 DEBUG: ------ Window Deinit (Pointer.xml) ------ 17:23:27 T:139916145747712 DEBUG: GetImageHash - unable to stat url http://telepoche.guidetele.com/medias/chaines/france2.gif 17:23:29 T:139916112176896 DEBUG: CPullupCorrection: detected pattern of length 1: 40000.00, frameduration: 40000.000000 17:23:29 T:139916112176896 DEBUG: CPullupCorrection: pattern lost on diff 80000.000000 17:23:29 T:139916112176896 DEBUG: XVBA::FFGetBuffer - created video surface 17:23:32 T:139916145747712 DEBUG: GetImageHash - unable to stat url http://telepoche.guidetele.com/medias/chaines/france3.gif 17:23:34 T:139916112176896 DEBUG: CPullupCorrection: detected pattern of length 1: 40000.00, frameduration: 40000.000000 17:23:35 T:139916112176896 DEBUG: CalcFrameRate framerate was:25.000000 calculated:25.000000 17:23:35 T:139916112176896 NOTICE: fps: 25.000000, pwidth: 1920, pheight: 1080, dwidth: 1920, dheight: 1080 17:23:35 T:139916112176896 DEBUG: OutputPicture - change configuration. 1920x1080. framerate: 25.00. format: XVBA 17:23:35 T:139916112176896 NOTICE: Display resolution ADJUST : DFP2: 1920x1080 @ 50.00Hz (14) (weight: 0.000) 17:23:35 T:139916880447392 NOTICE: Using GL_TEXTURE_2D 17:23:35 T:139916880447392 NOTICE: GL: Using XVBA render method 17:23:35 T:139916880447392 NOTICE: GL: NPOT texture support detected 17:23:35 T:139916880447392 NOTICE: GL: Using GL_ARB_pixel_buffer_object 17:23:35 T:139916112176896 WARNING: CRenderManager::WaitForBuffer - timeout waiting for buffer 17:23:35 T:139916880447392 WARNING: CLinuxRendererGL::UploadXVBATexture no xvba texture, index: 3 17:23:37 T:139916145747712 WARNING: Previous line repeats 2 times. 17:23:37 T:139916145747712 DEBUG: GetImageHash - unable to stat url http://telepoche.guidetele.com/medias/chaines/new-logo.png 17:23:42 T:139916145747712 DEBUG: GetImageHash - unable to stat url http://telepoche.guidetele.com/medias/chaines/w9.png 17:23:47 T:139916145747712 DEBUG: GetImageHash - unable to stat url http://telepoche.guidetele.com/medias/chaines/arte.gif 17:23:49 T:139916222322432 DEBUG: AddOnLog: Tvheadend HTSP Client: ParseChannelUpdate - id:18, name:'(null)', icon:'(null)', event:26985 17:23:52 T:139916145747712 DEBUG: GetImageHash - unable to stat url http://telepoche.guidetele.com/medias/chaines/france5.gif 17:23:57 T:139916145747712 DEBUG: GetImageHash - unable to stat url http://telepoche.guidetele.com/medias/chaines/m6_logo.gif
second trace
tail -200 ~/.xbmc/temp/xbmc.log 17:31:41 T:139858041079552 ERROR: ffmpeg[3CDB8700]: [h264] decode_slice_header error 17:31:41 T:139858041079552 ERROR: ffmpeg[3CDB8700]: [h264] get_buffer() failed (-1 0 0 (nil)) 17:31:41 T:139858041079552 ERROR: ffmpeg[3CDB8700]: [h264] decode_slice_header error 17:31:41 T:139858041079552 ERROR: ffmpeg[3CDB8700]: [h264] get_buffer() failed (-1 0 0 (nil)) 17:31:41 T:139858041079552 ERROR: ffmpeg[3CDB8700]: [h264] decode_slice_header error 17:31:41 T:139858041079552 ERROR: ffmpeg[3CDB8700]: [h264] no frame! 17:31:41 T:139858041079552 ERROR: Decode - avcodec_decode_video returned failure 17:31:41 T:139858041079552 DEBUG: CDVDPlayerVideo - video decoder returned error 17:31:41 T:139858041079552 DEBUG: --------------------- hurry: 0 17:31:41 T:139858041079552 NOTICE: XVBA::Check - Attempting recovery 17:31:41 T:139858041079552 DEBUG: CDVDPlayerVideo - video decoder returned error 17:31:41 T:139858041079552 DEBUG: --------------------- hurry: 0 17:31:41 T:139858041079552 ERROR: XVBA::CreateSession - failed to create decoder session, line 648 17:31:41 T:139858041079552 ERROR: Decoder failed with following stats: m_surfaceWidth 1920, m_surfaceHeight 1088, m_vidWidth 1920, m_vidHeight 1080, coded_width 1920, coded_height 1088 17:31:41 T:139858041079552 ERROR: ffmpeg[3CDB8700]: [h264] get_buffer() failed (-1 0 0 (nil)) 17:31:41 T:139858041079552 ERROR: ffmpeg[3CDB8700]: [h264] decode_slice_header error 17:31:41 T:139858041079552 ERROR: ffmpeg[3CDB8700]: [h264] get_buffer() failed (-1 0 0 (nil)) 17:31:41 T:139858041079552 ERROR: ffmpeg[3CDB8700]: [h264] decode_slice_header error 17:31:41 T:139858041079552 ERROR: ffmpeg[3CDB8700]: [h264] get_buffer() failed (-1 0 0 (nil)) 17:31:41 T:139858041079552 ERROR: ffmpeg[3CDB8700]: [h264] decode_slice_header error 17:31:41 T:139858041079552 ERROR: ffmpeg[3CDB8700]: [h264] get_buffer() failed (-1 0 0 (nil)) 17:31:41 T:139858041079552 ERROR: ffmpeg[3CDB8700]: [h264] decode_slice_header error 17:31:41 T:139858041079552 ERROR: ffmpeg[3CDB8700]: [h264] get_buffer() failed (-1 0 0 (nil)) 17:31:41 T:139858041079552 ERROR: ffmpeg[3CDB8700]: [h264] decode_slice_header error 17:31:41 T:139858041079552 ERROR: ffmpeg[3CDB8700]: [h264] get_buffer() failed (-1 0 0 (nil)) 17:31:41 T:139858041079552 ERROR: ffmpeg[3CDB8700]: [h264] decode_slice_header error 17:31:41 T:139858041079552 ERROR: ffmpeg[3CDB8700]: [h264] no frame! 17:31:41 T:139858041079552 ERROR: Decode - avcodec_decode_video returned failure 17:31:41 T:139858041079552 DEBUG: CDVDPlayerVideo - video decoder returned error 17:31:42 T:139858041079552 DEBUG: --------------------- hurry: 0 17:31:42 T:139858041079552 NOTICE: XVBA::Check - Attempting recovery 17:31:42 T:139858041079552 DEBUG: CDVDPlayerVideo - video decoder returned error 17:31:42 T:139858851968928 NOTICE: ----------- no buffer, out: 2, current: 2, display: 0 17:31:42 T:139858041079552 DEBUG: --------------------- hurry: 0 17:31:42 T:139858041079552 ERROR: XVBA::CreateSession - failed to create decoder session, line 648 17:31:42 T:139858041079552 ERROR: Decoder failed with following stats: m_surfaceWidth 1920, m_surfaceHeight 1088, m_vidWidth 1920, m_vidHeight 1080, coded_width 1920, coded_height 1088 17:31:42 T:139858041079552 ERROR: ffmpeg[3CDB8700]: [h264] get_buffer() failed (-1 0 0 (nil)) 17:31:42 T:139858041079552 ERROR: ffmpeg[3CDB8700]: [h264] decode_slice_header error 17:31:42 T:139858041079552 ERROR: ffmpeg[3CDB8700]: [h264] get_buffer() failed (-1 0 0 (nil)) 17:31:42 T:139858041079552 ERROR: ffmpeg[3CDB8700]: [h264] decode_slice_header error 17:31:42 T:139858041079552 ERROR: ffmpeg[3CDB8700]: [h264] get_buffer() failed (-1 0 0 (nil)) 17:31:42 T:139858041079552 ERROR: ffmpeg[3CDB8700]: [h264] decode_slice_header error 17:31:42 T:139858041079552 ERROR: ffmpeg[3CDB8700]: [h264] get_buffer() failed (-1 0 0 (nil)) 17:31:42 T:139858041079552 ERROR: ffmpeg[3CDB8700]: [h264] decode_slice_header error 17:31:42 T:139858041079552 ERROR: ffmpeg[3CDB8700]: [h264] get_buffer() failed (-1 0 0 (nil)) 17:31:42 T:139858041079552 ERROR: ffmpeg[3CDB8700]: [h264] decode_slice_header error 17:31:42 T:139858041079552 ERROR: ffmpeg[3CDB8700]: [h264] get_buffer() failed (-1 0 0 (nil)) 17:31:42 T:139858041079552 ERROR: ffmpeg[3CDB8700]: [h264] decode_slice_header error 17:31:42 T:139858041079552 ERROR: ffmpeg[3CDB8700]: [h264] no frame! 17:31:42 T:139858041079552 ERROR: Decode - avcodec_decode_video returned failure 17:31:42 T:139858041079552 DEBUG: CDVDPlayerVideo - video decoder returned error 17:31:42 T:139858041079552 DEBUG: --------------------- hurry: 0 17:31:42 T:139858041079552 NOTICE: XVBA::Check - Attempting recovery 17:31:42 T:139858041079552 DEBUG: CDVDPlayerVideo - video decoder returned error 17:31:42 T:139858041079552 DEBUG: --------------------- hurry: 0 17:31:42 T:139858041079552 ERROR: XVBA::CreateSession - failed to create decoder session, line 648 17:31:42 T:139858041079552 ERROR: Decoder failed with following stats: m_surfaceWidth 1920, m_surfaceHeight 1088, m_vidWidth 1920, m_vidHeight 1080, coded_width 1920, coded_height 1088 17:31:42 T:139858041079552 ERROR: ffmpeg[3CDB8700]: [h264] get_buffer() failed (-1 0 0 (nil)) 17:31:42 T:139858041079552 ERROR: ffmpeg[3CDB8700]: [h264] decode_slice_header error 17:31:42 T:139858041079552 ERROR: ffmpeg[3CDB8700]: [h264] get_buffer() failed (-1 0 0 (nil)) 17:31:42 T:139858041079552 ERROR: ffmpeg[3CDB8700]: [h264] decode_slice_header error 17:31:42 T:139858041079552 ERROR: ffmpeg[3CDB8700]: [h264] get_buffer() failed (-1 0 0 (nil)) 17:31:42 T:139858041079552 ERROR: ffmpeg[3CDB8700]: [h264] decode_slice_header error 17:31:42 T:139858041079552 ERROR: ffmpeg[3CDB8700]: [h264] get_buffer() failed (-1 0 0 (nil)) 17:31:42 T:139858041079552 ERROR: ffmpeg[3CDB8700]: [h264] decode_slice_header error 17:31:42 T:139858041079552 ERROR: ffmpeg[3CDB8700]: [h264] get_buffer() failed (-1 0 0 (nil)) 17:31:42 T:139858041079552 ERROR: ffmpeg[3CDB8700]: [h264] decode_slice_header error 17:31:42 T:139858041079552 ERROR: ffmpeg[3CDB8700]: [h264] get_buffer() failed (-1 0 0 (nil)) 17:31:42 T:139858041079552 ERROR: ffmpeg[3CDB8700]: [h264] decode_slice_header error 17:31:42 T:139858041079552 ERROR: ffmpeg[3CDB8700]: [h264] no frame! 17:31:42 T:139858041079552 ERROR: Decode - avcodec_decode_video returned failure 17:31:42 T:139858041079552 DEBUG: CDVDPlayerVideo - video decoder returned error 17:31:42 T:139858041079552 DEBUG: --------------------- hurry: 0 17:31:42 T:139858041079552 NOTICE: XVBA::Check - Attempting recovery 17:31:42 T:139858041079552 DEBUG: CDVDPlayerVideo - video decoder returned error 17:31:42 T:139858041079552 DEBUG: --------------------- hurry: 0 17:31:42 T:139858041079552 ERROR: XVBA::CreateSession - failed to create decoder session, line 648 17:31:42 T:139858041079552 ERROR: Decoder failed with following stats: m_surfaceWidth 1920, m_surfaceHeight 1088, m_vidWidth 1920, m_vidHeight 1080, coded_width 1920, coded_height 1088 17:31:42 T:139858041079552 ERROR: ffmpeg[3CDB8700]: [h264] get_buffer() failed (-1 0 0 (nil)) 17:31:42 T:139858041079552 ERROR: ffmpeg[3CDB8700]: [h264] decode_slice_header error 17:31:42 T:139858041079552 ERROR: ffmpeg[3CDB8700]: [h264] get_buffer() failed (-1 0 0 (nil)) 17:31:42 T:139858041079552 ERROR: ffmpeg[3CDB8700]: [h264] decode_slice_header error 17:31:42 T:139858041079552 ERROR: ffmpeg[3CDB8700]: [h264] get_buffer() failed (-1 0 0 (nil)) 17:31:42 T:139858041079552 ERROR: ffmpeg[3CDB8700]: [h264] decode_slice_header error 17:31:42 T:139858041079552 ERROR: ffmpeg[3CDB8700]: [h264] get_buffer() failed (-1 0 0 (nil)) 17:31:42 T:139858041079552 ERROR: ffmpeg[3CDB8700]: [h264] decode_slice_header error 17:31:42 T:139858041079552 ERROR: ffmpeg[3CDB8700]: [h264] get_buffer() failed (-1 0 0 (nil)) 17:31:42 T:139858041079552 ERROR: ffmpeg[3CDB8700]: [h264] decode_slice_header error 17:31:42 T:139858041079552 ERROR: ffmpeg[3CDB8700]: [h264] get_buffer() failed (-1 0 0 (nil)) 17:31:42 T:139858041079552 ERROR: ffmpeg[3CDB8700]: [h264] decode_slice_header error 17:31:42 T:139858041079552 ERROR: ffmpeg[3CDB8700]: [h264] no frame! 17:31:42 T:139858041079552 ERROR: Decode - avcodec_decode_video returned failure 17:31:42 T:139858041079552 DEBUG: CDVDPlayerVideo - video decoder returned error 17:31:42 T:139858041079552 DEBUG: --------------------- hurry: 0 17:31:42 T:139858041079552 NOTICE: XVBA::Check - Attempting recovery 17:31:42 T:139858041079552 DEBUG: CDVDPlayerVideo - video decoder returned error 17:31:42 T:139858851968928 NOTICE: ----------- no buffer, out: 2, current: 2, display: 0 17:31:42 T:139858041079552 DEBUG: --------------------- hurry: 0 17:31:42 T:139858041079552 ERROR: XVBA::CreateSession - failed to create decoder session, line 648 17:31:42 T:139858041079552 ERROR: Decoder failed with following stats: m_surfaceWidth 1920, m_surfaceHeight 1088, m_vidWidth 1920, m_vidHeight 1080, coded_width 1920, coded_height 1088 17:31:42 T:139858041079552 ERROR: ffmpeg[3CDB8700]: [h264] get_buffer() failed (-1 0 0 (nil)) 17:31:42 T:139858041079552 ERROR: ffmpeg[3CDB8700]: [h264] decode_slice_header error 17:31:42 T:139858041079552 ERROR: ffmpeg[3CDB8700]: [h264] get_buffer() failed (-1 0 0 (nil)) 17:31:42 T:139858041079552 ERROR: ffmpeg[3CDB8700]: [h264] decode_slice_header error 17:31:42 T:139858041079552 ERROR: ffmpeg[3CDB8700]: [h264] get_buffer() failed (-1 0 0 (nil)) 17:31:42 T:139858041079552 ERROR: ffmpeg[3CDB8700]: [h264] decode_slice_header error 17:31:42 T:139858041079552 ERROR: ffmpeg[3CDB8700]: [h264] get_buffer() failed (-1 0 0 (nil)) 17:31:42 T:139858041079552 ERROR: ffmpeg[3CDB8700]: [h264] decode_slice_header error 17:31:42 T:139858041079552 ERROR: ffmpeg[3CDB8700]: [h264] get_buffer() failed (-1 0 0 (nil)) 17:31:42 T:139858041079552 ERROR: ffmpeg[3CDB8700]: [h264] decode_slice_header error 17:31:42 T:139858041079552 ERROR: ffmpeg[3CDB8700]: [h264] get_buffer() failed (-1 0 0 (nil)) 17:31:42 T:139858041079552 ERROR: ffmpeg[3CDB8700]: [h264] decode_slice_header error 17:31:42 T:139858041079552 ERROR: ffmpeg[3CDB8700]: [h264] no frame! 17:31:42 T:139858041079552 ERROR: Decode - avcodec_decode_video returned failure 17:31:42 T:139858041079552 DEBUG: CDVDPlayerVideo - video decoder returned error 17:31:42 T:139858041079552 DEBUG: --------------------- hurry: 0 17:31:42 T:139858041079552 NOTICE: XVBA::Check - Attempting recovery 17:31:42 T:139858041079552 DEBUG: CDVDPlayerVideo - video decoder returned error 17:31:42 T:139858041079552 DEBUG: --------------------- hurry: 0 17:31:42 T:139858041079552 ERROR: XVBA::CreateSession - failed to create decoder session, line 648 17:31:42 T:139858041079552 ERROR: Decoder failed with following stats: m_surfaceWidth 1920, m_surfaceHeight 1088, m_vidWidth 1920, m_vidHeight 1080, coded_width 1920, coded_height 1088 17:31:42 T:139858041079552 ERROR: ffmpeg[3CDB8700]: [h264] get_buffer() failed (-1 0 0 (nil)) 17:31:42 T:139858041079552 ERROR: ffmpeg[3CDB8700]: [h264] decode_slice_header error 17:31:42 T:139858041079552 ERROR: ffmpeg[3CDB8700]: [h264] get_buffer() failed (-1 0 0 (nil)) 17:31:42 T:139858041079552 ERROR: ffmpeg[3CDB8700]: [h264] decode_slice_header error 17:31:42 T:139858041079552 ERROR: ffmpeg[3CDB8700]: [h264] get_buffer() failed (-1 0 0 (nil)) 17:31:42 T:139858041079552 ERROR: ffmpeg[3CDB8700]: [h264] decode_slice_header error 17:31:42 T:139858041079552 ERROR: ffmpeg[3CDB8700]: [h264] get_buffer() failed (-1 0 0 (nil)) 17:31:42 T:139858041079552 ERROR: ffmpeg[3CDB8700]: [h264] decode_slice_header error 17:31:42 T:139858041079552 ERROR: ffmpeg[3CDB8700]: [h264] get_buffer() failed (-1 0 0 (nil)) 17:31:42 T:139858041079552 ERROR: ffmpeg[3CDB8700]: [h264] decode_slice_header error 17:31:42 T:139858041079552 ERROR: ffmpeg[3CDB8700]: [h264] get_buffer() failed (-1 0 0 (nil)) 17:31:42 T:139858041079552 ERROR: ffmpeg[3CDB8700]: [h264] decode_slice_header error 17:31:42 T:139858041079552 ERROR: ffmpeg[3CDB8700]: [h264] no frame! 17:31:42 T:139858041079552 ERROR: Decode - avcodec_decode_video returned failure 17:31:42 T:139858041079552 DEBUG: CDVDPlayerVideo - video decoder returned error 17:31:42 T:139858041079552 DEBUG: --------------------- hurry: 0 17:31:42 T:139858041079552 NOTICE: XVBA::Check - Attempting recovery 17:31:42 T:139858041079552 DEBUG: CDVDPlayerVideo - video decoder returned error 17:31:42 T:139858041079552 DEBUG: --------------------- hurry: 0 17:31:42 T:139858041079552 ERROR: XVBA::CreateSession - failed to create decoder session, line 648 17:31:42 T:139858041079552 ERROR: Decoder failed with following stats: m_surfaceWidth 1920, m_surfaceHeight 1088, m_vidWidth 1920, m_vidHeight 1080, coded_width 1920, coded_height 1088 17:31:42 T:139858041079552 ERROR: ffmpeg[3CDB8700]: [h264] get_buffer() failed (-1 0 0 (nil)) 17:31:42 T:139858041079552 ERROR: ffmpeg[3CDB8700]: [h264] decode_slice_header error 17:31:42 T:139858041079552 ERROR: ffmpeg[3CDB8700]: [h264] get_buffer() failed (-1 0 0 (nil)) 17:31:42 T:139858041079552 ERROR: ffmpeg[3CDB8700]: [h264] decode_slice_header error 17:31:42 T:139858041079552 ERROR: ffmpeg[3CDB8700]: [h264] get_buffer() failed (-1 0 0 (nil)) 17:31:42 T:139858041079552 ERROR: ffmpeg[3CDB8700]: [h264] decode_slice_header error 17:31:42 T:139858041079552 ERROR: ffmpeg[3CDB8700]: [h264] get_buffer() failed (-1 0 0 (nil)) 17:31:42 T:139858041079552 ERROR: ffmpeg[3CDB8700]: [h264] decode_slice_header error 17:31:42 T:139858041079552 ERROR: ffmpeg[3CDB8700]: [h264] get_buffer() failed (-1 0 0 (nil)) 17:31:42 T:139858041079552 ERROR: ffmpeg[3CDB8700]: [h264] decode_slice_header error 17:31:42 T:139858041079552 ERROR: ffmpeg[3CDB8700]: [h264] get_buffer() failed (-1 0 0 (nil)) 17:31:42 T:139858041079552 ERROR: ffmpeg[3CDB8700]: [h264] decode_slice_header error 17:31:42 T:139858041079552 ERROR: ffmpeg[3CDB8700]: [h264] no frame! 17:31:42 T:139858041079552 ERROR: Decode - avcodec_decode_video returned failure 17:31:42 T:139858041079552 DEBUG: CDVDPlayerVideo - video decoder returned error 17:31:42 T:139858851968928 NOTICE: ----------- no buffer, out: 2, current: 2, display: 0 17:31:42 T:139858041079552 DEBUG: --------------------- hurry: 0 17:31:42 T:139858041079552 NOTICE: XVBA::Check - Attempting recovery 17:31:42 T:139858041079552 DEBUG: CDVDPlayerVideo - video decoder returned error 17:31:42 T:139858041079552 DEBUG: --------------------- hurry: 0 17:31:42 T:139858041079552 ERROR: XVBA::CreateSession - failed to create decoder session, line 648 17:31:42 T:139858041079552 ERROR: Decoder failed with following stats: m_surfaceWidth 1920, m_surfaceHeight 1088, m_vidWidth 1920, m_vidHeight 1080, coded_width 1920, coded_height 1088 17:31:42 T:139858041079552 ERROR: ffmpeg[3CDB8700]: [h264] get_buffer() failed (-1 0 0 (nil)) 17:31:42 T:139858041079552 ERROR: ffmpeg[3CDB8700]: [h264] decode_slice_header error 17:31:42 T:139858041079552 ERROR: ffmpeg[3CDB8700]: [h264] get_buffer() failed (-1 0 0 (nil)) 17:31:42 T:139858041079552 ERROR: ffmpeg[3CDB8700]: [h264] decode_slice_header error 17:31:42 T:139858041079552 ERROR: ffmpeg[3CDB8700]: [h264] get_buffer() failed (-1 0 0 (nil)) 17:31:42 T:139858041079552 ERROR: ffmpeg[3CDB8700]: [h264] decode_slice_header error 17:31:42 T:139858041079552 ERROR: ffmpeg[3CDB8700]: [h264] get_buffer() failed (-1 0 0 (nil)) 17:31:42 T:139858041079552 ERROR: ffmpeg[3CDB8700]: [h264] decode_slice_header error 17:31:42 T:139858041079552 ERROR: ffmpeg[3CDB8700]: [h264] get_buffer() failed (-1 0 0 (nil)) 17:31:42 T:139858041079552 ERROR: ffmpeg[3CDB8700]: [h264] decode_slice_header error 17:31:42 T:139858041079552 ERROR: ffmpeg[3CDB8700]: [h264] get_buffer() failed (-1 0 0 (nil)) 17:31:42 T:139858041079552 ERROR: ffmpeg[3CDB8700]: [h264] decode_slice_header error 17:31:42 T:139858041079552 ERROR: ffmpeg[3CDB8700]: [h264] no frame! 17:31:42 T:139858041079552 ERROR: Decode - avcodec_decode_video returned failure 17:31:42 T:139858041079552 DEBUG: CDVDPlayerVideo - video decoder returned error 17:31:42 T:139858041079552 DEBUG: --------------------- hurry: 0 17:31:42 T:139858041079552 NOTICE: XVBA::Check - Attempting recovery 17:31:42 T:139858041079552 DEBUG: CDVDPlayerVideo - video decoder returned error 17:31:42 T:139858041079552 DEBUG: --------------------- hurry: 0 17:31:42 T:139858041079552 ERROR: XVBA::CreateSession - failed to create decoder session, line 648 17:31:42 T:139858041079552 ERROR: Decoder failed with following stats: m_surfaceWidth 1920, m_surfaceHeight 1088, m_vidWidth 1920, m_vidHeight 1080, coded_width 1920, coded_height 1088 17:31:42 T:139858041079552 ERROR: ffmpeg[3CDB8700]: [h264] get_buffer() failed (-1 0 0 (nil)) 17:31:42 T:139858041079552 ERROR: ffmpeg[3CDB8700]: [h264] decode_slice_header error 17:31:42 T:139858041079552 ERROR: ffmpeg[3CDB8700]: [h264] get_buffer() failed (-1 0 0 (nil)) 17:31:42 T:139858041079552 ERROR: ffmpeg[3CDB8700]: [h264] decode_slice_header error 17:31:42 T:139858041079552 ERROR: ffmpeg[3CDB8700]: [h264] get_buffer() failed (-1 0 0 (nil)) 17:31:42 T:139858041079552 ERROR: ffmpeg[3CDB8700]: [h264] decode_slice_header error 17:31:42 T:139858041079552 ERROR: ffmpeg[3CDB8700]: [h264] get_buffer() failed (-1 0 0 (nil)) 17:31:42 T:139858041079552 ERROR: ffmpeg[3CDB8700]: [h264] decode_slice_header error 17:31:42 T:139858041079552 ERROR: ffmpeg[3CDB8700]: [h264] get_buffer() failed (-1 0 0 (nil)) 17:31:42 T:139858041079552 ERROR: ffmpeg[3CDB8700]: [h264] decode_slice_header error 17:31:42 T:139858041079552 ERROR: ffmpeg[3CDB8700]: [h264] get_buffer() failed (-1 0 0 (nil)) 17:31:42 T:139858041079552 ERROR: ffmpeg[3CDB8700]: [h264] decode_slice_header error 17:31:42 T:139858041079552 ERROR: ffmpeg[3CDB8700]: [h264] no frame! 17:31:42 T:139858041079552 ERROR: Decode - avcodec_decode_video returned failure 17:31:42 T:139858041079552 DEBUG: CDVDPlayerVideo - video decoder returned error
Additionnal info : when I manage to crash corrupts XBMC display, I can stop and restart but I never get good display anymore. But, when quitting and restarting XBMC, I get back normal/correct display. So the GPU itself is not in a dead state but rather the internal XBMC logic is not able to properly reinitialize something (dunno what yet).
In order to track this down I would need a full debug log. Here is what I think what happens. A decoder in XBMC is ref counted, the player holds a reference and the render buffers. When you switch channels the player releases the reference and opens the new source and a new decoder e.g. xvba. the renderer is still able to render the last frame in order not to get a black display. Now when you torture your system and manage it to open a third xvba instance you'll run out of video ram. This is most likely what happened looking at the log. The player itself would not flush the render buffers and can't recover from this state unless you push stop. XVBA dll is loaded only once. Hence if the interface is in a corrupted state it won't recover untill xbmc is terminated. That's actually a bug in xvba, the spec says that when the xvba context is destroyed, it will clean up all resources properly. This is not done (this one goes to AMD)
I have already implemented a function to do a pre-cleanup after the player releases its reference to the decoder. I guess I need to release more resources in this method. Only keep those resources required by the render buffers.
Done. Send by email.
I think we can close this one, right?
On 14/07/2012 13:13, Rainer Hochecker wrote:
I think we can close this one, right?
Reply to this email directly or view it on GitHub: https://github.com/FernetMenta/xbmc/issues/30#issuecomment-6982123
Yes.
-- eric
So I tester again the code on master this morning. First time it started, I got the video correctly on TV via tvheadend in the small windows, but going full screen gave me only black screen. Hitting escape I was correctly returning on the channel selection and has back the TV stream in the small windows.
After a while it crashed
[Thread debugging using libthread_db enabled] Using host libthread_db library "/lib/x86_64-linux-gnu/libthread_db.so.1". Core was generated by `/usr/local/lib/xbmc/xbmc.bin'. Program terminated with signal 11, Segmentation fault.
0 0x00000000038de6d7 in ?? ()
0 0x00000000038de6d7 in ?? ()
1 0x0000000000f6cee1 in CWinSystemX11::OnLostDevice (this=0x1cbd9b0) at WinSystemX11.cpp:518
2 0x0000000000f6d22f in CWinSystemX11::SetFullScreen (this=0x1cbd9b0, fullScreen=, res=...,
blankOtherDisplays=) at WinSystemX11.cpp:198
3 0x0000000000f707a7 in CWinSystemX11GL::SetFullScreen (this=0x1cbd9b0, fullScreen=true, res=..., blankOth
erDisplays=) at WinSystemX11GL.cpp:257
4 0x00000000008986ee in CGraphicContext::SetVideoResolution (this=0x1cbc8b0, res=, forceUpd
ate=) at GraphicContext.cpp:380
5 0x0000000000898d89 in CGraphicContext::SetFullScreenVideo (this=0x1cbc8b0, bOnOff=) at Gr
aphicContext.cpp:287
6 0x00000000006e5dc6 in CGUIWindowFullScreen::OnMessage (this=0x7fecac02b750, message=...) at GUIWindowFul
lScreen.cpp:771
7 0x000000000092ef35 in CGUIWindow::Close_Internal (this=0x7fecac02b750, forceClose=false, nextWindowID=0,
enableSound=true) at GUIWindow.cpp:359
8 0x0000000000935829 in CGUIWindowManager::CloseWindowSync (this=, window=0x7fecac02b750, n
extWindowID=) at GUIWindowManager.cpp:971
9 0x0000000000938721 in CGUIWindowManager::PreviousWindow (this=0x14b2c40) at GUIWindowManager.cpp:306
10 0x00000000006e8e96 in CGUIWindowFullScreen::OnAction (this=0x7fecac02b750, action=...) at GUIWindowFullS
creen.cpp:200
11 0x0000000000934c7d in CGUIWindowManager::OnAction (this=0x14b2c40, action=...) at GUIWindowManager.cpp:4
83
12 0x0000000000eb664f in CApplication::OnAction (this=0x14b1920, action=...) at Application.cpp:2412
13 0x0000000000eb8151 in CApplication::OnKey (this=0x14b1920, key=...) at Application.cpp:2327
14 0x0000000000eb8f02 in CApplication::OnEvent (newEvent=...) at Application.cpp:434
15 0x0000000000e86eaf in CWinEventsSDL::MessagePump () at WinEventsSDL.cpp:281
16 0x0000000000ebc8dd in CApplication::FrameMove (this=0x14b1920, processEvents=) at Applica
tion.cpp:2751
17 0x0000000000f443de in CXBApplicationEx::Run (this=0x14b1920) at XBApplicationEx.cpp:120
18 0x0000000000681819 in main (argc=1, argv=) at xbmc.cpp:93
I restarted, this time I managed to get the full screen TV display. I just noticed the OSD was not correctly resized when it was at first run. Once started it played correctly.