Page 1 of 1

Project: 7008 (Run 0, Clone 83, Gen 36) - High TPF

Posted: Thu Nov 01, 2012 9:37 am
by Fahrenheit451
Have found a similiar thread here: viewtopic.php?f=19&t=22591 for a 7611 WU.
According to HFM my PC has folded former 7008 WU's with an average TPF of about 2:20 min. But for the current 7008 WU TPF has increased to 8:55 min which causes a heavy drop in PPD for this WU (from ~26-30K to 3.5K).
I have checked the taskmanager but the A4 core is using 99% of the resources. Heat is not a problem, the i7 2600 temp is max. @ 49°C /120°F (more info: System 2 in my signature).
I am using client V7.1.52.

Here is the system log:

Code: Select all

*********************** Log Started 2012-10-28T16:29:36Z ***********************
16:29:36:************************* Folding@home Client *************************
16:29:36:      Website: http://folding.stanford.edu/
16:29:36:    Copyright: (c) 2009-2012 Stanford University
16:29:36:       Author: Joseph Coffland <joseph@cauldrondevelopment.com>
16:29:36:         Args: --lifeline 6736 --command-port=36330
16:29:36:       Config: C:/ProgramData/FAHClient/config.xml
16:29:36:******************************** Build ********************************
16:29:36:      Version: 7.1.52
16:29:36:         Date: Mar 20 2012
16:29:36:         Time: 19:37:42
16:29:36:      SVN Rev: 3515
16:29:36:       Branch: fah/trunk/client
16:29:36:     Compiler: Intel(R) C++ MSVC 1500 mode 1200
16:29:36:      Options: /TP /nologo /EHa /Qdiag-disable:4297,4103,1786,279 /Ox -arch:SSE
16:29:36:               /QaxSSE2,SSE3,SSSE3,SSE4.1,SSE4.2 /Qopenmp /Qrestrict /MT
16:29:36:     Platform: win32 XP
16:29:36:         Bits: 32
16:29:36:         Mode: Release
16:29:36:******************************* System ********************************
16:29:36:          CPU: Intel(R) Core(TM) i7-2600 CPU @ 3.40GHz
16:29:36:       CPU ID: GenuineIntel Family 6 Model 42 Stepping 7
16:29:36:         CPUs: 8
16:29:36:       Memory: 7.98GiB
16:29:36:  Free Memory: 6.13GiB
16:29:36:      Threads: WINDOWS_THREADS
16:29:36:   On Battery: false
16:29:36:   UTC offset: 1
16:29:36:          PID: 6436
16:29:36:          CWD: C:/ProgramData/FAHClient
16:29:36:           OS: Windows 7 Ultimate
16:29:36:      OS Arch: AMD64
16:29:36:         GPUs: 1
16:29:36:        GPU 0: FERMI:1 GF114 [GeForce GTX 560 Ti]
16:29:36:         CUDA: 2.1
16:29:36:  CUDA Driver: 5000
16:29:36:Win32 Service: false
16:29:36:***********************************************************************
16:29:36:<config>
16:29:36:  <service-description v='Folding@home Client'/>
16:29:36:  <service-restart v='true'/>
16:29:36:  <service-restart-delay v='5000'/>
16:29:36:
16:29:36:  <!-- Client Control -->
16:29:36:  <cycle-rate v='4'/>
16:29:36:  <cycles v='-1'/>
16:29:36:  <data-directory v='.'/>
16:29:36:  <disable-project-lookup v='false'/>
16:29:36:  <exec-directory v='D:\\FAHClient_V7'/>
16:29:36:  <exit-when-done v='false'/>
16:29:36:  <threads v='4'/>
16:29:36:
16:29:36:  <!-- Configuration -->
16:29:36:  <config-rotate v='true'/>
16:29:36:  <config-rotate-dir v='configs'/>
16:29:36:  <config-rotate-max v='16'/>
16:29:36:
16:29:36:  <!-- Debugging -->
16:29:36:  <assignment-servers>
16:29:36:    assign3.stanford.edu:8080 assign4.stanford.edu:80
16:29:36:  </assignment-servers>
16:29:36:  <capture-directory v='capture'/>
16:29:36:  <capture-sockets v='false'/>
16:29:36:  <debug-sockets v='false'/>
16:29:36:  <exception-locations v='true'/>
16:29:36:  <gpu-assignment-servers>
16:29:36:    assign-GPU.stanford.edu:80 assign-GPU.stanford.edu:8080
16:29:36:  </gpu-assignment-servers>
16:29:36:  <stack-traces v='false'/>
16:29:36:
16:29:36:  <!-- Error Handling -->
16:29:36:  <max-slot-errors v='5'/>
16:29:36:  <max-unit-errors v='5'/>
16:29:36:
16:29:36:  <!-- FahCore Control -->
16:29:36:  <checkpoint v='15'/>
16:29:36:  <core-dir v='cores'/>
16:29:36:  <core-priority v='idle'/>
16:29:36:  <cpu-affinity v='false'/>
16:29:36:  <cpu-usage v='100'/>
16:29:36:  <no-assembly v='false'/>
16:29:36:
16:29:36:  <!-- Folding Slot Configuration -->
16:29:36:  <client-subtype v='STDCLI'/>
16:29:36:  <client-type v='normal'/>
16:29:36:  <cpu-species v='X86_PENTIUM_II'/>
16:29:36:  <cpu-type v='AMD64'/>
16:29:36:  <cpus v='-1'/>
16:29:36:  <cuda-index v='0'/>
16:29:36:  <gpu v='true'/>
16:29:36:  <gpu-usage v='100'/>
16:29:36:  <max-packet-size v='normal'/>
16:29:36:  <opencl-index v='0'/>
16:29:36:  <os-species v='UNKNOWN'/>
16:29:36:  <os-type v='WIN32'/>
16:29:36:  <project-key v='0'/>
16:29:36:  <smp v='true'/>
16:29:36:
16:29:36:  <!-- Logging -->
16:29:36:  <log v='log.txt'/>
16:29:36:  <log-color v='false'/>
16:29:36:  <log-crlf v='true'/>
16:29:36:  <log-date v='false'/>
16:29:36:  <log-date-periodically v='21600'/>
16:29:36:  <log-debug v='true'/>
16:29:36:  <log-domain v='false'/>
16:29:36:  <log-header v='true'/>
16:29:36:  <log-level v='true'/>
16:29:36:  <log-no-info-header v='true'/>
16:29:36:  <log-redirect v='false'/>
16:29:36:  <log-rotate v='true'/>
16:29:36:  <log-rotate-dir v='logs'/>
16:29:36:  <log-rotate-max v='16'/>
16:29:36:  <log-short-level v='false'/>
16:29:36:  <log-simple-domains v='true'/>
16:29:36:  <log-thread-id v='false'/>
16:29:36:  <log-thread-prefix v='true'/>
16:29:36:  <log-time v='true'/>
16:29:36:  <log-to-screen v='true'/>
16:29:36:  <log-truncate v='false'/>
16:29:36:  <verbosity v='5'/>
16:29:36:
16:29:36:  <!-- Network -->
16:29:36:  <proxy v=':8080'/>
16:29:36:  <proxy-enable v='false'/>
16:29:36:  <proxy-pass v=''/>
16:29:36:  <proxy-user v=''/>
16:29:36:
16:29:36:  <!-- Process Control -->
16:29:36:  <child v='false'/>
16:29:36:  <daemon v='false'/>
16:29:36:  <pid v='false'/>
16:29:36:  <pid-file v='Folding@home Client.pid'/>
16:29:36:  <respawn v='false'/>
16:29:36:  <service v='false'/>
16:29:36:
16:29:36:  <!-- Remote Command Server -->
16:29:36:  <command-address v='0.0.0.0'/>
16:29:36:  <command-allow v='127.0.0.1,192.168.2.0/24'/>
16:29:36:  <command-allow-no-pass v='127.0.0.1,192.168.2.0/24'/>
16:29:36:  <command-deny v='0.0.0.0/0'/>
16:29:36:  <command-deny-no-pass v='0.0.0.0/0'/>
16:29:36:  <command-port v='36330'/>
16:29:36:
16:29:36:  <!-- Slot Control -->
16:29:36:  <max-shutdown-wait v='60'/>
16:29:36:  <pause-on-battery v='false'/>
16:29:36:  <pause-on-start v='false'/>
16:29:36:
16:29:36:  <!-- User Information -->
16:29:36:  <machine-id v='0'/>
16:29:36:  <passkey v='********************************'/>
16:29:36:  <team v='0'/>
16:29:36:  <user v='superduper4711'/>
16:29:36:
16:29:36:  <!-- Work Unit Control -->
16:29:36:  <dump-after-deadline v='true'/>
16:29:36:  <max-queue v='16'/>
16:29:36:  <max-units v='0'/>
16:29:36:  <next-unit-percentage v='99'/>
16:29:36:
16:29:36:  <!-- Folding Slots -->
16:29:36:  <slot id='0' type='SMP'>
16:29:36:    <client-type v='advanced'/>
16:29:36:    <cpus v='-1'/>
16:29:36:    <max-packet-size v='big'/>
16:29:36:  </slot>
16:29:36:  <slot id='1' type='GPU'/>
16:29:36:</config>
and here the WU log file:

