Project: 2669 (Run 17, Clone 94, Gen 118)

[Ragnar Dan]

This WU is taking my C2D ~62.5 minutes per frame, when normally such WU's would take under 10 minutes per frame. It will expire before it finishes.


In addition, I have another machine (BE-2400 A64-X2 @ 2840 MHz), which is taking about 104 minutes per frame with a 2677 (R11, C52, G40) WU, when normally it would be in the ~16 minutes per frame range.

I think there is possibly a difference between the problems the 2 machines are having, because the BE-2400 was progressing normally until it restarted itself for some reason (there may be a heat problem where it's located). Once that happened, it ran slowly and I seem to be getting a great deal of CPU time on the System Idle Process. It's running notfred's ISO in a VM under Win7 and with a GPU running too, and I thought it wasn't getting enough time from Windows, but seeing the C2D download a WU and begin going very slowly makes me wonder. I verified all 4 Fahcore_a2.exe processes are running on the C2D, and they have normal looking total CPU usage, anyway, though I forgot to check anything else.

Any thoughts would be appreciated.

[Ragnar Dan]

I thought I'd include the FAHlog.txt from the C2D machine to demonstrate the change in speed:

Code: Select all

[08:28:16] + Working ...
[08:28:16] 
[08:28:16] *------------------------------*
[08:28:16] Folding@Home Gromacs SMP Core
[08:28:16] Version 2.08 (Mon May 18 14:47:42 PDT 2009)
[08:28:16] 
[08:28:16] Preparing to commence simulation
[08:28:16] - Ensuring status. Please wait.
[08:28:25] - Assembly optimizations manually forced on.
[08:28:25] - Not checking prior termination.
[08:28:26] - Expanded 4840806 -> 24022397 (decompressed 496.2 percent)
[08:28:26] Called DecompressByteArray: compressed_data_size=4840806 data_size=24022397, decompressed_data_size=24022397 diff=0
[08:28:27] - Digital signature verified
[08:28:27] 
[08:28:27] Project: 2677 (Run 33, Clone 73, Gen 40)
[08:28:27] 
[08:28:27] Assembly optimizations on if available.
[08:28:27] Entering M.D.
[08:28:34] Multi-core optimizations on
[08:28:35] Completed 0 out of 250000 steps  (0%)
[08:38:25] Completed 2500 out of 250000 steps  (1%)
[08:48:18] Completed 5000 out of 250000 steps  (2%)
[08:58:11] Completed 7500 out of 250000 steps  (3%)
[09:08:02] Completed 10000 out of 250000 steps  (4%)
[09:17:53] Completed 12500 out of 250000 steps  (5%)
[09:27:46] Completed 15000 out of 250000 steps  (6%)
[09:37:34] Completed 17500 out of 250000 steps  (7%)
[09:47:28] Completed 20000 out of 250000 steps  (8%)
[09:57:17] Completed 22500 out of 250000 steps  (9%)
[10:07:07] Completed 25000 out of 250000 steps  (10%)
[10:17:01] Completed 27500 out of 250000 steps  (11%)
[10:26:53] Completed 30000 out of 250000 steps  (12%)
[10:36:42] Completed 32500 out of 250000 steps  (13%)
[10:46:31] Completed 35000 out of 250000 steps  (14%)
[10:56:23] Completed 37500 out of 250000 steps  (15%)
[11:06:14] Completed 40000 out of 250000 steps  (16%)
[11:16:05] Completed 42500 out of 250000 steps  (17%)
[11:25:55] Completed 45000 out of 250000 steps  (18%)
[11:35:47] Completed 47500 out of 250000 steps  (19%)
[11:45:38] Completed 50000 out of 250000 steps  (20%)
[11:55:31] Completed 52500 out of 250000 steps  (21%)
[12:05:19] Completed 55000 out of 250000 steps  (22%)
[12:15:08] Completed 57500 out of 250000 steps  (23%)
[12:25:02] Completed 60000 out of 250000 steps  (24%)
[12:34:53] Completed 62500 out of 250000 steps  (25%)
[12:44:44] Completed 65000 out of 250000 steps  (26%)
[12:54:33] Completed 67500 out of 250000 steps  (27%)
[13:04:25] Completed 70000 out of 250000 steps  (28%)
[13:14:17] Completed 72500 out of 250000 steps  (29%)
[13:24:08] Completed 75000 out of 250000 steps  (30%)
[13:34:01] Completed 77500 out of 250000 steps  (31%)
[13:43:52] Completed 80000 out of 250000 steps  (32%)
[13:53:45] Completed 82500 out of 250000 steps  (33%)
[14:03:36] Completed 85000 out of 250000 steps  (34%)
[14:13:26] Completed 87500 out of 250000 steps  (35%)
[14:23:17] Completed 90000 out of 250000 steps  (36%)
[14:33:08] Completed 92500 out of 250000 steps  (37%)
[14:43:01] Completed 95000 out of 250000 steps  (38%)
[14:52:54] Completed 97500 out of 250000 steps  (39%)
[15:02:42] Completed 100000 out of 250000 steps  (40%)
[15:12:35] Completed 102500 out of 250000 steps  (41%)
[15:22:27] Completed 105000 out of 250000 steps  (42%)
[15:32:14] Completed 107500 out of 250000 steps  (43%)
[15:42:06] Completed 110000 out of 250000 steps  (44%)
[15:51:56] Completed 112500 out of 250000 steps  (45%)
[16:01:48] Completed 115000 out of 250000 steps  (46%)
[16:11:39] Completed 117500 out of 250000 steps  (47%)
[16:21:27] Completed 120000 out of 250000 steps  (48%)
[16:31:18] Completed 122500 out of 250000 steps  (49%)
[16:41:09] Completed 125000 out of 250000 steps  (50%)
[16:51:01] Completed 127500 out of 250000 steps  (51%)
[17:00:53] Completed 130000 out of 250000 steps  (52%)
[17:10:42] Completed 132500 out of 250000 steps  (53%)
[17:20:32] Completed 135000 out of 250000 steps  (54%)
[17:30:26] Completed 137500 out of 250000 steps  (55%)
[17:40:16] Completed 140000 out of 250000 steps  (56%)
[17:50:05] Completed 142500 out of 250000 steps  (57%)
[17:59:54] Completed 145000 out of 250000 steps  (58%)
[18:09:45] Completed 147500 out of 250000 steps  (59%)
[18:19:35] Completed 150000 out of 250000 steps  (60%)
[18:29:26] Completed 152500 out of 250000 steps  (61%)
[18:39:16] Completed 155000 out of 250000 steps  (62%)
[18:49:08] Completed 157500 out of 250000 steps  (63%)
[18:59:00] Completed 160000 out of 250000 steps  (64%)
[19:08:52] Completed 162500 out of 250000 steps  (65%)
[19:18:46] Completed 165000 out of 250000 steps  (66%)
[19:28:36] Completed 167500 out of 250000 steps  (67%)
[19:38:28] Completed 170000 out of 250000 steps  (68%)
[19:48:20] Completed 172500 out of 250000 steps  (69%)
[19:58:13] Completed 175000 out of 250000 steps  (70%)
[20:08:04] Completed 177500 out of 250000 steps  (71%)
[20:17:53] Completed 180000 out of 250000 steps  (72%)
[20:27:42] Completed 182500 out of 250000 steps  (73%)
[20:37:33] Completed 185000 out of 250000 steps  (74%)
[20:47:25] Completed 187500 out of 250000 steps  (75%)
[20:57:16] Completed 190000 out of 250000 steps  (76%)
[21:07:09] Completed 192500 out of 250000 steps  (77%)
[21:16:58] Completed 195000 out of 250000 steps  (78%)
[21:26:51] Completed 197500 out of 250000 steps  (79%)
[21:36:41] Completed 200000 out of 250000 steps  (80%)
[21:46:33] Completed 202500 out of 250000 steps  (81%)
[21:56:26] Completed 205000 out of 250000 steps  (82%)
[22:06:20] Completed 207500 out of 250000 steps  (83%)
[22:16:12] Completed 210000 out of 250000 steps  (84%)
[22:26:04] Completed 212500 out of 250000 steps  (85%)
[22:35:56] Completed 215000 out of 250000 steps  (86%)
[22:45:47] Completed 217500 out of 250000 steps  (87%)
[22:55:39] Completed 220000 out of 250000 steps  (88%)
[23:05:32] Completed 222500 out of 250000 steps  (89%)
[23:15:24] Completed 225000 out of 250000 steps  (90%)
[23:25:15] Completed 227500 out of 250000 steps  (91%)
[23:35:07] Completed 230000 out of 250000 steps  (92%)
[23:45:01] Completed 232500 out of 250000 steps  (93%)
[23:54:53] Completed 235000 out of 250000 steps  (94%)
[00:04:44] Completed 237500 out of 250000 steps  (95%)
[00:14:37] Completed 240000 out of 250000 steps  (96%)
[00:24:26] Completed 242500 out of 250000 steps  (97%)
[00:34:17] Completed 245000 out of 250000 steps  (98%)
[00:44:10] Completed 247500 out of 250000 steps  (99%)
[00:54:02] Completed 250000 out of 250000 steps  (100%)
[00:54:02] DynamicWrapper: Finished Work Unit: sleep=10000
[00:54:12] 
[00:54:12] Finished Work Unit:
[00:54:12] - Reading up to 21172608 from "work/wudata_03.trr": Read 21172608
[00:54:13] trr file hash check passed.
[00:54:13] - Reading up to 27129116 from "work/wudata_03.xtc": Read 27129116
[00:54:13] xtc file hash check passed.
[00:54:13] edr file hash check passed.
[00:54:13] logfile size: 181806
[00:54:13] Leaving Run
[00:54:13] - Writing 48628282 bytes of core data to disk...
[00:54:13]   ... Done.
[00:54:13] - Shutting down core
[00:54:13] 
[00:54:13] Folding@home Core Shutdown: FINISHED_UNIT
[00:57:33] CoreStatus = 64 (100)
[00:57:33] Sending work to server


[00:57:33] + Attempting to send results
[01:03:21] + Results successfully sent
[01:03:21] Thank you for your contribution to Folding@Home.
[01:03:21] + Number of Units Completed: 4

[01:03:35] - Preparing to get new work unit...
[01:03:35] + Attempting to get work packet
[01:03:35] - Connecting to assignment server
[01:03:35] - Successful: assigned to (171.64.65.56).
[01:03:35] + News From Folding@Home: Welcome to Folding@Home
[01:03:35] Loaded queue successfully.
[01:03:44] + Closed connections
[01:03:44] 
[01:03:44] + Processing work unit
[01:03:44] At least 4 processors must be requested.Core required: FahCore_a2.exe
[01:03:44] Core found.
[01:03:44] Working on Unit 04 [August 30 01:03:44]
[01:03:44] + Working ...
[01:03:45] 
[01:03:45] *------------------------------*
[01:03:45] Folding@Home Gromacs SMP Core
[01:03:45] Version 2.08 (Mon May 18 14:47:42 PDT 2009)
[01:03:45] 
[01:03:45] Preparing to commence simulation
[01:03:45] - Ensuring status. Please wait.
[01:03:54] - Assembly optimizations manually forced on.
[01:03:54] - Not checking prior termination.
[01:03:55] - Expanded 1513580 -> 23985345 (decompressed 1584.6 percent)
[01:03:55] Called DecompressByteArray: compressed_data_size=1513580 data_size=23985345, decompressed_data_size=23985345 diff=0
[01:03:55] - Digital signature verified
[01:03:55] 
[01:03:55] Project: 2669 (Run 17, Clone 94, Gen 118)
[01:03:55] 
[01:03:55] Assembly optimizations on if available.
[01:03:55] Entering M.D.
[01:04:02] Multi-core optimizations on
[01:04:17] Completed 0 out of 250000 steps  (0%)
[02:06:39] Completed 2500 out of 250000 steps  (1%)
[03:09:10] Completed 5000 out of 250000 steps  (2%)
[04:11:40] Completed 7500 out of 250000 steps  (3%)
[05:14:11] Completed 10000 out of 250000 steps  (4%)
[06:16:42] Completed 12500 out of 250000 steps  (5%)
[07:19:12] Completed 15000 out of 250000 steps  (6%)
[08:21:43] Completed 17500 out of 250000 steps  (7%)
[09:24:13] Completed 20000 out of 250000 steps  (8%)
[10:26:44] Completed 22500 out of 250000 steps  (9%)
[11:29:15] Completed 25000 out of 250000 steps  (10%)
[12:31:45] Completed 27500 out of 250000 steps  (11%)
[13:34:16] Completed 30000 out of 250000 steps  (12%)
[14:36:47] Completed 32500 out of 250000 steps  (13%)
[15:39:18] Completed 35000 out of 250000 steps  (14%)
[16:41:48] Completed 37500 out of 250000 steps  (15%)
[17:44:18] Completed 40000 out of 250000 steps  (16%)
[18:46:49] Completed 42500 out of 250000 steps  (17%)

The BE-2400 finished its WU, uploaded it, and got a new one, and seems to be working normally again, so whatever the problem was seems to have been with that WU alone.

[anandhanju]

This WU is known to run slowly and is "bad" [see list here].

[Ragnar Dan]

Hey, thanks much for the informative reply.

I'd let it go and somewhat forgot about it today, but since seeing your response and the linked thread, I restarted on a new WU. It's odd, though, that all 4 cores did seem to be running for me. I don't know if that means anything, but since I wasn't bright enough to check the download size and since it's diskless the FAHlog starts anew each time it reboots, I couldn't check it after the reboot.