Stderr output

<core_client_version>8.2.4</core_client_version>
<![CDATA[
<stderr_txt>
2025-11-21 13:51:09 (14236): vboxwrapper version 26210
2025-11-21 13:51:09 (14236): BOINC client version: 8.2.4
2025-11-21 13:51:10 (14236): Detected: VirtualBox VboxManage Interface (Version: 7.1.4)
2025-11-21 13:51:10 (14236): Successfully copied 'init_data.xml' to the shared directory.
2025-11-21 13:51:11 (14236): Create VM. (boinc_fae7b5c2f22eb297, slot#5)
2025-11-21 13:51:11 (14236): Setting Memory Size for VM. (4500MB)
2025-11-21 13:51:11 (14236): Setting CPU Count for VM. (5)
2025-11-21 13:51:12 (14236): Setting Chipset Options for VM.
2025-11-21 13:51:12 (14236): Setting Graphics Controller Options for VM. (Driver: VBoxVGA, 16MB)
2025-11-21 13:51:12 (14236): Setting Boot Options for VM.
2025-11-21 13:51:13 (14236): Setting Network Configuration for NAT. (Driver: virtio)
2025-11-21 13:51:13 (14236): Enabling VM Network Access.
2025-11-21 13:51:13 (14236): Disabling USB Support for VM.
2025-11-21 13:51:14 (14236): Disabling COM Port Support for VM.
2025-11-21 13:51:14 (14236): Disabling LPT Port Support for VM.
2025-11-21 13:51:14 (14236): Disabling Audio Support for VM.
2025-11-21 13:51:14 (14236): Disabling Clipboard Support for VM.
2025-11-21 13:51:15 (14236): Disabling Drag and Drop Support for VM.
2025-11-21 13:51:15 (14236): Adding storage controller(s) to VM.
2025-11-21 13:51:15 (14236): Adding virtual disk drive to VM. (ATLAS_vbox_3.03_image.vdi)
2025-11-21 13:51:17 (14236): Adding VirtualBox Guest Additions to VM.
2025-11-21 13:51:18 (14236): Adding network bandwidth throttle group to VM. (Defaulting to 1024GB)
2025-11-21 13:51:18 (14236): forwarding host port 49270 to guest port 80
2025-11-21 13:51:18 (14236): Enabling remote desktop for VM.
2025-11-21 13:51:19 (14236): Enabling shared directory for VM.
2025-11-21 13:51:19 (14236): Starting VM using VBoxManage interface. (boinc_fae7b5c2f22eb297, slot#5)
2025-11-21 13:51:26 (14236): Successfully started VM. (PID = '23648')
2025-11-21 13:51:26 (14236): Reporting VM Process ID to BOINC.
2025-11-21 13:51:26 (14236): Guest Log: BIOS: VirtualBox 7.1.4
2025-11-21 13:51:26 (14236): Guest Log: CPUID EDX: 0x178bfbff
2025-11-21 13:51:26 (14236): Guest Log: BIOS: No PCI IDE controller, not probing IDE
2025-11-21 13:51:26 (14236): Guest Log: BIOS: AHCI 0-P#0: PCHS=16383/16/63 LCHS=1024/255/63 0x0000000002800000 sectors
2025-11-21 13:51:26 (14236): VM state change detected. (old = 'poweredoff', new = 'running')
2025-11-21 13:51:26 (14236): Detected: Web Application Enabled (http://localhost:49270)
2025-11-21 13:51:26 (14236): Detected: Remote Desktop Enabled (localhost:49273)
2025-11-21 13:51:27 (14236): VM state change detected. (old = 'running', new = 'paused')
2025-11-21 13:51:30 (14236): Preference change detected
2025-11-21 13:51:30 (14236): Setting CPU throttle for VM. (90%)
2025-11-21 13:51:30 (14236): Setting checkpoint interval to 900 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 900 seconds))
2025-11-21 13:51:31 (14236): VM state change detected. (old = 'paused', new = 'running')
2025-11-21 13:51:32 (14236): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032
2025-11-21 13:51:32 (14236): Guest Log: BIOS: Booting from Hard Disk...
2025-11-21 13:51:35 (14236): Guest Log: BIOS: KBD: unsupported int 16h function 03
2025-11-21 13:51:35 (14236): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 
2025-11-21 13:51:35 (14236): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=81
2025-11-21 13:51:35 (14236): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=81
2025-11-21 13:51:35 (14236): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=82
2025-11-21 13:51:35 (14236): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=82
2025-11-21 13:51:35 (14236): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=83
2025-11-21 13:51:35 (14236): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=83
2025-11-21 13:51:35 (14236): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=84
2025-11-21 13:51:35 (14236): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=84
2025-11-21 13:51:35 (14236): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=85
2025-11-21 13:51:35 (14236): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=85
2025-11-21 13:51:35 (14236): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=86
2025-11-21 13:51:35 (14236): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=86
2025-11-21 13:51:35 (14236): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=87
2025-11-21 13:51:35 (14236): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=87
2025-11-21 13:51:35 (14236): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=88
2025-11-21 13:51:35 (14236): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=88
2025-11-21 13:51:35 (14236): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=89
2025-11-21 13:51:35 (14236): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=89
2025-11-21 13:51:35 (14236): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=8a
2025-11-21 13:51:35 (14236): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=8a
2025-11-21 13:51:35 (14236): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=8b
2025-11-21 13:51:35 (14236): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=8b
2025-11-21 13:51:35 (14236): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=8c
2025-11-21 13:51:35 (14236): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=8c
2025-11-21 13:51:35 (14236): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=8d
2025-11-21 13:51:35 (14236): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=8d
2025-11-21 13:51:35 (14236): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=8e
2025-11-21 13:51:35 (14236): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=8e
2025-11-21 13:51:35 (14236): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=8f
2025-11-21 13:51:35 (14236): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=8f
2025-11-21 13:51:38 (14236): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds
2025-11-21 13:51:38 (14236): Guest Log: vboxguest: misc device minor 58, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000)
2025-11-21 13:51:41 (14236): VM state change detected. (old = 'running', new = 'paused')
2025-11-21 13:52:01 (14236): VM state change detected. (old = 'paused', new = 'running')
2025-11-21 13:52:02 (14236): Guest Log: VBoxService 5.2.32 r132073 (verbosity: 0) linux.amd64 (Jul 12 2019 10:32:28) release log
2025-11-21 13:52:02 (14236): Guest Log: 00:00:00.000106 main     Log opened 2025-11-21T13:51:36.220370000Z
2025-11-21 13:52:02 (14236): Guest Log: 00:00:00.000213 main     OS Product: Linux
2025-11-21 13:52:02 (14236): Guest Log: 00:00:00.000240 main     OS Release: 3.10.0-957.27.2.el7.x86_64
2025-11-21 13:52:02 (14236): Guest Log: 00:00:00.000262 main     OS Version: #1 SMP Mon Jul 29 17:46:05 UTC 2019
2025-11-21 13:52:02 (14236): Guest Log: 00:00:00.000284 main     Executable: /opt/VBoxGuestAdditions-5.2.32/sbin/VBoxService
2025-11-21 13:52:02 (14236): Guest Log: 00:00:00.000285 main     Process ID: 1330
2025-11-21 13:52:02 (14236): Guest Log: 00:00:00.000285 main     Package type: LINUX_64BITS_GENERIC
2025-11-21 13:52:02 (14236): Guest Log: 00:00:00.001030 main     5.2.32 r132073 started. Verbose level = 0
2025-11-21 13:52:02 (14236): Guest Log: [INFO] Probing /cvmfs/atlas.cern.ch...
2025-11-21 13:52:02 (14236): Guest Log: [INFO] Mounting shared directory
2025-11-21 13:52:02 (14236): Guest Log: [INFO] Checking for init_data.xml
2025-11-21 13:52:06 (14236): Guest Log: [INFO] Probing /cvmfs/atlas.cern.ch... OK
2025-11-21 13:52:06 (14236): Guest Log: [INFO] Detected branch: prod
2025-11-21 13:52:06 (14236): Guest Log: This is the prod version of the ATLAS job wrapper
2025-11-21 13:52:06 (14236): Guest Log: Copying input files
2025-11-21 13:52:07 (14236): Guest Log: Copied input files into RunAtlas.
2025-11-21 13:52:07 (14236): Guest Log: This VM did not configure a local http proxy via BOINC.
2025-11-21 13:52:07 (14236): Guest Log: Small home clusters do not require a local http proxy but it is suggested if
2025-11-21 13:52:07 (14236): Guest Log: more than 10 cores throughout the same LAN segment are regularly running ATLAS like tasks.
2025-11-21 13:52:07 (14236): Guest Log: Further information can be found at the LHC@home message board.
2025-11-21 13:52:12 (14236): Guest Log: 00:00:10.018792 timesync vgsvcTimeSyncWorker: Radical guest time change: 18 035 491 770 000ns (GuestNow=1 763 751 131 714 171 000 ns GuestLast=1 763 733 096 222 401 000 ns fSetTimeLastLoop=true )
2025-11-21 13:52:16 (14236): Guest Log: Running cvmfs_config stat atlas.cern.ch
2025-11-21 13:52:16 (14236): Guest Log: VERSION PID UPTIME(M) MEM(K) REVISION EXPIRES(M) NOCATALOGS CACHEUSE(K) CACHEMAX(K) NOFDUSE NOFDMAX NOIOERR NOOPEN HITRATE(%) RX(K) SPEED(K/S) HOST PROXY ONLINE
2025-11-21 13:52:16 (14236): Guest Log: 2.6.3.0 1609 0 30288 153139 4 1 3120538 4096000 0 65024 0 0 n/a 1292 719 http://s1ral-cvmfs.openhtc.io/cvmfs/atlas.cern.ch DIRECT 1
2025-11-21 13:52:17 (14236): Guest Log: copied the webapp to /var/www
2025-11-21 13:52:17 (14236): Guest Log: ATHENA_PROC_NUMBER=5
2025-11-21 13:52:17 (14236): Guest Log: ATHENA_CORE_NUMBER=5
2025-11-21 13:52:28 (14236): Guest Log: Apptainer command /cvmfs/atlas.cern.ch/repo/containers/sw/apptainer/x86_64-el7/current/bin/apptainer exec -B /cvmfs,/data,/home/atlas/RunAtlas /cvmfs/atlas.cern.ch/repo/containers/fs/singularity/x86_64-centos7 
2025-11-21 13:52:28 (14236): Guest Log:  *** Starting ATLAS job. (PandaID=6893878722 taskID=47523449) ***
2025-11-21 14:14:34 (14236): VM state change detected. (old = 'running', new = 'paused')
2025-11-21 14:14:54 (14236): VM state change detected. (old = 'paused', new = 'running')
2025-11-21 14:27:04 (14236): VM state change detected. (old = 'running', new = 'paused')
2025-11-21 14:27:24 (14236): VM state change detected. (old = 'paused', new = 'running')
2025-11-21 14:36:35 (14236): VM state change detected. (old = 'running', new = 'paused')
2025-11-21 14:36:55 (14236): VM state change detected. (old = 'paused', new = 'running')
2025-11-21 14:38:15 (14236): VM state change detected. (old = 'running', new = 'paused')
2025-11-21 14:38:35 (14236): VM state change detected. (old = 'paused', new = 'running')
2025-11-21 14:42:55 (14236): VM state change detected. (old = 'running', new = 'paused')
2025-11-21 14:43:15 (14236): VM state change detected. (old = 'paused', new = 'running')
2025-11-21 14:43:25 (14236): VM state change detected. (old = 'running', new = 'paused')
2025-11-21 14:43:45 (14236): VM state change detected. (old = 'paused', new = 'running')
2025-11-21 14:43:55 (14236): VM state change detected. (old = 'running', new = 'paused')
2025-11-21 14:44:15 (14236): VM state change detected. (old = 'paused', new = 'running')
2025-11-21 14:44:35 (14236): VM state change detected. (old = 'running', new = 'paused')
2025-11-21 14:44:55 (14236): VM state change detected. (old = 'paused', new = 'running')
2025-11-21 14:45:15 (14236): VM state change detected. (old = 'running', new = 'paused')
2025-11-21 14:45:35 (14236): VM state change detected. (old = 'paused', new = 'running')
2025-11-21 14:45:55 (14236): VM state change detected. (old = 'running', new = 'paused')
2025-11-21 14:46:15 (14236): VM state change detected. (old = 'paused', new = 'running')
2025-11-21 14:46:35 (14236): VM state change detected. (old = 'running', new = 'paused')
2025-11-21 14:46:55 (14236): VM state change detected. (old = 'paused', new = 'running')
2025-11-21 14:47:05 (14236): VM state change detected. (old = 'running', new = 'paused')
2025-11-21 14:47:25 (14236): VM state change detected. (old = 'paused', new = 'running')
2025-11-21 14:47:35 (14236): VM state change detected. (old = 'running', new = 'paused')
2025-11-21 14:47:55 (14236): VM state change detected. (old = 'paused', new = 'running')
2025-11-21 14:48:05 (14236): VM state change detected. (old = 'running', new = 'paused')
2025-11-21 14:48:25 (14236): VM state change detected. (old = 'paused', new = 'running')
2025-11-21 14:48:35 (14236): VM state change detected. (old = 'running', new = 'paused')
2025-11-21 14:48:55 (14236): VM state change detected. (old = 'paused', new = 'running')
2025-11-21 14:49:05 (14236): VM state change detected. (old = 'running', new = 'paused')
2025-11-21 14:49:25 (14236): VM state change detected. (old = 'paused', new = 'running')
2025-11-21 14:49:45 (14236): VM state change detected. (old = 'running', new = 'paused')
2025-11-21 14:50:05 (14236): VM state change detected. (old = 'paused', new = 'running')
2025-11-21 14:50:25 (14236): VM state change detected. (old = 'running', new = 'paused')
2025-11-21 14:50:45 (14236): VM state change detected. (old = 'paused', new = 'running')
2025-11-21 14:51:48 (14236): Stopping VM.
2025-11-21 14:52:00 (14236): Successfully stopped VM.
2025-11-21 16:02:15 (26772): vboxwrapper version 26210
2025-11-21 16:02:15 (26772): BOINC client version: 8.2.4
2025-11-21 16:02:16 (26772): Detected: VirtualBox VboxManage Interface (Version: 7.1.4)
2025-11-21 16:02:18 (26772): Guest Log: BIOS: VirtualBox 7.1.4
2025-11-21 16:02:18 (26772): Guest Log: CPUID EDX: 0x178bfbff
2025-11-21 16:02:18 (26772): Guest Log: BIOS: No PCI IDE controller, not probing IDE
2025-11-21 16:02:18 (26772): Guest Log: BIOS: AHCI 0-P#0: PCHS=16383/16/63 LCHS=1024/255/63 0x0000000002800000 sectors
2025-11-21 16:02:18 (26772): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032
2025-11-21 16:02:18 (26772): Guest Log: BIOS: Booting from Hard Disk...
2025-11-21 16:02:18 (26772): Guest Log: BIOS: KBD: unsupported int 16h function 03
2025-11-21 16:02:18 (26772): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 
2025-11-21 16:02:18 (26772): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=81
2025-11-21 16:02:18 (26772): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=81
2025-11-21 16:02:18 (26772): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=82
2025-11-21 16:02:18 (26772): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=82
2025-11-21 16:02:18 (26772): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=83
2025-11-21 16:02:18 (26772): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=83
2025-11-21 16:02:18 (26772): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=84
2025-11-21 16:02:18 (26772): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=84
2025-11-21 16:02:18 (26772): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=85
2025-11-21 16:02:18 (26772): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=85
2025-11-21 16:02:18 (26772): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=86
2025-11-21 16:02:18 (26772): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=86
2025-11-21 16:02:18 (26772): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=87
2025-11-21 16:02:18 (26772): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=87
2025-11-21 16:02:18 (26772): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=88
2025-11-21 16:02:18 (26772): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=88
2025-11-21 16:02:18 (26772): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=89
2025-11-21 16:02:18 (26772): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=89
2025-11-21 16:02:18 (26772): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=8a
2025-11-21 16:02:18 (26772): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=8a
2025-11-21 16:02:18 (26772): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=8b
2025-11-21 16:02:18 (26772): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=8b
2025-11-21 16:02:18 (26772): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=8c
2025-11-21 16:02:18 (26772): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=8c
2025-11-21 16:02:18 (26772): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=8d
2025-11-21 16:02:18 (26772): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=8d
2025-11-21 16:02:18 (26772): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=8e
2025-11-21 16:02:18 (26772): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=8e
2025-11-21 16:02:18 (26772): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=8f
2025-11-21 16:02:18 (26772): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=8f
2025-11-21 16:02:18 (26772): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds
2025-11-21 16:02:18 (26772): Guest Log: vboxguest: misc device minor 58, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000)
2025-11-21 16:02:18 (26772): Guest Log: VBoxService 5.2.32 r132073 (verbosity: 0) linux.amd64 (Jul 12 2019 10:32:28) release log
2025-11-21 16:02:18 (26772): Guest Log: 00:00:00.000106 main     Log opened 2025-11-21T13:51:36.220370000Z
2025-11-21 16:02:18 (26772): Guest Log: 00:00:00.000213 main     OS Product: Linux
2025-11-21 16:02:18 (26772): Guest Log: 00:00:00.000240 main     OS Release: 3.10.0-957.27.2.el7.x86_64
2025-11-21 16:02:18 (26772): Guest Log: 00:00:00.000262 main     OS Version: #1 SMP Mon Jul 29 17:46:05 UTC 2019
2025-11-21 16:02:18 (26772): Guest Log: 00:00:00.001030 main     5.2.32 r132073 started. Verbose level = 0
2025-11-21 16:02:18 (26772): Guest Log: [INFO] Probing /cvmfs/atlas.cern.ch...
2025-11-21 16:02:18 (26772): Guest Log: [INFO] Mounting shared directory
2025-11-21 16:02:18 (26772): Guest Log: [INFO] Checking for init_data.xml
2025-11-21 16:02:18 (26772): Guest Log: [INFO] Probing /cvmfs/atlas.cern.ch... OK
2025-11-21 16:02:18 (26772): Guest Log: [INFO] Detected branch: prod
2025-11-21 16:02:18 (26772): Guest Log: This is the prod version of the ATLAS job wrapper
2025-11-21 16:02:18 (26772): Guest Log: Copying input files
2025-11-21 16:02:18 (26772): Guest Log: Copied input files into RunAtlas.
2025-11-21 16:02:18 (26772): Guest Log: This VM did not configure a local http proxy via BOINC.
2025-11-21 16:02:18 (26772): Guest Log: Small home clusters do not require a local http proxy but it is suggested if
2025-11-21 16:02:18 (26772): Guest Log: more than 10 cores throughout the same LAN segment are regularly running ATLAS like tasks.
2025-11-21 16:02:18 (26772): Guest Log: Further information can be found at the LHC@home message board.
2025-11-21 16:02:18 (26772): Guest Log: 00:00:10.018792 timesync vgsvcTimeSyncWorker: Radical guest time change: 18 035 491 770 000ns (GuestNow=1 763 751 131 714 171 000 ns GuestLast=1 763 733 096 222 401 000 ns fSetTimeLastLoop=true )
2025-11-21 16:02:18 (26772): Guest Log: Running cvmfs_config stat atlas.cern.ch
2025-11-21 16:02:18 (26772): Guest Log: VERSION PID UPTIME(M) MEM(K) REVISION EXPIRES(M) NOCATALOGS CACHEUSE(K) CACHEMAX(K) NOFDUSE NOFDMAX NOIOERR NOOPEN HITRATE(%) RX(K) SPEED(K/S) HOST PROXY ONLINE
2025-11-21 16:02:18 (26772): Guest Log: 2.6.3.0 1609 0 30288 153139 4 1 3120538 4096000 0 65024 0 0 n/a 1292 719 http://s1ral-cvmfs.openhtc.io/cvmfs/atlas.cern.ch DIRECT 1
2025-11-21 16:02:18 (26772): Guest Log: copied the webapp to /var/www
2025-11-21 16:02:18 (26772): Guest Log: ATHENA_PROC_NUMBER=5
2025-11-21 16:02:18 (26772): Guest Log: ATHENA_CORE_NUMBER=5
2025-11-21 16:02:18 (26772): Guest Log: Apptainer command /cvmfs/atlas.cern.ch/repo/containers/sw/apptainer/x86_64-el7/current/bin/apptainer exec -B /cvmfs,/data,/home/atlas/RunAtlas /cvmfs/atlas.cern.ch/repo/containers/fs/singularity/x86_64-centos7 
2025-11-21 16:02:18 (26772): Guest Log:  *** Starting ATLAS job. (PandaID=6893878722 taskID=47523449) ***
2025-11-21 16:02:18 (26772): Starting VM using VBoxManage interface. (boinc_fae7b5c2f22eb297, slot#5)
2025-11-21 16:02:42 (26772): Successfully started VM. (PID = '18712')
2025-11-21 16:02:42 (26772): Reporting VM Process ID to BOINC.
2025-11-21 16:02:42 (26772): VM state change detected. (old = 'poweredoff', new = 'running')
2025-11-21 16:02:42 (26772): Detected: Web Application Enabled (http://localhost:49270)
2025-11-21 16:02:42 (26772): Detected: Remote Desktop Enabled (localhost:49273)
2025-11-21 16:02:42 (26772): Preference change detected
2025-11-21 16:02:42 (26772): Setting CPU throttle for VM. (90%)
2025-11-21 16:02:42 (26772): Setting checkpoint interval to 900 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 900 seconds))
2025-11-21 16:02:45 (26772): Guest Log: 00:53:51.398506 timesync vgsvcTimeSyncWorker: Radical host time change: 4 263 525 000 000ns (HostNow=1 763 758 965 631 000 000 ns HostLast=1 763 754 702 106 000 000 ns)
2025-11-21 16:02:48 (26772): VM state change detected. (old = 'running', new = 'paused')
2025-11-21 16:03:08 (26772): VM state change detected. (old = 'paused', new = 'running')
2025-11-21 16:03:15 (26772): Guest Log: 00:54:01.406465 timesync vgsvcTimeSyncWorker: Radical guest time change: 4 622 544 340 000ns (GuestNow=1 763 758 975 638 668 000 ns GuestLast=1 763 754 353 094 328 000 ns fSetTimeLastLoop=true )
2025-11-21 16:37:41 (26772): VM state change detected. (old = 'running', new = 'paused')
2025-11-21 16:38:01 (26772): VM state change detected. (old = 'paused', new = 'running')
2025-11-21 16:42:11 (26772): VM state change detected. (old = 'running', new = 'paused')
2025-11-21 16:42:31 (26772): VM state change detected. (old = 'paused', new = 'running')
2025-11-21 16:50:11 (26772): Status Report: Elapsed Time: '6000.000000'
2025-11-21 16:50:11 (26772): Status Report: CPU Time: '23500.906250'
2025-11-21 16:57:13 (26772): VM state change detected. (old = 'running', new = 'paused')
2025-11-21 16:57:33 (26772): VM state change detected. (old = 'paused', new = 'running')
2025-11-21 16:58:23 (26772): VM state change detected. (old = 'running', new = 'paused')
2025-11-21 16:58:43 (26772): VM state change detected. (old = 'paused', new = 'running')
2025-11-21 17:03:04 (26772): Stopping VM.
2025-11-21 17:03:16 (26772): Successfully stopped VM.
2025-11-21 17:58:48 (32816): vboxwrapper version 26210
2025-11-21 17:58:48 (32816): BOINC client version: 8.2.4
2025-11-21 17:58:49 (32816): Detected: VirtualBox VboxManage Interface (Version: 7.1.4)
2025-11-21 17:58:51 (32816): Guest Log: 00:53:51.398506 timesync vgsvcTimeSyncWorker: Radical host time change: 4 263 525 000 000ns (HostNow=1 763 758 965 631 000 000 ns HostLast=1 763 754 702 106 000 000 ns)
2025-11-21 17:58:51 (32816): Guest Log: 00:54:01.406465 timesync vgsvcTimeSyncWorker: Radical guest time change: 4 622 544 340 000ns (GuestNow=1 763 758 975 638 668 000 ns GuestLast=1 763 754 353 094 328 000 ns fSetTimeLastLoop=true )
2025-11-21 17:58:51 (32816): Starting VM using VBoxManage interface. (boinc_fae7b5c2f22eb297, slot#5)
2025-11-21 17:59:08 (32816): Successfully started VM. (PID = '25684')
2025-11-21 17:59:08 (32816): Reporting VM Process ID to BOINC.
2025-11-21 17:59:08 (32816): VM state change detected. (old = 'poweredoff', new = 'running')
2025-11-21 17:59:08 (32816): Detected: Web Application Enabled (http://localhost:49270)
2025-11-21 17:59:08 (32816): Detected: Remote Desktop Enabled (localhost:49273)
2025-11-21 17:59:09 (32816): Guest Log: 01:52:32.889466 timesync vgsvcTimeSyncWorker: Radical host time change: 3 373 256 000 000ns (HostNow=1 763 765 948 693 000 000 ns HostLast=1 763 762 575 437 000 000 ns)
2025-11-21 17:59:09 (32816): VM state change detected. (old = 'running', new = 'paused')
2025-11-21 17:59:17 (32816): Status Report: Elapsed Time: '6730.000000'
2025-11-21 17:59:17 (32816): Status Report: CPU Time: '26773.234375'
2025-11-21 17:59:17 (32816): Preference change detected
2025-11-21 17:59:17 (32816): Setting CPU throttle for VM. (90%)
2025-11-21 17:59:18 (32816): Setting checkpoint interval to 900 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 900 seconds))
2025-11-21 17:59:18 (32816): VM state change detected. (old = 'paused', new = 'running')
2025-11-21 17:59:28 (32816): Guest Log: 01:52:42.892913 timesync vgsvcTimeSyncWorker: Radical guest time change: 3 471 592 315 000ns (GuestNow=1 763 765 958 705 092 000 ns GuestLast=1 763 762 487 112 777 000 ns fSetTimeLastLoop=true )
2025-11-21 17:59:28 (32816): VM state change detected. (old = 'running', new = 'paused')
2025-11-21 17:59:48 (32816): VM state change detected. (old = 'paused', new = 'running')
2025-11-21 18:27:11 (32816): VM state change detected. (old = 'running', new = 'paused')
2025-11-21 18:27:31 (32816): VM state change detected. (old = 'paused', new = 'running')
2025-11-21 18:59:35 (32816): Stopping VM.
2025-11-21 18:59:47 (32816): Successfully stopped VM.
2025-11-21 20:35:16 (21124): vboxwrapper version 26210
2025-11-21 20:35:16 (21124): BOINC client version: 8.2.4
2025-11-21 20:35:18 (21124): Detected: VirtualBox VboxManage Interface (Version: 7.1.4)
2025-11-21 20:35:19 (21124): Guest Log: 01:52:32.889466 timesync vgsvcTimeSyncWorker: Radical host time change: 3 373 256 000 000ns (HostNow=1 763 765 948 693 000 000 ns HostLast=1 763 762 575 437 000 000 ns)
2025-11-21 20:35:19 (21124): Guest Log: 01:52:42.892913 timesync vgsvcTimeSyncWorker: Radical guest time change: 3 471 592 315 000ns (GuestNow=1 763 765 958 705 092 000 ns GuestLast=1 763 762 487 112 777 000 ns fSetTimeLastLoop=true )
2025-11-21 20:35:19 (21124): Starting VM using VBoxManage interface. (boinc_fae7b5c2f22eb297, slot#5)
2025-11-21 20:35:30 (21124): Successfully started VM. (PID = '1380')
2025-11-21 20:35:30 (21124): Reporting VM Process ID to BOINC.
2025-11-21 20:35:30 (21124): VM state change detected. (old = 'poweredoff', new = 'running')
2025-11-21 20:35:30 (21124): Detected: Web Application Enabled (http://localhost:49270)
2025-11-21 20:35:30 (21124): Detected: Remote Desktop Enabled (localhost:49273)
2025-11-21 20:35:31 (21124): VM state change detected. (old = 'running', new = 'paused')
2025-11-21 20:35:38 (21124): Status Report: Elapsed Time: '10303.000000'
2025-11-21 20:35:38 (21124): Status Report: CPU Time: '42651.890625'
2025-11-21 20:35:38 (21124): Preference change detected
2025-11-21 20:35:38 (21124): Setting CPU throttle for VM. (90%)
2025-11-21 20:35:39 (21124): Setting checkpoint interval to 900 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 900 seconds))
2025-11-21 20:35:39 (21124): Guest Log: 02:52:14.269067 timesync vgsvcTimeSyncWorker: Radical host time change: 5 771 953 000 000ns (HostNow=1 763 775 339 279 000 000 ns HostLast=1 763 769 567 326 000 000 ns)
2025-11-21 20:35:39 (21124): VM state change detected. (old = 'paused', new = 'running')
2025-11-21 20:35:49 (21124): VM state change detected. (old = 'running', new = 'paused')
2025-11-21 20:36:09 (21124): Guest Log: 02:52:24.279555 timesync vgsvcTimeSyncWorker: Radical guest time change: 5 819 207 743 000ns (GuestNow=1 763 775 349 289 404 000 ns GuestLast=1 763 769 530 081 661 000 ns fSetTimeLastLoop=true )
2025-11-21 20:36:09 (21124): VM state change detected. (old = 'paused', new = 'running')
2025-11-21 21:27:44 (21124): VM state change detected. (old = 'running', new = 'paused')
2025-11-21 21:28:04 (21124): VM state change detected. (old = 'paused', new = 'running')
2025-11-21 21:36:04 (21124): Stopping VM.
2025-11-21 21:36:15 (21124): Successfully stopped VM.
2025-11-21 22:44:20 (19648): vboxwrapper version 26210
2025-11-21 22:44:20 (19648): BOINC client version: 8.2.4
2025-11-21 22:44:21 (19648): Detected: VirtualBox VboxManage Interface (Version: 7.1.4)
2025-11-21 22:44:22 (19648): Guest Log: 02:52:14.269067 timesync vgsvcTimeSyncWorker: Radical host time change: 5 771 953 000 000ns (HostNow=1 763 775 339 279 000 000 ns HostLast=1 763 769 567 326 000 000 ns)
2025-11-21 22:44:22 (19648): Guest Log: 02:52:24.279555 timesync vgsvcTimeSyncWorker: Radical guest time change: 5 819 207 743 000ns (GuestNow=1 763 775 349 289 404 000 ns GuestLast=1 763 769 530 081 661 000 ns fSetTimeLastLoop=true )
2025-11-21 22:44:22 (19648): Starting VM using VBoxManage interface. (boinc_fae7b5c2f22eb297, slot#5)
2025-11-21 22:44:33 (19648): Successfully started VM. (PID = '12440')
2025-11-21 22:44:33 (19648): Reporting VM Process ID to BOINC.
2025-11-21 22:44:33 (19648): VM state change detected. (old = 'poweredoff', new = 'running')
2025-11-21 22:44:33 (19648): Detected: Web Application Enabled (http://localhost:49270)
2025-11-21 22:44:33 (19648): Detected: Remote Desktop Enabled (localhost:49273)
2025-11-21 22:44:34 (19648): VM state change detected. (old = 'running', new = 'paused')
2025-11-21 22:44:45 (19648): Status Report: Elapsed Time: '13884.000000'
2025-11-21 22:44:45 (19648): Status Report: CPU Time: '58630.250000'
2025-11-21 22:44:45 (19648): Preference change detected
2025-11-21 22:44:45 (19648): Setting CPU throttle for VM. (90%)
2025-11-21 22:44:46 (19648): Setting checkpoint interval to 900 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 900 seconds))
2025-11-21 22:44:46 (19648): VM state change detected. (old = 'paused', new = 'running')
2025-11-21 22:44:49 (19648): Guest Log: 03:52:05.968730 timesync vgsvcTimeSyncWorker: Radical host time change: 4 130 356 000 000ns (HostNow=1 763 783 089 517 000 000 ns HostLast=1 763 778 959 161 000 000 ns)
2025-11-21 22:44:59 (19648): Guest Log: 03:52:15.969950 timesync vgsvcTimeSyncWorker: Radical guest time change: 4 168 539 547 000ns (GuestNow=1 763 783 099 518 286 000 ns GuestLast=1 763 778 930 978 739 000 ns fSetTimeLastLoop=true )
2025-11-21 23:08:59 (19648): VM state change detected. (old = 'running', new = 'paused')
2025-11-21 23:09:20 (19648): VM state change detected. (old = 'paused', new = 'running')
2025-11-21 23:27:01 (19648): VM state change detected. (old = 'running', new = 'paused')
2025-11-21 23:27:21 (19648): VM state change detected. (old = 'paused', new = 'running')
2025-11-21 23:32:31 (19648): VM state change detected. (old = 'running', new = 'paused')
2025-11-21 23:32:51 (19648): VM state change detected. (old = 'paused', new = 'running')
2025-11-21 23:37:52 (19648): VM state change detected. (old = 'running', new = 'paused')
2025-11-21 23:38:12 (19648): VM state change detected. (old = 'paused', new = 'running')
2025-11-21 23:39:02 (19648): VM state change detected. (old = 'running', new = 'paused')
2025-11-21 23:39:22 (19648): VM state change detected. (old = 'paused', new = 'running')
2025-11-21 23:43:33 (19648): VM state change detected. (old = 'running', new = 'paused')
2025-11-21 23:43:53 (19648): VM state change detected. (old = 'paused', new = 'running')
2025-11-21 23:44:52 (19648): Stopping VM.
2025-11-21 23:45:04 (19648): Successfully stopped VM.
2025-11-22 01:00:09 (14712): vboxwrapper version 26210
2025-11-22 01:00:09 (14712): BOINC client version: 8.2.4
2025-11-22 01:00:10 (14712): Detected: VirtualBox VboxManage Interface (Version: 7.1.4)
2025-11-22 01:00:11 (14712): Guest Log: 03:52:05.968730 timesync vgsvcTimeSyncWorker: Radical host time change: 4 130 356 000 000ns (HostNow=1 763 783 089 517 000 000 ns HostLast=1 763 778 959 161 000 000 ns)
2025-11-22 01:00:11 (14712): Guest Log: 03:52:15.969950 timesync vgsvcTimeSyncWorker: Radical guest time change: 4 168 539 547 000ns (GuestNow=1 763 783 099 518 286 000 ns GuestLast=1 763 778 930 978 739 000 ns fSetTimeLastLoop=true )
2025-11-22 01:00:11 (14712): Starting VM using VBoxManage interface. (boinc_fae7b5c2f22eb297, slot#5)
2025-11-22 01:00:25 (14712): Successfully started VM. (PID = '21036')
2025-11-22 01:00:25 (14712): Reporting VM Process ID to BOINC.
2025-11-22 01:00:25 (14712): VM state change detected. (old = 'poweredoff', new = 'running')
2025-11-22 01:00:25 (14712): Detected: Web Application Enabled (http://localhost:49270)
2025-11-22 01:00:25 (14712): Detected: Remote Desktop Enabled (localhost:49273)
2025-11-22 01:00:26 (14712): VM state change detected. (old = 'running', new = 'paused')
2025-11-22 01:00:40 (14712): Status Report: Elapsed Time: '17361.000000'
2025-11-22 01:00:40 (14712): Status Report: CPU Time: '74235.937500'
2025-11-22 01:00:40 (14712): Preference change detected
2025-11-22 01:00:40 (14712): Setting CPU throttle for VM. (90%)
2025-11-22 01:00:40 (14712): Setting checkpoint interval to 900 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 900 seconds))
2025-11-22 01:00:41 (14712): VM state change detected. (old = 'paused', new = 'running')
2025-11-22 01:00:48 (14712): Guest Log: 04:50:17.449085 timesync vgsvcTimeSyncWorker: Radical host time change: 4 556 693 000 000ns (HostNow=1 763 791 247 750 000 000 ns HostLast=1 763 786 691 057 000 000 ns)
2025-11-22 01:00:58 (14712): Guest Log: 04:50:27.456928 timesync vgsvcTimeSyncWorker: Radical guest time change: 4 676 760 632 000ns (GuestNow=1 763 791 257 757 946 000 ns GuestLast=1 763 786 580 997 314 000 ns fSetTimeLastLoop=true )
2025-11-22 01:43:45 (14712): VM state change detected. (old = 'running', new = 'paused')
2025-11-22 01:44:05 (14712): VM state change detected. (old = 'paused', new = 'running')
2025-11-22 02:01:05 (14712): Stopping VM.
2025-11-22 02:01:17 (14712): Successfully stopped VM.
2025-11-22 03:16:28 (27536): vboxwrapper version 26210
2025-11-22 03:16:28 (27536): BOINC client version: 8.2.4
2025-11-22 03:16:29 (27536): Detected: VirtualBox VboxManage Interface (Version: 7.1.4)
2025-11-22 03:16:31 (27536): Guest Log: 04:50:17.449085 timesync vgsvcTimeSyncWorker: Radical host time change: 4 556 693 000 000ns (HostNow=1 763 791 247 750 000 000 ns HostLast=1 763 786 691 057 000 000 ns)
2025-11-22 03:16:31 (27536): Guest Log: 04:50:27.456928 timesync vgsvcTimeSyncWorker: Radical guest time change: 4 676 760 632 000ns (GuestNow=1 763 791 257 757 946 000 ns GuestLast=1 763 786 580 997 314 000 ns fSetTimeLastLoop=true )
2025-11-22 03:16:31 (27536): Starting VM using VBoxManage interface. (boinc_fae7b5c2f22eb297, slot#5)
2025-11-22 03:16:42 (27536): Successfully started VM. (PID = '33136')
2025-11-22 03:16:42 (27536): Reporting VM Process ID to BOINC.
2025-11-22 03:16:42 (27536): VM state change detected. (old = 'poweredoff', new = 'running')
2025-11-22 03:16:42 (27536): Detected: Web Application Enabled (http://localhost:49270)
2025-11-22 03:16:42 (27536): Detected: Remote Desktop Enabled (localhost:49273)
2025-11-22 03:16:43 (27536): VM state change detected. (old = 'running', new = 'paused')
2025-11-22 03:16:55 (27536): Status Report: Elapsed Time: '20963.000000'
2025-11-22 03:16:55 (27536): Status Report: CPU Time: '90283.500000'
2025-11-22 03:16:55 (27536): Preference change detected
2025-11-22 03:16:55 (27536): Setting CPU throttle for VM. (90%)
2025-11-22 03:16:55 (27536): Setting checkpoint interval to 900 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 900 seconds))
2025-11-22 03:16:55 (27536): VM state change detected. (old = 'paused', new = 'running')
2025-11-22 03:16:57 (27536): Guest Log: 05:50:19.042124 timesync vgsvcTimeSyncWorker: Radical host time change: 4 558 220 000 000ns (HostNow=1 763 799 417 071 000 000 ns HostLast=1 763 794 858 851 000 000 ns)
2025-11-22 03:17:07 (27536): Guest Log: 05:50:29.042974 timesync vgsvcTimeSyncWorker: Radical guest time change: 4 577 732 785 000ns (GuestNow=1 763 799 427 073 887 000 ns GuestLast=1 763 794 849 341 102 000 ns fSetTimeLastLoop=true )
2025-11-22 03:21:47 (27536): VM state change detected. (old = 'running', new = 'paused')
2025-11-22 03:22:07 (27536): VM state change detected. (old = 'paused', new = 'running')
2025-11-22 03:43:59 (27536): VM state change detected. (old = 'running', new = 'paused')
2025-11-22 03:44:19 (27536): VM state change detected. (old = 'paused', new = 'running')
2025-11-22 04:17:20 (27536): Stopping VM.
2025-11-22 04:17:32 (27536): Successfully stopped VM.
2025-11-22 05:38:41 (32936): vboxwrapper version 26210
2025-11-22 05:38:42 (32936): BOINC client version: 8.2.4
2025-11-22 05:38:43 (32936): Detected: VirtualBox VboxManage Interface (Version: 7.1.4)
2025-11-22 05:38:44 (32936): Guest Log: 05:50:19.042124 timesync vgsvcTimeSyncWorker: Radical host time change: 4 558 220 000 000ns (HostNow=1 763 799 417 071 000 000 ns HostLast=1 763 794 858 851 000 000 ns)
2025-11-22 05:38:44 (32936): Guest Log: 05:50:29.042974 timesync vgsvcTimeSyncWorker: Radical guest time change: 4 577 732 785 000ns (GuestNow=1 763 799 427 073 887 000 ns GuestLast=1 763 794 849 341 102 000 ns fSetTimeLastLoop=true )
2025-11-22 05:38:44 (32936): Starting VM using VBoxManage interface. (boinc_fae7b5c2f22eb297, slot#5)
2025-11-22 05:38:58 (32936): Successfully started VM. (PID = '26472')
2025-11-22 05:38:58 (32936): Reporting VM Process ID to BOINC.
2025-11-22 05:38:58 (32936): VM state change detected. (old = 'poweredoff', new = 'running')
2025-11-22 05:38:58 (32936): Detected: Web Application Enabled (http://localhost:49270)
2025-11-22 05:38:58 (32936): Detected: Remote Desktop Enabled (localhost:49273)
2025-11-22 05:38:58 (32936): Status Report: Elapsed Time: '24544.000000'
2025-11-22 05:38:58 (32936): Status Report: CPU Time: '106263.734375'
2025-11-22 05:38:58 (32936): Preference change detected
2025-11-22 05:38:58 (32936): Setting CPU throttle for VM. (90%)
2025-11-22 05:38:58 (32936): Setting checkpoint interval to 900 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 900 seconds))
2025-11-22 05:39:04 (32936): Guest Log: 06:50:10.636689 timesync vgsvcTimeSyncWorker: Radical host time change: 4 906 915 000 000ns (HostNow=1 763 807 943 903 000 000 ns HostLast=1 763 803 036 988 000 000 ns)
2025-11-22 05:39:14 (32936): Guest Log: 06:50:20.644481 timesync vgsvcTimeSyncWorker: Radical guest time change: 4 945 244 214 000ns (GuestNow=1 763 807 953 911 172 000 ns GuestLast=1 763 803 008 666 958 000 ns fSetTimeLastLoop=true )
2025-11-22 05:44:21 (32936): VM state change detected. (old = 'running', new = 'paused')
2025-11-22 05:44:41 (32936): VM state change detected. (old = 'paused', new = 'running')
2025-11-22 06:07:09 (32936): Guest Log:  *** Job finished ***
2025-11-22 06:07:09 (32936): Guest Log:  *** The last 20 lines of the pilot log: ***
2025-11-22 06:07:09 (32936): Guest Log: 2025-11-22 11:06:42,230 | WARNING  | pilot monitor received instruction that args.graceful_stop has been set
2025-11-22 06:07:09 (32936): Guest Log: 2025-11-22 11:06:42,230 | WARNING  | will wait for a maximum of 300 s for threads to finish
2025-11-22 06:07:09 (32936): Guest Log: 2025-11-22 11:06:42,675 | INFO     | [monitor] cgroup control has ended
2025-11-22 06:07:09 (32936): Guest Log: 2025-11-22 11:06:44,436 | INFO     | only monitor.control thread still running - safe to abort: ['<_MainThread(MainThread, started 139751077967680)>', '<ExcThread(monitor, started 139750610544384)>']
2025-11-22 06:07:09 (32936): Guest Log: 2025-11-22 11:06:45,285 | WARNING  | job_aborted has been set - aborting pilot monitoring
2025-11-22 06:07:09 (32936): Guest Log: 2025-11-22 11:06:45,285 | INFO     | [monitor] control thread has ended
2025-11-22 06:07:09 (32936): Guest Log: 2025-11-22 11:06:49,468 | INFO     | all workflow threads have been joined
2025-11-22 06:07:09 (32936): Guest Log: 2025-11-22 11:06:49,468 | INFO     | end of generic workflow (traces error code: 0)
2025-11-22 06:07:09 (32936): Guest Log: 2025-11-22 11:06:49,468 | INFO     | traces error code: 0
2025-11-22 06:07:09 (32936): Guest Log: 2025-11-22 11:06:49,468 | INFO     | pilot has finished (exit code=0, shell exit code=0)
2025-11-22 06:07:09 (32936): Guest Log: 2025-11-22 11:06:49,528 [wrapper] ==== pilot stdout END ====
2025-11-22 06:07:09 (32936): Guest Log: 2025-11-22 11:06:49,531 [wrapper] ==== wrapper stdout RESUME ====
2025-11-22 06:07:09 (32936): Guest Log: 2025-11-22 11:06:49,533 [wrapper] pilotpid: 6032
2025-11-22 06:07:09 (32936): Guest Log: 2025-11-22 11:06:49,536 [wrapper] Pilot exit status: 0
2025-11-22 06:07:09 (32936): Guest Log: 2025-11-22 11:06:49,546 [wrapper] pandaids: 6893878722
2025-11-22 06:07:09 (32936): Guest Log: 2025-11-22 11:06:49,564 [wrapper] cleanup supervisor_pilot  7000 6033
2025-11-22 06:07:09 (32936): Guest Log: 2025-11-22 11:06:49,566 [wrapper] Test setup, not cleaning
2025-11-22 06:07:09 (32936): Guest Log: 2025-11-22 11:06:49,569 [wrapper] apfmon messages muted
2025-11-22 06:07:09 (32936): Guest Log: 2025-11-22 11:06:49,572 [wrapper] ==== wrapper stdout END ====
2025-11-22 06:07:09 (32936): Guest Log: 2025-11-22 11:06:49,575 [wrapper] ==== wrapper stderr END ====
2025-11-22 06:07:09 (32936): Guest Log:  *** Error codes and diagnostics ***
2025-11-22 06:07:09 (32936): Guest Log:     "exeErrorCode": 0,
2025-11-22 06:07:09 (32936): Guest Log:     "exeErrorDiag": "",
2025-11-22 06:07:09 (32936): Guest Log:     "pilotErrorCode": 0,
2025-11-22 06:07:09 (32936): Guest Log:     "pilotErrorDiag": "",
2025-11-22 06:07:09 (32936): Guest Log:  *** Listing of results directory ***
2025-11-22 06:07:09 (32936): Guest Log: total 907472
2025-11-22 06:07:09 (32936): Guest Log: -rw-r--r--. 1 atlas atlas    576529 Nov 20 18:04 pilot3.tar.gz
2025-11-22 06:07:09 (32936): Guest Log: -rw-r--r--. 1 atlas atlas      5112 Nov 20 19:01 queuedata.json
2025-11-22 06:07:09 (32936): Guest Log: -rwx------. 1 atlas atlas     36292 Nov 20 19:02 runpilot2-wrapper.sh
2025-11-22 06:07:09 (32936): Guest Log: -rwxr-xr-x. 1 atlas atlas     11783 Nov 21 18:52 init_data.xml
2025-11-22 06:07:09 (32936): Guest Log: -rwxr-xr-x. 1 atlas atlas    590104 Nov 21 18:52 input.tar.gz
2025-11-22 06:07:09 (32936): Guest Log: -rwxr-xr-x. 1 atlas atlas     15847 Nov 21 18:52 start_atlas.sh
2025-11-22 06:07:09 (32936): Guest Log: lrwxrwxrwx. 1 atlas atlas        20 Nov 21 18:52 EVNT.47493127._000814.pool.root.1 -> /data/./ATLAS.root_0
2025-11-22 06:07:09 (32936): Guest Log: -rw-r--r--. 1 atlas atlas      2784 Nov 21 18:52 pandaJob.out
2025-11-22 06:07:09 (32936): Guest Log: -rw-------. 1 atlas atlas    989798 Nov 21 18:54 agis_schedconf.cvmfs.json
2025-11-22 06:07:09 (32936): Guest Log: drwx------. 5 atlas atlas      4096 Nov 21 18:54 pilot3
2025-11-22 06:07:09 (32936): Guest Log: -rw-------. 1 atlas atlas 915788993 Nov 22 11:05 HITS.47523449._008133.pool.root.1
2025-11-22 06:07:09 (32936): Guest Log: -rw-------. 1 atlas atlas      1041 Nov 22 11:05 memory_monitor_summary.json
2025-11-22 06:07:09 (32936): Guest Log: -rw-------. 1 atlas atlas   1526164 Nov 22 11:05 agis_ddmendpoints.agis.ALL.json
2025-11-22 06:07:09 (32936): Guest Log: -rw-------. 1 atlas atlas   1383652 Nov 22 11:06 log.47523449._008133.job.log.tgz.1
2025-11-22 06:07:09 (32936): Guest Log: -rw-------. 1 atlas atlas      8635 Nov 22 11:06 heartbeat.json
2025-11-22 06:07:09 (32936): Guest Log: -rw-------. 1 atlas atlas        97 Nov 22 11:06 pilot_heartbeat.json
2025-11-22 06:07:09 (32936): Guest Log: -rw-------. 1 atlas atlas      4805 Nov 22 11:06 pilotlog.txt
2025-11-22 06:07:09 (32936): Guest Log: -rw-------. 1 atlas atlas   3420349 Nov 22 11:06 log.47523449._008133.job.log.1
2025-11-22 06:07:09 (32936): Guest Log: -rw-------. 1 atlas atlas       353 Nov 22 11:06 output.list
2025-11-22 06:07:09 (32936): Guest Log: -rw-r--r--. 1 atlas atlas       620 Nov 22 11:06 runtime_log
2025-11-22 06:07:09 (32936): Guest Log: -rw-------. 1 atlas atlas   4823040 Nov 22 11:06 result.tar.gz
2025-11-22 06:07:09 (32936): Guest Log: -rw-r--r--. 1 atlas atlas      8746 Nov 22 11:06 runtime_log.err
2025-11-22 06:07:09 (32936): Guest Log: -rw-------. 1 atlas atlas       664 Nov 22 11:06 IX9NDmu6Og8nsSi4ap6QjLDmwznN0nGgGQJmNLGKDmNQIKDmJQoHIm.diag
2025-11-22 06:07:09 (32936): Guest Log: Looking for outputfile HITS.47523449._008133.pool.root.1
2025-11-22 06:07:09 (32936): Guest Log: HITS file was successfully produced
2025-11-22 06:07:09 (32936): Guest Log: -rw-------. 1 atlas atlas 915788993 Nov 22 11:05 /home/atlas/RunAtlas/HITS.47523449._008133.pool.root.1
2025-11-22 06:07:12 (32936): Guest Log: Successfully finished the ATLAS job!
2025-11-22 06:07:12 (32936): Guest Log: Copying the results back to the shared directory!
2025-11-22 06:07:13 (32936): Guest Log:  *** Contents of shared directory: ***
2025-11-22 06:07:13 (32936): Guest Log: total 1300476
2025-11-22 06:07:13 (32936): Guest Log: -rwxrwxrwx. 1 root root 410447648 Nov 21 18:51 ATLAS.root_0
2025-11-22 06:07:13 (32936): Guest Log: -rwxrwxrwx. 1 root root 915788993 Nov 22  2025 HITS.pool.root.1
2025-11-22 06:07:13 (32936): Guest Log: -rwxrwxrwx. 1 root root     11783 Nov 21 18:51 init_data.xml
2025-11-22 06:07:13 (32936): Guest Log: -rwxrwxrwx. 1 root root    590104 Nov 21 07:02 input.tar.gz
2025-11-22 06:07:13 (32936): Guest Log: -rwxrwxrwx. 1 root root   4823040 Nov 22  2025 result.tar.gz
2025-11-22 06:07:13 (32936): Guest Log: -rwxrwxrwx. 1 root root     15847 Nov 21 07:02 start_atlas.sh
2025-11-22 06:07:13 (32936): Guest Log:  *** Success! Shutting down the machine. ***
2025-11-22 06:07:13 (32936): VM Completion File Detected.
2025-11-22 06:07:13 (32936): Powering off VM.
2025-11-22 06:07:14 (32936): Successfully stopped VM.
2025-11-22 06:07:14 (32936): Deregistering VM. (boinc_fae7b5c2f22eb297, slot#5)
2025-11-22 06:07:14 (32936): Removing network bandwidth throttle group from VM.
2025-11-22 06:07:14 (32936): Removing VM from VirtualBox.
2025-11-22 06:07:19 (32936): called boinc_finish(0)

</stderr_txt>
]]>

©2025 CERN