Job instance 273668831

Name	Theory_2390-1120636-4_1
Workunit	139356808
Created	16 May 2020, 17:08:31 UTC
Sent	16 May 2020, 17:10:47 UTC
Report deadline	27 May 2020, 17:10:47 UTC
Received	28 May 2020, 10:48:43 UTC
Server state	Over
Outcome	Success
Client state	Done
Exit status	0 (0x00000000)
Computer ID	10603097
Run time	20 hours 24 min 47 sec
CPU time	17 hours 35 min 21 sec
Priority	1589632005
Validate state	Valid
Credit	268.10
Device peak FLOPS	1.58 GFLOPS
Application version	Theory Simulation v300.06 (vbox64_theory) windows_x86_64
Peak working set size	65.13 MB
Peak swap size	78.24 MB
Peak disk usage	3.34 GB
Stderr output

<core_client_version>7.6.33</core_client_version>
<![CDATA[
<stderr_txt>
2:26:50 (2772): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 180 seconds) or (Vbox_job.xml: 600 seconds))
2020-05-25 12:26:54 (2772): Guest Log: 00:27:01.305452 timesync vgsvcTimeSyncWorker: Radical host time change: 79 532 605 000 000ns (HostNow=1 590 402 414 112 000 000 ns HostLast=1 590 322 881 507 000 000 ns)

2020-05-25 12:27:04 (2772): Guest Log: 00:27:11.314394 timesync vgsvcTimeSyncWorker: Radical guest time change: 79 532 615 399 000ns (GuestNow=1 590 402 424 121 044 000 ns GuestLast=1 590 322 891 505 645 000 ns fSetTimeLastLoop=true )

2020-05-25 12:35:45 (2772): Stopping VM.
2020-05-25 14:21:04 (3728): Detected: vboxwrapper 26197
2020-05-25 14:21:04 (3728): Detected: BOINC client v7.7
2020-05-25 14:21:15 (3728): Detected: VirtualBox VboxManage Interface (Version: 5.2.6)
2020-05-25 14:21:16 (3728): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2020-05-25 14:21:18 (3728): Starting VM using VBoxManage interface. (boinc_b3c2c36ff4dff6fa, slot#1)
2020-05-25 14:21:37 (3728): Successfully started VM. (PID = '5388')
2020-05-25 14:21:37 (3728): Reporting VM Process ID to BOINC.
2020-05-25 14:21:37 (3728): Guest Log: BIOS: VirtualBox 5.2.6

2020-05-25 14:21:37 (3728): Guest Log: CPUID EDX: 0x078bfbff

2020-05-25 14:21:37 (3728): Guest Log: BIOS: ata0-0: PCHS=16383/16/63 LCHS=1024/255/63

2020-05-25 14:21:37 (3728): VM state change detected. (old = 'PoweredOff', new = 'Running')
2020-05-25 14:21:37 (3728): Detected: Web Application Enabled (http://localhost:51965)
2020-05-25 14:21:37 (3728): Preference change detected
2020-05-25 14:21:37 (3728): Setting CPU throttle for VM. (80%)
2020-05-25 14:21:37 (3728): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 180 seconds) or (Vbox_job.xml: 600 seconds))
2020-05-25 14:21:39 (3728): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032

2020-05-25 14:21:39 (3728): Guest Log: BIOS: Booting from Hard Disk...

2020-05-25 14:21:47 (3728): Guest Log: BIOS: KBD: unsupported int 16h function 03

2020-05-25 14:21:47 (3728): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 

2020-05-25 14:23:12 (3728): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds

2020-05-25 14:23:12 (3728): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000)

2020-05-25 14:23:24 (3728): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log

2020-05-25 14:23:24 (3728): Guest Log: 00:00:00.000332 main     Log opened 2020-05-25T12:23:00.902020000Z

2020-05-25 14:23:24 (3728): Guest Log: 00:00:00.003596 main     OS Product: Linux

2020-05-25 14:23:24 (3728): Guest Log: 00:00:00.003787 main     OS Release: 4.14.76-13.cernvm.x86_64

2020-05-25 14:23:24 (3728): Guest Log: 00:00:00.009956 main     OS Version: #1 SMP Tue Oct 16 18:26:15 CEST 2018

2020-05-25 14:23:24 (3728): Guest Log: 00:00:00.011349 main     Executable: /usr/sbin/VBoxService

2020-05-25 14:23:24 (3728): Guest Log: 00:00:00.011356 main     Process ID: 3213

2020-05-25 14:23:24 (3728): Guest Log: 00:00:00.011358 main     Package type: LINUX_64BITS_GENERIC

2020-05-25 14:23:24 (3728): Guest Log: 00:00:00.027692 main     5.2.6 r120293 started. Verbose level = 0

2020-05-25 14:24:35 (3728): Guest Log: 14:24:34 CEST +02:00 2020-05-25: cranky: [INFO] Detected Theory App

2020-05-25 14:24:35 (3728): Guest Log: 14:24:35 CEST +02:00 2020-05-25: cranky: [INFO] Checking CVMFS.

2020-05-25 14:24:40 (3728): Guest Log: Probing /cvmfs/sft.cern.ch... OK

2020-05-25 14:24:43 (3728): 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-05-25 14:24:43 (3728): Guest Log: 2.5.2.0 4136 0 26352 18076 3 1 1063452 4096001 0 65024 0 0 n/a 0 0 http://s1cern-cvmfs.openhtc.io/cvmfs/sft.cern.ch DIRECT 1

2020-05-25 14:24:48 (3728): Guest Log: Probing /cvmfs/grid.cern.ch... OK

2020-05-25 14:24:50 (3728): 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-05-25 14:24:50 (3728): Guest Log: 2.5.2.0 4183 0 27580 12317 3 2 1063452 4096001 0 65024 0 2 0 4273 952 http://s1cern-cvmfs.openhtc.io/cvmfs/grid.cern.ch DIRECT 1

2020-05-25 14:24:55 (3728): Guest Log: Probing /cvmfs/cernvm-prod.cern.ch... OK

2020-05-25 14:24:57 (3728): 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-05-25 14:24:57 (3728): Guest Log: 2.5.2.0 4112 0 27216 249 3 1 1063452 4096001 0 65024 0 0 n/a 0 0 http://s1cern-cvmfs.openhtc.io/cvmfs/cernvm-prod.cern.ch DIRECT 1

2020-05-25 14:25:03 (3728): Guest Log: Probing /cvmfs/alice.cern.ch... OK

2020-05-25 14:25:05 (3728): 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-05-25 14:25:05 (3728): Guest Log: 2.5.2.0 4161 1 28588 6662 3 1 1063452 4096001 0 65024 0 0 n/a 4070 951 http://s1bnl-cvmfs.openhtc.io/cvmfs/alice.cern.ch DIRECT 1

2020-05-25 14:25:05 (3728): Guest Log: 14:25:05 CEST +02:00 2020-05-25: cranky: [INFO] Checking runc.

2020-05-25 14:25:06 (3728): Guest Log: 14:25:06 CEST +02:00 2020-05-25: cranky: [INFO] Creating the filesystem.

2020-05-25 14:25:06 (3728): Guest Log: 14:25:06 CEST +02:00 2020-05-25: cranky: [INFO] Using /cvmfs/cernvm-prod.cern.ch/cvm3

2020-05-25 14:25:07 (3728): Guest Log: 14:25:07 CEST +02:00 2020-05-25: cranky: [INFO] Updating config.json.

2020-05-25 14:25:07 (3728): Guest Log: 14:25:07 CEST +02:00 2020-05-25: cranky: [INFO] Running Container 'runc'.

2020-05-25 14:25:09 (3728): Guest Log: job: htmld=/shared/html/job

2020-05-25 14:25:09 (3728): Guest Log: job: unpack exitcode=0

2020-05-25 14:25:12 (3728): Guest Log: 14:25:11 CEST +02:00 2020-05-25: cranky: [INFO] ===> [runRivet] Mon May 25 12:25:09 UTC 2020 [boinc pp jets 7000 40,-,460 - pythia6 6.428 373 100000 4]

2020-05-25 14:51:53 (3728): Status Report: Job Duration: '864000.000000'
2020-05-25 14:51:53 (3728): Status Report: Elapsed Time: '6000.862740'
2020-05-25 14:51:53 (3728): Status Report: CPU Time: '4790.671875'
2020-05-25 16:02:59 (3728): VM state change detected. (old = 'Running', new = 'Paused')
2020-05-25 16:03:07 (3728): VM state change detected. (old = 'Paused', new = 'Running')
2020-05-25 16:35:08 (3728): Status Report: Job Duration: '864000.000000'
2020-05-25 16:35:08 (3728): Status Report: Elapsed Time: '12000.886530'
2020-05-25 16:35:08 (3728): Status Report: CPU Time: '10138.828125'
2020-05-25 16:54:21 (3728): VM state change detected. (old = 'Running', new = 'Paused')
2020-05-25 16:54:37 (3728): Error in resume VM for VM: -2135228414
Command:
VBoxManage -q controlvm "boinc_b3c2c36ff4dff6fa" resume
Output:
VBoxManage.exe: error: VM is paused due to host power management
VBoxManage.exe: error: Details: code VBOX_E_INVALID_VM_STATE (0x80bb0002), component ConsoleWrap, interface IConsole, callee IUnknown
VBoxManage.exe: error: Context: "Resume()" at line 410 of file VBoxManageControlVM.cpp

