Stderr output

<core_client_version>7.14.2</core_client_version>
<![CDATA[
<stderr_txt>
2019-07-16 16:12:05 (24196): vboxwrapper (7.9.26200): starting
2019-07-16 16:12:05 (24196): Feature: Checkpoint interval offset (247 seconds)
2019-07-16 16:12:05 (24196): Detected: VirtualBox COM Interface (Version: 5.2.8)
2019-07-16 16:12:05 (24196): Detected: Minimum checkpoint interval (900.000000 seconds)
2019-07-16 16:12:05 (24196): Successfully copied 'init_data.xml' to the shared directory.
2019-07-16 16:12:05 (24196): Create VM. (boinc_496899da936d8b4d, slot#12)
2019-07-16 16:12:05 (24196): Setting Memory Size for VM. (6600MB)
2019-07-16 16:12:05 (24196): Setting CPU Count for VM. (4)
2019-07-16 16:12:05 (24196): Setting Chipset Options for VM.
2019-07-16 16:12:05 (24196): Setting Boot Options for VM.
2019-07-16 16:12:05 (24196): Enabling VM Network Access.
2019-07-16 16:12:05 (24196): Setting Network Configuration for NAT.
2019-07-16 16:12:05 (24196): Disabling USB Support for VM.
2019-07-16 16:12:05 (24196): Disabling COM Port Support for VM.
2019-07-16 16:12:05 (24196): Disabling LPT Port Support for VM.
2019-07-16 16:12:05 (24196): Disabling Audio Support for VM.
2019-07-16 16:12:05 (24196): Disabling Clipboard Support for VM.
2019-07-16 16:12:05 (24196): Disabling Drag and Drop Support for VM.
2019-07-16 16:12:05 (24196): Adding storage controller(s) to VM.
2019-07-16 16:12:05 (24196): Adding virtual disk drive to VM. (vm_image.vdi)
2019-07-16 16:12:06 (24196): Adding VirtualBox Guest Additions to VM.
2019-07-16 16:12:06 (24196): Adding network bandwidth throttle group to VM. (Defaulting to 1024GB)
2019-07-16 16:12:06 (24196): forwarding host port 64709 to guest port 80
2019-07-16 16:12:06 (24196): Enabling remote desktop for VM.
2019-07-16 16:12:06 (24196): Required extension pack not installed, remote desktop not enabled.
2019-07-16 16:12:06 (24196): Enabling shared directory for VM.
2019-07-16 16:12:06 (24196): Starting VM. (boinc_496899da936d8b4d, slot#12)
2019-07-16 16:12:15 (24196): Guest Log: BIOS: VirtualBox 5.2.8

2019-07-16 16:12:15 (24196): Guest Log: CPUID EDX: 0x178bfbff

2019-07-16 16:12:15 (24196): Guest Log: BIOS: ata0-0: PCHS=16383/16/63 LCHS=1024/255/63

2019-07-16 16:12:15 (24196): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032

2019-07-16 16:12:15 (24196): Guest Log: BIOS: Booting from Hard Disk...

2019-07-16 16:12:15 (24196): Successfully started VM. (PID = '22240')
2019-07-16 16:12:15 (24196): Reporting VM Process ID to BOINC.
2019-07-16 16:12:20 (24196): Guest Log: BIOS: KBD: unsupported int 16h function 03

2019-07-16 16:12:20 (24196): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 

2019-07-16 16:12:20 (24196): VM state change detected. (old = 'poweroff', new = 'running')
2019-07-16 16:12:25 (24196): Detected: Web Application Enabled (http://localhost:64709)
2019-07-16 16:12:30 (24196): Preference change detected
2019-07-16 16:12:30 (24196): Setting CPU throttle for VM. (100%)
2019-07-16 16:12:30 (24196): Setting checkpoint interval to 900 seconds. (Higher value of (Preference: 600 seconds) or (Vbox_job.xml: 900 seconds))
2019-07-16 16:12:55 (24196): Guest Log: vboxguest: major 0, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000)

2019-07-16 16:13:05 (24196): Guest Log: VBoxGuest: VBoxGuestCommonGuestCapsAcquire: pSession(0xffff8801ace0e010), OR(0x0), NOT(0xffffffff), flags(0x0)

2019-07-16 16:13:05 (24196): Guest Log: VBoxGuest: VBoxGuestCommonGuestCapsAcquire: pSession(0xffff8801aed58810), OR(0x0), NOT(0xffffffff), flags(0x0)

2019-07-16 16:13:05 (24196): Guest Log: VBoxGuest: VBoxGuestCommonGuestCapsAcquire: pSession(0xffff8801aec29610), OR(0x0), NOT(0xffffffff), flags(0x0)

2019-07-16 16:13:05 (24196): Guest Log: VBoxGuest: VBoxGuestCommonGuestCapsAcquire: pSession(0xffff8801aed58c10), OR(0x0), NOT(0xffffffff), flags(0x0)

2019-07-16 16:14:20 (24196): Guest Log: Copying input files into RunAtlas.

2019-07-16 16:14:30 (24196): Guest Log: Copied input files into RunAtlas.

2019-07-16 16:15:00 (24196): Guest Log: copied the webapp to /var/www

2019-07-16 16:15:00 (24196): Guest Log: This vm does not need to setup http proxy

2019-07-16 16:15:00 (24196): Guest Log: ATHENA_PROC_NUMBER=4

2019-07-16 16:15:00 (24196): Guest Log: Starting ATLAS job. (PandaID=4418186580 taskID=18604991)

2019-07-16 17:52:34 (24196): Status Report: Elapsed Time: '6004.265575'
2019-07-16 17:52:34 (24196): Status Report: CPU Time: '21029.515625'
2019-07-16 18:15:27 (24196): Stopping VM.
2019-07-16 18:16:00 (24196): Successfully stopped VM.
2019-07-16 18:20:15 (19076): vboxwrapper (7.9.26200): starting
2019-07-16 18:20:15 (19076): Feature: Checkpoint interval offset (541 seconds)
2019-07-16 18:20:15 (19076): Detected: VirtualBox COM Interface (Version: 5.2.8)
2019-07-16 18:20:15 (19076): Detected: Minimum checkpoint interval (900.000000 seconds)
2019-07-16 18:20:15 (19076): Guest Log: BIOS: VirtualBox 5.2.8

2019-07-16 18:20:15 (19076): Guest Log: CPUID EDX: 0x178bfbff

2019-07-16 18:20:15 (19076): Guest Log: BIOS: ata0-0: PCHS=16383/16/63 LCHS=1024/255/63

2019-07-16 18:20:15 (19076): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032

2019-07-16 18:20:15 (19076): Guest Log: BIOS: Booting from Hard Disk...

2019-07-16 18:20:15 (19076): Guest Log: BIOS: KBD: unsupported int 16h function 03

2019-07-16 18:20:15 (19076): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 

2019-07-16 18:20:15 (19076): Guest Log: vboxguest: major 0, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000)

2019-07-16 18:20:15 (19076): Guest Log: VBoxGuest: VBoxGuestCommonGuestCapsAcquire: pSession(0xffff8801ace0e010), OR(0x0), NOT(0xffffffff), flags(0x0)

2019-07-16 18:20:15 (19076): Guest Log: VBoxGuest: VBoxGuestCommonGuestCapsAcquire: pSession(0xffff8801aed58810), OR(0x0), NOT(0xffffffff), flags(0x0)

2019-07-16 18:20:15 (19076): Guest Log: VBoxGuest: VBoxGuestCommonGuestCapsAcquire: pSession(0xffff8801aec29610), OR(0x0), NOT(0xffffffff), flags(0x0)

2019-07-16 18:20:15 (19076): Guest Log: VBoxGuest: VBoxGuestCommonGuestCapsAcquire: pSession(0xffff8801aed58c10), OR(0x0), NOT(0xffffffff), flags(0x0)

2019-07-16 18:20:15 (19076): Guest Log: Copying input files into RunAtlas.

2019-07-16 18:20:15 (19076): Guest Log: Copied input files into RunAtlas.

2019-07-16 18:20:15 (19076): Guest Log: copied the webapp to /var/www

2019-07-16 18:20:15 (19076): Guest Log: This vm does not need to setup http proxy

2019-07-16 18:20:15 (19076): Guest Log: ATHENA_PROC_NUMBER=4

2019-07-16 18:20:15 (19076): Guest Log: Starting ATLAS job. (PandaID=4418186580 taskID=18604991)

2019-07-16 18:20:15 (19076): Starting VM. (boinc_496899da936d8b4d, slot#12)
2019-07-16 18:21:15 (19076): Successfully started VM. (PID = '14356')
2019-07-16 18:21:15 (19076): Reporting VM Process ID to BOINC.
2019-07-16 18:21:20 (19076): VM state change detected. (old = 'poweroff', new = 'running')
2019-07-16 18:21:25 (19076): Detected: Web Application Enabled (http://localhost:64709)
2019-07-16 18:21:30 (19076): Status Report: Elapsed Time: '7377.274645'
2019-07-16 18:21:30 (19076): Status Report: CPU Time: '26415.281250'
2019-07-16 18:21:30 (19076): Preference change detected
2019-07-16 18:21:30 (19076): Setting CPU throttle for VM. (100%)
2019-07-16 18:21:30 (19076): Setting checkpoint interval to 900 seconds. (Higher value of (Preference: 600 seconds) or (Vbox_job.xml: 900 seconds))
2019-07-16 20:01:34 (19076): Status Report: Elapsed Time: '13381.279640'
2019-07-16 20:01:34 (19076): Status Report: CPU Time: '50510.609375'
2019-07-16 20:21:36 (19076): Stopping VM.
2019-07-16 20:22:09 (19076): Successfully stopped VM.
2019-07-16 21:32:21 (11780): vboxwrapper (7.9.26200): starting
2019-07-16 21:32:21 (11780): Feature: Checkpoint interval offset (105 seconds)
2019-07-16 21:32:21 (11780): Detected: VirtualBox COM Interface (Version: 5.2.8)
2019-07-16 21:32:21 (11780): Detected: Minimum checkpoint interval (900.000000 seconds)
2019-07-16 21:32:21 (11780): Starting VM. (boinc_496899da936d8b4d, slot#12)
2019-07-16 21:32:49 (11780): Successfully started VM. (PID = '11528')
2019-07-16 21:32:49 (11780): Reporting VM Process ID to BOINC.
2019-07-16 21:32:54 (11780): VM state change detected. (old = 'poweroff', new = 'running')
2019-07-16 21:32:59 (11780): Detected: Web Application Enabled (http://localhost:64709)
2019-07-16 21:33:04 (11780): Status Report: Elapsed Time: '14583.088516'
2019-07-16 21:33:04 (11780): Status Report: CPU Time: '55107.468750'
2019-07-16 21:33:04 (11780): Preference change detected
2019-07-16 21:33:04 (11780): Setting CPU throttle for VM. (100%)
2019-07-16 21:33:04 (11780): Setting checkpoint interval to 900 seconds. (Higher value of (Preference: 600 seconds) or (Vbox_job.xml: 900 seconds))
2019-07-16 23:13:04 (11780): Status Report: Elapsed Time: '20583.156854'
2019-07-16 23:13:04 (11780): Status Report: CPU Time: '78989.171875'
2019-07-16 23:33:41 (11780): Stopping VM.
2019-07-16 23:34:15 (11780): Successfully stopped VM.
2019-07-16 23:37:50 (22240): vboxwrapper (7.9.26200): starting
2019-07-16 23:37:50 (22240): Feature: Checkpoint interval offset (130 seconds)
2019-07-16 23:37:50 (22240): Detected: VirtualBox COM Interface (Version: 5.2.8)
2019-07-16 23:37:50 (22240): Detected: Minimum checkpoint interval (900.000000 seconds)
2019-07-16 23:37:50 (22240): Starting VM. (boinc_496899da936d8b4d, slot#12)
2019-07-16 23:38:17 (22240): Successfully started VM. (PID = '13864')
2019-07-16 23:38:17 (22240): Reporting VM Process ID to BOINC.
2019-07-16 23:38:22 (22240): VM state change detected. (old = 'poweroff', new = 'running')
2019-07-16 23:38:27 (22240): Detected: Web Application Enabled (http://localhost:64709)
2019-07-16 23:38:32 (22240): Status Report: Elapsed Time: '21820.212444'
2019-07-16 23:38:32 (22240): Status Report: CPU Time: '83990.000000'
2019-07-16 23:38:32 (22240): Preference change detected
2019-07-16 23:38:32 (22240): Setting CPU throttle for VM. (100%)
2019-07-16 23:38:32 (22240): Setting checkpoint interval to 900 seconds. (Higher value of (Preference: 600 seconds) or (Vbox_job.xml: 900 seconds))
2019-07-17 01:15:56 (22240): Guest Log: The last 10 lines of the pilot log.

2019-07-17 01:15:56 (22240): Guest Log: -rw-------  1 atlas01 atlas01 11357 Jun  3 19:18 LICENSE

2019-07-17 01:15:56 (22240): Guest Log: drwx------ 14 atlas01 atlas01  4096 Jul 16 16:16 pilot

2019-07-17 01:15:56 (22240): Guest Log: -rwx------  1 atlas01 atlas01 21428 Jul 11 17:38 pilot.py

2019-07-17 01:15:56 (22240): Guest Log: -rw-------  1 atlas01 atlas01     8 Jul 15 15:01 PILOTVERSION

2019-07-17 01:15:56 (22240): Guest Log: -rw-------  1 atlas01 atlas01  2251 Jun  3 19:18 README.md

2019-07-17 01:15:56 (22240): Guest Log: -rw-------  1 atlas01 atlas01   221 Jun  3 19:18 TODO.md

2019-07-17 01:15:56 (22240): Guest Log: 2019-07-16 23:15:51 UTC [wrapper] Test setup, not cleaning

2019-07-17 01:15:56 (22240): Guest Log: 2019-07-16 23:15:51 UTC [wrapper] ==== wrapper stdout END ====

2019-07-17 01:15:56 (22240): Guest Log: 2019-07-16 23:15:51 UTC [wrapper] ==== wrapper stderr END ====

2019-07-17 01:15:56 (22240): Guest Log: Listing of results directory

2019-07-17 01:15:56 (22240): Guest Log: total 512820

2019-07-17 01:15:56 (22240): Guest Log: drwx------ 5 atlas01 atlas01      4096 Jul 15 15:01 pilot2

2019-07-17 01:15:56 (22240): Guest Log: -rwx------ 1 atlas01 atlas01     15232 Jul 16 15:21 runpilot2-wrapper.sh

2019-07-17 01:15:56 (22240): Guest Log: -rw-r--r-- 1 atlas01 atlas01      4468 Jul 16 15:21 queuedata.json

2019-07-17 01:15:56 (22240): Guest Log: -rw-r--r-- 1 atlas01 atlas01   4056709 Jul 16 15:21 pilot2.tar.gz

2019-07-17 01:15:56 (22240): Guest Log: -rw------- 1 atlas01 atlas01      2871 Jul 16 15:21 pandaJobData.out

2019-07-17 01:15:56 (22240): Guest Log: -rwxr-xr-x 1 atlas01 atlas01 380517359 Jul 16 16:14 EVNT.17323818._000473.pool.root.1

2019-07-17 01:15:56 (22240): Guest Log: -rwxr-xr-x 1 atlas01 atlas01      8727 Jul 16 16:14 init_data.xml

2019-07-17 01:15:56 (22240): Guest Log: -rwxr-xr-x 1 atlas01 atlas01   4066470 Jul 16 16:14 input.tar.gz

2019-07-17 01:15:56 (22240): Guest Log: -rwxr-xr-x 1 atlas01 atlas01       815 Jul 16 16:14 RTE.tar.gz

2019-07-17 01:15:56 (22240): Guest Log: -rwxr-xr-x 1 atlas01 atlas01      8659 Jul 16 16:14 start_atlas.sh

2019-07-17 01:15:56 (22240): Guest Log: drwxr-xr-x 3 atlas01 atlas01      4096 Jul 16 16:14 APPS

2019-07-17 01:15:56 (22240): Guest Log: -rw------- 1 atlas01 atlas01   4670882 Jul 16 16:16 agis_schedconf.cvmfs.json

2019-07-17 01:15:56 (22240): Guest Log: -rw------- 1 atlas01 atlas01   7333189 Jul 16 16:16 agis_ddmendpoints.json

2019-07-17 01:15:56 (22240): Guest Log: -rw------- 1 atlas01 atlas01 117017022 Jul 17 01:11 HITS.18604991._005508.pool.root.1

2019-07-17 01:15:56 (22240): Guest Log: -rw------- 1 atlas01 atlas01    851929 Jul 17 01:14 log.18604991._005508.job.log.tgz.1

2019-07-17 01:15:56 (22240): Guest Log: drwxrwx--- 3 atlas01 atlas01      4096 Jul 17 01:15 PanDA_Pilot-4418186580

2019-07-17 01:15:56 (22240): Guest Log: -rw------- 1 atlas01 atlas01     80535 Jul 17 01:15 heartbeat.json

2019-07-17 01:15:56 (22240): Guest Log: -rw------- 1 atlas01 atlas01   1352015 Jul 17 01:15 pilotlog.txt

2019-07-17 01:15:56 (22240): Guest Log: -rw------- 1 atlas01 atlas01   1368592 Jul 17 01:15 log.18604991._005508.job.log.1

2019-07-17 01:15:56 (22240): Guest Log: -rw------- 1 atlas01 atlas01       513 Jul 17 01:15 sepKDmjZc7unShfckohDCDFpABFKDmABFKDm5ljYDmABFKDmx6eWrm.diag

2019-07-17 01:15:56 (22240): Guest Log: -rw------- 1 atlas01 atlas01       391 Jul 17 01:15 output.list

2019-07-17 01:15:56 (22240): Guest Log: -rw-r--r-- 1 atlas01 atlas01      6829 Jul 17 01:15 runtime_log.err

2019-07-17 01:15:56 (22240): Guest Log: -rw-r--r-- 1 atlas01 atlas01       692 Jul 17 01:15 runtime_log

2019-07-17 01:15:56 (22240): Guest Log: -rw------- 1 atlas01 atlas01   3686400 Jul 17 01:15 result.tar.gz

2019-07-17 01:15:56 (22240): Guest Log: HITS file was successfully produced

2019-07-17 01:15:56 (22240): Guest Log: -rw------- 1 atlas01 atlas01 117017022 Jul 17 01:11 /home/atlas01/RunAtlas/HITS.18604991._005508.pool.root.1

2019-07-17 01:15:56 (22240): Guest Log: Successfully finished the ATLAS job!

2019-07-17 01:15:56 (22240): Guest Log: Copying the results back to the shared directory!

2019-07-17 01:15:56 (22240): Guest Log: Copied the result file back to the shared directory and created atlas_done file!

2019-07-17 01:15:56 (22240): Guest Log: Success! Shutting down the machine.

2019-07-17 01:15:56 (22240): VM Completion File Detected.
2019-07-17 01:15:56 (22240): Powering off VM.
2019-07-17 01:15:58 (22240): Successfully stopped VM.
2019-07-17 01:16:03 (22240): Deregistering VM. (boinc_496899da936d8b4d, slot#12)
2019-07-17 01:16:03 (22240): Removing virtual disk drive(s) from VM.
2019-07-17 01:16:03 (22240): Removing network bandwidth throttle group from VM.
2019-07-17 01:16:03 (22240): Removing storage controller(s) from VM.
2019-07-17 01:16:03 (22240): Removing VM from VirtualBox.
01:16:09 (22240): called boinc_finish(0)

</stderr_txt>
]]>

©2024 CERN