SVN 4887 - hb_audio_resample errors

mithrandir · Post by **mithrandir** » Thu Aug 09, 2012 2:47 am

Since the latest bump of libav, I am now seeing the following error crop up in some encodes:

ERROR: hb_audio_resample_update: avresample_open() failed
ERROR: hb_audio_resample: resample needed but libavresample context is NULL

In one example this is how I was trying to encode the audio :

Code: Select all

[06:51:42]  * audio track 1
[06:51:42]    + decoder: English (AC3) (5.1 ch) (track 1, id 0x80bd)
[06:51:42]      + bitrate: 448 kbps, samplerate: 48000 Hz
[06:51:42]    + mixdown: Dolby Pro Logic II
[06:51:42]    + encoder: AC3 (ffmpeg)
[06:51:42]      + bitrate: 192 kbps, samplerate: 48000 Hz
[06:51:42]  * audio track 2
[06:51:42]    + decoder: English (AC3) (Dolby Surround) (track 3, id 0x82bd)
[06:51:42]      + bitrate: 192 kbps, samplerate: 48000 Hz
[06:51:42]    + mixdown: Mono
[06:51:42]    + encoder: AAC (ffmpeg)
[06:51:42]      + bitrate: 48 kbps, samplerate: 32000 Hz

The "hb_audio_resample" error message was repeated in the log over 62,000 times and the encode log file itself was over 8MB.

In the resulting output file, the audio for track 1 cuts in and out (with gaps as long as 15-20 seconds, other times just 50-200ms) and track 2 is completely silent.

For audio track 2 (source = AC3 track 3) I tried different audio encoders (AC3, MP3, Ogg), different mixdowns, different samplerates (auto, 48, 32) and the errors always crop up. AC3 passthrough works OK. Audio track 1 plays OK if I passthrough track 2 or omit it completely.

The same options encoded OK in SVN 4582.

Deleted User 11865 · Post by **Deleted User 11865** » Thu Aug 09, 2012 9:03 am

Aha. I'll look into this; but if you have a small sample that reproduces the issue, it may help.

Deleted User 11865 · Post by **Deleted User 11865** » Thu Aug 09, 2012 6:27 pm

As fate would have it, I'm not at home when this happens. I may be able to look into this tomorrow, or it will have to wait until Sunday.

Deleted User 11865 · Post by **Deleted User 11865** » Fri Aug 10, 2012 6:58 pm

No Audio appears to be the same issue.

Deleted User 11865 · Post by **Deleted User 11865** » Fri Aug 10, 2012 7:02 pm

The bad news is, I can't seem to reproduce this issue

It doesn't seem platform-specific either, as I got reports from a Windows and Linux user.

Questions:

1) does this happen for all your sources with AC3 audio?

2) does this happen for other audio sources (e.g. DTS, TrueHD, LPCM, AAC, etc.)?

Tomorrow's nightly will print a bit more information: https://trac.handbrake.fr/changeset/4897

Though I won't be adding additional, temporary logging to non-debug builds.

mithrandir · Post by **mithrandir** » Sat Aug 11, 2012 1:06 am

I've encountered the problem for both DVD and BD sources.

The problematic tracks have always been stereo AC3 secondary tracks (like director commentary). Source track 1 (main audio stream) has never been a problem, whether it is 5.1 or 2.0 or AC3 or DTS. The secondary/commentary tracks aren't always a problem either, only for some. For those tracks that give me problems, I tried making them the only output track but the errors still occurred. It's a source issue rather than an output issue.

randomreuben · Post by **randomreuben** » Sat Aug 11, 2012 1:28 am

I have been away from my computer for a while now, so when I loaded the latest nightly, I got the errors that I got. I went back to the 0.9.8 release and I was able to encode my DVDs again. I only have DVDs and I only encode from AC3 audio. I never select the DTS track. I had the libav issue for all of the DVDs I tried with the nightly.

EDIT: I can't remember if I selected more than 1 AC3 track at a time or not. Perhaps this is causing the issue?

Deleted User 11865 · Post by **Deleted User 11865** » Sat Aug 11, 2012 9:06 am

mithrandir wrote:The secondary/commentary tracks aren't always a problem either, only for some. For those tracks that give me problems, I tried making them the only output track but the errors still occurred. It's a source issue rather than an output issue.

Can you clarify "some", i.e. which BD/DVD(s) did you have an issue with, and which track number(s)?

Deleted User 11865 · Post by **Deleted User 11865** » Sat Aug 11, 2012 9:14 am

OK, what's causing the issue just occurred to me (downmixing AV_CH_LAYOUT_STEREO_DONMIX to AV_CH_LAYOUT_STEREO, which happens whne the source is Dolby Surround). I didn't try it, but I'm pretty sure I should be able reproduce it now. The fix is easy, but will have to wait until later today when I get home.

Deleted User 11865 · Post by **Deleted User 11865** » Sat Aug 11, 2012 9:26 am

Nevermind, I did it right away: https://trac.handbrake.fr/changeset/4898

It should work now (i.e. in the next nightly).

mithrandir · Post by **mithrandir** » Sun Aug 12, 2012 2:03 pm

I installed the 4899 CLI and retried my two sources. Unfortunately I'm having a hard time getting consistent reportable results, it's a mess.

I no longer see "hb_audio_resample" errors in the logs. That's the most consistent change.

When I tried my BD source (The Tree Of Life), the problem track was no longer silent but sounded like a buzzsaw. You do hear the underlying audio but it buzzes.

I then tried my DVD source (Step Into Liquid). The problem track encodes but I encountered a lot of strange behavior. At first I made the mistake of selecting DPLII mixdown. Since the source is Dolby Surround, HB corrected this to Dolby Surround (according to the log) but the output was strange: audio came out of the right channel only. I then changed the mixdown to Mono and it encoded properly.

