Is anyone making any progress on this (increasingly common) error?

Every instance I've investigated in detail shares the same characteristsics:

2 WUs from the same project finish 1 second apart.
The second WU then re-starts before uploading, when one would expect a different WU to start.

Here's a standard log showing the full sequence of events: I'm happy to do any enhanced logging which might help to track it down.

Edit: this particular log is from BOINC 5.8.9, running as a service under Windows XP.

2007-02-21 20:12:11 [SETI@home] Sending scheduler request: To fetch work
2007-02-21 20:12:11 [SETI@home] Requesting 13201 seconds of new work, and reporting 1 completed tasks
2007-02-21 20:12:17 [SETI@home] Scheduler RPC succeeded [server version 507]
2007-02-21 20:12:17 [SETI@home] Deferring communication for 11 sec
2007-02-21 20:12:17 [SETI@home] Reason: requested by project
2007-02-21 20:12:19 [SETI@home] [file_xfer] Started download of file 11no03aa.4732.12274.92334.3.38
2007-02-21 20:12:19 [SETI@home] [file_xfer] Started download of file 11no03aa.4732.12274.92334.3.43
2007-02-21 20:12:26 [SETI@home] [file_xfer] Finished download of file 11no03aa.4732.12274.92334.3.38
2007-02-21 20:12:26 [SETI@home] [file_xfer] Throughput 68354 bytes/sec
2007-02-21 20:12:26 [SETI@home] [file_xfer] Finished download of file 11no03aa.4732.12274.92334.3.43
2007-02-21 20:12:26 [SETI@home] [file_xfer] Throughput 68756 bytes/sec
2007-02-21 20:12:26 [SETI@home] [file_xfer] Started download of file 11no03aa.4732.12274.92334.3.44
2007-02-21 20:12:26 [SETI@home] [file_xfer] Started download of file 11no03aa.4732.12274.92334.3.45
2007-02-21 20:12:27 [SETI@home] Starting 11no03aa.4732.12274.92334.3.38_3
2007-02-21 20:12:27 [SETI@home] Starting task 11no03aa.4732.12274.92334.3.38_3 using setiathome_enhanced version 517
2007-02-21 20:12:27 [SETI@home] Starting 11no03aa.4732.12274.92334.3.43_3
2007-02-21 20:12:27 [SETI@home] Starting task 11no03aa.4732.12274.92334.3.43_3 using setiathome_enhanced version 517
2007-02-21 20:12:27 [SETI@home] Starting 01no03aa.5768.1328.765888.3.102_0
2007-02-21 20:12:27 [SETI@home] Starting task 01no03aa.5768.1328.765888.3.102_0 using setiathome_enhanced version 517
2007-02-21 20:12:27 [SETI@home] Starting 01no03aa.5768.1233.373588.3.77_0
2007-02-21 20:12:27 [SETI@home] Starting task 01no03aa.5768.1233.373588.3.77_0 using setiathome_enhanced version 517
2007-02-21 20:12:36 [SETI@home] [file_xfer] Finished download of file 11no03aa.4732.12274.92334.3.44
2007-02-21 20:12:36 [SETI@home] [file_xfer] Throughput 44306 bytes/sec
2007-02-21 20:12:36 [SETI@home] [file_xfer] Finished download of file 11no03aa.4732.12274.92334.3.45
2007-02-21 20:12:36 [SETI@home] [file_xfer] Throughput 44219 bytes/sec
2007-02-21 20:12:36 [SETI@home] [file_xfer] Started download of file 15au03aa.20522.7056.765914.3.4
2007-02-21 20:12:38 [SETI@home] Starting 11no03aa.4732.12274.92334.3.44_3
2007-02-21 20:12:38 [SETI@home] Starting task 11no03aa.4732.12274.92334.3.44_3 using setiathome_enhanced version 517
2007-02-21 20:12:38 [SETI@home] Starting 11no03aa.4732.12274.92334.3.45_0
2007-02-21 20:12:38 [SETI@home] Starting task 11no03aa.4732.12274.92334.3.45_0 using setiathome_enhanced version 517
2007-02-21 20:12:46 [SETI@home] [file_xfer] Finished download of file 15au03aa.20522.7056.765914.3.4
2007-02-21 20:12:46 [SETI@home] [file_xfer] Throughput 47095 bytes/sec
2007-02-21 20:12:53 [SETI@home] Computation for task 11no03aa.4732.12274.92334.3.38_3 finished
2007-02-21 20:12:54 [SETI@home] Computation for task 11no03aa.4732.12274.92334.3.43_3 finished
2007-02-21 20:12:55 [SETI@home] [file_xfer] Started upload of file 11no03aa.4732.12274.92334.3.38_3_0
2007-02-21 20:12:55 [SETI@home] Starting 11no03aa.4732.12274.92334.3.43_3
2007-02-21 20:12:55 [SETI@home] Starting task 11no03aa.4732.12274.92334.3.43_3 using setiathome_enhanced version 517
2007-02-21 20:12:56 [SETI@home] [file_xfer] Started upload of file 11no03aa.4732.12274.92334.3.43_3_0
2007-02-21 20:12:58 [SETI@home] Computation for task 11no03aa.4732.12274.92334.3.44_3 finished
2007-02-21 20:12:58 [SETI@home] Resuming task 19au03aa.16709.19889.648582.3.172_0 using setiathome_enhanced version 517
2007-02-21 20:12:58 [SETI@home] Resuming task 19au03aa.16709.20530.392322.3.171_3 using setiathome_enhanced version 517
2007-02-21 20:12:59 [SETI@home] Computation for task 11no03aa.4732.12274.92334.3.45_0 finished
2007-02-21 20:13:03 [SETI@home] [file_xfer] Finished upload of file 11no03aa.4732.12274.92334.3.38_3_0
2007-02-21 20:13:03 [SETI@home] [file_xfer] Throughput 4505 bytes/sec
2007-02-21 20:13:03 [SETI@home] [file_xfer] Started upload of file 11no03aa.4732.12274.92334.3.44_3_0
2007-02-21 20:13:09 [SETI@home] [error] Error on file upload: socket read incomplete: asked for 16382, got 7421: No such file or directory
2007-02-21 20:13:09 [SETI@home] [file_xfer] Temporarily failed upload of 11no03aa.4732.12274.92334.3.43_3_0: transient upload error

