gistfile1.txt

##################################
##### normal skip log entry ######
##################################

07:52:34 T:3392   DEBUG: Keyboard: scancode: 0x4d, sym: 0x0113, unicode: 0x0000, modifier: 0x0
07:52:34 T:3392   DEBUG: CInputManager::OnKey: right (0xf083) pressed, action is StepForward
07:52:34 T:3392   DEBUG: ------ Window Init (DialogSeekBar.xml) ------
07:52:34 T:3392   DEBUG: Keyboard: scancode: 0x4d, sym: 0x0113, unicode: 0x0000, modifier: 0x0
07:52:34 T:3392   DEBUG: CInputManager::OnKey: right (0xf083) pressed, action is StepForward
07:52:34 T:3392   DEBUG: Keyboard: scancode: 0x4d, sym: 0x0113, unicode: 0x0000, modifier: 0x0
07:52:34 T:3392   DEBUG: CInputManager::OnKey: right (0xf083) pressed, action is StepForward
07:52:34 T:3872   DEBUG: CPullupCorrection: detected pattern of length 1: 40000.00, frameduration: 40000.000000
07:52:35 T:5476   DEBUG: CDVDPlayer::SetCaching - caching state 3
07:52:35 T:5476   DEBUG: demuxer seek to: 66294
07:52:35 T:3392   DEBUG: CDVDMsgGeneralSynchronize - global timeout
07:52:35 T:3392   DEBUG: CAnnouncementManager - Announcement: OnSeek from xbmc
07:52:35 T:3392   DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnSeek
07:52:35 T:3392   DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - Announce(flag=Player, sender=xbmc, message=OnSeek)
07:52:35 T:5476   ERROR: ffmpeg[1564]: [mpegts] read_timestamp() failed in the middle
07:52:35 T:5476   DEBUG: CDVDDemuxFFmpeg::SeekTime - unknown position after seek
07:52:35 T:5476   DEBUG: demuxer seek to: 66294, success

07:52:35 T:3872   DEBUG: CDVDPlayerVideo - CDVDMsg::GENERAL_SYNCHRONIZE
07:52:35 T:2620   DEBUG: CDVDPlayerAudio - CDVDMsg::GENERAL_SYNCHRONIZE
07:52:35 T:5476   DEBUG: CDVDPlayer - CDVDMsg::GENERAL_SYNCHRONIZE
07:52:35 T:3872   DEBUG: CDVDPlayerVideo - CDVDMsg::GENERAL_RESYNC(63558988.777775, 1)
07:52:35 T:3872    INFO: CDVDPlayerVideo - Stillframe left, switching to normal playback
07:52:35 T:2620   DEBUG: CDVDPlayerAudio - CDVDMsg::GENERAL_RESYNC(63359999.888882, 1)
07:52:35 T:5476   DEBUG: CDVDPlayer::HandleMessages - player started 1
07:52:35 T:5476   DEBUG: CDVDPlayer::HandleMessages - player started 2
07:52:35 T:5476   DEBUG: CDVDPlayer::SetCaching - caching state 4
07:52:35 T:2620 WARNING: CDVDMessageQueue(audio)::Get - asked for new data packet, with nothing available
07:52:35 T:5476   DEBUG: CDVDPlayer::SetCaching - caching state 0
07:52:35 T:2620  NOTICE: CDVDPlayerAudio::OutputPacket skipping a packets of duration 24
07:52:35 T:2620   DEBUG: CDVDPlayerAudio::HandleSyncError - average error -3402.560232 below threshold of 50000.000000

###################################### NOTE ##########################################
The above log sequence is repeated many times (with different timecodes obviously) as 
I skip forward through the file. 
However, at some random point, a requested skip back of 1 minute (3 back key presses) causes 
a "go back to the beginning" problem i.e. it starts playing at timecode 0.
The last "good" skip and then the problem skip log entries are below
#######################################################################################

#########################
#### Last Good Skip #####
#########################

