[mythtv-users] Playback issue with firewire captured video

Jeff Wormsley daworm at comcast.net
Tue Mar 8 03:14:37 UTC 2005 

I have a problem with playing back some captures made via firewire.  It 
doesn't seem to matter if they are SDTV or HDTV sources, as I have had 
some of each.  There are two symptoms, one bad, one worse.

Bad: Video doesn't play, after about 5 seconds, control returns to MythTV.

Worse: Crash.

In both cases, the preview panel shows video in the little window for 
the show in question.

Following is a frontend log of the crash situation.

Here's the bits I am most worried about:

 > 2005-03-07 21:51:08.034 waiting for prebuffer...
 > 2005-03-07 21:51:08.034 prebuffer wait timed out..

Lots of these.  I do get some audio stuttering when playback works.  CPU 
is slightly underpowered, so I expect this until the new CPU gets here.

 > 2005-03-07 21:51:09.366 Broadcasting free space avail
 > 2005-03-07 21:51:09.387 Broadcasting free space avail

Lots of these as well.  No idea what they are for.

 > 2005-03-07 21:51:09.661 nVidiaVideoSync: Could not open device 
/dev/nvidia0, Permission denied

What should the permissions be?  The device exists.

[root at mythtv mythtv]# ll /dev/nv*
crw-rw----  1 root root 195, 255 Mar  2 23:27 /dev/nvidia
crw-rw----  1 root root 195,   0 Nov 27 08:28 /dev/nvidia0
crw-rw-rw-  1 root root 195,   1 Nov 27 08:28 /dev/nvidia1
crw-rw-rw-  1 root root 195,   2 Nov 27 08:28 /dev/nvidia2
crw-rw-rw-  1 root root 195,   3 Nov 27 08:28 /dev/nvidia3
crw-rw----  1 root root 195, 255 Nov 27 08:28 /dev/nvidiactl

 > 2005-03-07 21:51:09.661 DRMVideoSync: Could not open device 
/dev/dri/card0, No such file or directory

Truly doesn't exist.  Should it?

[root at mythtv mythtv]# ll /dev/d*
crw-------  1 root   root 36, 14 Mar  2 23:24 /dev/dnrtmsg
crw-------  1 mythtv root 14,  3 Mar  2 23:25 /dev/dsp
lrwxrwxrwx  1 root   root      3 Mar  2 23:24 /dev/dvd -> hdc

 > 2005-03-07 21:51:09.662 OpenGLVideoSync: GLX extensions: 
GLX_ARB_multisample GLX_EXT_visual_info GLX_EXT_visual_rating 
GLX_EXT_import_context GLX_SGIX_fbconfig GLX_ARB_get_proc_address
 > 2005-03-07 21:51:09.662 OpenGLVideoSync: GLX Video Sync extension not 
present.

I have glx in my xorg.conf file:

Section "Module"
        Load  "dbe"
        Load  "extmod"
        Load  "fbdevhw"
        Load  "glx"
        Load  "record"
        Load  "freetype"
        Load  "type1"
#       Load  "dri"
        Load  "v4l"
EndSection

 > 2005-03-07 21:51:09.663 RTCVideoSync: Could not set RTC frequency, 
Permission denied.

What permission?  Where is that set?

 > 2005-03-07 21:51:09.663 Using audio as timebase
 > 2005-03-07 21:51:09.663 Video timing method: USleep with busy wait

No matter what I've tried, every install I've done always has "USleep 
with busy wait".  Isn't this the worst way to do timing?

 > terminate called after throwing an instance of 'std::bad_alloc'
 >   what():  St9bad_alloc
 > Aborted

This is the crash.  Any ideas?  Is this related to the above issues?

Thanks in advance!
Jeff.

- - - - - - - - - - -

Full log from the crash:

2005-03-07 21:50:58.443 New DB connection, total: 1
2005-03-07 21:50:58.452 mythfrontend version: 0.17.20050223-1 www.mythtv.org
2005-03-07 21:50:58.452 Enabled verbose msgs : important general 
playback audio
2005-03-07 21:50:58.756 Switching to wide mode (Minimalist-wide)
2005-03-07 21:50:59.274 New DB connection, total: 2
2005-03-07 21:50:59.276 Joystick disabled.
2005-03-07 21:50:59.323 Registering Internal as a media playback plugin.
2005-03-07 21:50:59.340 Registering MythDVD DVD Media Handler as a media 
handler
2005-03-07 21:50:59.342 Registering MythDVD VCD Media Handler as a media 
handler
2005-03-07 21:50:59.469 Registering MythMusic Media Handler as a media 
handler
2005-03-07 21:50:59.772 Starting media monitor.
2005-03-07 21:51:03.170 All Programs
2005-03-07 21:51:05.443 Connecting to backend server: 
192.168.254.15:6543 (try 1 of 5)
2005-03-07 21:51:05.448 Using protocol version 14
adding pes stream at pid 0x800 with type 2
adding pes stream at pid 0x801 with type 129
2005-03-07 21:51:08.011 AVFD
2005-03-07 21:51:08.011 AVFD: Opening Stream #0: codec id 2
2005-03-07 21:51:08.011 AVFD: Looking for decoder for 2
2005-03-07 21:51:08.011 AVFD
2005-03-07 21:51:08.011 AVFD: Opening Stream #1: codec id 86020
2005-03-07 21:51:08.011 AVFD: Looking for decoder for 86020
2005-03-07 21:51:08.012 Stream #1 (audio track #0) is an audio stream 
with 2 channels.
2005-03-07 21:51:08.012 Auto-selecting AC3 audio track (stream #1).
2005-03-07 21:51:08.012 Initializing audio parms from audio track #0.
2005-03-07 21:51:08.014 Estimated bitrate = 384
2005-03-07 21:51:08.025 Image size. dispxoff 0, dispyoff: 0, dispwoff: 
0, disphoff: 0
2005-03-07 21:51:08.026 Image size. imgx 0, imgy: 0, imgw: 0, imgh: 0
2005-03-07 21:51:08.034 waiting for prebuffer...
2005-03-07 21:51:08.034 prebuffer wait timed out..
2005-03-07 21:51:08.034 waiting for prebuffer...
2005-03-07 21:51:08.034 prebuffer wait timed out..
2005-03-07 21:51:08.034 waiting for prebuffer...
2005-03-07 21:51:08.034 prebuffer wait timed out..
2005-03-07 21:51:08.034 waiting for prebuffer...
2005-03-07 21:51:08.034 prebuffer wait timed out..
2005-03-07 21:51:08.034 waiting for prebuffer...
2005-03-07 21:51:08.034 prebuffer wait timed out..
2005-03-07 21:51:08.034 waiting for prebuffer...
2005-03-07 21:51:08.034 prebuffer wait timed out..
2005-03-07 21:51:08.034 waiting for prebuffer...
2005-03-07 21:51:08.034 prebuffer wait timed out..
2005-03-07 21:51:08.034 waiting for prebuffer...
2005-03-07 21:51:08.034 prebuffer wait timed out..
2005-03-07 21:51:08.034 waiting for prebuffer...
2005-03-07 21:51:08.035 prebuffer wait timed out..
2005-03-07 21:51:08.035 waiting for prebuffer...
2005-03-07 21:51:08.035 prebuffer wait timed out..
2005-03-07 21:51:08.035 waiting for prebuffer...
2005-03-07 21:51:08.035 prebuffer wait timed out..
2005-03-07 21:51:08.035 waiting for prebuffer...
2005-03-07 21:51:08.035 prebuffer wait timed out..
2005-03-07 21:51:08.035 waiting for prebuffer...
2005-03-07 21:51:08.035 prebuffer wait timed out..
2005-03-07 21:51:08.035 waiting for prebuffer...
2005-03-07 21:51:08.035 prebuffer wait timed out..
2005-03-07 21:51:08.035 waiting for prebuffer...
2005-03-07 21:51:08.035 prebuffer wait timed out..
2005-03-07 21:51:08.035 waiting for prebuffer...
2005-03-07 21:51:08.035 prebuffer wait timed out..
2005-03-07 21:51:08.035 waiting for prebuffer...
2005-03-07 21:51:08.035 prebuffer wait timed out..
2005-03-07 21:51:08.035 waiting for prebuffer...
2005-03-07 21:51:08.035 prebuffer wait timed out..
2005-03-07 21:51:08.036 waiting for prebuffer...
2005-03-07 21:51:08.036 prebuffer wait timed out..
2005-03-07 21:51:08.036 waiting for prebuffer...
2005-03-07 21:51:08.036 prebuffer wait timed out..
2005-03-07 21:51:08.036 waiting for prebuffer...
2005-03-07 21:51:08.036 prebuffer wait timed out..
2005-03-07 21:51:08.036 waiting for prebuffer...
2005-03-07 21:51:08.036 prebuffer wait timed out..
2005-03-07 21:51:08.036 waiting for prebuffer...
2005-03-07 21:51:08.036 prebuffer wait timed out..
2005-03-07 21:51:08.036 waiting for prebuffer...
2005-03-07 21:51:08.036 prebuffer wait timed out..
2005-03-07 21:51:08.036 waiting for prebuffer...
2005-03-07 21:51:08.036 prebuffer wait timed out..
2005-03-07 21:51:08.036 waiting for prebuffer...
2005-03-07 21:51:08.036 prebuffer wait timed out..
2005-03-07 21:51:08.036 waiting for prebuffer...
2005-03-07 21:51:08.036 prebuffer wait timed out..
2005-03-07 21:51:08.036 waiting for prebuffer...
2005-03-07 21:51:08.036 prebuffer wait timed out..
2005-03-07 21:51:08.037 waiting for prebuffer...
2005-03-07 21:51:08.037 prebuffer wait timed out..
2005-03-07 21:51:08.037 waiting for prebuffer...
2005-03-07 21:51:08.037 prebuffer wait timed out..
2005-03-07 21:51:08.037 waiting for prebuffer...
2005-03-07 21:51:08.037 prebuffer wait timed out..
2005-03-07 21:51:08.037 waiting for prebuffer...
2005-03-07 21:51:08.037 prebuffer wait timed out..
2005-03-07 21:51:08.037 waiting for prebuffer...
2005-03-07 21:51:08.037 prebuffer wait timed out..
2005-03-07 21:51:08.037 waiting for prebuffer...
2005-03-07 21:51:08.037 prebuffer wait timed out..
2005-03-07 21:51:08.037 waiting for prebuffer...
2005-03-07 21:51:08.037 prebuffer wait timed out..
2005-03-07 21:51:08.037 waiting for prebuffer...
2005-03-07 21:51:08.037 prebuffer wait timed out..
2005-03-07 21:51:08.037 waiting for prebuffer...
2005-03-07 21:51:08.037 prebuffer wait timed out..
2005-03-07 21:51:08.037 waiting for prebuffer...
2005-03-07 21:51:08.037 prebuffer wait timed out..
2005-03-07 21:51:08.038 waiting for prebuffer...
2005-03-07 21:51:08.038 prebuffer wait timed out..
2005-03-07 21:51:08.038 waiting for prebuffer...
2005-03-07 21:51:08.038 prebuffer wait timed out..
2005-03-07 21:51:08.038 waiting for prebuffer...
2005-03-07 21:51:08.038 prebuffer wait timed out..
2005-03-07 21:51:08.038 waiting for prebuffer...
2005-03-07 21:51:08.038 prebuffer wait timed out..
2005-03-07 21:51:08.038 waiting for prebuffer...
2005-03-07 21:51:08.038 prebuffer wait timed out..
2005-03-07 21:51:08.038 waiting for prebuffer...
2005-03-07 21:51:08.038 prebuffer wait timed out..
2005-03-07 21:51:08.038 waiting for prebuffer...
2005-03-07 21:51:08.038 prebuffer wait timed out..
2005-03-07 21:51:08.038 waiting for prebuffer...
2005-03-07 21:51:08.038 prebuffer wait timed out..
2005-03-07 21:51:08.038 waiting for prebuffer...
2005-03-07 21:51:08.038 prebuffer wait timed out..
2005-03-07 21:51:08.038 waiting for prebuffer...
2005-03-07 21:51:08.039 prebuffer wait timed out..
2005-03-07 21:51:08.039 waiting for prebuffer...
2005-03-07 21:51:08.039 prebuffer wait timed out..
2005-03-07 21:51:08.039 waiting for prebuffer...
2005-03-07 21:51:08.039 prebuffer wait timed out..
2005-03-07 21:51:08.039 waiting for prebuffer...
2005-03-07 21:51:08.039 prebuffer wait timed out..
2005-03-07 21:51:08.039 waiting for prebuffer...
2005-03-07 21:51:08.039 prebuffer wait timed out..
2005-03-07 21:51:08.039 waiting for prebuffer...
2005-03-07 21:51:08.039 prebuffer wait timed out..
2005-03-07 21:51:08.039 waiting for prebuffer...
2005-03-07 21:51:08.039 prebuffer wait timed out..
2005-03-07 21:51:08.039 waiting for prebuffer...
2005-03-07 21:51:08.051 AvFormatDecoder: Video has changed from 0x0 to 
1920x1088.
2005-03-07 21:51:08.039 prebuffer wait timed out..
2005-03-07 21:51:08.190 waiting for prebuffer...
2005-03-07 21:51:08.312 Image size. dispxoff 0, dispyoff: 0, dispwoff: 
0, disphoff: 0
2005-03-07 21:51:08.312 Image size. imgx 0, imgy: 0, imgw: 1920, imgh: 1080
2005-03-07 21:51:08.312 detectInterlace(Detect Scan, Detect Scan, 29.97, 
1088) ->Interlaced Scan
2005-03-07 21:51:08.313 Interlaced: Interlaced Scan  video_height: 1088  
fps: 29.97
2005-03-07 21:51:08.330 prebuffer wait timed out..
2005-03-07 21:51:08.330 waiting for prebuffer...
2005-03-07 21:51:08.465 prebuffer wait timed out..
2005-03-07 21:51:08.465 waiting for prebuffer...
2005-03-07 21:51:08.600 prebuffer wait timed out..
2005-03-07 21:51:08.600 waiting for prebuffer...
2005-03-07 21:51:08.735 prebuffer wait timed out..
2005-03-07 21:51:08.735 waiting for prebuffer...
adding pes stream at pid 0x800 with type 2
adding pes stream at pid 0x801 with type 129
2005-03-07 21:51:09.249 AVFD
2005-03-07 21:51:09.249 AVFD: Opening Stream #0: codec id 2
2005-03-07 21:51:09.250 Using libmpeg2 for video decoding
2005-03-07 21:51:09.250 AVFD: Looking for decoder for 2
2005-03-07 21:51:09.250 AVFD
2005-03-07 21:51:09.251 AVFD: Opening Stream #1: codec id 86020
2005-03-07 21:51:09.251 AVFD: Looking for decoder for 86020
2005-03-07 21:51:09.251 Stream #1 (audio track #0) is an audio stream 
with 2 channels.
2005-03-07 21:51:09.251 Auto-selecting AC3 audio track (stream #1).
2005-03-07 21:51:09.251 Initializing audio parms from audio track #0.
2005-03-07 21:51:09.253 Estimated bitrate = 384
2005-03-07 21:51:09.325 Position map filled from DB to: 107231
2005-03-07 21:51:09.326 SyncPositionMap prerecorded, from DB: 3474 entries
2005-03-07 21:51:09.326 Position map found
2005-03-07 21:51:09.330 Killing AudioOutputDSP
2005-03-07 21:51:09.331 Opening audio device 'spdif'.
2005-03-07 21:51:09.341 in SetParameters(format=2, channels=2, 
rate=48000, buffer_time=500000, period_time=125000)
2005-03-07 21:51:09.341 get_buffer_size returned 16384
2005-03-07 21:51:09.341 set_period_time_near returned 125000
2005-03-07 21:51:09.341 get_period_size returned 6000
2005-03-07 21:51:09.343 Opening mixer default
2005-03-07 21:51:09.346 Setting Master volume to 100
2005-03-07 21:51:09.346 Volume range is 0 to 31, mult=3.22581
2005-03-07 21:51:09.346 channel 0 vol set to 31
2005-03-07 21:51:09.346 Setting Master volume to 100
2005-03-07 21:51:09.346 Volume range is 0 to 31, mult=3.22581
2005-03-07 21:51:09.346 channel 1 vol set to 31
2005-03-07 21:51:09.347 Setting PCM volume to 100
2005-03-07 21:51:09.347 Volume range is 0 to 31, mult=3.22581
2005-03-07 21:51:09.347 channel 0 vol set to 31
2005-03-07 21:51:09.347 Setting PCM volume to 100
2005-03-07 21:51:09.347 Volume range is 0 to 31, mult=3.22581
2005-03-07 21:51:09.347 channel 1 vol set to 31
2005-03-07 21:51:09.347 Volume range is 0 to 31, mult=3.22581
2005-03-07 21:51:09.347 Audio fragment size: 4096
2005-03-07 21:51:09.348 Audio Stretch Factor: 1
2005-03-07 21:51:09.348 Ending reconfigure
2005-03-07 21:51:09.355 Over/underscan. V: 0, H: 0, XOff: 0, YOff: 0
2005-03-07 21:51:09.362 Using XV port 153
2005-03-07 21:51:09.366 Image size. dispxoff 220, dispyoff: 0, dispwoff: 
1320, disphoff: 960
2005-03-07 21:51:09.366 Image size. imgx 0, imgy: 0, imgw: 0, imgh: 0
2005-03-07 21:51:09.366 kickoffOutputAudioLoop: pid = 28766
2005-03-07 21:51:09.366 OutputAudioLoop: Play Event
2005-03-07 21:51:09.366 Broadcasting free space avail
2005-03-07 21:51:09.387 Broadcasting free space avail
2005-03-07 21:51:09.407 Broadcasting free space avail
2005-03-07 21:51:09.411 Broadcasting free space avail
2005-03-07 21:51:09.415 Broadcasting free space avail
2005-03-07 21:51:09.419 Broadcasting free space avail
2005-03-07 21:51:09.423 Broadcasting free space avail
2005-03-07 21:51:09.427 Broadcasting free space avail
2005-03-07 21:51:09.431 Broadcasting free space avail
2005-03-07 21:51:09.435 Broadcasting free space avail
2005-03-07 21:51:09.439 Broadcasting free space avail
2005-03-07 21:51:09.443 Broadcasting free space avail
2005-03-07 21:51:09.447 Broadcasting free space avail
2005-03-07 21:51:09.451 Broadcasting free space avail
2005-03-07 21:51:09.455 Broadcasting free space avail
2005-03-07 21:51:09.459 Broadcasting free space avail
2005-03-07 21:51:09.463 Broadcasting free space avail
2005-03-07 21:51:09.467 Broadcasting free space avail
2005-03-07 21:51:09.471 Broadcasting free space avail
2005-03-07 21:51:09.475 Broadcasting free space avail
2005-03-07 21:51:09.479 Broadcasting free space avail
2005-03-07 21:51:09.483 Broadcasting free space avail
2005-03-07 21:51:09.487 Broadcasting free space avail
2005-03-07 21:51:09.491 Broadcasting free space avail
2005-03-07 21:51:09.495 Broadcasting free space avail
2005-03-07 21:51:09.499 Broadcasting free space avail
2005-03-07 21:51:09.503 Broadcasting free space avail
2005-03-07 21:51:09.507 Broadcasting free space avail
2005-03-07 21:51:09.511 Broadcasting free space avail
2005-03-07 21:51:09.515 Broadcasting free space avail
2005-03-07 21:51:09.519 Broadcasting free space avail
2005-03-07 21:51:09.523 Broadcasting free space avail
2005-03-07 21:51:09.527 Broadcasting free space avail
2005-03-07 21:51:09.531 Broadcasting free space avail
2005-03-07 21:51:09.535 Broadcasting free space avail
2005-03-07 21:51:09.539 Broadcasting free space avail
2005-03-07 21:51:09.543 Broadcasting free space avail
2005-03-07 21:51:09.547 Broadcasting free space avail
2005-03-07 21:51:09.551 Broadcasting free space avail
2005-03-07 21:51:09.555 Broadcasting free space avail
2005-03-07 21:51:09.559 Broadcasting free space avail
2005-03-07 21:51:09.563 Broadcasting free space avail
2005-03-07 21:51:09.567 Broadcasting free space avail
2005-03-07 21:51:09.571 Broadcasting free space avail
2005-03-07 21:51:09.575 Broadcasting free space avail
2005-03-07 21:51:09.579 Broadcasting free space avail
2005-03-07 21:51:09.583 Broadcasting free space avail
2005-03-07 21:51:09.587 Broadcasting free space avail
Text area set as scrolling, but no movement
2005-03-07 21:51:09.591 Broadcasting free space avail
2005-03-07 21:51:09.595 Broadcasting free space avail
2005-03-07 21:51:09.599 Broadcasting free space avail
2005-03-07 21:51:09.603 Broadcasting free space avail
2005-03-07 21:51:09.603 Changing from None to WatchingPreRecorded
2005-03-07 21:51:09.604 The realtime priority setting is not enabled.
2005-03-07 21:51:09.610 OutputAudioLoop: audio paused
2005-03-07 21:51:09.610 16384 bytes free on soundcard
2005-03-07 21:51:09.648 AvFormatDecoder: Video has changed from 0x0 to 
1920x1088.
2005-03-07 21:51:09.652 Image size. dispxoff 0, dispyoff: 0, dispwoff: 
1760, disphoff: 960
2005-03-07 21:51:09.652 Image size. imgx 0, imgy: 0, imgw: 1920, imgh: 1080
2005-03-07 21:51:09.661 nVidiaVideoSync: Could not open device 
/dev/nvidia0, Permission denied
2005-03-07 21:51:09.661 DRMVideoSync: Could not open device 
/dev/dri/card0, No such file or directory
2005-03-07 21:51:09.662 OpenGLVideoSync: GLX extensions: 
GLX_ARB_multisample GLX_EXT_visual_info GLX_EXT_visual_rating 
GLX_EXT_import_context GLX_SGIX_fbconfig GLX_ARB_get_proc_address
2005-03-07 21:51:09.662 OpenGLVideoSync: GLX Video Sync extension not 
present.
2005-03-07 21:51:09.663 RTCVideoSync: Could not set RTC frequency, 
Permission denied.
2005-03-07 21:51:09.663 Using audio as timebase
2005-03-07 21:51:09.663 Video timing method: USleep with busy wait
2005-03-07 21:51:09.663 Refresh rate: 33401, frame interval: 33366
2005-03-07 21:51:09.663 waiting for prebuffer...
2005-03-07 21:51:09.798 prebuffer wait timed out..
2005-03-07 21:51:09.798 waiting for prebuffer...
2005-03-07 21:51:09.933 prebuffer wait timed out..
2005-03-07 21:51:09.933 waiting for prebuffer...
2005-03-07 21:51:10.068 prebuffer wait timed out..
2005-03-07 21:51:10.068 waiting for prebuffer...
2005-03-07 21:51:10.203 prebuffer wait timed out..
2005-03-07 21:51:10.203 waiting for prebuffer...
2005-03-07 21:51:10.338 prebuffer wait timed out..
2005-03-07 21:51:10.338 waiting for prebuffer...
2005-03-07 21:51:10.473 prebuffer wait timed out..
2005-03-07 21:51:10.473 waiting for prebuffer...
2005-03-07 21:51:10.608 prebuffer wait timed out..
2005-03-07 21:51:10.608 waiting for prebuffer...
2005-03-07 21:51:10.743 prebuffer wait timed out..
2005-03-07 21:51:10.743 waiting for prebuffer...
2005-03-07 21:51:10.878 prebuffer wait timed out..
2005-03-07 21:51:10.878 waiting for prebuffer...
terminate called after throwing an instance of 'std::bad_alloc'
  what():  St9bad_alloc
Aborted

More information about the mythtv-users mailing list