Stderr output

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

2019-07-05 07:22:03 (5276): Guest Log: CPUID EDX: 0x078bfbff

2019-07-05 07:22:03 (5276): Guest Log: BIOS: ata0-0: PCHS=16383/16/63 LCHS=1024/255/63

2019-07-05 07:22:03 (5276): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032

2019-07-05 07:22:03 (5276): Guest Log: BIOS: Booting from Hard Disk...

2019-07-05 07:22:03 (5276): Guest Log: BIOS: KBD: unsupported int 16h function 03

2019-07-05 07:22:03 (5276): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 

2019-07-05 07:22:03 (5276): Successfully started VM. (PID = '23484')
2019-07-05 07:22:03 (5276): Reporting VM Process ID to BOINC.
2019-07-05 07:22:09 (5276): VM state change detected. (old = 'poweroff', new = 'running')
2019-07-05 07:22:14 (5276): Detected: Web Application Enabled (http://localhost:58985)
2019-07-05 07:22:19 (5276): Preference change detected
2019-07-05 07:22:19 (5276): Setting CPU throttle for VM. (100%)
2019-07-05 07:22:19 (5276): Setting checkpoint interval to 900 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 900 seconds))
2019-07-05 07:22:35 (5276): Guest Log: vboxguest: major 0, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000)

2019-07-05 07:22:50 (5276): Guest Log: VBoxGuest: VBoxGuestCommonGuestCapsAcquire: pSession(0xffff880107036010), OR(0x0), NOT(0xffffffff), flags(0x0)

2019-07-05 07:22:50 (5276): Guest Log: VBoxGuest: VBoxGuestCommonGuestCapsAcquire: pSession(0xffff880107036610), OR(0x0), NOT(0xffffffff), flags(0x0)

2019-07-05 07:22:50 (5276): Guest Log: VBoxGuest: VBoxGuestCommonGuestCapsAcquire: pSession(0xffff880107036410), OR(0x0), NOT(0xffffffff), flags(0x0)

2019-07-05 07:22:50 (5276): Guest Log: VBoxGuest: VBoxGuestCommonGuestCapsAcquire: pSession(0xffff880107036810), OR(0x0), NOT(0xffffffff), flags(0x0)

2019-07-05 07:23:55 (5276): Guest Log: Copying input files into RunAtlas.

2019-07-05 07:24:00 (5276): Guest Log: Copied input files into RunAtlas.

2019-07-05 07:24:40 (5276): Guest Log: copied the webapp to /var/www

2019-07-05 07:24:40 (5276): Guest Log: This vm does not need to setup http proxy

2019-07-05 07:24:40 (5276): Guest Log: core_number=1

2019-07-05 07:24:40 (5276): Guest Log: Starting ATLAS job. (Binary file (standard input) matches taskID=18514514)

