FFmpeg audio out of sync

For help and support with Universal Media Server
Forum rules
Please make sure you follow the Problem Reporting Guidelines before posting if you want a reply
Post Reply
ziatonic
Posts: 2
Joined: Sun Sep 01, 2013 10:17 pm

FFmpeg audio out of sync

Post by ziatonic »

Using both 2.6.5 and 3.0, I have FFmpeg set as default and to remux when possible. All my MKVs remux as they should and play normally, but every movie's audio get's at least 5 seconds out of sync when I use the "go to" feature. Pausing doesnt work. I have subtitles disabled and have no need (and don't want) to transcode. Any ideas?

Code: Select all

DEBUG 2013-09-01 06:34:38.414 [New I/O worker #3] Requesting time seek: 195.0 seconds
DEBUG 2013-09-01 06:34:38.415 [External Process Stopper] Stopping process: tsMuxeR.exe-20
DEBUG 2013-09-01 06:34:38.415 [New I/O worker #3] Creating pipe \\.\pipe\1378031678415tsmuxerout.ts
DEBUG 2013-09-01 06:34:38.415 [Thread-105] Error: null
DEBUG 2013-09-01 06:34:38.416 [New I/O worker #3] Creating pipe \\.\pipe\1378031678416ffmpegvideo
DEBUG 2013-09-01 06:34:38.416 [New I/O worker #3] Creating pipe \\.\pipe\1378031678416videoout
DEBUG 2013-09-01 06:34:38.417 [New I/O worker #3] Creating pipe \\.\pipe\1378031678417ffmpegaudio01
DEBUG 2013-09-01 06:34:38.418 [New I/O worker #3] Creating pipe \\.\pipe\1378031678417audioout
DEBUG 2013-09-01 06:34:38.420 [Thread-110] Disconnected pipe: \\.\pipe\1378031653494audioout
DEBUG 2013-09-01 06:34:38.421 [Thread-106] Disconnected pipe: \\.\pipe\1378031653492videoout
DEBUG 2013-09-01 06:34:38.421 [Thread-104] Error :null
DEBUG 2013-09-01 06:34:38.422 [External Process Stopper] Stopping process: ffmpeg.exe-21
DEBUG 2013-09-01 06:34:38.422 [External Process Stopper] Stopping process: ffmpeg.exe-22
DEBUG 2013-09-01 06:34:38.423 [Thread-102] Error: null
DEBUG 2013-09-01 06:34:38.423 [Thread-108] Error :null
DEBUG 2013-09-01 06:34:38.423 [Thread-109] Error: null
DEBUG 2013-09-01 06:34:38.425 [Thread-123] Waiting for pipe connection \\.\pipe\1378031678415tsmuxerout.ts
DEBUG 2013-09-01 06:34:38.426 [Thread-131] Waiting for pipe connection \\.\pipe\1378031678417audioout
DEBUG 2013-09-01 06:34:38.426 [Thread-127] Waiting for pipe connection \\.\pipe\1378031678416videoout
DEBUG 2013-09-01 06:34:38.432 [ffmpeg.exe-25] Starting "C:\Program Files (x86)\Universal Media Server\win32\ffmpeg.exe" -y -loglevel fatal -ss 195.0 -threads 2 -i G:\Movies\Wreck-It.Ralph.2012.BluRay.720p.DTS.x264-CHD\Wreck-It.Ralph.2012.BluRay.720p.DTS.x264-CHD.mkv -threads 2 -bufsize 31250000 -maxrate 31250000 -copypriorss 0 -avoid_negative_ts 1 -an -c:v copy -bsf h264_mp4toannexb -fflags +genpts -f rawvideo \\.\pipe\1378031678416ffmpegvideo
DEBUG 2013-09-01 06:34:38.435 [Thread-126] Waiting for pipe connection \\.\pipe\1378031678416ffmpegvideo
DEBUG 2013-09-01 06:34:38.436 [Thread-130] Waiting for pipe connection \\.\pipe\1378031678417ffmpegaudio01
DEBUG 2013-09-01 06:34:38.443 [ffmpeg.exe-25] Attaching thread: ffmpeg.exe-25
DEBUG 2013-09-01 06:34:38.521 [Thread-126] Result of \\.\pipe\1378031678416ffmpegvideo : true
DEBUG 2013-09-01 06:34:38.536 [tsMuxeR.exe-24] Starting "C:\Program Files (x86)\Universal Media Server\win32\tsMuxeR.exe" C:\Users\ANTAGO~1\AppData\Local\Temp\universalmediaserver\pms-tsmuxer.meta \\.\pipe\1378031678415tsmuxerout.ts
DEBUG 2013-09-01 06:34:38.538 [ffmpeg.exe-26] Starting "C:\Program Files (x86)\Universal Media Server\win32\ffmpeg.exe" -y -ss 195.0 -i G:\Movies\Wreck-It.Ralph.2012.BluRay.720p.DTS.x264-CHD\Wreck-It.Ralph.2012.BluRay.720p.DTS.x264-CHD.mkv -copypriorss 0 -avoid_negative_ts 1 -ac 2 -f dts -c:a copy \\.\pipe\1378031678417ffmpegaudio01
DEBUG 2013-09-01 06:34:38.544 [tsMuxeR.exe-24] Reading pipe: \\.\pipe\1378031678415tsmuxerout.ts
DEBUG 2013-09-01 06:34:38.575 [ffmpeg.exe-26] Attaching thread: ffmpeg.exe-26
DEBUG 2013-09-01 06:34:38.618 [tsMuxeR.exe-24] Attaching thread: tsMuxeR.exe-24
DEBUG 2013-09-01 06:34:38.754 [Thread-138] ffmpeg version N-52233-gee94362 Copyright (c) 2000-2013 the FFmpeg developers
DEBUG 2013-09-01 06:34:38.754 [Thread-138]   built on Apr 18 2013 02:50:33 with gcc 4.8.0 (GCC)
DEBUG 2013-09-01 06:34:38.754 [Thread-138]   configuration: --enable-gpl --enable-version3 --disable-w32threads --enable-avisynth --enable-bzlib --enable-fontconfig --enable-frei0r --enable-gnutls --enable-iconv --enable-libass --enable-libbluray --enable-libcaca --enable-libfreetype --enable-libgsm --enable-libilbc --enable-libmp3lame --enable-libopencore-amrnb --enable-libopencore-amrwb --enable-libopenjpeg --enable-libopus --enable-librtmp --enable-libschroedinger --enable-libsoxr --enable-libspeex --enable-libtheora --enable-libtwolame --enable-libvo-aacenc --enable-libvo-amrwbenc --enable-libvorbis --enable-libvpx --enable-libx264 --enable-libxavs --enable-libxvid --enable-zlib
DEBUG 2013-09-01 06:34:38.755 [Thread-138]   libavutil      52. 26.100 / 52. 26.100
DEBUG 2013-09-01 06:34:38.757 [Thread-138]   libavcodec     55.  2.100 / 55.  2.100
DEBUG 2013-09-01 06:34:38.757 [Thread-138]   libavformat    55.  2.100 / 55.  2.100
DEBUG 2013-09-01 06:34:38.757 [Thread-138]   libavdevice    55.  0.100 / 55.  0.100
DEBUG 2013-09-01 06:34:38.757 [Thread-138]   libavfilter     3. 56.103 /  3. 56.103
DEBUG 2013-09-01 06:34:38.758 [Thread-138]   libswscale      2.  2.100 /  2.  2.100
DEBUG 2013-09-01 06:34:38.758 [Thread-138]   libswresample   0. 17.102 /  0. 17.102
DEBUG 2013-09-01 06:34:38.758 [Thread-138]   libpostproc    52.  3.100 / 52.  3.100
DEBUG 2013-09-01 06:34:38.775 [Thread-138] [matroska,webm @ 0226a000] Unknown entry 0xF0
DEBUG 2013-09-01 06:34:38.783 [Thread-142] SmartLabs tsMuxeR.  Version 1.10.6  http://www.smlabs.net
DEBUG 2013-09-01 06:34:38.798 [Thread-138]     Last message repeated 1494 times
DEBUG 2013-09-01 06:34:38.798 [Thread-138] Input #0, matroska,webm, from 'G:\Movies\Wreck-It.Ralph.2012.BluRay.720p.DTS.x264-CHD\Wreck-It.Ralph.2012.BluRay.720p.DTS.x264-CHD.mkv':
DEBUG 2013-09-01 06:34:38.798 [Thread-138]   Metadata:
DEBUG 2013-09-01 06:34:38.798 [Thread-138]     creation_time   : 1970-01-01 00:00:02
DEBUG 2013-09-01 06:34:38.799 [Thread-138]   Duration: 01:41:13.54, start: 0.000000, bitrate: 4958 kb/s
DEBUG 2013-09-01 06:34:38.799 [Thread-138]     Stream #0:0(eng): Video: h264 (High), yuv420p, 1280x536, SAR 1:1 DAR 160:67, 23.98 fps, 23.98 tbr, 1k tbn, 47.95 tbc (default)
DEBUG 2013-09-01 06:34:38.800 [Thread-138]     Metadata:
DEBUG 2013-09-01 06:34:38.800 [Thread-138]       title           : Wreck.It.Ralph.2012.BluRay.720p.DTS.x264-CHD
DEBUG 2013-09-01 06:34:38.800 [Thread-138]     Stream #0:1(eng): Audio: dts (DTS), 48000 Hz, 5.1(side), fltp, 1536 kb/s (default)
DEBUG 2013-09-01 06:34:38.800 [Thread-138]     Metadata:
DEBUG 2013-09-01 06:34:38.800 [Thread-138]       title           : dts 5.1 1536kbps
DEBUG 2013-09-01 06:34:38.801 [Thread-130] Result of \\.\pipe\1378031678417ffmpegaudio01 : true
DEBUG 2013-09-01 06:34:38.802 [Thread-138] Output #0, dts, to '\\.\pipe\1378031678417ffmpegaudio01':
DEBUG 2013-09-01 06:34:38.802 [Thread-138]   Metadata:
DEBUG 2013-09-01 06:34:38.802 [Thread-138]     encoder         : Lavf55.2.100
DEBUG 2013-09-01 06:34:38.802 [Thread-138]     Stream #0:0(eng): Audio: dts, 48000 Hz, 5.1(side), 1536 kb/s (default)
DEBUG 2013-09-01 06:34:38.802 [Thread-138]     Metadata:
DEBUG 2013-09-01 06:34:38.803 [Thread-138]       title           : dts 5.1 1536kbps
DEBUG 2013-09-01 06:34:38.803 [Thread-138] Stream mapping:
DEBUG 2013-09-01 06:34:38.803 [Thread-138]   Stream #0:1 -> #0:0 (copy)
DEBUG 2013-09-01 06:34:38.803 [Thread-138] Press [q] to stop, [?] for help
DEBUG 2013-09-01 06:34:38.803 [Thread-138] [matroska,webm @ 0226a000] File is broken, keyframes not correctly marked!
DEBUG 2013-09-01 06:34:38.988 [Thread-127] Forced reconnection of \\.\pipe\1378031678416videoout with result : true
DEBUG 2013-09-01 06:34:38.988 [Thread-127] Result of \\.\pipe\1378031678416videoout : true
DEBUG 2013-09-01 06:34:38.989 [Thread-131] Forced reconnection of \\.\pipe\1378031678417audioout with result : true
DEBUG 2013-09-01 06:34:38.989 [Thread-131] Result of \\.\pipe\1378031678417audioout : true
DEBUG 2013-09-01 06:34:38.991 [Thread-142] Decoding H264 stream (track 1): Profile: [email protected]  Resolution: 1280:536p  Frame rate: 23.976
DEBUG 2013-09-01 06:34:38.991 [Thread-142] Change H264 level from 4.1 to 4.1
DEBUG 2013-09-01 06:34:38.991 [Thread-142] H.264 muxing fps not set. Get fps from stream.
DEBUG 2013-09-01 06:34:38.991 [Thread-142] H.264 stream does not contain fps field. Muxing fps=23.976
DEBUG 2013-09-01 06:34:39.001 [Thread-123] Result of \\.\pipe\1378031678415tsmuxerout.ts : true
DEBUG 2013-09-01 06:34:39.006 [Thread-142] 100.0% complete
DEBUG 2013-09-01 06:34:39.006 [Thread-142] H264 bitstream changed: insert nal unit delimiters
DEBUG 2013-09-01 06:34:39.006 [Thread-142] H264 bitstream changed: insert pict timing and buffering period SEI units
DEBUG 2013-09-01 06:34:39.006 [Thread-142] Decoding LPCM stream (track 2): Bitrate: 1536Kbps  Sample Rate: 48KHz  Channels: 2  Bits per sample: 16bit
DEBUG 2013-09-01 06:34:39.006 [Thread-142] B-pyramid level 1 detected. Shift DTS to 2 frames
DEBUG 2013-09-01 06:34:39.263 [Thread-142] 100.0% complete
DEBUG 2013-09-01 06:34:39.377 [Thread-138] size=    5863kB time=00:00:31.82 bitrate=1509.1kbits/s    
DEBUG 2013-09-01 06:34:39.452 [Thread-142] 100.0% complete
DEBUG 2013-09-01 06:34:39.698 [Thread-142] 100.0% complete
DEBUG 2013-09-01 06:34:39.856 [Thread-138] size=   11722kB time=00:01:03.63 bitrate=1509.1kbits/s    
DEBUG 2013-09-01 06:34:39.969 [Thread-142] 100.0% complete
DEBUG 2013-09-01 06:34:40.180 [Thread-142] 100.0% complete
DEBUG 2013-09-01 06:34:40.368 [Thread-138] size=   17965kB time=00:01:37.52 bitrate=1509.0kbits/s    
DEBUG 2013-09-01 06:34:40.384 [Thread-142] 100.0% complete
User avatar
SubJunk
Lead Developer
Posts: 3797
Joined: Sun May 27, 2012 4:12 pm

Re: FFmpeg audio out of sync

Post by SubJunk »

That's one of the reasons we don't enable those settings by default. If you disable the remux option it should be more stable.
ziatonic
Posts: 2
Joined: Sun Sep 01, 2013 10:17 pm

Re: FFmpeg audio out of sync

Post by ziatonic »

SubJunk wrote:That's one of the reasons we don't enable those settings by default. If you disable the remux option it should be more stable.
Oh I didn't know it was a known issue. I searched but didn't see anything. Anyway, I am using ffmpeg because I want my videos to remux and tsmuxer gives me the "This content cannot be played" error for every file I try. I have no need/desire to transcode because I don't use subs and I don't have any MKVs in formats the PS3 can't support. In addition, I want to stay away from transcoding because, at 1080 resolution with DTS, my cpu has a hard time keeping the buffer full. Any suggestions? Thanks.
Last edited by ziatonic on Wed Sep 04, 2013 9:43 pm, edited 2 times in total.
User avatar
SubJunk
Lead Developer
Posts: 3797
Joined: Sun May 27, 2012 4:12 pm

Re: FFmpeg audio out of sync

Post by SubJunk »

It's unusual for tsMuxeR to not work for a file like that. Can you delete your UMS preferences (delete or rename the folder C:\ProgramData\UMS) to reset the options to default and then attach the full debug log using these instructions.
Please use version 3.0.0-a5 for the testing :)
Post Reply