| Name | Theory_728661_1572127077.360271_0 |
| Workunit | 125585594 |
| Created | 26 Oct 2019, 21:57:59 UTC |
| Sent | 27 Oct 2019, 1:33:34 UTC |
| Report deadline | 27 Nov 2019, 1:33:34 UTC |
| Received | 28 Nov 2019, 9:13:11 UTC |
| Server state | Over |
| Outcome | Success |
| Client state | Done |
| Exit status | 0 (0x00000000) |
| Computer ID | 10586300 |
| Run time | 1 days 12 hours 5 min 6 sec |
| CPU time | 2 days 23 hours 14 min 39 sec |
| Validate state | Valid |
| Credit | 2,305.83 |
| Device peak FLOPS | 7.67 GFLOPS |
| Application version | Theory Simulation v263.98 (vbox64_mt_mcore) windows_x86_64 |
| Peak working set size | 79.29 MB |
| Peak swap size | 113.07 MB |
| Peak disk usage | 1.95 GB |
Stderr output
<core_client_version>7.14.2</core_client_version> <![CDATA[ <stderr_txt> ) 2019-10-29 19:43:24 (10956): VM state change detected. (old = 'Paused', new = 'Running') 2019-10-29 19:45:14 (10956): Guest Log: [INFO] Job finished in slot2 with 0. 2019-10-29 19:45:30 (10956): Guest Log: [INFO] New Job Starting in slot2 2019-10-29 19:45:30 (10956): Guest Log: [INFO] Condor JobID: 513324.28 in slot2 2019-10-29 19:45:35 (10956): Guest Log: [INFO] MCPlots JobID: 51862704 in slot2 2019-10-29 19:45:41 (10956): Guest Log: [INFO] ===> [runRivet] Tue Oct 29 18:45:19 AEST 2019 [boinc pp jets 7000 350 - pythia8 8.226 tune-2c 100000 144] 2019-10-29 21:03:44 (10956): Status Report: Job Duration: '129600.000000' 2019-10-29 21:03:44 (10956): Status Report: Elapsed Time: '51669.753170' 2019-10-29 21:03:44 (10956): Status Report: CPU Time: '101107.375000' 2019-10-29 22:35:17 (10956): VM state change detected. (old = 'Running', new = 'Paused') 2019-10-29 22:35:53 (10956): Stopping VM. 2019-10-29 22:35:54 (10956): Error in stop VM for VM: -108 Command: VBoxManage -q controlvm "boinc_11bbdcf43962130e" savestate Output: 2019-10-29 22:35:54 (10956): VM did not stop when requested. 2019-10-29 22:35:54 (10956): VM was successfully terminated. 2019-10-30 20:06:32 (6908): Detected: vboxwrapper 26197 2019-10-30 20:06:32 (6908): Detected: BOINC client v7.7 2019-10-30 20:06:33 (6908): Detected: VirtualBox VboxManage Interface (Version: 5.2.28) 2019-10-30 20:06:33 (6908): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds) 2019-10-30 20:06:33 (6908): Starting VM using VBoxManage interface. (boinc_11bbdcf43962130e, slot#3) 2019-10-30 20:06:38 (6908): Successfully started VM. (PID = '796') 2019-10-30 20:06:38 (6908): Reporting VM Process ID to BOINC. 2019-10-30 20:06:38 (6908): Guest Log: BIOS: VirtualBox 5.2.28 2019-10-30 20:06:38 (6908): Guest Log: CPUID EDX: 0x178bfbff 2019-10-30 20:06:38 (6908): Guest Log: BIOS: ata0-0: PCHS=16383/16/63 LCHS=1024/255/63 2019-10-30 20:06:38 (6908): VM state change detected. (old = 'PoweredOff', new = 'Running') 2019-10-30 20:06:38 (6908): Detected: Web Application Enabled (http://localhost:60730) 2019-10-30 20:06:38 (6908): Detected: Remote Desktop Enabled (localhost:60731) 2019-10-30 20:06:38 (6908): Status Report: Job Duration: '129600.000000' 2019-10-30 20:06:38 (6908): Status Report: Elapsed Time: '57156.753170' 2019-10-30 20:06:38 (6908): Status Report: CPU Time: '112026.687500' 2019-10-30 20:06:38 (6908): Preference change detected 2019-10-30 20:06:38 (6908): Setting CPU throttle for VM. (100%) 2019-10-30 20:06:39 (6908): Setting network throttle for VM. (250KB) 2019-10-30 20:06:39 (6908): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds)) 2019-10-30 20:06:40 (6908): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032 2019-10-30 20:06:40 (6908): Guest Log: BIOS: Booting from Hard Disk... 2019-10-30 20:06:42 (6908): Guest Log: BIOS: KBD: unsupported int 16h function 03 2019-10-30 20:06:42 (6908): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 2019-10-30 20:07:05 (6908): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds 2019-10-30 20:07:05 (6908): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000) 2019-10-30 20:07:41 (6908): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log 2019-10-30 20:07:41 (6908): Guest Log: 00:00:00.000577 main Log opened 2019-10-30T09:07:42.303371000Z 2019-10-30 20:07:41 (6908): Guest Log: 00:00:00.000954 main OS Product: Linux 2019-10-30 20:07:41 (6908): Guest Log: 00:00:00.001006 main OS Release: 4.14.44-11.cernvm.x86_64 2019-10-30 20:07:41 (6908): Guest Log: 00:00:00.001206 main OS Version: #1 SMP Mon May 28 16:49:00 CEST 2018 2019-10-30 20:07:41 (6908): Guest Log: 00:00:00.001257 main Executable: /usr/share/vboxguest52/usr/sbin/VBoxService 2019-10-30 20:07:41 (6908): Guest Log: 00:00:00.001259 main Process ID: 2940 2019-10-30 20:07:41 (6908): Guest Log: 00:00:00.001261 main Package type: LINUX_64BITS_GENERIC 2019-10-30 20:07:41 (6908): Guest Log: 00:00:00.003369 main 5.2.6 r120293 started. Verbose level = 0 2019-10-30 20:07:50 (6908): VM state change detected. (old = 'Running', new = 'Paused') 2019-10-30 20:22:06 (6908): VM state change detected. (old = 'Paused', new = 'Running') 2019-10-30 20:22:30 (6908): Guest Log: [INFO] Mounting the shared directory 2019-10-30 20:22:30 (6908): Guest Log: [INFO] Shared directory mounted, enabling vboxmonitor 2019-10-30 20:22:30 (6908): Guest Log: [DEBUG] Testing network connection to cern.ch on port 80 2019-10-30 20:22:31 (6908): Guest Log: [DEBUG] Connection to cern.ch 80 port [tcp/http] succeeded! 2019-10-30 20:22:31 (6908): Guest Log: [DEBUG] 0 2019-10-30 20:22:31 (6908): Guest Log: [DEBUG] Testing VCCS connection to vccs.cern.ch on port 443 2019-10-30 20:22:31 (6908): Guest Log: [DEBUG] Connection to vccs.cern.ch 443 port [tcp/https] succeeded! 2019-10-30 20:22:31 (6908): Guest Log: [DEBUG] 0 2019-10-30 20:22:32 (6908): Guest Log: [DEBUG] Testing connection to Condor server on port 9618 2019-10-30 20:22:32 (6908): Guest Log: [DEBUG] Connection to vccondor01.cern.ch 9618 port [tcp/condor] succeeded! 2019-10-30 20:22:32 (6908): Guest Log: [DEBUG] 0 2019-10-30 20:23:40 (6908): Guest Log: [DEBUG] Probing CVMFS ... 2019-10-30 20:23:42 (6908): VM state change detected. (old = 'Running', new = 'Paused') 2019-10-30 20:24:02 (6908): Guest Log: Probing /cvmfs/grid.cern.ch... OK 2019-10-30 20:24:02 (6908): VM state change detected. (old = 'Paused', new = 'Running') 2019-10-30 20:24:04 (6908): 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-10-30 20:24:04 (6908): Guest Log: 2.4.4.0 3639 1 25804 10615 3 1 677259 10240001 2 65024 0 3 100 0 0 http://s1asgc-cvmfs.openhtc.io:8080/cvmfs/grid.cern.ch DIRECT 1 2019-10-30 20:24:10 (6908): Guest Log: [INFO] Reading volunteer information 2019-10-30 20:24:10 (6908): Guest Log: [INFO] Volunteer: Duff_Moss (127134) 2019-10-30 20:24:10 (6908): Guest Log: [INFO] VMID: e5b01749-9626-457a-8945-16c64690aa2f 2019-10-30 20:24:11 (6908): Guest Log: [INFO] Requesting an X509 credential from LHC@home 2019-10-30 20:24:13 (6908): Guest Log: [INFO] Running the fast benchmark. 2019-10-30 20:27:13 (6908): Guest Log: [INFO] Machine performance 5.61 HEPSPEC06 2019-10-30 20:27:14 (6908): Guest Log: [INFO] Theory application starting. Check log files. 2019-10-30 20:27:14 (6908): Guest Log: [DEBUG] HTCondor ping 2019-10-30 20:27:18 (6908): Guest Log: [DEBUG] 0 2019-10-30 20:28:03 (6908): VM state change detected. (old = 'Running', new = 'Paused') 2019-10-30 20:31:07 (6908): VM state change detected. (old = 'Paused', new = 'Running') 2019-10-30 20:31:13 (6908): Guest Log: [INFO] New Job Starting in slot2 2019-10-30 20:31:13 (6908): Guest Log: [INFO] Condor JobID: 513642.63 in slot2 2019-10-30 20:31:18 (6908): Guest Log: [INFO] New Job Starting in slot1 2019-10-30 20:31:18 (6908): Guest Log: [INFO] Condor JobID: 513642.62 in slot1 2019-10-30 20:31:18 (6908): Guest Log: [INFO] MCPlots JobID: 51903378 in slot2 2019-10-30 20:31:23 (6908): Guest Log: [INFO] MCPlots JobID: 51903382 in slot1 2019-10-30 20:31:23 (6908): Guest Log: [INFO] ===> [runRivet] Wed Oct 30 19:27:50 AEST 2019 [boinc pp jets 7000 400 - pythia6 6.428 391 100000 146] 2019-10-30 20:31:28 (6908): Guest Log: [INFO] ===> [runRivet] Wed Oct 30 19:27:55 AEST 2019 [boinc pp jets 7000 400 - pythia6 6.426 353 100000 146] 2019-10-30 22:04:25 (6908): Status Report: Job Duration: '129600.000000' 2019-10-30 22:04:25 (6908): Status Report: Elapsed Time: '63156.753170' 2019-10-30 22:04:25 (6908): Status Report: CPU Time: '125203.500000' 2019-10-30 22:18:02 (6908): VM state change detected. (old = 'Running', new = 'Paused') 2019-10-30 22:18:13 (6908): Stopping VM. 2019-10-30 22:18:13 (6908): Error in stop VM for VM: -108 Command: VBoxManage -q controlvm "boinc_11bbdcf43962130e" savestate Output: 2019-10-30 22:18:13 (6908): VM did not stop when requested. 2019-10-30 22:18:13 (6908): VM was successfully terminated. 2019-11-01 20:52:56 (10312): Detected: vboxwrapper 26197 2019-11-01 20:52:56 (10312): Detected: BOINC client v7.7 2019-11-01 20:52:57 (10312): Detected: VirtualBox VboxManage Interface (Version: 5.2.28) 2019-11-01 20:52:58 (10312): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds) 2019-11-01 20:52:58 (10312): Starting VM using VBoxManage interface. (boinc_11bbdcf43962130e, slot#3) 2019-11-01 20:53:03 (10312): Successfully started VM. (PID = '13256') 2019-11-01 20:53:03 (10312): Reporting VM Process ID to BOINC. 2019-11-01 20:53:03 (10312): Guest Log: BIOS: VirtualBox 5.2.28 2019-11-01 20:53:03 (10312): Guest Log: CPUID EDX: 0x178bfbff 2019-11-01 20:53:03 (10312): Guest Log: BIOS: ata0-0: PCHS=16383/16/63 LCHS=1024/255/63 2019-11-01 20:53:03 (10312): VM state change detected. (old = 'PoweredOff', new = 'Running') 2019-11-01 20:53:03 (10312): Detected: Web Application Enabled (http://localhost:60730) 2019-11-01 20:53:03 (10312): Detected: Remote Desktop Enabled (localhost:60731) 2019-11-01 20:53:03 (10312): Status Report: Job Duration: '129600.000000' 2019-11-01 20:53:03 (10312): Status Report: Elapsed Time: '63970.753170' 2019-11-01 20:53:03 (10312): Status Report: CPU Time: '126803.906250' 2019-11-01 20:53:03 (10312): Preference change detected 2019-11-01 20:53:03 (10312): Setting CPU throttle for VM. (100%) 2019-11-01 20:53:03 (10312): Setting network throttle for VM. (250KB) 2019-11-01 20:53:03 (10312): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds)) 2019-11-01 20:53:05 (10312): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032 2019-11-01 20:53:05 (10312): Guest Log: BIOS: Booting from Hard Disk... 2019-11-01 20:53:07 (10312): Guest Log: BIOS: KBD: unsupported int 16h function 03 2019-11-01 20:53:07 (10312): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 2019-11-01 20:53:30 (10312): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds 2019-11-01 20:53:30 (10312): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000) 2019-11-01 20:54:01 (10312): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log 2019-11-01 20:54:01 (10312): Guest Log: 00:00:00.000309 main Log opened 2019-11-01T09:54:05.086586000Z 2019-11-01 20:54:01 (10312): Guest Log: 00:00:00.000551 main OS Product: Linux 2019-11-01 20:54:01 (10312): Guest Log: 00:00:00.000607 main OS Release: 4.14.44-11.cernvm.x86_64 2019-11-01 20:54:01 (10312): Guest Log: 00:00:00.000730 main OS Version: #1 SMP Mon May 28 16:49:00 CEST 2018 2019-11-01 20:54:01 (10312): Guest Log: 00:00:00.000783 main Executable: /usr/share/vboxguest52/usr/sbin/VBoxService 2019-11-01 20:54:01 (10312): Guest Log: 00:00:00.000785 main Process ID: 2935 2019-11-01 20:54:01 (10312): Guest Log: 00:00:00.000787 main Package type: LINUX_64BITS_GENERIC 2019-11-01 20:54:01 (10312): Guest Log: 00:00:00.003048 main 5.2.6 r120293 started. Verbose level = 0 2019-11-01 20:54:07 (10312): VM state change detected. (old = 'Running', new = 'Paused') 2019-11-01 20:54:20 (10312): VM state change detected. (old = 'Paused', new = 'Running') 2019-11-01 20:54:30 (10312): VM state change detected. (old = 'Running', new = 'Paused') 2019-11-01 20:55:00 (10312): VM state change detected. (old = 'Paused', new = 'Running') 2019-11-01 20:55:21 (10312): Guest Log: [INFO] Mounting the shared directory 2019-11-01 20:55:21 (10312): Guest Log: [INFO] Shared directory mounted, enabling vboxmonitor 2019-11-01 20:55:26 (10312): Guest Log: [DEBUG] Testing network connection to cern.ch on port 80 2019-11-01 20:55:26 (10312): Guest Log: [DEBUG] Connection to cern.ch 80 port [tcp/http] succeeded! 2019-11-01 20:55:26 (10312): Guest Log: [DEBUG] 0 2019-11-01 20:55:26 (10312): Guest Log: [DEBUG] Testing VCCS connection to vccs.cern.ch on port 443 2019-11-01 20:55:27 (10312): Guest Log: [DEBUG] Connection to vccs.cern.ch 443 port [tcp/https] succeeded! 2019-11-01 20:55:27 (10312): Guest Log: [DEBUG] 0 2019-11-01 20:55:27 (10312): Guest Log: [DEBUG] Testing connection to Condor server on port 9618 2019-11-01 20:55:28 (10312): Guest Log: [DEBUG] Connection to vccondor01.cern.ch 9618 port [tcp/condor] succeeded! 2019-11-01 20:55:28 (10312): Guest Log: [DEBUG] 0 2019-11-01 20:56:35 (10312): VM state change detected. (old = 'Running', new = 'Paused') 2019-11-01 20:56:45 (10312): VM state change detected. (old = 'Paused', new = 'Running') 2019-11-01 20:56:47 (10312): Guest Log: [DEBUG] Probing CVMFS ... 2019-11-01 20:56:49 (10312): Guest Log: Probing /cvmfs/grid.cern.ch... OK 2019-11-01 20:56:51 (10312): 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-11-01 20:56:51 (10312): Guest Log: 2.4.4.0 3633 1 25824 10633 3 1 690874 10240000 2 65024 0 3 100 1 1 http://s1ihep-cvmfs.openhtc.io/cvmfs/grid.cern.ch DIRECT 1 2019-11-01 20:56:58 (10312): Guest Log: [INFO] Reading volunteer information 2019-11-01 20:56:58 (10312): Guest Log: [INFO] Volunteer: Duff_Moss (127134) 2019-11-01 20:56:58 (10312): Guest Log: [INFO] VMID: e5b01749-9626-457a-8945-16c64690aa2f 2019-11-01 20:56:58 (10312): Guest Log: [INFO] Requesting an X509 credential from LHC@home 2019-11-01 20:57:03 (10312): Guest Log: [INFO] Running the fast benchmark. 2019-11-01 21:00:11 (10312): Guest Log: [INFO] Machine performance 5.37 HEPSPEC06 2019-11-01 21:00:11 (10312): Guest Log: [INFO] Theory application starting. Check log files. 2019-11-01 21:00:12 (10312): Guest Log: [DEBUG] HTCondor ping 2019-11-01 21:00:16 (10312): Guest Log: [DEBUG] 0 2019-11-01 21:00:55 (10312): Guest Log: [INFO] New Job Starting in slot1 2019-11-01 21:00:55 (10312): Guest Log: [INFO] Condor JobID: 513819.4 in slot1 2019-11-01 21:00:56 (10312): Guest Log: [INFO] New Job Starting in slot2 2019-11-01 21:00:56 (10312): Guest Log: [INFO] Condor JobID: 513819.6 in slot2 2019-11-01 21:01:00 (10312): Guest Log: [INFO] MCPlots JobID: 51931766 in slot1 2019-11-01 21:01:01 (10312): Guest Log: [INFO] MCPlots JobID: 51931797 in slot2 2019-11-01 21:01:05 (10312): Guest Log: [INFO] ===> [runRivet] Fri Nov 1 20:00:45 AEST 2019 [boinc ee zhad 206 - - pythia6 6.425 350 100000 146] 2019-11-01 21:01:06 (10312): Guest Log: [INFO] ===> [runRivet] Fri Nov 1 20:00:46 AEST 2019 [boinc ee zhad 43.6 - - pythia8 8.212 tune-cmw 100000 146] 2019-11-01 21:03:32 (10312): VM state change detected. (old = 'Running', new = 'Paused') 2019-11-01 21:04:06 (10312): VM state change detected. (old = 'Paused', new = 'Running') 2019-11-01 21:05:23 (10312): VM state change detected. (old = 'Running', new = 'Paused') 2019-11-01 21:05:33 (10312): VM state change detected. (old = 'Paused', new = 'Running') 2019-11-01 21:16:16 (10312): Guest Log: [INFO] Job finished in slot2 with 0. 2019-11-01 21:21:22 (10312): Guest Log: [INFO] New Job Starting in slot2 2019-11-01 21:21:22 (10312): Guest Log: [INFO] Condor JobID: 513819.139 in slot2 2019-11-01 21:21:28 (10312): Guest Log: [INFO] MCPlots JobID: 51931706 in slot2 2019-11-01 21:21:33 (10312): Guest Log: [INFO] ===> [runRivet] Fri Nov 1 20:21:22 AEST 2019 [boinc pp zinclusive 7000 -,-,50,130 - herwig++ 2.6.2 LHC-UE-EE-4-CTEQ6L1 100000 146] 2019-11-01 21:34:23 (10312): Guest Log: [INFO] Job finished in slot1 with 0. 2019-11-01 21:34:39 (10312): Guest Log: [INFO] New Job Starting in slot1 2019-11-01 21:34:39 (10312): Guest Log: [INFO] Condor JobID: 513821.2 in slot1 2019-11-01 21:34:44 (10312): Guest Log: [INFO] MCPlots JobID: 51931980 in slot1 2019-11-01 21:34:49 (10312): Guest Log: [INFO] ===> [runRivet] Fri Nov 1 20:34:39 AEST 2019 [boinc ee zhad 29 - - pythia6 6.428 z2 100000 147] 2019-11-01 21:53:34 (10312): Guest Log: [INFO] Job finished in slot1 with 0. 2019-11-01 21:53:56 (10312): Guest Log: [INFO] New Job Starting in slot1 2019-11-01 21:53:57 (10312): Guest Log: [INFO] Condor JobID: 513823.57 in slot1 2019-11-01 21:54:02 (10312): Guest Log: [INFO] MCPlots JobID: 51932490 in slot1 2019-11-01 21:54:07 (10312): Guest Log: [INFO] ===> [runRivet] Fri Nov 1 20:53:57 AEST 2019 [boinc pp jets 7000 150,-,1860 - pythia6 6.428 394 100000 147] 2019-11-01 22:34:26 (10312): Status Report: Job Duration: '129600.000000' 2019-11-01 22:34:26 (10312): Status Report: Elapsed Time: '69971.587423' 2019-11-01 22:34:26 (10312): Status Report: CPU Time: '138155.656250' 2019-11-01 23:17:46 (10312): VM state change detected. (old = 'Running', new = 'Paused') 2019-11-01 23:18:08 (10312): Stopping VM. 2019-11-01 23:18:08 (10312): Error in stop VM for VM: -108 Command: VBoxManage -q controlvm "boinc_11bbdcf43962130e" savestate Output: 2019-11-01 23:18:08 (10312): VM did not stop when requested. 2019-11-01 23:18:08 (10312): VM was successfully terminated. 2019-11-02 14:04:46 (9164): Detected: vboxwrapper 26197 2019-11-02 14:04:46 (9164): Detected: BOINC client v7.7 2019-11-02 14:04:50 (9164): Detected: VirtualBox VboxManage Interface (Version: 5.2.28) 2019-11-02 14:04:50 (9164): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds) 2019-11-02 14:04:54 (9164): Starting VM using VBoxManage interface. (boinc_11bbdcf43962130e, slot#3) 2019-11-02 14:05:05 (9164): Successfully started VM. (PID = '14904') 2019-11-02 14:05:05 (9164): Reporting VM Process ID to BOINC. 2019-11-02 14:05:05 (9164): Guest Log: BIOS: VirtualBox 5.2.28 2019-11-02 14:05:05 (9164): Guest Log: CPUID EDX: 0x178bfbff 2019-11-02 14:05:05 (9164): Guest Log: BIOS: ata0-0: PCHS=16383/16/63 LCHS=1024/255/63 2019-11-02 14:05:05 (9164): VM state change detected. (old = 'PoweredOff', new = 'Running') 2019-11-02 14:05:05 (9164): Detected: Web Application Enabled (http://localhost:60730) 2019-11-02 14:05:05 (9164): Detected: Remote Desktop Enabled (localhost:60731) 2019-11-02 14:05:06 (9164): VM state change detected. (old = 'Running', new = 'Paused') 2019-11-02 14:08:12 (9164): Stopping VM. 2019-11-02 14:08:12 (9164): Error in stop VM for VM: -108 Command: VBoxManage -q controlvm "boinc_11bbdcf43962130e" savestate Output: 2019-11-02 14:08:12 (9164): VM did not stop when requested. 2019-11-02 14:08:12 (9164): VM was successfully terminated. 2019-11-02 14:36:05 (12544): Detected: vboxwrapper 26197 2019-11-02 14:36:05 (12544): Detected: BOINC client v7.7 2019-11-02 14:36:06 (12544): Detected: VirtualBox VboxManage Interface (Version: 5.2.28) 2019-11-02 14:36:06 (12544): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds) 2019-11-02 14:36:07 (12544): Starting VM using VBoxManage interface. (boinc_11bbdcf43962130e, slot#3) 2019-11-02 14:36:11 (12544): Successfully started VM. (PID = '13968') 2019-11-02 14:36:11 (12544): Reporting VM Process ID to BOINC. 2019-11-02 14:36:11 (12544): Guest Log: BIOS: VirtualBox 5.2.28 2019-11-02 14:36:11 (12544): Guest Log: CPUID EDX: 0x178bfbff 2019-11-02 14:36:11 (12544): Guest Log: BIOS: ata0-0: PCHS=16383/16/63 LCHS=1024/255/63 2019-11-02 14:36:11 (12544): VM state change detected. (old = 'PoweredOff', new = 'Running') 2019-11-02 14:36:11 (12544): Detected: Web Application Enabled (http://localhost:60730) 2019-11-02 14:36:11 (12544): Detected: Remote Desktop Enabled (localhost:60731) 2019-11-02 14:36:11 (12544): Status Report: Job Duration: '129600.000000' 2019-11-02 14:36:11 (12544): Status Report: Elapsed Time: '72567.587423' 2019-11-02 14:36:11 (12544): Status Report: CPU Time: '143333.531250' 2019-11-02 14:36:11 (12544): Preference change detected 2019-11-02 14:36:11 (12544): Setting CPU throttle for VM. (100%) 2019-11-02 14:36:11 (12544): Setting network throttle for VM. (250KB) 2019-11-02 14:36:12 (12544): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds)) 2019-11-02 14:36:13 (12544): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032 2019-11-02 14:36:13 (12544): Guest Log: BIOS: Booting from Hard Disk... 2019-11-02 14:36:15 (12544): Guest Log: BIOS: KBD: unsupported int 16h function 03 2019-11-02 14:36:15 (12544): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 2019-11-02 14:36:39 (12544): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds 2019-11-02 14:36:39 (12544): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000) 2019-11-02 14:37:13 (12544): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log 2019-11-02 14:37:13 (12544): Guest Log: 00:00:00.000209 main Log opened 2019-11-02T03:37:12.761351000Z 2019-11-02 14:37:13 (12544): Guest Log: 00:00:00.000381 main OS Product: Linux 2019-11-02 14:37:13 (12544): Guest Log: 00:00:00.000418 main OS Release: 4.14.44-11.cernvm.x86_64 2019-11-02 14:37:13 (12544): Guest Log: 00:00:00.000452 main OS Version: #1 SMP Mon May 28 16:49:00 CEST 2018 2019-11-02 14:37:13 (12544): Guest Log: 00:00:00.000484 main Executable: /usr/share/vboxguest52/usr/sbin/VBoxService 2019-11-02 14:37:13 (12544): Guest Log: 00:00:00.000486 main Process ID: 2940 2019-11-02 14:37:13 (12544): Guest Log: 00:00:00.000487 main Package type: LINUX_64BITS_GENERIC 2019-11-02 14:37:13 (12544): Guest Log: 00:00:00.002452 main 5.2.6 r120293 started. Verbose level = 0 2019-11-02 14:37:48 (12544): Guest Log: [INFO] Mounting the shared directory 2019-11-02 14:37:48 (12544): Guest Log: [INFO] Shared directory mounted, enabling vboxmonitor 2019-11-02 14:37:48 (12544): Guest Log: [DEBUG] Testing network connection to cern.ch on port 80 2019-11-02 14:37:48 (12544): Guest Log: [DEBUG] Connection to cern.ch 80 port [tcp/http] succeeded! 2019-11-02 14:37:49 (12544): Guest Log: [DEBUG] 0 2019-11-02 14:37:49 (12544): Guest Log: [DEBUG] Testing VCCS connection to vccs.cern.ch on port 443 2019-11-02 14:37:49 (12544): Guest Log: [DEBUG] Connection to vccs.cern.ch 443 port [tcp/https] succeeded! 2019-11-02 14:37:49 (12544): Guest Log: [DEBUG] 0 2019-11-02 14:37:49 (12544): Guest Log: [DEBUG] Testing connection to Condor server on port 9618 2019-11-02 14:37:50 (12544): Guest Log: [DEBUG] Connection to vccondor01.cern.ch 9618 port [tcp/condor] succeeded! 2019-11-02 14:37:50 (12544): Guest Log: [DEBUG] 0 2019-11-02 14:38:59 (12544): Guest Log: [DEBUG] Probing CVMFS ... 2019-11-02 14:39:01 (12544): Guest Log: Probing /cvmfs/grid.cern.ch... OK 2019-11-02 14:39:02 (12544): 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-11-02 14:39:02 (12544): Guest Log: 2.4.4.0 3638 1 28252 10639 3 1 734458 10240000 2 65024 0 3 100 1 0 http://s1ihep-cvmfs.openhtc.io/cvmfs/grid.cern.ch DIRECT 1 2019-11-02 14:39:12 (12544): Guest Log: [INFO] Reading volunteer information 2019-11-02 14:39:12 (12544): Guest Log: [INFO] Volunteer: Duff_Moss (127134) 2019-11-02 14:39:12 (12544): Guest Log: [INFO] VMID: e5b01749-9626-457a-8945-16c64690aa2f 2019-11-02 14:39:12 (12544): Guest Log: [INFO] Requesting an X509 credential from LHC@home 2019-11-02 14:39:14 (12544): Guest Log: [INFO] Running the fast benchmark. 2019-11-02 14:42:11 (12544): Guest Log: [INFO] Machine performance 5.76 HEPSPEC06 2019-11-02 14:42:11 (12544): Guest Log: [INFO] Theory application starting. Check log files. 2019-11-02 14:42:12 (12544): Guest Log: [DEBUG] HTCondor ping 2019-11-02 14:42:16 (12544): Guest Log: [DEBUG] 0 2019-11-02 14:43:54 (12544): Guest Log: [INFO] New Job Starting in slot1 2019-11-02 14:43:55 (12544): Guest Log: [INFO] Condor JobID: 513892.88 in slot1 2019-11-02 14:43:55 (12544): Guest Log: [INFO] New Job Starting in slot2 2019-11-02 14:43:55 (12544): Guest Log: [INFO] Condor JobID: 513892.90 in slot2 2019-11-02 14:44:00 (12544): Guest Log: [INFO] MCPlots JobID: 51942020 in slot1 2019-11-02 14:44:00 (12544): Guest Log: [INFO] MCPlots JobID: 51941986 in slot2 2019-11-02 14:44:05 (12544): Guest Log: [INFO] ===> [runRivet] Sat Nov 2 13:43:54 AEST 2019 [boinc pp ue 2760 - - pythia6 6.428 390 100000 147] 2019-11-02 14:44:05 (12544): Guest Log: [INFO] ===> [runRivet] Sat Nov 2 13:43:54 AEST 2019 [boinc pp ue 900 - - pythia6 6.428 378 100000 147] 2019-11-02 14:45:51 (12544): VM state change detected. (old = 'Running', new = 'Paused') 2019-11-02 15:05:44 (12544): VM state change detected. (old = 'Paused', new = 'Running') 2019-11-02 15:06:55 (12544): VM state change detected. (old = 'Running', new = 'Paused') 2019-11-02 15:10:39 (12544): VM state change detected. (old = 'Paused', new = 'Running') 2019-11-02 15:31:12 (12544): VM state change detected. (old = 'Running', new = 'Paused') 2019-11-02 15:34:38 (12544): VM state change detected. (old = 'Paused', new = 'Running') 2019-11-02 15:51:42 (12544): Guest Log: [INFO] Job finished in slot2 with 0. 2019-11-02 15:52:00 (12544): Guest Log: [INFO] New Job Starting in slot2 2019-11-02 15:52:00 (12544): Guest Log: [INFO] Condor JobID: 513897.25 in slot2 2019-11-02 15:52:05 (12544): Guest Log: [INFO] MCPlots JobID: 51943024 in slot2 2019-11-02 15:52:10 (12544): Guest Log: [INFO] ===> [runRivet] Sat Nov 2 14:51:59 AEST 2019 [boinc ppbar mb-inelastic 500 - - pythia8 8.235 early 100000 147] 2019-11-02 16:00:49 (12544): Guest Log: [INFO] Job finished in slot1 with 0. 2019-11-02 16:01:05 (12544): Guest Log: [INFO] New Job Starting in slot1 2019-11-02 16:01:05 (12544): Guest Log: [INFO] Condor JobID: 513897.92 in slot1 2019-11-02 16:01:10 (12544): Guest Log: [INFO] MCPlots JobID: 51942965 in slot1 2019-11-02 16:01:16 (12544): Guest Log: [INFO] ===> [runRivet] Sat Nov 2 15:01:05 AEST 2019 [boinc pp mb-nsd 2360 - - pythia6 6.428 379 100000 147] 2019-11-02 16:24:38 (12544): Guest Log: [INFO] Job finished in slot2 with 0. 2019-11-02 16:24:55 (12544): Guest Log: [INFO] New Job Starting in slot2 2019-11-02 16:24:56 (12544): Guest Log: [INFO] Condor JobID: 513899.2 in slot2 2019-11-02 16:25:01 (12544): Guest Log: [INFO] MCPlots JobID: 51943219 in slot2 2019-11-02 16:25:06 (12544): Guest Log: [INFO] ===> [runRivet] Sat Nov 2 15:24:56 AEST 2019 [boinc ee zhad 29 - - pythia6 6.428 379 100000 147] 2019-11-02 16:43:23 (12544): Status Report: Job Duration: '129600.000000' 2019-11-02 16:43:23 (12544): Status Report: Elapsed Time: '78567.587423' 2019-11-02 16:43:23 (12544): Status Report: CPU Time: '154765.968750' 2019-11-02 16:43:24 (12544): Guest Log: [INFO] Job finished in slot2 with 0. 2019-11-02 16:43:40 (12544): Guest Log: [INFO] New Job Starting in slot2 2019-11-02 16:43:40 (12544): Guest Log: [INFO] Condor JobID: 513900.34 in slot2 2019-11-02 16:43:46 (12544): Guest Log: [INFO] MCPlots JobID: 51943420 in slot2 2019-11-02 16:43:51 (12544): Guest Log: [INFO] ===> [runRivet] Sat Nov 2 15:43:40 AEST 2019 [boinc pp jets 7000 100 - pythia6 6.428 351 100000 147] 2019-11-02 16:54:28 (12544): Guest Log: [INFO] Job finished in slot1 with 0. 2019-11-02 16:54:45 (12544): Guest Log: [INFO] New Job Starting in slot1 2019-11-02 16:54:45 (12544): Guest Log: [INFO] Condor JobID: 513713.74 in slot1 2019-11-02 16:54:51 (12544): Guest Log: [INFO] MCPlots JobID: 51916238 in slot1 2019-11-02 16:54:56 (12544): Guest Log: [INFO] ===> [runRivet] Sat Nov 2 15:54:46 AEST 2019 [boinc pp jets 7000 80,-,960 - pythia6 6.425 pnocr 100000 146] 2019-11-02 17:13:05 (12544): VM state change detected. (old = 'Running', new = 'Paused') 2019-11-02 17:21:15 (12544): VM state change detected. (old = 'Paused', new = 'Running') 2019-11-02 18:31:39 (12544): Status Report: Job Duration: '129600.000000' 2019-11-02 18:31:39 (12544): Status Report: Elapsed Time: '84567.587423' 2019-11-02 18:31:39 (12544): Status Report: CPU Time: '166704.968750' 2019-11-02 20:11:37 (12544): Guest Log: [INFO] Job finished in slot1 with 0. 2019-11-02 20:11:43 (12544): Status Report: Job Duration: '129600.000000' 2019-11-02 20:11:43 (12544): Status Report: Elapsed Time: '90567.587423' 2019-11-02 20:11:43 (12544): Status Report: CPU Time: '178663.625000' 2019-11-02 20:11:54 (12544): Guest Log: [INFO] New Job Starting in slot1 2019-11-02 20:11:54 (12544): Guest Log: [INFO] Condor JobID: 513911.12 in slot1 2019-11-02 20:11:59 (12544): Guest Log: [INFO] MCPlots JobID: 51944911 in slot1 2019-11-02 20:12:05 (12544): Guest Log: [INFO] ===> [runRivet] Sat Nov 2 19:11:55 AEST 2019 [boinc ee zhad 91.2 - - pythia6 6.425 pro-q2o 100000 148] 2019-11-02 20:42:35 (12544): VM state change detected. (old = 'Running', new = 'Paused') 2019-11-02 20:43:05 (12544): VM state change detected. (old = 'Paused', new = 'Running') 2019-11-02 20:55:27 (12544): Guest Log: [INFO] Job finished in slot1 with 0. 2019-11-02 20:55:48 (12544): Guest Log: [INFO] New Job Starting in slot1 2019-11-02 20:55:48 (12544): Guest Log: [INFO] Condor JobID: 513914.47 in slot1 2019-11-02 20:55:53 (12544): Guest Log: [INFO] MCPlots JobID: 51945324 in slot1 2019-11-02 20:55:58 (12544): Guest Log: [INFO] ===> [runRivet] Sat Nov 2 19:55:20 AEST 2019 [boinc pp jets 7000 350 - pythia8 8.180 default-noCR 100000 148] 2019-11-02 21:44:24 (12544): Guest Log: [INFO] Job finished in slot2 with 0. 2019-11-02 21:44:44 (12544): Guest Log: [INFO] New Job Starting in slot2 2019-11-02 21:44:44 (12544): Guest Log: [INFO] Condor JobID: 513917.24 in slot2 2019-11-02 21:44:49 (12544): Guest Log: [INFO] MCPlots JobID: 51945770 in slot2 2019-11-02 21:44:54 (12544): Guest Log: [INFO] ===> [runRivet] Sat Nov 2 20:44:19 AEST 2019 [boinc ppbar mb-inelastic 630 - - pythia6 6.426 350 100000 148] 2019-11-02 21:52:19 (12544): Status Report: Job Duration: '129600.000000' 2019-11-02 21:52:19 (12544): Status Report: Elapsed Time: '96568.569713' 2019-11-02 21:52:19 (12544): Status Report: CPU Time: '190502.890625' 2019-11-02 21:54:47 (12544): VM state change detected. (old = 'Running', new = 'Paused') 2019-11-02 21:55:27 (12544): VM state change detected. (old = 'Paused', new = 'Running') 2019-11-02 22:15:18 (12544): VM state change detected. (old = 'Running', new = 'Paused') 2019-11-02 22:15:27 (12544): Stopping VM. 2019-11-02 22:15:28 (12544): Error in stop VM for VM: -108 Command: VBoxManage -q controlvm "boinc_11bbdcf43962130e" savestate Output: 2019-11-02 22:15:28 (12544): VM did not stop when requested. 2019-11-02 22:15:28 (12544): VM was successfully terminated. 2019-11-04 19:56:01 (3096): Detected: vboxwrapper 26197 2019-11-04 19:56:01 (3096): Detected: BOINC client v7.7 2019-11-04 19:56:03 (3096): Detected: VirtualBox VboxManage Interface (Version: 5.2.28) 2019-11-04 19:56:03 (3096): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds) 2019-11-04 19:56:03 (3096): Starting VM using VBoxManage interface. (boinc_11bbdcf43962130e, slot#3) 2019-11-04 19:56:08 (3096): Successfully started VM. (PID = '1340') 2019-11-04 19:56:08 (3096): Reporting VM Process ID to BOINC. 2019-11-04 19:56:08 (3096): Guest Log: BIOS: VirtualBox 5.2.28 2019-11-04 19:56:08 (3096): Guest Log: CPUID EDX: 0x178bfbff 2019-11-04 19:56:08 (3096): Guest Log: BIOS: ata0-0: PCHS=16383/16/63 LCHS=1024/255/63 2019-11-04 19:56:08 (3096): VM state change detected. (old = 'PoweredOff', new = 'Running') 2019-11-04 19:56:08 (3096): Detected: Web Application Enabled (http://localhost:60730) 2019-11-04 19:56:08 (3096): Detected: Remote Desktop Enabled (localhost:60731) 2019-11-04 19:56:08 (3096): Status Report: Job Duration: '129600.000000' 2019-11-04 19:56:08 (3096): Status Report: Elapsed Time: '97903.569713' 2019-11-04 19:56:08 (3096): Status Report: CPU Time: '193163.218750' 2019-11-04 19:56:08 (3096): Preference change detected 2019-11-04 19:56:08 (3096): Setting CPU throttle for VM. (100%) 2019-11-04 19:56:09 (3096): Setting network throttle for VM. (250KB) 2019-11-04 19:56:09 (3096): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds)) 2019-11-04 19:56:10 (3096): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032 2019-11-04 19:56:10 (3096): Guest Log: BIOS: Booting from Hard Disk... 2019-11-04 19:56:13 (3096): Guest Log: BIOS: KBD: unsupported int 16h function 03 2019-11-04 19:56:13 (3096): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 2019-11-04 19:56:23 (3096): VM state change detected. (old = 'Running', new = 'Paused') 2019-11-04 19:56:33 (3096): VM state change detected. (old = 'Paused', new = 'Running') 2019-11-04 19:56:48 (3096): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds 2019-11-04 19:56:48 (3096): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000) 2019-11-04 19:56:53 (3096): VM state change detected. (old = 'Running', new = 'Paused') 2019-11-04 20:13:00 (3096): VM state change detected. (old = 'Paused', new = 'Running') 2019-11-04 20:13:18 (3096): VM state change detected. (old = 'Running', new = 'Paused') 2019-11-04 20:29:49 (3096): VM state change detected. (old = 'Paused', new = 'Running') 2019-11-04 20:29:50 (3096): VM state change detected. (old = 'Running', new = 'Paused') 2019-11-04 20:45:12 (3096): VM state change detected. (old = 'Paused', new = 'Running') 2019-11-04 20:45:26 (3096): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log 2019-11-04 20:45:26 (3096): Guest Log: 00:00:00.000514 main Log opened 2019-11-04T09:13:36.577426000Z 2019-11-04 20:45:26 (3096): Guest Log: 00:00:00.001874 main OS Product: Linux 2019-11-04 20:45:26 (3096): Guest Log: 00:00:00.001943 main OS Release: 4.14.44-11.cernvm.x86_64 2019-11-04 20:45:26 (3096): Guest Log: 00:00:00.002004 main OS Version: #1 SMP Mon May 28 16:49:00 CEST 2018 2019-11-04 20:45:26 (3096): Guest Log: 00:00:00.002047 main Executable: /usr/share/vboxguest52/usr/sbin/VBoxService 2019-11-04 20:45:26 (3096): Guest Log: 00:00:00.002049 main Process ID: 2939 2019-11-04 20:45:26 (3096): Guest Log: 00:00:00.002050 main Package type: LINUX_64BITS_GENERIC 2019-11-04 20:45:26 (3096): Guest Log: 00:00:00.005552 main 5.2.6 r120293 started. Verbose level = 0 2019-11-04 20:45:36 (3096): Guest Log: 00:00:10.013515 timesync vgsvcTimeSyncWorker: Radical guest time change: 1 919 366 598 000ns (GuestNow=1 572 860 735 956 491 000 ns GuestLast=1 572 858 816 589 893 000 ns fSetTimeLastLoop=true ) 2019-11-04 20:46:04 (3096): Guest Log: [INFO] Mounting the shared directory 2019-11-04 20:46:04 (3096): Guest Log: [INFO] Shared directory mounted, enabling vboxmonitor 2019-11-04 20:46:04 (3096): Guest Log: [DEBUG] Testing network connection to cern.ch on port 80 2019-11-04 20:46:05 (3096): Guest Log: [DEBUG] Connection to cern.ch 80 port [tcp/http] succeeded! 2019-11-04 20:46:05 (3096): Guest Log: [DEBUG] 0 2019-11-04 20:46:05 (3096): Guest Log: [DEBUG] Testing VCCS connection to vccs.cern.ch on port 443 2019-11-04 20:46:06 (3096): Guest Log: [DEBUG] Connection to vccs.cern.ch 443 port [tcp/https] succeeded! 2019-11-04 20:46:06 (3096): Guest Log: [DEBUG] 0 2019-11-04 20:46:06 (3096): Guest Log: [DEBUG] Testing connection to Condor server on port 9618 2019-11-04 20:46:07 (3096): Guest Log: [DEBUG] Connection to vccondor01.cern.ch 9618 port [tcp/condor] succeeded! 2019-11-04 20:46:07 (3096): Guest Log: [DEBUG] 0 2019-11-04 20:46:52 (3096): VM state change detected. (old = 'Running', new = 'Paused') 2019-11-04 20:50:18 (3096): VM state change detected. (old = 'Paused', new = 'Running') 2019-11-04 20:50:29 (3096): VM state change detected. (old = 'Running', new = 'Paused') 2019-11-04 20:57:10 (3096): VM state change detected. (old = 'Paused', new = 'Running') 2019-11-04 20:57:20 (3096): VM state change detected. (old = 'Running', new = 'Paused') 2019-11-04 20:57:46 (3096): VM state change detected. (old = 'Paused', new = 'Running') 2019-11-04 20:57:56 (3096): Guest Log: [DEBUG] Probing CVMFS ... 2019-11-04 20:57:58 (3096): Guest Log: Probing /cvmfs/grid.cern.ch... OK 2019-11-04 20:58:01 (3096): 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-11-04 20:58:01 (3096): Guest Log: 2.4.4.0 3640 1 27824 10657 3 1 808736 10240000 2 65024 0 3 100 0 0 http://s1cern-cvmfs.openhtc.io/cvmfs/grid.cern.ch DIRECT 1 2019-11-04 20:58:11 (3096): Guest Log: [INFO] Reading volunteer information 2019-11-04 20:58:17 (3096): Guest Log: [INFO] Volunteer: Duff_Moss (127134) 2019-11-04 20:58:17 (3096): Guest Log: [INFO] VMID: e5b01749-9626-457a-8945-16c64690aa2f 2019-11-04 20:58:25 (3096): Guest Log: [INFO] Requesting an X509 credential from LHC@home 2019-11-04 20:58:28 (3096): Guest Log: [INFO] Running the fast benchmark. 2019-11-04 20:58:44 (3096): VM state change detected. (old = 'Running', new = 'Paused') 2019-11-04 20:59:04 (3096): VM state change detected. (old = 'Paused', new = 'Running') 2019-11-04 20:59:33 (3096): VM state change detected. (old = 'Running', new = 'Paused') 2019-11-04 21:00:25 (3096): VM state change detected. (old = 'Paused', new = 'Running') 2019-11-04 21:02:54 (3096): Guest Log: [INFO] Machine performance 5.06 HEPSPEC06 2019-11-04 21:02:54 (3096): Guest Log: [INFO] Theory application starting. Check log files. 2019-11-04 21:03:01 (3096): Guest Log: [DEBUG] HTCondor ping 2019-11-04 21:03:09 (3096): Guest Log: [DEBUG] 0 2019-11-04 21:03:54 (3096): Guest Log: [INFO] New Job Starting in slot2 2019-11-04 21:03:54 (3096): Guest Log: [INFO] Condor JobID: 514100.28 in slot2 2019-11-04 21:03:54 (3096): Guest Log: [INFO] New Job Starting in slot1 2019-11-04 21:03:54 (3096): Guest Log: [INFO] Condor JobID: 514100.27 in slot1 2019-11-04 21:03:59 (3096): Guest Log: [INFO] MCPlots JobID: 51971594 in slot2 2019-11-04 21:03:59 (3096): Guest Log: [INFO] MCPlots JobID: 51971637 in slot1 2019-11-04 21:04:04 (3096): Guest Log: [INFO] ===> [runRivet] Mon Nov 4 19:52:20 AEST 2019 [boinc pp jets 7000 10 - pythia8 8.165 default 100000 148] 2019-11-04 21:04:05 (3096): Guest Log: [INFO] ===> [runRivet] Mon Nov 4 19:52:20 AEST 2019 [boinc pp jets 7000 - - pythia6 6.428 372 100000 148] 2019-11-04 21:42:38 (3096): VM state change detected. (old = 'Running', new = 'Paused') 2019-11-04 21:42:48 (3096): VM state change detected. (old = 'Paused', new = 'Running') 2019-11-04 22:19:14 (3096): Guest Log: [INFO] Job finished in slot1 with 0. 2019-11-04 22:19:32 (3096): Guest Log: [INFO] New Job Starting in slot1 2019-11-04 22:19:32 (3096): Guest Log: [INFO] Condor JobID: 514104.39 in slot1 2019-11-04 22:19:38 (3096): Guest Log: [INFO] MCPlots JobID: 51972232 in slot1 2019-11-04 22:19:43 (3096): Guest Log: [INFO] ===> [runRivet] Mon Nov 4 21:19:32 AEST 2019 [boinc pp jets 7000 500 - herwig7 7.1.1 default 100000 148] 2019-11-04 22:30:37 (3096): VM state change detected. (old = 'Running', new = 'Paused') 2019-11-04 22:31:05 (3096): Stopping VM. 2019-11-04 22:31:06 (3096): Error in stop VM for VM: -108 Command: VBoxManage -q controlvm "boinc_11bbdcf43962130e" savestate Output: 2019-11-04 22:31:06 (3096): VM did not stop when requested. 2019-11-04 22:31:06 (3096): VM was successfully terminated. 2019-11-09 11:59:04 (13368): Detected: vboxwrapper 26197 2019-11-09 11:59:04 (13368): Detected: BOINC client v7.7 2019-11-09 11:59:05 (13368): Detected: VirtualBox VboxManage Interface (Version: 5.2.28) 2019-11-09 11:59:05 (13368): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds) 2019-11-09 11:59:06 (13368): Starting VM using VBoxManage interface. (boinc_11bbdcf43962130e, slot#3) 2019-11-09 11:59:11 (13368): Successfully started VM. (PID = '12764') 2019-11-09 11:59:11 (13368): Reporting VM Process ID to BOINC. 2019-11-09 11:59:11 (13368): Guest Log: BIOS: VirtualBox 5.2.28 2019-11-09 11:59:11 (13368): Guest Log: CPUID EDX: 0x178bfbff 2019-11-09 11:59:11 (13368): Guest Log: BIOS: ata0-0: PCHS=16383/16/63 LCHS=1024/255/63 2019-11-09 11:59:11 (13368): VM state change detected. (old = 'PoweredOff', new = 'Running') 2019-11-09 11:59:11 (13368): Detected: Web Application Enabled (http://localhost:60730) 2019-11-09 11:59:11 (13368): Detected: Remote Desktop Enabled (localhost:60731) 2019-11-09 11:59:11 (13368): Status Report: Job Duration: '129600.000000' 2019-11-09 11:59:11 (13368): Status Report: Elapsed Time: '103605.612480' 2019-11-09 11:59:11 (13368): Status Report: CPU Time: '203708.906250' 2019-11-09 11:59:11 (13368): Preference change detected 2019-11-09 11:59:11 (13368): Setting CPU throttle for VM. (100%) 2019-11-09 11:59:11 (13368): Setting network throttle for VM. (250KB) 2019-11-09 11:59:11 (13368): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds)) 2019-11-09 11:59:13 (13368): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032 2019-11-09 11:59:13 (13368): Guest Log: BIOS: Booting from Hard Disk... 2019-11-09 11:59:16 (13368): Guest Log: BIOS: KBD: unsupported int 16h function 03 2019-11-09 11:59:16 (13368): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 2019-11-09 11:59:50 (13368): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds 2019-11-09 11:59:50 (13368): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000) 2019-11-09 12:00:29 (13368): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log 2019-11-09 12:00:29 (13368): Guest Log: 00:00:00.000207 main Log opened 2019-11-09T01:00:35.937784000Z 2019-11-09 12:00:29 (13368): Guest Log: 00:00:00.000627 main OS Product: Linux 2019-11-09 12:00:29 (13368): Guest Log: 00:00:00.000666 main OS Release: 4.14.44-11.cernvm.x86_64 2019-11-09 12:00:29 (13368): Guest Log: 00:00:00.000701 main OS Version: #1 SMP Mon May 28 16:49:00 CEST 2018 2019-11-09 12:00:29 (13368): Guest Log: 00:00:00.000734 main Executable: /usr/share/vboxguest52/usr/sbin/VBoxService 2019-11-09 12:00:29 (13368): Guest Log: 00:00:00.000736 main Process ID: 2946 2019-11-09 12:00:29 (13368): Guest Log: 00:00:00.000737 main Package type: LINUX_64BITS_GENERIC 2019-11-09 12:00:29 (13368): Guest Log: 00:00:00.002418 main 5.2.6 r120293 started. Verbose level = 0 2019-11-09 12:00:48 (13368): VM state change detected. (old = 'Running', new = 'Paused') 2019-11-09 12:01:00 (13368): VM state change detected. (old = 'Paused', new = 'Running') 2019-11-09 12:01:10 (13368): VM state change detected. (old = 'Running', new = 'Paused') 2019-11-09 12:01:40 (13368): VM state change detected. (old = 'Paused', new = 'Running') 2019-11-09 12:02:13 (13368): Guest Log: [INFO] Mounting the shared directory 2019-11-09 12:02:13 (13368): Guest Log: [INFO] Shared directory mounted, enabling vboxmonitor 2019-11-09 12:02:13 (13368): Guest Log: [DEBUG] Testing network connection to cern.ch on port 80 2019-11-09 12:02:13 (13368): Guest Log: [DEBUG] Connection to cern.ch 80 port [tcp/http] succeeded! 2019-11-09 12:02:13 (13368): Guest Log: [DEBUG] 0 2019-11-09 12:02:13 (13368): Guest Log: [DEBUG] Testing VCCS connection to vccs.cern.ch on port 443 2019-11-09 12:02:13 (13368): Guest Log: [DEBUG] Connection to vccs.cern.ch 443 port [tcp/https] succeeded! 2019-11-09 12:02:13 (13368): Guest Log: [DEBUG] 0 2019-11-09 12:02:13 (13368): Guest Log: [DEBUG] Testing connection to Condor server on port 9618 2019-11-09 12:02:13 (13368): Guest Log: [DEBUG] Connection to vccondor01.cern.ch 9618 port [tcp/condor] succeeded! 2019-11-09 12:02:13 (13368): Guest Log: [DEBUG] 0 2019-11-09 12:02:13 (13368): VM state change detected. (old = 'Running', new = 'Paused') 2019-11-09 12:02:34 (13368): VM state change detected. (old = 'Paused', new = 'Running') 2019-11-09 12:03:33 (13368): Guest Log: [DEBUG] Probing CVMFS ... 2019-11-09 12:03:36 (13368): Guest Log: Probing /cvmfs/grid.cern.ch... OK 2019-11-09 12:03:37 (13368): 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-11-09 12:03:37 (13368): Guest Log: 2.4.4.0 3645 1 25800 10693 3 1 1067206 10240000 2 65024 0 3 100 0 0 http://s1asgc-cvmfs.openhtc.io:8080/cvmfs/grid.cern.ch DIRECT 1 2019-11-09 12:03:48 (13368): Guest Log: [INFO] Reading volunteer information 2019-11-09 12:03:48 (13368): Guest Log: [INFO] Volunteer: Duff_Moss (127134) 2019-11-09 12:03:48 (13368): Guest Log: [INFO] VMID: e5b01749-9626-457a-8945-16c64690aa2f 2019-11-09 12:03:48 (13368): Guest Log: [INFO] Requesting an X509 credential from LHC@home 2019-11-09 12:03:53 (13368): Guest Log: [INFO] Running the fast benchmark. 2019-11-09 12:04:14 (13368): VM state change detected. (old = 'Running', new = 'Paused') 2019-11-09 12:04:27 (13368): VM state change detected. (old = 'Paused', new = 'Running') 2019-11-09 12:05:19 (13368): VM state change detected. (old = 'Running', new = 'Paused') 2019-11-09 13:11:57 (13368): VM state change detected. (old = 'Paused', new = 'Running') 2019-11-09 13:12:06 (13368): Guest Log: 00:03:40.064552 timesync vgsvcTimeSyncWorker: Radical host time change: 4 007 564 000 000ns (HostNow=1 573 265 521 320 000 000 ns HostLast=1 573 261 513 756 000 000 ns) 2019-11-09 13:13:33 (13368): Guest Log: 00:03:50.065278 timesync vgsvcTimeSyncWorker: Radical guest time change: 4 075 318 801 000ns (GuestNow=1 573 265 531 320 809 000 ns GuestLast=1 573 261 456 002 008 000 ns fSetTimeLastLoop=true ) 2019-11-09 13:13:33 (13368): VM state change detected. (old = 'Running', new = 'Paused') 2019-11-09 13:15:48 (13368): VM state change detected. (old = 'Paused', new = 'Running') 2019-11-09 13:16:08 (13368): VM state change detected. (old = 'Running', new = 'Paused') 2019-11-09 13:16:28 (13368): VM state change detected. (old = 'Paused', new = 'Running') 2019-11-09 13:17:05 (13368): Guest Log: [INFO] Machine performance 4.38 HEPSPEC06 2019-11-09 13:17:05 (13368): Guest Log: [INFO] Theory application starting. Check log files. 2019-11-09 13:17:06 (13368): Guest Log: [DEBUG] HTCondor ping 2019-11-09 13:17:10 (13368): Guest Log: [DEBUG] 0 2019-11-09 13:17:38 (13368): VM state change detected. (old = 'Running', new = 'Paused') 2019-11-09 13:17:49 (13368): VM state change detected. (old = 'Paused', new = 'Running') 2019-11-09 13:18:49 (13368): Guest Log: [INFO] New Job Starting in slot2 2019-11-09 13:18:49 (13368): Guest Log: [INFO] Condor JobID: 514560.10 in slot2 2019-11-09 13:18:49 (13368): Guest Log: [INFO] New Job Starting in slot1 2019-11-09 13:18:49 (13368): Guest Log: [INFO] Condor JobID: 514560.8 in slot1 2019-11-09 13:18:54 (13368): Guest Log: [INFO] MCPlots JobID: 52034132 in slot2 2019-11-09 13:18:54 (13368): Guest Log: [INFO] MCPlots JobID: 52034162 in slot1 2019-11-09 13:18:58 (13368): Guest Log: [INFO] ===> [runRivet] Sat Nov 9 12:16:04 AEST 2019 [boinc ppbar mb-inelastic 200 - - pythia8 8.212 tune-AU2ct10 100000 156] 2019-11-09 13:19:00 (13368): Guest Log: [INFO] ===> [runRivet] Sat Nov 9 12:16:04 AEST 2019 [boinc ppbar mb-inelastic 1800 - - pythia6 6.425 dw 100000 156] 2019-11-09 13:30:11 (13368): VM state change detected. (old = 'Running', new = 'Paused') 2019-11-09 13:30:21 (13368): VM state change detected. (old = 'Paused', new = 'Running') 2019-11-09 13:33:43 (13368): VM state change detected. (old = 'Running', new = 'Paused') 2019-11-09 13:33:53 (13368): VM state change detected. (old = 'Paused', new = 'Running') 2019-11-09 13:45:19 (13368): VM state change detected. (old = 'Running', new = 'Paused') 2019-11-09 13:45:29 (13368): VM state change detected. (old = 'Paused', new = 'Running') 2019-11-09 13:51:49 (13368): Guest Log: [INFO] Job finished in slot2 with 0. 2019-11-09 13:52:06 (13368): Guest Log: [INFO] New Job Starting in slot2 2019-11-09 13:52:06 (13368): Guest Log: [INFO] Condor JobID: 514561.54 in slot2 2019-11-09 13:52:12 (13368): Guest Log: [INFO] MCPlots JobID: 52034289 in slot2 2019-11-09 13:52:17 (13368): Guest Log: [INFO] ===> [runRivet] Sat Nov 9 12:51:57 AEST 2019 [boinc pp zinclusive 7000 20,-,50,200 - pythia6 6.427 p6 100000 156] 2019-11-09 13:57:04 (13368): Guest Log: [INFO] Job finished in slot1 with 0. 2019-11-09 13:57:19 (13368): Guest Log: [INFO] New Job Starting in slot1 2019-11-09 13:57:19 (13368): Guest Log: [INFO] Condor JobID: 514562.23 in slot1 2019-11-09 13:57:25 (13368): Guest Log: [INFO] MCPlots JobID: 52034442 in slot1 2019-11-09 13:57:30 (13368): Guest Log: [INFO] ===> [runRivet] Sat Nov 9 12:57:10 AEST 2019 [boinc ppbar ue 1800 15 - pythia6 6.427 372 100000 156] 2019-11-09 14:52:17 (13368): Status Report: Job Duration: '129600.000000' 2019-11-09 14:52:17 (13368): Status Report: Elapsed Time: '109606.102587' 2019-11-09 14:52:17 (13368): Status Report: CPU Time: '215124.812500' 2019-11-09 15:44:05 (13368): Guest Log: [INFO] Job finished in slot1 with 0. 2019-11-09 15:44:21 (13368): Guest Log: [INFO] New Job Starting in slot1 2019-11-09 15:44:21 (13368): Guest Log: [INFO] Condor JobID: 514568.14 in slot1 2019-11-09 15:44:26 (13368): Guest Log: [INFO] MCPlots JobID: 52035246 in slot1 2019-11-09 15:44:31 (13368): Guest Log: [INFO] ===> [runRivet] Sat Nov 9 14:44:20 AEST 2019 [boinc ppbar mb-inelastic 1800 - - sherpa 1.3.1 default 100000 156] 2019-11-09 16:07:05 (13368): Guest Log: [INFO] Job finished in slot2 with 0. 2019-11-09 16:07:21 (13368): Guest Log: [INFO] New Job Starting in slot2 2019-11-09 16:07:21 (13368): Guest Log: [INFO] Condor JobID: 514569.42 in slot2 2019-11-09 16:07:26 (13368): Guest Log: [INFO] MCPlots JobID: 52035308 in slot2 2019-11-09 16:07:31 (13368): Guest Log: [INFO] ===> [runRivet] Sat Nov 9 15:07:20 AEST 2019 [boinc pp jets 7000 150,-,2160 - pythia6 6.424 default 100000 156] 2019-11-09 16:32:21 (13368): Status Report: Job Duration: '129600.000000' 2019-11-09 16:32:21 (13368): Status Report: Elapsed Time: '115606.102587' 2019-11-09 16:32:21 (13368): Status Report: CPU Time: '226630.890625' 2019-11-09 18:09:42 (13368): Guest Log: [INFO] Job finished in slot2 with 0. 2019-11-09 18:09:59 (13368): Guest Log: [INFO] New Job Starting in slot2 2019-11-09 18:09:59 (13368): Guest Log: [INFO] Condor JobID: 514576.2 in slot2 2019-11-09 18:10:04 (13368): Guest Log: [INFO] MCPlots JobID: 52036266 in slot2 2019-11-09 18:10:10 (13368): Guest Log: [INFO] ===> [runRivet] Sat Nov 9 17:09:59 AEST 2019 [boinc ee zhad 34.8 - - pythia8 8.230 tune-fischer1 100000 156] 2019-11-09 18:12:25 (13368): Status Report: Job Duration: '129600.000000' 2019-11-09 18:12:25 (13368): Status Report: Elapsed Time: '121606.102587' 2019-11-09 18:12:25 (13368): Status Report: CPU Time: '238336.984375' 2019-11-09 18:22:30 (13368): Guest Log: [INFO] Job finished in slot2 with 0. 2019-11-09 18:22:46 (13368): Guest Log: [INFO] New Job Starting in slot2 2019-11-09 18:22:46 (13368): Guest Log: [INFO] Condor JobID: 514577.37 in slot2 2019-11-09 18:22:51 (13368): Guest Log: [INFO] MCPlots JobID: 52036429 in slot2 2019-11-09 18:22:57 (13368): Guest Log: [INFO] ===> [runRivet] Sat Nov 9 17:22:46 AEST 2019 [boinc pp jets 7000 150,-,1860 - pythia8 8.230 tune-AU2m 100000 156] 2019-11-09 19:04:15 (13368): Guest Log: [INFO] Job finished in slot1 with 0. 2019-11-09 19:04:32 (13368): Guest Log: [INFO] New Job Starting in slot1 2019-11-09 19:04:32 (13368): Guest Log: [INFO] Condor JobID: 514579.87 in slot1 2019-11-09 19:04:38 (13368): Guest Log: [INFO] MCPlots JobID: 52036732 in slot1 2019-11-09 19:04:43 (13368): Guest Log: [INFO] ===> [runRivet] Sat Nov 9 18:04:33 AEST 2019 [boinc pp w1j 7000 250 - pythia8 8.212 tune-4c 100000 156] 2019-11-09 19:37:45 (13368): VM state change detected. (old = 'Running', new = 'Paused') 2019-11-09 19:43:23 (13368): VM state change detected. (old = 'Paused', new = 'Running') 2019-11-09 19:43:33 (13368): VM state change detected. (old = 'Running', new = 'Paused') 2019-11-09 19:43:43 (13368): VM state change detected. (old = 'Paused', new = 'Running') 2019-11-09 19:44:23 (13368): VM state change detected. (old = 'Running', new = 'Paused') 2019-11-09 19:44:33 (13368): VM state change detected. (old = 'Paused', new = 'Running') 2019-11-09 19:44:43 (13368): VM state change detected. (old = 'Running', new = 'Paused') 2019-11-09 19:44:53 (13368): VM state change detected. (old = 'Paused', new = 'Running') 2019-11-09 19:45:23 (13368): VM state change detected. (old = 'Running', new = 'Paused') 2019-11-09 19:47:24 (13368): VM state change detected. (old = 'Paused', new = 'Running') 2019-11-09 19:48:54 (13368): VM state change detected. (old = 'Running', new = 'Paused') 2019-11-09 19:49:04 (13368): VM state change detected. (old = 'Paused', new = 'Running') 2019-11-09 19:49:53 (13368): VM state change detected. (old = 'Running', new = 'Paused') 2019-11-09 19:50:13 (13368): VM state change detected. (old = 'Paused', new = 'Running') 2019-11-09 20:01:24 (13368): Status Report: Job Duration: '129600.000000' 2019-11-09 20:01:24 (13368): Status Report: Elapsed Time: '127606.102587' 2019-11-09 20:01:24 (13368): Status Report: CPU Time: '250074.531250' 2019-11-09 20:05:25 (13368): VM state change detected. (old = 'Running', new = 'Paused') 2019-11-09 20:05:36 (13368): VM state change detected. (old = 'Paused', new = 'Running') 2019-11-09 20:20:28 (13368): VM state change detected. (old = 'Running', new = 'Paused') 2019-11-09 20:20:38 (13368): VM state change detected. (old = 'Paused', new = 'Running') 2019-11-09 20:24:42 (13368): VM state change detected. (old = 'Running', new = 'Paused') 2019-11-09 20:30:29 (13368): VM state change detected. (old = 'Paused', new = 'Running') 2019-11-09 20:31:19 (13368): VM state change detected. (old = 'Running', new = 'Paused') 2019-11-09 20:33:09 (13368): VM state change detected. (old = 'Paused', new = 'Running') 2019-11-09 20:33:19 (13368): VM state change detected. (old = 'Running', new = 'Paused') 2019-11-09 20:33:39 (13368): VM state change detected. (old = 'Paused', new = 'Running') 2019-11-09 20:33:49 (13368): VM state change detected. (old = 'Running', new = 'Paused') 2019-11-09 20:33:59 (13368): VM state change detected. (old = 'Paused', new = 'Running') 2019-11-09 20:34:09 (13368): VM state change detected. (old = 'Running', new = 'Paused') 2019-11-09 20:34:19 (13368): VM state change detected. (old = 'Paused', new = 'Running') 2019-11-09 20:36:13 (13368): VM state change detected. (old = 'Running', new = 'Paused') 2019-11-09 22:20:41 (13368): Stopping VM. 2019-11-09 22:20:41 (13368): Error in stop VM for VM: -108 Command: VBoxManage -q controlvm "boinc_11bbdcf43962130e" savestate Output: 2019-11-09 22:20:41 (13368): VM did not stop when requested. 2019-11-09 22:20:41 (13368): VM was successfully terminated. 2019-11-28 19:23:07 (14188): Detected: vboxwrapper 26197 2019-11-28 19:23:07 (14188): Detected: BOINC client v7.7 2019-11-28 19:23:09 (14188): Detected: VirtualBox VboxManage Interface (Version: 5.2.28) 2019-11-28 19:23:09 (14188): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds) 2019-11-28 19:23:10 (14188): Starting VM using VBoxManage interface. (boinc_11bbdcf43962130e, slot#3) 2019-11-28 19:23:16 (14188): Successfully started VM. (PID = '10072') 2019-11-28 19:23:16 (14188): Reporting VM Process ID to BOINC. 2019-11-28 19:23:16 (14188): Guest Log: BIOS: VirtualBox 5.2.28 2019-11-28 19:23:16 (14188): Guest Log: CPUID EDX: 0x178bfbff 2019-11-28 19:23:16 (14188): Guest Log: BIOS: ata0-0: PCHS=16383/16/63 LCHS=1024/255/63 2019-11-28 19:23:16 (14188): VM state change detected. (old = 'PoweredOff', new = 'Running') 2019-11-28 19:23:16 (14188): Detected: Web Application Enabled (http://localhost:60730) 2019-11-28 19:23:16 (14188): Detected: Remote Desktop Enabled (localhost:60731) 2019-11-28 19:23:16 (14188): Status Report: Job Duration: '129600.000000' 2019-11-28 19:23:16 (14188): Status Report: Elapsed Time: '129167.102587' 2019-11-28 19:23:16 (14188): Status Report: CPU Time: '253177.531250' 2019-11-28 19:23:16 (14188): Preference change detected 2019-11-28 19:23:16 (14188): Setting CPU throttle for VM. (100%) 2019-11-28 19:23:17 (14188): Setting network throttle for VM. (250KB) 2019-11-28 19:23:17 (14188): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds)) 2019-11-28 19:23:18 (14188): VM state change detected. (old = 'Running', new = 'Paused') 2019-11-28 19:23:39 (14188): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032 2019-11-28 19:23:39 (14188): Guest Log: BIOS: Booting from Hard Disk... 2019-11-28 19:23:39 (14188): VM state change detected. (old = 'Paused', new = 'Running') 2019-11-28 19:23:42 (14188): Guest Log: BIOS: KBD: unsupported int 16h function 03 2019-11-28 19:23:42 (14188): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 2019-11-28 19:23:49 (14188): VM state change detected. (old = 'Running', new = 'Paused') 2019-11-28 19:23:59 (14188): VM state change detected. (old = 'Paused', new = 'Running') 2019-11-28 19:24:58 (14188): VM state change detected. (old = 'Running', new = 'Paused') 2019-11-28 19:25:00 (14188): VM state change detected. (old = 'Paused', new = 'Running') 2019-11-28 19:25:12 (14188): VM state change detected. (old = 'Running', new = 'Paused') 2019-11-28 19:25:20 (14188): VM state change detected. (old = 'Paused', new = 'Running') 2019-11-28 19:25:24 (14188): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds 2019-11-28 19:25:24 (14188): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000) 2019-11-28 19:26:31 (14188): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log 2019-11-28 19:26:31 (14188): Guest Log: 00:00:00.000357 main Log opened 2019-11-28T08:26:37.573242000Z 2019-11-28 19:26:31 (14188): Guest Log: 00:00:00.000679 main OS Product: Linux 2019-11-28 19:26:31 (14188): Guest Log: 00:00:00.000731 main OS Release: 4.14.44-11.cernvm.x86_64 2019-11-28 19:26:31 (14188): Guest Log: 00:00:00.000781 main OS Version: #1 SMP Mon May 28 16:49:00 CEST 2018 2019-11-28 19:26:31 (14188): Guest Log: 00:00:00.000827 main Executable: /usr/share/vboxguest52/usr/sbin/VBoxService 2019-11-28 19:26:31 (14188): Guest Log: 00:00:00.000830 main Process ID: 2943 2019-11-28 19:26:31 (14188): Guest Log: 00:00:00.000831 main Package type: LINUX_64BITS_GENERIC 2019-11-28 19:26:31 (14188): Guest Log: 00:00:00.002944 main 5.2.6 r120293 started. Verbose level = 0 2019-11-28 19:26:51 (14188): VM state change detected. (old = 'Running', new = 'Paused') 2019-11-28 19:50:32 (14188): VM state change detected. (old = 'Paused', new = 'Running') 2019-11-28 19:51:00 (14188): Guest Log: [INFO] Mounting the shared directory 2019-11-28 19:51:00 (14188): Guest Log: [INFO] Shared directory mounted, enabling vboxmonitor 2019-11-28 19:51:00 (14188): Guest Log: [DEBUG] Testing network connection to cern.ch on port 80 2019-11-28 19:51:01 (14188): Guest Log: [DEBUG] Connection to cern.ch 80 port [tcp/http] succeeded! 2019-11-28 19:51:01 (14188): Guest Log: [DEBUG] 0 2019-11-28 19:51:01 (14188): Guest Log: [DEBUG] Testing VCCS connection to vccs.cern.ch on port 443 2019-11-28 19:51:02 (14188): Guest Log: [DEBUG] Connection to vccs.cern.ch 443 port [tcp/https] succeeded! 2019-11-28 19:51:02 (14188): Guest Log: [DEBUG] 0 2019-11-28 19:51:02 (14188): Guest Log: [DEBUG] Testing connection to Condor server on port 9618 2019-11-28 19:51:03 (14188): Guest Log: [DEBUG] Connection to vccondor01.cern.ch 9618 port [tcp/condor] succeeded! 2019-11-28 19:51:03 (14188): Guest Log: [DEBUG] 0 2019-11-28 19:51:42 (14188): VM state change detected. (old = 'Running', new = 'Paused') 2019-11-28 19:53:58 (14188): VM state change detected. (old = 'Paused', new = 'Running') 2019-11-28 19:54:07 (14188): VM state change detected. (old = 'Running', new = 'Paused') 2019-11-28 19:55:08 (14188): VM state change detected. (old = 'Paused', new = 'Running') 2019-11-28 19:55:18 (14188): VM state change detected. (old = 'Running', new = 'Paused') 2019-11-28 19:56:50 (14188): VM state change detected. (old = 'Paused', new = 'Running') 2019-11-28 19:57:04 (14188): Guest Log: [DEBUG] Probing CVMFS ... 2019-11-28 19:57:04 (14188): Guest Log: Probing /cvmfs/grid.cern.ch... OK 2019-11-28 19:57:04 (14188): 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-11-28 19:57:04 (14188): Guest Log: 2.4.4.0 3647 1 27828 10853 3 1 1189685 10240001 2 65024 0 3 100 0 0 http://s1asgc-cvmfs.openhtc.io:8080/cvmfs/grid.cern.ch DIRECT 1 2019-11-28 19:57:04 (14188): VM state change detected. (old = 'Running', new = 'Paused') 2019-11-28 19:57:17 (14188): VM state change detected. (old = 'Paused', new = 'Running') 2019-11-28 19:57:40 (14188): Guest Log: [INFO] Reading volunteer information 2019-11-28 19:57:40 (14188): VM state change detected. (old = 'Running', new = 'Paused') 2019-11-28 19:57:56 (14188): VM state change detected. (old = 'Paused', new = 'Running') 2019-11-28 19:58:14 (14188): Guest Log: [INFO] Volunteer: Duff_Moss (127134) 2019-11-28 19:58:14 (14188): Guest Log: [INFO] VMID: e5b01749-9626-457a-8945-16c64690aa2f 2019-11-28 19:58:14 (14188): Guest Log: [INFO] Requesting an X509 credential from LHC@home 2019-11-28 19:58:14 (14188): VM state change detected. (old = 'Running', new = 'Paused') 2019-11-28 19:58:31 (14188): VM state change detected. (old = 'Paused', new = 'Running') 2019-11-28 19:58:49 (14188): Guest Log: [INFO] Running the fast benchmark. 2019-11-28 19:58:49 (14188): VM state change detected. (old = 'Running', new = 'Paused') 2019-11-28 19:59:05 (14188): VM state change detected. (old = 'Paused', new = 'Running') 2019-11-28 19:59:28 (14188): VM state change detected. (old = 'Running', new = 'Paused') 2019-11-28 20:02:58 (14188): VM state change detected. (old = 'Paused', new = 'Running') 2019-11-28 20:03:17 (14188): VM state change detected. (old = 'Running', new = 'Paused') 2019-11-28 20:03:52 (14188): VM state change detected. (old = 'Paused', new = 'Running') 2019-11-28 20:04:11 (14188): VM state change detected. (old = 'Running', new = 'Paused') 2019-11-28 20:04:40 (14188): VM state change detected. (old = 'Paused', new = 'Running') 2019-11-28 20:05:15 (14188): VM state change detected. (old = 'Running', new = 'Paused') 2019-11-28 20:05:30 (14188): VM state change detected. (old = 'Paused', new = 'Running') 2019-11-28 20:06:27 (14188): VM state change detected. (old = 'Running', new = 'Paused') 2019-11-28 20:06:45 (14188): VM state change detected. (old = 'Paused', new = 'Running') 2019-11-28 20:07:16 (14188): Guest Log: [INFO] Machine performance 5.18 HEPSPEC06 2019-11-28 20:07:16 (14188): Guest Log: [INFO] Theory application starting. Check log files. 2019-11-28 20:07:20 (14188): Guest Log: [DEBUG] HTCondor ping 2019-11-28 20:07:25 (14188): Guest Log: [DEBUG] 0 2019-11-28 20:07:38 (14188): Powering off VM. 2019-11-28 20:12:56 (14188): VM did not power off when requested. 2019-11-28 20:12:56 (14188): VM was successfully terminated. 2019-11-28 20:12:56 (14188): Deregistering VM. (boinc_11bbdcf43962130e, slot#3) 2019-11-28 20:12:57 (14188): Removing network bandwidth throttle group from VM. 2019-11-28 20:12:58 (14188): Removing VM from VirtualBox. 20:13:03 (14188): called boinc_finish(0) </stderr_txt> ]]>
©2025 CERN