Name Theory_2279-799445-140_0
Workunit 1944816
Created 14 Oct 2019, 4:15:09 UTC
Sent 15 Oct 2019, 7:17:18 UTC
Report deadline 25 Oct 2019, 7:17:18 UTC
Received 16 Oct 2019, 1:09:35 UTC
Server state Over
Outcome Success
Client state Done
Exit status 0 (0x00000000)
Computer ID 3584
Run time 12 hours 42 min 29 sec
CPU time 12 hours 28 min 46 sec
Validate state Valid
Credit 297.70
Device peak FLOPS 2.81 GFLOPS
Application version Theory Simulation v5.18 (vbox64_theory)
windows_x86_64
Peak working set size 81.86 MB
Peak swap size 103.75 MB
Peak disk usage 994.68 MB

Stderr output

<core_client_version>7.14.2</core_client_version>
<![CDATA[
<stderr_txt>
2019-10-15 02:26:45 (6956): Detected: vboxwrapper 26197
2019-10-15 02:26:45 (6956): Detected: BOINC client v7.7
2019-10-15 02:26:45 (6956): Status Report: Launching vboxsvc.exe. (PID = '9116')
2019-10-15 02:26:47 (6956): Detected: VirtualBox VboxManage Interface (Version: 5.2.18)
2019-10-15 02:26:47 (6956): Detected: Sandbox Configuration Enabled
2019-10-15 02:26:47 (6956): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2019-10-15 02:26:47 (6956): Successfully copied 'init_data.xml' to the shared directory.
2019-10-15 02:26:47 (6956): Successfully copied 'input' to the shared directory.
2019-10-15 02:26:50 (6956): Create VM. (boinc_90a049bef096b374, slot#9)
2019-10-15 02:26:51 (6956): Setting Memory Size for VM. (630MB)
2019-10-15 02:26:51 (6956): Setting CPU Count for VM. (1)
2019-10-15 02:26:52 (6956): Setting Chipset Options for VM.
2019-10-15 02:26:52 (6956): Setting Boot Options for VM.
2019-10-15 02:26:52 (6956): Setting Network Configuration for NAT.
2019-10-15 02:26:53 (6956): Enabling VM Network Access.
2019-10-15 02:26:53 (6956): Disabling USB Support for VM.
2019-10-15 02:26:53 (6956): Disabling COM Port Support for VM.
2019-10-15 02:26:54 (6956): Disabling LPT Port Support for VM.
2019-10-15 02:26:54 (6956): Disabling Audio Support for VM.
2019-10-15 02:26:54 (6956): Disabling Clipboard Support for VM.
2019-10-15 02:26:55 (6956): Disabling Drag and Drop Support for VM.
2019-10-15 02:26:55 (6956): Adding storage controller(s) to VM.
2019-10-15 02:26:55 (6956): Adding virtual disk drive to VM. (vm_image.vdi)
2019-10-15 02:26:56 (6956): Adding VirtualBox Guest Additions to VM.
2019-10-15 02:26:56 (6956): Adding network bandwidth throttle group to VM. (Defaulting to 1024GB)
2019-10-15 02:26:56 (6956): forwarding host port 62585 to guest port 80
2019-10-15 02:26:57 (6956): Enabling remote desktop for VM.
2019-10-15 02:26:57 (6956): Enabling shared directory for VM.
2019-10-15 02:26:58 (6956): Starting VM using VBoxManage interface. (boinc_90a049bef096b374, slot#9)
2019-10-15 02:27:06 (6956): Successfully started VM. (PID = '3416')
2019-10-15 02:27:06 (6956): Reporting VM Process ID to BOINC.
2019-10-15 02:27:06 (6956): Guest Log: BIOS: VirtualBox 5.2.18

2019-10-15 02:27:06 (6956): Guest Log: CPUID EDX: 0x078bfbff

2019-10-15 02:27:06 (6956): Guest Log: BIOS: ata0-0: PCHS=16383/16/63 LCHS=1024/255/63

2019-10-15 02:27:06 (6956): VM state change detected. (old = 'PoweredOff', new = 'Running')
2019-10-15 02:27:06 (6956): Detected: Web Application Enabled (http://localhost:62585)
2019-10-15 02:27:06 (6956): Detected: Remote Desktop Enabled (localhost:62588)
2019-10-15 02:27:06 (6956): Preference change detected
2019-10-15 02:27:06 (6956): Setting CPU throttle for VM. (100%)
2019-10-15 02:27:06 (6956): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2019-10-15 02:27:08 (6956): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032

2019-10-15 02:27:08 (6956): Guest Log: BIOS: Booting from Hard Disk...

2019-10-15 02:27:10 (6956): Guest Log: BIOS: KBD: unsupported int 16h function 03

2019-10-15 02:27:10 (6956): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 

2019-10-15 02:27:37 (6956): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds

2019-10-15 02:27:37 (6956): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000)

2019-10-15 02:27:43 (6956): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log

2019-10-15 02:27:43 (6956): Guest Log: 00:00:00.000438 main     Log opened 2019-10-15T07:27:43.611747000Z

2019-10-15 02:27:43 (6956): Guest Log: 00:00:00.001322 main     OS Product: Linux

2019-10-15 02:27:43 (6956): Guest Log: 00:00:00.002840 main     OS Release: 4.14.76-13.cernvm.x86_64

2019-10-15 02:27:43 (6956): Guest Log: 00:00:00.002915 main     OS Version: #1 SMP Tue Oct 16 18:26:15 CEST 2018

2019-10-15 02:27:43 (6956): Guest Log: 00:00:00.002973 main     Executable: /usr/sbin/VBoxService

2019-10-15 02:27:43 (6956): Guest Log: 00:00:00.002976 main     Process ID: 3204

2019-10-15 02:27:43 (6956): Guest Log: 00:00:00.002978 main     Package type: LINUX_64BITS_GENERIC

2019-10-15 02:27:43 (6956): Guest Log: 00:00:00.007007 main     5.2.6 r120293 started. Verbose level = 0

2019-10-15 02:28:16 (6956): Guest Log: 09:28:16 CEST +02:00 2019-10-15: cranky: [INFO] Detected Theory App

2019-10-15 02:28:16 (6956): Guest Log: 09:28:16 CEST +02:00 2019-10-15: cranky: [INFO] Checking CVMFS.

2019-10-15 02:28:33 (6956): Guest Log: 09:28:33 CEST +02:00 2019-10-15: cranky: [INFO] Checking runc.

2019-10-15 02:28:33 (6956): Guest Log: 09:28:33 CEST +02:00 2019-10-15: cranky: [INFO] Creating the filesystem.

2019-10-15 02:28:33 (6956): Guest Log: 09:28:33 CEST +02:00 2019-10-15: cranky: [INFO] Using /cvmfs/cernvm-prod.cern.ch/cvm3

2019-10-15 02:28:33 (6956): Guest Log: 09:28:34 CEST +02:00 2019-10-15: cranky: [INFO] Updating config.json.

2019-10-15 02:28:33 (6956): Guest Log: 09:28:34 CEST +02:00 2019-10-15: cranky: [INFO] Running Container 'runc'.

2019-10-15 02:28:36 (6956): Guest Log: 09:28:36 CEST +02:00 2019-10-15: cranky: [INFO] ===> [runRivet] Tue Oct 15 07:28:34 UTC 2019 [boinc pp w1j 7000 100 - pythia8 8.212 tune-AU2loxx 100000 140]

2019-10-15 02:46:58 (6956): VM state change detected. (old = 'Running', new = 'Paused')
2019-10-15 03:02:39 (6956): VM state change detected. (old = 'Paused', new = 'Running')
2019-10-15 04:05:25 (6956): VM state change detected. (old = 'Running', new = 'Paused')
2019-10-15 04:54:14 (6956): Guest Log: 01:22:02.338745 timesync vgsvcTimeSyncWorker: Radical host time change: 2 938 736 000 000ns (HostNow=1 571 133 253 436 000 000 ns HostLast=1 571 130 314 700 000 000 ns)

2019-10-15 04:54:14 (6956): VM state change detected. (old = 'Paused', new = 'Running')
2019-10-15 04:54:24 (6956): Guest Log: 01:22:12.342161 timesync vgsvcTimeSyncWorker: Radical guest time change: 3 877 525 061 000ns (GuestNow=1 571 133 263 456 896 000 ns GuestLast=1 571 129 385 931 835 000 ns fSetTimeLastLoop=true )

2019-10-15 05:12:47 (6956): Status Report: Job Duration: '864000.000000'
2019-10-15 05:12:47 (6956): Status Report: Elapsed Time: '6000.000000'
2019-10-15 05:12:47 (6956): Status Report: CPU Time: '6019.421875'
2019-10-15 05:54:38 (6956): VM state change detected. (old = 'Running', new = 'Paused')
2019-10-15 06:14:44 (6956): VM state change detected. (old = 'Paused', new = 'Running')
2019-10-15 07:14:00 (6956): Status Report: Job Duration: '864000.000000'
2019-10-15 07:14:00 (6956): Status Report: Elapsed Time: '12000.000000'
2019-10-15 07:14:00 (6956): Status Report: CPU Time: '12084.093750'
2019-10-15 07:15:10 (6956): VM state change detected. (old = 'Running', new = 'Paused')
2019-10-15 10:04:46 (6956): VM state change detected. (old = 'Paused', new = 'Running')
2019-10-15 10:04:50 (6956): Guest Log: 03:22:55.924290 timesync vgsvcTimeSyncWorker: Radical host time change: 10 185 954 000 000ns (HostNow=1 571 151 889 386 000 000 ns HostLast=1 571 141 703 432 000 000 ns)

2019-10-15 10:04:59 (6956): Guest Log: 03:23:05.934093 timesync vgsvcTimeSyncWorker: Radical guest time change: 10 186 016 636 000ns (GuestNow=1 571 151 899 395 829 000 ns GuestLast=1 571 141 713 379 193 000 ns fSetTimeLastLoop=true )

2019-10-15 11:44:47 (6956): Status Report: Job Duration: '864000.000000'
2019-10-15 11:44:47 (6956): Status Report: Elapsed Time: '18000.000000'
2019-10-15 11:44:47 (6956): Status Report: CPU Time: '18141.609375'
2019-10-15 13:04:02 (6956): VM state change detected. (old = 'Running', new = 'Paused')
2019-10-15 13:14:59 (6956): VM state change detected. (old = 'Paused', new = 'Running')
2019-10-15 13:39:57 (6956): Status Report: Job Duration: '864000.000000'
2019-10-15 13:39:57 (6956): Status Report: Elapsed Time: '24000.747349'
2019-10-15 13:39:57 (6956): Status Report: CPU Time: '24005.109375'
2019-10-15 13:52:56 (6956): VM state change detected. (old = 'Running', new = 'Paused')
2019-10-15 14:00:40 (6956): VM state change detected. (old = 'Paused', new = 'Running')
2019-10-15 15:01:22 (6956): VM state change detected. (old = 'Running', new = 'Paused')
2019-10-15 15:09:06 (6956): VM state change detected. (old = 'Paused', new = 'Running')
2019-10-15 15:36:49 (6956): Status Report: Job Duration: '864000.000000'
2019-10-15 15:36:49 (6956): Status Report: Elapsed Time: '30001.370190'
2019-10-15 15:36:49 (6956): Status Report: CPU Time: '30086.109375'
2019-10-15 15:51:44 (6956): VM state change detected. (old = 'Running', new = 'Paused')
2019-10-15 15:55:38 (6956): VM state change detected. (old = 'Paused', new = 'Running')
2019-10-15 16:34:55 (6956): VM state change detected. (old = 'Running', new = 'Paused')
2019-10-15 16:39:59 (6956): VM state change detected. (old = 'Paused', new = 'Running')
2019-10-15 17:17:40 (6956): VM state change detected. (old = 'Running', new = 'Paused')
2019-10-15 17:21:43 (6956): VM state change detected. (old = 'Paused', new = 'Running')
2019-10-15 17:31:48 (6956): Status Report: Job Duration: '864000.000000'
2019-10-15 17:31:48 (6956): Status Report: Elapsed Time: '36002.131639'
2019-10-15 17:31:48 (6956): Status Report: CPU Time: '36191.328125'
2019-10-15 17:55:13 (6956): VM state change detected. (old = 'Running', new = 'Paused')
2019-10-15 17:59:14 (6956): VM state change detected. (old = 'Paused', new = 'Running')
2019-10-15 18:04:01 (6956): VM state change detected. (old = 'Running', new = 'Paused')
2019-10-15 18:06:08 (6956): VM state change detected. (old = 'Paused', new = 'Running')
2019-10-15 19:20:54 (6956): Status Report: Job Duration: '864000.000000'
2019-10-15 19:20:54 (6956): Status Report: Elapsed Time: '42002.573431'
2019-10-15 19:20:54 (6956): Status Report: CPU Time: '42345.640625'
2019-10-15 20:04:03 (6956): Guest Log: 02:45:06 CEST +02:00 2019-10-16: cranky: [INFO] Container 'runc' finished with status code 0.

2019-10-15 20:04:03 (6956): Guest Log: 02:45:06 CEST +02:00 2019-10-16: cranky: [INFO] Preparing output.

2019-10-15 20:04:07 (6956): Guest Log: [INFO] Job Finished

2019-10-15 20:04:07 (6956): Guest Log: [INFO] Shutting Down.

2019-10-15 20:04:07 (6956): VM Completion File Detected.
2019-10-15 20:04:07 (6956): VM Completion Message: Job Finished
.
2019-10-15 20:04:07 (6956): Powering off VM.
2019-10-15 20:09:08 (6956): VM did not power off when requested.
2019-10-15 20:09:08 (6956): VM was successfully terminated.
2019-10-15 20:09:08 (6956): Deregistering VM. (boinc_90a049bef096b374, slot#9)
2019-10-15 20:09:08 (6956): Removing network bandwidth throttle group from VM.
2019-10-15 20:09:09 (6956): Removing VM from VirtualBox.
20:09:14 (6956): called boinc_finish(0)

</stderr_txt>
]]>


©2024 CERN