2020-05-25 16:54:37 (3728): Stopping VM.
2020-05-25 16:55:23 (3728): Error in stop VM for VM: -182
Command:
VBoxManage -q controlvm "boinc_b3c2c36ff4dff6fa" savestate
Output:
0%...10%...20%...30%...40%...50%...60%...70%...80%...90%...
2020-05-25 16:55:23 (3728): VM did not stop when requested.
2020-05-25 16:55:23 (3728): VM was successfully terminated.
2020-05-25 17:20:46 (8080): Detected: vboxwrapper 26197
2020-05-25 17:20:46 (8080): Detected: BOINC client v7.7
2020-05-25 17:20:50 (8080): Detected: VirtualBox VboxManage Interface (Version: 5.2.6)
2020-05-25 17:20:52 (8080): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2020-05-25 17:21:00 (8080): Starting VM using VBoxManage interface. (boinc_b3c2c36ff4dff6fa, slot#1)
2020-05-25 17:21:25 (8080): Successfully started VM. (PID = '5112')
2020-05-25 17:21:25 (8080): Reporting VM Process ID to BOINC.
2020-05-25 17:21:25 (8080): Guest Log: BIOS: VirtualBox 5.2.6

2020-05-25 17:21:25 (8080): Guest Log: CPUID EDX: 0x078bfbff

2020-05-25 17:21:25 (8080): Guest Log: BIOS: ata0-0: PCHS=16383/16/63 LCHS=1024/255/63

2020-05-25 17:21:25 (8080): VM state change detected. (old = 'PoweredOff', new = 'Running')
2020-05-25 17:21:25 (8080): Detected: Web Application Enabled (http://localhost:51965)
2020-05-25 17:21:25 (8080): Status Report: Job Duration: '864000.000000'
2020-05-25 17:21:25 (8080): Status Report: Elapsed Time: '13085.022414'
2020-05-25 17:21:25 (8080): Status Report: CPU Time: '11094.531250'
2020-05-25 17:21:25 (8080): Preference change detected
2020-05-25 17:21:25 (8080): Setting CPU throttle for VM. (80%)
2020-05-25 17:21:25 (8080): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 180 seconds) or (Vbox_job.xml: 600 seconds))
2020-05-25 17:21:27 (8080): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032

2020-05-25 17:21:27 (8080): Guest Log: BIOS: Booting from Hard Disk...

2020-05-25 17:21:33 (8080): Guest Log: BIOS: KBD: unsupported int 16h function 03

2020-05-25 17:21:33 (8080): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 

2020-05-25 17:22:33 (8080): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds

2020-05-25 17:22:33 (8080): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000)

2020-05-25 17:22:49 (8080): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log

2020-05-25 17:22:49 (8080): Guest Log: 00:00:00.000302 main     Log opened 2020-05-25T15:22:26.321996000Z

2020-05-25 17:22:49 (8080): Guest Log: 00:00:00.001636 main     OS Product: Linux

2020-05-25 17:22:49 (8080): Guest Log: 00:00:00.001830 main     OS Release: 4.14.76-13.cernvm.x86_64

2020-05-25 17:22:49 (8080): Guest Log: 00:00:00.001943 main     OS Version: #1 SMP Tue Oct 16 18:26:15 CEST 2018

2020-05-25 17:22:49 (8080): Guest Log: 00:00:00.004409 main     Executable: /usr/sbin/VBoxService

2020-05-25 17:22:49 (8080): Guest Log: 00:00:00.004417 main     Process ID: 3233

2020-05-25 17:22:49 (8080): Guest Log: 00:00:00.004419 main     Package type: LINUX_64BITS_GENERIC

2020-05-25 17:22:50 (8080): Guest Log: 00:00:00.010250 main     5.2.6 r120293 started. Verbose level = 0

2020-05-25 17:24:12 (8080): Guest Log: 17:24:11 CEST +02:00 2020-05-25: cranky: [INFO] Detected Theory App

2020-05-25 17:24:12 (8080): Guest Log: 17:24:11 CEST +02:00 2020-05-25: cranky: [INFO] Checking CVMFS.

2020-05-25 17:24:17 (8080): Guest Log: Probing /cvmfs/sft.cern.ch... OK

2020-05-25 17:24:20 (8080): 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-05-25 17:24:20 (8080): Guest Log: 2.5.2.0 4186 0 25148 18076 3 1 1080669 4096001 0 65024 0 0 n/a 0 0 http://s1fnal-cvmfs.openhtc.io/cvmfs/sft.cern.ch DIRECT 1

2020-05-25 17:24:25 (8080): Guest Log: Probing /cvmfs/grid.cern.ch... OK

2020-05-25 17:24:27 (8080): 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-05-25 17:24:27 (8080): Guest Log: 2.5.2.0 4140 0 26128 12319 3 2 1080669 4096001 0 65024 0 2 100 0 0 http://s1cern-cvmfs.openhtc.io/cvmfs/grid.cern.ch DIRECT 1

2020-05-25 17:24:33 (8080): Guest Log: Probing /cvmfs/cernvm-prod.cern.ch... OK

2020-05-25 17:24:36 (8080): 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-05-25 17:24:36 (8080): Guest Log: 2.5.2.0 4115 0 27216 249 3 1 1080669 4096001 0 65024 0 0 n/a 0 0 http://s1cern-cvmfs.openhtc.io/cvmfs/cernvm-prod.cern.ch DIRECT 1

2020-05-25 17:24:42 (8080): Guest Log: Probing /cvmfs/alice.cern.ch... OK

2020-05-25 17:24:44 (8080): 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-05-25 17:24:44 (8080): Guest Log: 2.5.2.0 4163 1 29640 6664 3 1 1080669 4096001 0 65024 0 0 n/a 4070 545 http://s1cern-cvmfs.openhtc.io/cvmfs/alice.cern.ch DIRECT 1

2020-05-25 17:24:44 (8080): Guest Log: 17:24:44 CEST +02:00 2020-05-25: cranky: [INFO] Checking runc.

2020-05-25 17:24:45 (8080): Guest Log: 17:24:44 CEST +02:00 2020-05-25: cranky: [INFO] Creating the filesystem.

2020-05-25 17:24:45 (8080): Guest Log: 17:24:44 CEST +02:00 2020-05-25: cranky: [INFO] Using /cvmfs/cernvm-prod.cern.ch/cvm3

2020-05-25 17:24:46 (8080): Guest Log: 17:24:45 CEST +02:00 2020-05-25: cranky: [INFO] Updating config.json.

2020-05-25 17:24:46 (8080): Guest Log: 17:24:45 CEST +02:00 2020-05-25: cranky: [INFO] Running Container 'runc'.

2020-05-25 17:24:48 (8080): Guest Log: job: htmld=/shared/html/job

2020-05-25 17:24:48 (8080): Guest Log: job: unpack exitcode=0

2020-05-25 17:24:48 (8080): Guest Log: 17:24:48 CEST +02:00 2020-05-25: cranky: [INFO] ===> [runRivet] Mon May 25 15:24:47 UTC 2020 [boinc pp jets 7000 40,-,460 - pythia6 6.428 373 100000 4]

2020-05-25 17:59:51 (8080): VM state change detected. (old = 'Running', new = 'Paused')
2020-05-25 18:00:01 (8080): VM state change detected. (old = 'Paused', new = 'Running')
2020-05-25 19:05:39 (8080): Status Report: Job Duration: '864000.000000'
2020-05-25 19:05:39 (8080): Status Report: Elapsed Time: '19085.487159'
2020-05-25 19:05:39 (8080): Status Report: CPU Time: '16113.703125'
2020-05-25 19:31:16 (8080): Preference change detected
2020-05-25 19:31:18 (8080): Setting CPU throttle for VM. (80%)
2020-05-25 19:31:27 (8080): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 180 seconds) or (Vbox_job.xml: 600 seconds))
2020-05-25 19:49:15 (8080): Stopping VM.
2020-05-25 19:49:22 (8080): Error in stop VM for VM: -108
Command:
VBoxManage -q controlvm "boinc_b3c2c36ff4dff6fa" savestate
Output:

2020-05-25 19:49:22 (8080): VM did not stop when requested.
2020-05-25 19:49:22 (8080): VM was successfully terminated.
2020-05-26 10:26:02 (6860): Detected: vboxwrapper 26197
2020-05-26 10:26:02 (6860): Detected: BOINC client v7.7
2020-05-26 10:26:15 (6860): Detected: VirtualBox VboxManage Interface (Version: 5.2.6)
2020-05-26 10:26:15 (6860): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2020-05-26 10:26:17 (6860): Starting VM using VBoxManage interface. (boinc_b3c2c36ff4dff6fa, slot#1)
2020-05-26 10:26:38 (6860): Successfully started VM. (PID = '6804')
2020-05-26 10:26:38 (6860): Reporting VM Process ID to BOINC.
2020-05-26 10:26:38 (6860): Guest Log: BIOS: VirtualBox 5.2.6

2020-05-26 10:26:38 (6860): Guest Log: CPUID EDX: 0x078bfbff

2020-05-26 10:26:38 (6860): Guest Log: BIOS: ata0-0: PCHS=16383/16/63 LCHS=1024/255/63

2020-05-26 10:26:38 (6860): VM state change detected. (old = 'PoweredOff', new = 'Running')
2020-05-26 10:26:38 (6860): Detected: Web Application Enabled (http://localhost:51965)
2020-05-26 10:26:38 (6860): Status Report: Job Duration: '864000.000000'
2020-05-26 10:26:38 (6860): Status Report: Elapsed Time: '21538.582442'
2020-05-26 10:26:38 (6860): Status Report: CPU Time: '18333.671875'
2020-05-26 10:26:38 (6860): Preference change detected
2020-05-26 10:26:38 (6860): Setting CPU throttle for VM. (80%)
2020-05-26 10:26:38 (6860): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 180 seconds) or (Vbox_job.xml: 600 seconds))
2020-05-26 10:26:40 (6860): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032

