Index  | Recent Threads  | Unanswered Threads  | Who's Active  | Guidelines  | Search
 
World Community Grid Forums
  Category: Support
  Forum: BOINC Agent Support
  Thread: Incredibly short lasting completed WU after restart boinc-client

Quick Go ยป
No member browsing this thread
Thread Status: Active
Total posts in this thread: 1
[ Jump to Last Post ]
Post new Thread
Author
Previous Thread This topic has been viewed 1115 times and has 0 replies Next Thread
adriverhoef
Master Cruncher
The Netherlands
Joined: Apr 3, 2009
Post Count: 2198
Status: Offline
Project Badges:
Reply to this Post  Reply with Quote 
Incredibly short lasting completed WU after restart boinc-client
Hello,
While installing a new version of the BOINC software (7.14.2) on Fedora 28 I experienced something very weird. During the on-going installation, I noticed that one job was about to be finished after running for nearly 55 minutes. (According to the logs, that job finished at 16:25:18, that was 1 second before the installation of the new BOINC version.) During/after the installation, BOINC Manager was restarted at 16:25:19, benchmarks started running at 16:25:20, its results were ready 32 seconds later (16:25:52), and then 3 seconds later:

Thu 07 Mar 2019 16:25:55 CET | World Community Grid | Computation for task ZIKA_000401867_x5vig_ZIKV_E_DIII_antibody_0433_0 finished
Thu 07 Mar 2019 16:25:56 CET | World Community Grid | Sending scheduler request: To fetch work.
Thu 07 Mar 2019 16:25:56 CET | World Community Grid | Requesting new tasks for CPU
Thu 07 Mar 2019 16:25:57 CET | World Community Grid | Started upload of ZIKA_000401867_x5vig_ZIKV_E_DIII_antibody_0433_0_r891496409_0
Thu 07 Mar 2019 16:25:58 CET | World Community Grid | Scheduler request completed: got 0 new tasks
Thu 07 Mar 2019 16:25:58 CET | World Community Grid | Not sending work - last request too recent: 50 sec
Thu 07 Mar 2019 16:26:00 CET | World Community Grid | Finished upload of ZIKA_000401867_x5vig_ZIKV_E_DIII_antibody_0433_0_r891496409_0

So far, so good. However, when looking at the Results Status, it says:
Result Name                                        
ZIKA_000401867_x5vig_ZIKV_E_DIII_antibody_0433_0-- 
OS           OS Version                                                              AVN Status Sent Time         Due / Return Time CPUh  Claimed/Gr.
Linux Fedora Fedora 28 (Twenty Eight) [4.17.14-202.fc28.x86_64|libc 2.27 (GNU libc)] 720 Valid    3/5/19 06:29:56   3/7/19 15:28:05  0.00     0.0/0.0


Finished in 0 seconds! That can't be true crying, that is incorrect sad! When I open the Result Log it says that it has been running for nearly 55 minutes, which is quite normal and correct:


Result Log

