[03:00:32.564] New invocation is queued and will start shortly
[03:00:33.632] Starting the invocation (attempt 1)
[03:00:33.662] Popped buildset tag "buildset:commit/gitiles/fuchsia.googlesource.com/fuchsia/+/919cdc84f213d8a32b2881944e8e96f1f2815c43"
[03:00:33.662] Popped gitiles commit info from properties and tags
[03:00:33.662] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[03:00:33.663] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[03:00:33.663] Buildbucket request:
{
"requestId": "8913066068185184512",
"builder": {
"project": "fuchsia",
"bucket": "ci",
"builder": "core.arm64-profile-qemu_kvm"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8913066068185184512",
"job": "fuchsia/core.arm64-profile-qemu_kvm-af244ac6",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "58cd6ed2a618ad38ac405936bfbc51580ff3fa49"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@58cd6ed2a618ad38ac405936bfbc51580ff3fa49",
"title": "58cd6ed2a618ad38ac405936bfbc51580ff3fa49",
"url": "https://fuchsia.googlesource.com/fuchsia/+/58cd6ed2a618ad38ac405936bfbc51580ff3fa49"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "b190c470a936eb8d01e1b756f83dcb72e0edb5f8"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@b190c470a936eb8d01e1b756f83dcb72e0edb5f8",
"title": "b190c470a936eb8d01e1b756f83dcb72e0edb5f8",
"url": "https://fuchsia.googlesource.com/fuchsia/+/b190c470a936eb8d01e1b756f83dcb72e0edb5f8"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "19907058627b94f797282ae1c7ae89e2f5782a13"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@19907058627b94f797282ae1c7ae89e2f5782a13",
"title": "19907058627b94f797282ae1c7ae89e2f5782a13",
"url": "https://fuchsia.googlesource.com/fuchsia/+/19907058627b94f797282ae1c7ae89e2f5782a13"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "bef82885f0445c16e200047f1fd0536e583957a8"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@bef82885f0445c16e200047f1fd0536e583957a8",
"title": "bef82885f0445c16e200047f1fd0536e583957a8",
"url": "https://fuchsia.googlesource.com/fuchsia/+/bef82885f0445c16e200047f1fd0536e583957a8"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "9c81078294ff10a7de252129bb755b6eadc496d7"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@9c81078294ff10a7de252129bb755b6eadc496d7",
"title": "9c81078294ff10a7de252129bb755b6eadc496d7",
"url": "https://fuchsia.googlesource.com/fuchsia/+/9c81078294ff10a7de252129bb755b6eadc496d7"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "ef7fd1a8dea9fb41e633a33de4d7c35f1d6d9f84"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@ef7fd1a8dea9fb41e633a33de4d7c35f1d6d9f84",
"title": "ef7fd1a8dea9fb41e633a33de4d7c35f1d6d9f84",
"url": "https://fuchsia.googlesource.com/fuchsia/+/ef7fd1a8dea9fb41e633a33de4d7c35f1d6d9f84"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "373161a6af5334f8d293e423a59b48a182b902f8"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@373161a6af5334f8d293e423a59b48a182b902f8",
"title": "373161a6af5334f8d293e423a59b48a182b902f8",
"url": "https://fuchsia.googlesource.com/fuchsia/+/373161a6af5334f8d293e423a59b48a182b902f8"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "f0512f97d3770562f0a2ec3964b2361060da0297"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@f0512f97d3770562f0a2ec3964b2361060da0297",
"title": "f0512f97d3770562f0a2ec3964b2361060da0297",
"url": "https://fuchsia.googlesource.com/fuchsia/+/f0512f97d3770562f0a2ec3964b2361060da0297"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "f0e47f112837bfadabd09022b4f0cc021c95b8eb"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@f0e47f112837bfadabd09022b4f0cc021c95b8eb",
"title": "f0e47f112837bfadabd09022b4f0cc021c95b8eb",
"url": "https://fuchsia.googlesource.com/fuchsia/+/f0e47f112837bfadabd09022b4f0cc021c95b8eb"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "919cdc84f213d8a32b2881944e8e96f1f2815c43"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@919cdc84f213d8a32b2881944e8e96f1f2815c43",
"title": "919cdc84f213d8a32b2881944e8e96f1f2815c43",
"url": "https://fuchsia.googlesource.com/fuchsia/+/919cdc84f213d8a32b2881944e8e96f1f2815c43"
}
]
}
},
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "fuchsia",
"id": "919cdc84f213d8a32b2881944e8e96f1f2815c43",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8913066068185184512"
},
{
"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"
}
}
[03:00:34.213] Scheduled build:
{
"id": "8747636006304622913",
"builder": {
"project": "fuchsia",
"bucket": "ci",
"builder": "core.arm64-profile-qemu_kvm"
},
"createdBy": "project:fuchsia",
"createTime": "2024-05-18T03:00:33.724644725Z",
"updateTime": "2024-05-18T03:00:33.724644725Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "fuchsia",
"id": "919cdc84f213d8a32b2881944e8e96f1f2815c43",
"ref": "refs/heads/main"
}
}
}
[03:00:34.213] Task URL: https://cr-buildbucket.appspot.com/build/8747636006304622913
[03:00:34.213] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8913066068185184512:2:0) after 8m0s
[03:00:35.130] Received PubSub notification, asking Buildbucket for the build status
[03:00:35.158] Build status: SCHEDULED
[03:04:40.496] Received PubSub notification, asking Buildbucket for the build status
[03:04:40.519] Build status: STARTED
[03:08:34.228] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8913066068185184512:2:0)
[03:08:34.250] Build status: STARTED
[03:08:34.250] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8913066068185184512:5:0) after 5m10s
[03:13:44.274] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8913066068185184512:5:0)
[03:13:44.350] Build status: STARTED
[03:13:44.350] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8913066068185184512:6:0) after 9m37s
[03:23:21.371] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8913066068185184512:6:0)
[03:23:21.394] Build status: STARTED
[03:23:21.394] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8913066068185184512:7:0) after 8m33s
[03:31:54.411] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8913066068185184512:7:0)
[03:31:54.431] Build status: STARTED
[03:31:54.431] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8913066068185184512:8:0) after 3m33s
[03:35:27.446] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8913066068185184512:8:0)
[03:35:27.462] Build status: STARTED
[03:35:27.462] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8913066068185184512:9:0) after 5m16s
[03:40:43.536] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8913066068185184512:9:0)
[03:40:43.575] Build status: STARTED
[03:40:43.575] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8913066068185184512:10:0) after 7m9s
[03:47:52.603] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8913066068185184512:10:0)
[03:47:52.682] Build status: STARTED
[03:47:52.682] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8913066068185184512:11:0) after 8m24s
[03:56:16.699] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8913066068185184512:11:0)
[03:56:16.729] Build status: STARTED
[03:56:16.729] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8913066068185184512:12:0) after 9m5s
[04:05:21.843] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8913066068185184512:12:0)
[04:05:21.887] Build status: STARTED
[04:05:21.887] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8913066068185184512:13:0) after 5m7s
[04:10:28.914] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8913066068185184512:13:0)
[04:10:28.964] Build status: STARTED
[04:10:28.964] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8913066068185184512:14:0) after 8m30s
[04:18:59.034] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8913066068185184512:14:0)
[04:18:59.052] Build status: STARTED
[04:18:59.053] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8913066068185184512:15:0) after 3m27s
[04:22:26.086] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8913066068185184512:15:0)
[04:22:26.201] Build status: STARTED
[04:22:26.201] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8913066068185184512:16:0) after 3m4s
[04:25:30.255] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8913066068185184512:16:0)
[04:25:30.273] Build status: STARTED
[04:25:30.273] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8913066068185184512:17:0) after 8m22s
[04:33:52.777] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8913066068185184512:17:0)
[04:33:52.800] Build status: STARTED
[04:33:52.800] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8913066068185184512:18:0) after 8m11s
[04:42:03.867] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8913066068185184512:18:0)
[04:42:03.947] Build status: STARTED
[04:42:03.947] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8913066068185184512:19:0) after 3m18s
[04:45:21.964] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8913066068185184512:19:0)
[04:45:21.982] Build status: STARTED
[04:45:21.982] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8913066068185184512:20:0) after 2m50s
[04:48:12.318] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8913066068185184512:20:0)
[04:48:12.336] Build status: STARTED
[04:48:12.336] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8913066068185184512:21:0) after 4m18s
[04:52:30.499] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8913066068185184512:21:0)
[04:52:30.516] Build status: STARTED
[04:52:30.516] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8913066068185184512:22:0) after 5m46s
[04:58:16.534] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8913066068185184512:22:0)
[04:58:16.556] Build status: STARTED
[04:58:16.556] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8913066068185184512:23:0) after 2m5s
[05:00:21.899] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8913066068185184512:23:0)
[05:00:21.957] Build status: STARTED
[05:00:21.957] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8913066068185184512:24:0) after 7m26s
[05:07:48.308] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8913066068185184512:24:0)
[05:07:48.329] Build status: STARTED
[05:07:48.329] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8913066068185184512:25:0) after 3m51s
[05:10:03.412] Received PubSub notification, asking Buildbucket for the build status
[05:10:03.433] Build:
{
"id": "8747636006304622913",
"builder": {
"project": "fuchsia",
"bucket": "ci",
"builder": "core.arm64-profile-qemu_kvm"
},
"createdBy": "project:fuchsia",
"createTime": "2024-05-18T03:00:33.724644725Z",
"startTime": "2024-05-18T03:04:40.179741391Z",
"endTime": "2024-05-18T05:09:44.255798522Z",
"updateTime": "2024-05-18T05:09:44.255798522Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "fuchsia",
"id": "919cdc84f213d8a32b2881944e8e96f1f2815c43",
"ref": "refs/heads/main"
}
}
}
[05:10:03.433] Invocation finished in 2h9m30.886516146s with status SUCCEEDED