[01:26:18.233] New invocation is queued and will start shortly
[01:26:19.295] Starting the invocation (attempt 1)
[01:26:19.320] Popped buildset tag "buildset:commit/gitiles/fuchsia.googlesource.com/integration/+/5049095088e4a3278e12c9cb02d24363ecdf45e3"
[01:26:19.320] Popped gitiles commit info from properties and tags
[01:26:19.320] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[01:26:19.320] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[01:26:19.321] Buildbucket request:
{
"requestId": "8838420096854524416",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.arm64-debug"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8838420096854524416",
"job": "fuchsia/global.ci-core.arm64-debug-e5a0ec7f",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "2947122dce61b231e4ea413a8ab146bdb551ad12"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@2947122dce61b231e4ea413a8ab146bdb551ad12",
"title": "2947122dce61b231e4ea413a8ab146bdb551ad12",
"url": "https://fuchsia.googlesource.com/integration/+/2947122dce61b231e4ea413a8ab146bdb551ad12"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "aeedd4de731e0c324feb75ac073013e4d399f4e4"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@aeedd4de731e0c324feb75ac073013e4d399f4e4",
"title": "aeedd4de731e0c324feb75ac073013e4d399f4e4",
"url": "https://fuchsia.googlesource.com/integration/+/aeedd4de731e0c324feb75ac073013e4d399f4e4"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "a056b477767f9bd28b542e2e90ed0b9e050d56f5"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@a056b477767f9bd28b542e2e90ed0b9e050d56f5",
"title": "a056b477767f9bd28b542e2e90ed0b9e050d56f5",
"url": "https://fuchsia.googlesource.com/integration/+/a056b477767f9bd28b542e2e90ed0b9e050d56f5"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "fcf220fbc02a822b59f49ea19c5dc8d2b9f0c511"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@fcf220fbc02a822b59f49ea19c5dc8d2b9f0c511",
"title": "fcf220fbc02a822b59f49ea19c5dc8d2b9f0c511",
"url": "https://fuchsia.googlesource.com/integration/+/fcf220fbc02a822b59f49ea19c5dc8d2b9f0c511"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "50c3f0440fc5642586f5170b95989e15442f449b"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@50c3f0440fc5642586f5170b95989e15442f449b",
"title": "50c3f0440fc5642586f5170b95989e15442f449b",
"url": "https://fuchsia.googlesource.com/integration/+/50c3f0440fc5642586f5170b95989e15442f449b"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "a563f1c454fa1762cc9efba05687e56bb92ded17"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@a563f1c454fa1762cc9efba05687e56bb92ded17",
"title": "a563f1c454fa1762cc9efba05687e56bb92ded17",
"url": "https://fuchsia.googlesource.com/integration/+/a563f1c454fa1762cc9efba05687e56bb92ded17"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "70d94c658cc31be84a09e121788917845db62e9e"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@70d94c658cc31be84a09e121788917845db62e9e",
"title": "70d94c658cc31be84a09e121788917845db62e9e",
"url": "https://fuchsia.googlesource.com/integration/+/70d94c658cc31be84a09e121788917845db62e9e"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "5a073f1590b0649000ac61827dfd26011d1ea5f1"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@5a073f1590b0649000ac61827dfd26011d1ea5f1",
"title": "5a073f1590b0649000ac61827dfd26011d1ea5f1",
"url": "https://fuchsia.googlesource.com/integration/+/5a073f1590b0649000ac61827dfd26011d1ea5f1"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "bea97f58d7cdc88de654d4bef4fccec1c18dcdc8"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@bea97f58d7cdc88de654d4bef4fccec1c18dcdc8",
"title": "bea97f58d7cdc88de654d4bef4fccec1c18dcdc8",
"url": "https://fuchsia.googlesource.com/integration/+/bea97f58d7cdc88de654d4bef4fccec1c18dcdc8"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "64a47b1299a4859706001eb7f3cf165ea44139f1"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@64a47b1299a4859706001eb7f3cf165ea44139f1",
"title": "64a47b1299a4859706001eb7f3cf165ea44139f1",
"url": "https://fuchsia.googlesource.com/integration/+/64a47b1299a4859706001eb7f3cf165ea44139f1"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "a03eac5feb5efa1b7b67dae4671fccc2f5103a37"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@a03eac5feb5efa1b7b67dae4671fccc2f5103a37",
"title": "a03eac5feb5efa1b7b67dae4671fccc2f5103a37",
"url": "https://fuchsia.googlesource.com/integration/+/a03eac5feb5efa1b7b67dae4671fccc2f5103a37"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "bd130ff8b5725c517eb925bed18c9cb18cb88c12"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@bd130ff8b5725c517eb925bed18c9cb18cb88c12",
"title": "bd130ff8b5725c517eb925bed18c9cb18cb88c12",
"url": "https://fuchsia.googlesource.com/integration/+/bd130ff8b5725c517eb925bed18c9cb18cb88c12"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "5049095088e4a3278e12c9cb02d24363ecdf45e3"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@5049095088e4a3278e12c9cb02d24363ecdf45e3",
"title": "5049095088e4a3278e12c9cb02d24363ecdf45e3",
"url": "https://fuchsia.googlesource.com/integration/+/5049095088e4a3278e12c9cb02d24363ecdf45e3"
}
]
}
},
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "5049095088e4a3278e12c9cb02d24363ecdf45e3",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8838420096854524416"
},
{
"key": "scheduler_job_id",
"value": "fuchsia/global.ci-core.arm64-debug-e5a0ec7f"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[01:26:19.739] Scheduled build:
{
"id": "8672990034969644545",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.arm64-debug"
},
"createdBy": "project:fuchsia",
"createTime": "2026-08-20T01:26:19.410470477Z",
"updateTime": "2026-08-20T01:26:19.410470477Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "5049095088e4a3278e12c9cb02d24363ecdf45e3",
"ref": "refs/heads/main"
}
}
}
[01:26:19.739] Task URL: https://cr-buildbucket.appspot.com/build/8672990034969644545
[01:26:19.739] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.arm64-debug-e5a0ec7f:8838420096854524416:2:0) after 5m44s
[01:26:20.692] Received PubSub notification, asking Buildbucket for the build status
[01:26:20.711] Build status: SCHEDULED
[01:26:23.322] Received PubSub notification, asking Buildbucket for the build status
[01:26:23.479] Build status: STARTED
[01:32:03.893] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.arm64-debug-e5a0ec7f:8838420096854524416:2:0)
[01:32:03.916] Build status: STARTED
[01:32:03.916] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.arm64-debug-e5a0ec7f:8838420096854524416:5:0) after 6m54s
[01:38:58.133] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.arm64-debug-e5a0ec7f:8838420096854524416:5:0)
[01:38:58.217] Build status: STARTED
[01:38:58.218] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.arm64-debug-e5a0ec7f:8838420096854524416:6:0) after 1m13s
[01:40:11.238] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.arm64-debug-e5a0ec7f:8838420096854524416:6:0)
[01:40:11.260] Build status: STARTED
[01:40:11.260] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.arm64-debug-e5a0ec7f:8838420096854524416:7:0) after 3m46s
[01:43:57.321] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.arm64-debug-e5a0ec7f:8838420096854524416:7:0)
[01:43:57.383] Build status: STARTED
[01:43:57.383] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.arm64-debug-e5a0ec7f:8838420096854524416:8:0) after 8m49s
[01:52:46.399] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.arm64-debug-e5a0ec7f:8838420096854524416:8:0)
[01:52:46.470] Build status: STARTED
[01:52:46.470] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.arm64-debug-e5a0ec7f:8838420096854524416:9:0) after 5m12s
[01:57:58.563] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.arm64-debug-e5a0ec7f:8838420096854524416:9:0)
[01:57:58.583] Build status: STARTED
[01:57:58.583] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.arm64-debug-e5a0ec7f:8838420096854524416:10:0) after 1m13s
[01:59:11.693] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.arm64-debug-e5a0ec7f:8838420096854524416:10:0)
[01:59:11.712] Build status: STARTED
[01:59:11.712] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.arm64-debug-e5a0ec7f:8838420096854524416:11:0) after 3m5s
[02:02:16.730] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.arm64-debug-e5a0ec7f:8838420096854524416:11:0)
[02:02:16.786] Build status: STARTED
[02:02:16.786] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.arm64-debug-e5a0ec7f:8838420096854524416:12:0) after 2m9s
[02:04:25.907] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.arm64-debug-e5a0ec7f:8838420096854524416:12:0)
[02:04:25.924] Build status: STARTED
[02:04:25.924] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.arm64-debug-e5a0ec7f:8838420096854524416:13:0) after 6m24s
[02:10:50.048] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.arm64-debug-e5a0ec7f:8838420096854524416:13:0)
[02:10:50.078] Build status: STARTED
[02:10:50.078] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.arm64-debug-e5a0ec7f:8838420096854524416:14:0) after 2m1s
[02:12:51.167] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.arm64-debug-e5a0ec7f:8838420096854524416:14:0)
[02:12:51.188] Build status: STARTED
[02:12:51.188] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.arm64-debug-e5a0ec7f:8838420096854524416:15:0) after 4m7s
[02:16:58.204] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.arm64-debug-e5a0ec7f:8838420096854524416:15:0)
[02:16:58.223] Build status: STARTED
[02:16:58.223] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.arm64-debug-e5a0ec7f:8838420096854524416:16:0) after 9m16s
[02:26:14.235] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.arm64-debug-e5a0ec7f:8838420096854524416:16:0)
[02:26:14.255] Build status: STARTED
[02:26:14.255] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.arm64-debug-e5a0ec7f:8838420096854524416:17:0) after 1m37s
[02:27:51.274] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.arm64-debug-e5a0ec7f:8838420096854524416:17:0)
[02:27:51.294] Build status: STARTED
[02:27:51.294] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.arm64-debug-e5a0ec7f:8838420096854524416:18:0) after 6m32s
[02:34:23.419] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.arm64-debug-e5a0ec7f:8838420096854524416:18:0)
[02:34:23.448] Build status: STARTED
[02:34:23.448] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.arm64-debug-e5a0ec7f:8838420096854524416:19:0) after 6m2s
[02:40:25.468] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.arm64-debug-e5a0ec7f:8838420096854524416:19:0)
[02:40:25.484] Build status: STARTED
[02:40:25.484] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.arm64-debug-e5a0ec7f:8838420096854524416:20:0) after 7m16s
[02:47:41.528] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.arm64-debug-e5a0ec7f:8838420096854524416:20:0)
[02:47:41.545] Build status: STARTED
[02:47:41.545] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.arm64-debug-e5a0ec7f:8838420096854524416:21:0) after 7m35s
[02:55:16.565] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.arm64-debug-e5a0ec7f:8838420096854524416:21:0)
[02:55:16.600] Build status: STARTED
[02:55:16.600] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.arm64-debug-e5a0ec7f:8838420096854524416:22:0) after 3m25s
[02:58:41.634] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.arm64-debug-e5a0ec7f:8838420096854524416:22:0)
[02:58:41.654] Build status: STARTED
[02:58:41.654] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.arm64-debug-e5a0ec7f:8838420096854524416:23:0) after 1m57s
[03:00:38.672] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.arm64-debug-e5a0ec7f:8838420096854524416:23:0)
[03:00:38.697] Build status: STARTED
[03:00:38.697] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.arm64-debug-e5a0ec7f:8838420096854524416:24:0) after 3m51s
[03:04:29.785] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.arm64-debug-e5a0ec7f:8838420096854524416:24:0)
[03:04:29.850] Build status: STARTED
[03:04:29.850] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.arm64-debug-e5a0ec7f:8838420096854524416:25:0) after 8m40s
[03:13:09.899] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.arm64-debug-e5a0ec7f:8838420096854524416:25:0)
[03:13:09.917] Build status: STARTED
[03:13:09.917] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.arm64-debug-e5a0ec7f:8838420096854524416:26:0) after 6m34s
[03:19:44.076] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.arm64-debug-e5a0ec7f:8838420096854524416:26:0)
[03:19:44.101] Build status: STARTED
[03:19:44.101] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.arm64-debug-e5a0ec7f:8838420096854524416:27:0) after 9m28s
[03:29:12.264] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.arm64-debug-e5a0ec7f:8838420096854524416:27:0)
[03:29:12.283] Build status: STARTED
[03:29:12.283] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.arm64-debug-e5a0ec7f:8838420096854524416:28:0) after 8m19s
[03:35:08.677] Received PubSub notification, asking Buildbucket for the build status
[03:35:08.701] Build:
{
"id": "8672990034969644545",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.arm64-debug"
},
"createdBy": "project:fuchsia",
"createTime": "2026-08-20T01:26:19.410470477Z",
"startTime": "2026-08-20T01:26:23.144167640Z",
"endTime": "2026-08-20T03:35:08.162823342Z",
"updateTime": "2026-08-20T03:35:08.162823342Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "5049095088e4a3278e12c9cb02d24363ecdf45e3",
"ref": "refs/heads/main"
}
}
}
[03:35:08.701] Invocation finished in 2h8m50.472168728s with status SUCCEEDED