Name CMS_2740036_1680330202.476731_0
Workunit 2291846
Created 1 Apr 2023, 6:23:23 UTC
Sent 2 Apr 2023, 15:14:43 UTC
Report deadline 9 Apr 2023, 15:14:43 UTC
Received 2 Apr 2023, 18:27:26 UTC
Server state Over
Outcome Computation error
Client state Compute error
Exit status 194 (0x000000C2) EXIT_ABORTED_BY_CLIENT
Computer ID 4860
Run time 3 hours 6 min 3 sec
CPU time 4 hours 21 min 50 sec
Validate state Invalid
Credit 0.00
Device peak FLOPS 2.00 GFLOPS
Application version CMS Simulation v60.70 (vbox64_mt_mcore_cms)
windows_x86_64
Peak working set size 76.52 MB
Peak swap size 79.18 MB
Peak disk usage 620.35 MB

Stderr output

<core_client_version>7.20.2</core_client_version>
<![CDATA[
<message>
(unknown error) - exit code 194 (0xc2)</message>
<stderr_txt>
2023-04-02 15:19:48 (15920): Detected: vboxwrapper 26206
2023-04-02 15:19:48 (15920): Detected: BOINC client v7.20.2
2023-04-02 15:19:58 (15920): Detected: VirtualBox VboxManage Interface (Version: 6.1.34)
2023-04-02 15:19:59 (15920): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2023-04-02 15:19:59 (15920): Successfully copied 'init_data.xml' to the shared directory.
2023-04-02 15:20:02 (15920): Create VM. (boinc_abca86d06dd959d6, slot#21)
2023-04-02 15:20:04 (15920): Setting Memory Size for VM. (2792MB)
2023-04-02 15:20:05 (15920): Setting CPU Count for VM. (2)
2023-04-02 15:20:06 (15920): Setting Chipset Options for VM.
2023-04-02 15:20:07 (15920): Setting Graphics Controller Options for VM.
2023-04-02 15:20:08 (15920): Setting Boot Options for VM.
2023-04-02 15:20:09 (15920): Setting Network Configuration for NAT.
2023-04-02 15:20:11 (15920): Enabling VM Network Access.
2023-04-02 15:20:12 (15920): Disabling USB Support for VM.
2023-04-02 15:20:13 (15920): Disabling COM Port Support for VM.
2023-04-02 15:20:14 (15920): Disabling LPT Port Support for VM.
2023-04-02 15:20:16 (15920): Disabling Audio Support for VM.
2023-04-02 15:20:17 (15920): Disabling Clipboard Support for VM.
2023-04-02 15:20:18 (15920): Disabling Drag and Drop Support for VM.
2023-04-02 15:20:19 (15920): Adding storage controller(s) to VM.
2023-04-02 15:20:20 (15920): Adding virtual disk drive to VM. (CMS_2022_09_07.vdi)
2023-04-02 15:20:25 (15920): Adding VirtualBox Guest Additions to VM.
2023-04-02 15:20:26 (15920): Adding network bandwidth throttle group to VM. (Defaulting to 1024GB)
2023-04-02 15:20:27 (15920): forwarding host port 51979 to guest port 80
2023-04-02 15:20:28 (15920): Enabling remote desktop for VM.
2023-04-02 15:20:29 (15920): Required extension pack not installed, remote desktop not enabled.
2023-04-02 15:20:29 (15920): Enabling shared directory for VM.
2023-04-02 15:20:32 (15920): Starting VM using VBoxManage interface. (boinc_abca86d06dd959d6, slot#21)
2023-04-02 15:20:56 (15920): Successfully started VM. (PID = '15148')
2023-04-02 15:20:56 (15920): Reporting VM Process ID to BOINC.
2023-04-02 15:20:56 (15920): Guest Log: BIOS: VirtualBox 6.1.34
2023-04-02 15:20:56 (15920): Guest Log: CPUID EDX: 0x178bfbff
2023-04-02 15:20:56 (15920): Guest Log: BIOS: No PCI IDE controller, not probing IDE
2023-04-02 15:20:56 (15920): Guest Log: BIOS: AHCI 0-P#0: PCHS=16383/16/63 LCHS=1024/255/63 0x0000000002800000 sectors
2023-04-02 15:20:56 (15920): VM state change detected. (old = 'poweredoff', new = 'running')
2023-04-02 15:20:56 (15920): Detected: Web Application Enabled (http://localhost:51979)
2023-04-02 15:20:56 (15920): Preference change detected
2023-04-02 15:20:56 (15920): Setting CPU throttle for VM. (100%)
2023-04-02 15:20:57 (15920): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2023-04-02 15:20:58 (15920): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032
2023-04-02 15:20:58 (15920): Guest Log: BIOS: Booting from Hard Disk...
2023-04-02 15:21:01 (15920): Guest Log: BIOS: KBD: unsupported int 16h function 03
2023-04-02 15:21:01 (15920): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 
2023-04-02 15:22:15 (15920): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds
2023-04-02 15:22:15 (15920): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000)
2023-04-02 15:22:24 (15920): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log
2023-04-02 15:22:24 (15920): Guest Log: 00:00:00.001961 main     Log opened 2023-04-02T15:22:10.411559000Z
2023-04-02 15:22:24 (15920): Guest Log: 00:00:00.014668 main     OS Product: Linux
2023-04-02 15:22:24 (15920): Guest Log: 00:00:00.015917 main     OS Release: 4.14.232-19.cernvm.x86_64
2023-04-02 15:22:24 (15920): Guest Log: 00:00:00.016327 main     OS Version: #1 SMP Fri Apr 30 17:12:25 CEST 2021
2023-04-02 15:22:24 (15920): Guest Log: 00:00:00.019145 main     Executable: /usr/sbin/VBoxService
2023-04-02 15:22:24 (15920): Guest Log: 00:00:00.019181 main     Process ID: 2201
2023-04-02 15:22:24 (15920): Guest Log: 00:00:00.019186 main     Package type: LINUX_64BITS_GENERIC
2023-04-02 15:22:24 (15920): Guest Log: 00:00:00.057624 main     5.2.6 r120293 started. Verbose level = 0
2023-04-02 15:22:34 (15920): Guest Log: 00:00:10.130372 timesync vgsvcTimeSyncWorker: Radical guest time change: -35 976 565 576 000ns (GuestNow=1 680 412 953 958 755 000 ns GuestLast=1 680 448 930 524 331 000 ns fSetTimeLastLoop=true )
2023-04-02 15:24:21 (15920): Guest Log: [INFO] Mounting the shared directory
2023-04-02 15:24:23 (15920): Guest Log: [INFO] Shared directory mounted, enabling vboxmonitor
2023-04-02 15:24:26 (15920): Guest Log: [INFO] Sourcing essential functions from /cvmfs/grid.cern.ch
2023-04-02 15:24:28 (15920): Guest Log: [INFO] Testing connection to cern.ch
2023-04-02 15:24:33 (15920): Guest Log: [INFO] Testing connection to VCCS
2023-04-02 15:24:37 (15920): Guest Log: [INFO] Testing connection to HTCondor
2023-04-02 15:24:41 (15920): Guest Log: [INFO] Testing connection to WMAgent
2023-04-02 15:24:46 (15920): Guest Log: [INFO] Testing connection to EOSCMS
2023-04-02 15:24:51 (15920): Guest Log: [INFO] Testing connection to CMS-Factory
2023-04-02 15:24:56 (15920): Guest Log: [INFO] Testing connection to CMS-Frontier
2023-04-02 15:25:02 (15920): Guest Log: [INFO] Testing connection to Frontier
2023-04-02 15:25:09 (15920): Guest Log: [INFO] Could not find a local HTTP proxy
2023-04-02 15:25:13 (15920): Guest Log: [INFO] CVMFS and Frontier will have to use DIRECT connections
2023-04-02 15:25:15 (15920): Guest Log: [INFO] This makes the application less efficient
2023-04-02 15:25:18 (15920): Guest Log: [INFO] It also puts higher load on the project servers
2023-04-02 15:25:20 (15920): Guest Log: [INFO] Setting up a local HTTP proxy is highly recommended
2023-04-02 15:25:23 (15920): Guest Log: [INFO] Advice can be found in the project forum
2023-04-02 15:25:30 (15920): Guest Log: [INFO] Reloading and probing the CVMFS configuration
2023-04-02 15:26:19 (15920): Guest Log: [INFO] Probing /cvmfs/grid.cern.ch... OK
2023-04-02 15:26:38 (15920): Guest Log: [INFO] Probing /cvmfs/oasis.opensciencegrid.org... OK
2023-04-02 15:26:45 (15920): Guest Log: [INFO] Probing /cvmfs/singularity.opensciencegrid.org... OK
2023-04-02 15:26:45 (15920): Guest Log: [INFO] Probing /cvmfs/cms.cern.ch... OK
2023-04-02 15:26:45 (15920): Guest Log: [INFO] Probing /cvmfs/cms-ib.cern.ch... OK
2023-04-02 15:26:58 (15920): Guest Log: [INFO] Excerpt from "cvmfs_config stat": VERSION HOST PROXY
2023-04-02 15:27:02 (15920): Guest Log: [INFO] 2.7.2.0 http://s1swinburne-cvmfs.openhtc.io:8080 DIRECT
2023-04-02 15:27:05 (15920): Guest Log: [INFO] Environment HTTP proxy: not set
2023-04-02 15:27:10 (15920): Guest Log: [INFO] Reading volunteer information
2023-04-02 15:28:34 (15920): Guest Log: [INFO] Requesting an X509 credential from LHC@home
2023-04-02 15:28:52 (15920): Guest Log: [INFO] Requesting an X509 credential from vLHC@home-dev
2023-04-02 15:29:01 (15920): Guest Log: [INFO] CMS application starting. Check log files.
2023-04-02 15:29:08 (15920): Guest Log: [INFO] Requesting an idtoken from LHC@home
2023-04-02 15:29:14 (15920): Guest Log: [INFO] Requesting an idtoken from vLHC@home-dev
2023-04-02 17:03:26 (15920): Status Report: Job Duration: '64800.000000'
2023-04-02 17:03:26 (15920): Status Report: Elapsed Time: '6000.095762'
2023-04-02 17:03:26 (15920): Status Report: CPU Time: '9534.421875'
2023-04-02 18:25:25 (15920): VM Heartbeat file specified, but missing heartbeat.
2023-04-02 18:25:25 (15920): Powering off VM.
2023-04-02 18:25:32 (15920): Successfully stopped VM.
2023-04-02 18:25:32 (15920): Deregistering VM. (boinc_abca86d06dd959d6, slot#21)
2023-04-02 18:25:33 (15920): Removing network bandwidth throttle group from VM.
2023-04-02 18:25:35 (15920): Removing VM from VirtualBox.

    Hypervisor System Log:

00:00:39.582315          ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={85632c68-b5bb-4316-a900-5eb28d3413df} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
00:00:39.582592          ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={85632c68-b5bb-4316-a900-5eb28d3413df} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
00:00:39.635042          Saving settings file "C:\ProgramData\BOINC\slots\21\boinc_abca86d06dd959d6\boinc_abca86d06dd959d6.vbox" with version "1.16-windows"
00:00:40.866314          ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={85632c68-b5bb-4316-a900-5eb28d3413df} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
00:00:40.866984          ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={85632c68-b5bb-4316-a900-5eb28d3413df} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
00:00:40.867210          ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={85632c68-b5bb-4316-a900-5eb28d3413df} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
00:00:41.005236          ERROR [COM]: aRC=VBOX_E_OBJECT_NOT_FOUND (0x80bb0001) aIID={85632c68-b5bb-4316-a900-5eb28d3413df} aComponent={SessionMachine} aText={No storage device attached to device slot 0 on port 2 of controller 'Hard Disk Controller'}, preserve=false aResultDetail=0
00:00:41.007913          ERROR [COM]: aRC=VBOX_E_OBJECT_NOT_FOUND (0x80bb0001) aIID={85632c68-b5bb-4316-a900-5eb28d3413df} aComponent={SessionMachine} aText={No storage device attached to device slot 0 on port 2 of controller 'Hard Disk Controller'}, preserve=false aResultDetail=0
00:00:41.155395          ERROR [COM]: aRC=E_FAIL (0x80004005) aIID={85632c68-b5bb-4316-a900-5eb28d3413df} aComponent={SessionMachine} aText={This machine does not have any snapshots}, preserve=false aResultDetail=0
00:00:42.284562          ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={85632c68-b5bb-4316-a900-5eb28d3413df} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
00:00:42.285192          ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={85632c68-b5bb-4316-a900-5eb28d3413df} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
00:00:42.285482          ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={85632c68-b5bb-4316-a900-5eb28d3413df} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
00:00:42.347026          Launched VM: 66950272 pid: 4236 (0x108c) frontend: headless name: boinc_abca86d06dd959d6
00:01:06.435031          ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={85632c68-b5bb-4316-a900-5eb28d3413df} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
00:01:06.435561          ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={85632c68-b5bb-4316-a900-5eb28d3413df} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
00:01:06.435760          ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={85632c68-b5bb-4316-a900-5eb28d3413df} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
00:01:06.489796          Saving settings file "C:\ProgramData\BOINC\slots\21\boinc_abca86d06dd959d6\boinc_abca86d06dd959d6.vbox" with version "1.16-windows"
01:49:04.824965 dns-monitor HostDnsMonitor: updating information
01:49:04.826490 dns-monitor HostDnsMonitor: unchanged
03:05:35.251802          ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={85632c68-b5bb-4316-a900-5eb28d3413df} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
03:05:35.254277          ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={85632c68-b5bb-4316-a900-5eb28d3413df} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
03:05:35.254684          ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={85632c68-b5bb-4316-a900-5eb28d3413df} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
03:05:35.788767          Saving settings file "C:\ProgramData\BOINC\slots\21\boinc_abca86d06dd959d6\boinc_abca86d06dd959d6.vbox" with version "1.16-windows"
03:05:42.403019          ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={85632c68-b5bb-4316-a900-5eb28d3413df} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
03:05:42.403799          ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={85632c68-b5bb-4316-a900-5eb28d3413df} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
03:05:42.404073          ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={85632c68-b5bb-4316-a900-5eb28d3413df} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
03:05:42.451393          ERROR [COM]: aRC=E_FAIL (0x80004005) aIID={85632c68-b5bb-4316-a900-5eb28d3413df} aComponent={SessionMachine} aText={This machine does not have any snapshots}, preserve=false aResultDetail=0
03:05:43.978289          ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={85632c68-b5bb-4316-a900-5eb28d3413df} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
03:05:43.979154          ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={85632c68-b5bb-4316-a900-5eb28d3413df} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
03:05:43.979475          ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={85632c68-b5bb-4316-a900-5eb28d3413df} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
03:05:44.059828          Saving settings file "C:\ProgramData\BOINC\slots\21\boinc_abca86d06dd959d6\boinc_abca86d06dd959d6.vbox" with version "1.16-windows"
03:05:45.447055          ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={85632c68-b5bb-4316-a900-5eb28d3413df} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
03:05:45.447964          ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={85632c68-b5bb-4316-a900-5eb28d3413df} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
03:05:45.448433          ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={85632c68-b5bb-4316-a900-5eb28d3413df} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
03:05:45.459680          ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={85632c68-b5bb-4316-a900-5eb28d3413df} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
03:05:45.460413          ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={85632c68-b5bb-4316-a900-5eb28d3413df} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
03:05:45.460644          ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={85632c68-b5bb-4316-a900-5eb28d3413df} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
03:05:45.461359          Saving settings file "C:\Users\peter\.VirtualBox\VirtualBox.xml" with version "1.12-windows"
03:05:50.678105 main     VirtualBox: object deletion starts
03:05:50.682491 main     HostDnsMonitor: shutting down ...
03:05:50.685469 main     HostDnsMonitor: shut down
03:05:50.700807 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
03:05:50.702397 main     VirtualBox: object deleted

    VM Execution Log:

/CPU1/VM/Halt/BlockInsomnia   497317 ns/call (183442670624 ticks,  368864 times, max 711001034, min       1)
03:04:43.676695 /PROF/CPU1/VM/Halt/BlockOnTime     368299 ns/call ( 16403333112 ticks,   44538 times, max 116093576, min       1)
03:04:43.676695 /PROF/CPU1/VM/Halt/BlockOverslept  2591386 ns/call (1325025040024 ticks,  511319 times, max  92146375, min   50002)
03:04:43.676695 /PROF/CPU1/VM/Halt/R0HaltBlock          0 ns/call (           0 ticks,       0 times, max         0, min      -1)
03:04:43.676695 /PROF/CPU1/VM/Halt/R0HaltBlockInsomnia        0 ns/call (           0 ticks,       0 times, max         0, min      -1)
03:04:43.676695 /PROF/CPU1/VM/Halt/R0HaltBlockOnTime        0 ns/call (           0 ticks,       0 times, max         0, min      -1)
03:04:43.676695 /PROF/CPU1/VM/Halt/R0HaltBlockOverslept        0 ns/call (           0 ticks,       0 times, max         0, min      -1)
03:04:43.676695 /PROF/CPU1/VM/Halt/R0HaltExec           0 times
03:04:43.676695 /PROF/CPU1/VM/Halt/R0HaltExec/FromBlock        0 times
03:04:43.676695 /PROF/CPU1/VM/Halt/R0HaltExec/FromSpin        0 times
03:04:43.676695 /PROF/CPU1/VM/Halt/R0HaltHistoryCounter  1460099 times
03:04:43.676695 /PROF/CPU1/VM/Halt/R0HaltHistorySucceeded        0 times
03:04:43.676695 /PROF/CPU1/VM/Halt/R0HaltHistoryToRing3      134 times
03:04:43.676695 /PROF/CPU1/VM/Halt/R0HaltToR3     1460099 times
03:04:43.676695 /PROF/CPU1/VM/Halt/R0HaltToR3/FromSpin        0 times
03:04:43.676695 /PROF/CPU1/VM/Halt/R0HaltToR3/Other  1460099 times
03:04:43.676695 /PROF/CPU1/VM/Halt/R0HaltToR3/PendingFF        0 times
03:04:43.676695 /PROF/CPU1/VM/Halt/R0HaltToR3/PostWaitNoInt        0 times
03:04:43.676695 /PROF/CPU1/VM/Halt/R0HaltToR3/PostWaitPendingFF        0 times
03:04:43.676695 /PROF/CPU1/VM/Halt/R0HaltToR3/SmallDelta        0 times
03:04:43.676695 /PROF/CPU1/VM/Halt/Timers           13137 ns/call (105016371291 ticks, 7993629 times, max 110215480, min       2)
03:04:43.676695 /PROF/CPU1/VM/Halt/Yield            33873 ns/call (   966684142 ticks,   28538 times, max  15128145, min       1)
03:04:43.676695 /Public/NetAdapter/0/BytesReceived 220409829 bytes
03:04:43.676695 /Public/NetAdapter/0/BytesTransmitted  2588150 bytes
03:04:43.676695 /Public/NetAdapter/0/e1000              0 
03:04:43.676695 /Public/Storage/AHCI0/Port0/BytesRead 1872577024 bytes
03:04:43.676695 /Public/Storage/AHCI0/Port0/BytesWritten 679997952 bytes
03:04:43.676695 /Public/Storage/AHCI0/Port0/QueryBufAttempts        0 count
03:04:43.676695 /Public/Storage/AHCI0/Port0/QueryBufSuccess        0 count
03:04:43.676695 /Public/Storage/AHCI0/Port0/ReqsFlush     3203 count
03:04:43.676695 /Public/Storage/AHCI0/Port0/ReqsRead    42697 count
03:04:43.676695 /Public/Storage/AHCI0/Port0/ReqsSubmitted    63402 count
03:04:43.676695 /Public/Storage/AHCI0/Port0/ReqsSucceeded    63402 count
03:04:43.678228 /Public/Storage/AHCI0/Port0/ReqsWrite    17502 count
03:04:43.678270 /Public/Storage/AHCI0/Port1/BytesRead   321536 bytes
03:04:43.678308 /Public/Storage/AHCI0/Port1/QueryBufAttempts        0 count
03:04:43.678353 /Public/Storage/AHCI0/Port1/QueryBufSuccess        0 count
03:04:43.678403 /Public/Storage/AHCI0/Port1/ReqsRead       22 count
03:04:43.678444 /Public/Storage/AHCI0/Port1/ReqsSubmitted       22 count
03:04:43.678491 /Public/Storage/AHCI0/Port1/ReqsSucceeded       22 count
03:04:43.678540 /SELM/LoadHidSel/GstReadErrors          0 times
03:04:43.678581 /SELM/LoadHidSel/NoGoodGuest            0 times
03:04:43.678625 /TM/CPU/00/cNsExecuting          4538132444105 ns
03:04:43.678676 /TM/CPU/00/cNsHalted             2897048733111 ns
03:04:43.678703 /TM/CPU/00/cNsOther              3636094848733 ns
03:04:43.678744 /TM/CPU/00/cNsTotal              11071276025949 ns
03:04:43.678779 /TM/CPU/00/cPeriodsExecuting     26293041 count
03:04:43.678823 /TM/CPU/00/cPeriodsHalted         1472266 count
03:04:43.678861 /TM/CPU/00/pctExecuting                11 %
03:04:43.678905 /TM/CPU/00/pctHalted                   82 %
03:04:43.678952 /TM/CPU/00/pctOther                     6 %
03:04:43.678998 /TM/CPU/01/cNsExecuting          3569811004998 ns
03:04:43.679050 /TM/CPU/01/cNsHalted             2491072915809 ns
03:04:43.679094 /TM/CPU/01/cNsOther              5010392274098 ns
03:04:43.679137 /TM/CPU/01/cNsTotal              11071276194905 ns
03:04:43.679179 /TM/CPU/01/cPeriodsExecuting     25252470 count
03:04:43.679213 /TM/CPU/01/cPeriodsHalted         1387155 count
03:04:43.679261 /TM/CPU/01/pctExecuting                11 %
03:04:43.679299 /TM/CPU/01/pctHalted                   78 %
03:04:43.679334 /TM/CPU/01/pctOther                     9 %
03:04:43.679368 /TM/CPU/pctExecuting                   11 %
03:04:43.679413 /TM/CPU/pctHalted                      80 %
03:04:43.679441 /TM/CPU/pctOther                        8 %
03:04:43.679478 /TM/MaxHzHint                           0 Hz
03:04:43.679518 /TM/PIT/Handler                         0 ticks/call (           0 ticks,       0 times, max         0, min      -1)
03:04:43.679568 /TM/PIT/Irq                             0 times
03:04:43.679610 /TM/R0/1nsSteps                   1704877 times
03:04:43.679639 /TM/R3/1nsSteps                  28331114 times
03:04:43.679761 /TM/TSC/offCPU0                  110614976699948 ticks
03:04:43.679848 /TM/TSC/offCPU1                  110614976699948 ticks
03:04:43.679899 /TM/VirtualSync/CurrentOffset     6251213 ns
03:04:43.679966 ********************* End of statistics **********************
03:04:43.749644 NAT: Zone(nm:mbuf_cluster, used:0)
03:04:43.751686 NAT: Zone(nm:mbuf_packet, used:0)
03:04:43.751982 NAT: Zone(nm:mbuf, used:1)
03:04:43.754194 NAT: Zone(nm:mbuf_jumbo_pagesize, used:0)
03:04:43.756565 NAT: Zone(nm:mbuf_jumbo_9k, used:0)
03:04:43.757138 NAT: Zone(nm:mbuf_jumbo_16k, used:0)
03:04:43.757138 NAT: Zone(nm:mbuf_ext_refcnt, used:0)
03:04:43.766646 E1000#0: Interrupt attempts: 204351
03:04:43.766954 E1000#0: Interrupts raised : 35978
03:04:43.767028 E1000#0: Interrupts lowered: 3177
03:04:43.767076 E1000#0: ICR outside ISR   : 0
03:04:43.767126 E1000#0: IMS raised ints   : 8772
03:04:43.767172 E1000#0: Interrupts skipped: 72496
03:04:43.767222 E1000#0: Masked interrupts : 95877
03:04:43.767308 E1000#0: Early interrupts  : 0
03:04:43.767369 E1000#0: Late interrupts   : 4630
03:04:43.767415 E1000#0: Lost interrupts   : 0
03:04:43.767455 E1000#0: Interrupts by RX  : 162945
03:04:43.767495 E1000#0: Interrupts by TX  : 16240
03:04:43.767544 E1000#0: Interrupts by ICS : 4522
03:04:43.767593 E1000#0: Interrupts by RDTR: 0
03:04:43.767638 E1000#0: Interrupts by RDMT: 0
03:04:43.767675 E1000#0: Interrupts by TXQE: 0
03:04:43.767709 E1000#0: TX int delay asked: 0
03:04:43.767751 E1000#0: TX delayed:         0
03:04:43.767801 E1000#0: TX delay expired:   0
03:04:43.767838 E1000#0: TX no report asked: 16249
03:04:43.767873 E1000#0: TX abs timer expd : 0
03:04:43.767917 E1000#0: TX int timer expd : 0
03:04:43.767964 E1000#0: RX abs timer expd : 0
03:04:43.767996 E1000#0: RX int timer expd : 0
03:04:43.768060 E1000#0: TX CTX descriptors: 15976
03:04:43.768100 E1000#0: TX DAT descriptors: 16240
03:04:43.768149 E1000#0: TX LEG descriptors: 273
03:04:43.768198 E1000#0: Received frames   : 162945
03:04:43.768236 E1000#0: Transmitted frames: 16240
03:04:43.768280 E1000#0: TX frames up to 1514: 16146
03:04:43.768329 E1000#0: TX frames up to 2962: 6
03:04:43.768374 E1000#0: TX frames up to 4410: 85
03:04:43.768435 E1000#0: TX frames up to 5858: 0
03:04:43.768476 E1000#0: TX frames up to 7306: 2
03:04:43.768518 E1000#0: TX frames up to 8754: 1
03:04:43.768573 E1000#0: TX frames up to 16384: 0
03:04:43.768614 E1000#0: TX frames up to 32768: 0
03:04:43.768666 E1000#0: Larger TX frames    : 0
03:04:43.768711 E1000#0: Max TX Delay        : 0
03:04:43.781357 GIM: KVM: Resetting MSRs
03:04:43.801497 Changing the VM state from 'DESTROYING' to 'TERMINATED'
03:04:43.816386 Console: Machine state changed to 'PoweredOff'
03:04:43.829016 VBoxHeadless: processEventQueue: VERR_INTERRUPTED, termination requested
03:04:49.079077 VBoxHeadless: exiting

    VM Startup Log:


    VM Trace Log:

on: AMD Ryzen Threadripper 2990WX 32-Core Processor
Processor#55 speed: unknown
Processor#55 description: AMD Ryzen Threadripper 2990WX 32-Core Processor
Processor#56 speed: unknown
Processor#56 description: AMD Ryzen Threadripper 2990WX 32-Core Processor
Processor#57 speed: unknown
Processor#57 description: AMD Ryzen Threadripper 2990WX 32-Core Processor
Processor#58 speed: unknown
Processor#58 description: AMD Ryzen Threadripper 2990WX 32-Core Processor
Processor#59 speed: unknown
Processor#59 description: AMD Ryzen Threadripper 2990WX 32-Core Processor
Processor#60 speed: unknown
Processor#60 description: AMD Ryzen Threadripper 2990WX 32-Core Processor
Processor#61 speed: unknown
Processor#61 description: AMD Ryzen Threadripper 2990WX 32-Core Processor
Processor#62 speed: unknown
Processor#62 description: AMD Ryzen Threadripper 2990WX 32-Core Processor
Processor#63 speed: unknown
Processor#63 description: AMD Ryzen Threadripper 2990WX 32-Core Processor
Memory size: 16274 MByte
Memory available: 6604 MByte
Operating system: Windows 10
Operating system version: 10.0.19045

2023-04-02 15:20:00 (15920): 
Command: VBoxManage -q showvminfo "boinc_abca86d06dd959d6" --machinereadable 
Exit Code: -2135228415
Output:
VBoxManage.exe: error: Could not find a registered machine named 'boinc_abca86d06dd959d6'
VBoxManage.exe: error: Details: code VBOX_E_OBJECT_NOT_FOUND (0x80bb0001), component VirtualBoxWrap, interface IVirtualBox, callee IUnknown
VBoxManage.exe: error: Context: "FindMachine(Bstr(VMNameOrUuid).raw(), machine.asOutParam())" at line 2733 of file VBoxManageInfo.cpp

2023-04-02 15:20:01 (15920): 
Command: VBoxManage -q showhdinfo "C:\ProgramData\BOINC\slots\21/vm_image.vdi" 
Exit Code: -2135228412
Output:
VBoxManage.exe: error: Could not find file for the medium 'C:\ProgramData\BOINC\slots\21\vm_image.vdi' (VERR_FILE_NOT_FOUND)
VBoxManage.exe: error: Details: code VBOX_E_FILE_ERROR (0x80bb0004), component MediumWrap, interface IMedium, callee IUnknown
VBoxManage.exe: error: Context: "OpenMedium(Bstr(pszFilenameOrUuid).raw(), enmDevType, enmAccessMode, fForceNewUuidOnOpen, pMedium.asOutParam())" at line 191 of file VBoxManageDisk.cpp

2023-04-02 15:20:03 (15920): 
Command: VBoxManage -q createvm --name "boinc_abca86d06dd959d6" --basefolder "C:\ProgramData\BOINC\slots\21" --ostype "Linux26_64" --register
Exit Code: 0
Output:
Virtual machine 'boinc_abca86d06dd959d6' is created and registered.
UUID: 64cb102f-b812-4f4f-9825-7f853541ab8f
Settings file: 'C:\ProgramData\BOINC\slots\21\boinc_abca86d06dd959d6\boinc_abca86d06dd959d6.vbox'

2023-04-02 15:20:04 (15920): 
Command: VBoxManage -q modifyvm "boinc_abca86d06dd959d6" --description "CMS_2740036_1680330202.476731_0" 
Exit Code: 0
Output:

2023-04-02 15:20:05 (15920): 
Command: VBoxManage -q modifyvm "boinc_abca86d06dd959d6" --memory 2792 
Exit Code: 0
Output:

2023-04-02 15:20:06 (15920): 
Command: VBoxManage -q modifyvm "boinc_abca86d06dd959d6" --cpus 2 
Exit Code: 0
Output:

2023-04-02 15:20:07 (15920): 
Command: VBoxManage -q modifyvm "boinc_abca86d06dd959d6" --acpi on --ioapic on --rtcuseutc off 
Exit Code: 0
Output:

2023-04-02 15:20:08 (15920): 
Command: VBoxManage -q modifyvm "boinc_abca86d06dd959d6" --vram 16 --graphicscontroller VBoxVGA 
Exit Code: 0
Output:

2023-04-02 15:20:09 (15920): 
Command: VBoxManage -q modifyvm "boinc_abca86d06dd959d6" --boot1 disk --boot2 dvd --boot3 none --boot4 none 
Exit Code: 0
Output:

2023-04-02 15:20:11 (15920): 
Command: VBoxManage -q modifyvm "boinc_abca86d06dd959d6" --nic1 nat --natdnsproxy1 on --cableconnected1 off 
Exit Code: 0
Output:

2023-04-02 15:20:12 (15920): 
Command: VBoxManage -q modifyvm "boinc_abca86d06dd959d6" --cableconnected1 on 
Exit Code: 0
Output:

2023-04-02 15:20:13 (15920): 
Command: VBoxManage -q modifyvm "boinc_abca86d06dd959d6" --usb off 
Exit Code: 0
Output:

2023-04-02 15:20:14 (15920): 
Command: VBoxManage -q modifyvm "boinc_abca86d06dd959d6" --uart1 off --uart2 off 
Exit Code: 0
Output:

2023-04-02 15:20:15 (15920): 
Command: VBoxManage -q modifyvm "boinc_abca86d06dd959d6" --lpt1 off --lpt2 off 
Exit Code: 0
Output:

2023-04-02 15:20:16 (15920): 
Command: VBoxManage -q modifyvm "boinc_abca86d06dd959d6" --audio none 
Exit Code: 0
Output:

2023-04-02 15:20:18 (15920): 
Command: VBoxManage -q modifyvm "boinc_abca86d06dd959d6" --clipboard disabled 
Exit Code: 0
Output:

2023-04-02 15:20:19 (15920): 
Command: VBoxManage -q modifyvm "boinc_abca86d06dd959d6" --draganddrop disabled 
Exit Code: 0
Output:

2023-04-02 15:20:20 (15920): 
Command: VBoxManage -q storagectl "boinc_abca86d06dd959d6" --name "Hard Disk Controller" --add "sata" --controller "IntelAHCI" --hostiocache off --portcount 3
Exit Code: 0
Output:

2023-04-02 15:20:21 (15920): 
Command: VBoxManage -q showhdinfo "C:\ProgramData\BOINC/projects/lhcathomedev.cern.ch_lhcathome-dev/CMS_2022_09_07.vdi" 
Exit Code: 0
Output:
UUID:           8fb925ef-3497-4bfb-88e3-bbab2930787f
Parent UUID:    base
State:          created
Type:           normal (base)
Location:       C:\ProgramData\BOINC\projects\lhcathomedev.cern.ch_lhcathome-dev\CMS_2022_09_07.vdi
Storage format: VDI
Format variant: dynamic default
Capacity:       20480 MBytes
Size on disk:   3854 MBytes
Encryption:     disabled
Property:       AllocationBlockSize=1048576

2023-04-02 15:20:22 (15920): 
Command: VBoxManage -q storageattach "boinc_abca86d06dd959d6" --storagectl "Hard Disk Controller" --port 0 --device 0 --type hdd --medium "C:\ProgramData\BOINC/projects/lhcathomedev.cern.ch_lhcathome-dev/CMS_2022_09_07.vdi" 
Exit Code: 0
Output:

2023-04-02 15:20:23 (15920): 
Command: VBoxManage -q storageattach "boinc_abca86d06dd959d6" --storagectl "Hard Disk Controller" --port 0 --device 0 --type hdd --medium none 
Exit Code: 0
Output:

2023-04-02 15:20:24 (15920): 
Command: VBoxManage -q storageattach "boinc_abca86d06dd959d6" --storagectl "Hard Disk Controller" --port 0 --device 0 --type hdd --mtype multiattach --medium "C:\ProgramData\BOINC/projects/lhcathomedev.cern.ch_lhcathome-dev/CMS_2022_09_07.vdi" 
Exit Code: 0
Output:

2023-04-02 15:20:26 (15920): 
Command: VBoxManage -q storageattach "boinc_abca86d06dd959d6" --storagectl "Hard Disk Controller" --port 1 --device 0 --type dvddrive --medium "C:\Program Files\Oracle\VirtualBox/VBoxGuestAdditions.iso" 
Exit Code: 0
Output:

2023-04-02 15:20:27 (15920): 
Command: VBoxManage -q bandwidthctl "boinc_abca86d06dd959d6" add "boinc_abca86d06dd959d6_net" --type network --limit 1024G 
Exit Code: 0
Output:

2023-04-02 15:20:28 (15920): 
Command: VBoxManage -q modifyvm "boinc_abca86d06dd959d6" --natpf1 ",tcp,127.0.0.1,51979,,80" 
Exit Code: 0
Output:

2023-04-02 15:20:29 (15920): 
Command: VBoxManage -q list extpacks
Exit Code: 0
Output:
Extension Packs: 0

2023-04-02 15:20:30 (15920): 
Command: VBoxManage -q sharedfolder add "boinc_abca86d06dd959d6" --name "shared" --hostpath "C:\ProgramData\BOINC\slots\21/shared"
Exit Code: 0
Output:

2023-04-02 15:20:55 (15920): 
Command: VBoxManage -q startvm "boinc_abca86d06dd959d6" --type headless
Exit Code: 0
Output:
Waiting for VM "boinc_abca86d06dd959d6" to power on...
VM "boinc_abca86d06dd959d6" has been successfully started.

2023-04-02 15:20:57 (15920): 
Command: VBoxManage -q controlvm "boinc_abca86d06dd959d6" cpuexecutioncap 100 
Exit Code: 0
Output:

2023-04-02 18:25:32 (15920): 
Command: VBoxManage -q controlvm "boinc_abca86d06dd959d6" poweroff
Exit Code: 0
Output:
0%...10%...20%...30%...40%...50%...60%...70%...80%...90%...100%

2023-04-02 18:25:33 (15920): 
Command: VBoxManage -q snapshot "boinc_abca86d06dd959d6" list 
Exit Code: -108
Output:
This machine does not have any snapshots

2023-04-02 18:25:35 (15920): 
Command: VBoxManage -q bandwidthctl "boinc_abca86d06dd959d6" remove "boinc_abca86d06dd959d6_net" 
Exit Code: 0
Output:

2023-04-02 18:25:36 (15920): 
Command: VBoxManage -q unregistervm "boinc_abca86d06dd959d6" --delete 
Exit Code: 0
Output:
0%...10%...20%...30%...40%...50%...60%...70%...80%...90%...100%

18:25:50 (15920): called boinc_finish(194)

</stderr_txt>
]]>


©2024 CERN