2020-05-26 10:26:40 (6860): Guest Log: BIOS: Booting from Hard Disk...

2020-05-26 10:26:45 (6860): Guest Log: BIOS: KBD: unsupported int 16h function 03

2020-05-26 10:26:45 (6860): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 

2020-05-26 10:27:53 (6860): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds

2020-05-26 10:27:53 (6860): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000)

2020-05-26 10:28:16 (6860): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log

2020-05-26 10:28:16 (6860): Guest Log: 00:00:00.000291 main     Log opened 2020-05-26T08:27:52.064223000Z

2020-05-26 10:28:16 (6860): Guest Log: 00:00:00.002286 main     OS Product: Linux

2020-05-26 10:28:16 (6860): Guest Log: 00:00:00.005451 main     OS Release: 4.14.76-13.cernvm.x86_64

2020-05-26 10:28:16 (6860): Guest Log: 00:00:00.006306 main     OS Version: #1 SMP Tue Oct 16 18:26:15 CEST 2018

2020-05-26 10:28:16 (6860): Guest Log: 00:00:00.006997 main     Executable: /usr/sbin/VBoxService

2020-05-26 10:28:16 (6860): Guest Log: 00:00:00.007004 main     Process ID: 3236

2020-05-26 10:28:16 (6860): Guest Log: 00:00:00.007006 main     Package type: LINUX_64BITS_GENERIC

2020-05-26 10:28:16 (6860): Guest Log: 00:00:00.015755 main     5.2.6 r120293 started. Verbose level = 0

2020-05-26 10:29:44 (6860): Guest Log: 10:29:44 CEST +02:00 2020-05-26: cranky: [INFO] Detected Theory App

2020-05-26 10:29:44 (6860): Guest Log: 10:29:44 CEST +02:00 2020-05-26: cranky: [INFO] Checking CVMFS.

2020-05-26 10:29:49 (6860): Guest Log: Probing /cvmfs/sft.cern.ch... OK

2020-05-26 10:29:51 (6860): 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-05-26 10:29:52 (6860): Guest Log: 2.5.2.0 4166 0 28096 18077 3 1 1097907 4096001 0 65024 0 0 n/a 5 6 http://s1cern-cvmfs.openhtc.io/cvmfs/sft.cern.ch DIRECT 1

2020-05-26 10:29:56 (6860): Guest Log: Probing /cvmfs/grid.cern.ch... OK

2020-05-26 10:29:59 (6860): 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-05-26 10:29:59 (6860): Guest Log: 2.5.2.0 4188 1 27624 12325 3 2 1097907 4096001 0 65024 0 2 0 4273 958 http://s1cern-cvmfs.openhtc.io/cvmfs/grid.cern.ch DIRECT 1

2020-05-26 10:30:04 (6860): Guest Log: Probing /cvmfs/cernvm-prod.cern.ch... OK

2020-05-26 10:30:07 (6860): 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-05-26 10:30:07 (6860): Guest Log: 2.5.2.0 4145 1 25184 249 3 1 1097907 4096001 0 65024 0 0 n/a 0 0 http://s1cern-cvmfs.openhtc.io/cvmfs/cernvm-prod.cern.ch DIRECT 1

2020-05-26 10:30:11 (6860): Guest Log: Probing /cvmfs/alice.cern.ch... OK

2020-05-26 10:30:14 (6860): 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-05-26 10:30:14 (6860): Guest Log: 2.5.2.0 4124 1 28384 6668 3 1 1097907 4096001 0 65024 0 0 n/a 4071 1154 http://s1cern-cvmfs.openhtc.io/cvmfs/alice.cern.ch DIRECT 1

2020-05-26 10:30:14 (6860): Guest Log: 10:30:14 CEST +02:00 2020-05-26: cranky: [INFO] Checking runc.

2020-05-26 10:30:16 (6860): Guest Log: 10:30:16 CEST +02:00 2020-05-26: cranky: [INFO] Creating the filesystem.

2020-05-26 10:30:17 (6860): Guest Log: 10:30:16 CEST +02:00 2020-05-26: cranky: [INFO] Using /cvmfs/cernvm-prod.cern.ch/cvm3

2020-05-26 10:30:18 (6860): Guest Log: 10:30:17 CEST +02:00 2020-05-26: cranky: [INFO] Updating config.json.

2020-05-26 10:30:18 (6860): Guest Log: 10:30:18 CEST +02:00 2020-05-26: cranky: [INFO] Running Container 'runc'.

2020-05-26 10:30:25 (6860): Guest Log: job: htmld=/shared/html/job

2020-05-26 10:30:25 (6860): Guest Log: job: unpack exitcode=0

2020-05-26 10:30:26 (6860): Guest Log: 10:30:26 CEST +02:00 2020-05-26: cranky: [INFO] ===> [runRivet] Tue May 26 08:30:25 UTC 2020 [boinc pp jets 7000 40,-,460 - pythia6 6.428 373 100000 4]

2020-05-26 10:45:51 (6860): VM state change detected. (old = 'Running', new = 'Paused')
2020-05-26 10:46:01 (6860): VM state change detected. (old = 'Paused', new = 'Running')
2020-05-26 11:36:51 (6860): Stopping VM.
2020-05-26 11:36:52 (6860): Error in stop VM for VM: -108
Command:
VBoxManage -q controlvm "boinc_b3c2c36ff4dff6fa" savestate
Output:

2020-05-26 11:36:52 (6860): VM did not stop when requested.
2020-05-26 11:36:52 (6860): VM was successfully terminated.
2020-05-26 14:43:13 (6276): Detected: vboxwrapper 26197
2020-05-26 14:43:13 (6276): Detected: BOINC client v7.7
2020-05-26 14:43:29 (6276): Detected: VirtualBox VboxManage Interface (Version: 5.2.6)
2020-05-26 14:43:29 (6276): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2020-05-26 14:43:32 (6276): Starting VM using VBoxManage interface. (boinc_b3c2c36ff4dff6fa, slot#1)
2020-05-26 14:43:50 (6276): Successfully started VM. (PID = '1028')
2020-05-26 14:43:50 (6276): Reporting VM Process ID to BOINC.
2020-05-26 14:43:50 (6276): Guest Log: BIOS: VirtualBox 5.2.6

2020-05-26 14:43:50 (6276): Guest Log: CPUID EDX: 0x078bfbff

2020-05-26 14:43:50 (6276): Guest Log: BIOS: ata0-0: PCHS=16383/16/63 LCHS=1024/255/63

2020-05-26 14:43:50 (6276): VM state change detected. (old = 'PoweredOff', new = 'Running')
2020-05-26 14:43:50 (6276): Detected: Web Application Enabled (http://localhost:51965)
2020-05-26 14:43:50 (6276): Status Report: Job Duration: '864000.000000'
2020-05-26 14:43:50 (6276): Status Report: Elapsed Time: '25666.205407'
2020-05-26 14:43:50 (6276): Status Report: CPU Time: '21888.656250'
2020-05-26 14:43:50 (6276): Preference change detected
2020-05-26 14:43:50 (6276): Setting CPU throttle for VM. (80%)
2020-05-26 14:43:50 (6276): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 180 seconds) or (Vbox_job.xml: 600 seconds))
2020-05-26 14:43:52 (6276): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032

2020-05-26 14:43:52 (6276): Guest Log: BIOS: Booting from Hard Disk...

2020-05-26 14:43:58 (6276): Guest Log: BIOS: KBD: unsupported int 16h function 03

2020-05-26 14:43:58 (6276): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 

2020-05-26 14:45:40 (6276): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds

2020-05-26 14:45:40 (6276): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000)

2020-05-26 14:45:53 (6276): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log

2020-05-26 14:45:53 (6276): Guest Log: 00:00:00.000920 main     Log opened 2020-05-26T12:45:28.355506000Z

2020-05-26 14:45:53 (6276): Guest Log: 00:00:00.010529 main     OS Product: Linux

2020-05-26 14:45:53 (6276): Guest Log: 00:00:00.010762 main     OS Release: 4.14.76-13.cernvm.x86_64

2020-05-26 14:45:53 (6276): Guest Log: 00:00:00.010906 main     OS Version: #1 SMP Tue Oct 16 18:26:15 CEST 2018

2020-05-26 14:45:53 (6276): Guest Log: 00:00:00.011022 main     Executable: /usr/sbin/VBoxService

2020-05-26 14:45:53 (6276): Guest Log: 00:00:00.011025 main     Process ID: 3235

2020-05-26 14:45:53 (6276): Guest Log: 00:00:00.011027 main     Package type: LINUX_64BITS_GENERIC

2020-05-26 14:45:53 (6276): Guest Log: 00:00:00.020173 main     5.2.6 r120293 started. Verbose level = 0

2020-05-26 14:47:09 (6276): Guest Log: 14:47:08 CEST +02:00 2020-05-26: cranky: [INFO] Detected Theory App

