Stderr output

<core_client_version>8.2.4</core_client_version>
<![CDATA[
<stderr_txt>
 (old = 'paused', new = 'running')
2025-10-18 11:34:02 (14604): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 11:34:22 (14604): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 11:34:32 (14604): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 11:34:52 (14604): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 11:35:02 (14604): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 11:35:22 (14604): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 11:35:32 (14604): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 11:35:52 (14604): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 11:36:02 (14604): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 11:36:22 (14604): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 11:36:32 (14604): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 11:36:52 (14604): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 11:37:02 (14604): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 11:37:22 (14604): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 11:37:32 (14604): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 11:37:52 (14604): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 11:38:02 (14604): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 11:38:22 (14604): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 11:38:32 (14604): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 11:38:52 (14604): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 11:39:02 (14604): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 11:39:22 (14604): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 11:39:32 (14604): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 11:39:52 (14604): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 11:40:02 (14604): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 11:40:22 (14604): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 11:40:32 (14604): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 11:40:52 (14604): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 11:41:02 (14604): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 11:41:22 (14604): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 11:41:32 (14604): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 11:41:52 (14604): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 11:42:02 (14604): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 11:42:23 (14604): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 11:42:33 (14604): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 11:42:53 (14604): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 11:43:03 (14604): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 11:43:23 (14604): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 11:43:33 (14604): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 11:43:53 (14604): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 11:44:03 (14604): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 11:44:23 (14604): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 11:44:33 (14604): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 11:44:53 (14604): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 11:45:04 (14604): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 11:45:23 (14604): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 11:45:33 (14604): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 11:45:53 (14604): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 11:46:04 (14604): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 11:46:24 (14604): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 11:46:34 (14604): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 11:46:54 (14604): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 11:47:04 (14604): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 11:47:24 (14604): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 11:47:34 (14604): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 11:47:54 (14604): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 11:48:04 (14604): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 11:48:24 (14604): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 11:48:34 (14604): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 11:48:54 (14604): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 11:49:04 (14604): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 11:49:24 (14604): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 11:49:24 (14604): Stopping VM.
2025-10-18 11:49:54 (14604): Successfully stopped VM.
2025-10-18 12:50:15 (16836): vboxwrapper version 26210
2025-10-18 12:50:15 (16836): BOINC client version: 8.2.4
2025-10-18 12:50:16 (16836): Detected: VirtualBox VboxManage Interface (Version: 7.0.6)
2025-10-18 12:50:16 (16836): Guest Log: 01:13:50.968832 timesync vgsvcTimeSyncWorker: Radical host time change: 3 928 053 000 000ns (HostNow=1 760 798 669 044 000 000 ns HostLast=1 760 794 740 991 000 000 ns)
2025-10-18 12:50:16 (16836): Guest Log: 01:14:00.976201 timesync vgsvcTimeSyncWorker: Radical guest time change: 5 076 007 066 000ns (GuestNow=1 760 798 679 052 055 000 ns GuestLast=1 760 793 603 044 989 000 ns fSetTimeLastLoop=true )
2025-10-18 12:50:16 (16836): Starting VM using VBoxManage interface. (boinc_d84010c7b60321ef, slot#4)
2025-10-18 12:50:44 (16836): Successfully started VM. (PID = '13524')
2025-10-18 12:50:44 (16836): Reporting VM Process ID to BOINC.
2025-10-18 12:50:44 (16836): VM state change detected. (old = 'poweredoff', new = 'running')
2025-10-18 12:50:44 (16836): Detected: Web Application Enabled (http://localhost:63377)
2025-10-18 12:50:44 (16836): Preference change detected
2025-10-18 12:50:44 (16836): Setting CPU throttle for VM. (100%)
2025-10-18 12:50:45 (16836): Setting checkpoint interval to 900 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 900 seconds))
2025-10-18 12:50:50 (16836): Guest Log: 01:36:01.262025 timesync vgsvcTimeSyncWorker: Radical host time change: 3 708 648 000 000ns (HostNow=1 760 806 250 715 000 000 ns HostLast=1 760 802 542 067 000 000 ns)
2025-10-18 12:50:58 (16836): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 12:51:18 (16836): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 12:51:20 (16836): Guest Log: 01:36:11.266963 timesync vgsvcTimeSyncWorker: Radical guest time change: 3 847 449 999 000ns (GuestNow=1 760 806 260 721 939 000 ns GuestLast=1 760 802 413 271 940 000 ns fSetTimeLastLoop=true )
2025-10-18 12:51:28 (16836): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 12:51:49 (16836): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 12:51:59 (16836): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 12:52:19 (16836): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 12:52:29 (16836): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 12:52:49 (16836): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 12:52:59 (16836): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 12:53:19 (16836): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 12:53:29 (16836): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 12:53:49 (16836): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 12:53:59 (16836): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 12:54:19 (16836): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 12:54:29 (16836): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 12:54:49 (16836): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 12:54:59 (16836): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 12:55:19 (16836): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 12:55:29 (16836): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 12:55:49 (16836): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 12:55:59 (16836): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 12:56:19 (16836): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 12:56:29 (16836): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 12:56:49 (16836): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 12:56:59 (16836): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 12:57:39 (16836): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 12:57:49 (16836): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 12:58:09 (16836): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 12:58:19 (16836): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 12:58:39 (16836): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 12:58:49 (16836): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 12:59:09 (16836): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 12:59:19 (16836): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 12:59:39 (16836): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 12:59:49 (16836): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 13:00:09 (16836): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 13:00:19 (16836): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 13:00:39 (16836): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 13:00:49 (16836): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 13:01:09 (16836): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 13:01:19 (16836): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 13:01:39 (16836): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 13:01:49 (16836): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 13:02:09 (16836): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 13:02:19 (16836): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 13:02:40 (16836): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 13:02:49 (16836): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 13:03:10 (16836): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 13:03:20 (16836): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 13:03:40 (16836): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 13:03:51 (16836): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 13:04:11 (16836): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 13:04:21 (16836): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 13:04:41 (16836): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 13:04:50 (16836): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 13:05:11 (16836): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 13:05:21 (16836): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 13:05:41 (16836): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 13:05:51 (16836): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 13:06:11 (16836): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 13:06:21 (16836): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 13:06:41 (16836): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 13:06:51 (16836): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 13:07:11 (16836): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 13:07:21 (16836): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 13:07:41 (16836): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 13:07:51 (16836): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 13:08:11 (16836): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 13:08:21 (16836): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 13:08:41 (16836): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 13:08:51 (16836): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 13:09:11 (16836): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 13:09:21 (16836): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 13:09:41 (16836): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 13:09:51 (16836): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 13:10:11 (16836): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 13:10:21 (16836): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 13:10:41 (16836): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 13:10:51 (16836): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 13:11:11 (16836): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 13:11:21 (16836): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 13:11:41 (16836): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 13:11:51 (16836): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 13:12:11 (16836): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 13:12:21 (16836): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 13:12:41 (16836): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 13:12:51 (16836): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 13:13:11 (16836): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 13:13:21 (16836): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 13:13:41 (16836): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 13:13:51 (16836): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 13:14:11 (16836): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 13:14:22 (16836): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 13:14:42 (16836): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 13:14:52 (16836): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 13:15:12 (16836): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 13:15:22 (16836): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 13:15:42 (16836): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 13:15:52 (16836): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 13:16:12 (16836): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 13:16:22 (16836): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 13:16:42 (16836): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 13:16:52 (16836): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 13:17:12 (16836): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 13:17:22 (16836): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 13:17:42 (16836): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 13:17:52 (16836): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 13:18:12 (16836): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 13:18:22 (16836): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 13:18:42 (16836): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 13:18:52 (16836): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 13:19:12 (16836): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 13:19:22 (16836): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 13:19:42 (16836): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 13:19:52 (16836): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 13:20:12 (16836): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 13:20:22 (16836): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 13:20:42 (16836): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 13:20:52 (16836): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 13:21:12 (16836): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 13:21:22 (16836): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 13:21:42 (16836): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 13:21:52 (16836): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 13:22:12 (16836): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 13:22:22 (16836): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 13:22:42 (16836): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 13:22:52 (16836): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 13:23:12 (16836): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 13:23:22 (16836): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 13:23:42 (16836): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 13:23:52 (16836): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 13:24:12 (16836): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 13:24:22 (16836): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 13:24:42 (16836): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 13:24:52 (16836): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 13:25:12 (16836): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 13:25:22 (16836): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 13:25:42 (16836): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 13:25:52 (16836): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 13:26:12 (16836): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 13:26:23 (16836): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 13:26:43 (16836): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 13:26:53 (16836): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 13:27:13 (16836): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 13:27:23 (16836): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 13:27:43 (16836): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 13:27:53 (16836): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 13:28:13 (16836): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 13:28:23 (16836): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 13:28:43 (16836): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 13:28:53 (16836): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 13:29:13 (16836): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 13:29:23 (16836): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 13:29:43 (16836): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 13:29:53 (16836): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 13:30:13 (16836): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 13:30:23 (16836): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 13:30:43 (16836): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 13:30:53 (16836): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 13:31:13 (16836): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 13:31:23 (16836): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 13:31:43 (16836): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 13:31:53 (16836): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 13:32:13 (16836): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 13:32:23 (16836): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 13:32:43 (16836): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 13:32:53 (16836): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 13:33:13 (16836): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 13:33:23 (16836): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 13:33:43 (16836): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 13:33:53 (16836): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 13:34:13 (16836): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 13:34:23 (16836): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 13:34:43 (16836): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 13:34:53 (16836): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 13:35:13 (16836): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 13:35:23 (16836): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 13:35:43 (16836): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 13:35:53 (16836): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 13:36:13 (16836): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 13:36:23 (16836): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 13:36:43 (16836): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 13:36:53 (16836): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 13:37:14 (16836): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 13:37:24 (16836): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 13:37:44 (16836): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 13:37:54 (16836): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 13:38:14 (16836): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 13:38:24 (16836): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 13:38:44 (16836): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 13:38:54 (16836): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 13:39:14 (16836): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 13:39:20 (16836): Status Report: Elapsed Time: '6000.551658'
2025-10-18 13:39:20 (16836): Status Report: CPU Time: '46237.093750'
2025-10-18 13:39:24 (16836): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 13:39:44 (16836): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 13:39:54 (16836): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 13:40:14 (16836): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 13:40:24 (16836): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 13:40:44 (16836): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 13:40:54 (16836): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 13:41:14 (16836): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 13:41:24 (16836): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 13:41:44 (16836): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 13:41:54 (16836): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 13:42:14 (16836): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 13:42:24 (16836): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 13:42:44 (16836): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 13:42:54 (16836): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 13:43:14 (16836): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 13:43:24 (16836): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 13:43:44 (16836): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 13:43:54 (16836): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 13:44:14 (16836): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 13:44:24 (16836): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 13:44:44 (16836): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 13:44:54 (16836): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 13:45:14 (16836): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 13:45:24 (16836): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 13:45:44 (16836): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 13:45:54 (16836): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 13:46:14 (16836): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 13:46:24 (16836): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 13:46:44 (16836): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 13:46:54 (16836): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 13:47:14 (16836): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 13:47:24 (16836): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 13:47:45 (16836): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 13:47:55 (16836): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 13:48:15 (16836): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 13:48:25 (16836): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 13:48:45 (16836): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 13:48:55 (16836): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 13:49:15 (16836): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 13:49:25 (16836): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 13:49:45 (16836): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 13:49:55 (16836): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 13:50:15 (16836): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 13:50:25 (16836): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 13:50:45 (16836): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 13:50:49 (16836): Stopping VM.
2025-10-18 13:51:22 (16836): Successfully stopped VM.
2025-10-18 14:51:22 (508): vboxwrapper version 26210
2025-10-18 14:51:22 (508): BOINC client version: 8.2.4
2025-10-18 14:51:23 (508): Detected: VirtualBox VboxManage Interface (Version: 7.0.6)
2025-10-18 14:51:24 (508): Guest Log: 01:36:01.262025 timesync vgsvcTimeSyncWorker: Radical host time change: 3 708 648 000 000ns (HostNow=1 760 806 250 715 000 000 ns HostLast=1 760 802 542 067 000 000 ns)
2025-10-18 14:51:24 (508): Guest Log: 01:36:11.266963 timesync vgsvcTimeSyncWorker: Radical guest time change: 3 847 449 999 000ns (GuestNow=1 760 806 260 721 939 000 ns GuestLast=1 760 802 413 271 940 000 ns fSetTimeLastLoop=true )
2025-10-18 14:51:24 (508): Starting VM using VBoxManage interface. (boinc_d84010c7b60321ef, slot#4)
2025-10-18 14:51:48 (508): Successfully started VM. (PID = '444')
2025-10-18 14:51:48 (508): Reporting VM Process ID to BOINC.
2025-10-18 14:51:48 (508): VM state change detected. (old = 'poweredoff', new = 'running')
2025-10-18 14:51:48 (508): Detected: Web Application Enabled (http://localhost:63377)
2025-10-18 14:51:49 (508): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 14:51:49 (508): Status Report: Elapsed Time: '6202.130159'
2025-10-18 14:51:49 (508): Status Report: CPU Time: '47885.031250'
2025-10-18 14:51:49 (508): Preference change detected
2025-10-18 14:51:49 (508): Setting CPU throttle for VM. (100%)
2025-10-18 14:51:49 (508): Setting checkpoint interval to 900 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 900 seconds))
2025-10-18 14:51:50 (508): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 14:51:57 (508): Guest Log: 01:56:31.531531 timesync vgsvcTimeSyncWorker: Radical host time change: 3 666 927 000 000ns (HostNow=1 760 813 516 562 000 000 ns HostLast=1 760 809 849 635 000 000 ns)
2025-10-18 14:52:00 (508): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 14:52:20 (508): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 14:52:27 (508): Guest Log: 01:56:41.532013 timesync vgsvcTimeSyncWorker: Radical guest time change: 4 835 726 007 000ns (GuestNow=1 760 813 526 562 948 000 ns GuestLast=1 760 808 690 836 941 000 ns fSetTimeLastLoop=true )
2025-10-18 14:52:30 (508): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 14:52:50 (508): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 14:53:00 (508): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 14:53:20 (508): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 14:53:30 (508): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 14:53:50 (508): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 14:54:00 (508): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 14:54:20 (508): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 14:54:30 (508): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 14:54:50 (508): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 14:55:00 (508): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 14:55:20 (508): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 14:55:30 (508): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 14:55:50 (508): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 14:56:00 (508): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 14:56:20 (508): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 14:56:30 (508): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 14:56:50 (508): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 14:57:00 (508): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 14:57:20 (508): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 14:57:30 (508): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 14:57:50 (508): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 14:58:10 (508): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 14:58:30 (508): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 14:58:40 (508): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 14:59:00 (508): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 14:59:10 (508): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 14:59:30 (508): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 14:59:40 (508): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 15:00:00 (508): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 15:00:10 (508): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 15:00:31 (508): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 15:00:41 (508): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 15:01:01 (508): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 15:01:11 (508): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 15:01:31 (508): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 15:01:41 (508): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 15:02:01 (508): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 15:02:11 (508): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 15:02:31 (508): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 15:02:41 (508): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 15:03:01 (508): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 15:03:11 (508): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 15:03:31 (508): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 15:03:41 (508): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 15:04:01 (508): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 15:04:11 (508): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 15:04:31 (508): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 15:04:41 (508): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 15:05:01 (508): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 15:05:11 (508): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 15:05:31 (508): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 15:05:41 (508): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 15:06:01 (508): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 15:06:11 (508): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 15:06:31 (508): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 15:06:41 (508): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 15:07:01 (508): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 15:07:11 (508): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 15:07:31 (508): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 15:07:41 (508): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 15:08:01 (508): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 15:08:11 (508): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 15:08:32 (508): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 15:08:42 (508): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 15:09:01 (508): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 15:09:11 (508): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 15:09:31 (508): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 15:09:41 (508): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 15:10:01 (508): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 15:10:11 (508): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 15:10:31 (508): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 15:10:41 (508): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 15:11:01 (508): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 15:11:11 (508): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 15:11:32 (508): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 15:11:42 (508): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 15:12:02 (508): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 15:12:12 (508): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 15:12:32 (508): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 15:12:42 (508): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 15:13:02 (508): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 15:13:12 (508): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 15:13:32 (508): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 15:13:42 (508): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 15:14:02 (508): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 15:14:12 (508): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 15:14:33 (508): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 15:14:42 (508): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 15:15:02 (508): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 15:15:12 (508): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 15:15:32 (508): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 15:15:42 (508): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 15:16:02 (508): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 15:16:12 (508): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 15:16:32 (508): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 15:16:42 (508): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 15:17:02 (508): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 15:17:12 (508): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 15:17:32 (508): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 15:17:42 (508): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 15:18:02 (508): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 15:18:12 (508): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 15:18:32 (508): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 15:18:42 (508): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 15:19:02 (508): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 15:19:12 (508): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 15:19:32 (508): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 15:19:42 (508): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 15:20:02 (508): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 15:20:12 (508): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 15:20:32 (508): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 15:20:42 (508): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 15:21:02 (508): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 15:21:12 (508): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 15:21:32 (508): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 15:21:43 (508): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 15:22:03 (508): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 15:22:13 (508): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 15:22:33 (508): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 15:22:43 (508): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 15:23:03 (508): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 15:23:13 (508): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 15:23:34 (508): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 15:23:43 (508): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 15:24:03 (508): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 15:24:13 (508): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 15:24:33 (508): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 15:24:44 (508): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 15:25:04 (508): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 15:25:13 (508): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 15:25:33 (508): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 15:25:44 (508): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 15:26:03 (508): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 15:26:14 (508): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 15:26:34 (508): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 15:26:43 (508): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 15:27:03 (508): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 15:27:13 (508): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 15:27:33 (508): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 15:27:43 (508): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 15:28:03 (508): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 15:28:14 (508): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 15:28:33 (508): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 15:28:43 (508): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 15:29:04 (508): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 15:29:14 (508): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 15:29:34 (508): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 15:29:44 (508): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 15:30:03 (508): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 15:30:14 (508): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 15:30:34 (508): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 15:30:43 (508): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 15:31:04 (508): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 15:31:14 (508): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 15:31:34 (508): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 15:31:44 (508): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 15:32:04 (508): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 15:32:14 (508): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 15:32:34 (508): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 15:32:45 (508): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 15:33:05 (508): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 15:33:15 (508): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 15:33:35 (508): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 15:33:45 (508): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 15:34:05 (508): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 15:34:15 (508): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 15:34:35 (508): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 15:34:45 (508): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 15:35:05 (508): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 15:35:15 (508): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 15:35:35 (508): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 15:35:45 (508): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 15:36:05 (508): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 15:36:15 (508): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 15:36:35 (508): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 15:36:45 (508): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 15:37:05 (508): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 15:37:15 (508): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 15:37:35 (508): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 15:37:45 (508): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 15:38:05 (508): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 15:38:15 (508): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 15:38:35 (508): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 15:38:45 (508): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 15:39:05 (508): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 15:39:15 (508): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 15:39:35 (508): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 15:39:45 (508): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 15:40:05 (508): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 15:40:15 (508): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 15:40:35 (508): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 15:40:45 (508): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 15:41:05 (508): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 15:41:15 (508): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 15:41:35 (508): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 15:41:45 (508): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 15:42:05 (508): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 15:42:15 (508): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 15:42:35 (508): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 15:42:45 (508): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 15:43:05 (508): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 15:43:15 (508): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 15:43:35 (508): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 15:43:45 (508): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 15:44:05 (508): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 15:44:16 (508): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 15:44:36 (508): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 15:44:46 (508): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 15:45:06 (508): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 15:45:16 (508): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 15:45:36 (508): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 15:45:46 (508): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 15:46:06 (508): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 15:46:16 (508): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 15:46:36 (508): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 15:46:46 (508): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 15:47:06 (508): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 15:47:16 (508): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 15:47:36 (508): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 15:47:46 (508): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 15:48:06 (508): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 15:48:16 (508): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 15:48:36 (508): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 15:48:46 (508): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 15:49:06 (508): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 15:49:16 (508): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 15:49:36 (508): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 15:49:46 (508): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 15:50:06 (508): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 15:50:16 (508): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 15:50:36 (508): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 15:50:46 (508): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 15:51:06 (508): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 15:51:16 (508): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 15:51:36 (508): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 15:51:46 (508): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 15:52:06 (508): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 15:52:17 (508): Stopping VM.
2025-10-18 15:52:52 (508): Successfully stopped VM.
2025-10-18 16:53:05 (18168): vboxwrapper version 26210
2025-10-18 16:53:05 (18168): BOINC client version: 8.2.4
2025-10-18 16:53:07 (18168): Detected: VirtualBox VboxManage Interface (Version: 7.0.6)
2025-10-18 16:53:07 (18168): Guest Log: 01:56:31.531531 timesync vgsvcTimeSyncWorker: Radical host time change: 3 666 927 000 000ns (HostNow=1 760 813 516 562 000 000 ns HostLast=1 760 809 849 635 000 000 ns)
2025-10-18 16:53:07 (18168): Guest Log: 01:56:41.532013 timesync vgsvcTimeSyncWorker: Radical guest time change: 4 835 726 007 000ns (GuestNow=1 760 813 526 562 948 000 ns GuestLast=1 760 808 690 836 941 000 ns fSetTimeLastLoop=true )
2025-10-18 16:53:07 (18168): Starting VM using VBoxManage interface. (boinc_d84010c7b60321ef, slot#4)
2025-10-18 16:53:39 (18168): Successfully started VM. (PID = '22648')
2025-10-18 16:53:39 (18168): Reporting VM Process ID to BOINC.
2025-10-18 16:53:39 (18168): VM state change detected. (old = 'poweredoff', new = 'running')
2025-10-18 16:53:39 (18168): Detected: Web Application Enabled (http://localhost:63377)
2025-10-18 16:53:39 (18168): Status Report: Elapsed Time: '7299.130159'
2025-10-18 16:53:39 (18168): Status Report: CPU Time: '56971.171875'
2025-10-18 16:53:39 (18168): Preference change detected
2025-10-18 16:53:39 (18168): Setting CPU throttle for VM. (100%)
2025-10-18 16:53:40 (18168): Setting checkpoint interval to 900 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 900 seconds))
2025-10-18 16:53:40 (18168): Guest Log: 02:17:21.814777 timesync vgsvcTimeSyncWorker: Radical host time change: 3 687 480 000 000ns (HostNow=1 760 820 820 211 000 000 ns HostLast=1 760 817 132 731 000 000 ns)
2025-10-18 16:53:48 (18168): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 16:54:08 (18168): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 16:54:10 (18168): Guest Log: 02:17:31.816138 timesync vgsvcTimeSyncWorker: Radical guest time change: 4 855 141 113 000ns (GuestNow=1 760 820 830 213 106 000 ns GuestLast=1 760 815 975 071 993 000 ns fSetTimeLastLoop=true )
2025-10-18 16:54:18 (18168): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 16:54:38 (18168): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 16:54:48 (18168): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 16:55:08 (18168): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 16:55:18 (18168): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 16:55:38 (18168): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 16:55:48 (18168): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 16:56:08 (18168): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 16:56:18 (18168): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 16:56:38 (18168): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 16:56:48 (18168): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 16:57:09 (18168): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 16:57:19 (18168): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 16:57:39 (18168): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 16:57:49 (18168): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 16:58:09 (18168): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 16:58:19 (18168): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 16:58:39 (18168): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 16:58:49 (18168): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 16:59:09 (18168): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 16:59:19 (18168): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 16:59:39 (18168): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 16:59:49 (18168): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 17:00:09 (18168): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 17:00:19 (18168): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 17:00:39 (18168): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 17:00:49 (18168): VM state change detected. (old = 'running', new = 'paused')
2025-10-18 17:01:09 (18168): VM state change detected. (old = 'paused', new = 'running')
2025-10-18 17:05:06 (18168): Guest Log:  *** Job finished ***
2025-10-18 17:05:06 (18168): Guest Log:  *** The last 20 lines of the pilot log: ***
2025-10-18 17:05:06 (18168): Guest Log: 2025-10-18 20:59:46,295 | INFO     | all workflow threads have been joined
2025-10-18 17:05:06 (18168): Guest Log: 2025-10-18 20:59:46,295 | INFO     | end of generic workflow (traces error code: 0)
2025-10-18 17:05:06 (18168): Guest Log: 2025-10-18 20:59:46,296 | INFO     | traces error code: 0
2025-10-18 17:05:06 (18168): Guest Log: 2025-10-18 20:59:46,296 | INFO     | pilot has finished (exit code=0, shell exit code=0)
2025-10-18 17:05:06 (18168): Guest Log: 2025-10-18 21:00:06,332 | INFO     | PID=6085 has CPU usage=3.8% CMD=/cvmfs/atlas.cern.ch/repo/ATLASLocalRootBase/x86_64/python/3.9.20-x86_64-centos7/bin/python3 pilot3/pilot.py -q BOINC_MCORE -i PR -j managed --pilot-user ATLAS --harvester-submit-mode PUSH -w generic --job-type managed --resource-type MCORE -z -t
2025-10-18 17:05:06 (18168): Guest Log: 2025-10-18 21:00:06,333 | INFO     | found 0 job(s) in 20 queues
2025-10-18 17:05:06 (18168): Guest Log: 2025-10-18 21:00:06,334 | WARNING  | pilot monitor received instruction that args.graceful_stop has been set
2025-10-18 17:05:06 (18168): Guest Log: 2025-10-18 21:00:06,334 | WARNING  | will wait for a maximum of 300 s for threads to finish
2025-10-18 17:05:06 (18168): Guest Log: 2025-10-18 21:00:06,334 | WARNING  | job_aborted has been set - aborting pilot monitoring
2025-10-18 17:05:06 (18168): Guest Log: 2025-10-18 21:00:06,334 | INFO     | [monitor] control thread has ended
2025-10-18 17:05:06 (18168): Guest Log: 2025-10-18 21:00:06,453 [wrapper] ==== pilot stdout END ====
2025-10-18 17:05:06 (18168): Guest Log: 2025-10-18 21:00:06,468 [wrapper] ==== wrapper stdout RESUME ====
2025-10-18 17:05:06 (18168): Guest Log: 2025-10-18 21:00:06,482 [wrapper] pilotpid: 6085
2025-10-18 17:05:06 (18168): Guest Log: 2025-10-18 21:00:06,494 [wrapper] Pilot exit status: 0
2025-10-18 17:05:06 (18168): Guest Log: 2025-10-18 21:00:06,588 [wrapper] pandaids: 6846892247
2025-10-18 17:05:06 (18168): Guest Log: 2025-10-18 21:00:06,660 [wrapper] cleanup supervisor_pilot 22009 6086
2025-10-18 17:05:06 (18168): Guest Log: 2025-10-18 21:00:06,672 [wrapper] Test setup, not cleaning
2025-10-18 17:05:06 (18168): Guest Log: 2025-10-18 21:00:06,684 [wrapper] apfmon messages muted
2025-10-18 17:05:06 (18168): Guest Log: 2025-10-18 21:00:06,696 [wrapper] ==== wrapper stdout END ====
2025-10-18 17:05:06 (18168): Guest Log: 2025-10-18 21:00:06,708 [wrapper] ==== wrapper stderr END ====
2025-10-18 17:05:06 (18168): Guest Log:  *** Error codes and diagnostics ***
2025-10-18 17:05:06 (18168): Guest Log:     "exeErrorCode": 0,
2025-10-18 17:05:06 (18168): Guest Log:     "exeErrorDiag": "",
2025-10-18 17:05:06 (18168): Guest Log:     "pilotErrorCode": 0,
2025-10-18 17:05:06 (18168): Guest Log:     "pilotErrorDiag": "",
2025-10-18 17:05:06 (18168): Guest Log:  *** Listing of results directory ***
2025-10-18 17:05:06 (18168): Guest Log: total 207180
2025-10-18 17:05:06 (18168): Guest Log: -rw-r--r--. 1 atlas atlas    557409 Oct 16 02:36 pilot3.tar.gz
2025-10-18 17:05:06 (18168): Guest Log: -rwx------. 1 atlas atlas     36292 Oct 16 02:50 runpilot2-wrapper.sh
2025-10-18 17:05:06 (18168): Guest Log: -rw-r--r--. 1 atlas atlas      5112 Oct 16 02:50 queuedata.json
2025-10-18 17:05:06 (18168): Guest Log: -rwxr-xr-x. 1 atlas atlas      8899 Oct 18 09:19 init_data.xml
2025-10-18 17:05:06 (18168): Guest Log: -rwxr-xr-x. 1 atlas atlas    569675 Oct 18 09:19 input.tar.gz
2025-10-18 17:05:06 (18168): Guest Log: -rwxr-xr-x. 1 atlas atlas     15120 Oct 18 09:19 start_atlas.sh
2025-10-18 17:05:06 (18168): Guest Log: lrwxrwxrwx. 1 atlas atlas        20 Oct 18 09:19 EVNT.46776529._000111.pool.root.1 -> /data/./ATLAS.root_0
2025-10-18 17:05:06 (18168): Guest Log: -rw-r--r--. 1 atlas atlas      2557 Oct 18 09:19 pandaJob.out
2025-10-18 17:05:06 (18168): Guest Log: -rw-------. 1 atlas atlas    976749 Oct 18 09:20 agis_schedconf.cvmfs.json
2025-10-18 17:05:06 (18168): Guest Log: drwx------. 5 atlas atlas      4096 Oct 18 09:20 pilot3
2025-10-18 17:05:06 (18168): Guest Log: -rw-------. 1 atlas atlas 205451285 Oct 18 20:58 HITS.46776533._007802.pool.root.1
2025-10-18 17:05:06 (18168): Guest Log: -rw-------. 1 atlas atlas      1039 Oct 18 20:59 memory_monitor_summary.json
2025-10-18 17:05:06 (18168): Guest Log: -rw-------. 1 atlas atlas   1560740 Oct 18 20:59 agis_ddmendpoints.agis.ALL.json
2025-10-18 17:05:06 (18168): Guest Log: -rw-------. 1 atlas atlas    384735 Oct 18 20:59 log.46776533._007802.job.log.tgz.1
2025-10-18 17:05:06 (18168): Guest Log: -rw-------. 1 atlas atlas      7845 Oct 18 20:59 heartbeat.json
2025-10-18 17:05:06 (18168): Guest Log: -rw-------. 1 atlas atlas        97 Oct 18 21:00 pilot_heartbeat.json
2025-10-18 17:05:06 (18168): Guest Log: -rw-------. 1 atlas atlas       744 Oct 18 21:00 pilotlog.txt
2025-10-18 17:05:06 (18168): Guest Log: -rw-------. 1 atlas atlas   1049225 Oct 18 21:00 log.46776533._007802.job.log.1
2025-10-18 17:05:06 (18168): Guest Log: -rw-------. 1 atlas atlas       357 Oct 18 21:00 output.list
2025-10-18 17:05:06 (18168): Guest Log: -rw-r--r--. 1 atlas atlas       620 Oct 18 21:00 runtime_log
2025-10-18 17:05:06 (18168): Guest Log: -rw-------. 1 atlas atlas   1454080 Oct 18 21:00 result.tar.gz
2025-10-18 17:05:06 (18168): Guest Log: -rw-r--r--. 1 atlas atlas      8649 Oct 18 21:00 runtime_log.err
2025-10-18 17:05:06 (18168): Guest Log: -rw-------. 1 atlas atlas       661 Oct 18 21:00 9LyMDmnFTT8n9Rq4apOajLDm4fhM0noT9bVof3QYDmYTXMDm0yeJXm.diag
2025-10-18 17:05:06 (18168): Guest Log: Looking for outputfile HITS.46776533._007802.pool.root.1
2025-10-18 17:05:06 (18168): Guest Log: HITS file was successfully produced
2025-10-18 17:05:06 (18168): Guest Log: -rw-------. 1 atlas atlas 205451285 Oct 18 20:58 /home/atlas/RunAtlas/HITS.46776533._007802.pool.root.1
2025-10-18 17:05:08 (18168): Guest Log: Successfully finished th

©2025 CERN