Page 1 of 1

wu uploaded server refuse to give credit

Posted: Mon Dec 30, 2013 4:11 am
by hisui
this morning I'm having connection issues,after finished wu uploaded,I got an error message.log down below

Code: Select all

23:13:40:Trying to access database...
23:13:40:Successfully acquired database lock
23:13:40:Enabled folding slot 00: READY smp:2
23:13:40:WU01:FS00:Sending unit results: id:01 state:SEND error:NO_ERROR project:8569 run:0 clone:3 gen:362 core:0xa3 unit:0x00000f9c0a3b1e5951224ed693bb5125
23:13:40:WU01:FS00:Uploading 7.18MiB to 128.143.231.202
23:13:40:WU00:FS00:Connecting to assign3.stanford.edu:8080
23:13:40:WU01:FS00:Connecting to 128.143.231.202:8080
23:13:41:WU00:FS00:News: Welcome to Folding@Home
23:13:41:WU00:FS00:Assigned to work server 128.143.231.202
23:13:41:WU00:FS00:Requesting new work unit for slot 00: READY smp:2 from 128.143.231.202
23:13:41:WU00:FS00:Connecting to 128.143.231.202:8080
23:13:43:WU00:FS00:Downloading 3.67MiB
23:13:44:Server connection id=1 on 0.0.0.0:36330 from 127.0.0.1
23:13:46:WU01:FS00:Upload 2.61%
23:13:53:WU00:FS00:Download 3.40%
23:13:53:WU01:FS00:Upload 6.09%
23:13:59:WU01:FS00:Upload 8.70%
23:14:01:WU00:FS00:Download 5.10%
23:14:05:WU01:FS00:Upload 11.31%
23:14:09:WU00:FS00:Download 6.81%
23:14:11:WU01:FS00:Upload 13.92%
23:14:17:WU01:FS00:Upload 16.53%
23:14:18:WU00:FS00:Download 8.51%
23:14:23:WU01:FS00:Upload 19.14%
23:14:28:WU00:FS00:Download 10.21%
23:14:29:WU01:FS00:Upload 21.75%
23:14:35:WU01:FS00:Upload 24.36%
23:14:36:WU00:FS00:Download 11.91%
23:14:42:WU01:FS00:Upload 26.97%
23:14:46:WU00:FS00:Download 13.61%
23:14:48:WU01:FS00:Upload 29.58%
23:14:55:WU01:FS00:Upload 32.19%
23:15:02:WU01:FS00:Upload 34.80%
23:15:07:WU00:FS00:Download 15.31%
23:15:09:WU01:FS00:Upload 37.41%
23:15:16:WU01:FS00:Upload 40.02%
23:15:23:WU01:FS00:Upload 42.63%
23:15:30:WU01:FS00:Upload 45.24%
23:15:36:WU01:FS00:Upload 47.85%
23:15:43:WU01:FS00:Upload 50.46%
23:15:50:WU01:FS00:Upload 53.07%
23:15:56:WU01:FS00:Upload 55.68%
23:16:03:WU01:FS00:Upload 58.29%
23:16:11:WU01:FS00:Upload 60.90%
23:16:18:WU01:FS00:Upload 63.51%
23:16:25:WU01:FS00:Upload 66.12%
23:16:33:WU01:FS00:Upload 68.73%
23:16:41:WU01:FS00:Upload 71.34%
23:16:49:WU01:FS00:Upload 73.95%
23:16:56:WU01:FS00:Upload 76.56%
23:17:03:WU01:FS00:Upload 79.17%
23:17:11:WU01:FS00:Upload 81.78%
23:17:19:WU01:FS00:Upload 84.39%
23:17:25:WU01:FS00:Upload 86.13%
23:17:32:WU01:FS00:Upload 89.61%
23:17:38:WU01:FS00:Upload 93.09%
23:17:44:WU01:FS00:Upload 95.70%
23:17:53:WU01:FS00:Upload 98.31%
23:18:00:WU01:FS00:Upload 100.00%
23:18:03:WU01:FS00:Upload complete
23:18:03:WU01:FS00:Server responded GOT_ALREADY (434)
23:18:03:WARNING:WU01:FS00:Server did not like results, dumping
23:18:03:WU01:FS00:Cleaning up

