| Name | Theory_2390-1088916-12_0 |
| Workunit | 140176610 |
| Created | 25 May 2020, 15:43:19 UTC |
| Sent | 25 May 2020, 19:46:13 UTC |
| Report deadline | 5 Jun 2020, 19:46:13 UTC |
| Received | 6 Jun 2020, 5:28:54 UTC |
| Server state | Over |
| Outcome | Success |
| Client state | Done |
| Exit status | 0 (0x00000000) |
| Computer ID | 10636330 |
| Run time | 15 hours 6 min 19 sec |
| CPU time | 8 hours 55 min |
| Validate state | Valid |
| Credit | 534.58 |
| Device peak FLOPS | 4.25 GFLOPS |
| Application version | Theory Simulation v300.06 (vbox64_theory) windows_x86_64 |
| Peak working set size | 61.86 MB |
| Peak swap size | 82.14 MB |
| Peak disk usage | 2.24 GB |
Stderr output
<core_client_version>7.14.2</core_client_version> <![CDATA[ <stderr_txt> etected: BOINC client v7.7 2020-05-26 08:59:58 (22356): Detected: VirtualBox VboxManage Interface (Version: 6.1.4) 2020-05-26 08:59:59 (22356): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds) 2020-05-26 08:59:59 (22356): Successfully copied 'init_data.xml' to the shared directory. 2020-05-26 08:59:59 (22356): Successfully copied 'input' to the shared directory. 2020-05-26 09:00:01 (22356): Create VM. (boinc_956ad126053fe042, slot#9) 2020-05-26 09:00:01 (22356): Setting Memory Size for VM. (630MB) 2020-05-26 09:00:02 (22356): Setting CPU Count for VM. (1) 2020-05-26 09:00:02 (22356): Setting Chipset Options for VM. 2020-05-26 09:00:02 (22356): Setting Boot Options for VM. 2020-05-26 09:00:02 (22356): Setting Network Configuration for NAT. 2020-05-26 09:00:03 (22356): Enabling VM Network Access. 2020-05-26 09:00:03 (22356): Disabling USB Support for VM. 2020-05-26 09:00:03 (22356): Disabling COM Port Support for VM. 2020-05-26 09:00:03 (22356): Disabling LPT Port Support for VM. 2020-05-26 09:00:04 (22356): Disabling Audio Support for VM. 2020-05-26 09:00:04 (22356): Disabling Clipboard Support for VM. 2020-05-26 09:00:04 (22356): Disabling Drag and Drop Support for VM. 2020-05-26 09:00:04 (22356): Adding storage controller(s) to VM. 2020-05-26 09:00:05 (22356): Adding virtual disk drive to VM. (vm_image.vdi) 2020-05-26 09:00:05 (22356): Adding VirtualBox Guest Additions to VM. 2020-05-26 09:00:05 (22356): Adding network bandwidth throttle group to VM. (Defaulting to 1024GB) 2020-05-26 09:00:05 (22356): forwarding host port 64733 to guest port 80 2020-05-26 09:00:06 (22356): Enabling remote desktop for VM. 2020-05-26 09:00:06 (22356): Enabling shared directory for VM. 2020-05-26 09:00:06 (22356): Starting VM using VBoxManage interface. (boinc_956ad126053fe042, slot#9) 2020-05-26 09:00:12 (22356): Successfully started VM. (PID = '22800') 2020-05-26 09:00:12 (22356): Reporting VM Process ID to BOINC. 2020-05-26 09:00:12 (22356): Guest Log: BIOS: VirtualBox 6.1.4 2020-05-26 09:00:12 (22356): Guest Log: CPUID EDX: 0x178bfbff 2020-05-26 09:00:12 (22356): Guest Log: BIOS: ata0-0: PCHS=16383/16/63 LCHS=1024/255/63 2020-05-26 09:00:12 (22356): VM state change detected. (old = 'PoweredOff', new = 'Running') 2020-05-26 09:00:12 (22356): Detected: Web Application Enabled (http://localhost:64733) 2020-05-26 09:00:12 (22356): Detected: Remote Desktop Enabled (localhost:64734) 2020-05-26 09:00:12 (22356): Preference change detected 2020-05-26 09:00:12 (22356): Setting CPU throttle for VM. (65%) 2020-05-26 09:00:12 (22356): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds)) 2020-05-26 09:00:14 (22356): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032 2020-05-26 09:00:14 (22356): Guest Log: BIOS: Booting from Hard Disk... 2020-05-26 09:00:16 (22356): Guest Log: BIOS: KBD: unsupported int 16h function 03 2020-05-26 09:00:16 (22356): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 2020-05-26 09:00:40 (22356): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds 2020-05-26 09:00:40 (22356): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000) 2020-05-26 09:00:43 (22356): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log 2020-05-26 09:00:43 (22356): Guest Log: 00:00:00.000124 main Log opened 2020-05-26T01:00:46.468544000Z 2020-05-26 09:00:43 (22356): Guest Log: 00:00:00.000281 main OS Product: Linux 2020-05-26 09:00:43 (22356): Guest Log: 00:00:00.000355 main OS Release: 4.14.76-13.cernvm.x86_64 2020-05-26 09:00:43 (22356): Guest Log: 00:00:00.000396 main OS Version: #1 SMP Tue Oct 16 18:26:15 CEST 2018 2020-05-26 09:00:43 (22356): Guest Log: 00:00:00.000434 main Executable: /usr/sbin/VBoxService 2020-05-26 09:00:43 (22356): Guest Log: 00:00:00.000435 main Process ID: 3208 2020-05-26 09:00:43 (22356): Guest Log: 00:00:00.000436 main Package type: LINUX_64BITS_GENERIC 2020-05-26 09:00:43 (22356): Guest Log: 00:00:00.003769 main 5.2.6 r120293 started. Verbose level = 0 2020-05-26 09:01:31 (22356): Guest Log: 09:01:34 PST +08:00 2020-05-26: cranky: [INFO] Detected Theory App 2020-05-26 09:01:31 (22356): Guest Log: 09:01:34 PST +08:00 2020-05-26: cranky: [INFO] Checking CVMFS. 2020-05-26 09:01:32 (22356): Guest Log: Probing /cvmfs/sft.cern.ch... OK 2020-05-26 09:01:33 (22356): 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-05-26 09:01:33 (22356): Guest Log: 2.5.2.0 4056 0 28236 18077 3 1 263481 4096000 0 65024 0 0 n/a 5 1 http://s1asgc-cvmfs.openhtc.io:8080/cvmfs/sft.cern.ch DIRECT 1 2020-05-26 09:01:33 (22356): Guest Log: Probing /cvmfs/grid.cern.ch... OK 2020-05-26 09:01:34 (22356): 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-05-26 09:01:34 (22356): Guest Log: 2.5.2.0 4140 0 27540 12323 3 2 263481 4096000 0 65024 0 2 -100 6411 277 http://s1unl-cvmfs.openhtc.io/cvmfs/grid.cern.ch DIRECT 1 2020-05-26 09:01:35 (22356): Guest Log: Probing /cvmfs/cernvm-prod.cern.ch... OK 2020-05-26 09:01:36 (22356): 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-05-26 09:01:36 (22356): Guest Log: 2.5.2.0 4084 0 25180 249 3 1 263481 4096000 0 65024 0 0 n/a 4 2 http://s1asgc-cvmfs.openhtc.io:8080/cvmfs/cernvm-prod.cern.ch DIRECT 1 2020-05-26 09:01:37 (22356): Guest Log: Probing /cvmfs/alice.cern.ch... OK 2020-05-26 09:01:38 (22356): 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-05-26 09:01:38 (22356): Guest Log: 2.5.2.0 4116 0 32208 6666 3 1 263481 4096000 0 65024 0 0 n/a 4071 241 http://s1cern-cvmfs.openhtc.io/cvmfs/alice.cern.ch DIRECT 1 2020-05-26 09:01:38 (22356): Guest Log: 09:01:41 PST +08:00 2020-05-26: cranky: [INFO] Checking runc. 2020-05-26 09:01:38 (22356): Guest Log: 09:01:41 PST +08:00 2020-05-26: cranky: [INFO] Creating the filesystem. 2020-05-26 09:01:38 (22356): Guest Log: 09:01:41 PST +08:00 2020-05-26: cranky: [INFO] Using /cvmfs/cernvm-prod.cern.ch/cvm3 2020-05-26 09:01:38 (22356): Guest Log: 09:01:41 PST +08:00 2020-05-26: cranky: [INFO] Updating config.json. 2020-05-26 09:01:38 (22356): Guest Log: 09:01:41 PST +08:00 2020-05-26: cranky: [INFO] Running Container 'runc'. 2020-05-26 09:01:39 (22356): Guest Log: job: htmld=/shared/html/job 2020-05-26 09:01:39 (22356): Guest Log: job: unpack exitcode=0 2020-05-26 09:01:40 (22356): Guest Log: 09:01:43 PST +08:00 2020-05-26: cranky: [INFO] ===> [runRivet] Tue May 26 01:01:42 UTC 2020 [boinc ppbar jets 1960 17 - pythia8 8.240 tune-AU2m 100000 12] 2020-05-26 09:03:49 (22356): VM state change detected. (old = 'Running', new = 'Paused') 2020-05-26 09:03:59 (22356): VM state change detected. (old = 'Paused', new = 'Running') 2020-05-26 09:05:29 (22356): VM state change detected. (old = 'Running', new = 'Paused') 2020-05-26 09:05:39 (22356): VM state change detected. (old = 'Paused', new = 'Running') 2020-05-26 09:10:40 (22356): VM state change detected. (old = 'Running', new = 'Paused') 2020-05-26 09:10:50 (22356): VM state change detected. (old = 'Paused', new = 'Running') 2020-05-26 09:14:04 (22356): VM state change detected. (old = 'Running', new = 'Paused') 2020-05-26 09:14:14 (22356): VM state change detected. (old = 'Paused', new = 'Running') 2020-05-26 09:19:14 (22356): VM state change detected. (old = 'Running', new = 'Paused') 2020-05-26 09:19:24 (22356): VM state change detected. (old = 'Paused', new = 'Running') 2020-05-26 09:28:45 (22356): VM state change detected. (old = 'Running', new = 'Paused') 2020-05-26 09:28:55 (22356): VM state change detected. (old = 'Paused', new = 'Running') 2020-05-26 09:30:55 (22356): VM state change detected. (old = 'Running', new = 'Paused') 2020-05-26 09:31:05 (22356): VM state change detected. (old = 'Paused', new = 'Running') 2020-05-26 09:36:57 (22356): VM state change detected. (old = 'Running', new = 'Paused') 2020-05-26 09:37:07 (22356): VM state change detected. (old = 'Paused', new = 'Running') 2020-05-26 09:37:17 (22356): VM state change detected. (old = 'Running', new = 'Paused') 2020-05-26 09:37:27 (22356): VM state change detected. (old = 'Paused', new = 'Running') 2020-05-26 09:37:37 (22356): VM state change detected. (old = 'Running', new = 'Paused') 2020-05-26 09:37:47 (22356): VM state change detected. (old = 'Paused', new = 'Running') 2020-05-26 09:48:28 (22356): VM state change detected. (old = 'Running', new = 'Paused') 2020-05-26 09:48:38 (22356): VM state change detected. (old = 'Paused', new = 'Running') 2020-05-26 09:54:39 (22356): VM state change detected. (old = 'Running', new = 'Paused') 2020-05-26 09:54:49 (22356): VM state change detected. (old = 'Paused', new = 'Running') 2020-05-26 09:56:06 (22356): VM state change detected. (old = 'Running', new = 'Paused') 2020-05-26 09:57:12 (17300): Detected: vboxwrapper 26197 2020-05-26 09:57:12 (17300): Detected: BOINC client v7.7 2020-05-26 09:57:13 (17300): Detected: VirtualBox VboxManage Interface (Version: 6.1.4) 2020-05-26 09:57:13 (17300): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds) 2020-05-26 09:57:14 (17300): Starting VM using VBoxManage interface. (boinc_956ad126053fe042, slot#9) 2020-05-26 09:57:23 (17300): Successfully started VM. (PID = '29676') 2020-05-26 09:57:23 (17300): Reporting VM Process ID to BOINC. 2020-05-26 09:57:23 (17300): Guest Log: BIOS: VirtualBox 6.1.4 2020-05-26 09:57:23 (17300): Guest Log: CPUID EDX: 0x178bfbff 2020-05-26 09:57:23 (17300): Guest Log: BIOS: ata0-0: PCHS=16383/16/63 LCHS=1024/255/63 2020-05-26 09:57:23 (17300): VM state change detected. (old = 'PoweredOff', new = 'Running') 2020-05-26 09:57:23 (17300): Detected: Web Application Enabled (http://localhost:64733) 2020-05-26 09:57:23 (17300): Detected: Remote Desktop Enabled (localhost:64734) 2020-05-26 09:57:23 (17300): Preference change detected 2020-05-26 09:57:23 (17300): Setting CPU throttle for VM. (65%) 2020-05-26 09:57:23 (17300): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds)) 2020-05-26 09:57:25 (17300): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032 2020-05-26 09:57:25 (17300): Guest Log: BIOS: Booting from Hard Disk... 2020-05-26 09:57:28 (17300): Guest Log: BIOS: KBD: unsupported int 16h function 03 2020-05-26 09:57:28 (17300): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 2020-05-26 09:58:02 (17300): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds 2020-05-26 09:58:02 (17300): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000) 2020-05-26 09:58:08 (17300): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log 2020-05-26 09:58:08 (17300): Guest Log: 00:00:00.000159 main Log opened 2020-05-26T01:58:07.404616000Z 2020-05-26 09:58:08 (17300): Guest Log: 00:00:00.000293 main OS Product: Linux 2020-05-26 09:58:08 (17300): Guest Log: 00:00:00.000374 main OS Release: 4.14.76-13.cernvm.x86_64 2020-05-26 09:58:08 (17300): Guest Log: 00:00:00.000425 main OS Version: #1 SMP Tue Oct 16 18:26:15 CEST 2018 2020-05-26 09:58:08 (17300): Guest Log: 00:00:00.000491 main Executable: /usr/sbin/VBoxService 2020-05-26 09:58:08 (17300): Guest Log: 00:00:00.000492 main Process ID: 3221 2020-05-26 09:58:08 (17300): Guest Log: 00:00:00.000494 main Package type: LINUX_64BITS_GENERIC 2020-05-26 09:58:08 (17300): Guest Log: 00:00:00.004799 main 5.2.6 r120293 started. Verbose level = 0 2020-05-26 09:58:39 (17300): Guest Log: 09:58:37 PST +08:00 2020-05-26: cranky: [INFO] Detected Theory App 2020-05-26 09:58:39 (17300): Guest Log: 09:58:37 PST +08:00 2020-05-26: cranky: [INFO] Checking CVMFS. 2020-05-26 09:58:40 (17300): Guest Log: Probing /cvmfs/sft.cern.ch... OK 2020-05-26 09:58:41 (17300): 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-05-26 09:58:41 (17300): Guest Log: 2.5.2.0 4054 0 27192 18077 3 1 1148701 4096001 0 65024 0 0 n/a 0 0 http://s1asgc-cvmfs.openhtc.io:8080/cvmfs/sft.cern.ch DIRECT 1 2020-05-26 09:58:42 (17300): Guest Log: Probing /cvmfs/grid.cern.ch... OK 2020-05-26 09:58:43 (17300): 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-05-26 09:58:43 (17300): Guest Log: 2.5.2.0 4100 0 27508 12323 3 2 1148701 4096001 0 65024 0 2 100 0 0 http://s1asgc-cvmfs.openhtc.io:8080/cvmfs/grid.cern.ch DIRECT 1 2020-05-26 09:58:44 (17300): Guest Log: Probing /cvmfs/cernvm-prod.cern.ch... OK 2020-05-26 09:58:45 (17300): 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-05-26 09:58:45 (17300): Guest Log: 2.5.2.0 4123 0 27216 249 3 1 1148701 4096001 0 65024 0 0 n/a 0 0 http://s1bnl-cvmfs.openhtc.io/cvmfs/cernvm-prod.cern.ch DIRECT 1 2020-05-26 09:58:47 (17300): Guest Log: Probing /cvmfs/alice.cern.ch... OK 2020-05-26 09:58:48 (17300): 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-05-26 09:58:48 (17300): Guest Log: 2.5.2.0 4065 0 29616 6666 3 1 1148701 4096001 0 65024 0 0 n/a 0 0 http://s1asgc-cvmfs.openhtc.io:8080/cvmfs/alice.cern.ch DIRECT 1 2020-05-26 09:58:48 (17300): Guest Log: 09:58:46 PST +08:00 2020-05-26: cranky: [INFO] Checking runc. 2020-05-26 09:58:48 (17300): Guest Log: 09:58:47 PST +08:00 2020-05-26: cranky: [INFO] Creating the filesystem. 2020-05-26 09:58:48 (17300): Guest Log: 09:58:47 PST +08:00 2020-05-26: cranky: [INFO] Using /cvmfs/cernvm-prod.cern.ch/cvm3 2020-05-26 09:58:49 (17300): Guest Log: 09:58:47 PST +08:00 2020-05-26: cranky: [INFO] Updating config.json. 2020-05-26 09:58:49 (17300): Guest Log: 09:58:47 PST +08:00 2020-05-26: cranky: [INFO] Running Container 'runc'. 2020-05-26 09:58:51 (17300): Guest Log: job: htmld=/shared/html/job 2020-05-26 09:58:51 (17300): Guest Log: job: unpack exitcode=0 2020-05-26 09:58:53 (17300): Guest Log: 09:58:52 PST +08:00 2020-05-26: cranky: [INFO] ===> [runRivet] Tue May 26 01:58:50 UTC 2020 [boinc ppbar jets 1960 17 - pythia8 8.240 tune-AU2m 100000 12] 2020-05-26 09:59:52 (17300): VM state change detected. (old = 'Running', new = 'Paused') 2020-05-26 10:00:01 (17300): VM state change detected. (old = 'Paused', new = 'Running') 2020-05-26 10:00:21 (17300): VM state change detected. (old = 'Running', new = 'Paused') 2020-05-26 10:00:31 (17300): VM state change detected. (old = 'Paused', new = 'Running') 2020-05-26 10:04:21 (17300): VM state change detected. (old = 'Running', new = 'Paused') 2020-05-26 10:04:32 (17300): VM state change detected. (old = 'Paused', new = 'Running') 2020-05-26 10:07:24 (17300): VM state change detected. (old = 'Running', new = 'Paused') 2020-05-26 10:07:34 (17300): VM state change detected. (old = 'Paused', new = 'Running') 2020-05-26 10:08:14 (17300): VM state change detected. (old = 'Running', new = 'Paused') 2020-05-26 10:08:24 (17300): VM state change detected. (old = 'Paused', new = 'Running') 2020-05-26 10:08:54 (17300): VM state change detected. (old = 'Running', new = 'Paused') 2020-05-26 10:09:03 (17300): VM state change detected. (old = 'Paused', new = 'Running') 2020-05-26 10:10:04 (17300): VM state change detected. (old = 'Running', new = 'Paused') 2020-05-26 10:10:14 (17300): VM state change detected. (old = 'Paused', new = 'Running') 2020-05-26 10:34:57 (17300): VM state change detected. (old = 'Running', new = 'Paused') 2020-05-26 10:35:07 (17300): VM state change detected. (old = 'Paused', new = 'Running') 2020-05-26 10:39:59 (17300): VM state change detected. (old = 'Running', new = 'Paused') 2020-05-26 12:43:34 (23968): Detected: vboxwrapper 26197 2020-05-26 12:43:34 (23968): Detected: BOINC client v7.7 2020-05-26 12:43:35 (23968): Detected: VirtualBox VboxManage Interface (Version: 6.1.4) 2020-05-26 12:43:35 (23968): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds) 2020-05-26 12:43:36 (23968): Starting VM using VBoxManage interface. (boinc_956ad126053fe042, slot#9) 2020-05-26 12:43:45 (23968): Successfully started VM. (PID = '22360') 2020-05-26 12:43:45 (23968): Reporting VM Process ID to BOINC. 2020-05-26 12:43:45 (23968): Guest Log: BIOS: VirtualBox 6.1.4 2020-05-26 12:43:45 (23968): Guest Log: CPUID EDX: 0x178bfbff 2020-05-26 12:43:45 (23968): Guest Log: BIOS: ata0-0: PCHS=16383/16/63 LCHS=1024/255/63 2020-05-26 12:43:45 (23968): VM state change detected. (old = 'PoweredOff', new = 'Running') 2020-05-26 12:43:45 (23968): Detected: Web Application Enabled (http://localhost:64733) 2020-05-26 12:43:45 (23968): Detected: Remote Desktop Enabled (localhost:64734) 2020-05-26 12:43:46 (23968): VM state change detected. (old = 'Running', new = 'Paused') 2020-05-26 12:43:52 (23968): Preference change detected 2020-05-26 12:43:52 (23968): Setting CPU throttle for VM. (65%) 2020-05-26 12:43:53 (23968): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds)) 2020-05-26 12:43:53 (23968): VM state change detected. (old = 'Paused', new = 'Running') 2020-05-26 12:43:54 (23968): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032 2020-05-26 12:43:54 (23968): Guest Log: BIOS: Booting from Hard Disk... 2020-05-26 12:43:56 (23968): Guest Log: BIOS: KBD: unsupported int 16h function 03 2020-05-26 12:43:56 (23968): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 2020-05-26 12:44:14 (23968): VM state change detected. (old = 'Running', new = 'Paused') 2020-05-26 12:44:23 (23968): VM state change detected. (old = 'Paused', new = 'Running') 2020-05-26 12:44:35 (23968): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds 2020-05-26 12:44:35 (23968): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000) 2020-05-26 12:44:39 (23968): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log 2020-05-26 12:44:39 (23968): Guest Log: 00:00:00.000111 main Log opened 2020-05-26T04:44:29.496793000Z 2020-05-26 12:44:39 (23968): Guest Log: 00:00:00.000332 main OS Product: Linux 2020-05-26 12:44:39 (23968): Guest Log: 00:00:00.000373 main OS Release: 4.14.76-13.cernvm.x86_64 2020-05-26 12:44:39 (23968): Guest Log: 00:00:00.000403 main OS Version: #1 SMP Tue Oct 16 18:26:15 CEST 2018 2020-05-26 12:44:39 (23968): Guest Log: 00:00:00.000432 main Executable: /usr/sbin/VBoxService 2020-05-26 12:44:39 (23968): Guest Log: 00:00:00.000433 main Process ID: 3200 2020-05-26 12:44:39 (23968): Guest Log: 00:00:00.000434 main Package type: LINUX_64BITS_GENERIC 2020-05-26 12:44:39 (23968): Guest Log: 00:00:00.175261 main 5.2.6 r120293 started. Verbose level = 0 2020-05-26 12:45:11 (23968): Guest Log: 12:45:02 PST +08:00 2020-05-26: cranky: [INFO] Detected Theory App 2020-05-26 12:45:11 (23968): Guest Log: 12:45:02 PST +08:00 2020-05-26: cranky: [INFO] Checking CVMFS. 2020-05-26 12:45:12 (23968): Guest Log: Probing /cvmfs/sft.cern.ch... OK 2020-05-26 12:45:13 (23968): VM state change detected. (old = 'Running', new = 'Paused') 2020-05-26 12:45:23 (23968): 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-05-26 12:45:23 (23968): Guest Log: 2.5.2.0 4054 0 26280 18077 3 1 1157577 4096001 0 65024 0 0 n/a 0 0 http://s1asgc-cvmfs.openhtc.io:8080/cvmfs/sft.cern.ch DIRECT 1 2020-05-26 12:45:23 (23968): VM state change detected. (old = 'Paused', new = 'Running') 2020-05-26 12:45:24 (23968): Guest Log: Probing /cvmfs/grid.cern.ch... OK 2020-05-26 12:45:24 (23968): 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-05-26 12:45:24 (23968): Guest Log: 2.5.2.0 4075 0 27672 12323 3 2 1157577 4096001 0 65024 0 2 100 0 0 http://s1asgc-cvmfs.openhtc.io:8080/cvmfs/grid.cern.ch DIRECT 1 2020-05-26 12:45:25 (23968): Guest Log: Probing /cvmfs/cernvm-prod.cern.ch... OK 2020-05-26 12:45:26 (23968): 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-05-26 12:45:26 (23968): Guest Log: 2.5.2.0 4100 0 27180 249 3 1 1157577 4096001 0 65024 0 0 n/a 0 0 http://s1asgc-cvmfs.openhtc.io:8080/cvmfs/cernvm-prod.cern.ch DIRECT 1 2020-05-26 12:45:27 (23968): Guest Log: Probing /cvmfs/alice.cern.ch... OK 2020-05-26 12:45:28 (23968): 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-05-26 12:45:28 (23968): Guest Log: 2.5.2.0 4126 0 29664 6668 3 1 1157577 4096001 0 65024 0 0 n/a 4071 419 http://s1asgc-cvmfs.openhtc.io:8080/cvmfs/alice.cern.ch DIRECT 1 2020-05-26 12:45:28 (23968): Guest Log: 12:45:09 PST +08:00 2020-05-26: cranky: [INFO] Checking runc. 2020-05-26 12:45:29 (23968): Guest Log: 12:45:09 PST +08:00 2020-05-26: cranky: [INFO] Creating the filesystem. 2020-05-26 12:45:29 (23968): Guest Log: 12:45:09 PST +08:00 2020-05-26: cranky: [INFO] Using /cvmfs/cernvm-prod.cern.ch/cvm3 2020-05-26 12:45:29 (23968): Guest Log: 12:45:09 PST +08:00 2020-05-26: cranky: [INFO] Updating config.json. 2020-05-26 12:45:29 (23968): Guest Log: 12:45:10 PST +08:00 2020-05-26: cranky: [INFO] Running Container 'runc'. 2020-05-26 12:45:31 (23968): Guest Log: job: htmld=/shared/html/job 2020-05-26 12:45:31 (23968): Guest Log: job: unpack exitcode=0 2020-05-26 12:45:31 (23968): Guest Log: 12:45:12 PST +08:00 2020-05-26: cranky: [INFO] ===> [runRivet] Tue May 26 04:45:11 UTC 2020 [boinc ppbar jets 1960 17 - pythia8 8.240 tune-AU2m 100000 12] 2020-05-26 12:47:43 (23968): VM state change detected. (old = 'Running', new = 'Paused') 2020-05-26 12:47:53 (23968): VM state change detected. (old = 'Paused', new = 'Running') 2020-05-26 12:49:13 (23968): VM state change detected. (old = 'Running', new = 'Paused') 2020-05-26 12:49:23 (23968): VM state change detected. (old = 'Paused', new = 'Running') 2020-05-26 12:49:43 (23968): VM state change detected. (old = 'Running', new = 'Paused') 2020-05-26 12:49:53 (23968): VM state change detected. (old = 'Paused', new = 'Running') 2020-05-26 12:50:42 (23968): Status Report: Job Duration: '864000.000000' 2020-05-26 12:50:42 (23968): Status Report: Elapsed Time: '6000.000000' 2020-05-26 12:50:42 (23968): Status Report: CPU Time: '3539.656250' 2020-05-26 12:53:14 (23968): VM state change detected. (old = 'Running', new = 'Paused') 2020-05-26 12:53:24 (23968): VM state change detected. (old = 'Paused', new = 'Running') 2020-05-26 13:00:05 (23968): VM state change detected. (old = 'Running', new = 'Paused') 2020-05-26 13:00:15 (23968): VM state change detected. (old = 'Paused', new = 'Running') 2020-05-26 13:02:05 (23968): VM state change detected. (old = 'Running', new = 'Paused') 2020-05-26 13:02:15 (23968): VM state change detected. (old = 'Paused', new = 'Running') 2020-05-26 13:08:07 (23968): VM state change detected. (old = 'Running', new = 'Paused') 2020-05-26 13:08:17 (23968): VM state change detected. (old = 'Paused', new = 'Running') 2020-05-26 13:09:37 (23968): VM state change detected. (old = 'Running', new = 'Paused') 2020-05-26 13:09:47 (23968): VM state change detected. (old = 'Paused', new = 'Running') 2020-05-26 13:12:18 (23968): VM state change detected. (old = 'Running', new = 'Paused') 2020-05-26 13:12:38 (23968): VM state change detected. (old = 'Paused', new = 'Running') 2020-05-26 13:16:59 (23968): VM state change detected. (old = 'Running', new = 'Paused') 2020-05-26 13:17:09 (23968): VM state change detected. (old = 'Paused', new = 'Running') 2020-05-26 13:19:30 (23968): VM state change detected. (old = 'Running', new = 'Paused') 2020-05-26 13:19:39 (23968): VM state change detected. (old = 'Paused', new = 'Running') 2020-05-26 13:20:50 (23968): VM state change detected. (old = 'Running', new = 'Paused') 2020-05-26 13:21:00 (23968): VM state change detected. (old = 'Paused', new = 'Running') 2020-05-26 13:31:31 (23968): VM state change detected. (old = 'Running', new = 'Paused') 2020-05-26 13:31:41 (23968): VM state change detected. (old = 'Paused', new = 'Running') 2020-05-26 13:36:32 (23968): VM state change detected. (old = 'Running', new = 'Paused') 2020-05-26 13:36:42 (23968): VM state change detected. (old = 'Paused', new = 'Running') 2020-05-26 13:38:22 (23968): VM state change detected. (old = 'Running', new = 'Paused') 2020-05-26 13:38:32 (23968): VM state change detected. (old = 'Paused', new = 'Running') 2020-05-26 13:42:43 (23968): VM state change detected. (old = 'Running', new = 'Paused') 2020-05-26 13:42:53 (23968): VM state change detected. (old = 'Paused', new = 'Running') 2020-05-26 13:44:03 (23968): VM state change detected. (old = 'Running', new = 'Paused') 2020-05-26 13:44:13 (23968): VM state change detected. (old = 'Paused', new = 'Running') 2020-05-26 13:44:33 (23968): VM state change detected. (old = 'Running', new = 'Paused') 2020-05-26 13:44:43 (23968): VM state change detected. (old = 'Paused', new = 'Running') 2020-05-26 13:45:23 (23968): VM state change detected. (old = 'Running', new = 'Paused') 2020-05-26 13:45:33 (23968): VM state change detected. (old = 'Paused', new = 'Running') 2020-05-26 13:49:44 (23968): VM state change detected. (old = 'Running', new = 'Paused') 2020-05-26 13:49:54 (23968): VM state change detected. (old = 'Paused', new = 'Running') 2020-05-26 13:50:54 (23968): VM state change detected. (old = 'Running', new = 'Paused') 2020-05-26 13:51:04 (23968): VM state change detected. (old = 'Paused', new = 'Running') 2020-05-26 13:51:44 (23968): VM state change detected. (old = 'Running', new = 'Paused') 2020-05-26 13:51:54 (23968): VM state change detected. (old = 'Paused', new = 'Running') 2020-05-26 13:53:04 (23968): VM state change detected. (old = 'Running', new = 'Paused') 2020-05-26 13:53:14 (23968): VM state change detected. (old = 'Paused', new = 'Running') 2020-05-26 13:54:04 (23968): VM state change detected. (old = 'Running', new = 'Paused') 2020-05-26 13:54:14 (23968): VM state change detected. (old = 'Paused', new = 'Running') 2020-05-26 13:55:45 (23968): VM state change detected. (old = 'Running', new = 'Paused') 2020-05-26 13:55:55 (23968): VM state change detected. (old = 'Paused', new = 'Running') 2020-05-26 13:58:45 (23968): VM state change detected. (old = 'Running', new = 'Paused') 2020-05-26 13:58:55 (23968): VM state change detected. (old = 'Paused', new = 'Running') 2020-05-26 14:00:16 (23968): VM state change detected. (old = 'Running', new = 'Paused') 2020-05-26 14:00:26 (23968): VM state change detected. (old = 'Paused', new = 'Running') 2020-05-26 14:00:46 (23968): VM state change detected. (old = 'Running', new = 'Paused') 2020-05-26 14:00:56 (23968): VM state change detected. (old = 'Paused', new = 'Running') 2020-05-26 14:01:06 (23968): VM state change detected. (old = 'Running', new = 'Paused') 2020-05-26 14:01:16 (23968): VM state change detected. (old = 'Paused', new = 'Running') 2020-05-26 14:03:07 (23968): VM state change detected. (old = 'Running', new = 'Paused') 2020-05-26 14:03:17 (23968): VM state change detected. (old = 'Paused', new = 'Running') 2020-05-26 14:03:47 (23968): VM state change detected. (old = 'Running', new = 'Paused') 2020-05-26 14:03:57 (23968): VM state change detected. (old = 'Paused', new = 'Running') 2020-05-26 14:04:17 (23968): VM state change detected. (old = 'Running', new = 'Paused') 2020-05-26 14:04:27 (23968): VM state change detected. (old = 'Paused', new = 'Running') 2020-05-26 14:09:38 (23968): VM state change detected. (old = 'Running', new = 'Paused') 2020-05-26 14:09:48 (23968): VM state change detected. (old = 'Paused', new = 'Running') 2020-05-26 14:11:18 (23968): VM state change detected. (old = 'Running', new = 'Paused') 2020-05-26 14:11:28 (23968): VM state change detected. (old = 'Paused', new = 'Running') 2020-05-26 14:12:49 (23968): VM state change detected. (old = 'Running', new = 'Paused') 2020-05-26 14:12:59 (23968): VM state change detected. (old = 'Paused', new = 'Running') 2020-05-26 14:13:59 (23968): VM state change detected. (old = 'Running', new = 'Paused') 2020-05-26 14:14:09 (23968): VM state change detected. (old = 'Paused', new = 'Running') 2020-05-26 14:14:33 (23968): Stopping VM. 2020-05-26 21:50:29 (18148): Detected: vboxwrapper 26197 2020-05-26 21:50:29 (18148): Detected: BOINC client v7.7 2020-05-26 21:50:30 (18148): Detected: VirtualBox VboxManage Interface (Version: 6.1.4) 2020-05-26 21:50:30 (18148): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds) 2020-05-26 21:50:31 (18148): Starting VM using VBoxManage interface. (boinc_956ad126053fe042, slot#9) 2020-05-26 21:50:49 (18148): Successfully started VM. (PID = '24168') 2020-05-26 21:50:49 (18148): Reporting VM Process ID to BOINC. 2020-05-26 21:50:49 (18148): VM state change detected. (old = 'PoweredOff', new = 'Running') 2020-05-26 21:50:49 (18148): Detected: Web Application Enabled (http://localhost:64733) 2020-05-26 21:50:49 (18148): Detected: Remote Desktop Enabled (localhost:64734) 2020-05-26 21:50:49 (18148): Status Report: Job Duration: '864000.000000' 2020-05-26 21:50:49 (18148): Status Report: Elapsed Time: '10642.000000' 2020-05-26 21:50:49 (18148): Status Report: CPU Time: '7046.406250' 2020-05-26 21:50:49 (18148): Preference change detected 2020-05-26 21:50:49 (18148): Setting CPU throttle for VM. (65%) 2020-05-26 21:50:50 (18148): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds)) 2020-05-26 21:50:57 (18148): Guest Log: 01:23:55.173554 timesync vgsvcTimeSyncWorker: Radical host time change: 27 385 004 000 000ns (HostNow=1 590 501 056 745 000 000 ns HostLast=1 590 473 671 741 000 000 ns) 2020-05-26 21:51:07 (18148): Guest Log: 01:24:05.244085 timesync vgsvcTimeSyncWorker: Radical guest time change: 27 762 509 836 000ns (GuestNow=1 590 501 066 923 110 000 ns GuestLast=1 590 473 304 413 274 000 ns fSetTimeLastLoop=true ) 2020-05-26 21:56:49 (18148): VM state change detected. (old = 'Running', new = 'Paused') 2020-05-26 21:56:59 (18148): VM state change detected. (old = 'Paused', new = 'Running') 2020-05-26 21:58:20 (18148): VM state change detected. (old = 'Running', new = 'Paused') 2020-05-26 21:58:30 (18148): VM state change detected. (old = 'Paused', new = 'Running') 2020-05-29 08:04:38 (13212): Detected: vboxwrapper 26197 2020-05-29 08:04:39 (13212): Detected: BOINC client v7.7 2020-05-29 08:04:41 (13212): Detected: VirtualBox VboxManage Interface (Version: 6.1.4) 2020-05-29 08:04:42 (13212): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds) 2020-05-29 08:04:43 (13212): ERROR: VM failed to start 2020-05-29 08:04:49 (13212): 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-05-30 09:33:53 (372): Detected: vboxwrapper 26197 2020-05-30 09:33:53 (372): Detected: BOINC client v7.7 2020-05-30 09:33:57 (372): Detected: VirtualBox VboxManage Interface (Version: 6.1.4) 2020-05-30 09:33:58 (372): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds) 2020-05-30 09:33:58 (372): ERROR: VM failed to start 2020-05-30 09:34:03 (372): 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-05-30 09:34:40 (32216): Detected: vboxwrapper 26197 2020-05-30 09:34:40 (32216): Detected: BOINC client v7.7 2020-05-30 09:34:43 (32216): Detected: VirtualBox VboxManage Interface (Version: 6.1.4) 2020-05-30 09:34:44 (32216): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds) 2020-05-30 09:34:44 (32216): Starting VM using VBoxManage interface. (boinc_956ad126053fe042, slot#9) 2020-05-30 09:34:53 (32216): Successfully started VM. (PID = '39876') 2020-05-30 09:34:53 (32216): Reporting VM Process ID to BOINC. 2020-05-30 09:34:53 (32216): Guest Log: BIOS: VirtualBox 6.1.4 2020-05-30 09:34:53 (32216): Guest Log: CPUID EDX: 0x178bfbff 2020-05-30 09:34:53 (32216): Guest Log: BIOS: ata0-0: PCHS=16383/16/63 LCHS=1024/255/63 2020-05-30 09:34:53 (32216): VM state change detected. (old = 'PoweredOff', new = 'Running') 2020-05-30 09:34:53 (32216): Preference change detected 2020-05-30 09:34:53 (32216): Setting CPU throttle for VM. (65%) 2020-05-30 09:34:53 (32216): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds)) 2020-05-30 09:34:55 (32216): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032 2020-05-30 09:34:55 (32216): Guest Log: BIOS: Booting from Hard Disk... 2020-05-30 09:34:55 (32216): Stopping VM. 2020-05-30 11:29:52 (31512): Detected: vboxwrapper 26197 2020-05-30 11:29:52 (31512): Detected: BOINC client v7.7 2020-05-30 11:29:53 (31512): Detected: VirtualBox VboxManage Interface (Version: 6.1.4) 2020-05-30 11:29:53 (31512): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds) 2020-05-30 11:29:54 (31512): Starting VM using VBoxManage interface. (boinc_956ad126053fe042, slot#9) 2020-05-30 11:30:04 (31512): Successfully started VM. (PID = '15552') 2020-05-30 11:30:04 (31512): Reporting VM Process ID to BOINC. 2020-05-30 11:30:04 (31512): VM state change detected. (old = 'PoweredOff', new = 'Running') 2020-05-30 11:30:04 (31512): Preference change detected 2020-05-30 11:30:04 (31512): Setting CPU throttle for VM. (65%) 2020-05-30 11:30:05 (31512): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds)) 2020-05-30 11:30:06 (31512): Guest Log: BIOS: KBD: unsupported int 16h function 03 2020-05-30 11:30:06 (31512): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 2020-05-30 11:30:45 (31512): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds 2020-05-30 11:30:45 (31512): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000) 2020-05-30 11:30:53 (31512): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log 2020-05-30 11:30:53 (31512): Guest Log: 00:00:00.000149 main Log opened 2020-05-30T03:30:53.829943000Z 2020-05-30 11:30:53 (31512): Guest Log: 00:00:00.000525 main OS Product: Linux 2020-05-30 11:30:53 (31512): Guest Log: 00:00:00.000592 main OS Release: 4.14.76-13.cernvm.x86_64 2020-05-30 11:30:53 (31512): Guest Log: 00:00:00.000641 main OS Version: #1 SMP Tue Oct 16 18:26:15 CEST 2018 2020-05-30 11:30:53 (31512): Guest Log: 00:00:00.000688 main Executable: /usr/sbin/VBoxService 2020-05-30 11:30:53 (31512): Guest Log: 00:00:00.000690 main Process ID: 3229 2020-05-30 11:30:53 (31512): Guest Log: 00:00:00.000691 main Package type: LINUX_64BITS_GENERIC 2020-05-30 11:30:53 (31512): Guest Log: 00:00:00.054303 main 5.2.6 r120293 started. Verbose level = 0 2020-05-30 11:31:34 (31512): Guest Log: 11:31:34 PST +08:00 2020-05-30: cranky: [INFO] Detected Theory App 2020-05-30 11:31:34 (31512): Guest Log: 11:31:34 PST +08:00 2020-05-30: cranky: [INFO] Checking CVMFS. 2020-05-30 11:31:35 (31512): Guest Log: Probing /cvmfs/sft.cern.ch... OK 2020-05-30 11:31:38 (31512): 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-05-30 11:31:38 (31512): Guest Log: 2.5.2.0 4066 0 26208 18135 3 1 1174828 4096001 0 65024 0 0 n/a 5 3 http://s1asgc-cvmfs.openhtc.io:8080/cvmfs/sft.cern.ch DIRECT 1 2020-05-30 11:31:40 (31512): Guest Log: Probing /cvmfs/grid.cern.ch... OK 2020-05-30 11:31:41 (31512): 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-05-30 11:31:41 (31512): Guest Log: 2.5.2.0 4115 0 27412 12355 3 2 1174828 4096001 0 65024 0 2 0 4273 1161 http://s1asgc-cvmfs.openhtc.io:8080/cvmfs/grid.cern.ch DIRECT 1 2020-05-30 11:31:42 (31512): Guest Log: Probing /cvmfs/cernvm-prod.cern.ch... OK 2020-05-30 11:31:44 (31512): 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-05-30 11:31:44 (31512): Guest Log: 2.5.2.0 4092 0 27192 249 3 1 1174828 4096001 0 65024 0 0 n/a 0 0 http://s1unl-cvmfs.openhtc.io/cvmfs/cernvm-prod.cern.ch DIRECT 1 2020-05-30 11:31:45 (31512): Guest Log: Probing /cvmfs/alice.cern.ch... OK 2020-05-30 11:31:47 (31512): 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-05-30 11:31:47 (31512): Guest Log: 2.5.2.0 4136 0 28376 6695 3 1 1174828 4096001 0 65024 0 0 n/a 4077 1240 http://s1bnl-cvmfs.openhtc.io/cvmfs/alice.cern.ch DIRECT 1 2020-05-30 11:31:47 (31512): Guest Log: 11:31:47 PST +08:00 2020-05-30: cranky: [INFO] Checking runc. 2020-05-30 11:31:47 (31512): Guest Log: 11:31:47 PST +08:00 2020-05-30: cranky: [INFO] Creating the filesystem. 2020-05-30 11:31:48 (31512): Guest Log: 11:31:47 PST +08:00 2020-05-30: cranky: [INFO] Using /cvmfs/cernvm-prod.cern.ch/cvm3 2020-05-30 11:31:48 (31512): Guest Log: 11:31:48 PST +08:00 2020-05-30: cranky: [INFO] Updating config.json. 2020-05-30 11:31:48 (31512): Guest Log: 11:31:48 PST +08:00 2020-05-30: cranky: [INFO] Running Container 'runc'. 2020-05-30 11:31:50 (31512): Guest Log: job: htmld=/shared/html/job 2020-05-30 11:31:50 (31512): Guest Log: job: unpack exitcode=0 2020-05-30 11:31:50 (31512): Guest Log: 11:31:50 PST +08:00 2020-05-30: cranky: [INFO] ===> [runRivet] Sat May 30 03:31:50 UTC 2020 [boinc ppbar jets 1960 17 - pythia8 8.240 tune-AU2m 100000 12] 2020-05-30 13:10:21 (31512): Status Report: Job Duration: '864000.000000' 2020-05-30 13:10:21 (31512): Status Report: Elapsed Time: '6000.000000' 2020-05-30 13:10:21 (31512): Status Report: CPU Time: '4401.218750' 2020-05-30 14:11:30 (31512): VM state change detected. (old = 'Running', new = 'Paused') 2020-05-30 14:11:40 (31512): VM state change detected. (old = 'Paused', new = 'Running') 2020-05-30 14:13:33 (31512): VM state change detected. (old = 'Running', new = 'Paused') 2020-05-30 14:14:11 (31512): VM state change detected. (old = 'Paused', new = 'Running') 2020-05-30 14:19:42 (31512): VM state change detected. (old = 'Running', new = 'Paused') 2020-05-30 14:20:12 (31512): VM state change detected. (old = 'Paused', new = 'Running') 2020-05-30 14:27:04 (31512): VM state change detected. (old = 'Running', new = 'Paused') 2020-05-30 14:27:36 (31512): Error in resume VM for VM: -2135228414 Command: VBoxManage -q controlvm "boinc_956ad126053fe042" resume Output: VBoxManage.exe: error: Cannot resume the machine as it is not paused (machine state: Running) VBoxManage.exe: error: Details: code VBOX_E_INVALID_VM_STATE (0x80bb0002), component ConsoleWrap, interface IConsole, callee IUnknown VBoxManage.exe: error: Context: "Resume()" at line 410 of file VBoxManageControlVM.cpp 2020-05-30 14:27:36 (31512): VM state change detected. (old = 'Paused', new = 'Running') 2020-05-30 14:27:36 (31512): Stopping VM. 2020-05-30 14:28:06 (32420): Detected: vboxwrapper 26197 2020-05-30 14:28:06 (32420): Detected: BOINC client v7.7 2020-05-30 14:28:07 (32420): Detected: VirtualBox VboxManage Interface (Version: 6.1.4) 2020-05-30 14:28:07 (32420): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds) 2020-05-30 14:28:08 (32420): Starting VM using VBoxManage interface. (boinc_956ad126053fe042, slot#9) 2020-05-30 14:28:23 (32420): Successfully started VM. (PID = '32304') 2020-05-30 14:28:23 (32420): Reporting VM Process ID to BOINC. 2020-05-30 14:28:23 (32420): VM state change detected. (old = 'PoweredOff', new = 'Running') 2020-05-30 14:28:23 (32420): Status Report: Job Duration: '864000.000000' 2020-05-30 14:28:23 (32420): Status Report: Elapsed Time: '10492.000000' 2020-05-30 14:28:23 (32420): Status Report: CPU Time: '7774.828125' 2020-05-30 14:28:23 (32420): Preference change detected 2020-05-30 14:28:23 (32420): Setting CPU throttle for VM. (65%) 2020-05-30 14:28:23 (32420): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds)) 2020-05-30 14:42:14 (32420): VM state change detected. (old = 'Running', new = 'Paused') 2020-05-30 14:42:24 (32420): VM state change detected. (old = 'Paused', new = 'Running') 2020-05-30 15:22:54 (32420): VM state change detected. (old = 'Running', new = 'Paused') 2020-05-30 15:23:04 (32420): VM state change detected. (old = 'Paused', new = 'Running') 2020-05-30 16:08:56 (32420): Status Report: Job Duration: '864000.000000' 2020-05-30 16:08:56 (32420): Status Report: Elapsed Time: '16492.000000' 2020-05-30 16:08:56 (32420): Status Report: CPU Time: '12278.734375' 2020-05-30 16:09:51 (32420): VM state change detected. (old = 'Running', new = 'Paused') 2020-05-30 16:10:12 (32420): VM state change detected. (old = 'Paused', new = 'Running') 2020-05-30 17:26:40 (32420): VM state change detected. (old = 'Running', new = 'Paused') 2020-05-30 17:26:50 (32420): VM state change detected. (old = 'Paused', new = 'Running') 2020-05-30 17:27:21 (32420): VM state change detected. (old = 'Running', new = 'Paused') 2020-05-30 17:27:31 (32420): VM state change detected. (old = 'Paused', new = 'Running') 2020-05-30 17:49:47 (32420): Status Report: Job Duration: '864000.000000' 2020-05-30 17:49:47 (32420): Status Report: Elapsed Time: '22492.000000' 2020-05-30 17:49:47 (32420): Status Report: CPU Time: '16780.843750' 2020-05-30 19:13:40 (32420): VM state change detected. (old = 'Running', new = 'Paused') 2020-05-30 19:13:50 (32420): VM state change detected. (old = 'Paused', new = 'Running') 2020-05-30 19:18:30 (32420): VM state change detected. (old = 'Running', new = 'Paused') 2020-05-30 19:18:41 (32420): VM state change detected. (old = 'Paused', new = 'Running') 2020-05-30 19:30:25 (32420): Status Report: Job Duration: '864000.000000' 2020-05-30 19:30:25 (32420): Status Report: Elapsed Time: '28492.000000' 2020-05-30 19:30:25 (32420): Status Report: CPU Time: '21281.765625' 2020-05-30 19:40:14 (32420): VM state change detected. (old = 'Running', new = 'Paused') 2020-05-30 19:40:25 (32420): VM state change detected. (old = 'Paused', new = 'Running') 2020-05-30 19:41:35 (32420): VM state change detected. (old = 'Running', new = 'Paused') 2020-05-30 19:41:45 (32420): VM state change detected. (old = 'Paused', new = 'Running') 2020-05-30 19:42:25 (32420): VM state change detected. (old = 'Running', new = 'Paused') 2020-05-30 19:42:35 (32420): VM state change detected. (old = 'Paused', new = 'Running') 2020-05-30 19:44:35 (32420): VM state change detected. (old = 'Running', new = 'Paused') 2020-05-30 19:44:45 (32420): VM state change detected. (old = 'Paused', new = 'Running') 2020-05-30 19:44:55 (32420): VM state change detected. (old = 'Running', new = 'Paused') 2020-05-30 19:45:05 (32420): VM state change detected. (old = 'Paused', new = 'Running') 2020-05-30 19:47:56 (32420): VM state change detected. (old = 'Running', new = 'Paused') 2020-05-30 19:48:06 (32420): VM state change detected. (old = 'Paused', new = 'Running') 2020-05-30 19:49:16 (32420): VM state change detected. (old = 'Running', new = 'Paused') 2020-05-30 19:49:26 (32420): VM state change detected. (old = 'Paused', new = 'Running') 2020-05-30 19:50:26 (32420): VM state change detected. (old = 'Running', new = 'Paused') 2020-05-30 19:50:36 (32420): VM state change detected. (old = 'Paused', new = 'Running') 2020-05-30 21:05:49 (32420): VM state change detected. (old = 'Running', new = 'Paused') 2020-05-30 21:05:59 (32420): VM state change detected. (old = 'Paused', new = 'Running') 2020-05-30 21:09:11 (32420): Stopping VM. 2020-06-01 02:02:54 (36352): Detected: vboxwrapper 26197 2020-06-01 02:02:54 (36352): Detected: BOINC client v7.7 2020-06-01 02:03:04 (36352): Detected: VirtualBox VboxManage Interface (Version: 6.1.4) 2020-06-01 02:03:04 (36352): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds) 2020-06-01 02:03:05 (36352): Starting VM using VBoxManage interface. (boinc_956ad126053fe042, slot#9) 2020-06-01 02:03:29 (36352): Successfully started VM. (PID = '24376') 2020-06-01 02:03:29 (36352): Reporting VM Process ID to BOINC. 2020-06-01 02:03:29 (36352): VM state change detected. (old = 'PoweredOff', new = 'Running') 2020-06-01 02:03:29 (36352): Status Report: Job Duration: '864000.000000' 2020-06-01 02:03:29 (36352): Status Report: Elapsed Time: '34304.000000' 2020-06-01 02:03:29 (36352): Status Report: CPU Time: '25645.812500' 2020-06-01 02:03:29 (36352): Preference change detected 2020-06-01 02:03:29 (36352): Setting CPU throttle for VM. (65%) 2020-06-01 02:03:30 (36352): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds)) 2020-06-01 02:03:38 (36352): Guest Log: 09:33:24.670658 timesync vgsvcTimeSyncWorker: Radical host time change: 104 066 133 000 000ns (HostNow=1 590 948 217 786 000 000 ns HostLast=1 590 844 151 653 000 000 ns) 2020-06-01 02:03:48 (36352): Guest Log: 09:33:34.775691 timesync vgsvcTimeSyncWorker: Radical guest time change: 104 222 129 894 000ns (GuestNow=1 590 948 228 108 834 000 ns GuestLast=1 590 844 005 978 940 000 ns fSetTimeLastLoop=true ) 2020-06-01 02:04:04 (36352): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-01 03:53:14 (36352): Error in resume VM for VM: -2135228414 Command: VBoxManage -q controlvm "boinc_956ad126053fe042" resume Output: VBoxManage.exe: error: Cannot resume the machine as it is not paused (machine state: Running) VBoxManage.exe: error: Details: code VBOX_E_INVALID_VM_STATE (0x80bb0002), component ConsoleWrap, interface IConsole, callee IUnknown VBoxManage.exe: error: Context: "Resume()" at line 410 of file VBoxManageControlVM.cpp 2020-06-01 03:53:15 (36352): Guest Log: 09:33:54.786955 timesync vgsvcTimeSyncWorker: Radical host time change: 6 550 970 000 000ns (HostNow=1 590 954 789 089 000 000 ns HostLast=1 590 948 238 119 000 000 ns) 2020-06-01 03:53:15 (36352): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-01 03:53:15 (36352): Stopping VM. 2020-06-01 09:20:21 (9496): Detected: vboxwrapper 26197 2020-06-01 09:20:21 (9496): Detected: BOINC client v7.7 2020-06-01 09:20:23 (9496): Detected: VirtualBox VboxManage Interface (Version: 6.1.4) 2020-06-01 09:20:23 (9496): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds) 2020-06-01 09:20:24 (9496): ERROR: VM failed to start 2020-06-01 09:20:29 (9496): 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-01 12:54:24 (44872): Detected: vboxwrapper 26197 2020-06-01 12:54:24 (44872): Detected: BOINC client v7.7 2020-06-01 12:54:25 (44872): Detected: VirtualBox VboxManage Interface (Version: 6.1.4) 2020-06-01 12:54:26 (44872): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds) 2020-06-01 12:54:27 (44872): Starting VM using VBoxManage interface. (boinc_956ad126053fe042, slot#9) 2020-06-01 12:54:50 (44872): Successfully started VM. (PID = '31112') 2020-06-01 12:54:50 (44872): Reporting VM Process ID to BOINC. 2020-06-01 12:54:50 (44872): VM state change detected. (old = 'PoweredOff', new = 'Running') 2020-06-01 12:54:50 (44872): Stopping VM. 2020-06-01 13:39:57 (40644): Detected: vboxwrapper 26197 2020-06-01 13:39:57 (40644): Detected: BOINC client v7.7 2020-06-01 13:39:58 (40644): Detected: VirtualBox VboxManage Interface (Version: 6.1.4) 2020-06-01 13:39:58 (40644): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds) 2020-06-01 13:40:00 (40644): Starting VM using VBoxManage interface. (boinc_956ad126053fe042, slot#9) 2020-06-01 13:40:18 (40644): Successfully started VM. (PID = '3604') 2020-06-01 13:40:18 (40644): Reporting VM Process ID to BOINC. 2020-06-01 13:40:18 (40644): VM state change detected. (old = 'PoweredOff', new = 'Running') 2020-06-01 13:40:18 (40644): Status Report: Job Duration: '864000.000000' 2020-06-01 13:40:18 (40644): Status Report: Elapsed Time: '34339.000000' 2020-06-01 13:40:18 (40644): Status Report: CPU Time: '25675.984375' 2020-06-01 13:40:18 (40644): Preference change detected 2020-06-01 13:40:18 (40644): Setting CPU throttle for VM. (65%) 2020-06-01 13:40:19 (40644): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds)) 2020-06-01 13:40:19 (40644): Guest Log: 09:34:04.789762 timesync vgsvcTimeSyncWorker: Radical host time change: 35 230 053 000 000ns (HostNow=1 590 990 019 142 000 000 ns HostLast=1 590 954 789 089 000 000 ns) 2020-06-01 13:40:19 (40644): Guest Log: 09:34:04.874504 timesync vgsvcTimeSyncWorker: Radical guest time change: 6 550 971 747 000ns (GuestNow=1 590 954 799 091 796 000 ns GuestLast=1 590 948 248 120 049 000 ns fSetTimeLastLoop=true ) 2020-06-01 13:40:29 (40644): Guest Log: 09:34:14.878994 timesync vgsvcTimeSyncWorker: Radical guest time change: 35 230 139 471 000ns (GuestNow=1 590 990 029 231 267 000 ns GuestLast=1 590 954 799 091 796 000 ns fSetTimeLastLoop=true ) 2020-06-01 13:40:58 (40644): Stopping VM. 2020-06-01 13:42:00 (46448): Detected: vboxwrapper 26197 2020-06-01 13:42:00 (46448): Detected: BOINC client v7.7 2020-06-01 13:42:01 (46448): Detected: VirtualBox VboxManage Interface (Version: 6.1.4) 2020-06-01 13:42:01 (46448): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds) 2020-06-01 13:42:02 (46448): Starting VM using VBoxManage interface. (boinc_956ad126053fe042, slot#9) 2020-06-01 13:42:20 (46448): Successfully started VM. (PID = '33584') 2020-06-01 13:42:20 (46448): Reporting VM Process ID to BOINC. 2020-06-01 13:42:21 (46448): VM state change detected. (old = 'PoweredOff', new = 'Running') 2020-06-01 13:42:21 (46448): Status Report: Job Duration: '864000.000000' 2020-06-01 13:42:21 (46448): Status Report: Elapsed Time: '34379.000000' 2020-06-01 13:42:21 (46448): Status Report: CPU Time: '25716.250000' 2020-06-01 13:42:21 (46448): Preference change detected 2020-06-01 13:42:21 (46448): Setting CPU throttle for VM. (65%) 2020-06-01 13:42:21 (46448): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds)) 2020-06-01 13:44:04 (46448): Stopping VM. 2020-06-01 13:46:06 (44208): Detected: vboxwrapper 26197 2020-06-01 13:46:06 (44208): Detected: BOINC client v7.7 2020-06-01 13:46:07 (44208): Detected: VirtualBox VboxManage Interface (Version: 6.1.4) 2020-06-01 13:46:07 (44208): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds) 2020-06-01 13:46:08 (44208): Starting VM using VBoxManage interface. (boinc_956ad126053fe042, slot#9) 2020-06-01 13:46:26 (44208): Successfully started VM. (PID = '43056') 2020-06-01 13:46:26 (44208): Reporting VM Process ID to BOINC. 2020-06-01 13:46:26 (44208): VM state change detected. (old = 'PoweredOff', new = 'Running') 2020-06-01 13:46:26 (44208): Status Report: Job Duration: '864000.000000' 2020-06-01 13:46:26 (44208): Status Report: Elapsed Time: '34482.000000' 2020-06-01 13:46:26 (44208): Status Report: CPU Time: '25802.984375' 2020-06-01 13:46:26 (44208): Preference change detected 2020-06-01 13:46:26 (44208): Setting CPU throttle for VM. (65%) 2020-06-01 13:46:26 (44208): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds)) 2020-06-01 13:47:06 (44208): Stopping VM. 2020-06-01 13:49:10 (42680): Detected: vboxwrapper 26197 2020-06-01 13:49:10 (42680): Detected: BOINC client v7.7 2020-06-01 13:49:11 (42680): Detected: VirtualBox VboxManage Interface (Version: 6.1.4) 2020-06-01 13:49:11 (42680): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds) 2020-06-01 13:49:12 (42680): Starting VM using VBoxManage interface. (boinc_956ad126053fe042, slot#9) 2020-06-01 13:49:27 (42680): Successfully started VM. (PID = '23864') 2020-06-01 13:49:27 (42680): Reporting VM Process ID to BOINC. 2020-06-01 13:49:27 (42680): VM state change detected. (old = 'PoweredOff', new = 'Running') 2020-06-01 13:49:27 (42680): Status Report: Job Duration: '864000.000000' 2020-06-01 13:49:27 (42680): Status Report: Elapsed Time: '34522.000000' 2020-06-01 13:49:27 (42680): Status Report: CPU Time: '25844.375000' 2020-06-01 13:49:27 (42680): Preference change detected 2020-06-01 13:49:27 (42680): Setting CPU throttle for VM. (65%) 2020-06-01 13:49:28 (42680): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds)) 2020-06-01 13:57:22 (42680): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-01 13:57:32 (42680): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-01 13:59:23 (42680): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-01 13:59:33 (42680): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-01 14:01:24 (42680): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-01 14:01:34 (42680): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-01 14:03:45 (42680): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-01 14:03:55 (42680): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-01 14:04:04 (42680): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-01 14:04:15 (42680): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-01 14:04:46 (42680): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-01 14:05:05 (42680): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-01 14:05:15 (42680): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-01 14:05:25 (42680): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-01 14:09:37 (42680): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-01 14:09:47 (42680): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-01 14:11:17 (42680): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-01 14:11:38 (42680): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-01 14:14:28 (42680): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-01 14:14:38 (42680): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-01 14:15:58 (42680): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-01 14:16:08 (42680): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-01 14:16:38 (42680): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-01 14:16:48 (42680): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-01 14:18:39 (42680): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-01 14:18:49 (42680): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-01 14:21:50 (42680): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-01 14:22:00 (42680): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-01 14:29:55 (42680): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-01 14:30:02 (42680): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-01 15:01:11 (42680): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-01 15:01:22 (42680): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-01 15:03:02 (42680): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-01 15:03:12 (42680): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-01 15:03:20 (42680): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-01 18:05:14 (42680): Error in resume VM for VM: -2135228414 Command: VBoxManage -q controlvm "boinc_956ad126053fe042" resume Output: VBoxManage.exe: error: Cannot resume the machine as it is not paused (machine state: Running) VBoxManage.exe: error: Details: code VBOX_E_INVALID_VM_STATE (0x80bb0002), component ConsoleWrap, interface IConsole, callee IUnknown VBoxManage.exe: error: Context: "Resume()" at line 410 of file VBoxManageControlVM.cpp 2020-06-01 18:05:15 (42680): Guest Log: 10:48:08.957026 timesync vgsvcTimeSyncWorker: Radical host time change: 10 902 837 000 000ns (HostNow=1 591 005 896 174 000 000 ns HostLast=1 590 994 993 337 000 000 ns) 2020-06-01 18:05:15 (42680): Guest Log: 10:48:18.957554 timesync vgsvcTimeSyncWorker: Radical guest time change: 11 082 754 262 000ns (GuestNow=1 591 005 906 174 554 000 ns GuestLast=1 590 994 823 420 292 000 ns fSetTimeLastLoop=true ) 2020-06-01 18:05:15 (42680): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-01 18:05:15 (42680): Stopping VM. 2020-06-01 18:05:46 (43004): Detected: vboxwrapper 26197 2020-06-01 18:05:46 (43004): Detected: BOINC client v7.7 2020-06-01 18:05:47 (43004): Detected: VirtualBox VboxManage Interface (Version: 6.1.4) 2020-06-01 18:05:47 (43004): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds) 2020-06-01 18:05:48 (43004): Starting VM using VBoxManage interface. (boinc_956ad126053fe042, slot#9) 2020-06-01 18:06:15 (43004): Successfully started VM. (PID = '17324') 2020-06-01 18:06:15 (43004): Reporting VM Process ID to BOINC. 2020-06-01 18:06:15 (43004): VM state change detected. (old = 'PoweredOff', new = 'Running') 2020-06-01 18:06:15 (43004): Status Report: Job Duration: '864000.000000' 2020-06-01 18:06:15 (43004): Status Report: Elapsed Time: '38673.591536' 2020-06-01 18:06:15 (43004): Status Report: CPU Time: '28949.015625' 2020-06-01 18:06:15 (43004): Preference change detected 2020-06-01 18:06:15 (43004): Setting CPU throttle for VM. (65%) 2020-06-01 18:06:15 (43004): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds)) 2020-06-01 18:24:50 (43004): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-01 21:11:53 (43004): Error in resume VM for VM: -2135228414 Command: VBoxManage -q controlvm "boinc_956ad126053fe042" resume Output: VBoxManage.exe: error: Cannot resume the machine as it is not paused (machine state: Running) VBoxManage.exe: error: Details: code VBOX_E_INVALID_VM_STATE (0x80bb0002), component ConsoleWrap, interface IConsole, callee IUnknown VBoxManage.exe: error: Context: "Resume()" at line 410 of file VBoxManageControlVM.cpp 2020-06-01 21:11:58 (43004): Guest Log: 11:07:16.928893 timesync vgsvcTimeSyncWorker: Radical host time change: 10 022 927 000 000ns (HostNow=1 591 017 107 575 000 000 ns HostLast=1 591 007 084 648 000 000 ns) 2020-06-01 21:11:58 (43004): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-01 21:11:58 (43004): Stopping VM. 2020-06-01 21:12:31 (38708): Detected: vboxwrapper 26197 2020-06-01 21:12:31 (38708): Detected: BOINC client v7.7 2020-06-01 21:12:32 (38708): Detected: VirtualBox VboxManage Interface (Version: 6.1.4) 2020-06-01 21:12:32 (38708): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds) 2020-06-01 21:12:33 (38708): Starting VM using VBoxManage interface. (boinc_956ad126053fe042, slot#9) 2020-06-01 21:13:05 (38708): Successfully started VM. (PID = '15260') 2020-06-01 21:13:05 (38708): Reporting VM Process ID to BOINC. 2020-06-01 21:13:05 (38708): VM state change detected. (old = 'PoweredOff', new = 'Running') 2020-06-01 21:13:05 (38708): Status Report: Job Duration: '864000.000000' 2020-06-01 21:13:05 (38708): Status Report: Elapsed Time: '39787.591536' 2020-06-01 21:13:05 (38708): Status Report: CPU Time: '29787.109375' 2020-06-01 21:13:05 (38708): Preference change detected 2020-06-01 21:13:05 (38708): Setting CPU throttle for VM. (65%) 2020-06-01 21:13:06 (38708): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds)) 2020-06-01 21:13:07 (38708): Guest Log: 11:07:26.953520 timesync vgsvcTimeSyncWorker: Radical guest time change: 10 015 715 050 000ns (GuestNow=1 591 017 117 599 620 000 ns GuestLast=1 591 007 101 884 570 000 ns fSetTimeLastLoop=true ) 2020-06-01 21:49:11 (38708): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-01 21:49:21 (38708): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-01 21:51:41 (38708): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-01 21:51:51 (38708): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-01 21:53:42 (38708): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-01 21:53:52 (38708): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-01 21:54:22 (38708): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-01 21:54:32 (38708): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-01 22:01:54 (38708): VM state change detected. (old = 'Running', new = 'Paused') 2020-06-01 22:02:14 (38708): VM state change detected. (old = 'Paused', new = 'Running') 2020-06-01 22:05:45 (38708): Guest Log: job: run exitcode=0 2020-06-01 22:05:45 (38708): Guest Log: job: diskusage=2748 2020-06-01 22:05:45 (38708): Guest Log: job: logsize=80 k 2020-06-01 22:05:45 (38708): Guest Log: job: times= 2020-06-01 22:05:45 (38708): Guest Log: 0m0.012s 0m0.026s 2020-06-01 22:05:45 (38708): Guest Log: 696m52.531s 6m56.659s 2020-06-01 22:05:45 (38708): Guest Log: job: cpuusage=42229 2020-06-01 22:05:46 (38708): Guest Log: 22:04:46 PST +08:00 2020-06-01: cranky: [INFO] Container 'runc' finished with status code 0. 2020-06-01 22:05:46 (38708): Guest Log: 22:04:46 PST +08:00 2020-06-01: cranky: [INFO] Preparing output. 2020-06-01 22:05:59 (38708): Guest Log: [INFO] Job Finished 2020-06-01 22:05:59 (38708): Guest Log: [INFO] Shutting Down. 2020-06-01 22:05:59 (38708): VM Completion File Detected. 2020-06-01 22:05:59 (38708): VM Completion Message: Job Finished . 2020-06-01 22:05:59 (38708): Powering off VM. 2020-06-01 22:11:00 (38708): VM did not power off when requested. 2020-06-01 22:11:00 (38708): VM was successfully terminated. 2020-06-01 22:11:00 (38708): Deregistering VM. (boinc_956ad126053fe042, slot#9) 2020-06-01 22:11:01 (38708): Removing network ban
©2025 CERN