This disc has 2 secondary tracks (both Dolby Surround) but when I tried to encode all 3 tracks (primary 5.1 + two 2.0) HB would start but terminate after a few seconds. Nothing of interest in the log:

Code: Select all

[09:03:09] mpeg2: "Chapter 1" (1) at frame 0 time 9009
[09:03:09] thread 4d58cf8 started ("AVCodec Audio encoder (libavcodec)")
[09:03:09] thread 4d58d90 started ("Video Synchronization")
[09:03:09] thread 4d58e28 started ("Muxer")
[09:03:09] thread 4d58ec0 started ("Muxer")
[09:03:09] thread 4d58f58 started ("Muxer")
[09:03:09] sync: first pts is 9009

Only after I removed source track 2 could I get the encoding process to start again. However now the encoded audio output was loud square wave noise. This was the same audio setup that had worked earlier (when I changed the mixdown to Mono). No consistent results.

Back to BD. I re-tried the previous setup where the audio output had that buzzing character. This time around it wasn't a problem, the audio was fine.

I know these kinds of details aren't really helpful in diagnosing the root cause but I have to report that problems relating to these AC3 tracks remain.

Deleted User 11865 · Post by **Deleted User 11865** » Sun Aug 12, 2012 5:57 pm

Could you stop providing snippets, and give full logs so that we can see what's going on?

mithrandir · Post by **mithrandir** » Mon Aug 13, 2012 2:11 am

Here's the log from the BD encode where I encountered the buzzing audio in output track 2. I manually terminated the process after about 11 minutes.

Code: Select all

HandBrake svn4899 2012081201
OS: Microsoft Windows NT 6.1.7601 Service Pack 1
CPU: Intel(R) Core(TM)2 CPU          6300  @ 1.86GHz
Ram: 3069 MB, Screen: 1920x1080
Temp Dir: C:\Users\default\AppData\Local\Temp\
Install Dir: C:\Program Files\Handbrake 4877
Data Dir: C:\Users\default\AppData\Roaming\HandBrake\HandBrake\0.9.8.4877

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

CLI Query:  -i "L:\BDRips\The Tree Of Life" -t 1 --angle 1 -c 1-25 -o "L:\H.264\The Tree Of Life-HD21.mkv"  -f mkv  -w 1280 --loose-anamorphic  --modulus 16 -e x264 -q 21 --vfr -a 2,3 -E ffac3,ffaac -6 dpl2,mono -R Auto,32 -B 224,48 -D 0,0 --gain 0,0 --audio-copy-mask none --audio-fallback ffac3 --markers="C:\Users\default\AppData\Local\Temp\The Tree Of Life-HD21-1-chapters.csv" -x rc-lookahead=300:vbv-maxrate=40000:vbv-bufsize=30000:bframes=5:b-adapt=2:subme=8:aq-strength=0.8:psy-rd=1.0,0.15 --verbose=2
User Query: False

