[00:44:03.023] New invocation is queued and will start shortly
[00:44:04.088] Starting the invocation (attempt 1)
[00:44:04.115] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/512517175290ac8dd3799670f7f25c708c7962d7"
[00:44:04.115] Popped gitiles commit info from properties and tags
[00:44:04.115] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[00:44:04.115] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[00:44:04.115] Buildbucket request:
{
"requestId": "8893686904722986928",
"builder": {
"project": "chromium-m132",
"bucket": "ci",
"builder": "win-official"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8893686904722986928",
"job": "chromium-m132/win-official",
"triggers": [
{
"gitiles": {
"ref": "refs/branch-heads/6834",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "9ea63d49219bd1f64b316265e49d6d8b777995d5"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6834@9ea63d49219bd1f64b316265e49d6d8b777995d5",
"title": "9ea63d49219bd1f64b316265e49d6d8b777995d5",
"url": "https://chromium.googlesource.com/chromium/src/+/9ea63d49219bd1f64b316265e49d6d8b777995d5"
},
{
"gitiles": {
"ref": "refs/branch-heads/6834",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "7aec79d7666a2a78eef6eea825ff1542ccbab253"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6834@7aec79d7666a2a78eef6eea825ff1542ccbab253",
"title": "7aec79d7666a2a78eef6eea825ff1542ccbab253",
"url": "https://chromium.googlesource.com/chromium/src/+/7aec79d7666a2a78eef6eea825ff1542ccbab253"
},
{
"gitiles": {
"ref": "refs/branch-heads/6834",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "5bff9b4b390d100b6429f6ad586c62d17aaf807d"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6834@5bff9b4b390d100b6429f6ad586c62d17aaf807d",
"title": "5bff9b4b390d100b6429f6ad586c62d17aaf807d",
"url": "https://chromium.googlesource.com/chromium/src/+/5bff9b4b390d100b6429f6ad586c62d17aaf807d"
},
{
"gitiles": {
"ref": "refs/branch-heads/6834",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "fa8d3a00dfb5a2fee0060dd6a6ed917c5d29a73a"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6834@fa8d3a00dfb5a2fee0060dd6a6ed917c5d29a73a",
"title": "fa8d3a00dfb5a2fee0060dd6a6ed917c5d29a73a",
"url": "https://chromium.googlesource.com/chromium/src/+/fa8d3a00dfb5a2fee0060dd6a6ed917c5d29a73a"
},
{
"gitiles": {
"ref": "refs/branch-heads/6834",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "a735fbc700d07ef2ea2981726e86552440caa951"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6834@a735fbc700d07ef2ea2981726e86552440caa951",
"title": "a735fbc700d07ef2ea2981726e86552440caa951",
"url": "https://chromium.googlesource.com/chromium/src/+/a735fbc700d07ef2ea2981726e86552440caa951"
},
{
"gitiles": {
"ref": "refs/branch-heads/6834",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "d320fb9713a9f08241a17b6cb6ef6cf15ebe0ed4"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6834@d320fb9713a9f08241a17b6cb6ef6cf15ebe0ed4",
"title": "d320fb9713a9f08241a17b6cb6ef6cf15ebe0ed4",
"url": "https://chromium.googlesource.com/chromium/src/+/d320fb9713a9f08241a17b6cb6ef6cf15ebe0ed4"
},
{
"gitiles": {
"ref": "refs/branch-heads/6834",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "6cca6450ea3d4d4916e5ce49dc7ddabb8082685e"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6834@6cca6450ea3d4d4916e5ce49dc7ddabb8082685e",
"title": "6cca6450ea3d4d4916e5ce49dc7ddabb8082685e",
"url": "https://chromium.googlesource.com/chromium/src/+/6cca6450ea3d4d4916e5ce49dc7ddabb8082685e"
},
{
"gitiles": {
"ref": "refs/branch-heads/6834",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "bc93dbda0355bec4a84fa5d21c81d02229467116"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6834@bc93dbda0355bec4a84fa5d21c81d02229467116",
"title": "bc93dbda0355bec4a84fa5d21c81d02229467116",
"url": "https://chromium.googlesource.com/chromium/src/+/bc93dbda0355bec4a84fa5d21c81d02229467116"
},
{
"gitiles": {
"ref": "refs/branch-heads/6834",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "689cf39ec3e3ed723b61b79b8e0ee98859bc2a57"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6834@689cf39ec3e3ed723b61b79b8e0ee98859bc2a57",
"title": "689cf39ec3e3ed723b61b79b8e0ee98859bc2a57",
"url": "https://chromium.googlesource.com/chromium/src/+/689cf39ec3e3ed723b61b79b8e0ee98859bc2a57"
},
{
"gitiles": {
"ref": "refs/branch-heads/6834",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "20fcc4d5b5ebdd157dcfebccfc3adf3719428bfe"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6834@20fcc4d5b5ebdd157dcfebccfc3adf3719428bfe",
"title": "20fcc4d5b5ebdd157dcfebccfc3adf3719428bfe",
"url": "https://chromium.googlesource.com/chromium/src/+/20fcc4d5b5ebdd157dcfebccfc3adf3719428bfe"
},
{
"gitiles": {
"ref": "refs/branch-heads/6834",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "78dcd890f7d65fa1c834ce34090af9da3ee92312"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6834@78dcd890f7d65fa1c834ce34090af9da3ee92312",
"title": "78dcd890f7d65fa1c834ce34090af9da3ee92312",
"url": "https://chromium.googlesource.com/chromium/src/+/78dcd890f7d65fa1c834ce34090af9da3ee92312"
},
{
"gitiles": {
"ref": "refs/branch-heads/6834",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "88f827365ca53d5ddcc5867e02fbef8f95ab39f9"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6834@88f827365ca53d5ddcc5867e02fbef8f95ab39f9",
"title": "88f827365ca53d5ddcc5867e02fbef8f95ab39f9",
"url": "https://chromium.googlesource.com/chromium/src/+/88f827365ca53d5ddcc5867e02fbef8f95ab39f9"
},
{
"gitiles": {
"ref": "refs/branch-heads/6834",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "e7b91bd6f542da564f6ff3cb864bae6212a9e29b"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6834@e7b91bd6f542da564f6ff3cb864bae6212a9e29b",
"title": "e7b91bd6f542da564f6ff3cb864bae6212a9e29b",
"url": "https://chromium.googlesource.com/chromium/src/+/e7b91bd6f542da564f6ff3cb864bae6212a9e29b"
},
{
"gitiles": {
"ref": "refs/branch-heads/6834",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "512517175290ac8dd3799670f7f25c708c7962d7"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6834@512517175290ac8dd3799670f7f25c708c7962d7",
"title": "512517175290ac8dd3799670f7f25c708c7962d7",
"url": "https://chromium.googlesource.com/chromium/src/+/512517175290ac8dd3799670f7f25c708c7962d7"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "512517175290ac8dd3799670f7f25c708c7962d7",
"ref": "refs/branch-heads/6834"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8893686904722986928"
},
{
"key": "scheduler_job_id",
"value": "chromium-m132/win-official"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[00:44:05.012] Scheduled build:
{
"id": "8728256842867117041",
"builder": {
"project": "chromium-m132",
"bucket": "ci",
"builder": "win-official"
},
"number": 229,
"createdBy": "project:chromium-m132",
"createTime": "2024-12-18T00:44:04.168716935Z",
"updateTime": "2024-12-18T00:44:04.168716935Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "512517175290ac8dd3799670f7f25c708c7962d7",
"ref": "refs/branch-heads/6834"
}
}
}
[00:44:05.012] Task URL: https://cr-buildbucket.appspot.com/build/8728256842867117041
[00:44:05.012] Scheduling timer "check-buildbucket-build-status" (chromium-m132/win-official:8893686904722986928:2:0) after 8m21s
[00:44:05.900] Received PubSub notification, asking Buildbucket for the build status
[00:44:05.924] Build status: SCHEDULED
[00:44:09.851] Received PubSub notification, asking Buildbucket for the build status
[00:44:09.874] Build status: STARTED
[00:52:26.030] Handling timer "check-buildbucket-build-status" (chromium-m132/win-official:8893686904722986928:2:0)
[00:52:26.066] Build status: STARTED
[00:52:26.066] Scheduling timer "check-buildbucket-build-status" (chromium-m132/win-official:8893686904722986928:5:0) after 7m11s
[00:59:37.081] Handling timer "check-buildbucket-build-status" (chromium-m132/win-official:8893686904722986928:5:0)
[00:59:37.104] Build status: STARTED
[00:59:37.104] Scheduling timer "check-buildbucket-build-status" (chromium-m132/win-official:8893686904722986928:6:0) after 1m37s
[01:01:14.122] Handling timer "check-buildbucket-build-status" (chromium-m132/win-official:8893686904722986928:6:0)
[01:01:14.183] Build status: STARTED
[01:01:14.183] Scheduling timer "check-buildbucket-build-status" (chromium-m132/win-official:8893686904722986928:7:0) after 4m12s
[01:05:26.159] Handling timer "check-buildbucket-build-status" (chromium-m132/win-official:8893686904722986928:7:0)
[01:05:26.200] Build status: STARTED
[01:05:26.200] Scheduling timer "check-buildbucket-build-status" (chromium-m132/win-official:8893686904722986928:8:0) after 9m29s
[01:14:55.216] Handling timer "check-buildbucket-build-status" (chromium-m132/win-official:8893686904722986928:8:0)
[01:14:55.236] Build status: STARTED
[01:14:55.236] Scheduling timer "check-buildbucket-build-status" (chromium-m132/win-official:8893686904722986928:9:0) after 1m29s
[01:16:24.268] Handling timer "check-buildbucket-build-status" (chromium-m132/win-official:8893686904722986928:9:0)
[01:16:24.287] Build status: STARTED
[01:16:24.287] Scheduling timer "check-buildbucket-build-status" (chromium-m132/win-official:8893686904722986928:10:0) after 3m46s
[01:20:10.356] Handling timer "check-buildbucket-build-status" (chromium-m132/win-official:8893686904722986928:10:0)
[01:20:10.381] Build status: STARTED
[01:20:10.381] Scheduling timer "check-buildbucket-build-status" (chromium-m132/win-official:8893686904722986928:11:0) after 8m36s
[01:28:46.447] Handling timer "check-buildbucket-build-status" (chromium-m132/win-official:8893686904722986928:11:0)
[01:28:46.466] Build status: STARTED
[01:28:46.466] Scheduling timer "check-buildbucket-build-status" (chromium-m132/win-official:8893686904722986928:12:0) after 2m33s
[01:31:19.480] Handling timer "check-buildbucket-build-status" (chromium-m132/win-official:8893686904722986928:12:0)
[01:31:19.501] Build status: STARTED
[01:31:19.501] Scheduling timer "check-buildbucket-build-status" (chromium-m132/win-official:8893686904722986928:13:0) after 8m8s
[01:39:27.525] Handling timer "check-buildbucket-build-status" (chromium-m132/win-official:8893686904722986928:13:0)
[01:39:27.548] Build status: STARTED
[01:39:27.548] Scheduling timer "check-buildbucket-build-status" (chromium-m132/win-official:8893686904722986928:14:0) after 6m30s
[01:45:57.568] Handling timer "check-buildbucket-build-status" (chromium-m132/win-official:8893686904722986928:14:0)
[01:45:57.632] Build status: STARTED
[01:45:57.632] Scheduling timer "check-buildbucket-build-status" (chromium-m132/win-official:8893686904722986928:15:0) after 3m30s
[01:49:27.647] Handling timer "check-buildbucket-build-status" (chromium-m132/win-official:8893686904722986928:15:0)
[01:49:27.665] Build status: STARTED
[01:49:27.666] Scheduling timer "check-buildbucket-build-status" (chromium-m132/win-official:8893686904722986928:16:0) after 8m38s
[01:58:05.685] Handling timer "check-buildbucket-build-status" (chromium-m132/win-official:8893686904722986928:16:0)
[01:58:05.710] Build status: STARTED
[01:58:05.710] Scheduling timer "check-buildbucket-build-status" (chromium-m132/win-official:8893686904722986928:17:0) after 7m2s
[02:05:07.727] Handling timer "check-buildbucket-build-status" (chromium-m132/win-official:8893686904722986928:17:0)
[02:05:07.786] Build status: STARTED
[02:05:07.786] Scheduling timer "check-buildbucket-build-status" (chromium-m132/win-official:8893686904722986928:18:0) after 8m14s
[02:13:21.779] Handling timer "check-buildbucket-build-status" (chromium-m132/win-official:8893686904722986928:18:0)
[02:13:21.800] Build status: STARTED
[02:13:21.800] Scheduling timer "check-buildbucket-build-status" (chromium-m132/win-official:8893686904722986928:19:0) after 1m41s
[02:15:02.844] Handling timer "check-buildbucket-build-status" (chromium-m132/win-official:8893686904722986928:19:0)
[02:15:02.861] Build status: STARTED
[02:15:02.861] Scheduling timer "check-buildbucket-build-status" (chromium-m132/win-official:8893686904722986928:20:0) after 2m42s
[02:17:44.886] Handling timer "check-buildbucket-build-status" (chromium-m132/win-official:8893686904722986928:20:0)
[02:17:44.903] Build status: STARTED
[02:17:44.903] Scheduling timer "check-buildbucket-build-status" (chromium-m132/win-official:8893686904722986928:21:0) after 6m22s
[02:24:06.937] Handling timer "check-buildbucket-build-status" (chromium-m132/win-official:8893686904722986928:21:0)
[02:24:06.962] Build status: STARTED
[02:24:06.962] Scheduling timer "check-buildbucket-build-status" (chromium-m132/win-official:8893686904722986928:22:0) after 9m33s
[02:33:40.130] Handling timer "check-buildbucket-build-status" (chromium-m132/win-official:8893686904722986928:22:0)
[02:33:40.145] Build status: STARTED
[02:33:40.145] Scheduling timer "check-buildbucket-build-status" (chromium-m132/win-official:8893686904722986928:23:0) after 2m15s
[02:35:55.357] Handling timer "check-buildbucket-build-status" (chromium-m132/win-official:8893686904722986928:23:0)
[02:35:55.381] Build status: STARTED
[02:35:55.381] Scheduling timer "check-buildbucket-build-status" (chromium-m132/win-official:8893686904722986928:24:0) after 4m23s
[02:40:18.396] Handling timer "check-buildbucket-build-status" (chromium-m132/win-official:8893686904722986928:24:0)
[02:40:18.411] Build status: STARTED
[02:40:18.411] Scheduling timer "check-buildbucket-build-status" (chromium-m132/win-official:8893686904722986928:25:0) after 7m30s
[02:47:48.475] Handling timer "check-buildbucket-build-status" (chromium-m132/win-official:8893686904722986928:25:0)
[02:47:48.498] Build status: STARTED
[02:47:48.498] Scheduling timer "check-buildbucket-build-status" (chromium-m132/win-official:8893686904722986928:26:0) after 1m29s
[02:49:17.514] Handling timer "check-buildbucket-build-status" (chromium-m132/win-official:8893686904722986928:26:0)
[02:49:17.679] Build status: STARTED
[02:49:17.679] Scheduling timer "check-buildbucket-build-status" (chromium-m132/win-official:8893686904722986928:27:0) after 7m27s
[02:56:44.694] Handling timer "check-buildbucket-build-status" (chromium-m132/win-official:8893686904722986928:27:0)
[02:56:44.771] Build status: STARTED
[02:56:44.771] Scheduling timer "check-buildbucket-build-status" (chromium-m132/win-official:8893686904722986928:28:0) after 7m34s
[03:04:18.756] Handling timer "check-buildbucket-build-status" (chromium-m132/win-official:8893686904722986928:28:0)
[03:04:18.772] Build status: STARTED
[03:04:18.772] Scheduling timer "check-buildbucket-build-status" (chromium-m132/win-official:8893686904722986928:29:0) after 8m5s
[03:12:23.791] Handling timer "check-buildbucket-build-status" (chromium-m132/win-official:8893686904722986928:29:0)
[03:12:23.809] Build status: STARTED
[03:12:23.810] Scheduling timer "check-buildbucket-build-status" (chromium-m132/win-official:8893686904722986928:30:0) after 9m44s
[03:22:07.832] Handling timer "check-buildbucket-build-status" (chromium-m132/win-official:8893686904722986928:30:0)
[03:22:07.850] Build status: STARTED
[03:22:07.850] Scheduling timer "check-buildbucket-build-status" (chromium-m132/win-official:8893686904722986928:31:0) after 7m34s
[03:29:42.052] Handling timer "check-buildbucket-build-status" (chromium-m132/win-official:8893686904722986928:31:0)
[03:29:42.068] Build status: STARTED
[03:29:42.068] Scheduling timer "check-buildbucket-build-status" (chromium-m132/win-official:8893686904722986928:32:0) after 2m32s
[03:32:14.179] Handling timer "check-buildbucket-build-status" (chromium-m132/win-official:8893686904722986928:32:0)
[03:32:14.199] Build status: STARTED
[03:32:14.199] Scheduling timer "check-buildbucket-build-status" (chromium-m132/win-official:8893686904722986928:33:0) after 9m46s
[03:42:00.545] Handling timer "check-buildbucket-build-status" (chromium-m132/win-official:8893686904722986928:33:0)
[03:42:00.578] Build status: STARTED
[03:42:00.578] Scheduling timer "check-buildbucket-build-status" (chromium-m132/win-official:8893686904722986928:34:0) after 7m44s
[03:49:44.597] Handling timer "check-buildbucket-build-status" (chromium-m132/win-official:8893686904722986928:34:0)
[03:49:44.612] Build status: STARTED
[03:49:44.613] Scheduling timer "check-buildbucket-build-status" (chromium-m132/win-official:8893686904722986928:35:0) after 1m40s
[03:51:24.630] Handling timer "check-buildbucket-build-status" (chromium-m132/win-official:8893686904722986928:35:0)
[03:51:24.670] Build status: STARTED
[03:51:24.670] Scheduling timer "check-buildbucket-build-status" (chromium-m132/win-official:8893686904722986928:36:0) after 6m37s
[03:58:01.755] Handling timer "check-buildbucket-build-status" (chromium-m132/win-official:8893686904722986928:36:0)
[03:58:01.784] Build status: STARTED
[03:58:01.784] Scheduling timer "check-buildbucket-build-status" (chromium-m132/win-official:8893686904722986928:37:0) after 5m15s
[04:03:16.800] Handling timer "check-buildbucket-build-status" (chromium-m132/win-official:8893686904722986928:37:0)
[04:03:16.838] Build status: STARTED
[04:03:16.838] Scheduling timer "check-buildbucket-build-status" (chromium-m132/win-official:8893686904722986928:38:0) after 6m39s
[04:09:55.855] Handling timer "check-buildbucket-build-status" (chromium-m132/win-official:8893686904722986928:38:0)
[04:09:55.875] Build status: STARTED
[04:09:55.875] Scheduling timer "check-buildbucket-build-status" (chromium-m132/win-official:8893686904722986928:39:0) after 8m3s
[04:17:58.892] Handling timer "check-buildbucket-build-status" (chromium-m132/win-official:8893686904722986928:39:0)
[04:17:58.915] Build status: STARTED
[04:17:58.915] Scheduling timer "check-buildbucket-build-status" (chromium-m132/win-official:8893686904722986928:40:0) after 4m45s
[04:22:43.916] Handling timer "check-buildbucket-build-status" (chromium-m132/win-official:8893686904722986928:40:0)
[04:22:43.934] Build status: STARTED
[04:22:43.934] Scheduling timer "check-buildbucket-build-status" (chromium-m132/win-official:8893686904722986928:41:0) after 3m50s
[04:26:33.953] Handling timer "check-buildbucket-build-status" (chromium-m132/win-official:8893686904722986928:41:0)
[04:26:33.972] Build status: STARTED
[04:26:33.972] Scheduling timer "check-buildbucket-build-status" (chromium-m132/win-official:8893686904722986928:42:0) after 8m9s
[04:34:42.988] Handling timer "check-buildbucket-build-status" (chromium-m132/win-official:8893686904722986928:42:0)
[04:34:43.005] Build status: STARTED
[04:34:43.005] Scheduling timer "check-buildbucket-build-status" (chromium-m132/win-official:8893686904722986928:43:0) after 2m42s
[04:37:25.323] Handling timer "check-buildbucket-build-status" (chromium-m132/win-official:8893686904722986928:43:0)
[04:37:25.384] Build status: STARTED
[04:37:25.384] Scheduling timer "check-buildbucket-build-status" (chromium-m132/win-official:8893686904722986928:44:0) after 5m36s
[04:43:02.154] Handling timer "check-buildbucket-build-status" (chromium-m132/win-official:8893686904722986928:44:0)
[04:43:02.174] Build status: STARTED
[04:43:02.174] Scheduling timer "check-buildbucket-build-status" (chromium-m132/win-official:8893686904722986928:45:0) after 3m15s
[04:44:03.583] Received PubSub notification, asking Buildbucket for the build status
[04:44:03.600] Build:
{
"id": "8728256842867117041",
"builder": {
"project": "chromium-m132",
"bucket": "ci",
"builder": "win-official"
},
"number": 229,
"createdBy": "project:chromium-m132",
"createTime": "2024-12-18T00:44:04.168716935Z",
"startTime": "2024-12-18T00:44:09.649868237Z",
"endTime": "2024-12-18T04:43:55.126904739Z",
"updateTime": "2024-12-18T04:43:55.126904739Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "512517175290ac8dd3799670f7f25c708c7962d7",
"ref": "refs/branch-heads/6834"
}
}
}
[04:44:03.600] Invocation finished in 4h0m0.585222917s with status SUCCEEDED