[23:30:28.760] New invocation is queued and will start shortly
[23:30:28.760] Triggered by user:chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com
[23:30:29.999] Starting the invocation (attempt 1)
[23:30:30.032] Ignoring gitiles_ref tag without the buildset tag
[23:30:30.032] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[23:30:30.032] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[23:30:30.032] Buildbucket request:
{
"requestId": "9000867744673193728",
"builder": {
"project": "infra",
"bucket": "cron",
"builder": "publish_tarball"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"triggers": [
{
"buildbucket": {
"properties": {
"version": "96.0.4649.3"
},
"tags": [
"parent_buildername:publish_tarball_dispatcher",
"user_agent:recipe"
]
},
"id": "57c57078-d016-4af4-85f2-ba50defa52ad",
"title": "publish_tarball_dispatcher/0"
}
]
},
"version": "96.0.4649.3"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "9000867744673193728"
},
{
"key": "scheduler_job_id",
"value": "infra/publish_tarball"
},
{
"key": "user_agent",
"value": "luci-scheduler"
},
{
"key": "parent_buildername",
"value": "publish_tarball_dispatcher"
},
{
"key": "user_agent",
"value": "recipe"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[23:30:30.414] Scheduled build:
{
"id": "8835437682594504193",
"builder": {
"project": "infra",
"bucket": "cron",
"builder": "publish_tarball"
},
"createdBy": "project:infra",
"createTime": "2021-09-21T23:30:30.109082399Z",
"updateTime": "2021-09-21T23:30:30.245149449Z",
"status": "SCHEDULED",
"input": {
}
}
[23:30:30.414] Task URL: https://cr-buildbucket.appspot.com/build/8835437682594504193
[23:30:30.414] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball:9000867744673193728:2:0) after 9m11s
[23:37:05.258] Received PubSub notification, asking Buildbucket for the build status
[23:37:05.287] Build status: STARTED
[23:39:41.593] Handling timer "check-buildbucket-build-status" (infra/publish_tarball:9000867744673193728:2:0)
[23:39:41.622] Build status: STARTED
[23:39:41.622] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball:9000867744673193728:4:0) after 4m37s
[23:44:18.638] Handling timer "check-buildbucket-build-status" (infra/publish_tarball:9000867744673193728:4:0)
[23:44:18.669] Build status: STARTED
[23:44:18.669] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball:9000867744673193728:5:0) after 2m14s
[23:46:32.650] Handling timer "check-buildbucket-build-status" (infra/publish_tarball:9000867744673193728:5:0)
[23:46:32.763] Build status: STARTED
[23:46:32.763] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball:9000867744673193728:6:0) after 1m6s
[23:47:39.098] Handling timer "check-buildbucket-build-status" (infra/publish_tarball:9000867744673193728:6:0)
[23:47:39.179] Build status: STARTED
[23:47:39.179] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball:9000867744673193728:7:0) after 9m49s
[23:57:28.196] Handling timer "check-buildbucket-build-status" (infra/publish_tarball:9000867744673193728:7:0)
[23:57:28.238] Build status: STARTED
[23:57:28.238] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball:9000867744673193728:8:0) after 2m5s
[23:59:33.510] Handling timer "check-buildbucket-build-status" (infra/publish_tarball:9000867744673193728:8:0)
[23:59:33.599] Build status: STARTED
[23:59:33.599] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball:9000867744673193728:9:0) after 3m22s
[00:02:55.750] Handling timer "check-buildbucket-build-status" (infra/publish_tarball:9000867744673193728:9:0)
[00:02:55.793] Build status: STARTED
[00:02:55.793] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball:9000867744673193728:10:0) after 1m52s
[00:04:47.765] Handling timer "check-buildbucket-build-status" (infra/publish_tarball:9000867744673193728:10:0)
[00:04:47.826] Build status: STARTED
[00:04:47.826] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball:9000867744673193728:11:0) after 8m51s
[00:13:38.884] Handling timer "check-buildbucket-build-status" (infra/publish_tarball:9000867744673193728:11:0)
[00:13:38.921] Build status: STARTED
[00:13:38.921] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball:9000867744673193728:12:0) after 9m46s
[00:23:25.306] Handling timer "check-buildbucket-build-status" (infra/publish_tarball:9000867744673193728:12:0)
[00:23:25.337] Build status: STARTED
[00:23:25.337] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball:9000867744673193728:13:0) after 4m55s
[00:28:20.455] Handling timer "check-buildbucket-build-status" (infra/publish_tarball:9000867744673193728:13:0)
[00:28:20.487] Build status: STARTED
[00:28:20.487] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball:9000867744673193728:14:0) after 6m40s
[00:35:00.613] Handling timer "check-buildbucket-build-status" (infra/publish_tarball:9000867744673193728:14:0)
[00:35:00.643] Build status: STARTED
[00:35:00.643] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball:9000867744673193728:15:0) after 8m29s
[00:43:29.713] Handling timer "check-buildbucket-build-status" (infra/publish_tarball:9000867744673193728:15:0)
[00:43:29.770] Build status: STARTED
[00:43:29.770] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball:9000867744673193728:16:0) after 3m35s
[00:47:04.891] Handling timer "check-buildbucket-build-status" (infra/publish_tarball:9000867744673193728:16:0)
[00:47:04.922] Build status: STARTED
[00:47:04.922] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball:9000867744673193728:17:0) after 1m17s
[00:48:22.200] Handling timer "check-buildbucket-build-status" (infra/publish_tarball:9000867744673193728:17:0)
[00:48:22.281] Build status: STARTED
[00:48:22.281] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball:9000867744673193728:18:0) after 8m47s
[00:57:09.331] Handling timer "check-buildbucket-build-status" (infra/publish_tarball:9000867744673193728:18:0)
[00:57:09.380] Build status: STARTED
[00:57:09.381] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball:9000867744673193728:19:0) after 8m42s
[01:05:51.399] Handling timer "check-buildbucket-build-status" (infra/publish_tarball:9000867744673193728:19:0)
[01:05:51.431] Build status: STARTED
[01:05:51.431] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball:9000867744673193728:20:0) after 3m10s
[01:09:01.686] Handling timer "check-buildbucket-build-status" (infra/publish_tarball:9000867744673193728:20:0)
[01:09:01.723] Build status: STARTED
[01:09:01.723] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball:9000867744673193728:21:0) after 1m49s
[01:10:50.918] Handling timer "check-buildbucket-build-status" (infra/publish_tarball:9000867744673193728:21:0)
[01:10:50.988] Build status: STARTED
[01:10:50.988] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball:9000867744673193728:22:0) after 1m11s
[01:12:02.011] Handling timer "check-buildbucket-build-status" (infra/publish_tarball:9000867744673193728:22:0)
[01:12:02.052] Build status: STARTED
[01:12:02.052] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball:9000867744673193728:23:0) after 3m2s
[01:15:04.261] Handling timer "check-buildbucket-build-status" (infra/publish_tarball:9000867744673193728:23:0)
[01:15:04.297] Build status: STARTED
[01:15:04.297] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball:9000867744673193728:24:0) after 8m17s
[01:23:21.448] Handling timer "check-buildbucket-build-status" (infra/publish_tarball:9000867744673193728:24:0)
[01:23:21.481] Build status: STARTED
[01:23:21.481] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball:9000867744673193728:25:0) after 1m54s
[01:25:15.537] Handling timer "check-buildbucket-build-status" (infra/publish_tarball:9000867744673193728:25:0)
[01:25:15.567] Build status: STARTED
[01:25:15.567] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball:9000867744673193728:26:0) after 4m59s
[01:30:14.766] Handling timer "check-buildbucket-build-status" (infra/publish_tarball:9000867744673193728:26:0)
[01:30:14.798] Build status: STARTED
[01:30:14.798] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball:9000867744673193728:27:0) after 5m11s
[01:35:25.812] Handling timer "check-buildbucket-build-status" (infra/publish_tarball:9000867744673193728:27:0)
[01:35:25.844] Build status: STARTED
[01:35:25.844] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball:9000867744673193728:28:0) after 4m32s
[01:39:57.947] Handling timer "check-buildbucket-build-status" (infra/publish_tarball:9000867744673193728:28:0)
[01:39:57.989] Build status: STARTED
[01:39:57.989] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball:9000867744673193728:29:0) after 1m0s
[01:40:58.213] Handling timer "check-buildbucket-build-status" (infra/publish_tarball:9000867744673193728:29:0)
[01:40:58.279] Build status: STARTED
[01:40:58.279] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball:9000867744673193728:30:0) after 8m21s
[01:49:19.367] Handling timer "check-buildbucket-build-status" (infra/publish_tarball:9000867744673193728:30:0)
[01:49:19.398] Build status: STARTED
[01:49:19.398] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball:9000867744673193728:31:0) after 9m31s
[01:58:50.736] Handling timer "check-buildbucket-build-status" (infra/publish_tarball:9000867744673193728:31:0)
[01:58:50.767] Build status: STARTED
[01:58:50.767] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball:9000867744673193728:32:0) after 5m42s
[02:04:33.175] Handling timer "check-buildbucket-build-status" (infra/publish_tarball:9000867744673193728:32:0)
[02:04:33.209] Build status: STARTED
[02:04:33.209] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball:9000867744673193728:33:0) after 1m12s
[02:05:45.573] Handling timer "check-buildbucket-build-status" (infra/publish_tarball:9000867744673193728:33:0)
[02:05:45.619] Build status: STARTED
[02:05:45.619] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball:9000867744673193728:34:0) after 3m42s
[02:09:27.638] Handling timer "check-buildbucket-build-status" (infra/publish_tarball:9000867744673193728:34:0)
[02:09:27.671] Build status: STARTED
[02:09:27.671] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball:9000867744673193728:35:0) after 5m19s
[02:14:46.781] Handling timer "check-buildbucket-build-status" (infra/publish_tarball:9000867744673193728:35:0)
[02:14:46.813] Build status: STARTED
[02:14:46.813] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball:9000867744673193728:36:0) after 7m32s
[02:22:18.941] Handling timer "check-buildbucket-build-status" (infra/publish_tarball:9000867744673193728:36:0)
[02:22:18.981] Build status: STARTED
[02:22:18.981] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball:9000867744673193728:37:0) after 2m11s
[02:24:30.118] Handling timer "check-buildbucket-build-status" (infra/publish_tarball:9000867744673193728:37:0)
[02:24:30.168] Build status: STARTED
[02:24:30.168] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball:9000867744673193728:38:0) after 1m2s
[02:25:32.560] Handling timer "check-buildbucket-build-status" (infra/publish_tarball:9000867744673193728:38:0)
[02:25:32.605] Build status: STARTED
[02:25:32.606] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball:9000867744673193728:39:0) after 6m6s
[02:31:38.637] Handling timer "check-buildbucket-build-status" (infra/publish_tarball:9000867744673193728:39:0)
[02:31:38.666] Build status: STARTED
[02:31:38.666] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball:9000867744673193728:40:0) after 1m42s
[02:33:20.684] Handling timer "check-buildbucket-build-status" (infra/publish_tarball:9000867744673193728:40:0)
[02:33:20.745] Build status: STARTED
[02:33:20.745] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball:9000867744673193728:41:0) after 9m3s
[02:42:24.025] Handling timer "check-buildbucket-build-status" (infra/publish_tarball:9000867744673193728:41:0)
[02:42:24.076] Build status: STARTED
[02:42:24.076] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball:9000867744673193728:42:0) after 2m59s
[02:45:23.093] Handling timer "check-buildbucket-build-status" (infra/publish_tarball:9000867744673193728:42:0)
[02:45:23.124] Build status: STARTED
[02:45:23.124] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball:9000867744673193728:43:0) after 9m21s
[02:54:44.182] Handling timer "check-buildbucket-build-status" (infra/publish_tarball:9000867744673193728:43:0)
[02:54:44.211] Build status: STARTED
[02:54:44.212] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball:9000867744673193728:44:0) after 3m51s
[02:58:35.408] Handling timer "check-buildbucket-build-status" (infra/publish_tarball:9000867744673193728:44:0)
[02:58:35.479] Build status: STARTED
[02:58:35.479] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball:9000867744673193728:45:0) after 5m48s
[03:04:23.912] Handling timer "check-buildbucket-build-status" (infra/publish_tarball:9000867744673193728:45:0)
[03:04:23.955] Build status: STARTED
[03:04:23.955] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball:9000867744673193728:46:0) after 4m11s
[03:08:35.049] Handling timer "check-buildbucket-build-status" (infra/publish_tarball:9000867744673193728:46:0)
[03:08:35.090] Build status: STARTED
[03:08:35.090] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball:9000867744673193728:47:0) after 7m13s
[03:15:48.108] Handling timer "check-buildbucket-build-status" (infra/publish_tarball:9000867744673193728:47:0)
[03:15:48.265] Build status: STARTED
[03:15:48.266] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball:9000867744673193728:48:0) after 2m35s
[03:18:23.424] Handling timer "check-buildbucket-build-status" (infra/publish_tarball:9000867744673193728:48:0)
[03:18:23.474] Build status: STARTED
[03:18:23.474] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball:9000867744673193728:49:0) after 1m9s
[03:19:32.493] Handling timer "check-buildbucket-build-status" (infra/publish_tarball:9000867744673193728:49:0)
[03:19:32.521] Build status: STARTED
[03:19:32.521] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball:9000867744673193728:50:0) after 7m59s
[03:27:31.644] Handling timer "check-buildbucket-build-status" (infra/publish_tarball:9000867744673193728:50:0)
[03:27:31.681] Build status: STARTED
[03:27:31.681] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball:9000867744673193728:51:0) after 3m0s
[03:30:31.803] Handling timer "check-buildbucket-build-status" (infra/publish_tarball:9000867744673193728:51:0)
[03:30:31.835] Build status: STARTED
[03:30:31.835] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball:9000867744673193728:52:0) after 5m42s
[03:36:13.904] Handling timer "check-buildbucket-build-status" (infra/publish_tarball:9000867744673193728:52:0)
[03:36:13.932] Build status: STARTED
[03:36:13.932] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball:9000867744673193728:53:0) after 9m0s
[03:45:13.951] Handling timer "check-buildbucket-build-status" (infra/publish_tarball:9000867744673193728:53:0)
[03:45:13.998] Build status: STARTED
[03:45:13.998] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball:9000867744673193728:54:0) after 1m49s
[03:47:03.240] Handling timer "check-buildbucket-build-status" (infra/publish_tarball:9000867744673193728:54:0)
[03:47:03.271] Build status: STARTED
[03:47:03.271] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball:9000867744673193728:55:0) after 8m32s
[03:55:35.488] Handling timer "check-buildbucket-build-status" (infra/publish_tarball:9000867744673193728:55:0)
[03:55:35.519] Build status: STARTED
[03:55:35.519] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball:9000867744673193728:56:0) after 7m55s
[04:03:30.537] Handling timer "check-buildbucket-build-status" (infra/publish_tarball:9000867744673193728:56:0)
[04:03:30.569] Build status: STARTED
[04:03:30.569] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball:9000867744673193728:57:0) after 3m20s
[04:06:50.696] Handling timer "check-buildbucket-build-status" (infra/publish_tarball:9000867744673193728:57:0)
[04:06:50.723] Build status: STARTED
[04:06:50.723] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball:9000867744673193728:58:0) after 8m5s
[04:14:55.747] Handling timer "check-buildbucket-build-status" (infra/publish_tarball:9000867744673193728:58:0)
[04:14:55.799] Build status: STARTED
[04:14:55.799] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball:9000867744673193728:59:0) after 4m55s
[04:19:50.828] Handling timer "check-buildbucket-build-status" (infra/publish_tarball:9000867744673193728:59:0)
[04:19:50.871] Build status: STARTED
[04:19:50.871] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball:9000867744673193728:60:0) after 3m11s
[04:23:01.907] Handling timer "check-buildbucket-build-status" (infra/publish_tarball:9000867744673193728:60:0)
[04:23:02.088] Build status: STARTED
[04:23:02.088] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball:9000867744673193728:61:0) after 3m40s
[04:26:42.090] Handling timer "check-buildbucket-build-status" (infra/publish_tarball:9000867744673193728:61:0)
[04:26:42.171] Build status: STARTED
[04:26:42.171] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball:9000867744673193728:62:0) after 2m53s
[04:29:35.683] Handling timer "check-buildbucket-build-status" (infra/publish_tarball:9000867744673193728:62:0)
[04:29:35.716] Build status: STARTED
[04:29:35.716] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball:9000867744673193728:63:0) after 2m21s
[04:31:56.766] Handling timer "check-buildbucket-build-status" (infra/publish_tarball:9000867744673193728:63:0)
[04:31:56.798] Build status: STARTED
[04:31:56.798] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball:9000867744673193728:64:0) after 4m37s
[04:36:34.019] Handling timer "check-buildbucket-build-status" (infra/publish_tarball:9000867744673193728:64:0)
[04:36:34.050] Build status: STARTED
[04:36:34.050] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball:9000867744673193728:65:0) after 1m48s
[04:38:22.105] Handling timer "check-buildbucket-build-status" (infra/publish_tarball:9000867744673193728:65:0)
[04:38:22.142] Build status: STARTED
[04:38:22.142] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball:9000867744673193728:66:0) after 6m50s
[04:45:12.164] Handling timer "check-buildbucket-build-status" (infra/publish_tarball:9000867744673193728:66:0)
[04:45:12.385] Build status: STARTED
[04:45:12.385] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball:9000867744673193728:67:0) after 4m55s
[04:50:07.612] Handling timer "check-buildbucket-build-status" (infra/publish_tarball:9000867744673193728:67:0)
[04:50:07.646] Build status: STARTED
[04:50:07.646] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball:9000867744673193728:68:0) after 1m48s
[04:51:55.752] Handling timer "check-buildbucket-build-status" (infra/publish_tarball:9000867744673193728:68:0)
[04:51:55.801] Build status: STARTED
[04:51:55.801] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball:9000867744673193728:69:0) after 8m30s
[05:00:25.883] Handling timer "check-buildbucket-build-status" (infra/publish_tarball:9000867744673193728:69:0)
[05:00:25.928] Build status: STARTED
[05:00:25.928] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball:9000867744673193728:70:0) after 3m40s
[05:04:06.083] Handling timer "check-buildbucket-build-status" (infra/publish_tarball:9000867744673193728:70:0)
[05:04:06.116] Build status: STARTED
[05:04:06.116] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball:9000867744673193728:71:0) after 8m34s
[05:12:40.134] Handling timer "check-buildbucket-build-status" (infra/publish_tarball:9000867744673193728:71:0)
[05:12:40.169] Build status: STARTED
[05:12:40.169] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball:9000867744673193728:72:0) after 5m56s
[05:18:36.193] Handling timer "check-buildbucket-build-status" (infra/publish_tarball:9000867744673193728:72:0)
[05:18:36.538] Build status: STARTED
[05:18:36.538] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball:9000867744673193728:73:0) after 7m31s
[05:24:36.940] Received PubSub notification, asking Buildbucket for the build status
[05:24:36.975] Build:
{
"id": "8835437682594504193",
"builder": {
"project": "infra",
"bucket": "cron",
"builder": "publish_tarball"
},
"createdBy": "project:infra",
"createTime": "2021-09-21T23:30:30.109082399Z",
"startTime": "2021-09-21T23:37:03.988157Z",
"endTime": "2021-09-22T05:24:36.593762745Z",
"updateTime": "2021-09-22T05:24:36.594414017Z",
"status": "SUCCESS",
"input": {
}
}
[05:24:36.975] Invocation finished in 5h54m8.231552458s with status SUCCEEDED