Stderr output

<core_client_version>8.0.2</core_client_version>
<![CDATA[
<stderr_txt>
0 (2584): Setting CPU throttle for VM. (20%)
2025-07-29 08:00:00 (2584): Setting checkpoint interval to 900 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 900 seconds))
2025-07-29 08:00:01 (2584): Guest Log: 07:56:55.203897 timesync vgsvcTimeSyncWorker: Radical host time change: 2 268 572 000 000ns (HostNow=1 753 740 000 620 000 000 ns HostLast=1 753 737 732 048 000 000 ns)
2025-07-29 08:00:11 (2584): Guest Log: 07:57:05.228757 timesync vgsvcTimeSyncWorker: Radical guest time change: 2 288 506 213 000ns (GuestNow=1 753 740 010 644 904 000 ns GuestLast=1 753 737 722 138 691 000 ns fSetTimeLastLoop=true )
2025-07-29 08:10:12 (2584): VM state change detected. (old = 'running', new = 'paused')
2025-07-29 08:10:33 (2584): VM state change detected. (old = 'paused', new = 'running')
2025-07-29 08:11:02 (2584): VM state change detected. (old = 'running', new = 'paused')
2025-07-29 08:11:23 (2584): VM state change detected. (old = 'paused', new = 'running')
2025-07-29 08:12:52 (2584): VM state change detected. (old = 'running', new = 'paused')
2025-07-29 08:13:13 (2584): VM state change detected. (old = 'paused', new = 'running')
2025-07-29 08:22:33 (2584): VM state change detected. (old = 'running', new = 'paused')
2025-07-29 08:22:53 (2584): VM state change detected. (old = 'paused', new = 'running')
2025-07-29 08:24:03 (2584): VM state change detected. (old = 'running', new = 'paused')
2025-07-29 08:24:24 (2584): VM state change detected. (old = 'paused', new = 'running')
2025-07-29 08:25:13 (2584): VM state change detected. (old = 'running', new = 'paused')
2025-07-29 08:25:34 (2584): VM state change detected. (old = 'paused', new = 'running')
2025-07-29 08:25:53 (2584): VM state change detected. (old = 'running', new = 'paused')
2025-07-29 08:26:13 (2584): VM state change detected. (old = 'paused', new = 'running')
2025-07-29 08:26:43 (2584): VM state change detected. (old = 'running', new = 'paused')
2025-07-29 08:27:04 (2584): VM state change detected. (old = 'paused', new = 'running')
2025-07-29 08:27:13 (2584): VM state change detected. (old = 'running', new = 'paused')
2025-07-29 08:27:33 (2584): VM state change detected. (old = 'paused', new = 'running')
2025-07-29 08:27:53 (2584): VM state change detected. (old = 'running', new = 'paused')
2025-07-29 08:28:14 (2584): VM state change detected. (old = 'paused', new = 'running')
2025-07-29 08:38:03 (2584): VM state change detected. (old = 'running', new = 'paused')
2025-07-29 08:38:24 (2584): VM state change detected. (old = 'paused', new = 'running')
2025-07-29 08:39:03 (2584): VM state change detected. (old = 'running', new = 'paused')
2025-07-29 08:39:24 (2584): VM state change detected. (old = 'paused', new = 'running')
2025-07-29 08:40:13 (2584): VM state change detected. (old = 'running', new = 'paused')
2025-07-29 08:40:33 (2584): VM state change detected. (old = 'paused', new = 'running')
2025-07-29 08:42:03 (2584): VM state change detected. (old = 'running', new = 'paused')
2025-07-29 08:42:24 (2584): VM state change detected. (old = 'paused', new = 'running')
2025-07-29 08:42:43 (2584): VM state change detected. (old = 'running', new = 'paused')
2025-07-29 08:43:04 (2584): VM state change detected. (old = 'paused', new = 'running')
2025-07-29 08:43:23 (2584): VM state change detected. (old = 'running', new = 'paused')
2025-07-29 08:43:43 (2584): VM state change detected. (old = 'paused', new = 'running')
2025-07-29 08:52:44 (2584): VM state change detected. (old = 'running', new = 'paused')
2025-07-29 08:53:04 (2584): VM state change detected. (old = 'paused', new = 'running')
2025-07-29 08:54:24 (2584): VM state change detected. (old = 'running', new = 'paused')
2025-07-29 08:54:45 (2584): VM state change detected. (old = 'paused', new = 'running')
2025-07-29 08:55:15 (2584): VM state change detected. (old = 'running', new = 'paused')
2025-07-29 08:55:35 (2584): VM state change detected. (old = 'paused', new = 'running')
2025-07-29 08:56:04 (2584): VM state change detected. (old = 'running', new = 'paused')
2025-07-29 08:56:25 (2584): VM state change detected. (old = 'paused', new = 'running')
2025-07-29 08:57:15 (2584): VM state change detected. (old = 'running', new = 'paused')
2025-07-29 08:57:35 (2584): VM state change detected. (old = 'paused', new = 'running')
2025-07-29 08:57:45 (2584): VM state change detected. (old = 'running', new = 'paused')
2025-07-29 08:58:05 (2584): VM state change detected. (old = 'paused', new = 'running')
2025-07-29 09:08:15 (2584): VM state change detected. (old = 'running', new = 'paused')
2025-07-29 09:08:35 (2584): VM state change detected. (old = 'paused', new = 'running')
2025-07-29 09:09:05 (2584): VM state change detected. (old = 'running', new = 'paused')
2025-07-29 09:09:25 (2584): VM state change detected. (old = 'paused', new = 'running')
2025-07-29 09:10:15 (2584): VM state change detected. (old = 'running', new = 'paused')
2025-07-29 09:10:36 (2584): VM state change detected. (old = 'paused', new = 'running')
2025-07-29 09:11:16 (2584): VM state change detected. (old = 'running', new = 'paused')
2025-07-29 09:11:36 (2584): VM state change detected. (old = 'paused', new = 'running')
2025-07-29 09:12:56 (2584): VM state change detected. (old = 'running', new = 'paused')
2025-07-29 09:13:16 (2584): VM state change detected. (old = 'paused', new = 'running')
2025-07-29 09:22:46 (2584): VM state change detected. (old = 'running', new = 'paused')
2025-07-29 09:23:06 (2584): VM state change detected. (old = 'paused', new = 'running')
2025-07-29 09:23:16 (2584): VM state change detected. (old = 'running', new = 'paused')
2025-07-29 09:23:36 (2584): VM state change detected. (old = 'paused', new = 'running')
2025-07-29 09:24:26 (2584): VM state change detected. (old = 'running', new = 'paused')
2025-07-29 09:24:46 (2584): VM state change detected. (old = 'paused', new = 'running')
2025-07-29 09:25:06 (2584): VM state change detected. (old = 'running', new = 'paused')
2025-07-29 09:25:26 (2584): VM state change detected. (old = 'paused', new = 'running')
2025-07-29 09:26:26 (2584): VM state change detected. (old = 'running', new = 'paused')
2025-07-29 09:26:46 (2584): VM state change detected. (old = 'paused', new = 'running')
2025-07-29 09:27:16 (2584): VM state change detected. (old = 'running', new = 'paused')
2025-07-29 09:27:36 (2584): VM state change detected. (old = 'paused', new = 'running')
2025-07-29 09:27:46 (2584): VM state change detected. (old = 'running', new = 'paused')
2025-07-29 09:28:06 (2584): VM state change detected. (old = 'paused', new = 'running')
2025-07-29 09:37:37 (2584): VM state change detected. (old = 'running', new = 'paused')
2025-07-29 09:37:57 (2584): VM state change detected. (old = 'paused', new = 'running')
2025-07-29 09:39:17 (2584): VM state change detected. (old = 'running', new = 'paused')
2025-07-29 09:39:37 (2584): VM state change detected. (old = 'paused', new = 'running')
2025-07-29 09:40:27 (2584): VM state change detected. (old = 'running', new = 'paused')
2025-07-29 09:40:47 (2584): VM state change detected. (old = 'paused', new = 'running')
2025-07-29 09:41:27 (2584): VM state change detected. (old = 'running', new = 'paused')
2025-07-29 09:41:47 (2584): VM state change detected. (old = 'paused', new = 'running')
2025-07-29 09:42:47 (2584): VM state change detected. (old = 'running', new = 'paused')
2025-07-29 09:43:07 (2584): VM state change detected. (old = 'paused', new = 'running')
2025-07-29 09:43:17 (2584): VM state change detected. (old = 'running', new = 'paused')
2025-07-29 09:43:37 (2584): VM state change detected. (old = 'paused', new = 'running')
2025-07-29 09:52:47 (2584): VM state change detected. (old = 'running', new = 'paused')
2025-07-29 09:53:07 (2584): VM state change detected. (old = 'paused', new = 'running')
2025-07-29 09:53:37 (2584): VM state change detected. (old = 'running', new = 'paused')
2025-07-29 09:53:57 (2584): VM state change detected. (old = 'paused', new = 'running')
2025-07-29 09:54:47 (2584): VM state change detected. (old = 'running', new = 'paused')
2025-07-29 09:55:07 (2584): VM state change detected. (old = 'paused', new = 'running')
2025-07-29 09:55:20 (2584): Status Report: Elapsed Time: '54543.000000'
2025-07-29 09:55:20 (2584): Status Report: CPU Time: '32596.390625'
2025-07-29 09:57:47 (2584): VM state change detected. (old = 'running', new = 'paused')
2025-07-29 09:58:07 (2584): VM state change detected. (old = 'paused', new = 'running')
2025-07-29 10:08:08 (2584): VM state change detected. (old = 'running', new = 'paused')
2025-07-29 10:08:28 (2584): VM state change detected. (old = 'paused', new = 'running')
2025-07-29 10:09:18 (2584): VM state change detected. (old = 'running', new = 'paused')
2025-07-29 10:09:38 (2584): VM state change detected. (old = 'paused', new = 'running')
2025-07-29 10:10:08 (2584): VM state change detected. (old = 'running', new = 'paused')
2025-07-29 10:10:48 (2584): VM state change detected. (old = 'paused', new = 'running')
2025-07-29 10:10:58 (2584): VM state change detected. (old = 'running', new = 'paused')
2025-07-29 10:11:18 (2584): VM state change detected. (old = 'paused', new = 'running')
2025-07-29 10:11:38 (2584): VM state change detected. (old = 'running', new = 'paused')
2025-07-29 10:11:58 (2584): VM state change detected. (old = 'paused', new = 'running')
2025-07-29 10:12:28 (2584): VM state change detected. (old = 'running', new = 'paused')
2025-07-29 10:12:48 (2584): VM state change detected. (old = 'paused', new = 'running')
2025-07-29 10:12:58 (2584): VM state change detected. (old = 'running', new = 'paused')
2025-07-29 10:13:18 (2584): VM state change detected. (old = 'paused', new = 'running')
2025-07-29 10:13:38 (2584): VM state change detected. (old = 'running', new = 'paused')
2025-07-29 10:13:58 (2584): VM state change detected. (old = 'paused', new = 'running')
2025-07-29 10:22:58 (2584): VM state change detected. (old = 'running', new = 'paused')
2025-07-29 10:23:18 (2584): VM state change detected. (old = 'paused', new = 'running')
2025-07-29 10:24:49 (2584): VM state change detected. (old = 'running', new = 'paused')
2025-07-29 10:25:09 (2584): VM state change detected. (old = 'paused', new = 'running')
2025-07-29 10:25:49 (2584): VM state change detected. (old = 'running', new = 'paused')
2025-07-29 10:26:09 (2584): VM state change detected. (old = 'paused', new = 'running')
2025-07-29 10:26:59 (2584): VM state change detected. (old = 'running', new = 'paused')
2025-07-29 10:27:19 (2584): VM state change detected. (old = 'paused', new = 'running')
2025-07-29 10:27:39 (2584): VM state change detected. (old = 'running', new = 'paused')
2025-07-29 10:28:00 (2584): VM state change detected. (old = 'paused', new = 'running')
2025-07-29 10:28:09 (2584): VM state change detected. (old = 'running', new = 'paused')
2025-07-29 10:28:29 (2584): VM state change detected. (old = 'paused', new = 'running')
2025-07-29 10:38:20 (2584): VM state change detected. (old = 'running', new = 'paused')
2025-07-29 10:38:40 (2584): VM state change detected. (old = 'paused', new = 'running')
2025-07-29 10:39:20 (2584): VM state change detected. (old = 'running', new = 'paused')
2025-07-29 10:39:40 (2584): VM state change detected. (old = 'paused', new = 'running')
2025-07-29 10:40:10 (2584): VM state change detected. (old = 'running', new = 'paused')
2025-07-29 10:40:30 (2584): VM state change detected. (old = 'paused', new = 'running')
2025-07-29 10:40:50 (2584): VM state change detected. (old = 'running', new = 'paused')
2025-07-29 10:41:10 (2584): VM state change detected. (old = 'paused', new = 'running')
2025-07-29 10:41:50 (2584): VM state change detected. (old = 'running', new = 'paused')
2025-07-29 10:42:10 (2584): VM state change detected. (old = 'paused', new = 'running')
2025-07-29 10:42:40 (2584): VM state change detected. (old = 'running', new = 'paused')
2025-07-29 10:43:00 (2584): VM state change detected. (old = 'paused', new = 'running')
2025-07-29 10:43:30 (2584): VM state change detected. (old = 'running', new = 'paused')
2025-07-29 10:43:50 (2584): VM state change detected. (old = 'paused', new = 'running')
2025-07-29 10:53:21 (2584): VM state change detected. (old = 'running', new = 'paused')
2025-07-29 10:53:45 (2584): VM state change detected. (old = 'paused', new = 'running')
2025-07-29 10:54:11 (2584): VM state change detected. (old = 'running', new = 'paused')
2025-07-29 10:54:35 (2584): VM state change detected. (old = 'paused', new = 'running')
2025-07-29 10:55:01 (2584): VM state change detected. (old = 'running', new = 'paused')
2025-07-29 10:55:25 (2584): VM state change detected. (old = 'paused', new = 'running')
2025-07-29 10:55:51 (2584): VM state change detected. (old = 'running', new = 'paused')
2025-07-29 10:56:11 (2584): VM state change detected. (old = 'paused', new = 'running')
2025-07-29 10:57:21 (2584): VM state change detected. (old = 'running', new = 'paused')
2025-07-29 10:57:45 (2584): VM state change detected. (old = 'paused', new = 'running')
2025-07-29 10:58:21 (2584): VM state change detected. (old = 'running', new = 'paused')
2025-07-29 10:58:45 (2584): VM state change detected. (old = 'paused', new = 'running')
2025-07-29 11:09:21 (2584): VM state change detected. (old = 'running', new = 'paused')
2025-07-29 11:09:45 (2584): VM state change detected. (old = 'paused', new = 'running')
2025-07-29 11:10:31 (2584): VM state change detected. (old = 'running', new = 'paused')
2025-07-29 11:10:55 (2584): VM state change detected. (old = 'paused', new = 'running')
2025-07-29 11:11:21 (2584): VM state change detected. (old = 'running', new = 'paused')
2025-07-29 11:11:45 (2584): VM state change detected. (old = 'paused', new = 'running')
2025-07-29 11:12:51 (2584): VM state change detected. (old = 'running', new = 'paused')
2025-07-29 11:13:11 (2584): VM state change detected. (old = 'paused', new = 'running')
2025-07-29 11:13:21 (2584): VM state change detected. (old = 'running', new = 'paused')
2025-07-29 11:13:45 (2584): VM state change detected. (old = 'paused', new = 'running')
2025-07-29 11:22:22 (2584): VM state change detected. (old = 'running', new = 'paused')
2025-07-29 11:22:45 (2584): VM state change detected. (old = 'paused', new = 'running')
2025-07-29 11:24:22 (2584): VM state change detected. (old = 'running', new = 'paused')
2025-07-29 11:24:45 (2584): VM state change detected. (old = 'paused', new = 'running')
2025-07-29 11:26:32 (2584): VM state change detected. (old = 'running', new = 'paused')
2025-07-29 11:26:55 (2584): VM state change detected. (old = 'paused', new = 'running')
2025-07-29 11:27:22 (2584): VM state change detected. (old = 'running', new = 'paused')
2025-07-29 11:27:45 (2584): VM state change detected. (old = 'paused', new = 'running')
2025-07-29 11:28:12 (2584): VM state change detected. (old = 'running', new = 'paused')
2025-07-29 11:28:32 (2584): VM state change detected. (old = 'paused', new = 'running')
2025-07-29 11:37:42 (2584): VM state change detected. (old = 'running', new = 'paused')
2025-07-29 11:38:05 (2584): VM state change detected. (old = 'paused', new = 'running')
2025-07-29 11:38:32 (2584): VM state change detected. (old = 'running', new = 'paused')
2025-07-29 11:38:52 (2584): VM state change detected. (old = 'paused', new = 'running')
2025-07-29 11:39:32 (2584): VM state change detected. (old = 'running', new = 'paused')
2025-07-29 11:39:52 (2584): VM state change detected. (old = 'paused', new = 'running')
2025-07-29 11:40:03 (2584): VM state change detected. (old = 'running', new = 'paused')
2025-07-29 11:40:25 (2584): VM state change detected. (old = 'paused', new = 'running')
2025-07-29 11:40:33 (2584): VM state change detected. (old = 'running', new = 'paused')
2025-07-29 11:40:53 (2584): VM state change detected. (old = 'paused', new = 'running')
2025-07-29 11:42:23 (2584): VM state change detected. (old = 'running', new = 'paused')
2025-07-29 11:42:45 (2584): VM state change detected. (old = 'paused', new = 'running')
2025-07-29 11:43:33 (2584): VM state change detected. (old = 'running', new = 'paused')
2025-07-29 11:43:54 (2584): VM state change detected. (old = 'paused', new = 'running')
2025-07-29 11:52:26 (2584): Status Report: Elapsed Time: '60543.000000'
2025-07-29 11:52:26 (2584): Status Report: CPU Time: '36242.875000'
2025-07-29 11:53:24 (2584): VM state change detected. (old = 'running', new = 'paused')
2025-07-29 11:53:46 (2584): VM state change detected. (old = 'paused', new = 'running')
2025-07-29 11:54:04 (2584): VM state change detected. (old = 'running', new = 'paused')
2025-07-29 11:54:26 (2584): VM state change detected. (old = 'paused', new = 'running')
2025-07-29 11:55:34 (2584): VM state change detected. (old = 'running', new = 'paused')
2025-07-29 11:55:54 (2584): VM state change detected. (old = 'paused', new = 'running')
2025-07-29 11:56:24 (2584): VM state change detected. (old = 'running', new = 'paused')
2025-07-29 11:56:46 (2584): VM state change detected. (old = 'paused', new = 'running')
2025-07-29 11:57:34 (2584): VM state change detected. (old = 'running', new = 'paused')
2025-07-29 11:57:54 (2584): VM state change detected. (old = 'paused', new = 'running')
2025-07-29 11:58:04 (2584): VM state change detected. (old = 'running', new = 'paused')
2025-07-29 11:58:26 (2584): VM state change detected. (old = 'paused', new = 'running')
2025-07-29 12:07:24 (2584): VM state change detected. (old = 'running', new = 'paused')
2025-07-29 12:07:46 (2584): VM state change detected. (old = 'paused', new = 'running')
2025-07-29 12:08:04 (2584): VM state change detected. (old = 'running', new = 'paused')
2025-07-29 12:08:26 (2584): VM state change detected. (old = 'paused', new = 'running')
2025-07-29 12:08:34 (2584): VM state change detected. (old = 'running', new = 'paused')
2025-07-29 12:08:54 (2584): VM state change detected. (old = 'paused', new = 'running')
2025-07-29 12:09:04 (2584): VM state change detected. (old = 'running', new = 'paused')
2025-07-29 12:09:26 (2584): VM state change detected. (old = 'paused', new = 'running')
2025-07-29 12:09:45 (2584): VM state change detected. (old = 'running', new = 'paused')
2025-07-29 12:10:05 (2584): VM state change detected. (old = 'paused', new = 'running')
2025-07-29 12:10:15 (2584): VM state change detected. (old = 'running', new = 'paused')
2025-07-29 12:10:37 (2584): VM state change detected. (old = 'paused', new = 'running')
2025-07-29 12:10:45 (2584): VM state change detected. (old = 'running', new = 'paused')
2025-07-29 12:11:05 (2584): VM state change detected. (old = 'paused', new = 'running')
2025-07-29 12:11:15 (2584): VM state change detected. (old = 'running', new = 'paused')
2025-07-29 12:11:37 (2584): VM state change detected. (old = 'paused', new = 'running')
2025-07-29 12:11:55 (2584): VM state change detected. (old = 'running', new = 'paused')
2025-07-29 12:12:16 (2584): VM state change detected. (old = 'paused', new = 'running')
2025-07-29 12:12:35 (2584): VM state change detected. (old = 'running', new = 'paused')
2025-07-29 12:12:57 (2584): VM state change detected. (old = 'paused', new = 'running')
2025-07-29 12:13:06 (2584): VM state change detected. (old = 'running', new = 'paused')
2025-07-29 12:13:26 (2584): VM state change detected. (old = 'paused', new = 'running')
2025-07-29 12:13:36 (2584): VM state change detected. (old = 'running', new = 'paused')
2025-07-29 12:13:57 (2584): VM state change detected. (old = 'paused', new = 'running')
2025-07-29 12:16:36 (2584): VM state change detected. (old = 'running', new = 'paused')
2025-07-29 12:16:57 (2584): VM state change detected. (old = 'paused', new = 'running')
2025-07-29 12:22:16 (2584): VM state change detected. (old = 'running', new = 'paused')
2025-07-29 12:22:36 (2584): VM state change detected. (old = 'paused', new = 'running')
2025-07-29 12:22:46 (2584): VM state change detected. (old = 'running', new = 'paused')
2025-07-29 12:23:07 (2584): VM state change detected. (old = 'paused', new = 'running')
2025-07-29 12:23:16 (2584): VM state change detected. (old = 'running', new = 'paused')
2025-07-29 12:23:36 (2584): VM state change detected. (old = 'paused', new = 'running')
2025-07-29 12:23:46 (2584): VM state change detected. (old = 'running', new = 'paused')
2025-07-29 12:24:07 (2584): VM state change detected. (old = 'paused', new = 'running')
2025-07-29 12:24:16 (2584): VM state change detected. (old = 'running', new = 'paused')
2025-07-29 12:24:36 (2584): VM state change detected. (old = 'paused', new = 'running')
2025-07-29 12:24:46 (2584): VM state change detected. (old = 'running', new = 'paused')
2025-07-29 12:25:07 (2584): VM state change detected. (old = 'paused', new = 'running')
2025-07-29 12:25:16 (2584): VM state change detected. (old = 'running', new = 'paused')
2025-07-29 12:25:36 (2584): VM state change detected. (old = 'paused', new = 'running')
2025-07-29 12:25:46 (2584): VM state change detected. (old = 'running', new = 'paused')
2025-07-29 12:26:07 (2584): VM state change detected. (old = 'paused', new = 'running')
2025-07-29 12:26:16 (2584): VM state change detected. (old = 'running', new = 'paused')
2025-07-29 12:26:36 (2584): VM state change detected. (old = 'paused', new = 'running')
2025-07-29 12:26:46 (2584): VM state change detected. (old = 'running', new = 'paused')
2025-07-29 12:27:07 (2584): VM state change detected. (old = 'paused', new = 'running')
2025-07-29 12:27:16 (2584): VM state change detected. (old = 'running', new = 'paused')
2025-07-29 12:27:56 (2584): VM state change detected. (old = 'paused', new = 'running')
2025-07-29 12:28:07 (2584): VM state change detected. (old = 'running', new = 'paused')
2025-07-29 12:28:27 (2584): VM state change detected. (old = 'paused', new = 'running')
2025-07-29 12:28:37 (2584): VM state change detected. (old = 'running', new = 'paused')
2025-07-29 12:28:57 (2584): VM state change detected. (old = 'paused', new = 'running')
2025-07-29 12:37:18 (2584): VM state change detected. (old = 'running', new = 'paused')
2025-07-29 12:37:38 (2584): VM state change detected. (old = 'paused', new = 'running')
2025-07-29 12:37:48 (2584): VM state change detected. (old = 'running', new = 'paused')
2025-07-29 12:38:08 (2584): VM state change detected. (old = 'paused', new = 'running')
2025-07-29 12:38:18 (2584): VM state change detected. (old = 'running', new = 'paused')
2025-07-29 12:38:38 (2584): VM state change detected. (old = 'paused', new = 'running')
2025-07-29 12:38:48 (2584): VM state change detected. (old = 'running', new = 'paused')
2025-07-29 12:39:08 (2584): VM state change detected. (old = 'paused', new = 'running')
2025-07-29 12:39:18 (2584): VM state change detected. (old = 'running', new = 'paused')
2025-07-29 12:39:38 (2584): VM state change detected. (old = 'paused', new = 'running')
2025-07-29 12:39:48 (2584): VM state change detected. (old = 'running', new = 'paused')
2025-07-29 12:40:08 (2584): VM state change detected. (old = 'paused', new = 'running')
2025-07-29 12:40:18 (2584): VM state change detected. (old = 'running', new = 'paused')
2025-07-29 12:40:38 (2584): VM state change detected. (old = 'paused', new = 'running')
2025-07-29 12:40:48 (2584): VM state change detected. (old = 'running', new = 'paused')
2025-07-29 12:41:08 (2584): VM state change detected. (old = 'paused', new = 'running')
2025-07-29 12:41:18 (2584): VM state change detected. (old = 'running', new = 'paused')
2025-07-29 12:41:38 (2584): VM state change detected. (old = 'paused', new = 'running')
2025-07-29 12:41:48 (2584): VM state change detected. (old = 'running', new = 'paused')
2025-07-29 12:42:08 (2584): VM state change detected. (old = 'paused', new = 'running')
2025-07-29 12:42:18 (2584): VM state change detected. (old = 'running', new = 'paused')
2025-07-29 12:42:38 (2584): VM state change detected. (old = 'paused', new = 'running')
2025-07-29 12:42:48 (2584): VM state change detected. (old = 'running', new = 'paused')
2025-07-29 12:43:28 (2584): VM state change detected. (old = 'paused', new = 'running')
2025-07-29 12:43:38 (2584): VM state change detected. (old = 'running', new = 'paused')
2025-07-29 12:43:58 (2584): VM state change detected. (old = 'paused', new = 'running')
2025-07-29 12:52:18 (2584): VM state change detected. (old = 'running', new = 'paused')
2025-07-29 12:52:38 (2584): VM state change detected. (old = 'paused', new = 'running')
2025-07-29 12:52:48 (2584): VM state change detected. (old = 'running', new = 'paused')
2025-07-29 12:53:08 (2584): VM state change detected. (old = 'paused', new = 'running')
2025-07-29 12:53:18 (2584): VM state change detected. (old = 'running', new = 'paused')
2025-07-29 12:53:38 (2584): VM state change detected. (old = 'paused', new = 'running')
2025-07-29 12:53:48 (2584): VM state change detected. (old = 'running', new = 'paused')
2025-07-29 12:54:08 (2584): VM state change detected. (old = 'paused', new = 'running')
2025-07-29 12:54:18 (2584): VM state change detected. (old = 'running', new = 'paused')
2025-07-29 12:54:38 (2584): VM state change detected. (old = 'paused', new = 'running')
2025-07-29 12:54:48 (2584): VM state change detected. (old = 'running', new = 'paused')
2025-07-29 12:55:08 (2584): VM state change detected. (old = 'paused', new = 'running')
2025-07-29 12:55:18 (2584): VM state change detected. (old = 'running', new = 'paused')
2025-07-29 12:55:38 (2584): VM state change detected. (old = 'paused', new = 'running')
2025-07-29 12:55:48 (2584): VM state change detected. (old = 'running', new = 'paused')
2025-07-29 12:56:08 (2584): VM state change detected. (old = 'paused', new = 'running')
2025-07-29 12:56:18 (2584): VM state change detected. (old = 'running', new = 'paused')
2025-07-29 12:56:38 (2584): VM state change detected. (old = 'paused', new = 'running')
2025-07-29 12:56:48 (2584): VM state change detected. (old = 'running', new = 'paused')
2025-07-29 12:57:08 (2584): VM state change detected. (old = 'paused', new = 'running')
2025-07-29 12:57:18 (2584): VM state change detected. (old = 'running', new = 'paused')
2025-07-29 12:57:38 (2584): VM state change detected. (old = 'paused', new = 'running')
2025-07-29 12:57:48 (2584): VM state change detected. (old = 'running', new = 'paused')
2025-07-29 12:58:08 (2584): VM state change detected. (old = 'paused', new = 'running')
2025-07-29 12:58:18 (2584): VM state change detected. (old = 'running', new = 'paused')
2025-07-29 12:58:38 (2584): VM state change detected. (old = 'paused', new = 'running')
2025-07-29 13:07:19 (2584): VM state change detected. (old = 'running', new = 'paused')
2025-07-29 13:07:39 (2584): VM state change detected. (old = 'paused', new = 'running')
2025-07-29 13:07:49 (2584): VM state change detected. (old = 'running', new = 'paused')
2025-07-29 13:08:09 (2584): VM state change detected. (old = 'paused', new = 'running')
2025-07-29 13:08:19 (2584): VM state change detected. (old = 'running', new = 'paused')
2025-07-29 13:08:39 (2584): VM state change detected. (old = 'paused', new = 'running')
2025-07-29 13:08:49 (2584): VM state change detected. (old = 'running', new = 'paused')
2025-07-29 13:09:09 (2584): VM state change detected. (old = 'paused', new = 'running')
2025-07-29 13:09:19 (2584): VM state change detected. (old = 'running', new = 'paused')
2025-07-29 13:09:39 (2584): VM state change detected. (old = 'paused', new = 'running')
2025-07-29 13:09:49 (2584): VM state change detected. (old = 'running', new = 'paused')
2025-07-29 13:10:09 (2584): VM state change detected. (old = 'paused', new = 'running')
2025-07-29 13:10:19 (2584): VM state change detected. (old = 'running', new = 'paused')
2025-07-29 13:10:39 (2584): VM state change detected. (old = 'paused', new = 'running')
2025-07-29 13:10:49 (2584): VM state change detected. (old = 'running', new = 'paused')
2025-07-29 13:11:09 (2584): VM state change detected. (old = 'paused', new = 'running')
2025-07-29 13:11:19 (2584): VM state change detected. (old = 'running', new = 'paused')
2025-07-29 13:11:39 (2584): VM state change detected. (old = 'paused', new = 'running')
2025-07-29 13:11:49 (2584): VM state change detected. (old = 'running', new = 'paused')
2025-07-29 13:12:09 (2584): VM state change detected. (old = 'paused', new = 'running')
2025-07-29 13:12:19 (2584): VM state change detected. (old = 'running', new = 'paused')
2025-07-29 13:12:39 (2584): VM state change detected. (old = 'paused', new = 'running')
2025-07-29 13:12:49 (2584): VM state change detected. (old = 'running', new = 'paused')
2025-07-29 13:13:29 (2584): VM state change detected. (old = 'paused', new = 'running')
2025-07-29 13:13:39 (2584): VM state change detected. (old = 'running', new = 'paused')
2025-07-29 13:13:59 (2584): VM state change detected. (old = 'paused', new = 'running')
2025-07-29 13:16:48 (2584): Stopping VM.
2025-07-29 13:17:01 (2584): Successfully stopped VM.
2025-07-29 13:48:48 (2056): Detected: vboxwrapper 26206
2025-07-29 13:48:48 (2056): Detected: BOINC client v8.0.2
2025-07-29 13:48:48 (2056): Detected: VirtualBox VboxManage Interface (Version: 7.0.6)
2025-07-29 13:48:49 (2056): Guest Log: 07:56:55.203897 timesync vgsvcTimeSyncWorker: Radical host time change: 2 268 572 000 000ns (HostNow=1 753 740 000 620 000 000 ns HostLast=1 753 737 732 048 000 000 ns)
2025-07-29 13:48:49 (2056): Guest Log: 07:57:05.228757 timesync vgsvcTimeSyncWorker: Radical guest time change: 2 288 506 213 000ns (GuestNow=1 753 740 010 644 904 000 ns GuestLast=1 753 737 722 138 691 000 ns fSetTimeLastLoop=true )
2025-07-29 13:48:49 (2056): Starting VM using VBoxManage interface. (boinc_379e9b46f011caa4, slot#1)
2025-07-29 13:49:04 (2056): Successfully started VM. (PID = '19012')
2025-07-29 13:49:04 (2056): Reporting VM Process ID to BOINC.
2025-07-29 13:49:04 (2056): Guest Log: 12:18:02.827881 timesync vgsvcTimeSyncWorker: Radical host time change: 1 944 913 000 000ns (HostNow=1 753 760 944 497 000 000 ns HostLast=1 753 758 999 584 000 000 ns)
2025-07-29 13:49:04 (2056): VM state change detected. (old = 'poweredoff', new = 'running')
2025-07-29 13:49:04 (2056): Detected: Web Application Enabled (http://localhost:60766)
2025-07-29 13:49:04 (2056): Status Report: Elapsed Time: '64023.000000'
2025-07-29 13:49:04 (2056): Status Report: CPU Time: '38386.921875'
2025-07-29 13:49:04 (2056): Preference change detected
2025-07-29 13:49:04 (2056): Setting CPU throttle for VM. (20%)
2025-07-29 13:49:05 (2056): Setting checkpoint interval to 900 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 900 seconds))
2025-07-29 13:49:14 (2056): Guest Log: 12:18:12.853706 timesync vgsvcTimeSyncWorker: Radical guest time change: 2 863 149 625 000ns (GuestNow=1 753 760 954 522 860 000 ns GuestLast=1 753 758 091 373 235 000 ns fSetTimeLastLoop=true )
2025-07-29 13:52:55 (2056): VM state change detected. (old = 'running', new = 'paused')
2025-07-29 13:53:15 (2056): VM state change detected. (old = 'paused', new = 'running')
2025-07-29 13:54:35 (2056): VM state change detected. (old = 'running', new = 'paused')
2025-07-29 13:54:55 (2056): VM state change detected. (old = 'paused', new = 'running')
2025-07-29 13:56:35 (2056): VM state change detected. (old = 'running', new = 'paused')
2025-07-29 13:56:55 (2056): VM state change detected. (old = 'paused', new = 'running')
2025-07-29 13:57:55 (2056): VM state change detected. (old = 'running', new = 'paused')
2025-07-29 13:58:15 (2056): VM state change detected. (old = 'paused', new = 'running')
2025-07-29 13:58:45 (2056): VM state change detected. (old = 'running', new = 'paused')
2025-07-29 13:59:05 (2056): VM state change detected. (old = 'paused', new = 'running')
2025-07-29 14:08:05 (2056): VM state change detected. (old = 'running', new = 'paused')
2025-07-29 14:08:25 (2056): VM state change detected. (old = 'paused', new = 'running')
2025-07-29 14:08:45 (2056): VM state change detected. (old = 'running', new = 'paused')
2025-07-29 14:09:05 (2056): VM state change detected. (old = 'paused', new = 'running')
2025-07-29 14:09:35 (2056): VM state change detected. (old = 'running', new = 'paused')
2025-07-29 14:09:55 (2056): VM state change detected. (old = 'paused', new = 'running')
2025-07-29 14:10:55 (2056): VM state change detected. (old = 'running', new = 'paused')
2025-07-29 14:11:15 (2056): VM state change detected. (old = 'paused', new = 'running')
2025-07-29 14:12:05 (2056): VM state change detected. (old = 'running', new = 'paused')
2025-07-29 14:12:25 (2056): VM state change detected. (old = 'paused', new = 'running')
2025-07-29 14:12:55 (2056): VM state change detected. (old = 'running', new = 'paused')
2025-07-29 14:13:15 (2056): VM state change detected. (old = 'paused', new = 'running')
2025-07-29 14:13:25 (2056): VM state change detected. (old = 'running', new = 'paused')
2025-07-29 14:13:45 (2056): VM state change detected. (old = 'paused', new = 'running')
2025-07-29 14:22:56 (2056): VM state change detected. (old = 'running', new = 'paused')
2025-07-29 14:23:16 (2056): VM state change detected. (old = 'paused', new = 'running')
2025-07-29 14:23:56 (2056): VM state change detected. (old = 'running', new = 'paused')
2025-07-29 14:24:16 (2056): VM state change detected. (old = 'paused', new = 'running')
2025-07-29 14:25:16 (2056): VM state change detected. (old = 'running', new = 'paused')
2025-07-29 14:25:36 (2056): VM state change detected. (old = 'paused', new = 'running')
2025-07-29 14:27:56 (2056): VM state change detected. (old = 'running', new = 'paused')
2025-07-29 14:28:16 (2056): VM state change detected. (old = 'paused', new = 'running')
2025-07-29 14:28:36 (2056): VM state change detected. (old = 'running', new = 'paused')
2025-07-29 14:28:56 (2056): VM state change detected. (old = 'paused', new = 'running')
2025-07-29 14:37:56 (2056): VM state change detected. (old = 'running', new = 'paused')
2025-07-29 14:38:16 (2056): VM state change detected. (old = 'paused', new = 'running')
2025-07-29 14:39:16 (2056): VM state change detected. (old = 'running', new = 'paused')
2025-07-29 14:39:36 (2056): VM state change detected. (old = 'paused', new = 'running')
2025-07-29 14:39:57 (2056): VM state change detected. (old = 'running', new = 'paused')
2025-07-29 14:40:20 (2056): VM state change detected. (old = 'paused', new = 'running')
2025-07-29 14:40:57 (2056): VM state change detected. (old = 'running', new = 'paused')
2025-07-29 14:41:20 (2056): VM state change detected. (old = 'paused', new = 'running')
2025-07-29 14:42:57 (2056): VM state change detected. (old = 'running', new = 'paused')
2025-07-29 14:43:20 (2056): VM state change detected. (old = 'paused', new = 'running')
2025-07-29 14:53:08 (2056): VM state change detected. (old = 'running', new = 'paused')
2025-07-29 14:53:31 (2056): VM state change detected. (old = 'paused', new = 'running')
2025-07-29 14:53:58 (2056): VM state change detected. (old = 'running', new = 'paused')
2025-07-29 14:54:21 (2056): VM state change detected. (old = 'paused', new = 'running')
2025-07-29 14:54:58 (2056): VM state change detected. (old = 'running', new = 'paused')
2025-07-29 14:55:21 (2056): VM state change detected. (old = 'paused', new = 'running')
2025-07-29 14:55:58 (2056): VM state change detected. (old = 'running', new = 'paused')
2025-07-29 14:56:21 (2056): VM state change detected. (old = 'paused', new = 'running')
2025-07-29 14:57:18 (2056): VM state change detected. (old = 'running', new = 'paused')
2025-07-29 14:57:38 (2056): VM state change detected. (old = 'paused', new = 'running')
2025-07-29 14:57:58 (2056): VM state change detected. (old = 'running', new = 'paused')
2025-07-29 14:58:21 (2056): VM state change detected. (old = 'paused', new = 'running')
2025-07-29 15:07:18 (2056): VM state change detected. (old = 'running', new = 'paused')
2025-07-29 15:07:38 (2056): VM state change detected. (old = 'paused', new = 'running')
2025-07-29 15:08:08 (2056): VM state change detected. (old = 'running', new = 'paused')
2025-07-29 15:08:31 (2056): VM state change detected. (old = 'paused', new = 'running')
2025-07-29 15:09:08 (2056): VM state change detected. (old = 'running', new = 'paused')
2025-07-29 15:09:31 (2056): VM state change detected. (old = 'paused', new = 'running')
2025-07-29 15:09:48 (2056): VM state change detected. (old = 'running', new = 'paused')
2025-07-29 15:10:11 (2056): VM state change detected. (old = 'paused', new = 'running')
2025-07-29 15:10:49 (2056): VM state change detected. (old = 'running', new = 'paused')
2025-07-29 15:11:12 (2056): VM state change detected. (old = 'paused', new = 'running')
2025-07-29 15:11:59 (2056): VM state change detected. (old = 'running', new = 'paused')
2025-07-29 15:12:22 (2056): VM state change detected. (old = 'paused', new = 'running')
2025-07-29 15:12:59 (2056): VM state change detected. (old = 'running', new = 'paused')
2025-07-29 15:13:22 (2056): VM state change detected. (old = 'paused', new = 'running')
2025-07-29 15:24:49 (2056): VM state change detected. (old = 'running', new = 'paused')
2025-07-29 15:25:12 (2056): VM state change detected. (old = 'paused', new = 'running')
2025-07-29 15:25:39 (2056): VM state change detected. (old = 'running', new = 'paused')
2025-07-29 15:25:59 (2056): VM state change detected. (old = 'paused', new = 'running')
2025-07-29 15:26:09 (2056): VM state change detected. (old = 'running', new = 'paused')
2025-07-29 15:26:32 (2056): VM state change detected. (old = 'paused', new = 'running')
2025-07-29 15:27:09 (2056): VM state change detected. (old = 'running', new = 'paused')
2025-07-29 15:27:32 (2056): VM state change detected. (old = 'paused', new = 'running')
2025-07-29 15:28:09 (2056): VM state change detected. (old = 'running', new = 'paused')
2025-07-29 15:28:32 (2056): VM state change detected. (old = 'paused', new = 'running')
2025-07-29 15:38:10 (2056): VM state change detected. (old = 'running', new = 'paused')
2025-07-29 15:38:33 (2056): VM state change detected. (old = 'paused', new = 'running')
2025-07-29 15:40:20 (2056): VM state change detected. (old = 'running', new = 'paused')
2025-07-29 15:40:43 (2056): VM state change detected. (old = 'paused', new = 'running')
2025-07-29 15:40:50 (2056): VM state change detected. (old = 'running', new = 'paused')
2025-07-29 15:41:10 (2056): VM state change detected. (old = 'paused', new = 'running')
2025-07-29 15:41:50 (2056): VM state change detected. (old = 'running', new = 'paused')
2025-07-29 15:42:10 (2056): VM state change detected. (old = 'paused', new = 'running')
2025-07-29 15:42:20 (2056): VM state change detected. (old = 'running', new = 'paused')
2025-07-29 15:42:43 (2056): VM state change detected. (old = 'paused', new = 'running')
2025-07-29 15:43:00 (2056): VM state change detected. (old = 'running', new = 'paused')
2025-07-29 15:43:20 (2056): VM state change detected. (old = 'paused', new = 'running')
2025-07-29 15:43:30 (2056): VM state change detected. (old = 'running', new = 'paused')
2025-07-29 15:43:53 (2056): VM state change detected. (old = 'paused', new = 'running')
2025-07-29 15:46:42 (2056): Status Report: Elapsed Time: '70023.000000'
2025-07-29 15:46:42 (2056): Status Report: CPU Time: '42045.578125'
2025-07-29 15:52:20 (2056): VM state change detected. (old = 'running', new = 'paused')
2025-07-29 15:52:40 (2056): VM state change detected. (old = 'paused', new = 'running')
2025-07-29 15:53:11 (2056): VM state change detected. (old = 'running', new = 'paused')
2025-07-29 15:53:34 (2056): VM state change detected. (old = 'paused', new = 'running')
2025-07-29 15:54:22 (2056): VM state change detected. (old = 'running', new = 'paused')
2025-07-29 15:54:42 (2056): VM state change detected. (old = 'paused', new = 'running')
2025-07-29 15:55:32 (2056): VM state change detected. (old = 'running', new = 'paused')
2025-07-29 15:55:52 (2056): VM state change detected. (old = 'paused', new = 'running')
2025-07-29 15:56:22 (2056): VM state change detected. (old = 'running', new = 'paused')
2025-07-29 16:03:11 (2056): VM state change detected. (old = 'paused', new = 'running')
2025-07-29 16:04:56 (2056): Stopping VM.
2025-07-29 16:05:08 (2056): Successfully stopped VM.
2025-07-29 16:07:58 (5460): Detected: vboxwrapper 26206
2025-07-29 16:07:58 (5460): Detected: BOINC client v8.0.2
2025-07-29 16:07:59 (5460): Detected: VirtualBox VboxManage Interface (Version: 7.0.6)
2025-07-29 16:08:00 (5460): Guest Log: 12:18:02.827881 timesync vgsvcTimeSyncWorker: Radical host time change: 1 944 913 000 000ns (HostNow=1 753 760 944 497 000 000 ns HostLast=1 753 758 999 584 000 000 ns)
2025-07-29 16:08:00 (5460): Guest Log: 12:18:12.853706 timesync vgsvcTimeSyncWorker: Radical guest time change: 2 863 149 625 000ns (GuestNow=1 753 760 954 522 860 000 ns GuestLast=1 753 758 091 373 235 000 ns fSetTimeLastLoop=true )
2025-07-29 16:08:00 (5460): Starting VM using VBoxManage interface. (boinc_379e9b46f011caa4, slot#1)
2025-07-29 16:08:15 (5460): Successfully started VM. (PID = '7940')
2025-07-29 16:08:15 (5460): Reporting VM Process ID to BOINC.
2025-07-29 16:08:15 (5460): VM state change detected. (old = 'poweredoff', new = 'running')
2025-07-29 16:08:15 (5460): Detected: Web Application Enabled (http://localhost:60766)
2025-07-29 16:08:16 (5460): VM state change detected. (old = 'running', new = 'paused')
2025-07-29 16:08:35 (5460): Status Report: Elapsed Time: '70621.000000'
2025-07-29 16:08:35 (5460): Status Report: CPU Time: '42420.781250'
2025-07-29 16:08:35 (5460): Preference change detected
2025-07-29 16:08:35 (5460): Setting CPU throttle for VM. (20%)
2025-07-29 16:08:35 (5460): Setting checkpoint interval to 900 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 900 seconds))
2025-07-29 16:08:35 (5460): VM state change detected. (old = 'paused', new = 'running')
2025-07-29 16:08:43 (5460): VM state change detected. (old = 'running', new = 'paused')
2025-07-29 16:09:03 (5460): VM state change detected. (old = 'paused', new = 'running')
2025-07-29 16:09:14 (5460): VM state change detected. (old = 'running', new = 'paused')
2025-07-29 16:09:35 (5460): VM state change detected. (old = 'paused', new = 'running')
2025-07-29 16:09:44 (5460): VM state change detected. (old = 'running', new = 'paused')
2025-07-29 16:11:04 (5460): VM state change detected. (old = 'paused', new = 'running')
2025-07-29 16:11:14 (5460): VM state change detected. (old = 'running', new = 'paused')
2025-07-29 16:11:35 (5460): VM state change detected. (old = 'paused', new = 'running')
2025-07-29 16:12:04 (5460): VM state change detected. (old = 'running', new = 'paused')
2025-07-29 16:12:24 (5460): VM state change detected. (old = 'paused', new = 'running')
2025-07-29 16:12:54 (5460): VM state change detected. (old = 'running', new = 'paused')
2025-07-29 16:13:15 (5460): VM state change detected. (old = 'paused', new = 'running')
2025-07-29 16:14:46 (5460): Stopping VM.
2025-07-29 16:14:57 (5460): Successfully stopped VM.
2025-07-29 16:17:48 (18488): Detected: vboxwrapper 26206
2025-07-29 16:17:48 (18488): Detected: BOINC client v8.0.2
2025-07-29 16:17:48 (18488): Detected: VirtualBox VboxManage Interface (Version: 7.0.6)
2025-07-29 16:17:49 (18488): Starting VM using VBoxManage interface. (boinc_379e9b46f011caa4, slot#1)
2025-07-29 16:18:04 (18488): Successfully started VM. (PID = '18348')
2025-07-29 16:18:04 (18488): Reporting VM Process ID to BOINC.
2025-07-29 16:18:04 (18488): VM state change detected. (old = 'poweredoff', new = 'running')
2025-07-29 16:18:04 (18488): Detected: Web Application Enabled (http://localhost:60766)
2025-07-29 16:18:04 (18488): Status Report: Elapsed Time: '70803.000000'
2025-07-29 16:18:04 (18488): Status Report: CPU Time: '42533.250000'
2025-07-29 16:18:04 (18488): Preference change detected
2025-07-29 16:18:04 (18488): Setting CPU throttle for VM. (20%)
2025-07-29 16:18:05 (18488): Setting checkpoint interval to 900 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 900 seconds))
2025-07-29 16:20:43 (18488): Stopping VM.
2025-07-29 16:20:54 (18488): Successfully stopped VM.
2025-07-29 16:23:57 (18380): Detected: vboxwrapper 26206
2025-07-29 16:23:57 (18380): Detected: BOINC client v8.0.2
2025-07-29 16:23:58 (18380): Detected: VirtualBox VboxManage Interface (Version: 7.0.6)
2025-07-29 16:23:58 (18380): Starting VM using VBoxManage interface. (boinc_379e9b46f011caa4, slot#1)
2025-07-29 16:24:14 (18380): Successfully started VM. (PID = '9748')
2025-07-29 16:24:14 (18380): Reporting VM Process ID to BOINC.
2025-07-29 16:24:14 (18380): VM state change detected. (old = 'poweredoff', new = 'running')
2025-07-29 16:24:14 (18380): Detected: Web Application Enabled (http://localhost:60766)
2025-07-29 16:24:14 (18380): Status Report: Elapsed Time: '70961.000000'
2025-07-29 16:24:14 (18380): Status Report: CPU Time: '42636.265625'
2025-07-29 16:24:14 (18380): Preference change detected
2025-07-29 16:24:14 (18380): Setting CPU throttle for VM. (20%)
2025-07-29 16:24:14 (18380): Setting checkpoint interval to 900 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 900 seconds))
2025-07-29 16:25:09 (18380): VM state change detected. (old = 'running', new = 'paused')
2025-07-29 16:25:33 (18380): VM state change detected. (old = 'paused', new = 'running')
2025-07-29 16:25:49 (18380): VM state change detected. (old = 'running', new = 'paused')
2025-07-29 16:26:09 (18380): VM state change detected. (old = 'paused', new = 'running')
2025-07-29 16:26:19 (18380): VM state change detected. (old = 'running', new = 'paused')
2025-07-29 16:26:43 (18380): VM state change detected. (old = 'paused', new = 'running')
2025-07-29 16:27:09 (18380): VM state change detected. (old = 'running', new = 'paused')
2025-07-29 16:27:29 (18380): VM state change detected. (old = 'paused', new = 'running')
2025-07-29 16:27:49 (18380): VM state change detected. (old = 'running', new = 'paused')
2025-07-29 16:28:29 (18380): VM state change detected. (old = 'paused', new = 'running')
2025-07-29 16:38:20 (18380): VM state change detected. (old = 'running', new = 'paused')
2025-07-29 16:38:40 (18380): VM state change detected. (old = 'paused', new = 'running')
2025-07-29 16:39:20 (18380): VM state change detected. (old = 'running', new = 'paused')
2025-07-29 16:39:40 (18380): VM state change detected. (old = 'paused', new = 'running')
2025-07-29 16:41:00 (18380): VM state change detected. (old = 'running', new = 'paused')
2025-07-29 16:46:32 (18380): VM state change detected. (old = 'paused', new = 'running')
2025-07-29 16:52:21 (18380): VM state change detected. (old = 'running', new = 'paused')
2025-07-29 16:52:41 (18380): VM state change detected. (old = 'paused', new = 'running')
2025-07-29 16:54:12 (18380): VM state change detected. (old = 'running', new = 'paused')
2025-07-29 16:54:34 (18380): VM state change detected. (old = 'paused', new = 'running')
2025-07-29 16:55:22 (18380): VM state change detected. (old = 'running', new = 'paused')
2025-07-29 16:55:42 (18380): VM state change detected. (old = 'paused', new = 'running')
2025-07-29 16:56:12 (18380): VM state change detected. (old = 'running', new = 'paused')
2025-07-29 16:56:34 (18380): VM state change detected. (old = 'paused', new = 'running')
2025-07-29 16:57:12 (18380): VM state change detected. (old = 'running', new = 'paused')
2025-07-29 16:57:34 (18380): VM state change detected. (old = 'paused', new = 'running')
2025-07-29 16:57:52 (18380): VM state change detected. (old = 'running', new = 'paused')
2025-07-29 16:58:14 (18380): VM state change detected. (old = 'paused', new = 'running')
2025-07-29 16:58:32 (18380): VM state change detected. (old = 'running', new = 'paused')
2025-07-29 16:58:52 (18380): VM state change detected. (old = 'paused', new = 'running')
2025-07-29 16:59:09 (18380): Stopping VM.
2025-07-29 16:59:21 (18380): Successfully stopped VM.
2025-07-29 17:44:49 (5852): Detected: vboxwrapper 26206
2025-07-29 17:44:49 (5852): Detected: BOINC client v8.0.2
2025-07-29 17:44:49 (5852): Detected: VirtualBox VboxManage Interface (Version: 7.0.6)
2025-07-29 17:44:50 (5852): Starting VM using VBoxManage interface. (boinc_379e9b46f011caa4, slot#1)
2025-07-29 17:45:06 (5852): Successfully started VM. (PID = '7288')
2025-07-29 17:45:06 (5852): Reporting VM Process ID to BOINC.
2025-07-29 17:45:06 (5852): VM state change detected. (old = 'poweredoff', new = 'running')
2025-07-29 17:45:06 (5852): Detected: Web Application Enabled (http://localhost:60766)
2025-07-29 17:45:06 (5852): Status Report: Elapsed Time: '72393.000000'
2025-07-29 17:45:06 (5852): Status Report: CPU Time: '43519.125000'
2025-07-29 17:45:06 (5852): Preference change detected
2025-07-29 17:45:06 (5852): Setting CPU throttle for VM. (20%)
2025-07-29 17:45:06 (5852): Setting checkpoint interval to 900 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 900 seconds))
2025-07-29 17:45:15 (5852): Guest Log: 14:39:13.680215 timesync vgsvcTimeSyncWorker: Radical host time change: 2 765 391 000 000ns (HostNow=1 753 775 114 665 000 000 ns HostLast=1 753 772 349 274 000 000 ns)
2025-07-29 17:45:25 (5852): Guest Log: 14:39:23.734563 timesync vgsvcTimeSyncWorker: Radical guest time change: 3 411 319 604 000ns (GuestNow=1 753 775 125 038 634 000 ns GuestLast=1 753 771 713 719 030 000 ns fSetTimeLastLoop=true )
2025-07-29 17:51:38 (5852): Stopping VM.
2025-07-29 17:51:50 (5852): Successfully stopped VM.
2025-07-29 18:15:11 (5880): Detected: vboxwrapper 26206
2025-07-29 18:15:11 (5880): Detected: BOINC client v8.0.2
2025-07-29 18:15:12 (5880): Detected: VirtualBox VboxManage Interface (Version: 7.0.6)
2025-07-29 18:15:13 (5880): Guest Log: 14:39:13.680215 timesync vgsvcTimeSyncWorker: Radical host time change: 2 765 391 000 000ns (HostNow=1 753 775 114 665 000 000 ns HostLast=1 753 772 349 274 000 000 ns)
2025-07-29 18:15:13 (5880): Guest Log: 14:39:23.734563 timesync vgsvcTimeSyncWorker: Radical guest time change: 3 411 319 604 000ns (GuestNow=1 753 775 125 038 634 000 ns GuestLast=1 753 771 713 719 030 000 ns fSetTimeLastLoop=true )
2025-07-29 18:15:13 (5880): Starting VM using VBoxManage interface. (boinc_379e9b46f011caa4, slot#1)
2025-07-29 18:15:29 (5880): Successfully started VM. (PID = '2004')
2025-07-29 18:15:29 (5880): Reporting VM Process ID to BOINC.
2025-07-29 18:15:29 (5880): VM state change detected. (old = 'poweredoff', new = 'running')
2025-07-29 18:15:29 (5880): Detected: Web Application Enabled (http://localhost:60766)
2025-07-29 18:15:29 (5880): Stopping VM.
2025-07-29 18:15:41 (5880): Successfully stopped VM.
2025-07-29 18:40:14 (19240): Detected: vboxwrapper 26206
2025-07-29 18:40:14 (19240): Detected: BOINC client v8.0.2
2025-07-29 18:40:15 (19240): Detected: VirtualBox VboxManage Interface (Version: 7.0.6)
2025-07-29 18:40:15 (19240): Starting VM using VBoxManage interface. (boinc_379e9b46f011caa4, slot#1)
2025-07-29 18:40:31 (19240): Successfully started VM. (PID = '14932')
2025-07-29 18:40:31 (19240): Reporting VM Process ID to BOINC.
2025-07-29 18:40:31 (19240): VM state change detected. (old = 'poweredoff', new = 'running')
2025-07-29 18:40:31 (19240): Detected: Web Application Enabled (http://localhost:60766)
2025-07-29 18:40:32 (19240): VM state change detected. (old = 'running', new = 'paused')
2025-07-29 20:38:49 (19240): Status Report: Elapsed Time: '72785.000000'
2025-07-29 20:38:49 (19240): Status Report: CPU Time: '43767.390625'
2025-07-29 20:38:49 (19240): Preference change detected
2025-07-29 20:38:49 (19240): Setting CPU throttle for VM. (20%)
2025-07-29 20:38:49 (19240): Setting checkpoint interval to 900 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 900 seconds))
2025-07-29 20:38:49 (19240): VM state change detected. (old = 'paused', new = 'running')
2025-07-29 20:38:55 (19240): Guest Log: 14:45:45.261523 timesync vgsvcTimeSyncWorker: Radical host time change: 10 038 445 000 000ns (HostNow=1 753 785 534 983 000 000 ns HostLast=1 753 775 496 538 000 000 ns)
2025-07-29 20:39:05 (19240): Guest Log: 14:45:55.264551 timesync vgsvcTimeSyncWorker: Radical guest time change: 10 038 420 804 000ns (GuestNow=1 753 785 544 986 082 000 ns GuestLast=1 753 775 506 565 278 000 ns fSetTimeLastLoop=true )
2025-07-29 20:39:49 (19240): Stopping VM.
2025-07-29 20:40:02 (19240): Successfully stopped VM.
2025-07-29 20:47:18 (10772): Detected: vboxwrapper 26206
2025-07-29 20:47:18 (10772): Detected: BOINC client v8.0.2
2025-07-29 20:47:19 (10772): Detected: VirtualBox VboxManage Interface (Version: 7.0.6)
2025-07-29 20:47:20 (10772): Guest Log: 14:45:45.261523 timesync vgsvcTimeSyncWorker: Radical host time change: 10 038 445 000 000ns (HostNow=1 753 785 534 983 000 000 ns HostLast=1 753 775 496 538 000 000 ns)
2025-07-29 20:47:20 (10772): Guest Log: 14:45:55.264551 timesync vgsvcTimeSyncWorker: Radical guest time change: 10 038 420 804 000ns (GuestNow=1 753 785 544 986 082 000 ns GuestLast=1 753 775 506 565 278 000 ns fSetTimeLastLoop=true )
2025-07-29 20:47:20 (10772): Starting VM using VBoxManage interface. (boinc_379e9b46f011caa4, slot#1)
2025-07-29 20:47:36 (10772): Successfully started VM. (PID = '22544')
2025-07-29 20:47:36 (10772): Reporting VM Process ID to BOINC.
2025-07-29 20:47:36 (10772): VM state change detected. (old = 'poweredoff', new = 'running')
2025-07-29 20:47:36 (10772): Detected: Web Application Enabled (http://localhost:60766)
2025-07-29 20:47:37 (10772): VM state change detected. (old = 'running', new = 'paused')
2025-07-29 20:47:56 (10772): Status Report: Elapsed Time: '72846.000000'
2025-07-29 20:47:56 (10772): Status Report: CPU Time: '43829.625000'
2025-07-29 20:47:56 (10772): Preference change detected
2025-07-29 20:47:56 (10772): Setting CPU throttle for VM. (20%)
2025-07-29 20:47:56 (10772): Setting checkpoint interval to 900 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 900 seconds))
2025-07-29 20:47:56 (10772): VM state change detected. (old = 'paused', new = 'running')
2025-07-29 20:49:15 (10772): VM state change detected. (old = 'running', new = 'paused')
2025-07-29 20:49:35 (10772): VM state change detected. (old = 'paused', new = 'running')
2025-07-29 20:50:04 (10772): Stopping VM.
2025-07-29 20:50:17 (10772): Successfully stopped VM.
2025-07-30 06:31:54 (10188): Detected: vboxwrapper 26206
2025-07-30 06:31:54 (10188): Detected: BOINC client v8.0.2
2025-07-30 06:31:54 (10188): Detected: VirtualBox VboxManage Interface (Version: 7.0.6)
2025-07-30 06:31:55 (10188): Starting VM using VBoxManage interface. (boinc_379e9b46f011caa4, slot#1)
2025-07-30 06:32:16 (10188): Successfully started VM. (PID = '11412')
2025-07-30 06:32:16 (10188): Reporting VM Process ID to BOINC.
2025-07-30 06:32:16 (10188): VM state change detected. (old = 'poweredoff', new = 'running')
2025-07-30 06:32:16 (10188): Detected: Web Application Enabled (http://localhost:60766)
2025-07-30 06:32:16 (10188): Status Report: Elapsed Time: '72954.000000'
2025-07-30 06:32:16 (10188): Status Report: CPU Time: '43889.828125'
2025-07-30 06:32:16 (10188): Preference change detected
2025-07-30 06:32:16 (10188): Setting CPU throttle for VM. (20%)
2025-07-30 06:32:16 (10188): Setting checkpoint interval to 900 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 900 seconds))
2025-07-30 06:32:21 (10188): Guest Log: 14:48:36.024552 timesync vgsvcTimeSyncWorker: Radical host time change: 34 940 047 000 000ns (HostNow=1 753 821 140 255 000 000 ns HostLast=1 753 786 200 208 000 000 ns)
2025-07-30 06:32:31 (10188): Guest Log: 14:48:46.025349 timesync vgsvcTimeSyncWorker: Radical guest time change: 34 959 888 090 000ns (GuestNow=1 753 821 150 255 837 000 ns GuestLast=1 753 786 190 367 747 000 ns fSetTimeLastLoop=true )
2025-07-30 06:33:54 (10188): Stopping VM.
2025-07-30 06:34:07 (10188): Successfully stopped VM.
2025-07-30 06:39:17 (6552): Detected: vboxwrapper 26206
2025-07-30 06:39:17 (6552): Detected: BOINC client v8.0.2
2025-07-30 06:39:18 (6552): Detected: VirtualBox VboxManage Interface (Version: 7.0.6)
2025-07-30 06:39:19 (6552): Guest Log: 14:48:36.024552 timesync vgsvcTimeSyncWorker: Radical host time change: 34 940 047 000 000ns (HostNow=1 753 821 140 255 000 000 ns HostLast=1 753 786 200 208 000 000 ns)
2025-07-30 06:39:19 (6552): Guest Log: 14:48:46.025349 timesync vgsvcTimeSyncWorker: Radical guest time change: 34 959 888 090 000ns (GuestNow=1 753 821 150 255 837 000 ns GuestLast=1 753 786 190 367 747 000 ns fSetTimeLastLoop=true )
2025-07-30 06:39:19 (6552): Starting VM using VBoxManage interface. (boinc_379e9b46f011caa4, slot#1)
2025-07-30 06:39:34 (6552): Successfully started VM. (PID = '2448')
2025-07-30 06:39:34 (6552): Reporting VM Process ID to BOINC.
2025-07-30 06:39:34 (6552): VM state change detected. (old = 'poweredoff', new = 'running')
2025-07-30 06:39:34 (6552): Detected: Web Application Enabled (http://localhost:60766)
2025-07-30 06:39:34 (6552): Status Report: Elapsed Time: '73052.000000'
2025-07-30 06:39:34 (6552): Status Report: CPU Time: '43956.156250'
2025-07-30 06:39:34 (6552): Preference change detected
2025-07-30 06:39:34 (6552): Setting CPU throttle for VM. (20%)
2025-07-30 06:39:34 (6552): Setting checkpoint interval to 900 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 900 seconds))
2025-07-30 06:40:14 (6552): Guest Log:  *** Job finished ***
2025-07-30 06:40:14 (6552): Guest Log:  *** The last 20 lines of the pilot log: ***
2025-07-30 06:40:14 (6552): Guest Log: 2025-07-29 20:40:05,607 | INFO     | waiting for thread to finish: ['<_MainThread(MainThread, started 140500924237632)>', '<ExcThread(queue_monitor, started 140500040406784)>']
2025-07-30 06:40:14 (6552): Guest Log: 2025-07-29 20:40:05,607 | INFO     | [job] queue monitor thread has finished
2025-07-30 06:40:14 (6552): Guest Log: 2025-07-29 20:40:06,070 | INFO     | caller=run is remaining thread - safe to abort (names=['<_MainThread(MainThread, started 140500924237632)>'])
2025-07-30 06:40:14 (6552): Guest Log: 2025-07-29 20:40:11,108 | INFO     | all workflow threads have been joined
2025-07-30 06:40:14 (6552): Guest Log: 2025-07-29 20:40:11,108 | INFO     | end of generic workflow (traces error code: 1213)
2025-07-30 06:40:14 (6552): Guest Log: 2025-07-29 20:40:11,108 | INFO     | traces error code: 1213
2025-07-30 06:40:14 (6552): Guest Log: 2025-07-29 20:40:11,108 | INFO     | an exit code was already set: 1213 (will be converted to a standard shell code)
2025-07-30 06:40:14 (6552): Guest Log: 2025-07-29 20:40:11,108 | INFO     | pilot has finished (exit code=1213, shell exit code=81)
2025-07-30 06:40:14 (6552): Guest Log: 2025-07-29 20:40:11,318 [wrapper] ==== pilot stdout END ====
2025-07-30 06:40:14 (6552): Guest Log: 2025-07-29 20:40:11,321 [wrapper] ==== wrapper stdout RESUME ====
2025-07-30 06:40:14 (6552): Guest Log: 2025-07-29 20:40:11,324 [wrapper] pilotpid: 5846
2025-07-30 06:40:14 (6552): Guest Log: 2025-07-29 20:40:11,327 [wrapper] Pilot exit status: 81
2025-07-30 06:40:14 (6552): Guest Log: 2025-07-29 20:40:13,502 [wrapper] pandaids: 6745133701
2025-07-30 06:40:14 (6552): Guest Log: 2025-07-29 20:40:13,505 [wrapper] WARNING: pilot exitcode non-zero: 81
2025-07-30 06:40:14 (6552): Guest Log: 2025-07-29 20:40:13,508 [wrapper] WARNING: pilot exitcode non-zero: 81
2025-07-30 06:40:14 (6552): Guest Log: 2025-07-29 20:40:13,705 [wrapper] cleanup supervisor_pilot 28791 5847
2025-07-30 06:40:14 (6552): Guest Log: 2025-07-29 20:40:13,709 [wrapper] Test setup, not cleaning
2025-07-30 06:40:14 (6552): Guest Log: 2025-07-29 20:40:13,714 [wrapper] apfmon messages muted
2025-07-30 06:40:14 (6552): Guest Log: 2025-07-29 20:40:13,718 [wrapper] ==== wrapper stdout END ====
2025-07-30 06:40:14 (6552): Guest Log: 2025-07-29 20:40:13,723 [wrapper] ==== wrapper stderr END ====
2025-07-30 06:40:14 (6552): Guest Log:  *** Error codes and diagnostics ***
2025-07-30 06:40:14 (6552): Guest Log:  *** Listing of results directory ***
2025-07-30 06:40:15 (6552): Guest Log: total 12648
2025-07-30 06:40:15 (6552): Guest Log: -rw-r--r--. 1 atlas atlas    5112 Jul 26 11:02 queuedata.json
2025-07-30 06:40:15 (6552): Guest Log: -rwx------. 1 atlas atlas   36292 Jul 26 11:03 runpilot2-wrapper.sh
2025-07-30 06:40:15 (6552): Guest Log: -rw-r--r--. 1 atlas atlas  530466 Jul 26 11:03 pilot3.tar.gz
2025-07-30 06:40:15 (6552): Guest Log: -rwxr-xr-x. 1 atlas atlas    8801 Jul 27 20:36 init_data.xml
2025-07-30 06:40:15 (6552): Guest Log: -rwxr-xr-x. 1 atlas atlas  542893 Jul 27 20:36 input.tar.gz
2025-07-30 06:40:15 (6552): Guest Log: -rwxr-xr-x. 1 atlas atlas   15095 Jul 27 20:36 start_atlas.sh
2025-07-30 06:40:15 (6552): Guest Log: lrwxrwxrwx. 1 atlas atlas      20 Jul 27 20:36 EVNT.45743347._002094.pool.root.1 -> /data/./ATLAS.root_0
2025-07-30 06:40:15 (6552): Guest Log: -rw-r--r--. 1 atlas atlas    2562 Jul 27 20:37 pandaJob.out
2025-07-30 06:40:15 (6552): Guest Log: -rw-------. 1 atlas atlas  980109 Jul 27 20:38 agis_schedconf.cvmfs.json
2025-07-30 06:40:15 (6552): Guest Log: -rw-------. 1 atlas atlas 1593052 Jul 27 20:38 agis_ddmendpoints.agis.ALL.json
2025-07-30 06:40:15 (6552): Guest Log: drwx------. 4 atlas atlas    4096 Jul 27 20:38 pilot3
2025-07-30 06:40:15 (6552): Guest Log: -rw-------. 1 atlas atlas      98 Jul 29 20:39 pilot_heartbeat.json
2025-07-30 06:40:15 (6552): Guest Log: drwxrwx---. 2 atlas atlas    4096 Jul 29 20:39 PanDA_Pilot-6745133701
2025-07-30 06:40:15 (6552): Guest Log: -rw-------. 1 atlas atlas    1025 Jul 29 20:39 memory_monitor_summary.json
2025-07-30 06:40:15 (6552): Guest Log: -rw-------. 1 atlas atlas    1167 Jul 29 20:39 heartbeat.json
2025-07-30 06:40:15 (6552): Guest Log: -rw-------. 1 atlas atlas 3043009 Jul 29 20:40 pilotlog.txt
2025-07-30 06:40:15 (6552): Guest Log: -rw-------. 1 atlas atlas 3058551 Jul 29 20:40 log.45743349._036703.job.log.1
2025-07-30 06:40:15 (6552): Guest Log: -rw-r--r--. 1 atlas atlas     571 Jul 29 20:40 runtime_log
2025-07-30 06:40:15 (6552): Guest Log: -rw-------. 1 atlas atlas 3072000 Jul 29 20:40 result.tar.gz
2025-07-30 06:40:15 (6552): Guest Log: -rw-------. 1 atlas atlas     658 Jul 29 20:40 utUNDmyrrz7nsSi4ap6QjLDmwznN0nGgGQJmUXzaDmKuRLDmXuxIUm.diag
2025-07-30 06:40:15 (6552): Guest Log: -rw-r--r--. 1 atlas atlas    7938 Jul 29 20:40 runtime_log.err
2025-07-30 06:40:15 (6552): Guest Log: Looking for outputfile HITS.45743349._036703.pool.root.1
2025-07-30 06:40:15 (6552): Guest Log: No HITS file was produced
2025-07-30 06:40:15 (6552): Guest Log: Successfully finished the ATLAS job!
2025-07-30 06:40:15 (6552): Guest Log: Copying the results back to the shared directory!
2025-07-30 06:40:15 (6552): Guest Log:  *** Contents of shared directory: ***
2025-

©2025 CERN