Task 252205290

Name	Theory_2279-778303-186_0
Workunit	126676869
Created	18 Nov 2019, 18:34:39 UTC
Sent	18 Nov 2019, 19:30:35 UTC
Report deadline	29 Nov 2019, 19:30:35 UTC
Received	23 Dec 2019, 16:22:18 UTC
Server state	Over
Outcome	Success
Client state	Done
Exit status	0 (0x00000000)
Computer ID	10523321
Run time	5 hours 24 min 15 sec
CPU time	5 hours 20 min 5 sec
Validate state	Workunit error - check skipped
Credit	0.00
Device peak FLOPS	3.64 GFLOPS
Application version	Theory Simulation v300.02 (vbox64_theory) windows_x86_64
Peak working set size	72.98 MB
Peak swap size	80.93 MB
Peak disk usage	1.03 GB
Stderr output

<core_client_version>7.14.2</core_client_version>
<![CDATA[
<stderr_txt>
2019-11-18 20:33:28 (15456): Detected: vboxwrapper 26197
2019-11-18 20:33:28 (15456): Detected: BOINC client v7.7
2019-11-18 20:33:29 (15456): Detected: VirtualBox VboxManage Interface (Version: 5.2.8)
2019-11-18 20:33:29 (15456): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2019-11-18 20:33:29 (15456): Successfully copied 'init_data.xml' to the shared directory.
2019-11-18 20:33:29 (15456): Successfully copied 'input' to the shared directory.
2019-11-18 20:33:31 (15456): Create VM. (boinc_a66abc1aa31a79a5, slot#0)
2019-11-18 20:33:32 (15456): Setting Memory Size for VM. (630MB)
2019-11-18 20:33:32 (15456): Setting CPU Count for VM. (1)
2019-11-18 20:33:32 (15456): Setting Chipset Options for VM.
2019-11-18 20:33:32 (15456): Setting Boot Options for VM.
2019-11-18 20:33:33 (15456): Setting Network Configuration for NAT.
2019-11-18 20:33:33 (15456): Enabling VM Network Access.
2019-11-18 20:33:33 (15456): Disabling USB Support for VM.
2019-11-18 20:33:34 (15456): Disabling COM Port Support for VM.
2019-11-18 20:33:34 (15456): Disabling LPT Port Support for VM.
2019-11-18 20:33:34 (15456): Disabling Audio Support for VM.
2019-11-18 20:33:34 (15456): Disabling Clipboard Support for VM.
2019-11-18 20:33:35 (15456): Disabling Drag and Drop Support for VM.
2019-11-18 20:33:35 (15456): Adding storage controller(s) to VM.
2019-11-18 20:33:35 (15456): Adding virtual disk drive to VM. (vm_image.vdi)
2019-11-18 20:33:35 (15456): Adding VirtualBox Guest Additions to VM.
2019-11-18 20:33:36 (15456): Adding network bandwidth throttle group to VM. (Defaulting to 1024GB)
2019-11-18 20:33:36 (15456): forwarding host port 53086 to guest port 80
2019-11-18 20:33:36 (15456): Enabling remote desktop for VM.
2019-11-18 20:33:36 (15456): Required extension pack not installed, remote desktop not enabled.
2019-11-18 20:33:36 (15456): Enabling shared directory for VM.
2019-11-18 20:33:37 (15456): Starting VM using VBoxManage interface. (boinc_a66abc1aa31a79a5, slot#0)
2019-11-18 20:33:42 (15456): Successfully started VM. (PID = '14416')
2019-11-18 20:33:42 (15456): Reporting VM Process ID to BOINC.
2019-11-18 20:33:42 (15456): Guest Log: BIOS: VirtualBox 5.2.8

2019-11-18 20:33:42 (15456): Guest Log: CPUID EDX: 0x078bfbff

2019-11-18 20:33:42 (15456): Guest Log: BIOS: ata0-0: PCHS=16383/16/63 LCHS=1024/255/63

2019-11-18 20:33:42 (15456): VM state change detected. (old = 'PoweredOff', new = 'Running')
2019-11-18 20:33:42 (15456): Detected: Web Application Enabled (http://localhost:53086)
2019-11-18 20:33:42 (15456): Preference change detected
2019-11-18 20:33:42 (15456): Setting CPU throttle for VM. (100%)
2019-11-18 20:33:43 (15456): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2019-11-18 20:33:44 (15456): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032

2019-11-18 20:33:44 (15456): Guest Log: BIOS: Booting from Hard Disk...

2019-11-18 20:33:46 (15456): Guest Log: BIOS: KBD: unsupported int 16h function 03

2019-11-18 20:33:46 (15456): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 

2019-11-18 20:34:09 (15456): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds

2019-11-18 20:34:09 (15456): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000)

2019-11-18 20:34:15 (15456): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log

2019-11-18 20:34:15 (15456): Guest Log: 00:00:00.000086 main     Log opened 2019-11-18T19:34:15.919801000Z

2019-11-18 20:34:15 (15456): Guest Log: 00:00:00.000226 main     OS Product: Linux

2019-11-18 20:34:15 (15456): Guest Log: 00:00:00.000325 main     OS Release: 4.14.76-13.cernvm.x86_64

2019-11-18 20:34:15 (15456): Guest Log: 00:00:00.000354 main     OS Version: #1 SMP Tue Oct 16 18:26:15 CEST 2018

2019-11-18 20:34:15 (15456): Guest Log: 00:00:00.000381 main     Executable: /usr/sbin/VBoxService

2019-11-18 20:34:15 (15456): Guest Log: 00:00:00.000382 main     Process ID: 3196

2019-11-18 20:34:15 (15456): Guest Log: 00:00:00.000383 main     Package type: LINUX_64BITS_GENERIC

2019-11-18 20:34:15 (15456): Guest Log: 00:00:00.005789 main     5.2.6 r120293 started. Verbose level = 0

2019-11-18 20:34:26 (15456): Guest Log: 20:34:27 CET +01:00 2019-11-18: cranky: [INFO] Detected Theory App

2019-11-18 20:34:26 (15456): Guest Log: 20:34:27 CET +01:00 2019-11-18: cranky: [INFO] Checking CVMFS.

2019-11-18 20:34:31 (15456): Guest Log: 20:34:32 CET +01:00 2019-11-18: cranky: [INFO] Checking runc.

2019-11-18 20:34:31 (15456): Guest Log: 20:34:32 CET +01:00 2019-11-18: cranky: [INFO] Creating the filesystem.

2019-11-18 20:34:31 (15456): Guest Log: 20:34:32 CET +01:00 2019-11-18: cranky: [INFO] Using /cvmfs/cernvm-prod.cern.ch/cvm3

2019-11-18 20:34:31 (15456): Guest Log: 20:34:32 CET +01:00 2019-11-18: cranky: [INFO] Updating config.json.

2019-11-18 20:34:31 (15456): Guest Log: 20:34:32 CET +01:00 2019-11-18: cranky: [INFO] Running Container 'runc'.

2019-11-18 20:34:34 (15456): Guest Log: 20:34:35 CET +01:00 2019-11-18: cranky: [INFO] ===> [runRivet] Mon Nov 18 19:34:32 UTC 2019 [boinc pp jets 7000 350 - pythia8 8.226 tune-A2 100000 186]

2019-11-18 20:34:58 (15456): Stopping VM.
2019-11-19 21:38:36 (10104): Detected: vboxwrapper 26197
2019-11-19 21:38:36 (10104): Detected: BOINC client v7.7
2019-11-19 21:38:37 (10104): Detected: VirtualBox VboxManage Interface (Version: 5.2.8)
2019-11-19 21:38:37 (10104): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2019-11-19 21:38:38 (10104): Starting VM using VBoxManage interface. (boinc_a66abc1aa31a79a5, slot#0)
2019-11-19 21:38:50 (10104): Successfully started VM. (PID = '12032')
2019-11-19 21:38:50 (10104): Reporting VM Process ID to BOINC.
2019-11-19 21:38:50 (10104): VM state change detected. (old = 'PoweredOff', new = 'Running')
2019-11-19 21:38:50 (10104): Detected: Web Application Enabled (http://localhost:53086)
2019-11-19 21:38:51 (10104): VM state change detected. (old = 'Running', new = 'Paused')
2019-11-19 21:38:59 (10104): Preference change detected
2019-11-19 21:38:59 (10104): Setting CPU throttle for VM. (100%)
2019-11-19 21:39:00 (10104): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2019-11-19 21:39:00 (10104): VM state change detected. (old = 'Paused', new = 'Running')
2019-11-19 21:39:04 (10104): Guest Log: 00:00:50.030793 timesync vgsvcTimeSyncWorker: Radical host time change: 90 248 664 000 000ns (HostNow=1 574 195 943 707 000 000 ns HostLast=1 574 105 695 043 000 000 ns)

2019-11-19 21:39:14 (10104): Guest Log: 00:01:00.031267 timesync vgsvcTimeSyncWorker: Radical guest time change: 90 247 776 251 000ns (GuestNow=1 574 195 953 711 188 000 ns GuestLast=1 574 105 705 934 937 000 ns fSetTimeLastLoop=true )

2019-11-19 21:48:51 (10104): Stopping VM.
2019-11-23 14:47:22 (16088): Detected: vboxwrapper 26197
2019-11-23 14:47:22 (16088): Detected: BOINC client v7.7
2019-11-23 14:47:24 (16088): Detected: VirtualBox VboxManage Interface (Version: 5.2.8)
2019-11-23 14:47:24 (16088): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2019-11-23 14:47:24 (16088): Starting VM using VBoxManage interface. (boinc_a66abc1aa31a79a5, slot#0)
2019-11-23 14:47:36 (16088): Successfully started VM. (PID = '6668')
2019-11-23 14:47:36 (16088): Reporting VM Process ID to BOINC.
2019-11-23 14:47:36 (16088): VM state change detected. (old = 'PoweredOff', new = 'Running')
2019-11-23 14:47:36 (16088): Detected: Web Application Enabled (http://localhost:53086)
2019-11-23 14:47:37 (16088): VM state change detected. (old = 'Running', new = 'Paused')
2019-11-23 14:47:48 (16088): Preference change detected
2019-11-23 14:47:48 (16088): Setting CPU throttle for VM. (100%)
2019-11-23 14:47:48 (16088): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2019-11-23 14:47:48 (16088): Guest Log: 00:10:40.281012 timesync vgsvcTimeSyncWorker: Radical host time change: 320 344 674 000 000ns (HostNow=1 574 516 868 642 000 000 ns HostLast=1 574 196 523 968 000 000 ns)

2019-11-23 14:47:48 (16088): VM state change detected. (old = 'Paused', new = 'Running')
2019-11-23 14:47:58 (16088): Guest Log: 00:10:50.283714 timesync vgsvcTimeSyncWorker: Radical guest time change: 320 344 683 761 000ns (GuestNow=1 574 516 878 644 685 000 ns GuestLast=1 574 196 533 960 924 000 ns fSetTimeLastLoop=true )

2019-11-23 14:48:18 (16088): VM state change detected. (old = 'Running', new = 'Paused')
2019-11-23 14:48:28 (16088): VM state change detected. (old = 'Paused', new = 'Running')
2019-11-23 14:49:09 (16088): VM state change detected. (old = 'Running', new = 'Paused')
2019-11-23 14:49:19 (16088): VM state change detected. (old = 'Paused', new = 'Running')
2019-11-23 14:49:59 (16088): VM state change detected. (old = 'Running', new = 'Paused')
2019-11-23 14:50:09 (16088): VM state change detected. (old = 'Paused', new = 'Running')
2019-11-23 14:50:49 (16088): VM state change detected. (old = 'Running', new = 'Paused')
2019-11-23 14:50:59 (16088): VM state change detected. (old = 'Paused', new = 'Running')
2019-11-23 14:52:39 (16088): VM state change detected. (old = 'Running', new = 'Paused')
2019-11-23 14:52:49 (16088): VM state change detected. (old = 'Paused', new = 'Running')
2019-11-23 14:53:29 (16088): VM state change detected. (old = 'Running', new = 'Paused')
2019-11-23 14:53:39 (16088): VM state change detected. (old = 'Paused', new = 'Running')
2019-11-23 14:54:19 (16088): VM state change detected. (old = 'Running', new = 'Paused')
2019-11-23 14:54:29 (16088): VM state change detected. (old = 'Paused', new = 'Running')
2019-11-23 14:56:09 (16088): VM state change detected. (old = 'Running', new = 'Paused')
2019-11-23 14:56:19 (16088): VM state change detected. (old = 'Paused', new = 'Running')
2019-11-23 14:56:59 (16088): VM state change detected. (old = 'Running', new = 'Paused')
2019-11-23 14:57:09 (16088): VM state change detected. (old = 'Paused', new = 'Running')
2019-11-23 14:57:49 (16088): VM state change detected. (old = 'Running', new = 'Paused')
2019-11-23 14:57:59 (16088): VM state change detected. (old = 'Paused', new = 'Running')
2019-11-23 15:00:29 (16088): VM state change detected. (old = 'Running', new = 'Paused')
2019-11-23 15:00:39 (16088): VM state change detected. (old = 'Paused', new = 'Running')
2019-11-23 15:01:19 (16088): VM state change detected. (old = 'Running', new = 'Paused')
2019-11-23 15:01:29 (16088): VM state change detected. (old = 'Paused', new = 'Running')
2019-11-23 15:02:09 (16088): VM state change detected. (old = 'Running', new = 'Paused')
2019-11-23 15:02:19 (16088): VM state change detected. (old = 'Paused', new = 'Running')
2019-11-23 15:03:59 (16088): VM state change detected. (old = 'Running', new = 'Paused')
2019-11-23 15:04:09 (16088): VM state change detected. (old = 'Paused', new = 'Running')
2019-11-23 15:04:49 (16088): VM state change detected. (old = 'Running', new = 'Paused')
2019-11-23 15:04:59 (16088): VM state change detected. (old = 'Paused', new = 'Running')
2019-11-23 15:05:39 (16088): VM state change detected. (old = 'Running', new = 'Paused')
2019-11-23 15:05:49 (16088): VM state change detected. (old = 'Paused', new = 'Running')
2019-11-23 15:07:29 (16088): VM state change detected. (old = 'Running', new = 'Paused')
2019-11-23 15:07:39 (16088): VM state change detected. (old = 'Paused', new = 'Running')
2019-11-23 15:08:19 (16088): VM state change detected. (old = 'Running', new = 'Paused')
2019-11-23 15:08:30 (16088): VM state change detected. (old = 'Paused', new = 'Running')
2019-11-23 15:09:10 (16088): VM state change detected. (old = 'Running', new = 'Paused')
2019-11-23 15:09:20 (16088): VM state change detected. (old = 'Paused', new = 'Running')
2019-11-23 15:10:00 (16088): VM state change detected. (old = 'Running', new = 'Paused')
2019-11-23 15:10:10 (16088): VM state change detected. (old = 'Paused', new = 'Running')
2019-11-23 15:11:50 (16088): VM state change detected. (old = 'Running', new = 'Paused')
2019-11-23 15:12:00 (16088): VM state change detected. (old = 'Paused', new = 'Running')
2019-11-23 15:12:41 (16088): VM state change detected. (old = 'Running', new = 'Paused')
2019-11-23 15:12:50 (16088): VM state change detected. (old = 'Paused', new = 'Running')
2019-11-23 15:13:31 (16088): VM state change detected. (old = 'Running', new = 'Paused')
2019-11-23 15:13:40 (16088): VM state change detected. (old = 'Paused', new = 'Running')
2019-11-23 15:15:21 (16088): VM state change detected. (old = 'Running', new = 'Paused')
2019-11-23 15:15:30 (16088): VM state change detected. (old = 'Paused', new = 'Running')
2019-11-23 15:16:10 (16088): VM state change detected. (old = 'Running', new = 'Paused')
2019-11-23 15:16:21 (16088): VM state change detected. (old = 'Paused', new = 'Running')
2019-11-23 15:17:01 (16088): VM state change detected. (old = 'Running', new = 'Paused')
2019-11-23 15:17:11 (16088): VM state change detected. (old = 'Paused', new = 'Running')
2019-11-23 15:19:41 (16088): VM state change detected. (old = 'Running', new = 'Paused')
2019-11-23 15:19:51 (16088): VM state change detected. (old = 'Paused', new = 'Running')
2019-11-23 15:20:30 (16088): VM state change detected. (old = 'Running', new = 'Paused')
2019-11-23 15:20:41 (16088): VM state change detected. (old = 'Paused', new = 'Running')
2019-11-23 15:21:20 (16088): VM state change detected. (old = 'Running', new = 'Paused')
2019-11-23 15:21:31 (16088): VM state change detected. (old = 'Paused', new = 'Running')
2019-11-23 15:23:11 (16088): VM state change detected. (old = 'Running', new = 'Paused')
2019-11-23 15:23:21 (16088): VM state change detected. (old = 'Paused', new = 'Running')
2019-11-23 15:24:01 (16088): VM state change detected. (old = 'Running', new = 'Paused')
2019-11-23 15:24:11 (16088): VM state change detected. (old = 'Paused', new = 'Running')
2019-11-23 15:24:51 (16088): VM state change detected. (old = 'Running', new = 'Paused')
2019-11-23 15:25:01 (16088): VM state change detected. (old = 'Paused', new = 'Running')
2019-11-23 15:27:31 (16088): VM state change detected. (old = 'Running', new = 'Paused')
2019-11-23 15:27:41 (16088): VM state change detected. (old = 'Paused', new = 'Running')
2019-11-23 15:28:21 (16088): VM state change detected. (old = 'Running', new = 'Paused')
2019-11-23 15:28:31 (16088): VM state change detected. (old = 'Paused', new = 'Running')
2019-11-23 15:29:11 (16088): VM state change detected. (old = 'Running', new = 'Paused')
2019-11-23 15:29:21 (16088): VM state change detected. (old = 'Paused', new = 'Running')
2019-11-23 15:31:02 (16088): VM state change detected. (old = 'Running', new = 'Paused')
2019-11-23 15:31:12 (16088): VM state change detected. (old = 'Paused', new = 'Running')
2019-11-23 15:31:52 (16088): VM state change detected. (old = 'Running', new = 'Paused')
2019-11-23 15:32:02 (16088): VM state change detected. (old = 'Paused', new = 'Running')
2019-11-23 15:32:42 (16088): VM state change detected. (old = 'Running', new = 'Paused')
2019-11-23 15:32:52 (16088): VM state change detected. (old = 'Paused', new = 'Running')
2019-11-23 15:35:22 (16088): VM state change detected. (old = 'Running', new = 'Paused')
2019-11-23 15:35:32 (16088): VM state change detected. (old = 'Paused', new = 'Running')
2019-11-23 15:36:12 (16088): VM state change detected. (old = 'Running', new = 'Paused')
2019-11-23 15:36:22 (16088): VM state change detected. (old = 'Paused', new = 'Running')
2019-11-23 15:37:02 (16088): VM state change detected. (old = 'Running', new = 'Paused')
2019-11-23 15:37:12 (16088): VM state change detected. (old = 'Paused', new = 'Running')
2019-11-23 15:38:52 (16088): VM state change detected. (old = 'Running', new = 'Paused')
2019-11-23 15:39:02 (16088): VM state change detected. (old = 'Paused', new = 'Running')
2019-11-23 15:39:42 (16088): VM state change detected. (old = 'Running', new = 'Paused')
2019-11-23 15:39:52 (16088): VM state change detected. (old = 'Paused', new = 'Running')
2019-11-23 15:40:32 (16088): VM state change detected. (old = 'Running', new = 'Paused')
2019-11-23 15:40:42 (16088): VM state change detected. (old = 'Paused', new = 'Running')
2019-11-23 15:43:12 (16088): VM state change detected. (old = 'Running', new = 'Paused')
2019-11-23 15:43:22 (16088): VM state change detected. (old = 'Paused', new = 'Running')
2019-11-23 15:44:02 (16088): VM state change detected. (old = 'Running', new = 'Paused')
2019-11-23 15:44:12 (16088): VM state change detected. (old = 'Paused', new = 'Running')
2019-11-23 15:46:42 (16088): VM state change detected. (old = 'Running', new = 'Paused')
2019-11-23 15:46:52 (16088): VM state change detected. (old = 'Paused', new = 'Running')
2019-11-23 15:47:32 (16088): VM state change detected. (old = 'Running', new = 'Paused')
2019-11-23 15:47:42 (16088): VM state change detected. (old = 'Paused', new = 'Running')
2019-11-23 15:48:22 (16088): VM state change detected. (old = 'Running', new = 'Paused')
2019-11-23 15:48:32 (16088): VM state change detected. (old = 'Paused', new = 'Running')
2019-11-23 15:50:12 (16088): VM state change detected. (old = 'Running', new = 'Paused')
2019-11-23 15:50:22 (16088): VM state change detected. (old = 'Paused', new = 'Running')
2019-11-23 15:51:02 (16088): VM state change detected. (old = 'Running', new = 'Paused')
2019-11-23 15:51:12 (16088): VM state change detected. (old = 'Paused', new = 'Running')
2019-11-23 15:51:53 (16088): VM state change detected. (old = 'Running', new = 'Paused')
2019-11-23 15:52:03 (16088): VM state change detected. (old = 'Paused', new = 'Running')
2019-11-23 15:54:34 (16088): VM state change detected. (old = 'Running', new = 'Paused')
2019-11-23 15:54:44 (16088): VM state change detected. (old = 'Paused', new = 'Running')
2019-11-23 15:55:24 (16088): VM state change detected. (old = 'Running', new = 'Paused')
2019-11-23 15:55:34 (16088): VM state change detected. (old = 'Paused', new = 'Running')
2019-11-23 15:56:14 (16088): VM state change detected. (old = 'Running', new = 'Paused')
2019-11-23 15:56:24 (16088): VM state change detected. (old = 'Paused', new = 'Running')
2019-11-23 15:58:04 (16088): VM state change detected. (old = 'Running', new = 'Paused')
2019-11-23 15:58:14 (16088): VM state change detected. (old = 'Paused', new = 'Running')
2019-11-23 15:58:54 (16088): VM state change detected. (old = 'Running', new = 'Paused')
2019-11-23 15:59:04 (16088): VM state change detected. (old = 'Paused', new = 'Running')
2019-11-23 15:59:44 (16088): VM state change detected. (old = 'Running', new = 'Paused')
2019-11-23 15:59:54 (16088): VM state change detected. (old = 'Paused', new = 'Running')
2019-11-23 16:00:34 (16088): VM state change detected. (old = 'Running', new = 'Paused')
2019-11-23 16:00:44 (16088): VM state change detected. (old = 'Paused', new = 'Running')
2019-11-23 16:02:24 (16088): VM state change detected. (old = 'Running', new = 'Paused')
2019-11-23 16:02:34 (16088): VM state change detected. (old = 'Paused', new = 'Running')
2019-11-23 16:03:14 (16088): VM state change detected. (old = 'Running', new = 'Paused')
2019-11-23 16:03:24 (16088): VM state change detected. (old = 'Paused', new = 'Running')
2019-11-23 16:04:04 (16088): VM state change detected. (old = 'Running', new = 'Paused')
2019-11-23 16:04:14 (16088): VM state change detected. (old = 'Paused', new = 'Running')
2019-11-23 16:05:54 (16088): VM state change detected. (old = 'Running', new = 'Paused')
2019-11-23 16:06:04 (16088): VM state change detected. (old = 'Paused', new = 'Running')
2019-11-23 16:06:44 (16088): VM state change detected. (old = 'Running', new = 'Paused')
2019-11-23 16:06:54 (16088): VM state change detected. (old = 'Paused', new = 'Running')
2019-11-23 16:07:34 (16088): VM state change detected. (old = 'Running', new = 'Paused')
2019-11-23 16:07:44 (16088): VM state change detected. (old = 'Paused', new = 'Running')
2019-11-23 16:08:24 (16088): VM state change detected. (old = 'Running', new = 'Paused')
2019-11-23 16:08:34 (16088): VM state change detected. (old = 'Paused', new = 'Running')
2019-11-23 16:09:26 (16088): Stopping VM.
2019-11-23 16:53:46 (14572): Detected: vboxwrapper 26197
2019-11-23 16:53:46 (14572): Detected: BOINC client v7.7
2019-11-23 16:53:47 (14572): Detected: VirtualBox VboxManage Interface (Version: 5.2.8)
2019-11-23 16:53:47 (14572): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2019-11-23 16:53:48 (14572): Starting VM using VBoxManage interface. (boinc_a66abc1aa31a79a5, slot#0)
2019-11-23 16:54:01 (14572): Successfully started VM. (PID = '2972')
2019-11-23 16:54:01 (14572): Reporting VM Process ID to BOINC.
2019-11-23 16:54:01 (14572): Guest Log: 01:21:32.108201 timesync vgsvcTimeSyncWorker: Radical host time change: 2 682 727 000 000ns (HostNow=1 574 524 440 353 000 000 ns HostLast=1 574 521 757 626 000 000 ns)

2019-11-23 16:54:01 (14572): VM state change detected. (old = 'PoweredOff', new = 'Running')
2019-11-23 16:54:01 (14572): Detected: Web Application Enabled (http://localhost:53086)
2019-11-23 16:54:02 (14572): VM state change detected. (old = 'Running', new = 'Paused')
2019-11-23 16:54:07 (14572): Preference change detected
2019-11-23 16:54:07 (14572): Setting CPU throttle for VM. (100%)
2019-11-23 16:54:07 (14572): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2019-11-23 16:54:08 (14572): VM state change detected. (old = 'Paused', new = 'Running')
2019-11-23 16:54:17 (14572): Guest Log: 01:21:42.116294 timesync vgsvcTimeSyncWorker: Radical guest time change: 3 329 891 908 000ns (GuestNow=1 574 524 450 361 094 000 ns GuestLast=1 574 521 120 469 186 000 ns fSetTimeLastLoop=true )

2019-11-23 16:54:28 (14572): VM state change detected. (old = 'Running', new = 'Paused')
2019-11-23 16:54:38 (14572): VM state change detected. (old = 'Paused', new = 'Running')
2019-11-23 16:55:18 (14572): VM state change detected. (old = 'Running', new = 'Paused')
2019-11-23 16:55:28 (14572): VM state change detected. (old = 'Paused', new = 'Running')
2019-11-23 16:56:08 (14572): VM state change detected. (old = 'Running', new = 'Paused')
2019-11-23 16:56:18 (14572): VM state change detected. (old = 'Paused', new = 'Running')
2019-11-23 16:57:58 (14572): VM state change detected. (old = 'Running', new = 'Paused')
2019-11-23 16:58:08 (14572): VM state change detected. (old = 'Paused', new = 'Running')
2019-11-23 16:58:48 (14572): VM state change detected. (old = 'Running', new = 'Paused')
2019-11-23 16:58:58 (14572): VM state change detected. (old = 'Paused', new = 'Running')
2019-11-23 16:59:38 (14572): VM state change detected. (old = 'Running', new = 'Paused')
2019-11-23 16:59:48 (14572): VM state change detected. (old = 'Paused', new = 'Running')
2019-11-23 17:00:38 (14572): VM state change detected. (old = 'Running', new = 'Paused')
2019-11-23 17:00:48 (14572): VM state change detected. (old = 'Paused', new = 'Running')
2019-11-23 17:01:28 (14572): VM state change detected. (old = 'Running', new = 'Paused')
2019-11-23 17:01:38 (14572): VM state change detected. (old = 'Paused', new = 'Running')
2019-11-23 17:02:18 (14572): VM state change detected. (old = 'Running', new = 'Paused')
2019-11-23 17:02:28 (14572): VM state change detected. (old = 'Paused', new = 'Running')
2019-11-23 17:03:08 (14572): VM state change detected. (old = 'Running', new = 'Paused')
2019-11-23 17:03:18 (14572): VM state change detected. (old = 'Paused', new = 'Running')
2019-11-23 17:05:49 (14572): VM state change detected. (old = 'Running', new = 'Paused')
2019-11-23 17:05:59 (14572): VM state change detected. (old = 'Paused', new = 'Running')
2019-11-23 17:06:39 (14572): VM state change detected. (old = 'Running', new = 'Paused')
2019-11-23 17:06:49 (14572): VM state change detected. (old = 'Paused', new = 'Running')
2019-11-23 17:07:29 (14572): VM state change detected. (old = 'Running', new = 'Paused')
2019-11-23 17:07:39 (14572): VM state change detected. (old = 'Paused', new = 'Running')
2019-11-23 17:09:19 (14572): VM state change detected. (old = 'Running', new = 'Paused')
2019-11-23 17:09:29 (14572): VM state change detected. (old = 'Paused', new = 'Running')
2019-11-23 17:10:09 (14572): VM state change detected. (old = 'Running', new = 'Paused')
2019-11-23 17:10:19 (14572): VM state change detected. (old = 'Paused', new = 'Running')
2019-11-23 17:10:59 (14572): VM state change detected. (old = 'Running', new = 'Paused')
2019-11-23 17:11:09 (14572): VM state change detected. (old = 'Paused', new = 'Running')
2019-11-23 17:12:50 (14572): VM state change detected. (old = 'Running', new = 'Paused')
2019-11-23 17:13:00 (14572): VM state change detected. (old = 'Paused', new = 'Running')
2019-11-23 17:13:40 (14572): VM state change detected. (old = 'Running', new = 'Paused')
2019-11-23 19:55:38 (14572): Stopping VM.
2019-11-23 19:55:38 (14572): Error in stop VM for VM: -108
Command:
VBoxManage -q controlvm "boinc_a66abc1aa31a79a5" savestate
Output:

2019-11-23 19:55:38 (14572): VM did not stop when requested.
2019-11-23 19:55:38 (14572): VM was NOT successfully terminated.
2019-11-24 10:08:32 (2412): Detected: vboxwrapper 26197
2019-11-24 10:08:32 (2412): Detected: BOINC client v7.7
2019-11-24 10:08:33 (2412): Detected: VirtualBox VboxManage Interface (Version: 5.2.8)
2019-11-24 10:08:34 (2412): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2019-11-24 10:08:34 (2412): Starting VM using VBoxManage interface. (boinc_a66abc1aa31a79a5, slot#0)
2019-11-24 10:08:39 (2412): Successfully started VM. (PID = '6968')
2019-11-24 10:08:39 (2412): Reporting VM Process ID to BOINC.
2019-11-24 10:08:39 (2412): Guest Log: BIOS: VirtualBox 5.2.8

2019-11-24 10:08:39 (2412): Guest Log: CPUID EDX: 0x078bfbff

2019-11-24 10:08:39 (2412): Guest Log: BIOS: ata0-0: PCHS=16383/16/63 LCHS=1024/255/63

2019-11-24 10:08:39 (2412): VM state change detected. (old = 'PoweredOff', new = 'Running')
2019-11-24 10:08:39 (2412): Detected: Web Application Enabled (http://localhost:53086)
2019-11-24 10:08:39 (2412): Preference change detected
2019-11-24 10:08:39 (2412): Setting CPU throttle for VM. (100%)
2019-11-24 10:08:39 (2412): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2019-11-24 10:08:41 (2412): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032

2019-11-24 10:08:41 (2412): Guest Log: BIOS: Booting from Hard Disk...

2019-11-24 10:08:43 (2412): Guest Log: BIOS: KBD: unsupported int 16h function 03

2019-11-24 10:08:43 (2412): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 

2019-11-24 10:09:05 (2412): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds

2019-11-24 10:09:05 (2412): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000)

2019-11-24 10:09:08 (2412): VM state change detected. (old = 'Running', new = 'Paused')
2019-11-24 10:09:18 (2412): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log

2019-11-24 10:09:18 (2412): Guest Log: 00:00:00.000090 main     Log opened 2019-11-24T09:09:08.743256000Z

2019-11-24 10:09:18 (2412): Guest Log: 00:00:00.000204 main     OS Product: Linux

2019-11-24 10:09:18 (2412): Guest Log: 00:00:00.000238 main     OS Release: 4.14.76-13.cernvm.x86_64

2019-11-24 10:09:18 (2412): Guest Log: 00:00:00.000266 main     OS Version: #1 SMP Tue Oct 16 18:26:15 CEST 2018

2019-11-24 10:09:18 (2412): Guest Log: 00:00:00.000304 main     Executable: /usr/sbin/VBoxService

2019-11-24 10:09:18 (2412): Guest Log: 00:00:00.000305 main     Process ID: 3196

2019-11-24 10:09:18 (2412): Guest Log: 00:00:00.000305 main     Package type: LINUX_64BITS_GENERIC

2019-11-24 10:09:18 (2412): Guest Log: 00:00:00.005932 main     5.2.6 r120293 started. Verbose level = 0

2019-11-24 10:09:18 (2412): VM state change detected. (old = 'Paused', new = 'Running')
2019-11-24 10:09:31 (2412): Guest Log: 10:09:21 CET +01:00 2019-11-24: cranky: [INFO] Detected Theory App

2019-11-24 10:09:31 (2412): Guest Log: 10:09:21 CET +01:00 2019-11-24: cranky: [INFO] Checking CVMFS.

2019-11-24 10:09:36 (2412): Guest Log: 10:09:27 CET +01:00 2019-11-24: cranky: [INFO] Checking runc.

2019-11-24 10:09:37 (2412): Guest Log: 10:09:27 CET +01:00 2019-11-24: cranky: [INFO] Creating the filesystem.

2019-11-24 10:09:37 (2412): Guest Log: 10:09:27 CET +01:00 2019-11-24: cranky: [INFO] Using /cvmfs/cernvm-prod.cern.ch/cvm3

2019-11-24 10:09:37 (2412): Guest Log: 10:09:27 CET +01:00 2019-11-24: cranky: [INFO] Updating config.json.

2019-11-24 10:09:37 (2412): Guest Log: 10:09:27 CET +01:00 2019-11-24: cranky: [INFO] Running Container 'runc'.

2019-11-24 10:09:39 (2412): Guest Log: 10:09:29 CET +01:00 2019-11-24: cranky: [INFO] ===> [runRivet] Sun Nov 24 09:09:28 UTC 2019 [boinc pp jets 7000 350 - pythia8 8.226 tune-A2 100000 186]

2019-11-24 10:10:58 (2412): VM state change detected. (old = 'Running', new = 'Paused')
2019-11-24 10:11:08 (2412): VM state change detected. (old = 'Paused', new = 'Running')
2019-11-24 10:11:48 (2412): VM state change detected. (old = 'Running', new = 'Paused')
2019-11-24 10:11:58 (2412): VM state change detected. (old = 'Paused', new = 'Running')
2019-11-24 10:12:12 (2412): Status Report: Job Duration: '360000.000000'
2019-11-24 10:12:12 (2412): Status Report: Elapsed Time: '6000.000000'
2019-11-24 10:12:12 (2412): Status Report: CPU Time: '6024.984375'
2019-11-24 10:13:39 (2412): VM state change detected. (old = 'Running', new = 'Paused')
2019-11-24 10:13:49 (2412): VM state change detected. (old = 'Paused', new = 'Running')
2019-11-24 10:14:29 (2412): VM state change detected. (old = 'Running', new = 'Paused')
2019-11-24 10:14:39 (2412): VM state change detected. (old = 'Paused', new = 'Running')
2019-11-24 10:16:19 (2412): VM state change detected. (old = 'Running', new = 'Paused')
2019-11-24 10:16:29 (2412): VM state change detected. (old = 'Paused', new = 'Running')
2019-11-24 10:17:09 (2412): VM state change detected. (old = 'Running', new = 'Paused')
2019-11-24 10:17:19 (2412): VM state change detected. (old = 'Paused', new = 'Running')
2019-11-24 10:18:59 (2412): VM state change detected. (old = 'Running', new = 'Paused')
2019-11-24 10:19:09 (2412): VM state change detected. (old = 'Paused', new = 'Running')
2019-11-24 10:19:49 (2412): VM state change detected. (old = 'Running', new = 'Paused')
2019-11-24 10:19:59 (2412): VM state change detected. (old = 'Paused', new = 'Running')
2019-11-24 10:20:39 (2412): VM state change detected. (old = 'Running', new = 'Paused')
2019-11-24 10:20:49 (2412): VM state change detected. (old = 'Paused', new = 'Running')
2019-11-24 10:21:39 (2412): VM state change detected. (old = 'Running', new = 'Paused')
2019-11-24 10:21:49 (2412): VM state change detected. (old = 'Paused', new = 'Running')
2019-11-24 10:22:29 (2412): VM state change detected. (old = 'Running', new = 'Paused')
2019-11-24 10:22:39 (2412): VM state change detected. (old = 'Paused', new = 'Running')
2019-11-24 10:23:19 (2412): VM state change detected. (old = 'Running', new = 'Paused')
2019-11-24 10:23:29 (2412): VM state change detected. (old = 'Paused', new = 'Running')
2019-11-24 10:24:19 (2412): VM state change detected. (old = 'Running', new = 'Paused')
2019-11-24 10:24:29 (2412): VM state change detected. (old = 'Paused', new = 'Running')
2019-11-24 10:25:09 (2412): VM state change detected. (old = 'Running', new = 'Paused')
2019-11-24 10:25:19 (2412): VM state change detected. (old = 'Paused', new = 'Running')
2019-11-24 10:25:59 (2412): VM state change detected. (old = 'Running', new = 'Paused')
2019-11-24 10:26:09 (2412): VM state change detected. (old = 'Paused', new = 'Running')
2019-11-24 10:26:59 (2412): VM state change detected. (old = 'Running', new = 'Paused')
2019-11-24 10:27:09 (2412): VM state change detected. (old = 'Paused', new = 'Running')
2019-11-24 10:27:45 (2412): Stopping VM.
2019-11-24 14:30:25 (4016): Detected: vboxwrapper 26197
2019-11-24 14:30:25 (4016): Detected: BOINC client v7.7
2019-11-24 14:30:26 (4016): Detected: VirtualBox VboxManage Interface (Version: 5.2.8)
2019-11-24 14:30:26 (4016): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2019-11-24 14:30:26 (4016): Starting VM using VBoxManage interface. (boinc_a66abc1aa31a79a5, slot#0)
2019-11-24 14:30:34 (4016): Successfully started VM. (PID = '2772')
2019-11-24 14:30:34 (4016): Reporting VM Process ID to BOINC.
2019-11-24 14:30:34 (4016): VM state change detected. (old = 'PoweredOff', new = 'Running')
2019-11-24 14:30:34 (4016): Detected: Web Application Enabled (http://localhost:53086)
2019-11-24 14:30:34 (4016): Status Report: Job Duration: '360000.000000'
2019-11-24 14:30:34 (4016): Status Report: Elapsed Time: '6778.000000'
2019-11-24 14:30:34 (4016): Status Report: CPU Time: '6815.593750'
2019-11-24 14:30:34 (4016): Preference change detected
2019-11-24 14:30:34 (4016): Setting CPU throttle for VM. (100%)
2019-11-24 14:30:35 (4016): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2019-11-24 14:30:43 (4016): Guest Log: 00:16:00.555004 timesync vgsvcTimeSyncWorker: Radical host time change: 14 577 435 000 000ns (HostNow=1 574 602 243 232 000 000 ns HostLast=1 574 587 665 797 000 000 ns)

2019-11-24 14:30:53 (4016): Guest Log: 00:16:10.556474 timesync vgsvcTimeSyncWorker: Radical guest time change: 14 743 939 007 000ns (GuestNow=1 574 602 253 235 301 000 ns GuestLast=1 574 587 509 296 294 000 ns fSetTimeLastLoop=true )

2019-11-24 16:10:38 (4016): Status Report: Job Duration: '360000.000000'
2019-11-24 16:10:38 (4016): Status Report: Elapsed Time: '12778.000000'
2019-11-24 16:10:38 (4016): Status Report: CPU Time: '12825.703125'
2019-11-24 16:59:46 (4016): Stopping VM.
2019-12-23 15:22:14 (4792): Detected: vboxwrapper 26197
2019-12-23 15:22:14 (4792): Detected: BOINC client v7.7
2019-12-23 15:22:15 (4792): Detected: VirtualBox VboxManage Interface (Version: 5.2.8)
2019-12-23 15:22:15 (4792): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2019-12-23 15:22:16 (4792): Starting VM using VBoxManage interface. (boinc_a66abc1aa31a79a5, slot#0)
2019-12-23 15:22:29 (4792): Successfully started VM. (PID = '10920')
2019-12-23 15:22:29 (4792): Reporting VM Process ID to BOINC.
2019-12-23 15:22:29 (4792): Guest Log: 02:45:04.853616 timesync vgsvcTimeSyncWorker: Radical host time change: 2 499 770 937 000 000ns (HostNow=1 577 110 948 305 000 000 ns HostLast=1 574 611 177 368 000 000 ns)

2019-12-23 15:22:29 (4792): VM state change detected. (old = 'PoweredOff', new = 'Running')
2019-12-23 15:22:29 (4792): Detected: Web Application Enabled (http://localhost:53086)
2019-12-23 15:22:29 (4792): Status Report: Job Duration: '360000.000000'
2019-12-23 15:22:29 (4792): Status Report: Elapsed Time: '15724.000000'
2019-12-23 15:22:29 (4792): Status Report: CPU Time: '15768.125000'
2019-12-23 15:22:29 (4792): Preference change detected
2019-12-23 15:22:29 (4792): Setting CPU throttle for VM. (100%)
2019-12-23 15:22:29 (4792): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2019-12-23 15:22:39 (4792): Guest Log: 02:45:14.871074 timesync vgsvcTimeSyncWorker: Radical guest time change: 2 499 771 117 834 000ns (GuestNow=1 577 110 958 486 365 000 ns GuestLast=1 574 611 187 368 531 000 ns fSetTimeLastLoop=true )

2019-12-23 15:31:45 (4792): Stopping VM.
2019-12-23 16:28:29 (11856): Detected: vboxwrapper 26197
2019-12-23 16:28:29 (11856): Detected: BOINC client v7.7
2019-12-23 16:28:30 (11856): Detected: VirtualBox VboxManage Interface (Version: 5.2.8)
2019-12-23 16:28:30 (11856): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2019-12-23 16:28:30 (11856): Starting VM using VBoxManage interface. (boinc_a66abc1aa31a79a5, slot#0)
2019-12-23 16:28:38 (11856): Successfully started VM. (PID = '6048')
2019-12-23 16:28:38 (11856): Reporting VM Process ID to BOINC.
2019-12-23 16:28:38 (11856): VM state change detected. (old = 'PoweredOff', new = 'Running')
2019-12-23 16:28:38 (11856): Detected: Web Application Enabled (http://localhost:53086)
2019-12-23 16:28:38 (11856): Status Report: Job Duration: '360000.000000'
2019-12-23 16:28:38 (11856): Status Report: Elapsed Time: '16280.000000'
2019-12-23 16:28:38 (11856): Status Report: CPU Time: '16324.265625'
2019-12-23 16:28:38 (11856): Preference change detected
2019-12-23 16:28:38 (11856): Setting CPU throttle for VM. (100%)
2019-12-23 16:28:39 (11856): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2019-12-23 16:28:40 (11856): VM state change detected. (old = 'Running', new = 'Paused')
2019-12-23 16:28:50 (11856): Guest Log: 02:54:25.120102 timesync vgsvcTimeSyncWorker: Radical host time change: 3 431 980 000 000ns (HostNow=1 577 114 930 704 000 000 ns HostLast=1 577 111 498 724 000 000 ns)

2019-12-23 16:28:50 (11856): VM state change detected. (old = 'Paused', new = 'Running')
2019-12-23 16:29:00 (11856): Guest Log: 02:54:35.122003 timesync vgsvcTimeSyncWorker: Radical guest time change: 3 431 970 528 000ns (GuestNow=1 577 114 940 705 909 000 ns GuestLast=1 577 111 508 735 381 000 ns fSetTimeLastLoop=true )

2019-12-23 17:16:50 (11856): Guest Log: 17:16:50 CET +01:00 2019-12-23: cranky: [INFO] Container 'runc' finished with status code 0.

2019-12-23 17:16:50 (11856): Guest Log: 17:16:50 CET +01:00 2019-12-23: cranky: [INFO] Preparing output.

2019-12-23 17:16:52 (11856): Guest Log: [INFO] Job Finished

2019-12-23 17:16:52 (11856): Guest Log: [INFO] Shutting Down.

2019-12-23 17:16:52 (11856): VM Completion File Detected.
2019-12-23 17:16:52 (11856): VM Completion Message: Job Finished
.
2019-12-23 17:16:52 (11856): Powering off VM.
2019-12-23 17:21:53 (11856): VM did not power off when requested.
2019-12-23 17:21:53 (11856): VM was NOT successfully terminated.
2019-12-23 17:21:53 (11856): Deregistering VM. (boinc_a66abc1aa31a79a5, slot#0)
2019-12-23 17:21:53 (11856): Removing network bandwidth throttle group from VM.
2019-12-23 17:21:53 (11856): Removing VM from VirtualBox.
17:21:59 (11856): called boinc_finish(0)

</stderr_txt>
]]>
LHC@home

Stderr output
