Name | CMS_3004461_1620614429.679760_0 |
Workunit | 2074112 |
Created | 10 May 2021, 2:40:34 UTC |
Sent | 13 May 2021, 4:34:13 UTC |
Report deadline | 20 May 2021, 4:34:13 UTC |
Received | 14 May 2021, 4:24:17 UTC |
Server state | Over |
Outcome | Success |
Client state | Done |
Exit status | 0 (0x00000000) |
Computer ID | 4274 |
Run time | 17 hours 27 min 12 sec |
CPU time | 16 hours 59 min 31 sec |
Validate state | Valid |
Credit | 404.64 |
Device peak FLOPS | 2.79 GFLOPS |
Application version | CMS Simulation v50.00 (vbox64_mt_mcore_cms) windows_x86_64 |
Peak working set size | 79.29 MB |
Peak swap size | 87.91 MB |
Peak disk usage | 3.66 GB |
<core_client_version>7.16.11</core_client_version> <![CDATA[ <stderr_txt> 2021-05-13 00:39:20 (3320): Detected: vboxwrapper 26197 2021-05-13 00:39:20 (3320): Detected: BOINC client v7.7 2021-05-13 00:39:21 (3320): Detected: VirtualBox VboxManage Interface (Version: 5.2.44) 2021-05-13 00:39:21 (3320): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds) 2021-05-13 00:39:21 (3320): Successfully copied 'init_data.xml' to the shared directory. 2021-05-13 00:39:23 (3320): Create VM. (boinc_e968473acb0b0883, slot#9) 2021-05-13 00:39:23 (3320): Setting Memory Size for VM. (1896MB) 2021-05-13 00:39:24 (3320): Setting CPU Count for VM. (1) 2021-05-13 00:39:24 (3320): Setting Chipset Options for VM. 2021-05-13 00:39:24 (3320): Setting Boot Options for VM. 2021-05-13 00:39:25 (3320): Setting Network Configuration for NAT. 2021-05-13 00:39:25 (3320): Enabling VM Network Access. 2021-05-13 00:39:25 (3320): Disabling USB Support for VM. 2021-05-13 00:39:25 (3320): Disabling COM Port Support for VM. 2021-05-13 00:39:26 (3320): Disabling LPT Port Support for VM. 2021-05-13 00:39:26 (3320): Disabling Audio Support for VM. 2021-05-13 00:39:26 (3320): Disabling Clipboard Support for VM. 2021-05-13 00:39:27 (3320): Disabling Drag and Drop Support for VM. 2021-05-13 00:39:27 (3320): Adding storage controller(s) to VM. 2021-05-13 00:39:27 (3320): Adding virtual disk drive to VM. (vm_image.vdi) 2021-05-13 00:39:28 (3320): Adding VirtualBox Guest Additions to VM. 2021-05-13 00:39:28 (3320): Adding network bandwidth throttle group to VM. (Defaulting to 1024GB) 2021-05-13 00:39:28 (3320): forwarding host port 55251 to guest port 80 2021-05-13 00:39:28 (3320): Enabling remote desktop for VM. 2021-05-13 00:39:29 (3320): Required extension pack not installed, remote desktop not enabled. 2021-05-13 00:39:29 (3320): Enabling shared directory for VM. 2021-05-13 00:39:29 (3320): Starting VM using VBoxManage interface. (boinc_e968473acb0b0883, slot#9) 2021-05-13 00:39:36 (3320): Successfully started VM. (PID = '8724') 2021-05-13 00:39:36 (3320): Reporting VM Process ID to BOINC. 2021-05-13 00:39:36 (3320): Guest Log: BIOS: VirtualBox 5.2.44 2021-05-13 00:39:36 (3320): Guest Log: CPUID EDX: 0x078bfbff 2021-05-13 00:39:36 (3320): Guest Log: BIOS: ata0-0: PCHS=16383/16/63 LCHS=1024/255/63 2021-05-13 00:39:36 (3320): VM state change detected. (old = 'PoweredOff', new = 'Running') 2021-05-13 00:39:36 (3320): Detected: Web Application Enabled (http://localhost:55251) 2021-05-13 00:39:36 (3320): Preference change detected 2021-05-13 00:39:36 (3320): Setting CPU throttle for VM. (100%) 2021-05-13 00:39:36 (3320): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds)) 2021-05-13 00:39:38 (3320): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032 2021-05-13 00:39:38 (3320): Guest Log: BIOS: Booting from Hard Disk... 2021-05-13 00:39:40 (3320): Guest Log: BIOS: KBD: unsupported int 16h function 03 2021-05-13 00:39:40 (3320): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 2021-05-13 00:39:59 (3320): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds 2021-05-13 00:39:59 (3320): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000) 2021-05-13 00:40:30 (3320): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log 2021-05-13 00:40:30 (3320): Guest Log: 00:00:00.000407 main Log opened 2021-05-13T04:40:29.664606000Z 2021-05-13 00:40:30 (3320): Guest Log: 00:00:00.000746 main OS Product: Linux 2021-05-13 00:40:30 (3320): Guest Log: 00:00:00.000861 main OS Release: 4.14.157-17.cernvm.x86_64 2021-05-13 00:40:30 (3320): Guest Log: 00:00:00.000894 main OS Version: #1 SMP Wed Dec 4 17:26:45 CET 2019 2021-05-13 00:40:30 (3320): Guest Log: 00:00:00.000926 main Executable: /usr/share/vboxguest52/usr/sbin/VBoxService 2021-05-13 00:40:30 (3320): Guest Log: 00:00:00.000927 main Process ID: 2957 2021-05-13 00:40:30 (3320): Guest Log: 00:00:00.000928 main Package type: LINUX_64BITS_GENERIC 2021-05-13 00:40:30 (3320): Guest Log: 00:00:00.002180 main 5.2.6 r120293 started. Verbose level = 0 2021-05-13 00:40:53 (3320): Guest Log: [INFO] Mounting the shared directory 2021-05-13 00:40:53 (3320): Guest Log: [INFO] Shared directory mounted, enabling vboxmonitor 2021-05-13 00:40:53 (3320): Guest Log: [DEBUG] Testing network connection to cern.ch on port 80 2021-05-13 00:40:53 (3320): Guest Log: [DEBUG] Connection to cern.ch 80 port [tcp/http] succeeded! 2021-05-13 00:40:53 (3320): Guest Log: [DEBUG] 0 2021-05-13 00:40:53 (3320): Guest Log: [DEBUG] Testing VCCS connection to vccs.cern.ch on port 443 2021-05-13 00:40:54 (3320): Guest Log: [DEBUG] Connection to vccs.cern.ch 443 port [tcp/https] succeeded! 2021-05-13 00:40:54 (3320): Guest Log: [DEBUG] 0 2021-05-13 00:40:54 (3320): Guest Log: [DEBUG] Testing connection to Condor server on port 9618 2021-05-13 00:40:54 (3320): Guest Log: [DEBUG] Connection to vocms0840.cern.ch 9618 port [tcp/condor] succeeded! 2021-05-13 00:40:54 (3320): Guest Log: [DEBUG] 0 2021-05-13 00:40:55 (3320): Guest Log: [DEBUG] Testing connection to WMAgent server on port 4080 2021-05-13 00:40:55 (3320): Guest Log: [DEBUG] Connection to vocms0267.cern.ch 4080 port [tcp/lorica-in] succeeded! 2021-05-13 00:40:55 (3320): Guest Log: [DEBUG] 0 2021-05-13 00:40:55 (3320): Guest Log: [DEBUG] Testing connection to Frontier server on port 8080 2021-05-13 00:40:55 (3320): Guest Log: [DEBUG] Connection to cms-frontier.openhtc.io 8080 port [tcp/webcache] succeeded! 2021-05-13 00:40:55 (3320): Guest Log: [DEBUG] 0 2021-05-13 00:40:57 (3320): Guest Log: [INFO] CVMFS and Frontier will use DIRECT instead of an HTTP proxy. 2021-05-13 00:40:57 (3320): Guest Log: [INFO] A local HTTP proxy could help making the network usage 2021-05-13 00:40:57 (3320): Guest Log: [INFO] of this application more efficient. It would also help to 2021-05-13 00:40:57 (3320): Guest Log: [INFO] offload the project servers. 2021-05-13 00:40:57 (3320): Guest Log: [INFO] Details can be found in the project forum. 2021-05-13 00:41:09 (3320): Guest Log: [INFO] Reloading the CVMFS configuration (can take a while) ... 2021-05-13 00:42:23 (3320): Guest Log: [INFO] Probing CVMFS ... 2021-05-13 00:42:33 (3320): 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-05-13 00:42:33 (3320): Guest Log: 2.4.4.0 3739 1 27888 15165 3 1 1243110 4096000 2 65024 0 2 100 0 0 http://s1ihep-cvmfs.openhtc.io/cvmfs/grid.cern.ch DIRECT 1 2021-05-13 00:42:48 (3320): Guest Log: [INFO] Reading volunteer information 2021-05-13 00:42:49 (3320): Guest Log: [INFO] Volunteer: Contact (21) 2021-05-13 00:42:49 (3320): Guest Log: [INFO] VMID: 49b2fac1-df25-48d2-a4ee-4612ca6a31f8 2021-05-13 00:42:49 (3320): Guest Log: [INFO] Requesting an X509 credential from LHC@home 2021-05-13 00:42:51 (3320): Guest Log: [INFO] Requesting an X509 credential from vLHC@home-dev 2021-05-13 00:42:53 (3320): Guest Log: [INFO] Running the fast benchmark. 2021-05-13 00:43:48 (3320): Guest Log: [INFO] Machine performance 9.43 HEPSPEC06 2021-05-13 00:43:48 (3320): Guest Log: [INFO] CMS application starting. Check log files. 2021-05-13 00:43:51 (3320): Guest Log: [DEBUG] HTCondor ping 2021-05-13 00:43:53 (3320): Guest Log: [DEBUG] 0 2021-05-13 02:19:20 (3320): Stopping VM. 2021-05-13 02:20:34 (6396): Detected: vboxwrapper 26197 2021-05-13 02:20:34 (6396): Detected: BOINC client v7.7 2021-05-13 02:20:34 (6396): Detected: VirtualBox VboxManage Interface (Version: 5.2.44) 2021-05-13 02:20:35 (6396): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds) 2021-05-13 02:20:35 (6396): Starting VM using VBoxManage interface. (boinc_e968473acb0b0883, slot#9) 2021-05-13 02:20:47 (6396): Successfully started VM. (PID = '9148') 2021-05-13 02:20:47 (6396): Reporting VM Process ID to BOINC. 2021-05-13 02:20:47 (6396): VM state change detected. (old = 'PoweredOff', new = 'Running') 2021-05-13 02:20:47 (6396): Detected: Web Application Enabled (http://localhost:55251) 2021-05-13 02:20:47 (6396): Preference change detected 2021-05-13 02:20:47 (6396): Setting CPU throttle for VM. (100%) 2021-05-13 02:20:48 (6396): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds)) 2021-05-13 02:21:59 (6396): Status Report: Job Duration: '64800.000000' 2021-05-13 02:21:59 (6396): Status Report: Elapsed Time: '6000.000000' 2021-05-13 02:21:59 (6396): Status Report: CPU Time: '5659.109375' 2021-05-13 02:22:31 (6396): Stopping VM. 2021-05-13 02:41:54 (7284): Detected: vboxwrapper 26197 2021-05-13 02:41:54 (7284): Detected: BOINC client v7.7 2021-05-13 02:41:55 (7284): Detected: VirtualBox VboxManage Interface (Version: 5.2.44) 2021-05-13 02:41:55 (7284): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds) 2021-05-13 02:41:55 (7284): Starting VM using VBoxManage interface. (boinc_e968473acb0b0883, slot#9) 2021-05-13 02:42:07 (7284): Successfully started VM. (PID = '6212') 2021-05-13 02:42:07 (7284): Reporting VM Process ID to BOINC. 2021-05-13 02:42:07 (7284): VM state change detected. (old = 'PoweredOff', new = 'Running') 2021-05-13 02:42:07 (7284): Detected: Web Application Enabled (http://localhost:55251) 2021-05-13 02:42:07 (7284): Status Report: Job Duration: '64800.000000' 2021-05-13 02:42:07 (7284): Status Report: Elapsed Time: '6029.000000' 2021-05-13 02:42:07 (7284): Status Report: CPU Time: '5679.515625' 2021-05-13 02:42:07 (7284): Preference change detected 2021-05-13 02:42:07 (7284): Setting CPU throttle for VM. (100%) 2021-05-13 02:42:07 (7284): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds)) 2021-05-13 04:02:13 (7284): Stopping VM. 2021-05-13 04:05:02 (1196): Detected: vboxwrapper 26197 2021-05-13 04:05:02 (1196): Detected: BOINC client v7.7 2021-05-13 04:05:03 (1196): Detected: VirtualBox VboxManage Interface (Version: 5.2.44) 2021-05-13 04:05:03 (1196): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds) 2021-05-13 04:05:04 (1196): Starting VM using VBoxManage interface. (boinc_e968473acb0b0883, slot#9) 2021-05-13 04:05:15 (1196): Successfully started VM. (PID = '8564') 2021-05-13 04:05:15 (1196): Reporting VM Process ID to BOINC. 2021-05-13 04:05:15 (1196): VM state change detected. (old = 'PoweredOff', new = 'Running') 2021-05-13 04:05:15 (1196): Detected: Web Application Enabled (http://localhost:55251) 2021-05-13 04:05:15 (1196): Status Report: Job Duration: '64800.000000' 2021-05-13 04:05:15 (1196): Status Report: Elapsed Time: '10740.000000' 2021-05-13 04:05:15 (1196): Status Report: CPU Time: '10214.343750' 2021-05-13 04:05:15 (1196): Preference change detected 2021-05-13 04:05:15 (1196): Setting CPU throttle for VM. (100%) 2021-05-13 04:05:16 (1196): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds)) 2021-05-13 04:56:07 (1196): Stopping VM. 2021-05-13 04:57:13 (4880): Detected: vboxwrapper 26197 2021-05-13 04:57:13 (4880): Detected: BOINC client v7.7 2021-05-13 04:57:14 (4880): Detected: VirtualBox VboxManage Interface (Version: 5.2.44) 2021-05-13 04:57:14 (4880): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds) 2021-05-13 04:57:14 (4880): Starting VM using VBoxManage interface. (boinc_e968473acb0b0883, slot#9) 2021-05-13 04:57:27 (4880): Successfully started VM. (PID = '7384') 2021-05-13 04:57:27 (4880): Reporting VM Process ID to BOINC. 2021-05-13 04:57:27 (4880): VM state change detected. (old = 'PoweredOff', new = 'Running') 2021-05-13 04:57:27 (4880): Detected: Web Application Enabled (http://localhost:55251) 2021-05-13 04:57:27 (4880): Status Report: Job Duration: '64800.000000' 2021-05-13 04:57:27 (4880): Status Report: Elapsed Time: '13735.000000' 2021-05-13 04:57:27 (4880): Status Report: CPU Time: '12950.187500' 2021-05-13 04:57:27 (4880): Preference change detected 2021-05-13 04:57:27 (4880): Setting CPU throttle for VM. (100%) 2021-05-13 04:57:27 (4880): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds)) 2021-05-13 05:57:57 (4880): Stopping VM. 2021-05-13 06:36:25 (8440): Detected: vboxwrapper 26197 2021-05-13 06:36:25 (8440): Detected: BOINC client v7.7 2021-05-13 06:36:26 (8440): Detected: VirtualBox VboxManage Interface (Version: 5.2.44) 2021-05-13 06:36:26 (8440): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds) 2021-05-13 06:36:27 (8440): Starting VM using VBoxManage interface. (boinc_e968473acb0b0883, slot#9) 2021-05-13 06:36:47 (8440): Successfully started VM. (PID = '6384') 2021-05-13 06:36:47 (8440): Reporting VM Process ID to BOINC. 2021-05-13 06:36:47 (8440): Guest Log: 04:52:13.605454 timesync vgsvcTimeSyncWorker: Radical host time change: 2 337 442 000 000ns (HostNow=1 620 902 159 092 000 000 ns HostLast=1 620 899 821 650 000 000 ns) 2021-05-13 06:36:47 (8440): VM state change detected. (old = 'PoweredOff', new = 'Running') 2021-05-13 06:36:47 (8440): Detected: Web Application Enabled (http://localhost:55251) 2021-05-13 06:36:47 (8440): Status Report: Job Duration: '64800.000000' 2021-05-13 06:36:47 (8440): Status Report: Elapsed Time: '17295.199295' 2021-05-13 06:36:47 (8440): Status Report: CPU Time: '16561.484375' 2021-05-13 06:36:47 (8440): Preference change detected 2021-05-13 06:36:47 (8440): Setting CPU throttle for VM. (100%) 2021-05-13 06:36:47 (8440): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds)) 2021-05-13 06:36:56 (8440): Guest Log: 04:52:23.606205 timesync vgsvcTimeSyncWorker: Radical guest time change: 2 292 946 521 000ns (GuestNow=1 620 902 169 124 761 000 ns GuestLast=1 620 899 876 178 240 000 ns fSetTimeLastLoop=true ) 2021-05-13 08:14:44 (8440): Stopping VM. 2021-05-13 08:24:49 (980): Detected: vboxwrapper 26197 2021-05-13 08:24:49 (980): Detected: BOINC client v7.7 2021-05-13 08:24:49 (980): Detected: VirtualBox VboxManage Interface (Version: 5.2.44) 2021-05-13 08:24:50 (980): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds) 2021-05-13 08:24:50 (980): Starting VM using VBoxManage interface. (boinc_e968473acb0b0883, slot#9) 2021-05-13 08:25:01 (980): Successfully started VM. (PID = '6464') 2021-05-13 08:25:01 (980): Reporting VM Process ID to BOINC. 2021-05-13 08:25:01 (980): VM state change detected. (old = 'PoweredOff', new = 'Running') 2021-05-13 08:25:01 (980): Detected: Web Application Enabled (http://localhost:55251) 2021-05-13 08:25:01 (980): Status Report: Job Duration: '64800.000000' 2021-05-13 08:25:01 (980): Status Report: Elapsed Time: '23123.199295' 2021-05-13 08:25:01 (980): Status Report: CPU Time: '22119.828125' 2021-05-13 08:25:01 (980): Preference change detected 2021-05-13 08:25:01 (980): Setting CPU throttle for VM. (100%) 2021-05-13 08:25:02 (980): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds)) 2021-05-13 10:05:24 (980): Status Report: Job Duration: '64800.000000' 2021-05-13 10:05:24 (980): Status Report: Elapsed Time: '29123.199295' 2021-05-13 10:05:24 (980): Status Report: CPU Time: '28134.859375' 2021-05-13 10:12:16 (980): Stopping VM. 2021-05-13 10:13:31 (7636): Detected: vboxwrapper 26197 2021-05-13 10:13:31 (7636): Detected: BOINC client v7.7 2021-05-13 10:13:32 (7636): Detected: VirtualBox VboxManage Interface (Version: 5.2.44) 2021-05-13 10:13:32 (7636): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds) 2021-05-13 10:13:33 (7636): Starting VM using VBoxManage interface. (boinc_e968473acb0b0883, slot#9) 2021-05-13 10:15:25 (7636): Successfully started VM. (PID = '5024') 2021-05-13 10:15:25 (7636): Reporting VM Process ID to BOINC. 2021-05-13 10:15:25 (7636): VM state change detected. (old = 'PoweredOff', new = 'Running') 2021-05-13 10:15:25 (7636): Detected: Web Application Enabled (http://localhost:55251) 2021-05-13 10:15:25 (7636): Status Report: Job Duration: '64800.000000' 2021-05-13 10:15:25 (7636): Status Report: Elapsed Time: '29535.199295' 2021-05-13 10:15:25 (7636): Status Report: CPU Time: '28545.140625' 2021-05-13 10:15:25 (7636): Preference change detected 2021-05-13 10:15:25 (7636): Setting CPU throttle for VM. (100%) 2021-05-13 10:15:26 (7636): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds)) 2021-05-13 11:55:42 (7636): Status Report: Job Duration: '64800.000000' 2021-05-13 11:55:42 (7636): Status Report: Elapsed Time: '35535.246679' 2021-05-13 11:55:42 (7636): Status Report: CPU Time: '33848.859375' 2021-05-13 13:11:05 (7636): Stopping VM. 2021-05-13 13:11:52 (7636): Error in stop VM for VM: -182 Command: VBoxManage -q controlvm "boinc_e968473acb0b0883" savestate Output: 0%...10%...20%...30%...40%...50%...60%...70%...80%...90%... 2021-05-13 13:11:52 (7636): VM did not stop when requested. 2021-05-13 13:11:52 (7636): VM was successfully terminated. 2021-05-13 13:13:27 (8828): Detected: vboxwrapper 26197 2021-05-13 13:13:27 (8828): Detected: BOINC client v7.7 2021-05-13 13:13:28 (8828): Detected: VirtualBox VboxManage Interface (Version: 5.2.44) 2021-05-13 13:13:28 (8828): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds) 2021-05-13 13:13:29 (8828): Starting VM using VBoxManage interface. (boinc_e968473acb0b0883, slot#9) 2021-05-13 13:13:38 (8828): Successfully started VM. (PID = '4068') 2021-05-13 13:13:38 (8828): Reporting VM Process ID to BOINC. 2021-05-13 13:13:38 (8828): Guest Log: BIOS: VirtualBox 5.2.44 2021-05-13 13:13:38 (8828): Guest Log: CPUID EDX: 0x078bfbff 2021-05-13 13:13:38 (8828): Guest Log: BIOS: ata0-0: PCHS=16383/16/63 LCHS=1024/255/63 2021-05-13 13:13:38 (8828): VM state change detected. (old = 'PoweredOff', new = 'Running') 2021-05-13 13:13:38 (8828): Detected: Web Application Enabled (http://localhost:55251) 2021-05-13 13:13:38 (8828): Status Report: Job Duration: '64800.000000' 2021-05-13 13:13:38 (8828): Status Report: Elapsed Time: '40054.588510' 2021-05-13 13:13:38 (8828): Status Report: CPU Time: '38351.921875' 2021-05-13 13:13:38 (8828): Preference change detected 2021-05-13 13:13:38 (8828): Setting CPU throttle for VM. (100%) 2021-05-13 13:13:38 (8828): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds)) 2021-05-13 13:13:40 (8828): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032 2021-05-13 13:13:40 (8828): Guest Log: BIOS: Booting from Hard Disk... 2021-05-13 13:13:45 (8828): Guest Log: BIOS: KBD: unsupported int 16h function 03 2021-05-13 13:13:45 (8828): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 2021-05-13 13:14:09 (8828): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds 2021-05-13 13:14:09 (8828): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000) 2021-05-13 13:14:47 (8828): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log 2021-05-13 13:14:47 (8828): Guest Log: 00:00:00.000406 main Log opened 2021-05-13T17:14:44.269892000Z 2021-05-13 13:14:47 (8828): Guest Log: 00:00:00.001011 main OS Product: Linux 2021-05-13 13:14:47 (8828): Guest Log: 00:00:00.001171 main OS Release: 4.14.157-17.cernvm.x86_64 2021-05-13 13:14:47 (8828): Guest Log: 00:00:00.001208 main OS Version: #1 SMP Wed Dec 4 17:26:45 CET 2019 2021-05-13 13:14:47 (8828): Guest Log: 00:00:00.001241 main Executable: /usr/share/vboxguest52/usr/sbin/VBoxService 2021-05-13 13:14:47 (8828): Guest Log: 00:00:00.001242 main Process ID: 2905 2021-05-13 13:14:47 (8828): Guest Log: 00:00:00.001243 main Package type: LINUX_64BITS_GENERIC 2021-05-13 13:14:47 (8828): Guest Log: 00:00:00.002690 main 5.2.6 r120293 started. Verbose level = 0 2021-05-13 13:15:28 (8828): Guest Log: [INFO] Mounting the shared directory 2021-05-13 13:15:28 (8828): Guest Log: [INFO] Shared directory mounted, enabling vboxmonitor 2021-05-13 13:15:28 (8828): Guest Log: [DEBUG] Testing network connection to cern.ch on port 80 2021-05-13 13:15:29 (8828): Guest Log: [DEBUG] Connection to cern.ch 80 port [tcp/http] succeeded! 2021-05-13 13:15:29 (8828): Guest Log: [DEBUG] 0 2021-05-13 13:15:29 (8828): Guest Log: [DEBUG] Testing VCCS connection to vccs.cern.ch on port 443 2021-05-13 13:15:29 (8828): Guest Log: [DEBUG] Connection to vccs.cern.ch 443 port [tcp/https] succeeded! 2021-05-13 13:15:29 (8828): Guest Log: [DEBUG] 0 2021-05-13 13:15:29 (8828): Guest Log: [DEBUG] Testing connection to Condor server on port 9618 2021-05-13 13:15:29 (8828): Guest Log: [DEBUG] Connection to vocms0840.cern.ch 9618 port [tcp/condor] succeeded! 2021-05-13 13:15:30 (8828): Guest Log: [DEBUG] 0 2021-05-13 13:15:30 (8828): Guest Log: [DEBUG] Testing connection to WMAgent server on port 4080 2021-05-13 13:15:30 (8828): Guest Log: [DEBUG] Connection to vocms0267.cern.ch 4080 port [tcp/lorica-in] succeeded! 2021-05-13 13:15:30 (8828): Guest Log: [DEBUG] 0 2021-05-13 13:15:30 (8828): Guest Log: [DEBUG] Testing connection to Frontier server on port 8080 2021-05-13 13:15:30 (8828): Guest Log: [DEBUG] Connection to cms-frontier.openhtc.io 8080 port [tcp/webcache] succeeded! 2021-05-13 13:15:30 (8828): Guest Log: [DEBUG] 0 2021-05-13 13:15:31 (8828): Guest Log: [INFO] CVMFS and Frontier will use DIRECT instead of an HTTP proxy. 2021-05-13 13:15:32 (8828): Guest Log: [INFO] A local HTTP proxy could help making the network usage 2021-05-13 13:15:32 (8828): Guest Log: [INFO] of this application more efficient. It would also help to 2021-05-13 13:15:32 (8828): Guest Log: [INFO] offload the project servers. 2021-05-13 13:15:32 (8828): Guest Log: [INFO] Details can be found in the project forum. 2021-05-13 13:15:45 (8828): Guest Log: [INFO] Reloading the CVMFS configuration (can take a while) ... 2021-05-13 13:16:03 (8828): Guest Log: [INFO] Probing CVMFS ... 2021-05-13 13:16:14 (8828): 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-05-13 13:16:14 (8828): Guest Log: 2.4.4.0 3671 0 27760 15169 3 1 1790956 4096000 2 65024 0 2 100 0 0 http://s1ral-cvmfs.openhtc.io/cvmfs/grid.cern.ch DIRECT 1 2021-05-13 13:16:17 (8828): Guest Log: [INFO] Reading volunteer information 2021-05-13 13:16:17 (8828): Guest Log: [INFO] Volunteer: Contact (21) 2021-05-13 13:16:17 (8828): Guest Log: [INFO] VMID: 49b2fac1-df25-48d2-a4ee-4612ca6a31f8 2021-05-13 13:16:17 (8828): Guest Log: [INFO] Requesting an X509 credential from LHC@home 2021-05-13 13:16:19 (8828): Guest Log: [INFO] Requesting an X509 credential from vLHC@home-dev 2021-05-13 13:16:21 (8828): Guest Log: [INFO] Running the fast benchmark. 2021-05-13 13:17:17 (8828): Guest Log: [INFO] Machine performance 9.00 HEPSPEC06 2021-05-13 13:17:17 (8828): Guest Log: [INFO] CMS application starting. Check log files. 2021-05-13 13:17:19 (8828): Guest Log: [DEBUG] HTCondor ping 2021-05-13 13:17:21 (8828): Guest Log: [DEBUG] 0 2021-05-13 14:29:42 (8828): Stopping VM. 2021-05-13 14:32:34 (5396): Detected: vboxwrapper 26197 2021-05-13 14:32:34 (5396): Detected: BOINC client v7.7 2021-05-13 14:32:35 (5396): Detected: VirtualBox VboxManage Interface (Version: 5.2.44) 2021-05-13 14:32:35 (5396): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds) 2021-05-13 14:32:36 (5396): Starting VM using VBoxManage interface. (boinc_e968473acb0b0883, slot#9) 2021-05-13 14:32:57 (5396): Successfully started VM. (PID = '240') 2021-05-13 14:32:57 (5396): Reporting VM Process ID to BOINC. 2021-05-13 14:32:57 (5396): VM state change detected. (old = 'PoweredOff', new = 'Running') 2021-05-13 14:32:57 (5396): Detected: Web Application Enabled (http://localhost:55251) 2021-05-13 14:32:57 (5396): Status Report: Job Duration: '64800.000000' 2021-05-13 14:32:57 (5396): Status Report: Elapsed Time: '44593.588510' 2021-05-13 14:32:57 (5396): Status Report: CPU Time: '42607.484375' 2021-05-13 14:32:57 (5396): Preference change detected 2021-05-13 14:32:57 (5396): Setting CPU throttle for VM. (100%) 2021-05-13 14:32:57 (5396): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds)) 2021-05-13 16:13:36 (5396): Status Report: Job Duration: '64800.000000' 2021-05-13 16:13:36 (5396): Status Report: Elapsed Time: '50593.588510' 2021-05-13 16:13:36 (5396): Status Report: CPU Time: '48579.125000' 2021-05-13 16:52:54 (5396): Stopping VM. 2021-05-13 16:53:42 (5396): Error in stop VM for VM: -182 Command: VBoxManage -q controlvm "boinc_e968473acb0b0883" savestate Output: 0%...10%...20%...30%...40%...50%...60%...70%...80%...90%... 2021-05-13 16:53:42 (5396): VM did not stop when requested. 2021-05-13 16:53:42 (5396): VM was successfully terminated. 2021-05-13 17:46:30 (7976): Detected: vboxwrapper 26197 2021-05-13 17:46:30 (7976): Detected: BOINC client v7.7 2021-05-13 17:46:30 (7976): Detected: VirtualBox VboxManage Interface (Version: 5.2.44) 2021-05-13 17:46:30 (7976): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds) 2021-05-13 17:46:31 (7976): Starting VM using VBoxManage interface. (boinc_e968473acb0b0883, slot#9) 2021-05-13 17:46:36 (7976): Successfully started VM. (PID = '9164') 2021-05-13 17:46:36 (7976): Reporting VM Process ID to BOINC. 2021-05-13 17:46:36 (7976): Guest Log: BIOS: VirtualBox 5.2.44 2021-05-13 17:46:36 (7976): Guest Log: CPUID EDX: 0x078bfbff 2021-05-13 17:46:36 (7976): Guest Log: BIOS: ata0-0: PCHS=16383/16/63 LCHS=1024/255/63 2021-05-13 17:46:36 (7976): VM state change detected. (old = 'PoweredOff', new = 'Running') 2021-05-13 17:46:36 (7976): Detected: Web Application Enabled (http://localhost:55251) 2021-05-13 17:46:36 (7976): Status Report: Job Duration: '64800.000000' 2021-05-13 17:46:36 (7976): Status Report: Elapsed Time: '52931.588510' 2021-05-13 17:46:36 (7976): Status Report: CPU Time: '50913.281250' 2021-05-13 17:46:36 (7976): Preference change detected 2021-05-13 17:46:36 (7976): Setting CPU throttle for VM. (100%) 2021-05-13 17:46:36 (7976): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds)) 2021-05-13 17:46:38 (7976): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032 2021-05-13 17:46:38 (7976): Guest Log: BIOS: Booting from Hard Disk... 2021-05-13 17:46:40 (7976): Guest Log: BIOS: KBD: unsupported int 16h function 03 2021-05-13 17:46:40 (7976): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 2021-05-13 17:47:13 (7976): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds 2021-05-13 17:47:13 (7976): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000) 2021-05-13 17:47:49 (7976): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log 2021-05-13 17:47:49 (7976): Guest Log: 00:00:00.000479 main Log opened 2021-05-13T21:47:48.272080000Z 2021-05-13 17:47:49 (7976): Guest Log: 00:00:00.000824 main OS Product: Linux 2021-05-13 17:47:49 (7976): Guest Log: 00:00:00.000921 main OS Release: 4.14.157-17.cernvm.x86_64 2021-05-13 17:47:49 (7976): Guest Log: 00:00:00.000955 main OS Version: #1 SMP Wed Dec 4 17:26:45 CET 2019 2021-05-13 17:47:49 (7976): Guest Log: 00:00:00.000986 main Executable: /usr/share/vboxguest52/usr/sbin/VBoxService 2021-05-13 17:47:49 (7976): Guest Log: 00:00:00.000987 main Process ID: 2905 2021-05-13 17:47:49 (7976): Guest Log: 00:00:00.000988 main Package type: LINUX_64BITS_GENERIC 2021-05-13 17:47:49 (7976): Guest Log: 00:00:00.002870 main 5.2.6 r120293 started. Verbose level = 0 2021-05-13 17:48:29 (7976): Guest Log: [INFO] Mounting the shared directory 2021-05-13 17:48:29 (7976): Guest Log: [INFO] Shared directory mounted, enabling vboxmonitor 2021-05-13 17:48:29 (7976): Guest Log: [DEBUG] Testing network connection to cern.ch on port 80 2021-05-13 17:48:30 (7976): Guest Log: [DEBUG] Connection to cern.ch 80 port [tcp/http] succeeded! 2021-05-13 17:48:30 (7976): Guest Log: [DEBUG] 0 2021-05-13 17:48:30 (7976): Guest Log: [DEBUG] Testing VCCS connection to vccs.cern.ch on port 443 2021-05-13 17:48:31 (7976): Guest Log: [DEBUG] Connection to vccs.cern.ch 443 port [tcp/https] succeeded! 2021-05-13 17:48:31 (7976): Guest Log: [DEBUG] 0 2021-05-13 17:48:31 (7976): Guest Log: [DEBUG] Testing connection to Condor server on port 9618 2021-05-13 17:48:31 (7976): Guest Log: [DEBUG] Connection to vocms0840.cern.ch 9618 port [tcp/condor] succeeded! 2021-05-13 17:48:31 (7976): Guest Log: [DEBUG] 0 2021-05-13 17:48:31 (7976): Guest Log: [DEBUG] Testing connection to WMAgent server on port 4080 2021-05-13 17:48:32 (7976): Guest Log: [DEBUG] Connection to vocms0267.cern.ch 4080 port [tcp/lorica-in] succeeded! 2021-05-13 17:48:32 (7976): Guest Log: [DEBUG] 0 2021-05-13 17:48:32 (7976): Guest Log: [DEBUG] Testing connection to Frontier server on port 8080 2021-05-13 17:48:32 (7976): Guest Log: [DEBUG] Connection to cms-frontier.openhtc.io 8080 port [tcp/webcache] succeeded! 2021-05-13 17:48:32 (7976): Guest Log: [DEBUG] 0 2021-05-13 17:48:33 (7976): Guest Log: [INFO] CVMFS and Frontier will use DIRECT instead of an HTTP proxy. 2021-05-13 17:48:33 (7976): Guest Log: [INFO] A local HTTP proxy could help making the network usage 2021-05-13 17:48:33 (7976): Guest Log: [INFO] of this application more efficient. It would also help to 2021-05-13 17:48:33 (7976): Guest Log: [INFO] offload the project servers. 2021-05-13 17:48:33 (7976): Guest Log: [INFO] Details can be found in the project forum. 2021-05-13 17:48:48 (7976): Guest Log: [INFO] Reloading the CVMFS configuration (can take a while) ... 2021-05-13 17:49:07 (7976): Guest Log: [INFO] Probing CVMFS ... 2021-05-13 17:49:27 (7976): 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-05-13 17:49:27 (7976): Guest Log: 2.4.4.0 3669 1 25288 15171 3 1 1875303 4096000 2 65024 0 2 100 0 0 http://s1fnal-cvmfs.openhtc.io/cvmfs/grid.cern.ch DIRECT 1 2021-05-13 17:49:30 (7976): Guest Log: [INFO] Reading volunteer information 2021-05-13 17:49:30 (7976): Guest Log: [INFO] Volunteer: Contact (21) 2021-05-13 17:49:30 (7976): Guest Log: [INFO] VMID: 49b2fac1-df25-48d2-a4ee-4612ca6a31f8 2021-05-13 17:49:31 (7976): Guest Log: [INFO] Requesting an X509 credential from LHC@home 2021-05-13 17:49:32 (7976): Guest Log: [INFO] Requesting an X509 credential from vLHC@home-dev 2021-05-13 17:49:34 (7976): Guest Log: [INFO] Running the fast benchmark. 2021-05-13 17:50:29 (7976): Guest Log: [INFO] Machine performance 9.42 HEPSPEC06 2021-05-13 17:50:29 (7976): Guest Log: [INFO] CMS application starting. Check log files. 2021-05-13 17:50:31 (7976): Guest Log: [DEBUG] HTCondor ping 2021-05-13 17:50:34 (7976): Guest Log: [DEBUG] 0 2021-05-13 18:23:56 (7976): Stopping VM. 2021-05-13 19:55:56 (9168): Detected: vboxwrapper 26197 2021-05-13 19:55:56 (9168): Detected: BOINC client v7.7 2021-05-13 19:55:57 (9168): Detected: VirtualBox VboxManage Interface (Version: 5.2.44) 2021-05-13 19:55:58 (9168): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds) 2021-05-13 19:55:58 (9168): Starting VM using VBoxManage interface. (boinc_e968473acb0b0883, slot#9) 2021-05-13 19:56:18 (9168): Successfully started VM. (PID = '6236') 2021-05-13 19:56:18 (9168): Reporting VM Process ID to BOINC. 2021-05-13 19:56:18 (9168): VM state change detected. (old = 'PoweredOff', new = 'Running') 2021-05-13 19:56:18 (9168): Detected: Web Application Enabled (http://localhost:55251) 2021-05-13 19:56:18 (9168): Status Report: Job Duration: '64800.000000' 2021-05-13 19:56:18 (9168): Status Report: Elapsed Time: '55159.558362' 2021-05-13 19:56:18 (9168): Status Report: CPU Time: '52734.546875' 2021-05-13 19:56:18 (9168): Preference change detected 2021-05-13 19:56:18 (9168): Setting CPU throttle for VM. (100%) 2021-05-13 19:56:18 (9168): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds)) 2021-05-13 19:56:26 (9168): Guest Log: 00:36:20.107160 timesync vgsvcTimeSyncWorker: Radical host time change: 5 546 415 000 000ns (HostNow=1 620 950 185 978 000 000 ns HostLast=1 620 944 639 563 000 000 ns) 2021-05-13 19:56:36 (9168): Guest Log: 00:36:30.108026 timesync vgsvcTimeSyncWorker: Radical guest time change: 5 546 489 112 000ns (GuestNow=1 620 950 195 978 919 000 ns GuestLast=1 620 944 649 489 807 000 ns fSetTimeLastLoop=true ) 2021-05-13 20:56:45 (9168): Stopping VM. 2021-05-13 20:57:36 (9168): Error in stop VM for VM: -182 Command: VBoxManage -q controlvm "boinc_e968473acb0b0883" savestate Output: 0%...10%...20%...30%...40%...50%...60%...70%...80%...90%... 2021-05-13 20:57:36 (9168): VM did not stop when requested. 2021-05-13 20:57:36 (9168): VM was successfully terminated. 2021-05-13 21:13:07 (6664): Detected: vboxwrapper 26197 2021-05-13 21:13:07 (6664): Detected: BOINC client v7.7 2021-05-13 21:13:07 (6664): Detected: VirtualBox VboxManage Interface (Version: 5.2.44) 2021-05-13 21:13:08 (6664): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds) 2021-05-13 21:13:10 (6664): Starting VM using VBoxManage interface. (boinc_e968473acb0b0883, slot#9) 2021-05-13 21:13:21 (6664): Successfully started VM. (PID = '9168') 2021-05-13 21:13:21 (6664): Reporting VM Process ID to BOINC. 2021-05-13 21:13:21 (6664): Guest Log: BIOS: VirtualBox 5.2.44 2021-05-13 21:13:21 (6664): Guest Log: CPUID EDX: 0x078bfbff 2021-05-13 21:13:21 (6664): Guest Log: BIOS: ata0-0: PCHS=16383/16/63 LCHS=1024/255/63 2021-05-13 21:13:21 (6664): VM state change detected. (old = 'PoweredOff', new = 'Running') 2021-05-13 21:13:21 (6664): Detected: Web Application Enabled (http://localhost:55251) 2021-05-13 21:13:21 (6664): Status Report: Job Duration: '64800.000000' 2021-05-13 21:13:21 (6664): Status Report: Elapsed Time: '58742.784669' 2021-05-13 21:13:21 (6664): Status Report: CPU Time: '55928.515625' 2021-05-13 21:13:21 (6664): Preference change detected 2021-05-13 21:13:21 (6664): Setting CPU throttle for VM. (100%) 2021-05-13 21:13:21 (6664): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds)) 2021-05-13 21:13:23 (6664): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032 2021-05-13 21:13:23 (6664): Guest Log: BIOS: Booting from Hard Disk... 2021-05-13 21:13:26 (6664): Guest Log: BIOS: KBD: unsupported int 16h function 03 2021-05-13 21:13:26 (6664): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 2021-05-13 21:13:48 (6664): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds 2021-05-13 21:13:48 (6664): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000) 2021-05-13 21:14:24 (6664): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log 2021-05-13 21:14:24 (6664): Guest Log: 00:00:00.000427 main Log opened 2021-05-14T01:14:22.803166000Z 2021-05-13 21:14:24 (6664): Guest Log: 00:00:00.000765 main OS Product: Linux 2021-05-13 21:14:24 (6664): Guest Log: 00:00:00.000873 main OS Release: 4.14.157-17.cernvm.x86_64 2021-05-13 21:14:24 (6664): Guest Log: 00:00:00.000906 main OS Version: #1 SMP Wed Dec 4 17:26:45 CET 2019 2021-05-13 21:14:24 (6664): Guest Log: 00:00:00.000984 main Executable: /usr/share/vboxguest52/usr/sbin/VBoxService 2021-05-13 21:14:24 (6664): Guest Log: 00:00:00.000985 main Process ID: 2905 2021-05-13 21:14:24 (6664): Guest Log: 00:00:00.000986 main Package type: LINUX_64BITS_GENERIC 2021-05-13 21:14:24 (6664): Guest Log: 00:00:00.002239 main 5.2.6 r120293 started. Verbose level = 0 2021-05-13 21:14:56 (6664): Guest Log: [INFO] Mounting the shared directory 2021-05-13 21:14:56 (6664): Guest Log: [INFO] Shared directory mounted, enabling vboxmonitor 2021-05-13 21:14:56 (6664): Guest Log: [DEBUG] Testing network connection to cern.ch on port 80 2021-05-13 21:14:56 (6664): Guest Log: [DEBUG] Connection to cern.ch 80 port [tcp/http] succeeded! 2021-05-13 21:14:56 (6664): Guest Log: [DEBUG] 0 2021-05-13 21:14:56 (6664): Guest Log: [DEBUG] Testing VCCS connection to vccs.cern.ch on port 443 2021-05-13 21:14:57 (6664): Guest Log: [DEBUG] Connection to vccs.cern.ch 443 port [tcp/https] succeeded! 2021-05-13 21:14:57 (6664): Guest Log: [DEBUG] 0 2021-05-13 21:14:57 (6664): Guest Log: [DEBUG] Testing connection to Condor server on port 9618 2021-05-13 21:14:58 (6664): Guest Log: [DEBUG] Connection to vocms0840.cern.ch 9618 port [tcp/condor] succeeded! 2021-05-13 21:14:58 (6664): Guest Log: [DEBUG] 0 2021-05-13 21:14:58 (6664): Guest Log: [DEBUG] Testing connection to WMAgent server on port 4080 2021-05-13 21:14:58 (6664): Guest Log: [DEBUG] Connection to vocms0267.cern.ch 4080 port [tcp/lorica-in] succeeded! 2021-05-13 21:14:58 (6664): Guest Log: [DEBUG] 0 2021-05-13 21:14:58 (6664): Guest Log: [DEBUG] Testing connection to Frontier server on port 8080 2021-05-13 21:14:58 (6664): Guest Log: [DEBUG] Connection to cms-frontier.openhtc.io 8080 port [tcp/webcache] succeeded! 2021-05-13 21:14:58 (6664): Guest Log: [DEBUG] 0 2021-05-13 21:14:59 (6664): Guest Log: [INFO] CVMFS and Frontier will use DIRECT instead of an HTTP proxy. 2021-05-13 21:14:59 (6664): Guest Log: [INFO] A local HTTP proxy could help making the network usage 2021-05-13 21:14:59 (6664): Guest Log: [INFO] of this application more efficient. It would also help to 2021-05-13 21:14:59 (6664): Guest Log: [INFO] offload the project servers. 2021-05-13 21:14:59 (6664): Guest Log: [INFO] Details can be found in the project forum. 2021-05-13 21:15:11 (6664): Guest Log: [INFO] Reloading the CVMFS configuration (can take a while) ... 2021-05-13 21:15:26 (6664): Guest Log: [INFO] Probing CVMFS ... 2021-05-13 21:15:36 (6664): 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-05-13 21:15:36 (6664): Guest Log: 2.4.4.0 3669 0 25780 15173 3 1 2015111 4096001 2 65024 0 2 100 0 0 http://s1cern-cvmfs.openhtc.io/cvmfs/grid.cern.ch DIRECT 1 2021-05-13 21:15:39 (6664): Guest Log: [INFO] Reading volunteer information 2021-05-13 21:15:39 (6664): Guest Log: [INFO] Volunteer: Contact (21) 2021-05-13 21:15:39 (6664): Guest Log: [INFO] VMID: 49b2fac1-df25-48d2-a4ee-4612ca6a31f8 2021-05-13 21:15:40 (6664): Guest Log: [INFO] Requesting an X509 credential from LHC@home 2021-05-13 21:15:41 (6664): Guest Log: [INFO] Requesting an X509 credential from vLHC@home-dev 2021-05-13 21:15:43 (6664): Guest Log: [INFO] Running the fast benchmark. 2021-05-13 21:16:39 (6664): Guest Log: [INFO] Machine performance 9.02 HEPSPEC06 2021-05-13 21:16:39 (6664): Guest Log: [INFO] CMS application starting. Check log files. 2021-05-13 21:16:42 (6664): Guest Log: [DEBUG] HTCondor ping 2021-05-13 21:16:44 (6664): Guest Log: [DEBUG] 0 2021-05-13 22:13:39 (6664): Stopping VM. 2021-05-13 22:14:38 (6664): Error in stop VM for VM: -182 Command: VBoxManage -q controlvm "boinc_e968473acb0b0883" savestate Output: 0%...10%...20%...30%...40%...50%...60%...70%...80%...90%... 2021-05-13 22:14:38 (6664): VM did not stop when requested. 2021-05-13 22:14:38 (6664): VM was successfully terminated. 2021-05-13 23:35:05 (5396): Detected: vboxwrapper 26197 2021-05-13 23:35:05 (5396): Detected: BOINC client v7.7 2021-05-13 23:35:06 (5396): Detected: VirtualBox VboxManage Interface (Version: 5.2.44) 2021-05-13 23:35:06 (5396): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds) 2021-05-13 23:35:07 (5396): Starting VM using VBoxManage interface. (boinc_e968473acb0b0883, slot#9) 2021-05-13 23:35:11 (5396): Successfully started VM. (PID = '7704') 2021-05-13 23:35:11 (5396): Reporting VM Process ID to BOINC. 2021-05-13 23:35:11 (5396): Guest Log: BIOS: VirtualBox 5.2.44 2021-05-13 23:35:11 (5396): Guest Log: CPUID EDX: 0x078bfbff 2021-05-13 23:35:11 (5396): Guest Log: BIOS: ata0-0: PCHS=16383/16/63 LCHS=1024/255/63 2021-05-13 23:35:11 (5396): VM state change detected. (old = 'PoweredOff', new = 'Running') 2021-05-13 23:35:11 (5396): Detected: Web Application Enabled (http://localhost:55251) 2021-05-13 23:35:12 (5396): Status Report: Job Duration: '64800.000000' 2021-05-13 23:35:12 (5396): Status Report: Elapsed Time: '62190.821775' 2021-05-13 23:35:12 (5396): Status Report: CPU Time: '58780.734375' 2021-05-13 23:35:12 (5396): Preference change detected 2021-05-13 23:35:12 (5396): Setting CPU throttle for VM. (100%) 2021-05-13 23:35:12 (5396): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds)) 2021-05-13 23:35:14 (5396): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032 2021-05-13 23:35:14 (5396): Guest Log: BIOS: Booting from Hard Disk... 2021-05-13 23:35:16 (5396): Guest Log: BIOS: KBD: unsupported int 16h function 03 2021-05-13 23:35:16 (5396): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 2021-05-13 23:35:37 (5396): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds 2021-05-13 23:35:37 (5396): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000) 2021-05-13 23:36:10 (5396): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log 2021-05-13 23:36:10 (5396): Guest Log: 00:00:00.000260 main Log opened 2021-05-14T03:36:07.742766000Z 2021-05-13 23:36:10 (5396): Guest Log: 00:00:00.000801 main OS Product: Linux 2021-05-13 23:36:10 (5396): Guest Log: 00:00:00.000900 main OS Release: 4.14.157-17.cernvm.x86_64 2021-05-13 23:36:10 (5396): Guest Log: 00:00:00.000933 main OS Version: #1 SMP Wed Dec 4 17:26:45 CET 2019 2021-05-13 23:36:10 (5396): Guest Log: 00:00:00.000964 main Executable: /usr/share/vboxguest52/usr/sbin/VBoxService 2021-05-13 23:36:10 (5396): Guest Log: 00:00:00.000965 main Process ID: 2906 2021-05-13 23:36:10 (5396): Guest Log: 00:00:00.000966 main Package type: LINUX_64BITS_GENERIC 2021-05-13 23:36:10 (5396): Guest Log: 00:00:00.002308 main 5.2.6 r120293 started. Verbose level = 0 2021-05-13 23:36:35 (5396): Guest Log: [INFO] Mounting the shared directory 2021-05-13 23:36:35 (5396): Guest Log: [INFO] Shared directory mounted, enabling vboxmonitor 2021-05-13 23:36:35 (5396): Guest Log: [DEBUG] Testing network connection to cern.ch on port 80 2021-05-13 23:36:36 (5396): Guest Log: [DEBUG] Connection to cern.ch 80 port [tcp/http] succeeded! 2021-05-13 23:36:36 (5396): Guest Log: [DEBUG] 0 2021-05-13 23:36:36 (5396): Guest Log: [DEBUG] Testing VCCS connection to vccs.cern.ch on port 443 2021-05-13 23:36:36 (5396): Guest Log: [DEBUG] Connection to vccs.cern.ch 443 port [tcp/https] succeeded! 2021-05-13 23:36:36 (5396): Guest Log: [DEBUG] 0 2021-05-13 23:36:36 (5396): Guest Log: [DEBUG] Testing connection to Condor server on port 9618 2021-05-13 23:36:37 (5396): Guest Log: [DEBUG] Connection to vocms0840.cern.ch 9618 port [tcp/condor] succeeded! 2021-05-13 23:36:37 (5396): Guest Log: [DEBUG] 0 2021-05-13 23:36:37 (5396): Guest Log: [DEBUG] Testing connection to WMAgent server on port 4080 2021-05-13 23:36:37 (5396): Guest Log: [DEBUG] Connection to vocms0267.cern.ch 4080 port [tcp/lorica-in] succeeded! 2021-05-13 23:36:37 (5396): Guest Log: [DEBUG] 0 2021-05-13 23:36:38 (5396): Guest Log: [DEBUG] Testing connection to Frontier server on port 8080 2021-05-13 23:36:38 (5396): Guest Log: [DEBUG] Connection to cms-frontier.openhtc.io 8080 port [tcp/webcache] succeeded! 2021-05-13 23:36:38 (5396): Guest Log: [DEBUG] 0 2021-05-13 23:36:38 (5396): Guest Log: [INFO] CVMFS and Frontier will use DIRECT instead of an HTTP proxy. 2021-05-13 23:36:38 (5396): Guest Log: [INFO] A local HTTP proxy could help making the network usage 2021-05-13 23:36:38 (5396): Guest Log: [INFO] of this application more efficient. It would also help to 2021-05-13 23:36:38 (5396): Guest Log: [INFO] offload the project servers. 2021-05-13 23:36:38 (5396): Guest Log: [INFO] Details can be found in the project forum. 2021-05-13 23:36:50 (5396): Guest Log: [INFO] Reloading the CVMFS configuration (can take a while) ... 2021-05-13 23:37:05 (5396): Guest Log: [INFO] Probing CVMFS ... 2021-05-13 23:37:16 (5396): 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-05-13 23:37:16 (5396): Guest Log: 2.4.4.0 3670 0 27320 15173 3 1 2170962 4096001 2 65024 0 2 100 0 0 http://s1bnl-cvmfs.openhtc.io/cvmfs/grid.cern.ch DIRECT 1 2021-05-13 23:37:18 (5396): Guest Log: [INFO] Reading volunteer information 2021-05-13 23:37:18 (5396): Guest Log: [INFO] Volunteer: Contact (21) 2021-05-13 23:37:18 (5396): Guest Log: [INFO] VMID: 49b2fac1-df25-48d2-a4ee-4612ca6a31f8 2021-05-13 23:37:18 (5396): Guest Log: [INFO] Requesting an X509 credential from LHC@home 2021-05-13 23:37:20 (5396): Guest Log: [INFO] Requesting an X509 credential from vLHC@home-dev 2021-05-13 23:37:22 (5396): Guest Log: [INFO] Running the fast benchmark. 2021-05-13 23:38:19 (5396): Guest Log: [INFO] Machine performance 8.67 HEPSPEC06 2021-05-13 23:38:19 (5396): Guest Log: [INFO] CMS application starting. Check log files. 2021-05-13 23:38:22 (5396): Guest Log: [DEBUG] HTCondor ping 2021-05-13 23:38:24 (5396): Guest Log: [DEBUG] 0 2021-05-14 00:18:57 (5396): Powering off VM. 2021-05-14 00:23:59 (5396): VM did not power off when requested. 2021-05-14 00:23:59 (5396): VM was successfully terminated. 2021-05-14 00:23:59 (5396): Deregistering VM. (boinc_e968473acb0b0883, slot#9) 2021-05-14 00:23:59 (5396): Removing network bandwidth throttle group from VM. 2021-05-14 00:23:59 (5396): Removing VM from VirtualBox. 00:24:05 (5396): called boinc_finish(0) </stderr_txt> ]]>
©2024 CERN