Stderr output

<core_client_version>7.16.20</core_client_version>
<![CDATA[
<stderr_txt>
branch: prod
2025-11-24 12:36:14 (6772): Guest Log: This is the prod version of the ATLAS job wrapper
2025-11-24 12:36:14 (6772): Guest Log: Copying input files
2025-11-24 12:36:23 (6772): Guest Log: Copied input files into RunAtlas.
2025-11-24 12:36:23 (6772): Guest Log: This VM did not configure a local http proxy via BOINC.
2025-11-24 12:36:23 (6772): Guest Log: Small home clusters do not require a local http proxy but it is suggested if
2025-11-24 12:36:23 (6772): Guest Log: more than 10 cores throughout the same LAN segment are regularly running ATLAS like tasks.
2025-11-24 12:36:23 (6772): Guest Log: Further information can be found at the LHC@home message board.
2025-11-24 12:36:32 (6772): Guest Log: Running cvmfs_config stat atlas.cern.ch
2025-11-24 12:36:32 (6772): 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-24 12:36:32 (6772): Guest Log: 2.6.3.0 1754 0 32184 153244 4 1 3117639 4096000 0 65024 0 0 n/a 0 0 http://s1ral-cvmfs.openhtc.io/cvmfs/atlas.cern.ch DIRECT 1
2025-11-24 12:36:35 (6772): Guest Log: copied the webapp to /var/www
2025-11-24 12:36:36 (6772): Guest Log: ATHENA_PROC_NUMBER=8
2025-11-24 12:36:36 (6772): Guest Log: ATHENA_CORE_NUMBER=8
2025-11-24 12:36:36 (6772): 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-24 12:36:36 (6772): Guest Log:  *** Starting ATLAS job. (PandaID=6894053083 taskID=47523618) ***
2025-11-24 12:42:44 (6772): Stopping VM.
2025-11-24 12:42:59 (6772): Successfully stopped VM.
2025-11-24 12:45:43 (15476): vboxwrapper version 26210
2025-11-24 12:45:43 (15476): BOINC client version: 7.16.20
2025-11-24 12:45:44 (15476): Detected: VirtualBox VboxManage Interface (Version: 6.0.14)
2025-11-24 12:45:45 (15476): Guest Log: 00:00:50.036885 timesync vgsvcTimeSyncWorker: Radical host time change: 4 453 040 000 000ns (HostNow=1 763 984 160 516 000 000 ns HostLast=1 763 979 707 476 000 000 ns)
2025-11-24 12:45:45 (15476): Guest Log: 00:01:00.038911 timesync vgsvcTimeSyncWorker: Radical guest time change: 4 453 038 042 000ns (GuestNow=1 763 984 170 518 793 000 ns GuestLast=1 763 979 717 480 751 000 ns fSetTimeLastLoop=true )
2025-11-24 12:45:45 (15476): Guest Log: [INFO] Probing /cvmfs/atlas.cern.ch... OK
2025-11-24 12:45:45 (15476): Guest Log: [INFO] Detected branch: prod
2025-11-24 12:45:45 (15476): Guest Log: This is the prod version of the ATLAS job wrapper
2025-11-24 12:45:45 (15476): Guest Log: Copying input files
2025-11-24 12:45:45 (15476): Guest Log: Copied input files into RunAtlas.
2025-11-24 12:45:45 (15476): Guest Log: This VM did not configure a local http proxy via BOINC.
2025-11-24 12:45:45 (15476): Guest Log: Small home clusters do not require a local http proxy but it is suggested if
2025-11-24 12:45:45 (15476): Guest Log: more than 10 cores throughout the same LAN segment are regularly running ATLAS like tasks.
2025-11-24 12:45:45 (15476): Guest Log: Further information can be found at the LHC@home message board.
2025-11-24 12:45:45 (15476): Guest Log: Running cvmfs_config stat atlas.cern.ch
2025-11-24 12:45:45 (15476): 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-24 12:45:45 (15476): Guest Log: 2.6.3.0 1754 0 32184 153244 4 1 3117639 4096000 0 65024 0 0 n/a 0 0 http://s1ral-cvmfs.openhtc.io/cvmfs/atlas.cern.ch DIRECT 1
2025-11-24 12:45:45 (15476): Guest Log: copied the webapp to /var/www
2025-11-24 12:45:45 (15476): Guest Log: ATHENA_PROC_NUMBER=8
2025-11-24 12:45:45 (15476): Guest Log: ATHENA_CORE_NUMBER=8
2025-11-24 12:45:45 (15476): 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-24 12:45:45 (15476): Guest Log:  *** Starting ATLAS job. (PandaID=6894053083 taskID=47523618) ***
2025-11-24 12:45:45 (15476): Starting VM using VBoxManage interface. (boinc_c769c7770003d944, slot#7)
2025-11-24 12:47:11 (15476): Successfully started VM. (PID = '11160')
2025-11-24 12:47:11 (15476): Reporting VM Process ID to BOINC.
2025-11-24 12:47:11 (15476): VM state change detected. (old = 'poweredoff', new = 'running')
2025-11-24 12:47:11 (15476): Detected: Web Application Enabled (http://localhost:63018)
2025-11-24 12:47:11 (15476): Preference change detected
2025-11-24 12:47:11 (15476): Setting CPU throttle for VM. (100%)
2025-11-24 12:47:11 (15476): Setting checkpoint interval to 900 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 900 seconds))
2025-11-24 12:53:13 (15476): Stopping VM.
2025-11-24 12:53:31 (15476): Successfully stopped VM.
2025-11-24 13:10:50 (16464): vboxwrapper version 26210
2025-11-24 13:10:50 (16464): BOINC client version: 7.16.20
2025-11-24 13:10:51 (16464): Detected: VirtualBox VboxManage Interface (Version: 6.0.14)
2025-11-24 13:10:52 (16464): Starting VM using VBoxManage interface. (boinc_c769c7770003d944, slot#7)
2025-11-24 13:15:02 (8652): vboxwrapper version 26210
2025-11-24 13:15:02 (8652): BOINC client version: 7.16.20
2025-11-24 13:15:02 (8652): Detected: VirtualBox VboxManage Interface (Version: 6.0.14)
2025-11-24 13:15:08 (8652): VM was running
2025-11-24 13:15:08 (8652): Powering off VM.
2025-11-24 13:15:09 (8652): Successfully stopped VM.
2025-11-24 13:15:09 (8652): Starting VM using VBoxManage interface. (boinc_c769c7770003d944, slot#7)
2025-11-24 13:15:14 (8652): Successfully started VM. (PID = '3520')
2025-11-24 13:15:14 (8652): Reporting VM Process ID to BOINC.
2025-11-24 13:15:14 (8652): Guest Log: BIOS: VirtualBox 6.0.14
2025-11-24 13:15:14 (8652): Guest Log: CPUID EDX: 0x178bfbff
2025-11-24 13:15:14 (8652): Guest Log: BIOS: AHCI 0-P#0: PCHS=16383/16/63 LCHS=1024/255/63 0x0000000002800000 sectors
2025-11-24 13:15:14 (8652): VM state change detected. (old = 'poweredoff', new = 'running')
2025-11-24 13:15:14 (8652): Detected: Web Application Enabled (http://localhost:63018)
2025-11-24 13:15:14 (8652): Preference change detected
2025-11-24 13:15:14 (8652): Setting CPU throttle for VM. (100%)
2025-11-24 13:15:14 (8652): Setting checkpoint interval to 900 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 900 seconds))
2025-11-24 13:15:16 (8652): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032
2025-11-24 13:15:16 (8652): Guest Log: BIOS: Booting from Hard Disk...
2025-11-24 13:15:20 (8652): Guest Log: BIOS: KBD: unsupported int 16h function 03
2025-11-24 13:15:20 (8652): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 
2025-11-24 13:15:20 (8652): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=81
2025-11-24 13:15:20 (8652): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=81
2025-11-24 13:15:20 (8652): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=82
2025-11-24 13:15:20 (8652): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=82
2025-11-24 13:15:20 (8652): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=83
2025-11-24 13:15:20 (8652): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=83
2025-11-24 13:15:20 (8652): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=84
2025-11-24 13:15:20 (8652): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=84
2025-11-24 13:15:20 (8652): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=85
2025-11-24 13:15:20 (8652): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=85
2025-11-24 13:15:20 (8652): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=86
2025-11-24 13:15:20 (8652): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=86
2025-11-24 13:15:20 (8652): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=87
2025-11-24 13:15:20 (8652): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=87
2025-11-24 13:15:20 (8652): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=88
2025-11-24 13:15:20 (8652): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=88
2025-11-24 13:15:20 (8652): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=89
2025-11-24 13:15:20 (8652): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=89
2025-11-24 13:15:20 (8652): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=8a
2025-11-24 13:15:20 (8652): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=8a
2025-11-24 13:15:20 (8652): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=8b
2025-11-24 13:15:20 (8652): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=8b
2025-11-24 13:15:20 (8652): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=8c
2025-11-24 13:15:20 (8652): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=8c
2025-11-24 13:15:20 (8652): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=8d
2025-11-24 13:15:20 (8652): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=8d
2025-11-24 13:15:20 (8652): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=8e
2025-11-24 13:15:20 (8652): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=8e
2025-11-24 13:15:20 (8652): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=8f
2025-11-24 13:15:20 (8652): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=8f
2025-11-24 13:15:33 (8652): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds
2025-11-24 13:15:33 (8652): Guest Log: vboxguest: misc device minor 58, IRQ 20, I/O port d020, MMIO at 00000000f0000000 (size 0x400000)
2025-11-24 13:15:40 (8652): Stopping VM.
2025-11-24 13:15:41 (8652): Successfully stopped VM.
2025-11-24 13:48:45 (12168): vboxwrapper version 26210
2025-11-24 13:48:45 (12168): BOINC client version: 7.16.20
2025-11-24 13:48:47 (12168): Detected: VirtualBox VboxManage Interface (Version: 6.0.14)
2025-11-24 13:48:47 (12168): Guest Log: BIOS: VirtualBox 6.0.14
2025-11-24 13:48:47 (12168): Guest Log: CPUID EDX: 0x178bfbff
2025-11-24 13:48:47 (12168): Guest Log: BIOS: AHCI 0-P#0: PCHS=16383/16/63 LCHS=1024/255/63 0x0000000002800000 sectors
2025-11-24 13:48:47 (12168): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032
2025-11-24 13:48:47 (12168): Guest Log: BIOS: Booting from Hard Disk...
2025-11-24 13:48:47 (12168): Guest Log: BIOS: KBD: unsupported int 16h function 03
2025-11-24 13:48:47 (12168): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 
2025-11-24 13:48:47 (12168): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=81
2025-11-24 13:48:47 (12168): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=81
2025-11-24 13:48:47 (12168): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=82
2025-11-24 13:48:47 (12168): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=82
2025-11-24 13:48:47 (12168): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=83
2025-11-24 13:48:47 (12168): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=83
2025-11-24 13:48:47 (12168): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=84
2025-11-24 13:48:47 (12168): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=84
2025-11-24 13:48:47 (12168): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=85
2025-11-24 13:48:47 (12168): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=85
2025-11-24 13:48:47 (12168): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=86
2025-11-24 13:48:47 (12168): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=86
2025-11-24 13:48:47 (12168): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=87
2025-11-24 13:48:47 (12168): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=87
2025-11-24 13:48:47 (12168): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=88
2025-11-24 13:48:47 (12168): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=88
2025-11-24 13:48:47 (12168): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=89
2025-11-24 13:48:47 (12168): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=89
2025-11-24 13:48:47 (12168): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=8a
2025-11-24 13:48:47 (12168): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=8a
2025-11-24 13:48:47 (12168): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=8b
2025-11-24 13:48:47 (12168): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=8b
2025-11-24 13:48:47 (12168): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=8c
2025-11-24 13:48:47 (12168): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=8c
2025-11-24 13:48:47 (12168): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=8d
2025-11-24 13:48:47 (12168): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=8d
2025-11-24 13:48:47 (12168): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=8e
2025-11-24 13:48:47 (12168): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=8e
2025-11-24 13:48:47 (12168): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=8f
2025-11-24 13:48:47 (12168): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=8f
2025-11-24 13:48:47 (12168): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds
2025-11-24 13:48:47 (12168): Guest Log: vboxguest: misc device minor 58, IRQ 20, I/O port d020, MMIO at 00000000f0000000 (size 0x400000)
2025-11-24 13:48:47 (12168): Starting VM using VBoxManage interface. (boinc_c769c7770003d944, slot#7)
2025-11-24 13:48:59 (12168): Successfully started VM. (PID = '10612')
2025-11-24 13:48:59 (12168): Reporting VM Process ID to BOINC.
2025-11-24 13:48:59 (12168): VM state change detected. (old = 'poweredoff', new = 'running')
2025-11-24 13:48:59 (12168): Detected: Web Application Enabled (http://localhost:63018)
2025-11-24 13:48:59 (12168): Stopping VM.
2025-11-24 13:49:00 (12168): Successfully stopped VM.
2025-11-25 07:34:53 (7876): vboxwrapper version 26210
2025-11-25 07:34:53 (7876): BOINC client version: 7.16.20
2025-11-25 07:34:54 (7876): Detected: VirtualBox VboxManage Interface (Version: 6.0.14)
2025-11-25 07:34:55 (7876): Starting VM using VBoxManage interface. (boinc_c769c7770003d944, slot#7)
2025-11-25 07:35:03 (7876): Successfully started VM. (PID = '6444')
2025-11-25 07:35:03 (7876): Reporting VM Process ID to BOINC.
2025-11-25 07:35:03 (7876): VM state change detected. (old = 'poweredoff', new = 'running')
2025-11-25 07:35:03 (7876): Detected: Web Application Enabled (http://localhost:63018)
2025-11-25 07:35:03 (7876): Preference change detected
2025-11-25 07:35:03 (7876): Setting CPU throttle for VM. (100%)
2025-11-25 07:35:03 (7876): Setting checkpoint interval to 900 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 900 seconds))
2025-11-25 07:35:08 (7876): Guest Log: VBoxService 5.2.32 r132073 (verbosity: 0) linux.amd64 (Jul 12 2019 10:32:28) release log
2025-11-25 07:35:08 (7876): Guest Log: 00:00:00.000174 main     Log opened 2025-11-24T13:15:45.718567000Z
2025-11-25 07:35:08 (7876): Guest Log: 00:00:00.000283 main     OS Product: Linux
2025-11-25 07:35:08 (7876): Guest Log: 00:00:00.000372 main     OS Release: 3.10.0-957.27.2.el7.x86_64
2025-11-25 07:35:08 (7876): Guest Log: 00:00:00.000411 main     OS Version: #1 SMP Mon Jul 29 17:46:05 UTC 2019
2025-11-25 07:35:08 (7876): Guest Log: 00:00:00.000447 main     Executable: /opt/VBoxGuestAdditions-5.2.32/sbin/VBoxService
2025-11-25 07:35:08 (7876): Guest Log: 00:00:00.000448 main     Process ID: 1437
2025-11-25 07:35:08 (7876): Guest Log: 00:00:00.000449 main     Package type: LINUX_64BITS_GENERIC
2025-11-25 07:35:08 (7876): Guest Log: 00:00:00.001426 main     5.2.32 r132073 started. Verbose level = 0
2025-11-25 07:35:08 (7876): Guest Log: [INFO] Probing /cvmfs/atlas.cern.ch...
2025-11-25 07:35:08 (7876): Guest Log: [INFO] Mounting shared directory
2025-11-25 07:35:08 (7876): Guest Log: [INFO] Checking for init_data.xml
2025-11-25 07:35:16 (7876): Guest Log: [INFO] Probing /cvmfs/atlas.cern.ch... OK
2025-11-25 07:35:16 (7876): Guest Log: [INFO] Detected branch: prod
2025-11-25 07:35:16 (7876): Guest Log: This is the prod version of the ATLAS job wrapper
2025-11-25 07:35:16 (7876): Guest Log: Copying input files
2025-11-25 07:35:18 (7876): Guest Log: 00:00:10.003271 timesync vgsvcTimeSyncWorker: Radical guest time change: 62 372 182 890 000ns (GuestNow=1 764 052 517 904 240 000 ns GuestLast=1 763 990 145 721 350 000 ns fSetTimeLastLoop=true )
2025-11-25 07:35:23 (7876): Guest Log: Copied input files into RunAtlas.
2025-11-25 07:35:23 (7876): Guest Log: This VM did not configure a local http proxy via BOINC.
2025-11-25 07:35:23 (7876): Guest Log: Small home clusters do not require a local http proxy but it is suggested if
2025-11-25 07:35:23 (7876): Guest Log: more than 10 cores throughout the same LAN segment are regularly running ATLAS like tasks.
2025-11-25 07:35:23 (7876): Guest Log: Further information can be found at the LHC@home message board.
2025-11-25 07:35:32 (7876): Guest Log: Running cvmfs_config stat atlas.cern.ch
2025-11-25 07:35:33 (7876): 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-25 07:35:33 (7876): Guest Log: 2.6.3.0 1736 0 29996 153273 3 1 3855939 4096000 0 65024 0 0 n/a 0 0 http://s1cern-cvmfs.openhtc.io/cvmfs/atlas.cern.ch DIRECT 1
2025-11-25 07:35:36 (7876): Guest Log: copied the webapp to /var/www
2025-11-25 07:35:36 (7876): Guest Log: ATHENA_PROC_NUMBER=8
2025-11-25 07:35:36 (7876): Guest Log: ATHENA_CORE_NUMBER=8
2025-11-25 07:35:36 (7876): 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-25 07:35:36 (7876): Guest Log:  *** Starting ATLAS job. (PandaID=6894053083 taskID=47523618) ***
2025-11-25 07:36:17 (7876): Stopping VM.
2025-11-25 07:36:23 (7876): Successfully stopped VM.
2025-11-25 07:39:17 (2180): vboxwrapper version 26210
2025-11-25 07:39:17 (2180): BOINC client version: 7.16.20
2025-11-25 07:39:18 (2180): Detected: VirtualBox VboxManage Interface (Version: 6.0.14)
2025-11-25 07:39:19 (2180): Guest Log: VBoxService 5.2.32 r132073 (verbosity: 0) linux.amd64 (Jul 12 2019 10:32:28) release log
2025-11-25 07:39:19 (2180): Guest Log: 00:00:00.000174 main     Log opened 2025-11-24T13:15:45.718567000Z
2025-11-25 07:39:19 (2180): Guest Log: 00:00:00.000283 main     OS Product: Linux
2025-11-25 07:39:19 (2180): Guest Log: 00:00:00.000372 main     OS Release: 3.10.0-957.27.2.el7.x86_64
2025-11-25 07:39:19 (2180): Guest Log: 00:00:00.000411 main     OS Version: #1 SMP Mon Jul 29 17:46:05 UTC 2019
2025-11-25 07:39:19 (2180): Guest Log: 00:00:00.000447 main     Executable: /opt/VBoxGuestAdditions-5.2.32/sbin/VBoxService
2025-11-25 07:39:19 (2180): Guest Log: 00:00:00.000448 main     Process ID: 1437
2025-11-25 07:39:19 (2180): Guest Log: 00:00:00.000449 main     Package type: LINUX_64BITS_GENERIC
2025-11-25 07:39:19 (2180): Guest Log: 00:00:00.001426 main     5.2.32 r132073 started. Verbose level = 0
2025-11-25 07:39:19 (2180): Guest Log: [INFO] Probing /cvmfs/atlas.cern.ch...
2025-11-25 07:39:19 (2180): Guest Log: [INFO] Mounting shared directory
2025-11-25 07:39:19 (2180): Guest Log: [INFO] Checking for init_data.xml
2025-11-25 07:39:19 (2180): Guest Log: [INFO] Probing /cvmfs/atlas.cern.ch... OK
2025-11-25 07:39:19 (2180): Guest Log: [INFO] Detected branch: prod
2025-11-25 07:39:19 (2180): Guest Log: This is the prod version of the ATLAS job wrapper
2025-11-25 07:39:19 (2180): Guest Log: Copying input files
2025-11-25 07:39:19 (2180): Guest Log: 00:00:10.003271 timesync vgsvcTimeSyncWorker: Radical guest time change: 62 372 182 890 000ns (GuestNow=1 764 052 517 904 240 000 ns GuestLast=1 763 990 145 721 350 000 ns fSetTimeLastLoop=true )
2025-11-25 07:39:19 (2180): Guest Log: Copied input files into RunAtlas.
2025-11-25 07:39:19 (2180): Guest Log: This VM did not configure a local http proxy via BOINC.
2025-11-25 07:39:19 (2180): Guest Log: Small home clusters do not require a local http proxy but it is suggested if
2025-11-25 07:39:19 (2180): Guest Log: more than 10 cores throughout the same LAN segment are regularly running ATLAS like tasks.
2025-11-25 07:39:19 (2180): Guest Log: Further information can be found at the LHC@home message board.
2025-11-25 07:39:19 (2180): Guest Log: Running cvmfs_config stat atlas.cern.ch
2025-11-25 07:39:19 (2180): 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-25 07:39:19 (2180): Guest Log: 2.6.3.0 1736 0 29996 153273 3 1 3855939 4096000 0 65024 0 0 n/a 0 0 http://s1cern-cvmfs.openhtc.io/cvmfs/atlas.cern.ch DIRECT 1
2025-11-25 07:39:19 (2180): Guest Log: copied the webapp to /var/www
2025-11-25 07:39:19 (2180): Guest Log: ATHENA_PROC_NUMBER=8
2025-11-25 07:39:19 (2180): Guest Log: ATHENA_CORE_NUMBER=8
2025-11-25 07:39:19 (2180): 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-25 07:39:19 (2180): Guest Log:  *** Starting ATLAS job. (PandaID=6894053083 taskID=47523618) ***
2025-11-25 07:39:19 (2180): Starting VM using VBoxManage interface. (boinc_c769c7770003d944, slot#7)
2025-11-25 07:39:36 (2180): Successfully started VM. (PID = '11844')
2025-11-25 07:39:36 (2180): Reporting VM Process ID to BOINC.
2025-11-25 07:39:36 (2180): VM state change detected. (old = 'poweredoff', new = 'running')
2025-11-25 07:39:36 (2180): Detected: Web Application Enabled (http://localhost:63018)
2025-11-25 07:39:36 (2180): Preference change detected
2025-11-25 07:39:36 (2180): Setting CPU throttle for VM. (100%)
2025-11-25 07:39:37 (2180): Setting checkpoint interval to 900 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 900 seconds))
2025-11-25 07:42:33 (2180): Stopping VM.
2025-11-25 07:42:46 (2180): Successfully stopped VM.
2025-11-25 07:45:47 (16260): vboxwrapper version 26210
2025-11-25 07:45:47 (16260): BOINC client version: 7.16.20
2025-11-25 07:45:48 (16260): Detected: VirtualBox VboxManage Interface (Version: 6.0.14)
2025-11-25 07:45:49 (16260): Starting VM using VBoxManage interface. (boinc_c769c7770003d944, slot#7)
2025-11-25 07:46:39 (16260): Successfully started VM. (PID = '11232')
2025-11-25 07:46:39 (16260): Reporting VM Process ID to BOINC.
2025-11-25 07:46:39 (16260): VM state change detected. (old = 'poweredoff', new = 'running')
2025-11-25 07:46:39 (16260): Detected: Web Application Enabled (http://localhost:63018)
2025-11-25 07:46:39 (16260): Stopping VM.
2025-11-25 07:46:52 (16260): Successfully stopped VM.
2025-11-25 07:53:10 (4164): vboxwrapper version 26210
2025-11-25 07:53:10 (4164): BOINC client version: 7.16.20
2025-11-25 07:53:11 (4164): Detected: VirtualBox VboxManage Interface (Version: 6.0.14)
2025-11-25 07:53:12 (4164): Starting VM using VBoxManage interface. (boinc_c769c7770003d944, slot#7)
2025-11-25 07:54:09 (4164): Successfully started VM. (PID = '12976')
2025-11-25 07:54:09 (4164): Reporting VM Process ID to BOINC.
2025-11-25 07:54:09 (4164): VM state change detected. (old = 'poweredoff', new = 'running')
2025-11-25 07:54:09 (4164): Detected: Web Application Enabled (http://localhost:63018)
2025-11-25 07:54:09 (4164): Stopping VM.
2025-11-25 07:54:23 (4164): Successfully stopped VM.
2025-11-25 07:59:13 (10480): vboxwrapper version 26210
2025-11-25 07:59:13 (10480): BOINC client version: 7.16.20
2025-11-25 07:59:14 (10480): Detected: VirtualBox VboxManage Interface (Version: 6.0.14)
2025-11-25 07:59:15 (10480): Starting VM using VBoxManage interface. (boinc_c769c7770003d944, slot#7)
2025-11-25 08:00:43 (10480): Successfully started VM. (PID = '6368')
2025-11-25 08:00:43 (10480): Reporting VM Process ID to BOINC.
2025-11-25 08:00:43 (10480): VM state change detected. (old = 'poweredoff', new = 'running')
2025-11-25 08:00:43 (10480): Detected: Web Application Enabled (http://localhost:63018)
2025-11-25 08:00:43 (10480): Preference change detected
2025-11-25 08:00:43 (10480): Setting CPU throttle for VM. (100%)
2025-11-25 08:00:43 (10480): Setting checkpoint interval to 900 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 900 seconds))
2025-11-25 08:00:47 (10480): Stopping VM.
2025-11-25 08:01:02 (10480): Successfully stopped VM.
2025-11-25 08:10:27 (15484): vboxwrapper version 26210
2025-11-25 08:10:27 (15484): BOINC client version: 7.16.20
2025-11-25 08:10:28 (15484): Detected: VirtualBox VboxManage Interface (Version: 6.0.14)
2025-11-25 08:10:29 (15484): Starting VM using VBoxManage interface. (boinc_c769c7770003d944, slot#7)
2025-11-25 08:11:56 (15484): Successfully started VM. (PID = '7940')
2025-11-25 08:11:56 (15484): Reporting VM Process ID to BOINC.
2025-11-25 08:11:56 (15484): VM state change detected. (old = 'poweredoff', new = 'running')
2025-11-25 08:11:56 (15484): Detected: Web Application Enabled (http://localhost:63018)
2025-11-25 08:11:56 (15484): Preference change detected
2025-11-25 08:11:56 (15484): Setting CPU throttle for VM. (100%)
2025-11-25 08:11:56 (15484): Setting checkpoint interval to 900 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 900 seconds))
2025-11-25 08:12:21 (15484): Stopping VM.
2025-11-25 08:12:36 (15484): Successfully stopped VM.
2025-11-25 09:23:10 (11600): vboxwrapper version 26210
2025-11-25 09:23:10 (11600): BOINC client version: 7.16.20
2025-11-25 09:23:11 (11600): Detected: VirtualBox VboxManage Interface (Version: 6.0.14)
2025-11-25 09:23:12 (11600): Starting VM using VBoxManage interface. (boinc_c769c7770003d944, slot#7)
2025-11-25 09:24:27 (11600): Successfully started VM. (PID = '4532')
2025-11-25 09:24:27 (11600): Reporting VM Process ID to BOINC.
2025-11-25 09:24:27 (11600): VM state change detected. (old = 'poweredoff', new = 'running')
2025-11-25 09:24:27 (11600): Detected: Web Application Enabled (http://localhost:63018)
2025-11-25 09:24:27 (11600): Stopping VM.
2025-11-25 09:24:42 (11600): Successfully stopped VM.
2025-11-25 10:09:21 (3592): vboxwrapper version 26210
2025-11-25 10:09:21 (3592): BOINC client version: 7.16.20
2025-11-25 10:09:22 (3592): Detected: VirtualBox VboxManage Interface (Version: 6.0.14)
2025-11-25 10:09:22 (3592): Starting VM using VBoxManage interface. (boinc_c769c7770003d944, slot#7)
2025-11-25 10:10:54 (3592): Successfully started VM. (PID = '9076')
2025-11-25 10:10:54 (3592): Reporting VM Process ID to BOINC.
2025-11-25 10:10:54 (3592): VM state change detected. (old = 'poweredoff', new = 'running')
2025-11-25 10:10:54 (3592): Detected: Web Application Enabled (http://localhost:63018)
2025-11-25 10:10:54 (3592): Preference change detected
2025-11-25 10:10:54 (3592): Setting CPU throttle for VM. (100%)
2025-11-25 10:10:54 (3592): Setting checkpoint interval to 900 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 900 seconds))
2025-11-25 10:11:01 (3592): Guest Log: 00:04:50.220897 timesync vgsvcTimeSyncWorker: Radical host time change: 7 121 131 000 000ns (HostNow=1 764 061 861 353 000 000 ns HostLast=1 764 054 740 222 000 000 ns)
2025-11-25 10:11:11 (3592): Guest Log: 00:05:00.221438 timesync vgsvcTimeSyncWorker: Radical guest time change: 7 121 132 200 000ns (GuestNow=1 764 061 871 354 436 000 ns GuestLast=1 764 054 750 222 236 000 ns fSetTimeLastLoop=true )
2025-11-25 10:11:59 (3592): Stopping VM.
2025-11-25 10:12:16 (3592): Successfully stopped VM.
2025-11-25 10:21:57 (17336): vboxwrapper version 26210
2025-11-25 10:21:57 (17336): BOINC client version: 7.16.20
2025-11-25 10:21:58 (17336): Detected: VirtualBox VboxManage Interface (Version: 6.0.14)
2025-11-25 10:21:58 (17336): Guest Log: 00:04:50.220897 timesync vgsvcTimeSyncWorker: Radical host time change: 7 121 131 000 000ns (HostNow=1 764 061 861 353 000 000 ns HostLast=1 764 054 740 222 000 000 ns)
2025-11-25 10:21:58 (17336): Guest Log: 00:05:00.221438 timesync vgsvcTimeSyncWorker: Radical guest time change: 7 121 132 200 000ns (GuestNow=1 764 061 871 354 436 000 ns GuestLast=1 764 054 750 222 236 000 ns fSetTimeLastLoop=true )
2025-11-25 10:21:58 (17336): Starting VM using VBoxManage interface. (boinc_c769c7770003d944, slot#7)
2025-11-25 10:58:59 (1628): vboxwrapper version 26210
2025-11-25 10:58:59 (1628): BOINC client version: 7.16.20
2025-11-25 10:59:00 (1628): Detected: VirtualBox VboxManage Interface (Version: 6.0.14)
2025-11-25 10:59:06 (1628): VM was running
2025-11-25 10:59:06 (1628): Powering off VM.
2025-11-25 10:59:07 (1628): Successfully stopped VM.
2025-11-25 10:59:07 (1628): Starting VM using VBoxManage interface. (boinc_c769c7770003d944, slot#7)
2025-11-25 10:59:11 (1628): Successfully started VM. (PID = '16696')
2025-11-25 10:59:11 (1628): Reporting VM Process ID to BOINC.
2025-11-25 10:59:11 (1628): Guest Log: BIOS: VirtualBox 6.0.14
2025-11-25 10:59:11 (1628): Guest Log: CPUID EDX: 0x178bfbff
2025-11-25 10:59:11 (1628): Guest Log: BIOS: AHCI 0-P#0: PCHS=16383/16/63 LCHS=1024/255/63 0x0000000002800000 sectors
2025-11-25 10:59:11 (1628): VM state change detected. (old = 'poweredoff', new = 'running')
2025-11-25 10:59:11 (1628): Detected: Web Application Enabled (http://localhost:63018)
2025-11-25 10:59:11 (1628): Preference change detected
2025-11-25 10:59:11 (1628): Setting CPU throttle for VM. (100%)
2025-11-25 10:59:12 (1628): Setting checkpoint interval to 900 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 900 seconds))
2025-11-25 10:59:13 (1628): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032
2025-11-25 10:59:13 (1628): Guest Log: BIOS: Booting from Hard Disk...
2025-11-25 10:59:16 (1628): Guest Log: BIOS: KBD: unsupported int 16h function 03
2025-11-25 10:59:16 (1628): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 
2025-11-25 10:59:16 (1628): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=81
2025-11-25 10:59:16 (1628): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=81
2025-11-25 10:59:16 (1628): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=82
2025-11-25 10:59:16 (1628): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=82
2025-11-25 10:59:16 (1628): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=83
2025-11-25 10:59:16 (1628): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=83
2025-11-25 10:59:16 (1628): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=84
2025-11-25 10:59:16 (1628): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=84
2025-11-25 10:59:16 (1628): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=85
2025-11-25 10:59:16 (1628): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=85
2025-11-25 10:59:16 (1628): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=86
2025-11-25 10:59:16 (1628): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=86
2025-11-25 10:59:16 (1628): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=87
2025-11-25 10:59:16 (1628): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=87
2025-11-25 10:59:16 (1628): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=88
2025-11-25 10:59:16 (1628): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=88
2025-11-25 10:59:16 (1628): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=89
2025-11-25 10:59:16 (1628): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=89
2025-11-25 10:59:16 (1628): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=8a
2025-11-25 10:59:16 (1628): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=8a
2025-11-25 10:59:16 (1628): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=8b
2025-11-25 10:59:16 (1628): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=8b
2025-11-25 10:59:16 (1628): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=8c
2025-11-25 10:59:16 (1628): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=8c
2025-11-25 10:59:16 (1628): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=8d
2025-11-25 10:59:16 (1628): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=8d
2025-11-25 10:59:16 (1628): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=8e
2025-11-25 10:59:16 (1628): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=8e
2025-11-25 10:59:16 (1628): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=8f
2025-11-25 10:59:16 (1628): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=8f
2025-11-25 10:59:28 (1628): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds
2025-11-25 10:59:28 (1628): Guest Log: vboxguest: misc device minor 58, IRQ 20, I/O port d020, MMIO at 00000000f0000000 (size 0x400000)
2025-11-25 10:59:41 (1628): Guest Log: VBoxService 5.2.32 r132073 (verbosity: 0) linux.amd64 (Jul 12 2019 10:32:28) release log
2025-11-25 10:59:41 (1628): Guest Log: 00:00:00.000169 main     Log opened 2025-11-25T10:59:39.085619000Z
2025-11-25 10:59:41 (1628): Guest Log: 00:00:00.000274 main     OS Product: Linux
2025-11-25 10:59:41 (1628): Guest Log: 00:00:00.000316 main     OS Release: 3.10.0-957.27.2.el7.x86_64
2025-11-25 10:59:41 (1628): Guest Log: 00:00:00.000352 main     OS Version: #1 SMP Mon Jul 29 17:46:05 UTC 2019
2025-11-25 10:59:41 (1628): Guest Log: 00:00:00.000389 main     Executable: /opt/VBoxGuestAdditions-5.2.32/sbin/VBoxService
2025-11-25 10:59:41 (1628): Guest Log: 00:00:00.000390 main     Process ID: 1376
2025-11-25 10:59:41 (1628): Guest Log: 00:00:00.000391 main     Package type: LINUX_64BITS_GENERIC
2025-11-25 10:59:41 (1628): Guest Log: 00:00:00.001833 main     5.2.32 r132073 started. Verbose level = 0
2025-11-25 10:59:41 (1628): Guest Log: [INFO] Probing /cvmfs/atlas.cern.ch...
2025-11-25 10:59:41 (1628): Guest Log: [INFO] Mounting shared directory
2025-11-25 10:59:41 (1628): Guest Log: [INFO] Checking for init_data.xml
2025-11-25 10:59:47 (1628): Guest Log: [INFO] Probing /cvmfs/atlas.cern.ch... OK
2025-11-25 10:59:47 (1628): Guest Log: [INFO] Detected branch: prod
2025-11-25 10:59:47 (1628): Guest Log: This is the prod version of the ATLAS job wrapper
2025-11-25 10:59:48 (1628): Guest Log: Copying input files
2025-11-25 10:59:51 (1628): Guest Log: 00:00:10.016130 timesync vgsvcTimeSyncWorker: Radical guest time change: -3 587 924 480 000ns (GuestNow=1 764 064 791 164 526 000 ns GuestLast=1 764 068 379 089 006 000 ns fSetTimeLastLoop=true )
2025-11-25 10:59:56 (1628): Guest Log: Copied input files into RunAtlas.
2025-11-25 10:59:56 (1628): Guest Log: This VM did not configure a local http proxy via BOINC.
2025-11-25 10:59:56 (1628): Guest Log: Small home clusters do not require a local http proxy but it is suggested if
2025-11-25 10:59:56 (1628): Guest Log: more than 10 cores throughout the same LAN segment are regularly running ATLAS like tasks.
2025-11-25 10:59:56 (1628): Guest Log: Further information can be found at the LHC@home message board.
2025-11-25 11:00:04 (1628): Guest Log: Running cvmfs_config stat atlas.cern.ch
2025-11-25 11:00:05 (1628): 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-25 11:00:05 (1628): Guest Log: 2.6.3.0 1654 0 32112 153277 4 1 3882648 4096000 0 65024 0 0 n/a 0 0 http://s1cern-cvmfs.openhtc.io/cvmfs/atlas.cern.ch DIRECT 1
2025-11-25 11:00:07 (1628): Guest Log: copied the webapp to /var/www
2025-11-25 11:00:07 (1628): Guest Log: ATHENA_PROC_NUMBER=8
2025-11-25 11:00:07 (1628): Guest Log: ATHENA_CORE_NUMBER=8
2025-11-25 11:00:07 (1628): 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-25 11:00:08 (1628): Guest Log:  *** Starting ATLAS job. (PandaID=6894053083 taskID=47523618) ***
2025-11-25 11:00:52 (1628): Stopping VM.
2025-11-25 11:00:58 (1628): Successfully stopped VM.
2025-11-25 11:21:21 (8532): vboxwrapper version 26210
2025-11-25 11:21:21 (8532): BOINC client version: 7.16.20
2025-11-25 11:21:22 (8532): Detected: VirtualBox VboxManage Interface (Version: 6.0.14)
2025-11-25 11:21:23 (8532): Guest Log: BIOS: VirtualBox 6.0.14
2025-11-25 11:21:23 (8532): Guest Log: CPUID EDX: 0x178bfbff
2025-11-25 11:21:23 (8532): Guest Log: BIOS: AHCI 0-P#0: PCHS=16383/16/63 LCHS=1024/255/63 0x0000000002800000 sectors
2025-11-25 11:21:23 (8532): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032
2025-11-25 11:21:23 (8532): Guest Log: BIOS: Booting from Hard Disk...
2025-11-25 11:21:23 (8532): Guest Log: BIOS: KBD: unsupported int 16h function 03
2025-11-25 11:21:23 (8532): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 
2025-11-25 11:21:23 (8532): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=81
2025-11-25 11:21:23 (8532): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=81
2025-11-25 11:21:23 (8532): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=82
2025-11-25 11:21:23 (8532): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=82
2025-11-25 11:21:23 (8532): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=83
2025-11-25 11:21:23 (8532): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=83
2025-11-25 11:21:23 (8532): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=84
2025-11-25 11:21:23 (8532): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=84
2025-11-25 11:21:23 (8532): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=85
2025-11-25 11:21:23 (8532): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=85
2025-11-25 11:21:23 (8532): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=86
2025-11-25 11:21:23 (8532): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=86
2025-11-25 11:21:23 (8532): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=87
2025-11-25 11:21:23 (8532): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=87
2025-11-25 11:21:23 (8532): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=88
2025-11-25 11:21:23 (8532): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=88
2025-11-25 11:21:23 (8532): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=89
2025-11-25 11:21:23 (8532): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=89
2025-11-25 11:21:23 (8532): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=8a
2025-11-25 11:21:23 (8532): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=8a
2025-11-25 11:21:23 (8532): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=8b
2025-11-25 11:21:23 (8532): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=8b
2025-11-25 11:21:23 (8532): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=8c
2025-11-25 11:21:23 (8532): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=8c
2025-11-25 11:21:23 (8532): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=8d
2025-11-25 11:21:23 (8532): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=8d
2025-11-25 11:21:23 (8532): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=8e
2025-11-25 11:21:23 (8532): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=8e
2025-11-25 11:21:23 (8532): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=8f
2025-11-25 11:21:23 (8532): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=8f
2025-11-25 11:21:23 (8532): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds
2025-11-25 11:21:23 (8532): Guest Log: vboxguest: misc device minor 58, IRQ 20, I/O port d020, MMIO at 00000000f0000000 (size 0x400000)
2025-11-25 11:21:23 (8532): Guest Log: VBoxService 5.2.32 r132073 (verbosity: 0) linux.amd64 (Jul 12 2019 10:32:28) release log
2025-11-25 11:21:23 (8532): Guest Log: 00:00:00.000169 main     Log opened 2025-11-25T10:59:39.085619000Z
2025-11-25 11:21:23 (8532): Guest Log: 00:00:00.000274 main     OS Product: Linux
2025-11-25 11:21:23 (8532): Guest Log: 00:00:00.000316 main     OS Release: 3.10.0-957.27.2.el7.x86_64
2025-11-25 11:21:23 (8532): Guest Log: 00:00:00.000352 main     OS Version: #1 SMP Mon Jul 29 17:46:05 UTC 2019
2025-11-25 11:21:23 (8532): Guest Log: 00:00:00.000389 main     Executable: /opt/VBoxGuestAdditions-5.2.32/sbin/VBoxService
2025-11-25 11:21:23 (8532): Guest Log: 00:00:00.000390 main     Process ID: 1376
2025-11-25 11:21:23 (8532): Guest Log: 00:00:00.000391 main     Package type: LINUX_64BITS_GENERIC
2025-11-25 11:21:23 (8532): Guest Log: 00:00:00.001833 main     5.2.32 r132073 started. Verbose level = 0
2025-11-25 11:21:23 (8532): Guest Log: [INFO] Probing /cvmfs/atlas.cern.ch...
2025-11-25 11:21:23 (8532): Guest Log: [INFO] Mounting shared directory
2025-11-25 11:21:23 (8532): Guest Log: [INFO] Checking for init_data.xml
2025-11-25 11:21:23 (8532): Guest Log: [INFO] Probing /cvmfs/atlas.cern.ch... OK
2025-11-25 11:21:23 (8532): Guest Log: [INFO] Detected branch: prod
2025-11-25 11:21:23 (8532): Guest Log: This is the prod version of the ATLAS job wrapper
2025-11-25 11:21:23 (8532): Guest Log: Copying input files
2025-11-25 11:21:23 (8532): Guest Log: 00:00:10.016130 timesync vgsvcTimeSyncWorker: Radical guest time change: -3 587 924 480 000ns (GuestNow=1 764 064 791 164 526 000 ns GuestLast=1 764 068 379 089 006 000 ns fSetTimeLastLoop=true )
2025-11-25 11:21:23 (8532): Guest Log: Copied input files into RunAtlas.
2025-11-25 11:21:23 (8532): Guest Log: This VM did not configure a local http proxy via BOINC.
2025-11-25 11:21:23 (8532): Guest Log: Small home clusters do not require a local http proxy but it is suggested if
2025-11-25 11:21:23 (8532): Guest Log: more than 10 cores throughout the same LAN segment are regularly running ATLAS like tasks.
2025-11-25 11:21:23 (8532): Guest Log: Further information can be found at the LHC@home message board.
2025-11-25 11:21:23 (8532): Guest Log: Running cvmfs_config stat atlas.cern.ch
2025-11-25 11:21:23 (8532): 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-25 11:21:23 (8532): Guest Log: 2.6.3.0 1654 0 32112 153277 4 1 3882648 4096000 0 65024 0 0 n/a 0 0 http://s1cern-cvmfs.openhtc.io/cvmfs/atlas.cern.ch DIRECT 1
2025-11-25 11:21:23 (8532): Guest Log: copied the webapp to /var/www
2025-11-25 11:21:23 (8532): Guest Log: ATHENA_PROC_NUMBER=8
2025-11-25 11:21:23 (8532): Guest Log: ATHENA_CORE_NUMBER=8
2025-11-25 11:21:23 (8532): 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-25 11:21:23 (8532): Guest Log:  *** Starting ATLAS job. (PandaID=6894053083 taskID=47523618) ***
2025-11-25 11:21:23 (8532): Starting VM using VBoxManage interface. (boinc_c769c7770003d944, slot#7)
2025-11-25 11:21:52 (8532): Successfully started VM. (PID = '15036')
2025-11-25 11:21:52 (8532): Reporting VM Process ID to BOINC.
2025-11-25 11:21:52 (8532): VM state change detected. (old = 'poweredoff', new = 'running')
2025-11-25 11:21:52 (8532): Detected: Web Application Enabled (http://localhost:63018)
2025-11-25 11:21:52 (8532): Preference change detected
2025-11-25 11:21:52 (8532): Setting CPU throttle for VM. (100%)
2025-11-25 11:21:52 (8532): Setting checkpoint interval to 900 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 900 seconds))
2025-11-25 11:22:28 (8532): Stopping VM.
2025-11-25 11:22:35 (8532): Successfully stopped VM.
2025-11-25 11:25:29 (5644): vboxwrapper version 26210
2025-11-25 11:25:29 (5644): BOINC client version: 7.16.20
2025-11-25 11:25:30 (5644): Detected: VirtualBox VboxManage Interface (Version: 6.0.14)
2025-11-25 11:25:30 (5644): Starting VM using VBoxManage interface. (boinc_c769c7770003d944, slot#7)
2025-11-25 11:26:02 (5644): Successfully started VM. (PID = '16116')
2025-11-25 11:26:02 (5644): Reporting VM Process ID to BOINC.
2025-11-25 11:26:02 (5644): VM state change detected. (old = 'poweredoff', new = 'running')
2025-11-25 11:26:02 (5644): Detected: Web Application Enabled (http://localhost:63018)
2025-11-25 11:26:02 (5644): Preference change detected
2025-11-25 11:26:02 (5644): Setting CPU throttle for VM. (100%)
2025-11-25 11:26:03 (5644): Setting checkpoint interval to 900 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 900 seconds))
2025-11-25 11:28:26 (5644): Stopping VM.
2025-11-25 11:28:40 (5644): Successfully stopped VM.
2025-11-25 12:04:36 (12208): vboxwrapper version 26210
2025-11-25 12:04:36 (12208): BOINC client version: 7.16.20
2025-11-25 12:04:37 (12208): Detected: VirtualBox VboxManage Interface (Version: 6.0.14)
2025-11-25 12:04:38 (12208): Starting VM using VBoxManage interface. (boinc_c769c7770003d944, slot#7)
2025-11-25 12:06:47 (12208): Successfully started VM. (PID = '1088')
2025-11-25 12:06:47 (12208): Reporting VM Process ID to BOINC.
2025-11-25 12:06:47 (12208): VM state change detected. (old = 'poweredoff', new = 'running')
2025-11-25 12:06:47 (12208): Detected: Web Application Enabled (http://localhost:63018)
2025-11-25 12:06:47 (12208): Stopping VM.
2025-11-25 12:07:02 (12208): Successfully stopped VM.
2025-11-25 12:12:06 (15168): vboxwrapper version 26210
2025-11-25 12:12:06 (15168): BOINC client version: 7.16.20
2025-11-25 12:12:07 (15168): Detected: VirtualBox VboxManage Interface (Version: 6.0.14)
2025-11-25 12:12:08 (15168): Starting VM using VBoxManage interface. (boinc_c769c7770003d944, slot#7)
2025-11-25 12:13:50 (15168): Successfully started VM. (PID = '17096')
2025-11-25 12:13:50 (15168): Reporting VM Process ID to BOINC.
2025-11-25 12:13:50 (15168): VM state change detected. (old = 'poweredoff', new = 'running')
2025-11-25 12:13:50 (15168): Detected: Web Application Enabled (http://localhost:63018)
2025-11-25 12:13:50 (15168): Stopping VM.
2025-11-25 12:14:05 (15168): Successfully stopped VM.
2025-11-25 12:16:47 (17164): vboxwrapper version 26210
2025-11-25 12:16:47 (17164): BOINC client version: 7.16.20
2025-11-25 12:16:48 (17164): Detected: VirtualBox VboxManage Interface (Version: 6.0.14)
2025-11-25 12:16:49 (17164): Starting VM using VBoxManage interface. (boinc_c769c7770003d944, slot#7)
2025-11-25 12:18:27 (17164): Successfully started VM. (PID = '10512')
2025-11-25 12:18:27 (17164): Reporting VM Process ID to BOINC.
2025-11-25 12:18:27 (17164): VM state change detected. (old = 'poweredoff', new = 'running')
2025-11-25 12:18:27 (17164): Detected: Web Application Enabled (http://localhost:63018)
2025-11-25 12:18:27 (17164): Preference change detected
2025-11-25 12:18:27 (17164): Setting CPU throttle for VM. (100%)
2025-11-25 12:18:28 (17164): Setting checkpoint interval to 900 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 900 seconds))
2025-11-25 12:18:31 (17164): Guest Log: 00:04:20.267849 timesync vgsvcTimeSyncWorker: Radical host time change: 3 008 442 000 000ns (HostNow=1 764 069 511 404 000 000 ns HostLast=1 764 066 502 962 000 000 ns)
2025-11-25 12:18:41 (17164): Guest Log: 00:04:30.268882 timesync vgsvcTimeSyncWorker: Radical guest time change: 3 008 375 167 000ns (GuestNow=1 764 069 521 406 157 000 ns GuestLast=1 764 066 513 030 990 000 ns fSetTimeLastLoop=true )
2025-11-25 12:19:37 (17164): Stopping VM.
2025-11-25 12:19:54 (17164): Successfully stopped VM.
2025-11-25 12:22:37 (7516): vboxwrapper version 26210
2025-11-25 12:22:37 (7516): BOINC client version: 7.16.20
2025-11-25 12:22:38 (7516): Detected: VirtualBox VboxManage Interface (Version: 6.0.14)
2025-11-25 12:22:39 (7516): Guest Log: 00:04:20.267849 timesync vgsvcTimeSyncWorker: Radical host time change: 3 008 442 000 000ns (HostNow=1 764 069 511 404 000 000 ns HostLast=1 764 066 502 962 000 000 ns)
2025-11-25 12:22:39 (7516): Guest Log: 00:04:30.268882 timesync vgsvcTimeSyncWorker: Radical guest time change: 3 008 375 167 000ns (GuestNow=1 764 069 521 406 157 000 ns GuestLast=1 764 066 513 030 990 000 ns fSetTimeLastLoop=true )
2025-11-25 12:22:39 (7516): Starting VM using VBoxManage interface. (boinc_c769c7770003d944, slot#7)
2025-11-25 12:24:15 (7516): Successfully started VM. (PID = '13180')
2025-11-25 12:24:15 (7516): Reporting VM Process ID to BOINC.
2025-11-25 12:24:15 (7516): VM state change detected. (old = 'poweredoff', new = 'running')
2025-11-25 12:24:15 (7516): Detected: Web Application Enabled (http://localhost:63018)
2025-11-25 12:24:15 (7516): Preference change detected
2025-11-25 12:24:15 (7516): Setting CPU throttle for VM. (100%)
2025-11-25 12:24:16 (7516): Setting checkpoint interval to 900 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 900 seconds))
2025-11-25 12:24:59 (7516): Stopping VM.
2025-11-25 12:25:16 (7516): Successfully stopped VM.
2025-11-25 12:38:04 (4668): vboxwrapper version 26210
2025-11-25 12:38:04 (4668): BOINC client version: 7.16.20
2025-11-25 12:38:05 (4668): Detected: VirtualBox VboxManage Interface (Version: 6.0.14)
2025-11-25 12:38:06 (4668): Starting VM using VBoxManage interface. (boinc_c769c7770003d944, slot#7)
2025-11-25 12:40:13 (4668): Successfully started VM. (PID = '4164')
2025-11-25 12:40:13 (4668): Reporting VM Process ID to BOINC.
2025-11-25 12:40:13 (4668): VM state change detected. (old = 'poweredoff', new = 'running')
2025-11-25 12:40:13 (4668): Detected: Web Application Enabled (http://localhost:63018)
2025-11-25 12:40:13 (4668): Stopping VM.
2025-11-25 12:40:29 (4668): Successfully stopped VM.
2025-11-25 12:44:58 (8448): vboxwrapper version 26210
2025-11-25 12:44:58 (8448): BOINC client version: 7.16.20
2025-11-25 12:44:59 (8448): Detected: VirtualBox VboxManage Interface (Version: 6.0.14)
2025-11-25 12:45:00 (8448): Starting VM using VBoxManage interface. (boinc_c769c7770003d944, slot#7)
2025-11-25 12:46:45 (8448): Successfully started VM. (PID = '2876')
2025-11-25 12:46:45 (8448): Reporting VM Process ID to BOINC.
2025-11-25 12:46:45 (8448): VM state change detected. (old = 'poweredoff', new = 'running')
2025-11-25 12:46:45 (8448): Detected: Web Application Enabled (http://localhost:63018)
2025-11-25 12:46:45 (8448): Stopping VM.
2025-11-25 12:47:01 (8448): Successfully stopped VM.
2025-11-25 13:01:25 (8696): vboxwrapper version 26210
2025-11-25 13:01:25 (8696): BOINC client version: 7.16.20
2025-11-25 13:01:26 (8696): Detected: VirtualBox VboxManage Interface (Version: 6.0.14)
2025-11-25 13:01:27 (8696): Starting VM using VBoxManage interface. (boinc_c769c7770003d944, slot#7)
2025-11-25 13:03:08 (8696): Successfully started VM. (PID = '11400')
2025-11-25 13:03:08 (8696): Reporting VM Process ID to BOINC.
2025-11-25 13:03:08 (8696): VM state change detected. (old = 'poweredoff', new = 'running')
2025-11-25 13:03:08 (8696): Detected: Web Application Enabled (http://localhost:63018)
2025-11-25 13:03:08 (8696): Stopping VM.
2025-11-25 13:03:26 (8696): Successfully stopped VM.
2025-11-25 13:07:23 (4140): vboxwrapper version 26210
2025-11-25 13:07:23 (4140): BOINC client version: 7.16.20
2025-11-25 13:07:24 (4140): Detected: VirtualBox VboxManage Interface (Version: 6.0.14)
2025-11-25 13:07:25 (4140): Starting VM using VBoxManage interface. (boinc_c769c7770003d944, slot#7)
2025-11-25 13:09:22 (4140): Successfully started VM. (PID = '13844')
2025-11-25 13:09:22 (4140): Reporting VM Process ID to BOINC.
2025-11-25 13:09:22 (4140): VM state change detected. (old = 'poweredoff', new = 'running')
2025-11-25 13:09:22 (4140): Detected: Web Application Enabled (http://localhost:63018)
2025-11-25 13:09:22 (4140): Preference change detected
2025-11-25 13:09:22 (4140): Setting CPU throttle for VM. (100%)
2025-11-25 13:09:22 (4140): Setting checkpoint interval to 900 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 900 seconds))
2025-11-25 13:09:26 (4140): Guest Log: 00:06:20.411028 timesync vgsvcTimeSyncWorker: Radical host time change: 2 668 142 000 000ns (HostNow=1 764 072 566 590 000 000 ns HostLast=1 764 069 898 448 000 000 ns)
2025-11-25 13:09:28 (4140): Stopping VM.
2025-11-25 13:09:45 (4140): Successfully stopped VM.
2025-11-26 08:16:41 (11216): vboxwrapper version 26210
2025-11-26 08:16:41 (11216): BOINC client version: 7.16.20
2025-11-26 08:16:42 (11216): Detected: VirtualBox VboxManage Interface (Version: 6.0.14)
2025-11-26 08:16:43 (11216): Guest Log: 00:06:20.411028 timesync vgsvcTimeSyncWorker: Radical host time change: 2 668 142 000 000ns (HostNow=1 764 072 566 590 000 000 ns HostLast=1 764 069 898 448 000 000 ns)
2025-11-26 08:16:43 (11216): Starting VM using VBoxManage interface. (boinc_c769c7770003d944, slot#7)
2025-11-26 08:17:54 (11216): Successfully started VM. (PID = '1028')
2025-11-26 08:17:54 (11216): Reporting VM Process ID to BOINC.
2025-11-26 08:17:54 (11216): VM state change detected. (old = 'poweredoff', new = 'running')
2025-11-26 08:17:54 (11216): Detected: Web Application Enabled (http://localhost:63018)
2025-11-26 08:17:54 (11216): Preference change detected
2025-11-26 08:17:54 (11216): Setting CPU throttle for VM. (100%)
2025-11-26 08:17:54 (11216): Setting checkpoint interval to 900 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 900 seconds))
2025-11-26 08:18:01 (11216): Guest Log: 00:06:30.411864 timesync vgsvcTimeSyncWorker: Radical host time change: 68 914 888 000 000ns (HostNow=1 764 141 481 478 000 000 ns HostLast=1 764 072 566 590 000 000 ns)
2025-11-26 08:18:01 (11216): Guest Log: 00:06:30.411996 timesync vgsvcTimeSyncWorker: Radical guest time change: 2 668 140 242 000ns (GuestNow=1 764 072 576 590 470 000 ns GuestLast=1 764 069 908 450 228 000 ns fSetTimeLastLoop=true )
2025-11-26 08:18:05 (11216): Stopping VM.
2025-11-26 08:18:21 (11216): Successfully stopped VM.
2025-11-26 08:21:04 (6316): vboxwrapper version 26210
2025-11-26 08:21:04 (6316): BOINC client version: 7.16.20
2025-11-26 08:21:05 (6316): Detected: VirtualBox VboxManage Interface (Version: 6.0.14)
2025-11-26 08:21:06 (6316): Guest Log: 00:06:30.411864 timesync vgsvcTimeSyncWorker: Radical host time change: 68 914 888 000 000ns (HostNow=1 764 141 481 478 000 000 ns HostLast=1 764 072 566 590 000 000 ns)
2025-11-26 08:21:06 (6316): Guest Log: 00:06:30.411996 timesync vgsvcTimeSyncWorker: Radical guest time change: 2 668 140 242 000ns (GuestNow=1 764 072 576 590 470 000 ns GuestLast=1 764 069 908 450 228 000 ns fSetTimeLastLoop=true )
2025-11-26 08:21:06 (6316): Starting VM using VBoxManage interface. (boinc_c769c7770003d944, slot#7)
2025-11-26 08:22:21 (6316): Successfully started VM. (PID = '9928')
2025-11-26 08:22:21 (6316): Reporting VM Process ID to BOINC.
2025-11-26 08:22:21 (6316): VM state change detected. (old = 'poweredoff', new = 'running')
2025-11-26 08:22:21 (6316): Detected: Web Application Enabled (http://localhost:63018)
2025-11-26 08:22:21 (6316): Preference change detected
2025-11-26 08:22:21 (6316): Setting CPU throttle for VM. (100%)
2025-11-26 08:22:22 (6316): Setting checkpoint interval to 900 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 900 seconds))
2025-11-26 08:22:26 (6316): Guest Log: 00:06:40.413191 timesync vgsvcTimeSyncWorker: Radical guest time change: 68 914 888 895 000ns (GuestNow=1 764 141 491 479 365 000 ns GuestLast=1 764 072 576 590 470 000 ns fSetTimeLastLoop=true )
2025-11-26 08:24:09 (6316): Guest Log:  *** Job finished ***
2025-11-26 08:24:09 (6316): Guest Log:  *** The last 20 lines of the pilot log: ***
2025-11-26 08:24:09 (6316): Guest Log: no translation to shell exit code for error code 1150
2025-11-26 08:24:09 (6316): Guest Log: 2025-11-26 07:24:00,747 | INFO     | pilot has finished (exit code=1150, shell exit code=1)
2025-11-26 08:24:09 (6316): Guest Log: 2025-11-26 07:24:08,616 | INFO     | PID=6083 has CPU usage=1.9% CMD=/cvmfs/atlas.cern.ch/repo/ATLASLocalRootBase/x86_64/python/3.9.20-x86_64-centos7/bin/python3 pilot3/pilot.py -q BOINC_MCORE -i PR -j managed --pilot-user ATLAS --harvester-submit-mode PUSH -w generic --job-type managed --resource-type MCORE -z -t
2025-11-26 08:24:09 (6316): Guest Log: 2025-11-26 07:24:08,617 | INFO     | found 0 job(s) in 20 queues
2025-11-26 08:24:09 (6316): Guest Log: 2025-11-26 07:24:08,617 | WARNING  | pilot monitor received instruction that args.graceful_stop has been set
2025-11-26 08:24:09 (6316): Guest Log: 2025-11-26 07:24:08,617 | WARNING  | will wait for a maximum of 300 s for threads to finish
2025-11-26 08:24:09 (6316): Guest Log: 2025-11-26 07:24:08,617 | WARNING  | job_aborted has been set - aborting pilot monitoring
2025-11-26 08:24:09 (6316): Guest Log: 2025-11-26 07:24:08,617 | INFO     | [monitor] control thread has ended
2025-11-26 08:24:09 (6316): Guest Log: 2025-11-26 07:24:08,786 [wrapper] ==== pilot stdout END ====
2025-11-26 08:24:09 (6316): Guest Log: 2025-11-26 07:24:08,801 [wrapper] ==== wrapper stdout RESUME ====
2025-11-26 08:24:09 (6316): Guest Log: 2025-11-26 07:24:08,814 [wrapper] pilotpid: 6083
2025-11-26 08:24:09 (6316): Guest Log: 2025-11-26 07:24:08,826 [wrapper] Pilot exit status: 1
2025-11-26 08:24:09 (6316): Guest Log: 2025-11-26 07:24:08,930 [wrapper] pandaids: 6894053083
2025-11-26 08:24:09 (6316): Guest Log: 2025-11-26 07:24:08,949 [wrapper] WARNING: pilot exitcode non-zero: 1
2025-11-26 08:24:09 (6316): Guest Log: 2025-11-26 07:24:08,965 [wrapper] WARNING: pilot exitcode non-zero: 1
2025-11-26 08:24:09 (6316): Guest Log: 2025-11-26 07:24:09,060 [wrapper] cleanup supervisor_pilot  6089 6084
2025-11-26 08:24:09 (6316): Guest Log: 2025-11-26 07:24:09,075 [wrapper] Test setup, not cleaning
2025-11-26 08:24:09 (6316): Guest Log: 2025-11-26 07:24:09,090 [wrapper] apfmon messages muted
2025-11-26 08:24:09 (6316): Guest Log: 2025-11-26 07:24:09,104 [wrapper] ==== wrapper stdout END ====
2025-11-26 08:24:09 (6316): Guest Log: 2025-11-26 07:24:09,122 [wrapper] ==== wrapper stderr END ====
2025-11-26 08:24:09 (6316): Guest Log:  *** Error codes and diagnostics ***
2025-11-26 08:24:09 (6316): Guest Log:     "exeErrorCode": 0,
2025-11-26 08:24:09 (6316): Guest Log:     "exeErrorDiag": "",
2025-11-26 08:24:09 (6316): Guest Log:     "pilotErrorCode": 1150,
2025-11-26 08:24:09 (6316): Guest Log:     "pilotErrorDiag": "Looping job killed by pilot",
2025-11-26 08:24:09 (6316): Guest Log:  *** Listing of results directory ***
2025-11-26 08:24:09 (6316): Guest Log: total 4148
2025-11-26 08:24:09 (6316): Guest Log: -rw-r--r--. 1 atlas atlas    5112 Nov 23 14:16 queuedata.json
2025-11-26 08:24:09 (6316): Guest Log: -rw-r--r--. 1 atlas atlas  576529 Nov 23 14:16 pilot3.tar.gz
2025-11-26 08:24:09 (6316): Guest Log: -rwx------. 1 atlas atlas   36292 Nov 23 14:17 runpilot2-wrapper.sh
2025-11-26 08:24:09 (6316): Guest Log: -rwxr-xr-x. 1 atlas atlas    8775 Nov 25 09:59 init_data.xml
2025-11-26 08:24:09 (6316): Guest Log: -rwxr-xr-x. 1 atlas atlas  589958 Nov 25 09:59 input.tar.gz
2025-11-26 08:24:09 (6316): Guest Log: -rwxr-xr-x. 1 atlas atlas   15847 Nov 25 09:59 start_atlas.sh
2025-11-26 08:24:09 (6316): Guest Log: lrwxrwxrwx. 1 atlas atlas      20 Nov 25 09:59 EVNT.47523616._000002.pool.root.1 -> /data/./ATLAS.root_0
2025-11-26 08:24:09 (6316): Guest Log: -rw-r--r--. 1 atlas atlas    2611 Nov 25 10:00 pandaJob.out
2025-11-26 08:24:09 (6316): Guest Log: -rw-------. 1 atlas atlas  991894 Nov 25 10:00 agis_schedconf.cvmfs.json
2025-11-26 08:24:09 (6316): Guest Log: drwx------. 5 atlas atlas    4096 Nov 25 10:00 pilot3
2025-11-26 08:24:09 (6316): Guest Log: -rw-------. 1 atlas atlas    1017 Nov 26 07:22 memory_monitor_summary.json
2025-11-26 08:24:10 (6316): Guest Log: -rw-------. 1 atlas atlas   61003 Nov 26 07:22 log.47523618._000029.job.log.tgz.1
2025-11-26 08:24:10 (6316): Guest Log: -rw-------. 1 atlas atlas 1516240 Nov 26 07:22 agis_ddmendpoints.agis.ALL.json
2025-11-26 08:24:10 (6316): Guest Log: -rw-------. 1 atlas atlas      98 Nov 26 07:23 pilot_heartbeat.json
2025-11-26 08:24:10 (6316): Guest Log: -rw-------. 1 atlas atlas    2716 Nov 26 07:23 heartbeat.json
2025-11-26 08:24:10 (6316): Guest Log: -rw-------. 1 atlas atlas     744 Nov 26 07:24 pilotlog.txt
2025-11-26 08:24:10 (6316): Guest Log: -rw-------. 1 atlas atlas  147578 Nov 26 07:24 log.47523618._000029.job.log.1
2025-11-26 08:24:10 (6316): Guest Log: -rw-------. 1 atlas atlas     188 Nov 26 07:24 output.list
2025-11-26 08:24:10 (6316): Guest Log: -rw-r--r--. 1 atlas atlas     620 Nov 26 07:24 runtime_log
2025-11-26 08:24:10 (6316): Guest Log: -rw-------. 1 atlas atlas  225280 Nov 26 07:24 result.tar.gz
2025-11-26 08:24:10 (6316): Guest Log: -rw-r--r--. 1 atlas atlas    8580 Nov 26 07:24 runtime_log.err
2025-11-26 08:24:10 (6316): Guest Log: -rw-------. 1 atlas atlas     638 Nov 26 07:24 6wNMDma5Ph8nsSi4ap6QjLDmwznN0nGgGQJmNLGKDm5xKKDmFxlU6m.diag
2025-11-26 08:24:10 (6316): Guest Log: Looking for outputfile HITS.47523618._000029.pool.root.1
2025-11-26 08:24:10 (6316): Guest Log: No HITS file was produced
2025-11-26 08:24:10 (6316): Guest Log: Successfully finished the ATLAS job!
2025-11-26 08:24:10 (6316): Guest Log: Copying the results back to the shared directory!
2025-11-26 08:24:10 (6316): Guest Log:  *** Contents of shared directory: ***
2025-11-26 08:24:10 (6316): Guest Log: total 301352
2025-11-26 08:24:10 (6316): Guest Log: -rwxrwxrwx. 1 root root 307735345 Nov 24 10:20 ATLAS.root_0
2025-11-26 08:24:10 (6316): Guest Log: -rwxrwxrwx. 1 root root      8775 Nov 24 10:20 init_data.xml
2025-11-26 08:24:10 (6316): Guest Log: -rwxrwxrwx. 1 root root    589958 Nov 24 10:10 input.tar.gz
2025-11-26 08:24:10 (6316): Guest Log: -rwxrwxrwx. 1 root root    225280 Nov 26 07:24 result.tar.gz
2025-11-26 08:24:10 (6316): Guest Log: -rwxrwxrwx. 1 root root     15847 Nov 24 10:20 start_atlas.sh
2025-11-26 08:24:10 (6316): Guest Log:  *** Success! Shutting down the machine. ***
2025-11-26 08:24:10 (6316): VM Completion File Detected.
2025-11-26 08:24:10 (6316): Powering off VM.
2025-11-26 08:24:11 (6316): Successfully stopped VM.
2025-11-26 08:24:11 (6316): Deregistering VM. (boinc_c769c7770003d944, slot#7)
2025-11-26 08:24:11 (6316): Removing network bandwidth throttle group from VM.
2025-11-26 08:24:11 (6316): Removing VM from VirtualBox.
2025-11-26 08:24:17 (6316): called boinc_finish(0)

</stderr_txt>
]]>

©2025 CERN