[svn4929] A/V desync

Archive of historical bug reports.
Please use the GitHub link above to report issues.
Forum rules
*******************************
Please be aware we are now using GitHub for issue tracking and feature requests.
- This section of the forum is now closed to new topics.

*******************************
Post Reply
Ayoross
Posts: 48
Joined: Mon Jun 11, 2012 9:07 pm

[svn4929] A/V desync

Post by Ayoross »

Hello,

It seems there is an A/V desync with svn4929.
I'm using mkv container, H.264 video encoding and faac AAC audio coding.
Need more details ?

NB: I have no problem with AC3 passthru
User avatar
Rodeo
HandBrake Team
Posts: 12746
Joined: Tue Mar 03, 2009 8:55 pm

Re: [svn4929] A/V desync

Post by Rodeo »

Activity Log please. And also, which player are you using for playback?
Ayoross
Posts: 48
Joined: Mon Jun 11, 2012 9:07 pm

Re: [svn4929] A/V desync

Post by Ayoross »

I'm using MPC-HC (1.6.3). Same problem with svn4927. svn4917 is the last build OK.

Code: Select all

HandBrake svn4929 2012090301
OS: Microsoft Windows NT 6.1.7601 Service Pack 1
CPU: Intel(R) Core(TM) i7-3930K CPU @ 3.20GHz
Ram: 16334 MB, Screen: 1920x1200
Temp Dir: C:\Users\FG\AppData\Local\Temp\
Install Dir: C:\Program Files\Handbrake
Data Dir: C:\Users\FG\AppData\Roaming\HandBrake\HandBrake\0.9.8.4929

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

CLI Query:  -i "C:\TEMP\PRISON_BREAK_S3D1.iso" -t 2 --angle 1 -c 1-13 -o "C:\TEMP\Prison break S3E01 Survivre.mkv"  -f mkv  --decomb="bob" --strict-anamorphic  --modulus 16 -e x264 -q 19.5 --vfr -a 3,1 -E faac,faac -6 dpl2,dpl2 -R Auto,Auto -B 192,192 -D 0,0 --gain 0,0 --audio-copy-mask none --audio-fallback ffac3 --subtitle 5,4 --markers="C:\Users\FG\AppData\Local\Temp\Prison break S3E01 Survivre-2-chapters.csv" -x rc-lookahead=10:ref=2:bframes=8:b-adapt=2:direct=auto:me=umh:subme=10:merange=24:analyse=all:trellis=0 --verbose=1
User Query: False

[23:15:37] hb_init: starting libhb thread
HandBrake svn4929 (2012090301) - MinGW x86_64 - http://handbrake.fr
12 CPUs detected
Opening C:\TEMP\PRISON_BREAK_S3D1.iso...
[23:15:37] hb_scan: path=C:\TEMP\PRISON_BREAK_S3D1.iso, title_index=2
libbluray/bdnav/index_parse.c:157: indx_parse(): error opening C:\TEMP\PRISON_BREAK_S3D1.iso/BDMV/index.bdmv
libbluray/bluray.c:1471: nav_get_title_list(C:\TEMP\PRISON_BREAK_S3D1.iso) failed (0000000001E5E810)
[23:15:37] bd: not a bd - trying as a stream/file instead
libdvdnav: Using dvdnav version 4.1.3
libdvdread: Encrypted DVD support unavailable.
libdvdnav: DVD Title: F3_38106_SE
libdvdnav: DVD Serial Number: 39414d57
libdvdnav: DVD Title (Alternative): 
libdvdnav: Unable to find home directorylibdvdnav: DVD disk reports itself with Region mask 0x00000000. Regions: 1 2 3 4 5 6 7 8
libdvdread: Encrypted DVD support unavailable.
[23:15:37] scan: DVD has 14 title(s)
[23:15:37] scan: scanning title 2
[23:15:37] scan: opening IFO for VTS 1
[23:15:37] scan: duration is 00:41:19 (2479290 ms)
[23:15:37] pgc_id: 2, pgn: 1: pgc: 0000000001E67E90
[23:15:37] scan: vts=1, ttn=2, cells=0->12, blocks=0->3462041, 863052 blocks
[23:15:37] scan: checking audio 1
[23:15:37] scan: id=0x80bd, lang=English (AC3), 3cc=eng ext=1
[23:15:37] scan: checking audio 2
[23:15:37] scan: id=0x81bd, lang=Italiano (AC3), 3cc=ita ext=1
[23:15:37] scan: checking audio 3
[23:15:37] scan: id=0x82bd, lang=Francais (AC3), 3cc=fra ext=1
[23:15:37] scan: checking subtitle 1
[23:15:37] scan: id=0x20bd, lang=English, 3cc=eng ext=1
[23:15:37] scan: checking subtitle 2
[23:15:37] scan: id=0x21bd, lang=Italiano, 3cc=ita ext=1
[23:15:37] scan: checking subtitle 3
[23:15:37] scan: id=0x22bd, lang=Italiano, 3cc=ita ext=1
[23:15:37] scan: checking subtitle 4
[23:15:37] scan: id=0x23bd, lang=Francais, 3cc=fra ext=1
[23:15:37] scan: checking subtitle 5
[23:15:37] scan: id=0x24bd, lang=Francais, 3cc=fra ext=1
[23:15:37] scan: checking subtitle 6
[23:15:37] scan: id=0x25bd, lang=Nederlands, 3cc=nld ext=1
[23:15:37] scan: title 2 has 13 chapters
[23:15:37] scan: chap 1 c=0->0, b=0->73617 (73618), 198392 ms
[23:15:37] scan: chap 2 c=1->1, b=73618->179568 (105951), 308735 ms
[23:15:37] scan: chap 3 c=2->2, b=179569->222664 (43096), 133209 ms
[23:15:37] scan: chap 4 c=3->3, b=222665->337565 (114901), 336659 ms
[23:15:37] scan: chap 5 c=4->4, b=337566->396875 (59310), 176637 ms
[23:15:37] scan: chap 6 c=5->5, b=396876->453823 (56948), 150446 ms
[23:15:37] scan: chap 7 c=6->6, b=453824->515723 (61900), 193444 ms
[23:15:37] scan: chap 8 c=7->7, b=515724->604092 (88369), 222640 ms
[23:15:37] scan: chap 9 c=8->8, b=604093->643341 (39249), 127530 ms
[23:15:37] scan: chap 10 c=9->9, b=643342->718839 (75498), 210531 ms
[23:15:37] scan: chap 11 c=10->10, b=718840->791774 (72935), 188356 ms
[23:15:37] scan: chap 12 c=11->11, b=791775->863046 (71272), 232415 ms
[23:15:37] scan: chap 13 c=12->12, b=3462037->3462041 (5), 290 ms
[23:15:37] scan: aspect = 1.77778
[23:15:37] scan: decoding previews for title 2
libdvdnav: DVD disk reports itself with Region mask 0x00000000. Regions: 1 2 3 4 5 6 7 8
[23:15:37] scan: title angle(s) 1
[23:15:37] scan: audio 0x81bd: AC-3, rate=48000Hz, bitrate=384000 Italiano (AC3) (5.1 ch)
[23:15:37] scan: audio 0x82bd: AC-3, rate=48000Hz, bitrate=384000 Francais (AC3) (5.1 ch)
[23:15:37] scan: audio 0x80bd: AC-3, rate=48000Hz, bitrate=448000 English (AC3) (5.1 ch)
[23:15:37] scan: 10 previews, 720x576, 25.000 fps, autocrop = 2/2/0/0, aspect 16:9, PAR 64:45
[23:15:37] scan: title (0) job->width:720, job->height:400
[23:15:37] libhb: scan thread found 1 valid title(s)
+ title 2:
  + vts 1, ttn 2, cells 0->12 (863052 blocks)
  + duration: 00:41:19
  + size: 720x576, pixel aspect: 64/45, display aspect: 1.78, 25.000 fps
  + autocrop: 2/2/0/0
  + chapters:
    + 1: cells 0->0, 73618 blocks, duration 00:03:18
    + 2: cells 1->1, 105951 blocks, duration 00:05:09
    + 3: cells 2->2, 43096 blocks, duration 00:02:13
    + 4: cells 3->3, 114901 blocks, duration 00:05:37
    + 5: cells 4->4, 59310 blocks, duration 00:02:57
    + 6: cells 5->5, 56948 blocks, duration 00:02:30
    + 7: cells 6->6, 61900 blocks, duration 00:03:13
    + 8: cells 7->7, 88369 blocks, duration 00:03:43
    + 9: cells 8->8, 39249 blocks, duration 00:02:08
    + 10: cells 9->9, 75498 blocks, duration 00:03:31
    + 11: cells 10->10, 72935 blocks, duration 00:03:08
    + 12: cells 11->11, 71272 blocks, duration 00:03:52
    + 13: cells 12->12, 5 blocks, duration 00:00:00
  + audio tracks:
    + 1, English (AC3) (5.1 ch) (iso639-2: eng), 48000Hz, 448000bps
    + 2, Italiano (AC3) (5.1 ch) (iso639-2: ita), 48000Hz, 384000bps
    + 3, Francais (AC3) (5.1 ch) (iso639-2: fra), 48000Hz, 384000bps
  + subtitle tracks:
    + 1, English (iso639-2: eng) (Bitmap)(VOBSUB)
    + 2, Italiano (iso639-2: ita) (Bitmap)(VOBSUB)
    + 3, Italiano (iso639-2: ita) (Bitmap)(VOBSUB)
    + 4, Francais (iso639-2: fra) (Bitmap)(VOBSUB)
    + 5, Francais (iso639-2: fra) (Bitmap)(VOBSUB)
    + 6, Nederlands (iso639-2: nld) (Bitmap)(VOBSUB)
