2012-02-29, 18:42
I've been having this problem since Dharma, and I'm still having it on the Eden RC. When I leave XBMC alone for a while and then come back to it, I get a huge delay when starting playback, usually followed by the audio playing and video stuttering, then finally I get normal playback.
I finally thought to grab a debug log for it last night.
Can anyone tell me what it's doing here, what those length delays are between 22:29:50 and 22:31:00 are caused by, and why it only happens the first time I play back a file? If I stop and restart the same video, it plays normally on the second attempt.
I finally thought to grab a debug log for it last night.
Code:
22:29:50 T:2559249264 NOTICE: Display resolution ADJUST : 1920x1080 @ 60.00 - Full Screen (12) (weight: 0.001)
22:29:50 T:2559249264 DEBUG: CVideoReferenceClock: Clock speed 100.100000%
22:29:50 T:2939181936 DEBUG: CDVDPlayer::SetCaching - caching state 0
22:29:51 T:2939181936 DEBUG: CDVDPlayer::HandleMessages - player started 2
22:29:55 T:2559249264 DEBUG: CPullupCorrection: detected pattern of length 1: 33366.67, frameduration: 33366.666667
22:29:59 T:2559249264 WARNING: CDVDMessageQueue(video)::Get - asked for new data packet, with nothing available
22:29:59 T:2559249264 INFO: CDVDPlayerVideo - Stillframe detected, switching to forced 29.970030 fps
22:29:59 T:2559249264 DEBUG: CPullupCorrection: pattern lost on diff 166833.333333
22:30:13 T:2776202096 DEBUG: Thread Jobworker 2776202096 terminating (autodelete)
22:30:13 T:2814143344 DEBUG: Thread Jobworker 2814143344 terminating (autodelete)
22:30:13 T:2795866992 DEBUG: Thread Jobworker 2795866992 terminating (autodelete)
22:30:13 T:2863647600 DEBUG: Thread Jobworker 2863647600 terminating (autodelete)
22:30:31 T:2559249264 DEBUG: CPullupCorrection: detected pattern of length 1: 33366.67, frameduration: 33366.666667
22:31:00 T:2550856560 DEBUG: SetActiveDevice - SetActiveDevice from 0 to 2
22:31:00 T:2550856560 DEBUG: RemoveActiveDevice - Removing device 0
22:31:00 T:3017975568 DEBUG: Activating window ID: 12005
22:31:00 T:2550856560 DEBUG: Initialize - using alsa device plug:'hdmi:CARD=NVidia'
22:31:00 T:3017975568 DEBUG: ------ Window Deinit (MyVideoNav.xml) ------
22:31:00 T:3017975568 DEBUG: ------ Window Init (VideoFullScreen.xml) ------
22:31:00 T:3017975568 INFO: Loading skin file: VideoFullScreen.xml
22:31:01 T:2550856560 DEBUG: CALSADirectSound::Initialize - frame count:512, packet count:16, buffer size:8192
Can anyone tell me what it's doing here, what those length delays are between 22:29:50 and 22:31:00 are caused by, and why it only happens the first time I play back a file? If I stop and restart the same video, it plays normally on the second attempt.