Re: wu uploaded server refuse to give credit

Posted: Mon Dec 30, 2013 5:30 am
by bruce
The first time your client uploads the WU, you'll get credit but no credit if you try to upload it again. Then it will say"GOT_ALREADY"

What does your log show in the hour before 2013-12-29 21:05 GMT? Your log seems to start about two hours after it was actually uploaded.

Hi Hisui (team 0),
Your WU (P8569 R0 C3 G362) was added to the stats database on 2013-12-29 13:07:19 for 6644.99 points of credit.

Previous logs are stored in the logs subdirectory of FAH's data directory, renamed to the date and time. I would be interested in that segment of whichever log it happens to be in and a narrative of what happened. Under ordinary circumstances, when a WU is successfully uploaded, the local copy is deleted so the client doesn't try to upload it again. Obviously the server did note a successful upload but the client didn't delete it. My theory is that sometimes the confirmation message gets lost so you'll get this scenario.

Obviously it's better for the client to try again any time that the completion of the upload is uncertain, even if it leads to complaints like yours. Your report of connection issues is consistent with this explanation.

Re: wu uploaded server refuse to give credit

Posted: Mon Dec 30, 2013 10:11 am
by hisui
There was a failed upload
log see below

Code: Select all

******************************** Date: 29/12/13 ********************************
20:07:31:WU01:FS00:0xa3:Completed 500000 out of 500000 steps  (100%)
20:07:33:WU01:FS00:0xa3:DynamicWrapper: Finished Work Unit: sleep=10000
20:07:43:WU01:FS00:0xa3:
20:07:43:WU01:FS00:0xa3:Finished Work Unit:
20:07:43:WU01:FS00:0xa3:- Reading up to 8057664 from "01/wudata_01.trr": Read 8057664
20:07:43:WU01:FS00:0xa3:trr file hash check passed.
20:07:43:WU01:FS00:0xa3:edr file hash check passed.
20:07:43:WU01:FS00:0xa3:logfile size: 71713
20:07:43:WU01:FS00:0xa3:Leaving Run
20:07:46:WU01:FS00:0xa3:- Writing 8166209 bytes of core data to disk...
20:07:48:WU01:FS00:0xa3:Done: 8165697 -> 7532071 (compressed to 92.2 percent)
20:07:48:WU01:FS00:0xa3:  ... Done.
20:07:49:WU01:FS00:0xa3:- Shutting down core
20:07:49:WU01:FS00:0xa3:
20:07:49:WU01:FS00:0xa3:Folding@home Core Shutdown: FINISHED_UNIT
20:07:49:WU01:FS00:FahCore returned: FINISHED_UNIT (100 = 0x64)
20:07:50:WU01:FS00:Sending unit results: id:01 state:SEND error:NO_ERROR project:8569 run:0 clone:3 gen:362 core:0xa3 unit:0x00000f9c0a3b1e5951224ed693bb5125
20:07:50:WU01:FS00:Uploading 7.18MiB to 128.143.231.202
20:07:50:WU01:FS00:Connecting to 128.143.231.202:8080
20:07:56:WU01:FS00:Upload 1.74%
20:08:04:WU01:FS00:Upload 4.35%
20:08:12:WU01:FS00:Upload 6.96%
20:08:18:WU01:FS00:Upload 8.70%
20:08:26:WU01:FS00:Upload 11.31%
20:08:33:WU01:FS00:Upload 13.05%
20:08:50:WU01:FS00:Upload 13.92%
20:08:56:WU01:FS00:Upload 15.66%
20:09:04:WU01:FS00:Upload 18.27%
20:09:10:WU01:FS00:Upload 20.01%
20:09:18:WU01:FS00:Upload 22.62%
20:09:26:WU01:FS00:Upload 25.23%
20:09:47:WU01:FS00:Upload 26.97%
20:09:53:WU01:FS00:Upload 28.71%
20:09:59:WU01:FS00:Upload 30.45%
20:10:07:WU01:FS00:Upload 33.06%
20:10:14:WU01:FS00:Upload 35.67%
20:10:21:WU01:FS00:Upload 38.28%
20:10:29:WU01:FS00:Upload 40.89%
20:10:37:WU01:FS00:Upload 43.50%
20:10:44:WU01:FS00:Upload 45.24%
20:10:58:WU01:FS00:Upload 46.11%
20:11:04:WU01:FS00:Upload 47.85%
20:11:12:WU01:FS00:Upload 48.72%
20:11:18:WU01:FS00:Upload 50.46%
20:11:24:WU01:FS00:Upload 52.20%
20:11:30:WU01:FS00:Upload 53.94%
20:11:39:WU01:FS00:Upload 56.55%
20:11:47:WU01:FS00:Upload 59.16%
20:11:53:WU01:FS00:Upload 60.90%
20:12:01:WU01:FS00:Upload 63.51%
20:12:09:WU01:FS00:Upload 66.12%
20:12:17:WU01:FS00:Upload 68.73%
20:12:25:WU01:FS00:Upload 71.34%
20:12:32:WU01:FS00:Upload 73.08%
20:12:42:WU01:FS00:Upload 74.82%
20:12:48:WU01:FS00:Upload 76.56%
20:12:54:WU01:FS00:Upload 78.30%
20:13:02:WU01:FS00:Upload 80.91%
20:13:08:WU01:FS00:Upload 82.65%
20:13:21:WU01:FS00:Upload 85.26%
20:13:27:WU01:FS00:Upload 87.00%
20:13:35:WU01:FS00:Upload 89.61%
20:13:41:WU01:FS00:Upload 91.35%
20:13:49:WU01:FS00:Upload 93.96%
20:13:57:WU01:FS00:Upload 96.57%
20:14:03:WU01:FS00:Upload 98.31%
23:04:33:Lost lifeline PID 2996, exiting
23:04:33:Server connection id=1 ended

