[21:39:06.799] New invocation is queued and will start shortly
[21:39:07.910] Starting the invocation (attempt 1)
[21:39:07.940] Popped buildset tag "buildset:commit/gitiles/fuchsia.googlesource.com/fuchsia/+/953d39dbb7a33044cc60e7e4f7c46566b3acb652"
[21:39:07.940] Popped gitiles commit info from properties and tags
[21:39:07.940] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[21:39:07.940] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[21:39:07.943] Buildbucket request:
{
"requestId": "8899768536706369824",
"builder": {
"project": "fuchsia",
"bucket": "ci",
"builder": "core.arm64-profile-qemu_kvm"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8899768536706369824",
"job": "fuchsia/core.arm64-profile-qemu_kvm-af244ac6",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "28a4e22f26d46cb23b7b9ca4ff447b038d9a59fd"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@28a4e22f26d46cb23b7b9ca4ff447b038d9a59fd",
"title": "28a4e22f26d46cb23b7b9ca4ff447b038d9a59fd",
"url": "https://fuchsia.googlesource.com/fuchsia/+/28a4e22f26d46cb23b7b9ca4ff447b038d9a59fd"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "1472182aecf7b0e4c5aed3461f8c95cbe677dbec"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@1472182aecf7b0e4c5aed3461f8c95cbe677dbec",
"title": "1472182aecf7b0e4c5aed3461f8c95cbe677dbec",
"url": "https://fuchsia.googlesource.com/fuchsia/+/1472182aecf7b0e4c5aed3461f8c95cbe677dbec"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "3210eed66f6cb0ba28a971e894e5772fc42a15ab"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@3210eed66f6cb0ba28a971e894e5772fc42a15ab",
"title": "3210eed66f6cb0ba28a971e894e5772fc42a15ab",
"url": "https://fuchsia.googlesource.com/fuchsia/+/3210eed66f6cb0ba28a971e894e5772fc42a15ab"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "2685bdb7b9dfa9267405bacd3d4964ce76ca4edc"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@2685bdb7b9dfa9267405bacd3d4964ce76ca4edc",
"title": "2685bdb7b9dfa9267405bacd3d4964ce76ca4edc",
"url": "https://fuchsia.googlesource.com/fuchsia/+/2685bdb7b9dfa9267405bacd3d4964ce76ca4edc"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "ad0dd557860055f2864e3b11e6eaccadcd343dbe"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@ad0dd557860055f2864e3b11e6eaccadcd343dbe",
"title": "ad0dd557860055f2864e3b11e6eaccadcd343dbe",
"url": "https://fuchsia.googlesource.com/fuchsia/+/ad0dd557860055f2864e3b11e6eaccadcd343dbe"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "dd9f9e2f9d0b50645a3d9ac1c425e86568b3f91b"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@dd9f9e2f9d0b50645a3d9ac1c425e86568b3f91b",
"title": "dd9f9e2f9d0b50645a3d9ac1c425e86568b3f91b",
"url": "https://fuchsia.googlesource.com/fuchsia/+/dd9f9e2f9d0b50645a3d9ac1c425e86568b3f91b"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "e0470f3f176b58d35978cae392bc7ce063d7bf88"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@e0470f3f176b58d35978cae392bc7ce063d7bf88",
"title": "e0470f3f176b58d35978cae392bc7ce063d7bf88",
"url": "https://fuchsia.googlesource.com/fuchsia/+/e0470f3f176b58d35978cae392bc7ce063d7bf88"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "1335eb66bbf888b436ce16ffbb5c7fd78dafa885"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@1335eb66bbf888b436ce16ffbb5c7fd78dafa885",
"title": "1335eb66bbf888b436ce16ffbb5c7fd78dafa885",
"url": "https://fuchsia.googlesource.com/fuchsia/+/1335eb66bbf888b436ce16ffbb5c7fd78dafa885"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "d61c4419c3897e495df991f761e69e32c135830c"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@d61c4419c3897e495df991f761e69e32c135830c",
"title": "d61c4419c3897e495df991f761e69e32c135830c",
"url": "https://fuchsia.googlesource.com/fuchsia/+/d61c4419c3897e495df991f761e69e32c135830c"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "f2a7ec8f57b714c39fa0734d56c7c61d65d4c1bf"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@f2a7ec8f57b714c39fa0734d56c7c61d65d4c1bf",
"title": "f2a7ec8f57b714c39fa0734d56c7c61d65d4c1bf",
"url": "https://fuchsia.googlesource.com/fuchsia/+/f2a7ec8f57b714c39fa0734d56c7c61d65d4c1bf"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "d6e20332ff4cfb4402d99c4bbde6c2263f546e04"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@d6e20332ff4cfb4402d99c4bbde6c2263f546e04",
"title": "d6e20332ff4cfb4402d99c4bbde6c2263f546e04",
"url": "https://fuchsia.googlesource.com/fuchsia/+/d6e20332ff4cfb4402d99c4bbde6c2263f546e04"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "697c2943dbf5c225239d2ec5cef796a6ad96086f"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@697c2943dbf5c225239d2ec5cef796a6ad96086f",
"title": "697c2943dbf5c225239d2ec5cef796a6ad96086f",
"url": "https://fuchsia.googlesource.com/fuchsia/+/697c2943dbf5c225239d2ec5cef796a6ad96086f"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "af24f7792dfbf366b09005a98ec3412078e50080"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@af24f7792dfbf366b09005a98ec3412078e50080",
"title": "af24f7792dfbf366b09005a98ec3412078e50080",
"url": "https://fuchsia.googlesource.com/fuchsia/+/af24f7792dfbf366b09005a98ec3412078e50080"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "09da588e01564037e03e396130a9036381575e0a"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@09da588e01564037e03e396130a9036381575e0a",
"title": "09da588e01564037e03e396130a9036381575e0a",
"url": "https://fuchsia.googlesource.com/fuchsia/+/09da588e01564037e03e396130a9036381575e0a"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "e4f6719b695d6272b6421d5d6f4ae04910f36954"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@e4f6719b695d6272b6421d5d6f4ae04910f36954",
"title": "e4f6719b695d6272b6421d5d6f4ae04910f36954",
"url": "https://fuchsia.googlesource.com/fuchsia/+/e4f6719b695d6272b6421d5d6f4ae04910f36954"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "0d230f44cb80b47fcc9253945567dbc3608616d2"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@0d230f44cb80b47fcc9253945567dbc3608616d2",
"title": "0d230f44cb80b47fcc9253945567dbc3608616d2",
"url": "https://fuchsia.googlesource.com/fuchsia/+/0d230f44cb80b47fcc9253945567dbc3608616d2"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "953d39dbb7a33044cc60e7e4f7c46566b3acb652"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@953d39dbb7a33044cc60e7e4f7c46566b3acb652",
"title": "953d39dbb7a33044cc60e7e4f7c46566b3acb652",
"url": "https://fuchsia.googlesource.com/fuchsia/+/953d39dbb7a33044cc60e7e4f7c46566b3acb652"
}
]
}
},
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "fuchsia",
"id": "953d39dbb7a33044cc60e7e4f7c46566b3acb652",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8899768536706369824"
},
{
"key": "scheduler_job_id",
"value": "fuchsia/core.arm64-profile-qemu_kvm-af244ac6"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[21:39:08.784] Scheduled build:
{
"id": "8734338474716441201",
"builder": {
"project": "fuchsia",
"bucket": "ci",
"builder": "core.arm64-profile-qemu_kvm"
},
"createdBy": "project:fuchsia",
"createTime": "2024-10-11T21:39:08.071345639Z",
"updateTime": "2024-10-11T21:39:08.071345639Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "fuchsia",
"id": "953d39dbb7a33044cc60e7e4f7c46566b3acb652",
"ref": "refs/heads/main"
}
}
}
[21:39:08.784] Task URL: https://cr-buildbucket.appspot.com/build/8734338474716441201
[21:39:08.784] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8899768536706369824:2:0) after 8m56s
[21:39:09.738] Received PubSub notification, asking Buildbucket for the build status
[21:39:09.754] Build status: SCHEDULED
[21:39:16.942] Received PubSub notification, asking Buildbucket for the build status
[21:39:16.959] Build status: STARTED
[21:48:04.851] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8899768536706369824:2:0)
[21:48:04.870] Build status: STARTED
[21:48:04.870] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8899768536706369824:5:0) after 7m54s
[21:55:59.016] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8899768536706369824:5:0)
[21:55:59.038] Build status: STARTED
[21:55:59.038] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8899768536706369824:6:0) after 7m27s
[22:03:26.152] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8899768536706369824:6:0)
[22:03:26.169] Build status: STARTED
[22:03:26.169] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8899768536706369824:7:0) after 9m42s
[22:13:08.194] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8899768536706369824:7:0)
[22:13:08.222] Build status: STARTED
[22:13:08.222] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8899768536706369824:8:0) after 1m25s
[22:14:33.238] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8899768536706369824:8:0)
[22:14:33.262] Build status: STARTED
[22:14:33.262] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8899768536706369824:9:0) after 1m58s
[22:16:31.279] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8899768536706369824:9:0)
[22:16:31.299] Build status: STARTED
[22:16:31.299] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8899768536706369824:10:0) after 6m0s
[22:22:31.315] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8899768536706369824:10:0)
[22:22:31.335] Build status: STARTED
[22:22:31.335] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8899768536706369824:11:0) after 7m37s
[22:30:08.312] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8899768536706369824:11:0)
[22:30:08.334] Build status: STARTED
[22:30:08.334] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8899768536706369824:12:0) after 9m9s
[22:39:17.420] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8899768536706369824:12:0)
[22:39:17.443] Build status: STARTED
[22:39:17.443] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8899768536706369824:13:0) after 4m7s
[22:43:24.461] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8899768536706369824:13:0)
[22:43:24.512] Build status: STARTED
[22:43:24.512] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8899768536706369824:14:0) after 6m27s
[22:49:51.567] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8899768536706369824:14:0)
[22:49:51.584] Build status: STARTED
[22:49:51.584] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8899768536706369824:15:0) after 5m4s
[22:54:55.600] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8899768536706369824:15:0)
[22:54:55.694] Build status: STARTED
[22:54:55.694] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8899768536706369824:16:0) after 3m28s
[22:58:23.728] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8899768536706369824:16:0)
[22:58:23.747] Build status: STARTED
[22:58:23.747] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8899768536706369824:17:0) after 1m52s
[23:00:15.763] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8899768536706369824:17:0)
[23:00:15.793] Build status: STARTED
[23:00:15.793] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8899768536706369824:18:0) after 3m0s
[23:03:15.814] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8899768536706369824:18:0)
[23:03:15.829] Build status: STARTED
[23:03:15.829] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8899768536706369824:19:0) after 2m32s
[23:05:47.892] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8899768536706369824:19:0)
[23:05:47.914] Build status: STARTED
[23:05:47.914] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8899768536706369824:20:0) after 5m46s
[23:11:33.984] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8899768536706369824:20:0)
[23:11:34.000] Build status: STARTED
[23:11:34.000] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8899768536706369824:21:0) after 8m1s
[23:16:33.265] Received PubSub notification, asking Buildbucket for the build status
[23:16:33.372] Build:
{
"id": "8734338474716441201",
"builder": {
"project": "fuchsia",
"bucket": "ci",
"builder": "core.arm64-profile-qemu_kvm"
},
"createdBy": "project:fuchsia",
"createTime": "2024-10-11T21:39:08.071345639Z",
"startTime": "2024-10-11T21:39:14.007176808Z",
"endTime": "2024-10-11T23:16:32.629764860Z",
"updateTime": "2024-10-11T23:16:32.629764860Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "fuchsia",
"id": "953d39dbb7a33044cc60e7e4f7c46566b3acb652",
"ref": "refs/heads/main"
}
}
}
[23:16:33.372] Invocation finished in 1h37m26.582384447s with status SUCCEEDED