Stderr output

<core_client_version>7.24.1</core_client_version>
<![CDATA[
<stderr_txt>
2024-11-27 00:42:01 (10392): vboxwrapper version 26207
2024-11-27 00:42:01 (10392): BOINC client version: 7.24.1
2024-11-27 00:42:02 (10392): Detected: VirtualBox VboxManage Interface (Version: 7.0.6)
2024-11-27 00:42:03 (10392): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2024-11-27 00:42:03 (10392): Successfully copied 'init_data.xml' to the shared directory.
2024-11-27 00:42:03 (10392): Successfully copied 'input' to the shared directory.
2024-11-27 00:42:04 (10392): Create VM. (boinc_903b487841c83aed, slot#0)
2024-11-27 00:42:07 (10392): Setting Memory Size for VM. (630MB)
2024-11-27 00:42:07 (10392): Setting CPU Count for VM. (1)
2024-11-27 00:42:08 (10392): Setting Chipset Options for VM.
2024-11-27 00:42:08 (10392): Setting Graphics Controller Options for VM.
2024-11-27 00:42:09 (10392): Setting Boot Options for VM.
2024-11-27 00:42:09 (10392): Setting Network Configuration for NAT.
2024-11-27 00:42:10 (10392): Enabling VM Network Access.
2024-11-27 00:42:10 (10392): Disabling USB Support for VM.
2024-11-27 00:42:10 (10392): Disabling COM Port Support for VM.
2024-11-27 00:42:11 (10392): Disabling LPT Port Support for VM.
2024-11-27 00:42:12 (10392): Disabling Audio Support for VM.
2024-11-27 00:42:13 (10392): Disabling Clipboard Support for VM.
2024-11-27 00:42:13 (10392): Disabling Drag and Drop Support for VM.
2024-11-27 00:42:14 (10392): Adding storage controller(s) to VM.
2024-11-27 00:42:14 (10392): Adding virtual disk drive to VM. (Theory_2024_04_30_prod.vdi)
2024-11-27 00:42:17 (10392): Adding VirtualBox Guest Additions to VM.
2024-11-27 00:42:18 (10392): Adding network bandwidth throttle group to VM. (Defaulting to 1024GB)
2024-11-27 00:42:18 (10392): forwarding host port 51204 to guest port 80
2024-11-27 00:42:18 (10392): Enabling remote desktop for VM.
2024-11-27 00:42:18 (10392): Required extension pack not installed, remote desktop not enabled.
2024-11-27 00:42:18 (10392): Enabling shared directory for VM.
2024-11-27 00:42:17 (10392): Starting VM using VBoxManage interface. (boinc_903b487841c83aed, slot#0)
2024-11-27 00:42:31 (10392): Successfully started VM. (PID = '5712')
2024-11-27 00:42:31 (10392): Reporting VM Process ID to BOINC.
2024-11-27 00:42:31 (10392): Guest Log: BIOS: VirtualBox 7.0.6
2024-11-27 00:42:31 (10392): Guest Log: CPUID EDX: 0x178bfbff
2024-11-27 00:42:31 (10392): Guest Log: BIOS: No PCI IDE controller, not probing IDE
2024-11-27 00:42:31 (10392): Guest Log: BIOS: AHCI 0-P#0: PCHS=16383/16/63 LCHS=1024/255/63 0x0000000002800000 sectors
2024-11-27 00:42:31 (10392): VM state change detected. (old = 'poweredoff', new = 'running')
2024-11-27 00:42:31 (10392): Detected: Web Application Enabled (http://localhost:51204)
2024-11-27 00:42:31 (10392): Preference change detected
2024-11-27 00:42:31 (10392): Setting CPU throttle for VM. (100%)
2024-11-27 00:42:31 (10392): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2024-11-27 00:42:33 (10392): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032
2024-11-27 00:42:33 (10392): Guest Log: BIOS: Booting from Hard Disk...
2024-11-27 00:42:39 (10392): Guest Log: BIOS: KBD: unsupported int 16h function 03
2024-11-27 00:42:39 (10392): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 
2024-11-27 00:43:11 (10392): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds
2024-11-27 00:43:13 (10392): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000)
2024-11-27 00:43:16 (10392): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log
2024-11-27 00:43:16 (10392): Guest Log: 00:00:00.000161 main     Log opened 2024-11-27T06:43:15.722921000Z
2024-11-27 00:43:16 (10392): Guest Log: 00:00:00.000284 main     OS Product: Linux
2024-11-27 00:43:16 (10392): Guest Log: 00:00:00.000331 main     OS Release: 4.14.76-13.cernvm.x86_64
2024-11-27 00:43:16 (10392): Guest Log: 00:00:00.000370 main     OS Version: #1 SMP Tue Oct 16 18:26:15 CEST 2018
2024-11-27 00:43:16 (10392): Guest Log: 00:00:00.000409 main     Executable: /usr/sbin/VBoxService
2024-11-27 00:43:16 (10392): Guest Log: 00:00:00.000410 main     Process ID: 3223
2024-11-27 00:43:16 (10392): Guest Log: 00:00:00.000412 main     Package type: LINUX_64BITS_GENERIC
2024-11-27 00:43:16 (10392): Guest Log: 00:00:00.004231 main     5.2.6 r120293 started. Verbose level = 0
2024-11-27 00:44:14 (10392): Guest Log: 07:44:14 CET +01:00 2024-11-27: cranky: [INFO] Detected Theory App
2024-11-27 00:44:14 (10392): Guest Log: 07:44:14 CET +01:00 2024-11-27: cranky: [INFO] Checking CVMFS.
2024-11-27 00:44:16 (10392): Guest Log: Probing /cvmfs/sft.cern.ch... OK
2024-11-27 00:44:16 (10392): Guest Log: VERSION PID UPTIME(M) MEM(K) REVISION EXPIRES(M) NOCATALOGS CACHEUSE(K) CACHEMAX(K) NOFDUSE NOFDMAX NOIOERR NOOPEN HITRATE(%) RX(K) SPEED(K/S) HOST PROXY ONLINE
2024-11-27 00:44:17 (10392): Guest Log: 2.5.2.0 4083 0 28048 31079 3 1 278327 4096000 0 65024 0 0 n/a 5 8 http://s1fnal-cvmfs.openhtc.io/cvmfs/sft.cern.ch DIRECT 1
2024-11-27 00:44:18 (10392): Guest Log: Probing /cvmfs/grid.cern.ch... OK
2024-11-27 00:44:18 (10392): Guest Log: VERSION PID UPTIME(M) MEM(K) REVISION EXPIRES(M) NOCATALOGS CACHEUSE(K) CACHEMAX(K) NOFDUSE NOFDMAX NOIOERR NOOPEN HITRATE(%) RX(K) SPEED(K/S) HOST PROXY ONLINE
2024-11-27 00:44:18 (10392): Guest Log: 2.5.2.0 4153 0 27540 25835 3 2 278327 4096000 0 65024 0 2 -100 10081 3973 http://s1fnal-cvmfs.openhtc.io/cvmfs/grid.cern.ch DIRECT 1
2024-11-27 00:44:20 (10392): Guest Log: Probing /cvmfs/cernvm-prod.cern.ch... OK
2024-11-27 00:44:20 (10392): Guest Log: VERSION PID UPTIME(M) MEM(K) REVISION EXPIRES(M) NOCATALOGS CACHEUSE(K) CACHEMAX(K) NOFDUSE NOFDMAX NOIOERR NOOPEN HITRATE(%) RX(K) SPEED(K/S) HOST PROXY ONLINE
2024-11-27 00:44:20 (10392): Guest Log: 2.5.2.0 4105 0 27232 272 3 1 278327 4096000 0 65024 0 0 n/a 4 1 http://s1cern-cvmfs.openhtc.io/cvmfs/cernvm-prod.cern.ch DIRECT 1
2024-11-27 00:44:22 (10392): Guest Log: Probing /cvmfs/alice.cern.ch... OK
2024-11-27 00:44:22 (10392): Guest Log: VERSION PID UPTIME(M) MEM(K) REVISION EXPIRES(M) NOCATALOGS CACHEUSE(K) CACHEMAX(K) NOFDUSE NOFDMAX NOIOERR NOOPEN HITRATE(%) RX(K) SPEED(K/S) HOST PROXY ONLINE
2024-11-27 00:44:23 (10392): Guest Log: 2.5.2.0 4127 0 40036 20451 3 1 278327 4096000 0 65024 0 0 n/a 6336 3313 http://s1ihep-cvmfs.openhtc.io/cvmfs/alice.cern.ch DIRECT 1
2024-11-27 00:44:23 (10392): Guest Log: 07:44:22 CET +01:00 2024-11-27: cranky: [INFO] Checking runc.
2024-11-27 00:44:23 (10392): Guest Log: 07:44:23 CET +01:00 2024-11-27: cranky: [INFO] Creating the filesystem.
2024-11-27 00:44:23 (10392): Guest Log: 07:44:23 CET +01:00 2024-11-27: cranky: [INFO] Using /cvmfs/cernvm-prod.cern.ch/cvm4
2024-11-27 00:44:23 (10392): Guest Log: 07:44:23 CET +01:00 2024-11-27: cranky: [INFO] Updating config.json.
2024-11-27 00:44:24 (10392): Guest Log: 07:44:23 CET +01:00 2024-11-27: cranky: [INFO] Running Container 'runc'.
2024-11-27 00:44:52 (10392): Guest Log: job: htmld=/shared/html/job
2024-11-27 00:44:55 (10392): Guest Log: job: unpack exitcode=0
2024-11-27 00:44:55 (10392): Guest Log: INFO: activated the work-around for ld:
2024-11-27 00:44:55 (10392): Guest Log: lrwxrwxrwx 1 0 0 15 Nov 27 06:44 /tmp/tmp.ZqoF7bHvi4/ld -> /usr/bin/ld.bfd
2024-11-27 00:44:57 (10392): Guest Log: 07:44:56 CET +01:00 2024-11-27: cranky: [INFO] ===> [runRivet] Wed Nov 27 06:44:54 UTC 2024 [boinc PbPb heavyion-mb 2760 - - pythia8 8.304 default 100000 1144]
2024-11-27 02:22:35 (10392): Status Report: Job Duration: '864000.000000'
2024-11-27 02:22:35 (10392): Status Report: Elapsed Time: '6000.066406'
2024-11-27 02:22:35 (10392): Status Report: CPU Time: '5385.734375'
2024-11-27 04:02:41 (10392): Status Report: Job Duration: '864000.000000'
2024-11-27 04:02:41 (10392): Status Report: Elapsed Time: '12000.066406'
2024-11-27 04:02:41 (10392): Status Report: CPU Time: '11392.765625'
2024-11-27 05:42:46 (10392): Status Report: Job Duration: '864000.000000'
2024-11-27 05:42:46 (10392): Status Report: Elapsed Time: '18000.066406'
2024-11-27 05:42:46 (10392): Status Report: CPU Time: '17398.125000'
2024-11-27 07:22:51 (10392): Status Report: Job Duration: '864000.000000'
2024-11-27 07:22:51 (10392): Status Report: Elapsed Time: '24000.066406'
2024-11-27 07:22:51 (10392): Status Report: CPU Time: '23403.234375'
2024-11-27 08:00:05 (10392): VM state change detected. (old = 'running', new = 'paused')
2024-11-27 12:02:59 (10392): Error in resume VM for VM: -182
Command:
VBoxManage -q controlvm "boinc_903b487841c83aed" 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 395 of file VBoxManageControlVM.cpp

2024-11-27 12:03:00 (10392): VM state change detected. (old = 'paused', new = 'running')
2024-11-27 12:03:11 (10392): Guest Log: 07:17:03.613323 timesync vgsvcTimeSyncWorker: Radical host time change: 14 580 854 000 000ns (HostNow=1 732 730 580 250 000 000 ns HostLast=1 732 715 999 396 000 000 ns)
2024-11-27 12:03:21 (10392): Guest Log: 07:17:14.344757 timesync vgsvcTimeSyncWorker: Radical guest time change: 14 584 191 152 000ns (GuestNow=1 732 730 593 557 721 000 ns GuestLast=1 732 716 009 366 569 000 ns fSetTimeLastLoop=true )
2024-11-27 13:05:51 (10392): Status Report: Job Duration: '864000.000000'
2024-11-27 13:05:51 (10392): Status Report: Elapsed Time: '30000.066406'
2024-11-27 13:05:51 (10392): Status Report: CPU Time: '29410.484375'
2024-11-27 14:45:56 (10392): Status Report: Job Duration: '864000.000000'
2024-11-27 14:45:56 (10392): Status Report: Elapsed Time: '36000.066406'
2024-11-27 14:45:56 (10392): Status Report: CPU Time: '35416.328125'
2024-11-27 16:26:00 (10392): Status Report: Job Duration: '864000.000000'
2024-11-27 16:26:00 (10392): Status Report: Elapsed Time: '42000.066406'
2024-11-27 16:26:00 (10392): Status Report: CPU Time: '41422.250000'
2024-11-27 18:06:05 (10392): Status Report: Job Duration: '864000.000000'
2024-11-27 18:06:05 (10392): Status Report: Elapsed Time: '48000.066406'
2024-11-27 18:06:05 (10392): Status Report: CPU Time: '47428.125000'
2024-11-27 19:46:10 (10392): Status Report: Job Duration: '864000.000000'
2024-11-27 19:46:10 (10392): Status Report: Elapsed Time: '54000.066406'
2024-11-27 19:46:10 (10392): Status Report: CPU Time: '53431.859375'
2024-11-27 21:26:15 (10392): Status Report: Job Duration: '864000.000000'
2024-11-27 21:26:15 (10392): Status Report: Elapsed Time: '60000.066406'
2024-11-27 21:26:15 (10392): Status Report: CPU Time: '59438.031250'
2024-11-27 23:06:19 (10392): Status Report: Job Duration: '864000.000000'
2024-11-27 23:06:19 (10392): Status Report: Elapsed Time: '66000.066406'
2024-11-27 23:06:19 (10392): Status Report: CPU Time: '65443.875000'
2024-11-28 00:46:24 (10392): Status Report: Job Duration: '864000.000000'
2024-11-28 00:46:24 (10392): Status Report: Elapsed Time: '72000.066406'
2024-11-28 00:46:24 (10392): Status Report: CPU Time: '71450.015625'
2024-11-28 02:26:29 (10392): Status Report: Job Duration: '864000.000000'
2024-11-28 02:26:29 (10392): Status Report: Elapsed Time: '78000.066406'
2024-11-28 02:26:29 (10392): Status Report: CPU Time: '77456.234375'
2024-11-28 04:06:34 (10392): Status Report: Job Duration: '864000.000000'
2024-11-28 04:06:34 (10392): Status Report: Elapsed Time: '84000.066406'
2024-11-28 04:06:34 (10392): Status Report: CPU Time: '83462.125000'
2024-11-28 05:46:40 (10392): Status Report: Job Duration: '864000.000000'
2024-11-28 05:46:40 (10392): Status Report: Elapsed Time: '90000.066406'
2024-11-28 05:46:40 (10392): Status Report: CPU Time: '89468.484375'
2024-11-28 07:26:44 (10392): Status Report: Job Duration: '864000.000000'
2024-11-28 07:26:44 (10392): Status Report: Elapsed Time: '96000.066406'
2024-11-28 07:26:44 (10392): Status Report: CPU Time: '95473.843750'
2024-11-28 08:00:04 (10392): VM state change detected. (old = 'running', new = 'paused')
2024-11-29 20:32:06 (10392): Error in resume VM for VM: -182
Command:
VBoxManage -q controlvm "boinc_903b487841c83aed" 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 395 of file VBoxManageControlVM.cpp

2024-11-29 20:32:07 (10392): Guest Log: 27:14:09.626736 timesync vgsvcTimeSyncWorker: Radical host time change: 131 487 954 000 000ns (HostNow=1 732 933 882 827 000 000 ns HostLast=1 732 802 394 873 000 000 ns)
2024-11-29 20:32:07 (10392): Guest Log: 27:14:20.091961 timesync vgsvcTimeSyncWorker: Radical guest time change: 131 492 320 029 000ns (GuestNow=1 732 933 897 225 980 000 ns GuestLast=1 732 802 404 905 951 000 ns fSetTimeLastLoop=true )
2024-11-29 20:32:07 (10392): VM state change detected. (old = 'paused', new = 'running')
2024-11-29 20:32:18 (10392): VM state change detected. (old = 'running', new = 'paused')
2024-11-29 20:32:36 (10392): VM state change detected. (old = 'paused', new = 'running')
2024-11-29 21:39:14 (10392): Status Report: Job Duration: '864000.000000'
2024-11-29 21:39:14 (10392): Status Report: Elapsed Time: '102000.066406'
2024-11-29 21:39:14 (10392): Status Report: CPU Time: '101513.625000'
2024-11-29 23:19:19 (10392): Status Report: Job Duration: '864000.000000'
2024-11-29 23:19:19 (10392): Status Report: Elapsed Time: '108000.066406'
2024-11-29 23:19:19 (10392): Status Report: CPU Time: '107519.734375'
2024-11-30 00:59:24 (10392): Status Report: Job Duration: '864000.000000'
2024-11-30 00:59:24 (10392): Status Report: Elapsed Time: '114000.066406'
2024-11-30 00:59:24 (10392): Status Report: CPU Time: '113525.984375'
2024-11-30 02:39:28 (10392): Status Report: Job Duration: '864000.000000'
2024-11-30 02:39:28 (10392): Status Report: Elapsed Time: '120000.066406'
2024-11-30 02:39:28 (10392): Status Report: CPU Time: '119531.937500'
2024-11-30 04:19:33 (10392): Status Report: Job Duration: '864000.000000'
2024-11-30 04:19:33 (10392): Status Report: Elapsed Time: '126000.066406'
2024-11-30 04:19:33 (10392): Status Report: CPU Time: '125537.921875'
2024-11-30 05:59:38 (10392): Status Report: Job Duration: '864000.000000'
2024-11-30 05:59:38 (10392): Status Report: Elapsed Time: '132000.066406'
2024-11-30 05:59:38 (10392): Status Report: CPU Time: '131542.500000'
2024-11-30 07:39:43 (10392): Status Report: Job Duration: '864000.000000'
2024-11-30 07:39:43 (10392): Status Report: Elapsed Time: '138000.066406'
2024-11-30 07:39:43 (10392): Status Report: CPU Time: '137546.781250'
2024-11-30 08:00:04 (10392): VM state change detected. (old = 'running', new = 'paused')
2024-12-01 12:27:38 (10392): Error in resume VM for VM: -182
Command:
VBoxManage -q controlvm "boinc_903b487841c83aed" 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 395 of file VBoxManageControlVM.cpp

2024-12-01 12:27:39 (10392): Guest Log: 38:42:50.649488 timesync vgsvcTimeSyncWorker: Radical host time change: 102 456 915 000 000ns (HostNow=1 733 077 652 875 000 000 ns HostLast=1 732 975 195 960 000 000 ns)
2024-12-01 12:27:39 (10392): VM state change detected. (old = 'paused', new = 'running')
2024-12-01 12:27:45 (10392): Guest Log: 38:43:00.650307 timesync vgsvcTimeSyncWorker: Radical guest time change: 102 458 716 796 000ns (GuestNow=1 733 077 664 688 054 000 ns GuestLast=1 732 975 205 971 258 000 ns fSetTimeLastLoop=true )
2024-12-01 12:32:46 (10392): VM state change detected. (old = 'running', new = 'paused')
2024-12-01 12:33:06 (10392): VM state change detected. (old = 'paused', new = 'running')
2024-12-01 12:36:19 (10392): VM state change detected. (old = 'running', new = 'paused')
2024-12-01 12:36:37 (10392): VM state change detected. (old = 'paused', new = 'running')
2024-12-01 13:48:07 (10392): Status Report: Job Duration: '864000.000000'
2024-12-01 13:48:07 (10392): Status Report: Elapsed Time: '144000.066406'
2024-12-01 13:48:07 (10392): Status Report: CPU Time: '143537.765625'
2024-12-01 15:28:12 (10392): Status Report: Job Duration: '864000.000000'
2024-12-01 15:28:12 (10392): Status Report: Elapsed Time: '150000.066406'
2024-12-01 15:28:12 (10392): Status Report: CPU Time: '149543.281250'
2024-12-01 17:08:17 (10392): Status Report: Job Duration: '864000.000000'
2024-12-01 17:08:17 (10392): Status Report: Elapsed Time: '156000.066406'
2024-12-01 17:08:17 (10392): Status Report: CPU Time: '155549.156250'
2024-12-01 18:48:22 (10392): Status Report: Job Duration: '864000.000000'
2024-12-01 18:48:22 (10392): Status Report: Elapsed Time: '162000.066406'
2024-12-01 18:48:22 (10392): Status Report: CPU Time: '161552.468750'
2024-12-01 20:28:26 (10392): Status Report: Job Duration: '864000.000000'
2024-12-01 20:28:26 (10392): Status Report: Elapsed Time: '168000.066406'
2024-12-01 20:28:26 (10392): Status Report: CPU Time: '167558.421875'
2024-12-01 22:08:31 (10392): Status Report: Job Duration: '864000.000000'
2024-12-01 22:08:31 (10392): Status Report: Elapsed Time: '174000.066406'
2024-12-01 22:08:31 (10392): Status Report: CPU Time: '173564.421875'
2024-12-01 23:48:36 (10392): Status Report: Job Duration: '864000.000000'
2024-12-01 23:48:36 (10392): Status Report: Elapsed Time: '180000.066406'
2024-12-01 23:48:36 (10392): Status Report: CPU Time: '179570.375000'
2024-12-02 01:28:41 (10392): Status Report: Job Duration: '864000.000000'
2024-12-02 01:28:41 (10392): Status Report: Elapsed Time: '186000.066406'
2024-12-02 01:28:41 (10392): Status Report: CPU Time: '185576.203125'
2024-12-02 03:08:46 (10392): Status Report: Job Duration: '864000.000000'
2024-12-02 03:08:46 (10392): Status Report: Elapsed Time: '192000.066406'
2024-12-02 03:08:46 (10392): Status Report: CPU Time: '191582.171875'
2024-12-02 04:48:50 (10392): Status Report: Job Duration: '864000.000000'
2024-12-02 04:48:50 (10392): Status Report: Elapsed Time: '198000.066406'
2024-12-02 04:48:50 (10392): Status Report: CPU Time: '197588.015625'
2024-12-02 06:28:56 (10392): Status Report: Job Duration: '864000.000000'
2024-12-02 06:28:56 (10392): Status Report: Elapsed Time: '204000.066406'
2024-12-02 06:28:56 (10392): Status Report: CPU Time: '203592.312500'
2024-12-02 08:00:04 (10392): VM state change detected. (old = 'running', new = 'paused')
2024-12-02 20:34:56 (10392): Error in resume VM for VM: -182
Command:
VBoxManage -q controlvm "boinc_903b487841c83aed" 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 395 of file VBoxManageControlVM.cpp

2024-12-02 20:35:22 (10392): Guest Log: 58:15:25.363251 timesync vgsvcTimeSyncWorker: Radical host time change: 45 297 929 000 000ns (HostNow=1 733 193 295 284 000 000 ns HostLast=1 733 147 997 355 000 000 ns)
2024-12-02 20:35:22 (10392): Guest Log: 58:15:37.417903 timesync vgsvcTimeSyncWorker: Radical guest time change: 45 305 720 810 000ns (GuestNow=1 733 193 311 230 223 000 ns GuestLast=1 733 148 005 509 413 000 ns fSetTimeLastLoop=true )
2024-12-02 20:35:22 (10392): VM state change detected. (old = 'paused', new = 'running')
2024-12-02 20:35:34 (10392): VM state change detected. (old = 'running', new = 'paused')
2024-12-02 20:35:54 (10392): VM state change detected. (old = 'paused', new = 'running')
2024-12-02 20:44:15 (10392): Status Report: Job Duration: '864000.000000'
2024-12-02 20:44:15 (10392): Status Report: Elapsed Time: '210000.965330'
2024-12-02 20:44:15 (10392): Status Report: CPU Time: '209605.203125'
2024-12-02 22:24:20 (10392): Status Report: Job Duration: '864000.000000'
2024-12-02 22:24:20 (10392): Status Report: Elapsed Time: '216000.965330'
2024-12-02 22:24:20 (10392): Status Report: CPU Time: '215610.937500'
2024-12-03 00:04:25 (10392): Status Report: Job Duration: '864000.000000'
2024-12-03 00:04:25 (10392): Status Report: Elapsed Time: '222000.965330'
2024-12-03 00:04:25 (10392): Status Report: CPU Time: '221616.828125'
2024-12-03 01:44:29 (10392): Status Report: Job Duration: '864000.000000'
2024-12-03 01:44:29 (10392): Status Report: Elapsed Time: '228000.965330'
2024-12-03 01:44:29 (10392): Status Report: CPU Time: '227622.781250'
2024-12-03 03:24:34 (10392): Status Report: Job Duration: '864000.000000'
2024-12-03 03:24:34 (10392): Status Report: Elapsed Time: '234000.965330'
2024-12-03 03:24:34 (10392): Status Report: CPU Time: '233628.500000'
2024-12-03 05:04:39 (10392): Status Report: Job Duration: '864000.000000'
2024-12-03 05:04:39 (10392): Status Report: Elapsed Time: '240000.965330'
2024-12-03 05:04:39 (10392): Status Report: CPU Time: '239633.531250'
2024-12-03 06:44:44 (10392): Status Report: Job Duration: '864000.000000'
2024-12-03 06:44:44 (10392): Status Report: Elapsed Time: '246000.965330'
2024-12-03 06:44:44 (10392): Status Report: CPU Time: '245638.843750'
2024-12-03 08:00:04 (10392): VM state change detected. (old = 'running', new = 'paused')
2024-12-03 20:23:32 (10392): Error in resume VM for VM: -182
Command:
VBoxManage -q controlvm "boinc_903b487841c83aed" 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 395 of file VBoxManageControlVM.cpp

2024-12-03 20:23:33 (10392): Guest Log: 69:40:37.243632 timesync vgsvcTimeSyncWorker: Radical host time change: 44 610 152 000 000ns (HostNow=1 733 279 009 425 000 000 ns HostLast=1 733 234 399 273 000 000 ns)
2024-12-03 20:23:33 (10392): VM state change detected. (old = 'paused', new = 'running')
2024-12-03 20:23:41 (10392): Guest Log: 69:40:47.244813 timesync vgsvcTimeSyncWorker: Radical guest time change: 44 611 930 371 000ns (GuestNow=1 733 279 021 206 245 000 ns GuestLast=1 733 234 409 275 874 000 ns fSetTimeLastLoop=true )
2024-12-03 20:48:17 (10392): Status Report: Job Duration: '864000.000000'
2024-12-03 20:48:17 (10392): Status Report: Elapsed Time: '252000.965330'
2024-12-03 20:48:17 (10392): Status Report: CPU Time: '251628.218750'
2024-12-03 22:28:22 (10392): Status Report: Job Duration: '864000.000000'
2024-12-03 22:28:22 (10392): Status Report: Elapsed Time: '258000.965330'
2024-12-03 22:28:22 (10392): Status Report: CPU Time: '257634.406250'
2024-12-04 00:08:27 (10392): Status Report: Job Duration: '864000.000000'
2024-12-04 00:08:27 (10392): Status Report: Elapsed Time: '264000.965330'
2024-12-04 00:08:27 (10392): Status Report: CPU Time: '263640.593750'
2024-12-04 01:48:32 (10392): Status Report: Job Duration: '864000.000000'
2024-12-04 01:48:32 (10392): Status Report: Elapsed Time: '270000.965330'
2024-12-04 01:48:32 (10392): Status Report: CPU Time: '269646.593750'
2024-12-04 03:28:36 (10392): Status Report: Job Duration: '864000.000000'
2024-12-04 03:28:36 (10392): Status Report: Elapsed Time: '276000.965330'
2024-12-04 03:28:36 (10392): Status Report: CPU Time: '275652.703125'
2024-12-04 05:08:42 (10392): Status Report: Job Duration: '864000.000000'
2024-12-04 05:08:42 (10392): Status Report: Elapsed Time: '282000.965330'
2024-12-04 05:08:42 (10392): Status Report: CPU Time: '281655.656250'
2024-12-04 06:48:47 (10392): Status Report: Job Duration: '864000.000000'
2024-12-04 06:48:47 (10392): Status Report: Elapsed Time: '288000.965330'
2024-12-04 06:48:47 (10392): Status Report: CPU Time: '287659.812500'
2024-12-04 08:00:04 (10392): VM state change detected. (old = 'running', new = 'paused')
2024-12-04 20:03:10 (10392): Error in resume VM for VM: -182
Command:
VBoxManage -q controlvm "boinc_903b487841c83aed" 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 395 of file VBoxManageControlVM.cpp

2024-12-04 20:03:11 (10392): Guest Log: 81:17:18.634501 timesync vgsvcTimeSyncWorker: Radical host time change: 43 388 558 000 000ns (HostNow=1 733 364 187 480 000 000 ns HostLast=1 733 320 798 922 000 000 ns)
2024-12-04 20:03:11 (10392): VM state change detected. (old = 'paused', new = 'running')
2024-12-04 20:03:21 (10392): Guest Log: 81:17:28.645523 timesync vgsvcTimeSyncWorker: Radical guest time change: 43 392 235 002 000ns (GuestNow=1 733 364 201 160 973 000 ns GuestLast=1 733 320 808 925 971 000 ns fSetTimeLastLoop=true )
2024-12-04 20:31:58 (10392): Status Report: Job Duration: '864000.000000'
2024-12-04 20:31:58 (10392): Status Report: Elapsed Time: '294000.965330'
2024-12-04 20:31:58 (10392): Status Report: CPU Time: '293651.656250'
2024-12-04 22:12:03 (10392): Status Report: Job Duration: '864000.000000'
2024-12-04 22:12:03 (10392): Status Report: Elapsed Time: '300000.965330'
2024-12-04 22:12:03 (10392): Status Report: CPU Time: '299657.859375'
2024-12-04 23:52:08 (10392): Status Report: Job Duration: '864000.000000'
2024-12-04 23:52:08 (10392): Status Report: Elapsed Time: '306000.965330'
2024-12-04 23:52:08 (10392): Status Report: CPU Time: '305664.250000'
2024-12-05 01:32:12 (10392): Status Report: Job Duration: '864000.000000'
2024-12-05 01:32:12 (10392): Status Report: Elapsed Time: '312000.965330'
2024-12-05 01:32:12 (10392): Status Report: CPU Time: '311670.265625'
2024-12-05 03:12:17 (10392): Status Report: Job Duration: '864000.000000'
2024-12-05 03:12:17 (10392): Status Report: Elapsed Time: '318000.965330'
2024-12-05 03:12:17 (10392): Status Report: CPU Time: '317676.031250'
2024-12-05 04:52:22 (10392): Status Report: Job Duration: '864000.000000'
2024-12-05 04:52:22 (10392): Status Report: Elapsed Time: '324000.965330'
2024-12-05 04:52:22 (10392): Status Report: CPU Time: '323680.750000'
2024-12-05 06:32:27 (10392): Status Report: Job Duration: '864000.000000'
2024-12-05 06:32:27 (10392): Status Report: Elapsed Time: '330000.965330'
2024-12-05 06:32:27 (10392): Status Report: CPU Time: '329684.328125'
2024-12-05 08:00:04 (10392): VM state change detected. (old = 'running', new = 'paused')
2024-12-05 20:25:20 (10392): Error in resume VM for VM: -182
Command:
VBoxManage -q controlvm "boinc_903b487841c83aed" 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 395 of file VBoxManageControlVM.cpp

2024-12-05 20:25:21 (10392): VM state change detected. (old = 'paused', new = 'running')
2024-12-05 20:25:26 (10392): Guest Log: 93:14:21.396578 timesync vgsvcTimeSyncWorker: Radical host time change: 44 721 801 000 000ns (HostNow=1 733 451 922 055 000 000 ns HostLast=1 733 407 200 254 000 000 ns)
2024-12-05 20:25:36 (10392): Guest Log: 93:14:31.397097 timesync vgsvcTimeSyncWorker: Radical guest time change: 44 725 440 059 000ns (GuestNow=1 733 451 935 703 796 000 ns GuestLast=1 733 407 210 263 737 000 ns fSetTimeLastLoop=true )
2024-12-05 20:37:49 (10392): Status Report: Job Duration: '864000.000000'
2024-12-05 20:37:49 (10392): Status Report: Elapsed Time: '336000.965330'
2024-12-05 20:37:49 (10392): Status Report: CPU Time: '335678.078125'
2024-12-05 22:17:54 (10392): Status Report: Job Duration: '864000.000000'
2024-12-05 22:17:54 (10392): Status Report: Elapsed Time: '342000.965330'
2024-12-05 22:17:54 (10392): Status Report: CPU Time: '341683.921875'
2024-12-05 23:57:58 (10392): Status Report: Job Duration: '864000.000000'
2024-12-05 23:57:58 (10392): Status Report: Elapsed Time: '348000.965330'
2024-12-05 23:57:58 (10392): Status Report: CPU Time: '347690.140625'
2024-12-06 01:38:03 (10392): Status Report: Job Duration: '864000.000000'
2024-12-06 01:38:03 (10392): Status Report: Elapsed Time: '354000.965330'
2024-12-06 01:38:03 (10392): Status Report: CPU Time: '353696.031250'
2024-12-06 03:18:08 (10392): Status Report: Job Duration: '864000.000000'
2024-12-06 03:18:08 (10392): Status Report: Elapsed Time: '360000.965330'
2024-12-06 03:18:08 (10392): Status Report: CPU Time: '359701.921875'
2024-12-06 04:58:13 (10392): Status Report: Job Duration: '864000.000000'
2024-12-06 04:58:13 (10392): Status Report: Elapsed Time: '366000.965330'
2024-12-06 04:58:13 (10392): Status Report: CPU Time: '365704.937500'
2024-12-06 06:38:18 (10392): Status Report: Job Duration: '864000.000000'
2024-12-06 06:38:18 (10392): Status Report: Elapsed Time: '372000.965330'
2024-12-06 06:38:18 (10392): Status Report: CPU Time: '371709.906250'
2024-12-06 08:00:05 (10392): VM state change detected. (old = 'running', new = 'paused')
2024-12-06 23:08:15 (10392): Error in resume VM for VM: -182
Command:
VBoxManage -q controlvm "boinc_903b487841c83aed" 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 395 of file VBoxManageControlVM.cpp

2024-12-06 23:08:15 (10392): VM state change detected. (old = 'paused', new = 'running')
2024-12-06 23:08:18 (10392): Guest Log: 104:49:13.522962 timesync vgsvcTimeSyncWorker: Radical host time change: 54 490 046 000 000ns (HostNow=1 733 548 093 549 000 000 ns HostLast=1 733 493 603 503 000 000 ns)
2024-12-06 23:08:28 (10392): Guest Log: 104:49:23.530891 timesync vgsvcTimeSyncWorker: Radical guest time change: 54 494 358 392 000ns (GuestNow=1 733 548 107 872 485 000 ns GuestLast=1 733 493 613 514 093 000 ns fSetTimeLastLoop=true )
2024-12-06 23:26:34 (10392): Status Report: Job Duration: '864000.000000'
2024-12-06 23:26:34 (10392): Status Report: Elapsed Time: '378000.965330'
2024-12-06 23:26:34 (10392): Status Report: CPU Time: '377727.437500'
2024-12-07 01:06:38 (10392): Status Report: Job Duration: '864000.000000'
2024-12-07 01:06:38 (10392): Status Report: Elapsed Time: '384000.965330'
2024-12-07 01:06:38 (10392): Status Report: CPU Time: '383733.406250'
2024-12-07 02:46:43 (10392): Status Report: Job Duration: '864000.000000'
2024-12-07 02:46:43 (10392): Status Report: Elapsed Time: '390000.965330'
2024-12-07 02:46:43 (10392): Status Report: CPU Time: '389734.578125'
2024-12-07 04:26:49 (10392): Status Report: Job Duration: '864000.000000'
2024-12-07 04:26:49 (10392): Status Report: Elapsed Time: '396000.965330'
2024-12-07 04:26:49 (10392): Status Report: CPU Time: '395740.984375'
2024-12-07 04:30:40 (10392): VM state change detected. (old = 'running', new = 'paused')
2024-12-07 04:31:00 (10392): VM state change detected. (old = 'paused', new = 'running')
2024-12-07 06:07:04 (10392): Status Report: Job Duration: '864000.000000'
2024-12-07 06:07:04 (10392): Status Report: Elapsed Time: '402001.349800'
2024-12-07 06:07:04 (10392): Status Report: CPU Time: '401747.734375'
2024-12-07 07:46:48 (10392): VM state change detected. (old = 'running', new = 'paused')
2024-12-07 07:47:08 (10392): VM state change detected. (old = 'paused', new = 'running')
2024-12-07 07:47:29 (10392): Status Report: Job Duration: '864000.000000'
2024-12-07 07:47:29 (10392): Status Report: Elapsed Time: '408001.349800'
2024-12-07 07:47:29 (10392): Status Report: CPU Time: '407752.453125'
2024-12-07 08:00:04 (10392): VM state change detected. (old = 'running', new = 'paused')
2024-12-07 21:12:44 (10392): Error in resume VM for VM: -182
Command:
VBoxManage -q controlvm "boinc_903b487841c83aed" 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 395 of file VBoxManageControlVM.cpp

2024-12-07 21:12:45 (10392): Guest Log: 113:40:49.434416 timesync vgsvcTimeSyncWorker: Radical host time change: 47 559 364 000 000ns (HostNow=1 733 627 563 266 000 000 ns HostLast=1 733 580 003 902 000 000 ns)
2024-12-07 21:12:45 (10392): VM state change detected. (old = 'paused', new = 'running')
2024-12-07 21:12:55 (10392): Guest Log: 113:40:59.440645 timesync vgsvcTimeSyncWorker: Radical guest time change: 47 583 008 161 000ns (GuestNow=1 733 627 575 028 187 000 ns GuestLast=1 733 579 992 020 026 000 ns fSetTimeLastLoop=true )
2024-12-07 22:40:15 (10392): Status Report: Job Duration: '864000.000000'
2024-12-07 22:40:15 (10392): Status Report: Elapsed Time: '414001.349800'
2024-12-07 22:40:15 (10392): Status Report: CPU Time: '413768.109375'
2024-12-07 23:59:46 (10392): VM state change detected. (old = 'running', new = 'paused')
2024-12-08 00:00:06 (10392): VM state change detected. (old = 'paused', new = 'running')
2024-12-08 00:20:41 (10392): Status Report: Job Duration: '864000.000000'
2024-12-08 00:20:41 (10392): Status Report: Elapsed Time: '420001.349800'
2024-12-08 00:20:41 (10392): Status Report: CPU Time: '419773.921875'
2024-12-08 01:19:21 (10392): Guest Log: job: run exitcode=0
2024-12-08 01:19:21 (10392): Guest Log: job: diskusage=10100
2024-12-08 01:19:21 (10392): Guest Log: job: logsize=120 k
2024-12-08 01:19:21 (10392): Guest Log: job: times=
2024-12-08 01:19:21 (10392): Guest Log: 0m0.015s 0m0.015s
2024-12-08 01:19:21 (10392): Guest Log: 6946m2.054s 44m28.487s
2024-12-08 01:19:21 (10392): Guest Log: job: cpuusage=419431
2024-12-08 01:19:22 (10392): Guest Log: 08:19:04 CET +01:00 2024-12-08: cranky: [INFO] Container 'runc' finished with status code 0.
2024-12-08 01:19:22 (10392): Guest Log: 08:19:04 CET +01:00 2024-12-08: cranky: [INFO] Preparing output.
2024-12-08 01:19:25 (10392): Guest Log: [INFO] Job Finished
2024-12-08 01:19:25 (10392): Guest Log: [INFO] Shutting Down.
2024-12-08 01:19:25 (10392): VM Completion File Detected.
2024-12-08 01:19:25 (10392): VM Completion Message: Job Finished
.
2024-12-08 01:19:25 (10392): Powering off VM.
2024-12-08 01:19:26 (10392): Successfully stopped VM.
2024-12-08 01:19:26 (10392): Deregistering VM. (boinc_903b487841c83aed, slot#0)
2024-12-08 01:19:26 (10392): Removing network bandwidth throttle group from VM.
2024-12-08 01:19:26 (10392): Removing VM from VirtualBox.
2024-12-08 01:19:31 (10392): called boinc_finish(0)

</stderr_txt>
]]>

©2025 CERN