[21:55:15.559] New invocation is queued and will start shortly
[21:55:16.779] Starting the invocation (attempt 1)
[21:55:16.812] Popped buildset tag "buildset:commit/gitiles/fuchsia.googlesource.com/integration/+/3e495d733b7f94f13775bbd95f07b2572857d719"
[21:55:16.812] Popped gitiles commit info from properties and tags
[21:55:16.812] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[21:55:16.813] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[21:55:16.813] Buildbucket request:
{
"requestId": "8853110083190425408",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "fuchsia-profile-minimal.arm64-release"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8853110083190425408",
"job": "fuchsia/global.ci-fuchsia-profile-minimal.arm64-release-e5a0ec7f",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "d6c9518921792edff56c16a6785fc460a5e8dd08"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@d6c9518921792edff56c16a6785fc460a5e8dd08",
"title": "d6c9518921792edff56c16a6785fc460a5e8dd08",
"url": "https://fuchsia.googlesource.com/integration/+/d6c9518921792edff56c16a6785fc460a5e8dd08"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "4f799c6077c76692517feece99a0b64da7624fb3"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@4f799c6077c76692517feece99a0b64da7624fb3",
"title": "4f799c6077c76692517feece99a0b64da7624fb3",
"url": "https://fuchsia.googlesource.com/integration/+/4f799c6077c76692517feece99a0b64da7624fb3"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "a39163eeef130e2dc5d418f7f80b6a0e7d3d0bea"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@a39163eeef130e2dc5d418f7f80b6a0e7d3d0bea",
"title": "a39163eeef130e2dc5d418f7f80b6a0e7d3d0bea",
"url": "https://fuchsia.googlesource.com/integration/+/a39163eeef130e2dc5d418f7f80b6a0e7d3d0bea"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "dbfadc22adb36bba68a86e36336683f5ab759b21"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@dbfadc22adb36bba68a86e36336683f5ab759b21",
"title": "dbfadc22adb36bba68a86e36336683f5ab759b21",
"url": "https://fuchsia.googlesource.com/integration/+/dbfadc22adb36bba68a86e36336683f5ab759b21"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "ed999872fcbe6a6bfa38e1b93d3263a80d603b91"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@ed999872fcbe6a6bfa38e1b93d3263a80d603b91",
"title": "ed999872fcbe6a6bfa38e1b93d3263a80d603b91",
"url": "https://fuchsia.googlesource.com/integration/+/ed999872fcbe6a6bfa38e1b93d3263a80d603b91"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "3e495d733b7f94f13775bbd95f07b2572857d719"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@3e495d733b7f94f13775bbd95f07b2572857d719",
"title": "3e495d733b7f94f13775bbd95f07b2572857d719",
"url": "https://fuchsia.googlesource.com/integration/+/3e495d733b7f94f13775bbd95f07b2572857d719"
}
]
}
},
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "3e495d733b7f94f13775bbd95f07b2572857d719",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8853110083190425408"
},
{
"key": "scheduler_job_id",
"value": "fuchsia/global.ci-fuchsia-profile-minimal.arm64-release-e5a0ec7f"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[21:55:17.175] Scheduled build:
{
"id": "8687680021151382993",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "fuchsia-profile-minimal.arm64-release"
},
"createdBy": "project:fuchsia",
"createTime": "2026-03-10T21:55:16.880266002Z",
"updateTime": "2026-03-10T21:55:16.880266002Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "3e495d733b7f94f13775bbd95f07b2572857d719",
"ref": "refs/heads/main"
}
}
}
[21:55:17.175] Task URL: https://cr-buildbucket.appspot.com/build/8687680021151382993
[21:55:17.175] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-profile-minimal.arm64-release-e5a0ec7f:8853110083190425408:2:0) after 7m43s
[21:55:18.136] Received PubSub notification, asking Buildbucket for the build status
[21:55:18.200] Build status: SCHEDULED
[21:55:20.321] Received PubSub notification, asking Buildbucket for the build status
[21:55:20.391] Build status: STARTED
[22:03:00.191] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-profile-minimal.arm64-release-e5a0ec7f:8853110083190425408:2:0)
[22:03:00.210] Build status: STARTED
[22:03:00.210] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-profile-minimal.arm64-release-e5a0ec7f:8853110083190425408:5:0) after 9m33s
[22:12:33.224] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-profile-minimal.arm64-release-e5a0ec7f:8853110083190425408:5:0)
[22:12:33.240] Build status: STARTED
[22:12:33.240] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-profile-minimal.arm64-release-e5a0ec7f:8853110083190425408:6:0) after 5m11s
[22:17:44.225] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-profile-minimal.arm64-release-e5a0ec7f:8853110083190425408:6:0)
[22:17:44.241] Build status: STARTED
[22:17:44.241] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-profile-minimal.arm64-release-e5a0ec7f:8853110083190425408:7:0) after 8m43s
[22:26:27.255] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-profile-minimal.arm64-release-e5a0ec7f:8853110083190425408:7:0)
[22:26:27.275] Build status: STARTED
[22:26:27.275] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-profile-minimal.arm64-release-e5a0ec7f:8853110083190425408:8:0) after 8m42s
[22:35:09.267] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-profile-minimal.arm64-release-e5a0ec7f:8853110083190425408:8:0)
[22:35:09.335] Build status: STARTED
[22:35:09.335] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-profile-minimal.arm64-release-e5a0ec7f:8853110083190425408:9:0) after 9m50s
[22:44:59.339] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-profile-minimal.arm64-release-e5a0ec7f:8853110083190425408:9:0)
[22:44:59.378] Build status: STARTED
[22:44:59.378] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-profile-minimal.arm64-release-e5a0ec7f:8853110083190425408:10:0) after 1m21s
[22:46:20.443] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-profile-minimal.arm64-release-e5a0ec7f:8853110083190425408:10:0)
[22:46:20.480] Build status: STARTED
[22:46:20.481] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-profile-minimal.arm64-release-e5a0ec7f:8853110083190425408:11:0) after 6m6s
[22:52:26.453] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-profile-minimal.arm64-release-e5a0ec7f:8853110083190425408:11:0)
[22:52:26.485] Build status: STARTED
[22:52:26.486] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-profile-minimal.arm64-release-e5a0ec7f:8853110083190425408:12:0) after 3m11s
[22:55:37.505] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-profile-minimal.arm64-release-e5a0ec7f:8853110083190425408:12:0)
[22:55:37.522] Build status: STARTED
[22:55:37.522] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-profile-minimal.arm64-release-e5a0ec7f:8853110083190425408:13:0) after 8m26s
[23:04:03.540] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-profile-minimal.arm64-release-e5a0ec7f:8853110083190425408:13:0)
[23:04:03.561] Build status: STARTED
[23:04:03.561] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-profile-minimal.arm64-release-e5a0ec7f:8853110083190425408:14:0) after 2m6s
[23:06:09.581] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-profile-minimal.arm64-release-e5a0ec7f:8853110083190425408:14:0)
[23:06:09.628] Build status: STARTED
[23:06:09.628] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-profile-minimal.arm64-release-e5a0ec7f:8853110083190425408:15:0) after 1m54s
[23:08:03.652] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-profile-minimal.arm64-release-e5a0ec7f:8853110083190425408:15:0)
[23:08:03.670] Build status: STARTED
[23:08:03.670] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-profile-minimal.arm64-release-e5a0ec7f:8853110083190425408:16:0) after 7m34s
[23:15:37.684] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-profile-minimal.arm64-release-e5a0ec7f:8853110083190425408:16:0)
[23:15:37.702] Build status: STARTED
[23:15:37.702] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-profile-minimal.arm64-release-e5a0ec7f:8853110083190425408:17:0) after 9m26s
[23:20:20.042] Received PubSub notification, asking Buildbucket for the build status
[23:20:20.086] Build:
{
"id": "8687680021151382993",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "fuchsia-profile-minimal.arm64-release"
},
"createdBy": "project:fuchsia",
"createTime": "2026-03-10T21:55:16.880266002Z",
"startTime": "2026-03-10T21:55:20.138247161Z",
"endTime": "2026-03-10T23:20:19.768372584Z",
"updateTime": "2026-03-10T23:20:19.768372584Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "3e495d733b7f94f13775bbd95f07b2572857d719",
"ref": "refs/heads/main"
}
}
}
[23:20:20.086] Invocation finished in 1h25m4.534260407s with status SUCCEEDED