Name CMS_2797717_1675430975.806168_0
Workunit 2277221
Created 3 Feb 2023, 13:29:36 UTC
Sent 6 Feb 2023, 22:57:06 UTC
Report deadline 13 Feb 2023, 22:57:06 UTC
Received 8 Feb 2023, 0:11:26 UTC
Server state Over
Outcome Success
Client state Done
Exit status 0 (0x00000000)
Computer ID 4318
Run time 10 hours 25 min 29 sec
CPU time 12 hours 19 min 21 sec
Validate state Valid
Credit 1,045.40
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.24 GB
Peak swap size 8.04 GB
Peak disk usage 3.08 GB

Stderr output

<core_client_version>7.16.11</core_client_version>
<![CDATA[
<stderr_txt>
2023-02-06 23:57:33 (1375395): Detected: vboxwrapper 26206
2023-02-06 23:57:33 (1375395): Detected: BOINC client v7.16.11
2023-02-06 23:57:34 (1375395): Detected: VirtualBox VboxManage Interface (Version: 6.1.26)
2023-02-06 23:57:34 (1375395): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2023-02-06 23:57:34 (1375395): Successfully copied 'init_data.xml' to the shared directory.
2023-02-06 23:57:34 (1375395): Create VM. (boinc_5382160ae12e574b, slot#16)
2023-02-06 23:57:34 (1375395): Setting Memory Size for VM. (15336MB)
2023-02-06 23:57:34 (1375395): Setting CPU Count for VM. (16)
2023-02-06 23:57:34 (1375395): Setting Chipset Options for VM.
2023-02-06 23:57:34 (1375395): Setting Graphics Controller Options for VM.
2023-02-06 23:57:34 (1375395): Setting Boot Options for VM.
2023-02-06 23:57:34 (1375395): Setting Network Configuration for NAT.
2023-02-06 23:57:35 (1375395): Enabling VM Network Access.
2023-02-06 23:57:35 (1375395): Disabling USB Support for VM.
2023-02-06 23:57:35 (1375395): Disabling COM Port Support for VM.
2023-02-06 23:57:35 (1375395): Disabling LPT Port Support for VM.
2023-02-06 23:57:35 (1375395): Disabling Audio Support for VM.
2023-02-06 23:57:35 (1375395): Disabling Clipboard Support for VM.
2023-02-06 23:57:35 (1375395): Disabling Drag and Drop Support for VM.
2023-02-06 23:57:35 (1375395): Adding storage controller(s) to VM.
2023-02-06 23:57:35 (1375395): Adding virtual disk drive to VM. (CMS_2022_09_07.vdi)
2023-02-06 23:57:36 (1375395): Adding network bandwidth throttle group to VM. (Defaulting to 1024GB)
2023-02-06 23:57:36 (1375395): forwarding host port 39173 to guest port 80
2023-02-06 23:57:36 (1375395): Enabling remote desktop for VM.
2023-02-06 23:57:36 (1375395): Required extension pack not installed, remote desktop not enabled.
2023-02-06 23:57:36 (1375395): Enabling shared directory for VM.
2023-02-06 23:57:36 (1375395): Starting VM using VBoxManage interface. (boinc_5382160ae12e574b, slot#16)
2023-02-06 23:57:38 (1375395): Successfully started VM. (PID = '1375800')
2023-02-06 23:57:38 (1375395): Reporting VM Process ID to BOINC.
2023-02-06 23:57:38 (1375395): Guest Log: BIOS: VirtualBox 6.1.25
2023-02-06 23:57:38 (1375395): Guest Log: CPUID EDX: 0x178bfbff
2023-02-06 23:57:38 (1375395): Guest Log: BIOS: No PCI IDE controller, not probing IDE
2023-02-06 23:57:38 (1375395): Guest Log: BIOS: AHCI 0-P#0: PCHS=16383/16/63 LCHS=1024/255/63 0x0000000002800000 sectors
2023-02-06 23:57:38 (1375395): VM state change detected. (old = 'poweredoff', new = 'running')
2023-02-06 23:57:38 (1375395): Detected: Web Application Enabled (http://localhost:39173)
2023-02-06 23:57:38 (1375395): Preference change detected
2023-02-06 23:57:38 (1375395): Setting CPU throttle for VM. (100%)
2023-02-06 23:57:38 (1375395): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2023-02-06 23:57:40 (1375395): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032
2023-02-06 23:57:40 (1375395): Guest Log: BIOS: Booting from Hard Disk...
2023-02-06 23:57:56 (1375395): Guest Log: BIOS: KBD: unsupported int 16h function 03
2023-02-06 23:57:56 (1375395): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 
2023-02-06 23:58:46 (1375395): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds
2023-02-06 23:58:46 (1375395): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000)
2023-02-06 23:58:50 (1375395): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log
2023-02-06 23:58:50 (1375395): Guest Log: 00:00:00.000427 main     Log opened 2023-02-06T22:58:49.719106000Z
2023-02-06 23:58:50 (1375395): Guest Log: 00:00:00.001356 main     OS Product: Linux
2023-02-06 23:58:50 (1375395): Guest Log: 00:00:00.001436 main     OS Release: 4.14.232-19.cernvm.x86_64
2023-02-06 23:58:50 (1375395): Guest Log: 00:00:00.001649 main     OS Version: #1 SMP Fri Apr 30 17:12:25 CEST 2021
2023-02-06 23:58:50 (1375395): Guest Log: 00:00:00.001893 main     Executable: /usr/sbin/VBoxService
2023-02-06 23:58:50 (1375395): Guest Log: 00:00:00.001895 main     Process ID: 2704
2023-02-06 23:58:50 (1375395): Guest Log: 00:00:00.001896 main     Package type: LINUX_64BITS_GENERIC
2023-02-06 23:58:50 (1375395): Guest Log: 00:00:00.004552 main     5.2.6 r120293 started. Verbose level = 0
2023-02-06 23:59:17 (1375395): Guest Log: [INFO] Mounting the shared directory
2023-02-06 23:59:18 (1375395): Guest Log: [INFO] Shared directory mounted, enabling vboxmonitor
2023-02-06 23:59:18 (1375395): Guest Log: [INFO] Sourcing essential functions from /cvmfs/grid.cern.ch
2023-02-06 23:59:19 (1375395): Guest Log: [INFO] Testing connection to cern.ch
2023-02-06 23:59:20 (1375395): Guest Log: [INFO] Testing connection to VCCS
2023-02-06 23:59:20 (1375395): Guest Log: [INFO] Testing connection to HTCondor
2023-02-06 23:59:20 (1375395): Guest Log: [INFO] Testing connection to WMAgent
2023-02-06 23:59:21 (1375395): Guest Log: [INFO] Testing connection to EOSCMS
2023-02-06 23:59:21 (1375395): Guest Log: [INFO] Testing connection to CMS-Factory
2023-02-06 23:59:22 (1375395): Guest Log: [INFO] Testing connection to CMS-Frontier
2023-02-06 23:59:22 (1375395): Guest Log: [INFO] Testing connection to Frontier
2023-02-06 23:59:24 (1375395): Guest Log: [INFO] Could not find a local HTTP proxy
2023-02-06 23:59:24 (1375395): Guest Log: [INFO] CVMFS and Frontier will have to use DIRECT connections
2023-02-06 23:59:24 (1375395): Guest Log: [INFO] This makes the application less efficient
2023-02-06 23:59:25 (1375395): Guest Log: [INFO] It also puts higher load on the project servers
2023-02-06 23:59:25 (1375395): Guest Log: [INFO] Setting up a local HTTP proxy is highly recommended
2023-02-06 23:59:25 (1375395): Guest Log: [INFO] Advice can be found in the project forum
2023-02-06 23:59:27 (1375395): Guest Log: [INFO] Reloading and probing the CVMFS configuration
2023-02-06 23:59:38 (1375395): Guest Log: [INFO] Probing /cvmfs/grid.cern.ch... OK
2023-02-06 23:59:43 (1375395): Guest Log: [INFO] Probing /cvmfs/oasis.opensciencegrid.org... OK
2023-02-06 23:59:43 (1375395): Guest Log: [INFO] Probing /cvmfs/cms-ib.cern.ch... OK
2023-02-06 23:59:43 (1375395): Guest Log: [INFO] Probing /cvmfs/singularity.opensciencegrid.org... OK
2023-02-06 23:59:43 (1375395): Guest Log: [INFO] Probing /cvmfs/cms.cern.ch... OK
2023-02-06 23:59:46 (1375395): Guest Log: [INFO] Excerpt from "cvmfs_config stat": VERSION HOST PROXY
2023-02-06 23:59:46 (1375395): Guest Log: [INFO] 2.7.2.0 http://s1ral-cvmfs.openhtc.io DIRECT
2023-02-06 23:59:46 (1375395): Guest Log: [INFO] Environment HTTP proxy: not set
2023-02-06 23:59:47 (1375395): Guest Log: [INFO] Reading volunteer information
2023-02-06 23:59:50 (1375395): Guest Log: [INFO] Requesting an X509 credential from LHC@home
2023-02-06 23:59:51 (1375395): Guest Log: [INFO] Requesting an X509 credential from vLHC@home-dev
2023-02-06 23:59:53 (1375395): Guest Log: [INFO] CMS application starting. Check log files.
2023-02-06 23:59:53 (1375395): Guest Log: [INFO] Requesting an idtoken from LHC@home
2023-02-06 23:59:54 (1375395): Guest Log: [INFO] Requesting an idtoken from vLHC@home-dev
2023-02-07 00:58:00 (1375395): Stopping VM.
2023-02-07 00:58:50 (1375395): Successfully stopped VM.
2023-02-07 03:30:23 (1464964): Detected: vboxwrapper 26206
2023-02-07 03:30:23 (1464964): Detected: BOINC client v7.16.11
2023-02-07 03:30:23 (1464964): Detected: VirtualBox VboxManage Interface (Version: 6.1.26)
2023-02-07 03:30:23 (1464964): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2023-02-07 03:30:24 (1464964): Guest Log: BIOS: VirtualBox 6.1.25
2023-02-07 03:30:24 (1464964): Guest Log: CPUID EDX: 0x178bfbff
2023-02-07 03:30:24 (1464964): Guest Log: BIOS: No PCI IDE controller, not probing IDE
2023-02-07 03:30:24 (1464964): Guest Log: BIOS: AHCI 0-P#0: PCHS=16383/16/63 LCHS=1024/255/63 0x0000000002800000 sectors
2023-02-07 03:30:24 (1464964): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032
2023-02-07 03:30:24 (1464964): Guest Log: BIOS: Booting from Hard Disk...
2023-02-07 03:30:24 (1464964): Guest Log: BIOS: KBD: unsupported int 16h function 03
2023-02-07 03:30:24 (1464964): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 
2023-02-07 03:30:24 (1464964): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds
2023-02-07 03:30:24 (1464964): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000)
2023-02-07 03:30:24 (1464964): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log
2023-02-07 03:30:24 (1464964): Guest Log: 00:00:00.000427 main     Log opened 2023-02-06T22:58:49.719106000Z
2023-02-07 03:30:24 (1464964): Guest Log: 00:00:00.001356 main     OS Product: Linux
2023-02-07 03:30:24 (1464964): Guest Log: 00:00:00.001436 main     OS Release: 4.14.232-19.cernvm.x86_64
2023-02-07 03:30:24 (1464964): Guest Log: 00:00:00.001649 main     OS Version: #1 SMP Fri Apr 30 17:12:25 CEST 2021
2023-02-07 03:30:24 (1464964): Guest Log: 00:00:00.001893 main     Executable: /usr/sbin/VBoxService
2023-02-07 03:30:24 (1464964): Guest Log: 00:00:00.001895 main     Process ID: 2704
2023-02-07 03:30:24 (1464964): Guest Log: 00:00:00.001896 main     Package type: LINUX_64BITS_GENERIC
2023-02-07 03:30:24 (1464964): Guest Log: 00:00:00.004552 main     5.2.6 r120293 started. Verbose level = 0
2023-02-07 03:30:24 (1464964): Guest Log: [INFO] Mounting the shared directory
2023-02-07 03:30:24 (1464964): Guest Log: [INFO] Shared directory mounted, enabling vboxmonitor
2023-02-07 03:30:24 (1464964): Guest Log: [INFO] Sourcing essential functions from /cvmfs/grid.cern.ch
2023-02-07 03:30:24 (1464964): Guest Log: [INFO] Testing connection to cern.ch
2023-02-07 03:30:24 (1464964): Guest Log: [INFO] Testing connection to VCCS
2023-02-07 03:30:24 (1464964): Guest Log: [INFO] Testing connection to HTCondor
2023-02-07 03:30:24 (1464964): Guest Log: [INFO] Testing connection to WMAgent
2023-02-07 03:30:24 (1464964): Guest Log: [INFO] Testing connection to EOSCMS
2023-02-07 03:30:24 (1464964): Guest Log: [INFO] Testing connection to CMS-Factory
2023-02-07 03:30:24 (1464964): Guest Log: [INFO] Testing connection to CMS-Frontier
2023-02-07 03:30:24 (1464964): Guest Log: [INFO] Testing connection to Frontier
2023-02-07 03:30:24 (1464964): Guest Log: [INFO] Could not find a local HTTP proxy
2023-02-07 03:30:24 (1464964): Guest Log: [INFO] CVMFS and Frontier will have to use DIRECT connections
2023-02-07 03:30:24 (1464964): Guest Log: [INFO] This makes the application less efficient
2023-02-07 03:30:24 (1464964): Guest Log: [INFO] It also puts higher load on the project servers
2023-02-07 03:30:24 (1464964): Guest Log: [INFO] Setting up a local HTTP proxy is highly recommended
2023-02-07 03:30:24 (1464964): Guest Log: [INFO] Advice can be found in the project forum
2023-02-07 03:30:24 (1464964): Guest Log: [INFO] Reloading and probing the CVMFS configuration
2023-02-07 03:30:24 (1464964): Guest Log: [INFO] Probing /cvmfs/grid.cern.ch... OK
2023-02-07 03:30:24 (1464964): Guest Log: [INFO] Probing /cvmfs/oasis.opensciencegrid.org... OK
2023-02-07 03:30:24 (1464964): Guest Log: [INFO] Probing /cvmfs/cms-ib.cern.ch... OK
2023-02-07 03:30:24 (1464964): Guest Log: [INFO] Probing /cvmfs/singularity.opensciencegrid.org... OK
2023-02-07 03:30:24 (1464964): Guest Log: [INFO] Probing /cvmfs/cms.cern.ch... OK
2023-02-07 03:30:24 (1464964): Guest Log: [INFO] Excerpt from "cvmfs_config stat": VERSION HOST PROXY
2023-02-07 03:30:24 (1464964): Guest Log: [INFO] 2.7.2.0 http://s1ral-cvmfs.openhtc.io DIRECT
2023-02-07 03:30:24 (1464964): Guest Log: [INFO] Environment HTTP proxy: not set
2023-02-07 03:30:24 (1464964): Guest Log: [INFO] Reading volunteer information
2023-02-07 03:30:24 (1464964): Guest Log: [INFO] Requesting an X509 credential from LHC@home
2023-02-07 03:30:24 (1464964): Guest Log: [INFO] Requesting an X509 credential from vLHC@home-dev
2023-02-07 03:30:24 (1464964): Guest Log: [INFO] CMS application starting. Check log files.
2023-02-07 03:30:24 (1464964): Guest Log: [INFO] Requesting an idtoken from LHC@home
2023-02-07 03:30:24 (1464964): Guest Log: [INFO] Requesting an idtoken from vLHC@home-dev
2023-02-07 03:30:24 (1464964): Starting VM using VBoxManage interface. (boinc_5382160ae12e574b, slot#16)
2023-02-07 03:30:37 (1464964): Successfully started VM. (PID = '1465057')
2023-02-07 03:30:37 (1464964): Reporting VM Process ID to BOINC.
2023-02-07 03:30:37 (1464964): VM state change detected. (old = 'poweredoff', new = 'running')
2023-02-07 03:30:37 (1464964): Detected: Web Application Enabled (http://localhost:39173)
2023-02-07 03:30:37 (1464964): Preference change detected
2023-02-07 03:30:37 (1464964): Setting CPU throttle for VM. (100%)
2023-02-07 03:30:37 (1464964): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2023-02-07 03:30:42 (1464964): Guest Log: 00:59:21.265799 timesync vgsvcTimeSyncWorker: Radical host time change: 9 165 235 000 000ns (HostNow=1 675 737 042 379 000 000 ns HostLast=1 675 727 877 144 000 000 ns)
2023-02-07 03:30:52 (1464964): Guest Log: 00:59:31.272813 timesync vgsvcTimeSyncWorker: Radical guest time change: 9 161 406 807 000ns (GuestNow=1 675 737 052 388 620 000 ns GuestLast=1 675 727 890 981 813 000 ns fSetTimeLastLoop=true )
2023-02-07 04:09:15 (1464964): Status Report: Job Duration: '64800.000000'
2023-02-07 04:09:15 (1464964): Status Report: Elapsed Time: '6000.000000'
2023-02-07 04:09:15 (1464964): Status Report: CPU Time: '7439.690000'
2023-02-07 04:31:02 (1464964): Stopping VM.
2023-02-07 06:35:34 (1548657): Detected: vboxwrapper 26206
2023-02-07 06:35:34 (1548657): Detected: BOINC client v7.16.11
2023-02-07 06:35:34 (1548657): Detected: VirtualBox VboxManage Interface (Version: 6.1.26)
2023-02-07 06:35:35 (1548657): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2023-02-07 06:35:35 (1548657): Guest Log: 00:59:21.265799 timesync vgsvcTimeSyncWorker: Radical host time change: 9 165 235 000 000ns (HostNow=1 675 737 042 379 000 000 ns HostLast=1 675 727 877 144 000 000 ns)
2023-02-07 06:35:35 (1548657): Guest Log: 00:59:31.272813 timesync vgsvcTimeSyncWorker: Radical guest time change: 9 161 406 807 000ns (GuestNow=1 675 737 052 388 620 000 ns GuestLast=1 675 727 890 981 813 000 ns fSetTimeLastLoop=true )
2023-02-07 06:35:35 (1548657): Starting VM using VBoxManage interface. (boinc_5382160ae12e574b, slot#16)
2023-02-07 06:35:36 (1548657): Successfully started VM. (PID = '1548733')
2023-02-07 06:35:36 (1548657): Reporting VM Process ID to BOINC.
2023-02-07 06:35:36 (1548657): Guest Log: BIOS: VirtualBox 6.1.25
2023-02-07 06:35:36 (1548657): Guest Log: CPUID EDX: 0x178bfbff
2023-02-07 06:35:36 (1548657): Guest Log: BIOS: No PCI IDE controller, not probing IDE
2023-02-07 06:35:36 (1548657): Guest Log: BIOS: AHCI 0-P#0: PCHS=16383/16/63 LCHS=1024/255/63 0x0000000002800000 sectors
2023-02-07 06:35:36 (1548657): VM state change detected. (old = 'poweredoff', new = 'running')
2023-02-07 06:35:36 (1548657): Detected: Web Application Enabled (http://localhost:39173)
2023-02-07 06:35:36 (1548657): Status Report: Job Duration: '64800.000000'
2023-02-07 06:35:36 (1548657): Status Report: Elapsed Time: '7320.000000'
2023-02-07 06:35:36 (1548657): Status Report: CPU Time: '8951.430000'
2023-02-07 06:35:36 (1548657): Preference change detected
2023-02-07 06:35:36 (1548657): Setting CPU throttle for VM. (100%)
2023-02-07 06:35:36 (1548657): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2023-02-07 06:35:38 (1548657): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032
2023-02-07 06:35:38 (1548657): Guest Log: BIOS: Booting from Hard Disk...
2023-02-07 06:35:41 (1548657): Guest Log: BIOS: KBD: unsupported int 16h function 03
2023-02-07 06:35:41 (1548657): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 
2023-02-07 06:36:26 (1548657): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds
2023-02-07 06:36:27 (1548657): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000)
2023-02-07 06:36:29 (1548657): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log
2023-02-07 06:36:29 (1548657): Guest Log: 00:00:00.000127 main     Log opened 2023-02-07T05:36:29.184466000Z
2023-02-07 06:36:29 (1548657): Guest Log: 00:00:00.000254 main     OS Product: Linux
2023-02-07 06:36:29 (1548657): Guest Log: 00:00:00.000297 main     OS Release: 4.14.232-19.cernvm.x86_64
2023-02-07 06:36:29 (1548657): Guest Log: 00:00:00.000331 main     OS Version: #1 SMP Fri Apr 30 17:12:25 CEST 2021
2023-02-07 06:36:29 (1548657): Guest Log: 00:00:00.000363 main     Executable: /usr/sbin/VBoxService
2023-02-07 06:36:29 (1548657): Guest Log: 00:00:00.000364 main     Process ID: 2716
2023-02-07 06:36:29 (1548657): Guest Log: 00:00:00.000365 main     Package type: LINUX_64BITS_GENERIC
2023-02-07 06:36:29 (1548657): Guest Log: 00:00:00.001476 main     5.2.6 r120293 started. Verbose level = 0
2023-02-07 06:36:54 (1548657): Guest Log: [INFO] Mounting the shared directory
2023-02-07 06:36:55 (1548657): Guest Log: [INFO] Shared directory mounted, enabling vboxmonitor
2023-02-07 06:36:55 (1548657): Guest Log: [INFO] Sourcing essential functions from /cvmfs/grid.cern.ch
2023-02-07 06:36:55 (1548657): Guest Log: [INFO] Testing connection to cern.ch
2023-02-07 06:36:56 (1548657): Guest Log: [INFO] Testing connection to VCCS
2023-02-07 06:36:57 (1548657): Guest Log: [INFO] Testing connection to HTCondor
2023-02-07 06:36:57 (1548657): Guest Log: [INFO] Testing connection to WMAgent
2023-02-07 06:36:57 (1548657): Guest Log: [INFO] Testing connection to EOSCMS
2023-02-07 06:36:57 (1548657): Guest Log: [INFO] Testing connection to CMS-Factory
2023-02-07 06:36:58 (1548657): Guest Log: [INFO] Testing connection to CMS-Frontier
2023-02-07 06:36:58 (1548657): Guest Log: [INFO] Testing connection to Frontier
2023-02-07 06:37:00 (1548657): Guest Log: [INFO] Could not find a local HTTP proxy
2023-02-07 06:37:01 (1548657): Guest Log: [INFO] CVMFS and Frontier will have to use DIRECT connections
2023-02-07 06:37:01 (1548657): Guest Log: [INFO] This makes the application less efficient
2023-02-07 06:37:01 (1548657): Guest Log: [INFO] It also puts higher load on the project servers
2023-02-07 06:37:01 (1548657): Guest Log: [INFO] Setting up a local HTTP proxy is highly recommended
2023-02-07 06:37:02 (1548657): Guest Log: [INFO] Advice can be found in the project forum
2023-02-07 06:37:02 (1548657): Guest Log: [INFO] Reloading and probing the CVMFS configuration
2023-02-07 06:37:13 (1548657): Guest Log: [INFO] Probing /cvmfs/grid.cern.ch... OK
2023-02-07 06:37:17 (1548657): Guest Log: [INFO] Probing /cvmfs/singularity.opensciencegrid.org... OK
2023-02-07 06:37:17 (1548657): Guest Log: [INFO] Probing /cvmfs/oasis.opensciencegrid.org... OK
2023-02-07 06:37:18 (1548657): Guest Log: [INFO] Probing /cvmfs/cms-ib.cern.ch... OK
2023-02-07 06:37:19 (1548657): Guest Log: [INFO] Probing /cvmfs/cms.cern.ch... OK
2023-02-07 06:37:21 (1548657): Guest Log: [INFO] Excerpt from "cvmfs_config stat": VERSION HOST PROXY
2023-02-07 06:37:21 (1548657): Guest Log: [INFO] 2.7.2.0 http://s1ral-cvmfs.openhtc.io DIRECT
2023-02-07 06:37:21 (1548657): Guest Log: [INFO] Environment HTTP proxy: not set
2023-02-07 06:37:22 (1548657): Guest Log: [INFO] Reading volunteer information
2023-02-07 06:37:25 (1548657): Guest Log: [INFO] Requesting an X509 credential from LHC@home
2023-02-07 06:37:26 (1548657): Guest Log: [INFO] Requesting an X509 credential from vLHC@home-dev
2023-02-07 06:37:27 (1548657): Guest Log: [INFO] CMS application starting. Check log files.
2023-02-07 06:37:28 (1548657): Guest Log: [INFO] Requesting an idtoken from LHC@home
2023-02-07 06:37:29 (1548657): Guest Log: [INFO] Requesting an idtoken from vLHC@home-dev
2023-02-07 07:35:55 (1548657): Stopping VM.
2023-02-07 07:36:40 (1548657): Successfully stopped VM.
2023-02-07 10:29:50 (1646170): Detected: vboxwrapper 26206
2023-02-07 10:29:50 (1646170): Detected: BOINC client v7.16.11
2023-02-07 10:29:50 (1646170): Detected: VirtualBox VboxManage Interface (Version: 6.1.26)
2023-02-07 10:29:50 (1646170): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2023-02-07 10:29:51 (1646170): Guest Log: BIOS: VirtualBox 6.1.25
2023-02-07 10:29:51 (1646170): Guest Log: CPUID EDX: 0x178bfbff
2023-02-07 10:29:51 (1646170): Guest Log: BIOS: No PCI IDE controller, not probing IDE
2023-02-07 10:29:51 (1646170): Guest Log: BIOS: AHCI 0-P#0: PCHS=16383/16/63 LCHS=1024/255/63 0x0000000002800000 sectors
2023-02-07 10:29:51 (1646170): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032
2023-02-07 10:29:51 (1646170): Guest Log: BIOS: Booting from Hard Disk...
2023-02-07 10:29:51 (1646170): Guest Log: BIOS: KBD: unsupported int 16h function 03
2023-02-07 10:29:51 (1646170): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 
2023-02-07 10:29:51 (1646170): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds
2023-02-07 10:29:51 (1646170): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000)
2023-02-07 10:29:51 (1646170): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log
2023-02-07 10:29:51 (1646170): Guest Log: 00:00:00.000127 main     Log opened 2023-02-07T05:36:29.184466000Z
2023-02-07 10:29:51 (1646170): Guest Log: 00:00:00.000254 main     OS Product: Linux
2023-02-07 10:29:51 (1646170): Guest Log: 00:00:00.000297 main     OS Release: 4.14.232-19.cernvm.x86_64
2023-02-07 10:29:51 (1646170): Guest Log: 00:00:00.000331 main     OS Version: #1 SMP Fri Apr 30 17:12:25 CEST 2021
2023-02-07 10:29:51 (1646170): Guest Log: 00:00:00.000363 main     Executable: /usr/sbin/VBoxService
2023-02-07 10:29:51 (1646170): Guest Log: 00:00:00.000364 main     Process ID: 2716
2023-02-07 10:29:51 (1646170): Guest Log: 00:00:00.000365 main     Package type: LINUX_64BITS_GENERIC
2023-02-07 10:29:51 (1646170): Guest Log: 00:00:00.001476 main     5.2.6 r120293 started. Verbose level = 0
2023-02-07 10:29:51 (1646170): Guest Log: [INFO] Mounting the shared directory
2023-02-07 10:29:51 (1646170): Guest Log: [INFO] Shared directory mounted, enabling vboxmonitor
2023-02-07 10:29:51 (1646170): Guest Log: [INFO] Sourcing essential functions from /cvmfs/grid.cern.ch
2023-02-07 10:29:51 (1646170): Guest Log: [INFO] Testing connection to cern.ch
2023-02-07 10:29:51 (1646170): Guest Log: [INFO] Testing connection to VCCS
2023-02-07 10:29:51 (1646170): Guest Log: [INFO] Testing connection to HTCondor
2023-02-07 10:29:51 (1646170): Guest Log: [INFO] Testing connection to WMAgent
2023-02-07 10:29:51 (1646170): Guest Log: [INFO] Testing connection to EOSCMS
2023-02-07 10:29:51 (1646170): Guest Log: [INFO] Testing connection to CMS-Factory
2023-02-07 10:29:51 (1646170): Guest Log: [INFO] Testing connection to CMS-Frontier
2023-02-07 10:29:51 (1646170): Guest Log: [INFO] Testing connection to Frontier
2023-02-07 10:29:51 (1646170): Guest Log: [INFO] Could not find a local HTTP proxy
2023-02-07 10:29:51 (1646170): Guest Log: [INFO] CVMFS and Frontier will have to use DIRECT connections
2023-02-07 10:29:51 (1646170): Guest Log: [INFO] This makes the application less efficient
2023-02-07 10:29:51 (1646170): Guest Log: [INFO] It also puts higher load on the project servers
2023-02-07 10:29:51 (1646170): Guest Log: [INFO] Setting up a local HTTP proxy is highly recommended
2023-02-07 10:29:51 (1646170): Guest Log: [INFO] Advice can be found in the project forum
2023-02-07 10:29:51 (1646170): Guest Log: [INFO] Reloading and probing the CVMFS configuration
2023-02-07 10:29:51 (1646170): Guest Log: [INFO] Probing /cvmfs/grid.cern.ch... OK
2023-02-07 10:29:51 (1646170): Guest Log: [INFO] Probing /cvmfs/singularity.opensciencegrid.org... OK
2023-02-07 10:29:51 (1646170): Guest Log: [INFO] Probing /cvmfs/oasis.opensciencegrid.org... OK
2023-02-07 10:29:51 (1646170): Guest Log: [INFO] Probing /cvmfs/cms-ib.cern.ch... OK
2023-02-07 10:29:51 (1646170): Guest Log: [INFO] Probing /cvmfs/cms.cern.ch... OK
2023-02-07 10:29:51 (1646170): Guest Log: [INFO] Excerpt from "cvmfs_config stat": VERSION HOST PROXY
2023-02-07 10:29:51 (1646170): Guest Log: [INFO] 2.7.2.0 http://s1ral-cvmfs.openhtc.io DIRECT
2023-02-07 10:29:51 (1646170): Guest Log: [INFO] Environment HTTP proxy: not set
2023-02-07 10:29:51 (1646170): Guest Log: [INFO] Reading volunteer information
2023-02-07 10:29:51 (1646170): Guest Log: [INFO] Requesting an X509 credential from LHC@home
2023-02-07 10:29:51 (1646170): Guest Log: [INFO] Requesting an X509 credential from vLHC@home-dev
2023-02-07 10:29:51 (1646170): Guest Log: [INFO] CMS application starting. Check log files.
2023-02-07 10:29:51 (1646170): Guest Log: [INFO] Requesting an idtoken from LHC@home
2023-02-07 10:29:51 (1646170): Guest Log: [INFO] Requesting an idtoken from vLHC@home-dev
2023-02-07 10:29:51 (1646170): Starting VM using VBoxManage interface. (boinc_5382160ae12e574b, slot#16)
2023-02-07 10:30:03 (1646170): Successfully started VM. (PID = '1646298')
2023-02-07 10:30:03 (1646170): Reporting VM Process ID to BOINC.
2023-02-07 10:30:03 (1646170): Guest Log: 00:59:31.319498 timesync vgsvcTimeSyncWorker: Radical host time change: 10 456 737 000 000ns (HostNow=1 675 762 203 399 000 000 ns HostLast=1 675 751 746 662 000 000 ns)
2023-02-07 10:30:03 (1646170): VM state change detected. (old = 'poweredoff', new = 'running')
2023-02-07 10:30:03 (1646170): Detected: Web Application Enabled (http://localhost:39173)
2023-02-07 10:30:03 (1646170): Status Report: Job Duration: '64800.000000'
2023-02-07 10:30:03 (1646170): Status Report: Elapsed Time: '10975.000000'
2023-02-07 10:30:03 (1646170): Status Report: CPU Time: '13766.080000'
2023-02-07 10:30:03 (1646170): Preference change detected
2023-02-07 10:30:03 (1646170): Setting CPU throttle for VM. (100%)
2023-02-07 10:30:03 (1646170): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2023-02-07 10:30:13 (1646170): Guest Log: 00:59:41.323618 timesync vgsvcTimeSyncWorker: Radical guest time change: 10 452 906 447 000ns (GuestNow=1 675 762 213 406 646 000 ns GuestLast=1 675 751 760 500 199 000 ns fSetTimeLastLoop=true )
2023-02-07 11:30:21 (1646170): Stopping VM.
2023-02-07 11:31:17 (1646170): Successfully stopped VM.
2023-02-07 13:40:43 (1724470): Detected: vboxwrapper 26206
2023-02-07 13:40:43 (1724470): Detected: BOINC client v7.16.11
2023-02-07 13:40:44 (1724470): Detected: VirtualBox VboxManage Interface (Version: 6.1.26)
2023-02-07 13:40:44 (1724470): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2023-02-07 13:40:44 (1724470): Guest Log: 00:59:31.319498 timesync vgsvcTimeSyncWorker: Radical host time change: 10 456 737 000 000ns (HostNow=1 675 762 203 399 000 000 ns HostLast=1 675 751 746 662 000 000 ns)
2023-02-07 13:40:44 (1724470): Guest Log: 00:59:41.323618 timesync vgsvcTimeSyncWorker: Radical guest time change: 10 452 906 447 000ns (GuestNow=1 675 762 213 406 646 000 ns GuestLast=1 675 751 760 500 199 000 ns fSetTimeLastLoop=true )
2023-02-07 13:40:44 (1724470): Starting VM using VBoxManage interface. (boinc_5382160ae12e574b, slot#16)
2023-02-07 13:40:45 (1724470): Successfully started VM. (PID = '1724568')
2023-02-07 13:40:45 (1724470): Reporting VM Process ID to BOINC.
2023-02-07 13:40:45 (1724470): Guest Log: BIOS: VirtualBox 6.1.25
2023-02-07 13:40:45 (1724470): Guest Log: CPUID EDX: 0x178bfbff
2023-02-07 13:40:45 (1724470): Guest Log: BIOS: No PCI IDE controller, not probing IDE
2023-02-07 13:40:45 (1724470): Guest Log: BIOS: AHCI 0-P#0: PCHS=16383/16/63 LCHS=1024/255/63 0x0000000002800000 sectors
2023-02-07 13:40:45 (1724470): VM state change detected. (old = 'poweredoff', new = 'running')
2023-02-07 13:40:45 (1724470): Detected: Web Application Enabled (http://localhost:39173)
2023-02-07 13:40:45 (1724470): Status Report: Job Duration: '64800.000000'
2023-02-07 13:40:45 (1724470): Status Report: Elapsed Time: '14629.000000'
2023-02-07 13:40:45 (1724470): Status Report: CPU Time: '18006.910000'
2023-02-07 13:40:45 (1724470): Preference change detected
2023-02-07 13:40:45 (1724470): Setting CPU throttle for VM. (100%)
2023-02-07 13:40:45 (1724470): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2023-02-07 13:40:47 (1724470): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032
2023-02-07 13:40:47 (1724470): Guest Log: BIOS: Booting from Hard Disk...
2023-02-07 13:40:50 (1724470): Guest Log: BIOS: KBD: unsupported int 16h function 03
2023-02-07 13:40:50 (1724470): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 
2023-02-07 13:41:39 (1724470): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds
2023-02-07 13:41:39 (1724470): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000)
2023-02-07 13:41:41 (1724470): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log
2023-02-07 13:41:41 (1724470): Guest Log: 00:00:00.000133 main     Log opened 2023-02-07T12:41:40.673957000Z
2023-02-07 13:41:41 (1724470): Guest Log: 00:00:00.000262 main     OS Product: Linux
2023-02-07 13:41:41 (1724470): Guest Log: 00:00:00.000307 main     OS Release: 4.14.232-19.cernvm.x86_64
2023-02-07 13:41:41 (1724470): Guest Log: 00:00:00.000349 main     OS Version: #1 SMP Fri Apr 30 17:12:25 CEST 2021
2023-02-07 13:41:41 (1724470): Guest Log: 00:00:00.000381 main     Executable: /usr/sbin/VBoxService
2023-02-07 13:41:41 (1724470): Guest Log: 00:00:00.000383 main     Process ID: 2717
2023-02-07 13:41:41 (1724470): Guest Log: 00:00:00.000383 main     Package type: LINUX_64BITS_GENERIC
2023-02-07 13:41:41 (1724470): Guest Log: 00:00:00.002373 main     5.2.6 r120293 started. Verbose level = 0
2023-02-07 13:42:06 (1724470): Guest Log: [INFO] Mounting the shared directory
2023-02-07 13:42:07 (1724470): Guest Log: [INFO] Shared directory mounted, enabling vboxmonitor
2023-02-07 13:42:07 (1724470): Guest Log: [INFO] Sourcing essential functions from /cvmfs/grid.cern.ch
2023-02-07 13:42:07 (1724470): Guest Log: [INFO] Testing connection to cern.ch
2023-02-07 13:42:08 (1724470): Guest Log: [INFO] Testing connection to VCCS
2023-02-07 13:42:08 (1724470): Guest Log: [INFO] Testing connection to HTCondor
2023-02-07 13:42:09 (1724470): Guest Log: [INFO] Testing connection to WMAgent
2023-02-07 13:42:10 (1724470): Guest Log: [INFO] Testing connection to EOSCMS
2023-02-07 13:42:10 (1724470): Guest Log: [INFO] Testing connection to CMS-Factory
2023-02-07 13:42:10 (1724470): Guest Log: [INFO] Testing connection to CMS-Frontier
2023-02-07 13:42:11 (1724470): Guest Log: [INFO] Testing connection to Frontier
2023-02-07 13:42:12 (1724470): Guest Log: [INFO] Could not find a local HTTP proxy
2023-02-07 13:42:12 (1724470): Guest Log: [INFO] CVMFS and Frontier will have to use DIRECT connections
2023-02-07 13:42:13 (1724470): Guest Log: [INFO] This makes the application less efficient
2023-02-07 13:42:13 (1724470): Guest Log: [INFO] It also puts higher load on the project servers
2023-02-07 13:42:13 (1724470): Guest Log: [INFO] Setting up a local HTTP proxy is highly recommended
2023-02-07 13:42:13 (1724470): Guest Log: [INFO] Advice can be found in the project forum
2023-02-07 13:42:14 (1724470): Guest Log: [INFO] Reloading and probing the CVMFS configuration
2023-02-07 13:42:25 (1724470): Guest Log: [INFO] Probing /cvmfs/grid.cern.ch... OK
2023-02-07 13:42:30 (1724470): Guest Log: [INFO] Probing /cvmfs/cms-ib.cern.ch... OK
2023-02-07 13:42:30 (1724470): Guest Log: [INFO] Probing /cvmfs/singularity.opensciencegrid.org... OK
2023-02-07 13:42:30 (1724470): Guest Log: [INFO] Probing /cvmfs/oasis.opensciencegrid.org... OK
2023-02-07 13:42:31 (1724470): Guest Log: [INFO] Probing /cvmfs/cms.cern.ch... OK
2023-02-07 13:42:33 (1724470): Guest Log: [INFO] Excerpt from "cvmfs_config stat": VERSION HOST PROXY
2023-02-07 13:42:33 (1724470): Guest Log: [INFO] 2.7.2.0 http://s1cern-cvmfs.openhtc.io DIRECT
2023-02-07 13:42:33 (1724470): Guest Log: [INFO] Environment HTTP proxy: not set
2023-02-07 13:42:34 (1724470): Guest Log: [INFO] Reading volunteer information
2023-02-07 13:42:37 (1724470): Guest Log: [INFO] Requesting an X509 credential from LHC@home
2023-02-07 13:42:38 (1724470): Guest Log: [INFO] Requesting an X509 credential from vLHC@home-dev
2023-02-07 13:42:39 (1724470): Guest Log: [INFO] CMS application starting. Check log files.
2023-02-07 13:42:40 (1724470): Guest Log: [INFO] Requesting an idtoken from LHC@home
2023-02-07 13:42:41 (1724470): Guest Log: [INFO] Requesting an idtoken from vLHC@home-dev
2023-02-07 14:41:34 (1724470): Stopping VM.
2023-02-07 15:14:49 (1764624): Detected: vboxwrapper 26206
2023-02-07 15:14:49 (1764624): Detected: BOINC client v7.16.11
2023-02-07 15:14:49 (1764624): Detected: VirtualBox VboxManage Interface (Version: 6.1.26)
2023-02-07 15:14:49 (1764624): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2023-02-07 15:14:49 (1764624): Guest Log: BIOS: VirtualBox 6.1.25
2023-02-07 15:14:49 (1764624): Guest Log: CPUID EDX: 0x178bfbff
2023-02-07 15:14:49 (1764624): Guest Log: BIOS: No PCI IDE controller, not probing IDE
2023-02-07 15:14:49 (1764624): Guest Log: BIOS: AHCI 0-P#0: PCHS=16383/16/63 LCHS=1024/255/63 0x0000000002800000 sectors
2023-02-07 15:14:49 (1764624): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032
2023-02-07 15:14:49 (1764624): Guest Log: BIOS: Booting from Hard Disk...
2023-02-07 15:14:49 (1764624): Guest Log: BIOS: KBD: unsupported int 16h function 03
2023-02-07 15:14:49 (1764624): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 
2023-02-07 15:14:49 (1764624): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds
2023-02-07 15:14:49 (1764624): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000)
2023-02-07 15:14:49 (1764624): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log
2023-02-07 15:14:49 (1764624): Guest Log: 00:00:00.000133 main     Log opened 2023-02-07T12:41:40.673957000Z
2023-02-07 15:14:49 (1764624): Guest Log: 00:00:00.000262 main     OS Product: Linux
2023-02-07 15:14:49 (1764624): Guest Log: 00:00:00.000307 main     OS Release: 4.14.232-19.cernvm.x86_64
2023-02-07 15:14:49 (1764624): Guest Log: 00:00:00.000349 main     OS Version: #1 SMP Fri Apr 30 17:12:25 CEST 2021
2023-02-07 15:14:49 (1764624): Guest Log: 00:00:00.000381 main     Executable: /usr/sbin/VBoxService
2023-02-07 15:14:49 (1764624): Guest Log: 00:00:00.000383 main     Process ID: 2717
2023-02-07 15:14:49 (1764624): Guest Log: 00:00:00.000383 main     Package type: LINUX_64BITS_GENERIC
2023-02-07 15:14:49 (1764624): Guest Log: 00:00:00.002373 main     5.2.6 r120293 started. Verbose level = 0
2023-02-07 15:14:49 (1764624): Guest Log: [INFO] Mounting the shared directory
2023-02-07 15:14:49 (1764624): Guest Log: [INFO] Shared directory mounted, enabling vboxmonitor
2023-02-07 15:14:49 (1764624): Guest Log: [INFO] Sourcing essential functions from /cvmfs/grid.cern.ch
2023-02-07 15:14:49 (1764624): Guest Log: [INFO] Testing connection to cern.ch
2023-02-07 15:14:49 (1764624): Guest Log: [INFO] Testing connection to VCCS
2023-02-07 15:14:49 (1764624): Guest Log: [INFO] Testing connection to HTCondor
2023-02-07 15:14:49 (1764624): Guest Log: [INFO] Testing connection to WMAgent
2023-02-07 15:14:49 (1764624): Guest Log: [INFO] Testing connection to EOSCMS
2023-02-07 15:14:49 (1764624): Guest Log: [INFO] Testing connection to CMS-Factory
2023-02-07 15:14:49 (1764624): Guest Log: [INFO] Testing connection to CMS-Frontier
2023-02-07 15:14:49 (1764624): Guest Log: [INFO] Testing connection to Frontier
2023-02-07 15:14:49 (1764624): Guest Log: [INFO] Could not find a local HTTP proxy
2023-02-07 15:14:49 (1764624): Guest Log: [INFO] CVMFS and Frontier will have to use DIRECT connections
2023-02-07 15:14:49 (1764624): Guest Log: [INFO] This makes the application less efficient
2023-02-07 15:14:49 (1764624): Guest Log: [INFO] It also puts higher load on the project servers
2023-02-07 15:14:49 (1764624): Guest Log: [INFO] Setting up a local HTTP proxy is highly recommended
2023-02-07 15:14:49 (1764624): Guest Log: [INFO] Advice can be found in the project forum
2023-02-07 15:14:49 (1764624): Guest Log: [INFO] Reloading and probing the CVMFS configuration
2023-02-07 15:14:49 (1764624): Guest Log: [INFO] Probing /cvmfs/grid.cern.ch... OK
2023-02-07 15:14:49 (1764624): Guest Log: [INFO] Probing /cvmfs/cms-ib.cern.ch... OK
2023-02-07 15:14:49 (1764624): Guest Log: [INFO] Probing /cvmfs/singularity.opensciencegrid.org... OK
2023-02-07 15:14:49 (1764624): Guest Log: [INFO] Probing /cvmfs/oasis.opensciencegrid.org... OK
2023-02-07 15:14:49 (1764624): Guest Log: [INFO] Probing /cvmfs/cms.cern.ch... OK
2023-02-07 15:14:49 (1764624): Guest Log: [INFO] Excerpt from "cvmfs_config stat": VERSION HOST PROXY
2023-02-07 15:14:49 (1764624): Guest Log: [INFO] 2.7.2.0 http://s1cern-cvmfs.openhtc.io DIRECT
2023-02-07 15:14:49 (1764624): Guest Log: [INFO] Environment HTTP proxy: not set
2023-02-07 15:14:49 (1764624): Guest Log: [INFO] Reading volunteer information
2023-02-07 15:14:49 (1764624): Guest Log: [INFO] Requesting an X509 credential from LHC@home
2023-02-07 15:14:49 (1764624): Guest Log: [INFO] Requesting an X509 credential from vLHC@home-dev
2023-02-07 15:14:49 (1764624): Guest Log: [INFO] CMS application starting. Check log files.
2023-02-07 15:14:49 (1764624): Guest Log: [INFO] Requesting an idtoken from LHC@home
2023-02-07 15:14:49 (1764624): Guest Log: [INFO] Requesting an idtoken from vLHC@home-dev
2023-02-07 15:14:49 (1764624): Starting VM using VBoxManage interface. (boinc_5382160ae12e574b, slot#16)
2023-02-07 15:15:07 (1764624): Successfully started VM. (PID = '1764700')
2023-02-07 15:15:07 (1764624): Reporting VM Process ID to BOINC.
2023-02-07 15:15:07 (1764624): VM state change detected. (old = 'poweredoff', new = 'running')
2023-02-07 15:15:07 (1764624): Detected: Web Application Enabled (http://localhost:39173)
2023-02-07 15:15:07 (1764624): Status Report: Job Duration: '64800.000000'
2023-02-07 15:15:07 (1764624): Status Report: Elapsed Time: '18314.000000'
2023-02-07 15:15:07 (1764624): Status Report: CPU Time: '22808.480000'
2023-02-07 15:15:07 (1764624): Preference change detected
2023-02-07 15:15:07 (1764624): Setting CPU throttle for VM. (100%)
2023-02-07 15:15:08 (1764624): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2023-02-07 15:15:11 (1764624): Guest Log: 01:00:03.002344 timesync vgsvcTimeSyncWorker: Radical host time change: 2 022 077 000 000ns (HostNow=1 675 779 311 795 000 000 ns HostLast=1 675 777 289 718 000 000 ns)
2023-02-07 15:15:22 (1764624): Guest Log: 01:00:13.019824 timesync vgsvcTimeSyncWorker: Radical guest time change: 2 018 301 350 000ns (GuestNow=1 675 779 321 892 966 000 ns GuestLast=1 675 777 303 591 616 000 ns fSetTimeLastLoop=true )
2023-02-07 16:15:27 (1764624): Stopping VM.
2023-02-07 16:16:13 (1764624): Successfully stopped VM.
2023-02-07 16:26:53 (1795613): Detected: vboxwrapper 26206
2023-02-07 16:26:53 (1795613): Detected: BOINC client v7.16.11
2023-02-07 16:26:53 (1795613): Detected: VirtualBox VboxManage Interface (Version: 6.1.26)
2023-02-07 16:26:53 (1795613): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2023-02-07 16:26:53 (1795613): Guest Log: 01:00:03.002344 timesync vgsvcTimeSyncWorker: Radical host time change: 2 022 077 000 000ns (HostNow=1 675 779 311 795 000 000 ns HostLast=1 675 777 289 718 000 000 ns)
2023-02-07 16:26:53 (1795613): Guest Log: 01:00:13.019824 timesync vgsvcTimeSyncWorker: Radical guest time change: 2 018 301 350 000ns (GuestNow=1 675 779 321 892 966 000 ns GuestLast=1 675 777 303 591 616 000 ns fSetTimeLastLoop=true )
2023-02-07 16:26:53 (1795613): Starting VM using VBoxManage interface. (boinc_5382160ae12e574b, slot#16)
2023-02-07 16:27:12 (1795613): Successfully started VM. (PID = '1795693')
2023-02-07 16:27:12 (1795613): Reporting VM Process ID to BOINC.
2023-02-07 16:27:12 (1795613): VM state change detected. (old = 'poweredoff', new = 'running')
2023-02-07 16:27:12 (1795613): Detected: Web Application Enabled (http://localhost:39173)
2023-02-07 16:27:12 (1795613): Status Report: Job Duration: '64800.000000'
2023-02-07 16:27:12 (1795613): Status Report: Elapsed Time: '21970.000000'
2023-02-07 16:27:12 (1795613): Status Report: CPU Time: '27034.870000'
2023-02-07 16:27:12 (1795613): Preference change detected
2023-02-07 16:27:12 (1795613): Setting CPU throttle for VM. (100%)
2023-02-07 16:27:13 (1795613): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2023-02-07 17:27:37 (1795613): Stopping VM.
2023-02-07 17:28:30 (1795613): Successfully stopped VM.
2023-02-07 17:59:41 (1835197): Detected: vboxwrapper 26206
2023-02-07 17:59:41 (1835197): Detected: BOINC client v7.16.11
2023-02-07 17:59:41 (1835197): Detected: VirtualBox VboxManage Interface (Version: 6.1.26)
2023-02-07 17:59:41 (1835197): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2023-02-07 17:59:41 (1835197): Starting VM using VBoxManage interface. (boinc_5382160ae12e574b, slot#16)
2023-02-07 18:00:01 (1835197): Successfully started VM. (PID = '1835275')
2023-02-07 18:00:01 (1835197): Reporting VM Process ID to BOINC.
2023-02-07 18:00:01 (1835197): VM state change detected. (old = 'poweredoff', new = 'running')
2023-02-07 18:00:01 (1835197): Detected: Web Application Enabled (http://localhost:39173)
2023-02-07 18:00:01 (1835197): Status Report: Job Duration: '64800.000000'
2023-02-07 18:00:01 (1835197): Status Report: Elapsed Time: '25631.000000'
2023-02-07 18:00:01 (1835197): Status Report: CPU Time: '31327.870000'
2023-02-07 18:00:01 (1835197): Preference change detected
2023-02-07 18:00:01 (1835197): Setting CPU throttle for VM. (100%)
2023-02-07 18:00:02 (1835197): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2023-02-07 18:00:04 (1835197): Guest Log: 03:00:57.350297 timesync vgsvcTimeSyncWorker: Radical host time change: 1 952 622 000 000ns (HostNow=1 675 789 204 187 000 000 ns HostLast=1 675 787 251 565 000 000 ns)
2023-02-07 18:00:14 (1835197): Guest Log: 03:01:07.351172 timesync vgsvcTimeSyncWorker: Radical guest time change: 1 948 717 102 000ns (GuestNow=1 675 789 214 201 609 000 ns GuestLast=1 675 787 265 484 507 000 ns fSetTimeLastLoop=true )
2023-02-07 18:59:52 (1835197): Stopping VM.
2023-02-07 19:00:38 (1835197): Successfully stopped VM.
2023-02-07 19:48:58 (1881017): Detected: vboxwrapper 26206
2023-02-07 19:48:58 (1881017): Detected: BOINC client v7.16.11
2023-02-07 19:48:58 (1881017): Detected: VirtualBox VboxManage Interface (Version: 6.1.26)
2023-02-07 19:48:58 (1881017): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2023-02-07 19:48:59 (1881017): Guest Log: 03:00:57.350297 timesync vgsvcTimeSyncWorker: Radical host time change: 1 952 622 000 000ns (HostNow=1 675 789 204 187 000 000 ns HostLast=1 675 787 251 565 000 000 ns)
2023-02-07 19:48:59 (1881017): Guest Log: 03:01:07.351172 timesync vgsvcTimeSyncWorker: Radical guest time change: 1 948 717 102 000ns (GuestNow=1 675 789 214 201 609 000 ns GuestLast=1 675 787 265 484 507 000 ns fSetTimeLastLoop=true )
2023-02-07 19:48:59 (1881017): Starting VM using VBoxManage interface. (boinc_5382160ae12e574b, slot#16)
2023-02-07 19:49:18 (1881017): Successfully started VM. (PID = '1881093')
2023-02-07 19:49:18 (1881017): Reporting VM Process ID to BOINC.
2023-02-07 19:49:18 (1881017): VM state change detected. (old = 'poweredoff', new = 'running')
2023-02-07 19:49:18 (1881017): Detected: Web Application Enabled (http://localhost:39173)
2023-02-07 19:49:18 (1881017): Status Report: Job Duration: '64800.000000'
2023-02-07 19:49:18 (1881017): Status Report: Elapsed Time: '29258.000000'
2023-02-07 19:49:18 (1881017): Status Report: CPU Time: '35542.560000'
2023-02-07 19:49:18 (1881017): Preference change detected
2023-02-07 19:49:18 (1881017): Setting CPU throttle for VM. (100%)
2023-02-07 19:49:19 (1881017): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2023-02-07 19:49:27 (1881017): Guest Log: 04:00:59.459562 timesync vgsvcTimeSyncWorker: Radical host time change: 2 974 926 000 000ns (HostNow=1 675 795 767 285 000 000 ns HostLast=1 675 792 792 359 000 000 ns)
2023-02-07 19:49:37 (1881017): Guest Log: 04:01:09.489014 timesync vgsvcTimeSyncWorker: Radical guest time change: 2 971 151 762 000ns (GuestNow=1 675 795 777 387 988 000 ns GuestLast=1 675 792 806 236 226 000 ns fSetTimeLastLoop=true )
2023-02-07 20:49:46 (1881017): Stopping VM.
2023-02-07 20:50:44 (1881017): Successfully stopped VM.
2023-02-07 22:30:27 (1948227): Detected: vboxwrapper 26206
2023-02-07 22:30:27 (1948227): Detected: BOINC client v7.16.11
2023-02-07 22:30:27 (1948227): Detected: VirtualBox VboxManage Interface (Version: 6.1.26)
2023-02-07 22:30:27 (1948227): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2023-02-07 22:30:28 (1948227): Guest Log: 04:00:59.459562 timesync vgsvcTimeSyncWorker: Radical host time change: 2 974 926 000 000ns (HostNow=1 675 795 767 285 000 000 ns HostLast=1 675 792 792 359 000 000 ns)
2023-02-07 22:30:28 (1948227): Guest Log: 04:01:09.489014 timesync vgsvcTimeSyncWorker: Radical guest time change: 2 971 151 762 000ns (GuestNow=1 675 795 777 387 988 000 ns GuestLast=1 675 792 806 236 226 000 ns fSetTimeLastLoop=true )
2023-02-07 22:30:28 (1948227): Starting VM using VBoxManage interface. (boinc_5382160ae12e574b, slot#16)
2023-02-07 22:30:42 (1948227): Successfully started VM. (PID = '1948332')
2023-02-07 22:30:42 (1948227): Reporting VM Process ID to BOINC.
2023-02-07 22:30:42 (1948227): Guest Log: 05:01:23.102869 timesync vgsvcTimeSyncWorker: Radical host time change: 6 064 667 000 000ns (HostNow=1 675 805 441 588 000 000 ns HostLast=1 675 799 376 921 000 000 ns)
2023-02-07 22:30:42 (1948227): VM state change detected. (old = 'poweredoff', new = 'running')
2023-02-07 22:30:42 (1948227): Detected: Web Application Enabled (http://localhost:39173)
2023-02-07 22:30:42 (1948227): Status Report: Job Duration: '64800.000000'
2023-02-07 22:30:42 (1948227): Status Report: Elapsed Time: '32922.000000'
2023-02-07 22:30:42 (1948227): Status Report: CPU Time: '39843.970000'
2023-02-07 22:30:42 (1948227): Preference change detected
2023-02-07 22:30:42 (1948227): Setting CPU throttle for VM. (100%)
2023-02-07 22:30:42 (1948227): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2023-02-07 22:30:52 (1948227): Guest Log: 05:01:33.110636 timesync vgsvcTimeSyncWorker: Radical guest time change: 6 060 737 692 000ns (GuestNow=1 675 805 451 665 303 000 ns GuestLast=1 675 799 390 927 611 000 ns fSetTimeLastLoop=true )
2023-02-07 23:31:03 (1948227): Stopping VM.
2023-02-08 00:46:58 (2006750): Detected: vboxwrapper 26206
2023-02-08 00:46:58 (2006750): Detected: BOINC client v7.16.11
2023-02-08 00:46:58 (2006750): Detected: VirtualBox VboxManage Interface (Version: 6.1.26)
2023-02-08 00:46:58 (2006750): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2023-02-08 00:46:58 (2006750): Guest Log: 05:01:23.102869 timesync vgsvcTimeSyncWorker: Radical host time change: 6 064 667 000 000ns (HostNow=1 675 805 441 588 000 000 ns HostLast=1 675 799 376 921 000 000 ns)
2023-02-08 00:46:58 (2006750): Guest Log: 05:01:33.110636 timesync vgsvcTimeSyncWorker: Radical guest time change: 6 060 737 692 000ns (GuestNow=1 675 805 451 665 303 000 ns GuestLast=1 675 799 390 927 611 000 ns fSetTimeLastLoop=true )
2023-02-08 00:46:58 (2006750): Starting VM using VBoxManage interface. (boinc_5382160ae12e574b, slot#16)
2023-02-08 00:47:13 (2006750): Successfully started VM. (PID = '2006873')
2023-02-08 00:47:13 (2006750): Reporting VM Process ID to BOINC.
2023-02-08 00:47:13 (2006750): VM state change detected. (old = 'poweredoff', new = 'running')
2023-02-08 00:47:13 (2006750): Detected: Web Application Enabled (http://localhost:39173)
2023-02-08 00:47:13 (2006750): Status Report: Job Duration: '64800.000000'
2023-02-08 00:47:13 (2006750): Status Report: Elapsed Time: '36579.000000'
2023-02-08 00:47:13 (2006750): Status Report: CPU Time: '44051.360000'
2023-02-08 00:47:13 (2006750): Preference change detected
2023-02-08 00:47:13 (2006750): Setting CPU throttle for VM. (100%)
2023-02-08 00:47:13 (2006750): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2023-02-08 00:47:19 (2006750): Guest Log: 06:01:55.255744 timesync vgsvcTimeSyncWorker: Radical host time change: 4 578 906 000 000ns (HostNow=1 675 813 638 817 000 000 ns HostLast=1 675 809 059 911 000 000 ns)
2023-02-08 00:47:29 (2006750): Guest Log: 06:02:05.260024 timesync vgsvcTimeSyncWorker: Radical guest time change: 4 575 014 871 000ns (GuestNow=1 675 813 648 823 272 000 ns GuestLast=1 675 809 073 808 401 000 ns fSetTimeLastLoop=true )
2023-02-08 01:11:14 (2006750): Guest Log: [INFO] glidein exited with return value 0.
2023-02-08 01:11:14 (2006750): Guest Log: [INFO] Shutting Down.
2023-02-08 01:11:14 (2006750): VM Completion File Detected.
2023-02-08 01:11:14 (2006750): VM Completion Message: glidein exited with return value 0.
.
2023-02-08 01:11:14 (2006750): Powering off VM.
2023-02-08 01:11:15 (2006750): Successfully stopped VM.
2023-02-08 01:11:15 (2006750): Deregistering VM. (boinc_5382160ae12e574b, slot#16)
2023-02-08 01:11:15 (2006750): Removing network bandwidth throttle group from VM.
2023-02-08 01:11:15 (2006750): Removing VM from VirtualBox.
01:11:20 (2006750): called boinc_finish(0)

</stderr_txt>
]]>


©2025 CERN