| Name | l55NDmZ1NxunShfckohDCDFpABFKDmABFKDmgaTTDmABFKDms3eqom_0 |
| Workunit | 1905417 |
| Created | 18 Jun 2019, 7:48:05 UTC |
| Sent | 19 Jun 2019, 10:23:05 UTC |
| Report deadline | 26 Jun 2019, 10:23:05 UTC |
| Received | 20 Jun 2019, 12:13:09 UTC |
| Server state | Over |
| Outcome | Success |
| Client state | Done |
| Exit status | 0 (0x00000000) |
| Computer ID | 1113 |
| Run time | 1 hours 15 min 5 sec |
| CPU time | 16 min 51 sec |
| Validate state | Valid |
| Credit | 338.06 |
| Device peak FLOPS | 13.30 GFLOPS |
| Application version | ATLAS Simulation v0.60 (vbox64_mt_mcore_atlas) windows_x86_64 |
| Peak working set size | 106.31 MB |
| Peak swap size | 156.48 MB |
| Peak disk usage | 1.98 GB |
Stderr output
<core_client_version>7.14.2</core_client_version>
<![CDATA[
<stderr_txt>
2019-06-19 12:24:49 (341616): vboxwrapper (7.9.26200): starting
2019-06-19 12:24:52 (341616): Feature: Checkpoint interval offset (27 seconds)
2019-06-19 12:24:52 (341616): Detected: VirtualBox COM Interface (Version: 5.2.18)
2019-06-19 12:24:52 (341616): Detected: Minimum checkpoint interval (900.000000 seconds)
2019-06-19 12:24:52 (341616): Successfully copied 'init_data.xml' to the shared directory.
2019-06-19 12:24:52 (341616): Create VM. (boinc_a9af4aaa0fa468b3, slot#11)
2019-06-19 12:24:53 (341616): Setting Memory Size for VM. (6600MB)
2019-06-19 12:24:53 (341616): Setting CPU Count for VM. (4)
2019-06-19 12:24:53 (341616): Setting Chipset Options for VM.
2019-06-19 12:24:53 (341616): Setting Boot Options for VM.
2019-06-19 12:24:53 (341616): Enabling VM Network Access.
2019-06-19 12:24:53 (341616): Setting Network Configuration for NAT.
2019-06-19 12:24:53 (341616): Disabling USB Support for VM.
2019-06-19 12:24:53 (341616): Disabling COM Port Support for VM.
2019-06-19 12:24:53 (341616): Disabling LPT Port Support for VM.
2019-06-19 12:24:53 (341616): Disabling Audio Support for VM.
2019-06-19 12:24:53 (341616): Disabling Clipboard Support for VM.
2019-06-19 12:24:53 (341616): Disabling Drag and Drop Support for VM.
2019-06-19 12:24:53 (341616): Adding storage controller(s) to VM.
2019-06-19 12:24:53 (341616): Adding virtual disk drive to VM. (vm_image.vdi)
2019-06-19 12:24:57 (341616): Adding VirtualBox Guest Additions to VM.
2019-06-19 12:24:57 (341616): Adding network bandwidth throttle group to VM. (Defaulting to 1024GB)
2019-06-19 12:24:57 (341616): forwarding host port 52125 to guest port 80
2019-06-19 12:24:57 (341616): Enabling remote desktop for VM.
2019-06-19 12:24:57 (341616): Enabling shared directory for VM.
2019-06-19 12:24:57 (341616): Starting VM. (boinc_a9af4aaa0fa468b3, slot#11)
2019-06-19 12:30:01 (341616): Successfully started VM. (PID = '341616')
2019-06-19 12:30:01 (341616): Reporting VM Process ID to BOINC.
2019-06-19 12:35:01 (341616): VM is no longer is a running state. It is in 'poweroff'.
2019-06-19 12:35:06 (341616):
NOTE: VM failed to enter an online state within the timeout period.
This might be a temporary problem and so this job will be rescheduled for another time.
Hypervisor System Log:
00:09:52.946765 ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={85cd948e-a71f-4289-281e-0ca7ad48cd89} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
00:09:53.946766 ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={85cd948e-a71f-4289-281e-0ca7ad48cd89} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
00:09:53.946766 ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={85cd948e-a71f-4289-281e-0ca7ad48cd89} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
00:09:54.946768 ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={85cd948e-a71f-4289-281e-0ca7ad48cd89} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
00:09:54.946768 ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={85cd948e-a71f-4289-281e-0ca7ad48cd89} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
00:09:55.955770 ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={85cd948e-a71f-4289-281e-0ca7ad48cd89} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
00:09:55.955770 ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={85cd948e-a71f-4289-281e-0ca7ad48cd89} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
00:09:56.956787 ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={85cd948e-a71f-4289-281e-0ca7ad48cd89} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
00:09:56.956787 ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={85cd948e-a71f-4289-281e-0ca7ad48cd89} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
00:09:57.958802 ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={85cd948e-a71f-4289-281e-0ca7ad48cd89} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
00:09:57.958802 ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={85cd948e-a71f-4289-281e-0ca7ad48cd89} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
00:09:58.961811 ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={85cd948e-a71f-4289-281e-0ca7ad48cd89} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
00:09:58.961811 ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={85cd948e-a71f-4289-281e-0ca7ad48cd89} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
00:09:59.962815 ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={85cd948e-a71f-4289-281e-0ca7ad48cd89} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
00:09:59.962815 ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={85cd948e-a71f-4289-281e-0ca7ad48cd89} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
00:10:00.966817 ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={85cd948e-a71f-4289-281e-0ca7ad48cd89} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
00:10:00.966817 ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={85cd948e-a71f-4289-281e-0ca7ad48cd89} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
00:10:01.966818 ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={85cd948e-a71f-4289-281e-0ca7ad48cd89} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
00:10:01.966818 ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={85cd948e-a71f-4289-281e-0ca7ad48cd89} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
00:10:02.966820 ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={85cd948e-a71f-4289-281e-0ca7ad48cd89} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
00:10:02.966820 ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={85cd948e-a71f-4289-281e-0ca7ad48cd89} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
00:10:03.967822 ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={85cd948e-a71f-4289-281e-0ca7ad48cd89} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
00:10:03.967822 ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={85cd948e-a71f-4289-281e-0ca7ad48cd89} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
00:10:04.968823 ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={85cd948e-a71f-4289-281e-0ca7ad48cd89} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
00:10:04.968823 ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={85cd948e-a71f-4289-281e-0ca7ad48cd89} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
00:10:05.969832 ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={85cd948e-a71f-4289-281e-0ca7ad48cd89} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
00:10:05.969832 ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={85cd948e-a71f-4289-281e-0ca7ad48cd89} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
00:10:06.970836 ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={85cd948e-a71f-4289-281e-0ca7ad48cd89} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
00:10:06.970836 ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={85cd948e-a71f-4289-281e-0ca7ad48cd89} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
00:10:07.977847 ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={85cd948e-a71f-4289-281e-0ca7ad48cd89} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
00:10:07.977847 ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={85cd948e-a71f-4289-281e-0ca7ad48cd89} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
00:10:08.981855 ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={85cd948e-a71f-4289-281e-0ca7ad48cd89} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
00:10:08.981855 ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={85cd948e-a71f-4289-281e-0ca7ad48cd89} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
00:10:09.990371 ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={85cd948e-a71f-4289-281e-0ca7ad48cd89} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
00:10:09.990371 ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={85cd948e-a71f-4289-281e-0ca7ad48cd89} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
00:10:11.104922 ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={85cd948e-a71f-4289-281e-0ca7ad48cd89} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
00:10:11.104922 ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={85cd948e-a71f-4289-281e-0ca7ad48cd89} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
VM Execution Log:
VM Startup Log:
VM Trace Log:
VM Exit Code: 259 (0x103)
2019-06-20 12:57:49 (412056): vboxwrapper (7.9.26200): starting
2019-06-20 12:57:52 (412056): Feature: Checkpoint interval offset (39 seconds)
2019-06-20 12:57:52 (412056): Detected: VirtualBox COM Interface (Version: 5.2.18)
2019-06-20 12:57:52 (412056): Detected: Minimum checkpoint interval (900.000000 seconds)
2019-06-20 12:57:52 (412056): Starting VM. (boinc_a9af4aaa0fa468b3, slot#11)
2019-06-20 12:58:04 (412056): Guest Log: BIOS: VirtualBox 5.2.18
2019-06-20 12:58:04 (412056): Guest Log: CPUID EDX: 0x178bfbff
2019-06-20 12:58:04 (412056): Guest Log: BIOS: ata0-0: PCHS=16383/16/63 LCHS=1024/255/63
2019-06-20 12:58:04 (412056): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032
2019-06-20 12:58:04 (412056): Guest Log: BIOS: Booting from Hard Disk...
2019-06-20 12:58:04 (412056): Successfully started VM. (PID = '407880')
2019-06-20 12:58:05 (412056): Reporting VM Process ID to BOINC.
2019-06-20 12:58:10 (412056): Guest Log: BIOS: KBD: unsupported int 16h function 03
2019-06-20 12:58:10 (412056): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000
2019-06-20 12:58:10 (412056): VM state change detected. (old = 'poweroff', new = 'running')
2019-06-20 12:58:21 (412056): Preference change detected
2019-06-20 12:58:21 (412056): Setting CPU throttle for VM. (100%)
2019-06-20 12:58:21 (412056): Setting checkpoint interval to 900 seconds. (Higher value of (Preference: 600 seconds) or (Vbox_job.xml: 900 seconds))
2019-06-20 12:58:32 (412056): Guest Log: vboxguest: major 0, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000)
2019-06-20 12:58:52 (412056): Guest Log: VBoxGuest: VBoxGuestCommonGuestCapsAcquire: pSession(0xffff8801ae693610), OR(0x0), NOT(0xffffffff), flags(0x0)
2019-06-20 12:58:52 (412056): Guest Log: VBoxGuest: VBoxGuestCommonGuestCapsAcquire: pSession(0xffff8801aed39010), OR(0x0), NOT(0xffffffff), flags(0x0)
2019-06-20 12:58:52 (412056): Guest Log: VBoxGuest: VBoxGuestCommonGuestCapsAcquire: pSession(0xffff8801ae693e10), OR(0x0), NOT(0xffffffff), flags(0x0)
2019-06-20 12:58:52 (412056): Guest Log: VBoxGuest: VBoxGuestCommonGuestCapsAcquire: pSession(0xffff8801ac21a810), OR(0x0), NOT(0xffffffff), flags(0x0)
2019-06-20 13:00:23 (412056): Guest Log: Copying input files into RunAtlas.
2019-06-20 13:00:53 (412056): Guest Log: Copied input files into RunAtlas.
2019-06-20 13:01:28 (412056): Guest Log: copied the webapp to /var/www
2019-06-20 13:01:28 (412056): Guest Log: This vm does not need to setup http proxy
2019-06-20 13:01:28 (412056): Guest Log: ATHENA_PROC_NUMBER=4
2019-06-20 13:01:28 (412056): Guest Log: Starting ATLAS job. (PandaID=4382533415 taskID=18251740)
2019-06-20 14:12:34 (412056): Guest Log: The last 10 lines of the pilot log.
2019-06-20 14:12:34 (412056): Guest Log: drwx------ 14 atlas01 atlas01 4096 Jun 20 13:02 pilot
2019-06-20 14:12:34 (412056): Guest Log: -rwx------ 1 atlas01 atlas01 21401 Jun 7 14:22 pilot.py
2019-06-20 14:12:34 (412056): Guest Log: -rw------- 1 atlas01 atlas01 9 Jun 18 02:07 PILOTVERSION
2019-06-20 14:12:34 (412056): Guest Log: -rw------- 1 atlas01 atlas01 2251 Jun 3 14:26 README.md
2019-06-20 14:12:34 (412056): Guest Log: -rw------- 1 atlas01 atlas01 221 Jun 3 14:26 TODO.md
2019-06-20 14:12:34 (412056): Guest Log: -rw------- 1 atlas01 atlas01 201 Jun 10 22:47 #tt#
2019-06-20 14:12:34 (412056): Guest Log: 2019-06-20 12:12:33 UTC [wrapper] Test setup, not cleaning
2019-06-20 14:12:34 (412056): Guest Log: 2019-06-20 12:12:34 UTC [wrapper] ==== wrapper stdout END ====
2019-06-20 14:12:34 (412056): Guest Log: 2019-06-20 12:12:34 UTC [wrapper] ==== wrapper stderr END ====
2019-06-20 14:12:34 (412056): Guest Log: Listing of results directory
2019-06-20 14:12:34 (412056): Guest Log: total 380820
2019-06-20 14:12:34 (412056): Guest Log: drwx------ 3 atlas01 atlas01 4096 Jun 11 00:38 pilot2
2019-06-20 14:12:34 (412056): Guest Log: -rw-r--r-- 1 atlas01 atlas01 4468 Jun 18 09:45 queuedata.json
2019-06-20 14:12:34 (412056): Guest Log: -rw-r--r-- 1 atlas01 atlas01 241086 Jun 18 09:47 pilot2.tar.gz
2019-06-20 14:12:34 (412056): Guest Log: -rwx------ 1 atlas01 atlas01 15232 Jun 18 09:47 runpilot2-wrapper.sh
2019-06-20 14:12:34 (412056): Guest Log: -rw------- 1 atlas01 atlas01 2886 Jun 18 09:47 pandaJobData.out
2019-06-20 14:12:34 (412056): Guest Log: -rwxr-xr-x 1 atlas01 atlas01 376031675 Jun 20 13:00 EVNT.17323996._000618.pool.root.1
2019-06-20 14:12:34 (412056): Guest Log: -rwxr-xr-x 1 atlas01 atlas01 10358 Jun 20 13:00 init_data.xml
2019-06-20 14:12:34 (412056): Guest Log: -rwxr-xr-x 1 atlas01 atlas01 250179 Jun 20 13:00 input.tar.gz
2019-06-20 14:12:34 (412056): Guest Log: -rwxr-xr-x 1 atlas01 atlas01 786 Jun 20 13:00 RTE.tar.gz
2019-06-20 14:12:34 (412056): Guest Log: -rwxr-xr-x 1 atlas01 atlas01 8659 Jun 20 13:00 start_atlas.sh
2019-06-20 14:12:34 (412056): Guest Log: drwxr-xr-x 3 atlas01 atlas01 4096 Jun 20 13:01 APPS
2019-06-20 14:12:34 (412056): Guest Log: -rw------- 1 atlas01 atlas01 243 Jun 20 13:01 setup.sh.local
2019-06-20 14:12:34 (412056): Guest Log: -rw------- 1 atlas01 atlas01 4711791 Jun 20 13:02 agis_schedconf.cvmfs.json
2019-06-20 14:12:34 (412056): Guest Log: -rw------- 1 atlas01 atlas01 7321401 Jun 20 13:02 agis_ddmendpoints.json
2019-06-20 14:12:34 (412056): Guest Log: -rw------- 1 atlas01 atlas01 296 Jun 20 14:11 memory_monitor_summary.json
2019-06-20 14:12:34 (412056): Guest Log: -rw------- 1 atlas01 atlas01 62949 Jun 20 14:11 log.18251740._031276.job.log.tgz.1
2019-06-20 14:12:34 (412056): Guest Log: drwxrwx--- 2 atlas01 atlas01 4096 Jun 20 14:11 PanDA_Pilot-4382533415
2019-06-20 14:12:34 (412056): Guest Log: -rw------- 1 atlas01 atlas01 9486 Jun 20 14:11 heartbeat.json
2019-06-20 14:12:34 (412056): Guest Log: -rw------- 1 atlas01 atlas01 266438 Jun 20 14:12 pilotlog.txt
2019-06-20 14:12:34 (412056): Guest Log: -rw------- 1 atlas01 atlas01 282654 Jun 20 14:12 log.18251740._031276.job.log.1
2019-06-20 14:12:34 (412056): Guest Log: -rw------- 1 atlas01 atlas01 491 Jun 20 14:12 l55NDmZ1NxunShfckohDCDFpABFKDmABFKDmgaTTDmABFKDms3eqom.diag
2019-06-20 14:12:34 (412056): Guest Log: -rw-r--r-- 1 atlas01 atlas01 7532 Jun 20 14:12 runtime_log.err
2019-06-20 14:12:34 (412056): Guest Log: -rw------- 1 atlas01 atlas01 205 Jun 20 14:12 output.list
2019-06-20 14:12:34 (412056): Guest Log: -rw-r--r-- 1 atlas01 atlas01 692 Jun 20 14:12 runtime_log
2019-06-20 14:12:34 (412056): Guest Log: -rw------- 1 atlas01 atlas01 645120 Jun 20 14:12 result.tar.gz
2019-06-20 14:12:39 (412056): Guest Log: No HITS file was produced
2019-06-20 14:12:39 (412056): Guest Log: Successfully finished the ATLAS job!
2019-06-20 14:12:39 (412056): Guest Log: Copying the results back to the shared directory!
2019-06-20 14:12:39 (412056): Guest Log: Copied the result file back to the shared directory and created atlas_done file!
2019-06-20 14:12:39 (412056): Guest Log: Success! Shutting down the machine.
2019-06-20 14:12:39 (412056): VM Completion File Detected.
2019-06-20 14:12:39 (412056): Powering off VM.
2019-06-20 14:12:42 (412056): Successfully stopped VM.
2019-06-20 14:12:47 (412056): Deregistering VM. (boinc_a9af4aaa0fa468b3, slot#11)
2019-06-20 14:12:47 (412056): Removing virtual disk drive(s) from VM.
2019-06-20 14:12:47 (412056): Removing network bandwidth throttle group from VM.
2019-06-20 14:12:47 (412056): Removing storage controller(s) from VM.
2019-06-20 14:12:47 (412056): Removing VM from VirtualBox.
14:12:52 (412056): called boinc_finish(0)
</stderr_txt>
]]>
©2024 CERN