---

Is anyone making any progress on this (increasingly common) error?

2 WUs from the same project finish 1 second apart. The second WU then re-starts before uploading, when one would expect a different WU to start.

There is following entry in the checkin notes:

David 15 Feb 2007
- core client: fix bug where if a task is aborted
(e.g. because it exceeds CPU limit)
it's restarted on the next enforce_schedule().
The problem: we're deleting the ACTIVE_TASK,
but the result is still in the ordered_scheduled_results list.
The solution: call request_schedule_cpus() in
handle_finished_apps() when an ACTIVE_TASK is deleted.

It could handle the same symptom (restarting finished task), but possibly also not exact this one.

The read problem could also be that Boinc could not read the same that was previously written - either the slot changed or the output file was overwritten by the new run.

Peter

Please try to reproduce the error on the latest development version.

OK, I've upgraded to 5.8.15, which is the latest at the time of writing.

It's difficult to reproduce it to order, because it's so critical on timing (2 WUs finish within 1 second), but I'll certainly report whatever I see. Would any extra logging help?

---

According to David Anderson, this problem was fixed on 5.8.11.

According to David Anderson, this problem was fixed on 5.8.11.

Looking at the datestamps on /dl, 5.8.11 was released on 09 Feb.

So how does that marry with the checkin quoted by Pepo, DA on 15 Feb?

Could somebody, anybody, please post a public link to the full checkin changelog?

And if there isn't one, make one?

[/rant]Sorry, posts at this time of day/night tend to be made under the affluence of incohol.

---

The checkin Pepo posted was about a different unrelated problem.

Full log is here

Many thanks for that link - I'll be hanging on to it for future reference.

If the fix was first in 5.8.11, and I saw the problem in 5.8.9, then the checkin would be between 05 Feb and 09 Feb, right? (There was no 5.8.10 for Windows).

David wrote 8 checkin notes during that timeframe. I can't reconcile any of them with the symptoms I've been describing.

I'll read a few more entries before and after tomorrow, when I've had some sleep (and when I'm sober!)

---

The checkin Pepo posted was about a different unrelated problem.

If, then I could imagine only this change:

David 2 Feb 2007
- core client: fix bug where client thinks an app is running but it isn't.
Basic problem: there are two layers
1) process control: start/stop/suspend/resume, handle exits.
files: app_*.C
It owns ACTIVE_TASK::task_state
2) CPU scheduling: decide what should run
files: cpu_sched.C
It owns ACTIVE_TASK::scheduler_state.
These layers weren't cleanly separated;
e.g. the CPU sched layer relied on the process control layer
to set scheduler_state in some cases,
and this wasn't always being done.
I fixed this; the layers are now separated.
Also: added new task_state value, PROCESS_QUIT_PENDING

