| Name | as0NDmlBO48n9Rq4apOajLDm4fhM0noT9bVo0NGKDmP11KDm9yrgBo_1 |
| Workunit | 238710933 |
| Created | 26 Jan 2026, 3:52:42 UTC |
| Sent | 26 Jan 2026, 8:19:25 UTC |
| Report deadline | 3 Feb 2026, 8:19:25 UTC |
| Received | 29 Jan 2026, 4:04:11 UTC |
| Server state | Over |
| Outcome | Success |
| Client state | Done |
| Exit status | 0 (0x00000000) |
| Computer ID | 10740518 |
| Run time | 8 hours 2 min 9 sec |
| CPU time | 23 hours 48 min 4 sec |
| Priority | 28 |
| Validate state | Valid |
| Credit | 629.60 |
| Device peak FLOPS | 16.56 GFLOPS |
| Application version | ATLAS Simulation v3.03 (vbox64_mt_mcore_atlas) windows_x86_64 |
| Peak working set size | 102.77 MB |
| Peak swap size | 133.56 MB |
| Peak disk usage | 1.73 GB |
Stderr output
<core_client_version>7.16.20</core_client_version> <![CDATA[ <stderr_txt> 2026-01-26 04:00:44 (11248): vboxwrapper version 26210 2026-01-26 04:00:44 (11248): BOINC client version: 7.16.20 2026-01-26 04:00:45 (11248): Detected: VirtualBox VboxManage Interface (Version: 6.0.14) 2026-01-26 04:00:45 (11248): Successfully copied 'init_data.xml' to the shared directory. 2026-01-26 04:00:46 (11248): Create VM. (boinc_b7a6f1a7d769f9c4, slot#2) 2026-01-26 04:00:46 (11248): Setting Memory Size for VM. (4400MB) 2026-01-26 04:00:46 (11248): Setting CPU Count for VM. (4) 2026-01-26 04:00:47 (11248): Setting Chipset Options for VM. 2026-01-26 04:00:47 (11248): Setting Graphics Controller Options for VM. (Driver: VBoxVGA, 16MB) 2026-01-26 04:00:47 (11248): Setting Boot Options for VM. 2026-01-26 04:00:48 (11248): Setting Network Configuration for NAT. (Driver: virtio) 2026-01-26 04:00:50 (11248): Enabling VM Network Access. 2026-01-26 04:00:50 (11248): Disabling USB Support for VM. 2026-01-26 04:00:52 (11248): Disabling COM Port Support for VM. 2026-01-26 04:00:53 (11248): Disabling LPT Port Support for VM. 2026-01-26 04:00:54 (11248): Disabling Audio Support for VM. 2026-01-26 04:00:55 (11248): Disabling Clipboard Support for VM. 2026-01-26 04:00:56 (11248): Disabling Drag and Drop Support for VM. 2026-01-26 04:00:57 (11248): Adding storage controller(s) to VM. 2026-01-26 04:00:57 (11248): Adding virtual disk drive to VM. (ATLAS_vbox_3.03_image.vdi) 2026-01-26 04:00:59 (11248): Adding VirtualBox Guest Additions to VM. 2026-01-26 04:01:00 (11248): Adding network bandwidth throttle group to VM. (Defaulting to 1024GB) 2026-01-26 04:01:01 (11248): forwarding host port 50818 to guest port 80 2026-01-26 04:01:01 (11248): Enabling remote desktop for VM. 2026-01-26 04:01:01 (11248): Required extension pack not installed, remote desktop not enabled. 2026-01-26 04:01:01 (11248): Enabling shared directory for VM. 2026-01-26 04:01:02 (11248): Starting VM using VBoxManage interface. (boinc_b7a6f1a7d769f9c4, slot#2) 2026-01-26 04:01:07 (11248): Successfully started VM. (PID = '5772') 2026-01-26 04:01:07 (11248): Reporting VM Process ID to BOINC. 2026-01-26 04:01:07 (11248): Guest Log: BIOS: VirtualBox 6.0.14 2026-01-26 04:01:07 (11248): Guest Log: CPUID EDX: 0x178bfbff 2026-01-26 04:01:07 (11248): Guest Log: BIOS: AHCI 0-P#0: PCHS=16383/16/63 LCHS=1024/255/63 0x0000000002800000 sectors 2026-01-26 04:01:07 (11248): VM state change detected. (old = 'poweredoff', new = 'running') 2026-01-26 04:01:07 (11248): Detected: Web Application Enabled (http://localhost:50818) 2026-01-26 04:01:08 (11248): VM state change detected. (old = 'running', new = 'paused') 2026-01-26 04:15:36 (11248): Preference change detected 2026-01-26 04:15:36 (11248): Setting CPU throttle for VM. (100%) 2026-01-26 04:15:36 (11248): Setting checkpoint interval to 900 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 900 seconds)) 2026-01-26 04:15:37 (11248): VM state change detected. (old = 'paused', new = 'running') 2026-01-26 04:15:38 (11248): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032 2026-01-26 04:15:38 (11248): Guest Log: BIOS: Booting from Hard Disk... 2026-01-26 04:15:42 (11248): Guest Log: BIOS: KBD: unsupported int 16h function 03 2026-01-26 04:15:42 (11248): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 2026-01-26 04:15:42 (11248): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=81 2026-01-26 04:15:42 (11248): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=81 2026-01-26 04:15:42 (11248): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=82 2026-01-26 04:15:42 (11248): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=82 2026-01-26 04:15:42 (11248): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=83 2026-01-26 04:15:42 (11248): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=83 2026-01-26 04:15:42 (11248): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=84 2026-01-26 04:15:42 (11248): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=84 2026-01-26 04:15:42 (11248): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=85 2026-01-26 04:15:42 (11248): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=85 2026-01-26 04:15:42 (11248): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=86 2026-01-26 04:15:42 (11248): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=86 2026-01-26 04:15:42 (11248): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=87 2026-01-26 04:15:42 (11248): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=87 2026-01-26 04:15:42 (11248): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=88 2026-01-26 04:15:42 (11248): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=88 2026-01-26 04:15:42 (11248): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=89 2026-01-26 04:15:42 (11248): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=89 2026-01-26 04:15:42 (11248): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=8a 2026-01-26 04:15:42 (11248): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=8a 2026-01-26 04:15:42 (11248): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=8b 2026-01-26 04:15:42 (11248): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=8b 2026-01-26 04:15:42 (11248): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=8c 2026-01-26 04:15:42 (11248): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=8c 2026-01-26 04:15:42 (11248): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=8d 2026-01-26 04:15:42 (11248): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=8d 2026-01-26 04:15:42 (11248): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=8e 2026-01-26 04:15:42 (11248): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=8e 2026-01-26 04:15:42 (11248): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=8f 2026-01-26 04:15:42 (11248): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=8f 2026-01-26 04:15:46 (11248): VM state change detected. (old = 'running', new = 'paused') 2026-01-26 04:19:07 (11248): VM state change detected. (old = 'paused', new = 'running') 2026-01-26 04:19:11 (11248): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds 2026-01-26 04:19:11 (11248): Guest Log: vboxguest: misc device minor 58, IRQ 20, I/O port d020, MMIO at 00000000f0000000 (size 0x400000) 2026-01-26 04:19:17 (11248): VM state change detected. (old = 'running', new = 'paused') 2026-01-26 04:25:17 (11248): VM state change detected. (old = 'paused', new = 'running') 2026-01-26 04:25:25 (11248): Guest Log: VBoxService 5.2.32 r132073 (verbosity: 0) linux.amd64 (Jul 12 2019 10:32:28) release log 2026-01-26 04:25:25 (11248): Guest Log: 00:00:00.000181 main Log opened 2026-01-26T04:01:35.653557000Z 2026-01-26 04:25:25 (11248): Guest Log: 00:00:00.000292 main OS Product: Linux 2026-01-26 04:25:25 (11248): Guest Log: 00:00:00.000367 main OS Release: 3.10.0-957.27.2.el7.x86_64 2026-01-26 04:25:25 (11248): Guest Log: 00:00:00.000404 main OS Version: #1 SMP Mon Jul 29 17:46:05 UTC 2019 2026-01-26 04:25:25 (11248): Guest Log: 00:00:00.000457 main Executable: /opt/VBoxGuestAdditions-5.2.32/sbin/VBoxService 2026-01-26 04:25:25 (11248): Guest Log: 00:00:00.000458 main Process ID: 1305 2026-01-26 04:25:25 (11248): Guest Log: 00:00:00.000459 main Package type: LINUX_64BITS_GENERIC 2026-01-26 04:25:25 (11248): Guest Log: 00:00:00.003951 main 5.2.32 r132073 started. Verbose level = 0 2026-01-26 04:25:26 (11248): Guest Log: [INFO] Probing /cvmfs/atlas.cern.ch... 2026-01-26 04:25:26 (11248): Guest Log: [INFO] Mounting shared directory 2026-01-26 04:25:26 (11248): Guest Log: [INFO] Checking for init_data.xml 2026-01-26 04:25:27 (11248): VM state change detected. (old = 'running', new = 'paused') 2026-01-26 04:28:47 (11248): VM state change detected. (old = 'paused', new = 'running') 2026-01-26 04:28:51 (11248): Guest Log: [INFO] Probing /cvmfs/atlas.cern.ch... OK 2026-01-26 04:28:51 (11248): Guest Log: [INFO] Detected branch: prod 2026-01-26 04:28:52 (11248): Guest Log: This is the prod version of the ATLAS job wrapper 2026-01-26 04:28:52 (11248): Guest Log: Copying input files 2026-01-26 04:28:54 (11248): Guest Log: Copied input files into RunAtlas. 2026-01-26 04:28:54 (11248): Guest Log: This VM did not configure a local http proxy via BOINC. 2026-01-26 04:28:54 (11248): Guest Log: Small home clusters do not require a local http proxy but it is suggested if 2026-01-26 04:28:54 (11248): Guest Log: more than 10 cores throughout the same LAN segment are regularly running ATLAS like tasks. 2026-01-26 04:28:54 (11248): Guest Log: Further information can be found at the LHC@home message board. 2026-01-26 04:28:55 (11248): Guest Log: 00:00:10.008330 timesync vgsvcTimeSyncWorker: Radical guest time change: 19 439 663 356 000ns (GuestNow=1 769 419 535 324 299 000 ns GuestLast=1 769 400 095 660 943 000 ns fSetTimeLastLoop=true ) 2026-01-26 04:28:58 (11248): VM state change detected. (old = 'running', new = 'paused') 2026-01-26 04:42:38 (11248): VM state change detected. (old = 'paused', new = 'running') 2026-01-26 04:42:45 (11248): Guest Log: Running cvmfs_config stat atlas.cern.ch 2026-01-26 04:42:46 (11248): 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 2026-01-26 04:42:46 (11248): Guest Log: 2.6.3.0 1585 0 30172 155541 3 1 3117737 4096000 0 65024 0 0 n/a 0 0 http://s1cern-cvmfs.openhtc.io/cvmfs/atlas.cern.ch DIRECT 1 2026-01-26 04:42:48 (11248): VM state change detected. (old = 'running', new = 'paused') 2026-01-26 04:44:38 (11248): VM state change detected. (old = 'paused', new = 'running') 2026-01-26 04:44:39 (11248): Guest Log: copied the webapp to /var/www 2026-01-26 04:44:39 (11248): Guest Log: ATHENA_PROC_NUMBER=4 2026-01-26 04:44:39 (11248): Guest Log: ATHENA_CORE_NUMBER=4 2026-01-26 04:44:40 (11248): 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 2026-01-26 04:44:40 (11248): Guest Log: *** Starting ATLAS job. (PandaID=6983467191 taskID=48329654) *** 2026-01-26 04:44:48 (11248): VM state change detected. (old = 'running', new = 'paused') 2026-01-26 05:00:50 (11248): VM state change detected. (old = 'paused', new = 'running') 2026-01-26 05:01:00 (11248): VM state change detected. (old = 'running', new = 'paused') 2026-01-26 05:01:39 (11248): VM state change detected. (old = 'paused', new = 'running') 2026-01-26 05:01:45 (11248): Guest Log: 00:00:50.021695 timesync vgsvcTimeSyncWorker: Radical guest time change: 2 100 855 333 000ns (GuestNow=1 769 421 666 189 887 000 ns GuestLast=1 769 419 565 334 554 000 ns fSetTimeLastLoop=true ) 2026-01-26 05:01:49 (11248): VM state change detected. (old = 'running', new = 'paused') 2026-01-26 05:08:10 (11248): VM state change detected. (old = 'paused', new = 'running') 2026-01-26 05:08:20 (11248): VM state change detected. (old = 'running', new = 'paused') 2026-01-26 05:45:51 (11248): VM state change detected. (old = 'paused', new = 'running') 2026-01-26 05:45:57 (11248): Guest Log: 00:01:10.024050 timesync vgsvcTimeSyncWorker: Radical host time change: 2 260 950 000 000ns (HostNow=1 769 424 356 931 000 000 ns HostLast=1 769 422 095 981 000 000 ns) 2026-01-26 05:46:01 (11248): VM state change detected. (old = 'running', new = 'paused') 2026-01-26 05:52:32 (11248): VM state change detected. (old = 'paused', new = 'running') 2026-01-26 05:52:38 (11248): Guest Log: 00:01:20.180072 timesync vgsvcTimeSyncWorker: Radical guest time change: 2 680 895 645 000ns (GuestNow=1 769 424 367 087 447 000 ns GuestLast=1 769 421 686 191 802 000 ns fSetTimeLastLoop=true ) 2026-01-26 05:52:42 (11248): VM state change detected. (old = 'running', new = 'paused') 2026-01-26 06:00:43 (11248): VM state change detected. (old = 'paused', new = 'running') 2026-01-26 06:00:53 (11248): VM state change detected. (old = 'running', new = 'paused') 2026-01-26 06:19:03 (11248): VM state change detected. (old = 'paused', new = 'running') 2026-01-26 06:19:14 (11248): VM state change detected. (old = 'running', new = 'paused') 2026-01-26 06:54:15 (11248): VM state change detected. (old = 'paused', new = 'running') 2026-01-26 06:54:19 (11248): Guest Log: 00:01:50.188986 timesync vgsvcTimeSyncWorker: Radical host time change: 2 110 388 000 000ns (HostNow=1 769 428 459 055 000 000 ns HostLast=1 769 426 348 667 000 000 ns) 2026-01-26 06:54:19 (11248): Guest Log: 00:01:50.189103 timesync vgsvcTimeSyncWorker: Radical guest time change: 1 971 585 448 000ns (GuestNow=1 769 426 358 673 857 000 ns GuestLast=1 769 424 387 088 409 000 ns fSetTimeLastLoop=true ) 2026-01-26 06:54:25 (11248): VM state change detected. (old = 'running', new = 'paused') 2026-01-26 07:00:45 (11248): VM state change detected. (old = 'paused', new = 'running') 2026-01-26 07:00:50 (11248): Guest Log: 00:02:00.432753 timesync vgsvcTimeSyncWorker: Radical guest time change: 2 110 625 710 000ns (GuestNow=1 769 428 469 299 567 000 ns GuestLast=1 769 426 358 673 857 000 ns fSetTimeLastLoop=true ) 2026-01-26 07:00:55 (11248): VM state change detected. (old = 'running', new = 'paused') 2026-01-26 07:17:56 (11248): VM state change detected. (old = 'paused', new = 'running') 2026-01-26 07:18:06 (11248): VM state change detected. (old = 'running', new = 'paused') 2026-01-26 07:18:47 (11248): VM state change detected. (old = 'paused', new = 'running') 2026-01-26 07:18:57 (11248): VM state change detected. (old = 'running', new = 'paused') 2026-01-26 20:00:48 (11248): VM state change detected. (old = 'paused', new = 'running') 2026-01-26 20:00:53 (11248): Guest Log: 00:02:30.804356 timesync vgsvcTimeSyncWorker: Radical host time change: 45 722 419 000 000ns (HostNow=1 769 475 653 492 000 000 ns HostLast=1 769 429 931 073 000 000 ns) 2026-01-26 20:00:58 (11248): VM state change detected. (old = 'running', new = 'paused') 2026-01-26 20:04:19 (11248): VM state change detected. (old = 'paused', new = 'running') 2026-01-26 20:04:24 (11248): Guest Log: 00:02:40.806001 timesync vgsvcTimeSyncWorker: Radical guest time change: 45 762 412 945 000ns (GuestNow=1 769 475 663 609 610 000 ns GuestLast=1 769 429 901 196 665 000 ns fSetTimeLastLoop=true ) 2026-01-26 20:04:29 (11248): VM state change detected. (old = 'running', new = 'paused') 2026-01-26 20:11:59 (11248): VM state change detected. (old = 'paused', new = 'running') 2026-01-26 20:12:09 (11248): VM state change detected. (old = 'running', new = 'paused') 2026-01-26 20:18:59 (11248): VM state change detected. (old = 'paused', new = 'running') 2026-01-26 20:19:09 (11248): VM state change detected. (old = 'running', new = 'paused') 2026-01-26 21:00:41 (11248): VM state change detected. (old = 'paused', new = 'running') 2026-01-26 21:00:47 (11248): Guest Log: 00:03:10.813662 timesync vgsvcTimeSyncWorker: Radical host time change: 2 502 258 000 000ns (HostNow=1 769 479 246 295 000 000 ns HostLast=1 769 476 744 037 000 000 ns) 2026-01-26 21:00:51 (11248): VM state change detected. (old = 'running', new = 'paused') 2026-01-26 21:22:52 (11248): VM state change detected. (old = 'paused', new = 'running') 2026-01-26 21:22:57 (11248): Guest Log: 00:03:20.814321 timesync vgsvcTimeSyncWorker: Radical guest time change: 3 562 678 024 000ns (GuestNow=1 769 479 256 295 233 000 ns GuestLast=1 769 475 693 617 209 000 ns fSetTimeLastLoop=true ) 2026-01-26 21:23:02 (11248): VM state change detected. (old = 'running', new = 'paused') 2026-01-26 21:27:02 (11248): VM state change detected. (old = 'paused', new = 'running') 2026-01-26 21:27:12 (11248): VM state change detected. (old = 'running', new = 'paused') 2026-01-26 22:00:45 (11248): VM state change detected. (old = 'paused', new = 'running') 2026-01-26 22:00:49 (11248): Guest Log: 00:03:41.064882 timesync vgsvcTimeSyncWorker: Radical host time change: 2 022 201 000 000ns (HostNow=1 769 482 848 857 000 000 ns HostLast=1 769 480 826 656 000 000 ns) 2026-01-26 22:00:55 (11248): VM state change detected. (old = 'running', new = 'paused') 2026-01-26 22:19:05 (11248): VM state change detected. (old = 'paused', new = 'running') 2026-01-26 22:19:09 (11248): Guest Log: 00:03:51.065795 timesync vgsvcTimeSyncWorker: Radical guest time change: 2 261 844 164 000ns (GuestNow=1 769 482 859 100 382 000 ns GuestLast=1 769 480 597 256 218 000 ns fSetTimeLastLoop=true ) 2026-01-26 22:19:25 (11248): VM state change detected. (old = 'running', new = 'paused') 2026-01-26 23:00:47 (11248): VM state change detected. (old = 'paused', new = 'running') 2026-01-26 23:00:51 (11248): Guest Log: 00:04:11.081226 timesync vgsvcTimeSyncWorker: Radical host time change: 2 491 181 000 000ns (HostNow=1 769 486 450 970 000 000 ns HostLast=1 769 483 959 789 000 000 ns) 2026-01-26 23:00:57 (11248): VM state change detected. (old = 'running', new = 'paused') 2026-01-26 23:24:18 (11248): VM state change detected. (old = 'paused', new = 'running') 2026-01-26 23:24:21 (11248): Guest Log: 00:04:21.083102 timesync vgsvcTimeSyncWorker: Radical guest time change: 3 581 855 315 000ns (GuestNow=1 769 486 460 970 999 000 ns GuestLast=1 769 482 879 115 684 000 ns fSetTimeLastLoop=true ) 2026-01-26 23:24:28 (11248): VM state change detected. (old = 'running', new = 'paused') 2026-01-27 00:00:51 (11248): VM state change detected. (old = 'paused', new = 'running') 2026-01-27 00:00:54 (11248): Guest Log: 00:04:31.084044 timesync vgsvcTimeSyncWorker: Radical host time change: 2 192 263 000 000ns (HostNow=1 769 490 054 022 000 000 ns HostLast=1 769 487 861 759 000 000 ns) 2026-01-27 00:01:01 (11248): VM state change detected. (old = 'running', new = 'paused') 2026-01-27 00:19:01 (11248): VM state change detected. (old = 'paused', new = 'running') 2026-01-27 00:19:04 (11248): Guest Log: 00:04:41.085491 timesync vgsvcTimeSyncWorker: Radical guest time change: 2 192 262 579 000ns (GuestNow=1 769 490 064 023 320 000 ns GuestLast=1 769 487 871 760 741 000 ns fSetTimeLastLoop=true ) 2026-01-27 00:19:11 (11248): VM state change detected. (old = 'running', new = 'paused') 2026-01-27 00:40:52 (11248): VM state change detected. (old = 'paused', new = 'running') 2026-01-27 00:41:02 (11248): VM state change detected. (old = 'running', new = 'paused') 2026-01-27 00:42:12 (11248): VM state change detected. (old = 'paused', new = 'running') 2026-01-27 00:42:15 (11248): Guest Log: 00:05:01.087413 timesync vgsvcTimeSyncWorker: Radical guest time change: 2 391 280 643 000ns (GuestNow=1 769 492 465 305 603 000 ns GuestLast=1 769 490 074 024 960 000 ns fSetTimeLastLoop=true ) 2026-01-27 00:42:22 (11248): VM state change detected. (old = 'running', new = 'paused') 2026-01-27 01:00:44 (11248): VM state change detected. (old = 'paused', new = 'running') 2026-01-27 01:00:53 (11248): VM state change detected. (old = 'running', new = 'paused') 2026-01-27 01:58:06 (11248): VM state change detected. (old = 'paused', new = 'running') 2026-01-27 01:58:10 (11248): Guest Log: 00:05:21.088781 timesync vgsvcTimeSyncWorker: Radical host time change: 3 442 591 000 000ns (HostNow=1 769 497 089 355 000 000 ns HostLast=1 769 493 646 764 000 000 ns) 2026-01-27 01:58:17 (11248): VM state change detected. (old = 'running', new = 'paused') 2026-01-27 01:58:27 (11248): VM state change detected. (old = 'paused', new = 'running') 2026-01-27 01:58:28 (11248): Guest Log: 00:05:31.093002 timesync vgsvcTimeSyncWorker: Radical guest time change: 4 614 052 554 000ns (GuestNow=1 769 497 099 359 348 000 ns GuestLast=1 769 492 485 306 794 000 ns fSetTimeLastLoop=true ) 2026-01-27 01:58:37 (11248): VM state change detected. (old = 'running', new = 'paused') 2026-01-27 02:00:57 (11248): VM state change detected. (old = 'paused', new = 'running') 2026-01-27 02:01:07 (11248): VM state change detected. (old = 'running', new = 'paused') 2026-01-27 02:19:08 (11248): VM state change detected. (old = 'paused', new = 'running') 2026-01-27 02:19:18 (11248): VM state change detected. (old = 'running', new = 'paused') 2026-01-27 03:01:01 (11248): VM state change detected. (old = 'paused', new = 'running') 2026-01-27 03:01:02 (11248): Guest Log: 00:06:01.114426 timesync vgsvcTimeSyncWorker: Radical host time change: 2 512 822 000 000ns (HostNow=1 769 500 861 931 000 000 ns HostLast=1 769 498 349 109 000 000 ns) 2026-01-27 03:01:11 (11248): VM state change detected. (old = 'running', new = 'paused') 2026-01-27 03:25:21 (11248): VM state change detected. (old = 'paused', new = 'running') 2026-01-27 03:25:22 (11248): Guest Log: 00:06:11.115718 timesync vgsvcTimeSyncWorker: Radical guest time change: 2 512 816 423 000ns (GuestNow=1 769 500 871 931 712 000 ns GuestLast=1 769 498 359 115 289 000 ns fSetTimeLastLoop=true ) 2026-01-27 03:25:31 (11248): VM state change detected. (old = 'running', new = 'paused') 2026-01-27 03:46:53 (11248): VM state change detected. (old = 'paused', new = 'running') 2026-01-27 03:47:03 (11248): VM state change detected. (old = 'running', new = 'paused') 2026-01-27 03:51:23 (11248): VM state change detected. (old = 'paused', new = 'running') 2026-01-27 03:51:33 (11248): VM state change detected. (old = 'running', new = 'paused') 2026-01-27 04:01:03 (11248): VM state change detected. (old = 'paused', new = 'running') 2026-01-27 04:01:14 (11248): VM state change detected. (old = 'running', new = 'paused') 2026-01-27 04:19:05 (11248): VM state change detected. (old = 'paused', new = 'running') 2026-01-27 04:19:15 (11248): VM state change detected. (old = 'running', new = 'paused') 2026-01-27 04:52:48 (11248): VM state change detected. (old = 'paused', new = 'running') 2026-01-27 04:52:57 (11248): Guest Log: 00:07:11.160319 timesync vgsvcTimeSyncWorker: Radical host time change: 2 022 173 000 000ns (HostNow=1 769 507 576 874 000 000 ns HostLast=1 769 505 554 701 000 000 ns) 2026-01-27 04:52:57 (11248): Guest Log: 00:07:11.160397 timesync vgsvcTimeSyncWorker: Radical guest time change: 1 911 156 002 000ns (GuestNow=1 769 505 564 708 609 000 ns GuestLast=1 769 503 653 552 607 000 ns fSetTimeLastLoop=true ) 2026-01-27 04:52:58 (11248): VM state change detected. (old = 'running', new = 'paused') 2026-01-27 05:00:48 (11248): VM state change detected. (old = 'paused', new = 'running') 2026-01-27 05:00:57 (11248): Guest Log: 00:07:21.160580 timesync vgsvcTimeSyncWorker: Radical guest time change: 2 022 166 515 000ns (GuestNow=1 769 507 586 875 124 000 ns GuestLast=1 769 505 564 708 609 000 ns fSetTimeLastLoop=true ) 2026-01-27 05:00:58 (11248): VM state change detected. (old = 'running', new = 'paused') 2026-01-27 05:45:31 (11248): VM state change detected. (old = 'paused', new = 'running') 2026-01-27 05:45:39 (11248): Guest Log: 00:07:31.164629 timesync vgsvcTimeSyncWorker: Radical host time change: 2 682 594 000 000ns (HostNow=1 769 510 739 447 000 000 ns HostLast=1 769 508 056 853 000 000 ns) 2026-01-27 05:45:40 (11248): VM state change detected. (old = 'running', new = 'paused') 2026-01-27 05:54:11 (11248): VM state change detected. (old = 'paused', new = 'running') 2026-01-27 05:54:20 (11248): Guest Log: 00:07:41.173401 timesync vgsvcTimeSyncWorker: Radical guest time change: 3 152 578 134 000ns (GuestNow=1 769 510 749 456 395 000 ns GuestLast=1 769 507 596 878 261 000 ns fSetTimeLastLoop=true ) 2026-01-27 05:54:21 (11248): VM state change detected. (old = 'running', new = 'paused') 2026-01-27 06:00:52 (11248): VM state change detected. (old = 'paused', new = 'running') 2026-01-27 06:01:01 (11248): VM state change detected. (old = 'running', new = 'paused') 2026-01-27 06:19:02 (11248): VM state change detected. (old = 'paused', new = 'running') 2026-01-27 06:19:12 (11248): VM state change detected. (old = 'running', new = 'paused') 2026-01-27 07:00:54 (11248): VM state change detected. (old = 'paused', new = 'running') 2026-01-27 07:01:04 (11248): Guest Log: 00:08:11.185094 timesync vgsvcTimeSyncWorker: Radical host time change: 2 512 379 000 000ns (HostNow=1 769 515 263 305 000 000 ns HostLast=1 769 512 750 926 000 000 ns) 2026-01-27 07:01:04 (11248): Guest Log: 00:08:11.185182 timesync vgsvcTimeSyncWorker: Radical guest time change: 1 991 475 987 000ns (GuestNow=1 769 512 760 934 597 000 ns GuestLast=1 769 510 769 458 610 000 ns fSetTimeLastLoop=true ) 2026-01-27 07:01:05 (11248): VM state change detected. (old = 'running', new = 'paused') 2026-01-27 07:02:05 (11248): VM state change detected. (old = 'paused', new = 'running') 2026-01-27 07:02:13 (11248): Guest Log: 00:08:21.186613 timesync vgsvcTimeSyncWorker: Radical guest time change: 2 512 372 506 000ns (GuestNow=1 769 515 273 307 103 000 ns GuestLast=1 769 512 760 934 597 000 ns fSetTimeLastLoop=true ) 2026-01-27 07:02:14 (11248): VM state change detected. (old = 'running', new = 'paused') 2026-01-27 07:48:47 (11248): VM state change detected. (old = 'paused', new = 'running') 2026-01-27 07:48:55 (11248): Guest Log: 00:08:31.192025 timesync vgsvcTimeSyncWorker: Radical host time change: 2 802 534 000 000ns (HostNow=1 769 518 135 608 000 000 ns HostLast=1 769 515 333 074 000 000 ns) 2026-01-27 07:48:57 (11248): VM state change detected. (old = 'running', new = 'paused') 2026-01-27 20:00:49 (11248): VM state change detected. (old = 'paused', new = 'running') 2026-01-27 20:01:00 (11248): Guest Log: 00:08:41.193888 timesync vgsvcTimeSyncWorker: Radical host time change: 43 921 488 000 000ns (HostNow=1 769 562 057 096 000 000 ns HostLast=1 769 518 135 608 000 000 ns) 2026-01-27 20:01:00 (11248): Guest Log: 00:08:41.193970 timesync vgsvcTimeSyncWorker: Radical guest time change: 2 862 306 157 000ns (GuestNow=1 769 518 145 614 164 000 ns GuestLast=1 769 515 283 308 007 000 ns fSetTimeLastLoop=true ) 2026-01-27 20:01:04 (11248): VM state change detected. (old = 'running', new = 'paused') 2026-01-27 20:19:28 (11248): VM state change detected. (old = 'paused', new = 'running') 2026-01-27 20:19:36 (11248): Guest Log: 00:08:51.194604 timesync vgsvcTimeSyncWorker: Radical guest time change: 43 921 483 044 000ns (GuestNow=1 769 562 067 097 208 000 ns GuestLast=1 769 518 145 614 164 000 ns fSetTimeLastLoop=true ) 2026-01-27 20:19:44 (11248): VM state change detected. (old = 'running', new = 'paused') 2026-01-27 20:47:40 (11248): VM state change detected. (old = 'paused', new = 'running') 2026-01-27 20:49:13 (11248): Guest Log: 00:09:21.197874 timesync vgsvcTimeSyncWorker: Radical guest time change: 2 788 953 501 000ns (GuestNow=1 769 564 876 052 857 000 ns GuestLast=1 769 562 087 099 356 000 ns fSetTimeLastLoop=true ) 2026-01-27 20:49:13 (11248): VM state change detected. (old = 'running', new = 'paused') 2026-01-27 21:00:51 (11248): VM state change detected. (old = 'paused', new = 'running') 2026-01-27 21:01:27 (11248): VM state change detected. (old = 'running', new = 'paused') 2026-01-27 21:38:22 (11248): VM state change detected. (old = 'paused', new = 'running') 2026-01-27 21:38:29 (11248): Guest Log: 00:11:21.221314 timesync vgsvcTimeSyncWorker: Radical host time change: 2 224 537 000 000ns (HostNow=1 769 567 909 186 000 000 ns HostLast=1 769 565 684 649 000 000 ns) 2026-01-27 21:38:32 (11248): VM state change detected. (old = 'running', new = 'paused') 2026-01-27 22:00:44 (11248): VM state change detected. (old = 'paused', new = 'running') 2026-01-27 22:00:51 (11248): Guest Log: 00:11:31.247390 timesync vgsvcTimeSyncWorker: Radical guest time change: 2 923 115 893 000ns (GuestNow=1 769 567 919 189 395 000 ns GuestLast=1 769 564 996 073 502 000 ns fSetTimeLastLoop=true ) 2026-01-27 22:00:54 (11248): VM state change detected. (old = 'running', new = 'paused') 2026-01-27 22:19:25 (11248): VM state change detected. (old = 'paused', new = 'running') 2026-01-27 22:23:18 (11248): VM state change detected. (old = 'running', new = 'paused') 2026-01-27 22:35:56 (11248): VM state change detected. (old = 'paused', new = 'running') 2026-01-27 22:36:09 (11248): Guest Log: 00:15:41.286639 timesync vgsvcTimeSyncWorker: Radical guest time change: 1 878 312 772 000ns (GuestNow=1 769 571 369 348 558 000 ns GuestLast=1 769 569 491 035 786 000 ns fSetTimeLastLoop=true ) 2026-01-27 22:49:38 (11248): Error in pause VM for VM: -182 Command: VBoxManage -q controlvm "boinc_b7a6f1a7d769f9c4" pause Output: 2026-01-27 23:04:51 (11248): Error in pause VM for VM: -182 Command: VBoxManage -q controlvm "boinc_b7a6f1a7d769f9c4" pause Output: 2026-01-27 23:19:55 (11248): Error in pause VM for VM: -182 Command: VBoxManage -q controlvm "boinc_b7a6f1a7d769f9c4" pause Output: 2026-01-27 23:35:18 (11248): Error in pause VM for VM: -182 Command: VBoxManage -q controlvm "boinc_b7a6f1a7d769f9c4" pause Output: 2026-01-27 23:48:16 (11248): Error in pause VM for VM: -182 Command: VBoxManage -q controlvm "boinc_b7a6f1a7d769f9c4" pause Output: 2026-01-28 00:05:35 (11248): Error in pause VM for VM: -182 Command: VBoxManage -q controlvm "boinc_b7a6f1a7d769f9c4" pause Output: 2026-01-28 00:24:58 (11248): Error in pause VM for VM: -182 Command: VBoxManage -q controlvm "boinc_b7a6f1a7d769f9c4" pause Output: 2026-01-28 00:49:47 (11248): Status Report: Elapsed Time: '6000.992697' 2026-01-28 00:49:47 (11248): Status Report: CPU Time: '20539.593750' 2026-01-28 01:07:46 (11248): Error in pause VM for VM: -182 Command: VBoxManage -q controlvm "boinc_b7a6f1a7d769f9c4" pause Output: 2026-01-28 01:34:33 (11248): VM state change detected. (old = 'running', new = 'paused') 2026-01-28 01:34:57 (11248): VM state change detected. (old = 'paused', new = 'running') 2026-01-28 01:51:39 (11248): Error in pause VM for VM: -182 Command: VBoxManage -q controlvm "boinc_b7a6f1a7d769f9c4" pause Output: 2026-01-28 02:06:52 (11248): Error in pause VM for VM: -182 Command: VBoxManage -q controlvm "boinc_b7a6f1a7d769f9c4" pause Output: 2026-01-28 02:22:03 (11248): Error in pause VM for VM: -182 Command: VBoxManage -q controlvm "boinc_b7a6f1a7d769f9c4" pause Output: 2026-01-28 02:30:13 (11248): Error in pause VM for VM: -182 Command: VBoxManage -q controlvm "boinc_b7a6f1a7d769f9c4" pause Output: 2026-01-28 02:37:13 (11248): Error in pause VM for VM: -182 Command: VBoxManage -q controlvm "boinc_b7a6f1a7d769f9c4" pause Output: 2026-01-28 02:52:24 (11248): Error in pause VM for VM: -182 Command: VBoxManage -q controlvm "boinc_b7a6f1a7d769f9c4" pause Output: 2026-01-28 03:07:29 (11248): Error in pause VM for VM: -182 Command: VBoxManage -q controlvm "boinc_b7a6f1a7d769f9c4" pause Output: 2026-01-28 03:22:46 (11248): Error in pause VM for VM: -182 Command: VBoxManage -q controlvm "boinc_b7a6f1a7d769f9c4" pause Output: 2026-01-28 03:24:40 (11248): Status Report: Elapsed Time: '12001.815439' 2026-01-28 03:24:40 (11248): Status Report: CPU Time: '48337.984375' 2026-01-28 03:31:15 (11248): Error in pause VM for VM: -182 Command: VBoxManage -q controlvm "boinc_b7a6f1a7d769f9c4" pause Output: 2026-01-28 03:37:49 (11248): Error in pause VM for VM: -182 Command: VBoxManage -q controlvm "boinc_b7a6f1a7d769f9c4" pause Output: 2026-01-28 03:53:00 (11248): Error in pause VM for VM: -182 Command: VBoxManage -q controlvm "boinc_b7a6f1a7d769f9c4" pause Output: 2026-01-28 03:58:09 (11248): Error in pause VM for VM: -182 Command: VBoxManage -q controlvm "boinc_b7a6f1a7d769f9c4" pause Output: 2026-01-28 04:08:12 (11248): Error in pause VM for VM: -182 Command: VBoxManage -q controlvm "boinc_b7a6f1a7d769f9c4" pause Output: 2026-01-28 04:14:40 (11248): Error in pause VM for VM: -182 Command: VBoxManage -q controlvm "boinc_b7a6f1a7d769f9c4" pause Output: 2026-01-28 04:23:26 (11248): Error in pause VM for VM: -182 Command: VBoxManage -q controlvm "boinc_b7a6f1a7d769f9c4" pause Output: 2026-01-28 04:29:07 (11248): Error in pause VM for VM: -182 Command: VBoxManage -q controlvm "boinc_b7a6f1a7d769f9c4" pause Output: 2026-01-28 04:38:28 (11248): Error in pause VM for VM: -182 Command: VBoxManage -q controlvm "boinc_b7a6f1a7d769f9c4" pause Output: 2026-01-28 04:48:19 (11248): Error in pause VM for VM: -182 Command: VBoxManage -q controlvm "boinc_b7a6f1a7d769f9c4" pause Output: 2026-01-28 04:53:33 (11248): Error in pause VM for VM: -182 Command: VBoxManage -q controlvm "boinc_b7a6f1a7d769f9c4" pause Output: 2026-01-28 04:59:09 (11248): Error in pause VM for VM: -182 Command: VBoxManage -q controlvm "boinc_b7a6f1a7d769f9c4" pause Output: 2026-01-28 05:08:49 (11248): Error in pause VM for VM: -182 Command: VBoxManage -q controlvm "boinc_b7a6f1a7d769f9c4" pause Output: 2026-01-28 05:23:48 (11248): Error in pause VM for VM: -182 Command: VBoxManage -q controlvm "boinc_b7a6f1a7d769f9c4" pause Output: 2026-01-28 05:31:24 (11248): Error in pause VM for VM: -182 Command: VBoxManage -q controlvm "boinc_b7a6f1a7d769f9c4" pause Output: 2026-01-28 05:38:12 (11248): Error in pause VM for VM: -182 Command: VBoxManage -q controlvm "boinc_b7a6f1a7d769f9c4" pause Output: 2026-01-28 06:09:34 (11248): Error in pause VM for VM: -182 Command: VBoxManage -q controlvm "boinc_b7a6f1a7d769f9c4" pause Output: 2026-01-28 06:15:12 (11248): Error in pause VM for VM: -182 Command: VBoxManage -q controlvm "boinc_b7a6f1a7d769f9c4" pause Output: 2026-01-28 06:24:42 (11248): Error in pause VM for VM: -182 Command: VBoxManage -q controlvm "boinc_b7a6f1a7d769f9c4" pause Output: 2026-01-28 06:31:27 (11248): Error in pause VM for VM: -182 Command: VBoxManage -q controlvm "boinc_b7a6f1a7d769f9c4" pause Output: 2026-01-28 06:39:56 (11248): Error in pause VM for VM: -182 Command: VBoxManage -q controlvm "boinc_b7a6f1a7d769f9c4" pause Output: 2026-01-28 06:40:02 (11248): VM state change detected. (old = 'running', new = 'paused') 2026-01-28 06:42:01 (11248): VM state change detected. (old = 'paused', new = 'running') 2026-01-28 06:42:16 (11248): VM state change detected. (old = 'running', new = 'paused') 2026-01-28 06:43:16 (11248): VM state change detected. (old = 'paused', new = 'running') 2026-01-28 06:43:26 (11248): VM state change detected. (old = 'running', new = 'paused') 2026-01-28 06:48:47 (11248): VM state change detected. (old = 'paused', new = 'running') 2026-01-28 06:48:57 (11248): VM state change detected. (old = 'running', new = 'paused') 2026-01-28 07:00:47 (11248): VM state change detected. (old = 'paused', new = 'running') 2026-01-28 07:00:57 (11248): VM state change detected. (old = 'running', new = 'paused') 2026-01-28 07:05:07 (11248): VM state change detected. (old = 'paused', new = 'running') 2026-01-28 07:05:18 (11248): VM state change detected. (old = 'running', new = 'paused') 2026-01-28 07:45:49 (11248): Guest Log: 08:20:35.154494 timesync vgsvcTimeSyncWorker: Radical host time change: 2 441 431 000 000ns (HostNow=1 769 604 348 788 000 000 ns HostLast=1 769 601 907 357 000 000 ns) 2026-01-28 07:45:49 (11248): VM state change detected. (old = 'paused', new = 'running') 2026-01-28 07:45:59 (11248): VM state change detected. (old = 'running', new = 'paused') 2026-01-28 20:00:50 (11248): Guest Log: 08:20:45.182147 timesync vgsvcTimeSyncWorker: Radical host time change: 44 100 615 000 000ns (HostNow=1 769 648 449 403 000 000 ns HostLast=1 769 604 348 788 000 000 ns) 2026-01-28 20:00:50 (11248): Guest Log: 08:20:45.182214 timesync vgsvcTimeSyncWorker: Radical guest time change: 2 691 864 646 000ns (GuestNow=1 769 604 358 815 761 000 ns GuestLast=1 769 601 666 951 115 000 ns fSetTimeLastLoop=true ) 2026-01-28 20:00:50 (11248): VM state change detected. (old = 'paused', new = 'running') 2026-01-28 20:00:59 (11248): VM state change detected. (old = 'running', new = 'paused') 2026-01-28 20:22:51 (11248): VM state change detected. (old = 'paused', new = 'running') 2026-01-28 20:22:52 (11248): Guest Log: 08:20:55.184131 timesync vgsvcTimeSyncWorker: Radical guest time change: 44 100 588 454 000ns (GuestNow=1 769 648 459 404 215 000 ns GuestLast=1 769 604 358 815 761 000 ns fSetTimeLastLoop=true ) 2026-01-28 20:23:01 (11248): VM state change detected. (old = 'running', new = 'paused') 2026-01-28 21:00:43 (11248): Guest Log: 08:21:05.186752 timesync vgsvcTimeSyncWorker: Radical host time change: 2 272 284 000 000ns (HostNow=1 769 652 043 480 000 000 ns HostLast=1 769 649 771 196 000 000 ns) 2026-01-28 21:00:43 (11248): VM state change detected. (old = 'paused', new = 'running') 2026-01-28 21:00:53 (11248): VM state change detected. (old = 'running', new = 'paused') 2026-01-28 21:11:03 (11248): VM state change detected. (old = 'paused', new = 'running') 2026-01-28 21:11:04 (11248): Guest Log: 08:21:15.191080 timesync vgsvcTimeSyncWorker: Radical guest time change: 2 272 286 491 000ns (GuestNow=1 769 652 053 485 128 000 ns GuestLast=1 769 649 781 198 637 000 ns fSetTimeLastLoop=true ) 2026-01-28 21:11:13 (11248): VM state change detected. (old = 'running', new = 'paused') 2026-01-28 22:00:46 (11248): VM state change detected. (old = 'paused', new = 'running') 2026-01-28 22:00:47 (11248): Guest Log: 08:21:25.192317 timesync vgsvcTimeSyncWorker: Radical host time change: 2 982 744 000 000ns (HostNow=1 769 655 646 553 000 000 ns HostLast=1 769 652 663 809 000 000 ns) 2026-01-28 22:00:56 (11248): VM state change detected. (old = 'running', new = 'paused') 2026-01-28 22:22:58 (11248): Guest Log: 08:21:35.193132 timesync vgsvcTimeSyncWorker: Radical guest time change: 3 593 068 689 000ns (GuestNow=1 769 655 656 554 238 000 ns GuestLast=1 769 652 063 485 549 000 ns fSetTimeLastLoop=true ) 2026-01-28 22:22:58 (11248): VM state change detected. (old = 'paused', new = 'running') 2026-01-28 22:23:08 (11248): VM state change detected. (old = 'running', new = 'paused') 2026-01-28 22:24:18 (11248): VM state change detected. (old = 'paused', new = 'running') 2026-01-28 22:24:28 (11248): VM state change detected. (old = 'running', new = 'paused') 2026-01-28 22:42:48 (11248): VM state change detected. (old = 'paused', new = 'running') 2026-01-28 22:42:56 (11248): Guest Log: *** Job finished *** 2026-01-28 22:42:56 (11248): Guest Log: *** The last 20 lines of the pilot log: *** 2026-01-28 22:42:56 (11248): Guest Log: 2026-01-29 03:23:12,969 | INFO | job.realtimelogging is not enabled 2026-01-28 22:42:56 (11248): Guest Log: 2026-01-29 03:23:13,971 | INFO | [payload] run_realtimelog thread has finished 2026-01-28 22:42:56 (11248): Guest Log: 2026-01-29 03:23:16,964 | INFO | [monitor] cgroup control has ended 2026-01-28 22:42:56 (11248): Guest Log: 2026-01-29 03:23:18,514 | INFO | only monitor.control thread still running - safe to abort: ['<_MainThread(MainThread, started 139993049024320)>', '<ExcThread(monitor, started 139992739325696)>'] 2026-01-28 22:42:56 (11248): Guest Log: 2026-01-29 03:23:19,388 | WARNING | job_aborted has been set - aborting pilot monitoring 2026-01-28 22:42:56 (11248): Guest Log: 2026-01-29 03:23:19,388 | INFO | [monitor] control thread has ended 2026-01-28 22:42:56 (11248): Guest Log: 2026-01-29 03:23:23,521 | INFO | all workflow threads have been joined 2026-01-28 22:42:56 (11248): Guest Log: 2026-01-29 03:23:23,521 | INFO | end of generic workflow (traces error code: 0) 2026-01-28 22:42:56 (11248): Guest Log: 2026-01-29 03:23:23,521 | INFO | traces error code: 0 2026-01-28 22:42:56 (11248): Guest Log: 2026-01-29 03:23:23,521 | INFO | pilot has finished (exit code=0, shell exit code=0) 2026-01-28 22:42:56 (11248): Guest Log: 2026-01-29 03:23:23,762 [wrapper] ==== pilot stdout END ==== 2026-01-28 22:42:56 (11248): Guest Log: 2026-01-29 03:23:23,771 [wrapper] ==== wrapper stdout RESUME ==== 2026-01-28 22:42:56 (11248): Guest Log: 2026-01-29 03:23:23,779 [wrapper] pilotpid: 6009 2026-01-28 22:42:56 (11248): Guest Log: 2026-01-29 03:23:23,787 [wrapper] Pilot exit status: 0 2026-01-28 22:42:56 (11248): Guest Log: 2026-01-29 03:23:24,266 [wrapper] pandaids: 6983467191 2026-01-28 22:42:56 (11248): Guest Log: 2026-01-29 03:23:24,325 [wrapper] cleanup supervisor_pilot 1261 6010 2026-01-28 22:42:56 (11248): Guest Log: 2026-01-29 03:23:24,340 [wrapper] Test setup, not cleaning 2026-01-28 22:42:56 (11248): Guest Log: 2026-01-29 03:23:24,347 [wrapper] apfmon messages muted 2026-01-28 22:42:56 (11248): Guest Log: 2026-01-29 03:23:24,358 [wrapper] ==== wrapper stdout END ==== 2026-01-28 22:42:56 (11248): Guest Log: 2026-01-29 03:23:24,367 [wrapper] ==== wrapper stderr END ==== 2026-01-28 22:42:56 (11248): Guest Log: *** Error codes and diagnostics *** 2026-01-28 22:42:56 (11248): Guest Log: "exeErrorCode": 0, 2026-01-28 22:42:56 (11248): Guest Log: "exeErrorDiag": "", 2026-01-28 22:42:56 (11248): Guest Log: "pilotErrorCode": 0, 2026-01-28 22:42:56 (11248): Guest Log: "pilotErrorDiag": "", 2026-01-28 22:42:56 (11248): Guest Log: *** Listing of results directory *** 2026-01-28 22:42:59 (11248): Guest Log: total 208064 2026-01-28 22:42:59 (11248): Guest Log: -rw-r--r--. 1 atlas atlas 584422 Jan 25 22:54 pilot3.tar.gz 2026-01-28 22:42:59 (11248): Guest Log: -rwx------. 1 atlas atlas 36322 Jan 25 22:55 runpilot2-wrapper.sh 2026-01-28 22:42:59 (11248): Guest Log: -rw-r--r--. 1 atlas atlas 5111 Jan 25 22:56 queuedata.json 2026-01-28 22:42:59 (11248): Guest Log: -rwxr-xr-x. 1 atlas atlas 8615 Jan 26 09:25 init_data.xml 2026-01-28 22:42:59 (11248): Guest Log: -rwxr-xr-x. 1 atlas atlas 597780 Jan 26 09:25 input.tar.gz 2026-01-28 22:42:59 (11248): Guest Log: -rwxr-xr-x. 1 atlas atlas 15845 Jan 26 09:25 start_atlas.sh 2026-01-28 22:42:59 (11248): Guest Log: lrwxrwxrwx. 1 atlas atlas 20 Jan 26 09:25 EVNT.48329652._000005.pool.root.1 -> /data/./ATLAS.root_0 2026-01-28 22:42:59 (11248): Guest Log: -rw-r--r--. 1 atlas atlas 2613 Jan 26 09:25 pandaJob.out 2026-01-28 22:42:59 (11248): Guest Log: -rw-------. 1 atlas atlas 426 Jan 26 12:18 workernode_map.json 2026-01-28 22:42:59 (11248): Guest Log: -rw-------. 1 atlas atlas 1004246 Jan 26 12:18 agis_schedconf.cvmfs.json 2026-01-28 22:42:59 (11248): Guest Log: drwx------. 5 atlas atlas 4096 Jan 26 12:18 pilot3 2026-01-28 22:42:59 (11248): Guest Log: -rw-------. 1 atlas atlas 199474817 Jan 29 01:00 HITS.48329654._000123.pool.root.1 2026-01-28 22:42:59 (11248): Guest Log: -rw-------. 1 atlas atlas 1029 Jan 29 03:00 memory_monitor_summary.json 2026-01-28 22:42:59 (11248): Guest Log: -rw-------. 1 atlas atlas 1516246 Jan 29 03:00 agis_ddmendpoints.agis.ALL.json 2026-01-28 22:42:59 (11248): Guest Log: -rw-------. 1 atlas atlas 639337 Jan 29 03:00 log.48329654._000123.job.log.tgz.1 2026-01-28 22:42:59 (11248): Guest Log: -rw-------. 1 atlas atlas 115 Jan 29 03:22 pilot_heartbeat.json 2026-01-28 22:42:59 (11248): Guest Log: -rw-------. 1 atlas atlas 6375 Jan 29 03:23 heartbeat.json 2026-01-28 22:42:59 (11248): Guest Log: -rw-------. 1 atlas atlas 4680 Jan 29 03:23 pilotlog.txt 2026-01-28 22:42:59 (11248): Guest Log: -rw-------. 1 atlas atlas 4218683 Jan 29 03:23 log.48329654._000123.job.log.1 2026-01-28 22:42:59 (11248): Guest Log: -rw-------. 1 atlas atlas 357 Jan 29 03:23 output.list 2026-01-28 22:42:59 (11248): Guest Log: -rw-r--r--. 1 atlas atlas 620 Jan 29 03:23 runtime_log 2026-01-28 22:42:59 (11248): Guest Log: -rw-------. 1 atlas atlas 4874240 Jan 29 03:23 result.tar.gz 2026-01-28 22:42:59 (11248): Guest Log: -rw-r--r--. 1 atlas atlas 8762 Jan 29 03:23 runtime_log.err 2026-01-28 22:42:59 (11248): Guest Log: -rw-------. 1 atlas atlas 663 Jan 29 03:23 as0NDmlBO48n9Rq4apOajLDm4fhM0noT9bVo0NGKDmP11KDm9yrgBo.diag 2026-01-28 22:42:59 (11248): Guest Log: Looking for outputfile HITS.48329654._000123.pool.root.1 2026-01-28 22:42:59 (11248): Guest Log: HITS file was successfully produced 2026-01-28 22:42:59 (11248): Guest Log: -rw-------. 1 atlas atlas 199474817 Jan 29 01:00 /home/atlas/RunAtlas/HITS.48329654._000123.pool.root.1 2026-01-28 22:42:59 (11248): Guest Log: Successfully finished the ATLAS job! 2026-01-28 22:42:59 (11248): Guest Log: Copying the results back to the shared directory! 2026-01-28 22:42:59 (11248): Guest Log: *** Contents of shared directory: *** 2026-01-28 22:42:59 (11248): Guest Log: total 468756 2026-01-28 22:42:59 (11248): Guest Log: -rwxrwxrwx. 1 root root 275026174 Jan 26 09:00 ATLAS.root_0 2026-01-28 22:42:59 (11248): Guest Log: -rwxrwxrwx. 1 root root 199474817 Jan 29 2026 HITS.pool.root.1 2026-01-28 22:42:59 (11248): Guest Log: -rwxrwxrwx. 1 root root 8615 Jan 26 09:00 init_data.xml 2026-01-28 22:42:59 (11248): Guest Log: -rwxrwxrwx. 1 root root 597780 Jan 26 08:19 input.tar.gz 2026-01-28 22:42:59 (11248): Guest Log: -rwxrwxrwx. 1 root root 4874240 Jan 29 2026 result.tar.gz 2026-01-28 22:42:59 (11248): Guest Log: -rwxrwxrwx. 1 root root 15845 Jan 26 08:19 start_atlas.sh 2026-01-28 22:42:59 (11248): Guest Log: *** Success! Shutting down the machine. *** 2026-01-28 22:42:59 (11248): VM Completion File Detected. 2026-01-28 22:42:59 (11248): Powering off VM. 2026-01-28 22:43:04 (11248): Successfully stopped VM. 2026-01-28 22:43:04 (11248): Deregistering VM. (boinc_b7a6f1a7d769f9c4, slot#2) 2026-01-28 22:43:04 (11248): Removing network bandwidth throttle group from VM. 2026-01-28 22:43:04 (11248): Removing VM from VirtualBox. 2026-01-28 22:43:11 (11248): called boinc_finish(0) </stderr_txt> ]]>
©2026 CERN