Reading chapter markers from file C:\Users\FG\AppData\Local\Temp\Prison break S3E01 Survivre-2-chapters.csv
[23:15:37] 1 job(s) to process
[23:15:37] starting job
[23:15:37] yadif thread started for segment 0
[23:15:37] yadif thread started for segment 1
[23:15:37] yadif thread started for segment 2
[23:15:37] yadif thread started for segment 3
[23:15:37] yadif thread started for segment 4
[23:15:37] yadif thread started for segment 5
[23:15:37] yadif thread started for segment 6
[23:15:37] yadif thread started for segment 7
[23:15:37] yadif thread started for segment 8
[23:15:37] yadif thread started for segment 9
[23:15:37] yadif thread started for segment 10
[23:15:37] yadif thread started for segment 11
[23:15:37] decomb filter thread started for segment 0
[23:15:37] decomb filter thread started for segment 1
[23:15:37] decomb filter thread started for segment 2
[23:15:37] decomb filter thread started for segment 3
[23:15:37] decomb filter thread started for segment 4
[23:15:37] decomb filter thread started for segment 5
[23:15:37] decomb filter thread started for segment 6
[23:15:37] decomb filter thread started for segment 7
[23:15:37] decomb filter thread started for segment 8
[23:15:37] decomb filter thread started for segment 9
[23:15:37] decomb filter thread started for segment 10
[23:15:37] decomb filter thread started for segment 11
[23:15:37] mask filter thread started for segment 0
[23:15:37] mask filter thread started for segment 1
[23:15:37] mask filter thread started for segment 2
[23:15:37] mask filter thread started for segment 3
[23:15:37] mask filter thread started for segment 4
[23:15:37] mask filter thread started for segment 5
[23:15:37] mask filter thread started for segment 6
[23:15:37] mask filter thread started for segment 7
[23:15:37] mask filter thread started for segment 8
[23:15:37] mask filter thread started for segment 9
[23:15:37] mask filter thread started for segment 10
[23:15:37] mask filter thread started for segment 11
[23:15:37] mask filter thread started for segment 0
[23:15:37] mask filter thread started for segment 1
[23:15:37] mask filter thread started for segment 2
[23:15:37] mask filter thread started for segment 3
[23:15:37] mask filter thread started for segment 4
[23:15:37] mask filter thread started for segment 5
[23:15:37] mask filter thread started for segment 6
[23:15:37] mask filter thread started for segment 7
[23:15:37] mask filter thread started for segment 8
[23:15:37] mask filter thread started for segment 9
[23:15:37] mask filter thread started for segment 10
[23:15:37] mask filter thread started for segment 11
[23:15:37] mask filter thread started for segment 0
[23:15:37] mask filter thread started for segment 1
[23:15:37] mask filter thread started for segment 2
[23:15:37] mask filter thread started for segment 3
[23:15:37] mask filter thread started for segment 4
[23:15:37] mask filter thread started for segment 5
[23:15:37] mask filter thread started for segment 6
[23:15:37] mask filter thread started for segment 7
[23:15:37] mask filter thread started for segment 8
[23:15:37] mask filter thread started for segment 9
[23:15:37] mask filter thread started for segment 10
[23:15:37] mask filter thread started for segment 11
[23:15:37] mask filter thread started for segment 0
[23:15:37] mask filter thread started for segment 1
[23:15:37] mask filter thread started for segment 2
[23:15:37] mask filter thread started for segment 3
[23:15:37] mask filter thread started for segment 4
[23:15:37] mask filter thread started for segment 5
[23:15:37] mask filter thread started for segment 6
[23:15:37] mask filter thread started for segment 7
[23:15:37] mask filter thread started for segment 8
[23:15:37] mask filter thread started for segment 9
[23:15:37] mask filter thread started for segment 10
[23:15:37] mask filter thread started for segment 11
[23:15:37] sync: expecting 61982 video frames
[23:15:37] job configuration:
[23:15:37]  * source
[23:15:37]    + C:\TEMP\PRISON_BREAK_S3D1.iso
[23:15:37]    + title 2, chapter(s) 1 to 13
[23:15:37]  * destination
[23:15:37]    + C:\TEMP\Prison break S3E01 Survivre.mkv
[23:15:37]    + container: Matroska (.mkv)
[23:15:37]      + chapter markers
[23:15:37]  * video track
[23:15:37]    + decoder: mpeg2
[23:15:37]      + bitrate 9800 kbps
[23:15:37]    + frame rate: same as source (around 25.000 fps)
[23:15:37]    + filters
[23:15:37]      + Decomb (455)
[23:15:37]      + Framerate Shaper (0:27000000:1080000)
[23:15:37]        + frame rate: same as source (around 25.000 fps)
[23:15:37]      + Crop and Scale (720:572:2:2:0:0)
[23:15:37]        + source: 720 * 576, crop (2/2/0/0): 720 * 572, scale: 720 * 572
[23:15:37]    + strict anamorphic
[23:15:37]      + storage dimensions: 720 * 572, mod 0
[23:15:37]      + pixel aspect ratio: 64 / 45
[23:15:37]      + display dimensions: 1024 * 572
[23:15:37]    + encoder: H.264 (x264)
[23:15:37]      + options: rc-lookahead=10:ref=2:bframes=8:b-adapt=2:direct=auto:me=umh:subme=10:merange=24:analyse=all:trellis=0
[23:15:37]      + quality: 19.50 (RF)
[23:15:37]  * subtitle track 1, Francais (track 5, id 0x24bd) Picture [VOBSUB] -> Passthrough
[23:15:37]  * subtitle track 2, Francais (track 4, id 0x23bd) Picture [VOBSUB] -> Passthrough
[23:15:37]  * audio track 1
[23:15:37]    + decoder: Francais (AC3) (5.1 ch) (track 3, id 0x82bd)
[23:15:37]      + bitrate: 384 kbps, samplerate: 48000 Hz
[23:15:37]    + mixdown: Dolby Pro Logic II
[23:15:37]    + encoder: AAC (faac)
[23:15:37]      + bitrate: 192 kbps, samplerate: 48000 Hz
[23:15:37]  * audio track 2
[23:15:37]    + decoder: English (AC3) (5.1 ch) (track 1, id 0x80bd)
[23:15:37]      + bitrate: 448 kbps, samplerate: 48000 Hz
[23:15:37]    + mixdown: Dolby Pro Logic II
[23:15:37]    + encoder: AAC (faac)
[23:15:37]      + bitrate: 192 kbps, samplerate: 48000 Hz
libdvdnav: Using dvdnav version 4.1.3
libdvdread: Encrypted DVD support unavailable.
libdvdnav: DVD Title: F3_38106_SE
libdvdnav: DVD Serial Number: 39414d57
libdvdnav: DVD Title (Alternative): 
libdvdnav: Unable to find home directorylibdvdnav: DVD disk reports itself with Region mask 0x00000000. Regions: 1 2 3 4 5 6 7 8
libdvdread: Encrypted DVD support unavailable.
libdvdnav: DVD disk reports itself with Region mask 0x00000000. Regions: 1 2 3 4 5 6 7 8
[23:15:37] encx264: encoding with stored aspect 64/45
[23:15:37] encx264: Encoding at constant RF 19.500000
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=64/45
[23:15:37] reader: first SCR 146 id 0xe0 DTS 22254
x264 [info]: using cpu capabilities: MMX2 SSE2Fast SSSE3 FastShuffle SSE4.2 AVX
[23:15:37] mpeg2: "Chapter 1" (1) at frame 0 time 3600
x264 [info]: profile High, level 3.0
[23:15:37] sync: first pts is 3600
[23:17:07] mpeg2: "Chapter 2" (2) at frame 4952 time 17830800
[23:19:15] mpeg2: "Chapter 3" (3) at frame 12668 time 45608400
[23:20:07] mpeg2: "Chapter 4" (4) at frame 15993 time 57578400
[23:22:28] mpeg2: "Chapter 5" (5) at frame 24406 time 87865200
[23:23:38] mpeg2: "Chapter 6" (6) at frame 28815 time 103737600
[23:24:43] mpeg2: "Chapter 7" (7) at frame 32580 time 117291600
[23:26:03] mpeg2: "Chapter 8" (8) at frame 37428 time 134744400
[23:27:41] mpeg2: "Chapter 9" (9) at frame 42995 time 154785600
[23:28:34] mpeg2: "Chapter 10" (10) at frame 46188 time 166280400
[23:30:07] mpeg2: "Chapter 11" (11) at frame 51443 time 185198400
[23:31:32] mpeg2: "Chapter 12" (12) at frame 56164 time 202194000
[23:32:54] reader: done. 1 scr changes
[23:32:54] work: average encoding speed for job is 59.762848 fps
[23:32:55] sync: got 61973 frames, 61982 expected
[23:32:55] decomb: deinterlaced 109 | blended 842 | unfiltered 61972 | total 62923
[23:32:55] render: lost time: 0 (0 frames)
[23:32:55] render: gained time: 0 (0 frames) (0 not accounted for)
[23:32:55] mpeg2 done: 61973 frames
x264 [info]: frame I:648   Avg QP:17.10  size: 52122  PSNR Mean Y:47.45 U:52.45 V:52.88 Avg:48.47 Global:47.40
x264 [info]: frame P:15007 Avg QP:21.14  size: 20420  PSNR Mean Y:43.70 U:48.89 V:49.41 Avg:44.80 Global:44.22
x264 [info]: frame B:47268 Avg QP:22.75  size:  8003  PSNR Mean Y:42.92 U:49.18 V:49.76 Avg:44.13 Global:43.30
x264 [info]: consecutive B-frames:  3.1%  4.4% 12.1% 20.1% 18.5% 36.2%  3.6%  0.9%  1.0%
x264 [info]: mb I  I16..4: 10.4% 79.0% 10.5%
x264 [info]: mb P  I16..4:  3.5% 27.6%  3.5%  P16..4: 35.5% 14.4%  8.1%  0.7%  0.2%    skip: 6.5%
x264 [info]: mb B  I16..4:  0.4%  4.1%  0.5%  B16..8: 46.9%  9.9%  2.3%  direct: 6.8%  skip:29.2%  L0:40.7% L1:44.4% BI:14.9%
x264 [info]: 8x8 transform intra:80.5% inter:66.0%
x264 [info]: direct mvs  spatial:99.9% temporal:0.1%
x264 [info]: coded y,uvDC,uvAC intra: 87.3% 74.2% 31.1% inter: 26.3% 22.3% 1.2%
x264 [info]: i16 v,h,dc,p: 52%  9%  5% 34%
x264 [info]: i8 v,h,dc,ddl,ddr,vr,hd,vl,hu: 13%  5%  4% 10% 14% 17% 12% 14% 10%
x264 [info]: i4 v,h,dc,ddl,ddr,vr,hd,vl,hu: 18%  5%  2%  9% 14% 18% 11% 14% 10%
x264 [info]: i8c dc,h,v,p: 30% 23% 28% 19%
x264 [info]: Weighted P-Frames: Y:3.1% UV:1.6%
x264 [info]: ref P L0: 58.3% 16.7% 24.6%  0.4%
x264 [info]: ref B L0: 91.1%  8.9%
x264 [info]: ref B L1: 94.8%  5.2%
x264 [info]: SSIM Mean Y:0.9716564 (15.475db)
x264 [info]: PSNR Mean Y:43.149 U:49.145 V:49.706 Avg:44.338 Global:43.532 kb/s:2283.84
[23:32:55] mux: track 0, 62923 frames, 718501674 bytes, 2283.75 kbps, fifo 2048
[23:32:55] mux: track 1, 116194 frames, 59467929 bytes, 189.02 kbps, fifo 4096
[23:32:55] mux: track 2, 116194 frames, 59474819 bytes, 189.04 kbps, fifo 4096
[23:32:55] mux: track 3, 29 frames, 63364 bytes, 0.20 kbps, fifo 8
[23:32:55] mux: track 4, 393 frames, 972836 bytes, 3.09 kbps, fifo 32
[23:32:55] libhb: work result = 0
Encode done!
HandBrake has exited.
User avatar
Rodeo
HandBrake Team
Posts: 12746
Joined: Tue Mar 03, 2009 8:55 pm

Re: [svn4929] A/V desync

Post by Rodeo »

Ayoross wrote:Same problem with svn4927. svn4917 is the last build OK.
That data is quite useful (especially when paired with the log).
What happens if you disable the decomb filter, is the sound in or out of sync?
Ayoross
Posts: 48
Joined: Mon Jun 11, 2012 9:07 pm

Re: [svn4929] A/V desync

Post by Ayoross »

svn4927 log :

Code: Select all

HandBrake svn4929 2012090301
OS: Microsoft Windows NT 6.1.7601 Service Pack 1
CPU: Intel(R) Core(TM) i7-3930K CPU @ 3.20GHz
Ram: 16334 MB, Screen: 1920x1200
Temp Dir: C:\Users\FG\AppData\Local\Temp\
Install Dir: C:\Program Files\Handbrake
Data Dir: C:\Users\FG\AppData\Roaming\HandBrake\HandBrake\0.9.8.4927

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

CLI Query:  -i "C:\TEMP\PRISON_BREAK_S3D1.iso" -t 2 --angle 1 -c 1-13 -o "C:\TEMP\Prison break S3E01 Survivre.mkv"  -f mkv  --decomb="bob" --strict-anamorphic  --modulus 16 -e x264 -q 19.5 --vfr -a 3,1 -E faac,faac -6 dpl2,dpl2 -R Auto,Auto -B 192,192 -D 0,0 --gain 0,0 --audio-copy-mask none --audio-fallback ffac3 --subtitle 5,4 --markers="C:\Users\FG\AppData\Local\Temp\Prison break S3E01 Survivre-2-chapters.csv" -x rc-lookahead=10:ref=2:bframes=8:b-adapt=2:direct=auto:me=umh:subme=10:merange=24:analyse=all:trellis=0 --verbose=1
User Query: False

