| Name | CMS_3813590_1752478961.195946_0 |
| Workunit | 233608539 |
| Created | 14 Jul 2025, 7:42:44 UTC |
| Sent | 14 Jul 2025, 8:26:21 UTC |
| Report deadline | 14 Aug 2025, 8:26:21 UTC |
| Received | 15 Jul 2025, 18:22:46 UTC |
| Server state | Over |
| Outcome | Computation error |
| Client state | Compute error |
| Exit status | -2147467259 (0x80004005) Unknown error code |
| Computer ID | 10698808 |
| Run time | 17 min 12 sec |
| CPU time | 3 min 17 sec |
| Validate state | Invalid |
| Credit | 0.00 |
| Device peak FLOPS | 15.99 GFLOPS |
| Application version | CMS Simulation v70.91 (vbox64_mt_mcore_cms) windows_x86_64 |
| Peak working set size | 76.30 MB |
| Peak swap size | 79.14 MB |
| Peak disk usage | 145.24 MB |
Stderr output
<core_client_version>8.0.2</core_client_version>
<![CDATA[
<message>
Unspecified error
(0x80004005) - exit code 2147500037 (0x80004005)</message>
<stderr_txt>
2025-07-15 03:55:20 (15528): vboxwrapper version 26210
2025-07-15 03:55:20 (15528): BOINC client version: 8.0.2
2025-07-15 03:55:20 (15528): Detected: VirtualBox VboxManage Interface (Version: 7.0.12)
2025-07-15 03:55:21 (15528): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2025-07-15 03:55:21 (15528): Successfully copied 'init_data.xml' to the shared directory.
2025-07-15 03:55:21 (15528): Create VM. (boinc_8a0d97428190f8f4, slot#1)
2025-07-15 03:55:22 (15528): Setting Memory Size for VM. (4584MB)
2025-07-15 03:55:22 (15528): Setting CPU Count for VM. (4)
2025-07-15 03:55:22 (15528): Setting Chipset Options for VM.
2025-07-15 03:55:23 (15528): Setting Graphics Controller Options for VM. (Driver: VBoxVGA, 16MB)
2025-07-15 03:55:23 (15528): Setting Boot Options for VM.
2025-07-15 03:55:24 (15528): Setting Network Configuration for NAT. (Driver: virtio)
2025-07-15 03:55:25 (15528): Enabling VM Network Access.
2025-07-15 03:55:25 (15528): Disabling USB Support for VM.
2025-07-15 03:55:25 (15528): Disabling COM Port Support for VM.
2025-07-15 03:55:26 (15528): Disabling LPT Port Support for VM.
2025-07-15 03:55:26 (15528): Disabling Audio Support for VM.
2025-07-15 03:55:27 (15528): Disabling Clipboard Support for VM.
2025-07-15 03:55:27 (15528): Disabling Drag and Drop Support for VM.
2025-07-15 03:55:27 (15528): Adding storage controller(s) to VM.
2025-07-15 03:55:27 (15528): Adding virtual disk drive to VM. (CMS_2025_04_08_prod.vdi)
2025-07-15 03:55:31 (15528): Adding VirtualBox Guest Additions to VM.
2025-07-15 03:55:31 (15528): Adding network bandwidth throttle group to VM. (Defaulting to 1024GB)
2025-07-15 03:55:31 (15528): forwarding host port 62323 to guest port 80
2025-07-15 03:55:32 (15528): Enabling remote desktop for VM.
2025-07-15 03:55:32 (15528): Required extension pack not installed, remote desktop not enabled.
2025-07-15 03:55:32 (15528): Enabling shared directory for VM.
2025-07-15 03:55:33 (15528): Starting VM using VBoxManage interface. (boinc_8a0d97428190f8f4, slot#1)
2025-07-15 03:55:42 (15528): Successfully started VM. (PID = '7172')
2025-07-15 03:55:42 (15528): Reporting VM Process ID to BOINC.
2025-07-15 03:55:42 (15528): Guest Log: BIOS: VirtualBox 7.0.12
2025-07-15 03:55:42 (15528): Guest Log: CPUID EDX: 0x178bfbff
2025-07-15 03:55:42 (15528): Guest Log: BIOS: No PCI IDE controller, not probing IDE
2025-07-15 03:55:42 (15528): Guest Log: BIOS: AHCI 0-P#0: PCHS=16383/16/63 LCHS=1024/255/63 0x0000000002800000 sectors
2025-07-15 03:55:42 (15528): VM state change detected. (old = 'poweredoff', new = 'running')
2025-07-15 03:55:42 (15528): Detected: Web Application Enabled (http://localhost:62323)
2025-07-15 03:55:43 (15528): VM state change detected. (old = 'running', new = 'paused')
2025-07-15 03:55:45 (15528): Preference change detected
2025-07-15 03:55:45 (15528): Setting CPU throttle for VM. (60%)
2025-07-15 03:55:45 (15528): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2025-07-15 03:55:46 (15528): VM state change detected. (old = 'paused', new = 'running')
2025-07-15 03:55:47 (15528): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032
2025-07-15 03:55:47 (15528): Guest Log: BIOS: Booting from Hard Disk...
2025-07-15 03:55:52 (15528): Guest Log: BIOS: KBD: unsupported int 16h function 03
2025-07-15 03:55:52 (15528): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000
2025-07-15 03:55:55 (15528): VM state change detected. (old = 'running', new = 'paused')
2025-07-15 04:00:36 (15528): VM state change detected. (old = 'paused', new = 'running')
2025-07-15 04:00:46 (15528): VM state change detected. (old = 'running', new = 'paused')
2025-07-15 04:02:46 (15528): VM state change detected. (old = 'paused', new = 'running')
2025-07-15 04:02:56 (15528): VM state change detected. (old = 'running', new = 'paused')
2025-07-15 04:06:17 (15528): VM state change detected. (old = 'paused', new = 'running')
2025-07-15 04:06:27 (15528): VM state change detected. (old = 'running', new = 'paused')
2025-07-15 04:08:27 (15528): VM state change detected. (old = 'paused', new = 'running')
2025-07-15 04:08:46 (15528): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds
2025-07-15 04:08:46 (15528): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000)
2025-07-15 04:08:47 (15528): VM state change detected. (old = 'running', new = 'paused')
2025-07-15 04:09:07 (15528): VM state change detected. (old = 'paused', new = 'running')
2025-07-15 04:09:09 (15528): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log
2025-07-15 04:09:09 (15528): Guest Log: 00:00:00.000671 main Log opened 2025-07-15T11:06:49.350527000Z
2025-07-15 04:09:09 (15528): Guest Log: 00:00:00.000880 main OS Product: Linux
2025-07-15 04:09:09 (15528): Guest Log: 00:00:00.000932 main OS Release: 4.14.232-19.cernvm.x86_64
2025-07-15 04:09:09 (15528): Guest Log: 00:00:00.000980 main OS Version: #1 SMP Fri Apr 30 17:12:25 CEST 2021
2025-07-15 04:09:09 (15528): Guest Log: 00:00:00.001027 main Executable: /usr/sbin/VBoxService
2025-07-15 04:09:09 (15528): Guest Log: 00:00:00.001029 main Process ID: 2292
2025-07-15 04:09:09 (15528): Guest Log: 00:00:00.001030 main Package type: LINUX_64BITS_GENERIC
2025-07-15 04:09:09 (15528): Guest Log: 00:00:00.002911 main 5.2.6 r120293 started. Verbose level = 0
2025-07-15 04:09:17 (15528): VM state change detected. (old = 'running', new = 'paused')
2025-07-15 04:16:18 (15528): VM state change detected. (old = 'paused', new = 'running')
2025-07-15 04:16:28 (15528): VM state change detected. (old = 'running', new = 'paused')
2025-07-15 04:16:49 (15528): VM state change detected. (old = 'paused', new = 'running')
2025-07-15 04:16:58 (15528): VM state change detected. (old = 'running', new = 'paused')
2025-07-15 04:17:18 (15528): VM state change detected. (old = 'paused', new = 'running')
2025-07-15 04:17:28 (15528): VM state change detected. (old = 'running', new = 'paused')
2025-07-15 04:17:49 (15528): VM state change detected. (old = 'paused', new = 'running')
2025-07-15 04:17:51 (15528): Guest Log: [INFO] Mounting the shared directory
2025-07-15 04:17:51 (15528): Guest Log: [INFO] Shared directory mounted, enabling vboxmonitor
2025-07-15 04:17:51 (15528): Guest Log: [INFO] Sourcing essential functions from /cvmfs/grid.cern.ch
2025-07-15 04:17:52 (15528): Guest Log: [INFO] Testing connection to cern.ch
2025-07-15 04:17:52 (15528): Guest Log: [INFO] Testing connection to VCCS
2025-07-15 04:17:53 (15528): Guest Log: [INFO] Testing connection to HTCondor
2025-07-15 04:17:54 (15528): Guest Log: [INFO] Testing connection to WMAgent
2025-07-15 04:17:54 (15528): Guest Log: [INFO] Testing connection to EOSCMS
2025-07-15 04:17:55 (15528): Guest Log: [INFO] Testing connection to CMS-Factory
2025-07-15 04:17:56 (15528): Guest Log: [INFO] Testing connection to CMS-Frontier
2025-07-15 04:17:57 (15528): Guest Log: [INFO] Testing connection to Frontier
2025-07-15 04:17:57 (15528): Guest Log: [INFO] Testing connection to http://cms-frontier.openhtc.io:8080/FrontierProd/Frontier/
2025-07-15 04:17:58 (15528): VM state change detected. (old = 'running', new = 'paused')
2025-07-15 04:18:38 (15528): VM state change detected. (old = 'paused', new = 'running')
2025-07-15 04:18:39 (15528): Guest Log: [INFO] Could not find a local HTTP proxy
2025-07-15 04:18:39 (15528): Guest Log: [INFO] CVMFS and Frontier will have to use DIRECT connections
2025-07-15 04:18:39 (15528): Guest Log: [INFO] This makes the application less efficient
2025-07-15 04:18:39 (15528): Guest Log: [INFO] It also puts higher load on the project servers
2025-07-15 04:18:40 (15528): Guest Log: [INFO] Setting up a local HTTP proxy is highly recommended
2025-07-15 04:18:40 (15528): Guest Log: [INFO] Advice can be found in the project forum
2025-07-15 04:18:41 (15528): Guest Log: [INFO] Reloading and probing the CVMFS configuration
2025-07-15 04:18:48 (15528): VM state change detected. (old = 'running', new = 'paused')
2025-07-15 04:19:08 (15528): VM state change detected. (old = 'paused', new = 'running')
2025-07-15 04:19:14 (15528): Guest Log: [INFO] Probing /cvmfs/grid.cern.ch... OK
2025-07-15 04:19:18 (15528): VM state change detected. (old = 'running', new = 'paused')
2025-07-15 04:19:38 (15528): VM state change detected. (old = 'paused', new = 'running')
2025-07-15 04:19:42 (15528): Guest Log: [INFO] Probing /cvmfs/cms-ib.cern.ch... OK
2025-07-15 04:19:42 (15528): Guest Log: [INFO] Probing /cvmfs/cms.cern.ch... OK
2025-07-15 04:19:42 (15528): Guest Log: [INFO] Probing /cvmfs/oasis.opensciencegrid.org... OK
2025-07-15 04:19:43 (15528): Guest Log: [INFO] Probing /cvmfs/singularity.opensciencegrid.org... OK
2025-07-15 04:19:45 (15528): Guest Log: [INFO] Excerpt from "cvmfs_config stat": VERSION HOST PROXY
2025-07-15 04:19:45 (15528): Guest Log: [INFO] 2.7.2.0 http://s1fnal-cvmfs.openhtc.io:8080 DIRECT
2025-07-15 04:19:45 (15528): Guest Log: [INFO] Environment HTTP proxy: not set
2025-07-15 04:19:45 (15528): Guest Log: [INFO] Reading volunteer information
2025-07-15 04:19:48 (15528): VM state change detected. (old = 'running', new = 'paused')
2025-07-15 04:20:08 (15528): VM state change detected. (old = 'paused', new = 'running')
2025-07-15 04:20:18 (15528): VM state change detected. (old = 'running', new = 'paused')
2025-07-15 04:20:38 (15528): VM state change detected. (old = 'paused', new = 'running')
2025-07-15 04:20:48 (15528): VM state change detected. (old = 'running', new = 'paused')
2025-07-15 04:21:08 (15528): VM state change detected. (old = 'paused', new = 'running')
2025-07-15 04:21:14 (15528): Guest Log: [INFO] Using weak account key.
2025-07-15 04:21:14 (15528): Guest Log: [INFO] Requesting an X509 credential from LHC@home
2025-07-15 04:21:18 (15528): Guest Log: [INFO] Using weak account key.
2025-07-15 04:21:18 (15528): VM state change detected. (old = 'running', new = 'paused')
2025-07-15 04:23:18 (15528): Guest Log: [INFO] Requesting an idtoken from LHC@home
2025-07-15 04:23:18 (15528): VM state change detected. (old = 'paused', new = 'running')
2025-07-15 04:23:19 (15528): Guest Log: [INFO] CMS application starting. Check log files.
2025-07-15 04:23:29 (15528): VM state change detected. (old = 'running', new = 'paused')
2025-07-15 04:25:09 (15528): VM state change detected. (old = 'paused', new = 'running')
2025-07-15 04:25:19 (15528): VM state change detected. (old = 'running', new = 'paused')
2025-07-15 04:28:18 (15528): VM state change detected. (old = 'paused', new = 'running')
2025-07-15 04:28:29 (15528): VM state change detected. (old = 'running', new = 'paused')
2025-07-15 04:32:49 (15528): VM state change detected. (old = 'paused', new = 'running')
2025-07-15 04:32:59 (15528): VM state change detected. (old = 'running', new = 'paused')
2025-07-15 04:33:59 (15528): VM state change detected. (old = 'paused', new = 'running')
2025-07-15 04:34:09 (15528): VM state change detected. (old = 'running', new = 'paused')
2025-07-15 04:58:34 (15528): VM state change detected. (old = 'paused', new = 'running')
2025-07-15 04:58:42 (15528): VM state change detected. (old = 'running', new = 'paused')
2025-07-15 04:59:01 (15528): VM state change detected. (old = 'paused', new = 'running')
2025-07-15 04:59:11 (15528): VM state change detected. (old = 'running', new = 'paused')
2025-07-15 05:51:57 (15528): VM state change detected. (old = 'paused', new = 'running')
2025-07-15 05:51:59 (15528): Guest Log: 00:03:00.065119 timesync vgsvcTimeSyncWorker: Radical host time change: 3 175 219 000 000ns (HostNow=1 752 583 918 219 000 000 ns HostLast=1 752 580 743 000 000 000 ns)
2025-07-15 05:52:08 (15528): VM state change detected. (old = 'running', new = 'paused')
2025-07-15 05:52:28 (15528): VM state change detected. (old = 'paused', new = 'running')
2025-07-15 05:52:29 (15528): Guest Log: 00:03:10.065503 timesync vgsvcTimeSyncWorker: Radical guest time change: 3 193 814 143 000ns (GuestNow=1 752 583 928 219 523 000 ns GuestLast=1 752 580 734 405 380 000 ns fSetTimeLastLoop=true )
2025-07-15 05:52:38 (15528): VM state change detected. (old = 'running', new = 'paused')
2025-07-15 05:52:58 (15528): VM state change detected. (old = 'paused', new = 'running')
2025-07-15 05:53:07 (15528): VM state change detected. (old = 'running', new = 'paused')
2025-07-15 05:55:47 (15528): VM state change detected. (old = 'paused', new = 'running')
2025-07-15 05:55:57 (15528): VM state change detected. (old = 'running', new = 'paused')
2025-07-15 08:37:45 (15528): VM state change detected. (old = 'paused', new = 'running')
2025-07-15 08:37:47 (15528): Guest Log: 00:03:40.095263 timesync vgsvcTimeSyncWorker: Radical host time change: 9 717 450 000 000ns (HostNow=1 752 593 866 558 000 000 ns HostLast=1 752 584 149 108 000 000 ns)
2025-07-15 08:37:57 (15528): Guest Log: 00:03:50.095735 timesync vgsvcTimeSyncWorker: Radical guest time change: 9 918 309 388 000ns (GuestNow=1 752 593 876 558 590 000 ns GuestLast=1 752 583 958 249 202 000 ns fSetTimeLastLoop=true )
2025-07-15 08:38:02 (15528): VM state change detected. (old = 'running', new = 'paused')
2025-07-15 08:38:22 (15528): VM state change detected. (old = 'paused', new = 'running')
2025-07-15 08:38:32 (15528): VM state change detected. (old = 'running', new = 'paused')
2025-07-15 08:38:52 (15528): VM state change detected. (old = 'paused', new = 'running')
2025-07-15 08:39:02 (15528): VM state change detected. (old = 'running', new = 'paused')
2025-07-15 08:39:22 (15528): VM state change detected. (old = 'paused', new = 'running')
2025-07-15 08:39:32 (15528): VM state change detected. (old = 'running', new = 'paused')
2025-07-15 08:39:52 (15528): VM state change detected. (old = 'paused', new = 'running')
2025-07-15 08:40:02 (15528): VM state change detected. (old = 'running', new = 'paused')
2025-07-15 08:40:22 (15528): VM state change detected. (old = 'paused', new = 'running')
2025-07-15 08:41:12 (15528): VM state change detected. (old = 'running', new = 'paused')
2025-07-15 08:41:32 (15528): VM state change detected. (old = 'paused', new = 'running')
2025-07-15 08:42:02 (15528): VM state change detected. (old = 'running', new = 'paused')
2025-07-15 08:42:22 (15528): VM state change detected. (old = 'paused', new = 'running')
2025-07-15 08:42:42 (15528): VM state change detected. (old = 'running', new = 'paused')
2025-07-15 08:43:03 (15528): VM state change detected. (old = 'paused', new = 'running')
2025-07-15 08:43:13 (15528): VM state change detected. (old = 'running', new = 'paused')
2025-07-15 08:49:14 (15528): VM state change detected. (old = 'paused', new = 'running')
2025-07-15 08:49:35 (15528): VM state change detected. (old = 'running', new = 'paused')
2025-07-15 09:31:46 (15528): VM state change detected. (old = 'paused', new = 'running')
2025-07-15 09:31:47 (15528): VM state change detected. (old = 'running', new = 'paused')
2025-07-15 09:32:08 (15528): VM state change detected. (old = 'paused', new = 'running')
2025-07-15 09:32:10 (15528): Guest Log: 00:06:50.240820 timesync vgsvcTimeSyncWorker: Radical host time change: 2 562 493 000 000ns (HostNow=1 752 597 130 091 000 000 ns HostLast=1 752 594 567 598 000 000 ns)
2025-07-15 09:32:20 (15528): Guest Log: 00:07:00.249463 timesync vgsvcTimeSyncWorker: Radical guest time change: 3 083 396 661 000ns (GuestNow=1 752 597 140 099 926 000 ns GuestLast=1 752 594 056 703 265 000 ns fSetTimeLastLoop=true )
2025-07-15 09:32:37 (15528): VM state change detected. (old = 'running', new = 'paused')
2025-07-15 09:32:57 (15528): VM state change detected. (old = 'paused', new = 'running')
2025-07-15 09:39:43 (15528): Stopping VM.
2025-07-15 09:39:47 (15528): Successfully stopped VM.
2025-07-15 11:03:10 (17096): vboxwrapper version 26210
2025-07-15 11:03:10 (17096): BOINC client version: 8.0.2
2025-07-15 11:03:11 (17096): Detected: VirtualBox VboxManage Interface (Version: 7.0.12)
2025-07-15 11:03:12 (17096): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2025-07-15 11:03:12 (17096): Guest Log: BIOS: VirtualBox 7.0.12
2025-07-15 11:03:12 (17096): Guest Log: CPUID EDX: 0x178bfbff
2025-07-15 11:03:12 (17096): Guest Log: BIOS: No PCI IDE controller, not probing IDE
2025-07-15 11:03:12 (17096): Guest Log: BIOS: AHCI 0-P#0: PCHS=16383/16/63 LCHS=1024/255/63 0x0000000002800000 sectors
2025-07-15 11:03:12 (17096): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032
2025-07-15 11:03:12 (17096): Guest Log: BIOS: Booting from Hard Disk...
2025-07-15 11:03:12 (17096): Guest Log: BIOS: KBD: unsupported int 16h function 03
2025-07-15 11:03:12 (17096): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000
2025-07-15 11:03:12 (17096): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds
2025-07-15 11:03:12 (17096): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000)
2025-07-15 11:03:12 (17096): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log
2025-07-15 11:03:12 (17096): Guest Log: 00:00:00.000671 main Log opened 2025-07-15T11:06:49.350527000Z
2025-07-15 11:03:12 (17096): Guest Log: 00:00:00.000880 main OS Product: Linux
2025-07-15 11:03:12 (17096): Guest Log: 00:00:00.000932 main OS Release: 4.14.232-19.cernvm.x86_64
2025-07-15 11:03:12 (17096): Guest Log: 00:00:00.000980 main OS Version: #1 SMP Fri Apr 30 17:12:25 CEST 2021
2025-07-15 11:03:12 (17096): Guest Log: 00:00:00.001027 main Executable: /usr/sbin/VBoxService
2025-07-15 11:03:12 (17096): Guest Log: 00:00:00.001029 main Process ID: 2292
2025-07-15 11:03:12 (17096): Guest Log: 00:00:00.001030 main Package type: LINUX_64BITS_GENERIC
2025-07-15 11:03:12 (17096): Guest Log: 00:00:00.002911 main 5.2.6 r120293 started. Verbose level = 0
2025-07-15 11:03:12 (17096): Guest Log: [INFO] Mounting the shared directory
2025-07-15 11:03:12 (17096): Guest Log: [INFO] Shared directory mounted, enabling vboxmonitor
2025-07-15 11:03:12 (17096): Guest Log: [INFO] Sourcing essential functions from /cvmfs/grid.cern.ch
2025-07-15 11:03:12 (17096): Guest Log: [INFO] Testing connection to cern.ch
2025-07-15 11:03:12 (17096): Guest Log: [INFO] Testing connection to VCCS
2025-07-15 11:03:12 (17096): Guest Log: [INFO] Testing connection to HTCondor
2025-07-15 11:03:12 (17096): Guest Log: [INFO] Testing connection to WMAgent
2025-07-15 11:03:12 (17096): Guest Log: [INFO] Testing connection to EOSCMS
2025-07-15 11:03:12 (17096): Guest Log: [INFO] Testing connection to CMS-Factory
2025-07-15 11:03:12 (17096): Guest Log: [INFO] Testing connection to CMS-Frontier
2025-07-15 11:03:12 (17096): Guest Log: [INFO] Testing connection to Frontier
2025-07-15 11:03:12 (17096): Guest Log: [INFO] Testing connection to http://cms-frontier.openhtc.io:8080/FrontierProd/Frontier/
2025-07-15 11:03:12 (17096): Guest Log: [INFO] Could not find a local HTTP proxy
2025-07-15 11:03:12 (17096): Guest Log: [INFO] CVMFS and Frontier will have to use DIRECT connections
2025-07-15 11:03:12 (17096): Guest Log: [INFO] This makes the application less efficient
2025-07-15 11:03:12 (17096): Guest Log: [INFO] It also puts higher load on the project servers
2025-07-15 11:03:12 (17096): Guest Log: [INFO] Setting up a local HTTP proxy is highly recommended
2025-07-15 11:03:12 (17096): Guest Log: [INFO] Advice can be found in the project forum
2025-07-15 11:03:12 (17096): Guest Log: [INFO] Reloading and probing the CVMFS configuration
2025-07-15 11:03:12 (17096): Guest Log: [INFO] Probing /cvmfs/grid.cern.ch... OK
2025-07-15 11:03:12 (17096): Guest Log: [INFO] Probing /cvmfs/cms-ib.cern.ch... OK
2025-07-15 11:03:12 (17096): Guest Log: [INFO] Probing /cvmfs/cms.cern.ch... OK
2025-07-15 11:03:12 (17096): Guest Log: [INFO] Probing /cvmfs/oasis.opensciencegrid.org... OK
2025-07-15 11:03:12 (17096): Guest Log: [INFO] Probing /cvmfs/singularity.opensciencegrid.org... OK
2025-07-15 11:03:12 (17096): Guest Log: [INFO] Excerpt from "cvmfs_config stat": VERSION HOST PROXY
2025-07-15 11:03:12 (17096): Guest Log: [INFO] 2.7.2.0 http://s1fnal-cvmfs.openhtc.io:8080 DIRECT
2025-07-15 11:03:12 (17096): Guest Log: [INFO] Environment HTTP proxy: not set
2025-07-15 11:03:12 (17096): Guest Log: [INFO] Reading volunteer information
2025-07-15 11:03:12 (17096): Guest Log: [INFO] Using weak account key.
2025-07-15 11:03:12 (17096): Guest Log: [INFO] Requesting an X509 credential from LHC@home
2025-07-15 11:03:12 (17096): Guest Log: [INFO] Using weak account key.
2025-07-15 11:03:12 (17096): Guest Log: [INFO] Requesting an idtoken from LHC@home
2025-07-15 11:03:12 (17096): Guest Log: [INFO] CMS application starting. Check log files.
2025-07-15 11:03:12 (17096): Guest Log: 00:03:00.065119 timesync vgsvcTimeSyncWorker: Radical host time change: 3 175 219 000 000ns (HostNow=1 752 583 918 219 000 000 ns HostLast=1 752 580 743 000 000 000 ns)
2025-07-15 11:03:12 (17096): Guest Log: 00:03:10.065503 timesync vgsvcTimeSyncWorker: Radical guest time change: 3 193 814 143 000ns (GuestNow=1 752 583 928 219 523 000 ns GuestLast=1 752 580 734 405 380 000 ns fSetTimeLastLoop=true )
2025-07-15 11:03:12 (17096): Guest Log: 00:03:40.095263 timesync vgsvcTimeSyncWorker: Radical host time change: 9 717 450 000 000ns (HostNow=1 752 593 866 558 000 000 ns HostLast=1 752 584 149 108 000 000 ns)
2025-07-15 11:03:12 (17096): Guest Log: 00:03:50.095735 timesync vgsvcTimeSyncWorker: Radical guest time change: 9 918 309 388 000ns (GuestNow=1 752 593 876 558 590 000 ns GuestLast=1 752 583 958 249 202 000 ns fSetTimeLastLoop=true )
2025-07-15 11:03:12 (17096): Guest Log: 00:06:50.240820 timesync vgsvcTimeSyncWorker: Radical host time change: 2 562 493 000 000ns (HostNow=1 752 597 130 091 000 000 ns HostLast=1 752 594 567 598 000 000 ns)
2025-07-15 11:03:12 (17096): Guest Log: 00:07:00.249463 timesync vgsvcTimeSyncWorker: Radical guest time change: 3 083 396 661 000ns (GuestNow=1 752 597 140 099 926 000 ns GuestLast=1 752 594 056 703 265 000 ns fSetTimeLastLoop=true )
2025-07-15 11:03:12 (17096): Starting VM using VBoxManage interface. (boinc_8a0d97428190f8f4, slot#1)
2025-07-15 11:21:11 (17096): Error in start VM for VM: -2147467259
Command:
VBoxManage -q startvm "boinc_8a0d97428190f8f4" --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_8a0d97428190f8f4" to power on...
2025-07-15 11:21:11 (17096): VM failed to start.
2025-07-15 11:21:11 (17096): Could not start
2025-07-15 11:21:11 (17096): ERROR: VM failed to start
2025-07-15 11:21:11 (17096): Powering off VM.
2025-07-15 11:21:11 (17096): Deregistering VM. (boinc_8a0d97428190f8f4, slot#1)
2025-07-15 11:21:11 (17096): Removing network bandwidth throttle group from VM.
2025-07-15 11:21:11 (17096): Removing VM from VirtualBox.
Hypervisor System Log:
00:18:00.989920 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:18:00.989936 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:18:00.989951 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:18:00.989966 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:18:00.989981 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:18:00.990003 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:18:00.990020 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:18:00.990036 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:18:00.990051 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:18:00.990066 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:18:00.990082 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:18:00.990097 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:18:00.990111 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:18:00.990126 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:18:00.990141 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:18:00.990155 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:18:00.991667 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:18:00.991692 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:18:00.991712 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:18:00.991728 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:18:00.991743 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:18:00.991758 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:18:00.991772 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:18:00.991790 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:18:00.991808 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:18:00.991824 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:18:00.991839 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:18:00.991854 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:18:00.991869 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:18:00.991884 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:18:00.991898 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:18:00.991913 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:18:00.991928 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:18:00.991944 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:18:00.992389 Saving settings file "C:\Users\devon\.VirtualBox\VirtualBox.xml" with version "1.12-windows"
00:18:01.000799 Finished saving settings file "C:\Users\devon\.VirtualBox\VirtualBox.xml"
00:18:01.001229 Saving settings file "C:\Users\devon\.VirtualBox\VirtualBox.xml" with version "1.12-windows"
00:18:01.009326 Finished saving settings file "C:\Users\devon\.VirtualBox\VirtualBox.xml"
00:18:01.011240 DeleteVM Saving settings file "C:\Users\devon\.VirtualBox\VirtualBox.xml" with version "1.12-windows"
00:18:01.017985 DeleteVM Finished saving settings file "C:\Users\devon\.VirtualBox\VirtualBox.xml"
VM Execution Log:
VM Startup Log:
VM Trace Log:
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\devon\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-07-15 11:03:12 (17096):
Command: VBoxManage -q list hostinfo
Exit Code: 0
Output:
Host Information:
Host time: 2025-07-15T18:03:11.981000000Z
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-4820K CPU @ 3.70GHz
Processor#1 speed: unknown
Processor#1 description: Intel(R) Core(TM) i7-4820K CPU @ 3.70GHz
Processor#2 speed: unknown
Processor#2 description: Intel(R) Core(TM) i7-4820K CPU @ 3.70GHz
Processor#3 speed: unknown
Processor#3 description: Intel(R) Core(TM) i7-4820K CPU @ 3.70GHz
Processor#4 speed: unknown
Processor#4 description: Intel(R) Core(TM) i7-4820K CPU @ 3.70GHz
Processor#5 speed: unknown
Processor#5 description: Intel(R) Core(TM) i7-4820K CPU @ 3.70GHz
Processor#6 speed: unknown
Processor#6 description: Intel(R) Core(TM) i7-4820K CPU @ 3.70GHz
Processor#7 speed: unknown
Processor#7 description: Intel(R) Core(TM) i7-4820K CPU @ 3.70GHz
Memory size: 24494 MByte
Memory available: 16965 MByte
Operating system: Windows 10
Operating system version: 10.0.19045
2025-07-15 11:03:12 (17096):
Command: VBoxManage -q showvminfo "boinc_8a0d97428190f8f4" --machinereadable
Exit Code: 0
Output:
name="boinc_8a0d97428190f8f4"
Encryption: disabled
groups="/"
ostype="Linux 2.6 / 3.x / 4.x / 5.x (64-bit)"
UUID="de9133fa-2653-43fe-b5e7-0dca0ba670b6"
CfgFile="D:\\ProgramData\\BOINC\\slots\\1\\boinc_8a0d97428190f8f4\\boinc_8a0d97428190f8f4.vbox"
SnapFldr="D:\\ProgramData\\BOINC\\slots\\1\\boinc_8a0d97428190f8f4\\Snapshots"
LogFldr="D:\\ProgramData\\BOINC\\slots\\1\\boinc_8a0d97428190f8f4\\Logs"
hardwareuuid="de9133fa-2653-43fe-b5e7-0dca0ba670b6"
memory=4584
pagefusion="off"
vram=16
cpuexecutioncap=60
hpet="off"
cpu-profile="host"
chipset="piix3"
firmware="BIOS"
cpus=4
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="D:\\ProgramData\\BOINC\\slots\\1\\boinc_8a0d97428190f8f4\\boinc_8a0d97428190f8f4.nvram"
rtcuseutc="off"
hwvirtex="on"
nestedpaging="on"
largepages="on"
vtxvpid="on"
vtxux="on"
virtvmsavevmload="on"
iommu="none"
paravirtprovider="default"
effparavirtprovider="kvm"
VMState="saved"
VMStateChangeTime="2025-07-15T16:39:46.000000000"
VMStateFile="D:\ProgramData\BOINC\slots\1\boinc_8a0d97428190f8f4\Snapshots\2025-07-15T16-39-43-198253300Z.sav"
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"="D:\\ProgramData\\BOINC\\slots\\1\\boinc_8a0d97428190f8f4\\Snapshots/{4f54e424-5751-4cf9-a5a9-0a42d57892c4}.vdi"
"Hard Disk Controller-ImageUUID-0-0"="4f54e424-5751-4cf9-a5a9-0a42d57892c4"
"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"="f53cac47-d9b5-4709-aa51-f025bbd8545f"
"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="0800271C2E7A"
cableconnected1="on"
nic1="nat"
nictype1="virtio"
nicspeed1="0"
mtu="0"
sockSnd="64"
sockRcv="64"
tcpWndSnd="64"
tcpWndRcv="64"
Forwarding(0)="tcp_62323_80,tcp,127.0.0.1,62323,,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_8a0d97428190f8f4_net",Network,1099511627776
SharedFolderNameMachineMapping1="shared"
SharedFolderPathMachineMapping1="D:\\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="D:\\ProgramData\\BOINC\\slots\\1\\boinc_8a0d97428190f8f4\\boinc_8a0d97428190f8f4-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="CMS_3813590_1752478961.195946_0"
GuestMemoryBalloon=0
2025-07-15 11:21:11 (17096):
Command: VBoxManage -q startvm "boinc_8a0d97428190f8f4" --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_8a0d97428190f8f4" to power on...
2025-07-15 11:21:11 (17096):
Command: VBoxManage -q snapshot "boinc_8a0d97428190f8f4" list
Exit Code: -108
Output:
This machine does not have any snapshots
2025-07-15 11:21:11 (17096):
Command: VBoxManage -q bandwidthctl "boinc_8a0d97428190f8f4" remove "boinc_8a0d97428190f8f4_net"
Exit Code: 0
Output:
2025-07-15 11:21:12 (17096):
Command: VBoxManage -q unregistervm "boinc_8a0d97428190f8f4" --delete
Exit Code: 0
Output:
0%...10%...20%...30%...40%...50%...60%...70%...80%...90%...100%
2025-07-15 11:21:22 (17096): called boinc_finish(-2147467259)
</stderr_txt>
]]>
©2025 CERN