[16:34:16.968] New invocation is queued and will start shortly
[16:34:19.432] Starting the invocation (attempt 1)
[16:34:19.460] Popped buildset tag "buildset:commit/gitiles/fuchsia.googlesource.com/integration/+/79697278e5b0c20329bbce01f415213462a04bab"
[16:34:19.460] Popped gitiles commit info from properties and tags
[16:34:19.460] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[16:34:19.462] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[16:34:19.462] Buildbucket request:
{
"requestId": "8871883849336340736",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.x64-full_determinism"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8871883849336340736",
"job": "fuchsia/global.ci-core.x64-full_determinism-e5a0ec7f",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "3d5b157ccb67ecc56a28dc770de5a03efe52f0af"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@3d5b157ccb67ecc56a28dc770de5a03efe52f0af",
"title": "3d5b157ccb67ecc56a28dc770de5a03efe52f0af",
"url": "https://fuchsia.googlesource.com/integration/+/3d5b157ccb67ecc56a28dc770de5a03efe52f0af"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "b8b22036282c874d7a5c7aed9fc2b6a9f198bc29"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@b8b22036282c874d7a5c7aed9fc2b6a9f198bc29",
"title": "b8b22036282c874d7a5c7aed9fc2b6a9f198bc29",
"url": "https://fuchsia.googlesource.com/integration/+/b8b22036282c874d7a5c7aed9fc2b6a9f198bc29"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "d6d9b4c2d1ea9bb2f042c4849ab2c9decdee9692"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@d6d9b4c2d1ea9bb2f042c4849ab2c9decdee9692",
"title": "d6d9b4c2d1ea9bb2f042c4849ab2c9decdee9692",
"url": "https://fuchsia.googlesource.com/integration/+/d6d9b4c2d1ea9bb2f042c4849ab2c9decdee9692"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "15a7c2b0ea7bb2a9748dca3b4a7a549352b05ad2"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@15a7c2b0ea7bb2a9748dca3b4a7a549352b05ad2",
"title": "15a7c2b0ea7bb2a9748dca3b4a7a549352b05ad2",
"url": "https://fuchsia.googlesource.com/integration/+/15a7c2b0ea7bb2a9748dca3b4a7a549352b05ad2"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "79697278e5b0c20329bbce01f415213462a04bab"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@79697278e5b0c20329bbce01f415213462a04bab",
"title": "79697278e5b0c20329bbce01f415213462a04bab",
"url": "https://fuchsia.googlesource.com/integration/+/79697278e5b0c20329bbce01f415213462a04bab"
}
]
}
},
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "79697278e5b0c20329bbce01f415213462a04bab",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8871883849336340736"
},
{
"key": "scheduler_job_id",
"value": "fuchsia/global.ci-core.x64-full_determinism-e5a0ec7f"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[16:34:19.908] Scheduled build:
{
"id": "8706453785951946289",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.x64-full_determinism"
},
"createdBy": "project:fuchsia",
"createTime": "2025-08-15T16:34:19.567060207Z",
"updateTime": "2025-08-15T16:34:19.567060207Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "79697278e5b0c20329bbce01f415213462a04bab",
"ref": "refs/heads/main"
}
}
}
[16:34:19.908] Task URL: https://cr-buildbucket.appspot.com/build/8706453785951946289
[16:34:19.908] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-full_determinism-e5a0ec7f:8871883849336340736:2:0) after 3m23s
[16:34:20.874] Received PubSub notification, asking Buildbucket for the build status
[16:34:20.924] Build status: SCHEDULED
[16:34:22.904] Received PubSub notification, asking Buildbucket for the build status
[16:34:22.972] Build status: STARTED
[16:37:42.926] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-full_determinism-e5a0ec7f:8871883849336340736:2:0)
[16:37:42.951] Build status: STARTED
[16:37:42.951] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-full_determinism-e5a0ec7f:8871883849336340736:5:0) after 8m55s
[16:46:38.079] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-full_determinism-e5a0ec7f:8871883849336340736:5:0)
[16:46:38.102] Build status: STARTED
[16:46:38.102] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-full_determinism-e5a0ec7f:8871883849336340736:6:0) after 1m29s
[16:48:07.207] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-full_determinism-e5a0ec7f:8871883849336340736:6:0)
[16:48:07.231] Build status: STARTED
[16:48:07.231] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-full_determinism-e5a0ec7f:8871883849336340736:7:0) after 6m54s
[16:55:01.353] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-full_determinism-e5a0ec7f:8871883849336340736:7:0)
[16:55:01.428] Build status: STARTED
[16:55:01.428] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-full_determinism-e5a0ec7f:8871883849336340736:8:0) after 7m14s
[17:02:15.484] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-full_determinism-e5a0ec7f:8871883849336340736:8:0)
[17:02:15.520] Build status: STARTED
[17:02:15.520] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-full_determinism-e5a0ec7f:8871883849336340736:9:0) after 9m55s
[17:12:10.585] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-full_determinism-e5a0ec7f:8871883849336340736:9:0)
[17:12:10.604] Build status: STARTED
[17:12:10.604] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-full_determinism-e5a0ec7f:8871883849336340736:10:0) after 1m4s
[17:13:14.770] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-full_determinism-e5a0ec7f:8871883849336340736:10:0)
[17:13:14.809] Build status: STARTED
[17:13:14.809] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-full_determinism-e5a0ec7f:8871883849336340736:11:0) after 6m52s
[17:20:06.874] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-full_determinism-e5a0ec7f:8871883849336340736:11:0)
[17:20:06.897] Build status: STARTED
[17:20:06.897] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-full_determinism-e5a0ec7f:8871883849336340736:12:0) after 3m38s
[17:23:44.990] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-full_determinism-e5a0ec7f:8871883849336340736:12:0)
[17:23:45.018] Build status: STARTED
[17:23:45.018] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-full_determinism-e5a0ec7f:8871883849336340736:13:0) after 8m3s
[17:31:48.139] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-full_determinism-e5a0ec7f:8871883849336340736:13:0)
[17:31:48.185] Build status: STARTED
[17:31:48.185] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-full_determinism-e5a0ec7f:8871883849336340736:14:0) after 9m53s
[17:41:41.252] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-full_determinism-e5a0ec7f:8871883849336340736:14:0)
[17:41:41.273] Build status: STARTED
[17:41:41.273] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-full_determinism-e5a0ec7f:8871883849336340736:15:0) after 8m33s
[17:50:14.326] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-full_determinism-e5a0ec7f:8871883849336340736:15:0)
[17:50:14.352] Build status: STARTED
[17:50:14.352] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-full_determinism-e5a0ec7f:8871883849336340736:16:0) after 8m36s
[17:58:50.444] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-full_determinism-e5a0ec7f:8871883849336340736:16:0)
[17:58:50.467] Build status: STARTED
[17:58:50.467] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-full_determinism-e5a0ec7f:8871883849336340736:17:0) after 4m14s
[18:03:04.478] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-full_determinism-e5a0ec7f:8871883849336340736:17:0)
[18:03:04.500] Build status: STARTED
[18:03:04.500] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-full_determinism-e5a0ec7f:8871883849336340736:18:0) after 4m44s
[18:07:48.517] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-full_determinism-e5a0ec7f:8871883849336340736:18:0)
[18:07:48.534] Build status: STARTED
[18:07:48.534] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-full_determinism-e5a0ec7f:8871883849336340736:19:0) after 7m7s
[18:14:55.598] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-full_determinism-e5a0ec7f:8871883849336340736:19:0)
[18:14:55.619] Build status: STARTED
[18:14:55.619] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-full_determinism-e5a0ec7f:8871883849336340736:20:0) after 2m13s
[18:17:08.879] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-full_determinism-e5a0ec7f:8871883849336340736:20:0)
[18:17:08.895] Build status: STARTED
[18:17:08.895] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-full_determinism-e5a0ec7f:8871883849336340736:21:0) after 1m37s
[18:18:45.969] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-full_determinism-e5a0ec7f:8871883849336340736:21:0)
[18:18:45.985] Build status: STARTED
[18:18:45.985] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-full_determinism-e5a0ec7f:8871883849336340736:22:0) after 7m59s
[18:26:44.983] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-full_determinism-e5a0ec7f:8871883849336340736:22:0)
[18:26:44.999] Build status: STARTED
[18:26:44.999] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-full_determinism-e5a0ec7f:8871883849336340736:23:0) after 4m45s
[18:31:30.018] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-full_determinism-e5a0ec7f:8871883849336340736:23:0)
[18:31:30.035] Build status: STARTED
[18:31:30.035] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-full_determinism-e5a0ec7f:8871883849336340736:24:0) after 3m33s
[18:35:03.207] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-full_determinism-e5a0ec7f:8871883849336340736:24:0)
[18:35:03.223] Build status: STARTED
[18:35:03.223] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-full_determinism-e5a0ec7f:8871883849336340736:25:0) after 3m35s
[18:38:38.307] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-full_determinism-e5a0ec7f:8871883849336340736:25:0)
[18:38:38.332] Build status: STARTED
[18:38:38.332] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-full_determinism-e5a0ec7f:8871883849336340736:26:0) after 5m0s
[18:43:38.385] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-full_determinism-e5a0ec7f:8871883849336340736:26:0)
[18:43:38.402] Build status: STARTED
[18:43:38.402] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-full_determinism-e5a0ec7f:8871883849336340736:27:0) after 2m33s
[18:46:11.421] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-full_determinism-e5a0ec7f:8871883849336340736:27:0)
[18:46:11.449] Build status: STARTED
[18:46:11.449] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-full_determinism-e5a0ec7f:8871883849336340736:28:0) after 9m32s
[18:55:43.517] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-full_determinism-e5a0ec7f:8871883849336340736:28:0)
[18:55:43.537] Build status: STARTED
[18:55:43.537] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-full_determinism-e5a0ec7f:8871883849336340736:29:0) after 3m23s
[18:59:06.651] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-full_determinism-e5a0ec7f:8871883849336340736:29:0)
[18:59:06.674] Build status: STARTED
[18:59:06.674] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-full_determinism-e5a0ec7f:8871883849336340736:30:0) after 9m21s
[19:08:27.703] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-full_determinism-e5a0ec7f:8871883849336340736:30:0)
[19:08:27.750] Build status: STARTED
[19:08:27.750] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-full_determinism-e5a0ec7f:8871883849336340736:31:0) after 7m49s
[19:16:16.840] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-full_determinism-e5a0ec7f:8871883849336340736:31:0)
[19:16:16.857] Build status: STARTED
[19:16:16.857] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-full_determinism-e5a0ec7f:8871883849336340736:32:0) after 8m55s
[19:25:11.920] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-full_determinism-e5a0ec7f:8871883849336340736:32:0)
[19:25:11.943] Build status: STARTED
[19:25:11.943] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-full_determinism-e5a0ec7f:8871883849336340736:33:0) after 4m41s
[19:29:53.040] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-full_determinism-e5a0ec7f:8871883849336340736:33:0)
[19:29:53.065] Build status: STARTED
[19:29:53.065] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-full_determinism-e5a0ec7f:8871883849336340736:34:0) after 5m20s
[19:35:13.086] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-full_determinism-e5a0ec7f:8871883849336340736:34:0)
[19:35:13.103] Build status: STARTED
[19:35:13.103] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-full_determinism-e5a0ec7f:8871883849336340736:35:0) after 5m56s
[19:41:09.120] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-full_determinism-e5a0ec7f:8871883849336340736:35:0)
[19:41:09.138] Build status: STARTED
[19:41:09.138] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-full_determinism-e5a0ec7f:8871883849336340736:36:0) after 8m32s
[19:49:41.155] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-full_determinism-e5a0ec7f:8871883849336340736:36:0)
[19:49:41.175] Build status: STARTED
[19:49:41.175] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-full_determinism-e5a0ec7f:8871883849336340736:37:0) after 9m30s
[19:59:11.197] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-full_determinism-e5a0ec7f:8871883849336340736:37:0)
[19:59:11.214] Build status: STARTED
[19:59:11.214] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-full_determinism-e5a0ec7f:8871883849336340736:38:0) after 9m35s
[20:08:46.265] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-full_determinism-e5a0ec7f:8871883849336340736:38:0)
[20:08:46.292] Build status: STARTED
[20:08:46.292] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-full_determinism-e5a0ec7f:8871883849336340736:39:0) after 1m46s
[20:10:32.383] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-full_determinism-e5a0ec7f:8871883849336340736:39:0)
[20:10:32.419] Build status: STARTED
[20:10:32.419] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-full_determinism-e5a0ec7f:8871883849336340736:40:0) after 9m45s
[20:20:17.450] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-full_determinism-e5a0ec7f:8871883849336340736:40:0)
[20:20:17.472] Build status: STARTED
[20:20:17.472] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-full_determinism-e5a0ec7f:8871883849336340736:41:0) after 4m22s
[20:24:39.543] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-full_determinism-e5a0ec7f:8871883849336340736:41:0)
[20:24:39.562] Build status: STARTED
[20:24:39.562] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-full_determinism-e5a0ec7f:8871883849336340736:42:0) after 7m28s
[20:32:07.585] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-full_determinism-e5a0ec7f:8871883849336340736:42:0)
[20:32:07.628] Build status: STARTED
[20:32:07.628] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-full_determinism-e5a0ec7f:8871883849336340736:43:0) after 6m15s
[20:32:12.730] Received PubSub notification, asking Buildbucket for the build status
[20:32:12.748] Build:
{
"id": "8706453785951946289",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.x64-full_determinism"
},
"createdBy": "project:fuchsia",
"createTime": "2025-08-15T16:34:19.567060207Z",
"startTime": "2025-08-15T16:34:22.503333253Z",
"endTime": "2025-08-15T20:32:10.484642049Z",
"updateTime": "2025-08-15T20:32:10.484642049Z",
"status": "FAILURE",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "79697278e5b0c20329bbce01f415213462a04bab",
"ref": "refs/heads/main"
}
}
}
[20:32:12.748] Invocation finished in 3h57m55.792503883s with status FAILED