[22:36:14] hb_init: starting libhb thread
HandBrake svn4927 (2012090201) - MinGW x86_64 - http://handbrake.fr
12 CPUs detected
Opening C:\TEMP\PRISON_BREAK_S3D1.iso...
[22:36:14] hb_scan: path=C:\TEMP\PRISON_BREAK_S3D1.iso, title_index=2
libbluray/bdnav/index_parse.c:157: indx_parse(): error opening C:\TEMP\PRISON_BREAK_S3D1.iso/BDMV/index.bdmv
libbluray/bluray.c:1471: nav_get_title_list(C:\TEMP\PRISON_BREAK_S3D1.iso) failed (0000000001E5E810)
[22:36:14] bd: not a bd - trying as a stream/file instead
libdvdnav: Using dvdnav version 4.1.3
libdvdread: Encrypted DVD support unavailable.
libdvdnav: DVD Title: F3_38106_SE
libdvdnav: DVD Serial Number: 39414d57
libdvdnav: DVD Title (Alternative): 
libdvdnav: Unable to find home directorylibdvdnav: DVD disk reports itself with Region mask 0x00000000. Regions: 1 2 3 4 5 6 7 8
libdvdread: Encrypted DVD support unavailable.
[22:36:14] scan: DVD has 14 title(s)
[22:36:14] scan: scanning title 2
[22:36:14] scan: opening IFO for VTS 1
[22:36:14] scan: duration is 00:41:19 (2479290 ms)
[22:36:14] pgc_id: 2, pgn: 1: pgc: 0000000001E67E90
[22:36:14] scan: vts=1, ttn=2, cells=0->12, blocks=0->3462041, 863052 blocks
[22:36:14] scan: checking audio 1
[22:36:14] scan: id=0x80bd, lang=English (AC3), 3cc=eng ext=1
[22:36:14] scan: checking audio 2
[22:36:14] scan: id=0x81bd, lang=Italiano (AC3), 3cc=ita ext=1
[22:36:14] scan: checking audio 3
[22:36:14] scan: id=0x82bd, lang=Francais (AC3), 3cc=fra ext=1
[22:36:14] scan: checking subtitle 1
[22:36:14] scan: id=0x20bd, lang=English, 3cc=eng ext=1
[22:36:14] scan: checking subtitle 2
[22:36:14] scan: id=0x21bd, lang=Italiano, 3cc=ita ext=1
[22:36:14] scan: checking subtitle 3
[22:36:14] scan: id=0x22bd, lang=Italiano, 3cc=ita ext=1
[22:36:14] scan: checking subtitle 4
[22:36:14] scan: id=0x23bd, lang=Francais, 3cc=fra ext=1
[22:36:14] scan: checking subtitle 5
[22:36:14] scan: id=0x24bd, lang=Francais, 3cc=fra ext=1
[22:36:14] scan: checking subtitle 6
[22:36:14] scan: id=0x25bd, lang=Nederlands, 3cc=nld ext=1
[22:36:14] scan: title 2 has 13 chapters
[22:36:14] scan: chap 1 c=0->0, b=0->73617 (73618), 198392 ms
[22:36:14] scan: chap 2 c=1->1, b=73618->179568 (105951), 308735 ms
[22:36:14] scan: chap 3 c=2->2, b=179569->222664 (43096), 133209 ms
[22:36:14] scan: chap 4 c=3->3, b=222665->337565 (114901), 336659 ms
[22:36:14] scan: chap 5 c=4->4, b=337566->396875 (59310), 176637 ms
[22:36:14] scan: chap 6 c=5->5, b=396876->453823 (56948), 150446 ms
[22:36:14] scan: chap 7 c=6->6, b=453824->515723 (61900), 193444 ms
[22:36:14] scan: chap 8 c=7->7, b=515724->604092 (88369), 222640 ms
[22:36:14] scan: chap 9 c=8->8, b=604093->643341 (39249), 127530 ms
[22:36:14] scan: chap 10 c=9->9, b=643342->718839 (75498), 210531 ms
[22:36:14] scan: chap 11 c=10->10, b=718840->791774 (72935), 188356 ms
[22:36:14] scan: chap 12 c=11->11, b=791775->863046 (71272), 232415 ms
[22:36:14] scan: chap 13 c=12->12, b=3462037->3462041 (5), 290 ms
[22:36:14] scan: aspect = 1.77778
[22:36:14] scan: decoding previews for title 2
libdvdnav: DVD disk reports itself with Region mask 0x00000000. Regions: 1 2 3 4 5 6 7 8
[22:36:14] scan: title angle(s) 1
[22:36:14] scan: audio 0x81bd: AC-3, rate=48000Hz, bitrate=384000 Italiano (AC3) (5.1 ch)
[22:36:14] scan: audio 0x82bd: AC-3, rate=48000Hz, bitrate=384000 Francais (AC3) (5.1 ch)
[22:36:14] scan: audio 0x80bd: AC-3, rate=48000Hz, bitrate=448000 English (AC3) (5.1 ch)
[22:36:15] scan: 10 previews, 720x576, 25.000 fps, autocrop = 2/2/0/0, aspect 16:9, PAR 64:45
[22:36:15] scan: title (0) job->width:720, job->height:400
[22:36:15] libhb: scan thread found 1 valid title(s)
+ title 2:
  + vts 1, ttn 2, cells 0->12 (863052 blocks)
  + duration: 00:41:19
  + size: 720x576, pixel aspect: 64/45, display aspect: 1.78, 25.000 fps
  + autocrop: 2/2/0/0
  + chapters:
    + 1: cells 0->0, 73618 blocks, duration 00:03:18
    + 2: cells 1->1, 105951 blocks, duration 00:05:09
    + 3: cells 2->2, 43096 blocks, duration 00:02:13
    + 4: cells 3->3, 114901 blocks, duration 00:05:37
    + 5: cells 4->4, 59310 blocks, duration 00:02:57
    + 6: cells 5->5, 56948 blocks, duration 00:02:30
    + 7: cells 6->6, 61900 blocks, duration 00:03:13
    + 8: cells 7->7, 88369 blocks, duration 00:03:43
    + 9: cells 8->8, 39249 blocks, duration 00:02:08
    + 10: cells 9->9, 75498 blocks, duration 00:03:31
    + 11: cells 10->10, 72935 blocks, duration 00:03:08
    + 12: cells 11->11, 71272 blocks, duration 00:03:52
    + 13: cells 12->12, 5 blocks, duration 00:00:00
  + audio tracks:
    + 1, English (AC3) (5.1 ch) (iso639-2: eng), 48000Hz, 448000bps
    + 2, Italiano (AC3) (5.1 ch) (iso639-2: ita), 48000Hz, 384000bps
    + 3, Francais (AC3) (5.1 ch) (iso639-2: fra), 48000Hz, 384000bps
  + subtitle tracks:
    + 1, English (iso639-2: eng) (Bitmap)(VOBSUB)
    + 2, Italiano (iso639-2: ita) (Bitmap)(VOBSUB)
    + 3, Italiano (iso639-2: ita) (Bitmap)(VOBSUB)
    + 4, Francais (iso639-2: fra) (Bitmap)(VOBSUB)
    + 5, Francais (iso639-2: fra) (Bitmap)(VOBSUB)
    + 6, Nederlands (iso639-2: nld) (Bitmap)(VOBSUB)
Reading chapter markers from file C:\Users\FG\AppData\Local\Temp\Prison break S3E01 Survivre-2-chapters.csv
[22:36:15] 1 job(s) to process
[22:36:15] starting job
[22:36:15] yadif thread started for segment 0
[22:36:15] yadif thread started for segment 1
[22:36:15] yadif thread started for segment 2
[22:36:15] yadif thread started for segment 3
[22:36:15] yadif thread started for segment 4
[22:36:15] yadif thread started for segment 5
[22:36:15] yadif thread started for segment 6
[22:36:15] yadif thread started for segment 7
[22:36:15] yadif thread started for segment 8
[22:36:15] yadif thread started for segment 9
[22:36:15] yadif thread started for segment 10
[22:36:15] yadif thread started for segment 11
[22:36:15] decomb filter thread started for segment 0
[22:36:15] decomb filter thread started for segment 1
[22:36:15] decomb filter thread started for segment 2
[22:36:15] decomb filter thread started for segment 3
[22:36:15] decomb filter thread started for segment 4
[22:36:15] decomb filter thread started for segment 5
[22:36:15] decomb filter thread started for segment 6
[22:36:15] decomb filter thread started for segment 7
[22:36:15] decomb filter thread started for segment 8
[22:36:15] decomb filter thread started for segment 9
[22:36:15] decomb filter thread started for segment 10
[22:36:15] decomb filter thread started for segment 11
[22:36:15] mask filter thread started for segment 0
[22:36:15] mask filter thread started for segment 1
[22:36:15] mask filter thread started for segment 2
[22:36:15] mask filter thread started for segment 3
[22:36:15] mask filter thread started for segment 4
[22:36:15] mask filter thread started for segment 5
[22:36:15] mask filter thread started for segment 6
[22:36:15] mask filter thread started for segment 7
[22:36:15] mask filter thread started for segment 8
[22:36:15] mask filter thread started for segment 9
[22:36:15] mask filter thread started for segment 10
[22:36:15] mask filter thread started for segment 11
[22:36:15] mask filter thread started for segment 0
[22:36:15] mask filter thread started for segment 1
[22:36:15] mask filter thread started for segment 2
[22:36:15] mask filter thread started for segment 3
[22:36:15] mask filter thread started for segment 4
[22:36:15] mask filter thread started for segment 5
[22:36:15] mask filter thread started for segment 6
[22:36:15] mask filter thread started for segment 7
[22:36:15] mask filter thread started for segment 8
[22:36:15] mask filter thread started for segment 9
[22:36:15] mask filter thread started for segment 10
[22:36:15] mask filter thread started for segment 11
[22:36:15] mask filter thread started for segment 0
[22:36:15] mask filter thread started for segment 1
[22:36:15] mask filter thread started for segment 2
[22:36:15] mask filter thread started for segment 3
[22:36:15] mask filter thread started for segment 4
[22:36:15] mask filter thread started for segment 5
[22:36:15] mask filter thread started for segment 6
[22:36:15] mask filter thread started for segment 7
[22:36:15] mask filter thread started for segment 8
[22:36:15] mask filter thread started for segment 9
[22:36:15] mask filter thread started for segment 10
[22:36:15] mask filter thread started for segment 11
[22:36:15] mask filter thread started for segment 0
[22:36:15] mask filter thread started for segment 1
[22:36:15] mask filter thread started for segment 2
[22:36:15] mask filter thread started for segment 3
[22:36:15] mask filter thread started for segment 4
[22:36:15] mask filter thread started for segment 5
[22:36:15] mask filter thread started for segment 6
[22:36:15] mask filter thread started for segment 7
[22:36:15] mask filter thread started for segment 8
[22:36:15] mask filter thread started for segment 9
[22:36:15] mask filter thread started for segment 10
[22:36:15] mask filter thread started for segment 11
[22:36:15] sync: expecting 61982 video frames
[22:36:15] job configuration:
[22:36:15]  * source
[22:36:15]    + C:\TEMP\PRISON_BREAK_S3D1.iso
[22:36:15]    + title 2, chapter(s) 1 to 13
[22:36:15]  * destination
[22:36:15]    + C:\TEMP\Prison break S3E01 Survivre.mkv
[22:36:15]    + container: Matroska (.mkv)
[22:36:15]      + chapter markers
[22:36:15]  * video track
[22:36:15]    + decoder: mpeg2
[22:36:15]      + bitrate 9800 kbps
[22:36:15]    + frame rate: same as source (around 25.000 fps)
[22:36:15]    + filters
[22:36:15]      + Decomb (455)
[22:36:15]      + Framerate Shaper (0:27000000:1080000)
[22:36:15]        + frame rate: same as source (around 25.000 fps)
[22:36:15]      + Crop and Scale (720:572:2:2:0:0)
[22:36:15]        + source: 720 * 576, crop (2/2/0/0): 720 * 572, scale: 720 * 572
[22:36:15]    + strict anamorphic
[22:36:15]      + storage dimensions: 720 * 572, mod 0
[22:36:15]      + pixel aspect ratio: 64 / 45
[22:36:15]      + display dimensions: 1024 * 572
[22:36:15]    + encoder: H.264 (x264)
[22:36:15]      + options: rc-lookahead=10:ref=2:bframes=8:b-adapt=2:direct=auto:me=umh:subme=10:merange=24:analyse=all:trellis=0
[22:36:15]      + quality: 19.50 (RF)
[22:36:15]  * subtitle track 1, Francais (track 5, id 0x24bd) Picture [VOBSUB] -> Passthrough
[22:36:15]  * subtitle track 2, Francais (track 4, id 0x23bd) Picture [VOBSUB] -> Passthrough
[22:36:15]  * audio track 1
[22:36:15]    + decoder: Francais (AC3) (5.1 ch) (track 3, id 0x82bd)
[22:36:15]      + bitrate: 384 kbps, samplerate: 48000 Hz
[22:36:15]    + mixdown: Dolby Pro Logic II
[22:36:15]    + encoder: AAC (faac)
[22:36:15]      + bitrate: 192 kbps, samplerate: 48000 Hz
[22:36:15]  * audio track 2
[22:36:15]    + decoder: English (AC3) (5.1 ch) (track 1, id 0x80bd)
[22:36:15]      + bitrate: 448 kbps, samplerate: 48000 Hz
[22:36:15]    + mixdown: Dolby Pro Logic II
[22:36:15]    + encoder: AAC (faac)
[22:36:15]      + bitrate: 192 kbps, samplerate: 48000 Hz
libdvdnav: Using dvdnav version 4.1.3
libdvdread: Encrypted DVD support unavailable.
libdvdnav: DVD Title: F3_38106_SE
libdvdnav: DVD Serial Number: 39414d57
libdvdnav: DVD Title (Alternative): 
libdvdnav: Unable to find home directorylibdvdnav: DVD disk reports itself with Region mask 0x00000000. Regions: 1 2 3 4 5 6 7 8
libdvdread: Encrypted DVD support unavailable.
libdvdnav: DVD disk reports itself with Region mask 0x00000000. Regions: 1 2 3 4 5 6 7 8
[22:36:15] encx264: encoding with stored aspect 64/45
[22:36:15] encx264: Encoding at constant RF 19.500000
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=64/45
x264 [info]: using cpu capabilities: MMX2 SSE2Fast SSSE3 FastShuffle SSE4.2 AVX
[22:36:15] reader: first SCR 146 id 0xe0 DTS 22254
[22:36:15] mpeg2: "Chapter 1" (1) at frame 0 time 3600
x264 [info]: profile High, level 3.0
[22:36:15] sync: first pts is 3600
[22:37:45] mpeg2: "Chapter 2" (2) at frame 4952 time 17830800
[22:39:51] mpeg2: "Chapter 3" (3) at frame 12668 time 45608400
[22:40:43] mpeg2: "Chapter 4" (4) at frame 15993 time 57578400
[22:43:03] mpeg2: "Chapter 5" (5) at frame 24406 time 87865200
[22:44:10] mpeg2: "Chapter 6" (6) at frame 28815 time 103737600
[22:45:13] mpeg2: "Chapter 7" (7) at frame 32580 time 117291600
[22:46:31] mpeg2: "Chapter 8" (8) at frame 37428 time 134744400
[22:48:07] mpeg2: "Chapter 9" (9) at frame 42995 time 154785600
[22:49:00] mpeg2: "Chapter 10" (10) at frame 46188 time 166280400
[22:50:30] mpeg2: "Chapter 11" (11) at frame 51443 time 185198400
[22:51:53] mpeg2: "Chapter 12" (12) at frame 56164 time 202194000
[22:53:13] reader: done. 1 scr changes
[22:53:13] work: average encoding speed for job is 60.840488 fps
[22:53:14] sync: got 61973 frames, 61982 expected
[22:53:14] decomb: deinterlaced 109 | blended 842 | unfiltered 61972 | total 62923
[22:53:14] render: lost time: 0 (0 frames)
[22:53:14] render: gained time: 0 (0 frames) (0 not accounted for)
[22:53:14] mpeg2 done: 61973 frames
x264 [info]: frame I:648   Avg QP:17.10  size: 52122  PSNR Mean Y:47.45 U:52.45 V:52.88 Avg:48.47 Global:47.40
x264 [info]: frame P:15007 Avg QP:21.14  size: 20420  PSNR Mean Y:43.70 U:48.89 V:49.41 Avg:44.80 Global:44.22
x264 [info]: frame B:47268 Avg QP:22.75  size:  8003  PSNR Mean Y:42.92 U:49.18 V:49.76 Avg:44.13 Global:43.30
x264 [info]: consecutive B-frames:  3.1%  4.4% 12.1% 20.1% 18.5% 36.2%  3.6%  0.9%  1.0%
x264 [info]: mb I  I16..4: 10.4% 79.0% 10.5%
x264 [info]: mb P  I16..4:  3.5% 27.6%  3.5%  P16..4: 35.5% 14.4%  8.1%  0.7%  0.2%    skip: 6.5%
x264 [info]: mb B  I16..4:  0.4%  4.1%  0.5%  B16..8: 46.9%  9.9%  2.3%  direct: 6.8%  skip:29.2%  L0:40.7% L1:44.4% BI:14.9%
x264 [info]: 8x8 transform intra:80.5% inter:66.0%
x264 [info]: direct mvs  spatial:99.9% temporal:0.1%
x264 [info]: coded y,uvDC,uvAC intra: 87.3% 74.2% 31.1% inter: 26.3% 22.3% 1.2%
x264 [info]: i16 v,h,dc,p: 52%  9%  5% 34%
x264 [info]: i8 v,h,dc,ddl,ddr,vr,hd,vl,hu: 13%  5%  4% 10% 14% 17% 12% 14% 10%
x264 [info]: i4 v,h,dc,ddl,ddr,vr,hd,vl,hu: 18%  5%  2%  9% 14% 18% 11% 14% 10%
x264 [info]: i8c dc,h,v,p: 30% 23% 28% 19%
x264 [info]: Weighted P-Frames: Y:3.1% UV:1.6%
x264 [info]: ref P L0: 58.4% 16.7% 24.6%  0.4%
x264 [info]: ref B L0: 91.1%  8.9%
x264 [info]: ref B L1: 94.8%  5.2%
x264 [info]: SSIM Mean Y:0.9716563 (15.475db)
x264 [info]: PSNR Mean Y:43.149 U:49.145 V:49.706 Avg:44.338 Global:43.532 kb/s:2283.83
[22:53:14] mux: track 0, 62923 frames, 718500822 bytes, 2283.75 kbps, fifo 2048
[22:53:14] mux: track 1, 116194 frames, 59467929 bytes, 189.02 kbps, fifo 4096
[22:53:14] mux: track 2, 116194 frames, 59474819 bytes, 189.04 kbps, fifo 4096
[22:53:14] mux: track 3, 29 frames, 63364 bytes, 0.20 kbps, fifo 8
[22:53:14] mux: track 4, 393 frames, 972836 bytes, 3.09 kbps, fifo 32
[22:53:14] libhb: work result = 0
Encode done!
HandBrake has exited.
I'll try without the decomb filter tonight.
Ayoross
Posts: 48
Joined: Mon Jun 11, 2012 9:07 pm

