Stderr output

<core_client_version>8.0.2</core_client_version>
<![CDATA[
<stderr_txt>
2025-04-18 10:53:30 (23468): Detected: vboxwrapper 26206
2025-04-18 10:53:30 (23468): Detected: BOINC client v8.0.2
2025-04-18 10:53:31 (23468): Detected: VirtualBox VboxManage Interface (Version: 7.1.4)
2025-04-18 10:53:31 (23468): Successfully copied 'init_data.xml' to the shared directory.
2025-04-18 10:53:31 (23468): Create VM. (boinc_b94b2e80f6fb4e30, slot#0)
2025-04-18 10:53:32 (23468): Setting Memory Size for VM. (4300MB)
2025-04-18 10:53:32 (23468): Setting CPU Count for VM. (3)
2025-04-18 10:53:33 (23468): Setting Chipset Options for VM.
2025-04-18 10:53:33 (23468): Setting Graphics Controller Options for VM.
2025-04-18 10:53:33 (23468): Setting Boot Options for VM.
2025-04-18 10:53:33 (23468): Setting Network Configuration for NAT.
2025-04-18 10:53:34 (23468): Enabling VM Network Access.
2025-04-18 10:53:34 (23468): Disabling USB Support for VM.
2025-04-18 10:53:34 (23468): Disabling COM Port Support for VM.
2025-04-18 10:53:34 (23468): Disabling LPT Port Support for VM.
2025-04-18 10:53:35 (23468): Disabling Audio Support for VM.
2025-04-18 10:53:35 (23468): Disabling Clipboard Support for VM.
2025-04-18 10:53:35 (23468): Disabling Drag and Drop Support for VM.
2025-04-18 10:53:35 (23468): Adding storage controller(s) to VM.
2025-04-18 10:53:36 (23468): Adding virtual disk drive to VM. (ATLAS_vbox_3.01_image.vdi)
2025-04-18 10:53:39 (23468): Adding VirtualBox Guest Additions to VM.
2025-04-18 10:53:39 (23468): Adding network bandwidth throttle group to VM. (Defaulting to 1024GB)
2025-04-18 10:53:39 (23468): forwarding host port 32094 to guest port 80
2025-04-18 10:53:39 (23468): Enabling remote desktop for VM.
2025-04-18 10:53:40 (23468): Required extension pack not installed, remote desktop not enabled.
2025-04-18 10:53:40 (23468): Enabling shared directory for VM.
2025-04-18 10:53:40 (23468): Starting VM using VBoxManage interface. (boinc_b94b2e80f6fb4e30, slot#0)
2025-04-18 10:53:46 (23468): Successfully started VM. (PID = '22008')
2025-04-18 10:53:46 (23468): Reporting VM Process ID to BOINC.
2025-04-18 10:53:46 (23468): Guest Log: BIOS: VirtualBox 7.1.4
2025-04-18 10:53:46 (23468): Guest Log: CPUID EDX: 0x178bfbff
2025-04-18 10:53:46 (23468): Guest Log: BIOS: No PCI IDE controller, not probing IDE
2025-04-18 10:53:46 (23468): Guest Log: BIOS: AHCI 0-P#0: PCHS=16383/16/63 LCHS=1024/255/63 0x0000000002800000 sectors
2025-04-18 10:53:46 (23468): VM state change detected. (old = 'poweredoff', new = 'running')
2025-04-18 10:53:46 (23468): Detected: Web Application Enabled (http://localhost:32094)
2025-04-18 10:53:47 (23468): VM state change detected. (old = 'running', new = 'paused')
2025-04-18 10:54:00 (23468): Preference change detected
2025-04-18 10:54:00 (23468): Setting CPU throttle for VM. (80%)
2025-04-18 10:54:01 (23468): Setting checkpoint interval to 900 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 900 seconds))
2025-04-18 10:54:01 (23468): VM state change detected. (old = 'paused', new = 'running')
2025-04-18 10:54:02 (23468): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032
2025-04-18 10:54:02 (23468): Guest Log: BIOS: Booting from Hard Disk...
2025-04-18 10:54:05 (23468): Guest Log: BIOS: KBD: unsupported int 16h function 03
2025-04-18 10:54:05 (23468): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 
2025-04-18 10:54:05 (23468): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=81
2025-04-18 10:54:05 (23468): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=81
2025-04-18 10:54:05 (23468): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=82
2025-04-18 10:54:05 (23468): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=82
2025-04-18 10:54:05 (23468): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=83
2025-04-18 10:54:05 (23468): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=83
2025-04-18 10:54:05 (23468): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=84
2025-04-18 10:54:05 (23468): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=84
2025-04-18 10:54:05 (23468): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=85
2025-04-18 10:54:05 (23468): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=85
2025-04-18 10:54:05 (23468): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=86
2025-04-18 10:54:05 (23468): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=86
2025-04-18 10:54:05 (23468): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=87
2025-04-18 10:54:05 (23468): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=87
2025-04-18 10:54:05 (23468): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=88
2025-04-18 10:54:05 (23468): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=88
2025-04-18 10:54:05 (23468): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=89
2025-04-18 10:54:05 (23468): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=89
2025-04-18 10:54:05 (23468): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=8a
2025-04-18 10:54:05 (23468): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=8a
2025-04-18 10:54:05 (23468): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=8b
2025-04-18 10:54:05 (23468): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=8b
2025-04-18 10:54:05 (23468): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=8c
2025-04-18 10:54:05 (23468): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=8c
2025-04-18 10:54:05 (23468): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=8d
2025-04-18 10:54:05 (23468): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=8d
2025-04-18 10:54:05 (23468): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=8e
2025-04-18 10:54:05 (23468): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=8e
2025-04-18 10:54:05 (23468): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=8f
2025-04-18 10:54:05 (23468): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=8f
2025-04-18 10:54:11 (23468): VM state change detected. (old = 'running', new = 'paused')
2025-04-18 10:54:31 (23468): VM state change detected. (old = 'paused', new = 'running')
2025-04-18 10:54:32 (23468): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds
2025-04-18 10:54:32 (23468): Guest Log: vboxguest: misc device minor 58, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000)
2025-04-18 10:54:40 (23468): Guest Log: VBoxService 5.2.32 r132073 (verbosity: 0) linux.amd64 (Jul 12 2019 10:32:28) release log
2025-04-18 10:54:40 (23468): Guest Log: 00:00:00.000171 main     Log opened 2025-04-18T10:54:04.292329000Z
2025-04-18 10:54:40 (23468): Guest Log: 00:00:00.000295 main     OS Product: Linux
2025-04-18 10:54:40 (23468): Guest Log: 00:00:00.000336 main     OS Release: 3.10.0-957.27.2.el7.x86_64
2025-04-18 10:54:40 (23468): Guest Log: 00:00:00.000370 main     OS Version: #1 SMP Mon Jul 29 17:46:05 UTC 2019
2025-04-18 10:54:40 (23468): Guest Log: 00:00:00.000403 main     Executable: /opt/VBoxGuestAdditions-5.2.32/sbin/VBoxService
2025-04-18 10:54:40 (23468): Guest Log: 00:00:00.000404 main     Process ID: 1309
2025-04-18 10:54:40 (23468): Guest Log: 00:00:00.000405 main     Package type: LINUX_64BITS_GENERIC
2025-04-18 10:54:40 (23468): Guest Log: 00:00:00.001148 main     5.2.32 r132073 started. Verbose level = 0
2025-04-18 10:54:40 (23468): Guest Log: [INFO] Probing /cvmfs/atlas.cern.ch...
2025-04-18 10:54:40 (23468): Guest Log: [INFO] Mounting shared directory
2025-04-18 10:54:40 (23468): Guest Log: [INFO] Checking for init_data.xml
2025-04-18 10:54:41 (23468): VM state change detected. (old = 'running', new = 'paused')
2025-04-18 10:55:01 (23468): VM state change detected. (old = 'paused', new = 'running')
2025-04-18 10:55:04 (23468): Guest Log: [INFO] Probing /cvmfs/atlas.cern.ch... OK
2025-04-18 10:55:04 (23468): Guest Log: [INFO] Detected branch: prod
2025-04-18 10:55:04 (23468): Guest Log: This is the prod version of the ATLAS job wrapper
2025-04-18 10:55:04 (23468): Guest Log: Copying input files
2025-04-18 10:55:06 (23468): Guest Log: Copied input files into RunAtlas.
2025-04-18 10:55:06 (23468): Guest Log: This VM did not configure a local http proxy via BOINC.
2025-04-18 10:55:06 (23468): Guest Log: Small home clusters do not require a local http proxy but it is suggested if
2025-04-18 10:55:06 (23468): Guest Log: more than 10 cores throughout the same LAN segment are regularly running ATLAS like tasks.
2025-04-18 10:55:06 (23468): Guest Log: Further information can be found at the LHC@home message board.
2025-04-18 10:55:10 (23468): Guest Log: 00:00:10.008218 timesync vgsvcTimeSyncWorker: Radical guest time change: -7 154 603 036 000ns (GuestNow=1 744 966 489 693 137 000 ns GuestLast=1 744 973 644 296 173 000 ns fSetTimeLastLoop=true )
2025-04-18 10:55:17 (23468): Guest Log: Running cvmfs_config stat atlas.cern.ch
2025-04-18 10:55:17 (23468): 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-04-18 10:55:17 (23468): Guest Log: 2.6.3.0 1583 0 30192 144995 4 1 3117322 4096000 0 65024 0 0 n/a 0 0 http://s1bnl-cvmfs.openhtc.io/cvmfs/atlas.cern.ch DIRECT 1
2025-04-18 10:55:17 (23468): Guest Log: copied the webapp to /var/www
2025-04-18 10:55:17 (23468): Guest Log: ATHENA_PROC_NUMBER=3
2025-04-18 10:55:17 (23468): Guest Log: ATHENA_CORE_NUMBER=3
2025-04-18 10:55:18 (23468): 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-04-18 10:55:18 (23468): Guest Log:  *** Starting ATLAS job. (PandaID=6603679875 taskID=44075483) ***
2025-04-18 10:55:21 (23468): VM state change detected. (old = 'running', new = 'paused')
2025-04-18 10:55:41 (23468): VM state change detected. (old = 'paused', new = 'running')
2025-04-18 10:55:51 (23468): VM state change detected. (old = 'running', new = 'paused')
2025-04-18 10:56:11 (23468): VM state change detected. (old = 'paused', new = 'running')
2025-04-18 10:56:21 (23468): VM state change detected. (old = 'running', new = 'paused')
2025-04-18 10:56:41 (23468): VM state change detected. (old = 'paused', new = 'running')
2025-04-18 10:56:51 (23468): VM state change detected. (old = 'running', new = 'paused')
2025-04-18 10:57:11 (23468): VM state change detected. (old = 'paused', new = 'running')
2025-04-18 10:57:11 (23468): Stopping VM.
2025-04-18 10:57:26 (23468): Successfully stopped VM.
2025-04-18 10:58:05 (14756): Detected: vboxwrapper 26206
2025-04-18 10:58:05 (14756): Detected: BOINC client v8.0.2
2025-04-18 10:58:06 (14756): Detected: VirtualBox VboxManage Interface (Version: 7.1.4)
2025-04-18 10:58:07 (14756): Guest Log: BIOS: VirtualBox 7.1.4
2025-04-18 10:58:07 (14756): Guest Log: CPUID EDX: 0x178bfbff
2025-04-18 10:58:07 (14756): Guest Log: BIOS: No PCI IDE controller, not probing IDE
2025-04-18 10:58:07 (14756): Guest Log: BIOS: AHCI 0-P#0: PCHS=16383/16/63 LCHS=1024/255/63 0x0000000002800000 sectors
2025-04-18 10:58:07 (14756): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032
2025-04-18 10:58:07 (14756): Guest Log: BIOS: Booting from Hard Disk...
2025-04-18 10:58:07 (14756): Guest Log: BIOS: KBD: unsupported int 16h function 03
2025-04-18 10:58:07 (14756): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 
2025-04-18 10:58:07 (14756): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=81
2025-04-18 10:58:07 (14756): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=81
2025-04-18 10:58:07 (14756): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=82
2025-04-18 10:58:07 (14756): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=82
2025-04-18 10:58:07 (14756): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=83
2025-04-18 10:58:07 (14756): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=83
2025-04-18 10:58:07 (14756): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=84
2025-04-18 10:58:07 (14756): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=84
2025-04-18 10:58:07 (14756): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=85
2025-04-18 10:58:07 (14756): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=85
2025-04-18 10:58:07 (14756): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=86
2025-04-18 10:58:07 (14756): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=86
2025-04-18 10:58:07 (14756): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=87
2025-04-18 10:58:07 (14756): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=87
2025-04-18 10:58:07 (14756): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=88
2025-04-18 10:58:07 (14756): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=88
2025-04-18 10:58:07 (14756): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=89
2025-04-18 10:58:07 (14756): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=89
2025-04-18 10:58:07 (14756): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=8a
2025-04-18 10:58:07 (14756): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=8a
2025-04-18 10:58:07 (14756): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=8b
2025-04-18 10:58:07 (14756): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=8b
2025-04-18 10:58:07 (14756): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=8c
2025-04-18 10:58:07 (14756): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=8c
2025-04-18 10:58:07 (14756): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=8d
2025-04-18 10:58:07 (14756): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=8d
2025-04-18 10:58:07 (14756): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=8e
2025-04-18 10:58:07 (14756): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=8e
2025-04-18 10:58:07 (14756): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=8f
2025-04-18 10:58:07 (14756): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=8f
2025-04-18 10:58:07 (14756): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds
2025-04-18 10:58:07 (14756): Guest Log: vboxguest: misc device minor 58, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000)
2025-04-18 10:58:07 (14756): Guest Log: VBoxService 5.2.32 r132073 (verbosity: 0) linux.amd64 (Jul 12 2019 10:32:28) release log
2025-04-18 10:58:07 (14756): Guest Log: 00:00:00.000171 main     Log opened 2025-04-18T10:54:04.292329000Z
2025-04-18 10:58:07 (14756): Guest Log: 00:00:00.000295 main     OS Product: Linux
2025-04-18 10:58:07 (14756): Guest Log: 00:00:00.000336 main     OS Release: 3.10.0-957.27.2.el7.x86_64
2025-04-18 10:58:07 (14756): Guest Log: 00:00:00.000370 main     OS Version: #1 SMP Mon Jul 29 17:46:05 UTC 2019
2025-04-18 10:58:07 (14756): Guest Log: 00:00:00.000403 main     Executable: /opt/VBoxGuestAdditions-5.2.32/sbin/VBoxService
2025-04-18 10:58:07 (14756): Guest Log: 00:00:00.000404 main     Process ID: 1309
2025-04-18 10:58:07 (14756): Guest Log: 00:00:00.000405 main     Package type: LINUX_64BITS_GENERIC
2025-04-18 10:58:07 (14756): Guest Log: 00:00:00.001148 main     5.2.32 r132073 started. Verbose level = 0
2025-04-18 10:58:07 (14756): Guest Log: [INFO] Probing /cvmfs/atlas.cern.ch...
2025-04-18 10:58:07 (14756): Guest Log: [INFO] Mounting shared directory
2025-04-18 10:58:07 (14756): Guest Log: [INFO] Checking for init_data.xml
2025-04-18 10:58:07 (14756): Guest Log: [INFO] Probing /cvmfs/atlas.cern.ch... OK
2025-04-18 10:58:07 (14756): Guest Log: [INFO] Detected branch: prod
2025-04-18 10:58:07 (14756): Guest Log: This is the prod version of the ATLAS job wrapper
2025-04-18 10:58:07 (14756): Guest Log: Copying input files
2025-04-18 10:58:07 (14756): Guest Log: Copied input files into RunAtlas.
2025-04-18 10:58:07 (14756): Guest Log: This VM did not configure a local http proxy via BOINC.
2025-04-18 10:58:07 (14756): Guest Log: Small home clusters do not require a local http proxy but it is suggested if
2025-04-18 10:58:07 (14756): Guest Log: more than 10 cores throughout the same LAN segment are regularly running ATLAS like tasks.
2025-04-18 10:58:07 (14756): Guest Log: Further information can be found at the LHC@home message board.
2025-04-18 10:58:07 (14756): Guest Log: 00:00:10.008218 timesync vgsvcTimeSyncWorker: Radical guest time change: -7 154 603 036 000ns (GuestNow=1 744 966 489 693 137 000 ns GuestLast=1 744 973 644 296 173 000 ns fSetTimeLastLoop=true )
2025-04-18 10:58:07 (14756): Guest Log: Running cvmfs_config stat atlas.cern.ch
2025-04-18 10:58:07 (14756): 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-04-18 10:58:07 (14756): Guest Log: 2.6.3.0 1583 0 30192 144995 4 1 3117322 4096000 0 65024 0 0 n/a 0 0 http://s1bnl-cvmfs.openhtc.io/cvmfs/atlas.cern.ch DIRECT 1
2025-04-18 10:58:07 (14756): Guest Log: copied the webapp to /var/www
2025-04-18 10:58:07 (14756): Guest Log: ATHENA_PROC_NUMBER=3
2025-04-18 10:58:07 (14756): Guest Log: ATHENA_CORE_NUMBER=3
2025-04-18 10:58:07 (14756): 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-04-18 10:58:07 (14756): Guest Log:  *** Starting ATLAS job. (PandaID=6603679875 taskID=44075483) ***
2025-04-18 10:58:07 (14756): Starting VM using VBoxManage interface. (boinc_b94b2e80f6fb4e30, slot#0)
2025-04-18 10:58:16 (14756): Successfully started VM. (PID = '23228')
2025-04-18 10:58:16 (14756): Reporting VM Process ID to BOINC.
2025-04-18 10:58:16 (14756): VM state change detected. (old = 'poweredoff', new = 'running')
2025-04-18 10:58:16 (14756): Detected: Web Application Enabled (http://localhost:32094)
2025-04-18 10:58:16 (14756): Preference change detected
2025-04-18 10:58:16 (14756): Setting CPU throttle for VM. (80%)
2025-04-18 10:58:16 (14756): Setting checkpoint interval to 900 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 900 seconds))
2025-04-18 10:58:29 (14756): Stopping VM.
2025-04-18 10:58:35 (14756): Successfully stopped VM.
2025-04-18 10:58:39 (19104): Detected: vboxwrapper 26206
2025-04-18 10:58:39 (19104): Detected: BOINC client v8.0.2
2025-04-18 10:58:40 (19104): Detected: VirtualBox VboxManage Interface (Version: 7.1.4)
2025-04-18 10:58:40 (19104): Starting VM using VBoxManage interface. (boinc_b94b2e80f6fb4e30, slot#0)
2025-04-18 10:58:49 (19104): Successfully started VM. (PID = '21184')
2025-04-18 10:58:49 (19104): Reporting VM Process ID to BOINC.
2025-04-18 10:58:49 (19104): VM state change detected. (old = 'poweredoff', new = 'running')
2025-04-18 10:58:49 (19104): Detected: Web Application Enabled (http://localhost:32094)
2025-04-18 10:58:50 (19104): VM state change detected. (old = 'running', new = 'paused')
2025-04-18 10:59:02 (19104): Preference change detected
2025-04-18 10:59:02 (19104): Setting CPU throttle for VM. (80%)
2025-04-18 10:59:03 (19104): Setting checkpoint interval to 900 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 900 seconds))
2025-04-18 10:59:03 (19104): VM state change detected. (old = 'paused', new = 'running')
2025-04-18 10:59:04 (19104): Stopping VM.
2025-04-18 10:59:11 (19104): Successfully stopped VM.
2025-04-18 11:02:09 (11144): Detected: vboxwrapper 26206
2025-04-18 11:02:09 (11144): Detected: BOINC client v8.0.2
2025-04-18 11:02:10 (11144): Detected: VirtualBox VboxManage Interface (Version: 7.1.4)
2025-04-18 11:02:11 (11144): ERROR: VM failed to start
2025-04-18 11:02:16 (11144): 
   BOINC will be notified that it needs to clean up the environment.
         This is a temporary problem and so this job will be rescheduled for another time.

2025-04-18 11:02:16 (22520): Detected: vboxwrapper 26206
2025-04-18 11:02:16 (22520): Detected: BOINC client v8.0.2
2025-04-18 11:02:17 (22520): Detected: VirtualBox VboxManage Interface (Version: 7.1.4)
2025-04-18 11:02:18 (22520): Starting VM using VBoxManage interface. (boinc_b94b2e80f6fb4e30, slot#0)
2025-04-18 11:02:26 (22520): Successfully started VM. (PID = '23468')
2025-04-18 11:02:26 (22520): Reporting VM Process ID to BOINC.
2025-04-18 11:02:26 (22520): VM state change detected. (old = 'poweredoff', new = 'running')
2025-04-18 11:02:26 (22520): Detected: Web Application Enabled (http://localhost:32094)
2025-04-18 11:02:26 (22520): Stopping VM.
2025-04-18 11:02:33 (22520): Successfully stopped VM.
2025-04-18 11:03:53 (23800): Detected: vboxwrapper 26206
2025-04-18 11:03:53 (23800): Detected: BOINC client v8.0.2
2025-04-18 11:03:54 (23800): Detected: VirtualBox VboxManage Interface (Version: 7.1.4)
2025-04-18 11:03:55 (23800): ERROR: VM failed to start
2025-04-18 11:04:00 (23800): 
   BOINC will be notified that it needs to clean up the environment.
         This is a temporary problem and so this job will be rescheduled for another time.

2025-04-18 11:05:56 (13252): Detected: vboxwrapper 26206
2025-04-18 11:05:56 (13252): Detected: BOINC client v8.0.2
2025-04-18 11:05:57 (13252): Detected: VirtualBox VboxManage Interface (Version: 7.1.4)
2025-04-18 11:05:58 (13252): ERROR: VM failed to start
2025-04-18 11:06:03 (13252): 
   BOINC will be notified that it needs to clean up the environment.
         This is a temporary problem and so this job will be rescheduled for another time.

2025-04-18 11:06:16 (15312): Detected: vboxwrapper 26206
2025-04-18 11:06:16 (15312): Detected: BOINC client v8.0.2
2025-04-18 11:06:17 (15312): Detected: VirtualBox VboxManage Interface (Version: 7.1.4)
2025-04-18 11:06:17 (15312): ERROR: VM failed to start
2025-04-18 11:06:22 (15312): 
   BOINC will be notified that it needs to clean up the environment.
         This is a temporary problem and so this job will be rescheduled for another time.

2025-04-18 11:07:00 (6056): Detected: vboxwrapper 26206
2025-04-18 11:07:00 (6056): Detected: BOINC client v8.0.2
2025-04-18 11:07:01 (6056): Detected: VirtualBox VboxManage Interface (Version: 7.1.4)
2025-04-18 11:07:02 (6056): Starting VM using VBoxManage interface. (boinc_b94b2e80f6fb4e30, slot#0)
2025-04-18 11:07:11 (6056): Successfully started VM. (PID = '24044')
2025-04-18 11:07:11 (6056): Reporting VM Process ID to BOINC.
2025-04-18 11:07:11 (6056): VM state change detected. (old = 'poweredoff', new = 'running')
2025-04-18 11:07:11 (6056): Detected: Web Application Enabled (http://localhost:32094)
2025-04-18 11:07:11 (6056): Stopping VM.
2025-04-18 11:07:17 (6056): Successfully stopped VM.
2025-04-18 11:07:18 (10588): Detected: vboxwrapper 26206
2025-04-18 11:07:18 (10588): Detected: BOINC client v8.0.2
2025-04-18 11:07:19 (10588): Detected: VirtualBox VboxManage Interface (Version: 7.1.4)
2025-04-18 11:07:20 (10588): Starting VM using VBoxManage interface. (boinc_b94b2e80f6fb4e30, slot#0)
2025-04-18 11:07:29 (10588): Successfully started VM. (PID = '9864')
2025-04-18 11:07:29 (10588): Reporting VM Process ID to BOINC.
2025-04-18 11:07:29 (10588): VM state change detected. (old = 'poweredoff', new = 'running')
2025-04-18 11:07:29 (10588): Detected: Web Application Enabled (http://localhost:32094)
2025-04-18 11:07:29 (10588): Stopping VM.
2025-04-18 11:07:36 (10588): Successfully stopped VM.
2025-04-18 11:09:28 (23832): Detected: vboxwrapper 26206
2025-04-18 11:09:28 (23832): Detected: BOINC client v8.0.2
2025-04-18 11:09:29 (23832): Detected: VirtualBox VboxManage Interface (Version: 7.1.4)
2025-04-18 11:09:30 (23832): Starting VM using VBoxManage interface. (boinc_b94b2e80f6fb4e30, slot#0)
2025-04-18 11:09:39 (23832): Successfully started VM. (PID = '21384')
2025-04-18 11:09:39 (23832): Reporting VM Process ID to BOINC.
2025-04-18 11:09:39 (23832): VM state change detected. (old = 'poweredoff', new = 'running')
2025-04-18 11:09:39 (23832): Detected: Web Application Enabled (http://localhost:32094)
2025-04-18 11:09:39 (23832): Stopping VM.
2025-04-18 11:09:46 (23832): Successfully stopped VM.
2025-04-18 11:11:15 (24812): Detected: vboxwrapper 26206
2025-04-18 11:11:15 (24812): Detected: BOINC client v8.0.2
2025-04-18 11:11:16 (24812): Detected: VirtualBox VboxManage Interface (Version: 7.1.4)
2025-04-18 11:11:17 (24812): Starting VM using VBoxManage interface. (boinc_b94b2e80f6fb4e30, slot#0)
2025-04-18 11:11:26 (24812): Successfully started VM. (PID = '15084')
2025-04-18 11:11:26 (24812): Reporting VM Process ID to BOINC.
2025-04-18 11:11:26 (24812): VM state change detected. (old = 'poweredoff', new = 'running')
2025-04-18 11:11:26 (24812): Detected: Web Application Enabled (http://localhost:32094)
2025-04-18 11:11:26 (24812): Stopping VM.
2025-04-18 11:11:33 (24812): Successfully stopped VM.
2025-04-18 11:11:35 (20940): Detected: vboxwrapper 26206
2025-04-18 11:11:35 (20940): Detected: BOINC client v8.0.2
2025-04-18 11:11:35 (20940): Detected: VirtualBox VboxManage Interface (Version: 7.1.4)
2025-04-18 11:11:36 (20940): ERROR: VM failed to start
2025-04-18 11:11:41 (20940): 
   BOINC will be notified that it needs to clean up the environment.
         This is a temporary problem and so this job will be rescheduled for another time.

2025-04-18 11:12:52 (24360): Detected: vboxwrapper 26206
2025-04-18 11:12:52 (24360): Detected: BOINC client v8.0.2
2025-04-18 11:12:53 (24360): Detected: VirtualBox VboxManage Interface (Version: 7.1.4)
2025-04-18 11:12:54 (24360): Starting VM using VBoxManage interface. (boinc_b94b2e80f6fb4e30, slot#0)
2025-04-18 11:13:03 (24360): Successfully started VM. (PID = '13736')
2025-04-18 11:13:03 (24360): Reporting VM Process ID to BOINC.
2025-04-18 11:13:03 (24360): VM state change detected. (old = 'poweredoff', new = 'running')
2025-04-18 11:13:03 (24360): Detected: Web Application Enabled (http://localhost:32094)
2025-04-18 11:13:03 (24360): Preference change detected
2025-04-18 11:13:03 (24360): Setting CPU throttle for VM. (80%)
2025-04-18 11:13:03 (24360): Setting checkpoint interval to 900 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 900 seconds))
2025-04-18 11:13:07 (24360): Guest Log:  *** Job finished ***
2025-04-18 11:13:07 (24360): Guest Log:  *** The last 20 lines of the pilot log: ***
2025-04-18 11:13:07 (24360): Guest Log:   File "<frozen importlib._bootstrap>", line 986, in _find_and_load_unlocked
2025-04-18 11:13:07 (24360): Guest Log:   File "<frozen importlib._bootstrap>", line 680, in _load_unlocked
2025-04-18 11:13:07 (24360): Guest Log:   File "<frozen importlib._bootstrap_external>", line 846, in exec_module
2025-04-18 11:13:07 (24360): Guest Log:   File "<frozen importlib._bootstrap_external>", line 982, in get_code
2025-04-18 11:13:07 (24360): Guest Log:   File "<frozen importlib._bootstrap_external>", line 1039, in get_data
2025-04-18 11:13:07 (24360): Guest Log: OSError: [Errno 5] Input/output error: '/cvmfs/atlas.cern.ch/repo/ATLASLocalRootBase/x86_64/python/3.9.20-x86_64-centos7/lib/python3.9/stringprep.py'
2025-04-18 11:13:07 (24360): Guest Log: 2025-04-18 09:02:35,708 [wrapper] ==== pilot stdout END ====
2025-04-18 11:13:07 (24360): Guest Log: 2025-04-18 09:02:35,714 [wrapper] ==== wrapper stdout RESUME ====
2025-04-18 11:13:07 (24360): Guest Log: 2025-04-18 09:02:35,719 [wrapper] pilotpid: 5878
2025-04-18 11:13:07 (24360): Guest Log: 2025-04-18 09:02:35,725 [wrapper] Pilot exit status: 1
2025-04-18 11:13:07 (24360): Guest Log: 2025-04-18 09:02:35,730 [wrapper] File not found: /home/atlas/RunAtlas/pilot3/pandaIDs.out, no payload
2025-04-18 11:13:07 (24360): Guest Log: 2025-04-18 09:02:35,736 [wrapper] File not found: /home/atlas/RunAtlas/pilot3/pandaIDs.out, no payload
2025-04-18 11:13:07 (24360): Guest Log: 2025-04-18 09:02:35,742 [wrapper] WARNING: pilot exitcode non-zero
2025-04-18 11:13:07 (24360): Guest Log: 2025-04-18 09:02:35,747 [wrapper] WARNING: pilot exitcode non-zero
2025-04-18 11:13:07 (24360): Guest Log: 2025-04-18 09:02:35,752 [wrapper] apfmon messages muted
2025-04-18 11:13:07 (24360): Guest Log: 2025-04-18 09:02:35,796 [wrapper] cleanup supervisor_pilot  5884 5879
2025-04-18 11:13:07 (24360): Guest Log: 2025-04-18 09:02:35,801 [wrapper] Test setup, not cleaning
2025-04-18 11:13:07 (24360): Guest Log: 2025-04-18 09:02:35,807 [wrapper] apfmon messages muted
2025-04-18 11:13:07 (24360): Guest Log: 2025-04-18 09:02:35,812 [wrapper] ==== wrapper stdout END ====
2025-04-18 11:13:07 (24360): Guest Log: 2025-04-18 09:02:35,817 [wrapper] ==== wrapper stderr END ====
2025-04-18 11:13:07 (24360): Guest Log:  *** Listing of results directory ***
2025-04-18 11:13:07 (24360): Guest Log: total 1140
2025-04-18 11:13:07 (24360): Guest Log: drwx------. 4 atlas atlas   4096 Apr  3 08:00 pilot3
2025-04-18 11:13:07 (24360): Guest Log: -rw-r--r--. 1 atlas atlas 495897 Apr 15 06:50 pilot3.tar.gz
2025-04-18 11:13:07 (24360): Guest Log: -rw-r--r--. 1 atlas atlas   5111 Apr 15 06:53 queuedata.json
2025-04-18 11:13:07 (24360): Guest Log: -rwx------. 1 atlas atlas  37140 Apr 15 06:55 runpilot2-wrapper.sh
2025-04-18 11:13:07 (24360): Guest Log: -rw-------. 1 atlas atlas   2577 Apr 15 06:55 pandaJobData.out
2025-04-18 11:13:07 (24360): Guest Log: -rwxr-xr-x. 1 atlas atlas   9051 Apr 18 08:54 init_data.xml
2025-04-18 11:13:07 (24360): Guest Log: -rwxr-xr-x. 1 atlas atlas 508893 Apr 18 08:54 input.tar.gz
2025-04-18 11:13:07 (24360): Guest Log: -rwxr-xr-x. 1 atlas atlas  17569 Apr 18 08:54 start_atlas.sh
2025-04-18 11:13:07 (24360): Guest Log: lrwxrwxrwx. 1 atlas atlas     20 Apr 18 08:54 EVNT.44075481._000981.pool.root.1 -> /data/./ATLAS.root_0
2025-04-18 11:13:07 (24360): Guest Log: -rw-r--r--. 1 atlas atlas   2577 Apr 18 08:54 pandaJob.out
2025-04-18 11:13:07 (24360): Guest Log: -rw-------. 1 atlas atlas    424 Apr 18 08:55 setup.sh.local
2025-04-18 11:13:07 (24360): Guest Log: -rw-------. 1 atlas atlas  13822 Apr 18 09:02 log.44075483._025159.job.log.1
2025-04-18 11:13:07 (24360): Guest Log: -rw-r--r--. 1 atlas atlas    554 Apr 18 09:02 runtime_log
2025-04-18 11:13:07 (24360): Guest Log: -rw-------. 1 atlas atlas  20480 Apr 18 09:02 result.tar.gz
2025-04-18 11:13:07 (24360): Guest Log: -rw-r--r--. 1 atlas atlas  10165 Apr 18 09:02 runtime_log.err
2025-04-18 11:13:07 (24360): Guest Log: -rw-------. 1 atlas atlas    630 Apr 18 09:02 ituNDmbNpO7nsSi4ap6QjLDmwznN0nGgGQJmkKkKDmQiXLDm9YTxDn.diag
2025-04-18 11:13:07 (24360): Guest Log: Looking for outputfile HITS.44075483._025159.pool.root.1
2025-04-18 11:13:07 (24360): Guest Log: No HITS file was produced
2025-04-18 11:13:07 (24360): Guest Log: Successfully finished the ATLAS job!
2025-04-18 11:13:07 (24360): Guest Log: Copying the results back to the shared directory!
2025-04-18 11:13:07 (24360): Guest Log:  *** Contents of shared directory: ***
2025-04-18 11:13:07 (24360): Guest Log: total 220336
2025-04-18 11:13:07 (24360): Guest Log: -rwxrwxrwx. 1 root root 225058248 Apr 18 08:53 ATLAS.root_0
2025-04-18 11:13:07 (24360): Guest Log: -rwxrwxrwx. 1 root root      9051 Apr 18 08:53 init_data.xml
2025-04-18 11:13:07 (24360): Guest Log: -rwxrwxrwx. 1 root root    508893 Apr 15 15:56 input.tar.gz
2025-04-18 11:13:07 (24360): Guest Log: -rwxrwxrwx. 1 root root     20480 Apr 18  2025 result.tar.gz
2025-04-18 11:13:07 (24360): Guest Log: -rwxrwxrwx. 1 root root     17569 Apr 15 15:56 start_atlas.sh
2025-04-18 11:13:07 (24360): Guest Log:  *** Success! Shutting down the machine. ***
2025-04-18 11:13:07 (24360): VM Completion File Detected.
2025-04-18 11:13:07 (24360): Powering off VM.
2025-04-18 11:13:08 (24360): Successfully stopped VM.
2025-04-18 11:13:08 (24360): Deregistering VM. (boinc_b94b2e80f6fb4e30, slot#0)
2025-04-18 11:13:08 (24360): Removing network bandwidth throttle group from VM.
2025-04-18 11:13:08 (24360): Removing VM from VirtualBox.
11:13:13 (24360): called boinc_finish(0)
2025-04-18 11:13:16 (23236): Detected: vboxwrapper 26206
2025-04-18 11:13:16 (23236): Detected: BOINC client v8.0.2
2025-04-18 11:13:17 (23236): Detected: VirtualBox VboxManage Interface (Version: 7.1.4)
2025-04-18 11:13:17 (23236): Create VM. (boinc_b94b2e80f6fb4e30, slot#0)
2025-04-18 11:13:18 (23236): Setting Memory Size for VM. (4300MB)
2025-04-18 11:13:18 (23236): Setting CPU Count for VM. (3)
2025-04-18 11:13:18 (23236): Setting Chipset Options for VM.
2025-04-18 11:13:19 (23236): Setting Graphics Controller Options for VM.
2025-04-18 11:13:19 (23236): Setting Boot Options for VM.
2025-04-18 11:13:19 (23236): Setting Network Configuration for NAT.
2025-04-18 11:13:20 (23236): Enabling VM Network Access.
2025-04-18 11:13:20 (23236): Disabling USB Support for VM.
2025-04-18 11:13:20 (23236): Disabling COM Port Support for VM.
2025-04-18 11:13:20 (23236): Disabling LPT Port Support for VM.
2025-04-18 11:13:21 (23236): Disabling Audio Support for VM.
2025-04-18 11:13:21 (23236): Disabling Clipboard Support for VM.
2025-04-18 11:13:21 (23236): Disabling Drag and Drop Support for VM.
2025-04-18 11:13:21 (23236): Adding storage controller(s) to VM.
2025-04-18 11:13:22 (23236): Adding virtual disk drive to VM. (ATLAS_vbox_3.01_image.vdi)
2025-04-18 11:13:25 (23236): Adding VirtualBox Guest Additions to VM.
2025-04-18 11:13:25 (23236): Adding network bandwidth throttle group to VM. (Defaulting to 1024GB)
2025-04-18 11:13:25 (23236): forwarding host port 32094 to guest port 80
2025-04-18 11:13:25 (23236): Enabling remote desktop for VM.
2025-04-18 11:13:26 (23236): Required extension pack not installed, remote desktop not enabled.
2025-04-18 11:13:26 (23236): Enabling shared directory for VM.
2025-04-18 11:13:26 (23236): ERROR: VM failed to start
2025-04-18 11:13:31 (23236): 
   BOINC will be notified that it needs to clean up the environment.
         This is a temporary problem and so this job will be rescheduled for another time.

2025-04-18 11:14:13 (4160): Detected: vboxwrapper 26206
2025-04-18 11:14:13 (4160): Detected: BOINC client v8.0.2
2025-04-18 11:14:14 (4160): Detected: VirtualBox VboxManage Interface (Version: 7.1.4)
2025-04-18 11:14:15 (4160): Starting VM using VBoxManage interface. (boinc_b94b2e80f6fb4e30, slot#0)
2025-04-18 11:14:21 (4160): Successfully started VM. (PID = '25444')
2025-04-18 11:14:21 (4160): Reporting VM Process ID to BOINC.
2025-04-18 11:14:21 (4160): Guest Log: BIOS: VirtualBox 7.1.4
2025-04-18 11:14:21 (4160): Guest Log: CPUID EDX: 0x178bfbff
2025-04-18 11:14:21 (4160): Guest Log: BIOS: No PCI IDE controller, not probing IDE
2025-04-18 11:14:21 (4160): Guest Log: BIOS: AHCI 0-P#0: PCHS=16383/16/63 LCHS=1024/255/63 0x0000000002800000 sectors
2025-04-18 11:14:21 (4160): VM state change detected. (old = 'poweredoff', new = 'running')
2025-04-18 11:14:21 (4160): Detected: Web Application Enabled (http://localhost:32094)
2025-04-18 11:14:21 (4160): VM Completion File Detected.
2025-04-18 11:14:21 (4160): Powering off VM.
2025-04-18 11:14:21 (4160): Successfully stopped VM.
2025-04-18 11:14:21 (4160): Deregistering VM. (boinc_b94b2e80f6fb4e30, slot#0)
2025-04-18 11:14:21 (4160): Removing network bandwidth throttle group from VM.
2025-04-18 11:14:22 (4160): Removing VM from VirtualBox.
11:14:27 (4160): called boinc_finish(0)

</stderr_txt>
]]>

©2025 CERN