PM takes long time to "come back"

[Sven]

Hi Team!

I recently have a strange behaviour with PM.
Currently I have a contact sheet open with about 900 images in. I unchecked the first 2 "colour indicators" and I scroll throug the images in full size. I tage some of them as !1! and some as "2". When closing the full screen view PM is going to update the filtered contact sheet to show only un"coloured" images.

This takes about 2 or 3 minutes. PM consumes in that time nearly ALL of the CPU.
PM is running from a SSD, the images are on my 2nd internal disk on the MacBook.

PM 4.6.7 on MacOS X 10.6.7

[Kirk Baker]

Sven,

I recently have a strange behaviour with PM.
Currently I have a contact sheet open with about 900 images in. I unchecked the first 2 "colour indicators" and I scroll throug the images in full size. I tage some of them as !1! and some as "2". When closing the full screen view PM is going to update the filtered contact sheet to show only un"coloured" images.

This takes about 2 or 3 minutes. PM consumes in that time nearly ALL of the CPU.
PM is running from a SSD, the images are on my 2nd internal disk on the MacBook.

PM 4.6.7 on MacOS X 10.6.7

So you're saying that you start out in the contact sheet, use the Color Class Filter widget in the bottom right corner of the screen to deselect the first two colors.  Does it take a long time at this point?
Then you open up the Preview window and mark some images with the first color class and some with the second color class.  Next you close the Preview window and return to the contact sheet.

It then takes a long time to rescan the contact sheet?  Can you use PM during this time, or do you have a spinning, colorful mouse cursor?

-Kirk

[Sven]

Kirk, you got it!

PM is not usable at this time and i get the "wheel of fortune" spinning at this time. Activitymonitor shows also PM not responding.

[Sven]

Did it the other way now. Having no filters applied, a folder with 750 images. Tagged some in the first and second colour while being in Preview. Then closed the preview and all is fine.
After that I deselected the first colour to show only the red and untagged images. This takes ages now. PM gives the Wheel of fortune and in the lower right PM says: "sort in progress".

Nothing special in the logfile...

Code: [Select]