Code: Select all


23:53:51:WU02:FS00:0xa4:Completed 490000 out of 500000 steps  (98%)
23:55:23:WU02:FS00:0xa4:Completed 495000 out of 500000 steps  (99%)
23:55:24:WU00:FS00:Connecting to assign3.stanford.edu:8080
23:55:26:WU00:FS00:News: Welcome to Folding@Home
23:55:26:WU00:FS00:Assigned to work server 129.74.85.15
23:55:26:WU00:FS00:Requesting new work unit for slot 00: RUNNING smp:8 from 129.74.85.15
23:55:26:WU00:FS00:Connecting to 129.74.85.15:8080
23:55:26:WU00:FS00:Downloading 38.67KiB
23:55:27:WU00:FS00:Download complete
23:55:27:WU00:FS00:Received Unit: id:00 state:DOWNLOAD error:OK project:7008 run:0 clone:83 gen:36 core:0xa4 unit:0x000000640001329c4dfb91b65e0ce8be
23:57:03:WU02:FS00:0xa4:Completed 500000 out of 500000 steps  (100%)
23:57:03:WU02:FS00:0xa4:DynamicWrapper: Finished Work Unit: sleep=10000
23:57:13:WU02:FS00:0xa4:
23:57:13:WU02:FS00:0xa4:Finished Work Unit:
23:57:13:WU02:FS00:0xa4:- Reading up to 879432 from "02/wudata_01.trr": Read 879432
23:57:13:WU02:FS00:0xa4:trr file hash check passed.
23:57:13:WU02:FS00:0xa4:- Reading up to 964036 from "02/wudata_01.xtc": Read 964036
23:57:13:WU02:FS00:0xa4:xtc file hash check passed.
23:57:13:WU02:FS00:0xa4:edr file hash check passed.
23:57:13:WU02:FS00:0xa4:logfile size: 27488
23:57:13:WU02:FS00:0xa4:Leaving Run
23:57:18:WU02:FS00:0xa4:- Writing 1879928 bytes of core data to disk...
23:57:18:WU02:FS00:0xa4:Done: 1879416 -> 1810372 (compressed to 96.3 percent)
23:57:18:WU02:FS00:0xa4:  ... Done.
23:57:19:WU02:FS00:0xa4:- Shutting down core
23:57:19:WU02:FS00:0xa4:
23:57:19:WU02:FS00:0xa4:Folding@home Core Shutdown: FINISHED_UNIT
23:57:19:WU02:FS00:FahCore returned: FINISHED_UNIT (100 = 0x64)
23:57:19:WU02:FS00:Sending unit results: id:02 state:SEND error:OK project:8056 run:61 clone:5 gen:41 core:0xa4 unit:0x000000326652edcb506cf052313b60f8
23:57:19:WU02:FS00:Uploading 1.73MiB to 171.67.108.59
23:57:19:WU02:FS00:Connecting to 171.67.108.59:8080
23:57:19:WU00:FS00:Starting
23:57:19:WU00:FS00:Running FahCore: D:\FAHClient_V7/FAHCoreWrapper.exe C:/ProgramData/FAHClient/cores/www.stanford.edu/~pande/Win32/AMD64/Core_a4.fah/FahCore_a4.exe -dir 00 -suffix 01 -version 701 -lifeline 6436 -checkpoint 15 -np 8
23:57:19:WU00:FS00:Started FahCore on PID 5176
23:57:19:WU00:FS00:Core PID:4644
23:57:19:WU00:FS00:FahCore 0xa4 started
23:57:20:WU00:FS00:0xa4:
23:57:20:WU00:FS00:0xa4:*------------------------------*
23:57:20:WU00:FS00:0xa4:Folding@Home Gromacs GB Core
23:57:20:WU00:FS00:0xa4:Version 2.27 (Dec. 15, 2010)
23:57:20:WU00:FS00:0xa4:
23:57:20:WU00:FS00:0xa4:Preparing to commence simulation
23:57:20:WU00:FS00:0xa4:- Looking at optimizations...
23:57:20:WU00:FS00:0xa4:- Created dyn
23:57:20:WU00:FS00:0xa4:- Files status OK
23:57:20:WU00:FS00:0xa4:- Expanded 39086 -> 200816 (decompressed 513.7 percent)
23:57:20:WU00:FS00:0xa4:Called DecompressByteArray: compressed_data_size=39086 data_size=200816, decompressed_data_size=200816 diff=0
23:57:20:WU00:FS00:0xa4:- Digital signature verified
23:57:20:WU00:FS00:0xa4:
23:57:20:WU00:FS00:0xa4:Project: 7008 (Run 0, Clone 83, Gen 36)
23:57:20:WU00:FS00:0xa4:
23:57:20:WU00:FS00:0xa4:Assembly optimizations on if available.
23:57:20:WU00:FS00:0xa4:Entering M.D.
23:57:25:WU02:FS00:Upload 21.71%
23:57:25:WU00:FS00:0xa4:Mapping NT from 8 to 8 
23:57:25:WU00:FS00:0xa4:Completed 0 out of 10000000 steps  (0%)
23:57:31:WU02:FS00:Upload 43.43%
23:57:37:WU02:FS00:Upload 65.14%
23:57:43:WU02:FS00:Upload 86.86%
23:57:47:WU02:FS00:Upload complete
23:57:47:WU02:FS00:Server responded WORK_ACK (400)
23:57:47:WU02:FS00:Final credit estimate, 1426.00 points
23:57:47:WU02:FS00:Cleaning up
00:06:06:WU00:FS00:0xa4:Completed 100000 out of 10000000 steps  (1%)
00:14:48:WU00:FS00:0xa4:Completed 200000 out of 10000000 steps  (2%)
00:23:40:WU00:FS00:0xa4:Completed 300000 out of 10000000 steps  (3%)
00:32:33:WU00:FS00:0xa4:Completed 400000 out of 10000000 steps  (4%)
00:40:54:WU00:FS00:0xa4:Completed 500000 out of 10000000 steps  (5%)
00:49:21:WU00:FS00:0xa4:Completed 600000 out of 10000000 steps  (6%)
00:57:41:WU00:FS00:0xa4:Completed 700000 out of 10000000 steps  (7%)
01:05:30:WU00:FS00:0xa4:Completed 800000 out of 10000000 steps  (8%)
01:13:21:WU00:FS00:0xa4:Completed 900000 out of 10000000 steps  (9%)
01:21:10:WU00:FS00:0xa4:Completed 1000000 out of 10000000 steps  (10%)
01:29:14:WU00:FS00:0xa4:Completed 1100000 out of 10000000 steps  (11%)
01:38:28:WU00:FS00:0xa4:Completed 1200000 out of 10000000 steps  (12%)
01:47:48:WU00:FS00:0xa4:Completed 1300000 out of 10000000 steps  (13%)
01:57:08:WU00:FS00:0xa4:Completed 1400000 out of 10000000 steps  (14%)
02:06:29:WU00:FS00:0xa4:Completed 1500000 out of 10000000 steps  (15%)
02:15:11:WU00:FS00:0xa4:Completed 1600000 out of 10000000 steps  (16%)
02:24:42:WU00:FS00:0xa4:Completed 1700000 out of 10000000 steps  (17%)
02:34:12:WU00:FS00:0xa4:Completed 1800000 out of 10000000 steps  (18%)
02:42:28:WU00:FS00:0xa4:Completed 1900000 out of 10000000 steps  (19%)
02:52:01:WU00:FS00:0xa4:Completed 2000000 out of 10000000 steps  (20%)
03:01:31:WU00:FS00:0xa4:Completed 2100000 out of 10000000 steps  (21%)
03:10:31:WU00:FS00:0xa4:Completed 2200000 out of 10000000 steps  (22%)
03:19:29:WU00:FS00:0xa4:Completed 2300000 out of 10000000 steps  (23%)
03:28:51:WU00:FS00:0xa4:Completed 2400000 out of 10000000 steps  (24%)
03:37:12:WU00:FS00:0xa4:Completed 2500000 out of 10000000 steps  (25%)
03:46:09:WU00:FS00:0xa4:Completed 2600000 out of 10000000 steps  (26%)
03:54:31:WU00:FS00:0xa4:Completed 2700000 out of 10000000 steps  (27%)
04:02:44:WU00:FS00:0xa4:Completed 2800000 out of 10000000 steps  (28%)
04:12:16:WU00:FS00:0xa4:Completed 2900000 out of 10000000 steps  (29%)
04:21:45:WU00:FS00:0xa4:Completed 3000000 out of 10000000 steps  (30%)
04:30:08:WU00:FS00:0xa4:Completed 3100000 out of 10000000 steps  (31%)
******************************** Date: 01/11/12 ********************************
04:38:50:WU00:FS00:0xa4:Completed 3200000 out of 10000000 steps  (32%)
04:47:30:WU00:FS00:0xa4:Completed 3300000 out of 10000000 steps  (33%)
04:55:57:WU00:FS00:0xa4:Completed 3400000 out of 10000000 steps  (34%)
05:04:27:WU00:FS00:0xa4:Completed 3500000 out of 10000000 steps  (35%)
05:13:49:WU00:FS00:0xa4:Completed 3600000 out of 10000000 steps  (36%)
05:23:09:WU00:FS00:0xa4:Completed 3700000 out of 10000000 steps  (37%)
05:32:07:WU00:FS00:0xa4:Completed 3800000 out of 10000000 steps  (38%)
05:40:37:WU00:FS00:0xa4:Completed 3900000 out of 10000000 steps  (39%)
05:49:59:WU00:FS00:0xa4:Completed 4000000 out of 10000000 steps  (40%)
05:58:27:WU00:FS00:0xa4:Completed 4100000 out of 10000000 steps  (41%)
06:06:41:WU00:FS00:0xa4:Completed 4200000 out of 10000000 steps  (42%)
06:14:55:WU00:FS00:0xa4:Completed 4300000 out of 10000000 steps  (43%)
06:24:27:WU00:FS00:0xa4:Completed 4400000 out of 10000000 steps  (44%)
06:33:29:WU00:FS00:0xa4:Completed 4500000 out of 10000000 steps  (45%)
06:41:45:WU00:FS00:0xa4:Completed 4600000 out of 10000000 steps  (46%)
06:50:00:WU00:FS00:0xa4:Completed 4700000 out of 10000000 steps  (47%)
06:58:14:WU00:FS00:0xa4:Completed 4800000 out of 10000000 steps  (48%)
07:07:11:WU00:FS00:0xa4:Completed 4900000 out of 10000000 steps  (49%)
07:15:49:WU00:FS00:0xa4:Completed 5000000 out of 10000000 steps  (50%)
07:24:47:WU00:FS00:0xa4:Completed 5100000 out of 10000000 steps  (51%)
07:33:11:WU00:FS00:0xa4:Completed 5200000 out of 10000000 steps  (52%)
07:41:38:WU00:FS00:0xa4:Completed 5300000 out of 10000000 steps  (53%)
07:50:05:WU00:FS00:0xa4:Completed 5400000 out of 10000000 steps  (54%)
07:59:28:WU00:FS00:0xa4:Completed 5500000 out of 10000000 steps  (55%)
08:08:23:WU00:FS00:0xa4:Completed 5600000 out of 10000000 steps  (56%)
08:17:07:WU00:FS00:0xa4:Completed 5700000 out of 10000000 steps  (57%)
08:25:28:WU00:FS00:0xa4:Completed 5800000 out of 10000000 steps  (58%)
08:33:49:WU00:FS00:0xa4:Completed 5900000 out of 10000000 steps  (59%)