Re: wu uploaded server refuse to give credit

Posted: Mon Dec 30, 2013 5:55 pm
by bruce
Thanks for the log.

Well, at the rate the uploading was progressing, the upload should have finished about 20:14:13 and there's no indication whether it was successful or not. (The messages about "lost lifeline" and "Server connection ended" are not related to the upload and happened hours later.)

Nothing there to disprove my theory, but also every reason for you to wonder if the upload was successful or not -- and for the client to retry later. At that time, the server did report that the earlier upload had been successful.

I'm not sure how the software could do any better error recovery, given that the connection failed at the very end of the upload.

Re: wu uploaded server refuse to give credit

Posted: Mon Dec 30, 2013 6:07 pm
by PantherX
I have experienced this issue when my connection drops. The WU is usually successfully transferred (when the uploaded percentage is higher than 95%) but I would lose the connection before my FAHClient ever gets the acknowledgement or the points estimate being printed in the log. The second upload would be a GOT_ALREADY (434) and the WU deleted. I highly doubt that anything can be done about it since the root cause is the internet connection, something that F@H has no control over.

Moreover, if you do run a network intensive application/congested internet connection, there can be a situation where the packets sent by the F@H Servers are not received by your FAHClient so the download/upload is stuck. Usually, the upload restarts automatically but sometimes, a manual restart of FAHClient is needed. If the download hangs, restarting FAHClient is the only way to get it to download a new WU again. Please note that this is a known issue (https://fah.stanford.edu/projects/FAHClient/ticket/983) and will hopefully be resolved in a future release of V7.