[00:51:38.142] New invocation is queued and will start shortly
[00:51:39.225] Starting the invocation (attempt 1)
[00:51:39.255] Popped buildset tag "buildset:commit/gitiles/pigweed.googlesource.com/pigweed/pigweed/+/46f12c7fbd0ba980f97b4ad8f926e2609f4f64b2"
[00:51:39.255] Popped gitiles commit info from properties and tags
[00:51:39.256] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[00:51:39.256] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[00:51:39.256] Buildbucket request:
{
"requestId": "8871943155557424288",
"builder": {
"project": "pigweed",
"bucket": "pigweed.dev.ci",
"builder": "pigweed-linux-bazel-main"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8871943155557424288",
"job": "pigweed/pigweed.dev.ci-pigweed-linux-bazel-main",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "4205cc1313322ebf055317f5afaca8468a9b6999"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@4205cc1313322ebf055317f5afaca8468a9b6999",
"title": "4205cc1313322ebf055317f5afaca8468a9b6999",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/4205cc1313322ebf055317f5afaca8468a9b6999"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "ca04ce80918935f69a92a7de85ea729693c7635a"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@ca04ce80918935f69a92a7de85ea729693c7635a",
"title": "ca04ce80918935f69a92a7de85ea729693c7635a",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/ca04ce80918935f69a92a7de85ea729693c7635a"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "cf9ded3a59603f10ef1cc84d194d933af62283f3"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@cf9ded3a59603f10ef1cc84d194d933af62283f3",
"title": "cf9ded3a59603f10ef1cc84d194d933af62283f3",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/cf9ded3a59603f10ef1cc84d194d933af62283f3"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "262e21c0a84598a31712d87d2450f159e43a89df"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@262e21c0a84598a31712d87d2450f159e43a89df",
"title": "262e21c0a84598a31712d87d2450f159e43a89df",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/262e21c0a84598a31712d87d2450f159e43a89df"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "0e3d7f9a594c158b597a93c369881e9cf5471752"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@0e3d7f9a594c158b597a93c369881e9cf5471752",
"title": "0e3d7f9a594c158b597a93c369881e9cf5471752",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/0e3d7f9a594c158b597a93c369881e9cf5471752"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "45c247da72014f0384d0f1a5c126c389464987df"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@45c247da72014f0384d0f1a5c126c389464987df",
"title": "45c247da72014f0384d0f1a5c126c389464987df",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/45c247da72014f0384d0f1a5c126c389464987df"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "46f12c7fbd0ba980f97b4ad8f926e2609f4f64b2"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@46f12c7fbd0ba980f97b4ad8f926e2609f4f64b2",
"title": "46f12c7fbd0ba980f97b4ad8f926e2609f4f64b2",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/46f12c7fbd0ba980f97b4ad8f926e2609f4f64b2"
}
]
}
},
"gitilesCommit": {
"host": "pigweed.googlesource.com",
"project": "pigweed/pigweed",
"id": "46f12c7fbd0ba980f97b4ad8f926e2609f4f64b2",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8871943155557424288"
},
{
"key": "scheduler_job_id",
"value": "pigweed/pigweed.dev.ci-pigweed-linux-bazel-main"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[00:51:39.661] Scheduled build:
{
"id": "8706513093671328801",
"builder": {
"project": "pigweed",
"bucket": "pigweed.dev.ci",
"builder": "pigweed-linux-bazel-main"
},
"createdBy": "project:pigweed",
"createTime": "2025-08-15T00:51:39.318101569Z",
"updateTime": "2025-08-15T00:51:39.318101569Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "pigweed.googlesource.com",
"project": "pigweed/pigweed",
"id": "46f12c7fbd0ba980f97b4ad8f926e2609f4f64b2",
"ref": "refs/heads/main"
}
}
}
[00:51:39.661] Task URL: https://cr-buildbucket.appspot.com/build/8706513093671328801
[00:51:39.661] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-bazel-main:8871943155557424288:2:0) after 2m24s
[00:51:40.680] Received PubSub notification, asking Buildbucket for the build status
[00:51:40.698] Build status: SCHEDULED
[00:54:03.697] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-bazel-main:8871943155557424288:2:0)
[00:54:03.718] Build status: SCHEDULED
[00:54:03.718] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-bazel-main:8871943155557424288:4:0) after 9m37s
[01:03:40.823] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-bazel-main:8871943155557424288:4:0)
[01:03:40.844] Build status: SCHEDULED
[01:03:40.844] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-bazel-main:8871943155557424288:5:0) after 2m19s
[01:05:59.967] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-bazel-main:8871943155557424288:5:0)
[01:06:00.015] Build status: SCHEDULED
[01:06:00.015] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-bazel-main:8871943155557424288:6:0) after 3m10s
[01:09:10.108] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-bazel-main:8871943155557424288:6:0)
[01:09:10.134] Build status: SCHEDULED
[01:09:10.134] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-bazel-main:8871943155557424288:7:0) after 6m57s
[01:16:07.201] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-bazel-main:8871943155557424288:7:0)
[01:16:07.218] Build status: SCHEDULED
[01:16:07.218] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-bazel-main:8871943155557424288:8:0) after 9m55s
[01:26:02.234] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-bazel-main:8871943155557424288:8:0)
[01:26:02.260] Build status: SCHEDULED
[01:26:02.260] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-bazel-main:8871943155557424288:9:0) after 9m59s
[01:36:01.282] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-bazel-main:8871943155557424288:9:0)
[01:36:01.296] Build status: SCHEDULED
[01:36:01.296] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-bazel-main:8871943155557424288:10:0) after 3m39s
[01:39:40.311] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-bazel-main:8871943155557424288:10:0)
[01:39:40.329] Build status: SCHEDULED
[01:39:40.329] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-bazel-main:8871943155557424288:11:0) after 3m57s
[01:43:37.344] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-bazel-main:8871943155557424288:11:0)
[01:43:37.429] Build status: SCHEDULED
[01:43:37.429] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-bazel-main:8871943155557424288:12:0) after 6m13s
[01:49:50.543] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-bazel-main:8871943155557424288:12:0)
[01:49:50.560] Build status: SCHEDULED
[01:49:50.560] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-bazel-main:8871943155557424288:13:0) after 9m11s
[01:59:01.587] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-bazel-main:8871943155557424288:13:0)
[01:59:01.606] Build status: SCHEDULED
[01:59:01.606] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-bazel-main:8871943155557424288:14:0) after 9m5s
[02:08:06.623] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-bazel-main:8871943155557424288:14:0)
[02:08:06.641] Build status: SCHEDULED
[02:08:06.641] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-bazel-main:8871943155557424288:15:0) after 6m54s
[02:15:00.748] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-bazel-main:8871943155557424288:15:0)
[02:15:00.769] Build status: SCHEDULED
[02:15:00.769] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-bazel-main:8871943155557424288:16:0) after 4m8s
[02:19:08.741] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-bazel-main:8871943155557424288:16:0)
[02:19:08.757] Build status: SCHEDULED
[02:19:08.757] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-bazel-main:8871943155557424288:17:0) after 2m40s
[02:21:48.781] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-bazel-main:8871943155557424288:17:0)
[02:21:48.800] Build status: SCHEDULED
[02:21:48.800] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-bazel-main:8871943155557424288:18:0) after 2m9s
[02:23:57.815] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-bazel-main:8871943155557424288:18:0)
[02:23:57.832] Build status: SCHEDULED
[02:23:57.832] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-bazel-main:8871943155557424288:19:0) after 3m53s
[02:27:50.847] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-bazel-main:8871943155557424288:19:0)
[02:27:50.865] Build status: SCHEDULED
[02:27:50.865] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-bazel-main:8871943155557424288:20:0) after 7m16s
[02:35:06.906] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-bazel-main:8871943155557424288:20:0)
[02:35:06.925] Build status: SCHEDULED
[02:35:06.925] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-bazel-main:8871943155557424288:21:0) after 7m42s
[02:42:48.917] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-bazel-main:8871943155557424288:21:0)
[02:42:48.951] Build status: SCHEDULED
[02:42:48.951] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-bazel-main:8871943155557424288:22:0) after 2m37s
[02:45:26.035] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-bazel-main:8871943155557424288:22:0)
[02:45:26.054] Build status: SCHEDULED
[02:45:26.054] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-bazel-main:8871943155557424288:23:0) after 4m53s
[02:50:19.054] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-bazel-main:8871943155557424288:23:0)
[02:50:19.116] Build status: SCHEDULED
[02:50:19.116] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-bazel-main:8871943155557424288:24:0) after 8m1s
[02:58:20.139] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-bazel-main:8871943155557424288:24:0)
[02:58:20.162] Build status: SCHEDULED
[02:58:20.162] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-bazel-main:8871943155557424288:25:0) after 8m16s
[03:06:36.227] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-bazel-main:8871943155557424288:25:0)
[03:06:36.244] Build status: SCHEDULED
[03:06:36.244] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-bazel-main:8871943155557424288:26:0) after 5m34s
[03:12:10.257] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-bazel-main:8871943155557424288:26:0)
[03:12:10.285] Build status: SCHEDULED
[03:12:10.285] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-bazel-main:8871943155557424288:27:0) after 1m58s
[03:14:08.338] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-bazel-main:8871943155557424288:27:0)
[03:14:08.420] Build status: SCHEDULED
[03:14:08.420] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-bazel-main:8871943155557424288:28:0) after 2m13s
[03:16:21.417] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-bazel-main:8871943155557424288:28:0)
[03:16:21.474] Build status: SCHEDULED
[03:16:21.474] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-bazel-main:8871943155557424288:29:0) after 3m54s
[03:20:15.550] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-bazel-main:8871943155557424288:29:0)
[03:20:15.569] Build status: SCHEDULED
[03:20:15.569] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-bazel-main:8871943155557424288:30:0) after 5m3s
[03:25:18.583] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-bazel-main:8871943155557424288:30:0)
[03:25:18.600] Build status: SCHEDULED
[03:25:18.600] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-bazel-main:8871943155557424288:31:0) after 5m9s
[03:30:27.646] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-bazel-main:8871943155557424288:31:0)
[03:30:27.696] Build status: SCHEDULED
[03:30:27.696] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-bazel-main:8871943155557424288:32:0) after 3m20s
[03:33:47.712] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-bazel-main:8871943155557424288:32:0)
[03:33:47.810] Build status: SCHEDULED
[03:33:47.810] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-bazel-main:8871943155557424288:33:0) after 3m5s
[03:36:52.844] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-bazel-main:8871943155557424288:33:0)
[03:36:52.896] Build status: SCHEDULED
[03:36:52.896] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-bazel-main:8871943155557424288:34:0) after 3m16s
[03:40:08.975] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-bazel-main:8871943155557424288:34:0)
[03:40:08.990] Build status: SCHEDULED
[03:40:08.990] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-bazel-main:8871943155557424288:35:0) after 8m5s
[03:48:14.014] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-bazel-main:8871943155557424288:35:0)
[03:48:14.035] Build status: SCHEDULED
[03:48:14.078] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-bazel-main:8871943155557424288:36:0) after 9m23s
[03:57:37.148] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-bazel-main:8871943155557424288:36:0)
[03:57:37.174] Build status: SCHEDULED
[03:57:37.174] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-bazel-main:8871943155557424288:37:0) after 7m6s
[04:04:40.696] Received PubSub notification, asking Buildbucket for the build status
[04:04:40.715] Build status: STARTED
[04:04:43.302] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-bazel-main:8871943155557424288:37:0)
[04:04:43.349] Build status: STARTED
[04:04:43.349] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-bazel-main:8871943155557424288:39:0) after 7m25s
[04:10:34.123] Received PubSub notification, asking Buildbucket for the build status
[04:10:34.184] Build:
{
"id": "8706513093671328801",
"builder": {
"project": "pigweed",
"bucket": "pigweed.dev.ci",
"builder": "pigweed-linux-bazel-main"
},
"createdBy": "project:pigweed",
"createTime": "2025-08-15T00:51:39.318101569Z",
"startTime": "2025-08-15T04:04:40.206464207Z",
"endTime": "2025-08-15T04:10:33.871862852Z",
"updateTime": "2025-08-15T04:10:33.871862852Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "pigweed.googlesource.com",
"project": "pigweed/pigweed",
"id": "46f12c7fbd0ba980f97b4ad8f926e2609f4f64b2",
"ref": "refs/heads/main"
}
}
}
[04:10:34.184] Invocation finished in 3h18m56.048098144s with status SUCCEEDED