[mythtv-users] automatic transcode messes up time index

Wed Apr 13 11:09:44 UTC 2005

Norman Lyon wrote:
> Has anyone seen a problem where the auto-transcode (or possibly the manual
> transcode) messes up the time index for the recorded programs?  I can playback
> just fine, but if I try to move forward 30 seconds, I may see a 40+ minute
> forward.....  skipping to the end of the commercial is sometimes worse.
> 
> I'm recording rtjpeg (from a BT878 card), detecting commercials, and
> auto-transcoding to mpeg4 right now.  If I try to watch a program after it's
> been comercial flagged but before the transcode's done, no problem.  It's only
> a problem after the transcode has completed.

Yes, I have this problem, too; although I only do manual transcodes. In 
addition, attempting to start playback from a 'bookmark' results in a 
black screen.

In

  From: Lloyd Brown <somewhere_or_other at byu.edu>
  Subject: [mythtv-users] problems seeking
  Date: Fri, 01 Apr 2005 08:09:09 -0700

Lloyd describes a similar problem.

I've also noticed that the frontend has trouble (re-)generating 
thumbnails for recordings that have been transcoded to mpeg4.

Below are my attempts at debugging this problem. I'm running mythtv 
0.17, installed from atrpms-stable (most recent version available 
there), on Fedora Core 3 (kernel 2.6.11-1.14_FC3smp; same results with 
previous FC3 kernels).

The first log is from mythfrontend -verbose playback. I ran the 
frontend, and played one of my transcoded-to-mpeg4 recordings; after a 
few moments I pressed 'right-arrow' to skip forward. The "Waited too 
long for decoder to pause" continue (although I've sometimes seen timeouts).

2005-04-13 12:46:12.144 mythfrontend version: 0.17.20050130-1 www.mythtv.org
2005-04-13 12:46:12.146 Enabled verbose msgs : important general playback
2005-04-13 12:46:13.153 Switching to square mode (Titivillus)
mythtv: could not connect to socket
2005-04-13 12:46:14.103 Joystick disabled.
mythtv: Connection refused
lirc_init failed for mythtv, see preceding messages
2005-04-13 12:46:14.206 Registering Internal as a media playback plugin.
2005-04-13 12:46:14.275 Registering MythDVD DVD Media Handler as a media 
handler
2005-04-13 12:46:14.275 Registering MythDVD VCD Media Handler as a media 
handler
2005-04-13 12:46:15.701 Registering MythMusic Media Handler as a media 
handler
SIP listening on IP Address 192.168.108.51:5060 NAT address 192.168.108.51
SIP: Cannot register; proxy, username or password not set
2005-04-13 12:46:23.555 All Programs
2005-04-13 12:46:25.756 Connecting to backend server: 
192.168.108.51:6543 (try 1 of 5)
2005-04-13 12:46:25.764 Using protocol version 14
2005-04-13 12:46:38.600 detectInterlace(Detect Scan, Detect Scan, 25, 
480) ->Interlaced Scan
2005-04-13 12:46:38.602 Interlaced: Interlaced Scan  video_height: 480 
fps: 25
2005-04-13 12:46:38.741 Estimated bitrate = 0
2005-04-13 12:46:38.747 Opening audio device '/dev/dsp'.
2005-04-13 12:46:38.748 Opening OSS audio device '/dev/dsp'.
2005-04-13 12:46:38.763 Over/underscan. V: 0.05, H: 0.05, XOff: 0, YOff: 0
2005-04-13 12:46:38.769 Using XV port 171
2005-04-13 12:46:38.776 Image size. dispxoff 0, dispyoff: 0, dispwoff: 
1600, disphoff: 1200
2005-04-13 12:46:38.776 Image size. imgx 24, imgy: 24, imgw: 432, imgh: 432
2005-04-13 12:46:39.408 Realtime priority would require SUID as root.
2005-04-13 12:46:39.410 Changing from None to WatchingPreRecorded
2005-04-13 12:46:39.416 Using deinterlace method kerneldeint
2005-04-13 12:46:39.416 nVidiaVideoSync: Could not open device 
/dev/nvidia0, Permission denied
2005-04-13 12:46:39.416 DRMVideoSync: Could not open device 
/dev/dri/card0, No such file or directory
2005-04-13 12:46:39.416 RTCVideoSync: Could not set RTC frequency, 
Permission denied.
2005-04-13 12:46:39.418 Using video as timebase
2005-04-13 12:46:39.418 Video timing method: USleep with busy wait
2005-04-13 12:46:39.418 Refresh rate: 13333, frame interval: 40000
2005-04-13 12:46:39.418 waiting for prebuffer...
2005-04-13 12:46:39.737 A/V diverged by 3.29745 frames, extending frame 
to keep audio in sync
2005-04-13 12:46:39.817 A/V diverged by 3.74183 frames, extending frame 
to keep audio in sync
2005-04-13 12:46:39.897 A/V diverged by 3.8376 frames, extending frame 
to keep audio in sync
2005-04-13 12:46:39.977 A/V diverged by 3.48445 frames, extending frame 
to keep audio in sync
2005-04-13 12:46:43.338 DoFastForward: Not enough info in positionMap, 
we need frame 840 but highest we have is 0
2005-04-13 12:46:43.342 SyncPositionMap prerecorded, from DB: 1 entries
2005-04-13 12:46:43.343 DoFastForward: Still Not enough info in 
positionMap, we need frame 840 but highest we have is 0.  Will seek 
frame-by-frame
2005-04-13 12:46:53.394 Waited too long for decoder to pause
2005-04-13 12:46:54.397 Waited too long for decoder to pause
2005-04-13 12:46:55.400 Waited too long for decoder to pause
2005-04-13 12:46:56.402 Waited too long for decoder to pause
2005-04-13 12:46:57.403 Waited too long for decoder to pause
2005-04-13 12:46:58.405 Waited too long for decoder to pause
2005-04-13 12:46:59.407 Waited too long for decoder to pause
2005-04-13 12:47:00.409 Waited too long for decoder to pause
---end

