FLAC playback loops and pauses

[SirSinnlos]

Same problem as here:

http://www.mediamonkey.com/forum/viewto ... ck#p256992

Music that I play with Windows Media player (MP3 & WAW) sounds OK.

Other Players play every type of compressed and uncompressed audio without problems on my system too

When I play FLAC’s with Media Monkey, I have significant problems.
The program acts bizarre (hangs, hiccups, unresponsive) and the music also comes out with hiccups, loops (it repeats parts of the same song many times).
When the program is stopped (via Task Manager, because it gets unresponsive), it visually disappears, but music can go on playing for a minute or so.

Same on my PC.

With

OS: Win7 Home Premium x64
CPU: Core i5 750
MB: Asus P7P55D
RAM: 4 GB G-Skill
Sound: M-Audio 1010LT
HD: Samsung HD503HI with sys partition and MM on it
Western WD10EARS-00Y5B1 for storing my Music Collection

I have Media Monkey Gold 3.2.1.1297 using out_asio(dll).dll (but doesn't matter wich output plugin I use)

The problem lasts when I use the onboard sound card instead of the Delta 1010LT.

The problem seems to be a little bit older but I havent found it on the bug tracker so I
thought it would be a good idea to report it...

[SirSinnlos]

Update: It seems to work under Windows XP with the same configuration.
I have installed Windows XP on the same PC and configurated MM as on Win7 (asio output)
Everything works fine here. I'm confused now.

[SyncMusic]

I also have Win7x64 and i play Flac files they thang. MM and my Music Files are on different Drives aswell. I have an Onboard Realtek Soundcard.
I'm using the latest MM3 version, I tried out MM4 beta to, but it also doesnt work there either.

Is there any Fix for this? With other programs the files play back just fine.

[Pazolini]

Hi, guys!

OS: Win7
CPU: Core i3 540
MB: Gigabyte H57
RAM: 4 GB
Sound: Asus Xonar D2
HD: Samsung HD103HJ with sys partition and MM on it
Western WD10EARS-00Y5B1 for storing my Music Collection

The problem lasts when I use the onboard sound card instead of ASUS with ASIO.

I have the same problem with lossless audio, but for instance with ape audio files.

Maybe our WD Advanced Format HDD's unstable function is responsible for this issue (app crash during playback).

Microsoft has published a (beta) Hotfix for win7 to handle properly AFHDD's bigger physical sectors in november this year:
http://support.microsoft.com/kb/982018
I have just red this article , I got no experience with this solution.
I just hope that our files did not bacame corrupt during file copy/move process from the older HDD to this new one...
One more link to this:
http://forums.whirlpool.net.au/archive/1492654

How did you done your job with this horrible thing???
THX

[Pazolini]

Maybe our WD Advanced Format HDD's unstable function is responsible for this issue (app crash during playback).

My solution is:
1. unplug your (to 512Byte-emulation by win7 formatted)
HDD (WD10EARS), and reboot; (turning off)
2. plug in your WD10EARS, and reboot your system
3. Enjoy your high performance HDD without appcrashes and clicksounds/pauses!

[pazolini]

My solution is:

Better Solution may be in some cases the following:

Check the Advanced Format WDxxEARS disk wether it is "non-optimally aligned". Especially when someone have made partitions on it with other tools like Acronis, Paragon than the embedded SW of win7.
It can be checked in 2 different way:
1. WD Align SWs by Paragon or Acronis (free downloads from WD's website) can indicate its state
2. C:/windows/system32/msinfo32.exe shows disk's partition starting offset with a number that must be devided by 4096 (proper alignment)

If the result is not optimal, after a full data saving (just with copying, not cloning!!!), delete your partitions with win7's logical disk manager, than repartition it with 4096 cluster size!

[Peke]

That is know issue with newer larger HDDs Never thought that it would influence playback

Home version of http://www.partitionwizard.com/ also work.

[zoomer]

I am experiencing this problem as well. However, the flacs are stored on a partition on a 1.5TB seagate 7200.11 drive, and that is aligned according to msinfo. It occurs with both onboard audio and a pci x-fi.

Drive media is stored: ST31500341AS, CC1H firmware

Windows 7 x64
4 GB Ram
Athlon II X3 440
Some MSI 785G mobo
OS is on a 1TB Hitachi 7k1000
Various other drives are connected

The flacs play fine in winamp5 and vlc. Did not get a problem with my other files; mp3 or oggs. Wouldn't that pinpoint the issue to the flac plugin in mm?

[nohitter151]

The flacs play fine in winamp5 and vlc. Did not get a problem with my other files; mp3 or oggs. Wouldn't that pinpoint the issue to the flac plugin in mm?

If that was the problem, wouldn't it occur for all MM users?

[zoomer]

The flacs play fine in winamp5 and vlc. Did not get a problem with my other files; mp3 or oggs. Wouldn't that pinpoint the issue to the flac plugin in mm?

If that was the problem, wouldn't it occur for all MM users?

It could be an issue with mm that shows up only under certain conditions.

Towards that end, I tried switching out f_flac.dll with in_flac.dll from winamp5. Unfortunately, mm didn't see the winamp flac plugin. Does anyone know why ther isn't a in_flac by default, and there's a f_flac_codec?


The application and the UI can appear unresponsive. However, all user actions are not lost, they are simply blocked until something releases the block. It also occurs a lot less with the waveOut plugin. One could through 5 minutes, but sound will pause for half a minute, before resuming just as if someone had paused it (without losing any audio, and the time elapsed counter pauses too). There is no looping, which I guess is what the dsound plugin does if it input buffer runs out. (Status of waveOut would drop to 0 blocks queued)
I might hazard a guess that the flac plugin would just stop pushing decoded blocks to the output plugin (through MM), or at least the output plugin doesn't get them for some reason.

The problems also tends to cluster together, ie. plays fine for 5 mins, gets interrupted more and more often every 1/2 min or so for varying amounts of time, decreasing amount of interruptions, and another long stretch. This repeats.

MM 3.2.4.1304
Sound device doesn't matter. Tried with realtek onboard and pci x-fi.

I want to solve this problem because I think MM is realy great, but I can't really use it unless I can fix the flac playback issue. Cranking the buffer length all the way up to compensate for the poor jitter characteristics is a ugly workaround.

[Lowlander]

I advice to create a debug log for the hang: http://www.mediamonkey.com/forum/viewtopic.php?f=7&t=69 and send it together with a small Flac file with this issue to support.

[nohitter151]

The flacs play fine in winamp5 and vlc. Did not get a problem with my other files; mp3 or oggs. Wouldn't that pinpoint the issue to the flac plugin in mm?

If that was the problem, wouldn't it occur for all MM users?

It could be an issue with mm that shows up only under certain conditions.

Towards that end, I tried switching out f_flac.dll with in_flac.dll from winamp5. Unfortunately, mm didn't see the winamp flac plugin. Does anyone know why ther isn't a in_flac by default, and there's a f_flac_codec?

I guess probably the same .dll is used for decoding and encoding, that's why there's just the one file. I have a library of mostly flac files, but I never observed such a problem with playback in MM.

[SyncMusic]

As stated before I also have the problem, but I have a standard HDD, so thats not my issue.

System: Win7 x64, cpu: Amd phenom II x4 955 ,Samsung F1 1TB for C:\ and a Samsung F2 Eco 500GB for E:\ for my Music+Movie Library, MM 3.2.4.1304, f_flac_codec.dll, out MMDS.dll

The problem also occured with older MM Versions and MM4beta.

When A Flac File plays it starts to loop back after half a minute or so. Mediamonkey is also Unresponsive during this time, The Windows thing "(Not Responding)" shows up).
With the waveoutput plugin the song just stops playing after a while but MM stays responsive exept for the lack of audio playback.

I actually reinstalled MM on E:\ and hoped that that might solve the problem but unfortunatly it didnt help.


In the Debug Log looping first started at 56, than at 60 and 65, than it suddenly jumped a bit and looped at aifferent part of the song.

Code: Select all

00000000	0.00000000	[2132] MMDS: Written 101376 bytes	
00000001	0.57477063	[2132] MMDS: Written 101376 bytes	
00000002	1.14457405	[2132] MMDS: Written 101376 bytes	
00000003	1.69709289	[2132] MMDS: Written 101376 bytes	
00000004	2.28865600	[2132] MMDS: Written 101376 bytes	
00000005	2.85869360	[2132] MMDS: Written 101376 bytes	
00000006	3.44804525	[2132] MMDS: Written 101376 bytes	
00000007	4.02023745	[2132] MMDS: Written 101376 bytes	
00000008	4.57030010	[2132] MMDS: Written 101376 bytes	
00000009	5.16028976	[2132] MMDS: Written 101376 bytes	
00000010	5.34475088	[2132] MMDS: ***Playback pointer crossed buffer end***	
00000011	5.71482801	[2132] MMDS: Written 101376 bytes	
00000012	6.30739117	[2132] MMDS: Written 101376 bytes	
00000013	6.89826012	[2132] MMDS: Written 101376 bytes	
00000014	7.46596336	[2132] MMDS: Written 101376 bytes	
00000015	8.03443623	[2132] MMDS: Written 101376 bytes	
00000016	8.59890556	[2132] MMDS: Written 101376 bytes	
00000017	9.16852093	[2132] MMDS: Written 101376 bytes	
00000018	9.80112362	[2132] MMDS: Written 101376 bytes	
00000019	10.35009384	[2132] MMDS: Written 101376 bytes	
00000020	10.91715050	[2132] MMDS: Written 101376 bytes	
00000021	11.46471405	[2132] MMDS: Written 101376 bytes	
00000022	12.05464745	[2132] MMDS: Written 101376 bytes	
00000023	12.62414932	[2132] MMDS: Written 101376 bytes	
00000024	13.19461441	[2132] MMDS: Written 101376 bytes	
00000025	13.53643799	[2132] Thread has started 7468	
00000026	13.53661919	[2132] Thread initialized 7468	
00000027	13.54512691	[2132] Going to make final callback 7468	
00000028	13.77976799	[2132] MMDS: Written 101376 bytes	
00000029	14.37074757	[2132] MMDS: Written 101376 bytes	
00000030	14.93983364	[2132] MMDS: Written 101376 bytes	
00000031	15.49522781	[2132] MMDS: Written 101376 bytes	
00000032	16.06534195	[2132] MMDS: Written 101376 bytes	
00000033	16.67793083	[2132] MMDS: Written 101376 bytes	
00000034	17.24833488	[2132] MMDS: Written 101376 bytes	
00000035	17.32942200	[2132] MMDS: ***Playback pointer crossed buffer end***	
00000036	17.79906273	[2132] MMDS: Written 101376 bytes	
00000037	18.39158058	[2132] MMDS: Written 101376 bytes	
00000038	18.96258354	[2132] MMDS: Written 101376 bytes	
00000039	19.51518822	[2132] MMDS: Written 101376 bytes	
00000040	20.12512970	[2132] MMDS: Written 101376 bytes	
00000041	20.69669914	[2132] MMDS: Written 101376 bytes	
00000042	21.25063515	[2132] MMDS: Written 101376 bytes	
00000043	21.82328796	[2132] MMDS: Written 101376 bytes	
00000044	22.41380501	[2132] MMDS: Written 101376 bytes	
00000045	22.98383904	[2132] MMDS: Written 101376 bytes	
00000046	23.55330849	[2132] MMDS: Written 101376 bytes	
00000047	24.10434914	[2132] MMDS: Written 101376 bytes	
00000048	25.26159859	[2132] MMDS: Written 101376 bytes	
00000049	25.84992218	[2132] MMDS: Written 101376 bytes	
00000050	26.99022102	[2132] MMDS: Written 101376 bytes	
00000051	27.58064270	[2132] MMDS: Written 101376 bytes	
00000052	28.17019081	[2132] MMDS: Written 101376 bytes	
00000053	28.71842766	[2132] MMDS: Written 101376 bytes	
00000054	29.30819511	[2132] MMDS: Written 101376 bytes	
00000055	29.34801102	[2132] MMDS: ***Playback pointer crossed buffer end***	
00000056	41.33671188	[2132] MMDS: ***Playback pointer crossed buffer end***	
00000057	43.54951096	[2132] Thread has started 5476	
00000058	43.54975891	[2132] Thread initialized 5476	
00000059	43.55838394	[2132] Going to make final callback 5476	
00000060	53.33750534	[2132] MMDS: ***Playback pointer crossed buffer end***	
00000061	65.31819916	[2132] MMDS: ***Playback pointer crossed buffer end***	
00000062	73.56453705	[2132] Thread has started 5508	
00000063	73.56472778	[2132] Thread initialized 5508	
00000064	73.57360840	[2132] Going to make final callback 5508	
00000065	77.31893921	[2132] MMDS: ***Playback pointer crossed buffer end***	
00000066	89.33944702	[2132] MMDS: ***Playback pointer crossed buffer end***	
00000067	89.57235718	[2132] MMDS: Written 101376 bytes	
00000068	89.58609009	[2132] MMDS: Written 101376 bytes	
00000069	89.59131622	[2132] MMDS: Written 101376 bytes	
00000070	89.61727905	[2132] MMDS: Written 101376 bytes	
00000071	89.64848328	[2132] MMDS: Written 101376 bytes	
00000072	89.65369415	[2132] MMDS: Written 101376 bytes	
00000073	89.65873718	[2132] MMDS: Written 101376 bytes	
00000074	89.66463470	[2132] MMDS: Written 101376 bytes	
00000075	89.66990662	[2132] MMDS: Written 101376 bytes	
00000076	89.67491150	[2132] MMDS: Written 101376 bytes	
00000077	89.68060303	[2132] MMDS: Written 101376 bytes	
00000078	89.68646240	[2132] MMDS: Written 101376 bytes	
00000079	89.69167328	[2132] MMDS: Written 101376 bytes	
00000080	89.69655609	[2132] MMDS: Written 101376 bytes	
00000081	89.70191193	[2132] MMDS: Written 101376 bytes	
00000082	89.70685577	[2132] MMDS: Written 101376 bytes	
00000083	89.71254730	[2132] MMDS: Written 101376 bytes	
00000084	89.71789551	[2132] MMDS: Written 101376 bytes	
00000085	89.72351074	[2132] MMDS: Written 101376 bytes	
00000086	89.72876740	[2132] MMDS: Written 101376 bytes	
00000087	89.73449707	[2132] MMDS: Written 101376 bytes	
00000088	89.74100494	[2132] MMDS: Written 101376 bytes	
00000089	89.74678040	[2132] MMDS: Written 101376 bytes	
00000090	89.75244141	[2132] MMDS: Written 101376 bytes	
00000091	89.75812531	[2132] MMDS: Written 101376 bytes	
00000092	89.76327515	[2132] MMDS: Written 101376 bytes	
00000093	89.76889801	[2132] MMDS: Written 101376 bytes	
00000094	89.77429962	[2132] MMDS: Written 101376 bytes	
00000095	89.77957153	[2132] MMDS: Written 101376 bytes	
00000096	89.78435516	[2132] MMDS: Written 101376 bytes	
00000097	89.78895569	[2132] MMDS: Written 101376 bytes	
00000098	89.79439545	[2132] MMDS: Written 101376 bytes	
00000099	89.81552887	[2132] MMDS: Written 101376 bytes	
00000100	89.82064056	[2132] MMDS: Written 101376 bytes	
00000101	89.82566071	[2132] MMDS: Written 101376 bytes	
00000102	89.83098602	[2132] MMDS: Written 101376 bytes	
00000103	89.83567810	[2132] MMDS: Written 101376 bytes	
00000104	103.57353210	[2132] Thread has started 6128	
00000105	103.57368469	[2132] Thread initialized 6128	
00000106	103.58171844	[2132] Going to make final callback 6128	
00000107	125.31761169	[2132] MMDS: ***Playback pointer crossed buffer end***	
00000108	133.59371948	[2132] Thread has started 5268	
00000109	133.59393311	[2132] Thread initialized 5268	
00000110	133.60327148	[2132] Going to make final callback 5268	
00000111	137.33831787	[2132] MMDS: ***Playback pointer crossed buffer end***	
00000112	139.21360779	[2132] Forms: Received WM_ACTIVATEAPP	
00000113	139.21368408	[2132] Forms: RestoreTopMost: Level: 1	
00000114	139.21420288	[2132] Winamp sim - ignoring.	
00000115	139.21426392	[2132] Winamp sim - ignoring.	
00000116	139.22444153	[2132] Winamp simulation window received messsage 0	
00000117	140.18005371	[2132] MMDS: Pause starting	
00000118	140.18037415	[2132] MMDS: Pause finished	
00000119	140.20036316	[2132] MMDS: Pausing, playsfor: 3528, ratio: 0,133333333333333	
00000120	140.24041748	[2132] MMDS: Pausing, playsfor: 10584, ratio: 0,4	
00000121	140.28036499	[2132] MMDS: Pausing, playsfor: 17640, ratio: 0,666666666666667	
00000122	140.32028198	[2132] MMDS: Pausing, playsfor: 24696, ratio: 0,933333333333333	
00000123	140.34025574	[2132] MMDS: Pausing, playsfor: 28224, ratio: 1,06666666666667	
00000124	141.04154968	[2132] Forms: Received WM_ACTIVATEAPP	
00000125	141.04203796	[2132] Forms: NormalizeTopMosts: Level: 0	
00000126	141.04385376	[2132] Winamp sim - ignoring.	
00000127	141.04403687	[2132] Winamp sim - ignoring.	

Also here in a differnt Flac song around 302, 304,310

Code: Select all

00000000	0.00000000	[7632] Forms: Received WM_ACTIVATEAPP	
00000001	0.00005964	[7632] Forms: Accepted WM_ACTIVATEAPP +	
00000002	0.00024787	[7632] Forms: RestoreTopMost: Level: 1	
00000003	0.00036960	[7632] Forms: RestoreTopMost: Starting	
00000004	0.00110196	[7632] Winamp sim - ignoring.	
00000005	0.00126622	[7632] Winamp simulation window received messsage 28	
00000006	0.00139040	[7632] Winamp sim - ignoring.	
00000007	0.02211978	[7632] Winamp sim - message left to the default processing	
00000008	0.02245663	[7632] Winamp simulation window received messsage 0	
00000009	0.02267956	[7632] Winamp sim - message left to the default processing	
00000010	0.09794499	[7632] Forms: NormalizeTopMosts: Level: 0	
00000011	0.09809312	[7632] Forms: NormalizeTopMost: Starting	
00000012	0.11069228	[7632] Forms: Received WM_ACTIVATEAPP	
00000013	0.11079592	[7632] Forms: Accepted WM_ACTIVATEAPP -	
00000014	0.11090299	[7632] Forms: NormalizeTopMosts: Level: 1	
00000015	0.11109757	[7632] Winamp sim - ignoring.	
00000016	0.11114157	[7632] Winamp simulation window received messsage 28	
00000017	0.11117775	[7632] Winamp sim - ignoring.	
00000018	8.35333252	[7632] Forms: Received WM_ACTIVATEAPP	
00000019	8.35353947	[7632] Forms: Accepted WM_ACTIVATEAPP +	
00000020	8.35358906	[7632] Forms: RestoreTopMost: Level: 2	
00000021	8.35373497	[7632] Winamp sim - ignoring.	
00000022	8.35385799	[7632] Winamp simulation window received messsage 28	
00000023	8.35395527	[7632] Winamp sim - ignoring.	
00000024	8.42887592	[7632] Forms: RestoreTopMost: Level: 1	
00000025	8.42889786	[7632] Forms: RestoreTopMost: Starting	
00000026	8.55375767	[7632] Winamp sim - message left to the default processing	
00000027	8.55378914	[7632] Winamp simulation window received messsage 0	
00000028	8.55381107	[7632] Winamp sim - message left to the default processing	
00000029	8.60182571	[7632] Thread has started 5020	
00000030	8.60363293	[7632] Thread registered, going to initialize 5020	
00000031	8.60438538	[7632] Thread initialized 5020	
00000032	8.61168098	[7632] Going to make final callback 5020	
00000033	8.61172581	[7632] Thread has finished 5020	
00000034	21.69106102	[7632] 4800 DB open SQL: SELECT Songs.ID FROM Songs WHERE Songs.ID=? AND ( Songs.TrackType=1 ) 	
00000035	21.69148827	[7632] 4800 DB lock finished, took 5124.	
00000036	21.69152832	[7632] 4800 DB open SQL: SELECT Songs.ID FROM Songs WHERE Songs.ID=? AND ( Songs.TrackType=2 ) 	
00000037	21.69179344	[7632] 4800 DB lock finished, took 3122.	
00000038	21.69183540	[7632] 4800 DB open SQL: SELECT Songs.ID FROM Songs WHERE Songs.ID=? AND ( Songs.TrackType=1 ) 	
00000039	21.69202423	[7632] 4800 DB lock finished, took 2163.	
00000040	21.69206238	[7632] 4800 DB open SQL: SELECT Songs.ID FROM Songs WHERE Songs.ID=? AND ( Songs.TrackType=2 ) 	
00000041	21.69224358	[7632] 4800 DB lock finished, took 2072.	
00000042	21.69334984	[7632] Player: Adding 56 track(s)	
00000043	21.69338799	[7632] Player: Added 56 track(s)	
00000044	21.69342041	[7632] _Stop: started	
00000045	21.69477654	[7632] _Stop: finished	
00000046	21.69747925	[7632] Player: Setting current track: E:\Music\Black Eyed Peas\The E.N.D. (Energy Never Dies)\02 - Rock That Body.flac	
00000047	21.69893265	[7632] GetFileInfo: E:\Music\Black Eyed Peas\The E.N.D. (Energy Never Dies)\02 - Rock That Body.flac	
00000048	21.69896126	[7632] GetFileInfo: readPictures=spAlways	
00000049	21.69899559	[7632] GetFileInfo: E:\Music\Black Eyed Peas\The E.N.D. (Energy Never Dies)\02 - Rock That Body.flac	
00000050	21.69914055	[7632] Added Cover Lists: 2	
00000051	21.69918251	[7632] FH.ReadFileInfo: E:\Music\Black Eyed Peas\The E.N.D. (Energy Never Dies)\02 - Rock That Body.flac	
00000052	21.69923019	[7632] Locking file: E:\Music\Black Eyed Peas\The E.N.D. (Energy Never Dies)\02 - Rock That Body.flac	
00000053	21.69926643	[7632] Locked file: E:\Music\Black Eyed Peas\The E.N.D. (Energy Never Dies)\02 - Rock That Body.flac	
00000054	21.69938850	[7632] Unlocking file: E:\Music\Black Eyed Peas\The E.N.D. (Energy Never Dies)\02 - Rock That Body.flac	
00000055	21.69941902	[7632] Unlocked file: E:\Music\Black Eyed Peas\The E.N.D. (Energy Never Dies)\02 - Rock That Body.flac	
00000056	21.69962502	[7632] FH.ReadFileInfo openned	
00000057	21.69973755	[7632] Added Covers: 2	
00000058	21.69983292	[7632] 4800 DB open SQL: SELECT Songs.* FROM Songs WHERE Songs.IDMedia=? AND Songs.SongPath=? 	
00000059	21.70049667	[7632] 4800 DB lock finished, took 8568.	
00000060	21.70053101	[7632] Added Cover Lists: 3	
00000061	21.70056534	[7632] Added Covers: 3	
00000062	21.70067596	[7632] Remaining Covers: 2	
00000063	21.70070457	[7632] Remaining Cover Lists: 2	
00000064	21.70076180	[7632] GetFileInfo: Found in DB	
00000065	21.70080757	[7632] 4800 DB open SQL: SELECT * FROM Covers WHERE IDSong=10329 ORDER BY CoverOrder 	
00000066	21.70116234	[7632] 4800 DB lock finished, took 4221.	
00000067	21.70118713	[7632] Added Covers: 3	
00000068	21.70123482	[7632] Remaining Covers: 2	
00000069	21.71393776	[7632] Winamp simulation window received messsage 12	
00000070	21.71397400	[7632] Winamp sim - message left to the default processing	
00000071	21.71981430	[7632] Thread has started 1300	
00000072	21.71986008	[7632] Thread registered, going to initialize 1300	
00000073	21.71989250	[7632] Thread initialized 1300	
00000074	21.76282692	[7632] 4800 DB open SQL: SELECT Songs.ID FROM Songs WHERE Songs.ID=? AND ( Songs.TrackType=1 ) 	
00000075	21.76305580	[7632] 4800 DB lock finished, took 2807.	
00000076	21.76309013	[7632] 4800 DB open SQL: SELECT Songs.ID FROM Songs WHERE Songs.ID=? AND ( Songs.TrackType=2 ) 	
00000077	21.76336098	[7632] 4800 DB lock finished, took 1540.	
00000078	21.76340103	[7632] Thread has started 6404	
00000079	21.76344299	[7632] 4800 DB open SQL: SELECT Songs.ID FROM Songs WHERE Songs.ID=? AND ( Songs.TrackType=1 ) 	
00000080	21.76346588	[7632] Thread registered, going to initialize 6404	
00000081	21.76349068	[7632] Thread initialized 6404	
00000082	21.76351738	[7632] Player: _Play() procedure entering.	
00000083	21.76369476	[7632] 4800 DB lock finished, took 2828.	
00000084	21.76373100	[7632] 4800 DB open SQL: SELECT Songs.ID FROM Songs WHERE Songs.ID=? AND ( Songs.TrackType=2 ) 	
00000085	21.76387215	[7632] 4800 DB lock finished, took 1533.	
00000086	21.76391220	[7632] 4800 DB open SQL: SELECT Songs.ID FROM Songs WHERE Songs.ID=? AND ( Songs.TrackType=1 ) 	
00000087	21.76404190	[7632] 4800 DB lock finished, took 1498.	
00000088	21.76406670	[7632] 4800 DB open SQL: SELECT Songs.ID FROM Songs WHERE Songs.ID=? AND ( Songs.TrackType=2 ) 	
00000089	21.76419258	[7632] 4800 DB lock finished, took 1414.	
00000090	21.76422119	[7632] 4800 DB open SQL: SELECT Songs.ID FROM Songs WHERE Songs.ID=? AND ( Songs.TrackType=1 ) 	
00000091	21.76434898	[7632] 4800 DB lock finished, took 1400.	
00000092	21.76437569	[7632] 4800 DB open SQL: SELECT Songs.ID FROM Songs WHERE Songs.ID=? AND ( Songs.TrackType=2 ) 	
00000093	21.76449585	[7632] 4800 DB lock finished, took 1400.	
00000094	21.76731110	[7632] Thread has started 3076	
00000095	21.76735878	[7632] Thread registered, going to initialize 3076	
00000096	21.76737595	[7632] Thread initialized 3076	
00000097	21.80596733	[7632] Winamp simulation window received messsage 12	
00000098	21.80598640	[7632] Winamp sim - message left to the default processing	
00000099	21.80615997	[7632] Player: Setting current track: E:\Music\Black Eyed Peas\The E.N.D. (Energy Never Dies)\02 - Rock That Body.flac	
00000100	21.80620193	[7632] Player: Song is not cached, try to play the original file	
00000101	21.80687714	[7632] Winamp sim - WM_USER message 603	
00000102	21.80694199	[7632] Winamp sim - WM_USER message 603	
00000103	21.80701256	[7632] Going to play file: E:\Music\Black Eyed Peas\The E.N.D. (Energy Never Dies)\02 - Rock That Body.flac	
00000104	21.80706787	[7632] Decoder: CreateDecoder() started	
00000105	21.80709839	[7632] Going to execute format plug-in constructor.	
00000106	21.80713654	[7632] Locking file: E:\Music\Black Eyed Peas\The E.N.D. (Energy Never Dies)\02 - Rock That Body.flac	
00000107	21.80716324	[7632] Locked file: E:\Music\Black Eyed Peas\The E.N.D. (Energy Never Dies)\02 - Rock That Body.flac	
00000108	21.80728912	[7632] Unlocking file: E:\Music\Black Eyed Peas\The E.N.D. (Energy Never Dies)\02 - Rock That Body.flac	
00000109	21.80731010	[7632] Unlocked file: E:\Music\Black Eyed Peas\The E.N.D. (Energy Never Dies)\02 - Rock That Body.flac	
00000110	21.80747604	[7632] Decoder: CreateDecoder() finished successfuly	
00000111	21.80956268	[7632] Winamp Playlist sim - WM_USER message 43	
00000112	21.80959129	[7632] Winamp Playlist sim - message left to the default processing	
00000113	21.80963707	[7632] Winamp Playlist sim - WM_USER message 1073741867	
00000114	21.80966377	[7632] Winamp Playlist sim - message left to the default processing	
00000115	21.80970192	[7632] Winamp sim - WM_USER message 603	
00000116	21.80973244	[7632] Winamp sim - WM_USER message 603	
00000117	21.80976677	[7632] Winamp sim - WM_USER message 104	
00000118	21.81013107	[7632] Thread has started 2836	
00000119	21.81016922	[7632] Thread registered, going to initialize 2836	
00000120	21.81019402	[7632] Thread initialized 2836	
00000121	21.81022263	[7632] MMDS: Playback of a new track starts	
00000122	21.81109238	[7632] MMDS: Output thread is starting	
00000123	21.82954407	[7632] MMDS: Creating new buffer	
00000124	21.82958412	[7632] MMDS: Waiting for buffer creation	
00000125	21.83115196	[7632] MMDS: Buffer creation result: 0	
00000126	21.83170891	[7632] MMDS: Buffer created	
00000127	21.83173752	[7632] MMDS: Track is going to be played	
00000128	21.83237648	[7632] Player: Playback started	
00000129	21.85208321	[7632] Going to make final callback 6404	
00000130	21.85213089	[7632] Thread has finished 6404	
00000131	21.87855530	[7632] MMDS: Written 101376 bytes	
00000132	21.88259888	[7632] Winamp simulation window received messsage 12	
00000133	21.88262177	[7632] Winamp sim - message left to the default processing	
00000134	21.88285255	[7632] MMDS: Written 101376 bytes	
00000135	21.88701248	[7632] MMDS: Written 101376 bytes	
00000136	21.89127350	[7632] MMDS: Written 101376 bytes	
00000137	21.89547348	[7632] MMDS: Written 101376 bytes	
00000138	21.89983368	[7632] MMDS: Written 101376 bytes	
00000139	21.90398407	[7632] MMDS: Written 101376 bytes	
00000140	21.90818596	[7632] MMDS: Written 101376 bytes	
00000141	21.91128731	[7632] 4800 DB open SQL: SELECT Songs.ID FROM Songs WHERE Songs.ID=? AND ( Songs.TrackType=1 ) 	
00000142	21.91149330	[7632] 4800 DB lock finished, took 2506.	
00000143	21.91152382	[7632] 4800 DB open SQL: SELECT Songs.ID FROM Songs WHERE Songs.ID=? AND ( Songs.TrackType=2 ) 	
00000144	21.91165543	[7632] 4800 DB lock finished, took 1498.	
00000145	21.91168976	[7632] 4800 DB open SQL: SELECT Songs.ID FROM Songs WHERE Songs.ID=? AND ( Songs.TrackType=1 ) 	
00000146	21.91181564	[7632] 4800 DB lock finished, took 1449.	
00000147	21.91184235	[7632] 4800 DB open SQL: SELECT Songs.ID FROM Songs WHERE Songs.ID=? AND ( Songs.TrackType=2 ) 	
00000148	21.91196632	[7632] 4800 DB lock finished, took 1414.	
00000149	21.91199684	[7632] 4800 DB open SQL: SELECT Songs.ID FROM Songs WHERE Songs.ID=? AND ( Songs.TrackType=1 ) 	
00000150	21.91212273	[7632] 4800 DB lock finished, took 1442.	
00000151	21.91215324	[7632] 4800 DB open SQL: SELECT Songs.ID FROM Songs WHERE Songs.ID=? AND ( Songs.TrackType=2 ) 	
00000152	21.91227913	[7632] 4800 DB lock finished, took 1477.	
00000153	21.91230965	[7632] 4800 DB open SQL: SELECT Songs.ID FROM Songs WHERE Songs.ID=? AND ( Songs.TrackType=1 ) 	
00000154	21.91234016	[7632] MMDS: Written 101376 bytes	
00000155	21.91244888	[7632] 4800 DB lock finished, took 1568.	
00000156	21.91247749	[7632] 4800 DB open SQL: SELECT Songs.ID FROM Songs WHERE Songs.ID=? AND ( Songs.TrackType=2 ) 	
00000157	21.91259766	[7632] 4800 DB lock finished, took 1365.	
00000158	21.91298676	[7632] Going to make final callback 3076	
00000159	21.91320801	[7632] Thread has finished 3076	
00000160	21.91653442	[7632] MMDS: Written 101376 bytes	
00000161	21.92100525	[7632] MMDS: Written 101376 bytes	
00000162	21.92538261	[7632] MMDS: Written 101376 bytes	
00000163	21.92957687	[7632] MMDS: Written 101376 bytes	
00000164	21.93392563	[7632] MMDS: Written 101376 bytes	
00000165	21.93824387	[7632] MMDS: Written 101376 bytes	
00000166	21.94262505	[7632] MMDS: Written 101376 bytes	
00000167	21.94705772	[7632] MMDS: Written 101376 bytes	
00000168	21.95122337	[7632] MMDS: Written 101376 bytes	
00000169	21.95535660	[7632] MMDS: Written 101376 bytes	
00000170	21.95963287	[7632] MMDS: Written 101376 bytes	
00000171	21.98292732	[7632] MMDS: Written 101376 bytes	
00000172	22.03001976	[7632] Going to make final callback 1300	
00000173	22.03014946	[7632] Thread has finished 1300	
00000174	22.15603447	[7632] 4800 DB open SQL: SELECT Songs.ID FROM Songs WHERE Songs.ID=? AND ( Songs.TrackType=1 ) 	
00000175	22.15675163	[7632] 4800 DB lock finished, took 8372.	
00000176	22.15686798	[7632] 4800 DB open SQL: SELECT Songs.ID FROM Songs WHERE Songs.ID=? AND ( Songs.TrackType=2 ) 	
00000177	22.15737724	[7632] 4800 DB lock finished, took 5782.	
00000178	22.15746498	[7632] 4800 DB open SQL: SELECT Songs.ID FROM Songs WHERE Songs.ID=? AND ( Songs.TrackType=1 ) 	
00000179	22.15795517	[7632] 4800 DB lock finished, took 5516.	
00000180	22.15804482	[7632] 4800 DB open SQL: SELECT Songs.ID FROM Songs WHERE Songs.ID=? AND ( Songs.TrackType=2 ) 	
00000181	22.15882111	[7632] 4800 DB lock finished, took 8645.	
00000182	22.15894890	[7632] 4800 DB open SQL: SELECT Songs.ID FROM Songs WHERE Songs.ID=? AND ( Songs.TrackType=1 ) 	
00000183	22.15947151	[7632] 4800 DB lock finished, took 5866.	
00000184	22.15955544	[7632] 4800 DB open SQL: SELECT Songs.ID FROM Songs WHERE Songs.ID=? AND ( Songs.TrackType=2 ) 	
00000185	22.16003418	[7632] 4800 DB lock finished, took 5516.	
00000186	22.16014099	[7632] 4800 DB open SQL: SELECT Songs.ID FROM Songs WHERE Songs.ID=? AND ( Songs.TrackType=1 ) 	
00000187	22.16061592	[7632] 4800 DB lock finished, took 5320.	
00000188	22.16070938	[7632] 4800 DB open SQL: SELECT Songs.ID FROM Songs WHERE Songs.ID=? AND ( Songs.TrackType=2 ) 	
00000189	22.16119003	[7632] 4800 DB lock finished, took 5320.	
00000190	22.23773003	[7632] Winamp simulation window received messsage 12	
00000191	22.23775291	[7632] Winamp sim - message left to the default processing	
00000192	22.32897186	[7632] 4800 DB open SQL: SELECT * FROM Filters WHERE ID=5 	
00000193	22.33169937	[7632] 4800 DB lock finished, took 3528.	
00000194	22.33243752	[7632] 4800 DB open SQL: SELECT * FROM Filters WHERE ID=5 	
00000195	22.33343124	[7632] 4800 DB lock finished, took 3570.	
00000196	22.33414078	[7632] 4800 DB open SQL: select ID from Songs where id=10329 AND  Songs.TrackType=1  	
00000197	22.33524895	[7632] 4800 DB lock finished, took 2681.	
00000198	22.33638954	[7632] 4800 DB open SQL: SELECT * FROM Filters WHERE ID=6 	
00000199	22.33747292	[7632] 4800 DB lock finished, took 2863.	
00000200	22.33815575	[7632] 4800 DB open SQL: SELECT * FROM Filters WHERE ID=6 	
00000201	22.33915138	[7632] 4800 DB lock finished, took 2779.	
00000202	22.33982658	[7632] 4800 DB open SQL: select ID from Songs where id=10329 AND  Songs.TrackType=2  	
00000203	22.34095955	[7632] 4800 DB lock finished, took 2548.	
00000204	22.58793449	[7632] MMDS: Written 101376 bytes	
00000205	23.13796806	[7632] MMDS: Written 101376 bytes	
00000206	23.72694588	[7632] MMDS: Written 101376 bytes	
00000207	24.00203896	[7632] Forms: Received WM_ACTIVATEAPP	
00000208	24.00208664	[7632] Forms: Accepted WM_ACTIVATEAPP -	
00000209	24.00228500	[7632] Forms: NormalizeTopMosts: Level: 0	
00000210	24.00287437	[7632] Forms: NormalizeTopMost: Starting	
00000211	24.00413513	[7632] Winamp sim - ignoring.	
00000212	24.00434685	[7632] Winamp simulation window received messsage 28	
00000213	24.00442314	[7632] Winamp sim - ignoring.	
00000214	24.30134964	[7632] MMDS: Written 101376 bytes	
00000215	24.88371658	[7632] MMDS: Written 101376 bytes	
00000216	25.43528557	[7632] MMDS: Written 101376 bytes	
00000217	26.00792694	[7632] MMDS: Written 101376 bytes	
00000218	26.59728432	[7632] MMDS: Written 101376 bytes	
00000219	27.20722961	[7632] MMDS: Written 101376 bytes	
00000220	27.74225807	[7632] MMDS: Written 101376 bytes	
00000221	28.31381035	[7632] MMDS: Written 101376 bytes	
00000222	28.90535545	[7632] MMDS: Written 101376 bytes	
00000223	29.45643425	[7632] MMDS: Written 101376 bytes	
00000224	30.04697609	[7632] MMDS: Written 101376 bytes	
00000225	30.63984108	[7632] MMDS: Written 101376 bytes	
00000226	31.18954849	[7632] MMDS: Written 101376 bytes	
00000227	31.78335953	[7632] MMDS: Written 101376 bytes	
00000228	32.35450745	[7632] MMDS: Written 101376 bytes	
00000229	33.44857407	[7632] MMDS: Written 101376 bytes	
00000230	33.94859695	[7632] MMDS: Written 101376 bytes	
00000231	33.95029449	[7632] MMDS: ***Playback pointer crossed buffer end***	
00000232	34.46268463	[7632] MMDS: Written 101376 bytes	
00000233	34.96666336	[7632] MMDS: Written 101376 bytes	
00000234	35.47670746	[7632] MMDS: Written 101376 bytes	
00000235	35.97673798	[7632] MMDS: Written 101376 bytes	
00000236	36.49056244	[7632] MMDS: Written 101376 bytes	
00000237	36.99084854	[7632] MMDS: Written 101376 bytes	
00000238	37.50137711	[7632] MMDS: Written 101376 bytes	
00000239	38.11289215	[7632] MMDS: Written 101376 bytes	
00000240	38.62169647	[7632] Thread has started 7716	
00000241	38.62182617	[7632] Thread registered, going to initialize 7716	
00000242	38.62192917	[7632] Thread initialized 7716	
00000243	38.63169861	[7632] Going to make final callback 7716	
00000244	38.63188553	[7632] Thread has finished 7716	
00000245	38.64694977	[7632] MMDS: Written 101376 bytes	
00000246	39.22042084	[7632] MMDS: Written 101376 bytes	
00000247	39.81450653	[7632] MMDS: Written 101376 bytes	
00000248	40.38545990	[7632] MMDS: Written 101376 bytes	
00000249	40.99911118	[7632] MMDS: Written 101376 bytes	
00000250	41.53206253	[7632] MMDS: Written 101376 bytes	
00000251	42.10467911	[7632] MMDS: Written 101376 bytes	
00000252	42.71606064	[7632] MMDS: Written 101376 bytes	
00000253	43.28414536	[7632] MMDS: Written 101376 bytes	
00000254	43.85553741	[7632] MMDS: Written 101376 bytes	
00000255	44.42719650	[7632] MMDS: Written 101376 bytes	
00000256	44.99715042	[7632] MMDS: Written 101376 bytes	
00000257	45.56795502	[7632] MMDS: Written 101376 bytes	
00000258	45.91630173	[7632] MMDS: ***Playback pointer crossed buffer end***	
00000259	46.11999512	[7632] MMDS: Written 101376 bytes	
00000260	46.71192551	[7632] MMDS: Written 101376 bytes	
00000261	47.26575089	[7632] MMDS: Written 101376 bytes	
00000262	47.83736420	[7632] MMDS: Written 101376 bytes	
00000263	48.44737244	[7632] MMDS: Written 101376 bytes	
00000264	49.01816177	[7632] MMDS: Written 101376 bytes	
00000265	49.56444168	[7632] MMDS: Written 101376 bytes	
00000266	50.15238190	[7632] MMDS: Written 101376 bytes	
00000267	50.72275543	[7632] MMDS: Written 101376 bytes	
00000268	51.29317856	[7632] MMDS: Written 101376 bytes	
00000269	51.88209915	[7632] MMDS: Written 101376 bytes	
00000270	52.43376541	[7632] MMDS: Written 101376 bytes	
00000271	53.02769089	[7632] MMDS: Written 101376 bytes	
00000272	53.61587524	[7632] MMDS: Written 101376 bytes	
00000273	54.16668320	[7632] MMDS: Written 101376 bytes	
00000274	54.75480652	[7632] MMDS: Written 101376 bytes	
00000275	55.34437943	[7632] MMDS: Written 101376 bytes	
00000276	55.93031311	[7632] MMDS: Written 101376 bytes	
00000277	56.49966049	[7632] MMDS: Written 101376 bytes	
00000278	57.06501389	[7632] MMDS: Written 101376 bytes	
00000279	57.61594772	[7632] MMDS: Written 101376 bytes	
00000280	57.92085266	[7632] MMDS: ***Playback pointer crossed buffer end***	
00000281	58.20200729	[7632] MMDS: Written 101376 bytes	
00000282	58.76832581	[7632] MMDS: Written 101376 bytes	
00000283	59.35656357	[7632] MMDS: Written 101376 bytes	
00000284	59.90773773	[7632] MMDS: Written 101376 bytes	
00000285	60.49667740	[7632] MMDS: Written 101376 bytes	
00000286	61.08811569	[7632] MMDS: Written 101376 bytes	
00000287	61.66211319	[7632] MMDS: Written 101376 bytes	
00000288	62.21060944	[7632] MMDS: Written 101376 bytes	
00000289	62.82112503	[7632] MMDS: Written 101376 bytes	
00000290	63.38373947	[7632] MMDS: Written 101376 bytes	
00000291	63.95116425	[7632] MMDS: Written 101376 bytes	
00000292	64.53645325	[7632] MMDS: Written 101376 bytes	
00000293	65.10146332	[7632] MMDS: Written 101376 bytes	
00000294	65.68934631	[7632] MMDS: Written 101376 bytes	
00000295	66.25943756	[7632] MMDS: Written 101376 bytes	
00000296	66.84860229	[7632] MMDS: Written 101376 bytes	
00000297	67.37601471	[7632] MMDS: Written 101376 bytes	
00000298	68.62991333	[7632] Thread has started 5496	
00000299	68.62995911	[7632] Thread registered, going to initialize 5496	
00000300	68.63039398	[7632] Thread initialized 5496	
00000301	68.63738251	[7632] Going to make final callback 5496	
00000302	68.63743591	[7632] Thread has finished 5496	
00000303	69.91559601	[7632] MMDS: ***Playback pointer crossed buffer end***	
00000304	81.93638611	[7632] MMDS: ***Playback pointer crossed buffer end***	
00000305	93.91713715	[7632] MMDS: ***Playback pointer crossed buffer end***	
00000306	98.65019226	[7632] Thread has started 8084	
00000307	98.65032959	[7632] Thread registered, going to initialize 8084	
00000308	98.65044403	[7632] Thread initialized 8084	
00000309	98.65860748	[7632] Going to make final callback 8084	
00000310	98.65865326	[7632] Thread has finished 8084	
00000311	105.93774414	[7632] MMDS: ***Playback pointer crossed buffer end***	
00000312	112.62596130	[7632] Forms: Received WM_ACTIVATEAPP	
00000313	112.62597656	[7632] Forms: Accepted WM_ACTIVATEAPP +	
00000314	112.62602234	[7632] Forms: RestoreTopMost: Level: 1	
00000315	112.62605286	[7632] Forms: RestoreTopMost: Starting	
00000316	112.62653351	[7632] Winamp sim - ignoring.	
00000317	112.62661743	[7632] Winamp simulation window received messsage 28	
00000318	112.62665558	[7632] Winamp sim - ignoring.	
00000319	112.63561249	[7632] Winamp sim - message left to the default processing	
00000320	112.63589478	[7632] Winamp simulation window received messsage 0	
00000321	112.63603973	[7632] Winamp sim - message left to the default processing	
00000322	113.53879547	[7632] MMDS: Pause starting	
00000323	113.53900146	[7632] MMDS: Starting smooth pause	
00000324	113.53932190	[7632] MMDS: Pause finished	
00000325	113.55925751	[7632] MMDS: Pausing, playsfor: 8820, ratio: 0,333333333333333	
00000326	113.59899139	[7632] MMDS: Pausing, playsfor: 15876, ratio: 0,6	
00000327	113.61903381	[7632] MMDS: Pausing, playsfor: 19404, ratio: 0,733333333333333	
00000328	113.65896606	[7632] MMDS: Pausing, playsfor: 26460, ratio: 1	
00000329	113.65899658	[7632] MMDS: End of smooth pausing	
00000330	114.85712433	[7632] Forms: Received WM_ACTIVATEAPP	
00000331	114.85717773	[7632] Forms: Accepted WM_ACTIVATEAPP -	
00000332	114.85736084	[7632] Forms: NormalizeTopMosts: Level: 0	
00000333	114.85779572	[7632] Forms: NormalizeTopMost: Starting	
00000334	114.85961151	[7632] Winamp sim - ignoring.	
00000335	114.85974884	[7632] Winamp simulation window received messsage 28	
00000336	114.85984802	[7632] Winamp sim - ignoring.	
00000337	127.68612671	[7632] MMDS: Written 101376 bytes	
00000338	127.69855499	[7632] MMDS: Written 101376 bytes	
00000339	127.70539093	[7632] MMDS: Written 101376 bytes	
00000340	127.71323395	[7632] MMDS: Written 101376 bytes	
00000341	127.72099304	[7632] MMDS: Written 101376 bytes	
00000342	127.72718048	[7632] MMDS: Written 101376 bytes	
00000343	127.73322296	[7632] MMDS: Written 101376 bytes	
00000344	127.73870087	[7632] MMDS: Written 101376 bytes	
00000345	127.74394989	[7632] MMDS: Written 101376 bytes	
00000346	127.74934387	[7632] MMDS: Written 101376 bytes	
00000347	127.75463104	[7632] MMDS: Written 101376 bytes	
00000348	127.75939178	[7632] MMDS: Written 101376 bytes	
00000349	127.76528168	[7632] MMDS: Written 101376 bytes	
00000350	127.77126312	[7632] MMDS: Written 101376 bytes	
00000351	127.77547455	[7632] MMDS: Written 101376 bytes	
00000352	127.78123474	[7632] MMDS: Written 101376 bytes	
00000353	127.78651428	[7632] MMDS: Written 101376 bytes	
00000354	127.79262543	[7632] MMDS: Written 101376 bytes	
00000355	127.79792023	[7632] MMDS: Written 101376 bytes	
00000356	127.80318451	[7632] MMDS: Written 101376 bytes	
00000357	127.80854797	[7632] MMDS: Written 101376 bytes	
00000358	127.81443787	[7632] MMDS: Written 101376 bytes	
00000359	127.82051086	[7632] MMDS: Written 101376 bytes	
00000360	127.82612610	[7632] MMDS: Written 101376 bytes	
00000361	127.83112335	[7632] MMDS: Written 101376 bytes	
00000362	127.83593750	[7632] MMDS: Written 101376 bytes	
00000363	127.84134674	[7632] MMDS: Written 101376 bytes	
00000364	127.84643555	[7632] MMDS: Written 101376 bytes	
00000365	127.85218811	[7632] MMDS: Written 101376 bytes	
00000366	127.85771942	[7632] MMDS: Written 101376 bytes	
00000367	127.86309052	[7632] MMDS: Written 101376 bytes	
00000368	127.86806488	[7632] MMDS: Written 101376 bytes	
00000369	127.87294006	[7632] MMDS: Written 101376 bytes	
00000370	127.87929535	[7632] MMDS: Written 101376 bytes	
00000371	127.88437653	[7632] MMDS: Written 101376 bytes	
00000372	127.88955688	[7632] MMDS: Written 101376 bytes	
00000373	127.89510345	[7632] MMDS: Written 101376 bytes	
00000374	127.90016174	[7632] MMDS: Written 101376 bytes	
00000375	127.90538788	[7632] MMDS: Written 101376 bytes	
00000376	127.91016388	[7632] MMDS: Written 101376 bytes	
00000377	127.91553497	[7632] MMDS: Written 101376 bytes	
00000378	127.92045593	[7632] MMDS: Written 101376 bytes	
00000379	127.92523956	[7632] MMDS: Written 101376 bytes	
00000380	127.93046570	[7632] MMDS: Written 101376 bytes	
00000381	127.93571472	[7632] MMDS: Written 101376 bytes	
00000382	127.94093323	[7632] MMDS: Written 101376 bytes	
00000383	127.94600677	[7632] MMDS: Written 101376 bytes	
00000384	127.95113373	[7632] MMDS: Written 101376 bytes	
00000385	127.95592499	[7632] MMDS: Written 101376 bytes	
00000386	127.96101379	[7632] MMDS: Written 101376 bytes	
00000387	127.96643066	[7632] MMDS: Written 101376 bytes	
00000388	127.97170258	[7632] MMDS: Written 101376 bytes	
00000389	127.97628021	[7632] MMDS: Written 101376 bytes	
00000390	127.98172760	[7632] MMDS: Written 101376 bytes	
00000391	127.98757172	[7632] MMDS: Written 101376 bytes	
00000392	127.99297333	[7632] MMDS: Written 101376 bytes	
00000393	127.99829865	[7632] MMDS: Written 101376 bytes	
00000394	128.00262451	[7632] MMDS: Written 101376 bytes	
00000395	128.00784302	[7632] MMDS: Written 101376 bytes	
00000396	128.01284790	[7632] MMDS: Written 101376 bytes	
00000397	128.01896667	[7632] MMDS: Written 101376 bytes	
00000398	128.02420044	[7632] MMDS: Written 101376 bytes	
00000399	128.02967834	[7632] MMDS: Written 101376 bytes	
00000400	128.03489685	[7632] MMDS: Written 101376 bytes	
00000401	128.65943909	[7632] Thread has started 1880	
00000402	128.65950012	[7632] Thread registered, going to initialize 1880	
00000403	128.65953064	[7632] Thread initialized 1880	
00000404	128.66764832	[7632] Going to make final callback 1880	
00000405	128.66770935	[7632] Thread has finished 1880	
00000406	158.68069458	[7632] Thread has started 5916	
00000407	158.68080139	[7632] Thread registered, going to initialize 5916	
00000408	158.68089294	[7632] Thread initialized 5916	
00000409	158.68879700	[7632] Going to make final callback 5916	
00000410	158.68884277	[7632] Thread has finished 5916