Task 3179130

Name	CMS_501186_1674035441.551963_0
Workunit	2273113
Created	18 Jan 2023, 9:50:43 UTC
Sent	20 Jan 2023, 7:52:57 UTC
Report deadline	27 Jan 2023, 7:52:57 UTC
Received	22 Jan 2023, 6:12:24 UTC
Server state	Over
Outcome	Success
Client state	Done
Exit status	0 (0x00000000)
Computer ID	4318
Run time	13 hours 1 min 57 sec
CPU time	15 hours 21 min 48 sec
Validate state	Valid
Credit	1,279.14
Device peak FLOPS	33.00 GFLOPS
Application version	CMS Simulation v60.70 (vbox64_mt_mcore_cms) x86_64-pc-linux-gnu
Peak working set size	4.98 GB
Peak swap size	7.78 GB
Peak disk usage	1.38 GB
Stderr output

<core_client_version>7.16.11</core_client_version>
<![CDATA[
<stderr_txt>
2023-01-20 09:10:43 (3696150): Detected: vboxwrapper 26206
2023-01-20 09:10:43 (3696150): Detected: BOINC client v7.16.11
2023-01-20 09:10:43 (3696150): Detected: VirtualBox VboxManage Interface (Version: 6.1.26)
2023-01-20 09:10:43 (3696150): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2023-01-20 09:10:43 (3696150): Successfully copied 'init_data.xml' to the shared directory.
2023-01-20 09:10:43 (3696150): Create VM. (boinc_574701246ee1dd0a, slot#9)
2023-01-20 09:10:43 (3696150): Setting Memory Size for VM. (15336MB)
2023-01-20 09:10:43 (3696150): Setting CPU Count for VM. (16)
2023-01-20 09:10:43 (3696150): Setting Chipset Options for VM.
2023-01-20 09:10:43 (3696150): Setting Graphics Controller Options for VM.
2023-01-20 09:10:43 (3696150): Setting Boot Options for VM.
2023-01-20 09:10:44 (3696150): Setting Network Configuration for NAT.
2023-01-20 09:10:44 (3696150): Enabling VM Network Access.
2023-01-20 09:10:44 (3696150): Disabling USB Support for VM.
2023-01-20 09:10:44 (3696150): Disabling COM Port Support for VM.
2023-01-20 09:10:44 (3696150): Disabling LPT Port Support for VM.
2023-01-20 09:10:44 (3696150): Disabling Audio Support for VM.
2023-01-20 09:10:44 (3696150): Disabling Clipboard Support for VM.
2023-01-20 09:10:44 (3696150): Disabling Drag and Drop Support for VM.
2023-01-20 09:10:44 (3696150): Adding storage controller(s) to VM.
2023-01-20 09:10:44 (3696150): Adding virtual disk drive to VM. (CMS_2022_09_07.vdi)
2023-01-20 09:10:45 (3696150): Adding network bandwidth throttle group to VM. (Defaulting to 1024GB)
2023-01-20 09:10:45 (3696150): forwarding host port 52483 to guest port 80
2023-01-20 09:10:45 (3696150): Enabling remote desktop for VM.
2023-01-20 09:10:45 (3696150): Required extension pack not installed, remote desktop not enabled.
2023-01-20 09:10:45 (3696150): Enabling shared directory for VM.
2023-01-20 09:10:45 (3696150): Starting VM using VBoxManage interface. (boinc_574701246ee1dd0a, slot#9)
2023-01-20 09:10:47 (3696150): Successfully started VM. (PID = '3696556')
2023-01-20 09:10:47 (3696150): Reporting VM Process ID to BOINC.
2023-01-20 09:10:47 (3696150): Guest Log: BIOS: VirtualBox 6.1.25
2023-01-20 09:10:47 (3696150): Guest Log: CPUID EDX: 0x178bfbff
2023-01-20 09:10:47 (3696150): Guest Log: BIOS: No PCI IDE controller, not probing IDE
2023-01-20 09:10:47 (3696150): Guest Log: BIOS: AHCI 0-P#0: PCHS=16383/16/63 LCHS=1024/255/63 0x0000000002800000 sectors
2023-01-20 09:10:47 (3696150): VM state change detected. (old = 'poweredoff', new = 'running')
2023-01-20 09:10:47 (3696150): Detected: Web Application Enabled (http://localhost:52483)
2023-01-20 09:10:47 (3696150): Preference change detected
2023-01-20 09:10:47 (3696150): Setting CPU throttle for VM. (100%)
2023-01-20 09:10:47 (3696150): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2023-01-20 09:10:49 (3696150): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032
2023-01-20 09:10:49 (3696150): Guest Log: BIOS: Booting from Hard Disk...
2023-01-20 09:11:05 (3696150): Guest Log: BIOS: KBD: unsupported int 16h function 03
2023-01-20 09:11:05 (3696150): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 
2023-01-20 09:11:55 (3696150): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds
2023-01-20 09:11:55 (3696150): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000)
2023-01-20 09:11:57 (3696150): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log
2023-01-20 09:11:57 (3696150): Guest Log: 00:00:00.001390 main     Log opened 2023-01-20T08:11:57.757105000Z
2023-01-20 09:11:57 (3696150): Guest Log: 00:00:00.001628 main     OS Product: Linux
2023-01-20 09:11:57 (3696150): Guest Log: 00:00:00.001671 main     OS Release: 4.14.232-19.cernvm.x86_64
2023-01-20 09:11:57 (3696150): Guest Log: 00:00:00.001706 main     OS Version: #1 SMP Fri Apr 30 17:12:25 CEST 2021
2023-01-20 09:11:57 (3696150): Guest Log: 00:00:00.001738 main     Executable: /usr/sbin/VBoxService
2023-01-20 09:11:57 (3696150): Guest Log: 00:00:00.001739 main     Process ID: 2702
2023-01-20 09:11:57 (3696150): Guest Log: 00:00:00.001740 main     Package type: LINUX_64BITS_GENERIC
2023-01-20 09:11:57 (3696150): Guest Log: 00:00:00.006702 main     5.2.6 r120293 started. Verbose level = 0
2023-01-20 09:12:30 (3696150): Guest Log: [INFO] Mounting the shared directory
2023-01-20 09:12:30 (3696150): Guest Log: [INFO] Shared directory mounted, enabling vboxmonitor
2023-01-20 09:12:31 (3696150): Guest Log: [INFO] Sourcing essential functions from /cvmfs/grid.cern.ch
2023-01-20 09:12:31 (3696150): Guest Log: [INFO] Testing connection to cern.ch
2023-01-20 09:12:32 (3696150): Guest Log: [INFO] Testing connection to VCCS
2023-01-20 09:12:32 (3696150): Guest Log: [INFO] Testing connection to HTCondor
2023-01-20 09:12:33 (3696150): Guest Log: [INFO] Testing connection to WMAgent
2023-01-20 09:12:33 (3696150): Guest Log: [INFO] Testing connection to EOSCMS
2023-01-20 09:12:34 (3696150): Guest Log: [INFO] Testing connection to CMS-Factory
2023-01-20 09:12:34 (3696150): Guest Log: [INFO] Testing connection to CMS-Frontier
2023-01-20 09:12:35 (3696150): Guest Log: [INFO] Testing connection to Frontier
2023-01-20 09:12:36 (3696150): Guest Log: [INFO] Could not find a local HTTP proxy
2023-01-20 09:12:36 (3696150): Guest Log: [INFO] CVMFS and Frontier will have to use DIRECT connections
2023-01-20 09:12:36 (3696150): Guest Log: [INFO] This makes the application less efficient
2023-01-20 09:12:36 (3696150): Guest Log: [INFO] It also puts higher load on the project servers
2023-01-20 09:12:37 (3696150): Guest Log: [INFO] Setting up a local HTTP proxy is highly recommended
2023-01-20 09:12:37 (3696150): Guest Log: [INFO] Advice can be found in the project forum
2023-01-20 09:12:37 (3696150): Guest Log: [INFO] Reloading and probing the CVMFS configuration
2023-01-20 09:13:25 (3696150): Guest Log: [INFO] Probing /cvmfs/grid.cern.ch... OK
2023-01-20 09:13:30 (3696150): Guest Log: [INFO] Probing /cvmfs/oasis.opensciencegrid.org... OK
2023-01-20 09:13:31 (3696150): Guest Log: [INFO] Probing /cvmfs/cms-ib.cern.ch... OK
2023-01-20 09:13:31 (3696150): Guest Log: [INFO] Probing /cvmfs/cms.cern.ch... OK
2023-01-20 09:13:31 (3696150): Guest Log: [INFO] Probing /cvmfs/singularity.opensciencegrid.org... OK
2023-01-20 09:13:35 (3696150): Guest Log: [INFO] Excerpt from "cvmfs_config stat": VERSION HOST PROXY
2023-01-20 09:13:35 (3696150): Guest Log: [INFO] 2.7.2.0 http://s1asgc-cvmfs.openhtc.io:8080 DIRECT
2023-01-20 09:13:35 (3696150): Guest Log: [INFO] Environment HTTP proxy: not set
2023-01-20 09:13:36 (3696150): Guest Log: [INFO] Reading volunteer information
2023-01-20 09:14:18 (3696150): Guest Log: [INFO] Requesting an X509 credential from LHC@home
2023-01-20 09:14:19 (3696150): Guest Log: [INFO] Requesting an X509 credential from vLHC@home-dev
2023-01-20 09:14:21 (3696150): Guest Log: [INFO] CMS application starting. Check log files.
2023-01-20 09:14:22 (3696150): Guest Log: [INFO] Requesting an idtoken from LHC@home
2023-01-20 09:14:22 (3696150): Guest Log: [INFO] Requesting an idtoken from vLHC@home-dev
2023-01-20 10:11:12 (3696150): Stopping VM.
2023-01-20 10:12:05 (3696150): Successfully stopped VM.
2023-01-20 10:28:53 (3729528): Detected: vboxwrapper 26206
2023-01-20 10:28:53 (3729528): Detected: BOINC client v7.16.11
2023-01-20 10:28:53 (3729528): Detected: VirtualBox VboxManage Interface (Version: 6.1.26)
2023-01-20 10:28:53 (3729528): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2023-01-20 10:28:54 (3729528): Guest Log: BIOS: VirtualBox 6.1.25
2023-01-20 10:28:54 (3729528): Guest Log: CPUID EDX: 0x178bfbff
2023-01-20 10:28:54 (3729528): Guest Log: BIOS: No PCI IDE controller, not probing IDE
2023-01-20 10:28:54 (3729528): Guest Log: BIOS: AHCI 0-P#0: PCHS=16383/16/63 LCHS=1024/255/63 0x0000000002800000 sectors
2023-01-20 10:28:54 (3729528): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032
2023-01-20 10:28:54 (3729528): Guest Log: BIOS: Booting from Hard Disk...
2023-01-20 10:28:54 (3729528): Guest Log: BIOS: KBD: unsupported int 16h function 03
2023-01-20 10:28:54 (3729528): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 
2023-01-20 10:28:54 (3729528): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds
2023-01-20 10:28:54 (3729528): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000)
2023-01-20 10:28:54 (3729528): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log
2023-01-20 10:28:54 (3729528): Guest Log: 00:00:00.001390 main     Log opened 2023-01-20T08:11:57.757105000Z
2023-01-20 10:28:54 (3729528): Guest Log: 00:00:00.001628 main     OS Product: Linux
2023-01-20 10:28:54 (3729528): Guest Log: 00:00:00.001671 main     OS Release: 4.14.232-19.cernvm.x86_64
2023-01-20 10:28:54 (3729528): Guest Log: 00:00:00.001706 main     OS Version: #1 SMP Fri Apr 30 17:12:25 CEST 2021
2023-01-20 10:28:54 (3729528): Guest Log: 00:00:00.001738 main     Executable: /usr/sbin/VBoxService
2023-01-20 10:28:54 (3729528): Guest Log: 00:00:00.001739 main     Process ID: 2702
2023-01-20 10:28:54 (3729528): Guest Log: 00:00:00.001740 main     Package type: LINUX_64BITS_GENERIC
2023-01-20 10:28:54 (3729528): Guest Log: 00:00:00.006702 main     5.2.6 r120293 started. Verbose level = 0
2023-01-20 10:28:54 (3729528): Guest Log: [INFO] Mounting the shared directory
2023-01-20 10:28:54 (3729528): Guest Log: [INFO] Shared directory mounted, enabling vboxmonitor
2023-01-20 10:28:54 (3729528): Guest Log: [INFO] Sourcing essential functions from /cvmfs/grid.cern.ch
2023-01-20 10:28:54 (3729528): Guest Log: [INFO] Testing connection to cern.ch
2023-01-20 10:28:54 (3729528): Guest Log: [INFO] Testing connection to VCCS
2023-01-20 10:28:54 (3729528): Guest Log: [INFO] Testing connection to HTCondor
2023-01-20 10:28:54 (3729528): Guest Log: [INFO] Testing connection to WMAgent
2023-01-20 10:28:54 (3729528): Guest Log: [INFO] Testing connection to EOSCMS
2023-01-20 10:28:54 (3729528): Guest Log: [INFO] Testing connection to CMS-Factory
2023-01-20 10:28:54 (3729528): Guest Log: [INFO] Testing connection to CMS-Frontier
2023-01-20 10:28:54 (3729528): Guest Log: [INFO] Testing connection to Frontier
2023-01-20 10:28:54 (3729528): Guest Log: [INFO] Could not find a local HTTP proxy
2023-01-20 10:28:54 (3729528): Guest Log: [INFO] CVMFS and Frontier will have to use DIRECT connections
2023-01-20 10:28:54 (3729528): Guest Log: [INFO] This makes the application less efficient
2023-01-20 10:28:54 (3729528): Guest Log: [INFO] It also puts higher load on the project servers
2023-01-20 10:28:54 (3729528): Guest Log: [INFO] Setting up a local HTTP proxy is highly recommended
2023-01-20 10:28:54 (3729528): Guest Log: [INFO] Advice can be found in the project forum
2023-01-20 10:28:54 (3729528): Guest Log: [INFO] Reloading and probing the CVMFS configuration
2023-01-20 10:28:54 (3729528): Guest Log: [INFO] Probing /cvmfs/grid.cern.ch... OK
2023-01-20 10:28:54 (3729528): Guest Log: [INFO] Probing /cvmfs/oasis.opensciencegrid.org... OK
2023-01-20 10:28:54 (3729528): Guest Log: [INFO] Probing /cvmfs/cms-ib.cern.ch... OK
2023-01-20 10:28:54 (3729528): Guest Log: [INFO] Probing /cvmfs/cms.cern.ch... OK
2023-01-20 10:28:54 (3729528): Guest Log: [INFO] Probing /cvmfs/singularity.opensciencegrid.org... OK
2023-01-20 10:28:54 (3729528): Guest Log: [INFO] Excerpt from "cvmfs_config stat": VERSION HOST PROXY
2023-01-20 10:28:54 (3729528): Guest Log: [INFO] 2.7.2.0 http://s1asgc-cvmfs.openhtc.io:8080 DIRECT
2023-01-20 10:28:54 (3729528): Guest Log: [INFO] Environment HTTP proxy: not set
2023-01-20 10:28:54 (3729528): Guest Log: [INFO] Reading volunteer information
2023-01-20 10:28:54 (3729528): Guest Log: [INFO] Requesting an X509 credential from LHC@home
2023-01-20 10:28:54 (3729528): Guest Log: [INFO] Requesting an X509 credential from vLHC@home-dev
2023-01-20 10:28:54 (3729528): Guest Log: [INFO] CMS application starting. Check log files.
2023-01-20 10:28:54 (3729528): Guest Log: [INFO] Requesting an idtoken from LHC@home
2023-01-20 10:28:54 (3729528): Guest Log: [INFO] Requesting an idtoken from vLHC@home-dev
2023-01-20 10:28:54 (3729528): Starting VM using VBoxManage interface. (boinc_574701246ee1dd0a, slot#9)
2023-01-20 10:29:10 (3729528): Successfully started VM. (PID = '3729605')
2023-01-20 10:29:10 (3729528): Reporting VM Process ID to BOINC.
2023-01-20 10:29:10 (3729528): VM state change detected. (old = 'poweredoff', new = 'running')
2023-01-20 10:29:10 (3729528): Detected: Web Application Enabled (http://localhost:52483)
2023-01-20 10:29:10 (3729528): Preference change detected
2023-01-20 10:29:10 (3729528): Setting CPU throttle for VM. (100%)
2023-01-20 10:29:10 (3729528): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2023-01-20 11:07:46 (3729528): Status Report: Job Duration: '64800.000000'
2023-01-20 11:07:46 (3729528): Status Report: Elapsed Time: '6000.000000'
2023-01-20 11:07:46 (3729528): Status Report: CPU Time: '7409.260000'
2023-01-20 11:29:38 (3729528): Stopping VM.
2023-01-20 11:30:26 (3729528): Successfully stopped VM.
2023-01-20 12:13:27 (3774268): Detected: vboxwrapper 26206
2023-01-20 12:13:27 (3774268): Detected: BOINC client v7.16.11
2023-01-20 12:13:27 (3774268): Detected: VirtualBox VboxManage Interface (Version: 6.1.26)
2023-01-20 12:13:27 (3774268): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2023-01-20 12:13:27 (3774268): Starting VM using VBoxManage interface. (boinc_574701246ee1dd0a, slot#9)
2023-01-20 12:13:41 (3774268): Successfully started VM. (PID = '3774344')
2023-01-20 12:13:41 (3774268): Reporting VM Process ID to BOINC.
2023-01-20 12:13:41 (3774268): VM state change detected. (old = 'poweredoff', new = 'running')
2023-01-20 12:13:41 (3774268): Detected: Web Application Enabled (http://localhost:52483)
2023-01-20 12:13:41 (3774268): Status Report: Job Duration: '64800.000000'
2023-01-20 12:13:41 (3774268): Status Report: Elapsed Time: '7325.000000'
2023-01-20 12:13:41 (3774268): Status Report: CPU Time: '8931.020000'
2023-01-20 12:13:41 (3774268): Preference change detected
2023-01-20 12:13:41 (3774268): Setting CPU throttle for VM. (100%)
2023-01-20 12:13:41 (3774268): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2023-01-20 12:13:45 (3774268): Guest Log: 01:59:55.973708 timesync vgsvcTimeSyncWorker: Radical host time change: 2 652 134 000 000ns (HostNow=1 674 213 225 332 000 000 ns HostLast=1 674 210 573 198 000 000 ns)
2023-01-20 12:13:55 (3774268): Guest Log: 02:00:05.981197 timesync vgsvcTimeSyncWorker: Radical guest time change: 2 648 242 473 000ns (GuestNow=1 674 213 235 339 911 000 ns GuestLast=1 674 210 587 097 438 000 ns fSetTimeLastLoop=true )
2023-01-20 13:13:59 (3774268): Stopping VM.
2023-01-20 13:14:42 (3774268): Successfully stopped VM.
2023-01-20 17:35:31 (3909380): Detected: vboxwrapper 26206
2023-01-20 17:35:31 (3909380): Detected: BOINC client v7.16.11
2023-01-20 17:35:31 (3909380): Detected: VirtualBox VboxManage Interface (Version: 6.1.26)
2023-01-20 17:35:31 (3909380): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2023-01-20 17:35:31 (3909380): Guest Log: 01:59:55.973708 timesync vgsvcTimeSyncWorker: Radical host time change: 2 652 134 000 000ns (HostNow=1 674 213 225 332 000 000 ns HostLast=1 674 210 573 198 000 000 ns)
2023-01-20 17:35:31 (3909380): Guest Log: 02:00:05.981197 timesync vgsvcTimeSyncWorker: Radical guest time change: 2 648 242 473 000ns (GuestNow=1 674 213 235 339 911 000 ns GuestLast=1 674 210 587 097 438 000 ns fSetTimeLastLoop=true )
2023-01-20 17:35:31 (3909380): Starting VM using VBoxManage interface. (boinc_574701246ee1dd0a, slot#9)
2023-01-20 17:35:45 (3909380): Successfully started VM. (PID = '3909472')
2023-01-20 17:35:45 (3909380): Reporting VM Process ID to BOINC.
2023-01-20 17:35:45 (3909380): VM state change detected. (old = 'poweredoff', new = 'running')
2023-01-20 17:35:45 (3909380): Detected: Web Application Enabled (http://localhost:52483)
2023-01-20 17:35:45 (3909380): Status Report: Job Duration: '64800.000000'
2023-01-20 17:35:45 (3909380): Status Report: Elapsed Time: '10979.000000'
2023-01-20 17:35:45 (3909380): Status Report: CPU Time: '13037.440000'
2023-01-20 17:35:45 (3909380): Preference change detected
2023-01-20 17:35:45 (3909380): Setting CPU throttle for VM. (100%)
2023-01-20 17:35:45 (3909380): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2023-01-20 17:35:51 (3909380): Guest Log: 03:00:20.093344 timesync vgsvcTimeSyncWorker: Radical host time change: 15 715 098 000 000ns (HostNow=1 674 232 550 672 000 000 ns HostLast=1 674 216 835 574 000 000 ns)
2023-01-20 17:36:01 (3909380): Guest Log: 03:00:30.095573 timesync vgsvcTimeSyncWorker: Radical guest time change: 15 711 223 766 000ns (GuestNow=1 674 232 560 675 009 000 ns GuestLast=1 674 216 849 451 243 000 ns fSetTimeLastLoop=true )
2023-01-20 18:35:49 (3909380): Stopping VM.
2023-01-20 21:08:40 (3999554): Detected: vboxwrapper 26206
2023-01-20 21:08:40 (3999554): Detected: BOINC client v7.16.11
2023-01-20 21:08:40 (3999554): Detected: VirtualBox VboxManage Interface (Version: 6.1.26)
2023-01-20 21:08:40 (3999554): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2023-01-20 21:08:40 (3999554): Guest Log: 03:00:20.093344 timesync vgsvcTimeSyncWorker: Radical host time change: 15 715 098 000 000ns (HostNow=1 674 232 550 672 000 000 ns HostLast=1 674 216 835 574 000 000 ns)
2023-01-20 21:08:40 (3999554): Guest Log: 03:00:30.095573 timesync vgsvcTimeSyncWorker: Radical guest time change: 15 711 223 766 000ns (GuestNow=1 674 232 560 675 009 000 ns GuestLast=1 674 216 849 451 243 000 ns fSetTimeLastLoop=true )
2023-01-20 21:08:40 (3999554): Starting VM using VBoxManage interface. (boinc_574701246ee1dd0a, slot#9)
2023-01-20 21:08:42 (3999554): Successfully started VM. (PID = '3999662')
2023-01-20 21:08:42 (3999554): Reporting VM Process ID to BOINC.
2023-01-20 21:08:42 (3999554): Guest Log: BIOS: VirtualBox 6.1.25
2023-01-20 21:08:42 (3999554): Guest Log: CPUID EDX: 0x178bfbff
2023-01-20 21:08:42 (3999554): Guest Log: BIOS: No PCI IDE controller, not probing IDE
2023-01-20 21:08:42 (3999554): Guest Log: BIOS: AHCI 0-P#0: PCHS=16383/16/63 LCHS=1024/255/63 0x0000000002800000 sectors
2023-01-20 21:08:42 (3999554): VM state change detected. (old = 'poweredoff', new = 'running')
2023-01-20 21:08:42 (3999554): Detected: Web Application Enabled (http://localhost:52483)
2023-01-20 21:08:42 (3999554): Status Report: Job Duration: '64800.000000'
2023-01-20 21:08:42 (3999554): Status Report: Elapsed Time: '14619.000000'
2023-01-20 21:08:42 (3999554): Status Report: CPU Time: '16552.920000'
2023-01-20 21:08:42 (3999554): Preference change detected
2023-01-20 21:08:42 (3999554): Setting CPU throttle for VM. (100%)
2023-01-20 21:08:42 (3999554): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2023-01-20 21:08:44 (3999554): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032
2023-01-20 21:08:44 (3999554): Guest Log: BIOS: Booting from Hard Disk...
2023-01-20 21:08:47 (3999554): Guest Log: BIOS: KBD: unsupported int 16h function 03
2023-01-20 21:08:47 (3999554): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 
2023-01-20 21:09:34 (3999554): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds
2023-01-20 21:09:34 (3999554): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000)
2023-01-20 21:09:37 (3999554): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log
2023-01-20 21:09:37 (3999554): Guest Log: 00:00:00.000258 main     Log opened 2023-01-20T20:09:37.033522000Z
2023-01-20 21:09:37 (3999554): Guest Log: 00:00:00.000481 main     OS Product: Linux
2023-01-20 21:09:37 (3999554): Guest Log: 00:00:00.000572 main     OS Release: 4.14.232-19.cernvm.x86_64
2023-01-20 21:09:37 (3999554): Guest Log: 00:00:00.000615 main     OS Version: #1 SMP Fri Apr 30 17:12:25 CEST 2021
2023-01-20 21:09:37 (3999554): Guest Log: 00:00:00.000800 main     Executable: /usr/sbin/VBoxService
2023-01-20 21:09:37 (3999554): Guest Log: 00:00:00.000802 main     Process ID: 2725
2023-01-20 21:09:37 (3999554): Guest Log: 00:00:00.000803 main     Package type: LINUX_64BITS_GENERIC
2023-01-20 21:09:37 (3999554): Guest Log: 00:00:00.002427 main     5.2.6 r120293 started. Verbose level = 0
2023-01-20 21:10:03 (3999554): Guest Log: [INFO] Mounting the shared directory
2023-01-20 21:10:03 (3999554): Guest Log: [INFO] Shared directory mounted, enabling vboxmonitor
2023-01-20 21:10:03 (3999554): Guest Log: [INFO] Sourcing essential functions from /cvmfs/grid.cern.ch
2023-01-20 21:10:04 (3999554): Guest Log: [INFO] Testing connection to cern.ch
2023-01-20 21:10:04 (3999554): Guest Log: [INFO] Testing connection to VCCS
2023-01-20 21:10:05 (3999554): Guest Log: [INFO] Testing connection to HTCondor
2023-01-20 21:10:05 (3999554): Guest Log: [INFO] Testing connection to WMAgent
2023-01-20 21:10:06 (3999554): Guest Log: [INFO] Testing connection to EOSCMS
2023-01-20 21:10:06 (3999554): Guest Log: [INFO] Testing connection to CMS-Factory
2023-01-20 21:10:07 (3999554): Guest Log: [INFO] Testing connection to CMS-Frontier
2023-01-20 21:10:07 (3999554): Guest Log: [INFO] Testing connection to Frontier
2023-01-20 21:10:09 (3999554): Guest Log: [INFO] Could not find a local HTTP proxy
2023-01-20 21:10:09 (3999554): Guest Log: [INFO] CVMFS and Frontier will have to use DIRECT connections
2023-01-20 21:10:09 (3999554): Guest Log: [INFO] This makes the application less efficient
2023-01-20 21:10:09 (3999554): Guest Log: [INFO] It also puts higher load on the project servers
2023-01-20 21:10:10 (3999554): Guest Log: [INFO] Setting up a local HTTP proxy is highly recommended
2023-01-20 21:10:10 (3999554): Guest Log: [INFO] Advice can be found in the project forum
2023-01-20 21:10:10 (3999554): Guest Log: [INFO] Reloading and probing the CVMFS configuration
2023-01-20 21:10:22 (3999554): Guest Log: [INFO] Probing /cvmfs/grid.cern.ch... OK
2023-01-20 21:10:26 (3999554): Guest Log: [INFO] Probing /cvmfs/singularity.opensciencegrid.org... OK
2023-01-20 21:10:26 (3999554): Guest Log: [INFO] Probing /cvmfs/oasis.opensciencegrid.org... OK
2023-01-20 21:10:27 (3999554): Guest Log: [INFO] Probing /cvmfs/cms.cern.ch... OK
2023-01-20 21:10:27 (3999554): Guest Log: [INFO] Probing /cvmfs/cms-ib.cern.ch... OK
2023-01-20 21:10:29 (3999554): Guest Log: [INFO] Excerpt from "cvmfs_config stat": VERSION HOST PROXY
2023-01-20 21:10:29 (3999554): Guest Log: [INFO] 2.7.2.0 http://s1ral-cvmfs.openhtc.io DIRECT
2023-01-20 21:10:29 (3999554): Guest Log: [INFO] Environment HTTP proxy: not set
2023-01-20 21:10:30 (3999554): Guest Log: [INFO] Reading volunteer information
2023-01-20 21:10:32 (3999554): Guest Log: [INFO] Requesting an X509 credential from LHC@home
2023-01-20 21:10:34 (3999554): Guest Log: [INFO] Requesting an X509 credential from vLHC@home-dev
2023-01-20 21:10:35 (3999554): Guest Log: [INFO] CMS application starting. Check log files.
2023-01-20 21:10:36 (3999554): Guest Log: [INFO] Requesting an idtoken from LHC@home
2023-01-20 21:10:36 (3999554): Guest Log: [INFO] Requesting an idtoken from vLHC@home-dev
2023-01-20 22:09:14 (3999554): Stopping VM.
2023-01-20 22:10:10 (3999554): Successfully stopped VM.
2023-01-21 00:54:49 (4083962): Detected: vboxwrapper 26206
2023-01-21 00:54:49 (4083962): Detected: BOINC client v7.16.11
2023-01-21 00:54:49 (4083962): Detected: VirtualBox VboxManage Interface (Version: 6.1.26)
2023-01-21 00:54:49 (4083962): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2023-01-21 00:54:49 (4083962): Guest Log: BIOS: VirtualBox 6.1.25
2023-01-21 00:54:49 (4083962): Guest Log: CPUID EDX: 0x178bfbff
2023-01-21 00:54:49 (4083962): Guest Log: BIOS: No PCI IDE controller, not probing IDE
2023-01-21 00:54:49 (4083962): Guest Log: BIOS: AHCI 0-P#0: PCHS=16383/16/63 LCHS=1024/255/63 0x0000000002800000 sectors
2023-01-21 00:54:49 (4083962): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032
2023-01-21 00:54:49 (4083962): Guest Log: BIOS: Booting from Hard Disk...
2023-01-21 00:54:49 (4083962): Guest Log: BIOS: KBD: unsupported int 16h function 03
2023-01-21 00:54:49 (4083962): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 
2023-01-21 00:54:49 (4083962): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds
2023-01-21 00:54:49 (4083962): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log
2023-01-21 00:54:49 (4083962): Guest Log: 00:00:00.000258 main     Log opened 2023-01-20T20:09:37.033522000Z
2023-01-21 00:54:49 (4083962): Guest Log: 00:00:00.000481 main     OS Product: Linux
2023-01-21 00:54:49 (4083962): Guest Log: 00:00:00.000572 main     OS Release: 4.14.232-19.cernvm.x86_64
2023-01-21 00:54:49 (4083962): Guest Log: 00:00:00.000615 main     OS Version: #1 SMP Fri Apr 30 17:12:25 CEST 2021
2023-01-21 00:54:49 (4083962): Guest Log: 00:00:00.000800 main     Executable: /usr/sbin/VBoxService
2023-01-21 00:54:49 (4083962): Guest Log: 00:00:00.000802 main     Process ID: 2725
2023-01-21 00:54:49 (4083962): Guest Log: 00:00:00.000803 main     Package type: LINUX_64BITS_GENERIC
2023-01-21 00:54:49 (4083962): Guest Log: 00:00:00.002427 main     5.2.6 r120293 started. Verbose level = 0
2023-01-21 00:54:49 (4083962): Guest Log: [INFO] Mounting the shared directory
2023-01-21 00:54:49 (4083962): Guest Log: [INFO] Shared directory mounted, enabling vboxmonitor
2023-01-21 00:54:49 (4083962): Guest Log: [INFO] Sourcing essential functions from /cvmfs/grid.cern.ch
2023-01-21 00:54:49 (4083962): Guest Log: [INFO] Testing connection to cern.ch
2023-01-21 00:54:49 (4083962): Guest Log: [INFO] Testing connection to VCCS
2023-01-21 00:54:49 (4083962): Guest Log: [INFO] Testing connection to HTCondor
2023-01-21 00:54:49 (4083962): Guest Log: [INFO] Testing connection to WMAgent
2023-01-21 00:54:49 (4083962): Guest Log: [INFO] Testing connection to EOSCMS
2023-01-21 00:54:49 (4083962): Guest Log: [INFO] Testing connection to CMS-Factory
2023-01-21 00:54:49 (4083962): Guest Log: [INFO] Testing connection to CMS-Frontier
2023-01-21 00:54:49 (4083962): Guest Log: [INFO] Testing connection to Frontier
2023-01-21 00:54:49 (4083962): Guest Log: [INFO] Could not find a local HTTP proxy
2023-01-21 00:54:49 (4083962): Guest Log: [INFO] CVMFS and Frontier will have to use DIRECT connections
2023-01-21 00:54:49 (4083962): Guest Log: [INFO] This makes the application less efficient
2023-01-21 00:54:49 (4083962): Guest Log: [INFO] It also puts higher load on the project servers
2023-01-21 00:54:49 (4083962): Guest Log: [INFO] Setting up a local HTTP proxy is highly recommended
2023-01-21 00:54:49 (4083962): Guest Log: [INFO] Advice can be found in the project forum
2023-01-21 00:54:49 (4083962): Guest Log: [INFO] Reloading and probing the CVMFS configuration
2023-01-21 00:54:49 (4083962): Guest Log: [INFO] Probing /cvmfs/grid.cern.ch... OK
2023-01-21 00:54:49 (4083962): Guest Log: [INFO] Probing /cvmfs/singularity.opensciencegrid.org... OK
2023-01-21 00:54:49 (4083962): Guest Log: [INFO] Probing /cvmfs/oasis.opensciencegrid.org... OK
2023-01-21 00:54:49 (4083962): Guest Log: [INFO] Probing /cvmfs/cms.cern.ch... OK
2023-01-21 00:54:49 (4083962): Guest Log: [INFO] Probing /cvmfs/cms-ib.cern.ch... OK
2023-01-21 00:54:49 (4083962): Guest Log: [INFO] Excerpt from "cvmfs_config stat": VERSION HOST PROXY
2023-01-21 00:54:49 (4083962): Guest Log: [INFO] 2.7.2.0 http://s1ral-cvmfs.openhtc.io DIRECT
2023-01-21 00:54:49 (4083962): Guest Log: [INFO] Environment HTTP proxy: not set
2023-01-21 00:54:49 (4083962): Guest Log: [INFO] Reading volunteer information
2023-01-21 00:54:49 (4083962): Guest Log: [INFO] Requesting an X509 credential from LHC@home
2023-01-21 00:54:49 (4083962): Guest Log: [INFO] Requesting an X509 credential from vLHC@home-dev
2023-01-21 00:54:49 (4083962): Guest Log: [INFO] CMS application starting. Check log files.
2023-01-21 00:54:49 (4083962): Guest Log: [INFO] Requesting an idtoken from LHC@home
2023-01-21 00:54:49 (4083962): Guest Log: [INFO] Requesting an idtoken from vLHC@home-dev
2023-01-21 00:54:49 (4083962): Starting VM using VBoxManage interface. (boinc_574701246ee1dd0a, slot#9)
2023-01-21 00:55:02 (4083962): Successfully started VM. (PID = '4084039')
2023-01-21 00:55:02 (4083962): Reporting VM Process ID to BOINC.
2023-01-21 00:55:02 (4083962): VM state change detected. (old = 'poweredoff', new = 'running')
2023-01-21 00:55:02 (4083962): Detected: Web Application Enabled (http://localhost:52483)
2023-01-21 00:55:02 (4083962): Status Report: Job Duration: '64800.000000'
2023-01-21 00:55:02 (4083962): Status Report: Elapsed Time: '18288.000000'
2023-01-21 00:55:02 (4083962): Status Report: CPU Time: '21229.380000'
2023-01-21 00:55:02 (4083962): Preference change detected
2023-01-21 00:55:02 (4083962): Setting CPU throttle for VM. (100%)
2023-01-21 00:55:02 (4083962): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2023-01-21 00:55:11 (4083962): Guest Log: 00:59:51.280312 timesync vgsvcTimeSyncWorker: Radical host time change: 9 957 044 000 000ns (HostNow=1 674 258 911 477 000 000 ns HostLast=1 674 248 954 433 000 000 ns)
2023-01-21 00:55:21 (4083962): Guest Log: 01:00:01.288223 timesync vgsvcTimeSyncWorker: Radical guest time change: 9 953 172 998 000ns (GuestNow=1 674 258 921 485 741 000 ns GuestLast=1 674 248 968 312 743 000 ns fSetTimeLastLoop=true )
2023-01-21 01:54:56 (4083962): Stopping VM.
2023-01-21 01:55:46 (4083962): Successfully stopped VM.
2023-01-21 04:26:57 (4156141): Detected: vboxwrapper 26206
2023-01-21 04:26:57 (4156141): Detected: BOINC client v7.16.11
2023-01-21 04:26:57 (4156141): Detected: VirtualBox VboxManage Interface (Version: 6.1.26)
2023-01-21 04:26:57 (4156141): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2023-01-21 04:26:57 (4156141): Guest Log: 00:59:51.280312 timesync vgsvcTimeSyncWorker: Radical host time change: 9 957 044 000 000ns (HostNow=1 674 258 911 477 000 000 ns HostLast=1 674 248 954 433 000 000 ns)
2023-01-21 04:26:57 (4156141): Guest Log: 01:00:01.288223 timesync vgsvcTimeSyncWorker: Radical guest time change: 9 953 172 998 000ns (GuestNow=1 674 258 921 485 741 000 ns GuestLast=1 674 248 968 312 743 000 ns fSetTimeLastLoop=true )
2023-01-21 04:26:57 (4156141): Starting VM using VBoxManage interface. (boinc_574701246ee1dd0a, slot#9)
2023-01-21 04:27:10 (4156141): Successfully started VM. (PID = '4156240')
2023-01-21 04:27:10 (4156141): Reporting VM Process ID to BOINC.
2023-01-21 04:27:10 (4156141): VM state change detected. (old = 'poweredoff', new = 'running')
2023-01-21 04:27:10 (4156141): Detected: Web Application Enabled (http://localhost:52483)
2023-01-21 04:27:10 (4156141): Status Report: Job Duration: '64800.000000'
2023-01-21 04:27:10 (4156141): Status Report: Elapsed Time: '21918.000000'
2023-01-21 04:27:10 (4156141): Status Report: CPU Time: '25465.140000'
2023-01-21 04:27:10 (4156141): Preference change detected
2023-01-21 04:27:10 (4156141): Setting CPU throttle for VM. (100%)
2023-01-21 04:27:10 (4156141): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2023-01-21 04:27:12 (4156141): Guest Log: 01:59:42.755407 timesync vgsvcTimeSyncWorker: Radical host time change: 9 142 854 000 000ns (HostNow=1 674 271 631 946 000 000 ns HostLast=1 674 262 489 092 000 000 ns)
2023-01-21 04:27:22 (4156141): Guest Log: 01:59:52.763795 timesync vgsvcTimeSyncWorker: Radical guest time change: 9 139 003 808 000ns (GuestNow=1 674 271 641 955 566 000 ns GuestLast=1 674 262 502 951 758 000 ns fSetTimeLastLoop=true )
2023-01-21 05:27:29 (4156141): Stopping VM.
2023-01-21 05:28:26 (4156141): Successfully stopped VM.
2023-01-21 07:59:40 (43472): Detected: vboxwrapper 26206
2023-01-21 07:59:40 (43472): Detected: BOINC client v7.16.11
2023-01-21 07:59:40 (43472): Detected: VirtualBox VboxManage Interface (Version: 6.1.26)
2023-01-21 07:59:40 (43472): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2023-01-21 07:59:40 (43472): Guest Log: 01:59:42.755407 timesync vgsvcTimeSyncWorker: Radical host time change: 9 142 854 000 000ns (HostNow=1 674 271 631 946 000 000 ns HostLast=1 674 262 489 092 000 000 ns)
2023-01-21 07:59:40 (43472): Guest Log: 01:59:52.763795 timesync vgsvcTimeSyncWorker: Radical guest time change: 9 139 003 808 000ns (GuestNow=1 674 271 641 955 566 000 ns GuestLast=1 674 262 502 951 758 000 ns fSetTimeLastLoop=true )
2023-01-21 07:59:40 (43472): Starting VM using VBoxManage interface. (boinc_574701246ee1dd0a, slot#9)
2023-01-21 07:59:54 (43472): Successfully started VM. (PID = '43593')
2023-01-21 07:59:54 (43472): Reporting VM Process ID to BOINC.
2023-01-21 07:59:54 (43472): VM state change detected. (old = 'poweredoff', new = 'running')
2023-01-21 07:59:54 (43472): Detected: Web Application Enabled (http://localhost:52483)
2023-01-21 07:59:54 (43472): Status Report: Job Duration: '64800.000000'
2023-01-21 07:59:54 (43472): Status Report: Elapsed Time: '25573.000000'
2023-01-21 07:59:54 (43472): Status Report: CPU Time: '29058.500000'
2023-01-21 07:59:54 (43472): Preference change detected
2023-01-21 07:59:54 (43472): Setting CPU throttle for VM. (100%)
2023-01-21 07:59:54 (43472): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2023-01-21 08:00:03 (43472): Guest Log: 03:00:14.430792 timesync vgsvcTimeSyncWorker: Radical host time change: 9 153 494 000 000ns (HostNow=1 674 284 403 222 000 000 ns HostLast=1 674 275 249 728 000 000 ns)
2023-01-21 08:00:14 (43472): Guest Log: 03:00:24.437040 timesync vgsvcTimeSyncWorker: Radical guest time change: 9 149 606 658 000ns (GuestNow=1 674 284 413 228 722 000 ns GuestLast=1 674 275 263 622 064 000 ns fSetTimeLastLoop=true )
2023-01-21 09:00:21 (43472): Stopping VM.
2023-01-21 11:16:57 (126525): Detected: vboxwrapper 26206
2023-01-21 11:16:57 (126525): Detected: BOINC client v7.16.11
2023-01-21 11:16:58 (126525): Detected: VirtualBox VboxManage Interface (Version: 6.1.26)
2023-01-21 11:16:58 (126525): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2023-01-21 11:16:58 (126525): Guest Log: 03:00:14.430792 timesync vgsvcTimeSyncWorker: Radical host time change: 9 153 494 000 000ns (HostNow=1 674 284 403 222 000 000 ns HostLast=1 674 275 249 728 000 000 ns)
2023-01-21 11:16:58 (126525): Guest Log: 03:00:24.437040 timesync vgsvcTimeSyncWorker: Radical guest time change: 9 149 606 658 000ns (GuestNow=1 674 284 413 228 722 000 ns GuestLast=1 674 275 263 622 064 000 ns fSetTimeLastLoop=true )
2023-01-21 11:16:58 (126525): Starting VM using VBoxManage interface. (boinc_574701246ee1dd0a, slot#9)
2023-01-21 11:16:59 (126525): Successfully started VM. (PID = '126601')
2023-01-21 11:16:59 (126525): Reporting VM Process ID to BOINC.
2023-01-21 11:16:59 (126525): Guest Log: BIOS: VirtualBox 6.1.25
2023-01-21 11:16:59 (126525): Guest Log: CPUID EDX: 0x178bfbff
2023-01-21 11:16:59 (126525): Guest Log: BIOS: No PCI IDE controller, not probing IDE
2023-01-21 11:16:59 (126525): Guest Log: BIOS: AHCI 0-P#0: PCHS=16383/16/63 LCHS=1024/255/63 0x0000000002800000 sectors
2023-01-21 11:16:59 (126525): VM state change detected. (old = 'poweredoff', new = 'running')
2023-01-21 11:16:59 (126525): Detected: Web Application Enabled (http://localhost:52483)
2023-01-21 11:16:59 (126525): Status Report: Job Duration: '64800.000000'
2023-01-21 11:16:59 (126525): Status Report: Elapsed Time: '29237.000000'
2023-01-21 11:16:59 (126525): Status Report: CPU Time: '33324.820000'
2023-01-21 11:16:59 (126525): Preference change detected
2023-01-21 11:16:59 (126525): Setting CPU throttle for VM. (100%)
2023-01-21 11:16:59 (126525): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2023-01-21 11:17:01 (126525): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032
2023-01-21 11:17:01 (126525): Guest Log: BIOS: Booting from Hard Disk...
2023-01-21 11:17:04 (126525): Guest Log: BIOS: KBD: unsupported int 16h function 03
2023-01-21 11:17:04 (126525): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 
2023-01-21 11:17:49 (126525): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds
2023-01-21 11:17:49 (126525): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000)
2023-01-21 11:17:51 (126525): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log
2023-01-21 11:17:51 (126525): Guest Log: 00:00:00.000136 main     Log opened 2023-01-21T10:17:51.533017000Z
2023-01-21 11:17:51 (126525): Guest Log: 00:00:00.000327 main     OS Product: Linux
2023-01-21 11:17:51 (126525): Guest Log: 00:00:00.000407 main     OS Release: 4.14.232-19.cernvm.x86_64
2023-01-21 11:17:51 (126525): Guest Log: 00:00:00.000447 main     OS Version: #1 SMP Fri Apr 30 17:12:25 CEST 2021
2023-01-21 11:17:51 (126525): Guest Log: 00:00:00.000484 main     Executable: /usr/sbin/VBoxService
2023-01-21 11:17:51 (126525): Guest Log: 00:00:00.000485 main     Process ID: 2709
2023-01-21 11:17:51 (126525): Guest Log: 00:00:00.000486 main     Package type: LINUX_64BITS_GENERIC
2023-01-21 11:17:51 (126525): Guest Log: 00:00:00.001872 main     5.2.6 r120293 started. Verbose level = 0
2023-01-21 11:18:19 (126525): Guest Log: [INFO] Mounting the shared directory
2023-01-21 11:18:19 (126525): Guest Log: [INFO] Shared directory mounted, enabling vboxmonitor
2023-01-21 11:18:19 (126525): Guest Log: [INFO] Sourcing essential functions from /cvmfs/grid.cern.ch
2023-01-21 11:18:20 (126525): Guest Log: [INFO] Testing connection to cern.ch
2023-01-21 11:18:20 (126525): Guest Log: [INFO] Testing connection to VCCS
2023-01-21 11:18:21 (126525): Guest Log: [INFO] Testing connection to HTCondor
2023-01-21 11:18:21 (126525): Guest Log: [INFO] Testing connection to WMAgent
2023-01-21 11:18:21 (126525): Guest Log: [INFO] Testing connection to EOSCMS
2023-01-21 11:18:22 (126525): Guest Log: [INFO] Testing connection to CMS-Factory
2023-01-21 11:18:22 (126525): Guest Log: [INFO] Testing connection to CMS-Frontier
2023-01-21 11:18:23 (126525): Guest Log: [INFO] Testing connection to Frontier
2023-01-21 11:18:25 (126525): Guest Log: [INFO] Could not find a local HTTP proxy
2023-01-21 11:18:25 (126525): Guest Log: [INFO] CVMFS and Frontier will have to use DIRECT connections
2023-01-21 11:18:25 (126525): Guest Log: [INFO] This makes the application less efficient
2023-01-21 11:18:25 (126525): Guest Log: [INFO] It also puts higher load on the project servers
2023-01-21 11:18:26 (126525): Guest Log: [INFO] Setting up a local HTTP proxy is highly recommended
2023-01-21 11:18:26 (126525): Guest Log: [INFO] Advice can be found in the project forum
2023-01-21 11:18:26 (126525): Guest Log: [INFO] Reloading and probing the CVMFS configuration
2023-01-21 11:18:37 (126525): Guest Log: [INFO] Probing /cvmfs/grid.cern.ch... OK
2023-01-21 11:18:41 (126525): Guest Log: [INFO] Probing /cvmfs/singularity.opensciencegrid.org... OK
2023-01-21 11:18:42 (126525): Guest Log: [INFO] Probing /cvmfs/oasis.opensciencegrid.org... OK
2023-01-21 11:18:42 (126525): Guest Log: [INFO] Probing /cvmfs/cms.cern.ch... OK
2023-01-21 11:18:42 (126525): Guest Log: [INFO] Probing /cvmfs/cms-ib.cern.ch... OK
2023-01-21 11:18:44 (126525): Guest Log: [INFO] Excerpt from "cvmfs_config stat": VERSION HOST PROXY
2023-01-21 11:18:44 (126525): Guest Log: [INFO] 2.7.2.0 http://s1ral-cvmfs.openhtc.io DIRECT
2023-01-21 11:18:44 (126525): Guest Log: [INFO] Environment HTTP proxy: not set
2023-01-21 11:18:45 (126525): Guest Log: [INFO] Reading volunteer information
2023-01-21 11:18:48 (126525): Guest Log: [INFO] Requesting an X509 credential from LHC@home
2023-01-21 11:18:49 (126525): Guest Log: [INFO] Requesting an X509 credential from vLHC@home-dev
2023-01-21 11:18:50 (126525): Guest Log: [INFO] CMS application starting. Check log files.
2023-01-21 11:18:50 (126525): Guest Log: [INFO] Requesting an idtoken from LHC@home
2023-01-21 11:18:51 (126525): Guest Log: [INFO] Requesting an idtoken from vLHC@home-dev
2023-01-21 12:17:33 (126525): Stopping VM.
2023-01-21 12:18:16 (126525): Successfully stopped VM.
2023-01-21 13:18:55 (177967): Detected: vboxwrapper 26206
2023-01-21 13:18:55 (177967): Detected: BOINC client v7.16.11
2023-01-21 13:18:55 (177967): Detected: VirtualBox VboxManage Interface (Version: 6.1.26)
2023-01-21 13:18:55 (177967): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2023-01-21 13:18:55 (177967): Guest Log: BIOS: VirtualBox 6.1.25
2023-01-21 13:18:55 (177967): Guest Log: CPUID EDX: 0x178bfbff
2023-01-21 13:18:55 (177967): Guest Log: BIOS: No PCI IDE controller, not probing IDE
2023-01-21 13:18:55 (177967): Guest Log: BIOS: AHCI 0-P#0: PCHS=16383/16/63 LCHS=1024/255/63 0x0000000002800000 sectors
2023-01-21 13:18:55 (177967): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032
2023-01-21 13:18:55 (177967): Guest Log: BIOS: Booting from Hard Disk...
2023-01-21 13:18:55 (177967): Guest Log: BIOS: KBD: unsupported int 16h function 03
2023-01-21 13:18:55 (177967): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 
2023-01-21 13:18:55 (177967): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds
2023-01-21 13:18:55 (177967): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000)
2023-01-21 13:18:55 (177967): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log
2023-01-21 13:18:55 (177967): Guest Log: 00:00:00.000136 main     Log opened 2023-01-21T10:17:51.533017000Z
2023-01-21 13:18:55 (177967): Guest Log: 00:00:00.000327 main     OS Product: Linux
2023-01-21 13:18:55 (177967): Guest Log: 00:00:00.000407 main     OS Release: 4.14.232-19.cernvm.x86_64
2023-01-21 13:18:55 (177967): Guest Log: 00:00:00.000447 main     OS Version: #1 SMP Fri Apr 30 17:12:25 CEST 2021
2023-01-21 13:18:55 (177967): Guest Log: 00:00:00.000484 main     Executable: /usr/sbin/VBoxService
2023-01-21 13:18:55 (177967): Guest Log: 00:00:00.000485 main     Process ID: 2709
2023-01-21 13:18:55 (177967): Guest Log: 00:00:00.000486 main     Package type: LINUX_64BITS_GENERIC
2023-01-21 13:18:55 (177967): Guest Log: 00:00:00.001872 main     5.2.6 r120293 started. Verbose level = 0
2023-01-21 13:18:55 (177967): Guest Log: [INFO] Mounting the shared directory
2023-01-21 13:18:55 (177967): Guest Log: [INFO] Shared directory mounted, enabling vboxmonitor
2023-01-21 13:18:55 (177967): Guest Log: [INFO] Sourcing essential functions from /cvmfs/grid.cern.ch
2023-01-21 13:18:55 (177967): Guest Log: [INFO] Testing connection to cern.ch
2023-01-21 13:18:55 (177967): Guest Log: [INFO] Testing connection to VCCS
2023-01-21 13:18:55 (177967): Guest Log: [INFO] Testing connection to HTCondor
2023-01-21 13:18:55 (177967): Guest Log: [INFO] Testing connection to WMAgent
2023-01-21 13:18:55 (177967): Guest Log: [INFO] Testing connection to EOSCMS
2023-01-21 13:18:55 (177967): Guest Log: [INFO] Testing connection to CMS-Factory
2023-01-21 13:18:55 (177967): Guest Log: [INFO] Testing connection to CMS-Frontier
2023-01-21 13:18:55 (177967): Guest Log: [INFO] Testing connection to Frontier
2023-01-21 13:18:55 (177967): Guest Log: [INFO] Could not find a local HTTP proxy
2023-01-21 13:18:55 (177967): Guest Log: [INFO] CVMFS and Frontier will have to use DIRECT connections
2023-01-21 13:18:55 (177967): Guest Log: [INFO] This makes the application less efficient
2023-01-21 13:18:55 (177967): Guest Log: [INFO] It also puts higher load on the project servers
2023-01-21 13:18:55 (177967): Guest Log: [INFO] Setting up a local HTTP proxy is highly recommended
2023-01-21 13:18:55 (177967): Guest Log: [INFO] Advice can be found in the project forum
2023-01-21 13:18:55 (177967): Guest Log: [INFO] Reloading and probing the CVMFS configuration
2023-01-21 13:18:55 (177967): Guest Log: [INFO] Probing /cvmfs/grid.cern.ch... OK
2023-01-21 13:18:55 (177967): Guest Log: [INFO] Probing /cvmfs/singularity.opensciencegrid.org... OK
2023-01-21 13:18:55 (177967): Guest Log: [INFO] Probing /cvmfs/oasis.opensciencegrid.org... OK
2023-01-21 13:18:55 (177967): Guest Log: [INFO] Probing /cvmfs/cms.cern.ch... OK
2023-01-21 13:18:55 (177967): Guest Log: [INFO] Excerpt from "cvmfs_config stat": VERSION HOST PROXY
2023-01-21 13:18:55 (177967): Guest Log: [INFO] 2.7.2.0 http://s1ral-cvmfs.openhtc.io DIRECT
2023-01-21 13:18:55 (177967): Guest Log: [INFO] Environment HTTP proxy: not set
2023-01-21 13:18:55 (177967): Guest Log: [INFO] Reading volunteer information
2023-01-21 13:18:55 (177967): Guest Log: [INFO] Requesting an X509 credential from LHC@home
2023-01-21 13:18:55 (177967): Guest Log: [INFO] Requesting an X509 credential from vLHC@home-dev
2023-01-21 13:18:55 (177967): Guest Log: [INFO] CMS application starting. Check log files.
2023-01-21 13:18:55 (177967): Guest Log: [INFO] Requesting an idtoken from LHC@home
2023-01-21 13:18:55 (177967): Guest Log: [INFO] Requesting an idtoken from vLHC@home-dev
2023-01-21 13:18:55 (177967): Starting VM using VBoxManage interface. (boinc_574701246ee1dd0a, slot#9)
2023-01-21 13:19:08 (177967): Successfully started VM. (PID = '178067')
2023-01-21 13:19:08 (177967): Reporting VM Process ID to BOINC.
2023-01-21 13:19:08 (177967): VM state change detected. (old = 'poweredoff', new = 'running')
2023-01-21 13:19:08 (177967): Detected: Web Application Enabled (http://localhost:52483)
2023-01-21 13:19:08 (177967): Status Report: Job Duration: '64800.000000'
2023-01-21 13:19:08 (177967): Status Report: Elapsed Time: '32907.000000'
2023-01-21 13:19:08 (177967): Status Report: CPU Time: '37994.430000'
2023-01-21 13:19:08 (177967): Preference change detected
2023-01-21 13:19:08 (177967): Setting CPU throttle for VM. (100%)
2023-01-21 13:19:08 (177967): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2023-01-21 13:19:16 (177967): Guest Log: 00:59:53.826832 timesync vgsvcTimeSyncWorker: Radical host time change: 3 704 450 000 000ns (HostNow=1 674 303 555 930 000 000 ns HostLast=1 674 299 851 480 000 000 ns)
2023-01-21 13:19:26 (177967): Guest Log: 01:00:03.837743 timesync vgsvcTimeSyncWorker: Radical guest time change: 3 700 582 528 000ns (GuestNow=1 674 303 565 941 525 000 ns GuestLast=1 674 299 865 358 997 000 ns fSetTimeLastLoop=true )
2023-01-21 14:19:07 (177967): Stopping VM.
2023-01-21 16:19:18 (252251): Detected: vboxwrapper 26206
2023-01-21 16:19:18 (252251): Detected: BOINC client v7.16.11
2023-01-21 16:19:18 (252251): Detected: VirtualBox VboxManage Interface (Version: 6.1.26)
2023-01-21 16:19:18 (252251): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2023-01-21 16:19:18 (252251): Guest Log: 00:59:53.826832 timesync vgsvcTimeSyncWorker: Radical host time change: 3 704 450 000 000ns (HostNow=1 674 303 555 930 000 000 ns HostLast=1 674 299 851 480 000 000 ns)
2023-01-21 16:19:18 (252251): Guest Log: 01:00:03.837743 timesync vgsvcTimeSyncWorker: Radical guest time change: 3 700 582 528 000ns (GuestNow=1 674 303 565 941 525 000 ns GuestLast=1 674 299 865 358 997 000 ns fSetTimeLastLoop=true )
2023-01-21 16:19:18 (252251): Starting VM using VBoxManage interface. (boinc_574701246ee1dd0a, slot#9)
2023-01-21 16:19:20 (252251): Successfully started VM. (PID = '252349')
2023-01-21 16:19:20 (252251): Reporting VM Process ID to BOINC.
2023-01-21 16:19:20 (252251): Guest Log: BIOS: VirtualBox 6.1.25
2023-01-21 16:19:20 (252251): Guest Log: CPUID EDX: 0x178bfbff
2023-01-21 16:19:20 (252251): Guest Log: BIOS: No PCI IDE controller, not probing IDE
2023-01-21 16:19:20 (252251): Guest Log: BIOS: AHCI 0-P#0: PCHS=16383/16/63 LCHS=1024/255/63 0x0000000002800000 sectors
2023-01-21 16:19:20 (252251): VM state change detected. (old = 'poweredoff', new = 'running')
2023-01-21 16:19:20 (252251): Detected: Web Application Enabled (http://localhost:52483)
2023-01-21 16:19:20 (252251): Status Report: Job Duration: '64800.000000'
2023-01-21 16:19:20 (252251): Status Report: Elapsed Time: '36542.000000'
2023-01-21 16:19:20 (252251): Status Report: CPU Time: '42239.230000'
2023-01-21 16:19:20 (252251): Preference change detected
2023-01-21 16:19:20 (252251): Setting CPU throttle for VM. (100%)
2023-01-21 16:19:20 (252251): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2023-01-21 16:19:21 (252251): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032
2023-01-21 16:19:21 (252251): Guest Log: BIOS: Booting from Hard Disk...
2023-01-21 16:19:24 (252251): Guest Log: BIOS: KBD: unsupported int 16h function 03
2023-01-21 16:19:24 (252251): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 
2023-01-21 16:20:08 (252251): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds
2023-01-21 16:20:08 (252251): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000)
2023-01-21 16:20:11 (252251): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log
2023-01-21 16:20:11 (252251): Guest Log: 00:00:00.001442 main     Log opened 2023-01-21T15:20:11.310952000Z
2023-01-21 16:20:11 (252251): Guest Log: 00:00:00.001845 main     OS Product: Linux
2023-01-21 16:20:11 (252251): Guest Log: 00:00:00.001892 main     OS Release: 4.14.232-19.cernvm.x86_64
2023-01-21 16:20:11 (252251): Guest Log: 00:00:00.001935 main     OS Version: #1 SMP Fri Apr 30 17:12:25 CEST 2021
2023-01-21 16:20:11 (252251): Guest Log: 00:00:00.001978 main     Executable: /usr/sbin/VBoxService
2023-01-21 16:20:11 (252251): Guest Log: 00:00:00.001980 main     Process ID: 2714
2023-01-21 16:20:11 (252251): Guest Log: 00:00:00.001980 main     Package type: LINUX_64BITS_GENERIC
2023-01-21 16:20:11 (252251): Guest Log: 00:00:00.003297 main     5.2.6 r120293 started. Verbose level = 0
2023-01-21 16:20:37 (252251): Guest Log: [INFO] Mounting the shared directory
2023-01-21 16:20:37 (252251): Guest Log: [INFO] Shared directory mounted, enabling vboxmonitor
2023-01-21 16:20:38 (252251): Guest Log: [INFO] Sourcing essential functions from /cvmfs/grid.cern.ch
2023-01-21 16:20:38 (252251): Guest Log: [INFO] Testing connection to cern.ch
2023-01-21 16:20:38 (252251): Guest Log: [INFO] Testing connection to VCCS
2023-01-21 16:20:39 (252251): Guest Log: [INFO] Testing connection to HTCondor
2023-01-21 16:20:39 (252251): Guest Log: [INFO] Testing connection to WMAgent
2023-01-21 16:20:39 (252251): Guest Log: [INFO] Testing connection to EOSCMS
2023-01-21 16:20:40 (252251): Guest Log: [INFO] Testing connection to CMS-Factory
2023-01-21 16:20:41 (252251): Guest Log: [INFO] Testing connection to CMS-Frontier
2023-01-21 16:20:41 (252251): Guest Log: [INFO] Testing connection to Frontier
2023-01-21 16:20:43 (252251): Guest Log: [INFO] Could not find a local HTTP proxy
2023-01-21 16:20:43 (252251): Guest Log: [INFO] CVMFS and Frontier will have to use DIRECT connections
2023-01-21 16:20:43 (252251): Guest Log: [INFO] This makes the application less efficient
2023-01-21 16:20:43 (252251): Guest Log: [INFO] It also puts higher load on the project servers
2023-01-21 16:20:44 (252251): Guest Log: [INFO] Setting up a local HTTP proxy is highly recommended
2023-01-21 16:20:44 (252251): Guest Log: [INFO] Advice can be found in the project forum
2023-01-21 16:20:44 (252251): Guest Log: [INFO] Reloading and probing the CVMFS configuration
2023-01-21 16:20:56 (252251): Guest Log: [INFO] Probing /cvmfs/grid.cern.ch... OK
2023-01-21 16:21:00 (252251): Guest Log: [INFO] Probing /cvmfs/oasis.opensciencegrid.org... OK
2023-01-21 16:21:01 (252251): Guest Log: [INFO] Probing /cvmfs/singularity.opensciencegrid.org... OK
2023-01-21 16:21:01 (252251): Guest Log: [INFO] Probing /cvmfs/cms.cern.ch... OK
2023-01-21 16:21:01 (252251): Guest Log: [INFO] Probing /cvmfs/cms-ib.cern.ch... OK
2023-01-21 16:21:04 (252251): Guest Log: [INFO] Excerpt from "cvmfs_config stat": VERSION HOST PROXY
2023-01-21 16:21:04 (252251): Guest Log: [INFO] 2.7.2.0 http://s1ral-cvmfs.openhtc.io DIRECT
2023-01-21 16:21:04 (252251): Guest Log: [INFO] Environment HTTP proxy: not set
2023-01-21 16:21:05 (252251): Guest Log: [INFO] Reading volunteer information
2023-01-21 16:21:07 (252251): Guest Log: [INFO] Requesting an X509 credential from LHC@home
2023-01-21 16:21:09 (252251): Guest Log: [INFO] Requesting an X509 credential from vLHC@home-dev
2023-01-21 16:21:10 (252251): Guest Log: [INFO] CMS application starting. Check log files.
2023-01-21 16:21:10 (252251): Guest Log: [INFO] Requesting an idtoken from LHC@home
2023-01-21 16:21:11 (252251): Guest Log: [INFO] Requesting an idtoken from vLHC@home-dev
2023-01-21 17:19:46 (252251): Stopping VM.
2023-01-21 17:20:21 (252251): Successfully stopped VM.
2023-01-22 03:56:57 (509961): Detected: vboxwrapper 26206
2023-01-22 03:56:57 (509961): Detected: BOINC client v7.16.11
2023-01-22 03:56:57 (509961): Detected: VirtualBox VboxManage Interface (Version: 6.1.26)
2023-01-22 03:56:57 (509961): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2023-01-22 03:56:57 (509961): Guest Log: BIOS: VirtualBox 6.1.25
2023-01-22 03:56:57 (509961): Guest Log: CPUID EDX: 0x178bfbff
2023-01-22 03:56:57 (509961): Guest Log: BIOS: No PCI IDE controller, not probing IDE
2023-01-22 03:56:57 (509961): Guest Log: BIOS: AHCI 0-P#0: PCHS=16383/16/63 LCHS=1024/255/63 0x0000000002800000 sectors
2023-01-22 03:56:57 (509961): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032
2023-01-22 03:56:57 (509961): Guest Log: BIOS: Booting from Hard Disk...
2023-01-22 03:56:57 (509961): Guest Log: BIOS: KBD: unsupported int 16h function 03
2023-01-22 03:56:57 (509961): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 
2023-01-22 03:56:57 (509961): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds
2023-01-22 03:56:57 (509961): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000)
2023-01-22 03:56:57 (509961): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log
2023-01-22 03:56:57 (509961): Guest Log: 00:00:00.001442 main     Log opened 2023-01-21T15:20:11.310952000Z
2023-01-22 03:56:57 (509961): Guest Log: 00:00:00.001845 main     OS Product: Linux
2023-01-22 03:56:57 (509961): Guest Log: 00:00:00.001892 main     OS Release: 4.14.232-19.cernvm.x86_64
2023-01-22 03:56:57 (509961): Guest Log: 00:00:00.001935 main     OS Version: #1 SMP Fri Apr 30 17:12:25 CEST 2021
2023-01-22 03:56:57 (509961): Guest Log: 00:00:00.001978 main     Executable: /usr/sbin/VBoxService
2023-01-22 03:56:57 (509961): Guest Log: 00:00:00.001980 main     Process ID: 2714
2023-01-22 03:56:57 (509961): Guest Log: 00:00:00.001980 main     Package type: LINUX_64BITS_GENERIC
2023-01-22 03:56:57 (509961): Guest Log: 00:00:00.003297 main     5.2.6 r120293 started. Verbose level = 0
2023-01-22 03:56:57 (509961): Guest Log: [INFO] Mounting the shared directory
2023-01-22 03:56:57 (509961): Guest Log: [INFO] Shared directory mounted, enabling vboxmonitor
2023-01-22 03:56:57 (509961): Guest Log: [INFO] Sourcing essential functions from /cvmfs/grid.cern.ch
2023-01-22 03:56:57 (509961): Guest Log: [INFO] Testing connection to cern.ch
2023-01-22 03:56:57 (509961): Guest Log: [INFO] Testing connection to VCCS
2023-01-22 03:56:57 (509961): Guest Log: [INFO] Testing connection to HTCondor
2023-01-22 03:56:57 (509961): Guest Log: [INFO] Testing connection to WMAgent
2023-01-22 03:56:57 (509961): Guest Log: [INFO] Testing connection to EOSCMS
2023-01-22 03:56:57 (509961): Guest Log: [INFO] Testing connection to CMS-Factory
2023-01-22 03:56:57 (509961): Guest Log: [INFO] Testing connection to CMS-Frontier
2023-01-22 03:56:57 (509961): Guest Log: [INFO] Testing connection to Frontier
2023-01-22 03:56:57 (509961): Guest Log: [INFO] Could not find a local HTTP proxy
2023-01-22 03:56:57 (509961): Guest Log: [INFO] CVMFS and Frontier will have to use DIRECT connections
2023-01-22 03:56:57 (509961): Guest Log: [INFO] This makes the application less efficient
2023-01-22 03:56:57 (509961): Guest Log: [INFO] It also puts higher load on the project servers
2023-01-22 03:56:57 (509961): Guest Log: [INFO] Setting up a local HTTP proxy is highly recommended
2023-01-22 03:56:57 (509961): Guest Log: [INFO] Advice can be found in the project forum
2023-01-22 03:56:57 (509961): Guest Log: [INFO] Reloading and probing the CVMFS configuration
2023-01-22 03:56:57 (509961): Guest Log: [INFO] Probing /cvmfs/grid.cern.ch... OK
2023-01-22 03:56:57 (509961): Guest Log: [INFO] Probing /cvmfs/oasis.opensciencegrid.org... OK
2023-01-22 03:56:57 (509961): Guest Log: [INFO] Probing /cvmfs/singularity.opensciencegrid.org... OK
2023-01-22 03:56:57 (509961): Guest Log: [INFO] Probing /cvmfs/cms.cern.ch... OK
2023-01-22 03:56:57 (509961): Guest Log: [INFO] Probing /cvmfs/cms-ib.cern.ch... OK
2023-01-22 03:56:57 (509961): Guest Log: [INFO] Excerpt from "cvmfs_config stat": VERSION HOST PROXY
2023-01-22 03:56:57 (509961): Guest Log: [INFO] 2.7.2.0 http://s1ral-cvmfs.openhtc.io DIRECT
2023-01-22 03:56:57 (509961): Guest Log: [INFO] Environment HTTP proxy: not set
2023-01-22 03:56:57 (509961): Guest Log: [INFO] Reading volunteer information
2023-01-22 03:56:57 (509961): Guest Log: [INFO] Requesting an X509 credential from LHC@home
2023-01-22 03:56:57 (509961): Guest Log: [INFO] Requesting an X509 credential from vLHC@home-dev
2023-01-22 03:56:57 (509961): Guest Log: [INFO] CMS application starting. Check log files.
2023-01-22 03:56:57 (509961): Guest Log: [INFO] Requesting an idtoken from LHC@home
2023-01-22 03:56:57 (509961): Guest Log: [INFO] Requesting an idtoken from vLHC@home-dev
2023-01-22 03:56:57 (509961): Starting VM using VBoxManage interface. (boinc_574701246ee1dd0a, slot#9)
2023-01-22 03:57:13 (509961): Successfully started VM. (PID = '510082')
2023-01-22 03:57:13 (509961): Reporting VM Process ID to BOINC.
2023-01-22 03:57:13 (509961): VM state change detected. (old = 'poweredoff', new = 'running')
2023-01-22 03:57:13 (509961): Detected: Web Application Enabled (http://localhost:52483)
2023-01-22 03:57:13 (509961): Status Report: Job Duration: '64800.000000'
2023-01-22 03:57:13 (509961): Status Report: Elapsed Time: '40205.000000'
2023-01-22 03:57:13 (509961): Status Report: CPU Time: '47008.080000'
2023-01-22 03:57:13 (509961): Preference change detected
2023-01-22 03:57:13 (509961): Setting CPU throttle for VM. (100%)
2023-01-22 03:57:13 (509961): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2023-01-22 03:57:16 (509961): Guest Log: 00:59:42.986414 timesync vgsvcTimeSyncWorker: Radical host time change: 38 255 957 000 000ns (HostNow=1 674 356 236 383 000 000 ns HostLast=1 674 317 980 426 000 000 ns)
2023-01-22 03:57:27 (509961): Guest Log: 00:59:52.988857 timesync vgsvcTimeSyncWorker: Radical guest time change: 38 252 093 705 000ns (GuestNow=1 674 356 246 387 490 000 ns GuestLast=1 674 317 994 293 785 000 ns fSetTimeLastLoop=true )
2023-01-22 04:57:38 (509961): Stopping VM.
2023-01-22 04:58:23 (509961): Successfully stopped VM.
2023-01-22 04:58:35 (536235): Detected: vboxwrapper 26206
2023-01-22 04:58:35 (536235): Detected: BOINC client v7.16.11
2023-01-22 04:58:35 (536235): Detected: VirtualBox VboxManage Interface (Version: 6.1.26)
2023-01-22 04:58:36 (536235): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2023-01-22 04:58:36 (536235): Guest Log: 00:59:42.986414 timesync vgsvcTimeSyncWorker: Radical host time change: 38 255 957 000 000ns (HostNow=1 674 356 236 383 000 000 ns HostLast=1 674 317 980 426 000 000 ns)
2023-01-22 04:58:36 (536235): Guest Log: 00:59:52.988857 timesync vgsvcTimeSyncWorker: Radical guest time change: 38 252 093 705 000ns (GuestNow=1 674 356 246 387 490 000 ns GuestLast=1 674 317 994 293 785 000 ns fSetTimeLastLoop=true )
2023-01-22 04:58:36 (536235): Starting VM using VBoxManage interface. (boinc_574701246ee1dd0a, slot#9)
2023-01-22 04:58:56 (536235): Successfully started VM. (PID = '536313')
2023-01-22 04:58:56 (536235): Reporting VM Process ID to BOINC.
2023-01-22 04:58:56 (536235): VM state change detected. (old = 'poweredoff', new = 'running')
2023-01-22 04:58:56 (536235): Detected: Web Application Enabled (http://localhost:52483)
2023-01-22 04:58:56 (536235): Status Report: Job Duration: '64800.000000'
2023-01-22 04:58:56 (536235): Status Report: Elapsed Time: '43866.000000'
2023-01-22 04:58:56 (536235): Status Report: CPU Time: '51248.690000'
2023-01-22 04:58:56 (536235): Preference change detected
2023-01-22 04:58:56 (536235): Setting CPU throttle for VM. (100%)
2023-01-22 04:58:57 (536235): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2023-01-22 05:59:07 (536235): Stopping VM.
2023-01-22 06:00:06 (536235): Successfully stopped VM.
2023-01-22 07:11:28 (592818): Detected: vboxwrapper 26206
2023-01-22 07:11:28 (592818): Detected: BOINC client v7.16.11
2023-01-22 07:11:28 (592818): Detected: VirtualBox VboxManage Interface (Version: 6.1.26)
2023-01-22 07:11:28 (592818): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2023-01-22 07:11:28 (592818): Starting VM using VBoxManage interface. (boinc_574701246ee1dd0a, slot#9)
2023-01-22 07:11:41 (592818): Successfully started VM. (PID = '592894')
2023-01-22 07:11:41 (592818): Reporting VM Process ID to BOINC.
2023-01-22 07:11:41 (592818): VM state change detected. (old = 'poweredoff', new = 'running')
2023-01-22 07:11:41 (592818): Detected: Web Application Enabled (http://localhost:52483)
2023-01-22 07:11:41 (592818): Status Report: Job Duration: '64800.000000'
2023-01-22 07:11:41 (592818): Status Report: Elapsed Time: '47513.000000'
2023-01-22 07:11:41 (592818): Status Report: CPU Time: '55274.200000'
2023-01-22 07:11:41 (592818): Preference change detected
2023-01-22 07:11:41 (592818): Setting CPU throttle for VM. (100%)
2023-01-22 07:11:41 (592818): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2023-01-22 07:11:42 (592818): Guest Log: 03:00:27.330459 timesync vgsvcTimeSyncWorker: Radical host time change: 4 363 127 000 000ns (HostNow=1 674 367 902 755 000 000 ns HostLast=1 674 363 539 628 000 000 ns)
2023-01-22 07:11:53 (592818): Guest Log: 03:00:37.332217 timesync vgsvcTimeSyncWorker: Radical guest time change: 4 359 248 264 000ns (GuestNow=1 674 367 912 758 564 000 ns GuestLast=1 674 363 553 510 300 000 ns fSetTimeLastLoop=true )
2023-01-22 07:12:12 (592818): Guest Log: [INFO] glidein exited with return value 0.
2023-01-22 07:12:12 (592818): Guest Log: [INFO] Shutting Down.
2023-01-22 07:12:12 (592818): VM Completion File Detected.
2023-01-22 07:12:12 (592818): VM Completion Message: glidein exited with return value 0.
.
2023-01-22 07:12:12 (592818): Powering off VM.
2023-01-22 07:12:13 (592818): Successfully stopped VM.
2023-01-22 07:12:13 (592818): Deregistering VM. (boinc_574701246ee1dd0a, slot#9)
2023-01-22 07:12:13 (592818): Removing network bandwidth throttle group from VM.
2023-01-22 07:12:13 (592818): Removing VM from VirtualBox.
07:12:18 (592818): called boinc_finish(0)

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

Stderr output
