Name CMS_3883110_1567160801.751928_0
Workunit 1927472
Created 30 Aug 2019, 10:26:43 UTC
Sent 31 Aug 2019, 3:18:16 UTC
Report deadline 7 Sep 2019, 3:18:16 UTC
Received 4 Sep 2019, 3:28:39 UTC
Server state Over
Outcome Success
Client state Done
Exit status 0 (0x00000000)
Computer ID 1031
Run time 17 hours 31 min 56 sec
CPU time 14 hours 56 min 54 sec
Validate state Valid
Credit 2,400.97
Device peak FLOPS 5.24 GFLOPS
Application version CMS Simulation v49.00 (vbox64_mt_mcore_cms)
x86_64-pc-linux-gnu
Peak working set size 2.84 GB
Peak swap size 4.42 GB
Peak disk usage 3.53 GB

Stderr output

<core_client_version>7.4.25</core_client_version>
<![CDATA[
<stderr_txt>
2019-08-31 04:19:35 (18937): vboxwrapper (7.7.26196): starting
2019-08-31 04:19:35 (18937): Feature: Checkpoint interval offset (292 seconds)
2019-08-31 04:19:35 (18937): Detected: VirtualBox VboxManage Interface (Version: 5.2.8)
2019-08-31 04:19:35 (18937): Detected: Minimum checkpoint interval (600.000000 seconds)
2019-08-31 04:19:35 (18937): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2019-08-31 04:19:35 (18937): Successfully copied 'init_data.xml' to the shared directory.
2019-08-31 04:19:35 (18937): Create VM. (boinc_cbb0746839bf963f, slot#0)
2019-08-31 04:19:35 (18937): Setting Memory Size for VM. (2792MB)
2019-08-31 04:19:36 (18937): Setting CPU Count for VM. (1)
2019-08-31 04:19:36 (18937): Setting Chipset Options for VM.
2019-08-31 04:19:36 (18937): Setting Boot Options for VM.
2019-08-31 04:19:36 (18937): Setting Network Configuration for NAT.
2019-08-31 04:19:36 (18937): Enabling VM Network Access.
2019-08-31 04:19:36 (18937): Disabling USB Support for VM.
2019-08-31 04:19:36 (18937): Disabling COM Port Support for VM.
2019-08-31 04:19:36 (18937): Disabling LPT Port Support for VM.
2019-08-31 04:19:36 (18937): Disabling Audio Support for VM.
2019-08-31 04:19:37 (18937): Disabling Clipboard Support for VM.
2019-08-31 04:19:37 (18937): Disabling Drag and Drop Support for VM.
2019-08-31 04:19:37 (18937): Adding storage controller(s) to VM.
2019-08-31 04:19:37 (18937): Adding virtual disk drive to VM. (vm_image.vdi)
2019-08-31 04:19:37 (18937): Adding VirtualBox Guest Additions to VM.
2019-08-31 04:19:37 (18937): Adding network bandwidth throttle group to VM. (Defaulting to 1024GB)
2019-08-31 04:19:37 (18937): forwarding host port 40464 to guest port 80
2019-08-31 04:19:37 (18937): Enabling remote desktop for VM.
2019-08-31 04:19:37 (18937): Enabling shared directory for VM.
2019-08-31 04:19:38 (18937): Starting VM. (boinc_cbb0746839bf963f, slot#0)
2019-08-31 04:19:42 (18937): Successfully started VM. (PID = '19449')
2019-08-31 04:19:42 (18937): Reporting VM Process ID to BOINC.
2019-08-31 04:19:43 (18937): Guest Log: BIOS: VirtualBox 5.2.8
2019-08-31 04:19:43 (18937): Guest Log: CPUID EDX: 0x078bfbff
2019-08-31 04:19:43 (18937): Guest Log: BIOS: ata0-0: PCHS=16383/16/63 LCHS=1024/255/63
2019-08-31 04:19:43 (18937): VM state change detected. (old = 'poweroff', new = 'running')
2019-08-31 04:19:43 (18937): Detected: Web Application Enabled (http://localhost:40464)
2019-08-31 04:19:43 (18937): Detected: Remote Desktop Enabled (localhost:32999)
2019-08-31 04:19:43 (18937): Preference change detected
2019-08-31 04:19:43 (18937): Setting CPU throttle for VM. (100%)
2019-08-31 04:19:43 (18937): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 600 seconds) or (Vbox_job.xml: 600 seconds))
2019-08-31 04:19:45 (18937): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032
2019-08-31 04:19:45 (18937): Guest Log: BIOS: Booting from Hard Disk...
2019-08-31 04:19:47 (18937): Guest Log: BIOS: KBD: unsupported int 16h function 03
2019-08-31 04:19:47 (18937): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 
2019-08-31 04:20:15 (18937): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds
2019-08-31 04:20:15 (18937): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000)
2019-08-31 04:21:08 (18937): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log
2019-08-31 04:21:08 (18937): Guest Log: 00:00:00.001408 main     Log opened 2019-08-31T03:21:07.354404000Z
2019-08-31 04:21:08 (18937): Guest Log: 00:00:00.002806 main     OS Product: Linux
2019-08-31 04:21:08 (18937): Guest Log: 00:00:00.002996 main     OS Release: 4.14.44-11.cernvm.x86_64
2019-08-31 04:21:08 (18937): Guest Log: 00:00:00.003161 main     OS Version: #1 SMP Mon May 28 16:49:00 CEST 2018
2019-08-31 04:21:08 (18937): Guest Log: 00:00:00.003223 main     Executable: /usr/share/vboxguest52/usr/sbin/VBoxService
2019-08-31 04:21:08 (18937): Guest Log: 00:00:00.003224 main     Process ID: 2970
2019-08-31 04:21:08 (18937): Guest Log: 00:00:00.003225 main     Package type: LINUX_64BITS_GENERIC
2019-08-31 04:21:08 (18937): Guest Log: 00:00:00.005259 main     5.2.6 r120293 started. Verbose level = 0
2019-08-31 04:22:57 (18937): Guest Log: [INFO] Mounting the shared directory
2019-08-31 04:22:57 (18937): Guest Log: [INFO] Shared directory mounted, enabling vboxmonitor
2019-08-31 04:22:57 (18937): Guest Log: [DEBUG] Testing network connection to cern.ch on port 80
2019-08-31 04:22:57 (18937): Guest Log: [DEBUG] Connection to cern.ch 80 port [tcp/http] succeeded!
2019-08-31 04:22:57 (18937): Guest Log: [DEBUG] 0
2019-08-31 04:22:57 (18937): Guest Log: [DEBUG] Testing VCCS connection to vccs.cern.ch on port 443
2019-08-31 04:22:58 (18937): Guest Log: [DEBUG] Connection to vccs.cern.ch 443 port [tcp/https] succeeded!
2019-08-31 04:22:58 (18937): Guest Log: [DEBUG] 0
2019-08-31 04:22:58 (18937): Guest Log: [DEBUG] Testing connection to Condor server on port 9618
2019-08-31 04:22:58 (18937): Guest Log: [DEBUG] Connection to vccondor01.cern.ch 9618 port [tcp/condor] succeeded!
2019-08-31 04:22:58 (18937): Guest Log: [DEBUG] 0
2019-08-31 04:22:59 (18937): Guest Log: [DEBUG] Detected local proxy http://192.168.100.137:3128 in init_data.xml
2019-08-31 04:22:59 (18937): Guest Log: [DEBUG] Testing connection to 192.168.100.137 on port 3128
2019-08-31 04:23:00 (18937): Guest Log: [DEBUG] Connection to 192.168.100.137 3128 port [tcp/squid] succeeded!
2019-08-31 04:23:00 (18937): Guest Log: [DEBUG] 0
2019-08-31 04:24:33 (18937): Guest Log: [DEBUG] Probing CVMFS ...
2019-08-31 04:24:57 (18937): Guest Log: Probing /cvmfs/grid.cern.ch... OK
2019-08-31 04:25:05 (18937): 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
2019-08-31 04:25:05 (18937): Guest Log: 2.4.4.0 3711 2 27924 10121 3 1 1454385 10240001 2 65024 0 3 100 0 0 http://s1ral-cvmfs.openhtc.io/cvmfs/grid.cern.ch http://192.168.100.137:3128 1
2019-08-31 04:25:11 (18937): Guest Log: [INFO] Reading volunteer information
2019-08-31 04:25:11 (18937): Guest Log: [INFO] Volunteer: m (178)
2019-08-31 04:25:11 (18937): Guest Log: [INFO] VMID: c5ab4400-4ef6-44a9-aaed-bee78432355d
2019-08-31 04:25:12 (18937): Guest Log: [INFO] Requesting an X509 credential from LHC@home
2019-08-31 04:25:13 (18937): Guest Log: [INFO] Requesting an X509 credential from vLHC@home-dev
2019-08-31 04:25:30 (18937): Guest Log: [INFO] Running the fast benchmark.
2019-08-31 04:26:30 (18937): Guest Log: [INFO] Machine performance 8.38 HEPSPEC06
2019-08-31 04:26:30 (18937): Guest Log: [INFO] CMS application starting. Check log files.
2019-08-31 04:26:33 (18937): Guest Log: [DEBUG] HTCondor ping
2019-08-31 04:26:35 (18937): Guest Log: [DEBUG] 0
2019-08-31 05:59:35 (18937): Status Report: Job Duration: '64800.000000'
2019-08-31 05:59:35 (18937): Status Report: Elapsed Time: '6000.914361'
2019-08-31 05:59:35 (18937): Status Report: CPU Time: '5256.810000'
2019-08-31 06:57:01 (18937): Stopping VM.
2019-09-01 01:09:25 (8383): vboxwrapper (7.7.26196): starting
2019-09-01 01:09:25 (8383): Feature: Checkpoint interval offset (156 seconds)
2019-09-01 01:09:25 (8383): Detected: VirtualBox VboxManage Interface (Version: 5.2.8)
2019-09-01 01:09:25 (8383): Detected: Minimum checkpoint interval (600.000000 seconds)
2019-09-01 01:09:25 (8383): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2019-09-01 01:09:25 (8383): Guest Log: 00:00:00.003223 main     Executable: /usr/share/vboxguest52/usr/sbin/VBoxService
2019-09-01 01:09:25 (8383): Guest Log: 00:00:00.003224 main     Process ID: 2970
2019-09-01 01:09:25 (8383): Guest Log: 00:00:00.003225 main     Package type: LINUX_64BITS_GENERIC
2019-09-01 01:09:25 (8383): Guest Log: 00:00:00.005259 main     5.2.6 r120293 started. Verbose level = 0
2019-09-01 01:09:25 (8383): Guest Log: [INFO] Mounting the shared directory
2019-09-01 01:09:25 (8383): Guest Log: [INFO] Shared directory mounted, enabling vboxmonitor
2019-09-01 01:09:25 (8383): Guest Log: [DEBUG] Testing network connection to cern.ch on port 80
2019-09-01 01:09:25 (8383): Guest Log: [DEBUG] Connection to cern.ch 80 port [tcp/http] succeeded!
2019-09-01 01:09:25 (8383): Guest Log: [DEBUG] 0
2019-09-01 01:09:25 (8383): Guest Log: [DEBUG] Testing VCCS connection to vccs.cern.ch on port 443
2019-09-01 01:09:25 (8383): Guest Log: [DEBUG] Connection to vccs.cern.ch 443 port [tcp/https] succeeded!
2019-09-01 01:09:25 (8383): Guest Log: [DEBUG] 0
2019-09-01 01:09:25 (8383): Guest Log: [DEBUG] Testing connection to Condor server on port 9618
2019-09-01 01:09:25 (8383): Guest Log: [DEBUG] Connection to vccondor01.cern.ch 9618 port [tcp/condor] succeeded!
2019-09-01 01:09:25 (8383): Guest Log: [DEBUG] 0
2019-09-01 01:09:25 (8383): Guest Log: [DEBUG] Detected local proxy http://192.168.100.137:3128 in init_data.xml
2019-09-01 01:09:25 (8383): Guest Log: [DEBUG] Testing connection to 192.168.100.137 on port 3128
2019-09-01 01:09:25 (8383): Guest Log: [DEBUG] Connection to 192.168.100.137 3128 port [tcp/squid] succeeded!
2019-09-01 01:09:25 (8383): Guest Log: [DEBUG] 0
2019-09-01 01:09:25 (8383): Guest Log: [DEBUG] Probing CVMFS ...
2019-09-01 01:09:25 (8383): Guest Log: Probing /cvmfs/grid.cern.ch... OK
2019-09-01 01:09:25 (8383): 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
2019-09-01 01:09:25 (8383): Guest Log: 2.4.4.0 3711 2 27924 10121 3 1 1454385 10240001 2 65024 0 3 100 0 0 http://s1ral-cvmfs.openhtc.io/cvmfs/grid.cern.ch http://192.168.100.137:3128 1
2019-09-01 01:09:25 (8383): Guest Log: [INFO] Reading volunteer information
2019-09-01 01:09:25 (8383): Guest Log: [INFO] Volunteer: m (178)
2019-09-01 01:09:25 (8383): Guest Log: [INFO] VMID: c5ab4400-4ef6-44a9-aaed-bee78432355d
2019-09-01 01:09:25 (8383): Guest Log: [INFO] Requesting an X509 credential from LHC@home
2019-09-01 01:09:25 (8383): Guest Log: [INFO] Requesting an X509 credential from vLHC@home-dev
2019-09-01 01:09:25 (8383): Guest Log: [INFO] Running the fast benchmark.
2019-09-01 01:09:25 (8383): Guest Log: [INFO] Machine performance 8.38 HEPSPEC06
2019-09-01 01:09:25 (8383): Guest Log: [INFO] CMS application starting. Check log files.
2019-09-01 01:09:25 (8383): Guest Log: [DEBUG] HTCondor ping
2019-09-01 01:09:25 (8383): Guest Log: [DEBUG] 0
2019-09-01 01:09:25 (8383): Starting VM. (boinc_cbb0746839bf963f, slot#0)
2019-09-01 01:09:26 (8383): Successfully started VM. (PID = '8472')
2019-09-01 01:09:26 (8383): Reporting VM Process ID to BOINC.
2019-09-01 01:09:27 (8383): VM state change detected. (old = 'poweroff', new = 'running')
2019-09-01 01:09:27 (8383): Detected: Web Application Enabled (http://localhost:40464)
2019-09-01 01:09:27 (8383): Detected: Remote Desktop Enabled (localhost:32999)
2019-09-01 01:09:27 (8383): Status Report: Job Duration: '64800.000000'
2019-09-01 01:09:27 (8383): Status Report: Elapsed Time: '9450.149791'
2019-09-01 01:09:27 (8383): Status Report: CPU Time: '8463.620000'
2019-09-01 01:09:27 (8383): Preference change detected
2019-09-01 01:09:27 (8383): Setting CPU throttle for VM. (100%)
2019-09-01 01:09:27 (8383): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 600 seconds) or (Vbox_job.xml: 600 seconds))
2019-09-01 01:26:11 (8383): Stopping VM.
2019-09-01 01:29:12 (5467): vboxwrapper (7.7.26196): starting
2019-09-01 01:29:12 (5467): Feature: Checkpoint interval offset (325 seconds)
2019-09-01 01:29:12 (5467): Detected: VirtualBox VboxManage Interface (Version: 5.2.8)
2019-09-01 01:29:13 (5467): Detected: Minimum checkpoint interval (600.000000 seconds)
2019-09-01 01:29:13 (5467): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2019-09-01 01:29:13 (5467): Guest Log: BIOS: VirtualBox 5.2.8
2019-09-01 01:29:13 (5467): Guest Log: CPUID EDX: 0x078bfbff
2019-09-01 01:29:13 (5467): Guest Log: BIOS: ata0-0: PCHS=16383/16/63 LCHS=1024/255/63
2019-09-01 01:29:13 (5467): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032
2019-09-01 01:29:13 (5467): Guest Log: BIOS: Booting from Hard Disk...
2019-09-01 01:29:13 (5467): Guest Log: BIOS: KBD: unsupported int 16h function 03
2019-09-01 01:29:13 (5467): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 
2019-09-01 01:29:13 (5467): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds
2019-09-01 01:29:13 (5467): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000)
2019-09-01 01:29:13 (5467): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log
2019-09-01 01:29:13 (5467): Guest Log: 00:00:00.001438 main     Log opened 2019-09-01T00:10:48.244636000Z
2019-09-01 01:29:13 (5467): Guest Log: 00:00:00.002824 main     OS Product: Linux
2019-09-01 01:29:13 (5467): Guest Log: 00:00:00.002994 main     OS Release: 4.14.44-11.cernvm.x86_64
2019-09-01 01:29:13 (5467): Guest Log: 00:00:00.003066 main     OS Version: #1 SMP Mon May 28 16:49:00 CEST 2018
2019-09-01 01:29:13 (5467): Guest Log: 00:00:00.003125 main     Executable: /usr/share/vboxguest52/usr/sbin/VBoxService
2019-09-01 01:29:13 (5467): Guest Log: 00:00:00.003126 main     Process ID: 2903
2019-09-01 01:29:13 (5467): Guest Log: 00:00:00.003126 main     Package type: LINUX_64BITS_GENERIC
2019-09-01 01:29:13 (5467): Guest Log: 00:00:00.005915 main     5.2.6 r120293 started. Verbose level = 0
2019-09-01 01:29:13 (5467): Guest Log: [INFO] Mounting the shared directory
2019-09-01 01:29:13 (5467): Guest Log: [INFO] Shared directory mounted, enabling vboxmonitor
2019-09-01 01:29:13 (5467): Guest Log: [DEBUG] Testing network connection to cern.ch on port 80
2019-09-01 01:29:13 (5467): Guest Log: [DEBUG] Connection to cern.ch 80 port [tcp/http] succeeded!
2019-09-01 01:29:13 (5467): Guest Log: [DEBUG] 0
2019-09-01 01:29:13 (5467): Guest Log: [DEBUG] Testing VCCS connection to vccs.cern.ch on port 443
2019-09-01 01:29:13 (5467): Guest Log: [DEBUG] Connection to vccs.cern.ch 443 port [tcp/https] succeeded!
2019-09-01 01:29:13 (5467): Guest Log: [DEBUG] 0
2019-09-01 01:29:13 (5467): Guest Log: [DEBUG] Testing connection to Condor server on port 9618
2019-09-01 01:29:13 (5467): Guest Log: [DEBUG] Connection to vccondor01.cern.ch 9618 port [tcp/condor] succeeded!
2019-09-01 01:29:13 (5467): Guest Log: [DEBUG] 0
2019-09-01 01:29:13 (5467): Guest Log: [DEBUG] Detected local proxy http://192.168.100.137:3128 in init_data.xml
2019-09-01 01:29:13 (5467): Guest Log: [DEBUG] Testing connection to 192.168.100.137 on port 3128
2019-09-01 01:29:13 (5467): Guest Log: [DEBUG] Connection to 192.168.100.137 3128 port [tcp/squid] succeeded!
2019-09-01 01:29:13 (5467): Guest Log: [DEBUG] 0
2019-09-01 01:29:13 (5467): Guest Log: [DEBUG] Probing CVMFS ...
2019-09-01 01:29:13 (5467): Guest Log: Probing /cvmfs/grid.cern.ch... OK
2019-09-01 01:29:13 (5467): 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
2019-09-01 01:29:13 (5467): Guest Log: 2.4.4.0 3624 2 27404 10127 3 1 1742626 10240000 2 65024 0 3 100 0 0 http://s1ral-cvmfs.openhtc.io/cvmfs/grid.cern.ch http://192.168.100.137:3128 1
2019-09-01 01:29:13 (5467): Guest Log: [INFO] Reading volunteer information
2019-09-01 01:29:13 (5467): Guest Log: [INFO] Volunteer: m (178)
2019-09-01 01:29:13 (5467): Guest Log: [INFO] VMID: c5ab4400-4ef6-44a9-aaed-bee78432355d
2019-09-01 01:29:13 (5467): Guest Log: [INFO] Requesting an X509 credential from LHC@home
2019-09-01 01:29:13 (5467): Guest Log: [INFO] Requesting an X509 credential from vLHC@home-dev
2019-09-01 01:29:13 (5467): Guest Log: [INFO] Running the fast benchmark.
2019-09-01 01:29:13 (5467): Guest Log: [INFO] Machine performance 8.67 HEPSPEC06
2019-09-01 01:29:13 (5467): Guest Log: [INFO] CMS application starting. Check log files.
2019-09-01 01:29:13 (5467): Guest Log: [DEBUG] HTCondor ping
2019-09-01 01:29:13 (5467): Guest Log: [DEBUG] 0
2019-09-01 01:29:13 (5467): Starting VM. (boinc_cbb0746839bf963f, slot#0)
2019-09-01 01:29:15 (5467): Successfully started VM. (PID = '5581')
2019-09-01 01:29:15 (5467): Reporting VM Process ID to BOINC.
2019-09-01 01:29:15 (5467): VM state change detected. (old = 'poweroff', new = 'running')
2019-09-01 01:29:15 (5467): Detected: Web Application Enabled (http://localhost:40464)
2019-09-01 01:29:15 (5467): Detected: Remote Desktop Enabled (localhost:32999)
2019-09-01 01:29:15 (5467): Status Report: Job Duration: '64800.000000'
2019-09-01 01:29:15 (5467): Status Report: Elapsed Time: '10458.209518'
2019-09-01 01:29:15 (5467): Status Report: CPU Time: '9143.060000'
2019-09-01 01:29:15 (5467): Preference change detected
2019-09-01 01:29:15 (5467): Setting CPU throttle for VM. (100%)
2019-09-01 01:29:15 (5467): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 600 seconds) or (Vbox_job.xml: 600 seconds))
2019-09-01 03:09:09 (5467): Status Report: Job Duration: '64800.000000'
2019-09-01 03:09:09 (5467): Status Report: Elapsed Time: '16459.177564'
2019-09-01 03:09:09 (5467): Status Report: CPU Time: '14582.900000'
2019-09-01 04:48:51 (5467): Status Report: Job Duration: '64800.000000'
2019-09-01 04:48:51 (5467): Status Report: Elapsed Time: '22459.343332'
2019-09-01 04:48:51 (5467): Status Report: CPU Time: '18984.290000'
2019-09-01 06:28:43 (5467): Status Report: Job Duration: '64800.000000'
2019-09-01 06:28:43 (5467): Status Report: Elapsed Time: '28460.573375'
2019-09-01 06:28:43 (5467): Status Report: CPU Time: '24219.160000'
2019-09-01 06:57:02 (5467): Stopping VM.
2019-09-02 01:09:24 (8275): vboxwrapper (7.7.26196): starting
2019-09-02 01:09:24 (8275): Feature: Checkpoint interval offset (274 seconds)
2019-09-02 01:09:24 (8275): Detected: VirtualBox VboxManage Interface (Version: 5.2.8)
2019-09-02 01:09:24 (8275): Detected: Minimum checkpoint interval (600.000000 seconds)
2019-09-02 01:09:24 (8275): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2019-09-02 01:09:24 (8275): Starting VM. (boinc_cbb0746839bf963f, slot#0)
2019-09-02 01:09:26 (8275): Successfully started VM. (PID = '8379')
2019-09-02 01:09:26 (8275): Reporting VM Process ID to BOINC.
2019-09-02 01:09:26 (8275): Guest Log: BIOS: VirtualBox 5.2.8
2019-09-02 01:09:26 (8275): Guest Log: CPUID EDX: 0x078bfbff
2019-09-02 01:09:26 (8275): Guest Log: BIOS: ata0-0: PCHS=16383/16/63 LCHS=1024/255/63
2019-09-02 01:09:26 (8275): VM state change detected. (old = 'poweroff', new = 'running')
2019-09-02 01:09:26 (8275): Detected: Web Application Enabled (http://localhost:40464)
2019-09-02 01:09:26 (8275): Detected: Remote Desktop Enabled (localhost:32999)
2019-09-02 01:09:26 (8275): Status Report: Job Duration: '64800.000000'
2019-09-02 01:09:26 (8275): Status Report: Elapsed Time: '30161.421365'
2019-09-02 01:09:26 (8275): Status Report: CPU Time: '25786.680000'
2019-09-02 01:09:26 (8275): Preference change detected
2019-09-02 01:09:26 (8275): Setting CPU throttle for VM. (100%)
2019-09-02 01:09:26 (8275): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 600 seconds) or (Vbox_job.xml: 600 seconds))
2019-09-02 01:09:28 (8275): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032
2019-09-02 01:09:28 (8275): Guest Log: BIOS: Booting from Hard Disk...
2019-09-02 01:09:30 (8275): Guest Log: BIOS: KBD: unsupported int 16h function 03
2019-09-02 01:09:30 (8275): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 
2019-09-02 01:10:17 (8275): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds
2019-09-02 01:10:17 (8275): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000)
2019-09-02 01:11:10 (8275): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log
2019-09-02 01:11:10 (8275): Guest Log: 00:00:00.001444 main     Log opened 2019-09-02T00:11:09.219653000Z
2019-09-02 01:11:10 (8275): Guest Log: 00:00:00.002920 main     OS Product: Linux
2019-09-02 01:11:10 (8275): Guest Log: 00:00:00.003098 main     OS Release: 4.14.44-11.cernvm.x86_64
2019-09-02 01:11:10 (8275): Guest Log: 00:00:00.003158 main     OS Version: #1 SMP Mon May 28 16:49:00 CEST 2018
2019-09-02 01:11:10 (8275): Guest Log: 00:00:00.003219 main     Executable: /usr/share/vboxguest52/usr/sbin/VBoxService
2019-09-02 01:11:10 (8275): Guest Log: 00:00:00.003220 main     Process ID: 2904
2019-09-02 01:11:10 (8275): Guest Log: 00:00:00.003221 main     Package type: LINUX_64BITS_GENERIC
2019-09-02 01:11:10 (8275): Guest Log: 00:00:00.005210 main     5.2.6 r120293 started. Verbose level = 0
2019-09-02 01:11:12 (8275): Stopping VM.
2019-09-02 01:11:15 (8275): Successfully stopped VM.
2019-09-02 01:14:13 (14623): vboxwrapper (7.7.26196): starting
2019-09-02 01:14:14 (14623): Feature: Checkpoint interval offset (540 seconds)
2019-09-02 01:14:14 (14623): Detected: VirtualBox VboxManage Interface (Version: 5.2.8)
2019-09-02 01:14:14 (14623): Detected: Minimum checkpoint interval (600.000000 seconds)
2019-09-02 01:14:14 (14623): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2019-09-02 01:14:14 (14623): Starting VM. (boinc_cbb0746839bf963f, slot#0)
2019-09-02 01:14:17 (14623): Successfully started VM. (PID = '14712')
2019-09-02 01:14:17 (14623): Reporting VM Process ID to BOINC.
2019-09-02 01:14:17 (14623): VM state change detected. (old = 'poweroff', new = 'running')
2019-09-02 01:14:17 (14623): Detected: Web Application Enabled (http://localhost:40464)
2019-09-02 01:14:17 (14623): Detected: Remote Desktop Enabled (localhost:32999)
2019-09-02 01:14:17 (14623): Status Report: Job Duration: '64800.000000'
2019-09-02 01:14:17 (14623): Status Report: Elapsed Time: '30268.585703'
2019-09-02 01:14:17 (14623): Status Report: CPU Time: '25833.780000'
2019-09-02 01:14:17 (14623): Preference change detected
2019-09-02 01:14:17 (14623): Setting CPU throttle for VM. (100%)
2019-09-02 01:14:18 (14623): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 600 seconds) or (Vbox_job.xml: 600 seconds))
2019-09-02 01:16:25 (14623): Guest Log: [INFO] Mounting the shared directory
2019-09-02 01:16:25 (14623): Guest Log: [INFO] Shared directory mounted, enabling vboxmonitor
2019-09-02 01:16:25 (14623): Guest Log: [DEBUG] Testing network connection to cern.ch on port 80
2019-09-02 01:16:26 (14623): Guest Log: [DEBUG] Connection to cern.ch 80 port [tcp/http] succeeded!
2019-09-02 01:16:26 (14623): Guest Log: [DEBUG] 0
2019-09-02 01:16:26 (14623): Guest Log: [DEBUG] Testing VCCS connection to vccs.cern.ch on port 443
2019-09-02 01:16:26 (14623): Guest Log: [DEBUG] Connection to vccs.cern.ch 443 port [tcp/https] succeeded!
2019-09-02 01:16:26 (14623): Guest Log: [DEBUG] 0
2019-09-02 01:16:27 (14623): Guest Log: [DEBUG] Testing connection to Condor server on port 9618
2019-09-02 01:16:27 (14623): Guest Log: [DEBUG] Connection to vccondor01.cern.ch 9618 port [tcp/condor] succeeded!
2019-09-02 01:16:27 (14623): Guest Log: [DEBUG] 0
2019-09-02 01:16:28 (14623): Guest Log: [DEBUG] Detected local proxy http://192.168.100.137:3128 in init_data.xml
2019-09-02 01:16:28 (14623): Guest Log: [DEBUG] Testing connection to 192.168.100.137 on port 3128
2019-09-02 01:16:28 (14623): Guest Log: [DEBUG] Connection to 192.168.100.137 3128 port [tcp/squid] succeeded!
2019-09-02 01:16:28 (14623): Guest Log: [DEBUG] 0
2019-09-02 01:18:00 (14623): Guest Log: [DEBUG] Probing CVMFS ...
2019-09-02 01:18:23 (14623): Guest Log: Probing /cvmfs/grid.cern.ch... OK
2019-09-02 01:18:31 (14623): 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
2019-09-02 01:18:31 (14623): Guest Log: 2.4.4.0 3628 2 27392 10135 3 1 1891031 10240001 2 65024 0 3 100 0 0 http://s1ral-cvmfs.openhtc.io/cvmfs/grid.cern.ch http://192.168.100.137:3128 1
2019-09-02 01:18:33 (14623): Guest Log: [INFO] Reading volunteer information
2019-09-02 01:18:33 (14623): Guest Log: [INFO] Volunteer: m (178)
2019-09-02 01:18:34 (14623): Guest Log: [INFO] VMID: c5ab4400-4ef6-44a9-aaed-bee78432355d
2019-09-02 01:18:35 (14623): Guest Log: [INFO] Requesting an X509 credential from LHC@home
2019-09-02 01:18:35 (14623): Guest Log: [INFO] Requesting an X509 credential from vLHC@home-dev
2019-09-02 01:18:53 (14623): Guest Log: [INFO] Running the fast benchmark.
2019-09-02 01:19:54 (14623): Guest Log: [INFO] Machine performance 8.30 HEPSPEC06
2019-09-02 01:19:55 (14623): Guest Log: [INFO] CMS application starting. Check log files.
2019-09-02 01:19:57 (14623): Guest Log: [DEBUG] HTCondor ping
2019-09-02 01:19:59 (14623): Guest Log: [DEBUG] 0
2019-09-02 02:54:13 (14623): Status Report: Job Duration: '64800.000000'
2019-09-02 02:54:13 (14623): Status Report: Elapsed Time: '36269.923766'
2019-09-02 02:54:13 (14623): Status Report: CPU Time: '31161.150000'
2019-09-02 04:33:54 (14623): Status Report: Job Duration: '64800.000000'
2019-09-02 04:33:54 (14623): Status Report: Elapsed Time: '42270.767604'
2019-09-02 04:33:54 (14623): Status Report: CPU Time: '35497.170000'
2019-09-02 06:13:51 (14623): Status Report: Job Duration: '64800.000000'
2019-09-02 06:13:51 (14623): Status Report: Elapsed Time: '48272.069246'
2019-09-02 06:13:51 (14623): Status Report: CPU Time: '41034.120000'
2019-09-02 06:57:02 (14623): Stopping VM.
2019-09-03 01:09:24 (8358): vboxwrapper (7.7.26196): starting
2019-09-03 01:09:24 (8358): Feature: Checkpoint interval offset (559 seconds)
2019-09-03 01:09:24 (8358): Detected: VirtualBox VboxManage Interface (Version: 5.2.8)
2019-09-03 01:09:24 (8358): Detected: Minimum checkpoint interval (600.000000 seconds)
2019-09-03 01:09:24 (8358): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2019-09-03 01:09:25 (8358): Starting VM. (boinc_cbb0746839bf963f, slot#0)
2019-09-03 01:09:26 (8358): Successfully started VM. (PID = '8447')
2019-09-03 01:09:26 (8358): Reporting VM Process ID to BOINC.
2019-09-03 01:09:26 (8358): Guest Log: BIOS: VirtualBox 5.2.8
2019-09-03 01:09:26 (8358): Guest Log: CPUID EDX: 0x078bfbff
2019-09-03 01:09:26 (8358): Guest Log: BIOS: ata0-0: PCHS=16383/16/63 LCHS=1024/255/63
2019-09-03 01:09:26 (8358): VM state change detected. (old = 'poweroff', new = 'running')
2019-09-03 01:09:26 (8358): Detected: Web Application Enabled (http://localhost:40464)
2019-09-03 01:09:26 (8358): Detected: Remote Desktop Enabled (localhost:32999)
2019-09-03 01:09:26 (8358): Status Report: Job Duration: '64800.000000'
2019-09-03 01:09:26 (8358): Status Report: Elapsed Time: '50879.265493'
2019-09-03 01:09:26 (8358): Status Report: CPU Time: '42395.490000'
2019-09-03 01:09:26 (8358): Preference change detected
2019-09-03 01:09:26 (8358): Setting CPU throttle for VM. (100%)
2019-09-03 01:09:26 (8358): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 600 seconds) or (Vbox_job.xml: 600 seconds))
2019-09-03 01:09:28 (8358): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032
2019-09-03 01:09:28 (8358): Guest Log: BIOS: Booting from Hard Disk...
2019-09-03 01:09:31 (8358): Guest Log: BIOS: KBD: unsupported int 16h function 03
2019-09-03 01:09:31 (8358): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 
2019-09-03 01:09:59 (8358): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds
2019-09-03 01:09:59 (8358): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000)
2019-09-03 01:10:48 (8358): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log
2019-09-03 01:10:48 (8358): Guest Log: 00:00:00.001388 main     Log opened 2019-09-03T00:10:48.144024000Z
2019-09-03 01:10:48 (8358): Guest Log: 00:00:00.002741 main     OS Product: Linux
2019-09-03 01:10:48 (8358): Guest Log: 00:00:00.002913 main     OS Release: 4.14.44-11.cernvm.x86_64
2019-09-03 01:10:48 (8358): Guest Log: 00:00:00.003099 main     OS Version: #1 SMP Mon May 28 16:49:00 CEST 2018
2019-09-03 01:10:48 (8358): Guest Log: 00:00:00.003161 main     Executable: /usr/share/vboxguest52/usr/sbin/VBoxService
2019-09-03 01:10:48 (8358): Guest Log: 00:00:00.003162 main     Process ID: 2906
2019-09-03 01:10:48 (8358): Guest Log: 00:00:00.003163 main     Package type: LINUX_64BITS_GENERIC
2019-09-03 01:10:48 (8358): Guest Log: 00:00:00.005486 main     5.2.6 r120293 started. Verbose level = 0
2019-09-03 01:11:14 (8358): Stopping VM.
2019-09-03 01:11:17 (8358): Successfully stopped VM.
2019-09-03 01:14:14 (14759): vboxwrapper (7.7.26196): starting
2019-09-03 01:14:14 (14759): Feature: Checkpoint interval offset (410 seconds)
2019-09-03 01:14:14 (14759): Detected: VirtualBox VboxManage Interface (Version: 5.2.8)
2019-09-03 01:14:14 (14759): Detected: Minimum checkpoint interval (600.000000 seconds)
2019-09-03 01:14:14 (14759): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2019-09-03 01:14:14 (14759): Starting VM. (boinc_cbb0746839bf963f, slot#0)
2019-09-03 01:14:17 (14759): Successfully started VM. (PID = '14848')
2019-09-03 01:14:17 (14759): Reporting VM Process ID to BOINC.
2019-09-03 01:14:20 (14759): VM state change detected. (old = 'poweroff', new = 'running')
2019-09-03 01:14:20 (14759): Detected: Web Application Enabled (http://localhost:40464)
2019-09-03 01:14:20 (14759): Detected: Remote Desktop Enabled (localhost:32999)
2019-09-03 01:14:21 (14759): Status Report: Job Duration: '64800.000000'
2019-09-03 01:14:21 (14759): Status Report: Elapsed Time: '50987.992194'
2019-09-03 01:14:21 (14759): Status Report: CPU Time: '42456.580000'
2019-09-03 01:14:21 (14759): Preference change detected
2019-09-03 01:14:21 (14759): Setting CPU throttle for VM. (100%)
2019-09-03 01:14:22 (14759): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 600 seconds) or (Vbox_job.xml: 600 seconds))
2019-09-03 01:15:44 (14759): Guest Log: [INFO] Mounting the shared directory
2019-09-03 01:15:44 (14759): Guest Log: [INFO] Shared directory mounted, enabling vboxmonitor
2019-09-03 01:15:45 (14759): Guest Log: [DEBUG] Testing network connection to cern.ch on port 80
2019-09-03 01:15:45 (14759): Guest Log: [DEBUG] Connection to cern.ch 80 port [tcp/http] succeeded!
2019-09-03 01:15:45 (14759): Guest Log: [DEBUG] 0
2019-09-03 01:15:45 (14759): Guest Log: [DEBUG] Testing VCCS connection to vccs.cern.ch on port 443
2019-09-03 01:15:46 (14759): Guest Log: [DEBUG] Connection to vccs.cern.ch 443 port [tcp/https] succeeded!
2019-09-03 01:15:46 (14759): Guest Log: [DEBUG] 0
2019-09-03 01:15:46 (14759): Guest Log: [DEBUG] Testing connection to Condor server on port 9618
2019-09-03 01:15:47 (14759): Guest Log: [DEBUG] Connection to vccondor01.cern.ch 9618 port [tcp/condor] succeeded!
2019-09-03 01:15:47 (14759): Guest Log: [DEBUG] 0
2019-09-03 01:15:47 (14759): Guest Log: [DEBUG] Detected local proxy http://192.168.100.137:3128 in init_data.xml
2019-09-03 01:15:47 (14759): Guest Log: [DEBUG] Testing connection to 192.168.100.137 on port 3128
2019-09-03 01:15:48 (14759): Guest Log: [DEBUG] Connection to 192.168.100.137 3128 port [tcp/squid] succeeded!
2019-09-03 01:15:48 (14759): Guest Log: [DEBUG] 0
2019-09-03 01:17:21 (14759): Guest Log: [DEBUG] Probing CVMFS ...
2019-09-03 01:17:42 (14759): Guest Log: Probing /cvmfs/grid.cern.ch... OK
2019-09-03 01:17:50 (14759): 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
2019-09-03 01:17:50 (14759): Guest Log: 2.4.4.0 3633 2 27828 10143 3 1 1957130 10240000 2 65024 0 3 100 0 0 http://s1ral-cvmfs.openhtc.io/cvmfs/grid.cern.ch http://192.168.100.137:3128 1
2019-09-03 01:17:51 (14759): Guest Log: [INFO] Reading volunteer information
2019-09-03 01:17:53 (14759): Guest Log: [INFO] Volunteer: m (178)
2019-09-03 01:17:53 (14759): Guest Log: [INFO] VMID: c5ab4400-4ef6-44a9-aaed-bee78432355d
2019-09-03 01:17:54 (14759): Guest Log: [INFO] Requesting an X509 credential from LHC@home
2019-09-03 01:17:55 (14759): Guest Log: [INFO] Requesting an X509 credential from vLHC@home-dev
2019-09-03 01:17:57 (14759): Guest Log: [INFO] Running the fast benchmark.
2019-09-03 01:18:59 (14759): Guest Log: [INFO] Machine performance 8.32 HEPSPEC06
2019-09-03 01:18:59 (14759): Guest Log: [INFO] CMS application starting. Check log files.
2019-09-03 01:19:02 (14759): Guest Log: [DEBUG] HTCondor ping
2019-09-03 01:19:04 (14759): Guest Log: [DEBUG] 0
2019-09-03 02:54:14 (14759): Status Report: Job Duration: '64800.000000'
2019-09-03 02:54:14 (14759): Status Report: Elapsed Time: '56988.603585'
2019-09-03 02:54:14 (14759): Status Report: CPU Time: '47821.200000'
2019-09-03 04:33:55 (14759): Status Report: Job Duration: '64800.000000'
2019-09-03 04:33:55 (14759): Status Report: Elapsed Time: '62989.508288'
2019-09-03 04:33:55 (14759): Status Report: CPU Time: '52142.010000'
2019-09-03 05:04:04 (14759): VM state change detected. (old = 'running', new = 'paused')
2019-09-03 06:57:01 (14759): Stopping VM.
2019-09-04 04:28:17 (14382): vboxwrapper (7.7.26196): starting
2019-09-04 04:28:17 (14382): Feature: Checkpoint interval offset (429 seconds)
2019-09-04 04:28:17 (14382): Detected: VirtualBox VboxManage Interface (Version: 5.2.8)
2019-09-04 04:28:17 (14382): Detected: Minimum checkpoint interval (600.000000 seconds)
2019-09-04 04:28:17 (14382): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2019-09-04 04:28:17 (14382): Starting VM. (boinc_cbb0746839bf963f, slot#0)
2019-09-04 04:28:19 (14382): Successfully started VM. (PID = '14486')
2019-09-04 04:28:19 (14382): Reporting VM Process ID to BOINC.
2019-09-04 04:28:19 (14382): Guest Log: BIOS: VirtualBox 5.2.8
2019-09-04 04:28:19 (14382): Guest Log: CPUID EDX: 0x078bfbff
2019-09-04 04:28:19 (14382): Guest Log: BIOS: ata0-0: PCHS=16383/16/63 LCHS=1024/255/63
2019-09-04 04:28:19 (14382): VM state change detected. (old = 'poweroff', new = 'running')
2019-09-04 04:28:19 (14382): Detected: Web Application Enabled (http://localhost:40464)
2019-09-04 04:28:19 (14382): Detected: Remote Desktop Enabled (localhost:32999)
2019-09-04 04:28:19 (14382): Status Report: Job Duration: '64800.000000'
2019-09-04 04:28:19 (14382): Status Report: Elapsed Time: '64795.658747'
2019-09-04 04:28:19 (14382): Status Report: CPU Time: '53814.520000'
2019-09-04 04:28:19 (14382): Preference change detected
2019-09-04 04:28:19 (14382): Setting CPU throttle for VM. (100%)
2019-09-04 04:28:19 (14382): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 600 seconds) or (Vbox_job.xml: 600 seconds))
2019-09-04 04:28:21 (14382): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032
2019-09-04 04:28:21 (14382): Guest Log: BIOS: Booting from Hard Disk...
2019-09-04 04:28:24 (14382): Guest Log: BIOS: KBD: unsupported int 16h function 03
2019-09-04 04:28:24 (14382): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 
2019-09-04 04:28:26 (14382): Powering off VM.
2019-09-04 04:28:27 (14382): Successfully stopped VM.
2019-09-04 04:28:27 (14382): Deregistering VM. (boinc_cbb0746839bf963f, slot#0)
2019-09-04 04:28:27 (14382): Removing network bandwidth throttle group from VM.
2019-09-04 04:28:27 (14382): Removing storage controller(s) from VM.
2019-09-04 04:28:27 (14382): Removing VM from VirtualBox.
2019-09-04 04:28:27 (14382): Removing virtual disk drive from VirtualBox.
04:28:33 (14382): called boinc_finish(0)

</stderr_txt>
]]>


©2024 CERN