| Name | Theory_2390-1129906-14_0 |
| Workunit | 2018855 |
| Created | 5 Jun 2020, 22:00:09 UTC |
| Sent | 6 Jun 2020, 20:30:39 UTC |
| Report deadline | 16 Jun 2020, 20:30:39 UTC |
| Received | 21 Jun 2020, 1:04:30 UTC |
| Server state | Over |
| Outcome | Success |
| Client state | Done |
| Exit status | 0 (0x00000000) |
| Computer ID | 2633 |
| Run time | 2 days 9 hours 57 min 52 sec |
| CPU time | 1 days 14 hours 11 min 29 sec |
| Validate state | Task was reported too late to validate |
| Credit | 0.00 |
| Device peak FLOPS | 3.29 GFLOPS |
| Application version | Theory Simulation v5.21 (vbox64_theory) windows_x86_64 |
| Peak working set size | 66.54 MB |
| Peak swap size | 82.62 MB |
| Peak disk usage | 6.62 GB |
Stderr output
<core_client_version>7.16.5</core_client_version> <![CDATA[ <stderr_txt> throttle group to VM. (Defaulting to 1024GB) 2020-06-06 16:30:59 (9184): forwarding host port 56744 to guest port 80 2020-06-06 16:31:00 (9184): Enabling remote desktop for VM. 2020-06-06 16:31:00 (9184): Enabling shared directory for VM. 2020-06-06 16:31:01 (9184): Starting VM using VBoxManage interface. (boinc_1a09eefc584ffd05, slot#0) 2020-06-06 16:31:19 (9184): Successfully started VM. (PID = '18872') 2020-06-06 16:31:19 (9184): Reporting VM Process ID to BOINC. 2020-06-06 16:31:19 (9184): Guest Log: BIOS: VirtualBox 6.1.4 2020-06-06 16:31:19 (9184): Guest Log: CPUID EDX: 0x178bfbff 2020-06-06 16:31:19 (9184): Guest Log: BIOS: ata0-0: PCHS=16383/16/63 LCHS=1024/255/63 2020-06-06 16:31:19 (9184): VM state change detected. (old = 'PoweredOff', new = 'Running') 2020-06-06 16:31:19 (9184): Detected: Web Application Enabled (http://localhost:56744) 2020-06-06 16:31:19 (9184): Detected: Remote Desktop Enabled (localhost:56745) 2020-06-06 16:31:19 (9184): Preference change detected 2020-06-06 16:31:19 (9184): Setting CPU throttle for VM. (75%) 2020-06-06 16:31:20 (9184): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds)) 2020-06-06 16:31:21 (9184): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032 2020-06-06 16:31:21 (9184): Guest Log: BIOS: Booting from Hard Disk... 2020-06-06 16:31:24 (9184): Guest Log: BIOS: KBD: unsupported int 16h function 03 2020-06-06 16:31:24 (9184): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 2020-06-06 16:32:02 (9184): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds 2020-06-06 16:32:02 (9184): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000) 2020-06-06 16:32:12 (9184): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log 2020-06-06 16:32:12 (9184): Guest Log: 00:00:00.000343 main Log opened 2020-06-06T20:32:12.612803000Z 2020-06-06 16:32:12 (9184): Guest Log: 00:00:00.000571 main OS Product: Linux 2020-06-06 16:32:12 (9184): Guest Log: 00:00:00.000682 main OS Release: 4.14.76-13.cernvm.x86_64 2020-06-06 16:32:12 (9184): Guest Log: 00:00:00.000789 main OS Version: #1 SMP Tue Oct 16 18:26:15 CEST 2018 2020-06-06 16:32:12 (9184): Guest Log: 00:00:00.000924 main Executable: /usr/sbin/VBoxService 2020-06-06 16:32:12 (9184): Guest Log: 00:00:00.000927 main Process ID: 3219 2020-06-06 16:32:12 (9184): Guest Log: 00:00:00.000930 main Package type: LINUX_64BITS_GENERIC 2020-06-06 16:32:12 (9184): Guest Log: 00:00:00.006111 main 5.2.6 r120293 started. Verbose level = 0 2020-06-06 16:33:00 (9184): Guest Log: 22:33:01 CEST +02:00 2020-06-06: cranky: [INFO] Detected Theory App 2020-06-06 16:33:01 (9184): Guest Log: 22:33:01 CEST +02:00 2020-06-06: cranky: [INFO] Checking CVMFS. 2020-06-06 16:33:04 (9184): Guest Log: Probing /cvmfs/sft.cern.ch... OK 2020-06-06 16:33:06 (9184): 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-06 16:33:06 (9184): Guest Log: 2.5.2.0 4076 0 28404 18178 3 1 263560 4096000 0 65024 0 0 n/a 5 4 http://s1unl-cvmfs.openhtc.io/cvmfs/sft.cern.ch DIRECT 1 2020-06-06 16:33:09 (9184): Guest Log: Probing /cvmfs/grid.cern.ch... OK 2020-06-06 16:33:11 (9184): 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-06 16:33:11 (9184): Guest Log: 2.5.2.0 4124 0 27500 12417 3 2 263560 4096000 0 65024 0 2 -100 6407 1253 http://s1fnal-cvmfs.openhtc.io/cvmfs/grid.cern.ch DIRECT 1 2020-06-06 16:33:14 (9184): Guest Log: Probing /cvmfs/cernvm-prod.cern.ch... OK 2020-06-06 16:33:16 (9184): 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-06 16:33:16 (9184): Guest Log: 2.5.2.0 4099 0 25852 251 3 1 263560 4096000 0 65024 0 0 n/a 4 3 http://s1unl-cvmfs.openhtc.io/cvmfs/cernvm-prod.cern.ch DIRECT 1 2020-06-06 16:33:19 (9184): Guest Log: Probing /cvmfs/alice.cern.ch... OK 2020-06-06 16:33:21 (9184): 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-06 16:33:21 (9184): Guest Log: 2.5.2.0 4146 0 28212 6749 3 1 263560 4096000 0 65024 0 0 n/a 4110 1189 http://s1unl-cvmfs.openhtc.io/cvmfs/alice.cern.ch DIRECT 1 2020-06-06 16:33:21 (9184): Guest Log: 22:33:21 CEST +02:00 2020-06-06: cranky: [INFO] Checking runc. 2020-06-06 16:33:21 (9184): Guest Log: 22:33:21 CEST +02:00 2020-06-06: cranky: [INFO] Creating the filesystem. 2020-06-06 16:33:21 (9184): Guest Log: 22:33:21 CEST +02:00 2020-06-06: cranky: [INFO] Using /cvmfs/cernvm-prod.cern.ch/cvm3 2020-06-06 16:33:21 (9184): Guest Log: 22:33:22 CEST +02:00 2020-06-06: cranky: [INFO] Updating config.json. 2020-06-06 16:33:22 (9184): Guest Log: 22:33:22 CEST +02:00 2020-06-06: cranky: [INFO] Running Container 'runc'. 2020-06-06 16:33:22 (9184): Guest Log: job: htmld=/shared/html/job 2020-06-06 16:33:22 (9184): Guest Log: job: unpack exitcode=0 2020-06-06 16:33:24 (9184): Guest Log: 22:33:24 CEST +02:00 2020-06-06: cranky: [INFO] ===> [runRivet] Sat Jun 6 20:33:23 UTC 2020 [boinc pp jets 7000 80,-,960 - herwig7 7.2.0 default 100000 14] 2020-06-06 17:39:40 (9184): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-06 17:39:47 (9184): Error in resume VM for VM: -2135228414 Command: VBoxManage -q controlvm "boinc_1a09eefc584ffd05" resume Output: VBoxManage.exe: error: VM is paused due to host power management VBoxManage.exe: error: Details: code VBOX_E_INVALID_VM_STATE (0x80bb0002), component ConsoleWrap, interface IConsole, callee IUnknown VBoxManage.exe: error: Context: "Resume()" at line 410 of file VBoxManageControlVM.cpp 2020-06-06 23:26:03 (9184): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-06 23:26:06 (9184): Guest Log: 01:07:32.528708 timesync vgsvcTimeSyncWorker: Radical host time change: 20 790 906 000 000ns (HostNow=1 591 500 366 041 000 000 ns HostLast=1 591 479 575 135 000 000 ns) 2020-06-06 23:26:16 (9184): Guest Log: 01:07:42.561609 timesync vgsvcTimeSyncWorker: Radical guest time change: 20 790 940 924 000ns (GuestNow=1 591 500 376 078 036 000 ns GuestLast=1 591 479 585 137 112 000 ns fSetTimeLastLoop=true ) 2020-06-06 23:40:40 (9184): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-06 23:46:33 (9184): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-07 00:03:51 (9184): Status Report: Job Duration: '864000.000000' 2020-06-07 00:03:51 (9184): Status Report: Elapsed Time: '6000.000000' 2020-06-07 00:03:51 (9184): Status Report: CPU Time: '4541.453125' 2020-06-07 00:06:10 (9184): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-07 00:06:49 (9184): Error in resume VM for VM: -2135228414 Command: VBoxManage -q controlvm "boinc_1a09eefc584ffd05" resume Output: VBoxManage.exe: error: Cannot resume the machine as it is not paused (machine state: Running) VBoxManage.exe: error: Details: code VBOX_E_INVALID_VM_STATE (0x80bb0002), component ConsoleWrap, interface IConsole, callee IUnknown VBoxManage.exe: error: Context: "Resume()" at line 410 of file VBoxManageControlVM.cpp 2020-06-07 00:06:49 (9184): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-07 00:06:56 (9184): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-07 20:30:08 (9184): Error in resume VM for VM: -2135228414 Command: VBoxManage -q controlvm "boinc_1a09eefc584ffd05" resume Output: VBoxManage.exe: error: Cannot resume the machine as it is not paused (machine state: Running) VBoxManage.exe: error: Details: code VBOX_E_INVALID_VM_STATE (0x80bb0002), component ConsoleWrap, interface IConsole, callee IUnknown VBoxManage.exe: error: Context: "Resume()" at line 410 of file VBoxManageControlVM.cpp 2020-06-07 20:30:09 (9184): Guest Log: 01:42:04.383371 timesync vgsvcTimeSyncWorker: Radical host time change: 73 392 849 000 000ns (HostNow=1 591 576 205 395 000 000 ns HostLast=1 591 502 812 546 000 000 ns) 2020-06-07 20:30:09 (9184): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-07 20:30:15 (9184): Guest Log: 01:42:14.389951 timesync vgsvcTimeSyncWorker: Radical guest time change: 73 777 501 804 000ns (GuestNow=1 591 576 215 401 590 000 ns GuestLast=1 591 502 437 899 786 000 ns fSetTimeLastLoop=true ) 2020-06-07 20:30:38 (9184): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-07 20:38:26 (9184): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-07 21:25:33 (9184): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-13 13:59:05 (9184): Error in resume VM for VM: -2135228414 Command: VBoxManage -q controlvm "boinc_1a09eefc584ffd05" resume Output: VBoxManage.exe: error: Cannot resume the machine as it is not paused (machine state: Running) VBoxManage.exe: error: Details: code VBOX_E_INVALID_VM_STATE (0x80bb0002), component ConsoleWrap, interface IConsole, callee IUnknown VBoxManage.exe: error: Context: "Resume()" at line 410 of file VBoxManageControlVM.cpp 2020-06-13 13:59:06 (9184): Guest Log: 02:30:05.637401 timesync vgsvcTimeSyncWorker: Radical host time change: 491 611 911 000 000ns (HostNow=1 592 071 136 586 000 000 ns HostLast=1 591 579 524 675 000 000 ns) 2020-06-13 13:59:06 (9184): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-13 13:59:07 (9184): Guest Log: 02:30:15.667372 timesync vgsvcTimeSyncWorker: Radical guest time change: 492 059 966 956 000ns (GuestNow=1 592 071 146 615 978 000 ns GuestLast=1 591 579 086 649 022 000 ns fSetTimeLastLoop=true ) 2020-06-13 13:59:09 (9184): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-13 14:00:35 (9184): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-13 14:35:14 (9184): Stopping VM. 2020-06-13 14:35:15 (9184): Error in stop VM for VM: -108 Command: VBoxManage -q controlvm "boinc_1a09eefc584ffd05" savestate Output: 2020-06-13 14:35:15 (9184): VM did not stop when requested. 2020-06-13 14:35:15 (9184): VM was successfully terminated. 2020-06-13 15:02:27 (1764): Detected: vboxwrapper 26197 2020-06-13 15:02:27 (1764): Detected: BOINC client v7.7 2020-06-13 15:02:29 (1764): Detected: VirtualBox VboxManage Interface (Version: 6.1.4) 2020-06-13 15:02:29 (1764): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds) 2020-06-13 15:02:29 (1764): Starting VM using VBoxManage interface. (boinc_1a09eefc584ffd05, slot#0) 2020-06-13 15:02:40 (1764): Successfully started VM. (PID = '18912') 2020-06-13 15:02:40 (1764): Reporting VM Process ID to BOINC. 2020-06-13 15:02:40 (1764): Guest Log: BIOS: VirtualBox 6.1.4 2020-06-13 15:02:40 (1764): Guest Log: CPUID EDX: 0x178bfbff 2020-06-13 15:02:40 (1764): Guest Log: BIOS: ata0-0: PCHS=16383/16/63 LCHS=1024/255/63 2020-06-13 15:02:40 (1764): VM state change detected. (old = 'PoweredOff', new = 'Running') 2020-06-13 15:02:40 (1764): Detected: Web Application Enabled (http://localhost:56744) 2020-06-13 15:02:40 (1764): Detected: Remote Desktop Enabled (localhost:56745) 2020-06-13 15:02:40 (1764): Status Report: Job Duration: '864000.000000' 2020-06-13 15:02:40 (1764): Status Report: Elapsed Time: '11037.103466' 2020-06-13 15:02:40 (1764): Status Report: CPU Time: '8392.265625' 2020-06-13 15:02:40 (1764): Preference change detected 2020-06-13 15:02:40 (1764): Setting CPU throttle for VM. (75%) 2020-06-13 15:02:40 (1764): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds)) 2020-06-13 15:02:42 (1764): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032 2020-06-13 15:02:42 (1764): Guest Log: BIOS: Booting from Hard Disk... 2020-06-13 15:02:45 (1764): Guest Log: BIOS: KBD: unsupported int 16h function 03 2020-06-13 15:02:45 (1764): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 2020-06-13 15:03:11 (1764): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds 2020-06-13 15:03:11 (1764): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000) 2020-06-13 15:03:17 (1764): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log 2020-06-13 15:03:17 (1764): Guest Log: 00:00:00.000272 main Log opened 2020-06-13T19:03:16.151232000Z 2020-06-13 15:03:17 (1764): Guest Log: 00:00:00.000443 main OS Product: Linux 2020-06-13 15:03:17 (1764): Guest Log: 00:00:00.000530 main OS Release: 4.14.76-13.cernvm.x86_64 2020-06-13 15:03:17 (1764): Guest Log: 00:00:00.000599 main OS Version: #1 SMP Tue Oct 16 18:26:15 CEST 2018 2020-06-13 15:03:17 (1764): Guest Log: 00:00:00.000665 main Executable: /usr/sbin/VBoxService 2020-06-13 15:03:17 (1764): Guest Log: 00:00:00.000667 main Process ID: 3214 2020-06-13 15:03:17 (1764): Guest Log: 00:00:00.000669 main Package type: LINUX_64BITS_GENERIC 2020-06-13 15:03:17 (1764): Guest Log: 00:00:00.005092 main 5.2.6 r120293 started. Verbose level = 0 2020-06-13 15:03:51 (1764): Guest Log: 21:03:50 CEST +02:00 2020-06-13: cranky: [INFO] Detected Theory App 2020-06-13 15:03:51 (1764): Guest Log: 21:03:50 CEST +02:00 2020-06-13: cranky: [INFO] Checking CVMFS. 2020-06-13 15:03:53 (1764): Guest Log: Probing /cvmfs/sft.cern.ch... OK 2020-06-13 15:03:54 (1764): 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-13 15:03:54 (1764): Guest Log: 2.5.2.0 4126 0 28032 18226 3 1 1674286 4096000 0 65024 0 0 n/a 0 0 http://s1bnl-cvmfs.openhtc.io/cvmfs/sft.cern.ch DIRECT 1 2020-06-13 15:03:55 (1764): Guest Log: Probing /cvmfs/grid.cern.ch... OK 2020-06-13 15:03:57 (1764): 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-13 15:03:57 (1764): Guest Log: 2.5.2.0 4082 0 27716 12473 3 2 1674286 4096000 0 65024 0 2 0 4271 1260 http://s1fnal-cvmfs.openhtc.io/cvmfs/grid.cern.ch DIRECT 1 2020-06-13 15:03:58 (1764): Guest Log: Probing /cvmfs/cernvm-prod.cern.ch... OK 2020-06-13 15:03:59 (1764): 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-13 15:03:59 (1764): Guest Log: 2.5.2.0 4103 0 27196 252 3 1 1674286 4096000 0 65024 0 0 n/a 0 0 http://s1asgc-cvmfs.openhtc.io:8080/cvmfs/cernvm-prod.cern.ch DIRECT 1 2020-06-13 15:04:01 (1764): Guest Log: Probing /cvmfs/alice.cern.ch... OK 2020-06-13 15:04:02 (1764): 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-13 15:04:02 (1764): Guest Log: 2.5.2.0 4059 0 30640 6791 3 1 1674286 4096000 0 65024 0 0 n/a 4127 1045 http://s1bnl-cvmfs.openhtc.io/cvmfs/alice.cern.ch DIRECT 1 2020-06-13 15:04:02 (1764): Guest Log: 21:04:01 CEST +02:00 2020-06-13: cranky: [INFO] Checking runc. 2020-06-13 15:04:02 (1764): Guest Log: 21:04:01 CEST +02:00 2020-06-13: cranky: [INFO] Creating the filesystem. 2020-06-13 15:04:02 (1764): Guest Log: 21:04:01 CEST +02:00 2020-06-13: cranky: [INFO] Using /cvmfs/cernvm-prod.cern.ch/cvm3 2020-06-13 15:04:03 (1764): Guest Log: 21:04:02 CEST +02:00 2020-06-13: cranky: [INFO] Updating config.json. 2020-06-13 15:04:03 (1764): Guest Log: 21:04:02 CEST +02:00 2020-06-13: cranky: [INFO] Running Container 'runc'. 2020-06-13 15:04:03 (1764): Guest Log: job: htmld=/shared/html/job 2020-06-13 15:04:03 (1764): Guest Log: job: unpack exitcode=0 2020-06-13 15:04:05 (1764): Guest Log: 21:04:04 CEST +02:00 2020-06-13: cranky: [INFO] ===> [runRivet] Sat Jun 13 19:04:02 UTC 2020 [boinc pp jets 7000 80,-,960 - herwig7 7.2.0 default 100000 14] 2020-06-13 16:42:47 (1764): Status Report: Job Duration: '864000.000000' 2020-06-13 16:42:47 (1764): Status Report: Elapsed Time: '17037.103466' 2020-06-13 16:42:47 (1764): Status Report: CPU Time: '12890.093750' 2020-06-13 18:22:52 (1764): Status Report: Job Duration: '864000.000000' 2020-06-13 18:22:52 (1764): Status Report: Elapsed Time: '23037.103466' 2020-06-13 18:22:52 (1764): Status Report: CPU Time: '17400.796875' 2020-06-13 18:40:58 (1764): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-14 18:30:26 (1764): Error in resume VM for VM: -2135228414 Command: VBoxManage -q controlvm "boinc_1a09eefc584ffd05" resume Output: VBoxManage.exe: error: VM is paused due to host power management VBoxManage.exe: error: Details: code VBOX_E_INVALID_VM_STATE (0x80bb0002), component ConsoleWrap, interface IConsole, callee IUnknown VBoxManage.exe: error: Context: "Resume()" at line 410 of file VBoxManageControlVM.cpp 2020-06-14 18:30:33 (1764): Error in resume VM for VM: -2135228414 Command: VBoxManage -q controlvm "boinc_1a09eefc584ffd05" resume Output: VBoxManage.exe: error: VM is paused due to host power management VBoxManage.exe: error: Details: code VBOX_E_INVALID_VM_STATE (0x80bb0002), component ConsoleWrap, interface IConsole, callee IUnknown VBoxManage.exe: error: Context: "Resume()" at line 410 of file VBoxManageControlVM.cpp 2020-06-14 18:30:40 (1764): Error in resume VM for VM: -2135228414 Command: VBoxManage -q controlvm "boinc_1a09eefc584ffd05" resume Output: VBoxManage.exe: error: VM is paused due to host power management VBoxManage.exe: error: Details: code VBOX_E_INVALID_VM_STATE (0x80bb0002), component ConsoleWrap, interface IConsole, callee IUnknown VBoxManage.exe: error: Context: "Resume()" at line 410 of file VBoxManageControlVM.cpp 2020-06-14 18:30:46 (1764): Error in resume VM for VM: -2135228414 Command: VBoxManage -q controlvm "boinc_1a09eefc584ffd05" resume Output: VBoxManage.exe: error: VM is paused due to host power management VBoxManage.exe: error: Details: code VBOX_E_INVALID_VM_STATE (0x80bb0002), component ConsoleWrap, interface IConsole, callee IUnknown VBoxManage.exe: error: Context: "Resume()" at line 410 of file VBoxManageControlVM.cpp 2020-06-14 18:30:53 (1764): Error in resume VM for VM: -2135228414 Command: VBoxManage -q controlvm "boinc_1a09eefc584ffd05" resume Output: VBoxManage.exe: error: VM is paused due to host power management VBoxManage.exe: error: Details: code VBOX_E_INVALID_VM_STATE (0x80bb0002), component ConsoleWrap, interface IConsole, callee IUnknown VBoxManage.exe: error: Context: "Resume()" at line 410 of file VBoxManageControlVM.cpp 2020-06-14 18:31:00 (1764): Error in resume VM for VM: -2135228414 Command: VBoxManage -q controlvm "boinc_1a09eefc584ffd05" resume Output: VBoxManage.exe: error: VM is paused due to host power management VBoxManage.exe: error: Details: code VBOX_E_INVALID_VM_STATE (0x80bb0002), component ConsoleWrap, interface IConsole, callee IUnknown VBoxManage.exe: error: Context: "Resume()" at line 410 of file VBoxManageControlVM.cpp 2020-06-14 18:31:06 (1764): Error in resume VM for VM: -2135228414 Command: VBoxManage -q controlvm "boinc_1a09eefc584ffd05" resume Output: VBoxManage.exe: error: VM is paused due to host power management VBoxManage.exe: error: Details: code VBOX_E_INVALID_VM_STATE (0x80bb0002), component ConsoleWrap, interface IConsole, callee IUnknown VBoxManage.exe: error: Context: "Resume()" at line 410 of file VBoxManageControlVM.cpp 2020-06-14 18:31:13 (1764): Error in resume VM for VM: -2135228414 Command: VBoxManage -q controlvm "boinc_1a09eefc584ffd05" resume Output: VBoxManage.exe: error: VM is paused due to host power management VBoxManage.exe: error: Details: code VBOX_E_INVALID_VM_STATE (0x80bb0002), component ConsoleWrap, interface IConsole, callee IUnknown VBoxManage.exe: error: Context: "Resume()" at line 410 of file VBoxManageControlVM.cpp 2020-06-14 18:31:19 (1764): Error in resume VM for VM: -2135228414 Command: VBoxManage -q controlvm "boinc_1a09eefc584ffd05" resume Output: VBoxManage.exe: error: VM is paused due to host power management VBoxManage.exe: error: Details: code VBOX_E_INVALID_VM_STATE (0x80bb0002), component ConsoleWrap, interface IConsole, callee IUnknown VBoxManage.exe: error: Context: "Resume()" at line 410 of file VBoxManageControlVM.cpp 2020-06-14 18:31:26 (1764): Error in resume VM for VM: -2135228414 Command: VBoxManage -q controlvm "boinc_1a09eefc584ffd05" resume Output: VBoxManage.exe: error: VM is paused due to host power management VBoxManage.exe: error: Details: code VBOX_E_INVALID_VM_STATE (0x80bb0002), component ConsoleWrap, interface IConsole, callee IUnknown VBoxManage.exe: error: Context: "Resume()" at line 410 of file VBoxManageControlVM.cpp 2020-06-14 18:31:33 (1764): Error in resume VM for VM: -2135228414 Command: VBoxManage -q controlvm "boinc_1a09eefc584ffd05" resume Output: VBoxManage.exe: error: VM is paused due to host power management VBoxManage.exe: error: Details: code VBOX_E_INVALID_VM_STATE (0x80bb0002), component ConsoleWrap, interface IConsole, callee IUnknown VBoxManage.exe: error: Context: "Resume()" at line 410 of file VBoxManageControlVM.cpp 2020-06-14 18:31:39 (1764): Error in resume VM for VM: -2135228414 Command: VBoxManage -q controlvm "boinc_1a09eefc584ffd05" resume Output: VBoxManage.exe: error: VM is paused due to host power management VBoxManage.exe: error: Details: code VBOX_E_INVALID_VM_STATE (0x80bb0002), component ConsoleWrap, interface IConsole, callee IUnknown VBoxManage.exe: error: Context: "Resume()" at line 410 of file VBoxManageControlVM.cpp 2020-06-14 18:31:46 (1764): Error in resume VM for VM: -2135228414 Command: VBoxManage -q controlvm "boinc_1a09eefc584ffd05" resume Output: VBoxManage.exe: error: VM is paused due to host power management VBoxManage.exe: error: Details: code VBOX_E_INVALID_VM_STATE (0x80bb0002), component ConsoleWrap, interface IConsole, callee IUnknown VBoxManage.exe: error: Context: "Resume()" at line 410 of file VBoxManageControlVM.cpp 2020-06-14 18:31:53 (1764): Error in resume VM for VM: -2135228414 Command: VBoxManage -q controlvm "boinc_1a09eefc584ffd05" resume Output: VBoxManage.exe: error: VM is paused due to host power management VBoxManage.exe: error: Details: code VBOX_E_INVALID_VM_STATE (0x80bb0002), component ConsoleWrap, interface IConsole, callee IUnknown VBoxManage.exe: error: Context: "Resume()" at line 410 of file VBoxManageControlVM.cpp 2020-06-14 18:31:59 (1764): Error in resume VM for VM: -2135228414 Command: VBoxManage -q controlvm "boinc_1a09eefc584ffd05" resume Output: VBoxManage.exe: error: VM is paused due to host power management VBoxManage.exe: error: Details: code VBOX_E_INVALID_VM_STATE (0x80bb0002), component ConsoleWrap, interface IConsole, callee IUnknown VBoxManage.exe: error: Context: "Resume()" at line 410 of file VBoxManageControlVM.cpp 2020-06-14 18:32:06 (1764): Error in resume VM for VM: -2135228414 Command: VBoxManage -q controlvm "boinc_1a09eefc584ffd05" resume Output: VBoxManage.exe: error: VM is paused due to host power management VBoxManage.exe: error: Details: code VBOX_E_INVALID_VM_STATE (0x80bb0002), component ConsoleWrap, interface IConsole, callee IUnknown VBoxManage.exe: error: Context: "Resume()" at line 410 of file VBoxManageControlVM.cpp 2020-06-14 18:32:12 (1764): Error in resume VM for VM: -2135228414 Command: VBoxManage -q controlvm "boinc_1a09eefc584ffd05" resume Output: VBoxManage.exe: error: VM is paused due to host power management VBoxManage.exe: error: Details: code VBOX_E_INVALID_VM_STATE (0x80bb0002), component ConsoleWrap, interface IConsole, callee IUnknown VBoxManage.exe: error: Context: "Resume()" at line 410 of file VBoxManageControlVM.cpp 2020-06-14 18:33:22 (15540): Detected: vboxwrapper 26197 2020-06-14 18:33:22 (15540): Detected: BOINC client v7.7 2020-06-14 18:33:24 (15540): Detected: VirtualBox VboxManage Interface (Version: 6.1.4) 2020-06-14 18:33:24 (15540): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds) 2020-06-14 18:33:25 (15540): Starting VM using VBoxManage interface. (boinc_1a09eefc584ffd05, slot#0) 2020-06-14 18:33:40 (15540): Successfully started VM. (PID = '17084') 2020-06-14 18:33:40 (15540): Reporting VM Process ID to BOINC. 2020-06-14 18:33:40 (15540): Guest Log: BIOS: VirtualBox 6.1.4 2020-06-14 18:33:40 (15540): Guest Log: CPUID EDX: 0x178bfbff 2020-06-14 18:33:40 (15540): Guest Log: BIOS: ata0-0: PCHS=16383/16/63 LCHS=1024/255/63 2020-06-14 18:33:40 (15540): VM state change detected. (old = 'PoweredOff', new = 'Running') 2020-06-14 18:33:40 (15540): Preference change detected 2020-06-14 18:33:40 (15540): Setting CPU throttle for VM. (75%) 2020-06-14 18:33:40 (15540): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds)) 2020-06-14 18:33:42 (15540): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032 2020-06-14 18:33:42 (15540): Guest Log: BIOS: Booting from Hard Disk... 2020-06-14 18:33:45 (15540): Guest Log: BIOS: KBD: unsupported int 16h function 03 2020-06-14 18:33:45 (15540): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 2020-06-14 18:34:16 (15540): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds 2020-06-14 18:34:16 (15540): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000) 2020-06-14 18:34:23 (15540): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log 2020-06-14 18:34:23 (15540): Guest Log: 00:00:00.000211 main Log opened 2020-06-14T22:34:23.125123000Z 2020-06-14 18:34:23 (15540): Guest Log: 00:00:00.001673 main OS Product: Linux 2020-06-14 18:34:23 (15540): Guest Log: 00:00:00.001847 main OS Release: 4.14.76-13.cernvm.x86_64 2020-06-14 18:34:23 (15540): Guest Log: 00:00:00.001915 main OS Version: #1 SMP Tue Oct 16 18:26:15 CEST 2018 2020-06-14 18:34:23 (15540): Guest Log: 00:00:00.001969 main Executable: /usr/sbin/VBoxService 2020-06-14 18:34:23 (15540): Guest Log: 00:00:00.001971 main Process ID: 3210 2020-06-14 18:34:23 (15540): Guest Log: 00:00:00.001973 main Package type: LINUX_64BITS_GENERIC 2020-06-14 18:34:23 (15540): Guest Log: 00:00:00.006296 main 5.2.6 r120293 started. Verbose level = 0 2020-06-14 18:34:55 (15540): Guest Log: 00:34:55 CEST +02:00 2020-06-15: cranky: [INFO] Detected Theory App 2020-06-14 18:34:55 (15540): Guest Log: 00:34:55 CEST +02:00 2020-06-15: cranky: [INFO] Checking CVMFS. 2020-06-14 18:34:57 (15540): Guest Log: Probing /cvmfs/sft.cern.ch... OK 2020-06-14 18:34:58 (15540): 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-14 18:34:58 (15540): Guest Log: 2.5.2.0 4100 0 28424 18228 3 1 1693158 4096000 0 65024 0 0 n/a 5 4 http://s1fnal-cvmfs.openhtc.io/cvmfs/sft.cern.ch DIRECT 1 2020-06-14 18:35:00 (15540): Guest Log: Probing /cvmfs/grid.cern.ch... OK 2020-06-14 18:35:01 (15540): 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-14 18:35:01 (15540): Guest Log: 2.5.2.0 4078 0 27556 12481 3 2 1693158 4096000 0 65024 0 2 0 4272 856 http://s1asgc-cvmfs.openhtc.io:8080/cvmfs/grid.cern.ch DIRECT 1 2020-06-14 18:35:03 (15540): Guest Log: Probing /cvmfs/cernvm-prod.cern.ch... OK 2020-06-14 18:35:05 (15540): 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-14 18:35:05 (15540): Guest Log: 2.5.2.0 4121 0 25136 252 3 1 1693158 4096000 0 65024 0 0 n/a 0 0 http://s1ral-cvmfs.openhtc.io/cvmfs/cernvm-prod.cern.ch DIRECT 1 2020-06-14 18:35:08 (15540): Guest Log: Probing /cvmfs/alice.cern.ch... OK 2020-06-14 18:35:09 (15540): 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-14 18:35:09 (15540): Guest Log: 2.5.2.0 4055 0 29748 6797 3 1 1693158 4096000 0 65024 0 0 n/a 4128 1087 http://s1unl-cvmfs.openhtc.io/cvmfs/alice.cern.ch DIRECT 1 2020-06-14 18:35:09 (15540): Guest Log: 00:35:08 CEST +02:00 2020-06-15: cranky: [INFO] Checking runc. 2020-06-14 18:35:09 (15540): Guest Log: 00:35:08 CEST +02:00 2020-06-15: cranky: [INFO] Creating the filesystem. 2020-06-14 18:35:09 (15540): Guest Log: 00:35:09 CEST +02:00 2020-06-15: cranky: [INFO] Using /cvmfs/cernvm-prod.cern.ch/cvm3 2020-06-14 18:35:10 (15540): Guest Log: 00:35:09 CEST +02:00 2020-06-15: cranky: [INFO] Updating config.json. 2020-06-14 18:35:10 (15540): Guest Log: 00:35:09 CEST +02:00 2020-06-15: cranky: [INFO] Running Container 'runc'. 2020-06-14 18:35:11 (15540): Guest Log: job: htmld=/shared/html/job 2020-06-14 18:35:11 (15540): Guest Log: job: unpack exitcode=0 2020-06-14 18:35:12 (15540): Guest Log: 00:35:11 CEST +02:00 2020-06-15: cranky: [INFO] ===> [runRivet] Sun Jun 14 22:35:10 UTC 2020 [boinc pp jets 7000 80,-,960 - herwig7 7.2.0 default 100000 14] 2020-06-14 19:05:27 (15540): Stopping VM. 2020-06-14 19:05:27 (15540): Error in stop VM for VM: -108 Command: VBoxManage -q controlvm "boinc_1a09eefc584ffd05" savestate Output: 2020-06-14 19:05:27 (15540): VM did not stop when requested. 2020-06-14 19:05:27 (15540): VM was successfully terminated. 2020-06-14 19:06:53 (15464): Detected: vboxwrapper 26197 2020-06-14 19:06:53 (15464): Detected: BOINC client v7.7 2020-06-14 19:06:55 (15464): Detected: VirtualBox VboxManage Interface (Version: 6.1.4) 2020-06-14 19:06:55 (15464): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds) 2020-06-14 19:06:55 (15464): Starting VM using VBoxManage interface. (boinc_1a09eefc584ffd05, slot#0) 2020-06-14 19:07:09 (15464): Successfully started VM. (PID = '16668') 2020-06-14 19:07:09 (15464): Reporting VM Process ID to BOINC. 2020-06-14 19:07:09 (15464): Guest Log: BIOS: VirtualBox 6.1.4 2020-06-14 19:07:09 (15464): Guest Log: CPUID EDX: 0x178bfbff 2020-06-14 19:07:09 (15464): Guest Log: BIOS: ata0-0: PCHS=16383/16/63 LCHS=1024/255/63 2020-06-14 19:07:09 (15464): VM state change detected. (old = 'PoweredOff', new = 'Running') 2020-06-14 19:07:09 (15464): Preference change detected 2020-06-14 19:07:09 (15464): Setting CPU throttle for VM. (75%) 2020-06-14 19:07:10 (15464): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds)) 2020-06-14 19:07:11 (15464): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032 2020-06-14 19:07:11 (15464): Guest Log: BIOS: Booting from Hard Disk... 2020-06-14 19:07:13 (15464): Guest Log: BIOS: KBD: unsupported int 16h function 03 2020-06-14 19:07:13 (15464): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 2020-06-14 19:07:46 (15464): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds 2020-06-14 19:07:46 (15464): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000) 2020-06-14 19:07:54 (15464): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log 2020-06-14 19:07:54 (15464): Guest Log: 00:00:00.000282 main Log opened 2020-06-14T23:07:54.739137000Z 2020-06-14 19:07:54 (15464): Guest Log: 00:00:00.000648 main OS Product: Linux 2020-06-14 19:07:54 (15464): Guest Log: 00:00:00.000787 main OS Release: 4.14.76-13.cernvm.x86_64 2020-06-14 19:07:54 (15464): Guest Log: 00:00:00.000874 main OS Version: #1 SMP Tue Oct 16 18:26:15 CEST 2018 2020-06-14 19:07:54 (15464): Guest Log: 00:00:00.000959 main Executable: /usr/sbin/VBoxService 2020-06-14 19:07:54 (15464): Guest Log: 00:00:00.000961 main Process ID: 3208 2020-06-14 19:07:54 (15464): Guest Log: 00:00:00.000963 main Package type: LINUX_64BITS_GENERIC 2020-06-14 19:07:54 (15464): Guest Log: 00:00:00.007412 main 5.2.6 r120293 started. Verbose level = 0 2020-06-14 19:08:36 (15464): Guest Log: 01:08:36 CEST +02:00 2020-06-15: cranky: [INFO] Detected Theory App 2020-06-14 19:08:36 (15464): Guest Log: 01:08:36 CEST +02:00 2020-06-15: cranky: [INFO] Checking CVMFS. 2020-06-14 19:08:38 (15464): Guest Log: Probing /cvmfs/sft.cern.ch... OK 2020-06-14 19:08:39 (15464): 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-14 19:08:39 (15464): Guest Log: 2.5.2.0 4129 0 27164 18228 3 1 1693158 4096000 0 65024 0 0 n/a 0 0 http://s1ral-cvmfs.openhtc.io/cvmfs/sft.cern.ch DIRECT 1 2020-06-14 19:08:41 (15464): Guest Log: Probing /cvmfs/grid.cern.ch... OK 2020-06-14 19:08:42 (15464): 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-14 19:08:42 (15464): Guest Log: 2.5.2.0 4081 0 27504 12481 3 2 1693158 4096000 0 65024 0 2 100 0 0 http://s1unl-cvmfs.openhtc.io/cvmfs/grid.cern.ch DIRECT 1 2020-06-14 19:08:44 (15464): Guest Log: Probing /cvmfs/cernvm-prod.cern.ch... OK 2020-06-14 19:08:46 (15464): 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-14 19:08:46 (15464): Guest Log: 2.5.2.0 4055 0 27228 252 3 1 1693158 4096000 0 65024 0 0 n/a 0 0 http://s1ral-cvmfs.openhtc.io/cvmfs/cernvm-prod.cern.ch DIRECT 1 2020-06-14 19:08:48 (15464): Guest Log: Probing /cvmfs/alice.cern.ch... OK 2020-06-14 19:08:49 (15464): 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-14 19:08:49 (15464): Guest Log: 2.5.2.0 4102 0 29712 6797 3 1 1693158 4096000 0 65024 0 0 n/a 0 0 http://s1fnal-cvmfs.openhtc.io/cvmfs/alice.cern.ch DIRECT 1 2020-06-14 19:08:49 (15464): Guest Log: 01:08:49 CEST +02:00 2020-06-15: cranky: [INFO] Checking runc. 2020-06-14 19:08:49 (15464): Guest Log: 01:08:49 CEST +02:00 2020-06-15: cranky: [INFO] Creating the filesystem. 2020-06-14 19:08:49 (15464): Guest Log: 01:08:49 CEST +02:00 2020-06-15: cranky: [INFO] Using /cvmfs/cernvm-prod.cern.ch/cvm3 2020-06-14 19:08:50 (15464): Guest Log: 01:08:50 CEST +02:00 2020-06-15: cranky: [INFO] Updating config.json. 2020-06-14 19:08:50 (15464): Guest Log: 01:08:50 CEST +02:00 2020-06-15: cranky: [INFO] Running Container 'runc'. 2020-06-14 19:08:51 (15464): Guest Log: job: htmld=/shared/html/job 2020-06-14 19:08:51 (15464): Guest Log: job: unpack exitcode=0 2020-06-14 19:08:52 (15464): Guest Log: 01:08:52 CEST +02:00 2020-06-15: cranky: [INFO] ===> [runRivet] Sun Jun 14 23:08:51 UTC 2020 [boinc pp jets 7000 80,-,960 - herwig7 7.2.0 default 100000 14] 2020-06-14 20:15:36 (15464): Status Report: Job Duration: '864000.000000' 2020-06-14 20:15:36 (15464): Status Report: Elapsed Time: '6000.464003' 2020-06-14 20:15:36 (15464): Status Report: CPU Time: '4487.875000' 2020-06-14 21:56:12 (15464): Status Report: Job Duration: '864000.000000' 2020-06-14 21:56:12 (15464): Status Report: Elapsed Time: '12001.402035' 2020-06-14 21:56:12 (15464): Status Report: CPU Time: '9021.203125' 2020-06-14 23:36:30 (15464): Status Report: Job Duration: '864000.000000' 2020-06-14 23:36:30 (15464): Status Report: Elapsed Time: '18001.402035' 2020-06-14 23:36:30 (15464): Status Report: CPU Time: '13542.343750' 2020-06-15 01:17:00 (15464): Status Report: Job Duration: '864000.000000' 2020-06-15 01:17:00 (15464): Status Report: Elapsed Time: '24001.714400' 2020-06-15 01:17:00 (15464): Status Report: CPU Time: '18076.250000' 2020-06-15 02:57:19 (15464): Status Report: Job Duration: '864000.000000' 2020-06-15 02:57:19 (15464): Status Report: Elapsed Time: '30002.356823' 2020-06-15 02:57:19 (15464): Status Report: CPU Time: '22598.828125' 2020-06-15 04:37:25 (15464): Status Report: Job Duration: '864000.000000' 2020-06-15 04:37:25 (15464): Status Report: Elapsed Time: '36002.356823' 2020-06-15 04:37:25 (15464): Status Report: CPU Time: '27111.015625' 2020-06-15 06:17:35 (15464): Status Report: Job Duration: '864000.000000' 2020-06-15 06:17:35 (15464): Status Report: Elapsed Time: '42002.356823' 2020-06-15 06:17:35 (15464): Status Report: CPU Time: '31644.765625' 2020-06-15 07:57:40 (15464): Status Report: Job Duration: '864000.000000' 2020-06-15 07:57:40 (15464): Status Report: Elapsed Time: '48002.356823' 2020-06-15 07:57:40 (15464): Status Report: CPU Time: '36163.875000' 2020-06-15 08:54:19 (15464): Stopping VM. 2020-06-15 08:54:19 (15464): Error in stop VM for VM: -108 Command: VBoxManage -q controlvm "boinc_1a09eefc584ffd05" savestate Output: 2020-06-15 08:54:19 (15464): VM did not stop when requested. 2020-06-15 08:54:19 (15464): VM was successfully terminated. 2020-06-15 09:19:01 (7988): Detected: vboxwrapper 26197 2020-06-15 09:19:01 (7988): Detected: BOINC client v7.7 2020-06-15 09:19:04 (7988): Detected: VirtualBox VboxManage Interface (Version: 6.1.4) 2020-06-15 09:19:04 (7988): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds) 2020-06-15 09:19:05 (7988): Starting VM using VBoxManage interface. (boinc_1a09eefc584ffd05, slot#0) 2020-06-15 09:19:18 (7988): Successfully started VM. (PID = '18332') 2020-06-15 09:19:18 (7988): Reporting VM Process ID to BOINC. 2020-06-15 09:19:18 (7988): Guest Log: BIOS: VirtualBox 6.1.4 2020-06-15 09:19:18 (7988): Guest Log: CPUID EDX: 0x178bfbff 2020-06-15 09:19:18 (7988): Guest Log: BIOS: ata0-0: PCHS=16383/16/63 LCHS=1024/255/63 2020-06-15 09:19:18 (7988): VM state change detected. (old = 'PoweredOff', new = 'Running') 2020-06-15 09:19:18 (7988): Status Report: Job Duration: '864000.000000' 2020-06-15 09:19:18 (7988): Status Report: Elapsed Time: '51377.545241' 2020-06-15 09:19:18 (7988): Status Report: CPU Time: '38695.906250' 2020-06-15 09:19:18 (7988): Preference change detected 2020-06-15 09:19:18 (7988): Setting CPU throttle for VM. (75%) 2020-06-15 09:19:19 (7988): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds)) 2020-06-15 09:19:19 (7988): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032 2020-06-15 09:19:20 (7988): Guest Log: BIOS: Booting from Hard Disk... 2020-06-15 09:19:22 (7988): Guest Log: BIOS: KBD: unsupported int 16h function 03 2020-06-15 09:19:22 (7988): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 2020-06-15 09:19:53 (7988): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds 2020-06-15 09:19:53 (7988): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000) 2020-06-15 09:19:58 (7988): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log 2020-06-15 09:19:58 (7988): Guest Log: 00:00:00.000220 main Log opened 2020-06-15T13:19:58.080475000Z 2020-06-15 09:19:58 (7988): Guest Log: 00:00:00.000400 main OS Product: Linux 2020-06-15 09:19:58 (7988): Guest Log: 00:00:00.000462 main OS Release: 4.14.76-13.cernvm.x86_64 2020-06-15 09:19:58 (7988): Guest Log: 00:00:00.000515 main OS Version: #1 SMP Tue Oct 16 18:26:15 CEST 2018 2020-06-15 09:19:58 (7988): Guest Log: 00:00:00.000567 main Executable: /usr/sbin/VBoxService 2020-06-15 09:19:58 (7988): Guest Log: 00:00:00.000568 main Process ID: 3192 2020-06-15 09:19:58 (7988): Guest Log: 00:00:00.000570 main Package type: LINUX_64BITS_GENERIC 2020-06-15 09:19:58 (7988): Guest Log: 00:00:00.004701 main 5.2.6 r120293 started. Verbose level = 0 2020-06-15 09:20:39 (7988): Guest Log: 15:20:39 CEST +02:00 2020-06-15: cranky: [INFO] Detected Theory App 2020-06-15 09:20:39 (7988): Guest Log: 15:20:39 CEST +02:00 2020-06-15: cranky: [INFO] Checking CVMFS. 2020-06-15 09:20:41 (7988): Guest Log: Probing /cvmfs/sft.cern.ch... OK 2020-06-15 09:20:42 (7988): 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-15 09:20:42 (7988): Guest Log: 2.5.2.0 4098 0 27164 18232 3 1 2193045 4096000 0 65024 0 0 n/a 0 0 http://s1bnl-cvmfs.openhtc.io/cvmfs/sft.cern.ch DIRECT 1 2020-06-15 09:20:44 (7988): Guest Log: Probing /cvmfs/grid.cern.ch... OK 2020-06-15 09:20:45 (7988): 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-15 09:20:45 (7988): Guest Log: 2.5.2.0 4073 0 27576 12485 3 2 2193045 4096000 0 65024 0 2 0 4272 997 http://s1cern-cvmfs.openhtc.io/cvmfs/grid.cern.ch DIRECT 1 2020-06-15 09:20:47 (7988): Guest Log: Probing /cvmfs/cernvm-prod.cern.ch... OK 2020-06-15 09:20:48 (7988): 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-15 09:20:48 (7988): Guest Log: 2.5.2.0 4051 0 25184 252 3 1 2193045 4096000 0 65024 0 0 n/a 0 0 http://s1fnal-cvmfs.openhtc.io/cvmfs/cernvm-prod.cern.ch DIRECT 1 2020-06-15 09:20:50 (7988): Guest Log: Probing /cvmfs/alice.cern.ch... OK 2020-06-15 09:20:51 (7988): 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-15 09:20:51 (7988): Guest Log: 2.5.2.0 4124 0 28200 6798 3 1 2193045 4096000 0 65024 0 0 n/a 4128 1187 http://s1fnal-cvmfs.openhtc.io/cvmfs/alice.cern.ch DIRECT 1 2020-06-15 09:20:51 (7988): Guest Log: 15:20:51 CEST +02:00 2020-06-15: cranky: [INFO] Checking runc. 2020-06-15 09:20:51 (7988): Guest Log: 15:20:51 CEST +02:00 2020-06-15: cranky: [INFO] Creating the filesystem. 2020-06-15 09:20:51 (7988): Guest Log: 15:20:51 CEST +02:00 2020-06-15: cranky: [INFO] Using /cvmfs/cernvm-prod.cern.ch/cvm3 2020-06-15 09:20:51 (7988): Guest Log: 15:20:51 CEST +02:00 2020-06-15: cranky: [INFO] Updating config.json. 2020-06-15 09:20:51 (7988): Guest Log: 15:20:51 CEST +02:00 2020-06-15: cranky: [INFO] Running Container 'runc'. 2020-06-15 09:20:52 (7988): Guest Log: job: htmld=/shared/html/job 2020-06-15 09:20:52 (7988): Guest Log: job: unpack exitcode=0 2020-06-15 09:20:54 (7988): Guest Log: 15:20:53 CEST +02:00 2020-06-15: cranky: [INFO] ===> [runRivet] Mon Jun 15 13:20:52 UTC 2020 [boinc pp jets 7000 80,-,960 - herwig7 7.2.0 default 100000 14] 2020-06-15 10:59:54 (7988): Status Report: Job Duration: '864000.000000' 2020-06-15 10:59:54 (7988): Status Report: Elapsed Time: '57378.435496' 2020-06-15 10:59:54 (7988): Status Report: CPU Time: '43316.390625' 2020-06-15 12:40:00 (7988): Status Report: Job Duration: '864000.000000' 2020-06-15 12:40:00 (7988): Status Report: Elapsed Time: '63378.435496' 2020-06-15 12:40:00 (7988): Status Report: CPU Time: '47830.156250' 2020-06-15 14:20:06 (7988): Status Report: Job Duration: '864000.000000' 2020-06-15 14:20:06 (7988): Status Report: Elapsed Time: '69378.435496' 2020-06-15 14:20:06 (7988): Status Report: CPU Time: '52401.468750' 2020-06-15 16:00:11 (7988): Status Report: Job Duration: '864000.000000' 2020-06-15 16:00:11 (7988): Status Report: Elapsed Time: '75378.435496' 2020-06-15 16:00:11 (7988): Status Report: CPU Time: '56915.734375' 2020-06-15 17:40:17 (7988): Status Report: Job Duration: '864000.000000' 2020-06-15 17:40:17 (7988): Status Report: Elapsed Time: '81378.435496' 2020-06-15 17:40:17 (7988): Status Report: CPU Time: '61429.937500' 2020-06-15 19:20:23 (7988): Status Report: Job Duration: '864000.000000' 2020-06-15 19:20:23 (7988): Status Report: Elapsed Time: '87378.435496' 2020-06-15 19:20:23 (7988): Status Report: CPU Time: '65943.171875' 2020-06-15 21:00:30 (7988): Status Report: Job Duration: '864000.000000' 2020-06-15 21:00:30 (7988): Status Report: Elapsed Time: '93378.435496' 2020-06-15 21:00:30 (7988): Status Report: CPU Time: '70455.546875' 2020-06-15 22:40:35 (7988): Status Report: Job Duration: '864000.000000' 2020-06-15 22:40:35 (7988): Status Report: Elapsed Time: '99378.435496' 2020-06-15 22:40:35 (7988): Status Report: CPU Time: '74968.062500' 2020-06-16 00:20:42 (7988): Status Report: Job Duration: '864000.000000' 2020-06-16 00:20:42 (7988): Status Report: Elapsed Time: '105378.435496' 2020-06-16 00:20:42 (7988): Status Report: CPU Time: '79481.750000' 2020-06-16 02:00:47 (7988): Status Report: Job Duration: '864000.000000' 2020-06-16 02:00:47 (7988): Status Report: Elapsed Time: '111378.435496' 2020-06-16 02:00:47 (7988): Status Report: CPU Time: '83994.218750' 2020-06-16 03:40:52 (7988): Status Report: Job Duration: '864000.000000' 2020-06-16 03:40:52 (7988): Status Report: Elapsed Time: '117378.435496' 2020-06-16 03:40:52 (7988): Status Report: CPU Time: '88506.328125' 2020-06-16 05:20:59 (7988): Status Report: Job Duration: '864000.000000' 2020-06-16 05:20:59 (7988): Status Report: Elapsed Time: '123378.435496' 2020-06-16 05:20:59 (7988): Status Report: CPU Time: '93044.734375' 2020-06-16 07:01:05 (7988): Status Report: Job Duration: '864000.000000' 2020-06-16 07:01:05 (7988): Status Report: Elapsed Time: '129378.435496' 2020-06-16 07:01:05 (7988): Status Report: CPU Time: '97617.171875' 2020-06-16 08:41:08 (7988): Status Report: Job Duration: '864000.000000' 2020-06-16 08:41:08 (7988): Status Report: Elapsed Time: '135378.435496' 2020-06-16 08:41:08 (7988): Status Report: CPU Time: '102180.109375' 2020-06-16 10:21:25 (7988): Status Report: Job Duration: '864000.000000' 2020-06-16 10:21:25 (7988): Status Report: Elapsed Time: '141378.494948' 2020-06-16 10:21:25 (7988): Status Report: CPU Time: '106723.296875' 2020-06-16 11:07:13 (7988): Stopping VM. 2020-06-16 11:07:13 (7988): Error in stop VM for VM: -108 Command: VBoxManage -q controlvm "boinc_1a09eefc584ffd05" savestate Output: 2020-06-16 11:07:13 (7988): VM did not stop when requested. 2020-06-16 11:07:13 (7988): VM was successfully terminated. 2020-06-16 11:08:48 (15632): Detected: vboxwrapper 26197 2020-06-16 11:08:48 (15632): Detected: BOINC client v7.7 2020-06-16 11:08:51 (15632): Detected: VirtualBox VboxManage Interface (Version: 6.1.4) 2020-06-16 11:08:51 (15632): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds) 2020-06-16 11:08:53 (15632): Starting VM using VBoxManage interface. (boinc_1a09eefc584ffd05, slot#0) 2020-06-16 11:09:16 (15632): Successfully started VM. (PID = '1468') 2020-06-16 11:09:16 (15632): Reporting VM Process ID to BOINC. 2020-06-16 11:09:16 (15632): Guest Log: BIOS: VirtualBox 6.1.4 2020-06-16 11:09:16 (15632): Guest Log: CPUID EDX: 0x178bfbff 2020-06-16 11:09:16 (15632): Guest Log: BIOS: ata0-0: PCHS=16383/16/63 LCHS=1024/255/63 2020-06-16 11:09:16 (15632): VM state change detected. (old = 'PoweredOff', new = 'Running') 2020-06-16 11:09:16 (15632): Status Report: Job Duration: '864000.000000' 2020-06-16 11:09:16 (15632): Status Report: Elapsed Time: '144124.755650' 2020-06-16 11:09:16 (15632): Status Report: CPU Time: '108811.640625' 2020-06-16 11:09:16 (15632): Preference change detected 2020-06-16 11:09:16 (15632): Setting CPU throttle for VM. (75%) 2020-06-16 11:09:16 (15632): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds)) 2020-06-16 11:09:18 (15632): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032 2020-06-16 11:09:18 (15632): Guest Log: BIOS: Booting from Hard Disk... 2020-06-16 11:09:20 (15632): Guest Log: BIOS: KBD: unsupported int 16h function 03 2020-06-16 11:09:20 (15632): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 2020-06-16 11:09:47 (15632): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds 2020-06-16 11:09:47 (15632): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000) 2020-06-16 11:09:52 (15632): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log 2020-06-16 11:09:52 (15632): Guest Log: 00:00:00.000195 main Log opened 2020-06-16T15:09:52.137285000Z 2020-06-16 11:09:52 (15632): Guest Log: 00:00:00.008592 main OS Product: Linux 2020-06-16 11:09:52 (15632): Guest Log: 00:00:00.008702 main OS Release: 4.14.76-13.cernvm.x86_64 2020-06-16 11:09:52 (15632): Guest Log: 00:00:00.008760 main OS Version: #1 SMP Tue Oct 16 18:26:15 CEST 2018 2020-06-16 11:09:52 (15632): Guest Log: 00:00:00.008800 main Executable: /usr/sbin/VBoxService 2020-06-16 11:09:52 (15632): Guest Log: 00:00:00.008801 main Process ID: 3204 2020-06-16 11:09:52 (15632): Guest Log: 00:00:00.008802 main Package type: LINUX_64BITS_GENERIC 2020-06-16 11:09:52 (15632): Guest Log: 00:00:00.011353 main 5.2.6 r120293 started. Verbose level = 0 2020-06-16 11:10:28 (15632): Guest Log: 17:10:28 CEST +02:00 2020-06-16: cranky: [INFO] Detected Theory App 2020-06-16 11:10:28 (15632): Guest Log: 17:10:28 CEST +02:00 2020-06-16: cranky: [INFO] Checking CVMFS. 2020-06-16 11:10:31 (15632): Guest Log: Probing /cvmfs/sft.cern.ch... OK 2020-06-16 11:10:32 (15632): 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-16 11:10:32 (15632): Guest Log: 2.5.2.0 4071 0 27188 18247 3 1 3523235 4096001 0 65024 0 0 n/a 0 0 http://s1bnl-cvmfs.openhtc.io/cvmfs/sft.cern.ch DIRECT 1 2020-06-16 11:10:34 (15632): Guest Log: Probing /cvmfs/grid.cern.ch... OK 2020-06-16 11:10:35 (15632): 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-16 11:10:35 (15632): Guest Log: 2.5.2.0 4093 0 25472 12493 3 2 3523235 4096001 0 65024 0 2 -100 6411 1256 http://s1fnal-cvmfs.openhtc.io/cvmfs/grid.cern.ch DIRECT 1 2020-06-16 11:10:37 (15632): Guest Log: Probing /cvmfs/cernvm-prod.cern.ch... OK 2020-06-16 11:10:39 (15632): 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-16 11:10:39 (15632): Guest Log: 2.5.2.0 4043 0 27220 253 3 1 3523235 4096001 0 65024 0 0 n/a 0 0 http://s1fnal-cvmfs.openhtc.io/cvmfs/cernvm-prod.cern.ch DIRECT 1 2020-06-16 11:10:41 (15632): Guest Log: Probing /cvmfs/alice.cern.ch... OK 2020-06-16 11:10:42 (15632): 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-16 11:10:42 (15632): Guest Log: 2.5.2.0 4116 0 27712 6810 3 1 3523235 4096001 0 65024 0 0 n/a 4133 1170 http://s1unl-cvmfs.openhtc.io/cvmfs/alice.cern.ch DIRECT 1 2020-06-16 11:10:42 (15632): Guest Log: 17:10:42 CEST +02:00 2020-06-16: cranky: [INFO] Checking runc. 2020-06-16 11:10:43 (15632): Guest Log: 17:10:43 CEST +02:00 2020-06-16: cranky: [INFO] Creating the filesystem. 2020-06-16 11:10:43 (15632): Guest Log: 17:10:43 CEST +02:00 2020-06-16: cranky: [INFO] Using /cvmfs/cernvm-prod.cern.ch/cvm3 2020-06-16 11:10:43 (15632): Guest Log: 17:10:43 CEST +02:00 2020-06-16: cranky: [INFO] Updating config.json. 2020-06-16 11:10:43 (15632): Guest Log: 17:10:43 CEST +02:00 2020-06-16: cranky: [INFO] Running Container 'runc'. 2020-06-16 11:10:44 (15632): Guest Log: job: htmld=/shared/html/job 2020-06-16 11:10:45 (15632): Guest Log: job: unpack exitcode=0 2020-06-16 11:10:45 (15632): Guest Log: 17:10:45 CEST +02:00 2020-06-16: cranky: [INFO] ===> [runRivet] Tue Jun 16 15:10:45 UTC 2020 [boinc pp jets 7000 80,-,960 - herwig7 7.2.0 default 100000 14] 2020-06-16 12:49:33 (15632): Status Report: Job Duration: '864000.000000' 2020-06-16 12:49:33 (15632): Status Report: Elapsed Time: '150124.888018' 2020-06-16 12:49:33 (15632): Status Report: CPU Time: '113347.593750' 2020-06-16 14:29:39 (15632): Status Report: Job Duration: '864000.000000' 2020-06-16 14:29:39 (15632): Status Report: Elapsed Time: '156124.888018' 2020-06-16 14:29:39 (15632): Status Report: CPU Time: '117863.031250' 2020-06-16 16:09:44 (15632): Status Report: Job Duration: '864000.000000' 2020-06-16 16:09:44 (15632): Status Report: Elapsed Time: '162124.888018' 2020-06-16 16:09:44 (15632): Status Report: CPU Time: '122374.546875' 2020-06-16 17:49:50 (15632): Status Report: Job Duration: '864000.000000' 2020-06-16 17:49:50 (15632): Status Report: Elapsed Time: '168124.888018' 2020-06-16 17:49:50 (15632): Status Report: CPU Time: '126885.078125' 2020-06-16 19:29:55 (15632): Status Report: Job Duration: '864000.000000' 2020-06-16 19:29:55 (15632): Status Report: Elapsed Time: '174124.888018' 2020-06-16 19:29:55 (15632): Status Report: CPU Time: '131395.359375' 2020-06-16 21:10:02 (15632): Status Report: Job Duration: '864000.000000' 2020-06-16 21:10:02 (15632): Status Report: Elapsed Time: '180124.888018' 2020-06-16 21:10:02 (15632): Status Report: CPU Time: '135906.546875' 2020-06-16 21:20:11 (15632): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-18 13:01:41 (15500): Detected: vboxwrapper 26197 2020-06-18 13:01:41 (15500): Detected: BOINC client v7.7 2020-06-18 13:01:44 (15500): Detected: VirtualBox VboxManage Interface (Version: 6.1.4) 2020-06-18 13:01:45 (15500): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds) 2020-06-18 13:01:47 (15500): Starting VM using VBoxManage interface. (boinc_1a09eefc584ffd05, slot#0) 2020-06-18 13:02:15 (15500): Successfully started VM. (PID = '16756') 2020-06-18 13:02:15 (15500): Reporting VM Process ID to BOINC. 2020-06-18 13:02:15 (15500): Guest Log: BIOS: VirtualBox 6.1.4 2020-06-18 13:02:15 (15500): Guest Log: CPUID EDX: 0x178bfbff 2020-06-18 13:02:15 (15500): Guest Log: BIOS: ata0-0: PCHS=16383/16/63 LCHS=1024/255/63 2020-06-18 13:02:15 (15500): VM state change detected. (old = 'PoweredOff', new = 'Running') 2020-06-18 13:02:15 (15500): Status Report: Job Duration: '864000.000000' 2020-06-18 13:02:15 (15500): Status Report: Elapsed Time: '180674.888018' 2020-06-18 13:02:15 (15500): Status Report: CPU Time: '136320.062500' 2020-06-18 13:02:15 (15500): Preference change detected 2020-06-18 13:02:15 (15500): Setting CPU throttle for VM. (75%) 2020-06-18 13:02:16 (15500): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds)) 2020-06-18 13:02:17 (15500): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032 2020-06-18 13:02:17 (15500): Guest Log: BIOS: Booting from Hard Disk... 2020-06-18 13:02:20 (15500): Guest Log: BIOS: KBD: unsupported int 16h function 03 2020-06-18 13:02:20 (15500): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 2020-06-18 13:02:50 (15500): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds 2020-06-18 13:02:50 (15500): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000) 2020-06-18 13:02:59 (15500): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log 2020-06-18 13:02:59 (15500): Guest Log: 00:00:00.000207 main Log opened 2020-06-18T17:03:00.063166000Z 2020-06-18 13:02:59 (15500): Guest Log: 00:00:00.000352 main OS Product: Linux 2020-06-18 13:02:59 (15500): Guest Log: 00:00:00.000442 main OS Release: 4.14.76-13.cernvm.x86_64 2020-06-18 13:02:59 (15500): Guest Log: 00:00:00.000494 main OS Version: #1 SMP Tue Oct 16 18:26:15 CEST 2018 2020-06-18 13:02:59 (15500): Guest Log: 00:00:00.000557 main Executable: /usr/sbin/VBoxService 2020-06-18 13:02:59 (15500): Guest Log: 00:00:00.000559 main Process ID: 3211 2020-06-18 13:02:59 (15500): Guest Log: 00:00:00.000560 main Package type: LINUX_64BITS_GENERIC 2020-06-18 13:02:59 (15500): Guest Log: 00:00:00.026714 main 5.2.6 r120293 started. Verbose level = 0 2020-06-18 13:03:52 (15500): Guest Log: 19:03:48 CEST +02:00 2020-06-18: cranky: [INFO] Detected Theory App 2020-06-18 13:03:52 (15500): Guest Log: 19:03:48 CEST +02:00 2020-06-18: cranky: [INFO] Checking CVMFS. 2020-06-18 13:03:52 (15500): Guest Log: Probing /cvmfs/sft.cern.ch... OK 2020-06-18 13:03:53 (15500): 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-18 13:03:53 (15500): Guest Log: 2.5.2.0 4090 0 26484 18300 3 1 2506772 4096000 0 65024 0 0 n/a 5 4 http://s1fnal-cvmfs.openhtc.io/cvmfs/sft.cern.ch DIRECT 1 2020-06-18 13:03:56 (15500): Guest Log: Probing /cvmfs/grid.cern.ch... OK 2020-06-18 13:03:58 (15500): 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-18 13:03:58 (15500): Guest Log: 2.5.2.0 4115 0 25656 12509 3 2 2506772 4096000 0 65024 0 2 0 4272 1091 http://s1unl-cvmfs.openhtc.io/cvmfs/grid.cern.ch DIRECT 1 2020-06-18 13:04:02 (15500): Guest Log: Probing /cvmfs/cernvm-prod.cern.ch... OK 2020-06-18 13:04:06 (15500): 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-18 13:04:06 (15500): Guest Log: 2.5.2.0 4068 0 25200 255 3 1 2506772 4096000 0 65024 0 0 n/a 4 3 http://s1fnal-cvmfs.openhtc.io/cvmfs/cernvm-prod.cern.ch DIRECT 1 2020-06-18 13:04:06 (15500): Guest Log: Probing /cvmfs/alice.cern.ch... OK 2020-06-18 13:04:08 (15500): 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-18 13:04:10 (15500): Guest Log: 2.5.2.0 4137 0 29736 6827 3 1 2506772 4096000 0 65024 0 0 n/a 4141 1131 http://s1bnl-cvmfs.openhtc.io/cvmfs/alice.cern.ch DIRECT 1 2020-06-18 13:04:10 (15500): Guest Log: 19:04:08 CEST +02:00 2020-06-18: cranky: [INFO] Checking runc. 2020-06-18 13:04:10 (15500): Guest Log: 19:04:08 CEST +02:00 2020-06-18: cranky: [INFO] Creating the filesystem. 2020-06-18 13:04:10 (15500): Guest Log: 19:04:09 CEST +02:00 2020-06-18: cranky: [INFO] Using /cvmfs/cernvm-prod.cern.ch/cvm3 2020-06-18 13:04:10 (15500): Guest Log: 19:04:09 CEST +02:00 2020-06-18: cranky: [INFO] Updating config.json. 2020-06-18 13:04:10 (15500): Guest Log: 19:04:09 CEST +02:00 2020-06-18: cranky: [INFO] Running Container 'runc'. 2020-06-18 13:04:10 (15500): Guest Log: job: htmld=/shared/html/job 2020-06-18 13:04:10 (15500): Guest Log: job: unpack exitcode=0 2020-06-18 13:04:11 (15500): Guest Log: 19:04:11 CEST +02:00 2020-06-18: cranky: [INFO] ===> [runRivet] Thu Jun 18 17:04:10 UTC 2020 [boinc pp jets 7000 80,-,960 - herwig7 7.2.0 default 100000 14] 2020-06-18 13:09:15 (15500): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-20 13:50:52 (15500): Error in resume VM for VM: -2135228414 Command: VBoxManage -q controlvm "boinc_1a09eefc584ffd05" resume Output: VBoxManage.exe: error: Cannot resume the machine as it is not paused (machine state: Running) VBoxManage.exe: error: Details: code VBOX_E_INVALID_VM_STATE (0x80bb0002), component ConsoleWrap, interface IConsole, callee IUnknown VBoxManage.exe: error: Context: "Resume()" at line 410 of file VBoxManageControlVM.cpp 2020-06-20 13:50:54 (15500): Guest Log: 00:06:20.291076 timesync vgsvcTimeSyncWorker: Radical host time change: 175 296 928 000 000ns (HostNow=1 592 675 446 407 000 000 ns HostLast=1 592 500 149 479 000 000 ns) 2020-06-20 13:50:54 (15500): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-20 13:50:57 (15500): Guest Log: 00:06:30.293527 timesync vgsvcTimeSyncWorker: Radical guest time change: 175 296 067 407 000ns (GuestNow=1 592 675 456 415 428 000 ns GuestLast=1 592 500 160 348 021 000 ns fSetTimeLastLoop=true ) 2020-06-20 14:10:44 (15500): Guest Log: job: run exitcode=1 2020-06-20 14:14:44 (15500): Guest Log: ./job: line 74: /usr/bin/du: Input/output error 2020-06-20 14:14:44 (15500): Guest Log: job: diskusage= 2020-06-20 14:17:57 (15500): Guest Log: ./job: line 80: /usr/bin/du: Input/output error 2020-06-20 14:17:57 (15500): Guest Log: job: logsize= k 2020-06-20 14:17:57 (15500): Guest Log: ./job: line 83: ((: > 1000 : syntax error: operand expected (error token is "> 1000 ") 2020-06-20 14:17:57 (15500): Guest Log: job: times= 2020-06-20 14:17:57 (15500): Guest Log: 0m0.013s 0m0.028s 2020-06-20 14:17:57 (15500): Guest Log: 0m56.824s 0m21.238s 2020-06-20 14:17:57 (15500): Guest Log: job: cpuusage=78 2020-06-20 14:21:15 (15500): Guest Log: /shared/bin/copilot-config: line 10: /usr/bin/xmllint: Input/output error 2020-06-20 14:24:40 (15500): Guest Log: /shared/bin/copilot-config: line 11: /usr/bin/xmllint: Input/output error 2020-06-20 14:27:45 (15500): Guest Log: /shared/bin/copilot-config: line 12: /usr/bin/xmllint: Input/output error 2020-06-20 14:28:09 (15500): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-20 20:51:58 (15500): Error in resume VM for VM: -2135228414 Command: VBoxManage -q controlvm "boinc_1a09eefc584ffd05" resume Output: VBoxManage.exe: error: Cannot resume the machine as it is not paused (machine state: Running) VBoxManage.exe: error: Details: code VBOX_E_INVALID_VM_STATE (0x80bb0002), component ConsoleWrap, interface IConsole, callee IUnknown VBoxManage.exe: error: Context: "Resume()" at line 410 of file VBoxManageControlVM.cpp 2020-06-20 20:51:58 (15500): Guest Log: 00:43:41.200302 timesync vgsvcTimeSyncWorker: Radical host time change: 23 031 989 000 000ns (HostNow=1 592 700 709 289 000 000 ns HostLast=1 592 677 677 300 000 000 ns) 2020-06-20 20:51:58 (15500): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-20 20:51:59 (15500): Guest Log: 00:43:51.200494 timesync vgsvcTimeSyncWorker: Radical guest time change: 23 031 967 016 000ns (GuestNow=1 592 700 719 289 204 000 ns GuestLast=1 592 677 687 322 188 000 ns fSetTimeLastLoop=true ) 2020-06-20 20:54:43 (15500): Guest Log: /shared/bin/copilot-config: line 13: /usr/bin/xmllint: Input/output error 2020-06-20 20:57:30 (15500): Guest Log: /shared/bin/copilot-config: line 14: /usr/bin/xmllint: Input/output error 2020-06-20 20:57:30 (15500): Guest Log: 02:57:29 CEST +02:00 2020-06-21: cranky: [INFO] Container 'runc' finished with status code 1. 2020-06-20 20:57:30 (15500): Guest Log: 02:57:29 CEST +02:00 2020-06-21: cranky: [INFO] Preparing output. 2020-06-20 20:59:03 (15500): VM Completion File Detected. 2020-06-20 20:59:03 (15500): VM Completion Message: Job Finished . 2020-06-20 20:59:03 (15500): Powering off VM. 2020-06-20 21:04:08 (15500): VM did not power off when requested. 2020-06-20 21:04:08 (15500): VM was successfully terminated. 2020-06-20 21:04:08 (15500): Deregistering VM. (boinc_1a09eefc584ffd05, slot#0) 2020-06-20 21:04:10 (15500): Removing network bandwidth throttle group from VM. 2020-06-20 21:04:10 (15500): Removing VM from VirtualBox. 21:04:16 (15500): called boinc_finish(0) </stderr_txt> ]]>
©2024 CERN