Page 1 of 1

[MacGUI] [svn 2311] AppleTV Encodes won't sync

Posted: Tue Apr 07, 2009 3:56 pm
by ArrMiHardies
I am having this issue with a variety of sources, but most reproducably with blu-ray sources. Using the AppleTV preset with no changes whatsoever, the end result will not sync to the AppleTV. This has occured to me several times now, a couple times from a DVD source, very frequently from a blu-ray (m2ts) source (about 50% of the time) and pretty much every time from a stand-alone file conversion. The only way I've been able to get the files to sync is to convert using iTunes' built in "Create AppleTV version" which is god-awful slow and pretty much locks up iTunes until it's done. After the iTunes has created the appleTV version, it seems the main difference is the final bitrate is much lower. I know this is a slightly older svn. I'm upgrading now to see if the problem exists with todays svns, but I thought I'd get this out there in case it hasn't been addressed yet.

Code: Select all

[08:41:42] macgui: trying to open video_ts folder (parent directory chosen)
[08:41:42] hb_scan: path=/Volumes/Proteus/igor.m2ts, title_index=0
[08:41:42] scan: trying to open with libdvdread
[08:41:42] dvd: not a dvd - trying as a stream/file instead
[08:41:42] file is MPEG Transport Stream with 192 byte packets offset 4 bytes
[08:41:42] hb_ts_stream_find_pids - found the following PIDS
[08:41:42]     Video PIDS : 
[08:41:42]       0x1011 type H.264 (0x1b)
[08:41:42]     Audio PIDS : 
[08:41:42]       0x1100 type AC-3 (0x81)
[08:41:46] transport stream pid 0x1100 (type 0x81) may be AC-3 audio (id 0x1)
[08:41:46] scan: decoding previews for title 1
[08:41:47] scan: audio 0x1: AC-3, rate=48000Hz, bitrate=640000 Unknown (AC3) (5.1 ch)
[h264 @ 0x1ea3c00]non-existing SPS 0 referenced in buffering period
[h264 @ 0x1ea3c00]non-existing PPS referenced
[h264 @ 0x1ea3c00]non-existing SPS 31 referenced in buffering period
[h264 @ 0x1ea3c00]non-existing PPS referenced
[h264 @ 0x1ea3c00]non-existing SPS 0 referenced in buffering period
[h264 @ 0x1ea3c00]non-existing PPS referenced
[h264 @ 0x1ea3c00]non-existing SPS 31 referenced in buffering period
[h264 @ 0x1ea3c00]non-existing PPS referenced
[h264 @ 0x1ea3c00]non-existing SPS 0 referenced in buffering period
[h264 @ 0x1ea3c00]non-existing PPS referenced
[08:42:02] scan: 10 previews, 1920x1080, 47.952 fps, autocrop = 22/20/0/0, aspect 16:9, PAR 1:1
[08:42:02] scan: title (0) job->width:1920, job->height:1040
[08:42:02] stream: 7 good frames, 0 errors (0%)
[08:42:02] libhb: scan thread found 1 valid title(s)
[08:42:06] macgui: ScanDone state received from fHandle

Re: [MacGUI] [svn 2259+] AppleTV Encodes won't sync

Posted: Tue Apr 07, 2009 5:14 pm
by belloq
I was curious about this... but your log file is a bit short and lacking details as to how you're trying to encode. That only looks like the initial startup scan of the source. You might way to post the full log.

When my encodes won't sync, it's typically because the video dimensions and/or framerate are beyond the aTV specs (960x540@29.97 or 1280x720@24).

Re: [MacGUI] [svn 2259+] AppleTV Encodes won't sync

Posted: Tue Apr 07, 2009 10:28 pm
by ArrMiHardies
as mentioned already, the video dimensions of the pre and post itunes appletv conversion are the same. the only thing I am seeing that is different is the bitrate. for example, the bitrate for the file i included the scan for above, is nearly 4000, while the itunes conversion would leave it around 2000. the dimensions are 947x512. Im currently running the encode again with the newest svn, so ill let you know how it goes when its done.

Re: [MacGUI] [svn 2259+] AppleTV Encodes won't sync

Posted: Tue Apr 07, 2009 11:09 pm
by dynaflash
well, since you don't provide a full encode log I have no way of knowing ( you know better btw) but If you have same as source selected for framerate in your settings, then .......

Code: Select all

[08:42:02] scan: 10 previews, 1920x1080, 47.952 fps, autocrop = 22/20/0/0, aspect 16:9, PAR 1:1
would probably account for the high bitrate using cq as well as a framerate that the atv probably cannot handle.
Having said that, van committed a fix for this in svn http://trac.handbrake.fr/changeset/2288 which probably fixes it if its an hb issue.

Again, a full encode log would eliminate the need for a crystal ball.

Re: [MacGUI] [svn 2259+] AppleTV Encodes won't sync

Posted: Wed Apr 08, 2009 12:16 am
by ArrMiHardies
yeah. ill let you know what happens with todays svn. i didnt have a full log, as it got pushed out of the activity window by some encodes later in the queue or at least, i couldnt figure out which one it was. so i rescanned the original source so i would at least be providing some sort of information.

Re: [MacGUI] [svn 2259+] AppleTV Encodes won't sync

Posted: Wed Apr 08, 2009 3:26 am
by ArrMiHardies
Looks like svn 2311 fixes the issue. the encode that didnt work on 2259 worked on 2311 and was about half the size, and half the bitrate. Now I need to go back through and redo the half dozen dvds or so that didn't work on the previous svn. Thats the perils of using an svn release though.

Re: [MacGUI] [svn 2259+] AppleTV Encodes won't sync

Posted: Wed Apr 08, 2009 4:56 am
by TedJ
Is this a good time to mention that Handbrake keeps permanent copies of it's encode logs?

http://trac.handbrake.fr/wiki/ActivityLog

Re: [MacGUI] [svn 2259+] AppleTV Encodes won't sync

Posted: Wed Apr 08, 2009 6:33 am
by belloq
TedJ wrote:Is this a good time to mention that Handbrake keeps permanent copies of it's encode logs?
Wow, even after I delete them?

Re: [MacGUI] [svn 2259+] AppleTV Encodes won't sync

