Stderr output

<core_client_version>7.16.11</core_client_version>
<![CDATA[
<stderr_txt>
2022-07-27 06:28:40 (3226659): Detected: vboxwrapper 26205
2022-07-27 06:28:40 (3226659): Detected: BOINC client v7.16.11
2022-07-27 06:28:40 (3226659): Detected: VirtualBox VboxManage Interface (Version: 6.1.26)
2022-07-27 06:28:41 (3226659): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2022-07-27 06:28:41 (3226659): Successfully copied 'init_data.xml' to the shared directory.
2022-07-27 06:28:42 (3226659): Create VM. (boinc_eeb043dd351aeb97, slot#10)
2022-07-27 06:28:47 (3226659): Setting Memory Size for VM. (15336MB)
2022-07-27 06:28:48 (3226659): Setting CPU Count for VM. (16)
2022-07-27 06:28:48 (3226659): Setting Chipset Options for VM.
2022-07-27 06:28:49 (3226659): Setting Graphics Controller Options for VM.
2022-07-27 06:28:50 (3226659): Setting Boot Options for VM.
2022-07-27 06:28:50 (3226659): Setting Network Configuration for NAT.
2022-07-27 06:28:50 (3226659): Enabling VM Network Access.
2022-07-27 06:28:51 (3226659): Disabling USB Support for VM.
2022-07-27 06:28:51 (3226659): Disabling COM Port Support for VM.
2022-07-27 06:28:51 (3226659): Disabling LPT Port Support for VM.
2022-07-27 06:28:51 (3226659): Disabling Audio Support for VM.
2022-07-27 06:28:51 (3226659): Disabling Clipboard Support for VM.
2022-07-27 06:28:51 (3226659): Disabling Drag and Drop Support for VM.
2022-07-27 06:28:51 (3226659): Adding storage controller(s) to VM.
2022-07-27 06:28:51 (3226659): Adding virtual disk drive to VM. (CMS_2022_07_25.vdi)
2022-07-27 06:28:52 (3226659): Adding network bandwidth throttle group to VM. (Defaulting to 1024GB)
2022-07-27 06:28:52 (3226659): forwarding host port 54747 to guest port 80
2022-07-27 06:28:52 (3226659): Enabling remote desktop for VM.
2022-07-27 06:28:52 (3226659): Required extension pack not installed, remote desktop not enabled.
2022-07-27 06:28:52 (3226659): Enabling shared directory for VM.
2022-07-27 06:28:52 (3226659): Starting VM using VBoxManage interface. (boinc_eeb043dd351aeb97, slot#10)
2022-07-27 06:28:53 (3226659): Successfully started VM. (PID = '3227204')
2022-07-27 06:28:53 (3226659): Reporting VM Process ID to BOINC.
2022-07-27 06:28:53 (3226659): Guest Log: BIOS: VirtualBox 6.1.25
2022-07-27 06:28:53 (3226659): Guest Log: CPUID EDX: 0x178bfbff
2022-07-27 06:28:53 (3226659): Guest Log: BIOS: No PCI IDE controller, not probing IDE
2022-07-27 06:28:53 (3226659): Guest Log: BIOS: AHCI 0-P#0: PCHS=16383/16/63 LCHS=1024/255/63 0x0000000002800000 sectors
2022-07-27 06:28:53 (3226659): VM state change detected. (old = 'poweredoff', new = 'running')
2022-07-27 06:28:53 (3226659): Detected: Web Application Enabled (http://localhost:54747)
2022-07-27 06:28:53 (3226659): Preference change detected
2022-07-27 06:28:53 (3226659): Setting CPU throttle for VM. (100%)
2022-07-27 06:28:54 (3226659): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2022-07-27 06:28:55 (3226659): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032
2022-07-27 06:28:55 (3226659): Guest Log: BIOS: Booting from Hard Disk...
2022-07-27 06:28:58 (3226659): Guest Log: BIOS: KBD: unsupported int 16h function 03
2022-07-27 06:28:58 (3226659): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 
2022-07-27 06:29:44 (3226659): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds
2022-07-27 06:29:44 (3226659): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000)
2022-07-27 06:29:48 (3226659): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log
2022-07-27 06:29:48 (3226659): Guest Log: 00:00:00.000321 main     Log opened 2022-07-27T04:29:47.937623000Z
2022-07-27 06:29:48 (3226659): Guest Log: 00:00:00.000549 main     OS Product: Linux
2022-07-27 06:29:48 (3226659): Guest Log: 00:00:00.000604 main     OS Release: 4.14.232-19.cernvm.x86_64
2022-07-27 06:29:48 (3226659): Guest Log: 00:00:00.000647 main     OS Version: #1 SMP Fri Apr 30 17:12:25 CEST 2021
2022-07-27 06:29:48 (3226659): Guest Log: 00:00:00.000737 main     Executable: /usr/sbin/VBoxService
2022-07-27 06:29:48 (3226659): Guest Log: 00:00:00.000738 main     Process ID: 2749
2022-07-27 06:29:48 (3226659): Guest Log: 00:00:00.000739 main     Package type: LINUX_64BITS_GENERIC
2022-07-27 06:29:48 (3226659): Guest Log: 00:00:00.003015 main     5.2.6 r120293 started. Verbose level = 0
2022-07-27 06:30:25 (3226659): Guest Log: [INFO] Mounting the shared directory
2022-07-27 06:30:25 (3226659): Guest Log: [INFO] Shared directory mounted, enabling vboxmonitor
2022-07-27 06:30:25 (3226659): Guest Log: [INFO] Sourcing essential functions from /cvmfs/grid.cern.ch
2022-07-27 06:30:25 (3226659): Guest Log: [INFO] Testing connection to cern.ch
2022-07-27 06:30:26 (3226659): Guest Log: [INFO] Testing connection to VCCS
2022-07-27 06:30:26 (3226659): Guest Log: [INFO] Testing connection to HTCondor
2022-07-27 06:30:27 (3226659): Guest Log: [INFO] Testing connection to WMAgent
2022-07-27 06:30:27 (3226659): Guest Log: [INFO] Testing connection to EOSCMS
2022-07-27 06:30:28 (3226659): Guest Log: [INFO] Testing connection to CMS-Frontier
2022-07-27 06:30:28 (3226659): Guest Log: [INFO] Testing connection to Frontier
2022-07-27 06:30:29 (3226659): Guest Log: [INFO] Could not find a local HTTP proxy
2022-07-27 06:30:29 (3226659): Guest Log: [INFO] CVMFS and Frontier will have to use DIRECT connections
2022-07-27 06:30:29 (3226659): Guest Log: [INFO] This makes the application less efficient
2022-07-27 06:30:30 (3226659): Guest Log: [INFO] It also puts higher load on the project servers
2022-07-27 06:30:30 (3226659): Guest Log: [INFO] Setting up a local HTTP proxy is highly recommended
2022-07-27 06:30:30 (3226659): Guest Log: [INFO] Advice can be found in the project forum
2022-07-27 06:30:30 (3226659): Guest Log: [INFO] Reloading and probing the CVMFS configuration
2022-07-27 06:30:36 (3226659): Guest Log: [INFO] Probing /cvmfs/cvmfs-config.cern.ch... OK
2022-07-27 06:30:36 (3226659): Guest Log: [INFO] Probing /cvmfs/grid.cern.ch... OK
2022-07-27 06:30:40 (3226659): Guest Log: [INFO] Probing /cvmfs/singularity.opensciencegrid.org... OK
2022-07-27 06:30:40 (3226659): Guest Log: [INFO] Probing /cvmfs/oasis.opensciencegrid.org... OK
2022-07-27 06:30:40 (3226659): Guest Log: [INFO] Probing /cvmfs/cms-ib.cern.ch... OK
2022-07-27 06:30:41 (3226659): Guest Log: [INFO] Probing /cvmfs/cms.cern.ch... OK
2022-07-27 06:30:43 (3226659): Guest Log: [INFO] Excerpt from "cvmfs_config stat": VERSION HOST PROXY
2022-07-27 06:30:43 (3226659): Guest Log: [INFO] 2.7.2.0 http://cvmfs-stratum-one.cern.ch http://128.142.248.156:3125
2022-07-27 06:30:43 (3226659): Guest Log: [INFO] Reading volunteer information
2022-07-27 06:30:47 (3226659): Guest Log: [INFO] Requesting an X509 credential from LHC@home
2022-07-27 06:30:48 (3226659): Guest Log: [INFO] Requesting an X509 credential from vLHC@home-dev
2022-07-27 06:30:49 (3226659): Guest Log: [INFO] CMS application starting. Check log files.
2022-07-27 06:30:50 (3226659): Guest Log: [INFO] Requesting an idtoken from LHC@home
2022-07-27 06:30:51 (3226659): Guest Log: [INFO] Requesting an idtoken from vLHC@home-dev
2022-07-27 07:29:20 (3226659): Stopping VM.
2022-07-27 07:30:09 (3226659): Successfully stopped VM.
2022-07-27 20:52:39 (3667964): Detected: vboxwrapper 26205
2022-07-27 20:52:39 (3667964): Detected: BOINC client v7.16.11
2022-07-27 20:52:40 (3667964): Detected: VirtualBox VboxManage Interface (Version: 6.1.26)
2022-07-27 20:52:40 (3667964): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2022-07-27 20:52:43 (3667964): Guest Log: BIOS: VirtualBox 6.1.25
2022-07-27 20:52:43 (3667964): Guest Log: CPUID EDX: 0x178bfbff
2022-07-27 20:52:43 (3667964): Guest Log: BIOS: No PCI IDE controller, not probing IDE
2022-07-27 20:52:43 (3667964): Guest Log: BIOS: AHCI 0-P#0: PCHS=16383/16/63 LCHS=1024/255/63 0x0000000002800000 sectors
2022-07-27 20:52:43 (3667964): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032
2022-07-27 20:52:43 (3667964): Guest Log: BIOS: Booting from Hard Disk...
2022-07-27 20:52:43 (3667964): Guest Log: BIOS: KBD: unsupported int 16h function 03
2022-07-27 20:52:43 (3667964): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 
2022-07-27 20:52:43 (3667964): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds
2022-07-27 20:52:43 (3667964): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000)
2022-07-27 20:52:43 (3667964): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log
2022-07-27 20:52:43 (3667964): Guest Log: 00:00:00.000321 main     Log opened 2022-07-27T04:29:47.937623000Z
2022-07-27 20:52:43 (3667964): Guest Log: 00:00:00.000549 main     OS Product: Linux
2022-07-27 20:52:43 (3667964): Guest Log: 00:00:00.000604 main     OS Release: 4.14.232-19.cernvm.x86_64
2022-07-27 20:52:43 (3667964): Guest Log: 00:00:00.000647 main     OS Version: #1 SMP Fri Apr 30 17:12:25 CEST 2021
2022-07-27 20:52:43 (3667964): Guest Log: 00:00:00.000737 main     Executable: /usr/sbin/VBoxService
2022-07-27 20:52:43 (3667964): Guest Log: 00:00:00.000738 main     Process ID: 2749
2022-07-27 20:52:43 (3667964): Guest Log: 00:00:00.000739 main     Package type: LINUX_64BITS_GENERIC
2022-07-27 20:52:43 (3667964): Guest Log: 00:00:00.003015 main     5.2.6 r120293 started. Verbose level = 0
2022-07-27 20:52:43 (3667964): Guest Log: [INFO] Mounting the shared directory
2022-07-27 20:52:43 (3667964): Guest Log: [INFO] Shared directory mounted, enabling vboxmonitor
2022-07-27 20:52:43 (3667964): Guest Log: [INFO] Sourcing essential functions from /cvmfs/grid.cern.ch
2022-07-27 20:52:43 (3667964): Guest Log: [INFO] Testing connection to cern.ch
2022-07-27 20:52:43 (3667964): Guest Log: [INFO] Testing connection to VCCS
2022-07-27 20:52:43 (3667964): Guest Log: [INFO] Testing connection to HTCondor
2022-07-27 20:52:43 (3667964): Guest Log: [INFO] Testing connection to WMAgent
2022-07-27 20:52:43 (3667964): Guest Log: [INFO] Testing connection to EOSCMS
2022-07-27 20:52:43 (3667964): Guest Log: [INFO] Testing connection to CMS-Frontier
2022-07-27 20:52:43 (3667964): Guest Log: [INFO] Testing connection to Frontier
2022-07-27 20:52:43 (3667964): Guest Log: [INFO] Could not find a local HTTP proxy
2022-07-27 20:52:43 (3667964): Guest Log: [INFO] CVMFS and Frontier will have to use DIRECT connections
2022-07-27 20:52:43 (3667964): Guest Log: [INFO] This makes the application less efficient
2022-07-27 20:52:43 (3667964): Guest Log: [INFO] It also puts higher load on the project servers
2022-07-27 20:52:43 (3667964): Guest Log: [INFO] Setting up a local HTTP proxy is highly recommended
2022-07-27 20:52:43 (3667964): Guest Log: [INFO] Advice can be found in the project forum
2022-07-27 20:52:43 (3667964): Guest Log: [INFO] Reloading and probing the CVMFS configuration
2022-07-27 20:52:43 (3667964): Guest Log: [INFO] Probing /cvmfs/cvmfs-config.cern.ch... OK
2022-07-27 20:52:43 (3667964): Guest Log: [INFO] Probing /cvmfs/grid.cern.ch... OK
2022-07-27 20:52:43 (3667964): Guest Log: [INFO] Probing /cvmfs/singularity.opensciencegrid.org... OK
2022-07-27 20:52:43 (3667964): Guest Log: [INFO] Probing /cvmfs/oasis.opensciencegrid.org... OK
2022-07-27 20:52:43 (3667964): Guest Log: [INFO] Probing /cvmfs/cms-ib.cern.ch... OK
2022-07-27 20:52:43 (3667964): Guest Log: [INFO] Probing /cvmfs/cms.cern.ch... OK
2022-07-27 20:52:43 (3667964): Guest Log: [INFO] Excerpt from "cvmfs_config stat": VERSION HOST PROXY
2022-07-27 20:52:43 (3667964): Guest Log: [INFO] 2.7.2.0 http://cvmfs-stratum-one.cern.ch http://128.142.248.156:3125
2022-07-27 20:52:43 (3667964): Guest Log: [INFO] Reading volunteer information
2022-07-27 20:52:43 (3667964): Guest Log: [INFO] Requesting an X509 credential from LHC@home
2022-07-27 20:52:43 (3667964): Guest Log: [INFO] Requesting an X509 credential from vLHC@home-dev
2022-07-27 20:52:43 (3667964): Guest Log: [INFO] CMS application starting. Check log files.
2022-07-27 20:52:43 (3667964): Guest Log: [INFO] Requesting an idtoken from LHC@home
2022-07-27 20:52:43 (3667964): Guest Log: [INFO] Requesting an idtoken from vLHC@home-dev
2022-07-27 20:52:43 (3667964): Starting VM using VBoxManage interface. (boinc_eeb043dd351aeb97, slot#10)
2022-07-27 20:53:14 (3667964): Successfully started VM. (PID = '3668070')
2022-07-27 20:53:14 (3667964): Reporting VM Process ID to BOINC.
2022-07-27 20:53:14 (3667964): VM state change detected. (old = 'poweredoff', new = 'running')
2022-07-27 20:53:14 (3667964): Detected: Web Application Enabled (http://localhost:54747)
2022-07-27 20:53:14 (3667964): Preference change detected
2022-07-27 20:53:14 (3667964): Setting CPU throttle for VM. (100%)
2022-07-27 20:53:15 (3667964): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2022-07-27 20:53:23 (3667964): Guest Log: 00:59:42.871920 timesync vgsvcTimeSyncWorker: Radical host time change: 48 242 891 000 000ns (HostNow=1 658 948 003 611 000 000 ns HostLast=1 658 899 760 720 000 000 ns)
2022-07-27 20:53:33 (3667964): Guest Log: 00:59:52.880750 timesync vgsvcTimeSyncWorker: Radical guest time change: 48 242 858 215 000ns (GuestNow=1 658 948 013 643 566 000 ns GuestLast=1 658 899 770 785 351 000 ns fSetTimeLastLoop=true )
2022-07-27 21:31:48 (3667964): Status Report: Job Duration: '64800.000000'
2022-07-27 21:31:48 (3667964): Status Report: Elapsed Time: '6000.000000'
2022-07-27 21:31:48 (3667964): Status Report: CPU Time: '7291.800000'
2022-07-27 21:53:01 (3667964): Stopping VM.
2022-07-27 21:53:48 (3667964): Successfully stopped VM.
2022-07-27 22:53:56 (3709586): Detected: vboxwrapper 26205
2022-07-27 22:53:56 (3709586): Detected: BOINC client v7.16.11
2022-07-27 22:53:56 (3709586): Detected: VirtualBox VboxManage Interface (Version: 6.1.26)
2022-07-27 22:53:57 (3709586): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2022-07-27 22:53:59 (3709586): Guest Log: 00:59:42.871920 timesync vgsvcTimeSyncWorker: Radical host time change: 48 242 891 000 000ns (HostNow=1 658 948 003 611 000 000 ns HostLast=1 658 899 760 720 000 000 ns)
2022-07-27 22:53:59 (3709586): Guest Log: 00:59:52.880750 timesync vgsvcTimeSyncWorker: Radical guest time change: 48 242 858 215 000ns (GuestNow=1 658 948 013 643 566 000 ns GuestLast=1 658 899 770 785 351 000 ns fSetTimeLastLoop=true )
2022-07-27 22:53:59 (3709586): Starting VM using VBoxManage interface. (boinc_eeb043dd351aeb97, slot#10)
2022-07-27 22:54:25 (3709586): Successfully started VM. (PID = '3709693')
2022-07-27 22:54:25 (3709586): Reporting VM Process ID to BOINC.
2022-07-27 22:54:25 (3709586): VM state change detected. (old = 'poweredoff', new = 'running')
2022-07-27 22:54:25 (3709586): Detected: Web Application Enabled (http://localhost:54747)
2022-07-27 22:54:25 (3709586): Status Report: Job Duration: '64800.000000'
2022-07-27 22:54:25 (3709586): Status Report: Elapsed Time: '7285.000000'
2022-07-27 22:54:25 (3709586): Status Report: CPU Time: '8743.720000'
2022-07-27 22:54:25 (3709586): Preference change detected
2022-07-27 22:54:25 (3709586): Setting CPU throttle for VM. (100%)
2022-07-27 22:54:26 (3709586): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2022-07-27 22:54:29 (3709586): Guest Log: 01:59:24.760414 timesync vgsvcTimeSyncWorker: Radical host time change: 3 693 574 000 000ns (HostNow=1 658 955 269 008 000 000 ns HostLast=1 658 951 575 434 000 000 ns)
2022-07-27 22:54:39 (3709586): Guest Log: 01:59:34.761016 timesync vgsvcTimeSyncWorker: Radical guest time change: 3 693 531 216 000ns (GuestNow=1 658 955 279 031 479 000 ns GuestLast=1 658 951 585 500 263 000 ns fSetTimeLastLoop=true )
2022-07-28 00:33:26 (3709586): Status Report: Job Duration: '64800.000000'
2022-07-28 00:33:26 (3709586): Status Report: Elapsed Time: '13285.000000'
2022-07-28 00:33:26 (3709586): Status Report: CPU Time: '13296.940000'
2022-07-28 02:12:26 (3709586): Status Report: Job Duration: '64800.000000'
2022-07-28 02:12:26 (3709586): Status Report: Elapsed Time: '19285.000000'
2022-07-28 02:12:26 (3709586): Status Report: CPU Time: '13843.490000'
2022-07-28 03:38:44 (3709586): Stopping VM.
2022-07-28 03:39:36 (3709586): Successfully stopped VM.
2022-07-28 07:41:28 (3891464): Detected: vboxwrapper 26205
2022-07-28 07:41:28 (3891464): Detected: BOINC client v7.16.11
2022-07-28 07:41:28 (3891464): Detected: VirtualBox VboxManage Interface (Version: 6.1.26)
2022-07-28 07:41:29 (3891464): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2022-07-28 07:41:30 (3891464): Guest Log: 01:59:24.760414 timesync vgsvcTimeSyncWorker: Radical host time change: 3 693 574 000 000ns (HostNow=1 658 955 269 008 000 000 ns HostLast=1 658 951 575 434 000 000 ns)
2022-07-28 07:41:30 (3891464): Guest Log: 01:59:34.761016 timesync vgsvcTimeSyncWorker: Radical guest time change: 3 693 531 216 000ns (GuestNow=1 658 955 279 031 479 000 ns GuestLast=1 658 951 585 500 263 000 ns fSetTimeLastLoop=true )
2022-07-28 07:41:30 (3891464): Starting VM using VBoxManage interface. (boinc_eeb043dd351aeb97, slot#10)
2022-07-28 07:41:59 (3891464): Successfully started VM. (PID = '3891542')
2022-07-28 07:41:59 (3891464): Reporting VM Process ID to BOINC.
2022-07-28 07:41:59 (3891464): VM state change detected. (old = 'poweredoff', new = 'running')
2022-07-28 07:41:59 (3891464): Detected: Web Application Enabled (http://localhost:54747)
2022-07-28 07:41:59 (3891464): Status Report: Job Duration: '64800.000000'
2022-07-28 07:41:59 (3891464): Status Report: Elapsed Time: '24515.000000'
2022-07-28 07:41:59 (3891464): Status Report: CPU Time: '14320.390000'
2022-07-28 07:41:59 (3891464): Preference change detected
2022-07-28 07:41:59 (3891464): Setting CPU throttle for VM. (100%)
2022-07-28 07:41:59 (3891464): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2022-07-28 07:42:00 (3891464): Guest Log: 06:43:43.032908 timesync vgsvcTimeSyncWorker: Radical host time change: 14 602 795 000 000ns (HostNow=1 658 986 920 037 000 000 ns HostLast=1 658 972 317 242 000 000 ns)
2022-07-28 07:42:10 (3891464): Guest Log: 06:43:53.041188 timesync vgsvcTimeSyncWorker: Radical guest time change: 14 602 753 279 000ns (GuestNow=1 658 986 930 050 990 000 ns GuestLast=1 658 972 327 297 711 000 ns fSetTimeLastLoop=true )
2022-07-28 09:20:59 (3891464): Status Report: Job Duration: '64800.000000'
2022-07-28 09:20:59 (3891464): Status Report: Elapsed Time: '30515.000000'
2022-07-28 09:20:59 (3891464): Status Report: CPU Time: '14875.980000'
2022-07-28 10:42:16 (3891464): Stopping VM.
2022-07-28 10:42:59 (3891464): Successfully stopped VM.
2022-07-28 11:26:57 (3982878): Detected: vboxwrapper 26205
2022-07-28 11:26:57 (3982878): Detected: BOINC client v7.16.11
2022-07-28 11:26:58 (3982878): Detected: VirtualBox VboxManage Interface (Version: 6.1.26)
2022-07-28 11:26:58 (3982878): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2022-07-28 11:26:58 (3982878): Guest Log: 06:43:43.032908 timesync vgsvcTimeSyncWorker: Radical host time change: 14 602 795 000 000ns (HostNow=1 658 986 920 037 000 000 ns HostLast=1 658 972 317 242 000 000 ns)
2022-07-28 11:26:58 (3982878): Guest Log: 06:43:53.041188 timesync vgsvcTimeSyncWorker: Radical guest time change: 14 602 753 279 000ns (GuestNow=1 658 986 930 050 990 000 ns GuestLast=1 658 972 327 297 711 000 ns fSetTimeLastLoop=true )
2022-07-28 11:26:58 (3982878): Starting VM using VBoxManage interface. (boinc_eeb043dd351aeb97, slot#10)
2022-07-28 11:27:18 (3982878): Successfully started VM. (PID = '3982975')
2022-07-28 11:27:18 (3982878): Reporting VM Process ID to BOINC.
2022-07-28 11:27:18 (3982878): VM state change detected. (old = 'poweredoff', new = 'running')
2022-07-28 11:27:18 (3982878): Detected: Web Application Enabled (http://localhost:54747)
2022-07-28 11:27:18 (3982878): Status Report: Job Duration: '64800.000000'
2022-07-28 11:27:18 (3982878): Status Report: Elapsed Time: '35441.000000'
2022-07-28 11:27:18 (3982878): Status Report: CPU Time: '15325.310000'
2022-07-28 11:27:18 (3982878): Preference change detected
2022-07-28 11:27:18 (3982878): Setting CPU throttle for VM. (100%)
2022-07-28 11:27:19 (3982878): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2022-07-28 11:27:25 (3982878): Guest Log: 09:44:07.327011 timesync vgsvcTimeSyncWorker: Radical host time change: 2 710 773 000 000ns (HostNow=1 659 000 444 906 000 000 ns HostLast=1 658 997 734 133 000 000 ns)
2022-07-28 11:27:35 (3982878): Guest Log: 09:44:17.332411 timesync vgsvcTimeSyncWorker: Radical guest time change: 2 710 617 870 000ns (GuestNow=1 659 000 454 933 034 000 ns GuestLast=1 658 997 744 315 164 000 ns fSetTimeLastLoop=true )
2022-07-28 12:27:36 (3982878): Stopping VM.
2022-07-28 12:28:26 (3982878): Successfully stopped VM.
2022-07-28 13:27:56 (4034136): Detected: vboxwrapper 26205
2022-07-28 13:27:56 (4034136): Detected: BOINC client v7.16.11
2022-07-28 13:27:56 (4034136): Detected: VirtualBox VboxManage Interface (Version: 6.1.26)
2022-07-28 13:27:57 (4034136): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2022-07-28 13:27:59 (4034136): Guest Log: 09:44:07.327011 timesync vgsvcTimeSyncWorker: Radical host time change: 2 710 773 000 000ns (HostNow=1 659 000 444 906 000 000 ns HostLast=1 658 997 734 133 000 000 ns)
2022-07-28 13:27:59 (4034136): Guest Log: 09:44:17.332411 timesync vgsvcTimeSyncWorker: Radical guest time change: 2 710 617 870 000ns (GuestNow=1 659 000 454 933 034 000 ns GuestLast=1 658 997 744 315 164 000 ns fSetTimeLastLoop=true )
2022-07-28 13:27:59 (4034136): Starting VM using VBoxManage interface. (boinc_eeb043dd351aeb97, slot#10)
2022-07-28 13:28:28 (4034136): Successfully started VM. (PID = '4034250')
2022-07-28 13:28:28 (4034136): Reporting VM Process ID to BOINC.
2022-07-28 13:28:28 (4034136): VM state change detected. (old = 'poweredoff', new = 'running')
2022-07-28 13:28:28 (4034136): Detected: Web Application Enabled (http://localhost:54747)
2022-07-28 13:28:28 (4034136): Status Report: Job Duration: '64800.000000'
2022-07-28 13:28:28 (4034136): Status Report: Elapsed Time: '39095.000000'
2022-07-28 13:28:28 (4034136): Status Report: CPU Time: '15670.570000'
2022-07-28 13:28:28 (4034136): Preference change detected
2022-07-28 13:28:28 (4034136): Setting CPU throttle for VM. (100%)
2022-07-28 13:28:29 (4034136): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2022-07-28 13:28:30 (4034136): Guest Log: 10:44:21.858685 timesync vgsvcTimeSyncWorker: Radical host time change: 3 660 862 000 000ns (HostNow=1 659 007 710 030 000 000 ns HostLast=1 659 004 049 168 000 000 ns)
2022-07-28 13:28:40 (4034136): Guest Log: 10:44:31.883234 timesync vgsvcTimeSyncWorker: Radical guest time change: 3 661 010 698 000ns (GuestNow=1 659 007 720 262 268 000 ns GuestLast=1 659 004 059 251 570 000 ns fSetTimeLastLoop=true )
2022-07-28 14:28:11 (4034136): Stopping VM.
2022-07-28 14:29:01 (4034136): Successfully stopped VM.
2022-07-28 15:29:09 (4085041): Detected: vboxwrapper 26205
2022-07-28 15:29:09 (4085041): Detected: BOINC client v7.16.11
2022-07-28 15:29:10 (4085041): Detected: VirtualBox VboxManage Interface (Version: 6.1.26)
2022-07-28 15:29:11 (4085041): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2022-07-28 15:29:12 (4085041): Guest Log: 10:44:21.858685 timesync vgsvcTimeSyncWorker: Radical host time change: 3 660 862 000 000ns (HostNow=1 659 007 710 030 000 000 ns HostLast=1 659 004 049 168 000 000 ns)
2022-07-28 15:29:12 (4085041): Guest Log: 10:44:31.883234 timesync vgsvcTimeSyncWorker: Radical guest time change: 3 661 010 698 000ns (GuestNow=1 659 007 720 262 268 000 ns GuestLast=1 659 004 059 251 570 000 ns fSetTimeLastLoop=true )
2022-07-28 15:29:12 (4085041): Starting VM using VBoxManage interface. (boinc_eeb043dd351aeb97, slot#10)
2022-07-28 15:29:47 (4085041): Successfully started VM. (PID = '4085122')
2022-07-28 15:29:47 (4085041): Reporting VM Process ID to BOINC.
2022-07-28 15:29:47 (4085041): VM state change detected. (old = 'poweredoff', new = 'running')
2022-07-28 15:29:47 (4085041): Detected: Web Application Enabled (http://localhost:54747)
2022-07-28 15:29:47 (4085041): Status Report: Job Duration: '64800.000000'
2022-07-28 15:29:47 (4085041): Status Report: Elapsed Time: '42713.000000'
2022-07-28 15:29:47 (4085041): Status Report: CPU Time: '16015.920000'
2022-07-28 15:29:47 (4085041): Preference change detected
2022-07-28 15:29:47 (4085041): Setting CPU throttle for VM. (100%)
2022-07-28 15:29:48 (4085041): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2022-07-28 15:29:50 (4085041): Guest Log: 11:44:06.307440 timesync vgsvcTimeSyncWorker: Radical host time change: 3 705 322 000 000ns (HostNow=1 659 014 989 931 000 000 ns HostLast=1 659 011 284 609 000 000 ns)
2022-07-28 15:30:00 (4085041): Guest Log: 11:44:16.311457 timesync vgsvcTimeSyncWorker: Radical guest time change: 3 705 280 329 000ns (GuestNow=1 659 014 999 950 895 000 ns GuestLast=1 659 011 294 670 566 000 ns fSetTimeLastLoop=true )
2022-07-28 17:08:48 (4085041): Status Report: Job Duration: '64800.000000'
2022-07-28 17:08:48 (4085041): Status Report: Elapsed Time: '48713.000000'
2022-07-28 17:08:48 (4085041): Status Report: CPU Time: '16593.250000'
2022-07-28 17:08:54 (4085041): Stopping VM.
2022-07-28 17:09:23 (4085041): Successfully stopped VM.
2022-07-28 17:18:57 (4130595): Detected: vboxwrapper 26205
2022-07-28 17:18:57 (4130595): Detected: BOINC client v7.16.11
2022-07-28 17:18:58 (4130595): Detected: VirtualBox VboxManage Interface (Version: 6.1.26)
2022-07-28 17:18:58 (4130595): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2022-07-28 17:18:58 (4130595): Guest Log: 11:44:06.307440 timesync vgsvcTimeSyncWorker: Radical host time change: 3 705 322 000 000ns (HostNow=1 659 014 989 931 000 000 ns HostLast=1 659 011 284 609 000 000 ns)
2022-07-28 17:18:58 (4130595): Guest Log: 11:44:16.311457 timesync vgsvcTimeSyncWorker: Radical guest time change: 3 705 280 329 000ns (GuestNow=1 659 014 999 950 895 000 ns GuestLast=1 659 011 294 670 566 000 ns fSetTimeLastLoop=true )
2022-07-28 17:18:58 (4130595): Starting VM using VBoxManage interface. (boinc_eeb043dd351aeb97, slot#10)
2022-07-28 17:19:11 (4130595): Successfully started VM. (PID = '4130693')
2022-07-28 17:19:11 (4130595): Reporting VM Process ID to BOINC.
2022-07-28 17:19:11 (4130595): VM state change detected. (old = 'poweredoff', new = 'running')
2022-07-28 17:19:11 (4130595): Detected: Web Application Enabled (http://localhost:54747)
2022-07-28 17:19:11 (4130595): Status Report: Job Duration: '64800.000000'
2022-07-28 17:19:11 (4130595): Status Report: Elapsed Time: '48720.000000'
2022-07-28 17:19:11 (4130595): Status Report: CPU Time: '16593.250000'
2022-07-28 17:19:11 (4130595): Preference change detected
2022-07-28 17:19:11 (4130595): Setting CPU throttle for VM. (100%)
2022-07-28 17:19:11 (4130595): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2022-07-28 18:19:58 (4130595): Stopping VM.
2022-07-28 18:20:33 (4130595): Successfully stopped VM.
2022-07-31 15:14:06 (3409014): Detected: vboxwrapper 26205
2022-07-31 15:14:06 (3409014): Detected: BOINC client v7.16.11
2022-07-31 15:14:07 (3409014): Detected: VirtualBox VboxManage Interface (Version: 6.1.26)
2022-07-31 15:14:07 (3409014): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2022-07-31 15:14:07 (3409014): Starting VM using VBoxManage interface. (boinc_eeb043dd351aeb97, slot#10)
2022-07-31 15:14:32 (3409014): Successfully started VM. (PID = '3409221')
2022-07-31 15:14:32 (3409014): Reporting VM Process ID to BOINC.
2022-07-31 15:14:32 (3409014): VM state change detected. (old = 'poweredoff', new = 'running')
2022-07-31 15:14:32 (3409014): Detected: Web Application Enabled (http://localhost:54747)
2022-07-31 15:14:32 (3409014): Status Report: Job Duration: '64800.000000'
2022-07-31 15:14:32 (3409014): Status Report: Elapsed Time: '52404.000000'
2022-07-31 15:14:32 (3409014): Status Report: CPU Time: '16950.070000'
2022-07-31 15:14:32 (3409014): Preference change detected
2022-07-31 15:14:32 (3409014): Setting CPU throttle for VM. (100%)
2022-07-31 15:14:32 (3409014): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2022-07-31 15:14:39 (3409014): Guest Log: 14:24:07.545992 timesync vgsvcTimeSyncWorker: Radical host time change: 248 082 509 000 000ns (HostNow=1 659 273 278 636 000 000 ns HostLast=1 659 025 196 127 000 000 ns)
2022-07-31 15:14:49 (3409014): Guest Log: 14:24:17.633584 timesync vgsvcTimeSyncWorker: Radical guest time change: 248 082 540 732 000ns (GuestNow=1 659 273 288 724 831 000 ns GuestLast=1 659 025 206 184 099 000 ns fSetTimeLastLoop=true )
2022-07-31 16:15:00 (3409014): Stopping VM.
2022-07-31 16:15:53 (3409014): Successfully stopped VM.
2022-07-31 16:35:10 (3494875): Detected: vboxwrapper 26205
2022-07-31 16:35:10 (3494875): Detected: BOINC client v7.16.11
2022-07-31 16:35:11 (3494875): Detected: VirtualBox VboxManage Interface (Version: 6.1.26)
2022-07-31 16:35:11 (3494875): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2022-07-31 16:35:11 (3494875): Guest Log: 14:24:07.545992 timesync vgsvcTimeSyncWorker: Radical host time change: 248 082 509 000 000ns (HostNow=1 659 273 278 636 000 000 ns HostLast=1 659 025 196 127 000 000 ns)
2022-07-31 16:35:11 (3494875): Guest Log: 14:24:17.633584 timesync vgsvcTimeSyncWorker: Radical guest time change: 248 082 540 732 000ns (GuestNow=1 659 273 288 724 831 000 ns GuestLast=1 659 025 206 184 099 000 ns fSetTimeLastLoop=true )
2022-07-31 16:35:11 (3494875): Starting VM using VBoxManage interface. (boinc_eeb043dd351aeb97, slot#10)
2022-07-31 16:35:32 (3494875): Successfully started VM. (PID = '3494994')
2022-07-31 16:35:32 (3494875): Reporting VM Process ID to BOINC.
2022-07-31 16:35:32 (3494875): VM state change detected. (old = 'poweredoff', new = 'running')
2022-07-31 16:35:32 (3494875): Detected: Web Application Enabled (http://localhost:54747)
2022-07-31 16:35:32 (3494875): Status Report: Job Duration: '64800.000000'
2022-07-31 16:35:32 (3494875): Status Report: Elapsed Time: '56069.000000'
2022-07-31 16:35:32 (3494875): Status Report: CPU Time: '17302.120000'
2022-07-31 16:35:32 (3494875): Preference change detected
2022-07-31 16:35:32 (3494875): Setting CPU throttle for VM. (100%)
2022-07-31 16:35:32 (3494875): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2022-07-31 17:35:24 (3494875): Stopping VM.
2022-07-31 17:36:11 (3494875): Successfully stopped VM.
2022-07-31 17:41:55 (3523279): Detected: vboxwrapper 26205
2022-07-31 17:41:55 (3523279): Detected: BOINC client v7.16.11
2022-07-31 17:41:58 (3523279): Detected: VirtualBox VboxManage Interface (Version: 6.1.26)
2022-07-31 17:41:58 (3523279): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2022-07-31 17:41:58 (3523279): Starting VM using VBoxManage interface. (boinc_eeb043dd351aeb97, slot#10)
2022-07-31 17:42:13 (3523279): Successfully started VM. (PID = '3523372')
2022-07-31 17:42:13 (3523279): Reporting VM Process ID to BOINC.
2022-07-31 17:42:13 (3523279): VM state change detected. (old = 'poweredoff', new = 'running')
2022-07-31 17:42:13 (3523279): Detected: Web Application Enabled (http://localhost:54747)
2022-07-31 17:42:13 (3523279): Status Report: Job Duration: '64800.000000'
2022-07-31 17:42:13 (3523279): Status Report: Elapsed Time: '59697.000000'
2022-07-31 17:42:13 (3523279): Status Report: CPU Time: '17647.040000'
2022-07-31 17:42:13 (3523279): Preference change detected
2022-07-31 17:42:13 (3523279): Setting CPU throttle for VM. (100%)
2022-07-31 17:42:13 (3523279): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2022-07-31 18:42:12 (3523279): Stopping VM.
2022-08-01 01:34:42 (139200): Detected: vboxwrapper 26205
2022-08-01 01:34:42 (139200): Detected: BOINC client v7.16.11
2022-08-01 01:34:43 (139200): Detected: VirtualBox VboxManage Interface (Version: 6.1.26)
2022-08-01 01:34:43 (139200): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2022-08-01 01:34:43 (139200): Starting VM using VBoxManage interface. (boinc_eeb043dd351aeb97, slot#10)
2022-08-01 01:34:44 (139200): Successfully started VM. (PID = '139324')
2022-08-01 01:34:44 (139200): Reporting VM Process ID to BOINC.
2022-08-01 01:34:44 (139200): Guest Log: BIOS: VirtualBox 6.1.25
2022-08-01 01:34:44 (139200): Guest Log: CPUID EDX: 0x178bfbff
2022-08-01 01:34:44 (139200): Guest Log: BIOS: No PCI IDE controller, not probing IDE
2022-08-01 01:34:44 (139200): Guest Log: BIOS: AHCI 0-P#0: PCHS=16383/16/63 LCHS=1024/255/63 0x0000000002800000 sectors
2022-08-01 01:34:44 (139200): VM state change detected. (old = 'poweredoff', new = 'running')
2022-08-01 01:34:44 (139200): Detected: Web Application Enabled (http://localhost:54747)
2022-08-01 01:34:44 (139200): Status Report: Job Duration: '64800.000000'
2022-08-01 01:34:44 (139200): Status Report: Elapsed Time: '63332.000000'
2022-08-01 01:34:44 (139200): Status Report: CPU Time: '17990.730000'
2022-08-01 01:34:44 (139200): Preference change detected
2022-08-01 01:34:44 (139200): Setting CPU throttle for VM. (100%)
2022-08-01 01:34:44 (139200): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2022-08-01 01:34:46 (139200): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032
2022-08-01 01:34:46 (139200): Guest Log: BIOS: Booting from Hard Disk...
2022-08-01 01:34:49 (139200): Guest Log: BIOS: KBD: unsupported int 16h function 03
2022-08-01 01:34:49 (139200): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 
2022-08-01 01:35:32 (139200): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds
2022-08-01 01:35:32 (139200): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000)
2022-08-01 01:35:36 (139200): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log
2022-08-01 01:35:36 (139200): Guest Log: 00:00:00.000215 main     Log opened 2022-07-31T23:35:35.756090000Z
2022-08-01 01:35:36 (139200): Guest Log: 00:00:00.001514 main     OS Product: Linux
2022-08-01 01:35:36 (139200): Guest Log: 00:00:00.001585 main     OS Release: 4.14.232-19.cernvm.x86_64
2022-08-01 01:35:36 (139200): Guest Log: 00:00:00.001635 main     OS Version: #1 SMP Fri Apr 30 17:12:25 CEST 2021
2022-08-01 01:35:36 (139200): Guest Log: 00:00:00.001672 main     Executable: /usr/sbin/VBoxService
2022-08-01 01:35:36 (139200): Guest Log: 00:00:00.001673 main     Process ID: 2761
2022-08-01 01:35:36 (139200): Guest Log: 00:00:00.001674 main     Package type: LINUX_64BITS_GENERIC
2022-08-01 01:35:36 (139200): Guest Log: 00:00:00.003053 main     5.2.6 r120293 started. Verbose level = 0
2022-08-01 01:36:09 (139200): Guest Log: [INFO] Mounting the shared directory
2022-08-01 01:36:09 (139200): Guest Log: [INFO] Shared directory mounted, enabling vboxmonitor
2022-08-01 01:36:10 (139200): Guest Log: [INFO] Sourcing essential functions from /cvmfs/grid.cern.ch
2022-08-01 01:36:10 (139200): Guest Log: [INFO] Testing connection to cern.ch
2022-08-01 01:36:10 (139200): Guest Log: [INFO] Testing connection to VCCS
2022-08-01 01:36:11 (139200): Guest Log: [INFO] Testing connection to HTCondor
2022-08-01 01:36:11 (139200): Guest Log: [INFO] Testing connection to WMAgent
2022-08-01 01:36:11 (139200): Guest Log: [INFO] Testing connection to EOSCMS
2022-08-01 01:36:12 (139200): Guest Log: [INFO] Testing connection to CMS-Frontier
2022-08-01 01:36:12 (139200): Guest Log: [INFO] Testing connection to Frontier
2022-08-01 01:36:12 (139200): Guest Log: [INFO] Could not find a local HTTP proxy
2022-08-01 01:36:13 (139200): Guest Log: [INFO] CVMFS and Frontier will have to use DIRECT connections
2022-08-01 01:36:13 (139200): Guest Log: [INFO] This makes the application less efficient
2022-08-01 01:36:13 (139200): Guest Log: [INFO] It also puts higher load on the project servers
2022-08-01 01:36:13 (139200): Guest Log: [INFO] Setting up a local HTTP proxy is highly recommended
2022-08-01 01:36:13 (139200): Guest Log: [INFO] Advice can be found in the project forum
2022-08-01 01:36:13 (139200): Guest Log: [INFO] Reloading and probing the CVMFS configuration
2022-08-01 01:36:19 (139200): Guest Log: [INFO] Probing /cvmfs/cvmfs-config.cern.ch... OK
2022-08-01 01:36:20 (139200): Guest Log: [INFO] Probing /cvmfs/grid.cern.ch... OK
2022-08-01 01:36:24 (139200): Guest Log: [INFO] Probing /cvmfs/oasis.opensciencegrid.org... OK
2022-08-01 01:36:24 (139200): Guest Log: [INFO] Probing /cvmfs/singularity.opensciencegrid.org... OK
2022-08-01 01:36:24 (139200): Guest Log: [INFO] Probing /cvmfs/cms.cern.ch... OK
2022-08-01 01:36:25 (139200): Guest Log: [INFO] Probing /cvmfs/cms-ib.cern.ch... OK
2022-08-01 01:36:26 (139200): Guest Log: [INFO] Excerpt from "cvmfs_config stat": VERSION HOST PROXY
2022-08-01 01:36:26 (139200): Guest Log: [INFO] 2.7.2.0 http://cvmfs-stratum-one.cern.ch http://128.142.161.84:3125
2022-08-01 01:36:26 (139200): Guest Log: [INFO] Reading volunteer information
2022-08-01 01:36:29 (139200): Guest Log: [INFO] Requesting an X509 credential from LHC@home
2022-08-01 01:36:30 (139200): Guest Log: [INFO] Requesting an X509 credential from vLHC@home-dev
2022-08-01 01:36:32 (139200): Guest Log: [INFO] CMS application starting. Check log files.
2022-08-01 01:36:32 (139200): Guest Log: [INFO] Requesting an idtoken from LHC@home
2022-08-01 01:36:33 (139200): Guest Log: [INFO] Requesting an idtoken from vLHC@home-dev
2022-08-01 01:58:59 (139200): Powering off VM.
2022-08-01 01:58:59 (139200): Successfully stopped VM.
2022-08-01 01:58:59 (139200): Deregistering VM. (boinc_eeb043dd351aeb97, slot#10)
2022-08-01 01:58:59 (139200): Removing network bandwidth throttle group from VM.
2022-08-01 01:58:59 (139200): Removing VM from VirtualBox.
01:59:05 (139200): called boinc_finish(0)

</stderr_txt>
]]>

©2024 CERN