| Name | Theory_2773-3002937-136_0 |
| Workunit | 229755553 |
| Created | 12 Jan 2025, 0:21:44 UTC |
| Sent | 12 Jan 2025, 8:47:34 UTC |
| Report deadline | 23 Jan 2025, 8:47:34 UTC |
| Received | 18 Jan 2025, 14:28:38 UTC |
| Server state | Over |
| Outcome | Computation error |
| Client state | Compute error |
| Exit status | -2147467259 (0x80004005) Unknown error code |
| Computer ID | 10774382 |
| Run time | 16 hours 9 min 19 sec |
| CPU time | 11 hours 50 min 18 sec |
| Validate state | Invalid |
| Credit | 0.00 |
| Device peak FLOPS | 4.73 GFLOPS |
| Application version | Theory Simulation v300.30 (vbox64_theory) windows_x86_64 |
| Peak working set size | 123.14 MB |
| Peak swap size | 61.72 MB |
| Peak disk usage | 2.12 GB |
Stderr output
<core_client_version>7.16.11</core_client_version>
<![CDATA[
<message>
- exit code 2147500037 (0x80004005)</message>
<stderr_txt>
2025-01-12 16:47:37 (408): vboxwrapper version 26207
2025-01-12 16:47:37 (408): BOINC client version: 7.16.11
2025-01-12 16:47:37 (408): Detected: VirtualBox VboxManage Interface (Version: 7.1.4)
2025-01-12 16:47:38 (408): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2025-01-12 16:47:38 (408): Successfully copied 'init_data.xml' to the shared directory.
2025-01-12 16:47:38 (408): Successfully copied 'input' to the shared directory.
2025-01-12 16:47:38 (408): Create VM. (boinc_957e168e41c386f6, slot#3)
2025-01-12 16:47:38 (408): Setting Memory Size for VM. (630MB)
2025-01-12 16:47:39 (408): Setting CPU Count for VM. (1)
2025-01-12 16:47:39 (408): Setting Chipset Options for VM.
2025-01-12 16:47:39 (408): Setting Graphics Controller Options for VM.
2025-01-12 16:47:40 (408): Setting Boot Options for VM.
2025-01-12 16:47:40 (408): Setting Network Configuration for NAT.
2025-01-12 16:47:40 (408): Enabling VM Network Access.
2025-01-12 16:47:40 (408): Disabling USB Support for VM.
2025-01-12 16:47:41 (408): Disabling COM Port Support for VM.
2025-01-12 16:47:41 (408): Disabling LPT Port Support for VM.
2025-01-12 16:47:41 (408): Disabling Audio Support for VM.
2025-01-12 16:47:42 (408): Disabling Clipboard Support for VM.
2025-01-12 16:47:42 (408): Disabling Drag and Drop Support for VM.
2025-01-12 16:47:42 (408): Adding storage controller(s) to VM.
2025-01-12 16:47:42 (408): Adding virtual disk drive to VM. (Theory_2024_04_30_prod.vdi)
2025-01-12 16:47:44 (408): Adding VirtualBox Guest Additions to VM.
2025-01-12 16:47:44 (408): Adding network bandwidth throttle group to VM. (Defaulting to 1024GB)
2025-01-12 16:47:44 (408): forwarding host port 4511 to guest port 80
2025-01-12 16:47:45 (408): Enabling remote desktop for VM.
2025-01-12 16:47:45 (408): Required extension pack not installed, remote desktop not enabled.
2025-01-12 16:47:45 (408): Enabling shared directory for VM.
2025-01-12 16:47:45 (408): Starting VM using VBoxManage interface. (boinc_957e168e41c386f6, slot#3)
2025-01-12 16:47:52 (408): Successfully started VM. (PID = '16576')
2025-01-12 16:47:52 (408): Reporting VM Process ID to BOINC.
2025-01-12 16:47:52 (408): Guest Log: BIOS: VirtualBox 7.1.4
2025-01-12 16:47:52 (408): Guest Log: CPUID EDX: 0x178bfbff
2025-01-12 16:47:52 (408): Guest Log: BIOS: No PCI IDE controller, not probing IDE
2025-01-12 16:47:52 (408): Guest Log: BIOS: AHCI 0-P#0: PCHS=16383/16/63 LCHS=1024/255/63 0x0000000002800000 sectors
2025-01-12 16:47:52 (408): VM state change detected. (old = 'poweredoff', new = 'running')
2025-01-12 16:47:52 (408): Detected: Web Application Enabled (http://localhost:4511)
2025-01-12 16:47:52 (408): Preference change detected
2025-01-12 16:47:52 (408): Setting CPU throttle for VM. (75%)
2025-01-12 16:47:52 (408): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2025-01-12 16:47:54 (408): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032
2025-01-12 16:47:54 (408): Guest Log: BIOS: Booting from Hard Disk...
2025-01-12 16:47:57 (408): Guest Log: BIOS: KBD: unsupported int 16h function 03
2025-01-12 16:47:57 (408): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000
2025-01-12 16:48:20 (408): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds
2025-01-12 16:48:20 (408): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000)
2025-01-12 16:48:23 (408): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log
2025-01-12 16:48:23 (408): Guest Log: 00:00:00.000075 main Log opened 2025-01-12T08:48:24.965361000Z
2025-01-12 16:48:23 (408): Guest Log: 00:00:00.000187 main OS Product: Linux
2025-01-12 16:48:23 (408): Guest Log: 00:00:00.000255 main OS Release: 4.14.76-13.cernvm.x86_64
2025-01-12 16:48:23 (408): Guest Log: 00:00:00.000298 main OS Version: #1 SMP Tue Oct 16 18:26:15 CEST 2018
2025-01-12 16:48:23 (408): Guest Log: 00:00:00.000340 main Executable: /usr/sbin/VBoxService
2025-01-12 16:48:23 (408): Guest Log: 00:00:00.000341 main Process ID: 3224
2025-01-12 16:48:23 (408): Guest Log: 00:00:00.000341 main Package type: LINUX_64BITS_GENERIC
2025-01-12 16:48:23 (408): Guest Log: 00:00:00.002570 main 5.2.6 r120293 started. Verbose level = 0
2025-01-12 16:49:02 (408): Guest Log: 09:49:04 CET +01:00 2025-01-12: cranky: [INFO] Detected Theory App
2025-01-12 16:49:02 (408): Guest Log: 09:49:04 CET +01:00 2025-01-12: cranky: [INFO] Checking CVMFS.
2025-01-12 16:49:03 (408): Guest Log: Probing /cvmfs/sft.cern.ch... OK
2025-01-12 16:49:05 (408): 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-12 16:49:05 (408): Guest Log: 2.5.2.0 4085 0 27200 31452 3 1 278826 4096000 0 65024 0 0 n/a 5 1 http://s1cern-cvmfs.openhtc.io/cvmfs/sft.cern.ch DIRECT 1
2025-01-12 16:49:06 (408): Guest Log: Probing /cvmfs/grid.cern.ch... OK
2025-01-12 16:49:07 (408): 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-12 16:49:07 (408): Guest Log: 2.5.2.0 4141 0 27500 26207 3 2 278826 4096000 0 65024 0 2 -100 16915 1033 http://s1cern-cvmfs.openhtc.io/cvmfs/grid.cern.ch DIRECT 1
2025-01-12 16:49:08 (408): Guest Log: Probing /cvmfs/cernvm-prod.cern.ch... OK
2025-01-12 16:49:09 (408): 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-12 16:49:09 (408): Guest Log: 2.5.2.0 4064 0 27228 272 3 1 278826 4096000 0 65024 0 0 n/a 4 1 http://s1ihep-cvmfs.openhtc.io/cvmfs/cernvm-prod.cern.ch DIRECT 1
2025-01-12 16:49:10 (408): Guest Log: Probing /cvmfs/alice.cern.ch... OK
2025-01-12 16:49:11 (408): 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-12 16:49:11 (408): Guest Log: 2.5.2.0 4113 0 41112 20836 3 1 278826 4096000 0 65024 0 0 n/a 6549 983 http://s1unl-cvmfs.openhtc.io/cvmfs/alice.cern.ch DIRECT 1
2025-01-12 16:49:11 (408): Guest Log: 09:49:12 CET +01:00 2025-01-12: cranky: [INFO] Checking runc.
2025-01-12 16:49:11 (408): Guest Log: 09:49:12 CET +01:00 2025-01-12: cranky: [INFO] Creating the filesystem.
2025-01-12 16:49:11 (408): Guest Log: 09:49:12 CET +01:00 2025-01-12: cranky: [INFO] Using /cvmfs/cernvm-prod.cern.ch/cvm4
2025-01-12 16:49:11 (408): Guest Log: 09:49:13 CET +01:00 2025-01-12: cranky: [INFO] Updating config.json.
2025-01-12 16:49:11 (408): Guest Log: 09:49:13 CET +01:00 2025-01-12: cranky: [INFO] Running Container 'runc'.
2025-01-12 16:49:23 (408): Guest Log: job: htmld=/shared/html/job
2025-01-12 16:49:25 (408): Guest Log: job: unpack exitcode=0
2025-01-12 16:49:25 (408): Guest Log: INFO: activated the work-around for ld:
2025-01-12 16:49:26 (408): Guest Log: lrwxrwxrwx 1 0 0 15 Jan 12 08:49 /tmp/tmp.H1cAMyXDLp/ld -> /usr/bin/ld.bfd
2025-01-12 16:49:27 (408): Guest Log: 09:49:29 CET +01:00 2025-01-12: cranky: [INFO] ===> [runRivet] Sun Jan 12 08:49:28 UTC 2025 [boinc pp jets 8000 250,-,4160 - powheg-box r3744 pthard2 100000 136]
2025-01-12 16:55:42 (408): VM state change detected. (old = 'running', new = 'paused')
2025-01-12 16:55:52 (408): VM state change detected. (old = 'paused', new = 'running')
2025-01-12 17:08:04 (408): VM state change detected. (old = 'running', new = 'paused')
2025-01-12 17:08:12 (408): VM state change detected. (old = 'paused', new = 'running')
2025-01-12 17:29:53 (408): VM state change detected. (old = 'running', new = 'paused')
2025-01-12 18:29:51 (408): VM state change detected. (old = 'paused', new = 'running')
2025-01-12 18:29:59 (408): Guest Log: 00:41:21.738248 timesync vgsvcTimeSyncWorker: Radical host time change: 3 608 909 000 000ns (HostNow=1 736 677 799 561 000 000 ns HostLast=1 736 674 190 652 000 000 ns)
2025-01-12 18:30:09 (408): Guest Log: 00:41:31.817043 timesync vgsvcTimeSyncWorker: Radical guest time change: 3 623 152 198 000ns (GuestNow=1 736 677 809 684 457 000 ns GuestLast=1 736 674 186 532 259 000 ns fSetTimeLastLoop=true )
2025-01-12 18:53:49 (408): VM state change detected. (old = 'running', new = 'paused')
2025-01-12 18:53:59 (408): VM state change detected. (old = 'paused', new = 'running')
2025-01-12 18:55:42 (408): VM state change detected. (old = 'running', new = 'paused')
2025-01-12 19:56:02 (408): VM state change detected. (old = 'paused', new = 'running')
2025-01-12 19:56:11 (408): Guest Log: 01:07:03.272698 timesync vgsvcTimeSyncWorker: Radical host time change: 3 629 869 000 000ns (HostNow=1 736 682 970 755 000 000 ns HostLast=1 736 679 340 886 000 000 ns)
2025-01-12 19:56:21 (408): Guest Log: 01:07:13.273739 timesync vgsvcTimeSyncWorker: Radical guest time change: 3 639 615 941 000ns (GuestNow=1 736 682 980 756 047 000 ns GuestLast=1 736 679 341 140 106 000 ns fSetTimeLastLoop=true )
2025-01-12 20:30:42 (408): Status Report: Job Duration: '864000.000000'
2025-01-12 20:30:42 (408): Status Report: Elapsed Time: '6000.230230'
2025-01-12 20:30:42 (408): Status Report: CPU Time: '3776.968750'
2025-01-12 20:55:35 (408): VM state change detected. (old = 'running', new = 'paused')
2025-01-12 21:56:30 (408): VM state change detected. (old = 'paused', new = 'running')
2025-01-12 21:56:38 (408): Guest Log: 02:06:35.757096 timesync vgsvcTimeSyncWorker: Radical host time change: 3 664 391 000 000ns (HostNow=1 736 690 197 631 000 000 ns HostLast=1 736 686 533 240 000 000 ns)
2025-01-12 21:56:48 (408): Guest Log: 02:06:45.763692 timesync vgsvcTimeSyncWorker: Radical guest time change: 3 664 398 214 000ns (GuestNow=1 736 690 207 637 603 000 ns GuestLast=1 736 686 543 239 389 000 ns fSetTimeLastLoop=true )
2025-01-12 22:07:42 (408): VM state change detected. (old = 'running', new = 'paused')
2025-01-12 22:09:12 (408): VM state change detected. (old = 'paused', new = 'running')
2025-01-12 22:21:44 (408): VM state change detected. (old = 'running', new = 'paused')
2025-01-12 22:21:52 (408): VM state change detected. (old = 'paused', new = 'running')
2025-01-12 22:22:02 (408): VM state change detected. (old = 'running', new = 'paused')
2025-01-12 23:22:02 (408): VM state change detected. (old = 'paused', new = 'running')
2025-01-12 23:22:04 (408): Guest Log: 02:30:26.589260 timesync vgsvcTimeSyncWorker: Radical host time change: 3 609 609 000 000ns (HostNow=1 736 695 324 266 000 000 ns HostLast=1 736 691 714 657 000 000 ns)
2025-01-12 23:22:14 (408): Guest Log: 02:30:36.618127 timesync vgsvcTimeSyncWorker: Radical guest time change: 3 705 831 724 000ns (GuestNow=1 736 695 334 294 880 000 ns GuestLast=1 736 691 628 463 156 000 ns fSetTimeLastLoop=true )
2025-01-12 23:22:46 (408): VM state change detected. (old = 'running', new = 'paused')
2025-01-12 23:22:56 (408): VM state change detected. (old = 'paused', new = 'running')
2025-01-12 23:53:08 (408): VM state change detected. (old = 'running', new = 'paused')
2025-01-12 23:53:18 (408): VM state change detected. (old = 'paused', new = 'running')
2025-01-12 23:56:18 (408): VM state change detected. (old = 'running', new = 'paused')
2025-01-12 23:56:38 (408): VM state change detected. (old = 'paused', new = 'running')
2025-01-13 00:06:20 (408): VM state change detected. (old = 'running', new = 'paused')
2025-01-13 00:06:29 (408): VM state change detected. (old = 'paused', new = 'running')
2025-01-13 00:16:00 (408): Status Report: Job Duration: '864000.000000'
2025-01-13 00:16:00 (408): Status Report: Elapsed Time: '12000.464449'
2025-01-13 00:16:00 (408): Status Report: CPU Time: '8277.031250'
2025-01-14 18:34:39 (18196): vboxwrapper version 26207
2025-01-14 18:34:39 (18196): BOINC client version: 7.16.11
2025-01-14 18:34:41 (18196): Detected: VirtualBox VboxManage Interface (Version: 7.1.4)
2025-01-14 18:34:41 (18196): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2025-01-14 18:34:42 (18196): Guest Log: BIOS: VirtualBox 7.1.4
2025-01-14 18:34:42 (18196): Guest Log: CPUID EDX: 0x178bfbff
2025-01-14 18:34:42 (18196): Guest Log: BIOS: No PCI IDE controller, not probing IDE
2025-01-14 18:34:42 (18196): Guest Log: BIOS: AHCI 0-P#0: PCHS=16383/16/63 LCHS=1024/255/63 0x0000000002800000 sectors
2025-01-14 18:34:42 (18196): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032
2025-01-14 18:34:42 (18196): Guest Log: BIOS: Booting from Hard Disk...
2025-01-14 18:34:42 (18196): Guest Log: BIOS: KBD: unsupported int 16h function 03
2025-01-14 18:34:42 (18196): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000
2025-01-14 18:34:42 (18196): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds
2025-01-14 18:34:42 (18196): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000)
2025-01-14 18:34:42 (18196): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log
2025-01-14 18:34:42 (18196): Guest Log: 00:00:00.000075 main Log opened 2025-01-12T08:48:24.965361000Z
2025-01-14 18:34:42 (18196): Guest Log: 00:00:00.000187 main OS Product: Linux
2025-01-14 18:34:42 (18196): Guest Log: 00:00:00.000255 main OS Release: 4.14.76-13.cernvm.x86_64
2025-01-14 18:34:42 (18196): Guest Log: 00:00:00.000298 main OS Version: #1 SMP Tue Oct 16 18:26:15 CEST 2018
2025-01-14 18:34:42 (18196): Guest Log: 00:00:00.000340 main Executable: /usr/sbin/VBoxService
2025-01-14 18:34:42 (18196): Guest Log: 00:00:00.000341 main Process ID: 3224
2025-01-14 18:34:42 (18196): Guest Log: 00:00:00.000341 main Package type: LINUX_64BITS_GENERIC
2025-01-14 18:34:42 (18196): Guest Log: 00:00:00.002570 main 5.2.6 r120293 started. Verbose level = 0
2025-01-14 18:34:42 (18196): Guest Log: 09:49:04 CET +01:00 2025-01-12: cranky: [INFO] Detected Theory App
2025-01-14 18:34:42 (18196): Guest Log: 09:49:04 CET +01:00 2025-01-12: cranky: [INFO] Checking CVMFS.
2025-01-14 18:34:42 (18196): Guest Log: Probing /cvmfs/sft.cern.ch... OK
2025-01-14 18:34:42 (18196): 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-14 18:34:42 (18196): Guest Log: 2.5.2.0 4085 0 27200 31452 3 1 278826 4096000 0 65024 0 0 n/a 5 1 http://s1cern-cvmfs.openhtc.io/cvmfs/sft.cern.ch DIRECT 1
2025-01-14 18:34:42 (18196): Guest Log: Probing /cvmfs/grid.cern.ch... OK
2025-01-14 18:34:42 (18196): 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-14 18:34:42 (18196): Guest Log: 2.5.2.0 4141 0 27500 26207 3 2 278826 4096000 0 65024 0 2 -100 16915 1033 http://s1cern-cvmfs.openhtc.io/cvmfs/grid.cern.ch DIRECT 1
2025-01-14 18:34:42 (18196): Guest Log: Probing /cvmfs/cernvm-prod.cern.ch... OK
2025-01-14 18:34:42 (18196): 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-14 18:34:42 (18196): Guest Log: 2.5.2.0 4064 0 27228 272 3 1 278826 4096000 0 65024 0 0 n/a 4 1 http://s1ihep-cvmfs.openhtc.io/cvmfs/cernvm-prod.cern.ch DIRECT 1
2025-01-14 18:34:42 (18196): Guest Log: Probing /cvmfs/alice.cern.ch... OK
2025-01-14 18:34:42 (18196): 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-14 18:34:42 (18196): Guest Log: 2.5.2.0 4113 0 41112 20836 3 1 278826 4096000 0 65024 0 0 n/a 6549 983 http://s1unl-cvmfs.openhtc.io/cvmfs/alice.cern.ch DIRECT 1
2025-01-14 18:34:42 (18196): Guest Log: 09:49:12 CET +01:00 2025-01-12: cranky: [INFO] Checking runc.
2025-01-14 18:34:42 (18196): Guest Log: 09:49:12 CET +01:00 2025-01-12: cranky: [INFO] Creating the filesystem.
2025-01-14 18:34:42 (18196): Guest Log: 09:49:12 CET +01:00 2025-01-12: cranky: [INFO] Using /cvmfs/cernvm-prod.cern.ch/cvm4
2025-01-14 18:34:42 (18196): Guest Log: 09:49:13 CET +01:00 2025-01-12: cranky: [INFO] Updating config.json.
2025-01-14 18:34:42 (18196): Guest Log: 09:49:13 CET +01:00 2025-01-12: cranky: [INFO] Running Container 'runc'.
2025-01-14 18:34:42 (18196): Guest Log: job: htmld=/shared/html/job
2025-01-14 18:34:42 (18196): Guest Log: job: unpack exitcode=0
2025-01-14 18:34:42 (18196): Guest Log: INFO: activated the work-around for ld:
2025-01-14 18:34:42 (18196): Guest Log: lrwxrwxrwx 1 0 0 15 Jan 12 08:49 /tmp/tmp.H1cAMyXDLp/ld -> /usr/bin/ld.bfd
2025-01-14 18:34:42 (18196): Guest Log: 09:49:29 CET +01:00 2025-01-12: cranky: [INFO] ===> [runRivet] Sun Jan 12 08:49:28 UTC 2025 [boinc pp jets 8000 250,-,4160 - powheg-box r3744 pthard2 100000 136]
2025-01-14 18:34:42 (18196): Guest Log: 00:41:21.738248 timesync vgsvcTimeSyncWorker: Radical host time change: 3 608 909 000 000ns (HostNow=1 736 677 799 561 000 000 ns HostLast=1 736 674 190 652 000 000 ns)
2025-01-14 18:34:42 (18196): Guest Log: 00:41:31.817043 timesync vgsvcTimeSyncWorker: Radical guest time change: 3 623 152 198 000ns (GuestNow=1 736 677 809 684 457 000 ns GuestLast=1 736 674 186 532 259 000 ns fSetTimeLastLoop=true )
2025-01-14 18:34:42 (18196): Guest Log: 01:07:03.272698 timesync vgsvcTimeSyncWorker: Radical host time change: 3 629 869 000 000ns (HostNow=1 736 682 970 755 000 000 ns HostLast=1 736 679 340 886 000 000 ns)
2025-01-14 18:34:42 (18196): Guest Log: 01:07:13.273739 timesync vgsvcTimeSyncWorker: Radical guest time change: 3 639 615 941 000ns (GuestNow=1 736 682 980 756 047 000 ns GuestLast=1 736 679 341 140 106 000 ns fSetTimeLastLoop=true )
2025-01-14 18:34:42 (18196): Guest Log: 02:06:35.757096 timesync vgsvcTimeSyncWorker: Radical host time change: 3 664 391 000 000ns (HostNow=1 736 690 197 631 000 000 ns HostLast=1 736 686 533 240 000 000 ns)
2025-01-14 18:34:42 (18196): Guest Log: 02:06:45.763692 timesync vgsvcTimeSyncWorker: Radical guest time change: 3 664 398 214 000ns (GuestNow=1 736 690 207 637 603 000 ns GuestLast=1 736 686 543 239 389 000 ns fSetTimeLastLoop=true )
2025-01-14 18:34:42 (18196): Guest Log: 02:30:26.589260 timesync vgsvcTimeSyncWorker: Radical host time change: 3 609 609 000 000ns (HostNow=1 736 695 324 266 000 000 ns HostLast=1 736 691 714 657 000 000 ns)
2025-01-14 18:34:42 (18196): Guest Log: 02:30:36.618127 timesync vgsvcTimeSyncWorker: Radical guest time change: 3 705 831 724 000ns (GuestNow=1 736 695 334 294 880 000 ns GuestLast=1 736 691 628 463 156 000 ns fSetTimeLastLoop=true )
2025-01-14 18:34:42 (18196): Starting VM using VBoxManage interface. (boinc_957e168e41c386f6, slot#3)
2025-01-14 18:34:49 (18196): Successfully started VM. (PID = '17360')
2025-01-14 18:34:49 (18196): Reporting VM Process ID to BOINC.
2025-01-14 18:34:49 (18196): Guest Log: BIOS: VirtualBox 7.1.4
2025-01-14 18:34:49 (18196): Guest Log: CPUID EDX: 0x178bfbff
2025-01-14 18:34:49 (18196): Guest Log: BIOS: No PCI IDE controller, not probing IDE
2025-01-14 18:34:49 (18196): Guest Log: BIOS: AHCI 0-P#0: PCHS=16383/16/63 LCHS=1024/255/63 0x0000000002800000 sectors
2025-01-14 18:34:49 (18196): VM state change detected. (old = 'poweredoff', new = 'running')
2025-01-14 18:34:49 (18196): Detected: Web Application Enabled (http://localhost:4511)
2025-01-14 18:34:49 (18196): Status Report: Job Duration: '864000.000000'
2025-01-14 18:34:49 (18196): Status Report: Elapsed Time: '12752.464449'
2025-01-14 18:34:49 (18196): Status Report: CPU Time: '8840.562500'
2025-01-14 18:34:49 (18196): Preference change detected
2025-01-14 18:34:49 (18196): Setting CPU throttle for VM. (75%)
2025-01-14 18:34:49 (18196): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2025-01-14 18:34:51 (18196): VM state change detected. (old = 'running', new = 'paused')
2025-01-14 18:35:01 (18196): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032
2025-01-14 18:35:01 (18196): Guest Log: BIOS: Booting from Hard Disk...
2025-01-14 18:35:01 (18196): VM state change detected. (old = 'paused', new = 'running')
2025-01-14 18:35:03 (18196): Guest Log: BIOS: KBD: unsupported int 16h function 03
2025-01-14 18:35:03 (18196): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000
2025-01-14 18:35:21 (18196): VM state change detected. (old = 'running', new = 'paused')
2025-01-14 18:35:30 (18196): VM state change detected. (old = 'paused', new = 'running')
2025-01-14 18:35:33 (18196): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds
2025-01-14 18:35:33 (18196): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000)
2025-01-14 18:35:37 (18196): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log
2025-01-14 18:35:37 (18196): Guest Log: 00:00:00.000069 main Log opened 2025-01-14T10:35:20.787310000Z
2025-01-14 18:35:37 (18196): Guest Log: 00:00:00.000684 main OS Product: Linux
2025-01-14 18:35:37 (18196): Guest Log: 00:00:00.000740 main OS Release: 4.14.76-13.cernvm.x86_64
2025-01-14 18:35:37 (18196): Guest Log: 00:00:00.000783 main OS Version: #1 SMP Tue Oct 16 18:26:15 CEST 2018
2025-01-14 18:35:37 (18196): Guest Log: 00:00:00.000825 main Executable: /usr/sbin/VBoxService
2025-01-14 18:35:37 (18196): Guest Log: 00:00:00.000826 main Process ID: 3222
2025-01-14 18:35:37 (18196): Guest Log: 00:00:00.000826 main Package type: LINUX_64BITS_GENERIC
2025-01-14 18:35:37 (18196): Guest Log: 00:00:00.002339 main 5.2.6 r120293 started. Verbose level = 0
2025-01-14 18:36:05 (18196): Guest Log: 11:35:49 CET +01:00 2025-01-14: cranky: [INFO] Detected Theory App
2025-01-14 18:36:05 (18196): Guest Log: 11:35:49 CET +01:00 2025-01-14: cranky: [INFO] Checking CVMFS.
2025-01-14 18:36:06 (18196): Guest Log: Probing /cvmfs/sft.cern.ch... OK
2025-01-14 18:36:07 (18196): 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-14 18:36:07 (18196): Guest Log: 2.5.2.0 4062 0 28424 31467 3 1 1743913 4096000 0 65024 0 0 n/a 5 2 http://s1fnal-cvmfs.openhtc.io/cvmfs/sft.cern.ch DIRECT 1
2025-01-14 18:36:08 (18196): Guest Log: Probing /cvmfs/grid.cern.ch... OK
2025-01-14 18:36:09 (18196): 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-14 18:36:09 (18196): Guest Log: 2.5.2.0 4132 0 27660 26223 3 2 1743913 4096000 0 65024 0 2 0 7945 1969 http://s1ral-cvmfs.openhtc.io/cvmfs/grid.cern.ch DIRECT 1
2025-01-14 18:36:10 (18196): Guest Log: Probing /cvmfs/cernvm-prod.cern.ch... OK
2025-01-14 18:36:10 (18196): 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-14 18:36:10 (18196): Guest Log: 2.5.2.0 4088 0 27228 272 3 1 1743913 4096000 0 65024 0 0 n/a 0 0 http://s1ihep-cvmfs.openhtc.io/cvmfs/cernvm-prod.cern.ch DIRECT 1
2025-01-14 18:36:11 (18196): Guest Log: Probing /cvmfs/alice.cern.ch... OK
2025-01-14 18:36:12 (18196): 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-14 18:36:12 (18196): Guest Log: 2.5.2.0 4108 0 39668 20848 3 1 1743913 4096000 0 65024 0 0 n/a 6552 1852 http://s1ral-cvmfs.openhtc.io/cvmfs/alice.cern.ch DIRECT 1
2025-01-14 18:36:12 (18196): Guest Log: 11:35:56 CET +01:00 2025-01-14: cranky: [INFO] Checking runc.
2025-01-14 18:36:12 (18196): Guest Log: 11:35:56 CET +01:00 2025-01-14: cranky: [INFO] Creating the filesystem.
2025-01-14 18:36:12 (18196): Guest Log: 11:35:56 CET +01:00 2025-01-14: cranky: [INFO] Using /cvmfs/cernvm-prod.cern.ch/cvm4
2025-01-14 18:36:12 (18196): Guest Log: 11:35:56 CET +01:00 2025-01-14: cranky: [INFO] Updating config.json.
2025-01-14 18:36:12 (18196): Guest Log: 11:35:56 CET +01:00 2025-01-14: cranky: [INFO] Running Container 'runc'.
2025-01-14 18:36:13 (18196): Guest Log: job: htmld=/shared/html/job
2025-01-14 18:36:13 (18196): Guest Log: job: unpack exitcode=0
2025-01-14 18:36:13 (18196): Guest Log: INFO: activated the work-around for ld:
2025-01-14 18:36:13 (18196): Guest Log: lrwxrwxrwx 1 0 0 15 Jan 14 10:35 /tmp/tmp.4nEigHCilJ/ld -> /usr/bin/ld.bfd
2025-01-14 18:36:14 (18196): Guest Log: 11:35:58 CET +01:00 2025-01-14: cranky: [INFO] ===> [runRivet] Tue Jan 14 10:35:56 UTC 2025 [boinc pp jets 8000 250,-,4160 - powheg-box r3744 pthard2 100000 136]
2025-01-14 20:15:17 (18196): Status Report: Job Duration: '864000.000000'
2025-01-14 20:15:17 (18196): Status Report: Elapsed Time: '18752.464449'
2025-01-14 20:15:17 (18196): Status Report: CPU Time: '13317.796875'
2025-01-14 20:52:49 (18196): VM state change detected. (old = 'running', new = 'paused')
2025-01-14 21:52:51 (18196): VM state change detected. (old = 'paused', new = 'running')
2025-01-14 21:52:58 (18196): Guest Log: 02:17:23.597054 timesync vgsvcTimeSyncWorker: Radical host time change: 3 612 416 000 000ns (HostNow=1 736 862 778 215 000 000 ns HostLast=1 736 859 165 799 000 000 ns)
2025-01-14 21:53:08 (18196): Guest Log: 02:17:33.643006 timesync vgsvcTimeSyncWorker: Radical guest time change: 3 623 986 445 000ns (GuestNow=1 736 862 788 283 121 000 ns GuestLast=1 736 859 164 296 676 000 ns fSetTimeLastLoop=true )
2025-01-14 22:10:08 (18196): VM state change detected. (old = 'running', new = 'paused')
2025-01-14 23:10:07 (18196): VM state change detected. (old = 'paused', new = 'running')
2025-01-14 23:10:08 (18196): Guest Log: 02:34:34.372165 timesync vgsvcTimeSyncWorker: Radical host time change: 3 609 149 000 000ns (HostNow=1 736 867 408 216 000 000 ns HostLast=1 736 863 799 067 000 000 ns)
2025-01-14 23:10:20 (18196): Guest Log: 02:34:44.376606 timesync vgsvcTimeSyncWorker: Radical guest time change: 3 609 208 191 000ns (GuestNow=1 736 867 418 220 452 000 ns GuestLast=1 736 863 809 012 261 000 ns fSetTimeLastLoop=true )
2025-01-14 23:11:57 (18196): VM state change detected. (old = 'running', new = 'paused')
2025-01-14 23:12:06 (18196): VM state change detected. (old = 'paused', new = 'running')
2025-01-14 23:18:29 (18196): VM state change detected. (old = 'running', new = 'paused')
2025-01-14 23:18:38 (18196): VM state change detected. (old = 'paused', new = 'running')
2025-01-14 23:24:39 (18196): VM state change detected. (old = 'running', new = 'paused')
2025-01-14 23:24:48 (18196): VM state change detected. (old = 'paused', new = 'running')
2025-01-14 23:53:58 (18196): VM state change detected. (old = 'running', new = 'paused')
2025-01-14 23:54:11 (18196): VM state change detected. (old = 'paused', new = 'running')
2025-01-14 23:57:41 (18196): VM state change detected. (old = 'running', new = 'paused')
2025-01-14 23:58:11 (18196): VM state change detected. (old = 'paused', new = 'running')
2025-01-14 23:58:42 (18196): VM state change detected. (old = 'running', new = 'paused')
2025-01-14 23:58:51 (18196): VM state change detected. (old = 'paused', new = 'running')
2025-01-14 23:59:20 (18196): Status Report: Job Duration: '864000.000000'
2025-01-14 23:59:20 (18196): Status Report: Elapsed Time: '24752.464449'
2025-01-14 23:59:20 (18196): Status Report: CPU Time: '17862.437500'
2025-01-15 00:01:02 (18196): VM state change detected. (old = 'running', new = 'paused')
2025-01-15 00:01:32 (18196): VM state change detected. (old = 'paused', new = 'running')
2025-01-15 00:05:24 (18196): VM state change detected. (old = 'running', new = 'paused')
2025-01-15 00:06:22 (18196): VM state change detected. (old = 'paused', new = 'running')
2025-01-15 00:07:25 (18196): VM state change detected. (old = 'running', new = 'paused')
2025-01-15 00:07:52 (18196): VM state change detected. (old = 'paused', new = 'running')
2025-01-15 00:10:26 (18196): VM state change detected. (old = 'running', new = 'paused')
2025-01-15 00:10:42 (18196): VM state change detected. (old = 'paused', new = 'running')
2025-01-15 00:16:34 (18196): VM state change detected. (old = 'running', new = 'paused')
2025-01-15 00:17:14 (18196): VM state change detected. (old = 'paused', new = 'running')
2025-01-15 00:18:43 (18196): VM state change detected. (old = 'running', new = 'paused')
2025-01-15 00:18:53 (18196): VM state change detected. (old = 'paused', new = 'running')
2025-01-15 00:33:15 (18196): VM state change detected. (old = 'running', new = 'paused')
2025-01-15 00:33:25 (18196): VM state change detected. (old = 'paused', new = 'running')
2025-01-15 01:44:01 (18196): Status Report: Job Duration: '864000.000000'
2025-01-15 01:44:01 (18196): Status Report: Elapsed Time: '30752.700342'
2025-01-15 01:44:01 (18196): Status Report: CPU Time: '22378.343750'
2025-01-15 03:24:07 (18196): Status Report: Job Duration: '864000.000000'
2025-01-15 03:24:07 (18196): Status Report: Elapsed Time: '36752.700342'
2025-01-15 03:24:07 (18196): Status Report: CPU Time: '26888.500000'
2025-01-15 05:04:19 (18196): Status Report: Job Duration: '864000.000000'
2025-01-15 05:04:19 (18196): Status Report: Elapsed Time: '42752.700342'
2025-01-15 05:04:19 (18196): Status Report: CPU Time: '31403.328125'
2025-01-15 06:44:24 (18196): Status Report: Job Duration: '864000.000000'
2025-01-15 06:44:24 (18196): Status Report: Elapsed Time: '48752.700342'
2025-01-15 06:44:24 (18196): Status Report: CPU Time: '35913.265625'
2025-01-15 08:24:29 (18196): Status Report: Job Duration: '864000.000000'
2025-01-15 08:24:29 (18196): Status Report: Elapsed Time: '54752.700342'
2025-01-15 08:24:29 (18196): Status Report: CPU Time: '40423.171875'
2025-01-15 09:13:56 (18196): VM state change detected. (old = 'running', new = 'paused')
2025-01-18 21:40:12 (9528): vboxwrapper version 26207
2025-01-18 21:40:12 (9528): BOINC client version: 7.16.11
2025-01-18 21:40:15 (9528): Detected: VirtualBox VboxManage Interface (Version: 7.1.4)
2025-01-18 21:40:16 (9528): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2025-01-18 21:40:16 (9528): Guest Log: BIOS: VirtualBox 7.1.4
2025-01-18 21:40:16 (9528): Guest Log: CPUID EDX: 0x178bfbff
2025-01-18 21:40:16 (9528): Guest Log: BIOS: No PCI IDE controller, not probing IDE
2025-01-18 21:40:16 (9528): Guest Log: BIOS: AHCI 0-P#0: PCHS=16383/16/63 LCHS=1024/255/63 0x0000000002800000 sectors
2025-01-18 21:40:16 (9528): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032
2025-01-18 21:40:16 (9528): Guest Log: BIOS: Booting from Hard Disk...
2025-01-18 21:40:16 (9528): Guest Log: BIOS: KBD: unsupported int 16h function 03
2025-01-18 21:40:16 (9528): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000
2025-01-18 21:40:16 (9528): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds
2025-01-18 21:40:16 (9528): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000)
2025-01-18 21:40:16 (9528): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log
2025-01-18 21:40:16 (9528): Guest Log: 00:00:00.000069 main Log opened 2025-01-14T10:35:20.787310000Z
2025-01-18 21:40:16 (9528): Guest Log: 00:00:00.000684 main OS Product: Linux
2025-01-18 21:40:16 (9528): Guest Log: 00:00:00.000740 main OS Release: 4.14.76-13.cernvm.x86_64
2025-01-18 21:40:16 (9528): Guest Log: 00:00:00.000783 main OS Version: #1 SMP Tue Oct 16 18:26:15 CEST 2018
2025-01-18 21:40:16 (9528): Guest Log: 00:00:00.000825 main Executable: /usr/sbin/VBoxService
2025-01-18 21:40:16 (9528): Guest Log: 00:00:00.000826 main Process ID: 3222
2025-01-18 21:40:16 (9528): Guest Log: 00:00:00.000826 main Package type: LINUX_64BITS_GENERIC
2025-01-18 21:40:16 (9528): Guest Log: 00:00:00.002339 main 5.2.6 r120293 started. Verbose level = 0
2025-01-18 21:40:16 (9528): Guest Log: 11:35:49 CET +01:00 2025-01-14: cranky: [INFO] Detected Theory App
2025-01-18 21:40:16 (9528): Guest Log: 11:35:49 CET +01:00 2025-01-14: cranky: [INFO] Checking CVMFS.
2025-01-18 21:40:16 (9528): Guest Log: Probing /cvmfs/sft.cern.ch... OK
2025-01-18 21:40:16 (9528): 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-18 21:40:16 (9528): Guest Log: 2.5.2.0 4062 0 28424 31467 3 1 1743913 4096000 0 65024 0 0 n/a 5 2 http://s1fnal-cvmfs.openhtc.io/cvmfs/sft.cern.ch DIRECT 1
2025-01-18 21:40:16 (9528): Guest Log: Probing /cvmfs/grid.cern.ch... OK
2025-01-18 21:40:16 (9528): 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-18 21:40:16 (9528): Guest Log: 2.5.2.0 4132 0 27660 26223 3 2 1743913 4096000 0 65024 0 2 0 7945 1969 http://s1ral-cvmfs.openhtc.io/cvmfs/grid.cern.ch DIRECT 1
2025-01-18 21:40:16 (9528): Guest Log: Probing /cvmfs/cernvm-prod.cern.ch... OK
2025-01-18 21:40:16 (9528): 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-18 21:40:16 (9528): Guest Log: 2.5.2.0 4088 0 27228 272 3 1 1743913 4096000 0 65024 0 0 n/a 0 0 http://s1ihep-cvmfs.openhtc.io/cvmfs/cernvm-prod.cern.ch DIRECT 1
2025-01-18 21:40:16 (9528): Guest Log: Probing /cvmfs/alice.cern.ch... OK
2025-01-18 21:40:16 (9528): 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-18 21:40:16 (9528): Guest Log: 2.5.2.0 4108 0 39668 20848 3 1 1743913 4096000 0 65024 0 0 n/a 6552 1852 http://s1ral-cvmfs.openhtc.io/cvmfs/alice.cern.ch DIRECT 1
2025-01-18 21:40:16 (9528): Guest Log: 11:35:56 CET +01:00 2025-01-14: cranky: [INFO] Checking runc.
2025-01-18 21:40:16 (9528): Guest Log: 11:35:56 CET +01:00 2025-01-14: cranky: [INFO] Creating the filesystem.
2025-01-18 21:40:16 (9528): Guest Log: 11:35:56 CET +01:00 2025-01-14: cranky: [INFO] Using /cvmfs/cernvm-prod.cern.ch/cvm4
2025-01-18 21:40:16 (9528): Guest Log: 11:35:56 CET +01:00 2025-01-14: cranky: [INFO] Updating config.json.
2025-01-18 21:40:16 (9528): Guest Log: 11:35:56 CET +01:00 2025-01-14: cranky: [INFO] Running Container 'runc'.
2025-01-18 21:40:16 (9528): Guest Log: job: htmld=/shared/html/job
2025-01-18 21:40:16 (9528): Guest Log: job: unpack exitcode=0
2025-01-18 21:40:16 (9528): Guest Log: INFO: activated the work-around for ld:
2025-01-18 21:40:16 (9528): Guest Log: lrwxrwxrwx 1 0 0 15 Jan 14 10:35 /tmp/tmp.4nEigHCilJ/ld -> /usr/bin/ld.bfd
2025-01-18 21:40:16 (9528): Guest Log: 11:35:58 CET +01:00 2025-01-14: cranky: [INFO] ===> [runRivet] Tue Jan 14 10:35:56 UTC 2025 [boinc pp jets 8000 250,-,4160 - powheg-box r3744 pthard2 100000 136]
2025-01-18 21:40:16 (9528): Guest Log: 02:17:23.597054 timesync vgsvcTimeSyncWorker: Radical host time change: 3 612 416 000 000ns (HostNow=1 736 862 778 215 000 000 ns HostLast=1 736 859 165 799 000 000 ns)
2025-01-18 21:40:16 (9528): Guest Log: 02:17:33.643006 timesync vgsvcTimeSyncWorker: Radical guest time change: 3 623 986 445 000ns (GuestNow=1 736 862 788 283 121 000 ns GuestLast=1 736 859 164 296 676 000 ns fSetTimeLastLoop=true )
2025-01-18 21:40:16 (9528): Guest Log: 02:34:34.372165 timesync vgsvcTimeSyncWorker: Radical host time change: 3 609 149 000 000ns (HostNow=1 736 867 408 216 000 000 ns HostLast=1 736 863 799 067 000 000 ns)
2025-01-18 21:40:16 (9528): Guest Log: 02:34:44.376606 timesync vgsvcTimeSyncWorker: Radical guest time change: 3 609 208 191 000ns (GuestNow=1 736 867 418 220 452 000 ns GuestLast=1 736 863 809 012 261 000 ns fSetTimeLastLoop=true )
2025-01-18 21:40:16 (9528): Starting VM using VBoxManage interface. (boinc_957e168e41c386f6, slot#3)
2025-01-18 21:40:22 (9528): Error in start VM for VM: -2147467259
Command:
VBoxManage -q startvm "boinc_957e168e41c386f6" --type headless
Output:
VBoxManage.exe: error: Generic invalid state error. (VERR_INVALID_STATE)
VBoxManage.exe: error: Details: code E_FAIL (0x80004005), component ConsoleWrap, interface IConsole
Waiting for VM "boinc_957e168e41c386f6" to power on...
2025-01-18 21:40:22 (9528): VM failed to start.
2025-01-18 21:40:22 (9528): Could not start
2025-01-18 21:40:22 (9528): ERROR: VM failed to start
2025-01-18 21:40:22 (9528): Powering off VM.
2025-01-18 21:40:22 (9528): Deregistering VM. (boinc_957e168e41c386f6, slot#3)
2025-01-18 21:40:22 (9528): Removing network bandwidth throttle group from VM.
2025-01-18 21:40:23 (9528): Removing VM from VirtualBox.
Hypervisor System Log:
00:00:17.973486 ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={e36a5081-a82a-40bd-9e4e-42a44d6ce50f} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
00:00:17.973528 ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={e36a5081-a82a-40bd-9e4e-42a44d6ce50f} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
00:00:17.973550 ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={e36a5081-a82a-40bd-9e4e-42a44d6ce50f} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
00:00:17.973566 ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={e36a5081-a82a-40bd-9e4e-42a44d6ce50f} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
00:00:17.973581 ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={e36a5081-a82a-40bd-9e4e-42a44d6ce50f} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
00:00:17.973596 ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={e36a5081-a82a-40bd-9e4e-42a44d6ce50f} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
00:00:17.973611 ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={e36a5081-a82a-40bd-9e4e-42a44d6ce50f} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
00:00:17.973626 ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={e36a5081-a82a-40bd-9e4e-42a44d6ce50f} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
00:00:17.973644 ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={e36a5081-a82a-40bd-9e4e-42a44d6ce50f} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
00:00:17.973659 ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={e36a5081-a82a-40bd-9e4e-42a44d6ce50f} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
00:00:17.973674 ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={e36a5081-a82a-40bd-9e4e-42a44d6ce50f} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
00:00:17.973688 ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={e36a5081-a82a-40bd-9e4e-42a44d6ce50f} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
00:00:17.973701 ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={e36a5081-a82a-40bd-9e4e-42a44d6ce50f} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
00:00:17.973715 ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={e36a5081-a82a-40bd-9e4e-42a44d6ce50f} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
00:00:17.973729 ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={e36a5081-a82a-40bd-9e4e-42a44d6ce50f} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
00:00:17.973742 ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={e36a5081-a82a-40bd-9e4e-42a44d6ce50f} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
00:00:17.973756 ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={e36a5081-a82a-40bd-9e4e-42a44d6ce50f} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
00:00:17.973769 ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={e36a5081-a82a-40bd-9e4e-42a44d6ce50f} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
00:00:18.245764 ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={e36a5081-a82a-40bd-9e4e-42a44d6ce50f} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
00:00:18.245804 ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={e36a5081-a82a-40bd-9e4e-42a44d6ce50f} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
00:00:18.245827 ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={e36a5081-a82a-40bd-9e4e-42a44d6ce50f} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
00:00:18.245845 ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={e36a5081-a82a-40bd-9e4e-42a44d6ce50f} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
00:00:18.245860 ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={e36a5081-a82a-40bd-9e4e-42a44d6ce50f} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
00:00:18.245875 ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={e36a5081-a82a-40bd-9e4e-42a44d6ce50f} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
00:00:18.245890 ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={e36a5081-a82a-40bd-9e4e-42a44d6ce50f} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
00:00:18.245905 ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={e36a5081-a82a-40bd-9e4e-42a44d6ce50f} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
00:00:18.245920 ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={e36a5081-a82a-40bd-9e4e-42a44d6ce50f} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
00:00:18.245935 ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={e36a5081-a82a-40bd-9e4e-42a44d6ce50f} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
00:00:18.245952 ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={e36a5081-a82a-40bd-9e4e-42a44d6ce50f} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
00:00:18.245966 ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={e36a5081-a82a-40bd-9e4e-42a44d6ce50f} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
00:00:18.245981 ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={e36a5081-a82a-40bd-9e4e-42a44d6ce50f} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
00:00:18.245996 ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={e36a5081-a82a-40bd-9e4e-42a44d6ce50f} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
00:00:18.246011 ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={e36a5081-a82a-40bd-9e4e-42a44d6ce50f} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
00:00:18.246025 ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={e36a5081-a82a-40bd-9e4e-42a44d6ce50f} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
00:00:18.246040 ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={e36a5081-a82a-40bd-9e4e-42a44d6ce50f} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
00:00:18.246054 ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={e36a5081-a82a-40bd-9e4e-42a44d6ce50f} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
00:00:18.248134 Saving settings file "C:\ProgramData\BOINC\slots\2\boinc_99166785d5f8b3f5\boinc_99166785d5f8b3f5.vbox" with version "1.19-windows"
00:00:18.262778 Finished saving settings file "C:\ProgramData\BOINC\slots\2\boinc_99166785d5f8b3f5\boinc_99166785d5f8b3f5.vbox"
VM Execution Log:
VM Startup Log:
VM Trace Log:
: 8
Maximum PIIX3 IDE Controllers: 1
Maximum PIIX3 SATA Controllers: 1
Maximum PIIX3 SCSI Controllers: 1
Maximum PIIX3 SAS Controllers: 1
Maximum PIIX3 NVMe Controllers: 1
Maximum PIIX3 virtio-scsi Controllers: 1
Maximum PIIX3 Floppy Controllers:1
ICH9 chipset properties:
Maximum ICH9 Network Adapter count: 36
Maximum ICH9 IDE Controllers: 1
Maximum ICH9 SATA Controllers: 8
Maximum ICH9 SCSI Controllers: 8
Maximum ICH9 SAS Controllers: 8
Maximum ICH9 NVMe Controllers: 8
Maximum ICH9 virtio-scsi Controllers: 8
Maximum ICH9 Floppy Controllers:1
Supported platform architectures: x86
x86 platform properties:
Maximum Serial Port count: 4
Maximum Parallel Port count: 2
Maximum Boot Position: 4
Maximum Floppy Port count: 1
Maximum Floppy Devices per Port: 2
Maximum virtio-scsi Port count: 256
Maximum virtio-scsi Devices per Port: 1
Maximum IDE Port count: 2
Maximum IDE Devices per port: 2
Maximum SATA Port count: 30
Maximum SATA Device per port: 1
Maximum SCSI Port count: 16
Maximum SCSI Devices per port: 1
Maximum SAS Port count: 255
Maximum SAS Devices per Port: 1
Maximum NVMe Port count: 255
Maximum NVMe Devices per Port: 1
Supported chipsets: PIIX3, ICH9
PIIX3 chipset properties:
Maximum PIIX3 Network Adapter count: 8
Maximum PIIX3 IDE Controllers: 1
Maximum PIIX3 SATA Controllers: 1
Maximum PIIX3 SCSI Controllers: 1
Maximum PIIX3 SAS Controllers: 1
Maximum PIIX3 NVMe Controllers: 1
Maximum PIIX3 virtio-scsi Controllers: 1
Maximum PIIX3 Floppy Controllers:1
ICH9 chipset properties:
Maximum ICH9 Network Adapter count: 36
Maximum ICH9 IDE Controllers: 1
Maximum ICH9 SATA Controllers: 8
Maximum ICH9 SCSI Controllers: 8
Maximum ICH9 SAS Controllers: 8
Maximum ICH9 NVMe Controllers: 8
Maximum ICH9 virtio-scsi Controllers: 8
Maximum ICH9 Floppy Controllers:1
2025-01-18 21:40:16 (9528):
Command: VBoxManage -q list hostinfo
Exit Code: 0
Output:
Host Information:
Host time: 2025-01-18T13:40:15.966000000Z
Processor online count: 6
Processor count: 6
Processor online core count: 6
Processor core count: 6
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: yes
Processor supports virt. vmsave/vmload: no
Processor#0 speed: unknown
Processor#0 description: Intel(R) Core(TM) i5-9400F CPU @ 2.90GHz
Processor#1 speed: unknown
Processor#1 description: Intel(R) Core(TM) i5-9400F CPU @ 2.90GHz
Processor#2 speed: unknown
Processor#2 description: Intel(R) Core(TM) i5-9400F CPU @ 2.90GHz
Processor#3 speed: unknown
Processor#3 description: Intel(R) Core(TM) i5-9400F CPU @ 2.90GHz
Processor#4 speed: unknown
Processor#4 description: Intel(R) Core(TM) i5-9400F CPU @ 2.90GHz
Processor#5 speed: unknown
Processor#5 description: Intel(R) Core(TM) i5-9400F CPU @ 2.90GHz
Memory size: 32702 MByte
Memory available: 26933 MByte
Operating system: Windows 10
Operating system version: 10.0.19045
2025-01-18 21:40:16 (9528):
Command: VBoxManage -q showvminfo "boinc_957e168e41c386f6" --machinereadable
Exit Code: 0
Output:
name="boinc_957e168e41c386f6"
encryption="disabled"
groups="/"
platformArchitecture="x86"
ostype="Linux 2.6 / 3.x / 4.x / 5.x (64-bit)"
UUID="da107d45-74d2-473a-9543-f65b909606a1"
CfgFile="C:\\ProgramData\\BOINC\\slots\\3\\boinc_957e168e41c386f6\\boinc_957e168e41c386f6.vbox"
SnapFldr="C:\\ProgramData\\BOINC\\slots\\3\\boinc_957e168e41c386f6\\Snapshots"
LogFldr="C:\\ProgramData\\BOINC\\slots\\3\\boinc_957e168e41c386f6\\Logs"
hardwareuuid="da107d45-74d2-473a-9543-f65b909606a1"
memory=630
pagefusion="off"
vram=16
cpuexecutioncap=75
cpu-profile="host"
chipset="piix3"
firmware="BIOS"
cpus=1
hpet="off"
pae="off"
longmode="on"
triplefaultreset="off"
apic="on"
x2apic="on"
nested-hw-virt="off"
hwvirtex="on"
nestedpaging="on"
largepages="on"
vtxvpid="on"
vtxux="on"
virtvmsavevmload="on"
cpuid-portability-level=0
bootmenu="messageandmenu"
boot1="disk"
boot2="dvd"
boot3="none"
boot4="none"
acpi="on"
ioapic="on"
biosapic="apic"
biossystemtimeoffset=0
NvramFile="C:\\ProgramData\\BOINC\\slots\\3\\boinc_957e168e41c386f6\\boinc_957e168e41c386f6.nvram"
rtcuseutc="off"
iommu="none"
paravirtprovider="default"
effparavirtprovider="kvm"
VMState="poweroff"
VMStateChangeTime="2025-01-14T10:35:29.000000000"
graphicscontroller="vboxvga"
monitorcount=1
accelerate3d="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\\3\\boinc_957e168e41c386f6\\Snapshots/{3d3c59fb-623f-4cc8-b972-013944746ba0}.vdi"
"Hard Disk Controller-ImageUUID-0-0"="3d3c59fb-623f-4cc8-b972-013944746ba0"
"Hard Disk Controller-nonrotational-0-0"="off"
"Hard Disk Controller-discard-0-0"="off"
"Hard Disk Controller-1-0"="C:\\Program Files\\Oracle\\VirtualBox\\VBoxGuestAdditions.iso"
"Hard Disk Controller-ImageUUID-1-0"="aafe926e-0730-46c9-be01-9191ca7c54fa"
"Hard Disk Controller-tempeject-1-0"="off"
"Hard Disk Controller-IsEjected-1-0"="off"
"Hard Disk Controller-nonrotational-1-0"="off"
"Hard Disk Controller-discard-1-0"="off"
"Hard Disk Controller-2-0"="none"
natnet1="nat"
macaddress1="08002713A5E8"
cableconnected1="on"
nic1="nat"
nictype1="82540EM"
nicspeed1="0"
mtu="0"
sockSnd="64"
sockRcv="64"
tcpWndSnd="64"
tcpWndRcv="64"
Forwarding(0)="tcp_4511_80,tcp,127.0.0.1,4511,,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"
clipboard_file_transfers="off"
draganddrop="disabled"
vrde="off"
usb="off"
ehci="off"
xhci="off"
BandwidthGroup0="boinc_957e168e41c386f6_net",Network,1099511627776
SharedFolderNameMachineMapping1="shared"
SharedFolderPathMachineMapping1="C:\\ProgramData\\BOINC\\slots\\3\\shared"
recording_started="off"
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\\3\\boinc_957e168e41c386f6\\boinc_957e168e41c386f6-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-3002937-136_0"
GuestMemoryBalloon=0
2025-01-18 21:40:22 (9528):
Command: VBoxManage -q startvm "boinc_957e168e41c386f6" --type headless
Exit Code: -2147467259
Output:
VBoxManage.exe: error: Generic invalid state error. (VERR_INVALID_STATE)
VBoxManage.exe: error: Details: code E_FAIL (0x80004005), component ConsoleWrap, interface IConsole
Waiting for VM "boinc_957e168e41c386f6" to power on...
2025-01-18 21:40:22 (9528):
Command: VBoxManage -q snapshot "boinc_957e168e41c386f6" list
Exit Code: -108
Output:
This machine does not have any snapshots
2025-01-18 21:40:23 (9528):
Command: VBoxManage -q bandwidthctl "boinc_957e168e41c386f6" remove "boinc_957e168e41c386f6_net"
Exit Code: 0
Output:
2025-01-18 21:40:23 (9528):
Command: VBoxManage -q unregistervm "boinc_957e168e41c386f6" --delete
Exit Code: 0
Output:
0%...10%...20%...30%...40%...50%...60%...70%...80%...90%...100%
2025-01-18 21:40:33 (9528): called boinc_finish(-2147467259)
</stderr_txt>
]]>
©2025 CERN