2020-05-26 14:47:09 (6276): Guest Log: 14:47:08 CEST +02:00 2020-05-26: cranky: [INFO] Checking CVMFS.

2020-05-26 14:47:14 (6276): Guest Log: Probing /cvmfs/sft.cern.ch... OK

2020-05-26 14:47:17 (6276): 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-05-26 14:47:17 (6276): Guest Log: 2.5.2.0 4147 0 26256 18089 3 1 1097945 4096001 0 65024 0 0 n/a 5 2 http://s1unl-cvmfs.openhtc.io/cvmfs/sft.cern.ch DIRECT 1

2020-05-26 14:47:24 (6276): Guest Log: Probing /cvmfs/grid.cern.ch... OK

2020-05-26 14:47:26 (6276): 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-05-26 14:47:26 (6276): Guest Log: 2.5.2.0 4192 0 27356 12325 3 2 1097945 4096001 0 65024 0 2 100 0 0 http://s1cern-cvmfs.openhtc.io/cvmfs/grid.cern.ch DIRECT 1

2020-05-26 14:47:32 (6276): Guest Log: Probing /cvmfs/cernvm-prod.cern.ch... OK

2020-05-26 14:47:35 (6276): 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-05-26 14:47:35 (6276): Guest Log: 2.5.2.0 4170 0 25488 249 3 1 1097945 4096001 0 65024 0 0 n/a 0 0 http://s1cern-cvmfs.openhtc.io/cvmfs/cernvm-prod.cern.ch DIRECT 1

2020-05-26 14:47:40 (6276): Guest Log: Probing /cvmfs/alice.cern.ch... OK

2020-05-26 14:47:43 (6276): 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-05-26 14:47:43 (6276): Guest Log: 2.5.2.0 4120 1 30628 6668 3 1 1097945 4096001 0 65024 0 0 n/a 0 0 http://s1cern-cvmfs.openhtc.io/cvmfs/alice.cern.ch DIRECT 1

2020-05-26 14:47:43 (6276): Guest Log: 14:47:42 CEST +02:00 2020-05-26: cranky: [INFO] Checking runc.

2020-05-26 14:47:45 (6276): Guest Log: 14:47:44 CEST +02:00 2020-05-26: cranky: [INFO] Creating the filesystem.

2020-05-26 14:47:46 (6276): Guest Log: 14:47:45 CEST +02:00 2020-05-26: cranky: [INFO] Using /cvmfs/cernvm-prod.cern.ch/cvm3

2020-05-26 14:47:47 (6276): Guest Log: 14:47:46 CEST +02:00 2020-05-26: cranky: [INFO] Updating config.json.

2020-05-26 14:47:47 (6276): Guest Log: 14:47:47 CEST +02:00 2020-05-26: cranky: [INFO] Running Container 'runc'.

2020-05-26 14:47:49 (6276): Guest Log: job: htmld=/shared/html/job

2020-05-26 14:47:50 (6276): Guest Log: job: unpack exitcode=0

2020-05-26 14:47:52 (6276): Guest Log: 14:47:51 CEST +02:00 2020-05-26: cranky: [INFO] ===> [runRivet] Tue May 26 12:47:49 UTC 2020 [boinc pp jets 7000 40,-,460 - pythia6 6.428 373 100000 4]

2020-05-26 15:01:45 (6276): VM state change detected. (old = 'Running', new = 'Paused')
2020-05-26 15:01:53 (6276): VM state change detected. (old = 'Paused', new = 'Running')
2020-05-26 15:03:47 (6276): VM state change detected. (old = 'Running', new = 'Paused')
2020-05-26 15:03:57 (6276): VM state change detected. (old = 'Paused', new = 'Running')
2020-05-26 15:04:30 (6276): VM state change detected. (old = 'Running', new = 'Paused')
2020-05-26 15:04:38 (6276): VM state change detected. (old = 'Paused', new = 'Running')
2020-05-26 15:04:59 (6276): VM state change detected. (old = 'Running', new = 'Paused')
2020-05-26 15:05:09 (6276): VM state change detected. (old = 'Paused', new = 'Running')
2020-05-26 15:05:30 (6276): VM state change detected. (old = 'Running', new = 'Paused')
2020-05-26 15:05:40 (6276): VM state change detected. (old = 'Paused', new = 'Running')
2020-05-26 15:05:53 (6276): Stopping VM.
2020-05-26 15:05:54 (6276): Error in stop VM for VM: -108
Command:
VBoxManage -q controlvm "boinc_b3c2c36ff4dff6fa" savestate
Output:

2020-05-26 15:05:54 (6276): VM did not stop when requested.
2020-05-26 15:05:54 (6276): VM was successfully terminated.
2020-05-26 16:08:55 (5340): Detected: vboxwrapper 26197
2020-05-26 16:08:55 (5340): Detected: BOINC client v7.7
2020-05-26 16:09:10 (5340): Detected: VirtualBox VboxManage Interface (Version: 5.2.6)
2020-05-26 16:09:11 (5340): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2020-05-26 16:09:15 (5340): Starting VM using VBoxManage interface. (boinc_b3c2c36ff4dff6fa, slot#1)
2020-05-26 16:09:54 (5340): Successfully started VM. (PID = '7728')
2020-05-26 16:09:54 (5340): Reporting VM Process ID to BOINC.
2020-05-26 16:09:54 (5340): Guest Log: BIOS: VirtualBox 5.2.6

2020-05-26 16:09:54 (5340): Guest Log: CPUID EDX: 0x078bfbff

2020-05-26 16:09:54 (5340): Guest Log: BIOS: ata0-0: PCHS=16383/16/63 LCHS=1024/255/63

2020-05-26 16:09:54 (5340): VM state change detected. (old = 'PoweredOff', new = 'Running')
2020-05-26 16:09:54 (5340): Detected: Web Application Enabled (http://localhost:51965)
2020-05-26 16:09:54 (5340): Status Report: Job Duration: '864000.000000'
2020-05-26 16:09:54 (5340): Status Report: Elapsed Time: '26871.481330'
2020-05-26 16:09:54 (5340): Status Report: CPU Time: '22833.765625'
2020-05-26 16:09:54 (5340): Preference change detected
2020-05-26 16:09:54 (5340): Setting CPU throttle for VM. (80%)
2020-05-26 16:09:56 (5340): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 180 seconds) or (Vbox_job.xml: 600 seconds))
2020-05-26 16:09:57 (5340): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032

2020-05-26 16:09:57 (5340): Guest Log: BIOS: Booting from Hard Disk...

2020-05-26 16:10:12 (5340): Guest Log: BIOS: KBD: unsupported int 16h function 03

2020-05-26 16:10:36 (5340): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 

2020-05-26 16:10:52 (5340): VM state change detected. (old = 'Running', new = 'Paused')
2020-05-26 16:10:58 (5340): VM state change detected. (old = 'Paused', new = 'Running')
2020-05-26 16:11:37 (5340): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds

2020-05-26 16:11:37 (5340): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000)

2020-05-26 16:12:13 (5340): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log

2020-05-26 16:12:13 (5340): Guest Log: 00:00:00.000292 main     Log opened 2020-05-26T14:11:25.178868000Z

2020-05-26 16:12:19 (5340): Guest Log: 00:00:23.669722 main     OS Product: Linux

2020-05-26 16:12:19 (5340): Guest Log: 00:00:23.669997 main     OS Release: 4.14.76-13.cernvm.x86_64

2020-05-26 16:12:19 (5340): Guest Log: 00:00:23.670101 main     OS Version: #1 SMP Tue Oct 16 18:26:15 CEST 2018

2020-05-26 16:12:19 (5340): Guest Log: 00:00:23.670242 main     Executable: /usr/sbin/VBoxService

2020-05-26 16:12:19 (5340): Guest Log: 00:00:23.670245 main     Process ID: 3229

2020-05-26 16:12:19 (5340): Guest Log: 00:00:23.670247 main     Package type: LINUX_64BITS_GENERIC

2020-05-26 16:12:19 (5340): Guest Log: 00:00:23.675340 main     5.2.6 r120293 started. Verbose level = 0

2020-05-26 16:12:26 (5340): VM state change detected. (old = 'Running', new = 'Paused')
2020-05-26 16:12:36 (5340): VM state change detected. (old = 'Paused', new = 'Running')
2020-05-26 16:13:43 (5340): Guest Log: 16:13:11 CEST +02:00 2020-05-26: cranky: [INFO] Detected Theory App

2020-05-26 16:13:43 (5340): Guest Log: 16:13:11 CEST +02:00 2020-05-26: cranky: [INFO] Checking CVMFS.

2020-05-26 16:13:48 (5340): Guest Log: Probing /cvmfs/sft.cern.ch... OK

2020-05-26 16:13:50 (5340): 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-05-26 16:13:50 (5340): Guest Log: 2.5.2.0 4143 0 28136 18089 3 1 1106518 4096001 0 65024 0 0 n/a 0 0 http://s1cern-cvmfs.openhtc.io/cvmfs/sft.cern.ch DIRECT 1

2020-05-26 16:13:56 (5340): Guest Log: Probing /cvmfs/grid.cern.ch... OK

2020-05-26 16:13:58 (5340): 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-05-26 16:13:58 (5340): Guest Log: 2.5.2.0 4164 0 27640 12327 3 2 1106518 4096001 0 65024 0 2 0 4273 894 http://s1fnal-cvmfs.openhtc.io/cvmfs/grid.cern.ch DIRECT 1

