| Name | CMS_792130_1737205856.422057_0 |
| Workunit | 229831751 |
| Created | 18 Jan 2025, 13:11:01 UTC |
| Sent | 18 Jan 2025, 13:42:43 UTC |
| Report deadline | 18 Feb 2025, 13:42:43 UTC |
| Received | 24 Jan 2025, 15:00:26 UTC |
| Server state | Over |
| Outcome | Success |
| Client state | Done |
| Exit status | 0 (0x00000000) |
| Computer ID | 10656357 |
| Run time | 18 hours 2 min 17 sec |
| CPU time | 20 hours 27 min 48 sec |
| Validate state | Valid |
| Credit | 3,237.32 |
| Device peak FLOPS | 21.20 GFLOPS |
| Application version | CMS Simulation v70.60 (vbox64_mt_mcore_cms) windows_x86_64 |
| Peak working set size | 89.78 MB |
| Peak swap size | 90.34 MB |
| Peak disk usage | 4.35 GB |
Stderr output
<core_client_version>8.0.2</core_client_version> <![CDATA[ <stderr_txt> 2025-01-20 08:51:38 (18288): BOINC client version: 8.0.2 2025-01-20 08:51:39 (18288): Detected: VirtualBox VboxManage Interface (Version: 7.0.6) 2025-01-20 08:51:39 (18288): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds) 2025-01-20 08:51:40 (18288): Guest Log: BIOS: VirtualBox 7.0.6 2025-01-20 08:51:40 (18288): Guest Log: CPUID EDX: 0x178bfbff 2025-01-20 08:51:40 (18288): Guest Log: BIOS: No PCI IDE controller, not probing IDE 2025-01-20 08:51:40 (18288): Guest Log: BIOS: AHCI 0-P#0: PCHS=16383/16/63 LCHS=1024/255/63 0x0000000002800000 sectors 2025-01-20 08:51:40 (18288): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032 2025-01-20 08:51:40 (18288): Guest Log: BIOS: Booting from Hard Disk... 2025-01-20 08:51:40 (18288): Guest Log: BIOS: KBD: unsupported int 16h function 03 2025-01-20 08:51:40 (18288): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 2025-01-20 08:51:40 (18288): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds 2025-01-20 08:51:40 (18288): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000) 2025-01-20 08:51:40 (18288): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log 2025-01-20 08:51:40 (18288): Guest Log: 00:00:00.003025 main Log opened 2025-01-19T15:48:53.124641000Z 2025-01-20 08:51:40 (18288): Guest Log: 00:00:00.003141 main OS Product: Linux 2025-01-20 08:51:40 (18288): Guest Log: 00:00:00.003175 main OS Release: 4.14.232-19.cernvm.x86_64 2025-01-20 08:51:40 (18288): Guest Log: 00:00:00.003198 main OS Version: #1 SMP Fri Apr 30 17:12:25 CEST 2021 2025-01-20 08:51:40 (18288): Guest Log: 00:00:00.003224 main Executable: /usr/sbin/VBoxService 2025-01-20 08:51:40 (18288): Guest Log: 00:00:00.003225 main Process ID: 2292 2025-01-20 08:51:40 (18288): Guest Log: 00:00:00.003225 main Package type: LINUX_64BITS_GENERIC 2025-01-20 08:51:40 (18288): Guest Log: 00:00:00.003854 main 5.2.6 r120293 started. Verbose level = 0 2025-01-20 08:51:40 (18288): Guest Log: [INFO] Mounting the shared directory 2025-01-20 08:51:40 (18288): Guest Log: [INFO] Shared directory mounted, enabling vboxmonitor 2025-01-20 08:51:40 (18288): Guest Log: [INFO] Sourcing essential functions from /cvmfs/grid.cern.ch 2025-01-20 08:51:40 (18288): Guest Log: [INFO] Testing connection to cern.ch 2025-01-20 08:51:40 (18288): Guest Log: [INFO] Testing connection to VCCS 2025-01-20 08:51:40 (18288): Guest Log: [INFO] Testing connection to HTCondor 2025-01-20 08:51:40 (18288): Guest Log: [INFO] Testing connection to WMAgent 2025-01-20 08:51:40 (18288): Guest Log: [INFO] Testing connection to EOSCMS 2025-01-20 08:51:40 (18288): Guest Log: [INFO] Testing connection to CMS-Factory 2025-01-20 08:51:40 (18288): Guest Log: [INFO] Testing connection to CMS-Frontier 2025-01-20 08:51:40 (18288): Guest Log: [INFO] Testing connection to Frontier 2025-01-20 08:51:40 (18288): Guest Log: [INFO] Could not find a local HTTP proxy 2025-01-20 08:51:40 (18288): Guest Log: [INFO] CVMFS and Frontier will have to use DIRECT connections 2025-01-20 08:51:40 (18288): Guest Log: [INFO] This makes the application less efficient 2025-01-20 08:51:40 (18288): Guest Log: [INFO] It also puts higher load on the project servers 2025-01-20 08:51:40 (18288): Guest Log: [INFO] Setting up a local HTTP proxy is highly recommended 2025-01-20 08:51:40 (18288): Guest Log: [INFO] Advice can be found in the project forum 2025-01-20 08:51:40 (18288): Guest Log: [INFO] Reloading and probing the CVMFS configuration 2025-01-20 08:51:40 (18288): Guest Log: [INFO] Probing /cvmfs/grid.cern.ch... OK 2025-01-20 08:51:40 (18288): Guest Log: [INFO] Probing /cvmfs/singularity.opensciencegrid.org... OK 2025-01-20 08:51:40 (18288): Guest Log: [INFO] Probing /cvmfs/cms-ib.cern.ch... OK 2025-01-20 08:51:40 (18288): Guest Log: [INFO] Probing /cvmfs/cms.cern.ch... OK 2025-01-20 08:51:40 (18288): Guest Log: [INFO] Probing /cvmfs/oasis.opensciencegrid.org... OK 2025-01-20 08:51:40 (18288): Guest Log: [INFO] Excerpt from "cvmfs_config stat": VERSION HOST PROXY 2025-01-20 08:51:40 (18288): Guest Log: [INFO] 2.7.2.0 http://s1fnal-cvmfs.openhtc.io:8080 DIRECT 2025-01-20 08:51:40 (18288): Guest Log: [INFO] Environment HTTP proxy: not set 2025-01-20 08:51:40 (18288): Guest Log: [INFO] Reading volunteer information 2025-01-20 08:51:40 (18288): Guest Log: [INFO] Requesting an X509 credential from LHC@home 2025-01-20 08:51:40 (18288): Guest Log: [INFO] Requesting an idtoken from LHC@home 2025-01-20 08:51:40 (18288): Guest Log: [INFO] CMS application starting. Check log files. 2025-01-20 08:51:40 (18288): Guest Log: 01:04:22.062954 timesync vgsvcTimeSyncWorker: Radical host time change: 3 180 600 000 000ns (HostNow=1 737 309 689 945 000 000 ns HostLast=1 737 306 509 345 000 000 ns) 2025-01-20 08:51:40 (18288): Guest Log: 01:04:32.085804 timesync vgsvcTimeSyncWorker: Radical guest time change: 4 104 926 348 000ns (GuestNow=1 737 309 700 039 359 000 ns GuestLast=1 737 305 595 113 011 000 ns fSetTimeLastLoop=true ) 2025-01-20 08:51:40 (18288): Guest Log: 02:10:36.016063 timesync vgsvcTimeSyncWorker: Radical host time change: 2 190 832 000 000ns (HostNow=1 737 318 087 645 000 000 ns HostLast=1 737 315 896 813 000 000 ns) 2025-01-20 08:51:40 (18288): Guest Log: 02:10:46.021576 timesync vgsvcTimeSyncWorker: Radical guest time change: 2 754 516 299 000ns (GuestNow=1 737 318 097 755 660 000 ns GuestLast=1 737 315 343 239 361 000 ns fSetTimeLastLoop=true ) 2025-01-20 08:51:40 (18288): Starting VM using VBoxManage interface. (boinc_ae611a5faba5c902, slot#0) 2025-01-20 08:51:46 (18288): Successfully started VM. (PID = '18156') 2025-01-20 08:51:46 (18288): Reporting VM Process ID to BOINC. 2025-01-20 08:51:46 (18288): Guest Log: BIOS: VirtualBox 7.0.6 2025-01-20 08:51:46 (18288): Guest Log: CPUID EDX: 0x178bfbff 2025-01-20 08:51:46 (18288): Guest Log: BIOS: No PCI IDE controller, not probing IDE 2025-01-20 08:51:46 (18288): Guest Log: BIOS: AHCI 0-P#0: PCHS=16383/16/63 LCHS=1024/255/63 0x0000000002800000 sectors 2025-01-20 08:51:46 (18288): VM state change detected. (old = 'poweredoff', new = 'running') 2025-01-20 08:51:46 (18288): Detected: Web Application Enabled (http://localhost:51557) 2025-01-20 08:51:46 (18288): Status Report: Job Duration: '64800.000000' 2025-01-20 08:51:46 (18288): Status Report: Elapsed Time: '8094.000000' 2025-01-20 08:51:46 (18288): Status Report: CPU Time: '8900.593750' 2025-01-20 08:51:46 (18288): Preference change detected 2025-01-20 08:51:46 (18288): Setting CPU throttle for VM. (25%) 2025-01-20 08:51:46 (18288): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds)) 2025-01-20 08:51:48 (18288): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032 2025-01-20 08:51:48 (18288): Guest Log: BIOS: Booting from Hard Disk... 2025-01-20 08:51:50 (18288): Guest Log: BIOS: KBD: unsupported int 16h function 03 2025-01-20 08:51:50 (18288): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 2025-01-20 08:52:22 (18288): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds 2025-01-20 08:52:22 (18288): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000) 2025-01-20 08:52:23 (18288): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log 2025-01-20 08:52:23 (18288): Guest Log: 00:00:00.000068 main Log opened 2025-01-20T08:52:21.977520000Z 2025-01-20 08:52:23 (18288): Guest Log: 00:00:00.000152 main OS Product: Linux 2025-01-20 08:52:23 (18288): Guest Log: 00:00:00.000177 main OS Release: 4.14.232-19.cernvm.x86_64 2025-01-20 08:52:23 (18288): Guest Log: 00:00:00.000198 main OS Version: #1 SMP Fri Apr 30 17:12:25 CEST 2021 2025-01-20 08:52:23 (18288): Guest Log: 00:00:00.000217 main Executable: /usr/sbin/VBoxService 2025-01-20 08:52:23 (18288): Guest Log: 00:00:00.000217 main Process ID: 2268 2025-01-20 08:52:23 (18288): Guest Log: 00:00:00.000218 main Package type: LINUX_64BITS_GENERIC 2025-01-20 08:52:23 (18288): Guest Log: 00:00:00.000956 main 5.2.6 r120293 started. Verbose level = 0 2025-01-20 08:52:35 (18288): Guest Log: [INFO] Mounting the shared directory 2025-01-20 08:52:35 (18288): Guest Log: [INFO] Shared directory mounted, enabling vboxmonitor 2025-01-20 08:52:35 (18288): Guest Log: [INFO] Sourcing essential functions from /cvmfs/grid.cern.ch 2025-01-20 08:52:35 (18288): Guest Log: [INFO] Testing connection to cern.ch 2025-01-20 08:52:35 (18288): Guest Log: [INFO] Testing connection to VCCS 2025-01-20 08:52:35 (18288): Guest Log: [INFO] Testing connection to HTCondor 2025-01-20 08:52:35 (18288): Guest Log: [INFO] Testing connection to WMAgent 2025-01-20 08:52:36 (18288): Guest Log: [INFO] Testing connection to EOSCMS 2025-01-20 08:52:36 (18288): Guest Log: [INFO] Testing connection to CMS-Factory 2025-01-20 08:52:36 (18288): Guest Log: [INFO] Testing connection to CMS-Frontier 2025-01-20 08:52:36 (18288): Guest Log: [INFO] Testing connection to Frontier 2025-01-20 08:52:36 (18288): Guest Log: [INFO] Could not find a local HTTP proxy 2025-01-20 08:52:36 (18288): Guest Log: [INFO] CVMFS and Frontier will have to use DIRECT connections 2025-01-20 08:52:36 (18288): Guest Log: [INFO] This makes the application less efficient 2025-01-20 08:52:36 (18288): Guest Log: [INFO] It also puts higher load on the project servers 2025-01-20 08:52:36 (18288): Guest Log: [INFO] Setting up a local HTTP proxy is highly recommended 2025-01-20 08:52:36 (18288): Guest Log: [INFO] Advice can be found in the project forum 2025-01-20 08:52:36 (18288): Guest Log: [INFO] Reloading and probing the CVMFS configuration 2025-01-20 08:52:41 (18288): Guest Log: [INFO] Probing /cvmfs/grid.cern.ch... OK 2025-01-20 08:52:43 (18288): Guest Log: [INFO] Probing /cvmfs/cms.cern.ch... OK 2025-01-20 08:52:43 (18288): Guest Log: [INFO] Probing /cvmfs/cms-ib.cern.ch... OK 2025-01-20 08:52:43 (18288): Guest Log: [INFO] Probing /cvmfs/singularity.opensciencegrid.org... OK 2025-01-20 08:52:43 (18288): Guest Log: [INFO] Probing /cvmfs/oasis.opensciencegrid.org... OK 2025-01-20 08:52:44 (18288): Guest Log: [INFO] Excerpt from "cvmfs_config stat": VERSION HOST PROXY 2025-01-20 08:52:44 (18288): Guest Log: [INFO] 2.7.2.0 http://s1ral-cvmfs.openhtc.io DIRECT 2025-01-20 08:52:44 (18288): Guest Log: [INFO] Environment HTTP proxy: not set 2025-01-20 08:52:44 (18288): Guest Log: [INFO] Reading volunteer information 2025-01-20 08:52:45 (18288): Guest Log: [INFO] Requesting an X509 credential from LHC@home 2025-01-20 08:52:46 (18288): Guest Log: [INFO] Requesting an idtoken from LHC@home 2025-01-20 08:52:46 (18288): Guest Log: [INFO] CMS application starting. Check log files. 2025-01-20 08:59:14 (18288): VM state change detected. (old = 'running', new = 'paused') 2025-01-20 08:59:35 (18288): VM state change detected. (old = 'paused', new = 'running') 2025-01-20 09:28:47 (18288): VM state change detected. (old = 'running', new = 'paused') 2025-01-21 09:49:32 (5464): vboxwrapper version 26208 2025-01-21 09:49:32 (5464): BOINC client version: 8.0.2 2025-01-21 09:49:33 (5464): Detected: VirtualBox VboxManage Interface (Version: 7.0.6) 2025-01-21 09:49:33 (5464): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds) 2025-01-21 09:49:34 (5464): Guest Log: BIOS: VirtualBox 7.0.6 2025-01-21 09:49:34 (5464): Guest Log: CPUID EDX: 0x178bfbff 2025-01-21 09:49:34 (5464): Guest Log: BIOS: No PCI IDE controller, not probing IDE 2025-01-21 09:49:34 (5464): Guest Log: BIOS: AHCI 0-P#0: PCHS=16383/16/63 LCHS=1024/255/63 0x0000000002800000 sectors 2025-01-21 09:49:34 (5464): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032 2025-01-21 09:49:34 (5464): Guest Log: BIOS: Booting from Hard Disk... 2025-01-21 09:49:34 (5464): Guest Log: BIOS: KBD: unsupported int 16h function 03 2025-01-21 09:49:34 (5464): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 2025-01-21 09:49:34 (5464): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds 2025-01-21 09:49:34 (5464): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000) 2025-01-21 09:49:34 (5464): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log 2025-01-21 09:49:34 (5464): Guest Log: 00:00:00.000068 main Log opened 2025-01-20T08:52:21.977520000Z 2025-01-21 09:49:34 (5464): Guest Log: 00:00:00.000152 main OS Product: Linux 2025-01-21 09:49:34 (5464): Guest Log: 00:00:00.000177 main OS Release: 4.14.232-19.cernvm.x86_64 2025-01-21 09:49:34 (5464): Guest Log: 00:00:00.000198 main OS Version: #1 SMP Fri Apr 30 17:12:25 CEST 2021 2025-01-21 09:49:34 (5464): Guest Log: 00:00:00.000217 main Executable: /usr/sbin/VBoxService 2025-01-21 09:49:34 (5464): Guest Log: 00:00:00.000217 main Process ID: 2268 2025-01-21 09:49:34 (5464): Guest Log: 00:00:00.000218 main Package type: LINUX_64BITS_GENERIC 2025-01-21 09:49:34 (5464): Guest Log: 00:00:00.000956 main 5.2.6 r120293 started. Verbose level = 0 2025-01-21 09:49:34 (5464): Guest Log: [INFO] Mounting the shared directory 2025-01-21 09:49:34 (5464): Guest Log: [INFO] Shared directory mounted, enabling vboxmonitor 2025-01-21 09:49:34 (5464): Guest Log: [INFO] Sourcing essential functions from /cvmfs/grid.cern.ch 2025-01-21 09:49:34 (5464): Guest Log: [INFO] Testing connection to cern.ch 2025-01-21 09:49:34 (5464): Guest Log: [INFO] Testing connection to VCCS 2025-01-21 09:49:34 (5464): Guest Log: [INFO] Testing connection to HTCondor 2025-01-21 09:49:34 (5464): Guest Log: [INFO] Testing connection to WMAgent 2025-01-21 09:49:34 (5464): Guest Log: [INFO] Testing connection to EOSCMS 2025-01-21 09:49:34 (5464): Guest Log: [INFO] Testing connection to CMS-Factory 2025-01-21 09:49:34 (5464): Guest Log: [INFO] Testing connection to CMS-Frontier 2025-01-21 09:49:34 (5464): Guest Log: [INFO] Testing connection to Frontier 2025-01-21 09:49:34 (5464): Guest Log: [INFO] Could not find a local HTTP proxy 2025-01-21 09:49:34 (5464): Guest Log: [INFO] CVMFS and Frontier will have to use DIRECT connections 2025-01-21 09:49:34 (5464): Guest Log: [INFO] This makes the application less efficient 2025-01-21 09:49:34 (5464): Guest Log: [INFO] It also puts higher load on the project servers 2025-01-21 09:49:34 (5464): Guest Log: [INFO] Setting up a local HTTP proxy is highly recommended 2025-01-21 09:49:34 (5464): Guest Log: [INFO] Advice can be found in the project forum 2025-01-21 09:49:34 (5464): Guest Log: [INFO] Reloading and probing the CVMFS configuration 2025-01-21 09:49:34 (5464): Guest Log: [INFO] Probing /cvmfs/grid.cern.ch... OK 2025-01-21 09:49:34 (5464): Guest Log: [INFO] Probing /cvmfs/cms.cern.ch... OK 2025-01-21 09:49:34 (5464): Guest Log: [INFO] Probing /cvmfs/cms-ib.cern.ch... OK 2025-01-21 09:49:34 (5464): Guest Log: [INFO] Probing /cvmfs/singularity.opensciencegrid.org... OK 2025-01-21 09:49:34 (5464): Guest Log: [INFO] Probing /cvmfs/oasis.opensciencegrid.org... OK 2025-01-21 09:49:34 (5464): Guest Log: [INFO] Excerpt from "cvmfs_config stat": VERSION HOST PROXY 2025-01-21 09:49:34 (5464): Guest Log: [INFO] 2.7.2.0 http://s1ral-cvmfs.openhtc.io DIRECT 2025-01-21 09:49:34 (5464): Guest Log: [INFO] Environment HTTP proxy: not set 2025-01-21 09:49:34 (5464): Guest Log: [INFO] Reading volunteer information 2025-01-21 09:49:34 (5464): Guest Log: [INFO] Requesting an X509 credential from LHC@home 2025-01-21 09:49:34 (5464): Guest Log: [INFO] Requesting an idtoken from LHC@home 2025-01-21 09:49:34 (5464): Guest Log: [INFO] CMS application starting. Check log files. 2025-01-21 09:49:34 (5464): Starting VM using VBoxManage interface. (boinc_ae611a5faba5c902, slot#0) 2025-01-21 09:49:40 (5464): Successfully started VM. (PID = '33168') 2025-01-21 09:49:40 (5464): Reporting VM Process ID to BOINC. 2025-01-21 09:49:40 (5464): Guest Log: BIOS: VirtualBox 7.0.6 2025-01-21 09:49:40 (5464): Guest Log: CPUID EDX: 0x178bfbff 2025-01-21 09:49:40 (5464): Guest Log: BIOS: No PCI IDE controller, not probing IDE 2025-01-21 09:49:40 (5464): Guest Log: BIOS: AHCI 0-P#0: PCHS=16383/16/63 LCHS=1024/255/63 0x0000000002800000 sectors 2025-01-21 09:49:40 (5464): VM state change detected. (old = 'poweredoff', new = 'running') 2025-01-21 09:49:40 (5464): Detected: Web Application Enabled (http://localhost:51557) 2025-01-21 09:49:40 (5464): Status Report: Job Duration: '64800.000000' 2025-01-21 09:49:40 (5464): Status Report: Elapsed Time: '10231.000000' 2025-01-21 09:49:40 (5464): Status Report: CPU Time: '11192.468750' 2025-01-21 09:49:40 (5464): Preference change detected 2025-01-21 09:49:40 (5464): Setting CPU throttle for VM. (25%) 2025-01-21 09:49:40 (5464): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds)) 2025-01-21 09:49:42 (5464): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032 2025-01-21 09:49:42 (5464): Guest Log: BIOS: Booting from Hard Disk... 2025-01-21 09:49:45 (5464): Guest Log: BIOS: KBD: unsupported int 16h function 03 2025-01-21 09:49:45 (5464): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 2025-01-21 09:50:17 (5464): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds 2025-01-21 09:50:17 (5464): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000) 2025-01-21 09:50:18 (5464): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log 2025-01-21 09:50:18 (5464): Guest Log: 00:00:00.000078 main Log opened 2025-01-21T09:50:16.396510000Z 2025-01-21 09:50:18 (5464): Guest Log: 00:00:00.000215 main OS Product: Linux 2025-01-21 09:50:18 (5464): Guest Log: 00:00:00.000238 main OS Release: 4.14.232-19.cernvm.x86_64 2025-01-21 09:50:18 (5464): Guest Log: 00:00:00.000255 main OS Version: #1 SMP Fri Apr 30 17:12:25 CEST 2021 2025-01-21 09:50:18 (5464): Guest Log: 00:00:00.000273 main Executable: /usr/sbin/VBoxService 2025-01-21 09:50:18 (5464): Guest Log: 00:00:00.000274 main Process ID: 2282 2025-01-21 09:50:18 (5464): Guest Log: 00:00:00.000274 main Package type: LINUX_64BITS_GENERIC 2025-01-21 09:50:18 (5464): Guest Log: 00:00:00.000722 main 5.2.6 r120293 started. Verbose level = 0 2025-01-21 09:50:29 (5464): VM state change detected. (old = 'running', new = 'paused') 2025-01-21 09:50:49 (5464): Guest Log: [INFO] Mounting the shared directory 2025-01-21 09:50:49 (5464): Guest Log: [INFO] Shared directory mounted, enabling vboxmonitor 2025-01-21 09:50:49 (5464): Guest Log: [INFO] Sourcing essential functions from /cvmfs/grid.cern.ch 2025-01-21 09:50:49 (5464): Guest Log: [INFO] Testing connection to cern.ch 2025-01-21 09:50:49 (5464): VM state change detected. (old = 'paused', new = 'running') 2025-01-21 09:50:50 (5464): Guest Log: [INFO] Testing connection to VCCS 2025-01-21 09:50:50 (5464): Guest Log: [INFO] Testing connection to HTCondor 2025-01-21 09:50:50 (5464): Guest Log: [INFO] Testing connection to WMAgent 2025-01-21 09:50:50 (5464): Guest Log: [INFO] Testing connection to EOSCMS 2025-01-21 09:50:50 (5464): Guest Log: [INFO] Testing connection to CMS-Factory 2025-01-21 09:50:50 (5464): Guest Log: [INFO] Testing connection to CMS-Frontier 2025-01-21 09:50:50 (5464): Guest Log: [INFO] Testing connection to Frontier 2025-01-21 09:50:51 (5464): Guest Log: [INFO] Could not find a local HTTP proxy 2025-01-21 09:50:51 (5464): Guest Log: [INFO] CVMFS and Frontier will have to use DIRECT connections 2025-01-21 09:50:51 (5464): Guest Log: [INFO] This makes the application less efficient 2025-01-21 09:50:51 (5464): Guest Log: [INFO] It also puts higher load on the project servers 2025-01-21 09:50:51 (5464): Guest Log: [INFO] Setting up a local HTTP proxy is highly recommended 2025-01-21 09:50:51 (5464): Guest Log: [INFO] Advice can be found in the project forum 2025-01-21 09:50:52 (5464): Guest Log: [INFO] Reloading and probing the CVMFS configuration 2025-01-21 09:50:58 (5464): Guest Log: [INFO] Probing /cvmfs/grid.cern.ch... OK 2025-01-21 09:51:00 (5464): Guest Log: [INFO] Probing /cvmfs/singularity.opensciencegrid.org... OK 2025-01-21 09:51:00 (5464): Guest Log: [INFO] Probing /cvmfs/oasis.opensciencegrid.org... OK 2025-01-21 09:51:00 (5464): Guest Log: [INFO] Probing /cvmfs/cms-ib.cern.ch... OK 2025-01-21 09:51:01 (5464): Guest Log: [INFO] Probing /cvmfs/cms.cern.ch... OK 2025-01-21 09:51:02 (5464): Guest Log: [INFO] Excerpt from "cvmfs_config stat": VERSION HOST PROXY 2025-01-21 09:51:02 (5464): Guest Log: [INFO] 2.7.2.0 http://s1cern-cvmfs.openhtc.io DIRECT 2025-01-21 09:51:02 (5464): Guest Log: [INFO] Environment HTTP proxy: not set 2025-01-21 09:51:02 (5464): Guest Log: [INFO] Reading volunteer information 2025-01-21 09:51:03 (5464): Guest Log: [INFO] Requesting an X509 credential from LHC@home 2025-01-21 09:51:05 (5464): Guest Log: [INFO] Requesting an idtoken from LHC@home 2025-01-21 09:51:05 (5464): Guest Log: [INFO] CMS application starting. Check log files. 2025-01-21 10:10:31 (5464): VM state change detected. (old = 'running', new = 'paused') 2025-01-21 10:10:51 (5464): VM state change detected. (old = 'paused', new = 'running') 2025-01-21 10:12:21 (5464): VM state change detected. (old = 'running', new = 'paused') 2025-01-21 10:12:41 (5464): VM state change detected. (old = 'paused', new = 'running') 2025-01-21 10:17:32 (5464): VM state change detected. (old = 'running', new = 'paused') 2025-01-21 10:17:52 (5464): VM state change detected. (old = 'paused', new = 'running') 2025-01-21 10:23:52 (5464): VM state change detected. (old = 'running', new = 'paused') 2025-01-21 10:24:12 (5464): VM state change detected. (old = 'paused', new = 'running') 2025-01-21 10:42:05 (5464): VM state change detected. (old = 'running', new = 'paused') 2025-01-21 10:42:24 (5464): VM state change detected. (old = 'paused', new = 'running') 2025-01-21 10:42:33 (5464): VM state change detected. (old = 'running', new = 'paused') 2025-01-21 10:53:48 (5464): VM state change detected. (old = 'paused', new = 'running') 2025-01-21 11:08:37 (5464): VM state change detected. (old = 'running', new = 'paused') 2025-01-21 11:08:57 (5464): VM state change detected. (old = 'paused', new = 'running') 2025-01-21 11:13:28 (5464): VM state change detected. (old = 'running', new = 'paused') 2025-01-21 11:13:49 (5464): VM state change detected. (old = 'paused', new = 'running') 2025-01-21 11:15:19 (5464): VM state change detected. (old = 'running', new = 'paused') 2025-01-21 11:15:39 (5464): VM state change detected. (old = 'paused', new = 'running') 2025-01-21 11:20:39 (5464): VM state change detected. (old = 'running', new = 'paused') 2025-01-21 11:20:40 (5464): VM state change detected. (old = 'paused', new = 'running') 2025-01-21 11:22:29 (5464): VM state change detected. (old = 'running', new = 'paused') 2025-01-21 11:22:49 (5464): VM state change detected. (old = 'paused', new = 'running') 2025-01-21 11:29:20 (5464): VM state change detected. (old = 'running', new = 'paused') 2025-01-21 11:29:40 (5464): VM state change detected. (old = 'paused', new = 'running') 2025-01-21 11:34:50 (5464): VM state change detected. (old = 'running', new = 'paused') 2025-01-21 11:35:10 (5464): VM state change detected. (old = 'paused', new = 'running') 2025-01-21 11:38:10 (5464): VM state change detected. (old = 'running', new = 'paused') 2025-01-21 11:38:30 (5464): VM state change detected. (old = 'paused', new = 'running') 2025-01-21 11:42:21 (5464): VM state change detected. (old = 'running', new = 'paused') 2025-01-21 11:42:41 (5464): VM state change detected. (old = 'paused', new = 'running') 2025-01-21 11:43:42 (5464): VM state change detected. (old = 'running', new = 'paused') 2025-01-21 11:44:02 (5464): VM state change detected. (old = 'paused', new = 'running') 2025-01-21 11:46:21 (5464): Status Report: Job Duration: '64800.000000' 2025-01-21 11:46:21 (5464): Status Report: Elapsed Time: '16231.000000' 2025-01-21 11:46:21 (5464): Status Report: CPU Time: '17968.453125' 2025-01-21 11:47:02 (5464): VM state change detected. (old = 'running', new = 'paused') 2025-01-21 11:47:22 (5464): VM state change detected. (old = 'paused', new = 'running') 2025-01-21 12:40:47 (5464): VM state change detected. (old = 'running', new = 'paused') 2025-01-21 12:41:07 (5464): VM state change detected. (old = 'paused', new = 'running') 2025-01-21 12:56:59 (5464): VM state change detected. (old = 'running', new = 'paused') 2025-01-21 12:57:19 (5464): VM state change detected. (old = 'paused', new = 'running') 2025-01-21 13:11:50 (5464): Stopping VM. 2025-01-21 13:12:04 (5464): Successfully stopped VM. 2025-01-21 14:12:18 (21004): vboxwrapper version 26208 2025-01-21 14:12:18 (21004): BOINC client version: 8.0.2 2025-01-21 14:12:19 (21004): Detected: VirtualBox VboxManage Interface (Version: 7.0.6) 2025-01-21 14:12:19 (21004): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds) 2025-01-21 14:12:20 (21004): Guest Log: BIOS: VirtualBox 7.0.6 2025-01-21 14:12:20 (21004): Guest Log: CPUID EDX: 0x178bfbff 2025-01-21 14:12:20 (21004): Guest Log: BIOS: No PCI IDE controller, not probing IDE 2025-01-21 14:12:20 (21004): Guest Log: BIOS: AHCI 0-P#0: PCHS=16383/16/63 LCHS=1024/255/63 0x0000000002800000 sectors 2025-01-21 14:12:20 (21004): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032 2025-01-21 14:12:20 (21004): Guest Log: BIOS: Booting from Hard Disk... 2025-01-21 14:12:20 (21004): Guest Log: BIOS: KBD: unsupported int 16h function 03 2025-01-21 14:12:20 (21004): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 2025-01-21 14:12:20 (21004): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds 2025-01-21 14:12:20 (21004): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000) 2025-01-21 14:12:20 (21004): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log 2025-01-21 14:12:20 (21004): Guest Log: 00:00:00.000078 main Log opened 2025-01-21T09:50:16.396510000Z 2025-01-21 14:12:20 (21004): Guest Log: 00:00:00.000215 main OS Product: Linux 2025-01-21 14:12:20 (21004): Guest Log: 00:00:00.000238 main OS Release: 4.14.232-19.cernvm.x86_64 2025-01-21 14:12:20 (21004): Guest Log: 00:00:00.000255 main OS Version: #1 SMP Fri Apr 30 17:12:25 CEST 2021 2025-01-21 14:12:20 (21004): Guest Log: 00:00:00.000273 main Executable: /usr/sbin/VBoxService 2025-01-21 14:12:20 (21004): Guest Log: 00:00:00.000274 main Process ID: 2282 2025-01-21 14:12:20 (21004): Guest Log: 00:00:00.000274 main Package type: LINUX_64BITS_GENERIC 2025-01-21 14:12:20 (21004): Guest Log: 00:00:00.000722 main 5.2.6 r120293 started. Verbose level = 0 2025-01-21 14:12:20 (21004): Guest Log: [INFO] Mounting the shared directory 2025-01-21 14:12:20 (21004): Guest Log: [INFO] Shared directory mounted, enabling vboxmonitor 2025-01-21 14:12:20 (21004): Guest Log: [INFO] Sourcing essential functions from /cvmfs/grid.cern.ch 2025-01-21 14:12:20 (21004): Guest Log: [INFO] Testing connection to cern.ch 2025-01-21 14:12:20 (21004): Guest Log: [INFO] Testing connection to VCCS 2025-01-21 14:12:20 (21004): Guest Log: [INFO] Testing connection to HTCondor 2025-01-21 14:12:20 (21004): Guest Log: [INFO] Testing connection to WMAgent 2025-01-21 14:12:20 (21004): Guest Log: [INFO] Testing connection to EOSCMS 2025-01-21 14:12:20 (21004): Guest Log: [INFO] Testing connection to CMS-Factory 2025-01-21 14:12:20 (21004): Guest Log: [INFO] Testing connection to CMS-Frontier 2025-01-21 14:12:20 (21004): Guest Log: [INFO] Testing connection to Frontier 2025-01-21 14:12:20 (21004): Guest Log: [INFO] Could not find a local HTTP proxy 2025-01-21 14:12:20 (21004): Guest Log: [INFO] CVMFS and Frontier will have to use DIRECT connections 2025-01-21 14:12:20 (21004): Guest Log: [INFO] This makes the application less efficient 2025-01-21 14:12:20 (21004): Guest Log: [INFO] It also puts higher load on the project servers 2025-01-21 14:12:20 (21004): Guest Log: [INFO] Setting up a local HTTP proxy is highly recommended 2025-01-21 14:12:20 (21004): Guest Log: [INFO] Advice can be found in the project forum 2025-01-21 14:12:20 (21004): Guest Log: [INFO] Reloading and probing the CVMFS configuration 2025-01-21 14:12:20 (21004): Guest Log: [INFO] Probing /cvmfs/grid.cern.ch... OK 2025-01-21 14:12:20 (21004): Guest Log: [INFO] Probing /cvmfs/singularity.opensciencegrid.org... OK 2025-01-21 14:12:20 (21004): Guest Log: [INFO] Probing /cvmfs/oasis.opensciencegrid.org... OK 2025-01-21 14:12:20 (21004): Guest Log: [INFO] Probing /cvmfs/cms-ib.cern.ch... OK 2025-01-21 14:12:20 (21004): Guest Log: [INFO] Probing /cvmfs/cms.cern.ch... OK 2025-01-21 14:12:20 (21004): Guest Log: [INFO] Excerpt from "cvmfs_config stat": VERSION HOST PROXY 2025-01-21 14:12:20 (21004): Guest Log: [INFO] 2.7.2.0 http://s1cern-cvmfs.openhtc.io DIRECT 2025-01-21 14:12:20 (21004): Guest Log: [INFO] Environment HTTP proxy: not set 2025-01-21 14:12:20 (21004): Guest Log: [INFO] Reading volunteer information 2025-01-21 14:12:20 (21004): Guest Log: [INFO] Requesting an X509 credential from LHC@home 2025-01-21 14:12:20 (21004): Guest Log: [INFO] Requesting an idtoken from LHC@home 2025-01-21 14:12:20 (21004): Guest Log: [INFO] CMS application starting. Check log files. 2025-01-21 14:12:20 (21004): Starting VM using VBoxManage interface. (boinc_ae611a5faba5c902, slot#0) 2025-01-21 14:12:36 (21004): Successfully started VM. (PID = '24668') 2025-01-21 14:12:36 (21004): Reporting VM Process ID to BOINC. 2025-01-21 14:12:36 (21004): VM state change detected. (old = 'poweredoff', new = 'running') 2025-01-21 14:12:36 (21004): Detected: Web Application Enabled (http://localhost:51557) 2025-01-21 14:12:36 (21004): Status Report: Job Duration: '64800.000000' 2025-01-21 14:12:36 (21004): Status Report: Elapsed Time: '21291.000000' 2025-01-21 14:12:36 (21004): Status Report: CPU Time: '23849.156250' 2025-01-21 14:12:36 (21004): Preference change detected 2025-01-21 14:12:36 (21004): Setting CPU throttle for VM. (25%) 2025-01-21 14:12:36 (21004): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds)) 2025-01-21 14:12:44 (21004): Guest Log: 03:04:29.630678 timesync vgsvcTimeSyncWorker: Radical host time change: 3 655 481 000 000ns (HostNow=1 737 468 764 122 000 000 ns HostLast=1 737 465 108 641 000 000 ns) 2025-01-21 14:12:54 (21004): Guest Log: 03:04:39.651721 timesync vgsvcTimeSyncWorker: Radical guest time change: 4 688 128 015 000ns (GuestNow=1 737 468 774 149 056 000 ns GuestLast=1 737 464 086 021 041 000 ns fSetTimeLastLoop=true ) 2025-01-21 14:21:28 (21004): VM state change detected. (old = 'running', new = 'paused') 2025-01-21 14:21:49 (21004): VM state change detected. (old = 'paused', new = 'running') 2025-01-21 14:30:58 (21004): VM state change detected. (old = 'running', new = 'paused') 2025-01-21 14:31:18 (21004): VM state change detected. (old = 'paused', new = 'running') 2025-01-21 15:04:12 (21004): VM state change detected. (old = 'running', new = 'paused') 2025-01-21 15:04:32 (21004): VM state change detected. (old = 'paused', new = 'running') 2025-01-21 15:40:05 (21004): VM state change detected. (old = 'running', new = 'paused') 2025-01-21 15:40:25 (21004): VM state change detected. (old = 'paused', new = 'running') 2025-01-21 15:54:08 (21004): Status Report: Job Duration: '64800.000000' 2025-01-21 15:54:08 (21004): Status Report: Elapsed Time: '27291.000000' 2025-01-21 15:54:08 (21004): Status Report: CPU Time: '30829.734375' 2025-01-21 16:03:58 (21004): VM state change detected. (old = 'running', new = 'paused') 2025-01-21 16:04:18 (21004): VM state change detected. (old = 'paused', new = 'running') 2025-01-21 16:17:40 (21004): VM state change detected. (old = 'running', new = 'paused') 2025-01-21 16:18:00 (21004): VM state change detected. (old = 'paused', new = 'running') 2025-01-21 16:28:30 (21004): VM state change detected. (old = 'running', new = 'paused') 2025-01-21 16:28:50 (21004): VM state change detected. (old = 'paused', new = 'running') 2025-01-21 16:36:51 (21004): VM state change detected. (old = 'running', new = 'paused') 2025-01-21 16:37:11 (21004): VM state change detected. (old = 'paused', new = 'running') 2025-01-21 16:49:13 (21004): VM state change detected. (old = 'running', new = 'paused') 2025-01-21 16:49:33 (21004): VM state change detected. (old = 'paused', new = 'running') 2025-01-21 16:52:23 (21004): VM state change detected. (old = 'running', new = 'paused') 2025-01-21 16:52:43 (21004): VM state change detected. (old = 'paused', new = 'running') 2025-01-21 16:58:14 (21004): VM state change detected. (old = 'running', new = 'paused') 2025-01-21 16:58:34 (21004): VM state change detected. (old = 'paused', new = 'running') 2025-01-21 17:00:25 (21004): VM state change detected. (old = 'running', new = 'paused') 2025-01-21 17:00:45 (21004): VM state change detected. (old = 'paused', new = 'running') 2025-01-21 17:06:35 (21004): VM state change detected. (old = 'running', new = 'paused') 2025-01-21 18:35:29 (21004): VM state change detected. (old = 'paused', new = 'running') 2025-01-21 18:35:33 (21004): Guest Log: 05:54:27.591636 timesync vgsvcTimeSyncWorker: Radical host time change: 5 343 531 000 000ns (HostNow=1 737 484 532 313 000 000 ns HostLast=1 737 479 188 782 000 000 ns) 2025-01-21 18:35:43 (21004): Guest Log: 05:54:37.615062 timesync vgsvcTimeSyncWorker: Radical guest time change: 5 580 247 584 000ns (GuestNow=1 737 484 542 336 493 000 ns GuestLast=1 737 478 962 088 909 000 ns fSetTimeLastLoop=true ) 2025-01-21 18:59:28 (21004): VM state change detected. (old = 'running', new = 'paused') 2025-01-22 09:46:52 (2168): vboxwrapper version 26208 2025-01-22 09:46:52 (2168): BOINC client version: 8.0.2 2025-01-22 09:46:53 (2168): Detected: VirtualBox VboxManage Interface (Version: 7.0.6) 2025-01-22 09:46:53 (2168): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds) 2025-01-22 09:46:54 (2168): Guest Log: 03:04:29.630678 timesync vgsvcTimeSyncWorker: Radical host time change: 3 655 481 000 000ns (HostNow=1 737 468 764 122 000 000 ns HostLast=1 737 465 108 641 000 000 ns) 2025-01-22 09:46:54 (2168): Guest Log: 03:04:39.651721 timesync vgsvcTimeSyncWorker: Radical guest time change: 4 688 128 015 000ns (GuestNow=1 737 468 774 149 056 000 ns GuestLast=1 737 464 086 021 041 000 ns fSetTimeLastLoop=true ) 2025-01-22 09:46:54 (2168): Guest Log: 05:54:27.591636 timesync vgsvcTimeSyncWorker: Radical host time change: 5 343 531 000 000ns (HostNow=1 737 484 532 313 000 000 ns HostLast=1 737 479 188 782 000 000 ns) 2025-01-22 09:46:54 (2168): Guest Log: 05:54:37.615062 timesync vgsvcTimeSyncWorker: Radical guest time change: 5 580 247 584 000ns (GuestNow=1 737 484 542 336 493 000 ns GuestLast=1 737 478 962 088 909 000 ns fSetTimeLastLoop=true ) 2025-01-22 09:46:54 (2168): Starting VM using VBoxManage interface. (boinc_ae611a5faba5c902, slot#0) 2025-01-22 09:47:00 (2168): Successfully started VM. (PID = '19592') 2025-01-22 09:47:00 (2168): Reporting VM Process ID to BOINC. 2025-01-22 09:47:00 (2168): Guest Log: BIOS: VirtualBox 7.0.6 2025-01-22 09:47:00 (2168): Guest Log: CPUID EDX: 0x178bfbff 2025-01-22 09:47:00 (2168): Guest Log: BIOS: No PCI IDE controller, not probing IDE 2025-01-22 09:47:00 (2168): Guest Log: BIOS: AHCI 0-P#0: PCHS=16383/16/63 LCHS=1024/255/63 0x0000000002800000 sectors 2025-01-22 09:47:00 (2168): VM state change detected. (old = 'poweredoff', new = 'running') 2025-01-22 09:47:00 (2168): Detected: Web Application Enabled (http://localhost:51557) 2025-01-22 09:47:00 (2168): Status Report: Job Duration: '64800.000000' 2025-01-22 09:47:00 (2168): Status Report: Elapsed Time: '32847.000000' 2025-01-22 09:47:00 (2168): Status Report: CPU Time: '37492.250000' 2025-01-22 09:47:00 (2168): Preference change detected 2025-01-22 09:47:00 (2168): Setting CPU throttle for VM. (25%) 2025-01-22 09:47:01 (2168): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds)) 2025-01-22 09:47:02 (2168): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032 2025-01-22 09:47:02 (2168): Guest Log: BIOS: Booting from Hard Disk... 2025-01-22 09:47:05 (2168): Guest Log: BIOS: KBD: unsupported int 16h function 03 2025-01-22 09:47:05 (2168): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 2025-01-22 09:47:31 (2168): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds 2025-01-22 09:47:31 (2168): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000) 2025-01-22 09:47:31 (2168): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log 2025-01-22 09:47:31 (2168): Guest Log: 00:00:00.000069 main Log opened 2025-01-22T09:47:31.125900000Z 2025-01-22 09:47:31 (2168): Guest Log: 00:00:00.000142 main OS Product: Linux 2025-01-22 09:47:31 (2168): Guest Log: 00:00:00.000163 main OS Release: 4.14.232-19.cernvm.x86_64 2025-01-22 09:47:31 (2168): Guest Log: 00:00:00.000183 main OS Version: #1 SMP Fri Apr 30 17:12:25 CEST 2021 2025-01-22 09:47:31 (2168): Guest Log: 00:00:00.000201 main Executable: /usr/sbin/VBoxService 2025-01-22 09:47:31 (2168): Guest Log: 00:00:00.000201 main Process ID: 2282 2025-01-22 09:47:31 (2168): Guest Log: 00:00:00.000202 main Package type: LINUX_64BITS_GENERIC 2025-01-22 09:47:31 (2168): Guest Log: 00:00:00.000737 main 5.2.6 r120293 started. Verbose level = 0 2025-01-22 09:47:42 (2168): Guest Log: [INFO] Mounting the shared directory 2025-01-22 09:47:42 (2168): Guest Log: [INFO] Shared directory mounted, enabling vboxmonitor 2025-01-22 09:47:42 (2168): Guest Log: [INFO] Sourcing essential functions from /cvmfs/grid.cern.ch 2025-01-22 09:47:42 (2168): Guest Log: [INFO] Testing connection to cern.ch 2025-01-22 09:47:42 (2168): Guest Log: [INFO] Testing connection to VCCS 2025-01-22 09:47:42 (2168): Guest Log: [INFO] Testing connection to HTCondor 2025-01-22 09:47:43 (2168): Guest Log: [INFO] Testing connection to WMAgent 2025-01-22 09:47:43 (2168): Guest Log: [INFO] Testing connection to EOSCMS 2025-01-22 09:47:43 (2168): Guest Log: [INFO] Testing connection to CMS-Factory 2025-01-22 09:47:43 (2168): Guest Log: [INFO] Testing connection to CMS-Frontier 2025-01-22 09:47:43 (2168): Guest Log: [INFO] Testing connection to Frontier 2025-01-22 09:47:43 (2168): Guest Log: [INFO] Could not find a local HTTP proxy 2025-01-22 09:47:43 (2168): Guest Log: [INFO] CVMFS and Frontier will have to use DIRECT connections 2025-01-22 09:47:43 (2168): Guest Log: [INFO] This makes the application less efficient 2025-01-22 09:47:44 (2168): Guest Log: [INFO] It also puts higher load on the project servers 2025-01-22 09:47:44 (2168): Guest Log: [INFO] Setting up a local HTTP proxy is highly recommended 2025-01-22 09:47:44 (2168): Guest Log: [INFO] Advice can be found in the project forum 2025-01-22 09:47:44 (2168): Guest Log: [INFO] Reloading and probing the CVMFS configuration 2025-01-22 09:47:48 (2168): Guest Log: [INFO] Probing /cvmfs/grid.cern.ch... OK 2025-01-22 09:47:50 (2168): Guest Log: [INFO] Probing /cvmfs/cms-ib.cern.ch... OK 2025-01-22 09:47:50 (2168): Guest Log: [INFO] Probing /cvmfs/cms.cern.ch... OK 2025-01-22 09:47:50 (2168): Guest Log: [INFO] Probing /cvmfs/singularity.opensciencegrid.org... OK 2025-01-22 09:47:50 (2168): Guest Log: [INFO] Probing /cvmfs/oasis.opensciencegrid.org... OK 2025-01-22 09:47:50 (2168): Guest Log: [INFO] Excerpt from "cvmfs_config stat": VERSION HOST PROXY 2025-01-22 09:47:50 (2168): Guest Log: [INFO] 2.7.2.0 http://s1fnal-cvmfs.openhtc.io:8080 DIRECT 2025-01-22 09:47:50 (2168): Guest Log: [INFO] Environment HTTP proxy: not set 2025-01-22 09:47:51 (2168): Guest Log: [INFO] Reading volunteer information 2025-01-22 09:47:52 (2168): Guest Log: [INFO] Requesting an X509 credential from LHC@home 2025-01-22 09:47:54 (2168): Guest Log: [INFO] Requesting an idtoken from LHC@home 2025-01-22 09:47:54 (2168): Guest Log: [INFO] CMS application starting. Check log files. 2025-01-22 09:50:45 (2168): Stopping VM. 2025-01-22 09:50:56 (2168): Successfully stopped VM. 2025-01-22 10:15:35 (23052): vboxwrapper version 26208 2025-01-22 10:15:35 (23052): BOINC client version: 8.0.2 2025-01-22 10:15:35 (23052): Detected: VirtualBox VboxManage Interface (Version: 7.0.6) 2025-01-22 10:15:35 (23052): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds) 2025-01-22 10:15:36 (23052): Guest Log: BIOS: VirtualBox 7.0.6 2025-01-22 10:15:36 (23052): Guest Log: CPUID EDX: 0x178bfbff 2025-01-22 10:15:36 (23052): Guest Log: BIOS: No PCI IDE controller, not probing IDE 2025-01-22 10:15:36 (23052): Guest Log: BIOS: AHCI 0-P#0: PCHS=16383/16/63 LCHS=1024/255/63 0x0000000002800000 sectors 2025-01-22 10:15:36 (23052): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032 2025-01-22 10:15:36 (23052): Guest Log: BIOS: Booting from Hard Disk... 2025-01-22 10:15:36 (23052): Guest Log: BIOS: KBD: unsupported int 16h function 03 2025-01-22 10:15:36 (23052): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 2025-01-22 10:15:36 (23052): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds 2025-01-22 10:15:36 (23052): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000) 2025-01-22 10:15:36 (23052): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log 2025-01-22 10:15:36 (23052): Guest Log: 00:00:00.000069 main Log opened 2025-01-22T09:47:31.125900000Z 2025-01-22 10:15:36 (23052): Guest Log: 00:00:00.000142 main OS Product: Linux 2025-01-22 10:15:36 (23052): Guest Log: 00:00:00.000163 main OS Release: 4.14.232-19.cernvm.x86_64 2025-01-22 10:15:36 (23052): Guest Log: 00:00:00.000183 main OS Version: #1 SMP Fri Apr 30 17:12:25 CEST 2021 2025-01-22 10:15:36 (23052): Guest Log: 00:00:00.000201 main Executable: /usr/sbin/VBoxService 2025-01-22 10:15:36 (23052): Guest Log: 00:00:00.000201 main Process ID: 2282 2025-01-22 10:15:36 (23052): Guest Log: 00:00:00.000202 main Package type: LINUX_64BITS_GENERIC 2025-01-22 10:15:36 (23052): Guest Log: 00:00:00.000737 main 5.2.6 r120293 started. Verbose level = 0 2025-01-22 10:15:36 (23052): Guest Log: [INFO] Mounting the shared directory 2025-01-22 10:15:36 (23052): Guest Log: [INFO] Shared directory mounted, enabling vboxmonitor 2025-01-22 10:15:36 (23052): Guest Log: [INFO] Sourcing essential functions from /cvmfs/grid.cern.ch 2025-01-22 10:15:36 (23052): Guest Log: [INFO] Testing connection to cern.ch 2025-01-22 10:15:36 (23052): Guest Log: [INFO] Testing connection to VCCS 2025-01-22 10:15:36 (23052): Guest Log: [INFO] Testing connection to HTCondor 2025-01-22 10:15:36 (23052): Guest Log: [INFO] Testing connection to WMAgent 2025-01-22 10:15:36 (23052): Guest Log: [INFO] Testing connection to EOSCMS 2025-01-22 10:15:36 (23052): Guest Log: [INFO] Testing connection to CMS-Factory 2025-01-22 10:15:36 (23052): Guest Log: [INFO] Testing connection to CMS-Frontier 2025-01-22 10:15:36 (23052): Guest Log: [INFO] Testing connection to Frontier 2025-01-22 10:15:36 (23052): Guest Log: [INFO] Could not find a local HTTP proxy 2025-01-22 10:15:36 (23052): Guest Log: [INFO] CVMFS and Frontier will have to use DIRECT connections 2025-01-22 10:15:36 (23052): Guest Log: [INFO] This makes the application less efficient 2025-01-22 10:15:36 (23052): Guest Log: [INFO] It also puts higher load on the project servers 2025-01-22 10:15:36 (23052): Guest Log: [INFO] Setting up a local HTTP proxy is highly recommended 2025-01-22 10:15:36 (23052): Guest Log: [INFO] Advice can be found in the project forum 2025-01-22 10:15:36 (23052): Guest Log: [INFO] Reloading and probing the CVMFS configuration 2025-01-22 10:15:36 (23052): Guest Log: [INFO] Probing /cvmfs/grid.cern.ch... OK 2025-01-22 10:15:36 (23052): Guest Log: [INFO] Probing /cvmfs/cms-ib.cern.ch... OK 2025-01-22 10:15:36 (23052): Guest Log: [INFO] Probing /cvmfs/cms.cern.ch... OK 2025-01-22 10:15:36 (23052): Guest Log: [INFO] Probing /cvmfs/singularity.opensciencegrid.org... OK 2025-01-22 10:15:36 (23052): Guest Log: [INFO] Probing /cvmfs/oasis.opensciencegrid.org... OK 2025-01-22 10:15:36 (23052): Guest Log: [INFO] Excerpt from "cvmfs_config stat": VERSION HOST PROXY 2025-01-22 10:15:36 (23052): Guest Log: [INFO] 2.7.2.0 http://s1fnal-cvmfs.openhtc.io:8080 DIRECT 2025-01-22 10:15:36 (23052): Guest Log: [INFO] Environment HTTP proxy: not set 2025-01-22 10:15:36 (23052): Guest Log: [INFO] Reading volunteer information 2025-01-22 10:15:36 (23052): Guest Log: [INFO] Requesting an X509 credential from LHC@home 2025-01-22 10:15:36 (23052): Guest Log: [INFO] Requesting an idtoken from LHC@home 2025-01-22 10:15:36 (23052): Guest Log: [INFO] CMS application starting. Check log files. 2025-01-22 10:15:36 (23052): Starting VM using VBoxManage interface. (boinc_ae611a5faba5c902, slot#0) 2025-01-22 10:15:54 (23052): Successfully started VM. (PID = '21668') 2025-01-22 10:15:54 (23052): Reporting VM Process ID to BOINC. 2025-01-22 10:15:54 (23052): VM state change detected. (old = 'poweredoff', new = 'running') 2025-01-22 10:15:54 (23052): Detected: Web Application Enabled (http://localhost:51557) 2025-01-22 10:15:54 (23052): Status Report: Job Duration: '64800.000000' 2025-01-22 10:15:54 (23052): Status Report: Elapsed Time: '33071.000000' 2025-01-22 10:15:54 (23052): Status Report: CPU Time: '37543.296875' 2025-01-22 10:15:54 (23052): Preference change detected 2025-01-22 10:15:54 (23052): Setting CPU throttle for VM. (25%) 2025-01-22 10:15:54 (23052): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds)) 2025-01-22 10:17:07 (23052): VM state change detected. (old = 'running', new = 'paused') 2025-01-22 10:20:25 (23052): VM state change detected. (old = 'paused', new = 'running') 2025-01-22 10:21:29 (23052): VM state change detected. (old = 'running', new = 'paused') 2025-01-22 10:21:49 (23052): VM state change detected. (old = 'paused', new = 'running') 2025-01-22 10:42:02 (23052): VM state change detected. (old = 'running', new = 'paused') 2025-01-22 10:42:22 (23052): VM state change detected. (old = 'paused', new = 'running') 2025-01-22 10:44:42 (23052): VM state change detected. (old = 'running', new = 'paused') 2025-01-22 10:45:02 (23052): VM state change detected. (old = 'paused', new = 'running') 2025-01-22 11:26:46 (23052): VM state change detected. (old = 'running', new = 'paused') 2025-01-22 11:27:06 (23052): VM state change detected. (old = 'paused', new = 'running') 2025-01-22 11:43:47 (23052): VM state change detected. (old = 'running', new = 'paused') 2025-01-22 11:44:07 (23052): VM state change detected. (old = 'paused', new = 'running') 2025-01-22 11:46:07 (23052): VM state change detected. (old = 'running', new = 'paused') 2025-01-22 11:46:27 (23052): VM state change detected. (old = 'paused', new = 'running') 2025-01-22 11:51:08 (23052): VM state change detected. (old = 'running', new = 'paused') 2025-01-22 11:51:28 (23052): VM state change detected. (old = 'paused', new = 'running') 2025-01-22 11:52:28 (23052): VM state change detected. (old = 'running', new = 'paused') 2025-01-22 11:52:48 (23052): VM state change detected. (old = 'paused', new = 'running') 2025-01-22 12:02:08 (23052): Status Report: Job Duration: '64800.000000' 2025-01-22 12:02:08 (23052): Status Report: Elapsed Time: '39071.000000' 2025-01-22 12:02:08 (23052): Status Report: CPU Time: '44558.687500' 2025-01-22 12:04:59 (23052): VM state change detected. (old = 'running', new = 'paused') 2025-01-22 12:05:19 (23052): VM state change detected. (old = 'paused', new = 'running') 2025-01-22 12:06:10 (23052): VM state change detected. (old = 'running', new = 'paused') 2025-01-22 12:06:30 (23052): VM state change detected. (old = 'paused', new = 'running') 2025-01-22 12:07:30 (23052): VM state change detected. (old = 'running', new = 'paused') 2025-01-22 12:07:50 (23052): VM state change detected. (old = 'paused', new = 'running') 2025-01-22 13:14:55 (23052): Stopping VM. 2025-01-22 13:15:10 (23052): Successfully stopped VM. 2025-01-22 14:15:41 (27940): vboxwrapper version 26208 2025-01-22 14:15:41 (27940): BOINC client version: 8.0.2 2025-01-22 14:15:42 (27940): Detected: VirtualBox VboxManage Interface (Version: 7.0.6) 2025-01-22 14:15:42 (27940): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds) 2025-01-22 14:15:42 (27940): Starting VM using VBoxManage interface. (boinc_ae611a5faba5c902, slot#0) 2025-01-22 14:15:59 (27940): Successfully started VM. (PID = '28508') 2025-01-22 14:15:59 (27940): Reporting VM Process ID to BOINC. 2025-01-22 14:15:59 (27940): VM state change detected. (old = 'poweredoff', new = 'running') 2025-01-22 14:15:59 (27940): Detected: Web Application Enabled (http://localhost:51557) 2025-01-22 14:15:59 (27940): Status Report: Job Duration: '64800.000000' 2025-01-22 14:15:59 (27940): Status Report: Elapsed Time: '43370.000000' 2025-01-22 14:15:59 (27940): Status Report: CPU Time: '49351.890625' 2025-01-22 14:15:59 (27940): Preference change detected 2025-01-22 14:15:59 (27940): Setting CPU throttle for VM. (25%) 2025-01-22 14:15:59 (27940): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds)) 2025-01-22 14:16:03 (27940): Guest Log: 02:55:27.668404 timesync vgsvcTimeSyncWorker: Radical host time change: 3 672 417 000 000ns (HostNow=1 737 555 363 031 000 000 ns HostLast=1 737 551 690 614 000 000 ns) 2025-01-22 14:16:13 (27940): Guest Log: 02:55:37.716562 timesync vgsvcTimeSyncWorker: Radical guest time change: 4 086 252 465 000ns (GuestNow=1 737 555 373 083 847 000 ns GuestLast=1 737 551 286 831 382 000 ns fSetTimeLastLoop=true ) 2025-01-22 14:16:53 (27940): VM state change detected. (old = 'running', new = 'paused') 2025-01-22 14:17:13 (27940): VM state change detected. (old = 'paused', new = 'running') 2025-01-22 15:12:18 (27940): VM state change detected. (old = 'running', new = 'paused') 2025-01-22 15:12:38 (27940): VM state change detected. (old = 'paused', new = 'running') 2025-01-22 15:18:48 (27940): VM state change detected. (old = 'running', new = 'paused') 2025-01-22 15:19:08 (27940): VM state change detected. (old = 'paused', new = 'running') 2025-01-22 15:24:00 (27940): VM state change detected. (old = 'running', new = 'paused') 2025-01-22 15:24:20 (27940): VM state change detected. (old = 'paused', new = 'running') 2025-01-22 15:41:21 (27940): VM state change detected. (old = 'running', new = 'paused') 2025-01-22 15:41:41 (27940): VM state change detected. (old = 'paused', new = 'running') 2025-01-22 15:46:51 (27940): VM state change detected. (old = 'running', new = 'paused') 2025-01-22 15:47:11 (27940): VM state change detected. (old = 'paused', new = 'running') 2025-01-22 15:58:12 (27940): Status Report: Job Duration: '64800.000000' 2025-01-22 15:58:12 (27940): Status Report: Elapsed Time: '49370.000000' 2025-01-22 15:58:12 (27940): Status Report: CPU Time: '56287.109375' 2025-01-22 16:52:08 (27940): Stopping VM. 2025-01-22 16:52:22 (27940): Successfully stopped VM. 2025-01-22 17:34:26 (19772): vboxwrapper version 26208 2025-01-22 17:34:26 (19772): BOINC client version: 8.0.2 2025-01-22 17:34:27 (19772): Detected: VirtualBox VboxManage Interface (Version: 7.0.6) 2025-01-22 17:34:27 (19772): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds) 2025-01-22 17:34:27 (19772): Guest Log: 02:55:27.668404 timesync vgsvcTimeSyncWorker: Radical host time change: 3 672 417 000 000ns (HostNow=1 737 555 363 031 000 000 ns HostLast=1 737 551 690 614 000 000 ns) 2025-01-22 17:34:27 (19772): Guest Log: 02:55:37.716562 timesync vgsvcTimeSyncWorker: Radical guest time change: 4 086 252 465 000ns (GuestNow=1 737 555 373 083 847 000 ns GuestLast=1 737 551 286 831 382 000 ns fSetTimeLastLoop=true ) 2025-01-22 17:34:27 (19772): Starting VM using VBoxManage interface. (boinc_ae611a5faba5c902, slot#0) 2025-01-22 17:34:45 (19772): Successfully started VM. (PID = '24072') 2025-01-22 17:34:45 (19772): Reporting VM Process ID to BOINC. 2025-01-22 17:34:45 (19772): VM state change detected. (old = 'poweredoff', new = 'running') 2025-01-22 17:34:45 (19772): Detected: Web Application Enabled (http://localhost:51557) 2025-01-22 17:34:45 (19772): Status Report: Job Duration: '64800.000000' 2025-01-22 17:34:45 (19772): Status Report: Elapsed Time: '52602.000000' 2025-01-22 17:34:45 (19772): Status Report: CPU Time: '60119.656250' 2025-01-22 17:34:45 (19772): Preference change detected 2025-01-22 17:34:45 (19772): Setting CPU throttle for VM. (25%) 2025-01-22 17:34:45 (19772): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds)) 2025-01-22 17:34:50 (19772): Guest Log: 05:29:43.368546 timesync vgsvcTimeSyncWorker: Radical host time change: 2 565 552 000 000ns (HostNow=1 737 567 289 673 000 000 ns HostLast=1 737 564 724 121 000 000 ns) 2025-01-22 17:35:00 (19772): Guest Log: 05:29:53.403517 timesync vgsvcTimeSyncWorker: Radical guest time change: 2 681 184 223 000ns (GuestNow=1 737 567 299 814 031 000 ns GuestLast=1 737 564 618 629 808 000 ns fSetTimeLastLoop=true ) 2025-01-22 19:14:51 (19772): Status Report: Job Duration: '64800.000000' 2025-01-22 19:14:51 (19772): Status Report: Elapsed Time: '58602.000000' 2025-01-22 19:14:51 (19772): Status Report: CPU Time: '66736.531250' 2025-01-22 20:09:26 (19772): Stopping VM. 2025-01-22 20:09:39 (19772): Successfully stopped VM. 2025-01-23 20:58:09 (18572): vboxwrapper version 26208 2025-01-23 20:58:09 (18572): BOINC client version: 8.0.2 2025-01-23 20:58:10 (18572): Detected: VirtualBox VboxManage Interface (Version: 7.0.6) 2025-01-23 20:58:10 (18572): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds) 2025-01-23 20:58:11 (18572): Guest Log: 05:29:43.368546 timesync vgsvcTimeSyncWorker: Radical host time change: 2 565 552 000 000ns (HostNow=1 737 567 289 673 000 000 ns HostLast=1 737 564 724 121 000 000 ns) 2025-01-23 20:58:11 (18572): Guest Log: 05:29:53.403517 timesync vgsvcTimeSyncWorker: Radical guest time change: 2 681 184 223 000ns (GuestNow=1 737 567 299 814 031 000 ns GuestLast=1 737 564 618 629 808 000 ns fSetTimeLastLoop=true ) 2025-01-23 20:58:11 (18572): Starting VM using VBoxManage interface. (boinc_ae611a5faba5c902, slot#0) 2025-01-23 20:58:28 (18572): Successfully started VM. (PID = '8728') 2025-01-23 20:58:28 (18572): Reporting VM Process ID to BOINC. 2025-01-23 20:58:28 (18572): VM state change detected. (old = 'poweredoff', new = 'running') 2025-01-23 20:58:28 (18572): Detected: Web Application Enabled (http://localhost:51557) 2025-01-23 20:58:28 (18572): Status Report: Job Duration: '64800.000000' 2025-01-23 20:58:28 (18572): Status Report: Elapsed Time: '61874.000000' 2025-01-23 20:58:28 (18572): Status Report: CPU Time: '70680.765625' 2025-01-23 20:58:28 (18572): Preference change detected 2025-01-23 20:58:28 (18572): Setting CPU throttle for VM. (25%) 2025-01-23 20:58:29 (18572): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds)) 2025-01-23 20:58:30 (18572): Guest Log: 08:04:23.124440 timesync vgsvcTimeSyncWorker: Radical host time change: 89 351 219 000 000ns (HostNow=1 737 665 910 739 000 000 ns HostLast=1 737 576 559 520 000 000 ns) 2025-01-23 20:58:40 (18572): Guest Log: 08:04:33.134177 timesync vgsvcTimeSyncWorker: Radical guest time change: 89 351 213 931 000ns (GuestNow=1 737 665 920 748 805 000 ns GuestLast=1 737 576 569 534 874 000 ns fSetTimeLastLoop=true ) 2025-01-23 21:00:20 (18572): VM state change detected. (old = 'running', new = 'paused') 2025-01-24 09:11:43 (9760): vboxwrapper version 26208 2025-01-24 09:11:43 (9760): BOINC client version: 8.0.2 2025-01-24 09:11:44 (9760): Detected: VirtualBox VboxManage Interface (Version: 7.0.6) 2025-01-24 09:11:44 (9760): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds) 2025-01-24 09:11:45 (9760): Guest Log: 08:04:23.124440 timesync vgsvcTimeSyncWorker: Radical host time change: 89 351 219 000 000ns (HostNow=1 737 665 910 739 000 000 ns HostLast=1 737 576 559 520 000 000 ns) 2025-01-24 09:11:45 (9760): Guest Log: 08:04:33.134177 timesync vgsvcTimeSyncWorker: Radical guest time change: 89 351 213 931 000ns (GuestNow=1 737 665 920 748 805 000 ns GuestLast=1 737 576 569 534 874 000 ns fSetTimeLastLoop=true ) 2025-01-24 09:11:45 (9760): Starting VM using VBoxManage interface. (boinc_ae611a5faba5c902, slot#0) 2025-01-24 09:11:51 (9760): Successfully started VM. (PID = '21540') 2025-01-24 09:11:51 (9760): Reporting VM Process ID to BOINC. 2025-01-24 09:11:51 (9760): Guest Log: BIOS: VirtualBox 7.0.6 2025-01-24 09:11:51 (9760): Guest Log: CPUID EDX: 0x178bfbff 2025-01-24 09:11:51 (9760): Guest Log: BIOS: No PCI IDE controller, not probing IDE 2025-01-24 09:11:51 (9760): Guest Log: BIOS: AHCI 0-P#0: PCHS=16383/16/63 LCHS=1024/255/63 0x0000000002800000 sectors 2025-01-24 09:11:51 (9760): VM state change detected. (old = 'poweredoff', new = 'running') 2025-01-24 09:11:51 (9760): Detected: Web Application Enabled (http://localhost:51557) 2025-01-24 09:11:51 (9760): Status Report: Job Duration: '64800.000000' 2025-01-24 09:11:51 (9760): Status Report: Elapsed Time: '61976.000000' 2025-01-24 09:11:51 (9760): Status Report: CPU Time: '70701.015625' 2025-01-24 09:11:51 (9760): Preference change detected 2025-01-24 09:11:51 (9760): Setting CPU throttle for VM. (25%) 2025-01-24 09:11:51 (9760): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds)) 2025-01-24 09:11:53 (9760): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032 2025-01-24 09:11:53 (9760): Guest Log: BIOS: Booting from Hard Disk... 2025-01-24 09:11:55 (9760): Guest Log: BIOS: KBD: unsupported int 16h function 03 2025-01-24 09:11:55 (9760): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 2025-01-24 09:12:26 (9760): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds 2025-01-24 09:12:26 (9760): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000) 2025-01-24 09:12:27 (9760): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log 2025-01-24 09:12:27 (9760): Guest Log: 00:00:00.000096 main Log opened 2025-01-24T09:12:26.656986000Z 2025-01-24 09:12:27 (9760): Guest Log: 00:00:00.000165 main OS Product: Linux 2025-01-24 09:12:27 (9760): Guest Log: 00:00:00.000184 main OS Release: 4.14.232-19.cernvm.x86_64 2025-01-24 09:12:27 (9760): Guest Log: 00:00:00.000201 main OS Version: #1 SMP Fri Apr 30 17:12:25 CEST 2021 2025-01-24 09:12:27 (9760): Guest Log: 00:00:00.000217 main Executable: /usr/sbin/VBoxService 2025-01-24 09:12:27 (9760): Guest Log: 00:00:00.000217 main Process ID: 2284 2025-01-24 09:12:27 (9760): Guest Log: 00:00:00.000218 main Package type: LINUX_64BITS_GENERIC 2025-01-24 09:12:27 (9760): Guest Log: 00:00:00.001339 main 5.2.6 r120293 started. Verbose level = 0 2025-01-24 09:12:36 (9760): Guest Log: [INFO] Mounting the shared directory 2025-01-24 09:12:36 (9760): Guest Log: [INFO] Shared directory mounted, enabling vboxmonitor 2025-01-24 09:12:36 (9760): Guest Log: [INFO] Sourcing essential functions from /cvmfs/grid.cern.ch 2025-01-24 09:12:36 (9760): Guest Log: [INFO] Testing connection to cern.ch 2025-01-24 09:12:36 (9760): Guest Log: [INFO] Testing connection to VCCS 2025-01-24 09:12:36 (9760): Guest Log: [INFO] Testing connection to HTCondor 2025-01-24 09:12:37 (9760): Guest Log: [INFO] Testing connection to WMAgent 2025-01-24 09:12:37 (9760): Guest Log: [INFO] Testing connection to EOSCMS 2025-01-24 09:12:37 (9760): Guest Log: [INFO] Testing connection to CMS-Factory 2025-01-24 09:12:37 (9760): Guest Log: [INFO] Testing connection to CMS-Frontier 2025-01-24 09:12:37 (9760): Guest Log: [INFO] Testing connection to Frontier 2025-01-24 09:12:37 (9760): Guest Log: [INFO] Could not find a local HTTP proxy 2025-01-24 09:12:38 (9760): Guest Log: [INFO] CVMFS and Frontier will have to use DIRECT connections 2025-01-24 09:12:38 (9760): Guest Log: [INFO] This makes the application less efficient 2025-01-24 09:12:38 (9760): Guest Log: [INFO] It also puts higher load on the project servers 2025-01-24 09:12:38 (9760): Guest Log: [INFO] Setting up a local HTTP proxy is highly recommended 2025-01-24 09:12:38 (9760): Guest Log: [INFO] Advice can be found in the project forum 2025-01-24 09:12:38 (9760): Guest Log: [INFO] Reloading and probing the CVMFS configuration 2025-01-24 09:12:42 (9760): Guest Log: [INFO] Probing /cvmfs/grid.cern.ch... OK 2025-01-24 09:12:44 (9760): Guest Log: [INFO] Probing /cvmfs/oasis.opensciencegrid.org... OK 2025-01-24 09:12:44 (9760): Guest Log: [INFO] Probing /cvmfs/cms-ib.cern.ch... OK 2025-01-24 09:12:45 (9760): Guest Log: [INFO] Probing /cvmfs/cms.cern.ch... OK 2025-01-24 09:12:45 (9760): Guest Log: [INFO] Probing /cvmfs/singularity.opensciencegrid.org... OK 2025-01-24 09:12:45 (9760): Guest Log: [INFO] Excerpt from "cvmfs_config stat": VERSION HOST PROXY 2025-01-24 09:12:45 (9760): Guest Log: [INFO] 2.7.2.0 http://s1fnal-cvmfs.openhtc.io:8080 DIRECT 2025-01-24 09:12:45 (9760): Guest Log: [INFO] Environment HTTP proxy: not set 2025-01-24 09:12:45 (9760): Guest Log: [INFO] Reading volunteer information 2025-01-24 09:12:49 (9760): Guest Log: [INFO] Requesting an X509 credential from LHC@home 2025-01-24 09:12:50 (9760): Guest Log: [INFO] Requesting an idtoken from LHC@home 2025-01-24 09:12:50 (9760): Guest Log: [INFO] CMS application starting. Check log files. 2025-01-24 09:26:46 (9760): VM state change detected. (old = 'running', new = 'paused') 2025-01-24 09:27:06 (9760): VM state change detected. (old = 'paused', new = 'running') 2025-01-24 09:42:05 (9760): VM state change detected. (old = 'running', new = 'paused') 2025-01-24 14:41:38 (9760): VM state change detected. (old = 'paused', new = 'running') 2025-01-24 14:41:44 (9760): VM state change detected. (old = 'running', new = 'paused') 2025-01-24 14:42:04 (9760): VM state change detected. (old = 'paused', new = 'running') 2025-01-24 14:42:05 (9760): Guest Log: 00:29:26.005843 timesync vgsvcTimeSyncWorker: Radical host time change: 18 003 687 000 000ns (HostNow=1 737 729 725 865 000 000 ns HostLast=1 737 711 722 178 000 000 ns) 2025-01-24 14:42:15 (9760): Guest Log: 00:29:36.006510 timesync vgsvcTimeSyncWorker: Radical guest time change: 18 023 266 786 000ns (GuestNow=1 737 729 735 897 576 000 ns GuestLast=1 737 711 712 630 790 000 ns fSetTimeLastLoop=true ) 2025-01-24 14:43:05 (9760): VM state change detected. (old = 'running', new = 'paused') 2025-01-24 14:43:25 (9760): VM state change detected. (old = 'paused', new = 'running') 2025-01-24 14:45:55 (9760): VM state change detected. (old = 'running', new = 'paused') 2025-01-24 14:46:15 (9760): VM state change detected. (old = 'paused', new = 'running') 2025-01-24 14:59:57 (9760): Powering off VM. 2025-01-24 14:59:58 (9760): Successfully stopped VM. 2025-01-24 14:59:58 (9760): Deregistering VM. (boinc_ae611a5faba5c902, slot#0) 2025-01-24 14:59:58 (9760): Removing network bandwidth throttle group from VM. 2025-01-24 14:59:58 (9760): Removing VM from VirtualBox. 2025-01-24 15:00:04 (9760): called boinc_finish(0) </stderr_txt> ]]>
©2025 CERN