Result Name: ZIKA_ 000401867_ x5vig_ ZIKV_ E_ DIII_ antibody_ 0433_ 0--
<core_client_version>7.14.2</core_client_version>
<![CDATA[
<stderr_txt>
INFO: result number = 0
INFO: No state to restore. Start from the beginning.
[15:31:01] Number of tasks = 28
[15:31:01] Running task 0,CPU time at start of task 0 was 0.000000
[15:31:01] ./ZINC000288975600.pdbqt size = 26 8 ../../projects/www.worldcommunitygrid.org/zika.x5vig_ZIKV_E_DIII_antibody.pdbqt size = 9636 0
[15:33:47] Finished task #0 cpu time used 147.924674
[15:33:47] Running task 1,CPU time at start of task 1 was 147.924674
[15:33:47] ./ZINC000288975601.pdbqt size = 26 5 ../../projects/www.worldcommunitygrid.org/zika.x5vig_ZIKV_E_DIII_antibody.pdbqt size = 9636 0
[15:36:10] Finished task #1 cpu time used 131.359360
[15:36:10] Running task 2,CPU time at start of task 2 was 279.284034
[15:36:10] ./ZINC000288975606.pdbqt size = 26 5 ../../projects/www.worldcommunitygrid.org/zika.x5vig_ZIKV_E_DIII_antibody.pdbqt size = 9636 0
[15:38:16] Finished task #2 cpu time used 126.096266
[15:38:16] Running task 3,CPU time at start of task 3 was 405.380300
[15:38:16] ./ZINC000288975638.pdbqt size = 25 5 ../../projects/www.worldcommunitygrid.org/zika.x5vig_ZIKV_E_DIII_antibody.pdbqt size = 9636 0
[15:40:08] Finished task #3 cpu time used 110.200625
[15:40:08] Running task 4,CPU time at start of task 4 was 515.580925
[15:40:08] ./ZINC000288975671.pdbqt size = 25 8 ../../projects/www.worldcommunitygrid.org/zika.x5vig_ZIKV_E_DIII_antibody.pdbqt size = 9636 0
[15:42:25] Finished task #4 cpu time used 136.318228
[15:42:25] Running task 5,CPU time at start of task 5 was 651.899153
[15:42:25] ./ZINC000288975674.pdbqt size = 25 8 ../../projects/www.worldcommunitygrid.org/zika.x5vig_ZIKV_E_DIII_antibody.pdbqt size = 9636 0
[15:44:43] Finished task #5 cpu time used 136.372867
[15:44:43] Running task 6,CPU time at start of task 6 was 788.272020
[15:44:43] ./ZINC000288975767.pdbqt size = 26 5 ../../projects/www.worldcommunitygrid.org/zika.x5vig_ZIKV_E_DIII_antibody.pdbqt size = 9636 0
[15:46:40] Finished task #6 cpu time used 116.433406
[15:46:40] Running task 7,CPU time at start of task 7 was 904.705426
[15:46:40] ./ZINC000288975771.pdbqt size = 26 5 ../../projects/www.worldcommunitygrid.org/zika.x5vig_ZIKV_E_DIII_antibody.pdbqt size = 9636 0
[15:48:35] Finished task #7 cpu time used 114.503500
[15:48:35] Running task 8,CPU time at start of task 8 was 1019.208926
[15:48:35] ./ZINC000288975814.pdbqt size = 24 4 ../../projects/www.worldcommunitygrid.org/zika.x5vig_ZIKV_E_DIII_antibody.pdbqt size = 9636 0
[15:50:00] Finished task #8 cpu time used 83.620772
[15:50:00] Running task 9,CPU time at start of task 9 was 1102.829698
[15:50:00] ./ZINC000288975823.pdbqt size = 21 4 ../../projects/www.worldcommunitygrid.org/zika.x5vig_ZIKV_E_DIII_antibody.pdbqt size = 9636 0
[15:51:12] Finished task #9 cpu time used 72.562701
[15:51:12] Running task 10,CPU time at start of task 10 was 1175.392399
[15:51:12] ./ZINC000288975898.pdbqt size = 26 6 ../../projects/www.worldcommunitygrid.org/zika.x5vig_ZIKV_E_DIII_antibody.pdbqt size = 9636 0
[15:53:22] Finished task #10 cpu time used 129.886641
[15:53:22] Running task 11,CPU time at start of task 11 was 1305.279040
[15:53:22] ./ZINC000288975928.pdbqt size = 26 8 ../../projects/www.worldcommunitygrid.org/zika.x5vig_ZIKV_E_DIII_antibody.pdbqt size = 9636 0
[15:56:16] Finished task #11 cpu time used 167.471957
[15:56:16] Running task 12,CPU time at start of task 12 was 1472.750997
[15:56:16] ./ZINC000288975932.pdbqt size = 26 8 ../../projects/www.worldcommunitygrid.org/zika.x5vig_ZIKV_E_DIII_antibody.pdbqt size = 9636 0
[15:59:07] Finished task #12 cpu time used 167.775341
[15:59:07] Running task 13,CPU time at start of task 13 was 1640.526338
[15:59:07] ./ZINC000288975991.pdbqt size = 24 3 ../../projects/www.worldcommunitygrid.org/zika.x5vig_ZIKV_E_DIII_antibody.pdbqt size = 9636 0
[16:00:17] Finished task #13 cpu time used 69.218073
[16:00:17] Running task 14,CPU time at start of task 14 was 1709.744411
[16:00:17] ./ZINC000288976008.pdbqt size = 24 6 ../../projects/www.worldcommunitygrid.org/zika.x5vig_ZIKV_E_DIII_antibody.pdbqt size = 9636 0
[16:02:22] Finished task #14 cpu time used 124.999319
[16:02:22] Running task 15,CPU time at start of task 15 was 1834.743730
[16:02:22] ./ZINC000288976013.pdbqt size = 25 6 ../../projects/www.worldcommunitygrid.org/zika.x5vig_ZIKV_E_DIII_antibody.pdbqt size = 9636 0
[16:04:23] Finished task #15 cpu time used 119.436312
[16:04:23] Running task 16,CPU time at start of task 16 was 1954.180042
[16:04:23] ./ZINC000288976017.pdbqt size = 25 6 ../../projects/www.worldcommunitygrid.org/zika.x5vig_ZIKV_E_DIII_antibody.pdbqt size = 9636 0
[16:06:25] Finished task #16 cpu time used 120.769414
[16:06:25] Running task 17,CPU time at start of task 17 was 2074.949456
[16:06:25] ./ZINC000288976094.pdbqt size = 26 6 ../../projects/www.worldcommunitygrid.org/zika.x5vig_ZIKV_E_DIII_antibody.pdbqt size = 9636 0
[16:08:47] Finished task #17 cpu time used 140.461498
[16:08:47] Running task 18,CPU time at start of task 18 was 2215.410954
[16:08:47] ./ZINC000288976096.pdbqt size = 26 6 ../../projects/www.worldcommunitygrid.org/zika.x5vig_ZIKV_E_DIII_antibody.pdbqt size = 9636 0
[16:11:04] Finished task #18 cpu time used 136.745224
[16:11:04] Running task 19,CPU time at start of task 19 was 2352.156178
[16:11:04] ./ZINC000288976131.pdbqt size = 23 3 ../../projects/www.worldcommunitygrid.org/zika.x5vig_ZIKV_E_DIII_antibody.pdbqt size = 9636 0
[16:12:22] Finished task #19 cpu time used 76.285367
[16:12:22] Running task 20,CPU time at start of task 20 was 2428.441545
[16:12:22] ./ZINC000288976134.pdbqt size = 23 3 ../../projects/www.worldcommunitygrid.org/zika.x5vig_ZIKV_E_DIII_antibody.pdbqt size = 9636 0
[16:13:39] Finished task #20 cpu time used 77.320334
[16:13:39] Running task 21,CPU time at start of task 21 was 2505.761879
[16:13:39] ./ZINC000288976288.pdbqt size = 24 5 ../../projects/www.worldcommunitygrid.org/zika.x5vig_ZIKV_E_DIII_antibody.pdbqt size = 9636 0
[16:15:12] Finished task #21 cpu time used 91.444270
[16:15:12] Running task 22,CPU time at start of task 22 was 2597.206149
[16:15:12] ./ZINC000288976413.pdbqt size = 27 5 ../../projects/www.worldcommunitygrid.org/zika.x5vig_ZIKV_E_DIII_antibody.pdbqt size = 9636 0
[16:17:08] Finished task #22 cpu time used 116.100478
[16:17:08] Running task 23,CPU time at start of task 23 was 2713.306627
[16:17:08] ./ZINC000288976416.pdbqt size = 27 5 ../../projects/www.worldcommunitygrid.org/zika.x5vig_ZIKV_E_DIII_antibody.pdbqt size = 9636 0
[16:19:06] Finished task #23 cpu time used 115.962720
[16:19:06] Running task 24,CPU time at start of task 24 was 2829.269347
[16:19:06] ./ZINC000288976491.pdbqt size = 24 5 ../../projects/www.worldcommunitygrid.org/zika.x5vig_ZIKV_E_DIII_antibody.pdbqt size = 9636 0
[16:20:57] Finished task #24 cpu time used 110.420345
[16:20:57] Running task 25,CPU time at start of task 25 was 2939.689692
[16:20:57] ./ZINC000288976512.pdbqt size = 20 4 ../../projects/www.worldcommunitygrid.org/zika.x5vig_ZIKV_E_DIII_antibody.pdbqt size = 9636 0
[16:22:04] Finished task #25 cpu time used 66.237691
[16:22:04] Running task 26,CPU time at start of task 26 was 3005.927383
[16:22:04] ./ZINC000288976515.pdbqt size = 20 4 ../../projects/www.worldcommunitygrid.org/zika.x5vig_ZIKV_E_DIII_antibody.pdbqt size = 9636 0
[16:23:11] Finished task #26 cpu time used 66.007466
[16:23:11] Running task 27,CPU time at start of task 27 was 3071.934849
[16:23:11] ./ZINC000288976567.pdbqt size = 24 7 ../../projects/www.worldcommunitygrid.org/zika.x5vig_ZIKV_E_DIII_antibody.pdbqt size = 9636 0
[16:25:17] Finished task #27 cpu time used 124.920236
16:25:18 (15146): called boinc_finish(0)
INFO: result number = 0
[16:25:53] Number of tasks = 28
16:25:53 (17118): called boinc_finish(0)

</stderr_txt>
]]>
Close

