| Name | CMS_1621035_1698719833.953682_0 |
| Workunit | 2342351 |
| Created | 31 Oct 2023, 2:37:18 UTC |
| Sent | 3 Nov 2023, 10:13:31 UTC |
| Report deadline | 10 Nov 2023, 10:13:31 UTC |
| Received | 8 Nov 2023, 8:58:53 UTC |
| Server state | Over |
| Outcome | Success |
| Client state | Done |
| Exit status | 0 (0x00000000) |
| Computer ID | 5009 |
| Run time | 17 hours 39 min 51 sec |
| CPU time | 18 hours 6 min 14 sec |
| Validate state | Valid |
| Credit | 9,836.66 |
| Device peak FLOPS | 66.16 GFLOPS |
| Application version | CMS Simulation v60.70 (vbox64_mt_mcore_cms) windows_x86_64 |
| Peak working set size | 90.84 MB |
| Peak swap size | 105.02 MB |
| Peak disk usage | 6.90 GB |
Stderr output
<core_client_version>7.24.1</core_client_version> <![CDATA[ <stderr_txt> , new = 'running') 2023-11-03 20:19:45 (19740): VM state change detected. (old = 'running', new = 'paused') 2023-11-03 20:20:05 (19740): VM state change detected. (old = 'paused', new = 'running') 2023-11-03 20:24:47 (19740): VM state change detected. (old = 'running', new = 'paused') 2023-11-03 20:25:08 (19740): VM state change detected. (old = 'paused', new = 'running') 2023-11-03 20:35:12 (19740): VM state change detected. (old = 'running', new = 'paused') 2023-11-03 20:35:32 (19740): VM state change detected. (old = 'paused', new = 'running') 2023-11-03 20:38:31 (19740): Status Report: Job Duration: '64800.000000' 2023-11-03 20:38:31 (19740): Status Report: Elapsed Time: '18000.000000' 2023-11-03 20:38:31 (19740): Status Report: CPU Time: '18415.406250' 2023-11-03 20:39:45 (19740): VM state change detected. (old = 'running', new = 'paused') 2023-11-03 20:40:05 (19740): VM state change detected. (old = 'paused', new = 'running') 2023-11-03 20:42:26 (19740): VM state change detected. (old = 'running', new = 'paused') 2023-11-03 20:42:46 (19740): VM state change detected. (old = 'paused', new = 'running') 2023-11-03 20:44:47 (19740): VM state change detected. (old = 'running', new = 'paused') 2023-11-03 20:45:07 (19740): VM state change detected. (old = 'paused', new = 'running') 2023-11-03 21:34:38 (19740): VM state change detected. (old = 'running', new = 'paused') 2023-11-03 21:34:58 (19740): VM state change detected. (old = 'paused', new = 'running') 2023-11-03 21:54:48 (19740): VM state change detected. (old = 'running', new = 'paused') 2023-11-03 21:55:08 (19740): VM state change detected. (old = 'paused', new = 'running') 2023-11-03 21:55:59 (19740): VM state change detected. (old = 'running', new = 'paused') 2023-11-03 21:56:19 (19740): VM state change detected. (old = 'paused', new = 'running') 2023-11-03 22:09:47 (19740): VM state change detected. (old = 'running', new = 'paused') 2023-11-03 22:10:07 (19740): VM state change detected. (old = 'paused', new = 'running') 2023-11-03 22:20:59 (19740): Status Report: Job Duration: '64800.000000' 2023-11-03 22:20:59 (19740): Status Report: Elapsed Time: '24000.000000' 2023-11-03 22:20:59 (19740): Status Report: CPU Time: '23936.671875' 2023-11-03 22:31:50 (19740): VM state change detected. (old = 'running', new = 'paused') 2023-11-03 22:32:10 (19740): VM state change detected. (old = 'paused', new = 'running') 2023-11-03 22:34:52 (19740): VM state change detected. (old = 'running', new = 'paused') 2023-11-03 22:35:12 (19740): VM state change detected. (old = 'paused', new = 'running') 2023-11-03 22:44:29 (19740): VM state change detected. (old = 'running', new = 'paused') 2023-11-03 22:44:49 (19740): VM state change detected. (old = 'paused', new = 'running') 2023-11-03 23:04:54 (19740): VM state change detected. (old = 'running', new = 'paused') 2023-11-03 23:05:14 (19740): VM state change detected. (old = 'paused', new = 'running') 2023-11-03 23:14:50 (19740): VM state change detected. (old = 'running', new = 'paused') 2023-11-03 23:15:10 (19740): VM state change detected. (old = 'paused', new = 'running') 2023-11-03 23:16:01 (19740): VM state change detected. (old = 'running', new = 'paused') 2023-11-03 23:16:21 (19740): VM state change detected. (old = 'paused', new = 'running') 2023-11-03 23:29:47 (19740): VM state change detected. (old = 'running', new = 'paused') 2023-11-03 23:30:08 (19740): VM state change detected. (old = 'paused', new = 'running') 2023-11-03 23:33:19 (19740): VM state change detected. (old = 'running', new = 'paused') 2023-11-03 23:33:39 (19740): VM state change detected. (old = 'paused', new = 'running') 2023-11-03 23:34:50 (19740): VM state change detected. (old = 'running', new = 'paused') 2023-11-03 23:35:10 (19740): VM state change detected. (old = 'paused', new = 'running') 2023-11-03 23:39:52 (19740): VM state change detected. (old = 'running', new = 'paused') 2023-11-03 23:40:12 (19740): VM state change detected. (old = 'paused', new = 'running') 2023-11-03 23:42:34 (19740): VM state change detected. (old = 'running', new = 'paused') 2023-11-03 23:42:54 (19740): VM state change detected. (old = 'paused', new = 'running') 2023-11-03 23:44:46 (19740): VM state change detected. (old = 'running', new = 'paused') 2023-11-03 23:45:06 (19740): VM state change detected. (old = 'paused', new = 'running') 2023-11-03 23:49:49 (19740): VM state change detected. (old = 'running', new = 'paused') 2023-11-03 23:50:09 (19740): VM state change detected. (old = 'paused', new = 'running') 2023-11-03 23:54:51 (19740): VM state change detected. (old = 'running', new = 'paused') 2023-11-03 23:55:11 (19740): VM state change detected. (old = 'paused', new = 'running') 2023-11-04 00:00:35 (19740): VM state change detected. (old = 'running', new = 'paused') 2023-11-04 00:00:55 (19740): VM state change detected. (old = 'paused', new = 'running') 2023-11-04 00:06:15 (19740): Status Report: Job Duration: '64800.000000' 2023-11-04 00:06:15 (19740): Status Report: Elapsed Time: '30000.000000' 2023-11-04 00:06:15 (19740): Status Report: CPU Time: '30388.328125' 2023-11-04 00:09:51 (19740): VM state change detected. (old = 'running', new = 'paused') 2023-11-04 00:10:11 (19740): VM state change detected. (old = 'paused', new = 'running') 2023-11-04 00:19:56 (19740): Stopping VM. 2023-11-04 00:20:43 (19740): Error in stop VM for VM: -182 Command: VBoxManage -q controlvm "boinc_4cfc7f408c0493aa" savestate Output: 0%...10%...20%... 2023-11-04 00:20:43 (19740): VM did not stop when requested. 2023-11-04 00:20:43 (19740): VM was successfully terminated. 2023-11-04 00:25:44 (1780): Detected: vboxwrapper 26206 2023-11-04 00:25:44 (1780): Detected: BOINC client v7.24.1 2023-11-04 00:25:45 (1780): Detected: VirtualBox VboxManage Interface (Version: 7.0.10) 2023-11-04 00:25:45 (1780): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds) 2023-11-04 00:25:46 (1780): Guest Log: BIOS: VirtualBox 7.0.10 2023-11-04 00:25:46 (1780): Guest Log: CPUID EDX: 0x178bfbff 2023-11-04 00:25:46 (1780): Guest Log: BIOS: No PCI IDE controller, not probing IDE 2023-11-04 00:25:46 (1780): Guest Log: BIOS: AHCI 0-P#0: PCHS=16383/16/63 LCHS=1024/255/63 0x0000000002800000 sectors 2023-11-04 00:25:46 (1780): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032 2023-11-04 00:25:46 (1780): Guest Log: BIOS: Booting from Hard Disk... 2023-11-04 00:25:46 (1780): Guest Log: BIOS: KBD: unsupported int 16h function 03 2023-11-04 00:25:46 (1780): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 2023-11-04 00:25:46 (1780): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds 2023-11-04 00:25:46 (1780): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000) 2023-11-04 00:25:46 (1780): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log 2023-11-04 00:25:46 (1780): Guest Log: 00:00:00.001828 main Log opened 2023-11-03T10:15:18.538802000Z 2023-11-04 00:25:46 (1780): Guest Log: 00:00:00.026619 main OS Product: Linux 2023-11-04 00:25:46 (1780): Guest Log: 00:00:00.033419 main OS Release: 4.14.232-19.cernvm.x86_64 2023-11-04 00:25:46 (1780): Guest Log: 00:00:00.037331 main OS Version: #1 SMP Fri Apr 30 17:12:25 CEST 2021 2023-11-04 00:25:46 (1780): Guest Log: 00:00:00.039986 main Executable: /usr/sbin/VBoxService 2023-11-04 00:25:46 (1780): Guest Log: 00:00:00.039987 main Process ID: 2662 2023-11-04 00:25:46 (1780): Guest Log: 00:00:00.039988 main Package type: LINUX_64BITS_GENERIC 2023-11-04 00:25:46 (1780): Guest Log: 00:00:00.078529 main 5.2.6 r120293 started. Verbose level = 0 2023-11-04 00:25:46 (1780): Guest Log: [INFO] Mounting the shared directory 2023-11-04 00:25:46 (1780): Guest Log: [INFO] Shared directory mounted, enabling vboxmonitor 2023-11-04 00:25:46 (1780): Guest Log: [INFO] Sourcing essential functions from /cvmfs/grid.cern.ch 2023-11-04 00:25:46 (1780): Guest Log: [INFO] Testing connection to cern.ch 2023-11-04 00:25:46 (1780): Guest Log: [INFO] Testing connection to VCCS 2023-11-04 00:25:46 (1780): Guest Log: [INFO] Testing connection to HTCondor 2023-11-04 00:25:46 (1780): Guest Log: [INFO] Testing connection to WMAgent 2023-11-04 00:25:46 (1780): Guest Log: [INFO] Testing connection to EOSCMS 2023-11-04 00:25:46 (1780): Guest Log: [INFO] Testing connection to CMS-Factory 2023-11-04 00:25:46 (1780): Guest Log: [INFO] Testing connection to CMS-Frontier 2023-11-04 00:25:46 (1780): Guest Log: [INFO] Testing connection to Frontier 2023-11-04 00:25:46 (1780): Guest Log: [INFO] Could not find a local HTTP proxy 2023-11-04 00:25:46 (1780): Guest Log: [INFO] CVMFS and Frontier will have to use DIRECT connections 2023-11-04 00:25:46 (1780): Guest Log: [INFO] This makes the application less efficient 2023-11-04 00:25:46 (1780): Guest Log: [INFO] It also puts higher load on the project servers 2023-11-04 00:25:46 (1780): Guest Log: [INFO] Setting up a local HTTP proxy is highly recommended 2023-11-04 00:25:46 (1780): Guest Log: [INFO] Advice can be found in the project forum 2023-11-04 00:25:46 (1780): Guest Log: [INFO] Reloading and probing the CVMFS configuration 2023-11-04 00:25:46 (1780): Guest Log: [INFO] Probing /cvmfs/grid.cern.ch... OK 2023-11-04 00:25:46 (1780): Guest Log: [INFO] Probing /cvmfs/cms-ib.cern.ch... OK 2023-11-04 00:25:46 (1780): Guest Log: [INFO] Probing /cvmfs/oasis.opensciencegrid.org... OK 2023-11-04 00:25:46 (1780): Guest Log: [INFO] Probing /cvmfs/cms.cern.ch... OK 2023-11-04 00:25:46 (1780): Guest Log: [INFO] Probing /cvmfs/singularity.opensciencegrid.org... OK 2023-11-04 00:25:46 (1780): Guest Log: [INFO] Excerpt from "cvmfs_config stat": VERSION HOST PROXY 2023-11-04 00:25:46 (1780): Guest Log: [INFO] 2.7.2.0 http://s1cern-cvmfs.openhtc.io DIRECT 2023-11-04 00:25:46 (1780): Guest Log: [INFO] Environment HTTP proxy: not set 2023-11-04 00:25:46 (1780): Guest Log: [INFO] Reading volunteer information 2023-11-04 00:25:46 (1780): Guest Log: [INFO] Requesting an X509 credential from LHC@home 2023-11-04 00:25:46 (1780): Guest Log: [INFO] Requesting an X509 credential from vLHC@home-dev 2023-11-04 00:25:46 (1780): Guest Log: [INFO] CMS application starting. Check log files. 2023-11-04 00:25:46 (1780): Guest Log: [INFO] Requesting an idtoken from LHC@home 2023-11-04 00:25:46 (1780): Guest Log: [INFO] Requesting an idtoken from vLHC@home-dev 2023-11-04 00:25:46 (1780): Starting VM using VBoxManage interface. (boinc_4cfc7f408c0493aa, slot#24) 2023-11-04 00:25:54 (1780): Successfully started VM. (PID = '21732') 2023-11-04 00:25:54 (1780): Reporting VM Process ID to BOINC. 2023-11-04 00:25:54 (1780): Guest Log: BIOS: VirtualBox 7.0.10 2023-11-04 00:25:54 (1780): Guest Log: CPUID EDX: 0x178bfbff 2023-11-04 00:25:54 (1780): Guest Log: BIOS: No PCI IDE controller, not probing IDE 2023-11-04 00:25:54 (1780): Guest Log: BIOS: AHCI 0-P#0: PCHS=16383/16/63 LCHS=1024/255/63 0x0000000002800000 sectors 2023-11-04 00:25:54 (1780): VM state change detected. (old = 'poweredoff', new = 'running') 2023-11-04 00:25:54 (1780): Detected: Web Application Enabled (http://localhost:49366) 2023-11-04 00:25:54 (1780): Status Report: Job Duration: '64800.000000' 2023-11-04 00:25:54 (1780): Status Report: Elapsed Time: '30800.000000' 2023-11-04 00:25:54 (1780): Status Report: CPU Time: '31259.281250' 2023-11-04 00:25:54 (1780): Preference change detected 2023-11-04 00:25:54 (1780): Setting CPU throttle for VM. (100%) 2023-11-04 00:25:54 (1780): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds)) 2023-11-04 00:25:56 (1780): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032 2023-11-04 00:25:56 (1780): Guest Log: BIOS: Booting from Hard Disk... 2023-11-04 00:25:59 (1780): Guest Log: BIOS: KBD: unsupported int 16h function 03 2023-11-04 00:25:59 (1780): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 2023-11-04 00:26:47 (1780): VM state change detected. (old = 'running', new = 'paused') 2023-11-04 00:26:53 (1780): VM state change detected. (old = 'paused', new = 'running') 2023-11-04 00:26:54 (1780): Stopping VM. 2023-11-04 00:27:22 (1780): Successfully stopped VM. 2023-11-04 00:30:53 (18336): Detected: vboxwrapper 26206 2023-11-04 00:30:53 (18336): Detected: BOINC client v7.24.1 2023-11-04 00:30:53 (18336): Detected: VirtualBox VboxManage Interface (Version: 7.0.10) 2023-11-04 00:30:54 (18336): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds) 2023-11-04 00:30:54 (18336): Guest Log: BIOS: VirtualBox 7.0.10 2023-11-04 00:30:54 (18336): Guest Log: CPUID EDX: 0x178bfbff 2023-11-04 00:30:54 (18336): Guest Log: BIOS: No PCI IDE controller, not probing IDE 2023-11-04 00:30:54 (18336): Guest Log: BIOS: AHCI 0-P#0: PCHS=16383/16/63 LCHS=1024/255/63 0x0000000002800000 sectors 2023-11-04 00:30:54 (18336): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032 2023-11-04 00:30:54 (18336): Guest Log: BIOS: Booting from Hard Disk... 2023-11-04 00:30:54 (18336): Guest Log: BIOS: KBD: unsupported int 16h function 03 2023-11-04 00:30:54 (18336): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 2023-11-04 00:30:54 (18336): Starting VM using VBoxManage interface. (boinc_4cfc7f408c0493aa, slot#24) 2023-11-04 00:31:03 (18336): Successfully started VM. (PID = '7364') 2023-11-04 00:31:03 (18336): Reporting VM Process ID to BOINC. 2023-11-04 00:31:03 (18336): VM state change detected. (old = 'poweredoff', new = 'running') 2023-11-04 00:31:03 (18336): Detected: Web Application Enabled (http://localhost:49366) 2023-11-04 00:31:03 (18336): Status Report: Job Duration: '64800.000000' 2023-11-04 00:31:03 (18336): Status Report: Elapsed Time: '30853.000000' 2023-11-04 00:31:03 (18336): Status Report: CPU Time: '31276.828125' 2023-11-04 00:31:03 (18336): Preference change detected 2023-11-04 00:31:03 (18336): Setting CPU throttle for VM. (100%) 2023-11-04 00:31:03 (18336): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds)) 2023-11-04 00:31:06 (18336): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds 2023-11-04 00:31:06 (18336): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000) 2023-11-04 00:31:09 (18336): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log 2023-11-04 00:31:09 (18336): Guest Log: 00:00:00.000283 main Log opened 2023-11-03T19:22:16.358410000Z 2023-11-04 00:31:09 (18336): Guest Log: 00:00:00.000429 main OS Product: Linux 2023-11-04 00:31:09 (18336): Guest Log: 00:00:00.000476 main OS Release: 4.14.232-19.cernvm.x86_64 2023-11-04 00:31:09 (18336): Guest Log: 00:00:00.000515 main OS Version: #1 SMP Fri Apr 30 17:12:25 CEST 2021 2023-11-04 00:31:09 (18336): Guest Log: 00:00:00.000567 main Executable: /usr/sbin/VBoxService 2023-11-04 00:31:09 (18336): Guest Log: 00:00:00.000568 main Process ID: 2630 2023-11-04 00:31:09 (18336): Guest Log: 00:00:00.000569 main Package type: LINUX_64BITS_GENERIC 2023-11-04 00:31:09 (18336): Guest Log: 00:00:00.003579 main 5.2.6 r120293 started. Verbose level = 0 2023-11-04 00:31:25 (18336): Guest Log: [INFO] Mounting the shared directory 2023-11-04 00:31:25 (18336): Guest Log: [INFO] Shared directory mounted, enabling vboxmonitor 2023-11-04 00:31:25 (18336): Guest Log: [INFO] Sourcing essential functions from /cvmfs/grid.cern.ch 2023-11-04 00:31:25 (18336): Guest Log: [INFO] Testing connection to cern.ch 2023-11-04 00:31:26 (18336): Guest Log: [INFO] Testing connection to VCCS 2023-11-04 00:31:26 (18336): Guest Log: [INFO] Testing connection to HTCondor 2023-11-04 00:31:26 (18336): Guest Log: [INFO] Testing connection to WMAgent 2023-11-04 00:31:27 (18336): Guest Log: [INFO] Testing connection to EOSCMS 2023-11-04 00:31:27 (18336): Guest Log: [INFO] Testing connection to CMS-Factory 2023-11-04 00:31:27 (18336): Guest Log: [INFO] Testing connection to CMS-Frontier 2023-11-04 00:31:28 (18336): Guest Log: [INFO] Testing connection to Frontier 2023-11-04 00:31:29 (18336): Guest Log: [INFO] Could not find a local HTTP proxy 2023-11-04 00:31:30 (18336): Guest Log: [INFO] CVMFS and Frontier will have to use DIRECT connections 2023-11-04 00:31:30 (18336): Guest Log: [INFO] This makes the application less efficient 2023-11-04 00:31:30 (18336): Guest Log: [INFO] It also puts higher load on the project servers 2023-11-04 00:31:30 (18336): Guest Log: [INFO] Setting up a local HTTP proxy is highly recommended 2023-11-04 00:31:30 (18336): Guest Log: [INFO] Advice can be found in the project forum 2023-11-04 00:31:30 (18336): Guest Log: [INFO] Reloading and probing the CVMFS configuration 2023-11-04 00:31:39 (18336): Guest Log: [INFO] Probing /cvmfs/grid.cern.ch... OK 2023-11-04 00:31:41 (18336): Guest Log: [INFO] Probing /cvmfs/cms.cern.ch... OK 2023-11-04 00:31:41 (18336): Guest Log: [INFO] Probing /cvmfs/cms-ib.cern.ch... OK 2023-11-04 00:31:41 (18336): Guest Log: [INFO] Probing /cvmfs/singularity.opensciencegrid.org... OK 2023-11-04 00:31:42 (18336): Guest Log: [INFO] Probing /cvmfs/oasis.opensciencegrid.org... OK 2023-11-04 00:31:43 (18336): Guest Log: [INFO] Excerpt from "cvmfs_config stat": VERSION HOST PROXY 2023-11-04 00:31:43 (18336): Guest Log: [INFO] 2.7.2.0 http://s1fnal-cvmfs.openhtc.io:8080 DIRECT 2023-11-04 00:31:43 (18336): Guest Log: [INFO] Environment HTTP proxy: not set 2023-11-04 00:31:43 (18336): Guest Log: [INFO] Reading volunteer information 2023-11-04 00:31:48 (18336): Guest Log: [INFO] Requesting an X509 credential from LHC@home 2023-11-04 00:31:49 (18336): Guest Log: [INFO] Requesting an X509 credential from vLHC@home-dev 2023-11-04 00:31:50 (18336): Guest Log: [INFO] CMS application starting. Check log files. 2023-11-04 00:31:50 (18336): Guest Log: [INFO] Requesting an idtoken from LHC@home 2023-11-04 00:31:51 (18336): Guest Log: [INFO] Requesting an idtoken from vLHC@home-dev 2023-11-04 00:32:00 (18336): Stopping VM. 2023-11-04 00:32:35 (18336): Successfully stopped VM. 2023-11-04 00:36:58 (20564): Detected: vboxwrapper 26206 2023-11-04 00:36:58 (20564): Detected: BOINC client v7.24.1 2023-11-04 00:36:58 (20564): Detected: VirtualBox VboxManage Interface (Version: 7.0.10) 2023-11-04 00:36:59 (20564): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds) 2023-11-04 00:36:59 (20564): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds 2023-11-04 00:36:59 (20564): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000) 2023-11-04 00:36:59 (20564): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log 2023-11-04 00:36:59 (20564): Guest Log: 00:00:00.000283 main Log opened 2023-11-03T19:22:16.358410000Z 2023-11-04 00:36:59 (20564): Guest Log: 00:00:00.000429 main OS Product: Linux 2023-11-04 00:36:59 (20564): Guest Log: 00:00:00.000476 main OS Release: 4.14.232-19.cernvm.x86_64 2023-11-04 00:36:59 (20564): Guest Log: 00:00:00.000515 main OS Version: #1 SMP Fri Apr 30 17:12:25 CEST 2021 2023-11-04 00:36:59 (20564): Guest Log: 00:00:00.000567 main Executable: /usr/sbin/VBoxService 2023-11-04 00:36:59 (20564): Guest Log: 00:00:00.000568 main Process ID: 2630 2023-11-04 00:36:59 (20564): Guest Log: 00:00:00.000569 main Package type: LINUX_64BITS_GENERIC 2023-11-04 00:36:59 (20564): Guest Log: 00:00:00.003579 main 5.2.6 r120293 started. Verbose level = 0 2023-11-04 00:36:59 (20564): Guest Log: [INFO] Mounting the shared directory 2023-11-04 00:36:59 (20564): Guest Log: [INFO] Shared directory mounted, enabling vboxmonitor 2023-11-04 00:36:59 (20564): Guest Log: [INFO] Sourcing essential functions from /cvmfs/grid.cern.ch 2023-11-04 00:36:59 (20564): Guest Log: [INFO] Testing connection to cern.ch 2023-11-04 00:36:59 (20564): Guest Log: [INFO] Testing connection to VCCS 2023-11-04 00:36:59 (20564): Guest Log: [INFO] Testing connection to HTCondor 2023-11-04 00:36:59 (20564): Guest Log: [INFO] Testing connection to WMAgent 2023-11-04 00:36:59 (20564): Guest Log: [INFO] Testing connection to EOSCMS 2023-11-04 00:36:59 (20564): Guest Log: [INFO] Testing connection to CMS-Factory 2023-11-04 00:36:59 (20564): Guest Log: [INFO] Testing connection to CMS-Frontier 2023-11-04 00:36:59 (20564): Guest Log: [INFO] Testing connection to Frontier 2023-11-04 00:36:59 (20564): Guest Log: [INFO] Could not find a local HTTP proxy 2023-11-04 00:36:59 (20564): Guest Log: [INFO] CVMFS and Frontier will have to use DIRECT connections 2023-11-04 00:36:59 (20564): Guest Log: [INFO] This makes the application less efficient 2023-11-04 00:36:59 (20564): Guest Log: [INFO] It also puts higher load on the project servers 2023-11-04 00:36:59 (20564): Guest Log: [INFO] Setting up a local HTTP proxy is highly recommended 2023-11-04 00:36:59 (20564): Guest Log: [INFO] Advice can be found in the project forum 2023-11-04 00:36:59 (20564): Guest Log: [INFO] Reloading and probing the CVMFS configuration 2023-11-04 00:36:59 (20564): Guest Log: [INFO] Probing /cvmfs/grid.cern.ch... OK 2023-11-04 00:36:59 (20564): Guest Log: [INFO] Probing /cvmfs/cms.cern.ch... OK 2023-11-04 00:36:59 (20564): Guest Log: [INFO] Probing /cvmfs/cms-ib.cern.ch... OK 2023-11-04 00:36:59 (20564): Guest Log: [INFO] Probing /cvmfs/singularity.opensciencegrid.org... OK 2023-11-04 00:36:59 (20564): Guest Log: [INFO] Probing /cvmfs/oasis.opensciencegrid.org... OK 2023-11-04 00:36:59 (20564): Guest Log: [INFO] Excerpt from "cvmfs_config stat": VERSION HOST PROXY 2023-11-04 00:36:59 (20564): Guest Log: [INFO] 2.7.2.0 http://s1fnal-cvmfs.openhtc.io:8080 DIRECT 2023-11-04 00:36:59 (20564): Guest Log: [INFO] Environment HTTP proxy: not set 2023-11-04 00:36:59 (20564): Guest Log: [INFO] Reading volunteer information 2023-11-04 00:36:59 (20564): Guest Log: [INFO] Requesting an X509 credential from LHC@home 2023-11-04 00:36:59 (20564): Guest Log: [INFO] Requesting an X509 credential from vLHC@home-dev 2023-11-04 00:36:59 (20564): Guest Log: [INFO] CMS application starting. Check log files. 2023-11-04 00:36:59 (20564): Guest Log: [INFO] Requesting an idtoken from LHC@home 2023-11-04 00:36:59 (20564): Guest Log: [INFO] Requesting an idtoken from vLHC@home-dev 2023-11-04 00:36:59 (20564): Starting VM using VBoxManage interface. (boinc_4cfc7f408c0493aa, slot#24) 2023-11-04 00:37:09 (20564): Successfully started VM. (PID = '13300') 2023-11-04 00:37:09 (20564): Reporting VM Process ID to BOINC. 2023-11-04 00:37:09 (20564): VM state change detected. (old = 'poweredoff', new = 'running') 2023-11-04 00:37:09 (20564): Detected: Web Application Enabled (http://localhost:49366) 2023-11-04 00:37:09 (20564): Status Report: Job Duration: '64800.000000' 2023-11-04 00:37:09 (20564): Status Report: Elapsed Time: '30910.000000' 2023-11-04 00:37:09 (20564): Status Report: CPU Time: '31343.531250' 2023-11-04 00:37:09 (20564): Preference change detected 2023-11-04 00:37:09 (20564): Setting CPU throttle for VM. (100%) 2023-11-04 00:37:09 (20564): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds)) 2023-11-04 00:38:01 (20564): VM state change detected. (old = 'running', new = 'paused') 2023-11-04 00:38:21 (20564): VM state change detected. (old = 'paused', new = 'running') 2023-11-04 00:48:22 (20564): Stopping VM. 2023-11-04 00:49:09 (20564): Error in stop VM for VM: -182 Command: VBoxManage -q controlvm "boinc_4cfc7f408c0493aa" savestate Output: 0%...10%...20%... 2023-11-04 00:49:09 (20564): VM did not stop when requested. 2023-11-04 00:49:09 (20564): VM was successfully terminated. 2023-11-04 00:58:11 (16428): Detected: vboxwrapper 26206 2023-11-04 00:58:11 (16428): Detected: BOINC client v7.24.1 2023-11-04 00:58:17 (16428): Detected: VirtualBox VboxManage Interface (Version: 7.0.10) 2023-11-04 00:58:18 (16428): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds) 2023-11-04 00:58:18 (16428): Starting VM using VBoxManage interface. (boinc_4cfc7f408c0493aa, slot#24) 2023-11-04 00:58:25 (16428): Successfully started VM. (PID = '23288') 2023-11-04 00:58:25 (16428): Reporting VM Process ID to BOINC. 2023-11-04 00:58:25 (16428): Guest Log: BIOS: VirtualBox 7.0.10 2023-11-04 00:58:25 (16428): Guest Log: CPUID EDX: 0x178bfbff 2023-11-04 00:58:25 (16428): Guest Log: BIOS: No PCI IDE controller, not probing IDE 2023-11-04 00:58:25 (16428): Guest Log: BIOS: AHCI 0-P#0: PCHS=16383/16/63 LCHS=1024/255/63 0x0000000002800000 sectors 2023-11-04 00:58:25 (16428): VM state change detected. (old = 'poweredoff', new = 'running') 2023-11-04 00:58:25 (16428): Detected: Web Application Enabled (http://localhost:49366) 2023-11-04 00:58:25 (16428): Status Report: Job Duration: '64800.000000' 2023-11-04 00:58:25 (16428): Status Report: Elapsed Time: '31562.000000' 2023-11-04 00:58:25 (16428): Status Report: CPU Time: '31905.687500' 2023-11-04 00:58:25 (16428): Preference change detected 2023-11-04 00:58:25 (16428): Setting CPU throttle for VM. (100%) 2023-11-04 00:58:26 (16428): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds)) 2023-11-04 00:58:27 (16428): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032 2023-11-04 00:58:27 (16428): Guest Log: BIOS: Booting from Hard Disk... 2023-11-04 00:58:30 (16428): Guest Log: BIOS: KBD: unsupported int 16h function 03 2023-11-04 00:58:30 (16428): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 2023-11-04 00:58:43 (16428): VM state change detected. (old = 'running', new = 'paused') 2023-11-04 00:59:03 (16428): VM state change detected. (old = 'paused', new = 'running') 2023-11-04 00:59:46 (16428): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds 2023-11-04 00:59:46 (16428): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000) 2023-11-04 00:59:54 (16428): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log 2023-11-04 00:59:54 (16428): Guest Log: 00:00:00.000212 main Log opened 2023-11-03T19:55:13.822677000Z 2023-11-04 00:59:54 (16428): Guest Log: 00:00:00.000342 main OS Product: Linux 2023-11-04 00:59:54 (16428): Guest Log: 00:00:00.000467 main OS Release: 4.14.232-19.cernvm.x86_64 2023-11-04 00:59:54 (16428): Guest Log: 00:00:00.000500 main OS Version: #1 SMP Fri Apr 30 17:12:25 CEST 2021 2023-11-04 00:59:54 (16428): Guest Log: 00:00:00.000526 main Executable: /usr/sbin/VBoxService 2023-11-04 00:59:54 (16428): Guest Log: 00:00:00.000527 main Process ID: 2678 2023-11-04 00:59:54 (16428): Guest Log: 00:00:00.000527 main Package type: LINUX_64BITS_GENERIC 2023-11-04 00:59:54 (16428): Guest Log: 00:00:00.001418 main 5.2.6 r120293 started. Verbose level = 0 2023-11-04 01:00:12 (16428): Guest Log: [INFO] Mounting the shared directory 2023-11-04 01:00:12 (16428): Guest Log: [INFO] Shared directory mounted, enabling vboxmonitor 2023-11-04 01:00:12 (16428): Guest Log: [INFO] Sourcing essential functions from /cvmfs/grid.cern.ch 2023-11-04 01:00:13 (16428): Guest Log: [INFO] Testing connection to cern.ch 2023-11-04 01:00:13 (16428): Guest Log: [INFO] Testing connection to VCCS 2023-11-04 01:00:13 (16428): Guest Log: [INFO] Testing connection to HTCondor 2023-11-04 01:00:13 (16428): Guest Log: [INFO] Testing connection to WMAgent 2023-11-04 01:00:14 (16428): Guest Log: [INFO] Testing connection to EOSCMS 2023-11-04 01:00:14 (16428): Guest Log: [INFO] Testing connection to CMS-Factory 2023-11-04 01:00:14 (16428): Guest Log: [INFO] Testing connection to CMS-Frontier 2023-11-04 01:00:15 (16428): Guest Log: [INFO] Testing connection to Frontier 2023-11-04 01:00:25 (16428): Guest Log: [INFO] Could not find a local HTTP proxy 2023-11-04 01:00:26 (16428): Guest Log: [INFO] CVMFS and Frontier will have to use DIRECT connections 2023-11-04 01:00:26 (16428): Guest Log: [INFO] This makes the application less efficient 2023-11-04 01:00:26 (16428): Guest Log: [INFO] It also puts higher load on the project servers 2023-11-04 01:00:26 (16428): Guest Log: [INFO] Setting up a local HTTP proxy is highly recommended 2023-11-04 01:00:26 (16428): Guest Log: [INFO] Advice can be found in the project forum 2023-11-04 01:00:26 (16428): Guest Log: [INFO] Reloading and probing the CVMFS configuration 2023-11-04 01:00:34 (16428): Guest Log: [INFO] Probing /cvmfs/grid.cern.ch... OK 2023-11-04 01:00:37 (16428): Guest Log: [INFO] Probing /cvmfs/cms-ib.cern.ch... OK 2023-11-04 01:00:37 (16428): Guest Log: [INFO] Probing /cvmfs/oasis.opensciencegrid.org... OK 2023-11-04 01:00:38 (16428): Guest Log: [INFO] Probing /cvmfs/cms.cern.ch... OK 2023-11-04 01:00:38 (16428): Guest Log: [INFO] Probing /cvmfs/singularity.opensciencegrid.org... OK 2023-11-04 01:00:39 (16428): Guest Log: [INFO] Excerpt from "cvmfs_config stat": VERSION HOST PROXY 2023-11-04 01:00:39 (16428): Guest Log: [INFO] 2.7.2.0 http://s1cern-cvmfs.openhtc.io DIRECT 2023-11-04 01:00:39 (16428): Guest Log: [INFO] Environment HTTP proxy: not set 2023-11-04 01:00:39 (16428): Guest Log: [INFO] Reading volunteer information 2023-11-04 01:00:41 (16428): Guest Log: [INFO] Requesting an X509 credential from LHC@home 2023-11-04 01:00:42 (16428): Guest Log: [INFO] Requesting an X509 credential from vLHC@home-dev 2023-11-04 01:00:43 (16428): Guest Log: [INFO] CMS application starting. Check log files. 2023-11-04 01:00:44 (16428): Guest Log: [INFO] Requesting an idtoken from LHC@home 2023-11-04 01:00:45 (16428): Guest Log: [INFO] Requesting an idtoken from vLHC@home-dev 2023-11-04 01:39:45 (16428): VM state change detected. (old = 'running', new = 'paused') 2023-11-04 01:40:05 (16428): VM state change detected. (old = 'paused', new = 'running') 2023-11-04 01:44:47 (16428): VM state change detected. (old = 'running', new = 'paused') 2023-11-04 01:45:08 (16428): VM state change detected. (old = 'paused', new = 'running') 2023-11-04 01:49:48 (16428): VM state change detected. (old = 'running', new = 'paused') 2023-11-04 01:50:09 (16428): VM state change detected. (old = 'paused', new = 'running') 2023-11-04 01:53:39 (16428): VM state change detected. (old = 'running', new = 'paused') 2023-11-04 01:53:59 (16428): VM state change detected. (old = 'paused', new = 'running') 2023-11-04 01:59:51 (16428): VM state change detected. (old = 'running', new = 'paused') 2023-11-04 02:00:11 (16428): VM state change detected. (old = 'paused', new = 'running') 2023-11-04 02:04:51 (16428): VM state change detected. (old = 'running', new = 'paused') 2023-11-04 02:05:11 (16428): VM state change detected. (old = 'paused', new = 'running') 2023-11-04 02:09:53 (16428): VM state change detected. (old = 'running', new = 'paused') 2023-11-04 02:10:13 (16428): VM state change detected. (old = 'paused', new = 'running') 2023-11-04 02:10:33 (16428): VM state change detected. (old = 'running', new = 'paused') 2023-11-04 02:10:53 (16428): VM state change detected. (old = 'paused', new = 'running') 2023-11-04 02:28:45 (16428): VM state change detected. (old = 'running', new = 'paused') 2023-11-04 02:29:05 (16428): VM state change detected. (old = 'paused', new = 'running') 2023-11-04 02:29:45 (16428): VM state change detected. (old = 'running', new = 'paused') 2023-11-04 02:30:05 (16428): VM state change detected. (old = 'paused', new = 'running') 2023-11-04 02:34:46 (16428): VM state change detected. (old = 'running', new = 'paused') 2023-11-04 02:35:06 (16428): VM state change detected. (old = 'paused', new = 'running') 2023-11-04 02:42:40 (16428): Status Report: Job Duration: '64800.000000' 2023-11-04 02:42:40 (16428): Status Report: Elapsed Time: '37562.000000' 2023-11-04 02:42:40 (16428): Status Report: CPU Time: '38015.453125' 2023-11-04 02:43:36 (16428): VM state change detected. (old = 'running', new = 'paused') 2023-11-04 02:43:56 (16428): VM state change detected. (old = 'paused', new = 'running') 2023-11-04 03:04:20 (16428): VM state change detected. (old = 'running', new = 'paused') 2023-11-04 03:04:40 (16428): VM state change detected. (old = 'paused', new = 'running') 2023-11-04 03:19:51 (16428): VM state change detected. (old = 'running', new = 'paused') 2023-11-04 03:20:11 (16428): VM state change detected. (old = 'paused', new = 'running') 2023-11-04 03:24:52 (16428): VM state change detected. (old = 'running', new = 'paused') 2023-11-04 03:25:12 (16428): VM state change detected. (old = 'paused', new = 'running') 2023-11-04 03:51:55 (16428): VM state change detected. (old = 'running', new = 'paused') 2023-11-04 03:52:16 (16428): VM state change detected. (old = 'paused', new = 'running') 2023-11-04 03:54:46 (16428): VM state change detected. (old = 'running', new = 'paused') 2023-11-04 03:55:06 (16428): VM state change detected. (old = 'paused', new = 'running') 2023-11-04 03:56:16 (16428): VM state change detected. (old = 'running', new = 'paused') 2023-11-04 03:56:36 (16428): VM state change detected. (old = 'paused', new = 'running') 2023-11-04 04:09:48 (16428): VM state change detected. (old = 'running', new = 'paused') 2023-11-04 04:10:09 (16428): VM state change detected. (old = 'paused', new = 'running') 2023-11-04 04:14:49 (16428): VM state change detected. (old = 'running', new = 'paused') 2023-11-04 04:15:09 (16428): VM state change detected. (old = 'paused', new = 'running') 2023-11-04 04:19:40 (16428): VM state change detected. (old = 'running', new = 'paused') 2023-11-04 04:20:00 (16428): VM state change detected. (old = 'paused', new = 'running') 2023-11-04 04:26:12 (16428): Status Report: Job Duration: '64800.000000' 2023-11-04 04:26:12 (16428): Status Report: Elapsed Time: '43562.000000' 2023-11-04 04:26:12 (16428): Status Report: CPU Time: '44430.953125' 2023-11-04 04:34:51 (16428): VM state change detected. (old = 'running', new = 'paused') 2023-11-04 04:35:11 (16428): VM state change detected. (old = 'paused', new = 'running') 2023-11-04 04:48:22 (16428): VM state change detected. (old = 'running', new = 'paused') 2023-11-04 04:48:42 (16428): VM state change detected. (old = 'paused', new = 'running') 2023-11-04 05:04:47 (16428): VM state change detected. (old = 'running', new = 'paused') 2023-11-04 05:05:07 (16428): VM state change detected. (old = 'paused', new = 'running') 2023-11-04 05:13:29 (16428): VM state change detected. (old = 'running', new = 'paused') 2023-11-04 05:13:49 (16428): VM state change detected. (old = 'paused', new = 'running') 2023-11-04 05:14:49 (16428): VM state change detected. (old = 'running', new = 'paused') 2023-11-04 05:15:09 (16428): VM state change detected. (old = 'paused', new = 'running') 2023-11-04 05:24:50 (16428): VM state change detected. (old = 'running', new = 'paused') 2023-11-04 05:25:10 (16428): VM state change detected. (old = 'paused', new = 'running') 2023-11-04 05:36:40 (16428): VM state change detected. (old = 'running', new = 'paused') 2023-11-04 05:37:00 (16428): VM state change detected. (old = 'paused', new = 'running') 2023-11-04 05:39:51 (16428): VM state change detected. (old = 'running', new = 'paused') 2023-11-04 05:40:11 (16428): VM state change detected. (old = 'paused', new = 'running') 2023-11-04 05:44:51 (16428): VM state change detected. (old = 'running', new = 'paused') 2023-11-04 05:45:11 (16428): VM state change detected. (old = 'paused', new = 'running') 2023-11-04 05:49:51 (16428): VM state change detected. (old = 'running', new = 'paused') 2023-11-04 05:50:12 (16428): VM state change detected. (old = 'paused', new = 'running') 2023-11-04 05:54:51 (16428): VM state change detected. (old = 'running', new = 'paused') 2023-11-04 05:55:11 (16428): VM state change detected. (old = 'paused', new = 'running') 2023-11-04 05:56:21 (16428): VM state change detected. (old = 'running', new = 'paused') 2023-11-04 05:56:41 (16428): VM state change detected. (old = 'paused', new = 'running') 2023-11-04 05:59:52 (16428): VM state change detected. (old = 'running', new = 'paused') 2023-11-04 06:00:12 (16428): VM state change detected. (old = 'paused', new = 'running') 2023-11-04 06:09:53 (16428): VM state change detected. (old = 'running', new = 'paused') 2023-11-04 06:10:13 (16428): VM state change detected. (old = 'paused', new = 'running') 2023-11-04 06:11:07 (16428): Status Report: Job Duration: '64800.000000' 2023-11-04 06:11:07 (16428): Status Report: Elapsed Time: '49562.000000' 2023-11-04 06:11:07 (16428): Status Report: CPU Time: '50932.281250' 2023-11-04 06:19:45 (16428): VM state change detected. (old = 'running', new = 'paused') 2023-11-04 06:20:05 (16428): VM state change detected. (old = 'paused', new = 'running') 2023-11-04 06:24:45 (16428): VM state change detected. (old = 'running', new = 'paused') 2023-11-04 06:25:05 (16428): VM state change detected. (old = 'paused', new = 'running') 2023-11-04 06:29:46 (16428): VM state change detected. (old = 'running', new = 'paused') 2023-11-04 06:30:06 (16428): VM state change detected. (old = 'paused', new = 'running') 2023-11-04 06:34:46 (16428): VM state change detected. (old = 'running', new = 'paused') 2023-11-04 06:35:06 (16428): VM state change detected. (old = 'paused', new = 'running') 2023-11-04 06:39:46 (16428): VM state change detected. (old = 'running', new = 'paused') 2023-11-04 06:40:06 (16428): VM state change detected. (old = 'paused', new = 'running') 2023-11-04 06:44:47 (16428): VM state change detected. (old = 'running', new = 'paused') 2023-11-04 06:45:07 (16428): VM state change detected. (old = 'paused', new = 'running') 2023-11-04 06:46:17 (16428): VM state change detected. (old = 'running', new = 'paused') 2023-11-04 06:46:37 (16428): VM state change detected. (old = 'paused', new = 'running') 2023-11-04 06:49:47 (16428): VM state change detected. (old = 'running', new = 'paused') 2023-11-04 06:50:07 (16428): VM state change detected. (old = 'paused', new = 'running') 2023-11-04 07:03:03 (16428): Stopping VM. 2023-11-04 07:03:51 (16428): Error in stop VM for VM: -182 Command: VBoxManage -q controlvm "boinc_4cfc7f408c0493aa" savestate Output: 0%...10%...20%...30%...40%... 2023-11-04 07:03:51 (16428): VM did not stop when requested. 2023-11-04 07:03:51 (16428): VM was successfully terminated. 2023-11-04 10:28:52 (25300): Detected: vboxwrapper 26206 2023-11-04 10:28:52 (25300): Detected: BOINC client v7.24.1 2023-11-04 10:28:53 (25300): Detected: VirtualBox VboxManage Interface (Version: 7.0.10) 2023-11-04 10:28:53 (25300): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds) 2023-11-04 10:28:54 (25300): Guest Log: BIOS: VirtualBox 7.0.10 2023-11-04 10:28:54 (25300): Guest Log: CPUID EDX: 0x178bfbff 2023-11-04 10:28:54 (25300): Guest Log: BIOS: No PCI IDE controller, not probing IDE 2023-11-04 10:28:54 (25300): Guest Log: BIOS: AHCI 0-P#0: PCHS=16383/16/63 LCHS=1024/255/63 0x0000000002800000 sectors 2023-11-04 10:28:54 (25300): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032 2023-11-04 10:28:54 (25300): Guest Log: BIOS: Booting from Hard Disk... 2023-11-04 10:28:54 (25300): Guest Log: BIOS: KBD: unsupported int 16h function 03 2023-11-04 10:28:54 (25300): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 2023-11-04 10:28:54 (25300): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds 2023-11-04 10:28:54 (25300): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000) 2023-11-04 10:28:54 (25300): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log 2023-11-04 10:28:54 (25300): Guest Log: 00:00:00.000212 main Log opened 2023-11-03T19:55:13.822677000Z 2023-11-04 10:28:54 (25300): Guest Log: 00:00:00.000342 main OS Product: Linux 2023-11-04 10:28:54 (25300): Guest Log: 00:00:00.000467 main OS Release: 4.14.232-19.cernvm.x86_64 2023-11-04 10:28:54 (25300): Guest Log: 00:00:00.000500 main OS Version: #1 SMP Fri Apr 30 17:12:25 CEST 2021 2023-11-04 10:28:54 (25300): Guest Log: 00:00:00.000526 main Executable: /usr/sbin/VBoxService 2023-11-04 10:28:54 (25300): Guest Log: 00:00:00.000527 main Process ID: 2678 2023-11-04 10:28:54 (25300): Guest Log: 00:00:00.000527 main Package type: LINUX_64BITS_GENERIC 2023-11-04 10:28:54 (25300): Guest Log: 00:00:00.001418 main 5.2.6 r120293 started. Verbose level = 0 2023-11-04 10:28:54 (25300): Guest Log: [INFO] Mounting the shared directory 2023-11-04 10:28:54 (25300): Guest Log: [INFO] Shared directory mounted, enabling vboxmonitor 2023-11-04 10:28:54 (25300): Guest Log: [INFO] Sourcing essential functions from /cvmfs/grid.cern.ch 2023-11-04 10:28:54 (25300): Guest Log: [INFO] Testing connection to cern.ch 2023-11-04 10:28:54 (25300): Guest Log: [INFO] Testing connection to VCCS 2023-11-04 10:28:54 (25300): Guest Log: [INFO] Testing connection to HTCondor 2023-11-04 10:28:54 (25300): Guest Log: [INFO] Testing connection to WMAgent 2023-11-04 10:28:54 (25300): Guest Log: [INFO] Testing connection to EOSCMS 2023-11-04 10:28:54 (25300): Guest Log: [INFO] Testing connection to CMS-Factory 2023-11-04 10:28:54 (25300): Guest Log: [INFO] Testing connection to CMS-Frontier 2023-11-04 10:28:54 (25300): Guest Log: [INFO] Testing connection to Frontier 2023-11-04 10:28:54 (25300): Guest Log: [INFO] Could not find a local HTTP proxy 2023-11-04 10:28:54 (25300): Guest Log: [INFO] CVMFS and Frontier will have to use DIRECT connections 2023-11-04 10:28:54 (25300): Guest Log: [INFO] This makes the application less efficient 2023-11-04 10:28:54 (25300): Guest Log: [INFO] It also puts higher load on the project servers 2023-11-04 10:28:54 (25300): Guest Log: [INFO] Setting up a local HTTP proxy is highly recommended 2023-11-04 10:28:54 (25300): Guest Log: [INFO] Advice can be found in the project forum 2023-11-04 10:28:54 (25300): Guest Log: [INFO] Reloading and probing the CVMFS configuration 2023-11-04 10:28:54 (25300): Guest Log: [INFO] Probing /cvmfs/grid.cern.ch... OK 2023-11-04 10:28:54 (25300): Guest Log: [INFO] Probing /cvmfs/cms-ib.cern.ch... OK 2023-11-04 10:28:54 (25300): Guest Log: [INFO] Probing /cvmfs/oasis.opensciencegrid.org... OK 2023-11-04 10:28:54 (25300): Guest Log: [INFO] Probing /cvmfs/cms.cern.ch... OK 2023-11-04 10:28:54 (25300): Guest Log: [INFO] Probing /cvmfs/singularity.opensciencegrid.org... OK 2023-11-04 10:28:54 (25300): Guest Log: [INFO] Excerpt from "cvmfs_config stat": VERSION HOST PROXY 2023-11-04 10:28:54 (25300): Guest Log: [INFO] 2.7.2.0 http://s1cern-cvmfs.openhtc.io DIRECT 2023-11-04 10:28:54 (25300): Guest Log: [INFO] Environment HTTP proxy: not set 2023-11-04 10:28:54 (25300): Guest Log: [INFO] Reading volunteer information 2023-11-04 10:28:54 (25300): Guest Log: [INFO] Requesting an X509 credential from LHC@home 2023-11-04 10:28:54 (25300): Guest Log: [INFO] Requesting an X509 credential from vLHC@home-dev 2023-11-04 10:28:54 (25300): Guest Log: [INFO] CMS application starting. Check log files. 2023-11-04 10:28:54 (25300): Guest Log: [INFO] Requesting an idtoken from LHC@home 2023-11-04 10:28:54 (25300): Guest Log: [INFO] Requesting an idtoken from vLHC@home-dev 2023-11-04 10:28:54 (25300): Starting VM using VBoxManage interface. (boinc_4cfc7f408c0493aa, slot#24) 2023-11-04 10:29:01 (25300): Successfully started VM. (PID = '18820') 2023-11-04 10:29:01 (25300): Reporting VM Process ID to BOINC. 2023-11-04 10:29:01 (25300): Guest Log: BIOS: VirtualBox 7.0.10 2023-11-04 10:29:01 (25300): Guest Log: CPUID EDX: 0x178bfbff 2023-11-04 10:29:01 (25300): Guest Log: BIOS: No PCI IDE controller, not probing IDE 2023-11-04 10:29:01 (25300): Guest Log: BIOS: AHCI 0-P#0: PCHS=16383/16/63 LCHS=1024/255/63 0x0000000002800000 sectors 2023-11-04 10:29:01 (25300): VM state change detected. (old = 'poweredoff', new = 'running') 2023-11-04 10:29:01 (25300): Detected: Web Application Enabled (http://localhost:49366) 2023-11-04 10:29:02 (25300): VM state change detected. (old = 'running', new = 'paused') 2023-11-04 10:29:21 (25300): Status Report: Job Duration: '64800.000000' 2023-11-04 10:29:21 (25300): Status Report: Elapsed Time: '52510.000000' 2023-11-04 10:29:21 (25300): Status Report: CPU Time: '54141.875000' 2023-11-04 10:29:21 (25300): Preference change detected 2023-11-04 10:29:21 (25300): Setting CPU throttle for VM. (100%) 2023-11-04 10:29:21 (25300): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds)) 2023-11-04 10:29:22 (25300): VM state change detected. (old = 'paused', new = 'running') 2023-11-04 10:29:23 (25300): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032 2023-11-04 10:29:23 (25300): Guest Log: BIOS: Booting from Hard Disk... 2023-11-04 10:29:26 (25300): Guest Log: BIOS: KBD: unsupported int 16h function 03 2023-11-04 10:29:26 (25300): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 2023-11-04 10:30:13 (25300): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds 2023-11-04 10:30:13 (25300): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000) 2023-11-04 10:30:17 (25300): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log 2023-11-04 10:30:17 (25300): Guest Log: 00:00:00.000177 main Log opened 2023-11-04T05:25:36.337796000Z 2023-11-04 10:30:17 (25300): Guest Log: 00:00:00.000481 main OS Product: Linux 2023-11-04 10:30:17 (25300): Guest Log: 00:00:00.000533 main OS Release: 4.14.232-19.cernvm.x86_64 2023-11-04 10:30:17 (25300): Guest Log: 00:00:00.000602 main OS Version: #1 SMP Fri Apr 30 17:12:25 CEST 2021 2023-11-04 10:30:17 (25300): Guest Log: 00:00:00.000654 main Executable: /usr/sbin/VBoxService 2023-11-04 10:30:17 (25300): Guest Log: 00:00:00.000655 main Process ID: 2631 2023-11-04 10:30:17 (25300): Guest Log: 00:00:00.000656 main Package type: LINUX_64BITS_GENERIC 2023-11-04 10:30:17 (25300): Guest Log: 00:00:00.001561 main 5.2.6 r120293 started. Verbose level = 0 2023-11-04 10:30:34 (25300): Guest Log: [INFO] Mounting the shared directory 2023-11-04 10:30:34 (25300): Guest Log: [INFO] Shared directory mounted, enabling vboxmonitor 2023-11-04 10:30:34 (25300): Guest Log: [INFO] Sourcing essential functions from /cvmfs/grid.cern.ch 2023-11-04 10:30:34 (25300): Guest Log: [INFO] Testing connection to cern.ch 2023-11-04 10:30:35 (25300): Guest Log: [INFO] Testing connection to VCCS 2023-11-04 10:30:35 (25300): Guest Log: [INFO] Testing connection to HTCondor 2023-11-04 10:30:35 (25300): Guest Log: [INFO] Testing connection to WMAgent 2023-11-04 10:30:36 (25300): Guest Log: [INFO] Testing connection to EOSCMS 2023-11-04 10:30:36 (25300): Guest Log: [INFO] Testing connection to CMS-Factory 2023-11-04 10:30:36 (25300): Guest Log: [INFO] Testing connection to CMS-Frontier 2023-11-04 10:30:37 (25300): Guest Log: [INFO] Testing connection to Frontier 2023-11-04 10:30:38 (25300): Guest Log: [INFO] Could not find a local HTTP proxy 2023-11-04 10:30:38 (25300): Guest Log: [INFO] CVMFS and Frontier will have to use DIRECT connections 2023-11-04 10:30:38 (25300): Guest Log: [INFO] This makes the application less efficient 2023-11-04 10:30:38 (25300): Guest Log: [INFO] It also puts higher load on the project servers 2023-11-04 10:30:38 (25300): Guest Log: [INFO] Setting up a local HTTP proxy is highly recommended 2023-11-04 10:30:38 (25300): Guest Log: [INFO] Advice can be found in the project forum 2023-11-04 10:30:38 (25300): Guest Log: [INFO] Reloading and probing the CVMFS configuration 2023-11-04 10:30:47 (25300): Guest Log: [INFO] Probing /cvmfs/grid.cern.ch... OK 2023-11-04 10:30:50 (25300): Guest Log: [INFO] Probing /cvmfs/singularity.opensciencegrid.org... OK 2023-11-04 10:30:52 (25300): Guest Log: [INFO] Probing /cvmfs/cms.cern.ch... OK 2023-11-04 10:30:52 (25300): Guest Log: [INFO] Probing /cvmfs/oasis.opensciencegrid.org... OK 2023-11-04 10:30:55 (25300): Guest Log: [INFO] Probing /cvmfs/cms-ib.cern.ch... OK 2023-11-04 10:30:56 (25300): Guest Log: [INFO] Excerpt from "cvmfs_config stat": VERSION HOST PROXY 2023-11-04 10:30:56 (25300): Guest Log: [INFO] 2.7.2.0 http://s1cern-cvmfs.openhtc.io DIRECT 2023-11-04 10:30:56 (25300): Guest Log: [INFO] Environment HTTP proxy: not set 2023-11-04 10:30:56 (25300): Guest Log: [INFO] Reading volunteer information 2023-11-04 10:31:01 (25300): Guest Log: [INFO] Requesting an X509 credential from LHC@home 2023-11-04 10:31:03 (25300): Guest Log: [INFO] Requesting an X509 credential from vLHC@home-dev 2023-11-04 10:31:04 (25300): Guest Log: [INFO] CMS application starting. Check log files. 2023-11-04 10:31:05 (25300): Guest Log: [INFO] Requesting an idtoken from LHC@home 2023-11-04 10:31:05 (25300): Guest Log: [INFO] Requesting an idtoken from vLHC@home-dev 2023-11-04 10:34:44 (25300): VM state change detected. (old = 'running', new = 'paused') 2023-11-04 10:35:04 (25300): VM state change detected. (old = 'paused', new = 'running') 2023-11-04 10:39:45 (25300): VM state change detected. (old = 'running', new = 'paused') 2023-11-04 10:40:05 (25300): VM state change detected. (old = 'paused', new = 'running') 2023-11-04 10:46:26 (25300): VM state change detected. (old = 'running', new = 'paused') 2023-11-04 10:46:46 (25300): VM state change detected. (old = 'paused', new = 'running') 2023-11-04 10:54:49 (25300): VM state change detected. (old = 'running', new = 'paused') 2023-11-04 10:55:09 (25300): VM state change detected. (old = 'paused', new = 'running') 2023-11-04 10:59:50 (25300): VM state change detected. (old = 'running', new = 'paused') 2023-11-04 11:00:10 (25300): VM state change detected. (old = 'paused', new = 'running') 2023-11-04 11:04:50 (25300): VM state change detected. (old = 'running', new = 'paused') 2023-11-04 11:05:10 (25300): VM state change detected. (old = 'paused', new = 'running') 2023-11-04 11:09:01 (25300): VM state change detected. (old = 'running', new = 'paused') 2023-11-04 11:09:20 (25300): VM state change detected. (old = 'paused', new = 'running') 2023-11-04 11:14:53 (25300): VM state change detected. (old = 'running', new = 'paused') 2023-11-04 11:15:04 (25300): VM state change detected. (old = 'paused', new = 'running') 2023-11-04 11:16:53 (25300): Stopping VM. 2023-11-04 11:17:39 (25300): Error in stop VM for VM: -182 Command: VBoxManage -q controlvm "boinc_4cfc7f408c0493aa" savestate Output: 0%...10%...20%...30%... 2023-11-04 11:17:39 (25300): VM did not stop when requested. 2023-11-04 11:17:39 (25300): VM was successfully terminated. 2023-11-06 21:04:40 (17860): Detected: vboxwrapper 26206 2023-11-06 21:04:40 (17860): Detected: BOINC client v7.24.1 2023-11-06 21:04:57 (17860): Detected: VirtualBox VboxManage Interface (Version: 7.0.10) 2023-11-06 21:04:57 (17860): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds) 2023-11-06 21:04:58 (17860): Guest Log: BIOS: VirtualBox 7.0.10 2023-11-06 21:04:58 (17860): Guest Log: CPUID EDX: 0x178bfbff 2023-11-06 21:04:58 (17860): Guest Log: BIOS: No PCI IDE controller, not probing IDE 2023-11-06 21:04:58 (17860): Guest Log: BIOS: AHCI 0-P#0: PCHS=16383/16/63 LCHS=1024/255/63 0x0000000002800000 sectors 2023-11-06 21:04:58 (17860): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032 2023-11-06 21:04:58 (17860): Guest Log: BIOS: Booting from Hard Disk... 2023-11-06 21:04:58 (17860): Guest Log: BIOS: KBD: unsupported int 16h function 03 2023-11-06 21:04:58 (17860): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 2023-11-06 21:04:58 (17860): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds 2023-11-06 21:04:58 (17860): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000) 2023-11-06 21:04:58 (17860): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log 2023-11-06 21:04:58 (17860): Guest Log: 00:00:00.000177 main Log opened 2023-11-04T05:25:36.337796000Z 2023-11-06 21:04:58 (17860): Guest Log: 00:00:00.000481 main OS Product: Linux 2023-11-06 21:04:58 (17860): Guest Log: 00:00:00.000533 main OS Release: 4.14.232-19.cernvm.x86_64 2023-11-06 21:04:58 (17860): Guest Log: 00:00:00.000602 main OS Version: #1 SMP Fri Apr 30 17:12:25 CEST 2021 2023-11-06 21:04:58 (17860): Guest Log: 00:00:00.000654 main Executable: /usr/sbin/VBoxService 2023-11-06 21:04:58 (17860): Guest Log: 00:00:00.000655 main Process ID: 2631 2023-11-06 21:04:58 (17860): Guest Log: 00:00:00.000656 main Package type: LINUX_64BITS_GENERIC 2023-11-06 21:04:58 (17860): Guest Log: 00:00:00.001561 main 5.2.6 r120293 started. Verbose level = 0 2023-11-06 21:04:58 (17860): Guest Log: [INFO] Mounting the shared directory 2023-11-06 21:04:58 (17860): Guest Log: [INFO] Shared directory mounted, enabling vboxmonitor 2023-11-06 21:04:58 (17860): Guest Log: [INFO] Sourcing essential functions from /cvmfs/grid.cern.ch 2023-11-06 21:04:58 (17860): Guest Log: [INFO] Testing connection to cern.ch 2023-11-06 21:04:58 (17860): Guest Log: [INFO] Testing connection to VCCS 2023-11-06 21:04:58 (17860): Guest Log: [INFO] Testing connection to HTCondor 2023-11-06 21:04:58 (17860): Guest Log: [INFO] Testing connection to WMAgent 2023-11-06 21:04:58 (17860): Guest Log: [INFO] Testing connection to EOSCMS 2023-11-06 21:04:58 (17860): Guest Log: [INFO] Testing connection to CMS-Factory 2023-11-06 21:04:58 (17860): Guest Log: [INFO] Testing connection to CMS-Frontier 2023-11-06 21:04:58 (17860): Guest Log: [INFO] Testing connection to Frontier 2023-11-06 21:04:58 (17860): Guest Log: [INFO] Could not find a local HTTP proxy 2023-11-06 21:04:58 (17860): Guest Log: [INFO] CVMFS and Frontier will have to use DIRECT connections 2023-11-06 21:04:58 (17860): Guest Log: [INFO] This makes the application less efficient 2023-11-06 21:04:58 (17860): Guest Log: [INFO] It also puts higher load on the project servers 2023-11-06 21:04:58 (17860): Guest Log: [INFO] Setting up a local HTTP proxy is highly recommended 2023-11-06 21:04:59 (17860): Guest Log: [INFO] Advice can be found in the project forum 2023-11-06 21:04:59 (17860): Guest Log: [INFO] Reloading and probing the CVMFS configuration 2023-11-06 21:04:59 (17860): Guest Log: [INFO] Probing /cvmfs/grid.cern.ch... OK 2023-11-06 21:04:59 (17860): Guest Log: [INFO] Probing /cvmfs/singularity.opensciencegrid.org... OK 2023-11-06 21:04:59 (17860): Guest Log: [INFO] Probing /cvmfs/cms.cern.ch... OK 2023-11-06 21:04:59 (17860): Guest Log: [INFO] Probing /cvmfs/oasis.opensciencegrid.org... OK 2023-11-06 21:04:59 (17860): Guest Log: [INFO] Probing /cvmfs/cms-ib.cern.ch... OK 2023-11-06 21:04:59 (17860): Guest Log: [INFO] Excerpt from "cvmfs_config stat": VERSION HOST PROXY 2023-11-06 21:04:59 (17860): Guest Log: [INFO] 2.7.2.0 http://s1cern-cvmfs.openhtc.io DIRECT 2023-11-06 21:04:59 (17860): Guest Log: [INFO] Environment HTTP proxy: not set 2023-11-06 21:04:59 (17860): Guest Log: [INFO] Reading volunteer information 2023-11-06 21:04:59 (17860): Guest Log: [INFO] Requesting an X509 credential from LHC@home 2023-11-06 21:04:59 (17860): Guest Log: [INFO] Requesting an X509 credential from vLHC@home-dev 2023-11-06 21:04:59 (17860): Guest Log: [INFO] CMS application starting. Check log files. 2023-11-06 21:04:59 (17860): Guest Log: [INFO] Requesting an idtoken from LHC@home 2023-11-06 21:04:59 (17860): Guest Log: [INFO] Requesting an idtoken from vLHC@home-dev 2023-11-06 21:04:59 (17860): Starting VM using VBoxManage interface. (boinc_4cfc7f408c0493aa, slot#24) 2023-11-06 21:05:26 (17860): Successfully started VM. (PID = '12988') 2023-11-06 21:05:26 (17860): Reporting VM Process ID to BOINC. 2023-11-06 21:05:26 (17860): Guest Log: BIOS: VirtualBox 7.0.10 2023-11-06 21:05:26 (17860): Guest Log: CPUID EDX: 0x178bfbff 2023-11-06 21:05:26 (17860): Guest Log: BIOS: No PCI IDE controller, not probing IDE 2023-11-06 21:05:26 (17860): Guest Log: BIOS: AHCI 0-P#0: PCHS=16383/16/63 LCHS=1024/255/63 0x0000000002800000 sectors 2023-11-06 21:05:26 (17860): VM state change detected. (old = 'poweredoff', new = 'running') 2023-11-06 21:05:26 (17860): Detected: Web Application Enabled (http://localhost:49366) 2023-11-06 21:05:26 (17860): Status Report: Job Duration: '64800.000000' 2023-11-06 21:05:26 (17860): Status Report: Elapsed Time: '55204.000000' 2023-11-06 21:05:26 (17860): Status Report: CPU Time: '56878.484375' 2023-11-06 21:05:26 (17860): Preference change detected 2023-11-06 21:05:26 (17860): Setting CPU throttle for VM. (100%) 2023-11-06 21:05:26 (17860): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds)) 2023-11-06 21:05:28 (17860): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032 2023-11-06 21:05:28 (17860): Guest Log: BIOS: Booting from Hard Disk... 2023-11-06 21:05:31 (17860): Guest Log: BIOS: KBD: unsupported int 16h function 03 2023-11-06 21:05:31 (17860): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 2023-11-06 21:06:20 (17860): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds 2023-11-06 21:06:20 (17860): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000) 2023-11-06 21:06:29 (17860): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log 2023-11-06 21:06:29 (17860): Guest Log: 00:00:00.024381 main Log opened 2023-11-06T16:01:49.900531000Z 2023-11-06 21:06:29 (17860): Guest Log: 00:00:00.096481 main OS Product: Linux 2023-11-06 21:06:29 (17860): Guest Log: 00:00:00.099033 main OS Release: 4.14.232-19.cernvm.x86_64 2023-11-06 21:06:29 (17860): Guest Log: 00:00:00.101721 main OS Version: #1 SMP Fri Apr 30 17:12:25 CEST 2021 2023-11-06 21:06:29 (17860): Guest Log: 00:00:00.107377 main Executable: /usr/sbin/VBoxService 2023-11-06 21:06:29 (17860): Guest Log: 00:00:00.107380 main Process ID: 2687 2023-11-06 21:06:29 (17860): Guest Log: 00:00:00.107380 main Package type: LINUX_64BITS_GENERIC 2023-11-06 21:06:29 (17860): Guest Log: 00:00:00.159065 main 5.2.6 r120293 started. Verbose level = 0 2023-11-06 21:06:51 (17860): Guest Log: [INFO] Mounting the shared directory 2023-11-06 21:06:51 (17860): Guest Log: [INFO] Shared directory mounted, enabling vboxmonitor 2023-11-06 21:06:51 (17860): Guest Log: [INFO] Sourcing essential functions from /cvmfs/grid.cern.ch 2023-11-06 21:06:52 (17860): Guest Log: [INFO] Testing connection to cern.ch 2023-11-06 21:06:52 (17860): Guest Log: [INFO] Testing connection to VCCS 2023-11-06 21:06:52 (17860): Guest Log: [INFO] Testing connection to HTCondor 2023-11-06 21:06:53 (17860): Guest Log: [INFO] Testing connection to WMAgent 2023-11-06 21:06:53 (17860): Guest Log: [INFO] Testing connection to EOSCMS 2023-11-06 21:06:53 (17860): Guest Log: [INFO] Testing connection to CMS-Factory 2023-11-06 21:06:54 (17860): Guest Log: [INFO] Testing connection to CMS-Frontier 2023-11-06 21:06:54 (17860): Guest Log: [INFO] Testing connection to Frontier 2023-11-06 21:07:05 (17860): Guest Log: [INFO] Could not find a local HTTP proxy 2023-11-06 21:07:05 (17860): Guest Log: [INFO] CVMFS and Frontier will have to use DIRECT connections 2023-11-06 21:07:05 (17860): Guest Log: [INFO] This makes the application less efficient 2023-11-06 21:07:05 (17860): Guest Log: [INFO] It also puts higher load on the project servers 2023-11-06 21:07:05 (17860): Guest Log: [INFO] Setting up a local HTTP proxy is highly recommended 2023-11-06 21:07:05 (17860): Guest Log: [INFO] Advice can be found in the project forum 2023-11-06 21:07:06 (17860): Guest Log: [INFO] Reloading and probing the CVMFS configuration 2023-11-06 21:07:15 (17860): Guest Log: [INFO] Probing /cvmfs/grid.cern.ch... OK 2023-11-06 21:07:19 (17860): Guest Log: [INFO] Probing /cvmfs/cms.cern.ch... OK 2023-11-06 21:07:20 (17860): Guest Log: [INFO] Probing /cvmfs/oasis.opensciencegrid.org... OK 2023-11-06 21:07:20 (17860): Guest Log: [INFO] Probing /cvmfs/cms-ib.cern.ch... OK 2023-11-06 21:07:22 (17860): Guest Log: [INFO] Probing /cvmfs/singularity.opensciencegrid.org... OK 2023-11-06 21:07:23 (17860): Guest Log: [INFO] Excerpt from "cvmfs_config stat": VERSION HOST PROXY 2023-11-06 21:07:23 (17860): Guest Log: [INFO] 2.7.2.0 http://s1cern-cvmfs.openhtc.io DIRECT 2023-11-06 21:07:24 (17860): Guest Log: [INFO] Environment HTTP proxy: not set 2023-11-06 21:07:24 (17860): Guest Log: [INFO] Reading volunteer information 2023-11-06 21:07:31 (17860): Guest Log: [INFO] Requesting an X509 credential from LHC@home 2023-11-06 21:07:32 (17860): Guest Log: [INFO] Requesting an X509 credential from vLHC@home-dev 2023-11-06 21:07:34 (17860): Guest Log: [INFO] CMS application starting. Check log files. 2023-11-06 21:07:35 (17860): Guest Log: [INFO] Requesting an idtoken from LHC@home 2023-11-06 21:07:35 (17860): Guest Log: [INFO] Requesting an idtoken from vLHC@home-dev 2023-11-06 22:17:34 (17860): VM state change detected. (old = 'running', new = 'paused') 2023-11-06 22:30:03 (17860): VM state change detected. (old = 'paused', new = 'running') 2023-11-06 22:30:10 (17860): Preference change detected 2023-11-06 22:30:10 (17860): Setting CPU throttle for VM. (100%) 2023-11-06 22:30:11 (17860): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds)) 2023-11-06 22:36:24 (17860): VM state change detected. (old = 'running', new = 'paused') 2023-11-06 22:36:32 (17860): VM state change detected. (old = 'paused', new = 'running') 2023-11-06 22:52:11 (17860): VM state change detected. (old = 'running', new = 'paused') 2023-11-06 22:52:31 (17860): VM state change detected. (old = 'paused', new = 'running') 2023-11-06 22:58:28 (17860): Status Report: Job Duration: '64800.000000' 2023-11-06 22:58:28 (17860): Status Report: Elapsed Time: '61204.000000' 2023-11-06 22:58:28 (17860): Status Report: CPU Time: '63030.218750' 2023-11-06 23:30:37 (17860): VM state change detected. (old = 'running', new = 'paused') 2023-11-08 13:57:02 (17860): VM state change detected. (old = 'paused', new = 'running') 2023-11-08 13:57:09 (17860): Guest Log: 02:11:20.284901 timesync vgsvcTimeSyncWorker: Radical host time change: 138 396 343 000 000ns (HostNow=1 699 433 828 546 000 000 ns HostLast=1 699 295 432 203 000 000 ns) 2023-11-08 13:57:19 (17860): Guest Log: 02:11:30.311260 timesync vgsvcTimeSyncWorker: Radical guest time change: 139 448 406 494 000ns (GuestNow=1 699 433 838 572 935 000 ns GuestLast=1 699 294 390 166 441 000 ns fSetTimeLastLoop=true ) 2023-11-08 13:57:34 (17860): VM state change detected. (old = 'running', new = 'paused') 2023-11-08 13:57:54 (17860): VM state change detected. (old = 'paused', new = 'running') 2023-11-08 13:59:54 (17860): VM state change detected. (old = 'running', new = 'paused') 2023-11-08 14:00:14 (17860): VM state change detected. (old = 'paused', new = 'running') 2023-11-08 14:03:17 (17860): Guest Log: [INFO] glidein exited with return value 0. 2023-11-08 14:03:17 (17860): Guest Log: [INFO] Shutting Down. 2023-11-08 14:03:17 (17860): VM Completion File Detected. 2023-11-08 14:03:17 (17860): VM Completion Message: glidein exited with return value 0. . 2023-11-08 14:03:17 (17860): Powering off VM. 2023-11-08 14:03:19 (17860): Successfully stopped VM. 2023-11-08 14:03:19 (17860): Deregistering VM. (boinc_4cfc7f408c0493aa, slot#24) 2023-11-08 14:03:19 (17860): Removing network bandwidth throttle group from VM. 2023-11-08 14:03:20 (17860): Removing VM from VirtualBox. 14:03:25 (17860): called boinc_finish(0) </stderr_txt> ]]>
©2025 CERN