[svn4585] MPEG-TS audio trouble

Report and discuss *new*, *consistent*, *reproducible* bugs here
roadside
Posts: 12
Joined: Sun Apr 08, 2012 7:21 pm

[svn4585] MPEG-TS audio trouble

Postby roadside » Wed Apr 11, 2012 6:07 am

Hi,
Tested > HandBrake-4585svn-x86-Win_CLI using Windows 7 Ultimate 32-bit edition (with High Profile, vbitrate: 4000 Kbps, audio: AC3)
The audio encoding is wrong. Other encoder make correct listening experience.

Test file > http://www.mediafire.com/?7sk6ppy437gc3p5

TedJ
Veteran User
Posts: 5388
Joined: Wed Feb 20, 2008 11:25 pm

Re: [svn4585] MPEG-TS audio trouble

Postby TedJ » Wed Apr 11, 2012 8:15 am

Activity log, please.

roadside
Posts: 12
Joined: Sun Apr 08, 2012 7:21 pm

Re: [svn4585] MPEG-TS audio trouble

Postby roadside » Wed Apr 11, 2012 10:29 am

Sorry, here is it:

1_test.m4v 2012.04.11. 12-27-24.txt

Code: Select all

HandBrake svn4582 2012040801
OS: Microsoft Windows NT 6.1.7601 Service Pack 1
CPU: AMD Athlon(tm) II P340 Dual-Core Processor
Ram: 1786 MB, Screen: 1366x768
Temp Dir: C:\Users\ZuiRoadside\AppData\Local\Temp\
Install Dir: C:\Program Files\Handbrake
Data Dir: C:\Users\ZuiRoadside\AppData\Roaming\HandBrake\HandBrake\0.9.6.4582

-------------------------------------------

CLI Query:  -i "D:\TESZT TS\1.ts" -t 1 -c 1 -o "D:\TESZT TS\1_test.m4v"  -f mp4 -4  --detelecine --decomb -w 1440 --loose-anamorphic  -e x264 -b 4000 --cfr  -a 1 -E ffac3 -B 192 -6 stereo -R Auto -D 0 --gain=0 --audio-copy-mask none --audio-fallback ffac3 --markers="C:\Users\ZuiRoadside\AppData\Local\Temp\1_test-1-chapters.csv" -x b-adapt=2:rc-lookahead=50 --verbose=2
User Query: False

[12:19:47] hb_init: starting libhb thread
[12:19:47] thread 2e34a8 started ("libhb")
HandBrake svn4582 (2012040801) - MinGW i686 - http://handbrake.fr
2 CPUs detected
Opening D:\TESZT TS\1.ts...
[12:19:47] hb_scan: path=D:\TESZT TS\1.ts, title_index=1
[12:19:47] thread 2e3980 started ("scan")
libbluray/bdnav/index_parse.c:157: indx_parse(): error opening D:\TESZT TS\1.ts/BDMV/index.bdmv
libbluray/bluray.c:1471: nav_get_title_list(D:\TESZT TS\1.ts) failed (002E41C0)
[12:19:47] bd: not a bd - trying as a stream/file instead
libdvdnav: Using dvdnav version 4.1.3
libdvdread: Encrypted DVD support unavailable.
libdvdnav:DVDOpenFileUDF:UDFFindFile /VIDEO_TS/VIDEO_TS.IFO failed
libdvdnav:DVDOpenFileUDF:UDFFindFile /VIDEO_TS/VIDEO_TS.BUP failed
libdvdread: Can't open file VIDEO_TS.IFO.
libdvdnav: vm: failed to read VIDEO_TS.IFO
[12:19:47] dvd: not a dvd - trying as a stream/file instead
[12:19:47] file is MPEG Transport Stream with 188 byte packets offset 0 bytes
[12:19:47] Found the following PIDS
[12:19:47]     Video PIDS :
[12:19:47]       0x3e9 type H.264 (0x1b) (PCR)
[12:19:47]     Audio PIDS :
[12:19:47]       0x3ea type MPEG1 (0x3)
[12:19:47]       0x3ec type AC3 (0x81)
[12:19:47]     Other PIDS :
[12:19:47]       0x3eb type DVB Teletext (0x0)
[12:19:47] stream id 0x3ea (type 0x3 substream 0x0) audio 0x3ea
[12:19:47] stream id 0x3ec (type 0x81 substream 0x0) audio 0x3ec
[12:19:47] scan: decoding previews for title 1
[12:19:47] scan: preview 1
[12:19:47] fifo_close: trashing 0 buffer(s)
[12:19:47] scan: audio 0x3ec: AC-3, rate=48000Hz, bitrate=384000 Magyar (AC3) (2.0 ch)
[12:19:47] fifo_close: trashing 0 buffer(s)
[12:19:47] scan: audio 0x3ea: mp2, rate=48000Hz, bitrate=200000 Magyar (MPEG1) (2.0 ch)
[12:19:47] scan: preview 2
Scanning title 1...
[h264 @ 003459E0] mmco: unref short failure
[12:19:47] scan: preview 3
[h264 @ 003459E0] mmco: unref short failure
[12:19:47] scan: preview 4
Scanning title 1...
[h264 @ 003459E0] mmco: unref short failure
[12:19:47] scan: preview 5
[h264 @ 003459E0] mmco: unref short failure
[12:19:47] scan: preview 6
[h264 @ 003459E0] mmco: unref short failure
Scanning title 1...
[12:19:47] scan: preview 7
[h264 @ 003459E0] mmco: unref short failure
[12:19:48] scan: preview 8
[h264 @ 003459E0] mmco: unref short failure
[12:19:48] scan: preview 9
Scanning title 1...
[12:19:48] scan: preview 10
[h264 @ 003459E0] mmco: unref short failure
[h264 @ 003459E0] mmco: unref short failure
[12:19:48] scan: 10 previews, 1440x1080, 25.000 fps, autocrop = 0/2/0/0, aspect 16:9, PAR 4:3
[12:19:48] scan: title (0) job->width:1440, job->height:800
[12:19:48] stream: 5 good frames, 0 errors (0%)
[12:19:48] thread 2e3980 exited ("scan")
[12:19:48] thread 2e3980 joined ("scan")
[12:19:48] libhb: scan thread found 1 valid title(s)
+ title 1:
  + stream: D:\TESZT TS\1.ts
  + duration: 00:01:02
  + size: 1440x1080, pixel aspect: 4/3, display aspect: 1.78, 25.000 fps
  + autocrop: 0/2/0/0
  + chapters:
    + 1: cells 0->0, 0 blocks, duration 00:01:02
  + audio tracks:
    + 1, Magyar (MPEG1) (2.0 ch) (iso639-2: hun)
    + 2, Magyar (AC3) (2.0 ch) (iso639-2: hun), 48000Hz, 384000bps
  + subtitle tracks:
Reading chapter markers from file C:\Users\ZuiRoadside\AppData\Local\Temp\1_test-1-chapters.csv
[12:19:48] thread 2e3980 started ("work")
[12:19:48] 1 job(s) to process
[12:19:48] starting job
[12:19:48] thread 2e9060 started ("yadif_filter_segment")
[12:19:48] yadif thread started for segment 0
[12:19:48] thread 2e72b8 started ("yadif_filter_segment")
[12:19:48] thread 2e0b448 started ("decomb_filter_segment")
[12:19:48] decomb thread started for segment 0
[12:19:48] thread 2e7350 started ("decomb_filter_segment")
[12:19:48] yadif thread started for segment 1
[12:19:48] decomb thread started for segment 1
[12:19:48] sync: expecting 1580 video frames
[12:19:48] work: only 1 chapter, disabling chapter markers
[12:19:48] job configuration:
[12:19:48]  * source
[12:19:48]    + D:\TESZT TS\1.ts
[12:19:48]    + title 1, chapter(s) 1 to 1
[12:19:48]  * destination
[12:19:48]    + D:\TESZT TS\1_test.m4v
[12:19:48]    + container: MPEG-4 (.mp4 and .m4v)
[12:19:48]      + 64-bit formatting
[12:19:48]  * video track
[12:19:48]    + decoder: h264
[12:19:48]      + bitrate 200 kbps
[12:19:48]    + frame rate: 25.000 fps -> constant 25.000 fps
[12:19:48]    + filters
[12:19:48]      + Detelecine (pullup) (default settings)
[12:19:48]      + Decomb (default settings)
[12:19:48]      + Framerate Shaper (1:27000000:1080000)
[12:19:48]        + frame rate: 25.000 fps -> constant 25.000 fps
[12:19:48]      + Crop and Scale (1440:1072:0:2:0:0)
[12:19:48]        + source: 1440 * 1080, crop (0/2/0/0): 1440 * 1078, scale: 1440 * 1072
[12:19:48]    + loose anamorphic
[12:19:48]      + storage dimensions: 1440 * 1072, mod 0
[12:19:48]      + pixel aspect ratio: 2144 / 1617
[12:19:48]      + display dimensions: 1909 * 1072
[12:19:48]    + encoder: H.264 (x264)
[12:19:48]      + options: b-adapt=2:rc-lookahead=50
[12:19:48]      + bitrate: 4000 kbps, pass: 0
[12:19:48]  * audio track 1
[12:19:48]    + decoder: Magyar (MPEG1) (2.0 ch) (track 1, id 0x3ea)
[12:19:48]    + mixdown: Stereo
[12:19:48]    + encoder: AC3 (ffmpeg)
[12:19:48]      + bitrate: 192 kbps, samplerate: 48000 Hz
[12:19:48] file is MPEG Transport Stream with 188 byte packets offset 0 bytes
[12:19:48] thread 29c9cf0 started ("Reader")
[12:19:48] thread 32e0c00 started ("Detelecine (pullup)")
[12:19:48] thread 32e0eb8 started ("Decomb")
[12:19:48] thread 32e0f50 started ("Framerate Shaper")
[12:19:48] thread 2e8ac8 started ("Crop and Scale")
[12:19:48] reader: first SCR 6103515 id 0x3e9 DTS 694144573
[12:19:48] thread 2e8b60 started ("AudioSynchronization")
[12:19:48] thread 2e8bf8 started ("Video decoder (libavcodec)")
[12:19:48] encx264: encoding with stored aspect 2144/1617
[12:19:48] encx264: opening libx264 (pass 0)
x264 [warning]: --psnr used with psy on: results will be invalid!
x264 [warning]: --tune psnr should be used if attempting to benchmark psnr!
x264 [info]: using SAR=2144/1617
x264 [info]: using cpu capabilities: MMX2 SSE2Fast FastShuffle LZCNT
x264 [info]: profile High, level 4.0
[12:19:48] thread af232a0 started ("H.264/AVC encoder (libx264)")
[12:19:48] thread af23338 started ("Audio decoder (libavcodec)")
[12:19:48] thread af233d0 started ("AVCodec Audio encoder (libavcodec)")
[12:19:48] thread af23468 started ("Video Synchronization")
[12:19:48] h264: "" (1) at frame 0 time 0
[12:19:48] muxmp4: using 64-bit MP4 formatting.
[12:19:48] muxmp4: track 0, chunk duration 14400
[12:19:48] muxmp4: track 1, chunk duration 7680
[12:19:48] thread af23500 started ("Muxer")
[12:26:42] sync: adding 7560 ms of silence to audio 0x3ea  start 680400, next 0
[12:27:00] hb_ts_stream_decode - eof
[12:27:00] reader: done. 3 scr changes
[12:27:00] thread 29c9cf0 exited ("Reader")
[12:27:10] encx264 input continuity err: last stop 4852800  start 4856400
[12:27:20] work: average encoding speed for job is 3.140387 fps
[12:27:20] thread af23500 exited ("Muxer")
[12:27:21] thread 32e0c00 exited ("Detelecine (pullup)")
[12:27:21] thread af233d0 exited ("AVCodec Audio encoder (libavcodec)")
[12:27:21] thread 2e8b60 exited ("AudioSynchronization")
[12:27:21] thread af232a0 exited ("H.264/AVC encoder (libx264)")
[12:27:21] thread af23338 exited ("Audio decoder (libavcodec)")
[12:27:21] thread 2e8ac8 exited ("Crop and Scale")
[12:27:21] thread af23468 exited ("Video Synchronization")
[12:27:21] thread 2e8bf8 exited ("Video decoder (libavcodec)")
[12:27:21] thread 32e0eb8 exited ("Decomb")
[12:27:21] thread af23468 joined ("Video Synchronization")
[12:27:21] sync: got 1350 frames, 1580 expected
[12:27:21] thread 32e0c00 joined ("Detelecine (pullup)")
[12:27:21] thread 32e0eb8 joined ("Decomb")
[12:27:21] decomb: deinterlaced 7 | blended 16 | unfiltered 1325 | total 1348
[12:27:21] thread 2e9060 exited ("yadif_filter_segment")
[12:27:21] thread 2e9060 joined ("yadif_filter_segment")
[12:27:21] thread 2e72b8 exited ("yadif_filter_segment")
[12:27:21] thread 2e72b8 joined ("yadif_filter_segment")
[12:27:21] thread 2e0b448 exited ("decomb_filter_segment")
[12:27:21] thread 2e0b448 joined ("decomb_filter_segment")
[12:27:21] thread 2e7350 exited ("decomb_filter_segment")
[12:27:21] thread 2e7350 joined ("decomb_filter_segment")
[12:27:21] thread 32e0f50 exited ("Framerate Shaper")
[12:27:21] thread 32e0f50 joined ("Framerate Shaper")
[12:27:21] render: 1351 frames output, 671 dropped and 674 duped for CFR/PFR
[12:27:21] render: lost time: 10800 (0 frames)
[12:27:21] render: gained time: 10800 (4 frames) (0 not accounted for)
[12:27:21] fifo_close: trashing 0 buffer(s)
[12:27:21] thread 2e8ac8 joined ("Crop and Scale")
[12:27:21] thread 2e8b60 joined ("AudioSynchronization")
[12:27:21] thread 2e8bf8 joined ("Video decoder (libavcodec)")
[12:27:21] h264-decoder done: 1558 frames, 0 decoder errors, 0 drops
[12:27:21] thread af232a0 joined ("H.264/AVC encoder (libx264)")
x264 [info]: frame I:11    Avg QP:15.78  size:155205  PSNR Mean Y:48.64 U:53.46 V:52.70 Avg:49.68 Global:49.63
x264 [info]: frame P:348   Avg QP:18.90  size: 52920  PSNR Mean Y:45.51 U:51.09 V:50.15 Avg:46.64 Global:46.58
x264 [info]: frame B:990   Avg QP:20.79  size:  6664  PSNR Mean Y:45.20 U:51.08 V:50.10 Avg:46.36 Global:46.29
x264 [info]: consecutive B-frames:  0.9%  2.5%  3.8% 92.8%
x264 [info]: mb I  I16..4:  8.7% 62.6% 28.8%
x264 [info]: mb P  I16..4:  2.3% 10.6%  2.6%  P16..4: 40.8% 23.2% 11.7%  0.0%  0.0%    skip: 8.8%
x264 [info]: mb B  I16..4:  0.1%  0.6%  0.2%  B16..8: 24.3%  3.3%  0.6%  direct: 1.4%  skip:69.5%  L0:43.0% L1:45.9% BI:11.1%
x264 [info]: final ratefactor: 19.06
x264 [info]: 8x8 transform intra:67.6% inter:68.8%
x264 [info]: coded y,uvDC,uvAC intra: 75.0% 66.3% 31.4% inter: 14.5% 14.5% 1.1%
x264 [info]: i16 v,h,dc,p: 46% 15%  7% 31%
x264 [info]: i8 v,h,dc,ddl,ddr,vr,hd,vl,hu: 30% 12% 13%  5%  7% 11%  6% 10%  6%
x264 [info]: i4 v,h,dc,ddl,ddr,vr,hd,vl,hu: 32% 14% 11%  5%  9% 11%  7%  7%  4%
x264 [info]: i8c dc,h,v,p: 49% 15% 29%  6%
x264 [info]: Weighted P-Frames: Y:0.0% UV:0.0%
x264 [info]: ref P L0: 59.1% 13.5% 19.2%  8.1%
x264 [info]: ref B L0: 89.6%  7.8%  2.6%
x264 [info]: ref B L1: 98.5%  1.5%
x264 [info]: SSIM Mean Y:0.9898061 (19.917db)
x264 [info]: PSNR Mean Y:45.304 U:51.104 V:50.131 Avg:46.461 Global:46.384 kb/s:3961.59
[12:27:21] thread af23338 joined ("Audio decoder (libavcodec)")
[12:27:21] mp2-decoder done: 0 frames, 0 decoder errors, 0 drops
[12:27:21] thread af233d0 joined ("AVCodec Audio encoder (libavcodec)")
[12:27:21] encavcodeca: closing libavcodec
[12:27:21] thread af23500 joined ("Muxer")
[12:27:21] mux: file size, 27115563 bytes
[12:27:21] mux: track 0, 1349 frames, 26720427 bytes, 3958.58 kbps, fifo 2048
[12:27:21] mux: video bitrate error, -279573 bytes
[12:27:21] mux: track 1, 485 frames, 372480 bytes, 55.18 kbps, fifo 8
[12:27:21] mux: overhead, 12.35 bytes per frame
[12:27:21] thread 29c9cf0 joined ("Reader")
[12:27:21] stream: 1578 good frames, 0 errors (0%)
[12:27:21] fifo_close: trashing 0 buffer(s)
[12:27:21] fifo_close: trashing 0 buffer(s)
[12:27:21] fifo_close: trashing 0 buffer(s)
[12:27:21] fifo_close: trashing 0 buffer(s)
[12:27:21] fifo_close: trashing 0 buffer(s)
[12:27:21] fifo_close: trashing 0 buffer(s)
[12:27:21] fifo_close: trashing 0 buffer(s)
[12:27:21] fifo_close: trashing 0 buffer(s)
[12:27:21] fifo_close: trashing 0 buffer(s)
[12:27:21] Freed 32 buffers of size 1024
[12:27:21] Freed 32 buffers of size 2048
[12:27:21] Freed 11 buffers of size 4096
[12:27:21] Freed 20 buffers of size 8192
[12:27:21] Freed 32 buffers of size 16384
[12:27:21] Freed 32 buffers of size 32768
[12:27:21] Freed 32 buffers of size 65536
[12:27:21] Freed 32 buffers of size 131072
[12:27:21] Freed 11 buffers of size 262144
[12:27:21] Freed 32 buffers of size 4194304
[12:27:21] Allocated 153661440 bytes of buffers on this pass and Freed 145272832 bytes, 8388608 bytes leaked
[12:27:21] thread 2e3980 exited ("work")
[12:27:21] thread 2e3980 joined ("work")
[12:27:21] libhb: work result = 0
Encode done!
[12:27:21] thread 2e34a8 exited ("libhb")
[12:27:21] thread 2e34a8 joined ("libhb")
HandBrake has exited.