Peter

Still reading!

The closest would seem to be:

David 2 Feb 2007
- core client: fix bug where client thinks an app is running but it isn't.
Basic problem: there are two layers
1) process control: start/stop/suspend/resume, handle exits.
files: app_*.C
It owns ACTIVE_TASK::task_state
2) CPU scheduling: decide what should run
files: cpu_sched.C
It owns ACTIVE_TASK::scheduler_state.
These layers weren't cleanly separated;
e.g. the CPU sched layer relied on the process control layer
to set scheduler_state in some cases,
and this wasn't always being done.
I fixed this; the layers are now separated.
Also: added new task_state value, PROCESS_QUIT_PENDING

But that change should have been in 5.8.9 - 05 Feb. And the bug is still in that version. Can I re-report it, please?

---

Pepo - Great minds think alike (and cross-post), LOL

---

:-)

And Nicolas has beaten me with the checkin_notes for seconds...

My resume - both (and few others) modifications are regarding improper finshad task handling (restarted task which already finished), there were additional tiny changes like adding "case PROCESS_QUIT_PENDING:" in app_control.C, so I think it belongs alltogether.

And hopefully all leaks are already closed.Peter

But that change should have been in 5.8.9 - 05 Feb. And the bug is still in that version. Can I re-report it, please?

Client releases aren't made of 100% latest code. Some code is made on a "branch" of the development, then merged back to the "trunk" when it's working correctly. So you can't really know what was changed based on the date. It looks like a quite disorganized way of working, and I thought so too, until I suddenly needed to do something like that on a little project of mine :P

David said it was fixed on 5.8.11, so if it was on 5.8.9 it's irrelevant.

The problem is still there in 5.8.11, I posted on Seti NC board a few mins ago. see Message 522180

Andy

If someone will notice any "Error on file upload" problem, it would be helpful to take a look into the log, if that particular result was possibly restarted once more after being finished, prior to being uploaded. Especially if it happened with newer Boinc than 5.8.11.

Peter

Here are the relevant lines from my C2D's stdoutdae.txt file,