2019-07-05 09:02:19 (5276): Status Report: Elapsed Time: '6000.034128'
2019-07-05 09:02:19 (5276): Status Report: CPU Time: '5256.578125'
2019-07-05 10:42:25 (5276): Status Report: Elapsed Time: '12006.446581'
2019-07-05 10:42:25 (5276): Status Report: CPU Time: '11229.968750'
2019-07-05 12:22:48 (5276): Status Report: Elapsed Time: '18009.746511'
2019-07-05 12:22:48 (5276): Status Report: CPU Time: '17133.812500'
2019-07-05 14:02:30 (5276): Status Report: Elapsed Time: '24011.729548'
2019-07-05 14:02:30 (5276): Status Report: CPU Time: '23076.015625'
2019-07-05 15:42:35 (5276): Status Report: Elapsed Time: '30016.491344'
2019-07-05 15:42:35 (5276): Status Report: CPU Time: '28919.062500'
2019-07-05 16:39:35 (5276): VM state change detected. (old = 'running', new = 'paused')
2019-07-05 17:10:10 (5276): VM state change detected. (old = 'paused', new = 'running')
2019-07-05 17:53:16 (5276): Status Report: Elapsed Time: '36017.487818'
2019-07-05 17:53:16 (5276): Status Report: CPU Time: '34739.468750'
2019-07-05 17:54:02 (5276): VM state change detected. (old = 'running', new = 'paused')
2019-07-05 18:54:53 (5276): VM state change detected. (old = 'paused', new = 'running')
2019-07-05 19:10:38 (5276): VM state change detected. (old = 'running', new = 'paused')
2019-07-05 20:10:09 (5276): VM state change detected. (old = 'paused', new = 'running')
2019-07-05 21:33:53 (5276): Status Report: Elapsed Time: '42021.128159'
2019-07-05 21:33:53 (5276): Status Report: CPU Time: '40545.093750'
2019-07-05 23:13:55 (5276): Status Report: Elapsed Time: '48023.830061'
2019-07-05 23:13:55 (5276): Status Report: CPU Time: '46512.625000'
2019-07-05 23:14:46 (5276): VM state change detected. (old = 'running', new = 'paused')
2019-07-05 23:52:50 (5276): VM state change detected. (old = 'paused', new = 'running')
2019-07-05 23:53:03 (5276): VM state change detected. (old = 'running', new = 'paused')
2019-07-05 23:53:31 (5276): VM state change detected. (old = 'paused', new = 'running')
2019-07-05 23:53:44 (5276): VM state change detected. (old = 'running', new = 'paused')
2019-07-05 23:55:42 (5276): VM state change detected. (old = 'paused', new = 'running')
2019-07-05 23:55:52 (5276): VM state change detected. (old = 'running', new = 'paused')
2019-07-06 00:33:06 (5276): VM state change detected. (old = 'paused', new = 'running')
2019-07-06 00:33:38 (5276): VM state change detected. (old = 'running', new = 'paused')
2019-07-06 02:11:37 (5276): VM state change detected. (old = 'paused', new = 'running')
2019-07-06 02:11:47 (5276): VM state change detected. (old = 'running', new = 'paused')
2019-07-06 02:20:19 (5276): VM state change detected. (old = 'paused', new = 'running')
2019-07-06 02:20:31 (5276): VM state change detected. (old = 'running', new = 'paused')
2019-07-06 02:20:59 (5276): VM state change detected. (old = 'paused', new = 'running')
2019-07-06 02:21:10 (5276): VM state change detected. (old = 'running', new = 'paused')
2019-07-06 02:21:40 (5276): VM state change detected. (old = 'paused', new = 'running')
2019-07-06 02:22:35 (5276): VM state change detected. (old = 'running', new = 'paused')
2019-07-07 16:46:26 (5276): Stopping VM.
2019-07-07 16:46:43 (5276): Successfully stopped VM.
2019-07-07 18:39:58 (13140): vboxwrapper (7.9.26200): starting
2019-07-07 18:39:58 (13140): Feature: Checkpoint interval offset (186 seconds)
2019-07-07 18:39:58 (13140): Detected: VirtualBox COM Interface (Version: 5.2.12)
2019-07-07 18:39:58 (13140): Detected: Minimum checkpoint interval (900.000000 seconds)
2019-07-07 18:39:58 (13140): Guest Log: BIOS: VirtualBox 5.2.12

2019-07-07 18:39:58 (13140): Guest Log: CPUID EDX: 0x078bfbff

2019-07-07 18:39:58 (13140): Guest Log: BIOS: ata0-0: PCHS=16383/16/63 LCHS=1024/255/63

2019-07-07 18:39:58 (13140): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032

2019-07-07 18:39:58 (13140): Guest Log: BIOS: Booting from Hard Disk...

2019-07-07 18:39:58 (13140): Guest Log: BIOS: KBD: unsupported int 16h function 03

2019-07-07 18:39:58 (13140): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 

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

2019-07-07 18:39:58 (13140): Guest Log: VBoxGuest: VBoxGuestCommonGuestCapsAcquire: pSession(0xffff880107036010), OR(0x0), NOT(0xffffffff), flags(0x0)

2019-07-07 18:39:58 (13140): Guest Log: VBoxGuest: VBoxGuestCommonGuestCapsAcquire: pSession(0xffff880107036610), OR(0x0), NOT(0xffffffff), flags(0x0)

2019-07-07 18:39:58 (13140): Guest Log: VBoxGuest: VBoxGuestCommonGuestCapsAcquire: pSession(0xffff880107036410), OR(0x0), NOT(0xffffffff), flags(0x0)

