Stderr output

<core_client_version>7.16.11</core_client_version>
<![CDATA[
<stderr_txt>
2022-11-28 10:35:36 (1169230): Detected: vboxwrapper 26206
2022-11-28 10:35:36 (1169230): Detected: BOINC client v7.16.11
2022-11-28 10:35:37 (1169230): Detected: VirtualBox VboxManage Interface (Version: 6.1.26)
2022-11-28 10:35:37 (1169230): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2022-11-28 10:35:37 (1169230): Successfully copied 'init_data.xml' to the shared directory.
2022-11-28 10:35:37 (1169230): Create VM. (boinc_6ad8266593738b76, slot#11)
2022-11-28 10:35:37 (1169230): Setting Memory Size for VM. (15336MB)
2022-11-28 10:35:37 (1169230): Setting CPU Count for VM. (16)
2022-11-28 10:35:37 (1169230): Setting Chipset Options for VM.
2022-11-28 10:35:38 (1169230): Setting Graphics Controller Options for VM.
2022-11-28 10:35:38 (1169230): Setting Boot Options for VM.
2022-11-28 10:35:38 (1169230): Setting Network Configuration for NAT.
2022-11-28 10:35:38 (1169230): Enabling VM Network Access.
2022-11-28 10:35:38 (1169230): Disabling USB Support for VM.
2022-11-28 10:35:38 (1169230): Disabling COM Port Support for VM.
2022-11-28 10:35:38 (1169230): Disabling LPT Port Support for VM.
2022-11-28 10:35:38 (1169230): Disabling Audio Support for VM.
2022-11-28 10:35:38 (1169230): Disabling Clipboard Support for VM.
2022-11-28 10:35:39 (1169230): Disabling Drag and Drop Support for VM.
2022-11-28 10:35:39 (1169230): Adding storage controller(s) to VM.
2022-11-28 10:35:39 (1169230): Adding virtual disk drive to VM. (CMS_2022_09_07.vdi)
2022-11-28 10:35:40 (1169230): Adding network bandwidth throttle group to VM. (Defaulting to 1024GB)
2022-11-28 10:35:40 (1169230): forwarding host port 49093 to guest port 80
2022-11-28 10:35:40 (1169230): Enabling remote desktop for VM.
2022-11-28 10:35:40 (1169230): Required extension pack not installed, remote desktop not enabled.
2022-11-28 10:35:40 (1169230): Enabling shared directory for VM.
2022-11-28 10:35:40 (1169230): Starting VM using VBoxManage interface. (boinc_6ad8266593738b76, slot#11)
2022-11-28 10:35:43 (1169230): Successfully started VM. (PID = '1169615')
2022-11-28 10:35:43 (1169230): Reporting VM Process ID to BOINC.
2022-11-28 10:35:43 (1169230): Guest Log: BIOS: VirtualBox 6.1.25
2022-11-28 10:35:43 (1169230): Guest Log: CPUID EDX: 0x178bfbff
2022-11-28 10:35:43 (1169230): Guest Log: BIOS: No PCI IDE controller, not probing IDE
2022-11-28 10:35:43 (1169230): Guest Log: BIOS: AHCI 0-P#0: PCHS=16383/16/63 LCHS=1024/255/63 0x0000000002800000 sectors
2022-11-28 10:35:43 (1169230): VM state change detected. (old = 'poweredoff', new = 'running')
2022-11-28 10:35:43 (1169230): Detected: Web Application Enabled (http://localhost:49093)
2022-11-28 10:35:43 (1169230): Preference change detected
2022-11-28 10:35:43 (1169230): Setting CPU throttle for VM. (100%)
2022-11-28 10:35:43 (1169230): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2022-11-28 10:35:45 (1169230): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032
2022-11-28 10:35:45 (1169230): Guest Log: BIOS: Booting from Hard Disk...
2022-11-28 10:35:54 (1169230): Guest Log: BIOS: KBD: unsupported int 16h function 03
2022-11-28 10:35:54 (1169230): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 
2022-11-28 10:36:43 (1169230): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds
2022-11-28 10:36:43 (1169230): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000)
2022-11-28 10:36:48 (1169230): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log
2022-11-28 10:36:48 (1169230): Guest Log: 00:00:00.000175 main     Log opened 2022-11-28T09:36:47.167098000Z
2022-11-28 10:36:48 (1169230): Guest Log: 00:00:00.000335 main     OS Product: Linux
2022-11-28 10:36:48 (1169230): Guest Log: 00:00:00.000380 main     OS Release: 4.14.232-19.cernvm.x86_64
2022-11-28 10:36:48 (1169230): Guest Log: 00:00:00.000417 main     OS Version: #1 SMP Fri Apr 30 17:12:25 CEST 2021
2022-11-28 10:36:48 (1169230): Guest Log: 00:00:00.000451 main     Executable: /usr/sbin/VBoxService
2022-11-28 10:36:48 (1169230): Guest Log: 00:00:00.000452 main     Process ID: 2705
2022-11-28 10:36:48 (1169230): Guest Log: 00:00:00.000453 main     Package type: LINUX_64BITS_GENERIC
2022-11-28 10:36:48 (1169230): Guest Log: 00:00:00.013979 main     5.2.6 r120293 started. Verbose level = 0
2022-11-28 10:37:14 (1169230): Guest Log: [INFO] Mounting the shared directory
2022-11-28 10:37:14 (1169230): Guest Log: [INFO] Shared directory mounted, enabling vboxmonitor
2022-11-28 10:37:14 (1169230): Guest Log: [INFO] Sourcing essential functions from /cvmfs/grid.cern.ch
2022-11-28 10:37:15 (1169230): Guest Log: [INFO] Testing connection to cern.ch
2022-11-28 10:37:15 (1169230): Guest Log: [INFO] Testing connection to VCCS
2022-11-28 10:37:16 (1169230): Guest Log: [INFO] Testing connection to HTCondor
2022-11-28 10:37:16 (1169230): Guest Log: [INFO] Testing connection to WMAgent
2022-11-28 10:37:17 (1169230): Guest Log: [INFO] Testing connection to EOSCMS
2022-11-28 10:37:17 (1169230): Guest Log: [INFO] Testing connection to CMS-Factory
2022-11-28 10:37:18 (1169230): Guest Log: [INFO] Testing connection to CMS-Frontier
2022-11-28 10:37:18 (1169230): Guest Log: [INFO] Testing connection to Frontier
2022-11-28 10:37:19 (1169230): Guest Log: [INFO] Could not find a local HTTP proxy
2022-11-28 10:37:19 (1169230): Guest Log: [INFO] CVMFS and Frontier will have to use DIRECT connections
2022-11-28 10:37:20 (1169230): Guest Log: [INFO] This makes the application less efficient
2022-11-28 10:37:20 (1169230): Guest Log: [INFO] It also puts higher load on the project servers
2022-11-28 10:37:20 (1169230): Guest Log: [INFO] Setting up a local HTTP proxy is highly recommended
2022-11-28 10:37:20 (1169230): Guest Log: [INFO] Advice can be found in the project forum
2022-11-28 10:37:21 (1169230): Guest Log: [INFO] Reloading and probing the CVMFS configuration
2022-11-28 10:37:33 (1169230): Guest Log: [INFO] Probing /cvmfs/grid.cern.ch... OK
2022-11-28 10:37:37 (1169230): Guest Log: [INFO] Probing /cvmfs/cms-ib.cern.ch... OK
2022-11-28 10:37:37 (1169230): Guest Log: [INFO] Probing /cvmfs/singularity.opensciencegrid.org... OK
2022-11-28 10:37:39 (1169230): Guest Log: [INFO] Probing /cvmfs/oasis.opensciencegrid.org... OK
2022-11-28 10:37:39 (1169230): Guest Log: [INFO] Probing /cvmfs/cms.cern.ch... OK
2022-11-28 10:37:40 (1169230): Guest Log: [INFO] Excerpt from "cvmfs_config stat": VERSION HOST PROXY
2022-11-28 10:37:41 (1169230): Guest Log: [INFO] 2.7.2.0 http://s1ral-cvmfs.openhtc.io DIRECT
2022-11-28 10:37:41 (1169230): Guest Log: [INFO] Environment HTTP proxy: not set
2022-11-28 10:37:41 (1169230): Guest Log: [INFO] Reading volunteer information
2022-11-28 10:37:46 (1169230): Guest Log: [INFO] Requesting an X509 credential from LHC@home
2022-11-28 10:37:47 (1169230): Guest Log: [INFO] Requesting an X509 credential from vLHC@home-dev
2022-11-28 10:37:48 (1169230): Guest Log: [INFO] CMS application starting. Check log files.
2022-11-28 10:37:49 (1169230): Guest Log: [INFO] Requesting an idtoken from LHC@home
2022-11-28 10:37:50 (1169230): Guest Log: [INFO] Requesting an idtoken from vLHC@home-dev
2022-11-28 11:35:44 (1169230): Stopping VM.
2022-11-28 11:36:39 (1169230): Successfully stopped VM.
2022-11-28 12:31:39 (1218503): Detected: vboxwrapper 26206
2022-11-28 12:31:39 (1218503): Detected: BOINC client v7.16.11
2022-11-28 12:31:39 (1218503): Detected: VirtualBox VboxManage Interface (Version: 6.1.26)
2022-11-28 12:31:39 (1218503): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2022-11-28 12:31:40 (1218503): Guest Log: BIOS: VirtualBox 6.1.25
2022-11-28 12:31:40 (1218503): Guest Log: CPUID EDX: 0x178bfbff
2022-11-28 12:31:40 (1218503): Guest Log: BIOS: No PCI IDE controller, not probing IDE
2022-11-28 12:31:40 (1218503): Guest Log: BIOS: AHCI 0-P#0: PCHS=16383/16/63 LCHS=1024/255/63 0x0000000002800000 sectors
2022-11-28 12:31:40 (1218503): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032
2022-11-28 12:31:40 (1218503): Guest Log: BIOS: Booting from Hard Disk...
2022-11-28 12:31:40 (1218503): Guest Log: BIOS: KBD: unsupported int 16h function 03
2022-11-28 12:31:40 (1218503): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 
2022-11-28 12:31:40 (1218503): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds
2022-11-28 12:31:40 (1218503): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000)
2022-11-28 12:31:40 (1218503): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log
2022-11-28 12:31:40 (1218503): Guest Log: 00:00:00.013979 main     5.2.6 r120293 started. Verbose level = 0
2022-11-28 12:31:40 (1218503): Guest Log: [INFO] Mounting the shared directory
2022-11-28 12:31:40 (1218503): Guest Log: [INFO] Shared directory mounted, enabling vboxmonitor
2022-11-28 12:31:40 (1218503): Guest Log: [INFO] Sourcing essential functions from /cvmfs/grid.cern.ch
2022-11-28 12:31:40 (1218503): Guest Log: [INFO] Testing connection to cern.ch
2022-11-28 12:31:40 (1218503): Guest Log: [INFO] Testing connection to VCCS
2022-11-28 12:31:40 (1218503): Guest Log: [INFO] Testing connection to HTCondor
2022-11-28 12:31:40 (1218503): Guest Log: [INFO] Testing connection to WMAgent
2022-11-28 12:31:40 (1218503): Guest Log: [INFO] Testing connection to EOSCMS
2022-11-28 12:31:40 (1218503): Guest Log: [INFO] Testing connection to CMS-Factory
2022-11-28 12:31:40 (1218503): Guest Log: [INFO] Testing connection to CMS-Frontier
2022-11-28 12:31:40 (1218503): Guest Log: [INFO] Testing connection to Frontier
2022-11-28 12:31:40 (1218503): Guest Log: [INFO] Could not find a local HTTP proxy
2022-11-28 12:31:40 (1218503): Guest Log: [INFO] CVMFS and Frontier will have to use DIRECT connections
2022-11-28 12:31:40 (1218503): Guest Log: [INFO] This makes the application less efficient
2022-11-28 12:31:40 (1218503): Guest Log: [INFO] It also puts higher load on the project servers
2022-11-28 12:31:40 (1218503): Guest Log: [INFO] Setting up a local HTTP proxy is highly recommended
2022-11-28 12:31:40 (1218503): Guest Log: [INFO] Advice can be found in the project forum
2022-11-28 12:31:40 (1218503): Guest Log: [INFO] Reloading and probing the CVMFS configuration
2022-11-28 12:31:40 (1218503): Guest Log: [INFO] Probing /cvmfs/grid.cern.ch... OK
2022-11-28 12:31:40 (1218503): Guest Log: [INFO] Probing /cvmfs/cms-ib.cern.ch... OK
2022-11-28 12:31:40 (1218503): Guest Log: [INFO] Probing /cvmfs/singularity.opensciencegrid.org... OK
2022-11-28 12:31:40 (1218503): Guest Log: [INFO] Probing /cvmfs/oasis.opensciencegrid.org... OK
2022-11-28 12:31:40 (1218503): Guest Log: [INFO] Probing /cvmfs/cms.cern.ch... OK
2022-11-28 12:31:40 (1218503): Guest Log: [INFO] Excerpt from "cvmfs_config stat": VERSION HOST PROXY
2022-11-28 12:31:40 (1218503): Guest Log: [INFO] 2.7.2.0 http://s1ral-cvmfs.openhtc.io DIRECT
2022-11-28 12:31:40 (1218503): Guest Log: [INFO] Environment HTTP proxy: not set
2022-11-28 12:31:40 (1218503): Guest Log: [INFO] Reading volunteer information
2022-11-28 12:31:40 (1218503): Guest Log: [INFO] Requesting an X509 credential from LHC@home
2022-11-28 12:31:40 (1218503): Guest Log: [INFO] Requesting an X509 credential from vLHC@home-dev
2022-11-28 12:31:40 (1218503): Guest Log: [INFO] CMS application starting. Check log files.
2022-11-28 12:31:40 (1218503): Guest Log: [INFO] Requesting an idtoken from LHC@home
2022-11-28 12:31:40 (1218503): Guest Log: [INFO] Requesting an idtoken from vLHC@home-dev
2022-11-28 12:31:40 (1218503): Starting VM using VBoxManage interface. (boinc_6ad8266593738b76, slot#11)
2022-11-28 12:32:02 (1218503): Successfully started VM. (PID = '1218610')
2022-11-28 12:32:02 (1218503): Reporting VM Process ID to BOINC.
2022-11-28 12:32:02 (1218503): VM state change detected. (old = 'poweredoff', new = 'running')
2022-11-28 12:32:02 (1218503): Detected: Web Application Enabled (http://localhost:49093)
2022-11-28 12:32:02 (1218503): Preference change detected
2022-11-28 12:32:02 (1218503): Setting CPU throttle for VM. (100%)
2022-11-28 12:32:03 (1218503): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2022-11-28 12:32:11 (1218503): Guest Log: 00:59:11.404253 timesync vgsvcTimeSyncWorker: Radical host time change: 3 386 473 000 000ns (HostNow=1 669 635 131 121 000 000 ns HostLast=1 669 631 744 648 000 000 ns)
2022-11-28 12:32:21 (1218503): Guest Log: 00:59:21.421077 timesync vgsvcTimeSyncWorker: Radical guest time change: 3 382 693 011 000ns (GuestNow=1 669 635 141 200 904 000 ns GuestLast=1 669 631 758 507 893 000 ns fSetTimeLastLoop=true )
2022-11-28 13:11:02 (1218503): Status Report: Job Duration: '64800.000000'
2022-11-28 13:11:02 (1218503): Status Report: Elapsed Time: '6000.000000'
2022-11-28 13:11:02 (1218503): Status Report: CPU Time: '7406.760000'
2022-11-28 13:32:24 (1218503): Stopping VM.
2022-11-28 13:33:21 (1218503): Successfully stopped VM.
2022-11-28 14:33:14 (1268737): Detected: vboxwrapper 26206
2022-11-28 14:33:14 (1268737): Detected: BOINC client v7.16.11
2022-11-28 14:33:15 (1268737): Detected: VirtualBox VboxManage Interface (Version: 6.1.26)
2022-11-28 14:33:15 (1268737): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2022-11-28 14:33:16 (1268737): Guest Log: 00:59:11.404253 timesync vgsvcTimeSyncWorker: Radical host time change: 3 386 473 000 000ns (HostNow=1 669 635 131 121 000 000 ns HostLast=1 669 631 744 648 000 000 ns)
2022-11-28 14:33:16 (1268737): Guest Log: 00:59:21.421077 timesync vgsvcTimeSyncWorker: Radical guest time change: 3 382 693 011 000ns (GuestNow=1 669 635 141 200 904 000 ns GuestLast=1 669 631 758 507 893 000 ns fSetTimeLastLoop=true )
2022-11-28 14:33:16 (1268737): Starting VM using VBoxManage interface. (boinc_6ad8266593738b76, slot#11)
2022-11-28 14:33:41 (1268737): Successfully started VM. (PID = '1268834')
2022-11-28 14:33:41 (1268737): Reporting VM Process ID to BOINC.
2022-11-28 14:33:41 (1268737): VM state change detected. (old = 'poweredoff', new = 'running')
2022-11-28 14:33:41 (1268737): Detected: Web Application Enabled (http://localhost:49093)
2022-11-28 14:33:41 (1268737): Status Report: Job Duration: '64800.000000'
2022-11-28 14:33:41 (1268737): Status Report: Elapsed Time: '7295.000000'
2022-11-28 14:33:41 (1268737): Status Report: CPU Time: '8904.850000'
2022-11-28 14:33:41 (1268737): Preference change detected
2022-11-28 14:33:41 (1268737): Setting CPU throttle for VM. (100%)
2022-11-28 14:33:41 (1268737): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2022-11-28 14:33:44 (1268737): Guest Log: 01:59:33.017420 timesync vgsvcTimeSyncWorker: Radical host time change: 3 684 918 000 000ns (HostNow=1 669 642 423 864 000 000 ns HostLast=1 669 638 738 946 000 000 ns)
2022-11-28 14:33:54 (1268737): Guest Log: 01:59:43.020623 timesync vgsvcTimeSyncWorker: Radical guest time change: 3 681 077 937 000ns (GuestNow=1 669 642 433 871 218 000 ns GuestLast=1 669 638 752 793 281 000 ns fSetTimeLastLoop=true )
2022-11-28 15:34:06 (1268737): Stopping VM.
2022-11-28 15:34:55 (1268737): Successfully stopped VM.
2022-11-28 16:33:15 (1318679): Detected: vboxwrapper 26206
2022-11-28 16:33:15 (1318679): Detected: BOINC client v7.16.11
2022-11-28 16:33:15 (1318679): Detected: VirtualBox VboxManage Interface (Version: 6.1.26)
2022-11-28 16:33:15 (1318679): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2022-11-28 16:33:16 (1318679): Guest Log: 01:59:33.017420 timesync vgsvcTimeSyncWorker: Radical host time change: 3 684 918 000 000ns (HostNow=1 669 642 423 864 000 000 ns HostLast=1 669 638 738 946 000 000 ns)
2022-11-28 16:33:16 (1318679): Guest Log: 01:59:43.020623 timesync vgsvcTimeSyncWorker: Radical guest time change: 3 681 077 937 000ns (GuestNow=1 669 642 433 871 218 000 ns GuestLast=1 669 638 752 793 281 000 ns fSetTimeLastLoop=true )
2022-11-28 16:33:16 (1318679): Starting VM using VBoxManage interface. (boinc_6ad8266593738b76, slot#11)
2022-11-28 16:33:37 (1318679): Successfully started VM. (PID = '1318786')
2022-11-28 16:33:37 (1318679): Reporting VM Process ID to BOINC.
2022-11-28 16:33:37 (1318679): VM state change detected. (old = 'poweredoff', new = 'running')
2022-11-28 16:33:37 (1318679): Detected: Web Application Enabled (http://localhost:49093)
2022-11-28 16:33:37 (1318679): Status Report: Job Duration: '64800.000000'
2022-11-28 16:33:37 (1318679): Status Report: Elapsed Time: '10957.000000'
2022-11-28 16:33:37 (1318679): Status Report: CPU Time: '12544.320000'
2022-11-28 16:33:37 (1318679): Preference change detected
2022-11-28 16:33:37 (1318679): Setting CPU throttle for VM. (100%)
2022-11-28 16:33:38 (1318679): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2022-11-28 16:33:41 (1318679): Guest Log: 03:00:04.723613 timesync vgsvcTimeSyncWorker: Radical host time change: 3 579 346 000 000ns (HostNow=1 669 649 620 945 000 000 ns HostLast=1 669 646 041 599 000 000 ns)
2022-11-28 16:33:51 (1318679): Guest Log: 03:00:14.727237 timesync vgsvcTimeSyncWorker: Radical guest time change: 3 575 538 166 000ns (GuestNow=1 669 649 631 030 468 000 ns GuestLast=1 669 646 055 492 302 000 ns fSetTimeLastLoop=true )
2022-11-28 17:33:44 (1318679): Stopping VM.
2022-11-28 17:34:44 (1318679): Successfully stopped VM.
2022-11-28 18:33:52 (1368971): Detected: vboxwrapper 26206
2022-11-28 18:33:52 (1368971): Detected: BOINC client v7.16.11
2022-11-28 18:33:53 (1368971): Detected: VirtualBox VboxManage Interface (Version: 6.1.26)
2022-11-28 18:33:53 (1368971): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2022-11-28 18:33:53 (1368971): Guest Log: 03:00:04.723613 timesync vgsvcTimeSyncWorker: Radical host time change: 3 579 346 000 000ns (HostNow=1 669 649 620 945 000 000 ns HostLast=1 669 646 041 599 000 000 ns)
2022-11-28 18:33:53 (1368971): Guest Log: 03:00:14.727237 timesync vgsvcTimeSyncWorker: Radical guest time change: 3 575 538 166 000ns (GuestNow=1 669 649 631 030 468 000 ns GuestLast=1 669 646 055 492 302 000 ns fSetTimeLastLoop=true )
2022-11-28 18:33:53 (1368971): Starting VM using VBoxManage interface. (boinc_6ad8266593738b76, slot#11)
2022-11-28 18:34:07 (1368971): Successfully started VM. (PID = '1369047')
2022-11-28 18:34:07 (1368971): Reporting VM Process ID to BOINC.
2022-11-28 18:34:07 (1368971): VM state change detected. (old = 'poweredoff', new = 'running')
2022-11-28 18:34:07 (1368971): Detected: Web Application Enabled (http://localhost:49093)
2022-11-28 18:34:07 (1368971): Status Report: Job Duration: '64800.000000'
2022-11-28 18:34:07 (1368971): Status Report: Elapsed Time: '14599.000000'
2022-11-28 18:34:07 (1368971): Status Report: CPU Time: '16855.320000'
2022-11-28 18:34:07 (1368971): Preference change detected
2022-11-28 18:34:07 (1368971): Setting CPU throttle for VM. (100%)
2022-11-28 18:34:07 (1368971): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2022-11-28 18:34:11 (1368971): Guest Log: 04:00:16.565399 timesync vgsvcTimeSyncWorker: Radical host time change: 3 631 762 000 000ns (HostNow=1 669 656 850 764 000 000 ns HostLast=1 669 653 219 002 000 000 ns)
2022-11-28 18:34:21 (1368971): Guest Log: 04:00:26.570063 timesync vgsvcTimeSyncWorker: Radical guest time change: 3 627 901 078 000ns (GuestNow=1 669 656 860 769 199 000 ns GuestLast=1 669 653 232 868 121 000 ns fSetTimeLastLoop=true )
2022-11-28 19:34:24 (1368971): Stopping VM.
2022-11-28 19:35:23 (1368971): Successfully stopped VM.
2022-11-28 20:34:32 (1419220): Detected: vboxwrapper 26206
2022-11-28 20:34:32 (1419220): Detected: BOINC client v7.16.11
2022-11-28 20:34:32 (1419220): Detected: VirtualBox VboxManage Interface (Version: 6.1.26)
2022-11-28 20:34:32 (1419220): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2022-11-28 20:34:33 (1419220): Guest Log: 04:00:16.565399 timesync vgsvcTimeSyncWorker: Radical host time change: 3 631 762 000 000ns (HostNow=1 669 656 850 764 000 000 ns HostLast=1 669 653 219 002 000 000 ns)
2022-11-28 20:34:33 (1419220): Guest Log: 04:00:26.570063 timesync vgsvcTimeSyncWorker: Radical guest time change: 3 627 901 078 000ns (GuestNow=1 669 656 860 769 199 000 ns GuestLast=1 669 653 232 868 121 000 ns fSetTimeLastLoop=true )
2022-11-28 20:34:33 (1419220): Starting VM using VBoxManage interface. (boinc_6ad8266593738b76, slot#11)
2022-11-28 20:34:52 (1419220): Successfully started VM. (PID = '1419300')
2022-11-28 20:34:52 (1419220): Reporting VM Process ID to BOINC.
2022-11-28 20:34:52 (1419220): VM state change detected. (old = 'poweredoff', new = 'running')
2022-11-28 20:34:52 (1419220): Detected: Web Application Enabled (http://localhost:49093)
2022-11-28 20:34:52 (1419220): Status Report: Job Duration: '64800.000000'
2022-11-28 20:34:52 (1419220): Status Report: Elapsed Time: '18252.000000'
2022-11-28 20:34:52 (1419220): Status Report: CPU Time: '20485.930000'
2022-11-28 20:34:52 (1419220): Preference change detected
2022-11-28 20:34:52 (1419220): Setting CPU throttle for VM. (100%)
2022-11-28 20:34:52 (1419220): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2022-11-28 20:34:56 (1419220): Guest Log: 05:00:39.296628 timesync vgsvcTimeSyncWorker: Radical host time change: 3 635 556 000 000ns (HostNow=1 669 664 095 165 000 000 ns HostLast=1 669 660 459 609 000 000 ns)
2022-11-28 20:35:05 (1419220): Guest Log: 05:00:49.301843 timesync vgsvcTimeSyncWorker: Radical guest time change: 3 631 678 919 000ns (GuestNow=1 669 664 105 172 442 000 ns GuestLast=1 669 660 473 493 523 000 ns fSetTimeLastLoop=true )
2022-11-28 21:35:30 (1419220): Stopping VM.
2022-11-28 22:36:11 (1470099): Detected: vboxwrapper 26206
2022-11-28 22:36:11 (1470099): Detected: BOINC client v7.16.11
2022-11-28 22:36:12 (1470099): Detected: VirtualBox VboxManage Interface (Version: 6.1.26)
2022-11-28 22:36:12 (1470099): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2022-11-28 22:36:12 (1470099): Guest Log: 05:00:39.296628 timesync vgsvcTimeSyncWorker: Radical host time change: 3 635 556 000 000ns (HostNow=1 669 664 095 165 000 000 ns HostLast=1 669 660 459 609 000 000 ns)
2022-11-28 22:36:12 (1470099): Guest Log: 05:00:49.301843 timesync vgsvcTimeSyncWorker: Radical guest time change: 3 631 678 919 000ns (GuestNow=1 669 664 105 172 442 000 ns GuestLast=1 669 660 473 493 523 000 ns fSetTimeLastLoop=true )
2022-11-28 22:36:12 (1470099): Starting VM using VBoxManage interface. (boinc_6ad8266593738b76, slot#11)
2022-11-28 22:36:14 (1470099): Successfully started VM. (PID = '1470191')
2022-11-28 22:36:14 (1470099): Reporting VM Process ID to BOINC.
2022-11-28 22:36:14 (1470099): Guest Log: BIOS: VirtualBox 6.1.25
2022-11-28 22:36:14 (1470099): Guest Log: CPUID EDX: 0x178bfbff
2022-11-28 22:36:14 (1470099): Guest Log: BIOS: No PCI IDE controller, not probing IDE
2022-11-28 22:36:14 (1470099): Guest Log: BIOS: AHCI 0-P#0: PCHS=16383/16/63 LCHS=1024/255/63 0x0000000002800000 sectors
2022-11-28 22:36:14 (1470099): VM state change detected. (old = 'poweredoff', new = 'running')
2022-11-28 22:36:14 (1470099): Detected: Web Application Enabled (http://localhost:49093)
2022-11-28 22:36:14 (1470099): Status Report: Job Duration: '64800.000000'
2022-11-28 22:36:14 (1470099): Status Report: Elapsed Time: '21927.000000'
2022-11-28 22:36:14 (1470099): Status Report: CPU Time: '24855.670000'
2022-11-28 22:36:14 (1470099): Preference change detected
2022-11-28 22:36:14 (1470099): Setting CPU throttle for VM. (100%)
2022-11-28 22:36:14 (1470099): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2022-11-28 22:36:16 (1470099): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032
2022-11-28 22:36:16 (1470099): Guest Log: BIOS: Booting from Hard Disk...
2022-11-28 22:36:19 (1470099): Guest Log: BIOS: KBD: unsupported int 16h function 03
2022-11-28 22:36:19 (1470099): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 
2022-11-28 22:37:03 (1470099): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds
2022-11-28 22:37:03 (1470099): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000)
2022-11-28 22:37:07 (1470099): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log
2022-11-28 22:37:07 (1470099): Guest Log: 00:00:00.000189 main     Log opened 2022-11-28T21:37:07.250531000Z
2022-11-28 22:37:07 (1470099): Guest Log: 00:00:00.000362 main     OS Product: Linux
2022-11-28 22:37:07 (1470099): Guest Log: 00:00:00.000418 main     OS Release: 4.14.232-19.cernvm.x86_64
2022-11-28 22:37:07 (1470099): Guest Log: 00:00:00.000477 main     OS Version: #1 SMP Fri Apr 30 17:12:25 CEST 2021
2022-11-28 22:37:07 (1470099): Guest Log: 00:00:00.000530 main     Executable: /usr/sbin/VBoxService
2022-11-28 22:37:07 (1470099): Guest Log: 00:00:00.000532 main     Process ID: 2719
2022-11-28 22:37:07 (1470099): Guest Log: 00:00:00.000533 main     Package type: LINUX_64BITS_GENERIC
2022-11-28 22:37:07 (1470099): Guest Log: 00:00:00.005167 main     5.2.6 r120293 started. Verbose level = 0
2022-11-28 22:37:32 (1470099): Guest Log: [INFO] Mounting the shared directory
2022-11-28 22:37:32 (1470099): Guest Log: [INFO] Shared directory mounted, enabling vboxmonitor
2022-11-28 22:37:33 (1470099): Guest Log: [INFO] Sourcing essential functions from /cvmfs/grid.cern.ch
2022-11-28 22:37:33 (1470099): Guest Log: [INFO] Testing connection to cern.ch
2022-11-28 22:37:33 (1470099): Guest Log: [INFO] Testing connection to VCCS
2022-11-28 22:37:34 (1470099): Guest Log: [INFO] Testing connection to HTCondor
2022-11-28 22:37:34 (1470099): Guest Log: [INFO] Testing connection to WMAgent
2022-11-28 22:37:34 (1470099): Guest Log: [INFO] Testing connection to EOSCMS
2022-11-28 22:37:35 (1470099): Guest Log: [INFO] Testing connection to CMS-Factory
2022-11-28 22:37:35 (1470099): Guest Log: [INFO] Testing connection to CMS-Frontier
2022-11-28 22:37:36 (1470099): Guest Log: [INFO] Testing connection to Frontier
2022-11-28 22:37:37 (1470099): Guest Log: [INFO] Could not find a local HTTP proxy
2022-11-28 22:37:37 (1470099): Guest Log: [INFO] CVMFS and Frontier will have to use DIRECT connections
2022-11-28 22:37:37 (1470099): Guest Log: [INFO] This makes the application less efficient
2022-11-28 22:37:37 (1470099): Guest Log: [INFO] It also puts higher load on the project servers
2022-11-28 22:37:37 (1470099): Guest Log: [INFO] Setting up a local HTTP proxy is highly recommended
2022-11-28 22:37:38 (1470099): Guest Log: [INFO] Advice can be found in the project forum
2022-11-28 22:37:38 (1470099): Guest Log: [INFO] Reloading and probing the CVMFS configuration
2022-11-28 22:37:49 (1470099): Guest Log: [INFO] Probing /cvmfs/grid.cern.ch... OK
2022-11-28 22:37:53 (1470099): Guest Log: [INFO] Probing /cvmfs/oasis.opensciencegrid.org... OK
2022-11-28 22:37:54 (1470099): Guest Log: [INFO] Probing /cvmfs/singularity.opensciencegrid.org... OK
2022-11-28 22:37:54 (1470099): Guest Log: [INFO] Probing /cvmfs/cms-ib.cern.ch... OK
2022-11-28 22:37:54 (1470099): Guest Log: [INFO] Probing /cvmfs/cms.cern.ch... OK
2022-11-28 22:37:56 (1470099): Guest Log: [INFO] Excerpt from "cvmfs_config stat": VERSION HOST PROXY
2022-11-28 22:37:57 (1470099): Guest Log: [INFO] 2.7.2.0 http://s1ral-cvmfs.openhtc.io DIRECT
2022-11-28 22:37:57 (1470099): Guest Log: [INFO] Environment HTTP proxy: not set
2022-11-28 22:37:57 (1470099): Guest Log: [INFO] Reading volunteer information
2022-11-28 22:38:00 (1470099): Guest Log: [INFO] Requesting an X509 credential from LHC@home
2022-11-28 22:38:01 (1470099): Guest Log: [INFO] Requesting an X509 credential from vLHC@home-dev
2022-11-28 22:38:03 (1470099): Guest Log: [INFO] CMS application starting. Check log files.
2022-11-28 22:38:04 (1470099): Guest Log: [INFO] Requesting an idtoken from LHC@home
2022-11-28 22:38:04 (1470099): Guest Log: [INFO] Requesting an idtoken from vLHC@home-dev
2022-11-28 23:37:01 (1470099): Stopping VM.
2022-11-28 23:37:51 (1470099): Successfully stopped VM.
2022-11-29 01:00:31 (1530022): Detected: vboxwrapper 26206
2022-11-29 01:00:31 (1530022): Detected: BOINC client v7.16.11
2022-11-29 01:00:31 (1530022): Detected: VirtualBox VboxManage Interface (Version: 6.1.26)
2022-11-29 01:00:31 (1530022): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2022-11-29 01:00:31 (1530022): Guest Log: BIOS: VirtualBox 6.1.25
2022-11-29 01:00:31 (1530022): Guest Log: CPUID EDX: 0x178bfbff
2022-11-29 01:00:31 (1530022): Guest Log: BIOS: No PCI IDE controller, not probing IDE
2022-11-29 01:00:31 (1530022): Guest Log: BIOS: AHCI 0-P#0: PCHS=16383/16/63 LCHS=1024/255/63 0x0000000002800000 sectors
2022-11-29 01:00:31 (1530022): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032
2022-11-29 01:00:31 (1530022): Guest Log: BIOS: Booting from Hard Disk...
2022-11-29 01:00:31 (1530022): Guest Log: BIOS: KBD: unsupported int 16h function 03
2022-11-29 01:00:31 (1530022): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 
2022-11-29 01:00:31 (1530022): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds
2022-11-29 01:00:31 (1530022): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000)
2022-11-29 01:00:31 (1530022): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log
2022-11-29 01:00:31 (1530022): Guest Log: 00:00:00.000189 main     Log opened 2022-11-28T21:37:07.250531000Z
2022-11-29 01:00:31 (1530022): Guest Log: 00:00:00.000362 main     OS Product: Linux
2022-11-29 01:00:31 (1530022): Guest Log: 00:00:00.000418 main     OS Release: 4.14.232-19.cernvm.x86_64
2022-11-29 01:00:31 (1530022): Guest Log: 00:00:00.000477 main     OS Version: #1 SMP Fri Apr 30 17:12:25 CEST 2021
2022-11-29 01:00:31 (1530022): Guest Log: 00:00:00.000530 main     Executable: /usr/sbin/VBoxService
2022-11-29 01:00:31 (1530022): Guest Log: 00:00:00.000532 main     Process ID: 2719
2022-11-29 01:00:31 (1530022): Guest Log: 00:00:00.000533 main     Package type: LINUX_64BITS_GENERIC
2022-11-29 01:00:31 (1530022): Guest Log: 00:00:00.005167 main     5.2.6 r120293 started. Verbose level = 0
2022-11-29 01:00:31 (1530022): Guest Log: [INFO] Mounting the shared directory
2022-11-29 01:00:31 (1530022): Guest Log: [INFO] Shared directory mounted, enabling vboxmonitor
2022-11-29 01:00:31 (1530022): Guest Log: [INFO] Sourcing essential functions from /cvmfs/grid.cern.ch
2022-11-29 01:00:31 (1530022): Guest Log: [INFO] Testing connection to cern.ch
2022-11-29 01:00:31 (1530022): Guest Log: [INFO] Testing connection to VCCS
2022-11-29 01:00:31 (1530022): Guest Log: [INFO] Testing connection to HTCondor
2022-11-29 01:00:31 (1530022): Guest Log: [INFO] Testing connection to WMAgent
2022-11-29 01:00:31 (1530022): Guest Log: [INFO] Testing connection to EOSCMS
2022-11-29 01:00:31 (1530022): Guest Log: [INFO] Testing connection to CMS-Factory
2022-11-29 01:00:31 (1530022): Guest Log: [INFO] Testing connection to CMS-Frontier
2022-11-29 01:00:31 (1530022): Guest Log: [INFO] Testing connection to Frontier
2022-11-29 01:00:31 (1530022): Guest Log: [INFO] Could not find a local HTTP proxy
2022-11-29 01:00:31 (1530022): Guest Log: [INFO] CVMFS and Frontier will have to use DIRECT connections
2022-11-29 01:00:31 (1530022): Guest Log: [INFO] This makes the application less efficient
2022-11-29 01:00:31 (1530022): Guest Log: [INFO] It also puts higher load on the project servers
2022-11-29 01:00:31 (1530022): Guest Log: [INFO] Setting up a local HTTP proxy is highly recommended
2022-11-29 01:00:31 (1530022): Guest Log: [INFO] Advice can be found in the project forum
2022-11-29 01:00:31 (1530022): Guest Log: [INFO] Reloading and probing the CVMFS configuration
2022-11-29 01:00:31 (1530022): Guest Log: [INFO] Probing /cvmfs/grid.cern.ch... OK
2022-11-29 01:00:31 (1530022): Guest Log: [INFO] Probing /cvmfs/oasis.opensciencegrid.org... OK
2022-11-29 01:00:31 (1530022): Guest Log: [INFO] Probing /cvmfs/singularity.opensciencegrid.org... OK
2022-11-29 01:00:31 (1530022): Guest Log: [INFO] Probing /cvmfs/cms-ib.cern.ch... OK
2022-11-29 01:00:31 (1530022): Guest Log: [INFO] Probing /cvmfs/cms.cern.ch... OK
2022-11-29 01:00:31 (1530022): Guest Log: [INFO] Excerpt from "cvmfs_config stat": VERSION HOST PROXY
2022-11-29 01:00:31 (1530022): Guest Log: [INFO] 2.7.2.0 http://s1ral-cvmfs.openhtc.io DIRECT
2022-11-29 01:00:31 (1530022): Guest Log: [INFO] Environment HTTP proxy: not set
2022-11-29 01:00:31 (1530022): Guest Log: [INFO] Reading volunteer information
2022-11-29 01:00:31 (1530022): Guest Log: [INFO] Requesting an X509 credential from LHC@home
2022-11-29 01:00:31 (1530022): Guest Log: [INFO] Requesting an X509 credential from vLHC@home-dev
2022-11-29 01:00:31 (1530022): Guest Log: [INFO] CMS application starting. Check log files.
2022-11-29 01:00:31 (1530022): Guest Log: [INFO] Requesting an idtoken from LHC@home
2022-11-29 01:00:31 (1530022): Guest Log: [INFO] Requesting an idtoken from vLHC@home-dev
2022-11-29 01:00:31 (1530022): Starting VM using VBoxManage interface. (boinc_6ad8266593738b76, slot#11)
2022-11-29 01:00:44 (1530022): Successfully started VM. (PID = '1530120')
2022-11-29 01:00:44 (1530022): Reporting VM Process ID to BOINC.
2022-11-29 01:00:44 (1530022): VM state change detected. (old = 'poweredoff', new = 'running')
2022-11-29 01:00:44 (1530022): Detected: Web Application Enabled (http://localhost:49093)
2022-11-29 01:00:44 (1530022): Status Report: Job Duration: '64800.000000'
2022-11-29 01:00:44 (1530022): Status Report: Elapsed Time: '25611.000000'
2022-11-29 01:00:44 (1530022): Status Report: CPU Time: '29684.740000'
2022-11-29 01:00:44 (1530022): Preference change detected
2022-11-29 01:00:44 (1530022): Setting CPU throttle for VM. (100%)
2022-11-29 01:00:44 (1530022): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2022-11-29 01:00:48 (1530022): Guest Log: 01:00:02.893532 timesync vgsvcTimeSyncWorker: Radical host time change: 5 031 551 000 000ns (HostNow=1 669 680 047 796 000 000 ns HostLast=1 669 675 016 245 000 000 ns)
2022-11-29 01:00:58 (1530022): Guest Log: 01:00:12.897992 timesync vgsvcTimeSyncWorker: Radical guest time change: 5 027 657 954 000ns (GuestNow=1 669 680 057 801 108 000 ns GuestLast=1 669 675 030 143 154 000 ns fSetTimeLastLoop=true )
2022-11-29 02:00:52 (1530022): Stopping VM.
2022-11-29 02:01:49 (1530022): Successfully stopped VM.
2022-11-29 03:01:19 (1579721): Detected: vboxwrapper 26206
2022-11-29 03:01:19 (1579721): Detected: BOINC client v7.16.11
2022-11-29 03:01:20 (1579721): Detected: VirtualBox VboxManage Interface (Version: 6.1.26)
2022-11-29 03:01:20 (1579721): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2022-11-29 03:01:20 (1579721): Guest Log: 01:00:02.893532 timesync vgsvcTimeSyncWorker: Radical host time change: 5 031 551 000 000ns (HostNow=1 669 680 047 796 000 000 ns HostLast=1 669 675 016 245 000 000 ns)
2022-11-29 03:01:20 (1579721): Guest Log: 01:00:12.897992 timesync vgsvcTimeSyncWorker: Radical guest time change: 5 027 657 954 000ns (GuestNow=1 669 680 057 801 108 000 ns GuestLast=1 669 675 030 143 154 000 ns fSetTimeLastLoop=true )
2022-11-29 03:01:20 (1579721): Starting VM using VBoxManage interface. (boinc_6ad8266593738b76, slot#11)
2022-11-29 03:01:33 (1579721): Successfully started VM. (PID = '1579848')
2022-11-29 03:01:33 (1579721): Reporting VM Process ID to BOINC.
2022-11-29 03:01:33 (1579721): VM state change detected. (old = 'poweredoff', new = 'running')
2022-11-29 03:01:33 (1579721): Detected: Web Application Enabled (http://localhost:49093)
2022-11-29 03:01:33 (1579721): Status Report: Job Duration: '64800.000000'
2022-11-29 03:01:33 (1579721): Status Report: Elapsed Time: '29255.000000'
2022-11-29 03:01:33 (1579721): Status Report: CPU Time: '33992.180000'
2022-11-29 03:01:33 (1579721): Preference change detected
2022-11-29 03:01:33 (1579721): Setting CPU throttle for VM. (100%)
2022-11-29 03:01:33 (1579721): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2022-11-29 03:01:36 (1579721): Guest Log: 02:00:15.921411 timesync vgsvcTimeSyncWorker: Radical host time change: 3 649 495 000 000ns (HostNow=1 669 687 296 421 000 000 ns HostLast=1 669 683 646 926 000 000 ns)
2022-11-29 03:01:47 (1579721): Guest Log: 02:00:25.940922 timesync vgsvcTimeSyncWorker: Radical guest time change: 3 645 616 894 000ns (GuestNow=1 669 687 306 440 947 000 ns GuestLast=1 669 683 660 824 053 000 ns fSetTimeLastLoop=true )
2022-11-29 04:01:41 (1579721): Stopping VM.
2022-11-29 04:02:39 (1579721): Successfully stopped VM.
2022-11-29 05:25:39 (1638789): Detected: vboxwrapper 26206
2022-11-29 05:25:39 (1638789): Detected: BOINC client v7.16.11
2022-11-29 05:25:39 (1638789): Detected: VirtualBox VboxManage Interface (Version: 6.1.26)
2022-11-29 05:25:39 (1638789): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2022-11-29 05:25:40 (1638789): Guest Log: 02:00:15.921411 timesync vgsvcTimeSyncWorker: Radical host time change: 3 649 495 000 000ns (HostNow=1 669 687 296 421 000 000 ns HostLast=1 669 683 646 926 000 000 ns)
2022-11-29 05:25:40 (1638789): Guest Log: 02:00:25.940922 timesync vgsvcTimeSyncWorker: Radical guest time change: 3 645 616 894 000ns (GuestNow=1 669 687 306 440 947 000 ns GuestLast=1 669 683 660 824 053 000 ns fSetTimeLastLoop=true )
2022-11-29 05:25:40 (1638789): Starting VM using VBoxManage interface. (boinc_6ad8266593738b76, slot#11)
2022-11-29 05:25:53 (1638789): Successfully started VM. (PID = '1638887')
2022-11-29 05:25:53 (1638789): Reporting VM Process ID to BOINC.
2022-11-29 05:25:53 (1638789): VM state change detected. (old = 'poweredoff', new = 'running')
2022-11-29 05:25:53 (1638789): Detected: Web Application Enabled (http://localhost:49093)
2022-11-29 05:25:53 (1638789): Status Report: Job Duration: '64800.000000'
2022-11-29 05:25:53 (1638789): Status Report: Elapsed Time: '32899.000000'
2022-11-29 05:25:53 (1638789): Status Report: CPU Time: '37675.690000'
2022-11-29 05:25:53 (1638789): Preference change detected
2022-11-29 05:25:53 (1638789): Setting CPU throttle for VM. (100%)
2022-11-29 05:25:53 (1638789): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2022-11-29 05:25:56 (1638789): Guest Log: 03:00:28.573891 timesync vgsvcTimeSyncWorker: Radical host time change: 5 060 131 000 000ns (HostNow=1 669 695 955 325 000 000 ns HostLast=1 669 690 895 194 000 000 ns)
2022-11-29 05:26:06 (1638789): Guest Log: 03:00:38.575088 timesync vgsvcTimeSyncWorker: Radical guest time change: 5 056 255 772 000ns (GuestNow=1 669 695 965 327 943 000 ns GuestLast=1 669 690 909 072 171 000 ns fSetTimeLastLoop=true )
2022-11-29 06:25:56 (1638789): Stopping VM.
2022-11-29 06:26:55 (1638789): Successfully stopped VM.
2022-11-29 07:26:15 (1688498): Detected: vboxwrapper 26206
2022-11-29 07:26:15 (1688498): Detected: BOINC client v7.16.11
2022-11-29 07:26:15 (1688498): Detected: VirtualBox VboxManage Interface (Version: 6.1.26)
2022-11-29 07:26:15 (1688498): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2022-11-29 07:26:16 (1688498): Guest Log: 03:00:28.573891 timesync vgsvcTimeSyncWorker: Radical host time change: 5 060 131 000 000ns (HostNow=1 669 695 955 325 000 000 ns HostLast=1 669 690 895 194 000 000 ns)
2022-11-29 07:26:16 (1688498): Guest Log: 03:00:38.575088 timesync vgsvcTimeSyncWorker: Radical guest time change: 5 056 255 772 000ns (GuestNow=1 669 695 965 327 943 000 ns GuestLast=1 669 690 909 072 171 000 ns fSetTimeLastLoop=true )
2022-11-29 07:26:16 (1688498): Starting VM using VBoxManage interface. (boinc_6ad8266593738b76, slot#11)
2022-11-29 07:26:38 (1688498): Successfully started VM. (PID = '1688599')
2022-11-29 07:26:38 (1688498): Reporting VM Process ID to BOINC.
2022-11-29 07:26:38 (1688498): VM state change detected. (old = 'poweredoff', new = 'running')
2022-11-29 07:26:38 (1688498): Detected: Web Application Enabled (http://localhost:49093)
2022-11-29 07:26:38 (1688498): Status Report: Job Duration: '64800.000000'
2022-11-29 07:26:38 (1688498): Status Report: Elapsed Time: '36538.000000'
2022-11-29 07:26:38 (1688498): Status Report: CPU Time: '41899.430000'
2022-11-29 07:26:38 (1688498): Preference change detected
2022-11-29 07:26:38 (1688498): Setting CPU throttle for VM. (100%)
2022-11-29 07:26:38 (1688498): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2022-11-29 07:26:44 (1688498): Guest Log: 04:00:40.138478 timesync vgsvcTimeSyncWorker: Radical host time change: 3 650 344 000 000ns (HostNow=1 669 703 203 356 000 000 ns HostLast=1 669 699 553 012 000 000 ns)
2022-11-29 07:26:54 (1688498): Guest Log: 04:00:50.140529 timesync vgsvcTimeSyncWorker: Radical guest time change: 3 646 482 311 000ns (GuestNow=1 669 703 213 365 843 000 ns GuestLast=1 669 699 566 883 532 000 ns fSetTimeLastLoop=true )
2022-11-29 08:26:21 (1688498): Stopping VM.
2022-11-29 09:26:50 (1739121): Detected: vboxwrapper 26206
2022-11-29 09:26:50 (1739121): Detected: BOINC client v7.16.11
2022-11-29 09:26:51 (1739121): Detected: VirtualBox VboxManage Interface (Version: 6.1.26)
2022-11-29 09:26:51 (1739121): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2022-11-29 09:26:51 (1739121): Guest Log: 04:00:40.138478 timesync vgsvcTimeSyncWorker: Radical host time change: 3 650 344 000 000ns (HostNow=1 669 703 203 356 000 000 ns HostLast=1 669 699 553 012 000 000 ns)
2022-11-29 09:26:51 (1739121): Guest Log: 04:00:50.140529 timesync vgsvcTimeSyncWorker: Radical guest time change: 3 646 482 311 000ns (GuestNow=1 669 703 213 365 843 000 ns GuestLast=1 669 699 566 883 532 000 ns fSetTimeLastLoop=true )
2022-11-29 09:26:51 (1739121): Starting VM using VBoxManage interface. (boinc_6ad8266593738b76, slot#11)
2022-11-29 09:26:54 (1739121): Successfully started VM. (PID = '1739252')
2022-11-29 09:26:54 (1739121): Reporting VM Process ID to BOINC.
2022-11-29 09:26:54 (1739121): Guest Log: BIOS: VirtualBox 6.1.25
2022-11-29 09:26:54 (1739121): Guest Log: CPUID EDX: 0x178bfbff
2022-11-29 09:26:54 (1739121): Guest Log: BIOS: No PCI IDE controller, not probing IDE
2022-11-29 09:26:54 (1739121): Guest Log: BIOS: AHCI 0-P#0: PCHS=16383/16/63 LCHS=1024/255/63 0x0000000002800000 sectors
2022-11-29 09:26:54 (1739121): VM state change detected. (old = 'poweredoff', new = 'running')
2022-11-29 09:26:54 (1739121): Detected: Web Application Enabled (http://localhost:49093)
2022-11-29 09:26:54 (1739121): Status Report: Job Duration: '64800.000000'
2022-11-29 09:26:54 (1739121): Status Report: Elapsed Time: '40157.000000'
2022-11-29 09:26:54 (1739121): Status Report: CPU Time: '45598.060000'
2022-11-29 09:26:54 (1739121): Preference change detected
2022-11-29 09:26:54 (1739121): Setting CPU throttle for VM. (100%)
2022-11-29 09:26:54 (1739121): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2022-11-29 09:26:56 (1739121): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032
2022-11-29 09:26:56 (1739121): Guest Log: BIOS: Booting from Hard Disk...
2022-11-29 09:26:58 (1739121): Guest Log: BIOS: KBD: unsupported int 16h function 03
2022-11-29 09:26:58 (1739121): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 
2022-11-29 09:27:41 (1739121): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds
2022-11-29 09:27:42 (1739121): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000)
2022-11-29 09:27:45 (1739121): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log
2022-11-29 09:27:45 (1739121): Guest Log: 00:00:00.000135 main     Log opened 2022-11-29T08:27:45.157297000Z
2022-11-29 09:27:45 (1739121): Guest Log: 00:00:00.000281 main     OS Product: Linux
2022-11-29 09:27:45 (1739121): Guest Log: 00:00:00.000331 main     OS Release: 4.14.232-19.cernvm.x86_64
2022-11-29 09:27:45 (1739121): Guest Log: 00:00:00.000366 main     OS Version: #1 SMP Fri Apr 30 17:12:25 CEST 2021
2022-11-29 09:27:45 (1739121): Guest Log: 00:00:00.000399 main     Executable: /usr/sbin/VBoxService
2022-11-29 09:27:45 (1739121): Guest Log: 00:00:00.000400 main     Process ID: 2710
2022-11-29 09:27:45 (1739121): Guest Log: 00:00:00.000401 main     Package type: LINUX_64BITS_GENERIC
2022-11-29 09:27:45 (1739121): Guest Log: 00:00:00.002243 main     5.2.6 r120293 started. Verbose level = 0
2022-11-29 09:28:11 (1739121): Guest Log: [INFO] Mounting the shared directory
2022-11-29 09:28:12 (1739121): Guest Log: [INFO] Shared directory mounted, enabling vboxmonitor
2022-11-29 09:28:12 (1739121): Guest Log: [INFO] Sourcing essential functions from /cvmfs/grid.cern.ch
2022-11-29 09:28:12 (1739121): Guest Log: [INFO] Testing connection to cern.ch
2022-11-29 09:28:13 (1739121): Guest Log: [INFO] Testing connection to VCCS
2022-11-29 09:28:13 (1739121): Guest Log: [INFO] Testing connection to HTCondor
2022-11-29 09:28:14 (1739121): Guest Log: [INFO] Testing connection to WMAgent
2022-11-29 09:28:14 (1739121): Guest Log: [INFO] Testing connection to EOSCMS
2022-11-29 09:28:15 (1739121): Guest Log: [INFO] Testing connection to CMS-Factory
2022-11-29 09:28:15 (1739121): Guest Log: [INFO] Testing connection to CMS-Frontier
2022-11-29 09:28:15 (1739121): Guest Log: [INFO] Testing connection to Frontier
2022-11-29 09:28:17 (1739121): Guest Log: [INFO] Could not find a local HTTP proxy
2022-11-29 09:28:17 (1739121): Guest Log: [INFO] CVMFS and Frontier will have to use DIRECT connections
2022-11-29 09:28:17 (1739121): Guest Log: [INFO] This makes the application less efficient
2022-11-29 09:28:17 (1739121): Guest Log: [INFO] It also puts higher load on the project servers
2022-11-29 09:28:18 (1739121): Guest Log: [INFO] Setting up a local HTTP proxy is highly recommended
2022-11-29 09:28:18 (1739121): Guest Log: [INFO] Advice can be found in the project forum
2022-11-29 09:28:18 (1739121): Guest Log: [INFO] Reloading and probing the CVMFS configuration
2022-11-29 09:28:40 (1739121): Guest Log: [INFO] Probing /cvmfs/grid.cern.ch... OK
2022-11-29 09:28:44 (1739121): Guest Log: [INFO] Probing /cvmfs/cms-ib.cern.ch... OK
2022-11-29 09:28:44 (1739121): Guest Log: [INFO] Probing /cvmfs/oasis.opensciencegrid.org... OK
2022-11-29 09:28:45 (1739121): Guest Log: [INFO] Probing /cvmfs/cms.cern.ch... OK
2022-11-29 09:28:45 (1739121): Guest Log: [INFO] Probing /cvmfs/singularity.opensciencegrid.org... OK
2022-11-29 09:28:47 (1739121): Guest Log: [INFO] Excerpt from "cvmfs_config stat": VERSION HOST PROXY
2022-11-29 09:28:47 (1739121): Guest Log: [INFO] 2.7.2.0 http://s1cern-cvmfs.openhtc.io DIRECT
2022-11-29 09:28:47 (1739121): Guest Log: [INFO] Environment HTTP proxy: not set
2022-11-29 09:28:47 (1739121): Guest Log: [INFO] Reading volunteer information
2022-11-29 09:28:50 (1739121): Guest Log: [INFO] Requesting an X509 credential from LHC@home
2022-11-29 09:28:51 (1739121): Guest Log: [INFO] Requesting an X509 credential from vLHC@home-dev
2022-11-29 09:28:52 (1739121): Guest Log: [INFO] CMS application starting. Check log files.
2022-11-29 09:28:52 (1739121): Guest Log: [INFO] Requesting an idtoken from LHC@home
2022-11-29 09:28:53 (1739121): Guest Log: [INFO] Requesting an idtoken from vLHC@home-dev
2022-11-29 10:27:06 (1739121): Stopping VM.
2022-11-29 10:28:00 (1739121): Successfully stopped VM.
2022-12-02 20:37:27 (3821932): Detected: vboxwrapper 26206
2022-12-02 20:37:27 (3821932): Detected: BOINC client v7.16.11
2022-12-02 20:37:27 (3821932): Detected: VirtualBox VboxManage Interface (Version: 6.1.26)
2022-12-02 20:37:27 (3821932): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2022-12-02 20:37:27 (3821932): Guest Log: BIOS: VirtualBox 6.1.25
2022-12-02 20:37:27 (3821932): Guest Log: CPUID EDX: 0x178bfbff
2022-12-02 20:37:27 (3821932): Guest Log: BIOS: No PCI IDE controller, not probing IDE
2022-12-02 20:37:27 (3821932): Guest Log: BIOS: AHCI 0-P#0: PCHS=16383/16/63 LCHS=1024/255/63 0x0000000002800000 sectors
2022-12-02 20:37:27 (3821932): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032
2022-12-02 20:37:27 (3821932): Guest Log: BIOS: Booting from Hard Disk...
2022-12-02 20:37:27 (3821932): Guest Log: BIOS: KBD: unsupported int 16h function 03
2022-12-02 20:37:27 (3821932): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 
2022-12-02 20:37:27 (3821932): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds
2022-12-02 20:37:27 (3821932): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000)
2022-12-02 20:37:27 (3821932): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log
2022-12-02 20:37:27 (3821932): Guest Log: 00:00:00.000135 main     Log opened 2022-11-29T08:27:45.157297000Z
2022-12-02 20:37:27 (3821932): Guest Log: 00:00:00.000281 main     OS Product: Linux
2022-12-02 20:37:27 (3821932): Guest Log: 00:00:00.000331 main     OS Release: 4.14.232-19.cernvm.x86_64
2022-12-02 20:37:27 (3821932): Guest Log: 00:00:00.000366 main     OS Version: #1 SMP Fri Apr 30 17:12:25 CEST 2021
2022-12-02 20:37:27 (3821932): Guest Log: 00:00:00.000399 main     Executable: /usr/sbin/VBoxService
2022-12-02 20:37:27 (3821932): Guest Log: 00:00:00.000400 main     Process ID: 2710
2022-12-02 20:37:27 (3821932): Guest Log: 00:00:00.000401 main     Package type: LINUX_64BITS_GENERIC
2022-12-02 20:37:27 (3821932): Guest Log: 00:00:00.002243 main     5.2.6 r120293 started. Verbose level = 0
2022-12-02 20:37:27 (3821932): Guest Log: [INFO] Mounting the shared directory
2022-12-02 20:37:27 (3821932): Guest Log: [INFO] Shared directory mounted, enabling vboxmonitor
2022-12-02 20:37:27 (3821932): Guest Log: [INFO] Sourcing essential functions from /cvmfs/grid.cern.ch
2022-12-02 20:37:27 (3821932): Guest Log: [INFO] Testing connection to cern.ch
2022-12-02 20:37:27 (3821932): Guest Log: [INFO] Testing connection to VCCS
2022-12-02 20:37:27 (3821932): Guest Log: [INFO] Testing connection to HTCondor
2022-12-02 20:37:27 (3821932): Guest Log: [INFO] Testing connection to WMAgent
2022-12-02 20:37:27 (3821932): Guest Log: [INFO] Testing connection to EOSCMS
2022-12-02 20:37:27 (3821932): Guest Log: [INFO] Testing connection to CMS-Factory
2022-12-02 20:37:27 (3821932): Guest Log: [INFO] Testing connection to CMS-Frontier
2022-12-02 20:37:27 (3821932): Guest Log: [INFO] Testing connection to Frontier
2022-12-02 20:37:27 (3821932): Guest Log: [INFO] Could not find a local HTTP proxy
2022-12-02 20:37:27 (3821932): Guest Log: [INFO] CVMFS and Frontier will have to use DIRECT connections
2022-12-02 20:37:27 (3821932): Guest Log: [INFO] This makes the application less efficient
2022-12-02 20:37:27 (3821932): Guest Log: [INFO] It also puts higher load on the project servers
2022-12-02 20:37:27 (3821932): Guest Log: [INFO] Setting up a local HTTP proxy is highly recommended
2022-12-02 20:37:27 (3821932): Guest Log: [INFO] Advice can be found in the project forum
2022-12-02 20:37:27 (3821932): Guest Log: [INFO] Reloading and probing the CVMFS configuration
2022-12-02 20:37:27 (3821932): Guest Log: [INFO] Probing /cvmfs/grid.cern.ch... OK
2022-12-02 20:37:27 (3821932): Guest Log: [INFO] Probing /cvmfs/cms-ib.cern.ch... OK
2022-12-02 20:37:27 (3821932): Guest Log: [INFO] Probing /cvmfs/oasis.opensciencegrid.org... OK
2022-12-02 20:37:27 (3821932): Guest Log: [INFO] Probing /cvmfs/cms.cern.ch... OK
2022-12-02 20:37:27 (3821932): Guest Log: [INFO] Probing /cvmfs/singularity.opensciencegrid.org... OK
2022-12-02 20:37:27 (3821932): Guest Log: [INFO] Excerpt from "cvmfs_config stat": VERSION HOST PROXY
2022-12-02 20:37:27 (3821932): Guest Log: [INFO] 2.7.2.0 http://s1cern-cvmfs.openhtc.io DIRECT
2022-12-02 20:37:27 (3821932): Guest Log: [INFO] Environment HTTP proxy: not set
2022-12-02 20:37:27 (3821932): Guest Log: [INFO] Reading volunteer information
2022-12-02 20:37:27 (3821932): Guest Log: [INFO] Requesting an X509 credential from LHC@home
2022-12-02 20:37:27 (3821932): Guest Log: [INFO] Requesting an X509 credential from vLHC@home-dev
2022-12-02 20:37:27 (3821932): Guest Log: [INFO] CMS application starting. Check log files.
2022-12-02 20:37:27 (3821932): Guest Log: [INFO] Requesting an idtoken from LHC@home
2022-12-02 20:37:27 (3821932): Guest Log: [INFO] Requesting an idtoken from vLHC@home-dev
2022-12-02 20:37:27 (3821932): Starting VM using VBoxManage interface. (boinc_6ad8266593738b76, slot#11)
2022-12-02 20:37:58 (3821932): Successfully started VM. (PID = '3822039')
2022-12-02 20:37:58 (3821932): Reporting VM Process ID to BOINC.
2022-12-02 20:37:58 (3821932): VM state change detected. (old = 'poweredoff', new = 'running')
2022-12-02 20:37:58 (3821932): Detected: Web Application Enabled (http://localhost:49093)
2022-12-02 20:37:58 (3821932): Status Report: Job Duration: '64800.000000'
2022-12-02 20:37:58 (3821932): Status Report: Elapsed Time: '43805.000000'
2022-12-02 20:37:58 (3821932): Status Report: CPU Time: '50360.570000'
2022-12-02 20:37:58 (3821932): Preference change detected
2022-12-02 20:37:58 (3821932): Setting CPU throttle for VM. (100%)
2022-12-02 20:37:58 (3821932): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2022-12-02 20:38:04 (3821932): Guest Log: 00:59:31.918841 timesync vgsvcTimeSyncWorker: Radical host time change: 295 860 608 000 000ns (HostNow=1 670 009 883 832 000 000 ns HostLast=1 669 714 023 224 000 000 ns)
2022-12-02 20:38:14 (3821932): Guest Log: 00:59:41.920929 timesync vgsvcTimeSyncWorker: Radical guest time change: 295 856 763 660 000ns (GuestNow=1 670 009 893 836 808 000 ns GuestLast=1 669 714 037 073 148 000 ns fSetTimeLastLoop=true )
2022-12-02 20:43:23 (3821932): Guest Log: [INFO] glidein exited with return value 0.
2022-12-02 20:43:23 (3821932): Guest Log: [INFO] Shutting Down.
2022-12-02 20:43:23 (3821932): VM Completion File Detected.
2022-12-02 20:43:23 (3821932): VM Completion Message: glidein exited with return value 0.
.
2022-12-02 20:43:23 (3821932): Powering off VM.
2022-12-02 20:43:23 (3821932): Successfully stopped VM.
2022-12-02 20:43:23 (3821932): Deregistering VM. (boinc_6ad8266593738b76, slot#11)
2022-12-02 20:43:23 (3821932): Removing network bandwidth throttle group from VM.
2022-12-02 20:43:23 (3821932): Removing VM from VirtualBox.
20:43:28 (3821932): called boinc_finish(0)

</stderr_txt>
]]>

©2025 CERN