| Name | CMS_3930282_1741800303.597122_0 |
| Workunit | 230793883 |
| Created | 12 Mar 2025, 17:25:07 UTC |
| Sent | 12 Mar 2025, 18:15:41 UTC |
| Report deadline | 12 Apr 2025, 18:15:41 UTC |
| Received | 14 Mar 2025, 7:55:08 UTC |
| Server state | Over |
| Outcome | Success |
| Client state | Done |
| Exit status | 0 (0x00000000) |
| Computer ID | 10870564 |
| Run time | 17 hours 52 min 1 sec |
| CPU time | 2 days 15 hours 57 min 24 sec |
| Validate state | Valid |
| Credit | 2,820.51 |
| Device peak FLOPS | 26.63 GFLOPS |
| Application version | CMS Simulation v70.60 (vbox64_mt_mcore_cms) x86_64-pc-linux-gnu |
| Peak working set size | 3.75 GB |
| Peak swap size | 5.22 GB |
| Peak disk usage | 2.49 GB |
Stderr output
<core_client_version>7.24.1</core_client_version> <![CDATA[ <stderr_txt> 2025-03-12 17:17:59 (8920): vboxwrapper version 26208 2025-03-12 17:17:59 (8920): BOINC client version: 7.24.1 2025-03-12 17:17:59 (8920): Detected: VirtualBox VboxManage Interface (Version: 7.0.16) 2025-03-12 17:17:59 (8920): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds) 2025-03-12 17:17:59 (8920): Successfully copied 'init_data.xml' to the shared directory. 2025-03-12 17:17:59 (8920): Create VM. (boinc_2978af719339f470, slot#2) 2025-03-12 17:17:59 (8920): Setting Memory Size for VM. (4584MB) 2025-03-12 17:17:59 (8920): Setting CPU Count for VM. (4) 2025-03-12 17:17:59 (8920): Setting Chipset Options for VM. 2025-03-12 17:18:00 (8920): Setting Graphics Controller Options for VM. 2025-03-12 17:18:00 (8920): Setting Boot Options for VM. 2025-03-12 17:18:00 (8920): Setting Network Configuration for NAT. 2025-03-12 17:18:00 (8920): Enabling VM Network Access. 2025-03-12 17:18:00 (8920): Disabling USB Support for VM. 2025-03-12 17:18:00 (8920): Disabling COM Port Support for VM. 2025-03-12 17:18:00 (8920): Disabling LPT Port Support for VM. 2025-03-12 17:18:00 (8920): Disabling Audio Support for VM. 2025-03-12 17:18:00 (8920): Disabling Clipboard Support for VM. 2025-03-12 17:18:00 (8920): Disabling Drag and Drop Support for VM. 2025-03-12 17:18:00 (8920): Adding storage controller(s) to VM. 2025-03-12 17:18:01 (8920): Adding virtual disk drive to VM. (CMS_2025_01_16_prod.vdi) 2025-03-12 17:18:01 (8920): Adding network bandwidth throttle group to VM. (Defaulting to 1024GB) 2025-03-12 17:18:01 (8920): forwarding host port 39669 to guest port 80 2025-03-12 17:18:01 (8920): Enabling remote desktop for VM. 2025-03-12 17:18:01 (8920): Required extension pack not installed, remote desktop not enabled. 2025-03-12 17:18:01 (8920): Enabling shared directory for VM. 2025-03-12 17:18:02 (8920): Starting VM using VBoxManage interface. (boinc_2978af719339f470, slot#2) 2025-03-12 17:18:03 (8920): Successfully started VM. (PID = '9399') 2025-03-12 17:18:03 (8920): Reporting VM Process ID to BOINC. 2025-03-12 17:18:03 (8920): Guest Log: BIOS: VirtualBox 7.0.16 2025-03-12 17:18:03 (8920): Guest Log: CPUID EDX: 0x178bfbff 2025-03-12 17:18:03 (8920): Guest Log: BIOS: No PCI IDE controller, not probing IDE 2025-03-12 17:18:03 (8920): Guest Log: BIOS: AHCI 0-P#0: PCHS=16383/16/63 LCHS=1024/255/63 0x0000000002800000 sectors 2025-03-12 17:18:03 (8920): VM state change detected. (old = 'poweredoff', new = 'running') 2025-03-12 17:18:03 (8920): Detected: Web Application Enabled (http://localhost:39669) 2025-03-12 17:18:04 (8920): VM state change detected. (old = 'running', new = 'paused') 2025-03-12 17:18:21 (8920): Preference change detected 2025-03-12 17:18:21 (8920): Setting CPU throttle for VM. (100%) 2025-03-12 17:18:21 (8920): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 180 seconds) or (Vbox_job.xml: 600 seconds)) 2025-03-12 17:18:22 (8920): VM state change detected. (old = 'paused', new = 'running') 2025-03-12 17:18:23 (8920): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032 2025-03-12 17:18:23 (8920): Guest Log: BIOS: Booting from Hard Disk... 2025-03-12 17:18:26 (8920): Guest Log: BIOS: KBD: unsupported int 16h function 03 2025-03-12 17:18:26 (8920): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 2025-03-12 17:19:00 (8920): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds 2025-03-12 17:19:00 (8920): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000) 2025-03-12 17:19:01 (8920): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log 2025-03-12 17:19:01 (8920): Guest Log: 00:00:00.000308 main Log opened 2025-03-12T22:19:06.819661000Z 2025-03-12 17:19:01 (8920): Guest Log: 00:00:00.001308 main OS Product: Linux 2025-03-12 17:19:01 (8920): Guest Log: 00:00:00.001413 main OS Release: 4.14.232-19.cernvm.x86_64 2025-03-12 17:19:01 (8920): Guest Log: 00:00:00.001439 main OS Version: #1 SMP Fri Apr 30 17:12:25 CEST 2021 2025-03-12 17:19:01 (8920): Guest Log: 00:00:00.001458 main Executable: /usr/sbin/VBoxService 2025-03-12 17:19:01 (8920): Guest Log: 00:00:00.001458 main Process ID: 2234 2025-03-12 17:19:01 (8920): Guest Log: 00:00:00.001459 main Package type: LINUX_64BITS_GENERIC 2025-03-12 17:19:01 (8920): Guest Log: 00:00:00.002854 main 5.2.6 r120293 started. Verbose level = 0 2025-03-12 17:19:16 (8920): Guest Log: [INFO] Mounting the shared directory 2025-03-12 17:19:16 (8920): Guest Log: [INFO] Shared directory mounted, enabling vboxmonitor 2025-03-12 17:19:16 (8920): Guest Log: [INFO] Sourcing essential functions from /cvmfs/grid.cern.ch 2025-03-12 17:19:17 (8920): Guest Log: [INFO] Testing connection to cern.ch 2025-03-12 17:19:18 (8920): Guest Log: [INFO] Testing connection to VCCS 2025-03-12 17:19:18 (8920): Guest Log: [INFO] Testing connection to HTCondor 2025-03-12 17:19:19 (8920): Guest Log: [INFO] Testing connection to WMAgent 2025-03-12 17:19:19 (8920): Guest Log: [INFO] Testing connection to EOSCMS 2025-03-12 17:19:20 (8920): Guest Log: [INFO] Testing connection to CMS-Factory 2025-03-12 17:19:20 (8920): Guest Log: [INFO] Testing connection to CMS-Frontier 2025-03-12 17:19:21 (8920): Guest Log: [INFO] Testing connection to Frontier 2025-03-12 17:19:21 (8920): Guest Log: [INFO] Could not find a local HTTP proxy 2025-03-12 17:19:22 (8920): Guest Log: [INFO] CVMFS and Frontier will have to use DIRECT connections 2025-03-12 17:19:22 (8920): Guest Log: [INFO] This makes the application less efficient 2025-03-12 17:19:22 (8920): Guest Log: [INFO] It also puts higher load on the project servers 2025-03-12 17:19:22 (8920): Guest Log: [INFO] Setting up a local HTTP proxy is highly recommended 2025-03-12 17:19:22 (8920): Guest Log: [INFO] Advice can be found in the project forum 2025-03-12 17:19:23 (8920): Guest Log: [INFO] Reloading and probing the CVMFS configuration 2025-03-12 17:19:30 (8920): Guest Log: [INFO] Probing /cvmfs/grid.cern.ch... OK 2025-03-12 17:19:32 (8920): Guest Log: [INFO] Probing /cvmfs/cms.cern.ch... OK 2025-03-12 17:19:32 (8920): Guest Log: [INFO] Probing /cvmfs/cms-ib.cern.ch... OK 2025-03-12 17:19:34 (8920): Guest Log: [INFO] Probing /cvmfs/singularity.opensciencegrid.org... OK 2025-03-12 17:19:34 (8920): Guest Log: [INFO] Probing /cvmfs/oasis.opensciencegrid.org... OK 2025-03-12 17:19:35 (8920): Guest Log: [INFO] Excerpt from "cvmfs_config stat": VERSION HOST PROXY 2025-03-12 17:19:35 (8920): Guest Log: [INFO] 2.7.2.0 http://s1cern-cvmfs.openhtc.io DIRECT 2025-03-12 17:19:35 (8920): Guest Log: [INFO] Environment HTTP proxy: not set 2025-03-12 17:19:36 (8920): Guest Log: [INFO] Reading volunteer information 2025-03-12 17:20:27 (8920): Guest Log: [INFO] Using weak account key. 2025-03-12 17:20:27 (8920): Guest Log: [INFO] Requesting an X509 credential from LHC@home 2025-03-12 17:20:28 (8920): Guest Log: [INFO] Using weak account key. 2025-03-12 17:20:28 (8920): Guest Log: [INFO] Requesting an idtoken from LHC@home 2025-03-12 17:20:30 (8920): Guest Log: [INFO] CMS application starting. Check log files. 2025-03-12 17:25:24 (8920): VM state change detected. (old = 'running', new = 'paused') 2025-03-12 17:25:44 (8920): VM state change detected. (old = 'paused', new = 'running') 2025-03-12 17:32:37 (8920): VM state change detected. (old = 'running', new = 'paused') 2025-03-12 17:32:58 (8920): VM state change detected. (old = 'paused', new = 'running') 2025-03-12 17:46:13 (8920): VM state change detected. (old = 'running', new = 'paused') 2025-03-12 17:46:34 (8920): VM state change detected. (old = 'paused', new = 'running') 2025-03-12 17:52:46 (8920): VM state change detected. (old = 'running', new = 'paused') 2025-03-12 17:53:07 (8920): VM state change detected. (old = 'paused', new = 'running') 2025-03-12 18:01:00 (8920): VM state change detected. (old = 'running', new = 'paused') 2025-03-12 18:01:20 (8920): VM state change detected. (old = 'paused', new = 'running') 2025-03-12 18:06:03 (8920): VM state change detected. (old = 'running', new = 'paused') 2025-03-12 18:06:23 (8920): VM state change detected. (old = 'paused', new = 'running') 2025-03-12 18:13:46 (8920): VM state change detected. (old = 'running', new = 'paused') 2025-03-12 18:14:27 (8920): VM state change detected. (old = 'paused', new = 'running') 2025-03-12 18:15:07 (8920): VM state change detected. (old = 'running', new = 'paused') 2025-03-12 18:15:48 (8920): VM state change detected. (old = 'paused', new = 'running') 2025-03-12 18:15:58 (8920): VM state change detected. (old = 'running', new = 'paused') 2025-03-12 18:17:18 (8920): VM state change detected. (old = 'paused', new = 'running') 2025-03-12 18:17:28 (8920): VM state change detected. (old = 'running', new = 'paused') 2025-03-12 18:17:49 (8920): VM state change detected. (old = 'paused', new = 'running') 2025-03-12 18:17:59 (8920): VM state change detected. (old = 'running', new = 'paused') 2025-03-12 18:18:18 (8920): VM state change detected. (old = 'paused', new = 'running') 2025-03-12 18:18:28 (8920): VM state change detected. (old = 'running', new = 'paused') 2025-03-12 18:19:49 (8920): VM state change detected. (old = 'paused', new = 'running') 2025-03-12 18:19:59 (8920): VM state change detected. (old = 'running', new = 'paused') 2025-03-12 18:20:19 (8920): VM state change detected. (old = 'paused', new = 'running') 2025-03-12 18:20:29 (8920): VM state change detected. (old = 'running', new = 'paused') 2025-03-12 18:20:50 (8920): VM state change detected. (old = 'paused', new = 'running') 2025-03-12 18:21:00 (8920): VM state change detected. (old = 'running', new = 'paused') 2025-03-12 18:21:20 (8920): VM state change detected. (old = 'paused', new = 'running') 2025-03-12 18:21:29 (8920): VM state change detected. (old = 'running', new = 'paused') 2025-03-12 18:21:50 (8920): VM state change detected. (old = 'paused', new = 'running') 2025-03-12 18:22:00 (8920): VM state change detected. (old = 'running', new = 'paused') 2025-03-12 18:22:20 (8920): VM state change detected. (old = 'paused', new = 'running') 2025-03-12 18:22:31 (8920): VM state change detected. (old = 'running', new = 'paused') 2025-03-12 18:22:51 (8920): VM state change detected. (old = 'paused', new = 'running') 2025-03-12 18:23:20 (8920): VM state change detected. (old = 'running', new = 'paused') 2025-03-12 18:28:43 (8920): VM state change detected. (old = 'paused', new = 'running') 2025-03-12 18:29:03 (8920): VM state change detected. (old = 'running', new = 'paused') 2025-03-12 18:30:24 (8920): VM state change detected. (old = 'paused', new = 'running') 2025-03-12 18:30:34 (8920): VM state change detected. (old = 'running', new = 'paused') 2025-03-12 18:30:54 (8920): VM state change detected. (old = 'paused', new = 'running') 2025-03-12 18:31:04 (8920): VM state change detected. (old = 'running', new = 'paused') 2025-03-12 18:31:25 (8920): VM state change detected. (old = 'paused', new = 'running') 2025-03-12 18:31:35 (8920): VM state change detected. (old = 'running', new = 'paused') 2025-03-12 18:31:54 (8920): VM state change detected. (old = 'paused', new = 'running') 2025-03-12 18:32:04 (8920): VM state change detected. (old = 'running', new = 'paused') 2025-03-12 18:32:25 (8920): VM state change detected. (old = 'paused', new = 'running') 2025-03-12 18:32:35 (8920): VM state change detected. (old = 'running', new = 'paused') 2025-03-12 18:33:15 (8920): VM state change detected. (old = 'paused', new = 'running') 2025-03-12 18:33:25 (8920): VM state change detected. (old = 'running', new = 'paused') 2025-03-12 18:33:45 (8920): VM state change detected. (old = 'paused', new = 'running') 2025-03-12 18:34:05 (8920): VM state change detected. (old = 'running', new = 'paused') 2025-03-12 18:34:26 (8920): VM state change detected. (old = 'paused', new = 'running') 2025-03-12 18:34:36 (8920): VM state change detected. (old = 'running', new = 'paused') 2025-03-12 18:34:56 (8920): VM state change detected. (old = 'paused', new = 'running') 2025-03-12 18:35:06 (8920): VM state change detected. (old = 'running', new = 'paused') 2025-03-12 18:35:46 (8920): VM state change detected. (old = 'paused', new = 'running') 2025-03-12 18:36:37 (8920): VM state change detected. (old = 'running', new = 'paused') 2025-03-12 18:37:17 (8920): VM state change detected. (old = 'paused', new = 'running') 2025-03-12 18:37:37 (8920): VM state change detected. (old = 'running', new = 'paused') 2025-03-12 18:37:57 (8920): VM state change detected. (old = 'paused', new = 'running') 2025-03-12 18:38:37 (8920): VM state change detected. (old = 'running', new = 'paused') 2025-03-12 18:39:18 (8920): VM state change detected. (old = 'paused', new = 'running') 2025-03-12 18:39:59 (8920): VM state change detected. (old = 'running', new = 'paused') 2025-03-12 18:40:18 (8920): VM state change detected. (old = 'paused', new = 'running') 2025-03-12 18:40:28 (8920): VM state change detected. (old = 'running', new = 'paused') 2025-03-12 18:40:48 (8920): VM state change detected. (old = 'paused', new = 'running') 2025-03-12 18:41:29 (8920): VM state change detected. (old = 'running', new = 'paused') 2025-03-12 18:41:50 (8920): VM state change detected. (old = 'paused', new = 'running') 2025-03-12 18:42:00 (8920): VM state change detected. (old = 'running', new = 'paused') 2025-03-12 18:42:39 (8920): VM state change detected. (old = 'paused', new = 'running') 2025-03-12 18:43:00 (8920): VM state change detected. (old = 'running', new = 'paused') 2025-03-12 18:43:20 (8920): VM state change detected. (old = 'paused', new = 'running') 2025-03-12 18:43:30 (8920): VM state change detected. (old = 'running', new = 'paused') 2025-03-12 18:43:50 (8920): VM state change detected. (old = 'paused', new = 'running') 2025-03-12 18:44:00 (8920): VM state change detected. (old = 'running', new = 'paused') 2025-03-12 18:44:20 (8920): VM state change detected. (old = 'paused', new = 'running') 2025-03-12 18:44:30 (8920): VM state change detected. (old = 'running', new = 'paused') 2025-03-12 18:44:51 (8920): VM state change detected. (old = 'paused', new = 'running') 2025-03-12 18:45:01 (8920): VM state change detected. (old = 'running', new = 'paused') 2025-03-12 18:45:41 (8920): VM state change detected. (old = 'paused', new = 'running') 2025-03-12 18:46:01 (8920): VM state change detected. (old = 'running', new = 'paused') 2025-03-12 18:47:21 (8920): VM state change detected. (old = 'paused', new = 'running') 2025-03-12 18:47:31 (8920): VM state change detected. (old = 'running', new = 'paused') 2025-03-12 18:47:52 (8920): VM state change detected. (old = 'paused', new = 'running') 2025-03-12 18:48:33 (8920): VM state change detected. (old = 'running', new = 'paused') 2025-03-12 18:48:52 (8920): VM state change detected. (old = 'paused', new = 'running') 2025-03-12 18:49:02 (8920): VM state change detected. (old = 'running', new = 'paused') 2025-03-12 18:49:22 (8920): VM state change detected. (old = 'paused', new = 'running') 2025-03-12 18:49:33 (8920): VM state change detected. (old = 'running', new = 'paused') 2025-03-12 18:49:53 (8920): VM state change detected. (old = 'paused', new = 'running') 2025-03-12 18:50:03 (8920): VM state change detected. (old = 'running', new = 'paused') 2025-03-12 18:50:23 (8920): VM state change detected. (old = 'paused', new = 'running') 2025-03-12 18:50:34 (8920): VM state change detected. (old = 'running', new = 'paused') 2025-03-12 18:50:53 (8920): VM state change detected. (old = 'paused', new = 'running') 2025-03-12 18:51:34 (8920): VM state change detected. (old = 'running', new = 'paused') 2025-03-12 18:52:14 (8920): VM state change detected. (old = 'paused', new = 'running') 2025-03-12 18:52:34 (8920): VM state change detected. (old = 'running', new = 'paused') 2025-03-12 18:52:54 (8920): VM state change detected. (old = 'paused', new = 'running') 2025-03-12 18:53:04 (8920): VM state change detected. (old = 'running', new = 'paused') 2025-03-12 18:53:25 (8920): VM state change detected. (old = 'paused', new = 'running') 2025-03-12 18:53:35 (8920): VM state change detected. (old = 'running', new = 'paused') 2025-03-12 18:53:55 (8920): VM state change detected. (old = 'paused', new = 'running') 2025-03-12 18:54:04 (8920): VM state change detected. (old = 'running', new = 'paused') 2025-03-12 18:54:25 (8920): VM state change detected. (old = 'paused', new = 'running') 2025-03-12 18:55:05 (8920): VM state change detected. (old = 'running', new = 'paused') 2025-03-12 18:55:45 (8920): VM state change detected. (old = 'paused', new = 'running') 2025-03-12 18:56:36 (8920): VM state change detected. (old = 'running', new = 'paused') 2025-03-12 18:57:17 (8920): VM state change detected. (old = 'paused', new = 'running') 2025-03-12 18:57:36 (8920): VM state change detected. (old = 'running', new = 'paused') 2025-03-12 18:57:56 (8920): VM state change detected. (old = 'paused', new = 'running') 2025-03-12 18:58:07 (8920): VM state change detected. (old = 'running', new = 'paused') 2025-03-12 18:58:47 (8920): VM state change detected. (old = 'paused', new = 'running') 2025-03-12 18:59:08 (8920): VM state change detected. (old = 'running', new = 'paused') 2025-03-12 18:59:27 (8920): VM state change detected. (old = 'paused', new = 'running') 2025-03-12 18:59:58 (8920): VM state change detected. (old = 'running', new = 'paused') 2025-03-12 19:00:18 (8920): VM state change detected. (old = 'paused', new = 'running') 2025-03-12 19:00:58 (8920): VM state change detected. (old = 'running', new = 'paused') 2025-03-12 19:01:38 (8920): VM state change detected. (old = 'paused', new = 'running') 2025-03-12 19:01:59 (8920): VM state change detected. (old = 'running', new = 'paused') 2025-03-12 19:02:19 (8920): VM state change detected. (old = 'paused', new = 'running') 2025-03-12 19:18:16 (8920): VM state change detected. (old = 'running', new = 'paused') 2025-03-12 19:18:36 (8920): VM state change detected. (old = 'paused', new = 'running') 2025-03-12 19:26:00 (8920): VM state change detected. (old = 'running', new = 'paused') 2025-03-12 19:26:20 (8920): VM state change detected. (old = 'paused', new = 'running') 2025-03-12 19:31:02 (8920): VM state change detected. (old = 'running', new = 'paused') 2025-03-12 19:31:22 (8920): VM state change detected. (old = 'paused', new = 'running') 2025-03-12 19:33:21 (8920): Status Report: Job Duration: '64800.000000' 2025-03-12 19:33:21 (8920): Status Report: Elapsed Time: '6000.000000' 2025-03-12 19:33:21 (8920): Status Report: CPU Time: '18377.970000' 2025-03-12 20:12:12 (8920): VM state change detected. (old = 'running', new = 'paused') 2025-03-12 20:12:32 (8920): VM state change detected. (old = 'paused', new = 'running') 2025-03-12 20:51:31 (8920): VM state change detected. (old = 'running', new = 'paused') 2025-03-12 20:51:51 (8920): VM state change detected. (old = 'paused', new = 'running') 2025-03-12 21:13:05 (8920): Status Report: Job Duration: '64800.000000' 2025-03-12 21:13:05 (8920): Status Report: Elapsed Time: '12000.000000' 2025-03-12 21:13:05 (8920): Status Report: CPU Time: '39629.210000' 2025-03-12 21:14:12 (8920): VM state change detected. (old = 'running', new = 'paused') 2025-03-12 21:14:31 (8920): VM state change detected. (old = 'paused', new = 'running') 2025-03-12 21:19:54 (8920): VM state change detected. (old = 'running', new = 'paused') 2025-03-12 21:20:14 (8920): VM state change detected. (old = 'paused', new = 'running') 2025-03-12 21:25:37 (8920): VM state change detected. (old = 'running', new = 'paused') 2025-03-12 21:25:57 (8920): VM state change detected. (old = 'paused', new = 'running') 2025-03-12 21:27:48 (8920): VM state change detected. (old = 'running', new = 'paused') 2025-03-12 21:28:08 (8920): VM state change detected. (old = 'paused', new = 'running') 2025-03-12 22:25:15 (8920): VM state change detected. (old = 'running', new = 'paused') 2025-03-12 22:25:35 (8920): VM state change detected. (old = 'paused', new = 'running') 2025-03-12 22:30:58 (8920): VM state change detected. (old = 'running', new = 'paused') 2025-03-12 22:31:18 (8920): VM state change detected. (old = 'paused', new = 'running') 2025-03-12 22:46:46 (8920): VM state change detected. (old = 'running', new = 'paused') 2025-03-12 22:47:06 (8920): VM state change detected. (old = 'paused', new = 'running') 2025-03-12 22:54:34 (8920): Status Report: Job Duration: '64800.000000' 2025-03-12 22:54:34 (8920): Status Report: Elapsed Time: '18000.000000' 2025-03-12 22:54:34 (8920): Status Report: CPU Time: '62940.670000' 2025-03-12 23:05:14 (8920): VM state change detected. (old = 'running', new = 'paused') 2025-03-12 23:05:35 (8920): VM state change detected. (old = 'paused', new = 'running') 2025-03-12 23:12:57 (8920): VM state change detected. (old = 'running', new = 'paused') 2025-03-12 23:13:18 (8920): VM state change detected. (old = 'paused', new = 'running') 2025-03-12 23:16:19 (8920): VM state change detected. (old = 'running', new = 'paused') 2025-03-12 23:16:39 (8920): VM state change detected. (old = 'paused', new = 'running') 2025-03-13 00:01:01 (8920): VM state change detected. (old = 'running', new = 'paused') 2025-03-13 00:01:22 (8920): VM state change detected. (old = 'paused', new = 'running') 2025-03-13 00:03:42 (8920): VM state change detected. (old = 'running', new = 'paused') 2025-03-13 00:04:03 (8920): VM state change detected. (old = 'paused', new = 'running') 2025-03-13 00:35:25 (8920): Status Report: Job Duration: '64800.000000' 2025-03-13 00:35:25 (8920): Status Report: Elapsed Time: '24000.000000' 2025-03-13 00:35:25 (8920): Status Report: CPU Time: '85300.500000' 2025-03-13 01:29:57 (8920): VM state change detected. (old = 'running', new = 'paused') 2025-03-13 01:30:17 (8920): VM state change detected. (old = 'paused', new = 'running') 2025-03-13 01:40:12 (8920): VM state change detected. (old = 'running', new = 'paused') 2025-03-13 01:40:32 (8920): VM state change detected. (old = 'paused', new = 'running') 2025-03-13 01:43:33 (8920): VM state change detected. (old = 'running', new = 'paused') 2025-03-13 01:43:54 (8920): VM state change detected. (old = 'paused', new = 'running') 2025-03-13 02:00:43 (8920): VM state change detected. (old = 'running', new = 'paused') 2025-03-13 02:01:43 (8920): VM state change detected. (old = 'paused', new = 'running') 2025-03-13 02:02:03 (8920): VM state change detected. (old = 'running', new = 'paused') 2025-03-13 02:02:24 (8920): VM state change detected. (old = 'paused', new = 'running') 2025-03-13 02:04:25 (8920): VM state change detected. (old = 'running', new = 'paused') 2025-03-13 02:04:44 (8920): VM state change detected. (old = 'paused', new = 'running') 2025-03-13 02:07:16 (8920): VM state change detected. (old = 'running', new = 'paused') 2025-03-13 02:07:36 (8920): VM state change detected. (old = 'paused', new = 'running') 2025-03-13 02:17:11 (8920): VM state change detected. (old = 'running', new = 'paused') 2025-03-13 02:17:31 (8920): VM state change detected. (old = 'paused', new = 'running') 2025-03-13 02:17:59 (8920): Status Report: Job Duration: '64800.000000' 2025-03-13 02:17:59 (8920): Status Report: Elapsed Time: '30000.000000' 2025-03-13 02:17:59 (8920): Status Report: CPU Time: '107687.590000' 2025-03-13 02:31:58 (8920): VM state change detected. (old = 'running', new = 'paused') 2025-03-13 02:32:19 (8920): VM state change detected. (old = 'paused', new = 'running') 2025-03-13 02:43:44 (8920): VM state change detected. (old = 'running', new = 'paused') 2025-03-13 02:44:04 (8920): VM state change detected. (old = 'paused', new = 'running') 2025-03-13 02:53:49 (8920): VM state change detected. (old = 'running', new = 'paused') 2025-03-13 02:54:09 (8920): VM state change detected. (old = 'paused', new = 'running') 2025-03-13 03:02:03 (8920): VM state change detected. (old = 'running', new = 'paused') 2025-03-13 03:02:23 (8920): VM state change detected. (old = 'paused', new = 'running') 2025-03-13 03:09:07 (8920): VM state change detected. (old = 'running', new = 'paused') 2025-03-13 03:09:26 (8920): VM state change detected. (old = 'paused', new = 'running') 2025-03-13 03:22:52 (8920): VM state change detected. (old = 'running', new = 'paused') 2025-03-13 03:23:12 (8920): VM state change detected. (old = 'paused', new = 'running') 2025-03-13 03:31:07 (8920): VM state change detected. (old = 'running', new = 'paused') 2025-03-13 03:31:26 (8920): VM state change detected. (old = 'paused', new = 'running') 2025-03-13 03:36:39 (8920): VM state change detected. (old = 'running', new = 'paused') 2025-03-13 03:36:59 (8920): VM state change detected. (old = 'paused', new = 'running') 2025-03-13 03:43:02 (8920): VM state change detected. (old = 'running', new = 'paused') 2025-03-13 03:44:02 (8920): VM state change detected. (old = 'paused', new = 'running') 2025-03-13 03:51:16 (8920): VM state change detected. (old = 'running', new = 'paused') 2025-03-13 03:51:36 (8920): VM state change detected. (old = 'paused', new = 'running') 2025-03-13 03:51:47 (8920): VM state change detected. (old = 'running', new = 'paused') 2025-03-13 03:52:06 (8920): VM state change detected. (old = 'paused', new = 'running') 2025-03-13 04:01:33 (8920): Status Report: Job Duration: '64800.000000' 2025-03-13 04:01:33 (8920): Status Report: Elapsed Time: '36000.000000' 2025-03-13 04:01:33 (8920): Status Report: CPU Time: '131032.990000' 2025-03-13 04:26:32 (8920): VM state change detected. (old = 'running', new = 'paused') 2025-03-13 04:26:52 (8920): VM state change detected. (old = 'paused', new = 'running') 2025-03-13 04:29:24 (8920): VM state change detected. (old = 'running', new = 'paused') 2025-03-13 04:29:43 (8920): VM state change detected. (old = 'paused', new = 'running') 2025-03-13 04:30:14 (8920): VM state change detected. (old = 'running', new = 'paused') 2025-03-13 04:30:34 (8920): VM state change detected. (old = 'paused', new = 'running') 2025-03-13 04:30:44 (8920): VM state change detected. (old = 'running', new = 'paused') 2025-03-13 04:31:05 (8920): VM state change detected. (old = 'paused', new = 'running') 2025-03-13 04:58:36 (1894): vboxwrapper version 26208 2025-03-13 04:58:36 (1894): BOINC client version: 7.24.1 2025-03-13 04:58:37 (1894): Detected: VirtualBox VboxManage Interface (Version: 7.0.16) 2025-03-13 04:58:37 (1894): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds) 2025-03-13 04:58:37 (1894): Guest Log: BIOS: VirtualBox 7.0.16 2025-03-13 04:58:37 (1894): Guest Log: CPUID EDX: 0x178bfbff 2025-03-13 04:58:37 (1894): Guest Log: BIOS: No PCI IDE controller, not probing IDE 2025-03-13 04:58:37 (1894): Guest Log: BIOS: AHCI 0-P#0: PCHS=16383/16/63 LCHS=1024/255/63 0x0000000002800000 sectors 2025-03-13 04:58:37 (1894): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032 2025-03-13 04:58:37 (1894): Guest Log: BIOS: Booting from Hard Disk... 2025-03-13 04:58:37 (1894): Guest Log: BIOS: KBD: unsupported int 16h function 03 2025-03-13 04:58:37 (1894): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 2025-03-13 04:58:37 (1894): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds 2025-03-13 04:58:37 (1894): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000) 2025-03-13 04:58:37 (1894): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log 2025-03-13 04:58:37 (1894): Guest Log: 00:00:00.000308 main Log opened 2025-03-12T22:19:06.819661000Z 2025-03-13 04:58:37 (1894): Guest Log: 00:00:00.001308 main OS Product: Linux 2025-03-13 04:58:37 (1894): Guest Log: 00:00:00.001413 main OS Release: 4.14.232-19.cernvm.x86_64 2025-03-13 04:58:37 (1894): Guest Log: 00:00:00.001439 main OS Version: #1 SMP Fri Apr 30 17:12:25 CEST 2021 2025-03-13 04:58:37 (1894): Guest Log: 00:00:00.001458 main Executable: /usr/sbin/VBoxService 2025-03-13 04:58:37 (1894): Guest Log: 00:00:00.001458 main Process ID: 2234 2025-03-13 04:58:37 (1894): Guest Log: 00:00:00.001459 main Package type: LINUX_64BITS_GENERIC 2025-03-13 04:58:37 (1894): Guest Log: 00:00:00.002854 main 5.2.6 r120293 started. Verbose level = 0 2025-03-13 04:58:37 (1894): Guest Log: [INFO] Mounting the shared directory 2025-03-13 04:58:37 (1894): Guest Log: [INFO] Shared directory mounted, enabling vboxmonitor 2025-03-13 04:58:37 (1894): Guest Log: [INFO] Sourcing essential functions from /cvmfs/grid.cern.ch 2025-03-13 04:58:37 (1894): Guest Log: [INFO] Testing connection to cern.ch 2025-03-13 04:58:37 (1894): Guest Log: [INFO] Testing connection to VCCS 2025-03-13 04:58:37 (1894): Guest Log: [INFO] Testing connection to HTCondor 2025-03-13 04:58:37 (1894): Guest Log: [INFO] Testing connection to WMAgent 2025-03-13 04:58:37 (1894): Guest Log: [INFO] Testing connection to EOSCMS 2025-03-13 04:58:37 (1894): Guest Log: [INFO] Testing connection to CMS-Factory 2025-03-13 04:58:37 (1894): Guest Log: [INFO] Testing connection to CMS-Frontier 2025-03-13 04:58:37 (1894): Guest Log: [INFO] Testing connection to Frontier 2025-03-13 04:58:37 (1894): Guest Log: [INFO] Could not find a local HTTP proxy 2025-03-13 04:58:37 (1894): Guest Log: [INFO] CVMFS and Frontier will have to use DIRECT connections 2025-03-13 04:58:37 (1894): Guest Log: [INFO] This makes the application less efficient 2025-03-13 04:58:37 (1894): Guest Log: [INFO] It also puts higher load on the project servers 2025-03-13 04:58:37 (1894): Guest Log: [INFO] Setting up a local HTTP proxy is highly recommended 2025-03-13 04:58:37 (1894): Guest Log: [INFO] Advice can be found in the project forum 2025-03-13 04:58:37 (1894): Guest Log: [INFO] Reloading and probing the CVMFS configuration 2025-03-13 04:58:37 (1894): Guest Log: [INFO] Probing /cvmfs/grid.cern.ch... OK 2025-03-13 04:58:37 (1894): Guest Log: [INFO] Probing /cvmfs/cms.cern.ch... OK 2025-03-13 04:58:37 (1894): Guest Log: [INFO] Probing /cvmfs/cms-ib.cern.ch... OK 2025-03-13 04:58:37 (1894): Guest Log: [INFO] Probing /cvmfs/singularity.opensciencegrid.org... OK 2025-03-13 04:58:37 (1894): Guest Log: [INFO] Probing /cvmfs/oasis.opensciencegrid.org... OK 2025-03-13 04:58:37 (1894): Guest Log: [INFO] Excerpt from "cvmfs_config stat": VERSION HOST PROXY 2025-03-13 04:58:37 (1894): Guest Log: [INFO] 2.7.2.0 http://s1cern-cvmfs.openhtc.io DIRECT 2025-03-13 04:58:37 (1894): Guest Log: [INFO] Environment HTTP proxy: not set 2025-03-13 04:58:37 (1894): Guest Log: [INFO] Reading volunteer information 2025-03-13 04:58:37 (1894): Guest Log: [INFO] Using weak account key. 2025-03-13 04:58:37 (1894): Guest Log: [INFO] Requesting an X509 credential from LHC@home 2025-03-13 04:58:37 (1894): Guest Log: [INFO] Using weak account key. 2025-03-13 04:58:37 (1894): Guest Log: [INFO] Requesting an idtoken from LHC@home 2025-03-13 04:58:37 (1894): Guest Log: [INFO] CMS application starting. Check log files. 2025-03-13 04:58:37 (1894): Starting VM using VBoxManage interface. (boinc_2978af719339f470, slot#2) 2025-03-13 04:58:38 (1894): Successfully started VM. (PID = '1999') 2025-03-13 04:58:38 (1894): Reporting VM Process ID to BOINC. 2025-03-13 04:58:38 (1894): Guest Log: BIOS: VirtualBox 7.0.16 2025-03-13 04:58:38 (1894): Guest Log: CPUID EDX: 0x178bfbff 2025-03-13 04:58:38 (1894): Guest Log: BIOS: No PCI IDE controller, not probing IDE 2025-03-13 04:58:38 (1894): Guest Log: BIOS: AHCI 0-P#0: PCHS=16383/16/63 LCHS=1024/255/63 0x0000000002800000 sectors 2025-03-13 04:58:38 (1894): VM state change detected. (old = 'poweredoff', new = 'running') 2025-03-13 04:58:38 (1894): Detected: Web Application Enabled (http://localhost:39669) 2025-03-13 04:58:38 (1894): Status Report: Job Duration: '64800.000000' 2025-03-13 04:58:38 (1894): Status Report: Elapsed Time: '39272.000000' 2025-03-13 04:58:38 (1894): Status Report: CPU Time: '142477.000000' 2025-03-13 04:58:38 (1894): Preference change detected 2025-03-13 04:58:38 (1894): Setting CPU throttle for VM. (100%) 2025-03-13 04:58:38 (1894): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 180 seconds) or (Vbox_job.xml: 600 seconds)) 2025-03-13 04:58:40 (1894): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032 2025-03-13 04:58:40 (1894): Guest Log: BIOS: Booting from Hard Disk... 2025-03-13 04:58:43 (1894): Guest Log: BIOS: KBD: unsupported int 16h function 03 2025-03-13 04:58:43 (1894): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 2025-03-13 16:09:26 (1894): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds 2025-03-13 16:09:26 (1894): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000) 2025-03-13 16:09:27 (1894): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log 2025-03-13 16:09:27 (1894): Guest Log: 00:00:00.000220 main Log opened 2025-03-13T09:59:24.335682000Z 2025-03-13 16:09:27 (1894): Guest Log: 00:00:00.000312 main OS Product: Linux 2025-03-13 16:09:27 (1894): Guest Log: 00:00:00.000334 main OS Release: 4.14.232-19.cernvm.x86_64 2025-03-13 16:09:27 (1894): Guest Log: 00:00:00.000357 main OS Version: #1 SMP Fri Apr 30 17:12:25 CEST 2021 2025-03-13 16:09:27 (1894): Guest Log: 00:00:00.000435 main Executable: /usr/sbin/VBoxService 2025-03-13 16:09:27 (1894): Guest Log: 00:00:00.000436 main Process ID: 2222 2025-03-13 16:09:27 (1894): Guest Log: 00:00:00.000437 main Package type: LINUX_64BITS_GENERIC 2025-03-13 16:09:27 (1894): Guest Log: 00:00:00.001611 main 5.2.6 r120293 started. Verbose level = 0 2025-03-13 16:09:37 (1894): Guest Log: 00:00:10.006069 timesync vgsvcTimeSyncWorker: Radical guest time change: 40 212 455 920 000ns (GuestNow=1 741 900 176 795 611 000 ns GuestLast=1 741 859 964 339 691 000 ns fSetTimeLastLoop=true ) 2025-03-13 16:09:42 (1894): Guest Log: [INFO] Mounting the shared directory 2025-03-13 16:09:42 (1894): Guest Log: [INFO] Shared directory mounted, enabling vboxmonitor 2025-03-13 16:09:42 (1894): Guest Log: [INFO] Sourcing essential functions from /cvmfs/grid.cern.ch 2025-03-13 16:09:42 (1894): Guest Log: [INFO] Testing connection to cern.ch 2025-03-13 16:09:42 (1894): Guest Log: [INFO] Testing connection to VCCS 2025-03-13 16:09:43 (1894): Guest Log: [INFO] Testing connection to HTCondor 2025-03-13 16:09:43 (1894): Guest Log: [INFO] Testing connection to WMAgent 2025-03-13 16:09:44 (1894): Guest Log: [INFO] Testing connection to EOSCMS 2025-03-13 16:09:45 (1894): Guest Log: [INFO] Testing connection to CMS-Factory 2025-03-13 16:09:45 (1894): Guest Log: [INFO] Testing connection to CMS-Frontier 2025-03-13 16:09:46 (1894): Guest Log: [INFO] Testing connection to Frontier 2025-03-13 16:09:46 (1894): Guest Log: [INFO] Could not find a local HTTP proxy 2025-03-13 16:09:46 (1894): Guest Log: [INFO] CVMFS and Frontier will have to use DIRECT connections 2025-03-13 16:09:47 (1894): Guest Log: [INFO] This makes the application less efficient 2025-03-13 16:09:47 (1894): Guest Log: [INFO] It also puts higher load on the project servers 2025-03-13 16:09:47 (1894): Guest Log: [INFO] Setting up a local HTTP proxy is highly recommended 2025-03-13 16:09:47 (1894): Guest Log: [INFO] Advice can be found in the project forum 2025-03-13 16:09:47 (1894): Guest Log: [INFO] Reloading and probing the CVMFS configuration 2025-03-13 16:09:54 (1894): Guest Log: [INFO] Probing /cvmfs/grid.cern.ch... OK 2025-03-13 16:09:57 (1894): Guest Log: [INFO] Probing /cvmfs/cms.cern.ch... OK 2025-03-13 16:09:59 (1894): Guest Log: [INFO] Probing /cvmfs/cms-ib.cern.ch... OK 2025-03-13 16:10:01 (1894): Guest Log: [INFO] Probing /cvmfs/oasis.opensciencegrid.org... OK 2025-03-13 16:10:01 (1894): Guest Log: [INFO] Probing /cvmfs/singularity.opensciencegrid.org... OK 2025-03-13 16:10:01 (1894): Guest Log: [INFO] Excerpt from "cvmfs_config stat": VERSION HOST PROXY 2025-03-13 16:10:01 (1894): Guest Log: [INFO] 2.7.2.0 http://s1bnl-cvmfs.openhtc.io DIRECT 2025-03-13 16:10:02 (1894): Guest Log: [INFO] Environment HTTP proxy: not set 2025-03-13 16:10:02 (1894): Guest Log: [INFO] Reading volunteer information 2025-03-13 16:10:09 (1894): Guest Log: [INFO] Using weak account key. 2025-03-13 16:10:09 (1894): Guest Log: [INFO] Requesting an X509 credential from LHC@home 2025-03-13 16:10:10 (1894): Guest Log: [INFO] Using weak account key. 2025-03-13 16:10:10 (1894): Guest Log: [INFO] Requesting an idtoken from LHC@home 2025-03-13 16:10:11 (1894): Guest Log: [INFO] CMS application starting. Check log files. 2025-03-13 16:13:01 (1894): VM state change detected. (old = 'running', new = 'paused') 2025-03-13 16:13:21 (1894): VM state change detected. (old = 'paused', new = 'running') 2025-03-13 16:15:21 (1894): VM state change detected. (old = 'running', new = 'paused') 2025-03-13 16:15:42 (1894): VM state change detected. (old = 'paused', new = 'running') 2025-03-13 16:17:02 (1894): VM state change detected. (old = 'running', new = 'paused') 2025-03-13 16:17:22 (1894): VM state change detected. (old = 'paused', new = 'running') 2025-03-13 16:38:02 (1894): VM state change detected. (old = 'running', new = 'paused') 2025-03-13 16:38:42 (1894): VM state change detected. (old = 'paused', new = 'running') 2025-03-13 16:43:35 (1894): VM state change detected. (old = 'running', new = 'paused') 2025-03-13 16:43:55 (1894): VM state change detected. (old = 'paused', new = 'running') 2025-03-13 16:44:05 (1894): VM state change detected. (old = 'running', new = 'paused') 2025-03-13 16:44:24 (1894): VM state change detected. (old = 'paused', new = 'running') 2025-03-13 17:12:08 (1894): VM state change detected. (old = 'running', new = 'paused') 2025-03-13 17:12:28 (1894): VM state change detected. (old = 'paused', new = 'running') 2025-03-13 17:15:30 (1894): VM state change detected. (old = 'running', new = 'paused') 2025-03-13 17:15:50 (1894): VM state change detected. (old = 'paused', new = 'running') 2025-03-13 17:16:00 (1894): VM state change detected. (old = 'running', new = 'paused') 2025-03-13 17:16:20 (1894): VM state change detected. (old = 'paused', new = 'running') 2025-03-13 17:30:17 (1894): VM state change detected. (old = 'running', new = 'paused') 2025-03-13 17:30:38 (1894): VM state change detected. (old = 'paused', new = 'running') 2025-03-13 17:51:39 (1894): Status Report: Job Duration: '64800.000000' 2025-03-13 17:51:39 (1894): Status Report: Elapsed Time: '45272.000000' 2025-03-13 17:51:39 (1894): Status Report: CPU Time: '162677.030000' 2025-03-13 18:06:15 (1894): VM state change detected. (old = 'running', new = 'paused') 2025-03-13 18:06:35 (1894): VM state change detected. (old = 'paused', new = 'running') 2025-03-13 18:25:04 (1894): VM state change detected. (old = 'running', new = 'paused') 2025-03-13 18:25:24 (1894): VM state change detected. (old = 'paused', new = 'running') 2025-03-13 18:43:12 (1894): VM state change detected. (old = 'running', new = 'paused') 2025-03-13 18:43:33 (1894): VM state change detected. (old = 'paused', new = 'running') 2025-03-13 18:50:16 (1894): VM state change detected. (old = 'running', new = 'paused') 2025-03-13 18:50:36 (1894): VM state change detected. (old = 'paused', new = 'running') 2025-03-13 19:32:07 (1894): Status Report: Job Duration: '64800.000000' 2025-03-13 19:32:07 (1894): Status Report: Elapsed Time: '51272.000000' 2025-03-13 19:32:07 (1894): Status Report: CPU Time: '183879.130000' 2025-03-13 19:49:34 (1894): VM state change detected. (old = 'running', new = 'paused') 2025-03-13 19:49:54 (1894): VM state change detected. (old = 'paused', new = 'running') 2025-03-13 20:45:51 (1894): VM state change detected. (old = 'running', new = 'paused') 2025-03-13 20:46:12 (1894): VM state change detected. (old = 'paused', new = 'running') 2025-03-13 21:11:53 (1894): Status Report: Job Duration: '64800.000000' 2025-03-13 21:11:53 (1894): Status Report: Elapsed Time: '57272.000000' 2025-03-13 21:11:53 (1894): Status Report: CPU Time: '207189.940000' 2025-03-13 22:34:43 (1898): vboxwrapper version 26208 2025-03-13 22:34:43 (1898): BOINC client version: 7.24.1 2025-03-13 22:34:43 (1898): Detected: VirtualBox VboxManage Interface (Version: 7.0.16) 2025-03-13 22:34:43 (1898): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds) 2025-03-13 22:34:43 (1898): Guest Log: BIOS: VirtualBox 7.0.16 2025-03-13 22:34:43 (1898): Guest Log: CPUID EDX: 0x178bfbff 2025-03-13 22:34:43 (1898): Guest Log: BIOS: No PCI IDE controller, not probing IDE 2025-03-13 22:34:43 (1898): Guest Log: BIOS: AHCI 0-P#0: PCHS=16383/16/63 LCHS=1024/255/63 0x0000000002800000 sectors 2025-03-13 22:34:43 (1898): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032 2025-03-13 22:34:43 (1898): Guest Log: BIOS: Booting from Hard Disk... 2025-03-13 22:34:43 (1898): Guest Log: BIOS: KBD: unsupported int 16h function 03 2025-03-13 22:34:43 (1898): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 2025-03-13 22:34:43 (1898): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds 2025-03-13 22:34:43 (1898): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000) 2025-03-13 22:34:43 (1898): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log 2025-03-13 22:34:43 (1898): Guest Log: 00:00:00.000220 main Log opened 2025-03-13T09:59:24.335682000Z 2025-03-13 22:34:43 (1898): Guest Log: 00:00:00.000312 main OS Product: Linux 2025-03-13 22:34:43 (1898): Guest Log: 00:00:00.000334 main OS Release: 4.14.232-19.cernvm.x86_64 2025-03-13 22:34:43 (1898): Guest Log: 00:00:00.000357 main OS Version: #1 SMP Fri Apr 30 17:12:25 CEST 2021 2025-03-13 22:34:43 (1898): Guest Log: 00:00:00.000435 main Executable: /usr/sbin/VBoxService 2025-03-13 22:34:43 (1898): Guest Log: 00:00:00.000436 main Process ID: 2222 2025-03-13 22:34:43 (1898): Guest Log: 00:00:00.000437 main Package type: LINUX_64BITS_GENERIC 2025-03-13 22:34:43 (1898): Guest Log: 00:00:00.001611 main 5.2.6 r120293 started. Verbose level = 0 2025-03-13 22:34:43 (1898): Guest Log: 00:00:10.006069 timesync vgsvcTimeSyncWorker: Radical guest time change: 40 212 455 920 000ns (GuestNow=1 741 900 176 795 611 000 ns GuestLast=1 741 859 964 339 691 000 ns fSetTimeLastLoop=true ) 2025-03-13 22:34:43 (1898): Guest Log: [INFO] Mounting the shared directory 2025-03-13 22:34:43 (1898): Guest Log: [INFO] Shared directory mounted, enabling vboxmonitor 2025-03-13 22:34:43 (1898): Guest Log: [INFO] Sourcing essential functions from /cvmfs/grid.cern.ch 2025-03-13 22:34:43 (1898): Guest Log: [INFO] Testing connection to cern.ch 2025-03-13 22:34:43 (1898): Guest Log: [INFO] Testing connection to VCCS 2025-03-13 22:34:43 (1898): Guest Log: [INFO] Testing connection to HTCondor 2025-03-13 22:34:43 (1898): Guest Log: [INFO] Testing connection to WMAgent 2025-03-13 22:34:43 (1898): Guest Log: [INFO] Testing connection to EOSCMS 2025-03-13 22:34:43 (1898): Guest Log: [INFO] Testing connection to CMS-Factory 2025-03-13 22:34:43 (1898): Guest Log: [INFO] Testing connection to CMS-Frontier 2025-03-13 22:34:43 (1898): Guest Log: [INFO] Testing connection to Frontier 2025-03-13 22:34:43 (1898): Guest Log: [INFO] Could not find a local HTTP proxy 2025-03-13 22:34:43 (1898): Guest Log: [INFO] CVMFS and Frontier will have to use DIRECT connections 2025-03-13 22:34:43 (1898): Guest Log: [INFO] This makes the application less efficient 2025-03-13 22:34:43 (1898): Guest Log: [INFO] It also puts higher load on the project servers 2025-03-13 22:34:43 (1898): Guest Log: [INFO] Setting up a local HTTP proxy is highly recommended 2025-03-13 22:34:43 (1898): Guest Log: [INFO] Advice can be found in the project forum 2025-03-13 22:34:43 (1898): Guest Log: [INFO] Reloading and probing the CVMFS configuration 2025-03-13 22:34:43 (1898): Guest Log: [INFO] Probing /cvmfs/grid.cern.ch... OK 2025-03-13 22:34:43 (1898): Guest Log: [INFO] Probing /cvmfs/cms.cern.ch... OK 2025-03-13 22:34:43 (1898): Guest Log: [INFO] Probing /cvmfs/cms-ib.cern.ch... OK 2025-03-13 22:34:43 (1898): Guest Log: [INFO] Probing /cvmfs/oasis.opensciencegrid.org... OK 2025-03-13 22:34:43 (1898): Guest Log: [INFO] Probing /cvmfs/singularity.opensciencegrid.org... OK 2025-03-13 22:34:43 (1898): Guest Log: [INFO] Excerpt from "cvmfs_config stat": VERSION HOST PROXY 2025-03-13 22:34:43 (1898): Guest Log: [INFO] 2.7.2.0 http://s1bnl-cvmfs.openhtc.io DIRECT 2025-03-13 22:34:43 (1898): Guest Log: [INFO] Environment HTTP proxy: not set 2025-03-13 22:34:43 (1898): Guest Log: [INFO] Reading volunteer information 2025-03-13 22:34:43 (1898): Guest Log: [INFO] Using weak account key. 2025-03-13 22:34:43 (1898): Guest Log: [INFO] Requesting an X509 credential from LHC@home 2025-03-13 22:34:43 (1898): Guest Log: [INFO] Using weak account key. 2025-03-13 22:34:43 (1898): Guest Log: [INFO] Requesting an idtoken from LHC@home 2025-03-13 22:34:43 (1898): Guest Log: [INFO] CMS application starting. Check log files. 2025-03-13 22:34:43 (1898): Starting VM using VBoxManage interface. (boinc_2978af719339f470, slot#2) 2025-03-13 22:34:44 (1898): Successfully started VM. (PID = '2003') 2025-03-13 22:34:44 (1898): Reporting VM Process ID to BOINC. 2025-03-13 22:34:44 (1898): Guest Log: BIOS: VirtualBox 7.0.16 2025-03-13 22:34:44 (1898): Guest Log: CPUID EDX: 0x178bfbff 2025-03-13 22:34:44 (1898): Guest Log: BIOS: No PCI IDE controller, not probing IDE 2025-03-13 22:34:44 (1898): Guest Log: BIOS: AHCI 0-P#0: PCHS=16383/16/63 LCHS=1024/255/63 0x0000000002800000 sectors 2025-03-13 22:34:44 (1898): VM state change detected. (old = 'poweredoff', new = 'running') 2025-03-13 22:34:44 (1898): Detected: Web Application Enabled (http://localhost:39669) 2025-03-13 22:34:44 (1898): Status Report: Job Duration: '64800.000000' 2025-03-13 22:34:44 (1898): Status Report: Elapsed Time: '62312.000000' 2025-03-13 22:34:44 (1898): Status Report: CPU Time: '225189.160000' 2025-03-13 22:34:44 (1898): Preference change detected 2025-03-13 22:34:44 (1898): Setting CPU throttle for VM. (100%) 2025-03-13 22:34:45 (1898): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 180 seconds) or (Vbox_job.xml: 600 seconds)) 2025-03-13 22:34:46 (1898): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032 2025-03-13 22:34:46 (1898): Guest Log: BIOS: Booting from Hard Disk... 2025-03-13 22:34:49 (1898): Guest Log: BIOS: KBD: unsupported int 16h function 03 2025-03-13 22:34:49 (1898): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 2025-03-14 01:32:10 (1898): VM state change detected. (old = 'running', new = 'paused') 2025-03-14 01:32:30 (1898): VM state change detected. (old = 'paused', new = 'running') 2025-03-14 01:32:42 (1898): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds 2025-03-14 01:32:42 (1898): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000) 2025-03-14 01:32:43 (1898): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log 2025-03-14 01:32:43 (1898): Guest Log: 00:00:00.000114 main Log opened 2025-03-14T03:35:35.761412000Z 2025-03-14 01:32:43 (1898): Guest Log: 00:00:00.000233 main OS Product: Linux 2025-03-14 01:32:43 (1898): Guest Log: 00:00:00.000253 main OS Release: 4.14.232-19.cernvm.x86_64 2025-03-14 01:32:43 (1898): Guest Log: 00:00:00.000267 main OS Version: #1 SMP Fri Apr 30 17:12:25 CEST 2021 2025-03-14 01:32:43 (1898): Guest Log: 00:00:00.000280 main Executable: /usr/sbin/VBoxService 2025-03-14 01:32:43 (1898): Guest Log: 00:00:00.000293 main Process ID: 2234 2025-03-14 01:32:43 (1898): Guest Log: 00:00:00.000293 main Package type: LINUX_64BITS_GENERIC 2025-03-14 01:32:43 (1898): Guest Log: 00:00:00.002223 main 5.2.6 r120293 started. Verbose level = 0 2025-03-14 01:32:53 (1898): Guest Log: 00:00:10.026138 timesync vgsvcTimeSyncWorker: Radical guest time change: 10 637 300 127 000ns (GuestNow=1 741 933 973 064 738 000 ns GuestLast=1 741 923 335 764 611 000 ns fSetTimeLastLoop=true ) 2025-03-14 01:32:58 (1898): Guest Log: [INFO] Mounting the shared directory 2025-03-14 01:32:58 (1898): Guest Log: [INFO] Shared directory mounted, enabling vboxmonitor 2025-03-14 01:32:58 (1898): Guest Log: [INFO] Sourcing essential functions from /cvmfs/grid.cern.ch 2025-03-14 01:32:58 (1898): Guest Log: [INFO] Testing connection to cern.ch 2025-03-14 01:32:59 (1898): Guest Log: [INFO] Testing connection to VCCS 2025-03-14 01:32:59 (1898): Guest Log: [INFO] Testing connection to HTCondor 2025-03-14 01:33:00 (1898): Guest Log: [INFO] Testing connection to WMAgent 2025-03-14 01:33:00 (1898): Guest Log: [INFO] Testing connection to EOSCMS 2025-03-14 01:33:01 (1898): Guest Log: [INFO] Testing connection to CMS-Factory 2025-03-14 01:33:01 (1898): Guest Log: [INFO] Testing connection to CMS-Frontier 2025-03-14 01:33:02 (1898): Guest Log: [INFO] Testing connection to Frontier 2025-03-14 01:33:03 (1898): Guest Log: [INFO] Could not find a local HTTP proxy 2025-03-14 01:33:03 (1898): Guest Log: [INFO] CVMFS and Frontier will have to use DIRECT connections 2025-03-14 01:33:03 (1898): Guest Log: [INFO] This makes the application less efficient 2025-03-14 01:33:04 (1898): Guest Log: [INFO] It also puts higher load on the project servers 2025-03-14 01:33:04 (1898): Guest Log: [INFO] Setting up a local HTTP proxy is highly recommended 2025-03-14 01:33:04 (1898): Guest Log: [INFO] Advice can be found in the project forum 2025-03-14 01:33:04 (1898): Guest Log: [INFO] Reloading and probing the CVMFS configuration 2025-03-14 01:33:10 (1898): VM state change detected. (old = 'running', new = 'paused') 2025-03-14 01:33:31 (1898): VM state change detected. (old = 'paused', new = 'running') 2025-03-14 01:33:33 (1898): Guest Log: [INFO] Probing /cvmfs/grid.cern.ch... OK 2025-03-14 01:33:36 (1898): Guest Log: [INFO] Probing /cvmfs/oasis.opensciencegrid.org... OK 2025-03-14 01:33:38 (1898): Guest Log: [INFO] Probing /cvmfs/cms-ib.cern.ch... OK 2025-03-14 01:33:39 (1898): Guest Log: [INFO] Probing /cvmfs/cms.cern.ch... OK 2025-03-14 01:33:39 (1898): Guest Log: [INFO] Probing /cvmfs/singularity.opensciencegrid.org... OK 2025-03-14 01:33:40 (1898): Guest Log: [INFO] Excerpt from "cvmfs_config stat": VERSION HOST PROXY 2025-03-14 01:33:40 (1898): Guest Log: [INFO] 2.7.2.0 http://s1bnl-cvmfs.openhtc.io DIRECT 2025-03-14 01:33:40 (1898): Guest Log: [INFO] Environment HTTP proxy: not set 2025-03-14 01:33:40 (1898): Guest Log: [INFO] Reading volunteer information 2025-03-14 01:33:50 (1898): Guest Log: [INFO] Using weak account key. 2025-03-14 01:33:50 (1898): Guest Log: [INFO] Requesting an X509 credential from LHC@home 2025-03-14 01:33:52 (1898): Guest Log: [INFO] Using weak account key. 2025-03-14 01:33:52 (1898): Guest Log: [INFO] Requesting an idtoken from LHC@home 2025-03-14 01:33:53 (1898): Guest Log: [INFO] CMS application starting. Check log files. 2025-03-14 01:34:20 (1898): VM state change detected. (old = 'running', new = 'paused') 2025-03-14 01:34:41 (1898): VM state change detected. (old = 'paused', new = 'running') 2025-03-14 01:40:43 (1898): VM state change detected. (old = 'running', new = 'paused') 2025-03-14 01:43:04 (1898): VM state change detected. (old = 'paused', new = 'running') 2025-03-14 01:43:15 (1898): VM state change detected. (old = 'running', new = 'paused') 2025-03-14 01:48:37 (1898): VM state change detected. (old = 'paused', new = 'running') 2025-03-14 01:49:27 (1898): VM state change detected. (old = 'running', new = 'paused') 2025-03-14 01:50:08 (1898): VM state change detected. (old = 'paused', new = 'running') 2025-03-14 01:50:18 (1898): VM state change detected. (old = 'running', new = 'paused') 2025-03-14 01:50:37 (1898): VM state change detected. (old = 'paused', new = 'running') 2025-03-14 01:50:57 (1898): VM state change detected. (old = 'running', new = 'paused') 2025-03-14 01:58:41 (1898): VM state change detected. (old = 'paused', new = 'running') 2025-03-14 01:58:51 (1898): VM state change detected. (old = 'running', new = 'paused') 2025-03-14 02:02:13 (1898): VM state change detected. (old = 'paused', new = 'running') 2025-03-14 02:02:22 (1898): VM state change detected. (old = 'running', new = 'paused') 2025-03-14 02:03:23 (1897): vboxwrapper version 26208 2025-03-14 02:03:23 (1897): BOINC client version: 7.24.1 2025-03-14 02:03:23 (1897): Detected: VirtualBox VboxManage Interface (Version: 7.0.16) 2025-03-14 02:03:23 (1897): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds) 2025-03-14 02:03:23 (1897): Guest Log: BIOS: VirtualBox 7.0.16 2025-03-14 02:03:23 (1897): Guest Log: CPUID EDX: 0x178bfbff 2025-03-14 02:03:23 (1897): Guest Log: BIOS: No PCI IDE controller, not probing IDE 2025-03-14 02:03:23 (1897): Guest Log: BIOS: AHCI 0-P#0: PCHS=16383/16/63 LCHS=1024/255/63 0x0000000002800000 sectors 2025-03-14 02:03:23 (1897): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032 2025-03-14 02:03:23 (1897): Guest Log: BIOS: Booting from Hard Disk... 2025-03-14 02:03:23 (1897): Guest Log: BIOS: KBD: unsupported int 16h function 03 2025-03-14 02:03:23 (1897): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 2025-03-14 02:03:23 (1897): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds 2025-03-14 02:03:23 (1897): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000) 2025-03-14 02:03:23 (1897): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log 2025-03-14 02:03:23 (1897): Guest Log: 00:00:00.000114 main Log opened 2025-03-14T03:35:35.761412000Z 2025-03-14 02:03:23 (1897): Guest Log: 00:00:00.000233 main OS Product: Linux 2025-03-14 02:03:23 (1897): Guest Log: 00:00:00.000253 main OS Release: 4.14.232-19.cernvm.x86_64 2025-03-14 02:03:23 (1897): Guest Log: 00:00:00.000267 main OS Version: #1 SMP Fri Apr 30 17:12:25 CEST 2021 2025-03-14 02:03:23 (1897): Guest Log: 00:00:00.000280 main Executable: /usr/sbin/VBoxService 2025-03-14 02:03:23 (1897): Guest Log: 00:00:00.000293 main Process ID: 2234 2025-03-14 02:03:23 (1897): Guest Log: 00:00:00.000293 main Package type: LINUX_64BITS_GENERIC 2025-03-14 02:03:23 (1897): Guest Log: 00:00:00.002223 main 5.2.6 r120293 started. Verbose level = 0 2025-03-14 02:03:23 (1897): Guest Log: 00:00:10.026138 timesync vgsvcTimeSyncWorker: Radical guest time change: 10 637 300 127 000ns (GuestNow=1 741 933 973 064 738 000 ns GuestLast=1 741 923 335 764 611 000 ns fSetTimeLastLoop=true ) 2025-03-14 02:03:23 (1897): Guest Log: [INFO] Mounting the shared directory 2025-03-14 02:03:23 (1897): Guest Log: [INFO] Shared directory mounted, enabling vboxmonitor 2025-03-14 02:03:23 (1897): Guest Log: [INFO] Sourcing essential functions from /cvmfs/grid.cern.ch 2025-03-14 02:03:23 (1897): Guest Log: [INFO] Testing connection to cern.ch 2025-03-14 02:03:23 (1897): Guest Log: [INFO] Testing connection to VCCS 2025-03-14 02:03:23 (1897): Guest Log: [INFO] Testing connection to HTCondor 2025-03-14 02:03:23 (1897): Guest Log: [INFO] Testing connection to WMAgent 2025-03-14 02:03:23 (1897): Guest Log: [INFO] Testing connection to EOSCMS 2025-03-14 02:03:23 (1897): Guest Log: [INFO] Testing connection to CMS-Factory 2025-03-14 02:03:23 (1897): Guest Log: [INFO] Testing connection to CMS-Frontier 2025-03-14 02:03:23 (1897): Guest Log: [INFO] Testing connection to Frontier 2025-03-14 02:03:23 (1897): Guest Log: [INFO] Could not find a local HTTP proxy 2025-03-14 02:03:23 (1897): Guest Log: [INFO] CVMFS and Frontier will have to use DIRECT connections 2025-03-14 02:03:23 (1897): Guest Log: [INFO] This makes the application less efficient 2025-03-14 02:03:23 (1897): Guest Log: [INFO] It also puts higher load on the project servers 2025-03-14 02:03:23 (1897): Guest Log: [INFO] Setting up a local HTTP proxy is highly recommended 2025-03-14 02:03:23 (1897): Guest Log: [INFO] Advice can be found in the project forum 2025-03-14 02:03:23 (1897): Guest Log: [INFO] Reloading and probing the CVMFS configuration 2025-03-14 02:03:23 (1897): Guest Log: [INFO] Probing /cvmfs/grid.cern.ch... OK 2025-03-14 02:03:23 (1897): Guest Log: [INFO] Probing /cvmfs/oasis.opensciencegrid.org... OK 2025-03-14 02:03:23 (1897): Guest Log: [INFO] Probing /cvmfs/cms-ib.cern.ch... OK 2025-03-14 02:03:23 (1897): Guest Log: [INFO] Probing /cvmfs/cms.cern.ch... OK 2025-03-14 02:03:23 (1897): Guest Log: [INFO] Probing /cvmfs/singularity.opensciencegrid.org... OK 2025-03-14 02:03:23 (1897): Guest Log: [INFO] Excerpt from "cvmfs_config stat": VERSION HOST PROXY 2025-03-14 02:03:23 (1897): Guest Log: [INFO] 2.7.2.0 http://s1bnl-cvmfs.openhtc.io DIRECT 2025-03-14 02:03:23 (1897): Guest Log: [INFO] Environment HTTP proxy: not set 2025-03-14 02:03:23 (1897): Guest Log: [INFO] Reading volunteer information 2025-03-14 02:03:23 (1897): Guest Log: [INFO] Using weak account key. 2025-03-14 02:03:23 (1897): Guest Log: [INFO] Requesting an X509 credential from LHC@home 2025-03-14 02:03:23 (1897): Guest Log: [INFO] Using weak account key. 2025-03-14 02:03:23 (1897): Guest Log: [INFO] Requesting an idtoken from LHC@home 2025-03-14 02:03:23 (1897): Guest Log: [INFO] CMS application starting. Check log files. 2025-03-14 02:03:23 (1897): Starting VM using VBoxManage interface. (boinc_2978af719339f470, slot#2) 2025-03-14 02:03:25 (1897): Successfully started VM. (PID = '2002') 2025-03-14 02:03:25 (1897): Reporting VM Process ID to BOINC. 2025-03-14 02:03:25 (1897): Guest Log: BIOS: VirtualBox 7.0.16 2025-03-14 02:03:25 (1897): Guest Log: CPUID EDX: 0x178bfbff 2025-03-14 02:03:25 (1897): Guest Log: BIOS: No PCI IDE controller, not probing IDE 2025-03-14 02:03:25 (1897): Guest Log: BIOS: AHCI 0-P#0: PCHS=16383/16/63 LCHS=1024/255/63 0x0000000002800000 sectors 2025-03-14 02:03:25 (1897): VM state change detected. (old = 'poweredoff', new = 'running') 2025-03-14 02:03:25 (1897): Detected: Web Application Enabled (http://localhost:39669) 2025-03-14 02:03:25 (1897): Status Report: Job Duration: '64800.000000' 2025-03-14 02:03:25 (1897): Status Report: Elapsed Time: '62893.000000' 2025-03-14 02:03:25 (1897): Status Report: CPU Time: '225445.890000' 2025-03-14 02:03:25 (1897): Preference change detected 2025-03-14 02:03:25 (1897): Setting CPU throttle for VM. (100%) 2025-03-14 02:03:25 (1897): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 180 seconds) or (Vbox_job.xml: 600 seconds)) 2025-03-14 02:03:27 (1897): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032 2025-03-14 02:03:27 (1897): Guest Log: BIOS: Booting from Hard Disk... 2025-03-14 02:03:29 (1897): Guest Log: BIOS: KBD: unsupported int 16h function 03 2025-03-14 02:03:29 (1897): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 2025-03-14 02:22:36 (1897): VM state change detected. (old = 'running', new = 'paused') 2025-03-14 02:22:55 (1897): VM state change detected. (old = 'paused', new = 'running') 2025-03-14 02:23:07 (1897): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds 2025-03-14 02:23:07 (1897): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000) 2025-03-14 02:23:07 (1897): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log 2025-03-14 02:23:07 (1897): Guest Log: 00:00:00.000164 main Log opened 2025-03-14T07:04:15.652252000Z 2025-03-14 02:23:07 (1897): Guest Log: 00:00:00.000275 main OS Product: Linux 2025-03-14 02:23:07 (1897): Guest Log: 00:00:00.000297 main OS Release: 4.14.232-19.cernvm.x86_64 2025-03-14 02:23:07 (1897): Guest Log: 00:00:00.000317 main OS Version: #1 SMP Fri Apr 30 17:12:25 CEST 2021 2025-03-14 02:23:07 (1897): Guest Log: 00:00:00.000334 main Executable: /usr/sbin/VBoxService 2025-03-14 02:23:07 (1897): Guest Log: 00:00:00.000335 main Process ID: 2226 2025-03-14 02:23:07 (1897): Guest Log: 00:00:00.000336 main Package type: LINUX_64BITS_GENERIC 2025-03-14 02:23:07 (1897): Guest Log: 00:00:00.001926 main 5.2.6 r120293 started. Verbose level = 0 2025-03-14 02:23:23 (1897): Guest Log: [INFO] Mounting the shared directory 2025-03-14 02:23:23 (1897): Guest Log: [INFO] Shared directory mounted, enabling vboxmonitor 2025-03-14 02:23:23 (1897): Guest Log: [INFO] Sourcing essential functions from /cvmfs/grid.cern.ch 2025-03-14 02:23:23 (1897): Guest Log: [INFO] Testing connection to cern.ch 2025-03-14 02:23:24 (1897): Guest Log: [INFO] Testing connection to VCCS 2025-03-14 02:23:24 (1897): Guest Log: [INFO] Testing connection to HTCondor 2025-03-14 02:23:25 (1897): Guest Log: [INFO] Testing connection to WMAgent 2025-03-14 02:23:25 (1897): Guest Log: [INFO] Testing connection to EOSCMS 2025-03-14 02:23:26 (1897): Guest Log: [INFO] Testing connection to CMS-Factory 2025-03-14 02:23:26 (1897): Guest Log: [INFO] Testing connection to CMS-Frontier 2025-03-14 02:23:27 (1897): Guest Log: [INFO] Testing connection to Frontier 2025-03-14 02:23:27 (1897): Guest Log: [INFO] Could not find a local HTTP proxy 2025-03-14 02:23:27 (1897): Guest Log: [INFO] CVMFS and Frontier will have to use DIRECT connections 2025-03-14 02:23:28 (1897): Guest Log: [INFO] This makes the application less efficient 2025-03-14 02:23:28 (1897): Guest Log: [INFO] It also puts higher load on the project servers 2025-03-14 02:23:28 (1897): Guest Log: [INFO] Setting up a local HTTP proxy is highly recommended 2025-03-14 02:23:28 (1897): Guest Log: [INFO] Advice can be found in the project forum 2025-03-14 02:23:28 (1897): Guest Log: [INFO] Reloading and probing the CVMFS configuration 2025-03-14 02:24:00 (1897): Guest Log: [INFO] Probing /cvmfs/grid.cern.ch... OK 2025-03-14 02:24:03 (1897): Guest Log: [INFO] Probing /cvmfs/cms.cern.ch... OK 2025-03-14 02:24:03 (1897): Guest Log: [INFO] Probing /cvmfs/oasis.opensciencegrid.org... OK 2025-03-14 02:24:04 (1897): Guest Log: [INFO] Probing /cvmfs/cms-ib.cern.ch... OK 2025-03-14 02:24:05 (1897): Guest Log: [INFO] Probing /cvmfs/singularity.opensciencegrid.org... OK 2025-03-14 02:24:05 (1897): Guest Log: [INFO] Excerpt from "cvmfs_config stat": VERSION HOST PROXY 2025-03-14 02:24:05 (1897): Guest Log: [INFO] 2.7.2.0 http://s1fnal-cvmfs.openhtc.io:8080 DIRECT 2025-03-14 02:24:05 (1897): Guest Log: [INFO] Environment HTTP proxy: not set 2025-03-14 02:24:06 (1897): Guest Log: [INFO] Reading volunteer information 2025-03-14 02:24:06 (1897): Guest Log: [INFO] Using weak account key. 2025-03-14 02:24:06 (1897): Guest Log: [INFO] Requesting an X509 credential from LHC@home 2025-03-14 02:24:08 (1897): Guest Log: [INFO] Using weak account key. 2025-03-14 02:24:08 (1897): Guest Log: [INFO] Requesting an idtoken from LHC@home 2025-03-14 02:24:09 (1897): Guest Log: [INFO] CMS application starting. Check log files. 2025-03-14 02:39:13 (1897): VM state change detected. (old = 'running', new = 'paused') 2025-03-14 02:39:32 (1897): VM state change detected. (old = 'paused', new = 'running') 2025-03-14 02:44:36 (1897): VM state change detected. (old = 'running', new = 'paused') 2025-03-14 02:44:55 (1897): VM state change detected. (old = 'paused', new = 'running') 2025-03-14 02:54:30 (1897): VM state change detected. (old = 'running', new = 'paused') 2025-03-14 02:54:50 (1897): VM state change detected. (old = 'paused', new = 'running') 2025-03-14 02:54:55 (1897): Powering off VM. 2025-03-14 02:54:55 (1897): Successfully stopped VM. 2025-03-14 02:54:55 (1897): Deregistering VM. (boinc_2978af719339f470, slot#2) 2025-03-14 02:54:55 (1897): Removing network bandwidth throttle group from VM. 2025-03-14 02:54:55 (1897): Removing VM from VirtualBox. 2025-03-14 02:55:00 (1897): called boinc_finish(0) </stderr_txt> ]]>
©2025 CERN