Re: [svn4929] A/V desync

Post by Ayoross »

Encode seems ok without the bob decomb filter.

log:

Code: Select all

HandBrake svn4917 2012082601
OS: Microsoft Windows NT 6.1.7601 Service Pack 1
CPU: Intel(R) Core(TM) i7-3930K CPU @ 3.20GHz
Ram: 16334 MB, Screen: 1920x1200
Temp Dir: C:\Users\FG\AppData\Local\Temp\
Install Dir: C:\Program Files\Handbrake
Data Dir: C:\Users\FG\AppData\Roaming\HandBrake\HandBrake\0.9.8.4929

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

CLI Query:  -i "C:\TEMP\PRISON_BREAK_S4D1.iso" -t 3 --angle 1 -c 1-13 -o "C:\TEMP\Prison break S4E02 À bout de souffle.mkv"  -f mkv  --strict-anamorphic  --modulus 16 -e x264 -q 19.5 --vfr -a 3,1 -E faac,faac -6 dpl2,dpl2 -R Auto,Auto -B 192,192 -D 0,0 --gain 0,0 --audio-copy-mask none --audio-fallback ffac3 --subtitle 6,3 --markers="C:\Users\FG\AppData\Local\Temp\Prison break S4E02 À bout de souffle-3-chapters.csv" -x rc-lookahead=10:ref=2:bframes=8:b-adapt=2:direct=auto:me=umh:subme=10:merange=24:analyse=all:trellis=0 --verbose=1
User Query: False

[22:14:31] hb_init: starting libhb thread
HandBrake svn4929 (2012090301) - MinGW x86_64 - http://handbrake.fr
12 CPUs detected
Opening C:\TEMP\PRISON_BREAK_S4D1.iso...
[22:14:31] hb_scan: path=C:\TEMP\PRISON_BREAK_S4D1.iso, title_index=3
libbluray/bdnav/index_parse.c:157: indx_parse(): error opening C:\TEMP\PRISON_BREAK_S4D1.iso/BDMV/index.bdmv
libbluray/bluray.c:1471: nav_get_title_list(C:\TEMP\PRISON_BREAK_S4D1.iso) failed (0000000001E7E900)
[22:14:31] bd: not a bd - trying as a stream/file instead
libdvdnav: Using dvdnav version 4.1.3
libdvdread: Encrypted DVD support unavailable.
libdvdnav: DVD Title: PRISON_BREAK_SEASON_4_F3_SE_D1  
libdvdnav: DVD Serial Number: 3B227D40
libdvdnav: DVD Title (Alternative): PRISON_BREAK_SEASON_4_F3_SE_D1
libdvdnav: Unable to find home directorylibdvdnav: DVD disk reports itself with Region mask 0x00000000. Regions: 1 2 3 4 5 6 7 8
libdvdread: Encrypted DVD support unavailable.
[22:14:31] scan: DVD has 14 title(s)
[22:14:31] scan: scanning title 3
[22:14:31] scan: opening IFO for VTS 1
[22:14:31] scan: duration is 00:42:10 (2530000 ms)
[22:14:31] pgc_id: 3, pgn: 1: pgc: 0000000001E8AD00
[22:14:31] scan: vts=1, ttn=3, cells=0->15, blocks=1881635->3686074, 901589 blocks
[22:14:31] scan: checking audio 1
[22:14:31] scan: id=0x80bd, lang=English (AC3), 3cc=eng ext=1
[22:14:31] scan: checking audio 2
[22:14:31] scan: id=0x81bd, lang=Italiano (AC3), 3cc=ita ext=1
[22:14:31] scan: checking audio 3
[22:14:31] scan: id=0x82bd, lang=Francais (AC3), 3cc=fra ext=1
[22:14:31] scan: checking audio 4
[22:14:31] scan: audio channel is not active
[22:14:31] scan: checking subtitle 1
[22:14:31] scan: id=0x21bd, lang=English, 3cc=eng ext=1
[22:14:31] scan: checking subtitle 2
[22:14:31] scan: id=0x23bd, lang=Italiano, 3cc=ita ext=1
[22:14:31] scan: checking subtitle 3
[22:14:31] scan: id=0x25bd, lang=Francais, 3cc=fra ext=1
[22:14:31] scan: checking subtitle 4
[22:14:31] scan: id=0x27bd, lang=Nederlands, 3cc=nld ext=1
[22:14:31] scan: checking subtitle 5
[22:14:31] scan: id=0x29bd, lang=Italiano, 3cc=ita ext=1
[22:14:31] scan: checking subtitle 6
[22:14:31] scan: id=0x2bbd, lang=Francais, 3cc=fra ext=1
[22:14:31] scan: checking subtitle 7
[22:14:31] scan: subtitle channel is not active
[22:14:31] scan: checking subtitle 8
[22:14:31] scan: subtitle channel is not active
[22:14:31] scan: checking subtitle 9
[22:14:31] scan: subtitle channel is not active
[22:14:31] scan: title 3 has 13 chapters
[22:14:31] scan: chap 1 c=0->0, b=1881635->2005849 (124215), 341704 ms
[22:14:31] scan: chap 2 c=1->1, b=2005850->2089191 (83342), 237576 ms
[22:14:31] scan: chap 3 c=2->3, b=2089192->2129168 (39977), 111824 ms
[22:14:31] scan: chap 4 c=4->4, b=2129169->2234541 (105373), 302726 ms
[22:14:31] scan: chap 5 c=5->5, b=2234542->2274192 (39651), 106573 ms
[22:14:31] scan: chap 6 c=6->7, b=2274193->2334691 (60499), 168016 ms
[22:14:31] scan: chap 7 c=8->8, b=2334692->2400817 (66126), 196603 ms
[22:14:31] scan: chap 8 c=9->9, b=2400818->2508897 (108080), 295612 ms
[22:14:31] scan: chap 9 c=10->11, b=2508898->2559811 (50914), 141503 ms
[22:14:31] scan: chap 10 c=12->12, b=2559812->2610127 (50316), 134289 ms
[22:14:31] scan: chap 11 c=13->13, b=2610128->2697387 (87260), 257846 ms
[22:14:31] scan: chap 12 c=14->14, b=2697388->2783217 (85830), 235592 ms
[22:14:31] scan: chap 13 c=15->15, b=3686069->3686074 (6), 130 ms
[22:14:31] scan: aspect = 1.77778
[22:14:31] scan: decoding previews for title 3
libdvdnav: DVD disk reports itself with Region mask 0x00000000. Regions: 1 2 3 4 5 6 7 8
[22:14:31] scan: title angle(s) 1
[22:14:31] scan: audio 0x81bd: AC-3, rate=48000Hz, bitrate=384000 Italiano (AC3) (5.1 ch)
[22:14:31] scan: audio 0x82bd: AC-3, rate=48000Hz, bitrate=384000 Francais (AC3) (5.1 ch)
[22:14:31] scan: audio 0x80bd: AC-3, rate=48000Hz, bitrate=448000 English (AC3) (5.1 ch)
[22:14:31] scan: 10 previews, 720x576, 25.000 fps, autocrop = 2/2/0/0, aspect 16:9, PAR 64:45
[22:14:31] scan: title (0) job->width:720, job->height:400
[22:14:32] libhb: scan thread found 1 valid title(s)
+ title 3:
  + vts 1, ttn 3, cells 0->15 (901589 blocks)
  + duration: 00:42:10
  + size: 720x576, pixel aspect: 64/45, display aspect: 1.78, 25.000 fps
  + autocrop: 2/2/0/0
  + chapters:
    + 1: cells 0->0, 124215 blocks, duration 00:05:42
    + 2: cells 1->1, 83342 blocks, duration 00:03:58
    + 3: cells 2->3, 39977 blocks, duration 00:01:52
    + 4: cells 4->4, 105373 blocks, duration 00:05:03
    + 5: cells 5->5, 39651 blocks, duration 00:01:47
    + 6: cells 6->7, 60499 blocks, duration 00:02:48
    + 7: cells 8->8, 66126 blocks, duration 00:03:17
    + 8: cells 9->9, 108080 blocks, duration 00:04:56
    + 9: cells 10->11, 50914 blocks, duration 00:02:22
    + 10: cells 12->12, 50316 blocks, duration 00:02:14
    + 11: cells 13->13, 87260 blocks, duration 00:04:18
    + 12: cells 14->14, 85830 blocks, duration 00:03:56
    + 13: cells 15->15, 6 blocks, duration 00:00:00
  + audio tracks:
    + 1, English (AC3) (5.1 ch) (iso639-2: eng), 48000Hz, 448000bps
    + 2, Italiano (AC3) (5.1 ch) (iso639-2: ita), 48000Hz, 384000bps
    + 3, Francais (AC3) (5.1 ch) (iso639-2: fra), 48000Hz, 384000bps
  + subtitle tracks:
    + 1, English (iso639-2: eng) (Bitmap)(VOBSUB)
    + 2, Italiano (iso639-2: ita) (Bitmap)(VOBSUB)
    + 3, Francais (iso639-2: fra) (Bitmap)(VOBSUB)
    + 4, Nederlands (iso639-2: nld) (Bitmap)(VOBSUB)
    + 5, Italiano (iso639-2: ita) (Bitmap)(VOBSUB)
    + 6, Francais (iso639-2: fra) (Bitmap)(VOBSUB)
