| Name | Theory_2773-2922189-284_2 |
| Workunit | 229823348 |
| Created | 18 Jan 2025, 13:13:56 UTC |
| Sent | 18 Jan 2025, 17:21:05 UTC |
| Report deadline | 29 Jan 2025, 17:21:05 UTC |
| Received | 27 Jan 2025, 11:19:06 UTC |
| Server state | Over |
| Outcome | Computation error |
| Client state | Compute error |
| Exit status | -2147467259 (0x80004005) Unknown error code |
| Computer ID | 10832933 |
| Run time | 18 hours 28 min 52 sec |
| CPU time | 11 hours 0 min 13 sec |
| Validate state | Invalid |
| Credit | 0.00 |
| Device peak FLOPS | 3.74 GFLOPS |
| Application version | Theory Simulation v300.60 (vbox64_theory) windows_x86_64 |
| Peak working set size | 84.85 MB |
| Peak swap size | 78.05 MB |
| Peak disk usage | 2.22 GB |
Stderr output
<core_client_version>7.24.1</core_client_version>
<![CDATA[
<message>
Errore non specificato.
(0x80004005) - exit code 2147500037 (0x80004005)</message>
<stderr_txt>
:24 (11636): Guest Log: Probing /cvmfs/cernvm-prod.cern.ch... OK
2025-01-21 09:58:24 (11636): 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
2025-01-21 09:58:24 (11636): Guest Log: 2.5.2.0 4103 0 28280 272 3 1 1744054 4096001 0 65024 0 0 n/a 0 0 http://s1cern-cvmfs.openhtc.io/cvmfs/cernvm-prod.cern.ch DIRECT 1
2025-01-21 09:58:24 (11636): Guest Log: Probing /cvmfs/alice.cern.ch... OK
2025-01-21 09:58:24 (11636): 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
2025-01-21 09:58:24 (11636): Guest Log: 2.5.2.0 4126 0 39360 20900 3 1 1744054 4096001 0 65024 0 0 n/a 6578 1944 http://s1ihep-cvmfs.openhtc.io/cvmfs/alice.cern.ch DIRECT 1
2025-01-21 09:58:24 (11636): Guest Log: 14:00:28 CET +01:00 2025-01-19: cranky: [INFO] Checking runc.
2025-01-21 09:58:24 (11636): Guest Log: 14:00:28 CET +01:00 2025-01-19: cranky: [INFO] Creating the filesystem.
2025-01-21 09:58:24 (11636): Guest Log: 14:00:28 CET +01:00 2025-01-19: cranky: [INFO] Using /cvmfs/cernvm-prod.cern.ch/cvm4
2025-01-21 09:58:24 (11636): Guest Log: 14:00:29 CET +01:00 2025-01-19: cranky: [INFO] Updating config.json.
2025-01-21 09:58:24 (11636): Guest Log: 14:00:29 CET +01:00 2025-01-19: cranky: [INFO] Running Container 'runc'.
2025-01-21 09:58:24 (11636): Guest Log: job: htmld=/shared/html/job
2025-01-21 09:58:24 (11636): Guest Log: job: unpack exitcode=0
2025-01-21 09:58:24 (11636): Guest Log: INFO: activated the work-around for ld:
2025-01-21 09:58:24 (11636): Guest Log: lrwxrwxrwx 1 0 0 15 Jan 19 13:00 /tmp/tmp.nKvsdkpKA8/ld -> /usr/bin/ld.bfd
2025-01-21 09:58:24 (11636): Guest Log: 14:00:31 CET +01:00 2025-01-19: cranky: [INFO] ===> [runRivet] Sun Jan 19 13:00:30 UTC 2025 [boinc pp jets 13000 150,-,1860 - powheg-box r3744 pthard2 100000 284]
2025-01-21 09:58:24 (11636): Starting VM using VBoxManage interface. (boinc_6326e43d11a75aac, slot#1)
2025-01-21 09:58:32 (11636): Successfully started VM. (PID = '2936')
2025-01-21 09:58:32 (11636): Reporting VM Process ID to BOINC.
2025-01-21 09:58:32 (11636): Guest Log: BIOS: VirtualBox 7.0.6
2025-01-21 09:58:32 (11636): Guest Log: CPUID EDX: 0x178bfbff
2025-01-21 09:58:32 (11636): Guest Log: BIOS: No PCI IDE controller, not probing IDE
2025-01-21 09:58:32 (11636): Guest Log: BIOS: AHCI 0-P#0: PCHS=16383/16/63 LCHS=1024/255/63 0x0000000002800000 sectors
2025-01-21 09:58:32 (11636): VM state change detected. (old = 'poweredoff', new = 'running')
2025-01-21 09:58:32 (11636): Detected: Web Application Enabled (http://localhost:49952)
2025-01-21 09:58:33 (11636): VM state change detected. (old = 'running', new = 'paused')
2025-01-21 09:58:43 (11636): Status Report: Job Duration: '864000.000000'
2025-01-21 09:58:43 (11636): Status Report: Elapsed Time: '15964.000000'
2025-01-21 09:58:43 (11636): Status Report: CPU Time: '9090.671875'
2025-01-21 09:58:43 (11636): Preference change detected
2025-01-21 09:58:43 (11636): Setting CPU throttle for VM. (60%)
2025-01-21 09:58:43 (11636): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2025-01-21 09:58:44 (11636): VM state change detected. (old = 'paused', new = 'running')
2025-01-21 09:58:45 (11636): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032
2025-01-21 09:58:45 (11636): Guest Log: BIOS: Booting from Hard Disk...
2025-01-21 09:58:48 (11636): Guest Log: BIOS: KBD: unsupported int 16h function 03
2025-01-21 09:58:48 (11636): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000
2025-01-21 09:59:21 (11636): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds
2025-01-21 09:59:21 (11636): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000)
2025-01-21 09:59:28 (11636): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log
2025-01-21 09:59:28 (11636): Guest Log: 00:00:00.000137 main Log opened 2025-01-21T08:59:27.451825000Z
2025-01-21 09:59:28 (11636): Guest Log: 00:00:00.000245 main OS Product: Linux
2025-01-21 09:59:28 (11636): Guest Log: 00:00:00.000279 main OS Release: 4.14.76-13.cernvm.x86_64
2025-01-21 09:59:28 (11636): Guest Log: 00:00:00.000319 main OS Version: #1 SMP Tue Oct 16 18:26:15 CEST 2018
2025-01-21 09:59:28 (11636): Guest Log: 00:00:00.000348 main Executable: /usr/sbin/VBoxService
2025-01-21 09:59:28 (11636): Guest Log: 00:00:00.000348 main Process ID: 3222
2025-01-21 09:59:28 (11636): Guest Log: 00:00:00.000349 main Package type: LINUX_64BITS_GENERIC
2025-01-21 09:59:28 (11636): Guest Log: 00:00:00.004251 main 5.2.6 r120293 started. Verbose level = 0
2025-01-21 10:00:02 (11636): Guest Log: 10:00:01 CET +01:00 2025-01-21: cranky: [INFO] Detected Theory App
2025-01-21 10:00:02 (11636): Guest Log: 10:00:01 CET +01:00 2025-01-21: cranky: [INFO] Checking CVMFS.
2025-01-21 10:00:04 (11636): Guest Log: Probing /cvmfs/sft.cern.ch... OK
2025-01-21 10:00:05 (11636): 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
2025-01-21 10:00:05 (11636): Guest Log: 2.5.2.0 4078 0 28312 31532 3 1 1777129 4096001 0 65024 0 0 n/a 5 5 http://s1cern-cvmfs.openhtc.io/cvmfs/sft.cern.ch DIRECT 1
2025-01-21 10:00:06 (11636): Guest Log: Probing /cvmfs/grid.cern.ch... OK
2025-01-21 10:00:07 (11636): 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
2025-01-21 10:00:07 (11636): Guest Log: 2.5.2.0 4104 0 27420 26279 3 2 1777129 4096001 0 65024 0 2 0 7947 4454 http://s1ral-cvmfs.openhtc.io/cvmfs/grid.cern.ch DIRECT 1
2025-01-21 10:00:09 (11636): Guest Log: Probing /cvmfs/cernvm-prod.cern.ch... OK
2025-01-21 10:00:10 (11636): 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
2025-01-21 10:00:10 (11636): Guest Log: 2.5.2.0 4057 0 25184 272 3 1 1777129 4096001 0 65024 0 0 n/a 0 0 http://s1ihep-cvmfs.openhtc.io/cvmfs/cernvm-prod.cern.ch DIRECT 1
2025-01-21 10:00:12 (11636): Guest Log: Probing /cvmfs/alice.cern.ch... OK
2025-01-21 10:00:13 (11636): 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
2025-01-21 10:00:13 (11636): Guest Log: 2.5.2.0 4128 0 41676 20918 3 1 1777129 4096001 0 65024 0 0 n/a 6583 3363 http://s1ihep-cvmfs.openhtc.io/cvmfs/alice.cern.ch DIRECT 1
2025-01-21 10:00:13 (11636): Guest Log: 10:00:12 CET +01:00 2025-01-21: cranky: [INFO] Checking runc.
2025-01-21 10:00:14 (11636): Guest Log: 10:00:13 CET +01:00 2025-01-21: cranky: [INFO] Creating the filesystem.
2025-01-21 10:00:14 (11636): Guest Log: 10:00:13 CET +01:00 2025-01-21: cranky: [INFO] Using /cvmfs/cernvm-prod.cern.ch/cvm4
2025-01-21 10:00:14 (11636): Guest Log: 10:00:13 CET +01:00 2025-01-21: cranky: [INFO] Updating config.json.
2025-01-21 10:00:14 (11636): Guest Log: 10:00:13 CET +01:00 2025-01-21: cranky: [INFO] Running Container 'runc'.
2025-01-21 10:00:15 (11636): Guest Log: job: htmld=/shared/html/job
2025-01-21 10:00:15 (11636): Guest Log: job: unpack exitcode=0
2025-01-21 10:00:15 (11636): Guest Log: INFO: activated the work-around for ld:
2025-01-21 10:00:15 (11636): Guest Log: lrwxrwxrwx 1 0 0 15 Jan 21 09:00 /tmp/tmp.AZ3h4azZak/ld -> /usr/bin/ld.bfd
2025-01-21 10:00:16 (11636): Guest Log: 10:00:15 CET +01:00 2025-01-21: cranky: [INFO] ===> [runRivet] Tue Jan 21 09:00:14 UTC 2025 [boinc pp jets 13000 150,-,1860 - powheg-box r3744 pthard2 100000 284]
2025-01-21 10:44:19 (11636): VM state change detected. (old = 'running', new = 'paused')
2025-01-21 10:55:09 (11636): Error in resume VM for VM: -182
Command:
VBoxManage -q controlvm "boinc_6326e43d11a75aac" resume
Output:
VBoxManage.exe: error: Cannot resume the machine as it is not paused (machine state: Running)
VBoxManage.exe: error: Details: code VBOX_E_INVALID_VM_STATE (0x80bb0002), component ConsoleWrap, interface IConsole, callee IUnknown
VBoxManage.exe: error: Context: "Resume()" at line 395 of file VBoxManageControlVM.cpp
2025-01-21 10:55:10 (11636): VM state change detected. (old = 'paused', new = 'running')
2025-01-21 10:55:15 (11636): VM state change detected. (old = 'running', new = 'paused')
2025-01-21 10:55:35 (11636): VM state change detected. (old = 'paused', new = 'running')
2025-01-21 11:35:28 (11636): VM state change detected. (old = 'running', new = 'paused')
2025-01-21 11:35:48 (11636): VM state change detected. (old = 'paused', new = 'running')
2025-01-21 11:46:19 (11636): VM state change detected. (old = 'running', new = 'paused')
2025-01-21 11:46:39 (11636): VM state change detected. (old = 'paused', new = 'running')
2025-01-21 11:50:01 (11636): VM state change detected. (old = 'running', new = 'paused')
2025-01-21 11:50:20 (11636): VM state change detected. (old = 'paused', new = 'running')
2025-01-21 11:50:57 (11636): VM state change detected. (old = 'running', new = 'paused')
2025-01-21 11:51:18 (11636): VM state change detected. (old = 'paused', new = 'running')
2025-01-21 11:51:43 (11636): Status Report: Job Duration: '864000.000000'
2025-01-21 11:51:43 (11636): Status Report: Elapsed Time: '21964.000000'
2025-01-21 11:51:43 (11636): Status Report: CPU Time: '12752.593750'
2025-01-21 11:52:38 (11636): VM state change detected. (old = 'running', new = 'paused')
2025-01-21 11:52:39 (11636): VM state change detected. (old = 'paused', new = 'running')
2025-01-21 11:56:09 (11636): VM state change detected. (old = 'running', new = 'paused')
2025-01-21 11:56:29 (11636): VM state change detected. (old = 'paused', new = 'running')
2025-01-21 11:56:40 (11636): VM state change detected. (old = 'running', new = 'paused')
2025-01-21 11:56:41 (11636): VM state change detected. (old = 'paused', new = 'running')
2025-01-21 12:03:42 (11636): VM state change detected. (old = 'running', new = 'paused')
2025-01-21 12:04:02 (11636): VM state change detected. (old = 'paused', new = 'running')
2025-01-21 12:08:23 (11636): VM state change detected. (old = 'running', new = 'paused')
2025-01-21 12:08:43 (11636): VM state change detected. (old = 'paused', new = 'running')
2025-01-21 12:43:00 (11636): VM state change detected. (old = 'running', new = 'paused')
2025-01-21 14:02:20 (11636): Error in resume VM for VM: -182
Command:
VBoxManage -q controlvm "boinc_6326e43d11a75aac" resume
Output:
VBoxManage.exe: error: Cannot resume the machine as it is not paused (machine state: Running)
VBoxManage.exe: error: Details: code VBOX_E_INVALID_VM_STATE (0x80bb0002), component ConsoleWrap, interface IConsole, callee IUnknown
VBoxManage.exe: error: Context: "Resume()" at line 395 of file VBoxManageControlVM.cpp
2025-01-21 14:02:21 (11636): VM state change detected. (old = 'paused', new = 'running')
2025-01-21 14:02:22 (11636): Guest Log: 02:30:13.633483 timesync vgsvcTimeSyncWorker: Radical host time change: 4 767 443 000 000ns (HostNow=1 737 464 542 374 000 000 ns HostLast=1 737 459 774 931 000 000 ns)
2025-01-21 14:02:29 (11636): VM state change detected. (old = 'running', new = 'paused')
2025-01-21 14:02:50 (11636): VM state change detected. (old = 'paused', new = 'running')
2025-01-21 14:02:53 (11636): Guest Log: 02:30:23.635708 timesync vgsvcTimeSyncWorker: Radical guest time change: 5 571 453 196 000ns (GuestNow=1 737 464 552 435 304 000 ns GuestLast=1 737 458 980 982 108 000 ns fSetTimeLastLoop=true )
2025-01-21 14:03:49 (11636): VM state change detected. (old = 'running', new = 'paused')
2025-01-21 14:04:09 (11636): VM state change detected. (old = 'paused', new = 'running')
2025-01-21 14:12:59 (11636): VM state change detected. (old = 'running', new = 'paused')
2025-01-21 14:13:21 (11636): VM state change detected. (old = 'paused', new = 'running')
2025-01-21 14:33:44 (11636): VM state change detected. (old = 'running', new = 'paused')
2025-01-21 14:34:24 (11636): VM state change detected. (old = 'paused', new = 'running')
2025-01-21 14:42:34 (11636): Stopping VM.
2025-01-21 14:42:37 (11636): Successfully stopped VM.
2025-01-21 14:43:23 (10472): vboxwrapper version 26208
2025-01-21 14:43:23 (10472): BOINC client version: 7.24.1
2025-01-21 14:43:24 (10472): Detected: VirtualBox VboxManage Interface (Version: 7.0.6)
2025-01-21 14:43:24 (10472): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2025-01-21 14:43:25 (10472): Guest Log: BIOS: VirtualBox 7.0.6
2025-01-21 14:43:25 (10472): Guest Log: CPUID EDX: 0x178bfbff
2025-01-21 14:43:25 (10472): Guest Log: BIOS: No PCI IDE controller, not probing IDE
2025-01-21 14:43:25 (10472): Guest Log: BIOS: AHCI 0-P#0: PCHS=16383/16/63 LCHS=1024/255/63 0x0000000002800000 sectors
2025-01-21 14:43:25 (10472): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032
2025-01-21 14:43:25 (10472): Guest Log: BIOS: Booting from Hard Disk...
2025-01-21 14:43:25 (10472): Guest Log: BIOS: KBD: unsupported int 16h function 03
2025-01-21 14:43:25 (10472): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000
2025-01-21 14:43:25 (10472): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds
2025-01-21 14:43:25 (10472): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000)
2025-01-21 14:43:25 (10472): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log
2025-01-21 14:43:25 (10472): Guest Log: 00:00:00.000137 main Log opened 2025-01-21T08:59:27.451825000Z
2025-01-21 14:43:25 (10472): Guest Log: 00:00:00.000245 main OS Product: Linux
2025-01-21 14:43:25 (10472): Guest Log: 00:00:00.000279 main OS Release: 4.14.76-13.cernvm.x86_64
2025-01-21 14:43:25 (10472): Guest Log: 00:00:00.000319 main OS Version: #1 SMP Tue Oct 16 18:26:15 CEST 2018
2025-01-21 14:43:25 (10472): Guest Log: 00:00:00.000348 main Executable: /usr/sbin/VBoxService
2025-01-21 14:43:25 (10472): Guest Log: 00:00:00.000348 main Process ID: 3222
2025-01-21 14:43:25 (10472): Guest Log: 00:00:00.000349 main Package type: LINUX_64BITS_GENERIC
2025-01-21 14:43:25 (10472): Guest Log: 00:00:00.004251 main 5.2.6 r120293 started. Verbose level = 0
2025-01-21 14:43:25 (10472): Guest Log: 10:00:01 CET +01:00 2025-01-21: cranky: [INFO] Detected Theory App
2025-01-21 14:43:25 (10472): Guest Log: 10:00:01 CET +01:00 2025-01-21: cranky: [INFO] Checking CVMFS.
2025-01-21 14:43:25 (10472): Guest Log: Probing /cvmfs/sft.cern.ch... OK
2025-01-21 14:43:25 (10472): 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
2025-01-21 14:43:25 (10472): Guest Log: 2.5.2.0 4078 0 28312 31532 3 1 1777129 4096001 0 65024 0 0 n/a 5 5 http://s1cern-cvmfs.openhtc.io/cvmfs/sft.cern.ch DIRECT 1
2025-01-21 14:43:25 (10472): Guest Log: Probing /cvmfs/grid.cern.ch... OK
2025-01-21 14:43:25 (10472): 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
2025-01-21 14:43:25 (10472): Guest Log: 2.5.2.0 4104 0 27420 26279 3 2 1777129 4096001 0 65024 0 2 0 7947 4454 http://s1ral-cvmfs.openhtc.io/cvmfs/grid.cern.ch DIRECT 1
2025-01-21 14:43:25 (10472): Guest Log: Probing /cvmfs/cernvm-prod.cern.ch... OK
2025-01-21 14:43:25 (10472): 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
2025-01-21 14:43:25 (10472): Guest Log: 2.5.2.0 4057 0 25184 272 3 1 1777129 4096001 0 65024 0 0 n/a 0 0 http://s1ihep-cvmfs.openhtc.io/cvmfs/cernvm-prod.cern.ch DIRECT 1
2025-01-21 14:43:25 (10472): Guest Log: Probing /cvmfs/alice.cern.ch... OK
2025-01-21 14:43:25 (10472): 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
2025-01-21 14:43:25 (10472): Guest Log: 2.5.2.0 4128 0 41676 20918 3 1 1777129 4096001 0 65024 0 0 n/a 6583 3363 http://s1ihep-cvmfs.openhtc.io/cvmfs/alice.cern.ch DIRECT 1
2025-01-21 14:43:25 (10472): Guest Log: 10:00:12 CET +01:00 2025-01-21: cranky: [INFO] Checking runc.
2025-01-21 14:43:25 (10472): Guest Log: 10:00:13 CET +01:00 2025-01-21: cranky: [INFO] Creating the filesystem.
2025-01-21 14:43:25 (10472): Guest Log: 10:00:13 CET +01:00 2025-01-21: cranky: [INFO] Using /cvmfs/cernvm-prod.cern.ch/cvm4
2025-01-21 14:43:25 (10472): Guest Log: 10:00:13 CET +01:00 2025-01-21: cranky: [INFO] Updating config.json.
2025-01-21 14:43:25 (10472): Guest Log: 10:00:13 CET +01:00 2025-01-21: cranky: [INFO] Running Container 'runc'.
2025-01-21 14:43:25 (10472): Guest Log: job: htmld=/shared/html/job
2025-01-21 14:43:25 (10472): Guest Log: job: unpack exitcode=0
2025-01-21 14:43:25 (10472): Guest Log: INFO: activated the work-around for ld:
2025-01-21 14:43:25 (10472): Guest Log: lrwxrwxrwx 1 0 0 15 Jan 21 09:00 /tmp/tmp.AZ3h4azZak/ld -> /usr/bin/ld.bfd
2025-01-21 14:43:25 (10472): Guest Log: 10:00:15 CET +01:00 2025-01-21: cranky: [INFO] ===> [runRivet] Tue Jan 21 09:00:14 UTC 2025 [boinc pp jets 13000 150,-,1860 - powheg-box r3744 pthard2 100000 284]
2025-01-21 14:43:25 (10472): Guest Log: 02:30:13.633483 timesync vgsvcTimeSyncWorker: Radical host time change: 4 767 443 000 000ns (HostNow=1 737 464 542 374 000 000 ns HostLast=1 737 459 774 931 000 000 ns)
2025-01-21 14:43:25 (10472): Guest Log: 02:30:23.635708 timesync vgsvcTimeSyncWorker: Radical guest time change: 5 571 453 196 000ns (GuestNow=1 737 464 552 435 304 000 ns GuestLast=1 737 458 980 982 108 000 ns fSetTimeLastLoop=true )
2025-01-21 14:43:25 (10472): Starting VM using VBoxManage interface. (boinc_6326e43d11a75aac, slot#1)
2025-01-21 14:43:40 (10472): Successfully started VM. (PID = '8900')
2025-01-21 14:43:40 (10472): Reporting VM Process ID to BOINC.
2025-01-21 14:43:40 (10472): VM state change detected. (old = 'poweredoff', new = 'running')
2025-01-21 14:43:40 (10472): Detected: Web Application Enabled (http://localhost:49952)
2025-01-21 14:43:40 (10472): Status Report: Job Duration: '864000.000000'
2025-01-21 14:43:40 (10472): Status Report: Elapsed Time: '27277.693626'
2025-01-21 14:43:40 (10472): Status Report: CPU Time: '15958.453125'
2025-01-21 14:43:40 (10472): Preference change detected
2025-01-21 14:43:40 (10472): Setting CPU throttle for VM. (60%)
2025-01-21 14:43:40 (10472): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2025-01-21 14:43:47 (10472): VM state change detected. (old = 'running', new = 'paused')
2025-01-21 14:44:08 (10472): VM state change detected. (old = 'paused', new = 'running')
2025-01-21 15:32:40 (10472): VM state change detected. (old = 'running', new = 'paused')
2025-01-21 15:33:00 (10472): VM state change detected. (old = 'paused', new = 'running')
2025-01-21 15:33:30 (10472): VM state change detected. (old = 'running', new = 'paused')
2025-01-21 15:33:50 (10472): VM state change detected. (old = 'paused', new = 'running')
2025-01-21 16:09:21 (10472): VM state change detected. (old = 'running', new = 'paused')
2025-01-21 16:09:42 (10472): VM state change detected. (old = 'paused', new = 'running')
2025-01-21 16:19:42 (10472): VM state change detected. (old = 'running', new = 'paused')
2025-01-21 16:20:02 (10472): VM state change detected. (old = 'paused', new = 'running')
2025-01-21 16:25:31 (10472): Status Report: Job Duration: '864000.000000'
2025-01-21 16:25:31 (10472): Status Report: Elapsed Time: '33277.693626'
2025-01-21 16:25:31 (10472): Status Report: CPU Time: '19570.828125'
2025-01-21 17:13:34 (10472): VM state change detected. (old = 'running', new = 'paused')
2025-01-21 17:13:55 (10472): VM state change detected. (old = 'paused', new = 'running')
2025-01-21 18:05:57 (10472): Status Report: Job Duration: '864000.000000'
2025-01-21 18:05:57 (10472): Status Report: Elapsed Time: '39277.693626'
2025-01-21 18:05:57 (10472): Status Report: CPU Time: '23181.812500'
2025-01-21 18:23:57 (10472): VM state change detected. (old = 'running', new = 'paused')
2025-01-21 18:24:20 (10472): VM state change detected. (old = 'paused', new = 'running')
2025-01-21 18:25:59 (10472): VM state change detected. (old = 'running', new = 'paused')
2025-01-21 18:26:19 (10472): VM state change detected. (old = 'paused', new = 'running')
2025-01-21 19:46:44 (10472): Status Report: Job Duration: '864000.000000'
2025-01-21 19:46:44 (10472): Status Report: Elapsed Time: '45278.147407'
2025-01-21 19:46:44 (10472): Status Report: CPU Time: '26782.671875'
2025-01-21 20:46:10 (10472): VM state change detected. (old = 'running', new = 'paused')
2025-01-22 18:15:08 (10472): VM state change detected. (old = 'paused', new = 'running')
2025-01-22 18:15:15 (10472): Guest Log: 09:08:49.519865 timesync vgsvcTimeSyncWorker: Radical host time change: 77 347 495 000 000ns (HostNow=1 737 566 115 109 000 000 ns HostLast=1 737 488 767 614 000 000 ns)
2025-01-22 18:15:21 (10472): VM state change detected. (old = 'running', new = 'paused')
2025-01-22 18:15:39 (10472): VM state change detected. (old = 'paused', new = 'running')
2025-01-22 18:15:42 (10472): Guest Log: 09:08:59.521351 timesync vgsvcTimeSyncWorker: Radical guest time change: 77 501 650 555 000ns (GuestNow=1 737 566 125 110 475 000 ns GuestLast=1 737 488 623 459 920 000 ns fSetTimeLastLoop=true )
2025-01-22 18:18:20 (10472): VM state change detected. (old = 'running', new = 'paused')
2025-01-22 18:18:40 (10472): VM state change detected. (old = 'paused', new = 'running')
2025-01-22 18:19:51 (10472): VM state change detected. (old = 'running', new = 'paused')
2025-01-22 18:20:11 (10472): VM state change detected. (old = 'paused', new = 'running')
2025-01-22 18:21:03 (10472): VM state change detected. (old = 'running', new = 'paused')
2025-01-23 18:09:32 (10472): Guest Log: 09:13:39.711262 timesync vgsvcTimeSyncWorker: Radical host time change: 85 719 689 000 000ns (HostNow=1 737 652 172 089 000 000 ns HostLast=1 737 566 452 400 000 000 ns)
2025-01-23 18:09:33 (10472): VM state change detected. (old = 'paused', new = 'running')
2025-01-23 18:09:42 (10472): Guest Log: 09:13:49.759857 timesync vgsvcTimeSyncWorker: Radical guest time change: 85 776 837 226 000ns (GuestNow=1 737 652 182 137 609 000 ns GuestLast=1 737 566 405 300 383 000 ns fSetTimeLastLoop=true )
2025-01-23 18:09:43 (10472): VM state change detected. (old = 'running', new = 'paused')
2025-01-23 18:10:03 (10472): VM state change detected. (old = 'paused', new = 'running')
2025-01-23 18:10:25 (10472): VM state change detected. (old = 'running', new = 'paused')
2025-01-23 18:10:26 (10472): VM state change detected. (old = 'paused', new = 'running')
2025-01-23 18:11:35 (10472): VM state change detected. (old = 'running', new = 'paused')
2025-01-23 18:11:55 (10472): VM state change detected. (old = 'paused', new = 'running')
2025-01-23 18:12:28 (10472): VM state change detected. (old = 'running', new = 'paused')
2025-01-24 18:12:14 (10472): Error in resume VM for VM: -182
Command:
VBoxManage -q controlvm "boinc_6326e43d11a75aac" resume
Output:
VBoxManage.exe: error: Cannot resume the machine as it is not paused (machine state: Running)
VBoxManage.exe: error: Details: code VBOX_E_INVALID_VM_STATE (0x80bb0002), component ConsoleWrap, interface IConsole, callee IUnknown
VBoxManage.exe: error: Context: "Resume()" at line 395 of file VBoxManageControlVM.cpp
2025-01-24 18:12:15 (10472): Guest Log: 09:15:59.952945 timesync vgsvcTimeSyncWorker: Radical host time change: 86 389 643 000 000ns (HostNow=1 737 738 732 874 000 000 ns HostLast=1 737 652 343 231 000 000 ns)
2025-01-24 18:12:15 (10472): VM state change detected. (old = 'paused', new = 'running')
2025-01-24 18:12:19 (10472): VM state change detected. (old = 'running', new = 'paused')
2025-01-24 18:12:20 (10472): VM state change detected. (old = 'paused', new = 'running')
2025-01-24 18:12:24 (10472): Guest Log: 09:16:09.959836 timesync vgsvcTimeSyncWorker: Radical guest time change: 86 430 550 201 000ns (GuestNow=1 737 738 742 880 879 000 ns GuestLast=1 737 652 312 330 678 000 ns fSetTimeLastLoop=true )
2025-01-24 18:14:35 (10472): VM state change detected. (old = 'running', new = 'paused')
2025-01-24 18:14:56 (10472): VM state change detected. (old = 'paused', new = 'running')
2025-01-24 18:18:48 (10472): VM state change detected. (old = 'running', new = 'paused')
2025-01-24 18:19:06 (10472): VM state change detected. (old = 'paused', new = 'running')
2025-01-24 18:20:04 (10472): VM state change detected. (old = 'running', new = 'paused')
2025-01-25 18:13:48 (10472): Error in resume VM for VM: -182
Command:
VBoxManage -q controlvm "boinc_6326e43d11a75aac" resume
Output:
VBoxManage.exe: error: Cannot resume the machine as it is not paused (machine state: Running)
VBoxManage.exe: error: Details: code VBOX_E_INVALID_VM_STATE (0x80bb0002), component ConsoleWrap, interface IConsole, callee IUnknown
VBoxManage.exe: error: Context: "Resume()" at line 395 of file VBoxManageControlVM.cpp
2025-01-25 18:13:49 (10472): VM state change detected. (old = 'paused', new = 'running')
2025-01-25 18:13:50 (10472): Guest Log: 09:23:20.422020 timesync vgsvcTimeSyncWorker: Radical host time change: 86 027 929 000 000ns (HostNow=1 737 825 230 968 000 000 ns HostLast=1 737 739 203 039 000 000 ns)
2025-01-25 18:13:53 (10472): VM state change detected. (old = 'running', new = 'paused')
2025-01-25 18:14:14 (10472): VM state change detected. (old = 'paused', new = 'running')
2025-01-25 18:14:21 (10472): Guest Log: 09:23:30.424355 timesync vgsvcTimeSyncWorker: Radical guest time change: 86 067 627 310 000ns (GuestNow=1 737 825 240 970 357 000 ns GuestLast=1 737 739 173 343 047 000 ns fSetTimeLastLoop=true )
2025-01-25 18:16:54 (10472): VM state change detected. (old = 'running', new = 'paused')
2025-01-25 18:17:15 (10472): VM state change detected. (old = 'paused', new = 'running')
2025-01-25 18:17:25 (10472): VM state change detected. (old = 'running', new = 'paused')
2025-01-25 18:17:45 (10472): VM state change detected. (old = 'paused', new = 'running')
2025-01-25 18:18:38 (10472): VM state change detected. (old = 'running', new = 'paused')
2025-01-25 21:35:15 (10472): VM state change detected. (old = 'paused', new = 'running')
2025-01-25 21:35:16 (10472): VM state change detected. (old = 'running', new = 'paused')
2025-01-25 21:35:17 (10472): VM state change detected. (old = 'paused', new = 'running')
2025-01-25 21:35:20 (10472): Guest Log: 09:27:10.597425 timesync vgsvcTimeSyncWorker: Radical host time change: 11 808 984 000 000ns (HostNow=1 737 837 320 418 000 000 ns HostLast=1 737 825 511 434 000 000 ns)
2025-01-25 21:35:27 (10472): VM state change detected. (old = 'running', new = 'paused')
2025-01-25 21:35:47 (10472): VM state change detected. (old = 'paused', new = 'running')
2025-01-25 21:35:50 (10472): Guest Log: 09:27:20.635692 timesync vgsvcTimeSyncWorker: Radical guest time change: 11 869 312 858 000ns (GuestNow=1 737 837 330 456 283 000 ns GuestLast=1 737 825 461 143 425 000 ns fSetTimeLastLoop=true )
2025-01-25 21:58:41 (10472): Status Report: Job Duration: '864000.000000'
2025-01-25 21:58:41 (10472): Status Report: Elapsed Time: '51278.147407'
2025-01-25 21:58:41 (10472): Status Report: CPU Time: '30419.890625'
2025-01-25 22:35:41 (10472): VM state change detected. (old = 'running', new = 'paused')
2025-01-26 09:07:57 (10472): Guest Log: 10:27:13.157980 timesync vgsvcTimeSyncWorker: Radical host time change: 37 945 889 000 000ns (HostNow=1 737 878 877 067 000 000 ns HostLast=1 737 840 931 178 000 000 ns)
2025-01-26 09:07:57 (10472): VM state change detected. (old = 'paused', new = 'running')
2025-01-26 09:08:07 (10472): Guest Log: 10:27:23.202019 timesync vgsvcTimeSyncWorker: Radical guest time change: 37 964 132 496 000ns (GuestNow=1 737 878 887 111 051 000 ns GuestLast=1 737 840 922 978 555 000 ns fSetTimeLastLoop=true )
2025-01-26 09:12:29 (10472): VM state change detected. (old = 'running', new = 'paused')
2025-01-26 09:12:50 (10472): VM state change detected. (old = 'paused', new = 'running')
2025-01-26 11:44:30 (8704): vboxwrapper version 26208
2025-01-26 11:44:30 (8704): BOINC client version: 7.24.1
2025-01-26 11:44:31 (8704): Detected: VirtualBox VboxManage Interface (Version: 7.0.6)
2025-01-26 11:44:31 (8704): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2025-01-26 11:44:32 (8704): Guest Log: 09:08:49.519865 timesync vgsvcTimeSyncWorker: Radical host time change: 77 347 495 000 000ns (HostNow=1 737 566 115 109 000 000 ns HostLast=1 737 488 767 614 000 000 ns)
2025-01-26 11:44:32 (8704): Guest Log: 09:08:59.521351 timesync vgsvcTimeSyncWorker: Radical guest time change: 77 501 650 555 000ns (GuestNow=1 737 566 125 110 475 000 ns GuestLast=1 737 488 623 459 920 000 ns fSetTimeLastLoop=true )
2025-01-26 11:44:32 (8704): Guest Log: 09:13:39.711262 timesync vgsvcTimeSyncWorker: Radical host time change: 85 719 689 000 000ns (HostNow=1 737 652 172 089 000 000 ns HostLast=1 737 566 452 400 000 000 ns)
2025-01-26 11:44:32 (8704): Guest Log: 09:13:49.759857 timesync vgsvcTimeSyncWorker: Radical guest time change: 85 776 837 226 000ns (GuestNow=1 737 652 182 137 609 000 ns GuestLast=1 737 566 405 300 383 000 ns fSetTimeLastLoop=true )
2025-01-26 11:44:32 (8704): Guest Log: 09:15:59.952945 timesync vgsvcTimeSyncWorker: Radical host time change: 86 389 643 000 000ns (HostNow=1 737 738 732 874 000 000 ns HostLast=1 737 652 343 231 000 000 ns)
2025-01-26 11:44:32 (8704): Guest Log: 09:16:09.959836 timesync vgsvcTimeSyncWorker: Radical guest time change: 86 430 550 201 000ns (GuestNow=1 737 738 742 880 879 000 ns GuestLast=1 737 652 312 330 678 000 ns fSetTimeLastLoop=true )
2025-01-26 11:44:32 (8704): Guest Log: 09:23:20.422020 timesync vgsvcTimeSyncWorker: Radical host time change: 86 027 929 000 000ns (HostNow=1 737 825 230 968 000 000 ns HostLast=1 737 739 203 039 000 000 ns)
2025-01-26 11:44:32 (8704): Guest Log: 09:23:30.424355 timesync vgsvcTimeSyncWorker: Radical guest time change: 86 067 627 310 000ns (GuestNow=1 737 825 240 970 357 000 ns GuestLast=1 737 739 173 343 047 000 ns fSetTimeLastLoop=true )
2025-01-26 11:44:32 (8704): Guest Log: 09:27:10.597425 timesync vgsvcTimeSyncWorker: Radical host time change: 11 808 984 000 000ns (HostNow=1 737 837 320 418 000 000 ns HostLast=1 737 825 511 434 000 000 ns)
2025-01-26 11:44:32 (8704): Guest Log: 09:27:20.635692 timesync vgsvcTimeSyncWorker: Radical guest time change: 11 869 312 858 000ns (GuestNow=1 737 837 330 456 283 000 ns GuestLast=1 737 825 461 143 425 000 ns fSetTimeLastLoop=true )
2025-01-26 11:44:32 (8704): Guest Log: 10:27:13.157980 timesync vgsvcTimeSyncWorker: Radical host time change: 37 945 889 000 000ns (HostNow=1 737 878 877 067 000 000 ns HostLast=1 737 840 931 178 000 000 ns)
2025-01-26 11:44:32 (8704): Guest Log: 10:27:23.202019 timesync vgsvcTimeSyncWorker: Radical guest time change: 37 964 132 496 000ns (GuestNow=1 737 878 887 111 051 000 ns GuestLast=1 737 840 922 978 555 000 ns fSetTimeLastLoop=true )
2025-01-26 11:44:32 (8704): Starting VM using VBoxManage interface. (boinc_6326e43d11a75aac, slot#1)
2025-01-26 11:44:39 (8704): Successfully started VM. (PID = '4260')
2025-01-26 11:44:39 (8704): Reporting VM Process ID to BOINC.
2025-01-26 11:44:39 (8704): Guest Log: BIOS: VirtualBox 7.0.6
2025-01-26 11:44:39 (8704): Guest Log: CPUID EDX: 0x178bfbff
2025-01-26 11:44:39 (8704): Guest Log: BIOS: No PCI IDE controller, not probing IDE
2025-01-26 11:44:39 (8704): Guest Log: BIOS: AHCI 0-P#0: PCHS=16383/16/63 LCHS=1024/255/63 0x0000000002800000 sectors
2025-01-26 11:44:39 (8704): VM state change detected. (old = 'poweredoff', new = 'running')
2025-01-26 11:44:39 (8704): Detected: Web Application Enabled (http://localhost:49952)
2025-01-26 11:44:39 (8704): Status Report: Job Duration: '864000.000000'
2025-01-26 11:44:39 (8704): Status Report: Elapsed Time: '53888.147407'
2025-01-26 11:44:39 (8704): Status Report: CPU Time: '31998.000000'
2025-01-26 11:44:39 (8704): Preference change detected
2025-01-26 11:44:39 (8704): Setting CPU throttle for VM. (60%)
2025-01-26 11:44:39 (8704): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2025-01-26 11:44:41 (8704): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032
2025-01-26 11:44:41 (8704): Guest Log: BIOS: Booting from Hard Disk...
2025-01-26 11:44:44 (8704): Guest Log: BIOS: KBD: unsupported int 16h function 03
2025-01-26 11:44:44 (8704): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000
2025-01-26 11:45:12 (8704): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds
2025-01-26 11:45:12 (8704): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000)
2025-01-26 11:45:17 (8704): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log
2025-01-26 11:45:17 (8704): Guest Log: 00:00:00.000151 main Log opened 2025-01-26T10:45:16.407195000Z
2025-01-26 11:45:17 (8704): Guest Log: 00:00:00.000253 main OS Product: Linux
2025-01-26 11:45:17 (8704): Guest Log: 00:00:00.000296 main OS Release: 4.14.76-13.cernvm.x86_64
2025-01-26 11:45:17 (8704): Guest Log: 00:00:00.000335 main OS Version: #1 SMP Tue Oct 16 18:26:15 CEST 2018
2025-01-26 11:45:17 (8704): Guest Log: 00:00:00.000373 main Executable: /usr/sbin/VBoxService
2025-01-26 11:45:17 (8704): Guest Log: 00:00:00.000374 main Process ID: 3219
2025-01-26 11:45:17 (8704): Guest Log: 00:00:00.000375 main Package type: LINUX_64BITS_GENERIC
2025-01-26 11:45:17 (8704): Guest Log: 00:00:00.003767 main 5.2.6 r120293 started. Verbose level = 0
2025-01-26 11:45:51 (8704): Guest Log: 11:45:50 CET +01:00 2025-01-26: cranky: [INFO] Detected Theory App
2025-01-26 11:45:51 (8704): Guest Log: 11:45:50 CET +01:00 2025-01-26: cranky: [INFO] Checking CVMFS.
2025-01-26 11:45:52 (8704): Guest Log: Probing /cvmfs/sft.cern.ch... OK
2025-01-26 11:45:53 (8704): 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
2025-01-26 11:45:53 (8704): Guest Log: 2.5.2.0 4125 0 28276 31565 3 1 1812983 4096001 0 65024 0 0 n/a 5 1 http://s1unl-cvmfs.openhtc.io/cvmfs/sft.cern.ch DIRECT 1
2025-01-26 11:45:55 (8704): Guest Log: Probing /cvmfs/grid.cern.ch... OK
2025-01-26 11:45:56 (8704): 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
2025-01-26 11:45:56 (8704): Guest Log: 2.5.2.0 4096 0 27528 26319 3 2 1812983 4096001 0 65024 0 2 0 7945 3836 http://s1cern-cvmfs.openhtc.io/cvmfs/grid.cern.ch DIRECT 1
2025-01-26 11:45:57 (8704): Guest Log: Probing /cvmfs/cernvm-prod.cern.ch... OK
2025-01-26 11:45:58 (8704): 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
2025-01-26 11:45:58 (8704): Guest Log: 2.5.2.0 4050 0 27208 272 3 1 1812983 4096001 0 65024 0 0 n/a 0 0 http://s1cern-cvmfs.openhtc.io/cvmfs/cernvm-prod.cern.ch DIRECT 1
2025-01-26 11:46:00 (8704): Guest Log: Probing /cvmfs/alice.cern.ch... OK
2025-01-26 11:46:01 (8704): 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
2025-01-26 11:46:01 (8704): Guest Log: 2.5.2.0 4072 0 41684 20976 3 1 1812983 4096001 0 65024 0 0 n/a 6604 3701 http://s1unl-cvmfs.openhtc.io/cvmfs/alice.cern.ch DIRECT 1
2025-01-26 11:46:01 (8704): Guest Log: 11:46:00 CET +01:00 2025-01-26: cranky: [INFO] Checking runc.
2025-01-26 11:46:01 (8704): Guest Log: 11:46:00 CET +01:00 2025-01-26: cranky: [INFO] Creating the filesystem.
2025-01-26 11:46:01 (8704): Guest Log: 11:46:00 CET +01:00 2025-01-26: cranky: [INFO] Using /cvmfs/cernvm-prod.cern.ch/cvm4
2025-01-26 11:46:01 (8704): Guest Log: 11:46:01 CET +01:00 2025-01-26: cranky: [INFO] Updating config.json.
2025-01-26 11:46:01 (8704): Guest Log: 11:46:01 CET +01:00 2025-01-26: cranky: [INFO] Running Container 'runc'.
2025-01-26 11:46:02 (8704): Guest Log: job: htmld=/shared/html/job
2025-01-26 11:46:02 (8704): Guest Log: job: unpack exitcode=0
2025-01-26 11:46:02 (8704): Guest Log: INFO: activated the work-around for ld:
2025-01-26 11:46:03 (8704): Guest Log: lrwxrwxrwx 1 0 0 15 Jan 26 10:46 /tmp/tmp.gVANHQIE2P/ld -> /usr/bin/ld.bfd
2025-01-26 11:46:04 (8704): Guest Log: 11:46:03 CET +01:00 2025-01-26: cranky: [INFO] ===> [runRivet] Sun Jan 26 10:46:02 UTC 2025 [boinc pp jets 13000 150,-,1860 - powheg-box r3744 pthard2 100000 284]
2025-01-26 11:51:52 (8704): VM state change detected. (old = 'running', new = 'paused')
2025-01-26 11:52:13 (8704): VM state change detected. (old = 'paused', new = 'running')
2025-01-26 12:11:04 (8704): VM state change detected. (old = 'running', new = 'paused')
2025-01-26 12:11:24 (8704): VM state change detected. (old = 'paused', new = 'running')
2025-01-26 12:16:04 (8704): VM state change detected. (old = 'running', new = 'paused')
2025-01-26 12:16:24 (8704): VM state change detected. (old = 'paused', new = 'running')
2025-01-26 13:02:23 (8704): VM state change detected. (old = 'running', new = 'paused')
2025-01-26 14:07:45 (8704): VM state change detected. (old = 'paused', new = 'running')
2025-01-26 14:07:53 (8704): Guest Log: 01:16:15.386755 timesync vgsvcTimeSyncWorker: Radical host time change: 3 932 114 000 000ns (HostNow=1 737 896 872 988 000 000 ns HostLast=1 737 892 940 874 000 000 ns)
2025-01-26 14:08:03 (8704): Guest Log: 01:16:25.455004 timesync vgsvcTimeSyncWorker: Radical guest time change: 3 991 502 911 000ns (GuestNow=1 737 896 883 131 683 000 ns GuestLast=1 737 892 891 628 772 000 ns fSetTimeLastLoop=true )
2025-01-26 14:08:06 (8704): VM state change detected. (old = 'running', new = 'paused')
2025-01-26 14:08:26 (8704): VM state change detected. (old = 'paused', new = 'running')
2025-01-26 14:08:46 (8704): VM state change detected. (old = 'running', new = 'paused')
2025-01-26 14:09:07 (8704): VM state change detected. (old = 'paused', new = 'running')
2025-01-26 14:09:16 (8704): VM state change detected. (old = 'running', new = 'paused')
2025-01-26 14:09:36 (8704): VM state change detected. (old = 'paused', new = 'running')
2025-01-26 14:12:36 (8704): VM state change detected. (old = 'running', new = 'paused')
2025-01-26 14:12:56 (8704): VM state change detected. (old = 'paused', new = 'running')
2025-01-26 14:32:42 (8704): Status Report: Job Duration: '864000.000000'
2025-01-26 14:32:42 (8704): Status Report: Elapsed Time: '59888.147407'
2025-01-26 14:32:42 (8704): Status Report: CPU Time: '35620.062500'
2025-01-26 15:45:11 (8704): VM state change detected. (old = 'running', new = 'paused')
2025-01-26 16:41:30 (8704): Error in resume VM for VM: -182
Command:
VBoxManage -q controlvm "boinc_6326e43d11a75aac" resume
Output:
VBoxManage.exe: error: Cannot resume the machine as it is not paused (machine state: Running)
VBoxManage.exe: error: Details: code VBOX_E_INVALID_VM_STATE (0x80bb0002), component ConsoleWrap, interface IConsole, callee IUnknown
VBoxManage.exe: error: Context: "Resume()" at line 395 of file VBoxManageControlVM.cpp
2025-01-26 16:41:31 (8704): VM state change detected. (old = 'paused', new = 'running')
2025-01-26 16:41:33 (8704): Guest Log: 02:52:19.802539 timesync vgsvcTimeSyncWorker: Radical host time change: 3 387 645 000 000ns (HostNow=1 737 906 092 456 000 000 ns HostLast=1 737 902 704 811 000 000 ns)
2025-01-26 16:41:43 (8704): Guest Log: 02:52:29.803526 timesync vgsvcTimeSyncWorker: Radical guest time change: 3 464 977 798 000ns (GuestNow=1 737 906 102 457 002 000 ns GuestLast=1 737 902 637 479 204 000 ns fSetTimeLastLoop=true )
2025-01-26 17:09:09 (8704): Status Report: Job Duration: '864000.000000'
2025-01-26 17:09:09 (8704): Status Report: Elapsed Time: '65888.147407'
2025-01-26 17:09:09 (8704): Status Report: CPU Time: '39228.687500'
2025-01-27 11:17:45 (5420): vboxwrapper version 26208
2025-01-27 11:17:45 (5420): BOINC client version: 7.24.1
2025-01-27 11:17:46 (5420): Detected: VirtualBox VboxManage Interface (Version: 7.0.6)
2025-01-27 11:17:46 (5420): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2025-01-27 11:17:47 (5420): Guest Log: BIOS: VirtualBox 7.0.6
2025-01-27 11:17:47 (5420): Guest Log: CPUID EDX: 0x178bfbff
2025-01-27 11:17:47 (5420): Guest Log: BIOS: No PCI IDE controller, not probing IDE
2025-01-27 11:17:47 (5420): Guest Log: BIOS: AHCI 0-P#0: PCHS=16383/16/63 LCHS=1024/255/63 0x0000000002800000 sectors
2025-01-27 11:17:47 (5420): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032
2025-01-27 11:17:47 (5420): Guest Log: BIOS: Booting from Hard Disk...
2025-01-27 11:17:47 (5420): Guest Log: BIOS: KBD: unsupported int 16h function 03
2025-01-27 11:17:47 (5420): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000
2025-01-27 11:17:47 (5420): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds
2025-01-27 11:17:47 (5420): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000)
2025-01-27 11:17:47 (5420): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log
2025-01-27 11:17:47 (5420): Guest Log: 00:00:00.000151 main Log opened 2025-01-26T10:45:16.407195000Z
2025-01-27 11:17:47 (5420): Guest Log: 00:00:00.000253 main OS Product: Linux
2025-01-27 11:17:47 (5420): Guest Log: 00:00:00.000296 main OS Release: 4.14.76-13.cernvm.x86_64
2025-01-27 11:17:47 (5420): Guest Log: 00:00:00.000335 main OS Version: #1 SMP Tue Oct 16 18:26:15 CEST 2018
2025-01-27 11:17:47 (5420): Guest Log: 00:00:00.000373 main Executable: /usr/sbin/VBoxService
2025-01-27 11:17:47 (5420): Guest Log: 00:00:00.000374 main Process ID: 3219
2025-01-27 11:17:47 (5420): Guest Log: 00:00:00.000375 main Package type: LINUX_64BITS_GENERIC
2025-01-27 11:17:47 (5420): Guest Log: 00:00:00.003767 main 5.2.6 r120293 started. Verbose level = 0
2025-01-27 11:17:47 (5420): Guest Log: 11:45:50 CET +01:00 2025-01-26: cranky: [INFO] Detected Theory App
2025-01-27 11:17:47 (5420): Guest Log: 11:45:50 CET +01:00 2025-01-26: cranky: [INFO] Checking CVMFS.
2025-01-27 11:17:47 (5420): Guest Log: Probing /cvmfs/sft.cern.ch... OK
2025-01-27 11:17:47 (5420): 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
2025-01-27 11:17:47 (5420): Guest Log: 2.5.2.0 4125 0 28276 31565 3 1 1812983 4096001 0 65024 0 0 n/a 5 1 http://s1unl-cvmfs.openhtc.io/cvmfs/sft.cern.ch DIRECT 1
2025-01-27 11:17:47 (5420): Guest Log: Probing /cvmfs/grid.cern.ch... OK
2025-01-27 11:17:47 (5420): 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
2025-01-27 11:17:47 (5420): Guest Log: 2.5.2.0 4096 0 27528 26319 3 2 1812983 4096001 0 65024 0 2 0 7945 3836 http://s1cern-cvmfs.openhtc.io/cvmfs/grid.cern.ch DIRECT 1
2025-01-27 11:17:47 (5420): Guest Log: Probing /cvmfs/cernvm-prod.cern.ch... OK
2025-01-27 11:17:47 (5420): 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
2025-01-27 11:17:47 (5420): Guest Log: 2.5.2.0 4050 0 27208 272 3 1 1812983 4096001 0 65024 0 0 n/a 0 0 http://s1cern-cvmfs.openhtc.io/cvmfs/cernvm-prod.cern.ch DIRECT 1
2025-01-27 11:17:47 (5420): Guest Log: Probing /cvmfs/alice.cern.ch... OK
2025-01-27 11:17:47 (5420): 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
2025-01-27 11:17:47 (5420): Guest Log: 2.5.2.0 4072 0 41684 20976 3 1 1812983 4096001 0 65024 0 0 n/a 6604 3701 http://s1unl-cvmfs.openhtc.io/cvmfs/alice.cern.ch DIRECT 1
2025-01-27 11:17:47 (5420): Guest Log: 11:46:00 CET +01:00 2025-01-26: cranky: [INFO] Checking runc.
2025-01-27 11:17:47 (5420): Guest Log: 11:46:00 CET +01:00 2025-01-26: cranky: [INFO] Creating the filesystem.
2025-01-27 11:17:47 (5420): Guest Log: 11:46:00 CET +01:00 2025-01-26: cranky: [INFO] Using /cvmfs/cernvm-prod.cern.ch/cvm4
2025-01-27 11:17:47 (5420): Guest Log: 11:46:01 CET +01:00 2025-01-26: cranky: [INFO] Updating config.json.
2025-01-27 11:17:47 (5420): Guest Log: 11:46:01 CET +01:00 2025-01-26: cranky: [INFO] Running Container 'runc'.
2025-01-27 11:17:47 (5420): Guest Log: job: htmld=/shared/html/job
2025-01-27 11:17:47 (5420): Guest Log: job: unpack exitcode=0
2025-01-27 11:17:47 (5420): Guest Log: INFO: activated the work-around for ld:
2025-01-27 11:17:47 (5420): Guest Log: lrwxrwxrwx 1 0 0 15 Jan 26 10:46 /tmp/tmp.gVANHQIE2P/ld -> /usr/bin/ld.bfd
2025-01-27 11:17:47 (5420): Guest Log: 11:46:03 CET +01:00 2025-01-26: cranky: [INFO] ===> [runRivet] Sun Jan 26 10:46:02 UTC 2025 [boinc pp jets 13000 150,-,1860 - powheg-box r3744 pthard2 100000 284]
2025-01-27 11:17:47 (5420): Guest Log: 01:16:15.386755 timesync vgsvcTimeSyncWorker: Radical host time change: 3 932 114 000 000ns (HostNow=1 737 896 872 988 000 000 ns HostLast=1 737 892 940 874 000 000 ns)
2025-01-27 11:17:47 (5420): Guest Log: 01:16:25.455004 timesync vgsvcTimeSyncWorker: Radical guest time change: 3 991 502 911 000ns (GuestNow=1 737 896 883 131 683 000 ns GuestLast=1 737 892 891 628 772 000 ns fSetTimeLastLoop=true )
2025-01-27 11:17:47 (5420): Guest Log: 02:52:19.802539 timesync vgsvcTimeSyncWorker: Radical host time change: 3 387 645 000 000ns (HostNow=1 737 906 092 456 000 000 ns HostLast=1 737 902 704 811 000 000 ns)
2025-01-27 11:17:47 (5420): Guest Log: 02:52:29.803526 timesync vgsvcTimeSyncWorker: Radical guest time change: 3 464 977 798 000ns (GuestNow=1 737 906 102 457 002 000 ns GuestLast=1 737 902 637 479 204 000 ns fSetTimeLastLoop=true )
2025-01-27 11:17:47 (5420): Starting VM using VBoxManage interface. (boinc_6326e43d11a75aac, slot#1)
2025-01-27 11:18:00 (5420): Error in start VM for VM: -2147467259
Command:
VBoxManage -q startvm "boinc_6326e43d11a75aac" --type headless
Output:
VBoxManage.exe: error: The VM session was aborted
VBoxManage.exe: error: Details: code E_FAIL (0x80004005), component SessionMachine, interface ISession
Waiting for VM "boinc_6326e43d11a75aac" to power on...
2025-01-27 11:18:00 (5420): VM failed to start.
2025-01-27 11:18:00 (5420): Could not start
2025-01-27 11:18:00 (5420): ERROR: VM failed to start
2025-01-27 11:18:00 (5420): Powering off VM.
2025-01-27 11:18:00 (5420): Deregistering VM. (boinc_6326e43d11a75aac, slot#1)
2025-01-27 11:18:01 (5420): Removing network bandwidth throttle group from VM.
2025-01-27 11:18:01 (5420): Removing VM from VirtualBox.
Hypervisor System Log:
00:00:15.487573 ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={300763af-5d6b-46e6-aa96-273eac15538a} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
00:00:15.487581 ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={300763af-5d6b-46e6-aa96-273eac15538a} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
00:00:15.487591 ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={300763af-5d6b-46e6-aa96-273eac15538a} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
00:00:15.487600 ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={300763af-5d6b-46e6-aa96-273eac15538a} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
00:00:15.487608 ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={300763af-5d6b-46e6-aa96-273eac15538a} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
00:00:15.487616 ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={300763af-5d6b-46e6-aa96-273eac15538a} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
00:00:15.487623 ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={300763af-5d6b-46e6-aa96-273eac15538a} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
00:00:15.487630 ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={300763af-5d6b-46e6-aa96-273eac15538a} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
00:00:15.487637 ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={300763af-5d6b-46e6-aa96-273eac15538a} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
00:00:15.487645 ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={300763af-5d6b-46e6-aa96-273eac15538a} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
00:00:15.487770 Saving settings file "C:\Users\Casa\.VirtualBox\VirtualBox.xml" with version "1.12-windows"
00:00:15.493801 Finished saving settings file "C:\Users\Casa\.VirtualBox\VirtualBox.xml"
00:00:15.493897 ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={300763af-5d6b-46e6-aa96-273eac15538a} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
00:00:15.493916 ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={300763af-5d6b-46e6-aa96-273eac15538a} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
00:00:15.493926 ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={300763af-5d6b-46e6-aa96-273eac15538a} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
00:00:15.493933 ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={300763af-5d6b-46e6-aa96-273eac15538a} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
00:00:15.493952 ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={300763af-5d6b-46e6-aa96-273eac15538a} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
00:00:15.493974 ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={300763af-5d6b-46e6-aa96-273eac15538a} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
00:00:15.493989 ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={300763af-5d6b-46e6-aa96-273eac15538a} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
00:00:15.494003 ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={300763af-5d6b-46e6-aa96-273eac15538a} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
00:00:15.494017 ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={300763af-5d6b-46e6-aa96-273eac15538a} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
00:00:15.494030 ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={300763af-5d6b-46e6-aa96-273eac15538a} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
00:00:15.494048 ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={300763af-5d6b-46e6-aa96-273eac15538a} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
00:00:15.494064 ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={300763af-5d6b-46e6-aa96-273eac15538a} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
00:00:15.494362 Saving settings file "C:\ProgramData\BOINC\slots\4\boinc_eecbdcde21e54f3a\boinc_eecbdcde21e54f3a.vbox" with version "1.19-windows"
00:00:15.499658 Finished saving settings file "C:\ProgramData\BOINC\slots\4\boinc_eecbdcde21e54f3a\boinc_eecbdcde21e54f3a.vbox"
00:00:15.500747 DeleteVM ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={300763af-5d6b-46e6-aa96-273eac15538a} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
00:00:15.500789 DeleteVM ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={300763af-5d6b-46e6-aa96-273eac15538a} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
00:00:15.500806 DeleteVM ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={300763af-5d6b-46e6-aa96-273eac15538a} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
00:00:15.500820 DeleteVM ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={300763af-5d6b-46e6-aa96-273eac15538a} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
00:00:15.500833 DeleteVM ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={300763af-5d6b-46e6-aa96-273eac15538a} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
00:00:15.500847 DeleteVM ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={300763af-5d6b-46e6-aa96-273eac15538a} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
00:00:15.500861 DeleteVM ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={300763af-5d6b-46e6-aa96-273eac15538a} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
00:00:15.500874 DeleteVM ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={300763af-5d6b-46e6-aa96-273eac15538a} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
00:00:15.500888 DeleteVM ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={300763af-5d6b-46e6-aa96-273eac15538a} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
00:00:15.500902 DeleteVM ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={300763af-5d6b-46e6-aa96-273eac15538a} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
00:00:15.500915 DeleteVM ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={300763af-5d6b-46e6-aa96-273eac15538a} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
00:00:15.500942 DeleteVM ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={300763af-5d6b-46e6-aa96-273eac15538a} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
00:00:15.501325 DeleteVM Saving settings file "C:\ProgramData\BOINC\slots\4\boinc_eecbdcde21e54f3a\boinc_eecbdcde21e54f3a.vbox" with version "1.19-windows"
00:00:15.509284 DeleteVM Finished saving settings file "C:\ProgramData\BOINC\slots\4\boinc_eecbdcde21e54f3a\boinc_eecbdcde21e54f3a.vbox"
VM Execution Log:
VM Startup Log:
VM Trace Log:
ytes
Minimum video RAM size: 0 Megabytes
Maximum video RAM size: 256 Megabytes
Maximum guest monitor count: 64
Minimum guest CPU count: 1
Maximum guest CPU count: 64
Virtual disk limit (info): 2199022206976 Bytes
Maximum Serial Port count: 4
Maximum Parallel Port count: 2
Maximum Boot Position: 4
Maximum PIIX3 Network Adapter count: 8
Maximum ICH9 Network Adapter count: 36
Maximum PIIX3 IDE Controllers: 1
Maximum ICH9 IDE Controllers: 1
Maximum IDE Port count: 2
Maximum Devices per IDE Port: 2
Maximum PIIX3 SATA Controllers: 1
Maximum ICH9 SATA Controllers: 8
Maximum SATA Port count: 30
Maximum Devices per SATA Port: 1
Maximum PIIX3 SCSI Controllers: 1
Maximum ICH9 SCSI Controllers: 8
Maximum SCSI Port count: 16
Maximum Devices per SCSI Port: 1
Maximum SAS PIIX3 Controllers: 1
Maximum SAS ICH9 Controllers: 8
Maximum SAS Port count: 255
Maximum Devices per SAS Port: 1
Maximum NVMe PIIX3 Controllers: 1
Maximum NVMe ICH9 Controllers: 8
Maximum NVMe Port count: 255
Maximum Devices per NVMe Port: 1
Maximum virtio-scsi PIIX3 Controllers: 1
Maximum virtio-scsi ICH9 Controllers: 8
Maximum virtio-scsi Port count: 256
Maximum Devices per virtio-scsi Port: 1
Maximum PIIX3 Floppy Controllers:1
Maximum ICH9 Floppy Controllers: 1
Maximum Floppy Port count: 1
Maximum Devices per Floppy Port: 2
Default machine folder: C:\Users\Casa\VirtualBox VMs
Raw-mode Supported: no
Exclusive HW virtualization use: off
Default hard disk format: VDI
VRDE auth library: VBoxAuth
Webservice auth. library: VBoxAuth
Remote desktop ExtPack:
VM encryption ExtPack:
Log history count: 3
Default frontend:
Default audio driver: Windows Audio Session
Autostart database path:
Default Guest Additions ISO: C:\Program Files\Oracle\VirtualBox/VBoxGuestAdditions.iso
Logging Level: all
Proxy Mode: System
Proxy URL:
User language: C
2025-01-27 11:17:46 (5420):
Command: VBoxManage -q list hostinfo
Exit Code: 0
Output:
Host Information:
Host time: 2025-01-27T10:17:46.555000000Z
Processor online count: 8
Processor count: 8
Processor online core count: 4
Processor core count: 4
Processor supports HW virtualization: yes
Processor supports PAE: yes
Processor supports long mode: yes
Processor supports nested paging: yes
Processor supports unrestricted guest: yes
Processor supports nested HW virtualization: no
Processor supports virt. vmsave/vmload: no
Processor#0 speed: unknown
Processor#0 description: Intel(R) Core(TM) i7-6770HQ CPU @ 2.60GHz
Processor#1 speed: unknown
Processor#1 description: Intel(R) Core(TM) i7-6770HQ CPU @ 2.60GHz
Processor#2 speed: unknown
Processor#2 description: Intel(R) Core(TM) i7-6770HQ CPU @ 2.60GHz
Processor#3 speed: unknown
Processor#3 description: Intel(R) Core(TM) i7-6770HQ CPU @ 2.60GHz
Processor#4 speed: unknown
Processor#4 description: Intel(R) Core(TM) i7-6770HQ CPU @ 2.60GHz
Processor#5 speed: unknown
Processor#5 description: Intel(R) Core(TM) i7-6770HQ CPU @ 2.60GHz
Processor#6 speed: unknown
Processor#6 description: Intel(R) Core(TM) i7-6770HQ CPU @ 2.60GHz
Processor#7 speed: unknown
Processor#7 description: Intel(R) Core(TM) i7-6770HQ CPU @ 2.60GHz
Memory size: 8081 MByte
Memory available: 4660 MByte
Operating system: Windows 10
Operating system version: 10.0.19045
2025-01-27 11:17:47 (5420):
Command: VBoxManage -q showvminfo "boinc_6326e43d11a75aac" --machinereadable
Exit Code: 0
Output:
name="boinc_6326e43d11a75aac"
Encryption: disabled
groups="/"
ostype="Linux 2.6 / 3.x / 4.x / 5.x (64-bit)"
UUID="4848b78e-7ecb-4edd-9673-d00a40e5e00e"
CfgFile="C:\\ProgramData\\BOINC\\slots\\1\\boinc_6326e43d11a75aac\\boinc_6326e43d11a75aac.vbox"
SnapFldr="C:\\ProgramData\\BOINC\\slots\\1\\boinc_6326e43d11a75aac\\Snapshots"
LogFldr="C:\\ProgramData\\BOINC\\slots\\1\\boinc_6326e43d11a75aac\\Logs"
hardwareuuid="4848b78e-7ecb-4edd-9673-d00a40e5e00e"
memory=630
pagefusion="off"
vram=16
cpuexecutioncap=60
hpet="off"
cpu-profile="host"
chipset="piix3"
firmware="BIOS"
cpus=1
pae="on"
longmode="on"
triplefaultreset="off"
apic="on"
x2apic="on"
nested-hw-virt="off"
cpuid-portability-level=0
bootmenu="messageandmenu"
boot1="disk"
boot2="dvd"
boot3="none"
boot4="none"
acpi="on"
ioapic="on"
biosapic="apic"
biossystemtimeoffset=0
BIOS NVRAM File="C:\\ProgramData\\BOINC\\slots\\1\\boinc_6326e43d11a75aac\\boinc_6326e43d11a75aac.nvram"
rtcuseutc="off"
hwvirtex="on"
nestedpaging="on"
largepages="on"
vtxvpid="on"
vtxux="on"
virtvmsavevmload="on"
iommu="none"
paravirtprovider="default"
effparavirtprovider="kvm"
VMState="aborted"
VMStateChangeTime="2025-01-26T16:20:42.000000000"
graphicscontroller="vboxvga"
monitorcount=1
accelerate3d="off"
accelerate2dvideo="off"
teleporterenabled="off"
teleporterport=0
teleporteraddress=""
teleporterpassword=""
tracing-enabled="off"
tracing-allow-vm-access="off"
tracing-config=""
autostart-enabled="off"
autostart-delay=0
defaultfrontend=""
vmprocpriority="default"
storagecontrollername0="Hard Disk Controller"
storagecontrollertype0="IntelAhci"
storagecontrollerinstance0="0"
storagecontrollermaxportcount0="30"
storagecontrollerportcount0="3"
storagecontrollerbootable0="on"
"Hard Disk Controller-0-0"="C:\\ProgramData\\BOINC\\slots\\1\\boinc_6326e43d11a75aac\\Snapshots/{7598b504-a1e5-4a54-82b9-b51bea1087e3}.vdi"
"Hard Disk Controller-ImageUUID-0-0"="7598b504-a1e5-4a54-82b9-b51bea1087e3"
"Hard Disk Controller-nonrotational-0-0"="off"
"Hard Disk Controller-discard-0-0"="off"
"Hard Disk Controller-1-0"="emptydrive"
"Hard Disk Controller-IsEjected-1-0"="off"
"Hard Disk Controller-2-0"="none"
natnet1="nat"
macaddress1="080027008488"
cableconnected1="on"
nic1="nat"
nictype1="82540EM"
nicspeed1="0"
mtu="0"
sockSnd="64"
sockRcv="64"
tcpWndSnd="64"
tcpWndRcv="64"
Forwarding(0)="tcp_49952_80,tcp,127.0.0.1,49952,,80"
nic2="none"
nic3="none"
nic4="none"
nic5="none"
nic6="none"
nic7="none"
nic8="none"
hidpointing="ps2mouse"
hidkeyboard="ps2kbd"
uart1="off"
uart2="off"
uart3="off"
uart4="off"
lpt1="off"
lpt2="off"
audio="none"
audio_out="off"
audio_in="off"
clipboard="disabled"
draganddrop="disabled"
vrde="off"
usb="off"
ehci="off"
xhci="off"
BandwidthGroup0="boinc_6326e43d11a75aac_net",Network,1099511627776
SharedFolderNameMachineMapping1="shared"
SharedFolderPathMachineMapping1="C:\\ProgramData\\BOINC\\slots\\1\\shared"
recording_enabled="off"
recording_screens=1
rec_screen0
rec_screen_enabled="on"
rec_screen_id=0
rec_screen_video_enabled="on"
rec_screen_audio_enabled="off"
rec_screen_dest="File"
rec_screen_dest_filename="C:\\ProgramData\\BOINC\\slots\\1\\boinc_6326e43d11a75aac\\boinc_6326e43d11a75aac-screen0.webm"
rec_screen_opts="vc_enabled=true,ac_enabled=false,ac_profile=med"
rec_screen_video_res_xy="1024x768"
rec_screen_video_rate_kbps=512
rec_screen_video_fps=25
description="Theory_2773-2922189-284_2"
GuestMemoryBalloon=0
2025-01-27 11:18:00 (5420):
Command: VBoxManage -q startvm "boinc_6326e43d11a75aac" --type headless
Exit Code: -2147467259
Output:
VBoxManage.exe: error: The VM session was aborted
VBoxManage.exe: error: Details: code E_FAIL (0x80004005), component SessionMachine, interface ISession
Waiting for VM "boinc_6326e43d11a75aac" to power on...
2025-01-27 11:18:01 (5420):
Command: VBoxManage -q snapshot "boinc_6326e43d11a75aac" list
Exit Code: -108
Output:
This machine does not have any snapshots
2025-01-27 11:18:01 (5420):
Command: VBoxManage -q bandwidthctl "boinc_6326e43d11a75aac" remove "boinc_6326e43d11a75aac_net"
Exit Code: 0
Output:
2025-01-27 11:18:01 (5420):
Command: VBoxManage -q unregistervm "boinc_6326e43d11a75aac" --delete
Exit Code: 0
Output:
0%...10%...20%...30%...40%...50%...60%...70%...80%...90%...100%
2025-01-27 11:18:11 (5420): called boinc_finish(-2147467259)
</stderr_txt>
]]>
©2025 CERN