Page 1 of 1

Project: 7600 (Run 37, Clone 1, Gen 16)

Posted: Sun Nov 06, 2011 1:04 am
by fredex
I'm running FAH on linux (32-bit OS) on a AMD Phenom II X2, a separate FAH client on each CPU core.

currently I've got a project 7600 workload on both cores, and both of them are painfully slow. they are:

[10:32:14] Project: 7600 (Run 37, Clone 1, Gen 16)
and
[04:50:10] Project: 7600 (Run 10, Clone 65, Gen 13)

both have been running for almost two days, and one is only 51% done and the other 59%.

Each of them is using 98-99% of its dedicated CPU core, so it's not bogged down on something else.

I notice it's NOT using SSE optimizations, as it normally does:

Code: Select all

[10:32:06] Connecting to http://assign.stanford.edu:8080/
[10:32:07] Posted data.
[10:32:07] Initial: 40AB; - Successful: assigned to (171.64.65.101).
[10:32:07] + News From Folding@Home: Welcome to Folding@Home
[10:32:07] Loaded queue successfully.
[10:32:07] Connecting to http://171.64.65.101:8080/
[10:32:08] Posted data.
[10:32:08] Initial: 0000; - Receiving payload (expected size: 303389)
[10:32:09] - Downloaded at ~296 kB/s
[10:32:09] - Averaged speed for that direction ~404 kB/s
[10:32:09] + Received work.
[10:32:09] + Closed connections
[10:32:14] 
[10:32:14] + Processing work unit
[10:32:14] Core required: FahCore_a4.exe
[10:32:14] Core found.
[10:32:14] Working on Unit 01 [November 4 10:32:14]
[10:32:14] + Working ...
[10:32:14] - Calling './FahCore_a4.exe -dir work/ -suffix 01 -checkpoint 15 -verbose -lifeline 4251 -version 602'

[10:32:14] 
[10:32:14] *------------------------------*
[10:32:14] Folding@Home Gromacs GB Core
[10:32:14] Version 2.27 (Dec. 15, 2010)
[10:32:14] 
[10:32:14] Preparing to commence simulation
[10:32:14] - Looking at optimizations...
[10:32:14] - Created dyn
[10:32:14] - Files status OK
[10:32:14] - Expanded 302877 -> 505816 (decompressed 167.0 percent)
[10:32:14] Called DecompressByteArray: compressed_data_size=302877 data_size=505816, decompressed_data_size=505816 diff=0
[10:32:14] - Digital signature verified
[10:32:14] 
[10:32:14] Project: 7600 (Run 37, Clone 1, Gen 16)
[10:32:14] 
[10:32:14] Assembly optimizations on if available.
[10:32:14] Entering M.D.
[10:32:20] Mapping NT from 1 to 1



Never had a work unit take so long before. anyone know what's happening here?

Project: 7600 (Run 13, Clone 38, Gen 16)

Posted: Mon Nov 07, 2011 4:21 pm
by RafaPolit
Same issue here... my Q6600 that normally reports around 3200ppd is reporting probably 1700ppd with this 7600 (13,38,16) WU. I am not one of those PPD obsessed types, but I do think that projects need to be moderately balanced. A 10%~20% difference is understandable, but 50% less is not really ideal. My Q6600 reports 25 mins per frame on a 937 point-base WU.

Here's my log for the WU in case it is useful:

Code: Select all

