[22:54:00.137] New invocation is queued and will start shortly
[22:54:02.614] Starting the invocation (attempt 1)
[22:54:02.642] Popped buildset tag "buildset:commit/gitiles/fuchsia.googlesource.com/integration/+/8d7dbe8098fbeebb41704866d96481b467dc367a"
[22:54:02.642] Popped gitiles commit info from properties and tags
[22:54:02.642] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[22:54:02.642] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[22:54:02.643] Buildbucket request:
{
"requestId": "8911903818594687040",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.arm64-cxx20"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8911903818594687040",
"job": "fuchsia/core.arm64-cxx20-e5a0ec7f",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "92c2f61be19de2a303e7c3b4d20197ad6edefe46"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@92c2f61be19de2a303e7c3b4d20197ad6edefe46",
"title": "92c2f61be19de2a303e7c3b4d20197ad6edefe46",
"url": "https://fuchsia.googlesource.com/integration/+/92c2f61be19de2a303e7c3b4d20197ad6edefe46"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "fd04962d9d5ba7186f685cb1e1cbf5be42cf339f"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@fd04962d9d5ba7186f685cb1e1cbf5be42cf339f",
"title": "fd04962d9d5ba7186f685cb1e1cbf5be42cf339f",
"url": "https://fuchsia.googlesource.com/integration/+/fd04962d9d5ba7186f685cb1e1cbf5be42cf339f"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "2b797cf1626580c770957a104f5b55007d1c81d9"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@2b797cf1626580c770957a104f5b55007d1c81d9",
"title": "2b797cf1626580c770957a104f5b55007d1c81d9",
"url": "https://fuchsia.googlesource.com/integration/+/2b797cf1626580c770957a104f5b55007d1c81d9"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "468180d8bfeb182ef607c8e561fcad37f643bca3"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@468180d8bfeb182ef607c8e561fcad37f643bca3",
"title": "468180d8bfeb182ef607c8e561fcad37f643bca3",
"url": "https://fuchsia.googlesource.com/integration/+/468180d8bfeb182ef607c8e561fcad37f643bca3"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "645b25a560a0f9780ebc3adcf84f2f3b4214a0af"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@645b25a560a0f9780ebc3adcf84f2f3b4214a0af",
"title": "645b25a560a0f9780ebc3adcf84f2f3b4214a0af",
"url": "https://fuchsia.googlesource.com/integration/+/645b25a560a0f9780ebc3adcf84f2f3b4214a0af"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "6b5b054b6d9007052485425f8a01bc68c7e7665a"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@6b5b054b6d9007052485425f8a01bc68c7e7665a",
"title": "6b5b054b6d9007052485425f8a01bc68c7e7665a",
"url": "https://fuchsia.googlesource.com/integration/+/6b5b054b6d9007052485425f8a01bc68c7e7665a"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "8d393a420fa77df67158ffc8470f427047a0e6e4"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@8d393a420fa77df67158ffc8470f427047a0e6e4",
"title": "8d393a420fa77df67158ffc8470f427047a0e6e4",
"url": "https://fuchsia.googlesource.com/integration/+/8d393a420fa77df67158ffc8470f427047a0e6e4"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "24be7ed571dc73b5155ebebef58b31d7ac513d3e"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@24be7ed571dc73b5155ebebef58b31d7ac513d3e",
"title": "24be7ed571dc73b5155ebebef58b31d7ac513d3e",
"url": "https://fuchsia.googlesource.com/integration/+/24be7ed571dc73b5155ebebef58b31d7ac513d3e"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "8fa43b7b032975935354b7801c79f46e6b7c286e"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@8fa43b7b032975935354b7801c79f46e6b7c286e",
"title": "8fa43b7b032975935354b7801c79f46e6b7c286e",
"url": "https://fuchsia.googlesource.com/integration/+/8fa43b7b032975935354b7801c79f46e6b7c286e"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "844c609b5b817fba015c3b83a7da8ee260f1167d"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@844c609b5b817fba015c3b83a7da8ee260f1167d",
"title": "844c609b5b817fba015c3b83a7da8ee260f1167d",
"url": "https://fuchsia.googlesource.com/integration/+/844c609b5b817fba015c3b83a7da8ee260f1167d"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "5694dc63a260cb805d73cc54ff3dc1f4859d4a8d"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@5694dc63a260cb805d73cc54ff3dc1f4859d4a8d",
"title": "5694dc63a260cb805d73cc54ff3dc1f4859d4a8d",
"url": "https://fuchsia.googlesource.com/integration/+/5694dc63a260cb805d73cc54ff3dc1f4859d4a8d"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "469311fe869dabdf17e8f27107d661704b8f0dd8"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@469311fe869dabdf17e8f27107d661704b8f0dd8",
"title": "469311fe869dabdf17e8f27107d661704b8f0dd8",
"url": "https://fuchsia.googlesource.com/integration/+/469311fe869dabdf17e8f27107d661704b8f0dd8"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "b2be995d445a4651a74a8f2c6a26ff8c8e393793"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@b2be995d445a4651a74a8f2c6a26ff8c8e393793",
"title": "b2be995d445a4651a74a8f2c6a26ff8c8e393793",
"url": "https://fuchsia.googlesource.com/integration/+/b2be995d445a4651a74a8f2c6a26ff8c8e393793"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "7f66aeda4ef821d77599b5ad4a44e8364ff5aac0"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@7f66aeda4ef821d77599b5ad4a44e8364ff5aac0",
"title": "7f66aeda4ef821d77599b5ad4a44e8364ff5aac0",
"url": "https://fuchsia.googlesource.com/integration/+/7f66aeda4ef821d77599b5ad4a44e8364ff5aac0"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "230e04c85afc72b48dcb31c190771d73be7c7123"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@230e04c85afc72b48dcb31c190771d73be7c7123",
"title": "230e04c85afc72b48dcb31c190771d73be7c7123",
"url": "https://fuchsia.googlesource.com/integration/+/230e04c85afc72b48dcb31c190771d73be7c7123"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "c523f3887a47f9cb8d6ebdbb8514fdddd0770218"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@c523f3887a47f9cb8d6ebdbb8514fdddd0770218",
"title": "c523f3887a47f9cb8d6ebdbb8514fdddd0770218",
"url": "https://fuchsia.googlesource.com/integration/+/c523f3887a47f9cb8d6ebdbb8514fdddd0770218"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "8d7dbe8098fbeebb41704866d96481b467dc367a"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@8d7dbe8098fbeebb41704866d96481b467dc367a",
"title": "8d7dbe8098fbeebb41704866d96481b467dc367a",
"url": "https://fuchsia.googlesource.com/integration/+/8d7dbe8098fbeebb41704866d96481b467dc367a"
}
]
}
},
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "8d7dbe8098fbeebb41704866d96481b467dc367a",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8911903818594687040"
},
{
"key": "scheduler_job_id",
"value": "fuchsia/core.arm64-cxx20-e5a0ec7f"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[22:54:03.066] Scheduled build:
{
"id": "8746473755247661953",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.arm64-cxx20"
},
"createdBy": "project:fuchsia",
"createTime": "2024-05-30T22:54:02.706377004Z",
"updateTime": "2024-05-30T22:54:02.706377004Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "8d7dbe8098fbeebb41704866d96481b467dc367a",
"ref": "refs/heads/main"
}
}
}
[22:54:03.066] Task URL: https://cr-buildbucket.appspot.com/build/8746473755247661953
[22:54:03.066] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-cxx20-e5a0ec7f:8911903818594687040:2:0) after 5m42s
[22:54:03.990] Received PubSub notification, asking Buildbucket for the build status
[22:54:04.014] Build status: SCHEDULED
[22:54:29.017] Received PubSub notification, asking Buildbucket for the build status
[22:54:29.044] Build status: STARTED
[22:59:45.102] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-cxx20-e5a0ec7f:8911903818594687040:2:0)
[22:59:45.118] Build status: STARTED
[22:59:45.118] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-cxx20-e5a0ec7f:8911903818594687040:5:0) after 1m23s
[23:01:08.152] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-cxx20-e5a0ec7f:8911903818594687040:5:0)
[23:01:08.191] Build status: STARTED
[23:01:08.191] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-cxx20-e5a0ec7f:8911903818594687040:6:0) after 9m1s
[23:10:09.219] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-cxx20-e5a0ec7f:8911903818594687040:6:0)
[23:10:09.236] Build status: STARTED
[23:10:09.236] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-cxx20-e5a0ec7f:8911903818594687040:7:0) after 9m11s
[23:19:20.265] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-cxx20-e5a0ec7f:8911903818594687040:7:0)
[23:19:20.284] Build status: STARTED
[23:19:20.284] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-cxx20-e5a0ec7f:8911903818594687040:8:0) after 6m25s
[23:25:45.291] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-cxx20-e5a0ec7f:8911903818594687040:8:0)
[23:25:45.334] Build status: STARTED
[23:25:45.335] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-cxx20-e5a0ec7f:8911903818594687040:9:0) after 2m58s
[23:28:43.362] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-cxx20-e5a0ec7f:8911903818594687040:9:0)
[23:28:43.381] Build status: STARTED
[23:28:43.381] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-cxx20-e5a0ec7f:8911903818594687040:10:0) after 5m33s
[23:34:16.384] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-cxx20-e5a0ec7f:8911903818594687040:10:0)
[23:34:16.401] Build status: STARTED
[23:34:16.401] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-cxx20-e5a0ec7f:8911903818594687040:11:0) after 3m38s
[23:37:54.437] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-cxx20-e5a0ec7f:8911903818594687040:11:0)
[23:37:54.457] Build status: STARTED
[23:37:54.457] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-cxx20-e5a0ec7f:8911903818594687040:12:0) after 7m57s
[23:45:51.643] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-cxx20-e5a0ec7f:8911903818594687040:12:0)
[23:45:51.662] Build status: STARTED
[23:45:51.662] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-cxx20-e5a0ec7f:8911903818594687040:13:0) after 8m9s
[23:54:00.679] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-cxx20-e5a0ec7f:8911903818594687040:13:0)
[23:54:00.696] Build status: STARTED
[23:54:00.696] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-cxx20-e5a0ec7f:8911903818594687040:14:0) after 8m47s
[00:02:47.773] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-cxx20-e5a0ec7f:8911903818594687040:14:0)
[00:02:47.793] Build status: STARTED
[00:02:47.793] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-cxx20-e5a0ec7f:8911903818594687040:15:0) after 9m25s
[00:12:12.853] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-cxx20-e5a0ec7f:8911903818594687040:15:0)
[00:12:12.872] Build status: STARTED
[00:12:12.872] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-cxx20-e5a0ec7f:8911903818594687040:16:0) after 4m5s
[00:16:17.960] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-cxx20-e5a0ec7f:8911903818594687040:16:0)
[00:16:17.979] Build status: STARTED
[00:16:17.979] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-cxx20-e5a0ec7f:8911903818594687040:17:0) after 6m27s
[00:22:45.003] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-cxx20-e5a0ec7f:8911903818594687040:17:0)
[00:22:45.020] Build status: STARTED
[00:22:45.020] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-cxx20-e5a0ec7f:8911903818594687040:18:0) after 6m30s
[00:24:07.238] Received PubSub notification, asking Buildbucket for the build status
[00:24:07.254] Build:
{
"id": "8746473755247661953",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.arm64-cxx20"
},
"createdBy": "project:fuchsia",
"createTime": "2024-05-30T22:54:02.706377004Z",
"startTime": "2024-05-30T22:54:28.323343852Z",
"endTime": "2024-05-31T00:24:05.788906823Z",
"updateTime": "2024-05-31T00:24:05.788906823Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "8d7dbe8098fbeebb41704866d96481b467dc367a",
"ref": "refs/heads/main"
}
}
}
[00:24:07.254] Invocation finished in 1h30m7.123314952s with status SUCCEEDED