[21:05:26.642] New invocation is queued and will start shortly
[21:05:27.827] Starting the invocation (attempt 1)
[21:05:27.949] Popped buildset tag "buildset:commit/gitiles/fuchsia.googlesource.com/fuchsia/+/9c7da036ea7ca0729ee8c22f917790729d17ce05"
[21:05:27.949] Popped gitiles commit info from properties and tags
[21:05:27.949] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[21:05:27.951] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[21:05:27.952] Buildbucket request:
{
"requestId": "8900586027690357520",
"builder": {
"project": "fuchsia",
"bucket": "ci",
"builder": "core.arm64-profile-qemu_kvm"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8900586027690357520",
"job": "fuchsia/core.arm64-profile-qemu_kvm-af244ac6",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "446c0d4e53df381e57ab2de4151823401022a00d"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@446c0d4e53df381e57ab2de4151823401022a00d",
"title": "446c0d4e53df381e57ab2de4151823401022a00d",
"url": "https://fuchsia.googlesource.com/fuchsia/+/446c0d4e53df381e57ab2de4151823401022a00d"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "99f5266c28d9fca6942278d2f3114d1af0b7d910"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@99f5266c28d9fca6942278d2f3114d1af0b7d910",
"title": "99f5266c28d9fca6942278d2f3114d1af0b7d910",
"url": "https://fuchsia.googlesource.com/fuchsia/+/99f5266c28d9fca6942278d2f3114d1af0b7d910"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "6976914c8ed36301f92093e5d8701f5bc7368dc4"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@6976914c8ed36301f92093e5d8701f5bc7368dc4",
"title": "6976914c8ed36301f92093e5d8701f5bc7368dc4",
"url": "https://fuchsia.googlesource.com/fuchsia/+/6976914c8ed36301f92093e5d8701f5bc7368dc4"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "485af07906f30dc9e3c46ef4b537e5105bfbde75"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@485af07906f30dc9e3c46ef4b537e5105bfbde75",
"title": "485af07906f30dc9e3c46ef4b537e5105bfbde75",
"url": "https://fuchsia.googlesource.com/fuchsia/+/485af07906f30dc9e3c46ef4b537e5105bfbde75"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "ec26024075c245bea785e83cd55ab9e054ac80f2"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@ec26024075c245bea785e83cd55ab9e054ac80f2",
"title": "ec26024075c245bea785e83cd55ab9e054ac80f2",
"url": "https://fuchsia.googlesource.com/fuchsia/+/ec26024075c245bea785e83cd55ab9e054ac80f2"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "fcb90d5235b8fbd68f3d00e640610d7a800ee82d"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@fcb90d5235b8fbd68f3d00e640610d7a800ee82d",
"title": "fcb90d5235b8fbd68f3d00e640610d7a800ee82d",
"url": "https://fuchsia.googlesource.com/fuchsia/+/fcb90d5235b8fbd68f3d00e640610d7a800ee82d"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "d83e08e2a184b8f160e57cd8b5cb7825ccc5f82f"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@d83e08e2a184b8f160e57cd8b5cb7825ccc5f82f",
"title": "d83e08e2a184b8f160e57cd8b5cb7825ccc5f82f",
"url": "https://fuchsia.googlesource.com/fuchsia/+/d83e08e2a184b8f160e57cd8b5cb7825ccc5f82f"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "642a5af303d5f18f2d7851364bc0f198586e4a10"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@642a5af303d5f18f2d7851364bc0f198586e4a10",
"title": "642a5af303d5f18f2d7851364bc0f198586e4a10",
"url": "https://fuchsia.googlesource.com/fuchsia/+/642a5af303d5f18f2d7851364bc0f198586e4a10"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "1d293f2aadac5c26093ae9b118e4a0875a3555e4"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@1d293f2aadac5c26093ae9b118e4a0875a3555e4",
"title": "1d293f2aadac5c26093ae9b118e4a0875a3555e4",
"url": "https://fuchsia.googlesource.com/fuchsia/+/1d293f2aadac5c26093ae9b118e4a0875a3555e4"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "8889dc6ab8b752d13b73d6b5ce1ba2d24f5a3ea1"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@8889dc6ab8b752d13b73d6b5ce1ba2d24f5a3ea1",
"title": "8889dc6ab8b752d13b73d6b5ce1ba2d24f5a3ea1",
"url": "https://fuchsia.googlesource.com/fuchsia/+/8889dc6ab8b752d13b73d6b5ce1ba2d24f5a3ea1"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "4da66c4704701e3171b0362f91895e209062d4d0"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@4da66c4704701e3171b0362f91895e209062d4d0",
"title": "4da66c4704701e3171b0362f91895e209062d4d0",
"url": "https://fuchsia.googlesource.com/fuchsia/+/4da66c4704701e3171b0362f91895e209062d4d0"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "526281125460e361859ca5dbb82cf2e7664dc0ad"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@526281125460e361859ca5dbb82cf2e7664dc0ad",
"title": "526281125460e361859ca5dbb82cf2e7664dc0ad",
"url": "https://fuchsia.googlesource.com/fuchsia/+/526281125460e361859ca5dbb82cf2e7664dc0ad"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "894dfca6bfca281b6c466efd5b66ce3fc1b1d6de"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@894dfca6bfca281b6c466efd5b66ce3fc1b1d6de",
"title": "894dfca6bfca281b6c466efd5b66ce3fc1b1d6de",
"url": "https://fuchsia.googlesource.com/fuchsia/+/894dfca6bfca281b6c466efd5b66ce3fc1b1d6de"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "9cfc1de1bab6b84d948739cb697cd4ecdea0825f"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@9cfc1de1bab6b84d948739cb697cd4ecdea0825f",
"title": "9cfc1de1bab6b84d948739cb697cd4ecdea0825f",
"url": "https://fuchsia.googlesource.com/fuchsia/+/9cfc1de1bab6b84d948739cb697cd4ecdea0825f"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "53491311c7c132ab56134e8f7c7e2f7f97d4b067"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@53491311c7c132ab56134e8f7c7e2f7f97d4b067",
"title": "53491311c7c132ab56134e8f7c7e2f7f97d4b067",
"url": "https://fuchsia.googlesource.com/fuchsia/+/53491311c7c132ab56134e8f7c7e2f7f97d4b067"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "8cde64d837768012ea605d85cfa7b4c87e16fc2d"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@8cde64d837768012ea605d85cfa7b4c87e16fc2d",
"title": "8cde64d837768012ea605d85cfa7b4c87e16fc2d",
"url": "https://fuchsia.googlesource.com/fuchsia/+/8cde64d837768012ea605d85cfa7b4c87e16fc2d"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "9c7da036ea7ca0729ee8c22f917790729d17ce05"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@9c7da036ea7ca0729ee8c22f917790729d17ce05",
"title": "9c7da036ea7ca0729ee8c22f917790729d17ce05",
"url": "https://fuchsia.googlesource.com/fuchsia/+/9c7da036ea7ca0729ee8c22f917790729d17ce05"
}
]
}
},
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "fuchsia",
"id": "9c7da036ea7ca0729ee8c22f917790729d17ce05",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8900586027690357520"
},
{
"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:05:28.381] Scheduled build:
{
"id": "8735155965594970417",
"builder": {
"project": "fuchsia",
"bucket": "ci",
"builder": "core.arm64-profile-qemu_kvm"
},
"createdBy": "project:fuchsia",
"createTime": "2024-10-02T21:05:28.017637566Z",
"updateTime": "2024-10-02T21:05:28.017637566Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "fuchsia",
"id": "9c7da036ea7ca0729ee8c22f917790729d17ce05",
"ref": "refs/heads/main"
}
}
}
[21:05:28.381] Task URL: https://cr-buildbucket.appspot.com/build/8735155965594970417
[21:05:28.381] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8900586027690357520:2:0) after 5m35s
[21:05:28.460] Received PubSub notification, asking Buildbucket for the build status
[21:05:28.479] Build status: SCHEDULED
[21:05:34.268] Received PubSub notification, asking Buildbucket for the build status
[21:05:34.286] Build status: STARTED
[21:11:03.425] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8900586027690357520:2:0)
[21:11:03.465] Build status: STARTED
[21:11:03.465] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8900586027690357520:5:0) after 6m8s
[21:17:11.512] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8900586027690357520:5:0)
[21:17:11.591] Build status: STARTED
[21:17:11.591] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8900586027690357520:6:0) after 5m17s
[21:22:28.677] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8900586027690357520:6:0)
[21:22:28.707] Build status: STARTED
[21:22:28.707] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8900586027690357520:7:0) after 7m21s
[21:29:49.750] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8900586027690357520:7:0)
[21:29:49.778] Build status: STARTED
[21:29:49.778] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8900586027690357520:8:0) after 5m53s
[21:35:42.866] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8900586027690357520:8:0)
[21:35:42.881] Build status: STARTED
[21:35:42.881] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8900586027690357520:9:0) after 5m48s
[21:41:30.900] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8900586027690357520:9:0)
[21:41:30.923] Build status: STARTED
[21:41:30.923] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8900586027690357520:10:0) after 7m52s
[21:49:22.919] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8900586027690357520:10:0)
[21:49:22.941] Build status: STARTED
[21:49:22.941] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8900586027690357520:11:0) after 2m40s
[21:52:03.006] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8900586027690357520:11:0)
[21:52:03.045] Build status: STARTED
[21:52:03.045] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8900586027690357520:12:0) after 2m40s
[21:54:43.062] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8900586027690357520:12:0)
[21:54:43.078] Build status: STARTED
[21:54:43.078] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8900586027690357520:13:0) after 2m12s
[21:56:55.096] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8900586027690357520:13:0)
[21:56:55.115] Build status: STARTED
[21:56:55.115] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8900586027690357520:14:0) after 4m55s
[22:01:50.344] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8900586027690357520:14:0)
[22:01:50.380] Build status: STARTED
[22:01:50.380] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8900586027690357520:15:0) after 2m36s
[22:04:26.398] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8900586027690357520:15:0)
[22:04:26.417] Build status: STARTED
[22:04:26.417] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8900586027690357520:16:0) after 1m37s
[22:06:03.437] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8900586027690357520:16:0)
[22:06:03.458] Build status: STARTED
[22:06:03.458] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8900586027690357520:17:0) after 2m14s
[22:08:17.434] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8900586027690357520:17:0)
[22:08:17.452] Build status: STARTED
[22:08:17.452] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8900586027690357520:18:0) after 6m30s
[22:14:47.436] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8900586027690357520:18:0)
[22:14:47.453] Build status: STARTED
[22:14:47.453] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8900586027690357520:19:0) after 7m2s
[22:21:49.456] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8900586027690357520:19:0)
[22:21:49.475] Build status: STARTED
[22:21:49.475] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8900586027690357520:20:0) after 3m10s
[22:24:59.506] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8900586027690357520:20:0)
[22:24:59.527] Build status: STARTED
[22:24:59.527] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8900586027690357520:21:0) after 6m52s
[22:31:51.598] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8900586027690357520:21:0)
[22:31:51.620] Build status: STARTED
[22:31:51.620] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8900586027690357520:22:0) after 4m47s
[22:36:38.710] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8900586027690357520:22:0)
[22:36:38.733] Build status: STARTED
[22:36:38.733] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8900586027690357520:23:0) after 8m16s
[22:44:54.758] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8900586027690357520:23:0)
[22:44:54.778] Build status: STARTED
[22:44:54.778] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8900586027690357520:24:0) after 3m37s
[22:48:31.803] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8900586027690357520:24:0)
[22:48:31.823] Build status: STARTED
[22:48:31.823] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8900586027690357520:25:0) after 2m53s
[22:51:24.922] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8900586027690357520:25:0)
[22:51:24.938] Build status: STARTED
[22:51:24.938] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8900586027690357520:26:0) after 9m32s
[23:00:57.054] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8900586027690357520:26:0)
[23:00:57.153] Build status: STARTED
[23:00:57.153] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8900586027690357520:27:0) after 9m19s
[23:04:08.091] Received PubSub notification, asking Buildbucket for the build status
[23:04:08.111] Build:
{
"id": "8735155965594970417",
"builder": {
"project": "fuchsia",
"bucket": "ci",
"builder": "core.arm64-profile-qemu_kvm"
},
"createdBy": "project:fuchsia",
"createTime": "2024-10-02T21:05:28.017637566Z",
"startTime": "2024-10-02T21:05:33.650356774Z",
"endTime": "2024-10-02T23:04:07.691543196Z",
"updateTime": "2024-10-02T23:04:07.691543196Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "fuchsia",
"id": "9c7da036ea7ca0729ee8c22f917790729d17ce05",
"ref": "refs/heads/main"
}
}
}
[23:04:08.111] Invocation finished in 1h58m41.476150173s with status SUCCEEDED