[23:34:33.135] New invocation is queued and will start shortly
[23:34:34.453] Starting the invocation (attempt 1)
[23:34:34.483] Popped buildset tag "buildset:commit/gitiles/fuchsia.googlesource.com/fuchsia/+/c749cd2a0088b58fbb4e764c2d69f6cb68ad3ce8"
[23:34:34.483] Popped gitiles commit info from properties and tags
[23:34:34.483] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[23:34:34.483] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[23:34:34.484] Buildbucket request:
{
"requestId": "8900667243578888544",
"builder": {
"project": "fuchsia",
"bucket": "ci",
"builder": "core.arm64-profile-qemu_kvm"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8900667243578888544",
"job": "fuchsia/core.arm64-profile-qemu_kvm-af244ac6",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "11bf06dd26e9d2acb26dad1b20ed68e2a0bfa805"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@11bf06dd26e9d2acb26dad1b20ed68e2a0bfa805",
"title": "11bf06dd26e9d2acb26dad1b20ed68e2a0bfa805",
"url": "https://fuchsia.googlesource.com/fuchsia/+/11bf06dd26e9d2acb26dad1b20ed68e2a0bfa805"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "97d0ad68142f15f075454f937ee1da64429b587b"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@97d0ad68142f15f075454f937ee1da64429b587b",
"title": "97d0ad68142f15f075454f937ee1da64429b587b",
"url": "https://fuchsia.googlesource.com/fuchsia/+/97d0ad68142f15f075454f937ee1da64429b587b"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "4bf9c1a3047d12e21a47e6c9b33af1dcc31977ed"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@4bf9c1a3047d12e21a47e6c9b33af1dcc31977ed",
"title": "4bf9c1a3047d12e21a47e6c9b33af1dcc31977ed",
"url": "https://fuchsia.googlesource.com/fuchsia/+/4bf9c1a3047d12e21a47e6c9b33af1dcc31977ed"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "c7f216f551a9af3eeeb949ad6f4c095fb01c5138"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@c7f216f551a9af3eeeb949ad6f4c095fb01c5138",
"title": "c7f216f551a9af3eeeb949ad6f4c095fb01c5138",
"url": "https://fuchsia.googlesource.com/fuchsia/+/c7f216f551a9af3eeeb949ad6f4c095fb01c5138"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "652ab24a6b55097b80178e7308c9301a9a6676f0"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@652ab24a6b55097b80178e7308c9301a9a6676f0",
"title": "652ab24a6b55097b80178e7308c9301a9a6676f0",
"url": "https://fuchsia.googlesource.com/fuchsia/+/652ab24a6b55097b80178e7308c9301a9a6676f0"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "2d0853d4266593a6937e794bedc3321e92487ebc"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@2d0853d4266593a6937e794bedc3321e92487ebc",
"title": "2d0853d4266593a6937e794bedc3321e92487ebc",
"url": "https://fuchsia.googlesource.com/fuchsia/+/2d0853d4266593a6937e794bedc3321e92487ebc"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "3d6915d42e3ae676ec98ee2f1c75c37a63febc5e"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@3d6915d42e3ae676ec98ee2f1c75c37a63febc5e",
"title": "3d6915d42e3ae676ec98ee2f1c75c37a63febc5e",
"url": "https://fuchsia.googlesource.com/fuchsia/+/3d6915d42e3ae676ec98ee2f1c75c37a63febc5e"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "306457c2a5a776201ad2c70355a1beb230094f00"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@306457c2a5a776201ad2c70355a1beb230094f00",
"title": "306457c2a5a776201ad2c70355a1beb230094f00",
"url": "https://fuchsia.googlesource.com/fuchsia/+/306457c2a5a776201ad2c70355a1beb230094f00"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "6674c9f52a481f3f5a6bc01d4e9d329f12d3b049"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@6674c9f52a481f3f5a6bc01d4e9d329f12d3b049",
"title": "6674c9f52a481f3f5a6bc01d4e9d329f12d3b049",
"url": "https://fuchsia.googlesource.com/fuchsia/+/6674c9f52a481f3f5a6bc01d4e9d329f12d3b049"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "8e4abb5966bad72c04b1e846a6db213c71152faa"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@8e4abb5966bad72c04b1e846a6db213c71152faa",
"title": "8e4abb5966bad72c04b1e846a6db213c71152faa",
"url": "https://fuchsia.googlesource.com/fuchsia/+/8e4abb5966bad72c04b1e846a6db213c71152faa"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "c2f3418943882315fad329d9a1a0ff1603fabd56"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@c2f3418943882315fad329d9a1a0ff1603fabd56",
"title": "c2f3418943882315fad329d9a1a0ff1603fabd56",
"url": "https://fuchsia.googlesource.com/fuchsia/+/c2f3418943882315fad329d9a1a0ff1603fabd56"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "8922df6f5c7d594b6a921105b0d5b2ca605c1356"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@8922df6f5c7d594b6a921105b0d5b2ca605c1356",
"title": "8922df6f5c7d594b6a921105b0d5b2ca605c1356",
"url": "https://fuchsia.googlesource.com/fuchsia/+/8922df6f5c7d594b6a921105b0d5b2ca605c1356"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "0864b5465639994a3a5de8c445dc37b6555087c5"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@0864b5465639994a3a5de8c445dc37b6555087c5",
"title": "0864b5465639994a3a5de8c445dc37b6555087c5",
"url": "https://fuchsia.googlesource.com/fuchsia/+/0864b5465639994a3a5de8c445dc37b6555087c5"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "c749cd2a0088b58fbb4e764c2d69f6cb68ad3ce8"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@c749cd2a0088b58fbb4e764c2d69f6cb68ad3ce8",
"title": "c749cd2a0088b58fbb4e764c2d69f6cb68ad3ce8",
"url": "https://fuchsia.googlesource.com/fuchsia/+/c749cd2a0088b58fbb4e764c2d69f6cb68ad3ce8"
}
]
}
},
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "fuchsia",
"id": "c749cd2a0088b58fbb4e764c2d69f6cb68ad3ce8",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8900667243578888544"
},
{
"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"
}
}
[23:34:34.937] Scheduled build:
{
"id": "8735237181441060913",
"builder": {
"project": "fuchsia",
"bucket": "ci",
"builder": "core.arm64-profile-qemu_kvm"
},
"createdBy": "project:fuchsia",
"createTime": "2024-10-01T23:34:34.550059342Z",
"updateTime": "2024-10-01T23:34:34.550059342Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "fuchsia",
"id": "c749cd2a0088b58fbb4e764c2d69f6cb68ad3ce8",
"ref": "refs/heads/main"
}
}
}
[23:34:34.937] Task URL: https://cr-buildbucket.appspot.com/build/8735237181441060913
[23:34:34.937] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8900667243578888544:2:0) after 2m47s
[23:34:35.836] Received PubSub notification, asking Buildbucket for the build status
[23:34:35.859] Build status: SCHEDULED
[23:34:40.002] Received PubSub notification, asking Buildbucket for the build status
[23:34:40.027] Build status: STARTED
[23:37:21.998] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8900667243578888544:2:0)
[23:37:22.018] Build status: STARTED
[23:37:22.018] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8900667243578888544:5:0) after 7m20s
[23:44:42.051] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8900667243578888544:5:0)
[23:44:42.069] Build status: STARTED
[23:44:42.069] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8900667243578888544:6:0) after 9m2s
[23:53:44.085] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8900667243578888544:6:0)
[23:53:44.111] Build status: STARTED
[23:53:44.111] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8900667243578888544:7:0) after 5m57s
[23:59:41.113] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8900667243578888544:7:0)
[23:59:41.206] Build status: STARTED
[23:59:41.206] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8900667243578888544:8:0) after 6m28s
[00:06:09.256] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8900667243578888544:8:0)
[00:06:09.274] Build status: STARTED
[00:06:09.274] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8900667243578888544:9:0) after 4m22s
[00:10:31.293] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8900667243578888544:9:0)
[00:10:31.319] Build status: STARTED
[00:10:31.319] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8900667243578888544:10:0) after 2m30s
[00:13:01.338] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8900667243578888544:10:0)
[00:13:01.365] Build status: STARTED
[00:13:01.365] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8900667243578888544:11:0) after 9m28s
[00:22:29.444] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8900667243578888544:11:0)
[00:22:29.467] Build status: STARTED
[00:22:29.468] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8900667243578888544:12:0) after 4m28s
[00:26:57.539] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8900667243578888544:12:0)
[00:26:57.563] Build status: STARTED
[00:26:57.563] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8900667243578888544:13:0) after 6m30s
[00:33:27.582] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8900667243578888544:13:0)
[00:33:27.604] Build status: STARTED
[00:33:27.604] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8900667243578888544:14:0) after 7m43s
[00:41:10.802] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8900667243578888544:14:0)
[00:41:10.833] Build status: STARTED
[00:41:10.833] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8900667243578888544:15:0) after 5m53s
[00:47:04.004] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8900667243578888544:15:0)
[00:47:04.054] Build status: STARTED
[00:47:04.054] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8900667243578888544:16:0) after 5m26s
[00:52:30.130] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8900667243578888544:16:0)
[00:52:30.151] Build status: STARTED
[00:52:30.151] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8900667243578888544:17:0) after 8m14s
[01:00:44.167] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8900667243578888544:17:0)
[01:00:44.185] Build status: STARTED
[01:00:44.185] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8900667243578888544:18:0) after 5m39s
[01:06:23.254] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8900667243578888544:18:0)
[01:06:23.275] Build status: STARTED
[01:06:23.275] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8900667243578888544:19:0) after 2m33s
[01:08:56.351] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8900667243578888544:19:0)
[01:08:56.855] Build status: STARTED
[01:08:56.855] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8900667243578888544:20:0) after 4m27s
[01:13:23.906] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8900667243578888544:20:0)
[01:13:23.922] Build status: STARTED
[01:13:23.922] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8900667243578888544:21:0) after 5m39s
[01:19:02.971] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8900667243578888544:21:0)
[01:19:03.055] Build status: STARTED
[01:19:03.055] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8900667243578888544:22:0) after 1m30s
[01:20:33.078] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8900667243578888544:22:0)
[01:20:33.098] Build status: STARTED
[01:20:33.098] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8900667243578888544:23:0) after 9m11s
[01:22:05.027] Received PubSub notification, asking Buildbucket for the build status
[01:22:05.050] Build:
{
"id": "8735237181441060913",
"builder": {
"project": "fuchsia",
"bucket": "ci",
"builder": "core.arm64-profile-qemu_kvm"
},
"createdBy": "project:fuchsia",
"createTime": "2024-10-01T23:34:34.550059342Z",
"startTime": "2024-10-01T23:34:39.785667392Z",
"endTime": "2024-10-02T01:22:04.447045134Z",
"updateTime": "2024-10-02T01:22:04.447045134Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "fuchsia",
"id": "c749cd2a0088b58fbb4e764c2d69f6cb68ad3ce8",
"ref": "refs/heads/main"
}
}
}
[01:22:05.050] Invocation finished in 1h47m31.922167118s with status SUCCEEDED