[08:46:31] hb_init: starting libhb thread
[08:46:31] thread 1c32b20 started ("libhb")
HandBrake svn4899 (2012081201) - MinGW i686 - http://handbrake.fr
2 CPUs detected
Opening L:\BDRips\The Tree Of Life...
[08:46:31] hb_scan: path=L:\BDRips\The Tree Of Life, title_index=1
[08:46:31] thread 1c32e18 started ("scan")
[08:46:31] scan: BD has 1 title(s)
[08:46:32] bd: scanning title 1
[08:46:32] bd: playlist 00000.MPLS
[08:46:32] bd: duration is 02:18:54 (8334326 ms)
[08:46:32] bd: video id=0x1011, stream type=H.264, format 1080p
[08:46:32] bd: aspect = 1.77778
[08:46:32] bd: audio id=0x711100, lang=English (DTS), 3cc=eng
[08:46:32] bd: audio id=0x1100, lang=English (DTS-HD MA), 3cc=eng
[08:46:32] bd: audio id=0x1101, lang=English (AC3), 3cc=eng
[08:46:32] bd: subtitle id=0x1200, lang=English, 3cc=eng
[08:46:32] bd: subtitle id=0x1201, lang=Espanol, 3cc=spa
[08:46:32] bd: chap 1 packet=768, 250333 ms
[08:46:32] bd: chap 2 packet=1036387968, 423005 ms
[08:46:32] bd: chap 3 packet=3460386816, 506631 ms
[08:46:32] bd: chap 4 packet=6198095040, 1038454 ms
[08:46:32] bd: chap 5 packet=10882968960, 700032 ms
[08:46:32] bd: chap 6 packet=14893119744, 408282 ms
[08:46:32] bd: chap 7 packet=17274917568, 203578 ms
[08:46:32] bd: chap 8 packet=18420212160, 638054 ms
[08:46:32] bd: chap 9 packet=22002513792, 84501 ms
[08:46:32] bd: chap 10 packet=22460314944, 296838 ms
[08:46:32] bd: chap 11 packet=24106286016, 240865 ms
[08:46:32] bd: chap 12 packet=25430066496, 309934 ms
[08:46:32] bd: chap 13 packet=27041829888, 91591 ms
[08:46:32] bd: chap 14 packet=27535013760, 239322 ms
[08:46:32] bd: chap 15 packet=28790088960, 403736 ms
[08:46:32] bd: chap 16 packet=30982905024, 148439 ms
[08:46:32] bd: chap 17 packet=31751510400, 112278 ms
[08:46:32] bd: chap 18 packet=32380838784, 332623 ms
[08:46:32] bd: chap 19 packet=34189036032, 409534 ms
[08:46:32] bd: chap 20 packet=36538801728, 255171 ms
[08:46:32] bd: chap 21 packet=37911615360, 151901 ms
[08:46:32] bd: chap 22 packet=38726936064, 664789 ms
[08:46:32] bd: chap 23 packet=42346218816, 74741 ms
[08:46:32] bd: chap 24 packet=42624836352, 348681 ms
[08:46:32] bd: chap 25 packet=43111600512, 1001 ms
[08:46:32] bd: title 1 has 25 chapters
[08:46:32] scan: decoding previews for title 1
[08:46:32] scan: title angle(s) 1
[08:46:32] scan: preview 1
[08:46:32] bd: Playitem 0
Scanning title 1...
[08:46:32] fifo_close: trashing 0 buffer(s)
[08:46:32] scan: audio 0x1101: AC-3, rate=48000Hz, bitrate=224000 English (AC3) (Dolby Surround)
[08:46:32] fifo_close: trashing 0 buffer(s)
[08:46:32] scan: audio 0x711100: dca, rate=48000Hz, bitrate=1536000 English (DTS) (5.1 ch)
[08:46:32] fifo_close: trashing 0 buffer(s)
[08:46:32] scan: audio 0x1100: dca, rate=48000Hz, bitrate=1536000 English (DTS-HD MA) (5.1 ch)
Scanning title 1...
[08:46:32] scan: preview 2
Scanning title 1...
[08:46:32] scan: preview 3
Scanning title 1...
[08:46:32] scan: preview 4
[08:46:32] scan: preview 5
Scanning title 1...
[08:46:33] scan: preview 6
Scanning title 1...
[08:46:33] scan: preview 7
Scanning title 1...
[08:46:33] scan: preview 8
Scanning title 1...
[08:46:33] scan: preview 9
[08:46:33] scan: preview 10
Scanning title 1...
Scanning title 1...
[08:46:34] scan: 10 previews, 1920x1080, 23.976 fps, autocrop = 20/20/2/2, aspect 16:9, PAR 1:1
[08:46:34] stream: 393 good frames, 0 errors (0%)
[08:46:34] scan: title (0) job->width:1920, job->height:1040
[08:46:34] thread 1c32e18 exited ("scan")
[08:46:34] thread 1c32e18 joined ("scan")
[08:46:34] libhb: scan thread found 1 valid title(s)
+ title 1:
  + playlist: 00000.MPLS
  + duration: 02:18:54
  + size: 1920x1080, pixel aspect: 1/1, display aspect: 1.78, 23.976 fps
  + autocrop: 20/20/2/2
  + chapters:
    + 1: cells 0->0, 0 blocks, duration 00:04:10
    + 2: cells 0->0, 0 blocks, duration 00:07:03
    + 3: cells 0->0, 0 blocks, duration 00:08:27
    + 4: cells 0->0, 0 blocks, duration 00:17:18
    + 5: cells 0->0, 0 blocks, duration 00:11:40
    + 6: cells 0->0, 0 blocks, duration 00:06:48
    + 7: cells 0->0, 0 blocks, duration 00:03:24
    + 8: cells 0->0, 0 blocks, duration 00:10:38
    + 9: cells 0->0, 0 blocks, duration 00:01:25
    + 10: cells 0->0, 0 blocks, duration 00:04:57
    + 11: cells 0->0, 0 blocks, duration 00:04:01
    + 12: cells 0->0, 0 blocks, duration 00:05:10
    + 13: cells 0->0, 0 blocks, duration 00:01:32
    + 14: cells 0->0, 0 blocks, duration 00:03:59
    + 15: cells 0->0, 0 blocks, duration 00:06:44
    + 16: cells 0->0, 0 blocks, duration 00:02:28
    + 17: cells 0->0, 0 blocks, duration 00:01:52
    + 18: cells 0->0, 0 blocks, duration 00:05:33
    + 19: cells 0->0, 0 blocks, duration 00:06:50
    + 20: cells 0->0, 0 blocks, duration 00:04:15
    + 21: cells 0->0, 0 blocks, duration 00:02:32
    + 22: cells 0->0, 0 blocks, duration 00:11:05
    + 23: cells 0->0, 0 blocks, duration 00:01:15
    + 24: cells 0->0, 0 blocks, duration 00:05:49
    + 25: cells 0->0, 0 blocks, duration 00:00:01
  + audio tracks:
    + 1, English (DTS) (5.1 ch) (iso639-2: eng), 48000Hz, 1536000bps
    + 2, English (DTS-HD MA) (5.1 ch) (iso639-2: eng)
    + 3, English (AC3) (Dolby Surround) (iso639-2: eng), 48000Hz, 224000bps
  + subtitle tracks:
    + 1, English (iso639-2: eng) (Bitmap)(PGS)
    + 2, Espanol (iso639-2: spa) (Bitmap)(PGS)
