Task 2953058

Name	Theory_2390-1144375-174_0
Workunit	2059697
Created	12 Feb 2021, 1:20:14 UTC
Sent	14 Feb 2021, 5:23:51 UTC
Report deadline	24 Feb 2021, 5:23:51 UTC
Received	17 Feb 2021, 15:36:52 UTC
Server state	Over
Outcome	Computation error
Client state	Compute error
Exit status	1 (0x00000001) Unknown error code
Computer ID	4073
Run time	2 days 15 hours 41 min 5 sec
CPU time	15 min 37 sec
Validate state	Invalid
Credit	0.00
Device peak FLOPS	3.44 GFLOPS
Application version	Theory Simulation v5.21 (vbox64_theory) windows_x86_64
Peak working set size	73.34 MB
Peak swap size	92.90 MB
Peak disk usage	1.02 GB
Stderr output

<core_client_version>7.16.11</core_client_version>
<![CDATA[
<message>
 - exit code 1 (0x1)</message>
<stderr_txt>
2021-02-14 18:49:17 (12748): Detected: vboxwrapper 26197
2021-02-14 18:49:17 (12748): Detected: BOINC client v7.7
2021-02-14 18:49:18 (12748): Detected: VirtualBox VboxManage Interface (Version: 6.1.18)
2021-02-14 18:49:18 (12748): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2021-02-14 18:49:18 (12748): Successfully copied 'init_data.xml' to the shared directory.
2021-02-14 18:49:18 (12748): Successfully copied 'input' to the shared directory.
2021-02-14 18:49:20 (12748): Create VM. (boinc_9e5c7654eb6b36df, slot#5)
2021-02-14 18:49:21 (12748): Setting Memory Size for VM. (630MB)
2021-02-14 18:49:21 (12748): Setting CPU Count for VM. (1)
2021-02-14 18:49:21 (12748): Setting Chipset Options for VM.
2021-02-14 18:49:21 (12748): Setting Boot Options for VM.
2021-02-14 18:49:22 (12748): Setting Network Configuration for NAT.
2021-02-14 18:49:22 (12748): Enabling VM Network Access.
2021-02-14 18:49:22 (12748): Disabling USB Support for VM.
2021-02-14 18:49:23 (12748): Disabling COM Port Support for VM.
2021-02-14 18:49:23 (12748): Disabling LPT Port Support for VM.
2021-02-14 18:49:23 (12748): Disabling Audio Support for VM.
2021-02-14 18:49:23 (12748): Disabling Clipboard Support for VM.
2021-02-14 18:49:24 (12748): Disabling Drag and Drop Support for VM.
2021-02-14 18:49:24 (12748): Adding storage controller(s) to VM.
2021-02-14 18:49:24 (12748): Adding virtual disk drive to VM. (vm_image.vdi)
2021-02-14 18:49:25 (12748): Adding VirtualBox Guest Additions to VM.
2021-02-14 18:49:25 (12748): Adding network bandwidth throttle group to VM. (Defaulting to 1024GB)
2021-02-14 18:49:25 (12748): forwarding host port 23444 to guest port 80
2021-02-14 18:49:26 (12748): Enabling remote desktop for VM.
2021-02-14 18:49:26 (12748): Enabling shared directory for VM.
2021-02-14 18:49:26 (12748): Starting VM using VBoxManage interface. (boinc_9e5c7654eb6b36df, slot#5)
2021-02-14 18:49:32 (12748): Successfully started VM. (PID = '380')
2021-02-14 18:49:32 (12748): Reporting VM Process ID to BOINC.
2021-02-14 18:49:32 (12748): Guest Log: BIOS: VirtualBox 6.1.18

2021-02-14 18:49:32 (12748): Guest Log: CPUID EDX: 0x178bfbff

2021-02-14 18:49:32 (12748): Guest Log: BIOS: ata0-0: PCHS=16383/16/63 LCHS=1024/255/63

2021-02-14 18:49:32 (12748): VM state change detected. (old = 'PoweredOff', new = 'Running')
2021-02-14 18:49:32 (12748): Detected: Web Application Enabled (http://localhost:23444)
2021-02-14 18:49:32 (12748): Detected: Remote Desktop Enabled (localhost:23445)
2021-02-14 18:49:32 (12748): Preference change detected
2021-02-14 18:49:32 (12748): Setting CPU throttle for VM. (99%)
2021-02-14 18:49:33 (12748): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 400 seconds) or (Vbox_job.xml: 600 seconds))
2021-02-14 18:49:34 (12748): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032

2021-02-14 18:49:34 (12748): Guest Log: BIOS: Booting from Hard Disk...

2021-02-14 18:49:37 (12748): Guest Log: BIOS: KBD: unsupported int 16h function 03

2021-02-14 18:49:37 (12748): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 

2021-02-14 18:49:58 (12748): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds

2021-02-14 18:49:58 (12748): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000)

2021-02-14 18:50:01 (12748): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log

2021-02-14 18:50:01 (12748): Guest Log: 00:00:00.000093 main     Log opened 2021-02-14T13:50:01.156708000Z

2021-02-14 18:50:01 (12748): Guest Log: 00:00:00.000212 main     OS Product: Linux

2021-02-14 18:50:01 (12748): Guest Log: 00:00:00.000250 main     OS Release: 4.14.76-13.cernvm.x86_64

2021-02-14 18:50:01 (12748): Guest Log: 00:00:00.000285 main     OS Version: #1 SMP Tue Oct 16 18:26:15 CEST 2018

2021-02-14 18:50:01 (12748): Guest Log: 00:00:00.000328 main     Executable: /usr/sbin/VBoxService

2021-02-14 18:50:01 (12748): Guest Log: 00:00:00.000329 main     Process ID: 3201

2021-02-14 18:50:01 (12748): Guest Log: 00:00:00.000329 main     Package type: LINUX_64BITS_GENERIC

2021-02-14 18:50:01 (12748): Guest Log: 00:00:00.003641 main     5.2.6 r120293 started. Verbose level = 0

2021-02-14 18:51:37 (12748): Guest Log: 16:51:36 MSK +03:00 2021-02-14: cranky: [INFO] Detected Theory App

2021-02-14 18:51:37 (12748): Guest Log: 16:51:36 MSK +03:00 2021-02-14: cranky: [INFO] Checking CVMFS.

2021-02-14 18:51:38 (12748): Guest Log: Probing /cvmfs/sft.cern.ch... Failed!

2021-02-14 18:51:38 (12748): Guest Log: 16:51:36 MSK +03:00 2021-02-14: cranky: [ERROR] 'cvmfs_config probe sft.cern.ch' failed.

2021-02-14 20:07:02 (12748): Stopping VM.
2021-02-14 20:56:54 (14012): Detected: vboxwrapper 26197
2021-02-14 20:56:54 (14012): Detected: BOINC client v7.7
2021-02-14 20:56:55 (14012): Detected: VirtualBox VboxManage Interface (Version: 6.1.18)
2021-02-14 20:56:55 (14012): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2021-02-14 20:56:56 (14012): Starting VM using VBoxManage interface. (boinc_9e5c7654eb6b36df, slot#5)
2021-02-14 20:57:06 (14012): Successfully started VM. (PID = '8704')
2021-02-14 20:57:06 (14012): Reporting VM Process ID to BOINC.
2021-02-14 20:57:06 (14012): VM state change detected. (old = 'PoweredOff', new = 'Running')
2021-02-14 20:57:06 (14012): Detected: Web Application Enabled (http://localhost:23444)
2021-02-14 20:57:06 (14012): Detected: Remote Desktop Enabled (localhost:23445)
2021-02-14 20:57:07 (14012): Preference change detected
2021-02-14 20:57:07 (14012): Setting CPU throttle for VM. (99%)
2021-02-14 20:57:07 (14012): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 400 seconds) or (Vbox_job.xml: 600 seconds))
2021-02-14 20:57:15 (14012): Guest Log: 01:17:10.644353 timesync vgsvcTimeSyncWorker: Radical host time change: 3 013 012 000 000ns (HostNow=1 613 318 234 810 000 000 ns HostLast=1 613 315 221 798 000 000 ns)

2021-02-14 20:57:25 (14012): Guest Log: 01:17:20.646393 timesync vgsvcTimeSyncWorker: Radical guest time change: 3 013 011 168 000ns (GuestNow=1 613 318 244 812 069 000 ns GuestLast=1 613 315 231 800 901 000 ns fSetTimeLastLoop=true )

2021-02-14 21:19:40 (14012): Status Report: Job Duration: '864000.000000'
2021-02-14 21:19:40 (14012): Status Report: Elapsed Time: '6000.000000'
2021-02-14 21:19:40 (14012): Status Report: CPU Time: '53.015625'
2021-02-14 21:47:31 (14012): Stopping VM.
2021-02-14 22:25:46 (15224): Detected: vboxwrapper 26197
2021-02-14 22:25:46 (15224): Detected: BOINC client v7.7
2021-02-14 22:25:47 (15224): Detected: VirtualBox VboxManage Interface (Version: 6.1.18)
2021-02-14 22:25:47 (15224): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2021-02-14 22:25:48 (15224): Starting VM using VBoxManage interface. (boinc_9e5c7654eb6b36df, slot#5)
2021-02-14 22:25:58 (15224): Successfully started VM. (PID = '3944')
2021-02-14 22:25:58 (15224): Reporting VM Process ID to BOINC.
2021-02-14 22:25:58 (15224): VM state change detected. (old = 'PoweredOff', new = 'Running')
2021-02-14 22:25:58 (15224): Detected: Web Application Enabled (http://localhost:23444)
2021-02-14 22:25:58 (15224): Detected: Remote Desktop Enabled (localhost:23445)
2021-02-14 22:25:58 (15224): Status Report: Job Duration: '864000.000000'
2021-02-14 22:25:58 (15224): Status Report: Elapsed Time: '7670.000000'
2021-02-14 22:25:58 (15224): Status Report: CPU Time: '58.234375'
2021-02-14 22:25:58 (15224): Preference change detected
2021-02-14 22:25:58 (15224): Setting CPU throttle for VM. (99%)
2021-02-14 22:25:58 (15224): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 400 seconds) or (Vbox_job.xml: 600 seconds))
2021-02-14 22:26:01 (15224): Guest Log: 02:07:31.006617 timesync vgsvcTimeSyncWorker: Radical host time change: 2 315 697 000 000ns (HostNow=1 613 323 560 867 000 000 ns HostLast=1 613 321 245 170 000 000 ns)

2021-02-14 22:26:11 (15224): Guest Log: 02:07:41.007719 timesync vgsvcTimeSyncWorker: Radical guest time change: 2 315 695 845 000ns (GuestNow=1 613 323 570 868 114 000 ns GuestLast=1 613 321 255 172 269 000 ns fSetTimeLastLoop=true )

2021-02-14 23:03:20 (15224): Stopping VM.
2021-02-14 23:39:52 (7388): Detected: vboxwrapper 26197
2021-02-14 23:39:52 (7388): Detected: BOINC client v7.7
2021-02-14 23:39:53 (7388): Detected: VirtualBox VboxManage Interface (Version: 6.1.18)
2021-02-14 23:39:53 (7388): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2021-02-14 23:39:54 (7388): Starting VM using VBoxManage interface. (boinc_9e5c7654eb6b36df, slot#5)
2021-02-14 23:40:04 (7388): Successfully started VM. (PID = '10324')
2021-02-14 23:40:04 (7388): Reporting VM Process ID to BOINC.
2021-02-14 23:40:04 (7388): Guest Log: 02:44:51.299819 timesync vgsvcTimeSyncWorker: Radical host time change: 2 213 223 000 000ns (HostNow=1 613 328 004 382 000 000 ns HostLast=1 613 325 791 159 000 000 ns)

2021-02-14 23:40:04 (7388): VM state change detected. (old = 'PoweredOff', new = 'Running')
2021-02-14 23:40:04 (7388): Detected: Web Application Enabled (http://localhost:23444)
2021-02-14 23:40:04 (7388): Detected: Remote Desktop Enabled (localhost:23445)
2021-02-14 23:40:04 (7388): Status Report: Job Duration: '864000.000000'
2021-02-14 23:40:04 (7388): Status Report: Elapsed Time: '9910.000000'
2021-02-14 23:40:04 (7388): Status Report: CPU Time: '73.656250'
2021-02-14 23:40:04 (7388): Preference change detected
2021-02-14 23:40:04 (7388): Setting CPU throttle for VM. (99%)
2021-02-14 23:40:04 (7388): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 400 seconds) or (Vbox_job.xml: 600 seconds))
2021-02-14 23:40:14 (7388): Guest Log: 02:45:01.301817 timesync vgsvcTimeSyncWorker: Radical guest time change: 2 213 223 899 000ns (GuestNow=1 613 328 014 384 035 000 ns GuestLast=1 613 325 801 160 136 000 ns fSetTimeLastLoop=true )

2021-02-15 00:16:26 (7388): Stopping VM.
2021-02-15 01:05:57 (8656): Detected: vboxwrapper 26197
2021-02-15 01:05:57 (8656): Detected: BOINC client v7.7
2021-02-15 01:05:58 (8656): Detected: VirtualBox VboxManage Interface (Version: 6.1.18)
2021-02-15 01:05:58 (8656): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2021-02-15 01:05:59 (8656): Starting VM using VBoxManage interface. (boinc_9e5c7654eb6b36df, slot#5)
2021-02-15 01:06:08 (8656): Successfully started VM. (PID = '6040')
2021-02-15 01:06:08 (8656): Reporting VM Process ID to BOINC.
2021-02-15 01:06:08 (8656): VM state change detected. (old = 'PoweredOff', new = 'Running')
2021-02-15 01:06:08 (8656): Detected: Web Application Enabled (http://localhost:23444)
2021-02-15 01:06:08 (8656): Detected: Remote Desktop Enabled (localhost:23445)
2021-02-15 01:06:08 (8656): Status Report: Job Duration: '864000.000000'
2021-02-15 01:06:08 (8656): Status Report: Elapsed Time: '12091.000000'
2021-02-15 01:06:08 (8656): Status Report: CPU Time: '88.000000'
2021-02-15 01:06:08 (8656): Preference change detected
2021-02-15 01:06:08 (8656): Setting CPU throttle for VM. (99%)
2021-02-15 01:06:08 (8656): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 400 seconds) or (Vbox_job.xml: 600 seconds))
2021-02-15 01:06:15 (8656): Guest Log: 03:21:21.600790 timesync vgsvcTimeSyncWorker: Radical host time change: 2 990 623 000 000ns (HostNow=1 613 333 175 303 000 000 ns HostLast=1 613 330 184 680 000 000 ns)

2021-02-15 01:06:25 (8656): Guest Log: 03:21:31.602830 timesync vgsvcTimeSyncWorker: Radical guest time change: 2 990 622 058 000ns (GuestNow=1 613 333 185 305 047 000 ns GuestLast=1 613 330 194 682 989 000 ns fSetTimeLastLoop=true )

2021-02-15 01:55:35 (8656): Stopping VM.
2021-02-15 02:08:11 (6004): Detected: vboxwrapper 26197
2021-02-15 02:08:11 (6004): Detected: BOINC client v7.7
2021-02-15 02:08:12 (6004): Detected: VirtualBox VboxManage Interface (Version: 6.1.18)
2021-02-15 02:08:13 (6004): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2021-02-15 02:08:13 (6004): Starting VM using VBoxManage interface. (boinc_9e5c7654eb6b36df, slot#5)
2021-02-15 02:08:23 (6004): Successfully started VM. (PID = '3296')
2021-02-15 02:08:23 (6004): Reporting VM Process ID to BOINC.
2021-02-15 02:08:23 (6004): VM state change detected. (old = 'PoweredOff', new = 'Running')
2021-02-15 02:08:23 (6004): Detected: Web Application Enabled (http://localhost:23444)
2021-02-15 02:08:23 (6004): Detected: Remote Desktop Enabled (localhost:23445)
2021-02-15 02:08:23 (6004): Status Report: Job Duration: '864000.000000'
2021-02-15 02:08:23 (6004): Status Report: Elapsed Time: '15056.000000'
2021-02-15 02:08:23 (6004): Status Report: CPU Time: '103.562500'
2021-02-15 02:08:23 (6004): Preference change detected
2021-02-15 02:08:23 (6004): Setting CPU throttle for VM. (99%)
2021-02-15 02:08:23 (6004): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 400 seconds) or (Vbox_job.xml: 600 seconds))
2021-02-15 02:41:45 (6004): Stopping VM.
2021-02-15 03:05:50 (11596): Detected: vboxwrapper 26197
2021-02-15 03:05:50 (11596): Detected: BOINC client v7.7
2021-02-15 03:05:51 (11596): Detected: VirtualBox VboxManage Interface (Version: 6.1.18)
2021-02-15 03:05:51 (11596): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2021-02-15 03:05:52 (11596): Starting VM using VBoxManage interface. (boinc_9e5c7654eb6b36df, slot#5)
2021-02-15 03:06:01 (11596): Successfully started VM. (PID = '15508')
2021-02-15 03:06:01 (11596): Reporting VM Process ID to BOINC.
2021-02-15 03:06:01 (11596): VM state change detected. (old = 'PoweredOff', new = 'Running')
2021-02-15 03:06:01 (11596): Detected: Web Application Enabled (http://localhost:23444)
2021-02-15 03:06:01 (11596): Detected: Remote Desktop Enabled (localhost:23445)
2021-02-15 03:06:01 (11596): Status Report: Job Duration: '864000.000000'
2021-02-15 03:06:01 (11596): Status Report: Elapsed Time: '17057.000000'
2021-02-15 03:06:01 (11596): Status Report: CPU Time: '117.125000'
2021-02-15 03:06:01 (11596): Preference change detected
2021-02-15 03:06:01 (11596): Setting CPU throttle for VM. (99%)
2021-02-15 03:06:01 (11596): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 400 seconds) or (Vbox_job.xml: 600 seconds))
2021-02-15 03:39:23 (11596): Stopping VM.
2021-02-15 04:29:29 (16504): Detected: vboxwrapper 26197
2021-02-15 04:29:29 (16504): Detected: BOINC client v7.7
2021-02-15 04:29:30 (16504): Detected: VirtualBox VboxManage Interface (Version: 6.1.18)
2021-02-15 04:29:30 (16504): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2021-02-15 04:29:31 (16504): Starting VM using VBoxManage interface. (boinc_9e5c7654eb6b36df, slot#5)
2021-02-15 04:29:39 (16504): Successfully started VM. (PID = '4084')
2021-02-15 04:29:39 (16504): Reporting VM Process ID to BOINC.
2021-02-15 04:29:39 (16504): VM state change detected. (old = 'PoweredOff', new = 'Running')
2021-02-15 04:29:39 (16504): Detected: Web Application Enabled (http://localhost:23444)
2021-02-15 04:29:39 (16504): Detected: Remote Desktop Enabled (localhost:23445)
2021-02-15 04:29:39 (16504): Status Report: Job Duration: '864000.000000'
2021-02-15 04:29:39 (16504): Status Report: Elapsed Time: '19058.000000'
2021-02-15 04:29:39 (16504): Status Report: CPU Time: '129.703125'
2021-02-15 04:29:39 (16504): Preference change detected
2021-02-15 04:29:39 (16504): Setting CPU throttle for VM. (99%)
2021-02-15 04:29:40 (16504): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 400 seconds) or (Vbox_job.xml: 600 seconds))
2021-02-15 04:29:42 (16504): Guest Log: 05:17:32.528903 timesync vgsvcTimeSyncWorker: Radical host time change: 3 025 113 000 000ns (HostNow=1 613 345 382 761 000 000 ns HostLast=1 613 342 357 648 000 000 ns)

2021-02-15 04:29:52 (16504): Guest Log: 05:17:42.530969 timesync vgsvcTimeSyncWorker: Radical guest time change: 3 025 113 344 000ns (GuestNow=1 613 345 392 763 074 000 ns GuestLast=1 613 342 367 649 730 000 ns fSetTimeLastLoop=true )

2021-02-15 05:03:03 (16504): Stopping VM.
2021-02-15 05:53:06 (1112): Detected: vboxwrapper 26197
2021-02-15 05:53:06 (1112): Detected: BOINC client v7.7
2021-02-15 05:53:07 (1112): Detected: VirtualBox VboxManage Interface (Version: 6.1.18)
2021-02-15 05:53:07 (1112): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2021-02-15 05:53:08 (1112): Starting VM using VBoxManage interface. (boinc_9e5c7654eb6b36df, slot#5)
2021-02-15 05:53:17 (1112): Successfully started VM. (PID = '12772')
2021-02-15 05:53:17 (1112): Reporting VM Process ID to BOINC.
2021-02-15 05:53:17 (1112): VM state change detected. (old = 'PoweredOff', new = 'Running')
2021-02-15 05:53:17 (1112): Detected: Web Application Enabled (http://localhost:23444)
2021-02-15 05:53:17 (1112): Detected: Remote Desktop Enabled (localhost:23445)
2021-02-15 05:53:17 (1112): Status Report: Job Duration: '864000.000000'
2021-02-15 05:53:17 (1112): Status Report: Elapsed Time: '21060.000000'
2021-02-15 05:53:17 (1112): Status Report: CPU Time: '142.125000'
2021-02-15 05:53:17 (1112): Preference change detected
2021-02-15 05:53:17 (1112): Setting CPU throttle for VM. (99%)
2021-02-15 05:53:17 (1112): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 400 seconds) or (Vbox_job.xml: 600 seconds))
2021-02-15 05:53:26 (1112): Guest Log: 05:51:02.799548 timesync vgsvcTimeSyncWorker: Radical host time change: 3 022 938 000 000ns (HostNow=1 613 350 405 968 000 000 ns HostLast=1 613 347 383 030 000 000 ns)

2021-02-15 05:53:36 (1112): Guest Log: 05:51:12.800693 timesync vgsvcTimeSyncWorker: Radical guest time change: 3 022 937 518 000ns (GuestNow=1 613 350 415 969 152 000 ns GuestLast=1 613 347 393 031 634 000 ns fSetTimeLastLoop=true )

2021-02-15 06:29:40 (1112): Stopping VM.
2021-02-15 07:06:42 (8104): Detected: vboxwrapper 26197
2021-02-15 07:06:42 (8104): Detected: BOINC client v7.7
2021-02-15 07:06:43 (8104): Detected: VirtualBox VboxManage Interface (Version: 6.1.18)
2021-02-15 07:06:44 (8104): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2021-02-15 07:06:44 (8104): Starting VM using VBoxManage interface. (boinc_9e5c7654eb6b36df, slot#5)
2021-02-15 07:06:53 (8104): Successfully started VM. (PID = '12800')
2021-02-15 07:06:53 (8104): Reporting VM Process ID to BOINC.
2021-02-15 07:06:53 (8104): VM state change detected. (old = 'PoweredOff', new = 'Running')
2021-02-15 07:06:53 (8104): Detected: Web Application Enabled (http://localhost:23444)
2021-02-15 07:06:53 (8104): Detected: Remote Desktop Enabled (localhost:23445)
2021-02-15 07:06:53 (8104): Status Report: Job Duration: '864000.000000'
2021-02-15 07:06:53 (8104): Status Report: Elapsed Time: '23242.000000'
2021-02-15 07:06:53 (8104): Status Report: CPU Time: '154.562500'
2021-02-15 07:06:53 (8104): Preference change detected
2021-02-15 07:06:53 (8104): Setting CPU throttle for VM. (99%)
2021-02-15 07:06:53 (8104): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 400 seconds) or (Vbox_job.xml: 600 seconds))
2021-02-15 07:06:58 (8104): Guest Log: 06:27:23.120808 timesync vgsvcTimeSyncWorker: Radical host time change: 2 241 799 000 000ns (HostNow=1 613 354 818 086 000 000 ns HostLast=1 613 352 576 287 000 000 ns)

2021-02-15 07:07:08 (8104): Guest Log: 06:27:33.122783 timesync vgsvcTimeSyncWorker: Radical guest time change: 2 241 798 736 000ns (GuestNow=1 613 354 828 087 984 000 ns GuestLast=1 613 352 586 289 248 000 ns fSetTimeLastLoop=true )

2021-02-15 07:44:16 (8104): Stopping VM.
2021-02-15 08:22:43 (16208): Detected: vboxwrapper 26197
2021-02-15 08:22:43 (16208): Detected: BOINC client v7.7
2021-02-15 08:22:44 (16208): Detected: VirtualBox VboxManage Interface (Version: 6.1.18)
2021-02-15 08:22:44 (16208): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2021-02-15 08:22:45 (16208): Starting VM using VBoxManage interface. (boinc_9e5c7654eb6b36df, slot#5)
2021-02-15 08:22:53 (16208): Successfully started VM. (PID = '2264')
2021-02-15 08:22:53 (16208): Reporting VM Process ID to BOINC.
2021-02-15 08:22:53 (16208): VM state change detected. (old = 'PoweredOff', new = 'Running')
2021-02-15 08:22:53 (16208): Detected: Web Application Enabled (http://localhost:23444)
2021-02-15 08:22:53 (16208): Detected: Remote Desktop Enabled (localhost:23445)
2021-02-15 08:22:53 (16208): Status Report: Job Duration: '864000.000000'
2021-02-15 08:22:53 (16208): Status Report: Elapsed Time: '25484.000000'
2021-02-15 08:22:53 (16208): Status Report: CPU Time: '168.484375'
2021-02-15 08:22:53 (16208): Preference change detected
2021-02-15 08:22:53 (16208): Setting CPU throttle for VM. (99%)
2021-02-15 08:22:54 (16208): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 400 seconds) or (Vbox_job.xml: 600 seconds))
2021-02-15 08:22:54 (16208): Guest Log: 07:04:43.377763 timesync vgsvcTimeSyncWorker: Radical host time change: 2 326 021 000 000ns (HostNow=1 613 359 374 363 000 000 ns HostLast=1 613 357 048 342 000 000 ns)

2021-02-15 08:23:04 (16208): Guest Log: 07:04:53.379546 timesync vgsvcTimeSyncWorker: Radical guest time change: 2 326 021 846 000ns (GuestNow=1 613 359 384 364 790 000 ns GuestLast=1 613 357 058 342 944 000 ns fSetTimeLastLoop=true )

2021-02-15 09:01:18 (16208): Stopping VM.
2021-02-15 09:39:13 (10712): Detected: vboxwrapper 26197
2021-02-15 09:39:13 (10712): Detected: BOINC client v7.7
2021-02-15 09:39:14 (10712): Detected: VirtualBox VboxManage Interface (Version: 6.1.18)
2021-02-15 09:39:15 (10712): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2021-02-15 09:39:15 (10712): Starting VM using VBoxManage interface. (boinc_9e5c7654eb6b36df, slot#5)
2021-02-15 09:39:24 (10712): Successfully started VM. (PID = '6156')
2021-02-15 09:39:24 (10712): Reporting VM Process ID to BOINC.
2021-02-15 09:39:24 (10712): VM state change detected. (old = 'PoweredOff', new = 'Running')
2021-02-15 09:39:24 (10712): Detected: Web Application Enabled (http://localhost:23444)
2021-02-15 09:39:24 (10712): Detected: Remote Desktop Enabled (localhost:23445)
2021-02-15 09:39:24 (10712): Status Report: Job Duration: '864000.000000'
2021-02-15 09:39:24 (10712): Status Report: Elapsed Time: '27787.000000'
2021-02-15 09:39:24 (10712): Status Report: CPU Time: '182.375000'
2021-02-15 09:39:24 (10712): Preference change detected
2021-02-15 09:39:24 (10712): Setting CPU throttle for VM. (99%)
2021-02-15 09:39:24 (10712): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 400 seconds) or (Vbox_job.xml: 600 seconds))
2021-02-15 09:39:30 (10712): Guest Log: 07:43:13.710651 timesync vgsvcTimeSyncWorker: Radical host time change: 2 294 930 000 000ns (HostNow=1 613 363 969 625 000 000 ns HostLast=1 613 361 674 695 000 000 ns)

2021-02-15 09:39:40 (10712): Guest Log: 07:43:23.711698 timesync vgsvcTimeSyncWorker: Radical guest time change: 2 294 930 179 000ns (GuestNow=1 613 363 979 626 056 000 ns GuestLast=1 613 361 684 695 877 000 ns fSetTimeLastLoop=true )

2021-02-15 10:16:48 (10712): Stopping VM.
2021-02-15 10:54:25 (17264): Detected: vboxwrapper 26197
2021-02-15 10:54:25 (17264): Detected: BOINC client v7.7
2021-02-15 10:54:26 (17264): Detected: VirtualBox VboxManage Interface (Version: 6.1.18)
2021-02-15 10:54:26 (17264): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2021-02-15 10:54:27 (17264): Starting VM using VBoxManage interface. (boinc_9e5c7654eb6b36df, slot#5)
2021-02-15 10:54:36 (17264): Successfully started VM. (PID = '11888')
2021-02-15 10:54:36 (17264): Reporting VM Process ID to BOINC.
2021-02-15 10:54:36 (17264): Guest Log: 08:20:33.960352 timesync vgsvcTimeSyncWorker: Radical host time change: 2 276 086 000 000ns (HostNow=1 613 368 475 961 000 000 ns HostLast=1 613 366 199 875 000 000 ns)

2021-02-15 10:54:36 (17264): VM state change detected. (old = 'PoweredOff', new = 'Running')
2021-02-15 10:54:36 (17264): Detected: Web Application Enabled (http://localhost:23444)
2021-02-15 10:54:36 (17264): Detected: Remote Desktop Enabled (localhost:23445)
2021-02-15 10:54:36 (17264): Status Report: Job Duration: '864000.000000'
2021-02-15 10:54:36 (17264): Status Report: Elapsed Time: '30030.000000'
2021-02-15 10:54:36 (17264): Status Report: CPU Time: '195.625000'
2021-02-15 10:54:36 (17264): Preference change detected
2021-02-15 10:54:36 (17264): Setting CPU throttle for VM. (99%)
2021-02-15 10:54:36 (17264): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 400 seconds) or (Vbox_job.xml: 600 seconds))
2021-02-15 10:54:46 (17264): Guest Log: 08:20:43.961176 timesync vgsvcTimeSyncWorker: Radical guest time change: 2 276 087 217 000ns (GuestNow=1 613 368 485 961 857 000 ns GuestLast=1 613 366 209 874 640 000 ns fSetTimeLastLoop=true )

2021-02-15 12:34:39 (17264): Status Report: Job Duration: '864000.000000'
2021-02-15 12:34:39 (17264): Status Report: Elapsed Time: '36030.000000'
2021-02-15 12:34:39 (17264): Status Report: CPU Time: '223.828125'
2021-02-15 14:14:43 (17264): Status Report: Job Duration: '864000.000000'
2021-02-15 14:14:43 (17264): Status Report: Elapsed Time: '42030.000000'
2021-02-15 14:14:43 (17264): Status Report: CPU Time: '245.765625'
2021-02-15 15:54:47 (17264): Status Report: Job Duration: '864000.000000'
2021-02-15 15:54:47 (17264): Status Report: Elapsed Time: '48030.000000'
2021-02-15 15:54:47 (17264): Status Report: CPU Time: '266.859375'
2021-02-15 17:34:51 (17264): Status Report: Job Duration: '864000.000000'
2021-02-15 17:34:51 (17264): Status Report: Elapsed Time: '54030.000000'
2021-02-15 17:34:51 (17264): Status Report: CPU Time: '287.468750'
2021-02-15 19:14:55 (17264): Status Report: Job Duration: '864000.000000'
2021-02-15 19:14:55 (17264): Status Report: Elapsed Time: '60030.000000'
2021-02-15 19:14:55 (17264): Status Report: CPU Time: '307.953125'
2021-02-15 20:54:59 (17264): Status Report: Job Duration: '864000.000000'
2021-02-15 20:54:59 (17264): Status Report: Elapsed Time: '66030.000000'
2021-02-15 20:54:59 (17264): Status Report: CPU Time: '329.421875'
2021-02-15 22:35:08 (17264): Status Report: Job Duration: '864000.000000'
2021-02-15 22:35:08 (17264): Status Report: Elapsed Time: '72030.000000'
2021-02-15 22:35:08 (17264): Status Report: CPU Time: '349.937500'
2021-02-16 00:15:12 (17264): Status Report: Job Duration: '864000.000000'
2021-02-16 00:15:12 (17264): Status Report: Elapsed Time: '78030.000000'
2021-02-16 00:15:12 (17264): Status Report: CPU Time: '370.140625'
2021-02-16 01:55:16 (17264): Status Report: Job Duration: '864000.000000'
2021-02-16 01:55:16 (17264): Status Report: Elapsed Time: '84030.000000'
2021-02-16 01:55:16 (17264): Status Report: CPU Time: '390.140625'
2021-02-16 03:35:20 (17264): Status Report: Job Duration: '864000.000000'
2021-02-16 03:35:20 (17264): Status Report: Elapsed Time: '90030.000000'
2021-02-16 03:35:20 (17264): Status Report: CPU Time: '409.328125'
2021-02-16 05:15:30 (17264): Status Report: Job Duration: '864000.000000'
2021-02-16 05:15:30 (17264): Status Report: Elapsed Time: '96030.000000'
2021-02-16 05:15:30 (17264): Status Report: CPU Time: '429.250000'
2021-02-16 06:55:33 (17264): Status Report: Job Duration: '864000.000000'
2021-02-16 06:55:33 (17264): Status Report: Elapsed Time: '102030.000000'
2021-02-16 06:55:33 (17264): Status Report: CPU Time: '448.250000'
2021-02-16 08:35:36 (17264): Status Report: Job Duration: '864000.000000'
2021-02-16 08:35:36 (17264): Status Report: Elapsed Time: '108030.000000'
2021-02-16 08:35:36 (17264): Status Report: CPU Time: '467.671875'
2021-02-16 10:15:40 (17264): Status Report: Job Duration: '864000.000000'
2021-02-16 10:15:40 (17264): Status Report: Elapsed Time: '114030.000000'
2021-02-16 10:15:40 (17264): Status Report: CPU Time: '487.078125'
2021-02-16 11:55:43 (17264): Status Report: Job Duration: '864000.000000'
2021-02-16 11:55:43 (17264): Status Report: Elapsed Time: '120030.000000'
2021-02-16 11:55:43 (17264): Status Report: CPU Time: '507.093750'
2021-02-16 13:35:47 (17264): Status Report: Job Duration: '864000.000000'
2021-02-16 13:35:47 (17264): Status Report: Elapsed Time: '126030.000000'
2021-02-16 13:35:47 (17264): Status Report: CPU Time: '526.781250'
2021-02-16 15:15:50 (17264): Status Report: Job Duration: '864000.000000'
2021-02-16 15:15:50 (17264): Status Report: Elapsed Time: '132030.000000'
2021-02-16 15:15:50 (17264): Status Report: CPU Time: '546.171875'
2021-02-16 16:55:54 (17264): Status Report: Job Duration: '864000.000000'
2021-02-16 16:55:54 (17264): Status Report: Elapsed Time: '138030.000000'
2021-02-16 16:55:54 (17264): Status Report: CPU Time: '565.734375'
2021-02-16 18:35:58 (17264): Status Report: Job Duration: '864000.000000'
2021-02-16 18:35:58 (17264): Status Report: Elapsed Time: '144030.000000'
2021-02-16 18:35:58 (17264): Status Report: CPU Time: '586.718750'
2021-02-16 20:16:02 (17264): Status Report: Job Duration: '864000.000000'
2021-02-16 20:16:02 (17264): Status Report: Elapsed Time: '150030.000000'
2021-02-16 20:16:02 (17264): Status Report: CPU Time: '607.500000'
2021-02-16 21:56:05 (17264): Status Report: Job Duration: '864000.000000'
2021-02-16 21:56:05 (17264): Status Report: Elapsed Time: '156030.000000'
2021-02-16 21:56:05 (17264): Status Report: CPU Time: '627.359375'
2021-02-16 23:36:15 (17264): Status Report: Job Duration: '864000.000000'
2021-02-16 23:36:15 (17264): Status Report: Elapsed Time: '162030.000000'
2021-02-16 23:36:15 (17264): Status Report: CPU Time: '647.546875'
2021-02-17 01:16:20 (17264): Status Report: Job Duration: '864000.000000'
2021-02-17 01:16:20 (17264): Status Report: Elapsed Time: '168030.000000'
2021-02-17 01:16:20 (17264): Status Report: CPU Time: '672.937500'
2021-02-17 02:56:24 (17264): Status Report: Job Duration: '864000.000000'
2021-02-17 02:56:24 (17264): Status Report: Elapsed Time: '174030.000000'
2021-02-17 02:56:24 (17264): Status Report: CPU Time: '701.156250'
2021-02-17 04:36:41 (17264): Status Report: Job Duration: '864000.000000'
2021-02-17 04:36:41 (17264): Status Report: Elapsed Time: '180030.000000'
2021-02-17 04:36:41 (17264): Status Report: CPU Time: '729.906250'
2021-02-17 06:16:45 (17264): Status Report: Job Duration: '864000.000000'
2021-02-17 06:16:45 (17264): Status Report: Elapsed Time: '186030.000000'
2021-02-17 06:16:45 (17264): Status Report: CPU Time: '753.625000'
2021-02-17 07:56:49 (17264): Status Report: Job Duration: '864000.000000'
2021-02-17 07:56:49 (17264): Status Report: Elapsed Time: '192030.000000'
2021-02-17 07:56:49 (17264): Status Report: CPU Time: '773.656250'
2021-02-17 09:36:53 (17264): Status Report: Job Duration: '864000.000000'
2021-02-17 09:36:53 (17264): Status Report: Elapsed Time: '198030.000000'
2021-02-17 09:36:53 (17264): Status Report: CPU Time: '793.937500'
2021-02-17 11:16:57 (17264): Status Report: Job Duration: '864000.000000'
2021-02-17 11:16:57 (17264): Status Report: Elapsed Time: '204030.000000'
2021-02-17 11:16:57 (17264): Status Report: CPU Time: '813.812500'
2021-02-17 12:57:01 (17264): Status Report: Job Duration: '864000.000000'
2021-02-17 12:57:01 (17264): Status Report: Elapsed Time: '210030.000000'
2021-02-17 12:57:01 (17264): Status Report: CPU Time: '834.437500'
2021-02-17 14:37:05 (17264): Status Report: Job Duration: '864000.000000'
2021-02-17 14:37:05 (17264): Status Report: Elapsed Time: '216030.000000'
2021-02-17 14:37:05 (17264): Status Report: CPU Time: '855.031250'
2021-02-17 16:17:09 (17264): Status Report: Job Duration: '864000.000000'
2021-02-17 16:17:09 (17264): Status Report: Elapsed Time: '222030.000000'
2021-02-17 16:17:09 (17264): Status Report: CPU Time: '876.765625'
2021-02-17 17:57:13 (17264): Status Report: Job Duration: '864000.000000'
2021-02-17 17:57:13 (17264): Status Report: Elapsed Time: '228030.000000'
2021-02-17 17:57:13 (17264): Status Report: CPU Time: '897.562500'
2021-02-17 18:39:26 (17264): Stopping VM.
2021-02-17 18:39:26 (17264): Error in stop VM for VM: -108
Command:
VBoxManage -q controlvm "boinc_9e5c7654eb6b36df" savestate
Output:

2021-02-17 18:39:26 (17264): VM did not stop when requested.
2021-02-17 18:39:26 (17264): VM was NOT successfully terminated.
2021-02-17 19:29:51 (14180): Detected: vboxwrapper 26197
2021-02-17 19:29:51 (14180): Detected: BOINC client v7.7
2021-02-17 19:29:53 (14180): Detected: VirtualBox VboxManage Interface (Version: 6.1.18)
2021-02-17 19:29:53 (14180): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2021-02-17 19:29:53 (14180): Starting VM using VBoxManage interface. (boinc_9e5c7654eb6b36df, slot#5)
2021-02-17 19:30:00 (14180): Successfully started VM. (PID = '7476')
2021-02-17 19:30:00 (14180): Reporting VM Process ID to BOINC.
2021-02-17 19:30:00 (14180): Guest Log: BIOS: VirtualBox 6.1.18

2021-02-17 19:30:00 (14180): Guest Log: CPUID EDX: 0x178bfbff

2021-02-17 19:30:00 (14180): Guest Log: BIOS: ata0-0: PCHS=16383/16/63 LCHS=1024/255/63

2021-02-17 19:30:00 (14180): VM state change detected. (old = 'PoweredOff', new = 'Running')
2021-02-17 19:30:00 (14180): Detected: Web Application Enabled (http://localhost:23444)
2021-02-17 19:30:00 (14180): Detected: Remote Desktop Enabled (localhost:23445)
2021-02-17 19:30:00 (14180): Status Report: Job Duration: '864000.000000'
2021-02-17 19:30:00 (14180): Status Report: Elapsed Time: '230560.000000'
2021-02-17 19:30:00 (14180): Status Report: CPU Time: '905.765625'
2021-02-17 19:30:00 (14180): Preference change detected
2021-02-17 19:30:00 (14180): Setting CPU throttle for VM. (99%)
2021-02-17 19:30:00 (14180): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 400 seconds) or (Vbox_job.xml: 600 seconds))
2021-02-17 19:30:02 (14180): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032

2021-02-17 19:30:02 (14180): Guest Log: BIOS: Booting from Hard Disk...

2021-02-17 19:30:05 (14180): Guest Log: BIOS: KBD: unsupported int 16h function 03

2021-02-17 19:30:05 (14180): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 

2021-02-17 19:30:32 (14180): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds

2021-02-17 19:30:32 (14180): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000)

2021-02-17 19:30:35 (14180): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log

2021-02-17 19:30:35 (14180): Guest Log: 00:00:00.000128 main     Log opened 2021-02-17T14:30:34.950351000Z

2021-02-17 19:30:35 (14180): Guest Log: 00:00:00.000240 main     OS Product: Linux

2021-02-17 19:30:35 (14180): Guest Log: 00:00:00.000288 main     OS Release: 4.14.76-13.cernvm.x86_64

2021-02-17 19:30:35 (14180): Guest Log: 00:00:00.000332 main     OS Version: #1 SMP Tue Oct 16 18:26:15 CEST 2018

2021-02-17 19:30:35 (14180): Guest Log: 00:00:00.000379 main     Executable: /usr/sbin/VBoxService

2021-02-17 19:30:35 (14180): Guest Log: 00:00:00.000380 main     Process ID: 3205

2021-02-17 19:30:35 (14180): Guest Log: 00:00:00.000381 main     Package type: LINUX_64BITS_GENERIC

2021-02-17 19:30:35 (14180): Guest Log: 00:00:00.002823 main     5.2.6 r120293 started. Verbose level = 0

2021-02-17 19:31:28 (14180): Guest Log: -bash: /home/boinc/cranky: No such file or directory

2021-02-17 19:31:28 (14180): Guest Log: [ERROR] Job Failed

2021-02-17 19:31:28 (14180): Guest Log: [INFO] Shutting Down.

2021-02-17 19:31:28 (14180): VM Completion File Detected.
2021-02-17 19:31:28 (14180): VM Completion Message: Job Failed
.
2021-02-17 19:31:28 (14180): Powering off VM.
2021-02-17 19:36:29 (14180): VM did not power off when requested.
2021-02-17 19:36:29 (14180): VM was successfully terminated.
2021-02-17 19:36:29 (14180): Deregistering VM. (boinc_9e5c7654eb6b36df, slot#5)
2021-02-17 19:36:29 (14180): Removing network bandwidth throttle group from VM.
2021-02-17 19:36:30 (14180): Removing VM from VirtualBox.
19:36:35 (14180): called boinc_finish(1)

</stderr_txt>
]]>
Development for LHC@home

Stderr output
