| Name | Theory_2773-2959333-51_2 |
| Workunit | 223592626 |
| Created | 16 Jun 2024, 6:41:04 UTC |
| Sent | 17 Jun 2024, 8:32:36 UTC |
| Report deadline | 28 Jun 2024, 8:32:36 UTC |
| Received | 30 Jun 2024, 0:52:21 UTC |
| Server state | Over |
| Outcome | Success |
| Client state | Done |
| Exit status | 0 (0x00000000) |
| Computer ID | 10748203 |
| Run time | 1 days 0 hours 44 min 41 sec |
| CPU time | 3 hours 44 min 56 sec |
| Validate state | Valid |
| Credit | 1,524.91 |
| Device peak FLOPS | 7.40 GFLOPS |
| Application version | Theory Simulation v300.30 (vbox64_theory) windows_x86_64 |
| Peak working set size | 88.32 MB |
| Peak swap size | 77.77 MB |
| Peak disk usage | 2.19 GB |
Stderr output
<core_client_version>8.0.2</core_client_version>
<![CDATA[
<stderr_txt>
2024-06-27 01:08:40 (80496): vboxwrapper version 26207
2024-06-27 01:08:40 (80496): BOINC client version: 8.0.2
2024-06-27 01:08:40 (80496): Detected: VirtualBox VboxManage Interface (Version: 7.0.6)
2024-06-27 01:08:40 (80496): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2024-06-27 01:08:40 (80496): Successfully copied 'init_data.xml' to the shared directory.
2024-06-27 01:08:40 (80496): Successfully copied 'input' to the shared directory.
2024-06-27 01:08:41 (80496): Create VM. (boinc_39a61c006174a945, slot#1)
2024-06-27 01:08:41 (80496): Setting Memory Size for VM. (630MB)
2024-06-27 01:08:41 (80496): Setting CPU Count for VM. (1)
2024-06-27 01:08:42 (80496): Setting Chipset Options for VM.
2024-06-27 01:08:42 (80496): Setting Graphics Controller Options for VM.
2024-06-27 01:08:42 (80496): Setting Boot Options for VM.
2024-06-27 01:08:42 (80496): Setting Network Configuration for NAT.
2024-06-27 01:08:43 (80496): Enabling VM Network Access.
2024-06-27 01:08:43 (80496): Disabling USB Support for VM.
2024-06-27 01:08:43 (80496): Disabling COM Port Support for VM.
2024-06-27 01:08:43 (80496): Disabling LPT Port Support for VM.
2024-06-27 01:08:44 (80496): Disabling Audio Support for VM.
2024-06-27 01:08:44 (80496): Disabling Clipboard Support for VM.
2024-06-27 01:08:44 (80496): Disabling Drag and Drop Support for VM.
2024-06-27 01:08:44 (80496): Adding storage controller(s) to VM.
2024-06-27 01:08:45 (80496): Adding virtual disk drive to VM. (Theory_2024_04_30_prod.vdi)
2024-06-27 01:08:45 (80496): Adding VirtualBox Guest Additions to VM.
2024-06-27 01:08:45 (80496): Adding network bandwidth throttle group to VM. (Defaulting to 1024GB)
2024-06-27 01:08:46 (80496): forwarding host port 55680 to guest port 80
2024-06-27 01:08:46 (80496): Enabling remote desktop for VM.
2024-06-27 01:08:46 (80496): Required extension pack not installed, remote desktop not enabled.
2024-06-27 01:08:46 (80496): Enabling shared directory for VM.
2024-06-27 01:08:47 (80496): Starting VM using VBoxManage interface. (boinc_39a61c006174a945, slot#1)
2024-06-27 01:08:52 (80496): Successfully started VM. (PID = '54316')
2024-06-27 01:08:52 (80496): Reporting VM Process ID to BOINC.
2024-06-27 01:08:52 (80496): Guest Log: BIOS: VirtualBox 7.0.6
2024-06-27 01:08:52 (80496): Guest Log: CPUID EDX: 0x178bfbff
2024-06-27 01:08:52 (80496): Guest Log: BIOS: No PCI IDE controller, not probing IDE
2024-06-27 01:08:52 (80496): Guest Log: BIOS: AHCI 0-P#0: PCHS=16383/16/63 LCHS=1024/255/63 0x0000000002800000 sectors
2024-06-27 01:08:52 (80496): VM state change detected. (old = 'poweredoff', new = 'running')
2024-06-27 01:08:52 (80496): Detected: Web Application Enabled (http://localhost:55680)
2024-06-27 01:08:52 (80496): Preference change detected
2024-06-27 01:08:52 (80496): Setting CPU throttle for VM. (1%)
2024-06-27 01:08:52 (80496): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2024-06-27 01:08:54 (80496): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032
2024-06-27 01:08:54 (80496): Guest Log: BIOS: Booting from Hard Disk...
2024-06-27 01:08:57 (80496): Guest Log: BIOS: KBD: unsupported int 16h function 03
2024-06-27 01:08:57 (80496): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000
2024-06-27 01:09:21 (80496): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds
2024-06-27 01:09:21 (80496): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000)
2024-06-27 01:09:28 (80496): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log
2024-06-27 01:09:28 (80496): Guest Log: 00:00:00.000063 main Log opened 2024-06-26T23:09:29.962155000Z
2024-06-27 01:09:28 (80496): Guest Log: 00:00:00.000157 main OS Product: Linux
2024-06-27 01:09:28 (80496): Guest Log: 00:00:00.000187 main OS Release: 4.14.76-13.cernvm.x86_64
2024-06-27 01:09:28 (80496): Guest Log: 00:00:00.000212 main OS Version: #1 SMP Tue Oct 16 18:26:15 CEST 2018
2024-06-27 01:09:28 (80496): Guest Log: 00:00:00.000235 main Executable: /usr/sbin/VBoxService
2024-06-27 01:09:28 (80496): Guest Log: 00:00:00.000236 main Process ID: 3227
2024-06-27 01:09:28 (80496): Guest Log: 00:00:00.000236 main Package type: LINUX_64BITS_GENERIC
2024-06-27 01:09:28 (80496): Guest Log: 00:00:00.001610 main 5.2.6 r120293 started. Verbose level = 0
2024-06-27 01:09:59 (80496): Guest Log: 01:10:01 CEST +02:00 2024-06-27: cranky: [INFO] Detected Theory App
2024-06-27 01:09:59 (80496): Guest Log: 01:10:01 CEST +02:00 2024-06-27: cranky: [INFO] Checking CVMFS.
2024-06-27 01:10:02 (80496): Guest Log: Probing /cvmfs/sft.cern.ch... OK
2024-06-27 01:10:03 (80496): 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
2024-06-27 01:10:03 (80496): Guest Log: 2.5.2.0 4091 0 28196 29767 3 1 272418 4096000 0 65024 0 0 n/a 5 4 http://s1ihep-cvmfs.openhtc.io/cvmfs/sft.cern.ch DIRECT 1
2024-06-27 01:10:04 (80496): Guest Log: Probing /cvmfs/grid.cern.ch... OK
2024-06-27 01:10:06 (80496): 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
2024-06-27 01:10:06 (80496): Guest Log: 2.5.2.0 4112 0 27532 24571 3 2 272418 4096000 0 65024 0 2 -100 7960 17008 http://s1cern-cvmfs.openhtc.io/cvmfs/grid.cern.ch DIRECT 1
2024-06-27 01:10:08 (80496): Guest Log: Probing /cvmfs/cernvm-prod.cern.ch... OK
2024-06-27 01:10:09 (80496): 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
2024-06-27 01:10:09 (80496): Guest Log: 2.5.2.0 4139 0 25172 272 3 1 272418 4096000 0 65024 0 0 n/a 4 2 http://s1ihep-cvmfs.openhtc.io/cvmfs/cernvm-prod.cern.ch DIRECT 1
2024-06-27 01:10:10 (80496): Guest Log: Probing /cvmfs/alice.cern.ch... OK
2024-06-27 01:10:11 (80496): 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
2024-06-27 01:10:11 (80496): Guest Log: 2.5.2.0 4070 0 39712 19009 3 1 272418 4096000 0 65024 0 0 n/a 5540 4017 http://s1cern-cvmfs.openhtc.io/cvmfs/alice.cern.ch DIRECT 1
2024-06-27 01:10:11 (80496): Guest Log: 01:10:12 CEST +02:00 2024-06-27: cranky: [INFO] Checking runc.
2024-06-27 01:10:11 (80496): Guest Log: 01:10:12 CEST +02:00 2024-06-27: cranky: [INFO] Creating the filesystem.
2024-06-27 01:10:11 (80496): Guest Log: 01:10:12 CEST +02:00 2024-06-27: cranky: [INFO] Using /cvmfs/cernvm-prod.cern.ch/cvm4
2024-06-27 01:10:11 (80496): Guest Log: 01:10:12 CEST +02:00 2024-06-27: cranky: [INFO] Updating config.json.
2024-06-27 01:10:11 (80496): Guest Log: 01:10:13 CEST +02:00 2024-06-27: cranky: [INFO] Running Container 'runc'.
2024-06-27 01:10:14 (80496): Guest Log: job: htmld=/shared/html/job
2024-06-27 01:10:14 (80496): Guest Log: job: unpack exitcode=0
2024-06-27 01:10:14 (80496): Guest Log: INFO: activated the work-around for ld:
2024-06-27 01:10:14 (80496): Guest Log: lrwxrwxrwx 1 0 0 15 Jun 26 23:10 /tmp/tmp.9LTZEeqXil/ld -> /usr/bin/ld.bfd
2024-06-27 01:10:16 (80496): Guest Log: 01:10:17 CEST +02:00 2024-06-27: cranky: [INFO] ===> [runRivet] Wed Jun 26 23:10:15 UTC 2024 [boinc pp jets 7000 150 - powheg-box r3744 ptdef2 100000 51]
2024-06-27 01:15:50 (80496): VM state change detected. (old = 'running', new = 'paused')
2024-06-27 01:16:17 (80496): VM state change detected. (old = 'paused', new = 'running')
2024-06-27 01:39:03 (80496): VM state change detected. (old = 'running', new = 'paused')
2024-06-27 01:39:28 (80496): VM state change detected. (old = 'paused', new = 'running')
2024-06-27 02:02:26 (80496): VM state change detected. (old = 'running', new = 'paused')
2024-06-27 02:02:50 (80496): VM state change detected. (old = 'paused', new = 'running')
2024-06-27 02:25:19 (80496): VM state change detected. (old = 'running', new = 'paused')
2024-06-27 02:25:42 (80496): VM state change detected. (old = 'paused', new = 'running')
2024-06-27 02:48:13 (80496): VM state change detected. (old = 'running', new = 'paused')
2024-06-27 02:48:35 (80496): VM state change detected. (old = 'paused', new = 'running')
2024-06-27 02:51:18 (80496): Status Report: Job Duration: '864000.000000'
2024-06-27 02:51:18 (80496): Status Report: Elapsed Time: '6000.000000'
2024-06-27 02:51:18 (80496): Status Report: CPU Time: '903.671875'
2024-06-27 02:52:36 (80496): Stopping VM.
2024-06-27 02:52:38 (80496): Successfully stopped VM.
2024-06-27 02:57:35 (72084): vboxwrapper version 26207
2024-06-27 02:57:35 (72084): BOINC client version: 8.0.2
2024-06-27 02:57:36 (72084): Detected: VirtualBox VboxManage Interface (Version: 7.0.6)
2024-06-27 02:57:36 (72084): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2024-06-27 02:57:37 (72084): Guest Log: BIOS: VirtualBox 7.0.6
2024-06-27 02:57:37 (72084): Guest Log: CPUID EDX: 0x178bfbff
2024-06-27 02:57:37 (72084): Guest Log: BIOS: No PCI IDE controller, not probing IDE
2024-06-27 02:57:37 (72084): Guest Log: BIOS: AHCI 0-P#0: PCHS=16383/16/63 LCHS=1024/255/63 0x0000000002800000 sectors
2024-06-27 02:57:37 (72084): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032
2024-06-27 02:57:37 (72084): Guest Log: BIOS: Booting from Hard Disk...
2024-06-27 02:57:37 (72084): Guest Log: BIOS: KBD: unsupported int 16h function 03
2024-06-27 02:57:37 (72084): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000
2024-06-27 02:57:37 (72084): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds
2024-06-27 02:57:37 (72084): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000)
2024-06-27 02:57:37 (72084): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log
2024-06-27 02:57:37 (72084): Guest Log: 00:00:00.000063 main Log opened 2024-06-26T23:09:29.962155000Z
2024-06-27 02:57:37 (72084): Guest Log: 00:00:00.000157 main OS Product: Linux
2024-06-27 02:57:37 (72084): Guest Log: 00:00:00.000187 main OS Release: 4.14.76-13.cernvm.x86_64
2024-06-27 02:57:37 (72084): Guest Log: 00:00:00.000212 main OS Version: #1 SMP Tue Oct 16 18:26:15 CEST 2018
2024-06-27 02:57:37 (72084): Guest Log: 00:00:00.000235 main Executable: /usr/sbin/VBoxService
2024-06-27 02:57:37 (72084): Guest Log: 00:00:00.000236 main Process ID: 3227
2024-06-27 02:57:37 (72084): Guest Log: 00:00:00.000236 main Package type: LINUX_64BITS_GENERIC
2024-06-27 02:57:37 (72084): Guest Log: 00:00:00.001610 main 5.2.6 r120293 started. Verbose level = 0
2024-06-27 02:57:37 (72084): Guest Log: 01:10:01 CEST +02:00 2024-06-27: cranky: [INFO] Detected Theory App
2024-06-27 02:57:37 (72084): Guest Log: 01:10:01 CEST +02:00 2024-06-27: cranky: [INFO] Checking CVMFS.
2024-06-27 02:57:37 (72084): Guest Log: Probing /cvmfs/sft.cern.ch... OK
2024-06-27 02:57:37 (72084): 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
2024-06-27 02:57:37 (72084): Guest Log: 2.5.2.0 4091 0 28196 29767 3 1 272418 4096000 0 65024 0 0 n/a 5 4 http://s1ihep-cvmfs.openhtc.io/cvmfs/sft.cern.ch DIRECT 1
2024-06-27 02:57:37 (72084): Guest Log: Probing /cvmfs/grid.cern.ch... OK
2024-06-27 02:57:37 (72084): 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
2024-06-27 02:57:37 (72084): Guest Log: 2.5.2.0 4112 0 27532 24571 3 2 272418 4096000 0 65024 0 2 -100 7960 17008 http://s1cern-cvmfs.openhtc.io/cvmfs/grid.cern.ch DIRECT 1
2024-06-27 02:57:37 (72084): Guest Log: Probing /cvmfs/cernvm-prod.cern.ch... OK
2024-06-27 02:57:37 (72084): 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
2024-06-27 02:57:37 (72084): Guest Log: 2.5.2.0 4139 0 25172 272 3 1 272418 4096000 0 65024 0 0 n/a 4 2 http://s1ihep-cvmfs.openhtc.io/cvmfs/cernvm-prod.cern.ch DIRECT 1
2024-06-27 02:57:37 (72084): Guest Log: Probing /cvmfs/alice.cern.ch... OK
2024-06-27 02:57:37 (72084): 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
2024-06-27 02:57:37 (72084): Guest Log: 2.5.2.0 4070 0 39712 19009 3 1 272418 4096000 0 65024 0 0 n/a 5540 4017 http://s1cern-cvmfs.openhtc.io/cvmfs/alice.cern.ch DIRECT 1
2024-06-27 02:57:37 (72084): Guest Log: 01:10:12 CEST +02:00 2024-06-27: cranky: [INFO] Checking runc.
2024-06-27 02:57:37 (72084): Guest Log: 01:10:12 CEST +02:00 2024-06-27: cranky: [INFO] Creating the filesystem.
2024-06-27 02:57:37 (72084): Guest Log: 01:10:12 CEST +02:00 2024-06-27: cranky: [INFO] Using /cvmfs/cernvm-prod.cern.ch/cvm4
2024-06-27 02:57:37 (72084): Guest Log: 01:10:12 CEST +02:00 2024-06-27: cranky: [INFO] Updating config.json.
2024-06-27 02:57:37 (72084): Guest Log: 01:10:13 CEST +02:00 2024-06-27: cranky: [INFO] Running Container 'runc'.
2024-06-27 02:57:37 (72084): Guest Log: job: htmld=/shared/html/job
2024-06-27 02:57:37 (72084): Guest Log: job: unpack exitcode=0
2024-06-27 02:57:37 (72084): Guest Log: INFO: activated the work-around for ld:
2024-06-27 02:57:37 (72084): Guest Log: lrwxrwxrwx 1 0 0 15 Jun 26 23:10 /tmp/tmp.9LTZEeqXil/ld -> /usr/bin/ld.bfd
2024-06-27 02:57:37 (72084): Guest Log: 01:10:17 CEST +02:00 2024-06-27: cranky: [INFO] ===> [runRivet] Wed Jun 26 23:10:15 UTC 2024 [boinc pp jets 7000 150 - powheg-box r3744 ptdef2 100000 51]
2024-06-27 02:57:37 (72084): Starting VM using VBoxManage interface. (boinc_39a61c006174a945, slot#1)
2024-06-27 06:12:19 (72732): vboxwrapper version 26207
2024-06-27 06:12:19 (72732): BOINC client version: 8.0.2
2024-06-27 06:12:19 (72732): Detected: VirtualBox VboxManage Interface (Version: 7.0.6)
2024-06-27 06:12:20 (72732): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2024-06-27 06:12:25 (72732): VM was running
2024-06-27 06:12:25 (72732): Powering off VM.
2024-06-27 06:12:25 (72732): Error in poweroff VM for VM: -2135228414
Command:
VBoxManage -q controlvm "boinc_39a61c006174a945" poweroff
Output:
VBoxManage.exe: error: Invalid machine state: Restoring (must be Running, Paused or Stuck)
VBoxManage.exe: error: Details: code VBOX_E_INVALID_VM_STATE (0x80bb0002), component ConsoleWrap, interface IConsole, callee IUnknown
VBoxManage.exe: error: Context: "PowerDown(progress.asOutParam())" at line 604 of file VBoxManageControlVM.cpp
2024-06-27 06:12:25 (72732): VM did not power off when requested.
2024-06-27 06:12:25 (72732): VM was NOT successfully terminated.
2024-06-27 06:12:25 (72732): Could not stop running VM
2024-06-27 06:12:25 (72732): ERROR: VM failed to start
2024-06-27 06:12:30 (72732):
NOTE: VM was already running.
BOINC will be notified that it needs to clean up the environment.
This might be a temporary problem and so this job will be rescheduled for another time.
2024-06-28 03:04:03 (24064): vboxwrapper version 26207
2024-06-28 03:04:04 (24064): BOINC client version: 8.0.2
2024-06-28 03:04:05 (24064): Detected: VirtualBox VboxManage Interface (Version: 7.0.6)
2024-06-28 03:04:05 (24064): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2024-06-28 03:04:05 (24064): Starting VM using VBoxManage interface. (boinc_39a61c006174a945, slot#1)
2024-06-28 03:20:42 (16960): vboxwrapper version 26207
2024-06-28 03:20:42 (16960): BOINC client version: 8.0.2
2024-06-28 03:20:43 (16960): Detected: VirtualBox VboxManage Interface (Version: 7.0.6)
2024-06-28 03:20:43 (16960): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2024-06-28 03:20:49 (16960): VM was running
2024-06-28 03:20:49 (16960): Powering off VM.
2024-06-28 03:20:49 (16960): Error in poweroff VM for VM: -2135228414
Command:
VBoxManage -q controlvm "boinc_39a61c006174a945" poweroff
Output:
VBoxManage.exe: error: Invalid machine state: Restoring (must be Running, Paused or Stuck)
VBoxManage.exe: error: Details: code VBOX_E_INVALID_VM_STATE (0x80bb0002), component ConsoleWrap, interface IConsole, callee IUnknown
VBoxManage.exe: error: Context: "PowerDown(progress.asOutParam())" at line 604 of file VBoxManageControlVM.cpp
2024-06-28 03:20:49 (16960): VM did not power off when requested.
2024-06-28 03:20:49 (16960): VM was NOT successfully terminated.
2024-06-28 03:20:49 (16960): Could not stop running VM
2024-06-28 03:20:49 (16960): ERROR: VM failed to start
2024-06-28 03:20:54 (16960):
NOTE: VM was already running.
BOINC will be notified that it needs to clean up the environment.
This might be a temporary problem and so this job will be rescheduled for another time.
2024-06-29 03:00:56 (52280): vboxwrapper version 26207
2024-06-29 03:00:56 (52280): BOINC client version: 8.0.2
2024-06-29 03:00:58 (52280): Detected: VirtualBox VboxManage Interface (Version: 7.0.6)
2024-06-29 03:00:58 (52280): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2024-06-29 03:00:58 (52280): Starting VM using VBoxManage interface. (boinc_39a61c006174a945, slot#1)
2024-06-29 03:01:04 (52280): Successfully started VM. (PID = '64708')
2024-06-29 03:01:04 (52280): Reporting VM Process ID to BOINC.
2024-06-29 03:01:04 (52280): VM state change detected. (old = 'poweredoff', new = 'running')
2024-06-29 03:01:04 (52280): Detected: Web Application Enabled (http://localhost:55680)
2024-06-29 03:01:04 (52280): Status Report: Job Duration: '864000.000000'
2024-06-29 03:01:04 (52280): Status Report: Elapsed Time: '6078.000000'
2024-06-29 03:01:04 (52280): Status Report: CPU Time: '915.812500'
2024-06-29 03:01:04 (52280): Preference change detected
2024-06-29 03:01:04 (52280): Setting CPU throttle for VM. (1%)
2024-06-29 03:01:05 (52280): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2024-06-29 03:01:13 (52280): Guest Log: 01:41:20.043808 timesync vgsvcTimeSyncWorker: Radical host time change: 173 317 234 000 000ns (HostNow=1 719 622 872 732 000 000 ns HostLast=1 719 449 555 498 000 000 ns)
2024-06-29 03:01:23 (52280): Guest Log: 01:41:30.225675 timesync vgsvcTimeSyncWorker: Radical guest time change: 173 433 927 158 000ns (GuestNow=1 719 622 883 106 299 000 ns GuestLast=1 719 449 449 179 141 000 ns fSetTimeLastLoop=true )
2024-06-29 03:10:16 (52280): Stopping VM.
2024-06-29 03:10:18 (52280): Successfully stopped VM.
2024-06-29 03:15:14 (67956): vboxwrapper version 26207
2024-06-29 03:15:14 (67956): BOINC client version: 8.0.2
2024-06-29 03:15:15 (67956): Detected: VirtualBox VboxManage Interface (Version: 7.0.6)
2024-06-29 03:15:15 (67956): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2024-06-29 03:15:16 (67956): Guest Log: 01:41:20.043808 timesync vgsvcTimeSyncWorker: Radical host time change: 173 317 234 000 000ns (HostNow=1 719 622 872 732 000 000 ns HostLast=1 719 449 555 498 000 000 ns)
2024-06-29 03:15:16 (67956): Guest Log: 01:41:30.225675 timesync vgsvcTimeSyncWorker: Radical guest time change: 173 433 927 158 000ns (GuestNow=1 719 622 883 106 299 000 ns GuestLast=1 719 449 449 179 141 000 ns fSetTimeLastLoop=true )
2024-06-29 03:15:16 (67956): Starting VM using VBoxManage interface. (boinc_39a61c006174a945, slot#1)
2024-06-29 03:15:21 (67956): Successfully started VM. (PID = '65912')
2024-06-29 03:15:21 (67956): Reporting VM Process ID to BOINC.
2024-06-29 03:15:21 (67956): VM state change detected. (old = 'poweredoff', new = 'running')
2024-06-29 03:15:21 (67956): Detected: Web Application Enabled (http://localhost:55680)
2024-06-29 03:15:21 (67956): Status Report: Job Duration: '864000.000000'
2024-06-29 03:15:21 (67956): Status Report: Elapsed Time: '6628.000000'
2024-06-29 03:15:21 (67956): Status Report: CPU Time: '1000.546875'
2024-06-29 03:15:21 (67956): Preference change detected
2024-06-29 03:15:21 (67956): Setting CPU throttle for VM. (1%)
2024-06-29 03:15:22 (67956): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2024-06-29 03:35:57 (67956): VM state change detected. (old = 'running', new = 'paused')
2024-06-29 03:36:18 (67956): VM state change detected. (old = 'paused', new = 'running')
2024-06-29 03:57:52 (67956): VM state change detected. (old = 'running', new = 'paused')
2024-06-29 03:58:13 (67956): VM state change detected. (old = 'paused', new = 'running')
2024-06-29 04:19:35 (67956): VM state change detected. (old = 'running', new = 'paused')
2024-06-29 04:19:36 (67956): VM state change detected. (old = 'paused', new = 'running')
2024-06-29 04:41:07 (67956): VM state change detected. (old = 'running', new = 'paused')
2024-06-29 04:41:36 (67956): VM state change detected. (old = 'paused', new = 'running')
2024-06-29 04:56:53 (67956): Status Report: Job Duration: '864000.000000'
2024-06-29 04:56:53 (67956): Status Report: Elapsed Time: '12628.000000'
2024-06-29 04:56:53 (67956): Status Report: CPU Time: '1916.203125'
2024-06-29 05:03:10 (67956): VM state change detected. (old = 'running', new = 'paused')
2024-06-29 05:03:31 (67956): VM state change detected. (old = 'paused', new = 'running')
2024-06-29 05:25:45 (67956): VM state change detected. (old = 'running', new = 'paused')
2024-06-29 05:26:10 (67956): VM state change detected. (old = 'paused', new = 'running')
2024-06-29 05:47:58 (67956): VM state change detected. (old = 'running', new = 'paused')
2024-06-29 05:48:21 (67956): VM state change detected. (old = 'paused', new = 'running')
2024-06-29 06:10:12 (67956): VM state change detected. (old = 'running', new = 'paused')
2024-06-29 06:10:33 (67956): VM state change detected. (old = 'paused', new = 'running')
2024-06-29 06:32:46 (67956): VM state change detected. (old = 'running', new = 'paused')
2024-06-29 06:33:15 (67956): VM state change detected. (old = 'paused', new = 'running')
2024-06-29 06:39:14 (67956): Status Report: Job Duration: '864000.000000'
2024-06-29 06:39:14 (67956): Status Report: Elapsed Time: '18628.000000'
2024-06-29 06:39:14 (67956): Status Report: CPU Time: '2832.906250'
2024-06-29 06:54:51 (67956): VM state change detected. (old = 'running', new = 'paused')
2024-06-29 06:55:18 (67956): VM state change detected. (old = 'paused', new = 'running')
2024-06-29 07:17:35 (67956): VM state change detected. (old = 'running', new = 'paused')
2024-06-29 07:18:00 (67956): VM state change detected. (old = 'paused', new = 'running')
2024-06-29 07:40:18 (67956): VM state change detected. (old = 'running', new = 'paused')
2024-06-29 07:40:41 (67956): VM state change detected. (old = 'paused', new = 'running')
2024-06-29 08:00:10 (67956): Stopping VM.
2024-06-29 08:00:15 (67956): Successfully stopped VM.
2024-06-29 08:05:09 (102844): vboxwrapper version 26207
2024-06-29 08:05:09 (102844): BOINC client version: 8.0.2
2024-06-29 08:05:10 (102844): Detected: VirtualBox VboxManage Interface (Version: 7.0.6)
2024-06-29 08:05:10 (102844): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2024-06-29 08:05:11 (102844): Starting VM using VBoxManage interface. (boinc_39a61c006174a945, slot#1)
2024-06-29 08:05:16 (102844): Successfully started VM. (PID = '91108')
2024-06-29 08:05:16 (102844): Reporting VM Process ID to BOINC.
2024-06-29 08:05:16 (102844): VM state change detected. (old = 'poweredoff', new = 'running')
2024-06-29 08:05:16 (102844): Detected: Web Application Enabled (http://localhost:55680)
2024-06-29 08:05:16 (102844): Status Report: Job Duration: '864000.000000'
2024-06-29 08:05:16 (102844): Status Report: Elapsed Time: '23393.000000'
2024-06-29 08:05:16 (102844): Status Report: CPU Time: '3560.781250'
2024-06-29 08:05:16 (102844): Preference change detected
2024-06-29 08:05:16 (102844): Setting CPU throttle for VM. (1%)
2024-06-29 08:05:17 (102844): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2024-06-29 08:32:10 (102844): VM state change detected. (old = 'running', new = 'paused')
2024-06-29 08:32:37 (102844): VM state change detected. (old = 'paused', new = 'running')
2024-06-29 08:54:44 (102844): VM state change detected. (old = 'running', new = 'paused')
2024-06-29 08:55:08 (102844): VM state change detected. (old = 'paused', new = 'running')
2024-06-29 09:17:30 (102844): VM state change detected. (old = 'running', new = 'paused')
2024-06-29 09:17:52 (102844): VM state change detected. (old = 'paused', new = 'running')
2024-06-29 09:39:03 (102844): Stopping VM.
2024-06-29 09:39:06 (102844): Successfully stopped VM.
2024-06-29 09:44:01 (107600): vboxwrapper version 26207
2024-06-29 09:44:01 (107600): BOINC client version: 8.0.2
2024-06-29 09:44:02 (107600): Detected: VirtualBox VboxManage Interface (Version: 7.0.6)
2024-06-29 09:44:02 (107600): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2024-06-29 09:44:03 (107600): Starting VM using VBoxManage interface. (boinc_39a61c006174a945, slot#1)
2024-06-29 09:44:09 (107600): Successfully started VM. (PID = '119744')
2024-06-29 09:44:09 (107600): Reporting VM Process ID to BOINC.
2024-06-29 09:44:09 (107600): VM state change detected. (old = 'poweredoff', new = 'running')
2024-06-29 09:44:09 (107600): Detected: Web Application Enabled (http://localhost:55680)
2024-06-29 09:44:09 (107600): Status Report: Job Duration: '864000.000000'
2024-06-29 09:44:09 (107600): Status Report: Elapsed Time: '28930.000000'
2024-06-29 09:44:09 (107600): Status Report: CPU Time: '4405.578125'
2024-06-29 09:44:09 (107600): Preference change detected
2024-06-29 09:44:09 (107600): Setting CPU throttle for VM. (1%)
2024-06-29 09:44:09 (107600): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2024-06-29 10:10:31 (107600): VM state change detected. (old = 'running', new = 'paused')
2024-06-29 10:10:54 (107600): VM state change detected. (old = 'paused', new = 'running')
2024-06-29 10:33:09 (107600): VM state change detected. (old = 'running', new = 'paused')
2024-06-29 10:33:30 (107600): VM state change detected. (old = 'paused', new = 'running')
2024-06-29 10:55:16 (107600): VM state change detected. (old = 'running', new = 'paused')
2024-06-29 10:55:44 (107600): VM state change detected. (old = 'paused', new = 'running')
2024-06-29 11:17:52 (107600): VM state change detected. (old = 'running', new = 'paused')
2024-06-29 11:18:18 (107600): VM state change detected. (old = 'paused', new = 'running')
2024-06-29 11:26:04 (107600): Status Report: Job Duration: '864000.000000'
2024-06-29 11:26:04 (107600): Status Report: Elapsed Time: '34930.000000'
2024-06-29 11:26:04 (107600): Status Report: CPU Time: '5322.718750'
2024-06-29 11:40:20 (107600): VM state change detected. (old = 'running', new = 'paused')
2024-06-29 11:40:41 (107600): VM state change detected. (old = 'paused', new = 'running')
2024-06-29 12:02:26 (107600): VM state change detected. (old = 'running', new = 'paused')
2024-06-29 12:02:55 (107600): VM state change detected. (old = 'paused', new = 'running')
2024-06-29 12:25:21 (107600): VM state change detected. (old = 'running', new = 'paused')
2024-06-29 12:25:48 (107600): VM state change detected. (old = 'paused', new = 'running')
2024-06-29 12:47:37 (107600): VM state change detected. (old = 'running', new = 'paused')
2024-06-29 12:48:00 (107600): VM state change detected. (old = 'paused', new = 'running')
2024-06-29 13:08:03 (107600): Status Report: Job Duration: '864000.000000'
2024-06-29 13:08:03 (107600): Status Report: Elapsed Time: '40930.000000'
2024-06-29 13:08:03 (107600): Status Report: CPU Time: '6237.718750'
2024-06-29 13:09:21 (107600): VM state change detected. (old = 'running', new = 'paused')
2024-06-29 13:09:42 (107600): VM state change detected. (old = 'paused', new = 'running')
2024-06-29 13:31:04 (107600): VM state change detected. (old = 'running', new = 'paused')
2024-06-29 13:31:32 (107600): VM state change detected. (old = 'paused', new = 'running')
2024-06-29 13:53:08 (107600): VM state change detected. (old = 'running', new = 'paused')
2024-06-29 13:53:34 (107600): VM state change detected. (old = 'paused', new = 'running')
2024-06-29 14:14:51 (107600): VM state change detected. (old = 'running', new = 'paused')
2024-06-29 14:15:12 (107600): VM state change detected. (old = 'paused', new = 'running')
2024-06-29 14:36:45 (107600): VM state change detected. (old = 'running', new = 'paused')
2024-06-29 14:37:09 (107600): VM state change detected. (old = 'paused', new = 'running')
2024-06-29 14:50:24 (107600): Status Report: Job Duration: '864000.000000'
2024-06-29 14:50:24 (107600): Status Report: Elapsed Time: '46930.000000'
2024-06-29 14:50:24 (107600): Status Report: CPU Time: '7153.203125'
2024-06-29 14:58:29 (107600): VM state change detected. (old = 'running', new = 'paused')
2024-06-29 14:58:51 (107600): VM state change detected. (old = 'paused', new = 'running')
2024-06-29 15:20:22 (107600): VM state change detected. (old = 'running', new = 'paused')
2024-06-29 15:20:23 (107600): VM state change detected. (old = 'paused', new = 'running')
2024-06-29 15:42:26 (107600): VM state change detected. (old = 'running', new = 'paused')
2024-06-29 15:42:55 (107600): VM state change detected. (old = 'paused', new = 'running')
2024-06-29 16:04:30 (107600): VM state change detected. (old = 'running', new = 'paused')
2024-06-29 16:04:57 (107600): VM state change detected. (old = 'paused', new = 'running')
2024-06-29 16:26:22 (107600): VM state change detected. (old = 'running', new = 'paused')
2024-06-29 16:26:43 (107600): VM state change detected. (old = 'paused', new = 'running')
2024-06-29 16:32:32 (107600): Status Report: Job Duration: '864000.000000'
2024-06-29 16:32:32 (107600): Status Report: Elapsed Time: '52930.000000'
2024-06-29 16:32:32 (107600): Status Report: CPU Time: '8067.812500'
2024-06-29 16:48:16 (107600): VM state change detected. (old = 'running', new = 'paused')
2024-06-29 16:48:36 (107600): VM state change detected. (old = 'paused', new = 'running')
2024-06-29 17:10:00 (107600): VM state change detected. (old = 'running', new = 'paused')
2024-06-29 17:10:23 (107600): VM state change detected. (old = 'paused', new = 'running')
2024-06-29 17:32:04 (107600): VM state change detected. (old = 'running', new = 'paused')
2024-06-29 17:32:24 (107600): VM state change detected. (old = 'paused', new = 'running')
2024-06-29 17:53:38 (107600): VM state change detected. (old = 'running', new = 'paused')
2024-06-29 17:53:58 (107600): VM state change detected. (old = 'paused', new = 'running')
2024-06-29 18:14:14 (107600): Status Report: Job Duration: '864000.000000'
2024-06-29 18:14:14 (107600): Status Report: Elapsed Time: '58930.000000'
2024-06-29 18:14:14 (107600): Status Report: CPU Time: '8982.171875'
2024-06-29 18:15:10 (107600): VM state change detected. (old = 'running', new = 'paused')
2024-06-29 18:15:38 (107600): VM state change detected. (old = 'paused', new = 'running')
2024-06-29 18:37:04 (107600): VM state change detected. (old = 'running', new = 'paused')
2024-06-29 18:37:31 (107600): VM state change detected. (old = 'paused', new = 'running')
2024-06-29 18:58:58 (107600): VM state change detected. (old = 'running', new = 'paused')
2024-06-29 18:59:23 (107600): VM state change detected. (old = 'paused', new = 'running')
2024-06-29 19:20:38 (107600): Stopping VM.
2024-06-29 19:20:41 (107600): Successfully stopped VM.
2024-06-29 19:32:16 (91584): vboxwrapper version 26207
2024-06-29 19:32:16 (91584): BOINC client version: 8.0.2
2024-06-29 19:32:17 (91584): Detected: VirtualBox VboxManage Interface (Version: 7.0.6)
2024-06-29 19:32:17 (91584): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2024-06-29 19:32:18 (91584): Starting VM using VBoxManage interface. (boinc_39a61c006174a945, slot#1)
2024-06-29 19:32:24 (91584): Successfully started VM. (PID = '75468')
2024-06-29 19:32:24 (91584): Reporting VM Process ID to BOINC.
2024-06-29 19:32:24 (91584): VM state change detected. (old = 'poweredoff', new = 'running')
2024-06-29 19:32:24 (91584): Detected: Web Application Enabled (http://localhost:55680)
2024-06-29 19:32:24 (91584): Status Report: Job Duration: '864000.000000'
2024-06-29 19:32:24 (91584): Status Report: Elapsed Time: '62819.000000'
2024-06-29 19:32:24 (91584): Status Report: CPU Time: '9574.031250'
2024-06-29 19:32:24 (91584): Preference change detected
2024-06-29 19:32:24 (91584): Setting CPU throttle for VM. (1%)
2024-06-29 19:32:24 (91584): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2024-06-29 19:58:52 (91584): VM state change detected. (old = 'running', new = 'paused')
2024-06-29 19:59:17 (91584): VM state change detected. (old = 'paused', new = 'running')
2024-06-29 20:21:06 (91584): VM state change detected. (old = 'running', new = 'paused')
2024-06-29 20:21:29 (91584): VM state change detected. (old = 'paused', new = 'running')
2024-06-29 20:43:09 (91584): VM state change detected. (old = 'running', new = 'paused')
2024-06-29 20:43:31 (91584): VM state change detected. (old = 'paused', new = 'running')
2024-06-29 21:13:55 (91584): Status Report: Job Duration: '864000.000000'
2024-06-29 21:13:55 (91584): Status Report: Elapsed Time: '68819.000000'
2024-06-29 21:13:55 (91584): Status Report: CPU Time: '10487.546875'
2024-06-29 21:26:25 (91584): VM state change detected. (old = 'running', new = 'paused')
2024-06-29 21:26:54 (91584): VM state change detected. (old = 'paused', new = 'running')
2024-06-29 21:48:39 (91584): VM state change detected. (old = 'running', new = 'paused')
2024-06-29 21:49:06 (91584): VM state change detected. (old = 'paused', new = 'running')
2024-06-29 22:10:43 (91584): VM state change detected. (old = 'running', new = 'paused')
2024-06-29 22:11:08 (91584): VM state change detected. (old = 'paused', new = 'running')
2024-06-29 22:32:37 (91584): VM state change detected. (old = 'running', new = 'paused')
2024-06-29 22:33:00 (91584): VM state change detected. (old = 'paused', new = 'running')
2024-06-29 22:54:40 (91584): VM state change detected. (old = 'running', new = 'paused')
2024-06-29 22:55:01 (91584): VM state change detected. (old = 'paused', new = 'running')
2024-06-29 22:56:21 (91584): Status Report: Job Duration: '864000.000000'
2024-06-29 22:56:21 (91584): Status Report: Elapsed Time: '74819.000000'
2024-06-29 22:56:21 (91584): Status Report: CPU Time: '11389.593750'
2024-06-29 23:16:44 (91584): VM state change detected. (old = 'running', new = 'paused')
2024-06-29 23:17:04 (91584): VM state change detected. (old = 'paused', new = 'running')
2024-06-29 23:39:17 (91584): VM state change detected. (old = 'running', new = 'paused')
2024-06-29 23:39:44 (91584): VM state change detected. (old = 'paused', new = 'running')
2024-06-30 00:01:31 (91584): VM state change detected. (old = 'running', new = 'paused')
2024-06-30 00:01:56 (91584): VM state change detected. (old = 'paused', new = 'running')
2024-06-30 00:23:45 (91584): VM state change detected. (old = 'running', new = 'paused')
2024-06-30 00:24:09 (91584): VM state change detected. (old = 'paused', new = 'running')
2024-06-30 00:38:23 (91584): Status Report: Job Duration: '864000.000000'
2024-06-30 00:38:23 (91584): Status Report: Elapsed Time: '80819.000000'
2024-06-30 00:38:23 (91584): Status Report: CPU Time: '12302.890625'
2024-06-30 00:45:38 (91584): VM state change detected. (old = 'running', new = 'paused')
2024-06-30 00:46:00 (91584): VM state change detected. (old = 'paused', new = 'running')
2024-06-30 01:07:32 (91584): VM state change detected. (old = 'running', new = 'paused')
2024-06-30 01:07:33 (91584): VM state change detected. (old = 'paused', new = 'running')
2024-06-30 01:28:45 (91584): VM state change detected. (old = 'running', new = 'paused')
2024-06-30 01:29:14 (91584): VM state change detected. (old = 'paused', new = 'running')
2024-06-30 01:50:29 (91584): VM state change detected. (old = 'running', new = 'paused')
2024-06-30 01:50:57 (91584): VM state change detected. (old = 'paused', new = 'running')
2024-06-30 02:12:41 (91584): VM state change detected. (old = 'running', new = 'paused')
2024-06-30 02:13:08 (91584): VM state change detected. (old = 'paused', new = 'running')
2024-06-30 02:20:32 (91584): Status Report: Job Duration: '864000.000000'
2024-06-30 02:20:32 (91584): Status Report: Elapsed Time: '86819.000000'
2024-06-30 02:20:32 (91584): Status Report: CPU Time: '13214.156250'
2024-06-30 02:34:55 (91584): VM state change detected. (old = 'running', new = 'paused')
2024-06-30 02:35:20 (91584): VM state change detected. (old = 'paused', new = 'running')
2024-06-30 02:52:02 (91584): Guest Log: job: run exitcode=0
2024-06-30 02:52:02 (91584): Guest Log: job: diskusage=103712
2024-06-30 02:52:02 (91584): Guest Log: job: logsize=92 k
2024-06-30 02:52:02 (91584): Guest Log: job: times=
2024-06-30 02:52:02 (91584): Guest Log: 0m0.076s 0m0.095s
2024-06-30 02:52:02 (91584): Guest Log: 1453m6.367s 10m53.651s
2024-06-30 02:52:02 (91584): Guest Log: job: cpuusage=87840
2024-06-30 02:52:02 (91584): Guest Log: 02:45:11 CEST +02:00 2024-06-30: cranky: [INFO] Container 'runc' finished with status code 0.
2024-06-30 02:52:02 (91584): Guest Log: 02:45:11 CEST +02:00 2024-06-30: cranky: [INFO] Preparing output.
2024-06-30 02:52:03 (91584): Guest Log: [INFO] Job Finished
2024-06-30 02:52:03 (91584): Guest Log: [INFO] Shutting Down.
2024-06-30 02:52:03 (91584): VM Completion File Detected.
2024-06-30 02:52:03 (91584): VM Completion Message: Job Finished
.
2024-06-30 02:52:03 (91584): Powering off VM.
2024-06-30 02:52:03 (91584): Successfully stopped VM.
2024-06-30 02:52:03 (91584): Deregistering VM. (boinc_39a61c006174a945, slot#1)
2024-06-30 02:52:03 (91584): Removing network bandwidth throttle group from VM.
2024-06-30 02:52:04 (91584): Removing VM from VirtualBox.
2024-06-30 02:52:09 (91584): called boinc_finish(0)
</stderr_txt>
]]>
©2024 CERN