[18:56:22.477] New invocation is queued and will start shortly
[18:56:23.528] Starting the invocation (attempt 1)
[18:56:23.556] Popped buildset tag "buildset:commit/gitiles/fuchsia.googlesource.com/fuchsia/+/b29e2eabf5bd4b4413d99f29741ec803e023a825"
[18:56:23.556] Popped gitiles commit info from properties and tags
[18:56:23.556] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[18:56:23.556] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[18:56:23.557] Buildbucket request:
{
"requestId": "8899506984437714304",
"builder": {
"project": "fuchsia",
"bucket": "ci",
"builder": "core.arm64-profile-qemu_kvm"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8899506984437714304",
"job": "fuchsia/core.arm64-profile-qemu_kvm-af244ac6",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "47bfcb9da6a9cd9e706efb7dc25639031318e76c"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@47bfcb9da6a9cd9e706efb7dc25639031318e76c",
"title": "47bfcb9da6a9cd9e706efb7dc25639031318e76c",
"url": "https://fuchsia.googlesource.com/fuchsia/+/47bfcb9da6a9cd9e706efb7dc25639031318e76c"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "a691cdb2821d36ea9805f21a6c70bec1ecfd4a6c"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@a691cdb2821d36ea9805f21a6c70bec1ecfd4a6c",
"title": "a691cdb2821d36ea9805f21a6c70bec1ecfd4a6c",
"url": "https://fuchsia.googlesource.com/fuchsia/+/a691cdb2821d36ea9805f21a6c70bec1ecfd4a6c"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "8fe652260ccb0d28a1866a0352216dcb01ff4aac"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@8fe652260ccb0d28a1866a0352216dcb01ff4aac",
"title": "8fe652260ccb0d28a1866a0352216dcb01ff4aac",
"url": "https://fuchsia.googlesource.com/fuchsia/+/8fe652260ccb0d28a1866a0352216dcb01ff4aac"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "3092c1468d50eb580b1b6685ba8562340ffd5542"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@3092c1468d50eb580b1b6685ba8562340ffd5542",
"title": "3092c1468d50eb580b1b6685ba8562340ffd5542",
"url": "https://fuchsia.googlesource.com/fuchsia/+/3092c1468d50eb580b1b6685ba8562340ffd5542"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "57095c3febe4b9eb30495502a0992403ec3bf949"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@57095c3febe4b9eb30495502a0992403ec3bf949",
"title": "57095c3febe4b9eb30495502a0992403ec3bf949",
"url": "https://fuchsia.googlesource.com/fuchsia/+/57095c3febe4b9eb30495502a0992403ec3bf949"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "9333880c06538208f187a85ad9e700a31689ed41"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@9333880c06538208f187a85ad9e700a31689ed41",
"title": "9333880c06538208f187a85ad9e700a31689ed41",
"url": "https://fuchsia.googlesource.com/fuchsia/+/9333880c06538208f187a85ad9e700a31689ed41"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "32b4b1c605332570888b8f8981f3511a4731773e"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@32b4b1c605332570888b8f8981f3511a4731773e",
"title": "32b4b1c605332570888b8f8981f3511a4731773e",
"url": "https://fuchsia.googlesource.com/fuchsia/+/32b4b1c605332570888b8f8981f3511a4731773e"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "bffd317a0e1cb8484c19ca84611d7750f1741be1"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@bffd317a0e1cb8484c19ca84611d7750f1741be1",
"title": "bffd317a0e1cb8484c19ca84611d7750f1741be1",
"url": "https://fuchsia.googlesource.com/fuchsia/+/bffd317a0e1cb8484c19ca84611d7750f1741be1"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "1fe389e478fcad942958d3aab3da68d3cb408472"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@1fe389e478fcad942958d3aab3da68d3cb408472",
"title": "1fe389e478fcad942958d3aab3da68d3cb408472",
"url": "https://fuchsia.googlesource.com/fuchsia/+/1fe389e478fcad942958d3aab3da68d3cb408472"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "b29e2eabf5bd4b4413d99f29741ec803e023a825"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@b29e2eabf5bd4b4413d99f29741ec803e023a825",
"title": "b29e2eabf5bd4b4413d99f29741ec803e023a825",
"url": "https://fuchsia.googlesource.com/fuchsia/+/b29e2eabf5bd4b4413d99f29741ec803e023a825"
}
]
}
},
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "fuchsia",
"id": "b29e2eabf5bd4b4413d99f29741ec803e023a825",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8899506984437714304"
},
{
"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"
}
}
[18:56:23.995] Scheduled build:
{
"id": "8734076922596486545",
"builder": {
"project": "fuchsia",
"bucket": "ci",
"builder": "core.arm64-profile-qemu_kvm"
},
"createdBy": "project:fuchsia",
"createTime": "2024-10-14T18:56:23.611849784Z",
"updateTime": "2024-10-14T18:56:23.611849784Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "fuchsia",
"id": "b29e2eabf5bd4b4413d99f29741ec803e023a825",
"ref": "refs/heads/main"
}
}
}
[18:56:23.995] Task URL: https://cr-buildbucket.appspot.com/build/8734076922596486545
[18:56:23.995] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8899506984437714304:2:0) after 8m28s
[18:56:24.959] Received PubSub notification, asking Buildbucket for the build status
[18:56:25.046] Build status: SCHEDULED
[18:56:28.921] Received PubSub notification, asking Buildbucket for the build status
[18:56:28.988] Build status: STARTED
[19:04:52.056] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8899506984437714304:2:0)
[19:04:52.089] Build status: STARTED
[19:04:52.089] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8899506984437714304:5:0) after 5m10s
[19:10:02.172] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8899506984437714304:5:0)
[19:10:02.191] Build status: STARTED
[19:10:02.192] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8899506984437714304:6:0) after 9m34s
[19:19:36.166] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8899506984437714304:6:0)
[19:19:36.336] Build status: STARTED
[19:19:36.336] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8899506984437714304:7:0) after 7m26s
[19:27:02.429] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8899506984437714304:7:0)
[19:27:02.456] Build status: STARTED
[19:27:02.456] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8899506984437714304:8:0) after 2m38s
[19:29:40.474] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8899506984437714304:8:0)
[19:29:40.604] Build status: STARTED
[19:29:40.605] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8899506984437714304:9:0) after 6m33s
[19:36:13.732] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8899506984437714304:9:0)
[19:36:13.751] Build status: STARTED
[19:36:13.751] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8899506984437714304:10:0) after 2m30s
[19:38:43.769] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8899506984437714304:10:0)
[19:38:43.786] Build status: STARTED
[19:38:43.786] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8899506984437714304:11:0) after 6m21s
[19:45:04.964] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8899506984437714304:11:0)
[19:45:04.991] Build status: STARTED
[19:45:04.991] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8899506984437714304:12:0) after 6m34s
[19:51:39.016] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8899506984437714304:12:0)
[19:51:39.041] Build status: STARTED
[19:51:39.041] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8899506984437714304:13:0) after 5m33s
[19:57:12.152] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8899506984437714304:13:0)
[19:57:12.170] Build status: STARTED
[19:57:12.170] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8899506984437714304:14:0) after 6m53s
[20:04:05.204] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8899506984437714304:14:0)
[20:04:05.231] Build status: STARTED
[20:04:05.231] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8899506984437714304:15:0) after 9m32s
[20:13:37.355] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8899506984437714304:15:0)
[20:13:37.439] Build status: STARTED
[20:13:37.439] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8899506984437714304:16:0) after 4m20s
[20:17:57.460] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8899506984437714304:16:0)
[20:17:57.478] Build status: STARTED
[20:17:57.478] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8899506984437714304:17:0) after 5m45s
[20:23:42.470] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8899506984437714304:17:0)
[20:23:42.488] Build status: STARTED
[20:23:42.488] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8899506984437714304:18:0) after 3m33s
[20:27:15.505] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8899506984437714304:18:0)
[20:27:15.558] Build status: STARTED
[20:27:15.558] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8899506984437714304:19:0) after 7m0s
[20:34:15.609] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8899506984437714304:19:0)
[20:34:15.627] Build status: STARTED
[20:34:15.627] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8899506984437714304:20:0) after 3m31s
[20:37:46.694] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8899506984437714304:20:0)
[20:37:46.745] Build status: STARTED
[20:37:46.745] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8899506984437714304:21:0) after 2m34s
[20:40:20.807] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8899506984437714304:21:0)
[20:40:20.828] Build status: STARTED
[20:40:20.828] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8899506984437714304:22:0) after 5m34s
[20:45:54.868] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8899506984437714304:22:0)
[20:45:54.884] Build status: STARTED
[20:45:54.884] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8899506984437714304:23:0) after 3m38s
[20:49:32.902] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8899506984437714304:23:0)
[20:49:32.927] Build status: STARTED
[20:49:32.927] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8899506984437714304:24:0) after 6m5s
[20:55:38.109] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8899506984437714304:24:0)
[20:55:38.133] Build status: STARTED
[20:55:38.133] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8899506984437714304:25:0) after 5m38s
[20:58:21.684] Received PubSub notification, asking Buildbucket for the build status
[20:58:21.704] Build:
{
"id": "8734076922596486545",
"builder": {
"project": "fuchsia",
"bucket": "ci",
"builder": "core.arm64-profile-qemu_kvm"
},
"createdBy": "project:fuchsia",
"createTime": "2024-10-14T18:56:23.611849784Z",
"startTime": "2024-10-14T18:56:28.721352022Z",
"endTime": "2024-10-14T20:58:21.344902173Z",
"updateTime": "2024-10-14T20:58:21.344902173Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "fuchsia",
"id": "b29e2eabf5bd4b4413d99f29741ec803e023a825",
"ref": "refs/heads/main"
}
}
}
[20:58:21.704] Invocation finished in 2h1m59.232236607s with status SUCCEEDED