Stderr output

<core_client_version>8.0.2</core_client_version>
<![CDATA[
<message>
Unspecified error
 (0x80004005) - exit code 2147500037 (0x80004005)</message>
<stderr_txt>
 = 'paused', new = 'running')
2025-05-27 11:55:33 (26384): VM state change detected. (old = 'running', new = 'paused')
2025-05-27 11:55:53 (26384): VM state change detected. (old = 'paused', new = 'running')
2025-05-27 11:56:03 (26384): VM state change detected. (old = 'running', new = 'paused')
2025-05-27 11:56:23 (26384): VM state change detected. (old = 'paused', new = 'running')
2025-05-27 11:56:33 (26384): VM state change detected. (old = 'running', new = 'paused')
2025-05-27 11:56:53 (26384): VM state change detected. (old = 'paused', new = 'running')
2025-05-27 11:57:03 (26384): VM state change detected. (old = 'running', new = 'paused')
2025-05-27 11:57:24 (26384): VM state change detected. (old = 'paused', new = 'running')
2025-05-27 11:57:34 (26384): VM state change detected. (old = 'running', new = 'paused')
2025-05-27 11:57:54 (26384): VM state change detected. (old = 'paused', new = 'running')
2025-05-27 11:58:04 (26384): VM state change detected. (old = 'running', new = 'paused')
2025-05-27 11:58:24 (26384): VM state change detected. (old = 'paused', new = 'running')
2025-05-27 11:58:34 (26384): VM state change detected. (old = 'running', new = 'paused')
2025-05-27 11:58:54 (26384): VM state change detected. (old = 'paused', new = 'running')
2025-05-27 11:59:04 (26384): VM state change detected. (old = 'running', new = 'paused')
2025-05-27 11:59:24 (26384): VM state change detected. (old = 'paused', new = 'running')
2025-05-27 11:59:34 (26384): VM state change detected. (old = 'running', new = 'paused')
2025-05-27 11:59:54 (26384): VM state change detected. (old = 'paused', new = 'running')
2025-05-27 12:00:04 (26384): VM state change detected. (old = 'running', new = 'paused')
2025-05-27 12:00:24 (26384): VM state change detected. (old = 'paused', new = 'running')
2025-05-27 12:00:34 (26384): VM state change detected. (old = 'running', new = 'paused')
2025-05-27 12:00:54 (26384): VM state change detected. (old = 'paused', new = 'running')
2025-05-27 12:01:04 (26384): VM state change detected. (old = 'running', new = 'paused')
2025-05-27 12:01:24 (26384): VM state change detected. (old = 'paused', new = 'running')
2025-05-27 12:01:34 (26384): VM state change detected. (old = 'running', new = 'paused')
2025-05-27 12:01:54 (26384): VM state change detected. (old = 'paused', new = 'running')
2025-05-27 12:02:04 (26384): VM state change detected. (old = 'running', new = 'paused')
2025-05-27 12:02:24 (26384): VM state change detected. (old = 'paused', new = 'running')
2025-05-27 12:02:34 (26384): VM state change detected. (old = 'running', new = 'paused')
2025-05-27 12:02:54 (26384): VM state change detected. (old = 'paused', new = 'running')
2025-05-27 12:03:04 (26384): VM state change detected. (old = 'running', new = 'paused')
2025-05-27 12:03:24 (26384): VM state change detected. (old = 'paused', new = 'running')
2025-05-27 12:03:34 (26384): VM state change detected. (old = 'running', new = 'paused')
2025-05-27 12:03:54 (26384): VM state change detected. (old = 'paused', new = 'running')
2025-05-27 12:04:04 (26384): VM state change detected. (old = 'running', new = 'paused')
2025-05-27 12:04:24 (26384): VM state change detected. (old = 'paused', new = 'running')
2025-05-27 12:04:34 (26384): VM state change detected. (old = 'running', new = 'paused')
2025-05-27 12:04:54 (26384): VM state change detected. (old = 'paused', new = 'running')
2025-05-27 12:05:04 (26384): VM state change detected. (old = 'running', new = 'paused')
2025-05-27 12:05:24 (26384): VM state change detected. (old = 'paused', new = 'running')
2025-05-27 12:05:35 (26384): VM state change detected. (old = 'running', new = 'paused')
2025-05-27 12:05:54 (26384): VM state change detected. (old = 'paused', new = 'running')
2025-05-27 12:06:04 (26384): VM state change detected. (old = 'running', new = 'paused')
2025-05-27 12:06:25 (26384): VM state change detected. (old = 'paused', new = 'running')
2025-05-27 12:06:34 (26384): VM state change detected. (old = 'running', new = 'paused')
2025-05-27 12:06:56 (26384): VM state change detected. (old = 'paused', new = 'running')
2025-05-27 12:07:06 (26384): VM state change detected. (old = 'running', new = 'paused')
2025-05-27 12:07:26 (26384): VM state change detected. (old = 'paused', new = 'running')
2025-05-27 12:07:36 (26384): VM state change detected. (old = 'running', new = 'paused')
2025-05-27 12:07:56 (26384): VM state change detected. (old = 'paused', new = 'running')
2025-05-27 12:08:06 (26384): VM state change detected. (old = 'running', new = 'paused')
2025-05-27 12:09:55 (26384): VM state change detected. (old = 'paused', new = 'running')
2025-05-27 12:10:06 (26384): VM state change detected. (old = 'running', new = 'paused')
2025-05-27 12:10:26 (26384): VM state change detected. (old = 'paused', new = 'running')
2025-05-27 12:10:36 (26384): VM state change detected. (old = 'running', new = 'paused')
2025-05-27 12:10:56 (26384): VM state change detected. (old = 'paused', new = 'running')
2025-05-27 12:11:06 (26384): VM state change detected. (old = 'running', new = 'paused')
2025-05-27 12:11:26 (26384): VM state change detected. (old = 'paused', new = 'running')
2025-05-27 12:11:36 (26384): VM state change detected. (old = 'running', new = 'paused')
2025-05-27 12:11:56 (26384): VM state change detected. (old = 'paused', new = 'running')
2025-05-27 12:12:06 (26384): VM state change detected. (old = 'running', new = 'paused')
2025-05-27 12:12:26 (26384): VM state change detected. (old = 'paused', new = 'running')
2025-05-27 12:12:36 (26384): VM state change detected. (old = 'running', new = 'paused')
2025-05-27 12:12:56 (26384): VM state change detected. (old = 'paused', new = 'running')
2025-05-27 12:13:06 (26384): VM state change detected. (old = 'running', new = 'paused')
2025-05-27 12:13:26 (26384): VM state change detected. (old = 'paused', new = 'running')
2025-05-27 12:13:36 (26384): VM state change detected. (old = 'running', new = 'paused')
2025-05-27 12:13:56 (26384): VM state change detected. (old = 'paused', new = 'running')
2025-05-27 12:14:06 (26384): VM state change detected. (old = 'running', new = 'paused')
2025-05-27 12:14:26 (26384): VM state change detected. (old = 'paused', new = 'running')
2025-05-27 12:14:36 (26384): VM state change detected. (old = 'running', new = 'paused')
2025-05-27 12:14:56 (26384): VM state change detected. (old = 'paused', new = 'running')
2025-05-27 12:15:06 (26384): VM state change detected. (old = 'running', new = 'paused')
2025-05-27 12:15:26 (26384): VM state change detected. (old = 'paused', new = 'running')
2025-05-27 12:15:36 (26384): VM state change detected. (old = 'running', new = 'paused')
2025-05-27 12:15:56 (26384): VM state change detected. (old = 'paused', new = 'running')
2025-05-27 12:16:06 (26384): VM state change detected. (old = 'running', new = 'paused')
2025-05-27 12:16:26 (26384): VM state change detected. (old = 'paused', new = 'running')
2025-05-27 12:16:36 (26384): VM state change detected. (old = 'running', new = 'paused')
2025-05-27 12:16:56 (26384): VM state change detected. (old = 'paused', new = 'running')
2025-05-27 12:17:06 (26384): VM state change detected. (old = 'running', new = 'paused')
2025-05-27 12:17:26 (26384): VM state change detected. (old = 'paused', new = 'running')
2025-05-27 12:17:36 (26384): VM state change detected. (old = 'running', new = 'paused')
2025-05-27 12:17:56 (26384): VM state change detected. (old = 'paused', new = 'running')
2025-05-27 12:18:06 (26384): VM state change detected. (old = 'running', new = 'paused')
2025-05-27 12:18:26 (26384): VM state change detected. (old = 'paused', new = 'running')
2025-05-27 12:18:36 (26384): VM state change detected. (old = 'running', new = 'paused')
2025-05-27 12:18:56 (26384): VM state change detected. (old = 'paused', new = 'running')
2025-05-27 12:19:06 (26384): VM state change detected. (old = 'running', new = 'paused')
2025-05-27 12:19:26 (26384): VM state change detected. (old = 'paused', new = 'running')
2025-05-27 12:19:37 (26384): VM state change detected. (old = 'running', new = 'paused')
2025-05-27 12:19:57 (26384): VM state change detected. (old = 'paused', new = 'running')
2025-05-27 12:20:07 (26384): VM state change detected. (old = 'running', new = 'paused')
2025-05-27 12:20:27 (26384): VM state change detected. (old = 'paused', new = 'running')
2025-05-27 12:20:37 (26384): VM state change detected. (old = 'running', new = 'paused')
2025-05-27 12:20:57 (26384): VM state change detected. (old = 'paused', new = 'running')
2025-05-27 12:21:07 (26384): VM state change detected. (old = 'running', new = 'paused')
2025-05-27 12:21:27 (26384): VM state change detected. (old = 'paused', new = 'running')
2025-05-27 12:21:37 (26384): VM state change detected. (old = 'running', new = 'paused')
2025-05-27 12:21:57 (26384): VM state change detected. (old = 'paused', new = 'running')
2025-05-27 12:22:07 (26384): VM state change detected. (old = 'running', new = 'paused')
2025-05-27 12:22:27 (26384): VM state change detected. (old = 'paused', new = 'running')
2025-05-27 12:22:37 (26384): VM state change detected. (old = 'running', new = 'paused')
2025-05-27 12:22:57 (26384): VM state change detected. (old = 'paused', new = 'running')
2025-05-27 12:23:07 (26384): VM state change detected. (old = 'running', new = 'paused')
2025-05-27 12:23:27 (26384): VM state change detected. (old = 'paused', new = 'running')
2025-05-27 12:23:37 (26384): VM state change detected. (old = 'running', new = 'paused')
2025-05-27 12:23:57 (26384): VM state change detected. (old = 'paused', new = 'running')
2025-05-27 12:24:07 (26384): VM state change detected. (old = 'running', new = 'paused')
2025-05-27 12:24:27 (26384): VM state change detected. (old = 'paused', new = 'running')
2025-05-27 12:24:37 (26384): VM state change detected. (old = 'running', new = 'paused')
2025-05-27 12:24:57 (26384): VM state change detected. (old = 'paused', new = 'running')
2025-05-27 12:25:07 (26384): VM state change detected. (old = 'running', new = 'paused')
2025-05-27 12:25:27 (26384): VM state change detected. (old = 'paused', new = 'running')
2025-05-27 12:25:37 (26384): VM state change detected. (old = 'running', new = 'paused')
2025-05-27 12:25:57 (26384): VM state change detected. (old = 'paused', new = 'running')
2025-05-27 12:26:07 (26384): VM state change detected. (old = 'running', new = 'paused')
2025-05-27 12:26:27 (26384): VM state change detected. (old = 'paused', new = 'running')
2025-05-27 12:26:37 (26384): VM state change detected. (old = 'running', new = 'paused')
2025-05-27 12:26:57 (26384): VM state change detected. (old = 'paused', new = 'running')
2025-05-27 12:27:07 (26384): VM state change detected. (old = 'running', new = 'paused')
2025-05-27 12:27:27 (26384): VM state change detected. (old = 'paused', new = 'running')
2025-05-27 12:27:37 (26384): VM state change detected. (old = 'running', new = 'paused')
2025-05-27 12:27:57 (26384): VM state change detected. (old = 'paused', new = 'running')
2025-05-27 12:28:07 (26384): VM state change detected. (old = 'running', new = 'paused')
2025-05-27 12:28:27 (26384): VM state change detected. (old = 'paused', new = 'running')
2025-05-27 12:28:37 (26384): VM state change detected. (old = 'running', new = 'paused')
2025-05-27 12:28:57 (26384): VM state change detected. (old = 'paused', new = 'running')
2025-05-27 12:29:04 (26384): Status Report: Elapsed Time: '6000.000000'
2025-05-27 12:29:04 (26384): Status Report: CPU Time: '23367.171875'
2025-05-27 12:29:07 (26384): VM state change detected. (old = 'running', new = 'paused')
2025-05-27 12:29:27 (26384): VM state change detected. (old = 'paused', new = 'running')
2025-05-27 12:29:37 (26384): VM state change detected. (old = 'running', new = 'paused')
2025-05-27 12:29:57 (26384): VM state change detected. (old = 'paused', new = 'running')
2025-05-27 12:30:07 (26384): VM state change detected. (old = 'running', new = 'paused')
2025-05-27 12:30:27 (26384): VM state change detected. (old = 'paused', new = 'running')
2025-05-27 12:30:37 (26384): VM state change detected. (old = 'running', new = 'paused')
2025-05-27 12:30:57 (26384): VM state change detected. (old = 'paused', new = 'running')
2025-05-27 12:31:07 (26384): VM state change detected. (old = 'running', new = 'paused')
2025-05-27 12:31:27 (26384): VM state change detected. (old = 'paused', new = 'running')
2025-05-27 12:31:37 (26384): VM state change detected. (old = 'running', new = 'paused')
2025-05-27 12:31:58 (26384): VM state change detected. (old = 'paused', new = 'running')
2025-05-27 12:32:08 (26384): VM state change detected. (old = 'running', new = 'paused')
2025-05-27 12:32:28 (26384): VM state change detected. (old = 'paused', new = 'running')
2025-05-27 12:32:39 (26384): VM state change detected. (old = 'running', new = 'paused')
2025-05-27 12:32:58 (26384): VM state change detected. (old = 'paused', new = 'running')
2025-05-27 12:33:08 (26384): VM state change detected. (old = 'running', new = 'paused')
2025-05-27 12:33:29 (26384): VM state change detected. (old = 'paused', new = 'running')
2025-05-27 12:33:39 (26384): VM state change detected. (old = 'running', new = 'paused')
2025-05-27 12:33:59 (26384): VM state change detected. (old = 'paused', new = 'running')
2025-05-27 12:34:09 (26384): VM state change detected. (old = 'running', new = 'paused')
2025-05-27 12:34:29 (26384): VM state change detected. (old = 'paused', new = 'running')
2025-05-27 12:34:39 (26384): VM state change detected. (old = 'running', new = 'paused')
2025-05-27 12:34:59 (26384): VM state change detected. (old = 'paused', new = 'running')
2025-05-27 12:35:09 (26384): VM state change detected. (old = 'running', new = 'paused')
2025-05-27 12:35:29 (26384): VM state change detected. (old = 'paused', new = 'running')
2025-05-27 12:35:39 (26384): VM state change detected. (old = 'running', new = 'paused')
2025-05-27 12:35:59 (26384): VM state change detected. (old = 'paused', new = 'running')
2025-05-27 12:36:09 (26384): VM state change detected. (old = 'running', new = 'paused')
2025-05-27 12:36:29 (26384): VM state change detected. (old = 'paused', new = 'running')
2025-05-27 12:36:39 (26384): VM state change detected. (old = 'running', new = 'paused')
2025-05-27 12:36:59 (26384): VM state change detected. (old = 'paused', new = 'running')
2025-05-27 12:37:09 (26384): VM state change detected. (old = 'running', new = 'paused')
2025-05-27 12:37:29 (26384): VM state change detected. (old = 'paused', new = 'running')
2025-05-27 12:37:39 (26384): VM state change detected. (old = 'running', new = 'paused')
2025-05-27 12:37:59 (26384): VM state change detected. (old = 'paused', new = 'running')
2025-05-27 12:38:09 (26384): VM state change detected. (old = 'running', new = 'paused')
2025-05-27 12:38:29 (26384): VM state change detected. (old = 'paused', new = 'running')
2025-05-27 12:38:39 (26384): VM state change detected. (old = 'running', new = 'paused')
2025-05-27 12:38:59 (26384): VM state change detected. (old = 'paused', new = 'running')
2025-05-27 12:39:09 (26384): VM state change detected. (old = 'running', new = 'paused')
2025-05-27 12:39:29 (26384): VM state change detected. (old = 'paused', new = 'running')
2025-05-27 12:39:39 (26384): VM state change detected. (old = 'running', new = 'paused')
2025-05-27 12:39:59 (26384): VM state change detected. (old = 'paused', new = 'running')
2025-05-27 12:40:09 (26384): VM state change detected. (old = 'running', new = 'paused')
2025-05-27 12:40:29 (26384): VM state change detected. (old = 'paused', new = 'running')
2025-05-27 12:40:39 (26384): VM state change detected. (old = 'running', new = 'paused')
2025-05-27 12:40:59 (26384): VM state change detected. (old = 'paused', new = 'running')
2025-05-27 12:41:09 (26384): VM state change detected. (old = 'running', new = 'paused')
2025-05-27 12:41:29 (26384): VM state change detected. (old = 'paused', new = 'running')
2025-05-27 12:41:39 (26384): VM state change detected. (old = 'running', new = 'paused')
2025-05-27 12:41:59 (26384): VM state change detected. (old = 'paused', new = 'running')
2025-05-27 12:42:09 (26384): VM state change detected. (old = 'running', new = 'paused')
2025-05-27 12:42:29 (26384): VM state change detected. (old = 'paused', new = 'running')
2025-05-27 12:42:39 (26384): VM state change detected. (old = 'running', new = 'paused')
2025-05-27 12:42:59 (26384): VM state change detected. (old = 'paused', new = 'running')
2025-05-27 12:43:09 (26384): VM state change detected. (old = 'running', new = 'paused')
2025-05-27 12:43:29 (26384): VM state change detected. (old = 'paused', new = 'running')
2025-05-27 12:43:39 (26384): VM state change detected. (old = 'running', new = 'paused')
2025-05-27 12:43:59 (26384): VM state change detected. (old = 'paused', new = 'running')
2025-05-27 12:44:09 (26384): VM state change detected. (old = 'running', new = 'paused')
2025-05-27 12:44:29 (26384): VM state change detected. (old = 'paused', new = 'running')
2025-05-27 12:44:39 (26384): VM state change detected. (old = 'running', new = 'paused')
2025-05-27 12:44:59 (26384): VM state change detected. (old = 'paused', new = 'running')
2025-05-27 12:45:09 (26384): VM state change detected. (old = 'running', new = 'paused')
2025-05-27 12:45:29 (26384): VM state change detected. (old = 'paused', new = 'running')
2025-05-27 12:45:39 (26384): VM state change detected. (old = 'running', new = 'paused')
2025-05-27 12:45:59 (26384): VM state change detected. (old = 'paused', new = 'running')
2025-05-27 12:46:10 (26384): VM state change detected. (old = 'running', new = 'paused')
2025-05-27 12:46:30 (26384): VM state change detected. (old = 'paused', new = 'running')
2025-05-27 12:46:40 (26384): VM state change detected. (old = 'running', new = 'paused')
2025-05-27 12:47:00 (26384): VM state change detected. (old = 'paused', new = 'running')
2025-05-27 12:47:10 (26384): VM state change detected. (old = 'running', new = 'paused')
2025-05-27 12:47:30 (26384): VM state change detected. (old = 'paused', new = 'running')
2025-05-27 12:47:40 (26384): VM state change detected. (old = 'running', new = 'paused')
2025-05-27 12:48:00 (26384): VM state change detected. (old = 'paused', new = 'running')
2025-05-27 12:48:10 (26384): VM state change detected. (old = 'running', new = 'paused')
2025-05-27 12:48:30 (26384): VM state change detected. (old = 'paused', new = 'running')
2025-05-27 12:48:40 (26384): VM state change detected. (old = 'running', new = 'paused')
2025-05-27 12:49:00 (26384): VM state change detected. (old = 'paused', new = 'running')
2025-05-27 12:49:10 (26384): VM state change detected. (old = 'running', new = 'paused')
2025-05-27 12:49:30 (26384): VM state change detected. (old = 'paused', new = 'running')
2025-05-27 12:49:40 (26384): VM state change detected. (old = 'running', new = 'paused')
2025-05-27 12:50:00 (26384): VM state change detected. (old = 'paused', new = 'running')
2025-05-27 12:50:10 (26384): VM state change detected. (old = 'running', new = 'paused')
2025-05-27 12:50:30 (26384): VM state change detected. (old = 'paused', new = 'running')
2025-05-27 12:50:40 (26384): VM state change detected. (old = 'running', new = 'paused')
2025-05-27 12:51:00 (26384): VM state change detected. (old = 'paused', new = 'running')
2025-05-27 12:51:10 (26384): VM state change detected. (old = 'running', new = 'paused')
2025-05-27 12:51:30 (26384): VM state change detected. (old = 'paused', new = 'running')
2025-05-27 12:51:40 (26384): VM state change detected. (old = 'running', new = 'paused')
2025-05-27 12:52:00 (26384): VM state change detected. (old = 'paused', new = 'running')
2025-05-27 12:52:10 (26384): VM state change detected. (old = 'running', new = 'paused')
2025-05-27 12:52:30 (26384): VM state change detected. (old = 'paused', new = 'running')
2025-05-27 12:52:40 (26384): VM state change detected. (old = 'running', new = 'paused')
2025-05-27 12:53:00 (26384): VM state change detected. (old = 'paused', new = 'running')
2025-05-27 12:53:10 (26384): VM state change detected. (old = 'running', new = 'paused')
2025-05-27 12:53:30 (26384): VM state change detected. (old = 'paused', new = 'running')
2025-05-27 12:53:40 (26384): VM state change detected. (old = 'running', new = 'paused')
2025-05-27 12:54:00 (26384): VM state change detected. (old = 'paused', new = 'running')
2025-05-27 12:54:11 (26384): VM state change detected. (old = 'running', new = 'paused')
2025-05-27 12:54:31 (26384): VM state change detected. (old = 'paused', new = 'running')
2025-05-27 12:54:41 (26384): VM state change detected. (old = 'running', new = 'paused')
2025-05-27 12:55:01 (26384): VM state change detected. (old = 'paused', new = 'running')
2025-05-27 12:55:11 (26384): VM state change detected. (old = 'running', new = 'paused')
2025-05-27 12:55:31 (26384): VM state change detected. (old = 'paused', new = 'running')
2025-05-27 12:55:41 (26384): VM state change detected. (old = 'running', new = 'paused')
2025-05-27 12:58:39 (26384): VM state change detected. (old = 'paused', new = 'running')
2025-05-27 12:58:41 (26384): VM state change detected. (old = 'running', new = 'paused')
2025-05-27 12:59:01 (26384): VM state change detected. (old = 'paused', new = 'running')
2025-05-27 12:59:11 (26384): VM state change detected. (old = 'running', new = 'paused')
2025-05-27 12:59:31 (26384): VM state change detected. (old = 'paused', new = 'running')
2025-05-27 12:59:41 (26384): VM state change detected. (old = 'running', new = 'paused')
2025-05-27 13:00:01 (26384): VM state change detected. (old = 'paused', new = 'running')
2025-05-27 13:00:11 (26384): VM state change detected. (old = 'running', new = 'paused')
2025-05-27 13:00:31 (26384): VM state change detected. (old = 'paused', new = 'running')
2025-05-27 13:00:41 (26384): VM state change detected. (old = 'running', new = 'paused')
2025-05-27 13:01:01 (26384): VM state change detected. (old = 'paused', new = 'running')
2025-05-27 13:01:11 (26384): VM state change detected. (old = 'running', new = 'paused')
2025-05-27 13:01:31 (26384): VM state change detected. (old = 'paused', new = 'running')
2025-05-27 13:01:41 (26384): VM state change detected. (old = 'running', new = 'paused')
2025-05-27 13:02:02 (26384): VM state change detected. (old = 'paused', new = 'running')
2025-05-27 13:02:12 (26384): VM state change detected. (old = 'running', new = 'paused')
2025-05-27 13:02:32 (26384): VM state change detected. (old = 'paused', new = 'running')
2025-05-27 13:02:42 (26384): VM state change detected. (old = 'running', new = 'paused')
2025-05-27 13:03:02 (26384): VM state change detected. (old = 'paused', new = 'running')
2025-05-27 13:03:12 (26384): VM state change detected. (old = 'running', new = 'paused')
2025-05-27 13:03:32 (26384): VM state change detected. (old = 'paused', new = 'running')
2025-05-27 13:03:42 (26384): VM state change detected. (old = 'running', new = 'paused')
2025-05-27 13:04:02 (26384): VM state change detected. (old = 'paused', new = 'running')
2025-05-27 13:04:12 (26384): VM state change detected. (old = 'running', new = 'paused')
2025-05-27 13:04:32 (26384): VM state change detected. (old = 'paused', new = 'running')
2025-05-27 13:04:43 (26384): VM state change detected. (old = 'running', new = 'paused')
2025-05-27 13:05:03 (26384): VM state change detected. (old = 'paused', new = 'running')
2025-05-27 13:05:13 (26384): VM state change detected. (old = 'running', new = 'paused')
2025-05-27 13:05:33 (26384): VM state change detected. (old = 'paused', new = 'running')
2025-05-27 13:05:43 (26384): VM state change detected. (old = 'running', new = 'paused')
2025-05-27 13:06:03 (26384): VM state change detected. (old = 'paused', new = 'running')
2025-05-27 13:06:13 (26384): VM state change detected. (old = 'running', new = 'paused')
2025-05-27 13:06:33 (26384): VM state change detected. (old = 'paused', new = 'running')
2025-05-27 13:06:43 (26384): VM state change detected. (old = 'running', new = 'paused')
2025-05-27 13:07:03 (26384): VM state change detected. (old = 'paused', new = 'running')
2025-05-27 13:07:13 (26384): VM state change detected. (old = 'running', new = 'paused')
2025-05-27 13:07:33 (26384): VM state change detected. (old = 'paused', new = 'running')
2025-05-27 13:07:43 (26384): VM state change detected. (old = 'running', new = 'paused')
2025-05-27 13:08:03 (26384): VM state change detected. (old = 'paused', new = 'running')
2025-05-27 13:08:13 (26384): VM state change detected. (old = 'running', new = 'paused')
2025-05-27 13:08:33 (26384): VM state change detected. (old = 'paused', new = 'running')
2025-05-27 13:08:43 (26384): VM state change detected. (old = 'running', new = 'paused')
2025-05-27 13:09:03 (26384): VM state change detected. (old = 'paused', new = 'running')
2025-05-27 13:09:13 (26384): VM state change detected. (old = 'running', new = 'paused')
2025-05-27 13:09:33 (26384): VM state change detected. (old = 'paused', new = 'running')
2025-05-27 13:09:43 (26384): VM state change detected. (old = 'running', new = 'paused')
2025-05-27 13:10:03 (26384): VM state change detected. (old = 'paused', new = 'running')
2025-05-27 13:10:13 (26384): VM state change detected. (old = 'running', new = 'paused')
2025-05-27 13:10:33 (26384): VM state change detected. (old = 'paused', new = 'running')
2025-05-27 13:10:43 (26384): VM state change detected. (old = 'running', new = 'paused')
2025-05-27 13:11:03 (26384): VM state change detected. (old = 'paused', new = 'running')
2025-05-27 13:11:13 (26384): VM state change detected. (old = 'running', new = 'paused')
2025-05-27 13:11:33 (26384): VM state change detected. (old = 'paused', new = 'running')
2025-05-27 13:11:43 (26384): VM state change detected. (old = 'running', new = 'paused')
2025-05-27 13:12:03 (26384): VM state change detected. (old = 'paused', new = 'running')
2025-05-27 13:12:13 (26384): VM state change detected. (old = 'running', new = 'paused')
2025-05-27 13:12:33 (26384): VM state change detected. (old = 'paused', new = 'running')
2025-05-27 13:12:43 (26384): VM state change detected. (old = 'running', new = 'paused')
2025-05-27 13:13:03 (26384): VM state change detected. (old = 'paused', new = 'running')
2025-05-27 13:13:13 (26384): VM state change detected. (old = 'running', new = 'paused')
2025-05-27 13:13:33 (26384): VM state change detected. (old = 'paused', new = 'running')
2025-05-27 13:13:43 (26384): VM state change detected. (old = 'running', new = 'paused')
2025-05-27 13:14:03 (26384): VM state change detected. (old = 'paused', new = 'running')
2025-05-27 13:14:13 (26384): VM state change detected. (old = 'running', new = 'paused')
2025-05-27 13:14:33 (26384): VM state change detected. (old = 'paused', new = 'running')
2025-05-27 13:14:43 (26384): VM state change detected. (old = 'running', new = 'paused')
2025-05-27 13:15:04 (26384): VM state change detected. (old = 'paused', new = 'running')
2025-05-27 13:15:14 (26384): VM state change detected. (old = 'running', new = 'paused')
2025-05-27 13:15:34 (26384): VM state change detected. (old = 'paused', new = 'running')
2025-05-27 13:15:44 (26384): VM state change detected. (old = 'running', new = 'paused')
2025-05-27 13:16:04 (26384): VM state change detected. (old = 'paused', new = 'running')
2025-05-27 13:16:14 (26384): VM state change detected. (old = 'running', new = 'paused')
2025-05-27 13:16:34 (26384): VM state change detected. (old = 'paused', new = 'running')
2025-05-27 13:16:44 (26384): VM state change detected. (old = 'running', new = 'paused')
2025-05-27 13:17:04 (26384): VM state change detected. (old = 'paused', new = 'running')
2025-05-27 13:17:14 (26384): VM state change detected. (old = 'running', new = 'paused')
2025-05-27 13:17:34 (26384): VM state change detected. (old = 'paused', new = 'running')
2025-05-27 13:17:44 (26384): VM state change detected. (old = 'running', new = 'paused')
2025-05-27 13:18:04 (26384): VM state change detected. (old = 'paused', new = 'running')
2025-05-27 13:18:14 (26384): VM state change detected. (old = 'running', new = 'paused')
2025-05-27 13:18:34 (26384): VM state change detected. (old = 'paused', new = 'running')
2025-05-27 13:18:44 (26384): VM state change detected. (old = 'running', new = 'paused')
2025-05-27 13:19:04 (26384): VM state change detected. (old = 'paused', new = 'running')
2025-05-27 13:19:14 (26384): VM state change detected. (old = 'running', new = 'paused')
2025-05-27 13:19:34 (26384): VM state change detected. (old = 'paused', new = 'running')
2025-05-27 13:19:44 (26384): VM state change detected. (old = 'running', new = 'paused')
2025-05-27 13:20:04 (26384): VM state change detected. (old = 'paused', new = 'running')
2025-05-27 13:20:14 (26384): VM state change detected. (old = 'running', new = 'paused')
2025-05-27 13:20:34 (26384): VM state change detected. (old = 'paused', new = 'running')
2025-05-27 13:20:44 (26384): VM state change detected. (old = 'running', new = 'paused')
2025-05-27 13:21:04 (26384): VM state change detected. (old = 'paused', new = 'running')
2025-05-27 13:21:14 (26384): VM state change detected. (old = 'running', new = 'paused')
2025-05-27 13:21:34 (26384): VM state change detected. (old = 'paused', new = 'running')
2025-05-27 13:21:44 (26384): VM state change detected. (old = 'running', new = 'paused')
2025-05-27 13:22:04 (26384): VM state change detected. (old = 'paused', new = 'running')
2025-05-27 13:22:14 (26384): VM state change detected. (old = 'running', new = 'paused')
2025-05-27 13:22:35 (26384): VM state change detected. (old = 'paused', new = 'running')
2025-05-27 13:22:44 (26384): VM state change detected. (old = 'running', new = 'paused')
2025-05-27 13:23:04 (26384): VM state change detected. (old = 'paused', new = 'running')
2025-05-27 13:23:14 (26384): VM state change detected. (old = 'running', new = 'paused')
2025-05-27 13:23:35 (26384): VM state change detected. (old = 'paused', new = 'running')
2025-05-27 13:23:44 (26384): VM state change detected. (old = 'running', new = 'paused')
2025-05-27 13:24:04 (26384): VM state change detected. (old = 'paused', new = 'running')
2025-05-27 13:24:14 (26384): VM state change detected. (old = 'running', new = 'paused')
2025-05-27 13:24:35 (26384): VM state change detected. (old = 'paused', new = 'running')
2025-05-27 13:24:44 (26384): VM state change detected. (old = 'running', new = 'paused')
2025-05-27 13:25:04 (26384): VM state change detected. (old = 'paused', new = 'running')
2025-05-27 13:25:14 (26384): VM state change detected. (old = 'running', new = 'paused')
2025-05-27 13:25:35 (26384): VM state change detected. (old = 'paused', new = 'running')
2025-05-27 13:25:44 (26384): VM state change detected. (old = 'running', new = 'paused')
2025-05-27 13:26:05 (26384): VM state change detected. (old = 'paused', new = 'running')
2025-05-27 13:26:15 (26384): VM state change detected. (old = 'running', new = 'paused')
2025-05-27 13:26:35 (26384): VM state change detected. (old = 'paused', new = 'running')
2025-05-27 13:26:45 (26384): VM state change detected. (old = 'running', new = 'paused')
2025-05-27 13:27:05 (26384): VM state change detected. (old = 'paused', new = 'running')
2025-05-27 13:27:15 (26384): VM state change detected. (old = 'running', new = 'paused')
2025-05-27 13:27:35 (26384): VM state change detected. (old = 'paused', new = 'running')
2025-05-27 13:27:45 (26384): VM state change detected. (old = 'running', new = 'paused')
2025-05-27 13:28:05 (26384): VM state change detected. (old = 'paused', new = 'running')
2025-05-27 13:28:15 (26384): VM state change detected. (old = 'running', new = 'paused')
2025-05-27 13:28:36 (26384): VM state change detected. (old = 'paused', new = 'running')
2025-05-27 13:28:46 (26384): VM state change detected. (old = 'running', new = 'paused')
2025-05-27 13:29:06 (26384): VM state change detected. (old = 'paused', new = 'running')
2025-05-27 13:29:13 (26384): VM state change detected. (old = 'running', new = 'paused')
2025-05-27 13:31:41 (26384): VM state change detected. (old = 'paused', new = 'running')
2025-05-27 13:31:46 (26384): VM state change detected. (old = 'running', new = 'paused')
2025-05-27 13:32:06 (26384): VM state change detected. (old = 'paused', new = 'running')
2025-05-27 13:32:16 (26384): VM state change detected. (old = 'running', new = 'paused')
2025-05-27 13:32:36 (26384): VM state change detected. (old = 'paused', new = 'running')
2025-05-27 13:32:46 (26384): VM state change detected. (old = 'running', new = 'paused')
2025-05-27 13:33:06 (26384): VM state change detected. (old = 'paused', new = 'running')
2025-05-27 13:33:16 (26384): VM state change detected. (old = 'running', new = 'paused')
2025-05-27 13:33:36 (26384): VM state change detected. (old = 'paused', new = 'running')
2025-05-27 13:33:46 (26384): VM state change detected. (old = 'running', new = 'paused')
2025-05-27 13:34:06 (26384): VM state change detected. (old = 'paused', new = 'running')
2025-05-27 13:34:16 (26384): VM state change detected. (old = 'running', new = 'paused')
2025-05-27 13:38:38 (26384): VM state change detected. (old = 'paused', new = 'running')
2025-05-27 13:38:46 (26384): VM state change detected. (old = 'running', new = 'paused')
2025-05-27 13:39:07 (26384): VM state change detected. (old = 'paused', new = 'running')
2025-05-27 13:39:16 (26384): VM state change detected. (old = 'running', new = 'paused')
2025-05-27 13:39:37 (26384): VM state change detected. (old = 'paused', new = 'running')
2025-05-27 13:39:46 (26384): VM state change detected. (old = 'running', new = 'paused')
2025-05-27 13:40:07 (26384): VM state change detected. (old = 'paused', new = 'running')
2025-05-27 13:40:16 (26384): VM state change detected. (old = 'running', new = 'paused')
2025-05-27 13:40:37 (26384): VM state change detected. (old = 'paused', new = 'running')
2025-05-27 13:40:46 (26384): VM state change detected. (old = 'running', new = 'paused')
2025-05-27 13:41:07 (26384): VM state change detected. (old = 'paused', new = 'running')
2025-05-27 13:41:16 (26384): VM state change detected. (old = 'running', new = 'paused')
2025-05-27 13:41:37 (26384): VM state change detected. (old = 'paused', new = 'running')
2025-05-27 13:41:46 (26384): VM state change detected. (old = 'running', new = 'paused')
2025-05-27 13:42:07 (26384): VM state change detected. (old = 'paused', new = 'running')
2025-05-27 13:42:16 (26384): VM state change detected. (old = 'running', new = 'paused')
2025-05-27 13:42:37 (26384): VM state change detected. (old = 'paused', new = 'running')
2025-05-27 13:42:46 (26384): VM state change detected. (old = 'running', new = 'paused')
2025-05-27 13:43:07 (26384): VM state change detected. (old = 'paused', new = 'running')
2025-05-27 13:43:16 (26384): VM state change detected. (old = 'running', new = 'paused')
2025-05-27 13:43:38 (26384): VM state change detected. (old = 'paused', new = 'running')
2025-05-27 13:43:46 (26384): VM state change detected. (old = 'running', new = 'paused')
2025-05-27 13:44:08 (26384): VM state change detected. (old = 'paused', new = 'running')
2025-05-27 13:44:18 (26384): VM state change detected. (old = 'running', new = 'paused')
2025-05-27 13:47:23 (26384): VM state change detected. (old = 'paused', new = 'running')
2025-05-27 13:47:28 (26384): VM state change detected. (old = 'running', new = 'paused')
2025-05-27 13:47:48 (26384): VM state change detected. (old = 'paused', new = 'running')
2025-05-27 13:47:58 (26384): VM state change detected. (old = 'running', new = 'paused')
2025-05-27 13:48:18 (26384): VM state change detected. (old = 'paused', new = 'running')
2025-05-27 13:48:28 (26384): VM state change detected. (old = 'running', new = 'paused')
2025-05-27 13:48:48 (26384): VM state change detected. (old = 'paused', new = 'running')
2025-05-27 13:48:58 (26384): VM state change detected. (old = 'running', new = 'paused')
2025-05-27 13:49:18 (26384): VM state change detected. (old = 'paused', new = 'running')
2025-05-27 13:49:28 (26384): VM state change detected. (old = 'running', new = 'paused')
2025-05-27 13:49:48 (26384): VM state change detected. (old = 'paused', new = 'running')
2025-05-27 13:49:58 (26384): VM state change detected. (old = 'running', new = 'paused')
2025-05-27 13:50:18 (26384): VM state change detected. (old = 'paused', new = 'running')
2025-05-27 13:50:28 (26384): VM state change detected. (old = 'running', new = 'paused')
2025-05-27 13:50:48 (26384): VM state change detected. (old = 'paused', new = 'running')
2025-05-27 13:50:58 (26384): VM state change detected. (old = 'running', new = 'paused')
2025-05-27 13:51:18 (26384): VM state change detected. (old = 'paused', new = 'running')
2025-05-27 13:51:28 (26384): VM state change detected. (old = 'running', new = 'paused')
2025-05-27 13:51:48 (26384): VM state change detected. (old = 'paused', new = 'running')
2025-05-27 13:51:58 (26384): VM state change detected. (old = 'running', new = 'paused')
2025-05-27 13:52:18 (26384): VM state change detected. (old = 'paused', new = 'running')
2025-05-27 13:52:28 (26384): VM state change detected. (old = 'running', new = 'paused')
2025-05-27 13:52:48 (26384): VM state change detected. (old = 'paused', new = 'running')
2025-05-27 13:52:58 (26384): VM state change detected. (old = 'running', new = 'paused')
2025-05-27 13:53:18 (26384): VM state change detected. (old = 'paused', new = 'running')
2025-05-27 13:53:28 (26384): VM state change detected. (old = 'running', new = 'paused')
2025-05-27 14:46:31 (1884): Detected: vboxwrapper 26206
2025-05-27 14:46:31 (1884): Detected: BOINC client v8.0.2
2025-05-27 14:46:35 (1884): Detected: VirtualBox VboxManage Interface (Version: 7.0.26)
2025-05-27 14:47:24 (1884): Error: Timeout
2025-05-27 14:47:24 (1884): ERROR: VM failed to start
2025-05-27 14:47:29 (1884): 
   BOINC will be notified that it needs to clean up the environment.
         This is a temporary problem and so this job will be rescheduled for another time.

2025-05-27 14:52:55 (5320): Detected: vboxwrapper 26206
2025-05-27 14:52:55 (5320): Detected: BOINC client v8.0.2
2025-05-27 14:53:23 (5320): Detected: VirtualBox VboxManage Interface (Version: 7.0.26)
2025-05-27 14:53:24 (5320): Guest Log: BIOS: VirtualBox 7.0.26
2025-05-27 14:53:24 (5320): Guest Log: CPUID EDX: 0x178bfbff
2025-05-27 14:53:24 (5320): Guest Log: BIOS: No PCI IDE controller, not probing IDE
2025-05-27 14:53:24 (5320): Guest Log: BIOS: AHCI 0-P#0: PCHS=16383/16/63 LCHS=1024/255/63 0x0000000002800000 sectors
2025-05-27 14:53:24 (5320): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032
2025-05-27 14:53:24 (5320): Guest Log: BIOS: Booting from Hard Disk...
2025-05-27 14:53:24 (5320): Guest Log: BIOS: KBD: unsupported int 16h function 03
2025-05-27 14:53:24 (5320): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 
2025-05-27 14:53:24 (5320): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=81
2025-05-27 14:53:24 (5320): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=81
2025-05-27 14:53:24 (5320): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=82
2025-05-27 14:53:24 (5320): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=82
2025-05-27 14:53:24 (5320): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=83
2025-05-27 14:53:24 (5320): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=83
2025-05-27 14:53:24 (5320): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=84
2025-05-27 14:53:24 (5320): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=84
2025-05-27 14:53:24 (5320): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=85
2025-05-27 14:53:24 (5320): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=85
2025-05-27 14:53:24 (5320): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=86
2025-05-27 14:53:24 (5320): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=86
2025-05-27 14:53:24 (5320): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=87
2025-05-27 14:53:24 (5320): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=87
2025-05-27 14:53:24 (5320): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=88
2025-05-27 14:53:24 (5320): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=88
2025-05-27 14:53:24 (5320): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=89
2025-05-27 14:53:24 (5320): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=89
2025-05-27 14:53:24 (5320): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=8a
2025-05-27 14:53:24 (5320): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=8a
2025-05-27 14:53:24 (5320): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=8b
2025-05-27 14:53:24 (5320): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=8b
2025-05-27 14:53:24 (5320): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=8c
2025-05-27 14:53:24 (5320): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=8c
2025-05-27 14:53:24 (5320): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=8d
2025-05-27 14:53:24 (5320): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=8d
2025-05-27 14:53:24 (5320): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=8e
2025-05-27 14:53:24 (5320): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=8e
2025-05-27 14:53:24 (5320): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=8f
2025-05-27 14:53:24 (5320): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=8f
2025-05-27 14:53:24 (5320): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds
2025-05-27 14:53:24 (5320): Guest Log: vboxguest: misc device minor 58, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000)
2025-05-27 14:53:24 (5320): Guest Log: VBoxService 5.2.32 r132073 (verbosity: 0) linux.amd64 (Jul 12 2019 10:32:28) release log
2025-05-27 14:53:24 (5320): Guest Log: 00:00:00.000665 main     Log opened 2025-05-27T04:48:48.474753000Z
2025-05-27 14:53:24 (5320): Guest Log: 00:00:00.001424 main     OS Product: Linux
2025-05-27 14:53:24 (5320): Guest Log: 00:00:00.001599 main     OS Release: 3.10.0-957.27.2.el7.x86_64
2025-05-27 14:53:24 (5320): Guest Log: 00:00:00.001643 main     OS Version: #1 SMP Mon Jul 29 17:46:05 UTC 2019
2025-05-27 14:53:24 (5320): Guest Log: 00:00:00.001676 main     Executable: /opt/VBoxGuestAdditions-5.2.32/sbin/VBoxService
2025-05-27 14:53:24 (5320): Guest Log: 00:00:00.001677 main     Process ID: 1353
2025-05-27 14:53:24 (5320): Guest Log: 00:00:00.001678 main     Package type: LINUX_64BITS_GENERIC
2025-05-27 14:53:24 (5320): Guest Log: 00:00:00.004346 main     5.2.32 r132073 started. Verbose level = 0
2025-05-27 14:53:24 (5320): Guest Log: [INFO] Probing /cvmfs/atlas.cern.ch...
2025-05-27 14:53:24 (5320): Guest Log: [INFO] Mounting shared directory
2025-05-27 14:53:24 (5320): Guest Log: [INFO] Checking for init_data.xml
2025-05-27 14:53:24 (5320): Guest Log: 00:00:10.029841 timesync vgsvcTimeSyncWorker: Radical guest time change: -28 708 895 390 000ns (GuestNow=1 748 292 619 588 705 000 ns GuestLast=1 748 321 328 484 095 000 ns fSetTimeLastLoop=true )
2025-05-27 14:53:24 (5320): Guest Log: [INFO] Probing /cvmfs/atlas.cern.ch... OK
2025-05-27 14:53:24 (5320): Guest Log: [INFO] Detected branch: prod
2025-05-27 14:53:24 (5320): Guest Log: This is the prod version of the ATLAS job wrapper
2025-05-27 14:53:24 (5320): Guest Log: Copying input files
2025-05-27 14:53:24 (5320): Guest Log: Copied input files into RunAtlas.
2025-05-27 14:53:24 (5320): Guest Log: This VM did not configure a local http proxy via BOINC.
2025-05-27 14:53:24 (5320): Guest Log: Small home clusters do not require a local http proxy but it is suggested if
2025-05-27 14:53:24 (5320): Guest Log: more than 10 cores throughout the same LAN segment are regularly running ATLAS like tasks.
2025-05-27 14:53:24 (5320): Guest Log: Further information can be found at the LHC@home message board.
2025-05-27 14:53:24 (5320): Guest Log: Running cvmfs_config stat atlas.cern.ch
2025-05-27 14:53:24 (5320): Guest Log: VERSION PID UPTIME(M) MEM(K) REVISION EXPIRES(M) NOCATALOGS CACHEUSE(K) CACHEMAX(K) NOFDUSE NOFDMAX NOIOERR NOOPEN HITRATE(%) RX(K) SPEED(K/S) HOST PROXY ONLINE
2025-05-27 14:53:24 (5320): Guest Log: 2.6.3.0 1648 0 30172 146455 4 1 3117617 4096000 0 65024 0 0 n/a 0 0 http://s1fnal-cvmfs.openhtc.io:8080/cvmfs/atlas.cern.ch DIRECT 1
2025-05-27 14:53:24 (5320): Guest Log: copied the webapp to /var/www
2025-05-27 14:53:24 (5320): Guest Log: ATHENA_PROC_NUMBER=7
2025-05-27 14:53:24 (5320): Guest Log: ATHENA_CORE_NUMBER=7
2025-05-27 14:53:24 (5320): Guest Log: Apptainer command /cvmfs/atlas.cern.ch/repo/containers/sw/apptainer/x86_64-el7/current/bin/apptainer exec -B /cvmfs,/data,/home/atlas/RunAtlas /cvmfs/atlas.cern.ch/repo/containers/fs/singularity/x86_64-centos7 
2025-05-27 14:53:24 (5320): Guest Log:  *** Starting ATLAS job. (PandaID=6665754165 taskID=44871837) ***
2025-05-27 14:53:24 (5320): Guest Log: 00:47:24.543296 timesync vgsvcTimeSyncWorker: Radical guest time change: 2 237 000 890 000ns (GuestNow=1 748 301 404 880 878 000 ns GuestLast=1 748 299 167 879 988 000 ns fSetTimeLastLoop=true )
2025-05-27 14:53:24 (5320): Guest Log: 00:53:55.255993 timesync vgsvcTimeSyncWorker: Radical host time change: 2 622 318 000 000ns (HostNow=1 748 305 167 711 000 000 ns HostLast=1 748 302 545 393 000 000 ns)
2025-05-27 14:53:24 (5320): Guest Log: 00:54:05.257534 timesync vgsvcTimeSyncWorker: Radical guest time change: 3 382 119 384 000ns (GuestNow=1 748 305 177 712 719 000 ns GuestLast=1 748 301 795 593 335 000 ns fSetTimeLastLoop=true )
2025-05-27 14:53:24 (5320): Guest Log: 00:54:15.266287 timesync vgsvcTimeSyncWorker: Radical host time change: 2 722 306 000 000ns (HostNow=1 748 307 920 887 000 000 ns HostLast=1 748 305 198 581 000 000 ns)
2025-05-27 14:53:24 (5320): Guest Log: 00:54:25.274294 timesync vgsvcTimeSyncWorker: Radical guest time change: 2 743 174 865 000ns (GuestNow=1 748 307 930 895 691 000 ns GuestLast=1 748 305 187 720 826 000 ns fSetTimeLastLoop=true )
2025-05-27 14:53:25 (5320): Guest Log: 01:33:58.246182 timesync vgsvcTimeSyncWorker: Radical guest time change: 1 883 928 049 000ns (GuestNow=1 748 317 119 979 967 000 ns GuestLast=1 748 315 236 051 918 000 ns fSetTimeLastLoop=true )
2025-05-27 14:53:25 (5320): Starting VM using VBoxManage interface. (boinc_3b9aaac965519643, slot#0)
2025-05-27 14:54:04 (5320): Error in start VM for VM: -2147467259
Command:
VBoxManage -q startvm "boinc_3b9aaac965519643" --type headless
Output:
VBoxManage.exe: error: Generic invalid state error. (VERR_INVALID_STATE)
VBoxManage.exe: error: Details: code E_FAIL (0x80004005), component ConsoleWrap, interface IConsole
Waiting for VM "boinc_3b9aaac965519643" to power on...

2025-05-27 14:54:04 (5320): VM failed to start.
2025-05-27 14:54:04 (5320): Could not start 
2025-05-27 14:54:04 (5320): ERROR: VM failed to start
2025-05-27 14:54:04 (5320): Powering off VM.
2025-05-27 14:54:04 (5320): Deregistering VM. (boinc_3b9aaac965519643, slot#0)
2025-05-27 14:54:04 (5320): Removing network bandwidth throttle group from VM.
2025-05-27 14:54:05 (5320): Removing VM from VirtualBox.

    Hypervisor System Log:

00:01:10.004812          ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={300763af-5d6b-46e6-aa96-273eac15538a} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
00:01:10.004842          ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={300763af-5d6b-46e6-aa96-273eac15538a} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
00:01:10.004869          ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={300763af-5d6b-46e6-aa96-273eac15538a} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
00:01:10.004896          ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={300763af-5d6b-46e6-aa96-273eac15538a} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
00:01:10.004923          ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={300763af-5d6b-46e6-aa96-273eac15538a} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
00:01:10.004950          ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={300763af-5d6b-46e6-aa96-273eac15538a} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
00:01:10.005621          Saving settings file "C:\Users\Larry\.VirtualBox\VirtualBox.xml" with version "1.14-windows"
00:01:10.516823          Finished saving settings file "C:\Users\Larry\.VirtualBox\VirtualBox.xml"
00:01:10.517096          ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={300763af-5d6b-46e6-aa96-273eac15538a} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
00:01:10.517138          ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={300763af-5d6b-46e6-aa96-273eac15538a} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
00:01:10.517174          ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={300763af-5d6b-46e6-aa96-273eac15538a} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
00:01:10.517204          ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={300763af-5d6b-46e6-aa96-273eac15538a} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
00:01:10.517229          ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={300763af-5d6b-46e6-aa96-273eac15538a} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
00:01:10.517248          ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={300763af-5d6b-46e6-aa96-273eac15538a} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
00:01:10.517268          ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={300763af-5d6b-46e6-aa96-273eac15538a} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
00:01:10.517286          ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={300763af-5d6b-46e6-aa96-273eac15538a} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
00:01:10.517773          Saving settings file "D:\ProgramData\BOINC\slots\2\boinc_78939277305f480c\boinc_78939277305f480c.vbox" with version "1.19-windows"
00:01:10.541464          Finished saving settings file "D:\ProgramData\BOINC\slots\2\boinc_78939277305f480c\boinc_78939277305f480c.vbox"
00:01:10.542477 DeleteVM ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={300763af-5d6b-46e6-aa96-273eac15538a} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
00:01:10.542506 DeleteVM ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={300763af-5d6b-46e6-aa96-273eac15538a} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
00:01:10.542514 DeleteVM ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={300763af-5d6b-46e6-aa96-273eac15538a} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
00:01:10.542520 DeleteVM ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={300763af-5d6b-46e6-aa96-273eac15538a} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
00:01:10.542527 DeleteVM ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={300763af-5d6b-46e6-aa96-273eac15538a} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
00:01:10.542534 DeleteVM ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={300763af-5d6b-46e6-aa96-273eac15538a} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
00:01:10.542540 DeleteVM ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={300763af-5d6b-46e6-aa96-273eac15538a} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
00:01:10.542546 DeleteVM ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={300763af-5d6b-46e6-aa96-273eac15538a} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
00:01:10.543139 DeleteVM Saving settings file "D:\ProgramData\BOINC\slots\2\boinc_78939277305f480c\boinc_78939277305f480c.vbox" with version "1.19-windows"
00:01:10.558439 DeleteVM Finished saving settings file "D:\ProgramData\BOINC\slots\2\boinc_78939277305f480c\boinc_78939277305f480c.vbox"
00:01:11.396870          ERROR [COM]: aRC=VBOX_E_OBJECT_NOT_FOUND (0x80bb0001) aIID={ad47ad09-787b-44ab-b343-a082a3f2dfb1} aComponent={MediumWrap} aText={Property 'CRYPT/KeyId' does not exist}, preserve=false aResultDetail=0
00:01:11.397319          ERROR [COM]: aRC=VBOX_E_OBJECT_NOT_FOUND (0x80bb0001) aIID={ad47ad09-787b-44ab-b343-a082a3f2dfb1} aComponent={MediumWrap} aText={Property 'CRYPT/KeyId' does not exist}, preserve=false aResultDetail=0
00:01:12.457165          ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={300763af-5d6b-46e6-aa96-273eac15538a} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
00:01:12.457192          ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={300763af-5d6b-46e6-aa96-273eac15538a} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
00:01:12.457206          ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={300763af-5d6b-46e6-aa96-273eac15538a} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
00:01:12.457212          ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={300763af-5d6b-46e6-aa96-273eac15538a} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
00:01:12.457219          ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={300763af-5d6b-46e6-aa96-273eac15538a} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
00:01:12.457226          ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={300763af-5d6b-46e6-aa96-273eac15538a} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
00:01:12.457232          ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={300763af-5d6b-46e6-aa96-273eac15538a} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
00:01:12.457238          ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={300763af-5d6b-46e6-aa96-273eac15538a} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
00:01:12.684568          Saving settings file "D:\ProgramData\BOINC\slots\2\boinc_78939277305f480c\boinc_78939277305f480c.vbox" with version "1.19-windows"
00:01:12.700524          Finished saving settings file "D:\ProgramData\BOINC\slots\2\boinc_78939277305f480c\boinc_78939277305f480c.vbox"

    VM Execution Log:


    VM Startup Log:


    VM Trace Log:

erial Port count:       4
Maximum Parallel Port count:     2
Maximum Boot Position:           4
Maximum PIIX3 Network Adapter count:   8
Maximum ICH9 Network Adapter count:   36
Maximum PIIX3 IDE Controllers:   1
Maximum ICH9 IDE Controllers:    1
Maximum IDE Port count:          2
Maximum Devices per IDE Port:    2
Maximum PIIX3 SATA Controllers:  1
Maximum ICH9 SATA Controllers:   8
Maximum SATA Port count:         30
Maximum Devices per SATA Port:   1
Maximum PIIX3 SCSI Controllers:  1
Maximum ICH9 SCSI Controllers:   8
Maximum SCSI Port count:         16
Maximum Devices per SCSI Port:   1
Maximum SAS PIIX3 Controllers:   1
Maximum SAS ICH9 Controllers:    8
Maximum SAS Port count:          255
Maximum Devices per SAS Port:    1
Maximum NVMe PIIX3 Controllers:  1
Maximum NVMe ICH9 Controllers:   8
Maximum NVMe Port count:         255
Maximum Devices per NVMe Port:   1
Maximum virtio-scsi PIIX3 Controllers:  1
Maximum virtio-scsi ICH9 Controllers:   8
Maximum virtio-scsi Port count:         256
Maximum Devices per virtio-scsi Port:   1
Maximum PIIX3 Floppy Controllers:1
Maximum ICH9 Floppy Controllers: 1
Maximum Floppy Port count:       1
Maximum Devices per Floppy Port: 2
Default machine folder:          D:\0.Def
Raw-mode Supported:              no
Exclusive HW virtualization use: off
Default hard disk format:        VDI
VRDE auth library:               VBoxAuth
Webservice auth. library:        VBoxAuth
Remote desktop ExtPack:          
VM encryption ExtPack:           
Log history count:               3
Default frontend:                
Default audio driver:            Windows Audio Session
Autostart database path:         
Default Guest Additions ISO:     c:\Program Files\Oracle\VirtualBox/VBoxGuestAdditions.iso
Logging Level:                   all
Proxy Mode:                      System
Proxy URL:                       
User language:                   C

2025-05-27 14:53:23 (5320): 
Command: VBoxManage -q list hostinfo 
Exit Code: 0
Output:
Host Information:

Host time: 2025-05-27T06:53:23.215000000Z
Processor online count: 8
Processor count: 8
Processor online core count: 8
Processor core count: 8
Processor supports HW virtualization: yes
Processor supports PAE: yes
Processor supports long mode: yes
Processor supports nested paging: yes
Processor supports unrestricted guest: yes
Processor supports nested HW virtualization: yes
Processor supports virt. vmsave/vmload: no
Processor#0 speed: unknown
Processor#0 description: Intel(R) Core(TM) i7-9700 CPU @ 3.00GHz
Processor#1 speed: unknown
Processor#1 description: Intel(R) Core(TM) i7-9700 CPU @ 3.00GHz
Processor#2 speed: unknown
Processor#2 description: Intel(R) Core(TM) i7-9700 CPU @ 3.00GHz
Processor#3 speed: unknown
Processor#3 description: Intel(R) Core(TM) i7-9700 CPU @ 3.00GHz
Processor#4 speed: unknown
Processor#4 description: Intel(R) Core(TM) i7-9700 CPU @ 3.00GHz
Processor#5 speed: unknown
Processor#5 description: Intel(R) Core(TM) i7-9700 CPU @ 3.00GHz
Processor#6 speed: unknown
Processor#6 description: Intel(R) Core(TM) i7-9700 CPU @ 3.00GHz
Processor#7 speed: unknown
Processor#7 description: Intel(R) Core(TM) i7-9700 CPU @ 3.00GHz
Memory size: 32549 MByte
Memory available: 25667 MByte
Operating system: Windows 10
Operating system version: 10.0.19045

2025-05-27 14:53:24 (5320): 
Command: VBoxManage -q showvminfo "boinc_3b9aaac965519643" --machinereadable 
Exit Code: 0
Output:
name="boinc_3b9aaac965519643"
encryption="disabled"
groups="/"
ostype="Linux 2.6 / 3.x / 4.x / 5.x (64-bit)"
UUID="73e62e83-6b31-4c42-aa06-285ae70125ec"
CfgFile="D:\\ProgramData\\BOINC\\slots\\0\\boinc_3b9aaac965519643\\boinc_3b9aaac965519643.vbox"
SnapFldr="D:\\ProgramData\\BOINC\\slots\\0\\boinc_3b9aaac965519643\\Snapshots"
LogFldr="D:\\ProgramData\\BOINC\\slots\\0\\boinc_3b9aaac965519643\\Logs"
hardwareuuid="73e62e83-6b31-4c42-aa06-285ae70125ec"
memory=4700
pagefusion="off"
vram=16
cpuexecutioncap=60
hpet="off"
cpu-profile="host"
chipset="piix3"
firmware="BIOS"
cpus=7
pae="on"
longmode="on"
triplefaultreset="off"
apic="on"
x2apic="on"
nested-hw-virt="off"
cpuid-portability-level=0
bootmenu="messageandmenu"
boot1="disk"
boot2="dvd"
boot3="none"
boot4="none"
acpi="on"
ioapic="on"
biosapic="apic"
biossystemtimeoffset=0
NvramFile="D:\\ProgramData\\BOINC\\slots\\0\\boinc_3b9aaac965519643\\boinc_3b9aaac965519643.nvram"
rtcuseutc="off"
hwvirtex="on"
nestedpaging="on"
largepages="on"
vtxvpid="on"
vtxux="on"
virtvmsavevmload="on"
iommu="none"
paravirtprovider="default"
effparavirtprovider="kvm"
VMState="poweroff"
VMStateChangeTime="2025-05-26T20:48:12.000000000"
graphicscontroller="vboxvga"
monitorcount=1
accelerate3d="off"
accelerate2dvideo="off"
teleporterenabled="off"
teleporterport=0
teleporteraddress=""
teleporterpassword=""
tracing-enabled="off"
tracing-allow-vm-access="off"
tracing-config=""
autostart-enabled="off"
autostart-delay=0
defaultfrontend=""
vmprocpriority="default"
storagecontrollername0="Hard Disk Controller"
storagecontrollertype0="IntelAhci"
storagecontrollerinstance0="0"
storagecontrollermaxportcount0="30"
storagecontrollerportcount0="3"
storagecontrollerbootable0="on"
"Hard Disk Controller-0-0"="D:\\ProgramData\\BOINC\\slots\\0\\boinc_3b9aaac965519643\\Snapshots/{cc45dc98-1fbc-44ca-b20d-727f1106cbea}.vdi"
"Hard Disk Controller-ImageUUID-0-0"="cc45dc98-1fbc-44ca-b20d-727f1106cbea"
"Hard Disk Controller-nonrotational-0-0"="off"
"Hard Disk Controller-discard-0-0"="off"
"Hard Disk Controller-1-0"="C:\\Program Files\\Oracle\\VirtualBox\\VBoxGuestAdditions.iso"
"Hard Disk Controller-ImageUUID-1-0"="47b0e5fa-43fc-4dcb-ab19-604bb781470a"
"Hard Disk Controller-tempeject-1-0"="off"
"Hard Disk Controller-IsEjected-1-0"="off"
"Hard Disk Controller-nonrotational-1-0"="off"
"Hard Disk Controller-discard-1-0"="off"
"Hard Disk Controller-2-0"="none"
natnet1="nat"
macaddress1="080027A9822A"
cableconnected1="on"
nic1="nat"
nictype1="82540EM"
nicspeed1="0"
mtu="0"
sockSnd="64"
sockRcv="64"
tcpWndSnd="64"
tcpWndRcv="64"
Forwarding(0)="tcp_52186_80,tcp,127.0.0.1,52186,,80"
nic2="none"
nic3="none"
nic4="none"
nic5="none"
nic6="none"
nic7="none"
nic8="none"
hidpointing="ps2mouse"
hidkeyboard="ps2kbd"
uart1="off"
uart2="off"
uart3="off"
uart4="off"
lpt1="off"
lpt2="off"
audio="none"
audio_out="off"
audio_in="off"
clipboard="disabled"
draganddrop="disabled"
vrde="off"
usb="off"
ehci="off"
xhci="off"
BandwidthGroup0="boinc_3b9aaac965519643_net",Network,1099511627776
SharedFolderNameMachineMapping1="shared"
SharedFolderPathMachineMapping1="D:\\ProgramData\\BOINC\\slots\\0\\shared"
recording_enabled="off"
recording_screens=1
 rec_screen0
rec_screen_enabled="on"
rec_screen_id=0
rec_screen_video_enabled="on"
rec_screen_audio_enabled="off"
rec_screen_dest="File"
rec_screen_dest_filename="D:\\ProgramData\\BOINC\\slots\\0\\boinc_3b9aaac965519643\\boinc_3b9aaac965519643-screen0.webm"
rec_screen_opts="vc_enabled=true,ac_enabled=false,ac_profile=med"
rec_screen_video_res_xy="1024x768"
rec_screen_video_rate_kbps=512
rec_screen_video_fps=25
description="yvVKDmVtdd7nsSi4ap6QjLDmwznN0nGgGQJmUX

©2025 CERN