[21:15:59.075] New invocation is queued and will start shortly
[21:16:00.994] Starting the invocation (attempt 1)
[21:16:01.032] Popped buildset tag "buildset:commit/gitiles/fuchsia.googlesource.com/integration/+/413f44a796deee7497059c137e18d15acb2e13b1"
[21:16:01.032] Popped gitiles commit info from properties and tags
[21:16:01.032] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[21:16:01.032] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[21:16:01.032] Buildbucket request:
{
"requestId": "8910732224780418480",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "sdk-core-linux"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8910732224780418480",
"job": "fuchsia/sdk-core-linux-e5a0ec7f",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "e3fee2a0b7231a5c3c773263556d1bdd84ce7b0a"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@e3fee2a0b7231a5c3c773263556d1bdd84ce7b0a",
"title": "e3fee2a0b7231a5c3c773263556d1bdd84ce7b0a",
"url": "https://fuchsia.googlesource.com/integration/+/e3fee2a0b7231a5c3c773263556d1bdd84ce7b0a"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "bb630ab3b8ffbc6125eda02a97e8737fa87e7647"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@bb630ab3b8ffbc6125eda02a97e8737fa87e7647",
"title": "bb630ab3b8ffbc6125eda02a97e8737fa87e7647",
"url": "https://fuchsia.googlesource.com/integration/+/bb630ab3b8ffbc6125eda02a97e8737fa87e7647"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "3f9f288147273473ac16a0dca4c398bfbe76927b"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@3f9f288147273473ac16a0dca4c398bfbe76927b",
"title": "3f9f288147273473ac16a0dca4c398bfbe76927b",
"url": "https://fuchsia.googlesource.com/integration/+/3f9f288147273473ac16a0dca4c398bfbe76927b"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "ec03f71257f47cb16a3ae55a42e879bafa3f02f8"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@ec03f71257f47cb16a3ae55a42e879bafa3f02f8",
"title": "ec03f71257f47cb16a3ae55a42e879bafa3f02f8",
"url": "https://fuchsia.googlesource.com/integration/+/ec03f71257f47cb16a3ae55a42e879bafa3f02f8"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "d21f87f16c72d3d048fbabf1aea3b8e07febe33a"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@d21f87f16c72d3d048fbabf1aea3b8e07febe33a",
"title": "d21f87f16c72d3d048fbabf1aea3b8e07febe33a",
"url": "https://fuchsia.googlesource.com/integration/+/d21f87f16c72d3d048fbabf1aea3b8e07febe33a"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "e46cd0bdf2eb48968dfe4b5028a48c15a7cc0b41"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@e46cd0bdf2eb48968dfe4b5028a48c15a7cc0b41",
"title": "e46cd0bdf2eb48968dfe4b5028a48c15a7cc0b41",
"url": "https://fuchsia.googlesource.com/integration/+/e46cd0bdf2eb48968dfe4b5028a48c15a7cc0b41"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "9e170fb461e15b2d51aa91c1f3fc287f7dd3ba31"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@9e170fb461e15b2d51aa91c1f3fc287f7dd3ba31",
"title": "9e170fb461e15b2d51aa91c1f3fc287f7dd3ba31",
"url": "https://fuchsia.googlesource.com/integration/+/9e170fb461e15b2d51aa91c1f3fc287f7dd3ba31"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "a6d2f779c0680a42c09c466a34d57af7df427d66"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@a6d2f779c0680a42c09c466a34d57af7df427d66",
"title": "a6d2f779c0680a42c09c466a34d57af7df427d66",
"url": "https://fuchsia.googlesource.com/integration/+/a6d2f779c0680a42c09c466a34d57af7df427d66"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "9ecc9f3fd18d097b8843c6791aef7d574ba0142d"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@9ecc9f3fd18d097b8843c6791aef7d574ba0142d",
"title": "9ecc9f3fd18d097b8843c6791aef7d574ba0142d",
"url": "https://fuchsia.googlesource.com/integration/+/9ecc9f3fd18d097b8843c6791aef7d574ba0142d"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "d39a6b319a15ce3a30c2127708d1d07ba6c743ef"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@d39a6b319a15ce3a30c2127708d1d07ba6c743ef",
"title": "d39a6b319a15ce3a30c2127708d1d07ba6c743ef",
"url": "https://fuchsia.googlesource.com/integration/+/d39a6b319a15ce3a30c2127708d1d07ba6c743ef"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "1aac14d11d82793cd6512eb336eaa17941f66a47"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@1aac14d11d82793cd6512eb336eaa17941f66a47",
"title": "1aac14d11d82793cd6512eb336eaa17941f66a47",
"url": "https://fuchsia.googlesource.com/integration/+/1aac14d11d82793cd6512eb336eaa17941f66a47"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "8e52917fe24feb3bc2cfece17618056ee95dc0a4"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@8e52917fe24feb3bc2cfece17618056ee95dc0a4",
"title": "8e52917fe24feb3bc2cfece17618056ee95dc0a4",
"url": "https://fuchsia.googlesource.com/integration/+/8e52917fe24feb3bc2cfece17618056ee95dc0a4"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "4cd0150293480ef5849cdcceca0fcb8c7f6b602e"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@4cd0150293480ef5849cdcceca0fcb8c7f6b602e",
"title": "4cd0150293480ef5849cdcceca0fcb8c7f6b602e",
"url": "https://fuchsia.googlesource.com/integration/+/4cd0150293480ef5849cdcceca0fcb8c7f6b602e"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "6c1df28e7f85e7c32ad2208c9858da9daddd7539"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@6c1df28e7f85e7c32ad2208c9858da9daddd7539",
"title": "6c1df28e7f85e7c32ad2208c9858da9daddd7539",
"url": "https://fuchsia.googlesource.com/integration/+/6c1df28e7f85e7c32ad2208c9858da9daddd7539"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "6b881cee772f08795db9084539fc8c290d5d61ad"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@6b881cee772f08795db9084539fc8c290d5d61ad",
"title": "6b881cee772f08795db9084539fc8c290d5d61ad",
"url": "https://fuchsia.googlesource.com/integration/+/6b881cee772f08795db9084539fc8c290d5d61ad"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "413f44a796deee7497059c137e18d15acb2e13b1"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@413f44a796deee7497059c137e18d15acb2e13b1",
"title": "413f44a796deee7497059c137e18d15acb2e13b1",
"url": "https://fuchsia.googlesource.com/integration/+/413f44a796deee7497059c137e18d15acb2e13b1"
}
]
}
},
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "413f44a796deee7497059c137e18d15acb2e13b1",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8910732224780418480"
},
{
"key": "scheduler_job_id",
"value": "fuchsia/sdk-core-linux-e5a0ec7f"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[21:16:01.590] Scheduled build:
{
"id": "8745302161968883617",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "sdk-core-linux"
},
"createdBy": "project:fuchsia",
"createTime": "2024-06-12T21:16:01.126991379Z",
"updateTime": "2024-06-12T21:16:01.126991379Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "413f44a796deee7497059c137e18d15acb2e13b1",
"ref": "refs/heads/main"
}
}
}
[21:16:01.590] Task URL: https://cr-buildbucket.appspot.com/build/8745302161968883617
[21:16:01.590] Scheduling timer "check-buildbucket-build-status" (fuchsia/sdk-core-linux-e5a0ec7f:8910732224780418480:2:0) after 8m38s
[21:16:01.692] Received PubSub notification, asking Buildbucket for the build status
[21:16:01.761] Build status: SCHEDULED
[21:16:07.645] Received PubSub notification, asking Buildbucket for the build status
[21:16:07.668] Build status: STARTED
[21:24:39.607] Handling timer "check-buildbucket-build-status" (fuchsia/sdk-core-linux-e5a0ec7f:8910732224780418480:2:0)
[21:24:39.630] Build status: STARTED
[21:24:39.630] Scheduling timer "check-buildbucket-build-status" (fuchsia/sdk-core-linux-e5a0ec7f:8910732224780418480:5:0) after 3m8s
[21:27:47.681] Handling timer "check-buildbucket-build-status" (fuchsia/sdk-core-linux-e5a0ec7f:8910732224780418480:5:0)
[21:27:47.750] Build status: STARTED
[21:27:47.750] Scheduling timer "check-buildbucket-build-status" (fuchsia/sdk-core-linux-e5a0ec7f:8910732224780418480:6:0) after 1m24s
[21:29:11.872] Handling timer "check-buildbucket-build-status" (fuchsia/sdk-core-linux-e5a0ec7f:8910732224780418480:6:0)
[21:29:11.906] Build status: STARTED
[21:29:11.906] Scheduling timer "check-buildbucket-build-status" (fuchsia/sdk-core-linux-e5a0ec7f:8910732224780418480:7:0) after 7m44s
[21:36:55.930] Handling timer "check-buildbucket-build-status" (fuchsia/sdk-core-linux-e5a0ec7f:8910732224780418480:7:0)
[21:36:55.951] Build status: STARTED
[21:36:55.951] Scheduling timer "check-buildbucket-build-status" (fuchsia/sdk-core-linux-e5a0ec7f:8910732224780418480:8:0) after 4m45s
[21:41:40.996] Handling timer "check-buildbucket-build-status" (fuchsia/sdk-core-linux-e5a0ec7f:8910732224780418480:8:0)
[21:41:41.050] Build status: STARTED
[21:41:41.050] Scheduling timer "check-buildbucket-build-status" (fuchsia/sdk-core-linux-e5a0ec7f:8910732224780418480:9:0) after 3m40s
[21:45:21.073] Handling timer "check-buildbucket-build-status" (fuchsia/sdk-core-linux-e5a0ec7f:8910732224780418480:9:0)
[21:45:21.109] Build status: STARTED
[21:45:21.109] Scheduling timer "check-buildbucket-build-status" (fuchsia/sdk-core-linux-e5a0ec7f:8910732224780418480:10:0) after 7m24s
[21:52:45.408] Handling timer "check-buildbucket-build-status" (fuchsia/sdk-core-linux-e5a0ec7f:8910732224780418480:10:0)
[21:52:45.429] Build status: STARTED
[21:52:45.429] Scheduling timer "check-buildbucket-build-status" (fuchsia/sdk-core-linux-e5a0ec7f:8910732224780418480:11:0) after 3m20s
[21:56:05.559] Handling timer "check-buildbucket-build-status" (fuchsia/sdk-core-linux-e5a0ec7f:8910732224780418480:11:0)
[21:56:05.580] Build status: STARTED
[21:56:05.580] Scheduling timer "check-buildbucket-build-status" (fuchsia/sdk-core-linux-e5a0ec7f:8910732224780418480:12:0) after 1m37s
[21:57:42.650] Handling timer "check-buildbucket-build-status" (fuchsia/sdk-core-linux-e5a0ec7f:8910732224780418480:12:0)
[21:57:42.671] Build status: STARTED
[21:57:42.671] Scheduling timer "check-buildbucket-build-status" (fuchsia/sdk-core-linux-e5a0ec7f:8910732224780418480:13:0) after 6m29s
[22:04:11.662] Handling timer "check-buildbucket-build-status" (fuchsia/sdk-core-linux-e5a0ec7f:8910732224780418480:13:0)
[22:04:11.751] Build status: STARTED
[22:04:11.751] Scheduling timer "check-buildbucket-build-status" (fuchsia/sdk-core-linux-e5a0ec7f:8910732224780418480:14:0) after 5m59s
[22:10:10.855] Handling timer "check-buildbucket-build-status" (fuchsia/sdk-core-linux-e5a0ec7f:8910732224780418480:14:0)
[22:10:10.873] Build status: STARTED
[22:10:10.874] Scheduling timer "check-buildbucket-build-status" (fuchsia/sdk-core-linux-e5a0ec7f:8910732224780418480:15:0) after 1m7s
[22:11:17.999] Handling timer "check-buildbucket-build-status" (fuchsia/sdk-core-linux-e5a0ec7f:8910732224780418480:15:0)
[22:11:18.019] Build status: STARTED
[22:11:18.019] Scheduling timer "check-buildbucket-build-status" (fuchsia/sdk-core-linux-e5a0ec7f:8910732224780418480:16:0) after 3m26s
[22:14:44.084] Handling timer "check-buildbucket-build-status" (fuchsia/sdk-core-linux-e5a0ec7f:8910732224780418480:16:0)
[22:14:44.181] Build status: STARTED
[22:14:44.181] Scheduling timer "check-buildbucket-build-status" (fuchsia/sdk-core-linux-e5a0ec7f:8910732224780418480:17:0) after 5m27s
[22:20:11.372] Handling timer "check-buildbucket-build-status" (fuchsia/sdk-core-linux-e5a0ec7f:8910732224780418480:17:0)
[22:20:11.388] Build status: STARTED
[22:20:11.388] Scheduling timer "check-buildbucket-build-status" (fuchsia/sdk-core-linux-e5a0ec7f:8910732224780418480:18:0) after 2m4s
[22:22:15.456] Handling timer "check-buildbucket-build-status" (fuchsia/sdk-core-linux-e5a0ec7f:8910732224780418480:18:0)
[22:22:15.474] Build status: STARTED
[22:22:15.474] Scheduling timer "check-buildbucket-build-status" (fuchsia/sdk-core-linux-e5a0ec7f:8910732224780418480:19:0) after 4m51s
[22:27:06.532] Handling timer "check-buildbucket-build-status" (fuchsia/sdk-core-linux-e5a0ec7f:8910732224780418480:19:0)
[22:27:06.549] Build status: STARTED
[22:27:06.549] Scheduling timer "check-buildbucket-build-status" (fuchsia/sdk-core-linux-e5a0ec7f:8910732224780418480:20:0) after 9m47s
[22:28:27.217] Received PubSub notification, asking Buildbucket for the build status
[22:28:27.253] Build:
{
"id": "8745302161968883617",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "sdk-core-linux"
},
"createdBy": "project:fuchsia",
"createTime": "2024-06-12T21:16:01.126991379Z",
"startTime": "2024-06-12T21:16:07.452757621Z",
"endTime": "2024-06-12T22:28:26.995323388Z",
"updateTime": "2024-06-12T22:28:26.995323388Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "413f44a796deee7497059c137e18d15acb2e13b1",
"ref": "refs/heads/main"
}
}
}
[22:28:27.253] Invocation finished in 1h12m28.191803034s with status SUCCEEDED