2020-05-26 16:14:03 (5340): Guest Log: Probing /cvmfs/cernvm-prod.cern.ch... OK

2020-05-26 16:14:06 (5340): 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-05-26 16:14:06 (5340): Guest Log: 2.5.2.0 4184 0 25156 249 3 1 1106518 4096001 0 65024 0 0 n/a 0 0 http://s1cern-cvmfs.openhtc.io/cvmfs/cernvm-prod.cern.ch DIRECT 1

2020-05-26 16:14:12 (5340): Guest Log: Probing /cvmfs/alice.cern.ch... OK

2020-05-26 16:14:14 (5340): 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-05-26 16:14:14 (5340): Guest Log: 2.5.2.0 4120 1 30604 6668 3 1 1106518 4096001 0 65024 0 0 n/a 0 0 http://s1cern-cvmfs.openhtc.io/cvmfs/alice.cern.ch DIRECT 1

2020-05-26 16:14:14 (5340): Guest Log: 16:13:42 CEST +02:00 2020-05-26: cranky: [INFO] Checking runc.

2020-05-26 16:14:17 (5340): Guest Log: 16:13:44 CEST +02:00 2020-05-26: cranky: [INFO] Creating the filesystem.

2020-05-26 16:14:17 (5340): Guest Log: 16:13:44 CEST +02:00 2020-05-26: cranky: [INFO] Using /cvmfs/cernvm-prod.cern.ch/cvm3

2020-05-26 16:14:17 (5340): Guest Log: 16:13:44 CEST +02:00 2020-05-26: cranky: [INFO] Updating config.json.

2020-05-26 16:14:18 (5340): Guest Log: 16:13:45 CEST +02:00 2020-05-26: cranky: [INFO] Running Container 'runc'.

2020-05-26 16:14:23 (5340): Guest Log: job: htmld=/shared/html/job

2020-05-26 16:14:23 (5340): Guest Log: job: unpack exitcode=0

2020-05-26 16:14:24 (5340): Guest Log: 16:13:51 CEST +02:00 2020-05-26: cranky: [INFO] ===> [runRivet] Tue May 26 14:13:51 UTC 2020 [boinc pp jets 7000 40,-,460 - pythia6 6.428 373 100000 4]

2020-05-26 16:42:19 (5340): Stopping VM.
2020-05-26 16:47:30 (7620): Detected: vboxwrapper 26197
2020-05-26 16:47:30 (7620): Detected: BOINC client v7.7
2020-05-26 16:47:31 (7620): Detected: VirtualBox VboxManage Interface (Version: 5.2.6)
2020-05-26 16:47:32 (7620): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2020-05-26 16:47:32 (7620): Starting VM using VBoxManage interface. (boinc_b3c2c36ff4dff6fa, slot#1)
2020-05-26 16:47:47 (7620): Successfully started VM. (PID = '5844')
2020-05-26 16:47:47 (7620): Reporting VM Process ID to BOINC.
2020-05-26 16:47:47 (7620): VM state change detected. (old = 'PoweredOff', new = 'Running')
2020-05-26 16:47:47 (7620): Detected: Web Application Enabled (http://localhost:51965)
2020-05-26 16:47:47 (7620): Status Report: Job Duration: '864000.000000'
2020-05-26 16:47:47 (7620): Status Report: Elapsed Time: '28772.590276'
2020-05-26 16:47:47 (7620): Status Report: CPU Time: '24452.031250'
2020-05-26 16:47:47 (7620): Preference change detected
2020-05-26 16:47:47 (7620): Setting CPU throttle for VM. (80%)
2020-05-26 16:47:47 (7620): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 180 seconds) or (Vbox_job.xml: 600 seconds))
2020-05-26 16:47:52 (7620): VM state change detected. (old = 'Running', new = 'Paused')
2020-05-26 16:48:02 (7620): VM state change detected. (old = 'Paused', new = 'Running')
2020-05-26 16:50:12 (7620): Stopping VM.
2020-05-26 16:53:52 (7688): Detected: vboxwrapper 26197
2020-05-26 16:53:52 (7688): Detected: BOINC client v7.7
2020-05-26 16:53:53 (7688): Detected: VirtualBox VboxManage Interface (Version: 5.2.6)
2020-05-26 16:53:53 (7688): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2020-05-26 16:53:53 (7688): Starting VM using VBoxManage interface. (boinc_b3c2c36ff4dff6fa, slot#1)
2020-05-26 16:54:06 (7688): Successfully started VM. (PID = '1768')
2020-05-26 16:54:06 (7688): Reporting VM Process ID to BOINC.
2020-05-26 16:54:06 (7688): VM state change detected. (old = 'PoweredOff', new = 'Running')
2020-05-26 16:54:06 (7688): Detected: Web Application Enabled (http://localhost:51965)
2020-05-26 16:54:06 (7688): Status Report: Job Duration: '864000.000000'
2020-05-26 16:54:06 (7688): Status Report: Elapsed Time: '28906.590276'
2020-05-26 16:54:06 (7688): Status Report: CPU Time: '24578.015625'
2020-05-26 16:54:06 (7688): Preference change detected
2020-05-26 16:54:06 (7688): Setting CPU throttle for VM. (80%)
2020-05-26 16:54:06 (7688): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 180 seconds) or (Vbox_job.xml: 600 seconds))
2020-05-26 18:35:06 (7688): Status Report: Job Duration: '864000.000000'
2020-05-26 18:35:06 (7688): Status Report: Elapsed Time: '34906.922479'
2020-05-26 18:35:06 (7688): Status Report: CPU Time: '29943.234375'
2020-05-26 19:22:26 (7688): VM state change detected. (old = 'Running', new = 'Paused')
2020-05-26 19:22:31 (7688): VM state change detected. (old = 'Paused', new = 'Running')
2020-05-26 19:24:09 (7688): Stopping VM.
2020-05-26 19:24:58 (7688): Error in stop VM for VM: -182
Command:
VBoxManage -q controlvm "boinc_b3c2c36ff4dff6fa" savestate
Output:
0%...10%...20%...30%...40%...50%...60%...70%...80%...90%...
2020-05-26 19:24:58 (7688): VM did not stop when requested.
2020-05-26 19:24:58 (7688): VM was successfully terminated.
2020-05-27 09:35:23 (1020): Detected: vboxwrapper 26197
2020-05-27 09:35:23 (1020): Detected: BOINC client v7.7
2020-05-27 09:35:30 (1020): Detected: VirtualBox VboxManage Interface (Version: 5.2.6)
2020-05-27 09:35:31 (1020): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2020-05-27 09:35:32 (1020): Starting VM using VBoxManage interface. (boinc_b3c2c36ff4dff6fa, slot#1)
2020-05-27 09:35:55 (1020): Successfully started VM. (PID = '8940')
2020-05-27 09:35:55 (1020): Reporting VM Process ID to BOINC.
2020-05-27 09:35:55 (1020): Guest Log: BIOS: VirtualBox 5.2.6

2020-05-27 09:35:55 (1020): Guest Log: CPUID EDX: 0x078bfbff

2020-05-27 09:35:55 (1020): Guest Log: BIOS: ata0-0: PCHS=16383/16/63 LCHS=1024/255/63

2020-05-27 09:35:55 (1020): VM state change detected. (old = 'PoweredOff', new = 'Running')
2020-05-27 09:35:55 (1020): Detected: Web Application Enabled (http://localhost:51965)
2020-05-27 09:35:55 (1020): Status Report: Job Duration: '864000.000000'
2020-05-27 09:35:55 (1020): Status Report: Elapsed Time: '37766.040046'
2020-05-27 09:35:55 (1020): Status Report: CPU Time: '32480.187500'
2020-05-27 09:35:55 (1020): Preference change detected
2020-05-27 09:35:55 (1020): Setting CPU throttle for VM. (80%)
2020-05-27 09:35:56 (1020): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 180 seconds) or (Vbox_job.xml: 600 seconds))
2020-05-27 09:35:57 (1020): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032

2020-05-27 09:35:57 (1020): Guest Log: BIOS: Booting from Hard Disk...

2020-05-27 09:36:06 (1020): Guest Log: BIOS: KBD: unsupported int 16h function 03

2020-05-27 09:36:06 (1020): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 

2020-05-27 09:37:06 (1020): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds

2020-05-27 09:37:06 (1020): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000)

2020-05-27 09:37:20 (1020): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log

2020-05-27 09:37:20 (1020): Guest Log: 00:00:00.008895 main     Log opened 2020-05-27T07:36:54.870920000Z

2020-05-27 09:37:20 (1020): Guest Log: 00:00:00.014370 main     OS Product: Linux

2020-05-27 09:37:20 (1020): Guest Log: 00:00:00.017667 main     OS Release: 4.14.76-13.cernvm.x86_64

2020-05-27 09:37:20 (1020): Guest Log: 00:00:00.019603 main     OS Version: #1 SMP Tue Oct 16 18:26:15 CEST 2018

2020-05-27 09:37:20 (1020): Guest Log: 00:00:00.024630 main     Executable: /usr/sbin/VBoxService

2020-05-27 09:37:20 (1020): Guest Log: 00:00:00.024638 main     Process ID: 3220

2020-05-27 09:37:21 (1020): Guest Log: 00:00:00.024640 main     Package type: LINUX_64BITS_GENERIC

