[19:59:40.208] New invocation is queued and will start shortly
[19:59:41.227] Starting the invocation (attempt 1)
[19:59:41.251] Popped buildset tag "buildset:commit/gitiles/fuchsia.googlesource.com/integration/+/b0696099fb0b4c66ed45253a31a62e81d861ddb9"
[19:59:41.251] Popped gitiles commit info from properties and tags
[19:59:41.251] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[19:59:41.251] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[19:59:41.251] Buildbucket request:
{
"requestId": "8887453605700516192",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.arm64-asan"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8887453605700516192",
"job": "fuchsia/core.arm64-asan-e5a0ec7f",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "8da7f5ac21957aacc28b481cf5609bfb7ccef9fb"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@8da7f5ac21957aacc28b481cf5609bfb7ccef9fb",
"title": "8da7f5ac21957aacc28b481cf5609bfb7ccef9fb",
"url": "https://fuchsia.googlesource.com/integration/+/8da7f5ac21957aacc28b481cf5609bfb7ccef9fb"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "f2b9326eb45f125395eae8c8da2c28b95bd7477e"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@f2b9326eb45f125395eae8c8da2c28b95bd7477e",
"title": "f2b9326eb45f125395eae8c8da2c28b95bd7477e",
"url": "https://fuchsia.googlesource.com/integration/+/f2b9326eb45f125395eae8c8da2c28b95bd7477e"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "fddcffd6183b622d13cc35529dfda8eb224adde6"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@fddcffd6183b622d13cc35529dfda8eb224adde6",
"title": "fddcffd6183b622d13cc35529dfda8eb224adde6",
"url": "https://fuchsia.googlesource.com/integration/+/fddcffd6183b622d13cc35529dfda8eb224adde6"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "bc6a10a804023ed08036686eb36a5380b4e44779"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@bc6a10a804023ed08036686eb36a5380b4e44779",
"title": "bc6a10a804023ed08036686eb36a5380b4e44779",
"url": "https://fuchsia.googlesource.com/integration/+/bc6a10a804023ed08036686eb36a5380b4e44779"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "c888a98b892ba4383d16c869027a11038492e881"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@c888a98b892ba4383d16c869027a11038492e881",
"title": "c888a98b892ba4383d16c869027a11038492e881",
"url": "https://fuchsia.googlesource.com/integration/+/c888a98b892ba4383d16c869027a11038492e881"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "384f4b509556f3892397628b76c6154b053e3bc8"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@384f4b509556f3892397628b76c6154b053e3bc8",
"title": "384f4b509556f3892397628b76c6154b053e3bc8",
"url": "https://fuchsia.googlesource.com/integration/+/384f4b509556f3892397628b76c6154b053e3bc8"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "cc16877257c58db3daec0bfe4dd7c0acac9af226"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@cc16877257c58db3daec0bfe4dd7c0acac9af226",
"title": "cc16877257c58db3daec0bfe4dd7c0acac9af226",
"url": "https://fuchsia.googlesource.com/integration/+/cc16877257c58db3daec0bfe4dd7c0acac9af226"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "d94c0b3e2d356edd10810eeab6e17d2f2f4a8807"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@d94c0b3e2d356edd10810eeab6e17d2f2f4a8807",
"title": "d94c0b3e2d356edd10810eeab6e17d2f2f4a8807",
"url": "https://fuchsia.googlesource.com/integration/+/d94c0b3e2d356edd10810eeab6e17d2f2f4a8807"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "edca7ff11705df0f57f123463c2bcde340c76d6e"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@edca7ff11705df0f57f123463c2bcde340c76d6e",
"title": "edca7ff11705df0f57f123463c2bcde340c76d6e",
"url": "https://fuchsia.googlesource.com/integration/+/edca7ff11705df0f57f123463c2bcde340c76d6e"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "991e39c1d2b42a84a4dbab3f563c3044f2d6315f"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@991e39c1d2b42a84a4dbab3f563c3044f2d6315f",
"title": "991e39c1d2b42a84a4dbab3f563c3044f2d6315f",
"url": "https://fuchsia.googlesource.com/integration/+/991e39c1d2b42a84a4dbab3f563c3044f2d6315f"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "ff516421125490f4c08a911d15702b9d17d63d6c"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@ff516421125490f4c08a911d15702b9d17d63d6c",
"title": "ff516421125490f4c08a911d15702b9d17d63d6c",
"url": "https://fuchsia.googlesource.com/integration/+/ff516421125490f4c08a911d15702b9d17d63d6c"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "946f9caa475c156b800105017f9ec6429be5c624"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@946f9caa475c156b800105017f9ec6429be5c624",
"title": "946f9caa475c156b800105017f9ec6429be5c624",
"url": "https://fuchsia.googlesource.com/integration/+/946f9caa475c156b800105017f9ec6429be5c624"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "ea6b6ec00b12ce67151ebefa30905ce1967c5d43"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@ea6b6ec00b12ce67151ebefa30905ce1967c5d43",
"title": "ea6b6ec00b12ce67151ebefa30905ce1967c5d43",
"url": "https://fuchsia.googlesource.com/integration/+/ea6b6ec00b12ce67151ebefa30905ce1967c5d43"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "b0696099fb0b4c66ed45253a31a62e81d861ddb9"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@b0696099fb0b4c66ed45253a31a62e81d861ddb9",
"title": "b0696099fb0b4c66ed45253a31a62e81d861ddb9",
"url": "https://fuchsia.googlesource.com/integration/+/b0696099fb0b4c66ed45253a31a62e81d861ddb9"
}
]
}
},
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "b0696099fb0b4c66ed45253a31a62e81d861ddb9",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8887453605700516192"
},
{
"key": "scheduler_job_id",
"value": "fuchsia/core.arm64-asan-e5a0ec7f"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[19:59:41.680] Scheduled build:
{
"id": "8722023543898924049",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.arm64-asan"
},
"createdBy": "project:fuchsia",
"createTime": "2025-02-24T19:59:41.300042930Z",
"updateTime": "2025-02-24T19:59:41.300042930Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "b0696099fb0b4c66ed45253a31a62e81d861ddb9",
"ref": "refs/heads/main"
}
}
}
[19:59:41.680] Task URL: https://cr-buildbucket.appspot.com/build/8722023543898924049
[19:59:41.680] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8887453605700516192:2:0) after 7m11s
[19:59:42.629] Received PubSub notification, asking Buildbucket for the build status
[19:59:42.650] Build status: SCHEDULED
[20:01:42.049] Received PubSub notification, asking Buildbucket for the build status
[20:01:42.082] Build status: STARTED
[20:06:52.705] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8887453605700516192:2:0)
[20:06:52.761] Build status: STARTED
[20:06:52.761] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8887453605700516192:5:0) after 2m41s
[20:09:33.900] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8887453605700516192:5:0)
[20:09:33.927] Build status: STARTED
[20:09:33.927] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8887453605700516192:6:0) after 2m23s
[20:11:56.998] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8887453605700516192:6:0)
[20:11:57.018] Build status: STARTED
[20:11:57.018] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8887453605700516192:7:0) after 5m56s
[20:17:53.055] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8887453605700516192:7:0)
[20:17:53.076] Build status: STARTED
[20:17:53.076] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8887453605700516192:8:0) after 4m1s
[20:21:54.125] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8887453605700516192:8:0)
[20:21:54.139] Build status: STARTED
[20:21:54.139] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8887453605700516192:9:0) after 6m6s
[20:28:00.205] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8887453605700516192:9:0)
[20:28:00.221] Build status: STARTED
[20:28:00.221] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8887453605700516192:10:0) after 7m11s
[20:35:11.243] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8887453605700516192:10:0)
[20:35:11.274] Build status: STARTED
[20:35:11.274] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8887453605700516192:11:0) after 1m59s
[20:37:10.287] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8887453605700516192:11:0)
[20:37:10.303] Build status: STARTED
[20:37:10.303] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8887453605700516192:12:0) after 6m54s
[20:44:04.409] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8887453605700516192:12:0)
[20:44:04.433] Build status: STARTED
[20:44:04.433] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8887453605700516192:13:0) after 9m21s
[20:53:25.501] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8887453605700516192:13:0)
[20:53:25.527] Build status: STARTED
[20:53:25.527] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8887453605700516192:14:0) after 4m32s
[20:57:57.508] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8887453605700516192:14:0)
[20:57:57.527] Build status: STARTED
[20:57:57.527] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8887453605700516192:15:0) after 4m31s
[21:02:28.546] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8887453605700516192:15:0)
[21:02:28.563] Build status: STARTED
[21:02:28.563] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8887453605700516192:16:0) after 9m23s
[21:02:35.996] Received PubSub notification, asking Buildbucket for the build status
[21:02:36.016] Build:
{
"id": "8722023543898924049",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.arm64-asan"
},
"createdBy": "project:fuchsia",
"createTime": "2025-02-24T19:59:41.300042930Z",
"startTime": "2025-02-24T20:01:38.152522328Z",
"endTime": "2025-02-24T21:02:35.613822370Z",
"updateTime": "2025-02-24T21:02:35.613822370Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "b0696099fb0b4c66ed45253a31a62e81d861ddb9",
"ref": "refs/heads/main"
}
}
}
[21:02:36.016] Invocation finished in 1h2m55.816513425s with status SUCCEEDED