| Name | CMS_682386_1768195911.146158_0 |
| Workunit | 238454317 |
| 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:04 UTC |
| Server state | Over |
| Outcome | Success |
| Client state | Done |
| Exit status | 0 (0x00000000) |
| Computer ID | 10978737 |
| Run time | 11 hours 11 min 8 sec |
| CPU time | 1 days 1 hours 25 min 36 sec |
| Validate state | Valid |
| Credit | 12,176.16 |
| 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.60 GB |
| Peak swap size | 5.66 GB |
| Peak disk usage | 2.16 GB |
Stderr output
<core_client_version>7.24.1</core_client_version> <![CDATA[ <stderr_txt> 2026-01-12 07:04:55 (67733): vboxwrapper version 26210 2026-01-12 07:04:55 (67733): BOINC client version: 7.24.1 2026-01-12 07:04:55 (67733): Detected: VirtualBox VboxManage Interface (Version: 7.0.16) 2026-01-12 07:04:55 (67733): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds) 2026-01-12 07:04:55 (67733): Successfully copied 'init_data.xml' to the shared directory. 2026-01-12 07:04:55 (67733): Create VM. (boinc_3d06f3d8a7ce504e, slot#2) 2026-01-12 07:04:55 (67733): Setting Memory Size for VM. (4584MB) 2026-01-12 07:04:55 (67733): Setting CPU Count for VM. (4) 2026-01-12 07:04:55 (67733): Setting Chipset Options for VM. 2026-01-12 07:04:55 (67733): Setting Graphics Controller Options for VM. (Driver: VBoxVGA, 16MB) 2026-01-12 07:04:55 (67733): Setting Boot Options for VM. 2026-01-12 07:04:55 (67733): Setting Network Configuration for NAT. (Driver: virtio) 2026-01-12 07:04:55 (67733): Enabling VM Network Access. 2026-01-12 07:04:55 (67733): Disabling USB Support for VM. 2026-01-12 07:04:55 (67733): Disabling COM Port Support for VM. 2026-01-12 07:04:56 (67733): Disabling LPT Port Support for VM. 2026-01-12 07:04:56 (67733): Disabling Audio Support for VM. 2026-01-12 07:04:56 (67733): Disabling Clipboard Support for VM. 2026-01-12 07:04:56 (67733): Disabling Drag and Drop Support for VM. 2026-01-12 07:04:56 (67733): Adding storage controller(s) to VM. 2026-01-12 07:04:56 (67733): Adding virtual disk drive to VM. (CMS_2025_04_08_prod.vdi) 2026-01-12 07:04:56 (67733): Adding VirtualBox Guest Additions to VM. 2026-01-12 07:04:57 (67733): Adding network bandwidth throttle group to VM. (Defaulting to 1024GB) 2026-01-12 07:04:57 (67733): forwarding host port 39909 to guest port 80 2026-01-12 07:04:57 (67733): Enabling remote desktop for VM. 2026-01-12 07:04:57 (67733): Enabling shared directory for VM. 2026-01-12 07:04:57 (67733): Starting VM using VBoxManage interface. (boinc_3d06f3d8a7ce504e, slot#2) 2026-01-12 07:04:58 (67733): Successfully started VM. (PID = '68243') 2026-01-12 07:04:58 (67733): Reporting VM Process ID to BOINC. 2026-01-12 07:04:58 (67733): Guest Log: BIOS: VirtualBox 7.0.16 2026-01-12 07:04:58 (67733): Guest Log: CPUID EDX: 0x178bfbff 2026-01-12 07:04:58 (67733): Guest Log: BIOS: No PCI IDE controller, not probing IDE 2026-01-12 07:04:58 (67733): Guest Log: BIOS: AHCI 0-P#0: PCHS=16383/16/63 LCHS=1024/255/63 0x0000000002800000 sectors 2026-01-12 07:04:58 (67733): VM state change detected. (old = 'poweredoff', new = 'running') 2026-01-12 07:04:58 (67733): Detected: Web Application Enabled (http://localhost:39909) 2026-01-12 07:04:58 (67733): Detected: Remote Desktop Enabled (localhost:52133) 2026-01-12 07:04:58 (67733): Preference change detected 2026-01-12 07:04:58 (67733): Setting CPU throttle for VM. (60%) 2026-01-12 07:04:58 (67733): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds)) 2026-01-12 07:05:00 (67733): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032 2026-01-12 07:05:00 (67733): Guest Log: BIOS: Booting from Hard Disk... 2026-01-12 07:05:02 (67733): Guest Log: BIOS: KBD: unsupported int 16h function 03 2026-01-12 07:05:02 (67733): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 2026-01-12 07:05:24 (67733): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds 2026-01-12 07:05:24 (67733): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000) 2026-01-12 07:05:25 (67733): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log 2026-01-12 07:05:25 (67733): Guest Log: 00:00:00.000084 main Log opened 2026-01-12T07:05:25.137216000Z 2026-01-12 07:05:25 (67733): Guest Log: 00:00:00.000176 main OS Product: Linux 2026-01-12 07:05:25 (67733): Guest Log: 00:00:00.000195 main OS Release: 4.14.232-19.cernvm.x86_64 2026-01-12 07:05:25 (67733): Guest Log: 00:00:00.000208 main OS Version: #1 SMP Fri Apr 30 17:12:25 CEST 2021 2026-01-12 07:05:25 (67733): Guest Log: 00:00:00.000220 main Executable: /usr/sbin/VBoxService 2026-01-12 07:05:25 (67733): Guest Log: 00:00:00.000220 main Process ID: 2271 2026-01-12 07:05:25 (67733): Guest Log: 00:00:00.000221 main Package type: LINUX_64BITS_GENERIC 2026-01-12 07:05:25 (67733): Guest Log: 00:00:00.001508 main 5.2.6 r120293 started. Verbose level = 0 2026-01-12 07:05:36 (67733): Guest Log: [INFO] Mounting the shared directory 2026-01-12 07:05:36 (67733): Guest Log: [INFO] Shared directory mounted, enabling vboxmonitor 2026-01-12 07:05:36 (67733): Guest Log: [INFO] Sourcing essential functions from /cvmfs/grid.cern.ch 2026-01-12 07:05:36 (67733): Guest Log: [INFO] Testing connection to cern.ch 2026-01-12 07:05:37 (67733): Guest Log: [INFO] Testing connection to VCCS 2026-01-12 07:05:37 (67733): Guest Log: [INFO] Testing connection to HTCondor-Collector 2026-01-12 07:05:37 (67733): Guest Log: [INFO] Testing connection to HTCondor-CCB 2026-01-12 07:05:37 (67733): Guest Log: [INFO] Testing connection to WMAgent 2026-01-12 07:05:37 (67733): Guest Log: [INFO] Testing connection to EOSCMS 2026-01-12 07:05:37 (67733): Guest Log: [INFO] Testing connection to CMS-Factory 2026-01-12 07:05:38 (67733): Guest Log: [INFO] Testing connection to CMS-Frontier 2026-01-12 07:05:38 (67733): Guest Log: [INFO] Testing connection to Frontier 2026-01-12 07:05:38 (67733): Guest Log: [INFO] Testing connection to http://cms-frontier.openhtc.io:8080/FrontierProd/Frontier/ 2026-01-12 07:05:38 (67733): Guest Log: [INFO] Could not find a local HTTP proxy 2026-01-12 07:05:38 (67733): Guest Log: [INFO] CVMFS and Frontier will have to use DIRECT connections 2026-01-12 07:05:38 (67733): Guest Log: [INFO] This makes the application less efficient 2026-01-12 07:05:39 (67733): Guest Log: [INFO] It also puts higher load on the project servers 2026-01-12 07:05:39 (67733): Guest Log: [INFO] Setting up a local HTTP proxy is highly recommended 2026-01-12 07:05:39 (67733): Guest Log: [INFO] Advice can be found in the project forum 2026-01-12 07:05:39 (67733): Guest Log: [INFO] Reloading and probing the CVMFS configuration 2026-01-12 07:05:44 (67733): Guest Log: [INFO] Probing /cvmfs/grid.cern.ch... OK 2026-01-12 07:05:46 (67733): Guest Log: [INFO] Probing /cvmfs/oasis.opensciencegrid.org... OK 2026-01-12 07:05:46 (67733): Guest Log: [INFO] Probing /cvmfs/cms-ib.cern.ch... OK 2026-01-12 07:05:46 (67733): Guest Log: [INFO] Probing /cvmfs/singularity.opensciencegrid.org... OK 2026-01-12 07:05:47 (67733): Guest Log: [INFO] Probing /cvmfs/cms.cern.ch... OK 2026-01-12 07:05:47 (67733): Guest Log: [INFO] Excerpt from "cvmfs_config stat": VERSION HOST PROXY 2026-01-12 07:05:47 (67733): Guest Log: [INFO] 2.7.2.0 http://s1cern-cvmfs.openhtc.io DIRECT 2026-01-12 07:05:47 (67733): Guest Log: [INFO] Environment HTTP proxy: not set 2026-01-12 07:05:47 (67733): Guest Log: [INFO] Reading volunteer information 2026-01-12 07:05:54 (67733): Guest Log: [INFO] Requesting an X509 credential from LHC@home 2026-01-12 07:05:55 (67733): Guest Log: [INFO] Requesting an idtoken from LHC@home 2026-01-12 07:05:55 (67733): Guest Log: [INFO] CMS application starting. Check log files. 2026-01-12 07:09:02 (67733): VM state change detected. (old = 'running', new = 'paused') 2026-01-12 07:09:23 (67733): VM state change detected. (old = 'paused', new = 'running') 2026-01-12 07:15:14 (67733): VM state change detected. (old = 'running', new = 'paused') 2026-01-12 07:15:34 (67733): VM state change detected. (old = 'paused', new = 'running') 2026-01-12 07:20:25 (67733): VM state change detected. (old = 'running', new = 'paused') 2026-01-12 07:20:45 (67733): VM state change detected. (old = 'paused', new = 'running') 2026-01-12 07:31:28 (67733): VM state change detected. (old = 'running', new = 'paused') 2026-01-12 07:31:49 (67733): VM state change detected. (old = 'paused', new = 'running') 2026-01-12 07:42:51 (67733): VM state change detected. (old = 'running', new = 'paused') 2026-01-12 07:43:11 (67733): VM state change detected. (old = 'paused', new = 'running') 2026-01-12 07:54:35 (67733): VM state change detected. (old = 'running', new = 'paused') 2026-01-12 07:54:54 (67733): VM state change detected. (old = 'paused', new = 'running') 2026-01-12 08:00:36 (67733): VM state change detected. (old = 'running', new = 'paused') 2026-01-12 08:00:56 (67733): VM state change detected. (old = 'paused', new = 'running') 2026-01-12 08:40:08 (67733): VM state change detected. (old = 'running', new = 'paused') 2026-01-12 08:40:28 (67733): VM state change detected. (old = 'paused', new = 'running') 2026-01-12 08:46:50 (67733): Status Report: Job Duration: '64800.000000' 2026-01-12 08:46:50 (67733): Status Report: Elapsed Time: '6000.000000' 2026-01-12 08:46:50 (67733): Status Report: CPU Time: '13244.220000' 2026-01-12 09:16:10 (67733): VM state change detected. (old = 'running', new = 'paused') 2026-01-12 09:26:47 (67733): VM state change detected. (old = 'paused', new = 'running') 2026-01-12 09:43:41 (67733): VM state change detected. (old = 'running', new = 'paused') 2026-01-12 10:07:20 (67733): VM state change detected. (old = 'paused', new = 'running') 2026-01-12 10:07:39 (67733): Guest Log: 02:25:21.804308 timesync vgsvcTimeSyncWorker: Radical guest time change: 2 221 522 748 000ns (GuestNow=1 768 212 458 460 572 000 ns GuestLast=1 768 210 236 937 824 000 ns fSetTimeLastLoop=true ) 2026-01-12 10:07:57 (67733): VM state change detected. (old = 'running', new = 'paused') 2026-01-12 10:26:28 (67733): VM state change detected. (old = 'paused', new = 'running') 2026-01-12 10:26:44 (67733): VM state change detected. (old = 'running', new = 'paused') 2026-01-12 10:53:31 (67733): VM state change detected. (old = 'paused', new = 'running') 2026-01-12 10:53:46 (67733): Guest Log: 02:26:11.930019 timesync vgsvcTimeSyncWorker: Radical guest time change: 2 727 738 995 000ns (GuestNow=1 768 215 226 289 642 000 ns GuestLast=1 768 212 498 550 647 000 ns fSetTimeLastLoop=true ) 2026-01-12 10:54:11 (67733): VM state change detected. (old = 'running', new = 'paused') 2026-01-12 11:02:57 (67733): VM state change detected. (old = 'paused', new = 'running') 2026-01-12 11:03:29 (67733): VM state change detected. (old = 'running', new = 'paused') 2026-01-12 11:38:09 (67733): VM state change detected. (old = 'paused', new = 'running') 2026-01-12 11:38:12 (67733): Guest Log: 02:27:12.013006 timesync vgsvcTimeSyncWorker: Radical host time change: 2 090 098 000 000ns (HostNow=1 768 217 892 158 000 000 ns HostLast=1 768 215 802 060 000 000 ns) 2026-01-12 11:38:22 (67733): Guest Log: 02:27:22.014025 timesync vgsvcTimeSyncWorker: Radical guest time change: 2 615 844 775 000ns (GuestNow=1 768 217 902 188 210 000 ns GuestLast=1 768 215 286 343 435 000 ns fSetTimeLastLoop=true ) 2026-01-12 11:40:12 (67733): VM state change detected. (old = 'running', new = 'paused') 2026-01-12 12:01:28 (67733): VM state change detected. (old = 'paused', new = 'running') 2026-01-12 12:31:26 (67733): VM state change detected. (old = 'running', new = 'paused') 2026-01-12 12:44:41 (67733): VM state change detected. (old = 'paused', new = 'running') 2026-01-12 12:47:53 (67733): VM state change detected. (old = 'running', new = 'paused') 2026-01-12 12:52:52 (67733): VM state change detected. (old = 'paused', new = 'running') 2026-01-12 12:56:34 (67733): VM state change detected. (old = 'running', new = 'paused') 2026-01-12 13:01:34 (67733): VM state change detected. (old = 'paused', new = 'running') 2026-01-12 13:01:42 (67733): VM state change detected. (old = 'running', new = 'paused') 2026-01-12 13:46:42 (67733): VM state change detected. (old = 'paused', new = 'running') 2026-01-12 13:46:45 (67733): Guest Log: 03:06:14.985176 timesync vgsvcTimeSyncWorker: Radical host time change: 2 709 902 000 000ns (HostNow=1 768 225 605 031 000 000 ns HostLast=1 768 222 895 129 000 000 ns) 2026-01-12 13:46:55 (67733): Guest Log: 03:06:24.996769 timesync vgsvcTimeSyncWorker: Radical guest time change: 2 709 904 174 000ns (GuestNow=1 768 225 615 042 692 000 ns GuestLast=1 768 222 905 138 518 000 ns fSetTimeLastLoop=true ) 2026-01-12 13:47:30 (67733): VM state change detected. (old = 'running', new = 'paused') 2026-01-12 14:14:04 (67733): VM state change detected. (old = 'paused', new = 'running') 2026-01-12 14:18:44 (67733): VM state change detected. (old = 'running', new = 'paused') 2026-01-12 14:31:34 (67733): VM state change detected. (old = 'paused', new = 'running') 2026-01-12 14:31:36 (67733): VM state change detected. (old = 'running', new = 'paused') 2026-01-12 14:39:33 (67733): VM state change detected. (old = 'paused', new = 'running') 2026-01-12 14:39:50 (67733): VM state change detected. (old = 'running', new = 'paused') 2026-01-12 14:44:49 (67733): VM state change detected. (old = 'paused', new = 'running') 2026-01-12 14:45:15 (67733): VM state change detected. (old = 'running', new = 'paused') 2026-01-12 14:50:15 (67733): VM state change detected. (old = 'paused', new = 'running') 2026-01-12 14:50:24 (67733): VM state change detected. (old = 'running', new = 'paused') 2026-01-12 14:55:25 (67733): VM state change detected. (old = 'paused', new = 'running') 2026-01-12 14:56:40 (67733): VM state change detected. (old = 'running', new = 'paused') 2026-01-12 15:06:07 (67733): VM state change detected. (old = 'paused', new = 'running') 2026-01-12 15:09:52 (67733): VM state change detected. (old = 'running', new = 'paused') 2026-01-12 15:30:05 (67733): VM state change detected. (old = 'paused', new = 'running') 2026-01-12 15:30:09 (67733): VM state change detected. (old = 'running', new = 'paused') 2026-01-12 15:47:40 (67733): VM state change detected. (old = 'paused', new = 'running') 2026-01-12 15:47:44 (67733): VM state change detected. (old = 'running', new = 'paused') 2026-01-12 16:13:46 (67733): VM state change detected. (old = 'paused', new = 'running') 2026-01-12 16:13:49 (67733): Guest Log: 03:17:46.141027 timesync vgsvcTimeSyncWorker: Radical host time change: 2 622 745 000 000ns (HostNow=1 768 234 429 380 000 000 ns HostLast=1 768 231 806 635 000 000 ns) 2026-01-12 16:13:59 (67733): Guest Log: 03:17:56.146020 timesync vgsvcTimeSyncWorker: Radical guest time change: 2 622 734 011 000ns (GuestNow=1 768 234 439 399 471 000 ns GuestLast=1 768 231 816 665 460 000 ns fSetTimeLastLoop=true ) 2026-01-12 16:14:17 (67733): Status Report: Job Duration: '64800.000000' 2026-01-12 16:14:17 (67733): Status Report: Elapsed Time: '12000.000000' 2026-01-12 16:14:17 (67733): Status Report: CPU Time: '26918.750000' 2026-01-12 16:17:42 (67733): VM state change detected. (old = 'running', new = 'paused') 2026-01-12 17:10:30 (67733): VM state change detected. (old = 'paused', new = 'running') 2026-01-12 17:10:38 (67733): Guest Log: 03:21:46.441019 timesync vgsvcTimeSyncWorker: Radical host time change: 3 178 241 000 000ns (HostNow=1 768 237 837 904 000 000 ns HostLast=1 768 234 659 663 000 000 ns) 2026-01-12 17:10:48 (67733): Guest Log: 03:21:56.445007 timesync vgsvcTimeSyncWorker: Radical guest time change: 3 178 234 028 000ns (GuestNow=1 768 237 847 918 239 000 ns GuestLast=1 768 234 669 684 211 000 ns fSetTimeLastLoop=true ) 2026-01-12 17:23:20 (67733): VM state change detected. (old = 'running', new = 'paused') 2026-01-12 17:29:23 (67733): VM state change detected. (old = 'paused', new = 'running') 2026-01-12 17:35:44 (67733): VM state change detected. (old = 'running', new = 'paused') 2026-01-12 17:36:04 (67733): VM state change detected. (old = 'paused', new = 'running') 2026-01-12 17:38:44 (67733): VM state change detected. (old = 'running', new = 'paused') 2026-01-12 17:44:14 (67733): VM state change detected. (old = 'paused', new = 'running') 2026-01-12 17:52:37 (67733): VM state change detected. (old = 'running', new = 'paused') 2026-01-12 18:10:52 (67733): VM state change detected. (old = 'paused', new = 'running') 2026-01-12 18:11:01 (67733): VM state change detected. (old = 'running', new = 'paused') 2026-01-12 18:29:06 (67733): VM state change detected. (old = 'paused', new = 'running') 2026-01-12 18:29:15 (67733): Guest Log: 03:52:09.253110 timesync vgsvcTimeSyncWorker: Radical guest time change: 1 818 421 682 000ns (GuestNow=1 768 241 469 118 000 000 ns GuestLast=1 768 239 650 696 318 000 ns fSetTimeLastLoop=true ) 2026-01-12 18:29:27 (67733): VM state change detected. (old = 'running', new = 'paused') 2026-01-12 18:59:41 (67733): VM state change detected. (old = 'paused', new = 'running') 2026-01-12 18:59:49 (67733): Guest Log: 03:52:29.271415 timesync vgsvcTimeSyncWorker: Radical host time change: 1 824 817 000 000ns (HostNow=1 768 244 389 037 000 000 ns HostLast=1 768 242 564 220 000 000 ns) 2026-01-12 18:59:59 (67733): Guest Log: 03:52:39.321209 timesync vgsvcTimeSyncWorker: Radical guest time change: 2 909 952 665 000ns (GuestNow=1 768 244 399 087 885 000 ns GuestLast=1 768 241 489 135 220 000 ns fSetTimeLastLoop=true ) 2026-01-12 19:02:20 (67733): VM state change detected. (old = 'running', new = 'paused') 2026-01-12 19:07:20 (67733): VM state change detected. (old = 'paused', new = 'running') 2026-01-12 19:07:44 (67733): VM state change detected. (old = 'running', new = 'paused') 2026-01-12 19:12:43 (67733): VM state change detected. (old = 'paused', new = 'running') 2026-01-12 19:16:05 (67733): VM state change detected. (old = 'running', new = 'paused') 2026-01-12 19:25:56 (67733): VM state change detected. (old = 'paused', new = 'running') 2026-01-12 19:26:45 (67733): VM state change detected. (old = 'running', new = 'paused') 2026-01-12 19:31:45 (67733): VM state change detected. (old = 'paused', new = 'running') 2026-01-12 19:32:07 (67733): VM state change detected. (old = 'running', new = 'paused') 2026-01-12 21:25:44 (67733): VM state change detected. (old = 'paused', new = 'running') 2026-01-12 21:25:46 (67733): Guest Log: 03:59:59.763208 timesync vgsvcTimeSyncWorker: Radical host time change: 6 827 038 000 000ns (HostNow=1 768 253 146 381 000 000 ns HostLast=1 768 246 319 343 000 000 ns) 2026-01-12 21:25:56 (67733): Guest Log: 04:00:09.764394 timesync vgsvcTimeSyncWorker: Radical guest time change: 6 826 995 546 000ns (GuestNow=1 768 253 156 382 274 000 ns GuestLast=1 768 246 329 386 728 000 ns fSetTimeLastLoop=true ) 2026-01-12 21:26:59 (67733): VM state change detected. (old = 'running', new = 'paused') 2026-01-12 21:39:06 (67733): VM state change detected. (old = 'paused', new = 'running') 2026-01-12 21:39:54 (67733): VM state change detected. (old = 'running', new = 'paused') 2026-01-12 21:40:14 (67733): VM state change detected. (old = 'paused', new = 'running') 2026-01-12 21:42:48 (67733): VM state change detected. (old = 'running', new = 'paused') 2026-01-12 21:59:39 (67733): VM state change detected. (old = 'paused', new = 'running') 2026-01-12 21:59:43 (67733): VM state change detected. (old = 'running', new = 'paused') 2026-01-12 22:04:43 (67733): VM state change detected. (old = 'paused', new = 'running') 2026-01-12 22:04:52 (67733): VM state change detected. (old = 'running', new = 'paused') 2026-01-12 22:51:12 (67733): VM state change detected. (old = 'paused', new = 'running') 2026-01-12 22:51:15 (67733): Guest Log: 04:04:50.268423 timesync vgsvcTimeSyncWorker: Radical host time change: 2 790 092 000 000ns (HostNow=1 768 258 275 123 000 000 ns HostLast=1 768 255 485 031 000 000 ns) 2026-01-12 22:51:15 (67733): Guest Log: 04:04:50.268493 timesync vgsvcTimeSyncWorker: Radical guest time change: 2 068 185 243 000ns (GuestNow=1 768 255 495 045 122 000 ns GuestLast=1 768 253 426 859 879 000 ns fSetTimeLastLoop=true ) 2026-01-12 22:51:25 (67733): Guest Log: 04:05:00.277021 timesync vgsvcTimeSyncWorker: Radical guest time change: 2 790 098 767 000ns (GuestNow=1 768 258 285 143 889 000 ns GuestLast=1 768 255 495 045 122 000 ns fSetTimeLastLoop=true ) 2026-01-12 22:51:48 (67733): VM state change detected. (old = 'running', new = 'paused') 2026-01-12 23:05:39 (67733): VM state change detected. (old = 'paused', new = 'running') 2026-01-12 23:08:20 (67733): VM state change detected. (old = 'running', new = 'paused') 2026-01-12 23:17:52 (67733): VM state change detected. (old = 'paused', new = 'running') 2026-01-12 23:23:52 (67733): VM state change detected. (old = 'running', new = 'paused') 2026-01-12 23:33:02 (67733): VM state change detected. (old = 'paused', new = 'running') 2026-01-12 23:36:27 (67733): VM state change detected. (old = 'running', new = 'paused') 2026-01-12 23:55:47 (67733): VM state change detected. (old = 'paused', new = 'running') 2026-01-12 23:59:11 (67733): VM state change detected. (old = 'running', new = 'paused') 2026-01-13 00:08:41 (67733): VM state change detected. (old = 'paused', new = 'running') 2026-01-13 00:12:01 (67733): VM state change detected. (old = 'running', new = 'paused') 2026-01-13 07:45:41 (4108): vboxwrapper version 26210 2026-01-13 07:45:41 (4108): BOINC client version: 7.24.1 2026-01-13 07:45:41 (4108): Detected: VirtualBox VboxManage Interface (Version: 7.0.16) 2026-01-13 07:45:41 (4108): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds) 2026-01-13 07:45:41 (4108): Guest Log: BIOS: VirtualBox 7.0.16 2026-01-13 07:45:41 (4108): Guest Log: CPUID EDX: 0x178bfbff 2026-01-13 07:45:41 (4108): Guest Log: BIOS: No PCI IDE controller, not probing IDE 2026-01-13 07:45:41 (4108): Guest Log: BIOS: AHCI 0-P#0: PCHS=16383/16/63 LCHS=1024/255/63 0x0000000002800000 sectors 2026-01-13 07:45:41 (4108): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032 2026-01-13 07:45:41 (4108): Guest Log: BIOS: Booting from Hard Disk... 2026-01-13 07:45:41 (4108): Guest Log: BIOS: KBD: unsupported int 16h function 03 2026-01-13 07:45:41 (4108): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 2026-01-13 07:45:41 (4108): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds 2026-01-13 07:45:41 (4108): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000) 2026-01-13 07:45:41 (4108): 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 (4108): Guest Log: 00:00:00.000084 main Log opened 2026-01-12T07:05:25.137216000Z 2026-01-13 07:45:41 (4108): Guest Log: 00:00:00.000176 main OS Product: Linux 2026-01-13 07:45:41 (4108): Guest Log: 00:00:00.000195 main OS Release: 4.14.232-19.cernvm.x86_64 2026-01-13 07:45:41 (4108): Guest Log: 00:00:00.000208 main OS Version: #1 SMP Fri Apr 30 17:12:25 CEST 2021 2026-01-13 07:45:41 (4108): Guest Log: 00:00:00.000220 main Executable: /usr/sbin/VBoxService 2026-01-13 07:45:41 (4108): Guest Log: 00:00:00.000220 main Process ID: 2271 2026-01-13 07:45:41 (4108): Guest Log: 00:00:00.000221 main Package type: LINUX_64BITS_GENERIC 2026-01-13 07:45:41 (4108): Guest Log: 00:00:00.001508 main 5.2.6 r120293 started. Verbose level = 0 2026-01-13 07:45:41 (4108): Guest Log: [INFO] Mounting the shared directory 2026-01-13 07:45:41 (4108): Guest Log: [INFO] Shared directory mounted, enabling vboxmonitor 2026-01-13 07:45:41 (4108): Guest Log: [INFO] Sourcing essential functions from /cvmfs/grid.cern.ch 2026-01-13 07:45:41 (4108): Guest Log: [INFO] Testing connection to cern.ch 2026-01-13 07:45:41 (4108): Guest Log: [INFO] Testing connection to VCCS 2026-01-13 07:45:41 (4108): Guest Log: [INFO] Testing connection to HTCondor-Collector 2026-01-13 07:45:41 (4108): Guest Log: [INFO] Testing connection to HTCondor-CCB 2026-01-13 07:45:41 (4108): Guest Log: [INFO] Testing connection to WMAgent 2026-01-13 07:45:41 (4108): Guest Log: [INFO] Testing connection to EOSCMS 2026-01-13 07:45:41 (4108): Guest Log: [INFO] Testing connection to CMS-Factory 2026-01-13 07:45:41 (4108): Guest Log: [INFO] Testing connection to CMS-Frontier 2026-01-13 07:45:41 (4108): Guest Log: [INFO] Testing connection to Frontier 2026-01-13 07:45:41 (4108): Guest Log: [INFO] Testing connection to http://cms-frontier.openhtc.io:8080/FrontierProd/Frontier/ 2026-01-13 07:45:41 (4108): Guest Log: [INFO] Could not find a local HTTP proxy 2026-01-13 07:45:41 (4108): Guest Log: [INFO] CVMFS and Frontier will have to use DIRECT connections 2026-01-13 07:45:41 (4108): Guest Log: [INFO] This makes the application less efficient 2026-01-13 07:45:41 (4108): Guest Log: [INFO] It also puts higher load on the project servers 2026-01-13 07:45:41 (4108): Guest Log: [INFO] Setting up a local HTTP proxy is highly recommended 2026-01-13 07:45:41 (4108): Guest Log: [INFO] Advice can be found in the project forum 2026-01-13 07:45:41 (4108): Guest Log: [INFO] Reloading and probing the CVMFS configuration 2026-01-13 07:45:41 (4108): Guest Log: [INFO] Probing /cvmfs/grid.cern.ch... OK 2026-01-13 07:45:41 (4108): Guest Log: [INFO] Probing /cvmfs/oasis.opensciencegrid.org... OK 2026-01-13 07:45:41 (4108): Guest Log: [INFO] Probing /cvmfs/cms-ib.cern.ch... OK 2026-01-13 07:45:41 (4108): Guest Log: [INFO] Probing /cvmfs/singularity.opensciencegrid.org... OK 2026-01-13 07:45:41 (4108): Guest Log: [INFO] Probing /cvmfs/cms.cern.ch... OK 2026-01-13 07:45:41 (4108): Guest Log: [INFO] Excerpt from "cvmfs_config stat": VERSION HOST PROXY 2026-01-13 07:45:41 (4108): Guest Log: [INFO] 2.7.2.0 http://s1cern-cvmfs.openhtc.io DIRECT 2026-01-13 07:45:41 (4108): Guest Log: [INFO] Environment HTTP proxy: not set 2026-01-13 07:45:41 (4108): Guest Log: [INFO] Reading volunteer information 2026-01-13 07:45:41 (4108): Guest Log: [INFO] Requesting an X509 credential from LHC@home 2026-01-13 07:45:41 (4108): Guest Log: [INFO] Requesting an idtoken from LHC@home 2026-01-13 07:45:41 (4108): Guest Log: [INFO] CMS application starting. Check log files. 2026-01-13 07:45:41 (4108): Guest Log: 02:25:21.804308 timesync vgsvcTimeSyncWorker: Radical guest time change: 2 221 522 748 000ns (GuestNow=1 768 212 458 460 572 000 ns GuestLast=1 768 210 236 937 824 000 ns fSetTimeLastLoop=true ) 2026-01-13 07:45:41 (4108): Guest Log: 02:26:11.930019 timesync vgsvcTimeSyncWorker: Radical guest time change: 2 727 738 995 000ns (GuestNow=1 768 215 226 289 642 000 ns GuestLast=1 768 212 498 550 647 000 ns fSetTimeLastLoop=true ) 2026-01-13 07:45:41 (4108): Guest Log: 02:27:12.013006 timesync vgsvcTimeSyncWorker: Radical host time change: 2 090 098 000 000ns (HostNow=1 768 217 892 158 000 000 ns HostLast=1 768 215 802 060 000 000 ns) 2026-01-13 07:45:41 (4108): Guest Log: 02:27:22.014025 timesync vgsvcTimeSyncWorker: Radical guest time change: 2 615 844 775 000ns (GuestNow=1 768 217 902 188 210 000 ns GuestLast=1 768 215 286 343 435 000 ns fSetTimeLastLoop=true ) 2026-01-13 07:45:41 (4108): Guest Log: 03:06:14.985176 timesync vgsvcTimeSyncWorker: Radical host time change: 2 709 902 000 000ns (HostNow=1 768 225 605 031 000 000 ns HostLast=1 768 222 895 129 000 000 ns) 2026-01-13 07:45:41 (4108): Guest Log: 03:06:24.996769 timesync vgsvcTimeSyncWorker: Radical guest time change: 2 709 904 174 000ns (GuestNow=1 768 225 615 042 692 000 ns GuestLast=1 768 222 905 138 518 000 ns fSetTimeLastLoop=true ) 2026-01-13 07:45:41 (4108): Guest Log: 03:17:46.141027 timesync vgsvcTimeSyncWorker: Radical host time change: 2 622 745 000 000ns (HostNow=1 768 234 429 380 000 000 ns HostLast=1 768 231 806 635 000 000 ns) 2026-01-13 07:45:41 (4108): Guest Log: 03:17:56.146020 timesync vgsvcTimeSyncWorker: Radical guest time change: 2 622 734 011 000ns (GuestNow=1 768 234 439 399 471 000 ns GuestLast=1 768 231 816 665 460 000 ns fSetTimeLastLoop=true ) 2026-01-13 07:45:41 (4108): Guest Log: 03:21:46.441019 timesync vgsvcTimeSyncWorker: Radical host time change: 3 178 241 000 000ns (HostNow=1 768 237 837 904 000 000 ns HostLast=1 768 234 659 663 000 000 ns) 2026-01-13 07:45:41 (4108): Guest Log: 03:21:56.445007 timesync vgsvcTimeSyncWorker: Radical guest time change: 3 178 234 028 000ns (GuestNow=1 768 237 847 918 239 000 ns GuestLast=1 768 234 669 684 211 000 ns fSetTimeLastLoop=true ) 2026-01-13 07:45:41 (4108): Guest Log: 03:52:09.253110 timesync vgsvcTimeSyncWorker: Radical guest time change: 1 818 421 682 000ns (GuestNow=1 768 241 469 118 000 000 ns GuestLast=1 768 239 650 696 318 000 ns fSetTimeLastLoop=true ) 2026-01-13 07:45:41 (4108): Guest Log: 03:52:29.271415 timesync vgsvcTimeSyncWorker: Radical host time change: 1 824 817 000 000ns (HostNow=1 768 244 389 037 000 000 ns HostLast=1 768 242 564 220 000 000 ns) 2026-01-13 07:45:41 (4108): Guest Log: 03:52:39.321209 timesync vgsvcTimeSyncWorker: Radical guest time change: 2 909 952 665 000ns (GuestNow=1 768 244 399 087 885 000 ns GuestLast=1 768 241 489 135 220 000 ns fSetTimeLastLoop=true ) 2026-01-13 07:45:41 (4108): Guest Log: 03:59:59.763208 timesync vgsvcTimeSyncWorker: Radical host time change: 6 827 038 000 000ns (HostNow=1 768 253 146 381 000 000 ns HostLast=1 768 246 319 343 000 000 ns) 2026-01-13 07:45:41 (4108): Guest Log: 04:00:09.764394 timesync vgsvcTimeSyncWorker: Radical guest time change: 6 826 995 546 000ns (GuestNow=1 768 253 156 382 274 000 ns GuestLast=1 768 246 329 386 728 000 ns fSetTimeLastLoop=true ) 2026-01-13 07:45:41 (4108): Guest Log: 04:04:50.268423 timesync vgsvcTimeSyncWorker: Radical host time change: 2 790 092 000 000ns (HostNow=1 768 258 275 123 000 000 ns HostLast=1 768 255 485 031 000 000 ns) 2026-01-13 07:45:41 (4108): Guest Log: 04:04:50.268493 timesync vgsvcTimeSyncWorker: Radical guest time change: 2 068 185 243 000ns (GuestNow=1 768 255 495 045 122 000 ns GuestLast=1 768 253 426 859 879 000 ns fSetTimeLastLoop=true ) 2026-01-13 07:45:41 (4108): Guest Log: 04:05:00.277021 timesync vgsvcTimeSyncWorker: Radical guest time change: 2 790 098 767 000ns (GuestNow=1 768 258 285 143 889 000 ns GuestLast=1 768 255 495 045 122 000 ns fSetTimeLastLoop=true ) 2026-01-13 07:45:41 (4108): Starting VM using VBoxManage interface. (boinc_3d06f3d8a7ce504e, slot#2) 2026-01-13 07:45:42 (4108): Successfully started VM. (PID = '4651') 2026-01-13 07:45:42 (4108): Reporting VM Process ID to BOINC. 2026-01-13 07:45:42 (4108): Guest Log: BIOS: VirtualBox 7.0.16 2026-01-13 07:45:42 (4108): Guest Log: CPUID EDX: 0x178bfbff 2026-01-13 07:45:42 (4108): Guest Log: BIOS: No PCI IDE controller, not probing IDE 2026-01-13 07:45:42 (4108): Guest Log: BIOS: AHCI 0-P#0: PCHS=16383/16/63 LCHS=1024/255/63 0x0000000002800000 sectors 2026-01-13 07:45:42 (4108): VM state change detected. (old = 'poweredoff', new = 'running') 2026-01-13 07:45:42 (4108): Detected: Web Application Enabled (http://localhost:39909) 2026-01-13 07:45:42 (4108): Detected: Remote Desktop Enabled (localhost:52133) 2026-01-13 07:45:42 (4108): Status Report: Job Duration: '64800.000000' 2026-01-13 07:45:42 (4108): Status Report: Elapsed Time: '15953.000000' 2026-01-13 07:45:42 (4108): Status Report: CPU Time: '36114.060000' 2026-01-13 07:45:42 (4108): Preference change detected 2026-01-13 07:45:42 (4108): Setting CPU throttle for VM. (60%) 2026-01-13 07:45:42 (4108): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds)) 2026-01-13 07:45:44 (4108): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032 2026-01-13 07:45:44 (4108): Guest Log: BIOS: Booting from Hard Disk... 2026-01-13 07:45:46 (4108): Guest Log: BIOS: KBD: unsupported int 16h function 03 2026-01-13 07:45:46 (4108): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 2026-01-13 07:46:07 (4108): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds 2026-01-13 07:46:07 (4108): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000) 2026-01-13 07:46:07 (4108): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log 2026-01-13 07:46:07 (4108): Guest Log: 00:00:00.000073 main Log opened 2026-01-13T07:46:07.358831000Z 2026-01-13 07:46:07 (4108): Guest Log: 00:00:00.000136 main OS Product: Linux 2026-01-13 07:46:07 (4108): Guest Log: 00:00:00.000153 main OS Release: 4.14.232-19.cernvm.x86_64 2026-01-13 07:46:07 (4108): Guest Log: 00:00:00.000163 main OS Version: #1 SMP Fri Apr 30 17:12:25 CEST 2021 2026-01-13 07:46:07 (4108): Guest Log: 00:00:00.000173 main Executable: /usr/sbin/VBoxService 2026-01-13 07:46:07 (4108): Guest Log: 00:00:00.000173 main Process ID: 2278 2026-01-13 07:46:07 (4108): Guest Log: 00:00:00.000173 main Package type: LINUX_64BITS_GENERIC 2026-01-13 07:46:07 (4108): Guest Log: 00:00:00.000896 main 5.2.6 r120293 started. Verbose level = 0 2026-01-13 07:46:18 (4108): Guest Log: [INFO] Mounting the shared directory 2026-01-13 07:46:18 (4108): Guest Log: [INFO] Shared directory mounted, enabling vboxmonitor 2026-01-13 07:46:18 (4108): Guest Log: [INFO] Sourcing essential functions from /cvmfs/grid.cern.ch 2026-01-13 07:46:18 (4108): Guest Log: [INFO] Testing connection to cern.ch 2026-01-13 07:46:18 (4108): Guest Log: [INFO] Testing connection to VCCS 2026-01-13 07:46:19 (4108): Guest Log: [INFO] Testing connection to HTCondor-Collector 2026-01-13 07:46:19 (4108): Guest Log: [INFO] Testing connection to HTCondor-CCB 2026-01-13 07:46:19 (4108): Guest Log: [INFO] Testing connection to WMAgent 2026-01-13 07:46:19 (4108): Guest Log: [INFO] Testing connection to EOSCMS 2026-01-13 07:46:19 (4108): Guest Log: [INFO] Testing connection to CMS-Factory 2026-01-13 07:46:19 (4108): Guest Log: [INFO] Testing connection to CMS-Frontier 2026-01-13 07:46:20 (4108): Guest Log: [INFO] Testing connection to Frontier 2026-01-13 07:46:20 (4108): Guest Log: [INFO] Testing connection to http://cms-frontier.openhtc.io:8080/FrontierProd/Frontier/ 2026-01-13 07:46:20 (4108): Guest Log: [INFO] Could not find a local HTTP proxy 2026-01-13 07:46:20 (4108): Guest Log: [INFO] CVMFS and Frontier will have to use DIRECT connections 2026-01-13 07:46:20 (4108): Guest Log: [INFO] This makes the application less efficient 2026-01-13 07:46:20 (4108): Guest Log: [INFO] It also puts higher load on the project servers 2026-01-13 07:46:20 (4108): Guest Log: [INFO] Setting up a local HTTP proxy is highly recommended 2026-01-13 07:46:21 (4108): Guest Log: [INFO] Advice can be found in the project forum 2026-01-13 07:46:21 (4108): Guest Log: [INFO] Reloading and probing the CVMFS configuration 2026-01-13 07:46:25 (4108): Guest Log: [INFO] Probing /cvmfs/grid.cern.ch... OK 2026-01-13 07:46:27 (4108): Guest Log: [INFO] Probing /cvmfs/oasis.opensciencegrid.org... OK 2026-01-13 07:46:27 (4108): Guest Log: [INFO] Probing /cvmfs/singularity.opensciencegrid.org... OK 2026-01-13 07:46:28 (4108): Guest Log: [INFO] Probing /cvmfs/cms.cern.ch... OK 2026-01-13 07:46:28 (4108): Guest Log: [INFO] Probing /cvmfs/cms-ib.cern.ch... OK 2026-01-13 07:46:29 (4108): Guest Log: [INFO] Excerpt from "cvmfs_config stat": VERSION HOST PROXY 2026-01-13 07:46:29 (4108): Guest Log: [INFO] 2.7.2.0 http://s1cern-cvmfs.openhtc.io DIRECT 2026-01-13 07:46:29 (4108): Guest Log: [INFO] Environment HTTP proxy: not set 2026-01-13 07:46:29 (4108): Guest Log: [INFO] Reading volunteer information 2026-01-13 07:46:30 (4108): Guest Log: [INFO] Requesting an X509 credential from LHC@home 2026-01-13 07:46:31 (4108): Guest Log: [INFO] Requesting an idtoken from LHC@home 2026-01-13 07:46:31 (4108): Guest Log: [INFO] CMS application starting. Check log files. 2026-01-13 07:55:25 (4108): VM state change detected. (old = 'running', new = 'paused') 2026-01-13 07:55:45 (4108): VM state change detected. (old = 'paused', new = 'running') 2026-01-13 07:57:23 (4108): VM state change detected. (old = 'running', new = 'paused') 2026-01-13 09:39:10 (4108): VM state change detected. (old = 'paused', new = 'running') 2026-01-13 09:39:15 (4108): Guest Log: 00:11:00.760077 timesync vgsvcTimeSyncWorker: Radical host time change: 6 117 785 000 000ns (HostNow=1 768 297 155 600 000 000 ns HostLast=1 768 291 037 815 000 000 ns) 2026-01-13 09:39:25 (4108): Guest Log: 00:11:10.760845 timesync vgsvcTimeSyncWorker: Radical guest time change: 6 137 482 181 000ns (GuestNow=1 768 297 165 600 875 000 ns GuestLast=1 768 291 028 118 694 000 ns fSetTimeLastLoop=true ) 2026-01-13 09:49:16 (4108): VM state change detected. (old = 'running', new = 'paused') 2026-01-13 09:49:35 (4108): VM state change detected. (old = 'paused', new = 'running') 2026-01-13 10:00:41 (4108): VM state change detected. (old = 'running', new = 'paused') 2026-01-13 10:01:01 (4108): VM state change detected. (old = 'paused', new = 'running') 2026-01-13 10:11:56 (4108): VM state change detected. (old = 'running', new = 'paused') 2026-01-13 10:12:16 (4108): VM state change detected. (old = 'paused', new = 'running') 2026-01-13 10:23:11 (4108): VM state change detected. (old = 'running', new = 'paused') 2026-01-13 10:23:31 (4108): VM state change detected. (old = 'paused', new = 'running') 2026-01-13 10:34:16 (4108): VM state change detected. (old = 'running', new = 'paused') 2026-01-13 10:34:37 (4108): VM state change detected. (old = 'paused', new = 'running') 2026-01-13 10:43:10 (4108): VM state change detected. (old = 'running', new = 'paused') 2026-01-13 10:43:31 (4108): VM state change detected. (old = 'paused', new = 'running') 2026-01-13 10:45:51 (4108): VM state change detected. (old = 'running', new = 'paused') 2026-01-13 10:46:12 (4108): VM state change detected. (old = 'paused', new = 'running') 2026-01-13 10:56:46 (4108): VM state change detected. (old = 'running', new = 'paused') 2026-01-13 10:57:07 (4108): VM state change detected. (old = 'paused', new = 'running') 2026-01-13 11:08:11 (4108): VM state change detected. (old = 'running', new = 'paused') 2026-01-13 11:08:32 (4108): VM state change detected. (old = 'paused', new = 'running') 2026-01-13 11:10:08 (4108): Status Report: Job Duration: '64800.000000' 2026-01-13 11:10:08 (4108): Status Report: Elapsed Time: '21953.000000' 2026-01-13 11:10:08 (4108): Status Report: CPU Time: '49572.280000' 2026-01-13 11:34:54 (4108): VM state change detected. (old = 'running', new = 'paused') 2026-01-13 11:35:14 (4108): VM state change detected. (old = 'paused', new = 'running') 2026-01-13 12:08:19 (4108): VM state change detected. (old = 'running', new = 'paused') 2026-01-13 12:13:26 (4108): VM state change detected. (old = 'paused', new = 'running') 2026-01-13 12:14:15 (4108): VM state change detected. (old = 'running', new = 'paused') 2026-01-13 12:19:22 (4108): VM state change detected. (old = 'paused', new = 'running') 2026-01-13 12:19:50 (4108): VM state change detected. (old = 'running', new = 'paused') 2026-01-13 14:26:01 (4108): Guest Log: 02:38:02.096900 timesync vgsvcTimeSyncWorker: Radical host time change: 7 581 005 000 000ns (HostNow=1 768 314 361 516 000 000 ns HostLast=1 768 306 780 511 000 000 ns) 2026-01-13 14:26:01 (4108): VM state change detected. (old = 'paused', new = 'running') 2026-01-13 14:26:11 (4108): Guest Log: 02:38:12.151758 timesync vgsvcTimeSyncWorker: Radical guest time change: 8 394 809 896 000ns (GuestNow=1 768 314 371 658 839 000 ns GuestLast=1 768 305 976 848 943 000 ns fSetTimeLastLoop=true ) 2026-01-13 14:27:31 (4108): VM state change detected. (old = 'running', new = 'paused') 2026-01-13 14:32:32 (4108): VM state change detected. (old = 'paused', new = 'running') 2026-01-13 14:39:24 (4108): VM state change detected. (old = 'running', new = 'paused') 2026-01-13 14:39:44 (4108): VM state change detected. (old = 'paused', new = 'running') 2026-01-13 14:40:04 (4108): VM state change detected. (old = 'running', new = 'paused') 2026-01-13 14:40:24 (4108): VM state change detected. (old = 'paused', new = 'running') 2026-01-13 15:11:45 (4108): Status Report: Job Duration: '64800.000000' 2026-01-13 15:11:45 (4108): Status Report: Elapsed Time: '27953.000000' 2026-01-13 15:11:45 (4108): Status Report: CPU Time: '63253.000000' 2026-01-13 15:20:33 (4108): VM state change detected. (old = 'running', new = 'paused') 2026-01-13 15:20:53 (4108): VM state change detected. (old = 'paused', new = 'running') 2026-01-13 15:21:03 (4108): VM state change detected. (old = 'running', new = 'paused') 2026-01-13 15:21:24 (4108): VM state change detected. (old = 'paused', new = 'running') 2026-01-13 15:29:37 (4108): VM state change detected. (old = 'running', new = 'paused') 2026-01-13 15:29:58 (4108): VM state change detected. (old = 'paused', new = 'running') 2026-01-13 15:40:13 (4108): VM state change detected. (old = 'running', new = 'paused') 2026-01-13 16:01:57 (4108): VM state change detected. (old = 'paused', new = 'running') 2026-01-13 16:02:02 (4108): VM state change detected. (old = 'running', new = 'paused') 2026-01-13 16:02:23 (4108): VM state change detected. (old = 'paused', new = 'running') 2026-01-13 16:03:43 (4108): VM state change detected. (old = 'running', new = 'paused') 2026-01-13 16:04:03 (4108): VM state change detected. (old = 'paused', new = 'running') 2026-01-13 16:08:06 (4108): VM state change detected. (old = 'running', new = 'paused') 2026-01-13 16:08:26 (4108): VM state change detected. (old = 'paused', new = 'running') 2026-01-13 16:12:07 (4108): VM state change detected. (old = 'running', new = 'paused') 2026-01-13 16:12:27 (4108): VM state change detected. (old = 'paused', new = 'running') 2026-01-13 16:17:10 (4108): VM state change detected. (old = 'running', new = 'paused') 2026-01-13 16:17:30 (4108): VM state change detected. (old = 'paused', new = 'running') 2026-01-13 16:26:14 (4108): VM state change detected. (old = 'running', new = 'paused') 2026-01-13 16:26:34 (4108): VM state change detected. (old = 'paused', new = 'running') 2026-01-13 16:27:04 (4108): VM state change detected. (old = 'running', new = 'paused') 2026-01-13 16:27:25 (4108): VM state change detected. (old = 'paused', new = 'running') 2026-01-13 16:30:06 (4108): VM state change detected. (old = 'running', new = 'paused') 2026-01-13 16:30:26 (4108): VM state change detected. (old = 'paused', new = 'running') 2026-01-13 16:34:08 (4108): VM state change detected. (old = 'running', new = 'paused') 2026-01-13 16:34:28 (4108): VM state change detected. (old = 'paused', new = 'running') 2026-01-13 16:38:29 (4108): VM state change detected. (old = 'running', new = 'paused') 2026-01-13 16:38:50 (4108): VM state change detected. (old = 'paused', new = 'running') 2026-01-13 16:49:55 (4108): VM state change detected. (old = 'running', new = 'paused') 2026-01-13 16:50:14 (4108): VM state change detected. (old = 'paused', new = 'running') 2026-01-13 17:01:10 (4108): VM state change detected. (old = 'running', new = 'paused') 2026-01-13 17:01:30 (4108): VM state change detected. (old = 'paused', new = 'running') 2026-01-13 17:12:35 (4108): VM state change detected. (old = 'running', new = 'paused') 2026-01-13 17:12:55 (4108): VM state change detected. (old = 'paused', new = 'running') 2026-01-13 17:18:13 (4108): Status Report: Job Duration: '64800.000000' 2026-01-13 17:18:13 (4108): Status Report: Elapsed Time: '33953.000000' 2026-01-13 17:18:13 (4108): Status Report: CPU Time: '77176.670000' 2026-01-13 17:24:10 (4108): VM state change detected. (old = 'running', new = 'paused') 2026-01-13 17:24:31 (4108): VM state change detected. (old = 'paused', new = 'running') 2026-01-13 17:36:06 (4108): VM state change detected. (old = 'running', new = 'paused') 2026-01-13 17:36:26 (4108): VM state change detected. (old = 'paused', new = 'running') 2026-01-13 17:48:22 (4108): VM state change detected. (old = 'running', new = 'paused') 2026-01-13 17:48:42 (4108): VM state change detected. (old = 'paused', new = 'running') 2026-01-13 17:53:24 (4108): VM state change detected. (old = 'running', new = 'paused') 2026-01-13 17:53:44 (4108): VM state change detected. (old = 'paused', new = 'running') 2026-01-13 17:57:56 (4108): VM state change detected. (old = 'running', new = 'paused') 2026-01-13 17:58:16 (4108): VM state change detected. (old = 'paused', new = 'running') 2026-01-13 18:01:08 (4108): VM state change detected. (old = 'running', new = 'paused') 2026-01-13 18:01:27 (4108): VM state change detected. (old = 'paused', new = 'running') 2026-01-13 18:13:14 (4108): VM state change detected. (old = 'running', new = 'paused') 2026-01-13 18:13:34 (4108): VM state change detected. (old = 'paused', new = 'running') 2026-01-13 18:25:09 (4108): VM state change detected. (old = 'running', new = 'paused') 2026-01-13 18:25:29 (4108): VM state change detected. (old = 'paused', new = 'running') 2026-01-13 18:36:54 (4108): VM state change detected. (old = 'running', new = 'paused') 2026-01-13 18:37:15 (4108): VM state change detected. (old = 'paused', new = 'running') 2026-01-13 18:41:45 (4108): VM state change detected. (old = 'running', new = 'paused') 2026-01-13 18:47:21 (4108): VM state change detected. (old = 'paused', new = 'running') 2026-01-13 18:47:39 (4108): VM state change detected. (old = 'running', new = 'paused') 2026-01-13 18:47:59 (4108): VM state change detected. (old = 'paused', new = 'running') 2026-01-13 18:53:12 (4108): VM state change detected. (old = 'running', new = 'paused') 2026-01-13 18:53:31 (4108): VM state change detected. (old = 'paused', new = 'running') 2026-01-13 18:54:02 (4108): VM state change detected. (old = 'running', new = 'paused') 2026-01-13 18:54:22 (4108): VM state change detected. (old = 'paused', new = 'running') 2026-01-13 18:56:33 (4108): VM state change detected. (old = 'running', new = 'paused') 2026-01-13 18:56:54 (4108): VM state change detected. (old = 'paused', new = 'running') 2026-01-13 19:03:16 (4108): VM state change detected. (old = 'running', new = 'paused') 2026-01-13 19:03:37 (4108): VM state change detected. (old = 'paused', new = 'running') 2026-01-13 19:03:47 (4108): VM state change detected. (old = 'running', new = 'paused') 2026-01-13 19:04:06 (4108): VM state change detected. (old = 'paused', new = 'running') 2026-01-13 19:08:00 (4108): VM state change detected. (old = 'running', new = 'paused') 2026-01-13 19:14:13 (4108): VM state change detected. (old = 'paused', new = 'running') 2026-01-13 19:14:23 (4108): Status Report: Job Duration: '64800.000000' 2026-01-13 19:14:23 (4108): Status Report: Elapsed Time: '39953.000000' 2026-01-13 19:14:23 (4108): Status Report: CPU Time: '90832.310000' 2026-01-13 19:18:02 (4108): VM state change detected. (old = 'running', new = 'paused') 2026-01-13 19:18:23 (4108): VM state change detected. (old = 'paused', new = 'running') 2026-01-13 19:19:35 (4108): VM state change detected. (old = 'running', new = 'paused') 2026-01-13 23:46:34 (4108): Guest Log: 06:41:42.821992 timesync vgsvcTimeSyncWorker: Radical host time change: 16 028 288 000 000ns (HostNow=1 768 347 993 481 000 000 ns HostLast=1 768 331 965 193 000 000 ns) 2026-01-13 23:46:34 (4108): VM state change detected. (old = 'paused', new = 'running') 2026-01-13 23:46:43 (4108): VM state change detected. (old = 'running', new = 'paused') 2026-01-13 23:47:03 (4108): VM state change detected. (old = 'paused', new = 'running') 2026-01-13 23:47:04 (4108): Guest Log: 06:41:52.822202 timesync vgsvcTimeSyncWorker: Radical guest time change: 16 048 985 096 000ns (GuestNow=1 768 348 003 481 309 000 ns GuestLast=1 768 331 954 496 213 000 ns fSetTimeLastLoop=true ) 2026-01-13 23:47:22 (4108): VM state change detected. (old = 'running', new = 'paused') 2026-01-13 23:47:43 (4108): VM state change detected. (old = 'paused', new = 'running') 2026-01-13 23:51:53 (4108): Guest Log: [INFO] glidein exited with return value 0. 2026-01-13 23:51:53 (4108): Guest Log: [INFO] Shutting Down. 2026-01-13 23:51:53 (4108): VM Completion File Detected. 2026-01-13 23:51:53 (4108): VM Completion Message: glidein exited with return value 0. . 2026-01-13 23:51:53 (4108): Powering off VM. 2026-01-13 23:51:54 (4108): Successfully stopped VM. 2026-01-13 23:51:54 (4108): Deregistering VM. (boinc_3d06f3d8a7ce504e, slot#2) 2026-01-13 23:51:54 (4108): Removing network bandwidth throttle group from VM. 2026-01-13 23:51:54 (4108): Removing VM from VirtualBox. 2026-01-13 23:51:59 (4108): called boinc_finish(0) </stderr_txt> ]]>
©2026 CERN