| Name | CMS_2778376_1737998989.675836_0 |
| Workunit | 230001943 |
| Created | 27 Jan 2025, 17:29:54 UTC |
| Sent | 27 Jan 2025, 17:38:43 UTC |
| Report deadline | 27 Feb 2025, 17:38:43 UTC |
| Received | 28 Jan 2025, 19:47:31 UTC |
| Server state | Over |
| Outcome | Success |
| Client state | Done |
| Exit status | 0 (0x00000000) |
| Computer ID | 10848374 |
| Run time | 53 min 16 sec |
| CPU time | 3 hours 10 min 29 sec |
| Validate state | Valid |
| Credit | 188.19 |
| Device peak FLOPS | 24.81 GFLOPS |
| Application version | CMS Simulation v70.60 (vbox64_mt_mcore_cms) windows_x86_64 |
| Peak working set size | 84.01 MB |
| Peak swap size | 86.44 MB |
| Peak disk usage | 1.44 GB |
Stderr output
<core_client_version>7.24.1</core_client_version> <![CDATA[ <stderr_txt> 2025-01-27 20:05:39 (31832): vboxwrapper version 26208 2025-01-27 20:05:39 (31832): BOINC client version: 7.24.1 2025-01-27 20:05:40 (31832): Detected: VirtualBox VboxManage Interface (Version: 7.0.14) 2025-01-27 20:05:40 (31832): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds) 2025-01-27 20:05:40 (31832): Successfully copied 'init_data.xml' to the shared directory. 2025-01-27 20:05:40 (31832): Create VM. (boinc_f093f96f657b33f3, slot#12) 2025-01-27 20:05:41 (31832): Setting Memory Size for VM. (4584MB) 2025-01-27 20:05:42 (31832): Setting CPU Count for VM. (4) 2025-01-27 20:05:42 (31832): Setting Chipset Options for VM. 2025-01-27 20:05:42 (31832): Setting Graphics Controller Options for VM. 2025-01-27 20:05:42 (31832): Setting Boot Options for VM. 2025-01-27 20:05:43 (31832): Setting Network Configuration for NAT. 2025-01-27 20:05:43 (31832): Enabling VM Network Access. 2025-01-27 20:05:43 (31832): Disabling USB Support for VM. 2025-01-27 20:05:43 (31832): Disabling COM Port Support for VM. 2025-01-27 20:05:44 (31832): Disabling LPT Port Support for VM. 2025-01-27 20:05:44 (31832): Disabling Audio Support for VM. 2025-01-27 20:05:44 (31832): Disabling Clipboard Support for VM. 2025-01-27 20:05:44 (31832): Disabling Drag and Drop Support for VM. 2025-01-27 20:05:45 (31832): Adding storage controller(s) to VM. 2025-01-27 20:05:45 (31832): Adding virtual disk drive to VM. (CMS_2025_01_16_prod.vdi) 2025-01-27 20:05:45 (31832): Adding VirtualBox Guest Additions to VM. 2025-01-27 20:05:46 (31832): Adding network bandwidth throttle group to VM. (Defaulting to 1024GB) 2025-01-27 20:05:46 (31832): forwarding host port 50935 to guest port 80 2025-01-27 20:05:46 (31832): Enabling remote desktop for VM. 2025-01-27 20:05:46 (31832): Required extension pack not installed, remote desktop not enabled. 2025-01-27 20:05:46 (31832): Enabling shared directory for VM. 2025-01-27 20:05:47 (31832): Starting VM using VBoxManage interface. (boinc_f093f96f657b33f3, slot#12) 2025-01-27 20:05:51 (31832): Successfully started VM. (PID = '29368') 2025-01-27 20:05:51 (31832): Reporting VM Process ID to BOINC. 2025-01-27 20:05:51 (31832): Guest Log: BIOS: VirtualBox 7.0.14 2025-01-27 20:05:51 (31832): Guest Log: CPUID EDX: 0x178bfbff 2025-01-27 20:05:51 (31832): Guest Log: BIOS: No PCI IDE controller, not probing IDE 2025-01-27 20:05:51 (31832): Guest Log: BIOS: AHCI 0-P#0: PCHS=16383/16/63 LCHS=1024/255/63 0x0000000002800000 sectors 2025-01-27 20:05:51 (31832): VM state change detected. (old = 'poweredoff', new = 'running') 2025-01-27 20:05:51 (31832): Detected: Web Application Enabled (http://localhost:50935) 2025-01-27 20:05:52 (31832): VM state change detected. (old = 'running', new = 'paused') 2025-01-27 20:06:03 (31832): Preference change detected 2025-01-27 20:06:03 (31832): Setting CPU throttle for VM. (100%) 2025-01-27 20:06:03 (31832): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds)) 2025-01-27 20:06:03 (31832): VM state change detected. (old = 'paused', new = 'running') 2025-01-27 20:06:05 (31832): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032 2025-01-27 20:06:05 (31832): Guest Log: BIOS: Booting from Hard Disk... 2025-01-27 20:06:07 (31832): Guest Log: BIOS: KBD: unsupported int 16h function 03 2025-01-27 20:06:07 (31832): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 2025-01-27 20:06:13 (31832): VM state change detected. (old = 'running', new = 'paused') 2025-01-27 20:06:33 (31832): VM state change detected. (old = 'paused', new = 'running') 2025-01-27 20:06:43 (31832): VM state change detected. (old = 'running', new = 'paused') 2025-01-27 20:07:04 (31832): VM state change detected. (old = 'paused', new = 'running') 2025-01-27 20:07:13 (31832): VM state change detected. (old = 'running', new = 'paused') 2025-01-27 20:07:33 (31832): VM state change detected. (old = 'paused', new = 'running') 2025-01-27 20:07:34 (31832): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds 2025-01-27 20:07:34 (31832): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000) 2025-01-27 20:07:34 (31832): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log 2025-01-27 20:07:34 (31832): Guest Log: 00:00:00.000075 main Log opened 2025-01-27T19:07:14.226242000Z 2025-01-27 20:07:34 (31832): Guest Log: 00:00:00.000146 main OS Product: Linux 2025-01-27 20:07:34 (31832): Guest Log: 00:00:00.000163 main OS Release: 4.14.232-19.cernvm.x86_64 2025-01-27 20:07:34 (31832): Guest Log: 00:00:00.000178 main OS Version: #1 SMP Fri Apr 30 17:12:25 CEST 2021 2025-01-27 20:07:34 (31832): Guest Log: 00:00:00.000194 main Executable: /usr/sbin/VBoxService 2025-01-27 20:07:34 (31832): Guest Log: 00:00:00.000194 main Process ID: 2284 2025-01-27 20:07:34 (31832): Guest Log: 00:00:00.000195 main Package type: LINUX_64BITS_GENERIC 2025-01-27 20:07:34 (31832): Guest Log: 00:00:00.000713 main 5.2.6 r120293 started. Verbose level = 0 2025-01-27 20:07:43 (31832): VM state change detected. (old = 'running', new = 'paused') 2025-01-27 20:08:03 (31832): Guest Log: [INFO] Mounting the shared directory 2025-01-27 20:08:03 (31832): Guest Log: [INFO] Shared directory mounted, enabling vboxmonitor 2025-01-27 20:08:03 (31832): Guest Log: [INFO] Sourcing essential functions from /cvmfs/grid.cern.ch 2025-01-27 20:08:03 (31832): Guest Log: [INFO] Testing connection to cern.ch 2025-01-27 20:08:03 (31832): VM state change detected. (old = 'paused', new = 'running') 2025-01-27 20:08:04 (31832): Guest Log: [INFO] Testing connection to VCCS 2025-01-27 20:08:04 (31832): Guest Log: [INFO] Testing connection to HTCondor 2025-01-27 20:08:04 (31832): Guest Log: [INFO] Testing connection to WMAgent 2025-01-27 20:08:04 (31832): Guest Log: [INFO] Testing connection to EOSCMS 2025-01-27 20:08:04 (31832): Guest Log: [INFO] Testing connection to CMS-Factory 2025-01-27 20:08:04 (31832): Guest Log: [INFO] Testing connection to CMS-Frontier 2025-01-27 20:08:04 (31832): Guest Log: [INFO] Testing connection to Frontier 2025-01-27 20:08:04 (31832): Guest Log: [INFO] Could not find a local HTTP proxy 2025-01-27 20:08:05 (31832): Guest Log: [INFO] CVMFS and Frontier will have to use DIRECT connections 2025-01-27 20:08:05 (31832): Guest Log: [INFO] This makes the application less efficient 2025-01-27 20:08:05 (31832): Guest Log: [INFO] It also puts higher load on the project servers 2025-01-27 20:08:05 (31832): Guest Log: [INFO] Setting up a local HTTP proxy is highly recommended 2025-01-27 20:08:05 (31832): Guest Log: [INFO] Advice can be found in the project forum 2025-01-27 20:08:05 (31832): Guest Log: [INFO] Reloading and probing the CVMFS configuration 2025-01-27 20:08:10 (31832): Guest Log: [INFO] Probing /cvmfs/grid.cern.ch... OK 2025-01-27 20:08:12 (31832): Guest Log: [INFO] Probing /cvmfs/cms.cern.ch... OK 2025-01-27 20:08:12 (31832): Guest Log: [INFO] Probing /cvmfs/oasis.opensciencegrid.org... OK 2025-01-27 20:08:13 (31832): VM state change detected. (old = 'running', new = 'paused') 2025-01-27 20:08:33 (31832): Guest Log: [INFO] Probing /cvmfs/cms-ib.cern.ch... OK 2025-01-27 20:08:33 (31832): Guest Log: [INFO] Probing /cvmfs/singularity.opensciencegrid.org... OK 2025-01-27 20:08:33 (31832): Guest Log: [INFO] Excerpt from "cvmfs_config stat": VERSION HOST PROXY 2025-01-27 20:08:33 (31832): Guest Log: [INFO] 2.7.2.0 http://s1fnal-cvmfs.openhtc.io:8080 DIRECT 2025-01-27 20:08:33 (31832): Guest Log: [INFO] Environment HTTP proxy: not set 2025-01-27 20:08:33 (31832): Guest Log: [INFO] Reading volunteer information 2025-01-27 20:08:33 (31832): VM state change detected. (old = 'paused', new = 'running') 2025-01-27 20:08:38 (31832): Guest Log: [INFO] Requesting an X509 credential from LHC@home 2025-01-27 20:08:39 (31832): Guest Log: [INFO] Requesting an idtoken from LHC@home 2025-01-27 20:08:39 (31832): Guest Log: [INFO] CMS application starting. Check log files. 2025-01-27 20:08:43 (31832): VM state change detected. (old = 'running', new = 'paused') 2025-01-27 20:09:04 (31832): VM state change detected. (old = 'paused', new = 'running') 2025-01-27 20:09:13 (31832): VM state change detected. (old = 'running', new = 'paused') 2025-01-27 20:09:33 (31832): VM state change detected. (old = 'paused', new = 'running') 2025-01-27 20:09:43 (31832): VM state change detected. (old = 'running', new = 'paused') 2025-01-27 20:10:03 (31832): VM state change detected. (old = 'paused', new = 'running') 2025-01-27 20:10:13 (31832): VM state change detected. (old = 'running', new = 'paused') 2025-01-27 20:10:33 (31832): VM state change detected. (old = 'paused', new = 'running') 2025-01-27 20:10:44 (31832): VM state change detected. (old = 'running', new = 'paused') 2025-01-27 20:11:04 (31832): VM state change detected. (old = 'paused', new = 'running') 2025-01-27 20:11:13 (31832): VM state change detected. (old = 'running', new = 'paused') 2025-01-27 20:11:34 (31832): VM state change detected. (old = 'paused', new = 'running') 2025-01-27 20:11:43 (31832): VM state change detected. (old = 'running', new = 'paused') 2025-01-27 20:12:03 (31832): VM state change detected. (old = 'paused', new = 'running') 2025-01-27 20:12:13 (31832): VM state change detected. (old = 'running', new = 'paused') 2025-01-27 20:12:33 (31832): VM state change detected. (old = 'paused', new = 'running') 2025-01-27 20:12:44 (31832): VM state change detected. (old = 'running', new = 'paused') 2025-01-27 20:13:04 (31832): VM state change detected. (old = 'paused', new = 'running') 2025-01-27 20:13:14 (31832): VM state change detected. (old = 'running', new = 'paused') 2025-01-27 20:13:34 (31832): VM state change detected. (old = 'paused', new = 'running') 2025-01-27 20:13:44 (31832): VM state change detected. (old = 'running', new = 'paused') 2025-01-27 20:14:04 (31832): VM state change detected. (old = 'paused', new = 'running') 2025-01-27 20:14:13 (31832): VM state change detected. (old = 'running', new = 'paused') 2025-01-27 20:14:34 (31832): VM state change detected. (old = 'paused', new = 'running') 2025-01-27 20:14:43 (31832): VM state change detected. (old = 'running', new = 'paused') 2025-01-27 20:15:04 (31832): VM state change detected. (old = 'paused', new = 'running') 2025-01-27 20:15:14 (31832): VM state change detected. (old = 'running', new = 'paused') 2025-01-27 20:15:34 (31832): VM state change detected. (old = 'paused', new = 'running') 2025-01-27 20:15:44 (31832): VM state change detected. (old = 'running', new = 'paused') 2025-01-27 20:16:04 (31832): VM state change detected. (old = 'paused', new = 'running') 2025-01-27 20:16:14 (31832): VM state change detected. (old = 'running', new = 'paused') 2025-01-27 20:16:34 (31832): VM state change detected. (old = 'paused', new = 'running') 2025-01-27 20:16:44 (31832): VM state change detected. (old = 'running', new = 'paused') 2025-01-27 20:17:04 (31832): VM state change detected. (old = 'paused', new = 'running') 2025-01-27 20:17:13 (31832): VM state change detected. (old = 'running', new = 'paused') 2025-01-27 20:17:34 (31832): VM state change detected. (old = 'paused', new = 'running') 2025-01-27 20:17:44 (31832): VM state change detected. (old = 'running', new = 'paused') 2025-01-27 20:18:04 (31832): VM state change detected. (old = 'paused', new = 'running') 2025-01-27 20:18:14 (31832): VM state change detected. (old = 'running', new = 'paused') 2025-01-27 20:18:34 (31832): VM state change detected. (old = 'paused', new = 'running') 2025-01-27 20:18:44 (31832): VM state change detected. (old = 'running', new = 'paused') 2025-01-27 20:19:04 (31832): VM state change detected. (old = 'paused', new = 'running') 2025-01-27 20:20:25 (31832): VM state change detected. (old = 'running', new = 'paused') 2025-01-27 20:20:44 (31832): VM state change detected. (old = 'paused', new = 'running') 2025-01-27 20:21:36 (31832): VM state change detected. (old = 'running', new = 'paused') 2025-01-27 20:21:57 (31832): VM state change detected. (old = 'paused', new = 'running') 2025-01-27 20:30:15 (31832): VM state change detected. (old = 'running', new = 'paused') 2025-01-27 20:30:35 (31832): VM state change detected. (old = 'paused', new = 'running') 2025-01-27 20:30:45 (31832): VM state change detected. (old = 'running', new = 'paused') 2025-01-27 20:31:05 (31832): VM state change detected. (old = 'paused', new = 'running') 2025-01-27 20:31:15 (31832): VM state change detected. (old = 'running', new = 'paused') 2025-01-27 20:31:36 (31832): VM state change detected. (old = 'paused', new = 'running') 2025-01-27 20:31:45 (31832): VM state change detected. (old = 'running', new = 'paused') 2025-01-27 20:32:05 (31832): VM state change detected. (old = 'paused', new = 'running') 2025-01-27 20:32:15 (31832): VM state change detected. (old = 'running', new = 'paused') 2025-01-27 20:32:36 (31832): VM state change detected. (old = 'paused', new = 'running') 2025-01-27 20:32:45 (31832): VM state change detected. (old = 'running', new = 'paused') 2025-01-27 20:33:06 (31832): VM state change detected. (old = 'paused', new = 'running') 2025-01-27 20:33:15 (31832): VM state change detected. (old = 'running', new = 'paused') 2025-01-27 20:33:36 (31832): VM state change detected. (old = 'paused', new = 'running') 2025-01-27 20:33:45 (31832): VM state change detected. (old = 'running', new = 'paused') 2025-01-27 20:34:05 (31832): VM state change detected. (old = 'paused', new = 'running') 2025-01-27 20:34:15 (31832): VM state change detected. (old = 'running', new = 'paused') 2025-01-27 20:34:36 (31832): VM state change detected. (old = 'paused', new = 'running') 2025-01-27 20:34:46 (31832): VM state change detected. (old = 'running', new = 'paused') 2025-01-27 20:35:06 (31832): VM state change detected. (old = 'paused', new = 'running') 2025-01-27 20:35:16 (31832): VM state change detected. (old = 'running', new = 'paused') 2025-01-27 20:35:36 (31832): VM state change detected. (old = 'paused', new = 'running') 2025-01-27 20:35:46 (31832): VM state change detected. (old = 'running', new = 'paused') 2025-01-27 20:36:06 (31832): VM state change detected. (old = 'paused', new = 'running') 2025-01-27 20:36:16 (31832): VM state change detected. (old = 'running', new = 'paused') 2025-01-27 20:36:36 (31832): VM state change detected. (old = 'paused', new = 'running') 2025-01-27 20:36:46 (31832): VM state change detected. (old = 'running', new = 'paused') 2025-01-27 20:37:06 (31832): VM state change detected. (old = 'paused', new = 'running') 2025-01-27 20:37:15 (31832): VM state change detected. (old = 'running', new = 'paused') 2025-01-27 20:37:36 (31832): VM state change detected. (old = 'paused', new = 'running') 2025-01-27 20:37:45 (31832): VM state change detected. (old = 'running', new = 'paused') 2025-01-27 20:38:06 (31832): VM state change detected. (old = 'paused', new = 'running') 2025-01-27 20:38:16 (31832): VM state change detected. (old = 'running', new = 'paused') 2025-01-27 20:38:36 (31832): VM state change detected. (old = 'paused', new = 'running') 2025-01-27 20:38:46 (31832): VM state change detected. (old = 'running', new = 'paused') 2025-01-27 20:39:06 (31832): VM state change detected. (old = 'paused', new = 'running') 2025-01-27 20:39:16 (31832): VM state change detected. (old = 'running', new = 'paused') 2025-01-27 20:39:36 (31832): VM state change detected. (old = 'paused', new = 'running') 2025-01-27 20:48:46 (31832): VM state change detected. (old = 'running', new = 'paused') 2025-01-27 20:49:07 (31832): VM state change detected. (old = 'paused', new = 'running') 2025-01-27 20:50:56 (31832): VM state change detected. (old = 'running', new = 'paused') 2025-01-27 20:51:17 (31832): VM state change detected. (old = 'paused', new = 'running') 2025-01-27 20:51:37 (31832): VM state change detected. (old = 'running', new = 'paused') 2025-01-27 20:51:57 (31832): VM state change detected. (old = 'paused', new = 'running') 2025-01-27 20:53:17 (31832): VM state change detected. (old = 'running', new = 'paused') 2025-01-27 20:53:38 (31832): VM state change detected. (old = 'paused', new = 'running') 2025-01-27 20:54:37 (31832): VM state change detected. (old = 'running', new = 'paused') 2025-01-27 20:54:58 (31832): VM state change detected. (old = 'paused', new = 'running') 2025-01-27 20:55:08 (31832): VM state change detected. (old = 'running', new = 'paused') 2025-01-27 20:55:28 (31832): VM state change detected. (old = 'paused', new = 'running') 2025-01-27 20:57:57 (31832): VM state change detected. (old = 'running', new = 'paused') 2025-01-27 20:58:17 (31832): VM state change detected. (old = 'paused', new = 'running') 2025-01-27 20:59:18 (31832): VM state change detected. (old = 'running', new = 'paused') 2025-01-27 20:59:38 (31832): VM state change detected. (old = 'paused', new = 'running') 2025-01-27 21:02:48 (31832): VM state change detected. (old = 'running', new = 'paused') 2025-01-27 21:03:08 (31832): VM state change detected. (old = 'paused', new = 'running') 2025-01-27 21:04:29 (31832): VM state change detected. (old = 'running', new = 'paused') 2025-01-27 21:04:49 (31832): VM state change detected. (old = 'paused', new = 'running') 2025-01-27 21:04:59 (31832): VM state change detected. (old = 'running', new = 'paused') 2025-01-27 21:05:19 (31832): VM state change detected. (old = 'paused', new = 'running') 2025-01-27 21:05:29 (31832): VM state change detected. (old = 'running', new = 'paused') 2025-01-27 21:05:49 (31832): VM state change detected. (old = 'paused', new = 'running') 2025-01-27 21:05:59 (31832): VM state change detected. (old = 'running', new = 'paused') 2025-01-27 21:06:19 (31832): VM state change detected. (old = 'paused', new = 'running') 2025-01-27 21:06:29 (31832): VM state change detected. (old = 'running', new = 'paused') 2025-01-27 21:06:49 (31832): VM state change detected. (old = 'paused', new = 'running') 2025-01-27 21:06:59 (31832): VM state change detected. (old = 'running', new = 'paused') 2025-01-27 21:07:19 (31832): VM state change detected. (old = 'paused', new = 'running') 2025-01-27 21:07:29 (31832): VM state change detected. (old = 'running', new = 'paused') 2025-01-27 21:07:49 (31832): VM state change detected. (old = 'paused', new = 'running') 2025-01-27 21:07:59 (31832): VM state change detected. (old = 'running', new = 'paused') 2025-01-27 21:08:19 (31832): VM state change detected. (old = 'paused', new = 'running') 2025-01-27 21:08:29 (31832): VM state change detected. (old = 'running', new = 'paused') 2025-01-27 21:08:49 (31832): VM state change detected. (old = 'paused', new = 'running') 2025-01-27 21:08:59 (31832): VM state change detected. (old = 'running', new = 'paused') 2025-01-27 21:09:19 (31832): VM state change detected. (old = 'paused', new = 'running') 2025-01-27 21:09:29 (31832): VM state change detected. (old = 'running', new = 'paused') 2025-01-27 21:09:49 (31832): VM state change detected. (old = 'paused', new = 'running') 2025-01-27 21:09:59 (31832): VM state change detected. (old = 'running', new = 'paused') 2025-01-27 21:10:19 (31832): VM state change detected. (old = 'paused', new = 'running') 2025-01-27 21:10:29 (31832): VM state change detected. (old = 'running', new = 'paused') 2025-01-27 21:10:49 (31832): VM state change detected. (old = 'paused', new = 'running') 2025-01-27 21:10:59 (31832): VM state change detected. (old = 'running', new = 'paused') 2025-01-27 21:11:19 (31832): VM state change detected. (old = 'paused', new = 'running') 2025-01-27 21:11:29 (31832): VM state change detected. (old = 'running', new = 'paused') 2025-01-27 21:11:49 (31832): VM state change detected. (old = 'paused', new = 'running') 2025-01-27 21:11:59 (31832): VM state change detected. (old = 'running', new = 'paused') 2025-01-27 21:12:19 (31832): VM state change detected. (old = 'paused', new = 'running') 2025-01-27 21:12:29 (31832): VM state change detected. (old = 'running', new = 'paused') 2025-01-27 21:12:49 (31832): VM state change detected. (old = 'paused', new = 'running') 2025-01-27 21:12:59 (31832): VM state change detected. (old = 'running', new = 'paused') 2025-01-27 21:13:19 (31832): VM state change detected. (old = 'paused', new = 'running') 2025-01-27 21:13:29 (31832): VM state change detected. (old = 'running', new = 'paused') 2025-01-27 21:13:49 (31832): VM state change detected. (old = 'paused', new = 'running') 2025-01-27 21:13:59 (31832): VM state change detected. (old = 'running', new = 'paused') 2025-01-27 21:14:19 (31832): VM state change detected. (old = 'paused', new = 'running') 2025-01-27 21:14:29 (31832): VM state change detected. (old = 'running', new = 'paused') 2025-01-27 21:14:49 (31832): VM state change detected. (old = 'paused', new = 'running') 2025-01-27 21:14:59 (31832): VM state change detected. (old = 'running', new = 'paused') 2025-01-27 21:15:19 (31832): VM state change detected. (old = 'paused', new = 'running') 2025-01-27 21:15:29 (31832): VM state change detected. (old = 'running', new = 'paused') 2025-01-27 21:15:49 (31832): VM state change detected. (old = 'paused', new = 'running') 2025-01-27 21:15:59 (31832): VM state change detected. (old = 'running', new = 'paused') 2025-01-27 21:16:19 (31832): VM state change detected. (old = 'paused', new = 'running') 2025-01-27 21:16:29 (31832): VM state change detected. (old = 'running', new = 'paused') 2025-01-27 21:16:49 (31832): VM state change detected. (old = 'paused', new = 'running') 2025-01-27 21:16:59 (31832): VM state change detected. (old = 'running', new = 'paused') 2025-01-27 21:17:19 (31832): VM state change detected. (old = 'paused', new = 'running') 2025-01-27 21:17:29 (31832): VM state change detected. (old = 'running', new = 'paused') 2025-01-27 21:17:50 (31832): VM state change detected. (old = 'paused', new = 'running') 2025-01-27 21:18:00 (31832): VM state change detected. (old = 'running', new = 'paused') 2025-01-27 21:18:20 (31832): VM state change detected. (old = 'paused', new = 'running') 2025-01-27 21:18:30 (31832): VM state change detected. (old = 'running', new = 'paused') 2025-01-27 21:18:50 (31832): VM state change detected. (old = 'paused', new = 'running') 2025-01-27 21:19:00 (31832): VM state change detected. (old = 'running', new = 'paused') 2025-01-27 21:19:20 (31832): VM state change detected. (old = 'paused', new = 'running') 2025-01-27 21:19:30 (31832): VM state change detected. (old = 'running', new = 'paused') 2025-01-27 21:19:50 (31832): VM state change detected. (old = 'paused', new = 'running') 2025-01-27 21:20:00 (31832): VM state change detected. (old = 'running', new = 'paused') 2025-01-27 21:20:20 (31832): VM state change detected. (old = 'paused', new = 'running') 2025-01-27 21:20:30 (31832): VM state change detected. (old = 'running', new = 'paused') 2025-01-27 21:20:50 (31832): VM state change detected. (old = 'paused', new = 'running') 2025-01-27 21:21:00 (31832): VM state change detected. (old = 'running', new = 'paused') 2025-01-27 21:21:20 (31832): VM state change detected. (old = 'paused', new = 'running') 2025-01-27 21:21:30 (31832): VM state change detected. (old = 'running', new = 'paused') 2025-01-27 21:21:50 (31832): VM state change detected. (old = 'paused', new = 'running') 2025-01-27 21:22:00 (31832): VM state change detected. (old = 'running', new = 'paused') 2025-01-27 21:22:20 (31832): VM state change detected. (old = 'paused', new = 'running') 2025-01-27 21:27:20 (31832): VM state change detected. (old = 'running', new = 'paused') 2025-01-27 21:27:40 (31832): VM state change detected. (old = 'paused', new = 'running') 2025-01-27 21:28:00 (31832): VM state change detected. (old = 'running', new = 'paused') 2025-01-27 21:28:20 (31832): VM state change detected. (old = 'paused', new = 'running') 2025-01-27 21:28:30 (31832): VM state change detected. (old = 'running', new = 'paused') 2025-01-27 21:28:50 (31832): VM state change detected. (old = 'paused', new = 'running') 2025-01-27 21:29:00 (31832): VM state change detected. (old = 'running', new = 'paused') 2025-01-27 21:29:20 (31832): VM state change detected. (old = 'paused', new = 'running') 2025-01-27 21:29:30 (31832): VM state change detected. (old = 'running', new = 'paused') 2025-01-27 21:29:50 (31832): VM state change detected. (old = 'paused', new = 'running') 2025-01-27 21:30:00 (31832): VM state change detected. (old = 'running', new = 'paused') 2025-01-27 21:30:20 (31832): VM state change detected. (old = 'paused', new = 'running') 2025-01-27 21:30:30 (31832): VM state change detected. (old = 'running', new = 'paused') 2025-01-27 21:30:50 (31832): VM state change detected. (old = 'paused', new = 'running') 2025-01-27 21:31:00 (31832): VM state change detected. (old = 'running', new = 'paused') 2025-01-27 21:31:20 (31832): VM state change detected. (old = 'paused', new = 'running') 2025-01-27 21:31:30 (31832): VM state change detected. (old = 'running', new = 'paused') 2025-01-27 21:31:50 (31832): VM state change detected. (old = 'paused', new = 'running') 2025-01-27 21:32:00 (31832): VM state change detected. (old = 'running', new = 'paused') 2025-01-27 21:32:20 (31832): VM state change detected. (old = 'paused', new = 'running') 2025-01-27 21:32:30 (31832): VM state change detected. (old = 'running', new = 'paused') 2025-01-27 21:32:50 (31832): VM state change detected. (old = 'paused', new = 'running') 2025-01-27 21:32:57 (31832): Stopping VM. 2025-01-27 21:33:08 (31832): Successfully stopped VM. 2025-01-28 20:46:39 (33588): vboxwrapper version 26208 2025-01-28 20:46:39 (33588): BOINC client version: 7.24.1 2025-01-28 20:46:40 (33588): Detected: VirtualBox VboxManage Interface (Version: 7.0.14) 2025-01-28 20:46:40 (33588): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds) 2025-01-28 20:46:40 (33588): Guest Log: BIOS: VirtualBox 7.0.14 2025-01-28 20:46:40 (33588): Guest Log: CPUID EDX: 0x178bfbff 2025-01-28 20:46:40 (33588): Guest Log: BIOS: No PCI IDE controller, not probing IDE 2025-01-28 20:46:40 (33588): Guest Log: BIOS: AHCI 0-P#0: PCHS=16383/16/63 LCHS=1024/255/63 0x0000000002800000 sectors 2025-01-28 20:46:40 (33588): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032 2025-01-28 20:46:40 (33588): Guest Log: BIOS: Booting from Hard Disk... 2025-01-28 20:46:40 (33588): Guest Log: BIOS: KBD: unsupported int 16h function 03 2025-01-28 20:46:40 (33588): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 2025-01-28 20:46:40 (33588): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds 2025-01-28 20:46:40 (33588): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000) 2025-01-28 20:46:40 (33588): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log 2025-01-28 20:46:40 (33588): Guest Log: 00:00:00.000075 main Log opened 2025-01-27T19:07:14.226242000Z 2025-01-28 20:46:40 (33588): Guest Log: 00:00:00.000146 main OS Product: Linux 2025-01-28 20:46:40 (33588): Guest Log: 00:00:00.000163 main OS Release: 4.14.232-19.cernvm.x86_64 2025-01-28 20:46:40 (33588): Guest Log: 00:00:00.000178 main OS Version: #1 SMP Fri Apr 30 17:12:25 CEST 2021 2025-01-28 20:46:40 (33588): Guest Log: 00:00:00.000194 main Executable: /usr/sbin/VBoxService 2025-01-28 20:46:40 (33588): Guest Log: 00:00:00.000194 main Process ID: 2284 2025-01-28 20:46:40 (33588): Guest Log: 00:00:00.000195 main Package type: LINUX_64BITS_GENERIC 2025-01-28 20:46:40 (33588): Guest Log: 00:00:00.000713 main 5.2.6 r120293 started. Verbose level = 0 2025-01-28 20:46:40 (33588): Guest Log: [INFO] Mounting the shared directory 2025-01-28 20:46:40 (33588): Guest Log: [INFO] Shared directory mounted, enabling vboxmonitor 2025-01-28 20:46:40 (33588): Guest Log: [INFO] Sourcing essential functions from /cvmfs/grid.cern.ch 2025-01-28 20:46:40 (33588): Guest Log: [INFO] Testing connection to cern.ch 2025-01-28 20:46:40 (33588): Guest Log: [INFO] Testing connection to VCCS 2025-01-28 20:46:40 (33588): Guest Log: [INFO] Testing connection to HTCondor 2025-01-28 20:46:40 (33588): Guest Log: [INFO] Testing connection to WMAgent 2025-01-28 20:46:40 (33588): Guest Log: [INFO] Testing connection to EOSCMS 2025-01-28 20:46:40 (33588): Guest Log: [INFO] Testing connection to CMS-Factory 2025-01-28 20:46:40 (33588): Guest Log: [INFO] Testing connection to CMS-Frontier 2025-01-28 20:46:40 (33588): Guest Log: [INFO] Testing connection to Frontier 2025-01-28 20:46:40 (33588): Guest Log: [INFO] Could not find a local HTTP proxy 2025-01-28 20:46:40 (33588): Guest Log: [INFO] CVMFS and Frontier will have to use DIRECT connections 2025-01-28 20:46:40 (33588): Guest Log: [INFO] This makes the application less efficient 2025-01-28 20:46:40 (33588): Guest Log: [INFO] It also puts higher load on the project servers 2025-01-28 20:46:40 (33588): Guest Log: [INFO] Setting up a local HTTP proxy is highly recommended 2025-01-28 20:46:40 (33588): Guest Log: [INFO] Advice can be found in the project forum 2025-01-28 20:46:40 (33588): Guest Log: [INFO] Reloading and probing the CVMFS configuration 2025-01-28 20:46:40 (33588): Guest Log: [INFO] Probing /cvmfs/grid.cern.ch... OK 2025-01-28 20:46:40 (33588): Guest Log: [INFO] Probing /cvmfs/cms.cern.ch... OK 2025-01-28 20:46:40 (33588): Guest Log: [INFO] Probing /cvmfs/oasis.opensciencegrid.org... OK 2025-01-28 20:46:40 (33588): Guest Log: [INFO] Probing /cvmfs/cms-ib.cern.ch... OK 2025-01-28 20:46:40 (33588): Guest Log: [INFO] Probing /cvmfs/singularity.opensciencegrid.org... OK 2025-01-28 20:46:40 (33588): Guest Log: [INFO] Excerpt from "cvmfs_config stat": VERSION HOST PROXY 2025-01-28 20:46:40 (33588): Guest Log: [INFO] 2.7.2.0 http://s1fnal-cvmfs.openhtc.io:8080 DIRECT 2025-01-28 20:46:40 (33588): Guest Log: [INFO] Environment HTTP proxy: not set 2025-01-28 20:46:40 (33588): Guest Log: [INFO] Reading volunteer information 2025-01-28 20:46:40 (33588): Guest Log: [INFO] Requesting an X509 credential from LHC@home 2025-01-28 20:46:40 (33588): Guest Log: [INFO] Requesting an idtoken from LHC@home 2025-01-28 20:46:40 (33588): Guest Log: [INFO] CMS application starting. Check log files. 2025-01-28 20:46:40 (33588): Starting VM using VBoxManage interface. (boinc_f093f96f657b33f3, slot#12) 2025-01-28 20:47:01 (33588): Successfully started VM. (PID = '33020') 2025-01-28 20:47:01 (33588): Reporting VM Process ID to BOINC. 2025-01-28 20:47:01 (33588): VM state change detected. (old = 'poweredoff', new = 'running') 2025-01-28 20:47:01 (33588): Detected: Web Application Enabled (http://localhost:50935) 2025-01-28 20:47:01 (33588): Preference change detected 2025-01-28 20:47:01 (33588): Setting CPU throttle for VM. (100%) 2025-01-28 20:47:02 (33588): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds)) 2025-01-28 20:47:10 (33588): Guest Log: 00:52:01.114859 timesync vgsvcTimeSyncWorker: Radical host time change: 83 652 944 000 000ns (HostNow=1 738 093 630 080 000 000 ns HostLast=1 738 009 977 136 000 000 ns) 2025-01-28 20:47:11 (33588): Guest Log: [INFO] glidein exited with return value 0. 2025-01-28 20:47:11 (33588): Guest Log: [INFO] Shutting Down. 2025-01-28 20:47:11 (33588): VM Completion File Detected. 2025-01-28 20:47:11 (33588): VM Completion Message: glidein exited with return value 0. . 2025-01-28 20:47:11 (33588): Powering off VM. 2025-01-28 20:47:12 (33588): Successfully stopped VM. 2025-01-28 20:47:12 (33588): Deregistering VM. (boinc_f093f96f657b33f3, slot#12) 2025-01-28 20:47:12 (33588): Removing network bandwidth throttle group from VM. 2025-01-28 20:47:12 (33588): Removing VM from VirtualBox. 2025-01-28 20:47:18 (33588): called boinc_finish(0) </stderr_txt> ]]>
©2025 CERN