| Name | Theory_2390-1153369-18_0 |
| Workunit | 142070873 |
| Created | 21 Jun 2020, 14:02:19 UTC |
| Sent | 21 Jun 2020, 17:38:48 UTC |
| Report deadline | 2 Jul 2020, 17:38:48 UTC |
| Received | 3 Jul 2020, 13:23:39 UTC |
| Server state | Over |
| Outcome | Success |
| Client state | Done |
| Exit status | 0 (0x00000000) |
| Computer ID | 10643792 |
| Run time | 4 hours 30 min 22 sec |
| CPU time | 2 hours 42 min 11 sec |
| Validate state | Valid |
| Credit | 112.46 |
| Device peak FLOPS | 2.99 GFLOPS |
| Application version | Theory Simulation v300.06 (vbox64_theory) windows_x86_64 |
| Peak working set size | 59.14 MB |
| Peak swap size | 78.73 MB |
| Peak disk usage | 3.89 GB |
Stderr output
<core_client_version>7.16.5</core_client_version> <![CDATA[ <stderr_txt> 2020-06-21 20:43:20 (11596): Detected: vboxwrapper 26197 2020-06-21 20:43:20 (11596): Detected: BOINC client v7.7 2020-06-21 20:43:27 (11596): Detected: VirtualBox VboxManage Interface (Version: 6.0.14) 2020-06-21 20:43:27 (11596): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds) 2020-06-21 20:43:27 (11596): Successfully copied 'init_data.xml' to the shared directory. 2020-06-21 20:43:27 (11596): Successfully copied 'input' to the shared directory. 2020-06-21 20:43:28 (11596): Create VM. (boinc_576362c9e317eea1, slot#3) 2020-06-21 20:43:29 (11596): Setting Memory Size for VM. (630MB) 2020-06-21 20:43:29 (11596): Setting CPU Count for VM. (1) 2020-06-21 20:43:29 (11596): Setting Chipset Options for VM. 2020-06-21 20:43:29 (11596): Setting Boot Options for VM. 2020-06-21 20:43:30 (11596): Setting Network Configuration for NAT. 2020-06-21 20:43:30 (11596): Enabling VM Network Access. 2020-06-21 20:43:30 (11596): Disabling USB Support for VM. 2020-06-21 20:43:31 (11596): Disabling COM Port Support for VM. 2020-06-21 20:43:31 (11596): Disabling LPT Port Support for VM. 2020-06-21 20:43:31 (11596): Disabling Audio Support for VM. 2020-06-21 20:43:31 (11596): Disabling Clipboard Support for VM. 2020-06-21 20:43:32 (11596): Disabling Drag and Drop Support for VM. 2020-06-21 20:43:32 (11596): Adding storage controller(s) to VM. 2020-06-21 20:43:33 (11596): Adding virtual disk drive to VM. (vm_image.vdi) 2020-06-21 20:43:33 (11596): Adding VirtualBox Guest Additions to VM. 2020-06-21 20:43:33 (11596): Adding network bandwidth throttle group to VM. (Defaulting to 1024GB) 2020-06-21 20:43:33 (11596): forwarding host port 57647 to guest port 80 2020-06-21 20:43:34 (11596): Enabling remote desktop for VM. 2020-06-21 20:43:34 (11596): Required extension pack not installed, remote desktop not enabled. 2020-06-21 20:43:34 (11596): Enabling shared directory for VM. 2020-06-21 20:43:34 (11596): Starting VM using VBoxManage interface. (boinc_576362c9e317eea1, slot#3) 2020-06-21 20:43:40 (11596): Successfully started VM. (PID = '11872') 2020-06-21 20:43:40 (11596): Reporting VM Process ID to BOINC. 2020-06-21 20:43:40 (11596): Guest Log: BIOS: VirtualBox 6.0.14 2020-06-21 20:43:40 (11596): Guest Log: CPUID EDX: 0x178bfbff 2020-06-21 20:43:40 (11596): Guest Log: BIOS: ata0-0: PCHS=16383/16/63 LCHS=1024/255/63 2020-06-21 20:43:40 (11596): VM state change detected. (old = 'PoweredOff', new = 'Running') 2020-06-21 20:43:40 (11596): Detected: Web Application Enabled (http://localhost:57647) 2020-06-21 20:43:40 (11596): Preference change detected 2020-06-21 20:43:40 (11596): Setting CPU throttle for VM. (80%) 2020-06-21 20:43:41 (11596): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds)) 2020-06-21 20:43:42 (11596): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032 2020-06-21 20:43:42 (11596): Guest Log: BIOS: Booting from Hard Disk... 2020-06-21 20:43:45 (11596): Guest Log: BIOS: KBD: unsupported int 16h function 03 2020-06-21 20:43:45 (11596): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 2020-06-21 20:44:05 (11596): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds 2020-06-21 20:44:05 (11596): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000) 2020-06-21 20:44:09 (11596): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log 2020-06-21 20:44:09 (11596): Guest Log: 00:00:00.000130 main Log opened 2020-06-21T17:44:09.527946000Z 2020-06-21 20:44:09 (11596): Guest Log: 00:00:00.000311 main OS Product: Linux 2020-06-21 20:44:09 (11596): Guest Log: 00:00:00.000351 main OS Release: 4.14.76-13.cernvm.x86_64 2020-06-21 20:44:09 (11596): Guest Log: 00:00:00.000386 main OS Version: #1 SMP Tue Oct 16 18:26:15 CEST 2018 2020-06-21 20:44:09 (11596): Guest Log: 00:00:00.000419 main Executable: /usr/sbin/VBoxService 2020-06-21 20:44:09 (11596): Guest Log: 00:00:00.000420 main Process ID: 3190 2020-06-21 20:44:09 (11596): Guest Log: 00:00:00.000421 main Package type: LINUX_64BITS_GENERIC 2020-06-21 20:44:09 (11596): Guest Log: 00:00:00.004097 main 5.2.6 r120293 started. Verbose level = 0 2020-06-21 20:45:15 (11596): Guest Log: 20:45:15 EEST +03:00 2020-06-21: cranky: [INFO] Detected Theory App 2020-06-21 20:45:15 (11596): Guest Log: 20:45:15 EEST +03:00 2020-06-21: cranky: [INFO] Checking CVMFS. 2020-06-21 20:45:16 (11596): Guest Log: Probing /cvmfs/sft.cern.ch... OK 2020-06-21 20:45:18 (11596): 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 2020-06-21 20:45:18 (11596): Guest Log: 2.5.2.0 4052 0 27184 18307 3 1 263671 4096000 0 65024 0 0 n/a 5 0 http://s1cern-cvmfs.openhtc.io/cvmfs/sft.cern.ch DIRECT 1 2020-06-21 20:45:18 (11596): Guest Log: Probing /cvmfs/grid.cern.ch... OK 2020-06-21 20:45:19 (11596): 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 2020-06-21 20:45:19 (11596): Guest Log: 2.5.2.0 4092 1 27556 12533 3 2 263671 4096000 0 65024 0 2 -100 6410 149 http://s1cern-cvmfs.openhtc.io/cvmfs/grid.cern.ch DIRECT 1 2020-06-21 20:45:20 (11596): Guest Log: Probing /cvmfs/cernvm-prod.cern.ch... OK 2020-06-21 20:45:20 (11596): 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 2020-06-21 20:45:20 (11596): Guest Log: 2.5.2.0 4029 1 27228 255 3 1 263671 4096000 0 65024 0 0 n/a 4 2 http://s1unl-cvmfs.openhtc.io/cvmfs/cernvm-prod.cern.ch DIRECT 1 2020-06-21 20:45:21 (11596): Guest Log: Probing /cvmfs/alice.cern.ch... OK 2020-06-21 20:45:22 (11596): 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 2020-06-21 20:45:22 (11596): Guest Log: 2.5.2.0 4118 1 27844 6846 3 1 263671 4096000 0 65024 0 0 n/a 4151 119 http://s1cern-cvmfs.openhtc.io/cvmfs/alice.cern.ch DIRECT 1 2020-06-21 20:45:22 (11596): Guest Log: 20:45:22 EEST +03:00 2020-06-21: cranky: [INFO] Checking runc. 2020-06-21 20:45:22 (11596): Guest Log: 20:45:22 EEST +03:00 2020-06-21: cranky: [INFO] Creating the filesystem. 2020-06-21 20:45:22 (11596): Guest Log: 20:45:22 EEST +03:00 2020-06-21: cranky: [INFO] Using /cvmfs/cernvm-prod.cern.ch/cvm3 2020-06-21 20:45:22 (11596): Guest Log: 20:45:22 EEST +03:00 2020-06-21: cranky: [INFO] Updating config.json. 2020-06-21 20:45:22 (11596): Guest Log: 20:45:22 EEST +03:00 2020-06-21: cranky: [INFO] Running Container 'runc'. 2020-06-21 20:45:22 (11596): Guest Log: job: htmld=/shared/html/job 2020-06-21 20:45:22 (11596): Guest Log: job: unpack exitcode=0 2020-06-21 20:45:24 (11596): Guest Log: 20:45:24 EEST +03:00 2020-06-21: cranky: [INFO] ===> [runRivet] Sun Jun 21 17:45:22 UTC 2020 [boinc pp zinclusive 7000 -,-,50,130 - madgraph5amc 2.6.7.atlas2 lo 100000 18] 2020-06-21 21:41:33 (11596): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-21 21:41:43 (11596): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-21 22:23:54 (11596): Status Report: Job Duration: '864000.000000' 2020-06-21 22:23:54 (11596): Status Report: Elapsed Time: '6000.000000' 2020-06-21 22:23:54 (11596): Status Report: CPU Time: '1256.541255' 2020-06-21 22:56:00 (11596): Stopping VM. 2020-06-22 04:06:19 (11596): VM did not stop when requested. 2020-06-22 04:06:19 (11596): VM was successfully terminated. 2020-06-22 04:40:33 (7316): Detected: vboxwrapper 26197 2020-06-22 04:40:33 (7316): Detected: BOINC client v7.7 2020-06-22 04:40:35 (7316): Detected: VirtualBox VboxManage Interface (Version: 6.0.14) 2020-06-22 04:40:35 (7316): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds) 2020-06-22 04:40:35 (7316): Starting VM using VBoxManage interface. (boinc_576362c9e317eea1, slot#3) 2020-06-22 04:40:45 (7316): Successfully started VM. (PID = '12512') 2020-06-22 04:40:45 (7316): Reporting VM Process ID to BOINC. 2020-06-22 04:40:45 (7316): VM state change detected. (old = 'PoweredOff', new = 'Running') 2020-06-22 04:40:45 (7316): Detected: Web Application Enabled (http://localhost:57647) 2020-06-22 04:40:46 (7316): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-22 04:40:53 (7316): Status Report: Job Duration: '864000.000000' 2020-06-22 04:40:53 (7316): Status Report: Elapsed Time: '7923.000000' 2020-06-22 04:40:53 (7316): Status Report: CPU Time: '2985.391137' 2020-06-22 04:40:53 (7316): Preference change detected 2020-06-22 04:40:53 (7316): Setting CPU throttle for VM. (80%) 2020-06-22 04:40:53 (7316): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds)) 2020-06-22 04:40:54 (7316): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-22 04:41:01 (7316): Guest Log: 02:11:51.985584 timesync vgsvcTimeSyncWorker: Radical host time change: 20 701 537 000 000ns (HostNow=1 592 790 060 410 000 000 ns HostLast=1 592 769 358 873 000 000 ns) 2020-06-22 04:41:11 (7316): Guest Log: 02:12:02.015641 timesync vgsvcTimeSyncWorker: Radical guest time change: 20 709 075 690 000ns (GuestNow=1 592 790 070 498 237 000 ns GuestLast=1 592 769 361 422 547 000 ns fSetTimeLastLoop=true ) 2020-06-22 04:42:34 (7316): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-22 04:44:14 (7316): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-22 04:44:25 (7316): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-22 04:44:45 (7316): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-22 04:44:54 (7316): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-22 04:50:10 (7316): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-22 04:50:34 (7316): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-22 04:50:44 (7316): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-22 04:51:04 (7316): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-22 04:51:14 (7316): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-22 04:51:34 (7316): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-22 04:51:54 (7316): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-22 04:52:05 (7316): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-22 04:52:15 (7316): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-22 05:14:40 (7316): Stopping VM. 2020-06-22 05:34:21 (11824): Detected: vboxwrapper 26197 2020-06-22 05:34:21 (11824): Detected: BOINC client v7.7 2020-06-22 05:34:22 (11824): Detected: VirtualBox VboxManage Interface (Version: 6.0.14) 2020-06-22 05:34:23 (11824): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds) 2020-06-22 05:34:23 (11824): Starting VM using VBoxManage interface. (boinc_576362c9e317eea1, slot#3) 2020-06-22 05:34:31 (11824): Successfully started VM. (PID = '3152') 2020-06-22 05:34:31 (11824): Reporting VM Process ID to BOINC. 2020-06-22 05:34:31 (11824): VM state change detected. (old = 'PoweredOff', new = 'Running') 2020-06-22 05:34:31 (11824): Detected: Web Application Enabled (http://localhost:57647) 2020-06-22 05:34:31 (11824): Status Report: Job Duration: '864000.000000' 2020-06-22 05:34:31 (11824): Status Report: Elapsed Time: '9455.000000' 2020-06-22 05:34:31 (11824): Status Report: CPU Time: '4349.448281' 2020-06-22 05:34:31 (11824): Preference change detected 2020-06-22 05:34:31 (11824): Setting CPU throttle for VM. (80%) 2020-06-22 05:34:31 (11824): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds)) 2020-06-22 05:36:40 (11824): Stopping VM. 2020-06-22 05:40:18 (9008): Detected: vboxwrapper 26197 2020-06-22 05:40:18 (9008): Detected: BOINC client v7.7 2020-06-22 05:40:19 (9008): Detected: VirtualBox VboxManage Interface (Version: 6.0.14) 2020-06-22 05:40:20 (9008): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds) 2020-06-22 05:40:20 (9008): Starting VM using VBoxManage interface. (boinc_576362c9e317eea1, slot#3) 2020-06-22 05:40:28 (9008): Successfully started VM. (PID = '12668') 2020-06-22 05:40:28 (9008): Reporting VM Process ID to BOINC. 2020-06-22 05:40:28 (9008): VM state change detected. (old = 'PoweredOff', new = 'Running') 2020-06-22 05:40:28 (9008): Detected: Web Application Enabled (http://localhost:57647) 2020-06-22 05:40:28 (9008): Status Report: Job Duration: '864000.000000' 2020-06-22 05:40:28 (9008): Status Report: Elapsed Time: '9584.000000' 2020-06-22 05:40:28 (9008): Status Report: CPU Time: '4461.628600' 2020-06-22 05:40:28 (9008): Preference change detected 2020-06-22 05:40:28 (9008): Setting CPU throttle for VM. (80%) 2020-06-22 05:40:29 (9008): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds)) 2020-06-22 05:41:37 (9008): Stopping VM. 2020-06-22 05:41:41 (9008): Error in stop VM for VM: -2147024891 Command: VBoxManage -q controlvm "boinc_576362c9e317eea1" savestate Output: 0%...10%...20%...30%...40%...50%...60%...70%...80%...90%... Progress state: E_ACCESSDENIED VBoxManage.exe: error: Failed to save machine state VBoxManage.exe: error: Assertion failed: [SUCCEEDED(rc)] at 'F:\tinderbox\win-6.0\src\VBox\Main\src-server\MachineImpl.cpp' (10511) in long __cdecl Machine::i_saveStorageControllers(struct settings::Storage &). VBoxManage.exe: error: COM RC=E_ACCESSDENIED (0x80070005). VBoxManage.exe: error: Please contact the product vendor! VBoxManage.exe: error: Details: code E_FAIL (0x80004005), component SessionMachine, interface IMachine VBoxManage.exe: error: Context: "enum RTEXITCODE __cdecl handleControlVM(struct HandlerArg *)" at line 622 of file VBoxManageControlVM.cpp 2020-06-22 05:41:41 (9008): VM did not stop when requested. 2020-06-22 05:41:41 (9008): VM was successfully terminated. 2020-06-22 06:00:18 (12348): Detected: vboxwrapper 26197 2020-06-22 06:00:18 (12348): Detected: BOINC client v7.7 2020-06-22 06:01:03 (12348): Detected: VirtualBox VboxManage Interface (Version: 6.0.14) 2020-06-22 06:01:03 (12348): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds) 2020-06-22 06:01:03 (12348): Starting VM using VBoxManage interface. (boinc_576362c9e317eea1, slot#3) 2020-06-22 06:01:10 (12348): Successfully started VM. (PID = '10848') 2020-06-22 06:01:10 (12348): Reporting VM Process ID to BOINC. 2020-06-22 06:01:10 (12348): Guest Log: BIOS: VirtualBox 6.0.14 2020-06-22 06:01:10 (12348): Guest Log: CPUID EDX: 0x178bfbff 2020-06-22 06:01:10 (12348): Guest Log: BIOS: ata0-0: PCHS=16383/16/63 LCHS=1024/255/63 2020-06-22 06:01:10 (12348): VM state change detected. (old = 'PoweredOff', new = 'Running') 2020-06-22 06:01:10 (12348): Detected: Web Application Enabled (http://localhost:57647) 2020-06-22 06:01:10 (12348): Status Report: Job Duration: '864000.000000' 2020-06-22 06:01:10 (12348): Status Report: Elapsed Time: '9653.000000' 2020-06-22 06:01:10 (12348): Status Report: CPU Time: '4519.754573' 2020-06-22 06:01:10 (12348): Preference change detected 2020-06-22 06:01:10 (12348): Setting CPU throttle for VM. (80%) 2020-06-22 06:01:11 (12348): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds)) 2020-06-22 06:01:11 (12348): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032 2020-06-22 06:01:12 (12348): Guest Log: BIOS: Booting from Hard Disk... 2020-06-22 06:01:14 (12348): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-22 06:01:24 (12348): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-22 17:53:29 (12348): Stopping VM. 2020-06-22 17:58:14 (10712): Detected: vboxwrapper 26197 2020-06-22 17:58:14 (10712): Detected: BOINC client v7.7 2020-06-22 17:59:47 (10712): Detected: VirtualBox VboxManage Interface (Version: 6.0.14) 2020-06-22 17:59:48 (10712): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds) 2020-06-22 17:59:48 (10712): Starting VM using VBoxManage interface. (boinc_576362c9e317eea1, slot#3) 2020-06-22 18:00:03 (10712): Successfully started VM. (PID = '11180') 2020-06-22 18:00:03 (10712): Reporting VM Process ID to BOINC. 2020-06-22 18:00:03 (10712): Guest Log: BIOS: VirtualBox 6.0.14 2020-06-22 18:00:03 (10712): Guest Log: CPUID EDX: 0x178bfbff 2020-06-22 18:00:03 (10712): Guest Log: BIOS: ata0-0: PCHS=16383/16/63 LCHS=1024/255/63 2020-06-22 18:00:03 (10712): VM state change detected. (old = 'PoweredOff', new = 'Running') 2020-06-22 18:00:03 (10712): Detected: Web Application Enabled (http://localhost:57647) 2020-06-22 18:00:03 (10712): Status Report: Job Duration: '864000.000000' 2020-06-22 18:00:03 (10712): Status Report: Elapsed Time: '9758.243950' 2020-06-22 18:00:03 (10712): Status Report: CPU Time: '4607.707937' 2020-06-22 18:00:03 (10712): Preference change detected 2020-06-22 18:00:03 (10712): Setting CPU throttle for VM. (80%) 2020-06-22 18:00:03 (10712): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds)) 2020-06-22 18:00:05 (10712): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032 2020-06-22 18:00:05 (10712): Guest Log: BIOS: Booting from Hard Disk... 2020-06-22 18:00:17 (10712): Guest Log: BIOS: KBD: unsupported int 16h function 03 2020-06-22 18:00:17 (10712): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 2020-06-22 18:03:44 (10712): Error in pause VM for VM: -2135228414 Command: VBoxManage -q controlvm "boinc_576362c9e317eea1" pause Output: VBoxManage.exe: error: Already paused VBoxManage.exe: error: Details: code VBOX_E_INVALID_VM_STATE (0x80bb0002), component ConsoleWrap, interface IConsole, callee IUnknown VBoxManage.exe: error: Context: "Pause()" at line 405 of file VBoxManageControlVM.cpp 2020-06-22 18:03:45 (10712): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-22 18:03:46 (10712): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-22 18:05:48 (10712): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-22 18:05:57 (10712): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-22 18:06:00 (10712): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds 2020-06-22 18:06:00 (10712): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000) 2020-06-22 18:06:32 (10712): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log 2020-06-22 18:06:32 (10712): Guest Log: 00:00:00.000653 main Log opened 2020-06-22T15:06:32.465386000Z 2020-06-22 18:06:32 (10712): Guest Log: 00:00:00.001212 main OS Product: Linux 2020-06-22 18:06:32 (10712): Guest Log: 00:00:00.001427 main OS Release: 4.14.76-13.cernvm.x86_64 2020-06-22 18:06:32 (10712): Guest Log: 00:00:00.001473 main OS Version: #1 SMP Tue Oct 16 18:26:15 CEST 2018 2020-06-22 18:06:32 (10712): Guest Log: 00:00:00.001518 main Executable: /usr/sbin/VBoxService 2020-06-22 18:06:32 (10712): Guest Log: 00:00:00.001519 main Process ID: 3222 2020-06-22 18:06:32 (10712): Guest Log: 00:00:00.001520 main Package type: LINUX_64BITS_GENERIC 2020-06-22 18:06:32 (10712): Guest Log: 00:00:00.011394 main 5.2.6 r120293 started. Verbose level = 0 2020-06-22 18:07:48 (10712): Guest Log: 18:07:48 EEST +03:00 2020-06-22: cranky: [INFO] Detected Theory App 2020-06-22 18:07:48 (10712): Guest Log: 18:07:48 EEST +03:00 2020-06-22: cranky: [INFO] Checking CVMFS. 2020-06-22 18:07:49 (10712): Guest Log: Probing /cvmfs/sft.cern.ch... OK 2020-06-22 18:07:49 (10712): 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 2020-06-22 18:07:49 (10712): Guest Log: 2.5.2.0 4110 0 27176 18309 3 1 1742357 4096000 0 65024 0 0 n/a 5 0 http://s1ral-cvmfs.openhtc.io/cvmfs/sft.cern.ch DIRECT 1 2020-06-22 18:07:50 (10712): Guest Log: Probing /cvmfs/grid.cern.ch... OK 2020-06-22 18:07:51 (10712): 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 2020-06-22 18:07:51 (10712): Guest Log: 2.5.2.0 4132 0 27752 12541 3 2 1742357 4096000 0 65024 0 2 0 4273 1102 http://s1cern-cvmfs.openhtc.io/cvmfs/grid.cern.ch DIRECT 1 2020-06-22 18:07:52 (10712): Guest Log: Probing /cvmfs/cernvm-prod.cern.ch... OK 2020-06-22 18:07:52 (10712): 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 2020-06-22 18:07:52 (10712): Guest Log: 2.5.2.0 4088 0 27220 255 3 1 1742357 4096000 0 65024 0 0 n/a 0 0 http://s1cern-cvmfs.openhtc.io/cvmfs/cernvm-prod.cern.ch DIRECT 1 2020-06-22 18:07:53 (10712): Guest Log: Probing /cvmfs/alice.cern.ch... OK 2020-06-22 18:07:54 (10712): 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 2020-06-22 18:07:54 (10712): Guest Log: 2.5.2.0 4152 0 31144 6854 3 1 1742357 4096000 0 65024 0 0 n/a 4153 934 http://s1unl-cvmfs.openhtc.io/cvmfs/alice.cern.ch DIRECT 1 2020-06-22 18:07:54 (10712): Guest Log: 18:07:55 EEST +03:00 2020-06-22: cranky: [INFO] Checking runc. 2020-06-22 18:07:56 (10712): Guest Log: 18:07:57 EEST +03:00 2020-06-22: cranky: [INFO] Creating the filesystem. 2020-06-22 18:07:58 (10712): Guest Log: 18:07:58 EEST +03:00 2020-06-22: cranky: [INFO] Using /cvmfs/cernvm-prod.cern.ch/cvm3 2020-06-22 18:07:58 (10712): Guest Log: 18:07:58 EEST +03:00 2020-06-22: cranky: [INFO] Updating config.json. 2020-06-22 18:07:58 (10712): Guest Log: 18:07:58 EEST +03:00 2020-06-22: cranky: [INFO] Running Container 'runc'. 2020-06-22 18:08:21 (10712): Guest Log: job: htmld=/shared/html/job 2020-06-22 18:08:22 (10712): Guest Log: job: unpack exitcode=0 2020-06-22 18:08:23 (10712): Guest Log: 18:08:23 EEST +03:00 2020-06-22: cranky: [INFO] ===> [runRivet] Mon Jun 22 15:08:23 UTC 2020 [boinc pp zinclusive 7000 -,-,50,130 - madgraph5amc 2.6.7.atlas2 lo 100000 18] 2020-06-22 18:11:56 (10712): Stopping VM. 2020-06-22 19:08:29 (6632): Detected: vboxwrapper 26197 2020-06-22 19:08:29 (6632): Detected: BOINC client v7.7 2020-06-22 19:08:29 (6632): Detected: VirtualBox VboxManage Interface (Version: 6.0.14) 2020-06-22 19:08:29 (6632): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds) 2020-06-22 19:08:30 (6632): Starting VM using VBoxManage interface. (boinc_576362c9e317eea1, slot#3) 2020-06-22 19:08:49 (6632): Successfully started VM. (PID = '11596') 2020-06-22 19:08:49 (6632): Reporting VM Process ID to BOINC. 2020-06-22 19:08:49 (6632): VM state change detected. (old = 'PoweredOff', new = 'Running') 2020-06-22 19:08:49 (6632): Detected: Web Application Enabled (http://localhost:57647) 2020-06-22 19:08:49 (6632): Stopping VM. 2020-06-22 19:27:33 (10356): Detected: vboxwrapper 26197 2020-06-22 19:27:33 (10356): Detected: BOINC client v7.7 2020-06-22 19:27:34 (10356): Detected: VirtualBox VboxManage Interface (Version: 6.0.14) 2020-06-22 19:27:34 (10356): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds) 2020-06-22 19:27:34 (10356): Starting VM using VBoxManage interface. (boinc_576362c9e317eea1, slot#3) 2020-06-22 19:27:43 (10356): Successfully started VM. (PID = '8232') 2020-06-22 19:27:43 (10356): Reporting VM Process ID to BOINC. 2020-06-22 19:27:43 (10356): VM state change detected. (old = 'PoweredOff', new = 'Running') 2020-06-22 19:27:43 (10356): Detected: Web Application Enabled (http://localhost:57647) 2020-06-22 19:27:43 (10356): Status Report: Job Duration: '864000.000000' 2020-06-22 19:27:43 (10356): Status Report: Elapsed Time: '10328.243950' 2020-06-22 19:27:43 (10356): Status Report: CPU Time: '4692.338480' 2020-06-22 19:27:43 (10356): Preference change detected 2020-06-22 19:27:43 (10356): Setting CPU throttle for VM. (80%) 2020-06-22 19:27:43 (10356): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds)) 2020-06-22 19:27:48 (10356): Guest Log: 00:05:31.985248 timesync vgsvcTimeSyncWorker: Radical host time change: 4 553 569 000 000ns (HostNow=1 592 843 266 790 000 000 ns HostLast=1 592 838 713 221 000 000 ns) 2020-06-22 19:27:58 (10356): Guest Log: 00:05:42.043589 timesync vgsvcTimeSyncWorker: Radical guest time change: 4 552 956 231 000ns (GuestNow=1 592 843 277 039 294 000 ns GuestLast=1 592 838 724 083 063 000 ns fSetTimeLastLoop=true ) 2020-06-22 19:32:56 (10356): Stopping VM. 2020-06-22 19:42:58 (12528): Detected: vboxwrapper 26197 2020-06-22 19:42:58 (12528): Detected: BOINC client v7.7 2020-06-22 19:42:59 (12528): Detected: VirtualBox VboxManage Interface (Version: 6.0.14) 2020-06-22 19:42:59 (12528): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds) 2020-06-22 19:42:59 (12528): Starting VM using VBoxManage interface. (boinc_576362c9e317eea1, slot#3) 2020-06-22 19:43:09 (12528): Successfully started VM. (PID = '14324') 2020-06-22 19:43:09 (12528): Reporting VM Process ID to BOINC. 2020-06-22 19:43:09 (12528): VM state change detected. (old = 'PoweredOff', new = 'Running') 2020-06-22 19:43:09 (12528): Detected: Web Application Enabled (http://localhost:57647) 2020-06-22 19:43:09 (12528): Status Report: Job Duration: '864000.000000' 2020-06-22 19:43:09 (12528): Status Report: Elapsed Time: '10640.243950' 2020-06-22 19:43:09 (12528): Status Report: CPU Time: '4883.517706' 2020-06-22 19:43:09 (12528): Preference change detected 2020-06-22 19:43:09 (12528): Setting CPU throttle for VM. (80%) 2020-06-22 19:43:10 (12528): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds)) 2020-06-22 19:47:30 (12528): Stopping VM. 2020-06-22 19:50:30 (8356): Detected: vboxwrapper 26197 2020-06-22 19:50:30 (8356): Detected: BOINC client v7.7 2020-06-22 19:50:30 (8356): Detected: VirtualBox VboxManage Interface (Version: 6.0.14) 2020-06-22 19:50:30 (8356): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds) 2020-06-22 19:50:31 (8356): Starting VM using VBoxManage interface. (boinc_576362c9e317eea1, slot#3) 2020-06-22 19:50:40 (8356): Successfully started VM. (PID = '13672') 2020-06-22 19:50:40 (8356): Reporting VM Process ID to BOINC. 2020-06-22 19:50:40 (8356): VM state change detected. (old = 'PoweredOff', new = 'Running') 2020-06-22 19:50:40 (8356): Detected: Web Application Enabled (http://localhost:57647) 2020-06-22 19:50:40 (8356): Status Report: Job Duration: '864000.000000' 2020-06-22 19:50:40 (8356): Status Report: Elapsed Time: '10900.243950' 2020-06-22 19:50:40 (8356): Status Report: CPU Time: '5112.667575' 2020-06-22 19:50:40 (8356): Preference change detected 2020-06-22 19:50:40 (8356): Setting CPU throttle for VM. (80%) 2020-06-22 19:50:40 (8356): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds)) 2020-06-22 20:06:35 (8356): Stopping VM. 2020-06-22 20:22:17 (2200): Detected: vboxwrapper 26197 2020-06-22 20:22:17 (2200): Detected: BOINC client v7.7 2020-06-22 20:22:17 (2200): Detected: VirtualBox VboxManage Interface (Version: 6.0.14) 2020-06-22 20:22:18 (2200): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds) 2020-06-22 20:22:18 (2200): Starting VM using VBoxManage interface. (boinc_576362c9e317eea1, slot#3) 2020-06-22 20:22:26 (2200): Successfully started VM. (PID = '15084') 2020-06-22 20:22:26 (2200): Reporting VM Process ID to BOINC. 2020-06-22 20:22:26 (2200): VM state change detected. (old = 'PoweredOff', new = 'Running') 2020-06-22 20:22:26 (2200): Detected: Web Application Enabled (http://localhost:57647) 2020-06-22 20:22:26 (2200): Status Report: Job Duration: '864000.000000' 2020-06-22 20:22:26 (2200): Status Report: Elapsed Time: '11850.243950' 2020-06-22 20:22:26 (2200): Status Report: CPU Time: '5945.572514' 2020-06-22 20:22:26 (2200): Preference change detected 2020-06-22 20:22:26 (2200): Setting CPU throttle for VM. (80%) 2020-06-22 20:22:27 (2200): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds)) 2020-06-22 20:24:55 (2200): Stopping VM. 2020-06-22 20:40:00 (11464): Detected: vboxwrapper 26197 2020-06-22 20:40:00 (11464): Detected: BOINC client v7.7 2020-06-22 20:40:00 (11464): Detected: VirtualBox VboxManage Interface (Version: 6.0.14) 2020-06-22 20:40:00 (11464): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds) 2020-06-22 20:40:01 (11464): Starting VM using VBoxManage interface. (boinc_576362c9e317eea1, slot#3) 2020-06-22 20:40:09 (11464): Successfully started VM. (PID = '15204') 2020-06-22 20:40:09 (11464): Reporting VM Process ID to BOINC. 2020-06-22 20:40:09 (11464): VM state change detected. (old = 'PoweredOff', new = 'Running') 2020-06-22 20:40:09 (11464): Detected: Web Application Enabled (http://localhost:57647) 2020-06-22 20:40:09 (11464): Status Report: Job Duration: '864000.000000' 2020-06-22 20:40:09 (11464): Status Report: Elapsed Time: '11998.243950' 2020-06-22 20:40:09 (11464): Status Report: CPU Time: '6074.990944' 2020-06-22 20:40:09 (11464): Preference change detected 2020-06-22 20:40:09 (11464): Setting CPU throttle for VM. (80%) 2020-06-22 20:40:09 (11464): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds)) 2020-06-22 20:41:41 (11464): Stopping VM. 2020-06-22 20:45:38 (11704): Detected: vboxwrapper 26197 2020-06-22 20:45:38 (11704): Detected: BOINC client v7.7 2020-06-22 20:45:38 (11704): Detected: VirtualBox VboxManage Interface (Version: 6.0.14) 2020-06-22 20:45:39 (11704): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds) 2020-06-22 20:45:39 (11704): Starting VM using VBoxManage interface. (boinc_576362c9e317eea1, slot#3) 2020-06-22 20:45:47 (11704): Successfully started VM. (PID = '14056') 2020-06-22 20:45:47 (11704): Reporting VM Process ID to BOINC. 2020-06-22 20:45:47 (11704): VM state change detected. (old = 'PoweredOff', new = 'Running') 2020-06-22 20:45:47 (11704): Detected: Web Application Enabled (http://localhost:57647) 2020-06-22 20:45:47 (11704): Status Report: Job Duration: '864000.000000' 2020-06-22 20:45:47 (11704): Status Report: Elapsed Time: '12089.243950' 2020-06-22 20:45:47 (11704): Status Report: CPU Time: '6160.011489' 2020-06-22 20:45:47 (11704): Preference change detected 2020-06-22 20:45:47 (11704): Setting CPU throttle for VM. (80%) 2020-06-22 20:45:47 (11704): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds)) 2020-06-22 20:50:05 (11704): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-22 20:50:24 (11704): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-22 20:50:58 (11704): Stopping VM. 2020-06-22 21:25:46 (11828): Detected: vboxwrapper 26197 2020-06-22 21:25:46 (11828): Detected: BOINC client v7.7 2020-06-22 21:25:47 (11828): Detected: VirtualBox VboxManage Interface (Version: 6.0.14) 2020-06-22 21:25:47 (11828): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds) 2020-06-22 21:25:47 (11828): Starting VM using VBoxManage interface. (boinc_576362c9e317eea1, slot#3) 2020-06-22 21:26:05 (11828): Successfully started VM. (PID = '2012') 2020-06-22 21:26:05 (11828): Reporting VM Process ID to BOINC. 2020-06-22 21:26:05 (11828): VM state change detected. (old = 'PoweredOff', new = 'Running') 2020-06-22 21:26:05 (11828): Detected: Web Application Enabled (http://localhost:57647) 2020-06-22 21:26:05 (11828): Status Report: Job Duration: '864000.000000' 2020-06-22 21:26:05 (11828): Status Report: Elapsed Time: '12375.305084' 2020-06-22 21:26:05 (11828): Status Report: CPU Time: '6418.286745' 2020-06-22 21:26:05 (11828): Preference change detected 2020-06-22 21:26:05 (11828): Setting CPU throttle for VM. (80%) 2020-06-22 21:26:05 (11828): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds)) 2020-06-22 21:26:10 (11828): Guest Log: 00:40:03.374129 timesync vgsvcTimeSyncWorker: Radical host time change: 2 114 916 000 000ns (HostNow=1 592 850 369 187 000 000 ns HostLast=1 592 848 254 271 000 000 ns) 2020-06-22 21:26:20 (11828): Guest Log: 00:40:13.376954 timesync vgsvcTimeSyncWorker: Radical guest time change: 2 134 034 476 000ns (GuestNow=1 592 850 379 233 788 000 ns GuestLast=1 592 848 245 199 312 000 ns fSetTimeLastLoop=true ) 2020-06-22 21:29:57 (11828): Stopping VM. 2020-06-22 21:37:40 (8616): Detected: vboxwrapper 26197 2020-06-22 21:37:40 (8616): Detected: BOINC client v7.7 2020-06-22 21:37:40 (8616): Detected: VirtualBox VboxManage Interface (Version: 6.0.14) 2020-06-22 21:37:40 (8616): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds) 2020-06-22 21:37:41 (8616): Starting VM using VBoxManage interface. (boinc_576362c9e317eea1, slot#3) 2020-06-22 21:37:53 (8616): Successfully started VM. (PID = '14544') 2020-06-22 21:37:53 (8616): Reporting VM Process ID to BOINC. 2020-06-22 21:37:53 (8616): VM state change detected. (old = 'PoweredOff', new = 'Running') 2020-06-22 21:37:53 (8616): Detected: Web Application Enabled (http://localhost:57647) 2020-06-22 21:37:53 (8616): Status Report: Job Duration: '864000.000000' 2020-06-22 21:37:53 (8616): Status Report: Elapsed Time: '12606.305084' 2020-06-22 21:37:53 (8616): Status Report: CPU Time: '6627.936489' 2020-06-22 21:37:53 (8616): Preference change detected 2020-06-22 21:37:53 (8616): Setting CPU throttle for VM. (80%) 2020-06-22 21:37:53 (8616): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds)) 2020-06-22 21:38:40 (8616): Stopping VM. 2020-06-22 21:41:43 (11180): Detected: vboxwrapper 26197 2020-06-22 21:41:43 (11180): Detected: BOINC client v7.7 2020-06-22 21:41:43 (11180): Detected: VirtualBox VboxManage Interface (Version: 6.0.14) 2020-06-22 21:41:44 (11180): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds) 2020-06-22 21:41:44 (11180): Starting VM using VBoxManage interface. (boinc_576362c9e317eea1, slot#3) 2020-06-22 21:41:52 (11180): Successfully started VM. (PID = '15116') 2020-06-22 21:41:52 (11180): Reporting VM Process ID to BOINC. 2020-06-22 21:41:52 (11180): VM state change detected. (old = 'PoweredOff', new = 'Running') 2020-06-22 21:41:52 (11180): Detected: Web Application Enabled (http://localhost:57647) 2020-06-22 21:41:52 (11180): Status Report: Job Duration: '864000.000000' 2020-06-22 21:41:52 (11180): Status Report: Elapsed Time: '12653.305084' 2020-06-22 21:41:52 (11180): Status Report: CPU Time: '6667.997546' 2020-06-22 21:41:52 (11180): Preference change detected 2020-06-22 21:41:52 (11180): Setting CPU throttle for VM. (80%) 2020-06-22 21:41:53 (11180): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds)) 2020-06-22 21:50:07 (11180): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-22 21:50:27 (11180): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-22 21:52:26 (11180): Stopping VM. 2020-06-22 21:55:48 (15112): Detected: vboxwrapper 26197 2020-06-22 21:55:48 (15112): Detected: BOINC client v7.7 2020-06-22 21:55:48 (15112): Detected: VirtualBox VboxManage Interface (Version: 6.0.14) 2020-06-22 21:55:48 (15112): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds) 2020-06-22 21:55:49 (15112): Starting VM using VBoxManage interface. (boinc_576362c9e317eea1, slot#3) 2020-06-22 21:55:58 (15112): Successfully started VM. (PID = '13356') 2020-06-22 21:55:58 (15112): Reporting VM Process ID to BOINC. 2020-06-22 21:55:58 (15112): VM state change detected. (old = 'PoweredOff', new = 'Running') 2020-06-22 21:55:58 (15112): Detected: Web Application Enabled (http://localhost:57647) 2020-06-22 21:55:58 (15112): Status Report: Job Duration: '864000.000000' 2020-06-22 21:55:58 (15112): Status Report: Elapsed Time: '13261.305084' 2020-06-22 21:55:58 (15112): Status Report: CPU Time: '7210.366223' 2020-06-22 21:55:58 (15112): Preference change detected 2020-06-22 21:55:58 (15112): Setting CPU throttle for VM. (80%) 2020-06-22 21:55:58 (15112): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds)) 2020-06-22 21:59:06 (15112): Stopping VM. 2020-06-22 22:02:14 (9680): Detected: vboxwrapper 26197 2020-06-22 22:02:14 (9680): Detected: BOINC client v7.7 2020-06-22 22:02:14 (9680): Detected: VirtualBox VboxManage Interface (Version: 6.0.14) 2020-06-22 22:02:15 (9680): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds) 2020-06-22 22:02:15 (9680): Starting VM using VBoxManage interface. (boinc_576362c9e317eea1, slot#3) 2020-06-22 22:02:23 (9680): Successfully started VM. (PID = '10680') 2020-06-22 22:02:23 (9680): Reporting VM Process ID to BOINC. 2020-06-22 22:02:23 (9680): VM state change detected. (old = 'PoweredOff', new = 'Running') 2020-06-22 22:02:23 (9680): Detected: Web Application Enabled (http://localhost:57647) 2020-06-22 22:02:23 (9680): Status Report: Job Duration: '864000.000000' 2020-06-22 22:02:23 (9680): Status Report: Elapsed Time: '13449.305084' 2020-06-22 22:02:23 (9680): Status Report: CPU Time: '7375.867684' 2020-06-22 22:02:23 (9680): Preference change detected 2020-06-22 22:02:23 (9680): Setting CPU throttle for VM. (80%) 2020-06-22 22:02:23 (9680): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds)) 2020-06-22 22:13:10 (9680): Stopping VM. 2020-06-22 22:24:43 (672): Detected: vboxwrapper 26197 2020-06-22 22:24:43 (672): Detected: BOINC client v7.7 2020-06-22 22:24:43 (672): Detected: VirtualBox VboxManage Interface (Version: 6.0.14) 2020-06-22 22:24:44 (672): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds) 2020-06-22 22:24:44 (672): Starting VM using VBoxManage interface. (boinc_576362c9e317eea1, slot#3) 2020-06-22 22:25:01 (672): Successfully started VM. (PID = '500') 2020-06-22 22:25:01 (672): Reporting VM Process ID to BOINC. 2020-06-22 22:25:01 (672): VM state change detected. (old = 'PoweredOff', new = 'Running') 2020-06-22 22:25:01 (672): Detected: Web Application Enabled (http://localhost:57647) 2020-06-22 22:25:01 (672): Status Report: Job Duration: '864000.000000' 2020-06-22 22:25:01 (672): Status Report: Elapsed Time: '14094.305084' 2020-06-22 22:25:01 (672): Status Report: CPU Time: '7952.665781' 2020-06-22 22:25:01 (672): Preference change detected 2020-06-22 22:25:01 (672): Setting CPU throttle for VM. (80%) 2020-06-22 22:25:02 (672): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds)) 2020-06-22 22:26:47 (672): Stopping VM. 2020-06-22 22:38:27 (500): Detected: vboxwrapper 26197 2020-06-22 22:38:27 (500): Detected: BOINC client v7.7 2020-06-22 22:38:28 (500): Detected: VirtualBox VboxManage Interface (Version: 6.0.14) 2020-06-22 22:38:28 (500): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds) 2020-06-22 22:38:28 (500): Starting VM using VBoxManage interface. (boinc_576362c9e317eea1, slot#3) 2020-06-22 22:38:41 (500): Successfully started VM. (PID = '13620') 2020-06-22 22:38:41 (500): Reporting VM Process ID to BOINC. 2020-06-22 22:38:41 (500): VM state change detected. (old = 'PoweredOff', new = 'Running') 2020-06-22 22:38:41 (500): Detected: Web Application Enabled (http://localhost:57647) 2020-06-22 22:38:41 (500): Status Report: Job Duration: '864000.000000' 2020-06-22 22:38:41 (500): Status Report: Elapsed Time: '14199.305084' 2020-06-22 22:38:41 (500): Status Report: CPU Time: '8046.952785' 2020-06-22 22:38:41 (500): Preference change detected 2020-06-22 22:38:41 (500): Setting CPU throttle for VM. (80%) 2020-06-22 22:38:42 (500): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds)) 2020-06-22 23:06:07 (500): Stopping VM. 2020-06-22 23:42:07 (12952): Detected: vboxwrapper 26197 2020-06-22 23:42:07 (12952): Detected: BOINC client v7.7 2020-06-22 23:42:07 (12952): Detected: VirtualBox VboxManage Interface (Version: 6.0.14) 2020-06-22 23:42:08 (12952): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds) 2020-06-22 23:42:08 (12952): Starting VM using VBoxManage interface. (boinc_576362c9e317eea1, slot#3) 2020-06-22 23:42:16 (12952): Successfully started VM. (PID = '13000') 2020-06-22 23:42:16 (12952): Reporting VM Process ID to BOINC. 2020-06-22 23:42:16 (12952): VM state change detected. (old = 'PoweredOff', new = 'Running') 2020-06-22 23:42:16 (12952): Detected: Web Application Enabled (http://localhost:57647) 2020-06-22 23:42:16 (12952): Status Report: Job Duration: '864000.000000' 2020-06-22 23:42:16 (12952): Status Report: Elapsed Time: '15838.305084' 2020-06-22 23:42:16 (12952): Status Report: CPU Time: '9522.269842' 2020-06-22 23:42:16 (12952): Preference change detected 2020-06-22 23:42:16 (12952): Setting CPU throttle for VM. (80%) 2020-06-22 23:42:17 (12952): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds)) 2020-06-22 23:42:23 (12952): Guest Log: 01:38:15.269479 timesync vgsvcTimeSyncWorker: Radical host time change: 2 177 910 000 000ns (HostNow=1 592 858 542 849 000 000 ns HostLast=1 592 856 364 939 000 000 ns) 2020-06-22 23:42:33 (12952): Guest Log: 01:38:25.275304 timesync vgsvcTimeSyncWorker: Radical guest time change: 2 177 801 649 000ns (GuestNow=1 592 858 552 854 837 000 ns GuestLast=1 592 856 375 053 188 000 ns fSetTimeLastLoop=true ) 2020-06-22 23:46:07 (12952): Stopping VM. 2020-06-23 15:50:41 (9296): Detected: vboxwrapper 26197 2020-06-23 15:50:41 (9296): Detected: BOINC client v7.7 2020-06-23 15:50:42 (9296): Detected: VirtualBox VboxManage Interface (Version: 6.0.14) 2020-06-23 15:50:43 (9296): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds) 2020-06-23 15:50:43 (9296): Starting VM using VBoxManage interface. (boinc_576362c9e317eea1, slot#3) 2020-06-23 15:53:29 (672): Detected: vboxwrapper 26197 2020-06-23 15:53:29 (672): Detected: BOINC client v7.7 2020-06-23 15:53:30 (672): Detected: VirtualBox VboxManage Interface (Version: 6.0.14) 2020-06-23 15:53:30 (672): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds) 2020-06-23 15:53:32 (672): Starting VM using VBoxManage interface. (boinc_576362c9e317eea1, slot#3) 2020-06-23 15:54:04 (672): Error in start VM for VM: -2135228409 Command: VBoxManage -q startvm "boinc_576362c9e317eea1" --type headless Output: VBoxManage.exe: error: The machine 'boinc_576362c9e317eea1' is already locked by a session (or being locked or unlocked) VBoxManage.exe: error: Details: code VBOX_E_INVALID_OBJECT_STATE (0x80bb0007), component MachineWrap, interface IMachine, callee IUnknown VBoxManage.exe: error: Context: "LaunchVMProcess(a->session, sessionType.raw(), Bstr(strEnv).raw(), progress.asOutParam())" at line 726 of file VBoxManageMisc.cpp Notes: Another VirtualBox management application has locked the session for this VM. BOINC cannot properly monitor this VM and so this job will be aborted. 2020-06-23 15:54:04 (672): VM failed to start. 2020-06-23 15:54:04 (672): Could not start 2020-06-23 15:54:04 (672): ERROR: VM failed to start 2020-06-23 15:54:09 (672): NOTE: VM session lock error encountered. BOINC will be notified that it needs to clean up the environment. This might be a temporary problem and so this job will be rescheduled for another time. 2020-06-26 00:44:41 (7152): Detected: vboxwrapper 26197 2020-06-26 00:44:43 (7152): Detected: BOINC client v7.7 2020-06-26 00:53:55 (10472): Detected: vboxwrapper 26197 2020-06-26 00:53:55 (10472): Detected: BOINC client v7.7 2020-06-26 00:53:57 (10472): Detected: VirtualBox VboxManage Interface (Version: 6.0.14) 2020-06-26 00:53:58 (10472): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds) 2020-06-26 00:54:03 (10472): Starting VM using VBoxManage interface. (boinc_576362c9e317eea1, slot#3) 2020-06-26 00:54:36 (10472): Error in start VM for VM: -2135228409 Command: VBoxManage -q startvm "boinc_576362c9e317eea1" --type headless Output: VBoxManage.exe: error: The machine 'boinc_576362c9e317eea1' is already locked by a session (or being locked or unlocked) VBoxManage.exe: error: Details: code VBOX_E_INVALID_OBJECT_STATE (0x80bb0007), component MachineWrap, interface IMachine, callee IUnknown VBoxManage.exe: error: Context: "LaunchVMProcess(a->session, sessionType.raw(), Bstr(strEnv).raw(), progress.asOutParam())" at line 726 of file VBoxManageMisc.cpp Notes: Another VirtualBox management application has locked the session for this VM. BOINC cannot properly monitor this VM and so this job will be aborted. 2020-06-26 00:54:36 (10472): VM failed to start. 2020-06-26 00:54:36 (10472): Could not start 2020-06-26 00:54:36 (10472): ERROR: VM failed to start 2020-06-26 00:54:41 (10472): NOTE: VM session lock error encountered. BOINC will be notified that it needs to clean up the environment. This might be a temporary problem and so this job will be rescheduled for another time. 2020-06-26 00:57:16 (14052): Detected: vboxwrapper 26197 2020-06-26 00:57:16 (14052): Detected: BOINC client v7.7 2020-06-26 00:57:16 (14052): Detected: VirtualBox VboxManage Interface (Version: 6.0.14) 2020-06-26 00:57:17 (14052): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds) 2020-06-26 00:57:17 (14052): Starting VM using VBoxManage interface. (boinc_576362c9e317eea1, slot#3) 2020-06-26 00:57:50 (14052): Error in start VM for VM: -2135228409 Command: VBoxManage -q startvm "boinc_576362c9e317eea1" --type headless Output: VBoxManage.exe: error: The machine 'boinc_576362c9e317eea1' is already locked by a session (or being locked or unlocked) VBoxManage.exe: error: Details: code VBOX_E_INVALID_OBJECT_STATE (0x80bb0007), component MachineWrap, interface IMachine, callee IUnknown VBoxManage.exe: error: Context: "LaunchVMProcess(a->session, sessionType.raw(), Bstr(strEnv).raw(), progress.asOutParam())" at line 726 of file VBoxManageMisc.cpp Notes: Another VirtualBox management application has locked the session for this VM. BOINC cannot properly monitor this VM and so this job will be aborted. 2020-06-26 00:57:50 (14052): VM failed to start. 2020-06-26 00:57:50 (14052): Could not start 2020-06-26 00:57:50 (14052): ERROR: VM failed to start 2020-06-26 00:57:55 (14052): NOTE: VM session lock error encountered. BOINC will be notified that it needs to clean up the environment. This might be a temporary problem and so this job will be rescheduled for another time. 2020-06-27 03:45:27 (11908): Detected: vboxwrapper 26197 2020-06-27 03:45:28 (11908): Detected: BOINC client v7.7 2020-06-27 03:45:55 (11908): Detected: VirtualBox VboxManage Interface (Version: 6.0.14) 2020-06-27 03:45:58 (11908): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds) 2020-06-27 03:46:41 (11908): Starting VM using VBoxManage interface. (boinc_576362c9e317eea1, slot#3) 2020-06-27 03:47:22 (11908): Error in start VM for VM: -2135228409 Command: VBoxManage -q startvm "boinc_576362c9e317eea1" --type headless Output: VBoxManage.exe: error: The machine 'boinc_576362c9e317eea1' is already locked by a session (or being locked or unlocked) VBoxManage.exe: error: Details: code VBOX_E_INVALID_OBJECT_STATE (0x80bb0007), component MachineWrap, interface IMachine, callee IUnknown VBoxManage.exe: error: Context: "LaunchVMProcess(a->session, sessionType.raw(), Bstr(strEnv).raw(), progress.asOutParam())" at line 726 of file VBoxManageMisc.cpp Notes: Another VirtualBox management application has locked the session for this VM. BOINC cannot properly monitor this VM and so this job will be aborted. 2020-06-27 03:47:22 (11908): VM failed to start. 2020-06-27 03:47:22 (11908): Could not start 2020-06-27 03:47:22 (11908): ERROR: VM failed to start 2020-06-27 03:47:27 (11908): NOTE: VM session lock error encountered. BOINC will be notified that it needs to clean up the environment. This might be a temporary problem and so this job will be rescheduled for another time. 2020-06-27 03:52:53 (15552): Detected: vboxwrapper 26197 2020-06-27 03:52:53 (15552): Detected: BOINC client v7.7 2020-06-27 03:52:54 (15552): Detected: VirtualBox VboxManage Interface (Version: 6.0.14) 2020-06-27 03:52:54 (15552): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds) 2020-06-27 03:52:55 (15552): ERROR: VM failed to start 2020-06-27 03:53:00 (15552): BOINC will be notified that it needs to clean up the environment. This is a temporary problem and so this job will be rescheduled for another time. 2020-06-28 18:00:40 (13164): Detected: vboxwrapper 26197 2020-06-28 18:00:40 (13164): Detected: BOINC client v7.7 2020-06-28 18:01:08 (13164): Detected: VirtualBox VboxManage Interface (Version: 6.0.14) 2020-06-28 18:01:10 (13164): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds) 2020-06-28 18:01:25 (13164): ERROR: VM failed to start 2020-06-28 18:01:30 (13164): BOINC will be notified that it needs to clean up the environment. This is a temporary problem and so this job will be rescheduled for another time. 2020-06-28 18:03:51 (15256): Detected: vboxwrapper 26197 2020-06-28 18:03:51 (15256): Detected: BOINC client v7.7 2020-06-28 18:03:55 (15256): Detected: VirtualBox VboxManage Interface (Version: 6.0.14) 2020-06-28 18:03:55 (15256): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds) 2020-06-28 18:03:56 (15256): ERROR: VM failed to start 2020-06-28 18:04:01 (15256): BOINC will be notified that it needs to clean up the environment. This is a temporary problem and so this job will be rescheduled for another time. 2020-06-28 18:10:20 (13004): Detected: vboxwrapper 26197 2020-06-28 18:10:20 (13004): Detected: BOINC client v7.7 2020-06-28 18:10:21 (13004): Detected: VirtualBox VboxManage Interface (Version: 6.0.14) 2020-06-28 18:10:22 (13004): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds) 2020-06-28 18:10:22 (13004): Starting VM using VBoxManage interface. (boinc_576362c9e317eea1, slot#3) 2020-06-28 18:10:55 (13004): Error in start VM for VM: -2135228409 Command: VBoxManage -q startvm "boinc_576362c9e317eea1" --type headless Output: VBoxManage.exe: error: The machine 'boinc_576362c9e317eea1' is already locked by a session (or being locked or unlocked) VBoxManage.exe: error: Details: code VBOX_E_INVALID_OBJECT_STATE (0x80bb0007), component MachineWrap, interface IMachine, callee IUnknown VBoxManage.exe: error: Context: "LaunchVMProcess(a->session, sessionType.raw(), Bstr(strEnv).raw(), progress.asOutParam())" at line 726 of file VBoxManageMisc.cpp Notes: Another VirtualBox management application has locked the session for this VM. BOINC cannot properly monitor this VM and so this job will be aborted. 2020-06-28 18:10:55 (13004): VM failed to start. 2020-06-28 18:10:55 (13004): Could not start 2020-06-28 18:10:55 (13004): ERROR: VM failed to start 2020-06-28 18:11:00 (13004): NOTE: VM session lock error encountered. BOINC will be notified that it needs to clean up the environment. This might be a temporary problem and so this job will be rescheduled for another time. 2020-06-30 02:14:55 (620): Detected: vboxwrapper 26197 2020-06-30 02:14:55 (620): Detected: BOINC client v7.7 2020-06-30 02:15:06 (620): Detected: VirtualBox VboxManage Interface (Version: 6.0.14) 2020-06-30 02:15:06 (620): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds) 2020-06-30 02:15:08 (620): ERROR: VM failed to start 2020-06-30 02:15:13 (620): BOINC will be notified that it needs to clean up the environment. This is a temporary problem and so this job will be rescheduled for another time. 2020-06-30 18:36:02 (14516): Detected: vboxwrapper 26197 2020-06-30 18:36:02 (14516): Detected: BOINC client v7.7 2020-06-30 18:36:18 (14516): Detected: VirtualBox VboxManage Interface (Version: 6.0.14) 2020-06-30 18:36:19 (14516): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds) 2020-06-30 18:36:24 (14516): Starting VM using VBoxManage interface. (boinc_576362c9e317eea1, slot#3) 2020-06-30 18:36:59 (14516): Error in start VM for VM: -2135228409 Command: VBoxManage -q startvm "boinc_576362c9e317eea1" --type headless Output: VBoxManage.exe: error: The machine 'boinc_576362c9e317eea1' is already locked by a session (or being locked or unlocked) VBoxManage.exe: error: Details: code VBOX_E_INVALID_OBJECT_STATE (0x80bb0007), component MachineWrap, interface IMachine, callee IUnknown VBoxManage.exe: error: Context: "LaunchVMProcess(a->session, sessionType.raw(), Bstr(strEnv).raw(), progress.asOutParam())" at line 726 of file VBoxManageMisc.cpp Notes: Another VirtualBox management application has locked the session for this VM. BOINC cannot properly monitor this VM and so this job will be aborted. 2020-06-30 18:36:59 (14516): VM failed to start. 2020-06-30 18:36:59 (14516): Could not start 2020-06-30 18:36:59 (14516): ERROR: VM failed to start 2020-06-30 18:37:04 (14516): NOTE: VM session lock error encountered. BOINC will be notified that it needs to clean up the environment. This might be a temporary problem and so this job will be rescheduled for another time. 2020-07-01 00:40:05 (10380): Detected: vboxwrapper 26197 2020-07-01 00:40:05 (10380): Detected: BOINC client v7.7 2020-07-01 00:40:10 (10380): Detected: VirtualBox VboxManage Interface (Version: 6.0.14) 2020-07-01 00:40:13 (10380): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds) 2020-07-01 00:40:15 (10380): ERROR: VM failed to start 2020-07-01 00:40:20 (10380): BOINC will be notified that it needs to clean up the environment. This is a temporary problem and so this job will be rescheduled for another time. 2020-07-01 22:17:51 (15028): Detected: vboxwrapper 26197 2020-07-01 22:17:51 (15028): Detected: BOINC client v7.7 2020-07-01 22:18:14 (15028): Detected: VirtualBox VboxManage Interface (Version: 6.0.14) 2020-07-01 22:18:15 (15028): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds) 2020-07-01 22:18:39 (15028): Starting VM using VBoxManage interface. (boinc_576362c9e317eea1, slot#3) 2020-07-01 22:19:20 (15028): Error in start VM for VM: -2135228409 Command: VBoxManage -q startvm "boinc_576362c9e317eea1" --type headless Output: VBoxManage.exe: error: The machine 'boinc_576362c9e317eea1' is already locked by a session (or being locked or unlocked) VBoxManage.exe: error: Details: code VBOX_E_INVALID_OBJECT_STATE (0x80bb0007), component MachineWrap, interface IMachine, callee IUnknown VBoxManage.exe: error: Context: "LaunchVMProcess(a->session, sessionType.raw(), Bstr(strEnv).raw(), progress.asOutParam())" at line 726 of file VBoxManageMisc.cpp Notes: Another VirtualBox management application has locked the session for this VM. BOINC cannot properly monitor this VM and so this job will be aborted. 2020-07-01 22:19:20 (15028): VM failed to start. 2020-07-01 22:19:20 (15028): Could not start 2020-07-01 22:19:20 (15028): ERROR: VM failed to start 2020-07-01 22:19:25 (15028): NOTE: VM session lock error encountered. BOINC will be notified that it needs to clean up the environment. This might be a temporary problem and so this job will be rescheduled for another time. 2020-07-02 15:58:55 (13580): Detected: vboxwrapper 26197 2020-07-02 15:58:55 (13580): Detected: BOINC client v7.7 2020-07-02 15:59:03 (13580): Detected: VirtualBox VboxManage Interface (Version: 6.0.14) 2020-07-02 15:59:04 (13580): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds) 2020-07-02 15:59:07 (13580): ERROR: VM failed to start 2020-07-02 15:59:12 (13580): BOINC will be notified that it needs to clean up the environment. This is a temporary problem and so this job will be rescheduled for another time. 2020-07-03 16:14:53 (6500): Detected: vboxwrapper 26197 2020-07-03 16:14:53 (6500): Detected: BOINC client v7.7 2020-07-03 16:15:02 (6500): Detected: VirtualBox VboxManage Interface (Version: 6.0.14) 2020-07-03 16:15:02 (6500): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds) 2020-07-03 16:15:02 (6500): Starting VM using VBoxManage interface. (boinc_576362c9e317eea1, slot#3) 2020-07-03 16:15:12 (6500): Successfully started VM. (PID = '9076') 2020-07-03 16:15:12 (6500): Reporting VM Process ID to BOINC. 2020-07-03 16:15:12 (6500): Guest Log: BIOS: VirtualBox 6.0.14 2020-07-03 16:15:12 (6500): Guest Log: CPUID EDX: 0x178bfbff 2020-07-03 16:15:12 (6500): Guest Log: BIOS: ata0-0: PCHS=16383/16/63 LCHS=1024/255/63 2020-07-03 16:15:12 (6500): VM state change detected. (old = 'PoweredOff', new = 'Running') 2020-07-03 16:15:12 (6500): Detected: Web Application Enabled (http://localhost:57647) 2020-07-03 16:15:12 (6500): VM Completion File Detected. 2020-07-03 16:15:12 (6500): VM Completion Message: Job Finished . 2020-07-03 16:15:12 (6500): Powering off VM. 2020-07-03 16:20:12 (6500): VM did not power off when requested. 2020-07-03 16:20:12 (6500): VM was successfully terminated. 2020-07-03 16:20:12 (6500): Deregistering VM. (boinc_576362c9e317eea1, slot#3) 2020-07-03 16:21:22 (6500): Removing network bandwidth throttle group from VM. 2020-07-03 16:21:22 (6500): Removing VM from VirtualBox. 16:21:28 (6500): called boinc_finish(0) </stderr_txt> ]]>
©2024 CERN