Return to Top
===========

Additional info:
===========
(from the API)
        {
           "AppName": "zika",
           "ClaimedCredit": 0.0251652871681735,
           "CpuTime": 2.285833333333333E-6,
           "ElapsedTime": 3.550486111111111E-4,
           "ExitStatus": 0,
           "GrantedCredit": 0.0251652871681735,
           "DeviceId": 4377017,
           "DeviceName": "i7-8700-b",
           "ModTime": 1551972488,
           "WorkunitId": 1018254292,
           "ResultId": 837271799,
           "Name": "ZIKA_000401867_x5vig_ZIKV_E_DIII_antibody_0433_0",
           "Outcome": 1,
           "ReceivedTime": "2019-03-07T15:28:05",
           "ReportDeadline": "2019-03-15T06:29:56",
           "SentTime": "2019-03-05T06:29:56",
           "ServerState": 5,
           "ValidateState": 1,
           "FileDeleteState": 0
        }

===========
(from /var/lib/boinc/job_log_www.worldcommunitygrid.org.txt)
1551972356 ue 2893.084086 ct 0.008229 fe 19651594736062 nm ZIKA_000401867_x5vig_ZIKV_E_DIII_antibody_0433_0 et 1.278175 es 0
===========
(1551972356 = Thu 7 Mar 16:25:56 CET 2019 = Thu 7 Mar 15:25:56 UTC 2019)
===========
Remaining jobs are running normally. It's just this one that behaved like an anomaly.
----------------------------------------
[Edit 6 times, last edit by adriverhoef at Mar 8, 2019 2:15:48 PM]
[Mar 7, 2019 6:06:32 PM]   Link   Report threatening or abusive post: please login first  Go to top 
[ Jump to Last Post ]
Post new Thread