| Name | IYEMDmbh7N7nsSi4ap6QjLDmwznN0nGgGQJmkKkKDmoRTLDmaDXdTm_0 |
| Workunit | 231582465 |
| Created | 13 Apr 2025, 8:17:24 UTC |
| Sent | 13 Apr 2025, 11:30:55 UTC |
| Report deadline | 21 Apr 2025, 11:30:55 UTC |
| Received | 14 Apr 2025, 21:06:40 UTC |
| Server state | Over |
| Outcome | Success |
| Client state | Done |
| Exit status | 0 (0x00000000) |
| Computer ID | 10861508 |
| Run time | 2 hours 6 min 3 sec |
| CPU time | 9 hours 57 min 55 sec |
| Validate state | Valid |
| Credit | 1,385.75 |
| Device peak FLOPS | 33.68 GFLOPS |
| Application version | ATLAS Simulation v3.01 (vbox64_mt_mcore_atlas) x86_64-pc-linux-gnu |
| Peak working set size | 4.76 GB |
| Peak swap size | 7.10 GB |
| Peak disk usage | 4.27 GB |
Stderr output
<core_client_version>8.0.2</core_client_version> <![CDATA[ <stderr_txt> (12179): Successfully started VM. (PID = '12762') 2025-04-13 12:31:30 (12179): Reporting VM Process ID to BOINC. 2025-04-13 12:31:30 (12179): Guest Log: BIOS: VirtualBox 7.1.6 2025-04-13 12:31:30 (12179): Guest Log: CPUID EDX: 0x178bfbff 2025-04-13 12:31:30 (12179): Guest Log: BIOS: No PCI IDE controller, not probing IDE 2025-04-13 12:31:30 (12179): Guest Log: BIOS: AHCI 0-P#0: PCHS=16383/16/63 LCHS=1024/255/63 0x0000000002800000 sectors 2025-04-13 12:31:30 (12179): VM state change detected. (old = 'poweredoff', new = 'running') 2025-04-13 12:31:30 (12179): Detected: Web Application Enabled (http://localhost:53553) 2025-04-13 12:31:30 (12179): Preference change detected 2025-04-13 12:31:30 (12179): Setting CPU throttle for VM. (100%) 2025-04-13 12:31:30 (12179): Setting checkpoint interval to 900 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 900 seconds)) 2025-04-13 12:31:32 (12179): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032 2025-04-13 12:31:32 (12179): Guest Log: BIOS: Booting from Hard Disk... 2025-04-13 12:31:35 (12179): Guest Log: BIOS: KBD: unsupported int 16h function 03 2025-04-13 12:31:35 (12179): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 2025-04-13 12:31:35 (12179): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=81 2025-04-13 12:31:35 (12179): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=81 2025-04-13 12:31:35 (12179): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=82 2025-04-13 12:31:35 (12179): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=82 2025-04-13 12:31:35 (12179): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=83 2025-04-13 12:31:35 (12179): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=83 2025-04-13 12:31:35 (12179): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=84 2025-04-13 12:31:35 (12179): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=84 2025-04-13 12:31:35 (12179): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=85 2025-04-13 12:31:35 (12179): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=85 2025-04-13 12:31:35 (12179): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=86 2025-04-13 12:31:35 (12179): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=86 2025-04-13 12:31:35 (12179): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=87 2025-04-13 12:31:35 (12179): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=87 2025-04-13 12:31:35 (12179): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=88 2025-04-13 12:31:35 (12179): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=88 2025-04-13 12:31:35 (12179): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=89 2025-04-13 12:31:35 (12179): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=89 2025-04-13 12:31:35 (12179): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=8a 2025-04-13 12:31:35 (12179): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=8a 2025-04-13 12:31:35 (12179): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=8b 2025-04-13 12:31:35 (12179): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=8b 2025-04-13 12:31:35 (12179): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=8c 2025-04-13 12:31:35 (12179): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=8c 2025-04-13 12:31:35 (12179): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=8d 2025-04-13 12:31:35 (12179): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=8d 2025-04-13 12:31:35 (12179): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=8e 2025-04-13 12:31:35 (12179): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=8e 2025-04-13 12:31:35 (12179): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=8f 2025-04-13 12:31:35 (12179): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=8f 2025-04-13 12:31:40 (12179): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds 2025-04-13 12:31:40 (12179): Guest Log: vboxguest: misc device minor 58, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000) 2025-04-13 12:31:45 (12179): Guest Log: VBoxService 5.2.32 r132073 (verbosity: 0) linux.amd64 (Jul 12 2019 10:32:28) release log 2025-04-13 12:31:45 (12179): Guest Log: 00:00:00.000121 main Log opened 2025-04-13T11:31:44.048579000Z 2025-04-13 12:31:45 (12179): Guest Log: 00:00:00.000182 main OS Product: Linux 2025-04-13 12:31:45 (12179): Guest Log: 00:00:00.000193 main OS Release: 3.10.0-957.27.2.el7.x86_64 2025-04-13 12:31:45 (12179): Guest Log: 00:00:00.000203 main OS Version: #1 SMP Mon Jul 29 17:46:05 UTC 2019 2025-04-13 12:31:45 (12179): Guest Log: 00:00:00.000212 main Executable: /opt/VBoxGuestAdditions-5.2.32/sbin/VBoxService 2025-04-13 12:31:45 (12179): Guest Log: 00:00:00.000212 main Process ID: 1357 2025-04-13 12:31:45 (12179): Guest Log: 00:00:00.000212 main Package type: LINUX_64BITS_GENERIC 2025-04-13 12:31:45 (12179): Guest Log: 00:00:00.000922 main 5.2.32 r132073 started. Verbose level = 0 2025-04-13 12:32:12 (12179): Guest Log: [INFO] Probing /cvmfs/atlas.cern.ch... 2025-04-13 12:32:12 (12179): Guest Log: [INFO] Mounting shared directory 2025-04-13 12:32:12 (12179): Guest Log: [INFO] Checking for init_data.xml 2025-04-13 12:33:45 (12179): Guest Log: [INFO] Probing /cvmfs/atlas.cern.ch... OK 2025-04-13 12:33:45 (12179): Guest Log: [INFO] Detected branch: prod 2025-04-13 12:33:45 (12179): Guest Log: This is the prod version of the ATLAS job wrapper 2025-04-13 12:33:45 (12179): Guest Log: Copying input files 2025-04-13 12:33:45 (12179): Guest Log: Copied input files into RunAtlas. 2025-04-13 12:33:45 (12179): Guest Log: This VM did not configure a local http proxy via BOINC. 2025-04-13 12:33:45 (12179): Guest Log: Small home clusters do not require a local http proxy but it is suggested if 2025-04-13 12:33:45 (12179): Guest Log: more than 10 cores throughout the same LAN segment are regularly running ATLAS like tasks. 2025-04-13 12:33:45 (12179): Guest Log: Further information can be found at the LHC@home message board. 2025-04-13 12:35:10 (12179): Guest Log: Running cvmfs_config stat atlas.cern.ch 2025-04-13 12:35:10 (12179): 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-13 12:35:10 (12179): Guest Log: 2.6.3.0 1683 2 30128 144810 4 1 3117322 4096000 0 65024 0 0 n/a 0 0 http://s1swinburne-cvmfs.openhtc.io:8080/cvmfs/atlas.cern.ch DIRECT 1 2025-04-13 12:35:11 (12179): Guest Log: copied the webapp to /var/www 2025-04-13 12:35:11 (12179): Guest Log: ATHENA_PROC_NUMBER=8 2025-04-13 12:35:11 (12179): Guest Log: ATHENA_CORE_NUMBER=8 2025-04-13 12:35:12 (12179): 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-13 12:35:12 (12179): Guest Log: *** Starting ATLAS job. (PandaID=6600995205 taskID=44075164) *** 2025-04-13 12:42:00 (12179): Stopping VM. 2025-04-13 12:42:04 (12179): Successfully stopped VM. 2025-04-13 17:07:31 (104199): Detected: vboxwrapper 26206 2025-04-13 17:07:31 (104199): Detected: BOINC client v8.0.2 2025-04-13 17:07:31 (104199): Detected: VirtualBox VboxManage Interface (Version: 7.1.6) 2025-04-13 17:07:31 (104199): Guest Log: BIOS: VirtualBox 7.1.6 2025-04-13 17:07:31 (104199): Guest Log: CPUID EDX: 0x178bfbff 2025-04-13 17:07:31 (104199): Guest Log: BIOS: No PCI IDE controller, not probing IDE 2025-04-13 17:07:31 (104199): Guest Log: BIOS: AHCI 0-P#0: PCHS=16383/16/63 LCHS=1024/255/63 0x0000000002800000 sectors 2025-04-13 17:07:31 (104199): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032 2025-04-13 17:07:31 (104199): Guest Log: BIOS: Booting from Hard Disk... 2025-04-13 17:07:31 (104199): Guest Log: BIOS: KBD: unsupported int 16h function 03 2025-04-13 17:07:31 (104199): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 2025-04-13 17:07:31 (104199): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=81 2025-04-13 17:07:31 (104199): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=81 2025-04-13 17:07:31 (104199): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=82 2025-04-13 17:07:31 (104199): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=82 2025-04-13 17:07:31 (104199): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=83 2025-04-13 17:07:31 (104199): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=83 2025-04-13 17:07:31 (104199): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=84 2025-04-13 17:07:31 (104199): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=84 2025-04-13 17:07:31 (104199): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=85 2025-04-13 17:07:31 (104199): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=85 2025-04-13 17:07:31 (104199): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=86 2025-04-13 17:07:31 (104199): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=86 2025-04-13 17:07:31 (104199): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=87 2025-04-13 17:07:31 (104199): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=87 2025-04-13 17:07:31 (104199): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=88 2025-04-13 17:07:31 (104199): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=88 2025-04-13 17:07:31 (104199): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=89 2025-04-13 17:07:31 (104199): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=89 2025-04-13 17:07:31 (104199): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=8a 2025-04-13 17:07:31 (104199): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=8a 2025-04-13 17:07:31 (104199): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=8b 2025-04-13 17:07:31 (104199): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=8b 2025-04-13 17:07:31 (104199): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=8c 2025-04-13 17:07:31 (104199): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=8c 2025-04-13 17:07:31 (104199): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=8d 2025-04-13 17:07:31 (104199): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=8d 2025-04-13 17:07:31 (104199): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=8e 2025-04-13 17:07:31 (104199): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=8e 2025-04-13 17:07:31 (104199): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=8f 2025-04-13 17:07:31 (104199): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=8f 2025-04-13 17:07:31 (104199): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds 2025-04-13 17:07:31 (104199): Guest Log: vboxguest: misc device minor 58, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000) 2025-04-13 17:07:31 (104199): Guest Log: VBoxService 5.2.32 r132073 (verbosity: 0) linux.amd64 (Jul 12 2019 10:32:28) release log 2025-04-13 17:07:31 (104199): Guest Log: 00:00:00.000121 main Log opened 2025-04-13T11:31:44.048579000Z 2025-04-13 17:07:31 (104199): Guest Log: 00:00:00.000182 main OS Product: Linux 2025-04-13 17:07:31 (104199): Guest Log: 00:00:00.000193 main OS Release: 3.10.0-957.27.2.el7.x86_64 2025-04-13 17:07:31 (104199): Guest Log: 00:00:00.000203 main OS Version: #1 SMP Mon Jul 29 17:46:05 UTC 2019 2025-04-13 17:07:31 (104199): Guest Log: 00:00:00.000212 main Executable: /opt/VBoxGuestAdditions-5.2.32/sbin/VBoxService 2025-04-13 17:07:31 (104199): Guest Log: 00:00:00.000212 main Process ID: 1357 2025-04-13 17:07:31 (104199): Guest Log: 00:00:00.000212 main Package type: LINUX_64BITS_GENERIC 2025-04-13 17:07:31 (104199): Guest Log: 00:00:00.000922 main 5.2.32 r132073 started. Verbose level = 0 2025-04-13 17:07:31 (104199): Guest Log: [INFO] Probing /cvmfs/atlas.cern.ch... 2025-04-13 17:07:31 (104199): Guest Log: [INFO] Mounting shared directory 2025-04-13 17:07:31 (104199): Guest Log: [INFO] Checking for init_data.xml 2025-04-13 17:07:31 (104199): Guest Log: [INFO] Probing /cvmfs/atlas.cern.ch... OK 2025-04-13 17:07:31 (104199): Guest Log: [INFO] Detected branch: prod 2025-04-13 17:07:31 (104199): Guest Log: This is the prod version of the ATLAS job wrapper 2025-04-13 17:07:31 (104199): Guest Log: Copying input files 2025-04-13 17:07:31 (104199): Guest Log: Copied input files into RunAtlas. 2025-04-13 17:07:31 (104199): Guest Log: This VM did not configure a local http proxy via BOINC. 2025-04-13 17:07:31 (104199): Guest Log: Small home clusters do not require a local http proxy but it is suggested if 2025-04-13 17:07:31 (104199): Guest Log: more than 10 cores throughout the same LAN segment are regularly running ATLAS like tasks. 2025-04-13 17:07:31 (104199): Guest Log: Further information can be found at the LHC@home message board. 2025-04-13 17:07:31 (104199): Guest Log: Running cvmfs_config stat atlas.cern.ch 2025-04-13 17:07:31 (104199): 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-13 17:07:31 (104199): Guest Log: 2.6.3.0 1683 2 30128 144810 4 1 3117322 4096000 0 65024 0 0 n/a 0 0 http://s1swinburne-cvmfs.openhtc.io:8080/cvmfs/atlas.cern.ch DIRECT 1 2025-04-13 17:07:31 (104199): Guest Log: copied the webapp to /var/www 2025-04-13 17:07:31 (104199): Guest Log: ATHENA_PROC_NUMBER=8 2025-04-13 17:07:31 (104199): Guest Log: ATHENA_CORE_NUMBER=8 2025-04-13 17:07:31 (104199): 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-13 17:07:31 (104199): Guest Log: *** Starting ATLAS job. (PandaID=6600995205 taskID=44075164) *** 2025-04-13 17:07:31 (104199): Starting VM using VBoxManage interface. (boinc_61a02799abc9d051, slot#2) 2025-04-13 17:07:34 (104199): Successfully started VM. (PID = '104348') 2025-04-13 17:07:34 (104199): Reporting VM Process ID to BOINC. 2025-04-13 17:07:34 (104199): Guest Log: 00:11:13.132201 timesync vgsvcTimeSyncWorker: Radical host time change: 15 942 398 000 000ns (HostNow=1 744 560 454 409 000 000 ns HostLast=1 744 544 512 011 000 000 ns) 2025-04-13 17:07:34 (104199): VM state change detected. (old = 'poweredoff', new = 'running') 2025-04-13 17:07:34 (104199): Detected: Web Application Enabled (http://localhost:53553) 2025-04-13 17:07:34 (104199): Preference change detected 2025-04-13 17:07:34 (104199): Setting CPU throttle for VM. (100%) 2025-04-13 17:07:34 (104199): Setting checkpoint interval to 900 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 900 seconds)) 2025-04-13 17:07:44 (104199): Guest Log: 00:11:23.140651 timesync vgsvcTimeSyncWorker: Radical guest time change: 15 887 238 281 000ns (GuestNow=1 744 560 464 417 998 000 ns GuestLast=1 744 544 577 179 717 000 ns fSetTimeLastLoop=true ) 2025-04-13 17:20:02 (104199): Stopping VM. 2025-04-13 17:20:13 (104199): Successfully stopped VM. 2025-04-13 17:29:39 (107769): Detected: vboxwrapper 26206 2025-04-13 17:29:39 (107769): Detected: BOINC client v8.0.2 2025-04-13 17:29:39 (107769): Detected: VirtualBox VboxManage Interface (Version: 7.1.6) 2025-04-13 17:29:39 (107769): Guest Log: 00:11:13.132201 timesync vgsvcTimeSyncWorker: Radical host time change: 15 942 398 000 000ns (HostNow=1 744 560 454 409 000 000 ns HostLast=1 744 544 512 011 000 000 ns) 2025-04-13 17:29:39 (107769): Guest Log: 00:11:23.140651 timesync vgsvcTimeSyncWorker: Radical guest time change: 15 887 238 281 000ns (GuestNow=1 744 560 464 417 998 000 ns GuestLast=1 744 544 577 179 717 000 ns fSetTimeLastLoop=true ) 2025-04-13 17:29:39 (107769): Starting VM using VBoxManage interface. (boinc_61a02799abc9d051, slot#2) 2025-04-13 17:29:45 (107769): Successfully started VM. (PID = '107925') 2025-04-13 17:29:45 (107769): Reporting VM Process ID to BOINC. 2025-04-13 17:29:45 (107769): VM state change detected. (old = 'poweredoff', new = 'running') 2025-04-13 17:29:45 (107769): Detected: Web Application Enabled (http://localhost:53553) 2025-04-13 17:29:45 (107769): Stopping VM. 2025-04-13 17:29:57 (107769): Successfully stopped VM. 2025-04-13 19:39:22 (127809): Detected: vboxwrapper 26206 2025-04-13 19:39:22 (127809): Detected: BOINC client v8.0.2 2025-04-13 19:39:22 (127809): Detected: VirtualBox VboxManage Interface (Version: 7.1.6) 2025-04-13 19:39:22 (127809): Starting VM using VBoxManage interface. (boinc_61a02799abc9d051, slot#2) 2025-04-13 19:39:29 (127809): Successfully started VM. (PID = '127957') 2025-04-13 19:39:29 (127809): Reporting VM Process ID to BOINC. 2025-04-13 19:39:29 (127809): VM state change detected. (old = 'poweredoff', new = 'running') 2025-04-13 19:39:29 (127809): Detected: Web Application Enabled (http://localhost:53553) 2025-04-13 19:39:29 (127809): Preference change detected 2025-04-13 19:39:29 (127809): Setting CPU throttle for VM. (100%) 2025-04-13 19:39:29 (127809): Setting checkpoint interval to 900 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 900 seconds)) 2025-04-13 19:39:32 (127809): Guest Log: 00:24:54.805043 timesync vgsvcTimeSyncWorker: Radical host time change: 8 369 195 000 000ns (HostNow=1 744 569 564 512 000 000 ns HostLast=1 744 561 195 317 000 000 ns) 2025-04-13 19:39:42 (127809): Guest Log: 00:25:04.814438 timesync vgsvcTimeSyncWorker: Radical guest time change: 8 298 440 908 000ns (GuestNow=1 744 569 574 522 510 000 ns GuestLast=1 744 561 276 081 602 000 ns fSetTimeLastLoop=true ) 2025-04-13 19:58:25 (127809): Stopping VM. 2025-04-13 19:58:36 (127809): Successfully stopped VM. 2025-04-14 16:48:34 (1977): Detected: vboxwrapper 26206 2025-04-14 16:48:34 (1977): Detected: BOINC client v8.0.2 2025-04-14 16:48:34 (1977): Detected: VirtualBox VboxManage Interface (Version: 7.1.6) 2025-04-14 16:48:34 (1977): Guest Log: 00:24:54.805043 timesync vgsvcTimeSyncWorker: Radical host time change: 8 369 195 000 000ns (HostNow=1 744 569 564 512 000 000 ns HostLast=1 744 561 195 317 000 000 ns) 2025-04-14 16:48:34 (1977): Guest Log: 00:25:04.814438 timesync vgsvcTimeSyncWorker: Radical guest time change: 8 298 440 908 000ns (GuestNow=1 744 569 574 522 510 000 ns GuestLast=1 744 561 276 081 602 000 ns fSetTimeLastLoop=true ) 2025-04-14 16:48:34 (1977): Starting VM using VBoxManage interface. (boinc_61a02799abc9d051, slot#2) 2025-04-14 16:48:40 (1977): Successfully started VM. (PID = '2124') 2025-04-14 16:48:40 (1977): Reporting VM Process ID to BOINC. 2025-04-14 16:48:40 (1977): VM state change detected. (old = 'poweredoff', new = 'running') 2025-04-14 16:48:40 (1977): Detected: Web Application Enabled (http://localhost:53553) 2025-04-14 16:48:40 (1977): Preference change detected 2025-04-14 16:48:40 (1977): Setting CPU throttle for VM. (100%) 2025-04-14 16:48:40 (1977): Setting checkpoint interval to 900 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 900 seconds)) 2025-04-14 16:48:45 (1977): Guest Log: 00:45:37.584625 timesync vgsvcTimeSyncWorker: Radical host time change: 75 021 438 000 000ns (HostNow=1 744 645 685 388 000 000 ns HostLast=1 744 570 663 950 000 000 ns) 2025-04-14 16:48:53 (1977): Guest Log: 00:45:47.585373 timesync vgsvcTimeSyncWorker: Radical guest time change: 74 888 096 328 000ns (GuestNow=1 744 645 695 388 705 000 ns GuestLast=1 744 570 807 292 377 000 ns fSetTimeLastLoop=true ) 2025-04-14 16:49:46 (1977): VM state change detected. (old = 'running', new = 'paused') 2025-04-14 18:41:07 (1977): VM state change detected. (old = 'paused', new = 'running') 2025-04-14 18:41:10 (1977): Guest Log: 00:46:47.595479 timesync vgsvcTimeSyncWorker: Radical host time change: 6 689 299 000 000ns (HostNow=1 744 652 422 394 000 000 ns HostLast=1 744 645 733 095 000 000 ns) 2025-04-14 18:41:20 (1977): Guest Log: 00:46:57.596237 timesync vgsvcTimeSyncWorker: Radical guest time change: 6 676 995 886 000ns (GuestNow=1 744 652 432 394 903 000 ns GuestLast=1 744 645 755 399 017 000 ns fSetTimeLastLoop=true ) 2025-04-14 18:55:08 (1977): Stopping VM. 2025-04-14 18:55:18 (1977): Successfully stopped VM. 2025-04-14 18:55:53 (22770): Detected: vboxwrapper 26206 2025-04-14 18:55:53 (22770): Detected: BOINC client v8.0.2 2025-04-14 18:55:53 (22770): Detected: VirtualBox VboxManage Interface (Version: 7.1.6) 2025-04-14 18:55:53 (22770): Guest Log: 00:45:37.584625 timesync vgsvcTimeSyncWorker: Radical host time change: 75 021 438 000 000ns (HostNow=1 744 645 685 388 000 000 ns HostLast=1 744 570 663 950 000 000 ns) 2025-04-14 18:55:53 (22770): Guest Log: 00:45:47.585373 timesync vgsvcTimeSyncWorker: Radical guest time change: 74 888 096 328 000ns (GuestNow=1 744 645 695 388 705 000 ns GuestLast=1 744 570 807 292 377 000 ns fSetTimeLastLoop=true ) 2025-04-14 18:55:53 (22770): Guest Log: 00:46:47.595479 timesync vgsvcTimeSyncWorker: Radical host time change: 6 689 299 000 000ns (HostNow=1 744 652 422 394 000 000 ns HostLast=1 744 645 733 095 000 000 ns) 2025-04-14 18:55:53 (22770): Guest Log: 00:46:57.596237 timesync vgsvcTimeSyncWorker: Radical guest time change: 6 676 995 886 000ns (GuestNow=1 744 652 432 394 903 000 ns GuestLast=1 744 645 755 399 017 000 ns fSetTimeLastLoop=true ) 2025-04-14 18:55:53 (22770): Starting VM using VBoxManage interface. (boinc_61a02799abc9d051, slot#2) 2025-04-14 18:55:58 (22770): Successfully started VM. (PID = '22920') 2025-04-14 18:55:58 (22770): Reporting VM Process ID to BOINC. 2025-04-14 18:55:58 (22770): VM state change detected. (old = 'poweredoff', new = 'running') 2025-04-14 18:55:58 (22770): Detected: Web Application Enabled (http://localhost:53553) 2025-04-14 18:55:58 (22770): Preference change detected 2025-04-14 18:55:58 (22770): Setting CPU throttle for VM. (100%) 2025-04-14 18:55:58 (22770): Setting checkpoint interval to 900 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 900 seconds)) 2025-04-14 18:56:08 (22770): Stopping VM. 2025-04-14 18:56:19 (22770): Successfully stopped VM. 2025-04-14 18:56:43 (23153): Detected: vboxwrapper 26206 2025-04-14 18:56:43 (23153): Detected: BOINC client v8.0.2 2025-04-14 18:56:43 (23153): Detected: VirtualBox VboxManage Interface (Version: 7.1.6) 2025-04-14 18:56:43 (23153): Starting VM using VBoxManage interface. (boinc_61a02799abc9d051, slot#2) 2025-04-14 18:56:49 (23153): Successfully started VM. (PID = '23279') 2025-04-14 18:56:49 (23153): Reporting VM Process ID to BOINC. 2025-04-14 18:56:49 (23153): VM state change detected. (old = 'poweredoff', new = 'running') 2025-04-14 18:56:49 (23153): Detected: Web Application Enabled (http://localhost:53553) 2025-04-14 18:56:49 (23153): Preference change detected 2025-04-14 18:56:49 (23153): Setting CPU throttle for VM. (100%) 2025-04-14 18:56:49 (23153): Setting checkpoint interval to 900 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 900 seconds)) 2025-04-14 18:57:09 (23153): Stopping VM. 2025-04-14 18:57:20 (23153): Successfully stopped VM. 2025-04-14 18:57:44 (23530): Detected: vboxwrapper 26206 2025-04-14 18:57:44 (23530): Detected: BOINC client v8.0.2 2025-04-14 18:57:44 (23530): Detected: VirtualBox VboxManage Interface (Version: 7.1.6) 2025-04-14 18:57:44 (23530): Starting VM using VBoxManage interface. (boinc_61a02799abc9d051, slot#2) 2025-04-14 18:57:49 (23530): Successfully started VM. (PID = '23656') 2025-04-14 18:57:49 (23530): Reporting VM Process ID to BOINC. 2025-04-14 18:57:49 (23530): VM state change detected. (old = 'poweredoff', new = 'running') 2025-04-14 18:57:49 (23530): Detected: Web Application Enabled (http://localhost:53553) 2025-04-14 18:57:49 (23530): Preference change detected 2025-04-14 18:57:49 (23530): Setting CPU throttle for VM. (100%) 2025-04-14 18:57:49 (23530): Setting checkpoint interval to 900 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 900 seconds)) 2025-04-14 18:57:59 (23530): Stopping VM. 2025-04-14 18:58:10 (23530): Successfully stopped VM. 2025-04-14 19:03:26 (25371): Detected: vboxwrapper 26206 2025-04-14 19:03:26 (25371): Detected: BOINC client v8.0.2 2025-04-14 19:03:26 (25371): Detected: VirtualBox VboxManage Interface (Version: 7.1.6) 2025-04-14 19:03:26 (25371): Starting VM using VBoxManage interface. (boinc_61a02799abc9d051, slot#2) 2025-04-14 19:03:32 (25371): Successfully started VM. (PID = '25527') 2025-04-14 19:03:32 (25371): Reporting VM Process ID to BOINC. 2025-04-14 19:03:32 (25371): VM state change detected. (old = 'poweredoff', new = 'running') 2025-04-14 19:03:32 (25371): Detected: Web Application Enabled (http://localhost:53553) 2025-04-14 19:03:32 (25371): Preference change detected 2025-04-14 19:03:32 (25371): Setting CPU throttle for VM. (100%) 2025-04-14 19:03:32 (25371): Setting checkpoint interval to 900 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 900 seconds)) 2025-04-14 19:05:52 (25371): Stopping VM. 2025-04-14 19:06:04 (25371): Successfully stopped VM. 2025-04-14 19:08:18 (26632): Detected: vboxwrapper 26206 2025-04-14 19:08:18 (26632): Detected: BOINC client v8.0.2 2025-04-14 19:08:18 (26632): Detected: VirtualBox VboxManage Interface (Version: 7.1.6) 2025-04-14 19:08:18 (26632): Starting VM using VBoxManage interface. (boinc_61a02799abc9d051, slot#2) 2025-04-14 19:08:24 (26632): Successfully started VM. (PID = '26780') 2025-04-14 19:08:24 (26632): Reporting VM Process ID to BOINC. 2025-04-14 19:08:24 (26632): VM state change detected. (old = 'poweredoff', new = 'running') 2025-04-14 19:08:24 (26632): Detected: Web Application Enabled (http://localhost:53553) 2025-04-14 19:08:24 (26632): Stopping VM. 2025-04-14 19:08:35 (26632): Successfully stopped VM. 2025-04-14 19:24:03 (30002): Detected: vboxwrapper 26206 2025-04-14 19:24:03 (30002): Detected: BOINC client v8.0.2 2025-04-14 19:24:03 (30002): Detected: VirtualBox VboxManage Interface (Version: 7.1.6) 2025-04-14 19:24:03 (30002): Starting VM using VBoxManage interface. (boinc_61a02799abc9d051, slot#2) 2025-04-14 19:24:09 (30002): Successfully started VM. (PID = '30148') 2025-04-14 19:24:09 (30002): Reporting VM Process ID to BOINC. 2025-04-14 19:24:09 (30002): VM state change detected. (old = 'poweredoff', new = 'running') 2025-04-14 19:24:09 (30002): Detected: Web Application Enabled (http://localhost:53553) 2025-04-14 19:24:09 (30002): Preference change detected 2025-04-14 19:24:09 (30002): Setting CPU throttle for VM. (100%) 2025-04-14 19:24:09 (30002): Setting checkpoint interval to 900 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 900 seconds)) 2025-04-14 19:29:10 (30002): Stopping VM. 2025-04-14 19:29:23 (30002): Successfully stopped VM. 2025-04-14 19:30:45 (32788): Detected: vboxwrapper 26206 2025-04-14 19:30:45 (32788): Detected: BOINC client v8.0.2 2025-04-14 19:30:46 (32788): Detected: VirtualBox VboxManage Interface (Version: 7.1.6) 2025-04-14 19:30:46 (32788): Starting VM using VBoxManage interface. (boinc_61a02799abc9d051, slot#2) 2025-04-14 19:30:52 (32788): Successfully started VM. (PID = '32936') 2025-04-14 19:30:52 (32788): Reporting VM Process ID to BOINC. 2025-04-14 19:30:52 (32788): VM state change detected. (old = 'poweredoff', new = 'running') 2025-04-14 19:30:52 (32788): Detected: Web Application Enabled (http://localhost:53553) 2025-04-14 19:30:52 (32788): Preference change detected 2025-04-14 19:30:52 (32788): Setting CPU throttle for VM. (100%) 2025-04-14 19:30:52 (32788): Setting checkpoint interval to 900 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 900 seconds)) 2025-04-14 19:36:20 (1894): Detected: vboxwrapper 26206 2025-04-14 19:36:20 (1894): Detected: BOINC client v8.0.2 2025-04-14 19:36:21 (1894): Detected: VirtualBox VboxManage Interface (Version: 7.1.6) 2025-04-14 19:36:21 (1894): Starting VM using VBoxManage interface. (boinc_61a02799abc9d051, slot#2) 2025-04-14 19:36:22 (1894): Successfully started VM. (PID = '2036') 2025-04-14 19:36:22 (1894): Reporting VM Process ID to BOINC. 2025-04-14 19:36:22 (1894): Guest Log: BIOS: VirtualBox 7.1.6 2025-04-14 19:36:22 (1894): Guest Log: CPUID EDX: 0x178bfbff 2025-04-14 19:36:22 (1894): Guest Log: BIOS: No PCI IDE controller, not probing IDE 2025-04-14 19:36:22 (1894): Guest Log: BIOS: AHCI 0-P#0: PCHS=16383/16/63 LCHS=1024/255/63 0x0000000002800000 sectors 2025-04-14 19:36:22 (1894): VM state change detected. (old = 'poweredoff', new = 'running') 2025-04-14 19:36:22 (1894): Detected: Web Application Enabled (http://localhost:53553) 2025-04-14 19:36:22 (1894): Preference change detected 2025-04-14 19:36:22 (1894): Setting CPU throttle for VM. (100%) 2025-04-14 19:36:22 (1894): Setting checkpoint interval to 900 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 900 seconds)) 2025-04-14 19:36:24 (1894): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032 2025-04-14 19:36:24 (1894): Guest Log: BIOS: Booting from Hard Disk... 2025-04-14 19:36:27 (1894): Guest Log: BIOS: KBD: unsupported int 16h function 03 2025-04-14 19:36:27 (1894): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 2025-04-14 19:36:27 (1894): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=81 2025-04-14 19:36:27 (1894): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=81 2025-04-14 19:36:27 (1894): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=82 2025-04-14 19:36:27 (1894): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=82 2025-04-14 19:36:27 (1894): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=83 2025-04-14 19:36:27 (1894): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=83 2025-04-14 19:36:27 (1894): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=84 2025-04-14 19:36:27 (1894): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=84 2025-04-14 19:36:27 (1894): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=85 2025-04-14 19:36:27 (1894): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=85 2025-04-14 19:36:27 (1894): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=86 2025-04-14 19:36:27 (1894): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=86 2025-04-14 19:36:27 (1894): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=87 2025-04-14 19:36:27 (1894): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=87 2025-04-14 19:36:27 (1894): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=88 2025-04-14 19:36:27 (1894): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=88 2025-04-14 19:36:27 (1894): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=89 2025-04-14 19:36:27 (1894): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=89 2025-04-14 19:36:27 (1894): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=8a 2025-04-14 19:36:27 (1894): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=8a 2025-04-14 19:36:27 (1894): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=8b 2025-04-14 19:36:27 (1894): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=8b 2025-04-14 19:36:27 (1894): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=8c 2025-04-14 19:36:27 (1894): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=8c 2025-04-14 19:36:27 (1894): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=8d 2025-04-14 19:36:27 (1894): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=8d 2025-04-14 19:36:27 (1894): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=8e 2025-04-14 19:36:27 (1894): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=8e 2025-04-14 19:36:27 (1894): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=8f 2025-04-14 19:36:27 (1894): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=8f 2025-04-14 19:36:34 (1894): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds 2025-04-14 19:36:34 (1894): Guest Log: vboxguest: misc device minor 58, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000) 2025-04-14 19:36:41 (1894): Guest Log: VBoxService 5.2.32 r132073 (verbosity: 0) linux.amd64 (Jul 12 2019 10:32:28) release log 2025-04-14 19:36:41 (1894): Guest Log: 00:00:00.000168 main Log opened 2025-04-14T18:36:39.811376000Z 2025-04-14 19:36:41 (1894): Guest Log: 00:00:00.000238 main OS Product: Linux 2025-04-14 19:36:41 (1894): Guest Log: 00:00:00.000256 main OS Release: 3.10.0-957.27.2.el7.x86_64 2025-04-14 19:36:41 (1894): Guest Log: 00:00:00.000267 main OS Version: #1 SMP Mon Jul 29 17:46:05 UTC 2019 2025-04-14 19:36:41 (1894): Guest Log: 00:00:00.000279 main Executable: /opt/VBoxGuestAdditions-5.2.32/sbin/VBoxService 2025-04-14 19:36:41 (1894): Guest Log: 00:00:00.000280 main Process ID: 1361 2025-04-14 19:36:41 (1894): Guest Log: 00:00:00.000280 main Package type: LINUX_64BITS_GENERIC 2025-04-14 19:36:41 (1894): Guest Log: 00:00:00.002001 main 5.2.32 r132073 started. Verbose level = 0 2025-04-14 19:37:04 (1894): Guest Log: [INFO] Probing /cvmfs/atlas.cern.ch... 2025-04-14 19:37:04 (1894): Guest Log: [INFO] Mounting shared directory 2025-04-14 19:37:04 (1894): Guest Log: [INFO] Checking for init_data.xml 2025-04-14 19:37:11 (1894): Stopping VM. 2025-04-14 19:37:13 (1894): Successfully stopped VM. 2025-04-14 19:47:49 (6982): Detected: vboxwrapper 26206 2025-04-14 19:47:49 (6982): Detected: BOINC client v8.0.2 2025-04-14 19:47:49 (6982): Detected: VirtualBox VboxManage Interface (Version: 7.1.6) 2025-04-14 19:47:49 (6982): Guest Log: BIOS: VirtualBox 7.1.6 2025-04-14 19:47:49 (6982): Guest Log: CPUID EDX: 0x178bfbff 2025-04-14 19:47:49 (6982): Guest Log: BIOS: No PCI IDE controller, not probing IDE 2025-04-14 19:47:49 (6982): Guest Log: BIOS: AHCI 0-P#0: PCHS=16383/16/63 LCHS=1024/255/63 0x0000000002800000 sectors 2025-04-14 19:47:49 (6982): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032 2025-04-14 19:47:49 (6982): Guest Log: BIOS: Booting from Hard Disk... 2025-04-14 19:47:49 (6982): Guest Log: BIOS: KBD: unsupported int 16h function 03 2025-04-14 19:47:49 (6982): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 2025-04-14 19:47:49 (6982): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=81 2025-04-14 19:47:49 (6982): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=81 2025-04-14 19:47:49 (6982): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=82 2025-04-14 19:47:49 (6982): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=82 2025-04-14 19:47:49 (6982): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=83 2025-04-14 19:47:49 (6982): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=83 2025-04-14 19:47:49 (6982): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=84 2025-04-14 19:47:49 (6982): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=84 2025-04-14 19:47:49 (6982): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=85 2025-04-14 19:47:49 (6982): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=85 2025-04-14 19:47:49 (6982): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=86 2025-04-14 19:47:49 (6982): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=86 2025-04-14 19:47:49 (6982): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=87 2025-04-14 19:47:49 (6982): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=87 2025-04-14 19:47:49 (6982): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=88 2025-04-14 19:47:49 (6982): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=88 2025-04-14 19:47:49 (6982): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=89 2025-04-14 19:47:49 (6982): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=89 2025-04-14 19:47:49 (6982): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=8a 2025-04-14 19:47:49 (6982): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=8a 2025-04-14 19:47:49 (6982): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=8b 2025-04-14 19:47:49 (6982): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=8b 2025-04-14 19:47:49 (6982): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=8c 2025-04-14 19:47:49 (6982): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=8c 2025-04-14 19:47:49 (6982): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=8d 2025-04-14 19:47:49 (6982): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=8d 2025-04-14 19:47:49 (6982): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=8e 2025-04-14 19:47:49 (6982): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=8e 2025-04-14 19:47:49 (6982): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=8f 2025-04-14 19:47:49 (6982): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=8f 2025-04-14 19:47:49 (6982): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds 2025-04-14 19:47:49 (6982): Guest Log: vboxguest: misc device minor 58, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000) 2025-04-14 19:47:49 (6982): Guest Log: VBoxService 5.2.32 r132073 (verbosity: 0) linux.amd64 (Jul 12 2019 10:32:28) release log 2025-04-14 19:47:49 (6982): Guest Log: 00:00:00.000168 main Log opened 2025-04-14T18:36:39.811376000Z 2025-04-14 19:47:49 (6982): Guest Log: 00:00:00.000238 main OS Product: Linux 2025-04-14 19:47:49 (6982): Guest Log: 00:00:00.000256 main OS Release: 3.10.0-957.27.2.el7.x86_64 2025-04-14 19:47:49 (6982): Guest Log: 00:00:00.000267 main OS Version: #1 SMP Mon Jul 29 17:46:05 UTC 2019 2025-04-14 19:47:49 (6982): Guest Log: 00:00:00.000279 main Executable: /opt/VBoxGuestAdditions-5.2.32/sbin/VBoxService 2025-04-14 19:47:49 (6982): Guest Log: 00:00:00.000280 main Process ID: 1361 2025-04-14 19:47:49 (6982): Guest Log: 00:00:00.000280 main Package type: LINUX_64BITS_GENERIC 2025-04-14 19:47:49 (6982): Guest Log: 00:00:00.002001 main 5.2.32 r132073 started. Verbose level = 0 2025-04-14 19:47:49 (6982): Guest Log: [INFO] Probing /cvmfs/atlas.cern.ch... 2025-04-14 19:47:49 (6982): Guest Log: [INFO] Mounting shared directory 2025-04-14 19:47:49 (6982): Guest Log: [INFO] Checking for init_data.xml 2025-04-14 19:47:49 (6982): Starting VM using VBoxManage interface. (boinc_61a02799abc9d051, slot#2) 2025-04-14 19:47:51 (6982): Successfully started VM. (PID = '7124') 2025-04-14 19:47:51 (6982): Reporting VM Process ID to BOINC. 2025-04-14 19:47:51 (6982): VM state change detected. (old = 'poweredoff', new = 'running') 2025-04-14 19:47:51 (6982): Detected: Web Application Enabled (http://localhost:53553) 2025-04-14 19:47:51 (6982): Preference change detected 2025-04-14 19:47:51 (6982): Setting CPU throttle for VM. (100%) 2025-04-14 19:47:51 (6982): Setting checkpoint interval to 900 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 900 seconds)) 2025-04-14 19:49:12 (6982): Guest Log: [INFO] Probing /cvmfs/atlas.cern.ch... OK 2025-04-14 19:49:12 (6982): Guest Log: [INFO] Detected branch: prod 2025-04-14 19:49:12 (6982): Guest Log: This is the prod version of the ATLAS job wrapper 2025-04-14 19:49:12 (6982): Guest Log: Copying input files 2025-04-14 19:49:13 (6982): Guest Log: Copied input files into RunAtlas. 2025-04-14 19:49:13 (6982): Guest Log: This VM did not configure a local http proxy via BOINC. 2025-04-14 19:49:13 (6982): Guest Log: Small home clusters do not require a local http proxy but it is suggested if 2025-04-14 19:49:13 (6982): Guest Log: more than 10 cores throughout the same LAN segment are regularly running ATLAS like tasks. 2025-04-14 19:49:13 (6982): Guest Log: Further information can be found at the LHC@home message board. 2025-04-14 19:50:49 (6982): Guest Log: Running cvmfs_config stat atlas.cern.ch 2025-04-14 19:50:49 (6982): 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-14 19:50:49 (6982): Guest Log: 2.6.3.0 1798 2 32028 144860 3 1 2233104 4096001 0 65024 0 0 n/a 1 0 http://s1ral-cvmfs.openhtc.io/cvmfs/atlas.cern.ch DIRECT 1 2025-04-14 19:50:49 (6982): Guest Log: copied the webapp to /var/www 2025-04-14 19:50:49 (6982): Guest Log: ATHENA_PROC_NUMBER=8 2025-04-14 19:50:49 (6982): Guest Log: ATHENA_CORE_NUMBER=8 2025-04-14 19:50:49 (6982): 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-14 19:50:49 (6982): Guest Log: *** Starting ATLAS job. (PandaID=6600995205 taskID=44075164) *** 2025-04-14 20:21:02 (6982): Status Report: Elapsed Time: '6000.000000' 2025-04-14 20:21:02 (6982): Status Report: CPU Time: '24380.790000' 2025-04-14 20:32:52 (6982): Stopping VM. 2025-04-14 20:33:04 (6982): Successfully stopped VM. 2025-04-14 20:35:57 (14892): Detected: vboxwrapper 26206 2025-04-14 20:35:57 (14892): Detected: BOINC client v8.0.2 2025-04-14 20:35:57 (14892): Detected: VirtualBox VboxManage Interface (Version: 7.1.6) 2025-04-14 20:35:58 (14892): Guest Log: [INFO] Probing /cvmfs/atlas.cern.ch... OK 2025-04-14 20:35:58 (14892): Guest Log: [INFO] Detected branch: prod 2025-04-14 20:35:58 (14892): Guest Log: This is the prod version of the ATLAS job wrapper 2025-04-14 20:35:58 (14892): Guest Log: Copying input files 2025-04-14 20:35:58 (14892): Guest Log: Copied input files into RunAtlas. 2025-04-14 20:35:58 (14892): Guest Log: This VM did not configure a local http proxy via BOINC. 2025-04-14 20:35:58 (14892): Guest Log: Small home clusters do not require a local http proxy but it is suggested if 2025-04-14 20:35:58 (14892): Guest Log: more than 10 cores throughout the same LAN segment are regularly running ATLAS like tasks. 2025-04-14 20:35:58 (14892): Guest Log: Further information can be found at the LHC@home message board. 2025-04-14 20:35:58 (14892): Guest Log: Running cvmfs_config stat atlas.cern.ch 2025-04-14 20:35:58 (14892): 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-14 20:35:58 (14892): Guest Log: 2.6.3.0 1798 2 32028 144860 3 1 2233104 4096001 0 65024 0 0 n/a 1 0 http://s1ral-cvmfs.openhtc.io/cvmfs/atlas.cern.ch DIRECT 1 2025-04-14 20:35:58 (14892): Guest Log: copied the webapp to /var/www 2025-04-14 20:35:58 (14892): Guest Log: ATHENA_PROC_NUMBER=8 2025-04-14 20:35:58 (14892): Guest Log: ATHENA_CORE_NUMBER=8 2025-04-14 20:35:58 (14892): 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-14 20:35:58 (14892): Guest Log: *** Starting ATLAS job. (PandaID=6600995205 taskID=44075164) *** 2025-04-14 20:35:58 (14892): Starting VM using VBoxManage interface. (boinc_61a02799abc9d051, slot#2) 2025-04-14 20:36:03 (14892): Successfully started VM. (PID = '15032') 2025-04-14 20:36:03 (14892): Reporting VM Process ID to BOINC. 2025-04-14 20:36:03 (14892): VM state change detected. (old = 'poweredoff', new = 'running') 2025-04-14 20:36:03 (14892): Detected: Web Application Enabled (http://localhost:53553) 2025-04-14 20:36:03 (14892): Status Report: Elapsed Time: '6716.000000' 2025-04-14 20:36:03 (14892): Status Report: CPU Time: '29143.190000' 2025-04-14 20:36:03 (14892): Preference change detected 2025-04-14 20:36:03 (14892): Setting CPU throttle for VM. (100%) 2025-04-14 20:36:03 (14892): Setting checkpoint interval to 900 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 900 seconds)) 2025-04-14 20:42:06 (14892): Stopping VM. 2025-04-14 20:42:17 (14892): Successfully stopped VM. 2025-04-14 21:04:17 (20120): Detected: vboxwrapper 26206 2025-04-14 21:04:17 (20120): Detected: BOINC client v8.0.2 2025-04-14 21:04:17 (20120): Detected: VirtualBox VboxManage Interface (Version: 7.1.6) 2025-04-14 21:04:17 (20120): Starting VM using VBoxManage interface. (boinc_61a02799abc9d051, slot#2) 2025-04-14 21:04:24 (20120): Successfully started VM. (PID = '20259') 2025-04-14 21:04:24 (20120): Reporting VM Process ID to BOINC. 2025-04-14 21:04:24 (20120): VM state change detected. (old = 'poweredoff', new = 'running') 2025-04-14 21:04:24 (20120): Detected: Web Application Enabled (http://localhost:53553) 2025-04-14 21:04:24 (20120): Status Report: Elapsed Time: '7082.000000' 2025-04-14 21:04:24 (20120): Status Report: CPU Time: '31722.440000' 2025-04-14 21:04:24 (20120): Preference change detected 2025-04-14 21:04:24 (20120): Setting CPU throttle for VM. (100%) 2025-04-14 21:04:24 (20120): Setting checkpoint interval to 900 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 900 seconds)) 2025-04-14 21:07:04 (20120): Stopping VM. 2025-04-14 21:07:15 (20120): Successfully stopped VM. 2025-04-14 21:07:38 (21330): Detected: vboxwrapper 26206 2025-04-14 21:07:38 (21330): Detected: BOINC client v8.0.2 2025-04-14 21:07:39 (21330): Detected: VirtualBox VboxManage Interface (Version: 7.1.6) 2025-04-14 21:07:39 (21330): Starting VM using VBoxManage interface. (boinc_61a02799abc9d051, slot#2) 2025-04-14 21:07:44 (21330): Successfully started VM. (PID = '21470') 2025-04-14 21:07:44 (21330): Reporting VM Process ID to BOINC. 2025-04-14 21:07:44 (21330): VM state change detected. (old = 'poweredoff', new = 'running') 2025-04-14 21:07:44 (21330): Detected: Web Application Enabled (http://localhost:53553) 2025-04-14 21:07:44 (21330): Status Report: Elapsed Time: '7244.000000' 2025-04-14 21:07:44 (21330): Status Report: CPU Time: '32876.660000' 2025-04-14 21:07:44 (21330): Preference change detected 2025-04-14 21:07:44 (21330): Setting CPU throttle for VM. (100%) 2025-04-14 21:07:44 (21330): Setting checkpoint interval to 900 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 900 seconds)) 2025-04-14 21:15:07 (21330): Stopping VM. 2025-04-14 21:15:18 (21330): Successfully stopped VM. 2025-04-14 21:33:17 (27197): Detected: vboxwrapper 26206 2025-04-14 21:33:17 (27197): Detected: BOINC client v8.0.2 2025-04-14 21:33:17 (27197): Detected: VirtualBox VboxManage Interface (Version: 7.1.6) 2025-04-14 21:33:17 (27197): Starting VM using VBoxManage interface. (boinc_61a02799abc9d051, slot#2) 2025-04-14 21:33:23 (27197): Successfully started VM. (PID = '27340') 2025-04-14 21:33:23 (27197): Reporting VM Process ID to BOINC. 2025-04-14 21:33:23 (27197): VM state change detected. (old = 'poweredoff', new = 'running') 2025-04-14 21:33:23 (27197): Detected: Web Application Enabled (http://localhost:53553) 2025-04-14 21:33:23 (27197): Stopping VM. 2025-04-14 21:33:35 (27197): Successfully stopped VM. 2025-04-14 21:46:32 (32084): Detected: vboxwrapper 26206 2025-04-14 21:46:32 (32084): Detected: BOINC client v8.0.2 2025-04-14 21:46:32 (32084): Detected: VirtualBox VboxManage Interface (Version: 7.1.6) 2025-04-14 21:46:32 (32084): Starting VM using VBoxManage interface. (boinc_61a02799abc9d051, slot#2) 2025-04-14 21:46:38 (32084): Successfully started VM. (PID = '32227') 2025-04-14 21:46:38 (32084): Reporting VM Process ID to BOINC. 2025-04-14 21:46:38 (32084): VM state change detected. (old = 'poweredoff', new = 'running') 2025-04-14 21:46:38 (32084): Detected: Web Application Enabled (http://localhost:53553) 2025-04-14 21:46:38 (32084): Status Report: Elapsed Time: '7691.000000' 2025-04-14 21:46:38 (32084): Status Report: CPU Time: '35849.950000' 2025-04-14 21:46:38 (32084): Preference change detected 2025-04-14 21:46:38 (32084): Setting CPU throttle for VM. (100%) 2025-04-14 21:46:38 (32084): Setting checkpoint interval to 900 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 900 seconds)) 2025-04-14 21:46:45 (32084): Guest Log: 01:07:31.445859 timesync vgsvcTimeSyncWorker: Radical host time change: 1 895 521 000 000ns (HostNow=1 744 663 568 115 000 000 ns HostLast=1 744 661 672 594 000 000 ns) 2025-04-14 21:46:54 (32084): Guest Log: 01:07:41.450332 timesync vgsvcTimeSyncWorker: Radical guest time change: 1 856 105 969 000ns (GuestNow=1 744 663 578 123 535 000 ns GuestLast=1 744 661 722 017 566 000 ns fSetTimeLastLoop=true ) 2025-04-14 21:50:49 (32084): Guest Log: *** Job finished *** 2025-04-14 21:50:49 (32084): Guest Log: *** The last 20 lines of the pilot log: *** 2025-04-14 21:50:49 (32084): Guest Log: 2025-04-14 20:50:24,773 | INFO | [data] copytool_out thread has finished 2025-04-14 21:50:49 (32084): Guest Log: 2025-04-14 20:50:24,782 | INFO | [payload] failed_post thread has finished 2025-04-14 21:50:49 (32084): Guest Log: 2025-04-14 20:50:26,958 | INFO | [data] queue_monitor thread has finished 2025-04-14 21:50:49 (32084): Guest Log: 2025-04-14 20:50:28,149 | INFO | only monitor.control thread still running - safe to abort: ['<_MainThread(MainThread, started 140077492275008)>', '<ExcThread(monitor, started 140076935804672)>'] 2025-04-14 21:50:49 (32084): Guest Log: 2025-04-14 20:50:28,635 | WARNING | job_aborted has been set - aborting pilot monitoring 2025-04-14 21:50:49 (32084): Guest Log: 2025-04-14 20:50:28,635 | INFO | [monitor] control thread has ended 2025-04-14 21:50:49 (32084): Guest Log: 2025-04-14 20:50:33,191 | INFO | all workflow threads have been joined 2025-04-14 21:50:49 (32084): Guest Log: 2025-04-14 20:50:33,191 | INFO | end of generic workflow (traces error code: 0) 2025-04-14 21:50:49 (32084): Guest Log: 2025-04-14 20:50:33,191 | INFO | traces error code: 0 2025-04-14 21:50:49 (32084): Guest Log: 2025-04-14 20:50:33,191 | INFO | pilot has finished (exit code=0, shell exit code=0) 2025-04-14 21:50:49 (32084): Guest Log: 2025-04-14 20:50:33,302 [wrapper] ==== pilot stdout END ==== 2025-04-14 21:50:49 (32084): Guest Log: 2025-04-14 20:50:33,330 [wrapper] ==== wrapper stdout RESUME ==== 2025-04-14 21:50:49 (32084): Guest Log: 2025-04-14 20:50:33,335 [wrapper] pilotpid: 6235 2025-04-14 21:50:49 (32084): Guest Log: 2025-04-14 20:50:33,348 [wrapper] Pilot exit status: 0 2025-04-14 21:50:49 (32084): Guest Log: 2025-04-14 20:50:33,460 [wrapper] pandaids: 6600995205 2025-04-14 21:50:49 (32084): Guest Log: 2025-04-14 20:50:33,512 [wrapper] cleanup supervisor_pilot 2208 6236 2025-04-14 21:50:49 (32084): Guest Log: 2025-04-14 20:50:33,520 [wrapper] Test setup, not cleaning 2025-04-14 21:50:49 (32084): Guest Log: 2025-04-14 20:50:33,528 [wrapper] apfmon messages muted 2025-04-14 21:50:49 (32084): Guest Log: 2025-04-14 20:50:33,534 [wrapper] ==== wrapper stdout END ==== 2025-04-14 21:50:49 (32084): Guest Log: 2025-04-14 20:50:33,546 [wrapper] ==== wrapper stderr END ==== 2025-04-14 21:50:49 (32084): Guest Log: *** Error codes and diagnostics *** 2025-04-14 21:50:49 (32084): Guest Log: "exeErrorCode": 0, 2025-04-14 21:50:49 (32084): Guest Log: "exeErrorDiag": "", 2025-04-14 21:50:49 (32084): Guest Log: "pilotErrorCode": 0, 2025-04-14 21:50:49 (32084): Guest Log: "pilotErrorDiag": "", 2025-04-14 21:50:49 (32084): Guest Log: *** Listing of results directory *** 2025-04-14 21:50:49 (32084): Guest Log: total 190224 2025-04-14 21:50:49 (32084): Guest Log: -rw-r--r--. 1 atlas atlas 495897 Apr 13 08:16 pilot3.tar.gz 2025-04-14 21:50:49 (32084): Guest Log: -rwx------. 1 atlas atlas 37140 Apr 13 08:17 runpilot2-wrapper.sh 2025-04-14 21:50:49 (32084): Guest Log: -rw-r--r--. 1 atlas atlas 5111 Apr 13 08:17 queuedata.json 2025-04-14 21:50:49 (32084): Guest Log: -rw-r--r--. 1 atlas atlas 17569 Apr 14 18:49 start_atlas.sh 2025-04-14 21:50:49 (32084): Guest Log: -rw-r--r--. 1 atlas atlas 508875 Apr 14 18:49 input.tar.gz 2025-04-14 21:50:49 (32084): Guest Log: -rw-r--r--. 1 atlas atlas 6948 Apr 14 18:49 init_data.xml 2025-04-14 21:50:49 (32084): Guest Log: lrwxrwxrwx. 1 atlas atlas 20 Apr 14 18:49 EVNT.44075162._002124.pool.root.1 -> /data/./ATLAS.root_0 2025-04-14 21:50:49 (32084): Guest Log: -rw-r--r--. 1 atlas atlas 2550 Apr 14 18:51 pandaJob.out 2025-04-14 21:50:49 (32084): Guest Log: -rw-------. 1 atlas atlas 424 Apr 14 18:51 setup.sh.local 2025-04-14 21:50:49 (32084): Guest Log: -rw-------. 1 atlas atlas 986091 Apr 14 18:55 agis_schedconf.cvmfs.json 2025-04-14 21:50:49 (32084): Guest Log: -rw-------. 1 atlas atlas 1603529 Apr 14 18:55 agis_ddmendpoints.agis.ALL.json 2025-04-14 21:50:49 (32084): Guest Log: drwx------. 4 atlas atlas 4096 Apr 14 18:55 pilot3 2025-04-14 21:50:49 (32084): Guest Log: -rw-------. 1 atlas atlas 189831286 Apr 14 20:15 HITS.44075164._055072.pool.root.1 2025-04-14 21:50:49 (32084): Guest Log: -rw-------. 1 atlas atlas 1021 Apr 14 20:15 memory_monitor_summary.json 2025-04-14 21:50:49 (32084): Guest Log: -rw-------. 1 atlas atlas 281216 Apr 14 20:49 log.44075164._055072.job.log.tgz.1 2025-04-14 21:50:49 (32084): Guest Log: -rw-------. 1 atlas atlas 97 Apr 14 20:50 pilot_heartbeat.json 2025-04-14 21:50:49 (32084): Guest Log: -rw-------. 1 atlas atlas 7676 Apr 14 20:50 heartbeat.json 2025-04-14 21:50:49 (32084): Guest Log: -rw-------. 1 atlas atlas 4722 Apr 14 20:50 pilotlog.txt 2025-04-14 21:50:49 (32084): Guest Log: -rw-------. 1 atlas atlas 311616 Apr 14 20:50 log.44075164._055072.job.log.1 2025-04-14 21:50:49 (32084): Guest Log: -rw-------. 1 atlas atlas 357 Apr 14 20:50 output.list 2025-04-14 21:50:49 (32084): Guest Log: -rw-r--r--. 1 atlas atlas 620 Apr 14 20:50 runtime_log 2025-04-14 21:50:49 (32084): Guest Log: -rw-------. 1 atlas atlas 614400 Apr 14 20:50 result.tar.gz 2025-04-14 21:50:49 (32084): Guest Log: -rw-r--r--. 1 atlas atlas 10949 Apr 14 20:50 runtime_log.err 2025-04-14 21:50:49 (32084): Guest Log: -rw-------. 1 atlas atlas 658 Apr 14 20:50 IYEMDmbh7N7nsSi4ap6QjLDmwznN0nGgGQJmkKkKDmoRTLDmaDXdTm.diag 2025-04-14 21:50:49 (32084): Guest Log: Looking for outputfile HITS.44075164._055072.pool.root.1 2025-04-14 21:50:49 (32084): Guest Log: HITS file was successfully produced 2025-04-14 21:50:49 (32084): Guest Log: -rw-------. 1 atlas atlas 189831286 Apr 14 20:15 /home/atlas/RunAtlas/HITS.44075164._055072.pool.root.1 2025-04-14 21:50:49 (32084): Guest Log: Successfully finished the ATLAS job! 2025-04-14 21:50:49 (32084): Guest Log: Copying the results back to the shared directory! 2025-04-14 21:50:49 (32084): Guest Log: *** Contents of shared directory: *** 2025-04-14 21:50:49 (32084): Guest Log: total 216572 2025-04-14 21:50:49 (32084): Guest Log: -rw-r--r--. 1 root root 220612504 Apr 13 11:31 ATLAS.root_0 2025-04-14 21:50:49 (32084): Guest Log: -rw-r--r--. 1 root root 6948 Apr 13 11:31 init_data.xml 2025-04-14 21:50:49 (32084): Guest Log: -rw-r--r--. 1 root root 508875 Apr 13 11:31 input.tar.gz 2025-04-14 21:50:49 (32084): Guest Log: -rw-r--r--. 1 root root 614400 Apr 14 2025 result.tar.gz 2025-04-14 21:50:49 (32084): Guest Log: -rw-r--r--. 1 root root 17569 Apr 13 11:31 start_atlas.sh 2025-04-14 21:50:49 (32084): Guest Log: *** Success! Shutting down the machine. *** 2025-04-14 21:50:49 (32084): Stopping VM. 2025-04-14 21:51:01 (32084): Successfully stopped VM. 2025-04-14 22:06:08 (36574): Detected: vboxwrapper 26206 2025-04-14 22:06:08 (36574): Detected: BOINC client v8.0.2 2025-04-14 22:06:08 (36574): Detected: VirtualBox VboxManage Interface (Version: 7.1.6) 2025-04-14 22:06:08 (36574): Guest Log: 01:07:31.445859 timesync vgsvcTimeSyncWorker: Radical host time change: 1 895 521 000 000ns (HostNow=1 744 663 568 115 000 000 ns HostLast=1 744 661 672 594 000 000 ns) 2025-04-14 22:06:08 (36574): Guest Log: 01:07:41.450332 timesync vgsvcTimeSyncWorker: Radical guest time change: 1 856 105 969 000ns (GuestNow=1 744 663 578 123 535 000 ns GuestLast=1 744 661 722 017 566 000 ns fSetTimeLastLoop=true ) 2025-04-14 22:06:08 (36574): Guest Log: *** Job finished *** 2025-04-14 22:06:08 (36574): Guest Log: *** The last 20 lines of the pilot log: *** 2025-04-14 22:06:08 (36574): Guest Log: 2025-04-14 20:50:24,773 | INFO | [data] copytool_out thread has finished 2025-04-14 22:06:08 (36574): Guest Log: 2025-04-14 20:50:24,782 | INFO | [payload] failed_post thread has finished 2025-04-14 22:06:08 (36574): Guest Log: 2025-04-14 20:50:26,958 | INFO | [data] queue_monitor thread has finished 2025-04-14 22:06:08 (36574): Guest Log: 2025-04-14 20:50:28,149 | INFO | only monitor.control thread still running - safe to abort: ['<_MainThread(MainThread, started 140077492275008)>', '<ExcThread(monitor, started 140076935804672)>'] 2025-04-14 22:06:08 (36574): Guest Log: 2025-04-14 20:50:28,635 | WARNING | job_aborted has been set - aborting pilot monitoring 2025-04-14 22:06:08 (36574): Guest Log: 2025-04-14 20:50:28,635 | INFO | [monitor] control thread has ended 2025-04-14 22:06:08 (36574): Guest Log: 2025-04-14 20:50:33,191 | INFO | all workflow threads have been joined 2025-04-14 22:06:08 (36574): Guest Log: 2025-04-14 20:50:33,191 | INFO | end of generic workflow (traces error code: 0) 2025-04-14 22:06:08 (36574): Guest Log: 2025-04-14 20:50:33,191 | INFO | traces error code: 0 2025-04-14 22:06:08 (36574): Guest Log: 2025-04-14 20:50:33,191 | INFO | pilot has finished (exit code=0, shell exit code=0) 2025-04-14 22:06:08 (36574): Guest Log: 2025-04-14 20:50:33,348 [wrapper] Pilot exit status: 0 2025-04-14 22:06:08 (36574): Guest Log: 2025-04-14 20:50:33,460 [wrapper] pandaids: 6600995205 2025-04-14 22:06:08 (36574): Guest Log: 2025-04-14 20:50:33,512 [wrapper] cleanup supervisor_pilot 2208 6236 2025-04-14 22:06:08 (36574): Guest Log: 2025-04-14 20:50:33,520 [wrapper] Test setup, not cleaning 2025-04-14 22:06:08 (36574): Guest Log: 2025-04-14 20:50:33,528 [wrapper] apfmon messages muted 2025-04-14 22:06:08 (36574): Guest Log: 2025-04-14 20:50:33,534 [wrapper] ==== wrapper stdout END ==== 2025-04-14 22:06:08 (36574): Guest Log: 2025-04-14 20:50:33,546 [wrapper] ==== wrapper stderr END ==== 2025-04-14 22:06:08 (36574): Guest Log: *** Error codes and diagnostics *** 2025-04-14 22:06:08 (36574): Guest Log: "exeErrorCode": 0, 2025-04-14 22:06:08 (36574): Guest Log: "exeErrorDiag": "", 2025-04-14 22:06:08 (36574): Guest Log: "pilotErrorCode": 0, 2025-04-14 22:06:08 (36574): Guest Log: "pilotErrorDiag": "", 2025-04-14 22:06:08 (36574): Guest Log: *** Listing of results directory *** 2025-04-14 22:06:08 (36574): Guest Log: total 190224 2025-04-14 22:06:08 (36574): Guest Log: -rw-r--r--. 1 atlas atlas 495897 Apr 13 08:16 pilot3.tar.gz 2025-04-14 22:06:08 (36574): Guest Log: -rwx------. 1 atlas atlas 37140 Apr 13 08:17 runpilot2-wrapper.sh 2025-04-14 22:06:08 (36574): Guest Log: -rw-r--r--. 1 atlas atlas 5111 Apr 13 08:17 queuedata.json 2025-04-14 22:06:08 (36574): Guest Log: -rw-r--r--. 1 atlas atlas 17569 Apr 14 18:49 start_atlas.sh 2025-04-14 22:06:08 (36574): Guest Log: -rw-r--r--. 1 atlas atlas 508875 Apr 14 18:49 input.tar.gz 2025-04-14 22:06:08 (36574): Guest Log: -rw-r--r--. 1 atlas atlas 6948 Apr 14 18:49 init_data.xml 2025-04-14 22:06:08 (36574): Guest Log: lrwxrwxrwx. 1 atlas atlas 20 Apr 14 18:49 EVNT.44075162._002124.pool.root.1 -> /data/./ATLAS.root_0 2025-04-14 22:06:08 (36574): Guest Log: -rw-r--r--. 1 atlas atlas 2550 Apr 14 18:51 pandaJob.out 2025-04-14 22:06:08 (36574): Guest Log: -rw-------. 1 atlas atlas 424 Apr 14 18:51 setup.sh.local 2025-04-14 22:06:08 (36574): Guest Log: -rw-------. 1 atlas atlas 986091 Apr 14 18:55 agis_schedconf.cvmfs.json 2025-04-14 22:06:08 (36574): Guest Log: -rw-------. 1 atlas atlas 1603529 Apr 14 18:55 agis_ddmendpoints.agis.ALL.json 2025-04-14 22:06:08 (36574): Guest Log: drwx------. 4 atlas atlas 4096 Apr 14 18:55 pilot3 2025-04-14 22:06:08 (36574): Guest Log: -rw-------. 1 atlas atlas 189831286 Apr 14 20:15 HITS.44075164._055072.pool.root.1 2025-04-14 22:06:08 (36574): Guest Log: -rw-------. 1 atlas atlas 1021 Apr 14 20:15 memory_monitor_summary.json 2025-04-14 22:06:08 (36574): Guest Log: -rw-------. 1 atlas atlas 281216 Apr 14 20:49 log.44075164._055072.job.log.tgz.1 2025-04-14 22:06:08 (36574): Guest Log: -rw-------. 1 atlas atlas 97 Apr 14 20:50 pilot_heartbeat.json 2025-04-14 22:06:08 (36574): Guest Log: -rw-------. 1 atlas atlas 7676 Apr 14 20:50 heartbeat.json 2025-04-14 22:06:08 (36574): Guest Log: -rw-------. 1 atlas atlas 4722 Apr 14 20:50 pilotlog.txt 2025-04-14 22:06:08 (36574): Guest Log: -rw-------. 1 atlas atlas 311616 Apr 14 20:50 log.44075164._055072.job.log.1 2025-04-14 22:06:08 (36574): Guest Log: -rw-------. 1 atlas atlas 357 Apr 14 20:50 output.list 2025-04-14 22:06:08 (36574): Guest Log: -rw-r--r--. 1 atlas atlas 620 Apr 14 20:50 runtime_log 2025-04-14 22:06:08 (36574): Guest Log: -rw-------. 1 atlas atlas 614400 Apr 14 20:50 result.tar.gz 2025-04-14 22:06:08 (36574): Guest Log: -rw-r--r--. 1 atlas atlas 10949 Apr 14 20:50 runtime_log.err 2025-04-14 22:06:08 (36574): Guest Log: -rw-------. 1 atlas atlas 658 Apr 14 20:50 IYEMDmbh7N7nsSi4ap6QjLDmwznN0nGgGQJmkKkKDmoRTLDmaDXdTm.diag 2025-04-14 22:06:08 (36574): Guest Log: Looking for outputfile HITS.44075164._055072.pool.root.1 2025-04-14 22:06:08 (36574): Guest Log: HITS file was successfully produced 2025-04-14 22:06:08 (36574): Guest Log: -rw-------. 1 atlas atlas 189831286 Apr 14 20:15 /home/atlas/RunAtlas/HITS.44075164._055072.pool.root.1 2025-04-14 22:06:08 (36574): Guest Log: Successfully finished the ATLAS job! 2025-04-14 22:06:08 (36574): Guest Log: Copying the results back to the shared directory! 2025-04-14 22:06:08 (36574): Guest Log: *** Contents of shared directory: *** 2025-04-14 22:06:08 (36574): Guest Log: total 216572 2025-04-14 22:06:08 (36574): Guest Log: -rw-r--r--. 1 root root 220612504 Apr 13 11:31 ATLAS.root_0 2025-04-14 22:06:08 (36574): Guest Log: -rw-r--r--. 1 root root 6948 Apr 13 11:31 init_data.xml 2025-04-14 22:06:08 (36574): Guest Log: -rw-r--r--. 1 root root 508875 Apr 13 11:31 input.tar.gz 2025-04-14 22:06:08 (36574): Guest Log: -rw-r--r--. 1 root root 614400 Apr 14 2025 result.tar.gz 2025-04-14 22:06:08 (36574): Guest Log: -rw-r--r--. 1 root root 17569 Apr 13 11:31 start_atlas.sh 2025-04-14 22:06:08 (36574): Guest Log: *** Success! Shutting down the machine. *** 2025-04-14 22:06:08 (36574): Starting VM using VBoxManage interface. (boinc_61a02799abc9d051, slot#2) 2025-04-14 22:06:14 (36574): Successfully started VM. (PID = '36714') 2025-04-14 22:06:14 (36574): Reporting VM Process ID to BOINC. 2025-04-14 22:06:14 (36574): VM state change detected. (old = 'poweredoff', new = 'running') 2025-04-14 22:06:14 (36574): Detected: Web Application Enabled (http://localhost:53553) 2025-04-14 22:06:14 (36574): VM Completion File Detected. 2025-04-14 22:06:14 (36574): Powering off VM. 2025-04-14 22:06:15 (36574): Successfully stopped VM. 2025-04-14 22:06:15 (36574): Deregistering VM. (boinc_61a02799abc9d051, slot#2) 2025-04-14 22:06:15 (36574): Removing network bandwidth throttle group from VM. 2025-04-14 22:06:15 (36574): Removing VM from VirtualBox. 22:06:20 (36574): called boinc_finish(0) </stderr_txt> ]]>
©2025 CERN