2007-02-23 00:08:52 [SETI@home] Computation for task 11no03aa.4732.20290.1022148.3.160_0 finished
2007-02-23 00:08:52 [SETI@home] Starting 11no03aa.4732.20290.1022148.3.193_2
2007-02-23 00:08:52 [SETI@home] Starting task 11no03aa.4732.20290.1022148.3.193_2 using setiathome_enhanced version 515
2007-02-23 00:08:52 [SETI@home] Starting 11no03aa.4732.20290.1022148.3.183_0
2007-02-23 00:08:52 [SETI@home] Starting task 11no03aa.4732.20290.1022148.3.183_0 using setiathome_enhanced version 515
2007-02-23 00:08:54 [SETI@home] [file_xfer] Finished upload of file 11no03aa.4732.20290.1022148.3.152_3_0
2007-02-23 00:08:54 [SETI@home] [file_xfer] Throughput 7082 bytes/sec
2007-02-23 00:08:54 [SETI@home] [file_xfer] Started upload of file 11no03aa.4732.20290.1022148.3.160_0_0
2007-02-23 00:08:58 [SETI@home] [file_xfer] Finished upload of file 11no03aa.4732.20290.1022148.3.160_0_0
2007-02-23 00:08:58 [SETI@home] [file_xfer] Throughput 23188 bytes/sec
2007-02-23 00:08:59 [SETI@home] Computation for task 11no03aa.4732.20290.1022148.3.193_2 finished
2007-02-23 00:08:59 [SETI@home] Starting 11no03aa.4732.20290.1022148.3.226_1
2007-02-23 00:08:59 [SETI@home] Starting task 11no03aa.4732.20290.1022148.3.226_1 using setiathome_enhanced version 515
2007-02-23 00:09:00 [SETI@home] Computation for task 11no03aa.4732.20290.1022148.3.183_0 finished
2007-02-23 00:09:01 [SETI@home] [file_xfer] Started upload of file 11no03aa.4732.20290.1022148.3.193_2_0
2007-02-23 00:09:04 [SETI@home] [file_xfer] Finished upload of file 11no03aa.4732.20290.1022148.3.193_2_0
2007-02-23 00:09:04 [SETI@home] [file_xfer] Throughput 17441 bytes/sec
2007-02-23 00:09:04 [SETI@home] [file_xfer] Started upload of file 11no03aa.4732.20290.1022148.3.183_0_0
2007-02-23 00:09:05 [SETI@home] Starting 11no03aa.4732.20290.1022148.3.183_0
2007-02-23 00:09:05 [SETI@home] Starting task 11no03aa.4732.20290.1022148.3.183_0 using setiathome_enhanced version 515
2007-02-23 00:09:06 [SETI@home] Computation for task 11no03aa.4732.20290.1022148.3.226_1 finished
2007-02-23 00:09:06 [SETI@home] Resuming task 18se03aa.2009.5280.553406.3.26_2 using setiathome_enhanced version 515
2007-02-23 00:09:06 [SETI@home] Starting 09ap00aa.21686.30674.54816.3.32_1
2007-02-23 00:09:06 [SETI@home] Starting task 09ap00aa.21686.30674.54816.3.32_1 using setiathome_enhanced version 515
2007-02-23 00:09:09 [SETI@home] Sending scheduler request: To fetch work
2007-02-23 00:09:09 [SETI@home] Requesting 12843 seconds of new work, and reporting 4 completed tasks
2007-02-23 00:09:15 [SETI@home] Scheduler RPC succeeded [server version 507]
2007-02-23 00:09:15 [SETI@home] Deferring communication for 11 sec
2007-02-23 00:09:15 [SETI@home] Reason: requested by project
2007-02-23 00:09:20 [SETI@home Beta Test] Sending scheduler request: To fetch work
2007-02-23 00:09:20 [SETI@home Beta Test] Requesting 42797 seconds of new work
2007-02-23 00:09:25 [SETI@home Beta Test] Scheduler RPC succeeded [server version 509]
2007-02-23 00:09:25 [SETI@home Beta Test] Deferring communication for 7 sec
2007-02-23 00:09:25 [SETI@home Beta Test] Reason: requested by project
2007-02-23 00:09:25 [SETI@home Beta Test] Deferring communication for 3 hr 19 min 6 sec
2007-02-23 00:09:25 [SETI@home Beta Test] Reason: no work from project
2007-02-23 00:09:30 [SETI@home] Sending scheduler request: To fetch work
2007-02-23 00:09:30 [SETI@home] Requesting 1147 seconds of new work
2007-02-23 00:09:35 [SETI@home] Scheduler RPC succeeded [server version 507]
2007-02-23 00:09:35 [SETI@home] Deferring communication for 11 sec
2007-02-23 00:09:35 [SETI@home] Reason: requested by project
2007-02-23 00:18:50 [SETI@home] Sending scheduler request: To fetch work
2007-02-23 00:18:50 [SETI@home] Requesting 28 seconds of new work
2007-02-23 00:18:55 [---] Project communication failed: attempting access to reference site
2007-02-23 00:18:55 [SETI@home] [file_xfer] Temporarily failed upload of 11no03aa.4732.20290.1022148.3.153_0_0: http error
2007-02-23 00:18:55 [SETI@home] Backing off 1 min 0 sec on upload of file 11no03aa.4732.20290.1022148.3.153_0_0
2007-02-23 00:18:55 [SETI@home] [file_xfer] Started upload of file 11no03aa.4732.20290.1022148.3.226_1_0
2007-02-23 00:18:55 [SETI@home] Scheduler RPC succeeded [server version 507]
2007-02-23 00:18:55 [SETI@home] Deferring communication for 11 sec
2007-02-23 00:18:55 [SETI@home] Reason: requested by project
2007-02-23 00:18:56 [---] Access to reference site succeeded - project servers may be temporarily down.
2007-02-23 00:19:00 [SETI@home] [file_xfer] Finished upload of file 11no03aa.4732.20290.1022148.3.226_1_0
2007-02-23 00:19:00 [SETI@home] [file_xfer] Throughput 19647 bytes/sec
2007-02-23 00:19:00 [SETI@home] [file_xfer] Started download of file 11no03aa.4732.27985.928404.3.48
2007-02-23 00:19:05 [SETI@home] [file_xfer] Finished download of file 11no03aa.4732.27985.928404.3.48
2007-02-23 00:19:05 [SETI@home] [file_xfer] Throughput 119851 bytes/sec
2007-02-23 00:19:05 [SETI@home] [file_xfer] Started download of file 18se03aa.8593.32272.509654.3.63
2007-02-23 00:19:10 [---] Project communication failed: attempting access to reference site
2007-02-23 00:19:10 [SETI@home] [file_xfer] Temporarily failed upload of 11no03aa.4732.20290.1022148.3.183_0_0: http error
2007-02-23 00:19:10 [SETI@home] Backing off 1 min 0 sec on upload of file 11no03aa.4732.20290.1022148.3.183_0_0
2007-02-23 00:19:10 [SETI@home] [file_xfer] Started download of file 15au03aa.20522.23185.792316.3.247
2007-02-23 00:19:11 [---] Access to reference site succeeded - project servers may be temporarily down.
2007-02-23 00:19:11 [SETI@home] [file_xfer] Finished download of file 18se03aa.8593.32272.509654.3.63
2007-02-23 00:19:11 [SETI@home] [file_xfer] Throughput 68368 bytes/sec
2007-02-23 00:19:11 [SETI@home] [file_xfer] Started download of file 11no03aa.4732.27985.928404.3.44
2007-02-23 00:19:16 [SETI@home] [file_xfer] Finished download of file 11no03aa.4732.27985.928404.3.44
2007-02-23 00:19:16 [SETI@home] [file_xfer] Throughput 86095 bytes/sec
2007-02-23 00:19:16 [SETI@home] [file_xfer] Started download of file 15au03aa.20522.23185.792316.3.255
2007-02-23 00:19:19 [SETI@home] [file_xfer] Finished download of file 15au03aa.20522.23185.792316.3.247
2007-02-23 00:19:19 [SETI@home] [file_xfer] Throughput 45260 bytes/sec
2007-02-23 00:19:19 [SETI@home] [file_xfer] Started download of file 11no03aa.4732.28033.598592.3.210
2007-02-23 00:19:24 [SETI@home] [file_xfer] Finished download of file 15au03aa.20522.23185.792316.3.255
2007-02-23 00:19:24 [SETI@home] [file_xfer] Throughput 48072 bytes/sec
2007-02-23 00:19:24 [SETI@home] [file_xfer] Finished download of file 11no03aa.4732.28033.598592.3.210
2007-02-23 00:19:24 [SETI@home] [file_xfer] Throughput 73804 bytes/sec
2007-02-23 00:19:56 [SETI@home] [file_xfer] Started upload of file 11no03aa.4732.20290.1022148.3.153_0_0
2007-02-23 00:19:59 [SETI@home] [error] Error on file upload: socket read incomplete: asked for 16382, got 7589: No such file or directory
2007-02-23 00:19:59 [SETI@home] [file_xfer] Temporarily failed upload of 11no03aa.4732.20290.1022148.3.153_0_0: transient upload error
2007-02-23 00:19:59 [SETI@home] Backing off 1 min 0 sec on upload of file 11no03aa.4732.20290.1022148.3.153_0_0
2007-02-23 00:20:10 [SETI@home] [file_xfer] Started upload of file 11no03aa.4732.20290.1022148.3.183_0_0
2007-02-23 00:20:13 [SETI@home] [error] Error on file upload: socket read incomplete: asked for 16382, got 7589: No such file or directory
2007-02-23 00:20:13 [SETI@home] [file_xfer] Temporarily failed upload of 11no03aa.4732.20290.1022148.3.183_0_0: transient upload error
2007-02-23 00:20:13 [SETI@home] Backing off 1 min 0 sec on upload of file 11no03aa.4732.20290.1022148.3.183_0_0
2007-02-23 00:20:59 [SETI@home] [file_xfer] Started upload of file 11no03aa.4732.20290.1022148.3.153_0_0
2007-02-23 00:21:01 [SETI@home] [error] Error on file upload: socket read incomplete: asked for 16382, got 7589: No such file or directory
2007-02-23 00:21:01 [SETI@home] [file_xfer] Temporarily failed upload of 11no03aa.4732.20290.1022148.3.153_0_0: transient upload error
2007-02-23 00:21:01 [SETI@home] Backing off 1 min 0 sec on upload of file 11no03aa.4732.20290.1022148.3.153_0_0
2007-02-23 00:21:13 [SETI@home] [file_xfer] Started upload of file 11no03aa.4732.20290.1022148.3.183_0_0
2007-02-23 00:21:17 [SETI@home] [error] Error on file upload: socket read incomplete: asked for 16382, got 7589: No such file or directory
2007-02-23 00:21:17 [SETI@home] [file_xfer] Temporarily failed upload of 11no03aa.4732.20290.1022148.3.183_0_0: transient upload error
2007-02-23 00:21:17 [SETI@home] Backing off 1 min 0 sec on upload of file 11no03aa.4732.20290.1022148.3.183_0_0

