1272 strange background processing going on

[Teknojnky]

I noticed some seemingly abnormable cpu usage and disk access going on so I opened up debug viewer and find a whole bunch of similar stuff to this going on..

Code: Select all

[4648] ProcessFile: Our file J:\Media\MP3\_Soundtrack\2001 - American Pie 2 [MP3]\01 - blink-182 - Every Time I Look for You.mp3
[4648] 4204 DB lock finished, took 3984.
[4648] Locking file: J:\Media\MP3\_Soundtrack\2001 - American Pie 2 [MP3]\01 - blink-182 - Every Time I Look for You.mp3
[4648] GetFileInfo: J:\Media\MP3\_Soundtrack\2001 - American Pie 2 [MP3]\01 - blink-182 - Every Time I Look for You.mp3
[4648] 4204 DB lock finished, took 536.
[4648] 4204 DB open SQL: SELECT SignType FROM Songs WHERE ID=? 
[4648] Unlocking file: J:\Media\MP3\_Soundtrack\2001 - American Pie 2 [MP3]\01 - blink-182 - Every Time I Look for You.mp3
[4648] ProcessFile: Our file J:\Media\MP3\_Soundtrack\2001 - American Pie 2 [MP3]\02 - Green Day - Scumbag.mp3
[4648] 4204 DB lock finished, took 2876.
[4648] Locking file: J:\Media\MP3\_Soundtrack\2001 - American Pie 2 [MP3]\02 - Green Day - Scumbag.mp3
[4648] GetFileInfo: J:\Media\MP3\_Soundtrack\2001 - American Pie 2 [MP3]\02 - Green Day - Scumbag.mp3
[4648] 4204 DB lock finished, took 490.
[4648] 4204 DB open SQL: SELECT SignType FROM Songs WHERE ID=? 
[4648] Unlocking file: J:\Media\MP3\_Soundtrack\2001 - American Pie 2 [MP3]\02 - Green Day - Scumbag.mp3
[4648] ProcessFile: Our file J:\Media\MP3\_Soundtrack\2001 - American Pie 2 [MP3]\03 - Left Front Tire - Bring You Down.mp3
[4648] 4204 DB lock finished, took 2950.
[4648] Locking file: J:\Media\MP3\_Soundtrack\2001 - American Pie 2 [MP3]\03 - Left Front Tire - Bring You Down.mp3
[4648] GetFileInfo: J:\Media\MP3\_Soundtrack\2001 - American Pie 2 [MP3]\03 - Left Front Tire - Bring You Down.mp3
[4648] 4204 DB lock finished, took 631.
[4648] 4204 DB open SQL: SELECT SignType FROM Songs WHERE ID=? 
[4648] Unlocking file: J:\Media\MP3\_Soundtrack\2001 - American Pie 2 [MP3]\03 - Left Front Tire - Bring You Down.mp3
[4648] ProcessFile: Our file J:\Media\MP3\_Soundtrack\2001 - American Pie 2 [MP3]\04 - American Hi-Fi - Vertigo.mp3
[4648] 4204 DB lock finished, took 2827.
[4648] Locking file: J:\Media\MP3\_Soundtrack\2001 - American Pie 2 [MP3]\04 - American Hi-Fi - Vertigo.mp3
[4648] GetFileInfo: J:\Media\MP3\_Soundtrack\2001 - American Pie 2 [MP3]\04 - American Hi-Fi - Vertigo.mp3
[4648] 4204 DB lock finished, took 547.
[4648] 4204 DB open SQL: SELECT SignType FROM Songs WHERE ID=? 
[4648] Unlocking file: J:\Media\MP3\_Soundtrack\2001 - American Pie 2 [MP3]\04 - American Hi-Fi - Vertigo.mp3
[4648] ProcessFile: Our file J:\Media\MP3\_Soundtrack\2001 - American Pie 2 [MP3]\05 - Uncle Kracker - (I'm Gonna) Split This Room in Half.mp3
[4648] 4204 DB lock finished, took 3057.
[4648] Locking file: J:\Media\MP3\_Soundtrack\2001 - American Pie 2 [MP3]\05 - Uncle Kracker - (I'm Gonna) Split This Room in Half.mp3
[4648] GetFileInfo: J:\Media\MP3\_Soundtrack\2001 - American Pie 2 [MP3]\05 - Uncle Kracker - (I'm Gonna) Split This Room in Half.mp3
[4648] 4204 DB lock finished, took 497.
[4648] 4204 DB open SQL: SELECT SignType FROM Songs WHERE ID=? 
[4648] Unlocking file: J:\Media\MP3\_Soundtrack\2001 - American Pie 2 [MP3]\05 - Uncle Kracker - (I'm Gonna) Split This Room in Half.mp3
[4648] ProcessFile: Our file J:\Media\MP3\_Soundtrack\2001 - American Pie 2 [MP3]\06 - 3 Doors Down - Be Like That.mp3
[4648] 4204 DB lock finished, took 2948.
[4648] Locking file: J:\Media\MP3\_Soundtrack\2001 - American Pie 2 [MP3]\06 - 3 Doors Down - Be Like That.mp3
[4648] GetFileInfo: J:\Media\MP3\_Soundtrack\2001 - American Pie 2 [MP3]\06 - 3 Doors Down - Be Like That.mp3
[4648] 4204 DB lock finished, took 512.
[4648] 4204 DB open SQL: SELECT SignType FROM Songs WHERE ID=? 
[4648] Unlocking file: J:\Media\MP3\_Soundtrack\2001 - American Pie 2 [MP3]\06 - 3 Doors Down - Be Like That.mp3
[4648] Scan: Found candidate for source of move - J:\Media\MP3\_Soundtrack\2001 - American Pie 2 [MP3]\07 - Alien Ant Farm - Good (For a Woman).mp3
[4648] Locked file: J:\Media\MP3\_Soundtrack\2001 - American Pie 2 [MP3]\07 - Alien Ant Farm - Good (For a Woman).mp3
[4648] 4204 DB open SQL: SELECT Songs.* FROM Songs WHERE Songs.IDMedia=? AND Songs.SongPath=? 
[4648] GetFileInfo: Found in DB
[4648] 4204 DB lock finished, took 305.
[4648] Unlocked file: J:\Media\MP3\_Soundtrack\2001 - American Pie 2 [MP3]\07 - Alien Ant Farm - Good (For a Woman).mp3
[4648] 4204 DB open SQL: SELECT Songs.* 
[4648] FROM Songs 
[4648] WHERE Songs.FileLength=5965824 AND Songs.FileModified>=39863.5150392 AND Songs.FileModified<=39863.5150878 
[4648] Scan: Found candidate for source of move - J:\Media\MP3\_Soundtrack\2001 - American Pie 2 [MP3]\08 - Angela Ammons - Always Getting Over You.mp3
[4648] Locked file: J:\Media\MP3\_Soundtrack\2001 - American Pie 2 [MP3]\08 - Angela Ammons - Always Getting Over You.mp3
[4648] 4204 DB open SQL: SELECT Songs.* FROM Songs WHERE Songs.IDMedia=? AND Songs.SongPath=? 
[4648] 4204 DB lock finished, took 78181.
[4648] 4204 DB open SQL: SELECT SignType FROM Songs WHERE ID=? 
[4648] Unlocking file: J:\Media\MP3\_Soundtrack\2001 - American Pie 2 [MP3]\08 - Angela Ammons - Always Getting Over You.mp3
[4648] ProcessFile: Our file J:\Media\MP3\_Soundtrack\2001 - American Pie 2 [MP3]\09 - Jettingham - Cheating.mp3
[4648] 4204 DB lock finished, took 3824.
[4648] Locking file: J:\Media\MP3\_Soundtrack\2001 - American Pie 2 [MP3]\09 - Jettingham - Cheating.mp3
[4648] GetFileInfo: J:\Media\MP3\_Soundtrack\2001 - American Pie 2 [MP3]\09 - Jettingham - Cheating.mp3
[4648] 4204 DB lock finished, took 571.
[4648] 4204 DB open SQL: SELECT SignType FROM Songs WHERE ID=? 
[4648] Unlocking file: J:\Media\MP3\_Soundtrack\2001 - American Pie 2 [MP3]\09 - Jettingham - Cheating.mp3
[4648] ProcessFile: Our file J:\Media\MP3\_Soundtrack\2001 - American Pie 2 [MP3]\10 - Flying Blind - Smokescreen.mp3
[4648] 4204 DB lock finished, took 7457.
[4648] Locking file: J:\Media\MP3\_Soundtrack\2001 - American Pie 2 [MP3]\10 - Flying Blind - Smokescreen.mp3
[4648] GetFileInfo: J:\Media\MP3\_Soundtrack\2001 - American Pie 2 [MP3]\10 - Flying Blind - Smokescreen.mp3
[4648] 4204 DB lock finished, took 619.
[4648] 4204 DB open SQL: SELECT SignType FROM Songs WHERE ID=? 
[4648] Unlocking file: J:\Media\MP3\_Soundtrack\2001 - American Pie 2 [MP3]\10 - Flying Blind - Smokescreen.mp3
[4648] ProcessFile: Our file J:\Media\MP3\_Soundtrack\2001 - American Pie 2 [MP3]\11 - Fenix TX - Phoebe Cates.mp3
[4648] 4204 DB lock finished, took 3747.
[4648] Locking file: J:\Media\MP3\_Soundtrack\2001 - American Pie 2 [MP3]\11 - Fenix TX - Phoebe Cates.mp3
[4648] GetFileInfo: J:\Media\MP3\_Soundtrack\2001 - American Pie 2 [MP3]\11 - Fenix TX - Phoebe Cates.mp3
[4648] 4204 DB lock finished, took 564.
[4648] 4204 DB open SQL: SELECT SignType FROM Songs WHERE ID=? 
[4648] Unlocking file: J:\Media\MP3\_Soundtrack\2001 - American Pie 2 [MP3]\11 - Fenix TX - Phoebe Cates.mp3
[4648] ProcessFile: Our file J:\Media\MP3\_Soundtrack\2001 - American Pie 2 [MP3]\12 - The Exit - Susan.mp3
[4648] 4204 DB lock finished, took 3005.
[4648] Locking file: J:\Media\MP3\_Soundtrack\2001 - American Pie 2 [MP3]\12 - The Exit - Susan.mp3
[4648] GetFileInfo: J:\Media\MP3\_Soundtrack\2001 - American Pie 2 [MP3]\12 - The Exit - Susan.mp3
[4648] 4204 DB lock finished, took 462.
[4648] 4204 DB open SQL: SELECT SignType FROM Songs WHERE ID=? 
[4648] Unlocking file: J:\Media\MP3\_Soundtrack\2001 - American Pie 2 [MP3]\12 - The Exit - Susan.mp3
[4648] ProcessFile: Our file J:\Media\MP3\_Soundtrack\2001 - American Pie 2 [MP3]\13 - Sum 41 - Fat Lip.mp3
[4648] 4204 DB lock finished, took 3027.
[4648] Locking file: J:\Media\MP3\_Soundtrack\2001 - American Pie 2 [MP3]\13 - Sum 41 - Fat Lip.mp3
[4648] GetFileInfo: J:\Media\MP3\_Soundtrack\2001 - American Pie 2 [MP3]\13 - Sum 41 - Fat Lip.mp3
[4648] 4204 DB lock finished, took 447.
[4648] 4204 DB open SQL: SELECT SignType FROM Songs WHERE ID=? 
[4648] Unlocking file: J:\Media\MP3\_Soundtrack\2001 - American Pie 2 [MP3]\13 - Sum 41 - Fat Lip.mp3
[4648] ProcessFile: Our file J:\Media\MP3\_Soundtrack\2001 - American Pie 2 [MP3]\14 - Lucia - I Will.mp3
[4648] 4204 DB lock finished, took 2925.
[4648] Locking file: J:\Media\MP3\_Soundtrack\2001 - American Pie 2 [MP3]\14 - Lucia - I Will.mp3
[4648] GetFileInfo: J:\Media\MP3\_Soundtrack\2001 - American Pie 2 [MP3]\14 - Lucia - I Will.mp3
[4648] 4204 DB lock finished, took 630.
[4648] 4204 DB open SQL: SELECT SignType FROM Songs WHERE ID=? 
[4648] Unlocking file: J:\Media\MP3\_Soundtrack\2001 - American Pie 2 [MP3]\14 - Lucia - I Will.mp3
[4648] ProcessFile: Our file J:\Media\MP3\_Soundtrack\2001 - American Pie 2 [MP3]\15 - Oleander - Halo.mp3
[4648] 4204 DB lock finished, took 3205.
[4648] Locking file: J:\Media\MP3\_Soundtrack\2001 - American Pie 2 [MP3]\15 - Oleander - Halo.mp3
[4648] GetFileInfo: J:\Media\MP3\_Soundtrack\2001 - American Pie 2 [MP3]\15 - Oleander - Halo.mp3
[4648] 4204 DB lock finished, took 461.
[4648] 4204 DB open SQL: SELECT SignType FROM Songs WHERE ID=? 
[4648] Unlocking file: J:\Media\MP3\_Soundtrack\2001 - American Pie 2 [MP3]\15 - Oleander - Halo.mp3
[4648] ProcessFile: Our file J:\Media\MP3\_Soundtrack\2001 - American Pie 2 [MP3]\16 - Stroke 9 - Here's One for You.mp3
[4648] 4204 DB lock finished, took 2927.
[4648] Unlocking file: J:\Media\MP3\_Soundtrack\2001 - American Pie 2 [MP3]\16 - Stroke 9 - Here's One for You.mp3
[4648] 4204 DB exec SQL: INSERT INTO DeletedSongs_3 (IDSong) VALUES (?) 
[4648] 4204 DB lock finished, took 865.
[4648] 4204 DB exec SQL: INSERT INTO DeletedSongs_3 (IDSong) VALUES (?) 
[4648] 4204 DB lock finished, took 288.
[4648] 4204 DB exec SQL: INSERT INTO DeletedSongs_3 (IDSong) VALUES (?) 
[4648] 4204 DB lock finished, took 272.
[4648] 4204 DB exec SQL: INSERT INTO DeletedSongs_3 (IDSong) VALUES (?) 
[4648] 4204 DB lock finished, took 273.
[4648] 4204 DB exec SQL: INSERT INTO DeletedSongs_3 (IDSong) VALUES (?) 
[4648] 4204 DB lock finished, took 270.
[4648] 4204 DB exec SQL: INSERT INTO DeletedSongs_3 (IDSong) VALUES (?) 
[4648] 4204 DB lock finished, took 336.
[4648] 4204 DB exec SQL: INSERT INTO DeletedSongs_3 (IDSong) VALUES (?) 
[4648] 4204 DB lock finished, took 274.
[4648] 4204 DB exec SQL: INSERT INTO DeletedSongs_3 (IDSong) VALUES (?) 
[4648] 4204 DB lock finished, took 271.
[4648] 4204 DB exec SQL: INSERT INTO DeletedSongs_3 (IDSong) VALUES (?) 
[4648] 4204 DB lock finished, took 271.
[4648] 4204 DB exec SQL: INSERT INTO DeletedSongs_3 (IDSong) VALUES (?) 
[4648] 4204 DB lock finished, took 276.
[4648] 4204 DB exec SQL: INSERT INTO DeletedSongs_3 (IDSong) VALUES (?) 
[4648] 4204 DB lock finished, took 275.
[4648] 4204 DB exec SQL: INSERT INTO DeletedSongs_3 (IDSong) VALUES (?) 
[4648] 4204 DB lock finished, took 278.
[4648] 4204 DB exec SQL: INSERT INTO DeletedSongs_3 (IDSong) VALUES (?) 
[4648] 4204 DB lock finished, took 270.
[4648] 4204 DB exec SQL: INSERT INTO DeletedSongs_3 (IDSong) VALUES (?) 
[4648] 4204 DB lock finished, took 272.
[4648] 4204 DB exec SQL: INSERT INTO DeletedSongs_3 (IDSong) VALUES (?) 
[4648] 4204 DB lock finished, took 267.
[4648] 4204 DB exec SQL: INSERT INTO DeletedSongs_3 (IDSong) VALUES (?) 
[4648] 4204 DB lock finished, took 275.
[4648] Added Cover Lists: 2
[4648] Added Cover Lists: 3
[4648] 4204 DB open SQL: SELECT * FROM Covers WHERE IDSong=107275 ORDER BY CoverOrder 
[4648] 4204 DB lock finished, took 42799.
[4648] Added Covers: 7
[4648] 4204 DB lock finished, took 759.
[4648] Remaining Covers: 5
[4648] 4204 DB lock finished, took 738.
[4648] Added Covers: 7
[4648] 4204 DB lock finished, took 735.
[4648] Remaining Covers: 5
[4648] 4204 DB lock finished, took 695.
[4648] Added Covers: 7
[4648] 4204 DB lock finished, took 507.
[4648] Remaining Covers: 5
[4648] 4204 DB lock finished, took 704.
[4648] Added Covers: 7
[4648] 4204 DB lock finished, took 505.
[4648] Remaining Covers: 5
[4648] 4204 DB lock finished, took 808.
[4648] Added Covers: 7
[4648] 4204 DB lock finished, took 524.
[4648] Remaining Covers: 5
[4648] 4204 DB lock finished, took 687.
[4648] Added Covers: 7
[4648] 4204 DB lock finished, took 514.
[4648] Remaining Covers: 5
[4648] 4204 DB lock finished, took 689.
[4648] Added Covers: 7
[4648] 4204 DB lock finished, took 545.
[4648] Remaining Covers: 5
[4648] 4204 DB lock finished, took 697.
[4648] Added Covers: 7
[4648] 4204 DB lock finished, took 503.
[4648] Remaining Covers: 5
[4648] 4204 DB lock finished, took 684.
[4648] Added Covers: 7
[4648] 4204 DB lock finished, took 541.
[4648] Remaining Covers: 5
[4648] 4204 DB lock finished, took 746.
[4648] Added Covers: 7
[4648] 4204 DB lock finished, took 508.
[4648] Remaining Covers: 5
[4648] 4204 DB lock finished, took 715.
[4648] Added Covers: 7
[4648] 4204 DB lock finished, took 514.
[4648] Remaining Covers: 5
[4648] 4204 DB lock finished, took 683.
[4648] Added Covers: 7
[4648] 4204 DB lock finished, took 534.
[4648] Remaining Covers: 5
[4648] 4204 DB lock finished, took 680.
[4648] Added Covers: 7
[4648] 4204 DB lock finished, took 504.
[4648] Remaining Covers: 5
[4648] 4204 DB lock finished, took 714.
[4648] Added Covers: 7
[4648] 4204 DB lock finished, took 508.
[4648] Remaining Covers: 5
[4648] 4204 DB lock finished, took 742.
[4648] Added Covers: 7
[4648] 4204 DB lock finished, took 514.
[4648] Remaining Covers: 5
[4648] 4204 DB lock finished, took 682.
[4648] Added Covers: 7
[4648] 4204 DB lock finished, took 680.
[4648] Remaining Covers: 5
[4648] Remaining Cover Lists: 2
[4648] Remaining Cover Lists: 1

What is this?

I have background auto-organize unchecked (but have a single rule listed), and also background volume analysis is unchecked.

[rusty]

Looks like hash calculations being performed. Do you have it enabled? That would account for slow scanning.

-Rusty

[Teknojnky]

yes I have analyze for duplicates enabled, but I had no add/rescan going on and to my knowledge nothing was changing any monitored files.

[jiri]

It looks like a normal start-up scan - don't you have it configured in File Monitor?

Jiri

[Teknojnky]

yea I have a large monitored folder, with a secondary folder startup scan, but this log was taken after MM had been up already.

No background processes were running in the status, and I had not (knowingly) triggered anything that would be scanning or doing any updates, thats why I consider it strange, as I don't know what it was doing nor what triggered it.

[jiri]

The log really looks like a scan and can hardly be anything else. Is it reproducible? If so, you can try to create a complete debug log and e-mail it to me together with description of when you see/don't see any progress bars in MM interface.

Jiri

[Teknojnky]

unfortunatelly I don't have a complete log as I only opened debug viewer to see why my cpu/disk usage was so high and saw a (then) constant stream of the same thing with seemingly random albums/files/etc.

I can't reproduce since I don't know what triggered it, and I have not noticed it happening since then, but if I do I will try to capture a more complete log.

[rusty]

Teknojnky,

I assume you're no longer observing this issue in 1276?

-Rusty

[Teknojnky]

nope, haven't seen it so far