| Name | CMS_682388_1768195911.206048_0 |
| Workunit | 238454319 |
| Created | 12 Jan 2026, 5:31:53 UTC |
| Sent | 12 Jan 2026, 7:04:52 UTC |
| Report deadline | 12 Feb 2026, 7:04:52 UTC |
| Received | 13 Jan 2026, 23:52:16 UTC |
| Server state | Over |
| Outcome | Success |
| Client state | Done |
| Exit status | 0 (0x00000000) |
| Computer ID | 10978737 |
| Run time | 10 hours 59 min 54 sec |
| CPU time | 1 days 1 hours 0 min 13 sec |
| Validate state | Valid |
| Credit | 11,003.21 |
| Device peak FLOPS | 32.86 GFLOPS |
| Application version | CMS Simulation v70.91 (vbox64_mt_mcore_cms) x86_64-pc-linux-gnu |
| Peak working set size | 3.56 GB |
| Peak swap size | 5.28 GB |
| Peak disk usage | 2.17 GB |
Stderr output
<core_client_version>7.24.1</core_client_version> <![CDATA[ <stderr_txt> 2026-01-12 07:15:09 (69383): vboxwrapper version 26210 2026-01-12 07:15:09 (69383): BOINC client version: 7.24.1 2026-01-12 07:15:09 (69383): Detected: VirtualBox VboxManage Interface (Version: 7.0.16) 2026-01-12 07:15:09 (69383): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds) 2026-01-12 07:15:09 (69383): Successfully copied 'init_data.xml' to the shared directory. 2026-01-12 07:15:09 (69383): Create VM. (boinc_de071caff443437f, slot#4) 2026-01-12 07:15:10 (69383): Setting Memory Size for VM. (4584MB) 2026-01-12 07:15:10 (69383): Setting CPU Count for VM. (4) 2026-01-12 07:15:10 (69383): Setting Chipset Options for VM. 2026-01-12 07:15:10 (69383): Setting Graphics Controller Options for VM. (Driver: VBoxVGA, 16MB) 2026-01-12 07:15:10 (69383): Setting Boot Options for VM. 2026-01-12 07:15:10 (69383): Setting Network Configuration for NAT. (Driver: virtio) 2026-01-12 07:15:10 (69383): Enabling VM Network Access. 2026-01-12 07:15:10 (69383): Disabling USB Support for VM. 2026-01-12 07:15:10 (69383): Disabling COM Port Support for VM. 2026-01-12 07:15:10 (69383): Disabling LPT Port Support for VM. 2026-01-12 07:15:10 (69383): Disabling Audio Support for VM. 2026-01-12 07:15:10 (69383): Disabling Clipboard Support for VM. 2026-01-12 07:15:10 (69383): Disabling Drag and Drop Support for VM. 2026-01-12 07:15:10 (69383): Adding storage controller(s) to VM. 2026-01-12 07:15:11 (69383): Adding virtual disk drive to VM. (CMS_2025_04_08_prod.vdi) 2026-01-12 07:15:11 (69383): Adding VirtualBox Guest Additions to VM. 2026-01-12 07:15:11 (69383): Adding network bandwidth throttle group to VM. (Defaulting to 1024GB) 2026-01-12 07:15:11 (69383): forwarding host port 56103 to guest port 80 2026-01-12 07:15:11 (69383): Enabling remote desktop for VM. 2026-01-12 07:15:11 (69383): Enabling shared directory for VM. 2026-01-12 07:15:11 (69383): Starting VM using VBoxManage interface. (boinc_de071caff443437f, slot#4) 2026-01-12 07:15:12 (69383): Successfully started VM. (PID = '69784') 2026-01-12 07:15:12 (69383): Reporting VM Process ID to BOINC. 2026-01-12 07:15:12 (69383): Guest Log: BIOS: VirtualBox 7.0.16 2026-01-12 07:15:12 (69383): Guest Log: CPUID EDX: 0x178bfbff 2026-01-12 07:15:12 (69383): Guest Log: BIOS: No PCI IDE controller, not probing IDE 2026-01-12 07:15:12 (69383): Guest Log: BIOS: AHCI 0-P#0: PCHS=16383/16/63 LCHS=1024/255/63 0x0000000002800000 sectors 2026-01-12 07:15:12 (69383): VM state change detected. (old = 'poweredoff', new = 'running') 2026-01-12 07:15:12 (69383): Detected: Web Application Enabled (http://localhost:56103) 2026-01-12 07:15:12 (69383): Detected: Remote Desktop Enabled (localhost:37769) 2026-01-12 07:15:12 (69383): Preference change detected 2026-01-12 07:15:12 (69383): Setting CPU throttle for VM. (60%) 2026-01-12 07:15:12 (69383): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds)) 2026-01-12 07:15:14 (69383): VM state change detected. (old = 'running', new = 'paused') 2026-01-12 07:15:34 (69383): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032 2026-01-12 07:15:34 (69383): Guest Log: BIOS: Booting from Hard Disk... 2026-01-12 07:15:34 (69383): VM state change detected. (old = 'paused', new = 'running') 2026-01-12 07:15:37 (69383): Guest Log: BIOS: KBD: unsupported int 16h function 03 2026-01-12 07:15:37 (69383): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 2026-01-12 07:15:59 (69383): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds 2026-01-12 07:15:59 (69383): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000) 2026-01-12 07:16:00 (69383): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log 2026-01-12 07:16:00 (69383): Guest Log: 00:00:00.000067 main Log opened 2026-01-12T07:15:59.595596000Z 2026-01-12 07:16:00 (69383): Guest Log: 00:00:00.000125 main OS Product: Linux 2026-01-12 07:16:00 (69383): Guest Log: 00:00:00.000141 main OS Release: 4.14.232-19.cernvm.x86_64 2026-01-12 07:16:00 (69383): Guest Log: 00:00:00.000153 main OS Version: #1 SMP Fri Apr 30 17:12:25 CEST 2021 2026-01-12 07:16:00 (69383): Guest Log: 00:00:00.000162 main Executable: /usr/sbin/VBoxService 2026-01-12 07:16:00 (69383): Guest Log: 00:00:00.000163 main Process ID: 2295 2026-01-12 07:16:00 (69383): Guest Log: 00:00:00.000163 main Package type: LINUX_64BITS_GENERIC 2026-01-12 07:16:00 (69383): Guest Log: 00:00:00.001506 main 5.2.6 r120293 started. Verbose level = 0 2026-01-12 07:16:12 (69383): Guest Log: [INFO] Mounting the shared directory 2026-01-12 07:16:12 (69383): Guest Log: [INFO] Shared directory mounted, enabling vboxmonitor 2026-01-12 07:16:12 (69383): Guest Log: [INFO] Sourcing essential functions from /cvmfs/grid.cern.ch 2026-01-12 07:16:12 (69383): Guest Log: [INFO] Testing connection to cern.ch 2026-01-12 07:16:12 (69383): Guest Log: [INFO] Testing connection to VCCS 2026-01-12 07:16:12 (69383): Guest Log: [INFO] Testing connection to HTCondor-Collector 2026-01-12 07:16:12 (69383): Guest Log: [INFO] Testing connection to HTCondor-CCB 2026-01-12 07:16:12 (69383): Guest Log: [INFO] Testing connection to WMAgent 2026-01-12 07:16:12 (69383): Guest Log: [INFO] Testing connection to EOSCMS 2026-01-12 07:16:13 (69383): Guest Log: [INFO] Testing connection to CMS-Factory 2026-01-12 07:16:13 (69383): Guest Log: [INFO] Testing connection to CMS-Frontier 2026-01-12 07:16:13 (69383): Guest Log: [INFO] Testing connection to Frontier 2026-01-12 07:16:13 (69383): Guest Log: [INFO] Testing connection to http://cms-frontier.openhtc.io:8080/FrontierProd/Frontier/ 2026-01-12 07:16:14 (69383): Guest Log: [INFO] Could not find a local HTTP proxy 2026-01-12 07:16:14 (69383): Guest Log: [INFO] CVMFS and Frontier will have to use DIRECT connections 2026-01-12 07:16:14 (69383): Guest Log: [INFO] This makes the application less efficient 2026-01-12 07:16:14 (69383): Guest Log: [INFO] It also puts higher load on the project servers 2026-01-12 07:16:14 (69383): Guest Log: [INFO] Setting up a local HTTP proxy is highly recommended 2026-01-12 07:16:14 (69383): Guest Log: [INFO] Advice can be found in the project forum 2026-01-12 07:16:15 (69383): Guest Log: [INFO] Reloading and probing the CVMFS configuration 2026-01-12 07:16:19 (69383): Guest Log: [INFO] Probing /cvmfs/grid.cern.ch... OK 2026-01-12 07:16:21 (69383): Guest Log: [INFO] Probing /cvmfs/cms-ib.cern.ch... OK 2026-01-12 07:16:21 (69383): Guest Log: [INFO] Probing /cvmfs/singularity.opensciencegrid.org... OK 2026-01-12 07:16:21 (69383): Guest Log: [INFO] Probing /cvmfs/cms.cern.ch... OK 2026-01-12 07:16:24 (69383): Guest Log: [INFO] Probing /cvmfs/oasis.opensciencegrid.org... OK 2026-01-12 07:16:24 (69383): Guest Log: [INFO] Excerpt from "cvmfs_config stat": VERSION HOST PROXY 2026-01-12 07:16:24 (69383): Guest Log: [INFO] 2.7.2.0 http://s1cern-cvmfs.openhtc.io DIRECT 2026-01-12 07:16:24 (69383): Guest Log: [INFO] Environment HTTP proxy: not set 2026-01-12 07:16:24 (69383): Guest Log: [INFO] Reading volunteer information 2026-01-12 07:16:30 (69383): Guest Log: [INFO] Requesting an X509 credential from LHC@home 2026-01-12 07:16:32 (69383): Guest Log: [INFO] Requesting an idtoken from LHC@home 2026-01-12 07:16:32 (69383): Guest Log: [INFO] CMS application starting. Check log files. 2026-01-12 07:20:25 (69383): VM state change detected. (old = 'running', new = 'paused') 2026-01-12 07:20:45 (69383): VM state change detected. (old = 'paused', new = 'running') 2026-01-12 07:31:28 (69383): VM state change detected. (old = 'running', new = 'paused') 2026-01-12 07:31:49 (69383): VM state change detected. (old = 'paused', new = 'running') 2026-01-12 07:42:51 (69383): VM state change detected. (old = 'running', new = 'paused') 2026-01-12 07:43:11 (69383): VM state change detected. (old = 'paused', new = 'running') 2026-01-12 07:54:35 (69383): VM state change detected. (old = 'running', new = 'paused') 2026-01-12 07:54:54 (69383): VM state change detected. (old = 'paused', new = 'running') 2026-01-12 08:00:36 (69383): VM state change detected. (old = 'running', new = 'paused') 2026-01-12 08:00:56 (69383): VM state change detected. (old = 'paused', new = 'running') 2026-01-12 08:40:08 (69383): VM state change detected. (old = 'running', new = 'paused') 2026-01-12 08:40:28 (69383): VM state change detected. (old = 'paused', new = 'running') 2026-01-12 08:56:42 (69383): Status Report: Job Duration: '64800.000000' 2026-01-12 08:56:42 (69383): Status Report: Elapsed Time: '6000.000000' 2026-01-12 08:56:42 (69383): Status Report: CPU Time: '13235.240000' 2026-01-12 09:16:10 (69383): VM state change detected. (old = 'running', new = 'paused') 2026-01-12 09:26:47 (69383): VM state change detected. (old = 'paused', new = 'running') 2026-01-12 09:43:41 (69383): VM state change detected. (old = 'running', new = 'paused') 2026-01-12 10:07:20 (69383): VM state change detected. (old = 'paused', new = 'running') 2026-01-12 10:07:32 (69383): Guest Log: 02:15:20.881555 timesync vgsvcTimeSyncWorker: Radical guest time change: 2 181 269 043 000ns (GuestNow=1 768 212 451 732 036 000 ns GuestLast=1 768 210 270 462 993 000 ns fSetTimeLastLoop=true ) 2026-01-12 10:07:57 (69383): VM state change detected. (old = 'running', new = 'paused') 2026-01-12 10:26:28 (69383): VM state change detected. (old = 'paused', new = 'running') 2026-01-12 10:26:45 (69383): VM state change detected. (old = 'running', new = 'paused') 2026-01-12 10:53:30 (69383): VM state change detected. (old = 'paused', new = 'running') 2026-01-12 10:53:48 (69383): Guest Log: 02:16:20.952587 timesync vgsvcTimeSyncWorker: Radical guest time change: 2 726 757 060 000ns (GuestNow=1 768 215 228 553 067 000 ns GuestLast=1 768 212 501 796 007 000 ns fSetTimeLastLoop=true ) 2026-01-12 10:54:11 (69383): VM state change detected. (old = 'running', new = 'paused') 2026-01-12 11:02:56 (69383): VM state change detected. (old = 'paused', new = 'running') 2026-01-12 11:03:29 (69383): VM state change detected. (old = 'running', new = 'paused') 2026-01-12 11:38:10 (69383): VM state change detected. (old = 'paused', new = 'running') 2026-01-12 11:38:14 (69383): Guest Log: 02:17:21.043264 timesync vgsvcTimeSyncWorker: Radical host time change: 2 090 114 000 000ns (HostNow=1 768 217 893 407 000 000 ns HostLast=1 768 215 803 293 000 000 ns) 2026-01-12 11:38:23 (69383): Guest Log: 02:17:31.049428 timesync vgsvcTimeSyncWorker: Radical guest time change: 2 614 904 920 000ns (GuestNow=1 768 217 903 480 916 000 ns GuestLast=1 768 215 288 575 996 000 ns fSetTimeLastLoop=true ) 2026-01-12 11:40:12 (69383): VM state change detected. (old = 'running', new = 'paused') 2026-01-12 12:01:29 (69383): VM state change detected. (old = 'paused', new = 'running') 2026-01-12 12:31:26 (69383): VM state change detected. (old = 'running', new = 'paused') 2026-01-12 12:44:42 (69383): VM state change detected. (old = 'paused', new = 'running') 2026-01-12 12:47:52 (69383): VM state change detected. (old = 'running', new = 'paused') 2026-01-12 12:52:52 (69383): VM state change detected. (old = 'paused', new = 'running') 2026-01-12 12:56:35 (69383): VM state change detected. (old = 'running', new = 'paused') 2026-01-12 13:01:35 (69383): VM state change detected. (old = 'paused', new = 'running') 2026-01-12 13:01:43 (69383): VM state change detected. (old = 'running', new = 'paused') 2026-01-12 13:46:42 (69383): VM state change detected. (old = 'paused', new = 'running') 2026-01-12 13:46:48 (69383): Guest Log: 02:56:24.217897 timesync vgsvcTimeSyncWorker: Radical host time change: 2 709 896 000 000ns (HostNow=1 768 225 608 515 000 000 ns HostLast=1 768 222 898 619 000 000 ns) 2026-01-12 13:46:58 (69383): Guest Log: 02:56:34.218437 timesync vgsvcTimeSyncWorker: Radical guest time change: 2 709 899 368 000ns (GuestNow=1 768 225 618 523 650 000 ns GuestLast=1 768 222 908 624 282 000 ns fSetTimeLastLoop=true ) 2026-01-12 13:47:30 (69383): VM state change detected. (old = 'running', new = 'paused') 2026-01-12 14:14:03 (69383): VM state change detected. (old = 'paused', new = 'running') 2026-01-12 14:18:44 (69383): VM state change detected. (old = 'running', new = 'paused') 2026-01-12 14:31:34 (69383): VM state change detected. (old = 'paused', new = 'running') 2026-01-12 14:31:36 (69383): VM state change detected. (old = 'running', new = 'paused') 2026-01-12 14:39:33 (69383): VM state change detected. (old = 'paused', new = 'running') 2026-01-12 14:39:51 (69383): VM state change detected. (old = 'running', new = 'paused') 2026-01-12 14:44:50 (69383): VM state change detected. (old = 'paused', new = 'running') 2026-01-12 14:45:15 (69383): VM state change detected. (old = 'running', new = 'paused') 2026-01-12 14:50:15 (69383): VM state change detected. (old = 'paused', new = 'running') 2026-01-12 14:50:24 (69383): VM state change detected. (old = 'running', new = 'paused') 2026-01-12 14:55:24 (69383): VM state change detected. (old = 'paused', new = 'running') 2026-01-12 14:56:41 (69383): VM state change detected. (old = 'running', new = 'paused') 2026-01-12 15:06:07 (69383): VM state change detected. (old = 'paused', new = 'running') 2026-01-12 15:09:52 (69383): VM state change detected. (old = 'running', new = 'paused') 2026-01-12 15:30:06 (69383): VM state change detected. (old = 'paused', new = 'running') 2026-01-12 15:30:10 (69383): VM state change detected. (old = 'running', new = 'paused') 2026-01-12 15:47:40 (69383): VM state change detected. (old = 'paused', new = 'running') 2026-01-12 15:47:44 (69383): VM state change detected. (old = 'running', new = 'paused') 2026-01-12 16:13:46 (69383): VM state change detected. (old = 'paused', new = 'running') 2026-01-12 16:13:50 (69383): Guest Log: 03:07:54.907614 timesync vgsvcTimeSyncWorker: Radical host time change: 2 622 787 000 000ns (HostNow=1 768 234 430 493 000 000 ns HostLast=1 768 231 807 706 000 000 ns) 2026-01-12 16:14:00 (69383): Guest Log: 03:08:04.913191 timesync vgsvcTimeSyncWorker: Radical guest time change: 2 622 792 007 000ns (GuestNow=1 768 234 440 498 663 000 ns GuestLast=1 768 231 817 706 656 000 ns fSetTimeLastLoop=true ) 2026-01-12 16:17:42 (69383): VM state change detected. (old = 'running', new = 'paused') 2026-01-12 17:10:29 (69383): VM state change detected. (old = 'paused', new = 'running') 2026-01-12 17:10:38 (69383): Guest Log: 03:11:55.340566 timesync vgsvcTimeSyncWorker: Radical host time change: 3 177 268 000 000ns (HostNow=1 768 237 838 062 000 000 ns HostLast=1 768 234 660 794 000 000 ns) 2026-01-12 17:10:48 (69383): Guest Log: 03:12:05.347495 timesync vgsvcTimeSyncWorker: Radical guest time change: 3 177 328 970 000ns (GuestNow=1 768 237 848 161 967 000 ns GuestLast=1 768 234 670 832 997 000 ns fSetTimeLastLoop=true ) 2026-01-12 17:16:56 (69383): Status Report: Job Duration: '64800.000000' 2026-01-12 17:16:56 (69383): Status Report: Elapsed Time: '12000.000000' 2026-01-12 17:16:56 (69383): Status Report: CPU Time: '26953.590000' 2026-01-12 17:23:20 (69383): VM state change detected. (old = 'running', new = 'paused') 2026-01-12 17:29:22 (69383): VM state change detected. (old = 'paused', new = 'running') 2026-01-12 17:35:45 (69383): VM state change detected. (old = 'running', new = 'paused') 2026-01-12 17:36:05 (69383): VM state change detected. (old = 'paused', new = 'running') 2026-01-12 17:38:44 (69383): VM state change detected. (old = 'running', new = 'paused') 2026-01-12 17:44:14 (69383): VM state change detected. (old = 'paused', new = 'running') 2026-01-12 17:52:37 (69383): VM state change detected. (old = 'running', new = 'paused') 2026-01-12 18:10:52 (69383): VM state change detected. (old = 'paused', new = 'running') 2026-01-12 18:11:01 (69383): VM state change detected. (old = 'running', new = 'paused') 2026-01-12 18:29:06 (69383): VM state change detected. (old = 'paused', new = 'running') 2026-01-12 18:29:12 (69383): Guest Log: 03:42:17.715511 timesync vgsvcTimeSyncWorker: Radical guest time change: 1 816 496 496 000ns (GuestNow=1 768 241 466 929 730 000 ns GuestLast=1 768 239 650 433 234 000 ns fSetTimeLastLoop=true ) 2026-01-12 18:29:26 (69383): VM state change detected. (old = 'running', new = 'paused') 2026-01-12 18:59:41 (69383): VM state change detected. (old = 'paused', new = 'running') 2026-01-12 18:59:46 (69383): Guest Log: 03:42:37.805197 timesync vgsvcTimeSyncWorker: Radical host time change: 1 824 784 000 000ns (HostNow=1 768 244 386 833 000 000 ns HostLast=1 768 242 562 049 000 000 ns) 2026-01-12 18:59:57 (69383): Guest Log: 03:42:47.819959 timesync vgsvcTimeSyncWorker: Radical guest time change: 2 909 921 742 000ns (GuestNow=1 768 244 396 894 462 000 ns GuestLast=1 768 241 486 972 720 000 ns fSetTimeLastLoop=true ) 2026-01-12 19:02:20 (69383): VM state change detected. (old = 'running', new = 'paused') 2026-01-12 19:07:20 (69383): VM state change detected. (old = 'paused', new = 'running') 2026-01-12 19:07:43 (69383): VM state change detected. (old = 'running', new = 'paused') 2026-01-12 19:12:43 (69383): VM state change detected. (old = 'paused', new = 'running') 2026-01-12 19:16:05 (69383): VM state change detected. (old = 'running', new = 'paused') 2026-01-12 19:25:56 (69383): VM state change detected. (old = 'paused', new = 'running') 2026-01-12 19:26:45 (69383): VM state change detected. (old = 'running', new = 'paused') 2026-01-12 19:31:45 (69383): VM state change detected. (old = 'paused', new = 'running') 2026-01-12 19:32:07 (69383): VM state change detected. (old = 'running', new = 'paused') 2026-01-12 21:25:44 (69383): VM state change detected. (old = 'paused', new = 'running') 2026-01-12 21:25:46 (69383): Guest Log: 03:50:08.335267 timesync vgsvcTimeSyncWorker: Radical host time change: 6 826 973 000 000ns (HostNow=1 768 253 145 260 000 000 ns HostLast=1 768 246 318 287 000 000 ns) 2026-01-12 21:25:56 (69383): Guest Log: 03:50:18.412427 timesync vgsvcTimeSyncWorker: Radical guest time change: 6 827 071 863 000ns (GuestNow=1 768 253 155 347 928 000 ns GuestLast=1 768 246 328 276 065 000 ns fSetTimeLastLoop=true ) 2026-01-12 21:26:59 (69383): VM state change detected. (old = 'running', new = 'paused') 2026-01-12 21:39:07 (69383): VM state change detected. (old = 'paused', new = 'running') 2026-01-12 21:39:54 (69383): VM state change detected. (old = 'running', new = 'paused') 2026-01-12 21:40:14 (69383): VM state change detected. (old = 'paused', new = 'running') 2026-01-12 21:42:49 (69383): VM state change detected. (old = 'running', new = 'paused') 2026-01-12 21:59:39 (69383): VM state change detected. (old = 'paused', new = 'running') 2026-01-12 21:59:43 (69383): VM state change detected. (old = 'running', new = 'paused') 2026-01-12 22:04:43 (69383): VM state change detected. (old = 'paused', new = 'running') 2026-01-12 22:04:52 (69383): VM state change detected. (old = 'running', new = 'paused') 2026-01-12 22:51:12 (69383): VM state change detected. (old = 'paused', new = 'running') 2026-01-12 22:51:14 (69383): Guest Log: 03:54:58.788628 timesync vgsvcTimeSyncWorker: Radical host time change: 2 790 145 000 000ns (HostNow=1 768 258 274 157 000 000 ns HostLast=1 768 255 484 012 000 000 ns) 2026-01-12 22:51:14 (69383): Guest Log: 03:54:58.788676 timesync vgsvcTimeSyncWorker: Radical guest time change: 2 068 364 022 000ns (GuestNow=1 768 255 494 050 022 000 ns GuestLast=1 768 253 425 686 000 000 ns fSetTimeLastLoop=true ) 2026-01-12 22:51:24 (69383): Guest Log: 03:55:08.790492 timesync vgsvcTimeSyncWorker: Radical guest time change: 2 790 108 939 000ns (GuestNow=1 768 258 284 158 961 000 ns GuestLast=1 768 255 494 050 022 000 ns fSetTimeLastLoop=true ) 2026-01-12 22:51:47 (69383): VM state change detected. (old = 'running', new = 'paused') 2026-01-12 23:05:40 (69383): VM state change detected. (old = 'paused', new = 'running') 2026-01-12 23:08:20 (69383): VM state change detected. (old = 'running', new = 'paused') 2026-01-12 23:17:52 (69383): VM state change detected. (old = 'paused', new = 'running') 2026-01-12 23:23:52 (69383): VM state change detected. (old = 'running', new = 'paused') 2026-01-12 23:33:02 (69383): VM state change detected. (old = 'paused', new = 'running') 2026-01-12 23:36:28 (69383): VM state change detected. (old = 'running', new = 'paused') 2026-01-12 23:55:47 (69383): VM state change detected. (old = 'paused', new = 'running') 2026-01-12 23:59:11 (69383): VM state change detected. (old = 'running', new = 'paused') 2026-01-13 00:08:40 (69383): VM state change detected. (old = 'paused', new = 'running') 2026-01-13 00:12:01 (69383): VM state change detected. (old = 'running', new = 'paused') 2026-01-13 07:45:41 (4124): vboxwrapper version 26210 2026-01-13 07:45:41 (4124): BOINC client version: 7.24.1 2026-01-13 07:45:41 (4124): Detected: VirtualBox VboxManage Interface (Version: 7.0.16) 2026-01-13 07:45:41 (4124): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds) 2026-01-13 07:45:41 (4124): Guest Log: BIOS: VirtualBox 7.0.16 2026-01-13 07:45:41 (4124): Guest Log: CPUID EDX: 0x178bfbff 2026-01-13 07:45:41 (4124): Guest Log: BIOS: No PCI IDE controller, not probing IDE 2026-01-13 07:45:41 (4124): Guest Log: BIOS: AHCI 0-P#0: PCHS=16383/16/63 LCHS=1024/255/63 0x0000000002800000 sectors 2026-01-13 07:45:41 (4124): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032 2026-01-13 07:45:41 (4124): Guest Log: BIOS: Booting from Hard Disk... 2026-01-13 07:45:41 (4124): Guest Log: BIOS: KBD: unsupported int 16h function 03 2026-01-13 07:45:41 (4124): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 2026-01-13 07:45:41 (4124): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds 2026-01-13 07:45:41 (4124): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000) 2026-01-13 07:45:41 (4124): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log 2026-01-13 07:45:41 (4124): Guest Log: 00:00:00.000067 main Log opened 2026-01-12T07:15:59.595596000Z 2026-01-13 07:45:41 (4124): Guest Log: 00:00:00.000125 main OS Product: Linux 2026-01-13 07:45:41 (4124): Guest Log: 00:00:00.000141 main OS Release: 4.14.232-19.cernvm.x86_64 2026-01-13 07:45:41 (4124): Guest Log: 00:00:00.000153 main OS Version: #1 SMP Fri Apr 30 17:12:25 CEST 2021 2026-01-13 07:45:41 (4124): Guest Log: 00:00:00.000162 main Executable: /usr/sbin/VBoxService 2026-01-13 07:45:41 (4124): Guest Log: 00:00:00.000163 main Process ID: 2295 2026-01-13 07:45:41 (4124): Guest Log: 00:00:00.000163 main Package type: LINUX_64BITS_GENERIC 2026-01-13 07:45:41 (4124): Guest Log: 00:00:00.001506 main 5.2.6 r120293 started. Verbose level = 0 2026-01-13 07:45:41 (4124): Guest Log: [INFO] Mounting the shared directory 2026-01-13 07:45:41 (4124): Guest Log: [INFO] Shared directory mounted, enabling vboxmonitor 2026-01-13 07:45:41 (4124): Guest Log: [INFO] Sourcing essential functions from /cvmfs/grid.cern.ch 2026-01-13 07:45:41 (4124): Guest Log: [INFO] Testing connection to cern.ch 2026-01-13 07:45:41 (4124): Guest Log: [INFO] Testing connection to VCCS 2026-01-13 07:45:41 (4124): Guest Log: [INFO] Testing connection to HTCondor-Collector 2026-01-13 07:45:41 (4124): Guest Log: [INFO] Testing connection to HTCondor-CCB 2026-01-13 07:45:41 (4124): Guest Log: [INFO] Testing connection to WMAgent 2026-01-13 07:45:41 (4124): Guest Log: [INFO] Testing connection to EOSCMS 2026-01-13 07:45:41 (4124): Guest Log: [INFO] Testing connection to CMS-Factory 2026-01-13 07:45:41 (4124): Guest Log: [INFO] Testing connection to CMS-Frontier 2026-01-13 07:45:41 (4124): Guest Log: [INFO] Testing connection to Frontier 2026-01-13 07:45:41 (4124): Guest Log: [INFO] Testing connection to http://cms-frontier.openhtc.io:8080/FrontierProd/Frontier/ 2026-01-13 07:45:41 (4124): Guest Log: [INFO] Could not find a local HTTP proxy 2026-01-13 07:45:41 (4124): Guest Log: [INFO] CVMFS and Frontier will have to use DIRECT connections 2026-01-13 07:45:41 (4124): Guest Log: [INFO] This makes the application less efficient 2026-01-13 07:45:41 (4124): Guest Log: [INFO] It also puts higher load on the project servers 2026-01-13 07:45:41 (4124): Guest Log: [INFO] Setting up a local HTTP proxy is highly recommended 2026-01-13 07:45:41 (4124): Guest Log: [INFO] Advice can be found in the project forum 2026-01-13 07:45:41 (4124): Guest Log: [INFO] Reloading and probing the CVMFS configuration 2026-01-13 07:45:41 (4124): Guest Log: [INFO] Probing /cvmfs/grid.cern.ch... OK 2026-01-13 07:45:41 (4124): Guest Log: [INFO] Probing /cvmfs/cms-ib.cern.ch... OK 2026-01-13 07:45:41 (4124): Guest Log: [INFO] Probing /cvmfs/singularity.opensciencegrid.org... OK 2026-01-13 07:45:41 (4124): Guest Log: [INFO] Probing /cvmfs/cms.cern.ch... OK 2026-01-13 07:45:41 (4124): Guest Log: [INFO] Probing /cvmfs/oasis.opensciencegrid.org... OK 2026-01-13 07:45:41 (4124): Guest Log: [INFO] Excerpt from "cvmfs_config stat": VERSION HOST PROXY 2026-01-13 07:45:41 (4124): Guest Log: [INFO] 2.7.2.0 http://s1cern-cvmfs.openhtc.io DIRECT 2026-01-13 07:45:41 (4124): Guest Log: [INFO] Environment HTTP proxy: not set 2026-01-13 07:45:41 (4124): Guest Log: [INFO] Reading volunteer information 2026-01-13 07:45:41 (4124): Guest Log: [INFO] Requesting an X509 credential from LHC@home 2026-01-13 07:45:41 (4124): Guest Log: [INFO] Requesting an idtoken from LHC@home 2026-01-13 07:45:41 (4124): Guest Log: [INFO] CMS application starting. Check log files. 2026-01-13 07:45:41 (4124): Guest Log: 02:15:20.881555 timesync vgsvcTimeSyncWorker: Radical guest time change: 2 181 269 043 000ns (GuestNow=1 768 212 451 732 036 000 ns GuestLast=1 768 210 270 462 993 000 ns fSetTimeLastLoop=true ) 2026-01-13 07:45:41 (4124): Guest Log: 02:16:20.952587 timesync vgsvcTimeSyncWorker: Radical guest time change: 2 726 757 060 000ns (GuestNow=1 768 215 228 553 067 000 ns GuestLast=1 768 212 501 796 007 000 ns fSetTimeLastLoop=true ) 2026-01-13 07:45:41 (4124): Guest Log: 02:17:21.043264 timesync vgsvcTimeSyncWorker: Radical host time change: 2 090 114 000 000ns (HostNow=1 768 217 893 407 000 000 ns HostLast=1 768 215 803 293 000 000 ns) 2026-01-13 07:45:41 (4124): Guest Log: 02:17:31.049428 timesync vgsvcTimeSyncWorker: Radical guest time change: 2 614 904 920 000ns (GuestNow=1 768 217 903 480 916 000 ns GuestLast=1 768 215 288 575 996 000 ns fSetTimeLastLoop=true ) 2026-01-13 07:45:41 (4124): Guest Log: 02:56:24.217897 timesync vgsvcTimeSyncWorker: Radical host time change: 2 709 896 000 000ns (HostNow=1 768 225 608 515 000 000 ns HostLast=1 768 222 898 619 000 000 ns) 2026-01-13 07:45:41 (4124): Guest Log: 02:56:34.218437 timesync vgsvcTimeSyncWorker: Radical guest time change: 2 709 899 368 000ns (GuestNow=1 768 225 618 523 650 000 ns GuestLast=1 768 222 908 624 282 000 ns fSetTimeLastLoop=true ) 2026-01-13 07:45:41 (4124): Guest Log: 03:07:54.907614 timesync vgsvcTimeSyncWorker: Radical host time change: 2 622 787 000 000ns (HostNow=1 768 234 430 493 000 000 ns HostLast=1 768 231 807 706 000 000 ns) 2026-01-13 07:45:41 (4124): Guest Log: 03:08:04.913191 timesync vgsvcTimeSyncWorker: Radical guest time change: 2 622 792 007 000ns (GuestNow=1 768 234 440 498 663 000 ns GuestLast=1 768 231 817 706 656 000 ns fSetTimeLastLoop=true ) 2026-01-13 07:45:41 (4124): Guest Log: 03:11:55.340566 timesync vgsvcTimeSyncWorker: Radical host time change: 3 177 268 000 000ns (HostNow=1 768 237 838 062 000 000 ns HostLast=1 768 234 660 794 000 000 ns) 2026-01-13 07:45:41 (4124): Guest Log: 03:12:05.347495 timesync vgsvcTimeSyncWorker: Radical guest time change: 3 177 328 970 000ns (GuestNow=1 768 237 848 161 967 000 ns GuestLast=1 768 234 670 832 997 000 ns fSetTimeLastLoop=true ) 2026-01-13 07:45:41 (4124): Guest Log: 03:42:17.715511 timesync vgsvcTimeSyncWorker: Radical guest time change: 1 816 496 496 000ns (GuestNow=1 768 241 466 929 730 000 ns GuestLast=1 768 239 650 433 234 000 ns fSetTimeLastLoop=true ) 2026-01-13 07:45:41 (4124): Guest Log: 03:42:37.805197 timesync vgsvcTimeSyncWorker: Radical host time change: 1 824 784 000 000ns (HostNow=1 768 244 386 833 000 000 ns HostLast=1 768 242 562 049 000 000 ns) 2026-01-13 07:45:41 (4124): Guest Log: 03:42:47.819959 timesync vgsvcTimeSyncWorker: Radical guest time change: 2 909 921 742 000ns (GuestNow=1 768 244 396 894 462 000 ns GuestLast=1 768 241 486 972 720 000 ns fSetTimeLastLoop=true ) 2026-01-13 07:45:41 (4124): Guest Log: 03:50:08.335267 timesync vgsvcTimeSyncWorker: Radical host time change: 6 826 973 000 000ns (HostNow=1 768 253 145 260 000 000 ns HostLast=1 768 246 318 287 000 000 ns) 2026-01-13 07:45:41 (4124): Guest Log: 03:50:18.412427 timesync vgsvcTimeSyncWorker: Radical guest time change: 6 827 071 863 000ns (GuestNow=1 768 253 155 347 928 000 ns GuestLast=1 768 246 328 276 065 000 ns fSetTimeLastLoop=true ) 2026-01-13 07:45:41 (4124): Guest Log: 03:54:58.788628 timesync vgsvcTimeSyncWorker: Radical host time change: 2 790 145 000 000ns (HostNow=1 768 258 274 157 000 000 ns HostLast=1 768 255 484 012 000 000 ns) 2026-01-13 07:45:41 (4124): Guest Log: 03:54:58.788676 timesync vgsvcTimeSyncWorker: Radical guest time change: 2 068 364 022 000ns (GuestNow=1 768 255 494 050 022 000 ns GuestLast=1 768 253 425 686 000 000 ns fSetTimeLastLoop=true ) 2026-01-13 07:45:41 (4124): Guest Log: 03:55:08.790492 timesync vgsvcTimeSyncWorker: Radical guest time change: 2 790 108 939 000ns (GuestNow=1 768 258 284 158 961 000 ns GuestLast=1 768 255 494 050 022 000 ns fSetTimeLastLoop=true ) 2026-01-13 07:45:41 (4124): Starting VM using VBoxManage interface. (boinc_de071caff443437f, slot#4) 2026-01-13 07:45:42 (4124): Successfully started VM. (PID = '4637') 2026-01-13 07:45:42 (4124): Reporting VM Process ID to BOINC. 2026-01-13 07:45:42 (4124): Guest Log: BIOS: VirtualBox 7.0.16 2026-01-13 07:45:42 (4124): Guest Log: CPUID EDX: 0x178bfbff 2026-01-13 07:45:42 (4124): Guest Log: BIOS: No PCI IDE controller, not probing IDE 2026-01-13 07:45:42 (4124): Guest Log: BIOS: AHCI 0-P#0: PCHS=16383/16/63 LCHS=1024/255/63 0x0000000002800000 sectors 2026-01-13 07:45:42 (4124): VM state change detected. (old = 'poweredoff', new = 'running') 2026-01-13 07:45:42 (4124): Detected: Web Application Enabled (http://localhost:56103) 2026-01-13 07:45:42 (4124): Detected: Remote Desktop Enabled (localhost:37769) 2026-01-13 07:45:42 (4124): Status Report: Job Duration: '64800.000000' 2026-01-13 07:45:42 (4124): Status Report: Elapsed Time: '15359.000000' 2026-01-13 07:45:42 (4124): Status Report: CPU Time: '34764.720000' 2026-01-13 07:45:42 (4124): Preference change detected 2026-01-13 07:45:42 (4124): Setting CPU throttle for VM. (60%) 2026-01-13 07:45:42 (4124): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds)) 2026-01-13 07:45:44 (4124): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032 2026-01-13 07:45:44 (4124): Guest Log: BIOS: Booting from Hard Disk... 2026-01-13 07:45:46 (4124): Guest Log: BIOS: KBD: unsupported int 16h function 03 2026-01-13 07:45:46 (4124): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 2026-01-13 07:46:13 (4124): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds 2026-01-13 07:46:13 (4124): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000) 2026-01-13 07:46:13 (4124): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log 2026-01-13 07:46:13 (4124): Guest Log: 00:00:00.000194 main Log opened 2026-01-13T07:46:13.544082000Z 2026-01-13 07:46:13 (4124): Guest Log: 00:00:00.000274 main OS Product: Linux 2026-01-13 07:46:13 (4124): Guest Log: 00:00:00.000289 main OS Release: 4.14.232-19.cernvm.x86_64 2026-01-13 07:46:13 (4124): Guest Log: 00:00:00.000299 main OS Version: #1 SMP Fri Apr 30 17:12:25 CEST 2021 2026-01-13 07:46:13 (4124): Guest Log: 00:00:00.000308 main Executable: /usr/sbin/VBoxService 2026-01-13 07:46:13 (4124): Guest Log: 00:00:00.000308 main Process ID: 2285 2026-01-13 07:46:13 (4124): Guest Log: 00:00:00.000308 main Package type: LINUX_64BITS_GENERIC 2026-01-13 07:46:13 (4124): Guest Log: 00:00:00.000939 main 5.2.6 r120293 started. Verbose level = 0 2026-01-13 07:46:23 (4124): Guest Log: [INFO] Mounting the shared directory 2026-01-13 07:46:23 (4124): Guest Log: [INFO] Shared directory mounted, enabling vboxmonitor 2026-01-13 07:46:23 (4124): Guest Log: [INFO] Sourcing essential functions from /cvmfs/grid.cern.ch 2026-01-13 07:46:23 (4124): Guest Log: [INFO] Testing connection to cern.ch 2026-01-13 07:46:24 (4124): Guest Log: [INFO] Testing connection to VCCS 2026-01-13 07:46:24 (4124): Guest Log: [INFO] Testing connection to HTCondor-Collector 2026-01-13 07:46:24 (4124): Guest Log: [INFO] Testing connection to HTCondor-CCB 2026-01-13 07:46:24 (4124): Guest Log: [INFO] Testing connection to WMAgent 2026-01-13 07:46:24 (4124): Guest Log: [INFO] Testing connection to EOSCMS 2026-01-13 07:46:24 (4124): Guest Log: [INFO] Testing connection to CMS-Factory 2026-01-13 07:46:24 (4124): Guest Log: [INFO] Testing connection to CMS-Frontier 2026-01-13 07:46:24 (4124): Guest Log: [INFO] Testing connection to Frontier 2026-01-13 07:46:25 (4124): Guest Log: [INFO] Testing connection to http://cms-frontier.openhtc.io:8080/FrontierProd/Frontier/ 2026-01-13 07:46:25 (4124): Guest Log: [INFO] Could not find a local HTTP proxy 2026-01-13 07:46:25 (4124): Guest Log: [INFO] CVMFS and Frontier will have to use DIRECT connections 2026-01-13 07:46:25 (4124): Guest Log: [INFO] This makes the application less efficient 2026-01-13 07:46:25 (4124): Guest Log: [INFO] It also puts higher load on the project servers 2026-01-13 07:46:25 (4124): Guest Log: [INFO] Setting up a local HTTP proxy is highly recommended 2026-01-13 07:46:25 (4124): Guest Log: [INFO] Advice can be found in the project forum 2026-01-13 07:46:25 (4124): Guest Log: [INFO] Reloading and probing the CVMFS configuration 2026-01-13 07:46:29 (4124): Guest Log: [INFO] Probing /cvmfs/grid.cern.ch... OK 2026-01-13 07:46:31 (4124): Guest Log: [INFO] Probing /cvmfs/cms.cern.ch... OK 2026-01-13 07:46:32 (4124): Guest Log: [INFO] Probing /cvmfs/cms-ib.cern.ch... OK 2026-01-13 07:46:32 (4124): Guest Log: [INFO] Probing /cvmfs/oasis.opensciencegrid.org... OK 2026-01-13 07:46:32 (4124): Guest Log: [INFO] Probing /cvmfs/singularity.opensciencegrid.org... OK 2026-01-13 07:46:32 (4124): Guest Log: [INFO] Excerpt from "cvmfs_config stat": VERSION HOST PROXY 2026-01-13 07:46:32 (4124): Guest Log: [INFO] 2.7.2.0 http://s1cern-cvmfs.openhtc.io DIRECT 2026-01-13 07:46:32 (4124): Guest Log: [INFO] Environment HTTP proxy: not set 2026-01-13 07:46:32 (4124): Guest Log: [INFO] Reading volunteer information 2026-01-13 07:46:34 (4124): Guest Log: [INFO] Requesting an X509 credential from LHC@home 2026-01-13 07:46:34 (4124): Guest Log: [INFO] Requesting an idtoken from LHC@home 2026-01-13 07:46:35 (4124): Guest Log: [INFO] CMS application starting. Check log files. 2026-01-13 07:55:25 (4124): VM state change detected. (old = 'running', new = 'paused') 2026-01-13 07:55:45 (4124): VM state change detected. (old = 'paused', new = 'running') 2026-01-13 07:57:23 (4124): VM state change detected. (old = 'running', new = 'paused') 2026-01-13 09:39:10 (4124): VM state change detected. (old = 'paused', new = 'running') 2026-01-13 09:39:11 (4124): Guest Log: 00:10:50.712324 timesync vgsvcTimeSyncWorker: Radical host time change: 6 117 758 000 000ns (HostNow=1 768 297 151 638 000 000 ns HostLast=1 768 291 033 880 000 000 ns) 2026-01-13 09:39:22 (4124): Guest Log: 00:11:00.749537 timesync vgsvcTimeSyncWorker: Radical guest time change: 6 137 670 047 000ns (GuestNow=1 768 297 161 800 691 000 ns GuestLast=1 768 291 024 130 644 000 ns fSetTimeLastLoop=true ) 2026-01-13 09:49:16 (4124): VM state change detected. (old = 'running', new = 'paused') 2026-01-13 09:49:35 (4124): VM state change detected. (old = 'paused', new = 'running') 2026-01-13 10:00:41 (4124): VM state change detected. (old = 'running', new = 'paused') 2026-01-13 10:01:01 (4124): VM state change detected. (old = 'paused', new = 'running') 2026-01-13 10:11:56 (4124): VM state change detected. (old = 'running', new = 'paused') 2026-01-13 10:12:16 (4124): VM state change detected. (old = 'paused', new = 'running') 2026-01-13 10:23:11 (4124): VM state change detected. (old = 'running', new = 'paused') 2026-01-13 10:23:31 (4124): VM state change detected. (old = 'paused', new = 'running') 2026-01-13 10:34:16 (4124): VM state change detected. (old = 'running', new = 'paused') 2026-01-13 10:34:36 (4124): VM state change detected. (old = 'paused', new = 'running') 2026-01-13 10:43:10 (4124): VM state change detected. (old = 'running', new = 'paused') 2026-01-13 10:43:31 (4124): VM state change detected. (old = 'paused', new = 'running') 2026-01-13 10:45:51 (4124): VM state change detected. (old = 'running', new = 'paused') 2026-01-13 10:46:11 (4124): VM state change detected. (old = 'paused', new = 'running') 2026-01-13 10:56:46 (4124): VM state change detected. (old = 'running', new = 'paused') 2026-01-13 10:57:06 (4124): VM state change detected. (old = 'paused', new = 'running') 2026-01-13 11:08:11 (4124): VM state change detected. (old = 'running', new = 'paused') 2026-01-13 11:08:31 (4124): VM state change detected. (old = 'paused', new = 'running') 2026-01-13 11:10:04 (4124): Status Report: Job Duration: '64800.000000' 2026-01-13 11:10:04 (4124): Status Report: Elapsed Time: '21359.000000' 2026-01-13 11:10:04 (4124): Status Report: CPU Time: '48246.700000' 2026-01-13 11:34:53 (4124): VM state change detected. (old = 'running', new = 'paused') 2026-01-13 11:35:14 (4124): VM state change detected. (old = 'paused', new = 'running') 2026-01-13 12:08:19 (4124): VM state change detected. (old = 'running', new = 'paused') 2026-01-13 12:13:26 (4124): VM state change detected. (old = 'paused', new = 'running') 2026-01-13 12:14:15 (4124): VM state change detected. (old = 'running', new = 'paused') 2026-01-13 12:19:21 (4124): VM state change detected. (old = 'paused', new = 'running') 2026-01-13 12:19:51 (4124): VM state change detected. (old = 'running', new = 'paused') 2026-01-13 14:26:02 (4124): VM state change detected. (old = 'paused', new = 'running') 2026-01-13 14:26:04 (4124): Guest Log: 02:38:02.155529 timesync vgsvcTimeSyncWorker: Radical host time change: 7 581 050 000 000ns (HostNow=1 768 314 363 975 000 000 ns HostLast=1 768 306 782 925 000 000 ns) 2026-01-13 14:26:14 (4124): Guest Log: 02:38:12.158512 timesync vgsvcTimeSyncWorker: Radical guest time change: 8 390 823 171 000ns (GuestNow=1 768 314 374 003 912 000 ns GuestLast=1 768 305 983 180 741 000 ns fSetTimeLastLoop=true ) 2026-01-13 14:27:31 (4124): VM state change detected. (old = 'running', new = 'paused') 2026-01-13 14:32:32 (4124): VM state change detected. (old = 'paused', new = 'running') 2026-01-13 14:39:24 (4124): VM state change detected. (old = 'running', new = 'paused') 2026-01-13 14:39:44 (4124): VM state change detected. (old = 'paused', new = 'running') 2026-01-13 14:40:04 (4124): VM state change detected. (old = 'running', new = 'paused') 2026-01-13 14:40:24 (4124): VM state change detected. (old = 'paused', new = 'running') 2026-01-13 15:11:40 (4124): Status Report: Job Duration: '64800.000000' 2026-01-13 15:11:40 (4124): Status Report: Elapsed Time: '27359.000000' 2026-01-13 15:11:40 (4124): Status Report: CPU Time: '61902.400000' 2026-01-13 15:20:33 (4124): VM state change detected. (old = 'running', new = 'paused') 2026-01-13 15:20:53 (4124): VM state change detected. (old = 'paused', new = 'running') 2026-01-13 15:21:03 (4124): VM state change detected. (old = 'running', new = 'paused') 2026-01-13 15:21:24 (4124): VM state change detected. (old = 'paused', new = 'running') 2026-01-13 15:29:37 (4124): VM state change detected. (old = 'running', new = 'paused') 2026-01-13 15:29:57 (4124): VM state change detected. (old = 'paused', new = 'running') 2026-01-13 15:40:13 (4124): VM state change detected. (old = 'running', new = 'paused') 2026-01-13 16:03:39 (4124): VM state change detected. (old = 'paused', new = 'running') 2026-01-13 16:03:43 (4124): VM state change detected. (old = 'running', new = 'paused') 2026-01-13 16:04:03 (4124): VM state change detected. (old = 'paused', new = 'running') 2026-01-13 16:04:15 (4124): Guest Log: 03:45:47.906387 timesync vgsvcTimeSyncWorker: Radical guest time change: 1 835 132 859 000ns (GuestNow=1 768 320 254 874 821 000 ns GuestLast=1 768 318 419 741 962 000 ns fSetTimeLastLoop=true ) 2026-01-13 16:08:05 (4124): VM state change detected. (old = 'running', new = 'paused') 2026-01-13 16:08:26 (4124): VM state change detected. (old = 'paused', new = 'running') 2026-01-13 16:12:08 (4124): VM state change detected. (old = 'running', new = 'paused') 2026-01-13 16:12:27 (4124): VM state change detected. (old = 'paused', new = 'running') 2026-01-13 16:17:10 (4124): VM state change detected. (old = 'running', new = 'paused') 2026-01-13 16:17:30 (4124): VM state change detected. (old = 'paused', new = 'running') 2026-01-13 16:26:14 (4124): VM state change detected. (old = 'running', new = 'paused') 2026-01-13 16:26:34 (4124): VM state change detected. (old = 'paused', new = 'running') 2026-01-13 16:27:04 (4124): VM state change detected. (old = 'running', new = 'paused') 2026-01-13 16:27:25 (4124): VM state change detected. (old = 'paused', new = 'running') 2026-01-13 16:30:05 (4124): VM state change detected. (old = 'running', new = 'paused') 2026-01-13 16:30:26 (4124): VM state change detected. (old = 'paused', new = 'running') 2026-01-13 16:34:08 (4124): VM state change detected. (old = 'running', new = 'paused') 2026-01-13 16:34:28 (4124): VM state change detected. (old = 'paused', new = 'running') 2026-01-13 16:38:29 (4124): VM state change detected. (old = 'running', new = 'paused') 2026-01-13 16:38:49 (4124): VM state change detected. (old = 'paused', new = 'running') 2026-01-13 16:49:55 (4124): VM state change detected. (old = 'running', new = 'paused') 2026-01-13 16:50:15 (4124): VM state change detected. (old = 'paused', new = 'running') 2026-01-13 17:01:09 (4124): VM state change detected. (old = 'running', new = 'paused') 2026-01-13 17:01:30 (4124): VM state change detected. (old = 'paused', new = 'running') 2026-01-13 17:12:35 (4124): VM state change detected. (old = 'running', new = 'paused') 2026-01-13 17:12:55 (4124): VM state change detected. (old = 'paused', new = 'running') 2026-01-13 17:19:30 (4124): Status Report: Job Duration: '64800.000000' 2026-01-13 17:19:30 (4124): Status Report: Elapsed Time: '33359.000000' 2026-01-13 17:19:30 (4124): Status Report: CPU Time: '75819.210000' 2026-01-13 17:24:10 (4124): VM state change detected. (old = 'running', new = 'paused') 2026-01-13 17:24:31 (4124): VM state change detected. (old = 'paused', new = 'running') 2026-01-13 17:36:06 (4124): VM state change detected. (old = 'running', new = 'paused') 2026-01-13 17:36:26 (4124): VM state change detected. (old = 'paused', new = 'running') 2026-01-13 17:48:21 (4124): VM state change detected. (old = 'running', new = 'paused') 2026-01-13 17:48:42 (4124): VM state change detected. (old = 'paused', new = 'running') 2026-01-13 17:53:24 (4124): VM state change detected. (old = 'running', new = 'paused') 2026-01-13 17:53:44 (4124): VM state change detected. (old = 'paused', new = 'running') 2026-01-13 17:57:56 (4124): VM state change detected. (old = 'running', new = 'paused') 2026-01-13 17:58:16 (4124): VM state change detected. (old = 'paused', new = 'running') 2026-01-13 18:01:08 (4124): VM state change detected. (old = 'running', new = 'paused') 2026-01-13 18:01:28 (4124): VM state change detected. (old = 'paused', new = 'running') 2026-01-13 18:13:13 (4124): VM state change detected. (old = 'running', new = 'paused') 2026-01-13 18:13:34 (4124): VM state change detected. (old = 'paused', new = 'running') 2026-01-13 18:25:09 (4124): VM state change detected. (old = 'running', new = 'paused') 2026-01-13 18:25:29 (4124): VM state change detected. (old = 'paused', new = 'running') 2026-01-13 18:36:54 (4124): VM state change detected. (old = 'running', new = 'paused') 2026-01-13 18:37:15 (4124): VM state change detected. (old = 'paused', new = 'running') 2026-01-13 18:41:45 (4124): VM state change detected. (old = 'running', new = 'paused') 2026-01-13 18:47:21 (4124): VM state change detected. (old = 'paused', new = 'running') 2026-01-13 18:47:39 (4124): VM state change detected. (old = 'running', new = 'paused') 2026-01-13 18:47:59 (4124): VM state change detected. (old = 'paused', new = 'running') 2026-01-13 18:53:12 (4124): VM state change detected. (old = 'running', new = 'paused') 2026-01-13 18:53:32 (4124): VM state change detected. (old = 'paused', new = 'running') 2026-01-13 18:54:02 (4124): VM state change detected. (old = 'running', new = 'paused') 2026-01-13 18:54:22 (4124): VM state change detected. (old = 'paused', new = 'running') 2026-01-13 18:56:33 (4124): VM state change detected. (old = 'running', new = 'paused') 2026-01-13 18:56:54 (4124): VM state change detected. (old = 'paused', new = 'running') 2026-01-13 19:03:16 (4124): VM state change detected. (old = 'running', new = 'paused') 2026-01-13 19:03:37 (4124): VM state change detected. (old = 'paused', new = 'running') 2026-01-13 19:03:47 (4124): VM state change detected. (old = 'running', new = 'paused') 2026-01-13 19:04:06 (4124): VM state change detected. (old = 'paused', new = 'running') 2026-01-13 19:08:00 (4124): VM state change detected. (old = 'running', new = 'paused') 2026-01-13 19:14:13 (4124): VM state change detected. (old = 'paused', new = 'running') 2026-01-13 19:15:42 (4124): Status Report: Job Duration: '64800.000000' 2026-01-13 19:15:42 (4124): Status Report: Elapsed Time: '39359.000000' 2026-01-13 19:15:42 (4124): Status Report: CPU Time: '89482.480000' 2026-01-13 19:18:02 (4124): VM state change detected. (old = 'running', new = 'paused') 2026-01-13 19:18:23 (4124): VM state change detected. (old = 'paused', new = 'running') 2026-01-13 19:19:35 (4124): VM state change detected. (old = 'running', new = 'paused') 2026-01-13 23:46:33 (4124): VM state change detected. (old = 'paused', new = 'running') 2026-01-13 23:46:38 (4124): Guest Log: 06:40:22.347661 timesync vgsvcTimeSyncWorker: Radical host time change: 16 027 475 000 000ns (HostNow=1 768 347 998 206 000 000 ns HostLast=1 768 331 970 731 000 000 ns) 2026-01-13 23:46:43 (4124): VM state change detected. (old = 'running', new = 'paused') 2026-01-13 23:47:03 (4124): VM state change detected. (old = 'paused', new = 'running') 2026-01-13 23:47:08 (4124): Guest Log: 06:40:32.355180 timesync vgsvcTimeSyncWorker: Radical guest time change: 16 048 224 525 000ns (GuestNow=1 768 348 008 213 551 000 ns GuestLast=1 768 331 959 989 026 000 ns fSetTimeLastLoop=true ) 2026-01-13 23:47:23 (4124): VM state change detected. (old = 'running', new = 'paused') 2026-01-13 23:47:43 (4124): VM state change detected. (old = 'paused', new = 'running') 2026-01-13 23:52:04 (4124): Guest Log: [INFO] glidein exited with return value 0. 2026-01-13 23:52:04 (4124): Guest Log: [INFO] Shutting Down. 2026-01-13 23:52:04 (4124): VM Completion File Detected. 2026-01-13 23:52:04 (4124): VM Completion Message: glidein exited with return value 0. . 2026-01-13 23:52:04 (4124): Powering off VM. 2026-01-13 23:52:04 (4124): Successfully stopped VM. 2026-01-13 23:52:04 (4124): Deregistering VM. (boinc_de071caff443437f, slot#4) 2026-01-13 23:52:04 (4124): Removing network bandwidth throttle group from VM. 2026-01-13 23:52:05 (4124): Removing VM from VirtualBox. 2026-01-13 23:52:10 (4124): called boinc_finish(0) </stderr_txt> ]]>
©2026 CERN