Stderr output

<core_client_version>7.16.11</core_client_version>
<![CDATA[
<stderr_txt>
2021-06-08 18:15:30 (6020): Detected: vboxwrapper 26197
2021-06-08 18:15:30 (6020): Detected: BOINC client v7.7
2021-06-08 18:15:31 (6020): Detected: VirtualBox VboxManage Interface (Version: 6.1.4)
2021-06-08 18:15:32 (6020): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2021-06-08 18:15:32 (6020): Successfully copied 'init_data.xml' to the shared directory.
2021-06-08 18:15:32 (6020): Successfully copied 'input' to the shared directory.
2021-06-08 18:15:33 (6020): Create VM. (boinc_7b18156f87c4eeb3, slot#1)
2021-06-08 18:15:34 (6020): Setting Memory Size for VM. (630MB)
2021-06-08 18:15:34 (6020): Setting CPU Count for VM. (1)
2021-06-08 18:15:35 (6020): Setting Chipset Options for VM.
2021-06-08 18:15:35 (6020): Setting Boot Options for VM.
2021-06-08 18:15:36 (6020): Setting Network Configuration for NAT.
2021-06-08 18:15:36 (6020): Enabling VM Network Access.
2021-06-08 18:15:37 (6020): Disabling USB Support for VM.
2021-06-08 18:15:37 (6020): Disabling COM Port Support for VM.
2021-06-08 18:15:37 (6020): Disabling LPT Port Support for VM.
2021-06-08 18:15:38 (6020): Disabling Audio Support for VM.
2021-06-08 18:15:38 (6020): Disabling Clipboard Support for VM.
2021-06-08 18:15:38 (6020): Disabling Drag and Drop Support for VM.
2021-06-08 18:15:38 (6020): Adding storage controller(s) to VM.
2021-06-08 18:15:39 (6020): Adding virtual disk drive to VM. (vm_image.vdi)
2021-06-08 18:15:39 (6020): Adding VirtualBox Guest Additions to VM.
2021-06-08 18:15:40 (6020): Adding network bandwidth throttle group to VM. (Defaulting to 1024GB)
2021-06-08 18:15:40 (6020): forwarding host port 60133 to guest port 80
2021-06-08 18:15:40 (6020): Enabling remote desktop for VM.
2021-06-08 18:15:41 (6020): Enabling shared directory for VM.
2021-06-08 18:15:42 (6020): Starting VM using VBoxManage interface. (boinc_7b18156f87c4eeb3, slot#1)
2021-06-08 18:16:03 (6020): Successfully started VM. (PID = '13308')
2021-06-08 18:16:03 (6020): Reporting VM Process ID to BOINC.
2021-06-08 18:16:03 (6020): Guest Log: BIOS: VirtualBox 6.1.4

2021-06-08 18:16:03 (6020): Guest Log: CPUID EDX: 0x178bfbff

2021-06-08 18:16:03 (6020): Guest Log: BIOS: ata0-0: PCHS=16383/16/63 LCHS=1024/255/63

2021-06-08 18:16:03 (6020): VM state change detected. (old = 'PoweredOff', new = 'Running')
2021-06-08 18:16:03 (6020): Detected: Web Application Enabled (http://localhost:60133)
2021-06-08 18:16:03 (6020): Detected: Remote Desktop Enabled (localhost:60134)
2021-06-08 18:16:03 (6020): Preference change detected
2021-06-08 18:16:03 (6020): Setting CPU throttle for VM. (75%)
2021-06-08 18:16:03 (6020): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2021-06-08 18:16:05 (6020): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032

2021-06-08 18:16:05 (6020): Guest Log: BIOS: Booting from Hard Disk...

2021-06-08 18:16:09 (6020): Guest Log: BIOS: KBD: unsupported int 16h function 03

2021-06-08 18:16:09 (6020): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 

2021-06-08 18:17:10 (6020): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds

2021-06-08 18:17:10 (6020): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000)

2021-06-08 18:17:22 (6020): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log

2021-06-08 18:17:22 (6020): Guest Log: 00:00:00.000212 main     Log opened 2021-06-08T22:17:23.476901000Z

2021-06-08 18:17:22 (6020): Guest Log: 00:00:00.000464 main     OS Product: Linux

2021-06-08 18:17:22 (6020): Guest Log: 00:00:00.000628 main     OS Release: 4.14.76-13.cernvm.x86_64

2021-06-08 18:17:22 (6020): Guest Log: 00:00:00.000737 main     OS Version: #1 SMP Tue Oct 16 18:26:15 CEST 2018

2021-06-08 18:17:22 (6020): Guest Log: 00:00:00.002984 main     Executable: /usr/sbin/VBoxService

2021-06-08 18:17:22 (6020): Guest Log: 00:00:00.002988 main     Process ID: 3227

2021-06-08 18:17:22 (6020): Guest Log: 00:00:00.002990 main     Package type: LINUX_64BITS_GENERIC

2021-06-08 18:17:22 (6020): Guest Log: 00:00:00.008092 main     5.2.6 r120293 started. Verbose level = 0

2021-06-08 18:18:44 (6020): Guest Log: 00:18:44 CEST +02:00 2021-06-09: cranky: [INFO] Detected Theory App

2021-06-08 18:18:44 (6020): Guest Log: 00:18:44 CEST +02:00 2021-06-09: cranky: [INFO] Checking CVMFS.

2021-06-08 18:18:49 (6020): Guest Log: Probing /cvmfs/sft.cern.ch... OK

2021-06-08 18:18:52 (6020): 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

2021-06-08 18:18:52 (6020): Guest Log: 2.5.2.0 4130 0 25932 20330 3 1 265805 4096000 0 65024 0 0 n/a 5 3 http://s1bnl-cvmfs.openhtc.io/cvmfs/sft.cern.ch DIRECT 1

2021-06-08 18:18:58 (6020): Guest Log: Probing /cvmfs/grid.cern.ch... OK

2021-06-08 18:19:00 (6020): 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

2021-06-08 18:19:00 (6020): Guest Log: 2.5.2.0 4107 0 27424 15383 3 2 265805 4096000 0 65024 0 2 -100 6413 1095 http://s1bnl-cvmfs.openhtc.io/cvmfs/grid.cern.ch DIRECT 1

2021-06-08 18:19:06 (6020): Guest Log: Probing /cvmfs/cernvm-prod.cern.ch... OK

2021-06-08 18:19:10 (6020): 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

2021-06-08 18:19:10 (6020): Guest Log: 2.5.2.0 4150 0 27196 267 3 1 265805 4096000 0 65024 0 0 n/a 4 2 http://s1bnl-cvmfs.openhtc.io/cvmfs/cernvm-prod.cern.ch DIRECT 1

2021-06-08 18:19:15 (6020): Guest Log: Probing /cvmfs/alice.cern.ch... OK

2021-06-08 18:19:18 (6020): 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

2021-06-08 18:19:18 (6020): Guest Log: 2.5.2.0 4177 1 31296 9480 3 1 265805 4096000 0 65024 0 0 n/a 5037 1069 http://s1bnl-cvmfs.openhtc.io/cvmfs/alice.cern.ch DIRECT 1

2021-06-08 18:19:18 (6020): Guest Log: 00:19:18 CEST +02:00 2021-06-09: cranky: [INFO] Checking runc.

2021-06-08 18:19:18 (6020): Guest Log: 00:19:18 CEST +02:00 2021-06-09: cranky: [INFO] Creating the filesystem.

2021-06-08 18:19:18 (6020): Guest Log: 00:19:19 CEST +02:00 2021-06-09: cranky: [INFO] Using /cvmfs/cernvm-prod.cern.ch/cvm3

2021-06-08 18:19:19 (6020): Guest Log: 00:19:19 CEST +02:00 2021-06-09: cranky: [INFO] Updating config.json.

2021-06-08 18:19:19 (6020): Guest Log: 00:19:20 CEST +02:00 2021-06-09: cranky: [INFO] Running Container 'runc'.

2021-06-08 18:19:33 (6020): Guest Log: job: htmld=/shared/html/job

2021-06-08 18:19:33 (6020): Guest Log: job: unpack exitcode=0

2021-06-08 18:19:34 (6020): Guest Log: 00:19:34 CEST +02:00 2021-06-09: cranky: [INFO] ===> [runRivet] Tue Jun  8 22:19:34 UTC 2021 [boinc pp jets 7000 800 - pythia8 8.212 tune-AU2lox 100000 188]

2021-06-08 19:16:03 (6020): VM state change detected. (old = 'Running', new = 'Paused')
2021-06-08 21:37:47 (6020): VM state change detected. (old = 'Paused', new = 'Running')
2021-06-08 21:37:50 (6020): Guest Log: 00:58:43.472233 timesync vgsvcTimeSyncWorker: Radical host time change: 8 513 169 000 000ns (HostNow=1 623 202 669 982 000 000 ns HostLast=1 623 194 156 813 000 000 ns)

2021-06-08 21:38:00 (6020): Guest Log: 00:58:53.502453 timesync vgsvcTimeSyncWorker: Radical guest time change: 8 513 208 512 000ns (GuestNow=1 623 202 680 033 968 000 ns GuestLast=1 623 194 166 825 456 000 ns fSetTimeLastLoop=true )

2021-06-08 22:17:55 (6020): Status Report: Job Duration: '864000.000000'
2021-06-08 22:17:55 (6020): Status Report: Elapsed Time: '6000.160864'
2021-06-08 22:17:55 (6020): Status Report: CPU Time: '4622.546875'
2021-06-08 23:42:42 (6020): VM state change detected. (old = 'Running', new = 'Paused')
2021-06-09 02:26:01 (6020): VM state change detected. (old = 'Paused', new = 'Running')
2021-06-09 02:26:05 (6020): Guest Log: 03:03:40.509392 timesync vgsvcTimeSyncWorker: Radical host time change: 9 808 676 000 000ns (HostNow=1 623 219 965 709 000 000 ns HostLast=1 623 210 157 033 000 000 ns)

2021-06-09 02:26:15 (6020): Guest Log: 03:03:50.513430 timesync vgsvcTimeSyncWorker: Radical guest time change: 9 808 672 215 000ns (GuestNow=1 623 219 975 713 064 000 ns GuestLast=1 623 210 167 040 849 000 ns fSetTimeLastLoop=true )

2021-06-09 02:41:33 (6020): Status Report: Job Duration: '864000.000000'
2021-06-09 02:41:33 (6020): Status Report: Elapsed Time: '12001.035173'
2021-06-09 02:41:33 (6020): Status Report: CPU Time: '9144.375000'
2021-06-09 04:21:39 (6020): Status Report: Job Duration: '864000.000000'
2021-06-09 04:21:39 (6020): Status Report: Elapsed Time: '18001.035173'
2021-06-09 04:21:39 (6020): Status Report: CPU Time: '13659.562500'
2021-06-09 06:01:44 (6020): Status Report: Job Duration: '864000.000000'
2021-06-09 06:01:44 (6020): Status Report: Elapsed Time: '24001.035173'
2021-06-09 06:01:44 (6020): Status Report: CPU Time: '18176.484375'
2021-06-09 07:41:50 (6020): Status Report: Job Duration: '864000.000000'
2021-06-09 07:41:50 (6020): Status Report: Elapsed Time: '30001.035173'
2021-06-09 07:41:50 (6020): Status Report: CPU Time: '22692.500000'
2021-06-09 08:21:11 (6020): VM state change detected. (old = 'Running', new = 'Paused')
2021-06-09 10:31:51 (6020): VM state change detected. (old = 'Paused', new = 'Running')
2021-06-09 10:31:58 (6020): Guest Log: 08:58:52.816779 timesync vgsvcTimeSyncWorker: Radical host time change: 7 850 802 000 000ns (HostNow=1 623 249 118 814 000 000 ns HostLast=1 623 241 268 012 000 000 ns)

2021-06-09 10:32:08 (6020): Guest Log: 08:59:02.818517 timesync vgsvcTimeSyncWorker: Radical guest time change: 7 850 799 381 000ns (GuestNow=1 623 249 128 815 759 000 ns GuestLast=1 623 241 278 016 378 000 ns fSetTimeLastLoop=true )

2021-06-09 11:06:55 (6020): Stopping VM.
2021-06-09 11:06:55 (6020): Error in stop VM for VM: -108
Command:
VBoxManage -q controlvm "boinc_7b18156f87c4eeb3" savestate
Output:

2021-06-09 11:06:55 (6020): VM did not stop when requested.
2021-06-09 11:06:55 (6020): VM was successfully terminated.
2021-06-09 11:18:37 (9448): Detected: vboxwrapper 26197
2021-06-09 11:18:37 (9448): Detected: BOINC client v7.7
2021-06-09 11:18:38 (9448): Detected: VirtualBox VboxManage Interface (Version: 6.1.4)
2021-06-09 11:18:39 (9448): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2021-06-09 11:18:41 (9448): Starting VM using VBoxManage interface. (boinc_7b18156f87c4eeb3, slot#1)
2021-06-09 11:19:19 (9448): Successfully started VM. (PID = '16308')
2021-06-09 11:19:19 (9448): Reporting VM Process ID to BOINC.
2021-06-09 11:19:20 (9448): Guest Log: BIOS: VirtualBox 6.1.4

2021-06-09 11:19:20 (9448): Guest Log: CPUID EDX: 0x178bfbff

2021-06-09 11:19:20 (9448): Guest Log: BIOS: ata0-0: PCHS=16383/16/63 LCHS=1024/255/63

2021-06-09 11:19:20 (9448): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032

2021-06-09 11:19:20 (9448): Guest Log: BIOS: Booting from Hard Disk...

2021-06-09 11:19:20 (9448): VM state change detected. (old = 'PoweredOff', new = 'Running')
2021-06-09 11:19:20 (9448): Detected: Web Application Enabled (http://localhost:60133)
2021-06-09 11:19:20 (9448): Detected: Remote Desktop Enabled (localhost:60134)
2021-06-09 11:19:20 (9448): Status Report: Job Duration: '864000.000000'
2021-06-09 11:19:20 (9448): Status Report: Elapsed Time: '34456.711353'
2021-06-09 11:19:20 (9448): Status Report: CPU Time: '26053.890625'
2021-06-09 11:19:20 (9448): Preference change detected
2021-06-09 11:19:20 (9448): Setting CPU throttle for VM. (75%)
2021-06-09 11:19:22 (9448): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2021-06-09 11:19:22 (9448): Guest Log: BIOS: KBD: unsupported int 16h function 03

2021-06-09 11:19:22 (9448): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 

2021-06-09 11:20:47 (9448): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds

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

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

2021-06-09 11:21:06 (9448): Guest Log: 00:00:00.040524 main     Log opened 2021-06-09T15:21:06.985659000Z

2021-06-09 11:21:06 (9448): Guest Log: 00:00:00.057297 main     OS Product: Linux

2021-06-09 11:21:06 (9448): Guest Log: 00:00:00.057705 main     OS Release: 4.14.76-13.cernvm.x86_64

2021-06-09 11:21:06 (9448): Guest Log: 00:00:00.058081 main     OS Version: #1 SMP Tue Oct 16 18:26:15 CEST 2018

2021-06-09 11:21:06 (9448): Guest Log: 00:00:00.058292 main     Executable: /usr/sbin/VBoxService

2021-06-09 11:21:06 (9448): Guest Log: 00:00:00.058298 main     Process ID: 3239

2021-06-09 11:21:06 (9448): Guest Log: 00:00:00.058302 main     Package type: LINUX_64BITS_GENERIC

2021-06-09 11:21:06 (9448): Guest Log: 00:00:00.091738 main     5.2.6 r120293 started. Verbose level = 0

2021-06-09 11:22:59 (9448): Guest Log: 17:22:59 CEST +02:00 2021-06-09: cranky: [INFO] Detected Theory App

2021-06-09 11:22:59 (9448): Guest Log: 17:22:59 CEST +02:00 2021-06-09: cranky: [INFO] Checking CVMFS.

2021-06-09 11:23:06 (9448): Guest Log: Probing /cvmfs/sft.cern.ch... OK

2021-06-09 11:23:10 (9448): 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

2021-06-09 11:23:10 (9448): Guest Log: 2.5.2.0 4187 0 28256 20335 3 1 1363232 4096001 0 65024 0 0 n/a 0 0 http://s1ihep-cvmfs.openhtc.io/cvmfs/sft.cern.ch DIRECT 1

2021-06-09 11:23:18 (9448): Guest Log: Probing /cvmfs/grid.cern.ch... OK

2021-06-09 11:23:23 (9448): 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

2021-06-09 11:23:24 (9448): Guest Log: 2.5.2.0 4141 1 27716 15389 3 2 1363232 4096001 0 65024 0 2 0 4275 705 http://s1fnal-cvmfs.openhtc.io/cvmfs/grid.cern.ch DIRECT 1

2021-06-09 11:23:32 (9448): Guest Log: Probing /cvmfs/cernvm-prod.cern.ch... OK

2021-06-09 11:23:36 (9448): 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

2021-06-09 11:23:36 (9448): Guest Log: 2.5.2.0 4162 1 27184 267 3 1 1363232 4096001 0 65024 0 0 n/a 0 0 http://s1ral-cvmfs.openhtc.io/cvmfs/cernvm-prod.cern.ch DIRECT 1

2021-06-09 11:23:44 (9448): Guest Log: Probing /cvmfs/alice.cern.ch... OK

2021-06-09 11:23:49 (9448): 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

2021-06-09 11:23:49 (9448): Guest Log: 2.5.2.0 4209 1 31280 9483 3 1 1363232 4096001 0 65024 0 0 n/a 5037 981 http://s1ihep-cvmfs.openhtc.io/cvmfs/alice.cern.ch DIRECT 1

2021-06-09 11:23:49 (9448): Guest Log: 17:23:49 CEST +02:00 2021-06-09: cranky: [INFO] Checking runc.

2021-06-09 11:23:50 (9448): Guest Log: 17:23:50 CEST +02:00 2021-06-09: cranky: [INFO] Creating the filesystem.

2021-06-09 11:23:50 (9448): Guest Log: 17:23:50 CEST +02:00 2021-06-09: cranky: [INFO] Using /cvmfs/cernvm-prod.cern.ch/cvm3

2021-06-09 11:23:51 (9448): Guest Log: 17:23:51 CEST +02:00 2021-06-09: cranky: [INFO] Updating config.json.

2021-06-09 11:23:51 (9448): Guest Log: 17:23:52 CEST +02:00 2021-06-09: cranky: [INFO] Running Container 'runc'.

2021-06-09 11:23:53 (9448): Guest Log: job: htmld=/shared/html/job

2021-06-09 11:23:54 (9448): Guest Log: job: unpack exitcode=0

2021-06-09 11:23:56 (9448): Guest Log: 17:23:56 CEST +02:00 2021-06-09: cranky: [INFO] ===> [runRivet] Wed Jun  9 15:23:54 UTC 2021 [boinc pp jets 7000 800 - pythia8 8.212 tune-AU2lox 100000 188]

2021-06-09 13:01:09 (9448): Status Report: Job Duration: '864000.000000'
2021-06-09 13:01:09 (9448): Status Report: Elapsed Time: '40456.943176'
2021-06-09 13:01:09 (9448): Status Report: CPU Time: '30602.968750'
2021-06-09 13:11:59 (9448): VM state change detected. (old = 'Running', new = 'Paused')
2021-06-09 18:59:56 (9448): VM state change detected. (old = 'Paused', new = 'Running')
2021-06-09 19:00:00 (9448): Guest Log: 01:50:57.102229 timesync vgsvcTimeSyncWorker: Radical host time change: 20 886 588 000 000ns (HostNow=1 623 279 600 525 000 000 ns HostLast=1 623 258 713 937 000 000 ns)

2021-06-09 19:00:10 (9448): Guest Log: 01:51:07.110748 timesync vgsvcTimeSyncWorker: Radical guest time change: 20 886 639 584 000ns (GuestNow=1 623 279 610 610 154 000 ns GuestLast=1 623 258 723 970 570 000 ns fSetTimeLastLoop=true )

2021-06-09 20:29:15 (9448): Status Report: Job Duration: '864000.000000'
2021-06-09 20:29:15 (9448): Status Report: Elapsed Time: '46456.943176'
2021-06-09 20:29:15 (9448): Status Report: CPU Time: '35131.218750'
2021-06-09 22:09:20 (9448): Status Report: Job Duration: '864000.000000'
2021-06-09 22:09:20 (9448): Status Report: Elapsed Time: '52456.943176'
2021-06-09 22:09:20 (9448): Status Report: CPU Time: '39649.703125'
2021-06-09 23:49:41 (9448): Status Report: Job Duration: '864000.000000'
2021-06-09 23:49:41 (9448): Status Report: Elapsed Time: '58457.403166'
2021-06-09 23:49:41 (9448): Status Report: CPU Time: '44165.562500'
2021-06-10 01:29:46 (9448): Status Report: Job Duration: '864000.000000'
2021-06-10 01:29:46 (9448): Status Report: Elapsed Time: '64457.403166'
2021-06-10 01:29:46 (9448): Status Report: CPU Time: '48677.609375'
2021-06-10 03:09:53 (9448): Status Report: Job Duration: '864000.000000'
2021-06-10 03:09:53 (9448): Status Report: Elapsed Time: '70458.403166'
2021-06-10 03:09:53 (9448): Status Report: CPU Time: '53197.234375'
2021-06-10 03:58:48 (9448): VM state change detected. (old = 'Running', new = 'Paused')
2021-06-10 05:43:58 (9448): VM state change detected. (old = 'Paused', new = 'Running')
2021-06-10 05:44:03 (9448): Guest Log: 10:49:49.016828 timesync vgsvcTimeSyncWorker: Radical host time change: 6 319 849 000 000ns (HostNow=1 623 318 242 399 000 000 ns HostLast=1 623 311 922 550 000 000 ns)

2021-06-10 05:44:13 (9448): Guest Log: 10:49:59.018972 timesync vgsvcTimeSyncWorker: Radical guest time change: 6 319 885 231 000ns (GuestNow=1 623 318 252 401 280 000 ns GuestLast=1 623 311 932 516 049 000 ns fSetTimeLastLoop=true )

2021-06-10 06:35:11 (9448): Status Report: Job Duration: '864000.000000'
2021-06-10 06:35:11 (9448): Status Report: Elapsed Time: '76458.403166'
2021-06-10 06:35:11 (9448): Status Report: CPU Time: '57709.765625'
2021-06-10 06:44:36 (9448): VM state change detected. (old = 'Running', new = 'Paused')
2021-06-10 08:27:19 (9448): Guest Log: 11:50:23.122873 timesync vgsvcTimeSyncWorker: Radical host time change: 6 172 642 000 000ns (HostNow=1 623 328 039 137 000 000 ns HostLast=1 623 321 866 495 000 000 ns)

2021-06-10 08:27:19 (9448): VM state change detected. (old = 'Paused', new = 'Running')
2021-06-10 08:27:29 (9448): Guest Log: 11:50:33.124376 timesync vgsvcTimeSyncWorker: Radical guest time change: 6 172 633 367 000ns (GuestNow=1 623 328 049 138 540 000 ns GuestLast=1 623 321 876 505 173 000 ns fSetTimeLastLoop=true )

2021-06-10 09:58:00 (9448): Status Report: Job Duration: '864000.000000'
2021-06-10 09:58:00 (9448): Status Report: Elapsed Time: '82458.403166'
2021-06-10 09:58:00 (9448): Status Report: CPU Time: '62226.312500'
2021-06-10 11:06:14 (9448): Stopping VM.
2021-06-10 11:06:15 (9448): Error in stop VM for VM: -108
Command:
VBoxManage -q controlvm "boinc_7b18156f87c4eeb3" savestate
Output:

2021-06-10 11:06:15 (9448): VM did not stop when requested.
2021-06-10 11:06:15 (9448): VM was successfully terminated.
2021-06-10 11:22:36 (15576): Detected: vboxwrapper 26197
2021-06-10 11:22:36 (15576): Detected: BOINC client v7.7
2021-06-10 11:22:37 (15576): Detected: VirtualBox VboxManage Interface (Version: 6.1.4)
2021-06-10 11:22:37 (15576): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2021-06-10 11:22:38 (15576): Starting VM using VBoxManage interface. (boinc_7b18156f87c4eeb3, slot#1)
2021-06-10 11:22:49 (15576): Successfully started VM. (PID = '15612')
2021-06-10 11:22:49 (15576): Reporting VM Process ID to BOINC.
2021-06-10 11:22:49 (15576): Guest Log: BIOS: VirtualBox 6.1.4

2021-06-10 11:22:49 (15576): Guest Log: CPUID EDX: 0x178bfbff

2021-06-10 11:22:49 (15576): Guest Log: BIOS: ata0-0: PCHS=16383/16/63 LCHS=1024/255/63

2021-06-10 11:22:49 (15576): VM state change detected. (old = 'PoweredOff', new = 'Running')
2021-06-10 11:22:49 (15576): Detected: Web Application Enabled (http://localhost:60133)
2021-06-10 11:22:49 (15576): Detected: Remote Desktop Enabled (localhost:60134)
2021-06-10 11:22:49 (15576): Status Report: Job Duration: '864000.000000'
2021-06-10 11:22:49 (15576): Status Report: Elapsed Time: '86532.281967'
2021-06-10 11:22:49 (15576): Status Report: CPU Time: '65294.937500'
2021-06-10 11:22:49 (15576): Preference change detected
2021-06-10 11:22:49 (15576): Setting CPU throttle for VM. (75%)
2021-06-10 11:22:50 (15576): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2021-06-10 11:22:51 (15576): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032

2021-06-10 11:22:51 (15576): Guest Log: BIOS: Booting from Hard Disk...

2021-06-10 11:22:53 (15576): Guest Log: BIOS: KBD: unsupported int 16h function 03

2021-06-10 11:22:53 (15576): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 

2021-06-10 11:23:30 (15576): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds

2021-06-10 11:23:30 (15576): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000)

2021-06-10 11:23:37 (15576): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log

2021-06-10 11:23:37 (15576): Guest Log: 00:00:00.000464 main     Log opened 2021-06-10T15:23:38.816661000Z

2021-06-10 11:23:37 (15576): Guest Log: 00:00:00.001371 main     OS Product: Linux

2021-06-10 11:23:37 (15576): Guest Log: 00:00:00.001471 main     OS Release: 4.14.76-13.cernvm.x86_64

2021-06-10 11:23:37 (15576): Guest Log: 00:00:00.001573 main     OS Version: #1 SMP Tue Oct 16 18:26:15 CEST 2018

2021-06-10 11:23:37 (15576): Guest Log: 00:00:00.001643 main     Executable: /usr/sbin/VBoxService

2021-06-10 11:23:37 (15576): Guest Log: 00:00:00.001645 main     Process ID: 3210

2021-06-10 11:23:37 (15576): Guest Log: 00:00:00.001647 main     Package type: LINUX_64BITS_GENERIC

2021-06-10 11:23:37 (15576): Guest Log: 00:00:00.005315 main     5.2.6 r120293 started. Verbose level = 0

2021-06-10 11:24:21 (15576): Guest Log: 17:24:23 CEST +02:00 2021-06-10: cranky: [INFO] Detected Theory App

2021-06-10 11:24:21 (15576): Guest Log: 17:24:23 CEST +02:00 2021-06-10: cranky: [INFO] Checking CVMFS.

2021-06-10 11:24:24 (15576): Guest Log: Probing /cvmfs/sft.cern.ch... OK

2021-06-10 11:24:25 (15576): 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

2021-06-10 11:24:25 (15576): Guest Log: 2.5.2.0 4109 0 25144 20341 3 1 1382891 4096001 0 65024 0 0 n/a 0 0 http://s1ral-cvmfs.openhtc.io/cvmfs/sft.cern.ch DIRECT 1

2021-06-10 11:24:27 (15576): Guest Log: Probing /cvmfs/grid.cern.ch... OK

2021-06-10 11:24:29 (15576): 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

2021-06-10 11:24:29 (15576): Guest Log: 2.5.2.0 4062 0 27576 15397 3 2 1382891 4096001 0 65024 0 2 0 4275 894 http://s1bnl-cvmfs.openhtc.io/cvmfs/grid.cern.ch DIRECT 1

2021-06-10 11:24:31 (15576): Guest Log: Probing /cvmfs/cernvm-prod.cern.ch... OK

2021-06-10 11:24:32 (15576): 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

2021-06-10 11:24:32 (15576): Guest Log: 2.5.2.0 4073 0 25244 267 3 1 1382891 4096001 0 65024 0 0 n/a 0 0 http://s1cern-cvmfs.openhtc.io/cvmfs/cernvm-prod.cern.ch DIRECT 1

2021-06-10 11:24:35 (15576): Guest Log: Probing /cvmfs/alice.cern.ch... OK

2021-06-10 11:24:36 (15576): 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

2021-06-10 11:24:36 (15576): Guest Log: 2.5.2.0 4130 0 31288 9489 3 1 1382891 4096001 0 65024 0 0 n/a 5039 998 http://s1bnl-cvmfs.openhtc.io/cvmfs/alice.cern.ch DIRECT 1

2021-06-10 11:24:36 (15576): Guest Log: 17:24:38 CEST +02:00 2021-06-10: cranky: [INFO] Checking runc.

2021-06-10 11:24:36 (15576): Guest Log: 17:24:38 CEST +02:00 2021-06-10: cranky: [INFO] Creating the filesystem.

2021-06-10 11:24:37 (15576): Guest Log: 17:24:38 CEST +02:00 2021-06-10: cranky: [INFO] Using /cvmfs/cernvm-prod.cern.ch/cvm3

2021-06-10 11:24:37 (15576): Guest Log: 17:24:39 CEST +02:00 2021-06-10: cranky: [INFO] Updating config.json.

2021-06-10 11:24:37 (15576): Guest Log: 17:24:39 CEST +02:00 2021-06-10: cranky: [INFO] Running Container 'runc'.

2021-06-10 11:24:38 (15576): Guest Log: job: htmld=/shared/html/job

2021-06-10 11:24:38 (15576): Guest Log: job: unpack exitcode=0

2021-06-10 11:24:39 (15576): Guest Log: 17:24:41 CEST +02:00 2021-06-10: cranky: [INFO] ===> [runRivet] Thu Jun 10 15:24:40 UTC 2021 [boinc pp jets 7000 800 - pythia8 8.212 tune-AU2lox 100000 188]

2021-06-10 13:03:48 (15576): Status Report: Job Duration: '864000.000000'
2021-06-10 13:03:48 (15576): Status Report: Elapsed Time: '92532.519956'
2021-06-10 13:03:48 (15576): Status Report: CPU Time: '69810.843750'
2021-06-10 13:16:38 (15576): VM state change detected. (old = 'Running', new = 'Paused')
2021-06-10 13:21:56 (15576): VM state change detected. (old = 'Paused', new = 'Running')
2021-06-10 14:43:00 (15576): VM state change detected. (old = 'Running', new = 'Paused')
2021-06-10 14:43:06 (15576): VM state change detected. (old = 'Paused', new = 'Running')
2021-06-10 14:51:28 (15576): Status Report: Job Duration: '864000.000000'
2021-06-10 14:51:28 (15576): Status Report: Elapsed Time: '98532.936464'
2021-06-10 14:51:28 (15576): Status Report: CPU Time: '74401.687500'
2021-06-10 16:31:33 (15576): Status Report: Job Duration: '864000.000000'
2021-06-10 16:31:33 (15576): Status Report: Elapsed Time: '104532.936464'
2021-06-10 16:31:33 (15576): Status Report: CPU Time: '78909.546875'
2021-06-10 18:11:38 (15576): Status Report: Job Duration: '864000.000000'
2021-06-10 18:11:38 (15576): Status Report: Elapsed Time: '110532.936464'
2021-06-10 18:11:38 (15576): Status Report: CPU Time: '83417.640625'
2021-06-10 19:51:48 (15576): Status Report: Job Duration: '864000.000000'
2021-06-10 19:51:48 (15576): Status Report: Elapsed Time: '116533.366432'
2021-06-10 19:51:48 (15576): Status Report: CPU Time: '87923.031250'
2021-06-10 21:31:50 (15576): Status Report: Job Duration: '864000.000000'
2021-06-10 21:31:50 (15576): Status Report: Elapsed Time: '122533.366432'
2021-06-10 21:31:50 (15576): Status Report: CPU Time: '92428.343750'
2021-06-10 23:11:53 (15576): Status Report: Job Duration: '864000.000000'
2021-06-10 23:11:53 (15576): Status Report: Elapsed Time: '128533.366432'
2021-06-10 23:11:53 (15576): Status Report: CPU Time: '96933.984375'
2021-06-11 00:52:04 (15576): Status Report: Job Duration: '864000.000000'
2021-06-11 00:52:04 (15576): Status Report: Elapsed Time: '134534.366432'
2021-06-11 00:52:04 (15576): Status Report: CPU Time: '101442.421875'
2021-06-11 02:32:06 (15576): Status Report: Job Duration: '864000.000000'
2021-06-11 02:32:06 (15576): Status Report: Elapsed Time: '140534.366432'
2021-06-11 02:32:06 (15576): Status Report: CPU Time: '105949.640625'
2021-06-11 04:12:08 (15576): Status Report: Job Duration: '864000.000000'
2021-06-11 04:12:08 (15576): Status Report: Elapsed Time: '146534.366432'
2021-06-11 04:12:08 (15576): Status Report: CPU Time: '110456.375000'
2021-06-11 05:52:10 (15576): Status Report: Job Duration: '864000.000000'
2021-06-11 05:52:10 (15576): Status Report: Elapsed Time: '152534.366432'
2021-06-11 05:52:10 (15576): Status Report: CPU Time: '114963.531250'
2021-06-11 07:32:12 (15576): Status Report: Job Duration: '864000.000000'
2021-06-11 07:32:12 (15576): Status Report: Elapsed Time: '158534.366432'
2021-06-11 07:32:12 (15576): Status Report: CPU Time: '119470.375000'
2021-06-11 09:12:22 (15576): Status Report: Job Duration: '864000.000000'
2021-06-11 09:12:22 (15576): Status Report: Elapsed Time: '164534.366432'
2021-06-11 09:12:22 (15576): Status Report: CPU Time: '123981.031250'
2021-06-11 09:17:43 (15576): Guest Log: job: run exitcode=0

2021-06-11 09:17:43 (15576): Guest Log: job: diskusage=2732

2021-06-11 09:17:43 (15576): Guest Log: job: logsize=92 k

2021-06-11 09:17:43 (15576): Guest Log: job: times=

2021-06-11 09:17:43 (15576): Guest Log: 0m0.034s 0m0.101s

2021-06-11 09:17:43 (15576): Guest Log: 1252m53.471s 18m6.200s

2021-06-11 09:17:43 (15576): Guest Log: job: cpuusage=76260

2021-06-11 09:17:44 (15576): Guest Log: 15:12:55 CEST +02:00 2021-06-11: cranky: [INFO] Container 'runc' finished with status code 0.

2021-06-11 09:17:44 (15576): Guest Log: 15:12:55 CEST +02:00 2021-06-11: cranky: [INFO] Preparing output.

2021-06-11 09:17:48 (15576): Guest Log: [INFO] Job Finished

2021-06-11 09:17:48 (15576): Guest Log: [INFO] Shutting Down.

2021-06-11 09:17:48 (15576): VM Completion File Detected.
2021-06-11 09:17:48 (15576): VM Completion Message: Job Finished
.
2021-06-11 09:17:48 (15576): Powering off VM.
2021-06-11 09:22:49 (15576): VM did not power off when requested.
2021-06-11 09:22:49 (15576): VM was successfully terminated.
2021-06-11 09:22:49 (15576): Deregistering VM. (boinc_7b18156f87c4eeb3, slot#1)
2021-06-11 09:22:51 (15576): Removing network bandwidth throttle group from VM.
2021-06-11 09:22:51 (15576): Removing VM from VirtualBox.
09:22:57 (15576): called boinc_finish(0)

</stderr_txt>
]]>

©2024 CERN