| Name | CMS_3056641_1752140460.206793_0 |
| Workunit | 233562804 |
| Created | 10 Jul 2025, 9:41:04 UTC |
| Sent | 10 Jul 2025, 11:27:10 UTC |
| Report deadline | 10 Aug 2025, 11:27:10 UTC |
| Received | 16 Jul 2025, 15:41:11 UTC |
| Server state | Over |
| Outcome | Success |
| Client state | Done |
| Exit status | 0 (0x00000000) |
| Computer ID | 10844504 |
| Run time | 17 hours 52 min 5 sec |
| CPU time | 2 days 9 hours 55 min 37 sec |
| Validate state | Valid |
| Credit | 1,731.89 |
| Device peak FLOPS | 13.52 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.40 GB |
| Peak disk usage | 3.26 GB |
Stderr output
<core_client_version>8.2.4</core_client_version> <![CDATA[ <stderr_txt> 0): Guest Log: [INFO] Advice can be found in the project forum 2025-07-10 19:03:47 (4250): Guest Log: [INFO] Reloading and probing the CVMFS configuration 2025-07-10 19:03:47 (4250): Guest Log: [INFO] Probing /cvmfs/grid.cern.ch... OK 2025-07-10 19:03:47 (4250): Guest Log: [INFO] Probing /cvmfs/cms.cern.ch... OK 2025-07-10 19:03:47 (4250): Guest Log: [INFO] Probing /cvmfs/cms-ib.cern.ch... OK 2025-07-10 19:03:47 (4250): Guest Log: [INFO] Probing /cvmfs/oasis.opensciencegrid.org... OK 2025-07-10 19:03:47 (4250): Guest Log: [INFO] Probing /cvmfs/singularity.opensciencegrid.org... OK 2025-07-10 19:03:47 (4250): Guest Log: [INFO] Excerpt from "cvmfs_config stat": VERSION HOST PROXY 2025-07-10 19:03:47 (4250): Guest Log: [INFO] 2.7.2.0 http://s1cern-cvmfs.openhtc.io DIRECT 2025-07-10 19:03:47 (4250): Guest Log: [INFO] Environment HTTP proxy: not set 2025-07-10 19:03:47 (4250): Guest Log: [INFO] Reading volunteer information 2025-07-10 19:03:47 (4250): Guest Log: [INFO] Requesting an X509 credential from LHC@home 2025-07-10 19:03:47 (4250): Guest Log: [INFO] Requesting an idtoken from LHC@home 2025-07-10 19:03:47 (4250): Guest Log: [INFO] CMS application starting. Check log files. 2025-07-10 19:03:47 (4250): Starting VM using VBoxManage interface. (boinc_47b40863cd814ce0, slot#15) 2025-07-10 19:03:49 (4250): Successfully started VM. (PID = '4660') 2025-07-10 19:03:49 (4250): Reporting VM Process ID to BOINC. 2025-07-10 19:03:49 (4250): Guest Log: BIOS: VirtualBox 7.0.18 2025-07-10 19:03:49 (4250): Guest Log: CPUID EDX: 0x178bfbff 2025-07-10 19:03:49 (4250): Guest Log: BIOS: No PCI IDE controller, not probing IDE 2025-07-10 19:03:49 (4250): Guest Log: BIOS: AHCI 0-P#0: PCHS=16383/16/63 LCHS=1024/255/63 0x0000000002800000 sectors 2025-07-10 19:03:49 (4250): VM state change detected. (old = 'poweredoff', new = 'running') 2025-07-10 19:03:49 (4250): Detected: Web Application Enabled (http://localhost:43293) 2025-07-10 19:03:49 (4250): Detected: Remote Desktop Enabled (localhost:48153) 2025-07-10 19:03:49 (4250): Status Report: Job Duration: '64800.000000' 2025-07-10 19:03:49 (4250): Status Report: Elapsed Time: '17690.000000' 2025-07-10 19:03:49 (4250): Status Report: CPU Time: '50875.420000' 2025-07-10 19:03:49 (4250): Preference change detected 2025-07-10 19:03:49 (4250): Setting CPU throttle for VM. (100%) 2025-07-10 19:03:49 (4250): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds)) 2025-07-10 19:03:51 (4250): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032 2025-07-10 19:03:51 (4250): Guest Log: BIOS: Booting from Hard Disk... 2025-07-10 19:04:00 (4250): Guest Log: BIOS: KBD: unsupported int 16h function 03 2025-07-10 19:04:00 (4250): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 2025-07-10 19:04:25 (4250): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds 2025-07-10 19:04:25 (4250): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000) 2025-07-10 19:04:26 (4250): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log 2025-07-10 19:04:26 (4250): Guest Log: 00:00:00.000079 main Log opened 2025-07-10T17:04:25.759392000Z 2025-07-10 19:04:26 (4250): Guest Log: 00:00:00.000172 main OS Product: Linux 2025-07-10 19:04:26 (4250): Guest Log: 00:00:00.000189 main OS Release: 4.14.232-19.cernvm.x86_64 2025-07-10 19:04:26 (4250): Guest Log: 00:00:00.000201 main OS Version: #1 SMP Fri Apr 30 17:12:25 CEST 2021 2025-07-10 19:04:26 (4250): Guest Log: 00:00:00.000214 main Executable: /usr/sbin/VBoxService 2025-07-10 19:04:26 (4250): Guest Log: 00:00:00.000215 main Process ID: 2276 2025-07-10 19:04:26 (4250): Guest Log: 00:00:00.000215 main Package type: LINUX_64BITS_GENERIC 2025-07-10 19:04:26 (4250): Guest Log: 00:00:00.001056 main 5.2.6 r120293 started. Verbose level = 0 2025-07-10 19:04:38 (4250): Guest Log: [INFO] Mounting the shared directory 2025-07-10 19:04:38 (4250): Guest Log: [INFO] Shared directory mounted, enabling vboxmonitor 2025-07-10 19:04:38 (4250): Guest Log: [INFO] Sourcing essential functions from /cvmfs/grid.cern.ch 2025-07-10 19:04:38 (4250): Guest Log: [INFO] Testing connection to cern.ch 2025-07-10 19:04:39 (4250): Guest Log: [INFO] Testing connection to VCCS 2025-07-10 19:04:39 (4250): Guest Log: [INFO] Testing connection to HTCondor 2025-07-10 19:04:39 (4250): Guest Log: [INFO] Testing connection to WMAgent 2025-07-10 19:04:39 (4250): Guest Log: [INFO] Testing connection to EOSCMS 2025-07-10 19:04:39 (4250): Guest Log: [INFO] Testing connection to CMS-Factory 2025-07-10 19:04:40 (4250): Guest Log: [INFO] Testing connection to CMS-Frontier 2025-07-10 19:04:40 (4250): Guest Log: [INFO] Testing connection to Frontier 2025-07-10 19:04:41 (4250): Guest Log: [INFO] Testing connection to http://cms-frontier.openhtc.io:8080/FrontierProd/Frontier/ 2025-07-10 19:04:41 (4250): Guest Log: [INFO] Could not find a local HTTP proxy 2025-07-10 19:04:41 (4250): Guest Log: [INFO] CVMFS and Frontier will have to use DIRECT connections 2025-07-10 19:04:42 (4250): Guest Log: [INFO] This makes the application less efficient 2025-07-10 19:04:42 (4250): Guest Log: [INFO] It also puts higher load on the project servers 2025-07-10 19:04:42 (4250): Guest Log: [INFO] Setting up a local HTTP proxy is highly recommended 2025-07-10 19:04:42 (4250): Guest Log: [INFO] Advice can be found in the project forum 2025-07-10 19:04:42 (4250): Guest Log: [INFO] Reloading and probing the CVMFS configuration 2025-07-10 19:04:49 (4250): Guest Log: [INFO] Probing /cvmfs/grid.cern.ch... OK 2025-07-10 19:04:51 (4250): Guest Log: [INFO] Probing /cvmfs/oasis.opensciencegrid.org... OK 2025-07-10 19:04:51 (4250): Guest Log: [INFO] Probing /cvmfs/cms-ib.cern.ch... OK 2025-07-10 19:04:51 (4250): Guest Log: [INFO] Probing /cvmfs/cms.cern.ch... OK 2025-07-10 19:04:51 (4250): Guest Log: [INFO] Probing /cvmfs/singularity.opensciencegrid.org... OK 2025-07-10 19:04:52 (4250): Guest Log: [INFO] Excerpt from "cvmfs_config stat": VERSION HOST PROXY 2025-07-10 19:04:52 (4250): Guest Log: [INFO] 2.7.2.0 http://s1fnal-cvmfs.openhtc.io:8080 DIRECT 2025-07-10 19:04:52 (4250): Guest Log: [INFO] Environment HTTP proxy: not set 2025-07-10 19:04:52 (4250): Guest Log: [INFO] Reading volunteer information 2025-07-10 19:04:58 (4250): Guest Log: [INFO] Requesting an X509 credential from LHC@home 2025-07-10 19:04:59 (4250): Guest Log: [INFO] Requesting an idtoken from LHC@home 2025-07-10 19:05:00 (4250): Guest Log: [INFO] CMS application starting. Check log files. 2025-07-10 19:12:57 (4250): Preference change detected 2025-07-10 19:12:57 (4250): Setting CPU throttle for VM. (100%) 2025-07-10 19:12:57 (4250): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds)) 2025-07-10 19:13:03 (4250): Preference change detected 2025-07-10 19:13:03 (4250): Setting CPU throttle for VM. (100%) 2025-07-10 19:13:03 (4250): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds)) 2025-07-10 19:13:12 (4250): Preference change detected 2025-07-10 19:13:12 (4250): Setting CPU throttle for VM. (100%) 2025-07-10 19:13:12 (4250): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds)) 2025-07-10 19:15:15 (4250): VM state change detected. (old = 'running', new = 'paused') 2025-07-10 19:15:36 (4250): VM state change detected. (old = 'paused', new = 'running') 2025-07-10 19:17:36 (4250): VM state change detected. (old = 'running', new = 'paused') 2025-07-10 19:17:56 (4250): VM state change detected. (old = 'paused', new = 'running') 2025-07-10 19:23:38 (4250): VM state change detected. (old = 'running', new = 'paused') 2025-07-10 19:23:58 (4250): VM state change detected. (old = 'paused', new = 'running') 2025-07-10 19:28:59 (4250): VM state change detected. (old = 'running', new = 'paused') 2025-07-10 19:29:19 (4250): VM state change detected. (old = 'paused', new = 'running') 2025-07-10 19:33:01 (4250): VM state change detected. (old = 'running', new = 'paused') 2025-07-10 19:33:21 (4250): VM state change detected. (old = 'paused', new = 'running') 2025-07-10 19:37:33 (4250): VM state change detected. (old = 'running', new = 'paused') 2025-07-10 19:37:53 (4250): VM state change detected. (old = 'paused', new = 'running') 2025-07-10 19:42:14 (4250): VM state change detected. (old = 'running', new = 'paused') 2025-07-10 19:42:34 (4250): VM state change detected. (old = 'paused', new = 'running') 2025-07-10 19:42:55 (4250): VM state change detected. (old = 'running', new = 'paused') 2025-07-10 19:43:15 (4250): VM state change detected. (old = 'paused', new = 'running') 2025-07-10 20:02:32 (4250): VM state change detected. (old = 'running', new = 'paused') 2025-07-10 20:02:52 (4250): VM state change detected. (old = 'paused', new = 'running') 2025-07-10 20:05:13 (4250): VM state change detected. (old = 'running', new = 'paused') 2025-07-10 20:05:33 (4250): VM state change detected. (old = 'paused', new = 'running') 2025-07-10 20:05:34 (4250): VM state change detected. (old = 'running', new = 'paused') 2025-07-10 20:09:23 (4250): VM state change detected. (old = 'paused', new = 'running') 2025-07-10 20:09:24 (4250): VM state change detected. (old = 'running', new = 'paused') 2025-07-10 20:09:44 (4250): VM state change detected. (old = 'paused', new = 'running') 2025-07-10 20:10:23 (4250): VM state change detected. (old = 'running', new = 'paused') 2025-07-10 20:10:44 (4250): VM state change detected. (old = 'paused', new = 'running') 2025-07-10 20:17:57 (4250): VM state change detected. (old = 'running', new = 'paused') 2025-07-10 20:18:17 (4250): VM state change detected. (old = 'paused', new = 'running') 2025-07-10 20:20:28 (4250): VM state change detected. (old = 'running', new = 'paused') 2025-07-10 20:20:49 (4250): VM state change detected. (old = 'paused', new = 'running') 2025-07-10 20:26:19 (4250): VM state change detected. (old = 'running', new = 'paused') 2025-07-10 20:26:39 (4250): VM state change detected. (old = 'paused', new = 'running') 2025-07-10 20:26:40 (4250): VM state change detected. (old = 'running', new = 'paused') 2025-07-10 20:29:15 (4250): VM state change detected. (old = 'paused', new = 'running') 2025-07-10 20:29:20 (4250): VM state change detected. (old = 'running', new = 'paused') 2025-07-10 20:29:40 (4250): VM state change detected. (old = 'paused', new = 'running') 2025-07-10 20:31:11 (4250): VM state change detected. (old = 'running', new = 'paused') 2025-07-10 20:31:31 (4250): VM state change detected. (old = 'paused', new = 'running') 2025-07-10 20:33:01 (4250): VM state change detected. (old = 'running', new = 'paused') 2025-07-10 20:33:21 (4250): VM state change detected. (old = 'paused', new = 'running') 2025-07-10 20:34:11 (4250): VM state change detected. (old = 'running', new = 'paused') 2025-07-10 20:34:31 (4250): VM state change detected. (old = 'paused', new = 'running') 2025-07-10 20:36:31 (4250): VM state change detected. (old = 'running', new = 'paused') 2025-07-10 20:36:51 (4250): VM state change detected. (old = 'paused', new = 'running') 2025-07-10 20:36:52 (4250): VM state change detected. (old = 'running', new = 'paused') 2025-07-11 14:15:12 (159216): vboxwrapper version 26210 2025-07-11 14:15:12 (159216): BOINC client version: 8.2.4 2025-07-11 14:15:12 (159216): Detected: VirtualBox VboxManage Interface (Version: 7.0.18) 2025-07-11 14:15:12 (159216): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds) 2025-07-11 14:15:13 (159216): Register VM. (boinc_47b40863cd814ce0, slot#15) 2025-07-11 14:15:14 (159216): Guest Log: BIOS: VirtualBox 7.0.18 2025-07-11 14:15:14 (159216): Guest Log: CPUID EDX: 0x178bfbff 2025-07-11 14:15:14 (159216): Guest Log: BIOS: No PCI IDE controller, not probing IDE 2025-07-11 14:15:14 (159216): Guest Log: BIOS: AHCI 0-P#0: PCHS=16383/16/63 LCHS=1024/255/63 0x0000000002800000 sectors 2025-07-11 14:15:14 (159216): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032 2025-07-11 14:15:14 (159216): Guest Log: BIOS: Booting from Hard Disk... 2025-07-11 14:15:14 (159216): Guest Log: BIOS: KBD: unsupported int 16h function 03 2025-07-11 14:15:14 (159216): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 2025-07-11 14:15:14 (159216): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds 2025-07-11 14:15:14 (159216): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000) 2025-07-11 14:15:14 (159216): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log 2025-07-11 14:15:14 (159216): Guest Log: 00:00:00.000079 main Log opened 2025-07-10T17:04:25.759392000Z 2025-07-11 14:15:14 (159216): Guest Log: 00:00:00.000172 main OS Product: Linux 2025-07-11 14:15:14 (159216): Guest Log: 00:00:00.000189 main OS Release: 4.14.232-19.cernvm.x86_64 2025-07-11 14:15:14 (159216): Guest Log: 00:00:00.000201 main OS Version: #1 SMP Fri Apr 30 17:12:25 CEST 2021 2025-07-11 14:15:14 (159216): Guest Log: 00:00:00.000214 main Executable: /usr/sbin/VBoxService 2025-07-11 14:15:14 (159216): Guest Log: 00:00:00.000215 main Process ID: 2276 2025-07-11 14:15:14 (159216): Guest Log: 00:00:00.000215 main Package type: LINUX_64BITS_GENERIC 2025-07-11 14:15:14 (159216): Guest Log: 00:00:00.001056 main 5.2.6 r120293 started. Verbose level = 0 2025-07-11 14:15:14 (159216): Guest Log: [INFO] Mounting the shared directory 2025-07-11 14:15:14 (159216): Guest Log: [INFO] Shared directory mounted, enabling vboxmonitor 2025-07-11 14:15:14 (159216): Guest Log: [INFO] Sourcing essential functions from /cvmfs/grid.cern.ch 2025-07-11 14:15:14 (159216): Guest Log: [INFO] Testing connection to cern.ch 2025-07-11 14:15:14 (159216): Guest Log: [INFO] Testing connection to VCCS 2025-07-11 14:15:14 (159216): Guest Log: [INFO] Testing connection to HTCondor 2025-07-11 14:15:14 (159216): Guest Log: [INFO] Testing connection to WMAgent 2025-07-11 14:15:14 (159216): Guest Log: [INFO] Testing connection to EOSCMS 2025-07-11 14:15:14 (159216): Guest Log: [INFO] Testing connection to CMS-Factory 2025-07-11 14:15:14 (159216): Guest Log: [INFO] Testing connection to CMS-Frontier 2025-07-11 14:15:14 (159216): Guest Log: [INFO] Testing connection to Frontier 2025-07-11 14:15:14 (159216): Guest Log: [INFO] Testing connection to http://cms-frontier.openhtc.io:8080/FrontierProd/Frontier/ 2025-07-11 14:15:14 (159216): Guest Log: [INFO] Could not find a local HTTP proxy 2025-07-11 14:15:14 (159216): Guest Log: [INFO] CVMFS and Frontier will have to use DIRECT connections 2025-07-11 14:15:14 (159216): Guest Log: [INFO] This makes the application less efficient 2025-07-11 14:15:14 (159216): Guest Log: [INFO] It also puts higher load on the project servers 2025-07-11 14:15:14 (159216): Guest Log: [INFO] Setting up a local HTTP proxy is highly recommended 2025-07-11 14:15:14 (159216): Guest Log: [INFO] Advice can be found in the project forum 2025-07-11 14:15:14 (159216): Guest Log: [INFO] Reloading and probing the CVMFS configuration 2025-07-11 14:15:14 (159216): Guest Log: [INFO] Probing /cvmfs/grid.cern.ch... OK 2025-07-11 14:15:14 (159216): Guest Log: [INFO] Probing /cvmfs/oasis.opensciencegrid.org... OK 2025-07-11 14:15:14 (159216): Guest Log: [INFO] Probing /cvmfs/cms-ib.cern.ch... OK 2025-07-11 14:15:14 (159216): Guest Log: [INFO] Probing /cvmfs/cms.cern.ch... OK 2025-07-11 14:15:14 (159216): Guest Log: [INFO] Probing /cvmfs/singularity.opensciencegrid.org... OK 2025-07-11 14:15:14 (159216): Guest Log: [INFO] Excerpt from "cvmfs_config stat": VERSION HOST PROXY 2025-07-11 14:15:14 (159216): Guest Log: [INFO] 2.7.2.0 http://s1fnal-cvmfs.openhtc.io:8080 DIRECT 2025-07-11 14:15:14 (159216): Guest Log: [INFO] Environment HTTP proxy: not set 2025-07-11 14:15:14 (159216): Guest Log: [INFO] Reading volunteer information 2025-07-11 14:15:14 (159216): Guest Log: [INFO] Requesting an X509 credential from LHC@home 2025-07-11 14:15:14 (159216): Guest Log: [INFO] Requesting an idtoken from LHC@home 2025-07-11 14:15:14 (159216): Guest Log: [INFO] CMS application starting. Check log files. 2025-07-11 14:15:14 (159216): Starting VM using VBoxManage interface. (boinc_47b40863cd814ce0, slot#15) 2025-07-11 14:15:16 (159216): Successfully started VM. (PID = '159341') 2025-07-11 14:15:16 (159216): Reporting VM Process ID to BOINC. 2025-07-11 14:15:16 (159216): Guest Log: BIOS: VirtualBox 7.0.18 2025-07-11 14:15:16 (159216): Guest Log: CPUID EDX: 0x178bfbff 2025-07-11 14:15:16 (159216): Guest Log: BIOS: No PCI IDE controller, not probing IDE 2025-07-11 14:15:16 (159216): Guest Log: BIOS: AHCI 0-P#0: PCHS=16383/16/63 LCHS=1024/255/63 0x0000000002800000 sectors 2025-07-11 14:15:16 (159216): VM state change detected. (old = 'poweredoff', new = 'running') 2025-07-11 14:15:16 (159216): Detected: Web Application Enabled (http://localhost:43293) 2025-07-11 14:15:16 (159216): Detected: Remote Desktop Enabled (localhost:48153) 2025-07-11 14:15:17 (159216): VM state change detected. (old = 'running', new = 'paused') 2025-07-11 14:15:37 (159216): Status Report: Job Duration: '64800.000000' 2025-07-11 14:15:37 (159216): Status Report: Elapsed Time: '22502.000000' 2025-07-11 14:15:37 (159216): Status Report: CPU Time: '66856.300000' 2025-07-11 14:15:37 (159216): Preference change detected 2025-07-11 14:15:37 (159216): Setting CPU throttle for VM. (100%) 2025-07-11 14:15:37 (159216): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds)) 2025-07-11 14:15:38 (159216): VM state change detected. (old = 'paused', new = 'running') 2025-07-11 14:15:39 (159216): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032 2025-07-11 14:15:39 (159216): Guest Log: BIOS: Booting from Hard Disk... 2025-07-11 14:15:41 (159216): Guest Log: BIOS: KBD: unsupported int 16h function 03 2025-07-11 14:15:41 (159216): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 2025-07-11 14:16:00 (159216): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds 2025-07-11 14:16:00 (159216): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000) 2025-07-11 14:16:01 (159216): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log 2025-07-11 14:16:01 (159216): Guest Log: 00:00:00.000075 main Log opened 2025-07-11T12:16:00.897557000Z 2025-07-11 14:16:01 (159216): Guest Log: 00:00:00.001332 main OS Product: Linux 2025-07-11 14:16:01 (159216): Guest Log: 00:00:00.001360 main OS Release: 4.14.232-19.cernvm.x86_64 2025-07-11 14:16:01 (159216): Guest Log: 00:00:00.001373 main OS Version: #1 SMP Fri Apr 30 17:12:25 CEST 2021 2025-07-11 14:16:01 (159216): Guest Log: 00:00:00.001383 main Executable: /usr/sbin/VBoxService 2025-07-11 14:16:01 (159216): Guest Log: 00:00:00.001384 main Process ID: 2287 2025-07-11 14:16:01 (159216): Guest Log: 00:00:00.001384 main Package type: LINUX_64BITS_GENERIC 2025-07-11 14:16:01 (159216): Guest Log: 00:00:00.003381 main 5.2.6 r120293 started. Verbose level = 0 2025-07-11 14:16:16 (159216): Guest Log: [INFO] Mounting the shared directory 2025-07-11 14:16:16 (159216): Guest Log: [INFO] Shared directory mounted, enabling vboxmonitor 2025-07-11 14:16:16 (159216): Guest Log: [INFO] Sourcing essential functions from /cvmfs/grid.cern.ch 2025-07-11 14:16:16 (159216): Guest Log: [INFO] Testing connection to cern.ch 2025-07-11 14:16:16 (159216): Guest Log: [INFO] Testing connection to VCCS 2025-07-11 14:16:17 (159216): Guest Log: [INFO] Testing connection to HTCondor 2025-07-11 14:16:17 (159216): Guest Log: [INFO] Testing connection to WMAgent 2025-07-11 14:16:17 (159216): Guest Log: [INFO] Testing connection to EOSCMS 2025-07-11 14:16:17 (159216): Guest Log: [INFO] Testing connection to CMS-Factory 2025-07-11 14:16:17 (159216): Guest Log: [INFO] Testing connection to CMS-Frontier 2025-07-11 14:16:18 (159216): Guest Log: [INFO] Testing connection to Frontier 2025-07-11 14:16:18 (159216): Guest Log: [INFO] Testing connection to http://cms-frontier.openhtc.io:8080/FrontierProd/Frontier/ 2025-07-11 14:16:18 (159216): Guest Log: [INFO] Could not find a local HTTP proxy 2025-07-11 14:16:18 (159216): Guest Log: [INFO] CVMFS and Frontier will have to use DIRECT connections 2025-07-11 14:16:18 (159216): Guest Log: [INFO] This makes the application less efficient 2025-07-11 14:16:18 (159216): Guest Log: [INFO] It also puts higher load on the project servers 2025-07-11 14:16:18 (159216): Guest Log: [INFO] Setting up a local HTTP proxy is highly recommended 2025-07-11 14:16:18 (159216): Guest Log: [INFO] Advice can be found in the project forum 2025-07-11 14:16:19 (159216): Guest Log: [INFO] Reloading and probing the CVMFS configuration 2025-07-11 14:16:24 (159216): Guest Log: [INFO] Probing /cvmfs/grid.cern.ch... OK 2025-07-11 14:16:25 (159216): Guest Log: [INFO] Probing /cvmfs/oasis.opensciencegrid.org... OK 2025-07-11 14:16:25 (159216): Guest Log: [INFO] Probing /cvmfs/singularity.opensciencegrid.org... OK 2025-07-11 14:16:25 (159216): Guest Log: [INFO] Probing /cvmfs/cms.cern.ch... OK 2025-07-11 14:16:27 (159216): Guest Log: [INFO] Probing /cvmfs/cms-ib.cern.ch... OK 2025-07-11 14:16:28 (159216): Guest Log: [INFO] Excerpt from "cvmfs_config stat": VERSION HOST PROXY 2025-07-11 14:16:28 (159216): Guest Log: [INFO] 2.7.2.0 http://s1fnal-cvmfs.openhtc.io:8080 DIRECT 2025-07-11 14:16:28 (159216): Guest Log: [INFO] Environment HTTP proxy: not set 2025-07-11 14:16:28 (159216): Guest Log: [INFO] Reading volunteer information 2025-07-11 14:16:34 (159216): Guest Log: [INFO] Requesting an X509 credential from LHC@home 2025-07-11 14:16:35 (159216): Guest Log: [INFO] Requesting an idtoken from LHC@home 2025-07-11 14:16:36 (159216): Guest Log: [INFO] CMS application starting. Check log files. 2025-07-11 14:27:41 (159216): VM state change detected. (old = 'running', new = 'paused') 2025-07-11 14:28:01 (159216): VM state change detected. (old = 'paused', new = 'running') 2025-07-11 14:29:01 (159216): VM state change detected. (old = 'running', new = 'paused') 2025-07-11 14:39:01 (159216): VM state change detected. (old = 'paused', new = 'running') 2025-07-11 14:39:07 (159216): VM state change detected. (old = 'running', new = 'paused') 2025-07-11 14:39:27 (159216): VM state change detected. (old = 'paused', new = 'running') 2025-07-11 14:42:57 (159216): VM state change detected. (old = 'running', new = 'paused') 2025-07-11 14:43:18 (159216): VM state change detected. (old = 'paused', new = 'running') 2025-07-11 14:49:54 (159216): VM state change detected. (old = 'running', new = 'paused') 2025-07-11 14:50:14 (159216): VM state change detected. (old = 'paused', new = 'running') 2025-07-11 15:18:41 (159216): VM state change detected. (old = 'running', new = 'paused') 2025-07-11 15:19:02 (159216): VM state change detected. (old = 'paused', new = 'running') 2025-07-11 15:27:13 (159216): VM state change detected. (old = 'running', new = 'paused') 2025-07-11 15:27:34 (159216): VM state change detected. (old = 'paused', new = 'running') 2025-07-11 15:32:34 (159216): VM state change detected. (old = 'running', new = 'paused') 2025-07-11 15:32:54 (159216): VM state change detected. (old = 'paused', new = 'running') 2025-07-11 15:40:39 (159216): VM state change detected. (old = 'running', new = 'paused') 2025-07-11 17:31:07 (159216): VM state change detected. (old = 'paused', new = 'running') 2025-07-11 17:31:11 (159216): Guest Log: 01:12:22.166997 timesync vgsvcTimeSyncWorker: Radical host time change: 6 638 235 000 000ns (HostNow=1 752 247 870 933 000 000 ns HostLast=1 752 241 232 698 000 000 ns) 2025-07-11 17:31:21 (159216): Guest Log: 01:12:32.177155 timesync vgsvcTimeSyncWorker: Radical guest time change: 7 377 925 229 000ns (GuestNow=1 752 247 880 966 417 000 ns GuestLast=1 752 240 503 041 188 000 ns fSetTimeLastLoop=true ) 2025-07-11 17:32:08 (159216): VM state change detected. (old = 'running', new = 'paused') 2025-07-11 17:32:29 (159216): VM state change detected. (old = 'paused', new = 'running') 2025-07-11 17:32:58 (159216): VM state change detected. (old = 'running', new = 'paused') 2025-07-11 17:33:18 (159216): VM state change detected. (old = 'paused', new = 'running') 2025-07-11 17:33:28 (159216): VM state change detected. (old = 'running', new = 'paused') 2025-07-11 18:08:58 (159216): VM state change detected. (old = 'paused', new = 'running') 2025-07-11 18:09:01 (159216): Guest Log: 01:14:02.223549 timesync vgsvcTimeSyncWorker: Radical host time change: 2 139 564 000 000ns (HostNow=1 752 250 141 121 000 000 ns HostLast=1 752 248 001 557 000 000 ns) 2025-07-11 18:09:11 (159216): VM state change detected. (old = 'running', new = 'paused') 2025-07-11 18:26:53 (159216): Guest Log: 01:14:12.224156 timesync vgsvcTimeSyncWorker: Radical guest time change: 2 180 121 585 000ns (GuestNow=1 752 250 151 127 991 000 ns GuestLast=1 752 247 971 006 406 000 ns fSetTimeLastLoop=true ) 2025-07-11 18:26:53 (159216): VM state change detected. (old = 'paused', new = 'running') 2025-07-11 18:27:04 (159216): VM state change detected. (old = 'running', new = 'paused') 2025-07-11 18:46:31 (159216): VM state change detected. (old = 'paused', new = 'running') 2025-07-11 18:46:51 (159216): Guest Log: 01:14:42.244099 timesync vgsvcTimeSyncWorker: Radical guest time change: 2 239 486 920 000ns (GuestNow=1 752 252 410 629 928 000 ns GuestLast=1 752 250 171 143 008 000 ns fSetTimeLastLoop=true ) 2025-07-11 18:47:22 (159216): VM state change detected. (old = 'running', new = 'paused') 2025-07-11 18:47:43 (159216): VM state change detected. (old = 'paused', new = 'running') 2025-07-11 18:48:32 (159216): VM state change detected. (old = 'running', new = 'paused') 2025-07-11 18:48:52 (159216): VM state change detected. (old = 'paused', new = 'running') 2025-07-11 18:50:04 (159216): VM state change detected. (old = 'running', new = 'paused') 2025-07-11 18:50:24 (159216): VM state change detected. (old = 'paused', new = 'running') 2025-07-11 18:50:34 (159216): VM state change detected. (old = 'running', new = 'paused') 2025-07-11 18:51:15 (159216): VM state change detected. (old = 'paused', new = 'running') 2025-07-11 18:51:25 (159216): VM state change detected. (old = 'running', new = 'paused') 2025-07-11 18:52:45 (159216): VM state change detected. (old = 'paused', new = 'running') 2025-07-11 18:52:56 (159216): VM state change detected. (old = 'running', new = 'paused') 2025-07-11 19:07:39 (159216): VM state change detected. (old = 'paused', new = 'running') 2025-07-11 19:07:49 (159216): VM state change detected. (old = 'running', new = 'paused') 2025-07-11 19:17:11 (159216): Preference change detected 2025-07-11 19:17:11 (159216): Setting CPU throttle for VM. (100%) 2025-07-11 19:17:12 (159216): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds)) 2025-07-11 19:17:12 (159216): VM state change detected. (old = 'paused', new = 'running') 2025-07-11 19:20:04 (159216): Preference change detected 2025-07-11 19:20:04 (159216): Setting CPU throttle for VM. (100%) 2025-07-11 19:20:04 (159216): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds)) 2025-07-11 19:32:49 (159216): Preference change detected 2025-07-11 19:32:49 (159216): Setting CPU throttle for VM. (100%) 2025-07-11 19:32:50 (159216): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds)) 2025-07-11 19:32:54 (159216): Preference change detected 2025-07-11 19:32:54 (159216): Setting CPU throttle for VM. (100%) 2025-07-11 19:32:55 (159216): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds)) 2025-07-11 19:38:27 (159216): Status Report: Job Duration: '64800.000000' 2025-07-11 19:38:27 (159216): Status Report: Elapsed Time: '28502.000000' 2025-07-11 19:38:27 (159216): Status Report: CPU Time: '85035.300000' 2025-07-11 20:15:49 (159216): Preference change detected 2025-07-11 20:15:49 (159216): Setting CPU throttle for VM. (100%) 2025-07-11 20:15:49 (159216): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds)) 2025-07-11 20:16:39 (159216): Preference change detected 2025-07-11 20:16:39 (159216): Setting CPU throttle for VM. (100%) 2025-07-11 20:16:40 (159216): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds)) 2025-07-11 20:16:55 (159216): Preference change detected 2025-07-11 20:16:55 (159216): Setting CPU throttle for VM. (100%) 2025-07-11 20:16:56 (159216): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds)) 2025-07-11 20:17:01 (159216): Preference change detected 2025-07-11 20:17:01 (159216): Setting CPU throttle for VM. (100%) 2025-07-11 20:17:01 (159216): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds)) 2025-07-11 21:01:43 (159216): Preference change detected 2025-07-11 21:01:43 (159216): Setting CPU throttle for VM. (100%) 2025-07-11 21:01:43 (159216): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds)) 2025-07-11 21:17:36 (159216): Status Report: Job Duration: '64800.000000' 2025-07-11 21:17:36 (159216): Status Report: Elapsed Time: '34502.000000' 2025-07-11 21:17:36 (159216): Status Report: CPU Time: '105501.040000' 2025-07-11 22:56:48 (159216): Status Report: Job Duration: '64800.000000' 2025-07-11 22:56:48 (159216): Status Report: Elapsed Time: '40502.000000' 2025-07-11 22:56:48 (159216): Status Report: CPU Time: '125645.640000' 2025-07-12 00:35:57 (159216): Status Report: Job Duration: '64800.000000' 2025-07-12 00:35:57 (159216): Status Report: Elapsed Time: '46502.000000' 2025-07-12 00:35:57 (159216): Status Report: CPU Time: '144373.900000' 2025-07-12 00:38:42 (159216): VM state change detected. (old = 'running', new = 'paused') 2025-07-12 19:44:11 (1584840): vboxwrapper version 26210 2025-07-12 19:44:11 (1584840): BOINC client version: 8.2.4 2025-07-12 19:44:12 (1584840): Detected: VirtualBox VboxManage Interface (Version: 7.0.18) 2025-07-12 19:44:12 (1584840): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds) 2025-07-12 19:44:12 (1584840): Guest Log: BIOS: VirtualBox 7.0.18 2025-07-12 19:44:12 (1584840): Guest Log: CPUID EDX: 0x178bfbff 2025-07-12 19:44:12 (1584840): Guest Log: BIOS: No PCI IDE controller, not probing IDE 2025-07-12 19:44:12 (1584840): Guest Log: BIOS: AHCI 0-P#0: PCHS=16383/16/63 LCHS=1024/255/63 0x0000000002800000 sectors 2025-07-12 19:44:12 (1584840): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032 2025-07-12 19:44:12 (1584840): Guest Log: BIOS: Booting from Hard Disk... 2025-07-12 19:44:12 (1584840): Guest Log: BIOS: KBD: unsupported int 16h function 03 2025-07-12 19:44:12 (1584840): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 2025-07-12 19:44:12 (1584840): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds 2025-07-12 19:44:12 (1584840): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000) 2025-07-12 19:44:12 (1584840): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log 2025-07-12 19:44:12 (1584840): Guest Log: 00:00:00.000075 main Log opened 2025-07-11T12:16:00.897557000Z 2025-07-12 19:44:12 (1584840): Guest Log: 00:00:00.001332 main OS Product: Linux 2025-07-12 19:44:12 (1584840): Guest Log: 00:00:00.001360 main OS Release: 4.14.232-19.cernvm.x86_64 2025-07-12 19:44:12 (1584840): Guest Log: 00:00:00.001373 main OS Version: #1 SMP Fri Apr 30 17:12:25 CEST 2021 2025-07-12 19:44:12 (1584840): Guest Log: 00:00:00.001383 main Executable: /usr/sbin/VBoxService 2025-07-12 19:44:12 (1584840): Guest Log: 00:00:00.001384 main Process ID: 2287 2025-07-12 19:44:12 (1584840): Guest Log: 00:00:00.001384 main Package type: LINUX_64BITS_GENERIC 2025-07-12 19:44:12 (1584840): Guest Log: 00:00:00.003381 main 5.2.6 r120293 started. Verbose level = 0 2025-07-12 19:44:12 (1584840): Guest Log: [INFO] Mounting the shared directory 2025-07-12 19:44:12 (1584840): Guest Log: [INFO] Shared directory mounted, enabling vboxmonitor 2025-07-12 19:44:12 (1584840): Guest Log: [INFO] Sourcing essential functions from /cvmfs/grid.cern.ch 2025-07-12 19:44:12 (1584840): Guest Log: [INFO] Testing connection to cern.ch 2025-07-12 19:44:12 (1584840): Guest Log: [INFO] Testing connection to VCCS 2025-07-12 19:44:12 (1584840): Guest Log: [INFO] Testing connection to HTCondor 2025-07-12 19:44:12 (1584840): Guest Log: [INFO] Testing connection to WMAgent 2025-07-12 19:44:12 (1584840): Guest Log: [INFO] Testing connection to EOSCMS 2025-07-12 19:44:12 (1584840): Guest Log: [INFO] Testing connection to CMS-Factory 2025-07-12 19:44:12 (1584840): Guest Log: [INFO] Testing connection to CMS-Frontier 2025-07-12 19:44:12 (1584840): Guest Log: [INFO] Testing connection to Frontier 2025-07-12 19:44:12 (1584840): Guest Log: [INFO] Testing connection to http://cms-frontier.openhtc.io:8080/FrontierProd/Frontier/ 2025-07-12 19:44:12 (1584840): Guest Log: [INFO] Could not find a local HTTP proxy 2025-07-12 19:44:12 (1584840): Guest Log: [INFO] CVMFS and Frontier will have to use DIRECT connections 2025-07-12 19:44:12 (1584840): Guest Log: [INFO] This makes the application less efficient 2025-07-12 19:44:12 (1584840): Guest Log: [INFO] It also puts higher load on the project servers 2025-07-12 19:44:12 (1584840): Guest Log: [INFO] Setting up a local HTTP proxy is highly recommended 2025-07-12 19:44:12 (1584840): Guest Log: [INFO] Advice can be found in the project forum 2025-07-12 19:44:12 (1584840): Guest Log: [INFO] Reloading and probing the CVMFS configuration 2025-07-12 19:44:12 (1584840): Guest Log: [INFO] Probing /cvmfs/grid.cern.ch... OK 2025-07-12 19:44:12 (1584840): Guest Log: [INFO] Probing /cvmfs/oasis.opensciencegrid.org... OK 2025-07-12 19:44:12 (1584840): Guest Log: [INFO] Probing /cvmfs/singularity.opensciencegrid.org... OK 2025-07-12 19:44:12 (1584840): Guest Log: [INFO] Probing /cvmfs/cms.cern.ch... OK 2025-07-12 19:44:12 (1584840): Guest Log: [INFO] Probing /cvmfs/cms-ib.cern.ch... OK 2025-07-12 19:44:12 (1584840): Guest Log: [INFO] Excerpt from "cvmfs_config stat": VERSION HOST PROXY 2025-07-12 19:44:12 (1584840): Guest Log: [INFO] 2.7.2.0 http://s1fnal-cvmfs.openhtc.io:8080 DIRECT 2025-07-12 19:44:12 (1584840): Guest Log: [INFO] Environment HTTP proxy: not set 2025-07-12 19:44:12 (1584840): Guest Log: [INFO] Reading volunteer information 2025-07-12 19:44:12 (1584840): Guest Log: [INFO] Requesting an X509 credential from LHC@home 2025-07-12 19:44:12 (1584840): Guest Log: [INFO] Requesting an idtoken from LHC@home 2025-07-12 19:44:12 (1584840): Guest Log: [INFO] CMS application starting. Check log files. 2025-07-12 19:44:12 (1584840): Guest Log: 01:12:22.166997 timesync vgsvcTimeSyncWorker: Radical host time change: 6 638 235 000 000ns (HostNow=1 752 247 870 933 000 000 ns HostLast=1 752 241 232 698 000 000 ns) 2025-07-12 19:44:12 (1584840): Guest Log: 01:12:32.177155 timesync vgsvcTimeSyncWorker: Radical guest time change: 7 377 925 229 000ns (GuestNow=1 752 247 880 966 417 000 ns GuestLast=1 752 240 503 041 188 000 ns fSetTimeLastLoop=true ) 2025-07-12 19:44:12 (1584840): Guest Log: 01:14:02.223549 timesync vgsvcTimeSyncWorker: Radical host time change: 2 139 564 000 000ns (HostNow=1 752 250 141 121 000 000 ns HostLast=1 752 248 001 557 000 000 ns) 2025-07-12 19:44:12 (1584840): Guest Log: 01:14:12.224156 timesync vgsvcTimeSyncWorker: Radical guest time change: 2 180 121 585 000ns (GuestNow=1 752 250 151 127 991 000 ns GuestLast=1 752 247 971 006 406 000 ns fSetTimeLastLoop=true ) 2025-07-12 19:44:12 (1584840): Guest Log: 01:14:42.244099 timesync vgsvcTimeSyncWorker: Radical guest time change: 2 239 486 920 000ns (GuestNow=1 752 252 410 629 928 000 ns GuestLast=1 752 250 171 143 008 000 ns fSetTimeLastLoop=true ) 2025-07-12 19:44:12 (1584840): Starting VM using VBoxManage interface. (boinc_47b40863cd814ce0, slot#15) 2025-07-12 19:44:14 (1584840): Successfully started VM. (PID = '1584984') 2025-07-12 19:44:14 (1584840): Reporting VM Process ID to BOINC. 2025-07-12 19:44:14 (1584840): Guest Log: BIOS: VirtualBox 7.0.18 2025-07-12 19:44:14 (1584840): Guest Log: CPUID EDX: 0x178bfbff 2025-07-12 19:44:14 (1584840): Guest Log: BIOS: No PCI IDE controller, not probing IDE 2025-07-12 19:44:14 (1584840): Guest Log: BIOS: AHCI 0-P#0: PCHS=16383/16/63 LCHS=1024/255/63 0x0000000002800000 sectors 2025-07-12 19:44:14 (1584840): VM state change detected. (old = 'poweredoff', new = 'running') 2025-07-12 19:44:14 (1584840): Detected: Web Application Enabled (http://localhost:43293) 2025-07-12 19:44:14 (1584840): Detected: Remote Desktop Enabled (localhost:48153) 2025-07-12 19:44:14 (1584840): Status Report: Job Duration: '64800.000000' 2025-07-12 19:44:14 (1584840): Status Report: Elapsed Time: '46664.000000' 2025-07-12 19:44:14 (1584840): Status Report: CPU Time: '144939.270000' 2025-07-12 19:44:14 (1584840): Preference change detected 2025-07-12 19:44:14 (1584840): Setting CPU throttle for VM. (100%) 2025-07-12 19:44:14 (1584840): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds)) 2025-07-12 19:44:16 (1584840): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032 2025-07-12 19:44:16 (1584840): Guest Log: BIOS: Booting from Hard Disk... 2025-07-12 19:44:20 (1584840): Guest Log: BIOS: KBD: unsupported int 16h function 03 2025-07-12 19:44:20 (1584840): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 2025-07-12 19:44:40 (1584840): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds 2025-07-12 19:44:40 (1584840): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000) 2025-07-12 19:44:40 (1584840): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log 2025-07-12 19:44:40 (1584840): Guest Log: 00:00:00.000071 main Log opened 2025-07-12T17:44:40.631696000Z 2025-07-12 19:44:40 (1584840): Guest Log: 00:00:00.000150 main OS Product: Linux 2025-07-12 19:44:40 (1584840): Guest Log: 00:00:00.000168 main OS Release: 4.14.232-19.cernvm.x86_64 2025-07-12 19:44:40 (1584840): Guest Log: 00:00:00.000178 main OS Version: #1 SMP Fri Apr 30 17:12:25 CEST 2021 2025-07-12 19:44:40 (1584840): Guest Log: 00:00:00.000188 main Executable: /usr/sbin/VBoxService 2025-07-12 19:44:40 (1584840): Guest Log: 00:00:00.000189 main Process ID: 2275 2025-07-12 19:44:40 (1584840): Guest Log: 00:00:00.000189 main Package type: LINUX_64BITS_GENERIC 2025-07-12 19:44:40 (1584840): Guest Log: 00:00:00.002321 main 5.2.6 r120293 started. Verbose level = 0 2025-07-12 19:44:59 (1584840): Guest Log: [INFO] Mounting the shared directory 2025-07-12 19:44:59 (1584840): Guest Log: [INFO] Shared directory mounted, enabling vboxmonitor 2025-07-12 19:44:59 (1584840): Guest Log: [INFO] Sourcing essential functions from /cvmfs/grid.cern.ch 2025-07-12 19:44:59 (1584840): Guest Log: [INFO] Testing connection to cern.ch 2025-07-12 19:44:59 (1584840): Guest Log: [INFO] Testing connection to VCCS 2025-07-12 19:45:00 (1584840): Guest Log: [INFO] Testing connection to HTCondor 2025-07-12 19:45:00 (1584840): Guest Log: [INFO] Testing connection to WMAgent 2025-07-12 19:45:00 (1584840): Guest Log: [INFO] Testing connection to EOSCMS 2025-07-12 19:45:00 (1584840): Guest Log: [INFO] Testing connection to CMS-Factory 2025-07-12 19:45:00 (1584840): Guest Log: [INFO] Testing connection to CMS-Frontier 2025-07-12 19:45:00 (1584840): Guest Log: [INFO] Testing connection to Frontier 2025-07-12 19:45:01 (1584840): Guest Log: [INFO] Testing connection to http://cms-frontier.openhtc.io:8080/FrontierProd/Frontier/ 2025-07-12 19:45:01 (1584840): Guest Log: [INFO] Could not find a local HTTP proxy 2025-07-12 19:45:01 (1584840): Guest Log: [INFO] CVMFS and Frontier will have to use DIRECT connections 2025-07-12 19:45:01 (1584840): Guest Log: [INFO] This makes the application less efficient 2025-07-12 19:45:01 (1584840): Guest Log: [INFO] It also puts higher load on the project servers 2025-07-12 19:45:01 (1584840): Guest Log: [INFO] Setting up a local HTTP proxy is highly recommended 2025-07-12 19:45:02 (1584840): Guest Log: [INFO] Advice can be found in the project forum 2025-07-12 19:45:02 (1584840): Guest Log: [INFO] Reloading and probing the CVMFS configuration 2025-07-12 19:45:06 (1584840): Guest Log: [INFO] Probing /cvmfs/grid.cern.ch... OK 2025-07-12 19:45:07 (1584840): Guest Log: [INFO] Probing /cvmfs/cms-ib.cern.ch... OK 2025-07-12 19:45:08 (1584840): Guest Log: [INFO] Probing /cvmfs/oasis.opensciencegrid.org... OK 2025-07-12 19:45:08 (1584840): Guest Log: [INFO] Probing /cvmfs/cms.cern.ch... OK 2025-07-12 19:45:08 (1584840): Guest Log: [INFO] Probing /cvmfs/singularity.opensciencegrid.org... OK 2025-07-12 19:45:08 (1584840): Guest Log: [INFO] Excerpt from "cvmfs_config stat": VERSION HOST PROXY 2025-07-12 19:45:09 (1584840): Guest Log: [INFO] 2.7.2.0 http://s1fnal-cvmfs.openhtc.io:8080 DIRECT 2025-07-12 19:45:09 (1584840): Guest Log: [INFO] Environment HTTP proxy: not set 2025-07-12 19:45:09 (1584840): Guest Log: [INFO] Reading volunteer information 2025-07-12 19:45:14 (1584840): Guest Log: [INFO] Requesting an X509 credential from LHC@home 2025-07-12 19:45:15 (1584840): Guest Log: [INFO] Requesting an idtoken from LHC@home 2025-07-12 19:45:16 (1584840): Guest Log: [INFO] CMS application starting. Check log files. 2025-07-12 20:07:09 (1584840): VM state change detected. (old = 'running', new = 'paused') 2025-07-12 20:13:14 (1584840): VM state change detected. (old = 'paused', new = 'running') 2025-07-12 21:29:30 (1584840): Status Report: Job Duration: '64800.000000' 2025-07-12 21:29:30 (1584840): Status Report: Elapsed Time: '52664.000000' 2025-07-12 21:29:30 (1584840): Status Report: CPU Time: '166094.640000' 2025-07-12 23:08:41 (1584840): Status Report: Job Duration: '64800.000000' 2025-07-12 23:08:41 (1584840): Status Report: Elapsed Time: '58664.000000' 2025-07-12 23:08:41 (1584840): Status Report: CPU Time: '188026.710000' 2025-07-13 00:14:11 (1584840): VM state change detected. (old = 'running', new = 'paused') 2025-07-15 19:20:57 (463616): vboxwrapper version 26210 2025-07-15 19:20:57 (463616): BOINC client version: 8.2.4 2025-07-15 19:20:57 (463616): Detected: VirtualBox VboxManage Interface (Version: 7.0.18) 2025-07-15 19:20:57 (463616): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds) 2025-07-15 19:20:57 (463616): Guest Log: BIOS: VirtualBox 7.0.18 2025-07-15 19:20:57 (463616): Guest Log: CPUID EDX: 0x178bfbff 2025-07-15 19:20:57 (463616): Guest Log: BIOS: No PCI IDE controller, not probing IDE 2025-07-15 19:20:57 (463616): Guest Log: BIOS: AHCI 0-P#0: PCHS=16383/16/63 LCHS=1024/255/63 0x0000000002800000 sectors 2025-07-15 19:20:57 (463616): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032 2025-07-15 19:20:57 (463616): Guest Log: BIOS: Booting from Hard Disk... 2025-07-15 19:20:57 (463616): Guest Log: BIOS: KBD: unsupported int 16h function 03 2025-07-15 19:20:57 (463616): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 2025-07-15 19:20:57 (463616): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds 2025-07-15 19:20:57 (463616): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000) 2025-07-15 19:20:57 (463616): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log 2025-07-15 19:20:57 (463616): Guest Log: 00:00:00.000071 main Log opened 2025-07-12T17:44:40.631696000Z 2025-07-15 19:20:57 (463616): Guest Log: 00:00:00.000150 main OS Product: Linux 2025-07-15 19:20:57 (463616): Guest Log: 00:00:00.000168 main OS Release: 4.14.232-19.cernvm.x86_64 2025-07-15 19:20:57 (463616): Guest Log: 00:00:00.000178 main OS Version: #1 SMP Fri Apr 30 17:12:25 CEST 2021 2025-07-15 19:20:57 (463616): Guest Log: 00:00:00.000188 main Executable: /usr/sbin/VBoxService 2025-07-15 19:20:57 (463616): Guest Log: 00:00:00.000189 main Process ID: 2275 2025-07-15 19:20:57 (463616): Guest Log: 00:00:00.000189 main Package type: LINUX_64BITS_GENERIC 2025-07-15 19:20:57 (463616): Guest Log: 00:00:00.002321 main 5.2.6 r120293 started. Verbose level = 0 2025-07-15 19:20:57 (463616): Guest Log: [INFO] Mounting the shared directory 2025-07-15 19:20:57 (463616): Guest Log: [INFO] Shared directory mounted, enabling vboxmonitor 2025-07-15 19:20:57 (463616): Guest Log: [INFO] Sourcing essential functions from /cvmfs/grid.cern.ch 2025-07-15 19:20:57 (463616): Guest Log: [INFO] Testing connection to cern.ch 2025-07-15 19:20:57 (463616): Guest Log: [INFO] Testing connection to VCCS 2025-07-15 19:20:57 (463616): Guest Log: [INFO] Testing connection to HTCondor 2025-07-15 19:20:57 (463616): Guest Log: [INFO] Testing connection to WMAgent 2025-07-15 19:20:57 (463616): Guest Log: [INFO] Testing connection to EOSCMS 2025-07-15 19:20:57 (463616): Guest Log: [INFO] Testing connection to CMS-Factory 2025-07-15 19:20:57 (463616): Guest Log: [INFO] Testing connection to CMS-Frontier 2025-07-15 19:20:57 (463616): Guest Log: [INFO] Testing connection to Frontier 2025-07-15 19:20:57 (463616): Guest Log: [INFO] Testing connection to http://cms-frontier.openhtc.io:8080/FrontierProd/Frontier/ 2025-07-15 19:20:57 (463616): Guest Log: [INFO] Could not find a local HTTP proxy 2025-07-15 19:20:57 (463616): Guest Log: [INFO] CVMFS and Frontier will have to use DIRECT connections 2025-07-15 19:20:57 (463616): Guest Log: [INFO] This makes the application less efficient 2025-07-15 19:20:57 (463616): Guest Log: [INFO] It also puts higher load on the project servers 2025-07-15 19:20:57 (463616): Guest Log: [INFO] Setting up a local HTTP proxy is highly recommended 2025-07-15 19:20:57 (463616): Guest Log: [INFO] Advice can be found in the project forum 2025-07-15 19:20:57 (463616): Guest Log: [INFO] Reloading and probing the CVMFS configuration 2025-07-15 19:20:57 (463616): Guest Log: [INFO] Probing /cvmfs/grid.cern.ch... OK 2025-07-15 19:20:57 (463616): Guest Log: [INFO] Probing /cvmfs/cms-ib.cern.ch... OK 2025-07-15 19:20:57 (463616): Guest Log: [INFO] Probing /cvmfs/oasis.opensciencegrid.org... OK 2025-07-15 19:20:57 (463616): Guest Log: [INFO] Probing /cvmfs/cms.cern.ch... OK 2025-07-15 19:20:57 (463616): Guest Log: [INFO] Probing /cvmfs/singularity.opensciencegrid.org... OK 2025-07-15 19:20:57 (463616): Guest Log: [INFO] Excerpt from "cvmfs_config stat": VERSION HOST PROXY 2025-07-15 19:20:57 (463616): Guest Log: [INFO] 2.7.2.0 http://s1fnal-cvmfs.openhtc.io:8080 DIRECT 2025-07-15 19:20:57 (463616): Guest Log: [INFO] Environment HTTP proxy: not set 2025-07-15 19:20:57 (463616): Guest Log: [INFO] Reading volunteer information 2025-07-15 19:20:57 (463616): Guest Log: [INFO] Requesting an X509 credential from LHC@home 2025-07-15 19:20:57 (463616): Guest Log: [INFO] Requesting an idtoken from LHC@home 2025-07-15 19:20:57 (463616): Guest Log: [INFO] CMS application starting. Check log files. 2025-07-15 19:20:57 (463616): Starting VM using VBoxManage interface. (boinc_47b40863cd814ce0, slot#15) 2025-07-15 19:20:58 (463616): Successfully started VM. (PID = '463710') 2025-07-15 19:20:58 (463616): Reporting VM Process ID to BOINC. 2025-07-15 19:20:58 (463616): Guest Log: BIOS: VirtualBox 7.0.18 2025-07-15 19:20:58 (463616): Guest Log: CPUID EDX: 0x178bfbff 2025-07-15 19:20:58 (463616): Guest Log: BIOS: No PCI IDE controller, not probing IDE 2025-07-15 19:20:58 (463616): Guest Log: BIOS: AHCI 0-P#0: PCHS=16383/16/63 LCHS=1024/255/63 0x0000000002800000 sectors 2025-07-15 19:20:58 (463616): VM state change detected. (old = 'poweredoff', new = 'running') 2025-07-15 19:20:58 (463616): Detected: Web Application Enabled (http://localhost:43293) 2025-07-15 19:20:58 (463616): Detected: Remote Desktop Enabled (localhost:48153) 2025-07-15 19:20:58 (463616): Status Report: Job Duration: '64800.000000' 2025-07-15 19:20:58 (463616): Status Report: Elapsed Time: '62606.000000' 2025-07-15 19:20:58 (463616): Status Report: CPU Time: '202277.940000' 2025-07-15 19:20:58 (463616): Preference change detected 2025-07-15 19:20:58 (463616): Setting CPU throttle for VM. (100%) 2025-07-15 19:20:58 (463616): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds)) 2025-07-15 19:21:00 (463616): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032 2025-07-15 19:21:00 (463616): Guest Log: BIOS: Booting from Hard Disk... 2025-07-15 19:21:02 (463616): Guest Log: BIOS: KBD: unsupported int 16h function 03 2025-07-15 19:21:02 (463616): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 2025-07-15 19:21:28 (463616): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds 2025-07-15 19:21:28 (463616): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000) 2025-07-15 19:21:28 (463616): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log 2025-07-15 19:21:28 (463616): Guest Log: 00:00:00.000109 main Log opened 2025-07-15T17:21:28.334752000Z 2025-07-15 19:21:28 (463616): Guest Log: 00:00:00.000284 main OS Product: Linux 2025-07-15 19:21:28 (463616): Guest Log: 00:00:00.000304 main OS Release: 4.14.232-19.cernvm.x86_64 2025-07-15 19:21:28 (463616): Guest Log: 00:00:00.000315 main OS Version: #1 SMP Fri Apr 30 17:12:25 CEST 2021 2025-07-15 19:21:28 (463616): Guest Log: 00:00:00.000325 main Executable: /usr/sbin/VBoxService 2025-07-15 19:21:28 (463616): Guest Log: 00:00:00.000325 main Process ID: 2277 2025-07-15 19:21:28 (463616): Guest Log: 00:00:00.000326 main Package type: LINUX_64BITS_GENERIC 2025-07-15 19:21:28 (463616): Guest Log: 00:00:00.001334 main 5.2.6 r120293 started. Verbose level = 0 2025-07-15 19:21:43 (463616): Guest Log: [INFO] Mounting the shared directory 2025-07-15 19:21:43 (463616): Guest Log: [INFO] Shared directory mounted, enabling vboxmonitor 2025-07-15 19:21:43 (463616): Guest Log: [INFO] Sourcing essential functions from /cvmfs/grid.cern.ch 2025-07-15 19:21:43 (463616): Guest Log: [INFO] Testing connection to cern.ch 2025-07-15 19:21:43 (463616): Guest Log: [INFO] Testing connection to VCCS 2025-07-15 19:21:44 (463616): Guest Log: [INFO] Testing connection to HTCondor 2025-07-15 19:21:44 (463616): Guest Log: [INFO] Testing connection to WMAgent 2025-07-15 19:21:44 (463616): Guest Log: [INFO] Testing connection to EOSCMS 2025-07-15 19:21:44 (463616): Guest Log: [INFO] Testing connection to CMS-Factory 2025-07-15 19:21:44 (463616): Guest Log: [INFO] Testing connection to CMS-Frontier 2025-07-15 19:21:45 (463616): Guest Log: [INFO] Testing connection to Frontier 2025-07-15 19:21:45 (463616): Guest Log: [INFO] Testing connection to http://cms-frontier.openhtc.io:8080/FrontierProd/Frontier/ 2025-07-15 19:21:45 (463616): Guest Log: [INFO] Could not find a local HTTP proxy 2025-07-15 19:21:45 (463616): Guest Log: [INFO] CVMFS and Frontier will have to use DIRECT connections 2025-07-15 19:21:45 (463616): Guest Log: [INFO] This makes the application less efficient 2025-07-15 19:21:46 (463616): Guest Log: [INFO] It also puts higher load on the project servers 2025-07-15 19:21:46 (463616): Guest Log: [INFO] Setting up a local HTTP proxy is highly recommended 2025-07-15 19:21:46 (463616): Guest Log: [INFO] Advice can be found in the project forum 2025-07-15 19:21:46 (463616): Guest Log: [INFO] Reloading and probing the CVMFS configuration 2025-07-15 19:21:50 (463616): Guest Log: [INFO] Probing /cvmfs/grid.cern.ch... OK 2025-07-15 19:21:52 (463616): Guest Log: [INFO] Probing /cvmfs/singularity.opensciencegrid.org... OK 2025-07-15 19:21:52 (463616): Guest Log: [INFO] Probing /cvmfs/cms-ib.cern.ch... OK 2025-07-15 19:21:52 (463616): Guest Log: [INFO] Probing /cvmfs/oasis.opensciencegrid.org... OK 2025-07-15 19:21:56 (463616): Guest Log: [INFO] Probing /cvmfs/cms.cern.ch... OK 2025-07-15 19:21:57 (463616): Guest Log: [INFO] Excerpt from "cvmfs_config stat": VERSION HOST PROXY 2025-07-15 19:21:57 (463616): Guest Log: [INFO] 2.7.2.0 http://s1ihep-cvmfs.openhtc.io:8080 DIRECT 2025-07-15 19:21:57 (463616): Guest Log: [INFO] Environment HTTP proxy: not set 2025-07-15 19:21:57 (463616): Guest Log: [INFO] Reading volunteer information 2025-07-15 19:22:13 (463616): Guest Log: [INFO] Requesting an X509 credential from LHC@home 2025-07-15 19:22:15 (463616): Guest Log: [INFO] Requesting an idtoken from LHC@home 2025-07-15 19:22:15 (463616): Guest Log: [INFO] CMS application starting. Check log files. 2025-07-15 19:24:06 (463616): VM state change detected. (old = 'running', new = 'paused') 2025-07-15 19:30:17 (463616): VM state change detected. (old = 'paused', new = 'running') 2025-07-15 19:44:16 (463616): VM state change detected. (old = 'running', new = 'paused') 2025-07-16 17:20:55 (462463): vboxwrapper version 26210 2025-07-16 17:20:55 (462463): BOINC client version: 8.2.4 2025-07-16 17:20:55 (462463): Detected: VirtualBox VboxManage Interface (Version: 7.0.18) 2025-07-16 17:20:55 (462463): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds) 2025-07-16 17:20:55 (462463): Guest Log: BIOS: VirtualBox 7.0.18 2025-07-16 17:20:55 (462463): Guest Log: CPUID EDX: 0x178bfbff 2025-07-16 17:20:55 (462463): Guest Log: BIOS: No PCI IDE controller, not probing IDE 2025-07-16 17:20:55 (462463): Guest Log: BIOS: AHCI 0-P#0: PCHS=16383/16/63 LCHS=1024/255/63 0x0000000002800000 sectors 2025-07-16 17:20:55 (462463): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032 2025-07-16 17:20:55 (462463): Guest Log: BIOS: Booting from Hard Disk... 2025-07-16 17:20:55 (462463): Guest Log: BIOS: KBD: unsupported int 16h function 03 2025-07-16 17:20:55 (462463): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 2025-07-16 17:20:55 (462463): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds 2025-07-16 17:20:55 (462463): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000) 2025-07-16 17:20:55 (462463): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log 2025-07-16 17:20:55 (462463): Guest Log: 00:00:00.000109 main Log opened 2025-07-15T17:21:28.334752000Z 2025-07-16 17:20:55 (462463): Guest Log: 00:00:00.000284 main OS Product: Linux 2025-07-16 17:20:55 (462463): Guest Log: 00:00:00.000304 main OS Release: 4.14.232-19.cernvm.x86_64 2025-07-16 17:20:55 (462463): Guest Log: 00:00:00.000315 main OS Version: #1 SMP Fri Apr 30 17:12:25 CEST 2021 2025-07-16 17:20:55 (462463): Guest Log: 00:00:00.000325 main Executable: /usr/sbin/VBoxService 2025-07-16 17:20:55 (462463): Guest Log: 00:00:00.000325 main Process ID: 2277 2025-07-16 17:20:55 (462463): Guest Log: 00:00:00.000326 main Package type: LINUX_64BITS_GENERIC 2025-07-16 17:20:55 (462463): Guest Log: 00:00:00.001334 main 5.2.6 r120293 started. Verbose level = 0 2025-07-16 17:20:55 (462463): Guest Log: [INFO] Mounting the shared directory 2025-07-16 17:20:55 (462463): Guest Log: [INFO] Shared directory mounted, enabling vboxmonitor 2025-07-16 17:20:55 (462463): Guest Log: [INFO] Sourcing essential functions from /cvmfs/grid.cern.ch 2025-07-16 17:20:55 (462463): Guest Log: [INFO] Testing connection to cern.ch 2025-07-16 17:20:55 (462463): Guest Log: [INFO] Testing connection to VCCS 2025-07-16 17:20:55 (462463): Guest Log: [INFO] Testing connection to HTCondor 2025-07-16 17:20:55 (462463): Guest Log: [INFO] Testing connection to WMAgent 2025-07-16 17:20:55 (462463): Guest Log: [INFO] Testing connection to EOSCMS 2025-07-16 17:20:55 (462463): Guest Log: [INFO] Testing connection to CMS-Factory 2025-07-16 17:20:55 (462463): Guest Log: [INFO] Testing connection to CMS-Frontier 2025-07-16 17:20:55 (462463): Guest Log: [INFO] Testing connection to Frontier 2025-07-16 17:20:55 (462463): Guest Log: [INFO] Testing connection to http://cms-frontier.openhtc.io:8080/FrontierProd/Frontier/ 2025-07-16 17:20:55 (462463): Guest Log: [INFO] Could not find a local HTTP proxy 2025-07-16 17:20:55 (462463): Guest Log: [INFO] CVMFS and Frontier will have to use DIRECT connections 2025-07-16 17:20:55 (462463): Guest Log: [INFO] This makes the application less efficient 2025-07-16 17:20:55 (462463): Guest Log: [INFO] It also puts higher load on the project servers 2025-07-16 17:20:55 (462463): Guest Log: [INFO] Setting up a local HTTP proxy is highly recommended 2025-07-16 17:20:55 (462463): Guest Log: [INFO] Advice can be found in the project forum 2025-07-16 17:20:55 (462463): Guest Log: [INFO] Reloading and probing the CVMFS configuration 2025-07-16 17:20:55 (462463): Guest Log: [INFO] Probing /cvmfs/grid.cern.ch... OK 2025-07-16 17:20:55 (462463): Guest Log: [INFO] Probing /cvmfs/singularity.opensciencegrid.org... OK 2025-07-16 17:20:55 (462463): Guest Log: [INFO] Probing /cvmfs/cms-ib.cern.ch... OK 2025-07-16 17:20:55 (462463): Guest Log: [INFO] Probing /cvmfs/oasis.opensciencegrid.org... OK 2025-07-16 17:20:55 (462463): Guest Log: [INFO] Probing /cvmfs/cms.cern.ch... OK 2025-07-16 17:20:55 (462463): Guest Log: [INFO] Excerpt from "cvmfs_config stat": VERSION HOST PROXY 2025-07-16 17:20:55 (462463): Guest Log: [INFO] 2.7.2.0 http://s1ihep-cvmfs.openhtc.io:8080 DIRECT 2025-07-16 17:20:55 (462463): Guest Log: [INFO] Environment HTTP proxy: not set 2025-07-16 17:20:55 (462463): Guest Log: [INFO] Reading volunteer information 2025-07-16 17:20:55 (462463): Guest Log: [INFO] Requesting an X509 credential from LHC@home 2025-07-16 17:20:55 (462463): Guest Log: [INFO] Requesting an idtoken from LHC@home 2025-07-16 17:20:55 (462463): Guest Log: [INFO] CMS application starting. Check log files. 2025-07-16 17:20:55 (462463): Starting VM using VBoxManage interface. (boinc_47b40863cd814ce0, slot#15) 2025-07-16 17:20:57 (462463): Successfully started VM. (PID = '462564') 2025-07-16 17:20:57 (462463): Reporting VM Process ID to BOINC. 2025-07-16 17:20:57 (462463): Guest Log: BIOS: VirtualBox 7.0.18 2025-07-16 17:20:57 (462463): Guest Log: CPUID EDX: 0x178bfbff 2025-07-16 17:20:57 (462463): Guest Log: BIOS: No PCI IDE controller, not probing IDE 2025-07-16 17:20:57 (462463): Guest Log: BIOS: AHCI 0-P#0: PCHS=16383/16/63 LCHS=1024/255/63 0x0000000002800000 sectors 2025-07-16 17:20:57 (462463): VM state change detected. (old = 'poweredoff', new = 'running') 2025-07-16 17:20:57 (462463): Detected: Web Application Enabled (http://localhost:43293) 2025-07-16 17:20:57 (462463): Detected: Remote Desktop Enabled (localhost:48153) 2025-07-16 17:20:57 (462463): Status Report: Job Duration: '64800.000000' 2025-07-16 17:20:57 (462463): Status Report: Elapsed Time: '63588.000000' 2025-07-16 17:20:57 (462463): Status Report: CPU Time: '204858.660000' 2025-07-16 17:20:57 (462463): Preference change detected 2025-07-16 17:20:57 (462463): Setting CPU throttle for VM. (100%) 2025-07-16 17:20:57 (462463): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds)) 2025-07-16 17:20:59 (462463): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032 2025-07-16 17:20:59 (462463): Guest Log: BIOS: Booting from Hard Disk... 2025-07-16 17:21:01 (462463): Guest Log: BIOS: KBD: unsupported int 16h function 03 2025-07-16 17:21:01 (462463): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 2025-07-16 17:21:28 (462463): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds 2025-07-16 17:21:28 (462463): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000) 2025-07-16 17:21:29 (462463): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log 2025-07-16 17:21:29 (462463): Guest Log: 00:00:00.000075 main Log opened 2025-07-16T15:21:29.161854000Z 2025-07-16 17:21:29 (462463): Guest Log: 00:00:00.000157 main OS Product: Linux 2025-07-16 17:21:29 (462463): Guest Log: 00:00:00.000178 main OS Release: 4.14.232-19.cernvm.x86_64 2025-07-16 17:21:29 (462463): Guest Log: 00:00:00.000189 main OS Version: #1 SMP Fri Apr 30 17:12:25 CEST 2021 2025-07-16 17:21:29 (462463): Guest Log: 00:00:00.000206 main Executable: /usr/sbin/VBoxService 2025-07-16 17:21:29 (462463): Guest Log: 00:00:00.000206 main Process ID: 2287 2025-07-16 17:21:29 (462463): Guest Log: 00:00:00.000206 main Package type: LINUX_64BITS_GENERIC 2025-07-16 17:21:29 (462463): Guest Log: 00:00:00.001454 main 5.2.6 r120293 started. Verbose level = 0 2025-07-16 17:21:43 (462463): Guest Log: [INFO] Mounting the shared directory 2025-07-16 17:21:43 (462463): Guest Log: [INFO] Shared directory mounted, enabling vboxmonitor 2025-07-16 17:21:43 (462463): Guest Log: [INFO] Sourcing essential functions from /cvmfs/grid.cern.ch 2025-07-16 17:21:43 (462463): Guest Log: [INFO] Testing connection to cern.ch 2025-07-16 17:21:44 (462463): Guest Log: [INFO] Testing connection to VCCS 2025-07-16 17:21:44 (462463): Guest Log: [INFO] Testing connection to HTCondor 2025-07-16 17:21:44 (462463): Guest Log: [INFO] Testing connection to WMAgent 2025-07-16 17:21:44 (462463): Guest Log: [INFO] Testing connection to EOSCMS 2025-07-16 17:21:44 (462463): Guest Log: [INFO] Testing connection to CMS-Factory 2025-07-16 17:21:44 (462463): Guest Log: [INFO] Testing connection to CMS-Frontier 2025-07-16 17:21:44 (462463): Guest Log: [INFO] Testing connection to Frontier 2025-07-16 17:21:45 (462463): Guest Log: [INFO] Testing connection to http://cms-frontier.openhtc.io:8080/FrontierProd/Frontier/ 2025-07-16 17:21:45 (462463): Guest Log: [INFO] Could not find a local HTTP proxy 2025-07-16 17:21:45 (462463): Guest Log: [INFO] CVMFS and Frontier will have to use DIRECT connections 2025-07-16 17:21:45 (462463): Guest Log: [INFO] This makes the application less efficient 2025-07-16 17:21:45 (462463): Guest Log: [INFO] It also puts higher load on the project servers 2025-07-16 17:21:45 (462463): Guest Log: [INFO] Setting up a local HTTP proxy is highly recommended 2025-07-16 17:21:46 (462463): Guest Log: [INFO] Advice can be found in the project forum 2025-07-16 17:21:46 (462463): Guest Log: [INFO] Reloading and probing the CVMFS configuration 2025-07-16 17:21:51 (462463): Guest Log: [INFO] Probing /cvmfs/grid.cern.ch... OK 2025-07-16 17:21:52 (462463): Guest Log: [INFO] Probing /cvmfs/cms-ib.cern.ch... OK 2025-07-16 17:21:53 (462463): Guest Log: [INFO] Probing /cvmfs/singularity.opensciencegrid.org... OK 2025-07-16 17:21:53 (462463): Guest Log: [INFO] Probing /cvmfs/oasis.opensciencegrid.org... OK 2025-07-16 17:21:53 (462463): Guest Log: [INFO] Probing /cvmfs/cms.cern.ch... OK 2025-07-16 17:21:54 (462463): Guest Log: [INFO] Excerpt from "cvmfs_config stat": VERSION HOST PROXY 2025-07-16 17:21:54 (462463): Guest Log: [INFO] 2.7.2.0 http://s1cern-cvmfs.openhtc.io DIRECT 2025-07-16 17:21:54 (462463): Guest Log: [INFO] Environment HTTP proxy: not set 2025-07-16 17:21:54 (462463): Guest Log: [INFO] Reading volunteer information 2025-07-16 17:21:56 (462463): Guest Log: [INFO] Requesting an X509 credential from LHC@home 2025-07-16 17:21:57 (462463): Guest Log: [INFO] Requesting an idtoken from LHC@home 2025-07-16 17:21:57 (462463): Guest Log: [INFO] CMS application starting. Check log files. 2025-07-16 17:41:00 (462463): Powering off VM. 2025-07-16 17:41:00 (462463): Successfully stopped VM. 2025-07-16 17:41:00 (462463): Deregistering VM. (boinc_47b40863cd814ce0, slot#15) 2025-07-16 17:41:00 (462463): Removing network bandwidth throttle group from VM. 2025-07-16 17:41:00 (462463): Removing VM from VirtualBox. 2025-07-16 17:41:05 (462463): called boinc_finish(0) </stderr_txt> ]]>
©2025 CERN