07:53:48 T:3392   DEBUG: Keyboard: scancode: 0x4b, sym: 0x0114, unicode: 0x0000, modifier: 0x0
07:53:48 T:3392   DEBUG: CInputManager::OnKey: left (0xf082) pressed, action is StepBack
07:53:49 T:3392   DEBUG: Keyboard: scancode: 0x4b, sym: 0x0114, unicode: 0x0000, modifier: 0x0
07:53:49 T:3392   DEBUG: CInputManager::OnKey: left (0xf082) pressed, action is StepBack
07:53:49 T:3392   DEBUG: Keyboard: scancode: 0x4b, sym: 0x0114, unicode: 0x0000, modifier: 0x0
07:53:49 T:3392   DEBUG: CInputManager::OnKey: left (0xf082) pressed, action is StepBack
07:53:50 T:5476   DEBUG: CDVDPlayer::SetCaching - caching state 3
07:53:50 T:5476   DEBUG: demuxer seek to: 2972219
07:53:50 T:5476   DEBUG: CDVDDemuxFFmpeg::SeekTime - unknown position after seek
07:53:50 T:5476   DEBUG: demuxer seek to: 2972219, success
07:53:50 T:3392   DEBUG: CDVDMsgGeneralSynchronize - global timeout
07:53:50 T:3392   DEBUG: CAnnouncementManager - Announcement: OnSeek from xbmc
07:53:50 T:3392   DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnSeek
07:53:50 T:3392   DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - Announce(flag=Player, sender=xbmc, message=OnSeek)
07:53:50 T:3872   DEBUG: CDVDPlayerVideo - CDVDMsg::GENERAL_SYNCHRONIZE
07:53:50 T:2620   DEBUG: CDVDPlayerAudio - CDVDMsg::GENERAL_SYNCHRONIZE
07:53:50 T:5476   DEBUG: CDVDPlayer - CDVDMsg::GENERAL_SYNCHRONIZE
07:53:50 T:3872   DEBUG: CDVDPlayerVideo - CDVDMsg::GENERAL_RESYNC(-8026022.333340, 1)
07:53:50 T:3872    INFO: CDVDPlayerVideo - Stillframe left, switching to normal playback
07:53:50 T:2620   DEBUG: CDVDPlayerAudio - CDVDMsg::GENERAL_RESYNC(-8305011.111116, 1)
07:53:50 T:5476   DEBUG: CDVDPlayer::HandleMessages - player started 1
07:53:50 T:5476   DEBUG: CDVDPlayer::HandleMessages - player started 2
07:53:50 T:5476   DEBUG: CDVDPlayer::SetCaching - caching state 4
07:53:50 T:5476   DEBUG: CDVDPlayer::SetCaching - caching state 0
07:53:50 T:2620  NOTICE: CDVDPlayerAudio::OutputPacket skipping a packets of duration 24
07:53:50 T:2620   DEBUG: CDVDPlayerAudio::HandleSyncError - average error -14060.469006 below threshold of 50000.000000
07:53:50 T:2620   DEBUG: CDVDClock::Discontinuity - CDVDPlayerAudio::HandleSyncError2 - was:-8335006.307939, should be:-8349066.456209, error:-14060.148270

###############################################################################
##### the "go back to beginning" skip - note the demux "seek to" time ... #####
###############################################################################

07:53:50 T:3392   DEBUG: Keyboard: scancode: 0x4b, sym: 0x0114, unicode: 0x0000, modifier: 0x0
07:53:50 T:3392   DEBUG: CInputManager::OnKey: left (0xf082) pressed, action is StepBack
07:53:50 T:3392   DEBUG: Keyboard: scancode: 0x4b, sym: 0x0114, unicode: 0x0000, modifier: 0x0
07:53:50 T:3392   DEBUG: CInputManager::OnKey: left (0xf082) pressed, action is StepBack
07:53:51 T:2620   DEBUG: CDVDClock::Discontinuity - CDVDPlayerAudio::HandleSyncError2 - was:-7344006.850688, should be:-7354593.252904, error:-10586.402216
07:53:51 T:3392   DEBUG: Keyboard: scancode: 0x4b, sym: 0x0114, unicode: 0x0000, modifier: 0x0
07:53:51 T:3392   DEBUG: CInputManager::OnKey: left (0xf082) pressed, action is StepBack
07:53:52 T:5476   DEBUG: CDVDPlayer::SetCaching - caching state 3
07:53:52 T:5476   DEBUG: demuxer seek to: -51495
07:53:52 T:5476   DEBUG: CDVDDemuxFFmpeg::SeekTime - seek ended up on time 0
07:53:52 T:5476   DEBUG: demuxer seek to: -51495, success
07:53:52 T:3392   DEBUG: CDVDMsgGeneralSynchronize - global timeout
07:53:52 T:3392   DEBUG: CAnnouncementManager - Announcement: OnSeek from xbmc
07:53:52 T:3392   DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnSeek
07:53:52 T:3392   DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - Announce(flag=Player, sender=xbmc, message=OnSeek)
07:53:52 T:3872   DEBUG: CDVDPlayerVideo - CDVDMsg::GENERAL_SYNCHRONIZE
07:53:52 T:2620   DEBUG: CDVDPlayerAudio - CDVDMsg::GENERAL_SYNCHRONIZE
07:53:52 T:5476   DEBUG: CDVDPlayer - CDVDMsg::GENERAL_SYNCHRONIZE
07:53:52 T:3872   DEBUG: CDVDPlayerVideo - CDVDMsg::GENERAL_RESYNC(-7946022.333338, 1)
07:53:52 T:3872    INFO: CDVDPlayerVideo - Stillframe left, switching to normal playback
07:53:52 T:3872 WARNING: CDVDMessageQueue(video)::Get - asked for new data packet, with nothing available
07:53:52 T:2620   DEBUG: CDVDPlayerAudio - CDVDMsg::GENERAL_RESYNC(-8305011.111116, 1)
07:53:52 T:5476   DEBUG: CDVDPlayer::HandleMessages - player started 1
07:53:52 T:5476   DEBUG: CDVDPlayer::HandleMessages - player started 2
07:53:52 T:5476   DEBUG: CDVDPlayer::SetCaching - caching state 4
07:53:52 T:5476   DEBUG: CDVDPlayer::SetCaching - caching state 0
07:53:52 T:2620  NOTICE: CDVDPlayerAudio::OutputPacket skipping a packets of duration 24
07:53:52 T:2620   DEBUG: CDVDPlayerAudio::HandleSyncError - average error -10547.821669 below threshold of 50000.000000
07:53:52 T:2620   DEBUG: CDVDClock::Discontinuity - CDVDPlayerAudio::HandleSyncError2 - was:-8351113.966669, should be:-8361661.146866, error:-10547.180198