[19:46:02.059] New invocation is queued and will start shortly
[19:46:03.177] Starting the invocation (attempt 1)
[19:46:03.226] Popped buildset tag "buildset:commit/gitiles/fuchsia.googlesource.com/integration/+/309df429d4ebd26c1ac120dff5547065f6509bc3"
[19:46:03.226] Popped gitiles commit info from properties and tags
[19:46:03.226] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[19:46:03.226] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[19:46:03.227] Buildbucket request:
{
"requestId": "8851849855792550560",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.x64-asan"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8851849855792550560",
"job": "fuchsia/global.ci-core.x64-asan-e5a0ec7f",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "f27eedbfa358b18f96671b4189c234986db8efc8"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@f27eedbfa358b18f96671b4189c234986db8efc8",
"title": "f27eedbfa358b18f96671b4189c234986db8efc8",
"url": "https://fuchsia.googlesource.com/integration/+/f27eedbfa358b18f96671b4189c234986db8efc8"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "c7d8c04743878aa7fb8f28e48d3f6651baf4518a"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@c7d8c04743878aa7fb8f28e48d3f6651baf4518a",
"title": "c7d8c04743878aa7fb8f28e48d3f6651baf4518a",
"url": "https://fuchsia.googlesource.com/integration/+/c7d8c04743878aa7fb8f28e48d3f6651baf4518a"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "d53c0e04cce114658eab5ea2dcca6d07879c33dd"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@d53c0e04cce114658eab5ea2dcca6d07879c33dd",
"title": "d53c0e04cce114658eab5ea2dcca6d07879c33dd",
"url": "https://fuchsia.googlesource.com/integration/+/d53c0e04cce114658eab5ea2dcca6d07879c33dd"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "80c82ef2cd3670c1b838396262d395db758b2487"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@80c82ef2cd3670c1b838396262d395db758b2487",
"title": "80c82ef2cd3670c1b838396262d395db758b2487",
"url": "https://fuchsia.googlesource.com/integration/+/80c82ef2cd3670c1b838396262d395db758b2487"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "21419f0171a4fcd51272af2b7a0dd2abccce0fe7"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@21419f0171a4fcd51272af2b7a0dd2abccce0fe7",
"title": "21419f0171a4fcd51272af2b7a0dd2abccce0fe7",
"url": "https://fuchsia.googlesource.com/integration/+/21419f0171a4fcd51272af2b7a0dd2abccce0fe7"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "f19d7561aa347f5704fe032350dfd83a7d4f60b5"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@f19d7561aa347f5704fe032350dfd83a7d4f60b5",
"title": "f19d7561aa347f5704fe032350dfd83a7d4f60b5",
"url": "https://fuchsia.googlesource.com/integration/+/f19d7561aa347f5704fe032350dfd83a7d4f60b5"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "309df429d4ebd26c1ac120dff5547065f6509bc3"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@309df429d4ebd26c1ac120dff5547065f6509bc3",
"title": "309df429d4ebd26c1ac120dff5547065f6509bc3",
"url": "https://fuchsia.googlesource.com/integration/+/309df429d4ebd26c1ac120dff5547065f6509bc3"
}
]
}
},
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "309df429d4ebd26c1ac120dff5547065f6509bc3",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8851849855792550560"
},
{
"key": "scheduler_job_id",
"value": "fuchsia/global.ci-core.x64-asan-e5a0ec7f"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[19:46:03.591] Scheduled build:
{
"id": "8686419793853237537",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.x64-asan"
},
"createdBy": "project:fuchsia",
"createTime": "2026-03-24T19:46:03.287737706Z",
"updateTime": "2026-03-24T19:46:03.287737706Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "309df429d4ebd26c1ac120dff5547065f6509bc3",
"ref": "refs/heads/main"
}
}
}
[19:46:03.591] Task URL: https://cr-buildbucket.appspot.com/build/8686419793853237537
[19:46:03.591] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-asan-e5a0ec7f:8851849855792550560:2:0) after 6m3s
[19:46:24.777] Received PubSub notification, asking Buildbucket for the build status
[19:46:24.792] Build status: STARTED
[19:46:30.850] Received PubSub notification, asking Buildbucket for the build status
[19:46:30.932] Build status: STARTED
[19:52:06.606] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-asan-e5a0ec7f:8851849855792550560:2:0)
[19:52:06.717] Build status: STARTED
[19:52:06.717] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-asan-e5a0ec7f:8851849855792550560:5:0) after 6m20s
[19:58:26.734] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-asan-e5a0ec7f:8851849855792550560:5:0)
[19:58:26.749] Build status: STARTED
[19:58:26.750] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-asan-e5a0ec7f:8851849855792550560:6:0) after 2m52s
[20:01:18.804] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-asan-e5a0ec7f:8851849855792550560:6:0)
[20:01:18.836] Build status: STARTED
[20:01:18.836] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-asan-e5a0ec7f:8851849855792550560:7:0) after 3m55s
[20:05:14.176] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-asan-e5a0ec7f:8851849855792550560:7:0)
[20:05:14.403] Build status: STARTED
[20:05:14.406] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-asan-e5a0ec7f:8851849855792550560:8:0) after 2m49s
[20:08:03.472] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-asan-e5a0ec7f:8851849855792550560:8:0)
[20:08:03.500] Build status: STARTED
[20:08:03.500] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-asan-e5a0ec7f:8851849855792550560:9:0) after 6m4s
[20:14:07.599] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-asan-e5a0ec7f:8851849855792550560:9:0)
[20:14:07.616] Build status: STARTED
[20:14:07.616] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-asan-e5a0ec7f:8851849855792550560:10:0) after 9m46s
[20:23:53.722] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-asan-e5a0ec7f:8851849855792550560:10:0)
[20:23:53.743] Build status: STARTED
[20:23:53.743] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-asan-e5a0ec7f:8851849855792550560:11:0) after 2m50s
[20:26:43.773] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-asan-e5a0ec7f:8851849855792550560:11:0)
[20:26:43.790] Build status: STARTED
[20:26:43.790] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-asan-e5a0ec7f:8851849855792550560:12:0) after 1m26s
[20:28:09.980] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-asan-e5a0ec7f:8851849855792550560:12:0)
[20:28:09.999] Build status: STARTED
[20:28:09.999] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-asan-e5a0ec7f:8851849855792550560:13:0) after 3m45s
[20:31:55.101] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-asan-e5a0ec7f:8851849855792550560:13:0)
[20:31:55.119] Build status: STARTED
[20:31:55.119] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-asan-e5a0ec7f:8851849855792550560:14:0) after 6m31s
[20:38:26.186] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-asan-e5a0ec7f:8851849855792550560:14:0)
[20:38:26.207] Build status: STARTED
[20:38:26.207] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-asan-e5a0ec7f:8851849855792550560:15:0) after 6m56s
[20:45:22.188] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-asan-e5a0ec7f:8851849855792550560:15:0)
[20:45:22.205] Build status: STARTED
[20:45:22.205] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-asan-e5a0ec7f:8851849855792550560:16:0) after 2m59s
[20:48:21.221] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-asan-e5a0ec7f:8851849855792550560:16:0)
[20:48:21.242] Build status: STARTED
[20:48:21.242] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-asan-e5a0ec7f:8851849855792550560:17:0) after 8m12s
[20:52:40.357] Received PubSub notification, asking Buildbucket for the build status
[20:52:40.422] Build:
{
"id": "8686419793853237537",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.x64-asan"
},
"createdBy": "project:fuchsia",
"createTime": "2026-03-24T19:46:03.287737706Z",
"startTime": "2026-03-24T19:46:08.530330413Z",
"endTime": "2026-03-24T20:52:39.792280455Z",
"updateTime": "2026-03-24T20:52:39.792280455Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "309df429d4ebd26c1ac120dff5547065f6509bc3",
"ref": "refs/heads/main"
}
}
}
[20:52:40.422] Invocation finished in 1h6m38.368185155s with status SUCCEEDED