Task 278742713

Name	Theory_2390-1114515-16_1
Workunit	141621311
Created	23 Jun 2020, 6:36:21 UTC
Sent	23 Jun 2020, 6:43:21 UTC
Report deadline	4 Jul 2020, 6:43:21 UTC
Received	5 Jul 2020, 7:12:05 UTC
Server state	Over
Outcome	Success
Client state	Done
Exit status	0 (0x00000000)
Computer ID	10653371
Run time	4 days 0 hours 8 min 0 sec
CPU time	22 hours 50 min 1 sec
Validate state	Valid
Credit	801.11
Device peak FLOPS	1.00 GFLOPS
Application version	Theory Simulation v300.06 (vbox64_theory) windows_x86_64
Peak working set size	71.79 MB
Peak swap size	75.38 MB
Peak disk usage	2.82 GB
Stderr output

<core_client_version>7.16.5</core_client_version>
<![CDATA[
<stderr_txt>
2020-06-23 06:05:49 (22816): Detected: vboxwrapper 26197
2020-06-23 06:05:49 (22816): Detected: BOINC client v7.7
2020-06-23 06:05:50 (22816): Detected: VirtualBox VboxManage Interface (Version: 6.0.14)
2020-06-23 06:05:50 (22816): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2020-06-23 06:05:50 (22816): Successfully copied 'init_data.xml' to the shared directory.
2020-06-23 06:05:50 (22816): Successfully copied 'input' to the shared directory.
2020-06-23 06:05:52 (22816): Create VM. (boinc_ea139dfdac523a57, slot#7)
2020-06-23 06:05:54 (22816): Setting Memory Size for VM. (630MB)
2020-06-23 06:05:54 (22816): Setting CPU Count for VM. (1)
2020-06-23 06:05:55 (22816): Setting Chipset Options for VM.
2020-06-23 06:05:55 (22816): Setting Boot Options for VM.
2020-06-23 06:05:55 (22816): Setting Network Configuration for NAT.
2020-06-23 06:05:55 (22816): Enabling VM Network Access.
2020-06-23 06:05:56 (22816): Disabling USB Support for VM.
2020-06-23 06:05:56 (22816): Disabling COM Port Support for VM.
2020-06-23 06:05:56 (22816): Disabling LPT Port Support for VM.
2020-06-23 06:05:56 (22816): Disabling Audio Support for VM.
2020-06-23 06:05:57 (22816): Disabling Clipboard Support for VM.
2020-06-23 06:05:57 (22816): Disabling Drag and Drop Support for VM.
2020-06-23 06:05:57 (22816): Adding storage controller(s) to VM.
2020-06-23 06:05:57 (22816): Adding virtual disk drive to VM. (vm_image.vdi)
2020-06-23 06:05:58 (22816): Adding VirtualBox Guest Additions to VM.
2020-06-23 06:05:58 (22816): Adding network bandwidth throttle group to VM. (Defaulting to 1024GB)
2020-06-23 06:05:58 (22816): forwarding host port 55841 to guest port 80
2020-06-23 06:05:59 (22816): Enabling remote desktop for VM.
2020-06-23 06:05:59 (22816): Required extension pack not installed, remote desktop not enabled.
2020-06-23 06:05:59 (22816): Enabling shared directory for VM.
2020-06-23 06:05:59 (22816): Starting VM using VBoxManage interface. (boinc_ea139dfdac523a57, slot#7)
2020-06-23 06:06:06 (22816): Successfully started VM. (PID = '18192')
2020-06-23 06:06:06 (22816): Reporting VM Process ID to BOINC.
2020-06-23 06:06:06 (22816): Guest Log: BIOS: VirtualBox 6.0.14

2020-06-23 06:06:06 (22816): Guest Log: CPUID EDX: 0x178bfbff

2020-06-23 06:06:06 (22816): Guest Log: BIOS: ata0-0: PCHS=16383/16/63 LCHS=1024/255/63

2020-06-23 06:06:06 (22816): VM state change detected. (old = 'PoweredOff', new = 'Running')
2020-06-23 06:06:06 (22816): Detected: Web Application Enabled (http://localhost:55841)
2020-06-23 06:06:06 (22816): Preference change detected
2020-06-23 06:06:06 (22816): Setting CPU throttle for VM. (50%)
2020-06-23 06:06:06 (22816): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2020-06-23 06:06:08 (22816): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032

2020-06-23 06:06:08 (22816): Guest Log: BIOS: Booting from Hard Disk...

2020-06-23 06:06:11 (22816): Guest Log: BIOS: KBD: unsupported int 16h function 03

2020-06-23 06:06:11 (22816): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 

2020-06-23 06:06:52 (22816): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds

2020-06-23 06:06:52 (22816): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000)

2020-06-23 06:07:02 (22816): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log

2020-06-23 06:07:02 (22816): Guest Log: 00:00:00.000295 main     Log opened 2020-06-23T10:07:02.687958000Z

2020-06-23 06:07:02 (22816): Guest Log: 00:00:00.000494 main     OS Product: Linux

2020-06-23 06:07:02 (22816): Guest Log: 00:00:00.000567 main     OS Release: 4.14.76-13.cernvm.x86_64

2020-06-23 06:07:02 (22816): Guest Log: 00:00:00.000636 main     OS Version: #1 SMP Tue Oct 16 18:26:15 CEST 2018

2020-06-23 06:07:02 (22816): Guest Log: 00:00:00.000701 main     Executable: /usr/sbin/VBoxService

2020-06-23 06:07:02 (22816): Guest Log: 00:00:00.000703 main     Process ID: 3202

2020-06-23 06:07:02 (22816): Guest Log: 00:00:00.000705 main     Package type: LINUX_64BITS_GENERIC

2020-06-23 06:07:02 (22816): Guest Log: 00:00:00.005623 main     5.2.6 r120293 started. Verbose level = 0

2020-06-23 06:08:10 (22816): Guest Log: 05:08:10 CDT -05:00 2020-06-23: cranky: [INFO] Detected Theory App

2020-06-23 06:08:10 (22816): Guest Log: 05:08:10 CDT -05:00 2020-06-23: cranky: [INFO] Checking CVMFS.

2020-06-23 06:08:15 (22816): Guest Log: Probing /cvmfs/sft.cern.ch... OK

2020-06-23 06:08:17 (22816): Guest Log: VERSION PID UPTIME(M) MEM(K) REVISION EXPIRES(M) NOCATALOGS CACHEUSE(K) CACHEMAX(K) NOFDUSE NOFDMAX NOIOERR NOOPEN HITRATE(%) RX(K) SPEED(K/S) HOST PROXY ONLINE

2020-06-23 06:08:17 (22816): Guest Log: 2.5.2.0 4116 0 27852 18311 3 1 263675 4096000 0 65024 0 0 n/a 5 2 http://s1cern-cvmfs.openhtc.io/cvmfs/sft.cern.ch DIRECT 1

2020-06-23 06:08:23 (22816): Guest Log: Probing /cvmfs/grid.cern.ch... OK

2020-06-23 06:08:25 (22816): Guest Log: VERSION PID UPTIME(M) MEM(K) REVISION EXPIRES(M) NOCATALOGS CACHEUSE(K) CACHEMAX(K) NOFDUSE NOFDMAX NOIOERR NOOPEN HITRATE(%) RX(K) SPEED(K/S) HOST PROXY ONLINE

2020-06-23 06:08:25 (22816): Guest Log: 2.5.2.0 4137 0 27536 12547 3 2 263675 4096000 0 65024 0 2 -100 6410 2551 http://s1unl-cvmfs.openhtc.io/cvmfs/grid.cern.ch DIRECT 1

2020-06-23 06:08:30 (22816): Guest Log: Probing /cvmfs/cernvm-prod.cern.ch... OK

2020-06-23 06:08:33 (22816): Guest Log: VERSION PID UPTIME(M) MEM(K) REVISION EXPIRES(M) NOCATALOGS CACHEUSE(K) CACHEMAX(K) NOFDUSE NOFDMAX NOIOERR NOOPEN HITRATE(%) RX(K) SPEED(K/S) HOST PROXY ONLINE

2020-06-23 06:08:33 (22816): Guest Log: 2.5.2.0 4096 0 27228 255 3 1 263675 4096000 0 65024 0 0 n/a 4 4 http://s1unl-cvmfs.openhtc.io/cvmfs/cernvm-prod.cern.ch DIRECT 1

2020-06-23 06:08:38 (22816): Guest Log: Probing /cvmfs/alice.cern.ch... OK

2020-06-23 06:08:41 (22816): Guest Log: VERSION PID UPTIME(M) MEM(K) REVISION EXPIRES(M) NOCATALOGS CACHEUSE(K) CACHEMAX(K) NOFDUSE NOFDMAX NOIOERR NOOPEN HITRATE(%) RX(K) SPEED(K/S) HOST PROXY ONLINE

2020-06-23 06:08:41 (22816): Guest Log: 2.5.2.0 4074 1 29800 6859 3 1 263675 4096000 0 65024 0 0 n/a 4154 2502 http://s1fnal-cvmfs.openhtc.io/cvmfs/alice.cern.ch DIRECT 1

2020-06-23 06:08:41 (22816): Guest Log: 05:08:41 CDT -05:00 2020-06-23: cranky: [INFO] Checking runc.

2020-06-23 06:08:41 (22816): Guest Log: 05:08:41 CDT -05:00 2020-06-23: cranky: [INFO] Creating the filesystem.

2020-06-23 06:08:41 (22816): Guest Log: 05:08:41 CDT -05:00 2020-06-23: cranky: [INFO] Using /cvmfs/cernvm-prod.cern.ch/cvm3

2020-06-23 06:08:42 (22816): Guest Log: 05:08:42 CDT -05:00 2020-06-23: cranky: [INFO] Updating config.json.

2020-06-23 06:08:42 (22816): Guest Log: 05:08:42 CDT -05:00 2020-06-23: cranky: [INFO] Running Container 'runc'.

2020-06-23 06:08:43 (22816): Guest Log: job: htmld=/shared/html/job

2020-06-23 06:08:43 (22816): Guest Log: job: unpack exitcode=0

2020-06-23 06:08:44 (22816): Guest Log: 05:08:44 CDT -05:00 2020-06-23: cranky: [INFO] ===> [runRivet] Tue Jun 23 10:08:43 UTC 2020 [boinc pp jets 7000 250 - pythia8 8.240 fischerPP1 100000 16]

2020-06-23 07:46:11 (22816): Status Report: Job Duration: '864000.000000'
2020-06-23 07:46:11 (22816): Status Report: Elapsed Time: '6000.000000'
2020-06-23 07:46:11 (22816): Status Report: CPU Time: '3550.984375'
2020-06-23 08:33:49 (22816): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-23 08:33:58 (22816): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-23 08:37:19 (22816): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-23 08:37:29 (22816): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-23 08:38:09 (22816): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-23 08:38:19 (22816): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-23 08:38:49 (22816): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-23 08:38:59 (22816): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-23 08:47:33 (22816): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-23 08:47:42 (22816): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-23 08:47:52 (22816): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-23 08:48:02 (22816): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-23 08:49:13 (22816): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-23 08:49:23 (22816): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-23 08:56:04 (22816): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-23 08:56:14 (22816): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-23 08:57:14 (22816): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-23 08:57:24 (22816): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-23 09:05:09 (22816): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-23 09:05:17 (22816): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-23 09:27:12 (22816): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-23 09:27:22 (22816): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-23 09:28:17 (22816): Status Report: Job Duration: '864000.000000'
2020-06-23 09:28:17 (22816): Status Report: Elapsed Time: '12000.000000'
2020-06-23 09:28:17 (22816): Status Report: CPU Time: '7159.453125'
2020-06-23 09:54:10 (22816): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-23 09:54:20 (22816): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-23 09:58:42 (22816): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-23 09:58:52 (22816): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-23 10:01:33 (22816): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-23 10:01:43 (22816): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-23 10:13:05 (22816): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-23 10:13:15 (22816): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-23 10:34:53 (22816): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-23 10:35:03 (22816): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-23 10:52:27 (22816): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-23 10:52:37 (22816): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-23 11:09:28 (22816): Status Report: Job Duration: '864000.000000'
2020-06-23 11:09:28 (22816): Status Report: Elapsed Time: '18000.000000'
2020-06-23 11:09:28 (22816): Status Report: CPU Time: '10772.500000'
2020-06-23 11:34:37 (22816): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-23 11:34:57 (22816): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-23 11:35:38 (22816): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-23 11:35:48 (22816): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-23 11:37:38 (22816): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-23 11:37:48 (22816): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-23 11:38:08 (22816): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-23 11:38:18 (22816): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-23 11:38:48 (22816): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-23 11:38:58 (22816): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-23 11:46:39 (22816): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-23 11:46:50 (22816): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-23 11:49:10 (22816): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-23 11:49:20 (22816): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-23 11:54:32 (22816): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-23 11:54:42 (22816): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-23 12:00:23 (22816): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-23 12:00:33 (22816): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-23 12:32:00 (22816): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-23 12:32:10 (22816): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-23 12:51:34 (22816): Status Report: Job Duration: '864000.000000'
2020-06-23 12:51:34 (22816): Status Report: Elapsed Time: '24000.000000'
2020-06-23 12:51:34 (22816): Status Report: CPU Time: '14385.281250'
2020-06-23 12:55:56 (22816): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-23 12:56:06 (22816): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-23 12:56:26 (22816): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-23 12:56:36 (22816): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-23 13:50:09 (22816): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-23 13:50:19 (22816): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-23 13:50:40 (22816): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-23 13:50:49 (22816): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-23 13:50:59 (22816): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-23 13:51:09 (22816): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-23 13:51:30 (22816): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-23 13:51:39 (22816): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-23 14:32:45 (22816): Status Report: Job Duration: '864000.000000'
2020-06-23 14:32:45 (22816): Status Report: Elapsed Time: '30000.000000'
2020-06-23 14:32:45 (22816): Status Report: CPU Time: '17993.546875'
2020-06-23 14:51:23 (22816): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-23 14:51:34 (22816): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-23 16:05:13 (22816): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-23 16:05:23 (22816): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-23 16:13:13 (22816): Status Report: Job Duration: '864000.000000'
2020-06-23 16:13:13 (22816): Status Report: Elapsed Time: '36000.000000'
2020-06-23 16:13:13 (22816): Status Report: CPU Time: '21604.750000'
2020-06-23 16:59:15 (22816): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-23 16:59:25 (22816): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-23 17:53:30 (22816): Status Report: Job Duration: '864000.000000'
2020-06-23 17:53:30 (22816): Status Report: Elapsed Time: '42000.000000'
2020-06-23 17:53:30 (22816): Status Report: CPU Time: '25210.296875'
2020-06-23 19:02:59 (22816): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-23 19:03:08 (22816): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-23 19:33:45 (22816): Status Report: Job Duration: '864000.000000'
2020-06-23 19:33:45 (22816): Status Report: Elapsed Time: '48000.000000'
2020-06-23 19:33:45 (22816): Status Report: CPU Time: '28816.609375'
2020-06-23 20:48:27 (22816): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-23 20:48:37 (22816): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-23 21:14:07 (22816): Status Report: Job Duration: '864000.000000'
2020-06-23 21:14:07 (22816): Status Report: Elapsed Time: '54000.314703'
2020-06-23 21:14:07 (22816): Status Report: CPU Time: '32426.250000'
2020-06-23 21:44:04 (22816): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-23 21:44:14 (22816): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-23 21:47:02 (22816): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-23 21:47:35 (22816): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-23 22:54:50 (22816): Status Report: Job Duration: '864000.000000'
2020-06-23 22:54:50 (22816): Status Report: Elapsed Time: '60000.457052'
2020-06-23 22:54:50 (22816): Status Report: CPU Time: '36033.609375'
2020-06-24 00:34:57 (22816): Status Report: Job Duration: '864000.000000'
2020-06-24 00:34:57 (22816): Status Report: Elapsed Time: '66000.457052'
2020-06-24 00:34:57 (22816): Status Report: CPU Time: '39641.906250'
2020-06-24 00:40:03 (22816): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-24 00:40:14 (22816): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-24 02:15:15 (22816): Status Report: Job Duration: '864000.000000'
2020-06-24 02:15:15 (22816): Status Report: Elapsed Time: '72000.457052'
2020-06-24 02:15:15 (22816): Status Report: CPU Time: '43249.578125'
2020-06-24 03:55:22 (22816): Status Report: Job Duration: '864000.000000'
2020-06-24 03:55:22 (22816): Status Report: Elapsed Time: '78000.457052'
2020-06-24 03:55:22 (22816): Status Report: CPU Time: '46858.843750'
2020-06-24 05:35:28 (22816): Status Report: Job Duration: '864000.000000'
2020-06-24 05:35:28 (22816): Status Report: Elapsed Time: '84000.457052'
2020-06-24 05:35:28 (22816): Status Report: CPU Time: '50467.375000'
2020-06-24 07:15:36 (22816): Status Report: Job Duration: '864000.000000'
2020-06-24 07:15:36 (22816): Status Report: Elapsed Time: '90000.457052'
2020-06-24 07:15:36 (22816): Status Report: CPU Time: '54077.281250'
2020-06-24 07:45:53 (22816): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-24 07:46:03 (22816): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-24 08:55:53 (22816): Status Report: Job Duration: '864000.000000'
2020-06-24 08:55:53 (22816): Status Report: Elapsed Time: '96000.457052'
2020-06-24 08:55:53 (22816): Status Report: CPU Time: '57684.515625'
2020-06-24 10:01:32 (22816): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-24 10:01:41 (22816): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-24 10:19:06 (22816): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-24 10:19:16 (22816): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-24 10:23:38 (22816): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-24 10:23:48 (22816): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-24 10:24:08 (22816): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-24 10:24:19 (22816): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-24 10:26:29 (22816): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-24 10:26:39 (22816): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-24 10:30:40 (22816): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-24 10:30:50 (22816): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-24 10:31:20 (22816): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-24 10:31:40 (22816): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-24 10:31:50 (22816): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-24 10:32:00 (22816): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-24 10:33:10 (22816): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-24 10:33:20 (22816): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-24 10:37:47 (22816): Status Report: Job Duration: '864000.000000'
2020-06-24 10:37:47 (22816): Status Report: Elapsed Time: '102000.457052'
2020-06-24 10:37:47 (22816): Status Report: CPU Time: '61298.984375'
2020-06-24 10:42:53 (22816): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-24 10:43:03 (22816): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-24 12:11:42 (22816): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-24 12:11:52 (22816): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-24 12:18:15 (22816): Status Report: Job Duration: '864000.000000'
2020-06-24 12:18:15 (22816): Status Report: Elapsed Time: '108000.457052'
2020-06-24 12:18:15 (22816): Status Report: CPU Time: '64905.140625'
2020-06-24 13:35:26 (22816): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-24 13:35:35 (22816): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-24 13:41:57 (22816): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-24 13:42:07 (22816): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-24 13:58:41 (22816): Status Report: Job Duration: '864000.000000'
2020-06-24 13:58:41 (22816): Status Report: Elapsed Time: '114000.457052'
2020-06-24 13:58:41 (22816): Status Report: CPU Time: '68511.859375'
2020-06-24 15:01:03 (22816): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-24 15:01:12 (22816): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-24 15:38:57 (22816): Status Report: Job Duration: '864000.000000'
2020-06-24 15:38:57 (22816): Status Report: Elapsed Time: '120000.457052'
2020-06-24 15:38:57 (22816): Status Report: CPU Time: '72118.171875'
2020-06-24 17:19:02 (22816): Status Report: Job Duration: '864000.000000'
2020-06-24 17:19:02 (22816): Status Report: Elapsed Time: '126000.457052'
2020-06-24 17:19:02 (22816): Status Report: CPU Time: '75723.921875'
2020-06-24 17:19:52 (22816): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-24 17:20:02 (22816): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-24 17:41:08 (22816): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-24 17:41:17 (22816): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-24 17:58:21 (22816): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-24 17:58:30 (22816): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-24 18:01:11 (22816): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-24 18:01:20 (22816): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-24 18:59:49 (22816): Status Report: Job Duration: '864000.000000'
2020-06-24 18:59:49 (22816): Status Report: Elapsed Time: '132000.457052'
2020-06-24 18:59:49 (22816): Status Report: CPU Time: '79330.953125'
2020-06-24 19:01:44 (22816): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-24 19:01:53 (22816): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-24 20:12:22 (22816): Stopping VM.
2020-06-24 20:12:22 (22816): Error in stop VM for VM: -108
Command:
VBoxManage -q controlvm "boinc_ea139dfdac523a57" savestate
Output:

2020-06-24 20:12:22 (22816): VM did not stop when requested.
2020-06-24 20:12:22 (22816): VM was successfully terminated.
2020-06-24 21:20:12 (18252): Detected: vboxwrapper 26197
2020-06-24 21:20:12 (18252): Detected: BOINC client v7.7
2020-06-24 21:20:51 (18252): Detected: VirtualBox VboxManage Interface (Version: 6.0.14)
2020-06-24 21:20:51 (18252): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2020-06-24 21:20:52 (18252): Starting VM using VBoxManage interface. (boinc_ea139dfdac523a57, slot#7)
2020-06-24 21:21:50 (18252): Successfully started VM. (PID = '17764')
2020-06-24 21:21:50 (18252): Reporting VM Process ID to BOINC.
2020-06-24 21:21:50 (18252): Guest Log: BIOS: VirtualBox 6.0.14

2020-06-24 21:21:50 (18252): Guest Log: CPUID EDX: 0x178bfbff

2020-06-24 21:21:50 (18252): Guest Log: BIOS: ata0-0: PCHS=16383/16/63 LCHS=1024/255/63

2020-06-24 21:21:50 (18252): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032

2020-06-24 21:21:50 (18252): Guest Log: BIOS: Booting from Hard Disk...

2020-06-24 21:21:50 (18252): VM state change detected. (old = 'PoweredOff', new = 'Running')
2020-06-24 21:21:50 (18252): Detected: Web Application Enabled (http://localhost:55841)
2020-06-24 21:21:51 (18252): VM state change detected. (old = 'Running', new = 'Paused')
2020-06-24 21:21:57 (18252): Status Report: Job Duration: '864000.000000'
2020-06-24 21:21:57 (18252): Status Report: Elapsed Time: '136338.457052'
2020-06-24 21:21:57 (18252): Status Report: CPU Time: '81939.406250'
2020-06-24 21:21:57 (18252): Preference change detected
2020-06-24 21:21:57 (18252): Setting CPU throttle for VM. (50%)
2020-06-24 21:22:00 (18252): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2020-06-24 21:22:17 (18252): VM state change detected. (old = 'Paused', new = 'Running')
2020-06-24 21:22:53 (18252): Guest Log: BIOS: KBD: unsupported int 16h function 03

2020-06-24 21:22:53 (18252): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 

2020-06-24 21:28:14 (18252): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds

2020-06-24 21:28:14 (18252): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000)

2020-06-24 21:29:05 (18252): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log

2020-06-24 21:29:05 (18252): Guest Log: 00:00:00.000273 main     Log opened 2020-06-25T01:29:04.728416000Z

2020-06-24 21:29:05 (18252): Guest Log: 00:00:00.000511 main     OS Product: Linux

2020-06-24 21:29:05 (18252): Guest Log: 00:00:00.000628 main     OS Release: 4.14.76-13.cernvm.x86_64

2020-06-24 21:29:05 (18252): Guest Log: 00:00:00.000752 main     OS Version: #1 SMP Tue Oct 16 18:26:15 CEST 2018

2020-06-24 21:29:05 (18252): Guest Log: 00:00:00.000842 main     Executable: /usr/sbin/VBoxService

2020-06-24 21:29:05 (18252): Guest Log: 00:00:00.000845 main     Process ID: 3194

2020-06-24 21:29:05 (18252): Guest Log: 00:00:00.000848 main     Package type: LINUX_64BITS_GENERIC

2020-06-24 21:29:05 (18252): Guest Log: 00:00:00.005814 main     5.2.6 r120293 started. Verbose level = 0

2020-06-24 21:32:37 (18252): Guest Log: 20:32:21 CDT -05:00 2020-06-24: cranky: [INFO] Detected Theory App

2020-06-24 21:32:37 (18252): Guest Log: 20:32:21 CDT -05:00 2020-06-24: cranky: [INFO] Checking CVMFS.

2020-06-24 21:32:37 (18252): Guest Log: Probing /cvmfs/sft.cern.ch... OK

2020-06-24 21:32:37 (18252): Guest Log: VERSION PID UPTIME(M) MEM(K) REVISION EXPIRES(M) NOCATALOGS CACHEUSE(K) CACHEMAX(K) NOFDUSE NOFDMAX NOIOERR NOOPEN HITRATE(%) RX(K) SPEED(K/S) HOST PROXY ONLINE

2020-06-24 21:32:37 (18252): Guest Log: 2.5.2.0 4207 2 27600 18315 2 1 1195552 4096000 0 65024 0 0 n/a 0 0 http://s1ral-cvmfs.openhtc.io/cvmfs/sft.cern.ch DIRECT 1

2020-06-24 21:32:47 (18252): Guest Log: Probing /cvmfs/grid.cern.ch... OK

2020-06-24 21:32:47 (18252): Guest Log: VERSION PID UPTIME(M) MEM(K) REVISION EXPIRES(M) NOCATALOGS CACHEUSE(K) CACHEMAX(K) NOFDUSE NOFDMAX NOIOERR NOOPEN HITRATE(%) RX(K) SPEED(K/S) HOST PROXY ONLINE

2020-06-24 21:32:47 (18252): Guest Log: 2.5.2.0 4186 2 27592 12561 2 2 1195552 4096000 0 65024 0 2 0 4273 935 http://s1fnal-cvmfs.openhtc.io/cvmfs/grid.cern.ch DIRECT 1

2020-06-24 21:32:47 (18252): Guest Log: Probing /cvmfs/cernvm-prod.cern.ch... OK

2020-06-24 21:32:49 (18252): Guest Log: VERSION PID UPTIME(M) MEM(K) REVISION EXPIRES(M) NOCATALOGS CACHEUSE(K) CACHEMAX(K) NOFDUSE NOFDMAX NOIOERR NOOPEN HITRATE(%) RX(K) SPEED(K/S) HOST PROXY ONLINE

2020-06-24 21:32:49 (18252): Guest Log: 2.5.2.0 4135 2 27228 255 2 1 1195552 4096000 0 65024 0 0 n/a 0 0 http://s1bnl-cvmfs.openhtc.io/cvmfs/cernvm-prod.cern.ch DIRECT 1

2020-06-24 21:33:06 (18252): Guest Log: Probing /cvmfs/alice.cern.ch... OK

2020-06-24 21:33:06 (18252): Guest Log: VERSION PID UPTIME(M) MEM(K) REVISION EXPIRES(M) NOCATALOGS CACHEUSE(K) CACHEMAX(K) NOFDUSE NOFDMAX NOIOERR NOOPEN HITRATE(%) RX(K) SPEED(K/S) HOST PROXY ONLINE

2020-06-24 21:33:06 (18252): Guest Log: 2.5.2.0 4163 2 31040 6872 2 1 1195552 4096000 0 65024 0 0 n/a 4177 1551 http://s1asgc-cvmfs.openhtc.io:8080/cvmfs/alice.cern.ch DIRECT 1

2020-06-24 21:33:06 (18252): Guest Log: 20:32:56 CDT -05:00 2020-06-24: cranky: [INFO] Checking runc.

2020-06-24 21:33:10 (18252): Guest Log: 20:33:08 CDT -05:00 2020-06-24: cranky: [INFO] Creating the filesystem.

2020-06-24 21:33:14 (18252): Guest Log: 20:33:12 CDT -05:00 2020-06-24: cranky: [INFO] Using /cvmfs/cernvm-prod.cern.ch/cvm3

2020-06-24 21:33:14 (18252): Guest Log: 20:33:12 CDT -05:00 2020-06-24: cranky: [INFO] Updating config.json.

2020-06-24 21:33:14 (18252): Guest Log: 20:33:14 CDT -05:00 2020-06-24: cranky: [INFO] Running Container 'runc'.

2020-06-24 21:34:53 (18252): Guest Log: job: htmld=/shared/html/job

2020-06-24 21:34:53 (18252): Guest Log: job: unpack exitcode=0

2020-06-24 21:34:53 (18252): Guest Log: 20:34:50 CDT -05:00 2020-06-24: cranky: [INFO] ===> [runRivet] Thu Jun 25 01:34:48 UTC 2020 [boinc pp jets 7000 250 - pythia8 8.240 fischerPP1 100000 16]

2020-06-24 21:41:14 (18252): Stopping VM.
2020-07-02 11:53:40 (4596): Detected: vboxwrapper 26197
2020-07-02 11:53:40 (4596): Detected: BOINC client v7.7
2020-07-02 11:53:46 (4596): Detected: VirtualBox VboxManage Interface (Version: 6.0.14)
2020-07-02 11:53:46 (4596): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2020-07-02 11:53:47 (4596): Starting VM using VBoxManage interface. (boinc_ea139dfdac523a57, slot#7)
2020-07-02 11:55:08 (4596): Successfully started VM. (PID = '10460')
2020-07-02 11:55:08 (4596): Reporting VM Process ID to BOINC.
2020-07-02 11:55:08 (4596): VM state change detected. (old = 'PoweredOff', new = 'Running')
2020-07-02 11:55:09 (4596): Detected: Web Application Enabled (http://localhost:55841)
2020-07-02 11:55:09 (4596): Status Report: Job Duration: '864000.000000'
2020-07-02 11:55:09 (4596): Status Report: Elapsed Time: '137495.727667'
2020-07-02 11:55:09 (4596): Status Report: CPU Time: '82078.968750'
2020-07-02 11:55:09 (4596): Preference change detected
2020-07-02 11:55:09 (4596): Setting CPU throttle for VM. (50%)
2020-07-02 11:55:09 (4596): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2020-07-02 11:55:15 (4596): Guest Log: 00:12:20.169452 timesync vgsvcTimeSyncWorker: Radical host time change: 656 039 415 000 000ns (HostNow=1 593 705 314 312 000 000 ns HostLast=1 593 049 274 897 000 000 ns)

2020-07-02 11:55:25 (4596): Guest Log: 00:12:30.170488 timesync vgsvcTimeSyncWorker: Radical guest time change: 656 039 415 624 000ns (GuestNow=1 593 705 324 313 093 000 ns GuestLast=1 593 049 284 897 469 000 ns fSetTimeLastLoop=true )

2020-07-02 12:19:34 (4596): VM state change detected. (old = 'Running', new = 'Paused')
2020-07-02 12:19:44 (4596): VM state change detected. (old = 'Paused', new = 'Running')
2020-07-02 12:25:15 (4596): VM state change detected. (old = 'Running', new = 'Paused')
2020-07-02 12:25:25 (4596): VM state change detected. (old = 'Paused', new = 'Running')
2020-07-02 12:25:35 (4596): VM state change detected. (old = 'Running', new = 'Paused')
2020-07-02 12:25:45 (4596): VM state change detected. (old = 'Paused', new = 'Running')
2020-07-02 12:47:21 (4596): VM state change detected. (old = 'Running', new = 'Paused')
2020-07-02 12:47:31 (4596): VM state change detected. (old = 'Paused', new = 'Running')
2020-07-02 13:35:58 (4596): Status Report: Job Duration: '864000.000000'
2020-07-02 13:35:58 (4596): Status Report: Elapsed Time: '143495.727667'
2020-07-02 13:35:58 (4596): Status Report: CPU Time: '85382.343750'
2020-07-02 15:05:05 (4596): VM state change detected. (old = 'Running', new = 'Paused')
2020-07-02 15:05:15 (4596): VM state change detected. (old = 'Paused', new = 'Running')
2020-07-02 15:16:14 (4596): Status Report: Job Duration: '864000.000000'
2020-07-02 15:16:14 (4596): Status Report: Elapsed Time: '149495.727667'
2020-07-02 15:16:14 (4596): Status Report: CPU Time: '88990.140625'
2020-07-02 16:10:22 (4596): VM state change detected. (old = 'Running', new = 'Paused')
2020-07-02 16:10:32 (4596): VM state change detected. (old = 'Paused', new = 'Running')
2020-07-02 16:18:14 (4596): VM state change detected. (old = 'Running', new = 'Paused')
2020-07-02 16:18:24 (4596): VM state change detected. (old = 'Paused', new = 'Running')
2020-07-02 16:47:01 (4596): VM state change detected. (old = 'Running', new = 'Paused')
2020-07-02 16:47:11 (4596): VM state change detected. (old = 'Paused', new = 'Running')
2020-07-02 16:50:41 (4596): VM state change detected. (old = 'Running', new = 'Paused')
2020-07-02 16:50:51 (4596): VM state change detected. (old = 'Paused', new = 'Running')
2020-07-02 16:56:12 (4596): VM state change detected. (old = 'Running', new = 'Paused')
2020-07-02 16:56:22 (4596): VM state change detected. (old = 'Paused', new = 'Running')
2020-07-02 16:57:12 (4596): VM state change detected. (old = 'Running', new = 'Paused')
2020-07-02 16:57:22 (4596): VM state change detected. (old = 'Paused', new = 'Running')
2020-07-02 16:57:26 (4596): Status Report: Job Duration: '864000.000000'
2020-07-02 16:57:26 (4596): Status Report: Elapsed Time: '155495.727667'
2020-07-02 16:57:26 (4596): Status Report: CPU Time: '92603.359375'
2020-07-02 16:58:13 (4596): VM state change detected. (old = 'Running', new = 'Paused')
2020-07-02 16:58:22 (4596): VM state change detected. (old = 'Paused', new = 'Running')
2020-07-02 16:59:33 (4596): VM state change detected. (old = 'Running', new = 'Paused')
2020-07-02 16:59:43 (4596): VM state change detected. (old = 'Paused', new = 'Running')
2020-07-02 17:01:23 (4596): VM state change detected. (old = 'Running', new = 'Paused')
2020-07-02 17:01:33 (4596): VM state change detected. (old = 'Paused', new = 'Running')
2020-07-02 17:18:08 (4596): VM state change detected. (old = 'Running', new = 'Paused')
2020-07-02 17:18:18 (4596): VM state change detected. (old = 'Paused', new = 'Running')
2020-07-02 17:22:09 (4596): VM state change detected. (old = 'Running', new = 'Paused')
2020-07-02 17:22:19 (4596): VM state change detected. (old = 'Paused', new = 'Running')
2020-07-02 17:22:29 (4596): VM state change detected. (old = 'Running', new = 'Paused')
2020-07-02 17:22:39 (4596): VM state change detected. (old = 'Paused', new = 'Running')
2020-07-02 17:29:30 (4596): VM state change detected. (old = 'Running', new = 'Paused')
2020-07-02 17:29:40 (4596): VM state change detected. (old = 'Paused', new = 'Running')
2020-07-02 17:31:10 (4596): VM state change detected. (old = 'Running', new = 'Paused')
2020-07-02 17:31:20 (4596): VM state change detected. (old = 'Paused', new = 'Running')
2020-07-02 18:38:59 (4596): Status Report: Job Duration: '864000.000000'
2020-07-02 18:38:59 (4596): Status Report: Elapsed Time: '161495.727667'
2020-07-02 18:38:59 (4596): Status Report: CPU Time: '96212.156250'
2020-07-02 19:27:48 (4596): VM state change detected. (old = 'Running', new = 'Paused')
2020-07-02 19:27:58 (4596): VM state change detected. (old = 'Paused', new = 'Running')
2020-07-02 20:19:15 (4596): Status Report: Job Duration: '864000.000000'
2020-07-02 20:19:15 (4596): Status Report: Elapsed Time: '167495.727667'
2020-07-02 20:19:15 (4596): Status Report: CPU Time: '99828.890625'
2020-07-02 21:02:22 (4596): VM state change detected. (old = 'Running', new = 'Paused')
2020-07-02 21:02:32 (4596): VM state change detected. (old = 'Paused', new = 'Running')
2020-07-02 21:59:32 (4596): Status Report: Job Duration: '864000.000000'
2020-07-02 21:59:32 (4596): Status Report: Elapsed Time: '173495.727667'
2020-07-02 21:59:32 (4596): Status Report: CPU Time: '103439.843750'
2020-07-02 22:01:29 (4596): VM state change detected. (old = 'Running', new = 'Paused')
2020-07-02 22:01:38 (4596): VM state change detected. (old = 'Paused', new = 'Running')
2020-07-02 23:11:58 (4596): VM state change detected. (old = 'Running', new = 'Paused')
2020-07-02 23:12:07 (4596): VM state change detected. (old = 'Paused', new = 'Running')
2020-07-02 23:39:58 (4596): Status Report: Job Duration: '864000.000000'
2020-07-02 23:39:58 (4596): Status Report: Elapsed Time: '179495.727667'
2020-07-02 23:39:58 (4596): Status Report: CPU Time: '107046.968750'
2020-07-03 01:20:03 (4596): Status Report: Job Duration: '864000.000000'
2020-07-03 01:20:03 (4596): Status Report: Elapsed Time: '185495.727667'
2020-07-03 01:20:03 (4596): Status Report: CPU Time: '110652.906250'
2020-07-03 02:41:02 (4596): VM state change detected. (old = 'Running', new = 'Paused')
2020-07-03 02:41:12 (4596): VM state change detected. (old = 'Paused', new = 'Running')
2020-07-03 03:00:19 (4596): Status Report: Job Duration: '864000.000000'
2020-07-03 03:00:19 (4596): Status Report: Elapsed Time: '191495.727667'
2020-07-03 03:00:19 (4596): Status Report: CPU Time: '114258.890625'
2020-07-03 03:50:43 (4596): VM state change detected. (old = 'Running', new = 'Paused')
2020-07-03 03:50:53 (4596): VM state change detected. (old = 'Paused', new = 'Running')
2020-07-03 04:40:37 (4596): Status Report: Job Duration: '864000.000000'
2020-07-03 04:40:37 (4596): Status Report: Elapsed Time: '197495.727667'
2020-07-03 04:40:37 (4596): Status Report: CPU Time: '117865.656250'
2020-07-03 06:20:42 (4596): Status Report: Job Duration: '864000.000000'
2020-07-03 06:20:42 (4596): Status Report: Elapsed Time: '203495.727667'
2020-07-03 06:20:42 (4596): Status Report: CPU Time: '121472.156250'
2020-07-03 12:43:30 (12024): Detected: vboxwrapper 26197
2020-07-03 12:43:30 (12024): Detected: BOINC client v7.7
2020-07-03 12:43:35 (12024): Detected: VirtualBox VboxManage Interface (Version: 6.0.14)
2020-07-03 12:43:36 (12024): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2020-07-03 12:43:36 (12024): Starting VM using VBoxManage interface. (boinc_ea139dfdac523a57, slot#7)
2020-07-03 12:43:49 (12024): Successfully started VM. (PID = '5036')
2020-07-03 12:43:49 (12024): Reporting VM Process ID to BOINC.
2020-07-03 12:43:49 (12024): Guest Log: BIOS: VirtualBox 6.0.14

2020-07-03 12:43:49 (12024): Guest Log: CPUID EDX: 0x178bfbff

2020-07-03 12:43:49 (12024): Guest Log: BIOS: ata0-0: PCHS=16383/16/63 LCHS=1024/255/63

2020-07-03 12:43:49 (12024): VM state change detected. (old = 'PoweredOff', new = 'Running')
2020-07-03 12:43:50 (12024): VM state change detected. (old = 'Running', new = 'Paused')
2020-07-03 12:43:59 (12024): Preference change detected
2020-07-03 12:43:59 (12024): Setting CPU throttle for VM. (50%)
2020-07-03 12:44:00 (12024): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2020-07-03 12:44:00 (12024): VM state change detected. (old = 'Paused', new = 'Running')
2020-07-03 12:44:01 (12024): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032

2020-07-03 12:44:01 (12024): Guest Log: BIOS: Booting from Hard Disk...

2020-07-03 12:44:16 (12024): Guest Log: BIOS: KBD: unsupported int 16h function 03

2020-07-03 12:44:16 (12024): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 

2020-07-03 12:47:47 (12024): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds

2020-07-03 12:47:47 (12024): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000)

2020-07-03 12:48:34 (12024): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log

2020-07-03 12:48:34 (12024): Guest Log: 00:00:00.000295 main     Log opened 2020-07-03T16:48:33.810279000Z

2020-07-03 12:48:34 (12024): Guest Log: 00:00:00.000502 main     OS Product: Linux

2020-07-03 12:48:34 (12024): Guest Log: 00:00:00.000600 main     OS Release: 4.14.76-13.cernvm.x86_64

2020-07-03 12:48:34 (12024): Guest Log: 00:00:00.000699 main     OS Version: #1 SMP Tue Oct 16 18:26:15 CEST 2018

2020-07-03 12:48:34 (12024): Guest Log: 00:00:00.000765 main     Executable: /usr/sbin/VBoxService

2020-07-03 12:48:34 (12024): Guest Log: 00:00:00.000768 main     Process ID: 3245

2020-07-03 12:48:34 (12024): Guest Log: 00:00:00.000769 main     Package type: LINUX_64BITS_GENERIC

2020-07-03 12:48:34 (12024): Guest Log: 00:00:00.005254 main     5.2.6 r120293 started. Verbose level = 0

2020-07-03 12:50:58 (12024): Guest Log: 11:50:57 CDT -05:00 2020-07-03: cranky: [INFO] Detected Theory App

2020-07-03 12:50:58 (12024): Guest Log: 11:50:57 CDT -05:00 2020-07-03: cranky: [INFO] Checking CVMFS.

2020-07-03 12:51:03 (12024): Guest Log: Probing /cvmfs/sft.cern.ch... OK

2020-07-03 12:51:06 (12024): 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-07-03 12:51:06 (12024): Guest Log: 2.5.2.0 4167 1 28388 18391 2 1 1773529 4096000 0 65024 0 0 n/a 5 5 http://s1unl-cvmfs.openhtc.io/cvmfs/sft.cern.ch DIRECT 1

2020-07-03 12:51:11 (12024): Guest Log: Probing /cvmfs/grid.cern.ch... OK

2020-07-03 12:51:14 (12024): 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-07-03 12:51:14 (12024): Guest Log: 2.5.2.0 4188 1 27556 12629 2 2 1773529 4096000 0 65024 0 2 0 4272 2768 http://s1fnal-cvmfs.openhtc.io/cvmfs/grid.cern.ch DIRECT 1

2020-07-03 12:51:19 (12024): Guest Log: Probing /cvmfs/cernvm-prod.cern.ch... OK

2020-07-03 12:51:24 (12024): 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-07-03 12:51:24 (12024): Guest Log: 2.5.2.0 4145 1 27224 255 2 1 1773529 4096000 0 65024 0 0 n/a 0 0 http://s1ral-cvmfs.openhtc.io/cvmfs/cernvm-prod.cern.ch DIRECT 1

2020-07-03 12:51:30 (12024): Guest Log: Probing /cvmfs/alice.cern.ch... OK

2020-07-03 12:51:32 (12024): 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-07-03 12:51:32 (12024): Guest Log: 2.5.2.0 4209 2 29828 6933 2 1 1773529 4096000 0 65024 0 0 n/a 4182 2942 http://s1ral-cvmfs.openhtc.io/cvmfs/alice.cern.ch DIRECT 1

2020-07-03 12:51:32 (12024): Guest Log: 11:51:32 CDT -05:00 2020-07-03: cranky: [INFO] Checking runc.

2020-07-03 12:51:45 (12024): Guest Log: 11:51:45 CDT -05:00 2020-07-03: cranky: [INFO] Creating the filesystem.

2020-07-03 12:51:48 (12024): Guest Log: 11:51:48 CDT -05:00 2020-07-03: cranky: [INFO] Using /cvmfs/cernvm-prod.cern.ch/cvm3

2020-07-03 12:51:49 (12024): Guest Log: 11:51:49 CDT -05:00 2020-07-03: cranky: [INFO] Updating config.json.

2020-07-03 12:51:50 (12024): Guest Log: 11:51:49 CDT -05:00 2020-07-03: cranky: [INFO] Running Container 'runc'.

2020-07-03 12:52:28 (12024): Guest Log: job: htmld=/shared/html/job

2020-07-03 12:52:28 (12024): Guest Log: job: unpack exitcode=0

2020-07-03 12:52:31 (12024): Guest Log: 11:52:30 CDT -05:00 2020-07-03: cranky: [INFO] ===> [runRivet] Fri Jul  3 16:52:29 UTC 2020 [boinc pp jets 7000 250 - pythia8 8.240 fischerPP1 100000 16]

2020-07-03 13:00:26 (12024): VM state change detected. (old = 'Running', new = 'Paused')
2020-07-03 13:00:32 (12024): VM state change detected. (old = 'Paused', new = 'Running')
2020-07-03 13:48:08 (12024): VM state change detected. (old = 'Running', new = 'Paused')
2020-07-03 13:48:18 (12024): VM state change detected. (old = 'Paused', new = 'Running')
2020-07-03 14:18:29 (12024): VM state change detected. (old = 'Running', new = 'Paused')
2020-07-03 14:18:39 (12024): VM state change detected. (old = 'Paused', new = 'Running')
2020-07-03 14:24:22 (12024): VM state change detected. (old = 'Running', new = 'Paused')
2020-07-03 14:24:32 (12024): VM state change detected. (old = 'Paused', new = 'Running')
2020-07-03 14:24:49 (12024): Status Report: Job Duration: '864000.000000'
2020-07-03 14:24:49 (12024): Status Report: Elapsed Time: '6000.000000'
2020-07-03 14:24:49 (12024): Status Report: CPU Time: '3045.078125'
2020-07-03 14:25:32 (12024): VM state change detected. (old = 'Running', new = 'Paused')
2020-07-03 14:25:42 (12024): VM state change detected. (old = 'Paused', new = 'Running')
2020-07-03 14:53:42 (12024): VM state change detected. (old = 'Running', new = 'Paused')
2020-07-03 14:53:52 (12024): VM state change detected. (old = 'Paused', new = 'Running')
2020-07-03 14:54:43 (12024): VM state change detected. (old = 'Running', new = 'Paused')
2020-07-03 14:54:53 (12024): VM state change detected. (old = 'Paused', new = 'Running')
2020-07-03 14:56:13 (12024): VM state change detected. (old = 'Running', new = 'Paused')
2020-07-03 14:56:23 (12024): VM state change detected. (old = 'Paused', new = 'Running')
2020-07-03 14:56:53 (12024): VM state change detected. (old = 'Running', new = 'Paused')
2020-07-03 14:57:03 (12024): VM state change detected. (old = 'Paused', new = 'Running')
2020-07-03 15:06:35 (12024): VM state change detected. (old = 'Running', new = 'Paused')
2020-07-03 15:06:45 (12024): VM state change detected. (old = 'Paused', new = 'Running')
2020-07-03 15:06:55 (12024): VM state change detected. (old = 'Running', new = 'Paused')
2020-07-03 15:07:05 (12024): VM state change detected. (old = 'Paused', new = 'Running')
2020-07-03 15:08:46 (12024): VM state change detected. (old = 'Running', new = 'Paused')
2020-07-03 15:08:56 (12024): VM state change detected. (old = 'Paused', new = 'Running')
2020-07-03 15:09:46 (12024): VM state change detected. (old = 'Running', new = 'Paused')
2020-07-03 15:09:56 (12024): VM state change detected. (old = 'Paused', new = 'Running')
2020-07-03 15:10:26 (12024): VM state change detected. (old = 'Running', new = 'Paused')
2020-07-03 15:10:36 (12024): VM state change detected. (old = 'Paused', new = 'Running')
2020-07-03 15:10:46 (12024): VM state change detected. (old = 'Running', new = 'Paused')
2020-07-03 15:10:56 (12024): VM state change detected. (old = 'Paused', new = 'Running')
2020-07-03 15:11:16 (12024): VM state change detected. (old = 'Running', new = 'Paused')
2020-07-03 15:11:26 (12024): VM state change detected. (old = 'Paused', new = 'Running')
2020-07-03 15:12:46 (12024): VM state change detected. (old = 'Running', new = 'Paused')
2020-07-03 15:12:56 (12024): VM state change detected. (old = 'Paused', new = 'Running')
2020-07-03 15:13:06 (12024): VM state change detected. (old = 'Running', new = 'Paused')
2020-07-03 15:13:16 (12024): VM state change detected. (old = 'Paused', new = 'Running')
2020-07-03 15:14:17 (12024): VM state change detected. (old = 'Running', new = 'Paused')
2020-07-03 15:14:28 (12024): VM state change detected. (old = 'Paused', new = 'Running')
2020-07-03 16:07:40 (12024): Status Report: Job Duration: '864000.000000'
2020-07-03 16:07:40 (12024): Status Report: Elapsed Time: '12000.000000'
2020-07-03 16:07:40 (12024): Status Report: CPU Time: '6656.781250'
2020-07-03 17:47:45 (12024): Status Report: Job Duration: '864000.000000'
2020-07-03 17:47:45 (12024): Status Report: Elapsed Time: '18000.000000'
2020-07-03 17:47:45 (12024): Status Report: CPU Time: '10262.125000'
2020-07-03 19:27:50 (12024): Status Report: Job Duration: '864000.000000'
2020-07-03 19:27:50 (12024): Status Report: Elapsed Time: '24000.000000'
2020-07-03 19:27:50 (12024): Status Report: CPU Time: '13867.890625'
2020-07-03 21:07:55 (12024): Status Report: Job Duration: '864000.000000'
2020-07-03 21:07:55 (12024): Status Report: Elapsed Time: '30000.000000'
2020-07-03 21:07:55 (12024): Status Report: CPU Time: '17473.828125'
2020-07-03 22:48:00 (12024): Status Report: Job Duration: '864000.000000'
2020-07-03 22:48:00 (12024): Status Report: Elapsed Time: '36000.000000'
2020-07-03 22:48:00 (12024): Status Report: CPU Time: '21079.218750'
2020-07-04 00:28:05 (12024): Status Report: Job Duration: '864000.000000'
2020-07-04 00:28:05 (12024): Status Report: Elapsed Time: '42000.000000'
2020-07-04 00:28:05 (12024): Status Report: CPU Time: '24684.609375'
2020-07-04 02:08:10 (12024): Status Report: Job Duration: '864000.000000'
2020-07-04 02:08:10 (12024): Status Report: Elapsed Time: '48000.000000'
2020-07-04 02:08:10 (12024): Status Report: CPU Time: '28289.718750'
2020-07-04 03:48:15 (12024): Status Report: Job Duration: '864000.000000'
2020-07-04 03:48:15 (12024): Status Report: Elapsed Time: '54000.000000'
2020-07-04 03:48:15 (12024): Status Report: CPU Time: '31894.703125'
2020-07-04 05:28:20 (12024): Status Report: Job Duration: '864000.000000'
2020-07-04 05:28:20 (12024): Status Report: Elapsed Time: '60000.000000'
2020-07-04 05:28:20 (12024): Status Report: CPU Time: '35499.796875'
2020-07-04 07:08:25 (12024): Status Report: Job Duration: '864000.000000'
2020-07-04 07:08:25 (12024): Status Report: Elapsed Time: '66000.145206'
2020-07-04 07:08:25 (12024): Status Report: CPU Time: '39106.062500'
2020-07-04 08:48:30 (12024): Status Report: Job Duration: '864000.000000'
2020-07-04 08:48:30 (12024): Status Report: Elapsed Time: '72000.145206'
2020-07-04 08:48:30 (12024): Status Report: CPU Time: '42711.000000'
2020-07-04 09:38:33 (12024): VM state change detected. (old = 'Running', new = 'Paused')
2020-07-04 09:38:43 (12024): VM state change detected. (old = 'Paused', new = 'Running')
2020-07-04 09:55:08 (12024): VM state change detected. (old = 'Running', new = 'Paused')
2020-07-04 09:55:18 (12024): VM state change detected. (old = 'Paused', new = 'Running')
2020-07-04 09:56:08 (12024): VM state change detected. (old = 'Running', new = 'Paused')
2020-07-04 09:56:18 (12024): VM state change detected. (old = 'Paused', new = 'Running')
2020-07-04 09:57:08 (12024): VM state change detected. (old = 'Running', new = 'Paused')
2020-07-04 09:57:18 (12024): VM state change detected. (old = 'Paused', new = 'Running')
2020-07-04 09:58:08 (12024): VM state change detected. (old = 'Running', new = 'Paused')
2020-07-04 09:58:18 (12024): VM state change detected. (old = 'Paused', new = 'Running')
2020-07-04 09:59:08 (12024): VM state change detected. (old = 'Running', new = 'Paused')
2020-07-04 09:59:18 (12024): VM state change detected. (old = 'Paused', new = 'Running')
2020-07-04 10:29:42 (12024): Status Report: Job Duration: '864000.000000'
2020-07-04 10:29:42 (12024): Status Report: Elapsed Time: '78000.145206'
2020-07-04 10:29:42 (12024): Status Report: CPU Time: '46322.375000'
2020-07-04 11:16:16 (12024): VM state change detected. (old = 'Running', new = 'Paused')
2020-07-04 11:16:26 (12024): VM state change detected. (old = 'Paused', new = 'Running')
2020-07-04 11:42:13 (12024): VM state change detected. (old = 'Running', new = 'Paused')
2020-07-04 11:42:23 (12024): VM state change detected. (old = 'Paused', new = 'Running')
2020-07-04 12:10:09 (12024): Status Report: Job Duration: '864000.000000'
2020-07-04 12:10:09 (12024): Status Report: Elapsed Time: '84000.145206'
2020-07-04 12:10:09 (12024): Status Report: CPU Time: '49927.484375'
2020-07-04 12:17:08 (12024): VM state change detected. (old = 'Running', new = 'Paused')
2020-07-04 12:17:19 (12024): VM state change detected. (old = 'Paused', new = 'Running')
2020-07-04 13:50:26 (12024): Status Report: Job Duration: '864000.000000'
2020-07-04 13:50:26 (12024): Status Report: Elapsed Time: '90000.145206'
2020-07-04 13:50:26 (12024): Status Report: CPU Time: '53532.484375'
2020-07-04 15:30:31 (12024): Status Report: Job Duration: '864000.000000'
2020-07-04 15:30:31 (12024): Status Report: Elapsed Time: '96000.145206'
2020-07-04 15:30:31 (12024): Status Report: CPU Time: '57137.406250'
2020-07-04 17:10:36 (12024): Status Report: Job Duration: '864000.000000'
2020-07-04 17:10:36 (12024): Status Report: Elapsed Time: '102000.145206'
2020-07-04 17:10:36 (12024): Status Report: CPU Time: '60742.531250'
2020-07-04 18:50:41 (12024): Status Report: Job Duration: '864000.000000'
2020-07-04 18:50:41 (12024): Status Report: Elapsed Time: '108000.145206'
2020-07-04 18:50:41 (12024): Status Report: CPU Time: '64347.343750'
2020-07-04 20:30:46 (12024): Status Report: Job Duration: '864000.000000'
2020-07-04 20:30:46 (12024): Status Report: Elapsed Time: '114000.145206'
2020-07-04 20:30:46 (12024): Status Report: CPU Time: '67952.453125'
2020-07-04 22:10:51 (12024): Status Report: Job Duration: '864000.000000'
2020-07-04 22:10:51 (12024): Status Report: Elapsed Time: '120000.145206'
2020-07-04 22:10:51 (12024): Status Report: CPU Time: '71557.437500'
2020-07-04 23:50:56 (12024): Status Report: Job Duration: '864000.000000'
2020-07-04 23:50:56 (12024): Status Report: Elapsed Time: '126000.145206'
2020-07-04 23:50:56 (12024): Status Report: CPU Time: '75162.375000'
2020-07-05 01:31:01 (12024): Status Report: Job Duration: '864000.000000'
2020-07-05 01:31:01 (12024): Status Report: Elapsed Time: '132000.145206'
2020-07-05 01:31:01 (12024): Status Report: CPU Time: '78766.671875'
2020-07-05 03:06:28 (12024): Guest Log: job: run exitcode=0

2020-07-05 03:06:28 (12024): Guest Log: job: diskusage=5988

2020-07-05 03:06:28 (12024): Guest Log: job: logsize=104 k

2020-07-05 03:06:28 (12024): Guest Log: job: times=

2020-07-05 03:06:28 (12024): Guest Log: 0m0.068s 0m0.093s

2020-07-05 03:06:28 (12024): Guest Log: 2205m25.549s 24m5.571s

2020-07-05 03:06:28 (12024): Guest Log: job: cpuusage=133771

2020-07-05 03:06:29 (12024): Guest Log: 02:03:12 CDT -05:00 2020-07-05: cranky: [INFO] Container 'runc' finished with status code 0.

2020-07-05 03:06:29 (12024): Guest Log: 02:03:12 CDT -05:00 2020-07-05: cranky: [INFO] Preparing output.

2020-07-05 03:06:31 (12024): Guest Log: [INFO] Job Finished

2020-07-05 03:06:31 (12024): Guest Log: [INFO] Shutting Down.

2020-07-05 03:06:31 (12024): VM Completion File Detected.
2020-07-05 03:06:31 (12024): VM Completion Message: Job Finished
.
2020-07-05 03:06:31 (12024): Powering off VM.
2020-07-05 03:11:32 (12024): VM did not power off when requested.
2020-07-05 03:11:32 (12024): VM was successfully terminated.
2020-07-05 03:11:32 (12024): Deregistering VM. (boinc_ea139dfdac523a57, slot#7)
2020-07-05 03:11:32 (12024): Removing network bandwidth throttle group from VM.
2020-07-05 03:11:32 (12024): Removing VM from VirtualBox.
03:11:38 (12024): called boinc_finish(0)

</stderr_txt>
]]>
LHC@home

Stderr output
