| Name | Theory_2390-1119854-14_1 |
| Workunit | 140777245 |
| Created | 4 Jun 2020, 11:50:51 UTC |
| Sent | 4 Jun 2020, 11:55:25 UTC |
| Report deadline | 15 Jun 2020, 11:55:25 UTC |
| Received | 16 Jun 2020, 17:35:49 UTC |
| Server state | Over |
| Outcome | Success |
| Client state | Done |
| Exit status | 0 (0x00000000) |
| Computer ID | 10623858 |
| Run time | 10 hours 11 min 10 sec |
| CPU time | 9 hours 42 min 25 sec |
| Validate state | Valid |
| Credit | 241.12 |
| Device peak FLOPS | 2.84 GFLOPS |
| Application version | Theory Simulation v300.06 (vbox64_theory) windows_x86_64 |
| Peak working set size | 60.22 MB |
| Peak swap size | 57.69 MB |
| Peak disk usage | 3.15 GB |
Stderr output
<core_client_version>7.14.2</core_client_version> <![CDATA[ <stderr_txt> 92 251 3 1 1058172 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:45 (17140): Guest Log: Probing /cvmfs/alice.cern.ch... OK 2020-06-08 14:05:46 (17140): 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:46 (17140): Guest Log: 2.5.2.0 4101 0 29532 6758 3 1 1058172 4096001 0 65024 0 0 n/a 4111 1603 http://s1cern-cvmfs.openhtc.io/cvmfs/alice.cern.ch DIRECT 1 2020-06-08 14:05:46 (17140): Guest Log: 14:05:47 CEST +02:00 2020-06-08: cranky: [INFO] Checking runc. 2020-06-08 14:05:46 (17140): Guest Log: 14:05:47 CEST +02:00 2020-06-08: cranky: [INFO] Creating the filesystem. 2020-06-08 14:05:46 (17140): Guest Log: 14:05:48 CEST +02:00 2020-06-08: cranky: [INFO] Using /cvmfs/cernvm-prod.cern.ch/cvm3 2020-06-08 14:05:47 (17140): Guest Log: 14:05:48 CEST +02:00 2020-06-08: cranky: [INFO] Updating config.json. 2020-06-08 14:05:47 (17140): Guest Log: 14:05:48 CEST +02:00 2020-06-08: cranky: [INFO] Running Container 'runc'. 2020-06-08 14:05:48 (17140): Guest Log: job: htmld=/shared/html/job 2020-06-08 14:05:48 (17140): Guest Log: job: unpack exitcode=0 2020-06-08 14:05:49 (17140): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-08 14:05:59 (17140): Guest Log: 14:05:50 CEST +02:00 2020-06-08: cranky: [INFO] ===> [runRivet] Mon Jun 8 12:05:49 UTC 2020 [boinc pp jets 7000 400 - pythia6 6.425 pnocr 100000 14] 2020-06-08 14:05:59 (17140): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-08 14:07:09 (17140): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-08 14:07:19 (17140): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-08 14:08:49 (17140): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-08 14:09:09 (17140): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-08 14:09:39 (17140): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-08 14:09:49 (17140): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-08 14:10:51 (17140): Stopping VM. 2020-06-08 14:10:52 (17140): Error in stop VM for VM: -108 Command: VBoxManage -q controlvm "boinc_cdf5bee2dcb71a42" savestate Output: 2020-06-08 14:10:52 (17140): VM did not stop when requested. 2020-06-08 14:10:52 (17140): VM was NOT successfully terminated. 2020-06-09 18:15:38 (9824): Detected: vboxwrapper 26197 2020-06-09 18:15:39 (9824): Detected: BOINC client v7.7 2020-06-09 18:15:52 (9824): Detected: VirtualBox VboxManage Interface (Version: 6.1.4) 2020-06-09 18:15:52 (9824): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds) 2020-06-09 18:15:53 (9824): ERROR: VM failed to start 2020-06-09 18:15:58 (9824): 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 (8452): Detected: vboxwrapper 26197 2020-06-09 18:15:58 (8452): Detected: BOINC client v7.7 2020-06-09 18:15:59 (8452): Detected: VirtualBox VboxManage Interface (Version: 6.1.4) 2020-06-09 18:15:59 (8452): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds) 2020-06-09 18:15:59 (8452): Starting VM using VBoxManage interface. (boinc_cdf5bee2dcb71a42, slot#2) 2020-06-09 18:16:06 (8452): Successfully started VM. (PID = '7064') 2020-06-09 18:16:06 (8452): Reporting VM Process ID to BOINC. 2020-06-09 18:16:06 (8452): Guest Log: BIOS: VirtualBox 6.1.4 2020-06-09 18:16:06 (8452): Guest Log: CPUID EDX: 0x178bfbff 2020-06-09 18:16:06 (8452): Guest Log: BIOS: ata0-0: PCHS=16383/16/63 LCHS=1024/255/63 2020-06-09 18:16:06 (8452): VM state change detected. (old = 'PoweredOff', new = 'Running') 2020-06-09 18:16:06 (8452): Detected: Web Application Enabled (http://localhost:56760) 2020-06-09 18:16:07 (8452): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-09 18:16:15 (8452): Preference change detected 2020-06-09 18:16:15 (8452): Setting CPU throttle for VM. (100%) 2020-06-09 18:16:16 (8452): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds)) 2020-06-09 18:16:16 (8452): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-09 18:16:17 (8452): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032 2020-06-09 18:16:17 (8452): Guest Log: BIOS: Booting from Hard Disk... 2020-06-09 18:16:19 (8452): Guest Log: BIOS: KBD: unsupported int 16h function 03 2020-06-09 18:16:19 (8452): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 2020-06-09 18:16:26 (8452): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-09 18:16:36 (8452): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-09 18:16:46 (8452): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-09 18:17:05 (8452): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-09 18:17:16 (8452): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds 2020-06-09 18:17:16 (8452): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000) 2020-06-09 18:17:16 (8452): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-09 18:17:26 (8452): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-09 18:17:31 (8452): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log 2020-06-09 18:17:31 (8452): Guest Log: 00:00:00.000120 main Log opened 2020-06-09T16:17:04.205221000Z 2020-06-09 18:17:31 (8452): Guest Log: 00:00:00.000582 main OS Product: Linux 2020-06-09 18:17:31 (8452): Guest Log: 00:00:00.000638 main OS Release: 4.14.76-13.cernvm.x86_64 2020-06-09 18:17:31 (8452): Guest Log: 00:00:00.000677 main OS Version: #1 SMP Tue Oct 16 18:26:15 CEST 2018 2020-06-09 18:17:31 (8452): Guest Log: 00:00:00.000713 main Executable: /usr/sbin/VBoxService 2020-06-09 18:17:31 (8452): Guest Log: 00:00:00.000714 main Process ID: 3216 2020-06-09 18:17:31 (8452): Guest Log: 00:00:00.000715 main Package type: LINUX_64BITS_GENERIC 2020-06-09 18:17:31 (8452): Guest Log: 00:00:00.004157 main 5.2.6 r120293 started. Verbose level = 0 2020-06-09 18:18:02 (8452): Guest Log: 18:17:36 CEST +02:00 2020-06-09: cranky: [INFO] Detected Theory App 2020-06-09 18:18:02 (8452): Guest Log: 18:17:36 CEST +02:00 2020-06-09: cranky: [INFO] Checking CVMFS. 2020-06-09 18:18:04 (8452): Guest Log: Probing /cvmfs/sft.cern.ch... OK 2020-06-09 18:18:04 (8452): 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:04 (8452): Guest Log: 2.5.2.0 4053 0 26536 18210 3 1 1075754 4096001 0 65024 0 0 n/a 5 3 http://s1ral-cvmfs.openhtc.io/cvmfs/sft.cern.ch DIRECT 1 2020-06-09 18:18:06 (8452): Guest Log: Probing /cvmfs/grid.cern.ch... OK 2020-06-09 18:18:06 (8452): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-09 18:18:16 (8452): 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:16 (8452): Guest Log: 2.5.2.0 4127 0 25524 12439 3 2 1075754 4096001 0 65024 0 2 0 4273 1000 http://s1cern-cvmfs.openhtc.io/cvmfs/grid.cern.ch DIRECT 1 2020-06-09 18:18:16 (8452): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-09 18:18:17 (8452): Guest Log: Probing /cvmfs/cernvm-prod.cern.ch... OK 2020-06-09 18:18:18 (8452): 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:18 (8452): Guest Log: 2.5.2.0 4084 0 27224 252 3 1 1075754 4096001 0 65024 0 0 n/a 4 5 http://s1fnal-cvmfs.openhtc.io/cvmfs/cernvm-prod.cern.ch DIRECT 1 2020-06-09 18:18:19 (8452): Guest Log: Probing /cvmfs/alice.cern.ch... OK 2020-06-09 18:18:20 (8452): 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:20 (8452): Guest Log: 2.5.2.0 4106 0 30508 6766 3 1 1075754 4096001 0 65024 0 0 n/a 4114 988 http://s1cern-cvmfs.openhtc.io/cvmfs/alice.cern.ch DIRECT 1 2020-06-09 18:18:20 (8452): Guest Log: 18:17:44 CEST +02:00 2020-06-09: cranky: [INFO] Checking runc. 2020-06-09 18:18:20 (8452): Guest Log: 18:17:44 CEST +02:00 2020-06-09: cranky: [INFO] Creating the filesystem. 2020-06-09 18:18:20 (8452): 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 (8452): Guest Log: 18:17:44 CEST +02:00 2020-06-09: cranky: [INFO] Updating config.json. 2020-06-09 18:18:21 (8452): Guest Log: 18:17:44 CEST +02:00 2020-06-09: cranky: [INFO] Running Container 'runc'. 2020-06-09 18:18:21 (8452): Guest Log: job: htmld=/shared/html/job 2020-06-09 18:18:21 (8452): Guest Log: job: unpack exitcode=0 2020-06-09 18:18:23 (8452): Guest Log: 18:17:46 CEST +02:00 2020-06-09: cranky: [INFO] ===> [runRivet] Tue Jun 9 16:17:45 UTC 2020 [boinc pp jets 7000 400 - pythia6 6.425 pnocr 100000 14] 2020-06-09 18:18:36 (8452): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-09 18:18:46 (8452): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-09 18:21:37 (8452): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-09 18:21:47 (8452): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-09 18:24:18 (8452): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-09 18:24:28 (8452): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-09 18:32:39 (8452): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-09 18:32:49 (8452): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-09 18:38:40 (8452): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-09 18:38:50 (8452): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-09 18:40:58 (8452): Status Report: Job Duration: '864000.000000' 2020-06-09 18:40:58 (8452): Status Report: Elapsed Time: '6000.061745' 2020-06-09 18:40:58 (8452): Status Report: CPU Time: '5379.546875' 2020-06-09 18:45:01 (8452): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-09 18:45:11 (8452): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-09 18:46:01 (8452): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-09 18:46:11 (8452): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-09 18:53:42 (8452): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-09 18:53:52 (8452): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-09 19:09:10 (8452): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-09 19:09:55 (8452): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-09 19:15:11 (8452): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-09 19:16:17 (8452): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-09 19:23:03 (8452): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-09 19:23:09 (8452): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-09 19:37:18 (8452): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-09 19:37:19 (8452): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-09 19:51:02 (8452): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-09 19:51:04 (8452): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-09 19:52:39 (8452): Stopping VM. 2020-06-09 20:02:35 (8180): Detected: vboxwrapper 26197 2020-06-09 20:02:35 (8180): Detected: BOINC client v7.7 2020-06-09 20:02:36 (8180): Detected: VirtualBox VboxManage Interface (Version: 6.1.4) 2020-06-09 20:02:36 (8180): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds) 2020-06-09 20:02:37 (8180): Starting VM using VBoxManage interface. (boinc_cdf5bee2dcb71a42, slot#2) 2020-06-09 20:02:46 (8180): Successfully started VM. (PID = '17784') 2020-06-09 20:02:46 (8180): Reporting VM Process ID to BOINC. 2020-06-09 20:02:46 (8180): Guest Log: BIOS: VirtualBox 6.1.4 2020-06-09 20:02:46 (8180): Guest Log: CPUID EDX: 0x178bfbff 2020-06-09 20:02:46 (8180): Guest Log: BIOS: ata0-0: PCHS=16383/16/63 LCHS=1024/255/63 2020-06-09 20:02:46 (8180): VM state change detected. (old = 'PoweredOff', new = 'Running') 2020-06-09 20:02:46 (8180): Detected: Web Application Enabled (http://localhost:56760) 2020-06-09 20:02:46 (8180): Status Report: Job Duration: '864000.000000' 2020-06-09 20:02:46 (8180): Status Report: Elapsed Time: '9815.602244' 2020-06-09 20:02:46 (8180): Status Report: CPU Time: '9387.265625' 2020-06-09 20:02:46 (8180): Preference change detected 2020-06-09 20:02:46 (8180): Setting CPU throttle for VM. (100%) 2020-06-09 20:02:47 (8180): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds)) 2020-06-09 20:02:48 (8180): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032 2020-06-09 20:02:48 (8180): Guest Log: BIOS: Booting from Hard Disk... 2020-06-09 20:02:52 (8180): Guest Log: BIOS: KBD: unsupported int 16h function 03 2020-06-09 20:02:52 (8180): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 2020-06-09 20:03:16 (8180): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds 2020-06-09 20:03:22 (8180): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000) 2020-06-09 20:03:25 (8180): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log 2020-06-09 20:03:25 (8180): Guest Log: 00:00:00.000191 main Log opened 2020-06-09T18:03:24.547837000Z 2020-06-09 20:03:25 (8180): Guest Log: 00:00:00.000446 main OS Product: Linux 2020-06-09 20:03:25 (8180): Guest Log: 00:00:00.000532 main OS Release: 4.14.76-13.cernvm.x86_64 2020-06-09 20:03:25 (8180): Guest Log: 00:00:00.000627 main OS Version: #1 SMP Tue Oct 16 18:26:15 CEST 2018 2020-06-09 20:03:25 (8180): Guest Log: 00:00:00.000688 main Executable: /usr/sbin/VBoxService 2020-06-09 20:03:25 (8180): Guest Log: 00:00:00.000689 main Process ID: 3215 2020-06-09 20:03:25 (8180): Guest Log: 00:00:00.000691 main Package type: LINUX_64BITS_GENERIC 2020-06-09 20:03:25 (8180): Guest Log: 00:00:00.013032 main 5.2.6 r120293 started. Verbose level = 0 2020-06-09 20:03:55 (8180): Guest Log: 20:03:57 CEST +02:00 2020-06-09: cranky: [INFO] Detected Theory App 2020-06-09 20:03:56 (8180): Guest Log: 20:03:57 CEST +02:00 2020-06-09: cranky: [INFO] Checking CVMFS. 2020-06-09 20:03:57 (8180): Guest Log: Probing /cvmfs/sft.cern.ch... OK 2020-06-09 20:03:58 (8180): 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 20:03:58 (8180): Guest Log: 2.5.2.0 4103 0 28180 18211 3 1 1085014 4096001 0 65024 0 0 n/a 5 14 http://s1cern-cvmfs.openhtc.io/cvmfs/sft.cern.ch DIRECT 1 2020-06-09 20:04:01 (8180): Guest Log: Probing /cvmfs/grid.cern.ch... OK 2020-06-09 20:04:01 (8180): 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 20:04:01 (8180): Guest Log: 2.5.2.0 4124 0 25440 12439 3 2 1085014 4096001 0 65024 0 2 100 0 0 http://s1cern-cvmfs.openhtc.io/cvmfs/grid.cern.ch DIRECT 1 2020-06-09 20:04:02 (8180): Guest Log: Probing /cvmfs/cernvm-prod.cern.ch... OK 2020-06-09 20:04:05 (8180): 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 20:04:05 (8180): Guest Log: 2.5.2.0 4057 0 27204 252 3 1 1085014 4096001 0 65024 0 0 n/a 0 0 http://s1ihep-cvmfs.openhtc.io/cvmfs/cernvm-prod.cern.ch DIRECT 1 2020-06-09 20:04:05 (8180): Guest Log: Probing /cvmfs/alice.cern.ch... OK 2020-06-09 20:04:06 (8180): 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 20:04:06 (8180): Guest Log: 2.5.2.0 4078 0 29116 6767 3 1 1085014 4096001 0 65024 0 0 n/a 4117 3211 http://s1cern-cvmfs.openhtc.io/cvmfs/alice.cern.ch DIRECT 1 2020-06-09 20:04:06 (8180): Guest Log: 20:04:08 CEST +02:00 2020-06-09: cranky: [INFO] Checking runc. 2020-06-09 20:04:06 (8180): Guest Log: 20:04:08 CEST +02:00 2020-06-09: cranky: [INFO] Creating the filesystem. 2020-06-09 20:04:07 (8180): Guest Log: 20:04:08 CEST +02:00 2020-06-09: cranky: [INFO] Using /cvmfs/cernvm-prod.cern.ch/cvm3 2020-06-09 20:04:07 (8180): Guest Log: 20:04:08 CEST +02:00 2020-06-09: cranky: [INFO] Updating config.json. 2020-06-09 20:04:07 (8180): Guest Log: 20:04:08 CEST +02:00 2020-06-09: cranky: [INFO] Running Container 'runc'. 2020-06-09 20:04:08 (8180): Guest Log: job: htmld=/shared/html/job 2020-06-09 20:04:08 (8180): Guest Log: job: unpack exitcode=0 2020-06-09 20:04:10 (8180): Guest Log: 20:04:10 CEST +02:00 2020-06-09: cranky: [INFO] ===> [runRivet] Tue Jun 9 18:04:10 UTC 2020 [boinc pp jets 7000 400 - pythia6 6.425 pnocr 100000 14] 2020-06-09 20:09:23 (8180): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-09 20:09:33 (8180): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-09 20:19:05 (8180): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-09 20:19:07 (8180): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-09 20:20:33 (8180): Stopping VM. 2020-06-09 20:20:33 (8180): Error in stop VM for VM: -108 Command: VBoxManage -q controlvm "boinc_cdf5bee2dcb71a42" savestate Output: 2020-06-09 20:20:33 (8180): VM did not stop when requested. 2020-06-09 20:20:33 (8180): VM was NOT successfully terminated. 2020-06-11 15:02:28 (10976): Detected: vboxwrapper 26197 2020-06-11 15:02:28 (10976): Detected: BOINC client v7.7 2020-06-11 15:02:30 (10976): Detected: VirtualBox VboxManage Interface (Version: 6.1.4) 2020-06-11 15:02:30 (10976): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds) 2020-06-11 15:02:31 (10976): Starting VM using VBoxManage interface. (boinc_cdf5bee2dcb71a42, slot#2) 2020-06-11 15:02:44 (10976): Successfully started VM. (PID = '18860') 2020-06-11 15:02:44 (10976): Reporting VM Process ID to BOINC. 2020-06-11 15:02:44 (10976): Guest Log: BIOS: VirtualBox 6.1.4 2020-06-11 15:02:44 (10976): Guest Log: CPUID EDX: 0x178bfbff 2020-06-11 15:02:44 (10976): Guest Log: BIOS: ata0-0: PCHS=16383/16/63 LCHS=1024/255/63 2020-06-11 15:02:44 (10976): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032 2020-06-11 15:02:44 (10976): Guest Log: BIOS: Booting from Hard Disk... 2020-06-11 15:02:44 (10976): VM state change detected. (old = 'PoweredOff', new = 'Running') 2020-06-11 15:02:44 (10976): Detected: Web Application Enabled (http://localhost:56760) 2020-06-11 15:03:01 (10976): Guest Log: BIOS: KBD: unsupported int 16h function 03 2020-06-11 15:03:01 (10976): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 2020-06-11 15:03:02 (10976): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-11 15:03:23 (10976): Status Report: Job Duration: '864000.000000' 2020-06-11 15:03:23 (10976): Status Report: Elapsed Time: '10707.082697' 2020-06-11 15:03:23 (10976): Status Report: CPU Time: '10317.281250' 2020-06-11 15:03:23 (10976): Preference change detected 2020-06-11 15:03:23 (10976): Setting CPU throttle for VM. (100%) 2020-06-11 15:03:24 (10976): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds)) 2020-06-11 15:03:24 (10976): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-11 15:03:34 (10976): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-11 15:03:54 (10976): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-11 15:04:01 (10976): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds 2020-06-11 15:04:01 (10976): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000) 2020-06-11 15:04:04 (10976): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-11 15:04:14 (10976): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-11 15:04:20 (10976): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log 2020-06-11 15:04:20 (10976): Guest Log: 00:00:00.000244 main Log opened 2020-06-11T13:04:11.903948000Z 2020-06-11 15:04:20 (10976): Guest Log: 00:00:00.002603 main OS Product: Linux 2020-06-11 15:04:20 (10976): Guest Log: 00:00:00.002956 main OS Release: 4.14.76-13.cernvm.x86_64 2020-06-11 15:04:20 (10976): Guest Log: 00:00:00.003039 main OS Version: #1 SMP Tue Oct 16 18:26:15 CEST 2018 2020-06-11 15:04:20 (10976): Guest Log: 00:00:00.003109 main Executable: /usr/sbin/VBoxService 2020-06-11 15:04:20 (10976): Guest Log: 00:00:00.003111 main Process ID: 3218 2020-06-11 15:04:20 (10976): Guest Log: 00:00:00.003113 main Package type: LINUX_64BITS_GENERIC 2020-06-11 15:04:20 (10976): Guest Log: 00:00:00.014187 main 5.2.6 r120293 started. Verbose level = 0 2020-06-11 15:04:53 (10976): Guest Log: 15:04:46 CEST +02:00 2020-06-11: cranky: [INFO] Detected Theory App 2020-06-11 15:04:53 (10976): Guest Log: 15:04:46 CEST +02:00 2020-06-11: cranky: [INFO] Checking CVMFS. 2020-06-11 15:04:54 (10976): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-11 15:05:04 (10976): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-11 15:05:05 (10976): Guest Log: Probing /cvmfs/sft.cern.ch... OK 2020-06-11 15:05:06 (10976): 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:06 (10976): Guest Log: 2.5.2.0 4060 0 28000 18219 3 1 1102367 4096001 0 65024 0 0 n/a 5 5 http://s1fnal-cvmfs.openhtc.io/cvmfs/sft.cern.ch DIRECT 1 2020-06-11 15:05:08 (10976): Guest Log: Probing /cvmfs/grid.cern.ch... OK 2020-06-11 15:05:10 (10976): 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:10 (10976): Guest Log: 2.5.2.0 4105 0 27540 12455 3 2 1102367 4096001 0 65024 0 2 0 4273 1944 http://s1cern-cvmfs.openhtc.io/cvmfs/grid.cern.ch DIRECT 1 2020-06-11 15:05:11 (10976): Guest Log: Probing /cvmfs/cernvm-prod.cern.ch... OK 2020-06-11 15:05:12 (10976): 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:12 (10976): Guest Log: 2.5.2.0 4083 0 25376 252 3 1 1102367 4096001 0 65024 0 0 n/a 0 0 http://s1asgc-cvmfs.openhtc.io:8080/cvmfs/cernvm-prod.cern.ch DIRECT 1 2020-06-11 15:05:14 (10976): Guest Log: Probing /cvmfs/alice.cern.ch... OK 2020-06-11 15:05:22 (10976): 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 (10976): Guest Log: 2.5.2.0 4133 0 28244 6776 3 1 1102367 4096001 0 65024 0 0 n/a 4121 1544 http://s1cern-cvmfs.openhtc.io/cvmfs/alice.cern.ch DIRECT 1 2020-06-11 15:05:22 (10976): Guest Log: 15:04:58 CEST +02:00 2020-06-11: cranky: [INFO] Checking runc. 2020-06-11 15:05:22 (10976): Guest Log: 15:04:58 CEST +02:00 2020-06-11: cranky: [INFO] Creating the filesystem. 2020-06-11 15:05:22 (10976): Guest Log: 15:04:58 CEST +02:00 2020-06-11: cranky: [INFO] Using /cvmfs/cernvm-prod.cern.ch/cvm3 2020-06-11 15:05:22 (10976): Guest Log: 15:04:59 CEST +02:00 2020-06-11: cranky: [INFO] Updating config.json. 2020-06-11 15:05:22 (10976): Guest Log: 15:04:59 CEST +02:00 2020-06-11: cranky: [INFO] Running Container 'runc'. 2020-06-11 15:05:22 (10976): Guest Log: job: htmld=/shared/html/job 2020-06-11 15:05:22 (10976): Guest Log: job: unpack exitcode=0 2020-06-11 15:05:22 (10976): Guest Log: 15:05:01 CEST +02:00 2020-06-11: cranky: [INFO] ===> [runRivet] Thu Jun 11 13:05:00 UTC 2020 [boinc pp jets 7000 400 - pythia6 6.425 pnocr 100000 14] 2020-06-11 15:05:22 (10976): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-11 15:05:34 (10976): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-11 15:05:47 (10976): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-11 15:06:05 (10976): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-11 15:06:14 (10976): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-11 15:06:24 (10976): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-11 15:06:47 (10976): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-11 15:07:56 (10976): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-11 15:08:07 (10976): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-11 15:08:26 (10976): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-11 15:08:36 (10976): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-11 15:08:55 (10976): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-11 15:09:05 (10976): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-11 15:09:15 (10976): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-11 15:09:26 (10976): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-11 15:09:36 (10976): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-11 15:09:56 (10976): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-11 15:10:06 (10976): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-11 15:10:16 (10976): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-11 15:10:46 (10976): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-11 15:22:42 (10976): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-11 15:22:50 (10976): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-11 15:25:13 (10976): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-11 15:25:14 (10976): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-11 15:33:27 (10976): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-11 15:33:28 (10976): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-11 15:48:42 (10976): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-11 15:48:58 (10976): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-11 15:49:08 (10976): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-11 15:49:18 (10976): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-11 15:51:59 (10976): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-11 15:52:09 (10976): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-11 15:54:22 (10976): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-11 15:54:29 (10976): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-11 15:54:49 (10976): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-11 15:55:00 (10976): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-11 15:58:11 (10976): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-11 15:58:20 (10976): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-11 16:03:31 (10976): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-11 16:03:42 (10976): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-11 16:03:52 (10976): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-11 16:04:02 (10976): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-11 18:23:57 (10976): Guest Log: 03:14:44.013992 timesync vgsvcTimeSyncWorker: Radical host time change: 7 547 573 000 000ns (HostNow=1 591 892 633 599 000 000 ns HostLast=1 591 885 086 026 000 000 ns) 2020-06-11 18:23:57 (10976): Guest Log: 03:14:44.052252 timesync vgsvcTimeSyncWorker: Radical guest time change: 7 551 349 167 000ns (GuestNow=1 591 892 335 819 136 000 ns GuestLast=1 591 884 784 469 969 000 ns fSetTimeLastLoop=false) 2020-06-11 18:23:58 (10976): Stopping VM. 2020-06-13 15:53:48 (13248): Detected: vboxwrapper 26197 2020-06-13 15:53:48 (13248): Detected: BOINC client v7.7 2020-06-13 15:53:50 (13248): Detected: VirtualBox VboxManage Interface (Version: 6.1.4) 2020-06-13 15:53:50 (13248): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds) 2020-06-13 15:53:50 (13248): Starting VM using VBoxManage interface. (boinc_cdf5bee2dcb71a42, slot#2) 2020-06-13 15:54:07 (13248): Successfully started VM. (PID = '21240') 2020-06-13 15:54:07 (13248): Reporting VM Process ID to BOINC. 2020-06-13 15:54:10 (13248): Guest Log: 03:15:04.120149 timesync vgsvcTimeSyncWorker: Radical host time change: 163 802 686 000 000ns (HostNow=1 592 056 444 389 000 000 ns HostLast=1 591 892 641 703 000 000 ns) 2020-06-13 15:54:10 (13248): VM state change detected. (old = 'PoweredOff', new = 'Running') 2020-06-13 15:54:10 (13248): Detected: Web Application Enabled (http://localhost:56760) 2020-06-13 15:54:11 (13248): Status Report: Job Duration: '864000.000000' 2020-06-13 15:54:11 (13248): Status Report: Elapsed Time: '14627.485472' 2020-06-13 15:54:11 (13248): Status Report: CPU Time: '14240.218750' 2020-06-13 15:54:11 (13248): Preference change detected 2020-06-13 15:54:11 (13248): Setting CPU throttle for VM. (100%) 2020-06-13 15:54:20 (13248): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds)) 2020-06-13 15:54:20 (13248): Guest Log: 03:15:14.129449 timesync vgsvcTimeSyncWorker: Radical guest time change: 164 098 420 233 000ns (GuestNow=1 592 056 454 398 315 000 ns GuestLast=1 591 892 355 978 082 000 ns fSetTimeLastLoop=true ) 2020-06-13 15:54:23 (13248): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-13 15:54:53 (13248): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-13 15:56:46 (13248): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-13 15:56:53 (13248): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-13 15:59:05 (13248): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-13 15:59:12 (13248): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-13 16:01:46 (13248): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-13 16:01:53 (13248): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-13 16:03:04 (13248): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-13 16:03:23 (13248): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-13 16:04:45 (13248): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-13 16:05:04 (13248): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-13 16:05:34 (13248): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-13 16:05:54 (13248): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-13 16:08:07 (13248): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-13 16:08:25 (13248): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-13 16:09:15 (13248): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-13 16:09:25 (13248): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-13 16:11:20 (13248): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-13 16:11:25 (13248): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-13 16:11:56 (13248): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-13 16:12:06 (13248): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-13 16:12:26 (13248): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-13 16:12:35 (13248): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-13 16:14:39 (13248): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-13 16:14:46 (13248): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-13 16:15:37 (13248): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-13 16:15:46 (13248): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-13 16:16:08 (13248): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-13 16:16:16 (13248): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-13 16:16:37 (13248): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-13 16:16:56 (13248): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-13 16:17:57 (13248): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-13 16:18:17 (13248): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-13 16:20:11 (13248): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-13 16:20:27 (13248): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-13 16:21:09 (13248): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-13 16:21:17 (13248): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-13 16:21:28 (13248): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-13 16:21:47 (13248): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-13 16:23:12 (13248): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-13 16:23:18 (13248): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-13 16:23:33 (13248): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-13 16:23:47 (13248): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-13 16:24:00 (13248): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-13 16:24:27 (13248): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-13 16:25:39 (13248): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-13 16:25:50 (13248): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-13 16:27:28 (13248): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-13 16:27:38 (13248): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-13 16:27:58 (13248): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-13 16:28:08 (13248): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-13 16:29:29 (13248): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-13 16:29:39 (13248): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-13 16:34:50 (13248): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-13 16:34:59 (13248): Stopping VM. 2020-06-13 16:34:59 (13248): Error in stop VM for VM: -108 Command: VBoxManage -q controlvm "boinc_cdf5bee2dcb71a42" savestate Output: 2020-06-13 16:34:59 (13248): VM did not stop when requested. 2020-06-13 16:34:59 (13248): VM was NOT successfully terminated. 2020-06-14 11:10:18 (11352): Detected: vboxwrapper 26197 2020-06-14 11:10:18 (11352): Detected: BOINC client v7.7 2020-06-14 11:10:20 (11352): Detected: VirtualBox VboxManage Interface (Version: 6.1.4) 2020-06-14 11:10:20 (11352): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds) 2020-06-14 11:10:21 (11352): Starting VM using VBoxManage interface. (boinc_cdf5bee2dcb71a42, slot#2) 2020-06-14 11:10:28 (11352): Successfully started VM. (PID = '14844') 2020-06-14 11:10:28 (11352): Reporting VM Process ID to BOINC. 2020-06-14 11:10:28 (11352): Guest Log: BIOS: VirtualBox 6.1.4 2020-06-14 11:10:28 (11352): Guest Log: CPUID EDX: 0x178bfbff 2020-06-14 11:10:28 (11352): Guest Log: BIOS: ata0-0: PCHS=16383/16/63 LCHS=1024/255/63 2020-06-14 11:10:28 (11352): VM state change detected. (old = 'PoweredOff', new = 'Running') 2020-06-14 11:10:28 (11352): Detected: Web Application Enabled (http://localhost:56760) 2020-06-14 11:10:29 (11352): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-14 11:10:39 (11352): Status Report: Job Duration: '864000.000000' 2020-06-14 11:10:39 (11352): Status Report: Elapsed Time: '16522.990618' 2020-06-14 11:10:39 (11352): Status Report: CPU Time: '16269.375000' 2020-06-14 11:10:39 (11352): Preference change detected 2020-06-14 11:10:39 (11352): Setting CPU throttle for VM. (100%) 2020-06-14 11:10:39 (11352): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds)) 2020-06-14 11:10:39 (11352): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032 2020-06-14 11:10:39 (11352): Guest Log: BIOS: Booting from Hard Disk... 2020-06-14 11:10:39 (11352): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-14 11:10:43 (11352): Guest Log: BIOS: KBD: unsupported int 16h function 03 2020-06-14 11:10:43 (11352): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 2020-06-14 11:11:10 (11352): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-14 11:15:26 (11352): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds 2020-06-14 11:15:26 (11352): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000) 2020-06-14 11:15:26 (11352): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-14 11:15:32 (11352): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log 2020-06-14 11:15:32 (11352): Guest Log: 00:00:00.000342 main Log opened 2020-06-14T09:11:16.955434000Z 2020-06-14 11:15:32 (11352): Guest Log: 00:00:00.000553 main OS Product: Linux 2020-06-14 11:15:32 (11352): Guest Log: 00:00:00.000617 main OS Release: 4.14.76-13.cernvm.x86_64 2020-06-14 11:15:32 (11352): Guest Log: 00:00:00.000672 main OS Version: #1 SMP Tue Oct 16 18:26:15 CEST 2018 2020-06-14 11:15:32 (11352): Guest Log: 00:00:00.000718 main Executable: /usr/sbin/VBoxService 2020-06-14 11:15:32 (11352): Guest Log: 00:00:00.000719 main Process ID: 3213 2020-06-14 11:15:32 (11352): Guest Log: 00:00:00.000720 main Package type: LINUX_64BITS_GENERIC 2020-06-14 11:15:32 (11352): Guest Log: 00:00:00.006051 main 5.2.6 r120293 started. Verbose level = 0 2020-06-14 11:15:36 (11352): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-14 11:15:46 (11352): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-14 11:16:10 (11352): Guest Log: 11:11:45 CEST +02:00 2020-06-14: cranky: [INFO] Detected Theory App 2020-06-14 11:16:10 (11352): Guest Log: 11:11:45 CEST +02:00 2020-06-14: cranky: [INFO] Checking CVMFS. 2020-06-14 11:16:11 (11352): Guest Log: Probing /cvmfs/sft.cern.ch... OK 2020-06-14 11:16:12 (11352): 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:12 (11352): Guest Log: 2.5.2.0 4051 0 27208 18226 3 1 1120602 4096001 0 65024 0 0 n/a 5 4 http://s1cern-cvmfs.openhtc.io/cvmfs/sft.cern.ch DIRECT 1 2020-06-14 11:16:13 (11352): Guest Log: Probing /cvmfs/grid.cern.ch... OK 2020-06-14 11:16:14 (11352): 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:14 (11352): Guest Log: 2.5.2.0 4073 0 27716 12477 3 2 1120602 4096001 0 65024 0 2 0 4272 1838 http://s1cern-cvmfs.openhtc.io/cvmfs/grid.cern.ch DIRECT 1 2020-06-14 11:16:15 (11352): Guest Log: Probing /cvmfs/cernvm-prod.cern.ch... OK 2020-06-14 11:16:16 (11352): 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:16 (11352): Guest Log: 2.5.2.0 4099 0 28496 252 3 1 1120602 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:17 (11352): Guest Log: Probing /cvmfs/alice.cern.ch... OK 2020-06-14 11:16:18 (11352): 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:18 (11352): Guest Log: 2.5.2.0 4124 0 29736 6792 3 1 1120602 4096001 0 65024 0 0 n/a 4127 1074 http://s1cern-cvmfs.openhtc.io/cvmfs/alice.cern.ch DIRECT 1 2020-06-14 11:16:18 (11352): Guest Log: 11:11:53 CEST +02:00 2020-06-14: cranky: [INFO] Checking runc. 2020-06-14 11:16:18 (11352): Guest Log: 11:11:53 CEST +02:00 2020-06-14: cranky: [INFO] Creating the filesystem. 2020-06-14 11:16:18 (11352): Guest Log: 11:11:53 CEST +02:00 2020-06-14: cranky: [INFO] Using /cvmfs/cernvm-prod.cern.ch/cvm3 2020-06-14 11:16:18 (11352): Guest Log: 11:11:53 CEST +02:00 2020-06-14: cranky: [INFO] Updating config.json. 2020-06-14 11:16:18 (11352): Guest Log: 11:11:53 CEST +02:00 2020-06-14: cranky: [INFO] Running Container 'runc'. 2020-06-14 11:16:19 (11352): Guest Log: job: htmld=/shared/html/job 2020-06-14 11:16:19 (11352): Guest Log: job: unpack exitcode=0 2020-06-14 11:16:21 (11352): Guest Log: 11:11:56 CEST +02:00 2020-06-14: cranky: [INFO] ===> [runRivet] Sun Jun 14 09:11:54 UTC 2020 [boinc pp jets 7000 400 - pythia6 6.425 pnocr 100000 14] 2020-06-14 11:18:49 (11352): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-14 11:18:57 (11352): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-14 11:21:58 (11352): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-14 11:22:07 (11352): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-14 11:26:39 (11352): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-14 11:26:48 (11352): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-14 11:27:19 (11352): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-14 11:27:28 (11352): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-14 11:28:19 (11352): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-14 11:28:28 (11352): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-14 11:33:29 (11352): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-14 11:33:39 (11352): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-14 11:38:51 (11352): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-14 11:39:00 (11352): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-14 11:52:36 (11352): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-14 11:52:57 (11352): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-14 12:05:50 (11352): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-14 12:06:00 (11352): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-14 12:06:41 (11352): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-14 12:06:51 (11352): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-14 12:10:42 (11352): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-14 12:10:51 (11352): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-14 12:22:54 (11352): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-14 12:23:04 (11352): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-14 12:31:28 (11352): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-14 12:31:35 (11352): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-14 12:44:48 (11352): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-14 12:44:58 (11352): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-14 12:49:50 (11352): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-14 12:49:59 (11352): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-14 12:54:52 (11352): Stopping VM. 2020-06-14 12:54:53 (11352): Error in stop VM for VM: -108 Command: VBoxManage -q controlvm "boinc_cdf5bee2dcb71a42" savestate Output: 2020-06-14 12:54:53 (11352): VM did not stop when requested. 2020-06-14 12:54:53 (11352): VM was NOT successfully terminated. 2020-06-14 19:01:32 (1816): Detected: vboxwrapper 26197 2020-06-14 19:01:32 (1816): Detected: BOINC client v7.7 2020-06-14 19:01:33 (1816): Detected: VirtualBox VboxManage Interface (Version: 6.1.4) 2020-06-14 19:01:33 (1816): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds) 2020-06-14 19:01:34 (1816): Starting VM using VBoxManage interface. (boinc_cdf5bee2dcb71a42, slot#2) 2020-06-14 19:01:42 (1816): Successfully started VM. (PID = '4456') 2020-06-14 19:01:42 (1816): Reporting VM Process ID to BOINC. 2020-06-14 19:01:42 (1816): Guest Log: BIOS: VirtualBox 6.1.4 2020-06-14 19:01:42 (1816): Guest Log: CPUID EDX: 0x178bfbff 2020-06-14 19:01:42 (1816): Guest Log: BIOS: ata0-0: PCHS=16383/16/63 LCHS=1024/255/63 2020-06-14 19:01:42 (1816): VM state change detected. (old = 'PoweredOff', new = 'Running') 2020-06-14 19:01:42 (1816): Detected: Web Application Enabled (http://localhost:56760) 2020-06-14 19:01:43 (1816): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-14 19:01:53 (1816): Status Report: Job Duration: '864000.000000' 2020-06-14 19:01:53 (1816): Status Report: Elapsed Time: '21928.461884' 2020-06-14 19:01:53 (1816): Status Report: CPU Time: '21930.546875' 2020-06-14 19:01:53 (1816): Preference change detected 2020-06-14 19:01:53 (1816): Setting CPU throttle for VM. (100%) 2020-06-14 19:01:54 (1816): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds)) 2020-06-14 19:01:54 (1816): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-14 19:01:55 (1816): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032 2020-06-14 19:01:55 (1816): Guest Log: BIOS: Booting from Hard Disk... 2020-06-14 19:01:57 (1816): Guest Log: BIOS: KBD: unsupported int 16h function 03 2020-06-14 19:01:57 (1816): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 2020-06-14 19:02:21 (1816): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds 2020-06-14 19:02:21 (1816): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000) 2020-06-14 19:02:24 (1816): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-14 19:02:34 (1816): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-14 19:02:35 (1816): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log 2020-06-14 19:02:35 (1816): Guest Log: 00:00:00.000109 main Log opened 2020-06-14T17:02:26.459414000Z 2020-06-14 19:02:35 (1816): Guest Log: 00:00:00.000259 main OS Product: Linux 2020-06-14 19:02:35 (1816): Guest Log: 00:00:00.000304 main OS Release: 4.14.76-13.cernvm.x86_64 2020-06-14 19:02:35 (1816): Guest Log: 00:00:00.000340 main OS Version: #1 SMP Tue Oct 16 18:26:15 CEST 2018 2020-06-14 19:02:35 (1816): Guest Log: 00:00:00.000374 main Executable: /usr/sbin/VBoxService 2020-06-14 19:02:35 (1816): Guest Log: 00:00:00.000375 main Process ID: 3213 2020-06-14 19:02:35 (1816): Guest Log: 00:00:00.000376 main Package type: LINUX_64BITS_GENERIC 2020-06-14 19:02:35 (1816): Guest Log: 00:00:00.003649 main 5.2.6 r120293 started. Verbose level = 0 2020-06-14 19:02:53 (1816): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-14 19:03:03 (1816): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-14 19:03:15 (1816): Guest Log: 19:02:56 CEST +02:00 2020-06-14: cranky: [INFO] Detected Theory App 2020-06-14 19:03:15 (1816): Guest Log: 19:02:56 CEST +02:00 2020-06-14: cranky: [INFO] Checking CVMFS. 2020-06-14 19:03:16 (1816): Guest Log: Probing /cvmfs/sft.cern.ch... OK 2020-06-14 19:03:17 (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-14 19:03:17 (1816): Guest Log: 2.5.2.0 4097 0 28196 18227 3 1 1137989 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:18 (1816): Guest Log: Probing /cvmfs/grid.cern.ch... OK 2020-06-14 19:03:19 (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-14 19:03:19 (1816): Guest Log: 2.5.2.0 4119 0 27576 12479 3 2 1137989 4096001 0 65024 0 2 0 4272 2948 http://s1cern-cvmfs.openhtc.io/cvmfs/grid.cern.ch DIRECT 1 2020-06-14 19:03:20 (1816): Guest Log: Probing /cvmfs/cernvm-prod.cern.ch... OK 2020-06-14 19:03:21 (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-14 19:03:21 (1816): Guest Log: 2.5.2.0 4053 0 25164 252 3 1 1137989 4096001 0 65024 0 0 n/a 0 0 http://s1cern-cvmfs.openhtc.io/cvmfs/cernvm-prod.cern.ch DIRECT 1 2020-06-14 19:03:22 (1816): Guest Log: Probing /cvmfs/alice.cern.ch... OK 2020-06-14 19:03:23 (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-14 19:03:23 (1816): Guest Log: 2.5.2.0 4075 0 29708 6795 3 1 1137989 4096001 0 65024 0 0 n/a 4128 1872 http://s1cern-cvmfs.openhtc.io/cvmfs/alice.cern.ch DIRECT 1 2020-06-14 19:03:24 (1816): Guest Log: 19:03:04 CEST +02:00 2020-06-14: cranky: [INFO] Checking runc. 2020-06-14 19:03:24 (1816): Guest Log: 19:03:04 CEST +02:00 2020-06-14: cranky: [INFO] Creating the filesystem. 2020-06-14 19:03:24 (1816): Guest Log: 19:03:04 CEST +02:00 2020-06-14: cranky: [INFO] Using /cvmfs/cernvm-prod.cern.ch/cvm3 2020-06-14 19:03:24 (1816): Guest Log: 19:03:04 CEST +02:00 2020-06-14: cranky: [INFO] Updating config.json. 2020-06-14 19:03:24 (1816): Guest Log: 19:03:04 CEST +02:00 2020-06-14: cranky: [INFO] Running Container 'runc'. 2020-06-14 19:03:24 (1816): Guest Log: job: htmld=/shared/html/job 2020-06-14 19:03:24 (1816): Guest Log: job: unpack exitcode=0 2020-06-14 19:03:25 (1816): Guest Log: 19:03:07 CEST +02:00 2020-06-14: cranky: [INFO] ===> [runRivet] Sun Jun 14 17:03:05 UTC 2020 [boinc pp jets 7000 400 - pythia6 6.425 pnocr 100000 14] 2020-06-14 19:06:15 (1816): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-14 19:06:25 (1816): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-14 19:22:17 (1816): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-14 19:22:27 (1816): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-14 19:32:19 (1816): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-14 19:32:28 (1816): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-14 19:37:20 (1816): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-14 19:37:30 (1816): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-14 19:43:47 (1816): Stopping VM. 2020-06-14 19:43:48 (1816): Error in stop VM for VM: -108 Command: VBoxManage -q controlvm "boinc_cdf5bee2dcb71a42" savestate Output: 2020-06-14 19:43:48 (1816): VM did not stop when requested. 2020-06-14 19:43:48 (1816): VM was NOT successfully terminated. 2020-06-16 13:55:35 (3060): Detected: vboxwrapper 26197 2020-06-16 13:55:35 (3060): Detected: BOINC client v7.7 2020-06-16 13:55:37 (3060): Detected: VirtualBox VboxManage Interface (Version: 6.1.4) 2020-06-16 13:55:37 (3060): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds) 2020-06-16 13:55:38 (3060): Starting VM using VBoxManage interface. (boinc_cdf5bee2dcb71a42, slot#2) 2020-06-16 13:55:46 (3060): Successfully started VM. (PID = '7628') 2020-06-16 13:55:46 (3060): Reporting VM Process ID to BOINC. 2020-06-16 13:55:46 (3060): Guest Log: BIOS: VirtualBox 6.1.4 2020-06-16 13:55:46 (3060): Guest Log: CPUID EDX: 0x178bfbff 2020-06-16 13:55:46 (3060): Guest Log: BIOS: ata0-0: PCHS=16383/16/63 LCHS=1024/255/63 2020-06-16 13:55:46 (3060): VM state change detected. (old = 'PoweredOff', new = 'Running') 2020-06-16 13:55:46 (3060): Detected: Web Application Enabled (http://localhost:56760) 2020-06-16 13:55:47 (3060): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-16 13:55:56 (3060): Status Report: Job Duration: '864000.000000' 2020-06-16 13:55:56 (3060): Status Report: Elapsed Time: '24361.461884' 2020-06-16 13:55:56 (3060): Status Report: CPU Time: '24307.187500' 2020-06-16 13:55:56 (3060): Preference change detected 2020-06-16 13:55:56 (3060): Setting CPU throttle for VM. (100%) 2020-06-16 13:55:57 (3060): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds)) 2020-06-16 13:55:57 (3060): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-16 13:55:58 (3060): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032 2020-06-16 13:55:58 (3060): Guest Log: BIOS: Booting from Hard Disk... 2020-06-16 13:56:00 (3060): Guest Log: BIOS: KBD: unsupported int 16h function 03 2020-06-16 13:56:00 (3060): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 2020-06-16 13:56:17 (3060): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-16 13:56:37 (3060): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-16 13:56:57 (3060): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds 2020-06-16 13:56:57 (3060): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000) 2020-06-16 13:57:01 (3060): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log 2020-06-16 13:57:01 (3060): Guest Log: 00:00:00.000108 main Log opened 2020-06-16T11:57:02.548604000Z 2020-06-16 13:57:01 (3060): Guest Log: 00:00:00.000266 main OS Product: Linux 2020-06-16 13:57:01 (3060): Guest Log: 00:00:00.000315 main OS Release: 4.14.76-13.cernvm.x86_64 2020-06-16 13:57:01 (3060): Guest Log: 00:00:00.000353 main OS Version: #1 SMP Tue Oct 16 18:26:15 CEST 2018 2020-06-16 13:57:01 (3060): Guest Log: 00:00:00.000389 main Executable: /usr/sbin/VBoxService 2020-06-16 13:57:01 (3060): Guest Log: 00:00:00.000390 main Process ID: 3216 2020-06-16 13:57:01 (3060): Guest Log: 00:00:00.000391 main Package type: LINUX_64BITS_GENERIC 2020-06-16 13:57:01 (3060): Guest Log: 00:00:00.003988 main 5.2.6 r120293 started. Verbose level = 0 2020-06-16 13:57:31 (3060): Guest Log: 13:57:32 CEST +02:00 2020-06-16: cranky: [INFO] Detected Theory App 2020-06-16 13:57:31 (3060): Guest Log: 13:57:32 CEST +02:00 2020-06-16: cranky: [INFO] Checking CVMFS. 2020-06-16 13:57:33 (3060): Guest Log: Probing /cvmfs/sft.cern.ch... OK 2020-06-16 13:57:34 (3060): 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:34 (3060): Guest Log: 2.5.2.0 4057 0 27180 18244 3 1 1155368 4096001 0 65024 0 0 n/a 5 7 http://s1cern-cvmfs.openhtc.io/cvmfs/sft.cern.ch DIRECT 1 2020-06-16 13:57:35 (3060): Guest Log: Probing /cvmfs/grid.cern.ch... OK 2020-06-16 13:57:36 (3060): 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:36 (3060): Guest Log: 2.5.2.0 4082 0 27708 12491 3 2 1155368 4096001 0 65024 0 2 0 4273 829 http://s1ral-cvmfs.openhtc.io/cvmfs/grid.cern.ch DIRECT 1 2020-06-16 13:57:37 (3060): Guest Log: Probing /cvmfs/cernvm-prod.cern.ch... OK 2020-06-16 13:57:39 (3060): 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:39 (3060): Guest Log: 2.5.2.0 4127 0 26212 252 3 1 1155368 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:40 (3060): Guest Log: Probing /cvmfs/alice.cern.ch... OK 2020-06-16 13:57:41 (3060): 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:41 (3060): Guest Log: 2.5.2.0 4105 0 29752 6805 3 1 1155368 4096001 0 65024 0 0 n/a 4133 3107 http://s1ral-cvmfs.openhtc.io/cvmfs/alice.cern.ch DIRECT 1 2020-06-16 13:57:41 (3060): Guest Log: 13:57:42 CEST +02:00 2020-06-16: cranky: [INFO] Checking runc. 2020-06-16 13:57:41 (3060): Guest Log: 13:57:42 CEST +02:00 2020-06-16: cranky: [INFO] Creating the filesystem. 2020-06-16 13:57:41 (3060): Guest Log: 13:57:42 CEST +02:00 2020-06-16: cranky: [INFO] Using /cvmfs/cernvm-prod.cern.ch/cvm3 2020-06-16 13:57:41 (3060): Guest Log: 13:57:42 CEST +02:00 2020-06-16: cranky: [INFO] Updating config.json. 2020-06-16 13:57:42 (3060): Guest Log: 13:57:42 CEST +02:00 2020-06-16: cranky: [INFO] Running Container 'runc'. 2020-06-16 13:57:42 (3060): Guest Log: job: htmld=/shared/html/job 2020-06-16 13:57:42 (3060): Guest Log: job: unpack exitcode=0 2020-06-16 13:57:44 (3060): Guest Log: 13:57:44 CEST +02:00 2020-06-16: cranky: [INFO] ===> [runRivet] Tue Jun 16 11:57:43 UTC 2020 [boinc pp jets 7000 400 - pythia6 6.425 pnocr 100000 14] 2020-06-16 14:00:07 (3060): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-16 14:00:17 (3060): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-16 14:07:26 (3060): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-16 14:07:27 (3060): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-16 14:07:30 (3060): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-16 14:07:40 (3060): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-16 14:09:10 (3060): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-16 14:09:20 (3060): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-16 14:13:31 (3060): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-16 14:13:41 (3060): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-16 14:47:27 (3060): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-16 14:49:38 (3060): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-16 15:38:34 (3060): Guest Log: 01:38:39.518567 timesync vgsvcTimeSyncWorker: Radical host time change: 1 946 156 000 000ns (HostNow=1 592 314 708 991 000 000 ns HostLast=1 592 312 762 835 000 000 ns) 2020-06-16 15:39:54 (3060): Guest Log: 01:38:39.673284 timesync vgsvcTimeSyncWorker: Radical guest time change: 1 947 132 581 000ns (GuestNow=1 592 314 542 000 268 000 ns GuestLast=1 592 312 594 867 687 000 ns fSetTimeLastLoop=false) 2020-06-16 15:40:05 (3060): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-16 15:40:32 (3060): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-16 16:11:49 (3060): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-16 16:11:58 (3060): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-16 16:16:18 (3060): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-16 16:17:29 (3060): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-16 16:17:49 (3060): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-16 16:17:59 (3060): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-16 16:19:26 (3060): Status Report: Job Duration: '864000.000000' 2020-06-16 16:19:26 (3060): Status Report: Elapsed Time: '30361.756377' 2020-06-16 16:19:26 (3060): Status Report: CPU Time: '30259.734375' 2020-06-16 18:19:03 (3060): Guest Log: 04:17:23.484745 timesync vgsvcTimeSyncWorker: Radical host time change: 6 598 834 000 000ns (HostNow=1 592 324 342 899 000 000 ns HostLast=1 592 317 744 065 000 000 ns) 2020-06-16 18:19:03 (3060): Guest Log: 04:17:24.181085 timesync vgsvcTimeSyncWorker: Radical guest time change: 6 601 957 444 000ns (GuestNow=1 592 324 066 033 228 000 ns GuestLast=1 592 317 464 075 784 000 ns fSetTimeLastLoop=false) 2020-06-16 18:19:17 (3060): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-16 18:19:34 (3060): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-16 18:20:15 (3060): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-16 18:20:35 (3060): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-16 18:21:58 (3060): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-16 18:22:06 (3060): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-16 18:40:59 (3060): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-16 18:41:09 (3060): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-16 18:49:51 (3060): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-16 18:50:01 (3060): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-16 19:30:26 (3060): Guest Log: job: run exitcode=0 2020-06-16 19:30:26 (3060): Guest Log: job: diskusage=3552 2020-06-16 19:30:26 (3060): Guest Log: job: logsize=88 k 2020-06-16 19:30:26 (3060): Guest Log: job: times= 2020-06-16 19:30:26 (3060): Guest Log: 0m0.011s 0m0.011s 2020-06-16 19:30:26 (3060): Guest Log: 315m47.458s 3m26.091s 2020-06-16 19:30:26 (3060): Guest Log: job: cpuusage=19154 2020-06-16 19:30:26 (3060): Guest Log: 19:24:49 CEST +02:00 2020-06-16: cranky: [INFO] Container 'runc' finished with status code 0. 2020-06-16 19:30:26 (3060): Guest Log: 19:24:49 CEST +02:00 2020-06-16: cranky: [INFO] Preparing output. 2020-06-16 19:30:31 (3060): Guest Log: [INFO] Job Finished 2020-06-16 19:30:31 (3060): Guest Log: [INFO] Shutting Down. 2020-06-16 19:30:31 (3060): VM Completion File Detected. 2020-06-16 19:30:31 (3060): VM Completion Message: Job Finished . 2020-06-16 19:30:31 (3060): Powering off VM. 2020-06-16 19:35:32 (3060): VM did not power off when requested. 2020-06-16 19:35:32 (3060): VM was successfully terminated. 2020-06-16 19:35:32 (3060): Deregistering VM. (boinc_cdf5bee2dcb71a42, slot#2) 2020-06-16 19:35:32 (3060): Removing network bandwidth t
©2024 CERN