Reading chapter markers from file C:\Users\default\AppData\Local\Temp\The Tree Of Life-HD21-1-chapters.csv
[08:46:34] thread 1c32e18 started ("work")
[08:46:34] 1 job(s) to process
[08:46:34] starting job
[08:46:34] sync: expecting 199824 video frames
[08:46:34] job configuration:
[08:46:34]  * source
[08:46:34]    + L:\BDRips\The Tree Of Life
[08:46:34]    + title 1, chapter(s) 1 to 25
[08:46:34]  * destination
[08:46:34]    + L:\H.264\The Tree Of Life-HD21.mkv
[08:46:34]    + container: Matroska (.mkv)
[08:46:34]      + chapter markers
[08:46:34]  * video track
[08:46:34]    + decoder: h264
[08:46:34]      + bitrate 200 kbps
[08:46:34]    + frame rate: same as source (around 23.976 fps)
[08:46:34]    + filters
[08:46:34]      + Framerate Shaper (0:27000000:1126125)
[08:46:34]        + frame rate: same as source (around 23.976 fps)
[08:46:34]      + Crop and Scale (1280:688:20:20:2:2)
[08:46:34]        + source: 1920 * 1080, crop (20/20/2/2): 1916 * 1040, scale: 1280 * 688
[08:46:34]    + loose anamorphic
[08:46:34]      + storage dimensions: 1280 * 688, mod 16
[08:46:34]      + pixel aspect ratio: 20597 / 20800
[08:46:34]      + display dimensions: 1267 * 688
[08:46:34]    + encoder: H.264 (x264)
[08:46:34]      + options: rc-lookahead=300:vbv-maxrate=40000:vbv-bufsize=30000:bframes=5:b-adapt=2:subme=8:aq-strength=0.8:psy-rd=1.0,0.15
[08:46:34]      + quality: 21.00 (RF)
[08:46:34]  * audio track 1
[08:46:34]    + decoder: English (DTS-HD MA) (5.1 ch) (track 2, id 0x1100)
[08:46:34]      + bitrate: 1536 kbps, samplerate: 48000 Hz
[08:46:34]    + mixdown: Dolby Pro Logic II
[08:46:34]    + encoder: AC3 (ffmpeg)
[08:46:34]      + bitrate: 224 kbps, samplerate: 48000 Hz
[08:46:34]  * audio track 2
[08:46:34]    + decoder: English (AC3) (Dolby Surround) (track 3, id 0x1101)
[08:46:34]      + bitrate: 224 kbps, samplerate: 48000 Hz
[08:46:34]    + mixdown: Mono
[08:46:34]    + encoder: AAC (ffmpeg)
[08:46:34]      + bitrate: 48 kbps, samplerate: 32000 Hz
[08:46:34] thread 1c5e160 started ("Reader")
[08:46:34] thread 1c5e1f8 started ("Framerate Shaper")
[08:46:34] thread 1c59660 started ("Crop and Scale")
[08:46:34] thread 1c596f8 started ("AudioSynchronization")
[08:46:34] thread 1c59790 started ("AudioSynchronization")
[08:46:34] thread 1c33cb0 started ("Video decoder (libavcodec)")
[08:46:34] encx264: min-keyint: 24, keyint: 240
[08:46:34] encx264: encoding with stored aspect 20597/20800
[08:46:34] encx264: Encoding at constant RF 21.000000
[08:46:34] 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=20597/20800
x264 [info]: using cpu capabilities: MMX2 SSE2Fast SSSE3 Cache64
x264 [info]: profile High, level 4.1
[08:46:34] thread 280a7f8 started ("H.264/AVC encoder (libx264)")
[08:46:34] thread 41e1860 started ("Audio decoder (libavcodec)")
[08:46:34] thread 422b220 started ("AVCodec Audio encoder (libavcodec)")
[08:46:34] thread 422b2b8 started ("AC3 decoder")
[08:46:34] thread 422b350 started ("AVCodec Audio encoder (libavcodec)")
[08:46:34] thread 422b3e8 started ("Video Synchronization")
[08:46:34] bd: Playitem 0
[08:46:34] reader: first SCR 176246 id 0x1011 DTS 176246
[08:46:34] h264: "Chapter 1" (1) at frame 0 time 3754
[08:46:34] thread 422b480 started ("Muxer")
[08:46:34] thread 422b518 started ("Muxer")
[08:46:34] sync: first pts is 3754

Deleted User 11865 · Post by **Deleted User 11865** » Mon Aug 13, 2012 2:16 am

Lo and behold, different issue!

If it's what I think it is, you can work around it by not resampling to 32 kHz.

Otherwise please provide another log for a completed encode (you can encode just a chapter or two to speed up the process).

mithrandir · Post by **mithrandir** » Tue Aug 14, 2012 2:17 am

Here's the full log for a single chapter encode; track 2 has the buzzing character. I also tried an encode with samplerate set to Auto but the buzzing sound remained.

Code: Select all

HandBrake svn4899 2012081201
OS: Microsoft Windows NT 6.1.7601 Service Pack 1
CPU: Intel(R) Core(TM)2 CPU          6300  @ 1.86GHz
Ram: 3069 MB, Screen: 1920x1080
Temp Dir: C:\Users\default\AppData\Local\Temp\
Install Dir: C:\Program Files\Handbrake 4877
Data Dir: C:\Users\default\AppData\Roaming\HandBrake\HandBrake\0.9.8.4877

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

CLI Query:  -i "L:\BDRips\The Tree Of Life" -t 1 --angle 1 -c 1 -o "L:\H.264\The Tree Of Life-1.mkv"  -f mkv  -w 1280 --loose-anamorphic  --modulus 16 -e x264 -q 21 --vfr -a 2,3 -E ffac3,ffaac -6 dpl2,mono -R Auto,32 -B 224,48 -D 0,0 --gain 0,0 --audio-copy-mask none --audio-fallback ffac3 --markers="C:\Users\default\AppData\Local\Temp\The Tree Of Life-1-1-chapters.csv" -x rc-lookahead=300:vbv-maxrate=40000:vbv-bufsize=30000:bframes=5:b-adapt=2:subme=8:aq-strength=0.8:psy-rd=1.0,0.15 --verbose=2
User Query: False

