Re: [Bug #2263] Audio playback problem

Tim <[email protected]> Mon, 23 Aug 2004 17:09:11 +1200
Newsgroups gmane.comp.video.ogle.user
Organization Vic
Message-ID <[email protected]>
On Saturday 21 August 2004 8:47 pm, Bj|rn Englund wrote:
> ogle --debug >& ogle.log

Well, rather than one channel of music and one of clicking, it was 
silent...but not completely...

The startup screen is nice and in stereo. It worked with the previous version 
of Ogle I was using (0.9.2). Everything after clicking "play movie" on the 
menu screen is silent (it used to be half soundtrack and half annoying 
clicks). Interestingly, ogle refused to run in gui mode (even after I 
recompiled ogle_gui to match with the ogle-cvs version), so I ran it with "-u 
cli" (as well as -debug:)

I've attached the log file. Hope it helps.

Cheers,
Tim
ogle.log (text/x-log, 11.3 KB)
Build: Linux 2.6.5-1.358 #1 Sat May 8 09:04:50 EDT 2004 i686 i686 Mon Aug 23 16:54:02 NZST 2004 mmx Xv oss alsa
Runtime: Linux 2.6.5-1.358 #1 Sat May 8 09:04:50 EDT 2004 i686 i686
cpuinfo:
processor : 0, vendor_id : GenuineIntel, cpu family : 6, model : 9, model name : Intel(R) Pentium(R) M processor 1500MHz, stepping : 5, cpu MHz : 598.194, cache size : 1024 KB, fdiv_bug : no, hlt_bug : no, f00f_bug : no, coma_bug : no, fpu : yes, fpu_exception : yes, cpuid level : 2, wp : yes, flags : fpu vme de pse tsc msr mce cx8 mtrr pge mca cmov pat clflush dts acpi mmx fxsr sse sse2 tm pbe tm2 est, bogomips : 1186.20, ,

/dev/dvd -> /dev/cdrom
/dev/cdrom -> /dev/hdc
Valid DVD devices: /dev/hdc
hdparm v5.5
DMA is on for /dev/hdc

/dev/hdc:
 IO_support   =  0 (default 16-bit)
 unmaskirq    =  0 (off)
 using_dma    =  1 (on)
 keepsettings =  0 (off)
 readonly     =  1 (on)
 readahead    = 256 (on)
Note[ogle_ctrl]: ogle 0.9.2-cvs
Debug[ogle_ctrl]: Started /usr/local/lib/ogle/ogle_cli with pid 19429
Debug[ogle_ctrl]: Started /usr/local/lib/ogle/ogle_nav with pid 19430
Debug[ogle_ctrl]: Started /usr/local/lib/ogle/ogle_mpeg_ps with pid 19431
Debug[ogle_ctrl]: Started /usr/local/lib/ogle/ogle_vout with pid 19432
Debug[ogle_vout]: CLK_TCK: 100
Debug[ogle_nav]: Opening DVD at "/dev/dvd"
libdvdread: Using libdvdcss version 1.2.8 for DVD access
libdvdcss debug: opening target `/dev/dvd'
libdvdcss debug: using libc for access
libdvdcss debug: disc is scrambled
libdvdcss debug: requesting AGID
libdvdcss debug: drive authenticated, using variant 0
libdvdcss debug: authentication established
libdvdcss debug: GetASF authenticated, ASF=1
libdvdcss debug: decrypting disc key with player keys
libdvdcss debug: decrypted disc key is 19:de:ac:38:e6
libdvdcss debug: using CSS key cache dir: /home/tim/.dvdcss/KING_KING#2003080717102900/
libdvdread: Using libdvdcss version 1.2.8 for DVD access
libdvdcss debug: opening target `/dev/dvd'
libdvdcss debug: using libc for access
Debug[ogle_nav]: play_PGC: first_play_pgc
Debug[ogle_nav]: play_PGC: state.pgcN (2)
Debug[ogle_nav]: play_PG: state.pgN (1)
Debug[ogle_nav]: play_Cell: state.cellN (1)
Debug[ogle_nav]: sending audio demuxstream state disabled
Debug[ogle_nav]: id: bd, st: 80
Debug[ogle_nav]: sending video demuxstream state enabled
libdvdcss debug: disc is scrambled
libdvdcss debug: requesting AGID
libdvdcss debug: drive authenticated, using variant 0
libdvdcss debug: authentication established
libdvdcss debug: GetASF authenticated, ASF=1
libdvdcss debug: decrypting disc key with player keys
libdvdcss debug: decrypted disc key is 19:de:ac:38:e6
libdvdcss debug: using CSS key cache dir: /home/tim/.dvdcss/KING_KING#2003080717102900/

libdvdread: Attempting to retrieve all CSS keys
libdvdread: This can take a _long_ time, please be patient

libdvdread: Get key for /VIDEO_TS/VIDEO_TS.VOB at 0x0000011f
libdvdcss debug: key found in cache
libdvdread: Elapsed time 0
libdvdread: Get key for /VIDEO_TS/VTS_01_0.VOB at 0x00000fba
libdvdcss debug: key found in cache
libdvdread: Elapsed time 0
libdvdread: Get key for /VIDEO_TS/VTS_01_1.VOB at 0x000010a0
libdvdcss debug: key found in cache
libdvdread: Elapsed time 0
libdvdread: Found 1 VTS's
libdvdread: Elapsed time 0
Debug[ogle_mpeg_vs]: Using MMX accelerated media functions
Debug[ogle_ctrl]: Started /usr/local/lib/ogle/ogle_mpeg_vs with pid 19433
Debug[ogle_nav]: play_Cell_post: state.cellN (1)
Debug[ogle_nav]: last cell in this PGC
Debug[ogle_nav]: play_PGC_post:
Debug[ogle_nav]: play_PGC: state.pgcN (3)
Debug[ogle_nav]: play_PG: state.pgN (1)
Debug[ogle_nav]: play_Cell: state.cellN (1)
Debug[ogle_nav]: sending audio demuxstream state enabled
Debug[ogle_nav]: id: bd, st: 80
Debug[ogle_mpeg_ps]: Backward scr discontinuity
Debug[ogle_mpeg_ps]: system_clock_reference: [0.000000 s]
Debug[ogle_mpeg_ps]: prev system_clock_reference: [6.240347 s]
Debug[ogle_mpeg_ps]: demux: changed to scr_nr: 1
Debug[ogle_ctrl]: Started /usr/local/lib/ogle/ogle_audio with pid 19434
Debug[ogle_mpeg_vs]: ## req cap
Debug[ogle_mpeg_vs]: got cap and stream
Debug[ogle_mpeg_vs]: y_size: 414720, uv_size: 103680, yuv_size: 622080
Debug[ogle_mpeg_vs]: get ouput buffer
Debug[ogle_mpeg_vs]: psize: 622592, nr_bufs: 5
Debug[ogle_mpeg_vs]: pcs: 672, pbs: 3739648
Debug[ogle_ctrl]: Getting 3740320 bytes sysv shm
Debug[ogle_ctrl]: got capability video_out
Debug[ogle_mpeg_vs]: horizontal_size: 720, vertical_size: 576
Debug[ogle_mpeg_vs]: padded_width: 720, padded_height: 576
Debug[ogle_mpeg_vs]: frame rate: 25
No accelerated IMDCT transform found
Debug[ogle_audio]: decode_a52: found sync
Debug[ogle_audio]: Searching <speakers> for:
Debug[ogle_audio]:  'Left' 'Right'
Debug[ogle_audio]: or for:
Debug[ogle_audio]:  'AC3'
Debug[ogle_audio]: No channel configs found
Note[ogle_audio]: Using audio driver 'alsa'
Debug[ogle_audio]: Opening alsa pcm device: default
Debug[ogle_audio]: Min channels: 1
Debug[ogle_audio]: Max channels: 10000
Debug[ogle_audio]: Channels: 2
Debug[ogle_audio]: Wanted buffer time 500000, got 341333
Debug[ogle_audio]: Buffer size 16384 frames
Debug[ogle_audio]: Period time: 10666 us
Debug[ogle_audio]: Period size: 512 frames
Debug[ogle_audio]: Stream parameters are 48000 Hz, S16_LE, 2 channels, sample size: 4
Note[ogle_audio]: wanted 0 channels, got 2
Debug[ogle_audio]: channels 2, encoding 1, resolution 16
Debug[ogle_audio]: byte order 1, rate 48000
Debug[ogle_audio]: fragments -1, size 4096
Debug[ogle_audio]: 2 channels decoded:  Left Right
Note[ogle_audio]: delay resolution better than: 0.004999920, 240 samples, rate: 48000
Debug[ogle_vout]: Found a running xscreensaver, version "4.14" (>= 3.34).
Debug[ogle_vout]: set_sync_point()
Note[ogle_vout]: using default config for ':0.0'
Note[ogle_vout]: Using 'X11' as source for geometry
Note[ogle_vout]: Xinerama extension not found/active
Note[ogle_vout]: Using 'XF86VidMode' as source for resolution
Note[ogle_vout]: Display w: 347, h: 260, hp: 1024, vp: 768
Note[ogle_vout]: Display sar: 266240/266496 = 0.999039
Note[ogle_vout]: Found 8 matching visuals, using visual id: 23
Note[ogle_vout]: Found Xv extension 2.2, checking for suitable adaptors
Note[ogle_vout]: Xv adaptor "ATI Radeon Video Overlay" port 69 image format 842094169
Debug[ogle_vout]: Xv createimage 720x576
Debug[ogle_vout]: XvShmCreateImage: 49
Debug[ogle_vout]: resize: 767, 576
Debug[ogle_vout]: ss disable took 0.002211000 s
#!#!!Debug[ogle_vout]: resize: 767, 576
display: frame rate: 0.000 fps
Debug[ogle_nav]: play_Cell_post: state.cellN (1)
Debug[ogle_nav]: play_Cell: state.cellN (2)
Debug[ogle_mpeg_ps]: Backward scr discontinuity
Debug[ogle_mpeg_ps]: system_clock_reference: [0.000000 s]
Debug[ogle_mpeg_ps]: prev system_clock_reference: [10.335497 s]
Debug[ogle_mpeg_ps]: demux: changed to scr_nr: 2
Debug[ogle_nav]: play_Cell_post: state.cellN (2)
Debug[ogle_nav]: last cell in this PGC
Debug[ogle_nav]: play_PGC_post:
Debug[ogle_nav]: play_PGC: state.pgcN (1)
Debug[ogle_nav]: play_PG: state.pgN (1)
Debug[ogle_nav]: play_Cell: state.cellN (1)
Debug[ogle_nav]: sending audio demuxstream state disabled
Debug[ogle_nav]: id: bd, st: 80
Debug[ogle_nav]: sending subp demuxstream 0
Debug[ogle_nav]: sending subp demuxstream state enabled
Debug[ogle_nav]: id: bd, st: 20
Debug[ogle_mpeg_ps]: Backward scr discontinuity
Debug[ogle_mpeg_ps]: system_clock_reference: [0.000000 s]
Debug[ogle_mpeg_ps]: prev system_clock_reference: [2.400321 s]
Debug[ogle_mpeg_ps]: demux: changed to scr_nr: 3
Debug[ogle_nav]: forced select button 1
Note[ogle_nav]: Still picture select an item to continue.
Debug[ogle_vout]: scr_nr: 3, OFFSET_NOT_VALID
Debug[ogle_vout]: scr_nr: 3, OFFSET_NOT_VALID
Debug[ogle_vout]: scr_nr: 3, OFFSET_NOT_VALID
Debug[ogle_vout]: scr_nr: 3, OFFSET_NOT_VALID
Debug[ogle_vout]: scr_nr: 3, OFFSET_NOT_VALID
Debug[ogle_vout]: scr_nr: 3, OFFSET_NOT_VALID
Debug[ogle_vout]: scr_nr: 3, OFFSET_NOT_VALID
Debug[ogle_vout]: scr_nr: 3, OFFSET_NOT_VALID
Debug[ogle_vout]: scr_nr: 3, OFFSET_NOT_VALID
Debug[ogle_vout]: scr_nr: 3, OFFSET_NOT_VALID
Debug[ogle_vout]: scr_nr: 3, OFFSET_NOT_VALID
Debug[ogle_vout]: scr_nr: 3, OFFSET_NOT_VALID
Debug[ogle_vout]: scr_nr: 3, OFFSET_NOT_VALID
Debug[ogle_vout]: scr_nr: 3, OFFSET_NOT_VALID
Debug[ogle_vout]: scr_nr: 3, OFFSET_NOT_VALID
Debug[ogle_vout]: scr_nr: 3, OFFSET_NOT_VALID
Debug[ogle_vout]: scr_nr: 3, OFFSET_NOT_VALID
Debug[ogle_vout]: scr_nr: 3, OFFSET_NOT_VALID
Debug[ogle_vout]: scr_nr: 3, OFFSET_NOT_VALID
Debug[ogle_vout]: scr_nr: 3, OFFSET_NOT_VALID
Debug[ogle_vout]: set_sync_point()
Debug[ogle_vout]: scr_nr: 3, OFFSET_NOT_VALID
Debug[ogle_vout]: set_sync_point()
Debug[ogle_nav]: play_PGC: state.pgcN (3)
Debug[ogle_nav]: play_PGC: state.pgcN (1)
Debug[ogle_nav]: PGC pre commands didn't do a Jump, Link or Call
Debug[ogle_nav]: play_PG: state.pgN (1)
Debug[ogle_nav]: play_Cell: state.cellN (1)
Debug[ogle_nav]: sending audio demuxstream 0
Debug[ogle_nav]: oid: bd, ost: 80, nid: c0, nst: 00
Debug[ogle_nav]: sending audio demuxstream state enabled
Debug[ogle_nav]: id: c0, st: 00
Debug[ogle_nav]: sending subp demuxstream state disabled
Debug[ogle_nav]: id: bd, st: 20
Debug[ogle_mpeg_ps]: newid not private:
Debug[ogle_mpeg_ps]: oldid private
Debug[ogle_mpeg_ps]: Backward scr discontinuity
Debug[ogle_mpeg_ps]: system_clock_reference: [0.000000 s]
Debug[ogle_mpeg_ps]: prev system_clock_reference: [0.043886 s]
Debug[ogle_mpeg_ps]: demux: changed to scr_nr: 4
Debug[ogle_audio]: MPEG Audio
Debug[ogle_audio]: Closing alsa pcm device: default
Debug[ogle_audio]: flushing audio driver
Debug[ogle_audio]: flushed audio
Debug[ogle_audio]: Searching <speakers> for:
Debug[ogle_audio]:  'Mono'

Debug[ogle_audio]: No channel configs found
Note[ogle_audio]: Using audio driver 'alsa'
Debug[ogle_audio]: Opening alsa pcm device: default
Debug[ogle_audio]: Min channels: 1
Debug[ogle_audio]: Max channels: 10000
Debug[ogle_audio]: Channels: 2
Debug[ogle_audio]: Wanted buffer time 500000, got 341333
Debug[ogle_audio]: Buffer size 16384 frames
Debug[ogle_audio]: Period time: 10666 us
Debug[ogle_audio]: Period size: 512 frames
Debug[ogle_audio]: Stream parameters are 48000 Hz, S16_LE, 2 channels, sample size: 4
Note[ogle_audio]: wanted 0 channels, got 2
Debug[ogle_audio]: channels 2, encoding 1, resolution 16
Debug[ogle_audio]: byte order 1, rate 48000
Debug[ogle_audio]: fragments -1, size 4096
Note[ogle_audio]: delay resolution better than: 0.004999920, 240 samples, rate: 48000
!display: frame rate: 19.029 fps
display: frame rate: 24.998 fps
display: frame rate: 24.993 fps
Debug[ogle_ctrl]: child: 19429 exited with 0 (/usr/local/lib/ogle/ogle_cli)
Debug[ogle_ctrl]: pid: 19429 exited with status: 0 (/usr/local/lib/ogle/ogle_cli)
Debug[ogle_ctrl]: child: 19430 killed (/usr/local/lib/ogle/ogle_nav)
Debug[ogle_ctrl]: pid: 19430 terminated on signal: 2 (/usr/local/lib/ogle/ogle_nav)
Debug[ogle_ctrl]: slaying child: 19431 /usr/local/lib/ogle/ogle_mpeg_ps
Debug[ogle_ctrl]: slaying child: 19432 /usr/local/lib/ogle/ogle_vout
Debug[ogle_ctrl]: slaying child: 19433 /usr/local/lib/ogle/ogle_mpeg_vs
Debug[ogle_ctrl]: slaying child: 19434 /usr/local/lib/ogle/ogle_audio
Debug[ogle_ctrl]: Removing shmid: 4be40000
Debug[ogle_ctrl]: Removing shmid: 4be40001
Debug[ogle_ctrl]: Removing shmid: 4be40002
Debug[ogle_ctrl]: Removing shmid: 4be40003
Debug[ogle_ctrl]: Removing shmid: 4be40004
Debug[ogle_ctrl]: Removing shmid: 4be40005
Debug[ogle_ctrl]: Removing shmid: 4be40006
Debug[ogle_ctrl]: Removing sysv shmid: 4227089
ERROR[ogle_ctrl]: ogle_sysv_shmrm: Invalid argument
Note[ogle_ctrl]: exiting