Task 3197049

Name	CMS_1768674_1679805710.025320_0
Workunit	2290163
Created	26 Mar 2023, 4:41:53 UTC
Sent	27 Mar 2023, 7:07:13 UTC
Report deadline	3 Apr 2023, 7:07:13 UTC
Received	27 Mar 2023, 19:43:11 UTC
Server state	Over
Outcome	Success
Client state	Done
Exit status	0 (0x00000000)
Computer ID	4752
Run time	4 hours 39 min 55 sec
CPU time	4 hours 12 min 0 sec
Validate state	Valid
Credit	224.58
Device peak FLOPS	6.23 GFLOPS
Application version	CMS Simulation v60.70 (vbox64_mt_mcore_cms) windows_x86_64
Peak working set size	82.64 MB
Peak swap size	79.22 MB
Peak disk usage	1.60 GB
Stderr output

<core_client_version>7.22.0</core_client_version>
<![CDATA[
<stderr_txt>
2023-03-27 03:22:05 (7476): Detected: vboxwrapper 26206
2023-03-27 03:22:05 (7476): Detected: BOINC client v7.22.0
2023-03-27 03:22:06 (7476): Detected: VirtualBox VboxManage Interface (Version: 6.1.34)
2023-03-27 03:22:06 (7476): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2023-03-27 03:22:06 (7476): Successfully copied 'init_data.xml' to the shared directory.
2023-03-27 03:22:07 (7476): Create VM. (boinc_fea3032480e7c995, slot#4)
2023-03-27 03:22:07 (7476): Setting Memory Size for VM. (1896MB)
2023-03-27 03:22:07 (7476): Setting CPU Count for VM. (1)
2023-03-27 03:22:08 (7476): Setting Chipset Options for VM.
2023-03-27 03:22:08 (7476): Setting Graphics Controller Options for VM.
2023-03-27 03:22:08 (7476): Setting Boot Options for VM.
2023-03-27 03:22:08 (7476): Setting Network Configuration for NAT.
2023-03-27 03:22:09 (7476): Enabling VM Network Access.
2023-03-27 03:22:09 (7476): Disabling USB Support for VM.
2023-03-27 03:22:09 (7476): Disabling COM Port Support for VM.
2023-03-27 03:22:10 (7476): Disabling LPT Port Support for VM.
2023-03-27 03:22:10 (7476): Disabling Audio Support for VM.
2023-03-27 03:22:10 (7476): Disabling Clipboard Support for VM.
2023-03-27 03:22:10 (7476): Disabling Drag and Drop Support for VM.
2023-03-27 03:22:11 (7476): Adding storage controller(s) to VM.
2023-03-27 03:22:11 (7476): Adding virtual disk drive to VM. (CMS_2022_09_07.vdi)
2023-03-27 03:22:12 (7476): Adding VirtualBox Guest Additions to VM.
2023-03-27 03:22:12 (7476): Adding network bandwidth throttle group to VM. (Defaulting to 1024GB)
2023-03-27 03:22:13 (7476): forwarding host port 63860 to guest port 80
2023-03-27 03:22:13 (7476): Enabling remote desktop for VM.
2023-03-27 03:22:13 (7476): Required extension pack not installed, remote desktop not enabled.
2023-03-27 03:22:13 (7476): Enabling shared directory for VM.
2023-03-27 03:22:14 (7476): Starting VM using VBoxManage interface. (boinc_fea3032480e7c995, slot#4)
2023-03-27 03:22:17 (7476): Successfully started VM. (PID = '14904')
2023-03-27 03:22:17 (7476): Reporting VM Process ID to BOINC.
2023-03-27 03:22:17 (7476): Guest Log: BIOS: VirtualBox 6.1.34
2023-03-27 03:22:17 (7476): Guest Log: CPUID EDX: 0x178bfbff
2023-03-27 03:22:17 (7476): Guest Log: BIOS: No PCI IDE controller, not probing IDE
2023-03-27 03:22:17 (7476): Guest Log: BIOS: AHCI 0-P#0: PCHS=16383/16/63 LCHS=1024/255/63 0x0000000002800000 sectors
2023-03-27 03:22:17 (7476): VM state change detected. (old = 'poweredoff', new = 'running')
2023-03-27 03:22:17 (7476): Detected: Web Application Enabled (http://localhost:63860)
2023-03-27 03:22:18 (7476): VM state change detected. (old = 'running', new = 'paused')
2023-03-27 03:22:34 (7476): Preference change detected
2023-03-27 03:22:34 (7476): Setting CPU throttle for VM. (100%)
2023-03-27 03:22:34 (7476): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2023-03-27 03:22:34 (7476): VM state change detected. (old = 'paused', new = 'running')
2023-03-27 03:22:35 (7476): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032
2023-03-27 03:22:35 (7476): Guest Log: BIOS: Booting from Hard Disk...
2023-03-27 03:22:37 (7476): Guest Log: BIOS: KBD: unsupported int 16h function 03
2023-03-27 03:22:37 (7476): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 
2023-03-27 03:23:00 (7476): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds
2023-03-27 03:23:00 (7476): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000)
2023-03-27 03:23:01 (7476): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log
2023-03-27 03:23:01 (7476): Guest Log: 00:00:00.000161 main     Log opened 2023-03-27T07:23:01.779201000Z
2023-03-27 03:23:01 (7476): Guest Log: 00:00:00.000958 main     OS Product: Linux
2023-03-27 03:23:01 (7476): Guest Log: 00:00:00.001021 main     OS Release: 4.14.232-19.cernvm.x86_64
2023-03-27 03:23:01 (7476): Guest Log: 00:00:00.001062 main     OS Version: #1 SMP Fri Apr 30 17:12:25 CEST 2021
2023-03-27 03:23:01 (7476): Guest Log: 00:00:00.001097 main     Executable: /usr/sbin/VBoxService
2023-03-27 03:23:01 (7476): Guest Log: 00:00:00.001098 main     Process ID: 2161
2023-03-27 03:23:01 (7476): Guest Log: 00:00:00.001098 main     Package type: LINUX_64BITS_GENERIC
2023-03-27 03:23:01 (7476): Guest Log: 00:00:00.003329 main     5.2.6 r120293 started. Verbose level = 0
2023-03-27 03:23:12 (7476): Guest Log: [INFO] Mounting the shared directory
2023-03-27 03:23:12 (7476): Guest Log: [INFO] Shared directory mounted, enabling vboxmonitor
2023-03-27 03:23:13 (7476): Guest Log: [INFO] Sourcing essential functions from /cvmfs/grid.cern.ch
2023-03-27 03:23:13 (7476): Guest Log: [INFO] Testing connection to cern.ch
2023-03-27 03:23:13 (7476): Guest Log: [INFO] Testing connection to VCCS
2023-03-27 03:23:13 (7476): Guest Log: [INFO] Testing connection to HTCondor
2023-03-27 03:23:13 (7476): Guest Log: [INFO] Testing connection to WMAgent
2023-03-27 03:23:14 (7476): Guest Log: [INFO] Testing connection to EOSCMS
2023-03-27 03:23:14 (7476): Guest Log: [INFO] Testing connection to CMS-Factory
2023-03-27 03:23:14 (7476): Guest Log: [INFO] Testing connection to CMS-Frontier
2023-03-27 03:23:14 (7476): Guest Log: [INFO] Testing connection to Frontier
2023-03-27 03:23:15 (7476): Guest Log: [INFO] Could not find a local HTTP proxy
2023-03-27 03:23:15 (7476): Guest Log: [INFO] CVMFS and Frontier will have to use DIRECT connections
2023-03-27 03:23:15 (7476): Guest Log: [INFO] This makes the application less efficient
2023-03-27 03:23:15 (7476): Guest Log: [INFO] It also puts higher load on the project servers
2023-03-27 03:23:15 (7476): Guest Log: [INFO] Setting up a local HTTP proxy is highly recommended
2023-03-27 03:23:15 (7476): Guest Log: [INFO] Advice can be found in the project forum
2023-03-27 03:23:16 (7476): Guest Log: [INFO] Reloading and probing the CVMFS configuration
2023-03-27 03:23:21 (7476): Guest Log: [INFO] Probing /cvmfs/grid.cern.ch... OK
2023-03-27 03:23:24 (7476): Guest Log: [INFO] Probing /cvmfs/singularity.opensciencegrid.org... OK
2023-03-27 03:23:24 (7476): Guest Log: [INFO] Probing /cvmfs/oasis.opensciencegrid.org... OK
2023-03-27 03:23:25 (7476): Guest Log: [INFO] Probing /cvmfs/cms.cern.ch... OK
2023-03-27 03:23:25 (7476): Guest Log: [INFO] Probing /cvmfs/cms-ib.cern.ch... OK
2023-03-27 03:23:26 (7476): Guest Log: [INFO] Excerpt from "cvmfs_config stat": VERSION HOST PROXY
2023-03-27 03:23:26 (7476): Guest Log: [INFO] 2.7.2.0 http://s1fnal-cvmfs.openhtc.io:8080 DIRECT
2023-03-27 03:23:26 (7476): Guest Log: [INFO] Environment HTTP proxy: not set
2023-03-27 03:23:26 (7476): Guest Log: [INFO] Reading volunteer information
2023-03-27 03:23:30 (7476): Guest Log: [INFO] Requesting an X509 credential from LHC@home
2023-03-27 03:23:31 (7476): Guest Log: [INFO] Requesting an X509 credential from vLHC@home-dev
2023-03-27 03:23:32 (7476): Guest Log: [INFO] CMS application starting. Check log files.
2023-03-27 03:23:32 (7476): Guest Log: [INFO] Requesting an idtoken from LHC@home
2023-03-27 03:23:33 (7476): Guest Log: [INFO] Requesting an idtoken from vLHC@home-dev
2023-03-27 03:42:17 (7476): Stopping VM.
2023-03-27 03:42:24 (7476): Successfully stopped VM.
2023-03-27 03:43:30 (8796): Detected: vboxwrapper 26206
2023-03-27 03:43:30 (8796): Detected: BOINC client v7.22.0
2023-03-27 03:43:31 (8796): Detected: VirtualBox VboxManage Interface (Version: 6.1.34)
2023-03-27 03:43:32 (8796): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2023-03-27 03:43:32 (8796): Guest Log: BIOS: VirtualBox 6.1.34
2023-03-27 03:43:32 (8796): Guest Log: CPUID EDX: 0x178bfbff
2023-03-27 03:43:32 (8796): Guest Log: BIOS: No PCI IDE controller, not probing IDE
2023-03-27 03:43:32 (8796): Guest Log: BIOS: AHCI 0-P#0: PCHS=16383/16/63 LCHS=1024/255/63 0x0000000002800000 sectors
2023-03-27 03:43:32 (8796): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032
2023-03-27 03:43:32 (8796): Guest Log: BIOS: Booting from Hard Disk...
2023-03-27 03:43:32 (8796): Guest Log: BIOS: KBD: unsupported int 16h function 03
2023-03-27 03:43:32 (8796): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 
2023-03-27 03:43:32 (8796): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds
2023-03-27 03:43:32 (8796): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000)
2023-03-27 03:43:32 (8796): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log
2023-03-27 03:43:32 (8796): Guest Log: 00:00:00.000161 main     Log opened 2023-03-27T07:23:01.779201000Z
2023-03-27 03:43:32 (8796): Guest Log: 00:00:00.000958 main     OS Product: Linux
2023-03-27 03:43:32 (8796): Guest Log: 00:00:00.001021 main     OS Release: 4.14.232-19.cernvm.x86_64
2023-03-27 03:43:32 (8796): Guest Log: 00:00:00.001062 main     OS Version: #1 SMP Fri Apr 30 17:12:25 CEST 2021
2023-03-27 03:43:32 (8796): Guest Log: 00:00:00.001097 main     Executable: /usr/sbin/VBoxService
2023-03-27 03:43:32 (8796): Guest Log: 00:00:00.001098 main     Process ID: 2161
2023-03-27 03:43:32 (8796): Guest Log: 00:00:00.001098 main     Package type: LINUX_64BITS_GENERIC
2023-03-27 03:43:32 (8796): Guest Log: 00:00:00.003329 main     5.2.6 r120293 started. Verbose level = 0
2023-03-27 03:43:32 (8796): Guest Log: [INFO] Mounting the shared directory
2023-03-27 03:43:32 (8796): Guest Log: [INFO] Shared directory mounted, enabling vboxmonitor
2023-03-27 03:43:32 (8796): Guest Log: [INFO] Sourcing essential functions from /cvmfs/grid.cern.ch
2023-03-27 03:43:32 (8796): Guest Log: [INFO] Testing connection to cern.ch
2023-03-27 03:43:32 (8796): Guest Log: [INFO] Testing connection to VCCS
2023-03-27 03:43:32 (8796): Guest Log: [INFO] Testing connection to HTCondor
2023-03-27 03:43:32 (8796): Guest Log: [INFO] Testing connection to WMAgent
2023-03-27 03:43:32 (8796): Guest Log: [INFO] Testing connection to EOSCMS
2023-03-27 03:43:32 (8796): Guest Log: [INFO] Testing connection to CMS-Factory
2023-03-27 03:43:32 (8796): Guest Log: [INFO] Testing connection to CMS-Frontier
2023-03-27 03:43:32 (8796): Guest Log: [INFO] Testing connection to Frontier
2023-03-27 03:43:32 (8796): Guest Log: [INFO] Could not find a local HTTP proxy
2023-03-27 03:43:32 (8796): Guest Log: [INFO] CVMFS and Frontier will have to use DIRECT connections
2023-03-27 03:43:32 (8796): Guest Log: [INFO] This makes the application less efficient
2023-03-27 03:43:32 (8796): Guest Log: [INFO] It also puts higher load on the project servers
2023-03-27 03:43:32 (8796): Guest Log: [INFO] Setting up a local HTTP proxy is highly recommended
2023-03-27 03:43:32 (8796): Guest Log: [INFO] Advice can be found in the project forum
2023-03-27 03:43:32 (8796): Guest Log: [INFO] Reloading and probing the CVMFS configuration
2023-03-27 03:43:32 (8796): Guest Log: [INFO] Probing /cvmfs/grid.cern.ch... OK
2023-03-27 03:43:32 (8796): Guest Log: [INFO] Probing /cvmfs/singularity.opensciencegrid.org... OK
2023-03-27 03:43:32 (8796): Guest Log: [INFO] Probing /cvmfs/oasis.opensciencegrid.org... OK
2023-03-27 03:43:32 (8796): Guest Log: [INFO] Probing /cvmfs/cms.cern.ch... OK
2023-03-27 03:43:32 (8796): Guest Log: [INFO] Probing /cvmfs/cms-ib.cern.ch... OK
2023-03-27 03:43:32 (8796): Guest Log: [INFO] Excerpt from "cvmfs_config stat": VERSION HOST PROXY
2023-03-27 03:43:32 (8796): Guest Log: [INFO] 2.7.2.0 http://s1fnal-cvmfs.openhtc.io:8080 DIRECT
2023-03-27 03:43:32 (8796): Guest Log: [INFO] Environment HTTP proxy: not set
2023-03-27 03:43:32 (8796): Guest Log: [INFO] Reading volunteer information
2023-03-27 03:43:32 (8796): Guest Log: [INFO] Requesting an X509 credential from LHC@home
2023-03-27 03:43:32 (8796): Guest Log: [INFO] Requesting an X509 credential from vLHC@home-dev
2023-03-27 03:43:32 (8796): Guest Log: [INFO] CMS application starting. Check log files.
2023-03-27 03:43:32 (8796): Guest Log: [INFO] Requesting an idtoken from LHC@home
2023-03-27 03:43:32 (8796): Guest Log: [INFO] Requesting an idtoken from vLHC@home-dev
2023-03-27 03:43:32 (8796): Starting VM using VBoxManage interface. (boinc_fea3032480e7c995, slot#4)
2023-03-27 03:43:41 (8796): Successfully started VM. (PID = '17624')
2023-03-27 03:43:41 (8796): Reporting VM Process ID to BOINC.
2023-03-27 03:43:41 (8796): VM state change detected. (old = 'poweredoff', new = 'running')
2023-03-27 03:43:41 (8796): Detected: Web Application Enabled (http://localhost:63860)
2023-03-27 03:43:41 (8796): Preference change detected
2023-03-27 03:43:41 (8796): Setting CPU throttle for VM. (100%)
2023-03-27 03:43:41 (8796): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2023-03-27 03:53:35 (8796): Stopping VM.
2023-03-27 03:53:45 (8796): Successfully stopped VM.
2023-03-27 03:55:54 (14296): Detected: vboxwrapper 26206
2023-03-27 03:55:54 (14296): Detected: BOINC client v7.22.0
2023-03-27 03:55:55 (14296): Detected: VirtualBox VboxManage Interface (Version: 6.1.34)
2023-03-27 03:55:55 (14296): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2023-03-27 03:55:55 (14296): Starting VM using VBoxManage interface. (boinc_fea3032480e7c995, slot#4)
2023-03-27 03:56:05 (14296): Successfully started VM. (PID = '6072')
2023-03-27 03:56:05 (14296): Reporting VM Process ID to BOINC.
2023-03-27 03:56:05 (14296): VM state change detected. (old = 'poweredoff', new = 'running')
2023-03-27 03:56:05 (14296): Detected: Web Application Enabled (http://localhost:63860)
2023-03-27 03:56:05 (14296): Stopping VM.
2023-03-27 03:56:10 (14296): Successfully stopped VM.
2023-03-27 04:01:38 (10816): Detected: vboxwrapper 26206
2023-03-27 04:01:38 (10816): Detected: BOINC client v7.22.0
2023-03-27 04:01:39 (10816): Detected: VirtualBox VboxManage Interface (Version: 6.1.34)
2023-03-27 04:01:39 (10816): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2023-03-27 04:01:39 (10816): Starting VM using VBoxManage interface. (boinc_fea3032480e7c995, slot#4)
2023-03-27 04:01:48 (10816): Successfully started VM. (PID = '9232')
2023-03-27 04:01:48 (10816): Reporting VM Process ID to BOINC.
2023-03-27 04:01:48 (10816): VM state change detected. (old = 'poweredoff', new = 'running')
2023-03-27 04:01:48 (10816): Detected: Web Application Enabled (http://localhost:63860)
2023-03-27 04:01:48 (10816): Preference change detected
2023-03-27 04:01:48 (10816): Setting CPU throttle for VM. (100%)
2023-03-27 04:01:48 (10816): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2023-03-27 04:05:55 (10816): Stopping VM.
2023-03-27 04:06:02 (10816): Successfully stopped VM.
2023-03-27 04:08:11 (20708): Detected: vboxwrapper 26206
2023-03-27 04:08:11 (20708): Detected: BOINC client v7.22.0
2023-03-27 04:08:12 (20708): Detected: VirtualBox VboxManage Interface (Version: 6.1.34)
2023-03-27 04:08:12 (20708): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2023-03-27 04:08:12 (20708): Starting VM using VBoxManage interface. (boinc_fea3032480e7c995, slot#4)
2023-03-27 04:08:21 (20708): Successfully started VM. (PID = '15532')
2023-03-27 04:08:21 (20708): Reporting VM Process ID to BOINC.
2023-03-27 04:08:21 (20708): VM state change detected. (old = 'poweredoff', new = 'running')
2023-03-27 04:08:21 (20708): Detected: Web Application Enabled (http://localhost:63860)
2023-03-27 04:08:21 (20708): Preference change detected
2023-03-27 04:08:21 (20708): Setting CPU throttle for VM. (100%)
2023-03-27 04:08:21 (20708): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2023-03-27 04:10:03 (20708): Stopping VM.
2023-03-27 04:10:09 (20708): Successfully stopped VM.
2023-03-27 04:47:00 (17764): Detected: vboxwrapper 26206
2023-03-27 04:47:00 (17764): Detected: BOINC client v7.22.0
2023-03-27 04:47:01 (17764): Detected: VirtualBox VboxManage Interface (Version: 6.1.34)
2023-03-27 04:47:01 (17764): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2023-03-27 04:47:02 (17764): Starting VM using VBoxManage interface. (boinc_fea3032480e7c995, slot#4)
2023-03-27 04:47:11 (17764): Successfully started VM. (PID = '21368')
2023-03-27 04:47:11 (17764): Reporting VM Process ID to BOINC.
2023-03-27 04:47:11 (17764): VM state change detected. (old = 'poweredoff', new = 'running')
2023-03-27 04:47:11 (17764): Detected: Web Application Enabled (http://localhost:63860)
2023-03-27 04:47:11 (17764): Stopping VM.
2023-03-27 04:47:17 (17764): Successfully stopped VM.
2023-03-27 04:48:06 (6636): Detected: vboxwrapper 26206
2023-03-27 04:48:06 (6636): Detected: BOINC client v7.22.0
2023-03-27 04:48:07 (6636): Detected: VirtualBox VboxManage Interface (Version: 6.1.34)
2023-03-27 04:48:07 (6636): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2023-03-27 04:48:08 (6636): ERROR: VM failed to start
2023-03-27 04:48:13 (6636): 
   BOINC will be notified that it needs to clean up the environment.
         This is a temporary problem and so this job will be rescheduled for another time.

2023-03-27 05:49:48 (19364): Detected: vboxwrapper 26206
2023-03-27 05:49:48 (19364): Detected: BOINC client v7.22.0
2023-03-27 05:49:49 (19364): Detected: VirtualBox VboxManage Interface (Version: 6.1.34)
2023-03-27 05:49:49 (19364): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2023-03-27 05:49:49 (19364): Starting VM using VBoxManage interface. (boinc_fea3032480e7c995, slot#4)
2023-03-27 05:50:00 (19364): Successfully started VM. (PID = '10548')
2023-03-27 05:50:00 (19364): Reporting VM Process ID to BOINC.
2023-03-27 05:50:00 (19364): VM state change detected. (old = 'poweredoff', new = 'running')
2023-03-27 05:50:00 (19364): Detected: Web Application Enabled (http://localhost:63860)
2023-03-27 05:50:00 (19364): Preference change detected
2023-03-27 05:50:00 (19364): Setting CPU throttle for VM. (100%)
2023-03-27 05:50:00 (19364): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2023-03-27 05:50:06 (19364): Guest Log: 00:35:12.281849 timesync vgsvcTimeSyncWorker: Radical host time change: 5 996 661 000 000ns (HostNow=1 679 910 591 232 000 000 ns HostLast=1 679 904 594 571 000 000 ns)
2023-03-27 05:50:16 (19364): Guest Log: 00:35:22.302829 timesync vgsvcTimeSyncWorker: Radical guest time change: 6 040 482 008 000ns (GuestNow=1 679 910 601 259 163 000 ns GuestLast=1 679 904 560 777 155 000 ns fSetTimeLastLoop=true )
2023-03-27 05:55:39 (19364): VM state change detected. (old = 'running', new = 'paused')
2023-03-27 05:55:59 (19364): VM state change detected. (old = 'paused', new = 'running')
2023-03-27 06:00:39 (19364): VM state change detected. (old = 'running', new = 'paused')
2023-03-27 06:00:59 (19364): VM state change detected. (old = 'paused', new = 'running')
2023-03-27 06:01:15 (19364): Stopping VM.
2023-03-27 06:01:21 (19364): Successfully stopped VM.
2023-03-27 06:03:18 (20348): Detected: vboxwrapper 26206
2023-03-27 06:03:18 (20348): Detected: BOINC client v7.22.0
2023-03-27 06:03:19 (20348): Detected: VirtualBox VboxManage Interface (Version: 6.1.34)
2023-03-27 06:03:19 (20348): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2023-03-27 06:03:19 (20348): Guest Log: 00:35:12.281849 timesync vgsvcTimeSyncWorker: Radical host time change: 5 996 661 000 000ns (HostNow=1 679 910 591 232 000 000 ns HostLast=1 679 904 594 571 000 000 ns)
2023-03-27 06:03:19 (20348): Guest Log: 00:35:22.302829 timesync vgsvcTimeSyncWorker: Radical guest time change: 6 040 482 008 000ns (GuestNow=1 679 910 601 259 163 000 ns GuestLast=1 679 904 560 777 155 000 ns fSetTimeLastLoop=true )
2023-03-27 06:03:19 (20348): Starting VM using VBoxManage interface. (boinc_fea3032480e7c995, slot#4)
2023-03-27 06:03:28 (20348): Successfully started VM. (PID = '988')
2023-03-27 06:03:28 (20348): Reporting VM Process ID to BOINC.
2023-03-27 06:03:28 (20348): VM state change detected. (old = 'poweredoff', new = 'running')
2023-03-27 06:03:28 (20348): Detected: Web Application Enabled (http://localhost:63860)
2023-03-27 06:03:28 (20348): Preference change detected
2023-03-27 06:03:28 (20348): Setting CPU throttle for VM. (100%)
2023-03-27 06:03:28 (20348): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2023-03-27 06:03:41 (20348): Stopping VM.
2023-03-27 06:03:47 (20348): Successfully stopped VM.
2023-03-27 06:04:42 (10184): Detected: vboxwrapper 26206
2023-03-27 06:04:42 (10184): Detected: BOINC client v7.22.0
2023-03-27 06:04:43 (10184): Detected: VirtualBox VboxManage Interface (Version: 6.1.34)
2023-03-27 06:04:43 (10184): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2023-03-27 06:04:43 (10184): Starting VM using VBoxManage interface. (boinc_fea3032480e7c995, slot#4)
2023-03-27 06:04:52 (10184): Successfully started VM. (PID = '11124')
2023-03-27 06:04:52 (10184): Reporting VM Process ID to BOINC.
2023-03-27 06:04:52 (10184): VM state change detected. (old = 'poweredoff', new = 'running')
2023-03-27 06:04:52 (10184): Detected: Web Application Enabled (http://localhost:63860)
2023-03-27 06:04:52 (10184): Preference change detected
2023-03-27 06:04:52 (10184): Setting CPU throttle for VM. (100%)
2023-03-27 06:04:52 (10184): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2023-03-27 06:08:03 (10184): Stopping VM.
2023-03-27 06:08:09 (10184): Successfully stopped VM.
2023-03-27 06:09:44 (14944): Detected: vboxwrapper 26206
2023-03-27 06:09:44 (14944): Detected: BOINC client v7.22.0
2023-03-27 06:09:45 (14944): Detected: VirtualBox VboxManage Interface (Version: 6.1.34)
2023-03-27 06:09:45 (14944): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2023-03-27 06:09:46 (14944): Starting VM using VBoxManage interface. (boinc_fea3032480e7c995, slot#4)
2023-03-27 06:09:54 (14944): Successfully started VM. (PID = '15860')
2023-03-27 06:09:54 (14944): Reporting VM Process ID to BOINC.
2023-03-27 06:09:54 (14944): VM state change detected. (old = 'poweredoff', new = 'running')
2023-03-27 06:09:54 (14944): Detected: Web Application Enabled (http://localhost:63860)
2023-03-27 06:09:54 (14944): Preference change detected
2023-03-27 06:09:54 (14944): Setting CPU throttle for VM. (100%)
2023-03-27 06:09:55 (14944): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2023-03-27 06:12:17 (14944): Stopping VM.
2023-03-27 06:12:23 (14944): Successfully stopped VM.
2023-03-27 06:14:19 (14252): Detected: vboxwrapper 26206
2023-03-27 06:14:19 (14252): Detected: BOINC client v7.22.0
2023-03-27 06:14:20 (14252): Detected: VirtualBox VboxManage Interface (Version: 6.1.34)
2023-03-27 06:14:20 (14252): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2023-03-27 06:14:21 (14252): Starting VM using VBoxManage interface. (boinc_fea3032480e7c995, slot#4)
2023-03-27 06:14:29 (14252): Successfully started VM. (PID = '20804')
2023-03-27 06:14:29 (14252): Reporting VM Process ID to BOINC.
2023-03-27 06:14:29 (14252): VM state change detected. (old = 'poweredoff', new = 'running')
2023-03-27 06:14:29 (14252): Detected: Web Application Enabled (http://localhost:63860)
2023-03-27 06:14:29 (14252): Preference change detected
2023-03-27 06:14:29 (14252): Setting CPU throttle for VM. (100%)
2023-03-27 06:14:29 (14252): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2023-03-27 06:15:15 (14252): Stopping VM.
2023-03-27 06:15:22 (14252): Successfully stopped VM.
2023-03-27 06:20:29 (14500): Detected: vboxwrapper 26206
2023-03-27 06:20:29 (14500): Detected: BOINC client v7.22.0
2023-03-27 06:20:30 (14500): Detected: VirtualBox VboxManage Interface (Version: 6.1.34)
2023-03-27 06:20:30 (14500): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2023-03-27 06:20:31 (14500): Starting VM using VBoxManage interface. (boinc_fea3032480e7c995, slot#4)
2023-03-27 06:20:39 (14500): Successfully started VM. (PID = '16880')
2023-03-27 06:20:39 (14500): Reporting VM Process ID to BOINC.
2023-03-27 06:20:39 (14500): VM state change detected. (old = 'poweredoff', new = 'running')
2023-03-27 06:20:39 (14500): Detected: Web Application Enabled (http://localhost:63860)
2023-03-27 06:20:39 (14500): Preference change detected
2023-03-27 06:20:39 (14500): Setting CPU throttle for VM. (100%)
2023-03-27 06:20:39 (14500): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2023-03-27 06:22:40 (14500): VM state change detected. (old = 'running', new = 'paused')
2023-03-27 06:23:00 (14500): VM state change detected. (old = 'paused', new = 'running')
2023-03-27 06:25:19 (14500): Stopping VM.
2023-03-27 06:25:25 (14500): Successfully stopped VM.
2023-03-27 06:42:21 (20320): Detected: vboxwrapper 26206
2023-03-27 06:42:21 (20320): Detected: BOINC client v7.22.0
2023-03-27 06:42:21 (20320): Detected: VirtualBox VboxManage Interface (Version: 6.1.34)
2023-03-27 06:42:22 (20320): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2023-03-27 06:42:22 (20320): Starting VM using VBoxManage interface. (boinc_fea3032480e7c995, slot#4)
2023-03-27 06:42:32 (20320): Successfully started VM. (PID = '17964')
2023-03-27 06:42:32 (20320): Reporting VM Process ID to BOINC.
2023-03-27 06:42:32 (20320): VM state change detected. (old = 'poweredoff', new = 'running')
2023-03-27 06:42:32 (20320): Detected: Web Application Enabled (http://localhost:63860)
2023-03-27 06:42:32 (20320): Preference change detected
2023-03-27 06:42:32 (20320): Setting CPU throttle for VM. (100%)
2023-03-27 06:42:32 (20320): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2023-03-27 06:43:33 (20320): Stopping VM.
2023-03-27 06:43:41 (20320): Successfully stopped VM.
2023-03-27 06:52:24 (8312): Detected: vboxwrapper 26206
2023-03-27 06:52:24 (8312): Detected: BOINC client v7.22.0
2023-03-27 06:52:25 (8312): Detected: VirtualBox VboxManage Interface (Version: 6.1.34)
2023-03-27 06:52:25 (8312): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2023-03-27 06:52:26 (8312): Starting VM using VBoxManage interface. (boinc_fea3032480e7c995, slot#4)
2023-03-27 06:52:35 (8312): Successfully started VM. (PID = '16920')
2023-03-27 06:52:35 (8312): Reporting VM Process ID to BOINC.
2023-03-27 06:52:35 (8312): VM state change detected. (old = 'poweredoff', new = 'running')
2023-03-27 06:52:35 (8312): Detected: Web Application Enabled (http://localhost:63860)
2023-03-27 06:52:35 (8312): Preference change detected
2023-03-27 06:52:35 (8312): Setting CPU throttle for VM. (100%)
2023-03-27 06:52:35 (8312): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2023-03-27 06:52:36 (8312): Stopping VM.
2023-03-27 06:52:43 (8312): Successfully stopped VM.
2023-03-27 08:33:09 (14520): Detected: vboxwrapper 26206
2023-03-27 08:33:09 (14520): Detected: BOINC client v7.22.0
2023-03-27 08:33:10 (14520): Detected: VirtualBox VboxManage Interface (Version: 6.1.34)
2023-03-27 08:33:11 (14520): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2023-03-27 08:33:11 (14520): Starting VM using VBoxManage interface. (boinc_fea3032480e7c995, slot#4)
2023-03-27 08:33:21 (14520): Successfully started VM. (PID = '16032')
2023-03-27 08:33:21 (14520): Reporting VM Process ID to BOINC.
2023-03-27 08:33:21 (14520): VM state change detected. (old = 'poweredoff', new = 'running')
2023-03-27 08:33:21 (14520): Detected: Web Application Enabled (http://localhost:63860)
2023-03-27 08:33:21 (14520): Preference change detected
2023-03-27 08:33:21 (14520): Setting CPU throttle for VM. (100%)
2023-03-27 08:33:22 (14520): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2023-03-27 08:33:29 (14520): Guest Log: 00:57:53.059399 timesync vgsvcTimeSyncWorker: Radical host time change: 6 053 762 000 000ns (HostNow=1 679 920 388 112 000 000 ns HostLast=1 679 914 334 350 000 000 ns)
2023-03-27 08:33:39 (14520): Guest Log: 00:58:03.061657 timesync vgsvcTimeSyncWorker: Radical guest time change: 6 053 770 062 000ns (GuestNow=1 679 920 398 120 234 000 ns GuestLast=1 679 914 344 350 172 000 ns fSetTimeLastLoop=true )
2023-03-27 08:38:02 (14520): VM state change detected. (old = 'running', new = 'paused')
2023-03-27 08:38:22 (14520): VM state change detected. (old = 'paused', new = 'running')
2023-03-27 08:38:32 (14520): Stopping VM.
2023-03-27 08:38:38 (14520): Successfully stopped VM.
2023-03-27 08:42:17 (14804): Detected: vboxwrapper 26206
2023-03-27 08:42:17 (14804): Detected: BOINC client v7.22.0
2023-03-27 08:42:17 (14804): Detected: VirtualBox VboxManage Interface (Version: 6.1.34)
2023-03-27 08:42:18 (14804): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2023-03-27 08:42:18 (14804): Guest Log: 00:57:53.059399 timesync vgsvcTimeSyncWorker: Radical host time change: 6 053 762 000 000ns (HostNow=1 679 920 388 112 000 000 ns HostLast=1 679 914 334 350 000 000 ns)
2023-03-27 08:42:18 (14804): Guest Log: 00:58:03.061657 timesync vgsvcTimeSyncWorker: Radical guest time change: 6 053 770 062 000ns (GuestNow=1 679 920 398 120 234 000 ns GuestLast=1 679 914 344 350 172 000 ns fSetTimeLastLoop=true )
2023-03-27 08:42:18 (14804): Starting VM using VBoxManage interface. (boinc_fea3032480e7c995, slot#4)
2023-03-27 08:42:27 (14804): Successfully started VM. (PID = '21292')
2023-03-27 08:42:27 (14804): Reporting VM Process ID to BOINC.
2023-03-27 08:42:27 (14804): VM state change detected. (old = 'poweredoff', new = 'running')
2023-03-27 08:42:27 (14804): Detected: Web Application Enabled (http://localhost:63860)
2023-03-27 08:42:27 (14804): Preference change detected
2023-03-27 08:42:27 (14804): Setting CPU throttle for VM. (100%)
2023-03-27 08:42:27 (14804): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2023-03-27 08:53:59 (14804): Stopping VM.
2023-03-27 08:54:06 (14804): Successfully stopped VM.
2023-03-27 08:55:15 (15132): Detected: vboxwrapper 26206
2023-03-27 08:55:15 (15132): Detected: BOINC client v7.22.0
2023-03-27 08:55:16 (15132): Detected: VirtualBox VboxManage Interface (Version: 6.1.34)
2023-03-27 08:55:16 (15132): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2023-03-27 08:55:17 (15132): Starting VM using VBoxManage interface. (boinc_fea3032480e7c995, slot#4)
2023-03-27 08:55:27 (15132): Successfully started VM. (PID = '19728')
2023-03-27 08:55:27 (15132): Reporting VM Process ID to BOINC.
2023-03-27 08:55:27 (15132): VM state change detected. (old = 'poweredoff', new = 'running')
2023-03-27 08:55:27 (15132): Detected: Web Application Enabled (http://localhost:63860)
2023-03-27 08:55:27 (15132): Preference change detected
2023-03-27 08:55:27 (15132): Setting CPU throttle for VM. (100%)
2023-03-27 08:55:27 (15132): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2023-03-27 09:02:08 (15132): Stopping VM.
2023-03-27 09:02:19 (15132): Successfully stopped VM.
2023-03-27 09:03:30 (18456): Detected: vboxwrapper 26206
2023-03-27 09:03:30 (18456): Detected: BOINC client v7.22.0
2023-03-27 09:03:30 (18456): Detected: VirtualBox VboxManage Interface (Version: 6.1.34)
2023-03-27 09:03:31 (18456): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2023-03-27 09:03:31 (18456): Starting VM using VBoxManage interface. (boinc_fea3032480e7c995, slot#4)
2023-03-27 09:03:40 (18456): Successfully started VM. (PID = '6424')
2023-03-27 09:03:40 (18456): Reporting VM Process ID to BOINC.
2023-03-27 09:03:40 (18456): VM state change detected. (old = 'poweredoff', new = 'running')
2023-03-27 09:03:40 (18456): Detected: Web Application Enabled (http://localhost:63860)
2023-03-27 09:03:40 (18456): Preference change detected
2023-03-27 09:03:40 (18456): Setting CPU throttle for VM. (100%)
2023-03-27 09:03:41 (18456): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2023-03-27 09:23:02 (18456): Status Report: Job Duration: '64800.000000'
2023-03-27 09:23:02 (18456): Status Report: Elapsed Time: '6000.000000'
2023-03-27 09:23:02 (18456): Status Report: CPU Time: '5112.937500'
2023-03-27 09:34:04 (18456): Stopping VM.
2023-03-27 09:34:10 (18456): Successfully stopped VM.
2023-03-27 10:55:30 (18924): Detected: vboxwrapper 26206
2023-03-27 10:55:30 (18924): Detected: BOINC client v7.22.0
2023-03-27 10:55:30 (18924): Detected: VirtualBox VboxManage Interface (Version: 6.1.34)
2023-03-27 10:55:30 (18924): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2023-03-27 10:55:31 (18924): Starting VM using VBoxManage interface. (boinc_fea3032480e7c995, slot#4)
2023-03-27 10:55:42 (18924): Successfully started VM. (PID = '19484')
2023-03-27 10:55:42 (18924): Reporting VM Process ID to BOINC.
2023-03-27 10:55:42 (18924): VM state change detected. (old = 'poweredoff', new = 'running')
2023-03-27 10:55:42 (18924): Detected: Web Application Enabled (http://localhost:63860)
2023-03-27 10:55:42 (18924): Status Report: Job Duration: '64800.000000'
2023-03-27 10:55:42 (18924): Status Report: Elapsed Time: '6654.000000'
2023-03-27 10:55:42 (18924): Status Report: CPU Time: '5793.984375'
2023-03-27 10:55:42 (18924): Preference change detected
2023-03-27 10:55:42 (18924): Setting CPU throttle for VM. (100%)
2023-03-27 10:55:43 (18924): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2023-03-27 10:55:52 (18924): Guest Log: 01:51:27.007000 timesync vgsvcTimeSyncWorker: Radical host time change: 4 959 278 000 000ns (HostNow=1 679 928 948 557 000 000 ns HostLast=1 679 923 989 279 000 000 ns)
2023-03-27 10:56:02 (18924): Guest Log: 01:51:37.011962 timesync vgsvcTimeSyncWorker: Radical guest time change: 4 926 812 040 000ns (GuestNow=1 679 928 958 575 081 000 ns GuestLast=1 679 924 031 763 041 000 ns fSetTimeLastLoop=true )
2023-03-27 10:58:10 (18924): VM state change detected. (old = 'running', new = 'paused')
2023-03-27 10:58:30 (18924): VM state change detected. (old = 'paused', new = 'running')
2023-03-27 11:04:16 (18924): VM state change detected. (old = 'running', new = 'paused')
2023-03-27 11:04:37 (18924): VM state change detected. (old = 'paused', new = 'running')
2023-03-27 11:10:00 (18924): VM state change detected. (old = 'running', new = 'paused')
2023-03-27 11:10:20 (18924): VM state change detected. (old = 'paused', new = 'running')
2023-03-27 11:20:33 (18924): VM state change detected. (old = 'running', new = 'paused')
2023-03-27 11:20:53 (18924): VM state change detected. (old = 'paused', new = 'running')
2023-03-27 11:29:34 (18924): VM state change detected. (old = 'running', new = 'paused')
2023-03-27 11:29:54 (18924): VM state change detected. (old = 'paused', new = 'running')
2023-03-27 11:35:45 (18924): VM state change detected. (old = 'running', new = 'paused')
2023-03-27 11:36:05 (18924): VM state change detected. (old = 'paused', new = 'running')
2023-03-27 11:40:48 (18924): VM state change detected. (old = 'running', new = 'paused')
2023-03-27 11:41:08 (18924): VM state change detected. (old = 'paused', new = 'running')
2023-03-27 11:52:46 (18924): VM state change detected. (old = 'running', new = 'paused')
2023-03-27 11:53:06 (18924): VM state change detected. (old = 'paused', new = 'running')
2023-03-27 11:56:07 (18924): Stopping VM.
2023-03-27 11:56:15 (18924): Successfully stopped VM.
2023-03-27 12:36:20 (21100): Detected: vboxwrapper 26206
2023-03-27 12:36:20 (21100): Detected: BOINC client v7.22.0
2023-03-27 12:36:20 (21100): Detected: VirtualBox VboxManage Interface (Version: 6.1.34)
2023-03-27 12:36:20 (21100): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2023-03-27 12:36:21 (21100): Guest Log: 01:51:27.007000 timesync vgsvcTimeSyncWorker: Radical host time change: 4 959 278 000 000ns (HostNow=1 679 928 948 557 000 000 ns HostLast=1 679 923 989 279 000 000 ns)
2023-03-27 12:36:21 (21100): Guest Log: 01:51:37.011962 timesync vgsvcTimeSyncWorker: Radical guest time change: 4 926 812 040 000ns (GuestNow=1 679 928 958 575 081 000 ns GuestLast=1 679 924 031 763 041 000 ns fSetTimeLastLoop=true )
2023-03-27 12:36:21 (21100): Starting VM using VBoxManage interface. (boinc_fea3032480e7c995, slot#4)
2023-03-27 12:36:32 (21100): Successfully started VM. (PID = '19776')
2023-03-27 12:36:32 (21100): Reporting VM Process ID to BOINC.
2023-03-27 12:36:32 (21100): VM state change detected. (old = 'poweredoff', new = 'running')
2023-03-27 12:36:32 (21100): Detected: Web Application Enabled (http://localhost:63860)
2023-03-27 12:36:32 (21100): Status Report: Job Duration: '64800.000000'
2023-03-27 12:36:32 (21100): Status Report: Elapsed Time: '10014.248915'
2023-03-27 12:36:32 (21100): Status Report: CPU Time: '9505.125000'
2023-03-27 12:36:32 (21100): Preference change detected
2023-03-27 12:36:32 (21100): Setting CPU throttle for VM. (100%)
2023-03-27 12:36:32 (21100): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2023-03-27 12:36:38 (21100): Guest Log: 02:49:11.199336 timesync vgsvcTimeSyncWorker: Radical host time change: 2 425 428 000 000ns (HostNow=1 679 934 951 813 000 000 ns HostLast=1 679 932 526 385 000 000 ns)
2023-03-27 12:36:48 (21100): Guest Log: 02:49:21.203560 timesync vgsvcTimeSyncWorker: Radical guest time change: 2 549 054 770 000ns (GuestNow=1 679 934 961 817 219 000 ns GuestLast=1 679 932 412 762 449 000 ns fSetTimeLastLoop=true )
2023-03-27 12:44:15 (21100): VM state change detected. (old = 'running', new = 'paused')
2023-03-27 12:44:35 (21100): VM state change detected. (old = 'paused', new = 'running')
2023-03-27 12:50:16 (21100): VM state change detected. (old = 'running', new = 'paused')
2023-03-27 12:50:36 (21100): VM state change detected. (old = 'paused', new = 'running')
2023-03-27 12:58:57 (21100): VM state change detected. (old = 'running', new = 'paused')
2023-03-27 12:59:17 (21100): VM state change detected. (old = 'paused', new = 'running')
2023-03-27 13:05:39 (21100): VM state change detected. (old = 'running', new = 'paused')
2023-03-27 13:05:59 (21100): VM state change detected. (old = 'paused', new = 'running')
2023-03-27 13:16:45 (21100): VM state change detected. (old = 'running', new = 'paused')
2023-03-27 13:17:14 (21100): VM state change detected. (old = 'paused', new = 'running')
2023-03-27 13:33:50 (21100): VM state change detected. (old = 'running', new = 'paused')
2023-03-27 13:34:14 (21100): VM state change detected. (old = 'paused', new = 'running')
2023-03-27 13:37:16 (21100): Stopping VM.
2023-03-27 13:37:23 (21100): Successfully stopped VM.
2023-03-27 14:05:48 (21712): Detected: vboxwrapper 26206
2023-03-27 14:05:49 (21712): Detected: BOINC client v7.22.0
2023-03-27 14:05:52 (21712): Detected: VirtualBox VboxManage Interface (Version: 6.1.34)
2023-03-27 14:05:54 (21712): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2023-03-27 14:05:57 (21712): Guest Log: 02:49:11.199336 timesync vgsvcTimeSyncWorker: Radical host time change: 2 425 428 000 000ns (HostNow=1 679 934 951 813 000 000 ns HostLast=1 679 932 526 385 000 000 ns)
2023-03-27 14:05:57 (21712): Guest Log: 02:49:21.203560 timesync vgsvcTimeSyncWorker: Radical guest time change: 2 549 054 770 000ns (GuestNow=1 679 934 961 817 219 000 ns GuestLast=1 679 932 412 762 449 000 ns fSetTimeLastLoop=true )
2023-03-27 14:05:57 (21712): Starting VM using VBoxManage interface. (boinc_fea3032480e7c995, slot#4)
2023-03-27 14:06:08 (21712): Successfully started VM. (PID = '19440')
2023-03-27 14:06:08 (21712): Reporting VM Process ID to BOINC.
2023-03-27 14:06:08 (21712): VM state change detected. (old = 'poweredoff', new = 'running')
2023-03-27 14:06:08 (21712): Detected: Web Application Enabled (http://localhost:63860)
2023-03-27 14:06:08 (21712): Status Report: Job Duration: '64800.000000'
2023-03-27 14:06:08 (21712): Status Report: Elapsed Time: '13457.388464'
2023-03-27 14:06:08 (21712): Status Report: CPU Time: '11786.968750'
2023-03-27 14:06:08 (21712): Preference change detected
2023-03-27 14:06:08 (21712): Setting CPU throttle for VM. (100%)
2023-03-27 14:06:09 (21712): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2023-03-27 14:06:19 (21712): Guest Log: 03:48:05.036029 timesync vgsvcTimeSyncWorker: Radical guest time change: 1 889 342 540 000ns (GuestNow=1 679 940 364 951 906 000 ns GuestLast=1 679 938 475 609 366 000 ns fSetTimeLastLoop=true )
2023-03-27 14:06:49 (21712): VM state change detected. (old = 'running', new = 'paused')
2023-03-27 14:07:16 (21712): VM state change detected. (old = 'paused', new = 'running')
2023-03-27 14:07:23 (21712): VM state change detected. (old = 'running', new = 'paused')
2023-03-27 14:07:44 (21712): VM state change detected. (old = 'paused', new = 'running')
2023-03-27 14:10:18 (21712): VM state change detected. (old = 'running', new = 'paused')
2023-03-27 14:10:38 (21712): VM state change detected. (old = 'paused', new = 'running')
2023-03-27 14:24:50 (21712): VM state change detected. (old = 'running', new = 'paused')
2023-03-27 14:25:10 (21712): VM state change detected. (old = 'paused', new = 'running')
2023-03-27 14:38:41 (21712): VM state change detected. (old = 'running', new = 'paused')
2023-03-27 14:39:05 (21712): VM state change detected. (old = 'paused', new = 'running')
2023-03-27 14:41:05 (21712): VM state change detected. (old = 'running', new = 'paused')
2023-03-27 14:41:25 (21712): VM state change detected. (old = 'paused', new = 'running')
2023-03-27 15:05:36 (21712): VM state change detected. (old = 'running', new = 'paused')
2023-03-27 15:05:56 (21712): VM state change detected. (old = 'paused', new = 'running')
2023-03-27 15:05:59 (21712): Stopping VM.
2023-03-27 15:06:06 (21712): Successfully stopped VM.
2023-03-27 15:41:56 (11724): Detected: vboxwrapper 26206
2023-03-27 15:41:56 (11724): Detected: BOINC client v7.22.0
2023-03-27 15:41:57 (11724): Detected: VirtualBox VboxManage Interface (Version: 6.1.34)
2023-03-27 15:41:57 (11724): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2023-03-27 15:41:58 (11724): Guest Log: 03:48:05.036029 timesync vgsvcTimeSyncWorker: Radical guest time change: 1 889 342 540 000ns (GuestNow=1 679 940 364 951 906 000 ns GuestLast=1 679 938 475 609 366 000 ns fSetTimeLastLoop=true )
2023-03-27 15:41:58 (11724): Starting VM using VBoxManage interface. (boinc_fea3032480e7c995, slot#4)
2023-03-27 15:42:07 (11724): Successfully started VM. (PID = '9508')
2023-03-27 15:42:07 (11724): Reporting VM Process ID to BOINC.
2023-03-27 15:42:07 (11724): Guest Log: 04:45:18.726919 timesync vgsvcTimeSyncWorker: Radical host time change: 2 197 284 000 000ns (HostNow=1 679 946 108 309 000 000 ns HostLast=1 679 943 911 025 000 000 ns)
2023-03-27 15:42:07 (11724): VM state change detected. (old = 'poweredoff', new = 'running')
2023-03-27 15:42:07 (11724): Detected: Web Application Enabled (http://localhost:63860)
2023-03-27 15:42:07 (11724): Status Report: Job Duration: '64800.000000'
2023-03-27 15:42:07 (11724): Status Report: Elapsed Time: '16873.116398'
2023-03-27 15:42:07 (11724): Status Report: CPU Time: '15107.312500'
2023-03-27 15:42:07 (11724): Preference change detected
2023-03-27 15:42:07 (11724): Setting CPU throttle for VM. (100%)
2023-03-27 15:42:07 (11724): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2023-03-27 15:42:17 (11724): Guest Log: 04:45:28.727796 timesync vgsvcTimeSyncWorker: Radical guest time change: 2 319 661 207 000ns (GuestNow=1 679 946 118 322 646 000 ns GuestLast=1 679 943 798 661 439 000 ns fSetTimeLastLoop=true )
2023-03-27 15:42:48 (11724): Guest Log: [INFO] glidein exited with return value 0.
2023-03-27 15:42:48 (11724): Guest Log: [INFO] Shutting Down.
2023-03-27 15:42:48 (11724): VM Completion File Detected.
2023-03-27 15:42:48 (11724): VM Completion Message: glidein exited with return value 0.
.
2023-03-27 15:42:48 (11724): Powering off VM.
2023-03-27 15:42:49 (11724): Successfully stopped VM.
2023-03-27 15:42:49 (11724): Deregistering VM. (boinc_fea3032480e7c995, slot#4)
2023-03-27 15:42:49 (11724): Removing network bandwidth throttle group from VM.
2023-03-27 15:42:49 (11724): Removing VM from VirtualBox.
15:42:55 (11724): called boinc_finish(0)

</stderr_txt>
]]>
Development for LHC@home

Stderr output