I have copy of full file that I can fwd on if needed.

Andy

Here are the relevant lines from my C2D's stdoutdae.txt file,

I have copy of full file that I can fwd on if needed.

Andy

I'd like to go a little further back, if that's possible, to see the actual computation stages for 11no03aa.4732.20290.1022148.3.153

Or if you could forward the whole thing to initial dot surname at btinternet dot com, I'll have a look through it here.

---

Here are the relevant lines from my C2D's stdoutdae.txt file,

I have copy of full file that I can fwd on if needed.

Andy

I'd like to go a little further back, if that's possible, to see the actual computation stages for 11no03aa.4732.20290.1022148.3.153

Or if you could forward the whole thing to initial dot surname at btinternet dot com, I'll have a look through it here.

Sent it as an attachment, got to get architrave fitted to new door frame and whole thing primed and painted.

Andy

Here are the relevant lines from my C2D's stdoutdae.txt file,

I have copy of full file that I can fwd on if needed.

Andy

I'd like to go a little further back, if that's possible, to see the actual computation stages for 11no03aa.4732.20290.1022148.3.153

Or if you could forward the whole thing to initial dot surname at btinternet dot com, I'll have a look through it here.

Sent it as an attachment, got to get architrave fitted to new door frame and whole thing primed and painted.

