| Name | CMS_1659651_1718872447.211500_0 |
| Workunit | 223664980 |
| Created | 20 Jun 2024, 8:34:10 UTC |
| Sent | 20 Jun 2024, 8:49:26 UTC |
| Report deadline | 21 Jul 2024, 8:49:26 UTC |
| Received | 21 Jun 2024, 16:49:27 UTC |
| Server state | Over |
| Outcome | Success |
| Client state | Done |
| Exit status | 0 (0x00000000) |
| Computer ID | 10397649 |
| Run time | 13 hours 38 min 17 sec |
| CPU time | 1 days 20 hours 31 min 14 sec |
| Validate state | Valid |
| Credit | 1,935.30 |
| Device peak FLOPS | 18.27 GFLOPS |
| Application version | CMS Simulation v70.30 (vbox64_mt_mcore_cms) windows_x86_64 |
| Peak working set size | 97.95 MB |
| Peak swap size | 93.61 MB |
| Peak disk usage | 2.42 GB |
Stderr output
<core_client_version>8.0.2</core_client_version> <![CDATA[ <stderr_txt> 2024-06-20 10:03:44 (7508): vboxwrapper version 26207 2024-06-20 10:03:44 (7508): BOINC client version: 8.0.2 2024-06-20 10:03:46 (7508): Detected: VirtualBox VboxManage Interface (Version: 7.0.6) 2024-06-20 10:03:46 (7508): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds) 2024-06-20 10:03:46 (7508): Successfully copied 'init_data.xml' to the shared directory. 2024-06-20 10:03:46 (7508): Create VM. (boinc_4f2a2d4c1e80d078, slot#0) 2024-06-20 10:03:47 (7508): Setting Memory Size for VM. (4584MB) 2024-06-20 10:03:48 (7508): Setting CPU Count for VM. (4) 2024-06-20 10:03:48 (7508): Setting Chipset Options for VM. 2024-06-20 10:03:48 (7508): Setting Graphics Controller Options for VM. 2024-06-20 10:03:48 (7508): Setting Boot Options for VM. 2024-06-20 10:03:49 (7508): Setting Network Configuration for NAT. 2024-06-20 10:03:49 (7508): Enabling VM Network Access. 2024-06-20 10:03:50 (7508): Disabling USB Support for VM. 2024-06-20 10:03:50 (7508): Disabling COM Port Support for VM. 2024-06-20 10:03:50 (7508): Disabling LPT Port Support for VM. 2024-06-20 10:03:51 (7508): Disabling Audio Support for VM. 2024-06-20 10:03:51 (7508): Disabling Clipboard Support for VM. 2024-06-20 10:03:51 (7508): Disabling Drag and Drop Support for VM. 2024-06-20 10:03:52 (7508): Adding storage controller(s) to VM. 2024-06-20 10:03:52 (7508): Adding virtual disk drive to VM. (CMS_2024_04_29_prod.vdi) 2024-06-20 10:03:53 (7508): Adding VirtualBox Guest Additions to VM. 2024-06-20 10:03:54 (7508): Adding network bandwidth throttle group to VM. (Defaulting to 1024GB) 2024-06-20 10:03:54 (7508): forwarding host port 51256 to guest port 80 2024-06-20 10:03:54 (7508): Enabling remote desktop for VM. 2024-06-20 10:03:55 (7508): Required extension pack not installed, remote desktop not enabled. 2024-06-20 10:03:55 (7508): Enabling shared directory for VM. 2024-06-20 10:03:55 (7508): Starting VM using VBoxManage interface. (boinc_4f2a2d4c1e80d078, slot#0) 2024-06-20 10:04:01 (7508): Successfully started VM. (PID = '2164') 2024-06-20 10:04:01 (7508): Reporting VM Process ID to BOINC. 2024-06-20 10:04:01 (7508): Guest Log: BIOS: VirtualBox 7.0.6 2024-06-20 10:04:01 (7508): Guest Log: CPUID EDX: 0x178bfbff 2024-06-20 10:04:01 (7508): Guest Log: BIOS: No PCI IDE controller, not probing IDE 2024-06-20 10:04:01 (7508): Guest Log: BIOS: AHCI 0-P#0: PCHS=16383/16/63 LCHS=1024/255/63 0x0000000002800000 sectors 2024-06-20 10:04:01 (7508): VM state change detected. (old = 'poweredoff', new = 'running') 2024-06-20 10:04:01 (7508): Detected: Web Application Enabled (http://localhost:51256) 2024-06-20 10:04:02 (7508): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 10:04:14 (7508): Preference change detected 2024-06-20 10:04:14 (7508): Setting CPU throttle for VM. (100%) 2024-06-20 10:04:14 (7508): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds)) 2024-06-20 10:04:14 (7508): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 10:04:15 (7508): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032 2024-06-20 10:04:15 (7508): Guest Log: BIOS: Booting from Hard Disk... 2024-06-20 10:04:17 (7508): Guest Log: BIOS: KBD: unsupported int 16h function 03 2024-06-20 10:04:17 (7508): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 2024-06-20 10:04:43 (7508): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds 2024-06-20 10:04:43 (7508): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000) 2024-06-20 10:04:45 (7508): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log 2024-06-20 10:04:45 (7508): Guest Log: 00:00:00.000135 main Log opened 2024-06-20T09:04:44.327901000Z 2024-06-20 10:04:45 (7508): Guest Log: 00:00:00.000245 main OS Product: Linux 2024-06-20 10:04:45 (7508): Guest Log: 00:00:00.000275 main OS Release: 4.14.232-19.cernvm.x86_64 2024-06-20 10:04:45 (7508): Guest Log: 00:00:00.000299 main OS Version: #1 SMP Fri Apr 30 17:12:25 CEST 2021 2024-06-20 10:04:45 (7508): Guest Log: 00:00:00.000322 main Executable: /usr/sbin/VBoxService 2024-06-20 10:04:45 (7508): Guest Log: 00:00:00.000322 main Process ID: 2281 2024-06-20 10:04:45 (7508): Guest Log: 00:00:00.000323 main Package type: LINUX_64BITS_GENERIC 2024-06-20 10:04:45 (7508): Guest Log: 00:00:00.001028 main 5.2.6 r120293 started. Verbose level = 0 2024-06-20 10:05:15 (7508): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 10:05:35 (7508): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 10:05:48 (7508): Guest Log: [INFO] Mounting the shared directory 2024-06-20 10:05:48 (7508): Guest Log: [INFO] Shared directory mounted, enabling vboxmonitor 2024-06-20 10:05:48 (7508): Guest Log: [INFO] Sourcing essential functions from /cvmfs/grid.cern.ch 2024-06-20 10:05:49 (7508): Guest Log: [INFO] Testing connection to cern.ch 2024-06-20 10:05:49 (7508): Guest Log: [INFO] Testing connection to VCCS 2024-06-20 10:05:49 (7508): Guest Log: [INFO] Testing connection to HTCondor 2024-06-20 10:05:49 (7508): Guest Log: [INFO] Testing connection to WMAgent 2024-06-20 10:05:50 (7508): Guest Log: [INFO] Testing connection to EOSCMS 2024-06-20 10:05:50 (7508): Guest Log: [INFO] Testing connection to CMS-Factory 2024-06-20 10:05:50 (7508): Guest Log: [INFO] Testing connection to CMS-Frontier 2024-06-20 10:05:50 (7508): Guest Log: [INFO] Testing connection to Frontier 2024-06-20 10:05:51 (7508): Guest Log: [INFO] Could not find a local HTTP proxy 2024-06-20 10:05:51 (7508): Guest Log: [INFO] CVMFS and Frontier will have to use DIRECT connections 2024-06-20 10:05:51 (7508): Guest Log: [INFO] This makes the application less efficient 2024-06-20 10:05:51 (7508): Guest Log: [INFO] It also puts higher load on the project servers 2024-06-20 10:05:51 (7508): Guest Log: [INFO] Setting up a local HTTP proxy is highly recommended 2024-06-20 10:05:51 (7508): Guest Log: [INFO] Advice can be found in the project forum 2024-06-20 10:05:51 (7508): Guest Log: [INFO] Reloading and probing the CVMFS configuration 2024-06-20 10:06:26 (7508): Guest Log: [INFO] Probing /cvmfs/grid.cern.ch... OK 2024-06-20 10:06:28 (7508): Guest Log: [INFO] Probing /cvmfs/cms-ib.cern.ch... OK 2024-06-20 10:06:28 (7508): Guest Log: [INFO] Probing /cvmfs/cms.cern.ch... OK 2024-06-20 10:07:14 (7508): Guest Log: [INFO] Probing /cvmfs/oasis.opensciencegrid.org... OK 2024-06-20 10:07:57 (7508): Guest Log: [INFO] Probing /cvmfs/singularity.opensciencegrid.org... OK 2024-06-20 10:07:58 (7508): Guest Log: [INFO] Excerpt from "cvmfs_config stat": VERSION HOST PROXY 2024-06-20 10:07:58 (7508): Guest Log: [INFO] 2.7.2.0 http://s1cern-cvmfs.openhtc.io DIRECT 2024-06-20 10:07:58 (7508): Guest Log: [INFO] Environment HTTP proxy: not set 2024-06-20 10:07:58 (7508): Guest Log: [INFO] Reading volunteer information 2024-06-20 10:08:12 (7508): Guest Log: [INFO] Requesting an X509 credential from LHC@home 2024-06-20 10:08:13 (7508): Guest Log: [INFO] Requesting an idtoken from LHC@home 2024-06-20 10:08:14 (7508): Guest Log: [INFO] CMS application starting. Check log files. 2024-06-20 10:27:06 (7508): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 10:44:35 (7508): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 10:44:48 (7508): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 10:45:07 (7508): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 10:45:18 (7508): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 10:45:38 (7508): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 10:45:48 (7508): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 10:46:08 (7508): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 10:46:18 (7508): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 10:46:38 (7508): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 10:46:48 (7508): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 10:47:08 (7508): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 10:47:18 (7508): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 10:47:39 (7508): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 10:47:48 (7508): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 10:48:08 (7508): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 10:48:18 (7508): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 10:48:38 (7508): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 10:48:48 (7508): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 10:49:08 (7508): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 10:49:19 (7508): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 10:49:39 (7508): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 10:49:48 (7508): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 10:50:08 (7508): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 10:50:19 (7508): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 10:50:39 (7508): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 10:50:49 (7508): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 10:51:09 (7508): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 10:51:19 (7508): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 10:51:39 (7508): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 10:51:50 (7508): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 10:52:10 (7508): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 10:52:21 (7508): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 10:52:40 (7508): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 10:52:50 (7508): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 10:53:10 (7508): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 10:53:20 (7508): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 10:53:40 (7508): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 10:53:49 (7508): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 10:54:09 (7508): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 10:54:20 (7508): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 10:54:39 (7508): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 10:54:49 (7508): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 10:55:09 (7508): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 10:55:20 (7508): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 10:55:40 (7508): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 10:55:50 (7508): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 10:56:10 (7508): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 10:56:20 (7508): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 10:56:40 (7508): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 10:56:50 (7508): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 10:57:10 (7508): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 10:57:20 (7508): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 10:57:40 (7508): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 10:57:50 (7508): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 10:58:10 (7508): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 10:58:21 (7508): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 10:58:41 (7508): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 10:58:51 (7508): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 10:59:11 (7508): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 10:59:21 (7508): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 10:59:41 (7508): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 10:59:50 (7508): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 11:00:10 (7508): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 11:00:21 (7508): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 11:00:41 (7508): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 11:00:51 (7508): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 11:01:11 (7508): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 11:01:22 (7508): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 11:01:42 (7508): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 11:01:52 (7508): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 11:02:12 (7508): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 11:02:21 (7508): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 11:02:42 (7508): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 11:02:51 (7508): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 11:03:11 (7508): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 11:03:22 (7508): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 11:03:42 (7508): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 11:03:52 (7508): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 11:04:12 (7508): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 11:04:22 (7508): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 11:04:42 (7508): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 11:04:53 (7508): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 11:05:13 (7508): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 11:05:23 (7508): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 11:05:43 (7508): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 11:05:53 (7508): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 11:06:12 (7508): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 11:06:22 (7508): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 11:06:42 (7508): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 11:06:52 (7508): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 11:07:12 (7508): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 11:07:23 (7508): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 11:07:43 (7508): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 11:07:53 (7508): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 11:08:13 (7508): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 11:08:23 (7508): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 11:08:43 (7508): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 11:08:53 (7508): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 11:09:13 (7508): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 11:09:23 (7508): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 11:09:43 (7508): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 11:09:54 (7508): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 11:10:14 (7508): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 11:10:24 (7508): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 11:10:44 (7508): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 11:10:54 (7508): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 11:11:14 (7508): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 11:11:23 (7508): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 11:11:43 (7508): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 11:11:53 (7508): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 11:12:13 (7508): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 11:12:24 (7508): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 11:12:44 (7508): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 11:12:54 (7508): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 11:13:14 (7508): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 11:13:24 (7508): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 11:13:44 (7508): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 11:13:55 (7508): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 11:14:15 (7508): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 11:14:25 (7508): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 11:14:45 (7508): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 11:14:55 (7508): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 11:15:15 (7508): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 11:15:25 (7508): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 11:15:45 (7508): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 11:15:55 (7508): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 11:16:15 (7508): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 11:16:25 (7508): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 11:16:45 (7508): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 11:16:55 (7508): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 11:17:15 (7508): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 11:17:25 (7508): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 11:17:45 (7508): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 11:17:55 (7508): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 11:18:15 (7508): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 11:18:25 (7508): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 11:18:46 (7508): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 11:18:56 (7508): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 11:19:15 (7508): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 11:19:26 (7508): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 11:19:45 (7508): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 11:19:55 (7508): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 11:20:15 (7508): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 11:20:26 (7508): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 11:20:46 (7508): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 11:20:56 (7508): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 11:21:16 (7508): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 11:21:26 (7508): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 11:21:46 (7508): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 11:21:57 (7508): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 11:22:17 (7508): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 11:22:27 (7508): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 11:22:47 (7508): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 11:22:56 (7508): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 11:23:16 (7508): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 11:23:27 (7508): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 11:23:46 (7508): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 11:23:57 (7508): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 11:24:17 (7508): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 11:24:27 (7508): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 11:24:47 (7508): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 11:24:57 (7508): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 11:25:16 (7508): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 11:25:27 (7508): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 11:25:47 (7508): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 11:25:57 (7508): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 11:26:17 (7508): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 11:26:27 (7508): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 11:26:47 (7508): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 11:26:58 (7508): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 11:27:18 (7508): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 11:27:28 (7508): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 11:27:48 (7508): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 11:27:57 (7508): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 11:28:17 (7508): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 11:28:28 (7508): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 11:28:48 (7508): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 11:28:58 (7508): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 11:29:18 (7508): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 11:29:28 (7508): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 11:29:47 (7508): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 11:29:57 (7508): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 11:30:18 (7508): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 11:30:28 (7508): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 11:30:48 (7508): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 11:30:58 (7508): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 11:31:18 (7508): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 11:31:29 (7508): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 11:31:48 (7508): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 11:31:59 (7508): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 11:32:19 (7508): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 11:32:28 (7508): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 11:32:48 (7508): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 11:32:58 (7508): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 11:33:19 (7508): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 11:33:29 (7508): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 11:33:48 (7508): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 11:33:58 (7508): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 11:34:18 (7508): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 11:34:29 (7508): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 11:34:49 (7508): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 11:34:59 (7508): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 11:35:19 (7508): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 11:35:29 (7508): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 11:35:49 (7508): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 11:35:59 (7508): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 11:36:20 (7508): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 11:36:29 (7508): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 11:36:49 (7508): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 11:36:59 (7508): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 11:37:19 (7508): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 11:37:29 (7508): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 11:37:49 (7508): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 11:38:00 (7508): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 11:38:20 (7508): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 11:38:29 (7508): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 11:38:49 (7508): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 11:38:59 (7508): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 11:39:20 (7508): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 11:39:30 (7508): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 11:39:50 (7508): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 11:40:00 (7508): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 11:40:20 (7508): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 11:40:30 (7508): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 11:40:50 (7508): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 11:41:00 (7508): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 11:41:21 (7508): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 11:41:30 (7508): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 11:43:31 (7508): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 11:43:41 (7508): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 11:44:00 (7508): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 11:44:10 (7508): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 11:45:06 (7508): Preference change detected 2024-06-20 11:45:06 (7508): Setting CPU throttle for VM. (100%) 2024-06-20 11:45:08 (7508): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds)) 2024-06-20 11:45:08 (7508): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 11:45:54 (7508): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 11:46:08 (7508): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 11:46:28 (7508): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 11:46:58 (7508): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 11:46:59 (7508): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 11:52:19 (7508): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 11:52:20 (7508): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 11:55:54 (7508): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 12:01:58 (7508): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 12:03:20 (7508): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 12:03:27 (7508): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 12:03:54 (7508): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 12:06:35 (7508): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 12:06:38 (7508): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 12:06:40 (7508): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 12:06:48 (7508): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 12:09:07 (7508): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 12:22:08 (7508): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 12:22:13 (7508): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 12:23:13 (7508): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 12:23:14 (7508): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 12:24:34 (7508): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 12:24:35 (7508): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 12:27:01 (7508): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 12:27:05 (7508): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 12:30:49 (7508): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 12:30:51 (7508): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 12:31:15 (7508): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 12:31:34 (7508): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 12:32:09 (7508): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 12:32:18 (7508): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 12:32:33 (7508): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 12:32:39 (7508): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 12:35:06 (7508): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 12:35:17 (7508): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 12:36:19 (7508): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 12:36:24 (7508): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 12:37:43 (7508): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 12:37:49 (7508): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 12:41:54 (7508): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 12:42:08 (7508): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 12:47:09 (7508): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 12:47:13 (7508): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 12:51:41 (7508): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 12:52:02 (7508): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 12:52:19 (7508): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 12:52:21 (7508): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 12:52:28 (7508): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 12:52:43 (7508): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 12:54:25 (7508): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 12:54:26 (7508): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 12:54:38 (7508): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 12:54:40 (7508): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 12:55:09 (7508): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 12:55:17 (7508): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 12:55:47 (7508): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 12:56:23 (7508): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 12:57:03 (7508): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 12:57:17 (7508): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 12:58:07 (7508): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 12:58:10 (7508): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 12:58:37 (7508): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 12:58:45 (7508): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 12:58:57 (7508): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 12:58:58 (7508): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 12:59:06 (7508): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 12:59:24 (7508): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 12:59:33 (7508): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 13:00:17 (7508): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 13:02:00 (7508): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 13:02:01 (7508): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 13:04:06 (7508): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 13:04:09 (7508): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 13:05:41 (7508): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 13:05:57 (7508): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 13:09:25 (7508): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 13:09:26 (7508): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 13:10:02 (7508): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 13:55:38 (7508): Status Report: Job Duration: '64800.000000' 2024-06-20 13:55:38 (7508): Status Report: Elapsed Time: '6000.069761' 2024-06-20 13:55:38 (7508): Status Report: CPU Time: '22852.336888' 2024-06-20 15:38:14 (7508): Status Report: Job Duration: '64800.000000' 2024-06-20 15:38:14 (7508): Status Report: Elapsed Time: '12000.069761' 2024-06-20 15:38:14 (7508): Status Report: CPU Time: '46683.611252' 2024-06-20 16:06:23 (7508): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 16:06:25 (7508): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 16:06:27 (7508): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 16:06:41 (7508): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 16:06:48 (7508): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 16:07:35 (7508): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 16:07:37 (7508): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 16:10:44 (7508): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 16:10:49 (7508): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 16:15:02 (7508): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 16:15:04 (7508): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 16:15:18 (7508): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 16:15:19 (7508): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 16:16:28 (7508): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 16:16:30 (7508): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 16:19:31 (7508): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 17:34:00 (7508): Status Report: Job Duration: '64800.000000' 2024-06-20 17:34:00 (7508): Status Report: Elapsed Time: '18000.069761' 2024-06-20 17:34:00 (7508): Status Report: CPU Time: '70663.772970' 2024-06-20 19:16:34 (7508): Status Report: Job Duration: '64800.000000' 2024-06-20 19:16:34 (7508): Status Report: Elapsed Time: '24000.235409' 2024-06-20 19:16:34 (7508): Status Report: CPU Time: '93466.907143' 2024-06-20 20:57:25 (7508): Status Report: Job Duration: '64800.000000' 2024-06-20 20:57:25 (7508): Status Report: Elapsed Time: '30000.235409' 2024-06-20 20:57:25 (7508): Status Report: CPU Time: '108298.655418' 2024-06-20 22:19:43 (7508): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 22:21:30 (7508): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 22:21:31 (7508): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 22:23:08 (7508): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 22:23:10 (7508): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 22:23:26 (7508): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 22:23:28 (7508): VM state change detected. (old = 'running', new = 'paused') 2024-06-20 22:25:39 (7508): VM state change detected. (old = 'paused', new = 'running') 2024-06-20 22:25:48 (7508): VM state change detected. (old = 'running', new = 'saving') 2024-06-21 11:55:36 (4084): vboxwrapper version 26207 2024-06-21 11:55:36 (4084): BOINC client version: 8.0.2 2024-06-21 11:55:59 (4084): Detected: VirtualBox VboxManage Interface (Version: 7.0.6) 2024-06-21 11:55:59 (4084): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds) 2024-06-21 11:56:00 (4084): Guest Log: BIOS: VirtualBox 7.0.6 2024-06-21 11:56:00 (4084): Guest Log: CPUID EDX: 0x178bfbff 2024-06-21 11:56:00 (4084): Guest Log: BIOS: No PCI IDE controller, not probing IDE 2024-06-21 11:56:00 (4084): Guest Log: BIOS: AHCI 0-P#0: PCHS=16383/16/63 LCHS=1024/255/63 0x0000000002800000 sectors 2024-06-21 11:56:00 (4084): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032 2024-06-21 11:56:00 (4084): Guest Log: BIOS: Booting from Hard Disk... 2024-06-21 11:56:00 (4084): Guest Log: BIOS: KBD: unsupported int 16h function 03 2024-06-21 11:56:00 (4084): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 2024-06-21 11:56:00 (4084): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds 2024-06-21 11:56:00 (4084): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000) 2024-06-21 11:56:00 (4084): 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 (4084): Guest Log: 00:00:00.000135 main Log opened 2024-06-20T09:04:44.327901000Z 2024-06-21 11:56:00 (4084): Guest Log: 00:00:00.000245 main OS Product: Linux 2024-06-21 11:56:00 (4084): Guest Log: 00:00:00.000275 main OS Release: 4.14.232-19.cernvm.x86_64 2024-06-21 11:56:00 (4084): Guest Log: 00:00:00.000299 main OS Version: #1 SMP Fri Apr 30 17:12:25 CEST 2021 2024-06-21 11:56:00 (4084): Guest Log: 00:00:00.000322 main Executable: /usr/sbin/VBoxService 2024-06-21 11:56:00 (4084): Guest Log: 00:00:00.000322 main Process ID: 2281 2024-06-21 11:56:00 (4084): Guest Log: 00:00:00.000323 main Package type: LINUX_64BITS_GENERIC 2024-06-21 11:56:00 (4084): Guest Log: 00:00:00.001028 main 5.2.6 r120293 started. Verbose level = 0 2024-06-21 11:56:00 (4084): Guest Log: [INFO] Mounting the shared directory 2024-06-21 11:56:00 (4084): Guest Log: [INFO] Shared directory mounted, enabling vboxmonitor 2024-06-21 11:56:00 (4084): Guest Log: [INFO] Sourcing essential functions from /cvmfs/grid.cern.ch 2024-06-21 11:56:00 (4084): Guest Log: [INFO] Testing connection to cern.ch 2024-06-21 11:56:00 (4084): Guest Log: [INFO] Testing connection to VCCS 2024-06-21 11:56:00 (4084): Guest Log: [INFO] Testing connection to HTCondor 2024-06-21 11:56:00 (4084): Guest Log: [INFO] Testing connection to WMAgent 2024-06-21 11:56:00 (4084): Guest Log: [INFO] Testing connection to EOSCMS 2024-06-21 11:56:00 (4084): Guest Log: [INFO] Testing connection to CMS-Factory 2024-06-21 11:56:00 (4084): Guest Log: [INFO] Testing connection to CMS-Frontier 2024-06-21 11:56:00 (4084): Guest Log: [INFO] Testing connection to Frontier 2024-06-21 11:56:00 (4084): Guest Log: [INFO] Could not find a local HTTP proxy 2024-06-21 11:56:00 (4084): Guest Log: [INFO] CVMFS and Frontier will have to use DIRECT connections 2024-06-21 11:56:00 (4084): Guest Log: [INFO] This makes the application less efficient 2024-06-21 11:56:00 (4084): Guest Log: [INFO] It also puts higher load on the project servers 2024-06-21 11:56:00 (4084): Guest Log: [INFO] Setting up a local HTTP proxy is highly recommended 2024-06-21 11:56:00 (4084): Guest Log: [INFO] Advice can be found in the project forum 2024-06-21 11:56:00 (4084): Guest Log: [INFO] Reloading and probing the CVMFS configuration 2024-06-21 11:56:00 (4084): Guest Log: [INFO] Probing /cvmfs/grid.cern.ch... OK 2024-06-21 11:56:00 (4084): Guest Log: [INFO] Probing /cvmfs/cms-ib.cern.ch... OK 2024-06-21 11:56:00 (4084): Guest Log: [INFO] Probing /cvmfs/cms.cern.ch... OK 2024-06-21 11:56:00 (4084): Guest Log: [INFO] Probing /cvmfs/oasis.opensciencegrid.org... OK 2024-06-21 11:56:00 (4084): Guest Log: [INFO] Probing /cvmfs/singularity.opensciencegrid.org... OK 2024-06-21 11:56:00 (4084): Guest Log: [INFO] Excerpt from "cvmfs_config stat": VERSION HOST PROXY 2024-06-21 11:56:00 (4084): Guest Log: [INFO] 2.7.2.0 http://s1cern-cvmfs.openhtc.io DIRECT 2024-06-21 11:56:00 (4084): Guest Log: [INFO] Environment HTTP proxy: not set 2024-06-21 11:56:00 (4084): Guest Log: [INFO] Reading volunteer information 2024-06-21 11:56:00 (4084): Guest Log: [INFO] Requesting an X509 credential from LHC@home 2024-06-21 11:56:00 (4084): Guest Log: [INFO] Requesting an idtoken from LHC@home 2024-06-21 11:56:00 (4084): Guest Log: [INFO] CMS application starting. Check log files. 2024-06-21 11:56:00 (4084): Starting VM using VBoxManage interface. (boinc_4f2a2d4c1e80d078, slot#0) 2024-06-21 11:56:07 (4084): Successfully started VM. (PID = '5080') 2024-06-21 11:56:07 (4084): Reporting VM Process ID to BOINC. 2024-06-21 11:56:07 (4084): Guest Log: BIOS: VirtualBox 7.0.6 2024-06-21 11:56:07 (4084): Guest Log: CPUID EDX: 0x178bfbff 2024-06-21 11:56:07 (4084): Guest Log: BIOS: No PCI IDE controller, not probing IDE 2024-06-21 11:56:07 (4084): Guest Log: BIOS: AHCI 0-P#0: PCHS=16383/16/63 LCHS=1024/255/63 0x0000000002800000 sectors 2024-06-21 11:56:07 (4084): VM state change detected. (old = 'poweredoff', new = 'running') 2024-06-21 11:56:07 (4084): Detected: Web Application Enabled (http://localhost:51256) 2024-06-21 11:56:07 (4084): Status Report: Job Duration: '64800.000000' 2024-06-21 11:56:07 (4084): Status Report: Elapsed Time: '34886.235409' 2024-06-21 11:56:07 (4084): Status Report: CPU Time: '123719.713070' 2024-06-21 11:56:07 (4084): Preference change detected 2024-06-21 11:56:07 (4084): Setting CPU throttle for VM. (100%) 2024-06-21 11:56:08 (4084): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds)) 2024-06-21 11:56:09 (4084): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032 2024-06-21 11:56:09 (4084): Guest Log: BIOS: Booting from Hard Disk... 2024-06-21 11:56:11 (4084): Guest Log: BIOS: KBD: unsupported int 16h function 03 2024-06-21 11:56:11 (4084): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 2024-06-21 11:56:48 (4084): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds 2024-06-21 11:56:48 (4084): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000) 2024-06-21 11:56:50 (4084): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log 2024-06-21 11:56:50 (4084): Guest Log: 00:00:00.000129 main Log opened 2024-06-21T10:56:55.652303000Z 2024-06-21 11:56:50 (4084): Guest Log: 00:00:00.000247 main OS Product: Linux 2024-06-21 11:56:50 (4084): Guest Log: 00:00:00.000287 main OS Release: 4.14.232-19.cernvm.x86_64 2024-06-21 11:56:50 (4084): Guest Log: 00:00:00.000321 main OS Version: #1 SMP Fri Apr 30 17:12:25 CEST 2021 2024-06-21 11:56:50 (4084): Guest Log: 00:00:00.000354 main Executable: /usr/sbin/VBoxService 2024-06-21 11:56:50 (4084): Guest Log: 00:00:00.000355 main Process ID: 2286 2024-06-21 11:56:50 (4084): Guest Log: 00:00:00.000356 main Package type: LINUX_64BITS_GENERIC 2024-06-21 11:56:50 (4084): Guest Log: 00:00:00.001617 main 5.2.6 r120293 started. Verbose level = 0 2024-06-21 11:57:33 (4084): Guest Log: [INFO] Mounting the shared directory 2024-06-21 11:57:33 (4084): Guest Log: [INFO] Shared directory mounted, enabling vboxmonitor 2024-06-21 11:57:34 (4084): Guest Log: [INFO] Sourcing essential functions from /cvmfs/grid.cern.ch 2024-06-21 11:57:34 (4084): Guest Log: [INFO] Testing connection to cern.ch 2024-06-21 11:57:34 (4084): Guest Log: [INFO] Testing connection to VCCS 2024-06-21 11:57:34 (4084): Guest Log: [INFO] Testing connection to HTCondor 2024-06-21 11:57:34 (4084): Guest Log: [INFO] Testing connection to WMAgent 2024-06-21 11:57:34 (4084): Guest Log: [INFO] Testing connection to EOSCMS 2024-06-21 11:57:34 (4084): Guest Log: [INFO] Testing connection to CMS-Factory 2024-06-21 11:57:34 (4084): Guest Log: [INFO] Testing connection to CMS-Frontier 2024-06-21 11:57:34 (4084): Guest Log: [INFO] Testing connection to Frontier 2024-06-21 11:57:35 (4084): Guest Log: [INFO] Could not find a local HTTP proxy 2024-06-21 11:57:35 (4084): Guest Log: [INFO] CVMFS and Frontier will have to use DIRECT connections 2024-06-21 11:57:35 (4084): Guest Log: [INFO] This makes the application less efficient 2024-06-21 11:57:35 (4084): Guest Log: [INFO] It also puts higher load on the project servers 2024-06-21 11:57:35 (4084): Guest Log: [INFO] Setting up a local HTTP proxy is highly recommended 2024-06-21 11:57:35 (4084): Guest Log: [INFO] Advice can be found in the project forum 2024-06-21 11:57:35 (4084): Guest Log: [INFO] Reloading and probing the CVMFS configuration 2024-06-21 11:57:41 (4084): Guest Log: [INFO] Probing /cvmfs/grid.cern.ch... OK 2024-06-21 11:57:43 (4084): Guest Log: [INFO] Probing /cvmfs/cms.cern.ch... OK 2024-06-21 11:58:13 (4084): Guest Log: [INFO] Probing /cvmfs/oasis.opensciencegrid.org... OK 2024-06-21 11:58:15 (4084): Guest Log: [INFO] Probing /cvmfs/cms-ib.cern.ch... OK 2024-06-21 11:58:16 (4084): Guest Log: [INFO] Probing /cvmfs/singularity.opensciencegrid.org... OK 2024-06-21 11:58:16 (4084): Guest Log: [INFO] Excerpt from "cvmfs_config stat": VERSION HOST PROXY 2024-06-21 11:58:16 (4084): Guest Log: [INFO] 2.7.2.0 http://s1cern-cvmfs.openhtc.io DIRECT 2024-06-21 11:58:16 (4084): Guest Log: [INFO] Environment HTTP proxy: not set 2024-06-21 11:58:16 (4084): Guest Log: [INFO] Reading volunteer information 2024-06-21 11:58:18 (4084): Guest Log: [INFO] Requesting an X509 credential from LHC@home 2024-06-21 11:58:19 (4084): Guest Log: [INFO] Requesting an idtoken from LHC@home 2024-06-21 11:58:20 (4084): Guest Log: [INFO] CMS application starting. Check log files. 2024-06-21 12:01:20 (4084): VM state change detected. (old = 'running', new = 'paused') 2024-06-21 12:02:23 (4084): VM state change detected. (old = 'paused', new = 'running') 2024-06-21 12:02:26 (4084): VM state change detected. (old = 'running', new = 'paused') 2024-06-21 12:02:53 (4084): VM state change detected. (old = 'paused', new = 'running') 2024-06-21 12:03:11 (4084): VM state change detected. (old = 'running', new = 'paused') 2024-06-21 12:07:04 (4084): Preference change detected 2024-06-21 12:07:04 (4084): Setting CPU throttle for VM. (100%) 2024-06-21 12:07:05 (4084): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds)) 2024-06-21 12:07:05 (4084): VM state change detected. (old = 'paused', new = 'running') 2024-06-21 13:34:42 (4084): VM state change detected. (old = 'running', new = 'paused') 2024-06-21 13:35:42 (4084): VM state change detected. (old = 'paused', new = 'running') 2024-06-21 13:37:17 (4084): VM state change detected. (old = 'running', new = 'paused') 2024-06-21 13:55:02 (4084): VM state change detected. (old = 'paused', new = 'running') 2024-06-21 13:58:36 (4084): VM state change detected. (old = 'running', new = 'paused') 2024-06-21 14:51:32 (4084): VM state change detected. (old = 'paused', new = 'running') 2024-06-21 14:51:36 (4084): Guest Log: 01:37:46.609827 timesync vgsvcTimeSyncWorker: Radical host time change: 3 184 621 000 000ns (HostNow=1 718 977 895 797 000 000 ns HostLast=1 718 974 711 176 000 000 ns) 2024-06-21 14:51:46 (4084): Guest Log: 01:37:56.612635 timesync vgsvcTimeSyncWorker: Radical guest time change: 3 184 614 944 000ns (GuestNow=1 718 977 905 799 935 000 ns GuestLast=1 718 974 721 184 991 000 ns fSetTimeLastLoop=true ) 2024-06-21 14:51:47 (4084): VM state change detected. (old = 'running', new = 'paused') 2024-06-21 14:53:29 (4084): VM state change detected. (old = 'paused', new = 'running') 2024-06-21 14:53:34 (4084): VM state change detected. (old = 'running', new = 'paused') 2024-06-21 15:19:03 (4084): VM state change detected. (old = 'paused', new = 'running') 2024-06-21 15:22:03 (4084): Status Report: Job Duration: '64800.000000' 2024-06-21 15:22:03 (4084): Status Report: Elapsed Time: '40886.235409' 2024-06-21 15:22:03 (4084): Status Report: CPU Time: '142716.376043' 2024-06-21 16:35:20 (4084): VM state change detected. (old = 'running', new = 'paused') 2024-06-21 16:55:45 (4084): VM state change detected. (old = 'paused', new = 'running') 2024-06-21 16:56:09 (4084): VM state change detected. (old = 'running', new = 'paused') 2024-06-21 16:57:23 (4084): VM state change detected. (old = 'paused', new = 'running') 2024-06-21 16:59:23 (4084): VM state change detected. (old = 'running', new = 'paused') 2024-06-21 17:03:39 (4084): VM state change detected. (old = 'paused', new = 'running') 2024-06-21 17:05:02 (4084): VM state change detected. (old = 'running', new = 'paused') 2024-06-21 17:06:16 (4084): VM state change detected. (old = 'paused', new = 'running') 2024-06-21 17:30:50 (4084): Status Report: Job Duration: '64800.000000' 2024-06-21 17:30:50 (4084): Status Report: Elapsed Time: '46886.428059' 2024-06-21 17:30:50 (4084): Status Report: CPU Time: '160239.921572' 2024-06-21 17:49:15 (4084): Guest Log: [INFO] glidein exited with return value 0. 2024-06-21 17:49:15 (4084): Guest Log: [INFO] Shutting Down. 2024-06-21 17:49:15 (4084): VM Completion File Detected. 2024-06-21 17:49:15 (4084): VM Completion Message: glidein exited with return value 0. . 2024-06-21 17:49:15 (4084): Powering off VM. 2024-06-21 17:49:15 (4084): Successfully stopped VM. 2024-06-21 17:49:15 (4084): Deregistering VM. (boinc_4f2a2d4c1e80d078, slot#0) 2024-06-21 17:49:15 (4084): Removing network bandwidth throttle group from VM. 2024-06-21 17:49:16 (4084): Removing VM from VirtualBox. 2024-06-21 17:49:21 (4084): called boinc_finish(0) </stderr_txt> ]]>
©2024 CERN