2020-05-27 09:37:21 (1020): Guest Log: 00:00:00.059334 main     5.2.6 r120293 started. Verbose level = 0

2020-05-27 09:38:46 (1020): Guest Log: 09:38:46 CEST +02:00 2020-05-27: cranky: [INFO] Detected Theory App

2020-05-27 09:38:46 (1020): Guest Log: 09:38:46 CEST +02:00 2020-05-27: cranky: [INFO] Checking CVMFS.

2020-05-27 09:38:53 (1020): Guest Log: Probing /cvmfs/sft.cern.ch... OK

2020-05-27 09:38:55 (1020): 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-05-27 09:38:55 (1020): Guest Log: 2.5.2.0 4148 0 28504 18090 3 1 1123758 4096001 0 65024 0 0 n/a 5 6 http://s1cern-cvmfs.openhtc.io/cvmfs/sft.cern.ch DIRECT 1

2020-05-27 09:39:01 (1020): Guest Log: Probing /cvmfs/grid.cern.ch... OK

2020-05-27 09:39:04 (1020): 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-05-27 09:39:04 (1020): Guest Log: 2.5.2.0 4125 0 27664 12333 3 2 1123758 4096001 0 65024 0 2 0 4273 914 http://s1cern-cvmfs.openhtc.io/cvmfs/grid.cern.ch DIRECT 1

2020-05-27 09:39:10 (1020): Guest Log: Probing /cvmfs/cernvm-prod.cern.ch... OK

2020-05-27 09:39:13 (1020): 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-05-27 09:39:13 (1020): Guest Log: 2.5.2.0 4170 0 28252 249 3 1 1123758 4096001 0 65024 0 0 n/a 0 0 http://s1cern-cvmfs.openhtc.io/cvmfs/cernvm-prod.cern.ch DIRECT 1

2020-05-27 09:39:18 (1020): Guest Log: Probing /cvmfs/alice.cern.ch... OK

2020-05-27 09:39:21 (1020): 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-05-27 09:39:21 (1020): Guest Log: 2.5.2.0 4191 1 30728 6674 3 1 1123758 4096001 0 65024 0 0 n/a 4073 1173 http://s1ral-cvmfs.openhtc.io/cvmfs/alice.cern.ch DIRECT 1

2020-05-27 09:39:21 (1020): Guest Log: 09:39:20 CEST +02:00 2020-05-27: cranky: [INFO] Checking runc.

2020-05-27 09:39:21 (1020): Guest Log: 09:39:21 CEST +02:00 2020-05-27: cranky: [INFO] Creating the filesystem.

2020-05-27 09:39:22 (1020): Guest Log: 09:39:21 CEST +02:00 2020-05-27: cranky: [INFO] Using /cvmfs/cernvm-prod.cern.ch/cvm3

2020-05-27 09:39:22 (1020): Guest Log: 09:39:22 CEST +02:00 2020-05-27: cranky: [INFO] Updating config.json.

2020-05-27 09:39:23 (1020): Guest Log: 09:39:22 CEST +02:00 2020-05-27: cranky: [INFO] Running Container 'runc'.

2020-05-27 09:39:25 (1020): Guest Log: job: htmld=/shared/html/job

2020-05-27 09:39:25 (1020): Guest Log: job: unpack exitcode=0

2020-05-27 09:39:27 (1020): Guest Log: 09:39:27 CEST +02:00 2020-05-27: cranky: [INFO] ===> [runRivet] Wed May 27 07:39:25 UTC 2020 [boinc pp jets 7000 40,-,460 - pythia6 6.428 373 100000 4]

2020-05-27 10:51:22 (1020): VM state change detected. (old = 'Running', new = 'Paused')
2020-05-27 10:51:30 (1020): VM state change detected. (old = 'Paused', new = 'Running')
2020-05-27 11:17:36 (1020): Status Report: Job Duration: '864000.000000'
2020-05-27 11:17:36 (1020): Status Report: Elapsed Time: '43766.040046'
2020-05-27 11:17:36 (1020): Status Report: CPU Time: '37499.734375'
2020-05-27 11:22:12 (1020): VM state change detected. (old = 'Running', new = 'Paused')
2020-05-27 11:22:23 (1020): VM state change detected. (old = 'Paused', new = 'Running')
2020-05-27 12:06:49 (1020): Stopping VM.
2020-05-27 12:30:03 (5244): Detected: vboxwrapper 26197
2020-05-27 12:30:03 (5244): Detected: BOINC client v7.7
2020-05-27 12:30:04 (5244): Detected: VirtualBox VboxManage Interface (Version: 5.2.6)
2020-05-27 12:30:04 (5244): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2020-05-27 12:30:05 (5244): Starting VM using VBoxManage interface. (boinc_b3c2c36ff4dff6fa, slot#1)
2020-05-27 12:30:19 (5244): Successfully started VM. (PID = '7128')
2020-05-27 12:30:19 (5244): Reporting VM Process ID to BOINC.
2020-05-27 12:30:19 (5244): VM state change detected. (old = 'PoweredOff', new = 'Running')
2020-05-27 12:30:19 (5244): Detected: Web Application Enabled (http://localhost:51965)
2020-05-27 12:30:19 (5244): Status Report: Job Duration: '864000.000000'
2020-05-27 12:30:19 (5244): Status Report: Elapsed Time: '46621.956840'
2020-05-27 12:30:19 (5244): Status Report: CPU Time: '40052.875000'
2020-05-27 12:30:19 (5244): Preference change detected
2020-05-27 12:30:19 (5244): Setting CPU throttle for VM. (80%)
2020-05-27 12:30:19 (5244): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 180 seconds) or (Vbox_job.xml: 600 seconds))
2020-05-27 13:09:34 (5244): Stopping VM.
2020-05-27 17:12:00 (7744): Detected: vboxwrapper 26197
2020-05-27 17:12:01 (7744): Detected: BOINC client v7.7
2020-05-27 17:12:09 (7744): Detected: VirtualBox VboxManage Interface (Version: 5.2.6)
2020-05-27 17:12:09 (7744): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2020-05-27 17:12:10 (7744): Starting VM using VBoxManage interface. (boinc_b3c2c36ff4dff6fa, slot#1)
2020-05-27 17:12:29 (7744): Successfully started VM. (PID = '3448')
2020-05-27 17:12:29 (7744): Reporting VM Process ID to BOINC.
2020-05-27 17:12:29 (7744): Guest Log: BIOS: VirtualBox 5.2.6

2020-05-27 17:12:29 (7744): Guest Log: CPUID EDX: 0x078bfbff

2020-05-27 17:12:29 (7744): Guest Log: BIOS: ata0-0: PCHS=16383/16/63 LCHS=1024/255/63

2020-05-27 17:12:29 (7744): VM state change detected. (old = 'PoweredOff', new = 'Running')
2020-05-27 17:12:29 (7744): Detected: Web Application Enabled (http://localhost:51965)
2020-05-27 17:12:29 (7744): Status Report: Job Duration: '864000.000000'
2020-05-27 17:12:29 (7744): Status Report: Elapsed Time: '48933.956840'
2020-05-27 17:12:29 (7744): Status Report: CPU Time: '42114.390625'
2020-05-27 17:12:29 (7744): Preference change detected
2020-05-27 17:12:29 (7744): Setting CPU throttle for VM. (80%)
2020-05-27 17:12:29 (7744): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 180 seconds) or (Vbox_job.xml: 600 seconds))
2020-05-27 17:12:31 (7744): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032

2020-05-27 17:12:31 (7744): Guest Log: BIOS: Booting from Hard Disk...

2020-05-27 17:12:41 (7744): Guest Log: BIOS: KBD: unsupported int 16h function 03

2020-05-27 17:12:41 (7744): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 

2020-05-27 17:14:28 (7744): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds

2020-05-27 17:14:28 (7744): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000)

2020-05-27 17:14:28 (7744): Stopping VM.
2020-05-27 17:18:18 (9420): Detected: vboxwrapper 26197
2020-05-27 17:18:18 (9420): Detected: BOINC client v7.7
2020-05-27 17:18:19 (9420): Detected: VirtualBox VboxManage Interface (Version: 5.2.6)
2020-05-27 17:18:20 (9420): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2020-05-27 17:18:20 (9420): Starting VM using VBoxManage interface. (boinc_b3c2c36ff4dff6fa, slot#1)
2020-05-27 17:18:33 (9420): Successfully started VM. (PID = '9748')
2020-05-27 17:18:33 (9420): Reporting VM Process ID to BOINC.
2020-05-27 17:18:33 (9420): VM state change detected. (old = 'PoweredOff', new = 'Running')
2020-05-27 17:18:33 (9420): Detected: Web Application Enabled (http://localhost:51965)
2020-05-27 17:18:33 (9420): Status Report: Job Duration: '864000.000000'
2020-05-27 17:18:33 (9420): Status Report: Elapsed Time: '49051.534120'
2020-05-27 17:18:33 (9420): Status Report: CPU Time: '42153.515625'
2020-05-27 17:18:33 (9420): Preference change detected
2020-05-27 17:18:33 (9420): Setting CPU throttle for VM. (80%)
2020-05-27 17:18:33 (9420): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 180 seconds) or (Vbox_job.xml: 600 seconds))
2020-05-27 17:18:40 (9420): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log

2020-05-27 17:18:40 (9420): Guest Log: 00:00:00.000339 main     Log opened 2020-05-27T15:14:12.093456000Z

