Job instance 432414911

Name	EYAODmfXm88nsSi4ap6QjLDmwznN0nGgGQJmIVkRDmhCaKDmUz2BUo_0
Workunit	238986309
Created	7 Feb 2026, 1:47:54 UTC
Sent	7 Feb 2026, 5:09:42 UTC
Report deadline	15 Feb 2026, 5:09:42 UTC
Received	10 Feb 2026, 23:27:01 UTC
Server state	Over
Outcome	Success
Client state	Done
Exit status	0 (0x00000000)
Computer ID	10862817
Run time	4 hours 27 min 40 sec
CPU time	1 hours 14 min 33 sec
Priority	28
Validate state	Valid
Credit	296.70
Device peak FLOPS	2.84 GFLOPS
Application version	ATLAS Simulation v3.03 (vbox64_mt_mcore_atlas) windows_x86_64
Peak working set size	79.00 MB
Peak swap size	83.34 MB
Peak disk usage	1.89 GB
Stderr output

<core_client_version>8.0.2</core_client_version>
<![CDATA[
<stderr_txt>
(9888): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=8f
2026-02-10 14:21:28 (9888): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=8f
2026-02-10 14:21:28 (9888): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds
2026-02-10 14:21:28 (9888): Guest Log: vboxguest: misc device minor 58, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000)
2026-02-10 14:21:28 (9888): Guest Log: VBoxService 5.2.32 r132073 (verbosity: 0) linux.amd64 (Jul 12 2019 10:32:28) release log
2026-02-10 14:21:28 (9888): Guest Log: 00:00:00.000763 main     Log opened 2026-02-10T10:33:50.972848000Z
2026-02-10 14:21:28 (9888): Guest Log: 00:00:00.001307 main     OS Product: Linux
2026-02-10 14:21:28 (9888): Guest Log: 00:00:00.001516 main     OS Release: 3.10.0-957.27.2.el7.x86_64
2026-02-10 14:21:28 (9888): Guest Log: 00:00:00.001788 main     OS Version: #1 SMP Mon Jul 29 17:46:05 UTC 2019
2026-02-10 14:21:28 (9888): Guest Log: 00:00:00.002109 main     Executable: /opt/VBoxGuestAdditions-5.2.32/sbin/VBoxService
2026-02-10 14:21:28 (9888): Guest Log: 00:00:00.002113 main     Process ID: 1331
2026-02-10 14:21:28 (9888): Guest Log: 00:00:00.002116 main     Package type: LINUX_64BITS_GENERIC
2026-02-10 14:21:28 (9888): Guest Log: 00:00:00.074934 main     5.2.32 r132073 started. Verbose level = 0
2026-02-10 14:21:28 (9888): Guest Log: [INFO] Probing /cvmfs/atlas.cern.ch...
2026-02-10 14:21:28 (9888): Guest Log: [INFO] Mounting shared directory
2026-02-10 14:21:28 (9888): Guest Log: [INFO] Checking for init_data.xml
2026-02-10 14:21:28 (9888): Guest Log: 00:00:30.644587 timesync vgsvcTimeSyncWorker: Radical guest time change: 1 937 465 829 000ns (GuestNow=1 770 719 946 320 212 000 ns GuestLast=1 770 718 008 854 383 000 ns fSetTimeLastLoop=true )
2026-02-10 14:21:28 (9888): Guest Log: [INFO] Probing /cvmfs/atlas.cern.ch... OK
2026-02-10 14:21:28 (9888): Guest Log: [INFO] Detected branch: prod
2026-02-10 14:21:28 (9888): Guest Log: This is the prod version of the ATLAS job wrapper
2026-02-10 14:21:28 (9888): Guest Log: Copying input files
2026-02-10 14:21:28 (9888): Guest Log: Copied input files into RunAtlas.
2026-02-10 14:21:28 (9888): Guest Log: This VM did not configure a local http proxy via BOINC.
2026-02-10 14:21:28 (9888): Guest Log: Small home clusters do not require a local http proxy but it is suggested if
2026-02-10 14:21:28 (9888): Guest Log: more than 10 cores throughout the same LAN segment are regularly running ATLAS like tasks.
2026-02-10 14:21:28 (9888): Guest Log: Further information can be found at the LHC@home message board.
2026-02-10 14:21:28 (9888): Guest Log: Running cvmfs_config stat atlas.cern.ch
2026-02-10 14:21:28 (9888): 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-02-10 14:21:28 (9888): Guest Log: 2.6.3.0 1661 3 32120 156085 3 1 3117739 4096000 0 65024 0 0 n/a 0 0 http://s1cern-cvmfs.openhtc.io/cvmfs/atlas.cern.ch DIRECT 1
2026-02-10 14:21:28 (9888): Guest Log: copied the webapp to /var/www
2026-02-10 14:21:28 (9888): Guest Log: ATHENA_PROC_NUMBER=2
2026-02-10 14:21:28 (9888): Guest Log: ATHENA_CORE_NUMBER=2
2026-02-10 14:21:28 (9888): 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-02-10 14:21:28 (9888): Guest Log:  *** Starting ATLAS job. (PandaID=7005039489 taskID=48317952) ***
2026-02-10 14:21:28 (9888): Starting VM using VBoxManage interface. (boinc_876d44b9262ba17e, slot#1)
2026-02-10 14:22:04 (9888): Successfully started VM. (PID = '4928')
2026-02-10 14:22:04 (9888): Reporting VM Process ID to BOINC.
2026-02-10 14:22:04 (9888): VM state change detected. (old = 'poweredoff', new = 'running')
2026-02-10 14:22:04 (9888): Detected: Web Application Enabled (http://localhost:62712)
2026-02-10 14:22:04 (9888): Detected: Remote Desktop Enabled (localhost:62721)
2026-02-10 14:22:05 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 14:22:13 (9888): Preference change detected
2026-02-10 14:22:13 (9888): Setting CPU throttle for VM. (1%)
2026-02-10 14:22:13 (9888): Setting checkpoint interval to 3600 seconds. (Higher value of (Preference: 3600 seconds) or (Vbox_job.xml: 900 seconds))
2026-02-10 14:22:13 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 14:22:19 (9888): Guest Log: 00:09:03.291649 timesync vgsvcTimeSyncWorker: Radical host time change: 7 462 534 000 000ns (HostNow=1 770 729 738 535 000 000 ns HostLast=1 770 722 276 001 000 000 ns)
2026-02-10 14:22:29 (9888): Guest Log: 00:09:13.314476 timesync vgsvcTimeSyncWorker: Radical guest time change: 7 864 769 054 000ns (GuestNow=1 770 729 748 649 717 000 ns GuestLast=1 770 721 883 880 663 000 ns fSetTimeLastLoop=true )
2026-02-10 14:24:34 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 14:24:54 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 14:26:34 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 14:26:54 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 14:27:24 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 14:27:44 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 14:30:54 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 14:31:15 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 14:36:45 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 14:37:05 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 14:39:45 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 14:40:05 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 14:41:57 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 14:42:16 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 14:42:26 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 14:42:46 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 14:44:16 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 14:44:36 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 14:45:16 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 14:45:36 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 14:45:46 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 14:46:06 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 14:48:36 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 14:48:56 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 14:51:17 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 14:51:37 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 14:51:47 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 14:52:07 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 14:52:17 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 14:52:37 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 14:52:47 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 14:53:07 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 14:53:17 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 14:53:37 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 14:53:47 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 14:54:08 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 14:54:18 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 14:55:58 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 14:56:08 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 14:56:28 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 14:56:38 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 14:56:57 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 14:57:08 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 14:57:28 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 14:57:38 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 14:57:58 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 14:58:08 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 14:58:28 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 14:58:38 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 14:58:58 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 14:59:08 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 14:59:28 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 14:59:38 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 14:59:58 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 15:00:08 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 15:00:28 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 15:00:38 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 15:00:58 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 15:01:08 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 15:01:28 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 15:01:38 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 15:01:58 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 15:02:48 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 15:03:08 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 15:03:19 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 15:03:38 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 15:03:48 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 15:04:08 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 15:04:18 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 15:04:38 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 15:04:48 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 15:05:08 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 15:05:18 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 15:05:39 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 15:10:19 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 15:10:39 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 15:11:09 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 15:11:29 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 15:11:39 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 15:11:59 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 15:12:20 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 15:12:40 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 15:12:50 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 15:13:10 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 15:13:20 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 15:13:40 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 15:22:00 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 15:22:20 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 15:24:11 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 15:24:31 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 15:25:31 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 15:26:00 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 15:26:10 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 15:26:30 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 15:27:21 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 15:28:01 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 15:30:01 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 15:30:21 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 15:30:41 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 15:31:01 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 15:31:11 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 15:31:31 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 15:31:51 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 15:32:11 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 15:32:31 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 15:32:52 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 15:33:12 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 15:33:32 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 15:33:42 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 15:34:02 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 15:34:12 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 15:34:13 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 15:43:33 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 15:43:53 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 15:44:03 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 15:44:23 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 15:44:33 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 15:44:53 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 15:45:03 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 15:45:23 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 15:45:33 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 15:45:53 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 15:46:03 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 15:46:23 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 15:46:33 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 15:46:53 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 15:47:03 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 15:47:23 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 15:47:33 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 15:47:53 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 15:48:04 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 15:48:24 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 15:48:34 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 15:48:54 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 15:49:04 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 15:49:23 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 15:49:33 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 15:49:53 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 15:50:03 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 15:50:24 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 15:50:34 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 15:50:54 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 15:51:04 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 15:51:24 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 15:51:34 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 15:51:54 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 15:52:04 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 15:52:24 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 15:52:34 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 15:52:54 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 15:53:04 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 15:53:24 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 15:53:45 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 15:54:05 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 15:54:45 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 15:55:05 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 15:58:04 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 15:58:25 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 16:00:25 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 16:00:45 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 16:00:55 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 16:01:15 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 16:01:25 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 16:01:45 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 16:01:55 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 16:02:15 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 16:06:16 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 16:06:36 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 16:07:16 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 16:07:36 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 16:11:37 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 16:11:57 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 16:12:26 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 16:12:46 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 16:15:57 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 16:16:17 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 16:20:22 (9888): Status Report: Elapsed Time: '6000.250467'
2026-02-10 16:20:22 (9888): Status Report: CPU Time: '1687.734375'
2026-02-10 16:30:09 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 16:30:28 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 16:30:38 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 16:30:58 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 16:31:18 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 16:31:38 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 16:32:09 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 16:32:29 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 16:32:39 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 16:32:59 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 16:56:54 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 16:57:13 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 16:57:34 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 16:57:53 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 16:58:02 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 16:58:22 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 16:58:32 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 16:58:53 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 16:59:03 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 16:59:23 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 16:59:54 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 17:00:13 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 17:00:23 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 17:00:43 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 17:00:53 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 17:01:13 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 17:01:32 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 17:01:53 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 17:02:03 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 17:02:23 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 17:02:33 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 17:02:54 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 17:15:06 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 17:15:25 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 17:17:58 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 17:18:15 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 17:18:25 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 17:18:46 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 17:18:56 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 17:19:16 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 17:25:56 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 17:26:32 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 17:27:47 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 17:28:01 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 17:28:25 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 17:28:41 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 17:30:15 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 17:30:33 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 17:34:08 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 17:34:28 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 17:34:38 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 17:34:58 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 17:35:08 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 17:35:28 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 17:35:38 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 17:35:58 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 17:36:08 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 17:36:28 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 17:36:38 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 17:36:58 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 17:37:09 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 17:37:28 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 17:37:39 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 17:37:59 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 17:38:08 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 17:38:28 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 17:38:38 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 17:38:58 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 17:39:09 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 17:39:29 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 17:39:39 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 17:39:58 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 17:40:09 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 17:40:29 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 17:40:39 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 17:40:59 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 17:41:09 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 17:41:29 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 17:41:39 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 17:41:59 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 17:42:09 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 17:42:29 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 17:42:39 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 17:42:59 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 17:45:41 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 17:45:59 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 17:46:09 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 17:46:30 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 17:46:40 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 17:46:59 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 17:47:10 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 17:47:31 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 17:48:44 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 17:48:49 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 17:49:06 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 17:49:20 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 17:53:10 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 17:53:24 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 17:53:34 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 17:53:52 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 17:56:11 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 17:56:31 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 17:56:41 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 17:56:42 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 18:00:13 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 18:00:33 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 18:01:33 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 18:01:53 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 18:02:44 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 18:03:04 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 18:03:13 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 18:03:34 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 18:03:44 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 18:04:03 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 18:04:14 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 18:04:33 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 18:04:46 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 18:05:04 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 18:05:26 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 18:05:45 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 18:05:54 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 18:06:17 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 18:06:24 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 18:06:44 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 18:07:09 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 18:07:24 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 18:09:12 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 18:09:28 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 18:11:43 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 18:11:52 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 18:17:50 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 18:17:56 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 18:18:33 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 18:18:52 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 18:19:01 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 18:19:21 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 18:20:34 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 18:20:54 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 18:21:01 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 18:21:25 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 18:21:35 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 18:22:39 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 18:22:44 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 18:22:48 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 18:31:05 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 18:31:20 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 18:31:37 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 18:31:56 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 18:32:07 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 18:32:27 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 18:32:37 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 18:32:57 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 18:33:06 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 18:33:28 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 18:33:41 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 18:33:56 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 18:34:08 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 18:34:26 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 18:34:38 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 18:34:58 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 18:35:07 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 18:35:28 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 18:35:37 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 18:35:57 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 18:36:10 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 18:36:48 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 18:36:57 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 18:37:18 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 18:37:27 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 18:37:49 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 18:37:58 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 18:38:21 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 18:38:28 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 18:42:25 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 18:42:32 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 18:42:50 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 18:43:06 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 18:43:18 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 18:43:30 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 18:43:49 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 18:44:07 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 18:44:20 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 18:44:28 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 18:44:50 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 18:45:00 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 18:45:20 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 18:45:29 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 18:45:50 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 18:46:01 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 18:46:20 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 18:46:31 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 18:46:50 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 18:46:59 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 18:47:20 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 18:47:41 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 18:47:59 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 18:48:10 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 18:48:33 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 18:48:44 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 18:49:08 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 18:49:26 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 18:50:58 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 18:52:55 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 18:53:03 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 18:53:12 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 18:53:33 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 18:54:10 (9888): Status Report: Elapsed Time: '12000.565481'
2026-02-10 18:54:10 (9888): Status Report: CPU Time: '3558.328125'
2026-02-10 18:56:18 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 18:56:27 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 18:57:45 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 18:58:04 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 19:00:15 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 19:00:25 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 19:01:07 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 19:01:32 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 19:01:36 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 19:01:57 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 19:02:09 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 19:03:39 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 19:05:38 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 19:06:40 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 19:07:42 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 19:07:45 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 19:08:42 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 19:09:00 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 19:09:31 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 19:09:50 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 19:11:04 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 19:11:12 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 19:11:21 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 19:11:44 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 19:12:21 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 19:12:41 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 19:12:51 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 19:13:11 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 19:15:15 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 19:15:24 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 19:15:32 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 19:15:52 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 19:16:07 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 19:18:12 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 19:20:24 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 19:20:25 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 19:25:58 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 19:26:14 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 19:26:35 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 19:26:55 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 19:27:05 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 19:27:28 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 19:27:48 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 19:28:47 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 19:28:55 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 19:29:16 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 19:29:26 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 19:30:06 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 19:30:18 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 19:30:37 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 19:30:46 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 19:31:07 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 19:31:20 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 19:54:00 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 19:54:05 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 19:59:42 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 19:59:45 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 20:14:19 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 20:14:29 (9888): Guest Log: 03:57:38.830462 timesync vgsvcTimeSyncWorker: Radical guest time change: 2 772 748 456 000ns (GuestNow=1 770 749 995 466 002 000 ns GuestLast=1 770 747 222 717 546 000 ns fSetTimeLastLoop=true )
2026-02-10 20:19:08 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 20:19:15 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 20:19:31 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 20:19:52 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 20:20:02 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 20:20:51 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 20:21:08 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 20:21:25 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 20:21:33 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 20:22:00 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 20:22:05 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 20:22:25 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 20:22:34 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 20:37:11 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 20:37:13 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 20:37:27 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 20:37:41 (9888): Guest Log: 04:03:42.409442 timesync vgsvcTimeSyncWorker: Radical guest time change: 1 909 185 853 000ns (GuestNow=1 770 752 257 869 494 000 ns GuestLast=1 770 750 348 683 641 000 ns fSetTimeLastLoop=true )
2026-02-10 20:37:41 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 20:38:23 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 20:39:12 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 20:46:47 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 20:52:37 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 20:57:07 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 20:57:11 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 20:57:22 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 20:59:55 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 21:00:14 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 21:00:23 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 21:01:11 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 21:01:12 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 21:01:33 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 21:08:46 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 21:08:58 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 21:10:53 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 21:25:34 (9888): Error in resume VM for VM: -182
Command:
VBoxManage -q controlvm "boinc_876d44b9262ba17e" resume
Output:

2026-02-10 21:31:42 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 21:32:46 (9888): Guest Log: 04:22:39.229543 timesync vgsvcTimeSyncWorker: Radical guest time change: 2 134 425 220 000ns (GuestNow=1 770 755 518 336 337 000 ns GuestLast=1 770 753 383 911 117 000 ns fSetTimeLastLoop=true )
2026-02-10 21:32:46 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 21:42:12 (9888): Error in resume VM for VM: -182
Command:
VBoxManage -q controlvm "boinc_876d44b9262ba17e" resume
Output:

2026-02-10 21:45:00 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 21:45:35 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 21:45:48 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 21:45:55 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 21:46:15 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 21:46:25 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 21:47:44 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 21:47:55 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 21:49:36 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 21:49:45 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 21:50:05 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 21:50:15 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 21:51:15 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 21:51:56 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 21:52:36 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 21:52:48 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 21:53:05 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 21:53:25 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 21:53:45 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 21:53:55 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 21:55:03 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 21:55:15 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 21:55:36 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 21:55:46 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 21:56:06 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 21:56:17 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 21:56:40 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 21:56:46 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 21:59:27 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 21:59:36 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 22:03:17 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 22:03:27 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 22:03:47 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 22:03:57 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 22:04:17 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 22:04:27 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 23:51:15 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 23:51:17 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 23:51:54 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 23:51:58 (9888): Guest Log: 04:27:35.123902 timesync vgsvcTimeSyncWorker: Radical host time change: 6 454 697 000 000ns (HostNow=1 770 763 917 259 000 000 ns HostLast=1 770 757 462 562 000 000 ns)
2026-02-10 23:52:04 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 23:55:45 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 23:55:49 (9888): Guest Log: 04:27:45.150923 timesync vgsvcTimeSyncWorker: Radical guest time change: 6 900 174 487 000ns (GuestNow=1 770 763 927 913 361 000 ns GuestLast=1 770 757 027 738 874 000 ns fSetTimeLastLoop=true )
2026-02-10 23:55:54 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 23:56:35 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 23:56:44 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-10 23:57:04 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-10 23:57:15 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-11 00:09:19 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-11 00:09:28 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-11 00:09:48 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-11 00:09:59 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-11 00:10:20 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-11 00:10:28 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-11 00:10:51 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-11 00:11:00 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-11 00:11:20 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-11 00:11:28 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-11 00:12:49 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-11 00:13:00 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-11 00:13:19 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-11 00:13:29 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-11 00:13:49 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-11 00:13:59 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-11 00:14:20 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-11 00:14:29 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-11 00:14:50 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-11 00:14:59 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-11 00:15:20 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-11 00:15:29 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-11 00:15:50 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-11 00:16:01 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-11 00:16:20 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-11 00:16:32 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-11 00:16:50 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-11 00:17:00 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-11 00:17:20 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-11 00:17:30 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-11 00:17:50 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-11 00:18:00 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-11 00:18:20 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-11 00:18:30 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-11 00:18:50 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-11 00:19:11 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-11 00:19:50 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-11 00:20:00 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-11 00:20:20 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-11 00:20:30 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-11 00:20:50 (9888): Guest Log:  *** Job finished ***
2026-02-11 00:20:50 (9888): VM state change detected. (old = 'paused', new = 'running')
2026-02-11 00:20:51 (9888): Guest Log:  *** The last 20 lines of the pilot log: ***
2026-02-11 00:20:51 (9888): Guest Log: 2026-02-10 23:15:37,564 | INFO     | waiting for thread to finish: ['<_MainThread(MainThread, started 140296493840192)>', '<ExcThread(queue_monitor, started 140295811348224)>']
2026-02-11 00:20:51 (9888): Guest Log: 2026-02-10 23:15:39,569 | INFO     | waiting for thread to finish: ['<_MainThread(MainThread, started 140296493840192)>', '<ExcThread(queue_monitor, started 140295811348224)>']
2026-02-11 00:20:51 (9888): Guest Log: 2026-02-10 23:15:41,574 | INFO     | waiting for thread to finish: ['<_MainThread(MainThread, started 140296493840192)>', '<ExcThread(queue_monitor, started 140295811348224)>']
2026-02-11 00:20:51 (9888): Guest Log: 2026-02-10 23:15:43,582 | INFO     | waiting for thread to finish: ['<_MainThread(MainThread, started 140296493840192)>', '<ExcThread(queue_monitor, started 140295811348224)>']
2026-02-11 00:20:51 (9888): Guest Log: 2026-02-10 23:15:45,612 | INFO     | waiting for thread to finish: ['<_MainThread(MainThread, started 140296493840192)>', '<ExcThread(queue_monitor, started 140295811348224)>']
2026-02-11 00:20:51 (9888): Guest Log: 2026-02-10 23:15:47,628 | INFO     | waiting for thread to finish: ['<_MainThread(MainThread, started 140296493840192)>', '<ExcThread(queue_monitor, started 140295811348224)>']
2026-02-11 00:20:51 (9888): Guest Log: 2026-02-10 23:15:49,727 | INFO     | waiting for thread to finish: ['<_MainThread(MainThread, started 140296493840192)>', '<ExcThread(queue_monitor, started 140295811348224)>']
2026-02-11 00:20:52 (9888): Guest Log: 2026-02-10 23:15:50,755 [wrapper] The pilot process (6493) is still running after 3m. Sending SIGKILL (9).
2026-02-11 00:20:52 (9888): Guest Log: 2026-02-10 23:15:51,070 [wrapper] The pilot process (6493) is still running after 3m. Sending SIGKILL (9).
2026-02-11 00:20:52 (9888): Guest Log: 2026-02-10 23:15:52,611 [wrapper] ==== pilot stdout END ====
2026-02-11 00:20:52 (9888): Guest Log: 2026-02-10 23:15:53,448 [wrapper] ==== wrapper stdout RESUME ====
2026-02-11 00:20:52 (9888): Guest Log: 2026-02-10 23:15:54,183 [wrapper] pilotpid: 6493
2026-02-11 00:20:52 (9888): Guest Log: 2026-02-10 23:15:54,788 [wrapper] Pilot exit status: 137
2026-02-11 00:20:52 (9888): Guest Log: 2026-02-10 23:15:56,557 [wrapper] pandaids: 7005039489
2026-02-11 00:20:52 (9888): Guest Log: 2026-02-10 23:15:57,388 [wrapper] Found: wrapper_sigkill_6493, so killed by wrapper
2026-02-11 00:20:52 (9888): Guest Log: 2026-02-10 23:16:01,557 [wrapper] No supervise_pilot CHILD process found
2026-02-11 00:20:52 (9888): Guest Log: 2026-02-10 23:16:01,764 [wrapper] Test setup, not cleaning
2026-02-11 00:20:52 (9888): Guest Log: 2026-02-10 23:16:02,086 [wrapper] apfmon messages muted
2026-02-11 00:20:52 (9888): Guest Log: 2026-02-10 23:16:02,409 [wrapper] ==== wrapper stdout END ====
2026-02-11 00:20:52 (9888): Guest Log: 2026-02-10 23:16:02,808 [wrapper] ==== wrapper stderr END ====
2026-02-11 00:20:52 (9888): Guest Log:  *** Error codes and diagnostics ***
2026-02-11 00:20:52 (9888): Guest Log:  *** Listing of results directory ***
2026-02-11 00:20:54 (9888): Guest Log: total 7108
2026-02-11 00:20:54 (9888): Guest Log: -rw-r--r--. 1 atlas atlas  584627 Feb  7 01:42 pilot3.tar.gz
2026-02-11 00:20:54 (9888): Guest Log: -rw-r--r--. 1 atlas atlas    5111 Feb  7 01:43 queuedata.json
2026-02-11 00:20:54 (9888): Guest Log: -rwx------. 1 atlas atlas   36322 Feb  7 01:43 runpilot2-wrapper.sh
2026-02-11 00:20:54 (9888): Guest Log: -rwxr-xr-x. 1 atlas atlas    8959 Feb 10 11:06 init_data.xml
2026-02-11 00:20:54 (9888): Guest Log: -rwxr-xr-x. 1 atlas atlas  597942 Feb 10 11:06 input.tar.gz
2026-02-11 00:20:54 (9888): Guest Log: -rwxr-xr-x. 1 atlas atlas   15845 Feb 10 11:06 start_atlas.sh
2026-02-11 00:20:54 (9888): Guest Log: lrwxrwxrwx. 1 atlas atlas      20 Feb 10 11:06 EVNT.48317949._000285.pool.root.1 -> /data/./ATLAS.root_0
2026-02-11 00:20:54 (9888): Guest Log: -rw-r--r--. 1 atlas atlas    2523 Feb 10 11:07 pandaJob.out
2026-02-11 00:20:54 (9888): Guest Log: -rw-------. 1 atlas atlas 1512955 Feb 10 13:42 agis_ddmendpoints.agis.ALL.json
2026-02-11 00:20:54 (9888): Guest Log: -rw-------. 1 atlas atlas     429 Feb 10 13:52 workernode_map.json
2026-02-11 00:20:54 (9888): Guest Log: -rw-------. 1 atlas atlas 1009464 Feb 10 13:54 agis_schedconf.cvmfs.json
2026-02-11 00:20:54 (9888): Guest Log: drwx------. 5 atlas atlas    4096 Feb 10 13:54 pilot3
2026-02-11 00:20:54 (9888): Guest Log: -rw-------. 1 atlas atlas       0 Feb 10 22:52 wrapper_sigint_6493
2026-02-11 00:20:54 (9888): Guest Log: -rw-------. 1 atlas atlas     113 Feb 10 22:52 pilot_heartbeat.json
2026-02-11 00:20:54 (9888): Guest Log: drwxrwx---. 2 atlas atlas    4096 Feb 10 22:52 PanDA_Pilot-7005039489
2026-02-11 00:20:54 (9888): Guest Log: -rw-------. 1 atlas atlas    1028 Feb 10 22:52 memory_monitor_summary.json
2026-02-11 00:20:54 (9888): Guest Log: -rw-------. 1 atlas atlas    1180 Feb 10 22:53 heartbeat.json
2026-02-11 00:20:54 (9888): Guest Log: -rw-------. 1 atlas atlas 1133315 Feb 10 23:15 pilotlog.txt
2026-02-11 00:20:55 (9888): Guest Log: -rw-------. 1 atlas atlas       0 Feb 10 23:15 wrapper_sigkill_6493
2026-02-11 00:20:55 (9888): Guest Log: -rw-------. 1 atlas atlas 1146679 Feb 10 23:16 log.48317952._015126.job.log.1
2026-02-11 00:20:55 (9888): Guest Log: -rw-r--r--. 1 atlas atlas     571 Feb 10 23:16 runtime_log
2026-02-11 00:20:55 (9888): Guest Log: -rw-------. 1 atlas atlas 1157120 Feb 10 23:16 result.tar.gz
2026-02-11 00:20:55 (9888): Guest Log: -rw-r--r--. 1 atlas atlas    8148 Feb 10 23:16 runtime_log.err
2026-02-11 00:20:55 (9888): Guest Log: -rw-------. 1 atlas atlas     675 Feb 10 23:16 EYAODmfXm88nsSi4ap6QjLDmwznN0nGgGQJmIVkRDmhCaKDmUz2BUo.diag
2026-02-11 00:20:56 (9888): Guest Log: Looking for outputfile HITS.48317952._015126.pool.root.1
2026-02-11 00:20:56 (9888): Guest Log: No HITS file was produced
2026-02-11 00:20:56 (9888): Guest Log: Successfully finished the ATLAS job!
2026-02-11 00:20:56 (9888): Guest Log: Copying the results back to the shared directory!
2026-02-11 00:21:00 (9888): VM state change detected. (old = 'running', new = 'paused')
2026-02-11 00:21:43 (9888): VM state change detected. (old = '