Name | CMS_3210345_1699601461.835258_0 |
Workunit | 2344828 |
Created | 10 Nov 2023, 7:31:03 UTC |
Sent | 11 Nov 2023, 16:10:41 UTC |
Report deadline | 18 Nov 2023, 16:10:41 UTC |
Received | 12 Nov 2023, 15:39:15 UTC |
Server state | Over |
Outcome | Success |
Client state | Done |
Exit status | 0 (0x00000000) |
Computer ID | 4441 |
Run time | 11 hours 21 min 24 sec |
CPU time | 6 hours 36 min 17 sec |
Validate state | Valid |
Credit | 3,575.23 |
Device peak FLOPS | 3.81 GFLOPS |
Application version | CMS Simulation v60.70 (vbox64_mt_mcore_cms) windows_x86_64 |
Peak working set size | 83.40 MB |
Peak swap size | 106.18 MB |
Peak disk usage | 1.09 GB |
<core_client_version>7.16.11</core_client_version> <![CDATA[ <stderr_txt> 2023-11-11 17:10:44 (10028): Detected: vboxwrapper 26206 2023-11-11 17:10:44 (10028): Detected: BOINC client v7.16.11 2023-11-11 17:10:45 (10028): Detected: VirtualBox VboxManage Interface (Version: 6.1.12) 2023-11-11 17:10:45 (10028): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds) 2023-11-11 17:10:45 (10028): Successfully copied 'init_data.xml' to the shared directory. 2023-11-11 17:10:46 (10028): Create VM. (boinc_3b12eba33e5fa4bb, slot#0) 2023-11-11 17:10:46 (10028): Setting Memory Size for VM. (1896MB) 2023-11-11 17:10:47 (10028): Setting CPU Count for VM. (1) 2023-11-11 17:10:47 (10028): Setting Chipset Options for VM. 2023-11-11 17:10:49 (10028): Setting Graphics Controller Options for VM. 2023-11-11 17:10:49 (10028): Setting Boot Options for VM. 2023-11-11 17:10:49 (10028): Setting Network Configuration for NAT. 2023-11-11 17:10:50 (10028): Enabling VM Network Access. 2023-11-11 17:10:50 (10028): Disabling USB Support for VM. 2023-11-11 17:10:50 (10028): Disabling COM Port Support for VM. 2023-11-11 17:10:51 (10028): Disabling LPT Port Support for VM. 2023-11-11 17:10:51 (10028): Disabling Audio Support for VM. 2023-11-11 17:10:51 (10028): Disabling Clipboard Support for VM. 2023-11-11 17:10:51 (10028): Disabling Drag and Drop Support for VM. 2023-11-11 17:10:52 (10028): Adding storage controller(s) to VM. 2023-11-11 17:10:52 (10028): Adding virtual disk drive to VM. (CMS_2022_09_07.vdi) 2023-11-11 17:10:53 (10028): Adding VirtualBox Guest Additions to VM. 2023-11-11 17:10:54 (10028): Adding network bandwidth throttle group to VM. (Defaulting to 1024GB) 2023-11-11 17:10:54 (10028): forwarding host port 63087 to guest port 80 2023-11-11 17:10:54 (10028): Enabling remote desktop for VM. 2023-11-11 17:10:55 (10028): Enabling shared directory for VM. 2023-11-11 17:10:56 (10028): Starting VM using VBoxManage interface. (boinc_3b12eba33e5fa4bb, slot#0) 2023-11-11 17:11:03 (10028): Successfully started VM. (PID = '17840') 2023-11-11 17:11:03 (10028): Reporting VM Process ID to BOINC. 2023-11-11 17:11:03 (10028): Guest Log: BIOS: VirtualBox 6.1.12 2023-11-11 17:11:03 (10028): Guest Log: CPUID EDX: 0x178bfbff 2023-11-11 17:11:03 (10028): Guest Log: BIOS: No PCI IDE controller, not probing IDE 2023-11-11 17:11:03 (10028): Guest Log: BIOS: AHCI 0-P#0: PCHS=16383/16/63 LCHS=1024/255/63 0x0000000002800000 sectors 2023-11-11 17:11:03 (10028): VM state change detected. (old = 'poweredoff', new = 'running') 2023-11-11 17:11:03 (10028): Detected: Web Application Enabled (http://localhost:63087) 2023-11-11 17:11:03 (10028): Detected: Remote Desktop Enabled (localhost:63088) 2023-11-11 17:11:04 (10028): VM state change detected. (old = 'running', new = 'paused') 2023-11-11 17:11:11 (10028): Preference change detected 2023-11-11 17:11:11 (10028): Setting CPU throttle for VM. (50%) 2023-11-11 17:11:11 (10028): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds)) 2023-11-11 17:11:11 (10028): VM state change detected. (old = 'paused', new = 'running') 2023-11-11 17:11:12 (10028): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032 2023-11-11 17:11:12 (10028): Guest Log: BIOS: Booting from Hard Disk... 2023-11-11 17:11:15 (10028): Guest Log: BIOS: KBD: unsupported int 16h function 03 2023-11-11 17:11:15 (10028): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 2023-11-11 17:11:47 (10028): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds 2023-11-11 17:11:47 (10028): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000) 2023-11-11 17:11:50 (10028): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log 2023-11-11 17:11:50 (10028): Guest Log: 00:00:00.000181 main Log opened 2023-11-11T16:11:50.401060000Z 2023-11-11 17:11:50 (10028): Guest Log: 00:00:00.000751 main OS Product: Linux 2023-11-11 17:11:50 (10028): Guest Log: 00:00:00.000854 main OS Release: 4.14.232-19.cernvm.x86_64 2023-11-11 17:11:50 (10028): Guest Log: 00:00:00.000964 main OS Version: #1 SMP Fri Apr 30 17:12:25 CEST 2021 2023-11-11 17:11:50 (10028): Guest Log: 00:00:00.001036 main Executable: /usr/sbin/VBoxService 2023-11-11 17:11:50 (10028): Guest Log: 00:00:00.001038 main Process ID: 2170 2023-11-11 17:11:50 (10028): Guest Log: 00:00:00.001040 main Package type: LINUX_64BITS_GENERIC 2023-11-11 17:11:50 (10028): Guest Log: 00:00:00.026847 main 5.2.6 r120293 started. Verbose level = 0 2023-11-11 17:12:45 (10028): Guest Log: [INFO] Mounting the shared directory 2023-11-11 17:12:46 (10028): Guest Log: [INFO] Shared directory mounted, enabling vboxmonitor 2023-11-11 17:12:46 (10028): Guest Log: [INFO] Sourcing essential functions from /cvmfs/grid.cern.ch 2023-11-11 17:12:47 (10028): Guest Log: [INFO] Testing connection to cern.ch 2023-11-11 17:12:47 (10028): Guest Log: [INFO] Testing connection to VCCS 2023-11-11 17:12:48 (10028): Guest Log: [INFO] Testing connection to HTCondor 2023-11-11 17:12:48 (10028): Guest Log: [INFO] Testing connection to WMAgent 2023-11-11 17:12:49 (10028): Guest Log: [INFO] Testing connection to EOSCMS 2023-11-11 17:12:49 (10028): Guest Log: [INFO] Testing connection to CMS-Factory 2023-11-11 17:12:50 (10028): Guest Log: [INFO] Testing connection to CMS-Frontier 2023-11-11 17:12:51 (10028): Guest Log: [INFO] Testing connection to Frontier 2023-11-11 17:12:52 (10028): Guest Log: [INFO] Could not find a local HTTP proxy 2023-11-11 17:12:52 (10028): Guest Log: [INFO] CVMFS and Frontier will have to use DIRECT connections 2023-11-11 17:12:53 (10028): Guest Log: [INFO] This makes the application less efficient 2023-11-11 17:12:53 (10028): Guest Log: [INFO] It also puts higher load on the project servers 2023-11-11 17:12:53 (10028): Guest Log: [INFO] Setting up a local HTTP proxy is highly recommended 2023-11-11 17:12:54 (10028): Guest Log: [INFO] Advice can be found in the project forum 2023-11-11 17:12:55 (10028): Guest Log: [INFO] Reloading and probing the CVMFS configuration 2023-11-11 17:13:34 (10028): Guest Log: [INFO] Probing /cvmfs/grid.cern.ch... OK 2023-11-11 17:14:09 (10028): Guest Log: [INFO] Probing /cvmfs/oasis.opensciencegrid.org... OK 2023-11-11 17:14:11 (10028): Guest Log: [INFO] Probing /cvmfs/cms-ib.cern.ch... OK 2023-11-11 17:14:11 (10028): Guest Log: [INFO] Probing /cvmfs/cms.cern.ch... OK 2023-11-11 17:14:11 (10028): Guest Log: [INFO] Probing /cvmfs/singularity.opensciencegrid.org... OK 2023-11-11 17:14:14 (10028): Guest Log: [INFO] Excerpt from "cvmfs_config stat": VERSION HOST PROXY 2023-11-11 17:14:15 (10028): Guest Log: [INFO] 2.7.2.0 http://s1cern-cvmfs.openhtc.io DIRECT 2023-11-11 17:14:15 (10028): Guest Log: [INFO] Environment HTTP proxy: not set 2023-11-11 17:14:16 (10028): Guest Log: [INFO] Reading volunteer information 2023-11-11 17:14:23 (10028): Guest Log: [INFO] Requesting an X509 credential from LHC@home 2023-11-11 17:14:25 (10028): Guest Log: [INFO] Requesting an X509 credential from vLHC@home-dev 2023-11-11 17:14:27 (10028): Guest Log: [INFO] CMS application starting. Check log files. 2023-11-11 17:14:29 (10028): Guest Log: [INFO] Requesting an idtoken from LHC@home 2023-11-11 17:14:30 (10028): Guest Log: [INFO] Requesting an idtoken from vLHC@home-dev 2023-11-11 17:19:12 (10028): VM state change detected. (old = 'running', new = 'paused') 2023-11-11 17:19:32 (10028): VM state change detected. (old = 'paused', new = 'running') 2023-11-11 17:19:52 (10028): VM state change detected. (old = 'running', new = 'paused') 2023-11-11 17:20:03 (10028): VM state change detected. (old = 'paused', new = 'running') 2023-11-11 18:21:29 (10028): VM state change detected. (old = 'running', new = 'paused') 2023-11-11 18:21:39 (10028): VM state change detected. (old = 'paused', new = 'running') 2023-11-11 18:22:29 (10028): VM state change detected. (old = 'running', new = 'paused') 2023-11-11 18:22:39 (10028): VM state change detected. (old = 'paused', new = 'running') 2023-11-11 18:24:29 (10028): VM state change detected. (old = 'running', new = 'paused') 2023-11-11 18:24:39 (10028): VM state change detected. (old = 'paused', new = 'running') 2023-11-11 18:39:10 (10028): VM state change detected. (old = 'running', new = 'paused') 2023-11-11 18:39:20 (10028): VM state change detected. (old = 'paused', new = 'running') 2023-11-11 18:40:20 (10028): VM state change detected. (old = 'running', new = 'paused') 2023-11-11 18:40:30 (10028): VM state change detected. (old = 'paused', new = 'running') 2023-11-11 18:43:01 (10028): VM state change detected. (old = 'running', new = 'paused') 2023-11-11 18:43:11 (10028): VM state change detected. (old = 'paused', new = 'running') 2023-11-11 18:45:11 (10028): VM state change detected. (old = 'running', new = 'paused') 2023-11-11 18:45:21 (10028): VM state change detected. (old = 'paused', new = 'running') 2023-11-11 18:53:09 (10028): Status Report: Job Duration: '64800.000000' 2023-11-11 18:53:09 (10028): Status Report: Elapsed Time: '6000.000000' 2023-11-11 18:53:09 (10028): Status Report: CPU Time: '3603.562500' 2023-11-11 18:58:32 (10028): VM state change detected. (old = 'running', new = 'paused') 2023-11-11 18:58:42 (10028): VM state change detected. (old = 'paused', new = 'running') 2023-11-11 18:59:12 (10028): VM state change detected. (old = 'running', new = 'paused') 2023-11-11 18:59:22 (10028): VM state change detected. (old = 'paused', new = 'running') 2023-11-11 19:02:32 (10028): VM state change detected. (old = 'running', new = 'paused') 2023-11-11 19:02:42 (10028): VM state change detected. (old = 'paused', new = 'running') 2023-11-11 19:03:22 (10028): VM state change detected. (old = 'running', new = 'paused') 2023-11-11 19:03:32 (10028): VM state change detected. (old = 'paused', new = 'running') 2023-11-11 19:04:12 (10028): VM state change detected. (old = 'running', new = 'paused') 2023-11-11 19:04:22 (10028): VM state change detected. (old = 'paused', new = 'running') 2023-11-11 19:04:52 (10028): VM state change detected. (old = 'running', new = 'paused') 2023-11-11 19:05:02 (10028): VM state change detected. (old = 'paused', new = 'running') 2023-11-11 19:05:42 (10028): VM state change detected. (old = 'running', new = 'paused') 2023-11-11 19:05:52 (10028): VM state change detected. (old = 'paused', new = 'running') 2023-11-11 19:08:12 (10028): VM state change detected. (old = 'running', new = 'paused') 2023-11-11 19:08:22 (10028): VM state change detected. (old = 'paused', new = 'running') 2023-11-11 19:09:02 (10028): VM state change detected. (old = 'running', new = 'paused') 2023-11-11 19:09:12 (10028): VM state change detected. (old = 'paused', new = 'running') 2023-11-11 19:10:32 (10028): VM state change detected. (old = 'running', new = 'paused') 2023-11-11 19:10:42 (10028): VM state change detected. (old = 'paused', new = 'running') 2023-11-11 19:32:33 (10028): VM state change detected. (old = 'running', new = 'paused') 2023-11-11 19:32:43 (10028): VM state change detected. (old = 'paused', new = 'running') 2023-11-11 19:37:24 (10028): VM state change detected. (old = 'running', new = 'paused') 2023-11-11 19:37:34 (10028): VM state change detected. (old = 'paused', new = 'running') 2023-11-11 19:48:24 (10028): VM state change detected. (old = 'running', new = 'paused') 2023-11-11 19:48:34 (10028): VM state change detected. (old = 'paused', new = 'running') 2023-11-11 20:35:36 (10028): Status Report: Job Duration: '64800.000000' 2023-11-11 20:35:36 (10028): Status Report: Elapsed Time: '12000.000000' 2023-11-11 20:35:36 (10028): Status Report: CPU Time: '7236.328125' 2023-11-11 20:39:08 (10028): VM state change detected. (old = 'running', new = 'paused') 2023-11-11 20:39:18 (10028): VM state change detected. (old = 'paused', new = 'running') 2023-11-11 20:40:48 (10028): VM state change detected. (old = 'running', new = 'paused') 2023-11-11 20:40:58 (10028): VM state change detected. (old = 'paused', new = 'running') 2023-11-11 20:49:28 (10028): VM state change detected. (old = 'running', new = 'paused') 2023-11-11 20:49:38 (10028): VM state change detected. (old = 'paused', new = 'running') 2023-11-11 22:16:14 (10028): Status Report: Job Duration: '64800.000000' 2023-11-11 22:16:14 (10028): Status Report: Elapsed Time: '18000.000000' 2023-11-11 22:16:14 (10028): Status Report: CPU Time: '10860.531250' 2023-11-11 22:37:33 (10028): VM state change detected. (old = 'running', new = 'paused') 2023-11-11 22:37:43 (10028): VM state change detected. (old = 'paused', new = 'running') 2023-11-11 22:56:54 (10028): VM state change detected. (old = 'running', new = 'paused') 2023-11-11 22:57:04 (10028): VM state change detected. (old = 'paused', new = 'running') 2023-11-11 23:01:14 (10028): VM state change detected. (old = 'running', new = 'paused') 2023-11-11 23:01:24 (10028): VM state change detected. (old = 'paused', new = 'running') 2023-11-11 23:02:44 (10028): VM state change detected. (old = 'running', new = 'paused') 2023-11-11 23:02:54 (10028): VM state change detected. (old = 'paused', new = 'running') 2023-11-11 23:05:34 (10028): VM state change detected. (old = 'running', new = 'paused') 2023-11-11 23:05:44 (10028): VM state change detected. (old = 'paused', new = 'running') 2023-11-11 23:26:07 (10028): VM state change detected. (old = 'running', new = 'paused') 2023-11-11 23:26:17 (10028): VM state change detected. (old = 'paused', new = 'running') 2023-11-11 23:31:17 (10028): VM state change detected. (old = 'running', new = 'paused') 2023-11-11 23:31:27 (10028): VM state change detected. (old = 'paused', new = 'running') 2023-11-11 23:38:57 (10028): VM state change detected. (old = 'running', new = 'paused') 2023-11-11 23:39:07 (10028): VM state change detected. (old = 'paused', new = 'running') 2023-11-11 23:44:17 (10028): VM state change detected. (old = 'running', new = 'paused') 2023-11-11 23:44:27 (10028): VM state change detected. (old = 'paused', new = 'running') 2023-11-11 23:46:08 (10028): VM state change detected. (old = 'running', new = 'paused') 2023-11-11 23:46:18 (10028): VM state change detected. (old = 'paused', new = 'running') 2023-11-11 23:58:09 (10028): Status Report: Job Duration: '64800.000000' 2023-11-11 23:58:09 (10028): Status Report: Elapsed Time: '24000.147728' 2023-11-11 23:58:09 (10028): Status Report: CPU Time: '14380.937500' 2023-11-12 00:20:39 (10028): VM state change detected. (old = 'running', new = 'paused') 2023-11-12 00:20:49 (10028): VM state change detected. (old = 'paused', new = 'running') 2023-11-12 00:21:29 (10028): VM state change detected. (old = 'running', new = 'paused') 2023-11-12 00:21:39 (10028): VM state change detected. (old = 'paused', new = 'running') 2023-11-12 00:23:59 (10028): VM state change detected. (old = 'running', new = 'paused') 2023-11-12 00:24:09 (10028): VM state change detected. (old = 'paused', new = 'running') 2023-11-12 00:28:10 (10028): VM state change detected. (old = 'running', new = 'paused') 2023-11-12 00:28:30 (10028): VM state change detected. (old = 'paused', new = 'running') 2023-11-12 00:30:50 (10028): VM state change detected. (old = 'running', new = 'paused') 2023-11-12 00:31:00 (10028): VM state change detected. (old = 'paused', new = 'running') 2023-11-12 00:35:40 (10028): VM state change detected. (old = 'running', new = 'paused') 2023-11-12 00:35:50 (10028): VM state change detected. (old = 'paused', new = 'running') 2023-11-12 00:40:50 (10028): VM state change detected. (old = 'running', new = 'paused') 2023-11-12 00:41:00 (10028): VM state change detected. (old = 'paused', new = 'running') 2023-11-12 00:41:40 (10028): VM state change detected. (old = 'running', new = 'paused') 2023-11-12 00:41:50 (10028): VM state change detected. (old = 'paused', new = 'running') 2023-11-12 00:42:00 (10028): VM state change detected. (old = 'running', new = 'paused') 2023-11-12 00:42:10 (10028): VM state change detected. (old = 'paused', new = 'running') 2023-11-12 00:42:30 (10028): VM state change detected. (old = 'running', new = 'paused') 2023-11-12 00:42:40 (10028): VM state change detected. (old = 'paused', new = 'running') 2023-11-12 00:48:21 (10028): VM state change detected. (old = 'running', new = 'paused') 2023-11-12 00:48:30 (10028): VM state change detected. (old = 'paused', new = 'running') 2023-11-12 00:59:11 (10028): VM state change detected. (old = 'running', new = 'paused') 2023-11-12 00:59:31 (10028): VM state change detected. (old = 'paused', new = 'running') 2023-11-12 01:00:41 (10028): VM state change detected. (old = 'running', new = 'paused') 2023-11-12 01:00:51 (10028): VM state change detected. (old = 'paused', new = 'running') 2023-11-12 01:03:41 (10028): VM state change detected. (old = 'running', new = 'paused') 2023-11-12 01:03:51 (10028): VM state change detected. (old = 'paused', new = 'running') 2023-11-12 01:19:23 (10028): VM state change detected. (old = 'running', new = 'paused') 2023-11-12 01:19:33 (10028): VM state change detected. (old = 'paused', new = 'running') 2023-11-12 01:21:43 (10028): VM state change detected. (old = 'running', new = 'paused') 2023-11-12 01:21:53 (10028): VM state change detected. (old = 'paused', new = 'running') 2023-11-12 11:11:10 (11460): Detected: vboxwrapper 26206 2023-11-12 11:11:10 (11460): Detected: BOINC client v7.16.11 2023-11-12 11:11:11 (11460): Detected: VirtualBox VboxManage Interface (Version: 6.1.12) 2023-11-12 11:11:11 (11460): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds) 2023-11-12 11:11:11 (11460): Guest Log: BIOS: VirtualBox 6.1.12 2023-11-12 11:11:11 (11460): Guest Log: CPUID EDX: 0x178bfbff 2023-11-12 11:11:11 (11460): Guest Log: BIOS: No PCI IDE controller, not probing IDE 2023-11-12 11:11:11 (11460): Guest Log: BIOS: AHCI 0-P#0: PCHS=16383/16/63 LCHS=1024/255/63 0x0000000002800000 sectors 2023-11-12 11:11:11 (11460): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032 2023-11-12 11:11:11 (11460): Guest Log: BIOS: Booting from Hard Disk... 2023-11-12 11:11:11 (11460): Guest Log: BIOS: KBD: unsupported int 16h function 03 2023-11-12 11:11:11 (11460): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 2023-11-12 11:11:11 (11460): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds 2023-11-12 11:11:11 (11460): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000) 2023-11-12 11:11:11 (11460): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log 2023-11-12 11:11:11 (11460): Guest Log: 00:00:00.000181 main Log opened 2023-11-11T16:11:50.401060000Z 2023-11-12 11:11:11 (11460): Guest Log: 00:00:00.000751 main OS Product: Linux 2023-11-12 11:11:11 (11460): Guest Log: 00:00:00.000854 main OS Release: 4.14.232-19.cernvm.x86_64 2023-11-12 11:11:11 (11460): Guest Log: 00:00:00.000964 main OS Version: #1 SMP Fri Apr 30 17:12:25 CEST 2021 2023-11-12 11:11:11 (11460): Guest Log: 00:00:00.001036 main Executable: /usr/sbin/VBoxService 2023-11-12 11:11:11 (11460): Guest Log: 00:00:00.001038 main Process ID: 2170 2023-11-12 11:11:11 (11460): Guest Log: 00:00:00.001040 main Package type: LINUX_64BITS_GENERIC 2023-11-12 11:11:11 (11460): Guest Log: 00:00:00.026847 main 5.2.6 r120293 started. Verbose level = 0 2023-11-12 11:11:11 (11460): Guest Log: [INFO] Mounting the shared directory 2023-11-12 11:11:11 (11460): Guest Log: [INFO] Shared directory mounted, enabling vboxmonitor 2023-11-12 11:11:11 (11460): Guest Log: [INFO] Sourcing essential functions from /cvmfs/grid.cern.ch 2023-11-12 11:11:11 (11460): Guest Log: [INFO] Testing connection to cern.ch 2023-11-12 11:11:11 (11460): Guest Log: [INFO] Testing connection to VCCS 2023-11-12 11:11:11 (11460): Guest Log: [INFO] Testing connection to HTCondor 2023-11-12 11:11:11 (11460): Guest Log: [INFO] Testing connection to WMAgent 2023-11-12 11:11:11 (11460): Guest Log: [INFO] Testing connection to EOSCMS 2023-11-12 11:11:11 (11460): Guest Log: [INFO] Testing connection to CMS-Factory 2023-11-12 11:11:11 (11460): Guest Log: [INFO] Testing connection to CMS-Frontier 2023-11-12 11:11:11 (11460): Guest Log: [INFO] Testing connection to Frontier 2023-11-12 11:11:11 (11460): Guest Log: [INFO] Could not find a local HTTP proxy 2023-11-12 11:11:11 (11460): Guest Log: [INFO] CVMFS and Frontier will have to use DIRECT connections 2023-11-12 11:11:11 (11460): Guest Log: [INFO] This makes the application less efficient 2023-11-12 11:11:11 (11460): Guest Log: [INFO] It also puts higher load on the project servers 2023-11-12 11:11:11 (11460): Guest Log: [INFO] Setting up a local HTTP proxy is highly recommended 2023-11-12 11:11:11 (11460): Guest Log: [INFO] Advice can be found in the project forum 2023-11-12 11:11:11 (11460): Guest Log: [INFO] Reloading and probing the CVMFS configuration 2023-11-12 11:11:11 (11460): Guest Log: [INFO] Probing /cvmfs/grid.cern.ch... OK 2023-11-12 11:11:11 (11460): Guest Log: [INFO] Probing /cvmfs/oasis.opensciencegrid.org... OK 2023-11-12 11:11:11 (11460): Guest Log: [INFO] Probing /cvmfs/cms-ib.cern.ch... OK 2023-11-12 11:11:11 (11460): Guest Log: [INFO] Probing /cvmfs/cms.cern.ch... OK 2023-11-12 11:11:11 (11460): Guest Log: [INFO] Probing /cvmfs/singularity.opensciencegrid.org... OK 2023-11-12 11:11:11 (11460): Guest Log: [INFO] Excerpt from "cvmfs_config stat": VERSION HOST PROXY 2023-11-12 11:11:11 (11460): Guest Log: [INFO] 2.7.2.0 http://s1cern-cvmfs.openhtc.io DIRECT 2023-11-12 11:11:11 (11460): Guest Log: [INFO] Environment HTTP proxy: not set 2023-11-12 11:11:11 (11460): Guest Log: [INFO] Reading volunteer information 2023-11-12 11:11:11 (11460): Guest Log: [INFO] Requesting an X509 credential from LHC@home 2023-11-12 11:11:11 (11460): Guest Log: [INFO] Requesting an X509 credential from vLHC@home-dev 2023-11-12 11:11:11 (11460): Guest Log: [INFO] CMS application starting. Check log files. 2023-11-12 11:11:11 (11460): Guest Log: [INFO] Requesting an idtoken from LHC@home 2023-11-12 11:11:11 (11460): Guest Log: [INFO] Requesting an idtoken from vLHC@home-dev 2023-11-12 11:11:11 (11460): Starting VM using VBoxManage interface. (boinc_3b12eba33e5fa4bb, slot#0) 2023-11-12 11:11:16 (11460): Successfully started VM. (PID = '5164') 2023-11-12 11:11:16 (11460): Reporting VM Process ID to BOINC. 2023-11-12 11:11:16 (11460): Guest Log: BIOS: VirtualBox 6.1.12 2023-11-12 11:11:16 (11460): Guest Log: CPUID EDX: 0x178bfbff 2023-11-12 11:11:16 (11460): Guest Log: BIOS: No PCI IDE controller, not probing IDE 2023-11-12 11:11:16 (11460): Guest Log: BIOS: AHCI 0-P#0: PCHS=16383/16/63 LCHS=1024/255/63 0x0000000002800000 sectors 2023-11-12 11:11:16 (11460): VM state change detected. (old = 'poweredoff', new = 'running') 2023-11-12 11:11:16 (11460): Detected: Web Application Enabled (http://localhost:63087) 2023-11-12 11:11:16 (11460): Detected: Remote Desktop Enabled (localhost:63088) 2023-11-12 11:11:16 (11460): Status Report: Job Duration: '64800.000000' 2023-11-12 11:11:16 (11460): Status Report: Elapsed Time: '29290.147728' 2023-11-12 11:11:16 (11460): Status Report: CPU Time: '17589.546875' 2023-11-12 11:11:16 (11460): Preference change detected 2023-11-12 11:11:16 (11460): Setting CPU throttle for VM. (50%) 2023-11-12 11:11:16 (11460): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds)) 2023-11-12 11:11:18 (11460): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032 2023-11-12 11:11:18 (11460): Guest Log: BIOS: Booting from Hard Disk... 2023-11-12 11:11:21 (11460): Guest Log: BIOS: KBD: unsupported int 16h function 03 2023-11-12 11:11:21 (11460): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 2023-11-12 11:11:54 (11460): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds 2023-11-12 11:11:54 (11460): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000) 2023-11-12 11:11:55 (11460): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log 2023-11-12 11:11:55 (11460): Guest Log: 00:00:00.000090 main Log opened 2023-11-12T10:11:55.999146000Z 2023-11-12 11:11:55 (11460): Guest Log: 00:00:00.000180 main OS Product: Linux 2023-11-12 11:11:55 (11460): Guest Log: 00:00:00.000225 main OS Release: 4.14.232-19.cernvm.x86_64 2023-11-12 11:11:55 (11460): Guest Log: 00:00:00.000254 main OS Version: #1 SMP Fri Apr 30 17:12:25 CEST 2021 2023-11-12 11:11:55 (11460): Guest Log: 00:00:00.000292 main Executable: /usr/sbin/VBoxService 2023-11-12 11:11:55 (11460): Guest Log: 00:00:00.000293 main Process ID: 2150 2023-11-12 11:11:55 (11460): Guest Log: 00:00:00.000293 main Package type: LINUX_64BITS_GENERIC 2023-11-12 11:11:55 (11460): Guest Log: 00:00:00.003036 main 5.2.6 r120293 started. Verbose level = 0 2023-11-12 11:12:21 (11460): Guest Log: [INFO] Mounting the shared directory 2023-11-12 11:12:21 (11460): Guest Log: [INFO] Shared directory mounted, enabling vboxmonitor 2023-11-12 11:12:22 (11460): Guest Log: [INFO] Sourcing essential functions from /cvmfs/grid.cern.ch 2023-11-12 11:12:22 (11460): Guest Log: [INFO] Testing connection to cern.ch 2023-11-12 11:12:22 (11460): Guest Log: [INFO] Testing connection to VCCS 2023-11-12 11:12:23 (11460): Guest Log: [INFO] Testing connection to HTCondor 2023-11-12 11:12:24 (11460): Guest Log: [INFO] Testing connection to WMAgent 2023-11-12 11:12:24 (11460): Guest Log: [INFO] Testing connection to EOSCMS 2023-11-12 11:12:25 (11460): Guest Log: [INFO] Testing connection to CMS-Factory 2023-11-12 11:12:25 (11460): Guest Log: [INFO] Testing connection to CMS-Frontier 2023-11-12 11:12:26 (11460): Guest Log: [INFO] Testing connection to Frontier 2023-11-12 11:12:37 (11460): Guest Log: [INFO] Could not find a local HTTP proxy 2023-11-12 11:12:37 (11460): Guest Log: [INFO] CVMFS and Frontier will have to use DIRECT connections 2023-11-12 11:12:38 (11460): Guest Log: [INFO] This makes the application less efficient 2023-11-12 11:12:38 (11460): Guest Log: [INFO] It also puts higher load on the project servers 2023-11-12 11:12:38 (11460): Guest Log: [INFO] Setting up a local HTTP proxy is highly recommended 2023-11-12 11:12:39 (11460): Guest Log: [INFO] Advice can be found in the project forum 2023-11-12 11:12:39 (11460): Guest Log: [INFO] Reloading and probing the CVMFS configuration 2023-11-12 11:13:12 (11460): VM state change detected. (old = 'running', new = 'paused') 2023-11-12 11:14:02 (11460): VM state change detected. (old = 'paused', new = 'running') 2023-11-12 11:14:10 (11460): Guest Log: [INFO] Probing /cvmfs/grid.cern.ch... OK 2023-11-12 11:14:35 (11460): Guest Log: [INFO] Probing /cvmfs/oasis.opensciencegrid.org... OK 2023-11-12 11:14:35 (11460): Guest Log: [INFO] Probing /cvmfs/cms-ib.cern.ch... OK 2023-11-12 11:14:36 (11460): Guest Log: [INFO] Probing /cvmfs/singularity.opensciencegrid.org... OK 2023-11-12 11:14:39 (11460): Guest Log: [INFO] Probing /cvmfs/cms.cern.ch... OK 2023-11-12 11:14:42 (11460): Guest Log: [INFO] Excerpt from "cvmfs_config stat": VERSION HOST PROXY 2023-11-12 11:14:42 (11460): Guest Log: [INFO] 2.7.2.0 http://s1cern-cvmfs.openhtc.io DIRECT 2023-11-12 11:14:42 (11460): Guest Log: [INFO] Environment HTTP proxy: not set 2023-11-12 11:14:43 (11460): Guest Log: [INFO] Reading volunteer information 2023-11-12 11:14:47 (11460): Guest Log: [INFO] Requesting an X509 credential from LHC@home 2023-11-12 11:14:48 (11460): Guest Log: [INFO] Requesting an X509 credential from vLHC@home-dev 2023-11-12 11:14:51 (11460): Guest Log: [INFO] CMS application starting. Check log files. 2023-11-12 11:14:52 (11460): Guest Log: [INFO] Requesting an idtoken from LHC@home 2023-11-12 11:14:54 (11460): Guest Log: [INFO] Requesting an idtoken from vLHC@home-dev 2023-11-12 11:52:06 (11460): VM state change detected. (old = 'running', new = 'paused') 2023-11-12 11:52:16 (11460): VM state change detected. (old = 'paused', new = 'running') 2023-11-12 12:21:08 (11460): VM state change detected. (old = 'running', new = 'paused') 2023-11-12 12:21:18 (11460): VM state change detected. (old = 'paused', new = 'running') 2023-11-12 12:45:49 (11460): VM state change detected. (old = 'running', new = 'paused') 2023-11-12 12:45:59 (11460): VM state change detected. (old = 'paused', new = 'running') 2023-11-12 12:52:47 (11460): Status Report: Job Duration: '64800.000000' 2023-11-12 12:52:47 (11460): Status Report: Elapsed Time: '35290.949152' 2023-11-12 12:52:47 (11460): Status Report: CPU Time: '21172.609375' 2023-11-12 13:22:52 (11460): VM state change detected. (old = 'running', new = 'paused') 2023-11-12 13:23:02 (11460): VM state change detected. (old = 'paused', new = 'running') 2023-11-12 13:34:52 (11460): VM state change detected. (old = 'running', new = 'paused') 2023-11-12 13:35:02 (11460): VM state change detected. (old = 'paused', new = 'running') 2023-11-12 13:35:52 (11460): VM state change detected. (old = 'running', new = 'paused') 2023-11-12 13:36:02 (11460): VM state change detected. (old = 'paused', new = 'running') 2023-11-12 13:48:53 (11460): VM state change detected. (old = 'running', new = 'paused') 2023-11-12 13:49:04 (11460): VM state change detected. (old = 'paused', new = 'running') 2023-11-12 13:56:34 (11460): VM state change detected. (old = 'running', new = 'paused') 2023-11-12 13:56:44 (11460): VM state change detected. (old = 'paused', new = 'running') 2023-11-12 15:18:36 (11460): Guest Log: 04:04:38.690651 timesync vgsvcTimeSyncWorker: Radical host time change: 4 630 360 000 000ns (HostNow=1 699 798 715 618 000 000 ns HostLast=1 699 794 085 258 000 000 ns) 2023-11-12 15:18:36 (11460): Guest Log: 04:04:38.965949 timesync vgsvcTimeSyncWorker: Radical guest time change: 4 632 736 805 000ns (GuestNow=1 699 798 594 563 163 000 ns GuestLast=1 699 793 961 826 358 000 ns fSetTimeLastLoop=false) 2023-11-12 15:56:46 (11460): Guest Log: 04:42:49.987470 timesync vgsvcTimeSyncWorker: Radical host time change: 2 290 332 000 000ns (HostNow=1 699 801 005 950 000 000 ns HostLast=1 699 798 715 618 000 000 ns) 2023-11-12 15:56:46 (11460): Guest Log: 04:42:49.987618 timesync vgsvcTimeSyncWorker: Radical guest time change: 2 291 423 322 000ns (GuestNow=1 699 800 885 986 485 000 ns GuestLast=1 699 798 594 563 163 000 ns fSetTimeLastLoop=false) 2023-11-12 15:56:57 (11460): VM state change detected. (old = 'running', new = 'paused') 2023-11-12 15:57:07 (11460): VM state change detected. (old = 'paused', new = 'running') 2023-11-12 16:20:18 (11460): Guest Log: [INFO] glidein exited with return value 0. 2023-11-12 16:20:18 (11460): Guest Log: [INFO] Shutting Down. 2023-11-12 16:20:18 (11460): VM Completion File Detected. 2023-11-12 16:20:18 (11460): VM Completion Message: glidein exited with return value 0. . 2023-11-12 16:20:18 (11460): Powering off VM. 2023-11-12 16:20:19 (11460): Successfully stopped VM. 2023-11-12 16:20:19 (11460): Deregistering VM. (boinc_3b12eba33e5fa4bb, slot#0) 2023-11-12 16:20:19 (11460): Removing network bandwidth throttle group from VM. 2023-11-12 16:20:19 (11460): Removing VM from VirtualBox. 16:20:25 (11460): called boinc_finish(0) </stderr_txt> ]]>
©2024 CERN