Task 3284156

Name	CMS_1830192_1703473307.428882_0
Workunit	2371751
Created	25 Dec 2023, 3:01:50 UTC
Sent	25 Dec 2023, 17:32:59 UTC
Report deadline	1 Jan 2024, 17:32:59 UTC
Received	26 Dec 2023, 8:35:43 UTC
Server state	Over
Outcome	Success
Client state	Done
Exit status	0 (0x00000000)
Computer ID	4752
Run time	10 hours 46 min 14 sec
CPU time	10 hours 45 min 30 sec
Validate state	Valid
Credit	616.74
Device peak FLOPS	5.13 GFLOPS
Application version	CMS Simulation v60.70 (vbox64_mt_mcore_cms) windows_x86_64
Peak working set size	86.47 MB
Peak swap size	80.60 MB
Peak disk usage	1.45 GB
Stderr output

<core_client_version>7.24.1</core_client_version>
<![CDATA[
<stderr_txt>
2023-12-25 12:42:38 (4580): Detected: vboxwrapper 26206
2023-12-25 12:42:38 (4580): Detected: BOINC client v7.24.1
2023-12-25 12:42:39 (4580): Detected: VirtualBox VboxManage Interface (Version: 7.0.6)
2023-12-25 12:42:39 (4580): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2023-12-25 12:42:39 (4580): Successfully copied 'init_data.xml' to the shared directory.
2023-12-25 12:42:40 (4580): Create VM. (boinc_1cb628fcf1ed3308, slot#3)
2023-12-25 12:42:41 (4580): Setting Memory Size for VM. (1896MB)
2023-12-25 12:42:41 (4580): Setting CPU Count for VM. (1)
2023-12-25 12:42:41 (4580): Setting Chipset Options for VM.
2023-12-25 12:42:41 (4580): Setting Graphics Controller Options for VM.
2023-12-25 12:42:42 (4580): Setting Boot Options for VM.
2023-12-25 12:42:42 (4580): Setting Network Configuration for NAT.
2023-12-25 12:42:42 (4580): Enabling VM Network Access.
2023-12-25 12:42:42 (4580): Disabling USB Support for VM.
2023-12-25 12:42:43 (4580): Disabling COM Port Support for VM.
2023-12-25 12:42:43 (4580): Disabling LPT Port Support for VM.
2023-12-25 12:42:43 (4580): Disabling Audio Support for VM.
2023-12-25 12:42:44 (4580): Disabling Clipboard Support for VM.
2023-12-25 12:42:44 (4580): Disabling Drag and Drop Support for VM.
2023-12-25 12:42:44 (4580): Adding storage controller(s) to VM.
2023-12-25 12:42:44 (4580): Adding virtual disk drive to VM. (CMS_2022_09_07.vdi)
2023-12-25 12:42:47 (4580): Adding VirtualBox Guest Additions to VM.
2023-12-25 12:42:48 (4580): Adding network bandwidth throttle group to VM. (Defaulting to 1024GB)
2023-12-25 12:42:48 (4580): forwarding host port 56654 to guest port 80
2023-12-25 12:42:48 (4580): Enabling remote desktop for VM.
2023-12-25 12:42:49 (4580): Required extension pack not installed, remote desktop not enabled.
2023-12-25 12:42:49 (4580): Enabling shared directory for VM.
2023-12-25 12:42:49 (4580): Starting VM using VBoxManage interface. (boinc_1cb628fcf1ed3308, slot#3)
2023-12-25 12:42:55 (4580): Successfully started VM. (PID = '4656')
2023-12-25 12:42:55 (4580): Reporting VM Process ID to BOINC.
2023-12-25 12:42:55 (4580): Guest Log: BIOS: VirtualBox 7.0.6
2023-12-25 12:42:55 (4580): Guest Log: CPUID EDX: 0x178bfbff
2023-12-25 12:42:55 (4580): Guest Log: BIOS: No PCI IDE controller, not probing IDE
2023-12-25 12:42:55 (4580): Guest Log: BIOS: AHCI 0-P#0: PCHS=16383/16/63 LCHS=1024/255/63 0x0000000002800000 sectors
2023-12-25 12:42:55 (4580): VM state change detected. (old = 'poweredoff', new = 'running')
2023-12-25 12:42:55 (4580): Detected: Web Application Enabled (http://localhost:56654)
2023-12-25 12:42:55 (4580): Preference change detected
2023-12-25 12:42:55 (4580): Setting CPU throttle for VM. (100%)
2023-12-25 12:42:55 (4580): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2023-12-25 12:42:57 (4580): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032
2023-12-25 12:42:57 (4580): Guest Log: BIOS: Booting from Hard Disk...
2023-12-25 12:42:59 (4580): Guest Log: BIOS: KBD: unsupported int 16h function 03
2023-12-25 12:42:59 (4580): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 
2023-12-25 12:43:21 (4580): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds
2023-12-25 12:43:21 (4580): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000)
2023-12-25 12:43:22 (4580): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log
2023-12-25 12:43:22 (4580): Guest Log: 00:00:00.000148 main     Log opened 2023-12-25T17:43:23.283537000Z
2023-12-25 12:43:22 (4580): Guest Log: 00:00:00.000399 main     OS Product: Linux
2023-12-25 12:43:22 (4580): Guest Log: 00:00:00.000441 main     OS Release: 4.14.232-19.cernvm.x86_64
2023-12-25 12:43:22 (4580): Guest Log: 00:00:00.000474 main     OS Version: #1 SMP Fri Apr 30 17:12:25 CEST 2021
2023-12-25 12:43:22 (4580): Guest Log: 00:00:00.000507 main     Executable: /usr/sbin/VBoxService
2023-12-25 12:43:22 (4580): Guest Log: 00:00:00.000507 main     Process ID: 2168
2023-12-25 12:43:22 (4580): Guest Log: 00:00:00.000508 main     Package type: LINUX_64BITS_GENERIC
2023-12-25 12:43:22 (4580): Guest Log: 00:00:00.003198 main     5.2.6 r120293 started. Verbose level = 0
2023-12-25 12:43:32 (4580): Guest Log: [INFO] Mounting the shared directory
2023-12-25 12:43:32 (4580): Guest Log: [INFO] Shared directory mounted, enabling vboxmonitor
2023-12-25 12:43:33 (4580): Guest Log: [INFO] Sourcing essential functions from /cvmfs/grid.cern.ch
2023-12-25 12:43:33 (4580): Guest Log: [INFO] Testing connection to cern.ch
2023-12-25 12:43:33 (4580): Guest Log: [INFO] Testing connection to VCCS
2023-12-25 12:43:33 (4580): Guest Log: [INFO] Testing connection to HTCondor
2023-12-25 12:43:34 (4580): Guest Log: [INFO] Testing connection to WMAgent
2023-12-25 12:43:34 (4580): Guest Log: [INFO] Testing connection to EOSCMS
2023-12-25 12:43:34 (4580): Guest Log: [INFO] Testing connection to CMS-Factory
2023-12-25 12:43:35 (4580): Guest Log: [INFO] Testing connection to CMS-Frontier
2023-12-25 12:43:35 (4580): Guest Log: [INFO] Testing connection to Frontier
2023-12-25 12:43:36 (4580): Guest Log: [INFO] Could not find a local HTTP proxy
2023-12-25 12:43:36 (4580): Guest Log: [INFO] CVMFS and Frontier will have to use DIRECT connections
2023-12-25 12:43:36 (4580): Guest Log: [INFO] This makes the application less efficient
2023-12-25 12:43:36 (4580): Guest Log: [INFO] It also puts higher load on the project servers
2023-12-25 12:43:36 (4580): Guest Log: [INFO] Setting up a local HTTP proxy is highly recommended
2023-12-25 12:43:36 (4580): Guest Log: [INFO] Advice can be found in the project forum
2023-12-25 12:43:37 (4580): Guest Log: [INFO] Reloading and probing the CVMFS configuration
2023-12-25 12:43:42 (4580): Guest Log: [INFO] Probing /cvmfs/grid.cern.ch... OK
2023-12-25 12:43:45 (4580): Guest Log: [INFO] Probing /cvmfs/singularity.opensciencegrid.org... OK
2023-12-25 12:43:45 (4580): Guest Log: [INFO] Probing /cvmfs/cms-ib.cern.ch... OK
2023-12-25 12:43:45 (4580): Guest Log: [INFO] Probing /cvmfs/oasis.opensciencegrid.org... OK
2023-12-25 12:43:47 (4580): Guest Log: [INFO] Probing /cvmfs/cms.cern.ch... OK
2023-12-25 12:43:47 (4580): Guest Log: [INFO] Excerpt from "cvmfs_config stat": VERSION HOST PROXY
2023-12-25 12:43:48 (4580): Guest Log: [INFO] 2.7.2.0 http://s1ral-cvmfs.openhtc.io DIRECT
2023-12-25 12:43:48 (4580): Guest Log: [INFO] Environment HTTP proxy: not set
2023-12-25 12:43:48 (4580): Guest Log: [INFO] Reading volunteer information
2023-12-25 12:43:58 (4580): Guest Log: [INFO] Requesting an X509 credential from LHC@home
2023-12-25 12:43:59 (4580): Guest Log: [INFO] Requesting an X509 credential from vLHC@home-dev
2023-12-25 12:44:01 (4580): Guest Log: [INFO] CMS application starting. Check log files.
2023-12-25 12:44:01 (4580): Guest Log: [INFO] Requesting an idtoken from LHC@home
2023-12-25 12:44:01 (4580): Guest Log: [INFO] Requesting an idtoken from vLHC@home-dev
2023-12-25 14:05:44 (4580): Stopping VM.
2023-12-25 14:05:51 (4580): Successfully stopped VM.
2023-12-25 14:25:00 (2652): Detected: vboxwrapper 26206
2023-12-25 14:25:00 (2652): Detected: BOINC client v7.24.1
2023-12-25 14:25:01 (2652): Detected: VirtualBox VboxManage Interface (Version: 7.0.6)
2023-12-25 14:25:01 (2652): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2023-12-25 14:25:02 (2652): Guest Log: BIOS: VirtualBox 7.0.6
2023-12-25 14:25:02 (2652): Guest Log: CPUID EDX: 0x178bfbff
2023-12-25 14:25:02 (2652): Guest Log: BIOS: No PCI IDE controller, not probing IDE
2023-12-25 14:25:02 (2652): Guest Log: BIOS: AHCI 0-P#0: PCHS=16383/16/63 LCHS=1024/255/63 0x0000000002800000 sectors
2023-12-25 14:25:02 (2652): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032
2023-12-25 14:25:02 (2652): Guest Log: BIOS: Booting from Hard Disk...
2023-12-25 14:25:02 (2652): Guest Log: BIOS: KBD: unsupported int 16h function 03
2023-12-25 14:25:02 (2652): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 
2023-12-25 14:25:02 (2652): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds
2023-12-25 14:25:02 (2652): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000)
2023-12-25 14:25:02 (2652): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log
2023-12-25 14:25:02 (2652): Guest Log: 00:00:00.000148 main     Log opened 2023-12-25T17:43:23.283537000Z
2023-12-25 14:25:02 (2652): Guest Log: 00:00:00.000399 main     OS Product: Linux
2023-12-25 14:25:02 (2652): Guest Log: 00:00:00.000441 main     OS Release: 4.14.232-19.cernvm.x86_64
2023-12-25 14:25:02 (2652): Guest Log: 00:00:00.000474 main     OS Version: #1 SMP Fri Apr 30 17:12:25 CEST 2021
2023-12-25 14:25:02 (2652): Guest Log: 00:00:00.000507 main     Executable: /usr/sbin/VBoxService
2023-12-25 14:25:02 (2652): Guest Log: 00:00:00.000507 main     Process ID: 2168
2023-12-25 14:25:02 (2652): Guest Log: 00:00:00.000508 main     Package type: LINUX_64BITS_GENERIC
2023-12-25 14:25:02 (2652): Guest Log: 00:00:00.003198 main     5.2.6 r120293 started. Verbose level = 0
2023-12-25 14:25:02 (2652): Guest Log: [INFO] Mounting the shared directory
2023-12-25 14:25:02 (2652): Guest Log: [INFO] Shared directory mounted, enabling vboxmonitor
2023-12-25 14:25:02 (2652): Guest Log: [INFO] Sourcing essential functions from /cvmfs/grid.cern.ch
2023-12-25 14:25:02 (2652): Guest Log: [INFO] Testing connection to cern.ch
2023-12-25 14:25:02 (2652): Guest Log: [INFO] Testing connection to VCCS
2023-12-25 14:25:02 (2652): Guest Log: [INFO] Testing connection to HTCondor
2023-12-25 14:25:02 (2652): Guest Log: [INFO] Testing connection to WMAgent
2023-12-25 14:25:02 (2652): Guest Log: [INFO] Testing connection to EOSCMS
2023-12-25 14:25:02 (2652): Guest Log: [INFO] Testing connection to CMS-Factory
2023-12-25 14:25:02 (2652): Guest Log: [INFO] Testing connection to CMS-Frontier
2023-12-25 14:25:02 (2652): Guest Log: [INFO] Testing connection to Frontier
2023-12-25 14:25:02 (2652): Guest Log: [INFO] Could not find a local HTTP proxy
2023-12-25 14:25:02 (2652): Guest Log: [INFO] CVMFS and Frontier will have to use DIRECT connections
2023-12-25 14:25:02 (2652): Guest Log: [INFO] This makes the application less efficient
2023-12-25 14:25:02 (2652): Guest Log: [INFO] It also puts higher load on the project servers
2023-12-25 14:25:02 (2652): Guest Log: [INFO] Setting up a local HTTP proxy is highly recommended
2023-12-25 14:25:02 (2652): Guest Log: [INFO] Advice can be found in the project forum
2023-12-25 14:25:02 (2652): Guest Log: [INFO] Reloading and probing the CVMFS configuration
2023-12-25 14:25:02 (2652): Guest Log: [INFO] Probing /cvmfs/grid.cern.ch... OK
2023-12-25 14:25:02 (2652): Guest Log: [INFO] Probing /cvmfs/singularity.opensciencegrid.org... OK
2023-12-25 14:25:02 (2652): Guest Log: [INFO] Probing /cvmfs/cms-ib.cern.ch... OK
2023-12-25 14:25:02 (2652): Guest Log: [INFO] Probing /cvmfs/oasis.opensciencegrid.org... OK
2023-12-25 14:25:02 (2652): Guest Log: [INFO] Probing /cvmfs/cms.cern.ch... OK
2023-12-25 14:25:02 (2652): Guest Log: [INFO] Excerpt from "cvmfs_config stat": VERSION HOST PROXY
2023-12-25 14:25:02 (2652): Guest Log: [INFO] 2.7.2.0 http://s1ral-cvmfs.openhtc.io DIRECT
2023-12-25 14:25:02 (2652): Guest Log: [INFO] Environment HTTP proxy: not set
2023-12-25 14:25:02 (2652): Guest Log: [INFO] Reading volunteer information
2023-12-25 14:25:02 (2652): Guest Log: [INFO] Requesting an X509 credential from LHC@home
2023-12-25 14:25:02 (2652): Guest Log: [INFO] Requesting an X509 credential from vLHC@home-dev
2023-12-25 14:25:02 (2652): Guest Log: [INFO] CMS application starting. Check log files.
2023-12-25 14:25:02 (2652): Guest Log: [INFO] Requesting an idtoken from LHC@home
2023-12-25 14:25:02 (2652): Guest Log: [INFO] Requesting an idtoken from vLHC@home-dev
2023-12-25 14:25:02 (2652): Starting VM using VBoxManage interface. (boinc_1cb628fcf1ed3308, slot#3)
2023-12-25 14:25:15 (2652): Successfully started VM. (PID = '11796')
2023-12-25 14:25:15 (2652): Reporting VM Process ID to BOINC.
2023-12-25 14:25:15 (2652): VM state change detected. (old = 'poweredoff', new = 'running')
2023-12-25 14:25:15 (2652): Detected: Web Application Enabled (http://localhost:56654)
2023-12-25 14:25:15 (2652): Preference change detected
2023-12-25 14:25:15 (2652): Setting CPU throttle for VM. (100%)
2023-12-25 14:25:16 (2652): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2023-12-25 14:44:40 (2652): Status Report: Job Duration: '64800.000000'
2023-12-25 14:44:40 (2652): Status Report: Elapsed Time: '6000.020963'
2023-12-25 14:44:40 (2652): Status Report: CPU Time: '5742.406250'
2023-12-25 15:25:53 (2652): Stopping VM.
2023-12-25 15:25:59 (2652): Successfully stopped VM.
2023-12-25 15:31:32 (4204): Detected: vboxwrapper 26206
2023-12-25 15:31:32 (4204): Detected: BOINC client v7.24.1
2023-12-25 15:31:33 (4204): Detected: VirtualBox VboxManage Interface (Version: 7.0.6)
2023-12-25 15:31:33 (4204): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2023-12-25 15:31:34 (4204): Starting VM using VBoxManage interface. (boinc_1cb628fcf1ed3308, slot#3)
2023-12-25 15:31:43 (4204): Successfully started VM. (PID = '7072')
2023-12-25 15:31:43 (4204): Reporting VM Process ID to BOINC.
2023-12-25 15:31:43 (4204): VM state change detected. (old = 'poweredoff', new = 'running')
2023-12-25 15:31:43 (4204): Detected: Web Application Enabled (http://localhost:56654)
2023-12-25 15:31:43 (4204): Status Report: Job Duration: '64800.000000'
2023-12-25 15:31:43 (4204): Status Report: Elapsed Time: '8409.020639'
2023-12-25 15:31:43 (4204): Status Report: CPU Time: '8254.140625'
2023-12-25 15:31:43 (4204): Preference change detected
2023-12-25 15:31:43 (4204): Setting CPU throttle for VM. (100%)
2023-12-25 15:31:43 (4204): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2023-12-25 16:42:22 (12064): Detected: vboxwrapper 26206
2023-12-25 16:42:22 (12064): Detected: BOINC client v7.24.1
2023-12-25 16:42:47 (12064): Detected: VirtualBox VboxManage Interface (Version: 7.0.6)
2023-12-25 16:42:55 (12064): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2023-12-25 16:50:40 (9956): Detected: vboxwrapper 26206
2023-12-25 16:50:40 (9956): Detected: BOINC client v7.24.1
2023-12-25 16:50:52 (9956): Detected: VirtualBox VboxManage Interface (Version: 7.0.6)
2023-12-25 16:51:06 (9956): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2023-12-25 16:51:31 (9956): Starting VM using VBoxManage interface. (boinc_1cb628fcf1ed3308, slot#3)
2023-12-25 16:51:39 (9956): Successfully started VM. (PID = '13156')
2023-12-25 16:51:39 (9956): Reporting VM Process ID to BOINC.
2023-12-25 16:51:39 (9956): Guest Log: BIOS: VirtualBox 7.0.6
2023-12-25 16:51:39 (9956): Guest Log: CPUID EDX: 0x178bfbff
2023-12-25 16:51:39 (9956): Guest Log: BIOS: No PCI IDE controller, not probing IDE
2023-12-25 16:51:39 (9956): Guest Log: BIOS: AHCI 0-P#0: PCHS=16383/16/63 LCHS=1024/255/63 0x0000000002800000 sectors
2023-12-25 16:51:39 (9956): VM state change detected. (old = 'poweredoff', new = 'running')
2023-12-25 16:51:39 (9956): Detected: Web Application Enabled (http://localhost:56654)
2023-12-25 16:51:39 (9956): Status Report: Job Duration: '64800.000000'
2023-12-25 16:51:39 (9956): Status Report: Elapsed Time: '9613.281335'
2023-12-25 16:51:39 (9956): Status Report: CPU Time: '9494.375000'
2023-12-25 16:51:39 (9956): Preference change detected
2023-12-25 16:51:39 (9956): Setting CPU throttle for VM. (100%)
2023-12-25 16:51:40 (9956): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2023-12-25 16:51:40 (9956): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032
2023-12-25 16:51:40 (9956): Guest Log: BIOS: Booting from Hard Disk...
2023-12-25 16:51:50 (9956): Guest Log: BIOS: KBD: unsupported int 16h function 03
2023-12-25 16:51:50 (9956): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 
2023-12-25 16:52:05 (9956): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds
2023-12-25 16:52:05 (9956): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000)
2023-12-25 16:52:05 (9956): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log
2023-12-25 16:52:05 (9956): Guest Log: 00:00:00.000091 main     Log opened 2023-12-25T21:52:03.697906000Z
2023-12-25 16:52:05 (9956): Guest Log: 00:00:00.000259 main     OS Product: Linux
2023-12-25 16:52:05 (9956): Guest Log: 00:00:00.000302 main     OS Release: 4.14.232-19.cernvm.x86_64
2023-12-25 16:52:06 (9956): Guest Log: 00:00:00.000336 main     OS Version: #1 SMP Fri Apr 30 17:12:25 CEST 2021
2023-12-25 16:52:06 (9956): Guest Log: 00:00:00.000369 main     Executable: /usr/sbin/VBoxService
2023-12-25 16:52:06 (9956): Guest Log: 00:00:00.000369 main     Process ID: 2164
2023-12-25 16:52:06 (9956): Guest Log: 00:00:00.000370 main     Package type: LINUX_64BITS_GENERIC
2023-12-25 16:52:06 (9956): Guest Log: 00:00:00.003380 main     5.2.6 r120293 started. Verbose level = 0
2023-12-25 16:52:15 (9956): Guest Log: [INFO] Mounting the shared directory
2023-12-25 16:52:15 (9956): Guest Log: [INFO] Shared directory mounted, enabling vboxmonitor
2023-12-25 16:52:15 (9956): Guest Log: [INFO] Sourcing essential functions from /cvmfs/grid.cern.ch
2023-12-25 16:52:15 (9956): Guest Log: [INFO] Testing connection to cern.ch
2023-12-25 16:52:16 (9956): Guest Log: [INFO] Testing connection to VCCS
2023-12-25 16:52:16 (9956): Guest Log: [INFO] Testing connection to HTCondor
2023-12-25 16:52:17 (9956): Guest Log: [INFO] Testing connection to WMAgent
2023-12-25 16:52:17 (9956): Guest Log: [INFO] Testing connection to EOSCMS
2023-12-25 16:52:17 (9956): Guest Log: [INFO] Testing connection to CMS-Factory
2023-12-25 16:52:18 (9956): Guest Log: [INFO] Testing connection to CMS-Frontier
2023-12-25 16:52:18 (9956): Guest Log: [INFO] Testing connection to Frontier
2023-12-25 16:52:18 (9956): Guest Log: [INFO] Could not find a local HTTP proxy
2023-12-25 16:52:18 (9956): Guest Log: [INFO] CVMFS and Frontier will have to use DIRECT connections
2023-12-25 16:52:18 (9956): Guest Log: [INFO] This makes the application less efficient
2023-12-25 16:52:18 (9956): Guest Log: [INFO] It also puts higher load on the project servers
2023-12-25 16:52:18 (9956): Guest Log: [INFO] Setting up a local HTTP proxy is highly recommended
2023-12-25 16:52:19 (9956): Guest Log: [INFO] Advice can be found in the project forum
2023-12-25 16:52:19 (9956): Guest Log: [INFO] Reloading and probing the CVMFS configuration
2023-12-25 16:52:26 (9956): Guest Log: [INFO] Probing /cvmfs/grid.cern.ch... OK
2023-12-25 16:52:28 (9956): Guest Log: [INFO] Probing /cvmfs/oasis.opensciencegrid.org... OK
2023-12-25 16:52:28 (9956): Guest Log: [INFO] Probing /cvmfs/cms.cern.ch... OK
2023-12-25 16:52:28 (9956): Guest Log: [INFO] Probing /cvmfs/singularity.opensciencegrid.org... OK
2023-12-25 16:52:30 (9956): Guest Log: [INFO] Probing /cvmfs/cms-ib.cern.ch... OK
2023-12-25 16:52:30 (9956): Guest Log: [INFO] Excerpt from "cvmfs_config stat": VERSION HOST PROXY
2023-12-25 16:52:30 (9956): Guest Log: [INFO] 2.7.2.0 http://s1fnal-cvmfs.openhtc.io:8080 DIRECT
2023-12-25 16:52:30 (9956): Guest Log: [INFO] Environment HTTP proxy: not set
2023-12-25 16:52:30 (9956): Guest Log: [INFO] Reading volunteer information
2023-12-25 16:52:31 (9956): Guest Log: [INFO] Requesting an X509 credential from LHC@home
2023-12-25 16:52:31 (9956): Guest Log: [INFO] Requesting an X509 credential from vLHC@home-dev
2023-12-25 16:52:32 (9956): Guest Log: [INFO] CMS application starting. Check log files.
2023-12-25 16:52:32 (9956): Guest Log: [INFO] Requesting an idtoken from LHC@home
2023-12-25 16:52:33 (9956): Guest Log: [INFO] Requesting an idtoken from vLHC@home-dev
2023-12-25 17:02:41 (9956): Stopping VM.
2023-12-25 17:02:47 (9956): Successfully stopped VM.
2023-12-25 17:18:50 (5772): Detected: vboxwrapper 26206
2023-12-25 17:18:50 (5772): Detected: BOINC client v7.24.1
2023-12-25 17:25:03 (9728): Detected: vboxwrapper 26206
2023-12-25 17:25:03 (9728): Detected: BOINC client v7.24.1
2023-12-25 17:25:11 (9728): Detected: VirtualBox VboxManage Interface (Version: 7.0.6)
2023-12-25 17:25:17 (9728): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2023-12-25 17:25:30 (9728): Guest Log: BIOS: VirtualBox 7.0.6
2023-12-25 17:25:30 (9728): Guest Log: CPUID EDX: 0x178bfbff
2023-12-25 17:25:30 (9728): Guest Log: BIOS: No PCI IDE controller, not probing IDE
2023-12-25 17:25:30 (9728): Guest Log: BIOS: AHCI 0-P#0: PCHS=16383/16/63 LCHS=1024/255/63 0x0000000002800000 sectors
2023-12-25 17:25:30 (9728): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032
2023-12-25 17:25:30 (9728): Guest Log: BIOS: Booting from Hard Disk...
2023-12-25 17:25:30 (9728): Guest Log: BIOS: KBD: unsupported int 16h function 03
2023-12-25 17:25:30 (9728): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 
2023-12-25 17:25:30 (9728): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds
2023-12-25 17:25:30 (9728): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000)
2023-12-25 17:25:30 (9728): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log
2023-12-25 17:25:30 (9728): Guest Log: 00:00:00.000091 main     Log opened 2023-12-25T21:52:03.697906000Z
2023-12-25 17:25:30 (9728): Guest Log: 00:00:00.000259 main     OS Product: Linux
2023-12-25 17:25:30 (9728): Guest Log: 00:00:00.000302 main     OS Release: 4.14.232-19.cernvm.x86_64
2023-12-25 17:25:30 (9728): Guest Log: 00:00:00.000336 main     OS Version: #1 SMP Fri Apr 30 17:12:25 CEST 2021
2023-12-25 17:25:30 (9728): Guest Log: 00:00:00.000369 main     Executable: /usr/sbin/VBoxService
2023-12-25 17:25:30 (9728): Guest Log: 00:00:00.000369 main     Process ID: 2164
2023-12-25 17:25:30 (9728): Guest Log: 00:00:00.000370 main     Package type: LINUX_64BITS_GENERIC
2023-12-25 17:25:30 (9728): Guest Log: 00:00:00.003380 main     5.2.6 r120293 started. Verbose level = 0
2023-12-25 17:25:30 (9728): Guest Log: [INFO] Mounting the shared directory
2023-12-25 17:25:30 (9728): Guest Log: [INFO] Shared directory mounted, enabling vboxmonitor
2023-12-25 17:25:30 (9728): Guest Log: [INFO] Sourcing essential functions from /cvmfs/grid.cern.ch
2023-12-25 17:25:30 (9728): Guest Log: [INFO] Testing connection to cern.ch
2023-12-25 17:25:30 (9728): Guest Log: [INFO] Testing connection to VCCS
2023-12-25 17:25:30 (9728): Guest Log: [INFO] Testing connection to HTCondor
2023-12-25 17:25:30 (9728): Guest Log: [INFO] Testing connection to WMAgent
2023-12-25 17:25:30 (9728): Guest Log: [INFO] Testing connection to EOSCMS
2023-12-25 17:25:30 (9728): Guest Log: [INFO] Testing connection to CMS-Factory
2023-12-25 17:25:30 (9728): Guest Log: [INFO] Testing connection to CMS-Frontier
2023-12-25 17:25:30 (9728): Guest Log: [INFO] Testing connection to Frontier
2023-12-25 17:25:30 (9728): Guest Log: [INFO] Could not find a local HTTP proxy
2023-12-25 17:25:30 (9728): Guest Log: [INFO] CVMFS and Frontier will have to use DIRECT connections
2023-12-25 17:25:30 (9728): Guest Log: [INFO] This makes the application less efficient
2023-12-25 17:25:30 (9728): Guest Log: [INFO] It also puts higher load on the project servers
2023-12-25 17:25:30 (9728): Guest Log: [INFO] Setting up a local HTTP proxy is highly recommended
2023-12-25 17:25:30 (9728): Guest Log: [INFO] Advice can be found in the project forum
2023-12-25 17:25:30 (9728): Guest Log: [INFO] Reloading and probing the CVMFS configuration
2023-12-25 17:25:30 (9728): Guest Log: [INFO] Probing /cvmfs/grid.cern.ch... OK
2023-12-25 17:25:30 (9728): Guest Log: [INFO] Probing /cvmfs/oasis.opensciencegrid.org... OK
2023-12-25 17:25:30 (9728): Guest Log: [INFO] Probing /cvmfs/cms.cern.ch... OK
2023-12-25 17:25:30 (9728): Guest Log: [INFO] Probing /cvmfs/singularity.opensciencegrid.org... OK
2023-12-25 17:25:30 (9728): Guest Log: [INFO] Probing /cvmfs/cms-ib.cern.ch... OK
2023-12-25 17:25:30 (9728): Guest Log: [INFO] Excerpt from "cvmfs_config stat": VERSION HOST PROXY
2023-12-25 17:25:30 (9728): Guest Log: [INFO] 2.7.2.0 http://s1fnal-cvmfs.openhtc.io:8080 DIRECT
2023-12-25 17:25:30 (9728): Guest Log: [INFO] Environment HTTP proxy: not set
2023-12-25 17:25:30 (9728): Guest Log: [INFO] Reading volunteer information
2023-12-25 17:25:30 (9728): Guest Log: [INFO] Requesting an X509 credential from LHC@home
2023-12-25 17:25:30 (9728): Guest Log: [INFO] Requesting an X509 credential from vLHC@home-dev
2023-12-25 17:25:30 (9728): Guest Log: [INFO] CMS application starting. Check log files.
2023-12-25 17:25:30 (9728): Guest Log: [INFO] Requesting an idtoken from LHC@home
2023-12-25 17:25:30 (9728): Guest Log: [INFO] Requesting an idtoken from vLHC@home-dev
2023-12-25 17:25:30 (9728): Starting VM using VBoxManage interface. (boinc_1cb628fcf1ed3308, slot#3)
2023-12-25 17:25:53 (9728): Successfully started VM. (PID = '10264')
2023-12-25 17:25:53 (9728): Reporting VM Process ID to BOINC.
2023-12-25 17:25:54 (9728): VM state change detected. (old = 'poweredoff', new = 'running')
2023-12-25 17:25:54 (9728): Detected: Web Application Enabled (http://localhost:56654)
2023-12-25 17:25:54 (9728): Status Report: Job Duration: '64800.000000'
2023-12-25 17:25:54 (9728): Status Report: Elapsed Time: '10200.431451'
2023-12-25 17:25:54 (9728): Status Report: CPU Time: '10069.750000'
2023-12-25 17:25:54 (9728): Preference change detected
2023-12-25 17:25:54 (9728): Setting CPU throttle for VM. (100%)
2023-12-25 17:25:54 (9728): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2023-12-25 19:01:42 (9728): Stopping VM.
2023-12-25 19:01:50 (9728): Successfully stopped VM.
2023-12-25 19:02:49 (7304): Detected: vboxwrapper 26206
2023-12-25 19:02:49 (7304): Detected: BOINC client v7.24.1
2023-12-25 19:02:50 (7304): Detected: VirtualBox VboxManage Interface (Version: 7.0.6)
2023-12-25 19:02:50 (7304): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2023-12-25 19:02:50 (7304): Starting VM using VBoxManage interface. (boinc_1cb628fcf1ed3308, slot#3)
2023-12-25 19:03:00 (7304): Successfully started VM. (PID = '9028')
2023-12-25 19:03:00 (7304): Reporting VM Process ID to BOINC.
2023-12-25 19:03:00 (7304): VM state change detected. (old = 'poweredoff', new = 'running')
2023-12-25 19:03:00 (7304): Detected: Web Application Enabled (http://localhost:56654)
2023-12-25 19:03:00 (7304): Status Report: Job Duration: '64800.000000'
2023-12-25 19:03:00 (7304): Status Report: Elapsed Time: '15692.671426'
2023-12-25 19:03:00 (7304): Status Report: CPU Time: '15808.812500'
2023-12-25 19:03:00 (7304): Preference change detected
2023-12-25 19:03:00 (7304): Setting CPU throttle for VM. (100%)
2023-12-25 19:03:00 (7304): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2023-12-25 20:43:29 (7304): Status Report: Job Duration: '64800.000000'
2023-12-25 20:43:29 (7304): Status Report: Elapsed Time: '21692.671426'
2023-12-25 20:43:29 (7304): Status Report: CPU Time: '21850.062500'
2023-12-25 22:24:05 (7304): Status Report: Job Duration: '64800.000000'
2023-12-25 22:24:05 (7304): Status Report: Elapsed Time: '27692.671426'
2023-12-25 22:24:05 (7304): Status Report: CPU Time: '27685.906250'
2023-12-25 23:12:02 (7304): Stopping VM.
2023-12-25 23:12:07 (7304): Successfully stopped VM.
2023-12-25 23:14:25 (9868): Detected: vboxwrapper 26206
2023-12-25 23:14:25 (9868): Detected: BOINC client v7.24.1
2023-12-25 23:14:27 (9868): Detected: VirtualBox VboxManage Interface (Version: 7.0.6)
2023-12-25 23:14:27 (9868): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2023-12-25 23:14:27 (9868): Starting VM using VBoxManage interface. (boinc_1cb628fcf1ed3308, slot#3)
2023-12-25 23:14:37 (9868): Successfully started VM. (PID = '12656')
2023-12-25 23:14:37 (9868): Reporting VM Process ID to BOINC.
2023-12-25 23:14:37 (9868): VM state change detected. (old = 'poweredoff', new = 'running')
2023-12-25 23:14:37 (9868): Detected: Web Application Enabled (http://localhost:56654)
2023-12-25 23:14:37 (9868): Status Report: Job Duration: '64800.000000'
2023-12-25 23:14:37 (9868): Status Report: Elapsed Time: '30553.671426'
2023-12-25 23:14:37 (9868): Status Report: CPU Time: '30456.218750'
2023-12-25 23:14:37 (9868): Preference change detected
2023-12-25 23:14:37 (9868): Setting CPU throttle for VM. (100%)
2023-12-25 23:14:37 (9868): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2023-12-25 23:17:27 (9868): Stopping VM.
2023-12-25 23:17:33 (9868): Successfully stopped VM.
2023-12-26 00:12:29 (9132): Detected: vboxwrapper 26206
2023-12-26 00:12:29 (9132): Detected: BOINC client v7.24.1
2023-12-26 00:12:30 (9132): Detected: VirtualBox VboxManage Interface (Version: 7.0.6)
2023-12-26 00:12:30 (9132): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2023-12-26 00:12:30 (9132): Starting VM using VBoxManage interface. (boinc_1cb628fcf1ed3308, slot#3)
2023-12-26 00:12:42 (9132): Successfully started VM. (PID = '6924')
2023-12-26 00:12:42 (9132): Reporting VM Process ID to BOINC.
2023-12-26 00:12:42 (9132): VM state change detected. (old = 'poweredoff', new = 'running')
2023-12-26 00:12:42 (9132): Detected: Web Application Enabled (http://localhost:56654)
2023-12-26 00:12:42 (9132): Status Report: Job Duration: '64800.000000'
2023-12-26 00:12:42 (9132): Status Report: Elapsed Time: '30720.671426'
2023-12-26 00:12:42 (9132): Status Report: CPU Time: '30626.078125'
2023-12-26 00:12:42 (9132): Preference change detected
2023-12-26 00:12:42 (9132): Setting CPU throttle for VM. (100%)
2023-12-26 00:12:42 (9132): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2023-12-26 00:12:47 (9132): Guest Log: 05:58:34.688357 timesync vgsvcTimeSyncWorker: Radical host time change: 3 317 161 000 000ns (HostNow=1 703 567 521 895 000 000 ns HostLast=1 703 564 204 734 000 000 ns)
2023-12-26 00:12:57 (9132): Guest Log: 05:58:44.701510 timesync vgsvcTimeSyncWorker: Radical guest time change: 3 299 698 101 000ns (GuestNow=1 703 567 531 914 865 000 ns GuestLast=1 703 564 232 216 764 000 ns fSetTimeLastLoop=true )
2023-12-26 01:19:09 (9132): Stopping VM.
2023-12-26 01:19:16 (9132): Successfully stopped VM.
2023-12-26 01:26:43 (7616): Detected: vboxwrapper 26206
2023-12-26 01:26:43 (7616): Detected: BOINC client v7.24.1
2023-12-26 01:26:44 (7616): Detected: VirtualBox VboxManage Interface (Version: 7.0.6)
2023-12-26 01:26:45 (7616): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2023-12-26 01:26:45 (7616): Guest Log: 05:58:34.688357 timesync vgsvcTimeSyncWorker: Radical host time change: 3 317 161 000 000ns (HostNow=1 703 567 521 895 000 000 ns HostLast=1 703 564 204 734 000 000 ns)
2023-12-26 01:26:45 (7616): Guest Log: 05:58:44.701510 timesync vgsvcTimeSyncWorker: Radical guest time change: 3 299 698 101 000ns (GuestNow=1 703 567 531 914 865 000 ns GuestLast=1 703 564 232 216 764 000 ns fSetTimeLastLoop=true )
2023-12-26 01:26:45 (7616): Starting VM using VBoxManage interface. (boinc_1cb628fcf1ed3308, slot#3)
2023-12-26 01:26:55 (7616): Successfully started VM. (PID = '5456')
2023-12-26 01:26:55 (7616): Reporting VM Process ID to BOINC.
2023-12-26 01:26:55 (7616): VM state change detected. (old = 'poweredoff', new = 'running')
2023-12-26 01:26:55 (7616): Detected: Web Application Enabled (http://localhost:56654)
2023-12-26 01:26:55 (7616): Status Report: Job Duration: '64800.000000'
2023-12-26 01:26:55 (7616): Status Report: Elapsed Time: '34669.671426'
2023-12-26 01:26:55 (7616): Status Report: CPU Time: '34658.171875'
2023-12-26 01:26:55 (7616): Preference change detected
2023-12-26 01:26:55 (7616): Setting CPU throttle for VM. (100%)
2023-12-26 01:26:55 (7616): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2023-12-26 01:35:32 (7616): Stopping VM.
2023-12-26 01:35:38 (7616): Successfully stopped VM.
2023-12-26 02:19:52 (9648): Detected: vboxwrapper 26206
2023-12-26 02:19:52 (9648): Detected: BOINC client v7.24.1
2023-12-26 02:19:53 (9648): Detected: VirtualBox VboxManage Interface (Version: 7.0.6)
2023-12-26 02:19:53 (9648): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2023-12-26 02:19:54 (9648): Starting VM using VBoxManage interface. (boinc_1cb628fcf1ed3308, slot#3)
2023-12-26 02:20:03 (9648): Successfully started VM. (PID = '8360')
2023-12-26 02:20:03 (9648): Reporting VM Process ID to BOINC.
2023-12-26 02:20:03 (9648): VM state change detected. (old = 'poweredoff', new = 'running')
2023-12-26 02:20:03 (9648): Detected: Web Application Enabled (http://localhost:56654)
2023-12-26 02:20:03 (9648): Status Report: Job Duration: '64800.000000'
2023-12-26 02:20:03 (9648): Status Report: Elapsed Time: '35165.671426'
2023-12-26 02:20:03 (9648): Status Report: CPU Time: '35175.125000'
2023-12-26 02:20:03 (9648): Preference change detected
2023-12-26 02:20:03 (9648): Setting CPU throttle for VM. (100%)
2023-12-26 02:20:04 (9648): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2023-12-26 02:20:05 (9648): Guest Log: 07:13:39.719085 timesync vgsvcTimeSyncWorker: Radical host time change: 2 672 183 000 000ns (HostNow=1 703 575 180 714 000 000 ns HostLast=1 703 572 508 531 000 000 ns)
2023-12-26 02:20:15 (9648): Guest Log: 07:13:49.722869 timesync vgsvcTimeSyncWorker: Radical guest time change: 2 701 892 497 000ns (GuestNow=1 703 575 190 734 060 000 ns GuestLast=1 703 572 488 841 563 000 ns fSetTimeLastLoop=true )
2023-12-26 03:23:43 (9648): Stopping VM.
2023-12-26 03:23:48 (9648): Successfully stopped VM.
2023-12-26 03:34:48 (12796): Detected: vboxwrapper 26206
2023-12-26 03:34:48 (12796): Detected: BOINC client v7.24.1
2023-12-26 03:34:49 (12796): Detected: VirtualBox VboxManage Interface (Version: 7.0.6)
2023-12-26 03:34:50 (12796): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2023-12-26 03:34:50 (12796): Guest Log: 07:13:39.719085 timesync vgsvcTimeSyncWorker: Radical host time change: 2 672 183 000 000ns (HostNow=1 703 575 180 714 000 000 ns HostLast=1 703 572 508 531 000 000 ns)
2023-12-26 03:34:50 (12796): Guest Log: 07:13:49.722869 timesync vgsvcTimeSyncWorker: Radical guest time change: 2 701 892 497 000ns (GuestNow=1 703 575 190 734 060 000 ns GuestLast=1 703 572 488 841 563 000 ns fSetTimeLastLoop=true )
2023-12-26 03:34:50 (12796): Starting VM using VBoxManage interface. (boinc_1cb628fcf1ed3308, slot#3)
2023-12-26 03:34:58 (12796): Successfully started VM. (PID = '8516')
2023-12-26 03:34:58 (12796): Reporting VM Process ID to BOINC.
2023-12-26 03:34:58 (12796): VM state change detected. (old = 'poweredoff', new = 'running')
2023-12-26 03:34:58 (12796): Detected: Web Application Enabled (http://localhost:56654)
2023-12-26 03:34:58 (12796): Status Report: Job Duration: '64800.000000'
2023-12-26 03:34:58 (12796): Status Report: Elapsed Time: '38956.671426'
2023-12-26 03:34:58 (12796): Status Report: CPU Time: '38722.500000'
2023-12-26 03:34:58 (12796): Preference change detected
2023-12-26 03:34:58 (12796): Setting CPU throttle for VM. (100%)
2023-12-26 03:34:59 (12796): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2023-12-26 03:35:25 (12796): Guest Log: [INFO] glidein exited with return value 0.
2023-12-26 03:35:25 (12796): Guest Log: [INFO] Shutting Down.
2023-12-26 03:35:25 (12796): VM Completion File Detected.
2023-12-26 03:35:25 (12796): VM Completion Message: glidein exited with return value 0.
.
2023-12-26 03:35:25 (12796): Powering off VM.
2023-12-26 03:35:25 (12796): Successfully stopped VM.
2023-12-26 03:35:25 (12796): Deregistering VM. (boinc_1cb628fcf1ed3308, slot#3)
2023-12-26 03:35:25 (12796): Removing network bandwidth throttle group from VM.
2023-12-26 03:35:25 (12796): Removing VM from VirtualBox.
03:35:31 (12796): called boinc_finish(0)

</stderr_txt>
]]>
Development for LHC@home

Stderr output
