| Name | Theory_2390-1125963-162_0 |
| Workunit | 150241758 |
| Created | 24 Dec 2020, 16:43:01 UTC |
| Sent | 24 Dec 2020, 19:56:40 UTC |
| Report deadline | 4 Jan 2021, 19:56:40 UTC |
| Received | 5 Jan 2021, 10:12:25 UTC |
| Server state | Over |
| Outcome | Success |
| Client state | Done |
| Exit status | 0 (0x00000000) |
| Computer ID | 10672324 |
| Run time | 15 hours 11 min 29 sec |
| CPU time | 14 hours 42 min 5 sec |
| Validate state | Valid |
| Credit | 587.30 |
| Device peak FLOPS | 4.64 GFLOPS |
| Application version | Theory Simulation v300.06 (vbox64_theory) windows_x86_64 |
| Peak working set size | 65.03 MB |
| Peak swap size | 82.48 MB |
| Peak disk usage | 2.14 GB |
Stderr output
<core_client_version>7.16.11</core_client_version> <![CDATA[ <stderr_txt> 2020-12-31 15:19:29 (10512): Detected: vboxwrapper 26197 2020-12-31 15:19:29 (10512): Detected: BOINC client v7.7 2020-12-31 15:19:30 (10512): Detected: VirtualBox VboxManage Interface (Version: 6.1.16) 2020-12-31 15:19:30 (10512): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds) 2020-12-31 15:19:30 (10512): Successfully copied 'init_data.xml' to the shared directory. 2020-12-31 15:19:30 (10512): Successfully copied 'input' to the shared directory. 2020-12-31 15:19:31 (10512): Create VM. (boinc_5c48a61c5b26b3d6, slot#10) 2020-12-31 15:19:32 (10512): Setting Memory Size for VM. (630MB) 2020-12-31 15:19:32 (10512): Setting CPU Count for VM. (1) 2020-12-31 15:19:32 (10512): Setting Chipset Options for VM. 2020-12-31 15:19:32 (10512): Setting Boot Options for VM. 2020-12-31 15:19:33 (10512): Setting Network Configuration for NAT. 2020-12-31 15:19:33 (10512): Enabling VM Network Access. 2020-12-31 15:19:33 (10512): Disabling USB Support for VM. 2020-12-31 15:19:34 (10512): Disabling COM Port Support for VM. 2020-12-31 15:19:34 (10512): Disabling LPT Port Support for VM. 2020-12-31 15:19:34 (10512): Disabling Audio Support for VM. 2020-12-31 15:19:34 (10512): Disabling Clipboard Support for VM. 2020-12-31 15:19:35 (10512): Disabling Drag and Drop Support for VM. 2020-12-31 15:19:35 (10512): Adding storage controller(s) to VM. 2020-12-31 15:19:35 (10512): Adding virtual disk drive to VM. (vm_image.vdi) 2020-12-31 15:19:35 (10512): Adding VirtualBox Guest Additions to VM. 2020-12-31 15:19:36 (10512): Adding network bandwidth throttle group to VM. (Defaulting to 1024GB) 2020-12-31 15:19:36 (10512): forwarding host port 53725 to guest port 80 2020-12-31 15:19:36 (10512): Enabling remote desktop for VM. 2020-12-31 15:19:37 (10512): Enabling shared directory for VM. 2020-12-31 15:19:37 (10512): Starting VM using VBoxManage interface. (boinc_5c48a61c5b26b3d6, slot#10) 2020-12-31 15:19:43 (10512): Successfully started VM. (PID = '13420') 2020-12-31 15:19:43 (10512): Reporting VM Process ID to BOINC. 2020-12-31 15:19:43 (10512): Guest Log: BIOS: VirtualBox 6.1.16 2020-12-31 15:19:43 (10512): Guest Log: CPUID EDX: 0x178bfbff 2020-12-31 15:19:43 (10512): Guest Log: BIOS: ata0-0: PCHS=16383/16/63 LCHS=1024/255/63 2020-12-31 15:19:43 (10512): VM state change detected. (old = 'PoweredOff', new = 'Running') 2020-12-31 15:19:43 (10512): Detected: Web Application Enabled (http://localhost:53725) 2020-12-31 15:19:43 (10512): Detected: Remote Desktop Enabled (localhost:53726) 2020-12-31 15:19:43 (10512): Preference change detected 2020-12-31 15:19:43 (10512): Setting CPU throttle for VM. (100%) 2020-12-31 15:19:43 (10512): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 300 seconds) or (Vbox_job.xml: 600 seconds)) 2020-12-31 15:19:45 (10512): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032 2020-12-31 15:19:45 (10512): Guest Log: BIOS: Booting from Hard Disk... 2020-12-31 15:19:47 (10512): Guest Log: BIOS: KBD: unsupported int 16h function 03 2020-12-31 15:19:47 (10512): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 2020-12-31 15:20:06 (10512): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds 2020-12-31 15:20:06 (10512): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000) 2020-12-31 15:20:09 (10512): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log 2020-12-31 15:20:09 (10512): Guest Log: 00:00:00.000105 main Log opened 2020-12-31T12:20:09.272094000Z 2020-12-31 15:20:09 (10512): Guest Log: 00:00:00.000231 main OS Product: Linux 2020-12-31 15:20:09 (10512): Guest Log: 00:00:00.000286 main OS Release: 4.14.76-13.cernvm.x86_64 2020-12-31 15:20:09 (10512): Guest Log: 00:00:00.000335 main OS Version: #1 SMP Tue Oct 16 18:26:15 CEST 2018 2020-12-31 15:20:09 (10512): Guest Log: 00:00:00.000383 main Executable: /usr/sbin/VBoxService 2020-12-31 15:20:09 (10512): Guest Log: 00:00:00.000384 main Process ID: 3194 2020-12-31 15:20:09 (10512): Guest Log: 00:00:00.000407 main Package type: LINUX_64BITS_GENERIC 2020-12-31 15:20:09 (10512): Guest Log: 00:00:00.004573 main 5.2.6 r120293 started. Verbose level = 0 2020-12-31 15:20:27 (10512): Guest Log: 15:20:27 MSK +03:00 2020-12-31: cranky: [INFO] Detected Theory App 2020-12-31 15:20:27 (10512): Guest Log: 15:20:27 MSK +03:00 2020-12-31: cranky: [INFO] Checking CVMFS. 2020-12-31 15:20:27 (10512): Guest Log: Probing /cvmfs/sft.cern.ch... OK 2020-12-31 15:20:28 (10512): 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-12-31 15:20:28 (10512): Guest Log: 2.5.2.0 4031 0 28512 19406 3 1 265013 4096000 0 65024 0 0 n/a 5 10 http://s1cern-cvmfs.openhtc.io/cvmfs/sft.cern.ch DIRECT 1 2020-12-31 15:20:28 (10512): Guest Log: Probing /cvmfs/grid.cern.ch... OK 2020-12-31 15:20:29 (10512): 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-12-31 15:20:29 (10512): Guest Log: 2.5.2.0 4097 0 25472 14075 3 2 265013 4096000 0 65024 0 2 -100 6419 6570 http://s1cern-cvmfs.openhtc.io/cvmfs/grid.cern.ch DIRECT 1 2020-12-31 15:20:29 (10512): Guest Log: Probing /cvmfs/cernvm-prod.cern.ch... OK 2020-12-31 15:20:30 (10512): 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-12-31 15:20:30 (10512): Guest Log: 2.5.2.0 4074 0 27224 259 3 1 265013 4096000 0 65024 0 0 n/a 4 5 http://s1cern-cvmfs.openhtc.io/cvmfs/cernvm-prod.cern.ch DIRECT 1 2020-12-31 15:20:30 (10512): Guest Log: Probing /cvmfs/alice.cern.ch... OK 2020-12-31 15:20:31 (10512): 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-12-31 15:20:31 (10512): Guest Log: 2.5.2.0 4053 0 30692 8296 3 1 265013 4096000 0 65024 0 0 n/a 4698 5501 http://s1cern-cvmfs.openhtc.io/cvmfs/alice.cern.ch DIRECT 1 2020-12-31 15:20:31 (10512): Guest Log: 15:20:30 MSK +03:00 2020-12-31: cranky: [INFO] Checking runc. 2020-12-31 15:20:31 (10512): Guest Log: 15:20:30 MSK +03:00 2020-12-31: cranky: [INFO] Creating the filesystem. 2020-12-31 15:20:31 (10512): Guest Log: 15:20:30 MSK +03:00 2020-12-31: cranky: [INFO] Using /cvmfs/cernvm-prod.cern.ch/cvm3 2020-12-31 15:20:31 (10512): Guest Log: 15:20:31 MSK +03:00 2020-12-31: cranky: [INFO] Updating config.json. 2020-12-31 15:20:31 (10512): Guest Log: 15:20:31 MSK +03:00 2020-12-31: cranky: [INFO] Running Container 'runc'. 2020-12-31 15:20:33 (10512): Guest Log: job: htmld=/shared/html/job 2020-12-31 15:20:33 (10512): Guest Log: job: unpack exitcode=0 2020-12-31 15:20:35 (10512): Guest Log: 15:20:35 MSK +03:00 2020-12-31: cranky: [INFO] ===> [runRivet] Thu Dec 31 12:20:33 UTC 2020 [boinc pp jets 7000 800 - pythia8 8.243 tune-AU2m 100000 162] 2020-12-31 15:26:25 (10512): VM state change detected. (old = 'Running', new = 'Paused') 2020-12-31 15:31:26 (10512): VM state change detected. (old = 'Paused', new = 'Running') 2020-12-31 15:32:22 (10512): VM state change detected. (old = 'Running', new = 'Paused') 2020-12-31 15:50:15 (10512): VM state change detected. (old = 'Paused', new = 'Running') 2020-12-31 15:51:40 (10512): VM state change detected. (old = 'Running', new = 'Paused') 2020-12-31 16:00:18 (10512): VM state change detected. (old = 'Paused', new = 'Running') 2020-12-31 16:57:16 (10512): VM state change detected. (old = 'Running', new = 'Paused') 2020-12-31 17:01:22 (10512): VM state change detected. (old = 'Paused', new = 'Running') 2020-12-31 17:24:52 (10512): VM state change detected. (old = 'Running', new = 'Paused') 2020-12-31 17:28:52 (10512): VM state change detected. (old = 'Paused', new = 'Running') 2020-12-31 17:32:05 (10512): VM state change detected. (old = 'Running', new = 'Paused') 2020-12-31 17:35:10 (10512): VM state change detected. (old = 'Paused', new = 'Running') 2020-12-31 17:42:38 (10512): Status Report: Job Duration: '864000.000000' 2020-12-31 17:42:38 (10512): Status Report: Elapsed Time: '6000.000000' 2020-12-31 17:42:38 (10512): Status Report: CPU Time: '5926.250000' 2020-12-31 18:10:37 (10512): VM state change detected. (old = 'Running', new = 'Paused') 2020-12-31 18:15:33 (10512): VM state change detected. (old = 'Paused', new = 'Running') 2020-12-31 18:18:11 (10512): VM state change detected. (old = 'Running', new = 'Paused') 2020-12-31 18:18:16 (10512): Stopping VM. 2020-12-31 18:18:16 (10512): Error in stop VM for VM: -108 Command: VBoxManage -q controlvm "boinc_5c48a61c5b26b3d6" savestate Output: 2020-12-31 18:18:16 (10512): VM did not stop when requested. 2020-12-31 18:18:16 (10512): VM was successfully terminated. 2021-01-01 11:44:50 (2544): Detected: vboxwrapper 26197 2021-01-01 11:44:50 (2544): Detected: BOINC client v7.7 2021-01-01 11:44:51 (2544): Detected: VirtualBox VboxManage Interface (Version: 6.1.16) 2021-01-01 11:44:51 (2544): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds) 2021-01-01 11:44:52 (2544): Starting VM using VBoxManage interface. (boinc_5c48a61c5b26b3d6, slot#10) 2021-01-01 11:44:57 (2544): Successfully started VM. (PID = '7408') 2021-01-01 11:44:57 (2544): Reporting VM Process ID to BOINC. 2021-01-01 11:44:57 (2544): Guest Log: BIOS: VirtualBox 6.1.16 2021-01-01 11:44:57 (2544): Guest Log: CPUID EDX: 0x178bfbff 2021-01-01 11:44:57 (2544): Guest Log: BIOS: ata0-0: PCHS=16383/16/63 LCHS=1024/255/63 2021-01-01 11:44:57 (2544): VM state change detected. (old = 'PoweredOff', new = 'Running') 2021-01-01 11:44:57 (2544): Detected: Web Application Enabled (http://localhost:53725) 2021-01-01 11:44:57 (2544): Detected: Remote Desktop Enabled (localhost:53726) 2021-01-01 11:44:57 (2544): Status Report: Job Duration: '864000.000000' 2021-01-01 11:44:57 (2544): Status Report: Elapsed Time: '7831.000000' 2021-01-01 11:44:57 (2544): Status Report: CPU Time: '7750.437500' 2021-01-01 11:44:57 (2544): Preference change detected 2021-01-01 11:44:57 (2544): Setting CPU throttle for VM. (100%) 2021-01-01 11:44:58 (2544): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 300 seconds) or (Vbox_job.xml: 600 seconds)) 2021-01-01 11:44:59 (2544): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032 2021-01-01 11:44:59 (2544): Guest Log: BIOS: Booting from Hard Disk... 2021-01-01 11:45:01 (2544): Guest Log: BIOS: KBD: unsupported int 16h function 03 2021-01-01 11:45:01 (2544): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 2021-01-01 11:45:28 (2544): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds 2021-01-01 11:45:28 (2544): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000) 2021-01-01 11:45:32 (2544): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log 2021-01-01 11:45:32 (2544): Guest Log: 00:00:00.000115 main Log opened 2021-01-01T08:45:37.841069000Z 2021-01-01 11:45:32 (2544): Guest Log: 00:00:00.000321 main OS Product: Linux 2021-01-01 11:45:32 (2544): Guest Log: 00:00:00.000376 main OS Release: 4.14.76-13.cernvm.x86_64 2021-01-01 11:45:32 (2544): Guest Log: 00:00:00.000431 main OS Version: #1 SMP Tue Oct 16 18:26:15 CEST 2018 2021-01-01 11:45:32 (2544): Guest Log: 00:00:00.000482 main Executable: /usr/sbin/VBoxService 2021-01-01 11:45:32 (2544): Guest Log: 00:00:00.000483 main Process ID: 3195 2021-01-01 11:45:32 (2544): Guest Log: 00:00:00.000483 main Package type: LINUX_64BITS_GENERIC 2021-01-01 11:45:32 (2544): Guest Log: 00:00:00.008192 main 5.2.6 r120293 started. Verbose level = 0 2021-01-01 11:45:52 (2544): Guest Log: 11:45:58 MSK +03:00 2021-01-01: cranky: [INFO] Detected Theory App 2021-01-01 11:45:52 (2544): Guest Log: 11:45:58 MSK +03:00 2021-01-01: cranky: [INFO] Checking CVMFS. 2021-01-01 11:45:52 (2544): Guest Log: Probing /cvmfs/sft.cern.ch... OK 2021-01-01 11:45:53 (2544): 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 2021-01-01 11:45:53 (2544): Guest Log: 2.5.2.0 4071 0 25168 19407 3 1 1320683 4096000 0 65024 0 0 n/a 5 5 http://s1ihep-cvmfs.openhtc.io/cvmfs/sft.cern.ch DIRECT 1 2021-01-01 11:45:53 (2544): Guest Log: Probing /cvmfs/grid.cern.ch... OK 2021-01-01 11:45:54 (2544): 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 2021-01-01 11:45:54 (2544): Guest Log: 2.5.2.0 4101 0 27692 14083 3 2 1320683 4096000 0 65024 0 2 0 4281 1871 http://s1cern-cvmfs.openhtc.io/cvmfs/grid.cern.ch DIRECT 1 2021-01-01 11:45:54 (2544): Guest Log: Probing /cvmfs/cernvm-prod.cern.ch... OK 2021-01-01 11:45:55 (2544): 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 2021-01-01 11:45:55 (2544): Guest Log: 2.5.2.0 4046 0 27224 259 3 1 1320683 4096000 0 65024 0 0 n/a 0 0 http://s1cern-cvmfs.openhtc.io/cvmfs/cernvm-prod.cern.ch DIRECT 1 2021-01-01 11:45:55 (2544): Guest Log: Probing /cvmfs/alice.cern.ch... OK 2021-01-01 11:45:56 (2544): 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 2021-01-01 11:45:56 (2544): Guest Log: 2.5.2.0 4034 0 30672 8302 3 1 1320683 4096000 0 65024 0 0 n/a 4699 1965 http://s1cern-cvmfs.openhtc.io/cvmfs/alice.cern.ch DIRECT 1 2021-01-01 11:45:56 (2544): Guest Log: 11:46:02 MSK +03:00 2021-01-01: cranky: [INFO] Checking runc. 2021-01-01 11:45:56 (2544): Guest Log: 11:46:02 MSK +03:00 2021-01-01: cranky: [INFO] Creating the filesystem. 2021-01-01 11:45:56 (2544): Guest Log: 11:46:02 MSK +03:00 2021-01-01: cranky: [INFO] Using /cvmfs/cernvm-prod.cern.ch/cvm3 2021-01-01 11:45:57 (2544): Guest Log: 11:46:02 MSK +03:00 2021-01-01: cranky: [INFO] Updating config.json. 2021-01-01 11:45:57 (2544): Guest Log: 11:46:02 MSK +03:00 2021-01-01: cranky: [INFO] Running Container 'runc'. 2021-01-01 11:45:57 (2544): Guest Log: job: htmld=/shared/html/job 2021-01-01 11:45:57 (2544): Guest Log: job: unpack exitcode=0 2021-01-01 11:45:59 (2544): Guest Log: 11:46:04 MSK +03:00 2021-01-01: cranky: [INFO] ===> [runRivet] Fri Jan 1 08:46:03 UTC 2021 [boinc pp jets 7000 800 - pythia8 8.243 tune-AU2m 100000 162] 2021-01-01 11:48:23 (2544): VM state change detected. (old = 'Running', new = 'Paused') 2021-01-01 12:09:19 (2544): VM state change detected. (old = 'Paused', new = 'Running') 2021-01-01 12:25:24 (2544): VM state change detected. (old = 'Running', new = 'Paused') 2021-01-01 12:29:04 (2544): VM state change detected. (old = 'Paused', new = 'Running') 2021-01-01 12:31:53 (2544): VM state change detected. (old = 'Running', new = 'Paused') 2021-01-01 12:34:53 (2544): VM state change detected. (old = 'Paused', new = 'Running') 2021-01-01 12:59:03 (2544): VM state change detected. (old = 'Running', new = 'Paused') 2021-01-01 13:03:16 (2544): VM state change detected. (old = 'Paused', new = 'Running') 2021-01-01 13:04:07 (2544): VM state change detected. (old = 'Running', new = 'Paused') 2021-01-01 14:01:54 (2544): Stopping VM. 2021-01-01 14:01:54 (2544): Error in stop VM for VM: -108 Command: VBoxManage -q controlvm "boinc_5c48a61c5b26b3d6" savestate Output: 2021-01-01 14:01:54 (2544): VM did not stop when requested. 2021-01-01 14:01:54 (2544): VM was successfully terminated. 2021-01-01 17:29:25 (8776): Detected: vboxwrapper 26197 2021-01-01 17:29:25 (8776): Detected: BOINC client v7.7 2021-01-01 17:29:26 (8776): Detected: VirtualBox VboxManage Interface (Version: 6.1.16) 2021-01-01 17:29:27 (8776): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds) 2021-01-01 17:29:27 (8776): Starting VM using VBoxManage interface. (boinc_5c48a61c5b26b3d6, slot#10) 2021-01-01 17:29:32 (8776): Successfully started VM. (PID = '10344') 2021-01-01 17:29:32 (8776): Reporting VM Process ID to BOINC. 2021-01-01 17:29:32 (8776): Guest Log: BIOS: VirtualBox 6.1.16 2021-01-01 17:29:32 (8776): Guest Log: CPUID EDX: 0x178bfbff 2021-01-01 17:29:32 (8776): Guest Log: BIOS: ata0-0: PCHS=16383/16/63 LCHS=1024/255/63 2021-01-01 17:29:32 (8776): VM state change detected. (old = 'PoweredOff', new = 'Running') 2021-01-01 17:29:32 (8776): Detected: Web Application Enabled (http://localhost:53725) 2021-01-01 17:29:32 (8776): Detected: Remote Desktop Enabled (localhost:53726) 2021-01-01 17:29:32 (8776): Status Report: Job Duration: '864000.000000' 2021-01-01 17:29:32 (8776): Status Report: Elapsed Time: '10654.151402' 2021-01-01 17:29:32 (8776): Status Report: CPU Time: '10540.750000' 2021-01-01 17:29:32 (8776): Preference change detected 2021-01-01 17:29:32 (8776): Setting CPU throttle for VM. (100%) 2021-01-01 17:29:33 (8776): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 300 seconds) or (Vbox_job.xml: 600 seconds)) 2021-01-01 17:29:34 (8776): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032 2021-01-01 17:29:34 (8776): Guest Log: BIOS: Booting from Hard Disk... 2021-01-01 17:29:36 (8776): Guest Log: BIOS: KBD: unsupported int 16h function 03 2021-01-01 17:29:36 (8776): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 2021-01-01 17:30:02 (8776): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds 2021-01-01 17:30:02 (8776): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000) 2021-01-01 17:30:05 (8776): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log 2021-01-01 17:30:05 (8776): Guest Log: 00:00:00.000117 main Log opened 2021-01-01T14:30:11.592804000Z 2021-01-01 17:30:05 (8776): Guest Log: 00:00:00.000403 main OS Product: Linux 2021-01-01 17:30:05 (8776): Guest Log: 00:00:00.000465 main OS Release: 4.14.76-13.cernvm.x86_64 2021-01-01 17:30:05 (8776): Guest Log: 00:00:00.000502 main OS Version: #1 SMP Tue Oct 16 18:26:15 CEST 2018 2021-01-01 17:30:05 (8776): Guest Log: 00:00:00.000542 main Executable: /usr/sbin/VBoxService 2021-01-01 17:30:05 (8776): Guest Log: 00:00:00.000543 main Process ID: 3202 2021-01-01 17:30:05 (8776): Guest Log: 00:00:00.000544 main Package type: LINUX_64BITS_GENERIC 2021-01-01 17:30:05 (8776): Guest Log: 00:00:00.005055 main 5.2.6 r120293 started. Verbose level = 0 2021-01-01 17:30:29 (8776): Guest Log: 17:30:35 MSK +03:00 2021-01-01: cranky: [INFO] Detected Theory App 2021-01-01 17:30:29 (8776): Guest Log: 17:30:35 MSK +03:00 2021-01-01: cranky: [INFO] Checking CVMFS. 2021-01-01 17:30:30 (8776): Guest Log: Probing /cvmfs/sft.cern.ch... OK 2021-01-01 17:30:30 (8776): 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 2021-01-01 17:30:30 (8776): Guest Log: 2.5.2.0 4107 0 28000 19408 3 1 1329063 4096000 0 65024 0 0 n/a 5 15 http://s1cern-cvmfs.openhtc.io/cvmfs/sft.cern.ch DIRECT 1 2021-01-01 17:30:30 (8776): Guest Log: Probing /cvmfs/grid.cern.ch... OK 2021-01-01 17:30:31 (8776): 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 2021-01-01 17:30:31 (8776): Guest Log: 2.5.2.0 4062 0 27648 14085 3 2 1329063 4096000 0 65024 0 2 0 4281 4613 http://s1ral-cvmfs.openhtc.io/cvmfs/grid.cern.ch DIRECT 1 2021-01-01 17:30:31 (8776): Guest Log: Probing /cvmfs/cernvm-prod.cern.ch... OK 2021-01-01 17:30:32 (8776): 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 2021-01-01 17:30:32 (8776): Guest Log: 2.5.2.0 4036 0 27212 259 3 1 1329063 4096000 0 65024 0 0 n/a 0 0 http://s1cern-cvmfs.openhtc.io/cvmfs/cernvm-prod.cern.ch DIRECT 1 2021-01-01 17:30:32 (8776): Guest Log: Probing /cvmfs/alice.cern.ch... OK 2021-01-01 17:30:33 (8776): 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 2021-01-01 17:30:33 (8776): Guest Log: 2.5.2.0 4085 0 31448 8302 3 1 1329063 4096000 0 65024 0 0 n/a 0 0 http://s1cern-cvmfs.openhtc.io/cvmfs/alice.cern.ch DIRECT 1 2021-01-01 17:30:33 (8776): Guest Log: 17:30:39 MSK +03:00 2021-01-01: cranky: [INFO] Checking runc. 2021-01-01 17:30:33 (8776): Guest Log: 17:30:39 MSK +03:00 2021-01-01: cranky: [INFO] Creating the filesystem. 2021-01-01 17:30:33 (8776): Guest Log: 17:30:39 MSK +03:00 2021-01-01: cranky: [INFO] Using /cvmfs/cernvm-prod.cern.ch/cvm3 2021-01-01 17:30:33 (8776): Guest Log: 17:30:39 MSK +03:00 2021-01-01: cranky: [INFO] Updating config.json. 2021-01-01 17:30:33 (8776): Guest Log: 17:30:39 MSK +03:00 2021-01-01: cranky: [INFO] Running Container 'runc'. 2021-01-01 17:30:34 (8776): Guest Log: job: htmld=/shared/html/job 2021-01-01 17:30:34 (8776): Guest Log: job: unpack exitcode=0 2021-01-01 17:30:36 (8776): Guest Log: 17:30:42 MSK +03:00 2021-01-01: cranky: [INFO] ===> [runRivet] Fri Jan 1 14:30:40 UTC 2021 [boinc pp jets 7000 800 - pythia8 8.243 tune-AU2m 100000 162] 2021-01-01 17:37:29 (8776): VM state change detected. (old = 'Running', new = 'Paused') 2021-01-01 17:51:30 (8776): VM state change detected. (old = 'Paused', new = 'Running') 2021-01-01 17:57:14 (8776): VM state change detected. (old = 'Running', new = 'Paused') 2021-01-01 18:54:33 (8776): VM state change detected. (old = 'Paused', new = 'Running') 2021-01-01 18:54:37 (8776): Guest Log: 00:13:10.472902 timesync vgsvcTimeSyncWorker: Radical host time change: 3 506 018 000 000ns (HostNow=1 609 516 475 476 000 000 ns HostLast=1 609 512 969 458 000 000 ns) 2021-01-01 18:54:47 (8776): Guest Log: 00:13:20.502541 timesync vgsvcTimeSyncWorker: Radical guest time change: 4 283 598 787 000ns (GuestNow=1 609 516 485 538 925 000 ns GuestLast=1 609 512 201 940 138 000 ns fSetTimeLastLoop=true ) 2021-01-01 18:58:13 (8776): VM state change detected. (old = 'Running', new = 'Paused') 2021-01-01 19:59:41 (8776): VM state change detected. (old = 'Paused', new = 'Running') 2021-01-01 19:59:44 (8776): Guest Log: 00:16:50.592108 timesync vgsvcTimeSyncWorker: Radical host time change: 3 695 066 000 000ns (HostNow=1 609 520 335 516 000 000 ns HostLast=1 609 516 640 450 000 000 ns) 2021-01-01 19:59:54 (8776): Guest Log: 00:17:00.594386 timesync vgsvcTimeSyncWorker: Radical guest time change: 3 649 889 797 000ns (GuestNow=1 609 520 345 518 281 000 ns GuestLast=1 609 516 695 628 484 000 ns fSetTimeLastLoop=true ) 2021-01-01 20:14:59 (8776): VM state change detected. (old = 'Running', new = 'Paused') 2021-01-01 20:23:17 (8776): VM state change detected. (old = 'Paused', new = 'Running') 2021-01-01 21:20:11 (8776): VM state change detected. (old = 'Running', new = 'Paused') 2021-01-01 21:24:29 (8776): VM state change detected. (old = 'Paused', new = 'Running') 2021-01-01 21:24:52 (8776): VM state change detected. (old = 'Running', new = 'Paused') 2021-01-01 21:27:52 (8776): VM state change detected. (old = 'Paused', new = 'Running') 2021-01-01 21:30:03 (8776): VM state change detected. (old = 'Running', new = 'Paused') 2021-01-01 21:59:38 (8776): VM state change detected. (old = 'Paused', new = 'Running') 2021-01-01 21:59:54 (8776): Guest Log: 01:31:52.027067 timesync vgsvcTimeSyncWorker: Radical guest time change: 2 724 521 340 000ns (GuestNow=1 609 527 551 464 149 000 ns GuestLast=1 609 524 826 942 809 000 ns fSetTimeLastLoop=true ) 2021-01-01 22:06:30 (8776): VM state change detected. (old = 'Running', new = 'Paused') 2021-01-01 22:12:54 (8776): Stopping VM. 2021-01-02 14:24:41 (10376): Detected: vboxwrapper 26197 2021-01-02 14:24:41 (10376): Detected: BOINC client v7.7 2021-01-02 14:24:42 (10376): Detected: VirtualBox VboxManage Interface (Version: 6.1.16) 2021-01-02 14:24:42 (10376): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds) 2021-01-02 14:24:43 (10376): Starting VM using VBoxManage interface. (boinc_5c48a61c5b26b3d6, slot#10) 2021-01-02 14:24:55 (10376): Successfully started VM. (PID = '7324') 2021-01-02 14:24:55 (10376): Reporting VM Process ID to BOINC. 2021-01-02 14:24:55 (10376): VM state change detected. (old = 'PoweredOff', new = 'Running') 2021-01-02 14:24:55 (10376): Detected: Web Application Enabled (http://localhost:53725) 2021-01-02 14:24:55 (10376): Detected: Remote Desktop Enabled (localhost:53726) 2021-01-02 14:24:55 (10376): Status Report: Job Duration: '864000.000000' 2021-01-02 14:24:55 (10376): Status Report: Elapsed Time: '16478.571692' 2021-01-02 14:24:55 (10376): Status Report: CPU Time: '16367.468750' 2021-01-02 14:24:55 (10376): Preference change detected 2021-01-02 14:24:55 (10376): Setting CPU throttle for VM. (100%) 2021-01-02 14:24:56 (10376): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 300 seconds) or (Vbox_job.xml: 600 seconds)) 2021-01-02 14:24:58 (10376): Guest Log: 01:38:32.174068 timesync vgsvcTimeSyncWorker: Radical host time change: 58 711 597 000 000ns (HostNow=1 609 586 676 120 000 000 ns HostLast=1 609 527 964 523 000 000 ns) 2021-01-02 14:25:09 (10376): Guest Log: 01:38:42.184170 timesync vgsvcTimeSyncWorker: Radical guest time change: 58 734 518 956 000ns (GuestNow=1 609 586 686 130 101 000 ns GuestLast=1 609 527 951 611 145 000 ns fSetTimeLastLoop=true ) 2021-01-02 14:36:28 (10376): VM state change detected. (old = 'Running', new = 'Paused') 2021-01-02 14:56:29 (10376): VM state change detected. (old = 'Paused', new = 'Running') 2021-01-02 14:56:31 (10376): VM state change detected. (old = 'Running', new = 'Paused') 2021-01-02 14:59:49 (10376): VM state change detected. (old = 'Paused', new = 'Running') 2021-01-02 15:17:45 (10376): VM state change detected. (old = 'Running', new = 'Paused') 2021-01-02 16:15:09 (10376): VM state change detected. (old = 'Paused', new = 'Running') 2021-01-02 16:15:11 (10376): Guest Log: 02:08:02.790022 timesync vgsvcTimeSyncWorker: Radical host time change: 3 450 974 000 000ns (HostNow=1 609 593 265 988 000 000 ns HostLast=1 609 589 815 014 000 000 ns) 2021-01-02 16:15:21 (10376): Guest Log: 02:08:12.791223 timesync vgsvcTimeSyncWorker: Radical guest time change: 3 440 121 114 000ns (GuestNow=1 609 593 275 989 210 000 ns GuestLast=1 609 589 835 868 096 000 ns fSetTimeLastLoop=true ) 2021-01-02 16:17:27 (10376): VM state change detected. (old = 'Running', new = 'Paused') 2021-01-02 16:56:15 (10376): Stopping VM. 2021-01-03 13:50:03 (3092): Detected: vboxwrapper 26197 2021-01-03 13:50:03 (3092): Detected: BOINC client v7.7 2021-01-03 13:50:04 (3092): Detected: VirtualBox VboxManage Interface (Version: 6.1.16) 2021-01-03 13:50:04 (3092): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds) 2021-01-03 13:50:05 (3092): Starting VM using VBoxManage interface. (boinc_5c48a61c5b26b3d6, slot#10) 2021-01-03 13:50:17 (3092): Successfully started VM. (PID = '8480') 2021-01-03 13:50:17 (3092): Reporting VM Process ID to BOINC. 2021-01-03 13:50:17 (3092): VM state change detected. (old = 'PoweredOff', new = 'Running') 2021-01-03 13:50:17 (3092): Detected: Web Application Enabled (http://localhost:53725) 2021-01-03 13:50:17 (3092): Detected: Remote Desktop Enabled (localhost:53726) 2021-01-03 13:50:17 (3092): Status Report: Job Duration: '864000.000000' 2021-01-03 13:50:17 (3092): Status Report: Elapsed Time: '18356.571692' 2021-01-03 13:50:17 (3092): Status Report: CPU Time: '18258.156250' 2021-01-03 13:50:17 (3092): Preference change detected 2021-01-03 13:50:17 (3092): Setting CPU throttle for VM. (100%) 2021-01-03 13:50:18 (3092): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 300 seconds) or (Vbox_job.xml: 600 seconds)) 2021-01-03 13:50:19 (3092): Guest Log: 02:10:22.858563 timesync vgsvcTimeSyncWorker: Radical host time change: 77 576 956 000 000ns (HostNow=1 609 671 008 420 000 000 ns HostLast=1 609 593 431 464 000 000 ns) 2021-01-03 13:50:29 (3092): Guest Log: 02:10:32.859733 timesync vgsvcTimeSyncWorker: Radical guest time change: 77 612 364 625 000ns (GuestNow=1 609 671 018 421 168 000 ns GuestLast=1 609 593 406 056 543 000 ns fSetTimeLastLoop=true ) 2021-01-03 14:00:06 (3092): VM state change detected. (old = 'Running', new = 'Paused') 2021-01-03 14:16:52 (3092): VM state change detected. (old = 'Paused', new = 'Running') 2021-01-03 14:20:38 (3092): VM state change detected. (old = 'Running', new = 'Paused') 2021-01-03 14:35:14 (3092): VM state change detected. (old = 'Paused', new = 'Running') 2021-01-03 14:35:30 (3092): Guest Log: 02:24:13.122870 timesync vgsvcTimeSyncWorker: Radical guest time change: 1 860 295 625 000ns (GuestNow=1 609 673 688 978 433 000 ns GuestLast=1 609 671 828 682 808 000 ns fSetTimeLastLoop=true ) 2021-01-03 14:37:10 (3092): VM state change detected. (old = 'Running', new = 'Paused') 2021-01-03 14:46:50 (3092): VM state change detected. (old = 'Paused', new = 'Running') 2021-01-03 14:47:03 (3092): VM state change detected. (old = 'Running', new = 'Paused') 2021-01-03 16:24:43 (3092): VM state change detected. (old = 'Paused', new = 'Running') 2021-01-03 16:24:49 (3092): Guest Log: 02:26:13.147164 timesync vgsvcTimeSyncWorker: Radical host time change: 5 866 061 000 000ns (HostNow=1 609 680 277 932 000 000 ns HostLast=1 609 674 411 871 000 000 ns) 2021-01-03 16:24:59 (3092): Guest Log: 02:26:23.148509 timesync vgsvcTimeSyncWorker: Radical guest time change: 6 478 930 537 000ns (GuestNow=1 609 680 287 933 322 000 ns GuestLast=1 609 673 809 002 785 000 ns fSetTimeLastLoop=true ) 2021-01-03 16:30:59 (3092): VM state change detected. (old = 'Running', new = 'Paused') 2021-01-03 16:35:08 (3092): VM state change detected. (old = 'Paused', new = 'Running') 2021-01-03 16:55:57 (3092): VM state change detected. (old = 'Running', new = 'Paused') 2021-01-03 17:01:14 (3092): VM state change detected. (old = 'Paused', new = 'Running') 2021-01-03 17:22:30 (3092): VM state change detected. (old = 'Running', new = 'Paused') 2021-01-03 17:26:24 (3092): VM state change detected. (old = 'Paused', new = 'Running') 2021-01-03 17:39:21 (3092): VM state change detected. (old = 'Running', new = 'Paused') 2021-01-03 17:50:44 (3092): VM state change detected. (old = 'Paused', new = 'Running') 2021-01-03 17:50:47 (3092): VM state change detected. (old = 'Running', new = 'Paused') 2021-01-03 17:50:55 (3092): Stopping VM. 2021-01-04 13:10:08 (9852): Detected: vboxwrapper 26197 2021-01-04 13:10:08 (9852): Detected: BOINC client v7.7 2021-01-04 13:10:10 (9852): Detected: VirtualBox VboxManage Interface (Version: 6.1.16) 2021-01-04 13:10:10 (9852): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds) 2021-01-04 13:10:10 (9852): Starting VM using VBoxManage interface. (boinc_5c48a61c5b26b3d6, slot#10) 2021-01-04 13:10:24 (9852): Successfully started VM. (PID = '6896') 2021-01-04 13:10:24 (9852): Reporting VM Process ID to BOINC. 2021-01-04 13:10:24 (9852): VM state change detected. (old = 'PoweredOff', new = 'Running') 2021-01-04 13:10:24 (9852): Detected: Web Application Enabled (http://localhost:53725) 2021-01-04 13:10:24 (9852): Detected: Remote Desktop Enabled (localhost:53726) 2021-01-04 13:10:24 (9852): Status Report: Job Duration: '864000.000000' 2021-01-04 13:10:24 (9852): Status Report: Elapsed Time: '22856.434575' 2021-01-04 13:10:24 (9852): Status Report: CPU Time: '22831.562500' 2021-01-04 13:10:24 (9852): Preference change detected 2021-01-04 13:10:24 (9852): Setting CPU throttle for VM. (100%) 2021-01-04 13:10:25 (9852): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 300 seconds) or (Vbox_job.xml: 600 seconds)) 2021-01-04 13:10:29 (9852): Guest Log: 03:27:34.378362 timesync vgsvcTimeSyncWorker: Radical host time change: 70 268 240 000 000ns (HostNow=1 609 754 979 912 000 000 ns HostLast=1 609 684 711 672 000 000 ns) 2021-01-04 13:10:39 (9852): Guest Log: 03:27:44.379460 timesync vgsvcTimeSyncWorker: Radical guest time change: 71 030 749 870 000ns (GuestNow=1 609 754 989 913 069 000 ns GuestLast=1 609 683 959 163 199 000 ns fSetTimeLastLoop=true ) 2021-01-04 13:22:31 (9852): VM state change detected. (old = 'Running', new = 'Paused') 2021-01-04 13:29:17 (9852): VM state change detected. (old = 'Paused', new = 'Running') 2021-01-04 13:32:53 (9852): VM state change detected. (old = 'Running', new = 'Paused') 2021-01-04 14:27:24 (9852): Stopping VM. 2021-01-04 14:27:25 (9852): Error in stop VM for VM: -108 Command: VBoxManage -q controlvm "boinc_5c48a61c5b26b3d6" savestate Output: 2021-01-04 14:27:25 (9852): VM did not stop when requested. 2021-01-04 14:27:25 (9852): VM was successfully terminated. 2021-01-04 17:46:44 (15196): Detected: vboxwrapper 26197 2021-01-04 17:46:44 (15196): Detected: BOINC client v7.7 2021-01-04 17:46:45 (15196): Detected: VirtualBox VboxManage Interface (Version: 6.1.16) 2021-01-04 17:46:46 (15196): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds) 2021-01-04 17:46:46 (15196): Starting VM using VBoxManage interface. (boinc_5c48a61c5b26b3d6, slot#10) 2021-01-04 17:46:52 (15196): Successfully started VM. (PID = '9248') 2021-01-04 17:46:52 (15196): Reporting VM Process ID to BOINC. 2021-01-04 17:46:52 (15196): Guest Log: BIOS: VirtualBox 6.1.16 2021-01-04 17:46:52 (15196): Guest Log: CPUID EDX: 0x178bfbff 2021-01-04 17:46:52 (15196): Guest Log: BIOS: ata0-0: PCHS=16383/16/63 LCHS=1024/255/63 2021-01-04 17:46:52 (15196): VM state change detected. (old = 'PoweredOff', new = 'Running') 2021-01-04 17:46:52 (15196): Detected: Web Application Enabled (http://localhost:53725) 2021-01-04 17:46:52 (15196): Detected: Remote Desktop Enabled (localhost:53726) 2021-01-04 17:46:52 (15196): Status Report: Job Duration: '864000.000000' 2021-01-04 17:46:52 (15196): Status Report: Elapsed Time: '23783.532262' 2021-01-04 17:46:52 (15196): Status Report: CPU Time: '23763.906250' 2021-01-04 17:46:52 (15196): Preference change detected 2021-01-04 17:46:52 (15196): Setting CPU throttle for VM. (100%) 2021-01-04 17:46:52 (15196): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 300 seconds) or (Vbox_job.xml: 600 seconds)) 2021-01-04 17:46:54 (15196): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032 2021-01-04 17:46:54 (15196): Guest Log: BIOS: Booting from Hard Disk... 2021-01-04 17:46:57 (15196): Guest Log: BIOS: KBD: unsupported int 16h function 03 2021-01-04 17:46:57 (15196): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 2021-01-04 17:47:18 (15196): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds 2021-01-04 17:47:18 (15196): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000) 2021-01-04 17:47:22 (15196): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log 2021-01-04 17:47:22 (15196): Guest Log: 00:00:00.000114 main Log opened 2021-01-04T14:47:22.836640000Z 2021-01-04 17:47:22 (15196): Guest Log: 00:00:00.000258 main OS Product: Linux 2021-01-04 17:47:22 (15196): Guest Log: 00:00:00.000306 main OS Release: 4.14.76-13.cernvm.x86_64 2021-01-04 17:47:22 (15196): Guest Log: 00:00:00.000352 main OS Version: #1 SMP Tue Oct 16 18:26:15 CEST 2018 2021-01-04 17:47:22 (15196): Guest Log: 00:00:00.000403 main Executable: /usr/sbin/VBoxService 2021-01-04 17:47:22 (15196): Guest Log: 00:00:00.000404 main Process ID: 3195 2021-01-04 17:47:22 (15196): Guest Log: 00:00:00.000405 main Package type: LINUX_64BITS_GENERIC 2021-01-04 17:47:22 (15196): Guest Log: 00:00:00.003124 main 5.2.6 r120293 started. Verbose level = 0 2021-01-04 17:47:40 (15196): Guest Log: 17:47:41 MSK +03:00 2021-01-04: cranky: [INFO] Detected Theory App 2021-01-04 17:47:41 (15196): Guest Log: 17:47:41 MSK +03:00 2021-01-04: cranky: [INFO] Checking CVMFS. 2021-01-04 17:47:41 (15196): Guest Log: Probing /cvmfs/sft.cern.ch... OK 2021-01-04 17:47:41 (15196): 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 2021-01-04 17:47:41 (15196): Guest Log: 2.5.2.0 4099 0 27760 19418 3 1 1356257 4096000 0 65024 0 0 n/a 5 11 http://s1cern-cvmfs.openhtc.io/cvmfs/sft.cern.ch DIRECT 1 2021-01-04 17:47:42 (15196): Guest Log: Probing /cvmfs/grid.cern.ch... OK 2021-01-04 17:47:42 (15196): 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 2021-01-04 17:47:42 (15196): Guest Log: 2.5.2.0 4055 0 27688 14109 3 2 1356257 4096000 0 65024 0 2 0 4281 2607 http://s1bnl-cvmfs.openhtc.io/cvmfs/grid.cern.ch DIRECT 1 2021-01-04 17:47:43 (15196): Guest Log: Probing /cvmfs/cernvm-prod.cern.ch... OK 2021-01-04 17:47:43 (15196): 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 2021-01-04 17:47:43 (15196): Guest Log: 2.5.2.0 4030 0 27220 259 3 1 1356257 4096000 0 65024 0 0 n/a 0 0 http://s1cern-cvmfs.openhtc.io/cvmfs/cernvm-prod.cern.ch DIRECT 1 2021-01-04 17:47:43 (15196): Guest Log: Probing /cvmfs/alice.cern.ch... OK 2021-01-04 17:47:44 (15196): 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 2021-01-04 17:47:44 (15196): Guest Log: 2.5.2.0 4077 0 30688 8320 3 1 1356257 4096000 0 65024 0 0 n/a 4701 2402 http://s1cern-cvmfs.openhtc.io/cvmfs/alice.cern.ch DIRECT 1 2021-01-04 17:47:44 (15196): Guest Log: 17:47:45 MSK +03:00 2021-01-04: cranky: [INFO] Checking runc. 2021-01-04 17:47:44 (15196): Guest Log: 17:47:45 MSK +03:00 2021-01-04: cranky: [INFO] Creating the filesystem. 2021-01-04 17:47:44 (15196): Guest Log: 17:47:45 MSK +03:00 2021-01-04: cranky: [INFO] Using /cvmfs/cernvm-prod.cern.ch/cvm3 2021-01-04 17:47:44 (15196): Guest Log: 17:47:45 MSK +03:00 2021-01-04: cranky: [INFO] Updating config.json. 2021-01-04 17:47:44 (15196): Guest Log: 17:47:45 MSK +03:00 2021-01-04: cranky: [INFO] Running Container 'runc'. 2021-01-04 17:47:45 (15196): Guest Log: job: htmld=/shared/html/job 2021-01-04 17:47:45 (15196): Guest Log: job: unpack exitcode=0 2021-01-04 17:47:46 (15196): Guest Log: 17:47:47 MSK +03:00 2021-01-04: cranky: [INFO] ===> [runRivet] Mon Jan 4 14:47:46 UTC 2021 [boinc pp jets 7000 800 - pythia8 8.243 tune-AU2m 100000 162] 2021-01-04 17:50:24 (15196): VM state change detected. (old = 'Running', new = 'Paused') 2021-01-04 17:55:14 (15196): VM state change detected. (old = 'Paused', new = 'Running') 2021-01-04 17:55:51 (15196): VM state change detected. (old = 'Running', new = 'Paused') 2021-01-04 18:02:10 (15196): Stopping VM. 2021-01-05 00:54:12 (11984): Detected: vboxwrapper 26197 2021-01-05 00:54:12 (11984): Detected: BOINC client v7.7 2021-01-05 00:54:13 (11984): Detected: VirtualBox VboxManage Interface (Version: 6.1.16) 2021-01-05 00:54:14 (11984): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds) 2021-01-05 00:54:14 (11984): Starting VM using VBoxManage interface. (boinc_5c48a61c5b26b3d6, slot#10) 2021-01-05 00:54:30 (11984): Successfully started VM. (PID = '7276') 2021-01-05 00:54:30 (11984): Reporting VM Process ID to BOINC. 2021-01-05 00:54:30 (11984): Guest Log: 00:03:40.305138 timesync vgsvcTimeSyncWorker: Radical host time change: 25 128 981 000 000ns (HostNow=1 609 797 270 197 000 000 ns HostLast=1 609 772 141 216 000 000 ns) 2021-01-05 00:54:30 (11984): VM state change detected. (old = 'PoweredOff', new = 'Running') 2021-01-05 00:54:30 (11984): Detected: Web Application Enabled (http://localhost:53725) 2021-01-05 00:54:30 (11984): Detected: Remote Desktop Enabled (localhost:53726) 2021-01-05 00:54:30 (11984): Status Report: Job Duration: '864000.000000' 2021-01-05 00:54:30 (11984): Status Report: Elapsed Time: '24026.532262' 2021-01-05 00:54:30 (11984): Status Report: CPU Time: '23972.515625' 2021-01-05 00:54:30 (11984): Preference change detected 2021-01-05 00:54:30 (11984): Setting CPU throttle for VM. (100%) 2021-01-05 00:54:30 (11984): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 300 seconds) or (Vbox_job.xml: 600 seconds)) 2021-01-05 00:54:40 (11984): Guest Log: 00:03:50.337171 timesync vgsvcTimeSyncWorker: Radical guest time change: 25 417 209 217 000ns (GuestNow=1 609 797 280 267 119 000 ns GuestLast=1 609 771 863 057 902 000 ns fSetTimeLastLoop=true ) 2021-01-05 01:20:33 (11984): VM state change detected. (old = 'Running', new = 'Paused') 2021-01-05 01:22:11 (11984): Stopping VM. 2021-01-05 04:54:47 (1324): Detected: vboxwrapper 26197 2021-01-05 04:54:47 (1324): Detected: BOINC client v7.7 2021-01-05 04:54:49 (1324): Detected: VirtualBox VboxManage Interface (Version: 6.1.16) 2021-01-05 04:54:49 (1324): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds) 2021-01-05 04:54:49 (1324): Starting VM using VBoxManage interface. (boinc_5c48a61c5b26b3d6, slot#10) 2021-01-05 04:55:02 (1324): Successfully started VM. (PID = '12496') 2021-01-05 04:55:02 (1324): Reporting VM Process ID to BOINC. 2021-01-05 04:55:02 (1324): VM state change detected. (old = 'PoweredOff', new = 'Running') 2021-01-05 04:55:02 (1324): Detected: Web Application Enabled (http://localhost:53725) 2021-01-05 04:55:02 (1324): Detected: Remote Desktop Enabled (localhost:53726) 2021-01-05 04:55:02 (1324): Status Report: Job Duration: '864000.000000' 2021-01-05 04:55:02 (1324): Status Report: Elapsed Time: '25558.860459' 2021-01-05 04:55:02 (1324): Status Report: CPU Time: '25517.187500' 2021-01-05 04:55:02 (1324): Preference change detected 2021-01-05 04:55:02 (1324): Setting CPU throttle for VM. (100%) 2021-01-05 04:55:03 (1324): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 300 seconds) or (Vbox_job.xml: 600 seconds)) 2021-01-05 04:55:08 (1324): Guest Log: 00:29:50.880550 timesync vgsvcTimeSyncWorker: Radical host time change: 12 877 776 000 000ns (HostNow=1 609 811 708 460 000 000 ns HostLast=1 609 798 830 684 000 000 ns) 2021-01-05 04:55:18 (1324): Guest Log: 00:30:00.885801 timesync vgsvcTimeSyncWorker: Radical guest time change: 12 877 654 779 000ns (GuestNow=1 609 811 718 465 263 000 ns GuestLast=1 609 798 840 810 484 000 ns fSetTimeLastLoop=true ) 2021-01-05 04:58:31 (1324): VM state change detected. (old = 'Running', new = 'Paused') 2021-01-05 05:03:23 (1324): VM state change detected. (old = 'Paused', new = 'Running') 2021-01-05 06:48:52 (1324): Status Report: Job Duration: '864000.000000' 2021-01-05 06:48:52 (1324): Status Report: Elapsed Time: '31559.385150' 2021-01-05 06:48:52 (1324): Status Report: CPU Time: '31707.359375' 2021-01-05 08:37:54 (1324): Status Report: Job Duration: '864000.000000' 2021-01-05 08:37:54 (1324): Status Report: Elapsed Time: '37560.344247' 2021-01-05 08:37:54 (1324): Status Report: CPU Time: '37931.687500' 2021-01-05 09:23:21 (1324): VM state change detected. (old = 'Running', new = 'Paused') 2021-01-05 09:26:20 (1324): VM state change detected. (old = 'Paused', new = 'Running') 2021-01-05 10:31:31 (1324): Status Report: Job Duration: '864000.000000' 2021-01-05 10:31:31 (1324): Status Report: Elapsed Time: '43560.660412' 2021-01-05 10:31:31 (1324): Status Report: CPU Time: '44307.765625' 2021-01-05 12:22:01 (1324): Status Report: Job Duration: '864000.000000' 2021-01-05 12:22:01 (1324): Status Report: Elapsed Time: '49560.982899' 2021-01-05 12:22:01 (1324): Status Report: CPU Time: '50455.296875' 2021-01-05 13:07:04 (1324): Guest Log: job: run exitcode=0 2021-01-05 13:07:04 (1324): Guest Log: job: diskusage=2776 2021-01-05 13:07:04 (1324): Guest Log: job: logsize=92 k 2021-01-05 13:07:04 (1324): Guest Log: job: times= 2021-01-05 13:07:04 (1324): Guest Log: 0m0.010s 0m0.014s 2021-01-05 13:07:04 (1324): Guest Log: 504m5.840s 4m36.105s 2021-01-05 13:07:04 (1324): Guest Log: job: cpuusage=30522 2021-01-05 13:07:04 (1324): Guest Log: 12:59:29 MSK +03:00 2021-01-05: cranky: [INFO] Container 'runc' finished with status code 0. 2021-01-05 13:07:04 (1324): Guest Log: 12:59:29 MSK +03:00 2021-01-05: cranky: [INFO] Preparing output. 2021-01-05 13:07:08 (1324): Guest Log: [INFO] Job Finished 2021-01-05 13:07:08 (1324): Guest Log: [INFO] Shutting Down. 2021-01-05 13:07:08 (1324): VM Completion File Detected. 2021-01-05 13:07:08 (1324): VM Completion Message: Job Finished . 2021-01-05 13:07:08 (1324): Powering off VM. 2021-01-05 13:12:10 (1324): VM did not power off when requested. 2021-01-05 13:12:10 (1324): VM was successfully terminated. 2021-01-05 13:12:10 (1324): Deregistering VM. (boinc_5c48a61c5b26b3d6, slot#10) 2021-01-05 13:12:10 (1324): Removing network bandwidth throttle group from VM. 2021-01-05 13:12:10 (1324): Removing VM from VirtualBox. 13:12:16 (1324): called boinc_finish(0) </stderr_txt> ]]>
©2024 CERN