Name XvdMDm0VOe7nsSi4ap6QjLDmwznN0nGgGQJmUXzaDm2KbKDm8HzP6n_2
Workunit 232657455
Created 2 Jun 2025, 7:22:20 UTC
Sent 2 Jun 2025, 7:22:25 UTC
Report deadline 10 Jun 2025, 7:22:25 UTC
Received 4 Jun 2025, 0:50:03 UTC
Server state Over
Outcome Success
Client state Done
Exit status 0 (0x00000000)
Computer ID 10576326
Run time 1 days 12 hours 5 min 20 sec
CPU time 3 days 20 hours 56 min 4 sec
Validate state Valid
Credit 654.13
Device peak FLOPS 9.17 GFLOPS
Application version ATLAS Simulation v3.01 (vbox64_mt_mcore_atlas)
windows_x86_64
Peak working set size 67.49 MB
Peak swap size 67.92 MB
Peak disk usage 3.48 GB

Stderr output

<core_client_version>7.12.1</core_client_version>
<![CDATA[
<stderr_txt>
REVISION EXPIRES(M) NOCATALOGS CACHEUSE(K) CACHEMAX(K) NOFDUSE NOFDMAX NOIOERR NOOPEN HITRATE(%) RX(K) SPEED(K/S) HOST PROXY ONLINE
2025-06-03 14:46:11 (27064): Guest Log: 2.6.3.0 1593 0 31956 146725 4 1 2626109 4096000 0 65024 0 0 n/a 1 0 http://s1ral-cvmfs.openhtc.io/cvmfs/atlas.cern.ch DIRECT 1
2025-06-03 14:46:11 (27064): Guest Log: copied the webapp to /var/www
2025-06-03 14:46:11 (27064): Guest Log: ATHENA_PROC_NUMBER=3
2025-06-03 14:46:11 (27064): Guest Log: ATHENA_CORE_NUMBER=3
2025-06-03 14:46:11 (27064): Guest Log: Apptainer command /cvmfs/atlas.cern.ch/repo/containers/sw/apptainer/x86_64-el7/current/bin/apptainer exec -B /cvmfs,/data,/home/atlas/RunAtlas /cvmfs/atlas.cern.ch/repo/containers/fs/singularity/x86_64-centos7 
2025-06-03 14:46:11 (27064): Guest Log:  *** Starting ATLAS job. (PandaID=6668048742 taskID=44871837) ***
2025-06-03 14:46:11 (27064): Starting VM using VBoxManage interface. (boinc_14bc23e1c6e06f8c, slot#2)
2025-06-03 14:46:40 (27064): Successfully started VM. (PID = '30224')
2025-06-03 14:46:40 (27064): Reporting VM Process ID to BOINC.
2025-06-03 14:46:40 (27064): VM state change detected. (old = 'poweredoff', new = 'running')
2025-06-03 14:46:40 (27064): Detected: Web Application Enabled (http://localhost:61765)
2025-06-03 14:46:41 (27064): VM state change detected. (old = 'running', new = 'paused')
2025-06-03 14:46:51 (27064): Status Report: Elapsed Time: '59113.315803'
2025-06-03 14:46:51 (27064): Status Report: CPU Time: '154318.156250'
2025-06-03 14:46:51 (27064): Preference change detected
2025-06-03 14:46:51 (27064): Setting CPU throttle for VM. (90%)
2025-06-03 14:46:51 (27064): Setting checkpoint interval to 900 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 900 seconds))
2025-06-03 14:46:52 (27064): VM state change detected. (old = 'paused', new = 'running')
2025-06-03 14:47:24 (27064): VM state change detected. (old = 'running', new = 'paused')
2025-06-03 14:47:31 (27064): VM state change detected. (old = 'paused', new = 'running')
2025-06-03 14:51:03 (27064): Stopping VM.
2025-06-03 14:51:37 (27064): Successfully stopped VM.
2025-06-03 14:51:38 (27580): Detected: vboxwrapper 26206
2025-06-03 14:51:38 (27580): Detected: BOINC client v7.12.1
2025-06-03 14:51:39 (27580): Detected: VirtualBox VboxManage Interface (Version: 7.1.8)
2025-06-03 14:51:40 (27580): Starting VM using VBoxManage interface. (boinc_14bc23e1c6e06f8c, slot#2)
2025-06-03 14:52:08 (27580): Successfully started VM. (PID = '33816')
2025-06-03 14:52:08 (27580): Reporting VM Process ID to BOINC.
2025-06-03 14:52:08 (27580): VM state change detected. (old = 'poweredoff', new = 'running')
2025-06-03 14:52:08 (27580): Detected: Web Application Enabled (http://localhost:61765)
2025-06-03 14:52:09 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-03 14:52:11 (27580): Status Report: Elapsed Time: '59329.467201'
2025-06-03 14:52:11 (27580): Status Report: CPU Time: '154959.015625'
2025-06-03 14:52:11 (27580): Preference change detected
2025-06-03 14:52:11 (27580): Setting CPU throttle for VM. (90%)
2025-06-03 14:52:15 (27580): Setting checkpoint interval to 900 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 900 seconds))
2025-06-03 14:52:15 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-03 14:52:23 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-03 14:52:42 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-03 14:52:52 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-03 14:53:42 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-03 14:54:10 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-03 14:54:53 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-03 14:55:04 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-03 14:55:46 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-03 14:55:53 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-03 14:56:03 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-03 14:56:42 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-03 14:56:52 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-03 14:57:03 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-03 14:57:13 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-03 14:57:45 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-03 14:57:53 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-03 14:58:27 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-03 14:58:53 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-03 14:59:03 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-03 14:59:22 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-03 14:59:28 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-03 14:59:33 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-03 14:59:43 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-03 15:00:34 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-03 15:00:49 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-03 15:01:14 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-03 15:01:53 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-03 15:02:14 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-03 15:05:16 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-03 15:05:25 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-03 15:07:27 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-03 15:07:45 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-03 15:08:09 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-03 15:08:24 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-03 15:08:52 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-03 15:09:35 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-03 15:10:32 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-03 15:10:43 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-03 15:10:45 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-03 15:10:55 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-03 15:11:31 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-03 15:11:56 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-03 15:12:26 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-03 15:12:46 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-03 15:17:07 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-03 15:17:55 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-03 15:18:14 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-03 15:19:07 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-03 15:19:17 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-03 15:19:27 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-03 15:19:38 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-03 15:19:47 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-03 15:19:58 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-03 15:20:19 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-03 15:20:38 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-03 15:20:48 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-03 15:21:09 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-03 15:21:27 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-03 15:30:48 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-03 15:30:58 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-03 15:31:39 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-03 15:31:49 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-03 15:35:39 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-03 15:36:10 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-03 15:37:03 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-03 15:37:20 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-03 15:40:21 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-03 15:40:30 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-03 15:41:05 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-03 15:41:20 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-03 15:47:04 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-03 15:47:22 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-03 15:50:25 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-03 15:50:26 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-03 15:50:43 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-03 15:50:52 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-03 15:55:33 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-03 15:55:43 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-03 15:56:03 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-03 15:56:23 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-03 15:58:44 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-03 15:58:53 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-03 16:01:24 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-03 16:01:34 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-03 16:03:14 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-03 16:03:23 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-03 16:05:34 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-03 16:05:44 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-03 16:05:56 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-03 16:06:14 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-03 16:08:32 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-03 16:08:45 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-03 16:10:35 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-03 16:10:45 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-03 16:19:16 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-03 16:19:26 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-03 16:19:45 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-03 16:19:47 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-03 16:19:56 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-03 16:20:06 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-03 16:20:38 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-03 16:20:46 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-03 16:21:09 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-03 16:21:17 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-03 16:21:46 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-03 16:21:56 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-03 16:22:57 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-03 16:23:37 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-03 16:24:26 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-03 16:25:19 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-03 16:25:28 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-03 16:25:38 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-03 16:25:49 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-03 16:25:59 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-03 16:26:24 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-03 16:26:48 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-03 16:27:48 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-03 16:27:58 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-03 16:28:41 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-03 16:28:48 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-03 16:29:39 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-03 16:29:49 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-03 16:30:19 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-03 16:30:29 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-03 16:33:33 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-03 16:33:34 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-03 16:33:40 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-03 16:33:49 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-03 16:34:10 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-03 16:34:24 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-03 16:34:32 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-03 16:34:39 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-03 16:35:28 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-03 16:35:59 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-03 16:36:15 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-03 16:36:40 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-03 16:37:40 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-03 16:37:50 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-03 16:38:20 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-03 16:38:30 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-03 16:38:50 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-03 16:39:00 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-03 16:39:10 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-03 16:39:21 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-03 16:39:51 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-03 16:40:01 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-03 16:41:31 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-03 16:41:41 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-03 16:46:11 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-03 16:46:21 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-03 16:48:05 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-03 16:48:21 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-03 16:51:59 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-03 16:52:26 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-03 16:52:30 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-03 16:52:43 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-03 16:58:44 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-03 16:58:54 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-03 17:00:14 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-03 17:00:24 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-03 17:02:34 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-03 17:02:44 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-03 17:05:37 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-03 17:06:11 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-03 17:06:30 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-03 17:06:45 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-03 17:07:05 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-03 17:07:15 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-03 17:09:27 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-03 17:09:46 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-03 17:10:43 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-03 17:10:45 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-03 17:11:00 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-03 17:11:16 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-03 17:11:34 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-03 17:11:56 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-03 17:12:19 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-03 17:12:45 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-03 17:15:16 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-03 17:15:33 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-03 17:15:37 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-03 17:16:07 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-03 17:16:50 (27580): Status Report: Elapsed Time: '65329.713882'
2025-06-03 17:16:50 (27580): Status Report: CPU Time: '173076.093750'
2025-06-03 17:19:10 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-03 17:20:06 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-03 17:20:12 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-03 17:20:18 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-03 17:20:46 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-03 17:21:24 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-03 17:21:25 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-03 17:22:08 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-03 17:22:34 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-03 17:22:38 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-03 17:22:52 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-03 17:23:38 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-03 17:23:58 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-03 17:24:59 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-03 17:25:08 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-03 17:25:18 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-03 17:25:44 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-03 17:26:48 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-03 17:28:12 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-03 17:29:44 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-03 17:30:19 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-03 17:31:04 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-03 17:31:30 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-03 17:31:59 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-03 17:32:30 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-03 17:32:59 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-03 17:33:20 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-03 17:33:30 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-03 17:34:48 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-03 17:35:23 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-03 17:35:37 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-03 17:35:50 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-03 17:37:02 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-03 17:37:12 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-03 17:37:22 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-03 17:38:23 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-03 17:38:49 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-03 17:38:56 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-03 17:39:02 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-03 17:39:11 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-03 17:39:31 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-03 17:39:41 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-03 17:39:52 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-03 17:40:02 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-03 17:40:21 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-03 17:40:31 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-03 17:40:42 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-03 17:41:02 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-03 17:41:13 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-03 17:41:21 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-03 17:41:42 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-03 17:41:52 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-03 17:43:10 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-03 17:43:12 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-03 17:43:35 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-03 17:43:43 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-03 17:43:52 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-03 17:44:02 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-03 17:45:24 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-03 17:45:42 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-03 17:46:03 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-03 17:46:08 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-03 17:46:16 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-03 17:46:22 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-03 17:47:24 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-03 17:47:33 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-03 17:48:05 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-03 17:48:13 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-03 17:49:53 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-03 17:51:43 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-03 17:52:29 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-03 17:54:54 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-03 17:55:22 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-03 17:55:31 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-03 17:56:12 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-03 17:56:48 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-03 17:57:25 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-03 17:58:19 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-03 17:58:27 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-03 17:59:06 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-03 17:59:26 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-03 17:59:46 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-03 18:00:07 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-03 18:00:26 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-03 18:00:40 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-03 18:01:07 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-03 18:01:21 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-03 18:01:56 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-03 18:02:06 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-03 18:02:17 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-03 18:02:46 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-03 18:03:25 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-03 18:03:49 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-03 18:03:50 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-03 18:04:01 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-03 18:04:37 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-03 18:04:54 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-03 18:04:58 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-03 18:05:38 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-03 18:05:48 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-03 18:07:30 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-03 18:07:38 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-03 18:08:04 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-03 18:08:28 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-03 18:09:29 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-03 18:09:38 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-03 18:11:21 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-03 18:11:29 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-03 18:11:49 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-03 18:12:31 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-03 18:12:39 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-03 18:13:53 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-03 18:14:24 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-03 18:15:23 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-03 18:15:31 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-03 18:16:00 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-03 18:16:11 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-03 18:16:30 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-03 18:16:40 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-03 18:18:12 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-03 18:18:26 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-03 18:18:39 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-03 18:19:14 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-03 18:21:11 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-03 18:21:38 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-03 18:22:07 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-03 18:22:22 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-03 18:23:32 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-03 18:24:03 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-03 18:24:22 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-03 18:24:42 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-03 18:25:10 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-03 18:25:23 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-03 18:26:23 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-03 18:26:33 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-03 18:26:52 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-03 18:27:03 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-03 18:27:13 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-03 18:27:22 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-03 18:27:32 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-03 18:27:53 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-03 18:28:03 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-03 18:28:13 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-03 18:28:33 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-03 18:28:53 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-03 18:29:03 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-03 18:29:15 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-03 18:29:41 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-03 18:29:43 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-03 18:29:53 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-03 18:30:06 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-03 18:31:13 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-03 18:31:31 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-03 18:31:53 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-03 18:32:19 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-03 18:32:30 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-03 18:32:33 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-03 18:33:33 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-03 18:33:44 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-03 18:33:54 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-03 18:34:38 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-03 18:35:04 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-03 18:35:24 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-03 18:35:54 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-03 18:36:05 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-03 18:36:15 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-03 18:36:29 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-03 18:37:21 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-03 18:37:26 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-03 18:38:56 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-03 18:39:10 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-03 18:39:46 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-03 18:40:06 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-03 18:40:28 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-03 18:40:43 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-03 18:40:55 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-03 18:41:07 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-03 18:41:16 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-03 18:41:29 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-03 18:41:46 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-03 19:56:20 (27580): Status Report: Elapsed Time: '71330.416555'
2025-06-03 19:56:20 (27580): Status Report: CPU Time: '190718.765625'
2025-06-03 20:21:09 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-03 20:21:19 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-03 20:47:21 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-03 20:47:32 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-03 21:23:27 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-03 21:23:36 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-03 21:39:33 (27580): Status Report: Elapsed Time: '77330.416555'
2025-06-03 21:39:33 (27580): Status Report: CPU Time: '207167.859375'
2025-06-03 21:42:59 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-03 21:43:09 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-03 22:42:07 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-03 22:42:17 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-03 23:16:21 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-03 23:16:31 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-03 23:22:54 (27580): Status Report: Elapsed Time: '83330.800365'
2025-06-03 23:22:54 (27580): Status Report: CPU Time: '223654.640625'
2025-06-03 23:42:33 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-03 23:42:43 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-03 23:42:57 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-03 23:43:24 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-04 00:33:10 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-04 00:33:20 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-04 01:03:14 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-04 01:03:24 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-04 01:06:52 (27580): Status Report: Elapsed Time: '89331.143150'
2025-06-04 01:06:52 (27580): Status Report: CPU Time: '240143.031250'
2025-06-04 01:09:44 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-04 01:09:55 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-04 01:21:26 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-04 01:21:48 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-04 01:23:26 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-04 01:23:36 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-04 02:21:04 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-04 02:21:14 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-04 02:50:35 (27580): Status Report: Elapsed Time: '95331.782543'
2025-06-04 02:50:35 (27580): Status Report: CPU Time: '256610.625000'
2025-06-04 03:40:54 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-04 03:41:04 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-04 04:34:34 (27580): Status Report: Elapsed Time: '101332.626264'
2025-06-04 04:34:34 (27580): Status Report: CPU Time: '273238.015625'
2025-06-04 04:43:31 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-04 04:43:41 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-04 05:15:05 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-04 05:15:15 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-04 06:17:40 (27580): Status Report: Elapsed Time: '107333.221407'
2025-06-04 06:17:40 (27580): Status Report: CPU Time: '289698.171875'
2025-06-04 07:17:11 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-04 07:17:20 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-04 07:39:54 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-04 07:40:13 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-04 07:45:44 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-04 07:45:54 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-04 07:47:05 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-04 07:47:14 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-04 08:01:22 (27580): Status Report: Elapsed Time: '113333.666382'
2025-06-04 08:01:22 (27580): Status Report: CPU Time: '306182.656250'
2025-06-04 08:17:19 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-04 08:17:29 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-04 08:21:09 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-04 08:21:19 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-04 08:33:11 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-04 08:33:30 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-04 08:47:12 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-04 08:47:22 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-04 08:48:22 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-04 08:48:32 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-04 09:04:39 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-04 09:05:21 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-04 09:05:27 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-04 09:05:56 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-04 09:06:06 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-04 09:08:13 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-04 09:08:37 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-04 09:09:10 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-04 09:09:18 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-04 09:09:56 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-04 09:10:07 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-04 09:10:17 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-04 09:10:48 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-04 09:11:17 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-04 09:12:18 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-04 09:12:37 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-04 09:13:07 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-04 09:13:17 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-04 09:13:48 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-04 09:14:17 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-04 09:14:42 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-04 09:15:07 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-04 09:15:20 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-04 09:15:48 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-04 09:15:59 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-04 09:16:17 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-04 09:16:31 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-04 09:17:49 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-04 09:17:59 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-04 09:18:28 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-04 09:18:58 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-04 09:19:28 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-04 09:19:54 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-04 09:20:09 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-04 09:20:37 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-04 09:20:38 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-04 09:20:39 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-04 09:21:08 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-04 09:21:48 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-04 09:21:58 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-04 09:22:09 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-04 09:22:19 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-04 09:22:29 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-04 09:22:59 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-04 09:23:09 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-04 09:23:49 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-04 09:23:59 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-04 09:24:29 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-04 09:24:39 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-04 09:25:09 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-04 09:25:39 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-04 09:25:49 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-04 09:26:00 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-04 09:27:10 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-04 09:32:40 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-04 09:36:18 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-04 09:36:28 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-04 09:36:31 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-04 09:42:03 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-04 09:42:12 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-04 09:42:53 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-04 09:43:02 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-04 09:44:03 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-04 09:44:13 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-04 09:45:33 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-04 09:45:42 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-04 09:46:43 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-04 09:46:53 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-04 09:47:36 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-04 09:47:43 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-04 09:48:07 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-04 09:48:23 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-04 09:49:43 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-04 09:50:23 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-04 09:51:30 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-04 09:51:43 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-04 09:52:04 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-04 09:52:14 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-04 09:52:36 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-04 09:52:53 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-04 09:55:04 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-04 09:55:14 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-04 09:56:28 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-04 09:56:44 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-04 09:58:44 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-04 09:58:55 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-04 10:01:25 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-04 10:02:30 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-04 10:03:39 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-04 10:04:36 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-04 10:04:57 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-04 10:05:06 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-04 10:05:17 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-04 10:05:30 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-04 10:05:36 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-04 10:06:16 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-04 10:07:44 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-04 10:09:38 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-04 10:10:05 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-04 10:10:37 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-04 10:11:13 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-04 10:12:08 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-04 10:12:18 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-04 10:12:39 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-04 10:13:11 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-04 10:13:29 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-04 10:14:35 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-04 10:15:19 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-04 10:15:40 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-04 10:15:59 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-04 10:16:09 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-04 10:16:20 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-04 10:16:50 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-04 10:17:10 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-04 10:18:02 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-04 10:18:10 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-04 10:21:10 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-04 10:21:20 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-04 10:26:16 (27580): Status Report: Elapsed Time: '119333.928894'
2025-06-04 10:26:16 (27580): Status Report: CPU Time: '323701.890625'
2025-06-04 10:31:33 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-04 10:31:41 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-04 10:32:11 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-04 10:32:22 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-04 10:58:45 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-04 10:59:04 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-04 11:20:29 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-04 11:20:47 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-04 11:22:08 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-04 11:22:48 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-04 11:24:30 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-04 11:24:31 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-04 11:24:43 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-04 11:24:53 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-04 11:25:04 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-04 11:25:59 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-04 11:27:19 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-04 11:27:29 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-04 11:28:29 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-04 11:28:40 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-04 11:28:52 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-04 11:29:40 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-04 11:30:08 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-04 11:30:30 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-04 11:35:25 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-04 11:36:00 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-04 11:37:12 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-04 11:37:31 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-04 11:37:52 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-04 11:38:41 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-04 11:39:01 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-04 11:39:11 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-04 11:39:21 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-04 11:39:31 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-04 11:41:11 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-04 11:41:21 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-04 11:43:11 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-04 11:43:31 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-04 11:44:02 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-04 11:44:23 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-04 11:44:31 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-04 11:44:41 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-04 11:44:52 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-04 11:45:02 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-04 11:45:22 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-04 11:46:22 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-04 11:46:32 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-04 11:46:42 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-04 11:47:01 (27580): VM state change detected. (old = 'running', new = 'paused')
2025-06-04 11:47:22 (27580): VM state change detected. (old = 'paused', new = 'running')
2025-06-04 11:47:46 (27580): Guest Log:  *** Job finished ***
2025-06-04 11:47:46 (27580): Guest Log:  *** The last 20 lines of the pilot log: ***
2025-06-04 11:47:46 (27580): Guest Log: 2025-06-03 23:42:32,954 | WARNING  | job:queue_monitor:received graceful stop - abort after this iteration
2025-06-04 11:47:46 (27580): Guest Log: 2025-06-03 23:42:33,956 | INFO     | [job] queue monitor thread has finished
2025-06-04 11:47:46 (27580): Guest Log: 2025-06-03 23:42:35,204 | INFO     | [data] queue_monitor thread has finished
2025-06-04 11:47:46 (27580): Guest Log: 2025-06-03 23:42:36,497 | INFO     | only monitor.control thread still running - safe to abort: ['<_MainThread(MainThread, started 140502707263296)>', '<ExcThread(monitor, started 140502112388864)>']
2025-06-04 11:47:46 (27580): Guest Log: 2025-06-03 23:42:37,229 | WARNING  | job_aborted has been set - aborting pilot monitoring
2025-06-04 11:47:46 (27580): Guest Log: 2025-06-03 23:42:37,230 | INFO     | [monitor] control thread has ended
2025-06-04 11:47:46 (27580): Guest Log: 2025-06-03 23:42:41,507 | INFO     | all workflow threads have been joined
2025-06-04 11:47:46 (27580): Guest Log: 2025-06-03 23:42:41,510 | INFO     | end of generic workflow (traces error code: 0)
2025-06-04 11:47:46 (27580): Guest Log: 2025-06-03 23:42:41,511 | INFO     | traces error code: 0
2025-06-04 11:47:46 (27580): Guest Log: 2025-06-03 23:42:41,513 | INFO     | pilot has finished (exit code=0, shell exit code=0)
2025-06-04 11:47:46 (27580): Guest Log: 2025-06-03 23:42:41,790 [wrapper] ==== pilot stdout END ====
2025-06-04 11:47:46 (27580): Guest Log: 2025-06-03 23:42:41,801 [wrapper] ==== wrapper stdout RESUME ====
2025-06-04 11:47:46 (27580): Guest Log: 2025-06-03 23:42:41,812 [wrapper] pilotpid: 5896
2025-06-04 11:47:46 (27580): Guest Log: 2025-06-03 23:42:41,824 [wrapper] Pilot exit status: 0
2025-06-04 11:47:46 (27580): Guest Log: 2025-06-03 23:42:42,009 [wrapper] pandaids: 6668048742
2025-06-04 11:47:46 (27580): Guest Log: 2025-06-03 23:42:42,089 [wrapper] cleanup supervisor_pilot 22181 5897
2025-06-04 11:47:46 (27580): Guest Log: 2025-06-03 23:42:42,101 [wrapper] Test setup, not cleaning
2025-06-04 11:47:46 (27580): Guest Log: 2025-06-03 23:42:42,114 [wrapper] apfmon messages muted
2025-06-04 11:47:46 (27580): Guest Log: 2025-06-03 23:42:42,126 [wrapper] ==== wrapper stdout END ====
2025-06-04 11:47:46 (27580): Guest Log: 2025-06-03 23:42:42,138 [wrapper] ==== wrapper stderr END ====
2025-06-04 11:47:46 (27580): Guest Log:  *** Error codes and diagnostics ***
2025-06-04 11:47:46 (27580): Guest Log:     "exeErrorCode": 0,
2025-06-04 11:47:46 (27580): Guest Log:     "exeErrorDiag": "",
2025-06-04 11:47:46 (27580): Guest Log:     "pilotErrorCode": 0,
2025-06-04 11:47:46 (27580): Guest Log:     "pilotErrorDiag": "",
2025-06-04 11:47:46 (27580): Guest Log:  *** Listing of results directory ***
2025-06-04 11:47:46 (27580): Guest Log: total 208124
2025-06-04 11:47:46 (27580): Guest Log: -rw-r--r--. 1 atlas atlas    495897 May 28 04:31 pilot3.tar.gz
2025-06-04 11:47:46 (27580): Guest Log: -rw-r--r--. 1 atlas atlas      5111 May 28 05:25 queuedata.json
2025-06-04 11:47:46 (27580): Guest Log: -rwx------. 1 atlas atlas     37140 May 28 05:28 runpilot2-wrapper.sh
2025-06-04 11:47:46 (27580): Guest Log: -rwxr-xr-x. 1 atlas atlas      9834 Jun  3 01:39 init_data.xml
2025-06-04 11:47:46 (27580): Guest Log: -rwxr-xr-x. 1 atlas atlas    508884 Jun  3 01:39 input.tar.gz
2025-06-04 11:47:46 (27580): Guest Log: -rwxr-xr-x. 1 atlas atlas     15093 Jun  3 01:39 start_atlas.sh
2025-06-04 11:47:46 (27580): Guest Log: lrwxrwxrwx. 1 atlas atlas        20 Jun  3 01:39 EVNT.44871834._003097.pool.root.1 -> /data/./ATLAS.root_0
2025-06-04 11:47:46 (27580): Guest Log: -rw-r--r--. 1 atlas atlas      2559 Jun  3 01:39 pandaJob.out
2025-06-04 11:47:46 (27580): Guest Log: -rw-------. 1 atlas atlas    988073 Jun  3 01:40 agis_schedconf.cvmfs.json
2025-06-04 11:47:46 (27580): Guest Log: drwx------. 4 atlas atlas      4096 Jun  3 01:40 pilot3
2025-06-04 11:47:46 (27580): Guest Log: -rw-------. 1 atlas atlas 196723802 Jun  3 23:40 HITS.44871837._079247.pool.root.1
2025-06-04 11:47:46 (27580): Guest Log: -rw-------. 1 atlas atlas      1029 Jun  3 23:41 memory_monitor_summary.json
2025-06-04 11:47:46 (27580): Guest Log: -rw-------. 1 atlas atlas   1603667 Jun  3 23:41 agis_ddmendpoints.agis.ALL.json
2025-06-04 11:47:46 (27580): Guest Log: -rw-------. 1 atlas atlas    781890 Jun  3 23:41 log.44871837._079247.job.log.tgz.1
2025-06-04 11:47:46 (27580): Guest Log: -rw-------. 1 atlas atlas        97 Jun  3 23:42 pilot_heartbeat.json
2025-06-04 11:47:46 (27580): Guest Log: -rw-------. 1 atlas atlas      7698 Jun  3 23:42 heartbeat.json
2025-06-04 11:47:46 (27580): Guest Log: -rw-------. 1 atlas atlas      4599 Jun  3 23:42 pilotlog.txt
2025-06-04 11:47:46 (27580): Guest Log: -rw-------. 1 atlas atlas   5530188 Jun  3 23:42 log.44871837._079247.job.log.1
2025-06-04 11:47:46 (27580): Guest Log: -rw-------. 1 atlas atlas       357 Jun  3 23:42 output.list
2025-06-04 11:47:46 (27580): Guest Log: -rw-r--r--. 1 atlas atlas       620 Jun  3 23:42 runtime_log
2025-06-04 11:47:46 (27580): Guest Log: -rw-------. 1 atlas atlas   6328320 Jun  3 23:42 result.tar.gz
2025-06-04 11:47:46 (27580): Guest Log: -rw-------. 1 atlas atlas       666 Jun  3 23:42 XvdMDm0VOe7nsSi4ap6QjLDmwznN0nGgGQJmUXzaDm2KbKDm8HzP6n.diag
2025-06-04 11:47:46 (27580): Guest Log: -rw-r--r--. 1 atlas atlas      8601 Jun  3 23:42 runtime_log.err
2025-06-04 11:47:46 (27580): Guest Log: Looking for outputfile HITS.44871837._079247.pool.root.1
2025-06-04 11:47:46 (27580): Guest Log: HITS file was successfully produced
2025-06-04 11:47:46 (27580): Guest Log: -rw-------. 1 atlas atlas 196723802 Jun  3 23:40 /home/atlas/Ru


©2025 CERN