Stderr output

<core_client_version>7.10.2</core_client_version>
<![CDATA[
<stderr_txt>
2019-08-14 08:14:56 (23968): vboxwrapper (7.9.26200): starting
2019-08-14 08:14:56 (23968): Feature: Checkpoint interval offset (233 seconds)
2019-08-14 08:14:56 (23968): Detected: VirtualBox COM Interface (Version: 5.2.10)
2019-08-14 08:14:56 (23968): Detected: Minimum checkpoint interval (900.000000 seconds)
2019-08-14 08:14:56 (23968): Successfully copied 'init_data.xml' to the shared directory.
2019-08-14 08:14:56 (23968): Create VM. (boinc_fd1d566fda835784, slot#3)
2019-08-14 08:14:56 (23968): Setting Memory Size for VM. (4800MB)
2019-08-14 08:14:56 (23968): Setting CPU Count for VM. (2)
2019-08-14 08:14:56 (23968): Setting Chipset Options for VM.
2019-08-14 08:14:56 (23968): Setting Boot Options for VM.
2019-08-14 08:14:56 (23968): Enabling VM Network Access.
2019-08-14 08:14:56 (23968): Setting Network Configuration for NAT.
2019-08-14 08:14:56 (23968): Disabling USB Support for VM.
2019-08-14 08:14:56 (23968): Disabling COM Port Support for VM.
2019-08-14 08:14:56 (23968): Disabling LPT Port Support for VM.
2019-08-14 08:14:56 (23968): Disabling Audio Support for VM.
2019-08-14 08:14:56 (23968): Disabling Clipboard Support for VM.
2019-08-14 08:14:56 (23968): Disabling Drag and Drop Support for VM.
2019-08-14 08:14:56 (23968): Adding storage controller(s) to VM.
2019-08-14 08:14:56 (23968): Adding virtual disk drive to VM. (vm_image.vdi)
2019-08-14 08:14:57 (23968): Adding VirtualBox Guest Additions to VM.
2019-08-14 08:14:57 (23968): Adding network bandwidth throttle group to VM. (Defaulting to 1024GB)
2019-08-14 08:14:57 (23968): forwarding host port 60437 to guest port 80
2019-08-14 08:14:57 (23968): Enabling remote desktop for VM.
2019-08-14 08:14:57 (23968): Enabling shared directory for VM.
2019-08-14 08:14:57 (23968): Starting VM. (boinc_fd1d566fda835784, slot#3)
2019-08-14 08:15:07 (23968): Guest Log: BIOS: VirtualBox 5.2.10

2019-08-14 08:15:07 (23968): Guest Log: CPUID EDX: 0x178bfbff

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

2019-08-14 08:15:07 (23968): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032

2019-08-14 08:15:07 (23968): Guest Log: BIOS: Booting from Hard Disk...

2019-08-14 08:15:07 (23968): Guest Log: BIOS: KBD: unsupported int 16h function 03

2019-08-14 08:15:07 (23968): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 

2019-08-14 08:15:07 (23968): Successfully started VM. (PID = '24364')
2019-08-14 08:15:07 (23968): Reporting VM Process ID to BOINC.
2019-08-14 08:15:12 (23968): VM state change detected. (old = 'poweroff', new = 'running')
2019-08-14 08:15:17 (23968): Detected: Web Application Enabled (http://localhost:60437)
2019-08-14 08:15:17 (23968): Detected: Remote Desktop Enabled (localhost:60438)
2019-08-14 08:15:22 (23968): Preference change detected
2019-08-14 08:15:22 (23968): Setting CPU throttle for VM. (100%)
2019-08-14 08:15:23 (23968): Setting checkpoint interval to 900 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 900 seconds))
2019-08-14 08:15:33 (23968): Guest Log: vboxguest: major 0, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000)

2019-08-14 08:15:43 (23968): Guest Log: VBoxGuest: VBoxGuestCommonGuestCapsAcquire: pSession(0xffff88013d4cbe10), OR(0x0), NOT(0xffffffff), flags(0x0)

2019-08-14 08:15:43 (23968): Guest Log: VBoxGuest: VBoxGuestCommonGuestCapsAcquire: pSession(0xffff88013e27dc10), OR(0x0), NOT(0xffffffff), flags(0x0)

2019-08-14 08:15:43 (23968): Guest Log: VBoxGuest: VBoxGuestCommonGuestCapsAcquire: pSession(0xffff88013d4cb810), OR(0x0), NOT(0xffffffff), flags(0x0)

2019-08-14 08:15:43 (23968): Guest Log: VBoxGuest: VBoxGuestCommonGuestCapsAcquire: pSession(0xffff880143db2c10), OR(0x0), NOT(0xffffffff), flags(0x0)

2019-08-14 08:16:13 (23968): Guest Log: Copying input files into RunAtlas.

2019-08-14 08:16:23 (23968): Guest Log: Copied input files into RunAtlas.

2019-08-14 08:16:38 (23968): Guest Log: copied the webapp to /var/www

2019-08-14 08:16:38 (23968): Guest Log: This vm does not need to setup http proxy

2019-08-14 08:16:38 (23968): Guest Log: ATHENA_PROC_NUMBER=2

2019-08-14 08:16:38 (23968): Guest Log: Starting ATLAS job. (PandaID=4448065871 taskID=18604976)

2019-08-14 09:55:26 (23968): Status Report: Elapsed Time: '6003.772409'
2019-08-14 09:55:26 (23968): Status Report: CPU Time: '10340.078125'
2019-08-14 11:35:29 (23968): Status Report: Elapsed Time: '12006.911674'
2019-08-14 11:35:29 (23968): Status Report: CPU Time: '22329.671875'
2019-08-14 13:15:32 (23968): Status Report: Elapsed Time: '18010.165373'
2019-08-14 13:15:32 (23968): Status Report: CPU Time: '34316.187500'
2019-08-14 14:55:35 (23968): Status Report: Elapsed Time: '24013.066149'
2019-08-14 14:55:35 (23968): Status Report: CPU Time: '46334.703125'
2019-08-14 16:35:38 (23968): Status Report: Elapsed Time: '30015.991776'
2019-08-14 16:35:38 (23968): Status Report: CPU Time: '58350.234375'
2019-08-14 18:15:39 (23968): Status Report: Elapsed Time: '36016.619106'
2019-08-14 18:15:39 (23968): Status Report: CPU Time: '70274.218750'
2019-08-14 19:55:44 (23968): Status Report: Elapsed Time: '42021.443722'
2019-08-14 19:55:44 (23968): Status Report: CPU Time: '82252.437500'
2019-08-14 21:35:48 (23968): Status Report: Elapsed Time: '48025.593229'
2019-08-14 21:35:48 (23968): Status Report: CPU Time: '94145.046875'
2019-08-14 23:15:52 (23968): Status Report: Elapsed Time: '54029.533064'
2019-08-14 23:15:52 (23968): Status Report: CPU Time: '106156.531250'
2019-08-15 00:04:37 (23968): Stopping VM.
2019-08-15 00:04:37 (23968): Error 0x80070005 in vbox52::VBOX_VM::stop (c:\users\david\documents\boinc_git\boinc\samples\vboxwrapper\vbox_mscom_impl.cpp:1452)
2019-08-15 00:04:37 (23968): Error Source     : SessionMachine
2019-08-15 00:04:37 (23968): Error Description: The object is not ready
2019-08-15 00:06:50 (12124): vboxwrapper (7.9.26200): starting
2019-08-15 00:06:52 (12124): Feature: Checkpoint interval offset (125 seconds)
2019-08-15 00:06:52 (12124): Detected: VirtualBox COM Interface (Version: 5.2.10)
2019-08-15 00:06:52 (12124): Detected: Minimum checkpoint interval (900.000000 seconds)
2019-08-15 00:06:52 (12124): Guest Log: BIOS: VirtualBox 5.2.10

2019-08-15 00:06:52 (12124): Guest Log: CPUID EDX: 0x178bfbff

2019-08-15 00:06:52 (12124): Guest Log: BIOS: ata0-0: PCHS=16383/16/63 LCHS=1024/255/63

2019-08-15 00:06:52 (12124): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032

2019-08-15 00:06:52 (12124): Guest Log: BIOS: Booting from Hard Disk...

2019-08-15 00:06:52 (12124): Guest Log: BIOS: KBD: unsupported int 16h function 03

2019-08-15 00:06:52 (12124): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 

2019-08-15 00:06:52 (12124): Guest Log: vboxguest: major 0, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000)

2019-08-15 00:06:52 (12124): Guest Log: VBoxGuest: VBoxGuestCommonGuestCapsAcquire: pSession(0xffff88013d4cbe10), OR(0x0), NOT(0xffffffff), flags(0x0)

2019-08-15 00:06:52 (12124): Guest Log: VBoxGuest: VBoxGuestCommonGuestCapsAcquire: pSession(0xffff88013e27dc10), OR(0x0), NOT(0xffffffff), flags(0x0)

2019-08-15 00:06:52 (12124): Guest Log: VBoxGuest: VBoxGuestCommonGuestCapsAcquire: pSession(0xffff88013d4cb810), OR(0x0), NOT(0xffffffff), flags(0x0)

2019-08-15 00:06:52 (12124): Guest Log: VBoxGuest: VBoxGuestCommonGuestCapsAcquire: pSession(0xffff880143db2c10), OR(0x0), NOT(0xffffffff), flags(0x0)

2019-08-15 00:06:52 (12124): Guest Log: Copying input files into RunAtlas.

2019-08-15 00:06:52 (12124): Guest Log: Copied input files into RunAtlas.

2019-08-15 00:06:52 (12124): Guest Log: copied the webapp to /var/www

2019-08-15 00:06:52 (12124): Guest Log: This vm does not need to setup http proxy

2019-08-15 00:06:52 (12124): Guest Log: ATHENA_PROC_NUMBER=2

2019-08-15 00:06:52 (12124): Guest Log: Starting ATLAS job. (PandaID=4448065871 taskID=18604976)

2019-08-15 00:06:52 (12124): Starting VM. (boinc_fd1d566fda835784, slot#3)
2019-08-15 00:07:03 (12124): Successfully started VM. (PID = '13032')
2019-08-15 00:07:03 (12124): Reporting VM Process ID to BOINC.
2019-08-15 00:07:08 (12124): VM state change detected. (old = 'poweroff', new = 'running')
2019-08-15 00:07:13 (12124): Detected: Web Application Enabled (http://localhost:60437)
2019-08-15 00:07:13 (12124): Detected: Remote Desktop Enabled (localhost:60438)
2019-08-15 00:07:34 (12124): Status Report: Elapsed Time: '56955.182529'
2019-08-15 00:07:34 (12124): Status Report: CPU Time: '111940.078125'
2019-08-15 00:07:34 (12124): Preference change detected
2019-08-15 00:07:34 (12124): Setting CPU throttle for VM. (100%)
2019-08-15 00:07:34 (12124): Setting checkpoint interval to 900 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 900 seconds))
2019-08-15 00:10:11 (12124): Guest Log: Copying input files into RunAtlas.

2019-08-15 00:10:21 (12124): Guest Log: Copied input files into RunAtlas.

2019-08-15 00:10:26 (12124): Guest Log: copied the webapp to /var/www

2019-08-15 00:10:26 (12124): Guest Log: This vm does not need to setup http proxy

2019-08-15 00:10:26 (12124): Guest Log: ATHENA_PROC_NUMBER=2

2019-08-15 00:10:26 (12124): Guest Log: Starting ATLAS job. (Binary file (standard input) matches Binary file (standard input) matches)

2019-08-15 01:47:22 (12124): Status Report: Elapsed Time: '62958.657356'
2019-08-15 01:47:22 (12124): Status Report: CPU Time: '122284.171875'
2019-08-15 03:27:22 (12124): Status Report: Elapsed Time: '68958.756519'
2019-08-15 03:27:22 (12124): Status Report: CPU Time: '134290.796875'
2019-08-15 05:07:24 (12124): Status Report: Elapsed Time: '74960.607303'
2019-08-15 05:07:24 (12124): Status Report: CPU Time: '146152.125000'
2019-08-15 06:47:24 (12124): Status Report: Elapsed Time: '80960.704189'
2019-08-15 06:47:24 (12124): Status Report: CPU Time: '158055.000000'
2019-08-15 08:27:28 (12124): Status Report: Elapsed Time: '86964.659465'
2019-08-15 08:27:28 (12124): Status Report: CPU Time: '170011.703125'
2019-08-15 10:07:31 (12124): Status Report: Elapsed Time: '92967.752586'
2019-08-15 10:07:31 (12124): Status Report: CPU Time: '182025.765625'
2019-08-15 11:47:34 (12124): Status Report: Elapsed Time: '98970.791772'
2019-08-15 11:47:34 (12124): Status Report: CPU Time: '193936.828125'
2019-08-15 13:27:37 (12124): Status Report: Elapsed Time: '104973.981447'
2019-08-15 13:27:37 (12124): Status Report: CPU Time: '205945.796875'
2019-08-15 15:07:41 (12124): Status Report: Elapsed Time: '110977.360200'
2019-08-15 15:07:41 (12124): Status Report: CPU Time: '217950.218750'
2019-08-15 15:55:52 (12124): Guest Log: The last 10 lines of the pilot log.

2019-08-15 15:55:52 (12124): Guest Log: -rwx------  1 atlas01 atlas01 20463 Jul 25 10:38 pilot.py

2019-08-15 15:55:52 (12124): Guest Log: -rw-------  1 atlas01 atlas01     8 Aug  7 07:02 PILOTVERSION

2019-08-15 15:55:52 (12124): Guest Log: -rw-------  1 atlas01 atlas01  2251 Jul 25 10:38 README.md

2019-08-15 15:55:52 (12124): Guest Log: -rw-------  1 atlas01 atlas01   221 Jul 25 10:38 TODO.md

2019-08-15 15:55:52 (12124): Guest Log: 2019-08-15 19:55:51 UTC [wrapper] Test setup, not cleaning

2019-08-15 15:55:52 (12124): Guest Log: 2019-08-15 19:55:51 UTC [wrapper] ==== wrapper stdout END ====

2019-08-15 15:55:52 (12124): Guest Log: 2019-08-15 19:55:51 UTC [wrapper] ==== wrapper stderr END ====

2019-08-15 15:55:52 (12124): Guest Log: 2019-08-15 19:55:51 UTC [wrapper] wrapper wrapperexiting ec=0, duration=56723

2019-08-15 15:55:52 (12124): Guest Log: 2019-08-15 19:55:51 UTC [wrapper] apfmon messages muted

2019-08-15 15:55:52 (12124): Guest Log: Listing of results directory

2019-08-15 15:55:52 (12124): Guest Log: total 505884

2019-08-15 15:55:52 (12124): Guest Log: drwx------ 3 atlas01 atlas01      4096 Aug  7 07:02 pilot2

2019-08-15 15:55:52 (12124): Guest Log: -rw-r--r-- 1 atlas01 atlas01    253018 Aug 14 01:33 pilot2.tar.gz

2019-08-15 15:55:52 (12124): Guest Log: -rwx------ 1 atlas01 atlas01     12641 Aug 14 02:18 runpilot2-wrapper.sh

2019-08-15 15:55:52 (12124): Guest Log: -rw------- 1 atlas01 atlas01      2872 Aug 14 02:18 pandaJobData.out

2019-08-15 15:55:52 (12124): Guest Log: -rw-r--r-- 1 atlas01 atlas01      4468 Aug 14 02:18 queuedata.json

2019-08-15 15:55:52 (12124): Guest Log: -rwxr-xr-x 1 atlas01 atlas01 382054624 Aug 15 00:10 EVNT.17323813._000976.pool.root.1

2019-08-15 15:55:52 (12124): Guest Log: -rwxr-xr-x 1 atlas01 atlas01      8630 Aug 15 00:10 init_data.xml

2019-08-15 15:55:52 (12124): Guest Log: -rwxr-xr-x 1 atlas01 atlas01    261614 Aug 15 00:10 input.tar.gz

2019-08-15 15:55:52 (12124): Guest Log: -rwxr-xr-x 1 atlas01 atlas01       815 Aug 15 00:10 RTE.tar.gz

2019-08-15 15:55:52 (12124): Guest Log: -rwxr-xr-x 1 atlas01 atlas01      8659 Aug 15 00:10 start_atlas.sh

2019-08-15 15:55:52 (12124): Guest Log: drwxr-xr-x 3 atlas01 atlas01      4096 Aug 15 00:10 APPS

2019-08-15 15:55:52 (12124): Guest Log: -rw------- 1 atlas01 atlas01   4448398 Aug 15 00:11 agis_schedconf.cvmfs.json

2019-08-15 15:55:52 (12124): Guest Log: -rw------- 1 atlas01 atlas01   7409162 Aug 15 00:11 agis_ddmendpoints.json

2019-08-15 15:55:52 (12124): Guest Log: -rw------- 1 atlas01 atlas01 114008402 Aug 15 15:52 HITS.18604976._050067.pool.root.1

2019-08-15 15:55:52 (12124): Guest Log: -rw------- 1 atlas01 atlas01    704563 Aug 15 15:54 log.18604976._050067.job.log.tgz.1

2019-08-15 15:55:52 (12124): Guest Log: -rw------- 1 atlas01 atlas01     90389 Aug 15 15:55 heartbeat.json

2019-08-15 15:55:52 (12124): Guest Log: -rw------- 1 atlas01 atlas01     11513 Aug 15 15:55 pilotlog.txt

2019-08-15 15:55:52 (12124): Guest Log: -rw------- 1 atlas01 atlas01   2616817 Aug 15 15:55 log.18604976._050067.job.log.1

2019-08-15 15:55:52 (12124): Guest Log: -rw------- 1 atlas01 atlas01       502 Aug 15 15:55 WRxMDmWn1HvnShfckohDCDFpABFKDmABFKDm7UpaDmABFKDm6f3Q9n.diag

2019-08-15 15:55:52 (12124): Guest Log: -rw------- 1 atlas01 atlas01       391 Aug 15 15:55 output.list

2019-08-15 15:55:52 (12124): Guest Log: -rw-r--r-- 1 atlas01 atlas01      6787 Aug 15 15:55 runtime_log.err

2019-08-15 15:55:52 (12124): Guest Log: -rw-r--r-- 1 atlas01 atlas01       692 Aug 15 15:55 runtime_log

2019-08-15 15:55:52 (12124): Guest Log: -rw------- 1 atlas01 atlas01   6041600 Aug 15 15:55 result.tar.gz

2019-08-15 15:55:52 (12124): Guest Log: HITS file was successfully produced

2019-08-15 15:55:52 (12124): Guest Log: -rw------- 1 atlas01 atlas01 114008402 Aug 15 15:52 /home/atlas01/RunAtlas/HITS.18604976._050067.pool.root.1

2019-08-15 15:55:57 (12124): Guest Log: Successfully finished the ATLAS job!

2019-08-15 15:55:57 (12124): Guest Log: Copying the results back to the shared directory!

2019-08-15 15:55:57 (12124): Guest Log: Copied the result file back to the shared directory and created atlas_done file!

2019-08-15 15:55:57 (12124): Guest Log: Success! Shutting down the machine.

2019-08-15 15:55:57 (12124): VM Completion File Detected.
2019-08-15 15:55:57 (12124): Powering off VM.
2019-08-15 15:55:59 (12124): Successfully stopped VM.
2019-08-15 15:56:04 (12124): Deregistering VM. (boinc_fd1d566fda835784, slot#3)
2019-08-15 15:56:04 (12124): Removing virtual disk drive(s) from VM.
2019-08-15 15:56:04 (12124): Removing network bandwidth throttle group from VM.
2019-08-15 15:56:04 (12124): Removing storage controller(s) from VM.
2019-08-15 15:56:04 (12124): Removing VM from VirtualBox.
15:56:09 (12124): called boinc_finish(0)

</stderr_txt>
]]>

©2024 CERN