Name | CMS_1001039_1568727573.442470_0 |
Workunit | 1939138 |
Created | 17 Sep 2019, 13:39:38 UTC |
Sent | 18 Sep 2019, 0:11:16 UTC |
Report deadline | 25 Sep 2019, 0:11:16 UTC |
Received | 20 Sep 2019, 2:19:30 UTC |
Server state | Over |
Outcome | Success |
Client state | Done |
Exit status | 0 (0x00000000) |
Computer ID | 3943 |
Run time | 17 hours 53 min 17 sec |
CPU time | 16 hours 19 min 51 sec |
Validate state | Valid |
Credit | 2,686.30 |
Device peak FLOPS | 4.50 GFLOPS |
Application version | CMS Simulation v49.00 (vbox64_mt_mcore_cms) x86_64-pc-linux-gnu |
Peak working set size | 1.96 GB |
Peak swap size | 3.99 GB |
Peak disk usage | 3.93 GB |
<core_client_version>7.6.31</core_client_version> <![CDATA[ <stderr_txt> 2019-09-18 04:19:17 (22819): vboxwrapper (7.7.26196): starting 2019-09-18 04:19:17 (22819): Feature: Checkpoint interval offset (232 seconds) 2019-09-18 04:19:17 (22819): Detected: VirtualBox VboxManage Interface (Version: 6.0.12) 2019-09-18 04:19:18 (22819): Detected: Minimum checkpoint interval (600.000000 seconds) 2019-09-18 04:19:18 (22819): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds) 2019-09-18 04:19:18 (22819): Successfully copied 'init_data.xml' to the shared directory. 2019-09-18 04:19:18 (22819): Create VM. (boinc_86cb921e89be8a0c, slot#7) 2019-09-18 04:19:18 (22819): Setting Memory Size for VM. (1896MB) 2019-09-18 04:19:18 (22819): Setting CPU Count for VM. (1) 2019-09-18 04:19:18 (22819): Setting Chipset Options for VM. 2019-09-18 04:19:18 (22819): Setting Boot Options for VM. 2019-09-18 04:19:18 (22819): Setting Network Configuration for NAT. 2019-09-18 04:19:18 (22819): Enabling VM Network Access. 2019-09-18 04:19:18 (22819): Disabling USB Support for VM. 2019-09-18 04:19:18 (22819): Disabling COM Port Support for VM. 2019-09-18 04:19:18 (22819): Disabling LPT Port Support for VM. 2019-09-18 04:19:18 (22819): Disabling Audio Support for VM. 2019-09-18 04:19:18 (22819): Disabling Clipboard Support for VM. 2019-09-18 04:19:18 (22819): Disabling Drag and Drop Support for VM. 2019-09-18 04:19:18 (22819): Adding storage controller(s) to VM. 2019-09-18 04:19:18 (22819): Adding virtual disk drive to VM. (vm_image.vdi) 2019-09-18 04:19:18 (22819): Adding VirtualBox Guest Additions to VM. 2019-09-18 04:19:18 (22819): Adding network bandwidth throttle group to VM. (Defaulting to 1024GB) 2019-09-18 04:19:19 (22819): forwarding host port 53783 to guest port 80 2019-09-18 04:19:19 (22819): Enabling remote desktop for VM. 2019-09-18 04:19:19 (22819): Required extension pack not installed, remote desktop not enabled. 2019-09-18 04:19:19 (22819): Enabling shared directory for VM. 2019-09-18 04:19:19 (22819): Starting VM. (boinc_86cb921e89be8a0c, slot#7) 2019-09-18 04:19:20 (22819): Successfully started VM. (PID = '23287') 2019-09-18 04:19:20 (22819): Reporting VM Process ID to BOINC. 2019-09-18 04:19:20 (22819): Guest Log: BIOS: VirtualBox 6.0.12 2019-09-18 04:19:20 (22819): Guest Log: CPUID EDX: 0x178bfbff 2019-09-18 04:19:20 (22819): Guest Log: BIOS: ata0-0: PCHS=16383/16/63 LCHS=1024/255/63 2019-09-18 04:19:20 (22819): VM state change detected. (old = 'poweroff', new = 'running') 2019-09-18 04:19:20 (22819): Detected: Web Application Enabled (http://localhost:53783) 2019-09-18 04:19:20 (22819): Preference change detected 2019-09-18 04:19:20 (22819): Setting CPU throttle for VM. (100%) 2019-09-18 04:19:20 (22819): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 40 seconds) or (Vbox_job.xml: 600 seconds)) 2019-09-18 04:19:22 (22819): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032 2019-09-18 04:19:22 (22819): Guest Log: BIOS: Booting from Hard Disk... 2019-09-18 04:19:26 (22819): Guest Log: BIOS: KBD: unsupported int 16h function 03 2019-09-18 04:19:26 (22819): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 2019-09-18 04:20:05 (22819): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds 2019-09-18 04:20:05 (22819): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000) 2019-09-18 04:20:28 (22819): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log 2019-09-18 04:20:28 (22819): Guest Log: 00:00:00.000161 main Log opened 2019-09-18T02:20:27.924700000Z 2019-09-18 04:20:28 (22819): Guest Log: 00:00:00.000328 main OS Product: Linux 2019-09-18 04:20:28 (22819): Guest Log: 00:00:00.000357 main OS Release: 4.14.44-11.cernvm.x86_64 2019-09-18 04:20:28 (22819): Guest Log: 00:00:00.000379 main OS Version: #1 SMP Mon May 28 16:49:00 CEST 2018 2019-09-18 04:20:28 (22819): Guest Log: 00:00:00.000410 main Executable: /usr/share/vboxguest52/usr/sbin/VBoxService 2019-09-18 04:20:28 (22819): Guest Log: 00:00:00.000411 main Process ID: 2956 2019-09-18 04:20:28 (22819): Guest Log: 00:00:00.000412 main Package type: LINUX_64BITS_GENERIC 2019-09-18 04:20:28 (22819): Guest Log: 00:00:00.015581 main 5.2.6 r120293 started. Verbose level = 0 2019-09-18 04:20:43 (22819): Guest Log: [INFO] Mounting the shared directory 2019-09-18 04:20:43 (22819): Guest Log: [INFO] Shared directory mounted, enabling vboxmonitor 2019-09-18 04:20:43 (22819): Guest Log: [DEBUG] Testing network connection to cern.ch on port 80 2019-09-18 04:20:44 (22819): Guest Log: [DEBUG] Connection to cern.ch 80 port [tcp/http] succeeded! 2019-09-18 04:20:44 (22819): Guest Log: [DEBUG] 0 2019-09-18 04:20:44 (22819): Guest Log: [DEBUG] Testing VCCS connection to vccs.cern.ch on port 443 2019-09-18 04:20:44 (22819): Guest Log: [DEBUG] Connection to vccs.cern.ch 443 port [tcp/https] succeeded! 2019-09-18 04:20:44 (22819): Guest Log: [DEBUG] 0 2019-09-18 04:20:44 (22819): Guest Log: [DEBUG] Testing connection to Condor server on port 9618 2019-09-18 04:20:44 (22819): Guest Log: [DEBUG] Connection to vccondor01.cern.ch 9618 port [tcp/condor] succeeded! 2019-09-18 04:20:44 (22819): Guest Log: [DEBUG] 0 2019-09-18 04:21:48 (22819): Guest Log: [DEBUG] Probing CVMFS ... 2019-09-18 04:21:48 (22819): Guest Log: Probing /cvmfs/grid.cern.ch... OK 2019-09-18 04:21:49 (22819): 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-18 04:21:49 (22819): Guest Log: 2.4.4.0 3695 1 25832 10267 3 1 1454385 10240001 2 65024 0 3 100 0 0 http://s1bnl-cvmfs.openhtc.io/cvmfs/grid.cern.ch DIRECT 1 2019-09-18 04:21:54 (22819): Guest Log: [INFO] Reading volunteer information 2019-09-18 04:21:54 (22819): Guest Log: [INFO] Volunteer: Rantanplan (569) 2019-09-18 04:21:54 (22819): Guest Log: [INFO] VMID: c5ab4400-4ef6-44a9-aaed-bee78432355d 2019-09-18 04:21:54 (22819): Guest Log: [INFO] Requesting an X509 credential from LHC@home 2019-09-18 04:21:57 (22819): Guest Log: [INFO] Requesting an X509 credential from vLHC@home-dev 2019-09-18 04:21:57 (22819): Guest Log: [INFO] Running the fast benchmark. 2019-09-18 04:22:36 (22819): Guest Log: [INFO] Machine performance 12.44 HEPSPEC06 2019-09-18 04:22:36 (22819): Guest Log: [INFO] CMS application starting. Check log files. 2019-09-18 04:22:37 (22819): Guest Log: [DEBUG] HTCondor ping 2019-09-18 04:22:38 (22819): Guest Log: [DEBUG] 0 2019-09-18 05:11:20 (30459): vboxwrapper (7.7.26196): starting 2019-09-18 05:11:20 (30459): Feature: Checkpoint interval offset (277 seconds) 2019-09-18 05:11:20 (30459): Detected: VirtualBox VboxManage Interface (Version: 6.0.12) 2019-09-18 05:11:20 (30459): Detected: Minimum checkpoint interval (600.000000 seconds) 2019-09-18 05:11:20 (30459): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds) 2019-09-18 05:11:21 (30459): Guest Log: BIOS: VirtualBox 6.0.12 2019-09-18 05:11:21 (30459): Guest Log: CPUID EDX: 0x178bfbff 2019-09-18 05:11:21 (30459): Guest Log: BIOS: ata0-0: PCHS=16383/16/63 LCHS=1024/255/63 2019-09-18 05:11:21 (30459): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032 2019-09-18 05:11:21 (30459): Guest Log: BIOS: Booting from Hard Disk... 2019-09-18 05:11:21 (30459): Guest Log: BIOS: KBD: unsupported int 16h function 03 2019-09-18 05:11:21 (30459): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 2019-09-18 05:11:21 (30459): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds 2019-09-18 05:11:21 (30459): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000) 2019-09-18 05:11:21 (30459): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log 2019-09-18 05:11:21 (30459): Guest Log: 00:00:00.000161 main Log opened 2019-09-18T02:20:27.924700000Z 2019-09-18 05:11:21 (30459): Guest Log: 00:00:00.000328 main OS Product: Linux 2019-09-18 05:11:21 (30459): Guest Log: 00:00:00.000357 main OS Release: 4.14.44-11.cernvm.x86_64 2019-09-18 05:11:21 (30459): Guest Log: 00:00:00.000379 main OS Version: #1 SMP Mon May 28 16:49:00 CEST 2018 2019-09-18 05:11:21 (30459): Guest Log: 00:00:00.000410 main Executable: /usr/share/vboxguest52/usr/sbin/VBoxService 2019-09-18 05:11:21 (30459): Guest Log: 00:00:00.000411 main Process ID: 2956 2019-09-18 05:11:21 (30459): Guest Log: 00:00:00.000412 main Package type: LINUX_64BITS_GENERIC 2019-09-18 05:11:21 (30459): Guest Log: 00:00:00.015581 main 5.2.6 r120293 started. Verbose level = 0 2019-09-18 05:11:21 (30459): Guest Log: [INFO] Mounting the shared directory 2019-09-18 05:11:21 (30459): Guest Log: [INFO] Shared directory mounted, enabling vboxmonitor 2019-09-18 05:11:21 (30459): Guest Log: [DEBUG] Testing network connection to cern.ch on port 80 2019-09-18 05:11:21 (30459): Guest Log: [DEBUG] Connection to cern.ch 80 port [tcp/http] succeeded! 2019-09-18 05:11:21 (30459): Guest Log: [DEBUG] 0 2019-09-18 05:11:21 (30459): Guest Log: [DEBUG] Testing VCCS connection to vccs.cern.ch on port 443 2019-09-18 05:11:21 (30459): Guest Log: [DEBUG] Connection to vccs.cern.ch 443 port [tcp/https] succeeded! 2019-09-18 05:11:21 (30459): Guest Log: [DEBUG] 0 2019-09-18 05:11:21 (30459): Guest Log: [DEBUG] Testing connection to Condor server on port 9618 2019-09-18 05:11:21 (30459): Guest Log: [DEBUG] Connection to vccondor01.cern.ch 9618 port [tcp/condor] succeeded! 2019-09-18 05:11:21 (30459): Guest Log: [DEBUG] 0 2019-09-18 05:11:21 (30459): Guest Log: [DEBUG] Probing CVMFS ... 2019-09-18 05:11:21 (30459): Guest Log: Probing /cvmfs/grid.cern.ch... OK 2019-09-18 05:11:21 (30459): 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-18 05:11:21 (30459): Guest Log: 2.4.4.0 3695 1 25832 10267 3 1 1454385 10240001 2 65024 0 3 100 0 0 http://s1bnl-cvmfs.openhtc.io/cvmfs/grid.cern.ch DIRECT 1 2019-09-18 05:11:21 (30459): Guest Log: [INFO] Reading volunteer information 2019-09-18 05:11:21 (30459): Guest Log: [INFO] Volunteer: Rantanplan (569) 2019-09-18 05:11:21 (30459): Guest Log: [INFO] VMID: c5ab4400-4ef6-44a9-aaed-bee78432355d 2019-09-18 05:11:21 (30459): Guest Log: [INFO] Requesting an X509 credential from LHC@home 2019-09-18 05:11:21 (30459): Guest Log: [INFO] Requesting an X509 credential from vLHC@home-dev 2019-09-18 05:11:21 (30459): Guest Log: [INFO] Running the fast benchmark. 2019-09-18 05:11:21 (30459): Guest Log: [INFO] Machine performance 12.44 HEPSPEC06 2019-09-18 05:11:21 (30459): Guest Log: [INFO] CMS application starting. Check log files. 2019-09-18 05:11:21 (30459): Guest Log: [DEBUG] HTCondor ping 2019-09-18 05:11:21 (30459): Guest Log: [DEBUG] 0 2019-09-18 05:11:21 (30459): Starting VM. (boinc_86cb921e89be8a0c, slot#7) 2019-09-18 05:11:22 (30459): Successfully started VM. (PID = '30551') 2019-09-18 05:11:22 (30459): Reporting VM Process ID to BOINC. 2019-09-18 05:11:23 (30459): VM state change detected. (old = 'poweroff', new = 'running') 2019-09-18 05:11:23 (30459): Detected: Web Application Enabled (http://localhost:53783) 2019-09-18 05:11:23 (30459): Preference change detected 2019-09-18 05:11:23 (30459): Setting CPU throttle for VM. (100%) 2019-09-18 05:11:23 (30459): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 40 seconds) or (Vbox_job.xml: 600 seconds)) 2019-09-18 06:27:02 (30459): Status Report: Job Duration: '64800.000000' 2019-09-18 06:27:02 (30459): Status Report: Elapsed Time: '6000.698462' 2019-09-18 06:27:02 (30459): Status Report: CPU Time: '5256.900000' 2019-09-18 08:06:54 (30459): Status Report: Job Duration: '64800.000000' 2019-09-18 08:06:54 (30459): Status Report: Elapsed Time: '12001.023259' 2019-09-18 08:06:54 (30459): Status Report: CPU Time: '10681.150000' 2019-09-18 09:46:36 (30459): Status Report: Job Duration: '64800.000000' 2019-09-18 09:46:36 (30459): Status Report: Elapsed Time: '18001.840220' 2019-09-18 09:46:36 (30459): Status Report: CPU Time: '16305.130000' 2019-09-18 11:26:12 (30459): Status Report: Job Duration: '64800.000000' 2019-09-18 11:26:12 (30459): Status Report: Elapsed Time: '24002.837270' 2019-09-18 11:26:12 (30459): Status Report: CPU Time: '21909.740000' 2019-09-18 13:05:41 (30459): Status Report: Job Duration: '64800.000000' 2019-09-18 13:05:41 (30459): Status Report: Elapsed Time: '30003.368504' 2019-09-18 13:05:41 (30459): Status Report: CPU Time: '27627.940000' 2019-09-18 21:09:21 (3220): vboxwrapper (7.7.26196): starting 2019-09-18 21:09:22 (3220): Feature: Checkpoint interval offset (468 seconds) 2019-09-18 21:09:22 (3220): Detected: VirtualBox VboxManage Interface (Version: 6.0.12) 2019-09-18 21:09:22 (3220): Detected: Minimum checkpoint interval (600.000000 seconds) 2019-09-18 21:09:22 (3220): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds) 2019-09-18 21:09:22 (3220): Guest Log: BIOS: VirtualBox 6.0.12 2019-09-18 21:09:22 (3220): Guest Log: CPUID EDX: 0x178bfbff 2019-09-18 21:09:22 (3220): Guest Log: BIOS: ata0-0: PCHS=16383/16/63 LCHS=1024/255/63 2019-09-18 21:09:22 (3220): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032 2019-09-18 21:09:22 (3220): Guest Log: BIOS: Booting from Hard Disk... 2019-09-18 21:09:22 (3220): Guest Log: BIOS: KBD: unsupported int 16h function 03 2019-09-18 21:09:22 (3220): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 2019-09-18 21:09:22 (3220): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds 2019-09-18 21:09:22 (3220): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000) 2019-09-18 21:09:22 (3220): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log 2019-09-18 21:09:22 (3220): Guest Log: 00:00:00.000274 main Log opened 2019-09-18T03:12:19.379045000Z 2019-09-18 21:09:22 (3220): Guest Log: 00:00:00.000831 main OS Product: Linux 2019-09-18 21:09:22 (3220): Guest Log: 00:00:00.000939 main OS Release: 4.14.44-11.cernvm.x86_64 2019-09-18 21:09:22 (3220): Guest Log: 00:00:00.001008 main OS Version: #1 SMP Mon May 28 16:49:00 CEST 2018 2019-09-18 21:09:22 (3220): Guest Log: 00:00:00.001036 main Executable: /usr/share/vboxguest52/usr/sbin/VBoxService 2019-09-18 21:09:22 (3220): Guest Log: 00:00:00.001036 main Process ID: 2909 2019-09-18 21:09:22 (3220): Guest Log: 00:00:00.001037 main Package type: LINUX_64BITS_GENERIC 2019-09-18 21:09:22 (3220): Guest Log: 00:00:00.003251 main 5.2.6 r120293 started. Verbose level = 0 2019-09-18 21:09:22 (3220): Guest Log: [INFO] Mounting the shared directory 2019-09-18 21:09:22 (3220): Guest Log: [INFO] Shared directory mounted, enabling vboxmonitor 2019-09-18 21:09:22 (3220): Guest Log: [DEBUG] Testing network connection to cern.ch on port 80 2019-09-18 21:09:22 (3220): Guest Log: [DEBUG] Connection to cern.ch 80 port [tcp/http] succeeded! 2019-09-18 21:09:22 (3220): Guest Log: [DEBUG] 0 2019-09-18 21:09:22 (3220): Guest Log: [DEBUG] Testing VCCS connection to vccs.cern.ch on port 443 2019-09-18 21:09:22 (3220): Guest Log: [DEBUG] Connection to vccs.cern.ch 443 port [tcp/https] succeeded! 2019-09-18 21:09:22 (3220): Guest Log: [DEBUG] 0 2019-09-18 21:09:22 (3220): Guest Log: [DEBUG] Testing connection to Condor server on port 9618 2019-09-18 21:09:22 (3220): Guest Log: [DEBUG] Connection to vccondor01.cern.ch 9618 port [tcp/condor] succeeded! 2019-09-18 21:09:22 (3220): Guest Log: [DEBUG] 0 2019-09-18 21:09:22 (3220): Guest Log: [DEBUG] Probing CVMFS ... 2019-09-18 21:09:22 (3220): Guest Log: Probing /cvmfs/grid.cern.ch... OK 2019-09-18 21:09:22 (3220): 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-18 21:09:22 (3220): Guest Log: 2.4.4.0 3628 1 25780 10267 3 1 2404108 10240001 2 65024 0 3 100 0 0 http://s1cern-cvmfs.openhtc.io/cvmfs/grid.cern.ch DIRECT 1 2019-09-18 21:09:22 (3220): Guest Log: [INFO] Reading volunteer information 2019-09-18 21:09:22 (3220): Guest Log: [INFO] Volunteer: Rantanplan (569) 2019-09-18 21:09:22 (3220): Guest Log: [INFO] VMID: c5ab4400-4ef6-44a9-aaed-bee78432355d 2019-09-18 21:09:22 (3220): Guest Log: [INFO] Requesting an X509 credential from LHC@home 2019-09-18 21:09:22 (3220): Guest Log: [INFO] Requesting an X509 credential from vLHC@home-dev 2019-09-18 21:09:22 (3220): Guest Log: [INFO] Running the fast benchmark. 2019-09-18 21:09:22 (3220): Guest Log: [INFO] Machine performance 12.76 HEPSPEC06 2019-09-18 21:09:22 (3220): Guest Log: [INFO] CMS application starting. Check log files. 2019-09-18 21:09:22 (3220): Guest Log: [DEBUG] HTCondor ping 2019-09-18 21:09:22 (3220): Guest Log: [DEBUG] 0 2019-09-18 21:09:22 (3220): Starting VM. (boinc_86cb921e89be8a0c, slot#7) 2019-09-18 21:09:23 (3220): Successfully started VM. (PID = '3336') 2019-09-18 21:09:23 (3220): Reporting VM Process ID to BOINC. 2019-09-18 21:09:23 (3220): VM state change detected. (old = 'poweroff', new = 'running') 2019-09-18 21:09:23 (3220): Detected: Web Application Enabled (http://localhost:53783) 2019-09-18 21:09:23 (3220): Status Report: Job Duration: '64800.000000' 2019-09-18 21:09:23 (3220): Status Report: Elapsed Time: '31933.827320' 2019-09-18 21:09:23 (3220): Status Report: CPU Time: '29362.150000' 2019-09-18 21:09:23 (3220): Preference change detected 2019-09-18 21:09:23 (3220): Setting CPU throttle for VM. (100%) 2019-09-18 21:09:23 (3220): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 40 seconds) or (Vbox_job.xml: 600 seconds)) 2019-09-18 21:13:10 (3220): Stopping VM. 2019-09-18 21:13:19 (3220): Successfully stopped VM. 2019-09-19 00:23:40 (31170): vboxwrapper (7.7.26196): starting 2019-09-19 00:23:41 (31170): Feature: Checkpoint interval offset (3 seconds) 2019-09-19 00:23:41 (31170): Detected: VirtualBox VboxManage Interface (Version: 6.0.12) 2019-09-19 00:23:41 (31170): Detected: Minimum checkpoint interval (600.000000 seconds) 2019-09-19 00:23:41 (31170): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds) 2019-09-19 00:23:41 (31170): Starting VM. (boinc_86cb921e89be8a0c, slot#7) 2019-09-19 00:23:47 (31170): Successfully started VM. (PID = '31277') 2019-09-19 00:23:47 (31170): Reporting VM Process ID to BOINC. 2019-09-19 00:23:48 (31170): VM state change detected. (old = 'poweroff', new = 'running') 2019-09-19 00:23:48 (31170): Detected: Web Application Enabled (http://localhost:53783) 2019-09-19 00:23:49 (31170): Status Report: Job Duration: '64800.000000' 2019-09-19 00:23:49 (31170): Status Report: Elapsed Time: '32162.885144' 2019-09-19 00:23:49 (31170): Status Report: CPU Time: '29467.590000' 2019-09-19 00:23:49 (31170): Preference change detected 2019-09-19 00:23:49 (31170): Setting CPU throttle for VM. (100%) 2019-09-19 00:23:49 (31170): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 40 seconds) or (Vbox_job.xml: 600 seconds)) 2019-09-19 00:23:55 (31170): Guest Log: 00:03:10.045004 timesync vgsvcTimeSyncWorker: Radical host time change: 11 445 384 000 000ns (HostNow=1 568 845 434 512 000 000 ns HostLast=1 568 833 989 128 000 000 ns) 2019-09-19 00:24:04 (31170): Guest Log: 00:03:20.045345 timesync vgsvcTimeSyncWorker: Radical guest time change: 11 445 386 559 000ns (GuestNow=1 568 845 444 512 387 000 ns GuestLast=1 568 833 999 125 828 000 ns fSetTimeLastLoop=true ) 2019-09-19 02:03:18 (31170): Status Report: Job Duration: '64800.000000' 2019-09-19 02:03:18 (31170): Status Report: Elapsed Time: '38162.907997' 2019-09-19 02:03:18 (31170): Status Report: CPU Time: '34846.560000' 2019-09-19 03:04:10 (31170): Stopping VM. 2019-09-19 03:04:23 (31170): Successfully stopped VM. 2019-09-19 07:22:19 (13125): vboxwrapper (7.7.26196): starting 2019-09-19 07:22:19 (13125): Feature: Checkpoint interval offset (441 seconds) 2019-09-19 07:22:19 (13125): Detected: VirtualBox VboxManage Interface (Version: 6.0.12) 2019-09-19 07:22:19 (13125): Detected: Minimum checkpoint interval (600.000000 seconds) 2019-09-19 07:22:19 (13125): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds) 2019-09-19 07:22:20 (13125): Starting VM. (boinc_86cb921e89be8a0c, slot#7) 2019-09-19 07:22:26 (13125): Successfully started VM. (PID = '13233') 2019-09-19 07:22:26 (13125): Reporting VM Process ID to BOINC. 2019-09-19 07:22:27 (13125): VM state change detected. (old = 'poweroff', new = 'running') 2019-09-19 07:22:27 (13125): Detected: Web Application Enabled (http://localhost:53783) 2019-09-19 07:22:27 (13125): Status Report: Job Duration: '64800.000000' 2019-09-19 07:22:27 (13125): Status Report: Elapsed Time: '41838.303497' 2019-09-19 07:22:27 (13125): Status Report: CPU Time: '38245.370000' 2019-09-19 07:22:27 (13125): Preference change detected 2019-09-19 07:22:27 (13125): Setting CPU throttle for VM. (100%) 2019-09-19 07:22:27 (13125): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 40 seconds) or (Vbox_job.xml: 600 seconds)) 2019-09-19 07:22:32 (13125): Guest Log: 02:43:32.804452 timesync vgsvcTimeSyncWorker: Radical host time change: 15 504 939 000 000ns (HostNow=1 568 870 552 194 000 000 ns HostLast=1 568 855 047 255 000 000 ns) 2019-09-19 07:22:43 (13125): Guest Log: 02:43:42.806389 timesync vgsvcTimeSyncWorker: Radical guest time change: 15 504 945 079 000ns (GuestNow=1 568 870 562 206 253 000 ns GuestLast=1 568 855 057 261 174 000 ns fSetTimeLastLoop=true ) 2019-09-19 08:36:03 (13125): Stopping VM. 2019-09-19 08:36:09 (13125): Successfully stopped VM. 2019-09-19 21:18:56 (19374): vboxwrapper (7.7.26196): starting 2019-09-19 21:18:56 (19374): Feature: Checkpoint interval offset (4 seconds) 2019-09-19 21:18:56 (19374): Detected: VirtualBox VboxManage Interface (Version: 6.0.12) 2019-09-19 21:18:56 (19374): Detected: Minimum checkpoint interval (600.000000 seconds) 2019-09-19 21:18:56 (19374): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds) 2019-09-19 21:18:57 (19374): Starting VM. (boinc_86cb921e89be8a0c, slot#7) 2019-09-19 21:19:03 (19374): Successfully started VM. (PID = '19481') 2019-09-19 21:19:03 (19374): Reporting VM Process ID to BOINC. 2019-09-19 21:19:05 (19374): Guest Log: 03:57:04.528174 timesync vgsvcTimeSyncWorker: Radical host time change: 45 788 870 000 000ns (HostNow=1 568 920 742 777 000 000 ns HostLast=1 568 874 953 907 000 000 ns) 2019-09-19 21:19:06 (19374): VM state change detected. (old = 'poweroff', new = 'running') 2019-09-19 21:19:06 (19374): Detected: Web Application Enabled (http://localhost:53783) 2019-09-19 21:19:07 (19374): Status Report: Job Duration: '64800.000000' 2019-09-19 21:19:07 (19374): Status Report: Elapsed Time: '46282.398276' 2019-09-19 21:19:07 (19374): Status Report: CPU Time: '42491.060000' 2019-09-19 21:19:07 (19374): Preference change detected 2019-09-19 21:19:07 (19374): Setting CPU throttle for VM. (100%) 2019-09-19 21:19:07 (19374): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 40 seconds) or (Vbox_job.xml: 600 seconds)) 2019-09-19 21:19:13 (19374): Guest Log: 03:57:14.536015 timesync vgsvcTimeSyncWorker: Radical guest time change: 45 788 856 835 000ns (GuestNow=1 568 920 752 784 863 000 ns GuestLast=1 568 874 963 928 028 000 ns fSetTimeLastLoop=true ) 2019-09-19 21:39:03 (19374): Stopping VM. 2019-09-19 21:58:54 (1394): vboxwrapper (7.7.26196): starting 2019-09-19 21:58:54 (1394): Feature: Checkpoint interval offset (22 seconds) 2019-09-19 21:58:54 (1394): Detected: VirtualBox VboxManage Interface (Version: 6.0.12) 2019-09-19 21:58:54 (1394): Detected: Minimum checkpoint interval (600.000000 seconds) 2019-09-19 21:58:54 (1394): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds) 2019-09-19 21:58:54 (1394): Guest Log: 03:57:04.528174 timesync vgsvcTimeSyncWorker: Radical host time change: 45 788 870 000 000ns (HostNow=1 568 920 742 777 000 000 ns HostLast=1 568 874 953 907 000 000 ns) 2019-09-19 21:58:54 (1394): Guest Log: 03:57:14.536015 timesync vgsvcTimeSyncWorker: Radical guest time change: 45 788 856 835 000ns (GuestNow=1 568 920 752 784 863 000 ns GuestLast=1 568 874 963 928 028 000 ns fSetTimeLastLoop=true ) 2019-09-19 21:58:54 (1394): Starting VM. (boinc_86cb921e89be8a0c, slot#7) 2019-09-19 21:58:56 (1394): Successfully started VM. (PID = '1548') 2019-09-19 21:58:56 (1394): Reporting VM Process ID to BOINC. 2019-09-19 21:58:56 (1394): VM state change detected. (old = 'poweroff', new = 'running') 2019-09-19 21:58:56 (1394): Detected: Web Application Enabled (http://localhost:53783) 2019-09-19 21:58:56 (1394): Status Report: Job Duration: '64800.000000' 2019-09-19 21:58:56 (1394): Status Report: Elapsed Time: '47489.770680' 2019-09-19 21:58:56 (1394): Status Report: CPU Time: '42936.000000' 2019-09-19 21:58:56 (1394): Preference change detected 2019-09-19 21:58:56 (1394): Setting CPU throttle for VM. (100%) 2019-09-19 21:58:56 (1394): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 40 seconds) or (Vbox_job.xml: 600 seconds)) 2019-09-19 21:59:16 (1394): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds 2019-09-19 21:59:16 (1394): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000) 2019-09-19 21:59:40 (1394): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log 2019-09-19 21:59:40 (1394): Guest Log: 00:00:00.000165 main Log opened 2019-09-19T19:59:39.970068000Z 2019-09-19 21:59:40 (1394): Guest Log: 00:00:00.000330 main OS Product: Linux 2019-09-19 21:59:40 (1394): Guest Log: 00:00:00.000380 main OS Release: 4.14.44-11.cernvm.x86_64 2019-09-19 21:59:40 (1394): Guest Log: 00:00:00.000403 main OS Version: #1 SMP Mon May 28 16:49:00 CEST 2018 2019-09-19 21:59:40 (1394): Guest Log: 00:00:00.000423 main Executable: /usr/share/vboxguest52/usr/sbin/VBoxService 2019-09-19 21:59:40 (1394): Guest Log: 00:00:00.000423 main Process ID: 2910 2019-09-19 21:59:40 (1394): Guest Log: 00:00:00.000424 main Package type: LINUX_64BITS_GENERIC 2019-09-19 21:59:40 (1394): Guest Log: 00:00:00.012892 main 5.2.6 r120293 started. Verbose level = 0 2019-09-19 21:59:54 (1394): Guest Log: [INFO] Mounting the shared directory 2019-09-19 21:59:54 (1394): Guest Log: [INFO] Shared directory mounted, enabling vboxmonitor 2019-09-19 21:59:54 (1394): Guest Log: [DEBUG] Testing network connection to cern.ch on port 80 2019-09-19 21:59:56 (1394): Guest Log: [DEBUG] Connection to cern.ch 80 port [tcp/http] succeeded! 2019-09-19 21:59:56 (1394): Guest Log: [DEBUG] 0 2019-09-19 21:59:56 (1394): Guest Log: [DEBUG] Testing VCCS connection to vccs.cern.ch on port 443 2019-09-19 21:59:56 (1394): Guest Log: [DEBUG] Connection to vccs.cern.ch 443 port [tcp/https] succeeded! 2019-09-19 21:59:56 (1394): Guest Log: [DEBUG] 0 2019-09-19 21:59:56 (1394): Guest Log: [DEBUG] Testing connection to Condor server on port 9618 2019-09-19 21:59:56 (1394): Guest Log: [DEBUG] Connection to vccondor01.cern.ch 9618 port [tcp/condor] succeeded! 2019-09-19 21:59:56 (1394): Guest Log: [DEBUG] 0 2019-09-19 22:00:59 (1394): Guest Log: [DEBUG] Probing CVMFS ... 2019-09-19 22:00:59 (1394): Guest Log: Probing /cvmfs/grid.cern.ch... OK 2019-09-19 22:01:00 (1394): 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-19 22:01:00 (1394): Guest Log: 2.4.4.0 3628 1 27808 10281 3 1 2542387 10240001 2 65024 0 3 100 0 0 http://s1bnl-cvmfs.openhtc.io/cvmfs/grid.cern.ch DIRECT 1 2019-09-19 22:01:02 (1394): Guest Log: [INFO] Reading volunteer information 2019-09-19 22:01:02 (1394): Guest Log: [INFO] Volunteer: Rantanplan (569) 2019-09-19 22:01:02 (1394): Guest Log: [INFO] VMID: c5ab4400-4ef6-44a9-aaed-bee78432355d 2019-09-19 22:01:02 (1394): Guest Log: [INFO] Requesting an X509 credential from LHC@home 2019-09-19 22:01:03 (1394): Guest Log: [INFO] Requesting an X509 credential from vLHC@home-dev 2019-09-19 22:01:03 (1394): Guest Log: [INFO] Running the fast benchmark. 2019-09-19 22:01:43 (1394): Guest Log: [INFO] Machine performance 12.83 HEPSPEC06 2019-09-19 22:01:43 (1394): Guest Log: [INFO] CMS application starting. Check log files. 2019-09-19 22:01:44 (1394): Guest Log: [DEBUG] HTCondor ping 2019-09-19 22:01:45 (1394): Guest Log: [DEBUG] 0 2019-09-19 23:37:19 (12058): vboxwrapper (7.7.26196): starting 2019-09-19 23:37:20 (12058): Feature: Checkpoint interval offset (543 seconds) 2019-09-19 23:37:20 (12058): Detected: VirtualBox VboxManage Interface (Version: 6.0.12) 2019-09-19 23:37:20 (12058): Detected: Minimum checkpoint interval (600.000000 seconds) 2019-09-19 23:37:20 (12058): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds) 2019-09-19 23:37:20 (12058): Guest Log: BIOS: VirtualBox 6.0.12 2019-09-19 23:37:20 (12058): Guest Log: CPUID EDX: 0x178bfbff 2019-09-19 23:37:20 (12058): Guest Log: BIOS: ata0-0: PCHS=16383/16/63 LCHS=1024/255/63 2019-09-19 23:37:20 (12058): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032 2019-09-19 23:37:20 (12058): Guest Log: BIOS: Booting from Hard Disk... 2019-09-19 23:37:20 (12058): Guest Log: BIOS: KBD: unsupported int 16h function 03 2019-09-19 23:37:20 (12058): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 2019-09-19 23:37:20 (12058): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds 2019-09-19 23:37:20 (12058): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000) 2019-09-19 23:37:20 (12058): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log 2019-09-19 23:37:20 (12058): Guest Log: 00:00:00.000165 main Log opened 2019-09-19T19:59:39.970068000Z 2019-09-19 23:37:20 (12058): Guest Log: 00:00:00.000330 main OS Product: Linux 2019-09-19 23:37:20 (12058): Guest Log: 00:00:00.000380 main OS Release: 4.14.44-11.cernvm.x86_64 2019-09-19 23:37:20 (12058): Guest Log: 00:00:00.000403 main OS Version: #1 SMP Mon May 28 16:49:00 CEST 2018 2019-09-19 23:37:20 (12058): Guest Log: 00:00:00.000423 main Executable: /usr/share/vboxguest52/usr/sbin/VBoxService 2019-09-19 23:37:20 (12058): Guest Log: 00:00:00.000423 main Process ID: 2910 2019-09-19 23:37:20 (12058): Guest Log: 00:00:00.000424 main Package type: LINUX_64BITS_GENERIC 2019-09-19 23:37:20 (12058): Guest Log: 00:00:00.012892 main 5.2.6 r120293 started. Verbose level = 0 2019-09-19 23:37:20 (12058): Guest Log: [INFO] Mounting the shared directory 2019-09-19 23:37:20 (12058): Guest Log: [INFO] Shared directory mounted, enabling vboxmonitor 2019-09-19 23:37:20 (12058): Guest Log: [DEBUG] Testing network connection to cern.ch on port 80 2019-09-19 23:37:20 (12058): Guest Log: [DEBUG] Connection to cern.ch 80 port [tcp/http] succeeded! 2019-09-19 23:37:20 (12058): Guest Log: [DEBUG] 0 2019-09-19 23:37:20 (12058): Guest Log: [DEBUG] Testing VCCS connection to vccs.cern.ch on port 443 2019-09-19 23:37:20 (12058): Guest Log: [DEBUG] Connection to vccs.cern.ch 443 port [tcp/https] succeeded! 2019-09-19 23:37:20 (12058): Guest Log: [DEBUG] 0 2019-09-19 23:37:20 (12058): Guest Log: [DEBUG] Testing connection to Condor server on port 9618 2019-09-19 23:37:20 (12058): Guest Log: [DEBUG] Connection to vccondor01.cern.ch 9618 port [tcp/condor] succeeded! 2019-09-19 23:37:20 (12058): Guest Log: [DEBUG] 0 2019-09-19 23:37:20 (12058): Guest Log: [DEBUG] Probing CVMFS ... 2019-09-19 23:37:20 (12058): Guest Log: Probing /cvmfs/grid.cern.ch... OK 2019-09-19 23:37:20 (12058): 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-19 23:37:20 (12058): Guest Log: 2.4.4.0 3628 1 27808 10281 3 1 2542387 10240001 2 65024 0 3 100 0 0 http://s1bnl-cvmfs.openhtc.io/cvmfs/grid.cern.ch DIRECT 1 2019-09-19 23:37:20 (12058): Guest Log: [INFO] Reading volunteer information 2019-09-19 23:37:20 (12058): Guest Log: [INFO] Volunteer: Rantanplan (569) 2019-09-19 23:37:20 (12058): Guest Log: [INFO] VMID: c5ab4400-4ef6-44a9-aaed-bee78432355d 2019-09-19 23:37:20 (12058): Guest Log: [INFO] Requesting an X509 credential from LHC@home 2019-09-19 23:37:20 (12058): Guest Log: [INFO] Requesting an X509 credential from vLHC@home-dev 2019-09-19 23:37:20 (12058): Guest Log: [INFO] Running the fast benchmark. 2019-09-19 23:37:20 (12058): Guest Log: [INFO] Machine performance 12.83 HEPSPEC06 2019-09-19 23:37:20 (12058): Guest Log: [INFO] CMS application starting. Check log files. 2019-09-19 23:37:20 (12058): Guest Log: [DEBUG] HTCondor ping 2019-09-19 23:37:20 (12058): Guest Log: [DEBUG] 0 2019-09-19 23:37:20 (12058): Starting VM. (boinc_86cb921e89be8a0c, slot#7) 2019-09-19 23:37:21 (12058): Successfully started VM. (PID = '12166') 2019-09-19 23:37:21 (12058): Reporting VM Process ID to BOINC. 2019-09-19 23:37:21 (12058): VM state change detected. (old = 'poweroff', new = 'running') 2019-09-19 23:37:21 (12058): Detected: Web Application Enabled (http://localhost:53783) 2019-09-19 23:37:21 (12058): Status Report: Job Duration: '64800.000000' 2019-09-19 23:37:21 (12058): Status Report: Elapsed Time: '47774.542477' 2019-09-19 23:37:21 (12058): Status Report: CPU Time: '43052.880000' 2019-09-19 23:37:21 (12058): Preference change detected 2019-09-19 23:37:21 (12058): Setting CPU throttle for VM. (100%) 2019-09-19 23:37:21 (12058): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 40 seconds) or (Vbox_job.xml: 600 seconds)) 2019-09-20 01:16:44 (12058): Status Report: Job Duration: '64800.000000' 2019-09-20 01:16:44 (12058): Status Report: Elapsed Time: '53774.792899' 2019-09-20 01:16:44 (12058): Status Report: CPU Time: '48540.630000' 2019-09-20 02:56:06 (12058): Status Report: Job Duration: '64800.000000' 2019-09-20 02:56:06 (12058): Status Report: Elapsed Time: '59775.899573' 2019-09-20 02:56:06 (12058): Status Report: CPU Time: '54059.980000' 2019-09-20 04:19:18 (12058): Powering off VM. 2019-09-20 04:19:19 (12058): Successfully stopped VM. 2019-09-20 04:19:19 (12058): Deregistering VM. (boinc_86cb921e89be8a0c, slot#7) 2019-09-20 04:19:19 (12058): Removing network bandwidth throttle group from VM. 2019-09-20 04:19:19 (12058): Removing storage controller(s) from VM. 2019-09-20 04:19:19 (12058): Removing VM from VirtualBox. 2019-09-20 04:19:19 (12058): Removing virtual disk drive from VirtualBox. 04:19:24 (12058): called boinc_finish(0) </stderr_txt> ]]>
©2024 CERN