Task 288243540

Name	Theory_2390-1150107-56_0
Workunit	147717083
Created	24 Oct 2020, 10:42:37 UTC
Sent	24 Oct 2020, 13:06:58 UTC
Report deadline	4 Nov 2020, 13:06:58 UTC
Received	5 Nov 2020, 10:04:01 UTC
Server state	Over
Outcome	Success
Client state	Done
Exit status	0 (0x00000000)
Computer ID	10627514
Run time	5 days 9 hours 27 min 54 sec
CPU time	5 days 6 hours 37 min 22 sec
Validate state	Valid
Credit	3,085.33
Device peak FLOPS	2.86 GFLOPS
Application version	Theory Simulation v300.06 (vbox64_theory) windows_x86_64
Peak working set size	64.50 MB
Peak swap size	83.57 MB
Peak disk usage	4.04 GB
Stderr output

<core_client_version>7.16.11</core_client_version>
<![CDATA[
<stderr_txt>
00779.781250'
2020-10-28 11:21:21 (3168): Status Report: Job Duration: '864000.000000'
2020-10-28 11:21:21 (3168): Status Report: Elapsed Time: '108544.811551'
2020-10-28 11:21:21 (3168): Status Report: CPU Time: '106792.421875'
2020-10-28 13:01:46 (3168): Status Report: Job Duration: '864000.000000'
2020-10-28 13:01:46 (3168): Status Report: Elapsed Time: '114544.811551'
2020-10-28 13:01:46 (3168): Status Report: CPU Time: '112804.625000'
2020-10-28 14:42:06 (3168): Status Report: Job Duration: '864000.000000'
2020-10-28 14:42:06 (3168): Status Report: Elapsed Time: '120544.811551'
2020-10-28 14:42:06 (3168): Status Report: CPU Time: '118812.859375'
2020-10-28 16:22:21 (3168): Status Report: Job Duration: '864000.000000'
2020-10-28 16:22:21 (3168): Status Report: Elapsed Time: '126544.811551'
2020-10-28 16:22:21 (3168): Status Report: CPU Time: '124814.062500'
2020-10-28 18:02:52 (3168): Status Report: Job Duration: '864000.000000'
2020-10-28 18:02:52 (3168): Status Report: Elapsed Time: '132544.811551'
2020-10-28 18:02:52 (3168): Status Report: CPU Time: '130831.015625'
2020-10-28 19:43:11 (3168): Status Report: Job Duration: '864000.000000'
2020-10-28 19:43:11 (3168): Status Report: Elapsed Time: '138544.811551'
2020-10-28 19:43:11 (3168): Status Report: CPU Time: '136840.328125'
2020-10-28 21:00:03 (3168): Stopping VM.
2020-10-28 21:00:57 (3168): Error in stop VM for VM: -182
Command:
VBoxManage -q controlvm "boinc_6ac7933ca813bd05" savestate
Output:
0%...10%...20%...30%...40%...50%...60%...70%...80%...
2020-10-28 21:00:57 (3168): VM did not stop when requested.
2020-10-28 21:00:57 (3168): VM was successfully terminated.
2020-10-29 08:00:00 (200): Detected: vboxwrapper 26197
2020-10-29 08:00:00 (200): Detected: BOINC client v7.7
2020-10-29 08:00:07 (200): Detected: VirtualBox VboxManage Interface (Version: 6.1.14)
2020-10-29 08:00:07 (200): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2020-10-29 08:00:08 (200): Starting VM using VBoxManage interface. (boinc_6ac7933ca813bd05, slot#4)
2020-10-29 08:00:17 (200): Successfully started VM. (PID = '11496')
2020-10-29 08:00:17 (200): Reporting VM Process ID to BOINC.
2020-10-29 08:00:17 (200): Guest Log: BIOS: VirtualBox 6.1.14

2020-10-29 08:00:17 (200): Guest Log: CPUID EDX: 0x178bfbff

2020-10-29 08:00:17 (200): Guest Log: BIOS: ata0-0: PCHS=16383/16/63 LCHS=1024/255/63

2020-10-29 08:00:17 (200): VM state change detected. (old = 'PoweredOff', new = 'Running')
2020-10-29 08:00:17 (200): Detected: Web Application Enabled (http://localhost:50989)
2020-10-29 08:00:17 (200): Detected: Remote Desktop Enabled (localhost:50990)
2020-10-29 08:00:17 (200): Status Report: Job Duration: '864000.000000'
2020-10-29 08:00:17 (200): Status Report: Elapsed Time: '143144.811551'
2020-10-29 08:00:17 (200): Status Report: CPU Time: '141440.781250'
2020-10-29 08:00:17 (200): Preference change detected
2020-10-29 08:00:17 (200): Setting CPU throttle for VM. (100%)
2020-10-29 08:00:18 (200): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2020-10-29 08:00:19 (200): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032

2020-10-29 08:00:19 (200): Guest Log: BIOS: Booting from Hard Disk...

2020-10-29 08:00:30 (200): Guest Log: BIOS: KBD: unsupported int 16h function 03

2020-10-29 08:00:30 (200): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 

2020-10-29 08:01:36 (200): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds

2020-10-29 08:01:36 (200): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000)

2020-10-29 08:01:43 (200): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log

2020-10-29 08:01:43 (200): Guest Log: 00:00:00.000338 main     Log opened 2020-10-29T06:01:43.102922000Z

2020-10-29 08:01:43 (200): Guest Log: 00:00:00.000954 main     OS Product: Linux

2020-10-29 08:01:43 (200): Guest Log: 00:00:00.003522 main     OS Release: 4.14.76-13.cernvm.x86_64

2020-10-29 08:01:43 (200): Guest Log: 00:00:00.003656 main     OS Version: #1 SMP Tue Oct 16 18:26:15 CEST 2018

2020-10-29 08:01:43 (200): Guest Log: 00:00:00.003736 main     Executable: /usr/sbin/VBoxService

2020-10-29 08:01:43 (200): Guest Log: 00:00:00.003739 main     Process ID: 3181

2020-10-29 08:01:43 (200): Guest Log: 00:00:00.003742 main     Package type: LINUX_64BITS_GENERIC

2020-10-29 08:01:43 (200): Guest Log: 00:00:00.007090 main     5.2.6 r120293 started. Verbose level = 0

2020-10-29 08:02:43 (200): Guest Log: 08:02:43 EET +02:00 2020-10-29: cranky: [INFO] Detected Theory App

2020-10-29 08:02:43 (200): Guest Log: 08:02:43 EET +02:00 2020-10-29: cranky: [INFO] Checking CVMFS.

2020-10-29 08:02:47 (200): Guest Log: Probing /cvmfs/sft.cern.ch... OK

2020-10-29 08:02:49 (200): 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-10-29 08:02:49 (200): Guest Log: 2.5.2.0 4146 0 27192 19179 3 1 1823977 4096001 0 65024 0 0 n/a 0 0 http://s1cern-cvmfs.openhtc.io/cvmfs/sft.cern.ch DIRECT 1

2020-10-29 08:02:53 (200): Guest Log: Probing /cvmfs/grid.cern.ch... OK

2020-10-29 08:02:55 (200): 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-10-29 08:02:55 (200): Guest Log: 2.5.2.0 4092 0 27560 13567 3 2 1823977 4096001 0 65024 0 2 0 4273 1350 http://s1asgc-cvmfs.openhtc.io:8080/cvmfs/grid.cern.ch DIRECT 1

2020-10-29 08:02:59 (200): Guest Log: Probing /cvmfs/cernvm-prod.cern.ch... OK

2020-10-29 08:03:01 (200): 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-10-29 08:03:01 (200): Guest Log: 2.5.2.0 4113 0 25736 259 3 1 1823977 4096001 0 65024 0 0 n/a 0 0 http://s1cern-cvmfs.openhtc.io/cvmfs/cernvm-prod.cern.ch DIRECT 1

2020-10-29 08:03:06 (200): Guest Log: Probing /cvmfs/alice.cern.ch... OK

2020-10-29 08:03:09 (200): 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-10-29 08:03:09 (200): Guest Log: 2.5.2.0 4143 0 30376 7841 3 1 1823977 4096001 0 65024 0 0 n/a 4523 1628 http://s1unl-cvmfs.openhtc.io/cvmfs/alice.cern.ch DIRECT 1

2020-10-29 08:03:09 (200): Guest Log: 08:03:08 EET +02:00 2020-10-29: cranky: [INFO] Checking runc.

2020-10-29 08:03:10 (200): Guest Log: 08:03:09 EET +02:00 2020-10-29: cranky: [INFO] Creating the filesystem.

2020-10-29 08:03:10 (200): Guest Log: 08:03:09 EET +02:00 2020-10-29: cranky: [INFO] Using /cvmfs/cernvm-prod.cern.ch/cvm3

2020-10-29 08:03:10 (200): Guest Log: 08:03:10 EET +02:00 2020-10-29: cranky: [INFO] Updating config.json.

2020-10-29 08:03:11 (200): Guest Log: 08:03:10 EET +02:00 2020-10-29: cranky: [INFO] Running Container 'runc'.

2020-10-29 08:03:13 (200): Guest Log: job: htmld=/shared/html/job

2020-10-29 08:03:13 (200): Guest Log: job: unpack exitcode=0

2020-10-29 08:03:16 (200): Guest Log: 08:03:15 EET +02:00 2020-10-29: cranky: [INFO] ===> [runRivet] Thu Oct 29 06:03:12 UTC 2020 [boinc pp z1j 7000 100 - pythia8 8.235 default 100000 56]

2020-10-29 09:41:33 (200): Status Report: Job Duration: '864000.000000'
2020-10-29 09:41:33 (200): Status Report: Elapsed Time: '149145.270251'
2020-10-29 09:41:33 (200): Status Report: CPU Time: '147005.562500'
2020-10-29 11:21:59 (200): Status Report: Job Duration: '864000.000000'
2020-10-29 11:21:59 (200): Status Report: Elapsed Time: '155145.270251'
2020-10-29 11:21:59 (200): Status Report: CPU Time: '153013.906250'
2020-10-29 13:02:40 (200): Status Report: Job Duration: '864000.000000'
2020-10-29 13:02:40 (200): Status Report: Elapsed Time: '161145.929500'
2020-10-29 13:02:40 (200): Status Report: CPU Time: '159042.984375'
2020-10-29 14:43:26 (200): Status Report: Job Duration: '864000.000000'
2020-10-29 14:43:26 (200): Status Report: Elapsed Time: '167145.929500'
2020-10-29 14:43:26 (200): Status Report: CPU Time: '165073.562500'
2020-10-29 14:58:38 (200): Stopping VM.
2020-10-29 14:59:33 (200): Error in stop VM for VM: -182
Command:
VBoxManage -q controlvm "boinc_6ac7933ca813bd05" savestate
Output:
0%...10%...20%...30%...40%...50%...60%...
2020-10-29 14:59:33 (200): VM did not stop when requested.
2020-10-29 14:59:33 (200): VM was successfully terminated.
2020-10-29 16:16:04 (10356): Detected: vboxwrapper 26197
2020-10-29 16:16:05 (10356): Detected: BOINC client v7.7
2020-10-29 16:16:07 (10356): Detected: VirtualBox VboxManage Interface (Version: 6.1.14)
2020-10-29 16:16:08 (10356): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2020-10-29 16:16:08 (10356): Starting VM using VBoxManage interface. (boinc_6ac7933ca813bd05, slot#4)
2020-10-29 16:16:17 (10356): Successfully started VM. (PID = '6096')
2020-10-29 16:16:17 (10356): Reporting VM Process ID to BOINC.
2020-10-29 16:16:17 (10356): Guest Log: BIOS: VirtualBox 6.1.14

2020-10-29 16:16:17 (10356): Guest Log: CPUID EDX: 0x178bfbff

2020-10-29 16:16:17 (10356): Guest Log: BIOS: ata0-0: PCHS=16383/16/63 LCHS=1024/255/63

2020-10-29 16:16:17 (10356): VM state change detected. (old = 'PoweredOff', new = 'Running')
2020-10-29 16:16:17 (10356): Detected: Web Application Enabled (http://localhost:50989)
2020-10-29 16:16:17 (10356): Detected: Remote Desktop Enabled (localhost:50990)
2020-10-29 16:16:17 (10356): Status Report: Job Duration: '864000.000000'
2020-10-29 16:16:17 (10356): Status Report: Elapsed Time: '168049.929500'
2020-10-29 16:16:17 (10356): Status Report: CPU Time: '165988.687500'
2020-10-29 16:16:17 (10356): Preference change detected
2020-10-29 16:16:17 (10356): Setting CPU throttle for VM. (100%)
2020-10-29 16:16:17 (10356): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2020-10-29 16:16:19 (10356): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032

2020-10-29 16:16:19 (10356): Guest Log: BIOS: Booting from Hard Disk...

2020-10-29 16:16:26 (10356): Guest Log: BIOS: KBD: unsupported int 16h function 03

2020-10-29 16:16:26 (10356): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 

2020-10-29 16:17:29 (10356): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds

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

2020-10-29 16:17:40 (10356): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log

2020-10-29 16:17:40 (10356): Guest Log: 00:00:00.000283 main     Log opened 2020-10-29T14:17:40.016336000Z

2020-10-29 16:17:40 (10356): Guest Log: 00:00:00.000531 main     OS Product: Linux

2020-10-29 16:17:40 (10356): Guest Log: 00:00:00.000668 main     OS Release: 4.14.76-13.cernvm.x86_64

2020-10-29 16:17:40 (10356): Guest Log: 00:00:00.000763 main     OS Version: #1 SMP Tue Oct 16 18:26:15 CEST 2018

2020-10-29 16:17:40 (10356): Guest Log: 00:00:00.000897 main     Executable: /usr/sbin/VBoxService

2020-10-29 16:17:40 (10356): Guest Log: 00:00:00.000899 main     Process ID: 3206

2020-10-29 16:17:40 (10356): Guest Log: 00:00:00.000901 main     Package type: LINUX_64BITS_GENERIC

2020-10-29 16:17:40 (10356): Guest Log: 00:00:00.008574 main     5.2.6 r120293 started. Verbose level = 0

2020-10-29 16:18:49 (10356): Guest Log: 16:18:49 EET +02:00 2020-10-29: cranky: [INFO] Detected Theory App

2020-10-29 16:18:49 (10356): Guest Log: 16:18:49 EET +02:00 2020-10-29: cranky: [INFO] Checking CVMFS.

2020-10-29 16:18:53 (10356): Guest Log: Probing /cvmfs/sft.cern.ch... OK

2020-10-29 16:18:55 (10356): 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-10-29 16:18:55 (10356): Guest Log: 2.5.2.0 4092 0 27188 19180 3 1 1832337 4096001 0 65024 0 0 n/a 0 0 http://s1cern-cvmfs.openhtc.io/cvmfs/sft.cern.ch DIRECT 1

2020-10-29 16:18:59 (10356): Guest Log: Probing /cvmfs/grid.cern.ch... OK

2020-10-29 16:19:02 (10356): 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-10-29 16:19:02 (10356): Guest Log: 2.5.2.0 4138 0 27544 13571 3 2 1832337 4096001 0 65024 0 2 0 4273 2647 http://s1bnl-cvmfs.openhtc.io/cvmfs/grid.cern.ch DIRECT 1

2020-10-29 16:19:08 (10356): Guest Log: Probing /cvmfs/cernvm-prod.cern.ch... OK

2020-10-29 16:19:11 (10356): 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-10-29 16:19:11 (10356): Guest Log: 2.5.2.0 4113 0 27204 259 3 1 1832337 4096001 0 65024 0 0 n/a 0 0 http://s1cern-cvmfs.openhtc.io/cvmfs/cernvm-prod.cern.ch DIRECT 1

2020-10-29 16:19:17 (10356): Guest Log: Probing /cvmfs/alice.cern.ch... OK

2020-10-29 16:19:20 (10356): 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-10-29 16:19:20 (10356): Guest Log: 2.5.2.0 4159 1 28324 7841 3 1 1832337 4096001 0 65024 0 0 n/a 0 0 http://s1cern-cvmfs.openhtc.io/cvmfs/alice.cern.ch DIRECT 1

2020-10-29 16:19:20 (10356): Guest Log: 16:19:20 EET +02:00 2020-10-29: cranky: [INFO] Checking runc.

2020-10-29 16:19:20 (10356): Guest Log: 16:19:20 EET +02:00 2020-10-29: cranky: [INFO] Creating the filesystem.

2020-10-29 16:19:20 (10356): Guest Log: 16:19:20 EET +02:00 2020-10-29: cranky: [INFO] Using /cvmfs/cernvm-prod.cern.ch/cvm3

2020-10-29 16:19:21 (10356): Guest Log: 16:19:21 EET +02:00 2020-10-29: cranky: [INFO] Updating config.json.

2020-10-29 16:19:21 (10356): Guest Log: 16:19:21 EET +02:00 2020-10-29: cranky: [INFO] Running Container 'runc'.

2020-10-29 16:19:26 (10356): Guest Log: job: htmld=/shared/html/job

2020-10-29 16:19:27 (10356): Guest Log: job: unpack exitcode=0

2020-10-29 16:19:28 (10356): Guest Log: 16:19:27 EET +02:00 2020-10-29: cranky: [INFO] ===> [runRivet] Thu Oct 29 14:19:27 UTC 2020 [boinc pp z1j 7000 100 - pythia8 8.235 default 100000 56]

2020-10-29 17:57:00 (10356): Status Report: Job Duration: '864000.000000'
2020-10-29 17:57:00 (10356): Status Report: Elapsed Time: '174050.648586'
2020-10-29 17:57:00 (10356): Status Report: CPU Time: '171098.265625'
2020-10-29 19:37:27 (10356): Status Report: Job Duration: '864000.000000'
2020-10-29 19:37:27 (10356): Status Report: Elapsed Time: '180050.648586'
2020-10-29 19:37:27 (10356): Status Report: CPU Time: '177116.031250'
2020-10-29 21:00:02 (10356): Stopping VM.
2020-10-29 21:00:54 (10356): Error in stop VM for VM: -182
Command:
VBoxManage -q controlvm "boinc_6ac7933ca813bd05" savestate
Output:
0%...10%...20%...30%...40%...50%...60%...70%...
2020-10-29 21:00:55 (10356): VM did not stop when requested.
2020-10-29 21:00:55 (10356): VM was successfully terminated.
2020-10-30 08:00:00 (5888): Detected: vboxwrapper 26197
2020-10-30 08:00:00 (5888): Detected: BOINC client v7.7
2020-10-30 08:00:03 (5888): Detected: VirtualBox VboxManage Interface (Version: 6.1.14)
2020-10-30 08:00:03 (5888): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2020-10-30 08:00:03 (5888): Starting VM using VBoxManage interface. (boinc_6ac7933ca813bd05, slot#4)
2020-10-30 08:00:11 (5888): Successfully started VM. (PID = '10504')
2020-10-30 08:00:11 (5888): Reporting VM Process ID to BOINC.
2020-10-30 08:00:11 (5888): Guest Log: BIOS: VirtualBox 6.1.14

2020-10-30 08:00:11 (5888): Guest Log: CPUID EDX: 0x178bfbff

2020-10-30 08:00:11 (5888): Guest Log: BIOS: ata0-0: PCHS=16383/16/63 LCHS=1024/255/63

2020-10-30 08:00:11 (5888): VM state change detected. (old = 'PoweredOff', new = 'Running')
2020-10-30 08:00:11 (5888): Detected: Web Application Enabled (http://localhost:50989)
2020-10-30 08:00:11 (5888): Detected: Remote Desktop Enabled (localhost:50990)
2020-10-30 08:00:11 (5888): Status Report: Job Duration: '864000.000000'
2020-10-30 08:00:11 (5888): Status Report: Elapsed Time: '184984.717658'
2020-10-30 08:00:11 (5888): Status Report: CPU Time: '182069.890625'
2020-10-30 08:00:11 (5888): Preference change detected
2020-10-30 08:00:11 (5888): Setting CPU throttle for VM. (100%)
2020-10-30 08:00:12 (5888): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2020-10-30 08:00:13 (5888): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032

2020-10-30 08:00:13 (5888): Guest Log: BIOS: Booting from Hard Disk...

2020-10-30 08:00:21 (5888): Guest Log: BIOS: KBD: unsupported int 16h function 03

2020-10-30 08:00:21 (5888): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 

2020-10-30 08:01:15 (5888): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds

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

2020-10-30 08:01:22 (5888): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log

2020-10-30 08:01:22 (5888): Guest Log: 00:00:00.000454 main     Log opened 2020-10-30T06:01:22.407413000Z

2020-10-30 08:01:22 (5888): Guest Log: 00:00:00.004524 main     OS Product: Linux

2020-10-30 08:01:22 (5888): Guest Log: 00:00:00.004871 main     OS Release: 4.14.76-13.cernvm.x86_64

2020-10-30 08:01:22 (5888): Guest Log: 00:00:00.004992 main     OS Version: #1 SMP Tue Oct 16 18:26:15 CEST 2018

2020-10-30 08:01:22 (5888): Guest Log: 00:00:00.005069 main     Executable: /usr/sbin/VBoxService

2020-10-30 08:01:22 (5888): Guest Log: 00:00:00.005071 main     Process ID: 3201

2020-10-30 08:01:22 (5888): Guest Log: 00:00:00.005073 main     Package type: LINUX_64BITS_GENERIC

2020-10-30 08:01:22 (5888): Guest Log: 00:00:00.013336 main     5.2.6 r120293 started. Verbose level = 0

2020-10-30 08:02:21 (5888): Guest Log: 08:02:21 EET +02:00 2020-10-30: cranky: [INFO] Detected Theory App

2020-10-30 08:02:21 (5888): Guest Log: 08:02:21 EET +02:00 2020-10-30: cranky: [INFO] Checking CVMFS.

2020-10-30 08:02:25 (5888): Guest Log: Probing /cvmfs/sft.cern.ch... OK

2020-10-30 08:02:28 (5888): 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-10-30 08:02:28 (5888): Guest Log: 2.5.2.0 4129 0 25140 19181 3 1 1850674 4096001 0 65024 0 0 n/a 0 0 http://s1ral-cvmfs.openhtc.io/cvmfs/sft.cern.ch DIRECT 1

2020-10-30 08:02:32 (5888): Guest Log: Probing /cvmfs/grid.cern.ch... OK

2020-10-30 08:02:34 (5888): 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-10-30 08:02:34 (5888): Guest Log: 2.5.2.0 4109 0 27596 13575 3 2 1850674 4096001 0 65024 0 2 0 4274 1362 http://s1bnl-cvmfs.openhtc.io/cvmfs/grid.cern.ch DIRECT 1

2020-10-30 08:02:39 (5888): Guest Log: Probing /cvmfs/cernvm-prod.cern.ch... OK

2020-10-30 08:02:41 (5888): 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-10-30 08:02:41 (5888): Guest Log: 2.5.2.0 4085 0 25184 259 3 1 1850674 4096001 0 65024 0 0 n/a 0 0 http://s1fnal-cvmfs.openhtc.io/cvmfs/cernvm-prod.cern.ch DIRECT 1

2020-10-30 08:02:45 (5888): Guest Log: Probing /cvmfs/alice.cern.ch... OK

2020-10-30 08:02:47 (5888): 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-10-30 08:02:47 (5888): Guest Log: 2.5.2.0 4153 0 30380 7848 3 1 1850674 4096001 0 65024 0 0 n/a 4525 887 http://s1cern-cvmfs.openhtc.io/cvmfs/alice.cern.ch DIRECT 1

2020-10-30 08:02:47 (5888): Guest Log: 08:02:47 EET +02:00 2020-10-30: cranky: [INFO] Checking runc.

2020-10-30 08:02:50 (5888): Guest Log: 08:02:49 EET +02:00 2020-10-30: cranky: [INFO] Creating the filesystem.

2020-10-30 08:02:51 (5888): Guest Log: 08:02:51 EET +02:00 2020-10-30: cranky: [INFO] Using /cvmfs/cernvm-prod.cern.ch/cvm3

2020-10-30 08:02:51 (5888): Guest Log: 08:02:51 EET +02:00 2020-10-30: cranky: [INFO] Updating config.json.

2020-10-30 08:02:52 (5888): Guest Log: 08:02:51 EET +02:00 2020-10-30: cranky: [INFO] Running Container 'runc'.

2020-10-30 08:03:20 (5888): Guest Log: job: htmld=/shared/html/job

2020-10-30 08:03:26 (5888): Guest Log: job: unpack exitcode=0

2020-10-30 08:03:28 (5888): Guest Log: 08:03:28 EET +02:00 2020-10-30: cranky: [INFO] ===> [runRivet] Fri Oct 30 06:03:26 UTC 2020 [boinc pp z1j 7000 100 - pythia8 8.235 default 100000 56]

2020-10-30 09:40:33 (5888): Status Report: Job Duration: '864000.000000'
2020-10-30 09:40:33 (5888): Status Report: Elapsed Time: '190984.891410'
2020-10-30 09:40:33 (5888): Status Report: CPU Time: '187113.968750'
2020-10-30 11:20:55 (5888): Status Report: Job Duration: '864000.000000'
2020-10-30 11:20:55 (5888): Status Report: Elapsed Time: '196984.891410'
2020-10-30 11:20:55 (5888): Status Report: CPU Time: '193128.515625'
2020-10-30 13:01:19 (5888): Status Report: Job Duration: '864000.000000'
2020-10-30 13:01:19 (5888): Status Report: Elapsed Time: '202984.891410'
2020-10-30 13:01:19 (5888): Status Report: CPU Time: '199144.906250'
2020-10-30 14:42:53 (5888): Status Report: Job Duration: '864000.000000'
2020-10-30 14:42:53 (5888): Status Report: Elapsed Time: '208984.966519'
2020-10-30 14:42:53 (5888): Status Report: CPU Time: '205217.734375'
2020-10-30 16:23:21 (5888): Status Report: Job Duration: '864000.000000'
2020-10-30 16:23:21 (5888): Status Report: Elapsed Time: '214985.088311'
2020-10-30 16:23:21 (5888): Status Report: CPU Time: '211238.343750'
2020-10-30 18:04:00 (5888): Status Report: Job Duration: '864000.000000'
2020-10-30 18:04:00 (5888): Status Report: Elapsed Time: '220985.088311'
2020-10-30 18:04:00 (5888): Status Report: CPU Time: '217266.359375'
2020-10-30 18:27:53 (5888): Stopping VM.
2020-10-30 18:28:44 (5888): Error in stop VM for VM: -182
Command:
VBoxManage -q controlvm "boinc_6ac7933ca813bd05" savestate
Output:
0%...10%...20%...30%...40%...50%...60%...70%...80%...
2020-10-30 18:28:44 (5888): VM did not stop when requested.
2020-10-30 18:28:44 (5888): VM was successfully terminated.
2020-10-31 08:00:01 (2340): Detected: vboxwrapper 26197
2020-10-31 08:00:01 (2340): Detected: BOINC client v7.7
2020-10-31 08:00:03 (2340): Detected: VirtualBox VboxManage Interface (Version: 6.1.14)
2020-10-31 08:00:03 (2340): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2020-10-31 08:00:04 (2340): Starting VM using VBoxManage interface. (boinc_6ac7933ca813bd05, slot#4)
2020-10-31 08:00:12 (2340): Successfully started VM. (PID = '6712')
2020-10-31 08:00:12 (2340): Reporting VM Process ID to BOINC.
2020-10-31 08:00:12 (2340): Guest Log: BIOS: VirtualBox 6.1.14

2020-10-31 08:00:12 (2340): Guest Log: CPUID EDX: 0x178bfbff

2020-10-31 08:00:12 (2340): Guest Log: BIOS: ata0-0: PCHS=16383/16/63 LCHS=1024/255/63

2020-10-31 08:00:12 (2340): VM state change detected. (old = 'PoweredOff', new = 'Running')
2020-10-31 08:00:12 (2340): Detected: Web Application Enabled (http://localhost:50989)
2020-10-31 08:00:12 (2340): Detected: Remote Desktop Enabled (localhost:50990)
2020-10-31 08:00:12 (2340): Status Report: Job Duration: '864000.000000'
2020-10-31 08:00:12 (2340): Status Report: Elapsed Time: '222405.088311'
2020-10-31 08:00:12 (2340): Status Report: CPU Time: '218692.734375'
2020-10-31 08:00:12 (2340): Preference change detected
2020-10-31 08:00:12 (2340): Setting CPU throttle for VM. (100%)
2020-10-31 08:00:12 (2340): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2020-10-31 08:00:14 (2340): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032

2020-10-31 08:00:15 (2340): Guest Log: BIOS: Booting from Hard Disk...

2020-10-31 08:00:23 (2340): Guest Log: BIOS: KBD: unsupported int 16h function 03

2020-10-31 08:00:23 (2340): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 

2020-10-31 08:01:14 (2340): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds

2020-10-31 08:01:14 (2340): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000)

2020-10-31 08:01:25 (2340): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log

2020-10-31 08:01:25 (2340): Guest Log: 00:00:00.000341 main     Log opened 2020-10-31T06:01:24.648728000Z

2020-10-31 08:01:25 (2340): Guest Log: 00:00:00.002342 main     OS Product: Linux

2020-10-31 08:01:25 (2340): Guest Log: 00:00:00.002468 main     OS Release: 4.14.76-13.cernvm.x86_64

2020-10-31 08:01:25 (2340): Guest Log: 00:00:00.002577 main     OS Version: #1 SMP Tue Oct 16 18:26:15 CEST 2018

2020-10-31 08:01:25 (2340): Guest Log: 00:00:00.004694 main     Executable: /usr/sbin/VBoxService

2020-10-31 08:01:25 (2340): Guest Log: 00:00:00.004699 main     Process ID: 3223

2020-10-31 08:01:25 (2340): Guest Log: 00:00:00.004701 main     Package type: LINUX_64BITS_GENERIC

2020-10-31 08:01:25 (2340): Guest Log: 00:00:00.011015 main     5.2.6 r120293 started. Verbose level = 0

2020-10-31 08:02:18 (2340): Guest Log: 08:02:17 EET +02:00 2020-10-31: cranky: [INFO] Detected Theory App

2020-10-31 08:02:18 (2340): Guest Log: 08:02:17 EET +02:00 2020-10-31: cranky: [INFO] Checking CVMFS.

2020-10-31 08:02:21 (2340): Guest Log: Probing /cvmfs/sft.cern.ch... OK

2020-10-31 08:02:22 (2340): 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-10-31 08:02:22 (2340): Guest Log: 2.5.2.0 4093 0 27204 19185 3 1 1869051 4096001 0 65024 0 0 n/a 5 9 http://s1ral-cvmfs.openhtc.io/cvmfs/sft.cern.ch DIRECT 1

2020-10-31 08:02:25 (2340): Guest Log: Probing /cvmfs/grid.cern.ch... OK

2020-10-31 08:02:27 (2340): 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-10-31 08:02:27 (2340): Guest Log: 2.5.2.0 4140 0 27588 13583 3 2 1869051 4096001 0 65024 0 2 0 4274 1886 http://s1asgc-cvmfs.openhtc.io:8080/cvmfs/grid.cern.ch DIRECT 1

2020-10-31 08:02:30 (2340): Guest Log: Probing /cvmfs/cernvm-prod.cern.ch... OK

2020-10-31 08:02:32 (2340): 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-10-31 08:02:32 (2340): Guest Log: 2.5.2.0 4115 0 27216 259 3 1 1869051 4096001 0 65024 0 0 n/a 0 0 http://s1cern-cvmfs.openhtc.io/cvmfs/cernvm-prod.cern.ch DIRECT 1

2020-10-31 08:02:35 (2340): Guest Log: Probing /cvmfs/alice.cern.ch... OK

2020-10-31 08:02:37 (2340): 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-10-31 08:02:37 (2340): Guest Log: 2.5.2.0 4166 0 30452 7855 3 1 1869051 4096001 0 65024 0 0 n/a 4525 1011 http://s1asgc-cvmfs.openhtc.io:8080/cvmfs/alice.cern.ch DIRECT 1

2020-10-31 08:02:37 (2340): Guest Log: 08:02:36 EET +02:00 2020-10-31: cranky: [INFO] Checking runc.

2020-10-31 08:02:37 (2340): Guest Log: 08:02:37 EET +02:00 2020-10-31: cranky: [INFO] Creating the filesystem.

2020-10-31 08:02:38 (2340): Guest Log: 08:02:37 EET +02:00 2020-10-31: cranky: [INFO] Using /cvmfs/cernvm-prod.cern.ch/cvm3

2020-10-31 08:02:38 (2340): Guest Log: 08:02:37 EET +02:00 2020-10-31: cranky: [INFO] Updating config.json.

2020-10-31 08:02:38 (2340): Guest Log: 08:02:37 EET +02:00 2020-10-31: cranky: [INFO] Running Container 'runc'.

2020-10-31 08:02:40 (2340): Guest Log: job: htmld=/shared/html/job

2020-10-31 08:02:40 (2340): Guest Log: job: unpack exitcode=0

2020-10-31 08:02:40 (2340): Guest Log: 08:02:40 EET +02:00 2020-10-31: cranky: [INFO] ===> [runRivet] Sat Oct 31 06:02:39 UTC 2020 [boinc pp z1j 7000 100 - pythia8 8.235 default 100000 56]

2020-10-31 09:40:55 (2340): Status Report: Job Duration: '864000.000000'
2020-10-31 09:40:55 (2340): Status Report: Elapsed Time: '228405.861584'
2020-10-31 09:40:55 (2340): Status Report: CPU Time: '223840.750000'
2020-10-31 11:23:00 (2340): Status Report: Job Duration: '864000.000000'
2020-10-31 11:23:00 (2340): Status Report: Elapsed Time: '234406.850186'
2020-10-31 11:23:00 (2340): Status Report: CPU Time: '229947.671875'
2020-10-31 13:03:16 (2340): Status Report: Job Duration: '864000.000000'
2020-10-31 13:03:16 (2340): Status Report: Elapsed Time: '240406.850186'
2020-10-31 13:03:16 (2340): Status Report: CPU Time: '235958.750000'
2020-10-31 14:43:32 (2340): Status Report: Job Duration: '864000.000000'
2020-10-31 14:43:32 (2340): Status Report: Elapsed Time: '246406.850186'
2020-10-31 14:43:32 (2340): Status Report: CPU Time: '241967.906250'
2020-10-31 16:23:53 (2340): Status Report: Job Duration: '864000.000000'
2020-10-31 16:23:53 (2340): Status Report: Elapsed Time: '252406.850186'
2020-10-31 16:23:53 (2340): Status Report: CPU Time: '247978.218750'
2020-10-31 18:04:16 (2340): Status Report: Job Duration: '864000.000000'
2020-10-31 18:04:16 (2340): Status Report: Elapsed Time: '258406.850186'
2020-10-31 18:04:16 (2340): Status Report: CPU Time: '253993.156250'
2020-10-31 19:44:38 (2340): Status Report: Job Duration: '864000.000000'
2020-10-31 19:44:38 (2340): Status Report: Elapsed Time: '264406.850186'
2020-10-31 19:44:38 (2340): Status Report: CPU Time: '260006.578125'
2020-10-31 21:00:02 (2340): Stopping VM.
2020-10-31 21:00:56 (2340): Error in stop VM for VM: -182
Command:
VBoxManage -q controlvm "boinc_6ac7933ca813bd05" savestate
Output:
0%...10%...20%...30%...40%...50%...60%...70%...80%...
2020-10-31 21:00:56 (2340): VM did not stop when requested.
2020-10-31 21:00:56 (2340): VM was successfully terminated.
2020-11-01 08:00:00 (4576): Detected: vboxwrapper 26197
2020-11-01 08:00:00 (4576): Detected: BOINC client v7.7
2020-11-01 08:00:09 (4576): Detected: VirtualBox VboxManage Interface (Version: 6.1.14)
2020-11-01 08:00:09 (4576): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2020-11-01 08:00:09 (4576): Starting VM using VBoxManage interface. (boinc_6ac7933ca813bd05, slot#4)
2020-11-01 08:00:17 (4576): Successfully started VM. (PID = '9408')
2020-11-01 08:00:17 (4576): Reporting VM Process ID to BOINC.
2020-11-01 08:00:17 (4576): Guest Log: BIOS: VirtualBox 6.1.14

2020-11-01 08:00:17 (4576): Guest Log: CPUID EDX: 0x178bfbff

2020-11-01 08:00:17 (4576): Guest Log: BIOS: ata0-0: PCHS=16383/16/63 LCHS=1024/255/63

2020-11-01 08:00:17 (4576): VM state change detected. (old = 'PoweredOff', new = 'Running')
2020-11-01 08:00:17 (4576): Detected: Web Application Enabled (http://localhost:50989)
2020-11-01 08:00:17 (4576): Detected: Remote Desktop Enabled (localhost:50990)
2020-11-01 08:00:17 (4576): Status Report: Job Duration: '864000.000000'
2020-11-01 08:00:17 (4576): Status Report: Elapsed Time: '268913.101823'
2020-11-01 08:00:17 (4576): Status Report: CPU Time: '264512.781250'
2020-11-01 08:00:17 (4576): Preference change detected
2020-11-01 08:00:17 (4576): Setting CPU throttle for VM. (100%)
2020-11-01 08:00:18 (4576): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2020-11-01 08:00:19 (4576): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032

2020-11-01 08:00:19 (4576): Guest Log: BIOS: Booting from Hard Disk...

2020-11-01 08:00:28 (4576): Guest Log: BIOS: KBD: unsupported int 16h function 03

2020-11-01 08:00:28 (4576): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 

2020-11-01 08:01:22 (4576): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds

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

2020-11-01 08:01:31 (4576): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log

2020-11-01 08:01:31 (4576): Guest Log: 00:00:00.000211 main     Log opened 2020-11-01T06:01:31.765698000Z

2020-11-01 08:01:31 (4576): Guest Log: 00:00:00.000667 main     OS Product: Linux

2020-11-01 08:01:31 (4576): Guest Log: 00:00:00.000749 main     OS Release: 4.14.76-13.cernvm.x86_64

2020-11-01 08:01:31 (4576): Guest Log: 00:00:00.000822 main     OS Version: #1 SMP Tue Oct 16 18:26:15 CEST 2018

2020-11-01 08:01:31 (4576): Guest Log: 00:00:00.004219 main     Executable: /usr/sbin/VBoxService

2020-11-01 08:01:31 (4576): Guest Log: 00:00:00.004224 main     Process ID: 3185

2020-11-01 08:01:31 (4576): Guest Log: 00:00:00.004226 main     Package type: LINUX_64BITS_GENERIC

2020-11-01 08:01:31 (4576): Guest Log: 00:00:00.006313 main     5.2.6 r120293 started. Verbose level = 0

2020-11-01 08:02:23 (4576): Guest Log: 08:02:23 EET +02:00 2020-11-01: cranky: [INFO] Detected Theory App

2020-11-01 08:02:23 (4576): Guest Log: 08:02:23 EET +02:00 2020-11-01: cranky: [INFO] Checking CVMFS.

2020-11-01 08:02:26 (4576): Guest Log: Probing /cvmfs/sft.cern.ch... OK

2020-11-01 08:02:27 (4576): 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-11-01 08:02:27 (4576): Guest Log: 2.5.2.0 4085 0 28316 19187 3 1 1887412 4096001 0 65024 0 0 n/a 0 0 http://s1fnal-cvmfs.openhtc.io/cvmfs/sft.cern.ch DIRECT 1

2020-11-01 08:02:29 (4576): Guest Log: Probing /cvmfs/grid.cern.ch... OK

2020-11-01 08:02:31 (4576): 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-11-01 08:02:31 (4576): Guest Log: 2.5.2.0 4157 0 27728 13591 3 2 1887412 4096001 0 65024 0 2 0 4274 932 http://s1bnl-cvmfs.openhtc.io/cvmfs/grid.cern.ch DIRECT 1

2020-11-01 08:02:35 (4576): Guest Log: Probing /cvmfs/cernvm-prod.cern.ch... OK

2020-11-01 08:02:38 (4576): 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-11-01 08:02:38 (4576): Guest Log: 2.5.2.0 4107 0 27540 259 3 1 1887412 4096001 0 65024 0 0 n/a 0 0 http://s1ral-cvmfs.openhtc.io/cvmfs/cernvm-prod.cern.ch DIRECT 1

2020-11-01 08:02:42 (4576): Guest Log: Probing /cvmfs/alice.cern.ch... OK

2020-11-01 08:02:45 (4576): 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-11-01 08:02:45 (4576): Guest Log: 2.5.2.0 4135 0 31004 7863 3 1 1887412 4096001 0 65024 0 0 n/a 4526 1095 http://s1bnl-cvmfs.openhtc.io/cvmfs/alice.cern.ch DIRECT 1

2020-11-01 08:02:45 (4576): Guest Log: 08:02:45 EET +02:00 2020-11-01: cranky: [INFO] Checking runc.

2020-11-01 08:02:46 (4576): Guest Log: 08:02:46 EET +02:00 2020-11-01: cranky: [INFO] Creating the filesystem.

2020-11-01 08:02:48 (4576): Guest Log: 08:02:48 EET +02:00 2020-11-01: cranky: [INFO] Using /cvmfs/cernvm-prod.cern.ch/cvm3

2020-11-01 08:02:49 (4576): Guest Log: 08:02:49 EET +02:00 2020-11-01: cranky: [INFO] Updating config.json.

2020-11-01 08:02:49 (4576): Guest Log: 08:02:49 EET +02:00 2020-11-01: cranky: [INFO] Running Container 'runc'.

2020-11-01 08:02:50 (4576): Guest Log: job: htmld=/shared/html/job

2020-11-01 08:02:53 (4576): Guest Log: job: unpack exitcode=0

2020-11-01 08:02:54 (4576): Guest Log: 08:02:54 EET +02:00 2020-11-01: cranky: [INFO] ===> [runRivet] Sun Nov  1 06:02:53 UTC 2020 [boinc pp z1j 7000 100 - pythia8 8.235 default 100000 56]

2020-11-01 08:53:46 (4576): Stopping VM.
2020-11-01 08:54:39 (4576): Error in stop VM for VM: -182
Command:
VBoxManage -q controlvm "boinc_6ac7933ca813bd05" savestate
Output:
0%...10%...20%...30%...40%...50%...60%...70%...80%...90%...
2020-11-01 08:54:39 (4576): VM did not stop when requested.
2020-11-01 08:54:39 (4576): VM was successfully terminated.
2020-11-01 09:32:15 (1668): Detected: vboxwrapper 26197
2020-11-01 09:32:15 (1668): Detected: BOINC client v7.7
2020-11-01 09:32:22 (1668): Detected: VirtualBox VboxManage Interface (Version: 6.1.14)
2020-11-01 09:32:23 (1668): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2020-11-01 09:32:23 (1668): Starting VM using VBoxManage interface. (boinc_6ac7933ca813bd05, slot#4)
2020-11-01 09:32:32 (1668): Successfully started VM. (PID = '11464')
2020-11-01 09:32:32 (1668): Reporting VM Process ID to BOINC.
2020-11-01 09:32:32 (1668): Guest Log: BIOS: VirtualBox 6.1.14

2020-11-01 09:32:32 (1668): Guest Log: CPUID EDX: 0x178bfbff

2020-11-01 09:32:32 (1668): Guest Log: BIOS: ata0-0: PCHS=16383/16/63 LCHS=1024/255/63

2020-11-01 09:32:32 (1668): VM state change detected. (old = 'PoweredOff', new = 'Running')
2020-11-01 09:32:32 (1668): Detected: Web Application Enabled (http://localhost:50989)
2020-11-01 09:32:32 (1668): Detected: Remote Desktop Enabled (localhost:50990)
2020-11-01 09:32:32 (1668): Status Report: Job Duration: '864000.000000'
2020-11-01 09:32:32 (1668): Status Report: Elapsed Time: '272104.101823'
2020-11-01 09:32:32 (1668): Status Report: CPU Time: '266967.406250'
2020-11-01 09:32:32 (1668): Preference change detected
2020-11-01 09:32:32 (1668): Setting CPU throttle for VM. (100%)
2020-11-01 09:32:33 (1668): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2020-11-01 09:32:34 (1668): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032

2020-11-01 09:32:34 (1668): Guest Log: BIOS: Booting from Hard Disk...

2020-11-01 09:32:42 (1668): Guest Log: BIOS: KBD: unsupported int 16h function 03

2020-11-01 09:32:42 (1668): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 

2020-11-01 09:33:47 (1668): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds

2020-11-01 09:33:47 (1668): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000)

2020-11-01 09:33:54 (1668): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log

2020-11-01 09:33:54 (1668): Guest Log: 00:00:00.000248 main     Log opened 2020-11-01T07:33:53.662363000Z

2020-11-01 09:33:54 (1668): Guest Log: 00:00:00.000393 main     OS Product: Linux

2020-11-01 09:33:54 (1668): Guest Log: 00:00:00.000445 main     OS Release: 4.14.76-13.cernvm.x86_64

2020-11-01 09:33:54 (1668): Guest Log: 00:00:00.000494 main     OS Version: #1 SMP Tue Oct 16 18:26:15 CEST 2018

2020-11-01 09:33:54 (1668): Guest Log: 00:00:00.000541 main     Executable: /usr/sbin/VBoxService

2020-11-01 09:33:54 (1668): Guest Log: 00:00:00.000543 main     Process ID: 3214

2020-11-01 09:33:54 (1668): Guest Log: 00:00:00.000545 main     Package type: LINUX_64BITS_GENERIC

2020-11-01 09:33:54 (1668): Guest Log: 00:00:00.006795 main     5.2.6 r120293 started. Verbose level = 0

2020-11-01 09:34:59 (1668): Guest Log: 09:34:58 EET +02:00 2020-11-01: cranky: [INFO] Detected Theory App

2020-11-01 09:34:59 (1668): Guest Log: 09:34:58 EET +02:00 2020-11-01: cranky: [INFO] Checking CVMFS.

2020-11-01 09:35:03 (1668): Guest Log: Probing /cvmfs/sft.cern.ch... OK

2020-11-01 09:35:05 (1668): 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-11-01 09:35:05 (1668): Guest Log: 2.5.2.0 4083 0 28404 19187 3 1 1887412 4096001 0 65024 0 0 n/a 0 0 http://s1bnl-cvmfs.openhtc.io/cvmfs/sft.cern.ch DIRECT 1

2020-11-01 09:35:10 (1668): Guest Log: Probing /cvmfs/grid.cern.ch... OK

2020-11-01 09:35:12 (1668): 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-11-01 09:35:12 (1668): Guest Log: 2.5.2.0 4130 0 27468 13591 3 2 1887412 4096001 0 65024 0 2 100 0 0 http://s1cern-cvmfs.openhtc.io/cvmfs/grid.cern.ch DIRECT 1

2020-11-01 09:35:17 (1668): Guest Log: Probing /cvmfs/cernvm-prod.cern.ch... OK

2020-11-01 09:35:20 (1668): 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-11-01 09:35:20 (1668): Guest Log: 2.5.2.0 4154 0 28060 259 3 1 1887412 4096001 0 65024 0 0 n/a 0 0 http://s1cern-cvmfs.openhtc.io/cvmfs/cernvm-prod.cern.ch DIRECT 1

2020-11-01 09:35:25 (1668): Guest Log: Probing /cvmfs/alice.cern.ch... OK

2020-11-01 09:35:28 (1668): 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-11-01 09:35:28 (1668): Guest Log: 2.5.2.0 4104 0 30372 7863 3 1 1887412 4096001 0 65024 0 0 n/a 0 0 http://s1cern-cvmfs.openhtc.io/cvmfs/alice.cern.ch DIRECT 1

2020-11-01 09:35:28 (1668): Guest Log: 09:35:28 EET +02:00 2020-11-01: cranky: [INFO] Checking runc.

2020-11-01 09:35:29 (1668): Guest Log: 09:35:28 EET +02:00 2020-11-01: cranky: [INFO] Creating the filesystem.

2020-11-01 09:35:30 (1668): Guest Log: 09:35:29 EET +02:00 2020-11-01: cranky: [INFO] Using /cvmfs/cernvm-prod.cern.ch/cvm3

2020-11-01 09:35:30 (1668): Guest Log: 09:35:30 EET +02:00 2020-11-01: cranky: [INFO] Updating config.json.

2020-11-01 09:35:30 (1668): Guest Log: 09:35:30 EET +02:00 2020-11-01: cranky: [INFO] Running Container 'runc'.

2020-11-01 09:35:35 (1668): Guest Log: job: htmld=/shared/html/job

2020-11-01 09:35:35 (1668): Guest Log: job: unpack exitcode=0

2020-11-01 09:35:37 (1668): Guest Log: 09:35:36 EET +02:00 2020-11-01: cranky: [INFO] ===> [runRivet] Sun Nov  1 07:35:35 UTC 2020 [boinc pp z1j 7000 100 - pythia8 8.235 default 100000 56]

2020-11-01 11:12:53 (1668): Status Report: Job Duration: '864000.000000'
2020-11-01 11:12:53 (1668): Status Report: Elapsed Time: '278104.429444'
2020-11-01 11:12:53 (1668): Status Report: CPU Time: '272365.500000'
2020-11-01 12:53:13 (1668): Status Report: Job Duration: '864000.000000'
2020-11-01 12:53:13 (1668): Status Report: Elapsed Time: '284104.429444'
2020-11-01 12:53:13 (1668): Status Report: CPU Time: '278379.359375'
2020-11-01 14:33:42 (1668): Status Report: Job Duration: '864000.000000'
2020-11-01 14:33:42 (1668): Status Report: Elapsed Time: '290104.429444'
2020-11-01 14:33:42 (1668): Status Report: CPU Time: '284400.109375'
2020-11-01 16:14:06 (1668): Status Report: Job Duration: '864000.000000'
2020-11-01 16:14:06 (1668): Status Report: Elapsed Time: '296104.429444'
2020-11-01 16:14:06 (1668): Status Report: CPU Time: '290409.234375'
2020-11-01 17:53:34 (1668): Stopping VM.
2020-11-01 18:04:43 (9824): Detected: vboxwrapper 26197
2020-11-01 18:04:43 (9824): Detected: BOINC client v7.7
2020-11-01 18:04:44 (9824): Detected: VirtualBox VboxManage Interface (Version: 6.1.14)
2020-11-01 18:04:44 (9824): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2020-11-01 18:04:45 (9824): Starting VM using VBoxManage interface. (boinc_6ac7933ca813bd05, slot#4)
2020-11-01 18:06:01 (9824): Successfully started VM. (PID = '4024')
2020-11-01 18:06:01 (9824): Reporting VM Process ID to BOINC.
2020-11-01 18:06:01 (9824): VM state change detected. (old = 'PoweredOff', new = 'Running')
2020-11-01 18:06:01 (9824): Detected: Web Application Enabled (http://localhost:50989)
2020-11-01 18:06:01 (9824): Detected: Remote Desktop Enabled (localhost:50990)
2020-11-01 18:06:01 (9824): Status Report: Job Duration: '864000.000000'
2020-11-01 18:06:01 (9824): Status Report: Elapsed Time: '301762.992524'
2020-11-01 18:06:01 (9824): Status Report: CPU Time: '296282.500000'
2020-11-01 18:06:01 (9824): Preference change detected
2020-11-01 18:06:01 (9824): Setting CPU throttle for VM. (100%)
2020-11-01 18:06:02 (9824): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2020-11-01 19:46:34 (9824): Status Report: Job Duration: '864000.000000'
2020-11-01 19:46:34 (9824): Status Report: Elapsed Time: '307762.992524'
2020-11-01 19:46:34 (9824): Status Report: CPU Time: '302317.437500'
2020-11-01 21:00:01 (9824): Stopping VM.
2020-11-02 08:00:01 (9876): Detected: vboxwrapper 26197
2020-11-02 08:00:01 (9876): Detected: BOINC client v7.7
2020-11-02 08:00:01 (9876): Detected: VirtualBox VboxManage Interface (Version: 6.1.16)
2020-11-02 08:00:02 (9876): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2020-11-02 08:00:02 (9876): Starting VM using VBoxManage interface. (boinc_6ac7933ca813bd05, slot#4)
2020-11-02 08:01:49 (9876): Successfully started VM. (PID = '6256')
2020-11-02 08:01:49 (9876): Reporting VM Process ID to BOINC.
2020-11-02 08:01:49 (9876): Guest Log: 11:13:56.035453 timesync vgsvcTimeSyncWorker: Radical host time change: 39 700 538 000 000ns (HostNow=1 604 296 896 542 000 000 ns HostLast=1 604 257 196 004 000 000 ns)

2020-11-02 08:01:49 (9876): VM state change detected. (old = 'PoweredOff', new = 'Running')
2020-11-02 08:01:49 (9876): Detected: Web Application Enabled (http://localhost:50989)
2020-11-02 08:01:49 (9876): Detected: Remote Desktop Enabled (localhost:50990)
2020-11-02 08:01:49 (9876): Status Report: Job Duration: '864000.000000'
2020-11-02 08:01:49 (9876): Status Report: Elapsed Time: '312146.035958'
2020-11-02 08:01:49 (9876): Status Report: CPU Time: '306715.812500'
2020-11-02 08:01:49 (9876): Preference change detected
2020-11-02 08:01:49 (9876): Setting CPU throttle for VM. (100%)
2020-11-02 08:02:26 (9876): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2020-11-02 08:02:26 (9876): Guest Log: 11:14:06.170535 timesync vgsvcTimeSyncWorker: Radical guest time change: 39 699 658 938 000ns (GuestNow=1 604 296 909 435 789 000 ns GuestLast=1 604 257 209 776 851 000 ns fSetTimeLastLoop=true )

2020-11-02 09:02:16 (9876): VM state change detected. (old = 'Running', new = 'Paused')
2020-11-02 09:02:18 (9876): VM state change detected. (old = 'Paused', new = 'Running')
2020-11-02 10:13:21 (9876): Status Report: Job Duration: '864000.000000'
2020-11-02 10:13:21 (9876): Status Report: Elapsed Time: '318146.886748'
2020-11-02 10:13:21 (9876): Status Report: CPU Time: '314457.015625'
2020-11-02 12:21:57 (9876): Status Report: Job Duration: '864000.000000'
2020-11-02 12:21:57 (9876): Status Report: Elapsed Time: '324147.052052'
2020-11-02 12:21:57 (9876): Status Report: CPU Time: '321990.890625'
2020-11-02 14:40:52 (9876): Status Report: Job Duration: '864000.000000'
2020-11-02 14:40:54 (9876): Status Report: Elapsed Time: '330147.818787'
2020-11-02 14:40:54 (9876): Status Report: CPU Time: '330137.796875'
2020-11-02 16:52:37 (9876): Status Report: Job Duration: '864000.000000'
2020-11-02 16:52:37 (9876): Status Report: Elapsed Time: '336148.590850'
2020-11-02 16:52:37 (9876): Status Report: CPU Time: '337874.515625'
2020-11-02 16:57:45 (9876): VM state change detected. (old = 'Running', new = 'Paused')
2020-11-02 16:57:54 (9876): VM state change detected. (old = 'Paused', new = 'Running')
2020-11-02 19:13:37 (9876): Status Report: Job Duration: '864000.000000'
2020-11-02 19:13:38 (9876): Status Report: Elapsed Time: '342149.093629'
2020-11-02 19:13:38 (9876): Status Report: CPU Time: '346161.500000'
2020-11-02 21:00:10 (9876): Stopping VM.
2020-11-03 08:00:02 (11428): Detected: vboxwrapper 26197
2020-11-03 08:00:02 (11428): Detected: BOINC client v7.7
2020-11-03 08:00:09 (11428): Detected: VirtualBox VboxManage Interface (Version: 6.1.16)
2020-11-03 08:00:09 (11428): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2020-11-03 08:00:10 (11428): Starting VM using VBoxManage interface. (boinc_6ac7933ca813bd05, slot#4)
2020-11-03 08:00:20 (11428): Successfully started VM. (PID = '7032')
2020-11-03 08:00:20 (11428): Reporting VM Process ID to BOINC.
2020-11-03 08:00:20 (11428): Guest Log: BIOS: VirtualBox 6.1.16

2020-11-03 08:00:20 (11428): Guest Log: CPUID EDX: 0x178bfbff

2020-11-03 08:00:20 (11428): Guest Log: BIOS: ata0-0: PCHS=16383/16/63 LCHS=1024/255/63

2020-11-03 08:00:20 (11428): VM state change detected. (old = 'PoweredOff', new = 'Running')
2020-11-03 08:00:20 (11428): Detected: Web Application Enabled (http://localhost:50989)
2020-11-03 08:00:20 (11428): Detected: Remote Desktop Enabled (localhost:50990)
2020-11-03 08:00:20 (11428): Status Report: Job Duration: '864000.000000'
2020-11-03 08:00:20 (11428): Status Report: Elapsed Time: '347086.250779'
2020-11-03 08:00:20 (11428): Status Report: CPU Time: '352442.171875'
2020-11-03 08:00:20 (11428): Preference change detected
2020-11-03 08:00:20 (11428): Setting CPU throttle for VM. (100%)
2020-11-03 08:00:20 (11428): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2020-11-03 08:00:22 (11428): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032

2020-11-03 08:00:22 (11428): Guest Log: BIOS: Booting from Hard Disk...

2020-11-03 08:00:27 (11428): Guest Log: BIOS: KBD: unsupported int 16h function 03

2020-11-03 08:00:27 (11428): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 

2020-11-03 08:01:25 (11428): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds

2020-11-03 08:01:25 (11428): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000)

2020-11-03 08:01:36 (11428): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log

2020-11-03 08:01:36 (11428): Guest Log: 00:00:00.000246 main     Log opened 2020-11-03T06:01:35.915791000Z

2020-11-03 08:01:36 (11428): Guest Log: 00:00:00.002321 main     OS Product: Linux

2020-11-03 08:01:36 (11428): Guest Log: 00:00:00.002420 main     OS Release: 4.14.76-13.cernvm.x86_64

2020-11-03 08:01:36 (11428): Guest Log: 00:00:00.002474 main     OS Version: #1 SMP Tue Oct 16 18:26:15 CEST 2018

2020-11-03 08:01:36 (11428): Guest Log: 00:00:00.002524 main     Executable: /usr/sbin/VBoxService

2020-11-03 08:01:36 (11428): Guest Log: 00:00:00.002526 main     Process ID: 3189

2020-11-03 08:01:36 (11428): Guest Log: 00:00:00.002528 main     Package type: LINUX_64BITS_GENERIC

2020-11-03 08:01:36 (11428): Guest Log: 00:00:00.005772 main     5.2.6 r120293 started. Verbose level = 0

2020-11-03 08:02:28 (11428): Guest Log: 08:02:27 EET +02:00 2020-11-03: cranky: [INFO] Detected Theory App

2020-11-03 08:02:28 (11428): Guest Log: 08:02:27 EET +02:00 2020-11-03: cranky: [INFO] Checking CVMFS.

2020-11-03 08:02:32 (11428): Guest Log: Probing /cvmfs/sft.cern.ch... OK

2020-11-03 08:02:34 (11428): 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-11-03 08:02:34 (11428): Guest Log: 2.5.2.0 4155 0 27192 19192 3 1 1914177 4096001 0 65024 0 0 n/a 0 0 http://s1ral-cvmfs.openhtc.io/cvmfs/sft.cern.ch DIRECT 1

2020-11-03 08:02:37 (11428): Guest Log: Probing /cvmfs/grid.cern.ch... OK

2020-11-03 08:02:39 (11428): 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-11-03 08:02:39 (11428): Guest Log: 2.5.2.0 4134 0 25484 13607 3 2 1914177 4096001 0 65024 0 2 0 4273 2694 http://s1ral-cvmfs.openhtc.io/cvmfs/grid.cern.ch DIRECT 1

2020-11-03 08:02:44 (11428): Guest Log: Probing /cvmfs/cernvm-prod.cern.ch... OK

2020-11-03 08:02:46 (11428): 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-11-03 08:02:46 (11428): Guest Log: 2.5.2.0 4108 0 27224 259 3 1 1914177 4096001 0 65024 0 0 n/a 0 0 http://s1cern-cvmfs.openhtc.io/cvmfs/cernvm-prod.cern.ch DIRECT 1

2020-11-03 08:02:50 (11428): Guest Log: Probing /cvmfs/alice.cern.ch... OK

2020-11-03 08:02:52 (11428): 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-11-03 08:02:52 (11428): Guest Log: 2.5.2.0 4082 0 28360 7878 3 1 1914177 4096001 0 65024 0 0 n/a 4529 2563 http://s1ral-cvmfs.openhtc.io/cvmfs/alice.cern.ch DIRECT 1

2020-11-03 08:02:52 (11428): Guest Log: 08:02:51 EET +02:00 2020-11-03: cranky: [INFO] Checking runc.

2020-11-03 08:02:54 (11428): Guest Log: 08:02:53 EET +02:00 2020-11-03: cranky: [INFO] Creating the filesystem.

2020-11-03 08:02:54 (11428): Guest Log: 08:02:54 EET +02:00 2020-11-03: cranky: [INFO] Using /cvmfs/cernvm-prod.cern.ch/cvm3

2020-11-03 08:02:55 (11428): Guest Log: 08:02:54 EET +02:00 2020-11-03: cranky: [INFO] Updating config.json.

2020-11-03 08:02:55 (11428): Guest Log: 08:02:55 EET +02:00 2020-11-03: cranky: [INFO] Running Container 'runc'.

2020-11-03 08:03:10 (11428): Guest Log: job: htmld=/shared/html/job

2020-11-03 08:03:11 (11428): Guest Log: job: unpack exitcode=0

2020-11-03 08:03:12 (11428): Guest Log: 08:03:11 EET +02:00 2020-11-03: cranky: [INFO] ===> [runRivet] Tue Nov  3 06:03:10 UTC 2020 [boinc pp z1j 7000 100 - pythia8 8.235 default 100000 56]

2020-11-03 09:40:42 (11428): Status Report: Job Duration: '864000.000000'
2020-11-03 09:40:42 (11428): Status Report: Elapsed Time: '353086.881186'
2020-11-03 09:40:42 (11428): Status Report: CPU Time: '357699.312500'
2020-11-03 11:21:31 (11428): Status Report: Job Duration: '864000.000000'
2020-11-03 11:21:31 (11428): Status Report: Elapsed Time: '359087.130868'
2020-11-03 11:21:31 (11428): Status Report: CPU Time: '363740.140625'
2020-11-03 13:02:13 (11428): Status Report: Job Duration: '864000.000000'
2020-11-03 13:02:13 (11428): Status Report: Elapsed Time: '365087.130868'
2020-11-03 13:02:13 (11428): Status Report: CPU Time: '369774.625000'
2020-11-03 14:42:58 (11428): Status Report: Job Duration: '864000.000000'
2020-11-03 14:42:58 (11428): Status Report: Elapsed Time: '371087.176503'
2020-11-03 14:42:58 (11428): Status Report: CPU Time: '375807.109375'
2020-11-03 16:23:21 (11428): Status Report: Job Duration: '864000.000000'
2020-11-03 16:23:21 (11428): Status Report: Elapsed Time: '377087.231908'
2020-11-03 16:23:21 (11428): Status Report: CPU Time: '381816.500000'
2020-11-03 18:03:44 (11428): Status Report: Job Duration: '864000.000000'
2020-11-03 18:03:44 (11428): Status Report: Elapsed Time: '383087.826232'
2020-11-03 18:03:44 (11428): Status Report: CPU Time: '387831.250000'
2020-11-03 19:44:11 (11428): Status Report: Job Duration: '864000.000000'
2020-11-03 19:44:11 (11428): Status Report: Elapsed Time: '389087.826232'
2020-11-03 19:44:11 (11428): Status Report: CPU Time: '393851.156250'
2020-11-03 21:00:03 (11428): Stopping VM.
2020-11-03 21:00:52 (11428): Error in stop VM for VM: -182
Command:
VBoxManage -q controlvm "boinc_6ac7933ca813bd05" savestate
Output:
0%...10%...20%...30%...40%...50%...60%...70%...80%...90%...
2020-11-03 21:00:52 (11428): VM did not stop when requested.
2020-11-03 21:00:52 (11428): VM was successfully terminated.
2020-11-04 08:00:00 (11624): Detected: vboxwrapper 26197
2020-11-04 08:00:00 (11624): Detected: BOINC client v7.7
2020-11-04 08:00:10 (11624): Detected: VirtualBox VboxManage Interface (Version: 6.1.16)
2020-11-04 08:00:10 (11624): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2020-11-04 08:00:11 (11624): Starting VM using VBoxManage interface. (boinc_6ac7933ca813bd05, slot#4)
2020-11-04 08:01:08 (11624): Successfully started VM. (PID = '8568')
2020-11-04 08:01:08 (11624): Reporting VM Process ID to BOINC.
2020-11-04 08:01:08 (11624): VM state change detected. (old = 'PoweredOff', new = 'Running')
2020-11-04 08:01:08 (11624): Detected: Web Application Enabled (http://localhost:50989)
2020-11-04 08:01:08 (11624): Detected: Remote Desktop Enabled (localhost:50990)
2020-11-04 08:01:08 (11624): Stopping VM.
2020-11-04 08:57:33 (6136): Detected: vboxwrapper 26197
2020-11-04 08:57:33 (6136): Detected: BOINC client v7.7
2020-11-04 08:57:35 (6136): Detected: VirtualBox VboxManage Interface (Version: 6.1.16)
2020-11-04 08:57:35 (6136): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2020-11-04 08:57:36 (6136): Starting VM using VBoxManage interface. (boinc_6ac7933ca813bd05, slot#4)
2020-11-04 08:58:10 (6136): Successfully started VM. (PID = '9912')
2020-11-04 08:58:10 (6136): Reporting VM Process ID to BOINC.
2020-11-04 08:58:10 (6136): VM state change detected. (old = 'PoweredOff', new = 'Running')
2020-11-04 08:58:10 (6136): Detected: Web Application Enabled (http://localhost:50989)
2020-11-04 08:58:10 (6136): Detected: Remote Desktop Enabled (localhost:50990)
2020-11-04 08:58:10 (6136): Status Report: Job Duration: '864000.000000'
2020-11-04 08:58:10 (6136): Status Report: Elapsed Time: '393617.166715'
2020-11-04 08:58:10 (6136): Status Report: CPU Time: '398397.265625'
2020-11-04 08:58:10 (6136): Preference change detected
2020-11-04 08:58:10 (6136): Setting CPU throttle for VM. (100%)
2020-11-04 08:58:10 (6136): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2020-11-04 08:58:14 (6136): Guest Log: 12:58:34.400442 timesync vgsvcTimeSyncWorker: Radical host time change: 43 093 771 000 000ns (HostNow=1 604 473 094 084 000 000 ns HostLast=1 604 430 000 313 000 000 ns)

2020-11-04 08:58:24 (6136): Guest Log: 12:58:44.425034 timesync vgsvcTimeSyncWorker: Radical guest time change: 43 093 913 426 000ns (GuestNow=1 604 473 104 168 963 000 ns GuestLast=1 604 430 010 255 537 000 ns fSetTimeLastLoop=true )

2020-11-04 10:38:38 (6136): Status Report: Job Duration: '864000.000000'
2020-11-04 10:38:38 (6136): Status Report: Elapsed Time: '399617.610643'
2020-11-04 10:38:38 (6136): Status Report: CPU Time: '404424.687500'
2020-11-04 12:19:12 (6136): Status Report: Job Duration: '864000.000000'
2020-11-04 12:19:12 (6136): Status Report: Elapsed Time: '405617.610643'
2020-11-04 12:19:12 (6136): Status Report: CPU Time: '410447.140625'
2020-11-04 13:59:32 (6136): Status Report: Job Duration: '864000.000000'
2020-11-04 13:59:32 (6136): Status Report: Elapsed Time: '411617.610643'
2020-11-04 13:59:32 (6136): Status Report: CPU Time: '416461.671875'
2020-11-04 15:40:05 (6136): Status Report: Job Duration: '864000.000000'
2020-11-04 15:40:05 (6136): Status Report: Elapsed Time: '417617.610643'
2020-11-04 15:40:05 (6136): Status Report: CPU Time: '422485.671875'
2020-11-04 17:20:24 (6136): Status Report: Job Duration: '864000.000000'
2020-11-04 17:20:24 (6136): Status Report: Elapsed Time: '423617.610643'
2020-11-04 17:20:24 (6136): Status Report: CPU Time: '428499.031250'
2020-11-04 19:00:44 (6136): Status Report: Job Duration: '864000.000000'
2020-11-04 19:00:44 (6136): Status Report: Elapsed Time: '429617.610643'
2020-11-04 19:00:44 (6136): Status Report: CPU Time: '434508.453125'
2020-11-04 20:41:10 (6136): Status Report: Job Duration: '864000.000000'
2020-11-04 20:41:10 (6136): Status Report: Elapsed Time: '435617.610643'
2020-11-04 20:41:10 (6136): Status Report: CPU Time: '440526.281250'
2020-11-04 21:00:02 (6136): Stopping VM.
2020-11-04 21:00:49 (6136): Error in stop VM for VM: -182
Command:
VBoxManage -q controlvm "boinc_6ac7933ca813bd05" savestate
Output:
0%...10%...20%...30%...40%...50%...60%...70%...80%...90%...
2020-11-04 21:00:49 (6136): VM did not stop when requested.
2020-11-04 21:00:49 (6136): VM was successfully terminated.
2020-11-05 08:00:00 (6488): Detected: vboxwrapper 26197
2020-11-05 08:00:00 (6488): Detected: BOINC client v7.7
2020-11-05 08:00:03 (6488): Detected: VirtualBox VboxManage Interface (Version: 6.1.16)
2020-11-05 08:00:03 (6488): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2020-11-05 08:00:04 (6488): Starting VM using VBoxManage interface. (boinc_6ac7933ca813bd05, slot#4)
2020-11-05 08:01:19 (6488): Successfully started VM. (PID = '4912')
2020-11-05 08:01:19 (6488): Reporting VM Process ID to BOINC.
2020-11-05 08:01:19 (6488): VM state change detected. (old = 'PoweredOff', new = 'Running')
2020-11-05 08:01:19 (6488): Detected: Web Application Enabled (http://localhost:50989)
2020-11-05 08:01:19 (6488): Detected: Remote Desktop Enabled (localhost:50990)
2020-11-05 08:01:19 (6488): Status Report: Job Duration: '864000.000000'
2020-11-05 08:01:19 (6488): Status Report: Elapsed Time: '436747.610643'
2020-11-05 08:01:19 (6488): Status Report: CPU Time: '441657.656250'
2020-11-05 08:01:19 (6488): Preference change detected
2020-11-05 08:01:19 (6488): Setting CPU throttle for VM. (100%)
2020-11-05 08:01:20 (6488): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2020-11-05 08:01:25 (6488): Guest Log: 25:00:31.238373 timesync vgsvcTimeSyncWorker: Radical host time change: 39 684 619 000 000ns (HostNow=1 604 556 085 685 000 000 ns HostLast=1 604 516 401 066 000 000 ns)

2020-11-05 08:01:35 (6488): Guest Log: 25:00:41.240433 timesync vgsvcTimeSyncWorker: Radical guest time change: 39 684 704 827 000ns (GuestNow=1 604 556 095 687 083 000 ns GuestLast=1 604 516 410 982 256 000 ns fSetTimeLastLoop=true )

2020-11-05 09:41:45 (6488): Status Report: Job Duration: '864000.000000'
2020-11-05 09:41:45 (6488): Status Report: Elapsed Time: '442747.610643'
2020-11-05 09:41:46 (6488): Status Report: CPU Time: '447683.140625'
2020-11-05 11:22:12 (6488): Status Report: Job Duration: '864000.000000'
2020-11-05 11:22:12 (6488): Status Report: Elapsed Time: '448747.843738'
2020-11-05 11:22:12 (6488): Status Report: CPU Time: '453694.468750'
2020-11-05 11:58:09 (6488): Guest Log: job: run exitcode=0

2020-11-05 11:58:09 (6488): Guest Log: job: diskusage=2688

2020-11-05 11:58:09 (6488): Guest Log: job: logsize=76 k

2020-11-05 11:58:09 (6488): Guest Log: job: times=

2020-11-05 11:58:09 (6488): Guest Log: 0m0.037s 0m0.057s

2020-11-05 11:58:09 (6488): Guest Log: 1659m48.507s 21m23.912s

2020-11-05 11:58:09 (6488): Guest Log: job: cpuusage=100873

2020-11-05 11:58:10 (6488): Guest Log: 11:58:10 EET +02:00 2020-11-05: cranky: [INFO] Container 'runc' finished with status code 0.

2020-11-05 11:58:10 (6488): Guest Log: 11:58:10 EET +02:00 2020-11-05: cranky: [INFO] Preparing output.

2020-11-05 11:58:16 (6488): Guest Log: [INFO] Job Finished

2020-11-05 11:58:16 (6488): Guest Log: [INFO] Shutting Down.

2020-11-05 11:58:16 (6488): VM Completion File Detected.
2020-11-05 11:58:16 (6488): VM Completion Message: Job Finished
.
2020-11-05 11:58:16 (6488): Powering off VM.
2020-11-05 12:03:38 (6488): VM did not power off when requested.
2020-11-05 12:03:38 (6488): VM was successfully terminated.
2020-11-05 12:03:38 (6488): Deregistering VM. (boinc_6ac7933ca813bd05, slot#4)
2020-11-05 12:03:39 (6488): Removing network bandwidth throttle group from VM.
2020-11-05 12:03:39 (6488): Removing VM from VirtualBox.
12:03:45 (6488): called boinc_finish(0)

</stderr_txt>
]]>
LHC@home

Stderr output
