by cnapan » Sun Oct 28, 2012 12:25 pm
I have the same problem others are reporting. One or two tracks fail to rip in Secure mode, but happily work in standard mode.
I can confirm the problem still persists in 4.0.7.1506, which I just ran in debug mode, and captured the debug log (see below).
I always assumed the problem was my cd drive, but from reading this thread it seems not.
I'm using Dell Precision T1500 Windows 7 64 bit, with SP1, i7 intel 860@2.8GHz, and the CD drive is: RW HL-DT-ST DVD+- GH50N.
To help track the problem I captured the debug logging while ripping a single track which I know always produces the error. Here's the result below. I've removed all the duplicate events, so if you see a break in the event numbers, it means the event at the start of the break is repeated (the number of times indicated by the size of the jump in the numbering... if that makes sense).
The debug session captures the period from when I start the rip till the error message appears at the end.
I hope you get this fixed. I didn't pay attention to the errors for ages because the tracks appeared to have been ripped, but then when you listen to it, there are problems - often at the end, but I wouldn't want to say precisely how the track is broken.
Code: Select all
00027445 47.79082108 [6256] FLAC: Main loop starting.
00028342 49.29759598 [6256] hpCDEBurn: We are splitting the reading process into more buffers
00029486 51.28553009 [6256] ReadAudioSec(): [t: start=149292, num=892], [tOver: start=0, len=0], [tOver2: start=149292, len=2097984]
00029487 51.28725815 [6256] Thread 'EncodeSongs_Threaded' is going to callback
00030028 52.22949982 [6256] ReadAudioSec(): [t: start=150184, num=892], [tOver: start=0, len=0], [tOver2: start=0, len=0]
00030029 52.23051453 [6256] hpCDEBurn: We are splitting the reading process into more buffers
00032383 56.09427261 [6256] Thread 'EncodeSongs_Threaded' is going to callback
00032387 56.28781128 [6256] hpCDEBurn: We are splitting the reading process into more buffers
00032888 57.03170395 [6256] ReadAudioSec(): [t: start=151968, num=892], [tOver: start=151968, len=2097984], [tOver2: start=0, len=0]
00032889 57.03274155 [6256] hpCDEBurn: We are splitting the reading process into more buffers
00034035 59.01425934 [6256] Thread 'EncodeSongs_Threaded' is going to callback
00034043 59.02297211 [6256] ReadAudioSec(): [t: start=152860, num=892], [tOver: start=0, len=0], [tOver2: start=152860, len=2097984]
00034044 59.02382660 [6256] hpCDEBurn: We are splitting the reading process into more buffers
00034548 59.94970703 [6256] ReadAudioSec(): [t: start=153752, num=892], [tOver: start=0, len=0], [tOver2: start=0, len=0]
00035083 60.89398956 [6256] ReadAudioSec(): [t: start=153752, num=892], [tOver: start=153752, len=2097984], [tOver2: start=0, len=0]
00035084 60.89495087 [6256] hpCDEBurn: We are splitting the reading process into more buffers
00037934 65.64172363 [6256] ReadAudioSec(): [t: start=155536, num=892], [tOver: start=155536, len=2097984], [tOver2: start=156428, len=2097984]
00037935 65.64268494 [6256] hpCDEBurn: We are splitting the reading process into more buffers
00038536 66.67864227 [6256] Thread 'EncodeSongs_Threaded' is going to callback
00038545 66.68738556 [6256] ReadAudioSec(): [t: start=156428, num=892], [tOver: start=0, len=0], [tOver2: start=156428, len=2097984]
00038546 66.68822479 [6256] hpCDEBurn: We are splitting the reading process into more buffers
00039577 68.54349518 [6256] ReadAudioSec(): [t: start=157320, num=892], [tOver: start=157320, len=2097984], [tOver2: start=0, len=0]
00039578 68.54456329 [6256] hpCDEBurn: We are splitting the reading process into more buffers
00041842 72.51254272 [6256] Winamp sim - ignoring.
00042379 73.26293182 [6256] ReadAudioSec(): [t: start=159104, num=892], [tOver: start=159104, len=2097984], [tOver2: start=159996, len=2097984]
00042380 73.26381683 [6256] hpCDEBurn: We are splitting the reading process into more buffers
00042968 74.28064728 [6256] Thread 'EncodeSongs_Threaded' is going to callback
00042969 74.28227997 [6256] hpCDEBurn: We are splitting the reading process into more buffers
00043468 75.20226288 [6256] Thread 'EncodeSongs_Threaded' is going to callback
00043477 75.21093750 [6256] ReadAudioSec(): [t: start=160888, num=892], [tOver: start=0, len=0], [tOver2: start=0, len=0]
00043478 75.21183777 [6256] hpCDEBurn: We are splitting the reading process into more buffers
00043921 76.06581116 [6256] Thread 'UpdateDriveLetters_Var'has finished 6468
00043985 76.13128662 [6256] ReadAudioSec(): [t: start=160888, num=892], [tOver: start=160888, len=2097984], [tOver2: start=0, len=0]
00043986 76.13225555 [6256] hpCDEBurn: We are splitting the reading process into more buffers
00045173 78.15706635 [6256] Thread 'EncodeSongs_Threaded' is going to callback
00045719 79.10894775 [6256] hpCDEBurn: We are splitting the reading process into more buffers
00046273 79.90770721 [6256] ReadAudioSec(): [t: start=162672, num=892], [tOver: start=162672, len=2097984], [tOver2: start=0, len=0]
00046274 79.90858459 [6256] hpCDEBurn: We are splitting the reading process into more buffers
00047357 81.83744812 [6256] ReadAudioSec(): [t: start=163564, num=892], [tOver: start=0, len=0], [tOver2: start=163564, len=2097984]
00047358 81.83911133 [6256] hpCDEBurn: We are splitting the reading process into more buffers
00047853 82.75308990 [6256] Thread 'EncodeSongs_Threaded' is going to callback
00047862 82.76159668 [6256] ReadAudioSec(): [t: start=164456, num=892], [tOver: start=0, len=0], [tOver2: start=0, len=0]
00047863 82.76252747 [6256] hpCDEBurn: We are splitting the reading process into more buffers
00050053 86.50937653 [6256] Thread 'EncodeSongs_Threaded' is going to callback
00050061 86.72033691 [6256] hpCDEBurn: We are splitting the reading process into more buffers
00051014 88.32493591 [6256] ReadAudioSec(): [t: start=166240, num=892], [tOver: start=166240, len=2097984], [tOver2: start=167132, len=2097984]
00051015 88.32595825 [6256] hpCDEBurn: We are splitting the reading process into more buffers
00051608 89.33502197 [6256] Thread 'EncodeSongs_Threaded' is going to callback
00051616 89.34251404 [6256] ReadAudioSec(): [t: start=167132, num=892], [tOver: start=0, len=0], [tOver2: start=167132, len=2097984]
00051617 89.34336853 [6256] hpCDEBurn: We are splitting the reading process into more buffers
00051698 89.42044830 [6256] Thread 'EncodeSongs_Threaded' is going to callback
00052130 90.26444244 [6256] hpCDEBurn: We are splitting the reading process into more buffers
00052190 90.32393646 [6256] Thread 'EncodeSongs_Threaded' is going to callback
00052733 91.27757263 [6256] hpCDEBurn: We are splitting the reading process into more buffers
00053286 92.06070709 [6256] ReadAudioSec(): [t: start=168024, num=892], [tOver: start=168024, len=2097984], [tOver2: start=168916, len=2097984]
00053287 92.06171417 [6256] hpCDEBurn: We are splitting the reading process into more buffers
00053842 93.04798126 [6256] Thread 'EncodeSongs_Threaded' is going to callback
00053850 93.05608368 [6256] ReadAudioSec(): [t: start=168916, num=892], [tOver: start=0, len=0], [tOver2: start=168916, len=2097984]
00053851 93.05697632 [6256] hpCDEBurn: We are splitting the reading process into more buffers
00053911 93.30666351 [6256] Thread 'EncodeSongs_Threaded' is going to callback
00054332 93.95075989 [6256] ReadAudioSec(): [t: start=169808, num=892], [tOver: start=0, len=0], [tOver2: start=0, len=0]
00054333 93.95206451 [6256] hpCDEBurn: We are splitting the reading process into more buffers
00056535 97.65472412 [6256] Thread 'EncodeSongs_Threaded' is going to callback
00056543 97.66319275 [6256] ReadAudioSec(): [t: start=171592, num=892], [tOver: start=0, len=0], [tOver2: start=0, len=0]
00056544 97.66410828 [6256] hpCDEBurn: We are splitting the reading process into more buffers
00057005 98.56230164 [6256] ReadAudioSec(): [t: start=171592, num=892], [tOver: start=171592, len=2097984], [tOver2: start=0, len=0]
00057006 98.56323242 [6256] hpCDEBurn: We are splitting the reading process into more buffers
00058630 101.43148041 [6256] Thread 'EncodeSongs_Threaded' is going to callback
00059215 102.43010712 [6256] hpCDEBurn: We are splitting the reading process into more buffers
00060273 104.14688873 [6256] Thread 'EncodeSongs_Threaded' is going to callback
00060281 104.15483093 [6256] hpCDEBurn: We are splitting the reading process into more buffers
00060765 105.04061890 [6256] Thread 'EncodeSongs_Threaded' is going to callback
00060774 105.04893494 [6256] hpCDEBurn: We are splitting the reading process into more buffers
00062985 108.70616150 [6256] ReadAudioSec(): [t: start=176944, num=892], [tOver: start=0, len=0], [tOver2: start=0, len=0]
00062986 108.70774841 [6256] hpCDEBurn: We are splitting the reading process into more buffers
00063472 109.60484314 [6256] ReadAudioSec(): [t: start=176944, num=892], [tOver: start=176944, len=2097984], [tOver2: start=0, len=0]
00063473 109.60595703 [6256] hpCDEBurn: We are splitting the reading process into more buffers
00065115 112.35913086 [6256] Thread 'EncodeSongs_Threaded' is going to callback
00065116 112.36000061 [6256] ReadAudioSec(): [t: start=178728, num=892], [tOver: start=0, len=0], [tOver2: start=0, len=0]
00065117 112.36083221 [6256] hpCDEBurn: We are splitting the reading process into more buffers
00065588 113.24606323 [6256] ReadAudioSec(): [t: start=178728, num=892], [tOver: start=178728, len=2097984], [tOver2: start=0, len=0]
00065589 113.24703979 [6256] hpCDEBurn: We are splitting the reading process into more buffers
00066049 114.13009644 [6256] ReadAudioSec(): [t: start=178728, num=892], [tOver: start=178728, len=2097984], [tOver2: start=179620, len=2097984]
00066050 114.13114929 [6256] hpCDEBurn: We are splitting the reading process into more buffers
00068152 117.76117706 [6256] ReadAudioSec(): [t: start=180512, num=892], [tOver: start=180512, len=2097984], [tOver2: start=181404, len=2097984]
00068153 117.76223755 [6256] hpCDEBurn: We are splitting the reading process into more buffers
00068705 118.73778534 [6256] ReadAudioSec(): [t: start=181404, num=892], [tOver: start=0, len=0], [tOver2: start=181404, len=2097984]
00068706 118.73907471 [6256] hpCDEBurn: We are splitting the reading process into more buffers
00068795 118.82598877 [6256] Thread 'EncodeSongs_Threaded' is going to callback
00069232 119.68296051 [6256] hpCDEBurn: We are splitting the reading process into more buffers
00070808 122.36597443 [6256] ReadAudioSec(): [t: start=183188, num=892], [tOver: start=0, len=0], [tOver2: start=183188, len=2097984]
00070809 122.36732483 [6256] hpCDEBurn: We are splitting the reading process into more buffers
00071278 123.24501038 [6256] Thread 'EncodeSongs_Threaded' is going to callback
00071288 123.25386810 [6256] hpCDEBurn: We are splitting the reading process into more buffers
00073365 126.84640503 [6256] ReadAudioSec(): [t: start=185864, num=892], [tOver: start=0, len=0], [tOver2: start=0, len=0]
00073366 126.84743500 [6256] hpCDEBurn: We are splitting the reading process into more buffers
00074483 128.60188293 [6256] ReadAudioSec(): [t: start=185864, num=892], [tOver: start=185864, len=2097984], [tOver2: start=186756, len=2097984]
00074484 128.60299683 [6256] hpCDEBurn: We are splitting the reading process into more buffers
00075032 129.57238770 [6256] Thread 'EncodeSongs_Threaded' is going to callback
00075039 129.58052063 [6256] hpCDEBurn: We are splitting the reading process into more buffers
00075571 130.52635193 [6256] Thread 'EncodeSongs_Threaded' is going to callback
00075961 131.33660889 [6256] hpCDEBurn: We are splitting the reading process into more buffers
00077106 133.14726257 [6256] ReadAudioSec(): [t: start=188540, num=892], [tOver: start=0, len=0], [tOver2: start=188540, len=2097984]
00077107 133.14869690 [6256] hpCDEBurn: We are splitting the reading process into more buffers
00077565 134.02615356 [6256] Thread 'EncodeSongs_Threaded' is going to callback
00077566 134.02705383 [6256] hpCDEBurn: We are splitting the reading process into more buffers
00078021 134.89280701 [6256] Thread 'EncodeSongs_Threaded' is going to callback
00078024 134.91156006 [6256] Forms: RestoreTopMost: Starting
00078025 134.91362000 [6256] MainForm: NC Activate -
00078038 134.92897034 [6256] Forms: NormalizeTopMost: Starting
00078039 134.93270874 [6256] MainForm: NC Activate -
00078574 135.93510437 [6256] TGenThread.Create: ThreadName: UpdateDriveLetters_Var , Running Threads: Internal: 2, All: 28
00078578 135.93873596 [6256] Thread initialized 1612
00078586 135.94746399 [6256] Thread 'UpdateDriveLetters_Var'has finished 1612
00081113 140.21447754 [6256] Forms: RestoreTopMost: Starting
00081115 140.21792603 [6256] MainForm: NC Activate +
00081116 140.21949768 [6256] hpCDEBurn: Freeing device
00081117 140.22041321 [6256] Thread 'CDRead_Var'has finished 4688
00081172 140.46321106 [6256] Unlocked file: Q:\mirrored\Music\Herbert Blomstedt - San Francisco Symphony\Nielsen- The Symphonies 1-3 [Disc 1]\05 - Symphony No.2 'The Four Temperaments'- 1. Allegro collerico.flac.XXXXXX
00081351 140.65228271 [6256] 6364 DB lock finished, took 64.
00081358 140.65812683 [6256] 6364 DB lock finished, took 214.
00081479 140.97695923 [6256] MainForm: NC Activate -
00081534 141.04756165 [6256] Winamp sim - ignoring.
00081678 141.19822693 [6256] BQ: Starting a new thread.
00081689 141.20756531 [6256] TGenThread.Create: ThreadName: BackgroundQueue , Running Threads: Internal: 1, All: 27
00081692 141.21092224 [6256] 6412 DB open SQL: SELECT Songs.ID FROM Songs WHERE ( Songs.TrackType in (3) ) LIMIT 1
00081699 141.22357178 [6256] 6412 DB lock finished, took 47.
00081700 141.22523499 [6256] New thread starting 1
00081711 141.23486328 [6256] TGenThread.Create: ThreadName: , Running Threads: Internal: 2, All: 28
00081715 141.23852539 [6256] Thread '' is going to callback
00081716 141.23985291 [6256] Thread ''has finished 4644
00082280 142.22050476 [6256] TGenThread.Create: ThreadName: _ManageTransactions , Running Threads: Internal: 2, All: 28
00082284 142.22406006 [6256] Thread '_ManageTransactions'has finished 5844
00083418 144.23806763 [6256] Thread 'BackgroundQueue'has finished 6412
00083429 144.24729919 [6256] TGenThread.Create: ThreadName: _ManageTransactions , Running Threads: Internal: 2, All: 28
00083433 144.25080872 [6256] Thread '_ManageTransactions'has finished 7052
I have the same problem others are reporting. One or two tracks fail to rip in Secure mode, but happily work in standard mode.
I can confirm the problem still persists in 4.0.7.1506, which I just ran in debug mode, and captured the debug log (see below).
I always assumed the problem was my cd drive, but from reading this thread it seems not.
I'm using Dell Precision T1500 Windows 7 64 bit, with SP1, i7 intel 860@2.8GHz, and the CD drive is: RW HL-DT-ST DVD+- GH50N.
To help track the problem I captured the debug logging while ripping a single track which I know always produces the error. Here's the result below. I've removed all the duplicate events, so if you see a break in the event numbers, it means the event at the start of the break is repeated (the number of times indicated by the size of the jump in the numbering... if that makes sense).
The debug session captures the period from when I start the rip till the error message appears at the end.
I hope you get this fixed. I didn't pay attention to the errors for ages because the tracks appeared to have been ripped, but then when you listen to it, there are problems - often at the end, but I wouldn't want to say precisely how the track is broken.
[code]
00027445 47.79082108 [6256] FLAC: Main loop starting.
00028342 49.29759598 [6256] hpCDEBurn: We are splitting the reading process into more buffers
00029486 51.28553009 [6256] ReadAudioSec(): [t: start=149292, num=892], [tOver: start=0, len=0], [tOver2: start=149292, len=2097984]
00029487 51.28725815 [6256] Thread 'EncodeSongs_Threaded' is going to callback
00030028 52.22949982 [6256] ReadAudioSec(): [t: start=150184, num=892], [tOver: start=0, len=0], [tOver2: start=0, len=0]
00030029 52.23051453 [6256] hpCDEBurn: We are splitting the reading process into more buffers
00032383 56.09427261 [6256] Thread 'EncodeSongs_Threaded' is going to callback
00032387 56.28781128 [6256] hpCDEBurn: We are splitting the reading process into more buffers
00032888 57.03170395 [6256] ReadAudioSec(): [t: start=151968, num=892], [tOver: start=151968, len=2097984], [tOver2: start=0, len=0]
00032889 57.03274155 [6256] hpCDEBurn: We are splitting the reading process into more buffers
00034035 59.01425934 [6256] Thread 'EncodeSongs_Threaded' is going to callback
00034043 59.02297211 [6256] ReadAudioSec(): [t: start=152860, num=892], [tOver: start=0, len=0], [tOver2: start=152860, len=2097984]
00034044 59.02382660 [6256] hpCDEBurn: We are splitting the reading process into more buffers
00034548 59.94970703 [6256] ReadAudioSec(): [t: start=153752, num=892], [tOver: start=0, len=0], [tOver2: start=0, len=0]
00035083 60.89398956 [6256] ReadAudioSec(): [t: start=153752, num=892], [tOver: start=153752, len=2097984], [tOver2: start=0, len=0]
00035084 60.89495087 [6256] hpCDEBurn: We are splitting the reading process into more buffers
00037934 65.64172363 [6256] ReadAudioSec(): [t: start=155536, num=892], [tOver: start=155536, len=2097984], [tOver2: start=156428, len=2097984]
00037935 65.64268494 [6256] hpCDEBurn: We are splitting the reading process into more buffers
00038536 66.67864227 [6256] Thread 'EncodeSongs_Threaded' is going to callback
00038545 66.68738556 [6256] ReadAudioSec(): [t: start=156428, num=892], [tOver: start=0, len=0], [tOver2: start=156428, len=2097984]
00038546 66.68822479 [6256] hpCDEBurn: We are splitting the reading process into more buffers
00039577 68.54349518 [6256] ReadAudioSec(): [t: start=157320, num=892], [tOver: start=157320, len=2097984], [tOver2: start=0, len=0]
00039578 68.54456329 [6256] hpCDEBurn: We are splitting the reading process into more buffers
00041842 72.51254272 [6256] Winamp sim - ignoring.
00042379 73.26293182 [6256] ReadAudioSec(): [t: start=159104, num=892], [tOver: start=159104, len=2097984], [tOver2: start=159996, len=2097984]
00042380 73.26381683 [6256] hpCDEBurn: We are splitting the reading process into more buffers
00042968 74.28064728 [6256] Thread 'EncodeSongs_Threaded' is going to callback
00042969 74.28227997 [6256] hpCDEBurn: We are splitting the reading process into more buffers
00043468 75.20226288 [6256] Thread 'EncodeSongs_Threaded' is going to callback
00043477 75.21093750 [6256] ReadAudioSec(): [t: start=160888, num=892], [tOver: start=0, len=0], [tOver2: start=0, len=0]
00043478 75.21183777 [6256] hpCDEBurn: We are splitting the reading process into more buffers
00043921 76.06581116 [6256] Thread 'UpdateDriveLetters_Var'has finished 6468
00043985 76.13128662 [6256] ReadAudioSec(): [t: start=160888, num=892], [tOver: start=160888, len=2097984], [tOver2: start=0, len=0]
00043986 76.13225555 [6256] hpCDEBurn: We are splitting the reading process into more buffers
00045173 78.15706635 [6256] Thread 'EncodeSongs_Threaded' is going to callback
00045719 79.10894775 [6256] hpCDEBurn: We are splitting the reading process into more buffers
00046273 79.90770721 [6256] ReadAudioSec(): [t: start=162672, num=892], [tOver: start=162672, len=2097984], [tOver2: start=0, len=0]
00046274 79.90858459 [6256] hpCDEBurn: We are splitting the reading process into more buffers
00047357 81.83744812 [6256] ReadAudioSec(): [t: start=163564, num=892], [tOver: start=0, len=0], [tOver2: start=163564, len=2097984]
00047358 81.83911133 [6256] hpCDEBurn: We are splitting the reading process into more buffers
00047853 82.75308990 [6256] Thread 'EncodeSongs_Threaded' is going to callback
00047862 82.76159668 [6256] ReadAudioSec(): [t: start=164456, num=892], [tOver: start=0, len=0], [tOver2: start=0, len=0]
00047863 82.76252747 [6256] hpCDEBurn: We are splitting the reading process into more buffers
00050053 86.50937653 [6256] Thread 'EncodeSongs_Threaded' is going to callback
00050061 86.72033691 [6256] hpCDEBurn: We are splitting the reading process into more buffers
00051014 88.32493591 [6256] ReadAudioSec(): [t: start=166240, num=892], [tOver: start=166240, len=2097984], [tOver2: start=167132, len=2097984]
00051015 88.32595825 [6256] hpCDEBurn: We are splitting the reading process into more buffers
00051608 89.33502197 [6256] Thread 'EncodeSongs_Threaded' is going to callback
00051616 89.34251404 [6256] ReadAudioSec(): [t: start=167132, num=892], [tOver: start=0, len=0], [tOver2: start=167132, len=2097984]
00051617 89.34336853 [6256] hpCDEBurn: We are splitting the reading process into more buffers
00051698 89.42044830 [6256] Thread 'EncodeSongs_Threaded' is going to callback
00052130 90.26444244 [6256] hpCDEBurn: We are splitting the reading process into more buffers
00052190 90.32393646 [6256] Thread 'EncodeSongs_Threaded' is going to callback
00052733 91.27757263 [6256] hpCDEBurn: We are splitting the reading process into more buffers
00053286 92.06070709 [6256] ReadAudioSec(): [t: start=168024, num=892], [tOver: start=168024, len=2097984], [tOver2: start=168916, len=2097984]
00053287 92.06171417 [6256] hpCDEBurn: We are splitting the reading process into more buffers
00053842 93.04798126 [6256] Thread 'EncodeSongs_Threaded' is going to callback
00053850 93.05608368 [6256] ReadAudioSec(): [t: start=168916, num=892], [tOver: start=0, len=0], [tOver2: start=168916, len=2097984]
00053851 93.05697632 [6256] hpCDEBurn: We are splitting the reading process into more buffers
00053911 93.30666351 [6256] Thread 'EncodeSongs_Threaded' is going to callback
00054332 93.95075989 [6256] ReadAudioSec(): [t: start=169808, num=892], [tOver: start=0, len=0], [tOver2: start=0, len=0]
00054333 93.95206451 [6256] hpCDEBurn: We are splitting the reading process into more buffers
00056535 97.65472412 [6256] Thread 'EncodeSongs_Threaded' is going to callback
00056543 97.66319275 [6256] ReadAudioSec(): [t: start=171592, num=892], [tOver: start=0, len=0], [tOver2: start=0, len=0]
00056544 97.66410828 [6256] hpCDEBurn: We are splitting the reading process into more buffers
00057005 98.56230164 [6256] ReadAudioSec(): [t: start=171592, num=892], [tOver: start=171592, len=2097984], [tOver2: start=0, len=0]
00057006 98.56323242 [6256] hpCDEBurn: We are splitting the reading process into more buffers
00058630 101.43148041 [6256] Thread 'EncodeSongs_Threaded' is going to callback
00059215 102.43010712 [6256] hpCDEBurn: We are splitting the reading process into more buffers
00060273 104.14688873 [6256] Thread 'EncodeSongs_Threaded' is going to callback
00060281 104.15483093 [6256] hpCDEBurn: We are splitting the reading process into more buffers
00060765 105.04061890 [6256] Thread 'EncodeSongs_Threaded' is going to callback
00060774 105.04893494 [6256] hpCDEBurn: We are splitting the reading process into more buffers
00062985 108.70616150 [6256] ReadAudioSec(): [t: start=176944, num=892], [tOver: start=0, len=0], [tOver2: start=0, len=0]
00062986 108.70774841 [6256] hpCDEBurn: We are splitting the reading process into more buffers
00063472 109.60484314 [6256] ReadAudioSec(): [t: start=176944, num=892], [tOver: start=176944, len=2097984], [tOver2: start=0, len=0]
00063473 109.60595703 [6256] hpCDEBurn: We are splitting the reading process into more buffers
00065115 112.35913086 [6256] Thread 'EncodeSongs_Threaded' is going to callback
00065116 112.36000061 [6256] ReadAudioSec(): [t: start=178728, num=892], [tOver: start=0, len=0], [tOver2: start=0, len=0]
00065117 112.36083221 [6256] hpCDEBurn: We are splitting the reading process into more buffers
00065588 113.24606323 [6256] ReadAudioSec(): [t: start=178728, num=892], [tOver: start=178728, len=2097984], [tOver2: start=0, len=0]
00065589 113.24703979 [6256] hpCDEBurn: We are splitting the reading process into more buffers
00066049 114.13009644 [6256] ReadAudioSec(): [t: start=178728, num=892], [tOver: start=178728, len=2097984], [tOver2: start=179620, len=2097984]
00066050 114.13114929 [6256] hpCDEBurn: We are splitting the reading process into more buffers
00068152 117.76117706 [6256] ReadAudioSec(): [t: start=180512, num=892], [tOver: start=180512, len=2097984], [tOver2: start=181404, len=2097984]
00068153 117.76223755 [6256] hpCDEBurn: We are splitting the reading process into more buffers
00068705 118.73778534 [6256] ReadAudioSec(): [t: start=181404, num=892], [tOver: start=0, len=0], [tOver2: start=181404, len=2097984]
00068706 118.73907471 [6256] hpCDEBurn: We are splitting the reading process into more buffers
00068795 118.82598877 [6256] Thread 'EncodeSongs_Threaded' is going to callback
00069232 119.68296051 [6256] hpCDEBurn: We are splitting the reading process into more buffers
00070808 122.36597443 [6256] ReadAudioSec(): [t: start=183188, num=892], [tOver: start=0, len=0], [tOver2: start=183188, len=2097984]
00070809 122.36732483 [6256] hpCDEBurn: We are splitting the reading process into more buffers
00071278 123.24501038 [6256] Thread 'EncodeSongs_Threaded' is going to callback
00071288 123.25386810 [6256] hpCDEBurn: We are splitting the reading process into more buffers
00073365 126.84640503 [6256] ReadAudioSec(): [t: start=185864, num=892], [tOver: start=0, len=0], [tOver2: start=0, len=0]
00073366 126.84743500 [6256] hpCDEBurn: We are splitting the reading process into more buffers
00074483 128.60188293 [6256] ReadAudioSec(): [t: start=185864, num=892], [tOver: start=185864, len=2097984], [tOver2: start=186756, len=2097984]
00074484 128.60299683 [6256] hpCDEBurn: We are splitting the reading process into more buffers
00075032 129.57238770 [6256] Thread 'EncodeSongs_Threaded' is going to callback
00075039 129.58052063 [6256] hpCDEBurn: We are splitting the reading process into more buffers
00075571 130.52635193 [6256] Thread 'EncodeSongs_Threaded' is going to callback
00075961 131.33660889 [6256] hpCDEBurn: We are splitting the reading process into more buffers
00077106 133.14726257 [6256] ReadAudioSec(): [t: start=188540, num=892], [tOver: start=0, len=0], [tOver2: start=188540, len=2097984]
00077107 133.14869690 [6256] hpCDEBurn: We are splitting the reading process into more buffers
00077565 134.02615356 [6256] Thread 'EncodeSongs_Threaded' is going to callback
00077566 134.02705383 [6256] hpCDEBurn: We are splitting the reading process into more buffers
00078021 134.89280701 [6256] Thread 'EncodeSongs_Threaded' is going to callback
00078024 134.91156006 [6256] Forms: RestoreTopMost: Starting
00078025 134.91362000 [6256] MainForm: NC Activate -
00078038 134.92897034 [6256] Forms: NormalizeTopMost: Starting
00078039 134.93270874 [6256] MainForm: NC Activate -
00078574 135.93510437 [6256] TGenThread.Create: ThreadName: UpdateDriveLetters_Var , Running Threads: Internal: 2, All: 28
00078578 135.93873596 [6256] Thread initialized 1612
00078586 135.94746399 [6256] Thread 'UpdateDriveLetters_Var'has finished 1612
00081113 140.21447754 [6256] Forms: RestoreTopMost: Starting
00081115 140.21792603 [6256] MainForm: NC Activate +
00081116 140.21949768 [6256] hpCDEBurn: Freeing device
00081117 140.22041321 [6256] Thread 'CDRead_Var'has finished 4688
00081172 140.46321106 [6256] Unlocked file: Q:\mirrored\Music\Herbert Blomstedt - San Francisco Symphony\Nielsen- The Symphonies 1-3 [Disc 1]\05 - Symphony No.2 'The Four Temperaments'- 1. Allegro collerico.flac.XXXXXX
00081351 140.65228271 [6256] 6364 DB lock finished, took 64.
00081358 140.65812683 [6256] 6364 DB lock finished, took 214.
00081479 140.97695923 [6256] MainForm: NC Activate -
00081534 141.04756165 [6256] Winamp sim - ignoring.
00081678 141.19822693 [6256] BQ: Starting a new thread.
00081689 141.20756531 [6256] TGenThread.Create: ThreadName: BackgroundQueue , Running Threads: Internal: 1, All: 27
00081692 141.21092224 [6256] 6412 DB open SQL: SELECT Songs.ID FROM Songs WHERE ( Songs.TrackType in (3) ) LIMIT 1
00081699 141.22357178 [6256] 6412 DB lock finished, took 47.
00081700 141.22523499 [6256] New thread starting 1
00081711 141.23486328 [6256] TGenThread.Create: ThreadName: , Running Threads: Internal: 2, All: 28
00081715 141.23852539 [6256] Thread '' is going to callback
00081716 141.23985291 [6256] Thread ''has finished 4644
00082280 142.22050476 [6256] TGenThread.Create: ThreadName: _ManageTransactions , Running Threads: Internal: 2, All: 28
00082284 142.22406006 [6256] Thread '_ManageTransactions'has finished 5844
00083418 144.23806763 [6256] Thread 'BackgroundQueue'has finished 6412
00083429 144.24729919 [6256] TGenThread.Create: ThreadName: _ManageTransactions , Running Threads: Internal: 2, All: 28
00083433 144.25080872 [6256] Thread '_ManageTransactions'has finished 7052
[/code]