Name CMS_3797555_1676067383.893937_0
Workunit 2278933
Created 10 Feb 2023, 22:16:28 UTC
Sent 13 Feb 2023, 7:00:49 UTC
Report deadline 20 Feb 2023, 7:00:49 UTC
Received 15 Feb 2023, 10:49:36 UTC
Server state Over
Outcome Success
Client state Done
Exit status 0 (0x00000000)
Computer ID 4318
Run time 17 hours 25 min 4 sec
CPU time 20 hours 7 min 29 sec
Validate state Valid
Credit 1,714.16
Device peak FLOPS 33.00 GFLOPS
Application version CMS Simulation v60.70 (vbox64_mt_mcore_cms)
x86_64-pc-linux-gnu
Peak working set size 5.28 GB
Peak swap size 8.08 GB
Peak disk usage 3.69 GB

Stderr output

<core_client_version>7.16.11</core_client_version>
<![CDATA[
<stderr_txt>
(1262471): Detected: Web Application Enabled (http://localhost:38623)
2023-02-13 18:42:31 (1262471): Status Report: Job Duration: '64800.000000'
2023-02-13 18:42:31 (1262471): Status Report: Elapsed Time: '14605.000000'
2023-02-13 18:42:31 (1262471): Status Report: CPU Time: '16755.470000'
2023-02-13 18:42:31 (1262471): Preference change detected
2023-02-13 18:42:31 (1262471): Setting CPU throttle for VM. (100%)
2023-02-13 18:42:32 (1262471): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2023-02-13 18:42:37 (1262471): Guest Log: 04:00:28.727375 timesync vgsvcTimeSyncWorker: Radical host time change: 3 147 153 000 000ns (HostNow=1 676 310 156 823 000 000 ns HostLast=1 676 307 009 670 000 000 ns)
2023-02-13 18:42:47 (1262471): Guest Log: 04:00:38.758519 timesync vgsvcTimeSyncWorker: Radical guest time change: 3 143 379 251 000ns (GuestNow=1 676 310 166 956 060 000 ns GuestLast=1 676 307 023 576 809 000 ns fSetTimeLastLoop=true )
2023-02-13 19:42:33 (1262471): Stopping VM.
2023-02-13 19:43:29 (1262471): Successfully stopped VM.
2023-02-13 21:37:04 (1334179): Detected: vboxwrapper 26206
2023-02-13 21:37:04 (1334179): Detected: BOINC client v7.16.11
2023-02-13 21:37:04 (1334179): Detected: VirtualBox VboxManage Interface (Version: 6.1.26)
2023-02-13 21:37:04 (1334179): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2023-02-13 21:37:04 (1334179): Guest Log: 04:00:28.727375 timesync vgsvcTimeSyncWorker: Radical host time change: 3 147 153 000 000ns (HostNow=1 676 310 156 823 000 000 ns HostLast=1 676 307 009 670 000 000 ns)
2023-02-13 21:37:04 (1334179): Guest Log: 04:00:38.758519 timesync vgsvcTimeSyncWorker: Radical guest time change: 3 143 379 251 000ns (GuestNow=1 676 310 166 956 060 000 ns GuestLast=1 676 307 023 576 809 000 ns fSetTimeLastLoop=true )
2023-02-13 21:37:04 (1334179): Starting VM using VBoxManage interface. (boinc_dc10c48fc5a480bc, slot#14)
2023-02-13 21:37:18 (1334179): Successfully started VM. (PID = '1334281')
2023-02-13 21:37:18 (1334179): Reporting VM Process ID to BOINC.
2023-02-13 21:37:18 (1334179): Guest Log: 05:00:30.352093 timesync vgsvcTimeSyncWorker: Radical host time change: 6 894 044 000 000ns (HostNow=1 676 320 638 738 000 000 ns HostLast=1 676 313 744 694 000 000 ns)
2023-02-13 21:37:18 (1334179): VM state change detected. (old = 'poweredoff', new = 'running')
2023-02-13 21:37:18 (1334179): Detected: Web Application Enabled (http://localhost:38623)
2023-02-13 21:37:18 (1334179): Status Report: Job Duration: '64800.000000'
2023-02-13 21:37:18 (1334179): Status Report: Elapsed Time: '18243.128443'
2023-02-13 21:37:18 (1334179): Status Report: CPU Time: '20989.120000'
2023-02-13 21:37:18 (1334179): Preference change detected
2023-02-13 21:37:18 (1334179): Setting CPU throttle for VM. (100%)
2023-02-13 21:37:18 (1334179): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2023-02-13 21:37:29 (1334179): Guest Log: 05:00:40.353121 timesync vgsvcTimeSyncWorker: Radical guest time change: 6 890 199 500 000ns (GuestNow=1 676 320 648 745 669 000 ns GuestLast=1 676 313 758 546 169 000 ns fSetTimeLastLoop=true )
2023-02-13 22:37:31 (1334179): Stopping VM.
2023-02-14 00:32:04 (1406387): Detected: vboxwrapper 26206
2023-02-14 00:32:04 (1406387): Detected: BOINC client v7.16.11
2023-02-14 00:32:04 (1406387): Detected: VirtualBox VboxManage Interface (Version: 6.1.26)
2023-02-14 00:32:04 (1406387): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2023-02-14 00:32:04 (1406387): Guest Log: 05:00:30.352093 timesync vgsvcTimeSyncWorker: Radical host time change: 6 894 044 000 000ns (HostNow=1 676 320 638 738 000 000 ns HostLast=1 676 313 744 694 000 000 ns)
2023-02-14 00:32:04 (1406387): Guest Log: 05:00:40.353121 timesync vgsvcTimeSyncWorker: Radical guest time change: 6 890 199 500 000ns (GuestNow=1 676 320 648 745 669 000 ns GuestLast=1 676 313 758 546 169 000 ns fSetTimeLastLoop=true )
2023-02-14 00:32:04 (1406387): Starting VM using VBoxManage interface. (boinc_dc10c48fc5a480bc, slot#14)
2023-02-14 00:32:05 (1406387): Successfully started VM. (PID = '1406485')
2023-02-14 00:32:05 (1406387): Reporting VM Process ID to BOINC.
2023-02-14 00:32:05 (1406387): Guest Log: BIOS: VirtualBox 6.1.25
2023-02-14 00:32:05 (1406387): Guest Log: CPUID EDX: 0x178bfbff
2023-02-14 00:32:05 (1406387): Guest Log: BIOS: No PCI IDE controller, not probing IDE
2023-02-14 00:32:05 (1406387): Guest Log: BIOS: AHCI 0-P#0: PCHS=16383/16/63 LCHS=1024/255/63 0x0000000002800000 sectors
2023-02-14 00:32:05 (1406387): VM state change detected. (old = 'poweredoff', new = 'running')
2023-02-14 00:32:05 (1406387): Detected: Web Application Enabled (http://localhost:38623)
2023-02-14 00:32:05 (1406387): Status Report: Job Duration: '64800.000000'
2023-02-14 00:32:05 (1406387): Status Report: Elapsed Time: '21892.128443'
2023-02-14 00:32:05 (1406387): Status Report: CPU Time: '25192.060000'
2023-02-14 00:32:05 (1406387): Preference change detected
2023-02-14 00:32:05 (1406387): Setting CPU throttle for VM. (100%)
2023-02-14 00:32:05 (1406387): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2023-02-14 00:32:07 (1406387): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032
2023-02-14 00:32:07 (1406387): Guest Log: BIOS: Booting from Hard Disk...
2023-02-14 00:32:10 (1406387): Guest Log: BIOS: KBD: unsupported int 16h function 03
2023-02-14 00:32:10 (1406387): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 
2023-02-14 00:32:54 (1406387): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds
2023-02-14 00:32:54 (1406387): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000)
2023-02-14 00:32:58 (1406387): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log
2023-02-14 00:32:58 (1406387): Guest Log: 00:00:00.002687 main     Log opened 2023-02-13T23:32:57.740137000Z
2023-02-14 00:32:58 (1406387): Guest Log: 00:00:00.003290 main     OS Product: Linux
2023-02-14 00:32:58 (1406387): Guest Log: 00:00:00.003655 main     OS Release: 4.14.232-19.cernvm.x86_64
2023-02-14 00:32:58 (1406387): Guest Log: 00:00:00.003811 main     OS Version: #1 SMP Fri Apr 30 17:12:25 CEST 2021
2023-02-14 00:32:58 (1406387): Guest Log: 00:00:00.003869 main     Executable: /usr/sbin/VBoxService
2023-02-14 00:32:58 (1406387): Guest Log: 00:00:00.003871 main     Process ID: 2730
2023-02-14 00:32:58 (1406387): Guest Log: 00:00:00.003871 main     Package type: LINUX_64BITS_GENERIC
2023-02-14 00:32:58 (1406387): Guest Log: 00:00:00.009074 main     5.2.6 r120293 started. Verbose level = 0
2023-02-14 00:33:25 (1406387): Guest Log: [INFO] Mounting the shared directory
2023-02-14 00:33:26 (1406387): Guest Log: [INFO] Shared directory mounted, enabling vboxmonitor
2023-02-14 00:33:26 (1406387): Guest Log: [INFO] Sourcing essential functions from /cvmfs/grid.cern.ch
2023-02-14 00:33:26 (1406387): Guest Log: [INFO] Testing connection to cern.ch
2023-02-14 00:33:27 (1406387): Guest Log: [INFO] Testing connection to VCCS
2023-02-14 00:33:27 (1406387): Guest Log: [INFO] Testing connection to HTCondor
2023-02-14 00:33:28 (1406387): Guest Log: [INFO] Testing connection to WMAgent
2023-02-14 00:33:28 (1406387): Guest Log: [INFO] Testing connection to EOSCMS
2023-02-14 00:33:29 (1406387): Guest Log: [INFO] Testing connection to CMS-Factory
2023-02-14 00:33:29 (1406387): Guest Log: [INFO] Testing connection to CMS-Frontier
2023-02-14 00:33:30 (1406387): Guest Log: [INFO] Testing connection to Frontier
2023-02-14 00:33:31 (1406387): Guest Log: [INFO] Could not find a local HTTP proxy
2023-02-14 00:33:32 (1406387): Guest Log: [INFO] CVMFS and Frontier will have to use DIRECT connections
2023-02-14 00:33:32 (1406387): Guest Log: [INFO] This makes the application less efficient
2023-02-14 00:33:32 (1406387): Guest Log: [INFO] It also puts higher load on the project servers
2023-02-14 00:33:32 (1406387): Guest Log: [INFO] Setting up a local HTTP proxy is highly recommended
2023-02-14 00:33:33 (1406387): Guest Log: [INFO] Advice can be found in the project forum
2023-02-14 00:33:33 (1406387): Guest Log: [INFO] Reloading and probing the CVMFS configuration
2023-02-14 00:33:47 (1406387): Guest Log: [INFO] Probing /cvmfs/grid.cern.ch... OK
2023-02-14 00:33:51 (1406387): Guest Log: [INFO] Probing /cvmfs/oasis.opensciencegrid.org... OK
2023-02-14 00:33:51 (1406387): Guest Log: [INFO] Probing /cvmfs/singularity.opensciencegrid.org... OK
2023-02-14 00:33:51 (1406387): Guest Log: [INFO] Probing /cvmfs/cms-ib.cern.ch... OK
2023-02-14 00:33:52 (1406387): Guest Log: [INFO] Probing /cvmfs/cms.cern.ch... OK
2023-02-14 00:33:55 (1406387): Guest Log: [INFO] Excerpt from "cvmfs_config stat": VERSION HOST PROXY
2023-02-14 00:33:55 (1406387): Guest Log: [INFO] 2.7.2.0 http://s1swinburne-cvmfs.openhtc.io:8080 DIRECT
2023-02-14 00:33:55 (1406387): Guest Log: [INFO] Environment HTTP proxy: not set
2023-02-14 00:33:56 (1406387): Guest Log: [INFO] Reading volunteer information
2023-02-14 00:34:20 (1406387): Guest Log: [INFO] Requesting an X509 credential from LHC@home
2023-02-14 00:34:21 (1406387): Guest Log: [INFO] Requesting an X509 credential from vLHC@home-dev
2023-02-14 00:34:22 (1406387): Guest Log: [INFO] CMS application starting. Check log files.
2023-02-14 00:34:22 (1406387): Guest Log: [INFO] Requesting an idtoken from LHC@home
2023-02-14 00:34:23 (1406387): Guest Log: [INFO] Requesting an idtoken from vLHC@home-dev
2023-02-14 01:32:58 (1406387): Stopping VM.
2023-02-14 01:33:57 (1406387): Successfully stopped VM.
2023-02-14 03:26:57 (1479393): Detected: vboxwrapper 26206
2023-02-14 03:26:57 (1479393): Detected: BOINC client v7.16.11
2023-02-14 03:26:57 (1479393): Detected: VirtualBox VboxManage Interface (Version: 6.1.26)
2023-02-14 03:26:57 (1479393): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2023-02-14 03:26:57 (1479393): Guest Log: BIOS: VirtualBox 6.1.25
2023-02-14 03:26:57 (1479393): Guest Log: CPUID EDX: 0x178bfbff
2023-02-14 03:26:57 (1479393): Guest Log: BIOS: No PCI IDE controller, not probing IDE
2023-02-14 03:26:57 (1479393): Guest Log: BIOS: AHCI 0-P#0: PCHS=16383/16/63 LCHS=1024/255/63 0x0000000002800000 sectors
2023-02-14 03:26:57 (1479393): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032
2023-02-14 03:26:57 (1479393): Guest Log: BIOS: Booting from Hard Disk...
2023-02-14 03:26:57 (1479393): Guest Log: BIOS: KBD: unsupported int 16h function 03
2023-02-14 03:26:57 (1479393): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 
2023-02-14 03:26:57 (1479393): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds
2023-02-14 03:26:57 (1479393): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000)
2023-02-14 03:26:57 (1479393): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log
2023-02-14 03:26:57 (1479393): Guest Log: 00:00:00.002687 main     Log opened 2023-02-13T23:32:57.740137000Z
2023-02-14 03:26:57 (1479393): Guest Log: 00:00:00.003290 main     OS Product: Linux
2023-02-14 03:26:57 (1479393): Guest Log: 00:00:00.003655 main     OS Release: 4.14.232-19.cernvm.x86_64
2023-02-14 03:26:57 (1479393): Guest Log: 00:00:00.003811 main     OS Version: #1 SMP Fri Apr 30 17:12:25 CEST 2021
2023-02-14 03:26:57 (1479393): Guest Log: 00:00:00.003869 main     Executable: /usr/sbin/VBoxService
2023-02-14 03:26:57 (1479393): Guest Log: 00:00:00.003871 main     Process ID: 2730
2023-02-14 03:26:57 (1479393): Guest Log: 00:00:00.003871 main     Package type: LINUX_64BITS_GENERIC
2023-02-14 03:26:57 (1479393): Guest Log: 00:00:00.009074 main     5.2.6 r120293 started. Verbose level = 0
2023-02-14 03:26:57 (1479393): Guest Log: [INFO] Mounting the shared directory
2023-02-14 03:26:57 (1479393): Guest Log: [INFO] Shared directory mounted, enabling vboxmonitor
2023-02-14 03:26:57 (1479393): Guest Log: [INFO] Sourcing essential functions from /cvmfs/grid.cern.ch
2023-02-14 03:26:57 (1479393): Guest Log: [INFO] Testing connection to cern.ch
2023-02-14 03:26:57 (1479393): Guest Log: [INFO] Testing connection to VCCS
2023-02-14 03:26:57 (1479393): Guest Log: [INFO] Testing connection to HTCondor
2023-02-14 03:26:57 (1479393): Guest Log: [INFO] Testing connection to WMAgent
2023-02-14 03:26:57 (1479393): Guest Log: [INFO] Testing connection to EOSCMS
2023-02-14 03:26:57 (1479393): Guest Log: [INFO] Testing connection to CMS-Factory
2023-02-14 03:26:57 (1479393): Guest Log: [INFO] Testing connection to CMS-Frontier
2023-02-14 03:26:57 (1479393): Guest Log: [INFO] Testing connection to Frontier
2023-02-14 03:26:57 (1479393): Guest Log: [INFO] Could not find a local HTTP proxy
2023-02-14 03:26:57 (1479393): Guest Log: [INFO] CVMFS and Frontier will have to use DIRECT connections
2023-02-14 03:26:57 (1479393): Guest Log: [INFO] This makes the application less efficient
2023-02-14 03:26:57 (1479393): Guest Log: [INFO] It also puts higher load on the project servers
2023-02-14 03:26:57 (1479393): Guest Log: [INFO] Setting up a local HTTP proxy is highly recommended
2023-02-14 03:26:57 (1479393): Guest Log: [INFO] Advice can be found in the project forum
2023-02-14 03:26:57 (1479393): Guest Log: [INFO] Reloading and probing the CVMFS configuration
2023-02-14 03:26:57 (1479393): Guest Log: [INFO] Probing /cvmfs/grid.cern.ch... OK
2023-02-14 03:26:57 (1479393): Guest Log: [INFO] Probing /cvmfs/oasis.opensciencegrid.org... OK
2023-02-14 03:26:57 (1479393): Guest Log: [INFO] Probing /cvmfs/singularity.opensciencegrid.org... OK
2023-02-14 03:26:57 (1479393): Guest Log: [INFO] Probing /cvmfs/cms-ib.cern.ch... OK
2023-02-14 03:26:57 (1479393): Guest Log: [INFO] Probing /cvmfs/cms.cern.ch... OK
2023-02-14 03:26:57 (1479393): Guest Log: [INFO] Excerpt from "cvmfs_config stat": VERSION HOST PROXY
2023-02-14 03:26:57 (1479393): Guest Log: [INFO] 2.7.2.0 http://s1swinburne-cvmfs.openhtc.io:8080 DIRECT
2023-02-14 03:26:57 (1479393): Guest Log: [INFO] Environment HTTP proxy: not set
2023-02-14 03:26:57 (1479393): Guest Log: [INFO] Reading volunteer information
2023-02-14 03:26:57 (1479393): Guest Log: [INFO] Requesting an X509 credential from LHC@home
2023-02-14 03:26:57 (1479393): Guest Log: [INFO] Requesting an X509 credential from vLHC@home-dev
2023-02-14 03:26:57 (1479393): Guest Log: [INFO] CMS application starting. Check log files.
2023-02-14 03:26:57 (1479393): Guest Log: [INFO] Requesting an idtoken from LHC@home
2023-02-14 03:26:57 (1479393): Guest Log: [INFO] Requesting an idtoken from vLHC@home-dev
2023-02-14 03:26:57 (1479393): Starting VM using VBoxManage interface. (boinc_dc10c48fc5a480bc, slot#14)
2023-02-14 03:27:10 (1479393): Successfully started VM. (PID = '1479491')
2023-02-14 03:27:10 (1479393): Reporting VM Process ID to BOINC.
2023-02-14 03:27:10 (1479393): VM state change detected. (old = 'poweredoff', new = 'running')
2023-02-14 03:27:10 (1479393): Detected: Web Application Enabled (http://localhost:38623)
2023-02-14 03:27:10 (1479393): Status Report: Job Duration: '64800.000000'
2023-02-14 03:27:10 (1479393): Status Report: Elapsed Time: '25581.128443'
2023-02-14 03:27:10 (1479393): Status Report: CPU Time: '30003.070000'
2023-02-14 03:27:10 (1479393): Preference change detected
2023-02-14 03:27:10 (1479393): Setting CPU throttle for VM. (100%)
2023-02-14 03:27:10 (1479393): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2023-02-14 03:27:16 (1479393): Guest Log: 01:00:11.322940 timesync vgsvcTimeSyncWorker: Radical host time change: 6 860 112 000 000ns (HostNow=1 676 341 635 289 000 000 ns HostLast=1 676 334 775 177 000 000 ns)
2023-02-14 03:27:26 (1479393): Guest Log: 01:00:21.323374 timesync vgsvcTimeSyncWorker: Radical guest time change: 6 856 231 878 000ns (GuestNow=1 676 341 645 290 821 000 ns GuestLast=1 676 334 789 058 943 000 ns fSetTimeLastLoop=true )
2023-02-14 04:27:44 (1479393): Stopping VM.
2023-02-14 04:28:40 (1479393): Successfully stopped VM.
2023-02-14 06:22:35 (1558076): Detected: vboxwrapper 26206
2023-02-14 06:22:35 (1558076): Detected: BOINC client v7.16.11
2023-02-14 06:22:36 (1558076): Detected: VirtualBox VboxManage Interface (Version: 6.1.26)
2023-02-14 06:22:36 (1558076): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2023-02-14 06:22:36 (1558076): Guest Log: 01:00:11.322940 timesync vgsvcTimeSyncWorker: Radical host time change: 6 860 112 000 000ns (HostNow=1 676 341 635 289 000 000 ns HostLast=1 676 334 775 177 000 000 ns)
2023-02-14 06:22:36 (1558076): Guest Log: 01:00:21.323374 timesync vgsvcTimeSyncWorker: Radical guest time change: 6 856 231 878 000ns (GuestNow=1 676 341 645 290 821 000 ns GuestLast=1 676 334 789 058 943 000 ns fSetTimeLastLoop=true )
2023-02-14 06:22:36 (1558076): Starting VM using VBoxManage interface. (boinc_dc10c48fc5a480bc, slot#14)
2023-02-14 06:22:49 (1558076): Successfully started VM. (PID = '1558174')
2023-02-14 06:22:49 (1558076): Reporting VM Process ID to BOINC.
2023-02-14 06:22:49 (1558076): VM state change detected. (old = 'poweredoff', new = 'running')
2023-02-14 06:22:49 (1558076): Detected: Web Application Enabled (http://localhost:38623)
2023-02-14 06:22:49 (1558076): Status Report: Job Duration: '64800.000000'
2023-02-14 06:22:49 (1558076): Status Report: Elapsed Time: '29252.128443'
2023-02-14 06:22:49 (1558076): Status Report: CPU Time: '34238.000000'
2023-02-14 06:22:49 (1558076): Preference change detected
2023-02-14 06:22:49 (1558076): Setting CPU throttle for VM. (100%)
2023-02-14 06:22:49 (1558076): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2023-02-14 06:22:55 (1558076): Guest Log: 02:00:52.648077 timesync vgsvcTimeSyncWorker: Radical host time change: 6 912 212 000 000ns (HostNow=1 676 352 174 931 000 000 ns HostLast=1 676 345 262 719 000 000 ns)
2023-02-14 06:23:04 (1558076): Guest Log: 02:01:02.650845 timesync vgsvcTimeSyncWorker: Radical guest time change: 6 908 319 051 000ns (GuestNow=1 676 352 184 934 145 000 ns GuestLast=1 676 345 276 615 094 000 ns fSetTimeLastLoop=true )
2023-02-14 07:22:46 (1558076): Stopping VM.
2023-02-14 09:15:09 (1617728): Detected: vboxwrapper 26206
2023-02-14 09:15:09 (1617728): Detected: BOINC client v7.16.11
2023-02-14 09:15:09 (1617728): Detected: VirtualBox VboxManage Interface (Version: 6.1.26)
2023-02-14 09:15:09 (1617728): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2023-02-14 09:15:09 (1617728): Guest Log: 02:00:52.648077 timesync vgsvcTimeSyncWorker: Radical host time change: 6 912 212 000 000ns (HostNow=1 676 352 174 931 000 000 ns HostLast=1 676 345 262 719 000 000 ns)
2023-02-14 09:15:09 (1617728): Guest Log: 02:01:02.650845 timesync vgsvcTimeSyncWorker: Radical guest time change: 6 908 319 051 000ns (GuestNow=1 676 352 184 934 145 000 ns GuestLast=1 676 345 276 615 094 000 ns fSetTimeLastLoop=true )
2023-02-14 09:15:09 (1617728): Starting VM using VBoxManage interface. (boinc_dc10c48fc5a480bc, slot#14)
2023-02-14 09:15:10 (1617728): Successfully started VM. (PID = '1617830')
2023-02-14 09:15:10 (1617728): Reporting VM Process ID to BOINC.
2023-02-14 09:15:10 (1617728): Guest Log: BIOS: VirtualBox 6.1.25
2023-02-14 09:15:10 (1617728): Guest Log: CPUID EDX: 0x178bfbff
2023-02-14 09:15:10 (1617728): Guest Log: BIOS: No PCI IDE controller, not probing IDE
2023-02-14 09:15:10 (1617728): Guest Log: BIOS: AHCI 0-P#0: PCHS=16383/16/63 LCHS=1024/255/63 0x0000000002800000 sectors
2023-02-14 09:15:10 (1617728): VM state change detected. (old = 'poweredoff', new = 'running')
2023-02-14 09:15:10 (1617728): Detected: Web Application Enabled (http://localhost:38623)
2023-02-14 09:15:10 (1617728): Status Report: Job Duration: '64800.000000'
2023-02-14 09:15:10 (1617728): Status Report: Elapsed Time: '32885.128443'
2023-02-14 09:15:10 (1617728): Status Report: CPU Time: '37844.020000'
2023-02-14 09:15:10 (1617728): Preference change detected
2023-02-14 09:15:10 (1617728): Setting CPU throttle for VM. (100%)
2023-02-14 09:15:10 (1617728): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2023-02-14 09:15:12 (1617728): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032
2023-02-14 09:15:12 (1617728): Guest Log: BIOS: Booting from Hard Disk...
2023-02-14 09:15:15 (1617728): Guest Log: BIOS: KBD: unsupported int 16h function 03
2023-02-14 09:15:15 (1617728): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 
2023-02-14 09:16:06 (1617728): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds
2023-02-14 09:16:06 (1617728): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000)
2023-02-14 09:16:10 (1617728): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log
2023-02-14 09:16:10 (1617728): Guest Log: 00:00:00.001965 main     Log opened 2023-02-14T08:16:10.326945000Z
2023-02-14 09:16:10 (1617728): Guest Log: 00:00:00.002112 main     OS Product: Linux
2023-02-14 09:16:10 (1617728): Guest Log: 00:00:00.002171 main     OS Release: 4.14.232-19.cernvm.x86_64
2023-02-14 09:16:10 (1617728): Guest Log: 00:00:00.002225 main     OS Version: #1 SMP Fri Apr 30 17:12:25 CEST 2021
2023-02-14 09:16:10 (1617728): Guest Log: 00:00:00.002277 main     Executable: /usr/sbin/VBoxService
2023-02-14 09:16:10 (1617728): Guest Log: 00:00:00.002278 main     Process ID: 2718
2023-02-14 09:16:10 (1617728): Guest Log: 00:00:00.002279 main     Package type: LINUX_64BITS_GENERIC
2023-02-14 09:16:10 (1617728): Guest Log: 00:00:00.003842 main     5.2.6 r120293 started. Verbose level = 0
2023-02-14 09:16:36 (1617728): Guest Log: [INFO] Mounting the shared directory
2023-02-14 09:16:37 (1617728): Guest Log: [INFO] Shared directory mounted, enabling vboxmonitor
2023-02-14 09:16:37 (1617728): Guest Log: [INFO] Sourcing essential functions from /cvmfs/grid.cern.ch
2023-02-14 09:16:38 (1617728): Guest Log: [INFO] Testing connection to cern.ch
2023-02-14 09:16:38 (1617728): Guest Log: [INFO] Testing connection to VCCS
2023-02-14 09:16:38 (1617728): Guest Log: [INFO] Testing connection to HTCondor
2023-02-14 09:16:39 (1617728): Guest Log: [INFO] Testing connection to WMAgent
2023-02-14 09:16:39 (1617728): Guest Log: [INFO] Testing connection to EOSCMS
2023-02-14 09:16:40 (1617728): Guest Log: [INFO] Testing connection to CMS-Factory
2023-02-14 09:16:40 (1617728): Guest Log: [INFO] Testing connection to CMS-Frontier
2023-02-14 09:16:41 (1617728): Guest Log: [INFO] Testing connection to Frontier
2023-02-14 09:16:42 (1617728): Guest Log: [INFO] Could not find a local HTTP proxy
2023-02-14 09:16:43 (1617728): Guest Log: [INFO] CVMFS and Frontier will have to use DIRECT connections
2023-02-14 09:16:43 (1617728): Guest Log: [INFO] This makes the application less efficient
2023-02-14 09:16:43 (1617728): Guest Log: [INFO] It also puts higher load on the project servers
2023-02-14 09:16:43 (1617728): Guest Log: [INFO] Setting up a local HTTP proxy is highly recommended
2023-02-14 09:16:43 (1617728): Guest Log: [INFO] Advice can be found in the project forum
2023-02-14 09:16:44 (1617728): Guest Log: [INFO] Reloading and probing the CVMFS configuration
2023-02-14 09:16:55 (1617728): Guest Log: [INFO] Probing /cvmfs/grid.cern.ch... OK
2023-02-14 09:17:00 (1617728): Guest Log: [INFO] Probing /cvmfs/cms-ib.cern.ch... OK
2023-02-14 09:17:00 (1617728): Guest Log: [INFO] Probing /cvmfs/oasis.opensciencegrid.org... OK
2023-02-14 09:17:00 (1617728): Guest Log: [INFO] Probing /cvmfs/singularity.opensciencegrid.org... OK
2023-02-14 09:17:01 (1617728): Guest Log: [INFO] Probing /cvmfs/cms.cern.ch... OK
2023-02-14 09:17:04 (1617728): Guest Log: [INFO] Excerpt from "cvmfs_config stat": VERSION HOST PROXY
2023-02-14 09:17:04 (1617728): Guest Log: [INFO] 2.7.2.0 http://s1bnl-cvmfs.openhtc.io DIRECT
2023-02-14 09:17:04 (1617728): Guest Log: [INFO] Environment HTTP proxy: not set
2023-02-14 09:17:05 (1617728): Guest Log: [INFO] Reading volunteer information
2023-02-14 09:17:14 (1617728): Guest Log: [INFO] Requesting an X509 credential from LHC@home
2023-02-14 09:17:15 (1617728): Guest Log: [INFO] Requesting an X509 credential from vLHC@home-dev
2023-02-14 09:17:16 (1617728): Guest Log: [INFO] CMS application starting. Check log files.
2023-02-14 09:17:17 (1617728): Guest Log: [INFO] Requesting an idtoken from LHC@home
2023-02-14 09:17:18 (1617728): Guest Log: [INFO] Requesting an idtoken from vLHC@home-dev
2023-02-14 10:15:26 (1617728): Stopping VM.
2023-02-14 10:15:53 (1617728): Successfully stopped VM.
2023-02-14 12:16:09 (1693264): Detected: vboxwrapper 26206
2023-02-14 12:16:09 (1693264): Detected: BOINC client v7.16.11
2023-02-14 12:16:09 (1693264): Detected: VirtualBox VboxManage Interface (Version: 6.1.26)
2023-02-14 12:16:09 (1693264): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2023-02-14 12:16:09 (1693264): Guest Log: BIOS: VirtualBox 6.1.25
2023-02-14 12:16:09 (1693264): Guest Log: CPUID EDX: 0x178bfbff
2023-02-14 12:16:09 (1693264): Guest Log: BIOS: No PCI IDE controller, not probing IDE
2023-02-14 12:16:09 (1693264): Guest Log: BIOS: AHCI 0-P#0: PCHS=16383/16/63 LCHS=1024/255/63 0x0000000002800000 sectors
2023-02-14 12:16:09 (1693264): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032
2023-02-14 12:16:09 (1693264): Guest Log: BIOS: Booting from Hard Disk...
2023-02-14 12:16:09 (1693264): Guest Log: BIOS: KBD: unsupported int 16h function 03
2023-02-14 12:16:09 (1693264): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 
2023-02-14 12:16:09 (1693264): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds
2023-02-14 12:16:09 (1693264): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000)
2023-02-14 12:16:09 (1693264): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log
2023-02-14 12:16:09 (1693264): Guest Log: 00:00:00.001965 main     Log opened 2023-02-14T08:16:10.326945000Z
2023-02-14 12:16:09 (1693264): Guest Log: 00:00:00.002112 main     OS Product: Linux
2023-02-14 12:16:09 (1693264): Guest Log: 00:00:00.002171 main     OS Release: 4.14.232-19.cernvm.x86_64
2023-02-14 12:16:09 (1693264): Guest Log: 00:00:00.002225 main     OS Version: #1 SMP Fri Apr 30 17:12:25 CEST 2021
2023-02-14 12:16:09 (1693264): Guest Log: 00:00:00.002277 main     Executable: /usr/sbin/VBoxService
2023-02-14 12:16:09 (1693264): Guest Log: 00:00:00.002278 main     Process ID: 2718
2023-02-14 12:16:09 (1693264): Guest Log: 00:00:00.002279 main     Package type: LINUX_64BITS_GENERIC
2023-02-14 12:16:09 (1693264): Guest Log: 00:00:00.003842 main     5.2.6 r120293 started. Verbose level = 0
2023-02-14 12:16:09 (1693264): Guest Log: [INFO] Mounting the shared directory
2023-02-14 12:16:09 (1693264): Guest Log: [INFO] Shared directory mounted, enabling vboxmonitor
2023-02-14 12:16:09 (1693264): Guest Log: [INFO] Sourcing essential functions from /cvmfs/grid.cern.ch
2023-02-14 12:16:09 (1693264): Guest Log: [INFO] Testing connection to cern.ch
2023-02-14 12:16:09 (1693264): Guest Log: [INFO] Testing connection to VCCS
2023-02-14 12:16:09 (1693264): Guest Log: [INFO] Testing connection to HTCondor
2023-02-14 12:16:09 (1693264): Guest Log: [INFO] Testing connection to WMAgent
2023-02-14 12:16:09 (1693264): Guest Log: [INFO] Testing connection to EOSCMS
2023-02-14 12:16:09 (1693264): Guest Log: [INFO] Testing connection to CMS-Factory
2023-02-14 12:16:09 (1693264): Guest Log: [INFO] Testing connection to CMS-Frontier
2023-02-14 12:16:09 (1693264): Guest Log: [INFO] Testing connection to Frontier
2023-02-14 12:16:09 (1693264): Guest Log: [INFO] Could not find a local HTTP proxy
2023-02-14 12:16:09 (1693264): Guest Log: [INFO] CVMFS and Frontier will have to use DIRECT connections
2023-02-14 12:16:09 (1693264): Guest Log: [INFO] This makes the application less efficient
2023-02-14 12:16:09 (1693264): Guest Log: [INFO] It also puts higher load on the project servers
2023-02-14 12:16:09 (1693264): Guest Log: [INFO] Setting up a local HTTP proxy is highly recommended
2023-02-14 12:16:09 (1693264): Guest Log: [INFO] Advice can be found in the project forum
2023-02-14 12:16:09 (1693264): Guest Log: [INFO] Reloading and probing the CVMFS configuration
2023-02-14 12:16:09 (1693264): Guest Log: [INFO] Probing /cvmfs/grid.cern.ch... OK
2023-02-14 12:16:09 (1693264): Guest Log: [INFO] Probing /cvmfs/cms-ib.cern.ch... OK
2023-02-14 12:16:09 (1693264): Guest Log: [INFO] Probing /cvmfs/oasis.opensciencegrid.org... OK
2023-02-14 12:16:09 (1693264): Guest Log: [INFO] Probing /cvmfs/singularity.opensciencegrid.org... OK
2023-02-14 12:16:09 (1693264): Guest Log: [INFO] Probing /cvmfs/cms.cern.ch... OK
2023-02-14 12:16:09 (1693264): Guest Log: [INFO] Excerpt from "cvmfs_config stat": VERSION HOST PROXY
2023-02-14 12:16:09 (1693264): Guest Log: [INFO] 2.7.2.0 http://s1bnl-cvmfs.openhtc.io DIRECT
2023-02-14 12:16:09 (1693264): Guest Log: [INFO] Environment HTTP proxy: not set
2023-02-14 12:16:09 (1693264): Guest Log: [INFO] Reading volunteer information
2023-02-14 12:16:09 (1693264): Guest Log: [INFO] Requesting an X509 credential from LHC@home
2023-02-14 12:16:09 (1693264): Guest Log: [INFO] Requesting an X509 credential from vLHC@home-dev
2023-02-14 12:16:09 (1693264): Guest Log: [INFO] CMS application starting. Check log files.
2023-02-14 12:16:09 (1693264): Guest Log: [INFO] Requesting an idtoken from LHC@home
2023-02-14 12:16:09 (1693264): Guest Log: [INFO] Requesting an idtoken from vLHC@home-dev
2023-02-14 12:16:09 (1693264): Starting VM using VBoxManage interface. (boinc_dc10c48fc5a480bc, slot#14)
2023-02-14 12:16:22 (1693264): Successfully started VM. (PID = '1693364')
2023-02-14 12:16:22 (1693264): Reporting VM Process ID to BOINC.
2023-02-14 12:16:22 (1693264): VM state change detected. (old = 'poweredoff', new = 'running')
2023-02-14 12:16:22 (1693264): Detected: Web Application Enabled (http://localhost:38623)
2023-02-14 12:16:22 (1693264): Status Report: Job Duration: '64800.000000'
2023-02-14 12:16:22 (1693264): Status Report: Elapsed Time: '36537.128443'
2023-02-14 12:16:22 (1693264): Status Report: CPU Time: '42625.950000'
2023-02-14 12:16:22 (1693264): Preference change detected
2023-02-14 12:16:22 (1693264): Setting CPU throttle for VM. (100%)
2023-02-14 12:16:22 (1693264): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2023-02-14 12:16:25 (1693264): Guest Log: 00:59:23.999527 timesync vgsvcTimeSyncWorker: Radical host time change: 7 264 647 000 000ns (HostNow=1 676 373 385 129 000 000 ns HostLast=1 676 366 120 482 000 000 ns)
2023-02-14 12:16:35 (1693264): Guest Log: 00:59:34.001523 timesync vgsvcTimeSyncWorker: Radical guest time change: 7 260 809 226 000ns (GuestNow=1 676 373 395 132 342 000 ns GuestLast=1 676 366 134 323 116 000 ns fSetTimeLastLoop=true )
2023-02-14 13:16:32 (1693264): Stopping VM.
2023-02-14 13:17:31 (1693264): Successfully stopped VM.
2023-02-14 15:10:26 (1764611): Detected: vboxwrapper 26206
2023-02-14 15:10:26 (1764611): Detected: BOINC client v7.16.11
2023-02-14 15:10:26 (1764611): Detected: VirtualBox VboxManage Interface (Version: 6.1.26)
2023-02-14 15:10:26 (1764611): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2023-02-14 15:10:27 (1764611): Guest Log: 00:59:23.999527 timesync vgsvcTimeSyncWorker: Radical host time change: 7 264 647 000 000ns (HostNow=1 676 373 385 129 000 000 ns HostLast=1 676 366 120 482 000 000 ns)
2023-02-14 15:10:27 (1764611): Guest Log: 00:59:34.001523 timesync vgsvcTimeSyncWorker: Radical guest time change: 7 260 809 226 000ns (GuestNow=1 676 373 395 132 342 000 ns GuestLast=1 676 366 134 323 116 000 ns fSetTimeLastLoop=true )
2023-02-14 15:10:27 (1764611): Starting VM using VBoxManage interface. (boinc_dc10c48fc5a480bc, slot#14)
2023-02-14 15:10:39 (1764611): Successfully started VM. (PID = '1764709')
2023-02-14 15:10:39 (1764611): Reporting VM Process ID to BOINC.
2023-02-14 15:10:39 (1764611): VM state change detected. (old = 'poweredoff', new = 'running')
2023-02-14 15:10:39 (1764611): Detected: Web Application Enabled (http://localhost:38623)
2023-02-14 15:10:39 (1764611): Status Report: Job Duration: '64800.000000'
2023-02-14 15:10:39 (1764611): Status Report: Elapsed Time: '40183.128443'
2023-02-14 15:10:39 (1764611): Status Report: CPU Time: '46811.550000'
2023-02-14 15:10:39 (1764611): Preference change detected
2023-02-14 15:10:39 (1764611): Setting CPU throttle for VM. (100%)
2023-02-14 15:10:39 (1764611): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2023-02-14 15:10:40 (1764611): Guest Log: 01:59:36.922385 timesync vgsvcTimeSyncWorker: Radical host time change: 6 855 752 000 000ns (HostNow=1 676 383 839 934 000 000 ns HostLast=1 676 376 984 182 000 000 ns)
2023-02-14 15:10:50 (1764611): Guest Log: 01:59:46.925655 timesync vgsvcTimeSyncWorker: Radical guest time change: 6 851 884 776 000ns (GuestNow=1 676 383 849 937 609 000 ns GuestLast=1 676 376 998 052 833 000 ns fSetTimeLastLoop=true )
2023-02-14 16:10:38 (1764611): Stopping VM.
2023-02-14 18:03:47 (1836952): Detected: vboxwrapper 26206
2023-02-14 18:03:47 (1836952): Detected: BOINC client v7.16.11
2023-02-14 18:03:47 (1836952): Detected: VirtualBox VboxManage Interface (Version: 6.1.26)
2023-02-14 18:03:47 (1836952): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2023-02-14 18:03:48 (1836952): Guest Log: 01:59:36.922385 timesync vgsvcTimeSyncWorker: Radical host time change: 6 855 752 000 000ns (HostNow=1 676 383 839 934 000 000 ns HostLast=1 676 376 984 182 000 000 ns)
2023-02-14 18:03:48 (1836952): Guest Log: 01:59:46.925655 timesync vgsvcTimeSyncWorker: Radical guest time change: 6 851 884 776 000ns (GuestNow=1 676 383 849 937 609 000 ns GuestLast=1 676 376 998 052 833 000 ns fSetTimeLastLoop=true )
2023-02-14 18:03:48 (1836952): Starting VM using VBoxManage interface. (boinc_dc10c48fc5a480bc, slot#14)
2023-02-14 18:03:49 (1836952): Successfully started VM. (PID = '1837050')
2023-02-14 18:03:49 (1836952): Reporting VM Process ID to BOINC.
2023-02-14 18:03:49 (1836952): Guest Log: BIOS: VirtualBox 6.1.25
2023-02-14 18:03:49 (1836952): Guest Log: CPUID EDX: 0x178bfbff
2023-02-14 18:03:49 (1836952): Guest Log: BIOS: No PCI IDE controller, not probing IDE
2023-02-14 18:03:49 (1836952): Guest Log: BIOS: AHCI 0-P#0: PCHS=16383/16/63 LCHS=1024/255/63 0x0000000002800000 sectors
2023-02-14 18:03:49 (1836952): VM state change detected. (old = 'poweredoff', new = 'running')
2023-02-14 18:03:49 (1836952): Detected: Web Application Enabled (http://localhost:38623)
2023-02-14 18:03:49 (1836952): Status Report: Job Duration: '64800.000000'
2023-02-14 18:03:49 (1836952): Status Report: Elapsed Time: '43818.128443'
2023-02-14 18:03:49 (1836952): Status Report: CPU Time: '50424.050000'
2023-02-14 18:03:49 (1836952): Preference change detected
2023-02-14 18:03:49 (1836952): Setting CPU throttle for VM. (100%)
2023-02-14 18:03:49 (1836952): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2023-02-14 18:03:51 (1836952): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032
2023-02-14 18:03:51 (1836952): Guest Log: BIOS: Booting from Hard Disk...
2023-02-14 18:03:54 (1836952): Guest Log: BIOS: KBD: unsupported int 16h function 03
2023-02-14 18:03:54 (1836952): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 
2023-02-14 18:04:36 (1836952): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds
2023-02-14 18:04:36 (1836952): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000)
2023-02-14 18:04:39 (1836952): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log
2023-02-14 18:04:39 (1836952): Guest Log: 00:00:00.000154 main     Log opened 2023-02-14T17:04:38.949579000Z
2023-02-14 18:04:39 (1836952): Guest Log: 00:00:00.000472 main     OS Product: Linux
2023-02-14 18:04:39 (1836952): Guest Log: 00:00:00.000525 main     OS Release: 4.14.232-19.cernvm.x86_64
2023-02-14 18:04:39 (1836952): Guest Log: 00:00:00.000574 main     OS Version: #1 SMP Fri Apr 30 17:12:25 CEST 2021
2023-02-14 18:04:39 (1836952): Guest Log: 00:00:00.000661 main     Executable: /usr/sbin/VBoxService
2023-02-14 18:04:39 (1836952): Guest Log: 00:00:00.000662 main     Process ID: 2704
2023-02-14 18:04:39 (1836952): Guest Log: 00:00:00.000663 main     Package type: LINUX_64BITS_GENERIC
2023-02-14 18:04:39 (1836952): Guest Log: 00:00:00.002115 main     5.2.6 r120293 started. Verbose level = 0
2023-02-14 18:05:05 (1836952): Guest Log: [INFO] Mounting the shared directory
2023-02-14 18:05:05 (1836952): Guest Log: [INFO] Shared directory mounted, enabling vboxmonitor
2023-02-14 18:05:05 (1836952): Guest Log: [INFO] Sourcing essential functions from /cvmfs/grid.cern.ch
2023-02-14 18:05:06 (1836952): Guest Log: [INFO] Testing connection to cern.ch
2023-02-14 18:05:06 (1836952): Guest Log: [INFO] Testing connection to VCCS
2023-02-14 18:05:07 (1836952): Guest Log: [INFO] Testing connection to HTCondor
2023-02-14 18:05:07 (1836952): Guest Log: [INFO] Testing connection to WMAgent
2023-02-14 18:05:08 (1836952): Guest Log: [INFO] Testing connection to EOSCMS
2023-02-14 18:05:08 (1836952): Guest Log: [INFO] Testing connection to CMS-Factory
2023-02-14 18:05:09 (1836952): Guest Log: [INFO] Testing connection to CMS-Frontier
2023-02-14 18:05:09 (1836952): Guest Log: [INFO] Testing connection to Frontier
2023-02-14 18:05:10 (1836952): Guest Log: [INFO] Could not find a local HTTP proxy
2023-02-14 18:05:11 (1836952): Guest Log: [INFO] CVMFS and Frontier will have to use DIRECT connections
2023-02-14 18:05:11 (1836952): Guest Log: [INFO] This makes the application less efficient
2023-02-14 18:05:11 (1836952): Guest Log: [INFO] It also puts higher load on the project servers
2023-02-14 18:05:11 (1836952): Guest Log: [INFO] Setting up a local HTTP proxy is highly recommended
2023-02-14 18:05:12 (1836952): Guest Log: [INFO] Advice can be found in the project forum
2023-02-14 18:05:12 (1836952): Guest Log: [INFO] Reloading and probing the CVMFS configuration
2023-02-14 18:05:24 (1836952): Guest Log: [INFO] Probing /cvmfs/grid.cern.ch... OK
2023-02-14 18:05:29 (1836952): Guest Log: [INFO] Probing /cvmfs/singularity.opensciencegrid.org... OK
2023-02-14 18:05:29 (1836952): Guest Log: [INFO] Probing /cvmfs/cms-ib.cern.ch... OK
2023-02-14 18:05:30 (1836952): Guest Log: [INFO] Probing /cvmfs/oasis.opensciencegrid.org... OK
2023-02-14 18:05:31 (1836952): Guest Log: [INFO] Probing /cvmfs/cms.cern.ch... OK
2023-02-14 18:05:33 (1836952): Guest Log: [INFO] Excerpt from "cvmfs_config stat": VERSION HOST PROXY
2023-02-14 18:05:33 (1836952): Guest Log: [INFO] 2.7.2.0 http://s1bnl-cvmfs.openhtc.io DIRECT
2023-02-14 18:05:33 (1836952): Guest Log: [INFO] Environment HTTP proxy: not set
2023-02-14 18:05:34 (1836952): Guest Log: [INFO] Reading volunteer information
2023-02-14 18:05:38 (1836952): Guest Log: [INFO] Requesting an X509 credential from LHC@home
2023-02-14 18:05:39 (1836952): Guest Log: [INFO] Requesting an X509 credential from vLHC@home-dev
2023-02-14 18:05:40 (1836952): Guest Log: [INFO] CMS application starting. Check log files.
2023-02-14 18:05:41 (1836952): Guest Log: [INFO] Requesting an idtoken from LHC@home
2023-02-14 18:05:41 (1836952): Guest Log: [INFO] Requesting an idtoken from vLHC@home-dev
2023-02-14 19:04:16 (1836952): Stopping VM.
2023-02-14 23:14:41 (1964229): Detected: vboxwrapper 26206
2023-02-14 23:14:41 (1964229): Detected: BOINC client v7.16.11
2023-02-14 23:14:41 (1964229): Detected: VirtualBox VboxManage Interface (Version: 6.1.26)
2023-02-14 23:14:41 (1964229): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2023-02-14 23:14:41 (1964229): Guest Log: BIOS: VirtualBox 6.1.25
2023-02-14 23:14:41 (1964229): Guest Log: CPUID EDX: 0x178bfbff
2023-02-14 23:14:41 (1964229): Guest Log: BIOS: No PCI IDE controller, not probing IDE
2023-02-14 23:14:41 (1964229): Guest Log: BIOS: AHCI 0-P#0: PCHS=16383/16/63 LCHS=1024/255/63 0x0000000002800000 sectors
2023-02-14 23:14:41 (1964229): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032
2023-02-14 23:14:41 (1964229): Guest Log: BIOS: Booting from Hard Disk...
2023-02-14 23:14:41 (1964229): Guest Log: BIOS: KBD: unsupported int 16h function 03
2023-02-14 23:14:41 (1964229): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 
2023-02-14 23:14:41 (1964229): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds
2023-02-14 23:14:41 (1964229): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000)
2023-02-14 23:14:41 (1964229): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log
2023-02-14 23:14:41 (1964229): Guest Log: 00:00:00.000154 main     Log opened 2023-02-14T17:04:38.949579000Z
2023-02-14 23:14:41 (1964229): Guest Log: 00:00:00.000472 main     OS Product: Linux
2023-02-14 23:14:41 (1964229): Guest Log: 00:00:00.000525 main     OS Release: 4.14.232-19.cernvm.x86_64
2023-02-14 23:14:41 (1964229): Guest Log: 00:00:00.000574 main     OS Version: #1 SMP Fri Apr 30 17:12:25 CEST 2021
2023-02-14 23:14:41 (1964229): Guest Log: 00:00:00.000661 main     Executable: /usr/sbin/VBoxService
2023-02-14 23:14:41 (1964229): Guest Log: 00:00:00.000662 main     Process ID: 2704
2023-02-14 23:14:41 (1964229): Guest Log: 00:00:00.000663 main     Package type: LINUX_64BITS_GENERIC
2023-02-14 23:14:41 (1964229): Guest Log: 00:00:00.002115 main     5.2.6 r120293 started. Verbose level = 0
2023-02-14 23:14:41 (1964229): Guest Log: [INFO] Mounting the shared directory
2023-02-14 23:14:41 (1964229): Guest Log: [INFO] Shared directory mounted, enabling vboxmonitor
2023-02-14 23:14:41 (1964229): Guest Log: [INFO] Sourcing essential functions from /cvmfs/grid.cern.ch
2023-02-14 23:14:41 (1964229): Guest Log: [INFO] Testing connection to cern.ch
2023-02-14 23:14:41 (1964229): Guest Log: [INFO] Testing connection to VCCS
2023-02-14 23:14:41 (1964229): Guest Log: [INFO] Testing connection to HTCondor
2023-02-14 23:14:41 (1964229): Guest Log: [INFO] Testing connection to WMAgent
2023-02-14 23:14:41 (1964229): Guest Log: [INFO] Testing connection to EOSCMS
2023-02-14 23:14:41 (1964229): Guest Log: [INFO] Testing connection to CMS-Factory
2023-02-14 23:14:41 (1964229): Guest Log: [INFO] Testing connection to CMS-Frontier
2023-02-14 23:14:41 (1964229): Guest Log: [INFO] Testing connection to Frontier
2023-02-14 23:14:41 (1964229): Guest Log: [INFO] Could not find a local HTTP proxy
2023-02-14 23:14:41 (1964229): Guest Log: [INFO] CVMFS and Frontier will have to use DIRECT connections
2023-02-14 23:14:41 (1964229): Guest Log: [INFO] This makes the application less efficient
2023-02-14 23:14:41 (1964229): Guest Log: [INFO] It also puts higher load on the project servers
2023-02-14 23:14:41 (1964229): Guest Log: [INFO] Setting up a local HTTP proxy is highly recommended
2023-02-14 23:14:41 (1964229): Guest Log: [INFO] Advice can be found in the project forum
2023-02-14 23:14:41 (1964229): Guest Log: [INFO] Reloading and probing the CVMFS configuration
2023-02-14 23:14:41 (1964229): Guest Log: [INFO] Probing /cvmfs/grid.cern.ch... OK
2023-02-14 23:14:41 (1964229): Guest Log: [INFO] Probing /cvmfs/singularity.opensciencegrid.org... OK
2023-02-14 23:14:41 (1964229): Guest Log: [INFO] Probing /cvmfs/cms-ib.cern.ch... OK
2023-02-14 23:14:41 (1964229): Guest Log: [INFO] Probing /cvmfs/oasis.opensciencegrid.org... OK
2023-02-14 23:14:41 (1964229): Guest Log: [INFO] Probing /cvmfs/cms.cern.ch... OK
2023-02-14 23:14:41 (1964229): Guest Log: [INFO] Excerpt from "cvmfs_config stat": VERSION HOST PROXY
2023-02-14 23:14:41 (1964229): Guest Log: [INFO] 2.7.2.0 http://s1bnl-cvmfs.openhtc.io DIRECT
2023-02-14 23:14:41 (1964229): Guest Log: [INFO] Environment HTTP proxy: not set
2023-02-14 23:14:41 (1964229): Guest Log: [INFO] Reading volunteer information
2023-02-14 23:14:41 (1964229): Guest Log: [INFO] Requesting an X509 credential from LHC@home
2023-02-14 23:14:41 (1964229): Guest Log: [INFO] Requesting an X509 credential from vLHC@home-dev
2023-02-14 23:14:41 (1964229): Guest Log: [INFO] CMS application starting. Check log files.
2023-02-14 23:14:41 (1964229): Guest Log: [INFO] Requesting an idtoken from LHC@home
2023-02-14 23:14:41 (1964229): Guest Log: [INFO] Requesting an idtoken from vLHC@home-dev
2023-02-14 23:14:41 (1964229): Starting VM using VBoxManage interface. (boinc_dc10c48fc5a480bc, slot#14)
2023-02-14 23:14:42 (1964229): Successfully started VM. (PID = '1964320')
2023-02-14 23:14:42 (1964229): Reporting VM Process ID to BOINC.
2023-02-14 23:14:42 (1964229): Guest Log: BIOS: VirtualBox 6.1.25
2023-02-14 23:14:42 (1964229): Guest Log: CPUID EDX: 0x178bfbff
2023-02-14 23:14:42 (1964229): Guest Log: BIOS: No PCI IDE controller, not probing IDE
2023-02-14 23:14:42 (1964229): Guest Log: BIOS: AHCI 0-P#0: PCHS=16383/16/63 LCHS=1024/255/63 0x0000000002800000 sectors
2023-02-14 23:14:42 (1964229): VM state change detected. (old = 'poweredoff', new = 'running')
2023-02-14 23:14:42 (1964229): Detected: Web Application Enabled (http://localhost:38623)
2023-02-14 23:14:42 (1964229): Status Report: Job Duration: '64800.000000'
2023-02-14 23:14:42 (1964229): Status Report: Elapsed Time: '47482.128443'
2023-02-14 23:14:42 (1964229): Status Report: CPU Time: '55228.910000'
2023-02-14 23:14:42 (1964229): Preference change detected
2023-02-14 23:14:42 (1964229): Setting CPU throttle for VM. (100%)
2023-02-14 23:14:42 (1964229): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2023-02-14 23:14:44 (1964229): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032
2023-02-14 23:14:44 (1964229): Guest Log: BIOS: Booting from Hard Disk...
2023-02-14 23:14:47 (1964229): Guest Log: BIOS: KBD: unsupported int 16h function 03
2023-02-14 23:14:47 (1964229): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 
2023-02-14 23:15:35 (1964229): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds
2023-02-14 23:15:35 (1964229): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000)
2023-02-14 23:15:39 (1964229): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log
2023-02-14 23:15:39 (1964229): Guest Log: 00:00:00.000183 main     Log opened 2023-02-14T22:15:39.213910000Z
2023-02-14 23:15:39 (1964229): Guest Log: 00:00:00.000368 main     OS Product: Linux
2023-02-14 23:15:39 (1964229): Guest Log: 00:00:00.000412 main     OS Release: 4.14.232-19.cernvm.x86_64
2023-02-14 23:15:39 (1964229): Guest Log: 00:00:00.000447 main     OS Version: #1 SMP Fri Apr 30 17:12:25 CEST 2021
2023-02-14 23:15:39 (1964229): Guest Log: 00:00:00.000479 main     Executable: /usr/sbin/VBoxService
2023-02-14 23:15:39 (1964229): Guest Log: 00:00:00.000481 main     Process ID: 2718
2023-02-14 23:15:39 (1964229): Guest Log: 00:00:00.000481 main     Package type: LINUX_64BITS_GENERIC
2023-02-14 23:15:39 (1964229): Guest Log: 00:00:00.003343 main     5.2.6 r120293 started. Verbose level = 0
2023-02-14 23:16:04 (1964229): Guest Log: [INFO] Mounting the shared directory
2023-02-14 23:16:05 (1964229): Guest Log: [INFO] Shared directory mounted, enabling vboxmonitor
2023-02-14 23:16:05 (1964229): Guest Log: [INFO] Sourcing essential functions from /cvmfs/grid.cern.ch
2023-02-14 23:16:05 (1964229): Guest Log: [INFO] Testing connection to cern.ch
2023-02-14 23:16:05 (1964229): Guest Log: [INFO] Testing connection to VCCS
2023-02-14 23:16:06 (1964229): Guest Log: [INFO] Testing connection to HTCondor
2023-02-14 23:16:06 (1964229): Guest Log: [INFO] Testing connection to WMAgent
2023-02-14 23:16:07 (1964229): Guest Log: [INFO] Testing connection to EOSCMS
2023-02-14 23:16:07 (1964229): Guest Log: [INFO] Testing connection to CMS-Factory
2023-02-14 23:16:08 (1964229): Guest Log: [INFO] Testing connection to CMS-Frontier
2023-02-14 23:16:09 (1964229): Guest Log: [INFO] Testing connection to Frontier
2023-02-14 23:16:10 (1964229): Guest Log: [INFO] Could not find a local HTTP proxy
2023-02-14 23:16:10 (1964229): Guest Log: [INFO] CVMFS and Frontier will have to use DIRECT connections
2023-02-14 23:16:10 (1964229): Guest Log: [INFO] This makes the application less efficient
2023-02-14 23:16:10 (1964229): Guest Log: [INFO] It also puts higher load on the project servers
2023-02-14 23:16:10 (1964229): Guest Log: [INFO] Setting up a local HTTP proxy is highly recommended
2023-02-14 23:16:11 (1964229): Guest Log: [INFO] Advice can be found in the project forum
2023-02-14 23:16:11 (1964229): Guest Log: [INFO] Reloading and probing the CVMFS configuration
2023-02-14 23:16:23 (1964229): Guest Log: [INFO] Probing /cvmfs/grid.cern.ch... OK
2023-02-14 23:16:27 (1964229): Guest Log: [INFO] Probing /cvmfs/oasis.opensciencegrid.org... OK
2023-02-14 23:16:28 (1964229): Guest Log: [INFO] Probing /cvmfs/cms.cern.ch... OK
2023-02-14 23:16:28 (1964229): Guest Log: [INFO] Probing /cvmfs/singularity.opensciencegrid.org... OK
2023-02-14 23:16:28 (1964229): Guest Log: [INFO] Probing /cvmfs/cms-ib.cern.ch... OK
2023-02-14 23:16:31 (1964229): Guest Log: [INFO] Excerpt from "cvmfs_config stat": VERSION HOST PROXY
2023-02-14 23:16:31 (1964229): Guest Log: [INFO] 2.7.2.0 http://s1bnl-cvmfs.openhtc.io DIRECT
2023-02-14 23:16:31 (1964229): Guest Log: [INFO] Environment HTTP proxy: not set
2023-02-14 23:16:32 (1964229): Guest Log: [INFO] Reading volunteer information
2023-02-14 23:16:37 (1964229): Guest Log: [INFO] Requesting an X509 credential from LHC@home
2023-02-14 23:16:38 (1964229): Guest Log: [INFO] Requesting an X509 credential from vLHC@home-dev
2023-02-14 23:16:40 (1964229): Guest Log: [INFO] CMS application starting. Check log files.
2023-02-14 23:16:40 (1964229): Guest Log: [INFO] Requesting an idtoken from LHC@home
2023-02-14 23:16:41 (1964229): Guest Log: [INFO] Requesting an idtoken from vLHC@home-dev
2023-02-15 00:15:20 (1964229): Stopping VM.
2023-02-15 00:16:16 (1964229): Successfully stopped VM.
2023-02-15 02:48:31 (2052530): Detected: vboxwrapper 26206
2023-02-15 02:48:31 (2052530): Detected: BOINC client v7.16.11
2023-02-15 02:48:31 (2052530): Detected: VirtualBox VboxManage Interface (Version: 6.1.26)
2023-02-15 02:48:31 (2052530): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2023-02-15 02:48:31 (2052530): Guest Log: BIOS: VirtualBox 6.1.25
2023-02-15 02:48:31 (2052530): Guest Log: CPUID EDX: 0x178bfbff
2023-02-15 02:48:31 (2052530): Guest Log: BIOS: No PCI IDE controller, not probing IDE
2023-02-15 02:48:31 (2052530): Guest Log: BIOS: AHCI 0-P#0: PCHS=16383/16/63 LCHS=1024/255/63 0x0000000002800000 sectors
2023-02-15 02:48:31 (2052530): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032
2023-02-15 02:48:31 (2052530): Guest Log: BIOS: Booting from Hard Disk...
2023-02-15 02:48:31 (2052530): Guest Log: BIOS: KBD: unsupported int 16h function 03
2023-02-15 02:48:31 (2052530): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 
2023-02-15 02:48:31 (2052530): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds
2023-02-15 02:48:31 (2052530): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000)
2023-02-15 02:48:31 (2052530): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log
2023-02-15 02:48:31 (2052530): Guest Log: 00:00:00.000183 main     Log opened 2023-02-14T22:15:39.213910000Z
2023-02-15 02:48:31 (2052530): Guest Log: 00:00:00.000368 main     OS Product: Linux
2023-02-15 02:48:31 (2052530): Guest Log: 00:00:00.000412 main     OS Release: 4.14.232-19.cernvm.x86_64
2023-02-15 02:48:31 (2052530): Guest Log: 00:00:00.000447 main     OS Version: #1 SMP Fri Apr 30 17:12:25 CEST 2021
2023-02-15 02:48:31 (2052530): Guest Log: 00:00:00.000479 main     Executable: /usr/sbin/VBoxService
2023-02-15 02:48:31 (2052530): Guest Log: 00:00:00.000481 main     Process ID: 2718
2023-02-15 02:48:31 (2052530): Guest Log: 00:00:00.000481 main     Package type: LINUX_64BITS_GENERIC
2023-02-15 02:48:31 (2052530): Guest Log: 00:00:00.003343 main     5.2.6 r120293 started. Verbose level = 0
2023-02-15 02:48:31 (2052530): Guest Log: [INFO] Mounting the shared directory
2023-02-15 02:48:31 (2052530): Guest Log: [INFO] Shared directory mounted, enabling vboxmonitor
2023-02-15 02:48:31 (2052530): Guest Log: [INFO] Sourcing essential functions from /cvmfs/grid.cern.ch
2023-02-15 02:48:31 (2052530): Guest Log: [INFO] Testing connection to cern.ch
2023-02-15 02:48:31 (2052530): Guest Log: [INFO] Testing connection to VCCS
2023-02-15 02:48:31 (2052530): Guest Log: [INFO] Testing connection to HTCondor
2023-02-15 02:48:31 (2052530): Guest Log: [INFO] Testing connection to WMAgent
2023-02-15 02:48:31 (2052530): Guest Log: [INFO] Testing connection to EOSCMS
2023-02-15 02:48:31 (2052530): Guest Log: [INFO] Testing connection to CMS-Factory
2023-02-15 02:48:31 (2052530): Guest Log: [INFO] Testing connection to CMS-Frontier
2023-02-15 02:48:31 (2052530): Guest Log: [INFO] Testing connection to Frontier
2023-02-15 02:48:31 (2052530): Guest Log: [INFO] Could not find a local HTTP proxy
2023-02-15 02:48:31 (2052530): Guest Log: [INFO] CVMFS and Frontier will have to use DIRECT connections
2023-02-15 02:48:31 (2052530): Guest Log: [INFO] This makes the application less efficient
2023-02-15 02:48:31 (2052530): Guest Log: [INFO] It also puts higher load on the project servers
2023-02-15 02:48:31 (2052530): Guest Log: [INFO] Setting up a local HTTP proxy is highly recommended
2023-02-15 02:48:31 (2052530): Guest Log: [INFO] Advice can be found in the project forum
2023-02-15 02:48:31 (2052530): Guest Log: [INFO] Reloading and probing the CVMFS configuration
2023-02-15 02:48:31 (2052530): Guest Log: [INFO] Probing /cvmfs/grid.cern.ch... OK
2023-02-15 02:48:31 (2052530): Guest Log: [INFO] Probing /cvmfs/oasis.opensciencegrid.org... OK
2023-02-15 02:48:31 (2052530): Guest Log: [INFO] Probing /cvmfs/cms.cern.ch... OK
2023-02-15 02:48:31 (2052530): Guest Log: [INFO] Probing /cvmfs/singularity.opensciencegrid.org... OK
2023-02-15 02:48:31 (2052530): Guest Log: [INFO] Probing /cvmfs/cms-ib.cern.ch... OK
2023-02-15 02:48:31 (2052530): Guest Log: [INFO] Excerpt from "cvmfs_config stat": VERSION HOST PROXY
2023-02-15 02:48:31 (2052530): Guest Log: [INFO] 2.7.2.0 http://s1bnl-cvmfs.openhtc.io DIRECT
2023-02-15 02:48:31 (2052530): Guest Log: [INFO] Environment HTTP proxy: not set
2023-02-15 02:48:31 (2052530): Guest Log: [INFO] Reading volunteer information
2023-02-15 02:48:31 (2052530): Guest Log: [INFO] Requesting an X509 credential from LHC@home
2023-02-15 02:48:31 (2052530): Guest Log: [INFO] Requesting an X509 credential from vLHC@home-dev
2023-02-15 02:48:31 (2052530): Guest Log: [INFO] CMS application starting. Check log files.
2023-02-15 02:48:31 (2052530): Guest Log: [INFO] Requesting an idtoken from LHC@home
2023-02-15 02:48:31 (2052530): Guest Log: [INFO] Requesting an idtoken from vLHC@home-dev
2023-02-15 02:48:31 (2052530): Starting VM using VBoxManage interface. (boinc_dc10c48fc5a480bc, slot#14)
2023-02-15 02:48:44 (2052530): Successfully started VM. (PID = '2052621')
2023-02-15 02:48:44 (2052530): Reporting VM Process ID to BOINC.
2023-02-15 02:48:44 (2052530): VM state change detected. (old = 'poweredoff', new = 'running')
2023-02-15 02:48:44 (2052530): Detected: Web Application Enabled (http://localhost:38623)
2023-02-15 02:48:44 (2052530): Status Report: Job Duration: '64800.000000'
2023-02-15 02:48:44 (2052530): Status Report: Elapsed Time: '51156.128443'
2023-02-15 02:48:44 (2052530): Status Report: CPU Time: '60059.930000'
2023-02-15 02:48:44 (2052530): Preference change detected
2023-02-15 02:48:44 (2052530): Setting CPU throttle for VM. (100%)
2023-02-15 02:48:44 (2052530): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2023-02-15 02:48:50 (2052530): Guest Log: 00:59:51.902019 timesync vgsvcTimeSyncWorker: Radical host time change: 9 212 938 000 000ns (HostNow=1 676 425 730 179 000 000 ns HostLast=1 676 416 517 241 000 000 ns)
2023-02-15 02:49:00 (2052530): Guest Log: 01:00:01.909804 timesync vgsvcTimeSyncWorker: Radical guest time change: 9 209 073 918 000ns (GuestNow=1 676 425 740 188 203 000 ns GuestLast=1 676 416 531 114 285 000 ns fSetTimeLastLoop=true )
2023-02-15 03:49:23 (2052530): Stopping VM.
2023-02-15 03:50:20 (2052530): Successfully stopped VM.
2023-02-15 06:22:25 (2185008): Detected: vboxwrapper 26206
2023-02-15 06:22:25 (2185008): Detected: BOINC client v7.16.11
2023-02-15 06:22:26 (2185008): Detected: VirtualBox VboxManage Interface (Version: 6.1.26)
2023-02-15 06:22:26 (2185008): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2023-02-15 06:22:26 (2185008): Guest Log: 00:59:51.902019 timesync vgsvcTimeSyncWorker: Radical host time change: 9 212 938 000 000ns (HostNow=1 676 425 730 179 000 000 ns HostLast=1 676 416 517 241 000 000 ns)
2023-02-15 06:22:26 (2185008): Guest Log: 01:00:01.909804 timesync vgsvcTimeSyncWorker: Radical guest time change: 9 209 073 918 000ns (GuestNow=1 676 425 740 188 203 000 ns GuestLast=1 676 416 531 114 285 000 ns fSetTimeLastLoop=true )
2023-02-15 06:22:26 (2185008): Starting VM using VBoxManage interface. (boinc_dc10c48fc5a480bc, slot#14)
2023-02-15 06:22:39 (2185008): Successfully started VM. (PID = '2185129')
2023-02-15 06:22:39 (2185008): Reporting VM Process ID to BOINC.
2023-02-15 06:22:39 (2185008): VM state change detected. (old = 'poweredoff', new = 'running')
2023-02-15 06:22:39 (2185008): Detected: Web Application Enabled (http://localhost:38623)
2023-02-15 06:22:39 (2185008): Status Report: Job Duration: '64800.000000'
2023-02-15 06:22:39 (2185008): Status Report: Elapsed Time: '54832.128443'
2023-02-15 06:22:39 (2185008): Status Report: CPU Time: '64275.620000'
2023-02-15 06:22:39 (2185008): Preference change detected
2023-02-15 06:22:39 (2185008): Setting CPU throttle for VM. (100%)
2023-02-15 06:22:39 (2185008): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2023-02-15 06:22:44 (2185008): Guest Log: 02:00:35.649019 timesync vgsvcTimeSyncWorker: Radical host time change: 9 204 483 000 000ns (HostNow=1 676 438 564 507 000 000 ns HostLast=1 676 429 360 024 000 000 ns)
2023-02-15 06:22:55 (2185008): Guest Log: 02:00:45.650570 timesync vgsvcTimeSyncWorker: Radical guest time change: 9 200 583 581 000ns (GuestNow=1 676 438 574 509 743 000 ns GuestLast=1 676 429 373 926 162 000 ns fSetTimeLastLoop=true )
2023-02-15 07:22:58 (2185008): Stopping VM.
2023-02-15 07:23:50 (2185008): Successfully stopped VM.
2023-02-15 09:24:53 (2269627): Detected: vboxwrapper 26206
2023-02-15 09:24:53 (2269627): Detected: BOINC client v7.16.11
2023-02-15 09:24:53 (2269627): Detected: VirtualBox VboxManage Interface (Version: 6.1.26)
2023-02-15 09:24:53 (2269627): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2023-02-15 09:24:53 (2269627): Guest Log: 02:00:35.649019 timesync vgsvcTimeSyncWorker: Radical host time change: 9 204 483 000 000ns (HostNow=1 676 438 564 507 000 000 ns HostLast=1 676 429 360 024 000 000 ns)
2023-02-15 09:24:53 (2269627): Guest Log: 02:00:45.650570 timesync vgsvcTimeSyncWorker: Radical guest time change: 9 200 583 581 000ns (GuestNow=1 676 438 574 509 743 000 ns GuestLast=1 676 429 373 926 162 000 ns fSetTimeLastLoop=true )
2023-02-15 09:24:53 (2269627): Starting VM using VBoxManage interface. (boinc_dc10c48fc5a480bc, slot#14)
2023-02-15 09:25:07 (2269627): Successfully started VM. (PID = '2269703')
2023-02-15 09:25:07 (2269627): Reporting VM Process ID to BOINC.
2023-02-15 09:25:07 (2269627): VM state change detected. (old = 'poweredoff', new = 'running')
2023-02-15 09:25:07 (2269627): Detected: Web Application Enabled (http://localhost:38623)
2023-02-15 09:25:07 (2269627): Status Report: Job Duration: '64800.000000'
2023-02-15 09:25:07 (2269627): Status Report: Elapsed Time: '58487.114225'
2023-02-15 09:25:07 (2269627): Status Report: CPU Time: '67891.240000'
2023-02-15 09:25:07 (2269627): Preference change detected
2023-02-15 09:25:07 (2269627): Setting CPU throttle for VM. (100%)
2023-02-15 09:25:07 (2269627): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2023-02-15 09:25:10 (2269627): Guest Log: 03:00:58.211384 timesync vgsvcTimeSyncWorker: Radical host time change: 7 337 584 000 000ns (HostNow=1 676 449 510 759 000 000 ns HostLast=1 676 442 173 175 000 000 ns)
2023-02-15 09:25:20 (2269627): Guest Log: 03:01:08.220259 timesync vgsvcTimeSyncWorker: Radical guest time change: 7 333 700 212 000ns (GuestNow=1 676 449 520 769 337 000 ns GuestLast=1 676 442 187 069 125 000 ns fSetTimeLastLoop=true )
2023-02-15 10:25:21 (2269627): Stopping VM.
2023-02-15 10:26:07 (2269627): Successfully stopped VM.
2023-02-15 11:25:54 (2326357): Detected: vboxwrapper 26206
2023-02-15 11:25:54 (2326357): Detected: BOINC client v7.16.11
2023-02-15 11:25:55 (2326357): Detected: VirtualBox VboxManage Interface (Version: 6.1.26)
2023-02-15 11:25:55 (2326357): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2023-02-15 11:26:00 (2326357): Guest Log: 03:00:58.211384 timesync vgsvcTimeSyncWorker: Radical host time change: 7 337 584 000 000ns (HostNow=1 676 449 510 759 000 000 ns HostLast=1 676 442 173 175 000 000 ns)
2023-02-15 11:26:00 (2326357): Guest Log: 03:01:08.220259 timesync vgsvcTimeSyncWorker: Radical guest time change: 7 333 700 212 000ns (GuestNow=1 676 449 520 769 337 000 ns GuestLast=1 676 442 187 069 125 000 ns fSetTimeLastLoop=true )
2023-02-15 11:26:00 (2326357): Starting VM using VBoxManage interface. (boinc_dc10c48fc5a480bc, slot#14)
2023-02-15 11:26:13 (2326357): Successfully started VM. (PID = '2326485')
2023-02-15 11:26:13 (2326357): Reporting VM Process ID to BOINC.
2023-02-15 11:26:13 (2326357): VM state change detected. (old = 'poweredoff', new = 'running')
2023-02-15 11:26:13 (2326357): Detected: Web Application Enabled (http://localhost:38623)
2023-02-15 11:26:13 (2326357): Status Report: Job Duration: '64800.000000'
2023-02-15 11:26:13 (2326357): Status Report: Elapsed Time: '62138.301303'
2023-02-15 11:26:13 (2326357): Status Report: CPU Time: '72115.980000'
2023-02-15 11:26:13 (2326357): Preference change detected
2023-02-15 11:26:13 (2326357): Setting CPU throttle for VM. (100%)
2023-02-15 11:26:13 (2326357): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2023-02-15 11:26:20 (2326357): Guest Log: 04:01:20.549007 timesync vgsvcTimeSyncWorker: Radical host time change: 3 660 236 000 000ns (HostNow=1 676 456 779 444 000 000 ns HostLast=1 676 453 119 208 000 000 ns)
2023-02-15 11:26:30 (2326357): Guest Log: 04:01:30.558374 timesync vgsvcTimeSyncWorker: Radical guest time change: 3 656 361 546 000ns (GuestNow=1 676 456 789 456 479 000 ns GuestLast=1 676 453 133 094 933 000 ns fSetTimeLastLoop=true )
2023-02-15 11:49:23 (2326357): Guest Log: [INFO] glidein exited with return value 0.
2023-02-15 11:49:23 (2326357): Guest Log: [INFO] Shutting Down.
2023-02-15 11:49:24 (2326357): VM Completion File Detected.
2023-02-15 11:49:24 (2326357): VM Completion Message: glidein exited with return value 0.
.
2023-02-15 11:49:24 (2326357): Powering off VM.
2023-02-15 11:49:25 (2326357): Successfully stopped VM.
2023-02-15 11:49:25 (2326357): Deregistering VM. (boinc_dc10c48fc5a480bc, slot#14)
2023-02-15 11:49:25 (2326357): Removing network bandwidth throttle group from VM.
2023-02-15 11:49:25 (2326357): Removing VM from VirtualBox.
11:49:30 (2326357): called boinc_finish(0)

</stderr_txt>
]]>


©2024 CERN