[20:37:44] hb_init: starting libhb thread
[20:37:44] thread 2e2ab0 started ("libhb")
HandBrake svn4899 (2012081201) - MinGW i686 - http://handbrake.fr
2 CPUs detected
Opening L:\BDRips\The Tree Of Life...
[20:37:44] hb_scan: path=L:\BDRips\The Tree Of Life, title_index=1
[20:37:44] thread 2e35a8 started ("scan")
[20:37:44] scan: BD has 1 title(s)
[20:37:44] bd: scanning title 1
[20:37:44] bd: playlist 00000.MPLS
[20:37:44] bd: duration is 02:18:54 (8334326 ms)
[20:37:44] bd: video id=0x1011, stream type=H.264, format 1080p
[20:37:44] bd: aspect = 1.77778
[20:37:44] bd: audio id=0x711100, lang=English (DTS), 3cc=eng
[20:37:44] bd: audio id=0x1100, lang=English (DTS-HD MA), 3cc=eng
[20:37:44] bd: audio id=0x1101, lang=English (AC3), 3cc=eng
[20:37:44] bd: subtitle id=0x1200, lang=English, 3cc=eng
[20:37:44] bd: subtitle id=0x1201, lang=Espanol, 3cc=spa
[20:37:44] bd: chap 1 packet=768, 250333 ms
[20:37:44] bd: chap 2 packet=1036387968, 423005 ms
[20:37:44] bd: chap 3 packet=3460386816, 506631 ms
[20:37:44] bd: chap 4 packet=6198095040, 1038454 ms
[20:37:44] bd: chap 5 packet=10882968960, 700032 ms
[20:37:44] bd: chap 6 packet=14893119744, 408282 ms
[20:37:44] bd: chap 7 packet=17274917568, 203578 ms
[20:37:44] bd: chap 8 packet=18420212160, 638054 ms
[20:37:44] bd: chap 9 packet=22002513792, 84501 ms
[20:37:44] bd: chap 10 packet=22460314944, 296838 ms
[20:37:44] bd: chap 11 packet=24106286016, 240865 ms
[20:37:44] bd: chap 12 packet=25430066496, 309934 ms
[20:37:44] bd: chap 13 packet=27041829888, 91591 ms
[20:37:44] bd: chap 14 packet=27535013760, 239322 ms
[20:37:44] bd: chap 15 packet=28790088960, 403736 ms
[20:37:44] bd: chap 16 packet=30982905024, 148439 ms
[20:37:44] bd: chap 17 packet=31751510400, 112278 ms
[20:37:44] bd: chap 18 packet=32380838784, 332623 ms
[20:37:44] bd: chap 19 packet=34189036032, 409534 ms
[20:37:44] bd: chap 20 packet=36538801728, 255171 ms
[20:37:44] bd: chap 21 packet=37911615360, 151901 ms
[20:37:44] bd: chap 22 packet=38726936064, 664789 ms
[20:37:44] bd: chap 23 packet=42346218816, 74741 ms
[20:37:44] bd: chap 24 packet=42624836352, 348681 ms
[20:37:44] bd: chap 25 packet=43111600512, 1001 ms
[20:37:44] bd: title 1 has 25 chapters
[20:37:44] scan: decoding previews for title 1
[20:37:44] scan: title angle(s) 1
[20:37:44] scan: preview 1
[20:37:44] bd: Playitem 0
Scanning title 1...
[20:37:44] fifo_close: trashing 0 buffer(s)
[20:37:44] scan: audio 0x1101: AC-3, rate=48000Hz, bitrate=224000 English (AC3) (Dolby Surround)
[20:37:44] fifo_close: trashing 0 buffer(s)
[20:37:44] scan: audio 0x711100: dca, rate=48000Hz, bitrate=1536000 English (DTS) (5.1 ch)
[20:37:44] fifo_close: trashing 0 buffer(s)
[20:37:44] scan: audio 0x1100: dca, rate=48000Hz, bitrate=1536000 English (DTS-HD MA) (5.1 ch)
Scanning title 1...
[20:37:44] scan: preview 2
Scanning title 1...
[20:37:45] scan: preview 3
[20:37:45] scan: preview 4
Scanning title 1...
[20:37:45] scan: preview 5
Scanning title 1...
[20:37:45] scan: preview 6
[20:37:45] scan: preview 7
Scanning title 1...
[20:37:45] scan: preview 8
Scanning title 1...
[20:37:46] scan: preview 9
Scanning title 1...
[20:37:46] scan: preview 10
Scanning title 1...
Scanning title 1...
[20:37:46] scan: 10 previews, 1920x1080, 23.976 fps, autocrop = 20/20/2/2, aspect 16:9, PAR 1:1
[20:37:46] stream: 393 good frames, 0 errors (0%)
[20:37:46] scan: title (0) job->width:1920, job->height:1040
[20:37:46] thread 2e35a8 exited ("scan")
[20:37:46] thread 2e35a8 joined ("scan")
[20:37:46] libhb: scan thread found 1 valid title(s)
+ title 1:
  + playlist: 00000.MPLS
  + duration: 02:18:54
  + size: 1920x1080, pixel aspect: 1/1, display aspect: 1.78, 23.976 fps
  + autocrop: 20/20/2/2
  + chapters:
    + 1: cells 0->0, 0 blocks, duration 00:04:10
    + 2: cells 0->0, 0 blocks, duration 00:07:03
    + 3: cells 0->0, 0 blocks, duration 00:08:27
    + 4: cells 0->0, 0 blocks, duration 00:17:18
    + 5: cells 0->0, 0 blocks, duration 00:11:40
    + 6: cells 0->0, 0 blocks, duration 00:06:48
    + 7: cells 0->0, 0 blocks, duration 00:03:24
    + 8: cells 0->0, 0 blocks, duration 00:10:38
    + 9: cells 0->0, 0 blocks, duration 00:01:25
    + 10: cells 0->0, 0 blocks, duration 00:04:57
    + 11: cells 0->0, 0 blocks, duration 00:04:01
    + 12: cells 0->0, 0 blocks, duration 00:05:10
    + 13: cells 0->0, 0 blocks, duration 00:01:32
    + 14: cells 0->0, 0 blocks, duration 00:03:59
    + 15: cells 0->0, 0 blocks, duration 00:06:44
    + 16: cells 0->0, 0 blocks, duration 00:02:28
    + 17: cells 0->0, 0 blocks, duration 00:01:52
    + 18: cells 0->0, 0 blocks, duration 00:05:33
    + 19: cells 0->0, 0 blocks, duration 00:06:50
    + 20: cells 0->0, 0 blocks, duration 00:04:15
    + 21: cells 0->0, 0 blocks, duration 00:02:32
    + 22: cells 0->0, 0 blocks, duration 00:11:05
    + 23: cells 0->0, 0 blocks, duration 00:01:15
    + 24: cells 0->0, 0 blocks, duration 00:05:49
    + 25: cells 0->0, 0 blocks, duration 00:00:01
  + audio tracks:
    + 1, English (DTS) (5.1 ch) (iso639-2: eng), 48000Hz, 1536000bps
    + 2, English (DTS-HD MA) (5.1 ch) (iso639-2: eng)
    + 3, English (AC3) (Dolby Surround) (iso639-2: eng), 48000Hz, 224000bps
  + subtitle tracks:
    + 1, English (iso639-2: eng) (Bitmap)(PGS)
    + 2, Espanol (iso639-2: spa) (Bitmap)(PGS)
