Name | hjOODmyUQutnlyackoJh5iwnABFKDmABFKDmlqZaDmABFKDmYXnuOm_0 |
Workunit | 105253111 |
Created | 21 Dec 2018, 3:17:50 UTC |
Sent | 21 Dec 2018, 5:02:37 UTC |
Report deadline | 29 Dec 2018, 5:02:37 UTC |
Received | 21 Dec 2018, 7:59:21 UTC |
Server state | Over |
Outcome | Success |
Client state | Done |
Exit status | 0 (0x00000000) |
Computer ID | 10524082 |
Run time | 12 min 44 sec |
CPU time | 4 min 25 sec |
Validate state | Valid |
Credit | 134.99 |
Device peak FLOPS | 16.14 GFLOPS |
Application version | ATLAS Simulation v1.01 (vbox64_mt_mcore_atlas) windows_x86_64 |
Peak working set size | 114.89 MB |
Peak swap size | 125.09 MB |
Peak disk usage | 2.39 GB |
<core_client_version>7.8.3</core_client_version> <![CDATA[ <stderr_txt> 2018-12-21 15:33:48 (659484): vboxwrapper (7.7.26196): starting 2018-12-21 15:33:48 (659484): Feature: Checkpoint interval offset (34 seconds) 2018-12-21 15:33:48 (659484): Detected: VirtualBox COM Interface (Version: 5.1.26) 2018-12-21 15:33:48 (659484): Detected: Minimum checkpoint interval (900.000000 seconds) 2018-12-21 15:33:48 (659484): Successfully copied 'init_data.xml' to the shared directory. 2018-12-21 15:33:48 (659484): Create VM. (boinc_4342fa2cc39b93b1, slot#3) 2018-12-21 15:33:48 (659484): Setting Memory Size for VM. (6600MB) 2018-12-21 15:33:48 (659484): Setting CPU Count for VM. (4) 2018-12-21 15:33:48 (659484): Setting Chipset Options for VM. 2018-12-21 15:33:48 (659484): Setting Boot Options for VM. 2018-12-21 15:33:48 (659484): Enabling VM Network Access. 2018-12-21 15:33:48 (659484): Setting Network Configuration for NAT. 2018-12-21 15:33:48 (659484): Disabling USB Support for VM. 2018-12-21 15:33:48 (659484): Disabling COM Port Support for VM. 2018-12-21 15:33:48 (659484): Disabling LPT Port Support for VM. 2018-12-21 15:33:48 (659484): Disabling Audio Support for VM. 2018-12-21 15:33:48 (659484): Disabling Clipboard Support for VM. 2018-12-21 15:33:48 (659484): Disabling Drag and Drop Support for VM. 2018-12-21 15:33:48 (659484): Adding storage controller(s) to VM. 2018-12-21 15:33:48 (659484): Adding virtual disk drive to VM. (vm_image.vdi) 2018-12-21 15:33:48 (659484): Adding VirtualBox Guest Additions to VM. 2018-12-21 15:33:48 (659484): Adding network bandwidth throttle group to VM. (Defaulting to 1024GB) 2018-12-21 15:33:48 (659484): forwarding host port 56225 to guest port 80 2018-12-21 15:33:48 (659484): Enabling remote desktop for VM. 2018-12-21 15:33:48 (659484): Required extension pack not installed, remote desktop not enabled. 2018-12-21 15:33:48 (659484): Enabling shared directory for VM. 2018-12-21 15:33:48 (659484): Starting VM. (boinc_4342fa2cc39b93b1, slot#3) 2018-12-21 15:34:01 (659484): Guest Log: BIOS: VirtualBox 5.1.26 2018-12-21 15:34:01 (659484): Guest Log: BIOS: ata0-0: PCHS=16383/16/63 LCHS=1024/255/63 2018-12-21 15:34:01 (659484): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032 2018-12-21 15:34:01 (659484): Guest Log: BIOS: Booting from Hard Disk... 2018-12-21 15:34:01 (659484): Guest Log: BIOS: KBD: unsupported int 16h function 03 2018-12-21 15:34:01 (659484): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 2018-12-21 15:34:01 (659484): Successfully started VM. (PID = '667440') 2018-12-21 15:34:01 (659484): Reporting VM Process ID to BOINC. 2018-12-21 15:34:11 (659484): VM state change detected. (old = 'poweroff', new = 'running') 2018-12-21 15:34:21 (659484): Detected: Web Application Enabled (http://localhost:56225) 2018-12-21 15:34:31 (659484): Guest Log: vboxguest: major 0, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000) 2018-12-21 15:34:31 (659484): VM state change detected. (old = 'running', new = 'paused') 2018-12-21 15:34:41 (659484): Preference change detected 2018-12-21 15:34:41 (659484): Setting CPU throttle for VM. (100%) 2018-12-21 15:34:41 (659484): Setting checkpoint interval to 900 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 900 seconds)) 2018-12-21 15:34:41 (659484): VM state change detected. (old = 'paused', new = 'running') 2018-12-21 15:34:51 (659484): VM state change detected. (old = 'running', new = 'paused') 2018-12-21 15:35:01 (659484): Guest Log: VBoxGuest: VBoxGuestCommonGuestCapsAcquire: pSession(0xffff8801ace48410), OR(0x0), NOT(0xffffffff), flags(0x0) 2018-12-21 15:35:01 (659484): Guest Log: VBoxGuest: VBoxGuestCommonGuestCapsAcquire: pSession(0xffff8801ae4cf010), OR(0x0), NOT(0xffffffff), flags(0x0) 2018-12-21 15:35:01 (659484): Guest Log: VBoxGuest: VBoxGuestCommonGuestCapsAcquire: pSession(0xffff8801ac3aa210), OR(0x0), NOT(0xffffffff), flags(0x0) 2018-12-21 15:35:01 (659484): Guest Log: VBoxGuest: VBoxGuestCommonGuestCapsAcquire: pSession(0xffff8801ac3aa210), OR(0x0), NOT(0xffffffff), flags(0x0) 2018-12-21 15:35:01 (659484): VM state change detected. (old = 'paused', new = 'running') 2018-12-21 15:35:32 (659484): VM state change detected. (old = 'running', new = 'paused') 2018-12-21 15:35:42 (659484): VM state change detected. (old = 'paused', new = 'running') 2018-12-21 15:35:52 (659484): VM state change detected. (old = 'running', new = 'paused') 2018-12-21 15:36:02 (659484): VM state change detected. (old = 'paused', new = 'running') 2018-12-21 15:36:23 (659484): VM state change detected. (old = 'running', new = 'paused') 2018-12-21 15:37:43 (659484): VM state change detected. (old = 'paused', new = 'running') 2018-12-21 15:37:53 (659484): VM state change detected. (old = 'running', new = 'paused') 2018-12-21 15:38:13 (659484): VM state change detected. (old = 'paused', new = 'running') 2018-12-21 15:38:33 (659484): Guest Log: Copying input files into RunAtlas. 2018-12-21 15:38:33 (659484): Guest Log: Copied input files into RunAtlas. 2018-12-21 15:39:14 (659484): VM state change detected. (old = 'running', new = 'paused') 2018-12-21 15:39:24 (659484): VM state change detected. (old = 'paused', new = 'running') 2018-12-21 15:39:34 (659484): Guest Log: copied the webapp to /var/www 2018-12-21 15:39:34 (659484): Guest Log: This vm does not need to setup http proxy 2018-12-21 15:39:34 (659484): Guest Log: ATHENA_PROC_NUMBER=4 2018-12-21 15:39:34 (659484): Guest Log: Starting ATLAS job. (PandaID=4186029746 taskID=16512119) 2018-12-21 15:39:35 (659484): VM state change detected. (old = 'running', new = 'paused') 2018-12-21 15:39:45 (659484): VM state change detected. (old = 'paused', new = 'running') 2018-12-21 15:39:55 (659484): VM state change detected. (old = 'running', new = 'paused') 2018-12-21 15:40:15 (659484): VM state change detected. (old = 'paused', new = 'running') 2018-12-21 15:40:46 (659484): VM state change detected. (old = 'running', new = 'paused') 2018-12-21 15:41:36 (659484): VM state change detected. (old = 'paused', new = 'running') 2018-12-21 15:42:17 (659484): VM state change detected. (old = 'running', new = 'paused') 2018-12-21 15:44:37 (659484): VM state change detected. (old = 'paused', new = 'running') 2018-12-21 15:44:58 (659484): VM state change detected. (old = 'running', new = 'paused') 2018-12-21 15:46:58 (659484): VM state change detected. (old = 'paused', new = 'running') 2018-12-21 15:47:08 (659484): VM state change detected. (old = 'running', new = 'paused') 2018-12-21 15:47:58 (659484): VM state change detected. (old = 'paused', new = 'running') 2018-12-21 15:51:29 (659484): VM state change detected. (old = 'running', new = 'paused') 2018-12-21 15:51:39 (659484): VM state change detected. (old = 'paused', new = 'running') 2018-12-21 15:51:49 (659484): VM state change detected. (old = 'running', new = 'paused') 2018-12-21 15:52:29 (659484): VM state change detected. (old = 'paused', new = 'running') 2018-12-21 15:52:39 (659484): VM state change detected. (old = 'running', new = 'paused') 2018-12-21 15:52:50 (659484): VM state change detected. (old = 'paused', new = 'running') 2018-12-21 15:53:00 (659484): VM state change detected. (old = 'running', new = 'paused') 2018-12-21 15:53:10 (659484): VM state change detected. (old = 'paused', new = 'running') 2018-12-21 15:53:20 (659484): VM state change detected. (old = 'running', new = 'paused') 2018-12-21 15:53:50 (659484): VM state change detected. (old = 'paused', new = 'running') 2018-12-21 15:54:10 (659484): VM state change detected. (old = 'running', new = 'paused') 2018-12-21 15:54:50 (659484): VM state change detected. (old = 'paused', new = 'running') 2018-12-21 15:56:30 (659484): VM state change detected. (old = 'running', new = 'paused') 2018-12-21 15:56:50 (659484): VM state change detected. (old = 'paused', new = 'running') 2018-12-21 15:57:31 (659484): VM state change detected. (old = 'running', new = 'paused') 2018-12-21 15:57:51 (659484): VM state change detected. (old = 'paused', new = 'running') 2018-12-21 15:58:31 (659484): VM state change detected. (old = 'running', new = 'paused') 2018-12-21 15:58:41 (659484): VM state change detected. (old = 'paused', new = 'running') 2018-12-21 15:58:51 (659484): Guest Log: log_extracts: 2018-12-21 15:58:51 (659484): Guest Log: - Last 10 lines from /home/atlas01/RunAtlas/Panda_Pilot_3498_1545377853/PandaJob/athena_stdout.txt - 2018-12-21 15:58:51 (659484): Guest Log: PyJobTransforms.trfExe.preExecute 2018-12-21 17:41:02,379 INFO Batch/grid running - command outputs will not be echoed. Logs for EVNTtoHITS are in log.EVNTtoHITS 2018-12-21 15:58:51 (659484): Guest Log: PyJobTransforms.trfExe.preExecute 2018-12-21 17:41:02,382 INFO Now writing wrapper for substep executor EVNTtoHITS 2018-12-21 15:58:51 (659484): Guest Log: PyJobTransforms.trfExe._writeAthenaWrapper 2018-12-21 17:41:02,383 INFO Valgrind not engaged 2018-12-21 15:58:51 (659484): Guest Log: PyJobTransforms.trfExe.preExecute 2018-12-21 17:41:02,383 INFO Athena will be executed in a subshell via ['./runwrapper.EVNTtoHITS.sh'] 2018-12-21 15:58:51 (659484): Guest Log: PyJobTransforms.trfExe.execute 2018-12-21 17:41:02,383 INFO Starting execution of EVNTtoHITS (['./runwrapper.EVNTtoHITS.sh']) 2018-12-21 15:58:51 (659484): Guest Log: PyJobTransforms.trfExe.execute 2018-12-21 17:44:00,951 INFO EVNTtoHITS executor returns 33 2018-12-21 15:58:51 (659484): Guest Log: PyJobTransforms.trfExe.validate 2018-12-21 17:44:02,043 ERROR Validation of return code failed: Non-zero return code from EVNTtoHITS (33) (Error code 65) 2018-12-21 15:58:51 (659484): Guest Log: PyJobTransforms.trfExe.validate 2018-12-21 17:44:02,077 INFO Scanning logfile log.EVNTtoHITS for errors 2018-12-21 15:58:51 (659484): Guest Log: PyJobTransforms.transform.execute 2018-12-21 17:44:02,109 CRITICAL Transform executor raised TransformValidationException: Non-zero return code from EVNTtoHITS (33); Logfile error in log.EVNTtoHITS: "IOVDbSvc FATAL Conditions database connection COOLOFL_TRT/OFLP200 cannot be opened - STOP" 2018-12-21 15:58:51 (659484): Guest Log: PyJobTransforms.transform.execute 2018-12-21 17:44:05,291 WARNING Transform now exiting early with exit code 65 (Non-zero return code from EVNTtoHITS (33); Logfile error in log.EVNTtoHITS: "IOVDbSvc FATAL Conditions database connection COOLOFL_TRT/OFLP200 cannot be opened - STOP") 2018-12-21 15:58:51 (659484): Guest Log: - Walltime - 2018-12-21 15:58:51 (659484): Guest Log: JobRetrival=1, StageIn=10, Execution=233, StageOut=0, CleanUp=10 2018-12-21 15:58:51 (659484): Guest Log: The last 10 lines of the pilot log. 2018-12-21 15:58:51 (659484): Guest Log: <lfn name="log.16512119._008805.job.log.tgz.1"/> 2018-12-21 15:58:51 (659484): Guest Log: </logical> 2018-12-21 15:58:51 (659484): Guest Log: <metadata att_name="surl" att_value="srm://srm.ndgf.org:8443/srm/managerv2?SFN=/atlas/disk/atlasdatadisk/rucio/mc16_13TeV/db/83/log.16512119._008805.job.log.tgz.1"/> 2018-12-21 15:58:51 (659484): Guest Log: <metadata att_name="fsize" att_value="66309"/> 2018-12-21 15:58:51 (659484): Guest Log: <metadata att_name="adler32" att_value="eac34549"/> 2018-12-21 15:58:51 (659484): Guest Log: </File> 2018-12-21 15:58:51 (659484): Guest Log: </POOLFILECATALOG> 2018-12-21 15:58:51 (659484): Guest Log: --------- 2018-12-21 15:58:51 (659484): Guest Log: output list 2018-12-21 15:58:51 (659484): Guest Log: log.16512119._008805.job.log.tgz.1 srm://srm.ndgf.org:8443;autodir=no;spacetoken=ATLASDATADISK/srm/managerv2?SFN=/atlas/disk/atlasdatadisk/rucio/mc16_13TeV/db/83/log.16512119._008805.job.log.tgz.1:checksumtype=adler32:checksumvalue=eac34549 2018-12-21 15:58:51 (659484): Guest Log: No HITS file was produced 2018-12-21 15:58:51 (659484): Guest Log: total 205028 2018-12-21 15:58:51 (659484): Guest Log: -rw-r--r-- 1 atlas01 atlas01 4436 Dec 21 13:15 queuedata.pilot.json 2018-12-21 15:58:51 (659484): Guest Log: -rwx------ 1 atlas01 atlas01 2441 Dec 21 13:17 ARCpilot 2018-12-21 15:58:51 (659484): Guest Log: -rwxr-xr-x 1 atlas01 atlas01 195102254 Dec 21 17:36 EVNT.15754982._000193.pool.root.1 2018-12-21 15:58:51 (659484): Guest Log: -rwxr-xr-x 1 atlas01 atlas01 8446 Dec 21 17:36 init_data.xml 2018-12-21 15:58:51 (659484): Guest Log: -rwxr-xr-x 1 atlas01 atlas01 606 Dec 21 17:36 RTE.tar.gz 2018-12-21 15:58:51 (659484): Guest Log: -rwxr-xr-x 1 atlas01 atlas01 1088718 Dec 21 17:36 input.tar.gz 2018-12-21 15:58:51 (659484): Guest Log: -rwxr-xr-x 1 atlas01 atlas01 14291 Dec 21 17:36 start_atlas.sh 2018-12-21 15:58:51 (659484): Guest Log: drwxr-xr-x 3 atlas01 atlas01 4096 Dec 21 17:37 APPS 2018-12-21 15:58:51 (659484): Guest Log: -rw------- 1 atlas01 atlas01 2926 Dec 21 17:37 pandaJobData.out 2018-12-21 15:58:51 (659484): Guest Log: -rw------- 1 atlas01 atlas01 22 Dec 21 17:37 PILOT_INITDIR 2018-12-21 15:58:51 (659484): Guest Log: -rw------- 1 atlas01 atlas01 50 Dec 21 17:38 CURRENT_SITEWORKDIR 2018-12-21 15:58:51 (659484): Guest Log: -rw------- 1 atlas01 atlas01 11431 Dec 21 17:38 pilotlog.txt 2018-12-21 15:58:51 (659484): Guest Log: -rw------- 1 atlas01 atlas01 1 Dec 21 17:38 STATUSCODE 2018-12-21 15:58:51 (659484): Guest Log: -rw------- 1 atlas01 atlas01 11 Dec 21 17:38 pandaIDs.out 2018-12-21 15:58:51 (659484): Guest Log: -rw------- 1 atlas01 atlas01 19 Dec 21 17:38 START_TIME_4186029746 2018-12-21 15:58:51 (659484): Guest Log: -rw------- 1 atlas01 atlas01 3801 Dec 21 17:38 queuedata.json 2018-12-21 15:58:51 (659484): Guest Log: -rw------- 1 atlas01 atlas01 2926 Dec 21 17:38 pandaJobData_1.out 2018-12-21 15:58:51 (659484): Guest Log: -rw------- 1 atlas01 atlas01 7158983 Dec 21 17:40 agis_ddmendpoints.cvmfs.json 2018-12-21 15:58:51 (659484): Guest Log: -rw------- 1 atlas01 atlas01 5694590 Dec 21 17:40 agis_schedconf.cvmfs.json 2018-12-21 15:58:51 (659484): Guest Log: -rw------- 1 atlas01 atlas01 302 Dec 21 17:44 memory_monitor_summary.json 2018-12-21 15:58:51 (659484): Guest Log: -rw------- 1 atlas01 atlas01 203 Dec 21 17:44 pilot_error_report.json 2018-12-21 15:58:51 (659484): Guest Log: -rw------- 1 atlas01 atlas01 46 Dec 21 17:45 workdir_size-4186029746.json 2018-12-21 15:58:51 (659484): Guest Log: -rw------- 1 atlas01 atlas01 66309 Dec 21 17:45 log.16512119._008805.job.log.tgz.1 2018-12-21 15:58:51 (659484): Guest Log: -rw------- 1 atlas01 atlas01 241 Dec 21 17:45 output.list 2018-12-21 15:58:51 (659484): Guest Log: -rw------- 1 atlas01 atlas01 1756 Dec 21 17:45 log_extracts.txt 2018-12-21 15:58:51 (659484): Guest Log: -rw------- 1 atlas01 atlas01 12145 Dec 21 17:45 panda_node_struct.pickle 2018-12-21 15:58:51 (659484): Guest Log: -rw------- 1 atlas01 atlas01 137 Dec 21 17:46 pilotlog-last.txt 2018-12-21 15:58:51 (659484): Guest Log: -rw------- 1 atlas01 atlas01 598 Dec 21 17:46 metadata-surl.xml 2018-12-21 15:58:51 (659484): Guest Log: -rw------- 1 atlas01 atlas01 189192 Dec 21 17:46 log.16512119._008805.job.log.1 2018-12-21 15:58:51 (659484): Guest Log: -rw------- 1 atlas01 atlas01 4163 Dec 21 17:46 jobSmallFiles.tgz 2018-12-21 15:58:51 (659484): Guest Log: -rw------- 1 atlas01 atlas01 496 Dec 21 17:46 hjOODmyUQutnlyackoJh5iwnABFKDmABFKDmlqZaDmABFKDmYXnuOm.diag 2018-12-21 15:58:51 (659484): Guest Log: -rw-r--r-- 1 atlas01 atlas01 10052 Dec 21 17:46 runtime_log.err 2018-12-21 15:58:51 (659484): Guest Log: -rw-r--r-- 1 atlas01 atlas01 604 Dec 21 17:46 runtime_log 2018-12-21 15:58:51 (659484): Guest Log: -rw------- 1 atlas01 atlas01 460800 Dec 21 17:46 result.tar.gz 2018-12-21 15:58:51 (659484): Guest Log: Successfully finished the ATLAS job! 2018-12-21 15:58:51 (659484): Guest Log: Copying the results back to the shared directory! 2018-12-21 15:58:51 (659484): Guest Log: Copied the result file back to the shared directory and created atlas_done file! 2018-12-21 15:58:51 (659484): Guest Log: Success! Shutting down the machine. 2018-12-21 15:58:51 (659484): VM Completion File Detected. 2018-12-21 15:58:51 (659484): Powering off VM. 2018-12-21 15:58:52 (659484): Successfully stopped VM. 2018-12-21 15:58:57 (659484): Deregistering VM. (boinc_4342fa2cc39b93b1, slot#3) 2018-12-21 15:58:57 (659484): Removing virtual disk drive(s) from VM. 2018-12-21 15:58:57 (659484): Removing network bandwidth throttle group from VM. 2018-12-21 15:58:57 (659484): Removing storage controller(s) from VM. 2018-12-21 15:58:58 (659484): Removing VM from VirtualBox. 15:59:03 (659484): called boinc_finish(0) </stderr_txt> ]]>
©2024 CERN