Task 2778783

Name	Theory_2279-803750-60_0
Workunit	1898432
Created	21 May 2019, 3:45:08 UTC
Sent	22 May 2019, 13:52:57 UTC
Report deadline	1 Jun 2019, 13:52:57 UTC
Received	28 May 2019, 20:18:52 UTC
Server state	Over
Outcome	Success
Client state	Done
Exit status	0 (0x00000000)
Computer ID	2148
Run time	3 hours 9 min 3 sec
CPU time	3 hours 28 min 7 sec
Validate state	Valid
Credit	830.55
Device peak FLOPS	31.63 GFLOPS
Application version	Theory Simulation v4.16 (vbox64_mt_mcore) windows_x86_64
Peak working set size	61.80 MB
Peak swap size	93.46 MB
Peak disk usage	1.78 GB
Stderr output

<core_client_version>7.14.2</core_client_version>
<![CDATA[
<stderr_txt>
2019-05-27 17:10:58 (11892): Detected: vboxwrapper 26197
2019-05-27 17:10:58 (11892): Detected: BOINC client v7.7
2019-05-27 17:10:59 (11892): Detected: VirtualBox VboxManage Interface (Version: 5.2.8)
2019-05-27 17:10:59 (11892): Successfully copied 'init_data.xml' to the shared directory.
2019-05-27 17:10:59 (11892): Successfully copied 'cranky' to the shared directory.
2019-05-27 17:10:59 (11892): Successfully copied 'input' to the shared directory.
2019-05-27 17:11:00 (11892): Create VM. (boinc_e33dfa8d3b26ede2, slot#1)
2019-05-27 17:11:01 (11892): Setting Memory Size for VM. (1430MB)
2019-05-27 17:11:01 (11892): Setting CPU Count for VM. (8)
2019-05-27 17:11:01 (11892): Setting Chipset Options for VM.
2019-05-27 17:11:01 (11892): Setting Boot Options for VM.
2019-05-27 17:11:02 (11892): Setting Network Configuration for NAT.
2019-05-27 17:11:02 (11892): Enabling VM Network Access.
2019-05-27 17:11:02 (11892): Disabling USB Support for VM.
2019-05-27 17:11:02 (11892): Disabling COM Port Support for VM.
2019-05-27 17:11:03 (11892): Disabling LPT Port Support for VM.
2019-05-27 17:11:03 (11892): Disabling Audio Support for VM.
2019-05-27 17:11:03 (11892): Disabling Clipboard Support for VM.
2019-05-27 17:11:03 (11892): Disabling Drag and Drop Support for VM.
2019-05-27 17:11:04 (11892): Adding storage controller(s) to VM.
2019-05-27 17:11:04 (11892): Adding virtual disk drive to VM. (vm_image.vdi)
2019-05-27 17:11:04 (11892): Adding VirtualBox Guest Additions to VM.
2019-05-27 17:11:05 (11892): Adding network bandwidth throttle group to VM. (Defaulting to 1024GB)
2019-05-27 17:11:05 (11892): forwarding host port 54705 to guest port 80
2019-05-27 17:11:05 (11892): Enabling remote desktop for VM.
2019-05-27 17:11:05 (11892): Required extension pack not installed, remote desktop not enabled.
2019-05-27 17:11:05 (11892): Enabling shared directory for VM.
2019-05-27 17:11:06 (11892): Starting VM using VBoxManage interface. (boinc_e33dfa8d3b26ede2, slot#1)
2019-05-27 17:11:09 (11892): Successfully started VM. (PID = '7656')
2019-05-27 17:11:09 (11892): Reporting VM Process ID to BOINC.
2019-05-27 17:11:09 (11892): Guest Log: BIOS: VirtualBox 5.2.8

2019-05-27 17:11:09 (11892): Guest Log: CPUID EDX: 0x178bfbff

2019-05-27 17:11:09 (11892): Guest Log: BIOS: ata0-0: PCHS=16383/16/63 LCHS=1024/255/63

2019-05-27 17:11:09 (11892): VM state change detected. (old = 'PoweredOff', new = 'Running')
2019-05-27 17:11:09 (11892): Detected: Web Application Enabled (http://localhost:54705)
2019-05-27 17:11:09 (11892): Preference change detected
2019-05-27 17:11:09 (11892): Setting CPU throttle for VM. (100%)
2019-05-27 17:11:09 (11892): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2019-05-27 17:11:11 (11892): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032

2019-05-27 17:11:11 (11892): Guest Log: BIOS: Booting from Hard Disk...

2019-05-27 17:11:13 (11892): Guest Log: BIOS: KBD: unsupported int 16h function 03

2019-05-27 17:11:13 (11892): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 

2019-05-27 17:11:37 (11892): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds

2019-05-27 17:11:37 (11892): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000)

2019-05-27 17:11:40 (11892): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log

2019-05-27 17:11:40 (11892): Guest Log: 00:00:00.000302 main     Log opened 2019-05-27T21:11:41.960867000Z

2019-05-27 17:11:40 (11892): Guest Log: 00:00:00.000418 main     OS Product: Linux

2019-05-27 17:11:40 (11892): Guest Log: 00:00:00.000458 main     OS Release: 4.14.76-13.cernvm.x86_64

2019-05-27 17:11:40 (11892): Guest Log: 00:00:00.000537 main     OS Version: #1 SMP Tue Oct 16 18:26:15 CEST 2018

2019-05-27 17:11:40 (11892): Guest Log: 00:00:00.000575 main     Executable: /usr/sbin/VBoxService

2019-05-27 17:11:40 (11892): Guest Log: 00:00:00.000576 main     Process ID: 3551

2019-05-27 17:11:40 (11892): Guest Log: 00:00:00.000577 main     Package type: LINUX_64BITS_GENERIC

2019-05-27 17:11:40 (11892): Guest Log: 00:00:00.001606 main     5.2.6 r120293 started. Verbose level = 0

2019-05-27 17:11:50 (11892): Guest Log: 21:11:52 2019-05-27: cranky: [INFO] Detected Theory App

2019-05-27 17:11:50 (11892): Guest Log: 21:11:52 2019-05-27: cranky: [INFO] Checking CVMFS.

2019-05-27 17:11:56 (11892): Guest Log: 21:11:58 2019-05-27: cranky: [INFO] Checking runc.

2019-05-27 17:11:56 (11892): Guest Log: 21:11:58 2019-05-27: cranky: [INFO] Creating the filesystem.

2019-05-27 17:11:56 (11892): Guest Log: 21:11:58 2019-05-27: cranky: [INFO] Using /cvmfs/cernvm-prod.cern.ch/cvm3

2019-05-27 17:11:56 (11892): Guest Log: 21:11:58 2019-05-27: cranky: [INFO] Updating config.json.

2019-05-27 17:11:57 (11892): Guest Log: 21:11:58 2019-05-27: cranky: [INFO] Running Container 'runc'.

2019-05-27 18:00:02 (11892): Stopping VM.
2019-05-28 08:30:00 (21148): Detected: vboxwrapper 26197
2019-05-28 08:30:00 (21148): Detected: BOINC client v7.7
2019-05-28 08:30:01 (21148): Detected: VirtualBox VboxManage Interface (Version: 5.2.8)
2019-05-28 08:30:02 (21148): Starting VM using VBoxManage interface. (boinc_e33dfa8d3b26ede2, slot#1)
2019-05-28 08:30:08 (21148): Successfully started VM. (PID = '21516')
2019-05-28 08:30:08 (21148): Reporting VM Process ID to BOINC.
2019-05-28 08:30:08 (21148): VM state change detected. (old = 'PoweredOff', new = 'Running')
2019-05-28 08:30:08 (21148): Detected: Web Application Enabled (http://localhost:54705)
2019-05-28 08:30:08 (21148): Preference change detected
2019-05-28 08:30:08 (21148): Setting CPU throttle for VM. (100%)
2019-05-28 08:30:08 (21148): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2019-05-28 08:30:16 (21148): Guest Log: 00:48:30.496082 timesync vgsvcTimeSyncWorker: Radical host time change: 52 215 290 000 000ns (HostNow=1 559 046 616 220 000 000 ns HostLast=1 558 994 400 930 000 000 ns)

2019-05-28 08:30:26 (21148): Guest Log: 00:48:40.496483 timesync vgsvcTimeSyncWorker: Radical guest time change: 52 213 764 758 000ns (GuestNow=1 559 046 626 220 867 000 ns GuestLast=1 558 994 412 456 109 000 ns fSetTimeLastLoop=true )

2019-05-28 08:50:49 (21148): Stopping VM.
2019-05-28 10:14:22 (20192): Detected: vboxwrapper 26197
2019-05-28 10:14:22 (20192): Detected: BOINC client v7.7
2019-05-28 10:14:23 (20192): Detected: VirtualBox VboxManage Interface (Version: 5.2.8)
2019-05-28 10:14:24 (20192): Starting VM using VBoxManage interface. (boinc_e33dfa8d3b26ede2, slot#1)
2019-05-28 10:14:31 (20192): Successfully started VM. (PID = '15168')
2019-05-28 10:14:31 (20192): Reporting VM Process ID to BOINC.
2019-05-28 10:14:31 (20192): VM state change detected. (old = 'PoweredOff', new = 'Running')
2019-05-28 10:14:31 (20192): Detected: Web Application Enabled (http://localhost:54705)
2019-05-28 10:14:31 (20192): Preference change detected
2019-05-28 10:14:31 (20192): Setting CPU throttle for VM. (100%)
2019-05-28 10:14:32 (20192): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2019-05-28 10:14:37 (20192): Guest Log: 01:09:10.713336 timesync vgsvcTimeSyncWorker: Radical host time change: 5 030 556 000 000ns (HostNow=1 559 052 877 091 000 000 ns HostLast=1 559 047 846 535 000 000 ns)

2019-05-28 10:14:47 (20192): Guest Log: 01:09:20.714075 timesync vgsvcTimeSyncWorker: Radical guest time change: 5 030 654 493 000ns (GuestNow=1 559 052 887 091 968 000 ns GuestLast=1 559 047 856 437 475 000 ns fSetTimeLastLoop=true )

2019-05-28 10:16:57 (20192): Stopping VM.
2019-05-28 12:06:06 (19720): Detected: vboxwrapper 26197
2019-05-28 12:06:06 (19720): Detected: BOINC client v7.7
2019-05-28 12:06:07 (19720): Detected: VirtualBox VboxManage Interface (Version: 5.2.8)
2019-05-28 12:06:07 (19720): Starting VM using VBoxManage interface. (boinc_e33dfa8d3b26ede2, slot#1)
2019-05-28 12:06:15 (19720): Successfully started VM. (PID = '23020')
2019-05-28 12:06:15 (19720): Reporting VM Process ID to BOINC.
2019-05-28 12:06:15 (19720): VM state change detected. (old = 'PoweredOff', new = 'Running')
2019-05-28 12:06:15 (19720): Detected: Web Application Enabled (http://localhost:54705)
2019-05-28 12:06:15 (19720): Preference change detected
2019-05-28 12:06:15 (19720): Setting CPU throttle for VM. (100%)
2019-05-28 12:06:15 (19720): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2019-05-28 12:06:24 (19720): Guest Log: 01:11:40.725243 timesync vgsvcTimeSyncWorker: Radical host time change: 6 566 154 000 000ns (HostNow=1 559 059 583 266 000 000 ns HostLast=1 559 053 017 112 000 000 ns)

2019-05-28 12:06:34 (19720): Guest Log: 01:11:50.725752 timesync vgsvcTimeSyncWorker: Radical guest time change: 6 566 163 995 000ns (GuestNow=1 559 059 593 266 816 000 ns GuestLast=1 559 053 027 102 821 000 ns fSetTimeLastLoop=true )

2019-05-28 12:08:29 (19720): Stopping VM.
2019-05-28 12:12:39 (9388): Detected: vboxwrapper 26197
2019-05-28 12:12:39 (9388): Detected: BOINC client v7.7
2019-05-28 12:12:40 (9388): Detected: VirtualBox VboxManage Interface (Version: 5.2.8)
2019-05-28 12:12:41 (9388): Starting VM using VBoxManage interface. (boinc_e33dfa8d3b26ede2, slot#1)
2019-05-28 12:12:47 (9388): Successfully started VM. (PID = '8492')
2019-05-28 12:12:47 (9388): Reporting VM Process ID to BOINC.
2019-05-28 12:12:47 (9388): VM state change detected. (old = 'PoweredOff', new = 'Running')
2019-05-28 12:12:47 (9388): Detected: Web Application Enabled (http://localhost:54705)
2019-05-28 12:12:47 (9388): Preference change detected
2019-05-28 12:12:47 (9388): Setting CPU throttle for VM. (100%)
2019-05-28 12:12:48 (9388): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2019-05-28 12:12:55 (9388): VM state change detected. (old = 'Running', new = 'Paused')
2019-05-28 12:13:05 (9388): VM state change detected. (old = 'Paused', new = 'Running')
2019-05-28 12:13:34 (9388): Stopping VM.
2019-05-28 12:30:38 (11744): Detected: vboxwrapper 26197
2019-05-28 12:30:38 (11744): Detected: BOINC client v7.7
2019-05-28 12:30:38 (11744): Detected: VirtualBox VboxManage Interface (Version: 5.2.8)
2019-05-28 12:30:39 (11744): Starting VM using VBoxManage interface. (boinc_e33dfa8d3b26ede2, slot#1)
2019-05-28 12:30:46 (11744): Successfully started VM. (PID = '21500')
2019-05-28 12:30:46 (11744): Reporting VM Process ID to BOINC.
2019-05-28 12:30:46 (11744): VM state change detected. (old = 'PoweredOff', new = 'Running')
2019-05-28 12:30:46 (11744): Detected: Web Application Enabled (http://localhost:54705)
2019-05-28 12:30:46 (11744): Preference change detected
2019-05-28 12:30:46 (11744): Setting CPU throttle for VM. (100%)
2019-05-28 12:30:46 (11744): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2019-05-28 12:31:37 (11744): VM state change detected. (old = 'Running', new = 'Paused')
2019-05-28 12:31:47 (11744): VM state change detected. (old = 'Paused', new = 'Running')
2019-05-28 12:32:57 (11744): VM state change detected. (old = 'Running', new = 'Paused')
2019-05-28 12:33:07 (11744): VM state change detected. (old = 'Paused', new = 'Running')
2019-05-28 12:33:17 (11744): VM state change detected. (old = 'Running', new = 'Paused')
2019-05-28 12:33:27 (11744): VM state change detected. (old = 'Paused', new = 'Running')
2019-05-28 12:56:29 (11744): Status Report: Job Duration: '64800.000000'
2019-05-28 12:56:29 (11744): Status Report: Elapsed Time: '6000.000000'
2019-05-28 12:56:29 (11744): Status Report: CPU Time: '6678.090808'
2019-05-28 13:48:32 (11744): Stopping VM.
2019-05-28 14:09:11 (17956): Detected: vboxwrapper 26197
2019-05-28 14:09:11 (17956): Detected: BOINC client v7.7
2019-05-28 14:09:12 (17956): Detected: VirtualBox VboxManage Interface (Version: 5.2.8)
2019-05-28 14:09:12 (17956): Starting VM using VBoxManage interface. (boinc_e33dfa8d3b26ede2, slot#1)
2019-05-28 14:09:19 (17956): Successfully started VM. (PID = '10224')
2019-05-28 14:09:19 (17956): Reporting VM Process ID to BOINC.
2019-05-28 14:09:19 (17956): VM state change detected. (old = 'PoweredOff', new = 'Running')
2019-05-28 14:09:19 (17956): Detected: Web Application Enabled (http://localhost:54705)
2019-05-28 14:09:19 (17956): Status Report: Job Duration: '64800.000000'
2019-05-28 14:09:19 (17956): Status Report: Elapsed Time: '9121.000000'
2019-05-28 14:09:19 (17956): Status Report: CPU Time: '10191.997733'
2019-05-28 14:09:19 (17956): Preference change detected
2019-05-28 14:09:19 (17956): Setting CPU throttle for VM. (100%)
2019-05-28 14:09:19 (17956): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2019-05-28 14:10:21 (17956): VM state change detected. (old = 'Running', new = 'Paused')
2019-05-28 14:10:31 (17956): VM state change detected. (old = 'Paused', new = 'Running')
2019-05-28 14:11:41 (17956): VM state change detected. (old = 'Running', new = 'Paused')
2019-05-28 14:11:51 (17956): VM state change detected. (old = 'Paused', new = 'Running')
2019-05-28 14:18:31 (17956): VM state change detected. (old = 'Running', new = 'Paused')
2019-05-28 14:18:41 (17956): VM state change detected. (old = 'Paused', new = 'Running')
2019-05-28 14:22:31 (17956): VM state change detected. (old = 'Running', new = 'Paused')
2019-05-28 14:22:41 (17956): VM state change detected. (old = 'Paused', new = 'Running')
2019-05-28 14:23:51 (17956): VM state change detected. (old = 'Running', new = 'Paused')
2019-05-28 14:24:01 (17956): VM state change detected. (old = 'Paused', new = 'Running')
2019-05-28 14:24:31 (17956): VM state change detected. (old = 'Running', new = 'Paused')
2019-05-28 14:24:41 (17956): VM state change detected. (old = 'Paused', new = 'Running')
2019-05-28 14:26:01 (17956): VM state change detected. (old = 'Running', new = 'Paused')
2019-05-28 14:26:11 (17956): VM state change detected. (old = 'Paused', new = 'Running')
2019-05-28 14:28:32 (17956): VM state change detected. (old = 'Running', new = 'Paused')
2019-05-28 14:28:42 (17956): VM state change detected. (old = 'Paused', new = 'Running')
2019-05-28 14:29:52 (17956): VM state change detected. (old = 'Running', new = 'Paused')
2019-05-28 14:30:02 (17956): VM state change detected. (old = 'Paused', new = 'Running')
2019-05-28 14:30:32 (17956): VM state change detected. (old = 'Running', new = 'Paused')
2019-05-28 14:30:42 (17956): VM state change detected. (old = 'Paused', new = 'Running')
2019-05-28 14:31:52 (17956): VM state change detected. (old = 'Running', new = 'Paused')
2019-05-28 14:32:02 (17956): VM state change detected. (old = 'Paused', new = 'Running')
2019-05-28 14:32:32 (17956): VM state change detected. (old = 'Running', new = 'Paused')
2019-05-28 14:32:42 (17956): VM state change detected. (old = 'Paused', new = 'Running')
2019-05-28 14:33:52 (17956): VM state change detected. (old = 'Running', new = 'Paused')
2019-05-28 14:34:02 (17956): VM state change detected. (old = 'Paused', new = 'Running')
2019-05-28 14:35:52 (17956): VM state change detected. (old = 'Running', new = 'Paused')
2019-05-28 14:36:02 (17956): VM state change detected. (old = 'Paused', new = 'Running')
2019-05-28 14:36:12 (17956): VM state change detected. (old = 'Running', new = 'Paused')
2019-05-28 14:36:22 (17956): VM state change detected. (old = 'Paused', new = 'Running')
2019-05-28 14:39:52 (17956): VM state change detected. (old = 'Running', new = 'Paused')
2019-05-28 14:40:02 (17956): VM state change detected. (old = 'Paused', new = 'Running')
2019-05-28 14:40:42 (17956): VM state change detected. (old = 'Running', new = 'Paused')
2019-05-28 14:40:52 (17956): VM state change detected. (old = 'Paused', new = 'Running')
2019-05-28 14:42:42 (17956): VM state change detected. (old = 'Running', new = 'Paused')
2019-05-28 14:42:52 (17956): VM state change detected. (old = 'Paused', new = 'Running')
2019-05-28 14:44:42 (17956): VM state change detected. (old = 'Running', new = 'Paused')
2019-05-28 14:44:52 (17956): VM state change detected. (old = 'Paused', new = 'Running')
2019-05-28 14:46:02 (17956): VM state change detected. (old = 'Running', new = 'Paused')
2019-05-28 14:46:22 (17956): VM state change detected. (old = 'Paused', new = 'Running')
2019-05-28 14:46:54 (17956): Guest Log: ===> [runRivet] Mon May 27 21:12:39 UTC 2019 [boinc pp z1j 7000 150 - pythia8 8.230 tune-A2 100000 60]

2019-05-28 14:46:54 (17956): Guest Log: 18:43:26 2019-05-28: cranky: [INFO] Preparing output.

2019-05-28 14:46:54 (17956): VM Completion File Detected.
2019-05-28 14:46:54 (17956): Powering off VM.
2019-05-28 14:51:55 (17956): VM did not power off when requested.
2019-05-28 14:51:55 (17956): VM was successfully terminated.
2019-05-28 14:51:55 (17956): Deregistering VM. (boinc_e33dfa8d3b26ede2, slot#1)
2019-05-28 14:51:55 (17956): Removing network bandwidth throttle group from VM.
2019-05-28 14:51:55 (17956): Removing VM from VirtualBox.
14:52:01 (17956): called boinc_finish(0)

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

Stderr output