2019-07-07 18:39:58 (13140): Guest Log: VBoxGuest: VBoxGuestCommonGuestCapsAcquire: pSession(0xffff880107036810), OR(0x0), NOT(0xffffffff), flags(0x0)

2019-07-07 18:39:58 (13140): Guest Log: Copying input files into RunAtlas.

2019-07-07 18:39:58 (13140): Guest Log: Copied input files into RunAtlas.

2019-07-07 18:39:58 (13140): Guest Log: copied the webapp to /var/www

2019-07-07 18:39:58 (13140): Guest Log: This vm does not need to setup http proxy

2019-07-07 18:39:58 (13140): Guest Log: core_number=1

2019-07-07 18:39:58 (13140): Guest Log: Starting ATLAS job. (Binary file (standard input) matches taskID=18514514)

2019-07-07 18:39:58 (13140): Starting VM. (boinc_c242f0594a8ca233, slot#1)
2019-07-07 18:41:06 (13140): Successfully started VM. (PID = '5384')
2019-07-07 18:41:06 (13140): Reporting VM Process ID to BOINC.
2019-07-07 18:41:11 (13140): VM state change detected. (old = 'poweroff', new = 'running')
2019-07-07 18:41:16 (13140): Detected: Web Application Enabled (http://localhost:58985)
2019-07-07 18:41:21 (13140): Status Report: Elapsed Time: '48183.295364'
2019-07-07 18:41:21 (13140): Status Report: CPU Time: '46665.187500'
2019-07-07 18:41:21 (13140): Preference change detected
2019-07-07 18:41:21 (13140): Setting CPU throttle for VM. (100%)
2019-07-07 18:41:21 (13140): Setting checkpoint interval to 900 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 900 seconds))
2019-07-07 18:43:09 (13140): Guest Log: The last 10 lines of the pilot log.

2019-07-07 18:43:09 (13140): Guest Log: drwx------ 14 atlas01 atlas01  4096 Jul  5 14:29 pilot

2019-07-07 18:43:09 (13140): Guest Log: -rwx------  1 atlas01 atlas01 21370 May 31 14:26 pilot.py

2019-07-07 18:43:09 (13140): Guest Log: -rw-------  1 atlas01 atlas01     8 May 31 15:52 PILOTVERSION

2019-07-07 18:43:09 (13140): Guest Log: -rw-------  1 atlas01 atlas01     7 May 31 14:29 PILOTVERSION~

2019-07-07 18:43:09 (13140): Guest Log: -rw-------  1 atlas01 atlas01  2251 May 31 14:25 README.md

2019-07-07 18:43:09 (13140): Guest Log: -rw-------  1 atlas01 atlas01   221 May 31 14:25 TODO.md

2019-07-07 18:43:09 (13140): Guest Log: 2019-07-07 23:43:08 UTC [wrapper] Test setup, not cleaning

2019-07-07 18:43:09 (13140): Guest Log: 2019-07-07 23:43:08 UTC [wrapper] ==== wrapper stdout END ====

2019-07-07 18:43:09 (13140): Guest Log: 2019-07-07 23:43:08 UTC [wrapper] ==== wrapper stderr END ====

2019-07-07 18:43:09 (13140): Guest Log: Listing of results directory

2019-07-07 18:43:09 (13140): Guest Log: total 389408

2019-07-07 18:43:09 (13140): Guest Log: drwx------ 3 atlas01 atlas01      4096 May 31 14:36 pilot2

2019-07-07 18:43:09 (13140): Guest Log: -rw-r--r-- 1 atlas01 atlas01    237372 Jun 24 09:16 pilot2.tar.gz

2019-07-07 18:43:09 (13140): Guest Log: -rwx------ 1 atlas01 atlas01     15232 Jul  5 11:30 runpilot2-wrapper.sh

2019-07-07 18:43:09 (13140): Guest Log: -rw------- 1 atlas01 atlas01      2869 Jul  5 11:30 pandaJobData.out

2019-07-07 18:43:09 (13140): Guest Log: -rw-r--r-- 1 atlas01 atlas01      4468 Jul  5 11:30 queuedata.json

2019-07-07 18:43:09 (13140): Guest Log: -rwxr-xr-x 1 atlas01 atlas01 377321562 Jul  5 14:26 EVNT.17323631._000132.pool.root.1