2020-05-27 17:18:40 (9420): Guest Log: 00:00:00.002069 main     OS Product: Linux

2020-05-27 17:18:40 (9420): Guest Log: 00:00:00.002280 main     OS Release: 4.14.76-13.cernvm.x86_64

2020-05-27 17:18:40 (9420): Guest Log: 00:00:00.002391 main     OS Version: #1 SMP Tue Oct 16 18:26:15 CEST 2018

2020-05-27 17:18:40 (9420): Guest Log: 00:00:00.002496 main     Executable: /usr/sbin/VBoxService

2020-05-27 17:18:40 (9420): Guest Log: 00:00:00.002498 main     Process ID: 3245

2020-05-27 17:18:40 (9420): Guest Log: 00:00:00.002500 main     Package type: LINUX_64BITS_GENERIC

2020-05-27 17:18:40 (9420): Guest Log: 00:00:00.044874 main     5.2.6 r120293 started. Verbose level = 0

2020-05-27 17:20:16 (9420): Guest Log: 17:20:15 CEST +02:00 2020-05-27: cranky: [INFO] Detected Theory App

2020-05-27 17:20:16 (9420): Guest Log: 17:20:15 CEST +02:00 2020-05-27: cranky: [INFO] Checking CVMFS.

2020-05-27 17:20:23 (9420): Guest Log: Probing /cvmfs/sft.cern.ch... OK

2020-05-27 17:20:25 (9420): 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-05-27 17:20:25 (9420): Guest Log: 2.5.2.0 4119 0 26172 18108 3 1 1141017 4096001 0 65024 0 0 n/a 5 0 http://s1cern-cvmfs.openhtc.io/cvmfs/sft.cern.ch DIRECT 1

2020-05-27 17:20:32 (9420): Guest Log: Probing /cvmfs/grid.cern.ch... OK

2020-05-27 17:20:35 (9420): 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-05-27 17:20:35 (9420): Guest Log: 2.5.2.0 4187 1 27600 12335 3 2 1141017 4096001 0 65024 0 2 0 4273 197 http://s1cern-cvmfs.openhtc.io/cvmfs/grid.cern.ch DIRECT 1

2020-05-27 17:20:41 (9420): Guest Log: Probing /cvmfs/cernvm-prod.cern.ch... OK

2020-05-27 17:20:44 (9420): 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-05-27 17:20:44 (9420): Guest Log: 2.5.2.0 4145 1 25168 249 3 1 1141017 4096001 0 65024 0 0 n/a 0 0 http://s1bnl-cvmfs.openhtc.io/cvmfs/cernvm-prod.cern.ch DIRECT 1

2020-05-27 17:20:50 (9420): Guest Log: Probing /cvmfs/alice.cern.ch... OK

2020-05-27 17:20:53 (9420): 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-05-27 17:20:53 (9420): Guest Log: 2.5.2.0 4166 1 27872 6675 3 1 1141017 4096001 0 65024 0 0 n/a 4073 384 http://s1cern-cvmfs.openhtc.io/cvmfs/alice.cern.ch DIRECT 1

2020-05-27 17:20:53 (9420): Guest Log: 17:20:52 CEST +02:00 2020-05-27: cranky: [INFO] Checking runc.

2020-05-27 17:20:54 (9420): Guest Log: 17:20:53 CEST +02:00 2020-05-27: cranky: [INFO] Creating the filesystem.

2020-05-27 17:20:54 (9420): Guest Log: 17:20:53 CEST +02:00 2020-05-27: cranky: [INFO] Using /cvmfs/cernvm-prod.cern.ch/cvm3

2020-05-27 17:20:55 (9420): Guest Log: 17:20:54 CEST +02:00 2020-05-27: cranky: [INFO] Updating config.json.

2020-05-27 17:20:55 (9420): Guest Log: 17:20:54 CEST +02:00 2020-05-27: cranky: [INFO] Running Container 'runc'.

2020-05-27 17:20:57 (9420): Guest Log: job: htmld=/shared/html/job

2020-05-27 17:20:57 (9420): Guest Log: job: unpack exitcode=0

2020-05-27 17:20:57 (9420): Guest Log: 17:20:56 CEST +02:00 2020-05-27: cranky: [INFO] ===> [runRivet] Wed May 27 15:20:56 UTC 2020 [boinc pp jets 7000 40,-,460 - pythia6 6.428 373 100000 4]

2020-05-27 17:48:08 (9420): VM state change detected. (old = 'Running', new = 'Paused')
2020-05-27 17:48:16 (9420): VM state change detected. (old = 'Paused', new = 'Running')
2020-05-27 17:49:40 (9420): VM state change detected. (old = 'Running', new = 'Paused')
2020-05-27 17:49:51 (9420): VM state change detected. (old = 'Paused', new = 'Running')
2020-05-27 17:53:57 (9420): VM state change detected. (old = 'Running', new = 'Paused')
2020-05-27 17:54:08 (9420): VM state change detected. (old = 'Paused', new = 'Running')
2020-05-27 18:02:36 (9420): VM state change detected. (old = 'Running', new = 'Paused')
2020-05-27 18:02:46 (9420): VM state change detected. (old = 'Paused', new = 'Running')
2020-05-27 18:08:55 (9420): VM state change detected. (old = 'Running', new = 'Paused')
2020-05-27 18:09:06 (9420): VM state change detected. (old = 'Paused', new = 'Running')
2020-05-27 18:20:35 (9420): VM state change detected. (old = 'Running', new = 'Paused')
2020-05-27 18:21:36 (9420): VM state change detected. (old = 'Paused', new = 'Running')
2020-05-27 18:29:19 (9420): VM state change detected. (old = 'Running', new = 'Paused')
2020-05-27 18:29:30 (9420): VM state change detected. (old = 'Paused', new = 'Running')
2020-05-27 18:30:20 (9420): VM state change detected. (old = 'Running', new = 'Paused')
2020-05-27 18:30:30 (9420): VM state change detected. (old = 'Paused', new = 'Running')
2020-05-27 18:31:51 (9420): VM state change detected. (old = 'Running', new = 'Paused')
2020-05-27 18:32:02 (9420): VM state change detected. (old = 'Paused', new = 'Running')
2020-05-27 18:53:08 (9420): VM state change detected. (old = 'Running', new = 'Paused')
2020-05-27 18:53:18 (9420): VM state change detected. (old = 'Paused', new = 'Running')
2020-05-27 18:53:39 (9420): VM state change detected. (old = 'Running', new = 'Paused')
2020-05-27 18:53:49 (9420): VM state change detected. (old = 'Paused', new = 'Running')
2020-05-27 18:57:12 (9420): VM state change detected. (old = 'Running', new = 'Paused')
2020-05-27 18:57:22 (9420): VM state change detected. (old = 'Paused', new = 'Running')
2020-05-27 18:57:32 (9420): VM state change detected. (old = 'Running', new = 'Paused')
2020-05-27 18:57:42 (9420): VM state change detected. (old = 'Paused', new = 'Running')
2020-05-27 18:58:33 (9420): VM state change detected. (old = 'Running', new = 'Paused')
2020-05-27 18:58:43 (9420): VM state change detected. (old = 'Paused', new = 'Running')
2020-05-27 18:59:54 (9420): VM state change detected. (old = 'Running', new = 'Paused')
2020-05-27 19:00:04 (9420): VM state change detected. (old = 'Paused', new = 'Running')
2020-05-27 19:01:53 (9420): VM state change detected. (old = 'Running', new = 'Paused')
2020-05-27 19:02:08 (9420): VM state change detected. (old = 'Paused', new = 'Running')
2020-05-27 19:04:14 (9420): VM state change detected. (old = 'Running', new = 'Paused')
2020-05-27 19:04:22 (9420): VM state change detected. (old = 'Paused', new = 'Running')
2020-05-27 19:04:35 (9420): VM state change detected. (old = 'Running', new = 'Paused')
2020-05-27 19:04:55 (9420): VM state change detected. (old = 'Paused', new = 'Running')
2020-05-27 19:05:16 (9420): VM state change detected. (old = 'Running', new = 'Paused')
2020-05-27 19:05:26 (9420): VM state change detected. (old = 'Paused', new = 'Running')
2020-05-27 19:06:39 (9420): VM state change detected. (old = 'Running', new = 'Paused')
2020-05-27 19:06:49 (9420): VM state change detected. (old = 'Paused', new = 'Running')
2020-05-27 19:07:29 (9420): Status Report: Job Duration: '864000.000000'
2020-05-27 19:07:29 (9420): Status Report: Elapsed Time: '55052.315378'
2020-05-27 19:07:29 (9420): Status Report: CPU Time: '47319.593750'
2020-05-27 19:08:22 (9420): VM state change detected. (old = 'Running', new = 'Paused')
2020-05-27 19:08:32 (9420): VM state change detected. (old = 'Paused', new = 'Running')
2020-05-27 19:10:16 (9420): Stopping VM.
2020-05-27 19:10:22 (9420): Error in stop VM for VM: -108
Command:
VBoxManage -q controlvm "boinc_b3c2c36ff4dff6fa" savestate
Output:

2020-05-27 19:10:22 (9420): VM did not stop when requested.
2020-05-27 19:10:22 (9420): VM was NOT successfully terminated.
2020-05-28 07:39:03 (4668): Detected: vboxwrapper 26197
2020-05-28 07:39:04 (4668): Detected: BOINC client v7.7
2020-05-28 07:39:10 (4668): Detected: VirtualBox VboxManage Interface (Version: 5.2.6)
2020-05-28 07:39:11 (4668): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2020-05-28 07:39:12 (4668): Starting VM using VBoxManage interface. (boinc_b3c2c36ff4dff6fa, slot#1)
2020-05-28 07:39:32 (4668): Successfully started VM. (PID = '428')
2020-05-28 07:39:32 (4668): Reporting VM Process ID to BOINC.
2020-05-28 07:39:32 (4668): Guest Log: BIOS: VirtualBox 5.2.6

2020-05-28 07:39:32 (4668): Guest Log: CPUID EDX: 0x078bfbff

2020-05-28 07:39:32 (4668): Guest Log: BIOS: ata0-0: PCHS=16383/16/63 LCHS=1024/255/63

2020-05-28 07:39:32 (4668): VM state change detected. (old = 'PoweredOff', new = 'Running')
2020-05-28 07:39:32 (4668): Detected: Web Application Enabled (http://localhost:51965)
2020-05-28 07:39:33 (4668): Status Report: Job Duration: '864000.000000'
2020-05-28 07:39:33 (4668): Status Report: Elapsed Time: '55192.315378'
2020-05-28 07:39:33 (4668): Status Report: CPU Time: '47445.781250'
2020-05-28 07:39:33 (4668): Preference change detected
2020-05-28 07:39:33 (4668): Setting CPU throttle for VM. (80%)
2020-05-28 07:39:33 (4668): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 180 seconds) or (Vbox_job.xml: 600 seconds))
2020-05-28 07:39:35 (4668): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032

2020-05-28 07:39:35 (4668): Guest Log: BIOS: Booting from Hard Disk...

2020-05-28 07:39:49 (4668): Guest Log: BIOS: KBD: unsupported int 16h function 03

2020-05-28 07:39:49 (4668): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 

2020-05-28 07:41:06 (4668): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds

2020-05-28 07:41:06 (4668): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000)

2020-05-28 07:41:18 (4668): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log

2020-05-28 07:41:18 (4668): Guest Log: 00:00:00.000332 main     Log opened 2020-05-28T05:40:50.892043000Z

2020-05-28 07:41:18 (4668): Guest Log: 00:00:00.000673 main     OS Product: Linux

2020-05-28 07:41:18 (4668): Guest Log: 00:00:00.000792 main     OS Release: 4.14.76-13.cernvm.x86_64

2020-05-28 07:41:19 (4668): Guest Log: 00:00:00.000901 main     OS Version: #1 SMP Tue Oct 16 18:26:15 CEST 2018

2020-05-28 07:41:19 (4668): Guest Log: 00:00:00.002662 main     Executable: /usr/sbin/VBoxService

2020-05-28 07:41:19 (4668): Guest Log: 00:00:00.002669 main     Process ID: 3220

2020-05-28 07:41:19 (4668): Guest Log: 00:00:00.002671 main     Package type: LINUX_64BITS_GENERIC

2020-05-28 07:41:19 (4668): Guest Log: 00:00:00.012421 main     5.2.6 r120293 started. Verbose level = 0

2020-05-28 07:42:45 (4668): Guest Log: 07:42:44 CEST +02:00 2020-05-28: cranky: [INFO] Detected Theory App

2020-05-28 07:42:45 (4668): Guest Log: 07:42:44 CEST +02:00 2020-05-28: cranky: [INFO] Checking CVMFS.

2020-05-28 07:42:51 (4668): Guest Log: Probing /cvmfs/sft.cern.ch... OK

2020-05-28 07:42:54 (4668): 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-05-28 07:42:54 (4668): Guest Log: 2.5.2.0 4164 0 28256 18127 3 1 2077375 4096001 0 65024 0 0 n/a 5 2 http://s1bnl-cvmfs.openhtc.io/cvmfs/sft.cern.ch DIRECT 1

2020-05-28 07:43:01 (4668): Guest Log: Probing /cvmfs/grid.cern.ch... OK

2020-05-28 07:43:05 (4668): 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-05-28 07:43:05 (4668): Guest Log: 2.5.2.0 4189 0 25944 12339 3 2 2077375 4096001 0 65024 0 2 0 4273 1078 http://s1cern-cvmfs.openhtc.io/cvmfs/grid.cern.ch DIRECT 1

2020-05-28 07:43:12 (4668): Guest Log: Probing /cvmfs/cernvm-prod.cern.ch... OK

2020-05-28 07:43:15 (4668): 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-05-28 07:43:15 (4668): Guest Log: 2.5.2.0 4116 1 25176 249 3 1 2077375 4096001 0 65024 0 0 n/a 0 0 http://s1unl-cvmfs.openhtc.io/cvmfs/cernvm-prod.cern.ch DIRECT 1

2020-05-28 07:43:22 (4668): Guest Log: Probing /cvmfs/alice.cern.ch... OK

2020-05-28 07:43:25 (4668): 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-05-28 07:43:25 (4668): Guest Log: 2.5.2.0 4137 1 30664 6680 3 1 2077375 4096001 0 65024 0 0 n/a 4075 1125 http://s1cern-cvmfs.openhtc.io/cvmfs/alice.cern.ch DIRECT 1

2020-05-28 07:43:25 (4668): Guest Log: 07:43:25 CEST +02:00 2020-05-28: cranky: [INFO] Checking runc.

2020-05-28 07:43:32 (4668): Guest Log: 07:43:32 CEST +02:00 2020-05-28: cranky: [INFO] Creating the filesystem.

2020-05-28 07:43:33 (4668): Guest Log: 07:43:32 CEST +02:00 2020-05-28: cranky: [INFO] Using /cvmfs/cernvm-prod.cern.ch/cvm3

2020-05-28 07:43:33 (4668): Guest Log: 07:43:33 CEST +02:00 2020-05-28: cranky: [INFO] Updating config.json.

2020-05-28 07:43:34 (4668): Guest Log: 07:43:33 CEST +02:00 2020-05-28: cranky: [INFO] Running Container 'runc'.

2020-05-28 07:43:35 (4668): Guest Log: job: htmld=/shared/html/job

2020-05-28 07:43:35 (4668): Guest Log: job: unpack exitcode=0

2020-05-28 07:43:36 (4668): Guest Log: 07:43:35 CEST +02:00 2020-05-28: cranky: [INFO] ===> [runRivet] Thu May 28 05:43:35 UTC 2020 [boinc pp jets 7000 40,-,460 - pythia6 6.428 373 100000 4]

2020-05-28 09:21:08 (4668): Status Report: Job Duration: '864000.000000'
2020-05-28 09:21:08 (4668): Status Report: Elapsed Time: '61192.608537'
2020-05-28 09:21:08 (4668): Status Report: CPU Time: '52694.562500'
2020-05-28 09:35:42 (4668): Preference change detected
2020-05-28 09:35:42 (4668): Setting CPU throttle for VM. (80%)
2020-05-28 09:35:43 (4668): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 180 seconds) or (Vbox_job.xml: 600 seconds))
2020-05-28 11:01:45 (4668): Status Report: Job Duration: '864000.000000'
2020-05-28 11:01:45 (4668): Status Report: Elapsed Time: '67193.233124'
2020-05-28 11:01:45 (4668): Status Report: CPU Time: '57981.046875'
2020-05-28 12:42:47 (4668): Status Report: Job Duration: '864000.000000'
2020-05-28 12:42:47 (4668): Status Report: Elapsed Time: '73193.233124'
2020-05-28 12:42:47 (4668): Status Report: CPU Time: '63282.765625'
2020-05-28 12:43:28 (4668): Guest Log: job: run exitcode=0

2020-05-28 12:43:28 (4668): Guest Log: job: diskusage=1992

2020-05-28 12:43:28 (4668): Guest Log: job: logsize=80 k

2020-05-28 12:43:28 (4668): Guest Log: job: times=

2020-05-28 12:43:28 (4668): Guest Log: 0m0.023s 0m0.048s

2020-05-28 12:43:28 (4668): Guest Log: 283m16.461s 5m29.850s

2020-05-28 12:43:28 (4668): Guest Log: job: cpuusage=17326

2020-05-28 12:43:29 (4668): Guest Log: 12:43:29 CEST +02:00 2020-05-28: cranky: [INFO] Container 'runc' finished with status code 0.

2020-05-28 12:43:29 (4668): Guest Log: 12:43:29 CEST +02:00 2020-05-28: cranky: [INFO] Preparing output.

2020-05-28 12:43:36 (4668): Guest Log: [INFO] Job Finished

2020-05-28 12:43:36 (4668): Guest Log: [INFO] Shutting Down.

2020-05-28 12:43:36 (4668): VM Completion File Detected.
2020-05-28 12:43:36 (4668): VM Completion Message: Job Finished
.
2020-05-28 12:43:36 (4668): Powering off VM.
2020-05-28 12:48:45 (4668): VM did not power off when requested.
2020-05-28 12:48:45 (4668): VM was successfully terminated.
2020-05-28 12:48:45 (4668): Deregistering VM. (boinc_b3c2c36ff4dff6fa, slot#1)
2020-05-28 12:48:48 (4668): Removing network bandwidth throttle group from VM.
2020-05-28 12:48:48 (4668): Removing VM from VirtualBox.
12:48:54 (4668): called boinc_finish(0)

</stderr_txt>
]]>