Reading chapter markers from file C:\Users\default\AppData\Local\Temp\The Tree Of Life-1-1-chapters.csv
[20:37:46] thread 2e35a8 started ("work")
[20:37:46] 1 job(s) to process
[20:37:46] starting job
[20:37:46] sync: expecting 6001 video frames
[20:37:46] work: only 1 chapter, disabling chapter markers
[20:37:46] job configuration:
[20:37:46]  * source
[20:37:46]    + L:\BDRips\The Tree Of Life
[20:37:46]    + title 1, chapter(s) 1 to 1
[20:37:46]  * destination
[20:37:46]    + L:\H.264\The Tree Of Life-1.mkv
[20:37:46]    + container: Matroska (.mkv)
[20:37:46]  * video track
[20:37:46]    + decoder: h264
[20:37:46]      + bitrate 200 kbps
[20:37:46]    + frame rate: same as source (around 23.976 fps)
[20:37:46]    + filters
[20:37:46]      + Framerate Shaper (0:27000000:1126125)
[20:37:46]        + frame rate: same as source (around 23.976 fps)
[20:37:46]      + Crop and Scale (1280:688:20:20:2:2)
[20:37:46]        + source: 1920 * 1080, crop (20/20/2/2): 1916 * 1040, scale: 1280 * 688
[20:37:46]    + loose anamorphic
[20:37:46]      + storage dimensions: 1280 * 688, mod 16
[20:37:46]      + pixel aspect ratio: 20597 / 20800
[20:37:46]      + display dimensions: 1267 * 688
[20:37:46]    + encoder: H.264 (x264)
[20:37:46]      + options: rc-lookahead=300:vbv-maxrate=40000:vbv-bufsize=30000:bframes=5:b-adapt=2:subme=8:aq-strength=0.8:psy-rd=1.0,0.15
[20:37:46]      + quality: 21.00 (RF)
[20:37:46]  * audio track 1
[20:37:46]    + decoder: English (DTS-HD MA) (5.1 ch) (track 2, id 0x1100)
[20:37:46]      + bitrate: 1536 kbps, samplerate: 48000 Hz
[20:37:46]    + mixdown: Dolby Pro Logic II
[20:37:46]    + encoder: AC3 (ffmpeg)
[20:37:46]      + bitrate: 224 kbps, samplerate: 48000 Hz
[20:37:46]  * audio track 2
[20:37:46]    + decoder: English (AC3) (Dolby Surround) (track 3, id 0x1101)
[20:37:46]      + bitrate: 224 kbps, samplerate: 48000 Hz
[20:37:46]    + mixdown: Mono
[20:37:46]    + encoder: AAC (ffmpeg)
[20:37:46]      + bitrate: 48 kbps, samplerate: 32000 Hz
[20:37:46] thread 309708 started ("Reader")
[20:37:46] thread 28bae10 started ("Framerate Shaper")
[20:37:46] thread 3092e0 started ("Crop and Scale")
[20:37:46] thread 28baea8 started ("AudioSynchronization")
[20:37:46] thread 28bac90 started ("AudioSynchronization")
[20:37:46] bd: Playitem 0
[20:37:46] reader: first SCR 176246 id 0x1011 DTS 176246
[20:37:46] thread 29852b8 started ("Video decoder (libavcodec)")
[20:37:46] encx264: min-keyint: 24, keyint: 240
[20:37:46] encx264: encoding with stored aspect 20597/20800
[20:37:46] encx264: Encoding at constant RF 21.000000
[20:37:46] 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=20597/20800
x264 [info]: using cpu capabilities: MMX2 SSE2Fast SSSE3 Cache64
[20:37:46] h264: "Chapter 1" (1) at frame 0 time 3754
x264 [info]: profile High, level 4.1
[20:37:46] thread 29855b0 started ("H.264/AVC encoder (libx264)")
[20:37:46] thread 2985648 started ("Audio decoder (libavcodec)")
[20:37:46] thread 29856e0 started ("AVCodec Audio encoder (libavcodec)")
[20:37:46] thread 2985778 started ("AC3 decoder")
[20:37:46] thread 2985810 started ("AVCodec Audio encoder (libavcodec)")
[20:37:46] thread 29858a8 started ("Video Synchronization")
[20:37:46] thread 2985940 started ("Muxer")
[20:37:46] thread 29859d8 started ("Muxer")
[20:37:46] sync: first pts is 3754
[20:55:18] reader: end of chapter 1 (media 1) reached at media chapter 2
[20:55:19] reader: done. 3 scr changes
[20:55:19] thread 309708 exited ("Reader")
[20:56:15] work: average encoding speed for job is 5.638218 fps
[20:56:15] thread 28bae10 exited ("Framerate Shaper")
[20:56:15] thread 29855b0 exited ("H.264/AVC encoder (libx264)")
[20:56:15] thread 29858a8 exited ("Video Synchronization")
[20:56:15] thread 29859d8 exited ("Muxer")
[20:56:15] thread 2985778 exited ("AC3 decoder")
[20:56:15] thread 2985648 exited ("Audio decoder (libavcodec)")
[20:56:15] thread 29852b8 exited ("Video decoder (libavcodec)")
[20:56:15] thread 28baea8 exited ("AudioSynchronization")
[20:56:15] thread 29858a8 joined ("Video Synchronization")
[20:56:15] sync: got 6002 frames, 6001 expected
[20:56:15] thread 28bae10 joined ("Framerate Shaper")
[20:56:15] render: lost time: 0 (0 frames)
[20:56:15] render: gained time: 0 (0 frames) (0 not accounted for)
[20:56:15] fifo_close: trashing 0 buffer(s)
[20:56:15] thread 2985940 exited ("Muxer")
[20:56:15] thread 28bac90 exited ("AudioSynchronization")
[20:56:15] thread 29856e0 exited ("AVCodec Audio encoder (libavcodec)")
[20:56:15] thread 2985810 exited ("AVCodec Audio encoder (libavcodec)")
[20:56:15] thread 3092e0 exited ("Crop and Scale")
[20:56:15] thread 3092e0 joined ("Crop and Scale")
[20:56:15] thread 28baea8 joined ("AudioSynchronization")
[20:56:15] thread 28bac90 joined ("AudioSynchronization")
[20:56:15] thread 29852b8 joined ("Video decoder (libavcodec)")
[20:56:15] h264-decoder done: 6002 frames, 0 decoder errors, 0 drops
[20:56:15] thread 29855b0 joined ("H.264/AVC encoder (libx264)")
x264 [info]: frame I:36    Avg QP:17.07  size:126247  PSNR Mean Y:51.50 U:55.72 V:56.09 Avg:52.31 Global:46.22
x264 [info]: frame P:1324  Avg QP:22.44  size: 36230  PSNR Mean Y:49.99 U:55.05 V:55.84 Avg:50.45 Global:43.59
x264 [info]: frame B:4642  Avg QP:24.78  size:  7553  PSNR Mean Y:51.62 U:57.40 V:58.37 Avg:52.03 Global:42.89
x264 [info]: consecutive B-frames:  2.1%  2.2%  7.1% 21.4% 14.9% 52.2%
x264 [info]: mb I  I16..4: 28.2% 54.7% 17.1%
x264 [info]: mb P  I16..4:  2.7%  9.1%  1.8%  P16..4: 28.0% 15.6%  9.6%  0.0%  0.0%    skip:33.1%
x264 [info]: mb B  I16..4:  0.2%  0.8%  0.2%  B16..8: 29.6%  4.3%  1.3%  direct: 2.6%  skip:61.1%  L0:40.6% L1:46.1% BI:13.2%
x264 [info]: 8x8 transform intra:65.4% inter:69.0%
x264 [info]: coded y,uvDC,uvAC intra: 73.8% 67.9% 37.3% inter: 17.4% 14.3% 1.8%
x264 [info]: i16 v,h,dc,p: 56% 13%  4% 27%
x264 [info]: i8 v,h,dc,ddl,ddr,vr,hd,vl,hu:  8%  8%  6% 11% 15% 13% 14% 12% 12%
x264 [info]: i4 v,h,dc,ddl,ddr,vr,hd,vl,hu:  8%  8%  5% 10% 15% 14% 14% 12% 13%
x264 [info]: i8c dc,h,v,p: 47% 22% 17% 14%
x264 [info]: Weighted P-Frames: Y:9.4% UV:4.6%
x264 [info]: ref P L0: 58.3% 18.2% 18.4%  4.9%  0.2%
x264 [info]: ref B L0: 92.6%  6.1%  1.2%
x264 [info]: ref B L1: 96.9%  3.1%
x264 [info]: SSIM Mean Y:0.9725649 (15.617db)
x264 [info]: PSNR Mean Y:51.260 U:56.868 V:57.798 Avg:51.682 Global:43.050 kb/s:2798.52
[20:56:15] thread 2985648 joined ("Audio decoder (libavcodec)")
[20:56:15] dca-decoder done: 0 frames, 0 decoder errors, 0 drops
[20:56:15] thread 29856e0 joined ("AVCodec Audio encoder (libavcodec)")
[20:56:15] encavcodeca: closing libavcodec
[20:56:15] thread 2985778 joined ("AC3 decoder")
[20:56:15] thread 2985810 joined ("AVCodec Audio encoder (libavcodec)")
[20:56:15] encavcodeca: closing libavcodec
[20:56:15] thread 2985940 joined ("Muxer")
[20:56:15] thread 29859d8 joined ("Muxer")
[20:56:16] mux: file size, 96283280 bytes
[20:56:16] mux: track 0, 6002 frames, 87571363 bytes, 2798.55 kbps, fifo 4096
[20:56:16] mux: track 1, 7804 frames, 6992384 bytes, 223.46 kbps, fifo 4096
[20:56:16] mux: track 2, 7815 frames, 1477576 bytes, 47.22 kbps, fifo 4096
[20:56:16] mux: overhead, 11.19 bytes per frame
[20:56:16] thread 309708 joined ("Reader")
[20:56:16] stream: 6003 good frames, 0 errors (0%)
[20:56:16] fifo_close: trashing 0 buffer(s)
[20:56:16] fifo_close: trashing 0 buffer(s)
[20:56:16] fifo_close: trashing 0 buffer(s)
[20:56:16] fifo_close: trashing 0 buffer(s)
[20:56:16] fifo_close: trashing 0 buffer(s)
[20:56:16] fifo_close: trashing 0 buffer(s)
[20:56:16] fifo_close: trashing 0 buffer(s)
[20:56:16] fifo_close: trashing 0 buffer(s)
[20:56:16] fifo_close: trashing 0 buffer(s)
[20:56:16] fifo_close: trashing 0 buffer(s)
[20:56:16] fifo_close: trashing 0 buffer(s)
[20:56:16] fifo_close: trashing 0 buffer(s)
[20:56:16] fifo_close: trashing 0 buffer(s)
[20:56:16] Freed 32 buffers of size 1024
[20:56:16] Freed 32 buffers of size 2048
[20:56:16] Freed 32 buffers of size 4096
[20:56:16] Freed 32 buffers of size 8192
[20:56:16] Freed 32 buffers of size 16384
[20:56:16] Freed 32 buffers of size 32768
[20:56:16] Freed 32 buffers of size 65536
[20:56:16] Freed 32 buffers of size 131072
[20:56:16] Freed 32 buffers of size 262144
[20:56:16] Freed 12 buffers of size 524288
[20:56:16] Freed 4 buffers of size 1048576
[20:56:16] Freed 32 buffers of size 2097152
[20:56:16] Freed 32 buffers of size 4194304
[20:56:16] Allocated 228556800 bytes of buffers on this pass and Freed 228556800 bytes, 0 bytes leaked
[20:56:16] thread 2e35a8 exited ("work")
[20:56:16] thread 2e35a8 joined ("work")
[20:56:16] libhb: work result = 0
Encode done!
[20:56:16] thread 2e2ab0 exited ("libhb")
[20:56:16] thread 2e2ab0 joined ("libhb")
HandBrake has exited.

