Stderr output

<core_client_version>7.22.0</core_client_version>
<![CDATA[
<stderr_txt>
2023-03-12 01:56:17 (3100): Detected: vboxwrapper 26206
2023-03-12 01:56:17 (3100): Detected: BOINC client v7.22.0
2023-03-12 01:56:18 (3100): Detected: VirtualBox VboxManage Interface (Version: 6.1.34)
2023-03-12 01:56:18 (3100): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2023-03-12 01:56:18 (3100): Successfully copied 'init_data.xml' to the shared directory.
2023-03-12 01:56:19 (3100): Create VM. (boinc_7e4c12efadfa13d0, slot#5)
2023-03-12 01:56:19 (3100): Setting Memory Size for VM. (1896MB)
2023-03-12 01:56:20 (3100): Setting CPU Count for VM. (1)
2023-03-12 01:56:20 (3100): Setting Chipset Options for VM.
2023-03-12 01:56:20 (3100): Setting Graphics Controller Options for VM.
2023-03-12 01:56:20 (3100): Setting Boot Options for VM.
2023-03-12 01:56:20 (3100): Setting Network Configuration for NAT.
2023-03-12 01:56:21 (3100): Enabling VM Network Access.
2023-03-12 01:56:21 (3100): Disabling USB Support for VM.
2023-03-12 01:56:21 (3100): Disabling COM Port Support for VM.
2023-03-12 01:56:22 (3100): Disabling LPT Port Support for VM.
2023-03-12 01:56:22 (3100): Disabling Audio Support for VM.
2023-03-12 01:56:22 (3100): Disabling Clipboard Support for VM.
2023-03-12 01:56:22 (3100): Disabling Drag and Drop Support for VM.
2023-03-12 01:56:23 (3100): Adding storage controller(s) to VM.
2023-03-12 01:56:23 (3100): Adding virtual disk drive to VM. (CMS_2022_09_07.vdi)
2023-03-12 01:56:24 (3100): Adding VirtualBox Guest Additions to VM.
2023-03-12 01:56:24 (3100): Adding network bandwidth throttle group to VM. (Defaulting to 1024GB)
2023-03-12 01:56:25 (3100): forwarding host port 59349 to guest port 80
2023-03-12 01:56:25 (3100): Enabling remote desktop for VM.
2023-03-12 01:56:25 (3100): Required extension pack not installed, remote desktop not enabled.
2023-03-12 01:56:25 (3100): Enabling shared directory for VM.
2023-03-12 01:56:26 (3100): Starting VM using VBoxManage interface. (boinc_7e4c12efadfa13d0, slot#5)
2023-03-12 01:56:30 (3100): Successfully started VM. (PID = '12260')
2023-03-12 01:56:30 (3100): Reporting VM Process ID to BOINC.
2023-03-12 01:56:30 (3100): Guest Log: BIOS: VirtualBox 6.1.34
2023-03-12 01:56:30 (3100): Guest Log: CPUID EDX: 0x178bfbff
2023-03-12 01:56:30 (3100): Guest Log: BIOS: No PCI IDE controller, not probing IDE
2023-03-12 01:56:30 (3100): Guest Log: BIOS: AHCI 0-P#0: PCHS=16383/16/63 LCHS=1024/255/63 0x0000000002800000 sectors
2023-03-12 01:56:30 (3100): VM state change detected. (old = 'poweredoff', new = 'running')
2023-03-12 01:56:30 (3100): Detected: Web Application Enabled (http://localhost:59349)
2023-03-12 01:56:31 (3100): VM state change detected. (old = 'running', new = 'paused')
2023-03-12 01:56:46 (3100): Preference change detected
2023-03-12 01:56:46 (3100): Setting CPU throttle for VM. (100%)
2023-03-12 01:56:47 (3100): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2023-03-12 01:56:47 (3100): VM state change detected. (old = 'paused', new = 'running')
2023-03-12 01:56:48 (3100): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032
2023-03-12 01:56:48 (3100): Guest Log: BIOS: Booting from Hard Disk...
2023-03-12 01:56:50 (3100): Guest Log: BIOS: KBD: unsupported int 16h function 03
2023-03-12 01:56:50 (3100): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 
2023-03-12 01:57:13 (3100): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds
2023-03-12 01:57:13 (3100): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000)
2023-03-12 01:57:13 (3100): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log
2023-03-12 01:57:13 (3100): Guest Log: 00:00:00.000084 main     Log opened 2023-03-12T06:57:14.314850000Z
2023-03-12 01:57:13 (3100): Guest Log: 00:00:00.000230 main     OS Product: Linux
2023-03-12 01:57:13 (3100): Guest Log: 00:00:00.000271 main     OS Release: 4.14.232-19.cernvm.x86_64
2023-03-12 01:57:13 (3100): Guest Log: 00:00:00.000307 main     OS Version: #1 SMP Fri Apr 30 17:12:25 CEST 2021
2023-03-12 01:57:13 (3100): Guest Log: 00:00:00.000340 main     Executable: /usr/sbin/VBoxService
2023-03-12 01:57:13 (3100): Guest Log: 00:00:00.000341 main     Process ID: 2158
2023-03-12 01:57:13 (3100): Guest Log: 00:00:00.000341 main     Package type: LINUX_64BITS_GENERIC
2023-03-12 01:57:13 (3100): Guest Log: 00:00:00.002348 main     5.2.6 r120293 started. Verbose level = 0
2023-03-12 01:57:24 (3100): Guest Log: [INFO] Mounting the shared directory
2023-03-12 01:57:24 (3100): Guest Log: [INFO] Shared directory mounted, enabling vboxmonitor
2023-03-12 01:57:24 (3100): Guest Log: [INFO] Sourcing essential functions from /cvmfs/grid.cern.ch
2023-03-12 01:57:24 (3100): Guest Log: [INFO] Testing connection to cern.ch
2023-03-12 01:57:24 (3100): Guest Log: [INFO] Testing connection to VCCS
2023-03-12 01:57:25 (3100): Guest Log: [INFO] Testing connection to HTCondor
2023-03-12 01:57:25 (3100): Guest Log: [INFO] Testing connection to WMAgent
2023-03-12 01:57:25 (3100): Guest Log: [INFO] Testing connection to EOSCMS
2023-03-12 01:57:25 (3100): Guest Log: [INFO] Testing connection to CMS-Factory
2023-03-12 01:57:26 (3100): Guest Log: [INFO] Testing connection to CMS-Frontier
2023-03-12 01:57:26 (3100): Guest Log: [INFO] Testing connection to Frontier
2023-03-12 01:57:26 (3100): Guest Log: [INFO] Could not find a local HTTP proxy
2023-03-12 01:57:26 (3100): Guest Log: [INFO] CVMFS and Frontier will have to use DIRECT connections
2023-03-12 01:57:26 (3100): Guest Log: [INFO] This makes the application less efficient
2023-03-12 01:57:27 (3100): Guest Log: [INFO] It also puts higher load on the project servers
2023-03-12 01:57:27 (3100): Guest Log: [INFO] Setting up a local HTTP proxy is highly recommended
2023-03-12 01:57:27 (3100): Guest Log: [INFO] Advice can be found in the project forum
2023-03-12 01:57:27 (3100): Guest Log: [INFO] Reloading and probing the CVMFS configuration
2023-03-12 01:57:32 (3100): Guest Log: [INFO] Probing /cvmfs/grid.cern.ch... OK
2023-03-12 01:57:35 (3100): Guest Log: [INFO] Probing /cvmfs/cms.cern.ch... OK
2023-03-12 01:57:35 (3100): Guest Log: [INFO] Probing /cvmfs/oasis.opensciencegrid.org... OK
2023-03-12 01:57:35 (3100): Guest Log: [INFO] Probing /cvmfs/cms-ib.cern.ch... OK
2023-03-12 01:57:35 (3100): Guest Log: [INFO] Probing /cvmfs/singularity.opensciencegrid.org... OK
2023-03-12 01:57:36 (3100): Guest Log: [INFO] Excerpt from "cvmfs_config stat": VERSION HOST PROXY
2023-03-12 01:57:36 (3100): Guest Log: [INFO] 2.7.2.0 http://s1fnal-cvmfs.openhtc.io:8080 DIRECT
2023-03-12 01:57:36 (3100): Guest Log: [INFO] Environment HTTP proxy: not set
2023-03-12 01:57:36 (3100): Guest Log: [INFO] Reading volunteer information
2023-03-12 01:57:40 (3100): Guest Log: [INFO] Requesting an X509 credential from LHC@home
2023-03-12 01:57:40 (3100): Guest Log: [INFO] Requesting an X509 credential from vLHC@home-dev
2023-03-12 01:57:42 (3100): Guest Log: [INFO] CMS application starting. Check log files.
2023-03-12 01:57:42 (3100): Guest Log: [INFO] Requesting an idtoken from LHC@home
2023-03-12 01:57:42 (3100): Guest Log: [INFO] Requesting an idtoken from vLHC@home-dev
2023-03-12 03:05:28 (3100): VM state change detected. (old = 'running', new = 'paused')
2023-03-12 03:05:54 (3100): VM state change detected. (old = 'paused', new = 'running')
2023-03-12 03:50:05 (3100): VM state change detected. (old = 'running', new = 'paused')
2023-03-12 03:50:30 (3100): VM state change detected. (old = 'paused', new = 'running')
2023-03-12 04:36:51 (3100): VM state change detected. (old = 'running', new = 'paused')
2023-03-12 04:37:11 (3100): VM state change detected. (old = 'paused', new = 'running')
2023-03-12 04:37:21 (3100): VM state change detected. (old = 'running', new = 'paused')
2023-03-12 04:37:41 (3100): VM state change detected. (old = 'paused', new = 'running')
2023-03-12 04:39:31 (3100): Status Report: Job Duration: '64800.000000'
2023-03-12 04:39:31 (3100): Status Report: Elapsed Time: '6000.517450'
2023-03-12 04:39:31 (3100): Status Report: CPU Time: '6084.812500'
2023-03-12 05:00:48 (3100): VM state change detected. (old = 'running', new = 'paused')
2023-03-12 05:01:08 (3100): VM state change detected. (old = 'paused', new = 'running')
2023-03-12 05:20:13 (3100): VM state change detected. (old = 'running', new = 'paused')
2023-03-12 05:20:33 (3100): VM state change detected. (old = 'paused', new = 'running')
2023-03-12 05:24:34 (3100): VM state change detected. (old = 'running', new = 'paused')
2023-03-12 05:24:54 (3100): VM state change detected. (old = 'paused', new = 'running')
2023-03-12 05:34:46 (3100): VM state change detected. (old = 'running', new = 'paused')
2023-03-12 05:35:11 (3100): VM state change detected. (old = 'paused', new = 'running')
2023-03-12 05:49:31 (3100): VM state change detected. (old = 'running', new = 'paused')
2023-03-12 05:49:51 (3100): VM state change detected. (old = 'paused', new = 'running')
2023-03-12 06:03:35 (3100): VM state change detected. (old = 'running', new = 'paused')
2023-03-12 06:03:55 (3100): VM state change detected. (old = 'paused', new = 'running')
2023-03-12 06:07:18 (3100): VM state change detected. (old = 'running', new = 'paused')
2023-03-12 06:07:43 (3100): VM state change detected. (old = 'paused', new = 'running')
2023-03-12 06:14:00 (3100): VM state change detected. (old = 'running', new = 'paused')
2023-03-12 06:14:20 (3100): VM state change detected. (old = 'paused', new = 'running')
2023-03-12 06:23:11 (3100): Status Report: Job Duration: '64800.000000'
2023-03-12 06:23:11 (3100): Status Report: Elapsed Time: '12000.880642'
2023-03-12 06:23:11 (3100): Status Report: CPU Time: '12023.296875'
2023-03-12 06:26:06 (3100): VM state change detected. (old = 'running', new = 'paused')
2023-03-12 06:26:34 (3100): VM state change detected. (old = 'paused', new = 'running')
2023-03-12 06:58:24 (3100): VM state change detected. (old = 'running', new = 'paused')
2023-03-12 06:58:44 (3100): VM state change detected. (old = 'paused', new = 'running')
2023-03-12 07:12:07 (3100): VM state change detected. (old = 'running', new = 'paused')
2023-03-12 07:12:27 (3100): VM state change detected. (old = 'paused', new = 'running')
2023-03-12 07:23:40 (3100): VM state change detected. (old = 'running', new = 'paused')
2023-03-12 07:24:00 (3100): VM state change detected. (old = 'paused', new = 'running')
2023-03-12 07:40:54 (3100): VM state change detected. (old = 'running', new = 'paused')
2023-03-12 07:41:14 (3100): VM state change detected. (old = 'paused', new = 'running')
2023-03-12 07:56:29 (3100): VM state change detected. (old = 'running', new = 'paused')
2023-03-12 07:56:51 (3100): VM state change detected. (old = 'paused', new = 'running')
2023-03-12 07:56:51 (3100): Stopping VM.
2023-03-12 07:56:58 (3100): Successfully stopped VM.
2023-03-12 07:58:27 (17904): Detected: vboxwrapper 26206
2023-03-12 07:58:27 (17904): Detected: BOINC client v7.22.0
2023-03-12 07:58:28 (17904): Detected: VirtualBox VboxManage Interface (Version: 6.1.34)
2023-03-12 07:58:28 (17904): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2023-03-12 07:58:29 (17904): Guest Log: BIOS: VirtualBox 6.1.34
2023-03-12 07:58:29 (17904): Guest Log: CPUID EDX: 0x178bfbff
2023-03-12 07:58:29 (17904): Guest Log: BIOS: No PCI IDE controller, not probing IDE
2023-03-12 07:58:29 (17904): Guest Log: BIOS: AHCI 0-P#0: PCHS=16383/16/63 LCHS=1024/255/63 0x0000000002800000 sectors
2023-03-12 07:58:29 (17904): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032
2023-03-12 07:58:29 (17904): Guest Log: BIOS: Booting from Hard Disk...
2023-03-12 07:58:29 (17904): Guest Log: BIOS: KBD: unsupported int 16h function 03
2023-03-12 07:58:29 (17904): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 
2023-03-12 07:58:29 (17904): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds
2023-03-12 07:58:29 (17904): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000)
2023-03-12 07:58:29 (17904): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log
2023-03-12 07:58:29 (17904): Guest Log: 00:00:00.000084 main     Log opened 2023-03-12T06:57:14.314850000Z
2023-03-12 07:58:29 (17904): Guest Log: 00:00:00.000230 main     OS Product: Linux
2023-03-12 07:58:29 (17904): Guest Log: 00:00:00.000271 main     OS Release: 4.14.232-19.cernvm.x86_64
2023-03-12 07:58:29 (17904): Guest Log: 00:00:00.000307 main     OS Version: #1 SMP Fri Apr 30 17:12:25 CEST 2021
2023-03-12 07:58:29 (17904): Guest Log: 00:00:00.000340 main     Executable: /usr/sbin/VBoxService
2023-03-12 07:58:29 (17904): Guest Log: 00:00:00.000341 main     Process ID: 2158
2023-03-12 07:58:29 (17904): Guest Log: 00:00:00.000341 main     Package type: LINUX_64BITS_GENERIC
2023-03-12 07:58:29 (17904): Guest Log: 00:00:00.002348 main     5.2.6 r120293 started. Verbose level = 0
2023-03-12 07:58:29 (17904): Guest Log: [INFO] Mounting the shared directory
2023-03-12 07:58:29 (17904): Guest Log: [INFO] Shared directory mounted, enabling vboxmonitor
2023-03-12 07:58:29 (17904): Guest Log: [INFO] Sourcing essential functions from /cvmfs/grid.cern.ch
2023-03-12 07:58:29 (17904): Guest Log: [INFO] Testing connection to cern.ch
2023-03-12 07:58:29 (17904): Guest Log: [INFO] Testing connection to VCCS
2023-03-12 07:58:29 (17904): Guest Log: [INFO] Testing connection to HTCondor
2023-03-12 07:58:29 (17904): Guest Log: [INFO] Testing connection to WMAgent
2023-03-12 07:58:29 (17904): Guest Log: [INFO] Testing connection to EOSCMS
2023-03-12 07:58:29 (17904): Guest Log: [INFO] Testing connection to CMS-Factory
2023-03-12 07:58:29 (17904): Guest Log: [INFO] Testing connection to CMS-Frontier
2023-03-12 07:58:29 (17904): Guest Log: [INFO] Testing connection to Frontier
2023-03-12 07:58:29 (17904): Guest Log: [INFO] Could not find a local HTTP proxy
2023-03-12 07:58:29 (17904): Guest Log: [INFO] CVMFS and Frontier will have to use DIRECT connections
2023-03-12 07:58:29 (17904): Guest Log: [INFO] This makes the application less efficient
2023-03-12 07:58:29 (17904): Guest Log: [INFO] It also puts higher load on the project servers
2023-03-12 07:58:29 (17904): Guest Log: [INFO] Setting up a local HTTP proxy is highly recommended
2023-03-12 07:58:29 (17904): Guest Log: [INFO] Advice can be found in the project forum
2023-03-12 07:58:29 (17904): Guest Log: [INFO] Reloading and probing the CVMFS configuration
2023-03-12 07:58:29 (17904): Guest Log: [INFO] Probing /cvmfs/grid.cern.ch... OK
2023-03-12 07:58:29 (17904): Guest Log: [INFO] Probing /cvmfs/cms.cern.ch... OK
2023-03-12 07:58:29 (17904): Guest Log: [INFO] Probing /cvmfs/oasis.opensciencegrid.org... OK
2023-03-12 07:58:29 (17904): Guest Log: [INFO] Probing /cvmfs/cms-ib.cern.ch... OK
2023-03-12 07:58:29 (17904): Guest Log: [INFO] Probing /cvmfs/singularity.opensciencegrid.org... OK
2023-03-12 07:58:29 (17904): Guest Log: [INFO] Excerpt from "cvmfs_config stat": VERSION HOST PROXY
2023-03-12 07:58:29 (17904): Guest Log: [INFO] 2.7.2.0 http://s1fnal-cvmfs.openhtc.io:8080 DIRECT
2023-03-12 07:58:29 (17904): Guest Log: [INFO] Environment HTTP proxy: not set
2023-03-12 07:58:29 (17904): Guest Log: [INFO] Reading volunteer information
2023-03-12 07:58:29 (17904): Guest Log: [INFO] Requesting an X509 credential from LHC@home
2023-03-12 07:58:29 (17904): Guest Log: [INFO] Requesting an X509 credential from vLHC@home-dev
2023-03-12 07:58:29 (17904): Guest Log: [INFO] CMS application starting. Check log files.
2023-03-12 07:58:29 (17904): Guest Log: [INFO] Requesting an idtoken from LHC@home
2023-03-12 07:58:29 (17904): Guest Log: [INFO] Requesting an idtoken from vLHC@home-dev
2023-03-12 07:58:29 (17904): Starting VM using VBoxManage interface. (boinc_7e4c12efadfa13d0, slot#5)
2023-03-12 07:58:57 (17904): Successfully started VM. (PID = '1572')
2023-03-12 07:58:57 (17904): Reporting VM Process ID to BOINC.
2023-03-12 07:58:57 (17904): VM state change detected. (old = 'poweredoff', new = 'running')
2023-03-12 07:58:57 (17904): Detected: Web Application Enabled (http://localhost:59349)
2023-03-12 07:58:57 (17904): Status Report: Job Duration: '64800.000000'
2023-03-12 07:58:57 (17904): Status Report: Elapsed Time: '17442.968822'
2023-03-12 07:58:57 (17904): Status Report: CPU Time: '16772.125000'
2023-03-12 07:58:57 (17904): Preference change detected
2023-03-12 07:58:57 (17904): Setting CPU throttle for VM. (100%)
2023-03-12 07:59:01 (17904): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2023-03-12 08:08:35 (17904): Stopping VM.
2023-03-12 08:08:46 (17904): Successfully stopped VM.
2023-03-12 08:25:27 (10764): Detected: vboxwrapper 26206
2023-03-12 08:25:27 (10764): Detected: BOINC client v7.22.0
2023-03-12 08:25:33 (10764): Detected: VirtualBox VboxManage Interface (Version: 6.1.34)
2023-03-12 08:25:44 (10764): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2023-03-12 08:25:56 (10764): ERROR: VM failed to start
2023-03-12 08:26:01 (10764): 
   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-12 11:55:48 (15188): Detected: vboxwrapper 26206
2023-03-12 11:55:48 (15188): Detected: BOINC client v7.22.0
2023-03-12 11:55:49 (15188): Detected: VirtualBox VboxManage Interface (Version: 6.1.34)
2023-03-12 11:55:49 (15188): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2023-03-12 11:55:50 (15188): Starting VM using VBoxManage interface. (boinc_7e4c12efadfa13d0, slot#5)
2023-03-12 11:56:01 (15188): Successfully started VM. (PID = '13812')
2023-03-12 11:56:01 (15188): Reporting VM Process ID to BOINC.
2023-03-12 11:56:01 (15188): VM state change detected. (old = 'poweredoff', new = 'running')
2023-03-12 11:56:01 (15188): Detected: Web Application Enabled (http://localhost:59349)
2023-03-12 11:56:01 (15188): Status Report: Job Duration: '64800.000000'
2023-03-12 11:56:01 (15188): Status Report: Elapsed Time: '18000.637403'
2023-03-12 11:56:01 (15188): Status Report: CPU Time: '17366.140625'
2023-03-12 11:56:01 (15188): Preference change detected
2023-03-12 11:56:01 (15188): Setting CPU throttle for VM. (100%)
2023-03-12 11:56:01 (15188): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2023-03-12 11:56:03 (15188): Guest Log: 05:03:50.745004 timesync vgsvcTimeSyncWorker: Radical host time change: 13 705 369 000 000ns (HostNow=1 678 636 557 405 000 000 ns HostLast=1 678 622 852 036 000 000 ns)
2023-03-12 11:56:13 (15188): Guest Log: 05:04:00.752765 timesync vgsvcTimeSyncWorker: Radical guest time change: 13 676 178 212 000ns (GuestNow=1 678 636 567 420 802 000 ns GuestLast=1 678 622 891 242 590 000 ns fSetTimeLastLoop=true )
2023-03-12 11:59:57 (15188): VM state change detected. (old = 'running', new = 'paused')
2023-03-12 12:00:18 (15188): VM state change detected. (old = 'paused', new = 'running')
2023-03-12 12:00:43 (15188): Stopping VM.
2023-03-12 12:00:53 (15188): Successfully stopped VM.
2023-03-12 12:02:32 (2112): Detected: vboxwrapper 26206
2023-03-12 12:02:32 (2112): Detected: BOINC client v7.22.0
2023-03-12 12:02:33 (2112): Detected: VirtualBox VboxManage Interface (Version: 6.1.34)
2023-03-12 12:02:40 (2112): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2023-03-12 12:02:42 (2112): Guest Log: 05:03:50.745004 timesync vgsvcTimeSyncWorker: Radical host time change: 13 705 369 000 000ns (HostNow=1 678 636 557 405 000 000 ns HostLast=1 678 622 852 036 000 000 ns)
2023-03-12 12:02:42 (2112): Guest Log: 05:04:00.752765 timesync vgsvcTimeSyncWorker: Radical guest time change: 13 676 178 212 000ns (GuestNow=1 678 636 567 420 802 000 ns GuestLast=1 678 622 891 242 590 000 ns fSetTimeLastLoop=true )
2023-03-12 12:02:42 (2112): Starting VM using VBoxManage interface. (boinc_7e4c12efadfa13d0, slot#5)
2023-03-12 12:03:07 (2112): Successfully started VM. (PID = '14740')
2023-03-12 12:03:07 (2112): Reporting VM Process ID to BOINC.
2023-03-12 12:03:07 (2112): VM state change detected. (old = 'poweredoff', new = 'running')
2023-03-12 12:03:07 (2112): Detected: Web Application Enabled (http://localhost:59349)
2023-03-12 12:03:07 (2112): Status Report: Job Duration: '64800.000000'
2023-03-12 12:03:07 (2112): Status Report: Elapsed Time: '18250.637403'
2023-03-12 12:03:07 (2112): Status Report: CPU Time: '17652.921875'
2023-03-12 12:03:07 (2112): Preference change detected
2023-03-12 12:03:07 (2112): Setting CPU throttle for VM. (100%)
2023-03-12 12:03:41 (2112): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2023-03-12 12:05:14 (2112): Stopping VM.
2023-03-12 12:05:27 (2112): Successfully stopped VM.
2023-03-12 12:07:39 (2676): Detected: vboxwrapper 26206
2023-03-12 12:07:39 (2676): Detected: BOINC client v7.22.0
2023-03-12 12:07:40 (2676): Detected: VirtualBox VboxManage Interface (Version: 6.1.34)
2023-03-12 12:07:40 (2676): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2023-03-12 12:07:41 (2676): ERROR: VM failed to start
2023-03-12 12:07:46 (2676): 
   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-12 12:09:38 (9640): Detected: vboxwrapper 26206
2023-03-12 12:09:38 (9640): Detected: BOINC client v7.22.0
2023-03-12 12:09:39 (9640): Detected: VirtualBox VboxManage Interface (Version: 6.1.34)
2023-03-12 12:09:40 (9640): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2023-03-12 12:09:46 (9640): Starting VM using VBoxManage interface. (boinc_7e4c12efadfa13d0, slot#5)
2023-03-12 12:10:08 (9640): Successfully started VM. (PID = '16088')
2023-03-12 12:10:08 (9640): Reporting VM Process ID to BOINC.
2023-03-12 12:10:08 (9640): VM state change detected. (old = 'poweredoff', new = 'running')
2023-03-12 12:10:08 (9640): Detected: Web Application Enabled (http://localhost:59349)
2023-03-12 12:10:08 (9640): Stopping VM.
2023-03-12 12:10:17 (9640): Successfully stopped VM.
2023-03-12 12:12:40 (17232): Detected: vboxwrapper 26206
2023-03-12 12:12:40 (17232): Detected: BOINC client v7.22.0
2023-03-12 12:12:41 (17232): Detected: VirtualBox VboxManage Interface (Version: 6.1.34)
2023-03-12 12:12:45 (17232): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2023-03-12 12:12:52 (17232): Starting VM using VBoxManage interface. (boinc_7e4c12efadfa13d0, slot#5)
2023-03-12 12:13:19 (17232): Successfully started VM. (PID = '15260')
2023-03-12 12:13:19 (17232): Reporting VM Process ID to BOINC.
2023-03-12 12:13:19 (17232): VM state change detected. (old = 'poweredoff', new = 'running')
2023-03-12 12:13:19 (17232): Detected: Web Application Enabled (http://localhost:59349)
2023-03-12 12:13:19 (17232): Status Report: Job Duration: '64800.000000'
2023-03-12 12:13:19 (17232): Status Report: Elapsed Time: '18375.195428'
2023-03-12 12:13:19 (17232): Status Report: CPU Time: '17812.875000'
2023-03-12 12:13:19 (17232): Preference change detected
2023-03-12 12:13:19 (17232): Setting CPU throttle for VM. (100%)
2023-03-12 12:14:02 (17232): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2023-03-12 12:56:59 (17232): VM state change detected. (old = 'running', new = 'paused')
2023-03-12 12:57:20 (17232): VM state change detected. (old = 'paused', new = 'running')
2023-03-12 12:57:40 (17232): Stopping VM.
2023-03-12 12:57:46 (17232): Successfully stopped VM.
2023-03-13 19:06:55 (10308): Detected: vboxwrapper 26206
2023-03-13 19:06:55 (10308): Detected: BOINC client v7.22.0
2023-03-13 19:06:56 (10308): Detected: VirtualBox VboxManage Interface (Version: 6.1.34)
2023-03-13 19:06:56 (10308): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2023-03-13 19:06:57 (10308): Starting VM using VBoxManage interface. (boinc_7e4c12efadfa13d0, slot#5)
2023-03-13 19:07:07 (10308): Successfully started VM. (PID = '14612')
2023-03-13 19:07:07 (10308): Reporting VM Process ID to BOINC.
2023-03-13 19:07:07 (10308): VM state change detected. (old = 'poweredoff', new = 'running')
2023-03-13 19:07:07 (10308): Detected: Web Application Enabled (http://localhost:59349)
2023-03-13 19:07:08 (10308): VM state change detected. (old = 'running', new = 'paused')
2023-03-13 19:07:23 (10308): Status Report: Job Duration: '64800.000000'
2023-03-13 19:07:23 (10308): Status Report: Elapsed Time: '20931.040258'
2023-03-13 19:07:23 (10308): Status Report: CPU Time: '20760.703125'
2023-03-13 19:07:23 (10308): Preference change detected
2023-03-13 19:07:23 (10308): Setting CPU throttle for VM. (100%)
2023-03-13 19:07:23 (10308): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2023-03-13 19:07:24 (10308): VM state change detected. (old = 'paused', new = 'running')
2023-03-13 19:07:28 (10308): Guest Log: 05:55:14.158455 timesync vgsvcTimeSyncWorker: Radical host time change: 108 584 236 000 000ns (HostNow=1 678 748 806 966 000 000 ns HostLast=1 678 640 222 730 000 000 ns)
2023-03-13 19:07:38 (10308): Guest Log: 05:55:24.163026 timesync vgsvcTimeSyncWorker: Radical guest time change: 108 604 843 987 000ns (GuestNow=1 678 748 816 970 506 000 ns GuestLast=1 678 640 212 126 519 000 ns fSetTimeLastLoop=true )
2023-03-13 19:12:41 (10308): Guest Log: [INFO] glidein exited with return value 0.
2023-03-13 19:12:41 (10308): Guest Log: [INFO] Shutting Down.
2023-03-13 19:12:41 (10308): VM Completion File Detected.
2023-03-13 19:12:41 (10308): VM Completion Message: glidein exited with return value 0.
.
2023-03-13 19:12:41 (10308): Powering off VM.
2023-03-13 19:12:42 (10308): Successfully stopped VM.
2023-03-13 19:12:42 (10308): Deregistering VM. (boinc_7e4c12efadfa13d0, slot#5)
2023-03-13 19:12:42 (10308): Removing network bandwidth throttle group from VM.
2023-03-13 19:12:42 (10308): Removing VM from VirtualBox.
19:12:47 (10308): called boinc_finish(0)

</stderr_txt>
]]>

©2025 CERN