[23:58:47.714] New invocation is queued and will start shortly
[23:58:48.779] Starting the invocation (attempt 1)
[23:58:48.819] Popped buildset tag "buildset:commit/gitiles/fuchsia.googlesource.com/integration/+/934b8302b9c4cb5aa996c81bd37404ae1e25d5c0"
[23:58:48.819] Popped gitiles commit info from properties and tags
[23:58:48.819] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[23:58:48.820] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[23:58:48.820] Buildbucket request:
{
"requestId": "8836523066128804112",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.x64-balanced"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8836523066128804112",
"job": "fuchsia/global.ci-core.x64-balanced-e5a0ec7f",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "495d9a8b0e754077effed12802011bdfc2a93f77"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@495d9a8b0e754077effed12802011bdfc2a93f77",
"title": "495d9a8b0e754077effed12802011bdfc2a93f77",
"url": "https://fuchsia.googlesource.com/integration/+/495d9a8b0e754077effed12802011bdfc2a93f77"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "b4959e15852a510b796c637a532aecec9ef7e137"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@b4959e15852a510b796c637a532aecec9ef7e137",
"title": "b4959e15852a510b796c637a532aecec9ef7e137",
"url": "https://fuchsia.googlesource.com/integration/+/b4959e15852a510b796c637a532aecec9ef7e137"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "8b6a270fbd3dae01697614f2ef083e9a7c1c4963"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@8b6a270fbd3dae01697614f2ef083e9a7c1c4963",
"title": "8b6a270fbd3dae01697614f2ef083e9a7c1c4963",
"url": "https://fuchsia.googlesource.com/integration/+/8b6a270fbd3dae01697614f2ef083e9a7c1c4963"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "e465b23e6d36a6392ed46077eaee5489a93c7811"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@e465b23e6d36a6392ed46077eaee5489a93c7811",
"title": "e465b23e6d36a6392ed46077eaee5489a93c7811",
"url": "https://fuchsia.googlesource.com/integration/+/e465b23e6d36a6392ed46077eaee5489a93c7811"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "c885948430cbd6583e0c055863013a576325a415"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@c885948430cbd6583e0c055863013a576325a415",
"title": "c885948430cbd6583e0c055863013a576325a415",
"url": "https://fuchsia.googlesource.com/integration/+/c885948430cbd6583e0c055863013a576325a415"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "18501f564e6329a1164bb2bc71526fc8c155105e"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@18501f564e6329a1164bb2bc71526fc8c155105e",
"title": "18501f564e6329a1164bb2bc71526fc8c155105e",
"url": "https://fuchsia.googlesource.com/integration/+/18501f564e6329a1164bb2bc71526fc8c155105e"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "7b2d2f463a62cd5ffdb22717665b6a6f0975e11f"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@7b2d2f463a62cd5ffdb22717665b6a6f0975e11f",
"title": "7b2d2f463a62cd5ffdb22717665b6a6f0975e11f",
"url": "https://fuchsia.googlesource.com/integration/+/7b2d2f463a62cd5ffdb22717665b6a6f0975e11f"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "06e24a5af1a8174299df7c04dd95b3ef0f5f60b2"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@06e24a5af1a8174299df7c04dd95b3ef0f5f60b2",
"title": "06e24a5af1a8174299df7c04dd95b3ef0f5f60b2",
"url": "https://fuchsia.googlesource.com/integration/+/06e24a5af1a8174299df7c04dd95b3ef0f5f60b2"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "0a319982f74f709f862dbc293e5bfbb76cccd0bb"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@0a319982f74f709f862dbc293e5bfbb76cccd0bb",
"title": "0a319982f74f709f862dbc293e5bfbb76cccd0bb",
"url": "https://fuchsia.googlesource.com/integration/+/0a319982f74f709f862dbc293e5bfbb76cccd0bb"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "934b8302b9c4cb5aa996c81bd37404ae1e25d5c0"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@934b8302b9c4cb5aa996c81bd37404ae1e25d5c0",
"title": "934b8302b9c4cb5aa996c81bd37404ae1e25d5c0",
"url": "https://fuchsia.googlesource.com/integration/+/934b8302b9c4cb5aa996c81bd37404ae1e25d5c0"
}
]
}
},
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "934b8302b9c4cb5aa996c81bd37404ae1e25d5c0",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8836523066128804112"
},
{
"key": "scheduler_job_id",
"value": "fuchsia/global.ci-core.x64-balanced-e5a0ec7f"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[23:58:50.534] Scheduled build:
{
"id": "8671093004232176001",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.x64-balanced"
},
"createdBy": "project:fuchsia",
"createTime": "2026-09-09T23:58:48.901446698Z",
"updateTime": "2026-09-09T23:58:48.901446698Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "934b8302b9c4cb5aa996c81bd37404ae1e25d5c0",
"ref": "refs/heads/main"
}
}
}
[23:58:50.535] Task URL: https://cr-buildbucket.appspot.com/build/8671093004232176001
[23:58:50.535] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-balanced-e5a0ec7f:8836523066128804112:2:0) after 5m35s
[23:58:51.478] Received PubSub notification, asking Buildbucket for the build status
[23:58:51.497] Build status: SCHEDULED
[23:58:55.131] Received PubSub notification, asking Buildbucket for the build status
[23:58:55.154] Build status: STARTED
[00:04:25.616] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-balanced-e5a0ec7f:8836523066128804112:2:0)
[00:04:25.630] Build status: STARTED
[00:04:25.630] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-balanced-e5a0ec7f:8836523066128804112:5:0) after 6m24s
[00:10:49.651] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-balanced-e5a0ec7f:8836523066128804112:5:0)
[00:10:49.725] Build status: STARTED
[00:10:49.725] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-balanced-e5a0ec7f:8836523066128804112:6:0) after 7m32s
[00:18:21.816] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-balanced-e5a0ec7f:8836523066128804112:6:0)
[00:18:21.834] Build status: STARTED
[00:18:21.834] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-balanced-e5a0ec7f:8836523066128804112:7:0) after 3m50s
[00:22:11.853] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-balanced-e5a0ec7f:8836523066128804112:7:0)
[00:22:11.882] Build status: STARTED
[00:22:11.882] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-balanced-e5a0ec7f:8836523066128804112:8:0) after 5m53s
[00:28:04.902] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-balanced-e5a0ec7f:8836523066128804112:8:0)
[00:28:04.941] Build status: STARTED
[00:28:04.941] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-balanced-e5a0ec7f:8836523066128804112:9:0) after 6m24s
[00:34:28.975] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-balanced-e5a0ec7f:8836523066128804112:9:0)
[00:34:28.992] Build status: STARTED
[00:34:28.992] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-balanced-e5a0ec7f:8836523066128804112:10:0) after 9m35s
[00:44:04.117] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-balanced-e5a0ec7f:8836523066128804112:10:0)
[00:44:04.145] Build status: STARTED
[00:44:04.145] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-balanced-e5a0ec7f:8836523066128804112:11:0) after 6m43s
[00:50:47.162] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-balanced-e5a0ec7f:8836523066128804112:11:0)
[00:50:47.182] Build status: STARTED
[00:50:47.182] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-balanced-e5a0ec7f:8836523066128804112:12:0) after 1m11s
[00:51:58.220] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-balanced-e5a0ec7f:8836523066128804112:12:0)
[00:51:58.246] Build status: STARTED
[00:51:58.246] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-balanced-e5a0ec7f:8836523066128804112:13:0) after 6m14s
[00:58:12.380] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-balanced-e5a0ec7f:8836523066128804112:13:0)
[00:58:12.400] Build status: STARTED
[00:58:12.400] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-balanced-e5a0ec7f:8836523066128804112:14:0) after 4m47s
[01:02:59.476] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-balanced-e5a0ec7f:8836523066128804112:14:0)
[01:02:59.495] Build status: STARTED
[01:02:59.495] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-balanced-e5a0ec7f:8836523066128804112:15:0) after 4m42s
[01:07:41.589] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-balanced-e5a0ec7f:8836523066128804112:15:0)
[01:07:41.608] Build status: STARTED
[01:07:41.608] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-balanced-e5a0ec7f:8836523066128804112:16:0) after 2m42s
[01:10:23.614] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-balanced-e5a0ec7f:8836523066128804112:16:0)
[01:10:23.636] Build status: STARTED
[01:10:23.636] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-balanced-e5a0ec7f:8836523066128804112:17:0) after 1m28s
[01:11:51.765] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-balanced-e5a0ec7f:8836523066128804112:17:0)
[01:11:51.782] Build status: STARTED
[01:11:51.782] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-balanced-e5a0ec7f:8836523066128804112:18:0) after 6m50s
[01:18:41.979] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-balanced-e5a0ec7f:8836523066128804112:18:0)
[01:18:42.040] Build status: STARTED
[01:18:42.040] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-balanced-e5a0ec7f:8836523066128804112:19:0) after 3m12s
[01:21:54.089] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-balanced-e5a0ec7f:8836523066128804112:19:0)
[01:21:54.122] Build status: STARTED
[01:21:54.122] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-balanced-e5a0ec7f:8836523066128804112:20:0) after 1m37s
[01:23:31.511] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-balanced-e5a0ec7f:8836523066128804112:20:0)
[01:23:31.536] Build status: STARTED
[01:23:31.536] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-balanced-e5a0ec7f:8836523066128804112:21:0) after 4m38s
[01:28:09.783] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-balanced-e5a0ec7f:8836523066128804112:21:0)
[01:28:09.814] Build status: STARTED
[01:28:09.814] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-balanced-e5a0ec7f:8836523066128804112:22:0) after 2m24s
[01:30:33.882] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-balanced-e5a0ec7f:8836523066128804112:22:0)
[01:30:33.908] Build status: STARTED
[01:30:33.908] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-balanced-e5a0ec7f:8836523066128804112:23:0) after 7m5s
[01:37:38.923] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-balanced-e5a0ec7f:8836523066128804112:23:0)
[01:37:38.951] Build status: STARTED
[01:37:38.951] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-balanced-e5a0ec7f:8836523066128804112:24:0) after 5m34s
[01:39:34.241] Received PubSub notification, asking Buildbucket for the build status
[01:39:34.262] Build:
{
"id": "8671093004232176001",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.x64-balanced"
},
"createdBy": "project:fuchsia",
"createTime": "2026-09-09T23:58:48.901446698Z",
"startTime": "2026-09-09T23:58:54.927639219Z",
"endTime": "2026-09-10T01:39:33.717104762Z",
"updateTime": "2026-09-10T01:39:33.717104762Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "934b8302b9c4cb5aa996c81bd37404ae1e25d5c0",
"ref": "refs/heads/main"
}
}
}
[01:39:34.262] Invocation finished in 1h40m46.552781772s with status SUCCEEDED