Task 3101496

Name	CMS_1755608_1658296562.981912_0
Workunit	2197856
Created	20 Jul 2022, 5:56:06 UTC
Sent	23 Jul 2022, 1:35:05 UTC
Report deadline	30 Jul 2022, 1:35:05 UTC
Received	24 Jul 2022, 7:24:08 UTC
Server state	Over
Outcome	Success
Client state	Done
Exit status	0 (0x00000000)
Computer ID	4318
Run time	14 hours 34 min 27 sec
CPU time	21 hours 17 min 31 sec
Validate state	Valid
Credit	2,101.21
Device peak FLOPS	32.68 GFLOPS
Application version	CMS Simulation v60.62 (vbox64_mt_mcore_cms) x86_64-pc-linux-gnu
Peak working set size	6.23 GB
Peak swap size	8.74 GB
Peak disk usage	4.44 GB
Stderr output

<core_client_version>7.16.11</core_client_version>
<![CDATA[
<stderr_txt>
2022-07-23 16:01:21 (589310): Detected: vboxwrapper 26204
2022-07-23 16:01:21 (589310): Detected: BOINC client v7.16.11
2022-07-23 16:01:22 (589310): Detected: VirtualBox VboxManage Interface (Version: 6.1.26)
2022-07-23 16:01:22 (589310): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2022-07-23 16:01:22 (589310): Successfully copied 'init_data.xml' to the shared directory.
2022-07-23 16:01:22 (589310): Create VM. (boinc_724be4a5fdd21542, slot#14)
2022-07-23 16:01:22 (589310): Setting Memory Size for VM. (15336MB)
2022-07-23 16:01:22 (589310): Setting CPU Count for VM. (16)
2022-07-23 16:01:23 (589310): Setting Chipset Options for VM.
2022-07-23 16:01:23 (589310): Setting Graphics Controller Options for VM.
2022-07-23 16:01:23 (589310): Setting Boot Options for VM.
2022-07-23 16:01:24 (589310): Setting Network Configuration for NAT.
2022-07-23 16:01:24 (589310): Enabling VM Network Access.
2022-07-23 16:01:24 (589310): Disabling USB Support for VM.
2022-07-23 16:01:24 (589310): Disabling COM Port Support for VM.
2022-07-23 16:01:25 (589310): Disabling LPT Port Support for VM.
2022-07-23 16:01:25 (589310): Disabling Audio Support for VM.
2022-07-23 16:01:25 (589310): Disabling Clipboard Support for VM.
2022-07-23 16:01:25 (589310): Disabling Drag and Drop Support for VM.
2022-07-23 16:01:25 (589310): Adding storage controller(s) to VM.
2022-07-23 16:01:25 (589310): Adding virtual disk drive to VM. (CMS_2022_06_22.vdi)
2022-07-23 16:01:26 (589310): Adding network bandwidth throttle group to VM. (Defaulting to 1024GB)
2022-07-23 16:01:26 (589310): forwarding host port 41611 to guest port 80
2022-07-23 16:01:26 (589310): Enabling remote desktop for VM.
2022-07-23 16:01:26 (589310): Required extension pack not installed, remote desktop not enabled.
2022-07-23 16:01:26 (589310): Enabling shared directory for VM.
2022-07-23 16:01:27 (589310): Starting VM using VBoxManage interface. (boinc_724be4a5fdd21542, slot#14)
2022-07-23 16:01:29 (589310): Successfully started VM. (PID = '589821')
2022-07-23 16:01:29 (589310): Reporting VM Process ID to BOINC.
2022-07-23 16:01:29 (589310): Guest Log: BIOS: VirtualBox 6.1.25
2022-07-23 16:01:29 (589310): Guest Log: CPUID EDX: 0x178bfbff
2022-07-23 16:01:29 (589310): Guest Log: BIOS: No PCI IDE controller, not probing IDE
2022-07-23 16:01:29 (589310): Guest Log: BIOS: AHCI 0-P#0: PCHS=16383/16/63 LCHS=1024/255/63 0x0000000002800000 sectors
2022-07-23 16:01:29 (589310): VM state change detected. (old = 'poweredoff', new = 'running')
2022-07-23 16:01:29 (589310): Detected: Web Application Enabled (http://localhost:41611)
2022-07-23 16:01:29 (589310): Preference change detected
2022-07-23 16:01:29 (589310): Setting CPU throttle for VM. (100%)
2022-07-23 16:01:29 (589310): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2022-07-23 16:01:31 (589310): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032
2022-07-23 16:01:31 (589310): Guest Log: BIOS: Booting from Hard Disk...
2022-07-23 16:01:41 (589310): Guest Log: BIOS: KBD: unsupported int 16h function 03
2022-07-23 16:01:41 (589310): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 
2022-07-23 16:02:29 (589310): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds
2022-07-23 16:02:29 (589310): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000)
2022-07-23 16:02:34 (589310): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log
2022-07-23 16:02:34 (589310): Guest Log: 00:00:00.000178 main     Log opened 2022-07-23T14:02:33.637549000Z
2022-07-23 16:02:34 (589310): Guest Log: 00:00:00.000381 main     OS Product: Linux
2022-07-23 16:02:34 (589310): Guest Log: 00:00:00.000424 main     OS Release: 4.14.232-19.cernvm.x86_64
2022-07-23 16:02:34 (589310): Guest Log: 00:00:00.000461 main     OS Version: #1 SMP Fri Apr 30 17:12:25 CEST 2021
2022-07-23 16:02:34 (589310): Guest Log: 00:00:00.000556 main     Executable: /usr/sbin/VBoxService
2022-07-23 16:02:34 (589310): Guest Log: 00:00:00.000558 main     Process ID: 2696
2022-07-23 16:02:34 (589310): Guest Log: 00:00:00.000559 main     Package type: LINUX_64BITS_GENERIC
2022-07-23 16:02:34 (589310): Guest Log: 00:00:00.002473 main     5.2.6 r120293 started. Verbose level = 0
2022-07-23 16:02:57 (589310): Guest Log: [INFO] Mounting the shared directory
2022-07-23 16:02:57 (589310): Guest Log: [INFO] Shared directory mounted, enabling vboxmonitor
2022-07-23 16:02:57 (589310): Guest Log: [INFO] Sourcing essential functions from /cvmfs/grid.cern.ch
2022-07-23 16:02:58 (589310): Guest Log: [INFO] Testing connection to cern.ch
2022-07-23 16:02:58 (589310): Guest Log: [INFO] Testing connection to VCCS
2022-07-23 16:02:59 (589310): Guest Log: [INFO] Testing connection to HTCondor
2022-07-23 16:02:59 (589310): Guest Log: [INFO] Testing connection to WMAgent
2022-07-23 16:03:00 (589310): Guest Log: [INFO] Testing connection to EOSCMS
2022-07-23 16:03:00 (589310): Guest Log: [INFO] Testing connection to CMS-Frontier
2022-07-23 16:03:00 (589310): Guest Log: [INFO] Testing connection to Frontier
2022-07-23 16:03:02 (589310): Guest Log: [INFO] Could not find a local HTTP proxy
2022-07-23 16:03:02 (589310): Guest Log: [INFO] CVMFS and Frontier will have to use DIRECT connections
2022-07-23 16:03:02 (589310): Guest Log: [INFO] This makes the application less efficient
2022-07-23 16:03:03 (589310): Guest Log: [INFO] It also puts higher load on the project servers
2022-07-23 16:03:03 (589310): Guest Log: [INFO] Setting up a local HTTP proxy is highly recommended
2022-07-23 16:03:03 (589310): Guest Log: [INFO] Advice can be found in the project forum
2022-07-23 16:03:03 (589310): Guest Log: [INFO] Reloading and probing the CVMFS configuration
2022-07-23 16:03:14 (589310): Guest Log: [INFO] Probing /cvmfs/cvmfs-config.cern.ch... OK
2022-07-23 16:03:15 (589310): Guest Log: [INFO] Probing /cvmfs/grid.cern.ch... OK
2022-07-23 16:03:19 (589310): Guest Log: [INFO] Probing /cvmfs/oasis.opensciencegrid.org... OK
2022-07-23 16:03:20 (589310): Guest Log: [INFO] Probing /cvmfs/singularity.opensciencegrid.org... OK
2022-07-23 16:03:20 (589310): Guest Log: [INFO] Probing /cvmfs/cms-ib.cern.ch... OK
2022-07-23 16:03:20 (589310): Guest Log: [INFO] Probing /cvmfs/cms.cern.ch... OK
2022-07-23 16:03:23 (589310): Guest Log: [INFO] Excerpt from "cvmfs_config stat": VERSION HOST PROXY
2022-07-23 16:03:23 (589310): Guest Log: [INFO] 2.7.2.0 http://s1ral-cvmfs.openhtc.io DIRECT
2022-07-23 16:03:23 (589310): Guest Log: [INFO] Reading volunteer information
2022-07-23 16:03:33 (589310): Guest Log: [INFO] Requesting an X509 credential from LHC@home
2022-07-23 16:03:35 (589310): Guest Log: [INFO] Requesting an X509 credential from vLHC@home-dev
2022-07-23 16:03:37 (589310): Guest Log: [INFO] CMS application starting. Check log files.
2022-07-23 17:01:29 (589310): Stopping VM.
2022-07-23 17:02:21 (589310): Successfully stopped VM.
2022-07-23 17:32:05 (644848): Detected: vboxwrapper 26204
2022-07-23 17:32:05 (644848): Detected: BOINC client v7.16.11
2022-07-23 17:32:06 (644848): Detected: VirtualBox VboxManage Interface (Version: 6.1.26)
2022-07-23 17:32:06 (644848): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2022-07-23 17:32:06 (644848): Guest Log: BIOS: VirtualBox 6.1.25
2022-07-23 17:32:06 (644848): Guest Log: CPUID EDX: 0x178bfbff
2022-07-23 17:32:06 (644848): Guest Log: BIOS: No PCI IDE controller, not probing IDE
2022-07-23 17:32:06 (644848): Guest Log: BIOS: AHCI 0-P#0: PCHS=16383/16/63 LCHS=1024/255/63 0x0000000002800000 sectors
2022-07-23 17:32:06 (644848): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032
2022-07-23 17:32:06 (644848): Guest Log: BIOS: Booting from Hard Disk...
2022-07-23 17:32:06 (644848): Guest Log: BIOS: KBD: unsupported int 16h function 03
2022-07-23 17:32:06 (644848): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 
2022-07-23 17:32:06 (644848): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds
2022-07-23 17:32:06 (644848): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000)
2022-07-23 17:32:06 (644848): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log
2022-07-23 17:32:06 (644848): Guest Log: 00:00:00.000178 main     Log opened 2022-07-23T14:02:33.637549000Z
2022-07-23 17:32:06 (644848): Guest Log: 00:00:00.000381 main     OS Product: Linux
2022-07-23 17:32:06 (644848): Guest Log: 00:00:00.000424 main     OS Release: 4.14.232-19.cernvm.x86_64
2022-07-23 17:32:06 (644848): Guest Log: 00:00:00.000461 main     OS Version: #1 SMP Fri Apr 30 17:12:25 CEST 2021
2022-07-23 17:32:06 (644848): Guest Log: 00:00:00.000556 main     Executable: /usr/sbin/VBoxService
2022-07-23 17:32:06 (644848): Guest Log: 00:00:00.000558 main     Process ID: 2696
2022-07-23 17:32:06 (644848): Guest Log: 00:00:00.000559 main     Package type: LINUX_64BITS_GENERIC
2022-07-23 17:32:06 (644848): Guest Log: 00:00:00.002473 main     5.2.6 r120293 started. Verbose level = 0
2022-07-23 17:32:06 (644848): Guest Log: [INFO] Mounting the shared directory
2022-07-23 17:32:06 (644848): Guest Log: [INFO] Shared directory mounted, enabling vboxmonitor
2022-07-23 17:32:06 (644848): Guest Log: [INFO] Sourcing essential functions from /cvmfs/grid.cern.ch
2022-07-23 17:32:06 (644848): Guest Log: [INFO] Testing connection to cern.ch
2022-07-23 17:32:06 (644848): Guest Log: [INFO] Testing connection to VCCS
2022-07-23 17:32:06 (644848): Guest Log: [INFO] Testing connection to HTCondor
2022-07-23 17:32:06 (644848): Guest Log: [INFO] Testing connection to WMAgent
2022-07-23 17:32:06 (644848): Guest Log: [INFO] Testing connection to EOSCMS
2022-07-23 17:32:06 (644848): Guest Log: [INFO] Testing connection to CMS-Frontier
2022-07-23 17:32:06 (644848): Guest Log: [INFO] Testing connection to Frontier
2022-07-23 17:32:06 (644848): Guest Log: [INFO] Could not find a local HTTP proxy
2022-07-23 17:32:06 (644848): Guest Log: [INFO] CVMFS and Frontier will have to use DIRECT connections
2022-07-23 17:32:06 (644848): Guest Log: [INFO] This makes the application less efficient
2022-07-23 17:32:06 (644848): Guest Log: [INFO] It also puts higher load on the project servers
2022-07-23 17:32:06 (644848): Guest Log: [INFO] Setting up a local HTTP proxy is highly recommended
2022-07-23 17:32:06 (644848): Guest Log: [INFO] Advice can be found in the project forum
2022-07-23 17:32:06 (644848): Guest Log: [INFO] Reloading and probing the CVMFS configuration
2022-07-23 17:32:06 (644848): Guest Log: [INFO] Probing /cvmfs/cvmfs-config.cern.ch... OK
2022-07-23 17:32:06 (644848): Guest Log: [INFO] Probing /cvmfs/grid.cern.ch... OK
2022-07-23 17:32:06 (644848): Guest Log: [INFO] Probing /cvmfs/oasis.opensciencegrid.org... OK
2022-07-23 17:32:06 (644848): Guest Log: [INFO] Probing /cvmfs/singularity.opensciencegrid.org... OK
2022-07-23 17:32:06 (644848): Guest Log: [INFO] Probing /cvmfs/cms-ib.cern.ch... OK
2022-07-23 17:32:06 (644848): Guest Log: [INFO] Probing /cvmfs/cms.cern.ch... OK
2022-07-23 17:32:06 (644848): Guest Log: [INFO] Excerpt from "cvmfs_config stat": VERSION HOST PROXY
2022-07-23 17:32:06 (644848): Guest Log: [INFO] 2.7.2.0 http://s1ral-cvmfs.openhtc.io DIRECT
2022-07-23 17:32:06 (644848): Guest Log: [INFO] Reading volunteer information
2022-07-23 17:32:06 (644848): Guest Log: [INFO] Requesting an X509 credential from LHC@home
2022-07-23 17:32:06 (644848): Guest Log: [INFO] Requesting an X509 credential from vLHC@home-dev
2022-07-23 17:32:06 (644848): Guest Log: [INFO] CMS application starting. Check log files.
2022-07-23 17:32:06 (644848): Starting VM using VBoxManage interface. (boinc_724be4a5fdd21542, slot#14)
2022-07-23 17:32:26 (644848): Successfully started VM. (PID = '644969')
2022-07-23 17:32:26 (644848): Reporting VM Process ID to BOINC.
2022-07-23 17:32:26 (644848): VM state change detected. (old = 'poweredoff', new = 'running')
2022-07-23 17:32:26 (644848): Detected: Web Application Enabled (http://localhost:41611)
2022-07-23 17:32:26 (644848): Preference change detected
2022-07-23 17:32:26 (644848): Setting CPU throttle for VM. (100%)
2022-07-23 17:32:26 (644848): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2022-07-23 17:32:30 (644848): Guest Log: 00:59:00.704236 timesync vgsvcTimeSyncWorker: Radical host time change: 1 865 585 000 000ns (HostNow=1 658 590 349 846 000 000 ns HostLast=1 658 588 484 261 000 000 ns)
2022-07-23 17:32:40 (644848): Guest Log: 00:59:10.723811 timesync vgsvcTimeSyncWorker: Radical guest time change: 1 865 587 071 000ns (GuestNow=1 658 590 359 897 055 000 ns GuestLast=1 658 588 494 309 984 000 ns fSetTimeLastLoop=true )
2022-07-23 18:11:27 (644848): Status Report: Job Duration: '64800.000000'
2022-07-23 18:11:27 (644848): Status Report: Elapsed Time: '6000.000000'
2022-07-23 18:11:27 (644848): Status Report: CPU Time: '10236.220000'
2022-07-23 18:32:53 (644848): Stopping VM.
2022-07-23 18:33:43 (644848): Successfully stopped VM.
2022-07-23 18:58:47 (696208): Detected: vboxwrapper 26204
2022-07-23 18:58:47 (696208): Detected: BOINC client v7.16.11
2022-07-23 18:58:47 (696208): Detected: VirtualBox VboxManage Interface (Version: 6.1.26)
2022-07-23 18:58:47 (696208): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2022-07-23 18:58:48 (696208): Guest Log: 00:59:00.704236 timesync vgsvcTimeSyncWorker: Radical host time change: 1 865 585 000 000ns (HostNow=1 658 590 349 846 000 000 ns HostLast=1 658 588 484 261 000 000 ns)
2022-07-23 18:58:48 (696208): Guest Log: 00:59:10.723811 timesync vgsvcTimeSyncWorker: Radical guest time change: 1 865 587 071 000ns (GuestNow=1 658 590 359 897 055 000 ns GuestLast=1 658 588 494 309 984 000 ns fSetTimeLastLoop=true )
2022-07-23 18:58:48 (696208): Starting VM using VBoxManage interface. (boinc_724be4a5fdd21542, slot#14)
2022-07-23 18:59:08 (696208): Successfully started VM. (PID = '696315')
2022-07-23 18:59:08 (696208): Reporting VM Process ID to BOINC.
2022-07-23 18:59:08 (696208): VM state change detected. (old = 'poweredoff', new = 'running')
2022-07-23 18:59:08 (696208): Detected: Web Application Enabled (http://localhost:41611)
2022-07-23 18:59:08 (696208): Status Report: Job Duration: '64800.000000'
2022-07-23 18:59:08 (696208): Status Report: Elapsed Time: '7299.000000'
2022-07-23 18:59:08 (696208): Status Report: CPU Time: '12374.940000'
2022-07-23 18:59:08 (696208): Preference change detected
2022-07-23 18:59:08 (696208): Setting CPU throttle for VM. (100%)
2022-07-23 18:59:09 (696208): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2022-07-23 19:59:06 (696208): Stopping VM.
2022-07-23 19:59:56 (696208): Successfully stopped VM.
2022-07-23 20:14:57 (734710): Detected: vboxwrapper 26204
2022-07-23 20:14:57 (734710): Detected: BOINC client v7.16.11
2022-07-23 20:14:58 (734710): Detected: VirtualBox VboxManage Interface (Version: 6.1.26)
2022-07-23 20:14:58 (734710): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2022-07-23 20:14:59 (734710): Starting VM using VBoxManage interface. (boinc_724be4a5fdd21542, slot#14)
2022-07-23 20:15:20 (734710): Successfully started VM. (PID = '734846')
2022-07-23 20:15:20 (734710): Reporting VM Process ID to BOINC.
2022-07-23 20:15:20 (734710): VM state change detected. (old = 'poweredoff', new = 'running')
2022-07-23 20:15:20 (734710): Detected: Web Application Enabled (http://localhost:41611)
2022-07-23 20:15:20 (734710): Status Report: Job Duration: '64800.000000'
2022-07-23 20:15:20 (734710): Status Report: Elapsed Time: '10933.000000'
2022-07-23 20:15:20 (734710): Status Report: CPU Time: '18277.830000'
2022-07-23 20:15:20 (734710): Preference change detected
2022-07-23 20:15:20 (734710): Setting CPU throttle for VM. (100%)
2022-07-23 20:15:20 (734710): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2022-07-23 21:15:52 (734710): Stopping VM.
2022-07-23 21:33:54 (774581): Detected: vboxwrapper 26204
2022-07-23 21:33:54 (774581): Detected: BOINC client v7.16.11
2022-07-23 21:33:54 (774581): Detected: VirtualBox VboxManage Interface (Version: 6.1.26)
2022-07-23 21:33:54 (774581): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2022-07-23 21:33:55 (774581): Starting VM using VBoxManage interface. (boinc_724be4a5fdd21542, slot#14)
2022-07-23 21:33:57 (774581): Successfully started VM. (PID = '774688')
2022-07-23 21:33:57 (774581): Reporting VM Process ID to BOINC.
2022-07-23 21:33:57 (774581): Guest Log: BIOS: VirtualBox 6.1.25
2022-07-23 21:33:57 (774581): Guest Log: CPUID EDX: 0x178bfbff
2022-07-23 21:33:57 (774581): Guest Log: BIOS: No PCI IDE controller, not probing IDE
2022-07-23 21:33:57 (774581): Guest Log: BIOS: AHCI 0-P#0: PCHS=16383/16/63 LCHS=1024/255/63 0x0000000002800000 sectors
2022-07-23 21:33:57 (774581): VM state change detected. (old = 'poweredoff', new = 'running')
2022-07-23 21:33:57 (774581): Detected: Web Application Enabled (http://localhost:41611)
2022-07-23 21:33:57 (774581): Status Report: Job Duration: '64800.000000'
2022-07-23 21:33:57 (774581): Status Report: Elapsed Time: '14601.000000'
2022-07-23 21:33:57 (774581): Status Report: CPU Time: '23292.450000'
2022-07-23 21:33:57 (774581): Preference change detected
2022-07-23 21:33:57 (774581): Setting CPU throttle for VM. (100%)
2022-07-23 21:33:57 (774581): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2022-07-23 21:33:59 (774581): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032
2022-07-23 21:33:59 (774581): Guest Log: BIOS: Booting from Hard Disk...
2022-07-23 21:34:07 (774581): Guest Log: BIOS: KBD: unsupported int 16h function 03
2022-07-23 21:34:07 (774581): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 
2022-07-23 21:34:58 (774581): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds
2022-07-23 21:34:58 (774581): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000)
2022-07-23 21:35:02 (774581): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log
2022-07-23 21:35:02 (774581): Guest Log: 00:00:00.000141 main     Log opened 2022-07-23T19:35:01.879645000Z
2022-07-23 21:35:02 (774581): Guest Log: 00:00:00.000288 main     OS Product: Linux
2022-07-23 21:35:02 (774581): Guest Log: 00:00:00.000334 main     OS Release: 4.14.232-19.cernvm.x86_64
2022-07-23 21:35:02 (774581): Guest Log: 00:00:00.000369 main     OS Version: #1 SMP Fri Apr 30 17:12:25 CEST 2021
2022-07-23 21:35:02 (774581): Guest Log: 00:00:00.000402 main     Executable: /usr/sbin/VBoxService
2022-07-23 21:35:02 (774581): Guest Log: 00:00:00.000403 main     Process ID: 2704
2022-07-23 21:35:02 (774581): Guest Log: 00:00:00.000404 main     Package type: LINUX_64BITS_GENERIC
2022-07-23 21:35:02 (774581): Guest Log: 00:00:00.002329 main     5.2.6 r120293 started. Verbose level = 0
2022-07-23 21:35:21 (774581): Guest Log: [INFO] Mounting the shared directory
2022-07-23 21:35:22 (774581): Guest Log: [INFO] Shared directory mounted, enabling vboxmonitor
2022-07-23 21:35:22 (774581): Guest Log: [INFO] Sourcing essential functions from /cvmfs/grid.cern.ch
2022-07-23 21:35:22 (774581): Guest Log: [INFO] Testing connection to cern.ch
2022-07-23 21:35:22 (774581): Guest Log: [INFO] Testing connection to VCCS
2022-07-23 21:35:23 (774581): Guest Log: [INFO] Testing connection to HTCondor
2022-07-23 21:35:23 (774581): Guest Log: [INFO] Testing connection to WMAgent
2022-07-23 21:35:23 (774581): Guest Log: [INFO] Testing connection to EOSCMS
2022-07-23 21:35:23 (774581): Guest Log: [INFO] Testing connection to CMS-Frontier
2022-07-23 21:35:24 (774581): Guest Log: [INFO] Testing connection to Frontier
2022-07-23 21:35:24 (774581): Guest Log: [INFO] Could not find a local HTTP proxy
2022-07-23 21:35:24 (774581): Guest Log: [INFO] CVMFS and Frontier will have to use DIRECT connections
2022-07-23 21:35:25 (774581): Guest Log: [INFO] This makes the application less efficient
2022-07-23 21:35:25 (774581): Guest Log: [INFO] It also puts higher load on the project servers
2022-07-23 21:35:25 (774581): Guest Log: [INFO] Setting up a local HTTP proxy is highly recommended
2022-07-23 21:35:25 (774581): Guest Log: [INFO] Advice can be found in the project forum
2022-07-23 21:35:25 (774581): Guest Log: [INFO] Reloading and probing the CVMFS configuration
2022-07-23 21:35:36 (774581): Guest Log: [INFO] Probing /cvmfs/cvmfs-config.cern.ch... OK
2022-07-23 21:35:37 (774581): Guest Log: [INFO] Probing /cvmfs/grid.cern.ch... OK
2022-07-23 21:35:40 (774581): Guest Log: [INFO] Probing /cvmfs/cms-ib.cern.ch... OK
2022-07-23 21:35:41 (774581): Guest Log: [INFO] Probing /cvmfs/singularity.opensciencegrid.org... OK
2022-07-23 21:35:41 (774581): Guest Log: [INFO] Probing /cvmfs/oasis.opensciencegrid.org... OK
2022-07-23 21:35:41 (774581): Guest Log: [INFO] Probing /cvmfs/cms.cern.ch... OK
2022-07-23 21:35:43 (774581): Guest Log: [INFO] Excerpt from "cvmfs_config stat": VERSION HOST PROXY
2022-07-23 21:35:43 (774581): Guest Log: [INFO] 2.7.2.0 http://s1asgc-cvmfs.openhtc.io:8080 DIRECT
2022-07-23 21:35:43 (774581): Guest Log: [INFO] Reading volunteer information
2022-07-23 21:35:53 (774581): Guest Log: [INFO] Requesting an X509 credential from LHC@home
2022-07-23 21:35:54 (774581): Guest Log: [INFO] Requesting an X509 credential from vLHC@home-dev
2022-07-23 21:35:56 (774581): Guest Log: [INFO] CMS application starting. Check log files.
2022-07-23 22:34:27 (774581): Stopping VM.
2022-07-23 22:35:20 (774581): Successfully stopped VM.
2022-07-23 23:14:33 (829860): Detected: vboxwrapper 26204
2022-07-23 23:14:33 (829860): Detected: BOINC client v7.16.11
2022-07-23 23:14:34 (829860): Detected: VirtualBox VboxManage Interface (Version: 6.1.26)
2022-07-23 23:14:34 (829860): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2022-07-23 23:14:35 (829860): Guest Log: BIOS: VirtualBox 6.1.25
2022-07-23 23:14:35 (829860): Guest Log: CPUID EDX: 0x178bfbff
2022-07-23 23:14:35 (829860): Guest Log: BIOS: No PCI IDE controller, not probing IDE
2022-07-23 23:14:35 (829860): Guest Log: BIOS: AHCI 0-P#0: PCHS=16383/16/63 LCHS=1024/255/63 0x0000000002800000 sectors
2022-07-23 23:14:35 (829860): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032
2022-07-23 23:14:35 (829860): Guest Log: BIOS: Booting from Hard Disk...
2022-07-23 23:14:35 (829860): Guest Log: BIOS: KBD: unsupported int 16h function 03
2022-07-23 23:14:35 (829860): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 
2022-07-23 23:14:35 (829860): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds
2022-07-23 23:14:35 (829860): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000)
2022-07-23 23:14:35 (829860): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log
2022-07-23 23:14:35 (829860): Guest Log: 00:00:00.000141 main     Log opened 2022-07-23T19:35:01.879645000Z
2022-07-23 23:14:35 (829860): Guest Log: 00:00:00.000288 main     OS Product: Linux
2022-07-23 23:14:35 (829860): Guest Log: 00:00:00.000334 main     OS Release: 4.14.232-19.cernvm.x86_64
2022-07-23 23:14:35 (829860): Guest Log: 00:00:00.000369 main     OS Version: #1 SMP Fri Apr 30 17:12:25 CEST 2021
2022-07-23 23:14:35 (829860): Guest Log: 00:00:00.000402 main     Executable: /usr/sbin/VBoxService
2022-07-23 23:14:35 (829860): Guest Log: 00:00:00.000403 main     Process ID: 2704
2022-07-23 23:14:35 (829860): Guest Log: 00:00:00.000404 main     Package type: LINUX_64BITS_GENERIC
2022-07-23 23:14:35 (829860): Guest Log: 00:00:00.002329 main     5.2.6 r120293 started. Verbose level = 0
2022-07-23 23:14:35 (829860): Guest Log: [INFO] Mounting the shared directory
2022-07-23 23:14:35 (829860): Guest Log: [INFO] Shared directory mounted, enabling vboxmonitor
2022-07-23 23:14:35 (829860): Guest Log: [INFO] Sourcing essential functions from /cvmfs/grid.cern.ch
2022-07-23 23:14:35 (829860): Guest Log: [INFO] Testing connection to cern.ch
2022-07-23 23:14:35 (829860): Guest Log: [INFO] Testing connection to VCCS
2022-07-23 23:14:35 (829860): Guest Log: [INFO] Testing connection to HTCondor
2022-07-23 23:14:35 (829860): Guest Log: [INFO] Testing connection to WMAgent
2022-07-23 23:14:35 (829860): Guest Log: [INFO] Testing connection to EOSCMS
2022-07-23 23:14:35 (829860): Guest Log: [INFO] Testing connection to CMS-Frontier
2022-07-23 23:14:35 (829860): Guest Log: [INFO] Testing connection to Frontier
2022-07-23 23:14:35 (829860): Guest Log: [INFO] Could not find a local HTTP proxy
2022-07-23 23:14:35 (829860): Guest Log: [INFO] CVMFS and Frontier will have to use DIRECT connections
2022-07-23 23:14:35 (829860): Guest Log: [INFO] This makes the application less efficient
2022-07-23 23:14:35 (829860): Guest Log: [INFO] It also puts higher load on the project servers
2022-07-23 23:14:35 (829860): Guest Log: [INFO] Setting up a local HTTP proxy is highly recommended
2022-07-23 23:14:35 (829860): Guest Log: [INFO] Advice can be found in the project forum
2022-07-23 23:14:35 (829860): Guest Log: [INFO] Reloading and probing the CVMFS configuration
2022-07-23 23:14:35 (829860): Guest Log: [INFO] Probing /cvmfs/cvmfs-config.cern.ch... OK
2022-07-23 23:14:35 (829860): Guest Log: [INFO] Probing /cvmfs/grid.cern.ch... OK
2022-07-23 23:14:35 (829860): Guest Log: [INFO] Probing /cvmfs/cms-ib.cern.ch... OK
2022-07-23 23:14:35 (829860): Guest Log: [INFO] Probing /cvmfs/singularity.opensciencegrid.org... OK
2022-07-23 23:14:35 (829860): Guest Log: [INFO] Probing /cvmfs/oasis.opensciencegrid.org... OK
2022-07-23 23:14:35 (829860): Guest Log: [INFO] Probing /cvmfs/cms.cern.ch... OK
2022-07-23 23:14:35 (829860): Guest Log: [INFO] Excerpt from "cvmfs_config stat": VERSION HOST PROXY
2022-07-23 23:14:35 (829860): Guest Log: [INFO] 2.7.2.0 http://s1asgc-cvmfs.openhtc.io:8080 DIRECT
2022-07-23 23:14:35 (829860): Guest Log: [INFO] Reading volunteer information
2022-07-23 23:14:35 (829860): Guest Log: [INFO] Requesting an X509 credential from LHC@home
2022-07-23 23:14:35 (829860): Guest Log: [INFO] Requesting an X509 credential from vLHC@home-dev
2022-07-23 23:14:35 (829860): Guest Log: [INFO] CMS application starting. Check log files.
2022-07-23 23:14:35 (829860): Starting VM using VBoxManage interface. (boinc_724be4a5fdd21542, slot#14)
2022-07-23 23:14:54 (829860): Successfully started VM. (PID = '829979')
2022-07-23 23:14:54 (829860): Reporting VM Process ID to BOINC.
2022-07-23 23:14:54 (829860): VM state change detected. (old = 'poweredoff', new = 'running')
2022-07-23 23:14:54 (829860): Detected: Web Application Enabled (http://localhost:41611)
2022-07-23 23:14:54 (829860): Status Report: Job Duration: '64800.000000'
2022-07-23 23:14:54 (829860): Status Report: Elapsed Time: '18267.000000'
2022-07-23 23:14:54 (829860): Status Report: CPU Time: '29206.130000'
2022-07-23 23:14:54 (829860): Preference change detected
2022-07-23 23:14:54 (829860): Setting CPU throttle for VM. (100%)
2022-07-23 23:14:54 (829860): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2022-07-23 23:15:00 (829860): Guest Log: 00:59:32.714996 timesync vgsvcTimeSyncWorker: Radical host time change: 2 435 423 000 000ns (HostNow=1 658 610 899 955 000 000 ns HostLast=1 658 608 464 532 000 000 ns)
2022-07-23 23:15:10 (829860): Guest Log: 00:59:42.732821 timesync vgsvcTimeSyncWorker: Radical guest time change: 2 435 380 062 000ns (GuestNow=1 658 610 909 973 664 000 ns GuestLast=1 658 608 474 593 602 000 ns fSetTimeLastLoop=true )
2022-07-24 00:15:06 (829860): Stopping VM.
2022-07-24 00:15:59 (829860): Successfully stopped VM.
2022-07-24 00:22:57 (862155): Detected: vboxwrapper 26204
2022-07-24 00:22:57 (862155): Detected: BOINC client v7.16.11
2022-07-24 00:22:58 (862155): Detected: VirtualBox VboxManage Interface (Version: 6.1.26)
2022-07-24 00:22:58 (862155): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2022-07-24 00:23:00 (862155): Guest Log: 00:59:32.714996 timesync vgsvcTimeSyncWorker: Radical host time change: 2 435 423 000 000ns (HostNow=1 658 610 899 955 000 000 ns HostLast=1 658 608 464 532 000 000 ns)
2022-07-24 00:23:00 (862155): Guest Log: 00:59:42.732821 timesync vgsvcTimeSyncWorker: Radical guest time change: 2 435 380 062 000ns (GuestNow=1 658 610 909 973 664 000 ns GuestLast=1 658 608 474 593 602 000 ns fSetTimeLastLoop=true )
2022-07-24 00:23:00 (862155): Starting VM using VBoxManage interface. (boinc_724be4a5fdd21542, slot#14)
2022-07-24 00:23:28 (862155): Successfully started VM. (PID = '862296')
2022-07-24 00:23:28 (862155): Reporting VM Process ID to BOINC.
2022-07-24 00:23:28 (862155): VM state change detected. (old = 'poweredoff', new = 'running')
2022-07-24 00:23:28 (862155): Detected: Web Application Enabled (http://localhost:41611)
2022-07-24 00:23:28 (862155): Status Report: Job Duration: '64800.000000'
2022-07-24 00:23:28 (862155): Status Report: Elapsed Time: '21915.000000'
2022-07-24 00:23:28 (862155): Status Report: CPU Time: '34603.720000'
2022-07-24 00:23:28 (862155): Preference change detected
2022-07-24 00:23:28 (862155): Setting CPU throttle for VM. (100%)
2022-07-24 00:23:28 (862155): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2022-07-24 01:23:24 (862155): Stopping VM.
2022-07-24 01:39:53 (898258): Detected: vboxwrapper 26204
2022-07-24 01:39:53 (898258): Detected: BOINC client v7.16.11
2022-07-24 01:39:55 (898258): Detected: VirtualBox VboxManage Interface (Version: 6.1.26)
2022-07-24 01:39:55 (898258): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2022-07-24 01:39:55 (898258): Starting VM using VBoxManage interface. (boinc_724be4a5fdd21542, slot#14)
2022-07-24 01:40:16 (898258): Successfully started VM. (PID = '898366')
2022-07-24 01:40:16 (898258): Reporting VM Process ID to BOINC.
2022-07-24 01:40:16 (898258): VM state change detected. (old = 'poweredoff', new = 'running')
2022-07-24 01:40:16 (898258): Detected: Web Application Enabled (http://localhost:41611)
2022-07-24 01:40:16 (898258): Status Report: Job Duration: '64800.000000'
2022-07-24 01:40:16 (898258): Status Report: Elapsed Time: '25547.000000'
2022-07-24 01:40:16 (898258): Status Report: CPU Time: '39338.370000'
2022-07-24 01:40:16 (898258): Preference change detected
2022-07-24 01:40:16 (898258): Setting CPU throttle for VM. (100%)
2022-07-24 01:40:16 (898258): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2022-07-24 03:19:17 (898258): Status Report: Job Duration: '64800.000000'
2022-07-24 03:19:17 (898258): Status Report: Elapsed Time: '31547.000000'
2022-07-24 03:19:17 (898258): Status Report: CPU Time: '47817.920000'
2022-07-24 04:58:17 (898258): Status Report: Job Duration: '64800.000000'
2022-07-24 04:58:17 (898258): Status Report: Elapsed Time: '37547.000000'
2022-07-24 04:58:17 (898258): Status Report: CPU Time: '55748.570000'
2022-07-24 06:37:18 (898258): Status Report: Job Duration: '64800.000000'
2022-07-24 06:37:18 (898258): Status Report: Elapsed Time: '43547.000000'
2022-07-24 06:37:18 (898258): Status Report: CPU Time: '64340.110000'
2022-07-24 08:16:18 (898258): Status Report: Job Duration: '64800.000000'
2022-07-24 08:16:18 (898258): Status Report: Elapsed Time: '49547.000000'
2022-07-24 08:16:18 (898258): Status Report: CPU Time: '72151.840000'
2022-07-24 09:12:59 (898258): Stopping VM.
2022-07-24 09:13:40 (898258): Successfully stopped VM.
2022-07-24 09:23:02 (1108290): Detected: vboxwrapper 26204
2022-07-24 09:23:02 (1108290): Detected: BOINC client v7.16.11
2022-07-24 09:23:02 (1108290): Detected: VirtualBox VboxManage Interface (Version: 6.1.26)
2022-07-24 09:23:02 (1108290): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2022-07-24 09:23:02 (1108290): Starting VM using VBoxManage interface. (boinc_724be4a5fdd21542, slot#14)
2022-07-24 09:23:18 (1108290): Successfully started VM. (PID = '1108388')
2022-07-24 09:23:18 (1108290): Reporting VM Process ID to BOINC.
2022-07-24 09:23:18 (1108290): VM state change detected. (old = 'poweredoff', new = 'running')
2022-07-24 09:23:18 (1108290): Detected: Web Application Enabled (http://localhost:41611)
2022-07-24 09:23:18 (1108290): Status Report: Job Duration: '64800.000000'
2022-07-24 09:23:18 (1108290): Status Report: Elapsed Time: '52982.000000'
2022-07-24 09:23:18 (1108290): Status Report: CPU Time: '76601.600000'
2022-07-24 09:23:18 (1108290): Preference change detected
2022-07-24 09:23:18 (1108290): Setting CPU throttle for VM. (100%)
2022-07-24 09:23:18 (1108290): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2022-07-24 09:23:59 (1108290): Guest Log: [INFO] glidein exited with return value 0.
2022-07-24 09:23:59 (1108290): Guest Log: [INFO] Shutting Down.
2022-07-24 09:23:59 (1108290): VM Completion File Detected.
2022-07-24 09:23:59 (1108290): VM Completion Message: glidein exited with return value 0.
.
2022-07-24 09:23:59 (1108290): Powering off VM.
2022-07-24 09:24:00 (1108290): Successfully stopped VM.
2022-07-24 09:24:00 (1108290): Deregistering VM. (boinc_724be4a5fdd21542, slot#14)
2022-07-24 09:24:00 (1108290): Removing network bandwidth throttle group from VM.
2022-07-24 09:24:00 (1108290): Removing VM from VirtualBox.
09:24:05 (1108290): called boinc_finish(0)

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

Stderr output
