Stderr output

<core_client_version>8.0.2</core_client_version>
<![CDATA[
<stderr_txt>
2025-06-02 01:27:38 (1252): vboxwrapper version 26210
2025-06-02 01:27:38 (1252): BOINC client version: 8.0.2
2025-06-02 01:27:42 (1252): Detected: VirtualBox VboxManage Interface (Version: 6.0.14)
2025-06-02 01:27:42 (1252): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2025-06-02 01:27:42 (1252): Successfully copied 'init_data.xml' to the shared directory.
2025-06-02 01:27:42 (1252): Create VM. (boinc_311ab8b2ccbeb2fc, slot#6)
2025-06-02 01:27:43 (1252): Setting Memory Size for VM. (4584MB)
2025-06-02 01:27:43 (1252): Setting CPU Count for VM. (4)
2025-06-02 01:27:43 (1252): Setting Chipset Options for VM.
2025-06-02 01:27:43 (1252): Setting Graphics Controller Options for VM. (Driver: VBoxVGA, 16MB)
2025-06-02 01:27:44 (1252): Setting Boot Options for VM.
2025-06-02 01:27:44 (1252): Setting Network Configuration for NAT. (Driver: virtio)
2025-06-02 01:27:45 (1252): Enabling VM Network Access.
2025-06-02 01:27:45 (1252): Disabling USB Support for VM.
2025-06-02 01:27:45 (1252): Disabling COM Port Support for VM.
2025-06-02 01:27:45 (1252): Disabling LPT Port Support for VM.
2025-06-02 01:27:46 (1252): Disabling Audio Support for VM.
2025-06-02 01:27:46 (1252): Disabling Clipboard Support for VM.
2025-06-02 01:27:46 (1252): Disabling Drag and Drop Support for VM.
2025-06-02 01:27:46 (1252): Adding storage controller(s) to VM.
2025-06-02 01:27:47 (1252): Adding virtual disk drive to VM. (CMS_2025_04_08_prod.vdi)
2025-06-02 01:27:48 (1252): Adding VirtualBox Guest Additions to VM.
2025-06-02 01:27:48 (1252): Adding network bandwidth throttle group to VM. (Defaulting to 1024GB)
2025-06-02 01:27:48 (1252): forwarding host port 55057 to guest port 80
2025-06-02 01:27:49 (1252): Enabling remote desktop for VM.
2025-06-02 01:27:49 (1252): Required extension pack not installed, remote desktop not enabled.
2025-06-02 01:27:49 (1252): Enabling shared directory for VM.
2025-06-02 01:27:49 (1252): Starting VM using VBoxManage interface. (boinc_311ab8b2ccbeb2fc, slot#6)
2025-06-02 01:27:56 (1252): Successfully started VM. (PID = '4660')
2025-06-02 01:27:56 (1252): Reporting VM Process ID to BOINC.
2025-06-02 01:27:56 (1252): Guest Log: BIOS: VirtualBox 6.0.14
2025-06-02 01:27:56 (1252): Guest Log: CPUID EDX: 0x178bfbff
2025-06-02 01:27:56 (1252): Guest Log: BIOS: AHCI 0-P#0: PCHS=16383/16/63 LCHS=1024/255/63 0x0000000002800000 sectors
2025-06-02 01:27:56 (1252): VM state change detected. (old = 'poweredoff', new = 'running')
2025-06-02 01:27:56 (1252): Detected: Web Application Enabled (http://localhost:55057)
2025-06-02 01:27:57 (1252): VM state change detected. (old = 'running', new = 'paused')
2025-06-02 01:28:00 (1252): Preference change detected
2025-06-02 01:28:00 (1252): Setting CPU throttle for VM. (100%)
2025-06-02 01:28:00 (1252): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 300 seconds) or (Vbox_job.xml: 600 seconds))
2025-06-02 01:28:01 (1252): VM state change detected. (old = 'paused', new = 'running')
2025-06-02 01:28:02 (1252): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032
2025-06-02 01:28:02 (1252): Guest Log: BIOS: Booting from Hard Disk...
2025-06-02 01:28:05 (1252): Guest Log: BIOS: KBD: unsupported int 16h function 03
2025-06-02 01:28:05 (1252): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 
2025-06-02 01:28:30 (1252): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds
2025-06-02 01:28:30 (1252): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0000000 (size 0x400000)
2025-06-02 01:28:32 (1252): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log
2025-06-02 01:28:32 (1252): Guest Log: 00:00:00.000166 main     Log opened 2025-06-02T05:28:31.189134000Z
2025-06-02 01:28:32 (1252): Guest Log: 00:00:00.000305 main     OS Product: Linux
2025-06-02 01:28:32 (1252): Guest Log: 00:00:00.000347 main     OS Release: 4.14.232-19.cernvm.x86_64
2025-06-02 01:28:32 (1252): Guest Log: 00:00:00.000387 main     OS Version: #1 SMP Fri Apr 30 17:12:25 CEST 2021
2025-06-02 01:28:32 (1252): Guest Log: 00:00:00.000425 main     Executable: /usr/sbin/VBoxService
2025-06-02 01:28:32 (1252): Guest Log: 00:00:00.000427 main     Process ID: 2297
2025-06-02 01:28:32 (1252): Guest Log: 00:00:00.000428 main     Package type: LINUX_64BITS_GENERIC
2025-06-02 01:28:32 (1252): Guest Log: 00:00:00.001325 main     5.2.6 r120293 started. Verbose level = 0
2025-06-02 01:28:52 (1252): Guest Log: [INFO] Mounting the shared directory
2025-06-02 01:28:52 (1252): Guest Log: [INFO] Shared directory mounted, enabling vboxmonitor
2025-06-02 01:28:52 (1252): Guest Log: [INFO] Sourcing essential functions from /cvmfs/grid.cern.ch
2025-06-02 01:28:52 (1252): Guest Log: [INFO] Testing connection to cern.ch
2025-06-02 01:28:53 (1252): Guest Log: [INFO] Testing connection to VCCS
2025-06-02 01:28:54 (1252): Guest Log: [INFO] Testing connection to HTCondor
2025-06-02 01:28:54 (1252): Guest Log: [INFO] Testing connection to WMAgent
2025-06-02 01:28:55 (1252): Guest Log: [INFO] Testing connection to EOSCMS
2025-06-02 01:28:55 (1252): Guest Log: [INFO] Testing connection to CMS-Factory
2025-06-02 01:28:56 (1252): Guest Log: [INFO] Testing connection to CMS-Frontier
2025-06-02 01:28:56 (1252): Guest Log: [INFO] Testing connection to Frontier
2025-06-02 01:28:56 (1252): Guest Log: [INFO] Testing connection to http://cms-frontier.openhtc.io:8080/FrontierProd/Frontier/
2025-06-02 01:28:58 (1252): Guest Log: [INFO] Could not find a local HTTP proxy
2025-06-02 01:28:58 (1252): Guest Log: [INFO] CVMFS and Frontier will have to use DIRECT connections
2025-06-02 01:28:58 (1252): Guest Log: [INFO] This makes the application less efficient
2025-06-02 01:28:58 (1252): Guest Log: [INFO] It also puts higher load on the project servers
2025-06-02 01:28:58 (1252): Guest Log: [INFO] Setting up a local HTTP proxy is highly recommended
2025-06-02 01:28:59 (1252): Guest Log: [INFO] Advice can be found in the project forum
2025-06-02 01:28:59 (1252): Guest Log: [INFO] Reloading and probing the CVMFS configuration
2025-06-02 01:29:07 (1252): Guest Log: [INFO] Probing /cvmfs/grid.cern.ch... OK
2025-06-02 01:29:11 (1252): Guest Log: [INFO] Probing /cvmfs/cms-ib.cern.ch... OK
2025-06-02 01:29:12 (1252): Guest Log: [INFO] Probing /cvmfs/singularity.opensciencegrid.org... OK
2025-06-02 01:29:12 (1252): Guest Log: [INFO] Probing /cvmfs/cms.cern.ch... OK
2025-06-02 01:29:12 (1252): Guest Log: [INFO] Probing /cvmfs/oasis.opensciencegrid.org... OK
2025-06-02 01:29:13 (1252): Guest Log: [INFO] Excerpt from "cvmfs_config stat": VERSION HOST PROXY
2025-06-02 01:29:14 (1252): Guest Log: [INFO] 2.7.2.0 http://s1ral-cvmfs.openhtc.io DIRECT
2025-06-02 01:29:14 (1252): Guest Log: [INFO] Environment HTTP proxy: not set
2025-06-02 01:29:14 (1252): Guest Log: [INFO] Reading volunteer information
2025-06-02 01:29:27 (1252): Guest Log: [INFO] Requesting an X509 credential from LHC@home
2025-06-02 01:29:28 (1252): Guest Log: [INFO] Requesting an idtoken from LHC@home
2025-06-02 01:29:30 (1252): Guest Log: [INFO] CMS application starting. Check log files.
2025-06-02 01:35:22 (1252): VM state change detected. (old = 'running', new = 'paused')
2025-06-02 01:35:42 (1252): VM state change detected. (old = 'paused', new = 'running')
2025-06-02 01:45:13 (1252): VM state change detected. (old = 'running', new = 'paused')
2025-06-02 01:45:33 (1252): VM state change detected. (old = 'paused', new = 'running')
2025-06-02 01:45:53 (1252): VM state change detected. (old = 'running', new = 'paused')
2025-06-02 01:46:13 (1252): VM state change detected. (old = 'paused', new = 'running')
2025-06-02 01:51:03 (1252): Stopping VM.
2025-06-02 01:52:00 (1252): Error in stop VM for VM: -182
Command:
VBoxManage -q controlvm "boinc_311ab8b2ccbeb2fc" savestate
Output:
0%...10%...20%...30%...40%...50%...60%...70%...
2025-06-02 01:52:00 (1252): VM did not stop when requested.
2025-06-02 01:52:00 (1252): VM was NOT successfully terminated.
2025-06-02 02:43:20 (13120): vboxwrapper version 26210
2025-06-02 02:43:20 (13120): BOINC client version: 8.0.2
2025-06-02 02:43:23 (13120): Detected: VirtualBox VboxManage Interface (Version: 6.0.14)
2025-06-02 02:43:24 (13120): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2025-06-02 02:43:24 (13120): Guest Log: BIOS: VirtualBox 6.0.14
2025-06-02 02:43:24 (13120): Guest Log: CPUID EDX: 0x178bfbff
2025-06-02 02:43:24 (13120): Guest Log: BIOS: AHCI 0-P#0: PCHS=16383/16/63 LCHS=1024/255/63 0x0000000002800000 sectors
2025-06-02 02:43:24 (13120): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032
2025-06-02 02:43:24 (13120): Guest Log: BIOS: Booting from Hard Disk...
2025-06-02 02:43:24 (13120): Guest Log: BIOS: KBD: unsupported int 16h function 03
2025-06-02 02:43:24 (13120): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 
2025-06-02 02:43:24 (13120): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds
2025-06-02 02:43:24 (13120): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0000000 (size 0x400000)
2025-06-02 02:43:24 (13120): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log
2025-06-02 02:43:24 (13120): Guest Log: 00:00:00.000166 main     Log opened 2025-06-02T05:28:31.189134000Z
2025-06-02 02:43:24 (13120): Guest Log: 00:00:00.000305 main     OS Product: Linux
2025-06-02 02:43:24 (13120): Guest Log: 00:00:00.000347 main     OS Release: 4.14.232-19.cernvm.x86_64
2025-06-02 02:43:24 (13120): Guest Log: 00:00:00.000387 main     OS Version: #1 SMP Fri Apr 30 17:12:25 CEST 2021
2025-06-02 02:43:24 (13120): Guest Log: 00:00:00.000425 main     Executable: /usr/sbin/VBoxService
2025-06-02 02:43:24 (13120): Guest Log: 00:00:00.000427 main     Process ID: 2297
2025-06-02 02:43:24 (13120): Guest Log: 00:00:00.000428 main     Package type: LINUX_64BITS_GENERIC
2025-06-02 02:43:24 (13120): Guest Log: 00:00:00.001325 main     5.2.6 r120293 started. Verbose level = 0
2025-06-02 02:43:24 (13120): Guest Log: [INFO] Mounting the shared directory
2025-06-02 02:43:24 (13120): Guest Log: [INFO] Shared directory mounted, enabling vboxmonitor
2025-06-02 02:43:24 (13120): Guest Log: [INFO] Sourcing essential functions from /cvmfs/grid.cern.ch
2025-06-02 02:43:24 (13120): Guest Log: [INFO] Testing connection to cern.ch
2025-06-02 02:43:24 (13120): Guest Log: [INFO] Testing connection to VCCS
2025-06-02 02:43:24 (13120): Guest Log: [INFO] Testing connection to HTCondor
2025-06-02 02:43:24 (13120): Guest Log: [INFO] Testing connection to WMAgent
2025-06-02 02:43:24 (13120): Guest Log: [INFO] Testing connection to EOSCMS
2025-06-02 02:43:24 (13120): Guest Log: [INFO] Testing connection to CMS-Factory
2025-06-02 02:43:24 (13120): Guest Log: [INFO] Testing connection to CMS-Frontier
2025-06-02 02:43:24 (13120): Guest Log: [INFO] Testing connection to Frontier
2025-06-02 02:43:24 (13120): Guest Log: [INFO] Testing connection to http://cms-frontier.openhtc.io:8080/FrontierProd/Frontier/
2025-06-02 02:43:24 (13120): Guest Log: [INFO] Could not find a local HTTP proxy
2025-06-02 02:43:24 (13120): Guest Log: [INFO] CVMFS and Frontier will have to use DIRECT connections
2025-06-02 02:43:24 (13120): Guest Log: [INFO] This makes the application less efficient
2025-06-02 02:43:24 (13120): Guest Log: [INFO] It also puts higher load on the project servers
2025-06-02 02:43:24 (13120): Guest Log: [INFO] Setting up a local HTTP proxy is highly recommended
2025-06-02 02:43:24 (13120): Guest Log: [INFO] Advice can be found in the project forum
2025-06-02 02:43:24 (13120): Guest Log: [INFO] Reloading and probing the CVMFS configuration
2025-06-02 02:43:24 (13120): Guest Log: [INFO] Probing /cvmfs/grid.cern.ch... OK
2025-06-02 02:43:24 (13120): Guest Log: [INFO] Probing /cvmfs/cms-ib.cern.ch... OK
2025-06-02 02:43:24 (13120): Guest Log: [INFO] Probing /cvmfs/singularity.opensciencegrid.org... OK
2025-06-02 02:43:24 (13120): Guest Log: [INFO] Probing /cvmfs/cms.cern.ch... OK
2025-06-02 02:43:24 (13120): Guest Log: [INFO] Probing /cvmfs/oasis.opensciencegrid.org... OK
2025-06-02 02:43:24 (13120): Guest Log: [INFO] Excerpt from "cvmfs_config stat": VERSION HOST PROXY
2025-06-02 02:43:24 (13120): Guest Log: [INFO] 2.7.2.0 http://s1ral-cvmfs.openhtc.io DIRECT
2025-06-02 02:43:24 (13120): Guest Log: [INFO] Environment HTTP proxy: not set
2025-06-02 02:43:24 (13120): Guest Log: [INFO] Reading volunteer information
2025-06-02 02:43:24 (13120): Guest Log: [INFO] Requesting an X509 credential from LHC@home
2025-06-02 02:43:24 (13120): Guest Log: [INFO] Requesting an idtoken from LHC@home
2025-06-02 02:43:24 (13120): Guest Log: [INFO] CMS application starting. Check log files.
2025-06-02 02:43:24 (13120): Starting VM using VBoxManage interface. (boinc_311ab8b2ccbeb2fc, slot#6)
2025-06-02 02:43:31 (13120): Successfully started VM. (PID = '11072')
2025-06-02 02:43:31 (13120): Reporting VM Process ID to BOINC.
2025-06-02 02:43:31 (13120): Guest Log: BIOS: VirtualBox 6.0.14
2025-06-02 02:43:31 (13120): Guest Log: CPUID EDX: 0x178bfbff
2025-06-02 02:43:31 (13120): Guest Log: BIOS: AHCI 0-P#0: PCHS=16383/16/63 LCHS=1024/255/63 0x0000000002800000 sectors
2025-06-02 02:43:31 (13120): VM state change detected. (old = 'poweredoff', new = 'running')
2025-06-02 02:43:31 (13120): Detected: Web Application Enabled (http://localhost:55057)
2025-06-02 02:43:32 (13120): VM state change detected. (old = 'running', new = 'paused')
2025-06-02 02:43:50 (13120): Preference change detected
2025-06-02 02:43:50 (13120): Setting CPU throttle for VM. (100%)
2025-06-02 02:43:51 (13120): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 300 seconds) or (Vbox_job.xml: 600 seconds))
2025-06-02 02:43:51 (13120): VM state change detected. (old = 'paused', new = 'running')
2025-06-02 02:43:52 (13120): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032
2025-06-02 02:43:52 (13120): Guest Log: BIOS: Booting from Hard Disk...
2025-06-02 02:43:55 (13120): Guest Log: BIOS: KBD: unsupported int 16h function 03
2025-06-02 02:43:55 (13120): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 
2025-06-02 02:44:01 (13120): VM state change detected. (old = 'running', new = 'paused')
2025-06-02 02:44:21 (13120): VM state change detected. (old = 'paused', new = 'running')
2025-06-02 02:44:41 (13120): VM state change detected. (old = 'running', new = 'paused')
2025-06-02 02:45:01 (13120): VM state change detected. (old = 'paused', new = 'running')
2025-06-02 02:45:02 (13120): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds
2025-06-02 02:45:02 (13120): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0000000 (size 0x400000)
2025-06-02 02:45:04 (13120): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log
2025-06-02 02:45:04 (13120): Guest Log: 00:00:00.000205 main     Log opened 2025-06-02T06:44:43.793477000Z
2025-06-02 02:45:04 (13120): Guest Log: 00:00:00.000387 main     OS Product: Linux
2025-06-02 02:45:04 (13120): Guest Log: 00:00:00.000501 main     OS Release: 4.14.232-19.cernvm.x86_64
2025-06-02 02:45:04 (13120): Guest Log: 00:00:00.000569 main     OS Version: #1 SMP Fri Apr 30 17:12:25 CEST 2021
2025-06-02 02:45:04 (13120): Guest Log: 00:00:00.000638 main     Executable: /usr/sbin/VBoxService
2025-06-02 02:45:04 (13120): Guest Log: 00:00:00.000640 main     Process ID: 2293
2025-06-02 02:45:04 (13120): Guest Log: 00:00:00.000641 main     Package type: LINUX_64BITS_GENERIC
2025-06-02 02:45:04 (13120): Guest Log: 00:00:00.002153 main     5.2.6 r120293 started. Verbose level = 0
2025-06-02 02:45:24 (13120): Guest Log: [INFO] Mounting the shared directory
2025-06-02 02:45:24 (13120): Guest Log: [INFO] Shared directory mounted, enabling vboxmonitor
2025-06-02 02:45:25 (13120): Guest Log: [INFO] Sourcing essential functions from /cvmfs/grid.cern.ch
2025-06-02 02:45:25 (13120): Guest Log: [INFO] Testing connection to cern.ch
2025-06-02 02:45:25 (13120): Guest Log: [INFO] Testing connection to VCCS
2025-06-02 02:45:26 (13120): Guest Log: [INFO] Testing connection to HTCondor
2025-06-02 02:45:26 (13120): Guest Log: [INFO] Testing connection to WMAgent
2025-06-02 02:45:27 (13120): Guest Log: [INFO] Testing connection to EOSCMS
2025-06-02 02:45:27 (13120): Guest Log: [INFO] Testing connection to CMS-Factory
2025-06-02 02:45:28 (13120): Guest Log: [INFO] Testing connection to CMS-Frontier
2025-06-02 02:45:28 (13120): Guest Log: [INFO] Testing connection to Frontier
2025-06-02 02:45:29 (13120): Guest Log: [INFO] Testing connection to http://cms-frontier.openhtc.io:8080/FrontierProd/Frontier/
2025-06-02 02:45:30 (13120): Guest Log: [INFO] Could not find a local HTTP proxy
2025-06-02 02:45:30 (13120): Guest Log: [INFO] CVMFS and Frontier will have to use DIRECT connections
2025-06-02 02:45:30 (13120): Guest Log: [INFO] This makes the application less efficient
2025-06-02 02:45:30 (13120): Guest Log: [INFO] It also puts higher load on the project servers
2025-06-02 02:45:30 (13120): Guest Log: [INFO] Setting up a local HTTP proxy is highly recommended
2025-06-02 02:45:31 (13120): Guest Log: [INFO] Advice can be found in the project forum
2025-06-02 02:45:31 (13120): Guest Log: [INFO] Reloading and probing the CVMFS configuration
2025-06-02 02:45:40 (13120): Guest Log: [INFO] Probing /cvmfs/grid.cern.ch... OK
2025-06-02 02:45:44 (13120): Guest Log: [INFO] Probing /cvmfs/oasis.opensciencegrid.org... OK
2025-06-02 02:45:45 (13120): Guest Log: [INFO] Probing /cvmfs/singularity.opensciencegrid.org... OK
2025-06-02 02:45:45 (13120): Guest Log: [INFO] Probing /cvmfs/cms.cern.ch... OK
2025-06-02 02:45:47 (13120): Guest Log: [INFO] Probing /cvmfs/cms-ib.cern.ch... OK
2025-06-02 02:45:49 (13120): Guest Log: [INFO] Excerpt from "cvmfs_config stat": VERSION HOST PROXY
2025-06-02 02:45:49 (13120): Guest Log: [INFO] 2.7.2.0 http://s1ihep-cvmfs.openhtc.io:8080 DIRECT
2025-06-02 02:45:49 (13120): Guest Log: [INFO] Environment HTTP proxy: not set
2025-06-02 02:45:49 (13120): Guest Log: [INFO] Reading volunteer information
2025-06-02 02:45:52 (13120): Guest Log: [INFO] Requesting an X509 credential from LHC@home
2025-06-02 02:45:54 (13120): Guest Log: [INFO] Requesting an idtoken from LHC@home
2025-06-02 02:45:55 (13120): Guest Log: [INFO] CMS application starting. Check log files.
2025-06-02 02:48:01 (13120): VM state change detected. (old = 'running', new = 'paused')
2025-06-02 02:48:21 (13120): VM state change detected. (old = 'paused', new = 'running')
2025-06-02 02:48:21 (13120): Stopping VM.
2025-06-02 02:48:28 (13120): Successfully stopped VM.
2025-06-02 02:54:52 (5876): vboxwrapper version 26210
2025-06-02 02:54:52 (5876): BOINC client version: 8.0.2
2025-06-02 02:54:56 (5876): Detected: VirtualBox VboxManage Interface (Version: 6.0.14)
2025-06-02 02:54:56 (5876): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2025-06-02 02:54:57 (5876): Guest Log: BIOS: VirtualBox 6.0.14
2025-06-02 02:54:57 (5876): Guest Log: CPUID EDX: 0x178bfbff
2025-06-02 02:54:57 (5876): Guest Log: BIOS: AHCI 0-P#0: PCHS=16383/16/63 LCHS=1024/255/63 0x0000000002800000 sectors
2025-06-02 02:54:57 (5876): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032
2025-06-02 02:54:57 (5876): Guest Log: BIOS: Booting from Hard Disk...
2025-06-02 02:54:57 (5876): Guest Log: BIOS: KBD: unsupported int 16h function 03
2025-06-02 02:54:57 (5876): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 
2025-06-02 02:54:57 (5876): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds
2025-06-02 02:54:57 (5876): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0000000 (size 0x400000)
2025-06-02 02:54:57 (5876): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log
2025-06-02 02:54:57 (5876): Guest Log: 00:00:00.000205 main     Log opened 2025-06-02T06:44:43.793477000Z
2025-06-02 02:54:57 (5876): Guest Log: 00:00:00.000387 main     OS Product: Linux
2025-06-02 02:54:57 (5876): Guest Log: 00:00:00.000501 main     OS Release: 4.14.232-19.cernvm.x86_64
2025-06-02 02:54:57 (5876): Guest Log: 00:00:00.000569 main     OS Version: #1 SMP Fri Apr 30 17:12:25 CEST 2021
2025-06-02 02:54:57 (5876): Guest Log: 00:00:00.000638 main     Executable: /usr/sbin/VBoxService
2025-06-02 02:54:57 (5876): Guest Log: 00:00:00.000640 main     Process ID: 2293
2025-06-02 02:54:57 (5876): Guest Log: 00:00:00.000641 main     Package type: LINUX_64BITS_GENERIC
2025-06-02 02:54:57 (5876): Guest Log: 00:00:00.002153 main     5.2.6 r120293 started. Verbose level = 0
2025-06-02 02:54:57 (5876): Guest Log: [INFO] Mounting the shared directory
2025-06-02 02:54:57 (5876): Guest Log: [INFO] Shared directory mounted, enabling vboxmonitor
2025-06-02 02:54:57 (5876): Guest Log: [INFO] Sourcing essential functions from /cvmfs/grid.cern.ch
2025-06-02 02:54:57 (5876): Guest Log: [INFO] Testing connection to cern.ch
2025-06-02 02:54:57 (5876): Guest Log: [INFO] Testing connection to VCCS
2025-06-02 02:54:57 (5876): Guest Log: [INFO] Testing connection to HTCondor
2025-06-02 02:54:57 (5876): Guest Log: [INFO] Testing connection to WMAgent
2025-06-02 02:54:57 (5876): Guest Log: [INFO] Testing connection to EOSCMS
2025-06-02 02:54:57 (5876): Guest Log: [INFO] Testing connection to CMS-Factory
2025-06-02 02:54:57 (5876): Guest Log: [INFO] Testing connection to CMS-Frontier
2025-06-02 02:54:57 (5876): Guest Log: [INFO] Testing connection to Frontier
2025-06-02 02:54:57 (5876): Guest Log: [INFO] Testing connection to http://cms-frontier.openhtc.io:8080/FrontierProd/Frontier/
2025-06-02 02:54:57 (5876): Guest Log: [INFO] Could not find a local HTTP proxy
2025-06-02 02:54:57 (5876): Guest Log: [INFO] CVMFS and Frontier will have to use DIRECT connections
2025-06-02 02:54:57 (5876): Guest Log: [INFO] This makes the application less efficient
2025-06-02 02:54:57 (5876): Guest Log: [INFO] It also puts higher load on the project servers
2025-06-02 02:54:57 (5876): Guest Log: [INFO] Setting up a local HTTP proxy is highly recommended
2025-06-02 02:54:57 (5876): Guest Log: [INFO] Advice can be found in the project forum
2025-06-02 02:54:57 (5876): Guest Log: [INFO] Reloading and probing the CVMFS configuration
2025-06-02 02:54:57 (5876): Guest Log: [INFO] Probing /cvmfs/grid.cern.ch... OK
2025-06-02 02:54:57 (5876): Guest Log: [INFO] Probing /cvmfs/oasis.opensciencegrid.org... OK
2025-06-02 02:54:57 (5876): Guest Log: [INFO] Probing /cvmfs/singularity.opensciencegrid.org... OK
2025-06-02 02:54:57 (5876): Guest Log: [INFO] Probing /cvmfs/cms.cern.ch... OK
2025-06-02 02:54:57 (5876): Guest Log: [INFO] Probing /cvmfs/cms-ib.cern.ch... OK
2025-06-02 02:54:57 (5876): Guest Log: [INFO] Excerpt from "cvmfs_config stat": VERSION HOST PROXY
2025-06-02 02:54:57 (5876): Guest Log: [INFO] 2.7.2.0 http://s1ihep-cvmfs.openhtc.io:8080 DIRECT
2025-06-02 02:54:57 (5876): Guest Log: [INFO] Environment HTTP proxy: not set
2025-06-02 02:54:57 (5876): Guest Log: [INFO] Reading volunteer information
2025-06-02 02:54:57 (5876): Guest Log: [INFO] Requesting an X509 credential from LHC@home
2025-06-02 02:54:57 (5876): Guest Log: [INFO] Requesting an idtoken from LHC@home
2025-06-02 02:54:57 (5876): Guest Log: [INFO] CMS application starting. Check log files.
2025-06-02 02:54:57 (5876): Starting VM using VBoxManage interface. (boinc_311ab8b2ccbeb2fc, slot#6)
2025-06-02 02:55:06 (5876): Successfully started VM. (PID = '13684')
2025-06-02 02:55:06 (5876): Reporting VM Process ID to BOINC.
2025-06-02 02:55:06 (5876): VM state change detected. (old = 'poweredoff', new = 'running')
2025-06-02 02:55:06 (5876): Detected: Web Application Enabled (http://localhost:55057)
2025-06-02 02:55:07 (5876): VM state change detected. (old = 'running', new = 'paused')
2025-06-02 02:55:14 (5876): Preference change detected
2025-06-02 02:55:14 (5876): Setting CPU throttle for VM. (100%)
2025-06-02 02:55:15 (5876): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 300 seconds) or (Vbox_job.xml: 600 seconds))
2025-06-02 02:55:15 (5876): VM state change detected. (old = 'paused', new = 'running')
2025-06-02 03:06:37 (5876): VM state change detected. (old = 'running', new = 'paused')
2025-06-02 03:06:57 (5876): VM state change detected. (old = 'paused', new = 'running')
2025-06-02 03:10:17 (5876): VM state change detected. (old = 'running', new = 'paused')
2025-06-02 03:10:38 (5876): VM state change detected. (old = 'paused', new = 'running')
2025-06-02 03:12:18 (5876): VM state change detected. (old = 'running', new = 'paused')
2025-06-02 03:12:37 (5876): VM state change detected. (old = 'paused', new = 'running')
2025-06-02 03:19:19 (5876): VM state change detected. (old = 'running', new = 'paused')
2025-06-02 03:19:38 (5876): VM state change detected. (old = 'paused', new = 'running')
2025-06-02 03:20:09 (5876): VM state change detected. (old = 'running', new = 'paused')
2025-06-02 03:20:29 (5876): VM state change detected. (old = 'paused', new = 'running')
2025-06-02 03:30:20 (5876): VM state change detected. (old = 'running', new = 'paused')
2025-06-02 03:30:41 (5876): VM state change detected. (old = 'paused', new = 'running')
2025-06-02 03:45:12 (5876): VM state change detected. (old = 'running', new = 'paused')
2025-06-02 03:45:32 (5876): VM state change detected. (old = 'paused', new = 'running')
2025-06-02 03:46:52 (5876): VM state change detected. (old = 'running', new = 'paused')
2025-06-02 03:47:12 (5876): VM state change detected. (old = 'paused', new = 'running')
2025-06-02 03:47:33 (5876): VM state change detected. (old = 'running', new = 'paused')
2025-06-02 03:47:53 (5876): VM state change detected. (old = 'paused', new = 'running')
2025-06-02 03:48:13 (5876): VM state change detected. (old = 'running', new = 'paused')
2025-06-02 03:48:33 (5876): VM state change detected. (old = 'paused', new = 'running')
2025-06-02 03:48:43 (5876): VM state change detected. (old = 'running', new = 'paused')
2025-06-02 03:49:03 (5876): VM state change detected. (old = 'paused', new = 'running')
2025-06-02 03:49:44 (5876): VM state change detected. (old = 'running', new = 'paused')
2025-06-02 03:50:03 (5876): VM state change detected. (old = 'paused', new = 'running')
2025-06-02 03:50:13 (5876): VM state change detected. (old = 'running', new = 'paused')
2025-06-02 03:50:33 (5876): VM state change detected. (old = 'paused', new = 'running')
2025-06-02 03:50:43 (5876): VM state change detected. (old = 'running', new = 'paused')
2025-06-02 03:51:03 (5876): VM state change detected. (old = 'paused', new = 'running')
2025-06-02 03:51:23 (5876): VM state change detected. (old = 'running', new = 'paused')
2025-06-02 03:51:43 (5876): VM state change detected. (old = 'paused', new = 'running')
2025-06-02 03:52:03 (5876): VM state change detected. (old = 'running', new = 'paused')
2025-06-02 03:52:23 (5876): VM state change detected. (old = 'paused', new = 'running')
2025-06-02 03:52:42 (5876): VM state change detected. (old = 'running', new = 'paused')
2025-06-02 03:53:02 (5876): VM state change detected. (old = 'paused', new = 'running')
2025-06-02 03:53:12 (5876): VM state change detected. (old = 'running', new = 'paused')
2025-06-02 03:53:33 (5876): VM state change detected. (old = 'paused', new = 'running')
2025-06-02 03:54:13 (5876): VM state change detected. (old = 'running', new = 'paused')
2025-06-02 03:54:33 (5876): VM state change detected. (old = 'paused', new = 'running')
2025-06-02 03:54:43 (5876): VM state change detected. (old = 'running', new = 'paused')
2025-06-02 03:55:03 (5876): VM state change detected. (old = 'paused', new = 'running')
2025-06-02 03:55:23 (5876): VM state change detected. (old = 'running', new = 'paused')
2025-06-02 03:55:43 (5876): VM state change detected. (old = 'paused', new = 'running')
2025-06-02 03:56:03 (5876): VM state change detected. (old = 'running', new = 'paused')
2025-06-02 03:56:23 (5876): VM state change detected. (old = 'paused', new = 'running')
2025-06-02 03:57:13 (5876): VM state change detected. (old = 'running', new = 'paused')
2025-06-02 03:57:33 (5876): VM state change detected. (old = 'paused', new = 'running')
2025-06-02 03:58:04 (5876): VM state change detected. (old = 'running', new = 'paused')
2025-06-02 03:58:24 (5876): VM state change detected. (old = 'paused', new = 'running')
2025-06-02 03:58:44 (5876): VM state change detected. (old = 'running', new = 'paused')
2025-06-02 03:59:04 (5876): VM state change detected. (old = 'paused', new = 'running')
2025-06-02 03:59:33 (5876): VM state change detected. (old = 'running', new = 'paused')
2025-06-02 03:59:53 (5876): VM state change detected. (old = 'paused', new = 'running')
2025-06-02 04:00:34 (5876): VM state change detected. (old = 'running', new = 'paused')
2025-06-02 04:00:54 (5876): VM state change detected. (old = 'paused', new = 'running')
2025-06-02 04:01:14 (5876): VM state change detected. (old = 'running', new = 'paused')
2025-06-02 04:01:34 (5876): VM state change detected. (old = 'paused', new = 'running')
2025-06-02 04:01:54 (5876): VM state change detected. (old = 'running', new = 'paused')
2025-06-02 04:02:14 (5876): VM state change detected. (old = 'paused', new = 'running')
2025-06-02 04:02:34 (5876): VM state change detected. (old = 'running', new = 'paused')
2025-06-02 04:02:54 (5876): VM state change detected. (old = 'paused', new = 'running')
2025-06-02 04:03:34 (5876): VM state change detected. (old = 'running', new = 'paused')
2025-06-02 04:03:54 (5876): VM state change detected. (old = 'paused', new = 'running')
2025-06-02 04:04:14 (5876): VM state change detected. (old = 'running', new = 'paused')
2025-06-02 04:04:35 (5876): VM state change detected. (old = 'paused', new = 'running')
2025-06-02 04:04:54 (5876): VM state change detected. (old = 'running', new = 'paused')
2025-06-02 04:05:14 (5876): VM state change detected. (old = 'paused', new = 'running')
2025-06-02 04:05:34 (5876): VM state change detected. (old = 'running', new = 'paused')
2025-06-02 04:05:55 (5876): VM state change detected. (old = 'paused', new = 'running')
2025-06-02 04:06:14 (5876): VM state change detected. (old = 'running', new = 'paused')
2025-06-02 04:06:34 (5876): VM state change detected. (old = 'paused', new = 'running')
2025-06-02 04:07:05 (5876): VM state change detected. (old = 'running', new = 'paused')
2025-06-02 04:07:24 (5876): VM state change detected. (old = 'paused', new = 'running')
2025-06-02 04:07:45 (5876): VM state change detected. (old = 'running', new = 'paused')
2025-06-02 04:08:05 (5876): VM state change detected. (old = 'paused', new = 'running')
2025-06-02 04:08:25 (5876): VM state change detected. (old = 'running', new = 'paused')
2025-06-02 04:08:45 (5876): VM state change detected. (old = 'paused', new = 'running')
2025-06-02 04:09:24 (5876): VM state change detected. (old = 'running', new = 'paused')
2025-06-02 04:09:44 (5876): VM state change detected. (old = 'paused', new = 'running')
2025-06-02 04:09:54 (5876): VM state change detected. (old = 'running', new = 'paused')
2025-06-02 04:10:14 (5876): VM state change detected. (old = 'paused', new = 'running')
2025-06-02 04:10:25 (5876): VM state change detected. (old = 'running', new = 'paused')
2025-06-02 04:10:45 (5876): VM state change detected. (old = 'paused', new = 'running')
2025-06-02 04:11:05 (5876): VM state change detected. (old = 'running', new = 'paused')
2025-06-02 04:11:25 (5876): VM state change detected. (old = 'paused', new = 'running')
2025-06-02 04:11:45 (5876): VM state change detected. (old = 'running', new = 'paused')
2025-06-02 04:12:05 (5876): VM state change detected. (old = 'paused', new = 'running')
2025-06-02 04:12:55 (5876): VM state change detected. (old = 'running', new = 'paused')
2025-06-02 04:13:15 (5876): VM state change detected. (old = 'paused', new = 'running')
2025-06-02 04:13:35 (5876): VM state change detected. (old = 'running', new = 'paused')
2025-06-02 04:13:55 (5876): VM state change detected. (old = 'paused', new = 'running')
2025-06-02 04:14:05 (5876): VM state change detected. (old = 'running', new = 'paused')
2025-06-02 04:14:25 (5876): VM state change detected. (old = 'paused', new = 'running')
2025-06-02 04:14:35 (5876): VM state change detected. (old = 'running', new = 'paused')
2025-06-02 04:14:55 (5876): VM state change detected. (old = 'paused', new = 'running')
2025-06-02 04:15:15 (5876): VM state change detected. (old = 'running', new = 'paused')
2025-06-02 04:15:36 (5876): VM state change detected. (old = 'paused', new = 'running')
2025-06-02 04:16:05 (5876): VM state change detected. (old = 'running', new = 'paused')
2025-06-02 04:16:25 (5876): VM state change detected. (old = 'paused', new = 'running')
2025-06-02 04:16:45 (5876): VM state change detected. (old = 'running', new = 'paused')
2025-06-02 04:17:05 (5876): VM state change detected. (old = 'paused', new = 'running')
2025-06-02 04:17:26 (5876): VM state change detected. (old = 'running', new = 'paused')
2025-06-02 04:17:46 (5876): VM state change detected. (old = 'paused', new = 'running')
2025-06-02 04:18:06 (5876): VM state change detected. (old = 'running', new = 'paused')
2025-06-02 04:18:26 (5876): VM state change detected. (old = 'paused', new = 'running')
2025-06-02 04:18:45 (5876): VM state change detected. (old = 'running', new = 'paused')
2025-06-02 04:19:06 (5876): VM state change detected. (old = 'paused', new = 'running')
2025-06-02 04:19:35 (5876): VM state change detected. (old = 'running', new = 'paused')
2025-06-02 04:19:55 (5876): VM state change detected. (old = 'paused', new = 'running')
2025-06-02 04:20:05 (5876): VM state change detected. (old = 'running', new = 'paused')
2025-06-02 04:20:25 (5876): VM state change detected. (old = 'paused', new = 'running')
2025-06-02 04:20:35 (5876): VM state change detected. (old = 'running', new = 'paused')
2025-06-02 04:20:55 (5876): VM state change detected. (old = 'paused', new = 'running')
2025-06-02 04:21:05 (5876): VM state change detected. (old = 'running', new = 'paused')
2025-06-02 04:21:26 (5876): VM state change detected. (old = 'paused', new = 'running')
2025-06-02 04:21:46 (5876): VM state change detected. (old = 'running', new = 'paused')
2025-06-02 04:22:06 (5876): VM state change detected. (old = 'paused', new = 'running')
2025-06-02 04:22:26 (5876): VM state change detected. (old = 'running', new = 'paused')
2025-06-02 04:22:46 (5876): VM state change detected. (old = 'paused', new = 'running')
2025-06-02 04:23:06 (5876): VM state change detected. (old = 'running', new = 'paused')
2025-06-02 04:23:26 (5876): VM state change detected. (old = 'paused', new = 'running')
2025-06-02 04:23:46 (5876): VM state change detected. (old = 'running', new = 'paused')
2025-06-02 04:24:06 (5876): VM state change detected. (old = 'paused', new = 'running')
2025-06-02 04:24:56 (5876): VM state change detected. (old = 'running', new = 'paused')
2025-06-02 04:25:16 (5876): VM state change detected. (old = 'paused', new = 'running')
2025-06-02 04:25:26 (5876): VM state change detected. (old = 'running', new = 'paused')
2025-06-02 04:25:46 (5876): VM state change detected. (old = 'paused', new = 'running')
2025-06-02 04:25:56 (5876): VM state change detected. (old = 'running', new = 'paused')
2025-06-02 04:26:16 (5876): VM state change detected. (old = 'paused', new = 'running')
2025-06-02 04:26:26 (5876): VM state change detected. (old = 'running', new = 'paused')
2025-06-02 04:26:46 (5876): VM state change detected. (old = 'paused', new = 'running')
2025-06-02 04:26:56 (5876): VM state change detected. (old = 'running', new = 'paused')
2025-06-02 04:27:16 (5876): VM state change detected. (old = 'paused', new = 'running')
2025-06-02 04:27:26 (5876): VM state change detected. (old = 'running', new = 'paused')
2025-06-02 04:27:46 (5876): VM state change detected. (old = 'paused', new = 'running')
2025-06-02 04:28:06 (5876): VM state change detected. (old = 'running', new = 'paused')
2025-06-02 04:28:26 (5876): VM state change detected. (old = 'paused', new = 'running')
2025-06-02 04:28:36 (5876): VM state change detected. (old = 'running', new = 'paused')
2025-06-02 04:28:56 (5876): VM state change detected. (old = 'paused', new = 'running')
2025-06-02 04:29:06 (5876): VM state change detected. (old = 'running', new = 'paused')
2025-06-02 04:29:27 (5876): VM state change detected. (old = 'paused', new = 'running')
2025-06-02 04:29:47 (5876): VM state change detected. (old = 'running', new = 'paused')
2025-06-02 04:30:07 (5876): VM state change detected. (old = 'paused', new = 'running')
2025-06-02 04:30:27 (5876): VM state change detected. (old = 'running', new = 'paused')
2025-06-02 04:30:47 (5876): VM state change detected. (old = 'paused', new = 'running')
2025-06-02 04:31:27 (5876): VM state change detected. (old = 'running', new = 'paused')
2025-06-02 04:31:46 (5876): VM state change detected. (old = 'paused', new = 'running')
2025-06-02 04:32:06 (5876): VM state change detected. (old = 'running', new = 'paused')
2025-06-02 04:32:27 (5876): VM state change detected. (old = 'paused', new = 'running')
2025-06-02 04:32:37 (5876): VM state change detected. (old = 'running', new = 'paused')
2025-06-02 04:32:57 (5876): VM state change detected. (old = 'paused', new = 'running')
2025-06-02 04:33:07 (5876): VM state change detected. (old = 'running', new = 'paused')
2025-06-02 04:33:27 (5876): VM state change detected. (old = 'paused', new = 'running')
2025-06-02 04:33:37 (5876): VM state change detected. (old = 'running', new = 'paused')
2025-06-02 04:33:57 (5876): VM state change detected. (old = 'paused', new = 'running')
2025-06-02 04:34:07 (5876): VM state change detected. (old = 'running', new = 'paused')
2025-06-02 04:34:28 (5876): VM state change detected. (old = 'paused', new = 'running')
2025-06-02 04:34:47 (5876): VM state change detected. (old = 'running', new = 'paused')
2025-06-02 04:35:08 (5876): VM state change detected. (old = 'paused', new = 'running')
2025-06-02 04:35:47 (5876): VM state change detected. (old = 'running', new = 'paused')
2025-06-02 04:36:07 (5876): VM state change detected. (old = 'paused', new = 'running')
2025-06-02 04:36:17 (5876): VM state change detected. (old = 'running', new = 'paused')
2025-06-02 04:36:39 (5876): VM state change detected. (old = 'paused', new = 'running')
2025-06-02 04:37:27 (5876): VM state change detected. (old = 'running', new = 'paused')
2025-06-02 04:37:47 (5876): VM state change detected. (old = 'paused', new = 'running')
2025-06-02 04:38:07 (5876): VM state change detected. (old = 'running', new = 'paused')
2025-06-02 04:38:28 (5876): VM state change detected. (old = 'paused', new = 'running')
2025-06-02 04:38:38 (5876): VM state change detected. (old = 'running', new = 'paused')
2025-06-02 04:38:59 (5876): VM state change detected. (old = 'paused', new = 'running')
2025-06-02 04:39:18 (5876): VM state change detected. (old = 'running', new = 'paused')
2025-06-02 04:39:38 (5876): VM state change detected. (old = 'paused', new = 'running')
2025-06-02 04:39:49 (5876): VM state change detected. (old = 'running', new = 'paused')
2025-06-02 04:40:09 (5876): VM state change detected. (old = 'paused', new = 'running')
2025-06-02 04:40:28 (5876): VM state change detected. (old = 'running', new = 'paused')
2025-06-02 04:40:48 (5876): VM state change detected. (old = 'paused', new = 'running')
2025-06-02 04:41:08 (5876): VM state change detected. (old = 'running', new = 'paused')
2025-06-02 04:41:28 (5876): VM state change detected. (old = 'paused', new = 'running')
2025-06-02 04:41:31 (5876): Status Report: Job Duration: '64800.000000'
2025-06-02 04:41:31 (5876): Status Report: Elapsed Time: '6000.590275'
2025-06-02 04:41:31 (5876): Status Report: CPU Time: '20493.421875'
2025-06-02 04:41:48 (5876): VM state change detected. (old = 'running', new = 'paused')
2025-06-02 04:42:08 (5876): VM state change detected. (old = 'paused', new = 'running')
2025-06-02 04:42:48 (5876): VM state change detected. (old = 'running', new = 'paused')
2025-06-02 04:43:08 (5876): VM state change detected. (old = 'paused', new = 'running')
2025-06-02 04:47:40 (5876): VM state change detected. (old = 'running', new = 'paused')
2025-06-02 04:48:00 (5876): VM state change detected. (old = 'paused', new = 'running')
2025-06-02 04:48:30 (5876): VM state change detected. (old = 'running', new = 'paused')
2025-06-02 04:48:50 (5876): VM state change detected. (old = 'paused', new = 'running')
2025-06-02 04:49:10 (5876): VM state change detected. (old = 'running', new = 'paused')
2025-06-02 04:49:30 (5876): VM state change detected. (old = 'paused', new = 'running')
2025-06-02 04:49:40 (5876): VM state change detected. (old = 'running', new = 'paused')
2025-06-02 04:50:00 (5876): VM state change detected. (old = 'paused', new = 'running')
2025-06-02 04:50:20 (5876): VM state change detected. (old = 'running', new = 'paused')
2025-06-02 04:50:40 (5876): VM state change detected. (old = 'paused', new = 'running')
2025-06-02 04:50:50 (5876): VM state change detected. (old = 'running', new = 'paused')
2025-06-02 04:51:10 (5876): VM state change detected. (old = 'paused', new = 'running')
2025-06-02 04:51:20 (5876): VM state change detected. (old = 'running', new = 'paused')
2025-06-02 04:51:40 (5876): VM state change detected. (old = 'paused', new = 'running')
2025-06-02 04:52:00 (5876): VM state change detected. (old = 'running', new = 'paused')
2025-06-02 04:52:20 (5876): VM state change detected. (old = 'paused', new = 'running')
2025-06-02 04:52:41 (5876): VM state change detected. (old = 'running', new = 'paused')
2025-06-02 04:53:00 (5876): VM state change detected. (old = 'paused', new = 'running')
2025-06-02 04:53:10 (5876): VM state change detected. (old = 'running', new = 'paused')
2025-06-02 04:53:30 (5876): VM state change detected. (old = 'paused', new = 'running')
2025-06-02 04:53:40 (5876): VM state change detected. (old = 'running', new = 'paused')
2025-06-02 04:54:00 (5876): VM state change detected. (old = 'paused', new = 'running')
2025-06-02 04:54:19 (5876): VM state change detected. (old = 'running', new = 'paused')
2025-06-02 04:54:40 (5876): VM state change detected. (old = 'paused', new = 'running')
2025-06-02 04:54:50 (5876): VM state change detected. (old = 'running', new = 'paused')
2025-06-02 04:55:11 (5876): VM state change detected. (old = 'paused', new = 'running')
2025-06-02 04:55:31 (5876): VM state change detected. (old = 'running', new = 'paused')
2025-06-02 04:55:50 (5876): VM state change detected. (old = 'paused', new = 'running')
2025-06-02 04:56:00 (5876): VM state change detected. (old = 'running', new = 'paused')
2025-06-02 04:56:20 (5876): VM state change detected. (old = 'paused', new = 'running')
2025-06-02 04:56:30 (5876): VM state change detected. (old = 'running', new = 'paused')
2025-06-02 04:56:50 (5876): VM state change detected. (old = 'paused', new = 'running')
2025-06-02 04:57:00 (5876): VM state change detected. (old = 'running', new = 'paused')
2025-06-02 04:57:20 (5876): VM state change detected. (old = 'paused', new = 'running')
2025-06-02 04:57:30 (5876): VM state change detected. (old = 'running', new = 'paused')
2025-06-02 04:57:50 (5876): VM state change detected. (old = 'paused', new = 'running')
2025-06-02 04:58:10 (5876): VM state change detected. (old = 'running', new = 'paused')
2025-06-02 04:58:30 (5876): VM state change detected. (old = 'paused', new = 'running')
2025-06-02 04:58:40 (5876): VM state change detected. (old = 'running', new = 'paused')
2025-06-02 04:59:00 (5876): VM state change detected. (old = 'paused', new = 'running')
2025-06-02 04:59:41 (5876): VM state change detected. (old = 'running', new = 'paused')
2025-06-02 05:00:01 (5876): VM state change detected. (old = 'paused', new = 'running')
2025-06-02 05:00:11 (5876): VM state change detected. (old = 'running', new = 'paused')
2025-06-02 05:00:31 (5876): VM state change detected. (old = 'paused', new = 'running')
2025-06-02 05:00:41 (5876): VM state change detected. (old = 'running', new = 'paused')
2025-06-02 05:01:01 (5876): VM state change detected. (old = 'paused', new = 'running')
2025-06-02 05:01:11 (5876): VM state change detected. (old = 'running', new = 'paused')
2025-06-02 05:01:31 (5876): VM state change detected. (old = 'paused', new = 'running')
2025-06-02 05:01:41 (5876): VM state change detected. (old = 'running', new = 'paused')
2025-06-02 05:02:01 (5876): VM state change detected. (old = 'paused', new = 'running')
2025-06-02 05:02:11 (5876): VM state change detected. (old = 'running', new = 'paused')
2025-06-02 05:02:31 (5876): VM state change detected. (old = 'paused', new = 'running')
2025-06-02 05:02:41 (5876): VM state change detected. (old = 'running', new = 'paused')
2025-06-02 05:03:01 (5876): VM state change detected. (old = 'paused', new = 'running')
2025-06-02 05:03:11 (5876): VM state change detected. (old = 'running', new = 'paused')
2025-06-02 05:03:31 (5876): VM state change detected. (old = 'paused', new = 'running')
2025-06-02 05:03:50 (5876): VM state change detected. (old = 'running', new = 'paused')
2025-06-02 05:04:10 (5876): VM state change detected. (old = 'paused', new = 'running')
2025-06-02 05:04:20 (5876): VM state change detected. (old = 'running', new = 'paused')
2025-06-02 05:04:40 (5876): VM state change detected. (old = 'paused', new = 'running')
2025-06-02 05:04:51 (5876): VM state change detected. (old = 'running', new = 'paused')
2025-06-02 05:05:12 (5876): VM state change detected. (old = 'paused', new = 'running')
2025-06-02 05:05:31 (5876): VM state change detected. (old = 'running', new = 'paused')
2025-06-02 05:05:51 (5876): VM state change detected. (old = 'paused', new = 'running')
2025-06-02 05:06:01 (5876): VM state change detected. (old = 'running', new = 'paused')
2025-06-02 05:06:21 (5876): VM state change detected. (old = 'paused', new = 'running')
2025-06-02 05:06:32 (5876): VM state change detected. (old = 'running', new = 'paused')
2025-06-02 05:06:51 (5876): VM state change detected. (old = 'paused', new = 'running')
2025-06-02 05:07:11 (5876): VM state change detected. (old = 'running', new = 'paused')
2025-06-02 05:07:31 (5876): VM state change detected. (old = 'paused', new = 'running')
2025-06-02 05:07:51 (5876): VM state change detected. (old = 'running', new = 'paused')
2025-06-02 05:08:11 (5876): VM state change detected. (old = 'paused', new = 'running')
2025-06-02 05:08:21 (5876): VM state change detected. (old = 'running', new = 'paused')
2025-06-02 05:08:42 (5876): VM state change detected. (old = 'paused', new = 'running')
2025-06-02 05:08:51 (5876): VM state change detected. (old = 'running', new = 'paused')
2025-06-02 05:09:12 (5876): VM state change detected. (old = 'paused', new = 'running')
2025-06-02 05:09:22 (5876): VM state change detected. (old = 'running', new = 'paused')
2025-06-02 05:09:42 (5876): VM state change detected. (old = 'paused', new = 'running')
2025-06-02 05:09:52 (5876): VM state change detected. (old = 'running', new = 'paused')
2025-06-02 05:10:12 (5876): VM state change detected. (old = 'paused', new = 'running')
2025-06-02 05:10:33 (5876): VM state change detected. (old = 'running', new = 'paused')
2025-06-02 05:10:52 (5876): VM state change detected. (old = 'paused', new = 'running')
2025-06-02 05:11:23 (5876): VM state change detected. (old = 'running', new = 'paused')
2025-06-02 05:11:42 (5876): VM state change detected. (old = 'paused', new = 'running')
2025-06-02 05:11:52 (5876): VM state change detected. (old = 'running', new = 'paused')
2025-06-02 05:12:12 (5876): VM state change detected. (old = 'paused', new = 'running')
2025-06-02 05:12:22 (5876): VM state change detected. (old = 'running', new = 'paused')
2025-06-02 05:12:42 (5876): VM state change detected. (old = 'paused', new = 'running')
2025-06-02 05:12:52 (5876): VM state change detected. (old = 'running', new = 'paused')
2025-06-02 05:13:12 (5876): VM state change detected. (old = 'paused', new = 'running')
2025-06-02 05:13:22 (5876): VM state change detected. (old = 'running', new = 'paused')
2025-06-02 05:13:42 (5876): VM state change detected. (old = 'paused', new = 'running')
2025-06-02 05:14:02 (5876): VM state change detected. (old = 'running', new = 'paused')
2025-06-02 05:14:22 (5876): VM state change detected. (old = 'paused', new = 'running')
2025-06-02 05:14:42 (5876): VM state change detected. (old = 'running', new = 'paused')
2025-06-02 05:15:02 (5876): VM state change detected. (old = 'paused', new = 'running')
2025-06-02 05:15:12 (5876): VM state change detected. (old = 'running', new = 'paused')
2025-06-02 05:15:32 (5876): VM state change detected. (old = 'paused', new = 'running')
2025-06-02 05:15:42 (5876): VM state change detected. (old = 'running', new = 'paused')
2025-06-02 05:16:03 (5876): VM state change detected. (old = 'paused', new = 'running')
2025-06-02 05:16:23 (5876): VM state change detected. (old = 'running', new = 'paused')
2025-06-02 05:16:43 (5876): VM state change detected. (old = 'paused', new = 'running')
2025-06-02 05:17:03 (5876): VM state change detected. (old = 'running', new = 'paused')
2025-06-02 05:17:23 (5876): VM state change detected. (old = 'paused', new = 'running')
2025-06-02 05:17:33 (5876): VM state change detected. (old = 'running', new = 'paused')
2025-06-02 05:17:53 (5876): VM state change detected. (old = 'paused', new = 'running')
2025-06-02 05:18:13 (5876): VM state change detected. (old = 'running', new = 'paused')
2025-06-02 05:18:33 (5876): VM state change detected. (old = 'paused', new = 'running')
2025-06-02 05:18:53 (5876): VM state change detected. (old = 'running', new = 'paused')
2025-06-02 05:19:13 (5876): VM state change detected. (old = 'paused', new = 'running')
2025-06-02 05:19:33 (5876): VM state change detected. (old = 'running', new = 'paused')
2025-06-02 05:19:52 (5876): VM state change detected. (old = 'paused', new = 'running')
2025-06-02 05:20:02 (5876): VM state change detected. (old = 'running', new = 'paused')
2025-06-02 05:20:42 (5876): VM state change detected. (old = 'paused', new = 'running')
2025-06-02 05:20:52 (5876): VM state change detected. (old = 'running', new = 'paused')
2025-06-02 05:21:13 (5876): VM state change detected. (old = 'paused', new = 'running')
2025-06-02 05:21:23 (5876): VM state change detected. (old = 'running', new = 'paused')
2025-06-02 05:21:43 (5876): VM state change detected. (old = 'paused', new = 'running')
2025-06-02 05:22:03 (5876): VM state change detected. (old = 'running', new = 'paused')
2025-06-02 05:22:23 (5876): VM state change detected. (old = 'paused', new = 'running')
2025-06-02 05:22:43 (5876): VM state change detected. (old = 'running', new = 'paused')
2025-06-02 05:23:03 (5876): VM state change detected. (old = 'paused', new = 'running')
2025-06-02 05:23:13 (5876): VM state change detected. (old = 'running', new = 'paused')
2025-06-02 05:23:33 (5876): VM state change detected. (old = 'paused', new = 'running')
2025-06-02 05:23:43 (5876): VM state change detected. (old = 'running', new = 'paused')
2025-06-02 05:24:03 (5876): VM state change detected. (old = 'paused', new = 'running')
2025-06-02 05:24:13 (5876): VM state change detected. (old = 'running', new = 'paused')
2025-06-02 05:24:33 (5876): VM state change detected. (old = 'paused', new = 'running')
2025-06-02 05:24:43 (5876): VM state change detected. (old = 'running', new = 'paused')
2025-06-02 05:25:03 (5876): VM state change detected. (old = 'paused', new = 'running')
2025-06-02 05:25:13 (5876): VM state change detected. (old = 'running', new = 'paused')
2025-06-02 05:25:33 (5876): VM state change detected. (old = 'paused', new = 'running')
2025-06-02 05:25:43 (5876): VM state change detected. (old = 'running', new = 'paused')
2025-06-02 05:26:03 (5876): VM state change detected. (old = 'paused', new = 'running')
2025-06-02 05:26:13 (5876): VM state change detected. (old = 'running', new = 'paused')
2025-06-02 05:26:33 (5876): VM state change detected. (old = 'paused', new = 'running')
2025-06-02 05:26:43 (5876): VM state change detected. (old = 'running', new = 'paused')
2025-06-02 05:27:03 (5876): VM state change detected. (old = 'paused', new = 'running')
2025-06-02 05:27:34 (5876): VM state change detected. (old = 'running', new = 'paused')
2025-06-02 05:27:54 (5876): VM state change detected. (old = 'paused', new = 'running')
2025-06-02 05:28:14 (5876): VM state change detected. (old = 'running', new = 'paused')
2025-06-02 05:28:34 (5876): VM state change detected. (old = 'paused', new = 'running')
2025-06-02 05:29:04 (5876): VM state change detected. (old = 'running', new = 'paused')
2025-06-02 05:29:24 (5876): VM state change detected. (old = 'paused', new = 'running')
2025-06-02 05:30:44 (5876): VM state change detected. (old = 'running', new = 'paused')
2025-06-02 05:31:04 (5876): VM state change detected. (old = 'paused', new = 'running')
2025-06-02 05:32:53 (5876): Stopping VM.
2025-06-02 05:33:16 (5876): Successfully stopped VM.
2025-06-02 12:25:50 (4724): vboxwrapper version 26210
2025-06-02 12:25:50 (4724): BOINC client version: 8.0.2
2025-06-02 12:25:56 (4724): Detected: VirtualBox VboxManage Interface (Version: 6.0.14)
2025-06-02 12:25:56 (4724): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2025-06-02 12:25:57 (4724): Starting VM using VBoxManage interface. (boinc_311ab8b2ccbeb2fc, slot#6)
2025-06-02 12:26:22 (4724): Successfully started VM. (PID = '16472')
2025-06-02 12:26:22 (4724): Reporting VM Process ID to BOINC.
2025-06-02 12:26:22 (4724): VM state change detected. (old = 'poweredoff', new = 'running')
2025-06-02 12:26:22 (4724): Detected: Web Application Enabled (http://localhost:55057)
2025-06-02 12:26:22 (4724): Status Report: Job Duration: '64800.000000'
2025-06-02 12:26:22 (4724): Status Report: Elapsed Time: '7477.643698'
2025-06-02 12:26:22 (4724): Status Report: CPU Time: '26661.015625'
2025-06-02 12:26:22 (4724): Preference change detected
2025-06-02 12:26:22 (4724): Setting CPU throttle for VM. (100%)
2025-06-02 12:26:24 (4724): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 300 seconds) or (Vbox_job.xml: 600 seconds))
2025-06-02 12:26:25 (4724): Guest Log: 01:46:32.835835 timesync vgsvcTimeSyncWorker: Radical host time change: 24 816 430 000 000ns (HostNow=1 748 881 584 733 000 000 ns HostLast=1 748 856 768 303 000 000 ns)
2025-06-02 12:26:34 (4724): Guest Log: 01:46:42.845507 timesync vgsvcTimeSyncWorker: Radical guest time change: 25 645 412 476 000ns (GuestNow=1 748 881 594 754 445 000 ns GuestLast=1 748 855 949 341 969 000 ns fSetTimeLastLoop=true )
2025-06-02 12:36:38 (4724): Stopping VM.
2025-06-02 12:37:08 (4724): Successfully stopped VM.
2025-06-02 12:45:39 (19956): vboxwrapper version 26210
2025-06-02 12:45:39 (19956): BOINC client version: 8.0.2
2025-06-02 12:45:43 (19956): Detected: VirtualBox VboxManage Interface (Version: 6.0.14)
2025-06-02 12:45:44 (19956): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2025-06-02 12:45:44 (19956): Guest Log: 01:46:32.835835 timesync vgsvcTimeSyncWorker: Radical host time change: 24 816 430 000 000ns (HostNow=1 748 881 584 733 000 000 ns HostLast=1 748 856 768 303 000 000 ns)
2025-06-02 12:45:44 (19956): Guest Log: 01:46:42.845507 timesync vgsvcTimeSyncWorker: Radical guest time change: 25 645 412 476 000ns (GuestNow=1 748 881 594 754 445 000 ns GuestLast=1 748 855 949 341 969 000 ns fSetTimeLastLoop=true )
2025-06-02 12:45:44 (19956): Starting VM using VBoxManage interface. (boinc_311ab8b2ccbeb2fc, slot#6)
2025-06-02 12:46:03 (19956): Successfully started VM. (PID = '4212')
2025-06-02 12:46:03 (19956): Reporting VM Process ID to BOINC.
2025-06-02 12:46:03 (19956): VM state change detected. (old = 'poweredoff', new = 'running')
2025-06-02 12:46:03 (19956): Detected: Web Application Enabled (http://localhost:55057)
2025-06-02 12:46:03 (19956): Status Report: Job Duration: '64800.000000'
2025-06-02 12:46:03 (19956): Status Report: Elapsed Time: '8092.717345'
2025-06-02 12:46:03 (19956): Status Report: CPU Time: '26796.437500'
2025-06-02 12:46:03 (19956): Preference change detected
2025-06-02 12:46:03 (19956): Setting CPU throttle for VM. (100%)
2025-06-02 12:46:05 (19956): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 300 seconds) or (Vbox_job.xml: 600 seconds))
2025-06-02 12:47:01 (19956): Guest Log: [INFO] glidein exited with return value 0.
2025-06-02 12:47:01 (19956): Guest Log: [INFO] Shutting Down.
2025-06-02 12:47:01 (19956): VM Completion File Detected.
2025-06-02 12:47:01 (19956): VM Completion Message: glidein exited with return value 0.
.
2025-06-02 12:47:01 (19956): Powering off VM.
2025-06-02 12:47:03 (19956): Successfully stopped VM.
2025-06-02 12:47:03 (19956): Deregistering VM. (boinc_311ab8b2ccbeb2fc, slot#6)
2025-06-02 12:47:03 (19956): Removing network bandwidth throttle group from VM.
2025-06-02 12:47:04 (19956): Removing VM from VirtualBox.
2025-06-02 12:47:09 (19956): called boinc_finish(0)

</stderr_txt>
]]>

©2025 CERN