Name CMS_3322069_1627849303.829393_0
Workunit 2115594
Created 1 Aug 2021, 20:21:46 UTC
Sent 2 Aug 2021, 3:39:56 UTC
Report deadline 9 Aug 2021, 3:39:56 UTC
Received 3 Aug 2021, 8:59:55 UTC
Server state Over
Outcome Success
Client state Done
Exit status 0 (0x00000000)
Computer ID 4458
Run time 12 hours 42 min 28 sec
CPU time 1 days 1 hours 41 min 21 sec
Validate state Valid
Credit 477.99
Device peak FLOPS 38.54 GFLOPS
Application version CMS Simulation v60.50 (vbox64_mt_mcore_cms)
x86_64-pc-linux-gnu
Peak working set size 3.67 GB
Peak swap size 7.46 GB
Peak disk usage 3.89 GB

Stderr output

<core_client_version>7.16.6</core_client_version>
<![CDATA[
<stderr_txt>
2021-08-02 15:59:00 (2766208): vboxwrapper (7.7.26196): starting
2021-08-02 15:59:00 (2766208): Feature: Checkpoint interval offset (433 seconds)
2021-08-02 15:59:00 (2766208): Detected: VirtualBox VboxManage Interface (Version: 6.1.26)
2021-08-02 15:59:00 (2766208): Detected: Minimum checkpoint interval (600.000000 seconds)
2021-08-02 15:59:00 (2766208): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2021-08-02 15:59:00 (2766208): Successfully copied 'init_data.xml' to the shared directory.
2021-08-02 15:59:00 (2766208): Create VM. (boinc_4a3597040a99b50d, slot#20)
2021-08-02 15:59:00 (2766208): Setting Memory Size for VM. (8168MB)
2021-08-02 15:59:01 (2766208): Setting CPU Count for VM. (8)
2021-08-02 15:59:01 (2766208): Setting Chipset Options for VM.
2021-08-02 15:59:01 (2766208): Setting Boot Options for VM.
2021-08-02 15:59:01 (2766208): Setting Network Configuration for NAT.
2021-08-02 15:59:01 (2766208): Enabling VM Network Access.
2021-08-02 15:59:01 (2766208): Disabling USB Support for VM.
2021-08-02 15:59:01 (2766208): Disabling COM Port Support for VM.
2021-08-02 15:59:01 (2766208): Disabling LPT Port Support for VM.
2021-08-02 15:59:01 (2766208): Disabling Audio Support for VM.
2021-08-02 15:59:01 (2766208): Disabling Clipboard Support for VM.
2021-08-02 15:59:01 (2766208): Disabling Drag and Drop Support for VM.
2021-08-02 15:59:02 (2766208): Adding storage controller(s) to VM.
2021-08-02 15:59:02 (2766208): Adding virtual disk drive to VM. (vm_image.vdi)
2021-08-02 15:59:02 (2766208): Adding VirtualBox Guest Additions to VM.
2021-08-02 15:59:02 (2766208): Adding network bandwidth throttle group to VM. (Defaulting to 1024GB)
2021-08-02 15:59:02 (2766208): forwarding host port 45179 to guest port 80
2021-08-02 15:59:02 (2766208): Enabling remote desktop for VM.
2021-08-02 15:59:02 (2766208): Enabling shared directory for VM.
2021-08-02 15:59:02 (2766208): Starting VM. (boinc_4a3597040a99b50d, slot#20)
2021-08-02 15:59:04 (2766208): Successfully started VM. (PID = '2766958')
2021-08-02 15:59:04 (2766208): Reporting VM Process ID to BOINC.
2021-08-02 15:59:04 (2766208): Guest Log: BIOS: VirtualBox 6.1.26
2021-08-02 15:59:04 (2766208): Guest Log: CPUID EDX: 0x178bfbff
2021-08-02 15:59:04 (2766208): Guest Log: BIOS: ata0-0: PCHS=16383/16/63 LCHS=1024/255/63
2021-08-02 15:59:04 (2766208): VM state change detected. (old = 'poweroff', new = 'running')
2021-08-02 15:59:04 (2766208): Detected: Web Application Enabled (http://localhost:45179)
2021-08-02 15:59:04 (2766208): Detected: Remote Desktop Enabled (localhost:51203)
2021-08-02 15:59:04 (2766208): Preference change detected
2021-08-02 15:59:04 (2766208): Setting CPU throttle for VM. (100%)
2021-08-02 15:59:05 (2766208): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 300 seconds) or (Vbox_job.xml: 600 seconds))
2021-08-02 15:59:06 (2766208): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032
2021-08-02 15:59:06 (2766208): Guest Log: BIOS: Booting from Hard Disk...
2021-08-02 15:59:08 (2766208): Guest Log: BIOS: KBD: unsupported int 16h function 03
2021-08-02 15:59:08 (2766208): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 
2021-08-02 16:00:22 (2766208): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds
2021-08-02 16:00:23 (2766208): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000)
2021-08-02 16:00:55 (2766208): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log
2021-08-02 16:00:55 (2766208): Guest Log: 00:00:00.060721 main     5.2.6 r120293 started. Verbose level = 0
2021-08-02 16:03:41 (2766208): Guest Log: [INFO] Mounting the shared directory
2021-08-02 16:03:43 (2766208): Guest Log: [INFO] Shared directory mounted, enabling vboxmonitor
2021-08-02 16:03:44 (2766208): Guest Log: [INFO] Testing connection to cern.ch
2021-08-02 16:03:45 (2766208): Guest Log: [INFO] Testing connection to VCCS
2021-08-02 16:03:47 (2766208): Guest Log: [INFO] Testing connection to HTCondor
2021-08-02 16:03:49 (2766208): Guest Log: [INFO] Testing connection to WMAgent
2021-08-02 16:03:50 (2766208): Guest Log: [INFO] Testing connection to Frontier
2021-08-02 16:03:59 (2766208): Guest Log: [INFO] Could not find a local HTTP proxy.
2021-08-02 16:04:00 (2766208): Guest Log: [INFO] CVMFS and Frontier will have to use DIRECT connections.
2021-08-02 16:04:01 (2766208): Guest Log: [INFO] This makes the application less efficient.
2021-08-02 16:04:05 (2766208): Guest Log: [INFO] It also puts higher load on the project servers.
2021-08-02 16:04:06 (2766208): Guest Log: [INFO] Setting up a local HTTP proxy is highly recommended.
2021-08-02 16:04:07 (2766208): Guest Log: [INFO] Advice can be found in the project forum.
2021-08-02 16:04:08 (2766208): Guest Log: [INFO] Reloading the CVMFS configuration (can take a while) ...
2021-08-02 16:04:19 (2766208): Guest Log: [INFO] Probing CVMFS ...
2021-08-02 16:04:22 (2766208): Guest Log: Probing /cvmfs/cvmfs-config.cern.ch... OK
2021-08-02 16:04:24 (2766208): Guest Log: Probing /cvmfs/grid.cern.ch... OK
2021-08-02 16:04:29 (2766208): Guest Log: Probing /cvmfs/cms.cern.ch... OK
2021-08-02 16:04:33 (2766208): Guest Log: Probing /cvmfs/cms-ib.cern.ch... OK
2021-08-02 16:04:37 (2766208): Guest Log: Probing /cvmfs/oasis.opensciencegrid.org... OK
2021-08-02 16:04:41 (2766208): Guest Log: Probing /cvmfs/singularity.opensciencegrid.org... OK
2021-08-02 16:04:44 (2766208): Guest Log: VERSION PID UPTIME(M) MEM(K) REVISION EXPIRES(M) NOCATALOGS CACHEUSE(K) CACHEMAX(K) NOFDUSE NOFDMAX NOIOERR NOOPEN HITRATE(%) RX(K) SPEED(K/S) HOST PROXY ONLINE
2021-08-02 16:04:44 (2766208): Guest Log: 2.7.2.0 3294 1 28252 15869 3 1 2080019 10240000 2 65024 0 8 100 0 0 http://s1fnal-cvmfs.openhtc.io/cvmfs/grid.cern.ch DIRECT 1
2021-08-02 16:04:50 (2766208): Guest Log: [INFO] Reading volunteer information
2021-08-02 16:04:59 (2766208): Guest Log: [INFO] Requesting an X509 credential from LHC@home
2021-08-02 16:05:03 (2766208): Guest Log: [INFO] Requesting an X509 credential from vLHC@home-dev
2021-08-02 16:05:16 (2766208): Guest Log: [INFO] CMS application starting. Check log files.
2021-08-02 17:38:06 (2766208): Status Report: Job Duration: '64800.000000'
2021-08-02 17:38:06 (2766208): Status Report: Elapsed Time: '6000.000000'
2021-08-02 17:38:06 (2766208): Status Report: CPU Time: '18392.050000'
2021-08-02 18:33:33 (2766208): Stopping VM.
2021-08-02 18:33:54 (2766208): Successfully stopped VM.
2021-08-02 18:38:36 (3792823): vboxwrapper (7.7.26196): starting
2021-08-02 18:38:36 (3792823): Feature: Checkpoint interval offset (427 seconds)
2021-08-02 18:38:36 (3792823): Detected: VirtualBox VboxManage Interface (Version: 6.1.26)
2021-08-02 18:38:36 (3792823): Detected: Minimum checkpoint interval (600.000000 seconds)
2021-08-02 18:38:36 (3792823): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2021-08-02 18:38:37 (3792823): Starting VM. (boinc_4a3597040a99b50d, slot#20)
2021-08-02 18:38:50 (3792823): Successfully started VM. (PID = '3792982')
2021-08-02 18:38:50 (3792823): Reporting VM Process ID to BOINC.
2021-08-02 18:38:51 (3792823): VM state change detected. (old = 'poweroff', new = 'running')
2021-08-02 18:38:51 (3792823): Detected: Web Application Enabled (http://localhost:45179)
2021-08-02 18:38:51 (3792823): Detected: Remote Desktop Enabled (localhost:51203)
2021-08-02 18:38:51 (3792823): Status Report: Job Duration: '64800.000000'
2021-08-02 18:38:51 (3792823): Status Report: Elapsed Time: '9360.000000'
2021-08-02 18:38:51 (3792823): Status Report: CPU Time: '29344.260000'
2021-08-02 18:38:51 (3792823): Preference change detected
2021-08-02 18:38:51 (3792823): Setting CPU throttle for VM. (100%)
2021-08-02 18:38:51 (3792823): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 300 seconds) or (Vbox_job.xml: 600 seconds))
2021-08-02 20:17:52 (3792823): Status Report: Job Duration: '64800.000000'
2021-08-02 20:17:52 (3792823): Status Report: Elapsed Time: '15360.000000'
2021-08-02 20:17:52 (3792823): Status Report: CPU Time: '48858.020000'
2021-08-02 20:34:42 (3792823): Stopping VM.
2021-08-02 20:35:03 (3792823): Successfully stopped VM.
2021-08-02 20:43:10 (337812): vboxwrapper (7.7.26196): starting
2021-08-02 20:43:10 (337812): Feature: Checkpoint interval offset (30 seconds)
2021-08-02 20:43:10 (337812): Detected: VirtualBox VboxManage Interface (Version: 6.1.26)
2021-08-02 20:43:10 (337812): Detected: Minimum checkpoint interval (600.000000 seconds)
2021-08-02 20:43:10 (337812): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2021-08-02 20:43:10 (337812): Starting VM. (boinc_4a3597040a99b50d, slot#20)
2021-08-02 20:43:24 (337812): Successfully started VM. (PID = '337938')
2021-08-02 20:43:24 (337812): Reporting VM Process ID to BOINC.
2021-08-02 20:43:24 (337812): VM state change detected. (old = 'poweroff', new = 'running')
2021-08-02 20:43:24 (337812): Detected: Web Application Enabled (http://localhost:45179)
2021-08-02 20:43:24 (337812): Detected: Remote Desktop Enabled (localhost:51203)
2021-08-02 20:43:25 (337812): Status Report: Job Duration: '64800.000000'
2021-08-02 20:43:25 (337812): Status Report: Elapsed Time: '16380.000000'
2021-08-02 20:43:25 (337812): Status Report: CPU Time: '52078.740000'
2021-08-02 20:43:25 (337812): Preference change detected
2021-08-02 20:43:25 (337812): Setting CPU throttle for VM. (100%)
2021-08-02 20:43:25 (337812): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 300 seconds) or (Vbox_job.xml: 600 seconds))
2021-08-02 22:22:27 (337812): Status Report: Job Duration: '64800.000000'
2021-08-02 22:22:27 (337812): Status Report: Elapsed Time: '22380.000000'
2021-08-02 22:22:27 (337812): Status Report: CPU Time: '61947.430000'
2021-08-03 00:01:29 (337812): Status Report: Job Duration: '64800.000000'
2021-08-03 00:01:29 (337812): Status Report: Elapsed Time: '28380.000000'
2021-08-03 00:01:29 (337812): Status Report: CPU Time: '70025.020000'
2021-08-03 01:40:32 (337812): Status Report: Job Duration: '64800.000000'
2021-08-03 01:40:32 (337812): Status Report: Elapsed Time: '34380.000000'
2021-08-03 01:40:32 (337812): Status Report: CPU Time: '77970.410000'
2021-08-03 03:19:34 (337812): Status Report: Job Duration: '64800.000000'
2021-08-03 03:19:34 (337812): Status Report: Elapsed Time: '40380.414680'
2021-08-03 03:19:34 (337812): Status Report: CPU Time: '86127.880000'
2021-08-03 04:58:36 (337812): Status Report: Job Duration: '64800.000000'
2021-08-03 04:58:36 (337812): Status Report: Elapsed Time: '46380.414680'
2021-08-03 04:58:36 (337812): Status Report: CPU Time: '92472.360000'
2021-08-03 04:59:44 (337812): Guest Log: [INFO] glidein exited with return value 0.
2021-08-03 04:59:44 (337812): Guest Log: [INFO] Shutting Down.
2021-08-03 04:59:44 (337812): VM Completion File Detected.
2021-08-03 04:59:44 (337812): VM Completion Message: glidein exited with return value 0.
.
2021-08-03 04:59:44 (337812): Powering off VM.
2021-08-03 04:59:45 (337812): Successfully stopped VM.
2021-08-03 04:59:45 (337812): Deregistering VM. (boinc_4a3597040a99b50d, slot#20)
2021-08-03 04:59:45 (337812): Removing network bandwidth throttle group from VM.
2021-08-03 04:59:45 (337812): Removing storage controller(s) from VM.
2021-08-03 04:59:45 (337812): Removing VM from VirtualBox.
2021-08-03 04:59:46 (337812): Removing virtual disk drive from VirtualBox.
04:59:51 (337812): called boinc_finish(0)

</stderr_txt>
]]>


©2024 CERN