last_encode_log1.txt

Code: Select all

HandBrake svn4582 2012040801
OS: Microsoft Windows NT 6.1.7601 Service Pack 1
CPU: AMD Athlon(tm) II P340 Dual-Core Processor
Ram: 1786 MB, Screen: 1366x768
Temp Dir: C:\Users\ZuiRoadside\AppData\Local\Temp\
Install Dir: C:\Program Files\Handbrake
Data Dir: C:\Users\ZuiRoadside\AppData\Roaming\HandBrake\HandBrake\0.9.6.4582

-------------------------------------------

CLI Query:  -i "D:\TESZT TS\1.ts" -t 1 -c 1 -o "D:\TESZT TS\1_test.m4v"  -f mp4 -4  --detelecine --decomb -w 1440 --loose-anamorphic  -e x264 -b 4000 --cfr  -a 1 -E ffac3 -B 192 -6 stereo -R Auto -D 0 --gain=0 --audio-copy-mask none --audio-fallback ffac3 --markers="C:\Users\ZuiRoadside\AppData\Local\Temp\1_test-1-chapters.csv" -x b-adapt=2:rc-lookahead=50 --verbose=2
User Query: False

[12:19:47] hb_init: starting libhb thread
[12:19:47] thread 2e34a8 started ("libhb")
HandBrake svn4582 (2012040801) - MinGW i686 - http://handbrake.fr
2 CPUs detected
Opening D:\TESZT TS\1.ts...
[12:19:47] hb_scan: path=D:\TESZT TS\1.ts, title_index=1
[12:19:47] thread 2e3980 started ("scan")
libbluray/bdnav/index_parse.c:157: indx_parse(): error opening D:\TESZT TS\1.ts/BDMV/index.bdmv
libbluray/bluray.c:1471: nav_get_title_list(D:\TESZT TS\1.ts) failed (002E41C0)
[12:19:47] bd: not a bd - trying as a stream/file instead
libdvdnav: Using dvdnav version 4.1.3
libdvdread: Encrypted DVD support unavailable.
libdvdnav:DVDOpenFileUDF:UDFFindFile /VIDEO_TS/VIDEO_TS.IFO failed
libdvdnav:DVDOpenFileUDF:UDFFindFile /VIDEO_TS/VIDEO_TS.BUP failed
libdvdread: Can't open file VIDEO_TS.IFO.
libdvdnav: vm: failed to read VIDEO_TS.IFO
[12:19:47] dvd: not a dvd - trying as a stream/file instead
[12:19:47] file is MPEG Transport Stream with 188 byte packets offset 0 bytes
[12:19:47] Found the following PIDS
[12:19:47]     Video PIDS :
[12:19:47]       0x3e9 type H.264 (0x1b) (PCR)
[12:19:47]     Audio PIDS :
[12:19:47]       0x3ea type MPEG1 (0x3)
[12:19:47]       0x3ec type AC3 (0x81)
[12:19:47]     Other PIDS :
[12:19:47]       0x3eb type DVB Teletext (0x0)
[12:19:47] stream id 0x3ea (type 0x3 substream 0x0) audio 0x3ea
[12:19:47] stream id 0x3ec (type 0x81 substream 0x0) audio 0x3ec
[12:19:47] scan: decoding previews for title 1
[12:19:47] scan: preview 1
[12:19:47] fifo_close: trashing 0 buffer(s)
[12:19:47] scan: audio 0x3ec: AC-3, rate=48000Hz, bitrate=384000 Magyar (AC3) (2.0 ch)
[12:19:47] fifo_close: trashing 0 buffer(s)
[12:19:47] scan: audio 0x3ea: mp2, rate=48000Hz, bitrate=200000 Magyar (MPEG1) (2.0 ch)
[12:19:47] scan: preview 2
Scanning title 1...
[h264 @ 003459E0] mmco: unref short failure
[12:19:47] scan: preview 3
[h264 @ 003459E0] mmco: unref short failure
[12:19:47] scan: preview 4
Scanning title 1...
[h264 @ 003459E0] mmco: unref short failure
[12:19:47] scan: preview 5
[h264 @ 003459E0] mmco: unref short failure
[12:19:47] scan: preview 6
[h264 @ 003459E0] mmco: unref short failure
Scanning title 1...
[12:19:47] scan: preview 7
[h264 @ 003459E0] mmco: unref short failure
[12:19:48] scan: preview 8
[h264 @ 003459E0] mmco: unref short failure
[12:19:48] scan: preview 9
Scanning title 1...
[12:19:48] scan: preview 10
[h264 @ 003459E0] mmco: unref short failure
[h264 @ 003459E0] mmco: unref short failure
[12:19:48] scan: 10 previews, 1440x1080, 25.000 fps, autocrop = 0/2/0/0, aspect 16:9, PAR 4:3
[12:19:48] scan: title (0) job->width:1440, job->height:800
[12:19:48] stream: 5 good frames, 0 errors (0%)
[12:19:48] thread 2e3980 exited ("scan")
[12:19:48] thread 2e3980 joined ("scan")
[12:19:48] libhb: scan thread found 1 valid title(s)
+ title 1:
  + stream: D:\TESZT TS\1.ts
  + duration: 00:01:02
  + size: 1440x1080, pixel aspect: 4/3, display aspect: 1.78, 25.000 fps
  + autocrop: 0/2/0/0
  + chapters:
    + 1: cells 0->0, 0 blocks, duration 00:01:02
  + audio tracks:
    + 1, Magyar (MPEG1) (2.0 ch) (iso639-2: hun)
    + 2, Magyar (AC3) (2.0 ch) (iso639-2: hun), 48000Hz, 384000bps
  + subtitle tracks:
Reading chapter markers from file C:\Users\ZuiRoadside\AppData\Local\Temp\1_test-1-chapters.csv
[12:19:48] thread 2e3980 started ("work")
[12:19:48] 1 job(s) to process
[12:19:48] starting job
[12:19:48] thread 2e9060 started ("yadif_filter_segment")
[12:19:48] yadif thread started for segment 0
[12:19:48] thread 2e72b8 started ("yadif_filter_segment")
[12:19:48] thread 2e0b448 started ("decomb_filter_segment")
[12:19:48] decomb thread started for segment 0
[12:19:48] thread 2e7350 started ("decomb_filter_segment")
[12:19:48] yadif thread started for segment 1
[12:19:48] decomb thread started for segment 1
[12:19:48] sync: expecting 1580 video frames
[12:19:48] work: only 1 chapter, disabling chapter markers
[12:19:48] job configuration:
[12:19:48]  * source
[12:19:48]    + D:\TESZT TS\1.ts
[12:19:48]    + title 1, chapter(s) 1 to 1
[12:19:48]  * destination
[12:19:48]    + D:\TESZT TS\1_test.m4v
[12:19:48]    + container: MPEG-4 (.mp4 and .m4v)
[12:19:48]      + 64-bit formatting
[12:19:48]  * video track
[12:19:48]    + decoder: h264
[12:19:48]      + bitrate 200 kbps
[12:19:48]    + frame rate: 25.000 fps -> constant 25.000 fps
[12:19:48]    + filters
[12:19:48]      + Detelecine (pullup) (default settings)
[12:19:48]      + Decomb (default settings)
[12:19:48]      + Framerate Shaper (1:27000000:1080000)
[12:19:48]        + frame rate: 25.000 fps -> constant 25.000 fps
[12:19:48]      + Crop and Scale (1440:1072:0:2:0:0)
[12:19:48]        + source: 1440 * 1080, crop (0/2/0/0): 1440 * 1078, scale: 1440 * 1072
[12:19:48]    + loose anamorphic
[12:19:48]      + storage dimensions: 1440 * 1072, mod 0
[12:19:48]      + pixel aspect ratio: 2144 / 1617
[12:19:48]      + display dimensions: 1909 * 1072
[12:19:48]    + encoder: H.264 (x264)
[12:19:48]      + options: b-adapt=2:rc-lookahead=50
[12:19:48]      + bitrate: 4000 kbps, pass: 0
[12:19:48]  * audio track 1
[12:19:48]    + decoder: Magyar (MPEG1) (2.0 ch) (track 1, id 0x3ea)
[12:19:48]    + mixdown: Stereo
[12:19:48]    + encoder: AC3 (ffmpeg)
[12:19:48]      + bitrate: 192 kbps, samplerate: 48000 Hz
[12:19:48] file is MPEG Transport Stream with 188 byte packets offset 0 bytes
[12:19:48] thread 29c9cf0 started ("Reader")
[12:19:48] thread 32e0c00 started ("Detelecine (pullup)")
[12:19:48] thread 32e0eb8 started ("Decomb")
[12:19:48] thread 32e0f50 started ("Framerate Shaper")
[12:19:48] thread 2e8ac8 started ("Crop and Scale")
[12:19:48] reader: first SCR 6103515 id 0x3e9 DTS 694144573
[12:19:48] thread 2e8b60 started ("AudioSynchronization")
[12:19:48] thread 2e8bf8 started ("Video decoder (libavcodec)")
[12:19:48] encx264: encoding with stored aspect 2144/1617
[12:19:48] encx264: opening libx264 (pass 0)
x264 [warning]: --psnr used with psy on: results will be invalid!
x264 [warning]: --tune psnr should be used if attempting to benchmark psnr!
x264 [info]: using SAR=2144/1617
x264 [info]: using cpu capabilities: MMX2 SSE2Fast FastShuffle LZCNT
x264 [info]: profile High, level 4.0
[12:19:48] thread af232a0 started ("H.264/AVC encoder (libx264)")
[12:19:48] thread af23338 started ("Audio decoder (libavcodec)")
[12:19:48] thread af233d0 started ("AVCodec Audio encoder (libavcodec)")
[12:19:48] thread af23468 started ("Video Synchronization")
[12:19:48] h264: "" (1) at frame 0 time 0
[12:19:48] muxmp4: using 64-bit MP4 formatting.
[12:19:48] muxmp4: track 0, chunk duration 14400
[12:19:48] muxmp4: track 1, chunk duration 7680
[12:19:48] thread af23500 started ("Muxer")
[12:26:42] sync: adding 7560 ms of silence to audio 0x3ea  start 680400, next 0
[12:27:00] hb_ts_stream_decode - eof
[12:27:00] reader: done. 3 scr changes
[12:27:00] thread 29c9cf0 exited ("Reader")
[12:27:10] encx264 input continuity err: last stop 4852800  start 4856400
[12:27:20] work: average encoding speed for job is 3.140387 fps
[12:27:20] thread af23500 exited ("Muxer")
[12:27:21] thread 32e0c00 exited ("Detelecine (pullup)")
[12:27:21] thread af233d0 exited ("AVCodec Audio encoder (libavcodec)")
[12:27:21] thread 2e8b60 exited ("AudioSynchronization")
[12:27:21] thread af232a0 exited ("H.264/AVC encoder (libx264)")
[12:27:21] thread af23338 exited ("Audio decoder (libavcodec)")
[12:27:21] thread 2e8ac8 exited ("Crop and Scale")
[12:27:21] thread af23468 exited ("Video Synchronization")
[12:27:21] thread 2e8bf8 exited ("Video decoder (libavcodec)")
[12:27:21] thread 32e0eb8 exited ("Decomb")
[12:27:21] thread af23468 joined ("Video Synchronization")
[12:27:21] sync: got 1350 frames, 1580 expected
[12:27:21] thread 32e0c00 joined ("Detelecine (pullup)")
[12:27:21] thread 32e0eb8 joined ("Decomb")
[12:27:21] decomb: deinterlaced 7 | blended 16 | unfiltered 1325 | total 1348
[12:27:21] thread 2e9060 exited ("yadif_filter_segment")
[12:27:21] thread 2e9060 joined ("yadif_filter_segment")
[12:27:21] thread 2e72b8 exited ("yadif_filter_segment")
[12:27:21] thread 2e72b8 joined ("yadif_filter_segment")
[12:27:21] thread 2e0b448 exited ("decomb_filter_segment")
[12:27:21] thread 2e0b448 joined ("decomb_filter_segment")
[12:27:21] thread 2e7350 exited ("decomb_filter_segment")
[12:27:21] thread 2e7350 joined ("decomb_filter_segment")
[12:27:21] thread 32e0f50 exited ("Framerate Shaper")
[12:27:21] thread 32e0f50 joined ("Framerate Shaper")
[12:27:21] render: 1351 frames output, 671 dropped and 674 duped for CFR/PFR
[12:27:21] render: lost time: 10800 (0 frames)
[12:27:21] render: gained time: 10800 (4 frames) (0 not accounted for)
[12:27:21] fifo_close: trashing 0 buffer(s)
[12:27:21] thread 2e8ac8 joined ("Crop and Scale")
[12:27:21] thread 2e8b60 joined ("AudioSynchronization")
[12:27:21] thread 2e8bf8 joined ("Video decoder (libavcodec)")
[12:27:21] h264-decoder done: 1558 frames, 0 decoder errors, 0 drops
[12:27:21] thread af232a0 joined ("H.264/AVC encoder (libx264)")
x264 [info]: frame I:11    Avg QP:15.78  size:155205  PSNR Mean Y:48.64 U:53.46 V:52.70 Avg:49.68 Global:49.63
x264 [info]: frame P:348   Avg QP:18.90  size: 52920  PSNR Mean Y:45.51 U:51.09 V:50.15 Avg:46.64 Global:46.58
x264 [info]: frame B:990   Avg QP:20.79  size:  6664  PSNR Mean Y:45.20 U:51.08 V:50.10 Avg:46.36 Global:46.29
x264 [info]: consecutive B-frames:  0.9%  2.5%  3.8% 92.8%
x264 [info]: mb I  I16..4:  8.7% 62.6% 28.8%
x264 [info]: mb P  I16..4:  2.3% 10.6%  2.6%  P16..4: 40.8% 23.2% 11.7%  0.0%  0.0%    skip: 8.8%
x264 [info]: mb B  I16..4:  0.1%  0.6%  0.2%  B16..8: 24.3%  3.3%  0.6%  direct: 1.4%  skip:69.5%  L0:43.0% L1:45.9% BI:11.1%
x264 [info]: final ratefactor: 19.06
x264 [info]: 8x8 transform intra:67.6% inter:68.8%
x264 [info]: coded y,uvDC,uvAC intra: 75.0% 66.3% 31.4% inter: 14.5% 14.5% 1.1%
x264 [info]: i16 v,h,dc,p: 46% 15%  7% 31%
x264 [info]: i8 v,h,dc,ddl,ddr,vr,hd,vl,hu: 30% 12% 13%  5%  7% 11%  6% 10%  6%
x264 [info]: i4 v,h,dc,ddl,ddr,vr,hd,vl,hu: 32% 14% 11%  5%  9% 11%  7%  7%  4%
x264 [info]: i8c dc,h,v,p: 49% 15% 29%  6%
x264 [info]: Weighted P-Frames: Y:0.0% UV:0.0%
x264 [info]: ref P L0: 59.1% 13.5% 19.2%  8.1%
x264 [info]: ref B L0: 89.6%  7.8%  2.6%
x264 [info]: ref B L1: 98.5%  1.5%
x264 [info]: SSIM Mean Y:0.9898061 (19.917db)
x264 [info]: PSNR Mean Y:45.304 U:51.104 V:50.131 Avg:46.461 Global:46.384 kb/s:3961.59
[12:27:21] thread af23338 joined ("Audio decoder (libavcodec)")
[12:27:21] mp2-decoder done: 0 frames, 0 decoder errors, 0 drops
[12:27:21] thread af233d0 joined ("AVCodec Audio encoder (libavcodec)")
[12:27:21] encavcodeca: closing libavcodec
[12:27:21] thread af23500 joined ("Muxer")
[12:27:21] mux: file size, 27115563 bytes
[12:27:21] mux: track 0, 1349 frames, 26720427 bytes, 3958.58 kbps, fifo 2048
[12:27:21] mux: video bitrate error, -279573 bytes
[12:27:21] mux: track 1, 485 frames, 372480 bytes, 55.18 kbps, fifo 8
[12:27:21] mux: overhead, 12.35 bytes per frame
[12:27:21] thread 29c9cf0 joined ("Reader")
[12:27:21] stream: 1578 good frames, 0 errors (0%)
[12:27:21] fifo_close: trashing 0 buffer(s)
[12:27:21] fifo_close: trashing 0 buffer(s)
[12:27:21] fifo_close: trashing 0 buffer(s)
[12:27:21] fifo_close: trashing 0 buffer(s)
[12:27:21] fifo_close: trashing 0 buffer(s)
[12:27:21] fifo_close: trashing 0 buffer(s)
[12:27:21] fifo_close: trashing 0 buffer(s)
[12:27:21] fifo_close: trashing 0 buffer(s)
[12:27:21] fifo_close: trashing 0 buffer(s)
[12:27:21] Freed 32 buffers of size 1024
[12:27:21] Freed 32 buffers of size 2048
[12:27:21] Freed 11 buffers of size 4096
[12:27:21] Freed 20 buffers of size 8192
[12:27:21] Freed 32 buffers of size 16384
[12:27:21] Freed 32 buffers of size 32768
[12:27:21] Freed 32 buffers of size 65536
[12:27:21] Freed 32 buffers of size 131072
[12:27:21] Freed 11 buffers of size 262144
[12:27:21] Freed 32 buffers of size 4194304
[12:27:21] Allocated 153661440 bytes of buffers on this pass and Freed 145272832 bytes, 8388608 bytes leaked
[12:27:21] thread 2e3980 exited ("work")
[12:27:21] thread 2e3980 joined ("work")
[12:27:21] libhb: work result = 0
Encode done!
[12:27:21] thread 2e34a8 exited ("libhb")
[12:27:21] thread 2e34a8 joined ("libhb")
HandBrake has exited.
Last edited by Rodeo on Wed Apr 11, 2012 12:11 pm, edited 1 time in total.
Reason: Placed logs inline, in between [code] [/code] blocks

