| Name | CMS_1659806_1718872455.101814_0 |
| Workunit | 223665121 |
| Created | 20 Jun 2024, 8:34:16 UTC |
| Sent | 20 Jun 2024, 8:49:26 UTC |
| Report deadline | 21 Jul 2024, 8:49:26 UTC |
| Received | 21 Jun 2024, 22:28:18 UTC |
| Server state | Over |
| Outcome | Success |
| Client state | Done |
| Exit status | 0 (0x00000000) |
| Computer ID | 10397649 |
| Run time | 18 hours 25 min 4 sec |
| CPU time | 2 days 12 hours 43 min 52 sec |
| Validate state | Valid |
| Credit | 2,513.24 |
| Device peak FLOPS | 18.27 GFLOPS |
| Application version | CMS Simulation v70.30 (vbox64_mt_mcore_cms) windows_x86_64 |
| Peak working set size | 98.30 MB |
| Peak swap size | 93.12 MB |
| Peak disk usage | 2.35 GB |
Stderr output
<core_client_version>8.0.2</core_client_version> <![CDATA[ <stderr_txt> 2024-06-20 10:05:11 (7760): vboxwrapper version 26207 2024-06-20 10:05:11 (7760): BOINC client version: 8.0.2 2024-06-20 10:05:12 (7760): Detected: VirtualBox VboxManage Interface (Version: 7.0.6) 2024-06-20 10:05:12 (7760): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds) 2024-06-20 10:05:12 (7760): Successfully copied 'init_data.xml' to the shared directory. 2024-06-20 10:05:13 (7760): Create VM. (boinc_9c3cb52103024680, slot#4) 2024-06-20 10:05:13 (7760): Setting Memory Size for VM. (4584MB) 2024-06-20 10:05:13 (7760): Setting CPU Count for VM. (4) 2024-06-20 10:05:14 (7760): Setting Chipset Options for VM. 2024-06-20 10:05:14 (7760): Setting Graphics Controller Options for VM. 2024-06-20 10:05:14 (7760): Setting Boot Options for VM. 2024-06-20 10:05:15 (7760): Setting Network Configuration for NAT. 2024-06-20 10:05:15 (7760): Enabling VM Network Access. 2024-06-20 10:05:15 (7760): Disabling USB Support for VM. 2024-06-20 10:05:16 (7760): Disabling COM Port Support for VM. 2024-06-20 10:05:16 (7760): Disabling LPT Port Support for VM. 2024-06-20 10:05:16 (7760): Disabling Audio Support for VM. 2024-06-20 10:05:17 (7760): Disabling Clipboard Support for VM. 2024-06-20 10:05:17 (7760): Disabling Drag and Drop Support for VM. 2024-06-20 10:05:17 (7760): Adding storage controller(s) to VM. 2024-06-20 10:05:17 (7760): Adding virtual disk drive to VM. (CMS_2024_04_29_prod.vdi) 2024-06-20 10:05:18 (7760): Adding VirtualBox Guest Additions to VM. 2024-06-20 10:05:18 (7760): Adding network bandwidth throttle group to VM. (Defaulting to 1024GB) 2024-06-20 10:05:19 (7760): forwarding host port 51321 to guest port 80 2024-06-20 10:05:19 (7760): Enabling remote desktop for VM. 2024-06-20 10:05:19 (7760): Required extension pack not installed, remote desktop not enabled. 2024-06-20 10:05:19 (7760): Enabling shared directory for VM. 2024-06-20 10:05:20 (7760): Starting VM using VBoxManage interface. (boinc_9c3cb52103024680, slot#4) 2024-06-20 10:05:25 (7760): Successfully started VM. (PID = '6908') 2024-06-20 10:05:25 (7760): Reporting VM Process ID to BOINC. 2024-06-20 10:05:25 (7760): Guest Log: BIOS: VirtualBox 7.0.6 2024-06-20 10:05:25 (7760): Guest Log: CPUID EDX: 0x178bfbff 2024-06-20 10:05:25 (7760): Guest Log: BIOS: No PCI IDE controller, not probing IDE 2024-06-20 10:05:25 (7760): Guest Log: BIOS: AHCI 0-P#0: PCHS=16383/16/63 LCHS=1024/255/63 0x0000000002800000 sectors 2024-06-20 10:05:25 (7760): VM state change detected. (old = 'poweredoff', new = 'running') 2024-06-20 10:05:25 (7760): Detected: Web Application Enabled (http://localhost:51321) 2024-06-20 10:05:26 (7760): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 10:05:34 (7760): Preference change detected 2024-06-20 10:05:34 (7760): Setting CPU throttle for VM. (100%) 2024-06-20 10:05:35 (7760): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds)) 2024-06-20 10:05:35 (7760): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 10:05:36 (7760): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032 2024-06-20 10:05:36 (7760): Guest Log: BIOS: Booting from Hard Disk... 2024-06-20 10:05:38 (7760): Guest Log: BIOS: KBD: unsupported int 16h function 03 2024-06-20 10:05:38 (7760): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 2024-06-20 10:06:13 (7760): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds 2024-06-20 10:06:13 (7760): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000) 2024-06-20 10:06:14 (7760): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log 2024-06-20 10:06:14 (7760): Guest Log: 00:00:00.000082 main Log opened 2024-06-20T09:06:13.124575000Z 2024-06-20 10:06:14 (7760): Guest Log: 00:00:00.000170 main OS Product: Linux 2024-06-20 10:06:14 (7760): Guest Log: 00:00:00.000204 main OS Release: 4.14.232-19.cernvm.x86_64 2024-06-20 10:06:14 (7760): Guest Log: 00:00:00.000227 main OS Version: #1 SMP Fri Apr 30 17:12:25 CEST 2021 2024-06-20 10:06:14 (7760): Guest Log: 00:00:00.000247 main Executable: /usr/sbin/VBoxService 2024-06-20 10:06:14 (7760): Guest Log: 00:00:00.000247 main Process ID: 2283 2024-06-20 10:06:14 (7760): Guest Log: 00:00:00.000248 main Package type: LINUX_64BITS_GENERIC 2024-06-20 10:06:14 (7760): Guest Log: 00:00:00.001896 main 5.2.6 r120293 started. Verbose level = 0 2024-06-20 10:06:26 (7760): Guest Log: [INFO] Mounting the shared directory 2024-06-20 10:06:26 (7760): Guest Log: [INFO] Shared directory mounted, enabling vboxmonitor 2024-06-20 10:06:26 (7760): Guest Log: [INFO] Sourcing essential functions from /cvmfs/grid.cern.ch 2024-06-20 10:06:26 (7760): Guest Log: [INFO] Testing connection to cern.ch 2024-06-20 10:06:27 (7760): Guest Log: [INFO] Testing connection to VCCS 2024-06-20 10:06:27 (7760): Guest Log: [INFO] Testing connection to HTCondor 2024-06-20 10:06:27 (7760): Guest Log: [INFO] Testing connection to WMAgent 2024-06-20 10:06:27 (7760): Guest Log: [INFO] Testing connection to EOSCMS 2024-06-20 10:06:27 (7760): Guest Log: [INFO] Testing connection to CMS-Factory 2024-06-20 10:06:27 (7760): Guest Log: [INFO] Testing connection to CMS-Frontier 2024-06-20 10:06:27 (7760): Guest Log: [INFO] Testing connection to Frontier 2024-06-20 10:06:28 (7760): Guest Log: [INFO] Could not find a local HTTP proxy 2024-06-20 10:06:28 (7760): Guest Log: [INFO] CVMFS and Frontier will have to use DIRECT connections 2024-06-20 10:06:28 (7760): Guest Log: [INFO] This makes the application less efficient 2024-06-20 10:06:28 (7760): Guest Log: [INFO] It also puts higher load on the project servers 2024-06-20 10:06:28 (7760): Guest Log: [INFO] Setting up a local HTTP proxy is highly recommended 2024-06-20 10:06:28 (7760): Guest Log: [INFO] Advice can be found in the project forum 2024-06-20 10:06:28 (7760): Guest Log: [INFO] Reloading and probing the CVMFS configuration 2024-06-20 10:07:12 (7760): Guest Log: [INFO] Probing /cvmfs/grid.cern.ch... OK 2024-06-20 10:07:14 (7760): Guest Log: [INFO] Probing /cvmfs/singularity.opensciencegrid.org... OK 2024-06-20 10:07:14 (7760): Guest Log: [INFO] Probing /cvmfs/cms-ib.cern.ch... OK 2024-06-20 10:07:49 (7760): Guest Log: [INFO] Probing /cvmfs/cms.cern.ch... OK 2024-06-20 10:08:16 (7760): Guest Log: [INFO] Probing /cvmfs/oasis.opensciencegrid.org... OK 2024-06-20 10:08:17 (7760): Guest Log: [INFO] Excerpt from "cvmfs_config stat": VERSION HOST PROXY 2024-06-20 10:08:17 (7760): Guest Log: [INFO] 2.7.2.0 http://s1cern-cvmfs.openhtc.io DIRECT 2024-06-20 10:08:17 (7760): Guest Log: [INFO] Environment HTTP proxy: not set 2024-06-20 10:08:17 (7760): Guest Log: [INFO] Reading volunteer information 2024-06-20 10:08:25 (7760): Guest Log: [INFO] Requesting an X509 credential from LHC@home 2024-06-20 10:08:26 (7760): Guest Log: [INFO] Requesting an idtoken from LHC@home 2024-06-20 10:08:27 (7760): Guest Log: [INFO] CMS application starting. Check log files. 2024-06-20 10:27:07 (7760): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 10:44:34 (7760): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 10:44:48 (7760): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 10:45:07 (7760): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 10:45:18 (7760): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 10:45:38 (7760): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 10:45:48 (7760): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 10:46:08 (7760): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 10:46:18 (7760): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 10:46:38 (7760): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 10:46:48 (7760): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 10:47:08 (7760): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 10:47:18 (7760): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 10:47:38 (7760): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 10:47:48 (7760): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 10:48:08 (7760): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 10:48:18 (7760): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 10:48:38 (7760): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 10:48:48 (7760): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 10:49:08 (7760): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 10:49:19 (7760): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 10:49:39 (7760): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 10:49:48 (7760): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 10:50:08 (7760): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 10:50:19 (7760): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 10:50:39 (7760): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 10:50:49 (7760): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 10:51:09 (7760): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 10:51:19 (7760): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 10:51:39 (7760): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 10:51:50 (7760): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 10:52:10 (7760): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 10:52:21 (7760): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 10:52:40 (7760): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 10:52:50 (7760): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 10:53:10 (7760): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 10:53:20 (7760): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 10:53:40 (7760): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 10:53:49 (7760): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 10:54:09 (7760): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 10:54:20 (7760): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 10:54:40 (7760): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 10:54:50 (7760): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 10:55:10 (7760): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 10:55:20 (7760): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 10:55:40 (7760): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 10:55:50 (7760): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 10:56:10 (7760): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 10:56:20 (7760): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 10:56:40 (7760): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 10:56:50 (7760): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 10:57:10 (7760): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 10:57:21 (7760): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 10:57:40 (7760): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 10:57:50 (7760): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 10:58:10 (7760): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 10:58:20 (7760): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 10:58:40 (7760): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 10:58:51 (7760): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 10:59:11 (7760): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 10:59:21 (7760): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 10:59:41 (7760): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 10:59:50 (7760): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 11:00:10 (7760): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 11:00:21 (7760): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 11:00:41 (7760): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 11:00:51 (7760): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 11:01:11 (7760): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 11:01:22 (7760): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 11:01:42 (7760): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 11:01:52 (7760): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 11:02:12 (7760): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 11:02:21 (7760): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 11:02:42 (7760): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 11:02:51 (7760): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 11:03:11 (7760): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 11:03:22 (7760): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 11:03:42 (7760): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 11:03:52 (7760): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 11:04:12 (7760): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 11:04:22 (7760): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 11:04:42 (7760): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 11:04:52 (7760): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 11:05:12 (7760): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 11:05:22 (7760): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 11:05:43 (7760): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 11:05:53 (7760): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 11:06:12 (7760): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 11:06:22 (7760): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 11:06:42 (7760): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 11:06:52 (7760): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 11:07:12 (7760): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 11:07:23 (7760): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 11:07:43 (7760): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 11:07:53 (7760): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 11:08:13 (7760): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 11:08:23 (7760): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 11:08:43 (7760): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 11:08:53 (7760): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 11:09:13 (7760): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 11:09:23 (7760): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 11:09:43 (7760): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 11:09:54 (7760): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 11:10:14 (7760): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 11:10:23 (7760): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 11:10:43 (7760): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 11:10:53 (7760): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 11:11:13 (7760): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 11:11:24 (7760): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 11:11:44 (7760): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 11:11:54 (7760): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 11:12:14 (7760): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 11:12:23 (7760): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 11:12:43 (7760): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 11:12:54 (7760): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 11:13:14 (7760): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 11:13:24 (7760): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 11:13:44 (7760): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 11:13:55 (7760): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 11:14:15 (7760): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 11:14:24 (7760): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 11:14:44 (7760): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 11:14:54 (7760): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 11:15:14 (7760): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 11:15:25 (7760): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 11:15:45 (7760): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 11:15:55 (7760): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 11:16:15 (7760): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 11:16:25 (7760): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 11:16:45 (7760): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 11:16:55 (7760): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 11:17:15 (7760): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 11:17:25 (7760): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 11:17:45 (7760): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 11:17:55 (7760): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 11:18:15 (7760): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 11:18:25 (7760): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 11:18:46 (7760): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 11:18:56 (7760): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 11:19:15 (7760): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 11:19:26 (7760): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 11:19:45 (7760): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 11:19:55 (7760): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 11:20:15 (7760): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 11:20:26 (7760): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 11:20:46 (7760): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 11:20:56 (7760): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 11:21:16 (7760): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 11:21:26 (7760): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 11:21:46 (7760): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 11:21:57 (7760): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 11:22:17 (7760): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 11:22:26 (7760): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 11:22:46 (7760): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 11:22:56 (7760): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 11:23:16 (7760): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 11:23:27 (7760): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 11:23:46 (7760): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 11:23:57 (7760): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 11:24:17 (7760): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 11:24:27 (7760): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 11:24:47 (7760): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 11:24:57 (7760): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 11:25:16 (7760): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 11:25:27 (7760): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 11:25:47 (7760): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 11:25:57 (7760): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 11:26:17 (7760): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 11:26:27 (7760): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 11:26:47 (7760): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 11:26:57 (7760): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 11:27:17 (7760): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 11:27:28 (7760): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 11:27:48 (7760): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 11:27:58 (7760): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 11:28:18 (7760): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 11:28:28 (7760): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 11:28:47 (7760): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 11:28:57 (7760): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 11:29:17 (7760): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 11:29:28 (7760): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 11:29:47 (7760): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 11:29:57 (7760): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 11:30:18 (7760): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 11:30:28 (7760): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 11:30:48 (7760): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 11:30:58 (7760): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 11:31:18 (7760): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 11:31:29 (7760): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 11:31:48 (7760): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 11:31:59 (7760): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 11:32:19 (7760): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 11:32:28 (7760): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 11:32:48 (7760): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 11:32:59 (7760): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 11:33:19 (7760): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 11:33:29 (7760): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 11:33:48 (7760): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 11:33:58 (7760): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 11:34:18 (7760): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 11:34:29 (7760): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 11:34:49 (7760): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 11:34:59 (7760): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 11:35:19 (7760): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 11:35:29 (7760): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 11:35:49 (7760): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 11:35:59 (7760): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 11:36:20 (7760): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 11:36:29 (7760): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 11:36:49 (7760): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 11:36:59 (7760): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 11:37:19 (7760): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 11:37:30 (7760): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 11:37:49 (7760): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 11:37:59 (7760): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 11:38:19 (7760): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 11:38:30 (7760): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 11:38:50 (7760): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 11:39:00 (7760): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 11:39:20 (7760): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 11:39:29 (7760): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 11:39:49 (7760): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 11:40:00 (7760): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 11:40:20 (7760): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 11:40:30 (7760): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 11:40:50 (7760): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 11:41:00 (7760): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 11:41:21 (7760): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 11:41:31 (7760): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 11:43:31 (7760): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 11:43:41 (7760): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 11:44:00 (7760): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 11:44:10 (7760): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 11:45:06 (7760): Preference change detected 2024-06-20 11:45:06 (7760): Setting CPU throttle for VM. (100%) 2024-06-20 11:45:08 (7760): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds)) 2024-06-20 11:45:08 (7760): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 11:45:55 (7760): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 11:46:08 (7760): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 11:46:28 (7760): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 11:46:58 (7760): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 11:46:59 (7760): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 11:52:18 (7760): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 11:52:19 (7760): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 11:55:53 (7760): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 12:01:58 (7760): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 12:03:20 (7760): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 12:03:27 (7760): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 12:03:54 (7760): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 12:06:35 (7760): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 12:06:38 (7760): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 12:06:40 (7760): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 12:06:48 (7760): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 12:09:07 (7760): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 12:22:08 (7760): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 12:22:13 (7760): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 12:23:13 (7760): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 12:23:14 (7760): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 12:24:34 (7760): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 12:24:35 (7760): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 12:27:01 (7760): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 12:27:05 (7760): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 12:30:49 (7760): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 12:30:51 (7760): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 12:31:15 (7760): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 12:31:34 (7760): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 12:32:09 (7760): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 12:32:18 (7760): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 12:32:33 (7760): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 12:32:39 (7760): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 12:35:06 (7760): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 12:35:17 (7760): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 12:36:19 (7760): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 12:36:24 (7760): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 12:37:43 (7760): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 12:37:49 (7760): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 12:41:54 (7760): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 12:42:08 (7760): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 12:47:09 (7760): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 12:47:13 (7760): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 12:51:41 (7760): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 12:52:02 (7760): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 12:52:19 (7760): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 12:52:21 (7760): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 12:52:28 (7760): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 12:52:43 (7760): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 12:54:25 (7760): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 12:54:26 (7760): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 12:54:38 (7760): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 12:54:40 (7760): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 12:55:09 (7760): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 12:55:17 (7760): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 12:55:47 (7760): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 12:56:23 (7760): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 12:57:03 (7760): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 12:57:17 (7760): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 12:58:07 (7760): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 12:58:10 (7760): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 12:58:37 (7760): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 12:58:45 (7760): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 12:58:57 (7760): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 12:58:58 (7760): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 12:59:06 (7760): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 12:59:23 (7760): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 12:59:32 (7760): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 13:00:17 (7760): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 13:02:00 (7760): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 13:02:01 (7760): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 13:04:06 (7760): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 13:04:09 (7760): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 13:05:41 (7760): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 13:05:57 (7760): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 13:09:26 (7760): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 13:09:27 (7760): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 13:10:03 (7760): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 13:56:39 (7760): Status Report: Job Duration: '64800.000000' 2024-06-20 13:56:39 (7760): Status Report: Elapsed Time: '6000.176405' 2024-06-20 13:56:39 (7760): Status Report: CPU Time: '20316.712234' 2024-06-20 15:39:15 (7760): Status Report: Job Duration: '64800.000000' 2024-06-20 15:39:15 (7760): Status Report: Elapsed Time: '12000.176405' 2024-06-20 15:39:15 (7760): Status Report: CPU Time: '44065.508869' 2024-06-20 16:06:23 (7760): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 16:06:25 (7760): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 16:06:27 (7760): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 16:06:41 (7760): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 16:06:48 (7760): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 16:07:35 (7760): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 16:07:37 (7760): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 16:10:44 (7760): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 16:10:49 (7760): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 16:15:02 (7760): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 16:15:04 (7760): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 16:15:18 (7760): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 16:15:19 (7760): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 16:16:28 (7760): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 16:16:29 (7760): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 16:19:31 (7760): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 17:35:01 (7760): Status Report: Job Duration: '64800.000000' 2024-06-20 17:35:01 (7760): Status Report: Elapsed Time: '18000.176405' 2024-06-20 17:35:01 (7760): Status Report: CPU Time: '68637.616382' 2024-06-20 19:17:29 (7760): Status Report: Job Duration: '64800.000000' 2024-06-20 19:17:29 (7760): Status Report: Elapsed Time: '24000.176405' 2024-06-20 19:17:29 (7760): Status Report: CPU Time: '91137.219409' 2024-06-20 20:58:19 (7760): Status Report: Job Duration: '64800.000000' 2024-06-20 20:58:19 (7760): Status Report: Elapsed Time: '30000.176405' 2024-06-20 20:58:19 (7760): Status Report: CPU Time: '109896.838862' 2024-06-20 22:19:43 (7760): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 22:21:30 (7760): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 22:21:31 (7760): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 22:23:08 (7760): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 22:23:10 (7760): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 22:23:26 (7760): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 22:23:28 (7760): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 22:25:39 (7760): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 22:25:42 (7760): VM state change detected. (old = 'running', new = 'saving') 2024-06-21 11:55:36 (3136): vboxwrapper version 26207 2024-06-21 11:55:36 (3136): BOINC client version: 8.0.2 2024-06-21 11:55:59 (3136): Detected: VirtualBox VboxManage Interface (Version: 7.0.6) 2024-06-21 11:55:59 (3136): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds) 2024-06-21 11:56:00 (3136): Guest Log: BIOS: VirtualBox 7.0.6 2024-06-21 11:56:00 (3136): Guest Log: CPUID EDX: 0x178bfbff 2024-06-21 11:56:00 (3136): Guest Log: BIOS: No PCI IDE controller, not probing IDE 2024-06-21 11:56:00 (3136): Guest Log: BIOS: AHCI 0-P#0: PCHS=16383/16/63 LCHS=1024/255/63 0x0000000002800000 sectors 2024-06-21 11:56:00 (3136): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032 2024-06-21 11:56:00 (3136): Guest Log: BIOS: Booting from Hard Disk... 2024-06-21 11:56:00 (3136): Guest Log: BIOS: KBD: unsupported int 16h function 03 2024-06-21 11:56:00 (3136): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 2024-06-21 11:56:00 (3136): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds 2024-06-21 11:56:00 (3136): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000) 2024-06-21 11:56:00 (3136): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log 2024-06-21 11:56:00 (3136): Guest Log: 00:00:00.000082 main Log opened 2024-06-20T09:06:13.124575000Z 2024-06-21 11:56:00 (3136): Guest Log: 00:00:00.000170 main OS Product: Linux 2024-06-21 11:56:00 (3136): Guest Log: 00:00:00.000204 main OS Release: 4.14.232-19.cernvm.x86_64 2024-06-21 11:56:00 (3136): Guest Log: 00:00:00.000227 main OS Version: #1 SMP Fri Apr 30 17:12:25 CEST 2021 2024-06-21 11:56:00 (3136): Guest Log: 00:00:00.000247 main Executable: /usr/sbin/VBoxService 2024-06-21 11:56:00 (3136): Guest Log: 00:00:00.000247 main Process ID: 2283 2024-06-21 11:56:00 (3136): Guest Log: 00:00:00.000248 main Package type: LINUX_64BITS_GENERIC 2024-06-21 11:56:00 (3136): Guest Log: 00:00:00.001896 main 5.2.6 r120293 started. Verbose level = 0 2024-06-21 11:56:00 (3136): Guest Log: [INFO] Mounting the shared directory 2024-06-21 11:56:00 (3136): Guest Log: [INFO] Shared directory mounted, enabling vboxmonitor 2024-06-21 11:56:00 (3136): Guest Log: [INFO] Sourcing essential functions from /cvmfs/grid.cern.ch 2024-06-21 11:56:00 (3136): Guest Log: [INFO] Testing connection to cern.ch 2024-06-21 11:56:00 (3136): Guest Log: [INFO] Testing connection to VCCS 2024-06-21 11:56:00 (3136): Guest Log: [INFO] Testing connection to HTCondor 2024-06-21 11:56:00 (3136): Guest Log: [INFO] Testing connection to WMAgent 2024-06-21 11:56:00 (3136): Guest Log: [INFO] Testing connection to EOSCMS 2024-06-21 11:56:00 (3136): Guest Log: [INFO] Testing connection to CMS-Factory 2024-06-21 11:56:00 (3136): Guest Log: [INFO] Testing connection to CMS-Frontier 2024-06-21 11:56:00 (3136): Guest Log: [INFO] Testing connection to Frontier 2024-06-21 11:56:00 (3136): Guest Log: [INFO] Could not find a local HTTP proxy 2024-06-21 11:56:00 (3136): Guest Log: [INFO] CVMFS and Frontier will have to use DIRECT connections 2024-06-21 11:56:00 (3136): Guest Log: [INFO] This makes the application less efficient 2024-06-21 11:56:00 (3136): Guest Log: [INFO] It also puts higher load on the project servers 2024-06-21 11:56:00 (3136): Guest Log: [INFO] Setting up a local HTTP proxy is highly recommended 2024-06-21 11:56:00 (3136): Guest Log: [INFO] Advice can be found in the project forum 2024-06-21 11:56:00 (3136): Guest Log: [INFO] Reloading and probing the CVMFS configuration 2024-06-21 11:56:00 (3136): Guest Log: [INFO] Probing /cvmfs/grid.cern.ch... OK 2024-06-21 11:56:00 (3136): Guest Log: [INFO] Probing /cvmfs/singularity.opensciencegrid.org... OK 2024-06-21 11:56:00 (3136): Guest Log: [INFO] Probing /cvmfs/cms-ib.cern.ch... OK 2024-06-21 11:56:00 (3136): Guest Log: [INFO] Probing /cvmfs/cms.cern.ch... OK 2024-06-21 11:56:00 (3136): Guest Log: [INFO] Probing /cvmfs/oasis.opensciencegrid.org... OK 2024-06-21 11:56:00 (3136): Guest Log: [INFO] Excerpt from "cvmfs_config stat": VERSION HOST PROXY 2024-06-21 11:56:00 (3136): Guest Log: [INFO] 2.7.2.0 http://s1cern-cvmfs.openhtc.io DIRECT 2024-06-21 11:56:00 (3136): Guest Log: [INFO] Environment HTTP proxy: not set 2024-06-21 11:56:00 (3136): Guest Log: [INFO] Reading volunteer information 2024-06-21 11:56:00 (3136): Guest Log: [INFO] Requesting an X509 credential from LHC@home 2024-06-21 11:56:00 (3136): Guest Log: [INFO] Requesting an idtoken from LHC@home 2024-06-21 11:56:00 (3136): Guest Log: [INFO] CMS application starting. Check log files. 2024-06-21 11:56:00 (3136): Starting VM using VBoxManage interface. (boinc_9c3cb52103024680, slot#4) 2024-06-21 11:56:07 (3136): Successfully started VM. (PID = '5056') 2024-06-21 11:56:07 (3136): Reporting VM Process ID to BOINC. 2024-06-21 11:56:07 (3136): Guest Log: BIOS: VirtualBox 7.0.6 2024-06-21 11:56:07 (3136): Guest Log: CPUID EDX: 0x178bfbff 2024-06-21 11:56:07 (3136): Guest Log: BIOS: No PCI IDE controller, not probing IDE 2024-06-21 11:56:07 (3136): Guest Log: BIOS: AHCI 0-P#0: PCHS=16383/16/63 LCHS=1024/255/63 0x0000000002800000 sectors 2024-06-21 11:56:07 (3136): VM state change detected. (old = 'poweredoff', new = 'running') 2024-06-21 11:56:07 (3136): Detected: Web Application Enabled (http://localhost:51321) 2024-06-21 11:56:07 (3136): Status Report: Job Duration: '64800.000000' 2024-06-21 11:56:07 (3136): Status Report: Elapsed Time: '34831.176405' 2024-06-21 11:56:07 (3136): Status Report: CPU Time: '123926.492395' 2024-06-21 11:56:07 (3136): Preference change detected 2024-06-21 11:56:07 (3136): Setting CPU throttle for VM. (100%) 2024-06-21 11:56:08 (3136): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds)) 2024-06-21 11:56:09 (3136): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032 2024-06-21 11:56:09 (3136): Guest Log: BIOS: Booting from Hard Disk... 2024-06-21 11:56:11 (3136): Guest Log: BIOS: KBD: unsupported int 16h function 03 2024-06-21 11:56:11 (3136): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 2024-06-21 11:56:50 (3136): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds 2024-06-21 11:56:50 (3136): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000) 2024-06-21 11:56:51 (3136): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log 2024-06-21 11:56:51 (3136): Guest Log: 00:00:00.000134 main Log opened 2024-06-21T10:56:50.070527000Z 2024-06-21 11:56:51 (3136): Guest Log: 00:00:00.000228 main OS Product: Linux 2024-06-21 11:56:51 (3136): Guest Log: 00:00:00.000262 main OS Release: 4.14.232-19.cernvm.x86_64 2024-06-21 11:56:51 (3136): Guest Log: 00:00:00.000295 main OS Version: #1 SMP Fri Apr 30 17:12:25 CEST 2021 2024-06-21 11:56:51 (3136): Guest Log: 00:00:00.000321 main Executable: /usr/sbin/VBoxService 2024-06-21 11:56:51 (3136): Guest Log: 00:00:00.000322 main Process ID: 2284 2024-06-21 11:56:51 (3136): Guest Log: 00:00:00.000323 main Package type: LINUX_64BITS_GENERIC 2024-06-21 11:56:51 (3136): Guest Log: 00:00:00.000911 main 5.2.6 r120293 started. Verbose level = 0 2024-06-21 11:57:02 (3136): Guest Log: [INFO] Mounting the shared directory 2024-06-21 11:57:02 (3136): Guest Log: [INFO] Shared directory mounted, enabling vboxmonitor 2024-06-21 11:57:02 (3136): Guest Log: [INFO] Sourcing essential functions from /cvmfs/grid.cern.ch 2024-06-21 11:57:03 (3136): Guest Log: [INFO] Testing connection to cern.ch 2024-06-21 11:57:03 (3136): Guest Log: [INFO] Testing connection to VCCS 2024-06-21 11:57:03 (3136): Guest Log: [INFO] Testing connection to HTCondor 2024-06-21 11:57:03 (3136): Guest Log: [INFO] Testing connection to WMAgent 2024-06-21 11:57:03 (3136): Guest Log: [INFO] Testing connection to EOSCMS 2024-06-21 11:57:03 (3136): Guest Log: [INFO] Testing connection to CMS-Factory 2024-06-21 11:57:03 (3136): Guest Log: [INFO] Testing connection to CMS-Frontier 2024-06-21 11:57:04 (3136): Guest Log: [INFO] Testing connection to Frontier 2024-06-21 11:57:04 (3136): Guest Log: [INFO] Could not find a local HTTP proxy 2024-06-21 11:57:04 (3136): Guest Log: [INFO] CVMFS and Frontier will have to use DIRECT connections 2024-06-21 11:57:04 (3136): Guest Log: [INFO] This makes the application less efficient 2024-06-21 11:57:04 (3136): Guest Log: [INFO] It also puts higher load on the project servers 2024-06-21 11:57:04 (3136): Guest Log: [INFO] Setting up a local HTTP proxy is highly recommended 2024-06-21 11:57:04 (3136): Guest Log: [INFO] Advice can be found in the project forum 2024-06-21 11:57:04 (3136): Guest Log: [INFO] Reloading and probing the CVMFS configuration 2024-06-21 11:58:00 (3136): Guest Log: [INFO] Probing /cvmfs/grid.cern.ch... OK 2024-06-21 11:58:03 (3136): Guest Log: [INFO] Probing /cvmfs/oasis.opensciencegrid.org... OK 2024-06-21 11:58:03 (3136): Guest Log: [INFO] Probing /cvmfs/cms.cern.ch... OK 2024-06-21 11:58:04 (3136): Guest Log: [INFO] Probing /cvmfs/singularity.opensciencegrid.org... OK 2024-06-21 11:58:38 (3136): Guest Log: [INFO] Probing /cvmfs/cms-ib.cern.ch... OK 2024-06-21 11:58:38 (3136): Guest Log: [INFO] Excerpt from "cvmfs_config stat": VERSION HOST PROXY 2024-06-21 11:58:38 (3136): Guest Log: [INFO] 2.7.2.0 http://s1cern-cvmfs.openhtc.io DIRECT 2024-06-21 11:58:38 (3136): Guest Log: [INFO] Environment HTTP proxy: not set 2024-06-21 11:58:39 (3136): Guest Log: [INFO] Reading volunteer information 2024-06-21 11:58:41 (3136): Guest Log: [INFO] Requesting an X509 credential from LHC@home 2024-06-21 11:58:42 (3136): Guest Log: [INFO] Requesting an idtoken from LHC@home 2024-06-21 11:58:42 (3136): Guest Log: [INFO] CMS application starting. Check log files. 2024-06-21 12:01:20 (3136): VM state change detected. (old = 'running', new = 'paused') 2024-06-21 12:02:23 (3136): VM state change detected. (old = 'paused', new = 'running') 2024-06-21 12:02:26 (3136): VM state change detected. (old = 'running', new = 'paused') 2024-06-21 12:02:53 (3136): VM state change detected. (old = 'paused', new = 'running') 2024-06-21 12:03:11 (3136): VM state change detected. (old = 'running', new = 'paused') 2024-06-21 12:07:04 (3136): Preference change detected 2024-06-21 12:07:04 (3136): Setting CPU throttle for VM. (100%) 2024-06-21 12:07:05 (3136): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds)) 2024-06-21 12:07:05 (3136): VM state change detected. (old = 'paused', new = 'running') 2024-06-21 13:34:42 (3136): VM state change detected. (old = 'running', new = 'paused') 2024-06-21 13:35:42 (3136): VM state change detected. (old = 'paused', new = 'running') 2024-06-21 13:37:17 (3136): VM state change detected. (old = 'running', new = 'paused') 2024-06-21 13:55:02 (3136): VM state change detected. (old = 'paused', new = 'running') 2024-06-21 13:58:36 (3136): VM state change detected. (old = 'running', new = 'paused') 2024-06-21 14:51:32 (3136): VM state change detected. (old = 'paused', new = 'running') 2024-06-21 14:51:40 (3136): Guest Log: 01:37:49.403206 timesync vgsvcTimeSyncWorker: Radical host time change: 3 184 700 000 000ns (HostNow=1 718 977 900 215 000 000 ns HostLast=1 718 974 715 515 000 000 ns) 2024-06-21 14:51:47 (3136): VM state change detected. (old = 'running', new = 'paused') 2024-06-21 14:53:29 (3136): VM state change detected. (old = 'paused', new = 'running') 2024-06-21 14:53:32 (3136): Guest Log: 01:37:59.408825 timesync vgsvcTimeSyncWorker: Radical guest time change: 3 184 707 169 000ns (GuestNow=1 718 977 910 228 543 000 ns GuestLast=1 718 974 725 521 374 000 ns fSetTimeLastLoop=true ) 2024-06-21 14:53:34 (3136): VM state change detected. (old = 'running', new = 'paused') 2024-06-21 15:19:03 (3136): VM state change detected. (old = 'paused', new = 'running') 2024-06-21 15:21:33 (3136): Status Report: Job Duration: '64800.000000' 2024-06-21 15:21:33 (3136): Status Report: Elapsed Time: '40831.176405' 2024-06-21 15:21:33 (3136): Status Report: CPU Time: '139461.883180' 2024-06-21 16:35:20 (3136): VM state change detected. (old = 'running', new = 'paused') 2024-06-21 16:55:45 (3136): VM state change detected. (old = 'paused', new = 'running') 2024-06-21 16:56:09 (3136): VM state change detected. (old = 'running', new = 'paused') 2024-06-21 16:57:23 (3136): VM state change detected. (old = 'paused', new = 'running') 2024-06-21 16:59:24 (3136): VM state change detected. (old = 'running', new = 'paused') 2024-06-21 17:03:39 (3136): VM state change detected. (old = 'paused', new = 'running') 2024-06-21 17:05:02 (3136): VM state change detected. (old = 'running', new = 'paused') 2024-06-21 17:06:16 (3136): VM state change detected. (old = 'paused', new = 'running') 2024-06-21 17:29:48 (3136): Status Report: Job Duration: '64800.000000' 2024-06-21 17:29:48 (3136): Status Report: Elapsed Time: '46831.176405' 2024-06-21 17:29:48 (3136): Status Report: CPU Time: '157014.242095' 2024-06-21 18:59:05 (3136): VM state change detected. (old = 'running', new = 'paused') 2024-06-21 19:50:02 (3136): VM state change detected. (old = 'paused', new = 'running') 2024-06-21 19:50:07 (3136): Guest Log: 04:51:06.179590 timesync vgsvcTimeSyncWorker: Radical host time change: 3 066 785 000 000ns (HostNow=1 718 995 807 251 000 000 ns HostLast=1 718 992 740 466 000 000 ns) 2024-06-21 19:50:18 (3136): Guest Log: 04:51:16.180821 timesync vgsvcTimeSyncWorker: Radical guest time change: 3 465 013 793 000ns (GuestNow=1 718 995 817 252 394 000 ns GuestLast=1 718 992 352 238 601 000 ns fSetTimeLastLoop=true ) 2024-06-21 20:02:01 (3136): Status Report: Job Duration: '64800.000000' 2024-06-21 20:02:01 (3136): Status Report: Elapsed Time: '52831.176405' 2024-06-21 20:02:01 (3136): Status Report: CPU Time: '175922.811303' 2024-06-21 21:45:28 (3136): Status Report: Job Duration: '64800.000000' 2024-06-21 21:45:28 (3136): Status Report: Elapsed Time: '58831.235039' 2024-06-21 21:45:28 (3136): Status Report: CPU Time: '196590.650588' 2024-06-21 23:28:09 (3136): Powering off VM. 2024-06-21 23:28:09 (3136): Successfully stopped VM. 2024-06-21 23:28:09 (3136): Deregistering VM. (boinc_9c3cb52103024680, slot#4) 2024-06-21 23:28:10 (3136): Removing network bandwidth throttle group from VM. 2024-06-21 23:28:10 (3136): Removing VM from VirtualBox. 2024-06-21 23:28:15 (3136): called boinc_finish(0) </stderr_txt> ]]>
©2024 CERN