[00:54:31.539] New invocation is queued and will start shortly
[00:54:32.850] Starting the invocation (attempt 1)
[00:54:32.888] Popped buildset tag "buildset:commit/gitiles/fuchsia.googlesource.com/integration/+/b6fa92cf8006bbc6dd01d1cffd0615de85c38789"
[00:54:32.888] Popped gitiles commit info from properties and tags
[00:54:32.888] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[00:54:32.889] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[00:54:32.889] Buildbucket request:
{
"requestId": "8861433725644438640",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "minimal.riscv64-balanced"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8861433725644438640",
"job": "fuchsia/global.ci-minimal.riscv64-balanced-e5a0ec7f",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "957d96372b550cba216e6992c5a0103ae9e77e0d"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@957d96372b550cba216e6992c5a0103ae9e77e0d",
"title": "957d96372b550cba216e6992c5a0103ae9e77e0d",
"url": "https://fuchsia.googlesource.com/integration/+/957d96372b550cba216e6992c5a0103ae9e77e0d"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "f9f15e25f8c18e934f3df69985a9c16c20274a49"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@f9f15e25f8c18e934f3df69985a9c16c20274a49",
"title": "f9f15e25f8c18e934f3df69985a9c16c20274a49",
"url": "https://fuchsia.googlesource.com/integration/+/f9f15e25f8c18e934f3df69985a9c16c20274a49"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "0a3f6c657b270cd3b37296f88495852f589068a7"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@0a3f6c657b270cd3b37296f88495852f589068a7",
"title": "0a3f6c657b270cd3b37296f88495852f589068a7",
"url": "https://fuchsia.googlesource.com/integration/+/0a3f6c657b270cd3b37296f88495852f589068a7"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "ca1a0f1317b9414b723c3265d778a5359ecf7645"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@ca1a0f1317b9414b723c3265d778a5359ecf7645",
"title": "ca1a0f1317b9414b723c3265d778a5359ecf7645",
"url": "https://fuchsia.googlesource.com/integration/+/ca1a0f1317b9414b723c3265d778a5359ecf7645"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "9703ce5a7d1771bfb670f70932ac05ebd951716c"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@9703ce5a7d1771bfb670f70932ac05ebd951716c",
"title": "9703ce5a7d1771bfb670f70932ac05ebd951716c",
"url": "https://fuchsia.googlesource.com/integration/+/9703ce5a7d1771bfb670f70932ac05ebd951716c"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "5247152aa7f52e121ca58b766e52977af04bb0e6"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@5247152aa7f52e121ca58b766e52977af04bb0e6",
"title": "5247152aa7f52e121ca58b766e52977af04bb0e6",
"url": "https://fuchsia.googlesource.com/integration/+/5247152aa7f52e121ca58b766e52977af04bb0e6"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "0a74fc434d9d7af89b2cd42278b3957b46d10ce8"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@0a74fc434d9d7af89b2cd42278b3957b46d10ce8",
"title": "0a74fc434d9d7af89b2cd42278b3957b46d10ce8",
"url": "https://fuchsia.googlesource.com/integration/+/0a74fc434d9d7af89b2cd42278b3957b46d10ce8"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "de2e01263df69084b845a33ac8c3956201873f8c"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@de2e01263df69084b845a33ac8c3956201873f8c",
"title": "de2e01263df69084b845a33ac8c3956201873f8c",
"url": "https://fuchsia.googlesource.com/integration/+/de2e01263df69084b845a33ac8c3956201873f8c"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "b6fa92cf8006bbc6dd01d1cffd0615de85c38789"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@b6fa92cf8006bbc6dd01d1cffd0615de85c38789",
"title": "b6fa92cf8006bbc6dd01d1cffd0615de85c38789",
"url": "https://fuchsia.googlesource.com/integration/+/b6fa92cf8006bbc6dd01d1cffd0615de85c38789"
}
]
}
},
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "b6fa92cf8006bbc6dd01d1cffd0615de85c38789",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8861433725644438640"
},
{
"key": "scheduler_job_id",
"value": "fuchsia/global.ci-minimal.riscv64-balanced-e5a0ec7f"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[00:54:33.291] Scheduled build:
{
"id": "8696003663506054593",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "minimal.riscv64-balanced"
},
"createdBy": "project:fuchsia",
"createTime": "2025-12-09T00:54:32.947676013Z",
"updateTime": "2025-12-09T00:54:32.947676013Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "b6fa92cf8006bbc6dd01d1cffd0615de85c38789",
"ref": "refs/heads/main"
}
}
}
[00:54:33.291] Task URL: https://cr-buildbucket.appspot.com/build/8696003663506054593
[00:54:33.291] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-minimal.riscv64-balanced-e5a0ec7f:8861433725644438640:2:0) after 2m50s
[00:54:34.163] Received PubSub notification, asking Buildbucket for the build status
[00:54:34.177] Build status: SCHEDULED
[00:55:31.886] Received PubSub notification, asking Buildbucket for the build status
[00:55:31.937] Build status: STARTED
[00:57:23.458] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-minimal.riscv64-balanced-e5a0ec7f:8861433725644438640:2:0)
[00:57:23.470] Build status: STARTED
[00:57:23.470] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-minimal.riscv64-balanced-e5a0ec7f:8861433725644438640:5:0) after 6m50s
[01:04:13.468] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-minimal.riscv64-balanced-e5a0ec7f:8861433725644438640:5:0)
[01:04:13.481] Build status: STARTED
[01:04:13.481] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-minimal.riscv64-balanced-e5a0ec7f:8861433725644438640:6:0) after 7m9s
[01:11:22.469] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-minimal.riscv64-balanced-e5a0ec7f:8861433725644438640:6:0)
[01:11:22.549] Build status: STARTED
[01:11:22.549] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-minimal.riscv64-balanced-e5a0ec7f:8861433725644438640:7:0) after 3m54s
[01:15:16.554] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-minimal.riscv64-balanced-e5a0ec7f:8861433725644438640:7:0)
[01:15:16.575] Build status: STARTED
[01:15:16.575] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-minimal.riscv64-balanced-e5a0ec7f:8861433725644438640:8:0) after 3m9s
[01:18:25.587] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-minimal.riscv64-balanced-e5a0ec7f:8861433725644438640:8:0)
[01:18:25.610] Build status: STARTED
[01:18:25.610] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-minimal.riscv64-balanced-e5a0ec7f:8861433725644438640:9:0) after 4m43s
[01:23:08.671] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-minimal.riscv64-balanced-e5a0ec7f:8861433725644438640:9:0)
[01:23:08.697] Build status: STARTED
[01:23:08.697] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-minimal.riscv64-balanced-e5a0ec7f:8861433725644438640:10:0) after 6m35s
[01:29:43.814] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-minimal.riscv64-balanced-e5a0ec7f:8861433725644438640:10:0)
[01:29:43.834] Build status: STARTED
[01:29:43.834] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-minimal.riscv64-balanced-e5a0ec7f:8861433725644438640:11:0) after 3m41s
[01:33:24.850] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-minimal.riscv64-balanced-e5a0ec7f:8861433725644438640:11:0)
[01:33:24.957] Build status: STARTED
[01:33:24.957] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-minimal.riscv64-balanced-e5a0ec7f:8861433725644438640:12:0) after 7m9s
[01:40:34.028] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-minimal.riscv64-balanced-e5a0ec7f:8861433725644438640:12:0)
[01:40:34.048] Build status: STARTED
[01:40:34.048] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-minimal.riscv64-balanced-e5a0ec7f:8861433725644438640:13:0) after 2m59s
[01:43:33.020] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-minimal.riscv64-balanced-e5a0ec7f:8861433725644438640:13:0)
[01:43:33.045] Build status: STARTED
[01:43:33.045] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-minimal.riscv64-balanced-e5a0ec7f:8861433725644438640:14:0) after 7m54s
[01:51:27.134] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-minimal.riscv64-balanced-e5a0ec7f:8861433725644438640:14:0)
[01:51:27.174] Build status: STARTED
[01:51:27.174] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-minimal.riscv64-balanced-e5a0ec7f:8861433725644438640:15:0) after 2m6s
[01:53:33.252] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-minimal.riscv64-balanced-e5a0ec7f:8861433725644438640:15:0)
[01:53:33.300] Build status: STARTED
[01:53:33.300] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-minimal.riscv64-balanced-e5a0ec7f:8861433725644438640:16:0) after 8m50s
[02:02:23.312] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-minimal.riscv64-balanced-e5a0ec7f:8861433725644438640:16:0)
[02:02:23.364] Build status: STARTED
[02:02:23.364] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-minimal.riscv64-balanced-e5a0ec7f:8861433725644438640:17:0) after 1m25s
[02:03:48.444] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-minimal.riscv64-balanced-e5a0ec7f:8861433725644438640:17:0)
[02:03:48.457] Build status: STARTED
[02:03:48.457] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-minimal.riscv64-balanced-e5a0ec7f:8861433725644438640:18:0) after 9m49s
[02:13:37.469] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-minimal.riscv64-balanced-e5a0ec7f:8861433725644438640:18:0)
[02:13:37.487] Build status: STARTED
[02:13:37.488] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-minimal.riscv64-balanced-e5a0ec7f:8861433725644438640:19:0) after 3m5s
[02:14:13.326] Received PubSub notification, asking Buildbucket for the build status
[02:14:13.359] Build:
{
"id": "8696003663506054593",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "minimal.riscv64-balanced"
},
"createdBy": "project:fuchsia",
"createTime": "2025-12-09T00:54:32.947676013Z",
"startTime": "2025-12-09T00:55:31.718991263Z",
"endTime": "2025-12-09T02:14:12.861854780Z",
"updateTime": "2025-12-09T02:14:12.861854780Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "b6fa92cf8006bbc6dd01d1cffd0615de85c38789",
"ref": "refs/heads/main"
}
}
}
[02:14:13.359] Invocation finished in 1h19m41.835407964s with status SUCCEEDED