Task 2847458

Name	Theory_2279-799400-194_0
Workunit	1961125
Created	14 Dec 2019, 15:40:14 UTC
Sent	14 Dec 2019, 16:47:55 UTC
Report deadline	24 Dec 2019, 16:47:55 UTC
Received	22 Dec 2019, 14:32:33 UTC
Server state	Over
Outcome	Success
Client state	Done
Exit status	0 (0x00000000)
Computer ID	1844
Run time	15 hours 35 min 47 sec
CPU time	11 hours 37 min 39 sec
Validate state	Valid
Credit	471.76
Device peak FLOPS	3.63 GFLOPS
Application version	Theory Simulation v5.18 (vbox64_theory) windows_x86_64
Peak working set size	72.38 MB
Peak swap size	80.12 MB
Peak disk usage	1.01 GB
Stderr output

<core_client_version>7.14.2</core_client_version>
<![CDATA[
<stderr_txt>
2019-12-14 17:48:39 (6416): Detected: vboxwrapper 26197
2019-12-14 17:48:39 (6416): Detected: BOINC client v7.7
2019-12-14 17:48:40 (6416): Detected: VirtualBox VboxManage Interface (Version: 5.2.8)
2019-12-14 17:48:41 (6416): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2019-12-14 17:48:41 (6416): Successfully copied 'init_data.xml' to the shared directory.
2019-12-14 17:48:41 (6416): Successfully copied 'input' to the shared directory.
2019-12-14 17:48:42 (6416): Create VM. (boinc_7883131ab420012e, slot#2)
2019-12-14 17:48:43 (6416): Setting Memory Size for VM. (630MB)
2019-12-14 17:48:43 (6416): Setting CPU Count for VM. (1)
2019-12-14 17:48:43 (6416): Setting Chipset Options for VM.
2019-12-14 17:48:44 (6416): Setting Boot Options for VM.
2019-12-14 17:48:44 (6416): Setting Network Configuration for NAT.
2019-12-14 17:48:44 (6416): Enabling VM Network Access.
2019-12-14 17:48:44 (6416): Disabling USB Support for VM.
2019-12-14 17:48:45 (6416): Disabling COM Port Support for VM.
2019-12-14 17:48:45 (6416): Disabling LPT Port Support for VM.
2019-12-14 17:48:45 (6416): Disabling Audio Support for VM.
2019-12-14 17:48:45 (6416): Disabling Clipboard Support for VM.
2019-12-14 17:48:46 (6416): Disabling Drag and Drop Support for VM.
2019-12-14 17:48:46 (6416): Adding storage controller(s) to VM.
2019-12-14 17:48:46 (6416): Adding virtual disk drive to VM. (vm_image.vdi)
2019-12-14 17:48:47 (6416): Adding VirtualBox Guest Additions to VM.
2019-12-14 17:48:47 (6416): Adding network bandwidth throttle group to VM. (Defaulting to 1024GB)
2019-12-14 17:48:47 (6416): forwarding host port 52279 to guest port 80
2019-12-14 17:48:48 (6416): Enabling remote desktop for VM.
2019-12-14 17:48:48 (6416): Required extension pack not installed, remote desktop not enabled.
2019-12-14 17:48:48 (6416): Enabling shared directory for VM.
2019-12-14 17:48:48 (6416): Starting VM using VBoxManage interface. (boinc_7883131ab420012e, slot#2)
2019-12-14 17:48:53 (6416): Successfully started VM. (PID = '10624')
2019-12-14 17:48:53 (6416): Reporting VM Process ID to BOINC.
2019-12-14 17:48:53 (6416): Guest Log: BIOS: VirtualBox 5.2.8

2019-12-14 17:48:53 (6416): Guest Log: CPUID EDX: 0x078bfbff

2019-12-14 17:48:53 (6416): Guest Log: BIOS: ata0-0: PCHS=16383/16/63 LCHS=1024/255/63

2019-12-14 17:48:53 (6416): VM state change detected. (old = 'PoweredOff', new = 'Running')
2019-12-14 17:48:53 (6416): Detected: Web Application Enabled (http://localhost:52279)
2019-12-14 17:48:53 (6416): Preference change detected
2019-12-14 17:48:53 (6416): Setting CPU throttle for VM. (70%)
2019-12-14 17:48:53 (6416): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2019-12-14 17:48:55 (6416): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032

2019-12-14 17:48:55 (6416): Guest Log: BIOS: Booting from Hard Disk...

2019-12-14 17:48:57 (6416): Guest Log: BIOS: KBD: unsupported int 16h function 03

2019-12-14 17:48:57 (6416): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 

2019-12-14 17:49:22 (6416): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds

2019-12-14 17:49:22 (6416): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000)

2019-12-14 17:49:27 (6416): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log

2019-12-14 17:49:27 (6416): Guest Log: 00:00:00.000115 main     Log opened 2019-12-14T16:49:30.250912000Z

2019-12-14 17:49:27 (6416): Guest Log: 00:00:00.000299 main     OS Product: Linux

2019-12-14 17:49:27 (6416): Guest Log: 00:00:00.000339 main     OS Release: 4.14.76-13.cernvm.x86_64

2019-12-14 17:49:27 (6416): Guest Log: 00:00:00.000370 main     OS Version: #1 SMP Tue Oct 16 18:26:15 CEST 2018

2019-12-14 17:49:27 (6416): Guest Log: 00:00:00.000400 main     Executable: /usr/sbin/VBoxService

2019-12-14 17:49:27 (6416): Guest Log: 00:00:00.000401 main     Process ID: 3196

2019-12-14 17:49:27 (6416): Guest Log: 00:00:00.000401 main     Package type: LINUX_64BITS_GENERIC

2019-12-14 17:49:27 (6416): Guest Log: 00:00:00.005831 main     5.2.6 r120293 started. Verbose level = 0

2019-12-14 17:49:43 (6416): Guest Log: 17:49:46 CET +01:00 2019-12-14: cranky: [INFO] Detected Theory App

2019-12-14 17:49:43 (6416): Guest Log: 17:49:46 CET +01:00 2019-12-14: cranky: [INFO] Checking CVMFS.

2019-12-14 17:49:50 (6416): Guest Log: 17:49:53 CET +01:00 2019-12-14: cranky: [INFO] Checking runc.

2019-12-14 17:49:50 (6416): Guest Log: 17:49:53 CET +01:00 2019-12-14: cranky: [INFO] Creating the filesystem.

2019-12-14 17:49:50 (6416): Guest Log: 17:49:53 CET +01:00 2019-12-14: cranky: [INFO] Using /cvmfs/cernvm-prod.cern.ch/cvm3

2019-12-14 17:49:50 (6416): Guest Log: 17:49:53 CET +01:00 2019-12-14: cranky: [INFO] Updating config.json.

2019-12-14 17:49:50 (6416): Guest Log: 17:49:53 CET +01:00 2019-12-14: cranky: [INFO] Running Container 'runc'.

2019-12-14 17:49:51 (6416): Guest Log: job: stdoutf=

2019-12-14 17:49:51 (6416): Guest Log: job: stderrf=

2019-12-14 17:49:51 (6416): Guest Log: job: htmld=/shared/html/job

2019-12-14 17:49:53 (6416): Guest Log: 17:49:55 CET +01:00 2019-12-14: cranky: [INFO] ===> [runRivet] Sat Dec 14 16:49:53 UTC 2019 [boinc pp w1j 7000 100 - pythia8 8.186 tune-4c 100000 194]

2019-12-14 17:56:55 (6416): VM state change detected. (old = 'Running', new = 'Paused')
2019-12-14 17:57:26 (6416): VM state change detected. (old = 'Paused', new = 'Running')
2019-12-14 19:30:13 (6416): Status Report: Job Duration: '864000.000000'
2019-12-14 19:30:13 (6416): Status Report: Elapsed Time: '6000.000000'
2019-12-14 19:30:13 (6416): Status Report: CPU Time: '4539.140625'
2019-12-14 19:40:31 (6416): VM state change detected. (old = 'Running', new = 'Paused')
2019-12-14 19:40:42 (6416): VM state change detected. (old = 'Paused', new = 'Running')
2019-12-14 20:26:42 (6416): VM state change detected. (old = 'Running', new = 'Paused')
2019-12-14 20:27:27 (6416): VM state change detected. (old = 'Paused', new = 'Running')
2019-12-14 20:37:18 (6416): VM state change detected. (old = 'Running', new = 'Paused')
2019-12-14 20:37:59 (6416): VM state change detected. (old = 'Paused', new = 'Running')
2019-12-14 20:38:06 (6416): VM state change detected. (old = 'Running', new = 'Paused')
2019-12-14 20:38:16 (6416): VM state change detected. (old = 'Paused', new = 'Running')
2019-12-14 20:59:11 (6416): VM state change detected. (old = 'Running', new = 'Paused')
2019-12-14 20:59:42 (6416): VM state change detected. (old = 'Paused', new = 'Running')
2019-12-14 21:01:13 (6416): VM state change detected. (old = 'Running', new = 'Paused')
2019-12-14 21:01:54 (6416): VM state change detected. (old = 'Paused', new = 'Running')
2019-12-14 21:06:27 (6416): VM state change detected. (old = 'Running', new = 'Paused')
2019-12-14 21:06:53 (6416): Stopping VM.
2019-12-14 21:06:53 (6416): Error in stop VM for VM: -108
Command:
VBoxManage -q controlvm "boinc_7883131ab420012e" savestate
Output:

2019-12-14 21:06:53 (6416): VM did not stop when requested.
2019-12-14 21:06:53 (6416): VM was successfully terminated.
2019-12-15 18:01:16 (3508): Detected: vboxwrapper 26197
2019-12-15 18:01:16 (3508): Detected: BOINC client v7.7
2019-12-15 18:01:17 (3508): Detected: VirtualBox VboxManage Interface (Version: 5.2.8)
2019-12-15 18:01:17 (3508): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2019-12-15 18:01:18 (3508): Starting VM using VBoxManage interface. (boinc_7883131ab420012e, slot#2)
2019-12-15 18:01:22 (3508): Successfully started VM. (PID = '5680')
2019-12-15 18:01:22 (3508): Reporting VM Process ID to BOINC.
2019-12-15 18:01:22 (3508): Guest Log: BIOS: VirtualBox 5.2.8

2019-12-15 18:01:22 (3508): Guest Log: CPUID EDX: 0x078bfbff

2019-12-15 18:01:22 (3508): Guest Log: BIOS: ata0-0: PCHS=16383/16/63 LCHS=1024/255/63

2019-12-15 18:01:22 (3508): VM state change detected. (old = 'PoweredOff', new = 'Running')
2019-12-15 18:01:22 (3508): Detected: Web Application Enabled (http://localhost:52279)
2019-12-15 18:01:22 (3508): Status Report: Job Duration: '864000.000000'
2019-12-15 18:01:22 (3508): Status Report: Elapsed Time: '11542.000000'
2019-12-15 18:01:22 (3508): Status Report: CPU Time: '8740.656250'
2019-12-15 18:01:22 (3508): Preference change detected
2019-12-15 18:01:22 (3508): Setting CPU throttle for VM. (70%)
2019-12-15 18:01:23 (3508): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2019-12-15 18:01:25 (3508): VM state change detected. (old = 'Running', new = 'Paused')
2019-12-15 18:03:54 (3508): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032

2019-12-15 18:03:54 (3508): Guest Log: BIOS: Booting from Hard Disk...

2019-12-15 18:03:54 (3508): VM state change detected. (old = 'Paused', new = 'Running')
2019-12-15 18:03:56 (3508): Guest Log: BIOS: KBD: unsupported int 16h function 03

2019-12-15 18:03:56 (3508): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 

2019-12-15 18:03:57 (3508): VM state change detected. (old = 'Running', new = 'Paused')
2019-12-15 18:13:14 (3508): Stopping VM.
2019-12-15 18:13:14 (3508): Error in stop VM for VM: -108
Command:
VBoxManage -q controlvm "boinc_7883131ab420012e" savestate
Output:

2019-12-15 18:13:14 (3508): VM did not stop when requested.
2019-12-15 18:13:14 (3508): VM was successfully terminated.
2019-12-16 14:30:31 (11956): Detected: vboxwrapper 26197
2019-12-16 14:30:31 (11956): Detected: BOINC client v7.7
2019-12-16 14:30:37 (11956): Detected: VirtualBox VboxManage Interface (Version: 5.2.8)
2019-12-16 14:30:37 (11956): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2019-12-16 14:30:38 (11956): Register VM. (boinc_7883131ab420012e, slot#2)
2019-12-16 14:30:38 (11956): Starting VM using VBoxManage interface. (boinc_7883131ab420012e, slot#2)
2019-12-16 14:30:49 (11956): Successfully started VM. (PID = '252')
2019-12-16 14:30:49 (11956): Reporting VM Process ID to BOINC.
2019-12-16 14:30:49 (11956): Guest Log: BIOS: VirtualBox 5.2.8

2019-12-16 14:30:49 (11956): Guest Log: CPUID EDX: 0x078bfbff

2019-12-16 14:30:49 (11956): Guest Log: BIOS: ata0-0: PCHS=16383/16/63 LCHS=1024/255/63

2019-12-16 14:30:49 (11956): VM state change detected. (old = 'PoweredOff', new = 'Running')
2019-12-16 14:30:49 (11956): Detected: Web Application Enabled (http://localhost:52279)
2019-12-16 14:30:50 (11956): VM state change detected. (old = 'Running', new = 'Paused')
2019-12-16 14:40:13 (11956): Status Report: Job Duration: '864000.000000'
2019-12-16 14:40:13 (11956): Status Report: Elapsed Time: '11544.000000'
2019-12-16 14:40:13 (11956): Status Report: CPU Time: '8740.656250'
2019-12-16 14:40:13 (11956): Preference change detected
2019-12-16 14:40:13 (11956): Setting CPU throttle for VM. (70%)
2019-12-16 14:40:13 (11956): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2019-12-16 14:40:14 (11956): VM state change detected. (old = 'Paused', new = 'Running')
2019-12-16 14:40:15 (11956): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032

2019-12-16 14:40:15 (11956): Guest Log: BIOS: Booting from Hard Disk...

2019-12-16 14:40:18 (11956): Guest Log: BIOS: KBD: unsupported int 16h function 03

2019-12-16 14:40:18 (11956): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 

2019-12-16 14:40:29 (11956): VM state change detected. (old = 'Running', new = 'Paused')
2019-12-16 14:42:04 (11956): VM state change detected. (old = 'Paused', new = 'Running')
2019-12-16 14:42:20 (11956): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds

2019-12-16 14:42:20 (11956): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000)

2019-12-16 14:42:25 (11956): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log

2019-12-16 14:42:25 (11956): Guest Log: 00:00:00.000132 main     Log opened 2019-12-16T13:40:56.721636000Z

2019-12-16 14:42:25 (11956): Guest Log: 00:00:00.005465 main     OS Product: Linux

2019-12-16 14:42:25 (11956): Guest Log: 00:00:00.005861 main     OS Release: 4.14.76-13.cernvm.x86_64

2019-12-16 14:42:25 (11956): Guest Log: 00:00:00.027783 main     OS Version: #1 SMP Tue Oct 16 18:26:15 CEST 2018

2019-12-16 14:42:25 (11956): Guest Log: 00:00:00.028150 main     Executable: /usr/sbin/VBoxService

2019-12-16 14:42:25 (11956): Guest Log: 00:00:00.028152 main     Process ID: 3199

2019-12-16 14:42:25 (11956): Guest Log: 00:00:00.028153 main     Package type: LINUX_64BITS_GENERIC

2019-12-16 14:42:25 (11956): Guest Log: 00:00:00.035804 main     5.2.6 r120293 started. Verbose level = 0

2019-12-16 14:42:42 (11956): Guest Log: 14:41:13 CET +01:00 2019-12-16: cranky: [INFO] Detected Theory App

2019-12-16 14:42:42 (11956): Guest Log: 14:41:13 CET +01:00 2019-12-16: cranky: [INFO] Checking CVMFS.

2019-12-16 14:42:46 (11956): VM state change detected. (old = 'Running', new = 'Paused')
2019-12-16 14:44:44 (11956): VM state change detected. (old = 'Paused', new = 'Running')
2019-12-16 14:44:47 (11956): Guest Log: 14:41:21 CET +01:00 2019-12-16: cranky: [INFO] Checking runc.

2019-12-16 14:44:47 (11956): Guest Log: 14:41:21 CET +01:00 2019-12-16: cranky: [INFO] Creating the filesystem.

2019-12-16 14:44:47 (11956): Guest Log: 14:41:21 CET +01:00 2019-12-16: cranky: [INFO] Using /cvmfs/cernvm-prod.cern.ch/cvm3

2019-12-16 14:44:47 (11956): Guest Log: 14:41:21 CET +01:00 2019-12-16: cranky: [INFO] Updating config.json.

2019-12-16 14:44:47 (11956): Guest Log: 14:41:22 CET +01:00 2019-12-16: cranky: [INFO] Running Container 'runc'.

2019-12-16 14:44:48 (11956): Guest Log: job: stdoutf=

2019-12-16 14:44:48 (11956): Guest Log: job: stderrf=

2019-12-16 14:44:48 (11956): Guest Log: job: htmld=/shared/html/job

2019-12-16 14:44:50 (11956): Guest Log: 14:41:24 CET +01:00 2019-12-16: cranky: [INFO] ===> [runRivet] Mon Dec 16 13:41:22 UTC 2019 [boinc pp w1j 7000 100 - pythia8 8.186 tune-4c 100000 194]

2019-12-16 14:44:53 (11956): VM state change detected. (old = 'Running', new = 'Paused')
2019-12-16 14:46:58 (11956): VM state change detected. (old = 'Paused', new = 'Running')
2019-12-16 14:47:34 (11956): VM state change detected. (old = 'Running', new = 'Paused')
2019-12-16 14:48:31 (11956): VM state change detected. (old = 'Paused', new = 'Running')
2019-12-16 14:49:25 (11956): VM state change detected. (old = 'Running', new = 'Paused')
2019-12-16 14:54:43 (11956): VM state change detected. (old = 'Paused', new = 'Running')
2019-12-16 14:55:00 (11956): VM state change detected. (old = 'Running', new = 'Paused')
2019-12-16 14:56:38 (11956): VM state change detected. (old = 'Paused', new = 'Running')
2019-12-16 14:59:39 (11956): VM state change detected. (old = 'Running', new = 'Paused')
2019-12-16 15:00:10 (11956): VM state change detected. (old = 'Paused', new = 'Running')
2019-12-16 15:04:28 (11956): VM state change detected. (old = 'Running', new = 'Paused')
2019-12-16 15:04:38 (11956): VM state change detected. (old = 'Paused', new = 'Running')
2019-12-16 15:04:58 (11956): VM state change detected. (old = 'Running', new = 'Paused')
2019-12-16 15:05:19 (11956): VM state change detected. (old = 'Paused', new = 'Running')
2019-12-16 15:18:34 (11956): VM state change detected. (old = 'Running', new = 'Paused')
2019-12-16 15:19:05 (11956): VM state change detected. (old = 'Paused', new = 'Running')
2019-12-16 15:51:13 (11956): VM state change detected. (old = 'Running', new = 'Paused')
2019-12-16 15:51:23 (11956): VM state change detected. (old = 'Paused', new = 'Running')
2019-12-16 16:36:28 (11956): Status Report: Job Duration: '864000.000000'
2019-12-16 16:36:28 (11956): Status Report: Elapsed Time: '17544.104832'
2019-12-16 16:36:28 (11956): Status Report: CPU Time: '13282.781250'
2019-12-16 17:19:47 (11956): VM state change detected. (old = 'Running', new = 'Paused')
2019-12-16 17:20:01 (11956): Stopping VM.
2019-12-16 17:20:01 (11956): Error in stop VM for VM: -108
Command:
VBoxManage -q controlvm "boinc_7883131ab420012e" savestate
Output:

2019-12-16 17:20:01 (11956): VM did not stop when requested.
2019-12-16 17:20:01 (11956): VM was successfully terminated.
2019-12-18 07:40:20 (7120): Detected: vboxwrapper 26197
2019-12-18 07:40:20 (7120): Detected: BOINC client v7.7
2019-12-18 07:40:24 (7120): Detected: VirtualBox VboxManage Interface (Version: 5.2.8)
2019-12-18 07:40:24 (7120): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2019-12-18 07:40:25 (7120): Starting VM using VBoxManage interface. (boinc_7883131ab420012e, slot#2)
2019-12-18 07:40:31 (7120): Successfully started VM. (PID = '1420')
2019-12-18 07:40:31 (7120): Reporting VM Process ID to BOINC.
2019-12-18 07:40:31 (7120): Guest Log: BIOS: VirtualBox 5.2.8

2019-12-18 07:40:31 (7120): Guest Log: CPUID EDX: 0x078bfbff

2019-12-18 07:40:31 (7120): Guest Log: BIOS: ata0-0: PCHS=16383/16/63 LCHS=1024/255/63

2019-12-18 07:40:31 (7120): VM state change detected. (old = 'PoweredOff', new = 'Running')
2019-12-18 07:40:31 (7120): Detected: Web Application Enabled (http://localhost:52279)
2019-12-18 07:40:31 (7120): Status Report: Job Duration: '864000.000000'
2019-12-18 07:40:31 (7120): Status Report: Elapsed Time: '20115.104832'
2019-12-18 07:40:31 (7120): Status Report: CPU Time: '15230.437500'
2019-12-18 07:40:31 (7120): Preference change detected
2019-12-18 07:40:31 (7120): Setting CPU throttle for VM. (70%)
2019-12-18 07:40:32 (7120): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2019-12-18 07:40:33 (7120): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032

2019-12-18 07:40:33 (7120): Guest Log: BIOS: Booting from Hard Disk...

2019-12-18 07:40:36 (7120): Guest Log: BIOS: KBD: unsupported int 16h function 03

2019-12-18 07:40:36 (7120): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 

2019-12-18 07:41:02 (7120): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds

2019-12-18 07:41:02 (7120): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000)

2019-12-18 07:41:06 (7120): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log

2019-12-18 07:41:06 (7120): Guest Log: 00:00:00.000097 main     Log opened 2019-12-18T06:41:13.561265000Z

2019-12-18 07:41:06 (7120): Guest Log: 00:00:00.000216 main     OS Product: Linux

2019-12-18 07:41:06 (7120): Guest Log: 00:00:00.000269 main     OS Release: 4.14.76-13.cernvm.x86_64

2019-12-18 07:41:06 (7120): Guest Log: 00:00:00.000303 main     OS Version: #1 SMP Tue Oct 16 18:26:15 CEST 2018

2019-12-18 07:41:06 (7120): Guest Log: 00:00:00.000334 main     Executable: /usr/sbin/VBoxService

2019-12-18 07:41:06 (7120): Guest Log: 00:00:00.000335 main     Process ID: 3198

2019-12-18 07:41:06 (7120): Guest Log: 00:00:00.000336 main     Package type: LINUX_64BITS_GENERIC

2019-12-18 07:41:06 (7120): Guest Log: 00:00:00.043818 main     5.2.6 r120293 started. Verbose level = 0

2019-12-18 07:41:16 (7120): VM state change detected. (old = 'Running', new = 'Paused')
2019-12-18 07:44:02 (7120): Stopping VM.
2019-12-18 07:44:02 (7120): Error in stop VM for VM: -108
Command:
VBoxManage -q controlvm "boinc_7883131ab420012e" savestate
Output:

2019-12-18 07:44:02 (7120): VM did not stop when requested.
2019-12-18 07:44:02 (7120): VM was successfully terminated.
2019-12-18 16:45:58 (11484): Detected: vboxwrapper 26197
2019-12-18 16:45:58 (11484): Detected: BOINC client v7.7
2019-12-18 16:45:59 (11484): Detected: VirtualBox VboxManage Interface (Version: 5.2.8)
2019-12-18 16:45:59 (11484): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2019-12-18 16:46:00 (11484): Starting VM using VBoxManage interface. (boinc_7883131ab420012e, slot#2)
2019-12-18 16:46:05 (11484): Successfully started VM. (PID = '11248')
2019-12-18 16:46:05 (11484): Reporting VM Process ID to BOINC.
2019-12-18 16:46:05 (11484): Guest Log: BIOS: VirtualBox 5.2.8

2019-12-18 16:46:05 (11484): Guest Log: CPUID EDX: 0x078bfbff

2019-12-18 16:46:05 (11484): Guest Log: BIOS: ata0-0: PCHS=16383/16/63 LCHS=1024/255/63

2019-12-18 16:46:05 (11484): VM state change detected. (old = 'PoweredOff', new = 'Running')
2019-12-18 16:46:05 (11484): Detected: Web Application Enabled (http://localhost:52279)
2019-12-18 16:46:05 (11484): Status Report: Job Duration: '864000.000000'
2019-12-18 16:46:05 (11484): Status Report: Elapsed Time: '20157.104832'
2019-12-18 16:46:05 (11484): Status Report: CPU Time: '15245.937500'
2019-12-18 16:46:05 (11484): Preference change detected
2019-12-18 16:46:05 (11484): Setting CPU throttle for VM. (70%)
2019-12-18 16:46:05 (11484): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2019-12-18 16:46:07 (11484): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032

2019-12-18 16:46:07 (11484): Guest Log: BIOS: Booting from Hard Disk...

2019-12-18 16:46:09 (11484): Guest Log: BIOS: KBD: unsupported int 16h function 03

2019-12-18 16:46:09 (11484): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 

2019-12-18 16:46:19 (11484): VM state change detected. (old = 'Running', new = 'Paused')
2019-12-18 16:49:40 (11484): Stopping VM.
2019-12-18 16:49:41 (11484): Error in stop VM for VM: -108
Command:
VBoxManage -q controlvm "boinc_7883131ab420012e" savestate
Output:

2019-12-18 16:49:41 (11484): VM did not stop when requested.
2019-12-18 16:49:41 (11484): VM was successfully terminated.
2019-12-19 08:02:25 (8796): Detected: vboxwrapper 26197
2019-12-19 08:02:25 (8796): Detected: BOINC client v7.7
2019-12-19 08:02:26 (8796): Detected: VirtualBox VboxManage Interface (Version: 5.2.8)
2019-12-19 08:02:26 (8796): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2019-12-19 08:02:27 (8796): Starting VM using VBoxManage interface. (boinc_7883131ab420012e, slot#2)
2019-12-19 08:02:32 (8796): Successfully started VM. (PID = '17504')
2019-12-19 08:02:32 (8796): Reporting VM Process ID to BOINC.
2019-12-19 08:02:32 (8796): Guest Log: BIOS: VirtualBox 5.2.8

2019-12-19 08:02:32 (8796): Guest Log: CPUID EDX: 0x078bfbff

2019-12-19 08:02:32 (8796): Guest Log: BIOS: ata0-0: PCHS=16383/16/63 LCHS=1024/255/63

2019-12-19 08:02:32 (8796): VM state change detected. (old = 'PoweredOff', new = 'Running')
2019-12-19 08:02:32 (8796): Detected: Web Application Enabled (http://localhost:52279)
2019-12-19 08:02:32 (8796): Status Report: Job Duration: '864000.000000'
2019-12-19 08:02:32 (8796): Status Report: Elapsed Time: '20169.104832'
2019-12-19 08:02:32 (8796): Status Report: CPU Time: '15252.156250'
2019-12-19 08:02:32 (8796): Preference change detected
2019-12-19 08:02:32 (8796): Setting CPU throttle for VM. (70%)
2019-12-19 08:02:33 (8796): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2019-12-19 08:02:34 (8796): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032

2019-12-19 08:02:34 (8796): Guest Log: BIOS: Booting from Hard Disk...

2019-12-19 08:02:37 (8796): Guest Log: BIOS: KBD: unsupported int 16h function 03

2019-12-19 08:02:37 (8796): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 

2019-12-19 08:03:07 (8796): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds

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

2019-12-19 08:03:10 (8796): VM state change detected. (old = 'Running', new = 'Paused')
2019-12-19 08:03:42 (8796): VM state change detected. (old = 'Paused', new = 'Running')
2019-12-19 08:03:43 (8796): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log

2019-12-19 08:03:43 (8796): Guest Log: 00:00:00.000088 main     Log opened 2019-12-19T07:03:12.147542000Z

2019-12-19 08:03:43 (8796): Guest Log: 00:00:00.000212 main     OS Product: Linux

2019-12-19 08:03:43 (8796): Guest Log: 00:00:00.000250 main     OS Release: 4.14.76-13.cernvm.x86_64

2019-12-19 08:03:43 (8796): Guest Log: 00:00:00.000282 main     OS Version: #1 SMP Tue Oct 16 18:26:15 CEST 2018

2019-12-19 08:03:43 (8796): Guest Log: 00:00:00.000322 main     Executable: /usr/sbin/VBoxService

2019-12-19 08:03:43 (8796): Guest Log: 00:00:00.000323 main     Process ID: 3204

2019-12-19 08:03:43 (8796): Guest Log: 00:00:00.000324 main     Package type: LINUX_64BITS_GENERIC

2019-12-19 08:03:43 (8796): Guest Log: 00:00:00.004776 main     5.2.6 r120293 started. Verbose level = 0

2019-12-19 08:04:00 (8796): Guest Log: 08:03:27 CET +01:00 2019-12-19: cranky: [INFO] Detected Theory App

2019-12-19 08:04:00 (8796): Guest Log: 08:03:27 CET +01:00 2019-12-19: cranky: [INFO] Checking CVMFS.

2019-12-19 08:04:06 (8796): Guest Log: 08:03:35 CET +01:00 2019-12-19: cranky: [INFO] Checking runc.

2019-12-19 08:04:06 (8796): Guest Log: 08:03:35 CET +01:00 2019-12-19: cranky: [INFO] Creating the filesystem.

2019-12-19 08:04:06 (8796): Guest Log: 08:03:35 CET +01:00 2019-12-19: cranky: [INFO] Using /cvmfs/cernvm-prod.cern.ch/cvm3

2019-12-19 08:04:06 (8796): Guest Log: 08:03:35 CET +01:00 2019-12-19: cranky: [INFO] Updating config.json.

2019-12-19 08:04:07 (8796): Guest Log: 08:03:35 CET +01:00 2019-12-19: cranky: [INFO] Running Container 'runc'.

2019-12-19 08:04:07 (8796): Guest Log: job: stdoutf=

2019-12-19 08:04:07 (8796): Guest Log: job: stderrf=

2019-12-19 08:04:07 (8796): Guest Log: job: htmld=/shared/html/job

2019-12-19 08:04:09 (8796): Guest Log: 08:03:37 CET +01:00 2019-12-19: cranky: [INFO] ===> [runRivet] Thu Dec 19 07:03:36 UTC 2019 [boinc pp w1j 7000 100 - pythia8 8.186 tune-4c 100000 194]

2019-12-19 08:05:22 (8796): VM state change detected. (old = 'Running', new = 'Paused')
2019-12-19 08:05:53 (8796): VM state change detected. (old = 'Paused', new = 'Running')
2019-12-19 08:06:00 (8796): VM state change detected. (old = 'Running', new = 'Paused')
2019-12-19 08:06:20 (8796): VM state change detected. (old = 'Paused', new = 'Running')
2019-12-19 08:10:10 (8796): VM state change detected. (old = 'Running', new = 'Paused')
2019-12-19 08:10:20 (8796): VM state change detected. (old = 'Paused', new = 'Running')
2019-12-19 08:14:35 (8796): VM state change detected. (old = 'Running', new = 'Paused')
2019-12-19 08:15:06 (8796): VM state change detected. (old = 'Paused', new = 'Running')
2019-12-19 08:18:34 (8796): VM state change detected. (old = 'Running', new = 'Paused')
2019-12-19 08:21:03 (8796): VM state change detected. (old = 'Paused', new = 'Running')
2019-12-19 08:24:35 (8796): VM state change detected. (old = 'Running', new = 'Paused')
2019-12-19 08:25:06 (8796): VM state change detected. (old = 'Paused', new = 'Running')
2019-12-19 08:26:03 (8796): VM state change detected. (old = 'Running', new = 'Paused')
2019-12-19 08:26:54 (8796): VM state change detected. (old = 'Paused', new = 'Running')
2019-12-19 08:26:58 (8796): VM state change detected. (old = 'Running', new = 'Paused')
2019-12-19 08:28:26 (8796): VM state change detected. (old = 'Paused', new = 'Running')
2019-12-19 08:28:30 (8796): VM state change detected. (old = 'Running', new = 'Paused')
2019-12-19 08:31:32 (8796): VM state change detected. (old = 'Paused', new = 'Running')
2019-12-19 08:31:34 (8796): VM state change detected. (old = 'Running', new = 'Paused')
2019-12-19 08:32:05 (8796): VM state change detected. (old = 'Paused', new = 'Running')
2019-12-19 08:35:12 (8796): VM state change detected. (old = 'Running', new = 'Paused')
2019-12-19 08:35:43 (8796): VM state change detected. (old = 'Paused', new = 'Running')
2019-12-19 08:39:07 (8796): VM state change detected. (old = 'Running', new = 'Paused')
2019-12-19 08:51:28 (8796): Stopping VM.
2019-12-19 08:51:28 (8796): Error in stop VM for VM: -108
Command:
VBoxManage -q controlvm "boinc_7883131ab420012e" savestate
Output:

2019-12-19 08:51:28 (8796): VM did not stop when requested.
2019-12-19 08:51:28 (8796): VM was successfully terminated.
2019-12-21 08:22:48 (14424): Detected: vboxwrapper 26197
2019-12-21 08:22:48 (14424): Detected: BOINC client v7.7
2019-12-21 08:22:49 (14424): Detected: VirtualBox VboxManage Interface (Version: 5.2.8)
2019-12-21 08:22:49 (14424): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2019-12-21 08:22:49 (14424): Starting VM using VBoxManage interface. (boinc_7883131ab420012e, slot#2)
2019-12-21 08:22:54 (14424): Successfully started VM. (PID = '984')
2019-12-21 08:22:54 (14424): Reporting VM Process ID to BOINC.
2019-12-21 08:22:54 (14424): Guest Log: BIOS: VirtualBox 5.2.8

2019-12-21 08:22:54 (14424): Guest Log: CPUID EDX: 0x078bfbff

2019-12-21 08:22:54 (14424): Guest Log: BIOS: ata0-0: PCHS=16383/16/63 LCHS=1024/255/63

2019-12-21 08:22:54 (14424): VM state change detected. (old = 'PoweredOff', new = 'Running')
2019-12-21 08:22:54 (14424): Detected: Web Application Enabled (http://localhost:52279)
2019-12-21 08:22:54 (14424): Status Report: Job Duration: '864000.000000'
2019-12-21 08:22:54 (14424): Status Report: Elapsed Time: '21602.714653'
2019-12-21 08:22:54 (14424): Status Report: CPU Time: '16363.125000'
2019-12-21 08:22:54 (14424): Preference change detected
2019-12-21 08:22:54 (14424): Setting CPU throttle for VM. (70%)
2019-12-21 08:22:54 (14424): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2019-12-21 08:22:56 (14424): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032

2019-12-21 08:22:56 (14424): Guest Log: BIOS: Booting from Hard Disk...

2019-12-21 08:22:59 (14424): Guest Log: BIOS: KBD: unsupported int 16h function 03

2019-12-21 08:22:59 (14424): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 

2019-12-21 08:23:25 (14424): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds

2019-12-21 08:23:25 (14424): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000)

2019-12-21 08:23:28 (14424): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log

2019-12-21 08:23:28 (14424): Guest Log: 00:00:00.000133 main     Log opened 2019-12-21T07:23:34.814995000Z

2019-12-21 08:23:28 (14424): Guest Log: 00:00:00.000252 main     OS Product: Linux

2019-12-21 08:23:28 (14424): Guest Log: 00:00:00.000304 main     OS Release: 4.14.76-13.cernvm.x86_64

2019-12-21 08:23:28 (14424): Guest Log: 00:00:00.000339 main     OS Version: #1 SMP Tue Oct 16 18:26:15 CEST 2018

2019-12-21 08:23:28 (14424): Guest Log: 00:00:00.000371 main     Executable: /usr/sbin/VBoxService

2019-12-21 08:23:28 (14424): Guest Log: 00:00:00.000372 main     Process ID: 3193

2019-12-21 08:23:28 (14424): Guest Log: 00:00:00.000372 main     Package type: LINUX_64BITS_GENERIC

2019-12-21 08:23:28 (14424): Guest Log: 00:00:00.005833 main     5.2.6 r120293 started. Verbose level = 0

2019-12-21 08:23:48 (14424): Guest Log: 08:23:54 CET +01:00 2019-12-21: cranky: [INFO] Detected Theory App

2019-12-21 08:23:48 (14424): Guest Log: 08:23:54 CET +01:00 2019-12-21: cranky: [INFO] Checking CVMFS.

2019-12-21 08:23:54 (14424): Guest Log: 08:24:01 CET +01:00 2019-12-21: cranky: [INFO] Checking runc.

2019-12-21 08:23:57 (14424): Guest Log: 08:24:02 CET +01:00 2019-12-21: cranky: [INFO] Creating the filesystem.

2019-12-21 08:23:57 (14424): Guest Log: 08:24:03 CET +01:00 2019-12-21: cranky: [INFO] Using /cvmfs/cernvm-prod.cern.ch/cvm3

2019-12-21 08:23:57 (14424): Guest Log: 08:24:03 CET +01:00 2019-12-21: cranky: [INFO] Updating config.json.

2019-12-21 08:23:57 (14424): Guest Log: 08:24:03 CET +01:00 2019-12-21: cranky: [INFO] Running Container 'runc'.

2019-12-21 08:23:59 (14424): Guest Log: job: stdoutf=

2019-12-21 08:23:59 (14424): Guest Log: job: stderrf=

2019-12-21 08:23:59 (14424): Guest Log: job: htmld=/shared/html/job

2019-12-21 08:23:59 (14424): Guest Log: 08:24:05 CET +01:00 2019-12-21: cranky: [INFO] ===> [runRivet] Sat Dec 21 07:24:05 UTC 2019 [boinc pp w1j 7000 100 - pythia8 8.186 tune-4c 100000 194]

2019-12-21 08:26:25 (14424): VM state change detected. (old = 'Running', new = 'Paused')
2019-12-21 08:26:58 (14424): VM state change detected. (old = 'Paused', new = 'Running')
2019-12-21 08:33:08 (14424): VM state change detected. (old = 'Running', new = 'Paused')
2019-12-21 08:33:18 (14424): VM state change detected. (old = 'Paused', new = 'Running')
2019-12-21 08:33:43 (14424): VM state change detected. (old = 'Running', new = 'Paused')
2019-12-21 08:34:14 (14424): VM state change detected. (old = 'Paused', new = 'Running')
2019-12-21 08:44:21 (14424): VM state change detected. (old = 'Running', new = 'Paused')
2019-12-21 08:47:01 (14424): VM state change detected. (old = 'Paused', new = 'Running')
2019-12-21 09:05:32 (14424): VM state change detected. (old = 'Running', new = 'Paused')
2019-12-21 09:07:32 (14424): VM state change detected. (old = 'Paused', new = 'Running')
2019-12-21 09:07:42 (14424): VM state change detected. (old = 'Running', new = 'Paused')
2019-12-21 09:09:33 (14424): VM state change detected. (old = 'Paused', new = 'Running')
2019-12-21 09:09:43 (14424): VM state change detected. (old = 'Running', new = 'Paused')
2019-12-21 09:10:33 (14424): VM state change detected. (old = 'Paused', new = 'Running')
2019-12-21 09:10:44 (14424): VM state change detected. (old = 'Running', new = 'Paused')
2019-12-21 09:12:24 (14424): VM state change detected. (old = 'Paused', new = 'Running')
2019-12-21 09:12:33 (14424): VM state change detected. (old = 'Running', new = 'Paused')
2019-12-21 09:12:53 (14424): VM state change detected. (old = 'Paused', new = 'Running')
2019-12-21 09:13:04 (14424): VM state change detected. (old = 'Running', new = 'Paused')
2019-12-21 09:13:34 (14424): VM state change detected. (old = 'Paused', new = 'Running')
2019-12-21 09:13:44 (14424): VM state change detected. (old = 'Running', new = 'Paused')
2019-12-21 09:14:24 (14424): VM state change detected. (old = 'Paused', new = 'Running')
2019-12-21 09:14:33 (14424): VM state change detected. (old = 'Running', new = 'Paused')
2019-12-21 09:14:43 (14424): VM state change detected. (old = 'Paused', new = 'Running')
2019-12-21 09:14:54 (14424): VM state change detected. (old = 'Running', new = 'Paused')
2019-12-21 09:15:15 (14424): VM state change detected. (old = 'Paused', new = 'Running')
2019-12-21 10:18:01 (14424): Status Report: Job Duration: '864000.000000'
2019-12-21 10:18:01 (14424): Status Report: Elapsed Time: '27603.471575'
2019-12-21 10:18:01 (14424): Status Report: CPU Time: '20955.453125'
2019-12-21 10:29:35 (14424): VM state change detected. (old = 'Running', new = 'Paused')
2019-12-21 10:30:08 (14424): VM state change detected. (old = 'Paused', new = 'Running')
2019-12-21 10:32:36 (14424): VM state change detected. (old = 'Running', new = 'Paused')
2019-12-21 10:33:15 (14424): VM state change detected. (old = 'Paused', new = 'Running')
2019-12-21 10:44:09 (14424): VM state change detected. (old = 'Running', new = 'Paused')
2019-12-21 10:44:40 (14424): VM state change detected. (old = 'Paused', new = 'Running')
2019-12-21 11:02:56 (14424): VM state change detected. (old = 'Running', new = 'Paused')
2019-12-21 11:03:58 (14424): VM state change detected. (old = 'Paused', new = 'Running')
2019-12-21 11:05:13 (14424): VM state change detected. (old = 'Running', new = 'Paused')
2019-12-21 11:06:21 (14424): VM state change detected. (old = 'Paused', new = 'Running')
2019-12-21 11:06:24 (14424): VM state change detected. (old = 'Running', new = 'Paused')
2019-12-21 11:14:29 (14424): VM state change detected. (old = 'Paused', new = 'Running')
2019-12-21 11:14:30 (14424): VM state change detected. (old = 'Running', new = 'Paused')
2019-12-21 11:17:54 (14424): Stopping VM.
2019-12-21 11:17:55 (14424): Error in stop VM for VM: -108
Command:
VBoxManage -q controlvm "boinc_7883131ab420012e" savestate
Output:

2019-12-21 11:17:55 (14424): VM did not stop when requested.
2019-12-21 11:17:55 (14424): VM was successfully terminated.
2019-12-21 11:20:49 (8492): Detected: vboxwrapper 26197
2019-12-21 11:20:49 (8492): Detected: BOINC client v7.7
2019-12-21 11:20:50 (8492): Detected: VirtualBox VboxManage Interface (Version: 5.2.8)
2019-12-21 11:20:50 (8492): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2019-12-21 11:20:50 (8492): Starting VM using VBoxManage interface. (boinc_7883131ab420012e, slot#2)
2019-12-21 11:20:55 (8492): Successfully started VM. (PID = '11712')
2019-12-21 11:20:55 (8492): Reporting VM Process ID to BOINC.
2019-12-21 11:20:55 (8492): Guest Log: BIOS: VirtualBox 5.2.8

2019-12-21 11:20:55 (8492): Guest Log: CPUID EDX: 0x078bfbff

2019-12-21 11:20:55 (8492): Guest Log: BIOS: ata0-0: PCHS=16383/16/63 LCHS=1024/255/63

2019-12-21 11:20:55 (8492): VM state change detected. (old = 'PoweredOff', new = 'Running')
2019-12-21 11:20:55 (8492): Detected: Web Application Enabled (http://localhost:52279)
2019-12-21 11:20:55 (8492): Status Report: Job Duration: '864000.000000'
2019-12-21 11:20:55 (8492): Status Report: Elapsed Time: '30247.471575'
2019-12-21 11:20:55 (8492): Status Report: CPU Time: '22965.562500'
2019-12-21 11:20:55 (8492): Preference change detected
2019-12-21 11:20:55 (8492): Setting CPU throttle for VM. (70%)
2019-12-21 11:20:56 (8492): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2019-12-21 11:20:57 (8492): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032

2019-12-21 11:20:57 (8492): Guest Log: BIOS: Booting from Hard Disk...

2019-12-21 11:20:58 (8492): VM state change detected. (old = 'Running', new = 'Paused')
2019-12-21 11:24:21 (8492): VM state change detected. (old = 'Paused', new = 'Running')
2019-12-21 11:24:22 (8492): Guest Log: BIOS: KBD: unsupported int 16h function 03

2019-12-21 11:24:22 (8492): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 

2019-12-21 11:24:41 (8492): VM state change detected. (old = 'Running', new = 'Paused')
2019-12-21 11:35:03 (8492): VM state change detected. (old = 'Paused', new = 'Running')
2019-12-21 11:35:12 (8492): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds

2019-12-21 11:35:12 (8492): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000)

2019-12-21 11:35:13 (8492): VM state change detected. (old = 'Running', new = 'Paused')
2019-12-21 13:32:21 (8492): VM state change detected. (old = 'Paused', new = 'Running')
2019-12-21 13:32:22 (8492): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log

2019-12-21 13:32:22 (8492): Guest Log: 00:00:00.000092 main     Log opened 2019-12-21T10:24:59.357690000Z

2019-12-21 13:32:22 (8492): Guest Log: 00:00:00.000203 main     OS Product: Linux

2019-12-21 13:32:22 (8492): Guest Log: 00:00:00.000240 main     OS Release: 4.14.76-13.cernvm.x86_64

2019-12-21 13:32:22 (8492): Guest Log: 00:00:00.000273 main     OS Version: #1 SMP Tue Oct 16 18:26:15 CEST 2018

2019-12-21 13:32:22 (8492): Guest Log: 00:00:00.000302 main     Executable: /usr/sbin/VBoxService

2019-12-21 13:32:22 (8492): Guest Log: 00:00:00.000303 main     Process ID: 3191

2019-12-21 13:32:22 (8492): Guest Log: 00:00:00.000304 main     Package type: LINUX_64BITS_GENERIC

2019-12-21 13:32:22 (8492): Guest Log: 00:00:00.006612 main     5.2.6 r120293 started. Verbose level = 0

2019-12-21 13:32:32 (8492): Guest Log: 00:00:10.027609 timesync vgsvcTimeSyncWorker: Radical guest time change: 7 651 741 990 000ns (GuestNow=1 576 931 551 121 577 000 ns GuestLast=1 576 923 899 379 587 000 ns fSetTimeLastLoop=true )

2019-12-21 13:32:38 (8492): Guest Log: 13:32:37 CET +01:00 2019-12-21: cranky: [INFO] Detected Theory App

2019-12-21 13:32:38 (8492): Guest Log: 13:32:37 CET +01:00 2019-12-21: cranky: [INFO] Checking CVMFS.

2019-12-21 13:32:45 (8492): Guest Log: 13:32:44 CET +01:00 2019-12-21: cranky: [INFO] Checking runc.

2019-12-21 13:32:45 (8492): Guest Log: 13:32:44 CET +01:00 2019-12-21: cranky: [INFO] Creating the filesystem.

2019-12-21 13:32:45 (8492): Guest Log: 13:32:44 CET +01:00 2019-12-21: cranky: [INFO] Using /cvmfs/cernvm-prod.cern.ch/cvm3

2019-12-21 13:32:45 (8492): Guest Log: 13:32:44 CET +01:00 2019-12-21: cranky: [INFO] Updating config.json.

2019-12-21 13:32:45 (8492): Guest Log: 13:32:44 CET +01:00 2019-12-21: cranky: [INFO] Running Container 'runc'.

2019-12-21 13:32:45 (8492): Guest Log: job: stdoutf=

2019-12-21 13:32:45 (8492): Guest Log: job: stderrf=

2019-12-21 13:32:45 (8492): Guest Log: job: htmld=/shared/html/job

2019-12-21 13:32:47 (8492): Guest Log: 13:32:46 CET +01:00 2019-12-21: cranky: [INFO] ===> [runRivet] Sat Dec 21 12:32:45 UTC 2019 [boinc pp w1j 7000 100 - pythia8 8.186 tune-4c 100000 194]

2019-12-21 13:51:38 (8492): VM state change detected. (old = 'Running', new = 'Paused')
2019-12-21 13:53:16 (8492): VM state change detected. (old = 'Paused', new = 'Running')
2019-12-21 13:53:46 (8492): VM state change detected. (old = 'Running', new = 'Paused')
2019-12-21 13:55:17 (8492): VM state change detected. (old = 'Paused', new = 'Running')
2019-12-21 13:55:29 (8492): VM state change detected. (old = 'Running', new = 'Paused')
2019-12-21 14:05:46 (8492): VM state change detected. (old = 'Paused', new = 'Running')
2019-12-21 14:09:26 (8492): VM state change detected. (old = 'Running', new = 'Paused')
2019-12-21 14:11:58 (8492): VM state change detected. (old = 'Paused', new = 'Running')
2019-12-21 14:12:37 (8492): VM state change detected. (old = 'Running', new = 'Paused')
2019-12-21 14:15:29 (8492): VM state change detected. (old = 'Paused', new = 'Running')
2019-12-21 14:16:50 (8492): VM state change detected. (old = 'Running', new = 'Paused')
2019-12-21 14:21:26 (8492): VM state change detected. (old = 'Paused', new = 'Running')
2019-12-21 14:23:12 (8492): VM state change detected. (old = 'Running', new = 'Paused')
2019-12-21 14:24:22 (8492): VM state change detected. (old = 'Paused', new = 'Running')
2019-12-21 14:24:30 (8492): VM state change detected. (old = 'Running', new = 'Paused')
2019-12-21 14:30:37 (8492): VM state change detected. (old = 'Paused', new = 'Running')
2019-12-21 14:31:26 (8492): VM state change detected. (old = 'Running', new = 'Paused')
2019-12-21 14:32:33 (8492): VM state change detected. (old = 'Paused', new = 'Running')
2019-12-21 14:33:37 (8492): VM state change detected. (old = 'Running', new = 'Paused')
2019-12-21 14:34:08 (8492): VM state change detected. (old = 'Paused', new = 'Running')
2019-12-21 14:36:59 (8492): VM state change detected. (old = 'Running', new = 'Paused')
2019-12-21 15:08:09 (8492): VM state change detected. (old = 'Paused', new = 'Running')
2019-12-21 15:08:13 (8492): Guest Log: 00:32:21.961778 timesync vgsvcTimeSyncWorker: Radical host time change: 1 879 443 000 000ns (HostNow=1 576 937 293 024 000 000 ns HostLast=1 576 935 413 581 000 000 ns)

2019-12-21 15:08:23 (8492): Guest Log: 00:32:31.966830 timesync vgsvcTimeSyncWorker: Radical guest time change: 2 414 513 470 000ns (GuestNow=1 576 937 303 050 475 000 ns GuestLast=1 576 934 888 537 005 000 ns fSetTimeLastLoop=true )

2019-12-21 15:17:23 (8492): VM state change detected. (old = 'Running', new = 'Paused')
2019-12-21 15:17:54 (8492): VM state change detected. (old = 'Paused', new = 'Running')
2019-12-21 15:30:41 (8492): VM state change detected. (old = 'Running', new = 'Paused')
2019-12-21 15:32:11 (8492): VM state change detected. (old = 'Paused', new = 'Running')
2019-12-21 15:32:44 (8492): VM state change detected. (old = 'Running', new = 'Paused')
2019-12-21 15:33:53 (8492): VM state change detected. (old = 'Paused', new = 'Running')
2019-12-21 15:37:11 (8492): VM state change detected. (old = 'Running', new = 'Paused')
2019-12-21 15:37:42 (8492): VM state change detected. (old = 'Paused', new = 'Running')
2019-12-21 15:44:47 (8492): VM state change detected. (old = 'Running', new = 'Paused')
2019-12-21 15:45:17 (8492): VM state change detected. (old = 'Paused', new = 'Running')
2019-12-21 15:57:29 (8492): VM state change detected. (old = 'Running', new = 'Paused')
2019-12-21 15:58:08 (8492): VM state change detected. (old = 'Paused', new = 'Running')
2019-12-21 16:08:09 (8492): VM state change detected. (old = 'Running', new = 'Paused')
2019-12-21 16:08:40 (8492): VM state change detected. (old = 'Paused', new = 'Running')
2019-12-21 16:19:33 (8492): VM state change detected. (old = 'Running', new = 'Paused')
2019-12-21 16:20:04 (8492): VM state change detected. (old = 'Paused', new = 'Running')
2019-12-21 16:22:21 (8492): Status Report: Job Duration: '864000.000000'
2019-12-21 16:22:21 (8492): Status Report: Elapsed Time: '36247.471575'
2019-12-21 16:22:21 (8492): Status Report: CPU Time: '27515.046875'
2019-12-21 17:06:38 (8492): VM state change detected. (old = 'Running', new = 'Paused')
2019-12-21 17:06:44 (8492): Stopping VM.
2019-12-21 17:06:44 (8492): Error in stop VM for VM: -108
Command:
VBoxManage -q controlvm "boinc_7883131ab420012e" savestate
Output:

2019-12-21 17:06:44 (8492): VM did not stop when requested.
2019-12-21 17:06:44 (8492): VM was successfully terminated.
2019-12-22 09:44:00 (6264): Detected: vboxwrapper 26197
2019-12-22 09:44:00 (6264): Detected: BOINC client v7.7
2019-12-22 09:44:01 (6264): Detected: VirtualBox VboxManage Interface (Version: 5.2.8)
2019-12-22 09:44:01 (6264): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2019-12-22 09:44:01 (6264): Starting VM using VBoxManage interface. (boinc_7883131ab420012e, slot#2)
2019-12-22 09:44:06 (6264): Successfully started VM. (PID = '8292')
2019-12-22 09:44:06 (6264): Reporting VM Process ID to BOINC.
2019-12-22 09:44:06 (6264): Guest Log: BIOS: VirtualBox 5.2.8

2019-12-22 09:44:06 (6264): Guest Log: CPUID EDX: 0x078bfbff

2019-12-22 09:44:06 (6264): Guest Log: BIOS: ata0-0: PCHS=16383/16/63 LCHS=1024/255/63

2019-12-22 09:44:06 (6264): VM state change detected. (old = 'PoweredOff', new = 'Running')
2019-12-22 09:44:06 (6264): Detected: Web Application Enabled (http://localhost:52279)
2019-12-22 09:44:06 (6264): Status Report: Job Duration: '864000.000000'
2019-12-22 09:44:06 (6264): Status Report: Elapsed Time: '38888.471575'
2019-12-22 09:44:06 (6264): Status Report: CPU Time: '29512.078125'
2019-12-22 09:44:06 (6264): Preference change detected
2019-12-22 09:44:06 (6264): Setting CPU throttle for VM. (70%)
2019-12-22 09:44:07 (6264): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2019-12-22 09:44:08 (6264): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032

2019-12-22 09:44:08 (6264): Guest Log: BIOS: Booting from Hard Disk...

2019-12-22 09:44:10 (6264): Guest Log: BIOS: KBD: unsupported int 16h function 03

2019-12-22 09:44:10 (6264): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 

2019-12-22 09:44:35 (6264): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds

2019-12-22 09:44:35 (6264): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000)

2019-12-22 09:44:40 (6264): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log

2019-12-22 09:44:40 (6264): Guest Log: 00:00:00.000149 main     Log opened 2019-12-22T08:44:45.765592000Z

2019-12-22 09:44:40 (6264): Guest Log: 00:00:00.000248 main     OS Product: Linux

2019-12-22 09:44:40 (6264): Guest Log: 00:00:00.000291 main     OS Release: 4.14.76-13.cernvm.x86_64

2019-12-22 09:44:40 (6264): Guest Log: 00:00:00.000320 main     OS Version: #1 SMP Tue Oct 16 18:26:15 CEST 2018

2019-12-22 09:44:40 (6264): Guest Log: 00:00:00.000356 main     Executable: /usr/sbin/VBoxService

2019-12-22 09:44:40 (6264): Guest Log: 00:00:00.000357 main     Process ID: 3192

2019-12-22 09:44:40 (6264): Guest Log: 00:00:00.000358 main     Package type: LINUX_64BITS_GENERIC

2019-12-22 09:44:40 (6264): Guest Log: 00:00:00.044670 main     5.2.6 r120293 started. Verbose level = 0

2019-12-22 09:44:55 (6264): Guest Log: 09:45:01 CET +01:00 2019-12-22: cranky: [INFO] Detected Theory App

2019-12-22 09:44:55 (6264): Guest Log: 09:45:01 CET +01:00 2019-12-22: cranky: [INFO] Checking CVMFS.

2019-12-22 09:45:02 (6264): Guest Log: 09:45:08 CET +01:00 2019-12-22: cranky: [INFO] Checking runc.

2019-12-22 09:45:02 (6264): Guest Log: 09:45:08 CET +01:00 2019-12-22: cranky: [INFO] Creating the filesystem.

2019-12-22 09:45:02 (6264): Guest Log: 09:45:08 CET +01:00 2019-12-22: cranky: [INFO] Using /cvmfs/cernvm-prod.cern.ch/cvm3

2019-12-22 09:45:03 (6264): Guest Log: 09:45:08 CET +01:00 2019-12-22: cranky: [INFO] Updating config.json.

2019-12-22 09:45:03 (6264): Guest Log: 09:45:08 CET +01:00 2019-12-22: cranky: [INFO] Running Container 'runc'.

2019-12-22 09:45:03 (6264): Guest Log: job: stdoutf=

2019-12-22 09:45:03 (6264): Guest Log: job: stderrf=

2019-12-22 09:45:03 (6264): Guest Log: job: htmld=/shared/html/job

2019-12-22 09:45:05 (6264): Guest Log: 09:45:10 CET +01:00 2019-12-22: cranky: [INFO] ===> [runRivet] Sun Dec 22 08:45:09 UTC 2019 [boinc pp w1j 7000 100 - pythia8 8.186 tune-4c 100000 194]

2019-12-22 09:54:54 (6264): VM state change detected. (old = 'Running', new = 'Paused')
2019-12-22 09:55:26 (6264): VM state change detected. (old = 'Paused', new = 'Running')
2019-12-22 10:05:30 (6264): VM state change detected. (old = 'Running', new = 'Paused')
2019-12-22 10:05:40 (6264): VM state change detected. (old = 'Paused', new = 'Running')
2019-12-22 10:28:32 (6264): VM state change detected. (old = 'Running', new = 'Paused')
2019-12-22 10:28:52 (6264): VM state change detected. (old = 'Paused', new = 'Running')
2019-12-22 10:29:02 (6264): VM state change detected. (old = 'Running', new = 'Paused')
2019-12-22 10:29:12 (6264): VM state change detected. (old = 'Paused', new = 'Running')
2019-12-22 10:29:22 (6264): VM state change detected. (old = 'Running', new = 'Paused')
2019-12-22 10:31:44 (6264): VM state change detected. (old = 'Paused', new = 'Running')
2019-12-22 10:31:57 (6264): VM state change detected. (old = 'Running', new = 'Paused')
2019-12-22 10:32:34 (6264): VM state change detected. (old = 'Paused', new = 'Running')
2019-12-22 10:32:51 (6264): VM state change detected. (old = 'Running', new = 'Paused')
2019-12-22 10:34:27 (6264): VM state change detected. (old = 'Paused', new = 'Running')
2019-12-22 10:36:23 (6264): VM state change detected. (old = 'Running', new = 'Paused')
2019-12-22 10:36:55 (6264): VM state change detected. (old = 'Paused', new = 'Running')
2019-12-22 10:47:48 (6264): VM state change detected. (old = 'Running', new = 'Paused')
2019-12-22 10:48:20 (6264): VM state change detected. (old = 'Paused', new = 'Running')
2019-12-22 11:03:05 (6264): VM state change detected. (old = 'Running', new = 'Paused')
2019-12-22 11:03:25 (6264): VM state change detected. (old = 'Paused', new = 'Running')
2019-12-22 11:15:36 (6264): VM state change detected. (old = 'Running', new = 'Paused')
2019-12-22 11:15:46 (6264): VM state change detected. (old = 'Paused', new = 'Running')
2019-12-22 11:19:06 (6264): VM state change detected. (old = 'Running', new = 'Paused')
2019-12-22 11:19:16 (6264): VM state change detected. (old = 'Paused', new = 'Running')
2019-12-22 11:32:35 (6264): Status Report: Job Duration: '864000.000000'
2019-12-22 11:32:35 (6264): Status Report: Elapsed Time: '44888.471575'
2019-12-22 11:32:35 (6264): Status Report: CPU Time: '34052.359375'
2019-12-22 12:57:33 (6264): VM state change detected. (old = 'Running', new = 'Paused')
2019-12-22 12:58:03 (6264): VM state change detected. (old = 'Paused', new = 'Running')
2019-12-22 13:15:46 (6264): Status Report: Job Duration: '864000.000000'
2019-12-22 13:15:46 (6264): Status Report: Elapsed Time: '50888.619927'
2019-12-22 13:15:46 (6264): Status Report: CPU Time: '38665.703125'
2019-12-22 14:26:39 (6264): Guest Log: job: CPU usage:

2019-12-22 14:26:39 (6264): Guest Log: 0m0.006s 0m0.006s

2019-12-22 14:26:39 (6264): Guest Log: 270m7.148s 1m27.742s

2019-12-22 14:26:39 (6264): Guest Log: 14:18:52 CET +01:00 2019-12-22: cranky: [INFO] Container 'runc' finished with status code 0.

2019-12-22 14:26:39 (6264): Guest Log: 14:18:52 CET +01:00 2019-12-22: cranky: [INFO] Preparing output.

2019-12-22 14:26:39 (6264): Guest Log: [INFO] Job Finished

2019-12-22 14:26:39 (6264): Guest Log: [INFO] Shutting Down.

2019-12-22 14:26:39 (6264): VM Completion File Detected.
2019-12-22 14:26:39 (6264): VM Completion Message: Job Finished
.
2019-12-22 14:26:39 (6264): Powering off VM.
2019-12-22 14:31:40 (6264): VM did not power off when requested.
2019-12-22 14:31:40 (6264): VM was successfully terminated.
2019-12-22 14:31:40 (6264): Deregistering VM. (boinc_7883131ab420012e, slot#2)
2019-12-22 14:31:40 (6264): Removing network bandwidth throttle group from VM.
2019-12-22 14:31:40 (6264): Removing VM from VirtualBox.
14:31:45 (6264): called boinc_finish(0)

</stderr_txt>
]]>
Development for LHC@home

Stderr output
