Stderr output

<core_client_version>7.14.2</core_client_version>
<![CDATA[
<stderr_txt>
2020-08-21 13:36:29 (8824): Detected: vboxwrapper 26197
2020-08-21 13:36:29 (8824): Detected: BOINC client v7.7
2020-08-21 13:36:29 (8824): Status Report: Detected vboxsvc.exe. (PID = '20832')
2020-08-21 13:36:30 (8824): Detected: VirtualBox VboxManage Interface (Version: 5.2.8)
2020-08-21 13:36:30 (8824): Detected: Sandbox Configuration Enabled
2020-08-21 13:36:30 (8824): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2020-08-21 13:36:30 (8824): Successfully copied 'init_data.xml' to the shared directory.
2020-08-21 13:36:30 (8824): Successfully copied 'input' to the shared directory.
2020-08-21 13:36:31 (8824): Create VM. (boinc_adffd3673c1767c9, slot#4)
2020-08-21 13:36:31 (8824): Setting Memory Size for VM. (630MB)
2020-08-21 13:36:32 (8824): Setting CPU Count for VM. (1)
2020-08-21 13:36:32 (8824): Setting Chipset Options for VM.
2020-08-21 13:36:32 (8824): Setting Boot Options for VM.
2020-08-21 13:36:32 (8824): Setting Network Configuration for NAT.
2020-08-21 13:36:33 (8824): Enabling VM Network Access.
2020-08-21 13:36:33 (8824): Disabling USB Support for VM.
2020-08-21 13:36:33 (8824): Disabling COM Port Support for VM.
2020-08-21 13:36:34 (8824): Disabling LPT Port Support for VM.
2020-08-21 13:36:34 (8824): Disabling Audio Support for VM.
2020-08-21 13:36:34 (8824): Disabling Clipboard Support for VM.
2020-08-21 13:36:34 (8824): Disabling Drag and Drop Support for VM.
2020-08-21 13:36:35 (8824): Adding storage controller(s) to VM.
2020-08-21 13:36:35 (8824): Adding virtual disk drive to VM. (vm_image.vdi)
2020-08-21 13:36:35 (8824): Adding VirtualBox Guest Additions to VM.
2020-08-21 13:36:35 (8824): Adding network bandwidth throttle group to VM. (Defaulting to 1024GB)
2020-08-21 13:36:36 (8824): forwarding host port 59431 to guest port 80
2020-08-21 13:36:36 (8824): Enabling remote desktop for VM.
2020-08-21 13:36:36 (8824): Required extension pack not installed, remote desktop not enabled.
2020-08-21 13:36:36 (8824): Enabling shared directory for VM.
2020-08-21 13:36:36 (8824): Starting VM using VBoxManage interface. (boinc_adffd3673c1767c9, slot#4)
2020-08-21 13:36:40 (8824): Successfully started VM. (PID = '6296')
2020-08-21 13:36:40 (8824): Reporting VM Process ID to BOINC.
2020-08-21 13:36:40 (8824): Guest Log: BIOS: VirtualBox 5.2.8

2020-08-21 13:36:40 (8824): Guest Log: CPUID EDX: 0x078bfbff

2020-08-21 13:36:40 (8824): Guest Log: BIOS: ata0-0: PCHS=16383/16/63 LCHS=1024/255/63

2020-08-21 13:36:40 (8824): VM state change detected. (old = 'PoweredOff', new = 'Running')
2020-08-21 13:36:40 (8824): Detected: Web Application Enabled (http://localhost:59431)
2020-08-21 13:36:40 (8824): Preference change detected
2020-08-21 13:36:40 (8824): Setting CPU throttle for VM. (60%)
2020-08-21 13:36:41 (8824): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2020-08-21 13:36:42 (8824): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032

2020-08-21 13:36:42 (8824): Guest Log: BIOS: Booting from Hard Disk...

2020-08-21 13:36:44 (8824): Guest Log: BIOS: KBD: unsupported int 16h function 03

2020-08-21 13:36:44 (8824): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 

2020-08-21 13:37:05 (8824): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds

2020-08-21 13:37:05 (8824): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000)

2020-08-21 13:37:07 (8824): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log

2020-08-21 13:37:07 (8824): Guest Log: 00:00:00.000082 main     Log opened 2020-08-21T11:37:06.576097000Z

2020-08-21 13:37:07 (8824): Guest Log: 00:00:00.000168 main     OS Product: Linux

2020-08-21 13:37:07 (8824): Guest Log: 00:00:00.000207 main     OS Release: 4.14.76-13.cernvm.x86_64

2020-08-21 13:37:07 (8824): Guest Log: 00:00:00.000242 main     OS Version: #1 SMP Tue Oct 16 18:26:15 CEST 2018

2020-08-21 13:37:07 (8824): Guest Log: 00:00:00.000276 main     Executable: /usr/sbin/VBoxService

2020-08-21 13:37:07 (8824): Guest Log: 00:00:00.000277 main     Process ID: 3190

2020-08-21 13:37:07 (8824): Guest Log: 00:00:00.000278 main     Package type: LINUX_64BITS_GENERIC

2020-08-21 13:37:07 (8824): Guest Log: 00:00:00.002824 main     5.2.6 r120293 started. Verbose level = 0

2020-08-21 13:37:35 (8824): Guest Log: 13:37:34 CEST +02:00 2020-08-21: cranky: [INFO] Detected Theory App

2020-08-21 13:37:35 (8824): Guest Log: 13:37:34 CEST +02:00 2020-08-21: cranky: [INFO] Checking CVMFS.

2020-08-21 13:37:36 (8824): Guest Log: Probing /cvmfs/sft.cern.ch... OK

2020-08-21 13:37:37 (8824): 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

2020-08-21 13:37:37 (8824): Guest Log: 2.5.2.0 4074 0 27176 18689 3 1 263940 4096000 0 65024 0 0 n/a 5 5 http://s1ral-cvmfs.openhtc.io/cvmfs/sft.cern.ch DIRECT 1

2020-08-21 13:37:38 (8824): Guest Log: Probing /cvmfs/grid.cern.ch... OK

2020-08-21 13:37:39 (8824): 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

2020-08-21 13:37:39 (8824): Guest Log: 2.5.2.0 4022 0 27536 13029 3 2 263940 4096000 0 65024 0 2 -100 6411 3065 http://s1cern-cvmfs.openhtc.io/cvmfs/grid.cern.ch DIRECT 1

2020-08-21 13:37:40 (8824): Guest Log: Probing /cvmfs/cernvm-prod.cern.ch... OK

2020-08-21 13:37:41 (8824): 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

2020-08-21 13:37:41 (8824): Guest Log: 2.5.2.0 4052 0 27428 255 3 1 263940 4096000 0 65024 0 0 n/a 4 4 http://s1unl-cvmfs.openhtc.io/cvmfs/cernvm-prod.cern.ch DIRECT 1

2020-08-21 13:37:42 (8824): Guest Log: Probing /cvmfs/alice.cern.ch... OK

2020-08-21 13:37:42 (8824): 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

2020-08-21 13:37:42 (8824): Guest Log: 2.5.2.0 4096 0 27904 7279 3 1 263940 4096000 0 65024 0 0 n/a 4262 2868 http://s1ral-cvmfs.openhtc.io/cvmfs/alice.cern.ch DIRECT 1

2020-08-21 13:37:42 (8824): Guest Log: 13:37:41 CEST +02:00 2020-08-21: cranky: [INFO] Checking runc.

2020-08-21 13:37:42 (8824): Guest Log: 13:37:41 CEST +02:00 2020-08-21: cranky: [INFO] Creating the filesystem.

2020-08-21 13:37:42 (8824): Guest Log: 13:37:41 CEST +02:00 2020-08-21: cranky: [INFO] Using /cvmfs/cernvm-prod.cern.ch/cvm3

2020-08-21 13:37:43 (8824): Guest Log: 13:37:41 CEST +02:00 2020-08-21: cranky: [INFO] Updating config.json.

2020-08-21 13:37:43 (8824): Guest Log: 13:37:41 CEST +02:00 2020-08-21: cranky: [INFO] Running Container 'runc'.

2020-08-21 13:37:43 (8824): Guest Log: job: htmld=/shared/html/job

2020-08-21 13:37:43 (8824): Guest Log: job: unpack exitcode=0

2020-08-21 13:37:45 (8824): Guest Log: 13:37:44 CEST +02:00 2020-08-21: cranky: [INFO] ===> [runRivet] Fri Aug 21 11:37:42 UTC 2020 [boinc pp jets 13000 250,-,4160 - pythia8 8.244 cr1 100000 38]

2020-08-21 15:16:48 (8824): Status Report: Job Duration: '864000.000000'
2020-08-21 15:16:48 (8824): Status Report: Elapsed Time: '6000.000000'
2020-08-21 15:16:48 (8824): Status Report: CPU Time: '3560.468750'
2020-08-21 16:56:56 (8824): Status Report: Job Duration: '864000.000000'
2020-08-21 16:56:56 (8824): Status Report: Elapsed Time: '12000.000000'
2020-08-21 16:56:56 (8824): Status Report: CPU Time: '7171.062500'
2020-08-21 18:37:07 (8824): Status Report: Job Duration: '864000.000000'
2020-08-21 18:37:07 (8824): Status Report: Elapsed Time: '18000.000000'
2020-08-21 18:37:07 (8824): Status Report: CPU Time: '10780.296875'
2020-08-21 20:17:16 (8824): Status Report: Job Duration: '864000.000000'
2020-08-21 20:17:16 (8824): Status Report: Elapsed Time: '24000.000000'
2020-08-21 20:17:16 (8824): Status Report: CPU Time: '14392.031250'
2020-08-21 21:57:24 (8824): Status Report: Job Duration: '864000.000000'
2020-08-21 21:57:24 (8824): Status Report: Elapsed Time: '30000.000000'
2020-08-21 21:57:24 (8824): Status Report: CPU Time: '18003.390625'
2020-08-21 23:37:33 (8824): Status Report: Job Duration: '864000.000000'
2020-08-21 23:37:33 (8824): Status Report: Elapsed Time: '36000.000000'
2020-08-21 23:37:33 (8824): Status Report: CPU Time: '21614.781250'
2020-08-22 01:17:42 (8824): Status Report: Job Duration: '864000.000000'
2020-08-22 01:17:42 (8824): Status Report: Elapsed Time: '42000.000000'
2020-08-22 01:17:42 (8824): Status Report: CPU Time: '25226.296875'
2020-08-22 02:57:51 (8824): Status Report: Job Duration: '864000.000000'
2020-08-22 02:57:51 (8824): Status Report: Elapsed Time: '48000.000000'
2020-08-22 02:57:51 (8824): Status Report: CPU Time: '28837.625000'
2020-08-22 04:37:59 (8824): Status Report: Job Duration: '864000.000000'
2020-08-22 04:37:59 (8824): Status Report: Elapsed Time: '54000.000000'
2020-08-22 04:37:59 (8824): Status Report: CPU Time: '32449.062500'
2020-08-22 06:18:09 (8824): Status Report: Job Duration: '864000.000000'
2020-08-22 06:18:09 (8824): Status Report: Elapsed Time: '60000.000000'
2020-08-22 06:18:09 (8824): Status Report: CPU Time: '36060.984375'
2020-08-22 07:58:18 (8824): Status Report: Job Duration: '864000.000000'
2020-08-22 07:58:18 (8824): Status Report: Elapsed Time: '66000.000000'
2020-08-22 07:58:18 (8824): Status Report: CPU Time: '39672.468750'
2020-08-22 09:38:27 (8824): Status Report: Job Duration: '864000.000000'
2020-08-22 09:38:27 (8824): Status Report: Elapsed Time: '72000.000000'
2020-08-22 09:38:27 (8824): Status Report: CPU Time: '43284.250000'
2020-08-22 11:18:36 (8824): Status Report: Job Duration: '864000.000000'
2020-08-22 11:18:36 (8824): Status Report: Elapsed Time: '78000.000000'
2020-08-22 11:18:36 (8824): Status Report: CPU Time: '46895.937500'
2020-08-22 12:58:46 (8824): Status Report: Job Duration: '864000.000000'
2020-08-22 12:58:46 (8824): Status Report: Elapsed Time: '84000.000000'
2020-08-22 12:58:46 (8824): Status Report: CPU Time: '50508.734375'
2020-08-22 14:38:55 (8824): Status Report: Job Duration: '864000.000000'
2020-08-22 14:38:55 (8824): Status Report: Elapsed Time: '90000.000000'
2020-08-22 14:38:55 (8824): Status Report: CPU Time: '54120.500000'
2020-08-22 16:19:05 (8824): Status Report: Job Duration: '864000.000000'
2020-08-22 16:19:05 (8824): Status Report: Elapsed Time: '96000.000000'
2020-08-22 16:19:05 (8824): Status Report: CPU Time: '57733.109375'
2020-08-22 17:59:14 (8824): Status Report: Job Duration: '864000.000000'
2020-08-22 17:59:14 (8824): Status Report: Elapsed Time: '102000.000000'
2020-08-22 17:59:14 (8824): Status Report: CPU Time: '61345.375000'
2020-08-22 19:00:44 (8824): VM state change detected. (old = 'Running', new = 'Paused')
2020-08-22 19:11:33 (8824): VM state change detected. (old = 'Paused', new = 'Running')
2020-08-22 19:50:14 (8824): Status Report: Job Duration: '864000.000000'
2020-08-22 19:50:14 (8824): Status Report: Elapsed Time: '108000.000000'
2020-08-22 19:50:14 (8824): Status Report: CPU Time: '64959.484375'
2020-08-22 21:12:22 (8824): VM state change detected. (old = 'Running', new = 'Paused')
2020-08-22 22:59:41 (8824): VM state change detected. (old = 'Paused', new = 'Running')
2020-08-22 22:59:46 (8824): Guest Log: 31:24:35.264108 timesync vgsvcTimeSyncWorker: Radical host time change: 6 449 537 000 000ns (HostNow=1 598 129 986 538 000 000 ns HostLast=1 598 123 537 001 000 000 ns)

2020-08-22 22:59:56 (8824): Guest Log: 31:24:45.344418 timesync vgsvcTimeSyncWorker: Radical guest time change: 7 094 824 888 000ns (GuestNow=1 598 129 996 641 646 000 ns GuestLast=1 598 122 901 816 758 000 ns fSetTimeLastLoop=true )

2020-08-22 23:17:43 (8824): Status Report: Job Duration: '864000.000000'
2020-08-22 23:17:43 (8824): Status Report: Elapsed Time: '114000.000000'
2020-08-22 23:17:43 (8824): Status Report: CPU Time: '68567.593750'
2020-08-23 00:57:52 (8824): Status Report: Job Duration: '864000.000000'
2020-08-23 00:57:52 (8824): Status Report: Elapsed Time: '120000.000000'
2020-08-23 00:57:52 (8824): Status Report: CPU Time: '72179.515625'
2020-08-23 02:38:01 (8824): Status Report: Job Duration: '864000.000000'
2020-08-23 02:38:01 (8824): Status Report: Elapsed Time: '126000.000000'
2020-08-23 02:38:01 (8824): Status Report: CPU Time: '75791.281250'
2020-08-23 04:18:10 (8824): Status Report: Job Duration: '864000.000000'
2020-08-23 04:18:10 (8824): Status Report: Elapsed Time: '132000.000000'
2020-08-23 04:18:10 (8824): Status Report: CPU Time: '79403.359375'
2020-08-23 05:58:19 (8824): Status Report: Job Duration: '864000.000000'
2020-08-23 05:58:19 (8824): Status Report: Elapsed Time: '138000.000000'
2020-08-23 05:58:19 (8824): Status Report: CPU Time: '83014.859375'
2020-08-23 07:38:29 (8824): Status Report: Job Duration: '864000.000000'
2020-08-23 07:38:29 (8824): Status Report: Elapsed Time: '144000.000000'
2020-08-23 07:38:29 (8824): Status Report: CPU Time: '86627.218750'
2020-08-23 09:18:24 (8824): VM state change detected. (old = 'Running', new = 'Paused')
2020-08-23 10:22:21 (8824): VM state change detected. (old = 'Paused', new = 'Running')
2020-08-23 10:22:22 (8824): Guest Log: 41:43:13.840584 timesync vgsvcTimeSyncWorker: Radical host time change: 3 846 484 000 000ns (HostNow=1 598 170 941 586 000 000 ns HostLast=1 598 167 095 102 000 000 ns)

2020-08-23 10:22:32 (8824): Guest Log: 41:43:23.841514 timesync vgsvcTimeSyncWorker: Radical guest time change: 3 846 449 141 000ns (GuestNow=1 598 170 951 586 937 000 ns GuestLast=1 598 167 105 137 796 000 ns fSetTimeLastLoop=true )

2020-08-23 10:22:36 (8824): Status Report: Job Duration: '864000.000000'
2020-08-23 10:22:36 (8824): Status Report: Elapsed Time: '150000.000000'
2020-08-23 10:22:36 (8824): Status Report: CPU Time: '90239.171875'
2020-08-23 12:02:44 (8824): Status Report: Job Duration: '864000.000000'
2020-08-23 12:02:44 (8824): Status Report: Elapsed Time: '156000.000000'
2020-08-23 12:02:44 (8824): Status Report: CPU Time: '93850.265625'
2020-08-23 13:34:15 (8824): VM state change detected. (old = 'Running', new = 'Paused')
2020-08-23 13:38:35 (8824): VM state change detected. (old = 'Paused', new = 'Running')
2020-08-23 13:47:14 (8824): Status Report: Job Duration: '864000.000000'
2020-08-23 13:47:14 (8824): Status Report: Elapsed Time: '162000.000000'
2020-08-23 13:47:14 (8824): Status Report: CPU Time: '97462.812500'
2020-08-23 15:27:24 (8824): Status Report: Job Duration: '864000.000000'
2020-08-23 15:27:24 (8824): Status Report: Elapsed Time: '168000.000000'
2020-08-23 15:27:24 (8824): Status Report: CPU Time: '101074.421875'
2020-08-23 17:07:31 (8824): Status Report: Job Duration: '864000.000000'
2020-08-23 17:07:31 (8824): Status Report: Elapsed Time: '174000.000000'
2020-08-23 17:07:31 (8824): Status Report: CPU Time: '104685.953125'
2020-08-23 18:47:40 (8824): Status Report: Job Duration: '864000.000000'
2020-08-23 18:47:40 (8824): Status Report: Elapsed Time: '180000.000000'
2020-08-23 18:47:40 (8824): Status Report: CPU Time: '108297.765625'
2020-08-23 19:06:07 (8824): VM state change detected. (old = 'Running', new = 'Paused')
2020-08-23 19:38:33 (8824): VM state change detected. (old = 'Paused', new = 'Running')
2020-08-23 19:38:39 (8824): Guest Log: 50:22:56.078947 timesync vgsvcTimeSyncWorker: Radical host time change: 1 956 164 000 000ns (HostNow=1 598 204 319 454 000 000 ns HostLast=1 598 202 363 290 000 000 ns)

2020-08-23 19:38:49 (8824): Guest Log: 50:23:06.079636 timesync vgsvcTimeSyncWorker: Radical guest time change: 2 205 630 375 000ns (GuestNow=1 598 204 329 454 714 000 ns GuestLast=1 598 202 123 824 339 000 ns fSetTimeLastLoop=true )

2020-08-23 21:00:17 (8824): Status Report: Job Duration: '864000.000000'
2020-08-23 21:00:17 (8824): Status Report: Elapsed Time: '186000.000000'
2020-08-23 21:00:17 (8824): Status Report: CPU Time: '111909.703125'
2020-08-23 21:39:58 (8824): VM state change detected. (old = 'Running', new = 'Paused')
2020-08-23 21:55:55 (8824): VM state change detected. (old = 'Paused', new = 'Running')
2020-08-23 22:18:14 (8824): VM state change detected. (old = 'Running', new = 'Paused')
2020-08-28 19:03:27 (8824): VM state change detected. (old = 'Paused', new = 'Running')
2020-08-28 19:03:29 (8824): Guest Log: 52:46:38.091297 timesync vgsvcTimeSyncWorker: Radical host time change: 420 322 503 000 000ns (HostNow=1 598 634 208 464 000 000 ns HostLast=1 598 213 885 961 000 000 ns)

2020-08-28 19:03:39 (8824): Guest Log: 52:46:48.093309 timesync vgsvcTimeSyncWorker: Radical guest time change: 421 276 999 693 000ns (GuestNow=1 598 634 218 466 038 000 ns GuestLast=1 598 212 941 466 345 000 ns fSetTimeLastLoop=true )

2020-08-28 19:41:35 (8824): Status Report: Job Duration: '864000.000000'
2020-08-28 19:41:35 (8824): Status Report: Elapsed Time: '192000.000000'
2020-08-28 19:41:35 (8824): Status Report: CPU Time: '115520.156250'
2020-08-28 20:07:32 (8824): VM state change detected. (old = 'Running', new = 'Paused')
2020-08-31 09:43:33 (8824): VM state change detected. (old = 'Paused', new = 'Running')
2020-08-31 09:43:35 (8824): Guest Log: 53:50:43.793190 timesync vgsvcTimeSyncWorker: Radical host time change: 221 772 756 000 000ns (HostNow=1 598 859 815 919 000 000 ns HostLast=1 598 638 043 163 000 000 ns)

2020-08-31 09:43:46 (8824): Guest Log: 53:50:53.794796 timesync vgsvcTimeSyncWorker: Radical guest time change: 221 771 754 715 000ns (GuestNow=1 598 859 825 920 614 000 ns GuestLast=1 598 638 054 165 899 000 ns fSetTimeLastLoop=true )

2020-08-31 10:58:04 (8824): Status Report: Job Duration: '864000.000000'
2020-08-31 10:58:04 (8824): Status Report: Elapsed Time: '198000.528854'
2020-08-31 10:58:04 (8824): Status Report: CPU Time: '119118.296875'
2020-08-31 12:30:58 (8824): VM state change detected. (old = 'Running', new = 'Paused')
2020-08-31 12:31:04 (8824): Error in resume VM for VM: -2135228414
Command:
VBoxManage -q controlvm "boinc_adffd3673c1767c9" resume
Output:
VBoxManage.exe: error: VM is paused due to host power management
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

2020-08-31 19:49:18 (8824): Error in resume VM for VM: -2135228414
Command:
VBoxManage -q controlvm "boinc_adffd3673c1767c9" 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

2020-08-31 19:49:18 (8824): Guest Log: 56:38:06.658860 timesync vgsvcTimeSyncWorker: Radical host time change: 26 289 550 000 000ns (HostNow=1 598 896 138 284 000 000 ns HostLast=1 598 869 848 734 000 000 ns)

2020-08-31 19:49:18 (8824): Guest Log: 56:38:16.661410 timesync vgsvcTimeSyncWorker: Radical guest time change: 26 289 501 891 000ns (GuestNow=1 598 896 148 286 557 000 ns GuestLast=1 598 869 858 784 666 000 ns fSetTimeLastLoop=true )

2020-08-31 19:49:19 (8824): VM state change detected. (old = 'Paused', new = 'Running')
2020-08-31 19:55:33 (8824): VM state change detected. (old = 'Running', new = 'Paused')
2020-09-01 08:07:25 (8824): Error in resume VM for VM: -2135228414
Command:
VBoxManage -q controlvm "boinc_adffd3673c1767c9" 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

2020-09-01 08:07:26 (8824): Guest Log: 56:44:50.222534 timesync vgsvcTimeSyncWorker: Radical host time change: 43 908 310 000 000ns (HostNow=1 598 940 439 678 000 000 ns HostLast=1 598 896 531 368 000 000 ns)

2020-09-01 08:07:26 (8824): VM state change detected. (old = 'Paused', new = 'Running')
2020-09-01 08:07:30 (8824): Guest Log: 56:45:00.278709 timesync vgsvcTimeSyncWorker: Radical guest time change: 43 907 886 538 000ns (GuestNow=1 598 940 449 734 193 000 ns GuestLast=1 598 896 541 847 655 000 ns fSetTimeLastLoop=true )

2020-09-01 08:09:04 (8824): Status Report: Job Duration: '864000.000000'
2020-09-01 08:09:04 (8824): Status Report: Elapsed Time: '204001.508940'
2020-09-01 08:09:04 (8824): Status Report: CPU Time: '122721.187500'
2020-09-01 09:49:25 (8824): Status Report: Job Duration: '864000.000000'
2020-09-01 09:49:25 (8824): Status Report: Elapsed Time: '210001.508940'
2020-09-01 09:49:25 (8824): Status Report: CPU Time: '126330.328125'
2020-09-01 11:29:36 (8824): Status Report: Job Duration: '864000.000000'
2020-09-01 11:29:36 (8824): Status Report: Elapsed Time: '216001.508940'
2020-09-01 11:29:36 (8824): Status Report: CPU Time: '129941.250000'
2020-09-01 13:09:45 (8824): Status Report: Job Duration: '864000.000000'
2020-09-01 13:09:45 (8824): Status Report: Elapsed Time: '222001.508940'
2020-09-01 13:09:45 (8824): Status Report: CPU Time: '133552.562500'
2020-09-01 14:49:57 (8824): Status Report: Job Duration: '864000.000000'
2020-09-01 14:49:57 (8824): Status Report: Elapsed Time: '228001.508940'
2020-09-01 14:49:57 (8824): Status Report: CPU Time: '137165.703125'
2020-09-01 16:06:23 (8824): Guest Log: job: run exitcode=0

2020-09-01 16:06:23 (8824): Guest Log: job: diskusage=2072

2020-09-01 16:06:23 (8824): Guest Log: job: logsize=68 k

2020-09-01 16:06:23 (8824): Guest Log: job: times=

2020-09-01 16:06:23 (8824): Guest Log: 0m0.007s 0m0.008s

2020-09-01 16:06:23 (8824): Guest Log: 3847m10.391s 10m4.518s

2020-09-01 16:06:23 (8824): Guest Log: job: cpuusage=231435

2020-09-01 16:06:23 (8824): Guest Log: 16:06:22 CEST +02:00 2020-09-01: cranky: [INFO] Container 'runc' finished with status code 0.

2020-09-01 16:06:23 (8824): Guest Log: 16:06:22 CEST +02:00 2020-09-01: cranky: [INFO] Preparing output.

2020-09-01 16:06:24 (8824): Guest Log: [INFO] Job Finished

2020-09-01 16:06:24 (8824): Guest Log: [INFO] Shutting Down.

2020-09-01 16:06:24 (8824): VM Completion File Detected.
2020-09-01 16:06:24 (8824): VM Completion Message: Job Finished
.
2020-09-01 16:06:24 (8824): Powering off VM.
2020-09-01 16:11:24 (8824): VM did not power off when requested.
2020-09-01 16:11:24 (8824): VM was successfully terminated.
2020-09-01 16:11:24 (8824): Deregistering VM. (boinc_adffd3673c1767c9, slot#4)
2020-09-01 16:11:24 (8824): Removing network bandwidth throttle group from VM.
2020-09-01 16:11:25 (8824): Removing VM from VirtualBox.
16:11:30 (8824): called boinc_finish(0)

</stderr_txt>
]]>

©2024 CERN