| Name | Theory_2390-1149639-14_1 |
| Workunit | 140742062 |
| Created | 4 Jun 2020, 14:07:19 UTC |
| Sent | 4 Jun 2020, 14:14:10 UTC |
| Report deadline | 15 Jun 2020, 14:14:10 UTC |
| Received | 16 Jun 2020, 17:33:31 UTC |
| Server state | Over |
| Outcome | Success |
| Client state | Done |
| Exit status | 0 (0x00000000) |
| Computer ID | 10623858 |
| Run time | 9 hours 22 min 24 sec |
| CPU time | 8 hours 45 min 37 sec |
| Validate state | Valid |
| Credit | 221.88 |
| Device peak FLOPS | 2.84 GFLOPS |
| Application version | Theory Simulation v300.06 (vbox64_theory) windows_x86_64 |
| Peak working set size | 60.75 MB |
| Peak swap size | 57.92 MB |
| Peak disk usage | 3.01 GB |
Stderr output
<core_client_version>7.14.2</core_client_version> <![CDATA[ <stderr_txt> 4:05:39 (17200): 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-08 14:05:39 (17200): Guest Log: 2.5.2.0 4126 0 25288 251 3 1 1093231 4096001 0 65024 0 0 n/a 0 0 http://s1cern-cvmfs.openhtc.io/cvmfs/cernvm-prod.cern.ch DIRECT 1 2020-06-08 14:05:40 (17200): Guest Log: Probing /cvmfs/alice.cern.ch... OK 2020-06-08 14:05:41 (17200): 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-08 14:05:41 (17200): Guest Log: 2.5.2.0 4081 0 30496 6758 3 1 1093231 4096001 0 65024 0 0 n/a 4111 2065 http://s1cern-cvmfs.openhtc.io/cvmfs/alice.cern.ch DIRECT 1 2020-06-08 14:05:41 (17200): Guest Log: 14:05:43 CEST +02:00 2020-06-08: cranky: [INFO] Checking runc. 2020-06-08 14:05:41 (17200): Guest Log: 14:05:43 CEST +02:00 2020-06-08: cranky: [INFO] Creating the filesystem. 2020-06-08 14:05:41 (17200): Guest Log: 14:05:43 CEST +02:00 2020-06-08: cranky: [INFO] Using /cvmfs/cernvm-prod.cern.ch/cvm3 2020-06-08 14:05:42 (17200): Guest Log: 14:05:43 CEST +02:00 2020-06-08: cranky: [INFO] Updating config.json. 2020-06-08 14:05:42 (17200): Guest Log: 14:05:43 CEST +02:00 2020-06-08: cranky: [INFO] Running Container 'runc'. 2020-06-08 14:05:43 (17200): Guest Log: job: htmld=/shared/html/job 2020-06-08 14:05:43 (17200): Guest Log: job: unpack exitcode=0 2020-06-08 14:05:44 (17200): Guest Log: 14:05:46 CEST +02:00 2020-06-08: cranky: [INFO] ===> [runRivet] Mon Jun 8 12:05:44 UTC 2020 [boinc pp winclusive 7000 20 - herwig++ 2.7.1 default 100000 14] 2020-06-08 14:05:49 (17200): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-08 14:05:59 (17200): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-08 14:07:09 (17200): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-08 14:07:19 (17200): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-08 14:08:49 (17200): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-08 14:09:09 (17200): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-08 14:09:39 (17200): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-08 14:09:49 (17200): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-08 14:10:51 (17200): Stopping VM. 2020-06-08 14:10:52 (17200): Error in stop VM for VM: -108 Command: VBoxManage -q controlvm "boinc_09ab3b7ed29b8370" savestate Output: 2020-06-08 14:10:52 (17200): VM did not stop when requested. 2020-06-08 14:10:52 (17200): VM was NOT successfully terminated. 2020-06-09 18:15:37 (4392): Detected: vboxwrapper 26197 2020-06-09 18:15:38 (4392): Detected: BOINC client v7.7 2020-06-09 18:15:52 (4392): Detected: VirtualBox VboxManage Interface (Version: 6.1.4) 2020-06-09 18:15:52 (4392): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds) 2020-06-09 18:15:53 (4392): ERROR: VM failed to start 2020-06-09 18:15:58 (4392): BOINC will be notified that it needs to clean up the environment. This is a temporary problem and so this job will be rescheduled for another time. 2020-06-09 18:15:58 (6896): Detected: vboxwrapper 26197 2020-06-09 18:15:58 (6896): Detected: BOINC client v7.7 2020-06-09 18:15:59 (6896): Detected: VirtualBox VboxManage Interface (Version: 6.1.4) 2020-06-09 18:15:59 (6896): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds) 2020-06-09 18:15:59 (6896): Starting VM using VBoxManage interface. (boinc_09ab3b7ed29b8370, slot#0) 2020-06-09 18:16:06 (6896): Successfully started VM. (PID = '8992') 2020-06-09 18:16:06 (6896): Reporting VM Process ID to BOINC. 2020-06-09 18:16:06 (6896): Guest Log: BIOS: VirtualBox 6.1.4 2020-06-09 18:16:06 (6896): Guest Log: CPUID EDX: 0x178bfbff 2020-06-09 18:16:06 (6896): Guest Log: BIOS: ata0-0: PCHS=16383/16/63 LCHS=1024/255/63 2020-06-09 18:16:06 (6896): VM state change detected. (old = 'PoweredOff', new = 'Running') 2020-06-09 18:16:06 (6896): Detected: Web Application Enabled (http://localhost:58168) 2020-06-09 18:16:07 (6896): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-09 18:16:15 (6896): Preference change detected 2020-06-09 18:16:15 (6896): Setting CPU throttle for VM. (100%) 2020-06-09 18:16:15 (6896): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds)) 2020-06-09 18:16:16 (6896): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-09 18:16:17 (6896): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032 2020-06-09 18:16:17 (6896): Guest Log: BIOS: Booting from Hard Disk... 2020-06-09 18:16:20 (6896): Guest Log: BIOS: KBD: unsupported int 16h function 03 2020-06-09 18:16:20 (6896): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 2020-06-09 18:16:26 (6896): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-09 18:16:36 (6896): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-09 18:16:46 (6896): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-09 18:17:06 (6896): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-09 18:17:16 (6896): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-09 18:17:26 (6896): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-09 18:17:27 (6896): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds 2020-06-09 18:17:27 (6896): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000) 2020-06-09 18:17:32 (6896): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log 2020-06-09 18:17:32 (6896): Guest Log: 00:00:00.000156 main Log opened 2020-06-09T16:17:04.613863000Z 2020-06-09 18:17:32 (6896): Guest Log: 00:00:00.000376 main OS Product: Linux 2020-06-09 18:17:32 (6896): Guest Log: 00:00:00.000466 main OS Release: 4.14.76-13.cernvm.x86_64 2020-06-09 18:17:32 (6896): Guest Log: 00:00:00.000550 main OS Version: #1 SMP Tue Oct 16 18:26:15 CEST 2018 2020-06-09 18:17:32 (6896): Guest Log: 00:00:00.000626 main Executable: /usr/sbin/VBoxService 2020-06-09 18:17:32 (6896): Guest Log: 00:00:00.000628 main Process ID: 3209 2020-06-09 18:17:32 (6896): Guest Log: 00:00:00.000629 main Package type: LINUX_64BITS_GENERIC 2020-06-09 18:17:32 (6896): Guest Log: 00:00:00.007323 main 5.2.6 r120293 started. Verbose level = 0 2020-06-09 18:18:03 (6896): Guest Log: 18:17:36 CEST +02:00 2020-06-09: cranky: [INFO] Detected Theory App 2020-06-09 18:18:03 (6896): Guest Log: 18:17:36 CEST +02:00 2020-06-09: cranky: [INFO] Checking CVMFS. 2020-06-09 18:18:04 (6896): Guest Log: Probing /cvmfs/sft.cern.ch... OK 2020-06-09 18:18:05 (6896): 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-09 18:18:05 (6896): Guest Log: 2.5.2.0 4047 0 26108 18210 3 1 1110862 4096001 0 65024 0 0 n/a 5 4 http://s1bnl-cvmfs.openhtc.io/cvmfs/sft.cern.ch DIRECT 1 2020-06-09 18:18:06 (6896): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-09 18:18:16 (6896): Guest Log: Probing /cvmfs/grid.cern.ch... OK 2020-06-09 18:18:16 (6896): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-09 18:18:17 (6896): 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-09 18:18:17 (6896): Guest Log: 2.5.2.0 4068 0 27544 12439 3 2 1110862 4096001 0 65024 0 2 0 4273 2842 http://s1cern-cvmfs.openhtc.io/cvmfs/grid.cern.ch DIRECT 1 2020-06-09 18:18:18 (6896): Guest Log: Probing /cvmfs/cernvm-prod.cern.ch... OK 2020-06-09 18:18:19 (6896): 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-09 18:18:19 (6896): Guest Log: 2.5.2.0 4096 0 27204 252 3 1 1110862 4096001 0 65024 0 0 n/a 4 2 http://s1cern-cvmfs.openhtc.io/cvmfs/cernvm-prod.cern.ch DIRECT 1 2020-06-09 18:18:20 (6896): Guest Log: Probing /cvmfs/alice.cern.ch... OK 2020-06-09 18:18:21 (6896): 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-09 18:18:21 (6896): Guest Log: 2.5.2.0 4123 0 32208 6766 3 1 1110862 4096001 0 65024 0 0 n/a 4114 1338 http://s1cern-cvmfs.openhtc.io/cvmfs/alice.cern.ch DIRECT 1 2020-06-09 18:18:21 (6896): Guest Log: 18:17:43 CEST +02:00 2020-06-09: cranky: [INFO] Checking runc. 2020-06-09 18:18:21 (6896): Guest Log: 18:17:44 CEST +02:00 2020-06-09: cranky: [INFO] Creating the filesystem. 2020-06-09 18:18:21 (6896): Guest Log: 18:17:44 CEST +02:00 2020-06-09: cranky: [INFO] Using /cvmfs/cernvm-prod.cern.ch/cvm3 2020-06-09 18:18:21 (6896): Guest Log: 18:17:44 CEST +02:00 2020-06-09: cranky: [INFO] Updating config.json. 2020-06-09 18:18:21 (6896): Guest Log: 18:17:44 CEST +02:00 2020-06-09: cranky: [INFO] Running Container 'runc'. 2020-06-09 18:18:22 (6896): Guest Log: job: htmld=/shared/html/job 2020-06-09 18:18:22 (6896): Guest Log: job: unpack exitcode=0 2020-06-09 18:18:23 (6896): Guest Log: 18:17:46 CEST +02:00 2020-06-09: cranky: [INFO] ===> [runRivet] Tue Jun 9 16:17:45 UTC 2020 [boinc pp winclusive 7000 20 - herwig++ 2.7.1 default 100000 14] 2020-06-09 18:18:36 (6896): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-09 18:18:46 (6896): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-09 18:21:37 (6896): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-09 18:21:47 (6896): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-09 18:24:18 (6896): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-09 18:24:28 (6896): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-09 18:32:38 (6896): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-09 18:32:48 (6896): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-09 18:38:40 (6896): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-09 18:38:50 (6896): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-09 18:45:01 (6896): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-09 18:45:11 (6896): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-09 18:46:01 (6896): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-09 18:46:11 (6896): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-09 18:53:42 (6896): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-09 18:53:52 (6896): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-09 19:09:10 (6896): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-09 19:09:55 (6896): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-09 19:15:11 (6896): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-09 19:16:17 (6896): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-09 19:23:03 (6896): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-09 19:23:09 (6896): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-09 19:41:24 (14572): Detected: vboxwrapper 26197 2020-06-09 19:41:24 (14572): Detected: BOINC client v7.7 2020-06-09 19:41:26 (14572): Detected: VirtualBox VboxManage Interface (Version: 6.1.4) 2020-06-09 19:41:26 (14572): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds) 2020-06-09 19:41:27 (14572): Starting VM using VBoxManage interface. (boinc_09ab3b7ed29b8370, slot#0) 2020-06-09 19:41:37 (14572): Successfully started VM. (PID = '18540') 2020-06-09 19:41:37 (14572): Reporting VM Process ID to BOINC. 2020-06-09 19:41:37 (14572): Guest Log: BIOS: VirtualBox 6.1.4 2020-06-09 19:41:37 (14572): Guest Log: CPUID EDX: 0x178bfbff 2020-06-09 19:41:37 (14572): Guest Log: BIOS: ata0-0: PCHS=16383/16/63 LCHS=1024/255/63 2020-06-09 19:41:37 (14572): VM state change detected. (old = 'PoweredOff', new = 'Running') 2020-06-09 19:41:37 (14572): Detected: Web Application Enabled (http://localhost:58168) 2020-06-09 19:41:37 (14572): Preference change detected 2020-06-09 19:41:37 (14572): Setting CPU throttle for VM. (100%) 2020-06-09 19:41:38 (14572): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds)) 2020-06-09 19:41:39 (14572): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032 2020-06-09 19:41:39 (14572): Guest Log: BIOS: Booting from Hard Disk... 2020-06-09 19:41:48 (14572): Guest Log: BIOS: KBD: unsupported int 16h function 03 2020-06-09 19:41:48 (14572): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 2020-06-09 19:42:08 (14572): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds 2020-06-09 19:42:08 (14572): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000) 2020-06-09 19:42:11 (14572): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log 2020-06-09 19:42:11 (14572): Guest Log: 00:00:00.000339 main Log opened 2020-06-09T17:42:13.037982000Z 2020-06-09 19:42:11 (14572): Guest Log: 00:00:00.001030 main OS Product: Linux 2020-06-09 19:42:11 (14572): Guest Log: 00:00:00.001137 main OS Release: 4.14.76-13.cernvm.x86_64 2020-06-09 19:42:11 (14572): Guest Log: 00:00:00.002561 main OS Version: #1 SMP Tue Oct 16 18:26:15 CEST 2018 2020-06-09 19:42:11 (14572): Guest Log: 00:00:00.002663 main Executable: /usr/sbin/VBoxService 2020-06-09 19:42:11 (14572): Guest Log: 00:00:00.002665 main Process ID: 3210 2020-06-09 19:42:11 (14572): Guest Log: 00:00:00.002667 main Package type: LINUX_64BITS_GENERIC 2020-06-09 19:42:11 (14572): Guest Log: 00:00:00.020182 main 5.2.6 r120293 started. Verbose level = 0 2020-06-09 19:42:44 (14572): Guest Log: 19:42:45 CEST +02:00 2020-06-09: cranky: [INFO] Detected Theory App 2020-06-09 19:42:44 (14572): Guest Log: 19:42:45 CEST +02:00 2020-06-09: cranky: [INFO] Checking CVMFS. 2020-06-09 19:42:45 (14572): Guest Log: Probing /cvmfs/sft.cern.ch... OK 2020-06-09 19:42:46 (14572): 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-09 19:42:46 (14572): Guest Log: 2.5.2.0 4072 0 26556 18210 3 1 1120103 4096001 0 65024 0 0 n/a 0 0 http://s1cern-cvmfs.openhtc.io/cvmfs/sft.cern.ch DIRECT 1 2020-06-09 19:42:47 (14572): Guest Log: Probing /cvmfs/grid.cern.ch... OK 2020-06-09 19:42:48 (14572): 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-09 19:42:48 (14572): Guest Log: 2.5.2.0 4050 0 27732 12439 3 2 1120103 4096001 0 65024 0 2 100 0 0 http://s1cern-cvmfs.openhtc.io/cvmfs/grid.cern.ch DIRECT 1 2020-06-09 19:42:50 (14572): Guest Log: Probing /cvmfs/cernvm-prod.cern.ch... OK 2020-06-09 19:42:51 (14572): 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-09 19:42:51 (14572): Guest Log: 2.5.2.0 4119 0 26700 252 3 1 1120103 4096001 0 65024 0 0 n/a 0 0 http://s1cern-cvmfs.openhtc.io/cvmfs/cernvm-prod.cern.ch DIRECT 1 2020-06-09 19:42:53 (14572): Guest Log: Probing /cvmfs/alice.cern.ch... OK 2020-06-09 19:42:53 (14572): 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-09 19:42:53 (14572): Guest Log: 2.5.2.0 4097 0 27684 6767 3 1 1120103 4096001 0 65024 0 0 n/a 4117 3416 http://s1cern-cvmfs.openhtc.io/cvmfs/alice.cern.ch DIRECT 1 2020-06-09 19:42:53 (14572): Guest Log: 19:42:55 CEST +02:00 2020-06-09: cranky: [INFO] Checking runc. 2020-06-09 19:42:54 (14572): Guest Log: 19:42:55 CEST +02:00 2020-06-09: cranky: [INFO] Creating the filesystem. 2020-06-09 19:42:54 (14572): Guest Log: 19:42:55 CEST +02:00 2020-06-09: cranky: [INFO] Using /cvmfs/cernvm-prod.cern.ch/cvm3 2020-06-09 19:42:54 (14572): Guest Log: 19:42:55 CEST +02:00 2020-06-09: cranky: [INFO] Updating config.json. 2020-06-09 19:42:54 (14572): Guest Log: 19:42:55 CEST +02:00 2020-06-09: cranky: [INFO] Running Container 'runc'. 2020-06-09 19:42:55 (14572): Guest Log: job: htmld=/shared/html/job 2020-06-09 19:42:55 (14572): Guest Log: job: unpack exitcode=0 2020-06-09 19:42:56 (14572): Guest Log: 19:42:58 CEST +02:00 2020-06-09: cranky: [INFO] ===> [runRivet] Tue Jun 9 17:42:57 UTC 2020 [boinc pp winclusive 7000 20 - herwig++ 2.7.1 default 100000 14] 2020-06-09 19:43:42 (14572): Status Report: Job Duration: '864000.000000' 2020-06-09 19:43:42 (14572): Status Report: Elapsed Time: '6000.014919' 2020-06-09 19:43:42 (14572): Status Report: CPU Time: '4986.781250' 2020-06-09 19:51:03 (14572): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-09 19:51:10 (14572): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-09 19:53:44 (14572): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-09 19:53:45 (14572): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-09 20:09:23 (14572): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-09 20:09:33 (14572): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-09 20:19:05 (14572): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-09 20:19:07 (14572): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-09 20:20:33 (14572): Stopping VM. 2020-06-09 20:20:33 (14572): Error in stop VM for VM: -108 Command: VBoxManage -q controlvm "boinc_09ab3b7ed29b8370" savestate Output: 2020-06-09 20:20:33 (14572): VM did not stop when requested. 2020-06-09 20:20:33 (14572): VM was NOT successfully terminated. 2020-06-11 15:02:28 (1816): Detected: vboxwrapper 26197 2020-06-11 15:02:28 (1816): Detected: BOINC client v7.7 2020-06-11 15:02:30 (1816): Detected: VirtualBox VboxManage Interface (Version: 6.1.4) 2020-06-11 15:02:30 (1816): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds) 2020-06-11 15:02:31 (1816): Starting VM using VBoxManage interface. (boinc_09ab3b7ed29b8370, slot#0) 2020-06-11 15:02:44 (1816): Successfully started VM. (PID = '18896') 2020-06-11 15:02:44 (1816): Reporting VM Process ID to BOINC. 2020-06-11 15:02:44 (1816): Guest Log: BIOS: VirtualBox 6.1.4 2020-06-11 15:02:44 (1816): Guest Log: CPUID EDX: 0x178bfbff 2020-06-11 15:02:44 (1816): Guest Log: BIOS: ata0-0: PCHS=16383/16/63 LCHS=1024/255/63 2020-06-11 15:02:44 (1816): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032 2020-06-11 15:02:44 (1816): Guest Log: BIOS: Booting from Hard Disk... 2020-06-11 15:02:44 (1816): VM state change detected. (old = 'PoweredOff', new = 'Running') 2020-06-11 15:02:44 (1816): Detected: Web Application Enabled (http://localhost:58168) 2020-06-11 15:02:51 (1816): Guest Log: BIOS: KBD: unsupported int 16h function 03 2020-06-11 15:02:51 (1816): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 2020-06-11 15:03:01 (1816): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-11 15:03:24 (1816): Status Report: Job Duration: '864000.000000' 2020-06-11 15:03:24 (1816): Status Report: Elapsed Time: '7851.860245' 2020-06-11 15:03:24 (1816): Status Report: CPU Time: '7036.734375' 2020-06-11 15:03:24 (1816): Preference change detected 2020-06-11 15:03:24 (1816): Setting CPU throttle for VM. (100%) 2020-06-11 15:03:24 (1816): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds)) 2020-06-11 15:03:24 (1816): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-11 15:03:34 (1816): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-11 15:03:54 (1816): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-11 15:04:04 (1816): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-11 15:04:15 (1816): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-11 15:04:26 (1816): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds 2020-06-11 15:04:26 (1816): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000) 2020-06-11 15:04:33 (1816): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log 2020-06-11 15:04:33 (1816): Guest Log: 00:00:00.000317 main Log opened 2020-06-11T13:04:25.391160000Z 2020-06-11 15:04:33 (1816): Guest Log: 00:00:00.000541 main OS Product: Linux 2020-06-11 15:04:33 (1816): Guest Log: 00:00:00.000601 main OS Release: 4.14.76-13.cernvm.x86_64 2020-06-11 15:04:33 (1816): Guest Log: 00:00:00.000647 main OS Version: #1 SMP Tue Oct 16 18:26:15 CEST 2018 2020-06-11 15:04:33 (1816): Guest Log: 00:00:00.000691 main Executable: /usr/sbin/VBoxService 2020-06-11 15:04:33 (1816): Guest Log: 00:00:00.000692 main Process ID: 3219 2020-06-11 15:04:33 (1816): Guest Log: 00:00:00.000693 main Package type: LINUX_64BITS_GENERIC 2020-06-11 15:04:33 (1816): Guest Log: 00:00:00.006700 main 5.2.6 r120293 started. Verbose level = 0 2020-06-11 15:04:54 (1816): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-11 15:05:04 (1816): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-11 15:05:22 (1816): Guest Log: 15:04:58 CEST +02:00 2020-06-11: cranky: [INFO] Detected Theory App 2020-06-11 15:05:22 (1816): Guest Log: 15:04:58 CEST +02:00 2020-06-11: cranky: [INFO] Checking CVMFS. 2020-06-11 15:05:22 (1816): Guest Log: Probing /cvmfs/sft.cern.ch... OK 2020-06-11 15:05:22 (1816): 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-11 15:05:22 (1816): Guest Log: 2.5.2.0 4106 0 27212 18219 3 1 1137475 4096001 0 65024 0 0 n/a 5 5 http://s1cern-cvmfs.openhtc.io/cvmfs/sft.cern.ch DIRECT 1 2020-06-11 15:05:22 (1816): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-11 15:05:34 (1816): Guest Log: Probing /cvmfs/grid.cern.ch... OK 2020-06-11 15:05:34 (1816): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-11 15:05:35 (1816): 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-11 15:05:36 (1816): Guest Log: 2.5.2.0 4085 0 25492 12455 3 2 1137475 4096001 0 65024 0 2 0 4273 1875 http://s1cern-cvmfs.openhtc.io/cvmfs/grid.cern.ch DIRECT 1 2020-06-11 15:05:37 (1816): Guest Log: Probing /cvmfs/cernvm-prod.cern.ch... OK 2020-06-11 15:05:39 (1816): 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-11 15:05:39 (1816): Guest Log: 2.5.2.0 4128 0 25840 252 3 1 1137475 4096001 0 65024 0 0 n/a 0 0 http://s1ral-cvmfs.openhtc.io/cvmfs/cernvm-prod.cern.ch DIRECT 1 2020-06-11 15:05:40 (1816): Guest Log: Probing /cvmfs/alice.cern.ch... OK 2020-06-11 15:05:42 (1816): 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-11 15:05:42 (1816): Guest Log: 2.5.2.0 4063 0 29128 6776 3 1 1137475 4096001 0 65024 0 0 n/a 4121 2221 http://s1cern-cvmfs.openhtc.io/cvmfs/alice.cern.ch DIRECT 1 2020-06-11 15:05:42 (1816): Guest Log: 15:05:12 CEST +02:00 2020-06-11: cranky: [INFO] Checking runc. 2020-06-11 15:05:42 (1816): Guest Log: 15:05:12 CEST +02:00 2020-06-11: cranky: [INFO] Creating the filesystem. 2020-06-11 15:05:42 (1816): Guest Log: 15:05:12 CEST +02:00 2020-06-11: cranky: [INFO] Using /cvmfs/cernvm-prod.cern.ch/cvm3 2020-06-11 15:05:42 (1816): Guest Log: 15:05:13 CEST +02:00 2020-06-11: cranky: [INFO] Updating config.json. 2020-06-11 15:05:44 (1816): Guest Log: 15:05:13 CEST +02:00 2020-06-11: cranky: [INFO] Running Container 'runc'. 2020-06-11 15:05:44 (1816): Guest Log: job: htmld=/shared/html/job 2020-06-11 15:05:44 (1816): Guest Log: job: unpack exitcode=0 2020-06-11 15:05:47 (1816): Guest Log: 15:05:15 CEST +02:00 2020-06-11: cranky: [INFO] ===> [runRivet] Thu Jun 11 13:05:14 UTC 2020 [boinc pp winclusive 7000 20 - herwig++ 2.7.1 default 100000 14] 2020-06-11 15:05:47 (1816): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-11 15:06:05 (1816): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-11 15:06:15 (1816): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-11 15:06:25 (1816): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-11 15:06:47 (1816): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-11 15:07:55 (1816): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-11 15:08:07 (1816): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-11 15:08:27 (1816): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-11 15:08:37 (1816): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-11 15:08:55 (1816): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-11 15:09:05 (1816): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-11 15:09:15 (1816): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-11 15:09:26 (1816): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-11 15:09:36 (1816): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-11 15:09:56 (1816): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-11 15:10:06 (1816): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-11 15:10:16 (1816): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-11 15:10:46 (1816): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-11 15:22:42 (1816): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-11 15:22:50 (1816): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-11 15:25:12 (1816): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-11 15:25:14 (1816): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-11 15:33:27 (1816): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-11 15:33:28 (1816): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-11 15:48:42 (1816): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-11 15:48:58 (1816): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-11 15:49:08 (1816): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-11 15:49:18 (1816): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-11 15:51:59 (1816): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-11 15:52:09 (1816): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-11 15:54:22 (1816): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-11 15:54:29 (1816): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-11 15:54:49 (1816): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-11 15:54:59 (1816): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-11 15:58:11 (1816): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-11 15:58:20 (1816): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-11 16:03:32 (1816): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-11 16:03:42 (1816): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-11 16:03:51 (1816): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-11 16:04:01 (1816): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-11 18:23:57 (1816): Guest Log: 03:14:29.526193 timesync vgsvcTimeSyncWorker: Radical host time change: 7 552 688 000 000ns (HostNow=1 591 892 633 522 000 000 ns HostLast=1 591 885 080 834 000 000 ns) 2020-06-11 18:23:57 (1816): Guest Log: 03:14:29.616153 timesync vgsvcTimeSyncWorker: Radical guest time change: 7 556 516 472 000ns (GuestNow=1 591 892 334 796 652 000 ns GuestLast=1 591 884 778 280 180 000 ns fSetTimeLastLoop=false) 2020-06-11 18:24:01 (1816): Stopping VM. 2020-06-13 15:53:48 (8744): Detected: vboxwrapper 26197 2020-06-13 15:53:48 (8744): Detected: BOINC client v7.7 2020-06-13 15:53:50 (8744): Detected: VirtualBox VboxManage Interface (Version: 6.1.4) 2020-06-13 15:53:50 (8744): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds) 2020-06-13 15:53:50 (8744): Starting VM using VBoxManage interface. (boinc_09ab3b7ed29b8370, slot#0) 2020-06-13 15:54:07 (8744): Successfully started VM. (PID = '16892') 2020-06-13 15:54:07 (8744): Reporting VM Process ID to BOINC. 2020-06-13 15:54:10 (8744): Guest Log: 03:14:49.690029 timesync vgsvcTimeSyncWorker: Radical host time change: 163 802 430 000 000ns (HostNow=1 592 056 444 391 000 000 ns HostLast=1 591 892 641 961 000 000 ns) 2020-06-13 15:54:10 (8744): VM state change detected. (old = 'PoweredOff', new = 'Running') 2020-06-13 15:54:10 (8744): Detected: Web Application Enabled (http://localhost:58168) 2020-06-13 15:54:11 (8744): Status Report: Job Duration: '864000.000000' 2020-06-13 15:54:11 (8744): Status Report: Elapsed Time: '11768.674710' 2020-06-13 15:54:11 (8744): Status Report: CPU Time: '10956.828125' 2020-06-13 15:54:11 (8744): Preference change detected 2020-06-13 15:54:11 (8744): Setting CPU throttle for VM. (100%) 2020-06-13 15:54:20 (8744): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds)) 2020-06-13 15:54:20 (8744): Guest Log: 03:14:59.700766 timesync vgsvcTimeSyncWorker: Radical guest time change: 164 099 380 956 000ns (GuestNow=1 592 056 454 401 707 000 ns GuestLast=1 591 892 355 020 751 000 ns fSetTimeLastLoop=true ) 2020-06-13 15:54:24 (8744): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-13 15:54:52 (8744): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-13 15:56:46 (8744): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-13 15:56:53 (8744): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-13 15:59:05 (8744): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-13 15:59:13 (8744): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-13 16:01:46 (8744): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-13 16:01:53 (8744): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-13 16:03:04 (8744): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-13 16:03:24 (8744): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-13 16:04:45 (8744): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-13 16:05:04 (8744): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-13 16:05:34 (8744): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-13 16:05:54 (8744): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-13 16:08:07 (8744): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-13 16:08:25 (8744): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-13 16:09:15 (8744): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-13 16:09:25 (8744): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-13 16:11:20 (8744): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-13 16:11:25 (8744): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-13 16:11:56 (8744): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-13 16:12:06 (8744): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-13 16:12:26 (8744): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-13 16:12:35 (8744): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-13 16:14:39 (8744): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-13 16:14:46 (8744): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-13 16:15:36 (8744): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-13 16:15:46 (8744): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-13 16:16:08 (8744): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-13 16:16:16 (8744): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-13 16:16:37 (8744): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-13 16:16:56 (8744): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-13 16:17:57 (8744): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-13 16:18:17 (8744): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-13 16:20:11 (8744): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-13 16:20:26 (8744): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-13 16:21:09 (8744): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-13 16:21:17 (8744): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-13 16:21:29 (8744): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-13 16:21:47 (8744): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-13 16:23:12 (8744): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-13 16:23:18 (8744): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-13 16:23:33 (8744): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-13 16:23:47 (8744): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-13 16:24:00 (8744): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-13 16:24:27 (8744): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-13 16:25:39 (8744): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-13 16:25:50 (8744): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-13 16:27:28 (8744): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-13 16:27:38 (8744): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-13 16:27:58 (8744): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-13 16:28:08 (8744): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-13 16:29:29 (8744): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-13 16:29:39 (8744): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-13 16:34:49 (8744): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-13 16:34:58 (8744): Stopping VM. 2020-06-13 16:34:58 (8744): Error in stop VM for VM: -108 Command: VBoxManage -q controlvm "boinc_09ab3b7ed29b8370" savestate Output: 2020-06-13 16:34:58 (8744): VM did not stop when requested. 2020-06-13 16:34:58 (8744): VM was NOT successfully terminated. 2020-06-14 11:10:18 (13916): Detected: vboxwrapper 26197 2020-06-14 11:10:18 (13916): Detected: BOINC client v7.7 2020-06-14 11:10:20 (13916): Detected: VirtualBox VboxManage Interface (Version: 6.1.4) 2020-06-14 11:10:20 (13916): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds) 2020-06-14 11:10:21 (13916): Starting VM using VBoxManage interface. (boinc_09ab3b7ed29b8370, slot#0) 2020-06-14 11:10:28 (13916): Successfully started VM. (PID = '14604') 2020-06-14 11:10:28 (13916): Reporting VM Process ID to BOINC. 2020-06-14 11:10:28 (13916): Guest Log: BIOS: VirtualBox 6.1.4 2020-06-14 11:10:28 (13916): Guest Log: CPUID EDX: 0x178bfbff 2020-06-14 11:10:28 (13916): Guest Log: BIOS: ata0-0: PCHS=16383/16/63 LCHS=1024/255/63 2020-06-14 11:10:28 (13916): VM state change detected. (old = 'PoweredOff', new = 'Running') 2020-06-14 11:10:28 (13916): Detected: Web Application Enabled (http://localhost:58168) 2020-06-14 11:10:29 (13916): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-14 11:10:39 (13916): Status Report: Job Duration: '864000.000000' 2020-06-14 11:10:39 (13916): Status Report: Elapsed Time: '13654.472771' 2020-06-14 11:10:39 (13916): Status Report: CPU Time: '12979.593750' 2020-06-14 11:10:39 (13916): Preference change detected 2020-06-14 11:10:39 (13916): Setting CPU throttle for VM. (100%) 2020-06-14 11:10:39 (13916): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds)) 2020-06-14 11:10:39 (13916): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032 2020-06-14 11:10:39 (13916): Guest Log: BIOS: Booting from Hard Disk... 2020-06-14 11:10:39 (13916): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-14 11:10:43 (13916): Guest Log: BIOS: KBD: unsupported int 16h function 03 2020-06-14 11:10:43 (13916): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 2020-06-14 11:11:09 (13916): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds 2020-06-14 11:11:09 (13916): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000) 2020-06-14 11:11:10 (13916): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-14 11:15:26 (13916): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-14 11:15:30 (13916): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log 2020-06-14 11:15:30 (13916): Guest Log: 00:00:00.000372 main Log opened 2020-06-14T09:11:15.323184000Z 2020-06-14 11:15:30 (13916): Guest Log: 00:00:00.000581 main OS Product: Linux 2020-06-14 11:15:30 (13916): Guest Log: 00:00:00.002276 main OS Release: 4.14.76-13.cernvm.x86_64 2020-06-14 11:15:30 (13916): Guest Log: 00:00:00.002442 main OS Version: #1 SMP Tue Oct 16 18:26:15 CEST 2018 2020-06-14 11:15:30 (13916): Guest Log: 00:00:00.002491 main Executable: /usr/sbin/VBoxService 2020-06-14 11:15:30 (13916): Guest Log: 00:00:00.002492 main Process ID: 3214 2020-06-14 11:15:30 (13916): Guest Log: 00:00:00.002493 main Package type: LINUX_64BITS_GENERIC 2020-06-14 11:15:30 (13916): Guest Log: 00:00:00.007100 main 5.2.6 r120293 started. Verbose level = 0 2020-06-14 11:15:36 (13916): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-14 11:15:46 (13916): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-14 11:16:15 (13916): Guest Log: 11:11:50 CEST +02:00 2020-06-14: cranky: [INFO] Detected Theory App 2020-06-14 11:16:15 (13916): Guest Log: 11:11:50 CEST +02:00 2020-06-14: cranky: [INFO] Checking CVMFS. 2020-06-14 11:16:16 (13916): Guest Log: Probing /cvmfs/sft.cern.ch... OK 2020-06-14 11:16:17 (13916): 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 11:16:17 (13916): Guest Log: 2.5.2.0 4128 0 25148 18226 3 1 1155767 4096001 0 65024 0 0 n/a 5 2 http://s1cern-cvmfs.openhtc.io/cvmfs/sft.cern.ch DIRECT 1 2020-06-14 11:16:18 (13916): Guest Log: Probing /cvmfs/grid.cern.ch... OK 2020-06-14 11:16:19 (13916): 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 11:16:19 (13916): Guest Log: 2.5.2.0 4079 0 27588 12477 3 2 1155767 4096001 0 65024 0 2 0 4272 627 http://s1unl-cvmfs.openhtc.io/cvmfs/grid.cern.ch DIRECT 1 2020-06-14 11:16:20 (13916): Guest Log: Probing /cvmfs/cernvm-prod.cern.ch... OK 2020-06-14 11:16:21 (13916): 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 11:16:21 (13916): Guest Log: 2.5.2.0 4101 0 27212 252 3 1 1155767 4096001 0 65024 0 0 n/a 0 0 http://s1cern-cvmfs.openhtc.io/cvmfs/cernvm-prod.cern.ch DIRECT 1 2020-06-14 11:16:22 (13916): Guest Log: Probing /cvmfs/alice.cern.ch... OK 2020-06-14 11:16:23 (13916): 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 11:16:23 (13916): Guest Log: 2.5.2.0 4054 0 29716 6792 3 1 1155767 4096001 0 65024 0 0 n/a 4127 2101 http://s1cern-cvmfs.openhtc.io/cvmfs/alice.cern.ch DIRECT 1 2020-06-14 11:16:23 (13916): Guest Log: 11:11:58 CEST +02:00 2020-06-14: cranky: [INFO] Checking runc. 2020-06-14 11:16:23 (13916): Guest Log: 11:11:58 CEST +02:00 2020-06-14: cranky: [INFO] Creating the filesystem. 2020-06-14 11:16:23 (13916): Guest Log: 11:11:58 CEST +02:00 2020-06-14: cranky: [INFO] Using /cvmfs/cernvm-prod.cern.ch/cvm3 2020-06-14 11:16:23 (13916): Guest Log: 11:11:58 CEST +02:00 2020-06-14: cranky: [INFO] Updating config.json. 2020-06-14 11:16:23 (13916): Guest Log: 11:11:58 CEST +02:00 2020-06-14: cranky: [INFO] Running Container 'runc'. 2020-06-14 11:16:24 (13916): Guest Log: job: htmld=/shared/html/job 2020-06-14 11:16:24 (13916): Guest Log: job: unpack exitcode=0 2020-06-14 11:16:25 (13916): Guest Log: 11:12:00 CEST +02:00 2020-06-14: cranky: [INFO] ===> [runRivet] Sun Jun 14 09:11:59 UTC 2020 [boinc pp winclusive 7000 20 - herwig++ 2.7.1 default 100000 14] 2020-06-14 11:18:49 (13916): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-14 11:18:57 (13916): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-14 11:21:58 (13916): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-14 11:22:07 (13916): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-14 11:26:39 (13916): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-14 11:26:48 (13916): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-14 11:27:19 (13916): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-14 11:27:28 (13916): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-14 11:28:19 (13916): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-14 11:28:28 (13916): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-14 11:33:29 (13916): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-14 11:33:39 (13916): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-14 11:38:51 (13916): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-14 11:39:00 (13916): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-14 11:52:36 (13916): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-14 11:52:46 (13916): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-14 12:05:50 (13916): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-14 12:06:00 (13916): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-14 12:06:41 (13916): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-14 12:06:51 (13916): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-14 12:10:41 (13916): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-14 12:10:52 (13916): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-14 12:22:53 (13916): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-14 12:23:03 (13916): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-14 12:31:28 (13916): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-14 12:31:35 (13916): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-14 12:44:47 (13916): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-14 12:44:57 (13916): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-14 12:49:50 (13916): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-14 12:49:59 (13916): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-14 12:54:52 (13916): Stopping VM. 2020-06-14 12:54:52 (13916): Error in stop VM for VM: -108 Command: VBoxManage -q controlvm "boinc_09ab3b7ed29b8370" savestate Output: 2020-06-14 12:54:52 (13916): VM did not stop when requested. 2020-06-14 12:54:52 (13916): VM was NOT successfully terminated. 2020-06-14 19:01:32 (18284): Detected: vboxwrapper 26197 2020-06-14 19:01:32 (18284): Detected: BOINC client v7.7 2020-06-14 19:01:33 (18284): Detected: VirtualBox VboxManage Interface (Version: 6.1.4) 2020-06-14 19:01:33 (18284): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds) 2020-06-14 19:01:34 (18284): Starting VM using VBoxManage interface. (boinc_09ab3b7ed29b8370, slot#0) 2020-06-14 19:01:42 (18284): Successfully started VM. (PID = '13232') 2020-06-14 19:01:42 (18284): Reporting VM Process ID to BOINC. 2020-06-14 19:01:42 (18284): Guest Log: BIOS: VirtualBox 6.1.4 2020-06-14 19:01:42 (18284): Guest Log: CPUID EDX: 0x178bfbff 2020-06-14 19:01:42 (18284): Guest Log: BIOS: ata0-0: PCHS=16383/16/63 LCHS=1024/255/63 2020-06-14 19:01:42 (18284): VM state change detected. (old = 'PoweredOff', new = 'Running') 2020-06-14 19:01:42 (18284): Detected: Web Application Enabled (http://localhost:58168) 2020-06-14 19:01:43 (18284): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-14 19:01:53 (18284): Status Report: Job Duration: '864000.000000' 2020-06-14 19:01:53 (18284): Status Report: Elapsed Time: '19075.110825' 2020-06-14 19:01:53 (18284): Status Report: CPU Time: '18631.593750' 2020-06-14 19:01:53 (18284): Preference change detected 2020-06-14 19:01:53 (18284): Setting CPU throttle for VM. (100%) 2020-06-14 19:01:54 (18284): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds)) 2020-06-14 19:01:54 (18284): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-14 19:01:55 (18284): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032 2020-06-14 19:01:55 (18284): Guest Log: BIOS: Booting from Hard Disk... 2020-06-14 19:01:57 (18284): Guest Log: BIOS: KBD: unsupported int 16h function 03 2020-06-14 19:01:57 (18284): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 2020-06-14 19:02:24 (18284): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-14 19:02:34 (18284): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-14 19:02:43 (18284): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds 2020-06-14 19:02:43 (18284): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000) 2020-06-14 19:02:49 (18284): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log 2020-06-14 19:02:49 (18284): Guest Log: 00:00:00.000133 main Log opened 2020-06-14T17:02:40.519061000Z 2020-06-14 19:02:49 (18284): Guest Log: 00:00:00.000323 main OS Product: Linux 2020-06-14 19:02:49 (18284): Guest Log: 00:00:00.000382 main OS Release: 4.14.76-13.cernvm.x86_64 2020-06-14 19:02:49 (18284): Guest Log: 00:00:00.000433 main OS Version: #1 SMP Tue Oct 16 18:26:15 CEST 2018 2020-06-14 19:02:49 (18284): Guest Log: 00:00:00.000482 main Executable: /usr/sbin/VBoxService 2020-06-14 19:02:49 (18284): Guest Log: 00:00:00.000484 main Process ID: 3211 2020-06-14 19:02:49 (18284): Guest Log: 00:00:00.000485 main Package type: LINUX_64BITS_GENERIC 2020-06-14 19:02:49 (18284): Guest Log: 00:00:00.008328 main 5.2.6 r120293 started. Verbose level = 0 2020-06-14 19:02:53 (18284): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-14 19:03:03 (18284): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-14 19:03:25 (18284): Guest Log: 19:03:06 CEST +02:00 2020-06-14: cranky: [INFO] Detected Theory App 2020-06-14 19:03:25 (18284): Guest Log: 19:03:06 CEST +02:00 2020-06-14: cranky: [INFO] Checking CVMFS. 2020-06-14 19:03:26 (18284): Guest Log: Probing /cvmfs/sft.cern.ch... OK 2020-06-14 19:03:28 (18284): 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:03:28 (18284): Guest Log: 2.5.2.0 4094 0 26516 18227 3 1 1173154 4096001 0 65024 0 0 n/a 0 0 http://s1cern-cvmfs.openhtc.io/cvmfs/sft.cern.ch DIRECT 1 2020-06-14 19:03:29 (18284): Guest Log: Probing /cvmfs/grid.cern.ch... OK 2020-06-14 19:03:30 (18284): 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:03:30 (18284): Guest Log: 2.5.2.0 4116 0 27692 12479 3 2 1173154 4096001 0 65024 0 2 0 4272 3701 http://s1cern-cvmfs.openhtc.io/cvmfs/grid.cern.ch DIRECT 1 2020-06-14 19:03:31 (18284): Guest Log: Probing /cvmfs/cernvm-prod.cern.ch... OK 2020-06-14 19:03:31 (18284): 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:03:31 (18284): Guest Log: 2.5.2.0 4073 0 25172 252 3 1 1173154 4096001 0 65024 0 0 n/a 0 0 http://s1unl-cvmfs.openhtc.io/cvmfs/cernvm-prod.cern.ch DIRECT 1 2020-06-14 19:03:32 (18284): Guest Log: Probing /cvmfs/alice.cern.ch... OK 2020-06-14 19:03:33 (18284): 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:03:33 (18284): Guest Log: 2.5.2.0 4046 0 30432 6795 3 1 1173154 4096001 0 65024 0 0 n/a 4128 2811 http://s1cern-cvmfs.openhtc.io/cvmfs/alice.cern.ch DIRECT 1 2020-06-14 19:03:33 (18284): Guest Log: 19:03:14 CEST +02:00 2020-06-14: cranky: [INFO] Checking runc. 2020-06-14 19:03:33 (18284): Guest Log: 19:03:14 CEST +02:00 2020-06-14: cranky: [INFO] Creating the filesystem. 2020-06-14 19:03:33 (18284): Guest Log: 19:03:14 CEST +02:00 2020-06-14: cranky: [INFO] Using /cvmfs/cernvm-prod.cern.ch/cvm3 2020-06-14 19:03:33 (18284): Guest Log: 19:03:14 CEST +02:00 2020-06-14: cranky: [INFO] Updating config.json. 2020-06-14 19:03:33 (18284): Guest Log: 19:03:14 CEST +02:00 2020-06-14: cranky: [INFO] Running Container 'runc'. 2020-06-14 19:03:34 (18284): Guest Log: job: htmld=/shared/html/job 2020-06-14 19:03:34 (18284): Guest Log: job: unpack exitcode=0 2020-06-14 19:03:36 (18284): Guest Log: 19:03:16 CEST +02:00 2020-06-14: cranky: [INFO] ===> [runRivet] Sun Jun 14 17:03:15 UTC 2020 [boinc pp winclusive 7000 20 - herwig++ 2.7.1 default 100000 14] 2020-06-14 19:06:14 (18284): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-14 19:06:24 (18284): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-14 19:22:18 (18284): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-14 19:22:27 (18284): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-14 19:32:19 (18284): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-14 19:32:28 (18284): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-14 19:37:19 (18284): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-14 19:37:29 (18284): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-14 19:43:47 (18284): Stopping VM. 2020-06-14 19:43:48 (18284): Error in stop VM for VM: -108 Command: VBoxManage -q controlvm "boinc_09ab3b7ed29b8370" savestate Output: 2020-06-14 19:43:48 (18284): VM did not stop when requested. 2020-06-14 19:43:48 (18284): VM was NOT successfully terminated. 2020-06-16 13:55:35 (1336): Detected: vboxwrapper 26197 2020-06-16 13:55:35 (1336): Detected: BOINC client v7.7 2020-06-16 13:55:37 (1336): Detected: VirtualBox VboxManage Interface (Version: 6.1.4) 2020-06-16 13:55:37 (1336): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds) 2020-06-16 13:55:38 (1336): Starting VM using VBoxManage interface. (boinc_09ab3b7ed29b8370, slot#0) 2020-06-16 13:55:46 (1336): Successfully started VM. (PID = '12364') 2020-06-16 13:55:46 (1336): Reporting VM Process ID to BOINC. 2020-06-16 13:55:46 (1336): Guest Log: BIOS: VirtualBox 6.1.4 2020-06-16 13:55:46 (1336): Guest Log: CPUID EDX: 0x178bfbff 2020-06-16 13:55:46 (1336): Guest Log: BIOS: ata0-0: PCHS=16383/16/63 LCHS=1024/255/63 2020-06-16 13:55:46 (1336): VM state change detected. (old = 'PoweredOff', new = 'Running') 2020-06-16 13:55:46 (1336): Detected: Web Application Enabled (http://localhost:58168) 2020-06-16 13:55:47 (1336): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-16 13:55:56 (1336): Status Report: Job Duration: '864000.000000' 2020-06-16 13:55:56 (1336): Status Report: Elapsed Time: '21510.110825' 2020-06-16 13:55:56 (1336): Status Report: CPU Time: '21002.656250' 2020-06-16 13:55:56 (1336): Preference change detected 2020-06-16 13:55:56 (1336): Setting CPU throttle for VM. (100%) 2020-06-16 13:55:57 (1336): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds)) 2020-06-16 13:55:57 (1336): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-16 13:55:58 (1336): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032 2020-06-16 13:55:58 (1336): Guest Log: BIOS: Booting from Hard Disk... 2020-06-16 13:56:00 (1336): Guest Log: BIOS: KBD: unsupported int 16h function 03 2020-06-16 13:56:00 (1336): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 2020-06-16 13:56:17 (1336): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-16 13:56:37 (1336): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-16 13:56:50 (1336): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds 2020-06-16 13:56:50 (1336): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000) 2020-06-16 13:56:57 (1336): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log 2020-06-16 13:56:57 (1336): Guest Log: 00:00:00.000286 main Log opened 2020-06-16T11:56:58.263395000Z 2020-06-16 13:56:57 (1336): Guest Log: 00:00:00.000505 main OS Product: Linux 2020-06-16 13:56:57 (1336): Guest Log: 00:00:00.000591 main OS Release: 4.14.76-13.cernvm.x86_64 2020-06-16 13:56:57 (1336): Guest Log: 00:00:00.000668 main OS Version: #1 SMP Tue Oct 16 18:26:15 CEST 2018 2020-06-16 13:56:57 (1336): Guest Log: 00:00:00.002183 main Executable: /usr/sbin/VBoxService 2020-06-16 13:56:57 (1336): Guest Log: 00:00:00.002187 main Process ID: 3216 2020-06-16 13:56:57 (1336): Guest Log: 00:00:00.002189 main Package type: LINUX_64BITS_GENERIC 2020-06-16 13:56:57 (1336): Guest Log: 00:00:00.007567 main 5.2.6 r120293 started. Verbose level = 0 2020-06-16 13:57:26 (1336): Guest Log: 13:57:26 CEST +02:00 2020-06-16: cranky: [INFO] Detected Theory App 2020-06-16 13:57:26 (1336): Guest Log: 13:57:26 CEST +02:00 2020-06-16: cranky: [INFO] Checking CVMFS. 2020-06-16 13:57:27 (1336): Guest Log: Probing /cvmfs/sft.cern.ch... OK 2020-06-16 13:57:28 (1336): 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 13:57:28 (1336): Guest Log: 2.5.2.0 4080 0 28392 18244 3 1 1190533 4096001 0 65024 0 0 n/a 5 2 http://s1cern-cvmfs.openhtc.io/cvmfs/sft.cern.ch DIRECT 1 2020-06-16 13:57:29 (1336): Guest Log: Probing /cvmfs/grid.cern.ch... OK 2020-06-16 13:57:30 (1336): 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 13:57:30 (1336): Guest Log: 2.5.2.0 4054 0 28348 12491 3 2 1190533 4096001 0 65024 0 2 0 4273 3026 http://s1cern-cvmfs.openhtc.io/cvmfs/grid.cern.ch DIRECT 1 2020-06-16 13:57:32 (1336): Guest Log: Probing /cvmfs/cernvm-prod.cern.ch... OK 2020-06-16 13:57:33 (1336): 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 13:57:33 (1336): Guest Log: 2.5.2.0 4101 0 25148 252 3 1 1190533 4096001 0 65024 0 0 n/a 0 0 http://s1cern-cvmfs.openhtc.io/cvmfs/cernvm-prod.cern.ch DIRECT 1 2020-06-16 13:57:34 (1336): Guest Log: Probing /cvmfs/alice.cern.ch... OK 2020-06-16 13:57:35 (1336): 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 13:57:35 (1336): Guest Log: 2.5.2.0 4125 0 28896 6805 3 1 1190533 4096001 0 65024 0 0 n/a 4133 1227 http://s1cern-cvmfs.openhtc.io/cvmfs/alice.cern.ch DIRECT 1 2020-06-16 13:57:35 (1336): Guest Log: 13:57:35 CEST +02:00 2020-06-16: cranky: [INFO] Checking runc. 2020-06-16 13:57:35 (1336): Guest Log: 13:57:35 CEST +02:00 2020-06-16: cranky: [INFO] Creating the filesystem. 2020-06-16 13:57:35 (1336): Guest Log: 13:57:35 CEST +02:00 2020-06-16: cranky: [INFO] Using /cvmfs/cernvm-prod.cern.ch/cvm3 2020-06-16 13:57:35 (1336): Guest Log: 13:57:36 CEST +02:00 2020-06-16: cranky: [INFO] Updating config.json. 2020-06-16 13:57:35 (1336): Guest Log: 13:57:36 CEST +02:00 2020-06-16: cranky: [INFO] Running Container 'runc'. 2020-06-16 13:57:36 (1336): Guest Log: job: htmld=/shared/html/job 2020-06-16 13:57:36 (1336): Guest Log: job: unpack exitcode=0 2020-06-16 13:57:37 (1336): Guest Log: 13:57:38 CEST +02:00 2020-06-16: cranky: [INFO] ===> [runRivet] Tue Jun 16 11:57:36 UTC 2020 [boinc pp winclusive 7000 20 - herwig++ 2.7.1 default 100000 14] 2020-06-16 14:00:07 (1336): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-16 14:00:18 (1336): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-16 14:07:26 (1336): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-16 14:07:27 (1336): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-16 14:07:30 (1336): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-16 14:07:40 (1336): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-16 14:09:11 (1336): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-16 14:09:21 (1336): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-16 14:13:31 (1336): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-16 14:13:41 (1336): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-16 14:47:27 (1336): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-16 14:49:39 (1336): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-16 15:38:34 (1336): Guest Log: 01:38:40.935718 timesync vgsvcTimeSyncWorker: Radical host time change: 1 948 036 000 000ns (HostNow=1 592 314 708 948 000 000 ns HostLast=1 592 312 760 912 000 000 ns) 2020-06-16 15:38:34 (1336): Guest Log: 01:38:41.369236 timesync vgsvcTimeSyncWorker: Radical guest time change: 1 949 010 767 000ns (GuestNow=1 592 314 539 141 911 000 ns GuestLast=1 592 312 590 131 144 000 ns fSetTimeLastLoop=false) 2020-06-16 15:40:06 (1336): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-16 15:40:32 (1336): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-16 16:11:48 (1336): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-16 16:11:58 (1336): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-16 16:16:19 (1336): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-16 16:17:29 (1336): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-16 16:17:48 (1336): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-16 16:17:59 (1336): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-16 16:19:06 (1336): Status Report: Job Duration: '864000.000000' 2020-06-16 16:19:06 (1336): Status Report: Elapsed Time: '27510.712223' 2020-06-16 16:19:06 (1336): Status Report: CPU Time: '26955.093750' 2020-06-16 18:19:03 (1336): Guest Log: 04:17:23.336649 timesync vgsvcTimeSyncWorker: Radical host time change: 6 624 374 000 000ns (HostNow=1 592 324 342 883 000 000 ns HostLast=1 592 317 718 509 000 000 ns) 2020-06-16 18:19:03 (1336): Guest Log: 04:17:23.658550 timesync vgsvcTimeSyncWorker: Radical guest time change: 6 600 543 783 000ns (GuestNow=1 592 324 061 599 743 000 ns GuestLast=1 592 317 461 055 960 000 ns fSetTimeLastLoop=false) 2020-06-16 18:19:17 (1336): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-16 18:19:34 (1336): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-16 18:20:15 (1336): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-16 18:20:35 (1336): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-16 18:21:58 (1336): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-16 18:22:06 (1336): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-16 18:40:59 (1336): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-16 18:41:09 (1336): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-16 18:49:52 (1336): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-16 18:50:02 (1336): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-16 19:28:12 (1336): Guest Log: job: run exitcode=0 2020-06-16 19:28:12 (1336): Guest Log: job: diskusage=3028 2020-06-16 19:28:12 (1336): Guest Log: job: logsize=676 k 2020-06-16 19:28:12 (1336): Guest Log: job: times= 2020-06-16 19:28:12 (1336): Guest Log: 0m0.005s 0m0.018s 2020-06-16 19:28:12 (1336): Guest Log: 314m28.966s 2m30.130s 2020-06-16 19:28:12 (1336): Guest Log: job: cpuusage=19019 2020-06-16 19:28:12 (1336): Guest Log: 19:22:31 CEST +02:00 2020-06-16: cranky: [INFO] Container 'runc' finished with status code 0. 2020-06-16 19:28:12 (1336): Guest Log: 19:22:31 CEST +02:00 2020-06-16: cranky: [INFO] Preparing output. 2020-06-16 19:28:14 (1336): Guest Log: [INFO] Job Finished 2020-06-16 19:28:14 (1336): Guest Log: [INFO] Shutting Down. 2020-06-16 19:28:14 (1336): VM Completion File Detected. 2020-06-16 19:28:14 (1336): VM Completion Message: Job Finished . 2020-06-16 19:28:14 (1336): Powering off VM. 2020-06-16 19:33:15 (1336): VM did not power off when requested. 2020-06-16 19:33:15 (1336): VM was successfully terminated. 2020-06-16 19:33:15 (1336): Deregistering VM. (boinc_09ab3b7ed29b8370, slot#0) 2020-06-16 19:33:15 (1336): Removing network bandwidth throttl
©2024 CERN