13:21:26:Unit 00:Folding@Home Gromacs GB Core
13:21:26:Unit 00:Version 2.27 (Dec. 15, 2010)
13:21:26:Unit 00:
13:21:26:Unit 00:Preparing to commence simulation
13:21:26:Unit 00:- Looking at optimizations...
13:21:26:Unit 00:- Created dyn
13:21:26:Unit 00:- Files status OK
13:21:26:Unit 00:- Expanded 302206 -> 505104 (decompressed 167.1 percent)
13:21:26:Unit 00:Called DecompressByteArray: compressed_data_size=302206 data_size=505104, decompressed_data_size=505104 diff=0
13:21:26:Unit 00:- Digital signature verified
13:21:26:Unit 00:
13:21:26:Unit 00:Project: 7600 (Run 13, Clone 38, Gen 16)
13:21:26:Unit 00:
13:21:26:Unit 00:Assembly optimizations on if available.
13:21:26:Unit 00:Entering M.D.
13:21:32:Unit 01: 38.17%
13:21:32:Unit 00:Mapping NT from 4 to 4 
13:21:32:Unit 00:Completed 0 out of 2000000 steps  (0%)
13:21:38:Unit 01: 80.09%
13:21:41:Unit 01: Upload complete
13:21:41:Server responded WORK_ACK (400)
13:21:41:Final credit estimate, 1734.00 points
13:21:41:Cleaning up Unit 01
13:21:52:Unit 02:Completed 42%
13:22:38:Unit 02:Completed 43%
13:23:25:Unit 02:Completed 44%
13:24:11:Unit 02:Completed 45%
13:24:57:Unit 02:Completed 46%
13:25:43:Unit 02:Completed 47%
13:26:30:Unit 02:Completed 48%
13:27:16:Unit 02:Completed 49%
13:28:02:Unit 02:Completed 50%
13:28:49:Unit 02:Completed 51%
13:29:35:Unit 02:Completed 52%
13:30:21:Unit 02:Completed 53%
13:31:07:Unit 02:Completed 54%
13:31:53:Unit 02:Completed 55%
13:32:40:Unit 02:Completed 56%
13:33:26:Unit 02:Completed 57%
13:34:12:Unit 02:Completed 58%
13:35:00:Unit 02:Completed 59%
13:35:47:Unit 02:Completed 60%
13:36:34:Unit 02:Completed 61%
13:37:22:Unit 02:Completed 62%
13:38:09:Unit 02:Completed 63%
13:38:59:Unit 02:Completed 64%
13:39:45:Unit 02:Completed 65%
13:40:33:Unit 02:Completed 66%
13:41:20:Unit 02:Completed 67%
13:41:47:Unit 00:Completed 20000 out of 2000000 steps  (1%)
13:42:09:Unit 02:Completed 68%
13:42:58:Unit 02:Completed 69%
13:43:45:Unit 02:Completed 70%
13:44:33:Unit 02:Completed 71%
13:45:20:Unit 02:Completed 72%
13:46:07:Unit 02:Completed 73%
13:46:54:Unit 02:Completed 74%
13:47:42:Unit 02:Completed 75%
13:48:29:Unit 02:Completed 76%
13:49:16:Unit 02:Completed 77%
13:50:02:Unit 02:Completed 78%
13:50:49:Unit 02:Completed 79%
13:51:37:Unit 02:Completed 80%
13:52:23:Unit 02:Completed 81%
13:53:11:Unit 02:Completed 82%
13:53:59:Unit 02:Completed 83%
13:54:46:Unit 02:Completed 84%
13:55:34:Unit 02:Completed 85%
13:56:21:Unit 02:Completed 86%
13:57:09:Unit 02:Completed 87%
13:57:55:Unit 02:Completed 88%
13:58:42:Unit 02:Completed 89%
13:59:29:Unit 02:Completed 90%
14:00:16:Unit 02:Completed 91%
14:01:02:Unit 02:Completed 92%
14:01:49:Unit 02:Completed 93%
14:02:35:Unit 02:Completed 94%
14:02:56:Unit 00:Completed 40000 out of 2000000 steps  (2%)
14:03:22:Unit 02:Completed 95%
14:04:08:Unit 02:Completed 96%
14:04:56:Unit 02:Completed 97%
14:05:43:Unit 02:Completed 98%
14:06:33:Unit 02:Completed 99%
14:06:33:Connecting to assign-GPU.stanford.edu:80
14:06:34:News: Welcome to Folding@Home
14:06:34:Assigned to work server 171.67.108.11
14:06:34:Requesting new work unit for slot 00: RUNNING gpu:0:"G92 [GeForce 9800 GTX]" from 171.67.108.11
14:06:34:Connecting to 171.67.108.11:8080
14:06:35:Slot 00: Downloading 46.13KiB
14:06:39:Slot 00: Download complete
14:06:39:Received Unit: id:01 state:DOWNLOAD error:OK project:5765 run:9 clone:183 gen:49 core:0x11 unit:0x461344504eb7e5fb003100b700091685
14:07:25:Unit 02:Completed 100%
14:07:26:Unit 02:Successful run
14:07:26:Unit 02:DynamicWrapper: Finished Work Unit: sleep=10000
14:07:36:Unit 02:Reserved 75924 bytes for xtc file; Cosm status=0
14:07:36:Unit 02:Allocated 75924 bytes for xtc file
14:07:36:Unit 02:- Reading up to 75924 from "02/wudata_01.xtc": Read 75924
14:07:36:Unit 02:Read 75924 bytes from xtc file; available packet space=786354540
14:07:36:Unit 02:xtc file hash check passed.
14:07:36:Unit 02:Reserved 15168 15168 786354540 bytes for arc file=<02/wudata_01.trr> Cosm status=0
14:07:36:Unit 02:Allocated 15168 bytes for arc file
14:07:36:Unit 02:- Reading up to 15168 from "02/wudata_01.trr": Read 15168
14:07:36:Unit 02:Read 15168 bytes from arc file; available packet space=786339372
14:07:36:Unit 02:trr file hash check passed.
14:07:36:Unit 02:Allocated 560 bytes for edr file
14:07:36:Unit 02:Read bedfile
14:07:36:Unit 02:edr file hash check passed.
14:07:36:Unit 02:Allocated 24687 bytes for logfile
14:07:36:Unit 02:Read logfile
14:07:36:Unit 02:GuardedRun: success in DynamicWrapper
14:07:36:Unit 02:GuardedRun: done
14:07:36:Unit 02:Run: GuardedRun completed.
14:07:37:Unit 02:+ Opened results file
14:07:37:Unit 02:- Writing 116851 bytes of core data to disk...
14:07:38:Unit 02:Done: 116339 -> 97815 (compressed to 84.0 percent)
14:07:38:Unit 02:  ... Done.
14:07:38:Unit 02:DeleteFrameFiles: successfully deleted file=02/wudata_01.ckp
14:07:38:Unit 02:Shutting down core 
14:07:38:Unit 02:
14:07:38:Unit 02:Folding@home Core Shutdown: FINISHED_UNIT
14:07:38:FahCore, running Unit 02, returned: FINISHED_UNIT (100 = 0x64)
14:07:38:Sending unit results: id:02 state:SEND error:OK project:5771 run:1 clone:157 gen:62 core:0x11 unit:0x2cee1de64eb7d3a8003e009d0001168b
14:07:38:Unit 02: Uploading 96.02KiB to 171.67.108.11
14:07:38:Connecting to 171.67.108.11:8080
14:07:38:Starting Unit 01
14:07:38:Running core: C:/ProgramData/FAHClient/cores/www.stanford.edu/~pande/Win32/AMD64/NVIDIA/G80/Core_11.fah/FahCore_11.exe -dir 01 -suffix 01 -lifeline 7900 -version 701 -checkpoint 15 -gpu 0
14:07:38:Started core on PID 7028
14:07:38:FahCore 0x11 started
14:07:39:Unit 01:
14:07:39:Unit 01:*------------------------------*
14:07:39:Unit 01:Folding@Home GPU Core
14:07:39:Unit 01:Version 1.31 (Tue Sep 15 10:57:42 PDT 2009)
14:07:39:Unit 01:
14:07:39:Unit 01:Compiler  : Microsoft (R) 32-bit C/C++ Optimizing Compiler Version 14.00.50727.762 for 80x86 
14:07:39:Unit 01:Build host: amoeba
14:07:39:Unit 01:Board Type: Nvidia
14:07:39:Unit 01:Core      : 
14:07:39:Unit 01:Preparing to commence simulation
14:07:39:Unit 01:- Looking at optimizations...
14:07:39:Unit 01:DeleteFrameFiles: successfully deleted file=01/wudata_01.ckp
14:07:39:Unit 01:- Created dyn
14:07:39:Unit 01:- Files status OK
14:07:39:Unit 01:- Expanded 46727 -> 252912 (decompressed 541.2 percent)
14:07:39:Unit 01:Called DecompressByteArray: compressed_data_size=46727 data_size=252912, decompressed_data_size=252912 diff=0
14:07:39:Unit 01:- Digital signature verified
14:07:39:Unit 01:
14:07:39:Unit 01:Project: 5765 (Run 9, Clone 183, Gen 49)
14:07:39:Unit 01:
14:07:39:Unit 01:Assembly optimizations on if available.
14:07:39:Unit 01:Entering M.D.
14:07:42:Unit 02: Upload complete
14:07:42:Server responded WORK_ACK (400)
14:07:42:Cleaning up Unit 02
14:07:44:Unit 01:Tpr hash 01/wudata_01.tpr:  774819023 77467693 2331325175 2950683007 1097477875
14:07:44:Unit 01:
14:07:44:Unit 01:Calling fah_main args: 14 usage=100
14:07:44:Unit 01:
14:07:45:Unit 01:Working on Protein
14:07:46:Unit 01:Client config unavailable.
14:07:46:Unit 01:Starting GUI Server
14:08:34:Unit 01:Completed 1%
14:09:21:Unit 01:Completed 2%
14:10:08:Unit 01:Completed 3%
14:10:55:Unit 01:Completed 4%
14:11:42:Unit 01:Completed 5%
14:12:31:Unit 01:Completed 6%
14:13:20:Unit 01:Completed 7%
14:14:08:Unit 01:Completed 8%
14:14:57:Unit 01:Completed 9%
14:15:46:Unit 01:Completed 10%
14:16:34:Unit 01:Completed 11%
14:17:22:Unit 01:Completed 12%
14:18:10:Unit 01:Completed 13%
14:18:59:Unit 01:Completed 14%
14:19:47:Unit 01:Completed 15%
14:20:35:Unit 01:Completed 16%
14:21:22:Unit 01:Completed 17%
14:22:08:Unit 01:Completed 18%
14:22:55:Unit 01:Completed 19%
14:23:42:Unit 01:Completed 20%
14:24:29:Unit 01:Completed 21%
14:25:11:Unit 00:Completed 60000 out of 2000000 steps  (3%)
14:25:17:Unit 01:Completed 22%
14:26:04:Unit 01:Completed 23%
14:26:52:Unit 01:Completed 24%
14:27:40:Unit 01:Completed 25%
14:28:28:Unit 01:Completed 26%
14:29:16:Unit 01:Completed 27%
14:30:03:Unit 01:Completed 28%
14:30:50:Unit 01:Completed 29%
14:31:37:Unit 01:Completed 30%
14:32:25:Unit 01:Completed 31%
14:33:16:Unit 01:Completed 32%
14:34:05:Unit 01:Completed 33%
14:34:53:Unit 01:Completed 34%
14:35:41:Unit 01:Completed 35%
14:36:30:Unit 01:Completed 36%
14:37:19:Unit 01:Completed 37%
14:38:06:Unit 01:Completed 38%
14:38:56:Unit 01:Completed 39%
14:39:45:Unit 01:Completed 40%
14:40:33:Unit 01:Completed 41%
14:41:21:Unit 01:Completed 42%
14:42:11:Unit 01:Completed 43%
14:42:58:Unit 01:Completed 44%
14:43:46:Unit 01:Completed 45%
14:44:33:Unit 01:Completed 46%
14:45:25:Unit 01:Completed 47%
14:46:15:Unit 01:Completed 48%
14:47:03:Unit 01:Completed 49%
14:47:50:Unit 01:Completed 50%
14:48:37:Unit 01:Completed 51%
14:49:24:Unit 01:Completed 52%
14:50:11:Unit 01:Completed 53%
14:50:59:Unit 01:Completed 54%
14:51:46:Unit 01:Completed 55%
14:52:34:Unit 01:Completed 56%
14:52:55:Unit 00:Completed 80000 out of 2000000 steps  (4%)
Best regards,
Rafa.