Reading chapter markers from file C:\Users\FG\AppData\Local\Temp\Prison break S4E02 À bout de souffle-3-chapters.csv
[22:14:32] 1 job(s) to process
[22:14:32] starting job
[22:14:32] sync: expecting 63249 video frames
[22:14:32] job configuration:
[22:14:32]  * source
[22:14:32]    + C:\TEMP\PRISON_BREAK_S4D1.iso
[22:14:32]    + title 3, chapter(s) 1 to 13
[22:14:32]  * destination
[22:14:32]    + C:\TEMP\Prison break S4E02 À bout de souffle.mkv
[22:14:32]    + container: Matroska (.mkv)
[22:14:32]      + chapter markers
[22:14:32]  * video track
[22:14:32]    + decoder: mpeg2
[22:14:32]      + bitrate 9800 kbps
[22:14:32]    + frame rate: same as source (around 25.000 fps)
[22:14:32]    + filters
[22:14:32]      + Framerate Shaper (0:27000000:1080000)
[22:14:32]        + frame rate: same as source (around 25.000 fps)
[22:14:32]      + Crop and Scale (720:572:2:2:0:0)
[22:14:32]        + source: 720 * 576, crop (2/2/0/0): 720 * 572, scale: 720 * 572
[22:14:32]    + strict anamorphic
[22:14:32]      + storage dimensions: 720 * 572, mod 0
[22:14:32]      + pixel aspect ratio: 64 / 45
[22:14:32]      + display dimensions: 1024 * 572
[22:14:32]    + encoder: H.264 (x264)
[22:14:32]      + options: rc-lookahead=10:ref=2:bframes=8:b-adapt=2:direct=auto:me=umh:subme=10:merange=24:analyse=all:trellis=0
[22:14:32]      + quality: 19.50 (RF)
[22:14:32]  * subtitle track 1, Francais (track 6, id 0x2bbd) Picture [VOBSUB] -> Passthrough
[22:14:32]  * subtitle track 2, Francais (track 3, id 0x25bd) Picture [VOBSUB] -> Passthrough
[22:14:32]  * audio track 1
[22:14:32]    + decoder: Francais (AC3) (5.1 ch) (track 3, id 0x82bd)
[22:14:32]      + bitrate: 384 kbps, samplerate: 48000 Hz
[22:14:32]    + mixdown: Dolby Pro Logic II
[22:14:32]    + encoder: AAC (faac)
[22:14:32]      + bitrate: 192 kbps, samplerate: 48000 Hz
[22:14:32]  * audio track 2
[22:14:32]    + decoder: English (AC3) (5.1 ch) (track 1, id 0x80bd)
[22:14:32]      + bitrate: 448 kbps, samplerate: 48000 Hz
[22:14:32]    + mixdown: Dolby Pro Logic II
[22:14:32]    + encoder: AAC (faac)
[22:14:32]      + bitrate: 192 kbps, samplerate: 48000 Hz
libdvdnav: Using dvdnav version 4.1.3
libdvdread: Encrypted DVD support unavailable.
libdvdnav: DVD Title: PRISON_BREAK_SEASON_4_F3_SE_D1  
libdvdnav: DVD Serial Number: 3B227D40
libdvdnav: DVD Title (Alternative): PRISON_BREAK_SEASON_4_F3_SE_D1
libdvdnav: Unable to find home directorylibdvdnav: DVD disk reports itself with Region mask 0x00000000. Regions: 1 2 3 4 5 6 7 8
libdvdread: Encrypted DVD support unavailable.
libdvdnav: DVD disk reports itself with Region mask 0x00000000. Regions: 1 2 3 4 5 6 7 8
[22:14:32] encx264: encoding with stored aspect 64/45
[22:14:32] encx264: Encoding at constant RF 19.500000
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=64/45
[22:14:32] reader: first SCR 146 id 0xe0 DTS 15458
x264 [info]: using cpu capabilities: MMX2 SSE2Fast SSSE3 FastShuffle SSE4.2 AVX
[22:14:32] mpeg2: "Chapter 1" (1) at frame 0 time 3600
x264 [info]: profile High, level 3.0
[22:14:32] sync: first pts is 3600
[22:16:37] mpeg2: "Chapter 2" (2) at frame 8545 time 30765600
[22:17:58] mpeg2: "Chapter 3" (3) at frame 14492 time 52174800
[22:18:33] mpeg2: "Chapter 4" (4) at frame 17282 time 62218800
[22:20:18] mpeg2: "Chapter 5" (5) at frame 24835 time 89409600
[22:20:57] mpeg2: "Chapter 6" (6) at frame 27496 time 98989200
[22:21:52] mpeg2: "Chapter 7" (7) at frame 31686 time 114073200
[22:22:59] mpeg2: "Chapter 8" (8) at frame 36601 time 131767200
[22:24:54] mpeg2: "Chapter 9" (9) at frame 43996 time 158389200
[22:25:46] mpeg2: "Chapter 10" (10) at frame 47548 time 171176400
[22:26:38] mpeg2: "Chapter 11" (11) at frame 50916 time 183301200
[22:28:05] mpeg2: "Chapter 12" (12) at frame 57359 time 206496000
[22:29:18] reader: done. 2 scr changes
[22:29:19] mpeg2: "Chapter 13" (13) at frame 63237 time 227656800
[22:29:19] 2529.520020s: Video -> Film
[22:29:19] work: average encoding speed for job is 71.272598 fps
[22:29:19] sync: got 63238 frames, 63249 expected
[22:29:19] render: lost time: 0 (0 frames)
[22:29:19] render: gained time: 0 (0 frames) (0 not accounted for)
[22:29:19] mpeg2 done: 63238 frames
x264 [info]: frame I:668   Avg QP:16.91  size: 52226  PSNR Mean Y:47.15 U:51.59 V:52.42 Avg:48.13 Global:47.61
x264 [info]: frame P:15357 Avg QP:20.81  size: 17348  PSNR Mean Y:44.05 U:48.57 V:49.44 Avg:45.09 Global:44.46
x264 [info]: frame B:47213 Avg QP:22.68  size:  5343  PSNR Mean Y:43.45 U:48.95 V:49.92 Avg:44.61 Global:43.61
x264 [info]: consecutive B-frames:  3.5%  4.7% 13.1% 18.0% 19.9% 34.6%  4.0%  1.0%  1.1%
x264 [info]: mb I  I16..4:  8.3% 78.8% 12.9%
x264 [info]: mb P  I16..4:  3.4% 21.0%  3.4%  P16..4: 39.4% 14.6%  9.3%  0.8%  0.2%    skip: 7.9%
x264 [info]: mb B  I16..4:  0.3%  1.9%  0.3%  B16..8: 47.7%  8.1%  1.8%  direct: 5.1%  skip:34.6%  L0:41.4% L1:46.7% BI:11.9%
x264 [info]: 8x8 transform intra:76.0% inter:63.6%
x264 [info]: direct mvs  spatial:99.9% temporal:0.1%
x264 [info]: coded y,uvDC,uvAC intra: 83.0% 75.6% 36.6% inter: 18.4% 19.9% 1.0%
x264 [info]: i16 v,h,dc,p: 51% 14%  5% 30%
x264 [info]: i8 v,h,dc,ddl,ddr,vr,hd,vl,hu: 14%  8%  5% 10% 13% 15% 12% 13% 11%
x264 [info]: i4 v,h,dc,ddl,ddr,vr,hd,vl,hu: 18%  9%  3%  8% 13% 16% 11% 12% 10%
x264 [info]: i8c dc,h,v,p: 29% 25% 28% 18%
x264 [info]: Weighted P-Frames: Y:1.8% UV:1.0%
x264 [info]: ref P L0: 60.1% 15.9% 23.8%  0.2%
x264 [info]: ref B L0: 91.4%  8.6%
x264 [info]: ref B L1: 95.0%  5.0%
x264 [info]: SSIM Mean Y:0.9760750 (16.211db)
x264 [info]: PSNR Mean Y:43.630 U:48.886 V:49.832 Avg:44.760 Global:43.830 kb/s:1750.65
[22:29:20] mux: track 0, 63238 frames, 553509124 bytes, 1750.56 kbps, fifo 2048
[22:29:20] mux: track 1, 118571 frames, 60643723 bytes, 191.80 kbps, fifo 4096
[22:29:20] mux: track 2, 118571 frames, 60666938 bytes, 191.87 kbps, fifo 4096
[22:29:20] mux: track 3, 4 frames, 264 bytes, 0.00 kbps, fifo 8
[22:29:20] mux: track 4, 586 frames, 1543838 bytes, 4.88 kbps, fifo 32
[22:29:20] libhb: work result = 0
Encode done!
HandBrake has exited.
User avatar
Rodeo
HandBrake Team
Posts: 12746
Joined: Tue Mar 03, 2009 8:55 pm

Re: [svn4929] A/V desync

Post by Rodeo »

Ayoross wrote:Encode seems ok without the bob decomb filter.
Awesome! Now we know where to start.
We may not be able to fix it right away, but at least we know which commit broke it.
User avatar
JohnAStebbins
HandBrake Team
Posts: 5585
Joined: Sat Feb 09, 2008 7:21 pm

Re: [svn4929] A/V desync

Post by JohnAStebbins »

How far off is the sync? And does it progressively get worse or is it consistently off by about the same amount?

It doesn't really make sense to me that decomb would be the cause if ac3 passthru works. But anything is possible.
User avatar
Rodeo
HandBrake Team
Posts: 12746
Joined: Tue Mar 03, 2009 8:55 pm

Re: [svn4929] A/V desync

Post by Rodeo »

JohnAStebbins wrote:It doesn't really make sense to me that decomb would be the cause if ac3 passthru works. But anything is possible.
Hmm, I missed that. Sorry.
Ayoross
Posts: 48
Joined: Mon Jun 11, 2012 9:07 pm

Re: [svn4929] A/V desync

Post by Ayoross »

I had different symptoms : with a first movie, I fixed jerky video and A/V desync with variable framerate (ac3 passthru). With another movie, video was normal (I was using variable framerate) but there was still A/V desync (faac AAC). Then I switched off the bob decomb filter, and the movie was ok.
As far as I can remember, the sync was good at the start of the movie, and getting worse, that's why I didn't see it immediately.

First movie, jerky and out of sync:

Code: Select all

HandBrake svn4929 2012090301
OS: Microsoft Windows NT 6.1.7601 Service Pack 1
CPU: Intel(R) Core(TM) i7-3930K CPU @ 3.20GHz
Ram: 16334 MB, Screen: 1920x1200
Temp Dir: C:\Users\FG\AppData\Local\Temp\
Install Dir: C:\Program Files\Handbrake
Data Dir: C:\Users\FG\AppData\Roaming\HandBrake\HandBrake\0.9.8.4929

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

CLI Query:  -i "C:\TEMP\LOST ROOM 1.iso" -t 1 --angle 1 -c 1-5 -o "C:\TEMP\The lost room E01 La clé.mkv"  -f mkv  --decomb="bob" --strict-anamorphic  --modulus 16 -e x264 -q 19.5 -r 0 --cfr -a 1,2 -E copy:ac3,copy:ac3 -6 auto,auto -R Auto,Auto -B 0,0 -D 0,0 --gain 0,0 --audio-copy-mask none --audio-fallback ffac3 --subtitle 1 --markers="C:\Users\FG\AppData\Local\Temp\The lost room E01 La clé-1-chapters.csv" -x rc-lookahead=10:ref=2:bframes=8:b-adapt=2:direct=auto:me=umh:subme=10:merange=24:analyse=all:trellis=0 --verbose=1
User Query: False