Andy

Email received OK, thanks, and the data is all there.

At a first glance, it's a slightly different pattern, but it's still a finish-restart-upload sequence. And as SETI result 481839220 confirms, this was under BOINC v5.8.11

I'll post more of the log when I've gone through it and highlighted the relevant points.

---

We've now got two full logs of this happening under 5.8.11, and they both show the same pattern as before: two WUs from the same project finish within a second: the second one is re-started by BOINC. Andy's (WinterKnight) is slightly different, in that the upload started just before the computation restart, but the end result was the same.

The second case was logged by 'The Eternal' on the SETI Beta board at message 15232, but I'll copy the log here to keep them together.

Nicolas, could you give us a little bit more on the context in which David said it was fixed in 5.8.11? Could the fix possibly have been delayed even beyond that release?

WinterKnight's log:
2007-02-23 00:08:38 [SETI@home] Starting 11no03aa.4732.20290.1022148.3.153_0
2007-02-23 00:08:38 [SETI@home] Starting task 11no03aa.4732.20290.1022148.3.153_0 using setiathome_enhanced version 515
2007-02-23 00:08:45 [SETI@home] Computation for task 11no03aa.4732.20290.1022148.3.152_3 finished
2007-02-23 00:08:45 [SETI@home] Starting 11no03aa.4732.20290.1022148.3.160_0
2007-02-23 00:08:45 [SETI@home] Starting task 11no03aa.4732.20290.1022148.3.160_0 using setiathome_enhanced version 515
2007-02-23 00:08:46 [SETI@home] Computation for task 11no03aa.4732.20290.1022148.3.153_0 finished
2007-02-23 00:08:47 [SETI@home] [file_xfer] Started upload of file 11no03aa.4732.20290.1022148.3.152_3_0
2007-02-23 00:08:48 [SETI@home] [file_xfer] Started upload of file 11no03aa.4732.20290.1022148.3.153_0_0
2007-02-23 00:08:50 [SETI@home] Starting 11no03aa.4732.20290.1022148.3.153_0
2007-02-23 00:08:50 [SETI@home] Starting task 11no03aa.4732.20290.1022148.3.153_0 using setiathome_enhanced version 515
2007-02-23 00:08:52 [SETI@home] Computation for task 11no03aa.4732.20290.1022148.3.160_0 finished
2007-02-23 00:08:52 [SETI@home] Starting 11no03aa.4732.20290.1022148.3.193_2
2007-02-23 00:08:52 [SETI@home] Starting task 11no03aa.4732.20290.1022148.3.193_2 using setiathome_enhanced version 515
2007-02-23 00:08:52 [SETI@home] Starting 11no03aa.4732.20290.1022148.3.183_0
2007-02-23 00:08:52 [SETI@home] Starting task 11no03aa.4732.20290.1022148.3.183_0 using setiathome_enhanced version 515
2007-02-23 00:08:54 [SETI@home] [file_xfer] Finished upload of file 11no03aa.4732.20290.1022148.3.152_3_0
2007-02-23 00:08:54 [SETI@home] [file_xfer] Throughput 7082 bytes/sec
2007-02-23 00:08:54 [SETI@home] [file_xfer] Started upload of file 11no03aa.4732.20290.1022148.3.160_0_0
2007-02-23 00:08:58 [SETI@home] [file_xfer] Finished upload of file 11no03aa.4732.20290.1022148.3.160_0_0
2007-02-23 00:08:58 [SETI@home] [file_xfer] Throughput 23188 bytes/sec
2007-02-23 00:08:59 [SETI@home] Computation for task 11no03aa.4732.20290.1022148.3.193_2 finished
2007-02-23 00:08:59 [SETI@home] Starting 11no03aa.4732.20290.1022148.3.226_1
2007-02-23 00:08:59 [SETI@home] Starting task 11no03aa.4732.20290.1022148.3.226_1 using setiathome_enhanced version 515
2007-02-23 00:09:00 [SETI@home] Computation for task 11no03aa.4732.20290.1022148.3.183_0 finished
2007-02-23 00:09:01 [SETI@home] [file_xfer] Started upload of file 11no03aa.4732.20290.1022148.3.193_2_0
2007-02-23 00:09:04 [SETI@home] [file_xfer] Finished upload of file 11no03aa.4732.20290.1022148.3.193_2_0
2007-02-23 00:09:04 [SETI@home] [file_xfer] Throughput 17441 bytes/sec
2007-02-23 00:09:04 [SETI@home] [file_xfer] Started upload of file 11no03aa.4732.20290.1022148.3.183_0_0
2007-02-23 00:09:05 [SETI@home] Starting 11no03aa.4732.20290.1022148.3.183_0
2007-02-23 00:09:05 [SETI@home] Starting task 11no03aa.4732.20290.1022148.3.183_0 using setiathome_enhanced version 515
2007-02-23 00:09:06 [SETI@home] Computation for task 11no03aa.4732.20290.1022148.3.226_1 finished
2007-02-23 00:09:06 [SETI@home] Resuming task 18se03aa.2009.5280.553406.3.26_2 using setiathome_enhanced version 515
2007-02-23 00:09:06 [SETI@home] Starting 09ap00aa.21686.30674.54816.3.32_1
2007-02-23 00:09:06 [SETI@home] Starting task 09ap00aa.21686.30674.54816.3.32_1 using setiathome_enhanced version 515
2007-02-23 00:09:09 [SETI@home] Sending scheduler request: To fetch work
2007-02-23 00:09:09 [SETI@home] Requesting 12843 seconds of new work, and reporting 4 completed tasks
2007-02-23 00:09:15 [SETI@home] Scheduler RPC succeeded [server version 507]
2007-02-23 00:09:15 [SETI@home] Deferring communication for 11 sec
2007-02-23 00:09:15 [SETI@home] Reason: requested by project
2007-02-23 00:09:30 [SETI@home] Sending scheduler request: To fetch work
2007-02-23 00:09:30 [SETI@home] Requesting 1147 seconds of new work
2007-02-23 00:09:35 [SETI@home] Scheduler RPC succeeded [server version 507]
2007-02-23 00:09:35 [SETI@home] Deferring communication for 11 sec
2007-02-23 00:09:35 [SETI@home] Reason: requested by project
2007-02-23 00:18:50 [SETI@home] Sending scheduler request: To fetch work
2007-02-23 00:18:50 [SETI@home] Requesting 28 seconds of new work
2007-02-23 00:18:55 [---] Project communication failed: attempting access to reference site
2007-02-23 00:18:55 [SETI@home] [file_xfer] Temporarily failed upload of 11no03aa.4732.20290.1022148.3.153_0_0: http error
2007-02-23 00:18:55 [SETI@home] Backing off 1 min 0 sec on upload of file 11no03aa.4732.20290.1022148.3.153_0_0