Posted: Wed Apr 08, 2009 2:41 pm
by ArrMiHardies
Well, just redid a couple of dvds with svn 2311 that didn't work previously, and they still won't sync to the appletv using the appleTV presets.
Here is the activity for one

Code: Select all

[23:14:29] scan: title (0) job->width:720, job->height:304
[23:14:29] scan: title (1) job->width:640, job->height:272
[23:14:29] scan: title (2) job->width:624, job->height:480
[23:14:29] scan: title (3) job->width:624, job->height:272
[23:14:29] scan: title (4) job->width:624, job->height:352
[23:14:29] scan: title (5) job->width:704, job->height:304
[23:14:29] scan: title (6) job->width:624, job->height:480
[23:14:29] libhb: scan thread found 7 valid title(s)
[23:14:29] macgui: ScanDone state received from fHandle
[23:18:57] macgui: Rip: Pending queue count is 2
[23:18:57] macgui: scanning specifically for title: 1
[23:18:57] macgui: performNewQueueScan currentQueueEncodeIndex is: 1
[23:18:57] hb_scan: path=/dev/disk8, title_index=1
[23:18:57] scan: trying to open with libdvdread
[23:19:02] scan: DVD has 7 title(s)
[23:19:02] scan: scanning title 1
[23:19:02] scan: opening IFO for VTS 1
[23:19:02] pgc_id: 1, pgn: 1: pgc: 0x19895060
[23:19:02] scan: vts=1, ttn=1, cells=0->18, blocks=20->2200909, 2200890 blocks
[23:19:02] scan: duration is 01:36:04 (5764100 ms)
[23:19:02] scan: checking audio 1
[23:19:02] scan: id=80bd, lang=English (AC3), 3cc=eng ext=0
[23:19:02] scan: checking subtitle 1
[23:19:02] scan: id=20bd, lang=English (Closed Caption), 3cc=eng
[23:19:02] scan: title 1 has 17 chapters
[23:19:02] scan: chap 1 c=0->1, b=20->193473 (193454), 521733 ms
[23:19:02] scan: chap 2 c=2->2, b=193474->314171 (120698), 320400 ms
[23:19:02] scan: chap 3 c=3->3, b=314172->459874 (145703), 378442 ms
[23:19:02] scan: chap 4 c=4->4, b=459875->605593 (145719), 386448 ms
[23:19:02] scan: chap 5 c=5->5, b=605594->726837 (121244), 324256 ms
[23:19:02] scan: chap 6 c=6->6, b=726838->829234 (102397), 272364 ms
[23:19:02] scan: chap 7 c=7->7, b=829235->994424 (165190), 435484 ms
[23:19:02] scan: chap 8 c=8->8, b=994425->1087453 (93029), 244354 ms
[23:19:02] scan: chap 9 c=9->9, b=1087454->1232757 (145304), 374273 ms
[23:19:02] scan: chap 10 c=10->10, b=1232758->1401933 (169176), 423595 ms
[23:19:02] scan: chap 11 c=11->11, b=1401934->1506420 (104487), 279514 ms
[23:19:02] scan: chap 12 c=12->12, b=1506421->1560060 (53640), 140135 ms
[23:19:02] scan: chap 13 c=13->14, b=1560061->1700478 (140418), 375794 ms
[23:19:02] scan: chap 14 c=15->15, b=1700479->1854320 (153842), 410432 ms
[23:19:02] scan: chap 15 c=16->16, b=1854321->2026641 (172321), 459355 ms
[23:19:02] scan: chap 16 c=17->17, b=2026642->2200709 (174068), 417337 ms
[23:19:02] scan: chap 17 c=18->18, b=2200710->2200909 (200), 176 ms
[23:19:02] scan: aspect = 0
[23:19:02] scan: decoding previews for title 1
[23:19:03] scan: audio 0x80bd: AC-3, rate=48000Hz, bitrate=384000 English (AC3) (5.1 ch)
[23:19:06] scan: 10 previews, 720x480, 29.970 fps, autocrop = 64/64/0/2, aspect 4:3, PAR 8:9
[23:19:06] scan: title (0) job->width:640, job->height:352
[23:19:06] libhb: scan thread found 1 valid title(s)
[23:19:06] macgui: ScanDone state received from fQueueEncodeLibhb
[23:19:06] macgui: processNewQueueEncode title list is: 1
[23:19:06] macgui: Preset: AppleTV
[23:19:06] macgui: processNewQueueEncode number of passes expected is: 1
[23:19:06] macgui: prepareJob audiotrack 2 is: 0
[23:19:06] macgui: prepareJob exiting
[23:19:06] 1 job(s) to process
[23:19:06] starting job
[23:19:06] job configuration:
[23:19:06]  * source
[23:19:06]    + /dev/disk8
[23:19:06]    + title 1, chapter(s) 1 to 17
[23:19:06]  * destination
[23:19:06]    + /Volumes/Proteus/HOCUS_POCUS.m4v
[23:19:06]    + container: MPEG-4 (.mp4 and .m4v)
[23:19:06]      + 64-bit formatting
[23:19:06]      + chapter markers
[23:19:06]  * video track
[23:19:06]    + decoder: mpeg2
[23:19:06]      + bitrate 8045 kbps
[23:19:06]    + frame rate: same as source (around 29.970 fps)
[23:19:06]    + loose anamorphic
[23:19:06]      + storage dimensions: 720 * 480 -> 720 * 352, crop 64/64/0/2
[23:19:06]      + pixel aspect ratio: 319 / 360
[23:19:06]      + display dimensions: 638 * 352
[23:19:06]    + encoder: x264
[23:19:06]      + options: level=30:cabac=0:ref=3:mixed-refs=1:bframes=6:weightb=1:direct=auto:no-fast-pskip=1:me=umh:subq=7:analyse=all
[23:19:06]      + quality: 21.00 (RF)
[23:19:06]  * audio track 0
[23:19:06]    + decoder: English (AC3) (5.1 ch) (track 1, id 80bd)
[23:19:06]      + bitrate: 384 kbps, samplerate: 48000 Hz
[23:19:06]    + mixdown: Dolby Pro Logic II
[23:19:06]    + dynamic range compression: 1.000000
[23:19:06]    + encoder: faac
[23:19:06]      + bitrate: 160 kbps, samplerate: 48000 Hz
[23:19:06]  * audio track 1
[23:19:06]    + decoder: English (AC3) (5.1 ch) (track 1, id 80bd)
[23:19:06]      + bitrate: 384 kbps, samplerate: 48000 Hz
[23:19:06]    + AC3 passthrough
[23:19:06] encx264: keyint-min: 30, keyint-max: 300
[23:19:06] encx264: encoding with stored aspect 319/360
[23:19:06] encx264: Encoding at constant RF 21.000000
x264 [info]: using SAR=319/360
x264 [info]: using cpu capabilities: MMX2 Cache64
x264 [info]: profile Main, level 3.0
No accelerated IMDCT transform found
No accelerated IMDCT transform found
[23:19:06] sync: expecting 172780 video frames
[23:19:07] dvd: end of cell 0 at block 20
[23:19:07] reader: first SCR 146
[23:19:07] mpeg2: "Chapter  1" (1) at frame 0 time 3003
[23:19:07] sync: first pts is 3003
[23:45:28] 522.403442s: Film -> Video
[23:45:29] 522.586914s: Video -> Film
[23:45:29] mpeg2: "Chapter  2" (2) at frame 12534 time 47035827
[23:45:31] sync got new chapter 2
[23:58:49] 842.883911s: Film -> Video
[23:58:49] 842.984009s: Video -> Film
[23:58:49] mpeg2: "Chapter  3" (3) at frame 20219 time 75879072
[23:58:51] sync got new chapter 3
[00:14:06] mpeg2: "Chapter  4" (4) at frame 29295 time 109950545
[00:14:07] sync got new chapter 4
[00:29:05] sync: adding 75 ms of silence to audio 0  start 144715302, next 144708480
[00:29:05] sync: adding 75 ms of silence to audio 1  start 144715302, next 144708480
[00:29:06] 1608.018188s: Film -> Video
[00:29:07] 1608.201782s: Video -> Film
[00:29:07] mpeg2: "Chapter  5" (5) at frame 38564 time 144741157
[00:29:09] sync got new chapter 5
[00:43:14] 1933.019531s: Film -> Video
[00:43:14] 1933.119873s: Video -> Film
[00:43:14] mpeg2: "Chapter  6" (6) at frame 46357 time 173991289
[00:43:16] sync got new chapter 6
[00:54:25] mpeg2: "Chapter  7" (7) at frame 52889 time 198511709
[00:54:28] sync got new chapter 7
[01:13:17] 2641.080811s: Film -> Video
[01:13:17] 2641.180908s: Video -> Film
[01:13:18] mpeg2: "Chapter  8" (8) at frame 63334 time 237716794
[01:13:20] sync got new chapter 8
[01:23:56] mpeg2: "Chapter  9" (9) at frame 69202 time 259744708
[01:23:58] sync got new chapter 9
[01:38:59] mpeg2: "Chapter 10" (10) at frame 78175 time 293427271
[01:39:01] sync got new chapter 10
[01:39:12] 3265.734863s: Film -> Video
[01:39:12] 3265.985107s: Video -> Film
[01:59:07] sync: adding 72 ms of silence to audio 1  start 331606821, next 331600320
[01:59:07] sync: adding 72 ms of silence to audio 0  start 331606821, next 331600320
[01:59:09] mpeg2: "Chapter 11" (11) at frame 88354 time 331632313
[01:59:11] sync got new chapter 11
[02:10:23] mpeg2: "Chapter 12" (12) at frame 95065 time 356825371
[02:10:25] sync got new chapter 12
[02:16:31] mpeg2: "Chapter 13" (13) at frame 98423 time 369431330
[02:16:32] sync got new chapter 13
[02:32:34] mpeg2: "Chapter 14" (14) at frame 107454 time 403333121
[02:32:36] sync got new chapter 14
[02:50:09] mpeg2: "Chapter 15" (15) at frame 117294 time 440269463
[02:50:11] sync got new chapter 15
[03:10:33] 5351.935547s: Film -> Video
[03:10:33] 5352.035645s: Video -> Film
[03:10:33] mpeg2: "Chapter 16" (16) at frame 128330 time 481693719
[03:10:35] sync got new chapter 16
[03:24:39] dvd: end of cell 18 at block 2200710
[03:24:40] reader: done. 17 scr changes
[03:24:44] mpeg2: "Chapter 17" (17) at frame 138337 time 519257613
[03:24:46] sync got new chapter 17
[03:24:47] sync: got 138351 frames, 172780 expected
[03:24:47] work: average encoding speed for job is 9.385712 fps
[03:24:48] mpeg2 done: 138352 frames
[03:24:48] render: lost time: 0 (0 frames)
[03:24:48] render: gained time: 0 (0 frames) (0 not accounted for)
x264 [info]: slice I:1741  Avg QP:16.41  size: 34226  PSNR Mean Y:46.68 U:50.50 V:50.77 Avg:47.62 Global:46.96
x264 [info]: slice P:123353 Avg QP:18.54  size:  8206  PSNR Mean Y:43.87 U:47.90 V:48.20 Avg:44.84 Global:43.98
x264 [info]: slice B:90001 Avg QP:20.87  size:  2623  PSNR Mean Y:43.27 U:47.69 V:47.93 Avg:44.30 Global:43.69
x264 [info]: consecutive B-frames: 37.7% 13.2% 21.2% 17.1% 10.3%  0.2%  0.2%
x264 [info]: mb I  I16..4: 11.0%  0.0% 89.0%
x264 [info]: mb P  I16..4:  1.1%  0.0%  5.0%  P16..4: 45.0% 19.3%  7.6%  0.4%  0.5%    skip:21.0%
x264 [info]: mb B  I16..4:  0.1%  0.0%  0.6%  B16..8: 42.5%  1.9%  2.3%  direct: 4.5%  skip:48.0%  L0:33.1% L1:55.9% BI:11.0%
x264 [info]: direct mvs  spatial:99.7%  temporal:0.3%
x264 [info]: ref P L0  79.5% 10.4% 10.1%
x264 [info]: ref B L0  74.1% 25.9%
x264 [info]: SSIM Mean Y:0.9766585
x264 [info]: PSNR Mean Y:43.641 U:47.830 V:48.107 Avg:44.637 Global:43.872 kb/s:1457.90
[03:24:49] libhb: work result = 0
[03:24:49] macgui: incrementQueueItemDone currentQueueEncodeIndex is incremented to: 2
and the other