invalid framerate 0
[15:46:28] hb_init: starting libhb thread
HandBrake svn4929 (2012090301) - MinGW x86_64 - http://handbrake.fr
12 CPUs detected
Opening C:\TEMP\LOST ROOM 1.iso...
[15:46:28] hb_scan: path=C:\TEMP\LOST ROOM 1.iso, title_index=1
libbluray/bdnav/index_parse.c:157: indx_parse(): error opening C:\TEMP\LOST ROOM 1.iso/BDMV/index.bdmv
libbluray/bluray.c:1471: nav_get_title_list(C:\TEMP\LOST ROOM 1.iso) failed (0000000001BFE7C0)
[15:46:28] bd: not a bd - trying as a stream/file instead
libdvdnav: Using dvdnav version 4.1.3
libdvdread: Encrypted DVD support unavailable.
libdvdnav: Can't read name block. Probably not a DVD-ROM device.
libdvdnav: Unable to find home directorylibdvdnav: DVD disk reports itself with Region mask 0x00000000. Regions: 1 2 3 4 5 6 7 8
libdvdread: Encrypted DVD support unavailable.
[15:46:28] scan: DVD has 6 title(s)
[15:46:28] scan: scanning title 1
[15:46:28] scan: opening IFO for VTS 1
[15:46:28] scan: duration is 00:41:49 (2509010 ms)
[15:46:28] pgc_id: 1, pgn: 1: pgc: 0000000001C06930
[15:46:28] scan: vts=1, ttn=1, cells=0->4, blocks=0->1079893, 1079894 blocks
[15:46:28] scan: checking audio 1
[15:46:28] scan: id=0x80bd, lang=Francais (AC3), 3cc=fra ext=0
[15:46:28] scan: checking audio 2
[15:46:28] scan: id=0x81bd, lang=English (AC3), 3cc=eng ext=0
[15:46:28] scan: checking subtitle 1
[15:46:28] scan: id=0x21bd, lang=Francais, 3cc=fra ext=0
[15:46:28] scan: title 1 has 5 chapters
[15:46:28] scan: chap 1 c=0->0, b=0->282707 (282708), 668425 ms
[15:46:28] scan: chap 2 c=1->1, b=282708->596688 (313981), 720578 ms
[15:46:28] scan: chap 3 c=2->2, b=596689->1072870 (476182), 1070905 ms
[15:46:28] scan: chap 4 c=3->3, b=1072871->1079885 (7015), 49080 ms
[15:46:28] scan: chap 5 c=4->4, b=1079886->1079893 (8), 20 ms
[15:46:28] scan: aspect = 1.77778
[15:46:28] scan: decoding previews for title 1
libdvdnav: DVD disk reports itself with Region mask 0x00000000. Regions: 1 2 3 4 5 6 7 8
[15:46:28] scan: title angle(s) 1
[15:46:28] scan: audio 0x80bd: AC-3, rate=48000Hz, bitrate=384000 Francais (AC3) (2.0 ch)
[15:46:28] scan: audio 0x81bd: AC-3, rate=48000Hz, bitrate=192000 English (AC3) (2.0 ch)
[15:46:28] scan: content PAR gives wrong aspect 2.37; using container aspect 1.78
[15:46:28] scan: 10 previews, 720x576, 25.000 fps, autocrop = 4/2/2/0, aspect 16:9, PAR 64:45
[15:46:28] scan: title (0) job->width:720, job->height:400
[15:46:28] libhb: scan thread found 1 valid title(s)
+ title 1:
  + vts 1, ttn 1, cells 0->4 (1079894 blocks)
  + duration: 00:41:49
  + size: 720x576, pixel aspect: 64/45, display aspect: 1.78, 25.000 fps
  + autocrop: 4/2/2/0
  + chapters:
    + 1: cells 0->0, 282708 blocks, duration 00:11:08
    + 2: cells 1->1, 313981 blocks, duration 00:12:01
    + 3: cells 2->2, 476182 blocks, duration 00:17:51
    + 4: cells 3->3, 7015 blocks, duration 00:00:49
    + 5: cells 4->4, 8 blocks, duration 00:00:00
  + audio tracks:
    + 1, Francais (AC3) (2.0 ch) (iso639-2: fra), 48000Hz, 384000bps
    + 2, English (AC3) (2.0 ch) (iso639-2: eng), 48000Hz, 192000bps
  + subtitle tracks:
    + 1, Francais (iso639-2: fra) (Bitmap)(VOBSUB)
Reading chapter markers from file C:\Users\FG\AppData\Local\Temp\The lost room E01 La clé-1-chapters.csv
[15:46:28] 1 job(s) to process
[15:46:28] starting job
[15:46:28] yadif thread started for segment 0
[15:46:28] yadif thread started for segment 1
[15:46:28] yadif thread started for segment 2
[15:46:28] yadif thread started for segment 3
[15:46:28] yadif thread started for segment 4
[15:46:28] yadif thread started for segment 5
[15:46:28] yadif thread started for segment 6
[15:46:28] yadif thread started for segment 7
[15:46:28] yadif thread started for segment 8
[15:46:28] yadif thread started for segment 9
[15:46:28] yadif thread started for segment 10
[15:46:28] yadif thread started for segment 11
[15:46:28] decomb filter thread started for segment 0
[15:46:28] decomb filter thread started for segment 1
[15:46:28] decomb filter thread started for segment 2
[15:46:28] decomb filter thread started for segment 3
[15:46:28] decomb filter thread started for segment 4
[15:46:28] decomb filter thread started for segment 5
[15:46:28] decomb filter thread started for segment 6
[15:46:28] decomb filter thread started for segment 7
[15:46:28] decomb filter thread started for segment 8
[15:46:28] decomb filter thread started for segment 9
[15:46:28] decomb filter thread started for segment 10
[15:46:28] decomb filter thread started for segment 11
[15:46:28] mask filter thread started for segment 0
[15:46:28] mask filter thread started for segment 1
[15:46:28] mask filter thread started for segment 2
[15:46:28] mask filter thread started for segment 3
[15:46:28] mask filter thread started for segment 4
[15:46:28] mask filter thread started for segment 5
[15:46:28] mask filter thread started for segment 6
[15:46:28] mask filter thread started for segment 8
[15:46:28] mask filter thread started for segment 7
[15:46:28] mask filter thread started for segment 9
[15:46:28] mask filter thread started for segment 10
[15:46:28] mask filter thread started for segment 11
[15:46:28] mask filter thread started for segment 0
[15:46:28] mask filter thread started for segment 1
[15:46:28] mask filter thread started for segment 2
[15:46:28] mask filter thread started for segment 3
[15:46:28] mask filter thread started for segment 4
[15:46:28] mask filter thread started for segment 5
[15:46:28] mask filter thread started for segment 6
[15:46:28] mask filter thread started for segment 7
[15:46:28] mask filter thread started for segment 8
[15:46:28] mask filter thread started for segment 9
[15:46:28] mask filter thread started for segment 10
[15:46:28] mask filter thread started for segment 11
[15:46:28] mask filter thread started for segment 0
[15:46:28] mask filter thread started for segment 1
[15:46:28] mask filter thread started for segment 2
[15:46:28] mask filter thread started for segment 3
[15:46:28] mask filter thread started for segment 4
[15:46:28] mask filter thread started for segment 5
[15:46:28] mask filter thread started for segment 6
[15:46:28] mask filter thread started for segment 7
[15:46:28] mask filter thread started for segment 8
[15:46:28] mask filter thread started for segment 9
[15:46:28] mask filter thread started for segment 10
[15:46:28] mask filter thread started for segment 11
[15:46:28] mask filter thread started for segment 0
[15:46:28] mask filter thread started for segment 1
[15:46:28] mask filter thread started for segment 2
[15:46:28] mask filter thread started for segment 3
[15:46:28] mask filter thread started for segment 4
[15:46:28] mask filter thread started for segment 5
[15:46:28] mask filter thread started for segment 6
[15:46:28] mask filter thread started for segment 7
[15:46:28] mask filter thread started for segment 8
[15:46:28] mask filter thread started for segment 9
[15:46:28] mask filter thread started for segment 10
[15:46:28] mask filter thread started for segment 11
[15:46:28] sync: expecting 62725 video frames
[15:46:28] job configuration:
[15:46:28]  * source
[15:46:28]    + C:\TEMP\LOST ROOM 1.iso
[15:46:28]    + title 1, chapter(s) 1 to 5
[15:46:28]  * destination
[15:46:28]    + C:\TEMP\The lost room E01 La clé.mkv
[15:46:28]    + container: Matroska (.mkv)
[15:46:28]      + chapter markers
[15:46:28]  * video track
[15:46:28]    + decoder: mpeg2
[15:46:28]      + bitrate 8400 kbps
[15:46:28]    + frame rate: 25.000 fps -> constant 25.000 fps
[15:46:28]    + filters
[15:46:28]      + Decomb (455)
[15:46:28]      + Framerate Shaper (1:27000000:1080000)
[15:46:28]        + frame rate: 25.000 fps -> constant 25.000 fps
[15:46:28]      + Crop and Scale (718:570:4:2:2:0)
[15:46:28]        + source: 720 * 576, crop (4/2/2/0): 718 * 570, scale: 718 * 570
[15:46:28]    + strict anamorphic
[15:46:28]      + storage dimensions: 718 * 570, mod 0
[15:46:28]      + pixel aspect ratio: 64 / 45
[15:46:28]      + display dimensions: 1021 * 570
[15:46:28]    + encoder: H.264 (x264)
[15:46:28]      + options: rc-lookahead=10:ref=2:bframes=8:b-adapt=2:direct=auto:me=umh:subme=10:merange=24:analyse=all:trellis=0
[15:46:28]      + quality: 19.50 (RF)
[15:46:28]  * subtitle track 1, Francais (track 1, id 0x21bd) Picture [VOBSUB] -> Passthrough
[15:46:28]  * audio track 1
[15:46:28]    + decoder: Francais (AC3) (2.0 ch) (track 1, id 0x80bd)
[15:46:28]      + bitrate: 384 kbps, samplerate: 48000 Hz
[15:46:28]    + AC3 Passthru
[15:46:28]  * audio track 2
[15:46:28]    + decoder: English (AC3) (2.0 ch) (track 2, id 0x81bd)
[15:46:28]      + bitrate: 192 kbps, samplerate: 48000 Hz
[15:46:28]    + AC3 Passthru
libdvdnav: Using dvdnav version 4.1.3
libdvdread: Encrypted DVD support unavailable.
libdvdnav: Can't read name block. Probably not a DVD-ROM device.
libdvdnav: Unable to find home directorylibdvdnav: DVD disk reports itself with Region mask 0x00000000. Regions: 1 2 3 4 5 6 7 8
libdvdread: Encrypted DVD support unavailable.
libdvdnav: DVD disk reports itself with Region mask 0x00000000. Regions: 1 2 3 4 5 6 7 8
[15:46:28] encx264: encoding with stored aspect 64/45
[15:46:28] encx264: Encoding at constant RF 19.500000
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=64/45
[15:46:28] reader: first SCR 1146 id 0xe0 DTS 1800
x264 [info]: using cpu capabilities: MMX2 SSE2Fast SSSE3 FastShuffle SSE4.2 AVX
[15:46:28] mpeg2: "Chapter 1" (1) at frame 0 time 3600
x264 [info]: profile High, level 3.0
[15:46:28] sync: first pts is 3600
[15:49:43] mpeg2: "Chapter 2" (2) at frame 16710 time 60159600
[15:53:15] mpeg2: "Chapter 3" (3) at frame 34723 time 125006400
[15:58:34] mpeg2: "Chapter 4" (4) at frame 61479 time 221328000
[15:58:38] reader: done. 2 scr changes
[15:58:38] mpeg2: "Chapter 5" (5) at frame 62715 time 225777600
[15:58:38] 2508.639893s: Video -> Film
[15:58:39] work: average encoding speed for job is 85.808891 fps
[15:58:40] sync: got 62717 frames, 62725 expected
[15:58:40] decomb: deinterlaced 2 | blended 20 | unfiltered 62716 | total 62738
[15:58:40] render: 62747 frames output, 31075 dropped and 31084 duped for CFR/PFR
[15:58:40] render: lost time: 0 (0 frames)
[15:58:40] render: gained time: 0 (0 frames) (0 not accounted for)
[15:58:40] mpeg2 done: 62717 frames
x264 [info]: frame I:556   Avg QP:15.68  size: 48395  PSNR Mean Y:48.42 U:50.87 V:52.33 Avg:49.08 Global:48.35
x264 [info]: frame P:16748 Avg QP:19.76  size: 14560  PSNR Mean Y:44.94 U:46.87 V:48.41 Avg:45.57 Global:45.22
x264 [info]: frame B:45443 Avg QP:21.65  size:  2438  PSNR Mean Y:44.54 U:46.91 V:48.91 Avg:45.25 Global:44.71
x264 [info]: consecutive B-frames:  2.8%  4.7% 13.9% 45.5% 30.9%  0.4%  0.3%  0.2%  1.4%
x264 [info]: mb I  I16..4: 13.4% 65.4% 21.2%
x264 [info]: mb P  I16..4:  3.1% 14.2%  2.4%  P16..4: 44.1% 16.6% 11.2%  0.5%  0.1%    skip: 7.8%
x264 [info]: mb B  I16..4:  0.2%  1.0%  0.1%  B16..8: 24.8%  3.2%  0.7%  direct: 2.3%  skip:67.8%  L0:41.3% L1:54.2% BI: 4.5%
x264 [info]: 8x8 transform intra:71.9% inter:53.9%
x264 [info]: direct mvs  spatial:100.0% temporal:0.0%
x264 [info]: coded y,uvDC,uvAC intra: 83.4% 82.6% 56.9% inter: 13.9% 16.5% 2.3%
x264 [info]: i16 v,h,dc,p: 47% 10%  9% 33%
x264 [info]: i8 v,h,dc,ddl,ddr,vr,hd,vl,hu: 12%  5%  3% 10% 15% 16% 13% 14% 11%
x264 [info]: i4 v,h,dc,ddl,ddr,vr,hd,vl,hu: 15%  5%  3%  9% 14% 16% 13% 13% 12%
x264 [info]: i8c dc,h,v,p: 36% 21% 25% 19%
x264 [info]: Weighted P-Frames: Y:3.5% UV:2.1%
x264 [info]: ref P L0: 58.9% 13.8% 26.9%  0.4%
x264 [info]: ref B L0: 80.7% 19.3%
x264 [info]: ref B L1: 98.2%  1.8%
x264 [info]: SSIM Mean Y:0.9765668 (16.302db)
x264 [info]: PSNR Mean Y:44.683 U:46.931 V:48.809 Avg:45.370 Global:44.865 kb/s:1216.19
[15:58:40] mux: track 0, 62747 frames, 381536728 bytes, 1216.11 kbps, fifo 2048
[15:58:40] mux: track 1, 78394 frames, 120413184 bytes, 383.81 kbps, fifo 4096
[15:58:40] mux: track 2, 78394 frames, 60206592 bytes, 191.90 kbps, fifo 4096
[15:58:40] mux: track 3, 420 frames, 1071452 bytes, 3.42 kbps, fifo 32
[15:58:40] libhb: work result = 0
Encode done!
HandBrake has exited.
same movie, fixed:

Code: Select all

HandBrake svn4929 2012090301
OS: Microsoft Windows NT 6.1.7601 Service Pack 1
CPU: Intel(R) Core(TM) i7-3930K CPU @ 3.20GHz
Ram: 16334 MB, Screen: 1920x1200
Temp Dir: C:\Users\FG\AppData\Local\Temp\
Install Dir: C:\Program Files\Handbrake
Data Dir: C:\Users\FG\AppData\Roaming\HandBrake\HandBrake\0.9.8.4929

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

CLI Query:  -i "C:\TEMP\LOST ROOM 1.iso" -t 1 --angle 1 -c 1-5 -o "C:\TEMP\The lost room E01 La clé.mkv"  -f mkv  --decomb="bob" --strict-anamorphic  --modulus 16 -e x264 -q 19.5 -r 0 --vfr -a 1,2 -E copy:ac3,copy:ac3 -6 auto,auto -R Auto,Auto -B 0,0 -D 0,0 --gain 0,0 --audio-copy-mask none --audio-fallback ffac3 --subtitle 1 --markers="C:\Users\FG\AppData\Local\Temp\The lost room E01 La clé-1-chapters.csv" -x rc-lookahead=10:ref=2:bframes=8:b-adapt=2:direct=auto:me=umh:subme=10:merange=24:analyse=all:trellis=0 --verbose=1
User Query: False

invalid framerate 0
[16:46:18] hb_init: starting libhb thread
HandBrake svn4929 (2012090301) - MinGW x86_64 - http://handbrake.fr
12 CPUs detected
Opening C:\TEMP\LOST ROOM 1.iso...
[16:46:18] hb_scan: path=C:\TEMP\LOST ROOM 1.iso, title_index=1
libbluray/bdnav/index_parse.c:157: indx_parse(): error opening C:\TEMP\LOST ROOM 1.iso/BDMV/index.bdmv
libbluray/bluray.c:1471: nav_get_title_list(C:\TEMP\LOST ROOM 1.iso) failed (0000000001CDE7C0)
[16:46:18] bd: not a bd - trying as a stream/file instead
libdvdnav: Using dvdnav version 4.1.3
libdvdread: Encrypted DVD support unavailable.
libdvdnav: Can't read name block. Probably not a DVD-ROM device.
libdvdnav: Unable to find home directorylibdvdnav: DVD disk reports itself with Region mask 0x00000000. Regions: 1 2 3 4 5 6 7 8
libdvdread: Encrypted DVD support unavailable.
[16:46:18] scan: DVD has 6 title(s)
[16:46:18] scan: scanning title 1
[16:46:18] scan: opening IFO for VTS 1
[16:46:18] scan: duration is 00:41:49 (2509010 ms)
[16:46:18] pgc_id: 1, pgn: 1: pgc: 0000000001CE6930
[16:46:18] scan: vts=1, ttn=1, cells=0->4, blocks=0->1079893, 1079894 blocks
[16:46:18] scan: checking audio 1
[16:46:18] scan: id=0x80bd, lang=Francais (AC3), 3cc=fra ext=0
[16:46:18] scan: checking audio 2
[16:46:18] scan: id=0x81bd, lang=English (AC3), 3cc=eng ext=0
[16:46:18] scan: checking subtitle 1
[16:46:18] scan: id=0x21bd, lang=Francais, 3cc=fra ext=0
[16:46:18] scan: title 1 has 5 chapters
[16:46:18] scan: chap 1 c=0->0, b=0->282707 (282708), 668425 ms
[16:46:18] scan: chap 2 c=1->1, b=282708->596688 (313981), 720578 ms
[16:46:18] scan: chap 3 c=2->2, b=596689->1072870 (476182), 1070905 ms
[16:46:18] scan: chap 4 c=3->3, b=1072871->1079885 (7015), 49080 ms
[16:46:18] scan: chap 5 c=4->4, b=1079886->1079893 (8), 20 ms
[16:46:18] scan: aspect = 1.77778
[16:46:18] scan: decoding previews for title 1
libdvdnav: DVD disk reports itself with Region mask 0x00000000. Regions: 1 2 3 4 5 6 7 8
[16:46:18] scan: title angle(s) 1
[16:46:18] scan: audio 0x80bd: AC-3, rate=48000Hz, bitrate=384000 Francais (AC3) (2.0 ch)
[16:46:18] scan: audio 0x81bd: AC-3, rate=48000Hz, bitrate=192000 English (AC3) (2.0 ch)
[16:46:18] scan: content PAR gives wrong aspect 2.37; using container aspect 1.78
[16:46:18] scan: 10 previews, 720x576, 25.000 fps, autocrop = 4/2/2/0, aspect 16:9, PAR 64:45
[16:46:18] scan: title (0) job->width:720, job->height:400
[16:46:18] libhb: scan thread found 1 valid title(s)
+ title 1:
  + vts 1, ttn 1, cells 0->4 (1079894 blocks)
  + duration: 00:41:49
  + size: 720x576, pixel aspect: 64/45, display aspect: 1.78, 25.000 fps
  + autocrop: 4/2/2/0
  + chapters:
    + 1: cells 0->0, 282708 blocks, duration 00:11:08
    + 2: cells 1->1, 313981 blocks, duration 00:12:01
    + 3: cells 2->2, 476182 blocks, duration 00:17:51
    + 4: cells 3->3, 7015 blocks, duration 00:00:49
    + 5: cells 4->4, 8 blocks, duration 00:00:00
  + audio tracks:
    + 1, Francais (AC3) (2.0 ch) (iso639-2: fra), 48000Hz, 384000bps
    + 2, English (AC3) (2.0 ch) (iso639-2: eng), 48000Hz, 192000bps
  + subtitle tracks:
    + 1, Francais (iso639-2: fra) (Bitmap)(VOBSUB)