Deleted User 11865 · Post by **Deleted User 11865** » Tue Aug 14, 2012 3:19 pm

What about if you use another encoder (such as FLAC), does the audio buzz too?

mithrandir · Post by **mithrandir** » Wed Aug 15, 2012 1:38 am

Using FLAC, changed samplerate to Auto.

Mono mixdown: Tried twice (same settings). First time the buzzing was consistent in pitch, the second time the pitch varied like you were hitting piano keys. In both cases, the audio was just the buzzing sound and the original audio did not come through at all. The important point though is that the output varies. You can do an encode, play it back, re-encode (just clicking the start button), play it back and the output is not always the same. I noticed this when I encoded with AAC as well. The CLI is a separate process that gets killed when an encode completes so I don't see how this could be a memory leak or something like that.

Automatic or stereo mixdown: good, clear audio but only out of the right channel.

mithrandir · Post by **mithrandir** » Wed Aug 15, 2012 1:59 am

As a sanity check I re-installed SVN 4829 and using the same settings the audio in the second track was correct. As a validation the primary track was reduced in volume which was typical for non-AC3 sources for that build.

Deleted User 11865 · Post by **Deleted User 11865** » Wed Aug 15, 2012 11:02 am

Hmm, OK. I can't reproduce this, I'm afraid. I'll have to try in a Windows VM, maybe it's platform-specific.

