[19:11:38.247] New invocation is queued and will start shortly
[19:11:39.353] Starting the invocation (attempt 1)
[19:11:39.390] Popped buildset tag "buildset:commit/gitiles/fuchsia.googlesource.com/integration/+/5939a1f0a32529d7bbec4021117a25d0e653beaa"
[19:11:39.390] Popped gitiles commit info from properties and tags
[19:11:39.390] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[19:11:39.390] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[19:11:39.391] Buildbucket request:
{
"requestId": "8839077847592606848",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.arm64-kasan"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8839077847592606848",
"job": "fuchsia/global.ci-core.arm64-kasan-e5a0ec7f",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "ae623c1384b7feafa6aeeacf88e3270a11820c81"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@ae623c1384b7feafa6aeeacf88e3270a11820c81",
"title": "ae623c1384b7feafa6aeeacf88e3270a11820c81",
"url": "https://fuchsia.googlesource.com/integration/+/ae623c1384b7feafa6aeeacf88e3270a11820c81"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "31098a235ae9fe75fa3c9fa7be4ba367df366ac4"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@31098a235ae9fe75fa3c9fa7be4ba367df366ac4",
"title": "31098a235ae9fe75fa3c9fa7be4ba367df366ac4",
"url": "https://fuchsia.googlesource.com/integration/+/31098a235ae9fe75fa3c9fa7be4ba367df366ac4"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "07951429c71ec53b8efbd2ae3cc8f86a7b0457d5"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@07951429c71ec53b8efbd2ae3cc8f86a7b0457d5",
"title": "07951429c71ec53b8efbd2ae3cc8f86a7b0457d5",
"url": "https://fuchsia.googlesource.com/integration/+/07951429c71ec53b8efbd2ae3cc8f86a7b0457d5"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "c7840dc21bde36d04ce3f3b194cced4967295721"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@c7840dc21bde36d04ce3f3b194cced4967295721",
"title": "c7840dc21bde36d04ce3f3b194cced4967295721",
"url": "https://fuchsia.googlesource.com/integration/+/c7840dc21bde36d04ce3f3b194cced4967295721"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "d1f85c91868d34cb2b9a7b0a3dd5ee23950397c6"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@d1f85c91868d34cb2b9a7b0a3dd5ee23950397c6",
"title": "d1f85c91868d34cb2b9a7b0a3dd5ee23950397c6",
"url": "https://fuchsia.googlesource.com/integration/+/d1f85c91868d34cb2b9a7b0a3dd5ee23950397c6"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "47699e3cb18a1af161c6d277a39882b9b9f2df3a"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@47699e3cb18a1af161c6d277a39882b9b9f2df3a",
"title": "47699e3cb18a1af161c6d277a39882b9b9f2df3a",
"url": "https://fuchsia.googlesource.com/integration/+/47699e3cb18a1af161c6d277a39882b9b9f2df3a"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "821e24aa4fde31ae5f3dbf6cba7f2d2fd7e12e13"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@821e24aa4fde31ae5f3dbf6cba7f2d2fd7e12e13",
"title": "821e24aa4fde31ae5f3dbf6cba7f2d2fd7e12e13",
"url": "https://fuchsia.googlesource.com/integration/+/821e24aa4fde31ae5f3dbf6cba7f2d2fd7e12e13"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "0b3fdac9dae7d4d3d360fdcefb67c2b11c18d5fa"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@0b3fdac9dae7d4d3d360fdcefb67c2b11c18d5fa",
"title": "0b3fdac9dae7d4d3d360fdcefb67c2b11c18d5fa",
"url": "https://fuchsia.googlesource.com/integration/+/0b3fdac9dae7d4d3d360fdcefb67c2b11c18d5fa"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "658e1011b2f74d2e91e5b45d22ef04bd6c5f73bd"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@658e1011b2f74d2e91e5b45d22ef04bd6c5f73bd",
"title": "658e1011b2f74d2e91e5b45d22ef04bd6c5f73bd",
"url": "https://fuchsia.googlesource.com/integration/+/658e1011b2f74d2e91e5b45d22ef04bd6c5f73bd"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "69c18c105feef9533455fac93106faafbd8e7704"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@69c18c105feef9533455fac93106faafbd8e7704",
"title": "69c18c105feef9533455fac93106faafbd8e7704",
"url": "https://fuchsia.googlesource.com/integration/+/69c18c105feef9533455fac93106faafbd8e7704"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "e88d17fff743ddea8e08c831e80a465648c99719"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@e88d17fff743ddea8e08c831e80a465648c99719",
"title": "e88d17fff743ddea8e08c831e80a465648c99719",
"url": "https://fuchsia.googlesource.com/integration/+/e88d17fff743ddea8e08c831e80a465648c99719"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "0cbeceb1610241cffda62a3d503993c57b86407c"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@0cbeceb1610241cffda62a3d503993c57b86407c",
"title": "0cbeceb1610241cffda62a3d503993c57b86407c",
"url": "https://fuchsia.googlesource.com/integration/+/0cbeceb1610241cffda62a3d503993c57b86407c"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "d8d16d0ae6aebf61f4595ba7f62226ddf0a9997a"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@d8d16d0ae6aebf61f4595ba7f62226ddf0a9997a",
"title": "d8d16d0ae6aebf61f4595ba7f62226ddf0a9997a",
"url": "https://fuchsia.googlesource.com/integration/+/d8d16d0ae6aebf61f4595ba7f62226ddf0a9997a"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "cd915453f2c082c009c669f7c4a13f1c94ff8171"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@cd915453f2c082c009c669f7c4a13f1c94ff8171",
"title": "cd915453f2c082c009c669f7c4a13f1c94ff8171",
"url": "https://fuchsia.googlesource.com/integration/+/cd915453f2c082c009c669f7c4a13f1c94ff8171"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "abbd866f78c559597c924eae489f74592dbe05ac"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@abbd866f78c559597c924eae489f74592dbe05ac",
"title": "abbd866f78c559597c924eae489f74592dbe05ac",
"url": "https://fuchsia.googlesource.com/integration/+/abbd866f78c559597c924eae489f74592dbe05ac"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "855c7bac3f4eaea82286ef2e05ef9c7c906c9e9a"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@855c7bac3f4eaea82286ef2e05ef9c7c906c9e9a",
"title": "855c7bac3f4eaea82286ef2e05ef9c7c906c9e9a",
"url": "https://fuchsia.googlesource.com/integration/+/855c7bac3f4eaea82286ef2e05ef9c7c906c9e9a"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "5939a1f0a32529d7bbec4021117a25d0e653beaa"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@5939a1f0a32529d7bbec4021117a25d0e653beaa",
"title": "5939a1f0a32529d7bbec4021117a25d0e653beaa",
"url": "https://fuchsia.googlesource.com/integration/+/5939a1f0a32529d7bbec4021117a25d0e653beaa"
}
]
}
},
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "5939a1f0a32529d7bbec4021117a25d0e653beaa",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8839077847592606848"
},
{
"key": "scheduler_job_id",
"value": "fuchsia/global.ci-core.arm64-kasan-e5a0ec7f"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[19:11:40.009] Scheduled build:
{
"id": "8673647785642648177",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.arm64-kasan"
},
"createdBy": "project:fuchsia",
"createTime": "2026-08-12T19:11:39.486773029Z",
"updateTime": "2026-08-12T19:11:39.486773029Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "5939a1f0a32529d7bbec4021117a25d0e653beaa",
"ref": "refs/heads/main"
}
},
"canary": true
}
[19:11:40.009] Task URL: https://cr-buildbucket.appspot.com/build/8673647785642648177
[19:11:40.009] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.arm64-kasan-e5a0ec7f:8839077847592606848:2:0) after 8m14s
[19:11:40.167] Received PubSub notification, asking Buildbucket for the build status
[19:11:40.187] Build status: SCHEDULED
[19:11:45.184] Received PubSub notification, asking Buildbucket for the build status
[19:11:45.211] Build status: STARTED
[19:19:54.069] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.arm64-kasan-e5a0ec7f:8839077847592606848:2:0)
[19:19:54.171] Build status: STARTED
[19:19:54.171] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.arm64-kasan-e5a0ec7f:8839077847592606848:5:0) after 8m46s
[19:28:40.336] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.arm64-kasan-e5a0ec7f:8839077847592606848:5:0)
[19:28:40.418] Build status: STARTED
[19:28:40.418] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.arm64-kasan-e5a0ec7f:8839077847592606848:6:0) after 1m37s
[19:30:17.445] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.arm64-kasan-e5a0ec7f:8839077847592606848:6:0)
[19:30:17.463] Build status: STARTED
[19:30:17.463] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.arm64-kasan-e5a0ec7f:8839077847592606848:7:0) after 1m8s
[19:31:25.482] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.arm64-kasan-e5a0ec7f:8839077847592606848:7:0)
[19:31:25.507] Build status: STARTED
[19:31:25.507] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.arm64-kasan-e5a0ec7f:8839077847592606848:8:0) after 1m12s
[19:32:37.522] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.arm64-kasan-e5a0ec7f:8839077847592606848:8:0)
[19:32:37.548] Build status: STARTED
[19:32:37.548] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.arm64-kasan-e5a0ec7f:8839077847592606848:9:0) after 2m23s
[19:35:00.656] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.arm64-kasan-e5a0ec7f:8839077847592606848:9:0)
[19:35:00.684] Build status: STARTED
[19:35:00.684] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.arm64-kasan-e5a0ec7f:8839077847592606848:10:0) after 3m38s
[19:38:38.939] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.arm64-kasan-e5a0ec7f:8839077847592606848:10:0)
[19:38:38.963] Build status: STARTED
[19:38:38.963] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.arm64-kasan-e5a0ec7f:8839077847592606848:11:0) after 2m14s
[19:40:52.951] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.arm64-kasan-e5a0ec7f:8839077847592606848:11:0)
[19:40:52.970] Build status: STARTED
[19:40:52.970] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.arm64-kasan-e5a0ec7f:8839077847592606848:12:0) after 3m43s
[19:44:36.045] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.arm64-kasan-e5a0ec7f:8839077847592606848:12:0)
[19:44:36.073] Build status: STARTED
[19:44:36.073] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.arm64-kasan-e5a0ec7f:8839077847592606848:13:0) after 1m2s
[19:45:38.210] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.arm64-kasan-e5a0ec7f:8839077847592606848:13:0)
[19:45:38.274] Build status: STARTED
[19:45:38.274] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.arm64-kasan-e5a0ec7f:8839077847592606848:14:0) after 8m15s
[19:53:53.288] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.arm64-kasan-e5a0ec7f:8839077847592606848:14:0)
[19:53:53.309] Build status: STARTED
[19:53:53.309] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.arm64-kasan-e5a0ec7f:8839077847592606848:15:0) after 5m34s
[19:59:27.340] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.arm64-kasan-e5a0ec7f:8839077847592606848:15:0)
[19:59:27.413] Build status: STARTED
[19:59:27.413] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.arm64-kasan-e5a0ec7f:8839077847592606848:16:0) after 1m19s
[20:00:46.412] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.arm64-kasan-e5a0ec7f:8839077847592606848:16:0)
[20:00:46.439] Build status: STARTED
[20:00:46.439] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.arm64-kasan-e5a0ec7f:8839077847592606848:17:0) after 8m19s
[20:09:05.647] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.arm64-kasan-e5a0ec7f:8839077847592606848:17:0)
[20:09:05.669] Build status: STARTED
[20:09:05.669] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.arm64-kasan-e5a0ec7f:8839077847592606848:18:0) after 4m50s
[20:13:55.723] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.arm64-kasan-e5a0ec7f:8839077847592606848:18:0)
[20:13:55.746] Build status: STARTED
[20:13:55.746] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.arm64-kasan-e5a0ec7f:8839077847592606848:19:0) after 6m50s
[20:20:45.916] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.arm64-kasan-e5a0ec7f:8839077847592606848:19:0)
[20:20:45.938] Build status: STARTED
[20:20:45.938] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.arm64-kasan-e5a0ec7f:8839077847592606848:20:0) after 8m40s
[20:29:26.168] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.arm64-kasan-e5a0ec7f:8839077847592606848:20:0)
[20:29:26.188] Build status: STARTED
[20:29:26.188] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.arm64-kasan-e5a0ec7f:8839077847592606848:21:0) after 5m6s
[20:34:32.208] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.arm64-kasan-e5a0ec7f:8839077847592606848:21:0)
[20:34:32.234] Build status: STARTED
[20:34:32.234] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.arm64-kasan-e5a0ec7f:8839077847592606848:22:0) after 5m9s
[20:39:41.221] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.arm64-kasan-e5a0ec7f:8839077847592606848:22:0)
[20:39:41.241] Build status: STARTED
[20:39:41.241] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.arm64-kasan-e5a0ec7f:8839077847592606848:23:0) after 9m53s
[20:49:34.308] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.arm64-kasan-e5a0ec7f:8839077847592606848:23:0)
[20:49:34.329] Build status: STARTED
[20:49:34.329] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.arm64-kasan-e5a0ec7f:8839077847592606848:24:0) after 3m20s
[20:52:54.341] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.arm64-kasan-e5a0ec7f:8839077847592606848:24:0)
[20:52:54.373] Build status: STARTED
[20:52:54.373] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.arm64-kasan-e5a0ec7f:8839077847592606848:25:0) after 6m20s
[20:59:14.488] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.arm64-kasan-e5a0ec7f:8839077847592606848:25:0)
[20:59:14.504] Build status: STARTED
[20:59:14.505] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.arm64-kasan-e5a0ec7f:8839077847592606848:26:0) after 2m1s
[21:01:15.568] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.arm64-kasan-e5a0ec7f:8839077847592606848:26:0)
[21:01:15.586] Build status: STARTED
[21:01:15.586] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.arm64-kasan-e5a0ec7f:8839077847592606848:27:0) after 3m55s
[21:05:10.627] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.arm64-kasan-e5a0ec7f:8839077847592606848:27:0)
[21:05:10.652] Build status: STARTED
[21:05:10.652] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.arm64-kasan-e5a0ec7f:8839077847592606848:28:0) after 8m52s
[21:14:02.691] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.arm64-kasan-e5a0ec7f:8839077847592606848:28:0)
[21:14:02.743] Build status: STARTED
[21:14:02.743] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.arm64-kasan-e5a0ec7f:8839077847592606848:29:0) after 1m17s
[21:15:19.880] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.arm64-kasan-e5a0ec7f:8839077847592606848:29:0)
[21:15:19.905] Build status: STARTED
[21:15:19.905] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.arm64-kasan-e5a0ec7f:8839077847592606848:30:0) after 5m17s
[21:19:56.055] Received PubSub notification, asking Buildbucket for the build status
[21:19:56.081] Build:
{
"id": "8673647785642648177",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.arm64-kasan"
},
"createdBy": "project:fuchsia",
"createTime": "2026-08-12T19:11:39.486773029Z",
"startTime": "2026-08-12T19:11:44.910953839Z",
"endTime": "2026-08-12T21:19:55.447645322Z",
"updateTime": "2026-08-12T21:19:55.447645322Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "5939a1f0a32529d7bbec4021117a25d0e653beaa",
"ref": "refs/heads/main"
}
},
"canary": true
}
[21:19:56.081] Invocation finished in 2h8m17.837571142s with status SUCCEEDED