Job instance 431997463

Name	ZQTLDmoOW48nsSi4ap6QjLDmwznN0nGgGQJmNLGKDmDU9KDm9DNYfm_1
Workunit	238720027
Created	26 Jan 2026, 20:10:29 UTC
Sent	27 Jan 2026, 4:18:04 UTC
Report deadline	4 Feb 2026, 4:18:04 UTC
Received	28 Jan 2026, 11:22:39 UTC
Server state	Over
Outcome	Success
Client state	Done
Exit status	0 (0x00000000)
Computer ID	10862817
Run time	42 min 0 sec
CPU time	42 min 42 sec
Priority	28
Validate state	Valid
Credit	40.84
Device peak FLOPS	2.84 GFLOPS
Application version	ATLAS Simulation v3.03 (vbox64_mt_mcore_atlas) windows_x86_64
Peak working set size	80.56 MB
Peak swap size	78.23 MB
Peak disk usage	1.19 GB
Stderr output

<core_client_version>8.0.2</core_client_version>
<![CDATA[
<stderr_txt>
c.io/cvmfs/atlas.cern.ch DIRECT 1
2026-01-28 02:36:19 (7532): Guest Log: copied the webapp to /var/www
2026-01-28 02:36:19 (7532): Guest Log: ATHENA_PROC_NUMBER=2
2026-01-28 02:36:19 (7532): Guest Log: ATHENA_CORE_NUMBER=2
2026-01-28 02:36:19 (7532): Guest Log: Apptainer command /cvmfs/atlas.cern.ch/repo/containers/sw/apptainer/x86_64-el7/current/bin/apptainer exec -B /cvmfs,/data,/home/atlas/RunAtlas /cvmfs/atlas.cern.ch/repo/containers/fs/singularity/x86_64-centos7 
2026-01-28 02:36:19 (7532): Guest Log:  *** Starting ATLAS job. (PandaID=6984062472 taskID=48317931) ***
2026-01-28 02:36:19 (7532): Starting VM using VBoxManage interface. (boinc_02563035265b9e3f, slot#0)
2026-01-28 02:37:19 (7532): Successfully started VM. (PID = '9316')
2026-01-28 02:37:19 (7532): Reporting VM Process ID to BOINC.
2026-01-28 02:37:20 (7532): VM state change detected. (old = 'poweredoff', new = 'running')
2026-01-28 02:37:20 (7532): Detected: Web Application Enabled (http://localhost:63403)
2026-01-28 02:37:20 (7532): Detected: Remote Desktop Enabled (localhost:63404)
2026-01-28 02:37:23 (7532): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 02:37:27 (7532): Preference change detected
2026-01-28 02:37:28 (7532): Setting CPU throttle for VM. (1%)
2026-01-28 02:37:29 (7532): Setting checkpoint interval to 3600 seconds. (Higher value of (Preference: 3600 seconds) or (Vbox_job.xml: 900 seconds))
2026-01-28 02:37:29 (7532): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 02:37:30 (7532): Guest Log: 00:06:42.034137 timesync vgsvcTimeSyncWorker: Radical host time change: 12 367 300 000 000ns (HostNow=1 769 564 250 383 000 000 ns HostLast=1 769 551 883 083 000 000 ns)
2026-01-28 02:37:38 (7532): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 03:56:08 (7532): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 03:56:10 (7532): Guest Log: 00:06:52.104086 timesync vgsvcTimeSyncWorker: Radical host time change: 4 720 320 000 000ns (HostNow=1 769 568 970 703 000 000 ns HostLast=1 769 564 250 383 000 000 ns)
2026-01-28 03:56:10 (7532): Guest Log: 00:06:52.104591 timesync vgsvcTimeSyncWorker: Radical guest time change: 12 531 652 042 000ns (GuestNow=1 769 564 260 412 947 000 ns GuestLast=1 769 551 728 760 905 000 ns fSetTimeLastLoop=true )
2026-01-28 03:56:18 (7532): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 03:56:38 (7532): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 03:56:40 (7532): Guest Log: 00:07:02.124362 timesync vgsvcTimeSyncWorker: Radical guest time change: 4 720 349 896 000ns (GuestNow=1 769 568 980 762 843 000 ns GuestLast=1 769 564 260 412 947 000 ns fSetTimeLastLoop=true )
2026-01-28 03:56:48 (7532): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 03:57:28 (7532): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 03:57:39 (7532): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 03:57:59 (7532): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 03:58:08 (7532): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 03:58:30 (7532): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 03:58:49 (7532): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 03:59:10 (7532): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 03:59:30 (7532): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 03:59:51 (7532): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 04:00:20 (7532): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 04:00:59 (7532): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 04:01:20 (7532): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 04:01:41 (7532): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 04:02:03 (7532): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 04:02:58 (7532): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 04:04:19 (7532): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 04:04:40 (7532): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 04:04:59 (7532): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 04:05:19 (7532): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 04:05:29 (7532): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 04:05:49 (7532): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 04:05:59 (7532): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 04:06:19 (7532): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 04:06:30 (7532): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 04:06:50 (7532): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 04:07:00 (7532): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 04:07:20 (7532): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 04:07:29 (7532): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 04:07:50 (7532): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 04:07:59 (7532): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 04:08:19 (7532): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 04:08:29 (7532): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 04:08:51 (7532): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 04:09:23 (7532): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 04:09:25 (7532): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 04:09:30 (7532): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 04:10:32 (7532): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 04:10:41 (7532): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 04:11:03 (7532): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 04:11:12 (7532): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 04:11:51 (7532): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 04:12:01 (7532): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 04:12:25 (7532): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 04:12:30 (7532): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 04:12:50 (7532): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 04:13:00 (7532): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 04:13:21 (7532): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 04:13:30 (7532): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 04:13:51 (7532): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 04:14:02 (7532): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 04:14:29 (7532): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 04:14:45 (7532): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 04:15:02 (7532): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 04:15:28 (7532): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 04:15:58 (7532): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 04:16:23 (7532): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 04:17:07 (7532): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 04:17:08 (7532): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 04:18:35 (7532): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 04:18:46 (7532): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 04:19:07 (7532): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 04:19:15 (7532): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 04:19:39 (7532): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 04:19:47 (7532): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 04:20:08 (7532): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 04:20:18 (7532): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 04:20:54 (7532): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 04:20:58 (7532): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 04:21:36 (7532): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 04:21:39 (7532): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 04:22:04 (7532): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 04:22:05 (7532): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 04:22:34 (7532): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 04:22:35 (7532): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 04:23:04 (7532): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 04:23:05 (7532): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 04:23:34 (7532): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 04:23:35 (7532): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 04:24:08 (7532): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 04:24:10 (7532): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 04:25:12 (7532): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 04:25:16 (7532): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 04:25:41 (7532): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 04:25:48 (7532): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 04:26:10 (7532): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 04:26:27 (7532): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 04:27:46 (7532): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 04:27:47 (7532): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 04:28:35 (7532): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 04:28:37 (7532): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 04:29:05 (7532): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 04:29:06 (7532): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 04:29:36 (7532): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 04:29:37 (7532): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 04:30:06 (7532): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 04:30:07 (7532): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 04:30:37 (7532): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 04:30:38 (7532): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 04:31:07 (7532): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 04:31:08 (7532): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 04:31:39 (7532): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 04:31:42 (7532): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 04:32:13 (7532): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 04:32:20 (7532): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 04:32:56 (7532): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 04:32:57 (7532): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 04:33:27 (7532): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 04:33:28 (7532): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 04:34:01 (7532): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 04:34:08 (7532): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 04:34:44 (7532): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 04:34:50 (7532): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 04:35:21 (7532): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 04:35:30 (7532): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 04:36:06 (7532): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 04:36:10 (7532): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 04:36:36 (7532): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 04:36:39 (7532): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 04:37:11 (7532): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 04:37:18 (7532): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 04:37:59 (7532): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 04:38:00 (7532): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 04:38:29 (7532): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 04:38:30 (7532): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 04:38:57 (7532): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 04:38:58 (7532): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 04:39:27 (7532): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 04:39:28 (7532): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 04:39:57 (7532): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 04:39:58 (7532): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 04:40:36 (7532): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 04:40:37 (7532): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 04:40:57 (7532): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 04:40:58 (7532): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 04:41:31 (7532): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 04:41:38 (7532): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 04:42:57 (7532): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 04:43:08 (7532): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 04:43:28 (7532): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 04:43:39 (7532): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 04:43:59 (7532): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 04:44:09 (7532): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 04:44:29 (7532): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 04:44:39 (7532): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 04:44:59 (7532): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 04:45:09 (7532): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 04:45:34 (7532): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 04:45:43 (7532): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 04:46:11 (7532): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 04:46:13 (7532): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 04:46:35 (7532): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 04:46:42 (7532): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 04:47:19 (7532): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 04:47:30 (7532): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 04:47:50 (7532): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 04:47:59 (7532): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 04:48:20 (7532): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 04:48:29 (7532): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 04:48:50 (7532): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 04:48:59 (7532): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 04:49:19 (7532): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 04:49:33 (7532): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 04:49:51 (7532): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 04:50:00 (7532): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 04:50:20 (7532): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 04:50:29 (7532): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 04:51:00 (7532): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 04:51:03 (7532): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 04:51:33 (7532): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 04:51:34 (7532): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 04:51:56 (7532): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 04:52:00 (7532): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 04:53:32 (7532): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 04:53:33 (7532): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 04:55:31 (7532): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 04:55:40 (7532): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 04:56:21 (7532): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 04:56:31 (7532): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 04:56:32 (7532): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 05:29:12 (7532): Error in pause VM for VM: -182
Command:
VBoxManage -q controlvm "boinc_02563035265b9e3f" pause
Output:

2026-01-28 05:46:37 (7532): Error in pause VM for VM: -182
Command:
VBoxManage -q controlvm "boinc_02563035265b9e3f" pause
Output:

2026-01-28 05:47:17 (7532): Error in pause VM for VM: -182
Command:
VBoxManage -q controlvm "boinc_02563035265b9e3f" pause
Output:
VBoxManage.exe: error: Already paused
VBoxManage.exe: error: Details: code VBOX_E_INVALID_VM_STATE (0x80bb0002), component ConsoleWrap, interface IConsole, callee IUnknown
VBoxManage.exe: error: Context: "Pause()" at line 390 of file VBoxManageControlVM.cpp

2026-01-28 05:47:17 (7532): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 05:47:18 (7532): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 05:47:36 (7532): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 05:47:57 (7532): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 05:48:16 (7532): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 05:48:37 (7532): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 05:48:57 (7532): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 05:49:17 (7532): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 05:49:27 (7532): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 05:49:48 (7532): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 05:50:08 (7532): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 05:50:28 (7532): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 05:50:37 (7532): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 05:50:58 (7532): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 05:51:08 (7532): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 05:51:28 (7532): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 05:51:38 (7532): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 05:52:00 (7532): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 05:52:10 (7532): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 05:52:29 (7532): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 05:52:37 (7532): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 05:53:00 (7532): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 05:53:08 (7532): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 05:53:28 (7532): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 05:53:37 (7532): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 05:54:13 (7532): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 05:56:38 (7532): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 05:56:49 (7532): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 05:56:50 (7532): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 05:57:16 (7532): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 05:57:20 (7532): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 05:57:49 (7532): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 05:57:50 (7532): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 05:58:37 (7532): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 05:58:40 (7532): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 05:59:00 (7532): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 05:59:10 (7532): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 05:59:36 (7532): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 05:59:40 (7532): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 06:00:10 (7532): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 06:00:11 (7532): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 06:01:20 (7532): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 06:01:21 (7532): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 06:01:50 (7532): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 06:01:51 (7532): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 06:02:20 (7532): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 06:02:21 (7532): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 06:02:50 (7532): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 06:02:51 (7532): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 06:03:20 (7532): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 06:03:21 (7532): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 06:03:50 (7532): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 06:03:51 (7532): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 06:04:20 (7532): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 06:04:21 (7532): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 06:04:50 (7532): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 06:04:51 (7532): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 06:05:21 (7532): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 06:05:22 (7532): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 06:05:51 (7532): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 06:05:52 (7532): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 06:06:21 (7532): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 06:06:22 (7532): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 06:06:51 (7532): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 06:06:52 (7532): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 06:07:20 (7532): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 06:07:21 (7532): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 06:07:46 (7532): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 06:07:51 (7532): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 06:08:14 (7532): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 06:08:21 (7532): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 06:09:00 (7532): Stopping VM.
2026-01-28 06:09:29 (7532): Successfully stopped VM.
2026-01-28 06:10:57 (1048): vboxwrapper version 26210
2026-01-28 06:11:03 (1048): BOINC client version: 8.0.2
2026-01-28 06:11:12 (1048): Detected: VirtualBox VboxManage Interface (Version: 7.0.6)
2026-01-28 06:11:14 (1048): Guest Log: 00:06:42.034137 timesync vgsvcTimeSyncWorker: Radical host time change: 12 367 300 000 000ns (HostNow=1 769 564 250 383 000 000 ns HostLast=1 769 551 883 083 000 000 ns)
2026-01-28 06:11:14 (1048): Guest Log: 00:06:52.104086 timesync vgsvcTimeSyncWorker: Radical host time change: 4 720 320 000 000ns (HostNow=1 769 568 970 703 000 000 ns HostLast=1 769 564 250 383 000 000 ns)
2026-01-28 06:11:14 (1048): Guest Log: 00:06:52.104591 timesync vgsvcTimeSyncWorker: Radical guest time change: 12 531 652 042 000ns (GuestNow=1 769 564 260 412 947 000 ns GuestLast=1 769 551 728 760 905 000 ns fSetTimeLastLoop=true )
2026-01-28 06:11:14 (1048): Guest Log: 00:07:02.124362 timesync vgsvcTimeSyncWorker: Radical guest time change: 4 720 349 896 000ns (GuestNow=1 769 568 980 762 843 000 ns GuestLast=1 769 564 260 412 947 000 ns fSetTimeLastLoop=true )
2026-01-28 06:11:14 (1048): Starting VM using VBoxManage interface. (boinc_02563035265b9e3f, slot#0)
2026-01-28 06:11:59 (1048): Successfully started VM. (PID = '4316')
2026-01-28 06:11:59 (1048): Reporting VM Process ID to BOINC.
2026-01-28 06:11:59 (1048): VM state change detected. (old = 'poweredoff', new = 'running')
2026-01-28 06:11:59 (1048): Detected: Web Application Enabled (http://localhost:63403)
2026-01-28 06:11:59 (1048): Detected: Remote Desktop Enabled (localhost:63404)
2026-01-28 06:12:00 (1048): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 06:12:17 (1048): Preference change detected
2026-01-28 06:12:17 (1048): Setting CPU throttle for VM. (1%)
2026-01-28 06:12:22 (1048): Setting checkpoint interval to 3600 seconds. (Higher value of (Preference: 3600 seconds) or (Vbox_job.xml: 900 seconds))
2026-01-28 06:12:22 (1048): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 06:12:23 (1048): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 06:12:45 (1048): Stopping VM.
2026-01-28 06:13:13 (1048): Successfully stopped VM.
2026-01-28 06:16:52 (4716): vboxwrapper version 26210
2026-01-28 06:16:54 (4716): BOINC client version: 8.0.2
2026-01-28 06:17:04 (4716): Detected: VirtualBox VboxManage Interface (Version: 7.0.6)
2026-01-28 06:17:06 (4716): Starting VM using VBoxManage interface. (boinc_02563035265b9e3f, slot#0)
2026-01-28 06:17:44 (4716): Successfully started VM. (PID = '2108')
2026-01-28 06:17:44 (4716): Reporting VM Process ID to BOINC.
2026-01-28 06:17:44 (4716): VM state change detected. (old = 'poweredoff', new = 'running')
2026-01-28 06:17:44 (4716): Detected: Web Application Enabled (http://localhost:63403)
2026-01-28 06:17:44 (4716): Detected: Remote Desktop Enabled (localhost:63404)
2026-01-28 06:17:45 (4716): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 06:18:31 (4716): Preference change detected
2026-01-28 06:18:31 (4716): Setting CPU throttle for VM. (1%)
2026-01-28 06:18:37 (4716): Setting checkpoint interval to 3600 seconds. (Higher value of (Preference: 3600 seconds) or (Vbox_job.xml: 900 seconds))
2026-01-28 06:18:37 (4716): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 06:18:38 (4716): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 06:19:20 (4716): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 06:19:25 (4716): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 06:20:10 (4716): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 06:20:15 (4716): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 06:20:57 (4716): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 06:20:58 (4716): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 06:21:26 (4716): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 06:21:27 (4716): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 06:21:51 (4716): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 06:21:59 (4716): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 06:22:18 (4716): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 06:22:28 (4716): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 06:23:00 (4716): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 06:23:07 (4716): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 06:23:42 (4716): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 06:23:49 (4716): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 06:24:21 (4716): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 06:24:26 (4716): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 06:25:07 (4716): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 06:25:08 (4716): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 06:25:42 (4716): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 06:25:47 (4716): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 06:26:30 (4716): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 06:26:31 (4716): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 06:27:01 (4716): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 06:27:09 (4716): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 06:27:48 (4716): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 06:27:49 (4716): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 06:28:12 (4716): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 06:28:21 (4716): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 06:28:47 (4716): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 06:28:48 (4716): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 06:29:22 (4716): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 06:29:31 (4716): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 06:30:09 (4716): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 06:30:10 (4716): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 06:30:49 (4716): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 06:30:50 (4716): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 06:31:29 (4716): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 06:31:32 (4716): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 06:31:54 (4716): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 06:31:59 (4716): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 06:32:28 (4716): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 06:32:29 (4716): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 06:33:02 (4716): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 06:33:09 (4716): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 06:33:45 (4716): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 06:33:51 (4716): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 06:34:16 (4716): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 06:34:20 (4716): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 06:34:48 (4716): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 06:34:49 (4716): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 06:35:19 (4716): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 06:35:20 (4716): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 06:35:49 (4716): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 06:35:50 (4716): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 06:36:20 (4716): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 06:36:33 (4716): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 06:37:13 (4716): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 06:37:20 (4716): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 06:38:42 (4716): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 06:38:50 (4716): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 06:39:10 (4716): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 06:39:20 (4716): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 06:39:42 (4716): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 06:39:50 (4716): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 06:40:10 (4716): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 06:40:21 (4716): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 06:40:41 (4716): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 06:40:51 (4716): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 06:41:11 (4716): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 06:41:21 (4716): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 06:41:41 (4716): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 06:41:50 (4716): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 06:42:11 (4716): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 06:42:24 (4716): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 06:42:46 (4716): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 06:42:50 (4716): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 06:43:10 (4716): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 06:43:21 (4716): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 06:43:49 (4716): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 06:43:50 (4716): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 06:44:11 (4716): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 06:44:20 (4716): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 06:44:41 (4716): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 06:44:50 (4716): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 06:45:30 (4716): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 06:45:39 (4716): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 06:45:58 (4716): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 06:46:09 (4716): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 06:46:32 (4716): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 06:46:44 (4716): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 06:46:59 (4716): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 06:47:12 (4716): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 06:47:25 (4716): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 06:47:40 (4716): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 06:47:46 (4716): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 06:47:55 (4716): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 06:48:14 (4716): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 06:48:28 (4716): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 06:48:48 (4716): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 06:48:58 (4716): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 06:49:42 (4716): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 06:49:43 (4716): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 06:50:13 (4716): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 06:50:14 (4716): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 06:50:53 (4716): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 06:51:04 (4716): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 06:51:33 (4716): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 06:51:34 (4716): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 06:52:03 (4716): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 06:52:04 (4716): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 06:52:38 (4716): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 06:52:45 (4716): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 06:53:25 (4716): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 06:53:29 (4716): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 06:53:57 (4716): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 06:54:02 (4716): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 06:54:43 (4716): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 06:54:45 (4716): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 06:55:42 (4716): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 06:56:15 (4716): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 06:56:35 (4716): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 06:57:15 (4716): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 06:57:35 (4716): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 06:57:45 (4716): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 06:58:05 (4716): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 06:58:15 (4716): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 06:58:35 (4716): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 06:58:45 (4716): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 06:59:05 (4716): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 06:59:15 (4716): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 07:00:04 (4716): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 07:00:05 (4716): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 07:01:50 (4716): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 07:04:37 (4716): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 07:05:26 (4716): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 07:05:27 (4716): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 07:05:57 (4716): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 07:05:58 (4716): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 07:06:26 (4716): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 07:06:27 (4716): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 07:06:56 (4716): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 07:06:57 (4716): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 07:08:07 (4716): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 07:08:08 (4716): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 07:08:57 (4716): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 07:08:58 (4716): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 07:09:20 (4716): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 07:09:28 (4716): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 07:09:57 (4716): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 07:09:59 (4716): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 07:10:27 (4716): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 07:10:28 (4716): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 07:12:38 (4716): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 07:12:39 (4716): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 07:14:38 (4716): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 07:14:39 (4716): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 07:15:05 (4716): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 07:15:09 (4716): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 07:16:08 (4716): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 07:16:09 (4716): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 07:17:08 (4716): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 07:17:10 (4716): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 07:17:58 (4716): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 07:17:59 (4716): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 07:18:28 (4716): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 07:18:29 (4716): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 07:18:52 (4716): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 07:18:59 (4716): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 07:19:20 (4716): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 07:19:29 (4716): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 07:20:08 (4716): Stopping VM.
2026-01-28 07:20:36 (4716): Successfully stopped VM.
2026-01-28 07:21:23 (8040): vboxwrapper version 26210
2026-01-28 07:21:37 (8040): BOINC client version: 8.0.2
2026-01-28 07:21:58 (8040): Detected: VirtualBox VboxManage Interface (Version: 7.0.6)
2026-01-28 07:22:10 (8040): ERROR: VM failed to start
2026-01-28 07:22:15 (8040): 
   BOINC will be notified that it needs to clean up the environment.
         This is a temporary problem and so this job will be rescheduled for another time.

2026-01-28 07:27:23 (6780): vboxwrapper version 26210
2026-01-28 07:27:23 (6780): BOINC client version: 8.0.2
2026-01-28 07:27:42 (6780): Detected: VirtualBox VboxManage Interface (Version: 7.0.6)
2026-01-28 07:27:44 (6780): Starting VM using VBoxManage interface. (boinc_02563035265b9e3f, slot#0)
2026-01-28 07:28:21 (6780): Successfully started VM. (PID = '4560')
2026-01-28 07:28:21 (6780): Reporting VM Process ID to BOINC.
2026-01-28 07:28:21 (6780): VM state change detected. (old = 'poweredoff', new = 'running')
2026-01-28 07:28:21 (6780): Detected: Web Application Enabled (http://localhost:63403)
2026-01-28 07:28:21 (6780): Detected: Remote Desktop Enabled (localhost:63404)
2026-01-28 07:28:22 (6780): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 07:29:26 (6780): Preference change detected
2026-01-28 07:29:26 (6780): Setting CPU throttle for VM. (1%)
2026-01-28 07:29:26 (6780): Setting checkpoint interval to 3600 seconds. (Higher value of (Preference: 3600 seconds) or (Vbox_job.xml: 900 seconds))
2026-01-28 07:29:27 (6780): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 07:29:35 (6780): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 07:29:55 (6780): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 07:30:05 (6780): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 07:30:25 (6780): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 07:30:35 (6780): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 07:30:55 (6780): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 07:31:05 (6780): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 07:31:25 (6780): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 07:31:35 (6780): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 07:31:55 (6780): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 07:32:05 (6780): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 07:32:25 (6780): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 07:32:35 (6780): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 07:32:56 (6780): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 07:33:05 (6780): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 07:33:25 (6780): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 07:33:35 (6780): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 07:33:55 (6780): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 07:34:05 (6780): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 07:34:25 (6780): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 07:34:35 (6780): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 07:34:56 (6780): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 07:35:05 (6780): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 07:35:25 (6780): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 07:35:35 (6780): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 07:35:55 (6780): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 07:36:06 (6780): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 07:36:26 (6780): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 07:36:35 (6780): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 07:36:55 (6780): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 07:37:06 (6780): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 07:37:26 (6780): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 07:37:35 (6780): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 07:37:55 (6780): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 07:38:05 (6780): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 07:38:25 (6780): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 07:38:35 (6780): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 07:38:56 (6780): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 07:39:06 (6780): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 07:39:26 (6780): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 07:39:36 (6780): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 07:39:56 (6780): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 07:40:06 (6780): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 07:40:26 (6780): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 07:40:36 (6780): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 07:40:56 (6780): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 07:41:06 (6780): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 07:41:26 (6780): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 07:41:36 (6780): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 07:41:56 (6780): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 07:42:06 (6780): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 07:42:26 (6780): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 07:42:36 (6780): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 07:42:56 (6780): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 07:43:06 (6780): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 07:43:26 (6780): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 07:43:36 (6780): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 07:43:56 (6780): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 07:44:06 (6780): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 07:44:27 (6780): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 07:44:37 (6780): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 07:44:57 (6780): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 07:45:07 (6780): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 07:45:27 (6780): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 07:45:37 (6780): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 07:45:57 (6780): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 07:46:07 (6780): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 07:46:27 (6780): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 07:46:36 (6780): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 07:46:57 (6780): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 07:47:06 (6780): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 07:47:26 (6780): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 07:47:36 (6780): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 07:47:56 (6780): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 07:48:06 (6780): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 07:48:27 (6780): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 07:48:37 (6780): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 07:48:57 (6780): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 07:49:07 (6780): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 07:49:27 (6780): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 07:49:37 (6780): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 07:49:57 (6780): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 07:50:07 (6780): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 07:50:27 (6780): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 07:50:37 (6780): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 07:50:57 (6780): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 07:51:07 (6780): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 07:51:27 (6780): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 07:51:37 (6780): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 07:51:57 (6780): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 07:52:07 (6780): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 07:52:27 (6780): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 07:52:37 (6780): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 07:52:57 (6780): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 07:53:07 (6780): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 07:53:27 (6780): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 07:53:38 (6780): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 07:53:58 (6780): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 07:54:08 (6780): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 07:54:28 (6780): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 07:54:38 (6780): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 07:54:57 (6780): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 07:55:07 (6780): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 07:55:28 (6780): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 07:55:38 (6780): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 07:55:58 (6780): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 07:56:07 (6780): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 07:56:28 (6780): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 07:56:37 (6780): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 07:56:59 (6780): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 07:57:08 (6780): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 07:57:28 (6780): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 07:57:38 (6780): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 07:57:58 (6780): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 07:58:08 (6780): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 07:58:28 (6780): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 07:58:38 (6780): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 07:58:58 (6780): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 07:59:08 (6780): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 07:59:28 (6780): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 07:59:38 (6780): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 07:59:58 (6780): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 08:00:09 (6780): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 08:00:29 (6780): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 08:00:39 (6780): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 08:01:00 (6780): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 08:01:10 (6780): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 08:01:29 (6780): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 08:01:39 (6780): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 08:01:59 (6780): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 08:02:09 (6780): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 08:02:29 (6780): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 08:02:39 (6780): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 08:02:59 (6780): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 08:03:09 (6780): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 08:03:29 (6780): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 08:03:39 (6780): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 08:03:59 (6780): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 08:04:09 (6780): Guest Log:  *** Job finished ***
2026-01-28 08:04:09 (6780): VM state change detected. (old = 'running', new = 'paused')
2026-01-28 08:04:29 (6780): Guest Log:  *** The last 20 lines of the pilot log: ***
2026-01-28 08:04:29 (6780): Guest Log: ---- Setup ALRB ----
2026-01-28 08:04:29 (6780): VM state change detected. (old = 'paused', new = 'running')
2026-01-28 08:04:30 (6780): Guest Log: 2026-01-28 01:37:38,699 [wrapper] Skipping Setup ALRB due to --container flag
2026-01-28 08:04:30 (6780): Guest Log: ---- Check python version ----
2026-01-28 08:04:30 (6780): Guest Log: 2026-01-28 01:37:39,416 [wrapper] python3 selected from cmdline
2026-01-28 08:04:30 (6780): Guest Log: 2026-01-28 01:37:39,827 [wrapper] Using ALRB to setup python3
2026-01-28 08:04:30 (6780): Guest Log: /cvmfs/atlas.cern.ch/repo/ATLASLocalRootBase/packageSetups/localSetup.sh: line 92: /cvmfs/atlas.cern.ch/repo/ATLASLocalRootBase/utilities/getCurrentEnv.sh: Input/output error
2026-01-28 08:04:30 (6780): Guest Log: /cvmfs/atlas.cern.ch/repo/ATLASLocalRootBase/packageSetups/localSetup.sh: line 99: /cvmfs/atlas.cern.ch/repo/ATLASLocalRootBase/utilities/validator.sh: Input/output error
2026-01-28 08:04:30 (6780): Guest Log: ./runpilot2-wrapper.sh: line 174: /usr/bin/python3: Input/output error
2026-01-28 08:04:30 (6780): Guest Log: 2026-01-28 07:00:52,549 [wrapper] ERROR: this site has python < 3.6
2026-01-28 08:04:30 (6780): Guest Log: 2026-01-28 07:00:52,782 [wrapper] ERROR: this site has python < 3.6
2026-01-28 08:04:31 (6780): Guest Log: 2026-01-28 07:00:53,140 [wrapper] Python /usr/bin/python3 is old: 
2026-01-28 08:04:31 (6780): Guest Log: 2026-01-28 07:00:53,402 [wrapper] FATAL: Failed to find a compatible python, exiting
2026-01-28 08:04:31 (6780): Guest Log: 2026-01-28 07:00:53,869 [wrapper] FATAL: Failed to find a compatible python, exiting
2026-01-28 08:04:31 (6780): Guest Log: 2026-01-28 07:00:54,593 [wrapper] No supervise_pilot process found
2026-01-28 08:04:31 (6780): Guest Log: 2026-01-28 07:00:55,215 [wrapper] No supervise_pilot CHILD process found
2026-01-28 08:04:31 (6780): Guest Log: 2026-01-28 07:00:55,731 [wrapper] Test setup, not cleaning
2026-01-28 08:04:31 (6780): Guest Log: 2026-01-28 07:00:56,054 [wrapper] apfmon messages muted
2026-01-28 08:04:31 (6780): Guest Log: 2026-01-28 07:00:56,349 [wrapper] ==== wrapper stdout END ====
2026-01-28 08:04:31 (6780): Guest Log: 2026-01-28 07:00:57,080 [wrapper] ==== wrapper stderr END ====
2026-01-28 08:04:31 (6780): Guest Log:  *** Listing of results directory ***
2026-01-28 08:04:31 (6780): Guest Log: total 1272
2026-01-28 08:04:31 (6780): Guest Log: drwx------. 4 atlas atlas   4096 Jan 14 10:00 pilot3
2026-01-28 08:04:31 (6780): Guest Log: -rw-r--r--. 1 atlas atlas 584422 Jan 26 07:37 pilot3.tar.gz
2026-01-28 08:04:31 (6780): Guest Log: -rwx------. 1 atlas atlas  36322 Jan 26 07:41 runpilot2-wrapper.sh
2026-01-28 08:04:31 (6780): Guest Log: -rw-------. 1 atlas atlas   2509 Jan 26 07:41 pandaJobData.out
2026-01-28 08:04:31 (6780): Guest Log: -rw-r--r--. 1 atlas atlas   5111 Jan 26 07:43 queuedata.json
2026-01-28 08:04:31 (6780): Guest Log: -rwxr-xr-x. 1 atlas atlas   8876 Jan 27 22:05 init_data.xml
2026-01-28 08:04:31 (6780): Guest Log: -rwxr-xr-x. 1 atlas atlas 597755 Jan 27 22:05 input.tar.gz
2026-01-28 08:04:31 (6780): Guest Log: -rwxr-xr-x. 1 atlas atlas  15845 Jan 27 22:05 start_atlas.sh
2026-01-28 08:04:31 (6780): Guest Log: lrwxrwxrwx. 1 atlas atlas     20 Jan 27 22:05 EVNT.48317929._000056.pool.root.1 -> /data/./ATLAS.root_0
2026-01-28 08:04:31 (6780): Guest Log: -rw-r--r--. 1 atlas atlas   2509 Jan 27 22:07 pandaJob.out
2026-01-28 08:04:31 (6780): Guest Log: -rw-------. 1 atlas atlas   3842 Jan 28 07:00 log.48317931._001579.job.log.1
2026-01-28 08:04:31 (6780): Guest Log: -rw-r--r--. 1 atlas atlas    554 Jan 28 07:01 runtime_log
2026-01-28 08:04:31 (6780): Guest Log: -rw-------. 1 atlas atlas  10240 Jan 28 07:01 result.tar.gz
2026-01-28 08:04:31 (6780): Guest Log: -rw-------. 1 atlas atlas    660 Jan 28 07:01 ZQTLDmoOW48nsSi4ap6QjLDmwznN0nGgGQJmNLGKDmDU9KDm9DNYfm.diag
2026-01-28 08:04:31 (6780): Guest Log: -rw-r--r--. 1 atlas atlas   8062 Jan 28 07:01 runtime_log.err
2026-01-28 08:04:31 (6780): Guest Log: Looking for outputfile HITS.48317931._001579.pool.root.1
2026-01-28 08:04:32 (6780): Guest Log: No HITS file was produced
2026-01-28 08:04:32 (6780): Guest Log: Successfully finished the ATLAS job!
2026-01-28 08:04:32 (6780): Guest