User avatar
Rodeo
HandBrake Team
Posts: 11188
Joined: Tue Mar 03, 2009 8:55 pm

Re: [svn4585] MPEG-TS audio trouble

Postby Rodeo » Wed Apr 11, 2012 12:14 pm

roadside wrote:The audio encoding is wrong. Other encoder make correct listening experience.


What is your issue exactly: out of sync audio? Or poor audio quality?

roadside
Posts: 12
Joined: Sun Apr 08, 2012 7:21 pm

Re: [svn4585] MPEG-TS audio trouble

Postby roadside » Wed Apr 11, 2012 12:39 pm

Out of sync and sometimes the audio is gone.

User avatar
Rodeo
HandBrake Team
Posts: 11188
Joined: Tue Mar 03, 2009 8:55 pm

Re: [svn4585] MPEG-TS audio trouble

Postby Rodeo » Wed Apr 11, 2012 1:39 pm

OK, thanks for the info. Will look into it when I have more time.

User avatar
JohnAStebbins
HandBrake Team
Posts: 4723
Joined: Sat Feb 09, 2008 7:21 pm

Re: [svn4585] MPEG-TS audio trouble

Postby JohnAStebbins » Wed Apr 11, 2012 4:11 pm

I had a look at this stream. It has dts timestamps that are complete garbage. Normally, the dts timestamp should precede the pts timestamp by a relatively small amount. In this stream, the dts is *after* the pts by a very large amount. It is 2 orders of magnitude larger than the pts. This causes HandBrake to compute a garbage SCR offset that results in timestamps with negative values. We can filter out this kind of nonsense easily enough where we parse the PES timestamps.