2019-07-07 18:43:09 (13140): Guest Log: -rwxr-xr-x 1 atlas01 atlas01      8754 Jul  5 14:26 init_data.xml

2019-07-07 18:43:09 (13140): Guest Log: -rwxr-xr-x 1 atlas01 atlas01    246322 Jul  5 14:26 input.tar.gz

2019-07-07 18:43:09 (13140): Guest Log: -rwxr-xr-x 1 atlas01 atlas01       815 Jul  5 14:26 RTE.tar.gz

2019-07-07 18:43:09 (13140): Guest Log: -rwxr-xr-x 1 atlas01 atlas01      8659 Jul  5 14:26 start_atlas.sh

2019-07-07 18:43:09 (13140): Guest Log: drwxr-xr-x 3 atlas01 atlas01      4096 Jul  5 14:27 APPS

2019-07-07 18:43:09 (13140): Guest Log: -rw------- 1 atlas01 atlas01   4669856 Jul  5 14:29 agis_schedconf.cvmfs.json

2019-07-07 18:43:09 (13140): Guest Log: -rw------- 1 atlas01 atlas01   7350419 Jul  5 14:29 agis_ddmendpoints.json

2019-07-07 18:43:09 (13140): Guest Log: -rw------- 1 atlas01 atlas01       298 Jul  8 01:41 memory_monitor_summary.json

2019-07-07 18:43:09 (13140): Guest Log: -rw------- 1 atlas01 atlas01    270716 Jul  8 01:41 log.18514514._006634.job.log.tgz.1

2019-07-07 18:43:09 (13140): Guest Log: drwxrwx--- 2 atlas01 atlas01      4096 Jul  8 01:42 PanDA_Pilot-4405491013

2019-07-07 18:43:09 (13140): Guest Log: -rw------- 1 atlas01 atlas01      2534 Jul  8 01:42 heartbeat.json

2019-07-07 18:43:09 (13140): Guest Log: -rw------- 1 atlas01 atlas01   2046062 Jul  8 01:43 pilotlog.txt

2019-07-07 18:43:09 (13140): Guest Log: -rw------- 1 atlas01 atlas01   2062607 Jul  8 01:43 log.18514514._006634.job.log.1

2019-07-07 18:43:09 (13140): Guest Log: -rw------- 1 atlas01 atlas01       500 Jul  8 01:43 g41LDmZjZ3unShfckohDCDFpABFKDmABFKDmxmdQDmABFKDmT4xhxm.diag

2019-07-07 18:43:09 (13140): Guest Log: -rw------- 1 atlas01 atlas01       205 Jul  8 01:43 output.list

2019-07-07 18:43:09 (13140): Guest Log: -rw-r--r-- 1 atlas01 atlas01      6650 Jul  8 01:43 runtime_log.err

2019-07-07 18:43:09 (13140): Guest Log: -rw-r--r-- 1 atlas01 atlas01       692 Jul  8 01:43 runtime_log

2019-07-07 18:43:09 (13140): Guest Log: -rw------- 1 atlas01 atlas01   4413440 Jul  8 01:43 result.tar.gz

2019-07-07 18:43:09 (13140): Guest Log: No HITS file was produced

2019-07-07 18:43:09 (13140): Guest Log: Successfully finished the ATLAS job!

2019-07-07 18:43:10 (13140): Guest Log: Copying the results back to the shared directory!

2019-07-07 18:43:10 (13140): Guest Log: Copied the result file back to the shared directory and created atlas_done file!

2019-07-07 18:43:10 (13140): Guest Log: Success! Shutting down the machine.

2019-07-07 18:43:10 (13140): VM Completion File Detected.
2019-07-07 18:43:10 (13140): Powering off VM.
2019-07-07 18:43:12 (13140): Successfully stopped VM.
2019-07-07 18:43:17 (13140): Deregistering VM. (boinc_c242f0594a8ca233, slot#1)
2019-07-07 18:43:17 (13140): Removing virtual disk drive(s) from VM.
2019-07-07 18:43:17 (13140): Removing network bandwidth throttle group from VM.
2019-07-07 18:43:17 (13140): Removing storage controller(s) from VM.
2019-07-07 18:43:17 (13140): Removing VM from VirtualBox.
18:43:22 (13140): called boinc_finish(0)

</stderr_txt>
]]>

©2024 CERN