Same WU assigned twice p2685-r11-c21-g0

Moderators: Site Moderators, FAHC Science Team

Post Reply
Mactin
Posts: 223
Joined: Sun Dec 02, 2007 1:08 pm
Location: Outremont, Montréal, Québec

Same WU assigned twice p2685-r11-c21-g0

Post by Mactin »

I was assigned p2685-r11-c21-g0 twice in a row !!!
I was credited 74094 points for the first time.

Code: Select all

[07:58:41] Completed 247500 out of 250000 steps  (99%)
[08:25:14] Completed 250000 out of 250000 steps  (100%)
[08:25:27] DynamicWrapper: Finished Work Unit: sleep=10000
[08:25:37] 
[08:25:37] Finished Work Unit:
[08:25:37] - Reading up to 56557056 from "work/wudata_01.trr": Read 56557056
[08:25:38] trr file hash check passed.
[08:25:38] - Reading up to 50443444 from "work/wudata_01.xtc": Read 50443444
[08:25:38] xtc file hash check passed.
[08:25:38] edr file hash check passed.
[08:25:38] logfile size: 206770
[08:25:38] Leaving Run
[08:25:38] - Writing 107375210 bytes of core data to disk...
[08:25:39]   ... Done.
[08:26:06] - Shutting down core
[08:26:06] 
[08:26:06] Folding@home Core Shutdown: FINISHED_UNIT
[08:26:14] CoreStatus = 64 (100)
[08:26:14] Unit 1 finished with 62 percent of time to deadline remaining.
[08:26:14] Updated performance fraction: 0.620569
[08:26:14] Sending work to server
[08:26:14] Project: 2685 (Run 11, Clone 21, Gen 0)


[08:26:14] + Attempting to send results [July 22 08:26:14 UTC]
[08:26:14] - Reading file work/wuresults_01.dat from core
[08:26:14]   (Read 107375210 bytes from disk)
[08:26:14] Connecting to http://171.67.108.22:8080/
[09:01:53] - Couldn't send HTTP request to server
[09:01:53] + Could not connect to Work Server (results)
[09:01:53]     (171.67.108.22:8080)
[09:01:53] + Retrying using alternative port
[09:01:53] Connecting to http://171.67.108.22:80/
[09:18:37] Posted data.
[09:18:37] Initial: 0000; + Results successfully sent
[09:18:42] Thank you for your contribution to Folding@Home.
[09:18:42] + Number of Units Completed: 317