I'm curious where this stream comes from. Who is broadcasting such junk?

There are no valid reasons for the dts to ever be larger than the pts. So I can add a simple test for this condition and ignore the dts in such cases.

User avatar
JohnAStebbins
HandBrake Team
Posts: 4723
Joined: Sat Feb 09, 2008 7:21 pm

Re: [svn4585] MPEG-TS audio trouble

Postby JohnAStebbins » Wed Apr 11, 2012 4:45 pm

Give the next nightly build a try
https://trac.handbrake.fr/changeset/4586

User avatar
JohnAStebbins
HandBrake Team
Posts: 4723
Joined: Sat Feb 09, 2008 7:21 pm

Re: [svn4585] MPEG-TS audio trouble

Postby JohnAStebbins » Wed Apr 11, 2012 6:30 pm

I forgot to mention. You should disable the detelecine filter for this video. It is making the output video stutter. It's a known problem with that filter that it can produce stutter when the input is PAL.

roadside
Posts: 12
Joined: Sun Apr 08, 2012 7:21 pm

Re: [svn4585] MPEG-TS audio trouble

Postby roadside » Wed Apr 11, 2012 7:29 pm

This junk is the Hungarian RTL Klub (RTL Group owned http://www.rtlgroup.com/www/htm/home.aspx). The leading European entertainment network. ;-)
I will try the new build.

roadside
Posts: 12
Joined: Sun Apr 08, 2012 7:21 pm

Re: [svn4585] MPEG-TS audio trouble

Postby roadside » Thu Apr 12, 2012 7:05 am

The new build is perfect, everything is ok.


Return to “Bugs”