Name CMS_3494160_1630629334.423841_0
Workunit 2124436
Created 3 Sep 2021, 0:35:36 UTC
Sent 5 Sep 2021, 17:02:48 UTC
Report deadline 12 Sep 2021, 17:02:48 UTC
Received 18 Sep 2021, 15:30:28 UTC
Server state Over
Outcome Computation error
Client state Aborted by user
Exit status 203 (0x000000CB) EXIT_ABORTED_VIA_GUI
Computer ID 3822
Run time 11 hours 10 min 53 sec
CPU time 11 hours 10 min 25 sec
Validate state Invalid
Credit 0.00
Device peak FLOPS 2.65 GFLOPS
Application version CMS Simulation v60.50 (vbox64_mt_mcore_cms)
windows_x86_64

Stderr output

<core_client_version>7.16.11</core_client_version>
<![CDATA[
<message>
aborted by user</message>
<stderr_txt>
2021-09-10 21:04:21 (1852): Detected: vboxwrapper 26197
2021-09-10 21:04:21 (1852): Detected: BOINC client v7.7
2021-09-10 21:04:47 (1852): Detected: VirtualBox VboxManage Interface (Version: 6.1.12)
2021-09-10 21:04:49 (1852): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2021-09-10 21:04:49 (1852): Successfully copied 'init_data.xml' to the shared directory.
2021-09-10 21:04:57 (1852): Create VM. (boinc_c3645098d5fa33b0, slot#4)
2021-09-10 21:05:07 (1852): Setting Memory Size for VM. (1896MB)
2021-09-10 21:05:08 (1852): Setting CPU Count for VM. (1)
2021-09-10 21:05:09 (1852): Setting Chipset Options for VM.
2021-09-10 21:05:10 (1852): Setting Boot Options for VM.
2021-09-10 21:05:22 (1852): Setting Network Configuration for NAT.
2021-09-10 21:05:27 (1852): Enabling VM Network Access.
2021-09-10 21:05:32 (1852): Disabling USB Support for VM.
2021-09-10 21:05:34 (1852): Disabling COM Port Support for VM.
2021-09-10 21:05:36 (1852): Disabling LPT Port Support for VM.
2021-09-10 21:05:36 (1852): Disabling Audio Support for VM.
2021-09-10 21:05:38 (1852): Disabling Clipboard Support for VM.
2021-09-10 21:05:40 (1852): Disabling Drag and Drop Support for VM.
2021-09-10 21:05:46 (1852): Adding storage controller(s) to VM.
2021-09-10 21:05:50 (1852): Adding virtual disk drive to VM. (vm_image.vdi)
2021-09-10 21:05:52 (1852): Adding VirtualBox Guest Additions to VM.
2021-09-10 21:05:55 (1852): Adding network bandwidth throttle group to VM. (Defaulting to 1024GB)
2021-09-10 21:05:59 (1852): forwarding host port 61221 to guest port 80
2021-09-10 21:06:02 (1852): Enabling remote desktop for VM.
2021-09-10 21:06:06 (1852): Enabling shared directory for VM.
2021-09-10 21:06:08 (1852): Starting VM using VBoxManage interface. (boinc_c3645098d5fa33b0, slot#4)
2021-09-10 21:06:34 (1852): Successfully started VM. (PID = '9132')
2021-09-10 21:06:34 (1852): Reporting VM Process ID to BOINC.
2021-09-10 21:06:34 (1852): Guest Log: BIOS: VirtualBox 6.1.12

2021-09-10 21:06:34 (1852): Guest Log: CPUID EDX: 0x178bfbff

2021-09-10 21:06:34 (1852): Guest Log: BIOS: ata0-0: PCHS=16383/16/63 LCHS=1024/255/63

2021-09-10 21:06:34 (1852): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032

2021-09-10 21:06:34 (1852): Guest Log: BIOS: Booting from Hard Disk...

2021-09-10 21:06:34 (1852): VM state change detected. (old = 'PoweredOff', new = 'Running')
2021-09-10 21:06:34 (1852): Detected: Web Application Enabled (http://localhost:61221)
2021-09-10 21:06:34 (1852): Detected: Remote Desktop Enabled (localhost:61222)
2021-09-10 21:06:35 (1852): Preference change detected
2021-09-10 21:06:35 (1852): Setting CPU throttle for VM. (100%)
2021-09-10 21:06:58 (1852): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 120 seconds) or (Vbox_job.xml: 600 seconds))
2021-09-10 21:06:58 (1852): Guest Log: BIOS: KBD: unsupported int 16h function 03

2021-09-10 21:06:58 (1852): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 

2021-09-10 21:07:32 (1852): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds

2021-09-10 21:07:32 (1852): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000)

2021-09-10 21:07:43 (1852): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log

2021-09-10 21:07:43 (1852): Guest Log: 00:00:00.050391 main     Log opened 2021-09-10T19:07:37.619266000Z

2021-09-10 21:07:43 (1852): Guest Log: 00:00:00.054186 main     OS Product: Linux

2021-09-10 21:07:43 (1852): Guest Log: 00:00:00.054395 main     OS Release: 4.14.232-19.cernvm.x86_64

2021-09-10 21:07:43 (1852): Guest Log: 00:00:00.054497 main     OS Version: #1 SMP Fri Apr 30 17:12:25 CEST 2021

2021-09-10 21:07:43 (1852): Guest Log: 00:00:00.054596 main     Executable: /usr/sbin/VBoxService

2021-09-10 21:07:43 (1852): Guest Log: 00:00:00.054598 main     Process ID: 2162

2021-09-10 21:07:43 (1852): Guest Log: 00:00:00.054600 main     Package type: LINUX_64BITS_GENERIC

2021-09-10 21:07:43 (1852): Guest Log: 00:00:00.064242 main     5.2.6 r120293 started. Verbose level = 0

2021-09-10 21:08:38 (1852): Guest Log: [INFO] Mounting the shared directory

2021-09-10 21:08:38 (1852): Guest Log: [INFO] Shared directory mounted, enabling vboxmonitor

2021-09-10 21:08:38 (1852): Guest Log: [INFO] Sourcing essential functions from /cvmfs/grid.cern.ch

2021-09-10 21:08:38 (1852): Guest Log: [INFO] Testing connection to cern.ch

2021-09-10 21:08:38 (1852): Guest Log: [INFO] Testing connection to VCCS

2021-09-10 21:08:38 (1852): Guest Log: [INFO] Testing connection to HTCondor

2021-09-10 21:08:38 (1852): Guest Log: [INFO] Testing connection to WMAgent

2021-09-10 21:08:38 (1852): Guest Log: [INFO] Testing connection to Frontier

2021-09-10 21:08:40 (1852): Guest Log: [INFO] Could not find a local HTTP proxy

2021-09-10 21:08:40 (1852): Guest Log: [INFO] CVMFS and Frontier will have to use DIRECT connections

2021-09-10 21:08:40 (1852): Guest Log: [INFO] This makes the application less efficient

2021-09-10 21:08:41 (1852): Guest Log: [INFO] It also puts higher load on the project servers

2021-09-10 21:08:46 (1852): Guest Log: [INFO] Setting up a local HTTP proxy is highly recommended

2021-09-10 21:08:51 (1852): Guest Log: [INFO] Advice can be found in the project forum

2021-09-10 21:08:51 (1852): Guest Log: [INFO] Reloading and probing the CVMFS configuration

2021-09-10 21:09:11 (1852): Guest Log: [INFO] Probing /cvmfs/grid.cern.ch... OK

2021-09-10 21:09:11 (1852): Guest Log: [INFO] Probing /cvmfs/cvmfs-config.cern.ch... OK

2021-09-10 21:09:26 (1852): Guest Log: [INFO] Probing /cvmfs/singularity.opensciencegrid.org... OK

2021-09-10 21:09:31 (1852): Guest Log: [INFO] Probing /cvmfs/oasis.opensciencegrid.org... OK

2021-09-10 21:09:31 (1852): Guest Log: [INFO] Probing /cvmfs/cms-ib.cern.ch... OK

2021-09-10 21:09:31 (1852): Guest Log: [INFO] Probing /cvmfs/cms.cern.ch... OK

2021-09-10 21:09:31 (1852): Guest Log: [INFO] Excerpt from "cvmfs_config stat": VERSION HOST PROXY

2021-09-10 21:09:31 (1852): Guest Log: [INFO] 2.7.2.0 http://s1cern-cvmfs.openhtc.io DIRECT

2021-09-10 21:09:31 (1852): Guest Log: [INFO] Reading volunteer information

2021-09-10 21:09:45 (1852): Guest Log: [INFO] Requesting an X509 credential from LHC@home

2021-09-10 21:09:47 (1852): Guest Log: [INFO] Requesting an X509 credential from vLHC@home-dev

2021-09-10 21:09:53 (1852): Guest Log: [INFO] CMS application starting. Check log files.

2021-09-10 21:13:23 (1852): VM state change detected. (old = 'Running', new = 'Paused')
2021-09-10 21:13:59 (1852): VM state change detected. (old = 'Paused', new = 'Running')
2021-09-10 21:24:02 (1852): VM state change detected. (old = 'Running', new = 'Paused')
2021-09-10 21:24:38 (1852): VM state change detected. (old = 'Paused', new = 'Running')
2021-09-10 21:34:40 (1852): VM state change detected. (old = 'Running', new = 'Paused')
2021-09-10 21:35:17 (1852): VM state change detected. (old = 'Paused', new = 'Running')
2021-09-10 21:45:21 (1852): VM state change detected. (old = 'Running', new = 'Paused')
2021-09-10 21:45:58 (1852): VM state change detected. (old = 'Paused', new = 'Running')
2021-09-10 21:56:01 (1852): VM state change detected. (old = 'Running', new = 'Paused')
2021-09-10 21:56:35 (1852): VM state change detected. (old = 'Paused', new = 'Running')
2021-09-10 21:57:40 (1852): VM state change detected. (old = 'Running', new = 'Paused')
2021-09-11 21:19:23 (1852): VM state change detected. (old = 'Paused', new = 'Running')
2021-09-11 21:20:35 (1852): Error in pause VM for VM: -2135228414
Command:
VBoxManage -q controlvm "boinc_c3645098d5fa33b0" pause
Output:
VBoxManage.exe: error: Already paused
VBoxManage.exe: error: Details: code VBOX_E_INVALID_VM_STATE (0x80bb0002), component ConsoleWrap, interface IConsole, callee IUnknown
VBoxManage.exe: error: Context: "Pause()" at line 406 of file VBoxManageControlVM.cpp

2021-09-11 21:20:41 (1852): Guest Log: 00:47:12.521297 timesync vgsvcTimeSyncWorker: Radical host time change: 84 117 697 000 000ns (HostNow=1 631 387 932 239 000 000 ns HostLast=1 631 303 814 542 000 000 ns)

2021-09-11 21:20:41 (1852): Guest Log: 00:47:22.530335 timesync vgsvcTimeSyncWorker: Radical guest time change: 84 254 284 345 000ns (GuestNow=1 631 387 943 216 451 000 ns GuestLast=1 631 303 688 932 106 000 ns fSetTimeLastLoop=true )

2021-09-11 21:20:42 (1852): VM state change detected. (old = 'Running', new = 'Paused')
2021-09-11 21:21:10 (1852): VM state change detected. (old = 'Paused', new = 'Running')
2021-09-11 21:31:44 (1852): VM state change detected. (old = 'Running', new = 'Paused')
2021-09-11 21:32:23 (1852): VM state change detected. (old = 'Paused', new = 'Running')
2021-09-11 21:45:50 (1852): Error in pause VM for VM: -2135228414
Command:
VBoxManage -q controlvm "boinc_c3645098d5fa33b0" pause
Output:
VBoxManage.exe: error: Already paused
VBoxManage.exe: error: Details: code VBOX_E_INVALID_VM_STATE (0x80bb0002), component ConsoleWrap, interface IConsole, callee IUnknown
VBoxManage.exe: error: Context: "Pause()" at line 406 of file VBoxManageControlVM.cpp

2021-09-11 21:45:52 (1852): VM state change detected. (old = 'Running', new = 'Paused')
2021-09-11 21:46:20 (1852): VM state change detected. (old = 'Paused', new = 'Running')
2021-09-11 21:57:13 (1852): VM state change detected. (old = 'Running', new = 'Paused')
2021-09-11 21:57:25 (1852): VM state change detected. (old = 'Paused', new = 'Running')
2021-09-11 22:05:16 (1852): VM state change detected. (old = 'Running', new = 'Paused')
2021-09-11 22:05:52 (1852): VM state change detected. (old = 'Paused', new = 'Running')
2021-09-11 22:16:04 (1852): VM state change detected. (old = 'Running', new = 'Paused')
2021-09-11 22:16:42 (1852): VM state change detected. (old = 'Paused', new = 'Running')
2021-09-11 22:26:46 (1852): VM state change detected. (old = 'Running', new = 'Paused')
2021-09-11 22:27:22 (1852): VM state change detected. (old = 'Paused', new = 'Running')
2021-09-11 22:33:41 (1852): Status Report: Job Duration: '64800.000000'
2021-09-11 22:33:41 (1852): Status Report: Elapsed Time: '6000.569324'
2021-09-11 22:33:41 (1852): Status Report: CPU Time: '5941.937500'
2021-09-11 22:37:26 (1852): VM state change detected. (old = 'Running', new = 'Paused')
2021-09-11 22:38:00 (1852): VM state change detected. (old = 'Paused', new = 'Running')
2021-09-11 22:48:06 (1852): VM state change detected. (old = 'Running', new = 'Paused')
2021-09-11 22:48:42 (1852): VM state change detected. (old = 'Paused', new = 'Running')
2021-09-11 22:58:44 (1852): VM state change detected. (old = 'Running', new = 'Paused')
2021-09-11 22:59:21 (1852): VM state change detected. (old = 'Paused', new = 'Running')
2021-09-11 23:09:41 (1852): VM state change detected. (old = 'Running', new = 'Paused')
2021-09-11 23:10:18 (1852): VM state change detected. (old = 'Paused', new = 'Running')
2021-09-11 23:20:40 (1852): VM state change detected. (old = 'Running', new = 'Paused')
2021-09-11 23:21:17 (1852): VM state change detected. (old = 'Paused', new = 'Running')
2021-09-11 23:31:56 (1852): VM state change detected. (old = 'Running', new = 'Paused')
2021-09-11 23:32:00 (1852): VM state change detected. (old = 'Paused', new = 'Running')
2021-09-11 23:42:01 (1852): VM state change detected. (old = 'Running', new = 'Paused')
2021-09-11 23:42:37 (1852): VM state change detected. (old = 'Paused', new = 'Running')
2021-09-11 23:52:40 (1852): VM state change detected. (old = 'Running', new = 'Paused')
2021-09-11 23:53:23 (1852): VM state change detected. (old = 'Paused', new = 'Running')
2021-09-12 00:03:56 (1852): VM state change detected. (old = 'Running', new = 'Paused')
2021-09-12 00:04:32 (1852): VM state change detected. (old = 'Paused', new = 'Running')
2021-09-12 00:14:34 (1852): VM state change detected. (old = 'Running', new = 'Paused')
2021-09-12 00:15:11 (1852): VM state change detected. (old = 'Paused', new = 'Running')
2021-09-12 00:24:36 (1852): Status Report: Job Duration: '64800.000000'
2021-09-12 00:24:36 (1852): Status Report: Elapsed Time: '12001.024302'
2021-09-12 00:24:36 (1852): Status Report: CPU Time: '12097.734375'
2021-09-12 00:25:13 (1852): VM state change detected. (old = 'Running', new = 'Paused')
2021-09-12 00:25:49 (1852): VM state change detected. (old = 'Paused', new = 'Running')
2021-09-12 00:33:10 (1852): VM state change detected. (old = 'Running', new = 'Paused')
2021-09-12 17:29:54 (1852): VM state change detected. (old = 'Paused', new = 'Running')
2021-09-12 17:32:03 (1852): Error in pause VM for VM: -2135228414
Command:
VBoxManage -q controlvm "boinc_c3645098d5fa33b0" pause
Output:
VBoxManage.exe: error: Already paused
VBoxManage.exe: error: Details: code VBOX_E_INVALID_VM_STATE (0x80bb0002), component ConsoleWrap, interface IConsole, callee IUnknown
VBoxManage.exe: error: Context: "Pause()" at line 406 of file VBoxManageControlVM.cpp

2021-09-12 17:32:04 (1852): Guest Log: 03:50:39.223459 timesync vgsvcTimeSyncWorker: Radical host time change: 61 077 684 000 000ns (HostNow=1 631 460 605 027 000 000 ns HostLast=1 631 399 527 343 000 000 ns)

2021-09-12 17:32:04 (1852): Guest Log: 03:50:49.228219 timesync vgsvcTimeSyncWorker: Radical guest time change: 61 675 122 243 000ns (GuestNow=1 631 460 615 031 777 000 ns GuestLast=1 631 398 939 909 534 000 ns fSetTimeLastLoop=true )

2021-09-12 17:32:04 (1852): VM state change detected. (old = 'Running', new = 'Paused')
2021-09-12 17:32:27 (1852): VM state change detected. (old = 'Paused', new = 'Running')
2021-09-12 17:44:17 (1852): Error in pause VM for VM: -2135228414
Command:
VBoxManage -q controlvm "boinc_c3645098d5fa33b0" pause
Output:
VBoxManage.exe: error: Already paused
VBoxManage.exe: error: Details: code VBOX_E_INVALID_VM_STATE (0x80bb0002), component ConsoleWrap, interface IConsole, callee IUnknown
VBoxManage.exe: error: Context: "Pause()" at line 406 of file VBoxManageControlVM.cpp

2021-09-12 17:44:19 (1852): VM state change detected. (old = 'Running', new = 'Paused')
2021-09-12 17:44:26 (1852): VM state change detected. (old = 'Paused', new = 'Running')
2021-09-12 17:56:27 (1852): VM state change detected. (old = 'Running', new = 'Paused')
2021-09-12 17:56:34 (1852): VM state change detected. (old = 'Paused', new = 'Running')
2021-09-12 18:09:09 (1852): Error in pause VM for VM: -2135228414
Command:
VBoxManage -q controlvm "boinc_c3645098d5fa33b0" pause
Output:
VBoxManage.exe: error: Already paused
VBoxManage.exe: error: Details: code VBOX_E_INVALID_VM_STATE (0x80bb0002), component ConsoleWrap, interface IConsole, callee IUnknown
VBoxManage.exe: error: Context: "Pause()" at line 406 of file VBoxManageControlVM.cpp

2021-09-12 18:09:10 (1852): VM state change detected. (old = 'Running', new = 'Paused')
2021-09-12 18:09:34 (1852): VM state change detected. (old = 'Paused', new = 'Running')
2021-09-12 18:16:25 (1852): VM state change detected. (old = 'Running', new = 'Paused')
2021-09-12 18:17:01 (1852): VM state change detected. (old = 'Paused', new = 'Running')
2021-09-12 18:27:09 (1852): VM state change detected. (old = 'Running', new = 'Paused')
2021-09-12 18:27:42 (1852): VM state change detected. (old = 'Paused', new = 'Running')
2021-09-12 18:37:48 (1852): VM state change detected. (old = 'Running', new = 'Paused')
2021-09-12 18:38:26 (1852): VM state change detected. (old = 'Paused', new = 'Running')
2021-09-12 18:48:25 (1852): VM state change detected. (old = 'Running', new = 'Paused')
2021-09-12 18:49:02 (1852): VM state change detected. (old = 'Paused', new = 'Running')
2021-09-12 18:59:15 (1852): VM state change detected. (old = 'Running', new = 'Paused')
2021-09-12 18:59:48 (1852): VM state change detected. (old = 'Paused', new = 'Running')
2021-09-12 19:09:57 (1852): VM state change detected. (old = 'Running', new = 'Paused')
2021-09-12 19:10:29 (1852): VM state change detected. (old = 'Paused', new = 'Running')
2021-09-12 19:20:40 (1852): VM state change detected. (old = 'Running', new = 'Paused')
2021-09-12 19:21:28 (1852): VM state change detected. (old = 'Paused', new = 'Running')
2021-09-12 19:25:33 (1852): Status Report: Job Duration: '64800.000000'
2021-09-12 19:25:33 (1852): Status Report: Elapsed Time: '18001.263731'
2021-09-12 19:25:33 (1852): Status Report: CPU Time: '18344.031250'
2021-09-12 19:31:34 (1852): VM state change detected. (old = 'Running', new = 'Paused')
2021-09-12 19:32:12 (1852): VM state change detected. (old = 'Paused', new = 'Running')
2021-09-12 19:42:10 (1852): VM state change detected. (old = 'Running', new = 'Paused')
2021-09-12 19:42:46 (1852): VM state change detected. (old = 'Paused', new = 'Running')
2021-09-12 19:52:47 (1852): VM state change detected. (old = 'Running', new = 'Paused')
2021-09-12 19:53:24 (1852): VM state change detected. (old = 'Paused', new = 'Running')
2021-09-12 20:03:28 (1852): VM state change detected. (old = 'Running', new = 'Paused')
2021-09-12 20:04:02 (1852): VM state change detected. (old = 'Paused', new = 'Running')
2021-09-12 20:14:03 (1852): VM state change detected. (old = 'Running', new = 'Paused')
2021-09-12 20:14:39 (1852): VM state change detected. (old = 'Paused', new = 'Running')
2021-09-12 20:24:43 (1852): VM state change detected. (old = 'Running', new = 'Paused')
2021-09-12 20:25:18 (1852): VM state change detected. (old = 'Paused', new = 'Running')
2021-09-12 20:35:19 (1852): VM state change detected. (old = 'Running', new = 'Paused')
2021-09-12 20:35:55 (1852): VM state change detected. (old = 'Paused', new = 'Running')
2021-09-12 20:45:58 (1852): VM state change detected. (old = 'Running', new = 'Paused')
2021-09-12 20:46:33 (1852): VM state change detected. (old = 'Paused', new = 'Running')
2021-09-12 20:56:34 (1852): VM state change detected. (old = 'Running', new = 'Paused')
2021-09-12 20:57:10 (1852): VM state change detected. (old = 'Paused', new = 'Running')
2021-09-12 21:07:13 (1852): VM state change detected. (old = 'Running', new = 'Paused')
2021-09-12 21:07:49 (1852): VM state change detected. (old = 'Paused', new = 'Running')
2021-09-12 21:14:28 (1852): Status Report: Job Duration: '64800.000000'
2021-09-12 21:14:28 (1852): Status Report: Elapsed Time: '24001.534349'
2021-09-12 21:14:28 (1852): Status Report: CPU Time: '24383.937500'
2021-09-12 21:17:50 (1852): VM state change detected. (old = 'Running', new = 'Paused')
2021-09-12 21:18:26 (1852): VM state change detected. (old = 'Paused', new = 'Running')
2021-09-12 21:28:44 (1852): VM state change detected. (old = 'Running', new = 'Paused')
2021-09-12 21:29:19 (1852): VM state change detected. (old = 'Paused', new = 'Running')
2021-09-12 21:39:21 (1852): VM state change detected. (old = 'Running', new = 'Paused')
2021-09-12 21:39:57 (1852): VM state change detected. (old = 'Paused', new = 'Running')
2021-09-12 21:50:13 (1852): VM state change detected. (old = 'Running', new = 'Paused')
2021-09-12 21:50:49 (1852): VM state change detected. (old = 'Paused', new = 'Running')
2021-09-12 21:58:25 (1852): VM state change detected. (old = 'Running', new = 'Paused')
2021-09-16 18:51:47 (1852): Error in resume VM for VM: -2135228414
Command:
VBoxManage -q controlvm "boinc_c3645098d5fa33b0" resume
Output:
VBoxManage.exe: error: Cannot resume the machine as it is not paused (machine state: Running)
VBoxManage.exe: error: Details: code VBOX_E_INVALID_VM_STATE (0x80bb0002), component ConsoleWrap, interface IConsole, callee IUnknown
VBoxManage.exe: error: Context: "Resume()" at line 410 of file VBoxManageControlVM.cpp

2021-09-16 18:51:48 (1852): Guest Log: 08:04:25.766883 timesync vgsvcTimeSyncWorker: Radical host time change: 334 113 993 000 000ns (HostNow=1 631 810 795 253 000 000 ns HostLast=1 631 476 681 260 000 000 ns)

2021-09-16 18:51:48 (1852): Guest Log: 08:04:35.770837 timesync vgsvcTimeSyncWorker: Radical guest time change: 334 973 686 515 000ns (GuestNow=1 631 810 805 256 956 000 ns GuestLast=1 631 475 831 570 441 000 ns fSetTimeLastLoop=true )

2021-09-16 18:51:48 (1852): VM state change detected. (old = 'Paused', new = 'Running')
2021-09-16 19:04:20 (1852): Error in pause VM for VM: -108
Command:
VBoxManage -q controlvm "boinc_c3645098d5fa33b0" pause
Output:

2021-09-16 19:04:41 (1852): VM state change detected. (old = 'Running', new = 'Paused')
2021-09-16 19:05:56 (1852): Error in resume VM for VM: -2135228414
Command:
VBoxManage -q controlvm "boinc_c3645098d5fa33b0" resume
Output:
VBoxManage.exe: error: Cannot resume the machine as it is not paused (machine state: Running)
VBoxManage.exe: error: Details: code VBOX_E_INVALID_VM_STATE (0x80bb0002), component ConsoleWrap, interface IConsole, callee IUnknown
VBoxManage.exe: error: Context: "Resume()" at line 410 of file VBoxManageControlVM.cpp

2021-09-16 19:05:57 (1852): VM state change detected. (old = 'Paused', new = 'Running')
2021-09-16 19:15:53 (1852): VM state change detected. (old = 'Running', new = 'Paused')
2021-09-16 19:16:30 (1852): VM state change detected. (old = 'Paused', new = 'Running')
2021-09-16 19:27:47 (1852): VM state change detected. (old = 'Running', new = 'Paused')
2021-09-16 19:28:22 (1852): VM state change detected. (old = 'Paused', new = 'Running')
2021-09-16 19:37:53 (1852): VM state change detected. (old = 'Running', new = 'Paused')
2021-09-16 19:39:04 (1852): VM state change detected. (old = 'Paused', new = 'Running')
2021-09-16 19:52:44 (1852): VM state change detected. (old = 'Running', new = 'Paused')
2021-09-16 19:52:47 (1852): VM state change detected. (old = 'Paused', new = 'Running')
2021-09-16 19:59:49 (1852): VM state change detected. (old = 'Running', new = 'Paused')
2021-09-16 20:00:25 (1852): VM state change detected. (old = 'Paused', new = 'Running')
2021-09-16 20:10:51 (1852): VM state change detected. (old = 'Running', new = 'Paused')
2021-09-16 20:11:19 (1852): VM state change detected. (old = 'Paused', new = 'Running')
2021-09-16 20:20:47 (1852): Status Report: Job Duration: '64800.000000'
2021-09-16 20:20:47 (1852): Status Report: Elapsed Time: '30002.460062'
2021-09-16 20:20:47 (1852): Status Report: CPU Time: '31362.171875'
2021-09-16 20:21:25 (1852): VM state change detected. (old = 'Running', new = 'Paused')
2021-09-16 20:22:02 (1852): VM state change detected. (old = 'Paused', new = 'Running')
2021-09-16 20:32:03 (1852): VM state change detected. (old = 'Running', new = 'Paused')
2021-09-16 20:32:41 (1852): VM state change detected. (old = 'Paused', new = 'Running')
2021-09-16 20:43:15 (1852): VM state change detected. (old = 'Running', new = 'Paused')
2021-09-16 20:43:32 (1852): VM state change detected. (old = 'Paused', new = 'Running')
2021-09-16 20:53:36 (1852): VM state change detected. (old = 'Running', new = 'Paused')
2021-09-16 20:54:12 (1852): VM state change detected. (old = 'Paused', new = 'Running')
2021-09-16 21:04:24 (1852): VM state change detected. (old = 'Running', new = 'Paused')
2021-09-16 21:05:04 (1852): VM state change detected. (old = 'Paused', new = 'Running')
2021-09-16 21:15:17 (1852): VM state change detected. (old = 'Running', new = 'Paused')
2021-09-16 21:15:53 (1852): VM state change detected. (old = 'Paused', new = 'Running')
2021-09-16 21:25:59 (1852): VM state change detected. (old = 'Running', new = 'Paused')
2021-09-16 21:26:32 (1852): VM state change detected. (old = 'Paused', new = 'Running')
2021-09-16 21:36:34 (1852): VM state change detected. (old = 'Running', new = 'Paused')
2021-09-16 21:37:11 (1852): VM state change detected. (old = 'Paused', new = 'Running')
2021-09-16 21:47:55 (1852): VM state change detected. (old = 'Running', new = 'Paused')
2021-09-16 21:49:13 (1852): VM state change detected. (old = 'Paused', new = 'Running')
2021-09-16 21:58:37 (1852): VM state change detected. (old = 'Running', new = 'Paused')
2021-09-16 21:59:13 (1852): VM state change detected. (old = 'Paused', new = 'Running')
2021-09-16 22:09:31 (1852): VM state change detected. (old = 'Running', new = 'Paused')
2021-09-16 22:10:08 (1852): VM state change detected. (old = 'Paused', new = 'Running')
2021-09-16 22:12:05 (1852): Status Report: Job Duration: '64800.000000'
2021-09-16 22:12:05 (1852): Status Report: Elapsed Time: '36002.951611'
2021-09-16 22:12:05 (1852): Status Report: CPU Time: '37446.625000'
2021-09-16 22:20:09 (1852): VM state change detected. (old = 'Running', new = 'Paused')
2021-09-16 22:20:45 (1852): VM state change detected. (old = 'Paused', new = 'Running')
2021-09-16 22:30:48 (1852): VM state change detected. (old = 'Running', new = 'Paused')
2021-09-16 22:31:24 (1852): VM state change detected. (old = 'Paused', new = 'Running')
2021-09-16 22:41:29 (1852): VM state change detected. (old = 'Running', new = 'Paused')
2021-09-16 22:42:06 (1852): VM state change detected. (old = 'Paused', new = 'Running')
2021-09-16 22:52:23 (1852): VM state change detected. (old = 'Running', new = 'Paused')
2021-09-16 22:52:59 (1852): VM state change detected. (old = 'Paused', new = 'Running')

</stderr_txt>
]]>


©2024 CERN