Stderr output

<core_client_version>7.16.6</core_client_version>
<![CDATA[
<stderr_txt>
2021-08-05 19:15:28 (7771): vboxwrapper (7.7.26197): starting
2021-08-05 19:15:28 (7771): Feature: Checkpoint interval offset (269 seconds)
2021-08-05 19:15:28 (7771): Detected: VirtualBox VboxManage Interface (Version: 6.1.26)
2021-08-05 19:15:28 (7771): Detected: Minimum checkpoint interval (900.000000 seconds)
2021-08-05 19:15:28 (7771): Successfully copied 'init_data.xml' to the shared directory.
2021-08-05 19:15:28 (7771): Create VM. (boinc_20c36e9349056e23, slot#12)
2021-08-05 19:15:28 (7771): Setting Memory Size for VM. (10200MB)
2021-08-05 19:15:29 (7771): Setting CPU Count for VM. (8)
2021-08-05 19:15:29 (7771): Setting Chipset Options for VM.
2021-08-05 19:15:29 (7771): Setting Boot Options for VM.
2021-08-05 19:15:29 (7771): Setting Network Configuration for NAT.
2021-08-05 19:15:29 (7771): Enabling VM Network Access.
2021-08-05 19:15:29 (7771): Disabling USB Support for VM.
2021-08-05 19:15:29 (7771): Disabling COM Port Support for VM.
2021-08-05 19:15:29 (7771): Disabling LPT Port Support for VM.
2021-08-05 19:15:29 (7771): Disabling Audio Support for VM.
2021-08-05 19:15:29 (7771): Disabling Clipboard Support for VM.
2021-08-05 19:15:29 (7771): Disabling Drag and Drop Support for VM.
2021-08-05 19:15:29 (7771): Adding storage controller(s) to VM.
2021-08-05 19:15:30 (7771): Adding virtual disk drive to VM. (vm_image.vdi)
2021-08-05 19:15:30 (7771): Adding VirtualBox Guest Additions to VM.
2021-08-05 19:15:30 (7771): Adding network bandwidth throttle group to VM. (Defaulting to 1024GB)
2021-08-05 19:15:30 (7771): forwarding host port 46699 to guest port 80
2021-08-05 19:15:30 (7771): Enabling remote desktop for VM.
2021-08-05 19:15:30 (7771): Enabling shared directory for VM.
2021-08-05 19:15:30 (7771): Starting VM. (boinc_20c36e9349056e23, slot#12)
2021-08-05 19:15:31 (7771): Successfully started VM. (PID = '8673')
2021-08-05 19:15:31 (7771): Reporting VM Process ID to BOINC.
2021-08-05 19:15:31 (7771): Guest Log: BIOS: VirtualBox 6.1.26
2021-08-05 19:15:31 (7771): Guest Log: CPUID EDX: 0x178bfbff
2021-08-05 19:15:31 (7771): Guest Log: BIOS: ata0-0: PCHS=16383/16/63 LCHS=1024/255/63
2021-08-05 19:15:31 (7771): VM state change detected. (old = 'poweroff', new = 'running')
2021-08-05 19:15:31 (7771): Detected: Web Application Enabled (http://localhost:46699)
2021-08-05 19:15:31 (7771): Detected: Remote Desktop Enabled (localhost:43745)
2021-08-05 19:15:32 (7771): Preference change detected
2021-08-05 19:15:32 (7771): Setting CPU throttle for VM. (100%)
2021-08-05 19:15:32 (7771): Setting checkpoint interval to 900 seconds. (Higher value of (Preference: 300 seconds) or (Vbox_job.xml: 900 seconds))
2021-08-05 19:15:34 (7771): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032
2021-08-05 19:15:35 (7771): Guest Log: BIOS: KBD: unsupported int 16h function 03
2021-08-05 19:15:35 (7771): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 
2021-08-05 19:15:35 (7771): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=81
2021-08-05 19:15:35 (7771): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=81
2021-08-05 19:15:35 (7771): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=82
2021-08-05 19:15:35 (7771): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=82
2021-08-05 19:15:35 (7771): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=83
2021-08-05 19:15:35 (7771): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=83
2021-08-05 19:15:35 (7771): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=84
2021-08-05 19:15:35 (7771): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=84
2021-08-05 19:15:35 (7771): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=85
2021-08-05 19:15:35 (7771): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=85
2021-08-05 19:15:35 (7771): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=86
2021-08-05 19:15:35 (7771): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=86
2021-08-05 19:15:35 (7771): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=87
2021-08-05 19:15:35 (7771): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=87
2021-08-05 19:15:35 (7771): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=88
2021-08-05 19:15:35 (7771): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=88
2021-08-05 19:15:35 (7771): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=89
2021-08-05 19:15:35 (7771): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=89
2021-08-05 19:15:35 (7771): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=8a
2021-08-05 19:15:35 (7771): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=8a
2021-08-05 19:15:35 (7771): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=8b
2021-08-05 19:15:35 (7771): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=8b
2021-08-05 19:15:35 (7771): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=8c
2021-08-05 19:15:35 (7771): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=8c
2021-08-05 19:15:35 (7771): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=8d
2021-08-05 19:15:35 (7771): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=8d
2021-08-05 19:15:35 (7771): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=8e
2021-08-05 19:15:35 (7771): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=8e
2021-08-05 19:15:35 (7771): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=8f
2021-08-05 19:15:35 (7771): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=8f
2021-08-05 19:15:40 (7771): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds
2021-08-05 19:15:40 (7771): Guest Log: vboxguest: misc device minor 58, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000)
2021-08-05 19:15:42 (7771): Guest Log: Checking CVMFS...
2021-08-05 19:15:46 (7771): Guest Log: CVMFS is ok
2021-08-05 19:15:46 (7771): Guest Log: VBoxService 5.2.32 r132073 (verbosity: 0) linux.amd64 (Jul 12 2019 10:32:28) release log
2021-08-05 19:15:46 (7771): Guest Log: 00:00:00.000182 main     Log opened 2021-08-05T19:15:44.127933000Z
2021-08-05 19:15:46 (7771): Guest Log: 00:00:00.000275 main     OS Product: Linux
2021-08-05 19:15:46 (7771): Guest Log: 00:00:00.000297 main     OS Release: 3.10.0-957.27.2.el7.x86_64
2021-08-05 19:15:46 (7771): Guest Log: 00:00:00.000316 main     OS Version: #1 SMP Mon Jul 29 17:46:05 UTC 2019
2021-08-05 19:15:46 (7771): Guest Log: 00:00:00.000334 main     Executable: /opt/VBoxGuestAdditions-5.2.32/sbin/VBoxService
2021-08-05 19:15:46 (7771): Guest Log: 00:00:00.000334 main     Process ID: 2064
2021-08-05 19:15:46 (7771): Guest Log: 00:00:00.000335 main     Package type: LINUX_64BITS_GENERIC
2021-08-05 19:15:46 (7771): Guest Log: 00:00:00.003710 main     5.2.32 r132073 started. Verbose level = 0
2021-08-05 19:15:47 (7771): Guest Log: Mounting shared directory
2021-08-05 19:15:47 (7771): Guest Log: Copying input files
2021-08-05 19:15:47 (7771): Guest Log: Copied input files into RunAtlas.
2021-08-05 19:15:48 (7771): Guest Log: copied the webapp to /var/www
2021-08-05 19:15:48 (7771): Guest Log: set up http_proxy http://192.168.1.100:3128
2021-08-05 19:15:48 (7771): Guest Log: Detected squid proxy http://192.168.1.100:3128 - will set in /etc/cvmfs/default.local
2021-08-05 19:15:56 (7771): Guest Log: 00:00:10.013365 timesync vgsvcTimeSyncWorker: Radical guest time change: 14 412 257 326 000ns (GuestNow=1 628 205 356 390 689 000 ns GuestLast=1 628 190 944 133 363 000 ns fSetTimeLastLoop=true )
2021-08-05 19:17:51 (7771): Guest Log: ATHENA_PROC_NUMBER=8
2021-08-05 19:17:51 (7771): Guest Log:  *** Starting ATLAS job. (PandaID=5141784114 taskID=NULL&cor) ***
2021-08-05 19:25:06 (7771): VM state change detected. (old = 'running', new = 'paused')
2021-08-05 20:55:36 (7771): VM state change detected. (old = 'paused', new = 'running')
2021-08-05 20:55:36 (7771): Guest Log: 00:09:19.376384 timesync vgsvcTimeSyncWorker: Radical host time change: 5 440 460 000 000ns (HostNow=1 628 211 280 301 000 000 ns HostLast=1 628 205 839 841 000 000 ns)
2021-08-05 20:55:46 (7771): Guest Log: 00:09:29.376769 timesync vgsvcTimeSyncWorker: Radical guest time change: 5 384 548 697 000ns (GuestNow=1 628 211 290 301 716 000 ns GuestLast=1 628 205 905 753 019 000 ns fSetTimeLastLoop=true )
2021-08-05 21:05:37 (7771): Guest Log:  *** Job finished ***
2021-08-05 21:05:37 (7771): Guest Log:  *** The last 20 lines of the pilot log: ***
2021-08-05 21:05:37 (7771): Guest Log:  *** Error codes and diagnostics ***
2021-08-05 21:05:37 (7771): Guest Log:     "exeErrorCode": 0,
2021-08-05 21:05:37 (7771): Guest Log:     "exeErrorDiag": "",
2021-08-05 21:05:37 (7771): Guest Log:     "pilotErrorCode": 0,
2021-08-05 21:05:37 (7771): Guest Log:     "pilotErrorDiag": "",
2021-08-05 21:05:37 (7771): Guest Log:  *** Listing of results directory ***
2021-08-05 21:05:37 (7771): Guest Log: total 8708
2021-08-05 21:05:37 (7771): Guest Log: -rw-r--r--. 1 atlas atlas  340725 Aug  5 14:24 pilot2.tar.gz
2021-08-05 21:05:37 (7771): Guest Log: -rwx------. 1 atlas atlas   20591 Aug  5 14:33 runpilot2-wrapper.sh
2021-08-05 21:05:37 (7771): Guest Log: -rw-r--r--. 1 atlas atlas    3214 Aug  5 14:37 queuedata.json
2021-08-05 21:05:37 (7771): Guest Log: -rw-r--r--. 1 atlas atlas  350503 Aug  5 23:15 input.tar.gz
2021-08-05 21:05:37 (7771): Guest Log: -rw-r--r--. 1 atlas atlas   16551 Aug  5 23:15 start_atlas.sh
2021-08-05 21:05:37 (7771): Guest Log: -rw-r--r--. 1 atlas atlas    9045 Aug  5 23:15 init_data.xml
2021-08-05 21:05:37 (7771): Guest Log: lrwxrwxrwx. 1 atlas atlas      20 Aug  5 23:15 EVNT.04972714._000031.pool.root.1 -> /data/./ATLAS.root_0
2021-08-05 21:05:37 (7771): Guest Log: -rw-r--r--. 1 atlas atlas    2610 Aug  5 23:17 pandaJob.out
2021-08-05 21:05:37 (7771): Guest Log: -rw-------. 1 atlas atlas     463 Aug  5 23:17 setup.sh.local
2021-08-05 21:05:37 (7771): Guest Log: -rw-------. 1 atlas atlas 1079124 Aug  5 23:18 agis_schedconf.cvmfs.json
2021-08-05 21:05:37 (7771): Guest Log: -rw-------. 1 atlas atlas 1962947 Aug  5 23:18 cric_ddmendpoints.json
2021-08-05 21:05:37 (7771): Guest Log: drwx------. 4 atlas atlas     260 Aug  5 23:18 pilot2
2021-08-05 21:05:37 (7771): Guest Log: -rw-------. 1 atlas atlas 2161137 Aug  6 01:03 output.1.00fb3843-a267-4c63-9c51-b0e5bc06689f_76927.pool.root
2021-08-05 21:05:37 (7771): Guest Log: -rw-------. 1 atlas atlas    1011 Aug  6 01:03 memory_monitor_summary.json
2021-08-05 21:05:37 (7771): Guest Log: -rw-------. 1 atlas atlas  163032 Aug  6 01:03 00fb3843-a267-4c63-9c51-b0e5bc06689f_76927.1.job.log.tgz
2021-08-05 21:05:37 (7771): Guest Log: -rw-------. 1 atlas atlas    7371 Aug  6 01:03 heartbeat.json
2021-08-05 21:05:37 (7771): Guest Log: -rw-------. 1 atlas atlas    7487 Aug  6 01:04 pilotlog.txt
2021-08-05 21:05:37 (7771): Guest Log: -rw-------. 1 atlas atlas  192076 Aug  6 01:04 00fb3843-a267-4c63-9c51-b0e5bc06689f_76927.1.job.log
2021-08-05 21:05:37 (7771): Guest Log: -rw-------. 1 atlas atlas     536 Aug  6 01:04 output.list
2021-08-05 21:05:37 (7771): Guest Log: -rw-r--r--. 1 atlas atlas     748 Aug  6 01:04 runtime_log
2021-08-05 21:05:37 (7771): Guest Log: -rw-r--r--. 1 atlas atlas   10678 Aug  6 01:04 runtime_log.err
2021-08-05 21:05:37 (7771): Guest Log: -rw-------. 1 atlas atlas 2539520 Aug  6 01:04 result.tar.gz
2021-08-05 21:05:37 (7771): Guest Log: -rw-------. 1 atlas atlas     559 Aug  6 01:04 9sBMDmdctVzn7Olcko1bjSoqABFKDmABFKDmumdXDmQcGKDmWNRpmn.diag
2021-08-05 21:05:37 (7771): Guest Log: Looking for outputfile output.1.00fb3843-a267-4c63-9c51-b0e5bc06689f_76927.pool.root
2021-08-05 21:05:37 (7771): Guest Log: HITS file was successfully produced
2021-08-05 21:05:37 (7771): Guest Log: -rw-------. 1 atlas atlas 2161137 Aug  6 01:03 /home/atlas/RunAtlas/output.1.00fb3843-a267-4c63-9c51-b0e5bc06689f_76927.pool.root
2021-08-05 21:05:37 (7771): Guest Log: Successfully finished the ATLAS job!
2021-08-05 21:05:37 (7771): Guest Log: Copying the results back to the shared directory!
2021-08-05 21:05:37 (7771): Guest Log:  *** Contents of shared directory: ***
2021-08-05 21:05:37 (7771): Guest Log: total 41796
2021-08-05 21:05:37 (7771): Guest Log: -rw-r--r--. 1 root root 37708683 Aug  5 23:15 ATLAS.root_0
2021-08-05 21:05:37 (7771): Guest Log: -rw-r--r--. 1 root root  2161137 Aug  6 01:03 HITS.pool.root.1
2021-08-05 21:05:37 (7771): Guest Log: -rw-r--r--. 1 root root     9045 Aug  5 23:15 init_data.xml
2021-08-05 21:05:37 (7771): Guest Log: -rw-r--r--. 1 root root   350503 Aug  5 23:15 input.tar.gz
2021-08-05 21:05:37 (7771): Guest Log: -rw-r--r--. 1 root root  2539520 Aug  6  2021 result.tar.gz
2021-08-05 21:05:37 (7771): Guest Log: -rw-r--r--. 1 root root    16551 Aug  5 23:15 start_atlas.sh
2021-08-05 21:05:37 (7771): Guest Log:  *** Success! Shutting down the machine. ***
2021-08-05 21:05:37 (7771): VM Completion File Detected.
2021-08-05 21:05:37 (7771): Powering off VM.
2021-08-05 21:05:38 (7771): Successfully stopped VM.
2021-08-05 21:05:38 (7771): Deregistering VM. (boinc_20c36e9349056e23, slot#12)
2021-08-05 21:05:38 (7771): Removing network bandwidth throttle group from VM.
2021-08-05 21:05:38 (7771): Removing storage controller(s) from VM.
2021-08-05 21:05:38 (7771): Removing VM from VirtualBox.
2021-08-05 21:05:38 (7771): Removing virtual disk drive from VirtualBox.
21:05:43 (7771): called boinc_finish(0)

</stderr_txt>
]]>

©2024 CERN