[01:10:08.817] New invocation is queued and will start shortly
[01:10:09.925] Starting the invocation (attempt 1)
[01:10:09.954] Popped buildset tag "buildset:commit/gitiles/fuchsia.googlesource.com/integration/+/2a7f4a6fc731ca8f877a7fbdf35d240b0fad2cc3"
[01:10:09.955] Popped gitiles commit info from properties and tags
[01:10:09.955] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[01:10:09.955] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[01:10:09.955] Buildbucket request:
{
"requestId": "8853550806748160208",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "sdk-core-linux-rstest"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8853550806748160208",
"job": "fuchsia/global.ci-sdk-core-linux-rstest-e5a0ec7f",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "cbc63dd1bae3181e42c43c330193f08438d83551"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@cbc63dd1bae3181e42c43c330193f08438d83551",
"title": "cbc63dd1bae3181e42c43c330193f08438d83551",
"url": "https://fuchsia.googlesource.com/integration/+/cbc63dd1bae3181e42c43c330193f08438d83551"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "8bc9b5928ce3c686a344b4f03b7681e16975dd4d"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@8bc9b5928ce3c686a344b4f03b7681e16975dd4d",
"title": "8bc9b5928ce3c686a344b4f03b7681e16975dd4d",
"url": "https://fuchsia.googlesource.com/integration/+/8bc9b5928ce3c686a344b4f03b7681e16975dd4d"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "5ae642f75e1b1174f417c59ea3f5f050ea81e2e8"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@5ae642f75e1b1174f417c59ea3f5f050ea81e2e8",
"title": "5ae642f75e1b1174f417c59ea3f5f050ea81e2e8",
"url": "https://fuchsia.googlesource.com/integration/+/5ae642f75e1b1174f417c59ea3f5f050ea81e2e8"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "3b2373faec60a553ae538f81cee1ee9b776fc167"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@3b2373faec60a553ae538f81cee1ee9b776fc167",
"title": "3b2373faec60a553ae538f81cee1ee9b776fc167",
"url": "https://fuchsia.googlesource.com/integration/+/3b2373faec60a553ae538f81cee1ee9b776fc167"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "5084794de594c1e9141c5dace9b61bfad01542fb"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@5084794de594c1e9141c5dace9b61bfad01542fb",
"title": "5084794de594c1e9141c5dace9b61bfad01542fb",
"url": "https://fuchsia.googlesource.com/integration/+/5084794de594c1e9141c5dace9b61bfad01542fb"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "2a7f4a6fc731ca8f877a7fbdf35d240b0fad2cc3"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@2a7f4a6fc731ca8f877a7fbdf35d240b0fad2cc3",
"title": "2a7f4a6fc731ca8f877a7fbdf35d240b0fad2cc3",
"url": "https://fuchsia.googlesource.com/integration/+/2a7f4a6fc731ca8f877a7fbdf35d240b0fad2cc3"
}
]
}
},
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "2a7f4a6fc731ca8f877a7fbdf35d240b0fad2cc3",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8853550806748160208"
},
{
"key": "scheduler_job_id",
"value": "fuchsia/global.ci-sdk-core-linux-rstest-e5a0ec7f"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[01:10:10.329] Scheduled build:
{
"id": "8688120744838233809",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "sdk-core-linux-rstest"
},
"createdBy": "project:fuchsia",
"createTime": "2026-03-06T01:10:10.019271735Z",
"updateTime": "2026-03-06T01:10:10.019271735Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "2a7f4a6fc731ca8f877a7fbdf35d240b0fad2cc3",
"ref": "refs/heads/main"
}
}
}
[01:10:10.329] Task URL: https://cr-buildbucket.appspot.com/build/8688120744838233809
[01:10:10.329] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-sdk-core-linux-rstest-e5a0ec7f:8853550806748160208:2:0) after 8m8s
[01:10:11.281] Received PubSub notification, asking Buildbucket for the build status
[01:10:11.390] Build status: SCHEDULED
[01:10:15.466] Received PubSub notification, asking Buildbucket for the build status
[01:10:15.483] Build status: STARTED
[01:18:18.340] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-sdk-core-linux-rstest-e5a0ec7f:8853550806748160208:2:0)
[01:18:18.352] Build status: STARTED
[01:18:18.352] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-sdk-core-linux-rstest-e5a0ec7f:8853550806748160208:5:0) after 6m55s
[01:25:13.365] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-sdk-core-linux-rstest-e5a0ec7f:8853550806748160208:5:0)
[01:25:13.382] Build status: STARTED
[01:25:13.382] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-sdk-core-linux-rstest-e5a0ec7f:8853550806748160208:6:0) after 8m17s
[01:33:30.546] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-sdk-core-linux-rstest-e5a0ec7f:8853550806748160208:6:0)
[01:33:30.680] Build status: STARTED
[01:33:30.680] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-sdk-core-linux-rstest-e5a0ec7f:8853550806748160208:7:0) after 9m23s
[01:42:53.888] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-sdk-core-linux-rstest-e5a0ec7f:8853550806748160208:7:0)
[01:42:53.906] Build status: STARTED
[01:42:53.906] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-sdk-core-linux-rstest-e5a0ec7f:8853550806748160208:8:0) after 9m56s
[01:52:49.927] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-sdk-core-linux-rstest-e5a0ec7f:8853550806748160208:8:0)
[01:52:49.942] Build status: STARTED
[01:52:49.942] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-sdk-core-linux-rstest-e5a0ec7f:8853550806748160208:9:0) after 3m44s
[01:56:33.952] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-sdk-core-linux-rstest-e5a0ec7f:8853550806748160208:9:0)
[01:56:33.978] Build status: STARTED
[01:56:33.978] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-sdk-core-linux-rstest-e5a0ec7f:8853550806748160208:10:0) after 6m49s
[02:03:23.086] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-sdk-core-linux-rstest-e5a0ec7f:8853550806748160208:10:0)
[02:03:23.102] Build status: STARTED
[02:03:23.103] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-sdk-core-linux-rstest-e5a0ec7f:8853550806748160208:11:0) after 3m16s
[02:06:39.198] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-sdk-core-linux-rstest-e5a0ec7f:8853550806748160208:11:0)
[02:06:39.221] Build status: STARTED
[02:06:39.221] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-sdk-core-linux-rstest-e5a0ec7f:8853550806748160208:12:0) after 7m53s
[02:14:32.293] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-sdk-core-linux-rstest-e5a0ec7f:8853550806748160208:12:0)
[02:14:32.350] Build status: STARTED
[02:14:32.350] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-sdk-core-linux-rstest-e5a0ec7f:8853550806748160208:13:0) after 5m48s
[02:20:20.402] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-sdk-core-linux-rstest-e5a0ec7f:8853550806748160208:13:0)
[02:20:20.473] Build status: STARTED
[02:20:20.473] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-sdk-core-linux-rstest-e5a0ec7f:8853550806748160208:14:0) after 9m5s
[02:29:25.538] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-sdk-core-linux-rstest-e5a0ec7f:8853550806748160208:14:0)
[02:29:25.554] Build status: STARTED
[02:29:25.554] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-sdk-core-linux-rstest-e5a0ec7f:8853550806748160208:15:0) after 9m34s
[02:38:59.543] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-sdk-core-linux-rstest-e5a0ec7f:8853550806748160208:15:0)
[02:38:59.563] Build status: STARTED
[02:38:59.564] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-sdk-core-linux-rstest-e5a0ec7f:8853550806748160208:16:0) after 1m45s
[02:40:44.580] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-sdk-core-linux-rstest-e5a0ec7f:8853550806748160208:16:0)
[02:40:44.600] Build status: STARTED
[02:40:44.600] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-sdk-core-linux-rstest-e5a0ec7f:8853550806748160208:17:0) after 6m5s
[02:46:49.614] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-sdk-core-linux-rstest-e5a0ec7f:8853550806748160208:17:0)
[02:46:49.627] Build status: STARTED
[02:46:49.627] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-sdk-core-linux-rstest-e5a0ec7f:8853550806748160208:18:0) after 1m59s
[02:48:48.691] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-sdk-core-linux-rstest-e5a0ec7f:8853550806748160208:18:0)
[02:48:48.711] Build status: STARTED
[02:48:48.711] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-sdk-core-linux-rstest-e5a0ec7f:8853550806748160208:19:0) after 9m26s
[02:58:14.863] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-sdk-core-linux-rstest-e5a0ec7f:8853550806748160208:19:0)
[02:58:14.881] Build status: STARTED
[02:58:14.881] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-sdk-core-linux-rstest-e5a0ec7f:8853550806748160208:20:0) after 3m15s
[03:01:29.945] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-sdk-core-linux-rstest-e5a0ec7f:8853550806748160208:20:0)
[03:01:29.971] Build status: STARTED
[03:01:29.971] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-sdk-core-linux-rstest-e5a0ec7f:8853550806748160208:21:0) after 6m1s
[03:07:31.038] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-sdk-core-linux-rstest-e5a0ec7f:8853550806748160208:21:0)
[03:07:31.057] Build status: STARTED
[03:07:31.057] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-sdk-core-linux-rstest-e5a0ec7f:8853550806748160208:22:0) after 1m12s
[03:08:43.506] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-sdk-core-linux-rstest-e5a0ec7f:8853550806748160208:22:0)
[03:08:43.524] Build status: STARTED
[03:08:43.524] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-sdk-core-linux-rstest-e5a0ec7f:8853550806748160208:23:0) after 7m53s
[03:16:36.542] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-sdk-core-linux-rstest-e5a0ec7f:8853550806748160208:23:0)
[03:16:36.578] Build status: STARTED
[03:16:36.578] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-sdk-core-linux-rstest-e5a0ec7f:8853550806748160208:24:0) after 8m50s
[03:25:26.659] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-sdk-core-linux-rstest-e5a0ec7f:8853550806748160208:24:0)
[03:25:26.675] Build status: STARTED
[03:25:26.675] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-sdk-core-linux-rstest-e5a0ec7f:8853550806748160208:25:0) after 8m8s
[03:33:34.690] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-sdk-core-linux-rstest-e5a0ec7f:8853550806748160208:25:0)
[03:33:34.705] Build status: STARTED
[03:33:34.705] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-sdk-core-linux-rstest-e5a0ec7f:8853550806748160208:26:0) after 1m31s
[03:35:05.727] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-sdk-core-linux-rstest-e5a0ec7f:8853550806748160208:26:0)
[03:35:05.748] Build status: STARTED
[03:35:05.748] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-sdk-core-linux-rstest-e5a0ec7f:8853550806748160208:27:0) after 3m28s
[03:38:33.856] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-sdk-core-linux-rstest-e5a0ec7f:8853550806748160208:27:0)
[03:38:33.870] Build status: STARTED
[03:38:33.870] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-sdk-core-linux-rstest-e5a0ec7f:8853550806748160208:28:0) after 1m37s
[03:40:10.939] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-sdk-core-linux-rstest-e5a0ec7f:8853550806748160208:28:0)
[03:40:10.975] Build status: STARTED
[03:40:10.975] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-sdk-core-linux-rstest-e5a0ec7f:8853550806748160208:29:0) after 8m4s
[03:48:14.988] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-sdk-core-linux-rstest-e5a0ec7f:8853550806748160208:29:0)
[03:48:15.052] Build status: STARTED
[03:48:15.052] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-sdk-core-linux-rstest-e5a0ec7f:8853550806748160208:30:0) after 4m13s
[03:52:28.039] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-sdk-core-linux-rstest-e5a0ec7f:8853550806748160208:30:0)
[03:52:28.054] Build status: STARTED
[03:52:28.054] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-sdk-core-linux-rstest-e5a0ec7f:8853550806748160208:31:0) after 3m47s
[03:56:15.073] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-sdk-core-linux-rstest-e5a0ec7f:8853550806748160208:31:0)
[03:56:15.092] Build status: STARTED
[03:56:15.093] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-sdk-core-linux-rstest-e5a0ec7f:8853550806748160208:32:0) after 2m47s
[03:59:02.106] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-sdk-core-linux-rstest-e5a0ec7f:8853550806748160208:32:0)
[03:59:02.124] Build status: STARTED
[03:59:02.124] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-sdk-core-linux-rstest-e5a0ec7f:8853550806748160208:33:0) after 3m13s
[04:02:15.091] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-sdk-core-linux-rstest-e5a0ec7f:8853550806748160208:33:0)
[04:02:15.106] Build status: STARTED
[04:02:15.106] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-sdk-core-linux-rstest-e5a0ec7f:8853550806748160208:34:0) after 2m53s
[04:05:08.275] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-sdk-core-linux-rstest-e5a0ec7f:8853550806748160208:34:0)
[04:05:08.358] Build status: STARTED
[04:05:08.358] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-sdk-core-linux-rstest-e5a0ec7f:8853550806748160208:35:0) after 9m51s
[04:14:59.419] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-sdk-core-linux-rstest-e5a0ec7f:8853550806748160208:35:0)
[04:14:59.435] Build status: STARTED
[04:14:59.435] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-sdk-core-linux-rstest-e5a0ec7f:8853550806748160208:36:0) after 9m42s
[04:19:59.003] Received PubSub notification, asking Buildbucket for the build status
[04:19:59.044] Build:
{
"id": "8688120744838233809",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "sdk-core-linux-rstest"
},
"createdBy": "project:fuchsia",
"createTime": "2026-03-06T01:10:10.019271735Z",
"startTime": "2026-03-06T01:10:15.284150167Z",
"endTime": "2026-03-06T04:19:56.883794406Z",
"updateTime": "2026-03-06T04:19:56.883794406Z",
"status": "FAILURE",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "2a7f4a6fc731ca8f877a7fbdf35d240b0fad2cc3",
"ref": "refs/heads/main"
}
}
}
[04:19:59.044] Invocation finished in 3h9m50.229952654s with status FAILED