[18:34:04.278] New invocation is queued and will start shortly
[18:34:05.443] Starting the invocation (attempt 1)
[18:34:05.470] Popped buildset tag "buildset:commit/gitiles/fuchsia.googlesource.com/integration/+/bf242cd8f00e7f4b36e30dd02d0cc1b9f78dcf77"
[18:34:05.470] Popped gitiles commit info from properties and tags
[18:34:05.470] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[18:34:05.470] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[18:34:05.471] Buildbucket request:
{
"requestId": "8880664218629534848",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.x64-full_determinism"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8880664218629534848",
"job": "fuchsia/global.ci-core.x64-full_determinism-e5a0ec7f",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "bf242cd8f00e7f4b36e30dd02d0cc1b9f78dcf77"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@bf242cd8f00e7f4b36e30dd02d0cc1b9f78dcf77",
"title": "bf242cd8f00e7f4b36e30dd02d0cc1b9f78dcf77",
"url": "https://fuchsia.googlesource.com/integration/+/bf242cd8f00e7f4b36e30dd02d0cc1b9f78dcf77"
}
]
}
},
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "bf242cd8f00e7f4b36e30dd02d0cc1b9f78dcf77",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8880664218629534848"
},
{
"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"
}
}
[18:34:05.844] Scheduled build:
{
"id": "8715234156659152817",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.x64-full_determinism"
},
"createdBy": "project:fuchsia",
"createTime": "2025-05-10T18:34:05.535463501Z",
"updateTime": "2025-05-10T18:34:05.535463501Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "bf242cd8f00e7f4b36e30dd02d0cc1b9f78dcf77",
"ref": "refs/heads/main"
}
},
"canary": true
}
[18:34:05.844] Task URL: https://cr-buildbucket.appspot.com/build/8715234156659152817
[18:34:05.844] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-full_determinism-e5a0ec7f:8880664218629534848:2:0) after 1m30s
[18:34:29.222] Received PubSub notification, asking Buildbucket for the build status
[18:34:29.284] Build status: STARTED
[18:34:42.771] Received PubSub notification, asking Buildbucket for the build status
[18:34:42.785] Build status: STARTED
[18:35:35.857] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-full_determinism-e5a0ec7f:8880664218629534848:2:0)
[18:35:35.873] Build status: STARTED
[18:35:35.873] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-full_determinism-e5a0ec7f:8880664218629534848:5:0) after 3m13s
[18:38:48.953] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-full_determinism-e5a0ec7f:8880664218629534848:5:0)
[18:38:48.975] Build status: STARTED
[18:38:48.975] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-full_determinism-e5a0ec7f:8880664218629534848:6:0) after 9m54s
[18:48:43.274] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-full_determinism-e5a0ec7f:8880664218629534848:6:0)
[18:48:43.369] Build status: STARTED
[18:48:43.369] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-full_determinism-e5a0ec7f:8880664218629534848:7:0) after 6m10s
[18:54:53.809] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-full_determinism-e5a0ec7f:8880664218629534848:7:0)
[18:54:53.835] Build status: STARTED
[18:54:53.835] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-full_determinism-e5a0ec7f:8880664218629534848:8:0) after 6m25s
[19:01:19.099] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-full_determinism-e5a0ec7f:8880664218629534848:8:0)
[19:01:19.121] Build status: STARTED
[19:01:19.121] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-full_determinism-e5a0ec7f:8880664218629534848:9:0) after 6m37s
[19:07:56.791] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-full_determinism-e5a0ec7f:8880664218629534848:9:0)
[19:07:56.820] Build status: STARTED
[19:07:56.820] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-full_determinism-e5a0ec7f:8880664218629534848:10:0) after 5m41s
[19:13:37.813] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-full_determinism-e5a0ec7f:8880664218629534848:10:0)
[19:13:37.829] Build status: STARTED
[19:13:37.829] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-full_determinism-e5a0ec7f:8880664218629534848:11:0) after 9m59s
[19:23:36.946] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-full_determinism-e5a0ec7f:8880664218629534848:11:0)
[19:23:37.032] Build status: STARTED
[19:23:37.032] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-full_determinism-e5a0ec7f:8880664218629534848:12:0) after 3m7s
[19:26:44.150] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-full_determinism-e5a0ec7f:8880664218629534848:12:0)
[19:26:44.171] Build status: STARTED
[19:26:44.172] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-full_determinism-e5a0ec7f:8880664218629534848:13:0) after 1m44s
[19:28:28.195] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-full_determinism-e5a0ec7f:8880664218629534848:13:0)
[19:28:28.212] Build status: STARTED
[19:28:28.212] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-full_determinism-e5a0ec7f:8880664218629534848:14:0) after 6m29s
[19:34:57.300] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-full_determinism-e5a0ec7f:8880664218629534848:14:0)
[19:34:57.322] Build status: STARTED
[19:34:57.322] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-full_determinism-e5a0ec7f:8880664218629534848:15:0) after 1m28s
[19:36:25.521] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-full_determinism-e5a0ec7f:8880664218629534848:15:0)
[19:36:25.557] Build status: STARTED
[19:36:25.558] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-full_determinism-e5a0ec7f:8880664218629534848:16:0) after 5m33s
[19:41:58.570] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-full_determinism-e5a0ec7f:8880664218629534848:16:0)
[19:41:58.600] Build status: STARTED
[19:41:58.600] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-full_determinism-e5a0ec7f:8880664218629534848:17:0) after 7m6s
[19:49:04.933] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-full_determinism-e5a0ec7f:8880664218629534848:17:0)
[19:49:04.951] Build status: STARTED
[19:49:04.951] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-full_determinism-e5a0ec7f:8880664218629534848:18:0) after 2m38s
[19:51:43.033] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-full_determinism-e5a0ec7f:8880664218629534848:18:0)
[19:51:43.055] Build status: STARTED
[19:51:43.055] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-full_determinism-e5a0ec7f:8880664218629534848:19:0) after 7m34s
[19:59:17.046] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-full_determinism-e5a0ec7f:8880664218629534848:19:0)
[19:59:17.066] Build status: STARTED
[19:59:17.066] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-full_determinism-e5a0ec7f:8880664218629534848:20:0) after 1m15s
[20:00:32.158] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-full_determinism-e5a0ec7f:8880664218629534848:20:0)
[20:00:32.245] Build status: STARTED
[20:00:32.245] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-full_determinism-e5a0ec7f:8880664218629534848:21:0) after 3m51s
[20:04:23.458] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-full_determinism-e5a0ec7f:8880664218629534848:21:0)
[20:04:23.486] Build status: STARTED
[20:04:23.487] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-full_determinism-e5a0ec7f:8880664218629534848:22:0) after 8m48s
[20:13:11.568] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-full_determinism-e5a0ec7f:8880664218629534848:22:0)
[20:13:11.588] Build status: STARTED
[20:13:11.588] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-full_determinism-e5a0ec7f:8880664218629534848:23:0) after 3m31s
[20:16:42.676] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-full_determinism-e5a0ec7f:8880664218629534848:23:0)
[20:16:42.749] Build status: STARTED
[20:16:42.749] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-full_determinism-e5a0ec7f:8880664218629534848:24:0) after 8m52s
[20:25:34.781] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-full_determinism-e5a0ec7f:8880664218629534848:24:0)
[20:25:34.942] Build status: STARTED
[20:25:34.942] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-full_determinism-e5a0ec7f:8880664218629534848:25:0) after 2m4s
[20:27:39.170] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-full_determinism-e5a0ec7f:8880664218629534848:25:0)
[20:27:39.245] Build status: STARTED
[20:27:39.245] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-full_determinism-e5a0ec7f:8880664218629534848:26:0) after 7m8s
[20:34:47.269] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-full_determinism-e5a0ec7f:8880664218629534848:26:0)
[20:34:47.291] Build status: STARTED
[20:34:47.291] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-full_determinism-e5a0ec7f:8880664218629534848:27:0) after 3m36s
[20:38:23.406] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-full_determinism-e5a0ec7f:8880664218629534848:27:0)
[20:38:23.427] Build status: STARTED
[20:38:23.427] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-full_determinism-e5a0ec7f:8880664218629534848:28:0) after 9m8s
[20:47:31.452] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-full_determinism-e5a0ec7f:8880664218629534848:28:0)
[20:47:31.471] Build status: STARTED
[20:47:31.471] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-full_determinism-e5a0ec7f:8880664218629534848:29:0) after 9m32s
[20:57:03.538] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-full_determinism-e5a0ec7f:8880664218629534848:29:0)
[20:57:03.560] Build status: STARTED
[20:57:03.560] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-full_determinism-e5a0ec7f:8880664218629534848:30:0) after 8m22s
[21:05:25.593] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-full_determinism-e5a0ec7f:8880664218629534848:30:0)
[21:05:25.614] Build status: STARTED
[21:05:25.614] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-full_determinism-e5a0ec7f:8880664218629534848:31:0) after 2m13s
[21:07:38.721] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-full_determinism-e5a0ec7f:8880664218629534848:31:0)
[21:07:38.791] Build status: STARTED
[21:07:38.791] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-full_determinism-e5a0ec7f:8880664218629534848:32:0) after 5m31s
[21:13:09.964] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-full_determinism-e5a0ec7f:8880664218629534848:32:0)
[21:13:09.988] Build status: STARTED
[21:13:09.988] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-full_determinism-e5a0ec7f:8880664218629534848:33:0) after 8m50s
[21:22:00.031] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-full_determinism-e5a0ec7f:8880664218629534848:33:0)
[21:22:00.050] Build status: STARTED
[21:22:00.051] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-full_determinism-e5a0ec7f:8880664218629534848:34:0) after 8m28s
[21:30:28.051] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-full_determinism-e5a0ec7f:8880664218629534848:34:0)
[21:30:28.071] Build status: STARTED
[21:30:28.071] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-full_determinism-e5a0ec7f:8880664218629534848:35:0) after 7m0s
[21:34:30.755] Received PubSub notification, asking Buildbucket for the build status
[21:34:30.829] Build:
{
"id": "8715234156659152817",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.x64-full_determinism"
},
"createdBy": "project:fuchsia",
"createTime": "2025-05-10T18:34:05.535463501Z",
"startTime": "2025-05-10T18:34:13.392262817Z",
"endTime": "2025-05-10T21:34:30.314068343Z",
"updateTime": "2025-05-10T21:34:30.314068343Z",
"status": "FAILURE",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "bf242cd8f00e7f4b36e30dd02d0cc1b9f78dcf77",
"ref": "refs/heads/main"
}
},
"canary": true
}
[21:34:30.829] Invocation finished in 3h0m26.556276293s with status FAILED