[18:16:22.994] New invocation is queued and will start shortly
[18:16:24.432] Starting the invocation (attempt 1)
[18:16:24.460] Popped buildset tag "buildset:commit/gitiles/fuchsia.googlesource.com/integration/+/40d5c3c5c6d3d33810b44a60cf609662ce9dd37a"
[18:16:24.460] Popped gitiles commit info from properties and tags
[18:16:24.460] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[18:16:24.460] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[18:16:24.461] Buildbucket request:
{
"requestId": "8883473837423833600",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "minimal.riscv64-coverage"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8883473837423833600",
"job": "fuchsia/global.ci-minimal.riscv64-coverage-e5a0ec7f",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "f771a2993367cbc8ca3e3f5caec44c5a7a4237e8"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@f771a2993367cbc8ca3e3f5caec44c5a7a4237e8",
"title": "f771a2993367cbc8ca3e3f5caec44c5a7a4237e8",
"url": "https://fuchsia.googlesource.com/integration/+/f771a2993367cbc8ca3e3f5caec44c5a7a4237e8"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "2e1f15986aaf8fda83f53d4e0b981c5d401b02e7"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@2e1f15986aaf8fda83f53d4e0b981c5d401b02e7",
"title": "2e1f15986aaf8fda83f53d4e0b981c5d401b02e7",
"url": "https://fuchsia.googlesource.com/integration/+/2e1f15986aaf8fda83f53d4e0b981c5d401b02e7"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "688eb1c1df3f74ab086af38218f9639240348b34"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@688eb1c1df3f74ab086af38218f9639240348b34",
"title": "688eb1c1df3f74ab086af38218f9639240348b34",
"url": "https://fuchsia.googlesource.com/integration/+/688eb1c1df3f74ab086af38218f9639240348b34"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "cb02049022b3abf2c13fce0bf300cf7f304882b1"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@cb02049022b3abf2c13fce0bf300cf7f304882b1",
"title": "cb02049022b3abf2c13fce0bf300cf7f304882b1",
"url": "https://fuchsia.googlesource.com/integration/+/cb02049022b3abf2c13fce0bf300cf7f304882b1"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "2e79fa0af7d3513e297ced1d5d797323a9a4f7b3"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@2e79fa0af7d3513e297ced1d5d797323a9a4f7b3",
"title": "2e79fa0af7d3513e297ced1d5d797323a9a4f7b3",
"url": "https://fuchsia.googlesource.com/integration/+/2e79fa0af7d3513e297ced1d5d797323a9a4f7b3"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "f5d2f2f114b562d5f1eb6f654957e8d25f2dea95"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@f5d2f2f114b562d5f1eb6f654957e8d25f2dea95",
"title": "f5d2f2f114b562d5f1eb6f654957e8d25f2dea95",
"url": "https://fuchsia.googlesource.com/integration/+/f5d2f2f114b562d5f1eb6f654957e8d25f2dea95"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "09b5edf60abad9b1d2f811342140c5fab52021e8"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@09b5edf60abad9b1d2f811342140c5fab52021e8",
"title": "09b5edf60abad9b1d2f811342140c5fab52021e8",
"url": "https://fuchsia.googlesource.com/integration/+/09b5edf60abad9b1d2f811342140c5fab52021e8"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "35ae3b4aee335be64819d31bb8010514534ec916"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@35ae3b4aee335be64819d31bb8010514534ec916",
"title": "35ae3b4aee335be64819d31bb8010514534ec916",
"url": "https://fuchsia.googlesource.com/integration/+/35ae3b4aee335be64819d31bb8010514534ec916"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "4e7ac73d1c0f3b6bbd3586a77349eedf8fba9127"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@4e7ac73d1c0f3b6bbd3586a77349eedf8fba9127",
"title": "4e7ac73d1c0f3b6bbd3586a77349eedf8fba9127",
"url": "https://fuchsia.googlesource.com/integration/+/4e7ac73d1c0f3b6bbd3586a77349eedf8fba9127"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "1cde17fa941a9d896bb3b0f6400e71e463bf423b"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@1cde17fa941a9d896bb3b0f6400e71e463bf423b",
"title": "1cde17fa941a9d896bb3b0f6400e71e463bf423b",
"url": "https://fuchsia.googlesource.com/integration/+/1cde17fa941a9d896bb3b0f6400e71e463bf423b"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "bb6514849aa684104b01d47e51edba34462e15ee"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@bb6514849aa684104b01d47e51edba34462e15ee",
"title": "bb6514849aa684104b01d47e51edba34462e15ee",
"url": "https://fuchsia.googlesource.com/integration/+/bb6514849aa684104b01d47e51edba34462e15ee"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "a3d6dae8b58585d02aa6d66f7f607e05d0fa86a4"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@a3d6dae8b58585d02aa6d66f7f607e05d0fa86a4",
"title": "a3d6dae8b58585d02aa6d66f7f607e05d0fa86a4",
"url": "https://fuchsia.googlesource.com/integration/+/a3d6dae8b58585d02aa6d66f7f607e05d0fa86a4"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "4c362cc3d250df32ee227e2d3ec02fdc93a81731"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@4c362cc3d250df32ee227e2d3ec02fdc93a81731",
"title": "4c362cc3d250df32ee227e2d3ec02fdc93a81731",
"url": "https://fuchsia.googlesource.com/integration/+/4c362cc3d250df32ee227e2d3ec02fdc93a81731"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "254afbcc00fcab5b86986cd42407ba46b3d23a30"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@254afbcc00fcab5b86986cd42407ba46b3d23a30",
"title": "254afbcc00fcab5b86986cd42407ba46b3d23a30",
"url": "https://fuchsia.googlesource.com/integration/+/254afbcc00fcab5b86986cd42407ba46b3d23a30"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "83bd2a31230548e28229d2585be165a93660256b"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@83bd2a31230548e28229d2585be165a93660256b",
"title": "83bd2a31230548e28229d2585be165a93660256b",
"url": "https://fuchsia.googlesource.com/integration/+/83bd2a31230548e28229d2585be165a93660256b"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "1e1a00e738fae8b2888865b557b00d3176eabc39"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@1e1a00e738fae8b2888865b557b00d3176eabc39",
"title": "1e1a00e738fae8b2888865b557b00d3176eabc39",
"url": "https://fuchsia.googlesource.com/integration/+/1e1a00e738fae8b2888865b557b00d3176eabc39"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "557fe561ff82d909ed58e374ca78657d7748ea8d"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@557fe561ff82d909ed58e374ca78657d7748ea8d",
"title": "557fe561ff82d909ed58e374ca78657d7748ea8d",
"url": "https://fuchsia.googlesource.com/integration/+/557fe561ff82d909ed58e374ca78657d7748ea8d"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "531205941efd620ae1cb499b4e594adc40a003a4"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@531205941efd620ae1cb499b4e594adc40a003a4",
"title": "531205941efd620ae1cb499b4e594adc40a003a4",
"url": "https://fuchsia.googlesource.com/integration/+/531205941efd620ae1cb499b4e594adc40a003a4"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "40d5c3c5c6d3d33810b44a60cf609662ce9dd37a"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@40d5c3c5c6d3d33810b44a60cf609662ce9dd37a",
"title": "40d5c3c5c6d3d33810b44a60cf609662ce9dd37a",
"url": "https://fuchsia.googlesource.com/integration/+/40d5c3c5c6d3d33810b44a60cf609662ce9dd37a"
}
]
}
},
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "40d5c3c5c6d3d33810b44a60cf609662ce9dd37a",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8883473837423833600"
},
{
"key": "scheduler_job_id",
"value": "fuchsia/global.ci-minimal.riscv64-coverage-e5a0ec7f"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[18:16:24.845] Scheduled build:
{
"id": "8718043775173801697",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "minimal.riscv64-coverage"
},
"createdBy": "project:fuchsia",
"createTime": "2025-04-09T18:16:24.519095550Z",
"updateTime": "2025-04-09T18:16:24.519095550Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "40d5c3c5c6d3d33810b44a60cf609662ce9dd37a",
"ref": "refs/heads/main"
}
}
}
[18:16:24.845] Task URL: https://cr-buildbucket.appspot.com/build/8718043775173801697
[18:16:24.845] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-minimal.riscv64-coverage-e5a0ec7f:8883473837423833600:2:0) after 9m6s
[18:16:25.833] Received PubSub notification, asking Buildbucket for the build status
[18:16:25.871] Build status: SCHEDULED
[18:18:34.446] Received PubSub notification, asking Buildbucket for the build status
[18:18:34.506] Build status: STARTED
[18:25:30.917] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-minimal.riscv64-coverage-e5a0ec7f:8883473837423833600:2:0)
[18:25:30.960] Build status: STARTED
[18:25:30.960] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-minimal.riscv64-coverage-e5a0ec7f:8883473837423833600:5:0) after 9m50s
[18:35:20.986] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-minimal.riscv64-coverage-e5a0ec7f:8883473837423833600:5:0)
[18:35:21.012] Build status: STARTED
[18:35:21.012] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-minimal.riscv64-coverage-e5a0ec7f:8883473837423833600:6:0) after 9m40s
[18:45:01.122] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-minimal.riscv64-coverage-e5a0ec7f:8883473837423833600:6:0)
[18:45:01.152] Build status: STARTED
[18:45:01.152] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-minimal.riscv64-coverage-e5a0ec7f:8883473837423833600:7:0) after 2m25s
[18:47:26.169] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-minimal.riscv64-coverage-e5a0ec7f:8883473837423833600:7:0)
[18:47:26.212] Build status: STARTED
[18:47:26.212] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-minimal.riscv64-coverage-e5a0ec7f:8883473837423833600:8:0) after 2m31s
[18:49:57.230] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-minimal.riscv64-coverage-e5a0ec7f:8883473837423833600:8:0)
[18:49:57.254] Build status: STARTED
[18:49:57.254] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-minimal.riscv64-coverage-e5a0ec7f:8883473837423833600:9:0) after 4m51s
[18:54:48.282] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-minimal.riscv64-coverage-e5a0ec7f:8883473837423833600:9:0)
[18:54:48.324] Build status: STARTED
[18:54:48.324] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-minimal.riscv64-coverage-e5a0ec7f:8883473837423833600:10:0) after 1m52s
[18:56:40.389] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-minimal.riscv64-coverage-e5a0ec7f:8883473837423833600:10:0)
[18:56:40.433] Build status: STARTED
[18:56:40.433] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-minimal.riscv64-coverage-e5a0ec7f:8883473837423833600:11:0) after 2m35s
[18:59:15.488] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-minimal.riscv64-coverage-e5a0ec7f:8883473837423833600:11:0)
[18:59:15.510] Build status: STARTED
[18:59:15.510] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-minimal.riscv64-coverage-e5a0ec7f:8883473837423833600:12:0) after 6m31s
[19:05:46.577] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-minimal.riscv64-coverage-e5a0ec7f:8883473837423833600:12:0)
[19:05:46.595] Build status: STARTED
[19:05:46.595] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-minimal.riscv64-coverage-e5a0ec7f:8883473837423833600:13:0) after 8m2s
[19:13:48.677] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-minimal.riscv64-coverage-e5a0ec7f:8883473837423833600:13:0)
[19:13:48.697] Build status: STARTED
[19:13:48.697] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-minimal.riscv64-coverage-e5a0ec7f:8883473837423833600:14:0) after 1m18s
[19:15:06.699] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-minimal.riscv64-coverage-e5a0ec7f:8883473837423833600:14:0)
[19:15:06.806] Build status: STARTED
[19:15:06.806] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-minimal.riscv64-coverage-e5a0ec7f:8883473837423833600:15:0) after 8m40s
[19:23:46.849] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-minimal.riscv64-coverage-e5a0ec7f:8883473837423833600:15:0)
[19:23:46.872] Build status: STARTED
[19:23:46.872] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-minimal.riscv64-coverage-e5a0ec7f:8883473837423833600:16:0) after 6m30s
[19:30:16.891] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-minimal.riscv64-coverage-e5a0ec7f:8883473837423833600:16:0)
[19:30:16.909] Build status: STARTED
[19:30:16.909] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-minimal.riscv64-coverage-e5a0ec7f:8883473837423833600:17:0) after 7m25s
[19:37:41.930] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-minimal.riscv64-coverage-e5a0ec7f:8883473837423833600:17:0)
[19:37:41.946] Build status: STARTED
[19:37:41.946] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-minimal.riscv64-coverage-e5a0ec7f:8883473837423833600:18:0) after 7m26s
[19:45:08.020] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-minimal.riscv64-coverage-e5a0ec7f:8883473837423833600:18:0)
[19:45:08.089] Build status: STARTED
[19:45:08.089] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-minimal.riscv64-coverage-e5a0ec7f:8883473837423833600:19:0) after 3m58s
[19:49:06.123] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-minimal.riscv64-coverage-e5a0ec7f:8883473837423833600:19:0)
[19:49:06.151] Build status: STARTED
[19:49:06.151] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-minimal.riscv64-coverage-e5a0ec7f:8883473837423833600:20:0) after 7m19s
[19:56:25.176] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-minimal.riscv64-coverage-e5a0ec7f:8883473837423833600:20:0)
[19:56:25.203] Build status: STARTED
[19:56:25.203] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-minimal.riscv64-coverage-e5a0ec7f:8883473837423833600:21:0) after 1m45s
[19:58:10.185] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-minimal.riscv64-coverage-e5a0ec7f:8883473837423833600:21:0)
[19:58:10.200] Build status: STARTED
[19:58:10.200] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-minimal.riscv64-coverage-e5a0ec7f:8883473837423833600:22:0) after 1m46s
[19:59:56.240] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-minimal.riscv64-coverage-e5a0ec7f:8883473837423833600:22:0)
[19:59:56.263] Build status: STARTED
[19:59:56.263] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-minimal.riscv64-coverage-e5a0ec7f:8883473837423833600:23:0) after 2m37s
[20:02:33.303] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-minimal.riscv64-coverage-e5a0ec7f:8883473837423833600:23:0)
[20:02:33.318] Build status: STARTED
[20:02:33.318] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-minimal.riscv64-coverage-e5a0ec7f:8883473837423833600:24:0) after 4m13s
[20:06:46.451] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-minimal.riscv64-coverage-e5a0ec7f:8883473837423833600:24:0)
[20:06:46.471] Build status: STARTED
[20:06:46.471] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-minimal.riscv64-coverage-e5a0ec7f:8883473837423833600:25:0) after 3m29s
[20:10:15.494] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-minimal.riscv64-coverage-e5a0ec7f:8883473837423833600:25:0)
[20:10:15.525] Build status: STARTED
[20:10:15.525] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-minimal.riscv64-coverage-e5a0ec7f:8883473837423833600:26:0) after 1m31s
[20:11:46.553] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-minimal.riscv64-coverage-e5a0ec7f:8883473837423833600:26:0)
[20:11:46.603] Build status: STARTED
[20:11:46.603] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-minimal.riscv64-coverage-e5a0ec7f:8883473837423833600:27:0) after 6m13s
[20:17:59.633] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-minimal.riscv64-coverage-e5a0ec7f:8883473837423833600:27:0)
[20:17:59.648] Build status: STARTED
[20:17:59.648] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-minimal.riscv64-coverage-e5a0ec7f:8883473837423833600:28:0) after 6m30s
[20:21:23.591] Received PubSub notification, asking Buildbucket for the build status
[20:21:23.614] Build:
{
"id": "8718043775173801697",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "minimal.riscv64-coverage"
},
"createdBy": "project:fuchsia",
"createTime": "2025-04-09T18:16:24.519095550Z",
"startTime": "2025-04-09T18:18:34.085551452Z",
"endTime": "2025-04-09T20:21:23.003062935Z",
"updateTime": "2025-04-09T20:21:23.003062935Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "40d5c3c5c6d3d33810b44a60cf609662ce9dd37a",
"ref": "refs/heads/main"
}
}
}
[20:21:23.614] Invocation finished in 2h5m0.624118336s with status SUCCEEDED