[00:00:07.145] New invocation is queued and will start shortly
[00:00:09.196] Starting the invocation (attempt 1)
[00:00:09.221] Ignoring gitiles_ref tag without the buildset tag
[00:00:09.221] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[00:00:09.221] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[00:00:09.221] Buildbucket request:
{
"requestId": "8864517445455049184",
"builder": {
"project": "fuchsia",
"bucket": "prod",
"builder": "rust-stage0-prod-polling-trigger"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8864517445455049184",
"job": "fuchsia/prod-rust-stage0-prod-polling-trigger",
"triggers": [
{
"cron": {
"generation": "2688"
},
"id": "cron:v1:2688"
}
]
}
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8864517445455049184"
},
{
"key": "scheduler_job_id",
"value": "fuchsia/prod-rust-stage0-prod-polling-trigger"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[00:00:09.613] Scheduled build:
{
"id": "8699087382547320145",
"builder": {
"project": "fuchsia",
"bucket": "prod",
"builder": "rust-stage0-prod-polling-trigger"
},
"createdBy": "project:fuchsia",
"createTime": "2025-11-05T00:00:09.298607522Z",
"updateTime": "2025-11-05T00:00:09.298607522Z",
"status": "SCHEDULED",
"input": {
}
}
[00:00:09.614] Task URL: https://cr-buildbucket.appspot.com/build/8699087382547320145
[00:00:09.614] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-rust-stage0-prod-polling-trigger:8864517445455049184:2:0) after 3m33s
[00:00:10.033] Received PubSub notification, asking Buildbucket for the build status
[00:00:10.202] Build status: SCHEDULED
[00:03:42.628] Handling timer "check-buildbucket-build-status" (fuchsia/prod-rust-stage0-prod-polling-trigger:8864517445455049184:2:0)
[00:03:42.675] Build status: SCHEDULED
[00:03:42.675] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-rust-stage0-prod-polling-trigger:8864517445455049184:4:0) after 8m28s
[00:12:10.738] Handling timer "check-buildbucket-build-status" (fuchsia/prod-rust-stage0-prod-polling-trigger:8864517445455049184:4:0)
[00:12:10.785] Build status: SCHEDULED
[00:12:10.785] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-rust-stage0-prod-polling-trigger:8864517445455049184:5:0) after 1m10s
[00:13:20.777] Handling timer "check-buildbucket-build-status" (fuchsia/prod-rust-stage0-prod-polling-trigger:8864517445455049184:5:0)
[00:13:20.803] Build status: SCHEDULED
[00:13:20.803] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-rust-stage0-prod-polling-trigger:8864517445455049184:6:0) after 7m32s
[00:20:52.824] Handling timer "check-buildbucket-build-status" (fuchsia/prod-rust-stage0-prod-polling-trigger:8864517445455049184:6:0)
[00:20:52.844] Build status: SCHEDULED
[00:20:52.844] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-rust-stage0-prod-polling-trigger:8864517445455049184:7:0) after 1m14s
[00:22:06.939] Handling timer "check-buildbucket-build-status" (fuchsia/prod-rust-stage0-prod-polling-trigger:8864517445455049184:7:0)
[00:22:06.967] Build status: SCHEDULED
[00:22:06.967] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-rust-stage0-prod-polling-trigger:8864517445455049184:8:0) after 2m23s
[00:24:29.987] Handling timer "check-buildbucket-build-status" (fuchsia/prod-rust-stage0-prod-polling-trigger:8864517445455049184:8:0)
[00:24:30.008] Build status: SCHEDULED
[00:24:30.008] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-rust-stage0-prod-polling-trigger:8864517445455049184:9:0) after 2m10s
[00:26:40.078] Handling timer "check-buildbucket-build-status" (fuchsia/prod-rust-stage0-prod-polling-trigger:8864517445455049184:9:0)
[00:26:40.102] Build status: SCHEDULED
[00:26:40.102] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-rust-stage0-prod-polling-trigger:8864517445455049184:10:0) after 1m21s
[00:28:01.172] Handling timer "check-buildbucket-build-status" (fuchsia/prod-rust-stage0-prod-polling-trigger:8864517445455049184:10:0)
[00:28:01.220] Build status: SCHEDULED
[00:28:01.221] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-rust-stage0-prod-polling-trigger:8864517445455049184:11:0) after 5m46s
[00:33:36.856] Received PubSub notification, asking Buildbucket for the build status
[00:33:36.882] Build status: STARTED
[00:33:47.368] Handling timer "check-buildbucket-build-status" (fuchsia/prod-rust-stage0-prod-polling-trigger:8864517445455049184:11:0)
[00:33:47.392] Build status: STARTED
[00:33:47.392] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-rust-stage0-prod-polling-trigger:8864517445455049184:13:0) after 2m43s
[00:36:30.403] Handling timer "check-buildbucket-build-status" (fuchsia/prod-rust-stage0-prod-polling-trigger:8864517445455049184:13:0)
[00:36:30.420] Build status: STARTED
[00:36:30.420] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-rust-stage0-prod-polling-trigger:8864517445455049184:14:0) after 1m29s
[00:37:59.449] Handling timer "check-buildbucket-build-status" (fuchsia/prod-rust-stage0-prod-polling-trigger:8864517445455049184:14:0)
[00:37:59.502] Build status: STARTED
[00:37:59.502] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-rust-stage0-prod-polling-trigger:8864517445455049184:15:0) after 8m2s
[00:46:01.546] Handling timer "check-buildbucket-build-status" (fuchsia/prod-rust-stage0-prod-polling-trigger:8864517445455049184:15:0)
[00:46:01.562] Build status: STARTED
[00:46:01.562] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-rust-stage0-prod-polling-trigger:8864517445455049184:16:0) after 3m54s
[00:49:55.603] Handling timer "check-buildbucket-build-status" (fuchsia/prod-rust-stage0-prod-polling-trigger:8864517445455049184:16:0)
[00:49:55.622] Build status: STARTED
[00:49:55.622] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-rust-stage0-prod-polling-trigger:8864517445455049184:17:0) after 5m23s
[00:55:18.727] Handling timer "check-buildbucket-build-status" (fuchsia/prod-rust-stage0-prod-polling-trigger:8864517445455049184:17:0)
[00:55:18.747] Build status: STARTED
[00:55:18.747] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-rust-stage0-prod-polling-trigger:8864517445455049184:18:0) after 4m57s
[01:00:15.757] Handling timer "check-buildbucket-build-status" (fuchsia/prod-rust-stage0-prod-polling-trigger:8864517445455049184:18:0)
[01:00:15.776] Build status: STARTED
[01:00:15.776] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-rust-stage0-prod-polling-trigger:8864517445455049184:19:0) after 6m20s
[01:06:35.839] Handling timer "check-buildbucket-build-status" (fuchsia/prod-rust-stage0-prod-polling-trigger:8864517445455049184:19:0)
[01:06:35.870] Build status: STARTED
[01:06:35.870] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-rust-stage0-prod-polling-trigger:8864517445455049184:20:0) after 9m49s
[01:16:24.883] Handling timer "check-buildbucket-build-status" (fuchsia/prod-rust-stage0-prod-polling-trigger:8864517445455049184:20:0)
[01:16:24.904] Build status: STARTED
[01:16:24.904] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-rust-stage0-prod-polling-trigger:8864517445455049184:21:0) after 7m38s
[01:24:02.974] Handling timer "check-buildbucket-build-status" (fuchsia/prod-rust-stage0-prod-polling-trigger:8864517445455049184:21:0)
[01:24:03.000] Build status: STARTED
[01:24:03.000] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-rust-stage0-prod-polling-trigger:8864517445455049184:22:0) after 1m21s
[01:25:23.991] Handling timer "check-buildbucket-build-status" (fuchsia/prod-rust-stage0-prod-polling-trigger:8864517445455049184:22:0)
[01:25:24.004] Build status: STARTED
[01:25:24.004] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-rust-stage0-prod-polling-trigger:8864517445455049184:23:0) after 9m1s
[01:34:25.018] Handling timer "check-buildbucket-build-status" (fuchsia/prod-rust-stage0-prod-polling-trigger:8864517445455049184:23:0)
[01:34:25.039] Build status: STARTED
[01:34:25.039] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-rust-stage0-prod-polling-trigger:8864517445455049184:24:0) after 1m57s
[01:36:22.054] Handling timer "check-buildbucket-build-status" (fuchsia/prod-rust-stage0-prod-polling-trigger:8864517445455049184:24:0)
[01:36:22.071] Build status: STARTED
[01:36:22.071] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-rust-stage0-prod-polling-trigger:8864517445455049184:25:0) after 2m24s
[01:38:46.171] Handling timer "check-buildbucket-build-status" (fuchsia/prod-rust-stage0-prod-polling-trigger:8864517445455049184:25:0)
[01:38:46.189] Build status: STARTED
[01:38:46.189] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-rust-stage0-prod-polling-trigger:8864517445455049184:26:0) after 8m41s
[01:47:27.191] Handling timer "check-buildbucket-build-status" (fuchsia/prod-rust-stage0-prod-polling-trigger:8864517445455049184:26:0)
[01:47:27.211] Build status: STARTED
[01:47:27.211] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-rust-stage0-prod-polling-trigger:8864517445455049184:27:0) after 9m6s
[01:56:33.372] Handling timer "check-buildbucket-build-status" (fuchsia/prod-rust-stage0-prod-polling-trigger:8864517445455049184:27:0)
[01:56:33.394] Build status: STARTED
[01:56:33.394] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-rust-stage0-prod-polling-trigger:8864517445455049184:28:0) after 6m57s
[02:03:30.460] Handling timer "check-buildbucket-build-status" (fuchsia/prod-rust-stage0-prod-polling-trigger:8864517445455049184:28:0)
[02:03:30.482] Build status: STARTED
[02:03:30.482] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-rust-stage0-prod-polling-trigger:8864517445455049184:29:0) after 1m27s
[02:04:57.517] Handling timer "check-buildbucket-build-status" (fuchsia/prod-rust-stage0-prod-polling-trigger:8864517445455049184:29:0)
[02:04:57.535] Build status: STARTED
[02:04:57.535] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-rust-stage0-prod-polling-trigger:8864517445455049184:30:0) after 2m3s
[02:07:00.650] Handling timer "check-buildbucket-build-status" (fuchsia/prod-rust-stage0-prod-polling-trigger:8864517445455049184:30:0)
[02:07:00.665] Build status: STARTED
[02:07:00.665] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-rust-stage0-prod-polling-trigger:8864517445455049184:31:0) after 5m37s
[02:12:37.762] Handling timer "check-buildbucket-build-status" (fuchsia/prod-rust-stage0-prod-polling-trigger:8864517445455049184:31:0)
[02:12:37.790] Build status: STARTED
[02:12:37.790] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-rust-stage0-prod-polling-trigger:8864517445455049184:32:0) after 2m18s
[02:14:55.807] Handling timer "check-buildbucket-build-status" (fuchsia/prod-rust-stage0-prod-polling-trigger:8864517445455049184:32:0)
[02:14:55.822] Build status: STARTED
[02:14:55.822] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-rust-stage0-prod-polling-trigger:8864517445455049184:33:0) after 1m41s
[02:16:36.859] Handling timer "check-buildbucket-build-status" (fuchsia/prod-rust-stage0-prod-polling-trigger:8864517445455049184:33:0)
[02:16:36.878] Build status: STARTED
[02:16:36.878] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-rust-stage0-prod-polling-trigger:8864517445455049184:34:0) after 6m30s
[02:23:06.994] Handling timer "check-buildbucket-build-status" (fuchsia/prod-rust-stage0-prod-polling-trigger:8864517445455049184:34:0)
[02:23:07.018] Build status: STARTED
[02:23:07.018] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-rust-stage0-prod-polling-trigger:8864517445455049184:35:0) after 9m28s
[02:32:35.057] Handling timer "check-buildbucket-build-status" (fuchsia/prod-rust-stage0-prod-polling-trigger:8864517445455049184:35:0)
[02:32:35.086] Build status: STARTED
[02:32:35.086] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-rust-stage0-prod-polling-trigger:8864517445455049184:36:0) after 9m53s
[02:42:28.204] Handling timer "check-buildbucket-build-status" (fuchsia/prod-rust-stage0-prod-polling-trigger:8864517445455049184:36:0)
[02:42:28.223] Build status: STARTED
[02:42:28.223] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-rust-stage0-prod-polling-trigger:8864517445455049184:37:0) after 9m43s
[02:52:11.343] Handling timer "check-buildbucket-build-status" (fuchsia/prod-rust-stage0-prod-polling-trigger:8864517445455049184:37:0)
[02:52:11.402] Build status: STARTED
[02:52:11.402] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-rust-stage0-prod-polling-trigger:8864517445455049184:38:0) after 4m2s
[02:56:13.475] Handling timer "check-buildbucket-build-status" (fuchsia/prod-rust-stage0-prod-polling-trigger:8864517445455049184:38:0)
[02:56:13.492] Build status: STARTED
[02:56:13.492] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-rust-stage0-prod-polling-trigger:8864517445455049184:39:0) after 9m25s
[03:05:38.508] Handling timer "check-buildbucket-build-status" (fuchsia/prod-rust-stage0-prod-polling-trigger:8864517445455049184:39:0)
[03:05:38.525] Build status: STARTED
[03:05:38.525] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-rust-stage0-prod-polling-trigger:8864517445455049184:40:0) after 9m57s
[03:15:35.543] Handling timer "check-buildbucket-build-status" (fuchsia/prod-rust-stage0-prod-polling-trigger:8864517445455049184:40:0)
[03:15:35.561] Build status: STARTED
[03:15:35.561] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-rust-stage0-prod-polling-trigger:8864517445455049184:41:0) after 2m53s
[03:18:28.625] Handling timer "check-buildbucket-build-status" (fuchsia/prod-rust-stage0-prod-polling-trigger:8864517445455049184:41:0)
[03:18:28.643] Build status: STARTED
[03:18:28.643] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-rust-stage0-prod-polling-trigger:8864517445455049184:42:0) after 5m27s
[03:23:55.660] Handling timer "check-buildbucket-build-status" (fuchsia/prod-rust-stage0-prod-polling-trigger:8864517445455049184:42:0)
[03:23:55.680] Build status: STARTED
[03:23:55.680] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-rust-stage0-prod-polling-trigger:8864517445455049184:43:0) after 7m8s
[03:31:03.698] Handling timer "check-buildbucket-build-status" (fuchsia/prod-rust-stage0-prod-polling-trigger:8864517445455049184:43:0)
[03:31:03.715] Build status: STARTED
[03:31:03.715] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-rust-stage0-prod-polling-trigger:8864517445455049184:44:0) after 2m56s
[03:33:59.731] Handling timer "check-buildbucket-build-status" (fuchsia/prod-rust-stage0-prod-polling-trigger:8864517445455049184:44:0)
[03:33:59.747] Build status: STARTED
[03:33:59.747] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-rust-stage0-prod-polling-trigger:8864517445455049184:45:0) after 5m34s
[03:39:33.805] Handling timer "check-buildbucket-build-status" (fuchsia/prod-rust-stage0-prod-polling-trigger:8864517445455049184:45:0)
[03:39:33.857] Build status: STARTED
[03:39:33.857] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-rust-stage0-prod-polling-trigger:8864517445455049184:46:0) after 3m45s
[03:43:18.874] Handling timer "check-buildbucket-build-status" (fuchsia/prod-rust-stage0-prod-polling-trigger:8864517445455049184:46:0)
[03:43:18.902] Build status: STARTED
[03:43:18.902] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-rust-stage0-prod-polling-trigger:8864517445455049184:47:0) after 6m44s
[03:50:02.960] Handling timer "check-buildbucket-build-status" (fuchsia/prod-rust-stage0-prod-polling-trigger:8864517445455049184:47:0)
[03:50:02.990] Build status: STARTED
[03:50:02.990] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-rust-stage0-prod-polling-trigger:8864517445455049184:48:0) after 9m19s
[03:59:22.003] Handling timer "check-buildbucket-build-status" (fuchsia/prod-rust-stage0-prod-polling-trigger:8864517445455049184:48:0)
[03:59:22.020] Build status: STARTED
[03:59:22.020] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-rust-stage0-prod-polling-trigger:8864517445455049184:49:0) after 8m12s
[04:07:34.038] Handling timer "check-buildbucket-build-status" (fuchsia/prod-rust-stage0-prod-polling-trigger:8864517445455049184:49:0)
[04:07:34.053] Build status: STARTED
[04:07:34.054] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-rust-stage0-prod-polling-trigger:8864517445455049184:50:0) after 6m19s
[04:13:53.077] Handling timer "check-buildbucket-build-status" (fuchsia/prod-rust-stage0-prod-polling-trigger:8864517445455049184:50:0)
[04:13:53.127] Build status: STARTED
[04:13:53.127] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-rust-stage0-prod-polling-trigger:8864517445455049184:51:0) after 1m1s
[04:14:54.188] Handling timer "check-buildbucket-build-status" (fuchsia/prod-rust-stage0-prod-polling-trigger:8864517445455049184:51:0)
[04:14:54.252] Build status: STARTED
[04:14:54.252] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-rust-stage0-prod-polling-trigger:8864517445455049184:52:0) after 7m7s
[04:22:01.271] Handling timer "check-buildbucket-build-status" (fuchsia/prod-rust-stage0-prod-polling-trigger:8864517445455049184:52:0)
[04:22:01.288] Build status: STARTED
[04:22:01.288] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-rust-stage0-prod-polling-trigger:8864517445455049184:53:0) after 5m44s
[04:27:45.305] Handling timer "check-buildbucket-build-status" (fuchsia/prod-rust-stage0-prod-polling-trigger:8864517445455049184:53:0)
[04:27:45.455] Build status: STARTED
[04:27:45.455] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-rust-stage0-prod-polling-trigger:8864517445455049184:54:0) after 9m10s
[04:36:55.473] Handling timer "check-buildbucket-build-status" (fuchsia/prod-rust-stage0-prod-polling-trigger:8864517445455049184:54:0)
[04:36:55.493] Build status: STARTED
[04:36:55.493] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-rust-stage0-prod-polling-trigger:8864517445455049184:55:0) after 4m4s
[04:40:59.509] Handling timer "check-buildbucket-build-status" (fuchsia/prod-rust-stage0-prod-polling-trigger:8864517445455049184:55:0)
[04:40:59.523] Build status: STARTED
[04:40:59.523] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-rust-stage0-prod-polling-trigger:8864517445455049184:56:0) after 6m43s
[04:47:42.561] Handling timer "check-buildbucket-build-status" (fuchsia/prod-rust-stage0-prod-polling-trigger:8864517445455049184:56:0)
[04:47:42.641] Build status: STARTED
[04:47:42.641] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-rust-stage0-prod-polling-trigger:8864517445455049184:57:0) after 8m59s
[04:54:45.011] Received PubSub notification, asking Buildbucket for the build status
[04:54:45.036] Build:
{
"id": "8699087382547320145",
"builder": {
"project": "fuchsia",
"bucket": "prod",
"builder": "rust-stage0-prod-polling-trigger"
},
"createdBy": "project:fuchsia",
"createTime": "2025-11-05T00:00:09.298607522Z",
"startTime": "2025-11-05T00:33:36.647379294Z",
"endTime": "2025-11-05T04:54:44.752810651Z",
"updateTime": "2025-11-05T04:54:44.752810651Z",
"status": "FAILURE",
"input": {
}
}
[04:54:45.036] Invocation finished in 4h54m37.894299462s with status FAILED