Reading chapter markers from file C:\Users\FG\AppData\Local\Temp\The lost room E01 La clé-1-chapters.csv
[16:46:18] 1 job(s) to process
[16:46:18] starting job
[16:46:18] yadif thread started for segment 0
[16:46:18] yadif thread started for segment 1
[16:46:18] yadif thread started for segment 2
[16:46:18] yadif thread started for segment 3
[16:46:18] yadif thread started for segment 4
[16:46:18] yadif thread started for segment 5
[16:46:18] yadif thread started for segment 6
[16:46:18] yadif thread started for segment 7
[16:46:18] yadif thread started for segment 8
[16:46:18] yadif thread started for segment 9
[16:46:18] yadif thread started for segment 10
[16:46:18] yadif thread started for segment 11
[16:46:18] decomb filter thread started for segment 0
[16:46:18] decomb filter thread started for segment 1
[16:46:18] decomb filter thread started for segment 2
[16:46:18] decomb filter thread started for segment 3
[16:46:18] decomb filter thread started for segment 4
[16:46:18] decomb filter thread started for segment 5
[16:46:18] decomb filter thread started for segment 6
[16:46:18] decomb filter thread started for segment 7
[16:46:18] decomb filter thread started for segment 8
[16:46:18] decomb filter thread started for segment 9
[16:46:18] decomb filter thread started for segment 10
[16:46:18] decomb filter thread started for segment 11
[16:46:18] mask filter thread started for segment 0
[16:46:18] mask filter thread started for segment 1
[16:46:18] mask filter thread started for segment 2
[16:46:18] mask filter thread started for segment 3
[16:46:18] mask filter thread started for segment 4
[16:46:18] mask filter thread started for segment 5
[16:46:18] mask filter thread started for segment 6
[16:46:18] mask filter thread started for segment 7
[16:46:18] mask filter thread started for segment 8
[16:46:18] mask filter thread started for segment 9
[16:46:18] mask filter thread started for segment 10
[16:46:18] mask filter thread started for segment 11
[16:46:18] mask filter thread started for segment 0
[16:46:18] mask filter thread started for segment 1
[16:46:18] mask filter thread started for segment 2
[16:46:18] mask filter thread started for segment 3
[16:46:18] mask filter thread started for segment 4
[16:46:18] mask filter thread started for segment 5
[16:46:18] mask filter thread started for segment 6
[16:46:18] mask filter thread started for segment 7
[16:46:18] mask filter thread started for segment 8
[16:46:18] mask filter thread started for segment 9
[16:46:18] mask filter thread started for segment 10
[16:46:18] mask filter thread started for segment 11
[16:46:18] mask filter thread started for segment 0
[16:46:18] mask filter thread started for segment 1
[16:46:18] mask filter thread started for segment 2
[16:46:18] mask filter thread started for segment 3
[16:46:18] mask filter thread started for segment 4
[16:46:18] mask filter thread started for segment 5
[16:46:18] mask filter thread started for segment 6
[16:46:18] mask filter thread started for segment 7
[16:46:18] mask filter thread started for segment 8
[16:46:18] mask filter thread started for segment 9
[16:46:18] mask filter thread started for segment 10
[16:46:18] mask filter thread started for segment 11
[16:46:18] mask filter thread started for segment 0
[16:46:18] mask filter thread started for segment 1
[16:46:18] mask filter thread started for segment 2
[16:46:18] mask filter thread started for segment 3
[16:46:18] mask filter thread started for segment 4
[16:46:18] mask filter thread started for segment 5
[16:46:18] mask filter thread started for segment 6
[16:46:18] mask filter thread started for segment 7
[16:46:18] mask filter thread started for segment 8
[16:46:18] mask filter thread started for segment 9
[16:46:18] mask filter thread started for segment 10
[16:46:18] mask filter thread started for segment 11
[16:46:18] sync: expecting 62725 video frames
[16:46:18] job configuration:
[16:46:18]  * source
[16:46:18]    + C:\TEMP\LOST ROOM 1.iso
[16:46:18]    + title 1, chapter(s) 1 to 5
[16:46:18]  * destination
[16:46:18]    + C:\TEMP\The lost room E01 La clé.mkv
[16:46:18]    + container: Matroska (.mkv)
[16:46:18]      + chapter markers
[16:46:18]  * video track
[16:46:18]    + decoder: mpeg2
[16:46:18]      + bitrate 8400 kbps
[16:46:18]    + frame rate: same as source (around 25.000 fps)
[16:46:18]    + filters
[16:46:18]      + Decomb (455)
[16:46:18]      + Framerate Shaper (0:27000000:1080000)
[16:46:18]        + frame rate: same as source (around 25.000 fps)
[16:46:18]      + Crop and Scale (718:570:4:2:2:0)
[16:46:18]        + source: 720 * 576, crop (4/2/2/0): 718 * 570, scale: 718 * 570
[16:46:18]    + strict anamorphic
[16:46:18]      + storage dimensions: 718 * 570, mod 0
[16:46:18]      + pixel aspect ratio: 64 / 45
[16:46:18]      + display dimensions: 1021 * 570
[16:46:18]    + encoder: H.264 (x264)
[16:46:18]      + options: rc-lookahead=10:ref=2:bframes=8:b-adapt=2:direct=auto:me=umh:subme=10:merange=24:analyse=all:trellis=0
[16:46:18]      + quality: 19.50 (RF)
[16:46:18]  * subtitle track 1, Francais (track 1, id 0x21bd) Picture [VOBSUB] -> Passthrough
[16:46:18]  * audio track 1
[16:46:18]    + decoder: Francais (AC3) (2.0 ch) (track 1, id 0x80bd)
[16:46:18]      + bitrate: 384 kbps, samplerate: 48000 Hz
[16:46:18]    + AC3 Passthru
[16:46:18]  * audio track 2
[16:46:18]    + decoder: English (AC3) (2.0 ch) (track 2, id 0x81bd)
[16:46:18]      + bitrate: 192 kbps, samplerate: 48000 Hz
[16:46:18]    + AC3 Passthru
libdvdnav: Using dvdnav version 4.1.3
libdvdread: Encrypted DVD support unavailable.
libdvdnav: Can't read name block. Probably not a DVD-ROM device.
libdvdnav: Unable to find home directorylibdvdnav: DVD disk reports itself with Region mask 0x00000000. Regions: 1 2 3 4 5 6 7 8
libdvdread: Encrypted DVD support unavailable.
libdvdnav: DVD disk reports itself with Region mask 0x00000000. Regions: 1 2 3 4 5 6 7 8
[16:46:18] encx264: encoding with stored aspect 64/45
[16:46:18] encx264: Encoding at constant RF 19.500000
x264 [warning]: --psnr used with psy on: results will be invalid!
x264 [warning]: [16:46:18] reader: first SCR 1146 id 0xe0 DTS 1800
--tune psnr should be used if attempting to benchmark psnr!
x264 [info]: using SAR=64/45
x264 [info]: using cpu capabilities: MMX2 SSE2Fast SSSE3 FastShuffle SSE4.2 AVX
[16:46:18] mpeg2: "Chapter 1" (1) at frame 0 time 3600
x264 [info]: profile High, level 3.0
[16:46:18] sync: first pts is 3600
[16:50:09] mpeg2: "Chapter 2" (2) at frame 16710 time 60159600
[16:54:22] mpeg2: "Chapter 3" (3) at frame 34723 time 125006400
[17:00:52] mpeg2: "Chapter 4" (4) at frame 61479 time 221328000
[17:00:56] reader: done. 2 scr changes
[17:00:56] mpeg2: "Chapter 5" (5) at frame 62715 time 225777600
[17:00:56] 2508.639893s: Video -> Film
[17:00:57] work: average encoding speed for job is 71.409454 fps
[17:00:57] sync: got 62717 frames, 62725 expected
[17:00:57] decomb: deinterlaced 2 | blended 20 | unfiltered 62716 | total 62738
[17:00:58] render: lost time: 0 (0 frames)
[17:00:58] render: gained time: 0 (0 frames) (0 not accounted for)
[17:00:58] mpeg2 done: 62717 frames
x264 [info]: frame I:553   Avg QP:16.66  size: 42915  PSNR Mean Y:47.91 U:50.56 V:51.94 Avg:48.58 Global:47.80
x264 [info]: frame P:12961 Avg QP:20.31  size: 13876  PSNR Mean Y:44.95 U:47.03 V:48.69 Avg:45.60 Global:45.17
x264 [info]: frame B:49224 Avg QP:21.92  size:  4604  PSNR Mean Y:43.76 U:46.49 V:48.51 Avg:44.55 Global:44.05
x264 [info]: consecutive B-frames:  2.1%  2.4%  6.4% 14.0% 21.8% 34.5% 13.7%  1.7%  3.5%
x264 [info]: mb I  I16..4: 14.9% 65.7% 19.4%
x264 [info]: mb P  I16..4:  4.2% 17.2%  2.8%  P16..4: 42.4% 14.7% 10.2%  0.5%  0.1%    skip: 7.9%
x264 [info]: mb B  I16..4:  0.3%  1.7%  0.2%  B16..8: 48.9%  7.2%  1.4%  direct: 5.1%  skip:35.1%  L0:42.9% L1:52.9% BI: 4.3%
x264 [info]: 8x8 transform intra:71.7% inter:58.7%
x264 [info]: direct mvs  spatial:100.0% temporal:0.0%
x264 [info]: coded y,uvDC,uvAC intra: 80.3% 78.6% 47.9% inter: 16.3% 21.8% 1.7%
x264 [info]: i16 v,h,dc,p: 47% 10%  9% 34%
x264 [info]: i8 v,h,dc,ddl,ddr,vr,hd,vl,hu: 12%  5%  3% 11% 16% 17% 13% 14% 10%
x264 [info]: i4 v,h,dc,ddl,ddr,vr,hd,vl,hu: 15%  5%  3%  9% 14% 17% 13% 13% 12%
x264 [info]: i8c dc,h,v,p: 33% 22% 26% 19%
x264 [info]: Weighted P-Frames: Y:3.9% UV:2.3%
x264 [info]: ref P L0: 59.5% 13.9% 26.2%  0.4%
x264 [info]: ref B L0: 89.1% 10.9%
x264 [info]: ref B L1: 91.8%  8.2%
x264 [info]: SSIM Mean Y:0.9733587 (15.744db)
x264 [info]: PSNR Mean Y:44.041 U:46.638 V:48.581 Avg:44.803 Global:44.286 kb/s:1371.41
[17:00:58] mux: track 0, 62738 frames, 430173510 bytes, 1371.33 kbps, fifo 2048
[17:00:58] mux: track 1, 78394 frames, 120413184 bytes, 383.86 kbps, fifo 4096
[17:00:58] mux: track 2, 78394 frames, 60206592 bytes, 191.93 kbps, fifo 4096
[17:00:58] mux: track 3, 420 frames, 1071452 bytes, 3.42 kbps, fifo 32
[17:00:58] libhb: work result = 0
Encode done!
HandBrake has exited.
User avatar
JohnAStebbins
HandBrake Team
Posts: 5585
Joined: Sat Feb 09, 2008 7:21 pm

Re: [svn4929] A/V desync

Post by JohnAStebbins »

Well, bob doubles framerate. So in your first attempt where you specified constant framerate, you doubled the rate with bob to 50fps, then dropped every other frame to bring the rate back down to 25fps. I wouldn't expect it to be all that jerky or out of sync, but I wouldn't expect it to look all that great either. I'll have to experiment with this combination of settings to see if there is some unexpected bad interaction going on.

Something is certainly suspicious in this statistic. It's dropping about half the frames and duplicating about the same number. Something must be wonky with the timestamps somewhere. My best guess would be bob is the culprit.

Code: Select all

[15:58:40] render: 62747 frames output, 31075 dropped and 31084 duped for CFR/PFR
User avatar
JohnAStebbins
HandBrake Team
Posts: 5585
Joined: Sat Feb 09, 2008 7:21 pm

Re: [svn4929] A/V desync

Post by JohnAStebbins »

I think this will fix the problem https://trac.handbrake.fr/changeset/4951

Should be available in the next nightly build.
User avatar
Rodeo
HandBrake Team
Posts: 12746
Joined: Tue Mar 03, 2009 8:55 pm

Re: [svn4929] A/V desync

Post by Rodeo »

This reminds me of something I noticed at the last minute and failed to report before the commit:
Ayoross wrote:

Code: Select all

[22:36:15] yadif thread started for segment 0
[22:36:15] yadif thread started for segment 1
[22:36:15] yadif thread started for segment 2
[22:36:15] yadif thread started for segment 3
[22:36:15] yadif thread started for segment 4
[22:36:15] yadif thread started for segment 5
[22:36:15] yadif thread started for segment 6
[22:36:15] yadif thread started for segment 7
[22:36:15] yadif thread started for segment 8
[22:36:15] yadif thread started for segment 9
[22:36:15] yadif thread started for segment 10
[22:36:15] yadif thread started for segment 11
[22:36:15] decomb filter thread started for segment 0
[22:36:15] decomb filter thread started for segment 1
[22:36:15] decomb filter thread started for segment 2
[22:36:15] decomb filter thread started for segment 3
[22:36:15] decomb filter thread started for segment 4
[22:36:15] decomb filter thread started for segment 5
[22:36:15] decomb filter thread started for segment 6
[22:36:15] decomb filter thread started for segment 7
[22:36:15] decomb filter thread started for segment 8
[22:36:15] decomb filter thread started for segment 9
[22:36:15] decomb filter thread started for segment 10
[22:36:15] decomb filter thread started for segment 11
[22:36:15] mask filter thread started for segment 0
[22:36:15] mask filter thread started for segment 1
[22:36:15] mask filter thread started for segment 2
[22:36:15] mask filter thread started for segment 3
[22:36:15] mask filter thread started for segment 4
[22:36:15] mask filter thread started for segment 5
[22:36:15] mask filter thread started for segment 6
[22:36:15] mask filter thread started for segment 7
[22:36:15] mask filter thread started for segment 8
[22:36:15] mask filter thread started for segment 9
[22:36:15] mask filter thread started for segment 10
[22:36:15] mask filter thread started for segment 11
[22:36:15] mask filter thread started for segment 0
[22:36:15] mask filter thread started for segment 1
[22:36:15] mask filter thread started for segment 2
[22:36:15] mask filter thread started for segment 3
[22:36:15] mask filter thread started for segment 4
[22:36:15] mask filter thread started for segment 5
[22:36:15] mask filter thread started for segment 6
[22:36:15] mask filter thread started for segment 7
[22:36:15] mask filter thread started for segment 8
[22:36:15] mask filter thread started for segment 9
[22:36:15] mask filter thread started for segment 10
[22:36:15] mask filter thread started for segment 11
[22:36:15] mask filter thread started for segment 0
[22:36:15] mask filter thread started for segment 1
[22:36:15] mask filter thread started for segment 2
[22:36:15] mask filter thread started for segment 3
[22:36:15] mask filter thread started for segment 4
[22:36:15] mask filter thread started for segment 5
[22:36:15] mask filter thread started for segment 6
[22:36:15] mask filter thread started for segment 7
[22:36:15] mask filter thread started for segment 8
[22:36:15] mask filter thread started for segment 9
[22:36:15] mask filter thread started for segment 10
[22:36:15] mask filter thread started for segment 11
[22:36:15] mask filter thread started for segment 0
[22:36:15] mask filter thread started for segment 1
[22:36:15] mask filter thread started for segment 2
[22:36:15] mask filter thread started for segment 3
[22:36:15] mask filter thread started for segment 4
[22:36:15] mask filter thread started for segment 5
[22:36:15] mask filter thread started for segment 6
[22:36:15] mask filter thread started for segment 7
[22:36:15] mask filter thread started for segment 8
[22:36:15] mask filter thread started for segment 9
[22:36:15] mask filter thread started for segment 10
[22:36:15] mask filter thread started for segment 11
This looks wrong to me. Is there a reason why multiple mask filter threads are started for the same segment?
User avatar
JohnAStebbins
HandBrake Team
Posts: 5585
Joined: Sat Feb 09, 2008 7:21 pm

Re: [svn4929] A/V desync

Post by JohnAStebbins »

Rodeo wrote:This looks wrong to me. Is there a reason why multiple mask filter threads are started for the same segment?
Thanks for pointing that out. Just a simple issue with several identical log messages. Fixed https://trac.handbrake.fr/changeset/4956
randomreuben
Veteran User
Posts: 468
Joined: Mon Nov 02, 2009 2:18 pm

Re: [svn4929] A/V desync

Post by randomreuben »

Code: Select all

 hb_log("mask dialate thread started for segment %d", segment); 
Should that be dilate or dialate? I don't know any better. Just thought I'd ask.
User avatar
Rodeo
HandBrake Team
Posts: 12746
Joined: Tue Mar 03, 2009 8:55 pm

Re: [svn4929] A/V desync

Post by Rodeo »

Post Reply