Task 276336379

Name	Theory_2390-1086688-14_1
Workunit	140805803
Created	4 Jun 2020, 19:25:41 UTC
Sent	4 Jun 2020, 19:29:22 UTC
Report deadline	15 Jun 2020, 19:29:22 UTC
Received	16 Jun 2020, 23:14:47 UTC
Server state	Over
Outcome	Success
Client state	Done
Exit status	0 (0x00000000)
Computer ID	10652740
Run time	7 hours 34 min 3 sec
CPU time	6 hours 48 min 46 sec
Validate state	Valid
Credit	221.71
Device peak FLOPS	3.52 GFLOPS
Application version	Theory Simulation v300.06 (vbox64_theory) windows_x86_64
Peak working set size	70.95 MB
Peak swap size	80.43 MB
Peak disk usage	2.89 GB
Stderr output

<core_client_version>7.14.2</core_client_version>
<![CDATA[
<stderr_txt>
): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-12 21:10:21 (3012): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-12 21:10:31 (3012): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-12 21:10:41 (3012): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-12 21:10:52 (3012): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-12 21:11:11 (3012): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-12 21:11:21 (3012): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-12 21:11:41 (3012): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-12 21:11:51 (3012): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-12 21:12:01 (3012): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-12 21:12:12 (3012): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-12 21:12:22 (3012): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-12 21:12:31 (3012): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-12 21:12:41 (3012): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-12 21:12:51 (3012): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-12 21:13:11 (3012): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-12 21:13:21 (3012): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-12 21:13:32 (3012): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-12 21:13:42 (3012): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-12 21:14:22 (3012): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-12 21:14:32 (3012): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-12 21:15:22 (3012): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-12 21:15:32 (3012): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-12 21:16:02 (3012): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-12 21:16:11 (3012): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-12 21:17:12 (3012): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-12 21:17:22 (3012): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-12 21:18:02 (3012): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-12 21:18:12 (3012): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-12 21:18:32 (3012): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-12 21:18:42 (3012): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-12 21:19:13 (3012): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-12 21:19:23 (3012): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-12 21:19:33 (3012): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-12 21:19:43 (3012): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-12 21:20:04 (3012): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-12 21:20:12 (3012): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-12 21:20:23 (3012): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-12 21:20:33 (3012): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-12 21:21:02 (3012): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-12 21:21:12 (3012): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-12 21:21:32 (3012): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-12 21:21:43 (3012): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-12 21:22:03 (3012): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-12 21:22:13 (3012): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-12 21:22:43 (3012): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-12 21:22:52 (3012): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-12 21:23:13 (3012): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-12 21:23:24 (3012): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-12 21:23:34 (3012): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-12 21:23:43 (3012): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-12 21:24:03 (3012): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-12 21:24:13 (3012): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-12 21:24:34 (3012): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-12 21:24:43 (3012): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-12 21:25:03 (3012): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-12 21:25:13 (3012): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-12 21:25:23 (3012): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-12 21:25:34 (3012): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-12 21:25:44 (3012): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-12 21:25:53 (3012): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-12 21:26:03 (3012): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-12 21:26:14 (3012): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-12 21:26:24 (3012): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-12 21:26:33 (3012): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-12 21:26:43 (3012): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-12 21:26:54 (3012): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-12 21:28:24 (3012): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-12 21:28:34 (3012): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-12 21:29:04 (3012): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-12 21:29:14 (3012): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-12 21:29:34 (3012): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-12 21:29:47 (3012): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-12 21:30:56 (3012): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-12 21:31:04 (3012): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-12 21:31:25 (3012): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-12 21:31:34 (3012): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-12 21:31:44 (3012): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-12 21:31:55 (3012): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-12 21:32:05 (3012): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-12 21:32:15 (3012): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-12 21:32:24 (3012): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-12 21:32:34 (3012): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-12 21:33:15 (3012): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-12 21:33:24 (3012): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-12 21:34:25 (3012): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-12 21:34:35 (3012): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-12 21:34:45 (3012): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-12 21:34:55 (3012): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-12 21:35:15 (3012): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-12 21:35:25 (3012): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-12 21:35:54 (3012): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-12 21:36:05 (3012): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-12 21:36:15 (3012): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-12 21:36:25 (3012): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-12 21:36:35 (3012): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-12 21:36:45 (3012): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-12 21:36:55 (3012): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-12 21:37:05 (3012): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-12 21:37:16 (3012): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-12 21:37:25 (3012): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-12 21:38:17 (3012): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-12 21:38:26 (3012): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-12 21:38:46 (3012): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-12 21:38:55 (3012): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-12 21:39:15 (3012): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-12 21:39:25 (3012): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-12 21:39:36 (3012): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-12 21:39:45 (3012): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-12 21:40:15 (3012): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-12 21:40:26 (3012): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-12 21:40:46 (3012): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-12 21:40:57 (3012): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-12 21:41:16 (3012): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-12 21:41:26 (3012): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-12 21:41:46 (3012): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-12 21:41:55 (3012): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-12 21:42:05 (3012): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-12 21:42:16 (3012): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-12 21:42:26 (3012): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-12 21:42:35 (3012): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-12 21:42:46 (3012): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-12 21:42:56 (3012): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-12 21:43:06 (3012): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-12 21:43:16 (3012): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-12 21:43:26 (3012): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-12 21:43:36 (3012): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-12 21:43:47 (3012): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-12 21:43:56 (3012): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-12 21:44:06 (3012): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-12 21:44:16 (3012): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-12 21:44:27 (3012): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-12 21:44:36 (3012): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-12 21:45:06 (3012): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-12 21:45:16 (3012): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-12 21:45:26 (3012): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-12 21:45:36 (3012): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-12 21:45:46 (3012): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-12 21:45:56 (3012): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-12 21:46:07 (3012): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-12 21:46:17 (3012): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-12 21:46:37 (3012): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-12 21:46:51 (3012): Stopping VM.
2020-06-12 21:46:52 (3012): Error in stop VM for VM: -108
Command:
VBoxManage -q controlvm "boinc_bd675fcb59a5bb8f" savestate
Output:

2020-06-12 21:46:52 (3012): VM did not stop when requested.
2020-06-12 21:46:52 (3012): VM was successfully terminated.
2020-06-14 16:05:05 (7088): Detected: vboxwrapper 26197
2020-06-14 16:05:05 (7088): Detected: BOINC client v7.7
2020-06-14 16:05:41 (7088): Detected: VirtualBox VboxManage Interface (Version: 5.2.8)
2020-06-14 16:05:41 (7088): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2020-06-14 16:05:45 (7088): Starting VM using VBoxManage interface. (boinc_bd675fcb59a5bb8f, slot#1)
2020-06-14 16:06:35 (7088): Successfully started VM. (PID = '10332')
2020-06-14 16:06:35 (7088): Reporting VM Process ID to BOINC.
2020-06-14 16:06:35 (7088): Guest Log: BIOS: VirtualBox 5.2.8

2020-06-14 16:06:35 (7088): Guest Log: CPUID EDX: 0x078bfbff

2020-06-14 16:06:35 (7088): Guest Log: BIOS: ata0-0: PCHS=16383/16/63 LCHS=1024/255/63

2020-06-14 16:06:35 (7088): VM state change detected. (old = 'PoweredOff', new = 'Running')
2020-06-14 16:06:35 (7088): Detected: Web Application Enabled (http://localhost:57929)
2020-06-14 16:06:35 (7088): Status Report: Job Duration: '864000.000000'
2020-06-14 16:06:35 (7088): Status Report: Elapsed Time: '16442.038914'
2020-06-14 16:06:35 (7088): Status Report: CPU Time: '16041.984375'
2020-06-14 16:06:35 (7088): Preference change detected
2020-06-14 16:06:35 (7088): Setting CPU throttle for VM. (100%)
2020-06-14 16:06:36 (7088): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2020-06-14 16:06:37 (7088): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032

2020-06-14 16:06:37 (7088): Guest Log: BIOS: Booting from Hard Disk...

2020-06-14 16:06:48 (7088): Guest Log: BIOS: KBD: unsupported int 16h function 03

2020-06-14 16:06:48 (7088): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 

2020-06-14 16:07:30 (7088): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds

2020-06-14 16:07:30 (7088): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000)

2020-06-14 16:07:37 (7088): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log

2020-06-14 16:07:37 (7088): Guest Log: 00:00:00.000128 main     Log opened 2020-06-14T13:07:37.293901000Z

2020-06-14 16:07:37 (7088): Guest Log: 00:00:00.000337 main     OS Product: Linux

2020-06-14 16:07:37 (7088): Guest Log: 00:00:00.000406 main     OS Release: 4.14.76-13.cernvm.x86_64

2020-06-14 16:07:37 (7088): Guest Log: 00:00:00.000459 main     OS Version: #1 SMP Tue Oct 16 18:26:15 CEST 2018

2020-06-14 16:07:37 (7088): Guest Log: 00:00:00.000510 main     Executable: /usr/sbin/VBoxService

2020-06-14 16:07:37 (7088): Guest Log: 00:00:00.000511 main     Process ID: 3206

2020-06-14 16:07:37 (7088): Guest Log: 00:00:00.000512 main     Package type: LINUX_64BITS_GENERIC

2020-06-14 16:07:37 (7088): Guest Log: 00:00:00.011816 main     5.2.6 r120293 started. Verbose level = 0

2020-06-14 16:08:18 (7088): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-14 16:08:26 (7088): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-14 16:08:32 (7088): Guest Log: 16:08:25 +03 +03:00 2020-06-14: cranky: [INFO] Detected Theory App

2020-06-14 16:08:32 (7088): Guest Log: 16:08:25 +03 +03:00 2020-06-14: cranky: [INFO] Checking CVMFS.

2020-06-14 16:08:33 (7088): Guest Log: Probing /cvmfs/sft.cern.ch... OK

2020-06-14 16:08:34 (7088): Guest Log: VERSION PID UPTIME(M) MEM(K) REVISION EXPIRES(M) NOCATALOGS CACHEUSE(K) CACHEMAX(K) NOFDUSE NOFDMAX NOIOERR NOOPEN HITRATE(%) RX(K) SPEED(K/S) HOST PROXY ONLINE

2020-06-14 16:08:34 (7088): Guest Log: 2.5.2.0 4065 0 28584 18227 3 1 1100548 4096001 0 65024 0 0 n/a 5 5 http://s1cern-cvmfs.openhtc.io/cvmfs/sft.cern.ch DIRECT 1

2020-06-14 16:08:35 (7088): Guest Log: Probing /cvmfs/grid.cern.ch... OK

2020-06-14 16:08:36 (7088): Guest Log: VERSION PID UPTIME(M) MEM(K) REVISION EXPIRES(M) NOCATALOGS CACHEUSE(K) CACHEMAX(K) NOFDUSE NOFDMAX NOIOERR NOOPEN HITRATE(%) RX(K) SPEED(K/S) HOST PROXY ONLINE

2020-06-14 16:08:36 (7088): Guest Log: 2.5.2.0 4108 0 27604 12477 3 2 1100548 4096001 0 65024 0 2 0 4272 962 http://s1ral-cvmfs.openhtc.io/cvmfs/grid.cern.ch DIRECT 1

2020-06-14 16:08:38 (7088): Guest Log: Probing /cvmfs/cernvm-prod.cern.ch... OK

2020-06-14 16:08:38 (7088): Guest Log: VERSION PID UPTIME(M) MEM(K) REVISION EXPIRES(M) NOCATALOGS CACHEUSE(K) CACHEMAX(K) NOFDUSE NOFDMAX NOIOERR NOOPEN HITRATE(%) RX(K) SPEED(K/S) HOST PROXY ONLINE

2020-06-14 16:08:38 (7088): Guest Log: 2.5.2.0 4088 0 25172 252 3 1 1100548 4096001 0 65024 0 0 n/a 0 0 http://s1ral-cvmfs.openhtc.io/cvmfs/cernvm-prod.cern.ch DIRECT 1

2020-06-14 16:08:39 (7088): Guest Log: Probing /cvmfs/alice.cern.ch... OK

2020-06-14 16:08:40 (7088): Guest Log: VERSION PID UPTIME(M) MEM(K) REVISION EXPIRES(M) NOCATALOGS CACHEUSE(K) CACHEMAX(K) NOFDUSE NOFDMAX NOIOERR NOOPEN HITRATE(%) RX(K) SPEED(K/S) HOST PROXY ONLINE

2020-06-14 16:08:40 (7088): Guest Log: 2.5.2.0 4135 0 28980 6792 3 1 1100548 4096001 0 65024 0 0 n/a 4127 847 http://s1fnal-cvmfs.openhtc.io/cvmfs/alice.cern.ch DIRECT 1

2020-06-14 16:08:40 (7088): Guest Log: 16:08:34 +03 +03:00 2020-06-14: cranky: [INFO] Checking runc.

2020-06-14 16:08:45 (7088): Guest Log: 16:08:39 +03 +03:00 2020-06-14: cranky: [INFO] Creating the filesystem.

2020-06-14 16:08:45 (7088): Guest Log: 16:08:39 +03 +03:00 2020-06-14: cranky: [INFO] Using /cvmfs/cernvm-prod.cern.ch/cvm3

2020-06-14 16:08:45 (7088): Guest Log: 16:08:39 +03 +03:00 2020-06-14: cranky: [INFO] Updating config.json.

2020-06-14 16:08:45 (7088): Guest Log: 16:08:39 +03 +03:00 2020-06-14: cranky: [INFO] Running Container 'runc'.

2020-06-14 16:08:56 (7088): Guest Log: job: htmld=/shared/html/job

2020-06-14 16:08:58 (7088): Guest Log: job: unpack exitcode=0

2020-06-14 16:08:59 (7088): Guest Log: 16:08:53 +03 +03:00 2020-06-14: cranky: [INFO] ===> [runRivet] Sun Jun 14 13:08:51 UTC 2020 [boinc ppbar jets 1800 65 - pythia6 6.426 356 100000 14]

2020-06-14 16:09:16 (7088): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-14 16:09:26 (7088): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-14 16:09:36 (7088): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-14 16:10:05 (7088): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-14 16:10:15 (7088): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-14 16:10:35 (7088): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-14 16:10:56 (7088): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-14 16:11:46 (7088): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-14 16:12:37 (7088): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-14 16:12:46 (7088): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-14 16:13:07 (7088): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-14 16:13:16 (7088): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-14 16:13:37 (7088): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-14 16:27:48 (7088): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-14 16:27:58 (7088): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-14 16:28:08 (7088): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-14 16:34:00 (7088): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-14 16:34:11 (7088): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-14 16:35:21 (7088): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-14 16:35:31 (7088): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-14 16:35:47 (7088): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-14 16:36:01 (7088): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-14 16:54:04 (7088): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-14 16:54:14 (7088): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-14 16:54:54 (7088): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-14 16:55:04 (7088): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-14 16:55:26 (7088): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-14 16:55:34 (7088): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-14 16:58:26 (7088): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-14 16:58:35 (7088): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-14 16:58:47 (7088): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-14 17:00:26 (7088): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-14 17:00:57 (7088): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-14 17:01:11 (7088): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-14 17:01:20 (7088): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-14 17:24:47 (7088): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-14 17:25:07 (7088): Guest Log: 00:34:38.966845 timesync vgsvcTimeSyncWorker: Radical guest time change: 2 580 808 345 000ns (GuestNow=1 592 144 706 890 320 000 ns GuestLast=1 592 142 126 081 975 000 ns fSetTimeLastLoop=true )

2020-06-14 17:25:19 (7088): Stopping VM.
2020-06-14 17:27:23 (7060): Detected: vboxwrapper 26197
2020-06-14 17:27:23 (7060): Detected: BOINC client v7.7
2020-06-14 17:27:25 (7060): Detected: VirtualBox VboxManage Interface (Version: 5.2.8)
2020-06-14 17:27:25 (7060): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2020-06-14 17:27:26 (7060): Starting VM using VBoxManage interface. (boinc_bd675fcb59a5bb8f, slot#1)
2020-06-14 17:27:39 (7060): Successfully started VM. (PID = '15132')
2020-06-14 17:27:39 (7060): Reporting VM Process ID to BOINC.
2020-06-14 17:27:39 (7060): VM state change detected. (old = 'PoweredOff', new = 'Running')
2020-06-14 17:27:39 (7060): Detected: Web Application Enabled (http://localhost:57929)
2020-06-14 17:27:40 (7060): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-14 17:27:48 (7060): Status Report: Job Duration: '864000.000000'
2020-06-14 17:27:48 (7060): Status Report: Elapsed Time: '18412.667538'
2020-06-14 17:27:48 (7060): Status Report: CPU Time: '17830.859375'
2020-06-14 17:27:48 (7060): Preference change detected
2020-06-14 17:27:48 (7060): Setting CPU throttle for VM. (100%)
2020-06-14 17:27:49 (7060): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2020-06-14 17:27:49 (7060): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-14 17:28:48 (7060): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-14 17:29:39 (7060): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-14 17:32:43 (7060): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-14 17:32:50 (7060): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-14 17:48:55 (7060): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-14 17:49:03 (7060): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-14 17:50:53 (7060): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-14 17:51:03 (7060): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-14 17:51:34 (7060): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-14 17:51:44 (7060): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-14 17:51:54 (7060): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-14 17:52:04 (7060): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-14 17:52:13 (7060): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-14 17:52:33 (7060): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-14 17:52:43 (7060): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-14 17:52:53 (7060): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-14 17:53:03 (7060): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-14 17:53:13 (7060): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-14 17:53:23 (7060): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-14 17:53:33 (7060): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-14 17:53:43 (7060): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-14 17:53:54 (7060): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-14 17:54:04 (7060): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-14 17:54:14 (7060): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-14 17:54:24 (7060): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-14 17:54:34 (7060): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-14 17:54:44 (7060): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-14 17:54:54 (7060): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-14 17:55:04 (7060): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-14 17:55:14 (7060): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-14 17:55:24 (7060): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-14 17:55:34 (7060): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-14 17:55:44 (7060): Stopping VM.
2020-06-14 17:55:44 (7060): Error in stop VM for VM: -108
Command:
VBoxManage -q controlvm "boinc_bd675fcb59a5bb8f" savestate
Output:

2020-06-14 17:55:44 (7060): VM did not stop when requested.
2020-06-14 17:55:44 (7060): VM was successfully terminated.
2020-06-15 15:03:52 (896): Detected: vboxwrapper 26197
2020-06-15 15:03:52 (896): Detected: BOINC client v7.7
2020-06-15 15:04:04 (896): Detected: VirtualBox VboxManage Interface (Version: 5.2.8)
2020-06-15 15:04:04 (896): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2020-06-15 15:04:06 (896): Starting VM using VBoxManage interface. (boinc_bd675fcb59a5bb8f, slot#1)
2020-06-15 15:05:01 (896): Successfully started VM. (PID = '11956')
2020-06-15 15:05:01 (896): Reporting VM Process ID to BOINC.
2020-06-15 15:05:01 (896): Guest Log: BIOS: VirtualBox 5.2.8

2020-06-15 15:05:01 (896): Guest Log: CPUID EDX: 0x078bfbff

2020-06-15 15:05:01 (896): Guest Log: BIOS: ata0-0: PCHS=16383/16/63 LCHS=1024/255/63

2020-06-15 15:05:01 (896): VM state change detected. (old = 'PoweredOff', new = 'Running')
2020-06-15 15:05:01 (896): Detected: Web Application Enabled (http://localhost:57929)
2020-06-15 15:05:01 (896): Status Report: Job Duration: '864000.000000'
2020-06-15 15:05:01 (896): Status Report: Elapsed Time: '19745.267462'
2020-06-15 15:05:01 (896): Status Report: CPU Time: '19262.171875'
2020-06-15 15:05:01 (896): Preference change detected
2020-06-15 15:05:01 (896): Setting CPU throttle for VM. (100%)
2020-06-15 15:05:02 (896): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2020-06-15 15:05:02 (896): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032

2020-06-15 15:05:03 (896): Guest Log: BIOS: Booting from Hard Disk...

2020-06-15 15:05:10 (896): Guest Log: BIOS: KBD: unsupported int 16h function 03

2020-06-15 15:05:10 (896): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 

2020-06-15 15:06:01 (896): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds

2020-06-15 15:06:01 (896): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000)

2020-06-15 15:06:07 (896): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log

2020-06-15 15:06:07 (896): Guest Log: 00:00:00.000125 main     Log opened 2020-06-15T12:06:08.885849000Z

2020-06-15 15:06:07 (896): Guest Log: 00:00:00.000261 main     OS Product: Linux

2020-06-15 15:06:07 (896): Guest Log: 00:00:00.000329 main     OS Release: 4.14.76-13.cernvm.x86_64

2020-06-15 15:06:07 (896): Guest Log: 00:00:00.000364 main     OS Version: #1 SMP Tue Oct 16 18:26:15 CEST 2018

2020-06-15 15:06:07 (896): Guest Log: 00:00:00.000397 main     Executable: /usr/sbin/VBoxService

2020-06-15 15:06:07 (896): Guest Log: 00:00:00.000398 main     Process ID: 3202

2020-06-15 15:06:07 (896): Guest Log: 00:00:00.000399 main     Package type: LINUX_64BITS_GENERIC

2020-06-15 15:06:07 (896): Guest Log: 00:00:00.005537 main     5.2.6 r120293 started. Verbose level = 0

2020-06-15 15:06:53 (896): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-15 15:07:12 (896): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-15 15:07:13 (896): Guest Log: 15:06:56 +03 +03:00 2020-06-15: cranky: [INFO] Detected Theory App

2020-06-15 15:07:13 (896): Guest Log: 15:06:56 +03 +03:00 2020-06-15: cranky: [INFO] Checking CVMFS.

2020-06-15 15:07:14 (896): Guest Log: Probing /cvmfs/sft.cern.ch... OK

2020-06-15 15:07:16 (896): Guest Log: VERSION PID UPTIME(M) MEM(K) REVISION EXPIRES(M) NOCATALOGS CACHEUSE(K) CACHEMAX(K) NOFDUSE NOFDMAX NOIOERR NOOPEN HITRATE(%) RX(K) SPEED(K/S) HOST PROXY ONLINE

2020-06-15 15:07:16 (896): Guest Log: 2.5.2.0 4087 0 28612 18229 3 1 1117919 4096001 0 65024 0 0 n/a 5 3 http://s1asgc-cvmfs.openhtc.io:8080/cvmfs/sft.cern.ch DIRECT 1

2020-06-15 15:07:18 (896): Guest Log: Probing /cvmfs/grid.cern.ch... OK

2020-06-15 15:07:19 (896): Guest Log: VERSION PID UPTIME(M) MEM(K) REVISION EXPIRES(M) NOCATALOGS CACHEUSE(K) CACHEMAX(K) NOFDUSE NOFDMAX NOIOERR NOOPEN HITRATE(%) RX(K) SPEED(K/S) HOST PROXY ONLINE

2020-06-15 15:07:19 (896): Guest Log: 2.5.2.0 4145 0 27664 12485 3 2 1117919 4096001 0 65024 0 2 0 4272 273 http://s1asgc-cvmfs.openhtc.io:8080/cvmfs/grid.cern.ch DIRECT 1

2020-06-15 15:07:20 (896): Guest Log: Probing /cvmfs/cernvm-prod.cern.ch... OK

2020-06-15 15:07:21 (896): Guest Log: VERSION PID UPTIME(M) MEM(K) REVISION EXPIRES(M) NOCATALOGS CACHEUSE(K) CACHEMAX(K) NOFDUSE NOFDMAX NOIOERR NOOPEN HITRATE(%) RX(K) SPEED(K/S) HOST PROXY ONLINE

2020-06-15 15:07:21 (896): Guest Log: 2.5.2.0 4065 0 27204 252 3 1 1117919 4096001 0 65024 0 0 n/a 0 0 http://s1ral-cvmfs.openhtc.io/cvmfs/cernvm-prod.cern.ch DIRECT 1

2020-06-15 15:07:22 (896): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-15 15:07:32 (896): Guest Log: Probing /cvmfs/alice.cern.ch... OK

2020-06-15 15:07:32 (896): Guest Log: VERSION PID UPTIME(M) MEM(K) REVISION EXPIRES(M) NOCATALOGS CACHEUSE(K) CACHEMAX(K) NOFDUSE NOFDMAX NOIOERR NOOPEN HITRATE(%) RX(K) SPEED(K/S) HOST PROXY ONLINE

2020-06-15 15:07:32 (896): Guest Log: 2.5.2.0 4115 0 29256 6798 3 1 1117919 4096001 0 65024 0 0 n/a 4128 1163 http://s1cern-cvmfs.openhtc.io/cvmfs/alice.cern.ch DIRECT 1

2020-06-15 15:07:32 (896): Guest Log: 15:07:05 +03 +03:00 2020-06-15: cranky: [INFO] Checking runc.

2020-06-15 15:07:32 (896): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-15 15:07:33 (896): Guest Log: 15:07:06 +03 +03:00 2020-06-15: cranky: [INFO] Creating the filesystem.

2020-06-15 15:07:33 (896): Guest Log: 15:07:06 +03 +03:00 2020-06-15: cranky: [INFO] Using /cvmfs/cernvm-prod.cern.ch/cvm3

2020-06-15 15:07:33 (896): Guest Log: 15:07:06 +03 +03:00 2020-06-15: cranky: [INFO] Updating config.json.

2020-06-15 15:07:33 (896): Guest Log: 15:07:06 +03 +03:00 2020-06-15: cranky: [INFO] Running Container 'runc'.

2020-06-15 15:07:36 (896): Guest Log: job: htmld=/shared/html/job

2020-06-15 15:07:36 (896): Guest Log: job: unpack exitcode=0

2020-06-15 15:07:37 (896): Guest Log: 15:07:10 +03 +03:00 2020-06-15: cranky: [INFO] ===> [runRivet] Mon Jun 15 12:07:09 UTC 2020 [boinc ppbar jets 1800 65 - pythia6 6.426 356 100000 14]

2020-06-15 15:08:53 (896): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-15 15:09:03 (896): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-15 15:09:13 (896): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-15 15:09:43 (896): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-15 15:10:03 (896): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-15 15:10:23 (896): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-15 15:10:34 (896): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-15 15:10:44 (896): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-15 15:12:44 (896): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-15 15:12:54 (896): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-15 15:13:04 (896): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-15 15:17:14 (896): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-15 15:17:26 (896): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-15 15:19:16 (896): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-15 15:19:26 (896): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-15 15:58:07 (896): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-15 15:58:12 (896): Guest Log: 00:05:40.576552 timesync vgsvcTimeSyncWorker: Radical host time change: 2 330 626 000 000ns (HostNow=1 592 225 891 600 000 000 ns HostLast=1 592 223 560 974 000 000 ns)

2020-06-15 15:58:17 (896): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-15 15:58:47 (896): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-15 15:58:52 (896): Guest Log: 00:05:50.688195 timesync vgsvcTimeSyncWorker: Radical guest time change: 2 792 663 738 000ns (GuestNow=1 592 225 901 867 923 000 ns GuestLast=1 592 223 109 204 185 000 ns fSetTimeLastLoop=true )

2020-06-15 15:58:58 (896): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-15 16:14:39 (896): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-15 16:14:49 (896): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-15 16:25:19 (896): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-15 16:25:29 (896): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-15 16:25:49 (896): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-15 16:26:00 (896): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-15 16:36:10 (896): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-15 16:36:20 (896): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-15 16:36:41 (896): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-15 16:36:50 (896): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-15 16:37:20 (896): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-15 16:37:30 (896): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-15 16:52:32 (896): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-15 16:52:42 (896): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-15 16:53:02 (896): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-15 16:53:12 (896): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-15 16:53:42 (896): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-15 16:53:52 (896): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-15 16:54:02 (896): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-15 16:57:25 (896): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-15 17:04:33 (896): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-15 17:05:24 (896): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-15 17:14:54 (896): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-15 17:15:04 (896): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-15 17:15:14 (896): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-15 17:15:46 (896): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-15 17:16:04 (896): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-15 17:16:25 (896): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-15 17:16:34 (896): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-15 17:16:54 (896): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-15 17:17:23 (896): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-15 17:17:25 (896): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-15 17:18:04 (896): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-15 17:18:25 (896): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-15 17:25:07 (896): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-15 17:25:15 (896): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-15 17:25:19 (896): Stopping VM.
2020-06-15 17:25:20 (896): Error in stop VM for VM: -108
Command:
VBoxManage -q controlvm "boinc_bd675fcb59a5bb8f" savestate
Output:

2020-06-15 17:25:20 (896): VM did not stop when requested.
2020-06-15 17:25:20 (896): VM was successfully terminated.
2020-06-15 21:01:38 (288): Detected: vboxwrapper 26197
2020-06-15 21:01:38 (288): Detected: BOINC client v7.7
2020-06-15 21:01:47 (288): Detected: VirtualBox VboxManage Interface (Version: 5.2.8)
2020-06-15 21:01:48 (288): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2020-06-15 21:01:49 (288): Starting VM using VBoxManage interface. (boinc_bd675fcb59a5bb8f, slot#1)
2020-06-15 21:02:35 (288): Successfully started VM. (PID = '4964')
2020-06-15 21:02:35 (288): Reporting VM Process ID to BOINC.
2020-06-15 21:02:35 (288): Guest Log: BIOS: VirtualBox 5.2.8

2020-06-15 21:02:35 (288): Guest Log: CPUID EDX: 0x078bfbff

2020-06-15 21:02:35 (288): Guest Log: BIOS: ata0-0: PCHS=16383/16/63 LCHS=1024/255/63

2020-06-15 21:02:35 (288): VM state change detected. (old = 'PoweredOff', new = 'Running')
2020-06-15 21:02:35 (288): Detected: Web Application Enabled (http://localhost:57929)
2020-06-15 21:02:35 (288): Status Report: Job Duration: '864000.000000'
2020-06-15 21:02:35 (288): Status Report: Elapsed Time: '20519.319943'
2020-06-15 21:02:35 (288): Status Report: CPU Time: '19897.187500'
2020-06-15 21:02:35 (288): Preference change detected
2020-06-15 21:02:35 (288): Setting CPU throttle for VM. (100%)
2020-06-15 21:02:36 (288): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2020-06-15 21:02:37 (288): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032

2020-06-15 21:02:37 (288): Guest Log: BIOS: Booting from Hard Disk...

2020-06-15 21:02:43 (288): Guest Log: BIOS: KBD: unsupported int 16h function 03

2020-06-15 21:02:43 (288): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 

2020-06-15 21:03:28 (288): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds

2020-06-15 21:03:28 (288): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000)

2020-06-15 21:03:32 (288): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log

2020-06-15 21:03:32 (288): Guest Log: 00:00:00.000100 main     Log opened 2020-06-15T18:03:33.375738000Z

2020-06-15 21:03:32 (288): Guest Log: 00:00:00.000189 main     OS Product: Linux

2020-06-15 21:03:32 (288): Guest Log: 00:00:00.000222 main     OS Release: 4.14.76-13.cernvm.x86_64

2020-06-15 21:03:32 (288): Guest Log: 00:00:00.000251 main     OS Version: #1 SMP Tue Oct 16 18:26:15 CEST 2018

2020-06-15 21:03:32 (288): Guest Log: 00:00:00.000307 main     Executable: /usr/sbin/VBoxService

2020-06-15 21:03:32 (288): Guest Log: 00:00:00.000308 main     Process ID: 3176

2020-06-15 21:03:32 (288): Guest Log: 00:00:00.000309 main     Package type: LINUX_64BITS_GENERIC

2020-06-15 21:03:32 (288): Guest Log: 00:00:00.006483 main     5.2.6 r120293 started. Verbose level = 0

2020-06-15 21:04:14 (288): Guest Log: 21:04:16 +03 +03:00 2020-06-15: cranky: [INFO] Detected Theory App

2020-06-15 21:04:14 (288): Guest Log: 21:04:16 +03 +03:00 2020-06-15: cranky: [INFO] Checking CVMFS.

2020-06-15 21:04:15 (288): Guest Log: Probing /cvmfs/sft.cern.ch... OK

2020-06-15 21:04:16 (288): Guest Log: VERSION PID UPTIME(M) MEM(K) REVISION EXPIRES(M) NOCATALOGS CACHEUSE(K) CACHEMAX(K) NOFDUSE NOFDMAX NOIOERR NOOPEN HITRATE(%) RX(K) SPEED(K/S) HOST PROXY ONLINE

2020-06-15 21:04:16 (288): Guest Log: 2.5.2.0 4061 0 27192 18238 3 1 1360939 4096001 0 65024 0 0 n/a 5 6 http://s1unl-cvmfs.openhtc.io/cvmfs/sft.cern.ch DIRECT 1

2020-06-15 21:04:17 (288): Guest Log: Probing /cvmfs/grid.cern.ch... OK

2020-06-15 21:04:18 (288): Guest Log: VERSION PID UPTIME(M) MEM(K) REVISION EXPIRES(M) NOCATALOGS CACHEUSE(K) CACHEMAX(K) NOFDUSE NOFDMAX NOIOERR NOOPEN HITRATE(%) RX(K) SPEED(K/S) HOST PROXY ONLINE

2020-06-15 21:04:18 (288): Guest Log: 2.5.2.0 4082 0 25496 12485 3 2 1360939 4096001 0 65024 0 2 100 0 0 http://s1unl-cvmfs.openhtc.io/cvmfs/grid.cern.ch DIRECT 1

2020-06-15 21:04:19 (288): Guest Log: Probing /cvmfs/cernvm-prod.cern.ch... OK

2020-06-15 21:04:19 (288): Guest Log: VERSION PID UPTIME(M) MEM(K) REVISION EXPIRES(M) NOCATALOGS CACHEUSE(K) CACHEMAX(K) NOFDUSE NOFDMAX NOIOERR NOOPEN HITRATE(%) RX(K) SPEED(K/S) HOST PROXY ONLINE

2020-06-15 21:04:19 (288): Guest Log: 2.5.2.0 4108 0 26464 252 3 1 1360939 4096001 0 65024 0 0 n/a 0 0 http://s1cern-cvmfs.openhtc.io/cvmfs/cernvm-prod.cern.ch DIRECT 1

2020-06-15 21:04:20 (288): Guest Log: Probing /cvmfs/alice.cern.ch... OK

2020-06-15 21:04:21 (288): Guest Log: VERSION PID UPTIME(M) MEM(K) REVISION EXPIRES(M) NOCATALOGS CACHEUSE(K) CACHEMAX(K) NOFDUSE NOFDMAX NOIOERR NOOPEN HITRATE(%) RX(K) SPEED(K/S) HOST PROXY ONLINE

2020-06-15 21:04:21 (288): Guest Log: 2.5.2.0 4136 0 30736 6802 3 1 1360939 4096001 0 65024 0 0 n/a 4128 1040 http://s1fnal-cvmfs.openhtc.io/cvmfs/alice.cern.ch DIRECT 1

2020-06-15 21:04:21 (288): Guest Log: 21:04:23 +03 +03:00 2020-06-15: cranky: [INFO] Checking runc.

2020-06-15 21:04:22 (288): Guest Log: 21:04:23 +03 +03:00 2020-06-15: cranky: [INFO] Creating the filesystem.

2020-06-15 21:04:22 (288): Guest Log: 21:04:23 +03 +03:00 2020-06-15: cranky: [INFO] Using /cvmfs/cernvm-prod.cern.ch/cvm3

2020-06-15 21:04:22 (288): Guest Log: 21:04:24 +03 +03:00 2020-06-15: cranky: [INFO] Updating config.json.

2020-06-15 21:04:22 (288): Guest Log: 21:04:24 +03 +03:00 2020-06-15: cranky: [INFO] Running Container 'runc'.

2020-06-15 21:04:32 (288): Guest Log: job: htmld=/shared/html/job

2020-06-15 21:04:32 (288): Guest Log: job: unpack exitcode=0

2020-06-15 21:04:32 (288): Guest Log: 21:04:34 +03 +03:00 2020-06-15: cranky: [INFO] ===> [runRivet] Mon Jun 15 18:04:34 UTC 2020 [boinc ppbar jets 1800 65 - pythia6 6.426 356 100000 14]

2020-06-15 21:13:39 (288): Stopping VM.
2020-06-15 21:13:39 (288): Error in stop VM for VM: -108
Command:
VBoxManage -q controlvm "boinc_bd675fcb59a5bb8f" savestate
Output:

2020-06-15 21:13:39 (288): VM did not stop when requested.
2020-06-15 21:13:39 (288): VM was successfully terminated.
2020-06-16 16:24:08 (13604): Detected: vboxwrapper 26197
2020-06-16 16:24:08 (13604): Detected: BOINC client v7.7
2020-06-16 16:24:11 (13604): Detected: VirtualBox VboxManage Interface (Version: 5.2.8)
2020-06-16 16:24:11 (13604): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2020-06-16 16:24:12 (13604): Starting VM using VBoxManage interface. (boinc_bd675fcb59a5bb8f, slot#1)
2020-06-16 16:24:22 (13604): Successfully started VM. (PID = '14200')
2020-06-16 16:24:22 (13604): Reporting VM Process ID to BOINC.
2020-06-16 16:24:22 (13604): Guest Log: BIOS: VirtualBox 5.2.8

2020-06-16 16:24:22 (13604): Guest Log: CPUID EDX: 0x078bfbff

2020-06-16 16:24:22 (13604): Guest Log: BIOS: ata0-0: PCHS=16383/16/63 LCHS=1024/255/63

2020-06-16 16:24:22 (13604): VM state change detected. (old = 'PoweredOff', new = 'Running')
2020-06-16 16:24:22 (13604): Detected: Web Application Enabled (http://localhost:57929)
2020-06-16 16:24:23 (13604): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-16 16:24:44 (13604): Status Report: Job Duration: '864000.000000'
2020-06-16 16:24:44 (13604): Status Report: Elapsed Time: '21171.319943'
2020-06-16 16:24:44 (13604): Status Report: CPU Time: '20249.125000'
2020-06-16 16:24:44 (13604): Preference change detected
2020-06-16 16:24:44 (13604): Setting CPU throttle for VM. (100%)
2020-06-16 16:24:45 (13604): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2020-06-16 16:24:45 (13604): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-16 16:24:46 (13604): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032

2020-06-16 16:24:46 (13604): Guest Log: BIOS: Booting from Hard Disk...

2020-06-16 16:24:51 (13604): Guest Log: BIOS: KBD: unsupported int 16h function 03

2020-06-16 16:24:51 (13604): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 

2020-06-16 16:25:34 (13604): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds

2020-06-16 16:25:34 (13604): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000)

2020-06-16 16:25:42 (13604): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log

2020-06-16 16:25:42 (13604): Guest Log: 00:00:00.000208 main     Log opened 2020-06-16T13:25:44.227120000Z

2020-06-16 16:25:42 (13604): Guest Log: 00:00:00.000321 main     OS Product: Linux

2020-06-16 16:25:42 (13604): Guest Log: 00:00:00.000360 main     OS Release: 4.14.76-13.cernvm.x86_64

2020-06-16 16:25:42 (13604): Guest Log: 00:00:00.000399 main     OS Version: #1 SMP Tue Oct 16 18:26:15 CEST 2018

2020-06-16 16:25:42 (13604): Guest Log: 00:00:00.000432 main     Executable: /usr/sbin/VBoxService

2020-06-16 16:25:42 (13604): Guest Log: 00:00:00.000433 main     Process ID: 3218

2020-06-16 16:25:42 (13604): Guest Log: 00:00:00.000434 main     Package type: LINUX_64BITS_GENERIC

2020-06-16 16:25:42 (13604): Guest Log: 00:00:00.006757 main     5.2.6 r120293 started. Verbose level = 0

2020-06-16 16:26:33 (13604): Guest Log: 16:26:34 +03 +03:00 2020-06-16: cranky: [INFO] Detected Theory App

2020-06-16 16:26:33 (13604): Guest Log: 16:26:34 +03 +03:00 2020-06-16: cranky: [INFO] Checking CVMFS.

2020-06-16 16:26:34 (13604): Guest Log: Probing /cvmfs/sft.cern.ch... OK

2020-06-16 16:26:35 (13604): Guest Log: VERSION PID UPTIME(M) MEM(K) REVISION EXPIRES(M) NOCATALOGS CACHEUSE(K) CACHEMAX(K) NOFDUSE NOFDMAX NOIOERR NOOPEN HITRATE(%) RX(K) SPEED(K/S) HOST PROXY ONLINE

2020-06-16 16:26:35 (13604): Guest Log: 2.5.2.0 4117 0 27208 18245 3 1 1629569 4096001 0 65024 0 0 n/a 5 4 http://s1bnl-cvmfs.openhtc.io/cvmfs/sft.cern.ch DIRECT 1

2020-06-16 16:26:36 (13604): Guest Log: Probing /cvmfs/grid.cern.ch... OK

2020-06-16 16:26:38 (13604): Guest Log: VERSION PID UPTIME(M) MEM(K) REVISION EXPIRES(M) NOCATALOGS CACHEUSE(K) CACHEMAX(K) NOFDUSE NOFDMAX NOIOERR NOOPEN HITRATE(%) RX(K) SPEED(K/S) HOST PROXY ONLINE

2020-06-16 16:26:38 (13604): Guest Log: 2.5.2.0 4070 0 25496 12493 3 2 1629569 4096001 0 65024 0 2 0 4273 643 http://s1cern-cvmfs.openhtc.io/cvmfs/grid.cern.ch DIRECT 1

2020-06-16 16:26:39 (13604): Guest Log: Probing /cvmfs/cernvm-prod.cern.ch... OK

2020-06-16 16:26:39 (13604): Guest Log: VERSION PID UPTIME(M) MEM(K) REVISION EXPIRES(M) NOCATALOGS CACHEUSE(K) CACHEMAX(K) NOFDUSE NOFDMAX NOIOERR NOOPEN HITRATE(%) RX(K) SPEED(K/S) HOST PROXY ONLINE

2020-06-16 16:26:39 (13604): Guest Log: 2.5.2.0 4097 0 27232 253 3 1 1629569 4096001 0 65024 0 0 n/a 4 2 http://s1bnl-cvmfs.openhtc.io/cvmfs/cernvm-prod.cern.ch DIRECT 1

2020-06-16 16:26:40 (13604): Guest Log: Probing /cvmfs/alice.cern.ch... OK

2020-06-16 16:26:41 (13604): Guest Log: VERSION PID UPTIME(M) MEM(K) REVISION EXPIRES(M) NOCATALOGS CACHEUSE(K) CACHEMAX(K) NOFDUSE NOFDMAX NOIOERR NOOPEN HITRATE(%) RX(K) SPEED(K/S) HOST PROXY ONLINE

2020-06-16 16:26:41 (13604): Guest Log: 2.5.2.0 4150 0 30420 6805 3 1 1629569 4096001 0 65024 0 0 n/a 4133 267 http://s1unl-cvmfs.openhtc.io/cvmfs/alice.cern.ch DIRECT 1

2020-06-16 16:26:41 (13604): Guest Log: 16:26:42 +03 +03:00 2020-06-16: cranky: [INFO] Checking runc.

2020-06-16 16:26:42 (13604): Guest Log: 16:26:44 +03 +03:00 2020-06-16: cranky: [INFO] Creating the filesystem.

2020-06-16 16:26:43 (13604): Guest Log: 16:26:44 +03 +03:00 2020-06-16: cranky: [INFO] Using /cvmfs/cernvm-prod.cern.ch/cvm3

2020-06-16 16:26:43 (13604): Guest Log: 16:26:44 +03 +03:00 2020-06-16: cranky: [INFO] Updating config.json.

2020-06-16 16:26:43 (13604): Guest Log: 16:26:44 +03 +03:00 2020-06-16: cranky: [INFO] Running Container 'runc'.

2020-06-16 16:26:55 (13604): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-16 16:27:05 (13604): Guest Log: job: htmld=/shared/html/job

2020-06-16 16:27:05 (13604): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-16 16:27:06 (13604): Guest Log: job: unpack exitcode=0

2020-06-16 16:27:07 (13604): Guest Log: 16:26:59 +03 +03:00 2020-06-16: cranky: [INFO] ===> [runRivet] Tue Jun 16 13:26:59 UTC 2020 [boinc ppbar jets 1800 65 - pythia6 6.426 356 100000 14]

2020-06-16 16:28:06 (13604): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-16 16:28:55 (13604): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-16 16:29:06 (13604): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-16 16:29:16 (13604): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-16 16:29:36 (13604): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-16 16:37:07 (13604): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-16 16:39:37 (13604): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-16 16:41:37 (13604): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-16 16:44:28 (13604): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-16 16:59:38 (13604): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-16 16:59:56 (13604): Stopping VM.
2020-06-16 20:43:15 (2088): Detected: vboxwrapper 26197
2020-06-16 20:43:15 (2088): Detected: BOINC client v7.7
2020-06-16 20:43:16 (2088): Detected: VirtualBox VboxManage Interface (Version: 5.2.8)
2020-06-16 20:43:16 (2088): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2020-06-16 20:43:17 (2088): Starting VM using VBoxManage interface. (boinc_bd675fcb59a5bb8f, slot#1)
2020-06-16 20:43:30 (2088): Successfully started VM. (PID = '15344')
2020-06-16 20:43:30 (2088): Reporting VM Process ID to BOINC.
2020-06-16 20:43:30 (2088): VM state change detected. (old = 'PoweredOff', new = 'Running')
2020-06-16 20:43:30 (2088): Detected: Web Application Enabled (http://localhost:57929)
2020-06-16 20:43:31 (2088): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-16 20:43:36 (2088): Status Report: Job Duration: '864000.000000'
2020-06-16 20:43:36 (2088): Status Report: Elapsed Time: '21720.319943'
2020-06-16 20:43:36 (2088): Status Report: CPU Time: '20322.203125'
2020-06-16 20:43:36 (2088): Preference change detected
2020-06-16 20:43:36 (2088): Setting CPU throttle for VM. (100%)
2020-06-16 20:43:36 (2088): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2020-06-16 20:43:37 (2088): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-16 20:43:39 (2088): Guest Log: 00:08:30.028815 timesync vgsvcTimeSyncWorker: Radical host time change: 13 427 778 000 000ns (HostNow=1 592 329 418 368 000 000 ns HostLast=1 592 315 990 590 000 000 ns)

2020-06-16 20:43:49 (2088): Guest Log: 00:08:40.029820 timesync vgsvcTimeSyncWorker: Radical guest time change: 13 427 778 717 000ns (GuestNow=1 592 329 428 369 042 000 ns GuestLast=1 592 316 000 590 325 000 ns fSetTimeLastLoop=true )

2020-06-16 20:50:19 (2088): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-16 20:50:49 (2088): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-16 20:51:38 (2088): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-16 20:51:39 (2088): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-16 20:51:51 (2088): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-16 20:52:19 (2088): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-16 20:52:29 (2088): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-16 20:52:39 (2088): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-16 20:52:50 (2088): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-16 20:52:59 (2088): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-16 20:55:39 (2088): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-16 20:55:49 (2088): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-16 20:56:10 (2088): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-16 20:59:30 (2088): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-16 20:59:40 (2088): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-16 21:00:00 (2088): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-16 21:00:20 (2088): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-16 21:01:00 (2088): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-16 21:01:30 (2088): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-16 21:01:40 (2088): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-16 21:05:21 (2088): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-16 21:05:31 (2088): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-16 21:06:01 (2088): Stopping VM.
2020-06-16 21:06:03 (2088): Error in stop VM for VM: -108
Command:
VBoxManage -q controlvm "boinc_bd675fcb59a5bb8f" savestate
Output:

2020-06-16 21:06:03 (2088): VM did not stop when requested.
2020-06-16 21:06:03 (2088): VM was successfully terminated.
2020-06-17 00:36:22 (4588): Detected: vboxwrapper 26197
2020-06-17 00:36:22 (4588): Detected: BOINC client v7.7
2020-06-17 00:36:26 (4588): Detected: VirtualBox VboxManage Interface (Version: 5.2.8)
2020-06-17 00:36:26 (4588): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2020-06-17 00:36:29 (4588): Starting VM using VBoxManage interface. (boinc_bd675fcb59a5bb8f, slot#1)
2020-06-17 00:36:49 (4588): Successfully started VM. (PID = '2840')
2020-06-17 00:36:49 (4588): Reporting VM Process ID to BOINC.
2020-06-17 00:36:49 (4588): Guest Log: BIOS: VirtualBox 5.2.8

2020-06-17 00:36:49 (4588): Guest Log: CPUID EDX: 0x078bfbff

2020-06-17 00:36:49 (4588): Guest Log: BIOS: ata0-0: PCHS=16383/16/63 LCHS=1024/255/63

2020-06-17 00:36:49 (4588): VM state change detected. (old = 'PoweredOff', new = 'Running')
2020-06-17 00:36:49 (4588): Detected: Web Application Enabled (http://localhost:57929)
2020-06-17 00:36:50 (4588): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-17 00:37:00 (4588): Status Report: Job Duration: '864000.000000'
2020-06-17 00:37:00 (4588): Status Report: Elapsed Time: '22669.319943'
2020-06-17 00:37:00 (4588): Status Report: CPU Time: '20747.484375'
2020-06-17 00:37:00 (4588): Preference change detected
2020-06-17 00:37:00 (4588): Setting CPU throttle for VM. (100%)
2020-06-17 00:37:00 (4588): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2020-06-17 00:37:00 (4588): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-17 00:37:01 (4588): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032

2020-06-17 00:37:01 (4588): Guest Log: BIOS: Booting from Hard Disk...

2020-06-17 00:37:07 (4588): Guest Log: BIOS: KBD: unsupported int 16h function 03

2020-06-17 00:37:07 (4588): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 

2020-06-17 00:37:50 (4588): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds

2020-06-17 00:37:50 (4588): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000)

2020-06-17 00:37:56 (4588): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log

2020-06-17 00:37:56 (4588): Guest Log: 00:00:00.000124 main     Log opened 2020-06-16T21:37:55.477707000Z

2020-06-17 00:37:56 (4588): Guest Log: 00:00:00.000274 main     OS Product: Linux

2020-06-17 00:37:56 (4588): Guest Log: 00:00:00.000317 main     OS Release: 4.14.76-13.cernvm.x86_64

2020-06-17 00:37:56 (4588): Guest Log: 00:00:00.000350 main     OS Version: #1 SMP Tue Oct 16 18:26:15 CEST 2018

2020-06-17 00:37:56 (4588): Guest Log: 00:00:00.000440 main     Executable: /usr/sbin/VBoxService

2020-06-17 00:37:56 (4588): Guest Log: 00:00:00.000441 main     Process ID: 3201

2020-06-17 00:37:56 (4588): Guest Log: 00:00:00.000444 main     Package type: LINUX_64BITS_GENERIC

2020-06-17 00:37:56 (4588): Guest Log: 00:00:00.007688 main     5.2.6 r120293 started. Verbose level = 0

2020-06-17 00:38:59 (4588): Guest Log: 00:38:58 +03 +03:00 2020-06-17: cranky: [INFO] Detected Theory App

2020-06-17 00:38:59 (4588): Guest Log: 00:38:58 +03 +03:00 2020-06-17: cranky: [INFO] Checking CVMFS.

2020-06-17 00:39:00 (4588): Guest Log: Probing /cvmfs/sft.cern.ch... OK

2020-06-17 00:39:00 (4588): Guest Log: VERSION PID UPTIME(M) MEM(K) REVISION EXPIRES(M) NOCATALOGS CACHEUSE(K) CACHEMAX(K) NOFDUSE NOFDMAX NOIOERR NOOPEN HITRATE(%) RX(K) SPEED(K/S) HOST PROXY ONLINE

2020-06-17 00:39:00 (4588): Guest Log: 2.5.2.0 4064 0 27184 18248 3 1 2136764 4096001 0 65024 0 0 n/a 0 0 http://s1cern-cvmfs.openhtc.io/cvmfs/sft.cern.ch DIRECT 1

2020-06-17 00:39:01 (4588): Guest Log: Probing /cvmfs/grid.cern.ch... OK

2020-06-17 00:39:02 (4588): Guest Log: VERSION PID UPTIME(M) MEM(K) REVISION EXPIRES(M) NOCATALOGS CACHEUSE(K) CACHEMAX(K) NOFDUSE NOFDMAX NOIOERR NOOPEN HITRATE(%) RX(K) SPEED(K/S) HOST PROXY ONLINE

2020-06-17 00:39:02 (4588): Guest Log: 2.5.2.0 4086 0 25480 12495 3 2 2136764 4096001 0 65024 0 2 0 4272 430 http://s1cern-cvmfs.openhtc.io/cvmfs/grid.cern.ch DIRECT 1

2020-06-17 00:39:04 (4588): Guest Log: Probing /cvmfs/cernvm-prod.cern.ch... OK

2020-06-17 00:39:04 (4588): Guest Log: VERSION PID UPTIME(M) MEM(K) REVISION EXPIRES(M) NOCATALOGS CACHEUSE(K) CACHEMAX(K) NOFDUSE NOFDMAX NOIOERR NOOPEN HITRATE(%) RX(K) SPEED(K/S) HOST PROXY ONLINE

2020-06-17 00:39:04 (4588): Guest Log: 2.5.2.0 4107 0 27188 254 3 1 2136764 4096001 0 65024 0 0 n/a 4 1 http://s1ral-cvmfs.openhtc.io/cvmfs/cernvm-prod.cern.ch DIRECT 1

2020-06-17 00:39:06 (4588): Guest Log: Probing /cvmfs/alice.cern.ch... OK

2020-06-17 00:39:07 (4588): Guest Log: VERSION PID UPTIME(M) MEM(K) REVISION EXPIRES(M) NOCATALOGS CACHEUSE(K) CACHEMAX(K) NOFDUSE NOFDMAX NOIOERR NOOPEN HITRATE(%) RX(K) SPEED(K/S) HOST PROXY ONLINE

2020-06-17 00:39:07 (4588): Guest Log: 2.5.2.0 4140 0 29728 6813 3 1 2136764 4096001 0 65024 0 0 n/a 4135 268 http://s1ral-cvmfs.openhtc.io/cvmfs/alice.cern.ch DIRECT 1

2020-06-17 00:39:07 (4588): Guest Log: 00:39:07 +03 +03:00 2020-06-17: cranky: [INFO] Checking runc.

2020-06-17 00:39:10 (4588): Guest Log: 00:39:10 +03 +03:00 2020-06-17: cranky: [INFO] Creating the filesystem.

2020-06-17 00:39:10 (4588): Guest Log: 00:39:10 +03 +03:00 2020-06-17: cranky: [INFO] Using /cvmfs/cernvm-prod.cern.ch/cvm3

2020-06-17 00:39:10 (4588): Guest Log: 00:39:10 +03 +03:00 2020-06-17: cranky: [INFO] Updating config.json.

2020-06-17 00:39:10 (4588): Guest Log: 00:39:10 +03 +03:00 2020-06-17: cranky: [INFO] Running Container 'runc'.

2020-06-17 00:39:22 (4588): Guest Log: job: htmld=/shared/html/job

2020-06-17 00:39:22 (4588): Guest Log: job: unpack exitcode=0

2020-06-17 00:39:23 (4588): Guest Log: 00:39:22 +03 +03:00 2020-06-17: cranky: [INFO] ===> [runRivet] Tue Jun 16 21:39:22 UTC 2020 [boinc ppbar jets 1800 65 - pythia6 6.426 356 100000 14]

2020-06-17 01:01:34 (4588): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-17 01:01:38 (4588): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-17 01:13:50 (4588): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-17 01:14:00 (4588): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-17 01:17:50 (4588): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-17 01:18:00 (4588): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-17 01:28:10 (4588): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-17 01:28:20 (4588): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-17 01:28:40 (4588): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-17 01:29:20 (4588): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-17 01:29:30 (4588): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-17 01:29:40 (4588): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-17 01:30:00 (4588): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-17 01:52:03 (4588): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-17 02:09:10 (4588): Guest Log: job: run exitcode=0

2020-06-17 02:09:10 (4588): Guest Log: job: diskusage=2120

2020-06-17 02:09:10 (4588): Guest Log: job: logsize=60 k

2020-06-17 02:09:10 (4588): Guest Log: job: times=

2020-06-17 02:09:10 (4588): Guest Log: 0m0.004s 0m0.038s

2020-06-17 02:09:10 (4588): Guest Log: 62m0.244s 0m24.811s

2020-06-17 02:09:10 (4588): Guest Log: job: cpuusage=3745

2020-06-17 02:09:11 (4588): Guest Log: 02:08:22 +03 +03:00 2020-06-17: cranky: [INFO] Container 'runc' finished with status code 0.

2020-06-17 02:09:11 (4588): Guest Log: 02:08:22 +03 +03:00 2020-06-17: cranky: [INFO] Preparing output.

2020-06-17 02:09:13 (4588): Guest Log: [INFO] Job Finished

2020-06-17 02:09:14 (4588): Guest Log: [INFO] Shutting Down.

2020-06-17 02:09:14 (4588): VM Completion File Detected.
2020-06-17 02:09:14 (4588): V
LHC@home

Stderr output