As a side note, since you have kept that 4829 installer, could you upload it somewhere and PM me the link?

randomreuben · Post by **randomreuben** » Wed Aug 15, 2012 11:47 am

I no longer have an audio resample error in my encodes. Thanks, Rodeo!

Deleted User 11865 · Post by **Deleted User 11865** » Wed Aug 15, 2012 2:24 pm

Now that I come to think of it, this could have been causing serious issues: https://trac.handbrake.fr/changeset/4903

mithrandir, could you check the next nightly and see if it also fixes your issue?

HandBrake

SVN 4887 - hb_audio_resample errors

SVN 4887 - hb_audio_resample errors

Re: SVN 4887 - hb_audio_resample errors

Re: SVN 4887 - hb_audio_resample errors

Re: SVN 4887 - hb_audio_resample errors

Re: SVN 4887 - hb_audio_resample errors

Re: SVN 4887 - hb_audio_resample errors

Re: SVN 4887 - hb_audio_resample errors

Re: SVN 4887 - hb_audio_resample errors

Re: SVN 4887 - hb_audio_resample errors

Re: SVN 4887 - hb_audio_resample errors

Re: SVN 4887 - hb_audio_resample errors

Re: SVN 4887 - hb_audio_resample errors

Re: SVN 4887 - hb_audio_resample errors

Re: SVN 4887 - hb_audio_resample errors

Re: SVN 4887 - hb_audio_resample errors

Re: SVN 4887 - hb_audio_resample errors

Re: SVN 4887 - hb_audio_resample errors

Re: SVN 4887 - hb_audio_resample errors

Re: SVN 4887 - hb_audio_resample errors

Re: SVN 4887 - hb_audio_resample errors

Re: SVN 4887 - hb_audio_resample errors