Here are the numbers from the HFM Benchmark viewer for different clients: CPU Core 1 and CPU Core 2 are from my system 1 (C2D@3Ghz). The values must be a bit older because last I run them as single cores was with v6 client. Now the system 1 is running on v7 in SMP mode. FAH Box One Slot 00 is running the reported WU, FAH Box One Slot 01 values are from a former v7 client on the same machine (System 2)
Project ID: 7008
Core: GRO-A4
Credit: 600
Frames: 100


Name: CPU Core 1
Path: REMOVED
Number of Frames Observed: 99

Min. Time / Frame : 00:12:31 - 2.126,6 PPD
Avg. Time / Frame : 00:13:05 - 1.990,0 PPD


Name: CPU Core 2
Path: REMOVED
Number of Frames Observed: 100

Min. Time / Frame : 00:12:22 - 2.165,4 PPD
Avg. Time / Frame : 00:12:42 - 2.080,7 PPD


Name: FAH Box One Slot 00
Path: 192.168.2.106-36330
Number of Frames Observed: 300

Min. Time / Frame : 00:01:58 - 34.144,9 PPD
Avg. Time / Frame : 00:03:42 - 13.231,8 PPD
Cur. Time / Frame : 00:09:16 - 3.377,5 PPD
R3F. Time / Frame : 00:09:30 - 3.278,5 PPD
All Time / Frame : 00:08:47 - 3.600,0 PPD
Eff. Time / Frame : 00:08:55 - 3.536,1 PPD


Name: FAH Box One Slot 01
Path: 192.168.2.106-36330
Number of Frames Observed: 200

Min. Time / Frame : 00:02:08 - 30.222,8 PPD
Avg. Time / Frame : 00:02:20 - 26.421,4 PPD
I will restart the system now and check if it will return to the expected lower TPF.

UPDATE: Restart doesn't help. TPF stays on 8:30min and more :(