Name C7aLDmu2bT5n9Rq4apoT9bVoABFKDmABFKDmlqFKDm6iOKDmlQJ5So_0
Workunit 223161471
Created 21 May 2024, 10:37:30 UTC
Sent 27 Sep 2024, 13:19:56 UTC
Report deadline 8 Sep 2025, 12:46:36 UTC
Received 23 May 2024, 3:30:43 UTC
Server state In progress
Outcome Success
Client state Compute error
Exit status -2147467259 (0x80004005) Unknown error code
Computer ID 10803325
Run time 21 hours 59 min 27 sec
CPU time 15 hours 21 min 42 sec
Validate state Initial
Credit 0.00
Device peak FLOPS 3.77 GFLOPS
Application version ATLAS Simulation v3.01 (vbox64_mt_mcore_atlas)
windows_x86_64
Peak working set size 88.67 MB
Peak swap size 108.28 MB
Peak disk usage 2.71 GB

Stderr output

<core_client_version>7.24.1</core_client_version>
<![CDATA[
<message>
Unspecified error
 (0x80004005) - exit code 2147500037 (0x80004005)</message>
<stderr_txt>
2024-05-21 16:36:56 (35596): Detected: vboxwrapper 26206
2024-05-21 16:36:56 (35596): Detected: BOINC client v7.24.1
2024-05-21 16:36:56 (35596): Detected: VirtualBox VboxManage Interface (Version: 7.0.14)
2024-05-21 16:36:57 (35596): Successfully copied 'init_data.xml' to the shared directory.
2024-05-21 16:36:57 (35596): Create VM. (boinc_ac126dfc4892e95c, slot#15)
2024-05-21 16:36:58 (35596): Setting Memory Size for VM. (4100MB)
2024-05-21 16:36:59 (35596): Setting CPU Count for VM. (1)
2024-05-21 16:36:59 (35596): Setting Chipset Options for VM.
2024-05-21 16:36:59 (35596): Setting Graphics Controller Options for VM.
2024-05-21 16:37:00 (35596): Setting Boot Options for VM.
2024-05-21 16:37:00 (35596): Setting Network Configuration for NAT.
2024-05-21 16:37:00 (35596): Enabling VM Network Access.
2024-05-21 16:37:00 (35596): Disabling USB Support for VM.
2024-05-21 16:37:01 (35596): Disabling COM Port Support for VM.
2024-05-21 16:37:01 (35596): Disabling LPT Port Support for VM.
2024-05-21 16:37:01 (35596): Disabling Audio Support for VM.
2024-05-21 16:37:02 (35596): Disabling Clipboard Support for VM.
2024-05-21 16:37:02 (35596): Disabling Drag and Drop Support for VM.
2024-05-21 16:37:02 (35596): Adding storage controller(s) to VM.
2024-05-21 16:37:03 (35596): Adding virtual disk drive to VM. (ATLAS_vbox_3.01_image.vdi)
2024-05-21 16:37:03 (35596): Adding VirtualBox Guest Additions to VM.
2024-05-21 16:37:04 (35596): Adding network bandwidth throttle group to VM. (Defaulting to 1024GB)
2024-05-21 16:37:04 (35596): forwarding host port 62903 to guest port 80
2024-05-21 16:37:05 (35596): Enabling remote desktop for VM.
2024-05-21 16:37:05 (35596): Enabling shared directory for VM.
2024-05-21 16:37:06 (35596): Starting VM using VBoxManage interface. (boinc_ac126dfc4892e95c, slot#15)
2024-05-21 16:37:14 (35596): Successfully started VM. (PID = '22708')
2024-05-21 16:37:14 (35596): Reporting VM Process ID to BOINC.
2024-05-21 16:37:14 (35596): Guest Log: BIOS: VirtualBox 7.0.14
2024-05-21 16:37:14 (35596): Guest Log: CPUID EDX: 0x178bfbff
2024-05-21 16:37:14 (35596): Guest Log: BIOS: No PCI IDE controller, not probing IDE
2024-05-21 16:37:14 (35596): Guest Log: BIOS: AHCI 0-P#0: PCHS=16383/16/63 LCHS=1024/255/63 0x0000000002800000 sectors
2024-05-21 16:37:14 (35596): VM state change detected. (old = 'poweredoff', new = 'running')
2024-05-21 16:37:14 (35596): Detected: Web Application Enabled (http://localhost:62903)
2024-05-21 16:37:14 (35596): Detected: Remote Desktop Enabled (localhost:62904)
2024-05-21 16:37:14 (35596): Preference change detected
2024-05-21 16:37:14 (35596): Setting CPU throttle for VM. (90%)
2024-05-21 16:37:14 (35596): Setting checkpoint interval to 900 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 900 seconds))
2024-05-21 16:37:16 (35596): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032
2024-05-21 16:37:16 (35596): Guest Log: BIOS: Booting from Hard Disk...
2024-05-21 16:37:19 (35596): Guest Log: BIOS: KBD: unsupported int 16h function 03
2024-05-21 16:37:19 (35596): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 
2024-05-21 16:37:19 (35596): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=81
2024-05-21 16:37:19 (35596): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=81
2024-05-21 16:37:19 (35596): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=82
2024-05-21 16:37:19 (35596): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=82
2024-05-21 16:37:19 (35596): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=83
2024-05-21 16:37:19 (35596): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=83
2024-05-21 16:37:19 (35596): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=84
2024-05-21 16:37:19 (35596): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=84
2024-05-21 16:37:19 (35596): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=85
2024-05-21 16:37:19 (35596): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=85
2024-05-21 16:37:19 (35596): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=86
2024-05-21 16:37:19 (35596): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=86
2024-05-21 16:37:19 (35596): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=87
2024-05-21 16:37:19 (35596): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=87
2024-05-21 16:37:19 (35596): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=88
2024-05-21 16:37:19 (35596): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=88
2024-05-21 16:37:19 (35596): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=89
2024-05-21 16:37:19 (35596): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=89
2024-05-21 16:37:19 (35596): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=8a
2024-05-21 16:37:19 (35596): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=8a
2024-05-21 16:37:19 (35596): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=8b
2024-05-21 16:37:19 (35596): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=8b
2024-05-21 16:37:19 (35596): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=8c
2024-05-21 16:37:19 (35596): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=8c
2024-05-21 16:37:19 (35596): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=8d
2024-05-21 16:37:19 (35596): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=8d
2024-05-21 16:37:19 (35596): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=8e
2024-05-21 16:37:19 (35596): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=8e
2024-05-21 16:37:19 (35596): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=8f
2024-05-21 16:37:19 (35596): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=8f
2024-05-21 16:37:31 (35596): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds
2024-05-21 16:37:31 (35596): Guest Log: vboxguest: misc device minor 58, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000)
2024-05-21 16:37:57 (35596): Guest Log: VBoxService 5.2.32 r132073 (verbosity: 0) linux.amd64 (Jul 12 2019 10:32:28) release log
2024-05-21 16:37:57 (35596): Guest Log: 00:00:00.000260 main     Log opened 2024-05-21T16:37:55.755921000Z
2024-05-21 16:37:57 (35596): Guest Log: 00:00:00.000432 main     OS Product: Linux
2024-05-21 16:37:57 (35596): Guest Log: 00:00:00.000499 main     OS Release: 3.10.0-957.27.2.el7.x86_64
2024-05-21 16:37:57 (35596): Guest Log: 00:00:00.000539 main     OS Version: #1 SMP Mon Jul 29 17:46:05 UTC 2019
2024-05-21 16:37:57 (35596): Guest Log: 00:00:00.000567 main     Executable: /opt/VBoxGuestAdditions-5.2.32/sbin/VBoxService
2024-05-21 16:37:57 (35596): Guest Log: 00:00:00.000568 main     Process ID: 1244
2024-05-21 16:37:57 (35596): Guest Log: 00:00:00.000568 main     Package type: LINUX_64BITS_GENERIC
2024-05-21 16:37:57 (35596): Guest Log: 00:00:00.003613 main     5.2.32 r132073 started. Verbose level = 0
2024-05-21 16:37:58 (35596): Guest Log: [INFO] Probing /cvmfs/atlas.cern.ch...
2024-05-21 16:37:58 (35596): Guest Log: [INFO] Mounting shared directory
2024-05-21 16:37:58 (35596): Guest Log: [INFO] Checking for init_data.xml
2024-05-21 16:38:07 (35596): Guest Log: 00:00:10.044661 timesync vgsvcTimeSyncWorker: Radical guest time change: 25 210 888 241 000ns (GuestNow=1 716 334 686 684 745 000 ns GuestLast=1 716 309 475 796 504 000 ns fSetTimeLastLoop=true )
2024-05-21 16:38:09 (35596): Guest Log: [INFO] Probing /cvmfs/atlas.cern.ch... OK
2024-05-21 16:38:09 (35596): Guest Log: [INFO] Detected branch: prod
2024-05-21 16:38:09 (35596): Guest Log: This is the prod version of the ATLAS job wrapper
2024-05-21 16:38:09 (35596): Guest Log: Copying input files
2024-05-21 16:38:18 (35596): Guest Log: Copied input files into RunAtlas.
2024-05-21 16:38:18 (35596): Guest Log: This VM did not configure a local http proxy via BOINC.
2024-05-21 16:38:18 (35596): Guest Log: Small home clusters do not require a local http proxy but it is suggested if
2024-05-21 16:38:18 (35596): Guest Log: more than 10 cores throughout the same LAN segment are regularly running ATLAS like tasks.
2024-05-21 16:38:18 (35596): Guest Log: Further information can be found at the LHC@home message board.
2024-05-21 16:38:30 (35596): Guest Log: Running cvmfs_config stat atlas.cern.ch
2024-05-21 16:38:30 (35596): Guest Log: VERSION PID UPTIME(M) MEM(K) REVISION EXPIRES(M) NOCATALOGS CACHEUSE(K) CACHEMAX(K) NOFDUSE NOFDMAX NOIOERR NOOPEN HITRATE(%) RX(K) SPEED(K/S) HOST PROXY ONLINE
2024-05-21 16:38:30 (35596): Guest Log: 2.6.3.0 1541 0 30068 133007 4 1 3116989 4096000 0 65024 0 0 n/a 1 0 http://s1ral-cvmfs.openhtc.io/cvmfs/atlas.cern.ch DIRECT 1
2024-05-21 16:38:35 (35596): Guest Log: copied the webapp to /var/www
2024-05-21 16:38:35 (35596): Guest Log: core_number=1
2024-05-21 16:38:35 (35596): Guest Log: Apptainer command /cvmfs/atlas.cern.ch/repo/containers/sw/apptainer/x86_64-el7/current/bin/apptainer exec -B /cvmfs,/data,/home/atlas/RunAtlas /cvmfs/atlas.cern.ch/repo/containers/fs/singularity/x86_64-centos7 
2024-05-21 16:38:35 (35596): Guest Log:  *** Starting ATLAS job. (PandaID=6211388378 taskID=38921007) ***
2024-05-21 16:56:42 (35596): VM state change detected. (old = 'running', new = 'paused')
2024-05-21 16:57:02 (35596): VM state change detected. (old = 'paused', new = 'running')
2024-05-21 17:08:55 (35596): VM state change detected. (old = 'running', new = 'paused')
2024-05-21 17:09:17 (35596): VM state change detected. (old = 'paused', new = 'running')
2024-05-21 17:25:18 (35596): VM state change detected. (old = 'running', new = 'paused')
2024-05-21 17:25:38 (35596): VM state change detected. (old = 'paused', new = 'running')
2024-05-21 17:37:51 (35596): VM state change detected. (old = 'running', new = 'paused')
2024-05-21 17:38:12 (35596): VM state change detected. (old = 'paused', new = 'running')
2024-05-21 17:53:15 (35596): VM state change detected. (old = 'running', new = 'paused')
2024-05-21 17:53:36 (35596): VM state change detected. (old = 'paused', new = 'running')
2024-05-21 18:10:58 (35596): VM state change detected. (old = 'running', new = 'paused')
2024-05-21 18:11:19 (35596): VM state change detected. (old = 'paused', new = 'running')
2024-05-21 18:14:49 (35596): VM state change detected. (old = 'running', new = 'paused')
2024-05-21 18:15:10 (35596): VM state change detected. (old = 'paused', new = 'running')
2024-05-21 18:19:52 (35596): Status Report: Elapsed Time: '6000.000000'
2024-05-21 18:19:52 (35596): Status Report: CPU Time: '3293.421875'
2024-05-21 18:21:43 (35596): VM state change detected. (old = 'running', new = 'paused')
2024-05-21 18:22:03 (35596): VM state change detected. (old = 'paused', new = 'running')
2024-05-21 18:48:48 (35596): VM state change detected. (old = 'running', new = 'paused')
2024-05-21 18:49:09 (35596): VM state change detected. (old = 'paused', new = 'running')
2024-05-21 19:15:34 (35596): VM state change detected. (old = 'running', new = 'paused')
2024-05-21 19:15:54 (35596): VM state change detected. (old = 'paused', new = 'running')
2024-05-21 19:42:28 (35596): VM state change detected. (old = 'running', new = 'paused')
2024-05-21 19:42:49 (35596): VM state change detected. (old = 'paused', new = 'running')
2024-05-21 19:52:21 (35596): VM state change detected. (old = 'running', new = 'paused')
2024-05-21 19:52:42 (35596): VM state change detected. (old = 'paused', new = 'running')
2024-05-21 20:01:35 (35596): VM state change detected. (old = 'running', new = 'paused')
2024-05-21 20:01:55 (35596): VM state change detected. (old = 'paused', new = 'running')
2024-05-21 20:02:07 (35596): Status Report: Elapsed Time: '12000.000000'
2024-05-21 20:02:07 (35596): Status Report: CPU Time: '6922.343750'
2024-05-21 20:10:09 (35596): VM state change detected. (old = 'running', new = 'paused')
2024-05-21 20:10:29 (35596): VM state change detected. (old = 'paused', new = 'running')
2024-05-21 20:38:06 (35596): VM state change detected. (old = 'running', new = 'paused')
2024-05-21 20:38:26 (35596): VM state change detected. (old = 'paused', new = 'running')
2024-05-21 21:04:03 (35596): VM state change detected. (old = 'running', new = 'paused')
2024-05-21 21:04:23 (35596): VM state change detected. (old = 'paused', new = 'running')
2024-05-21 21:31:29 (35596): VM state change detected. (old = 'running', new = 'paused')
2024-05-21 21:31:49 (35596): VM state change detected. (old = 'paused', new = 'running')
2024-05-21 21:35:50 (35596): VM state change detected. (old = 'running', new = 'paused')
2024-05-21 21:36:10 (35596): VM state change detected. (old = 'paused', new = 'running')
2024-05-21 21:43:58 (35596): Status Report: Elapsed Time: '18000.635536'
2024-05-21 21:43:58 (35596): Status Report: CPU Time: '10648.078125'
2024-05-21 21:59:04 (35596): VM state change detected. (old = 'running', new = 'paused')
2024-05-21 21:59:24 (35596): VM state change detected. (old = 'paused', new = 'running')
2024-05-21 22:10:58 (35596): VM state change detected. (old = 'running', new = 'paused')
2024-05-21 22:11:18 (35596): VM state change detected. (old = 'paused', new = 'running')
2024-05-21 22:26:51 (35596): VM state change detected. (old = 'running', new = 'paused')
2024-05-21 22:27:12 (35596): VM state change detected. (old = 'paused', new = 'running')
2024-05-21 22:52:17 (35596): VM state change detected. (old = 'running', new = 'paused')
2024-05-21 22:52:38 (35596): VM state change detected. (old = 'paused', new = 'running')
2024-05-21 23:25:30 (35596): Status Report: Elapsed Time: '24000.635536'
2024-05-21 23:25:30 (35596): Status Report: CPU Time: '15128.437500'
2024-05-21 23:25:47 (35596): VM state change detected. (old = 'running', new = 'paused')
2024-05-21 23:26:08 (35596): VM state change detected. (old = 'paused', new = 'running')
2024-05-22 01:05:58 (35596): Status Report: Elapsed Time: '30000.635536'
2024-05-22 01:05:58 (35596): Status Report: CPU Time: '20172.078125'
2024-05-22 01:15:03 (35596): VM state change detected. (old = 'running', new = 'paused')
2024-05-22 01:15:24 (35596): VM state change detected. (old = 'paused', new = 'running')
2024-05-22 01:16:44 (35596): VM state change detected. (old = 'running', new = 'paused')
2024-05-22 01:17:05 (35596): VM state change detected. (old = 'paused', new = 'running')
2024-05-22 01:33:41 (35596): VM state change detected. (old = 'running', new = 'paused')
2024-05-22 01:34:02 (35596): VM state change detected. (old = 'paused', new = 'running')
2024-05-22 01:43:34 (35596): VM state change detected. (old = 'running', new = 'paused')
2024-05-22 01:43:55 (35596): VM state change detected. (old = 'paused', new = 'running')
2024-05-22 02:11:11 (35596): VM state change detected. (old = 'running', new = 'paused')
2024-05-22 02:11:32 (35596): VM state change detected. (old = 'paused', new = 'running')
2024-05-22 02:38:36 (35596): VM state change detected. (old = 'running', new = 'paused')
2024-05-22 02:38:56 (35596): VM state change detected. (old = 'paused', new = 'running')
2024-05-22 02:48:14 (35596): Status Report: Elapsed Time: '36000.635536'
2024-05-22 02:48:14 (35596): Status Report: CPU Time: '24427.234375'
2024-05-22 02:48:39 (35596): VM state change detected. (old = 'running', new = 'paused')
2024-05-22 02:48:59 (35596): VM state change detected. (old = 'paused', new = 'running')
2024-05-22 03:06:32 (35596): VM state change detected. (old = 'running', new = 'paused')
2024-05-22 03:06:52 (35596): VM state change detected. (old = 'paused', new = 'running')
2024-05-22 03:35:19 (35596): VM state change detected. (old = 'running', new = 'paused')
2024-05-22 03:35:40 (35596): VM state change detected. (old = 'paused', new = 'running')
2024-05-22 03:36:30 (35596): VM state change detected. (old = 'running', new = 'paused')
2024-05-22 03:36:31 (35596): VM state change detected. (old = 'paused', new = 'running')
2024-05-22 04:04:57 (35596): VM state change detected. (old = 'running', new = 'paused')
2024-05-22 04:05:17 (35596): VM state change detected. (old = 'paused', new = 'running')
2024-05-22 04:11:29 (35596): VM state change detected. (old = 'running', new = 'paused')
2024-05-22 04:11:49 (35596): VM state change detected. (old = 'paused', new = 'running')
2024-05-22 04:30:08 (35596): Status Report: Elapsed Time: '42000.635536'
2024-05-22 04:30:08 (35596): Status Report: CPU Time: '28912.921875'
2024-05-22 04:38:05 (35596): VM state change detected. (old = 'running', new = 'paused')
2024-05-22 04:38:25 (35596): VM state change detected. (old = 'paused', new = 'running')
2024-05-22 04:40:57 (35596): VM state change detected. (old = 'running', new = 'paused')
2024-05-22 04:41:17 (35596): VM state change detected. (old = 'paused', new = 'running')
2024-05-22 04:57:51 (35596): VM state change detected. (old = 'running', new = 'paused')
2024-05-22 04:58:11 (35596): VM state change detected. (old = 'paused', new = 'running')
2024-05-22 05:15:34 (35596): VM state change detected. (old = 'running', new = 'paused')
2024-05-22 05:15:54 (35596): VM state change detected. (old = 'paused', new = 'running')
2024-05-22 05:44:12 (35596): VM state change detected. (old = 'running', new = 'paused')
2024-05-22 05:44:33 (35596): VM state change detected. (old = 'paused', new = 'running')
2024-05-22 06:12:00 (35596): Status Report: Elapsed Time: '48000.635536'
2024-05-22 06:12:00 (35596): Status Report: CPU Time: '32650.546875'
2024-05-22 06:12:46 (35596): VM state change detected. (old = 'running', new = 'paused')
2024-05-22 06:13:06 (35596): VM state change detected. (old = 'paused', new = 'running')
2024-05-22 06:42:31 (35596): VM state change detected. (old = 'running', new = 'paused')
2024-05-22 06:42:51 (35596): VM state change detected. (old = 'paused', new = 'running')
2024-05-22 06:45:34 (35596): VM state change detected. (old = 'running', new = 'paused')
2024-05-22 06:45:54 (35596): VM state change detected. (old = 'paused', new = 'running')
2024-05-22 07:14:28 (35596): VM state change detected. (old = 'running', new = 'paused')
2024-05-22 07:14:48 (35596): VM state change detected. (old = 'paused', new = 'running')
2024-05-22 07:45:25 (35596): VM state change detected. (old = 'running', new = 'paused')
2024-05-22 07:45:45 (35596): VM state change detected. (old = 'paused', new = 'running')
2024-05-22 07:53:52 (35596): Status Report: Elapsed Time: '54000.635536'
2024-05-22 07:53:52 (35596): Status Report: CPU Time: '36861.171875'
2024-05-22 08:02:58 (35596): VM state change detected. (old = 'running', new = 'paused')
2024-05-22 08:03:18 (35596): VM state change detected. (old = 'paused', new = 'running')
2024-05-22 08:21:52 (35596): VM state change detected. (old = 'running', new = 'paused')
2024-05-22 08:22:13 (35596): VM state change detected. (old = 'paused', new = 'running')
2024-05-22 09:14:02 (35596): VM state change detected. (old = 'running', new = 'paused')
2024-05-22 09:14:22 (35596): VM state change detected. (old = 'paused', new = 'running')
2024-05-22 09:21:23 (35596): VM state change detected. (old = 'running', new = 'paused')
2024-05-22 09:21:44 (35596): VM state change detected. (old = 'paused', new = 'running')
2024-05-22 09:33:56 (35596): VM state change detected. (old = 'running', new = 'paused')
2024-05-22 09:34:16 (35596): VM state change detected. (old = 'paused', new = 'running')
2024-05-22 09:35:45 (35596): Status Report: Elapsed Time: '60000.635536'
2024-05-22 09:35:45 (35596): Status Report: CPU Time: '41225.156250'
2024-05-22 09:59:51 (35596): VM state change detected. (old = 'running', new = 'paused')
2024-05-22 10:00:11 (35596): VM state change detected. (old = 'paused', new = 'running')
2024-05-22 10:06:34 (35596): VM state change detected. (old = 'running', new = 'paused')
2024-05-22 10:06:54 (35596): VM state change detected. (old = 'paused', new = 'running')
2024-05-22 10:08:14 (35596): VM state change detected. (old = 'running', new = 'paused')
2024-05-22 10:08:34 (35596): VM state change detected. (old = 'paused', new = 'running')
2024-05-22 10:15:36 (35596): VM state change detected. (old = 'running', new = 'paused')
2024-05-22 10:15:56 (35596): VM state change detected. (old = 'paused', new = 'running')
2024-05-22 10:20:37 (35596): VM state change detected. (old = 'running', new = 'paused')
2024-05-22 10:20:57 (35596): VM state change detected. (old = 'paused', new = 'running')
2024-05-22 10:36:00 (35596): VM state change detected. (old = 'running', new = 'paused')
2024-05-22 10:36:20 (35596): VM state change detected. (old = 'paused', new = 'running')
2024-05-22 10:40:41 (35596): VM state change detected. (old = 'running', new = 'paused')
2024-05-22 10:41:01 (35596): VM state change detected. (old = 'paused', new = 'running')
2024-05-22 10:42:01 (35596): VM state change detected. (old = 'running', new = 'paused')
2024-05-22 10:42:22 (35596): VM state change detected. (old = 'paused', new = 'running')
2024-05-22 10:44:05 (35596): VM state change detected. (old = 'running', new = 'paused')
2024-05-22 10:44:23 (35596): VM state change detected. (old = 'paused', new = 'running')
2024-05-22 10:52:56 (35596): VM state change detected. (old = 'running', new = 'paused')
2024-05-22 10:53:16 (35596): VM state change detected. (old = 'paused', new = 'running')
2024-05-22 11:01:50 (35596): VM state change detected. (old = 'running', new = 'paused')
2024-05-22 11:02:10 (35596): VM state change detected. (old = 'paused', new = 'running')
2024-05-22 11:10:32 (35596): VM state change detected. (old = 'running', new = 'paused')
2024-05-22 11:10:52 (35596): VM state change detected. (old = 'paused', new = 'running')
2024-05-22 11:20:05 (35596): Status Report: Elapsed Time: '66000.635536'
2024-05-22 11:20:05 (35596): Status Report: CPU Time: '45635.406250'
2024-05-22 11:30:07 (35596): VM state change detected. (old = 'running', new = 'paused')
2024-05-22 11:30:27 (35596): VM state change detected. (old = 'paused', new = 'running')
2024-05-22 11:42:31 (35596): VM state change detected. (old = 'running', new = 'paused')
2024-05-22 11:42:51 (35596): VM state change detected. (old = 'paused', new = 'running')
2024-05-22 11:49:22 (35596): VM state change detected. (old = 'running', new = 'paused')
2024-05-22 11:49:43 (35596): VM state change detected. (old = 'paused', new = 'running')
2024-05-22 11:52:53 (35596): VM state change detected. (old = 'running', new = 'paused')
2024-05-22 11:53:13 (35596): VM state change detected. (old = 'paused', new = 'running')
2024-05-22 11:56:34 (35596): VM state change detected. (old = 'running', new = 'paused')
2024-05-22 11:56:54 (35596): VM state change detected. (old = 'paused', new = 'running')
2024-05-22 12:12:17 (35596): VM state change detected. (old = 'running', new = 'paused')
2024-05-22 12:12:37 (35596): VM state change detected. (old = 'paused', new = 'running')
2024-05-22 12:14:47 (35596): VM state change detected. (old = 'running', new = 'paused')
2024-05-22 12:15:07 (35596): VM state change detected. (old = 'paused', new = 'running')
2024-05-22 12:16:07 (35596): VM state change detected. (old = 'running', new = 'paused')
2024-05-22 12:16:27 (35596): VM state change detected. (old = 'paused', new = 'running')
2024-05-22 12:43:12 (35596): VM state change detected. (old = 'running', new = 'paused')
2024-05-22 12:43:53 (35596): VM state change detected. (old = 'paused', new = 'running')
2024-05-22 12:49:57 (35596): VM state change detected. (old = 'running', new = 'paused')
2024-05-22 12:49:58 (35596): VM state change detected. (old = 'paused', new = 'running')
2024-05-22 12:55:57 (35596): VM state change detected. (old = 'running', new = 'paused')
2024-05-22 12:56:17 (35596): VM state change detected. (old = 'paused', new = 'running')
2024-05-22 13:04:08 (35596): Status Report: Elapsed Time: '72000.635536'
2024-05-22 13:04:08 (35596): Status Report: CPU Time: '50236.250000'
2024-05-22 13:21:01 (35596): VM state change detected. (old = 'running', new = 'paused')
2024-05-22 13:21:22 (35596): VM state change detected. (old = 'paused', new = 'running')
2024-05-22 13:44:10 (35596): VM state change detected. (old = 'running', new = 'paused')
2024-05-22 13:44:28 (35596): VM state change detected. (old = 'paused', new = 'running')
2024-05-22 13:48:28 (35596): VM state change detected. (old = 'running', new = 'paused')
2024-05-22 13:48:49 (35596): VM state change detected. (old = 'paused', new = 'running')
2024-05-22 13:53:09 (35596): VM state change detected. (old = 'running', new = 'paused')
2024-05-22 13:53:30 (35596): VM state change detected. (old = 'paused', new = 'running')
2024-05-22 14:24:47 (35596): VM state change detected. (old = 'running', new = 'paused')
2024-05-22 14:25:08 (35596): VM state change detected. (old = 'paused', new = 'running')
2024-05-22 14:46:04 (35596): Status Report: Elapsed Time: '78000.635536'
2024-05-22 14:46:04 (35596): Status Report: CPU Time: '54506.453125'
2024-05-22 14:54:13 (35596): VM state change detected. (old = 'running', new = 'paused')
2024-05-22 14:54:33 (35596): VM state change detected. (old = 'paused', new = 'running')
2024-05-22 14:57:04 (35596): VM state change detected. (old = 'running', new = 'paused')
2024-05-22 14:57:24 (35596): VM state change detected. (old = 'paused', new = 'running')
2024-05-22 15:04:16 (35596): VM state change detected. (old = 'running', new = 'paused')
2024-05-22 15:04:26 (35596): Stopping VM.
2024-05-22 15:05:12 (35596): Error in stop VM for VM: -182
Command:
VBoxManage -q controlvm "boinc_ac126dfc4892e95c" savestate
Output:
0%...10%...20%...30%...40%...50%...60%...
2024-05-22 15:05:12 (35596): VM did not stop when requested.
2024-05-22 15:05:12 (35596): VM was successfully terminated.
2024-05-22 19:29:20 (1700): Detected: vboxwrapper 26206
2024-05-22 19:29:20 (1700): Detected: BOINC client v7.24.1
2024-05-22 19:29:34 (1700): Detected: VirtualBox VboxManage Interface (Version: 7.0.14)
2024-05-22 19:29:37 (1700): Guest Log: BIOS: VirtualBox 7.0.14
2024-05-22 19:29:37 (1700): Guest Log: CPUID EDX: 0x178bfbff
2024-05-22 19:29:37 (1700): Guest Log: BIOS: No PCI IDE controller, not probing IDE
2024-05-22 19:29:37 (1700): Guest Log: BIOS: AHCI 0-P#0: PCHS=16383/16/63 LCHS=1024/255/63 0x0000000002800000 sectors
2024-05-22 19:29:37 (1700): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032
2024-05-22 19:29:37 (1700): Guest Log: BIOS: Booting from Hard Disk...
2024-05-22 19:29:37 (1700): Guest Log: BIOS: KBD: unsupported int 16h function 03
2024-05-22 19:29:37 (1700): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 
2024-05-22 19:29:37 (1700): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=81
2024-05-22 19:29:37 (1700): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=81
2024-05-22 19:29:37 (1700): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=82
2024-05-22 19:29:37 (1700): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=82
2024-05-22 19:29:37 (1700): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=83
2024-05-22 19:29:37 (1700): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=83
2024-05-22 19:29:37 (1700): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=84
2024-05-22 19:29:37 (1700): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=84
2024-05-22 19:29:37 (1700): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=85
2024-05-22 19:29:37 (1700): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=85
2024-05-22 19:29:37 (1700): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=86
2024-05-22 19:29:37 (1700): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=86
2024-05-22 19:29:37 (1700): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=87
2024-05-22 19:29:37 (1700): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=87
2024-05-22 19:29:37 (1700): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=88
2024-05-22 19:29:37 (1700): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=88
2024-05-22 19:29:37 (1700): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=89
2024-05-22 19:29:37 (1700): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=89
2024-05-22 19:29:37 (1700): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=8a
2024-05-22 19:29:37 (1700): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=8a
2024-05-22 19:29:37 (1700): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=8b
2024-05-22 19:29:37 (1700): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=8b
2024-05-22 19:29:37 (1700): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=8c
2024-05-22 19:29:37 (1700): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=8c
2024-05-22 19:29:37 (1700): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=8d
2024-05-22 19:29:37 (1700): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=8d
2024-05-22 19:29:37 (1700): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=8e
2024-05-22 19:29:37 (1700): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=8e
2024-05-22 19:29:37 (1700): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=8f
2024-05-22 19:29:37 (1700): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=8f
2024-05-22 19:29:37 (1700): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds
2024-05-22 19:29:37 (1700): Guest Log: vboxguest: misc device minor 58, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000)
2024-05-22 19:29:37 (1700): Guest Log: VBoxService 5.2.32 r132073 (verbosity: 0) linux.amd64 (Jul 12 2019 10:32:28) release log
2024-05-22 19:29:37 (1700): Guest Log: 00:00:00.000260 main     Log opened 2024-05-21T16:37:55.755921000Z
2024-05-22 19:29:37 (1700): Guest Log: 00:00:00.000432 main     OS Product: Linux
2024-05-22 19:29:37 (1700): Guest Log: 00:00:00.000499 main     OS Release: 3.10.0-957.27.2.el7.x86_64
2024-05-22 19:29:37 (1700): Guest Log: 00:00:00.000539 main     OS Version: #1 SMP Mon Jul 29 17:46:05 UTC 2019
2024-05-22 19:29:37 (1700): Guest Log: 00:00:00.000567 main     Executable: /opt/VBoxGuestAdditions-5.2.32/sbin/VBoxService
2024-05-22 19:29:37 (1700): Guest Log: 00:00:00.000568 main     Process ID: 1244
2024-05-22 19:29:37 (1700): Guest Log: 00:00:00.000568 main     Package type: LINUX_64BITS_GENERIC
2024-05-22 19:29:37 (1700): Guest Log: 00:00:00.003613 main     5.2.32 r132073 started. Verbose level = 0
2024-05-22 19:29:37 (1700): Guest Log: [INFO] Probing /cvmfs/atlas.cern.ch...
2024-05-22 19:29:37 (1700): Guest Log: [INFO] Mounting shared directory
2024-05-22 19:29:37 (1700): Guest Log: [INFO] Checking for init_data.xml
2024-05-22 19:29:37 (1700): Guest Log: 00:00:10.044661 timesync vgsvcTimeSyncWorker: Radical guest time change: 25 210 888 241 000ns (GuestNow=1 716 334 686 684 745 000 ns GuestLast=1 716 309 475 796 504 000 ns fSetTimeLastLoop=true )
2024-05-22 19:29:37 (1700): Guest Log: [INFO] Probing /cvmfs/atlas.cern.ch... OK
2024-05-22 19:29:37 (1700): Guest Log: [INFO] Detected branch: prod
2024-05-22 19:29:37 (1700): Guest Log: This is the prod version of the ATLAS job wrapper
2024-05-22 19:29:37 (1700): Guest Log: Copying input files
2024-05-22 19:29:37 (1700): Guest Log: Copied input files into RunAtlas.
2024-05-22 19:29:37 (1700): Guest Log: This VM did not configure a local http proxy via BOINC.
2024-05-22 19:29:37 (1700): Guest Log: Small home clusters do not require a local http proxy but it is suggested if
2024-05-22 19:29:37 (1700): Guest Log: more than 10 cores throughout the same LAN segment are regularly running ATLAS like tasks.
2024-05-22 19:29:37 (1700): Guest Log: Further information can be found at the LHC@home message board.
2024-05-22 19:29:37 (1700): Guest Log: Running cvmfs_config stat atlas.cern.ch
2024-05-22 19:29:37 (1700): Guest Log: VERSION PID UPTIME(M) MEM(K) REVISION EXPIRES(M) NOCATALOGS CACHEUSE(K) CACHEMAX(K) NOFDUSE NOFDMAX NOIOERR NOOPEN HITRATE(%) RX(K) SPEED(K/S) HOST PROXY ONLINE
2024-05-22 19:29:37 (1700): Guest Log: 2.6.3.0 1541 0 30068 133007 4 1 3116989 4096000 0 65024 0 0 n/a 1 0 http://s1ral-cvmfs.openhtc.io/cvmfs/atlas.cern.ch DIRECT 1
2024-05-22 19:29:37 (1700): Guest Log: copied the webapp to /var/www
2024-05-22 19:29:37 (1700): Guest Log: core_number=1
2024-05-22 19:29:37 (1700): Guest Log: Apptainer command /cvmfs/atlas.cern.ch/repo/containers/sw/apptainer/x86_64-el7/current/bin/apptainer exec -B /cvmfs,/data,/home/atlas/RunAtlas /cvmfs/atlas.cern.ch/repo/containers/fs/singularity/x86_64-centos7 
2024-05-22 19:29:37 (1700): Guest Log:  *** Starting ATLAS job. (PandaID=6211388378 taskID=38921007) ***
2024-05-22 19:29:37 (1700): Starting VM using VBoxManage interface. (boinc_ac126dfc4892e95c, slot#15)
2024-05-22 19:30:29 (1700): Error in start VM for VM: -2147467259
Command:
VBoxManage -q startvm "boinc_ac126dfc4892e95c" --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_ac126dfc4892e95c" to power on...

2024-05-22 19:30:29 (1700): VM failed to start.
2024-05-22 19:30:29 (1700): Could not start 
2024-05-22 19:30:29 (1700): ERROR: VM failed to start
2024-05-22 19:30:29 (1700): Powering off VM.
2024-05-22 19:30:29 (1700): Deregistering VM. (boinc_ac126dfc4892e95c, slot#15)
2024-05-22 19:30:29 (1700): Removing network bandwidth throttle group from VM.
2024-05-22 19:30:29 (1700): Removing VM from VirtualBox.

    Hypervisor System Log:

00:00:58.872431 USBPROXY usbLibGetDevices: Starting USB device enumeration
00:00:58.949530 USBPROXY Getting USB descriptor (id 0) failed (dwErr=31) on hub USB#ROOT_HUB30#4&6970116&0&0#{f18a0e88-c30c-11d0-8815-00a0c906bed8} port 4
00:00:58.979489 USBPROXY Getting USB descriptor (id 0) failed (dwErr=31) on hub USB#VID_05E3&PID_0608#5&2cf64626&0&13#{f18a0e88-c30c-11d0-8815-00a0c906bed8} port 4
00:00:58.979624 USBPROXY Getting USB descriptor (id 0) failed (dwErr=31) on hub USB#ROOT_HUB30#4&6970116&0&0#{f18a0e88-c30c-11d0-8815-00a0c906bed8} port 14
00:00:58.979854 USBPROXY usbLibGetDevices: Found 8 USB devices, 0 captured
00:01:00.740249          Finished saving settings file "E:\BOINC\Data\slots\3\boinc_2d068c7408dc585c\boinc_2d068c7408dc585c.vbox"
00:01:00.847439          Saving settings file "E:\BOINC\Data\slots\3\boinc_2d068c7408dc585c\boinc_2d068c7408dc585c.vbox" with version "1.19-windows"
00:01:00.952211          Finished saving settings file "E:\BOINC\Data\slots\3\boinc_2d068c7408dc585c\boinc_2d068c7408dc585c.vbox"
00:01:00.964591 USBPROXY usbLibGetDevices: Starting USB device enumeration
00:01:00.968582 USBPROXY Getting USB descriptor (id 0) failed (dwErr=31) on hub USB#ROOT_HUB30#4&6970116&0&0#{f18a0e88-c30c-11d0-8815-00a0c906bed8} port 4
00:01:00.986170 USBPROXY Getting USB descriptor (id 0) failed (dwErr=31) on hub USB#VID_05E3&PID_0608#5&2cf64626&0&13#{f18a0e88-c30c-11d0-8815-00a0c906bed8} port 4
00:01:00.986231 USBPROXY Getting USB descriptor (id 0) failed (dwErr=31) on hub USB#ROOT_HUB30#4&6970116&0&0#{f18a0e88-c30c-11d0-8815-00a0c906bed8} port 14
00:01:00.986379 USBPROXY usbLibGetDevices: Found 8 USB devices, 0 captured
00:01:01.104689          ERROR [COM]: aRC=VBOX_E_NOT_SUPPORTED (0x80bb0009) aIID={300763af-5d6b-46e6-aa96-273eac15538a} aComponent={SessionMachine} aText={This VM is not encrypted}, preserve=false aResultDetail=0
00:01:01.108091          ERROR [COM]: aRC=VBOX_E_OBJECT_NOT_FOUND (0x80bb0001) aIID={300763af-5d6b-46e6-aa96-273eac15538a} aComponent={SessionMachine} aText={No storage device attached to device slot 0 on port 2 of controller 'Hard Disk Controller'}, preserve=false aResultDetail=0
00:01:01.108139          ERROR [COM]: aRC=VBOX_E_OBJECT_NOT_FOUND (0x80bb0001) aIID={300763af-5d6b-46e6-aa96-273eac15538a} aComponent={SessionMachine} aText={No storage device attached to device slot 0 on port 2 of controller 'Hard Disk Controller'}, preserve=false aResultDetail=0
00:01:01.317285          ERROR [COM]: aRC=E_FAIL (0x80004005) aIID={300763af-5d6b-46e6-aa96-273eac15538a} aComponent={SessionMachine} aText={This machine does not have any snapshots}, preserve=false aResultDetail=0
00:01:01.620330          Launched VM: 3902337040 pid: 10436 (0x28c4) frontend: headless name: boinc_2d068c7408dc585c
00:01:03.778057 Watcher  Saving settings file "E:\BOINC\Data\slots\15\boinc_ac126dfc4892e95c\boinc_ac126dfc4892e95c.vbox" with version "1.19-windows"
00:01:03.807651          ERROR [COM]: aRC=VBOX_E_OBJECT_NOT_FOUND (0x80bb0001) aIID={ad47ad09-787b-44ab-b343-a082a3f2dfb1} aComponent={MediumWrap} aText={Property 'CRYPT/KeyId' does not exist}, preserve=false aResultDetail=0
00:01:03.808088          ERROR [COM]: aRC=VBOX_E_OBJECT_NOT_FOUND (0x80bb0001) aIID={ad47ad09-787b-44ab-b343-a082a3f2dfb1} aComponent={MediumWrap} aText={Property 'CRYPT/KeyId' does not exist}, preserve=false aResultDetail=0
00:01:03.974686 Watcher  Finished saving settings file "E:\BOINC\Data\slots\15\boinc_ac126dfc4892e95c\boinc_ac126dfc4892e95c.vbox"
00:01:03.975920 Watcher  Reaper: Pid 13820 (0x35fc) exited normally: -2147483645 (0x80000003)
00:01:04.030419          ERROR [COM]: aRC=E_FAIL (0x80004005) aIID={300763af-5d6b-46e6-aa96-273eac15538a} aComponent={SessionMachine} aText={This machine does not have any snapshots}, preserve=false aResultDetail=0
00:01:04.082799          Saving settings file "E:\BOINC\Data\slots\15\boinc_ac126dfc4892e95c\boinc_ac126dfc4892e95c.vbox" with version "1.19-windows"
00:01:04.169029          Finished saving settings file "E:\BOINC\Data\slots\15\boinc_ac126dfc4892e95c\boinc_ac126dfc4892e95c.vbox"
00:01:04.337314          Saving settings file "C:\Users\PMS\.VirtualBox\VirtualBox.xml" with version "1.12-windows"
00:01:04.339570          Finished saving settings file "C:\Users\PMS\.VirtualBox\VirtualBox.xml"
00:01:04.339771          Saving settings file "E:\BOINC\Data\slots\6\boinc_d152967ca35e4e4e\boinc_d152967ca35e4e4e.vbox" with version "1.19-windows"
00:01:04.485576          Finished saving settings file "E:\BOINC\Data\slots\6\boinc_d152967ca35e4e4e\boinc_d152967ca35e4e4e.vbox"
00:01:04.486418 DeleteVM Saving settings file "E:\BOINC\Data\slots\6\boinc_d152967ca35e4e4e\boinc_d152967ca35e4e4e.vbox" with version "1.19-windows"
00:01:04.575513 DeleteVM Finished saving settings file "E:\BOINC\Data\slots\6\boinc_d152967ca35e4e4e\boinc_d152967ca35e4e4e.vbox"
00:01:04.640927 Watcher  Saving settings file "E:\BOINC\Data\slots\6\boinc_d152967ca35e4e4e\boinc_d152967ca35e4e4e.vbox" with version "1.19-windows"
00:01:04.735830 Watcher  Finished saving settings file "E:\BOINC\Data\slots\6\boinc_d152967ca35e4e4e\boinc_d152967ca35e4e4e.vbox"
00:01:04.736828 Watcher  Reaper: Pid 12656 (0x3170) exited normally: -2147483645 (0x80000003)
00:01:04.794405          ERROR [COM]: aRC=E_FAIL (0x80004005) aIID={300763af-5d6b-46e6-aa96-273eac15538a} aComponent={SessionMachine} aText={This machine does not have any snapshots}, preserve=false aResultDetail=0
00:01:04.845819          Saving settings file "E:\BOINC\Data\slots\6\boinc_d152967ca35e4e4e\boinc_d152967ca35e4e4e.vbox" with version "1.19-windows"
00:01:04.991976          Finished saving settings file "E:\BOINC\Data\slots\6\boinc_d152967ca35e4e4e\boinc_d152967ca35e4e4e.vbox"
00:01:05.099843          Saving settings file "E:\BOINC\Data\slots\1\boinc_ea8c3786b8f4df0f\boinc_ea8c3786b8f4df0f.vbox" with version "1.19-windows"
00:01:05.211682          Finished saving settings file "E:\BOINC\Data\slots\1\boinc_ea8c3786b8f4df0f\boinc_ea8c3786b8f4df0f.vbox"
00:01:05.211929          Saving settings file "C:\Users\PMS\.VirtualBox\VirtualBox.xml" with version "1.12-windows"
00:01:05.259052          Finished saving settings file "C:\Users\PMS\.VirtualBox\VirtualBox.xml"
00:01:05.259213          Saving settings file "C:\Users\PMS\.VirtualBox\VirtualBox.xml" with version "1.12-windows"
00:01:05.261300          Finished saving settings file "C:\Users\PMS\.VirtualBox\VirtualBox.xml"
00:01:05.262162 DeleteVM Saving settings file "C:\Users\PMS\.VirtualBox\VirtualBox.xml" with version "1.12-windows"
00:01:05.264563 DeleteVM Finished saving settings file "C:\Users\PMS\.VirtualBox\VirtualBox.xml"
00:01:06.531098          ERROR [COM]: aRC=VBOX_E_OBJECT_NOT_FOUND (0x80bb0001) aIID={ad47ad09-787b-44ab-b343-a082a3f2dfb1} aComponent={MediumWrap} aText={Property 'CRYPT/KeyId' does not exist}, preserve=false aResultDetail=0
00:01:06.531531          ERROR [COM]: aRC=VBOX_E_OBJECT_NOT_FOUND (0x80bb0001) aIID={ad47ad09-787b-44ab-b343-a082a3f2dfb1} aComponent={MediumWrap} aText={Property 'CRYPT/KeyId' does not exist}, preserve=false aResultDetail=0
00:01:07.592216          Saving settings file "E:\BOINC\Data\slots\3\boinc_2d068c7408dc585c\boinc_2d068c7408dc585c.vbox" with version "1.19-windows"
00:01:07.757182          Finished saving settings file "E:\BOINC\Data\slots\3\boinc_2d068c7408dc585c\boinc_2d068c7408dc585c.vbox"
00:01:14.322282 USBPROXY usbLibGetDevices: Starting USB device enumeration
00:01:14.337836 USBPROXY Getting USB descriptor (id 0) failed (dwErr=31) on hub USB#ROOT_HUB30#4&6970116&0&0#{f18a0e88-c30c-11d0-8815-00a0c906bed8} port 4
00:01:14.355159 USBPROXY Getting USB descriptor (id 0) failed (dwErr=31) on hub USB#VID_05E3&PID_0608#5&2cf64626&0&13#{f18a0e88-c30c-11d0-8815-00a0c906bed8} port 4
00:01:14.355234 USBPROXY Getting USB descriptor (id 0) failed (dwErr=31) on hub USB#ROOT_HUB30#4&6970116&0&0#{f18a0e88-c30c-11d0-8815-00a0c906bed8} port 14
00:01:14.355398 USBPROXY usbLibGetDevices: Found 8 USB devices, 0 captured

    VM Execution Log:


    VM Startup Log:


    VM Trace Log:

lt audio driver:            Windows Audio Session
Autostart database path:         
Default Guest Additions ISO:     E:\VBox/VBoxGuestAdditions.iso
Logging Level:                   all
Proxy Mode:                      System
Proxy URL:                       
User language:                   C

2024-05-22 19:29:34 (1700): 
Command: VBoxManage -q list hostinfo 
Exit Code: 0
Output:
Host Information:

Host time: 2024-05-23T02:29:34.738000000Z
Processor online count: 16
Processor count: 16
Processor online core count: 10
Processor core count: 10
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: 12th Gen Intel(R) Core(TM) i5-12600K
Processor#1 speed: unknown
Processor#1 description: 12th Gen Intel(R) Core(TM) i5-12600K
Processor#2 speed: unknown
Processor#2 description: 12th Gen Intel(R) Core(TM) i5-12600K
Processor#3 speed: unknown
Processor#3 description: 12th Gen Intel(R) Core(TM) i5-12600K
Processor#4 speed: unknown
Processor#4 description: 12th Gen Intel(R) Core(TM) i5-12600K
Processor#5 speed: unknown
Processor#5 description: 12th Gen Intel(R) Core(TM) i5-12600K
Processor#6 speed: unknown
Processor#6 description: 12th Gen Intel(R) Core(TM) i5-12600K
Processor#7 speed: unknown
Processor#7 description: 12th Gen Intel(R) Core(TM) i5-12600K
Processor#8 speed: unknown
Processor#8 description: 12th Gen Intel(R) Core(TM) i5-12600K
Processor#9 speed: unknown
Processor#9 description: 12th Gen Intel(R) Core(TM) i5-12600K
Processor#10 speed: unknown
Processor#10 description: 12th Gen Intel(R) Core(TM) i5-12600K
Processor#11 speed: unknown
Processor#11 description: 12th Gen Intel(R) Core(TM) i5-12600K
Processor#12 speed: unknown
Processor#12 description: 12th Gen Intel(R) Core(TM) i5-12600K
Processor#13 speed: unknown
Processor#13 description: 12th Gen Intel(R) Core(TM) i5-12600K
Processor#14 speed: unknown
Processor#14 description: 12th Gen Intel(R) Core(TM) i5-12600K
Processor#15 speed: unknown
Processor#15 description: 12th Gen Intel(R) Core(TM) i5-12600K
Memory size: 32509 MByte
Memory available: 26821 MByte
Operating system: Windows 11
Operating system version: 10.0.22000

2024-05-22 19:29:36 (1700): 
Command: VBoxManage -q showvminfo "boinc_ac126dfc4892e95c" --machinereadable 
Exit Code: 0
Output:
name="boinc_ac126dfc4892e95c"
Encryption:     disabled
groups="/"
ostype="Linux 2.6 / 3.x / 4.x / 5.x (64-bit)"
UUID="ca2a45aa-3385-47ed-ac09-81e354b03e8d"
CfgFile="E:\\BOINC\\Data\\slots\\15\\boinc_ac126dfc4892e95c\\boinc_ac126dfc4892e95c.vbox"
SnapFldr="E:\\BOINC\\Data\\slots\\15\\boinc_ac126dfc4892e95c\\Snapshots"
LogFldr="E:\\BOINC\\Data\\slots\\15\\boinc_ac126dfc4892e95c\\Logs"
hardwareuuid="ca2a45aa-3385-47ed-ac09-81e354b03e8d"
memory=4100
pagefusion="off"
vram=16
cpuexecutioncap=90
hpet="off"
cpu-profile="host"
chipset="piix3"
firmware="BIOS"
cpus=1
pae="on"
longmode="on"
triplefaultreset="off"
apic="on"
x2apic="on"
nested-hw-virt="off"
cpuid-portability-level=0
bootmenu="messageandmenu"
boot1="disk"
boot2="dvd"
boot3="none"
boot4="none"
acpi="on"
ioapic="on"
biosapic="apic"
biossystemtimeoffset=0
BIOS NVRAM File="E:\\BOINC\\Data\\slots\\15\\boinc_ac126dfc4892e95c\\boinc_ac126dfc4892e95c.nvram"
rtcuseutc="off"
hwvirtex="on"
nestedpaging="on"
largepages="on"
vtxvpid="on"
vtxux="on"
virtvmsavevmload="on"
iommu="none"
paravirtprovider="default"
effparavirtprovider="kvm"
VMState="aborted"
VMStateChangeTime="2024-05-22T22:05:14.000000000"
graphicscontroller="vboxvga"
monitorcount=1
accelerate3d="off"
accelerate2dvideo="off"
teleporterenabled="off"
teleporterport=0
teleporteraddress=""
teleporterpassword=""
tracing-enabled="off"
tracing-allow-vm-access="off"
tracing-config=""
autostart-enabled="off"
autostart-delay=0
defaultfrontend=""
vmprocpriority="default"
storagecontrollername0="Hard Disk Controller"
storagecontrollertype0="IntelAhci"
storagecontrollerinstance0="0"
storagecontrollermaxportcount0="30"
storagecontrollerportcount0="3"
storagecontrollerbootable0="on"
"Hard Disk Controller-0-0"="E:\\BOINC\\Data\\slots\\15\\boinc_ac126dfc4892e95c\\Snapshots/{a06f2fac-d5fa-431a-90a9-9db952ca4561}.vdi"
"Hard Disk Controller-ImageUUID-0-0"="a06f2fac-d5fa-431a-90a9-9db952ca4561"
"Hard Disk Controller-nonrotational-0-0"="off"
"Hard Disk Controller-discard-0-0"="off"
"Hard Disk Controller-1-0"="E:\\VBox\\VBoxGuestAdditions.iso"
"Hard Disk Controller-ImageUUID-1-0"="740cc23d-53a2-410a-a63c-6bdfb3513f61"
"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="0800275EC09F"
cableconnected1="on"
nic1="nat"
nictype1="82540EM"
nicspeed1="0"
mtu="0"
sockSnd="64"
sockRcv="64"
tcpWndSnd="64"
tcpWndRcv="64"
Forwarding(0)="tcp_62903_80,tcp,127.0.0.1,62903,,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="on"
vrdeport=-1
vrdeports="62904"
vrdeaddress=""
vrdeauthtype="null"
vrdemulticon="off"
vrdereusecon="off"
vrdevideochannel="off"
vrdeproperty[TCP/Ports]="62904"
vrdeproperty[TCP/Address]=<not set>
vrdeproperty[VideoChannel/Enabled]=<not set>
vrdeproperty[VideoChannel/Quality]=<not set>
vrdeproperty[VideoChannel/DownscaleProtection]=<not set>
vrdeproperty[Client/DisableDisplay]=<not set>
vrdeproperty[Client/DisableInput]=<not set>
vrdeproperty[Client/DisableAudio]=<not set>
vrdeproperty[Client/DisableUSB]=<not set>
vrdeproperty[Client/DisableClipboard]=<not set>
vrdeproperty[Client/DisableUpstreamAudio]=<not set>
vrdeproperty[Client/DisableRDPDR]=<not set>
vrdeproperty[H3DRedirect/Enabled]=<not set>
vrdeproperty[Security/Method]=<not set>
vrdeproperty[Security/ServerCertificate]=<not set>
vrdeproperty[Security/ServerPrivateKey]=<not set>
vrdeproperty[Security/CACertificate]=<not set>
vrdeproperty[Audio/RateCorrectionMode]=<not set>
vrdeproperty[Audio/LogPath]=<not set>
usb="off"
ehci="off"
xhci="off"
BandwidthGroup0="boinc_ac126dfc4892e95c_net",Network,1099511627776
SharedFolderNameMachineMapping1="shared"
SharedFolderPathMachineMapping1="E:\\BOINC\\Data\\slots\\15\\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="E:\\BOINC\\Data\\slots\\15\\boinc_ac126dfc4892e95c\\boinc_ac126dfc4892e95c-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="C7aLDmu2bT5n9Rq4apoT9bVoABFKDmABFKDmlqFKDm6iOKDmlQJ5So_0"
GuestMemoryBalloon=0

2024-05-22 19:30:29 (1700): 
Command: VBoxManage -q startvm "boinc_ac126dfc4892e95c" --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_ac126dfc4892e95c" to power on...

2024-05-22 19:30:29 (1700): 
Command: VBoxManage -q snapshot "boinc_ac126dfc4892e95c" list 
Exit Code: -108
Output:
This machine does not have any snapshots

2024-05-22 19:30:29 (1700): 
Command: VBoxManage -q bandwidthctl "boinc_ac126dfc4892e95c" remove "boinc_ac126dfc4892e95c_net" 
Exit Code: 0
Output:

2024-05-22 19:30:30 (1700): 
Command: VBoxManage -q unregistervm "boinc_ac126dfc4892e95c" --delete 
Exit Code: 0
Output:
0%...10%...20%...30%...40%...50%...60%...70%...80%...90%...100%

19:30:40 (1700): called boinc_finish(-2147467259)

</stderr_txt>
]]>


©2024 CERN