I ran mythcommflag on this recording again, hoping that would fix the 
problem. However, the second log (with duplicate info from the top 
removed) is also from mythfrontend -verbose playback, after that 
myhtcommflag run:

2005-04-13 12:58:14.798 Changing from None to WatchingPreRecorded
2005-04-13 12:58:14.801 Realtime priority would require SUID as root.
2005-04-13 12:58:14.807 Using deinterlace method kerneldeint
2005-04-13 12:58:14.816 nVidiaVideoSync: Could not open device 
/dev/nvidia0, Permission denied
2005-04-13 12:58:14.818 DRMVideoSync: Could not open device 
/dev/dri/card0, No such file or directory
2005-04-13 12:58:14.821 RTCVideoSync: Could not set RTC frequency, 
Permission denied.
2005-04-13 12:58:14.824 Using video as timebase
2005-04-13 12:58:14.826 Video timing method: USleep with busy wait
2005-04-13 12:58:14.829 Refresh rate: 13333, frame interval: 40000
2005-04-13 12:58:14.831 waiting for prebuffer...
2005-04-13 12:58:15.349 A/V diverged by 3.0094 frames, extending frame 
to keep audio in sync
2005-04-13 12:58:15.429 A/V diverged by 3.09455 frames, extending frame 
to keep audio in sync
'video_output' mean = '40768.40', std. dev. = '5642.13', fps = '24.53'
2005-04-13 12:58:19.590 DoFastForward: Not enough info in positionMap, 
we need frame 866 but highest we have is 0
2005-04-13 12:58:19.594 SyncPositionMap prerecorded, from DB: 1 entries
2005-04-13 12:58:19.594 DoFastForward: Still Not enough info in 
positionMap, we need frame 866 but highest we have is 0.  Will seek 
frame-by-frame
2005-04-13 12:58:40.971 >> Player timeout
2005-04-13 12:58:41.053 Changing from WatchingPreRecorded to None
2005-04-13 12:58:41.121 Changing from None to None

Here, the frontend fell back to the recordings-index; and no 'Waited too 
long...' messages were logged.