[00:24:18.419] New invocation is queued and will start shortly
[00:24:19.709] Starting the invocation (attempt 1)
[00:24:19.745] Popped buildset tag "buildset:commit/gitiles/fuchsia.googlesource.com/integration/+/41fbbe70b56fec82f4161e5b3d884bed8d47f95d"
[00:24:19.745] Popped gitiles commit info from properties and tags
[00:24:19.745] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[00:24:19.745] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[00:24:19.746] Buildbucket request:
{
"requestId": "8878377259553587456",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "minimal.riscv64-lto"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8878377259553587456",
"job": "fuchsia/global.ci-minimal.riscv64-lto-e5a0ec7f",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "bc9aaa9bcbc568ea416591c9e430662038da812e"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@bc9aaa9bcbc568ea416591c9e430662038da812e",
"title": "bc9aaa9bcbc568ea416591c9e430662038da812e",
"url": "https://fuchsia.googlesource.com/integration/+/bc9aaa9bcbc568ea416591c9e430662038da812e"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "2b8ed13a837ef8548fb8666ab19d433e3506277d"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@2b8ed13a837ef8548fb8666ab19d433e3506277d",
"title": "2b8ed13a837ef8548fb8666ab19d433e3506277d",
"url": "https://fuchsia.googlesource.com/integration/+/2b8ed13a837ef8548fb8666ab19d433e3506277d"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "b5fa735e599cb2793d4c16ba0ab995106a2f9d76"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@b5fa735e599cb2793d4c16ba0ab995106a2f9d76",
"title": "b5fa735e599cb2793d4c16ba0ab995106a2f9d76",
"url": "https://fuchsia.googlesource.com/integration/+/b5fa735e599cb2793d4c16ba0ab995106a2f9d76"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "65f04d9ddb2cb8a960c15ee750c3ce53fa0016ac"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@65f04d9ddb2cb8a960c15ee750c3ce53fa0016ac",
"title": "65f04d9ddb2cb8a960c15ee750c3ce53fa0016ac",
"url": "https://fuchsia.googlesource.com/integration/+/65f04d9ddb2cb8a960c15ee750c3ce53fa0016ac"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "54bbd46ab9da890b1d68b2b56806945026db5606"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@54bbd46ab9da890b1d68b2b56806945026db5606",
"title": "54bbd46ab9da890b1d68b2b56806945026db5606",
"url": "https://fuchsia.googlesource.com/integration/+/54bbd46ab9da890b1d68b2b56806945026db5606"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "9027fbea905680381e55adf5f57d6730d21a1ba7"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@9027fbea905680381e55adf5f57d6730d21a1ba7",
"title": "9027fbea905680381e55adf5f57d6730d21a1ba7",
"url": "https://fuchsia.googlesource.com/integration/+/9027fbea905680381e55adf5f57d6730d21a1ba7"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "44012616abf4473de1257067a94c92dc3b890457"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@44012616abf4473de1257067a94c92dc3b890457",
"title": "44012616abf4473de1257067a94c92dc3b890457",
"url": "https://fuchsia.googlesource.com/integration/+/44012616abf4473de1257067a94c92dc3b890457"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "81c307529110c32057273373fabd851faa8e23f5"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@81c307529110c32057273373fabd851faa8e23f5",
"title": "81c307529110c32057273373fabd851faa8e23f5",
"url": "https://fuchsia.googlesource.com/integration/+/81c307529110c32057273373fabd851faa8e23f5"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "e30d5d25815f007910b43ff6022136b1b1f75fc9"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@e30d5d25815f007910b43ff6022136b1b1f75fc9",
"title": "e30d5d25815f007910b43ff6022136b1b1f75fc9",
"url": "https://fuchsia.googlesource.com/integration/+/e30d5d25815f007910b43ff6022136b1b1f75fc9"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "0403158bfab3bc097cf540f05a344ac5ef665306"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@0403158bfab3bc097cf540f05a344ac5ef665306",
"title": "0403158bfab3bc097cf540f05a344ac5ef665306",
"url": "https://fuchsia.googlesource.com/integration/+/0403158bfab3bc097cf540f05a344ac5ef665306"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "8da1d7865f137ce680da9b60d5dc77c29ffa130a"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@8da1d7865f137ce680da9b60d5dc77c29ffa130a",
"title": "8da1d7865f137ce680da9b60d5dc77c29ffa130a",
"url": "https://fuchsia.googlesource.com/integration/+/8da1d7865f137ce680da9b60d5dc77c29ffa130a"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "774d76213760eac3e49726027af6efbf5a3c76fc"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@774d76213760eac3e49726027af6efbf5a3c76fc",
"title": "774d76213760eac3e49726027af6efbf5a3c76fc",
"url": "https://fuchsia.googlesource.com/integration/+/774d76213760eac3e49726027af6efbf5a3c76fc"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "ea4eaf255df6a18c35a0af7126bed238372b4a4a"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@ea4eaf255df6a18c35a0af7126bed238372b4a4a",
"title": "ea4eaf255df6a18c35a0af7126bed238372b4a4a",
"url": "https://fuchsia.googlesource.com/integration/+/ea4eaf255df6a18c35a0af7126bed238372b4a4a"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "1b13109c09fbda06ef3f64e2ef6d669112d35784"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@1b13109c09fbda06ef3f64e2ef6d669112d35784",
"title": "1b13109c09fbda06ef3f64e2ef6d669112d35784",
"url": "https://fuchsia.googlesource.com/integration/+/1b13109c09fbda06ef3f64e2ef6d669112d35784"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "33033663a94181f22453a2443ec7fba37f75db18"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@33033663a94181f22453a2443ec7fba37f75db18",
"title": "33033663a94181f22453a2443ec7fba37f75db18",
"url": "https://fuchsia.googlesource.com/integration/+/33033663a94181f22453a2443ec7fba37f75db18"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "bf7ce28909ac257cedfe99092d1e99d8ea20071e"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@bf7ce28909ac257cedfe99092d1e99d8ea20071e",
"title": "bf7ce28909ac257cedfe99092d1e99d8ea20071e",
"url": "https://fuchsia.googlesource.com/integration/+/bf7ce28909ac257cedfe99092d1e99d8ea20071e"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "41fbbe70b56fec82f4161e5b3d884bed8d47f95d"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@41fbbe70b56fec82f4161e5b3d884bed8d47f95d",
"title": "41fbbe70b56fec82f4161e5b3d884bed8d47f95d",
"url": "https://fuchsia.googlesource.com/integration/+/41fbbe70b56fec82f4161e5b3d884bed8d47f95d"
}
]
}
},
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "41fbbe70b56fec82f4161e5b3d884bed8d47f95d",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8878377259553587456"
},
{
"key": "scheduler_job_id",
"value": "fuchsia/global.ci-minimal.riscv64-lto-e5a0ec7f"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[00:24:20.245] Scheduled build:
{
"id": "8712947197436142577",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "minimal.riscv64-lto"
},
"createdBy": "project:fuchsia",
"createTime": "2025-06-05T00:24:19.809938529Z",
"updateTime": "2025-06-05T00:24:19.809938529Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "41fbbe70b56fec82f4161e5b3d884bed8d47f95d",
"ref": "refs/heads/main"
}
}
}
[00:24:20.245] Task URL: https://cr-buildbucket.appspot.com/build/8712947197436142577
[00:24:20.245] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-minimal.riscv64-lto-e5a0ec7f:8878377259553587456:2:0) after 1m59s
[00:24:21.263] Received PubSub notification, asking Buildbucket for the build status
[00:24:21.292] Build status: SCHEDULED
[00:24:27.419] Received PubSub notification, asking Buildbucket for the build status
[00:24:27.444] Build status: STARTED
[00:26:19.292] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-minimal.riscv64-lto-e5a0ec7f:8878377259553587456:2:0)
[00:26:19.341] Build status: STARTED
[00:26:19.341] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-minimal.riscv64-lto-e5a0ec7f:8878377259553587456:5:0) after 1m22s
[00:27:41.358] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-minimal.riscv64-lto-e5a0ec7f:8878377259553587456:5:0)
[00:27:41.386] Build status: STARTED
[00:27:41.386] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-minimal.riscv64-lto-e5a0ec7f:8878377259553587456:6:0) after 1m17s
[00:28:58.393] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-minimal.riscv64-lto-e5a0ec7f:8878377259553587456:6:0)
[00:28:58.425] Build status: STARTED
[00:28:58.425] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-minimal.riscv64-lto-e5a0ec7f:8878377259553587456:7:0) after 3m34s
[00:32:32.515] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-minimal.riscv64-lto-e5a0ec7f:8878377259553587456:7:0)
[00:32:32.542] Build status: STARTED
[00:32:32.542] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-minimal.riscv64-lto-e5a0ec7f:8878377259553587456:8:0) after 8m38s
[00:41:10.563] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-minimal.riscv64-lto-e5a0ec7f:8878377259553587456:8:0)
[00:41:10.580] Build status: STARTED
[00:41:10.580] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-minimal.riscv64-lto-e5a0ec7f:8878377259553587456:9:0) after 9m5s
[00:50:15.603] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-minimal.riscv64-lto-e5a0ec7f:8878377259553587456:9:0)
[00:50:15.635] Build status: STARTED
[00:50:15.635] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-minimal.riscv64-lto-e5a0ec7f:8878377259553587456:10:0) after 6m24s
[00:56:39.702] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-minimal.riscv64-lto-e5a0ec7f:8878377259553587456:10:0)
[00:56:39.726] Build status: STARTED
[00:56:39.726] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-minimal.riscv64-lto-e5a0ec7f:8878377259553587456:11:0) after 5m35s
[01:02:14.789] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-minimal.riscv64-lto-e5a0ec7f:8878377259553587456:11:0)
[01:02:14.818] Build status: STARTED
[01:02:14.818] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-minimal.riscv64-lto-e5a0ec7f:8878377259553587456:12:0) after 3m35s
[01:05:49.837] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-minimal.riscv64-lto-e5a0ec7f:8878377259553587456:12:0)
[01:05:49.924] Build status: STARTED
[01:05:49.924] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-minimal.riscv64-lto-e5a0ec7f:8878377259553587456:13:0) after 2m38s
[01:08:28.001] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-minimal.riscv64-lto-e5a0ec7f:8878377259553587456:13:0)
[01:08:28.033] Build status: STARTED
[01:08:28.033] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-minimal.riscv64-lto-e5a0ec7f:8878377259553587456:14:0) after 3m58s
[01:12:26.104] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-minimal.riscv64-lto-e5a0ec7f:8878377259553587456:14:0)
[01:12:26.133] Build status: STARTED
[01:12:26.133] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-minimal.riscv64-lto-e5a0ec7f:8878377259553587456:15:0) after 4m48s
[01:17:14.162] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-minimal.riscv64-lto-e5a0ec7f:8878377259553587456:15:0)
[01:17:14.194] Build status: STARTED
[01:17:14.194] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-minimal.riscv64-lto-e5a0ec7f:8878377259553587456:16:0) after 2m18s
[01:19:32.206] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-minimal.riscv64-lto-e5a0ec7f:8878377259553587456:16:0)
[01:19:32.226] Build status: STARTED
[01:19:32.226] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-minimal.riscv64-lto-e5a0ec7f:8878377259553587456:17:0) after 8m4s
[01:27:36.378] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-minimal.riscv64-lto-e5a0ec7f:8878377259553587456:17:0)
[01:27:36.405] Build status: STARTED
[01:27:36.405] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-minimal.riscv64-lto-e5a0ec7f:8878377259553587456:18:0) after 3m2s
[01:30:38.449] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-minimal.riscv64-lto-e5a0ec7f:8878377259553587456:18:0)
[01:30:38.484] Build status: STARTED
[01:30:38.484] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-minimal.riscv64-lto-e5a0ec7f:8878377259553587456:19:0) after 9m2s
[01:39:40.623] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-minimal.riscv64-lto-e5a0ec7f:8878377259553587456:19:0)
[01:39:40.651] Build status: STARTED
[01:39:40.651] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-minimal.riscv64-lto-e5a0ec7f:8878377259553587456:20:0) after 6m42s
[01:46:22.929] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-minimal.riscv64-lto-e5a0ec7f:8878377259553587456:20:0)
[01:46:22.951] Build status: STARTED
[01:46:22.951] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-minimal.riscv64-lto-e5a0ec7f:8878377259553587456:21:0) after 7m52s
[01:54:15.043] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-minimal.riscv64-lto-e5a0ec7f:8878377259553587456:21:0)
[01:54:15.070] Build status: STARTED
[01:54:15.070] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-minimal.riscv64-lto-e5a0ec7f:8878377259553587456:22:0) after 8m3s
[02:02:18.141] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-minimal.riscv64-lto-e5a0ec7f:8878377259553587456:22:0)
[02:02:18.157] Build status: STARTED
[02:02:18.157] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-minimal.riscv64-lto-e5a0ec7f:8878377259553587456:23:0) after 1m7s
[02:03:25.180] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-minimal.riscv64-lto-e5a0ec7f:8878377259553587456:23:0)
[02:03:25.195] Build status: STARTED
[02:03:25.195] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-minimal.riscv64-lto-e5a0ec7f:8878377259553587456:24:0) after 4m4s
[02:07:29.262] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-minimal.riscv64-lto-e5a0ec7f:8878377259553587456:24:0)
[02:07:29.286] Build status: STARTED
[02:07:29.286] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-minimal.riscv64-lto-e5a0ec7f:8878377259553587456:25:0) after 5m1s
[02:12:30.309] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-minimal.riscv64-lto-e5a0ec7f:8878377259553587456:25:0)
[02:12:30.329] Build status: STARTED
[02:12:30.329] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-minimal.riscv64-lto-e5a0ec7f:8878377259553587456:26:0) after 5m39s
[02:18:09.475] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-minimal.riscv64-lto-e5a0ec7f:8878377259553587456:26:0)
[02:18:09.490] Build status: STARTED
[02:18:09.490] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-minimal.riscv64-lto-e5a0ec7f:8878377259553587456:27:0) after 8m19s
[02:26:28.498] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-minimal.riscv64-lto-e5a0ec7f:8878377259553587456:27:0)
[02:26:28.512] Build status: STARTED
[02:26:28.512] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-minimal.riscv64-lto-e5a0ec7f:8878377259553587456:28:0) after 5m50s
[02:31:05.820] Received PubSub notification, asking Buildbucket for the build status
[02:31:05.847] Build:
{
"id": "8712947197436142577",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "minimal.riscv64-lto"
},
"createdBy": "project:fuchsia",
"createTime": "2025-06-05T00:24:19.809938529Z",
"startTime": "2025-06-05T00:24:27.234316990Z",
"endTime": "2025-06-05T02:31:04.125637203Z",
"updateTime": "2025-06-05T02:31:04.125637203Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "41fbbe70b56fec82f4161e5b3d884bed8d47f95d",
"ref": "refs/heads/main"
}
}
}
[02:31:05.847] Invocation finished in 2h6m47.441084619s with status SUCCEEDED