Photo Mechanic version 4.6.7.0
[Sat Jun-04-2011 20:06:55.561][2684880192] ContactSheetWindowController.mm:239 ($Revision: 1.13.2.1 $) addTabForNewDocument: preview is not running, making window key
[Sat Jun-04-2011 20:07:04.885][2684880192] DiskUtils.mm:1222 ($Revision: 1.115.2.6 $) DiskUtils::DriveIsRemovable() entered for disk '/Volumes/Mac-Daten/Fotos/2011/2011_06_04_Motocross in Eichenried/Klasse1/fertig/'
[Sat Jun-04-2011 20:07:04.885][2684880192] DiskUtils.mm:1224 ($Revision: 1.115.2.6 $) Disk: '/Volumes/Mac-Daten/Fotos/2011/2011_06_04_Motocross in Eichenried/Klasse1/fertig/' dev entry: 'disk1s2'
[Sat Jun-04-2011 20:07:04.888][2684880192] DiskUtils.mm:1228 ($Revision: 1.115.2.6 $) Disk: '/Volumes/Mac-Daten/Fotos/2011/2011_06_04_Motocross in Eichenried/Klasse1/fertig/' disk object: 21855
[Sat Jun-04-2011 20:07:04.889][2684880192] DiskUtils.mm:1232 ($Revision: 1.115.2.6 $) removable != NULL
[Sat Jun-04-2011 20:07:04.889][2684880192] DiskUtils.mm:1235 ($Revision: 1.115.2.6 $) removable value: false
[Sat Jun-04-2011 20:07:04.889][2684880192] DiskUtils.mm:1243 ($Revision: 1.115.2.6 $) DiskUtils::DriveIsRemovable() exit with result: false
[Sat Jun-04-2011 20:07:04.892][2684880192] DiskUtils.mm:1222 ($Revision: 1.115.2.6 $) DiskUtils::DriveIsRemovable() entered for disk '/Volumes/Mac-Daten/Fotos/2011/2011_06_04_Motocross in Eichenried/Klasse1/fertig/'
[Sat Jun-04-2011 20:07:04.892][2684880192] DiskUtils.mm:1224 ($Revision: 1.115.2.6 $) Disk: '/Volumes/Mac-Daten/Fotos/2011/2011_06_04_Motocross in Eichenried/Klasse1/fertig/' dev entry: 'disk1s2'
[Sat Jun-04-2011 20:07:04.895][2684880192] DiskUtils.mm:1228 ($Revision: 1.115.2.6 $) Disk: '/Volumes/Mac-Daten/Fotos/2011/2011_06_04_Motocross in Eichenried/Klasse1/fertig/' disk object: 49671
[Sat Jun-04-2011 20:07:04.896][2684880192] DiskUtils.mm:1232 ($Revision: 1.115.2.6 $) removable != NULL
[Sat Jun-04-2011 20:07:04.896][2684880192] DiskUtils.mm:1235 ($Revision: 1.115.2.6 $) removable value: false
[Sat Jun-04-2011 20:07:04.896][2684880192] DiskUtils.mm:1243 ($Revision: 1.115.2.6 $) DiskUtils::DriveIsRemovable() exit with result: false
[Sat Jun-04-2011 20:08:46.442][2981076992] ruby_embed.cpp:90 ($Revision: 1.31 $) scan_for_templates: ignoring empty search path(s)
[Sat Jun-04-2011 20:18:14.577][2684880192] DiskUtils.mm:1222 ($Revision: 1.115.2.6 $) DiskUtils::DriveIsRemovable() entered for disk '/Volumes/Mac-Daten/Fotos/2011/2011_06_04_Motocross in Eichenried/Klasse2/'
[Sat Jun-04-2011 20:18:14.624][2684880192] DiskUtils.mm:1224 ($Revision: 1.115.2.6 $) Disk: '/Volumes/Mac-Daten/Fotos/2011/2011_06_04_Motocross in Eichenried/Klasse2/' dev entry: 'disk1s2'
[Sat Jun-04-2011 20:18:14.628][2684880192] DiskUtils.mm:1228 ($Revision: 1.115.2.6 $) Disk: '/Volumes/Mac-Daten/Fotos/2011/2011_06_04_Motocross in Eichenried/Klasse2/' disk object: 66431
[Sat Jun-04-2011 20:18:14.628][2684880192] DiskUtils.mm:1232 ($Revision: 1.115.2.6 $) removable != NULL
[Sat Jun-04-2011 20:18:14.628][2684880192] DiskUtils.mm:1235 ($Revision: 1.115.2.6 $) removable value: false
[Sat Jun-04-2011 20:18:14.629][2684880192] DiskUtils.mm:1243 ($Revision: 1.115.2.6 $) DiskUtils::DriveIsRemovable() exit with result: false
[Sat Jun-04-2011 20:18:14.633][2684880192] DiskUtils.mm:1222 ($Revision: 1.115.2.6 $) DiskUtils::DriveIsRemovable() entered for disk '/Volumes/Mac-Daten/Fotos/2011/2011_06_04_Motocross in Eichenried/Klasse2/'
[Sat Jun-04-2011 20:18:14.633][2684880192] DiskUtils.mm:1224 ($Revision: 1.115.2.6 $) Disk: '/Volumes/Mac-Daten/Fotos/2011/2011_06_04_Motocross in Eichenried/Klasse2/' dev entry: 'disk1s2'
[Sat Jun-04-2011 20:18:14.636][2684880192] DiskUtils.mm:1228 ($Revision: 1.115.2.6 $) Disk: '/Volumes/Mac-Daten/Fotos/2011/2011_06_04_Motocross in Eichenried/Klasse2/' disk object: 52355
[Sat Jun-04-2011 20:18:14.676][2684880192] DiskUtils.mm:1232 ($Revision: 1.115.2.6 $) removable != NULL
[Sat Jun-04-2011 20:18:14.677][2684880192] DiskUtils.mm:1235 ($Revision: 1.115.2.6 $) removable value: false
[Sat Jun-04-2011 20:18:14.677][2684880192] DiskUtils.mm:1243 ($Revision: 1.115.2.6 $) DiskUtils::DriveIsRemovable() exit with result: false
[Sat Jun-04-2011 20:18:15.323][2684880192] ContactSheetWindowController.mm:239 ($Revision: 1.13.2.1 $) addTabForNewDocument: preview is not running, making window key


[Kirk Baker]

Sven,

Does this seem like new behavior since PM 4.6.6?

Filtering by stars or color class does take longer to sort than sorting by filename alone until all metadata is loaded for all images in the contact sheet.  Have all of the thumbnails loaded?

-Kirk

[Sven]

Krik,
all thumbs have been loaded before starting to select images. I cannot remember when it started to be slow as now. But as far as I can remember it was a lot faster with an older version. For sure it was fast as hell with 4.6.5.

Disk-Cache is set to 4 GB with deleting it when PM closes and RAM to 384 MB

[Sven]

Short Update: Even without having colour-classes hidden it takes a long time to get responsive at all...
Having a folder with 1035 images in there, went in preview through about 502 of them, tagged 2 images in red and 404 in yellow, captured the ones marked in yellow and then quit the preview mode it took 12 minutes to become responsive again. After that time it started "Sort in progress" even the sort is by filename and no files have been deleted or added in between.
This step took another 6 min.

[Kirk Baker]

Short Update: Even without having colour-classes hidden it takes a long time to get responsive at all...
Having a folder with 1035 images in there, went in preview through about 502 of them, tagged 2 images in red and 404 in yellow, captured the ones marked in yellow and then quit the preview mode it took 12 minutes to become responsive again. After that time it started "Sort in progress" even the sort is by filename and no files have been deleted or added in between.
This step took another 6 min.

OK, well we'll see if we can reproduce the issue.  If not, then I'll have to send you a diagnostic version to get to the bottom of the issue.

-Kirk

[Sven]

I am willing to help where I can!

[Sven]

One more thing to add:
The same issue as before but when I close the preview and switching to a different App in the time, PM "re-indexes" all files, and then (when PM is finished) get the focus back on PM it takes another few minutes with the spinning wheel in the lower left of PM.