Name CMS_2214942_1658892771.989121_0
Workunit 2198916
Created 27 Jul 2022, 3:32:54 UTC
Sent 28 Jul 2022, 5:19:53 UTC
Report deadline 4 Aug 2022, 5:19:53 UTC
Received 29 Jul 2022, 5:16:43 UTC
Server state Over
Outcome Computation error
Client state Compute error
Exit status 194 (0x000000C2) EXIT_ABORTED_BY_CLIENT
Computer ID 4164
Run time 8 hours 30 min 27 sec
CPU time 7 hours 45 min 38 sec
Validate state Invalid
Credit 0.00
Device peak FLOPS 21.14 GFLOPS
Application version CMS Simulation v60.63 (vbox64_mt_mcore_cms)
windows_x86_64
Peak working set size 118.68 MB
Peak swap size 140.46 MB
Peak disk usage 648.99 MB

Stderr output

<core_client_version>7.16.20</core_client_version>
<![CDATA[
<message>
(unknown error) - exit code 194 (0xc2)</message>
<stderr_txt>
2022-07-28 07:19:54 (16460): Detected: vboxwrapper 26205
2022-07-28 07:19:54 (16460): Detected: BOINC client v7.16.20
2022-07-28 07:19:55 (16460): Detected: VirtualBox VboxManage Interface (Version: 6.0.14)
2022-07-28 07:19:55 (16460): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2022-07-28 07:19:55 (16460): Successfully copied 'init_data.xml' to the shared directory.
2022-07-28 07:19:56 (16460): Create VM. (boinc_fa1002db84c0c141, slot#0)
2022-07-28 07:19:56 (16460): Setting Memory Size for VM. (6376MB)
2022-07-28 07:19:57 (16460): Setting CPU Count for VM. (6)
2022-07-28 07:19:57 (16460): Setting Chipset Options for VM.
2022-07-28 07:19:57 (16460): Setting Graphics Controller Options for VM.
2022-07-28 07:19:57 (16460): Setting Boot Options for VM.
2022-07-28 07:19:58 (16460): Setting Network Configuration for NAT.
2022-07-28 07:19:58 (16460): Enabling VM Network Access.
2022-07-28 07:19:58 (16460): Disabling USB Support for VM.
2022-07-28 07:19:58 (16460): Disabling COM Port Support for VM.
2022-07-28 07:19:59 (16460): Disabling LPT Port Support for VM.
2022-07-28 07:19:59 (16460): Disabling Audio Support for VM.
2022-07-28 07:19:59 (16460): Disabling Clipboard Support for VM.
2022-07-28 07:19:59 (16460): Disabling Drag and Drop Support for VM.
2022-07-28 07:20:00 (16460): Adding storage controller(s) to VM.
2022-07-28 07:20:00 (16460): Adding virtual disk drive to VM. (CMS_2022_07_25.vdi)
2022-07-28 07:20:01 (16460): Adding VirtualBox Guest Additions to VM.
2022-07-28 07:20:01 (16460): Adding network bandwidth throttle group to VM. (Defaulting to 1024GB)
2022-07-28 07:20:02 (16460): forwarding host port 53097 to guest port 80
2022-07-28 07:20:02 (16460): Enabling remote desktop for VM.
2022-07-28 07:20:02 (16460): Required extension pack not installed, remote desktop not enabled.
2022-07-28 07:20:02 (16460): Enabling shared directory for VM.
2022-07-28 07:20:03 (16460): Starting VM using VBoxManage interface. (boinc_fa1002db84c0c141, slot#0)
2022-07-28 07:20:06 (16460): Successfully started VM. (PID = '17456')
2022-07-28 07:20:06 (16460): Reporting VM Process ID to BOINC.
2022-07-28 07:20:06 (16460): Guest Log: BIOS: VirtualBox 6.0.14
2022-07-28 07:20:06 (16460): Guest Log: CPUID EDX: 0x178bfbff
2022-07-28 07:20:06 (16460): Guest Log: BIOS: AHCI 0-P#0: PCHS=16383/16/63 LCHS=1024/255/63 0x0000000002800000 sectors
2022-07-28 07:20:06 (16460): VM state change detected. (old = 'poweredoff', new = 'running')
2022-07-28 07:20:06 (16460): Detected: Web Application Enabled (http://localhost:53097)
2022-07-28 07:20:06 (16460): Preference change detected
2022-07-28 07:20:06 (16460): Setting CPU throttle for VM. (100%)
2022-07-28 07:20:06 (16460): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2022-07-28 07:20:08 (16460): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032
2022-07-28 07:20:08 (16460): Guest Log: BIOS: Booting from Hard Disk...
2022-07-28 07:20:10 (16460): Guest Log: BIOS: KBD: unsupported int 16h function 03
2022-07-28 07:20:10 (16460): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 
2022-07-28 07:20:55 (16460): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds
2022-07-28 07:20:55 (16460): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000)
2022-07-28 07:20:57 (16460): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log
2022-07-28 07:20:57 (16460): Guest Log: 00:00:00.000143 main     Log opened 2022-07-28T05:20:58.329409000Z
2022-07-28 07:20:57 (16460): Guest Log: 00:00:00.000389 main     OS Product: Linux
2022-07-28 07:20:57 (16460): Guest Log: 00:00:00.000459 main     OS Release: 4.14.232-19.cernvm.x86_64
2022-07-28 07:20:57 (16460): Guest Log: 00:00:00.000768 main     OS Version: #1 SMP Fri Apr 30 17:12:25 CEST 2021
2022-07-28 07:20:57 (16460): Guest Log: 00:00:00.000811 main     Executable: /usr/sbin/VBoxService
2022-07-28 07:20:57 (16460): Guest Log: 00:00:00.000812 main     Process ID: 2385
2022-07-28 07:20:57 (16460): Guest Log: 00:00:00.000813 main     Package type: LINUX_64BITS_GENERIC
2022-07-28 07:20:57 (16460): Guest Log: 00:00:00.001601 main     5.2.6 r120293 started. Verbose level = 0
2022-07-28 07:21:41 (16460): Guest Log: [INFO] Mounting the shared directory
2022-07-28 07:21:42 (16460): Guest Log: [INFO] Shared directory mounted, enabling vboxmonitor
2022-07-28 07:21:42 (16460): Guest Log: [INFO] Sourcing essential functions from /cvmfs/grid.cern.ch
2022-07-28 07:21:42 (16460): Guest Log: [INFO] Testing connection to cern.ch
2022-07-28 07:21:44 (16460): Guest Log: [INFO] Testing connection to VCCS
2022-07-28 07:21:46 (16460): Guest Log: [INFO] Testing connection to HTCondor
2022-07-28 07:21:48 (16460): Guest Log: [INFO] Testing connection to WMAgent
2022-07-28 07:21:50 (16460): Guest Log: [INFO] Testing connection to EOSCMS
2022-07-28 07:21:53 (16460): Guest Log: [INFO] Testing connection to CMS-Frontier
2022-07-28 07:21:55 (16460): Guest Log: [INFO] Testing connection to Frontier
2022-07-28 07:22:03 (16460): Guest Log: [INFO] Could not find a local HTTP proxy
2022-07-28 07:22:03 (16460): Guest Log: [INFO] CVMFS and Frontier will have to use DIRECT connections
2022-07-28 07:22:03 (16460): Guest Log: [INFO] This makes the application less efficient
2022-07-28 07:22:03 (16460): Guest Log: [INFO] It also puts higher load on the project servers
2022-07-28 07:22:03 (16460): Guest Log: [INFO] Setting up a local HTTP proxy is highly recommended
2022-07-28 07:22:04 (16460): Guest Log: [INFO] Advice can be found in the project forum
2022-07-28 07:22:04 (16460): Guest Log: [INFO] Reloading and probing the CVMFS configuration
2022-07-28 07:22:05 (16460): Guest Log: [INFO] Probing /cvmfs/cvmfs-config.cern.ch... OK
2022-07-28 07:22:05 (16460): Guest Log: [INFO] Probing /cvmfs/grid.cern.ch... OK
2022-07-28 07:22:12 (16460): Guest Log: [INFO] Probing /cvmfs/cms.cern.ch... OK
2022-07-28 07:22:13 (16460): Guest Log: [INFO] Probing /cvmfs/oasis.opensciencegrid.org... OK
2022-07-28 07:22:14 (16460): Guest Log: [INFO] Probing /cvmfs/singularity.opensciencegrid.org... OK
2022-07-28 07:22:18 (16460): Guest Log: [INFO] Probing /cvmfs/cms-ib.cern.ch... OK
2022-07-28 07:22:18 (16460): Guest Log: [INFO] Excerpt from "cvmfs_config stat": VERSION HOST PROXY
2022-07-28 07:22:18 (16460): Guest Log: [INFO] 2.7.2.0 http://cvmfs-stratum-one.cern.ch http://128.142.161.84:3125
2022-07-28 07:22:18 (16460): Guest Log: [INFO] Reading volunteer information
2022-07-28 07:22:21 (16460): Guest Log: [INFO] Requesting an X509 credential from LHC@home
2022-07-28 07:22:24 (16460): Guest Log: [INFO] Requesting an X509 credential from vLHC@home-dev
2022-07-28 07:22:28 (16460): Guest Log: [INFO] CMS application starting. Check log files.
2022-07-28 07:22:28 (16460): Guest Log: [INFO] Requesting an idtoken from LHC@home
2022-07-28 07:22:31 (16460): Guest Log: [INFO] Requesting an idtoken from vLHC@home-dev
2022-07-28 07:25:53 (16460): Stopping VM.
2022-07-28 07:25:59 (16460): Successfully stopped VM.
2022-07-28 11:34:27 (3936): Detected: vboxwrapper 26205
2022-07-28 11:34:27 (3936): Detected: BOINC client v7.16.20
2022-07-28 11:34:28 (3936): Detected: VirtualBox VboxManage Interface (Version: 6.0.14)
2022-07-28 11:34:29 (3936): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2022-07-28 11:34:29 (3936): Guest Log: BIOS: VirtualBox 6.0.14
2022-07-28 11:34:29 (3936): Guest Log: CPUID EDX: 0x178bfbff
2022-07-28 11:34:29 (3936): Guest Log: BIOS: AHCI 0-P#0: PCHS=16383/16/63 LCHS=1024/255/63 0x0000000002800000 sectors
2022-07-28 11:34:29 (3936): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032
2022-07-28 11:34:29 (3936): Guest Log: BIOS: Booting from Hard Disk...
2022-07-28 11:34:29 (3936): Guest Log: BIOS: KBD: unsupported int 16h function 03
2022-07-28 11:34:29 (3936): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 
2022-07-28 11:34:29 (3936): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds
2022-07-28 11:34:29 (3936): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000)
2022-07-28 11:34:29 (3936): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log
2022-07-28 11:34:29 (3936): Guest Log: 00:00:00.000143 main     Log opened 2022-07-28T05:20:58.329409000Z
2022-07-28 11:34:29 (3936): Guest Log: 00:00:00.000389 main     OS Product: Linux
2022-07-28 11:34:29 (3936): Guest Log: 00:00:00.000459 main     OS Release: 4.14.232-19.cernvm.x86_64
2022-07-28 11:34:29 (3936): Guest Log: 00:00:00.000768 main     OS Version: #1 SMP Fri Apr 30 17:12:25 CEST 2021
2022-07-28 11:34:29 (3936): Guest Log: 00:00:00.000811 main     Executable: /usr/sbin/VBoxService
2022-07-28 11:34:29 (3936): Guest Log: 00:00:00.000812 main     Process ID: 2385
2022-07-28 11:34:29 (3936): Guest Log: 00:00:00.000813 main     Package type: LINUX_64BITS_GENERIC
2022-07-28 11:34:29 (3936): Guest Log: 00:00:00.001601 main     5.2.6 r120293 started. Verbose level = 0
2022-07-28 11:34:29 (3936): Guest Log: [INFO] Mounting the shared directory
2022-07-28 11:34:29 (3936): Guest Log: [INFO] Shared directory mounted, enabling vboxmonitor
2022-07-28 11:34:29 (3936): Guest Log: [INFO] Sourcing essential functions from /cvmfs/grid.cern.ch
2022-07-28 11:34:29 (3936): Guest Log: [INFO] Testing connection to cern.ch
2022-07-28 11:34:29 (3936): Guest Log: [INFO] Testing connection to VCCS
2022-07-28 11:34:29 (3936): Guest Log: [INFO] Testing connection to HTCondor
2022-07-28 11:34:29 (3936): Guest Log: [INFO] Testing connection to WMAgent
2022-07-28 11:34:29 (3936): Guest Log: [INFO] Testing connection to EOSCMS
2022-07-28 11:34:29 (3936): Guest Log: [INFO] Testing connection to CMS-Frontier
2022-07-28 11:34:29 (3936): Guest Log: [INFO] Testing connection to Frontier
2022-07-28 11:34:29 (3936): Guest Log: [INFO] Could not find a local HTTP proxy
2022-07-28 11:34:29 (3936): Guest Log: [INFO] CVMFS and Frontier will have to use DIRECT connections
2022-07-28 11:34:29 (3936): Guest Log: [INFO] This makes the application less efficient
2022-07-28 11:34:29 (3936): Guest Log: [INFO] It also puts higher load on the project servers
2022-07-28 11:34:29 (3936): Guest Log: [INFO] Setting up a local HTTP proxy is highly recommended
2022-07-28 11:34:29 (3936): Guest Log: [INFO] Advice can be found in the project forum
2022-07-28 11:34:29 (3936): Guest Log: [INFO] Reloading and probing the CVMFS configuration
2022-07-28 11:34:29 (3936): Guest Log: [INFO] Probing /cvmfs/cvmfs-config.cern.ch... OK
2022-07-28 11:34:29 (3936): Guest Log: [INFO] Probing /cvmfs/grid.cern.ch... OK
2022-07-28 11:34:29 (3936): Guest Log: [INFO] Probing /cvmfs/cms.cern.ch... OK
2022-07-28 11:34:29 (3936): Guest Log: [INFO] Probing /cvmfs/oasis.opensciencegrid.org... OK
2022-07-28 11:34:29 (3936): Guest Log: [INFO] Probing /cvmfs/singularity.opensciencegrid.org... OK
2022-07-28 11:34:29 (3936): Guest Log: [INFO] Probing /cvmfs/cms-ib.cern.ch... OK
2022-07-28 11:34:29 (3936): Guest Log: [INFO] Excerpt from "cvmfs_config stat": VERSION HOST PROXY
2022-07-28 11:34:29 (3936): Guest Log: [INFO] 2.7.2.0 http://cvmfs-stratum-one.cern.ch http://128.142.161.84:3125
2022-07-28 11:34:29 (3936): Guest Log: [INFO] Reading volunteer information
2022-07-28 11:34:29 (3936): Guest Log: [INFO] Requesting an X509 credential from LHC@home
2022-07-28 11:34:29 (3936): Guest Log: [INFO] Requesting an X509 credential from vLHC@home-dev
2022-07-28 11:34:29 (3936): Guest Log: [INFO] CMS application starting. Check log files.
2022-07-28 11:34:29 (3936): Guest Log: [INFO] Requesting an idtoken from LHC@home
2022-07-28 11:34:29 (3936): Guest Log: [INFO] Requesting an idtoken from vLHC@home-dev
2022-07-28 11:34:29 (3936): Starting VM using VBoxManage interface. (boinc_fa1002db84c0c141, slot#0)
2022-07-28 11:34:36 (3936): Successfully started VM. (PID = '13572')
2022-07-28 11:34:36 (3936): Reporting VM Process ID to BOINC.
2022-07-28 11:34:36 (3936): VM state change detected. (old = 'poweredoff', new = 'running')
2022-07-28 11:34:36 (3936): Detected: Web Application Enabled (http://localhost:53097)
2022-07-28 11:34:36 (3936): Preference change detected
2022-07-28 11:34:36 (3936): Setting CPU throttle for VM. (100%)
2022-07-28 11:34:37 (3936): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2022-07-28 11:34:38 (3936): Guest Log: 00:05:00.143967 timesync vgsvcTimeSyncWorker: Radical host time change: 14 932 097 000 000ns (HostNow=1 659 000 878 853 000 000 ns HostLast=1 658 985 946 756 000 000 ns)
2022-07-28 11:34:48 (3936): Guest Log: 00:05:10.145805 timesync vgsvcTimeSyncWorker: Radical guest time change: 14 930 382 288 000ns (GuestNow=1 659 000 888 855 142 000 ns GuestLast=1 658 985 958 472 854 000 ns fSetTimeLastLoop=true )
2022-07-28 11:47:10 (3936): VM state change detected. (old = 'running', new = 'paused')
2022-07-28 11:47:20 (3936): VM state change detected. (old = 'paused', new = 'running')
2022-07-28 12:47:23 (3936): VM state change detected. (old = 'running', new = 'paused')
2022-07-28 12:47:33 (3936): VM state change detected. (old = 'paused', new = 'running')
2022-07-28 13:09:17 (3936): Status Report: Job Duration: '64800.000000'
2022-07-28 13:09:17 (3936): Status Report: Elapsed Time: '6000.000000'
2022-07-28 13:09:17 (3936): Status Report: CPU Time: '5590.046875'
2022-07-28 13:47:36 (3936): VM state change detected. (old = 'running', new = 'paused')
2022-07-28 13:47:46 (3936): VM state change detected. (old = 'paused', new = 'running')
2022-07-28 14:47:39 (3936): VM state change detected. (old = 'running', new = 'paused')
2022-07-28 14:47:49 (3936): VM state change detected. (old = 'paused', new = 'running')
2022-07-28 14:49:45 (3936): Status Report: Job Duration: '64800.000000'
2022-07-28 14:49:45 (3936): Status Report: Elapsed Time: '12000.000000'
2022-07-28 14:49:45 (3936): Status Report: CPU Time: '11643.718750'
2022-07-28 15:47:52 (3936): VM state change detected. (old = 'running', new = 'paused')
2022-07-28 15:48:02 (3936): VM state change detected. (old = 'paused', new = 'running')
2022-07-28 16:30:01 (3936): Status Report: Job Duration: '64800.000000'
2022-07-28 16:30:01 (3936): Status Report: Elapsed Time: '18000.000000'
2022-07-28 16:30:01 (3936): Status Report: CPU Time: '17739.078125'
2022-07-28 16:48:06 (3936): VM state change detected. (old = 'running', new = 'paused')
2022-07-28 16:48:16 (3936): VM state change detected. (old = 'paused', new = 'running')
2022-07-28 17:48:12 (3936): VM state change detected. (old = 'running', new = 'paused')
2022-07-28 17:48:21 (3936): VM state change detected. (old = 'paused', new = 'running')
2022-07-28 18:10:28 (3936): Status Report: Job Duration: '64800.000000'
2022-07-28 18:10:28 (3936): Status Report: Elapsed Time: '24000.000000'
2022-07-28 18:10:28 (3936): Status Report: CPU Time: '23854.312500'
2022-07-28 18:48:23 (3936): VM state change detected. (old = 'running', new = 'paused')
2022-07-28 18:48:33 (3936): VM state change detected. (old = 'paused', new = 'running')
2022-07-28 19:15:37 (3936): VM state change detected. (old = 'running', new = 'paused')
2022-07-28 19:15:38 (3936): VM state change detected. (old = 'paused', new = 'running')
2022-07-28 19:15:39 (3936): VM state change detected. (old = 'running', new = 'paused')
2022-07-28 19:16:54 (3936): VM state change detected. (old = 'paused', new = 'running')
2022-07-28 19:16:55 (3936): VM state change detected. (old = 'running', new = 'paused')
2022-07-28 19:16:56 (3936): VM state change detected. (old = 'paused', new = 'running')
2022-07-28 19:16:57 (3936): VM state change detected. (old = 'running', new = 'paused')
2022-07-28 19:16:58 (3936): VM state change detected. (old = 'paused', new = 'running')
2022-07-28 19:19:01 (3936): VM state change detected. (old = 'running', new = 'paused')
2022-07-28 19:19:03 (3936): VM state change detected. (old = 'paused', new = 'running')
2022-07-28 19:19:07 (3936): VM state change detected. (old = 'running', new = 'paused')
2022-07-28 19:20:42 (3936): VM state change detected. (old = 'paused', new = 'running')
2022-07-28 19:20:43 (3936): VM state change detected. (old = 'running', new = 'paused')
2022-07-28 19:20:59 (3936): VM state change detected. (old = 'paused', new = 'running')
2022-07-28 19:21:00 (3936): VM state change detected. (old = 'running', new = 'paused')
2022-07-28 19:21:43 (3936): VM state change detected. (old = 'paused', new = 'running')
2022-07-29 03:39:28 (9984): Detected: vboxwrapper 26205
2022-07-29 03:39:28 (9984): Detected: BOINC client v7.16.20
2022-07-29 03:39:29 (9984): Detected: VirtualBox VboxManage Interface (Version: 6.0.14)
2022-07-29 03:39:29 (9984): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2022-07-29 03:39:30 (9984): Guest Log: 00:05:00.143967 timesync vgsvcTimeSyncWorker: Radical host time change: 14 932 097 000 000ns (HostNow=1 659 000 878 853 000 000 ns HostLast=1 658 985 946 756 000 000 ns)
2022-07-29 03:39:30 (9984): Guest Log: 00:05:10.145805 timesync vgsvcTimeSyncWorker: Radical guest time change: 14 930 382 288 000ns (GuestNow=1 659 000 888 855 142 000 ns GuestLast=1 658 985 958 472 854 000 ns fSetTimeLastLoop=true )
2022-07-29 03:39:30 (9984): Starting VM using VBoxManage interface. (boinc_fa1002db84c0c141, slot#0)
2022-07-29 03:39:34 (9984): Successfully started VM. (PID = '12192')
2022-07-29 03:39:34 (9984): Reporting VM Process ID to BOINC.
2022-07-29 03:39:34 (9984): Guest Log: BIOS: VirtualBox 6.0.14
2022-07-29 03:39:34 (9984): Guest Log: CPUID EDX: 0x178bfbff
2022-07-29 03:39:34 (9984): Guest Log: BIOS: AHCI 0-P#0: PCHS=16383/16/63 LCHS=1024/255/63 0x0000000002800000 sectors
2022-07-29 03:39:34 (9984): VM state change detected. (old = 'poweredoff', new = 'running')
2022-07-29 03:39:34 (9984): Detected: Web Application Enabled (http://localhost:53097)
2022-07-29 03:39:34 (9984): Status Report: Job Duration: '64800.000000'
2022-07-29 03:39:34 (9984): Status Report: Elapsed Time: '28201.687920'
2022-07-29 03:39:34 (9984): Status Report: CPU Time: '27892.046875'
2022-07-29 03:39:34 (9984): Preference change detected
2022-07-29 03:39:34 (9984): Setting CPU throttle for VM. (100%)
2022-07-29 03:39:35 (9984): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2022-07-29 03:39:36 (9984): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032
2022-07-29 03:39:36 (9984): Guest Log: BIOS: Booting from Hard Disk...
2022-07-29 03:39:38 (9984): Guest Log: BIOS: KBD: unsupported int 16h function 03
2022-07-29 03:39:38 (9984): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 
2022-07-29 03:41:12 (9984): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds
2022-07-29 03:41:12 (9984): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000)
2022-07-29 03:41:38 (9984): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log
2022-07-29 03:41:38 (9984): Guest Log: 00:00:00.000143 main     Log opened 2022-07-29T01:41:44.633818000Z
2022-07-29 03:41:38 (9984): Guest Log: 00:00:00.000295 main     OS Product: Linux
2022-07-29 03:41:38 (9984): Guest Log: 00:00:00.000357 main     OS Release: 4.14.232-19.cernvm.x86_64
2022-07-29 03:41:38 (9984): Guest Log: 00:00:00.000425 main     OS Version: #1 SMP Fri Apr 30 17:12:25 CEST 2021
2022-07-29 03:41:38 (9984): Guest Log: 00:00:00.000527 main     Executable: /usr/sbin/VBoxService
2022-07-29 03:41:38 (9984): Guest Log: 00:00:00.000529 main     Process ID: 2417
2022-07-29 03:41:38 (9984): Guest Log: 00:00:00.000529 main     Package type: LINUX_64BITS_GENERIC
2022-07-29 03:41:38 (9984): Guest Log: 00:00:00.001456 main     5.2.6 r120293 started. Verbose level = 0
2022-07-29 03:43:21 (9984): Guest Log: [INFO] Mounting the shared directory
2022-07-29 03:45:34 (9984): Guest Log: [INFO] Shared directory mounted, enabling vboxmonitor
2022-07-29 03:48:12 (9984): Guest Log: [INFO] Sourcing essential functions from /cvmfs/grid.cern.ch
2022-07-29 03:51:42 (9984): Guest Log: [INFO] Testing connection to cern.ch
2022-07-29 03:54:51 (9984): VM state change detected. (old = 'running', new = 'paused')
2022-07-29 03:55:01 (9984): VM state change detected. (old = 'paused', new = 'running')
2022-07-29 03:57:09 (9984): Guest Log: [DEBUG] Status run 1 of up to 3: 2
2022-07-29 04:03:41 (9984): Guest Log: [DEBUG] Status run 2 of up to 3: 2
2022-07-29 04:09:55 (9984): VM state change detected. (old = 'running', new = 'paused')
2022-07-29 04:10:05 (9984): VM state change detected. (old = 'paused', new = 'running')
2022-07-29 04:11:46 (9984): Guest Log: [DEBUG] Status run 3 of up to 3: 2
2022-07-29 04:19:58 (9984): VM Heartbeat file specified, but missing heartbeat.
2022-07-29 04:19:58 (9984): Powering off VM.
2022-07-29 04:19:58 (9984): Successfully stopped VM.
2022-07-29 04:19:58 (9984): Deregistering VM. (boinc_fa1002db84c0c141, slot#0)
2022-07-29 04:19:58 (9984): Removing network bandwidth throttle group from VM.
2022-07-29 04:19:58 (9984): Removing VM from VirtualBox.

    Hypervisor System Log:

04:05:08.324536          ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={5047460a-265d-4538-b23e-ddba5fb84976} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
04:05:08.324548          ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={5047460a-265d-4538-b23e-ddba5fb84976} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
04:05:08.324639          ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={5047460a-265d-4538-b23e-ddba5fb84976} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
04:05:08.324652          ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={5047460a-265d-4538-b23e-ddba5fb84976} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
04:05:08.324662          ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={5047460a-265d-4538-b23e-ddba5fb84976} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
04:05:08.324673          ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={5047460a-265d-4538-b23e-ddba5fb84976} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
04:05:08.324682          ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={5047460a-265d-4538-b23e-ddba5fb84976} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
04:05:08.324692          ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={5047460a-265d-4538-b23e-ddba5fb84976} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
04:05:08.324703          ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={5047460a-265d-4538-b23e-ddba5fb84976} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
04:05:08.324712          ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={5047460a-265d-4538-b23e-ddba5fb84976} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
04:05:08.324722          ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={5047460a-265d-4538-b23e-ddba5fb84976} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
04:05:08.324732          ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={5047460a-265d-4538-b23e-ddba5fb84976} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
04:05:08.324742          ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={5047460a-265d-4538-b23e-ddba5fb84976} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
04:05:08.324752          ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={5047460a-265d-4538-b23e-ddba5fb84976} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
04:05:08.324762          ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={5047460a-265d-4538-b23e-ddba5fb84976} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
04:05:08.324772          ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={5047460a-265d-4538-b23e-ddba5fb84976} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
04:05:08.324783          ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={5047460a-265d-4538-b23e-ddba5fb84976} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
04:05:08.324792          ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={5047460a-265d-4538-b23e-ddba5fb84976} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
04:05:08.324802          ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={5047460a-265d-4538-b23e-ddba5fb84976} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
04:05:08.324813          ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={5047460a-265d-4538-b23e-ddba5fb84976} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
04:05:08.324823          ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={5047460a-265d-4538-b23e-ddba5fb84976} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
04:05:08.324874          ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={5047460a-265d-4538-b23e-ddba5fb84976} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
04:05:08.324887          ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={5047460a-265d-4538-b23e-ddba5fb84976} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
04:05:08.324897          ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={5047460a-265d-4538-b23e-ddba5fb84976} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
04:05:08.324907          ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={5047460a-265d-4538-b23e-ddba5fb84976} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
04:05:08.324917          ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={5047460a-265d-4538-b23e-ddba5fb84976} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
04:05:08.324927          ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={5047460a-265d-4538-b23e-ddba5fb84976} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
04:05:08.324937          ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={5047460a-265d-4538-b23e-ddba5fb84976} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
04:05:08.324947          ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={5047460a-265d-4538-b23e-ddba5fb84976} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
04:05:08.324957          ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={5047460a-265d-4538-b23e-ddba5fb84976} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
04:05:08.324966          ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={5047460a-265d-4538-b23e-ddba5fb84976} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
04:05:08.324976          ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={5047460a-265d-4538-b23e-ddba5fb84976} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
04:05:08.324986          ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={5047460a-265d-4538-b23e-ddba5fb84976} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
04:05:08.326201          Saving settings file "C:\Users\mrbro\.VirtualBox\VirtualBox.xml" with version "1.12-windows"
04:05:13.353338 main     VirtualBox: object deletion starts
04:05:13.354767 main     ERROR [COM]: aRC=VBOX_E_OBJECT_IN_USE (0x80bb000c) aIID={ad47ad09-787b-44ab-b343-a082a3f2dfb1} aComponent={MediumWrap} aText={Medium 'C:\ProgramData\BOINC\slots\10\vm_image.vdi' cannot be closed because it is still attached to 1 virtual machines}, preserve=false aResultDetail=0
04:05:13.355240 main     ERROR [COM]: aRC=VBOX_E_OBJECT_IN_USE (0x80bb000c) aIID={ad47ad09-787b-44ab-b343-a082a3f2dfb1} aComponent={MediumWrap} aText={Medium 'C:\ProgramData\BOINC\slots\11\vm_image.vdi' cannot be closed because it is still attached to 1 virtual machines}, preserve=false aResultDetail=0
04:05:13.360035 Watcher  ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={d0a0163f-e254-4e5b-a1f2-011cf991c38d} aComponent={VirtualBoxWrap} aText={The object is not ready}, preserve=false aResultDetail=0
04:05:13.360734 main     VirtualBox: object deleted

    VM Execution Log:


    VM Startup Log:


    VM Trace Log:


Maximum guest RAM size:          2097152 Megabytes
Minimum video RAM size:          0 Megabytes
Maximum video RAM size:          256 Megabytes
Maximum guest monitor count:     64
Minimum guest CPU count:         1
Maximum guest CPU count:         32
Virtual disk limit (info):       2199022206976 Bytes
Maximum Serial Port count:       4
Maximum Parallel Port count:     2
Maximum Boot Position:           4
Maximum PIIX3 Network Adapter count:   8
Maximum ICH9 Network Adapter count:   36
Maximum PIIX3 IDE Controllers:   1
Maximum ICH9 IDE Controllers:    1
Maximum IDE Port count:          2
Maximum Devices per IDE Port:    2
Maximum PIIX3 SATA Controllers:  1
Maximum ICH9 SATA Controllers:   8
Maximum SATA Port count:         30
Maximum Devices per SATA Port:   1
Maximum PIIX3 SCSI Controllers:  1
Maximum ICH9 SCSI Controllers:   8
Maximum SCSI Port count:         16
Maximum Devices per SCSI Port:   1
Maximum SAS PIIX3 Controllers:   1
Maximum SAS ICH9 Controllers:    8
Maximum SAS Port count:          255
Maximum Devices per SAS Port:    1
Maximum NVMe PIIX3 Controllers:  1
Maximum NVMe ICH9 Controllers:   8
Maximum NVMe Port count:         255
Maximum Devices per NVMe Port:   1
Maximum 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\mrbro\VirtualBox VMs
Raw-mode Supported:              yes
Exclusive HW virtualization use: off
Default hard disk format:        VDI
VRDE auth library:               VBoxAuth
Webservice auth. library:        VBoxAuth
Remote desktop ExtPack:          
Log history count:               3
Default frontend:                
Default audio driver:            DirectSound
Autostart database path:         
Default Guest Additions ISO:     C:\Program Files\Oracle\VirtualBox/VBoxGuestAdditions.iso
Logging Level:                   all
Proxy Mode:                      System
Proxy URL:                       

2022-07-29 03:39:29 (9984): 
Command: VBoxManage -q list hostinfo 
Exit Code: 0
Output:
Host Information:

Host time: 2022-07-29T01:39:29.424000000Z
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#0 speed: unknown
Processor#0 description: AMD Ryzen 5 3500U with Radeon Vega Mobile Gfx
Processor#1 speed: unknown
Processor#1 description: AMD Ryzen 5 3500U with Radeon Vega Mobile Gfx
Processor#2 speed: unknown
Processor#2 description: AMD Ryzen 5 3500U with Radeon Vega Mobile Gfx
Processor#3 speed: unknown
Processor#3 description: AMD Ryzen 5 3500U with Radeon Vega Mobile Gfx
Processor#4 speed: unknown
Processor#4 description: AMD Ryzen 5 3500U with Radeon Vega Mobile Gfx
Processor#5 speed: unknown
Processor#5 description: AMD Ryzen 5 3500U with Radeon Vega Mobile Gfx
Processor#6 speed: unknown
Processor#6 description: AMD Ryzen 5 3500U with Radeon Vega Mobile Gfx
Processor#7 speed: unknown
Processor#7 description: AMD Ryzen 5 3500U with Radeon Vega Mobile Gfx
Memory size: 7103 MByte
Memory available: 1321 MByte
Operating system: Windows 10
Operating system version: 10.0.22000

2022-07-29 03:39:29 (9984): 
Command: VBoxManage -q showvminfo "boinc_fa1002db84c0c141" --machinereadable 
Exit Code: 0
Output:
name="boinc_fa1002db84c0c141"
groups="/"
ostype="Linux 2.6 / 3.x / 4.x (64-bit)"
UUID="d8a8fa69-f12c-4357-8ba8-5dda59ebe0d1"
CfgFile="C:\\ProgramData\\BOINC\\slots\\0\\boinc_fa1002db84c0c141\\boinc_fa1002db84c0c141.vbox"
SnapFldr="C:\\ProgramData\\BOINC\\slots\\0\\boinc_fa1002db84c0c141\\Snapshots"
LogFldr="C:\\ProgramData\\BOINC\\slots\\0\\boinc_fa1002db84c0c141\\Logs"
hardwareuuid="d8a8fa69-f12c-4357-8ba8-5dda59ebe0d1"
memory=6376
pagefusion="off"
vram=16
cpuexecutioncap=100
hpet="off"
cpu-profile="host"
chipset="piix3"
firmware="BIOS"
cpus=6
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
rtcuseutc="off"
hwvirtex="on"
nestedpaging="on"
largepages="on"
vtxvpid="on"
vtxux="on"
paravirtprovider="default"
effparavirtprovider="kvm"
VMState="poweroff"
VMStateChangeTime="2022-07-28T09:34:35.000000000"
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=""
storagecontrollername0="Hard Disk Controller"
storagecontrollertype0="IntelAhci"
storagecontrollerinstance0="0"
storagecontrollermaxportcount0="30"
storagecontrollerportcount0="3"
storagecontrollerbootable0="on"
"Hard Disk Controller-0-0"="C:\ProgramData\BOINC\slots\0\boinc_fa1002db84c0c141\Snapshots/{c3aeeaec-dfe2-4ca6-89b9-f7418e150549}.vdi"
"Hard Disk Controller-ImageUUID-0-0"="c3aeeaec-dfe2-4ca6-89b9-f7418e150549"
"Hard Disk Controller-1-0"="C:\Program Files\Oracle\VirtualBox\VBoxGuestAdditions.iso"
"Hard Disk Controller-ImageUUID-1-0"="c08dec24-bfd6-43da-b89f-7b31730b4844"
"Hard Disk Controller-tempeject"="off"
"Hard Disk Controller-IsEjected"="off"
"Hard Disk Controller-2-0"="none"
natnet1="nat"
macaddress1="0800279F1964"
cableconnected1="on"
nic1="nat"
nictype1="82540EM"
nicspeed1="0"
mtu="0"
sockSnd="64"
sockRcv="64"
tcpWndSnd="64"
tcpWndRcv="64"
Forwarding(0)="tcp_53097_80,tcp,127.0.0.1,53097,,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_fa1002db84c0c141_net,Network,1099511627776
SharedFolderNameMachineMapping1="shared"
SharedFolderPathMachineMapping1="C:\\ProgramData\\BOINC\\slots\\0/shared"
videocap="off"
videocapaudio="off"
capturescreens="0"
capturefilename="C:\\ProgramData\\BOINC\\slots\\0\\boinc_fa1002db84c0c141\\boinc_fa1002db84c0c141.webm"
captureres="1024x768"
capturevideorate=512
capturevideofps=25
captureopts=""
description="CMS_2214942_1658892771.989121_0"
GuestMemoryBalloon=0

2022-07-29 03:39:33 (9984): 
Command: VBoxManage -q startvm "boinc_fa1002db84c0c141" --type headless
Exit Code: 0
Output:
Waiting for VM "boinc_fa1002db84c0c141" to power on...
VM "boinc_fa1002db84c0c141" has been successfully started.

2022-07-29 03:39:35 (9984): 
Command: VBoxManage -q controlvm "boinc_fa1002db84c0c141" cpuexecutioncap 100 
Exit Code: 0
Output:

2022-07-29 03:54:50 (9984): 
Command: VBoxManage -q controlvm "boinc_fa1002db84c0c141" pause
Exit Code: 0
Output:

2022-07-29 03:55:00 (9984): 
Command: VBoxManage -q controlvm "boinc_fa1002db84c0c141" resume
Exit Code: 0
Output:

2022-07-29 04:09:55 (9984): 
Command: VBoxManage -q controlvm "boinc_fa1002db84c0c141" pause
Exit Code: 0
Output:

2022-07-29 04:10:05 (9984): 
Command: VBoxManage -q controlvm "boinc_fa1002db84c0c141" resume
Exit Code: 0
Output:

2022-07-29 04:19:58 (9984): 
Command: VBoxManage -q controlvm "boinc_fa1002db84c0c141" poweroff
Exit Code: 0
Output:
0%...10%...20%...30%...40%...50%...60%...70%...80%...90%...100%

2022-07-29 04:19:58 (9984): 
Command: VBoxManage -q snapshot "boinc_fa1002db84c0c141" list 
Exit Code: -108
Output:
This machine does not have any snapshots

2022-07-29 04:19:58 (9984): 
Command: VBoxManage -q bandwidthctl "boinc_fa1002db84c0c141" remove "boinc_fa1002db84c0c141_net" 
Exit Code: 0
Output:

2022-07-29 04:19:59 (9984): 
Command: VBoxManage -q unregistervm "boinc_fa1002db84c0c141" --delete 
Exit Code: 0
Output:
0%...10%...20%...30%...40%...50%...60%...70%...80%...90%...100%

04:20:04 (9984): called boinc_finish(194)

</stderr_txt>
]]>


©2024 CERN