Name CMS_2890318_1736343477.719373_0
Workunit 229719536
Created 8 Jan 2025, 13:38:02 UTC
Sent 8 Jan 2025, 13:54:23 UTC
Report deadline 8 Feb 2025, 13:54:23 UTC
Received 19 Jan 2025, 13:55:13 UTC
Server state Over
Outcome Success
Client state Done
Exit status 0 (0x00000000)
Computer ID 10861531
Run time 6 hours 57 min 53 sec
CPU time 12 hours 52 min 57 sec
Validate state Valid
Credit 10,616.13
Device peak FLOPS 19.86 GFLOPS
Application version CMS Simulation v70.30 (vbox64_mt_mcore_cms)
windows_x86_64
Peak working set size 88.05 MB
Peak swap size 88.76 MB
Peak disk usage 5.68 GB

Stderr output

<core_client_version>8.0.2</core_client_version>
<![CDATA[
<stderr_txt>
 detected. (old = 'running', new = 'paused')
2025-01-18 21:39:41 (16792): VM state change detected. (old = 'paused', new = 'running')
2025-01-18 21:39:42 (16792): VM state change detected. (old = 'running', new = 'paused')
2025-01-18 21:40:02 (16792): VM state change detected. (old = 'paused', new = 'running')
2025-01-18 21:40:07 (16792): Guest Log: 00:41:21.200939 timesync vgsvcTimeSyncWorker: Radical host time change: 5 489 829 000 000ns (HostNow=1 737 232 806 731 000 000 ns HostLast=1 737 227 316 902 000 000 ns)
2025-01-18 21:40:11 (16792): VM state change detected. (old = 'running', new = 'paused')
2025-01-18 21:40:31 (16792): VM state change detected. (old = 'paused', new = 'running')
2025-01-18 21:40:37 (16792): Guest Log: 00:41:31.208783 timesync vgsvcTimeSyncWorker: Radical guest time change: 6 250 669 360 000ns (GuestNow=1 737 232 816 740 489 000 ns GuestLast=1 737 226 566 071 129 000 ns fSetTimeLastLoop=true )
2025-01-18 21:40:42 (16792): VM state change detected. (old = 'running', new = 'paused')
2025-01-18 21:41:02 (16792): VM state change detected. (old = 'paused', new = 'running')
2025-01-18 21:41:12 (16792): VM state change detected. (old = 'running', new = 'paused')
2025-01-18 21:41:32 (16792): VM state change detected. (old = 'paused', new = 'running')
2025-01-18 21:41:41 (16792): VM state change detected. (old = 'running', new = 'paused')
2025-01-18 21:42:01 (16792): VM state change detected. (old = 'paused', new = 'running')
2025-01-18 21:42:12 (16792): VM state change detected. (old = 'running', new = 'paused')
2025-01-18 21:42:32 (16792): VM state change detected. (old = 'paused', new = 'running')
2025-01-18 21:42:42 (16792): VM state change detected. (old = 'running', new = 'paused')
2025-01-18 21:43:02 (16792): VM state change detected. (old = 'paused', new = 'running')
2025-01-18 21:43:12 (16792): VM state change detected. (old = 'running', new = 'paused')
2025-01-18 21:43:32 (16792): VM state change detected. (old = 'paused', new = 'running')
2025-01-18 21:43:42 (16792): VM state change detected. (old = 'running', new = 'paused')
2025-01-18 21:44:02 (16792): VM state change detected. (old = 'paused', new = 'running')
2025-01-18 21:44:12 (16792): VM state change detected. (old = 'running', new = 'paused')
2025-01-18 21:44:32 (16792): VM state change detected. (old = 'paused', new = 'running')
2025-01-18 21:44:42 (16792): VM state change detected. (old = 'running', new = 'paused')
2025-01-18 21:45:03 (16792): VM state change detected. (old = 'paused', new = 'running')
2025-01-18 21:45:12 (16792): VM state change detected. (old = 'running', new = 'paused')
2025-01-18 21:45:32 (16792): VM state change detected. (old = 'paused', new = 'running')
2025-01-18 21:45:42 (16792): VM state change detected. (old = 'running', new = 'paused')
2025-01-18 21:46:02 (16792): VM state change detected. (old = 'paused', new = 'running')
2025-01-18 21:46:12 (16792): VM state change detected. (old = 'running', new = 'paused')
2025-01-18 21:46:32 (16792): VM state change detected. (old = 'paused', new = 'running')
2025-01-18 21:46:42 (16792): VM state change detected. (old = 'running', new = 'paused')
2025-01-18 21:47:02 (16792): VM state change detected. (old = 'paused', new = 'running')
2025-01-18 21:47:12 (16792): VM state change detected. (old = 'running', new = 'paused')
2025-01-18 21:47:32 (16792): VM state change detected. (old = 'paused', new = 'running')
2025-01-18 21:47:42 (16792): VM state change detected. (old = 'running', new = 'paused')
2025-01-18 21:48:03 (16792): VM state change detected. (old = 'paused', new = 'running')
2025-01-18 21:48:13 (16792): VM state change detected. (old = 'running', new = 'paused')
2025-01-18 21:48:33 (16792): VM state change detected. (old = 'paused', new = 'running')
2025-01-18 21:48:42 (16792): VM state change detected. (old = 'running', new = 'paused')
2025-01-18 21:49:02 (16792): VM state change detected. (old = 'paused', new = 'running')
2025-01-18 21:49:12 (16792): VM state change detected. (old = 'running', new = 'paused')
2025-01-18 21:49:33 (16792): VM state change detected. (old = 'paused', new = 'running')
2025-01-18 21:49:42 (16792): VM state change detected. (old = 'running', new = 'paused')
2025-01-18 21:50:02 (16792): VM state change detected. (old = 'paused', new = 'running')
2025-01-18 21:50:12 (16792): VM state change detected. (old = 'running', new = 'paused')
2025-01-18 21:50:32 (16792): VM state change detected. (old = 'paused', new = 'running')
2025-01-18 21:50:42 (16792): VM state change detected. (old = 'running', new = 'paused')
2025-01-18 21:51:02 (16792): VM state change detected. (old = 'paused', new = 'running')
2025-01-18 21:51:13 (16792): VM state change detected. (old = 'running', new = 'paused')
2025-01-18 21:51:33 (16792): VM state change detected. (old = 'paused', new = 'running')
2025-01-18 21:51:43 (16792): VM state change detected. (old = 'running', new = 'paused')
2025-01-18 21:52:03 (16792): VM state change detected. (old = 'paused', new = 'running')
2025-01-18 21:52:13 (16792): VM state change detected. (old = 'running', new = 'paused')
2025-01-18 21:52:33 (16792): VM state change detected. (old = 'paused', new = 'running')
2025-01-18 21:52:43 (16792): VM state change detected. (old = 'running', new = 'paused')
2025-01-18 21:53:03 (16792): VM state change detected. (old = 'paused', new = 'running')
2025-01-18 21:53:12 (16792): VM state change detected. (old = 'running', new = 'paused')
2025-01-18 21:53:32 (16792): VM state change detected. (old = 'paused', new = 'running')
2025-01-18 21:53:43 (16792): VM state change detected. (old = 'running', new = 'paused')
2025-01-18 21:54:03 (16792): VM state change detected. (old = 'paused', new = 'running')
2025-01-18 21:54:13 (16792): VM state change detected. (old = 'running', new = 'paused')
2025-01-18 21:54:33 (16792): VM state change detected. (old = 'paused', new = 'running')
2025-01-18 21:54:42 (16792): VM state change detected. (old = 'running', new = 'paused')
2025-01-18 21:55:02 (16792): VM state change detected. (old = 'paused', new = 'running')
2025-01-18 21:55:13 (16792): VM state change detected. (old = 'running', new = 'paused')
2025-01-18 21:55:33 (16792): VM state change detected. (old = 'paused', new = 'running')
2025-01-18 21:55:43 (16792): VM state change detected. (old = 'running', new = 'paused')
2025-01-18 21:56:03 (16792): VM state change detected. (old = 'paused', new = 'running')
2025-01-18 21:56:13 (16792): VM state change detected. (old = 'running', new = 'paused')
2025-01-18 21:56:33 (16792): VM state change detected. (old = 'paused', new = 'running')
2025-01-18 21:56:43 (16792): VM state change detected. (old = 'running', new = 'paused')
2025-01-18 21:57:03 (16792): VM state change detected. (old = 'paused', new = 'running')
2025-01-18 21:57:13 (16792): VM state change detected. (old = 'running', new = 'paused')
2025-01-18 21:57:33 (16792): VM state change detected. (old = 'paused', new = 'running')
2025-01-18 21:57:43 (16792): VM state change detected. (old = 'running', new = 'paused')
2025-01-18 21:58:03 (16792): VM state change detected. (old = 'paused', new = 'running')
2025-01-18 21:58:13 (16792): VM state change detected. (old = 'running', new = 'paused')
2025-01-18 21:58:33 (16792): VM state change detected. (old = 'paused', new = 'running')
2025-01-18 21:58:43 (16792): VM state change detected. (old = 'running', new = 'paused')
2025-01-18 21:59:04 (16792): VM state change detected. (old = 'paused', new = 'running')
2025-01-18 21:59:14 (16792): VM state change detected. (old = 'running', new = 'paused')
2025-01-18 21:59:34 (16792): VM state change detected. (old = 'paused', new = 'running')
2025-01-18 21:59:43 (16792): VM state change detected. (old = 'running', new = 'paused')
2025-01-18 22:00:03 (16792): VM state change detected. (old = 'paused', new = 'running')
2025-01-18 22:00:13 (16792): VM state change detected. (old = 'running', new = 'paused')
2025-01-18 22:00:33 (16792): VM state change detected. (old = 'paused', new = 'running')
2025-01-18 22:00:43 (16792): VM state change detected. (old = 'running', new = 'paused')
2025-01-18 22:01:03 (16792): VM state change detected. (old = 'paused', new = 'running')
2025-01-18 22:01:13 (16792): VM state change detected. (old = 'running', new = 'paused')
2025-01-18 22:01:33 (16792): VM state change detected. (old = 'paused', new = 'running')
2025-01-18 22:01:43 (16792): VM state change detected. (old = 'running', new = 'paused')
2025-01-18 22:02:03 (16792): VM state change detected. (old = 'paused', new = 'running')
2025-01-18 22:02:14 (16792): VM state change detected. (old = 'running', new = 'paused')
2025-01-18 22:02:34 (16792): VM state change detected. (old = 'paused', new = 'running')
2025-01-18 22:02:44 (16792): VM state change detected. (old = 'running', new = 'paused')
2025-01-18 22:03:04 (16792): VM state change detected. (old = 'paused', new = 'running')
2025-01-18 22:03:14 (16792): VM state change detected. (old = 'running', new = 'paused')
2025-01-18 22:03:35 (16792): VM state change detected. (old = 'paused', new = 'running')
2025-01-18 22:03:44 (16792): VM state change detected. (old = 'running', new = 'paused')
2025-01-18 22:04:04 (16792): VM state change detected. (old = 'paused', new = 'running')
2025-01-18 22:04:14 (16792): VM state change detected. (old = 'running', new = 'paused')
2025-01-18 22:04:34 (16792): VM state change detected. (old = 'paused', new = 'running')
2025-01-18 22:04:44 (16792): VM state change detected. (old = 'running', new = 'paused')
2025-01-18 22:05:04 (16792): VM state change detected. (old = 'paused', new = 'running')
2025-01-18 22:05:14 (16792): VM state change detected. (old = 'running', new = 'paused')
2025-01-18 22:05:34 (16792): VM state change detected. (old = 'paused', new = 'running')
2025-01-18 22:05:43 (16792): VM state change detected. (old = 'running', new = 'paused')
2025-01-18 22:06:03 (16792): VM state change detected. (old = 'paused', new = 'running')
2025-01-18 22:06:13 (16792): VM state change detected. (old = 'running', new = 'paused')
2025-01-18 22:06:33 (16792): VM state change detected. (old = 'paused', new = 'running')
2025-01-18 22:06:43 (16792): VM state change detected. (old = 'running', new = 'paused')
2025-01-18 22:07:03 (16792): VM state change detected. (old = 'paused', new = 'running')
2025-01-18 22:07:13 (16792): VM state change detected. (old = 'running', new = 'paused')
2025-01-18 22:07:34 (16792): VM state change detected. (old = 'paused', new = 'running')
2025-01-18 22:07:44 (16792): VM state change detected. (old = 'running', new = 'paused')
2025-01-18 22:08:04 (16792): VM state change detected. (old = 'paused', new = 'running')
2025-01-18 22:08:13 (16792): VM state change detected. (old = 'running', new = 'paused')
2025-01-18 22:08:33 (16792): VM state change detected. (old = 'paused', new = 'running')
2025-01-18 22:08:44 (16792): VM state change detected. (old = 'running', new = 'paused')
2025-01-18 22:09:04 (16792): VM state change detected. (old = 'paused', new = 'running')
2025-01-18 22:09:14 (16792): VM state change detected. (old = 'running', new = 'paused')
2025-01-18 22:09:34 (16792): VM state change detected. (old = 'paused', new = 'running')
2025-01-18 22:09:44 (16792): VM state change detected. (old = 'running', new = 'paused')
2025-01-18 22:10:04 (16792): VM state change detected. (old = 'paused', new = 'running')
2025-01-18 22:10:14 (16792): VM state change detected. (old = 'running', new = 'paused')
2025-01-18 22:10:34 (16792): VM state change detected. (old = 'paused', new = 'running')
2025-01-18 22:12:34 (16792): VM state change detected. (old = 'running', new = 'paused')
2025-01-18 22:12:54 (16792): VM state change detected. (old = 'paused', new = 'running')
2025-01-18 22:21:26 (16792): VM state change detected. (old = 'running', new = 'paused')
2025-01-19 10:24:32 (6868): vboxwrapper version 26207
2025-01-19 10:24:32 (6868): BOINC client version: 8.0.2
2025-01-19 10:24:33 (6868): Detected: VirtualBox VboxManage Interface (Version: 7.0.6)
2025-01-19 10:24:33 (6868): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2025-01-19 10:24:34 (6868): Guest Log: BIOS: VirtualBox 7.0.6
2025-01-19 10:24:34 (6868): Guest Log: CPUID EDX: 0x178bfbff
2025-01-19 10:24:34 (6868): Guest Log: BIOS: No PCI IDE controller, not probing IDE
2025-01-19 10:24:34 (6868): Guest Log: BIOS: AHCI 0-P#0: PCHS=16383/16/63 LCHS=1024/255/63 0x0000000002800000 sectors
2025-01-19 10:24:34 (6868): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032
2025-01-19 10:24:34 (6868): Guest Log: BIOS: Booting from Hard Disk...
2025-01-19 10:24:34 (6868): Guest Log: BIOS: KBD: unsupported int 16h function 03
2025-01-19 10:24:34 (6868): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 
2025-01-19 10:24:34 (6868): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds
2025-01-19 10:24:34 (6868): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000)
2025-01-19 10:24:34 (6868): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log
2025-01-19 10:24:34 (6868): Guest Log: 00:00:00.000080 main     Log opened 2025-01-18T17:14:07.924255000Z
2025-01-19 10:24:34 (6868): Guest Log: 00:00:00.000164 main     OS Product: Linux
2025-01-19 10:24:34 (6868): Guest Log: 00:00:00.000190 main     OS Release: 4.14.232-19.cernvm.x86_64
2025-01-19 10:24:34 (6868): Guest Log: 00:00:00.000210 main     OS Version: #1 SMP Fri Apr 30 17:12:25 CEST 2021
2025-01-19 10:24:34 (6868): Guest Log: 00:00:00.000240 main     Executable: /usr/sbin/VBoxService
2025-01-19 10:24:34 (6868): Guest Log: 00:00:00.000241 main     Process ID: 2285
2025-01-19 10:24:34 (6868): Guest Log: 00:00:00.000242 main     Package type: LINUX_64BITS_GENERIC
2025-01-19 10:24:34 (6868): Guest Log: 00:00:00.000794 main     5.2.6 r120293 started. Verbose level = 0
2025-01-19 10:24:34 (6868): Guest Log: [INFO] Mounting the shared directory
2025-01-19 10:24:34 (6868): Guest Log: [INFO] Shared directory mounted, enabling vboxmonitor
2025-01-19 10:24:34 (6868): Guest Log: [INFO] Sourcing essential functions from /cvmfs/grid.cern.ch
2025-01-19 10:24:34 (6868): Guest Log: [INFO] Testing connection to cern.ch
2025-01-19 10:24:34 (6868): Guest Log: [INFO] Testing connection to VCCS
2025-01-19 10:24:34 (6868): Guest Log: [INFO] Testing connection to HTCondor
2025-01-19 10:24:34 (6868): Guest Log: [INFO] Testing connection to WMAgent
2025-01-19 10:24:34 (6868): Guest Log: [INFO] Testing connection to EOSCMS
2025-01-19 10:24:34 (6868): Guest Log: [INFO] Testing connection to CMS-Factory
2025-01-19 10:24:34 (6868): Guest Log: [INFO] Testing connection to CMS-Frontier
2025-01-19 10:24:34 (6868): Guest Log: [INFO] Testing connection to Frontier
2025-01-19 10:24:34 (6868): Guest Log: [INFO] Could not find a local HTTP proxy
2025-01-19 10:24:34 (6868): Guest Log: [INFO] CVMFS and Frontier will have to use DIRECT connections
2025-01-19 10:24:34 (6868): Guest Log: [INFO] This makes the application less efficient
2025-01-19 10:24:34 (6868): Guest Log: [INFO] It also puts higher load on the project servers
2025-01-19 10:24:34 (6868): Guest Log: [INFO] Setting up a local HTTP proxy is highly recommended
2025-01-19 10:24:34 (6868): Guest Log: [INFO] Advice can be found in the project forum
2025-01-19 10:24:34 (6868): Guest Log: [INFO] Reloading and probing the CVMFS configuration
2025-01-19 10:24:34 (6868): Guest Log: [INFO] Probing /cvmfs/grid.cern.ch... OK
2025-01-19 10:24:34 (6868): Guest Log: [INFO] Probing /cvmfs/oasis.opensciencegrid.org... OK
2025-01-19 10:24:34 (6868): Guest Log: [INFO] Probing /cvmfs/cms-ib.cern.ch... OK
2025-01-19 10:24:34 (6868): Guest Log: [INFO] Probing /cvmfs/singularity.opensciencegrid.org... OK
2025-01-19 10:24:34 (6868): Guest Log: [INFO] Probing /cvmfs/cms.cern.ch... OK
2025-01-19 10:24:34 (6868): Guest Log: [INFO] Excerpt from "cvmfs_config stat": VERSION HOST PROXY
2025-01-19 10:24:34 (6868): Guest Log: [INFO] 2.7.2.0 http://s1ral-cvmfs.openhtc.io DIRECT
2025-01-19 10:24:34 (6868): Guest Log: [INFO] Environment HTTP proxy: not set
2025-01-19 10:24:34 (6868): Guest Log: [INFO] Reading volunteer information
2025-01-19 10:24:34 (6868): Guest Log: [INFO] Using weak account key.
2025-01-19 10:24:34 (6868): Guest Log: [INFO] Requesting an X509 credential from LHC@home
2025-01-19 10:24:34 (6868): Guest Log: [INFO] Using weak account key.
2025-01-19 10:24:34 (6868): Guest Log: [INFO] Requesting an idtoken from LHC@home
2025-01-19 10:24:34 (6868): Guest Log: [INFO] CMS application starting. Check log files.
2025-01-19 10:24:34 (6868): Guest Log: 00:41:21.200939 timesync vgsvcTimeSyncWorker: Radical host time change: 5 489 829 000 000ns (HostNow=1 737 232 806 731 000 000 ns HostLast=1 737 227 316 902 000 000 ns)
2025-01-19 10:24:34 (6868): Guest Log: 00:41:31.208783 timesync vgsvcTimeSyncWorker: Radical guest time change: 6 250 669 360 000ns (GuestNow=1 737 232 816 740 489 000 ns GuestLast=1 737 226 566 071 129 000 ns fSetTimeLastLoop=true )
2025-01-19 10:24:34 (6868): Starting VM using VBoxManage interface. (boinc_3f14646333c6c302, slot#4)
2025-01-19 10:24:41 (6868): Successfully started VM. (PID = '12040')
2025-01-19 10:24:41 (6868): Reporting VM Process ID to BOINC.
2025-01-19 10:24:41 (6868): Guest Log: BIOS: VirtualBox 7.0.6
2025-01-19 10:24:41 (6868): Guest Log: CPUID EDX: 0x178bfbff
2025-01-19 10:24:41 (6868): Guest Log: BIOS: No PCI IDE controller, not probing IDE
2025-01-19 10:24:41 (6868): Guest Log: BIOS: AHCI 0-P#0: PCHS=16383/16/63 LCHS=1024/255/63 0x0000000002800000 sectors
2025-01-19 10:24:41 (6868): VM state change detected. (old = 'poweredoff', new = 'running')
2025-01-19 10:24:41 (6868): Detected: Web Application Enabled (http://localhost:62600)
2025-01-19 10:24:41 (6868): Status Report: Job Duration: '64800.000000'
2025-01-19 10:24:41 (6868): Status Report: Elapsed Time: '18759.584154'
2025-01-19 10:24:41 (6868): Status Report: CPU Time: '42762.625000'
2025-01-19 10:24:41 (6868): Preference change detected
2025-01-19 10:24:41 (6868): Setting CPU throttle for VM. (100%)
2025-01-19 10:24:41 (6868): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2025-01-19 10:24:43 (6868): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032
2025-01-19 10:24:43 (6868): Guest Log: BIOS: Booting from Hard Disk...
2025-01-19 10:24:45 (6868): Guest Log: BIOS: KBD: unsupported int 16h function 03
2025-01-19 10:24:45 (6868): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 
2025-01-19 10:25:17 (6868): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds
2025-01-19 10:25:17 (6868): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000)
2025-01-19 10:25:18 (6868): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log
2025-01-19 10:25:18 (6868): Guest Log: 00:00:00.000108 main     Log opened 2025-01-19T09:25:23.612889000Z
2025-01-19 10:25:18 (6868): Guest Log: 00:00:00.000183 main     OS Product: Linux
2025-01-19 10:25:18 (6868): Guest Log: 00:00:00.000211 main     OS Release: 4.14.232-19.cernvm.x86_64
2025-01-19 10:25:18 (6868): Guest Log: 00:00:00.000231 main     OS Version: #1 SMP Fri Apr 30 17:12:25 CEST 2021
2025-01-19 10:25:18 (6868): Guest Log: 00:00:00.000250 main     Executable: /usr/sbin/VBoxService
2025-01-19 10:25:18 (6868): Guest Log: 00:00:00.000250 main     Process ID: 2286
2025-01-19 10:25:18 (6868): Guest Log: 00:00:00.000251 main     Package type: LINUX_64BITS_GENERIC
2025-01-19 10:25:18 (6868): Guest Log: 00:00:00.001503 main     5.2.6 r120293 started. Verbose level = 0
2025-01-19 10:25:28 (6868): Guest Log: [INFO] Mounting the shared directory
2025-01-19 10:25:28 (6868): Guest Log: [INFO] Shared directory mounted, enabling vboxmonitor
2025-01-19 10:25:28 (6868): Guest Log: [INFO] Sourcing essential functions from /cvmfs/grid.cern.ch
2025-01-19 10:25:28 (6868): Guest Log: [INFO] Testing connection to cern.ch
2025-01-19 10:25:28 (6868): Guest Log: [INFO] Testing connection to VCCS
2025-01-19 10:25:29 (6868): Guest Log: [INFO] Testing connection to HTCondor
2025-01-19 10:25:29 (6868): Guest Log: [INFO] Testing connection to WMAgent
2025-01-19 10:25:29 (6868): Guest Log: [INFO] Testing connection to EOSCMS
2025-01-19 10:25:29 (6868): Guest Log: [INFO] Testing connection to CMS-Factory
2025-01-19 10:25:29 (6868): Guest Log: [INFO] Testing connection to CMS-Frontier
2025-01-19 10:25:29 (6868): Guest Log: [INFO] Testing connection to Frontier
2025-01-19 10:25:30 (6868): Guest Log: [INFO] Could not find a local HTTP proxy
2025-01-19 10:25:30 (6868): Guest Log: [INFO] CVMFS and Frontier will have to use DIRECT connections
2025-01-19 10:25:30 (6868): Guest Log: [INFO] This makes the application less efficient
2025-01-19 10:25:30 (6868): Guest Log: [INFO] It also puts higher load on the project servers
2025-01-19 10:25:30 (6868): Guest Log: [INFO] Setting up a local HTTP proxy is highly recommended
2025-01-19 10:25:30 (6868): Guest Log: [INFO] Advice can be found in the project forum
2025-01-19 10:25:30 (6868): Guest Log: [INFO] Reloading and probing the CVMFS configuration
2025-01-19 10:25:36 (6868): Guest Log: [INFO] Probing /cvmfs/grid.cern.ch... OK
2025-01-19 10:25:38 (6868): Guest Log: [INFO] Probing /cvmfs/cms-ib.cern.ch... OK
2025-01-19 10:25:38 (6868): Guest Log: [INFO] Probing /cvmfs/cms.cern.ch... OK
2025-01-19 10:25:39 (6868): Guest Log: [INFO] Probing /cvmfs/oasis.opensciencegrid.org... OK
2025-01-19 10:25:40 (6868): Guest Log: [INFO] Probing /cvmfs/singularity.opensciencegrid.org... OK
2025-01-19 10:25:41 (6868): Guest Log: [INFO] Excerpt from "cvmfs_config stat": VERSION HOST PROXY
2025-01-19 10:25:41 (6868): Guest Log: [INFO] 2.7.2.0 http://s1cern-cvmfs.openhtc.io DIRECT
2025-01-19 10:25:41 (6868): Guest Log: [INFO] Environment HTTP proxy: not set
2025-01-19 10:25:41 (6868): Guest Log: [INFO] Reading volunteer information
2025-01-19 10:25:42 (6868): Guest Log: [INFO] Using weak account key.
2025-01-19 10:25:42 (6868): Guest Log: [INFO] Requesting an X509 credential from LHC@home
2025-01-19 10:25:43 (6868): Guest Log: [INFO] Using weak account key.
2025-01-19 10:25:43 (6868): Guest Log: [INFO] Requesting an idtoken from LHC@home
2025-01-19 10:25:43 (6868): Guest Log: [INFO] CMS application starting. Check log files.
2025-01-19 10:30:24 (6868): VM state change detected. (old = 'running', new = 'paused')
2025-01-19 10:30:44 (6868): VM state change detected. (old = 'paused', new = 'running')
2025-01-19 10:30:54 (6868): VM state change detected. (old = 'running', new = 'paused')
2025-01-19 10:31:15 (6868): VM state change detected. (old = 'paused', new = 'running')
2025-01-19 10:31:54 (6868): VM state change detected. (old = 'running', new = 'paused')
2025-01-19 10:32:14 (6868): VM state change detected. (old = 'paused', new = 'running')
2025-01-19 10:32:24 (6868): VM state change detected. (old = 'running', new = 'paused')
2025-01-19 10:32:44 (6868): VM state change detected. (old = 'paused', new = 'running')
2025-01-19 10:32:54 (6868): VM state change detected. (old = 'running', new = 'paused')
2025-01-19 10:33:14 (6868): VM state change detected. (old = 'paused', new = 'running')
2025-01-19 10:35:44 (6868): VM state change detected. (old = 'running', new = 'paused')
2025-01-19 10:36:04 (6868): VM state change detected. (old = 'paused', new = 'running')
2025-01-19 10:36:15 (6868): VM state change detected. (old = 'running', new = 'paused')
2025-01-19 10:36:35 (6868): VM state change detected. (old = 'paused', new = 'running')
2025-01-19 10:36:55 (6868): VM state change detected. (old = 'running', new = 'paused')
2025-01-19 10:37:15 (6868): VM state change detected. (old = 'paused', new = 'running')
2025-01-19 10:37:34 (6868): VM state change detected. (old = 'running', new = 'paused')
2025-01-19 10:37:54 (6868): VM state change detected. (old = 'paused', new = 'running')
2025-01-19 10:38:04 (6868): VM state change detected. (old = 'running', new = 'paused')
2025-01-19 10:38:24 (6868): VM state change detected. (old = 'paused', new = 'running')
2025-01-19 10:38:35 (6868): VM state change detected. (old = 'running', new = 'paused')
2025-01-19 10:38:55 (6868): VM state change detected. (old = 'paused', new = 'running')
2025-01-19 10:39:05 (6868): VM state change detected. (old = 'running', new = 'paused')
2025-01-19 10:39:25 (6868): VM state change detected. (old = 'paused', new = 'running')
2025-01-19 10:39:35 (6868): VM state change detected. (old = 'running', new = 'paused')
2025-01-19 10:39:55 (6868): VM state change detected. (old = 'paused', new = 'running')
2025-01-19 10:40:25 (6868): VM state change detected. (old = 'running', new = 'paused')
2025-01-19 11:34:48 (7880): vboxwrapper version 26207
2025-01-19 11:34:48 (7880): BOINC client version: 8.0.2
2025-01-19 11:34:50 (7880): Detected: VirtualBox VboxManage Interface (Version: 7.0.6)
2025-01-19 11:34:50 (7880): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2025-01-19 11:34:51 (7880): Guest Log: BIOS: VirtualBox 7.0.6
2025-01-19 11:34:51 (7880): Guest Log: CPUID EDX: 0x178bfbff
2025-01-19 11:34:51 (7880): Guest Log: BIOS: No PCI IDE controller, not probing IDE
2025-01-19 11:34:51 (7880): Guest Log: BIOS: AHCI 0-P#0: PCHS=16383/16/63 LCHS=1024/255/63 0x0000000002800000 sectors
2025-01-19 11:34:51 (7880): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032
2025-01-19 11:34:51 (7880): Guest Log: BIOS: Booting from Hard Disk...
2025-01-19 11:34:51 (7880): Guest Log: BIOS: KBD: unsupported int 16h function 03
2025-01-19 11:34:51 (7880): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 
2025-01-19 11:34:51 (7880): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds
2025-01-19 11:34:51 (7880): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000)
2025-01-19 11:34:51 (7880): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log
2025-01-19 11:34:51 (7880): Guest Log: 00:00:00.000108 main     Log opened 2025-01-19T09:25:23.612889000Z
2025-01-19 11:34:51 (7880): Guest Log: 00:00:00.000183 main     OS Product: Linux
2025-01-19 11:34:51 (7880): Guest Log: 00:00:00.000211 main     OS Release: 4.14.232-19.cernvm.x86_64
2025-01-19 11:34:51 (7880): Guest Log: 00:00:00.000231 main     OS Version: #1 SMP Fri Apr 30 17:12:25 CEST 2021
2025-01-19 11:34:51 (7880): Guest Log: 00:00:00.000250 main     Executable: /usr/sbin/VBoxService
2025-01-19 11:34:51 (7880): Guest Log: 00:00:00.000250 main     Process ID: 2286
2025-01-19 11:34:51 (7880): Guest Log: 00:00:00.000251 main     Package type: LINUX_64BITS_GENERIC
2025-01-19 11:34:51 (7880): Guest Log: 00:00:00.001503 main     5.2.6 r120293 started. Verbose level = 0
2025-01-19 11:34:51 (7880): Guest Log: [INFO] Mounting the shared directory
2025-01-19 11:34:51 (7880): Guest Log: [INFO] Shared directory mounted, enabling vboxmonitor
2025-01-19 11:34:51 (7880): Guest Log: [INFO] Sourcing essential functions from /cvmfs/grid.cern.ch
2025-01-19 11:34:51 (7880): Guest Log: [INFO] Testing connection to cern.ch
2025-01-19 11:34:51 (7880): Guest Log: [INFO] Testing connection to VCCS
2025-01-19 11:34:51 (7880): Guest Log: [INFO] Testing connection to HTCondor
2025-01-19 11:34:51 (7880): Guest Log: [INFO] Testing connection to WMAgent
2025-01-19 11:34:51 (7880): Guest Log: [INFO] Testing connection to EOSCMS
2025-01-19 11:34:51 (7880): Guest Log: [INFO] Testing connection to CMS-Factory
2025-01-19 11:34:51 (7880): Guest Log: [INFO] Testing connection to CMS-Frontier
2025-01-19 11:34:51 (7880): Guest Log: [INFO] Testing connection to Frontier
2025-01-19 11:34:51 (7880): Guest Log: [INFO] Could not find a local HTTP proxy
2025-01-19 11:34:51 (7880): Guest Log: [INFO] CVMFS and Frontier will have to use DIRECT connections
2025-01-19 11:34:51 (7880): Guest Log: [INFO] This makes the application less efficient
2025-01-19 11:34:51 (7880): Guest Log: [INFO] It also puts higher load on the project servers
2025-01-19 11:34:51 (7880): Guest Log: [INFO] Setting up a local HTTP proxy is highly recommended
2025-01-19 11:34:51 (7880): Guest Log: [INFO] Advice can be found in the project forum
2025-01-19 11:34:51 (7880): Guest Log: [INFO] Reloading and probing the CVMFS configuration
2025-01-19 11:34:51 (7880): Guest Log: [INFO] Probing /cvmfs/grid.cern.ch... OK
2025-01-19 11:34:51 (7880): Guest Log: [INFO] Probing /cvmfs/cms-ib.cern.ch... OK
2025-01-19 11:34:51 (7880): Guest Log: [INFO] Probing /cvmfs/cms.cern.ch... OK
2025-01-19 11:34:51 (7880): Guest Log: [INFO] Probing /cvmfs/oasis.opensciencegrid.org... OK
2025-01-19 11:34:51 (7880): Guest Log: [INFO] Probing /cvmfs/singularity.opensciencegrid.org... OK
2025-01-19 11:34:51 (7880): Guest Log: [INFO] Excerpt from "cvmfs_config stat": VERSION HOST PROXY
2025-01-19 11:34:51 (7880): Guest Log: [INFO] 2.7.2.0 http://s1cern-cvmfs.openhtc.io DIRECT
2025-01-19 11:34:51 (7880): Guest Log: [INFO] Environment HTTP proxy: not set
2025-01-19 11:34:51 (7880): Guest Log: [INFO] Reading volunteer information
2025-01-19 11:34:51 (7880): Guest Log: [INFO] Using weak account key.
2025-01-19 11:34:51 (7880): Guest Log: [INFO] Requesting an X509 credential from LHC@home
2025-01-19 11:34:51 (7880): Guest Log: [INFO] Using weak account key.
2025-01-19 11:34:51 (7880): Guest Log: [INFO] Requesting an idtoken from LHC@home
2025-01-19 11:34:51 (7880): Guest Log: [INFO] CMS application starting. Check log files.
2025-01-19 11:34:51 (7880): Starting VM using VBoxManage interface. (boinc_3f14646333c6c302, slot#4)
2025-01-19 11:34:58 (7880): Successfully started VM. (PID = '9844')
2025-01-19 11:34:58 (7880): Reporting VM Process ID to BOINC.
2025-01-19 11:34:58 (7880): Guest Log: BIOS: VirtualBox 7.0.6
2025-01-19 11:34:58 (7880): Guest Log: CPUID EDX: 0x178bfbff
2025-01-19 11:34:58 (7880): Guest Log: BIOS: No PCI IDE controller, not probing IDE
2025-01-19 11:34:58 (7880): Guest Log: BIOS: AHCI 0-P#0: PCHS=16383/16/63 LCHS=1024/255/63 0x0000000002800000 sectors
2025-01-19 11:34:58 (7880): VM state change detected. (old = 'poweredoff', new = 'running')
2025-01-19 11:34:58 (7880): Detected: Web Application Enabled (http://localhost:62600)
2025-01-19 11:34:58 (7880): Status Report: Job Duration: '64800.000000'
2025-01-19 11:34:58 (7880): Status Report: Elapsed Time: '19387.584154'
2025-01-19 11:34:58 (7880): Status Report: CPU Time: '43272.593750'
2025-01-19 11:34:58 (7880): Preference change detected
2025-01-19 11:34:58 (7880): Setting CPU throttle for VM. (100%)
2025-01-19 11:34:58 (7880): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2025-01-19 11:35:00 (7880): VM state change detected. (old = 'running', new = 'paused')
2025-01-19 11:35:20 (7880): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032
2025-01-19 11:35:20 (7880): Guest Log: BIOS: Booting from Hard Disk...
2025-01-19 11:35:20 (7880): VM state change detected. (old = 'paused', new = 'running')
2025-01-19 11:35:22 (7880): Guest Log: BIOS: KBD: unsupported int 16h function 03
2025-01-19 11:35:22 (7880): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 
2025-01-19 11:35:49 (7880): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds
2025-01-19 11:35:49 (7880): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000)
2025-01-19 11:35:51 (7880): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log
2025-01-19 11:35:51 (7880): Guest Log: 00:00:00.000126 main     Log opened 2025-01-19T10:35:49.991058000Z
2025-01-19 11:35:51 (7880): Guest Log: 00:00:00.000201 main     OS Product: Linux
2025-01-19 11:35:51 (7880): Guest Log: 00:00:00.000227 main     OS Release: 4.14.232-19.cernvm.x86_64
2025-01-19 11:35:51 (7880): Guest Log: 00:00:00.000247 main     OS Version: #1 SMP Fri Apr 30 17:12:25 CEST 2021
2025-01-19 11:35:51 (7880): Guest Log: 00:00:00.000265 main     Executable: /usr/sbin/VBoxService
2025-01-19 11:35:51 (7880): Guest Log: 00:00:00.000266 main     Process ID: 2285
2025-01-19 11:35:51 (7880): Guest Log: 00:00:00.000266 main     Package type: LINUX_64BITS_GENERIC
2025-01-19 11:35:51 (7880): Guest Log: 00:00:00.001643 main     5.2.6 r120293 started. Verbose level = 0
2025-01-19 11:36:00 (7880): Guest Log: [INFO] Mounting the shared directory
2025-01-19 11:36:00 (7880): Guest Log: [INFO] Shared directory mounted, enabling vboxmonitor
2025-01-19 11:36:00 (7880): Guest Log: [INFO] Sourcing essential functions from /cvmfs/grid.cern.ch
2025-01-19 11:36:00 (7880): Guest Log: [INFO] Testing connection to cern.ch
2025-01-19 11:36:00 (7880): Guest Log: [INFO] Testing connection to VCCS
2025-01-19 11:36:00 (7880): Guest Log: [INFO] Testing connection to HTCondor
2025-01-19 11:36:00 (7880): Guest Log: [INFO] Testing connection to WMAgent
2025-01-19 11:36:00 (7880): Guest Log: [INFO] Testing connection to EOSCMS
2025-01-19 11:36:01 (7880): Guest Log: [INFO] Testing connection to CMS-Factory
2025-01-19 11:36:01 (7880): Guest Log: [INFO] Testing connection to CMS-Frontier
2025-01-19 11:36:01 (7880): Guest Log: [INFO] Testing connection to Frontier
2025-01-19 11:36:02 (7880): Guest Log: [INFO] Could not find a local HTTP proxy
2025-01-19 11:36:02 (7880): Guest Log: [INFO] CVMFS and Frontier will have to use DIRECT connections
2025-01-19 11:36:02 (7880): Guest Log: [INFO] This makes the application less efficient
2025-01-19 11:36:02 (7880): Guest Log: [INFO] It also puts higher load on the project servers
2025-01-19 11:36:02 (7880): Guest Log: [INFO] Setting up a local HTTP proxy is highly recommended
2025-01-19 11:36:02 (7880): Guest Log: [INFO] Advice can be found in the project forum
2025-01-19 11:36:02 (7880): Guest Log: [INFO] Reloading and probing the CVMFS configuration
2025-01-19 11:36:08 (7880): Guest Log: [INFO] Probing /cvmfs/grid.cern.ch... OK
2025-01-19 11:36:10 (7880): Guest Log: [INFO] Probing /cvmfs/oasis.opensciencegrid.org... OK
2025-01-19 11:36:10 (7880): Guest Log: [INFO] Probing /cvmfs/singularity.opensciencegrid.org... OK
2025-01-19 11:36:10 (7880): Guest Log: [INFO] Probing /cvmfs/cms.cern.ch... OK
2025-01-19 11:36:10 (7880): Guest Log: [INFO] Probing /cvmfs/cms-ib.cern.ch... OK
2025-01-19 11:36:11 (7880): Guest Log: [INFO] Excerpt from "cvmfs_config stat": VERSION HOST PROXY
2025-01-19 11:36:11 (7880): Guest Log: [INFO] 2.7.2.0 http://s1fnal-cvmfs.openhtc.io:8080 DIRECT
2025-01-19 11:36:11 (7880): Guest Log: [INFO] Environment HTTP proxy: not set
2025-01-19 11:36:11 (7880): Guest Log: [INFO] Reading volunteer information
2025-01-19 11:36:11 (7880): Guest Log: [INFO] Using weak account key.
2025-01-19 11:36:11 (7880): Guest Log: [INFO] Requesting an X509 credential from LHC@home
2025-01-19 11:36:12 (7880): Guest Log: [INFO] Using weak account key.
2025-01-19 11:36:12 (7880): Guest Log: [INFO] Requesting an idtoken from LHC@home
2025-01-19 11:36:13 (7880): Guest Log: [INFO] CMS application starting. Check log files.
2025-01-19 11:40:00 (7880): VM state change detected. (old = 'running', new = 'paused')
2025-01-19 11:40:20 (7880): VM state change detected. (old = 'paused', new = 'running')
2025-01-19 11:40:50 (7880): VM state change detected. (old = 'running', new = 'paused')
2025-01-19 11:41:10 (7880): VM state change detected. (old = 'paused', new = 'running')
2025-01-19 11:41:30 (7880): VM state change detected. (old = 'running', new = 'paused')
2025-01-19 11:41:50 (7880): VM state change detected. (old = 'paused', new = 'running')
2025-01-19 11:42:10 (7880): VM state change detected. (old = 'running', new = 'paused')
2025-01-19 11:42:30 (7880): VM state change detected. (old = 'paused', new = 'running')
2025-01-19 11:42:40 (7880): VM state change detected. (old = 'running', new = 'paused')
2025-01-19 11:43:00 (7880): VM state change detected. (old = 'paused', new = 'running')
2025-01-19 11:43:10 (7880): VM state change detected. (old = 'running', new = 'paused')
2025-01-19 11:43:30 (7880): VM state change detected. (old = 'paused', new = 'running')
2025-01-19 11:43:40 (7880): VM state change detected. (old = 'running', new = 'paused')
2025-01-19 11:44:00 (7880): VM state change detected. (old = 'paused', new = 'running')
2025-01-19 11:44:10 (7880): VM state change detected. (old = 'running', new = 'paused')
2025-01-19 11:44:30 (7880): VM state change detected. (old = 'paused', new = 'running')
2025-01-19 11:44:40 (7880): VM state change detected. (old = 'running', new = 'paused')
2025-01-19 11:45:00 (7880): VM state change detected. (old = 'paused', new = 'running')
2025-01-19 11:45:20 (7880): VM state change detected. (old = 'running', new = 'paused')
2025-01-19 11:45:40 (7880): VM state change detected. (old = 'paused', new = 'running')
2025-01-19 11:46:00 (7880): VM state change detected. (old = 'running', new = 'paused')
2025-01-19 11:46:20 (7880): VM state change detected. (old = 'paused', new = 'running')
2025-01-19 11:46:30 (7880): VM state change detected. (old = 'running', new = 'paused')
2025-01-19 11:46:51 (7880): VM state change detected. (old = 'paused', new = 'running')
2025-01-19 11:47:01 (7880): VM state change detected. (old = 'running', new = 'paused')
2025-01-19 11:47:21 (7880): VM state change detected. (old = 'paused', new = 'running')
2025-01-19 11:47:31 (7880): VM state change detected. (old = 'running', new = 'paused')
2025-01-19 11:47:51 (7880): VM state change detected. (old = 'paused', new = 'running')
2025-01-19 11:48:11 (7880): VM state change detected. (old = 'running', new = 'paused')
2025-01-19 11:48:32 (7880): VM state change detected. (old = 'paused', new = 'running')
2025-01-19 11:48:41 (7880): VM state change detected. (old = 'running', new = 'paused')
2025-01-19 11:49:01 (7880): VM state change detected. (old = 'paused', new = 'running')
2025-01-19 11:49:11 (7880): VM state change detected. (old = 'running', new = 'paused')
2025-01-19 11:49:31 (7880): VM state change detected. (old = 'paused', new = 'running')
2025-01-19 11:49:41 (7880): VM state change detected. (old = 'running', new = 'paused')
2025-01-19 11:50:01 (7880): VM state change detected. (old = 'paused', new = 'running')
2025-01-19 11:50:21 (7880): VM state change detected. (old = 'running', new = 'paused')
2025-01-19 11:50:41 (7880): VM state change detected. (old = 'paused', new = 'running')
2025-01-19 11:51:01 (7880): VM state change detected. (old = 'running', new = 'paused')
2025-01-19 11:51:21 (7880): VM state change detected. (old = 'paused', new = 'running')
2025-01-19 11:51:31 (7880): VM state change detected. (old = 'running', new = 'paused')
2025-01-19 11:51:51 (7880): VM state change detected. (old = 'paused', new = 'running')
2025-01-19 11:52:00 (7880): VM state change detected. (old = 'running', new = 'paused')
2025-01-19 11:52:21 (7880): VM state change detected. (old = 'paused', new = 'running')
2025-01-19 11:52:31 (7880): VM state change detected. (old = 'running', new = 'paused')
2025-01-19 11:52:51 (7880): VM state change detected. (old = 'paused', new = 'running')
2025-01-19 11:53:02 (7880): VM state change detected. (old = 'running', new = 'paused')
2025-01-19 11:53:21 (7880): VM state change detected. (old = 'paused', new = 'running')
2025-01-19 11:53:31 (7880): VM state change detected. (old = 'running', new = 'paused')
2025-01-19 11:53:51 (7880): VM state change detected. (old = 'paused', new = 'running')
2025-01-19 11:54:01 (7880): VM state change detected. (old = 'running', new = 'paused')
2025-01-19 11:54:21 (7880): VM state change detected. (old = 'paused', new = 'running')
2025-01-19 11:54:31 (7880): VM state change detected. (old = 'running', new = 'paused')
2025-01-19 11:54:51 (7880): VM state change detected. (old = 'paused', new = 'running')
2025-01-19 11:55:01 (7880): VM state change detected. (old = 'running', new = 'paused')
2025-01-19 11:55:21 (7880): VM state change detected. (old = 'paused', new = 'running')
2025-01-19 11:55:31 (7880): VM state change detected. (old = 'running', new = 'paused')
2025-01-19 11:55:51 (7880): VM state change detected. (old = 'paused', new = 'running')
2025-01-19 11:56:01 (7880): VM state change detected. (old = 'running', new = 'paused')
2025-01-19 11:56:21 (7880): VM state change detected. (old = 'paused', new = 'running')
2025-01-19 11:56:31 (7880): VM state change detected. (old = 'running', new = 'paused')
2025-01-19 11:56:51 (7880): VM state change detected. (old = 'paused', new = 'running')
2025-01-19 11:57:01 (7880): VM state change detected. (old = 'running', new = 'paused')
2025-01-19 11:57:21 (7880): VM state change detected. (old = 'paused', new = 'running')
2025-01-19 11:57:31 (7880): VM state change detected. (old = 'running', new = 'paused')
2025-01-19 11:57:51 (7880): VM state change detected. (old = 'paused', new = 'running')
2025-01-19 11:58:01 (7880): VM state change detected. (old = 'running', new = 'paused')
2025-01-19 11:58:21 (7880): VM state change detected. (old = 'paused', new = 'running')
2025-01-19 11:58:31 (7880): VM state change detected. (old = 'running', new = 'paused')
2025-01-19 11:58:52 (7880): VM state change detected. (old = 'paused', new = 'running')
2025-01-19 11:59:02 (7880): VM state change detected. (old = 'running', new = 'paused')
2025-01-19 11:59:22 (7880): VM state change detected. (old = 'paused', new = 'running')
2025-01-19 11:59:32 (7880): VM state change detected. (old = 'running', new = 'paused')
2025-01-19 11:59:52 (7880): VM state change detected. (old = 'paused', new = 'running')
2025-01-19 12:00:02 (7880): VM state change detected. (old = 'running', new = 'paused')
2025-01-19 12:00:22 (7880): VM state change detected. (old = 'paused', new = 'running')
2025-01-19 12:00:32 (7880): VM state change detected. (old = 'running', new = 'paused')
2025-01-19 12:00:52 (7880): VM state change detected. (old = 'paused', new = 'running')
2025-01-19 12:01:02 (7880): VM state change detected. (old = 'running', new = 'paused')
2025-01-19 12:01:22 (7880): VM state change detected. (old = 'paused', new = 'running')
2025-01-19 12:01:32 (7880): VM state change detected. (old = 'running', new = 'paused')
2025-01-19 12:01:52 (7880): VM state change detected. (old = 'paused', new = 'running')
2025-01-19 12:02:02 (7880): VM state change detected. (old = 'running', new = 'paused')
2025-01-19 12:02:22 (7880): VM state change detected. (old = 'paused', new = 'running')
2025-01-19 12:02:32 (7880): VM state change detected. (old = 'running', new = 'paused')
2025-01-19 12:02:52 (7880): VM state change detected. (old = 'paused', new = 'running')
2025-01-19 12:03:02 (7880): VM state change detected. (old = 'running', new = 'paused')
2025-01-19 12:03:22 (7880): VM state change detected. (old = 'paused', new = 'running')
2025-01-19 12:03:32 (7880): VM state change detected. (old = 'running', new = 'paused')
2025-01-19 12:03:52 (7880): VM state change detected. (old = 'paused', new = 'running')
2025-01-19 12:04:02 (7880): VM state change detected. (old = 'running', new = 'paused')
2025-01-19 12:04:22 (7880): VM state change detected. (old = 'paused', new = 'running')
2025-01-19 12:04:32 (7880): VM state change detected. (old = 'running', new = 'paused')
2025-01-19 12:04:52 (7880): VM state change detected. (old = 'paused', new = 'running')
2025-01-19 12:05:02 (7880): VM state change detected. (old = 'running', new = 'paused')
2025-01-19 12:05:22 (7880): VM state change detected. (old = 'paused', new = 'running')
2025-01-19 12:05:32 (7880): VM state change detected. (old = 'running', new = 'paused')
2025-01-19 12:05:52 (7880): VM state change detected. (old = 'paused', new = 'running')
2025-01-19 12:06:02 (7880): VM state change detected. (old = 'running', new = 'paused')
2025-01-19 12:06:22 (7880): VM state change detected. (old = 'paused', new = 'running')
2025-01-19 12:06:32 (7880): VM state change detected. (old = 'running', new = 'paused')
2025-01-19 12:06:52 (7880): VM state change detected. (old = 'paused', new = 'running')
2025-01-19 12:07:02 (7880): VM state change detected. (old = 'running', new = 'paused')
2025-01-19 12:07:22 (7880): VM state change detected. (old = 'paused', new = 'running')
2025-01-19 12:07:32 (7880): VM state change detected. (old = 'running', new = 'paused')
2025-01-19 12:07:52 (7880): VM state change detected. (old = 'paused', new = 'running')
2025-01-19 12:08:02 (7880): VM state change detected. (old = 'running', new = 'paused')
2025-01-19 12:08:22 (7880): VM state change detected. (old = 'paused', new = 'running')
2025-01-19 12:08:32 (7880): VM state change detected. (old = 'running', new = 'paused')
2025-01-19 12:08:52 (7880): VM state change detected. (old = 'paused', new = 'running')
2025-01-19 12:09:02 (7880): VM state change detected. (old = 'running', new = 'paused')
2025-01-19 12:09:22 (7880): VM state change detected. (old = 'paused', new = 'running')
2025-01-19 12:09:32 (7880): VM state change detected. (old = 'running', new = 'paused')
2025-01-19 12:09:52 (7880): VM state change detected. (old = 'paused', new = 'running')
2025-01-19 12:10:02 (7880): VM state change detected. (old = 'running', new = 'paused')
2025-01-19 12:10:22 (7880): VM state change detected. (old = 'paused', new = 'running')
2025-01-19 12:10:32 (7880): VM state change detected. (old = 'running', new = 'paused')
2025-01-19 12:10:52 (7880): VM state change detected. (old = 'paused', new = 'running')
2025-01-19 12:11:02 (7880): VM state change detected. (old = 'running', new = 'paused')
2025-01-19 12:11:22 (7880): VM state change detected. (old = 'paused', new = 'running')
2025-01-19 12:11:32 (7880): VM state change detected. (old = 'running', new = 'paused')
2025-01-19 12:11:52 (7880): VM state change detected. (old = 'paused', new = 'running')
2025-01-19 12:12:02 (7880): VM state change detected. (old = 'running', new = 'paused')
2025-01-19 12:12:22 (7880): VM state change detected. (old = 'paused', new = 'running')
2025-01-19 12:12:32 (7880): VM state change detected. (old = 'running', new = 'paused')
2025-01-19 12:12:52 (7880): VM state change detected. (old = 'paused', new = 'running')
2025-01-19 12:13:02 (7880): VM state change detected. (old = 'running', new = 'paused')
2025-01-19 12:13:22 (7880): VM state change detected. (old = 'paused', new = 'running')
2025-01-19 12:13:32 (7880): VM state change detected. (old = 'running', new = 'paused')
2025-01-19 12:13:53 (7880): VM state change detected. (old = 'paused', new = 'running')
2025-01-19 12:14:03 (7880): VM state change detected. (old = 'running', new = 'paused')
2025-01-19 12:14:23 (7880): VM state change detected. (old = 'paused', new = 'running')
2025-01-19 12:14:33 (7880): VM state change detected. (old = 'running', new = 'paused')
2025-01-19 12:14:53 (7880): VM state change detected. (old = 'paused', new = 'running')
2025-01-19 12:15:03 (7880): VM state change detected. (old = 'running', new = 'paused')
2025-01-19 12:15:23 (7880): VM state change detected. (old = 'paused', new = 'running')
2025-01-19 12:15:33 (7880): VM state change detected. (old = 'running', new = 'paused')
2025-01-19 12:15:53 (7880): VM state change detected. (old = 'paused', new = 'running')
2025-01-19 12:16:03 (7880): VM state change detected. (old = 'running', new = 'paused')
2025-01-19 12:16:23 (7880): VM state change detected. (old = 'paused', new = 'running')
2025-01-19 12:16:43 (7880): VM state change detected. (old = 'running', new = 'paused')
2025-01-19 12:17:03 (7880): VM state change detected. (old = 'paused', new = 'running')
2025-01-19 12:17:23 (7880): VM state change detected. (old = 'running', new = 'paused')
2025-01-19 12:17:43 (7880): VM state change detected. (old = 'paused', new = 'running')
2025-01-19 12:17:53 (7880): VM state change detected. (old = 'running', new = 'paused')
2025-01-19 12:18:13 (7880): VM state change detected. (old = 'paused', new = 'running')
2025-01-19 12:18:23 (7880): VM state change detected. (old = 'running', new = 'paused')
2025-01-19 12:18:43 (7880): VM state change detected. (old = 'paused', new = 'running')
2025-01-19 12:18:53 (7880): VM state change detected. (old = 'running', new = 'paused')
2025-01-19 12:19:13 (7880): VM state change detected. (old = 'paused', new = 'running')
2025-01-19 12:19:23 (7880): VM state change detected. (old = 'running', new = 'paused')
2025-01-19 12:19:43 (7880): VM state change detected. (old = 'paused', new = 'running')
2025-01-19 12:19:53 (7880): VM state change detected. (old = 'running', new = 'paused')
2025-01-19 12:20:13 (7880): VM state change detected. (old = 'paused', new = 'running')
2025-01-19 12:20:23 (7880): VM state change detected. (old = 'running', new = 'paused')
2025-01-19 12:20:43 (7880): VM state change detected. (old = 'paused', new = 'running')
2025-01-19 12:20:53 (7880): VM state change detected. (old = 'running', new = 'paused')
2025-01-19 12:21:13 (7880): VM state change detected. (old = 'paused', new = 'running')
2025-01-19 12:21:23 (7880): VM state change detected. (old = 'running', new = 'paused')
2025-01-19 12:21:43 (7880): VM state change detected. (old = 'paused', new = 'running')
2025-01-19 12:21:53 (7880): VM state change detected. (old = 'running', new = 'paused')
2025-01-19 12:22:13 (7880): VM state change detected. (old = 'paused', new = 'running')
2025-01-19 12:22:23 (7880): VM state change detected. (old = 'running', new = 'paused')
2025-01-19 12:22:43 (7880): VM state change detected. (old = 'paused', new = 'running')
2025-01-19 12:22:53 (7880): VM state change detected. (old = 'running', new = 'paused')
2025-01-19 12:23:13 (7880): VM state change detected. (old = 'paused', new = 'running')
2025-01-19 12:23:23 (7880): VM state change detected. (old = 'running', new = 'paused')
2025-01-19 12:23:43 (7880): VM state change detected. (old = 'paused', new = 'running')
2025-01-19 12:23:53 (7880): VM state change detected. (old = 'running', new = 'paused')
2025-01-19 12:24:13 (7880): VM state change detected. (old = 'paused', new = 'running')
2025-01-19 12:24:23 (7880): VM state change detected. (old = 'running', new = 'paused')
2025-01-19 12:24:43 (7880): VM state change detected. (old = 'paused', new = 'running')
2025-01-19 12:24:53 (7880): VM state change detected. (old = 'running', new = 'paused')
2025-01-19 12:25:13 (7880): VM state change detected. (old = 'paused', new = 'running')
2025-01-19 12:25:33 (7880): VM state change detected. (old = 'running', new = 'paused')
2025-01-19 12:25:53 (7880): VM state change detected. (old = 'paused', new = 'running')
2025-01-19 12:26:03 (7880): VM state change detected. (old = 'running', new = 'paused')
2025-01-19 12:26:23 (7880): VM state change detected. (old = 'paused', new = 'running')
2025-01-19 12:26:33 (7880): VM state change detected. (old = 'running', new = 'paused')
2025-01-19 12:26:53 (7880): VM state change detected. (old = 'paused', new = 'running')
2025-01-19 12:27:03 (7880): VM state change detected. (old = 'running', new = 'paused')
2025-01-19 12:27:23 (7880): VM state change detected. (old = 'paused', new = 'running')
2025-01-19 12:27:33 (7880): VM state change detected. (old = 'running', new = 'paused')
2025-01-19 13:58:51 (7880): VM state change detected. (old = 'paused', new = 'running')
2025-01-19 13:58:54 (7880): VM state change detected. (old = 'running', new = 'paused')
2025-01-19 13:59:14 (7880): VM state change detected. (old = 'paused', new = 'running')
2025-01-19 13:59:16 (7880): Guest Log: 00:21:30.727260 timesync vgsvcTimeSyncWorker: Radical host time change: 5 507 707 000 000ns (HostNow=1 737 291 556 466 000 000 ns HostLast=1 737 286 048 759 000 000 ns)
2025-01-19 13:59:26 (7880): Guest Log: 00:21:40.733181 timesync vgsvcTimeSyncWorker: Radical guest time change: 6 107 527 073 000ns (GuestNow=1 737 291 566 472 005 000 ns GuestLast=1 737 285 458 944 932 000 ns fSetTimeLastLoop=true )
2025-01-19 13:59:34 (7880): VM state change detected. (old = 'running', new = 'paused')
2025-01-19 13:59:54 (7880): VM state change detected. (old = 'paused', new = 'running')
2025-01-19 14:00:04 (7880): VM state change detected. (old = 'running', new = 'paused')
2025-01-19 14:00:24 (7880): VM state change detected. (old = 'paused', new = 'running')
2025-01-19 14:00:34 (7880): VM state change detected. (old = 'running', new = 'paused')
2025-01-19 14:00:54 (7880): VM state change detected. (old = 'paused', new = 'running')
2025-01-19 14:01:04 (7880): VM state change detected. (old = 'running', new = 'paused')
2025-01-19 14:01:24 (7880): VM state change detected. (old = 'paused', new = 'running')
2025-01-19 14:01:34 (7880): VM state change detected. (old = 'running', new = 'paused')
2025-01-19 14:01:54 (7880): VM state change detected. (old = 'paused', new = 'running')
2025-01-19 14:02:04 (7880): VM state change detected. (old = 'running', new = 'paused')
2025-01-19 14:02:24 (7880): VM state change detected. (old = 'paused', new = 'running')
2025-01-19 14:02:34 (7880): VM state change detected. (old = 'running', new = 'paused')
2025-01-19 14:02:55 (7880): VM state change detected. (old = 'paused', new = 'running')
2025-01-19 14:03:05 (7880): VM state change detected. (old = 'running', new = 'paused')
2025-01-19 14:03:25 (7880): VM state change detected. (old = 'paused', new = 'running')
2025-01-19 14:03:35 (7880): VM state change detected. (old = 'running', new = 'paused')
2025-01-19 14:03:55 (7880): VM state change detected. (old = 'paused', new = 'running')
2025-01-19 14:04:05 (7880): VM state change detected. (old = 'running', new = 'paused')
2025-01-19 14:04:25 (7880): VM state change detected. (old = 'paused', new = 'running')
2025-01-19 14:04:35 (7880): VM state change detected. (old = 'running', new = 'paused')
2025-01-19 14:04:55 (7880): VM state change detected. (old = 'paused', new = 'running')
2025-01-19 14:05:05 (7880): VM state change detected. (old = 'running', new = 'paused')
2025-01-19 14:05:25 (7880): VM state change detected. (old = 'paused', new = 'running')
2025-01-19 14:05:35 (7880): VM state change detected. (old = 'running', new = 'paused')
2025-01-19 14:05:55 (7880): VM state change detected. (old = 'paused', new = 'running')
2025-01-19 14:06:05 (7880): VM state change detected. (old = 'running', new = 'paused')
2025-01-19 14:06:25 (7880): VM state change detected. (old = 'paused', new = 'running')
2025-01-19 14:06:35 (7880): VM state change detected. (old = 'running', new = 'paused')
2025-01-19 14:06:55 (7880): VM state change detected. (old = 'paused', new = 'running')
2025-01-19 14:07:05 (7880): VM state change detected. (old = 'running', new = 'paused')
2025-01-19 14:07:25 (7880): VM state change detected. (old = 'paused', new = 'running')
2025-01-19 14:07:35 (7880): VM state change detected. (old = 'running', new = 'paused')
2025-01-19 14:07:55 (7880): VM state change detected. (old = 'paused', new = 'running')
2025-01-19 14:08:15 (7880): VM state change detected. (old = 'running', new = 'paused')
2025-01-19 14:08:35 (7880): VM state change detected. (old = 'paused', new = 'running')
2025-01-19 14:08:45 (7880): VM state change detected. (old = 'running', new = 'paused')
2025-01-19 14:09:05 (7880): VM state change detected. (old = 'paused', new = 'running')
2025-01-19 14:09:15 (7880): VM state change detected. (old = 'running', new = 'paused')
2025-01-19 14:09:35 (7880): VM state change detected. (old = 'paused', new = 'running')
2025-01-19 14:09:45 (7880): VM state change detected. (old = 'running', new = 'paused')
2025-01-19 14:10:05 (7880): VM state change detected. (old = 'paused', new = 'running')
2025-01-19 14:10:15 (7880): VM state change detected. (old = 'running', new = 'paused')
2025-01-19 14:10:35 (7880): VM state change detected. (old = 'paused', new = 'running')
2025-01-19 14:10:45 (7880): VM state change detected. (old = 'running', new = 'paused')
2025-01-19 14:11:05 (7880): VM state change detected. (old = 'paused', new = 'running')
2025-01-19 14:11:15 (7880): VM state change detected. (old = 'running', new = 'paused')
2025-01-19 14:11:35 (7880): VM state change detected. (old = 'paused', new = 'running')
2025-01-19 14:11:45 (7880): VM state change detected. (old = 'running', new = 'paused')
2025-01-19 14:12:05 (7880): VM state change detected. (old = 'paused', new = 'running')
2025-01-19 14:12:15 (7880): VM state change detected. (old = 'running', new = 'paused')
2025-01-19 14:12:35 (7880): VM state change detected. (old = 'paused', new = 'running')
2025-01-19 14:12:45 (7880): VM state change detected. (old = 'running', new = 'paused')
2025-01-19 14:13:05 (7880): VM state change detected. (old = 'paused', new = 'running')
2025-01-19 14:13:15 (7880): VM state change detected. (old = 'running', new = 'paused')
2025-01-19 14:13:35 (7880): VM state change detected. (old = 'paused', new = 'running')
2025-01-19 14:13:45 (7880): VM state change detected. (old = 'running', new = 'paused')
2025-01-19 14:14:05 (7880): VM state change detected. (old = 'paused', new = 'running')
2025-01-19 14:14:15 (7880): VM state change detected. (old = 'running', new = 'paused')
2025-01-19 14:14:35 (7880): VM state change detected. (old = 'paused', new = 'running')
2025-01-19 14:14:45 (7880): VM state change detected. (old = 'running', new = 'paused')
2025-01-19 14:15:05 (7880): VM state change detected. (old = 'paused', new = 'running')
2025-01-19 14:15:15 (7880): VM state change detected. (old = 'running', new = 'paused')
2025-01-19 14:15:36 (7880): VM state change detected. (old = 'paused', new = 'running')
2025-01-19 14:15:46 (7880): VM state change detected. (old = 'running', new = 'paused')
2025-01-19 14:16:06 (7880): VM state change detected. (old = 'paused', new = 'running')
2025-01-19 14:16:16 (7880): VM state change detected. (old = 'running', new = 'paused')
2025-01-19 14:16:36 (7880): VM state change detected. (old = 'paused', new = 'running')
2025-01-19 14:16:46 (7880): VM state change detected. (old = 'running', new = 'paused')
2025-01-19 14:17:06 (7880): VM state change detected. (old = 'paused', new = 'running')
2025-01-19 14:17:16 (7880): VM state change detected. (old = 'running', new = 'paused')
2025-01-19 14:17:36 (7880): VM state change detected. (old = 'paused', new = 'running')
2025-01-19 14:17:46 (7880): VM state change detected. (old = 'running', new = 'paused')
2025-01-19 14:18:06 (7880): VM state change detected. (old = 'paused', new = 'running')
2025-01-19 14:18:16 (7880): VM state change detected. (old = 'running', new = 'paused')
2025-01-19 14:18:36 (7880): VM state change detected. (old = 'paused', new = 'running')
2025-01-19 14:18:46 (7880): VM state change detected. (old = 'running', new = 'paused')
2025-01-19 14:19:06 (7880): VM state change detected. (old = 'paused', new = 'running')
2025-01-19 14:19:16 (7880): VM state change detected. (old = 'running', new = 'paused')
2025-01-19 14:19:36 (7880): VM state change detected. (old = 'paused', new = 'running')
2025-01-19 14:19:46 (7880): VM state change detected. (old = 'running', new = 'paused')
2025-01-19 14:20:06 (7880): VM state change detected. (old = 'paused', new = 'running')
2025-01-19 14:20:16 (7880): VM state change detected. (old = 'running', new = 'paused')
2025-01-19 14:20:36 (7880): VM state change detected. (old = 'paused', new = 'running')
2025-01-19 14:20:46 (7880): VM state change detected. (old = 'running', new = 'paused')
2025-01-19 14:21:06 (7880): VM state change detected. (old = 'paused', new = 'running')
2025-01-19 14:21:16 (7880): VM state change detected. (old = 'running', new = 'paused')
2025-01-19 14:21:36 (7880): VM state change detected. (old = 'paused', new = 'running')
2025-01-19 14:21:46 (7880): VM state change detected. (old = 'running', new = 'paused')
2025-01-19 14:22:06 (7880): VM state change detected. (old = 'paused', new = 'running')
2025-01-19 14:22:16 (7880): VM state change detected. (old = 'running', new = 'paused')
2025-01-19 14:22:36 (7880): VM state change detected. (old = 'paused', new = 'running')
2025-01-19 14:22:56 (7880): VM state change detected. (old = 'running', new = 'paused')
2025-01-19 14:23:16 (7880): VM state change detected. (old = 'paused', new = 'running')
2025-01-19 14:23:26 (7880): VM state change detected. (old = 'running', new = 'paused')
2025-01-19 14:23:46 (7880): VM state change detected. (old = 'paused', new = 'running')
2025-01-19 14:23:56 (7880): VM state change detected. (old = 'running', new = 'paused')
2025-01-19 14:24:16 (7880): VM state change detected. (old = 'paused', new = 'running')
2025-01-19 14:24:36 (7880): VM state change detected. (old = 'running', new = 'paused')
2025-01-19 14:24:56 (7880): VM state change detected. (old = 'paused', new = 'running')
2025-01-19 14:25:06 (7880): VM state change detected. (old = 'running', new = 'paused')
2025-01-19 14:25:26 (7880): VM state change detected. (old = 'paused', new = 'running')
2025-01-19 14:25:46 (7880): VM state change detected. (old = 'running', new = 'paused')
2025-01-19 14:26:06 (7880): VM state change detected. (old = 'paused', new = 'running')
2025-01-19 14:26:26 (7880): VM state change detected. (old = 'running', new = 'paused')
2025-01-19 14:26:46 (7880): VM state change detected. (old = 'paused', new = 'running')
2025-01-19 14:26:56 (7880): VM state change detected. (old = 'running', new = 'paused')
2025-01-19 14:27:16 (7880): VM state change detected. (old = 'paused', new = 'running')
2025-01-19 14:27:26 (7880): VM state change detected. (old = 'running', new = 'paused')
2025-01-19 14:27:46 (7880): VM state change detected. (old = 'paused', new = 'running')
2025-01-19 14:28:06 (7880): VM state change detected. (old = 'running', new = 'paused')
2025-01-19 14:28:26 (7880): VM state change detected. (old = 'paused', new = 'running')
2025-01-19 14:28:37 (7880): VM state change detected. (old = 'running', new = 'paused')
2025-01-19 14:28:57 (7880): VM state change detected. (old = 'paused', new = 'running')
2025-01-19 14:29:07 (7880): VM state change detected. (old = 'running', new = 'paused')
2025-01-19 14:29:27 (7880): VM state change detected. (old = 'paused', new = 'running')
2025-01-19 14:29:37 (7880): VM state change detected. (old = 'running', new = 'paused')
2025-01-19 14:29:57 (7880): VM state change detected. (old = 'paused', new = 'running')
2025-01-19 14:30:07 (7880): VM state change detected. (old = 'running', new = 'paused')
2025-01-19 14:30:27 (7880): VM state change detected. (old = 'paused', new = 'running')
2025-01-19 14:30:47 (7880): VM state change detected. (old = 'running', new = 'paused')
2025-01-19 14:31:07 (7880): VM state change detected. (old = 'paused', new = 'running')
2025-01-19 14:31:17 (7880): VM state change detected. (old = 'running', ne


©2025 CERN