Task 276394055

Name	Theory_2390-1106390-14_1
Workunit	140836755
Created	5 Jun 2020, 10:11:05 UTC
Sent	5 Jun 2020, 10:21:10 UTC
Report deadline	16 Jun 2020, 10:21:10 UTC
Received	17 Jun 2020, 21:21:42 UTC
Server state	Over
Outcome	Success
Client state	Done
Exit status	0 (0x00000000)
Computer ID	10592439
Run time	3 days 17 hours 56 min 12 sec
CPU time	10 hours 0 min 57 sec
Validate state	Valid
Credit	2,529.50
Device peak FLOPS	3.38 GFLOPS
Application version	Theory Simulation v300.06 (vbox64_theory) windows_x86_64
Peak working set size	76.21 MB
Peak swap size	80.24 MB
Peak disk usage	5.03 GB
Stderr output

<core_client_version>7.16.5</core_client_version>
<![CDATA[
<stderr_txt>
06-11 11:43:42 (16680): Guest Log: Probing /cvmfs/sft.cern.ch... OK

2020-06-11 11:43:44 (16680): 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-11 11:43:44 (16680): Guest Log: 2.5.2.0 4088 0 28328 18218 3 1 1255201 4096000 0 65024 0 0 n/a 5 0 http://s1ral-cvmfs.openhtc.io/cvmfs/sft.cern.ch DIRECT 1

2020-06-11 11:43:50 (16680): Guest Log: Probing /cvmfs/grid.cern.ch... OK

2020-06-11 11:43:53 (16680): 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-11 11:43:53 (16680): Guest Log: 2.5.2.0 4136 0 27716 12453 3 2 1255201 4096000 0 65024 0 2 0 4273 1187 http://s1cern-cvmfs.openhtc.io/cvmfs/grid.cern.ch DIRECT 1

2020-06-11 11:43:58 (16680): Guest Log: Probing /cvmfs/cernvm-prod.cern.ch... OK

2020-06-11 11:44:02 (16680): 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-11 11:44:02 (16680): Guest Log: 2.5.2.0 4114 0 25160 252 3 1 1255201 4096000 0 65024 0 0 n/a 0 0 http://s1ral-cvmfs.openhtc.io/cvmfs/cernvm-prod.cern.ch DIRECT 1

2020-06-11 11:44:08 (16680): Guest Log: Probing /cvmfs/alice.cern.ch... OK

2020-06-11 11:44:10 (16680): 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-11 11:44:10 (16680): Guest Log: 2.5.2.0 4066 1 27656 6776 3 1 1255201 4096000 0 65024 0 0 n/a 4121 1659 http://s1ral-cvmfs.openhtc.io/cvmfs/alice.cern.ch DIRECT 1

2020-06-11 11:44:10 (16680): Guest Log: 11:43:49 EEST +03:00 2020-06-11: cranky: [INFO] Checking runc.

2020-06-11 11:44:10 (16680): Guest Log: 11:43:49 EEST +03:00 2020-06-11: cranky: [INFO] Creating the filesystem.

2020-06-11 11:44:11 (16680): Guest Log: 11:43:49 EEST +03:00 2020-06-11: cranky: [INFO] Using /cvmfs/cernvm-prod.cern.ch/cvm3

2020-06-11 11:44:11 (16680): Guest Log: 11:43:50 EEST +03:00 2020-06-11: cranky: [INFO] Updating config.json.

2020-06-11 11:44:12 (16680): Guest Log: 11:43:50 EEST +03:00 2020-06-11: cranky: [INFO] Running Container 'runc'.

2020-06-11 11:44:13 (16680): Guest Log: job: htmld=/shared/html/job

2020-06-11 11:44:13 (16680): Guest Log: job: unpack exitcode=0

2020-06-11 11:44:14 (16680): Guest Log: 11:43:52 EEST +03:00 2020-06-11: cranky: [INFO] ===> [runRivet] Thu Jun 11 08:43:51 UTC 2020 [boinc pp jets 13000 250,-,4760 - pythia8 8.243 tune-AU2m 100000 14]

2020-06-11 11:45:22 (16680): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-11 11:45:32 (16680): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-11 13:00:28 (16680): Preference change detected
2020-06-11 13:00:28 (16680): Setting CPU throttle for VM. (40%)
2020-06-11 13:00:28 (16680): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2020-06-11 13:02:28 (16680): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-11 13:02:38 (16680): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-11 13:22:11 (16680): Status Report: Job Duration: '864000.000000'
2020-06-11 13:22:11 (16680): Status Report: Elapsed Time: '99166.975508'
2020-06-11 13:22:11 (16680): Status Report: CPU Time: '55113.625000'
2020-06-11 13:51:53 (16680): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-11 17:50:15 (16680): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-11 17:50:16 (16680): Guest Log: 02:09:11.478734 timesync vgsvcTimeSyncWorker: Radical host time change: 14 309 358 000 000ns (HostNow=1 591 887 012 391 000 000 ns HostLast=1 591 872 703 033 000 000 ns)

2020-06-11 17:50:23 (16680): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-11 17:51:14 (16680): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-11 17:51:17 (16680): Guest Log: 02:09:21.588955 timesync vgsvcTimeSyncWorker: Radical guest time change: 14 348 402 609 000ns (GuestNow=1 591 887 025 718 353 000 ns GuestLast=1 591 872 677 315 744 000 ns fSetTimeLastLoop=true )

2020-06-11 17:55:45 (16680): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-11 17:55:55 (16680): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-11 19:01:41 (16680): Status Report: Job Duration: '864000.000000'
2020-06-11 19:01:41 (16680): Status Report: Elapsed Time: '105167.268334'
2020-06-11 19:01:41 (16680): Status Report: CPU Time: '57820.421875'
2020-06-11 19:06:18 (16680): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-11 19:06:48 (16680): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-11 19:57:30 (16680): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-12 02:03:36 (16680): Error in resume VM for VM: -2135228414
Command:
VBoxManage -q controlvm "boinc_0e063649b902631f" resume
Output:
VBoxManage.exe: error: Cannot resume the machine as it is not paused (machine state: Running)
VBoxManage.exe: error: Details: code VBOX_E_INVALID_VM_STATE (0x80bb0002), component ConsoleWrap, interface IConsole, callee IUnknown
VBoxManage.exe: error: Context: "Resume()" at line 409 of file VBoxManageControlVM.cpp

2020-06-12 02:03:37 (16680): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-12 02:03:46 (16680): Guest Log: 04:15:09.495394 timesync vgsvcTimeSyncWorker: Radical host time change: 21 975 552 000 000ns (HostNow=1 591 916 625 796 000 000 ns HostLast=1 591 894 650 244 000 000 ns)

2020-06-12 02:03:49 (16680): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-12 02:04:09 (16680): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-12 02:04:15 (16680): Guest Log: 04:15:19.496641 timesync vgsvcTimeSyncWorker: Radical guest time change: 22 062 172 437 000ns (GuestNow=1 591 916 635 797 214 000 ns GuestLast=1 591 894 573 624 777 000 ns fSetTimeLastLoop=true )

2020-06-12 02:05:51 (16680): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-12 10:49:07 (16680): Error in resume VM for VM: -2135228414
Command:
VBoxManage -q controlvm "boinc_0e063649b902631f" resume
Output:
VBoxManage.exe: error: Cannot resume the machine as it is not paused (machine state: Running)
VBoxManage.exe: error: Details: code VBOX_E_INVALID_VM_STATE (0x80bb0002), component ConsoleWrap, interface IConsole, callee IUnknown
VBoxManage.exe: error: Context: "Resume()" at line 409 of file VBoxManageControlVM.cpp

2020-06-12 10:49:08 (16680): Guest Log: 04:16:59.531482 timesync vgsvcTimeSyncWorker: Radical host time change: 31 401 666 000 000ns (HostNow=1 591 948 147 136 000 000 ns HostLast=1 591 916 745 470 000 000 ns)

2020-06-12 10:49:08 (16680): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-12 10:49:13 (16680): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-12 10:49:34 (16680): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-12 10:49:38 (16680): Guest Log: 04:17:09.586371 timesync vgsvcTimeSyncWorker: Radical guest time change: 31 421 358 806 000ns (GuestNow=1 591 948 157 190 889 000 ns GuestLast=1 591 916 735 832 083 000 ns fSetTimeLastLoop=true )

2020-06-12 10:54:26 (16680): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-12 10:54:36 (16680): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-12 11:32:35 (16680): Status Report: Job Duration: '864000.000000'
2020-06-12 11:32:35 (16680): Status Report: Elapsed Time: '111167.268334'
2020-06-12 11:32:35 (16680): Status Report: CPU Time: '60532.765625'
2020-06-12 12:21:32 (16680): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-12 12:21:42 (16680): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-12 12:26:05 (16680): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-12 12:26:15 (16680): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-12 13:00:31 (16680): Preference change detected
2020-06-12 13:00:31 (16680): Setting CPU throttle for VM. (40%)
2020-06-12 13:00:31 (16680): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2020-06-12 13:02:15 (16680): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-12 13:02:25 (16680): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-12 13:13:15 (16680): Status Report: Job Duration: '864000.000000'
2020-06-12 13:13:15 (16680): Status Report: Elapsed Time: '117167.268334'
2020-06-12 13:13:15 (16680): Status Report: CPU Time: '63241.359375'
2020-06-12 14:53:20 (16680): Status Report: Job Duration: '864000.000000'
2020-06-12 14:53:20 (16680): Status Report: Elapsed Time: '123167.268334'
2020-06-12 14:53:20 (16680): Status Report: CPU Time: '65949.375000'
2020-06-12 16:33:26 (16680): Status Report: Job Duration: '864000.000000'
2020-06-12 16:33:26 (16680): Status Report: Elapsed Time: '129167.268334'
2020-06-12 16:33:26 (16680): Status Report: CPU Time: '68654.921875'
2020-06-12 18:13:32 (16680): Status Report: Job Duration: '864000.000000'
2020-06-12 18:13:32 (16680): Status Report: Elapsed Time: '135167.268334'
2020-06-12 18:13:32 (16680): Status Report: CPU Time: '71361.093750'
2020-06-12 19:37:55 (16680): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-13 21:12:02 (16680): Error in resume VM for VM: -2135228414
Command:
VBoxManage -q controlvm "boinc_0e063649b902631f" resume
Output:
VBoxManage.exe: error: Cannot resume the machine as it is not paused (machine state: Running)
VBoxManage.exe: error: Details: code VBOX_E_INVALID_VM_STATE (0x80bb0002), component ConsoleWrap, interface IConsole, callee IUnknown
VBoxManage.exe: error: Context: "Resume()" at line 409 of file VBoxManageControlVM.cpp

2020-06-13 21:12:03 (16680): Guest Log: 13:05:04.949445 timesync vgsvcTimeSyncWorker: Radical host time change: 92 052 904 000 000ns (HostNow=1 592 071 919 825 000 000 ns HostLast=1 591 979 866 921 000 000 ns)

2020-06-13 21:12:03 (16680): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-13 21:12:10 (16680): Guest Log: 13:05:14.957865 timesync vgsvcTimeSyncWorker: Radical guest time change: 92 097 279 474 000ns (GuestNow=1 592 071 929 833 428 000 ns GuestLast=1 591 979 832 553 954 000 ns fSetTimeLastLoop=true )

2020-06-13 21:12:26 (16680): Preference change detected
2020-06-13 21:12:26 (16680): Setting CPU throttle for VM. (40%)
2020-06-13 21:12:26 (16680): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2020-06-13 21:12:29 (16680): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-13 21:12:40 (16680): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-13 21:28:00 (16680): Status Report: Job Duration: '864000.000000'
2020-06-13 21:28:00 (16680): Status Report: Elapsed Time: '141167.268334'
2020-06-13 21:28:00 (16680): Status Report: CPU Time: '74068.406250'
2020-06-13 23:08:06 (16680): Status Report: Job Duration: '864000.000000'
2020-06-13 23:08:06 (16680): Status Report: Elapsed Time: '147167.268334'
2020-06-13 23:08:06 (16680): Status Report: CPU Time: '76774.125000'
2020-06-13 23:26:24 (16680): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-13 23:26:34 (16680): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-13 23:34:35 (16680): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-13 23:34:46 (16680): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-14 00:48:32 (16680): Status Report: Job Duration: '864000.000000'
2020-06-14 00:48:32 (16680): Status Report: Elapsed Time: '153167.268334'
2020-06-14 00:48:32 (16680): Status Report: CPU Time: '79480.984375'
2020-06-14 01:15:12 (16680): Stopping VM.
2020-06-14 01:15:12 (16680): Error in stop VM for VM: -108
Command:
VBoxManage -q controlvm "boinc_0e063649b902631f" savestate
Output:

2020-06-14 01:15:12 (16680): VM did not stop when requested.
2020-06-14 01:15:12 (16680): VM was successfully terminated.
2020-06-14 06:49:47 (11372): Detected: vboxwrapper 26197
2020-06-14 06:49:47 (11372): Detected: BOINC client v7.7
2020-06-14 06:49:49 (11372): Detected: VirtualBox VboxManage Interface (Version: 6.0.14)
2020-06-14 06:50:00 (11372): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2020-06-14 06:50:02 (11372): Starting VM using VBoxManage interface. (boinc_0e063649b902631f, slot#6)
2020-06-14 06:50:11 (11372): Successfully started VM. (PID = '22552')
2020-06-14 06:50:11 (11372): Reporting VM Process ID to BOINC.
2020-06-14 06:50:11 (11372): Guest Log: BIOS: VirtualBox 6.0.14

2020-06-14 06:50:11 (11372): Guest Log: CPUID EDX: 0x178bfbff

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

2020-06-14 06:50:11 (11372): VM state change detected. (old = 'PoweredOff', new = 'Running')
2020-06-14 06:50:11 (11372): Detected: Web Application Enabled (http://localhost:50133)
2020-06-14 06:50:12 (11372): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-14 06:50:18 (11372): Status Report: Job Duration: '864000.000000'
2020-06-14 06:50:18 (11372): Status Report: Elapsed Time: '154765.268334'
2020-06-14 06:50:18 (11372): Status Report: CPU Time: '80202.375000'
2020-06-14 06:50:18 (11372): Preference change detected
2020-06-14 06:50:18 (11372): Setting CPU throttle for VM. (40%)
2020-06-14 06:50:19 (11372): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2020-06-14 06:50:19 (11372): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-14 06:50:20 (11372): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032

2020-06-14 06:50:20 (11372): Guest Log: BIOS: Booting from Hard Disk...

2020-06-14 06:50:24 (11372): Guest Log: BIOS: KBD: unsupported int 16h function 03

2020-06-14 06:50:24 (11372): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 

2020-06-14 06:50:59 (11372): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds

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

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

2020-06-14 06:51:09 (11372): Guest Log: 00:00:00.000165 main     Log opened 2020-06-14T03:51:08.309410000Z

2020-06-14 06:51:09 (11372): Guest Log: 00:00:00.000348 main     OS Product: Linux

2020-06-14 06:51:09 (11372): Guest Log: 00:00:00.000406 main     OS Release: 4.14.76-13.cernvm.x86_64

2020-06-14 06:51:09 (11372): Guest Log: 00:00:00.000476 main     OS Version: #1 SMP Tue Oct 16 18:26:15 CEST 2018

2020-06-14 06:51:09 (11372): Guest Log: 00:00:00.000578 main     Executable: /usr/sbin/VBoxService

2020-06-14 06:51:09 (11372): Guest Log: 00:00:00.000580 main     Process ID: 3197

2020-06-14 06:51:09 (11372): Guest Log: 00:00:00.000581 main     Package type: LINUX_64BITS_GENERIC

2020-06-14 06:51:09 (11372): Guest Log: 00:00:00.005191 main     5.2.6 r120293 started. Verbose level = 0

2020-06-14 06:52:10 (11372): Guest Log: 06:52:09 EEST +03:00 2020-06-14: cranky: [INFO] Detected Theory App

2020-06-14 06:52:10 (11372): Guest Log: 06:52:09 EEST +03:00 2020-06-14: cranky: [INFO] Checking CVMFS.

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

2020-06-14 06:52:18 (11372): 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 06:52:18 (11372): Guest Log: 2.5.2.0 4128 0 28264 18226 3 1 1272624 4096000 0 65024 0 0 n/a 5 2 http://s1asgc-cvmfs.openhtc.io:8080/cvmfs/sft.cern.ch DIRECT 1

2020-06-14 06:52:23 (11372): Guest Log: Probing /cvmfs/grid.cern.ch... OK

2020-06-14 06:52:25 (11372): 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 06:52:25 (11372): Guest Log: 2.5.2.0 4103 0 25768 12475 3 2 1272624 4096000 0 65024 0 2 0 4271 1215 http://s1ral-cvmfs.openhtc.io/cvmfs/grid.cern.ch DIRECT 1

2020-06-14 06:52:31 (11372): Guest Log: Probing /cvmfs/cernvm-prod.cern.ch... OK

2020-06-14 06:52:34 (11372): 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 06:52:34 (11372): Guest Log: 2.5.2.0 4060 0 27220 252 3 1 1272624 4096000 0 65024 0 0 n/a 0 0 http://s1unl-cvmfs.openhtc.io/cvmfs/cernvm-prod.cern.ch DIRECT 1

2020-06-14 06:52:39 (11372): Guest Log: Probing /cvmfs/alice.cern.ch... OK

2020-06-14 06:52:42 (11372): 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 06:52:42 (11372): Guest Log: 2.5.2.0 4081 0 29076 6792 3 1 1272624 4096000 0 65024 0 0 n/a 4127 1353 http://s1ral-cvmfs.openhtc.io/cvmfs/alice.cern.ch DIRECT 1

2020-06-14 06:52:42 (11372): Guest Log: 06:52:41 EEST +03:00 2020-06-14: cranky: [INFO] Checking runc.

2020-06-14 06:52:42 (11372): Guest Log: 06:52:41 EEST +03:00 2020-06-14: cranky: [INFO] Creating the filesystem.

2020-06-14 06:52:42 (11372): Guest Log: 06:52:41 EEST +03:00 2020-06-14: cranky: [INFO] Using /cvmfs/cernvm-prod.cern.ch/cvm3

2020-06-14 06:52:43 (11372): Guest Log: 06:52:42 EEST +03:00 2020-06-14: cranky: [INFO] Updating config.json.

2020-06-14 06:52:43 (11372): Guest Log: 06:52:42 EEST +03:00 2020-06-14: cranky: [INFO] Running Container 'runc'.

2020-06-14 06:52:44 (11372): Guest Log: job: htmld=/shared/html/job

2020-06-14 06:52:44 (11372): Guest Log: job: unpack exitcode=0

2020-06-14 06:52:45 (11372): Guest Log: 06:52:45 EEST +03:00 2020-06-14: cranky: [INFO] ===> [runRivet] Sun Jun 14 03:52:44 UTC 2020 [boinc pp jets 13000 250,-,4760 - pythia8 8.243 tune-AU2m 100000 14]

2020-06-14 08:30:24 (11372): Status Report: Job Duration: '864000.000000'
2020-06-14 08:30:24 (11372): Status Report: Elapsed Time: '160765.268334'
2020-06-14 08:30:24 (11372): Status Report: CPU Time: '82903.781250'
2020-06-14 10:10:29 (11372): Status Report: Job Duration: '864000.000000'
2020-06-14 10:10:29 (11372): Status Report: Elapsed Time: '166765.268334'
2020-06-14 10:10:29 (11372): Status Report: CPU Time: '85608.921875'
2020-06-14 11:22:23 (11372): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-14 11:23:23 (11372): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-14 11:23:33 (11372): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-14 11:23:43 (11372): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-14 11:24:13 (11372): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-14 11:24:23 (11372): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-14 11:51:58 (11372): Status Report: Job Duration: '864000.000000'
2020-06-14 11:51:58 (11372): Status Report: Elapsed Time: '172765.268334'
2020-06-14 11:51:58 (11372): Status Report: CPU Time: '88318.375000'
2020-06-14 13:32:04 (11372): Status Report: Job Duration: '864000.000000'
2020-06-14 13:32:04 (11372): Status Report: Elapsed Time: '178765.268334'
2020-06-14 13:32:04 (11372): Status Report: CPU Time: '91023.937500'
2020-06-14 15:12:09 (11372): Status Report: Job Duration: '864000.000000'
2020-06-14 15:12:09 (11372): Status Report: Elapsed Time: '184765.268334'
2020-06-14 15:12:09 (11372): Status Report: CPU Time: '93728.921875'
2020-06-14 16:52:15 (11372): Status Report: Job Duration: '864000.000000'
2020-06-14 16:52:15 (11372): Status Report: Elapsed Time: '190765.268334'
2020-06-14 16:52:15 (11372): Status Report: CPU Time: '96433.781250'
2020-06-14 17:06:00 (11372): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-14 17:06:10 (11372): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-14 18:32:31 (11372): Status Report: Job Duration: '864000.000000'
2020-06-14 18:32:31 (11372): Status Report: Elapsed Time: '196765.268334'
2020-06-14 18:32:31 (11372): Status Report: CPU Time: '99139.328125'
2020-06-14 19:08:40 (11372): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-14 19:08:50 (11372): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-14 20:12:48 (11372): Status Report: Job Duration: '864000.000000'
2020-06-14 20:12:48 (11372): Status Report: Elapsed Time: '202765.268334'
2020-06-14 20:12:48 (11372): Status Report: CPU Time: '101846.156250'
2020-06-14 21:09:26 (11372): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-14 21:09:36 (11372): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-14 21:12:28 (11372): Preference change detected
2020-06-14 21:12:28 (11372): Setting CPU throttle for VM. (40%)
2020-06-14 21:12:29 (11372): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2020-06-14 21:53:05 (11372): Status Report: Job Duration: '864000.000000'
2020-06-14 21:53:05 (11372): Status Report: Elapsed Time: '208765.268334'
2020-06-14 21:53:05 (11372): Status Report: CPU Time: '104553.187500'
2020-06-14 22:09:27 (11372): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-14 22:09:37 (11372): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-14 22:26:09 (11372): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-14 22:26:19 (11372): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-14 22:57:25 (11372): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-14 22:57:35 (11372): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-14 23:09:37 (11372): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-14 23:09:47 (11372): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-14 23:33:55 (11372): Status Report: Job Duration: '864000.000000'
2020-06-14 23:33:55 (11372): Status Report: Elapsed Time: '214765.268334'
2020-06-14 23:33:55 (11372): Status Report: CPU Time: '107262.765625'
2020-06-15 00:23:57 (11372): Stopping VM.
2020-06-15 00:23:57 (11372): Error in stop VM for VM: -108
Command:
VBoxManage -q controlvm "boinc_0e063649b902631f" savestate
Output:

2020-06-15 00:23:57 (11372): VM did not stop when requested.
2020-06-15 00:23:57 (11372): VM was successfully terminated.
2020-06-15 10:23:05 (23616): Detected: vboxwrapper 26197
2020-06-15 10:23:05 (23616): Detected: BOINC client v7.7
2020-06-15 10:23:07 (23616): Detected: VirtualBox VboxManage Interface (Version: 6.0.14)
2020-06-15 10:23:08 (23616): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2020-06-15 10:23:09 (23616): Starting VM using VBoxManage interface. (boinc_0e063649b902631f, slot#6)
2020-06-15 10:23:17 (23616): Successfully started VM. (PID = '12696')
2020-06-15 10:23:17 (23616): Reporting VM Process ID to BOINC.
2020-06-15 10:23:17 (23616): Guest Log: BIOS: VirtualBox 6.0.14

2020-06-15 10:23:17 (23616): Guest Log: CPUID EDX: 0x178bfbff

2020-06-15 10:23:17 (23616): Guest Log: BIOS: ata0-0: PCHS=16383/16/63 LCHS=1024/255/63

2020-06-15 10:23:17 (23616): VM state change detected. (old = 'PoweredOff', new = 'Running')
2020-06-15 10:23:17 (23616): Detected: Web Application Enabled (http://localhost:50133)
2020-06-15 10:23:18 (23616): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-15 10:23:36 (23616): Status Report: Job Duration: '864000.000000'
2020-06-15 10:23:36 (23616): Status Report: Elapsed Time: '217764.268334'
2020-06-15 10:23:36 (23616): Status Report: CPU Time: '108620.125000'
2020-06-15 10:23:36 (23616): Preference change detected
2020-06-15 10:23:36 (23616): Setting CPU throttle for VM. (40%)
2020-06-15 10:23:36 (23616): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2020-06-15 10:23:37 (23616): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-15 10:23:38 (23616): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032

2020-06-15 10:23:38 (23616): Guest Log: BIOS: Booting from Hard Disk...

2020-06-15 10:23:40 (23616): Guest Log: BIOS: KBD: unsupported int 16h function 03

2020-06-15 10:23:40 (23616): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 

2020-06-15 10:24:16 (23616): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds

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

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

2020-06-15 10:24:24 (23616): Guest Log: 00:00:00.000176 main     Log opened 2020-06-15T07:24:23.178470000Z

2020-06-15 10:24:24 (23616): Guest Log: 00:00:00.000347 main     OS Product: Linux

2020-06-15 10:24:24 (23616): Guest Log: 00:00:00.000404 main     OS Release: 4.14.76-13.cernvm.x86_64

2020-06-15 10:24:24 (23616): Guest Log: 00:00:00.000474 main     OS Version: #1 SMP Tue Oct 16 18:26:15 CEST 2018

2020-06-15 10:24:24 (23616): Guest Log: 00:00:00.000528 main     Executable: /usr/sbin/VBoxService

2020-06-15 10:24:24 (23616): Guest Log: 00:00:00.000529 main     Process ID: 3186

2020-06-15 10:24:24 (23616): Guest Log: 00:00:00.000530 main     Package type: LINUX_64BITS_GENERIC

2020-06-15 10:24:24 (23616): Guest Log: 00:00:00.011357 main     5.2.6 r120293 started. Verbose level = 0

2020-06-15 10:25:26 (23616): Guest Log: 10:25:25 EEST +03:00 2020-06-15: cranky: [INFO] Detected Theory App

2020-06-15 10:25:26 (23616): Guest Log: 10:25:25 EEST +03:00 2020-06-15: cranky: [INFO] Checking CVMFS.

2020-06-15 10:25:32 (23616): Guest Log: Probing /cvmfs/sft.cern.ch... OK

2020-06-15 10:25:35 (23616): 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 10:25:35 (23616): Guest Log: 2.5.2.0 4103 0 27200 18228 3 1 1290032 4096000 0 65024 0 0 n/a 0 0 http://s1fnal-cvmfs.openhtc.io/cvmfs/sft.cern.ch DIRECT 1

2020-06-15 10:25:41 (23616): Guest Log: Probing /cvmfs/grid.cern.ch... OK

2020-06-15 10:25:44 (23616): 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 10:25:44 (23616): Guest Log: 2.5.2.0 4057 0 27696 12485 3 2 1290032 4096000 0 65024 0 2 0 4272 1785 http://s1ral-cvmfs.openhtc.io/cvmfs/grid.cern.ch DIRECT 1

2020-06-15 10:25:50 (23616): Guest Log: Probing /cvmfs/cernvm-prod.cern.ch... OK

2020-06-15 10:25:54 (23616): 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 10:25:54 (23616): Guest Log: 2.5.2.0 4081 0 27212 252 3 1 1290032 4096000 0 65024 0 0 n/a 0 0 http://s1ihep-cvmfs.openhtc.io/cvmfs/cernvm-prod.cern.ch DIRECT 1

2020-06-15 10:26:01 (23616): Guest Log: Probing /cvmfs/alice.cern.ch... OK

2020-06-15 10:26:05 (23616): 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 10:26:05 (23616): Guest Log: 2.5.2.0 4123 1 30176 6798 3 1 1290032 4096000 0 65024 0 0 n/a 4128 1598 http://s1ral-cvmfs.openhtc.io/cvmfs/alice.cern.ch DIRECT 1

2020-06-15 10:26:05 (23616): Guest Log: 10:26:04 EEST +03:00 2020-06-15: cranky: [INFO] Checking runc.

2020-06-15 10:26:06 (23616): Guest Log: 10:26:04 EEST +03:00 2020-06-15: cranky: [INFO] Creating the filesystem.

2020-06-15 10:26:06 (23616): Guest Log: 10:26:04 EEST +03:00 2020-06-15: cranky: [INFO] Using /cvmfs/cernvm-prod.cern.ch/cvm3

2020-06-15 10:26:07 (23616): Guest Log: 10:26:05 EEST +03:00 2020-06-15: cranky: [INFO] Updating config.json.

2020-06-15 10:26:07 (23616): Guest Log: 10:26:06 EEST +03:00 2020-06-15: cranky: [INFO] Running Container 'runc'.

2020-06-15 10:26:09 (23616): Guest Log: job: htmld=/shared/html/job

2020-06-15 10:26:09 (23616): Guest Log: job: unpack exitcode=0

2020-06-15 10:26:10 (23616): Guest Log: 10:26:08 EEST +03:00 2020-06-15: cranky: [INFO] ===> [runRivet] Mon Jun 15 07:26:08 UTC 2020 [boinc pp jets 13000 250,-,4760 - pythia8 8.243 tune-AU2m 100000 14]

2020-06-15 11:20:17 (23616): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-15 11:20:47 (23616): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-15 12:04:18 (23616): Status Report: Job Duration: '864000.000000'
2020-06-15 12:04:18 (23616): Status Report: Elapsed Time: '223764.268334'
2020-06-15 12:04:18 (23616): Status Report: CPU Time: '111322.812500'
2020-06-15 12:09:58 (23616): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-15 12:10:08 (23616): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-15 12:10:38 (23616): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-15 12:10:48 (23616): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-15 12:23:44 (23616): Stopping VM.
2020-06-15 12:23:44 (23616): Error in stop VM for VM: -108
Command:
VBoxManage -q controlvm "boinc_0e063649b902631f" savestate
Output:

2020-06-15 12:23:44 (23616): VM did not stop when requested.
2020-06-15 12:23:44 (23616): VM was successfully terminated.
2020-06-15 19:13:59 (15140): Detected: vboxwrapper 26197
2020-06-15 19:14:00 (15140): Detected: BOINC client v7.7
2020-06-15 19:14:01 (15140): Detected: VirtualBox VboxManage Interface (Version: 6.0.14)
2020-06-15 19:14:01 (15140): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2020-06-15 19:14:02 (15140): Starting VM using VBoxManage interface. (boinc_0e063649b902631f, slot#6)
2020-06-15 19:14:10 (15140): Successfully started VM. (PID = '13424')
2020-06-15 19:14:10 (15140): Reporting VM Process ID to BOINC.
2020-06-15 19:14:10 (15140): Guest Log: BIOS: VirtualBox 6.0.14

2020-06-15 19:14:10 (15140): Guest Log: CPUID EDX: 0x178bfbff

2020-06-15 19:14:10 (15140): Guest Log: BIOS: ata0-0: PCHS=16383/16/63 LCHS=1024/255/63

2020-06-15 19:14:10 (15140): VM state change detected. (old = 'PoweredOff', new = 'Running')
2020-06-15 19:14:10 (15140): Detected: Web Application Enabled (http://localhost:50133)
2020-06-15 19:14:11 (15140): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-15 19:14:42 (15140): Status Report: Job Duration: '864000.000000'
2020-06-15 19:14:42 (15140): Status Report: Elapsed Time: '224906.268334'
2020-06-15 19:14:42 (15140): Status Report: CPU Time: '111837.171875'
2020-06-15 19:14:42 (15140): Preference change detected
2020-06-15 19:14:42 (15140): Setting CPU throttle for VM. (40%)
2020-06-15 19:14:42 (15140): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2020-06-15 19:14:42 (15140): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-15 19:14:43 (15140): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032

2020-06-15 19:14:43 (15140): Guest Log: BIOS: Booting from Hard Disk...

2020-06-15 19:14:46 (15140): Guest Log: BIOS: KBD: unsupported int 16h function 03

2020-06-15 19:14:46 (15140): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 

2020-06-15 19:14:52 (15140): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-15 19:15:02 (15140): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-15 19:15:38 (15140): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds

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

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

2020-06-15 19:15:49 (15140): Guest Log: 00:00:00.000175 main     Log opened 2020-06-15T16:15:48.473093000Z

2020-06-15 19:15:49 (15140): Guest Log: 00:00:00.000351 main     OS Product: Linux

2020-06-15 19:15:49 (15140): Guest Log: 00:00:00.000411 main     OS Release: 4.14.76-13.cernvm.x86_64

2020-06-15 19:15:49 (15140): Guest Log: 00:00:00.000465 main     OS Version: #1 SMP Tue Oct 16 18:26:15 CEST 2018

2020-06-15 19:15:49 (15140): Guest Log: 00:00:00.000517 main     Executable: /usr/sbin/VBoxService

2020-06-15 19:15:49 (15140): Guest Log: 00:00:00.000519 main     Process ID: 3197

2020-06-15 19:15:49 (15140): Guest Log: 00:00:00.000519 main     Package type: LINUX_64BITS_GENERIC

2020-06-15 19:15:49 (15140): Guest Log: 00:00:00.004221 main     5.2.6 r120293 started. Verbose level = 0

2020-06-15 19:16:57 (15140): Guest Log: 19:16:56 EEST +03:00 2020-06-15: cranky: [INFO] Detected Theory App

2020-06-15 19:16:57 (15140): Guest Log: 19:16:56 EEST +03:00 2020-06-15: cranky: [INFO] Checking CVMFS.

2020-06-15 19:17:02 (15140): Guest Log: Probing /cvmfs/sft.cern.ch... OK

2020-06-15 19:17:05 (15140): 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 19:17:06 (15140): Guest Log: 2.5.2.0 4131 0 25172 18234 3 1 1299062 4096000 0 65024 0 0 n/a 5 6 http://s1ral-cvmfs.openhtc.io/cvmfs/sft.cern.ch DIRECT 1

2020-06-15 19:17:11 (15140): Guest Log: Probing /cvmfs/grid.cern.ch... OK

2020-06-15 19:17:13 (15140): 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 19:17:13 (15140): Guest Log: 2.5.2.0 4089 0 27652 12485 3 2 1299062 4096000 0 65024 0 2 100 0 0 http://s1ral-cvmfs.openhtc.io/cvmfs/grid.cern.ch DIRECT 1

2020-06-15 19:17:18 (15140): Guest Log: Probing /cvmfs/cernvm-prod.cern.ch... OK

2020-06-15 19:17:21 (15140): 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 19:17:21 (15140): Guest Log: 2.5.2.0 4066 0 25180 252 3 1 1299062 4096000 0 65024 0 0 n/a 0 0 http://s1ral-cvmfs.openhtc.io/cvmfs/cernvm-prod.cern.ch DIRECT 1

2020-06-15 19:17:26 (15140): Guest Log: Probing /cvmfs/alice.cern.ch... OK

2020-06-15 19:17:28 (15140): 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 19:17:28 (15140): Guest Log: 2.5.2.0 4111 0 30032 6800 3 1 1299062 4096000 0 65024 0 0 n/a 4128 1633 http://s1ral-cvmfs.openhtc.io/cvmfs/alice.cern.ch DIRECT 1

2020-06-15 19:17:28 (15140): Guest Log: 19:17:27 EEST +03:00 2020-06-15: cranky: [INFO] Checking runc.

2020-06-15 19:17:29 (15140): Guest Log: 19:17:27 EEST +03:00 2020-06-15: cranky: [INFO] Creating the filesystem.

2020-06-15 19:17:29 (15140): Guest Log: 19:17:27 EEST +03:00 2020-06-15: cranky: [INFO] Using /cvmfs/cernvm-prod.cern.ch/cvm3

2020-06-15 19:17:29 (15140): Guest Log: 19:17:28 EEST +03:00 2020-06-15: cranky: [INFO] Updating config.json.

2020-06-15 19:17:30 (15140): Guest Log: 19:17:28 EEST +03:00 2020-06-15: cranky: [INFO] Running Container 'runc'.

2020-06-15 19:17:31 (15140): Guest Log: job: htmld=/shared/html/job

2020-06-15 19:17:31 (15140): Guest Log: job: unpack exitcode=0

2020-06-15 19:17:32 (15140): Guest Log: 19:17:30 EEST +03:00 2020-06-15: cranky: [INFO] ===> [runRivet] Mon Jun 15 16:17:30 UTC 2020 [boinc pp jets 13000 250,-,4760 - pythia8 8.243 tune-AU2m 100000 14]

2020-06-15 20:04:12 (15140): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-15 20:04:23 (15140): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-15 20:42:40 (15140): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-15 20:42:50 (15140): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-15 20:55:20 (15140): Status Report: Job Duration: '864000.000000'
2020-06-15 20:55:20 (15140): Status Report: Elapsed Time: '230906.268334'
2020-06-15 20:55:20 (15140): Status Report: CPU Time: '114540.187500'
2020-06-15 21:12:30 (15140): Preference change detected
2020-06-15 21:12:30 (15140): Setting CPU throttle for VM. (40%)
2020-06-15 21:12:31 (15140): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2020-06-15 22:35:26 (15140): Status Report: Job Duration: '864000.000000'
2020-06-15 22:35:26 (15140): Status Report: Elapsed Time: '236906.268334'
2020-06-15 22:35:26 (15140): Status Report: CPU Time: '117248.609375'
2020-06-15 23:00:08 (15140): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-15 23:00:16 (15140): Stopping VM.
2020-06-15 23:00:16 (15140): Error in stop VM for VM: -108
Command:
VBoxManage -q controlvm "boinc_0e063649b902631f" savestate
Output:

2020-06-15 23:00:16 (15140): VM did not stop when requested.
2020-06-15 23:00:16 (15140): VM was successfully terminated.
2020-06-16 11:07:20 (6360): Detected: vboxwrapper 26197
2020-06-16 11:07:20 (6360): Detected: BOINC client v7.7
2020-06-16 11:07:24 (6360): Detected: VirtualBox VboxManage Interface (Version: 6.0.14)
2020-06-16 11:07:24 (6360): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2020-06-16 11:07:24 (6360): Starting VM using VBoxManage interface. (boinc_0e063649b902631f, slot#6)
2020-06-16 11:07:33 (6360): Successfully started VM. (PID = '12848')
2020-06-16 11:07:33 (6360): Reporting VM Process ID to BOINC.
2020-06-16 11:07:33 (6360): Guest Log: BIOS: VirtualBox 6.0.14

2020-06-16 11:07:33 (6360): Guest Log: CPUID EDX: 0x178bfbff

2020-06-16 11:07:33 (6360): Guest Log: BIOS: ata0-0: PCHS=16383/16/63 LCHS=1024/255/63

2020-06-16 11:07:33 (6360): VM state change detected. (old = 'PoweredOff', new = 'Running')
2020-06-16 11:07:33 (6360): Detected: Web Application Enabled (http://localhost:50133)
2020-06-16 11:07:34 (6360): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-16 11:07:40 (6360): Status Report: Job Duration: '864000.000000'
2020-06-16 11:07:40 (6360): Status Report: Elapsed Time: '238384.268334'
2020-06-16 11:07:40 (6360): Status Report: CPU Time: '117917.921875'
2020-06-16 11:07:40 (6360): Preference change detected
2020-06-16 11:07:40 (6360): Setting CPU throttle for VM. (40%)
2020-06-16 11:07:40 (6360): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2020-06-16 11:07:41 (6360): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-16 11:07:42 (6360): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032

2020-06-16 11:07:42 (6360): Guest Log: BIOS: Booting from Hard Disk...

2020-06-16 11:07:45 (6360): Guest Log: BIOS: KBD: unsupported int 16h function 03

2020-06-16 11:07:45 (6360): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 

2020-06-16 11:08:21 (6360): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds

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

2020-06-16 11:08:30 (6360): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log

2020-06-16 11:08:30 (6360): Guest Log: 00:00:00.000194 main     Log opened 2020-06-16T08:08:29.278613000Z

2020-06-16 11:08:30 (6360): Guest Log: 00:00:00.000426 main     OS Product: Linux

2020-06-16 11:08:30 (6360): Guest Log: 00:00:00.000515 main     OS Release: 4.14.76-13.cernvm.x86_64

2020-06-16 11:08:30 (6360): Guest Log: 00:00:00.000657 main     OS Version: #1 SMP Tue Oct 16 18:26:15 CEST 2018

2020-06-16 11:08:30 (6360): Guest Log: 00:00:00.000725 main     Executable: /usr/sbin/VBoxService

2020-06-16 11:08:30 (6360): Guest Log: 00:00:00.000727 main     Process ID: 3197

2020-06-16 11:08:30 (6360): Guest Log: 00:00:00.000728 main     Package type: LINUX_64BITS_GENERIC

2020-06-16 11:08:30 (6360): Guest Log: 00:00:00.005350 main     5.2.6 r120293 started. Verbose level = 0

2020-06-16 11:09:35 (6360): Guest Log: 11:09:33 EEST +03:00 2020-06-16: cranky: [INFO] Detected Theory App

2020-06-16 11:09:35 (6360): Guest Log: 11:09:33 EEST +03:00 2020-06-16: cranky: [INFO] Checking CVMFS.

2020-06-16 11:09:39 (6360): Guest Log: Probing /cvmfs/sft.cern.ch... OK

2020-06-16 11:09:42 (6360): 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 11:09:42 (6360): Guest Log: 2.5.2.0 4104 0 25152 18238 3 1 2110613 4096000 0 65024 0 0 n/a 0 0 http://s1ral-cvmfs.openhtc.io/cvmfs/sft.cern.ch DIRECT 1

2020-06-16 11:09:47 (6360): Guest Log: Probing /cvmfs/grid.cern.ch... OK

2020-06-16 11:09:50 (6360): 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 11:09:50 (6360): Guest Log: 2.5.2.0 4131 0 27500 12491 3 2 2110613 4096000 0 65024 0 2 0 4273 2103 http://s1ral-cvmfs.openhtc.io/cvmfs/grid.cern.ch DIRECT 1

2020-06-16 11:09:55 (6360): Guest Log: Probing /cvmfs/cernvm-prod.cern.ch... OK

2020-06-16 11:09:57 (6360): 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 11:09:57 (6360): Guest Log: 2.5.2.0 4058 0 27200 252 3 1 2110613 4096000 0 65024 0 0 n/a 0 0 http://s1cern-cvmfs.openhtc.io/cvmfs/cernvm-prod.cern.ch DIRECT 1

2020-06-16 11:10:03 (6360): Guest Log: Probing /cvmfs/alice.cern.ch... OK

2020-06-16 11:10:06 (6360): 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 11:10:06 (6360): Guest Log: 2.5.2.0 4083 1 29728 6805 3 1 2110613 4096000 0 65024 0 0 n/a 4133 1130 http://s1bnl-cvmfs.openhtc.io/cvmfs/alice.cern.ch DIRECT 1

2020-06-16 11:10:06 (6360): Guest Log: 11:10:04 EEST +03:00 2020-06-16: cranky: [INFO] Checking runc.

2020-06-16 11:10:06 (6360): Guest Log: 11:10:05 EEST +03:00 2020-06-16: cranky: [INFO] Creating the filesystem.

2020-06-16 11:10:06 (6360): Guest Log: 11:10:05 EEST +03:00 2020-06-16: cranky: [INFO] Using /cvmfs/cernvm-prod.cern.ch/cvm3

2020-06-16 11:10:07 (6360): Guest Log: 11:10:05 EEST +03:00 2020-06-16: cranky: [INFO] Updating config.json.

2020-06-16 11:10:07 (6360): Guest Log: 11:10:06 EEST +03:00 2020-06-16: cranky: [INFO] Running Container 'runc'.

2020-06-16 11:10:08 (6360): Guest Log: job: htmld=/shared/html/job

2020-06-16 11:10:08 (6360): Guest Log: job: unpack exitcode=0

2020-06-16 11:10:09 (6360): Guest Log: 11:10:08 EEST +03:00 2020-06-16: cranky: [INFO] ===> [runRivet] Tue Jun 16 08:10:07 UTC 2020 [boinc pp jets 13000 250,-,4760 - pythia8 8.243 tune-AU2m 100000 14]

2020-06-16 11:22:03 (6360): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-16 11:22:13 (6360): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-16 11:28:14 (6360): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-16 11:28:24 (6360): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-16 12:19:40 (6360): Stopping VM.
2020-06-16 12:19:40 (6360): Error in stop VM for VM: -108
Command:
VBoxManage -q controlvm "boinc_0e063649b902631f" savestate
Output:

2020-06-16 12:19:40 (6360): VM did not stop when requested.
2020-06-16 12:19:40 (6360): VM was successfully terminated.
2020-06-16 12:41:22 (11364): Detected: vboxwrapper 26197
2020-06-16 12:41:22 (11364): Detected: BOINC client v7.7
2020-06-16 12:41:25 (11364): Detected: VirtualBox VboxManage Interface (Version: 6.0.14)
2020-06-16 12:41:26 (11364): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2020-06-16 12:41:27 (11364): Starting VM using VBoxManage interface. (boinc_0e063649b902631f, slot#6)
2020-06-16 12:41:34 (11364): Successfully started VM. (PID = '848')
2020-06-16 12:41:34 (11364): Reporting VM Process ID to BOINC.
2020-06-16 12:41:34 (11364): Guest Log: BIOS: VirtualBox 6.0.14

2020-06-16 12:41:34 (11364): Guest Log: CPUID EDX: 0x178bfbff

2020-06-16 12:41:34 (11364): Guest Log: BIOS: ata0-0: PCHS=16383/16/63 LCHS=1024/255/63

2020-06-16 12:41:34 (11364): VM state change detected. (old = 'PoweredOff', new = 'Running')
2020-06-16 12:41:35 (11364): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-16 12:41:51 (11364): Preference change detected
2020-06-16 12:41:51 (11364): Setting CPU throttle for VM. (40%)
2020-06-16 12:41:51 (11364): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2020-06-16 12:41:51 (11364): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-16 12:41:52 (11364): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032

2020-06-16 12:41:52 (11364): Guest Log: BIOS: Booting from Hard Disk...

2020-06-16 12:41:55 (11364): Guest Log: BIOS: KBD: unsupported int 16h function 03

2020-06-16 12:41:55 (11364): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 

2020-06-16 12:42:11 (11364): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-16 12:42:21 (11364): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-16 12:42:39 (11364): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds

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

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

2020-06-16 12:42:49 (11364): Guest Log: 00:00:00.000175 main     Log opened 2020-06-16T09:42:48.293027000Z

2020-06-16 12:42:49 (11364): Guest Log: 00:00:00.000421 main     OS Product: Linux

2020-06-16 12:42:49 (11364): Guest Log: 00:00:00.000498 main     OS Release: 4.14.76-13.cernvm.x86_64

2020-06-16 12:42:49 (11364): Guest Log: 00:00:00.000566 main     OS Version: #1 SMP Tue Oct 16 18:26:15 CEST 2018

2020-06-16 12:42:49 (11364): Guest Log: 00:00:00.000641 main     Executable: /usr/sbin/VBoxService

2020-06-16 12:42:49 (11364): Guest Log: 00:00:00.000643 main     Process ID: 3195

2020-06-16 12:42:49 (11364): Guest Log: 00:00:00.000644 main     Package type: LINUX_64BITS_GENERIC

2020-06-16 12:42:49 (11364): Guest Log: 00:00:00.197869 main     5.2.6 r120293 started. Verbose level = 0

2020-06-16 12:43:48 (11364): Guest Log: 12:43:46 EEST +03:00 2020-06-16: cranky: [INFO] Detected Theory App

2020-06-16 12:43:48 (11364): Guest Log: 12:43:46 EEST +03:00 2020-06-16: cranky: [INFO] Checking CVMFS.

2020-06-16 12:43:52 (11364): Guest Log: Probing /cvmfs/sft.cern.ch... OK

2020-06-16 12:43:54 (11364): 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 12:43:54 (11364): Guest Log: 2.5.2.0 4124 0 27220 18241 3 1 2665400 4096000 0 65024 0 0 n/a 5 4 http://s1cern-cvmfs.openhtc.io/cvmfs/sft.cern.ch DIRECT 1

2020-06-16 12:43:59 (11364): Guest Log: Probing /cvmfs/grid.cern.ch... OK

2020-06-16 12:44:01 (11364): 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 12:44:01 (11364): Guest Log: 2.5.2.0 4053 0 27672 12491 3 2 2665400 4096000 0 65024 0 2 100 0 0 http://s1cern-cvmfs.openhtc.io/cvmfs/grid.cern.ch DIRECT 1

2020-06-16 12:44:06 (11364): Guest Log: Probing /cvmfs/cernvm-prod.cern.ch... OK

2020-06-16 12:44:08 (11364): 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 12:44:08 (11364): Guest Log: 2.5.2.0 4099 0 27844 252 3 1 2665400 4096000 0 65024 0 0 n/a 0 0 http://s1cern-cvmfs.openhtc.io/cvmfs/cernvm-prod.cern.ch DIRECT 1

2020-06-16 12:44:12 (11364): Guest Log: Probing /cvmfs/alice.cern.ch... OK

2020-06-16 12:44:14 (11364): 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 12:44:14 (11364): Guest Log: 2.5.2.0 4073 0 31024 6805 3 1 2665400 4096000 0 65024 0 0 n/a 0 0 http://s1ral-cvmfs.openhtc.io/cvmfs/alice.cern.ch DIRECT 1

2020-06-16 12:44:14 (11364): Guest Log: 12:44:12 EEST +03:00 2020-06-16: cranky: [INFO] Checking runc.

2020-06-16 12:44:15 (11364): Guest Log: 12:44:12 EEST +03:00 2020-06-16: cranky: [INFO] Creating the filesystem.

2020-06-16 12:44:15 (11364): Guest Log: 12:44:12 EEST +03:00 2020-06-16: cranky: [INFO] Using /cvmfs/cernvm-prod.cern.ch/cvm3

2020-06-16 12:44:15 (11364): Guest Log: 12:44:13 EEST +03:00 2020-06-16: cranky: [INFO] Updating config.json.

2020-06-16 12:44:16 (11364): Guest Log: 12:44:13 EEST +03:00 2020-06-16: cranky: [INFO] Running Container 'runc'.

2020-06-16 12:44:17 (11364): Guest Log: job: htmld=/shared/html/job

2020-06-16 12:44:17 (11364): Guest Log: job: unpack exitcode=0

2020-06-16 12:44:18 (11364): Guest Log: 12:44:15 EEST +03:00 2020-06-16: cranky: [INFO] ===> [runRivet] Tue Jun 16 09:44:15 UTC 2020 [boinc pp jets 13000 250,-,4760 - pythia8 8.243 tune-AU2m 100000 14]

2020-06-16 12:55:55 (11364): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-16 12:56:05 (11364): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-16 14:22:22 (11364): Status Report: Job Duration: '864000.000000'
2020-06-16 14:22:22 (11364): Status Report: Elapsed Time: '6000.000000'
2020-06-16 14:22:22 (11364): Status Report: CPU Time: '2697.250000'
2020-06-16 16:02:28 (11364): Status Report: Job Duration: '864000.000000'
2020-06-16 16:02:28 (11364): Status Report: Elapsed Time: '12000.000000'
2020-06-16 16:02:28 (11364): Status Report: CPU Time: '5422.609375'
2020-06-16 16:41:15 (11364): Stopping VM.
2020-06-16 16:41:16 (11364): Error in stop VM for VM: -108
Command:
VBoxManage -q controlvm "boinc_0e063649b902631f" savestate
Output:

2020-06-16 16:41:16 (11364): VM did not stop when requested.
2020-06-16 16:41:16 (11364): VM was successfully terminated.
2020-06-16 19:54:35 (10292): Detected: vboxwrapper 26197
2020-06-16 19:54:35 (10292): Detected: BOINC client v7.7
2020-06-16 19:54:37 (10292): Detected: VirtualBox VboxManage Interface (Version: 6.0.14)
2020-06-16 19:54:38 (10292): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2020-06-16 19:54:38 (10292): Starting VM using VBoxManage interface. (boinc_0e063649b902631f, slot#6)
2020-06-16 19:54:45 (10292): Successfully started VM. (PID = '16124')
2020-06-16 19:54:45 (10292): Reporting VM Process ID to BOINC.
2020-06-16 19:54:45 (10292): Guest Log: BIOS: VirtualBox 6.0.14

2020-06-16 19:54:45 (10292): Guest Log: CPUID EDX: 0x178bfbff

2020-06-16 19:54:45 (10292): Guest Log: BIOS: ata0-0: PCHS=16383/16/63 LCHS=1024/255/63

2020-06-16 19:54:45 (10292): VM state change detected. (old = 'PoweredOff', new = 'Running')
2020-06-16 19:54:46 (10292): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-16 19:54:54 (10292): Status Report: Job Duration: '864000.000000'
2020-06-16 19:54:54 (10292): Status Report: Elapsed Time: '14325.000000'
2020-06-16 19:54:54 (10292): Status Report: CPU Time: '6485.218750'
2020-06-16 19:54:54 (10292): Preference change detected
2020-06-16 19:54:54 (10292): Setting CPU throttle for VM. (40%)
2020-06-16 19:54:55 (10292): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2020-06-16 19:54:55 (10292): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-16 19:54:56 (10292): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032

2020-06-16 19:54:56 (10292): Guest Log: BIOS: Booting from Hard Disk...

2020-06-16 19:54:59 (10292): Guest Log: BIOS: KBD: unsupported int 16h function 03

2020-06-16 19:54:59 (10292): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 

2020-06-16 19:55:32 (10292): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds

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

2020-06-16 19:55:41 (10292): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log

2020-06-16 19:55:41 (10292): Guest Log: 00:00:00.000133 main     Log opened 2020-06-16T16:55:39.821958000Z

2020-06-16 19:55:41 (10292): Guest Log: 00:00:00.000297 main     OS Product: Linux

2020-06-16 19:55:41 (10292): Guest Log: 00:00:00.000349 main     OS Release: 4.14.76-13.cernvm.x86_64

2020-06-16 19:55:41 (10292): Guest Log: 00:00:00.000399 main     OS Version: #1 SMP Tue Oct 16 18:26:15 CEST 2018

2020-06-16 19:55:41 (10292): Guest Log: 00:00:00.000448 main     Executable: /usr/sbin/VBoxService

2020-06-16 19:55:41 (10292): Guest Log: 00:00:00.000449 main     Process ID: 3195

2020-06-16 19:55:41 (10292): Guest Log: 00:00:00.000450 main     Package type: LINUX_64BITS_GENERIC

2020-06-16 19:55:41 (10292): Guest Log: 00:00:00.068806 main     5.2.6 r120293 started. Verbose level = 0

2020-06-16 19:57:06 (10292): Guest Log: 19:57:05 EEST +03:00 2020-06-16: cranky: [INFO] Detected Theory App

2020-06-16 19:57:06 (10292): Guest Log: 19:57:05 EEST +03:00 2020-06-16: cranky: [INFO] Checking CVMFS.

2020-06-16 19:57:11 (10292): Guest Log: Probing /cvmfs/sft.cern.ch... OK

2020-06-16 19:57:13 (10292): 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 19:57:13 (10292): Guest Log: 2.5.2.0 4109 1 27208 18247 3 1 3970565 4096000 0 65024 0 0 n/a 5 0 http://s1ral-cvmfs.openhtc.io/cvmfs/sft.cern.ch DIRECT 1

2020-06-16 19:57:17 (10292): Guest Log: Probing /cvmfs/grid.cern.ch... OK

2020-06-16 19:57:19 (10292): 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 19:57:19 (10292): Guest Log: 2.5.2.0 4054 1 27708 12493 3 2 3970565 4096000 0 65024 0 2 0 4273 1911 http://s1cern-cvmfs.openhtc.io/cvmfs/grid.cern.ch DIRECT 1

2020-06-16 19:57:24 (10292): Guest Log: Probing /cvmfs/cernvm-prod.cern.ch... OK

2020-06-16 19:57:31 (10292): 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 19:57:31 (10292): Guest Log: 2.5.2.0 4078 1 27216 253 3 1 3970565 4096000 0 65024 0 0 n/a 0 0 http://s1ral-cvmfs.openhtc.io/cvmfs/cernvm-prod.cern.ch DIRECT 1

2020-06-16 19:57:36 (10292): Guest Log: Probing /cvmfs/alice.cern.ch... OK

2020-06-16 19:57:38 (10292): 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 19:57:38 (10292): Guest Log: 2.5.2.0 4143 1 30164 6808 3 1 3970565 4096000 0 65024 0 0 n/a 4133 171 http://s1ral-cvmfs.openhtc.io/cvmfs/alice.cern.ch DIRECT 1

2020-06-16 19:57:38 (10292): Guest Log: 19:57:37 EEST +03:00 2020-06-16: cranky: [INFO] Checking runc.

2020-06-16 19:57:39 (10292): Guest Log: 19:57:37 EEST +03:00 2020-06-16: cranky: [INFO] Creating the filesystem.

2020-06-16 19:57:39 (10292): Guest Log: 19:57:37 EEST +03:00 2020-06-16: cranky: [INFO] Using /cvmfs/cernvm-prod.cern.ch/cvm3

2020-06-16 19:57:39 (10292): Guest Log: 19:57:38 EEST +03:00 2020-06-16: cranky: [INFO] Updating config.json.

2020-06-16 19:57:40 (10292): Guest Log: 19:57:38 EEST +03:00 2020-06-16: cranky: [INFO] Running Container 'runc'.

2020-06-16 19:57:41 (10292): Guest Log: job: htmld=/shared/html/job

2020-06-16 19:57:41 (10292): Guest Log: job: unpack exitcode=0

2020-06-16 19:57:42 (10292): Guest Log: 19:57:41 EEST +03:00 2020-06-16: cranky: [INFO] ===> [runRivet] Tue Jun 16 16:57:40 UTC 2020 [boinc pp jets 13000 250,-,4760 - pythia8 8.243 tune-AU2m 100000 14]

2020-06-16 19:59:39 (10292): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-16 19:59:49 (10292): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-16 21:12:33 (10292): Preference change detected
2020-06-16 21:12:33 (10292): Setting CPU throttle for VM. (40%)
2020-06-16 21:12:33 (10292): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2020-06-16 21:32:08 (10292): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-16 21:32:18 (10292): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-16 21:35:22 (10292): Status Report: Job Duration: '864000.000000'
2020-06-16 21:35:22 (10292): Status Report: Elapsed Time: '20325.000000'
2020-06-16 21:35:22 (10292): Status Report: CPU Time: '8906.531250'
2020-06-16 23:15:27 (10292): Status Report: Job Duration: '864000.000000'
2020-06-16 23:15:27 (10292): Status Report: Elapsed Time: '26325.000000'
2020-06-16 23:15:27 (10292): Status Report: CPU Time: '11623.062500'
2020-06-17 00:55:33 (10292): Status Report: Job Duration: '864000.000000'
2020-06-17 00:55:33 (10292): Status Report: Elapsed Time: '32325.000000'
2020-06-17 00:55:33 (10292): Status Report: CPU Time: '14329.171875'
2020-06-17 01:44:43 (10292): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-17 11:19:03 (10292): Error in resume VM for VM: -2135228414
Command:
VBoxManage -q controlvm "boinc_0e063649b902631f" resume
Output:
VBoxManage.exe: error: Cannot resume the machine as it is not paused (machine state: Running)
VBoxManage.exe: error: Details: code VBOX_E_INVALID_VM_STATE (0x80bb0002), component ConsoleWrap, interface IConsole, callee IUnknown
VBoxManage.exe: error: Context: "Resume()" at line 409 of file VBoxManageControlVM.cpp

2020-06-17 11:19:04 (10292): Guest Log: 05:48:58.257710 timesync vgsvcTimeSyncWorker: Radical host time change: 34 465 750 000 000ns (HostNow=1 592 381 943 185 000 000 ns HostLast=1 592 347 477 435 000 000 ns)

2020-06-17 11:19:04 (10292): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-17 11:19:10 (10292): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-17 11:19:30 (10292): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-17 11:19:34 (10292): Guest Log: 05:49:08.374494 timesync vgsvcTimeSyncWorker: Radical guest time change: 34 475 962 990 000ns (GuestNow=1 592 381 953 530 011 000 ns GuestLast=1 592 347 477 567 021 000 ns fSetTimeLastLoop=true )

2020-06-17 11:28:53 (10292): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-17 11:29:03 (10292): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-17 12:10:33 (10292): Status Report: Job Duration: '864000.000000'
2020-06-17 12:10:33 (10292): Status Report: Elapsed Time: '38325.000000'
2020-06-17 12:10:33 (10292): Status Report: CPU Time: '17035.687500'
2020-06-17 12:20:45 (10292): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-17 12:20:55 (10292): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-17 12:21:05 (10292): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-17 12:21:15 (10292): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-17 13:51:01 (10292): Status Report: Job Duration: '864000.000000'
2020-06-17 13:51:01 (10292): Status Report: Elapsed Time: '44325.000000'
2020-06-17 13:51:01 (10292): Status Report: CPU Time: '19741.000000'
2020-06-17 15:00:21 (10292): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-17 15:21:52 (10292): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-17 15:21:58 (10292): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-17 15:22:09 (10292): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-17 15:52:47 (10292): Status Report: Job Duration: '864000.000000'
2020-06-17 15:52:47 (10292): Status Report: Elapsed Time: '50325.780031'
2020-06-17 15:52:47 (10292): Status Report: CPU Time: '22452.093750'
2020-06-17 17:32:52 (10292): Status Report: Job Duration: '864000.000000'
2020-06-17 17:32:52 (10292): Status Report: Elapsed Time: '56325.780031'
2020-06-17 17:32:52 (10292): Status Report: CPU Time: '25161.781250'
2020-06-17 19:12:58 (10292): Status Report: Job Duration: '864000.000000'
2020-06-17 19:12:58 (10292): Status Report: Elapsed Time: '62325.780031'
2020-06-17 19:12:58 (10292): Status Report: CPU Time: '27867.265625'
2020-06-17 20:01:52 (10292): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-17 20:02:12 (10292): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-17 20:53:26 (10292): Status Report: Job Duration: '864000.000000'
2020-06-17 20:53:26 (10292): Status Report: Elapsed Time: '68325.780031'
2020-06-17 20:53:26 (10292): Status Report: CPU Time: '30574.281250'
2020-06-17 21:12:36 (10292): Preference change detected
2020-06-17 21:12:36 (10292): Setting CPU throttle for VM. (40%)
2020-06-17 21:12:36 (10292): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2020-06-17 22:33:32 (10292): Status Report: Job Duration: '864000.000000'
2020-06-17 22:33:32 (10292): Status Report: Elapsed Time: '74325.780031'
2020-06-17 22:33:32 (10292): Status Report: CPU Time: '33279.000000'
2020-06-18 00:13:37 (10292): Status Report: Job Duration: '864000.000000'
2020-06-18 00:13:37 (10292): Status Report: Elapsed Time: '80325.780031'
2020-06-18 00:13:37 (10292): Status Report: CPU Time: '35984.546875'
2020-06-18 00:16:14 (10292): Guest Log: job: run exitcode=0

2020-06-18 00:16:14 (10292): Guest Log: job: diskusage=2084

2020-06-18 00:16:14 (10292): Guest Log: job: logsize=76 k

2020-06-18 00:16:14 (10292): Guest Log: job: times=

2020-06-18 00:16:14 (10292): Guest Log: 0m0.039s 0m0.062s

2020-06-18 00:16:14 (10292): Guest Log: 1064m52.554s 7m21.729s

2020-06-18 00:16:14 (10292): Guest Log: job: cpuusage=64334

2020-06-18 00:16:16 (10292): Guest Log: 00:16:02 EEST +03:00 2020-06-18: cranky: [INFO] Container 'runc' finished with status code 0.

2020-06-18 00:16:16 (10292): Guest Log: 00:16:02 EEST +03:00 2020-06-18: cranky: [INFO] Preparing output.

2020-06-18 00:16:20 (10292): Guest Log: [INFO] Job Finished

2020-06-18 00:16:21 (10292): Guest Log: [INFO] Shutting Down.

2020-06-18 00:16:21 (10292): VM Completion File Detected.
2020-06-18 00:16:21 (10292): VM Completion Message: Job Finished
.
2020-06-18 00:16:21 (10292): Powering off VM.
2020-06-18 00:21:22 (10292): VM did not power off when requested.
2020-06-18 00:21:22 (10292): VM was successfully terminated.
2020-06-18 00:21:22 (10292): Deregistering VM. (boinc_0e063649b902631f, slot#6)
2020-06-18 00:21:23 (10292): Removing network bandwidth throttle group from VM.
2020-06-18 00:21:23 (10292): Removing VM from VirtualBox.
00:21:29 (10292): called boinc_finish(0)

</stderr_txt>
]]>
LHC@home

Stderr output