Code: Select all

[03:24:49] macgui: scanning specifically for title: 1
[03:24:49] macgui: performNewQueueScan currentQueueEncodeIndex is: 2
[03:24:49] hb_scan: path=/dev/disk2, title_index=1
[03:24:49] scan: trying to open with libdvdread
[03:24:51] scan: DVD has 7 title(s)
[03:24:51] scan: scanning title 1
[03:24:51] scan: opening IFO for VTS 1
[03:24:51] pgc_id: 1, pgn: 1: pgc: 0x198c80d0
[03:24:51] scan: vts=1, ttn=1, cells=0->38, blocks=0->2203474, 2203475 blocks
[03:24:51] scan: duration is 01:37:42 (5862310 ms)
[03:24:51] scan: checking audio 1
[03:24:51] scan: id=80bd, lang=English (AC3), 3cc=eng ext=1
[03:24:51] scan: checking audio 2
[03:24:51] scan: id=81bd, lang=English (AC3), 3cc=eng ext=1
[03:24:51] scan: checking audio 3
[03:24:51] scan: id=82bd, lang=English (AC3) (Director's Commentary 1), 3cc=eng ext=3
[03:24:51] scan: checking audio 4
[03:24:51] scan: id=83bd, lang=English (AC3) (Director's Commentary 2), 3cc=eng ext=4
[03:24:51] scan: checking subtitle 1
[03:24:51] scan: id=20bd, lang=English, 3cc=eng
[03:24:51] scan: title 1 has 38 chapters
[03:24:51] scan: chap 1 c=0->0, b=0->52196 (52197), 145557 ms
[03:24:51] scan: chap 2 c=1->1, b=52197->90701 (38505), 93236 ms
[03:24:51] scan: chap 3 c=2->2, b=90702->140384 (49683), 128483 ms
[03:24:51] scan: chap 4 c=3->3, b=140385->215805 (75421), 198460 ms
[03:24:51] scan: chap 5 c=4->4, b=215806->276648 (60843), 154524 ms
[03:24:51] scan: chap 6 c=5->5, b=276649->349525 (72877), 193624 ms
[03:24:51] scan: chap 7 c=6->6, b=349526->391962 (42437), 114284 ms
[03:24:51] scan: chap 8 c=7->7, b=391963->456156 (64194), 169579 ms
[03:24:51] scan: chap 9 c=8->8, b=456157->528807 (72651), 171417 ms
[03:24:51] scan: chap 10 c=9->9, b=528808->642407 (113600), 250601 ms
[03:24:51] scan: chap 11 c=10->10, b=642408->701762 (59355), 132316 ms
[03:24:51] scan: chap 12 c=11->11, b=701763->811692 (109930), 322758 ms
[03:24:51] scan: chap 13 c=12->12, b=811693->838532 (26840), 70349 ms
[03:24:51] scan: chap 14 c=13->13, b=838533->911781 (73249), 176575 ms
[03:24:51] scan: chap 15 c=14->14, b=911782->968800 (57019), 168400 ms
[03:24:51] scan: chap 16 c=15->15, b=968801->1015973 (47173), 116456 ms
[03:24:51] scan: chap 17 c=16->16, b=1015974->1087501 (71528), 196465 ms
[03:24:51] scan: chap 18 c=17->17, b=1087502->1133189 (45688), 113272 ms
[03:24:51] scan: chap 19 c=18->18, b=1133190->1188119 (54930), 149522 ms
[03:24:51] scan: chap 20 c=19->19, b=1188120->1265569 (77450), 213515 ms
[03:24:51] scan: chap 21 c=20->20, b=1265570->1327059 (61490), 169425 ms
[03:24:51] scan: chap 22 c=21->21, b=1327060->1373156 (46097), 121414 ms
[03:24:51] scan: chap 23 c=22->22, b=1373157->1442656 (69500), 191610 ms
[03:24:51] scan: chap 24 c=23->23, b=1442657->1525247 (82591), 197678 ms
[03:24:51] scan: chap 25 c=24->24, b=1525248->1564305 (39058), 128598 ms
[03:24:51] scan: chap 26 c=25->25, b=1564306->1619529 (55224), 155546 ms
[03:24:51] scan: chap 27 c=26->26, b=1619530->1662015 (42486), 137338 ms
[03:24:51] scan: chap 28 c=27->27, b=1662016->1733819 (71804), 205676 ms
[03:24:51] scan: chap 29 c=28->28, b=1733820->1776067 (42248), 133619 ms
[03:24:51] scan: chap 30 c=29->29, b=1776068->1845245 (69178), 199638 ms
[03:24:51] scan: chap 31 c=30->30, b=1845246->1889565 (44320), 97258 ms
[03:24:51] scan: chap 32 c=31->31, b=1889566->1940488 (50923), 134454 ms
[03:24:51] scan: chap 33 c=32->32, b=1940489->1994758 (54270), 122303 ms
[03:24:51] scan: chap 34 c=33->34, b=1994759->2048524 (53766), 115620 ms
[03:24:51] scan: chap 35 c=35->35, b=2048525->2095293 (46769), 133475 ms
[03:24:51] scan: chap 36 c=36->36, b=2095294->2161070 (65777), 185616 ms
[03:24:51] scan: chap 37 c=37->37, b=2161071->2203470 (42400), 153455 ms
[03:24:51] scan: chap 38 c=38->38, b=2203471->2203474 (4), 176 ms
[03:24:51] scan: aspect = 0
[03:24:51] scan: decoding previews for title 1
[03:24:51] scan: audio 0x80bd: AC-3, rate=48000Hz, bitrate=448000 English (AC3) (5.1 ch)
[03:24:51] scan: audio 0x81bd: AC-3, rate=48000Hz, bitrate=192000 English (AC3) (Dolby Surround)
[03:24:51] scan: audio 0x82bd: AC-3, rate=48000Hz, bitrate=192000 English (AC3) (Director's Commentary 1) (Dolby Surround)
[03:24:51] scan: audio 0x83bd: AC-3, rate=48000Hz, bitrate=192000 English (AC3) (Director's Commentary 2) (Dolby Surround)
[03:24:53] scan: 10 previews, 720x480, 29.970 fps, autocrop = 58/56/4/0, aspect 16:9, PAR 32:27
[03:24:53] scan: title (0) job->width:720, job->height:304
[03:24:53] libhb: scan thread found 1 valid title(s)
[03:24:54] macgui: ScanDone state received from fQueueEncodeLibhb
[03:24:54] macgui: processNewQueueEncode title list is: 1
[03:24:54] macgui: Preset: AppleTV
[03:24:54] macgui: processNewQueueEncode number of passes expected is: 1
[03:24:54] macgui: prepareJob audiotrack 2 is: 0
[03:24:54] macgui: prepareJob exiting
[03:24:54] 2 job(s) to process
[03:24:54] starting job
[03:24:54] job configuration:
[03:24:54]  * source
[03:24:54]    + /dev/disk2
[03:24:54]    + title 1, chapter(s) 1 to 38
[03:24:54]  * destination
[03:24:54]    + /Volumes/Proteus/RUSH_HOUR.m4v
[03:24:54]    + container: MPEG-4 (.mp4 and .m4v)
[03:24:54]      + 64-bit formatting
[03:24:54]      + chapter markers
[03:24:54]  * video track
[03:24:54]    + decoder: mpeg2
[03:24:54]      + bitrate 8501 kbps
[03:24:54]    + frame rate: same as source (around 29.970 fps)
[03:24:54]    + loose anamorphic
[03:24:54]      + storage dimensions: 720 * 480 -> 720 * 368, crop 58/56/4/0
[03:24:54]      + pixel aspect ratio: 71 / 60
[03:24:54]      + display dimensions: 852 * 368
[03:24:54]  * subtitle track 0, English (id 20bd)
[03:24:54] sync: expecting 175723 video frames
[03:24:55] reader: first SCR 146
[03:51:13] dvd: end of cell 38 at block 2203471
[03:51:13] dvd: Skipping multi-angle cells 39-218
[03:51:13] reader: done. 1 scr changes
[03:51:13] sync: got 0 frames, 175723 expected
[03:51:13] work: average encoding speed for job is 0.000000 fps
[03:51:13] mpeg2 done: 0 frames
[03:51:13] render: lost time: 0 (0 frames)
[03:51:13] render: gained time: 0 (0 frames) (0 not accounted for)
[03:51:13] Subtitle stream 0x20bd 'English': 2421 hits (0 forced)
[03:51:13] starting job
[03:51:13] job configuration:
[03:51:13]  * source
[03:51:13]    + /dev/disk2
[03:51:13]    + title 1, chapter(s) 1 to 38
[03:51:13]  * destination
[03:51:13]    + /Volumes/Proteus/RUSH_HOUR.m4v
[03:51:13]    + container: MPEG-4 (.mp4 and .m4v)
[03:51:13]      + 64-bit formatting
[03:51:13]      + chapter markers
[03:51:13]  * video track
[03:51:13]    + decoder: mpeg2
[03:51:13]      + bitrate 8501 kbps
[03:51:13]    + frame rate: same as source (around 29.970 fps)
[03:51:13]    + loose anamorphic
[03:51:13]      + storage dimensions: 720 * 480 -> 720 * 368, crop 58/56/4/0
[03:51:13]      + pixel aspect ratio: 71 / 60
[03:51:13]      + display dimensions: 852 * 368
[03:51:13]    + encoder: x264
[03:51:13]      + options: level=30:cabac=0:ref=3:mixed-refs=1:bframes=6:weightb=1:direct=auto:no-fast-pskip=1:me=umh:subq=7:analyse=all
[03:51:13]      + quality: 21.00 (RF)
[03:51:13]  * audio track 0
[03:51:13]    + decoder: English (AC3) (5.1 ch) (track 1, id 80bd)
[03:51:13]      + bitrate: 448 kbps, samplerate: 48000 Hz
[03:51:13]    + mixdown: Dolby Pro Logic II
[03:51:13]    + dynamic range compression: 1.000000
[03:51:13]    + encoder: faac
[03:51:13]      + bitrate: 160 kbps, samplerate: 48000 Hz
[03:51:13]  * audio track 1
[03:51:13]    + decoder: English (AC3) (5.1 ch) (track 1, id 80bd)
[03:51:13]      + bitrate: 448 kbps, samplerate: 48000 Hz
[03:51:13]    + AC3 passthrough
[03:51:13] encx264: keyint-min: 30, keyint-max: 300
[03:51:13] encx264: encoding with stored aspect 71/60
[03:51:13] encx264: Encoding at constant RF 21.000000
x264 [info]: using SAR=71/60
x264 [info]: using cpu capabilities: MMX2 Cache64
x264 [info]: profile Main, level 3.0
No accelerated IMDCT transform found
No accelerated IMDCT transform found
[03:51:13] sync: expecting 175723 video frames
[03:51:14] reader: first SCR 146
[03:51:14] mpeg2: "Chapter  1" (1) at frame 0 time 9009
[03:51:14] sync: first pts is 9009
[03:56:14] mpeg2: "Chapter  2" (2) at frame 3492 time 13151638
[03:56:15] sync got new chapter 2
[03:59:55] 239.505905s: Film -> Video
[03:59:56] mpeg2: "Chapter  3" (3) at frame 5739 time 21579558
[03:59:56] 239.856262s: Video -> Film
[03:59:57] sync got new chapter 3
[04:04:20] 368.618225s: Film -> Video
[04:04:20] 368.801727s: Video -> Film
[04:04:20] mpeg2: "Chapter  4" (4) at frame 8835 time 33196663
[04:04:22] sync got new chapter 4
[04:11:25] 566.649353s: Film -> Video
[04:11:26] 567.016418s: Video -> Film
[04:11:26] mpeg2: "Chapter  5" (5) at frame 13590 time 51034483
[04:11:27] sync got new chapter 5
[04:17:02] 721.203796s: Film -> Video
[04:17:02] 721.320557s: Video -> Film
[04:17:02] mpeg2: "Chapter  6" (6) at frame 17286 time 64923358
[04:17:04] sync got new chapter 6
[04:18:57] 781.881042s: Film -> Video
[04:18:58] 782.064636s: Video -> Film
[04:23:34] 914.680420s: Film -> Video
[04:23:34] 915.030762s: Video -> Film
[04:23:34] mpeg2: "Chapter  7" (7) at frame 21930 time 82355773
[04:23:35] sync got new chapter 7
[04:27:24] 1029.445068s: Film -> Video
[04:27:25] mpeg2: "Chapter  8" (8) at frame 24681 time 92675583
[04:27:25] 1029.812134s: Video -> Film
[04:27:27] sync got new chapter 8
[04:32:59] 1199.631714s: Film -> Video
[04:33:00] 1199.815308s: Video -> Film
[04:33:00] mpeg2: "Chapter  9" (9) at frame 28761 time 107986378
[04:33:01] sync got new chapter 9
[04:39:19] 1371.286621s: Film -> Video
[04:39:19] mpeg2: "Chapter 10" (10) at frame 32880 time 123441318
[04:39:19] 1371.653564s: Video -> Film
[04:39:20] sync got new chapter 10
[04:40:03] 1391.156494s: Film -> Video
[04:40:03] 1391.206543s: Video -> Film
[04:40:04] 1391.656860s: Film -> Video
[04:40:04] 1391.706909s: Video -> Film
[04:40:04] 1391.823730s: Film -> Video
[04:40:04] 1391.873779s: Video -> Film
[04:49:06] 1622.220581s: Film -> Video
[04:49:07] 1622.571045s: Video -> Film
[04:49:07] mpeg2: "Chapter 11" (11) at frame 38901 time 146034388
[04:49:08] sync got new chapter 11
[04:54:23] 1754.836426s: Film -> Video
[04:54:23] 1755.019897s: Video -> Film
[04:54:23] mpeg2: "Chapter 12" (12) at frame 42078 time 157956298
[04:54:25] sync got new chapter 12
[04:57:55] 1861.943481s: Film -> Video
[04:57:55] 1862.126953s: Video -> Film
[04:58:07] 1868.449951s: Film -> Video
[04:58:07] 1868.499878s: Video -> Film
[05:05:51] 2077.508789s: Film -> Video
[05:05:51] mpeg2: "Chapter 13" (13) at frame 49815 time 186984798
[05:05:52] 2077.692383s: Video -> Film
[05:05:53] sync got new chapter 13
[05:08:13] 2148.413086s: Film -> Video
[05:08:14] 2148.596436s: Video -> Film
[05:08:14] mpeg2: "Chapter 14" (14) at frame 51519 time 193376683
[05:08:16] sync got new chapter 14
[05:14:46] 2324.572266s: Film -> Video
[05:14:47] 2324.939209s: Video -> Film
[05:14:47] mpeg2: "Chapter 15" (15) at frame 55749 time 209247538
[05:14:49] sync got new chapter 15
[05:15:48] 2354.619141s: Film -> Video
[05:15:48] 2354.668945s: Video -> Film
[05:15:48] 2354.785889s: Film -> Video
[05:15:48] 2354.835693s: Video -> Film
[05:20:13] 2493.257324s: Film -> Video
[05:20:13] 2493.440918s: Video -> Film
[05:20:13] mpeg2: "Chapter 16" (16) at frame 59790 time 224412688
[05:20:15] sync got new chapter 16
[05:24:52] 2610.207764s: Film -> Video
[05:24:52] 2610.391113s: Video -> Film
[05:24:52] mpeg2: "Chapter 17" (17) at frame 62595 time 234938203
[05:24:55] sync got new chapter 17
[05:32:17] 2806.737305s: Film -> Video
[05:32:18] 2806.920654s: Video -> Film
[05:32:18] mpeg2: "Chapter 18" (18) at frame 67308 time 252625873
[05:32:20] sync got new chapter 18
[05:36:31] 2920.184082s: Film -> Video
[05:36:32] 2920.367432s: Video -> Film
[05:36:32] mpeg2: "Chapter 19" (19) at frame 70029 time 262836073
[05:36:33] sync got new chapter 19
[05:41:41] mpeg2: "Chapter 20" (20) at frame 73614 time 276292516
[05:41:43] sync got new chapter 20
[05:42:26] 3092.522705s: Film -> Video
[05:42:26] 3092.556152s: Video -> Film
[05:42:28] 3093.690674s: Film -> Video
[05:42:28] 3093.740723s: Video -> Film
[05:42:35] 3098.862549s: Film -> Video
[05:42:35] 3099.029297s: Video -> Film
[05:48:58] 3283.713867s: Film -> Video
[05:48:59] 3283.897217s: Video -> Film
[05:48:59] mpeg2: "Chapter 21" (21) at frame 78747 time 295553758
[05:49:00] sync got new chapter 21
[05:54:47] 3452.699463s: Film -> Video
[05:54:48] 3453.066406s: Video -> Film
[05:54:48] mpeg2: "Chapter 22" (22) at frame 82806 time 310781971
[05:54:49] sync got new chapter 22
[05:59:04] mpeg2: "Chapter 23" (23) at frame 85713 time 321694873
[05:59:06] sync got new chapter 23
[06:00:41] 3618.715088s: Film -> Video
[06:00:42] 3618.765137s: Video -> Film
[06:05:54] 3765.344727s: Film -> Video
[06:05:55] 3765.711914s: Video -> Film
[06:05:55] mpeg2: "Chapter 24" (24) at frame 90303 time 338917078
[06:05:56] sync got new chapter 24
[06:13:44] mpeg2: "Chapter 25" (25) at frame 95052 time 356742886
[06:13:46] sync got new chapter 25
[06:18:01] 4091.837891s: Film -> Video
[06:18:01] mpeg2: "Chapter 26" (26) at frame 98130 time 368290923
[06:18:02] 4092.204834s: Video -> Film
[06:18:03] sync got new chapter 26
[06:20:16] 4160.089355s: Film -> Video
[06:20:16] 4160.139160s: Video -> Film
[06:23:17] mpeg2: "Chapter 27" (27) at frame 101874 time 382340458
[06:23:18] sync got new chapter 27
[06:24:41] 4289.051270s: Film -> Video
[06:24:41] 4289.101562s: Video -> Film
[06:26:41] 4358.270508s: Film -> Video
[06:26:42] 4358.454102s: Video -> Film
[06:26:51] 4364.793457s: Film -> Video
[06:26:51] 4364.977539s: Video -> Film
[06:26:58] 4369.631836s: Film -> Video
[06:26:58] 4369.681641s: Video -> Film
[06:27:19] 4382.294922s: Film -> Video
[06:27:19] 4382.344727s: Video -> Film
[06:27:25] mpeg2: "Chapter 28" (28) at frame 105177 time 394730836
[06:27:26] sync got new chapter 28
[06:34:02] 4591.169922s: Film -> Video
[06:34:03] 4591.353516s: Video -> Film
[06:34:03] mpeg2: "Chapter 29" (29) at frame 110106 time 413229316
[06:34:05] sync got new chapter 29
[06:35:12] 4624.536621s: Film -> Video
[06:35:12] 4624.586426s: Video -> Film
[06:38:21] 4724.803711s: Film -> Video
[06:38:22] 4725.170410s: Video -> Film
[06:38:22] mpeg2: "Chapter 30" (30) at frame 113316 time 425268343
[06:38:24] sync got new chapter 30
[06:44:43] 4896.825195s: Film -> Video
[06:44:43] 4896.875488s: Video -> Film
[06:44:57] 4904.666504s: Film -> Video
[06:44:57] 4904.699707s: Video -> Film
[06:45:42] mpeg2: "Chapter 31" (31) at frame 118089 time 443241298
[06:45:43] sync got new chapter 31
[06:49:40] 5021.616699s: Film -> Video
[06:49:41] mpeg2: "Chapter 32" (32) at frame 120417 time 451972521
[06:49:41] 5021.966797s: Video -> Film
[06:49:43] sync got new chapter 32
[06:54:38] 5156.400879s: Film -> Video
[06:54:38] 5156.584961s: Video -> Film
[06:54:38] mpeg2: "Chapter 33" (33) at frame 123648 time 464097133
[06:54:40] sync got new chapter 33
[06:59:28] 5279.040527s: Film -> Video
[06:59:28] 5279.391113s: Video -> Film
[06:59:28] mpeg2: "Chapter 34" (34) at frame 126594 time 475148173
[06:59:29] sync got new chapter 34
[07:03:54] mpeg2: "Chapter 35" (35) at frame 129375 time 485586601
[07:03:56] sync got new chapter 35
[07:08:36] 5528.623047s: Film -> Video
[07:08:36] 5528.806641s: Video -> Film
[07:08:36] mpeg2: "Chapter 36" (36) at frame 132564 time 497595598
[07:08:37] sync got new chapter 36
[07:13:08] 5714.475586s: Film -> Video
[07:13:08] mpeg2: "Chapter 37" (37) at frame 137022 time 514326813
[07:13:08] 5714.826172s: Video -> Film
[07:13:09] sync got new chapter 37
[07:15:20] 5855.933594s: Film -> Video
[07:15:20] 5855.983398s: Video -> Film
[07:15:22] 5858.769531s: Film -> Video
[07:15:22] 5858.819336s: Video -> Film
[07:15:26] 5862.940430s: Film -> Video
[07:15:26] 5862.990723s: Video -> Film
[07:15:26] 5863.440918s: Film -> Video
[07:15:26] 5863.490723s: Video -> Film
[07:15:27] 5864.291992s: Film -> Video
[07:15:27] 5864.508789s: Video -> Film
[07:15:27] dvd: end of cell 38 at block 2203471
[07:15:27] reader: done. 1 scr changes
[07:15:28] 5865.793457s: Film -> Video
[07:15:28] 5865.876465s: Video -> Film
[07:15:29] 5867.778320s: Film -> Video
[07:15:29] mpeg2: "Chapter 38" (38) at frame 140686 time 528122595
[07:15:29] 5868.028809s: Video -> Film
[07:15:29] sync: got 140686 frames, 175723 expected
[07:15:29] work: average encoding speed for job is 11.478549 fps
[07:15:31] mpeg2 done: 140687 frames
[07:15:31] render: lost time: 0 (0 frames)
[07:15:31] render: gained time: 0 (0 frames) (0 not accounted for)
x264 [info]: slice I:1815  Avg QP:18.23  size: 35979  PSNR Mean Y:44.83 U:47.80 V:48.73 Avg:45.66 Global:44.98
x264 [info]: slice P:124029 Avg QP:19.99  size:  6912  PSNR Mean Y:42.39 U:45.05 V:46.42 Avg:43.21 Global:42.50
x264 [info]: slice B:91152 Avg QP:22.60  size:  2020  PSNR Mean Y:42.20 U:45.01 V:46.53 Avg:43.01 Global:42.27
x264 [info]: consecutive B-frames: 35.7% 18.7% 20.8% 14.8%  8.4%  1.0%  0.5%
x264 [info]: mb I  I16..4: 14.1%  0.0% 85.9%
x264 [info]: mb P  I16..4:  1.1%  0.0%  3.7%  P16..4: 44.5% 16.8%  6.5%  0.5%  0.5%    skip:26.5%
x264 [info]: mb B  I16..4:  0.1%  0.0%  0.4%  B16..8: 32.8%  1.7%  1.8%  direct: 3.8%  skip:59.3%  L0:29.8% L1:58.7% BI:11.5%
x264 [info]: direct mvs  spatial:100.0%  temporal:0.0%
x264 [info]: ref P L0  81.5%  9.6%  9.0%
x264 [info]: ref B L0  76.0% 24.0%
x264 [info]: SSIM Mean Y:0.9806020
x264 [info]: PSNR Mean Y:42.332 U:45.054 V:46.487 Avg:43.148 Global:42.413 kb/s:1222.86
[07:15:31] libhb: work result = 0
[07:15:31] macgui: incrementQueueItemDone currentQueueEncodeIndex is incremented to: 3
[07:15:31] macgui: incrementQueueItemDone the 2 item queue is complete

Re: [MacGUI] [svn 2311] AppleTV Encodes won't sync

Posted: Wed Apr 08, 2009 6:26 pm
by belloq
Not that it is always accurate, but can you reply back with what Quicktime player reports as the image dimensions, bitrate and fps for the files? There have been instances when fps set to "same as source" will result in files with significantly higher fps, like 35-40fps. The suggestion in this case is to lock the fps to 29.97.

Re: [MacGUI] [svn 2311] AppleTV Encodes won't sync

Posted: Wed Apr 08, 2009 11:26 pm
by ArrMiHardies
I've since delected the second file, but the first file has an FPS 37.28 , 720x352 bitrate:1911

Re: [MacGUI] [svn 2311] AppleTV Encodes won't sync

Posted: Wed Apr 08, 2009 11:37 pm
by belloq
Well, there's your problem. The aTV won't play anything over 29.97 (or a value really close to it). Sync reported significantly fewer frames than expected: 140686 vs. 175723. Likely related to all the video<->film changes. I'm not sure why this results in a higher output framerate...

Try as I suggested above and lock the frame rate to 29.97. Hopefully, this won't, in turn, introducing frame stutters.

Re: [MacGUI] [svn 2259+] AppleTV Encodes won't sync

Posted: Thu Apr 09, 2009 12:31 am
by TedJ
belloq wrote:
TedJ wrote:Is this a good time to mention that Handbrake keeps permanent copies of it's encode logs?
Wow, even after I delete them?
Of course, since it's already sent the logs along with all your other personal files to the NSA.

I recommend investing in canned goods and shotguns. ;)

Re: [MacGUI] [svn 2311] AppleTV Encodes won't sync

Posted: Thu Apr 09, 2009 6:56 pm
by law.skynet
Hi,

i can confirm this bug. I do not know why but if i use the apple tv profile. there is always a bitrate bigger than 29 fps.

Re: [MacGUI] [svn 2311] AppleTV Encodes won't sync

Posted: Fri Apr 10, 2009 1:31 am
by dynaflash
law.skynet wrote: there is always a bitrate bigger than 29 fps.
No, there is not *always* a bitrate bigger than (29fps????). only on some sources due to vfr.

Re: [MacGUI] [svn 2311] AppleTV Encodes won't sync

Posted: Fri Apr 10, 2009 1:36 am
by ArrMiHardies
Well, locking the framerate seems to work. Gonna suck having to manually check set the framerate on everything just to avoid having to re-encode. Should probably get looked into.

Re: [MacGUI] [svn 2311] AppleTV Encodes won't sync

Posted: Fri Apr 10, 2009 1:39 am
by dynaflash
ArrMiHardies wrote:Well, locking the framerate seems to work. Gonna suck having to manually check set the framerate on everything just to avoid having to re-encode. Should probably get looked into.
Look into what ?? hb cannot tell you the final frame rate in a vfr encode until the encode is complete. Nothing to really look into.
Why don't you just make a duplicate of the ATV preset but with the framerate set to a locked in framerate ?

Besides, its not like its *everything*. almost all dvd's work fine with the supplied vfr. As far as other sources go, most work fine.

Ultimately this is *not* a bug afaiac.

Re: [MacGUI] [svn 2311] AppleTV Encodes won't sync

Posted: Fri Apr 10, 2009 4:46 am
by belloq
Yeah, this does pop up once in a while. It could be something that HB (or the encoders?) could figure out (somehow) down the road. There is a good explanation why a 29.97fps DVD encodes at 35 or 40fps, but the reason remains TBD.

Re: [MacGUI] [svn 2311] AppleTV Encodes won't sync

Posted: Fri Apr 10, 2009 2:42 pm
by jbrjake
belloq wrote:There is a good explanation why a 29.97fps DVD encodes at 35 or 40fps, but the reason remains TBD.
....err....but why do you think the DVD is actually 29.97 when all the evidence indicates it's actually faster?

Re: [MacGUI] [svn 2311] AppleTV Encodes won't sync

Posted: Fri Apr 10, 2009 3:27 pm
by belloq
jbrjake wrote:
belloq wrote:....err....but why do you think the DVD is actually 29.97 when all the evidence indicates it's actually faster?
The way I was thinking here was that the DVD appears to be a standard NTSC disc. It's mounted as a disc, has the correct dimensions and initial framerate of 29.97 reported. I wasn't aware that commercial NTSC DVDs were available that played in a home DVD player that were actually 35-40fps. Simply due to that being out of spec for NTSC. Not fully understanding the world of "standards" vs "what's really on the disc" heavily influences my "thinking" of course.

The end of the encode reports that 140686 frames were processed. The program is stated to run 1h 32m = 5520s. 140686/5520 = 25.49fps, not 37.28fps. If the same frame count (140686) were really running at 37.28fps then the program now only runs for 1 hour and (almost) 3 minutes.

What I really don't understand is what programs do the work to determine the actual FPS vs. those that "trust" some kind of header information. Do iTunes/aTV trust the header? For some encodes there is still some kind of disconnect between actual/reported fps and using the same as source setting which results in 35-40fps being reported (or actual?). This same thing has come up with various DVDs 3-4 times in just the last couple months.

(I know we've already tried to discuss this in other threads and am fully aware that I'm still just not getting it. Good thing it's Friday.)

Re: [MacGUI] [svn 2311] AppleTV Encodes won't sync

Posted: Fri Apr 10, 2009 8:42 pm
by belloq
dynaflash wrote:Ultimately this is *not* a bug afaiac.
If 0.9.3 encodes the same source, with the same settings, but results in a file with the same reported framerate as the source, but the SVN produces something different, is that considered a bug? Or are there changes in the SVN which would account for and explain this as an expected result?

(I don't have such an example, but it's something the OP and others could check, if they haven't already.)

Re: [MacGUI] [svn 2311] AppleTV Encodes won't sync

Posted: Sat Apr 11, 2009 3:33 pm
by jbrjake
Wait, when has anyone said anything about this being a post-0.9.3 issue? Isn't this just VFR sources that speed up the FPS in certain places?

Re: [MacGUI] [svn 2311] AppleTV Encodes won't sync

Posted: Sat Apr 11, 2009 7:53 pm
by belloq
jbrjake wrote:Wait, when has anyone said anything about this being a post-0.9.3 issue? Isn't this just VFR sources that speed up the FPS in certain places?
I don't always type what I'm thinking. I should have written: "I think that the OP or someone else who experiences this issue should determine that ... if 0.9.3 encodes the same source, with the same settings ...." and continue on with my previous query. Thinking this because the post started out discussing an SVN build, not the latest release.