[09:18:49] Trying to send all finished work units
[09:18:49] + No unsent completed units remaining.
[09:18:49] - Preparing to get new work unit...
[09:18:49] Cleaning up work directory
[09:18:49] + Attempting to get work packet
[09:18:49] Passkey found
[09:18:49] - Will indicate memory of 7500 MB
[09:18:49] - Detect CPU. Vendor: GenuineIntel, Family: 6, Model: 12, Stepping: 2
[09:18:49] - Connecting to assignment server
[09:18:49] Connecting to http://assign.stanford.edu:8080/
[09:18:49] Posted data.
[09:18:49] Initial: 43AB; - Successful: assigned to (171.67.108.22).
[09:18:49] + News From Folding@Home: Welcome to Folding@Home
[09:18:50] Loaded queue successfully.
[09:18:50] Connecting to http://171.67.108.22:8080/
[09:18:50] Posted data.
[09:18:50] Initial: 0000; - Error: Bad packet type from server, expected work assignment
[09:18:51] - Attempt #1  to get work failed, and no other work to do.
Waiting before retry.
[09:19:06] + Attempting to get work packet
[09:19:06] Passkey found
[09:19:06] - Will indicate memory of 7500 MB
[09:19:06] - Connecting to assignment server
[09:19:06] Connecting to http://assign.stanford.edu:8080/
[09:19:06] Posted data.
[09:19:06] Initial: 43AB; - Successful: assigned to (171.67.108.22).
[09:19:06] + News From Folding@Home: Welcome to Folding@Home
[09:19:06] Loaded queue successfully.
[09:19:06] Connecting to http://171.67.108.22:8080/
[09:19:07] Posted data.
[09:19:07] Initial: 0000; - Error: Bad packet type from server, expected work assignment
[09:19:07] - Attempt #2  to get work failed, and no other work to do.
Waiting before retry.
[09:19:18] + Attempting to get work packet
[09:19:18] Passkey found
[09:19:18] - Will indicate memory of 7500 MB
[09:19:18] - Connecting to assignment server
[09:19:18] Connecting to http://assign.stanford.edu:8080/
[09:19:19] Posted data.
[09:19:19] Initial: 43AB; - Successful: assigned to (171.67.108.22).
[09:19:19] + News From Folding@Home: Welcome to Folding@Home
[09:19:19] Loaded queue successfully.
[09:19:19] Connecting to http://171.67.108.22:8080/
[09:19:19] Posted data.
[09:19:19] Initial: 0000; - Error: Bad packet type from server, expected work assignment
[09:19:20] - Attempt #3  to get work failed, and no other work to do.
Waiting before retry.
[09:19:42] + Attempting to get work packet
[09:19:42] Passkey found
[09:19:42] - Will indicate memory of 7500 MB
[09:19:42] - Connecting to assignment server
[09:19:42] Connecting to http://assign.stanford.edu:8080/
[09:19:42] Posted data.
[09:19:42] Initial: 43AB; - Successful: assigned to (171.67.108.22).
[09:19:42] + News From Folding@Home: Welcome to Folding@Home
[09:19:43] Loaded queue successfully.
[09:19:43] Connecting to http://171.67.108.22:8080/
[09:19:51] Posted data.
[09:19:51] Initial: 0000; - Receiving payload (expected size: 21479095)
[09:20:23] - Downloaded at ~655 kB/s
[09:20:23] - Averaged speed for that direction ~747 kB/s
[09:20:23] + Received work.
[09:20:23] Trying to send all finished work units
[09:20:23] + No unsent completed units remaining.
[09:20:23] + Closed connections
[09:20:23] 
[09:20:23] + Processing work unit
[09:20:23] Core required: FahCore_a3.exe
[09:20:23] Core found.
[09:20:23] Working on queue slot 02 [July 22 09:20:23 UTC]
[09:20:23] + Working ...
[09:20:23] - Calling '.\FahCore_a3.exe -dir work/ -nice 19 -suffix 02 -np 12 -checkpoint 10 -forceasm -verbose -lifeline 5340 -version 629'

[09:20:23] 
[09:20:23] *------------------------------*
[09:20:23] Folding@Home Gromacs SMP Core
[09:20:23] Version 2.22 (Mar 12, 2010)
[09:20:23] 
[09:20:23] Preparing to commence simulation
[09:20:23] - Assembly optimizations manually forced on.
[09:20:23] - Not checking prior termination.
[09:20:28] - Expanded 21478583 -> 33038409 (decompressed 153.8 percent)
[09:20:28] Called DecompressByteArray: compressed_data_size=21478583 data_size=33038409, decompressed_data_size=33038409 diff=0
[09:20:29] - Digital signature verified
[09:20:29] 
[09:20:29] Project: 2685 (Run 11, Clone 21, Gen 0)
[09:20:29] 
[09:20:29] Assembly optimizations on if available.
[09:20:29] Entering M.D.
[09:20:38] Completed 0 out of 250000 steps  (0%)
[09:47:18] Completed 2500 out of 250000 steps  (1%)
I have a backup of the first time folded at 81%, I am tempted to stop it now and start the backup !
Image
bruce
Posts: 20824
Joined: Thu Nov 29, 2007 10:13 pm
Location: So. Cal.

Re: Same WU assigned twice p2685-r11-c21-g0

Post by bruce »

Hi Martin_i7-980x (team 96377),
Your WU (P2685 R11 C21 G0) was added to the stats database on 2010-07-22 03:15:54 PDT for 74093.7 points of credit.
That's 10:15:54 UTC.

According to your log, the WU was uploaded at some time between [July 22 08:26:14 UTC] and [09:18:37 UTC]. Also from your log, the same WU was downloaded between [09:19:51 UTC] and [July 22 09:20:23 UTC] and presumably is still processing.

Maybe there's a clock synchronization problem somewhere, but something just doesn't make sense. I don't know how you can get credit for a WU an hour before you uploaded it.

You do mention backups. Was a backup restored somewhere during those times causing the processing of this WU to happen in some unusual order? Do you accept the client's natural flow of Download -- Compute -- Upload or have you changed something somewhere?

