| Name | Theory_2390-1126631-298_1 |
| Workunit | 196944235 |
| Created | 1 Nov 2022, 13:17:10 UTC |
| Sent | 2 Nov 2022, 5:21:23 UTC |
| Report deadline | 13 Nov 2022, 5:21:23 UTC |
| Received | 14 Nov 2022, 10:37:59 UTC |
| Server state | Over |
| Outcome | Success |
| Client state | Done |
| Exit status | 0 (0x00000000) |
| Computer ID | 10592369 |
| Run time | 2 days 22 hours 13 min 16 sec |
| CPU time | 1 days 8 hours 12 min 41 sec |
| Validate state | Valid |
| Credit | 2,609.24 |
| Device peak FLOPS | 4.46 GFLOPS |
| Application version | Theory Simulation v300.06 (vbox64_theory) windows_x86_64 |
| Peak working set size | 82.25 MB |
| Peak swap size | 82.11 MB |
| Peak disk usage | 3.41 GB |
Stderr output
<core_client_version>7.16.11</core_client_version> <![CDATA[ <stderr_txt> D 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 2022-11-10 17:10:16 (3824): Guest Log: 2.5.2.0 4060 0 27180 24259 3 1 2449569 4096000 0 65024 0 0 n/a 5 6 http://s1ral-cvmfs.openhtc.io/cvmfs/sft.cern.ch DIRECT 1 2022-11-10 17:10:21 (3824): Guest Log: Probing /cvmfs/grid.cern.ch... OK 2022-11-10 17:10:23 (3824): 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 2022-11-10 17:10:23 (3824): Guest Log: 2.5.2.0 4084 0 25696 19731 3 2 2449569 4096000 0 65024 0 2 0 5645 959 http://s1ral-cvmfs.openhtc.io/cvmfs/grid.cern.ch DIRECT 1 2022-11-10 17:10:28 (3824): Guest Log: Probing /cvmfs/cernvm-prod.cern.ch... OK 2022-11-10 17:10:30 (3824): 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 2022-11-10 17:10:30 (3824): Guest Log: 2.5.2.0 4107 0 28540 268 3 1 2449569 4096000 0 65024 0 0 n/a 0 0 http://s1cern-cvmfs.openhtc.io/cvmfs/cernvm-prod.cern.ch DIRECT 1 2022-11-10 17:10:33 (3824): Guest Log: Probing /cvmfs/alice.cern.ch... OK 2022-11-10 17:10:34 (3824): 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 2022-11-10 17:10:34 (3824): Guest Log: 2.5.2.0 4137 0 34140 13790 3 1 2449569 4096000 0 65024 0 0 n/a 15032 647 http://s1cern-cvmfs.openhtc.io/cvmfs/alice.cern.ch DIRECT 1 2022-11-10 17:10:34 (3824): Guest Log: 17:10:34 GMT +00:00 2022-11-10: cranky: [INFO] Checking runc. 2022-11-10 17:10:35 (3824): Guest Log: 17:10:34 GMT +00:00 2022-11-10: cranky: [INFO] Creating the filesystem. 2022-11-10 17:10:35 (3824): Guest Log: 17:10:34 GMT +00:00 2022-11-10: cranky: [INFO] Using /cvmfs/cernvm-prod.cern.ch/cvm3 2022-11-10 17:10:35 (3824): Guest Log: 17:10:34 GMT +00:00 2022-11-10: cranky: [INFO] Updating config.json. 2022-11-10 17:10:35 (3824): Guest Log: 17:10:34 GMT +00:00 2022-11-10: cranky: [INFO] Running Container 'runc'. 2022-11-10 17:10:36 (3824): Guest Log: job: htmld=/shared/html/job 2022-11-10 17:10:36 (3824): Guest Log: job: unpack exitcode=0 2022-11-10 17:10:37 (3824): Guest Log: 17:10:36 GMT +00:00 2022-11-10: cranky: [INFO] ===> [runRivet] Thu Nov 10 17:10:35 UTC 2022 [boinc pp jets 7000 80,-,1060 - pythia8 8.244 tune-AU2loxx 100000 298] 2022-11-10 17:27:41 (3824): VM state change detected. (old = 'Running', new = 'Paused') 2022-11-10 17:33:17 (3824): VM state change detected. (old = 'Paused', new = 'Running') 2022-11-10 17:36:54 (3824): VM state change detected. (old = 'Running', new = 'Paused') 2022-11-10 20:34:17 (3824): VM state change detected. (old = 'Paused', new = 'Running') 2022-11-10 20:34:22 (3824): Guest Log: 00:22:02.260545 timesync vgsvcTimeSyncWorker: Radical host time change: 10 652 510 000 000ns (HostNow=1 668 112 461 262 000 000 ns HostLast=1 668 101 808 752 000 000 ns) 2022-11-10 20:34:32 (3824): Guest Log: 00:22:12.285107 timesync vgsvcTimeSyncWorker: Radical guest time change: 10 988 678 287 000ns (GuestNow=1 668 112 471 394 840 000 ns GuestLast=1 668 101 482 716 553 000 ns fSetTimeLastLoop=true ) 2022-11-10 20:35:23 (3824): VM state change detected. (old = 'Running', new = 'Paused') 2022-11-10 20:40:02 (3824): VM state change detected. (old = 'Paused', new = 'Running') 2022-11-10 20:42:21 (3824): VM state change detected. (old = 'Running', new = 'Paused') 2022-11-10 20:57:01 (3824): VM state change detected. (old = 'Paused', new = 'Running') 2022-11-10 21:04:24 (3824): VM state change detected. (old = 'Running', new = 'Paused') 2022-11-10 22:10:00 (3824): VM state change detected. (old = 'Paused', new = 'Running') 2022-11-10 22:10:08 (3824): Guest Log: 00:32:54.057649 timesync vgsvcTimeSyncWorker: Radical host time change: 3 945 275 000 000ns (HostNow=1 668 118 207 131 000 000 ns HostLast=1 668 114 261 856 000 000 ns) 2022-11-10 22:10:18 (3824): Guest Log: 00:33:04.073203 timesync vgsvcTimeSyncWorker: Radical guest time change: 5 103 979 234 000ns (GuestNow=1 668 118 217 146 588 000 ns GuestLast=1 668 113 113 167 354 000 ns fSetTimeLastLoop=true ) 2022-11-10 22:27:06 (3824): VM state change detected. (old = 'Running', new = 'Paused') 2022-11-10 22:34:38 (3824): VM state change detected. (old = 'Paused', new = 'Running') 2022-11-10 22:35:35 (3824): VM state change detected. (old = 'Running', new = 'Paused') 2022-11-10 22:47:51 (3824): VM state change detected. (old = 'Paused', new = 'Running') 2022-11-10 22:48:59 (3824): VM state change detected. (old = 'Running', new = 'Paused') 2022-11-10 22:52:06 (3824): VM state change detected. (old = 'Paused', new = 'Running') 2022-11-10 23:09:38 (3824): VM state change detected. (old = 'Running', new = 'Paused') 2022-11-10 23:12:44 (3824): VM state change detected. (old = 'Paused', new = 'Running') 2022-11-10 23:42:29 (3824): Status Report: Job Duration: '864000.000000' 2022-11-10 23:42:29 (3824): Status Report: Elapsed Time: '154409.000000' 2022-11-10 23:42:29 (3824): Status Report: CPU Time: '71543.609375' 2022-11-11 00:03:18 (3824): VM state change detected. (old = 'Running', new = 'Paused') 2022-11-11 00:06:24 (3824): VM state change detected. (old = 'Paused', new = 'Running') 2022-11-11 00:12:17 (3824): VM state change detected. (old = 'Running', new = 'Paused') 2022-11-11 00:15:25 (3824): VM state change detected. (old = 'Paused', new = 'Running') 2022-11-11 00:39:22 (3824): VM state change detected. (old = 'Running', new = 'Paused') 2022-11-11 00:42:27 (3824): VM state change detected. (old = 'Paused', new = 'Running') 2022-11-11 00:53:51 (3824): VM state change detected. (old = 'Running', new = 'Paused') 2022-11-11 00:56:56 (3824): VM state change detected. (old = 'Paused', new = 'Running') 2022-11-11 01:18:59 (3824): VM state change detected. (old = 'Running', new = 'Paused') 2022-11-11 01:22:08 (3824): VM state change detected. (old = 'Paused', new = 'Running') 2022-11-11 01:38:10 (3824): Status Report: Job Duration: '864000.000000' 2022-11-11 01:38:10 (3824): Status Report: Elapsed Time: '160409.000000' 2022-11-11 01:38:10 (3824): Status Report: CPU Time: '74279.765625' 2022-11-11 03:18:15 (3824): Status Report: Job Duration: '864000.000000' 2022-11-11 03:18:15 (3824): Status Report: Elapsed Time: '166409.000000' 2022-11-11 03:18:15 (3824): Status Report: CPU Time: '77008.562500' 2022-11-11 03:19:11 (3824): VM state change detected. (old = 'Running', new = 'Paused') 2022-11-11 10:39:05 (3824): Error in resume VM for VM: -2135228414 Command: VBoxManage -q controlvm "boinc_a5acfd5119f860a2" 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 2022-11-11 10:39:05 (3824): Guest Log: 05:00:38.761721 timesync vgsvcTimeSyncWorker: Radical host time change: 26 396 800 000 000ns (HostNow=1 668 163 142 007 000 000 ns HostLast=1 668 136 745 207 000 000 ns) 2022-11-11 10:39:05 (3824): VM state change detected. (old = 'Paused', new = 'Running') 2022-11-11 10:39:06 (3824): VM state change detected. (old = 'Running', new = 'Paused') 2022-11-11 10:46:45 (3824): VM state change detected. (old = 'Paused', new = 'Running') 2022-11-11 10:46:51 (3824): Guest Log: 05:00:48.762508 timesync vgsvcTimeSyncWorker: Radical guest time change: 27 506 043 742 000ns (GuestNow=1 668 163 152 007 789 000 ns GuestLast=1 668 135 645 964 047 000 ns fSetTimeLastLoop=true ) 2022-11-11 10:48:45 (3824): VM state change detected. (old = 'Running', new = 'Paused') 2022-11-11 12:07:12 (3824): VM state change detected. (old = 'Paused', new = 'Running') 2022-11-11 12:07:18 (3824): Guest Log: 05:02:48.979679 timesync vgsvcTimeSyncWorker: Radical host time change: 4 717 775 000 000ns (HostNow=1 668 168 438 691 000 000 ns HostLast=1 668 163 720 916 000 000 ns) 2022-11-11 12:07:28 (3824): Guest Log: 05:02:59.027235 timesync vgsvcTimeSyncWorker: Radical guest time change: 5 176 513 606 000ns (GuestNow=1 668 168 448 738 552 000 ns GuestLast=1 668 163 272 224 946 000 ns fSetTimeLastLoop=true ) 2022-11-11 12:12:49 (3824): VM state change detected. (old = 'Running', new = 'Paused') 2022-11-11 13:35:21 (3824): VM state change detected. (old = 'Paused', new = 'Running') 2022-11-11 13:35:22 (3824): Guest Log: 05:08:19.849608 timesync vgsvcTimeSyncWorker: Radical host time change: 4 962 562 000 000ns (HostNow=1 668 173 722 097 000 000 ns HostLast=1 668 168 759 535 000 000 ns) 2022-11-11 13:35:32 (3824): Guest Log: 05:08:29.862539 timesync vgsvcTimeSyncWorker: Radical guest time change: 4 962 549 083 000ns (GuestNow=1 668 173 732 109 961 000 ns GuestLast=1 668 168 769 560 878 000 ns fSetTimeLastLoop=true ) 2022-11-11 13:38:58 (3824): VM state change detected. (old = 'Running', new = 'Paused') 2022-11-11 13:46:06 (3824): VM state change detected. (old = 'Paused', new = 'Running') 2022-11-11 13:46:11 (3824): VM state change detected. (old = 'Running', new = 'Paused') 2022-11-11 13:53:23 (3824): Stopping VM. 2022-11-11 13:53:24 (3824): Error in stop VM for VM: -108 Command: VBoxManage -q controlvm "boinc_a5acfd5119f860a2" savestate Output: 2022-11-11 13:53:24 (3824): VM did not stop when requested. 2022-11-11 13:53:24 (3824): VM was NOT successfully terminated. 2022-11-11 16:18:28 (8668): Detected: vboxwrapper 26197 2022-11-11 16:18:28 (8668): Detected: BOINC client v7.7 2022-11-11 16:18:30 (8668): Detected: VirtualBox VboxManage Interface (Version: 6.1.12) 2022-11-11 16:18:30 (8668): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds) 2022-11-11 16:18:30 (8668): Starting VM using VBoxManage interface. (boinc_a5acfd5119f860a2, slot#1) 2022-11-11 16:18:35 (8668): Successfully started VM. (PID = '13868') 2022-11-11 16:18:35 (8668): Reporting VM Process ID to BOINC. 2022-11-11 16:18:35 (8668): Guest Log: BIOS: VirtualBox 6.1.12 2022-11-11 16:18:35 (8668): Guest Log: CPUID EDX: 0x178bfbff 2022-11-11 16:18:35 (8668): Guest Log: BIOS: ata0-0: PCHS=16383/16/63 LCHS=1024/255/63 2022-11-11 16:18:35 (8668): VM state change detected. (old = 'PoweredOff', new = 'Running') 2022-11-11 16:18:35 (8668): Detected: Web Application Enabled (http://localhost:58961) 2022-11-11 16:18:35 (8668): Detected: Remote Desktop Enabled (localhost:58962) 2022-11-11 16:18:35 (8668): Status Report: Job Duration: '864000.000000' 2022-11-11 16:18:35 (8668): Status Report: Elapsed Time: '167138.000000' 2022-11-11 16:18:35 (8668): Status Report: CPU Time: '77345.656250' 2022-11-11 16:18:35 (8668): Preference change detected 2022-11-11 16:18:35 (8668): Setting CPU throttle for VM. (42%) 2022-11-11 16:18:35 (8668): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 77 seconds) or (Vbox_job.xml: 600 seconds)) 2022-11-11 16:18:37 (8668): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032 2022-11-11 16:18:37 (8668): Guest Log: BIOS: Booting from Hard Disk... 2022-11-11 16:18:39 (8668): Guest Log: BIOS: KBD: unsupported int 16h function 03 2022-11-11 16:18:39 (8668): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 2022-11-11 16:19:04 (8668): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds 2022-11-11 16:19:04 (8668): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000) 2022-11-11 16:19:08 (8668): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log 2022-11-11 16:19:08 (8668): Guest Log: 00:00:00.000190 main Log opened 2022-11-11T16:19:08.108410000Z 2022-11-11 16:19:08 (8668): Guest Log: 00:00:00.009931 main OS Product: Linux 2022-11-11 16:19:08 (8668): Guest Log: 00:00:00.010029 main OS Release: 4.14.76-13.cernvm.x86_64 2022-11-11 16:19:08 (8668): Guest Log: 00:00:00.010064 main OS Version: #1 SMP Tue Oct 16 18:26:15 CEST 2018 2022-11-11 16:19:08 (8668): Guest Log: 00:00:00.010096 main Executable: /usr/sbin/VBoxService 2022-11-11 16:19:08 (8668): Guest Log: 00:00:00.010097 main Process ID: 3204 2022-11-11 16:19:08 (8668): Guest Log: 00:00:00.010097 main Package type: LINUX_64BITS_GENERIC 2022-11-11 16:19:08 (8668): Guest Log: 00:00:00.011784 main 5.2.6 r120293 started. Verbose level = 0 2022-11-11 16:19:48 (8668): Guest Log: 16:19:48 GMT +00:00 2022-11-11: cranky: [INFO] Detected Theory App 2022-11-11 16:19:48 (8668): Guest Log: 16:19:48 GMT +00:00 2022-11-11: cranky: [INFO] Checking CVMFS. 2022-11-11 16:19:50 (8668): Guest Log: Probing /cvmfs/sft.cern.ch... OK 2022-11-11 16:19:51 (8668): 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 2022-11-11 16:19:51 (8668): Guest Log: 2.5.2.0 4042 0 25968 24269 3 1 2492940 4096000 0 65024 0 0 n/a 0 0 http://s1ral-cvmfs.openhtc.io/cvmfs/sft.cern.ch DIRECT 1 2022-11-11 16:19:52 (8668): Guest Log: Probing /cvmfs/grid.cern.ch... OK 2022-11-11 16:19:54 (8668): 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 2022-11-11 16:19:54 (8668): Guest Log: 2.5.2.0 4121 0 25480 19739 3 2 2492940 4096000 0 65024 0 2 0 5646 655 http://s1asgc-cvmfs.openhtc.io:8080/cvmfs/grid.cern.ch DIRECT 1 2022-11-11 16:19:55 (8668): Guest Log: Probing /cvmfs/cernvm-prod.cern.ch... OK 2022-11-11 16:19:56 (8668): 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 2022-11-11 16:19:56 (8668): Guest Log: 2.5.2.0 4098 0 27208 268 3 1 2492940 4096000 0 65024 0 0 n/a 0 0 http://s1ral-cvmfs.openhtc.io/cvmfs/cernvm-prod.cern.ch DIRECT 1 2022-11-11 16:19:57 (8668): Guest Log: Probing /cvmfs/alice.cern.ch... OK 2022-11-11 16:19:58 (8668): 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 2022-11-11 16:19:58 (8668): Guest Log: 2.5.2.0 4075 0 34236 13797 3 1 2492940 4096000 0 65024 0 0 n/a 15032 1261 http://s1ral-cvmfs.openhtc.io/cvmfs/alice.cern.ch DIRECT 1 2022-11-11 16:19:58 (8668): Guest Log: 16:19:58 GMT +00:00 2022-11-11: cranky: [INFO] Checking runc. 2022-11-11 16:19:58 (8668): Guest Log: 16:19:58 GMT +00:00 2022-11-11: cranky: [INFO] Creating the filesystem. 2022-11-11 16:19:58 (8668): Guest Log: 16:19:58 GMT +00:00 2022-11-11: cranky: [INFO] Using /cvmfs/cernvm-prod.cern.ch/cvm3 2022-11-11 16:19:59 (8668): Guest Log: 16:19:58 GMT +00:00 2022-11-11: cranky: [INFO] Updating config.json. 2022-11-11 16:19:59 (8668): Guest Log: 16:19:58 GMT +00:00 2022-11-11: cranky: [INFO] Running Container 'runc'. 2022-11-11 16:19:59 (8668): Guest Log: job: htmld=/shared/html/job 2022-11-11 16:19:59 (8668): Guest Log: job: unpack exitcode=0 2022-11-11 16:20:01 (8668): Guest Log: 16:20:00 GMT +00:00 2022-11-11: cranky: [INFO] ===> [runRivet] Fri Nov 11 16:19:59 UTC 2022 [boinc pp jets 7000 80,-,1060 - pythia8 8.244 tune-AU2loxx 100000 298] 2022-11-11 16:51:22 (8668): VM state change detected. (old = 'Running', new = 'Paused') 2022-11-11 16:54:34 (8668): VM state change detected. (old = 'Paused', new = 'Running') 2022-11-11 17:18:13 (8668): VM state change detected. (old = 'Running', new = 'Paused') 2022-11-11 17:21:39 (8668): VM state change detected. (old = 'Paused', new = 'Running') 2022-11-11 17:28:01 (8668): VM state change detected. (old = 'Running', new = 'Paused') 2022-11-11 17:40:49 (8668): VM state change detected. (old = 'Paused', new = 'Running') 2022-11-11 17:41:35 (8668): VM state change detected. (old = 'Running', new = 'Paused') 2022-11-11 19:51:36 (8668): VM state change detected. (old = 'Paused', new = 'Running') 2022-11-11 19:51:42 (8668): Guest Log: 01:03:07.535149 timesync vgsvcTimeSyncWorker: Radical host time change: 7 810 952 000 000ns (HostNow=1 668 196 301 322 000 000 ns HostLast=1 668 188 490 370 000 000 ns) 2022-11-11 19:51:52 (8668): Guest Log: 01:03:17.611429 timesync vgsvcTimeSyncWorker: Radical guest time change: 8 975 818 778 000ns (GuestNow=1 668 196 311 430 202 000 ns GuestLast=1 668 187 335 611 424 000 ns fSetTimeLastLoop=true ) 2022-11-11 19:53:21 (8668): VM state change detected. (old = 'Running', new = 'Paused') 2022-11-11 21:16:07 (8668): VM state change detected. (old = 'Paused', new = 'Running') 2022-11-11 21:16:08 (8668): Guest Log: 01:04:47.873580 timesync vgsvcTimeSyncWorker: Radical host time change: 4 976 639 000 000ns (HostNow=1 668 201 368 278 000 000 ns HostLast=1 668 196 391 639 000 000 ns) 2022-11-11 21:16:18 (8668): Guest Log: 01:04:57.875172 timesync vgsvcTimeSyncWorker: Radical guest time change: 4 976 587 278 000ns (GuestNow=1 668 201 378 279 607 000 ns GuestLast=1 668 196 401 692 329 000 ns fSetTimeLastLoop=true ) 2022-11-11 21:17:34 (8668): VM state change detected. (old = 'Running', new = 'Paused') 2022-11-11 22:42:44 (8668): VM state change detected. (old = 'Paused', new = 'Running') 2022-11-11 22:42:49 (8668): Guest Log: 01:06:17.978886 timesync vgsvcTimeSyncWorker: Radical host time change: 5 120 590 000 000ns (HostNow=1 668 206 568 950 000 000 ns HostLast=1 668 201 448 360 000 000 ns) 2022-11-11 22:42:59 (8668): Guest Log: 01:06:27.980156 timesync vgsvcTimeSyncWorker: Radical guest time change: 5 120 567 974 000ns (GuestNow=1 668 206 578 951 278 000 ns GuestLast=1 668 201 458 383 304 000 ns fSetTimeLastLoop=true ) 2022-11-11 22:57:04 (8668): VM state change detected. (old = 'Running', new = 'Paused') 2022-11-11 23:01:13 (8668): VM state change detected. (old = 'Paused', new = 'Running') 2022-11-11 23:16:17 (8668): VM state change detected. (old = 'Running', new = 'Paused') 2022-11-11 23:23:56 (8668): VM state change detected. (old = 'Paused', new = 'Running') 2022-11-11 23:27:09 (8668): VM state change detected. (old = 'Running', new = 'Paused') 2022-11-11 23:30:14 (8668): VM state change detected. (old = 'Paused', new = 'Running') 2022-11-11 23:31:05 (8668): Status Report: Job Duration: '864000.000000' 2022-11-11 23:31:05 (8668): Status Report: Elapsed Time: '173138.000000' 2022-11-11 23:31:05 (8668): Status Report: CPU Time: '80061.328125' 2022-11-11 23:53:00 (8668): VM state change detected. (old = 'Running', new = 'Paused') 2022-11-11 23:56:04 (8668): VM state change detected. (old = 'Paused', new = 'Running') 2022-11-12 00:37:52 (8668): VM state change detected. (old = 'Running', new = 'Paused') 2022-11-12 00:42:31 (8668): VM state change detected. (old = 'Paused', new = 'Running') 2022-11-12 01:18:55 (8668): Status Report: Job Duration: '864000.000000' 2022-11-12 01:18:55 (8668): Status Report: Elapsed Time: '179138.000000' 2022-11-12 01:18:55 (8668): Status Report: CPU Time: '82777.578125' 2022-11-12 01:36:34 (8668): VM state change detected. (old = 'Running', new = 'Paused') 2022-11-12 01:39:34 (8668): VM state change detected. (old = 'Paused', new = 'Running') 2022-11-12 01:40:48 (8668): VM state change detected. (old = 'Running', new = 'Paused') 2022-11-12 01:40:58 (8668): VM state change detected. (old = 'Paused', new = 'Running') 2022-11-12 01:57:53 (8668): VM state change detected. (old = 'Running', new = 'Paused') 2022-11-12 02:03:50 (8668): VM state change detected. (old = 'Paused', new = 'Running') 2022-11-12 02:04:31 (8668): VM state change detected. (old = 'Running', new = 'Paused') 2022-11-12 02:22:41 (8668): VM state change detected. (old = 'Paused', new = 'Running') 2022-11-12 02:31:37 (8668): VM state change detected. (old = 'Running', new = 'Paused') 2022-11-12 02:40:22 (8668): VM state change detected. (old = 'Paused', new = 'Running') 2022-11-12 03:35:06 (8668): Status Report: Job Duration: '864000.000000' 2022-11-12 03:35:06 (8668): Status Report: Elapsed Time: '185138.000000' 2022-11-12 03:35:06 (8668): Status Report: CPU Time: '85506.484375' 2022-11-12 04:37:40 (8668): VM state change detected. (old = 'Running', new = 'Paused') 2022-11-12 11:16:13 (8668): Error in resume VM for VM: -2135228414 Command: VBoxManage -q controlvm "boinc_a5acfd5119f860a2" 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 2022-11-12 11:16:14 (8668): VM state change detected. (old = 'Paused', new = 'Running') 2022-11-12 11:16:15 (8668): VM state change detected. (old = 'Running', new = 'Paused') 2022-11-12 11:42:23 (8668): VM state change detected. (old = 'Paused', new = 'Running') 2022-11-12 11:42:27 (8668): Guest Log: 06:02:48.331301 timesync vgsvcTimeSyncWorker: Radical host time change: 25 487 073 000 000ns (HostNow=1 668 253 347 189 000 000 ns HostLast=1 668 227 860 116 000 000 ns) 2022-11-12 11:42:37 (8668): Guest Log: 06:02:58.396466 timesync vgsvcTimeSyncWorker: Radical guest time change: 26 007 944 318 000ns (GuestNow=1 668 253 357 254 177 000 ns GuestLast=1 668 227 349 309 859 000 ns fSetTimeLastLoop=true ) 2022-11-12 11:48:21 (8668): VM state change detected. (old = 'Running', new = 'Paused') 2022-11-12 13:03:03 (8668): VM state change detected. (old = 'Paused', new = 'Running') 2022-11-12 13:03:10 (8668): Guest Log: 06:08:49.014683 timesync vgsvcTimeSyncWorker: Radical host time change: 4 491 601 000 000ns (HostNow=1 668 258 189 429 000 000 ns HostLast=1 668 253 697 828 000 000 ns) 2022-11-12 13:03:20 (8668): Guest Log: 06:08:59.052741 timesync vgsvcTimeSyncWorker: Radical guest time change: 4 491 594 762 000ns (GuestNow=1 668 258 199 467 083 000 ns GuestLast=1 668 253 707 872 321 000 ns fSetTimeLastLoop=true ) 2022-11-12 13:08:50 (8668): VM state change detected. (old = 'Running', new = 'Paused') 2022-11-12 14:24:37 (8668): Guest Log: 06:14:29.891532 timesync vgsvcTimeSyncWorker: Radical host time change: 4 556 818 000 000ns (HostNow=1 668 263 077 073 000 000 ns HostLast=1 668 258 520 255 000 000 ns) 2022-11-12 14:24:37 (8668): VM state change detected. (old = 'Paused', new = 'Running') 2022-11-12 14:24:47 (8668): Guest Log: 06:14:39.892496 timesync vgsvcTimeSyncWorker: Radical guest time change: 4 556 768 093 000ns (GuestNow=1 668 263 087 073 974 000 ns GuestLast=1 668 258 530 305 881 000 ns fSetTimeLastLoop=true ) 2022-11-12 14:28:25 (8668): VM state change detected. (old = 'Running', new = 'Paused') 2022-11-12 14:49:30 (8668): Stopping VM. 2022-11-12 14:49:30 (8668): Error in stop VM for VM: -108 Command: VBoxManage -q controlvm "boinc_a5acfd5119f860a2" savestate Output: 2022-11-12 14:49:30 (8668): VM did not stop when requested. 2022-11-12 14:49:30 (8668): VM was NOT successfully terminated. 2022-11-12 16:40:17 (14820): Detected: vboxwrapper 26197 2022-11-12 16:40:17 (14820): Detected: BOINC client v7.7 2022-11-12 16:40:18 (14820): Detected: VirtualBox VboxManage Interface (Version: 6.1.12) 2022-11-12 16:40:18 (14820): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds) 2022-11-12 16:40:19 (14820): Starting VM using VBoxManage interface. (boinc_a5acfd5119f860a2, slot#1) 2022-11-12 16:40:23 (14820): Successfully started VM. (PID = '10600') 2022-11-12 16:40:23 (14820): Reporting VM Process ID to BOINC. 2022-11-12 16:40:23 (14820): Guest Log: BIOS: VirtualBox 6.1.12 2022-11-12 16:40:23 (14820): Guest Log: CPUID EDX: 0x178bfbff 2022-11-12 16:40:23 (14820): Guest Log: BIOS: ata0-0: PCHS=16383/16/63 LCHS=1024/255/63 2022-11-12 16:40:23 (14820): VM state change detected. (old = 'PoweredOff', new = 'Running') 2022-11-12 16:40:23 (14820): Detected: Web Application Enabled (http://localhost:58961) 2022-11-12 16:40:23 (14820): Detected: Remote Desktop Enabled (localhost:58962) 2022-11-12 16:40:23 (14820): Status Report: Job Duration: '864000.000000' 2022-11-12 16:40:23 (14820): Status Report: Elapsed Time: '189819.000000' 2022-11-12 16:40:23 (14820): Status Report: CPU Time: '87635.515625' 2022-11-12 16:40:23 (14820): Preference change detected 2022-11-12 16:40:23 (14820): Setting CPU throttle for VM. (42%) 2022-11-12 16:40:23 (14820): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 77 seconds) or (Vbox_job.xml: 600 seconds)) 2022-11-12 16:40:25 (14820): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032 2022-11-12 16:40:25 (14820): Guest Log: BIOS: Booting from Hard Disk... 2022-11-12 16:40:27 (14820): Guest Log: BIOS: KBD: unsupported int 16h function 03 2022-11-12 16:40:27 (14820): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 2022-11-12 16:40:52 (14820): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds 2022-11-12 16:40:52 (14820): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000) 2022-11-12 16:40:58 (14820): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log 2022-11-12 16:40:58 (14820): Guest Log: 00:00:00.000068 main Log opened 2022-11-12T16:40:58.387786000Z 2022-11-12 16:40:58 (14820): Guest Log: 00:00:00.000140 main OS Product: Linux 2022-11-12 16:40:58 (14820): Guest Log: 00:00:00.000165 main OS Release: 4.14.76-13.cernvm.x86_64 2022-11-12 16:40:58 (14820): Guest Log: 00:00:00.000198 main OS Version: #1 SMP Tue Oct 16 18:26:15 CEST 2018 2022-11-12 16:40:58 (14820): Guest Log: 00:00:00.000235 main Executable: /usr/sbin/VBoxService 2022-11-12 16:40:58 (14820): Guest Log: 00:00:00.000236 main Process ID: 3206 2022-11-12 16:40:58 (14820): Guest Log: 00:00:00.000237 main Package type: LINUX_64BITS_GENERIC 2022-11-12 16:40:58 (14820): Guest Log: 00:00:00.001916 main 5.2.6 r120293 started. Verbose level = 0 2022-11-12 16:41:30 (14820): Guest Log: 16:41:30 GMT +00:00 2022-11-12: cranky: [INFO] Detected Theory App 2022-11-12 16:41:30 (14820): Guest Log: 16:41:30 GMT +00:00 2022-11-12: cranky: [INFO] Checking CVMFS. 2022-11-12 16:41:31 (14820): Guest Log: Probing /cvmfs/sft.cern.ch... OK 2022-11-12 16:41:32 (14820): 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 2022-11-12 16:41:32 (14820): Guest Log: 2.5.2.0 4079 0 25444 24275 3 1 2535707 4096000 0 65024 0 0 n/a 0 0 http://s1ral-cvmfs.openhtc.io/cvmfs/sft.cern.ch DIRECT 1 2022-11-12 16:41:34 (14820): Guest Log: Probing /cvmfs/grid.cern.ch... OK 2022-11-12 16:41:34 (14820): 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 2022-11-12 16:41:34 (14820): Guest Log: 2.5.2.0 4051 0 27648 19747 3 2 2535707 4096000 0 65024 0 2 0 5643 1630 http://s1ral-cvmfs.openhtc.io/cvmfs/grid.cern.ch DIRECT 1 2022-11-12 16:41:36 (14820): Guest Log: Probing /cvmfs/cernvm-prod.cern.ch... OK 2022-11-12 16:41:37 (14820): 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 2022-11-12 16:41:37 (14820): Guest Log: 2.5.2.0 4120 0 25140 268 3 1 2535707 4096000 0 65024 0 0 n/a 0 0 http://s1ral-cvmfs.openhtc.io/cvmfs/cernvm-prod.cern.ch DIRECT 1 2022-11-12 16:41:38 (14820): Guest Log: Probing /cvmfs/alice.cern.ch... OK 2022-11-12 16:41:39 (14820): 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 2022-11-12 16:41:39 (14820): Guest Log: 2.5.2.0 4099 0 33748 13805 3 1 2535707 4096000 0 65024 0 0 n/a 15035 2030 http://s1ral-cvmfs.openhtc.io/cvmfs/alice.cern.ch DIRECT 1 2022-11-12 16:41:39 (14820): Guest Log: 16:41:39 GMT +00:00 2022-11-12: cranky: [INFO] Checking runc. 2022-11-12 16:41:39 (14820): Guest Log: 16:41:39 GMT +00:00 2022-11-12: cranky: [INFO] Creating the filesystem. 2022-11-12 16:41:39 (14820): Guest Log: 16:41:39 GMT +00:00 2022-11-12: cranky: [INFO] Using /cvmfs/cernvm-prod.cern.ch/cvm3 2022-11-12 16:41:39 (14820): Guest Log: 16:41:39 GMT +00:00 2022-11-12: cranky: [INFO] Updating config.json. 2022-11-12 16:41:39 (14820): Guest Log: 16:41:39 GMT +00:00 2022-11-12: cranky: [INFO] Running Container 'runc'. 2022-11-12 16:41:40 (14820): Guest Log: job: htmld=/shared/html/job 2022-11-12 16:41:40 (14820): Guest Log: job: unpack exitcode=0 2022-11-12 16:41:42 (14820): Guest Log: 16:41:41 GMT +00:00 2022-11-12: cranky: [INFO] ===> [runRivet] Sat Nov 12 16:41:40 UTC 2022 [boinc pp jets 7000 80,-,1060 - pythia8 8.244 tune-AU2loxx 100000 298] 2022-11-12 16:56:36 (14820): VM state change detected. (old = 'Running', new = 'Paused') 2022-11-12 16:59:40 (14820): VM state change detected. (old = 'Paused', new = 'Running') 2022-11-12 17:06:58 (14820): VM state change detected. (old = 'Running', new = 'Paused') 2022-11-12 17:10:02 (14820): VM state change detected. (old = 'Paused', new = 'Running') 2022-11-12 17:11:32 (14820): VM state change detected. (old = 'Running', new = 'Paused') 2022-11-12 17:21:56 (14820): VM state change detected. (old = 'Paused', new = 'Running') 2022-11-12 17:22:31 (14820): VM state change detected. (old = 'Running', new = 'Paused') 2022-11-12 17:26:38 (14820): VM state change detected. (old = 'Paused', new = 'Running') 2022-11-12 17:28:52 (14820): VM state change detected. (old = 'Running', new = 'Paused') 2022-11-12 19:28:55 (14820): VM state change detected. (old = 'Paused', new = 'Running') 2022-11-12 19:29:03 (14820): Guest Log: 00:27:23.053118 timesync vgsvcTimeSyncWorker: Radical host time change: 7 213 307 000 000ns (HostNow=1 668 281 342 982 000 000 ns HostLast=1 668 274 129 675 000 000 ns) 2022-11-12 19:29:13 (14820): Guest Log: 00:27:33.110407 timesync vgsvcTimeSyncWorker: Radical guest time change: 7 213 343 014 000ns (GuestNow=1 668 281 353 063 829 000 ns GuestLast=1 668 274 139 720 815 000 ns fSetTimeLastLoop=true ) 2022-11-12 19:29:40 (14820): VM state change detected. (old = 'Running', new = 'Paused') 2022-11-12 19:33:09 (14820): VM state change detected. (old = 'Paused', new = 'Running') 2022-11-12 20:03:45 (14820): VM state change detected. (old = 'Running', new = 'Paused') 2022-11-12 21:42:22 (14820): VM state change detected. (old = 'Paused', new = 'Running') 2022-11-12 21:42:25 (14820): Guest Log: 00:58:39.946264 timesync vgsvcTimeSyncWorker: Radical host time change: 5 927 082 000 000ns (HostNow=1 668 289 345 188 000 000 ns HostLast=1 668 283 418 106 000 000 ns) 2022-11-12 21:42:35 (14820): Guest Log: 00:58:49.979727 timesync vgsvcTimeSyncWorker: Radical guest time change: 6 135 321 906 000ns (GuestNow=1 668 289 355 221 525 000 ns GuestLast=1 668 283 219 899 619 000 ns fSetTimeLastLoop=true ) 2022-11-12 21:44:36 (14820): VM state change detected. (old = 'Running', new = 'Paused') 2022-11-12 22:26:53 (14820): VM state change detected. (old = 'Paused', new = 'Running') 2022-11-12 22:27:02 (14820): Guest Log: 01:01:00.277000 timesync vgsvcTimeSyncWorker: Radical host time change: 2 547 011 000 000ns (HostNow=1 668 292 022 481 000 000 ns HostLast=1 668 289 475 470 000 000 ns) 2022-11-12 22:27:12 (14820): Guest Log: 01:01:10.281948 timesync vgsvcTimeSyncWorker: Radical guest time change: 2 546 967 174 000ns (GuestNow=1 668 292 032 485 957 000 ns GuestLast=1 668 289 485 518 783 000 ns fSetTimeLastLoop=true ) 2022-11-12 22:38:23 (14820): VM state change detected. (old = 'Running', new = 'Paused') 2022-11-12 22:41:28 (14820): VM state change detected. (old = 'Paused', new = 'Running') 2022-11-12 22:47:08 (14820): VM state change detected. (old = 'Running', new = 'Paused') 2022-11-12 23:19:10 (14820): VM state change detected. (old = 'Paused', new = 'Running') 2022-11-12 23:19:12 (14820): Guest Log: 01:18:02.741436 timesync vgsvcTimeSyncWorker: Radical host time change: 1 931 624 000 000ns (HostNow=1 668 295 151 578 000 000 ns HostLast=1 668 293 219 954 000 000 ns) 2022-11-12 23:19:22 (14820): Guest Log: 01:18:12.771017 timesync vgsvcTimeSyncWorker: Radical guest time change: 2 116 662 238 000ns (GuestNow=1 668 295 161 607 611 000 ns GuestLast=1 668 293 044 945 373 000 ns fSetTimeLastLoop=true ) 2022-11-12 23:20:22 (14820): VM state change detected. (old = 'Running', new = 'Paused') 2022-11-12 23:45:44 (14820): VM state change detected. (old = 'Paused', new = 'Running') 2022-11-12 23:58:58 (14820): VM state change detected. (old = 'Running', new = 'Paused') 2022-11-13 00:02:02 (14820): VM state change detected. (old = 'Paused', new = 'Running') 2022-11-13 00:09:18 (14820): Status Report: Job Duration: '864000.000000' 2022-11-13 00:09:18 (14820): Status Report: Elapsed Time: '195819.000000' 2022-11-13 00:09:18 (14820): Status Report: CPU Time: '90352.640625' 2022-11-13 00:19:05 (14820): VM state change detected. (old = 'Running', new = 'Paused') 2022-11-13 00:22:18 (14820): VM state change detected. (old = 'Paused', new = 'Running') 2022-11-13 00:32:53 (14820): VM state change detected. (old = 'Running', new = 'Paused') 2022-11-13 00:35:59 (14820): VM state change detected. (old = 'Paused', new = 'Running') 2022-11-13 00:41:55 (14820): VM state change detected. (old = 'Running', new = 'Paused') 2022-11-13 00:44:59 (14820): VM state change detected. (old = 'Paused', new = 'Running') 2022-11-13 00:45:50 (14820): VM state change detected. (old = 'Running', new = 'Paused') 2022-11-13 00:49:16 (14820): VM state change detected. (old = 'Paused', new = 'Running') 2022-11-13 00:50:17 (14820): VM state change detected. (old = 'Running', new = 'Paused') 2022-11-13 01:14:22 (14820): VM state change detected. (old = 'Paused', new = 'Running') 2022-11-13 01:14:38 (14820): Guest Log: 02:08:10.546630 timesync vgsvcTimeSyncWorker: Radical guest time change: 2 406 392 820 000ns (GuestNow=1 668 302 078 068 457 000 ns GuestLast=1 668 299 671 675 637 000 ns fSetTimeLastLoop=true ) 2022-11-13 01:33:16 (14820): VM state change detected. (old = 'Running', new = 'Paused') 2022-11-13 01:37:16 (14820): VM state change detected. (old = 'Paused', new = 'Running') 2022-11-13 01:38:28 (14820): VM state change detected. (old = 'Running', new = 'Paused') 2022-11-13 01:46:35 (14820): VM state change detected. (old = 'Paused', new = 'Running') 2022-11-13 01:52:55 (14820): VM state change detected. (old = 'Running', new = 'Paused') 2022-11-13 02:00:53 (14820): VM state change detected. (old = 'Paused', new = 'Running') 2022-11-13 02:05:13 (14820): VM state change detected. (old = 'Running', new = 'Paused') 2022-11-13 02:10:13 (14820): VM state change detected. (old = 'Paused', new = 'Running') 2022-11-13 02:12:37 (14820): VM state change detected. (old = 'Running', new = 'Paused') 2022-11-13 02:20:13 (14820): VM state change detected. (old = 'Paused', new = 'Running') 2022-11-13 02:20:38 (14820): VM state change detected. (old = 'Running', new = 'Paused') 2022-11-13 02:25:32 (14820): VM state change detected. (old = 'Paused', new = 'Running') 2022-11-13 02:25:54 (14820): VM state change detected. (old = 'Running', new = 'Paused') 2022-11-13 02:32:52 (14820): VM state change detected. (old = 'Paused', new = 'Running') 2022-11-13 02:38:07 (14820): VM state change detected. (old = 'Running', new = 'Paused') 2022-11-13 02:45:10 (14820): VM state change detected. (old = 'Paused', new = 'Running') 2022-11-13 02:46:09 (14820): VM state change detected. (old = 'Running', new = 'Paused') 2022-11-13 03:31:37 (14820): VM state change detected. (old = 'Paused', new = 'Running') 2022-11-13 03:31:45 (14820): Guest Log: 02:48:15.098283 timesync vgsvcTimeSyncWorker: Radical host time change: 2 737 951 000 000ns (HostNow=1 668 310 305 122 000 000 ns HostLast=1 668 307 567 171 000 000 ns) 2022-11-13 03:31:55 (14820): Guest Log: 02:48:25.171413 timesync vgsvcTimeSyncWorker: Radical guest time change: 3 161 213 180 000ns (GuestNow=1 668 310 315 195 126 000 ns GuestLast=1 668 307 153 981 946 000 ns fSetTimeLastLoop=true ) 2022-11-13 03:31:57 (14820): VM state change detected. (old = 'Running', new = 'Paused') 2022-11-13 03:37:43 (14820): VM state change detected. (old = 'Paused', new = 'Running') 2022-11-13 03:43:15 (14820): VM state change detected. (old = 'Running', new = 'Paused') 2022-11-13 03:46:44 (14820): VM state change detected. (old = 'Paused', new = 'Running') 2022-11-13 04:12:52 (14820): Status Report: Job Duration: '864000.000000' 2022-11-13 04:12:52 (14820): Status Report: Elapsed Time: '201819.349699' 2022-11-13 04:12:52 (14820): Status Report: CPU Time: '93083.437500' 2022-11-13 05:43:45 (14820): VM state change detected. (old = 'Running', new = 'Paused') 2022-11-13 11:01:41 (14820): Error in resume VM for VM: -2135228414 Command: VBoxManage -q controlvm "boinc_a5acfd5119f860a2" 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 2022-11-13 11:01:42 (14820): VM state change detected. (old = 'Paused', new = 'Running') 2022-11-13 11:01:43 (14820): VM state change detected. (old = 'Running', new = 'Paused') 2022-11-13 11:06:28 (14820): VM state change detected. (old = 'Paused', new = 'Running') 2022-11-13 11:06:31 (14820): Guest Log: 04:51:11.106291 timesync vgsvcTimeSyncWorker: Radical host time change: 19 369 193 000 000ns (HostNow=1 668 337 591 953 000 000 ns HostLast=1 668 318 222 760 000 000 ns) 2022-11-13 11:06:41 (14820): Guest Log: 04:51:21.111426 timesync vgsvcTimeSyncWorker: Radical guest time change: 19 920 828 131 000ns (GuestNow=1 668 337 601 958 143 000 ns GuestLast=1 668 317 681 130 012 000 ns fSetTimeLastLoop=true ) 2022-11-13 11:07:14 (14820): VM state change detected. (old = 'Running', new = 'Paused') 2022-11-13 11:23:36 (14820): VM state change detected. (old = 'Paused', new = 'Running') 2022-11-13 11:25:10 (14820): VM state change detected. (old = 'Running', new = 'Paused') 2022-11-13 11:35:58 (14820): VM state change detected. (old = 'Paused', new = 'Running') 2022-11-13 11:36:22 (14820): VM state change detected. (old = 'Running', new = 'Paused') 2022-11-13 11:43:27 (14820): VM state change detected. (old = 'Paused', new = 'Running') 2022-11-13 11:48:32 (14820): VM state change detected. (old = 'Running', new = 'Paused') 2022-11-13 11:54:43 (14820): VM state change detected. (old = 'Paused', new = 'Running') 2022-11-13 11:56:09 (14820): Status Report: Job Duration: '864000.000000' 2022-11-13 11:56:09 (14820): Status Report: Elapsed Time: '207819.349699' 2022-11-13 11:56:09 (14820): Status Report: CPU Time: '95814.218750' 2022-11-13 12:01:30 (14820): VM state change detected. (old = 'Running', new = 'Paused') 2022-11-13 13:15:02 (14820): VM state change detected. (old = 'Paused', new = 'Running') 2022-11-13 13:15:11 (14820): Guest Log: 05:05:52.402310 timesync vgsvcTimeSyncWorker: Radical host time change: 4 421 781 000 000ns (HostNow=1 668 345 310 709 000 000 ns HostLast=1 668 340 888 928 000 000 ns) 2022-11-13 13:15:21 (14820): Guest Log: 05:06:02.403378 timesync vgsvcTimeSyncWorker: Radical guest time change: 5 217 977 485 000ns (GuestNow=1 668 345 320 710 069 000 ns GuestLast=1 668 340 102 732 584 000 ns fSetTimeLastLoop=true ) 2022-11-13 13:21:41 (14820): VM state change detected. (old = 'Running', new = 'Paused') 2022-11-13 13:45:26 (14820): VM state change detected. (old = 'Paused', new = 'Running') 2022-11-13 13:47:41 (14820): VM state change detected. (old = 'Running', new = 'Paused') 2022-11-13 14:45:51 (14820): VM state change detected. (old = 'Paused', new = 'Running') 2022-11-13 14:45:56 (14820): Guest Log: 05:14:43.565456 timesync vgsvcTimeSyncWorker: Radical host time change: 3 499 941 000 000ns (HostNow=1 668 350 756 025 000 000 ns HostLast=1 668 347 256 084 000 000 ns) 2022-11-13 14:46:06 (14820): Guest Log: 05:14:53.565706 timesync vgsvcTimeSyncWorker: Radical guest time change: 3 499 897 337 000ns (GuestNow=1 668 350 766 025 259 000 ns GuestLast=1 668 347 266 127 922 000 ns fSetTimeLastLoop=true ) 2022-11-13 14:52:12 (14820): VM state change detected. (old = 'Running', new = 'Paused') 2022-11-13 15:34:48 (14820): VM state change detected. (old = 'Paused', new = 'Running') 2022-11-13 15:34:53 (14820): Guest Log: 05:21:04.283356 timesync vgsvcTimeSyncWorker: Radical host time change: 2 565 607 000 000ns (HostNow=1 668 353 692 298 000 000 ns HostLast=1 668 351 126 691 000 000 ns) 2022-11-13 15:35:03 (14820): Guest Log: 05:21:14.286138 timesync vgsvcTimeSyncWorker: Radical guest time change: 2 565 557 894 000ns (GuestNow=1 668 353 702 300 788 000 ns GuestLast=1 668 351 136 742 894 000 ns fSetTimeLastLoop=true ) 2022-11-13 15:36:01 (14820): VM state change detected. (old = 'Running', new = 'Paused') 2022-11-13 16:11:09 (14820): VM state change detected. (old = 'Paused', new = 'Running') 2022-11-13 16:11:11 (14820): Guest Log: 05:22:14.447510 timesync vgsvcTimeSyncWorker: Radical host time change: 2 118 379 000 000ns (HostNow=1 668 355 870 832 000 000 ns HostLast=1 668 353 752 453 000 000 ns) 2022-11-13 16:11:21 (14820): Guest Log: 05:22:24.455304 timesync vgsvcTimeSyncWorker: Radical guest time change: 2 118 377 676 000ns (GuestNow=1 668 355 880 839 812 000 ns GuestLast=1 668 353 762 462 136 000 ns fSetTimeLastLoop=true ) 2022-11-13 16:22:04 (14820): VM state change detected. (old = 'Running', new = 'Paused') 2022-11-13 16:26:16 (14820): VM state change detected. (old = 'Paused', new = 'Running') 2022-11-13 16:31:18 (14820): VM state change detected. (old = 'Running', new = 'Paused') 2022-11-13 16:35:03 (14820): VM state change detected. (old = 'Paused', new = 'Running') 2022-11-13 16:36:00 (14820): VM state change detected. (old = 'Running', new = 'Paused') 2022-11-13 16:39:58 (14820): VM state change detected. (old = 'Paused', new = 'Running') 2022-11-13 16:40:28 (14820): VM state change detected. (old = 'Running', new = 'Paused') 2022-11-13 16:56:16 (14820): VM state change detected. (old = 'Paused', new = 'Running') 2022-11-13 17:00:40 (14820): VM state change detected. (old = 'Running', new = 'Paused') 2022-11-13 17:04:44 (14820): VM state change detected. (old = 'Paused', new = 'Running') 2022-11-13 17:05:12 (14820): VM state change detected. (old = 'Running', new = 'Paused') 2022-11-13 17:08:18 (14820): VM state change detected. (old = 'Paused', new = 'Running') 2022-11-13 17:08:50 (14820): VM state change detected. (old = 'Running', new = 'Paused') 2022-11-13 17:20:18 (14820): VM state change detected. (old = 'Paused', new = 'Running') 2022-11-13 17:21:18 (14820): VM state change detected. (old = 'Running', new = 'Paused') 2022-11-13 17:38:44 (14820): VM state change detected. (old = 'Paused', new = 'Running') 2022-11-13 17:39:00 (14820): Guest Log: 05:46:17.947701 timesync vgsvcTimeSyncWorker: Radical guest time change: 2 174 227 637 000ns (GuestNow=1 668 361 140 381 260 000 ns GuestLast=1 668 358 966 153 623 000 ns fSetTimeLastLoop=true ) 2022-11-13 17:43:48 (14820): VM state change detected. (old = 'Running', new = 'Paused') 2022-11-13 17:54:40 (14820): VM state change detected. (old = 'Paused', new = 'Running') 2022-11-13 17:55:40 (14820): VM state change detected. (old = 'Running', new = 'Paused') 2022-11-13 17:58:50 (14820): VM state change detected. (old = 'Paused', new = 'Running') 2022-11-13 18:00:31 (14820): VM state change detected. (old = 'Running', new = 'Paused') 2022-11-13 18:25:05 (14820): VM state change detected. (old = 'Paused', new = 'Running') 2022-11-13 18:25:17 (14820): Guest Log: 05:53:58.900354 timesync vgsvcTimeSyncWorker: Radical guest time change: 2 326 261 507 000ns (GuestNow=1 668 363 917 578 198 000 ns GuestLast=1 668 361 591 316 691 000 ns fSetTimeLastLoop=true ) 2022-11-13 18:32:28 (14820): VM state change detected. (old = 'Running', new = 'Paused') 2022-11-13 18:35:33 (14820): VM state change detected. (old = 'Paused', new = 'Running') 2022-11-13 18:45:45 (14820): VM state change detected. (old = 'Running', new = 'Paused') 2022-11-13 20:27:02 (14820): VM state change detected. (old = 'Paused', new = 'Running') 2022-11-13 20:27:11 (14820): Guest Log: 06:11:30.507323 timesync vgsvcTimeSyncWorker: Radical host time change: 6 086 399 000 000ns (HostNow=1 668 371 230 434 000 000 ns HostLast=1 668 365 144 035 000 000 ns) 2022-11-13 20:27:21 (14820): Guest Log: 06:11:40.508409 timesync vgsvcTimeSyncWorker: Radical guest time change: 6 271 249 944 000ns (GuestNow=1 668 371 240 435 106 000 ns GuestLast=1 668 364 969 185 162 000 ns fSetTimeLastLoop=true ) 2022-11-13 20:35:05 (14820): VM state change detected. (old = 'Running', new = 'Paused') 2022-11-13 20:38:11 (14820): VM state change detected. (old = 'Paused', new = 'Running') 2022-11-13 20:49:24 (14820): VM state change detected. (old = 'Running', new = 'Paused') 2022-11-13 20:52:50 (14820): VM state change detected. (old = 'Paused', new = 'Running') 2022-11-13 20:57:57 (14820): VM state change detected. (old = 'Running', new = 'Paused') 2022-11-13 21:01:03 (14820): VM state change detected. (old = 'Paused', new = 'Running') 2022-11-13 21:06:07 (14820): Status Report: Job Duration: '864000.000000' 2022-11-13 21:06:07 (14820): Status Report: Elapsed Time: '213819.349699' 2022-11-13 21:06:07 (14820): Status Report: CPU Time: '98545.921875' 2022-11-13 21:18:00 (14820): VM state change detected. (old = 'Running', new = 'Paused') 2022-11-13 21:23:49 (14820): VM state change detected. (old = 'Paused', new = 'Running') 2022-11-13 21:26:56 (14820): VM state change detected. (old = 'Running', new = 'Paused') 2022-11-13 21:27:20 (14820): Stopping VM. 2022-11-13 21:27:20 (14820): Error in stop VM for VM: -108 Command: VBoxManage -q controlvm "boinc_a5acfd5119f860a2" savestate Output: 2022-11-13 21:27:20 (14820): VM did not stop when requested. 2022-11-13 21:27:20 (14820): VM was NOT successfully terminated. 2022-11-13 21:32:08 (7848): Detected: vboxwrapper 26197 2022-11-13 21:32:08 (7848): Detected: BOINC client v7.7 2022-11-13 21:32:10 (7848): Detected: VirtualBox VboxManage Interface (Version: 6.1.12) 2022-11-13 21:32:10 (7848): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds) 2022-11-13 21:32:10 (7848): Starting VM using VBoxManage interface. (boinc_a5acfd5119f860a2, slot#1) 2022-11-13 21:32:15 (7848): Successfully started VM. (PID = '6424') 2022-11-13 21:32:15 (7848): Reporting VM Process ID to BOINC. 2022-11-13 21:32:15 (7848): Guest Log: BIOS: VirtualBox 6.1.12 2022-11-13 21:32:15 (7848): Guest Log: CPUID EDX: 0x178bfbff 2022-11-13 21:32:15 (7848): Guest Log: BIOS: ata0-0: PCHS=16383/16/63 LCHS=1024/255/63 2022-11-13 21:32:15 (7848): VM state change detected. (old = 'PoweredOff', new = 'Running') 2022-11-13 21:32:15 (7848): Detected: Web Application Enabled (http://localhost:58961) 2022-11-13 21:32:15 (7848): Detected: Remote Desktop Enabled (localhost:58962) 2022-11-13 21:32:15 (7848): Status Report: Job Duration: '864000.000000' 2022-11-13 21:32:15 (7848): Status Report: Elapsed Time: '214715.349699' 2022-11-13 21:32:15 (7848): Status Report: CPU Time: '98955.343750' 2022-11-13 21:32:15 (7848): Preference change detected 2022-11-13 21:32:15 (7848): Setting CPU throttle for VM. (42%) 2022-11-13 21:32:15 (7848): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 77 seconds) or (Vbox_job.xml: 600 seconds)) 2022-11-13 21:32:17 (7848): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032 2022-11-13 21:32:17 (7848): Guest Log: BIOS: Booting from Hard Disk... 2022-11-13 21:32:19 (7848): Guest Log: BIOS: KBD: unsupported int 16h function 03 2022-11-13 21:32:19 (7848): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 2022-11-13 21:32:44 (7848): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds 2022-11-13 21:32:45 (7848): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000) 2022-11-13 21:32:49 (7848): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log 2022-11-13 21:32:49 (7848): Guest Log: 00:00:00.000067 main Log opened 2022-11-13T21:32:49.770896000Z 2022-11-13 21:32:49 (7848): Guest Log: 00:00:00.000146 main OS Product: Linux 2022-11-13 21:32:49 (7848): Guest Log: 00:00:00.000181 main OS Release: 4.14.76-13.cernvm.x86_64 2022-11-13 21:32:49 (7848): Guest Log: 00:00:00.000222 main OS Version: #1 SMP Tue Oct 16 18:26:15 CEST 2018 2022-11-13 21:32:49 (7848): Guest Log: 00:00:00.000247 main Executable: /usr/sbin/VBoxService 2022-11-13 21:32:49 (7848): Guest Log: 00:00:00.000248 main Process ID: 3163 2022-11-13 21:32:49 (7848): Guest Log: 00:00:00.000248 main Package type: LINUX_64BITS_GENERIC 2022-11-13 21:32:49 (7848): Guest Log: 00:00:00.001294 main 5.2.6 r120293 started. Verbose level = 0 2022-11-13 21:33:35 (7848): Guest Log: 21:33:35 GMT +00:00 2022-11-13: cranky: [INFO] Detected Theory App 2022-11-13 21:33:35 (7848): Guest Log: 21:33:35 GMT +00:00 2022-11-13: cranky: [INFO] Checking CVMFS. 2022-11-13 21:33:38 (7848): Guest Log: Probing /cvmfs/sft.cern.ch... OK 2022-11-13 21:33:40 (7848): 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 2022-11-13 21:33:40 (7848): Guest Log: 2.5.2.0 4060 0 26356 24282 3 1 2589651 4096000 0 65024 0 0 n/a 0 0 http://s1bnl-cvmfs.openhtc.io/cvmfs/sft.cern.ch DIRECT 1 2022-11-13 21:33:43 (7848): Guest Log: Probing /cvmfs/grid.cern.ch... OK 2022-11-13 21:33:44 (7848): 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 2022-11-13 21:33:44 (7848): Guest Log: 2.5.2.0 4127 0 27508 19757 3 2 2589651 4096000 0 65024 0 2 0 5646 1106 http://s1ral-cvmfs.openhtc.io/cvmfs/grid.cern.ch DIRECT 1 2022-11-13 21:33:47 (7848): Guest Log: Probing /cvmfs/cernvm-prod.cern.ch... OK 2022-11-13 21:33:49 (7848): 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 2022-11-13 21:33:49 (7848): Guest Log: 2.5.2.0 4082 0 27216 268 3 1 2589651 4096000 0 65024 0 0 n/a 0 0 http://s1fnal-cvmfs.openhtc.io/cvmfs/cernvm-prod.cern.ch DIRECT 1 2022-11-13 21:33:52 (7848): Guest Log: Probing /cvmfs/alice.cern.ch... OK 2022-11-13 21:33:53 (7848): 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 2022-11-13 21:33:53 (7848): Guest Log: 2.5.2.0 4104 0 33752 13815 3 1 2589651 4096000 0 65024 0 0 n/a 15036 1648 http://s1ral-cvmfs.openhtc.io/cvmfs/alice.cern.ch DIRECT 1 2022-11-13 21:33:53 (7848): Guest Log: 21:33:53 GMT +00:00 2022-11-13: cranky: [INFO] Checking runc. 2022-11-13 21:33:53 (7848): Guest Log: 21:33:53 GMT +00:00 2022-11-13: cranky: [INFO] Creating the filesystem. 2022-11-13 21:33:53 (7848): Guest Log: 21:33:53 GMT +00:00 2022-11-13: cranky: [INFO] Using /cvmfs/cernvm-prod.cern.ch/cvm3 2022-11-13 21:33:54 (7848): Guest Log: 21:33:54 GMT +00:00 2022-11-13: cranky: [INFO] Updating config.json. 2022-11-13 21:33:54 (7848): Guest Log: 21:33:54 GMT +00:00 2022-11-13: cranky: [INFO] Running Container 'runc'. 2022-11-13 21:33:54 (7848): Guest Log: job: htmld=/shared/html/job 2022-11-13 21:33:54 (7848): Guest Log: job: unpack exitcode=0 2022-11-13 21:33:56 (7848): Guest Log: 21:33:56 GMT +00:00 2022-11-13: cranky: [INFO] ===> [runRivet] Sun Nov 13 21:33:54 UTC 2022 [boinc pp jets 7000 80,-,1060 - pythia8 8.244 tune-AU2loxx 100000 298] 2022-11-13 21:37:50 (7848): VM state change detected. (old = 'Running', new = 'Paused') 2022-11-13 21:44:37 (7848): VM state change detected. (old = 'Paused', new = 'Running') 2022-11-13 21:58:53 (7848): VM state change detected. (old = 'Running', new = 'Paused') 2022-11-13 22:03:25 (7848): VM state change detected. (old = 'Paused', new = 'Running') 2022-11-13 22:07:32 (7848): VM state change detected. (old = 'Running', new = 'Paused') 2022-11-13 22:11:33 (7848): VM state change detected. (old = 'Paused', new = 'Running') 2022-11-13 22:11:58 (7848): VM state change detected. (old = 'Running', new = 'Paused') 2022-11-13 22:36:11 (7848): VM state change detected. (old = 'Paused', new = 'Running') 2022-11-13 22:36:25 (7848): Guest Log: 00:24:03.040163 timesync vgsvcTimeSyncWorker: Radical guest time change: 2 381 937 934 000ns (GuestNow=1 668 378 984 508 612 000 ns GuestLast=1 668 376 602 570 678 000 ns fSetTimeLastLoop=true ) 2022-11-13 22:40:13 (7848): VM state change detected. (old = 'Running', new = 'Paused') 2022-11-13 22:40:24 (7848): Stopping VM. 2022-11-13 22:40:24 (7848): Error in stop VM for VM: -108 Command: VBoxManage -q controlvm "boinc_a5acfd5119f860a2" savestate Output: 2022-11-13 22:40:24 (7848): VM did not stop when requested. 2022-11-13 22:40:24 (7848): VM was NOT successfully terminated. 2022-11-13 22:46:03 (1800): Detected: vboxwrapper 26197 2022-11-13 22:46:03 (1800): Detected: BOINC client v7.7 2022-11-13 22:46:04 (1800): Detected: VirtualBox VboxManage Interface (Version: 6.1.12) 2022-11-13 22:46:04 (1800): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds) 2022-11-13 22:46:04 (1800): Starting VM using VBoxManage interface. (boinc_a5acfd5119f860a2, slot#1) 2022-11-13 22:46:10 (1800): Successfully started VM. (PID = '1600') 2022-11-13 22:46:10 (1800): Reporting VM Process ID to BOINC. 2022-11-13 22:46:10 (1800): Guest Log: BIOS: VirtualBox 6.1.12 2022-11-13 22:46:10 (1800): Guest Log: CPUID EDX: 0x178bfbff 2022-11-13 22:46:10 (1800): Guest Log: BIOS: ata0-0: PCHS=16383/16/63 LCHS=1024/255/63 2022-11-13 22:46:10 (1800): VM state change detected. (old = 'PoweredOff', new = 'Running') 2022-11-13 22:46:10 (1800): Detected: Web Application Enabled (http://localhost:58961) 2022-11-13 22:46:10 (1800): Detected: Remote Desktop Enabled (localhost:58962) 2022-11-13 22:46:10 (1800): Status Report: Job Duration: '864000.000000' 2022-11-13 22:46:10 (1800): Status Report: Elapsed Time: '216413.349699' 2022-11-13 22:46:10 (1800): Status Report: CPU Time: '99722.093750' 2022-11-13 22:46:10 (1800): Preference change detected 2022-11-13 22:46:10 (1800): Setting CPU throttle for VM. (42%) 2022-11-13 22:46:10 (1800): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 77 seconds) or (Vbox_job.xml: 600 seconds)) 2022-11-13 22:46:12 (1800): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032 2022-11-13 22:46:12 (1800): Guest Log: BIOS: Booting from Hard Disk... 2022-11-13 22:46:14 (1800): Guest Log: BIOS: KBD: unsupported int 16h function 03 2022-11-13 22:46:14 (1800): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 2022-11-13 22:46:38 (1800): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds 2022-11-13 22:46:38 (1800): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000) 2022-11-13 22:46:43 (1800): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log 2022-11-13 22:46:43 (1800): Guest Log: 00:00:00.000071 main Log opened 2022-11-13T22:46:43.772076000Z 2022-11-13 22:46:43 (1800): Guest Log: 00:00:00.000144 main OS Product: Linux 2022-11-13 22:46:43 (1800): Guest Log: 00:00:00.000171 main OS Release: 4.14.76-13.cernvm.x86_64 2022-11-13 22:46:43 (1800): Guest Log: 00:00:00.000193 main OS Version: #1 SMP Tue Oct 16 18:26:15 CEST 2018 2022-11-13 22:46:43 (1800): Guest Log: 00:00:00.000213 main Executable: /usr/sbin/VBoxService 2022-11-13 22:46:43 (1800): Guest Log: 00:00:00.000213 main Process ID: 3206 2022-11-13 22:46:43 (1800): Guest Log: 00:00:00.000214 main Package type: LINUX_64BITS_GENERIC 2022-11-13 22:46:43 (1800): Guest Log: 00:00:00.002263 main 5.2.6 r120293 started. Verbose level = 0 2022-11-13 22:47:34 (1800): Guest Log: 22:47:34 GMT +00:00 2022-11-13: cranky: [INFO] Detected Theory App 2022-11-13 22:47:34 (1800): Guest Log: 22:47:34 GMT +00:00 2022-11-13: cranky: [INFO] Checking CVMFS. 2022-11-13 22:47:39 (1800): Guest Log: Probing /cvmfs/sft.cern.ch... OK 2022-11-13 22:47:41 (1800): 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 2022-11-13 22:47:41 (1800): Guest Log: 2.5.2.0 4063 0 25136 24282 3 1 2589669 4096000 0 65024 0 0 n/a 0 0 http://s1ral-cvmfs.openhtc.io/cvmfs/sft.cern.ch DIRECT 1 2022-11-13 22:47:45 (1800): Guest Log: Probing /cvmfs/grid.cern.ch... OK 2022-11-13 22:47:47 (1800): 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 2022-11-13 22:47:47 (1800): Guest Log: 2.5.2.0 4133 0 25460 19757 3 2 2589669 4096000 0 65024 0 2 100 0 0 http://s1unl-cvmfs.openhtc.io/cvmfs/grid.cern.ch DIRECT 1 2022-11-13 22:47:52 (1800): Guest Log: Probing /cvmfs/cernvm-prod.cern.ch... OK 2022-11-13 22:47:53 (1800): 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 2022-11-13 22:47:53 (1800): Guest Log: 2.5.2.0 4086 0 27200 268 3 1 2589669 4096000 0 65024 0 0 n/a 0 0 http://s1cern-cvmfs.openhtc.io/cvmfs/cernvm-prod.cern.ch DIRECT 1 2022-11-13 22:47:55 (1800): Guest Log: Probing /cvmfs/alice.cern.ch... OK 2022-11-13 22:47:56 (1800): 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 2022-11-13 22:47:56 (1800): Guest Log: 2.5.2.0 4109 0 35004 13815 3 1 2589669 4096000 0 65024 0 0 n/a 0 0 http://s1cern-cvmfs.openhtc.io/cvmfs/alice.cern.ch DIRECT 1 2022-11-13 22:47:56 (1800): Guest Log: 22:47:56 GMT +00:00 2022-11-13: cranky: [INFO] Checking runc. 2022-11-13 22:47:56 (1800): Guest Log: 22:47:56 GMT +00:00 2022-11-13: cranky: [INFO] Creating the filesystem. 2022-11-13 22:47:56 (1800): Guest Log: 22:47:56 GMT +00:00 2022-11-13: cranky: [INFO] Using /cvmfs/cernvm-prod.cern.ch/cvm3 2022-11-13 22:47:56 (1800): Guest Log: 22:47:56 GMT +00:00 2022-11-13: cranky: [INFO] Updating config.json. 2022-11-13 22:47:56 (1800): Guest Log: 22:47:56 GMT +00:00 2022-11-13: cranky: [INFO] Running Container 'runc'. 2022-11-13 22:47:57 (1800): Guest Log: job: htmld=/shared/html/job 2022-11-13 22:47:57 (1800): Guest Log: job: unpack exitcode=0 2022-11-13 22:47:58 (1800): Guest Log: 22:47:59 GMT +00:00 2022-11-13: cranky: [INFO] ===> [runRivet] Sun Nov 13 22:47:57 UTC 2022 [boinc pp jets 7000 80,-,1060 - pythia8 8.244 tune-AU2loxx 100000 298] 2022-11-13 22:51:39 (1800): VM state change detected. (old = 'Running', new = 'Paused') 2022-11-13 23:15:50 (1800): VM state change detected. (old = 'Paused', new = 'Running') 2022-11-13 23:17:41 (1800): VM state change detected. (old = 'Running', new = 'Paused') 2022-11-13 23:25:41 (1800): VM state change detected. (old = 'Paused', new = 'Running') 2022-11-13 23:28:05 (1800): VM state change detected. (old = 'Running', new = 'Paused') 2022-11-13 23:33:41 (1800): VM state change detected. (old = 'Paused', new = 'Running') 2022-11-13 23:39:31 (1800): VM state change detected. (old = 'Running', new = 'Paused') 2022-11-13 23:47:43 (1800): VM state change detected. (old = 'Paused', new = 'Running') 2022-11-14 00:07:35 (1800): VM state change detected. (old = 'Running', new = 'Paused') 2022-11-14 00:10:42 (1800): VM state change detected. (old = 'Paused', new = 'Running') 2022-11-14 00:29:06 (1800): VM state change detected. (old = 'Running', new = 'Paused') 2022-11-14 00:32:45 (1800): VM state change detected. (old = 'Paused', new = 'Running') 2022-11-14 01:07:16 (1800): VM state change detected. (old = 'Running', new = 'Paused') 2022-11-14 01:18:14 (1800): VM state change detected. (old = 'Paused', new = 'Running') 2022-11-14 01:18:29 (1800): VM state change detected. (old = 'Running', new = 'Paused') 2022-11-14 01:38:50 (1800): VM state change detected. (old = 'Paused', new = 'Running') 2022-11-14 01:39:03 (1800): VM state change detected. (old = 'Running', new = 'Paused') 2022-11-14 01:49:25 (1800): VM state change detected. (old = 'Paused', new = 'Running') 2022-11-14 01:49:28 (1800): Guest Log: 01:28:24.188284 timesync vgsvcTimeSyncWorker: Radical guest time change: 2 291 980 775 000ns (GuestNow=1 668 389 946 643 368 000 ns GuestLast=1 668 387 654 662 593 000 ns fSetTimeLastLoop=true ) 2022-11-14 02:00:46 (1800): Status Report: Job Duration: '864000.000000' 2022-11-14 02:00:46 (1800): Status Report: Elapsed Time: '222413.349699' 2022-11-14 02:00:46 (1800): Status Report: CPU Time: '102446.125000' 2022-11-14 02:01:14 (1800): VM state change detected. (old = 'Running', new = 'Paused') 2022-11-14 02:05:51 (1800): VM state change detected. (old = 'Paused', new = 'Running') 2022-11-14 02:10:48 (1800): VM state change detected. (old = 'Running', new = 'Paused') 2022-11-14 02:20:50 (1800): VM state change detected. (old = 'Paused', new = 'Running') 2022-11-14 03:55:31 (1800): Status Report: Job Duration: '864000.000000' 2022-11-14 03:55:31 (1800): Status Report: Elapsed Time: '228413.349699' 2022-11-14 03:55:31 (1800): Status Report: CPU Time: '105166.265625' 2022-11-14 05:35:36 (1800): Status Report: Job Duration: '864000.000000' 2022-11-14 05:35:36 (1800): Status Report: Elapsed Time: '234413.349699' 2022-11-14 05:35:36 (1800): Status Report: CPU Time: '107886.250000' 2022-11-14 07:15:41 (1800): Status Report: Job Duration: '864000.000000' 2022-11-14 07:15:41 (1800): Status Report: Elapsed Time: '240413.349699' 2022-11-14 07:15:41 (1800): Status Report: CPU Time: '110606.062500' 2022-11-14 08:55:45 (1800): Status Report: Job Duration: '864000.000000' 2022-11-14 08:55:45 (1800): Status Report: Elapsed Time: '246413.349699' 2022-11-14 08:55:45 (1800): Status Report: CPU Time: '113325.843750' 2022-11-14 10:32:36 (1800): Guest Log: job: run exitcode=0 2022-11-14 10:32:36 (1800): Guest Log: job: diskusage=2112 2022-11-14 10:32:36 (1800): Guest Log: job: logsize=80 k 2022-11-14 10:32:36 (1800): Guest Log: job: times= 2022-11-14 10:32:36 (1800): Guest Log: 0m0.004s 0m0.007s 2022-11-14 10:32:36 (1800): Guest Log: 585m27.990s 3m20.911s 2022-11-14 10:32:36 (1800): Guest Log: job: cpuusage=35329 2022-11-14 10:32:36 (1800): Guest Log: 10:32:36 GMT +00:00 2022-11-14: cranky: [INFO] Container 'runc' finished with status code 0. 2022-11-14 10:32:36 (1800): Guest Log: 10:32:36 GMT +00:00 2022-11-14: cranky: [INFO] Preparing output. 2022-11-14 10:32:39 (1800): Guest Log: [INFO] Job Finished 2022-11-14 10:32:39 (1800): Guest Log: [INFO] Shutting Down. 2022-11-14 10:32:39 (1800): VM Completion File Detected. 2022-11-14 10:32:39 (1800): Powering off VM. 2022-11-14 10:37:41 (1800): VM did not power off when requested. 2022-11-14 10:37:41 (1800): VM was successfully terminated. 2022-11-14 10:37:41 (1800): Deregistering VM. (boinc_a5acfd5119f860a2, slot#1) 2022-11-14 10:37:42 (1800): Removing network bandwidth throttle group f
©2025 CERN