The Eternal's log:
2007-02-21 06:03:59 [SETI@home] [file_xfer] Started upload of file 11no03aa.4732.8626.304816.3.18_0_0
2007-02-21 06:03:59 [SETI@home] Computation for task 11no03aa.4732.8626.304816.3.22_0 finished
. 2007-02-21 06:03:59 [SETI@home] Starting 11no03aa.4732.8626.304816.3.23_0
. 2007-02-21 06:03:59 [SETI@home] Starting task 11no03aa.4732.8626.304816.3.23_0 using setiathome_enhanced version 519
2007-02-21 06:04:01 [SETI@home] [file_xfer] Finished upload of file 11no03aa.4732.8626.304816.3.18_0_0
2007-02-21 06:04:01 [SETI@home] [file_xfer] Throughput 67350 bytes/sec
2007-02-21 06:04:01 [SETI@home] [file_xfer] Started upload of file 11no03aa.4732.8626.304816.3.22_0_0
2007-02-21 06:04:04 [SETI@home] [file_xfer] Finished upload of file 11no03aa.4732.8626.304816.3.22_0_0
2007-02-21 06:04:04 [SETI@home] [file_xfer] Throughput 214138 bytes/sec
2007-02-21 06:13:28 [SETI@home Beta Test] Sending scheduler request: To fetch work
2007-02-21 06:13:28 [SETI@home Beta Test] Requesting 107916 seconds of new work
2007-02-21 06:13:33 [SETI@home Beta Test] Scheduler RPC succeeded [server version 509]
2007-02-21 06:13:33 [SETI@home Beta Test] Deferring communication for 7 sec
2007-02-21 06:13:33 [SETI@home Beta Test] Reason: requested by project
2007-02-21 06:13:33 [SETI@home Beta Test] Deferring communication for 42 min 44 sec
2007-02-21 06:13:33 [SETI@home Beta Test] Reason: no work from project
. 2007-02-21 06:23:23 [SETI@home] Computation for task 11no03aa.4732.8626.304816.3.29_0 finished
2007-02-21 06:23:23 [SETI@home] Resuming task 19au03aa.16709.20786.704834.3.98_1 using setiathome_enhanced version 519
. 2007-02-21 06:23:24 [SETI@home] Computation for task 11no03aa.4732.8626.304816.3.23_0 finished
2007-02-21 06:23:25 [SETI@home] [file_xfer] Started upload of file 11no03aa.4732.8626.304816.3.29_0_0
. 2007-02-21 06:23:25 [SETI@home] Starting 11no03aa.4732.8626.304816.3.23_0
. 2007-02-21 06:23:25 [SETI@home] Starting task 11no03aa.4732.8626.304816.3.23_0 using setiathome_enhanced version 519
. 2007-02-21 06:23:26 [SETI@home] [file_xfer] Started upload of file 11no03aa.4732.8626.304816.3.23_0_0
2007-02-21 06:23:27 [SETI@home] [file_xfer] Finished upload of file 11no03aa.4732.8626.304816.3.29_0_0
2007-02-21 06:23:27 [SETI@home] [file_xfer] Throughput 51216 bytes/sec
2007-02-21 06:23:28 [SETI@home] [error] Error on file upload: socket read incomplete: asked for 12226, got 7426: No such file or directory
. 2007-02-21 06:23:28 [SETI@home] [file_xfer] Temporarily failed upload of 11no03aa.4732.8626.304816.3.23_0_0: transient upload error
. 2007-02-21 06:23:28 [SETI@home] Backing off 1 min 0 sec on upload of file 11no03aa.4732.8626.304816.3.23_0_0
. 2007-02-21 06:24:28 [SETI@home] [file_xfer] Started upload of file 11no03aa.4732.8626.304816.3.23_0_0
2007-02-21 06:24:30 [SETI@home] [error] Error on file upload: socket read incomplete: asked for 12226, got 8963: No such file or directory
. 2007-02-21 06:24:30 [SETI@home] [file_xfer] Temporarily failed upload of 11no03aa.4732.8626.304816.3.23_0_0: transient upload error
. 2007-02-21 06:24:30 [SETI@home] Backing off 1 min 0 sec on upload of file 11no03aa.4732.8626.304816.3.23_0_0
2007-02-21 06:25:24 [SETI@home] Sending scheduler request: To fetch work
2007-02-21 06:25:24 [SETI@home] Requesting 13653 seconds of new work, and reporting 7 completed tasks
2007-02-21 06:25:29 [SETI@home] Scheduler RPC succeeded [server version 507]
2007-02-21 06:25:29 [SETI@home] Deferring communication for 11 sec
2007-02-21 06:25:29 [SETI@home] Reason: requested by project
2007-02-21 06:25:30 [SETI@home] [error] garbage_collect(); still have active task for acked result 11no03aa.4732.8626.304816.3.19_3; state 9
. 2007-02-21 06:25:30 [SETI@home] [file_xfer] Started upload of file 11no03aa.4732.8626.304816.3.23_0_0
2007-02-21 06:25:30 [SETI@home] Computation for task 11no03aa.4732.8626.304816.3.19_3 finished
2007-02-21 06:25:36 [SETI@home] [file_xfer] Started download of file 15au03aa.20522.4642.779818.3.216
2007-02-21 06:25:38 [SETI@home] [error] Error on file upload: socket read incomplete: asked for 12226, got 8963: No such file or directory
. 2007-02-21 06:25:38 [SETI@home] [file_xfer] Temporarily failed upload of 11no03aa.4732.8626.304816.3.23_0_0: transient upload error
. 2007-02-21 06:25:38 [SETI@home] Backing off 1 min 0 sec on upload of file 11no03aa.4732.8626.304816.3.23_0_0

---