Not all of the important events from your log are shown. (Specifically what time was the WU originally downloaded.) Were there other events that I need to know about?
Mactin
Posts: 223
Joined: Sun Dec 02, 2007 1:08 pm
Location: Outremont, Montréal, Québec

Re: Same WU assigned twice p2685-r11-c21-g0

Post by Mactin »

Bruce, thank you for your quick reply.

Every thing makes sense.

The work unit was downloaded twice.
The first time on July 20 at 01:47:57 UTC
It the completed (like you said between July 22 08:26:14 UTC and 09:18:37 UTC
It was credited like you and I noted previously (an hour later).

THEN IT DOWNLOADED AGAIN on July 22 09:20:23 UTC

There is no synchronisation problem
The problem is that the same WU was re-issued a second time to me.

The complete log:

Code: Select all

--- Opening Log file [July 20 01:47:18 UTC] 


# Windows SMP Console Edition #################################################
###############################################################################

                       Folding@Home Client Version 6.29

                          http://folding.stanford.edu

###############################################################################
###############################################################################

Launch directory: C:\Program Files (x86)\folding\m1 SMP
Executable: C:\Program Files (x86)\folding\m1 SMP\Folding@home-Win32-x86.exe
Arguments: -smp 12 -verbosity 9 -forceasm -bigadv -config 

[01:47:18] - Ask before connecting: No
[01:47:18] - User name: Martin_i7-980x (Team 96377)
[01:47:18] - User ID: 6C6A12517E046D46
[01:47:18] - Machine ID: 1
[01:47:18] 
[01:47:18] Configuring Folding@Home...


[01:47:21] - Ask before connecting: No
[01:47:21] - User name: Martin_i7-980x (Team 96377)
[01:47:21] - User ID: 6C6A12517E046D46
[01:47:21] - Machine ID: 1
[01:47:21] 
[01:47:21] Work directory not found. Creating...
[01:47:21] Could not open work queue, generating new queue...
[01:47:21] - Preparing to get new work unit...
[01:47:21] - Autosending finished units... [July 20 01:47:21 UTC]
[01:47:21] Cleaning up work directory
[01:47:21] Trying to send all finished work units
[01:47:21] + Attempting to get work packet
[01:47:21] + No unsent completed units remaining.
[01:47:21] Passkey found
[01:47:21] - Autosend completed
[01:47:21] - Will indicate memory of 7500 MB
[01:47:21] - Detect CPU. Vendor: GenuineIntel, Family: 6, Model: 12, Stepping: 2
[01:47:21] - Connecting to assignment server
[01:47:21] Connecting to http://assign.stanford.edu:8080/
[01:47:22] Posted data.
[01:47:22] Initial: 43AB; - Successful: assigned to (171.67.108.22).
[01:47:22] + News From Folding@Home: Welcome to Folding@Home
[01:47:22] Loaded queue successfully.
[01:47:22] Connecting to http://171.67.108.22:8080/
[01:47:32] Posted data.
[01:47:32] Initial: 0000; - Receiving payload (expected size: 21479095)
[01:47:57] - Downloaded at ~839 kB/s
[01:47:57] - Averaged speed for that direction ~839 kB/s
[01:47:57] + Received work.
[01:47:57] + Closed connections
[01:47:57] 
[01:47:57] + Processing work unit
[01:47:57] Core required: FahCore_a3.exe
[01:47:57] Core found.
[01:47:57] Working on queue slot 01 [July 20 01:47:57 UTC]
[01:47:57] + Working ...
[01:47:57] - Calling '.\FahCore_a3.exe -dir work/ -nice 19 -suffix 01 -np 12 -checkpoint 10 -forceasm -verbose -lifeline 5684 -version 629'

[01:47:57] 
[01:47:57] *------------------------------*
[01:47:57] Folding@Home Gromacs SMP Core
[01:47:57] Version 2.22 (Mar 12, 2010)
[01:47:57] 
[01:47:57] Preparing to commence simulation
[01:47:57] - Assembly optimizations manually forced on.
[01:47:57] - Not checking prior termination.
[01:48:02] - Expanded 21478583 -> 33038409 (decompressed 153.8 percent)
[01:48:02] Called DecompressByteArray: compressed_data_size=21478583 data_size=33038409, decompressed_data_size=33038409 diff=0
[01:48:02] - Digital signature verified
[01:48:02] 
[01:48:02] Project: 2685 (Run 11, Clone 21, Gen 0)
[01:48:02] 
[01:48:03] Assembly optimizations on if available.
[01:48:03] Entering M.D.
[01:48:12] Completed 0 out of 250000 steps  (0%)
[02:17:26] Completed 2500 out of 250000 steps  (1%)
[02:46:01] Completed 5000 out of 250000 steps  (2%)
[03:14:44] Completed 7500 out of 250000 steps  (3%)
[03:43:28] Completed 10000 out of 250000 steps  (4%)
[04:12:24] Completed 12500 out of 250000 steps  (5%)
[04:41:50] Completed 15000 out of 250000 steps  (6%)
[05:10:55] Completed 17500 out of 250000 steps  (7%)
[05:40:02] Completed 20000 out of 250000 steps  (8%)
[06:09:24] Completed 22500 out of 250000 steps  (9%)
[06:38:38] Completed 25000 out of 250000 steps  (10%)
[07:07:48] Completed 27500 out of 250000 steps  (11%)
[07:37:01] Completed 30000 out of 250000 steps  (12%)
[07:47:21] - Autosending finished units... [July 20 07:47:21 UTC]
[07:47:21] Trying to send all finished work units
[07:47:21] + No unsent completed units remaining.
[07:47:21] - Autosend completed
[08:06:13] Completed 32500 out of 250000 steps  (13%)
[08:35:28] Completed 35000 out of 250000 steps  (14%)
[09:04:42] Completed 37500 out of 250000 steps  (15%)
[09:33:57] Completed 40000 out of 250000 steps  (16%)
[10:03:10] Completed 42500 out of 250000 steps  (17%)
[10:32:24] Completed 45000 out of 250000 steps  (18%)
[11:01:37] Completed 47500 out of 250000 steps  (19%)
[11:30:52] Completed 50000 out of 250000 steps  (20%)
[12:00:04] Completed 52500 out of 250000 steps  (21%)
[12:29:18] Completed 55000 out of 250000 steps  (22%)
[12:58:31] Completed 57500 out of 250000 steps  (23%)
[13:27:43] Completed 60000 out of 250000 steps  (24%)
[13:47:21] - Autosending finished units... [July 20 13:47:21 UTC]
[13:47:21] Trying to send all finished work units
[13:47:21] + No unsent completed units remaining.
[13:47:21] - Autosend completed
[13:57:16] Completed 62500 out of 250000 steps  (25%)
[14:26:28] Completed 65000 out of 250000 steps  (26%)
[14:55:40] Completed 67500 out of 250000 steps  (27%)
[15:24:52] Completed 70000 out of 250000 steps  (28%)
[15:54:06] Completed 72500 out of 250000 steps  (29%)
[16:23:18] Completed 75000 out of 250000 steps  (30%)
[16:52:30] Completed 77500 out of 250000 steps  (31%)
[17:21:45] Completed 80000 out of 250000 steps  (32%)
[17:50:56] Completed 82500 out of 250000 steps  (33%)
[18:20:09] Completed 85000 out of 250000 steps  (34%)
[18:49:26] Completed 87500 out of 250000 steps  (35%)
[19:18:42] Completed 90000 out of 250000 steps  (36%)
[19:47:21] - Autosending finished units... [July 20 19:47:21 UTC]
[19:47:21] Trying to send all finished work units
[19:47:21] + No unsent completed units remaining.
[19:47:21] - Autosend completed
[19:47:51] Completed 92500 out of 250000 steps  (37%)
[20:17:02] Completed 95000 out of 250000 steps  (38%)
[20:46:18] Completed 97500 out of 250000 steps  (39%)
[21:15:32] Completed 100000 out of 250000 steps  (40%)
[21:44:44] Completed 102500 out of 250000 steps  (41%)
[22:13:56] Completed 105000 out of 250000 steps  (42%)
[22:43:28] Completed 107500 out of 250000 steps  (43%)
[23:12:43] Completed 110000 out of 250000 steps  (44%)
[23:41:58] Completed 112500 out of 250000 steps  (45%)
[00:11:11] Completed 115000 out of 250000 steps  (46%)
[00:40:25] Completed 117500 out of 250000 steps  (47%)
[01:09:36] Completed 120000 out of 250000 steps  (48%)
[01:38:49] Completed 122500 out of 250000 steps  (49%)
[01:47:21] - Autosending finished units... [July 21 01:47:21 UTC]
[01:47:21] Trying to send all finished work units
[01:47:21] + No unsent completed units remaining.
[01:47:21] - Autosend completed
[02:07:59] Completed 125000 out of 250000 steps  (50%)


--- Opening Log file [July 21 10:30:52 UTC] 


# Windows SMP Console Edition #################################################
###############################################################################

                       Folding@Home Client Version 6.29

                          http://folding.stanford.edu

###############################################################################
###############################################################################

Launch directory: C:\Program Files (x86)\folding\m1 SMP
Executable: C:\Program Files (x86)\folding\m1 SMP\Folding@home-Win32-x86.exe
Arguments: -smp 12 -verbosity 9 -forceasm -bigadv -config 

[10:30:52] - Ask before connecting: No
[10:30:52] - User name: Martin_i7-980x (Team 96377)
[10:30:52] - User ID: 6C6A12517E046D46
[10:30:52] - Machine ID: 1
[10:30:52] 
[10:30:52] Configuring Folding@Home...


[10:31:07] - Ask before connecting: No
[10:31:07] - User name: Martin_i7-980x (Team 96377)
[10:31:07] - User ID: 6C6A12517E046D46
[10:31:07] - Machine ID: 1
[10:31:07] 
[10:31:07] Loaded queue successfully.
[10:31:07] 
[10:31:07] - Autosending finished units... [July 21 10:31:07 UTC]
[10:31:07] + Processing work unit
[10:31:07] Trying to send all finished work units
[10:31:07] Core required: FahCore_a3.exe
[10:31:07] + No unsent completed units remaining.
[10:31:07] Core found.
[10:31:07] - Autosend completed
[10:31:07] Working on queue slot 01 [July 21 10:31:07 UTC]
[10:31:07] + Working ...
[10:31:07] - Calling '.\FahCore_a3.exe -dir work/ -nice 19 -suffix 01 -np 12 -checkpoint 10 -forceasm -verbose -lifeline 5340 -version 629'

[10:31:07] 
[10:31:07] *------------------------------*
[10:31:07] Folding@Home Gromacs SMP Core
[10:31:07] Version 2.22 (Mar 12, 2010)
[10:31:07] 
[10:31:07] Preparing to commence simulation
[10:31:07] - Ensuring status. Please wait.
[10:31:17] - Assembly optimizations manually forced on.
[10:31:17] - Not checking prior termination.
[10:31:22] - Expanded 21478583 -> 33038409 (decompressed 153.8 percent)
[10:31:22] Called DecompressByteArray: compressed_data_size=21478583 data_size=33038409, decompressed_data_size=33038409 diff=0
[10:31:22] - Digital signature verified
[10:31:22] 
[10:31:22] Project: 2685 (Run 11, Clone 21, Gen 0)
[10:31:22] 
[10:31:22] Assembly optimizations on if available.
[10:31:22] Entering M.D.
[10:31:28] Using Gromacs checkpoints
[10:31:35] Resuming from checkpoint
[10:31:35] Verified work/wudata_01.log
[10:31:36] Verified work/wudata_01.trr
[10:31:36] Verified work/wudata_01.xtc
[10:31:36] Verified work/wudata_01.edr
[10:31:37] Completed 126730 out of 250000 steps  (50%)
[10:39:49] Completed 127500 out of 250000 steps  (51%)
[11:06:42] Completed 130000 out of 250000 steps  (52%)
[11:33:17] Completed 132500 out of 250000 steps  (53%)
[11:59:48] Completed 135000 out of 250000 steps  (54%)
[12:26:22] Completed 137500 out of 250000 steps  (55%)
[12:53:26] Completed 140000 out of 250000 steps  (56%)
[13:20:01] Completed 142500 out of 250000 steps  (57%)
[13:46:39] Completed 145000 out of 250000 steps  (58%)
[14:13:14] Completed 147500 out of 250000 steps  (59%)
[14:39:45] Completed 150000 out of 250000 steps  (60%)
[15:06:19] Completed 152500 out of 250000 steps  (61%)
[15:32:53] Completed 155000 out of 250000 steps  (62%)
[15:59:25] Completed 157500 out of 250000 steps  (63%)
[16:25:58] Completed 160000 out of 250000 steps  (64%)
[16:31:07] - Autosending finished units... [July 21 16:31:07 UTC]
[16:31:07] Trying to send all finished work units
[16:31:07] + No unsent completed units remaining.
[16:31:07] - Autosend completed
[16:52:31] Completed 162500 out of 250000 steps  (65%)
[17:21:52] Completed 165000 out of 250000 steps  (66%)
[17:48:24] Completed 167500 out of 250000 steps  (67%)
[18:15:05] Completed 170000 out of 250000 steps  (68%)
[18:41:41] Completed 172500 out of 250000 steps  (69%)
[19:08:16] Completed 175000 out of 250000 steps  (70%)
[19:34:50] Completed 177500 out of 250000 steps  (71%)
[20:01:20] Completed 180000 out of 250000 steps  (72%)
[20:27:57] Completed 182500 out of 250000 steps  (73%)
[20:54:30] Completed 185000 out of 250000 steps  (74%)
[21:21:02] Completed 187500 out of 250000 steps  (75%)
[21:47:35] Completed 190000 out of 250000 steps  (76%)
[22:14:08] Completed 192500 out of 250000 steps  (77%)
[22:31:07] - Autosending finished units... [July 21 22:31:07 UTC]
[22:31:07] Trying to send all finished work units
[22:31:07] + No unsent completed units remaining.
[22:31:07] - Autosend completed
[22:40:44] Completed 195000 out of 250000 steps  (78%)
[23:07:17] Completed 197500 out of 250000 steps  (79%)
[23:33:54] Completed 200000 out of 250000 steps  (80%)
[00:00:24] Completed 202500 out of 250000 steps  (81%)
[00:26:58] Completed 205000 out of 250000 steps  (82%)
[00:53:32] Completed 207500 out of 250000 steps  (83%)
[01:20:02] Completed 210000 out of 250000 steps  (84%)
[01:46:39] Completed 212500 out of 250000 steps  (85%)
[02:13:11] Completed 215000 out of 250000 steps  (86%)
[02:39:41] Completed 217500 out of 250000 steps  (87%)
[03:06:13] Completed 220000 out of 250000 steps  (88%)
[03:32:46] Completed 222500 out of 250000 steps  (89%)
[03:59:15] Completed 225000 out of 250000 steps  (90%)
[04:25:53] Completed 227500 out of 250000 steps  (91%)
[04:31:07] - Autosending finished units... [July 22 04:31:07 UTC]
[04:31:07] Trying to send all finished work units
[04:31:07] + No unsent completed units remaining.
[04:31:07] - Autosend completed
[04:52:50] Completed 230000 out of 250000 steps  (92%)
[05:19:21] Completed 232500 out of 250000 steps  (93%)
[05:45:54] Completed 235000 out of 250000 steps  (94%)
[06:12:36] Completed 237500 out of 250000 steps  (95%)
[06:39:08] Completed 240000 out of 250000 steps  (96%)
[07:05:40] Completed 242500 out of 250000 steps  (97%)
[07:32:11] Completed 245000 out of 250000 steps  (98%)
[07:58:41] Completed 247500 out of 250000 steps  (99%)
[08:25:14] Completed 250000 out of 250000 steps  (100%)
[08:25:27] DynamicWrapper: Finished Work Unit: sleep=10000
[08:25:37] 
[08:25:37] Finished Work Unit:
[08:25:37] - Reading up to 56557056 from "work/wudata_01.trr": Read 56557056
[08:25:38] trr file hash check passed.
[08:25:38] - Reading up to 50443444 from "work/wudata_01.xtc": Read 50443444
[08:25:38] xtc file hash check passed.
[08:25:38] edr file hash check passed.
[08:25:38] logfile size: 206770
[08:25:38] Leaving Run
[08:25:38] - Writing 107375210 bytes of core data to disk...
[08:25:39]   ... Done.
[08:26:06] - Shutting down core
[08:26:06] 
[08:26:06] Folding@home Core Shutdown: FINISHED_UNIT
[08:26:14] CoreStatus = 64 (100)
[08:26:14] Unit 1 finished with 62 percent of time to deadline remaining.
[08:26:14] Updated performance fraction: 0.620569
[08:26:14] Sending work to server
[08:26:14] Project: 2685 (Run 11, Clone 21, Gen 0)


[08:26:14] + Attempting to send results [July 22 08:26:14 UTC]
[08:26:14] - Reading file work/wuresults_01.dat from core
[08:26:14]   (Read 107375210 bytes from disk)
[08:26:14] Connecting to http://171.67.108.22:8080/
[09:01:53] - Couldn't send HTTP request to server
[09:01:53] + Could not connect to Work Server (results)
[09:01:53]     (171.67.108.22:8080)
[09:01:53] + Retrying using alternative port
[09:01:53] Connecting to http://171.67.108.22:80/
[09:18:37] Posted data.
[09:18:37] Initial: 0000; + Results successfully sent
[09:18:42] Thank you for your contribution to Folding@Home.
[09:18:42] + Number of Units Completed: 317

[09:18:49] Trying to send all finished work units
[09:18:49] + No unsent completed units remaining.
[09:18:49] - Preparing to get new work unit...
[09:18:49] Cleaning up work directory
[09:18:49] + Attempting to get work packet
[09:18:49] Passkey found
[09:18:49] - Will indicate memory of 7500 MB
[09:18:49] - Detect CPU. Vendor: GenuineIntel, Family: 6, Model: 12, Stepping: 2
[09:18:49] - Connecting to assignment server
[09:18:49] Connecting to http://assign.stanford.edu:8080/
[09:18:49] Posted data.
[09:18:49] Initial: 43AB; - Successful: assigned to (171.67.108.22).
[09:18:49] + News From Folding@Home: Welcome to Folding@Home
[09:18:50] Loaded queue successfully.
[09:18:50] Connecting to http://171.67.108.22:8080/
[09:18:50] Posted data.
[09:18:50] Initial: 0000; - Error: Bad packet type from server, expected work assignment
[09:18:51] - Attempt #1  to get work failed, and no other work to do.
Waiting before retry.
[09:19:06] + Attempting to get work packet
[09:19:06] Passkey found
[09:19:06] - Will indicate memory of 7500 MB
[09:19:06] - Connecting to assignment server
[09:19:06] Connecting to http://assign.stanford.edu:8080/
[09:19:06] Posted data.
[09:19:06] Initial: 43AB; - Successful: assigned to (171.67.108.22).
[09:19:06] + News From Folding@Home: Welcome to Folding@Home
[09:19:06] Loaded queue successfully.
[09:19:06] Connecting to http://171.67.108.22:8080/
[09:19:07] Posted data.
[09:19:07] Initial: 0000; - Error: Bad packet type from server, expected work assignment
[09:19:07] - Attempt #2  to get work failed, and no other work to do.
Waiting before retry.
[09:19:18] + Attempting to get work packet
[09:19:18] Passkey found
[09:19:18] - Will indicate memory of 7500 MB
[09:19:18] - Connecting to assignment server
[09:19:18] Connecting to http://assign.stanford.edu:8080/
[09:19:19] Posted data.
[09:19:19] Initial: 43AB; - Successful: assigned to (171.67.108.22).
[09:19:19] + News From Folding@Home: Welcome to Folding@Home
[09:19:19] Loaded queue successfully.
[09:19:19] Connecting to http://171.67.108.22:8080/
[09:19:19] Posted data.
[09:19:19] Initial: 0000; - Error: Bad packet type from server, expected work assignment
[09:19:20] - Attempt #3  to get work failed, and no other work to do.
Waiting before retry.
[09:19:42] + Attempting to get work packet
[09:19:42] Passkey found
[09:19:42] - Will indicate memory of 7500 MB
[09:19:42] - Connecting to assignment server
[09:19:42] Connecting to http://assign.stanford.edu:8080/
[09:19:42] Posted data.
[09:19:42] Initial: 43AB; - Successful: assigned to (171.67.108.22).
[09:19:42] + News From Folding@Home: Welcome to Folding@Home
[09:19:43] Loaded queue successfully.
[09:19:43] Connecting to http://171.67.108.22:8080/
[09:19:51] Posted data.
[09:19:51] Initial: 0000; - Receiving payload (expected size: 21479095)
[09:20:23] - Downloaded at ~655 kB/s
[09:20:23] - Averaged speed for that direction ~747 kB/s
[09:20:23] + Received work.
[09:20:23] Trying to send all finished work units
[09:20:23] + No unsent completed units remaining.
[09:20:23] + Closed connections
[09:20:23] 
[09:20:23] + Processing work unit
[09:20:23] Core required: FahCore_a3.exe
[09:20:23] Core found.
[09:20:23] Working on queue slot 02 [July 22 09:20:23 UTC]
[09:20:23] + Working ...
[09:20:23] - Calling '.\FahCore_a3.exe -dir work/ -nice 19 -suffix 02 -np 12 -checkpoint 10 -forceasm -verbose -lifeline 5340 -version 629'

[09:20:23] 
[09:20:23] *------------------------------*
[09:20:23] Folding@Home Gromacs SMP Core
[09:20:23] Version 2.22 (Mar 12, 2010)
[09:20:23] 
[09:20:23] Preparing to commence simulation
[09:20:23] - Assembly optimizations manually forced on.
[09:20:23] - Not checking prior termination.
[09:20:28] - Expanded 21478583 -> 33038409 (decompressed 153.8 percent)
[09:20:28] Called DecompressByteArray: compressed_data_size=21478583 data_size=33038409, decompressed_data_size=33038409 diff=0
[09:20:29] - Digital signature verified
[09:20:29] 
[09:20:29] Project: 2685 (Run 11, Clone 21, Gen 0)
[09:20:29] 
[09:20:29] Assembly optimizations on if available.
[09:20:29] Entering M.D.
[09:20:38] Completed 0 out of 250000 steps  (0%)
[09:47:18] Completed 2500 out of 250000 steps  (1%)
[10:13:20] Completed 5000 out of 250000 steps  (2%)
[10:31:07] - Autosending finished units... [July 22 10:31:07 UTC]
[10:31:07] Trying to send all finished work units
[10:31:07] + No unsent completed units remaining.
[10:31:07] - Autosend completed
[10:39:30] Completed 7500 out of 250000 steps  (3%)
Note #1 : There was a power faillure that interupted folding for some 8 hours midway thru during the night.

Note #2 : I did a backup of my whole system while processing was at 81% done. Since I produce a complete system image on a second HD for a backup, it is realy easy to restore any file.

Note #3 : When I left home for work, it was still processing. When I get home, it should be at around 25%. combined with #2 above, I could "jump" it to 81% in an instant. Would this be reprehensible ?

Note #4 : p2685 processes much faster than p2684, thus a higher PPD.
Image
bruce
Posts: 20824
Joined: Thu Nov 29, 2007 10:13 pm
Location: So. Cal.

Re: Same WU assigned twice p2685-r11-c21-g0

Post by bruce »

Once a WU is completed, it's not supposed to be reissued -- although extra copies can be issued when the Preferred Deadline passes and perhaps at other times.

I can't explain why you got a WU after the server had received the completed WU. I do know that strange things happen. When a WU is reissued to the SAME machine it is supposed to be considered a duplicate -- generally after an EUE -- and you're not supposed to get credit for both of the duplicates.

Yes, it would be cheating to bump a WU from 25% to 81% -- but there's a pretty good chance that you won't get credit for it either way. I would discard it an move on, considering the fact that you already got credit for it.
Mactin
Posts: 223
Joined: Sun Dec 02, 2007 1:08 pm
Location: Outremont, Montréal, Québec

Re: Same WU assigned twice p2685-r11-c21-g0

Post by Mactin »

Thanks, but this is not the first time I had WUs re-assingned. and sometimes*, both instances where credited.
I think I'll take the chance and let it run.
If I dump it, would it put at risk my bonus qualification ? We nox have to think about this.

* recent examples
p10006-r132-c10-g10, credited on March 19th and 20th
p5768-r12-c237-g348, credited twice on April 26th
p4610-r12-c145-g36, credited twice on May 11th
p6329-r2-c264-g15, credited on June 5th and 6th
p6329-r4-c43-g10, credited twice on June 8th
p6330, r6,c28,g17, credited on June 6th and 8th
p10884-r0-c0-g1, credited June 11th and 12th
p5767-r3-c215-g31, credited on June 23rd and 24th

As you can see it happens more often than you think.
And I have logs for most of them.
Image
Post Reply