[00:26:17.620] New invocation is queued and will start shortly
[00:26:18.751] Starting the invocation (attempt 1)
[00:26:18.836] Popped buildset tag "buildset:commit/gitiles/pigweed.googlesource.com/pigweed/pigweed/+/05f07498722601b2a9d039dd44a933770331f1de"
[00:26:18.836] Popped gitiles commit info from properties and tags
[00:26:18.836] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[00:26:18.836] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[00:26:18.837] Buildbucket request:
{
"requestId": "8880279670848084352",
"builder": {
"project": "pigweed",
"bucket": "pigweed.dev.ci",
"builder": "pigweed-linux-zephyr"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8880279670848084352",
"job": "pigweed/pigweed.dev.ci-pigweed-linux-zephyr",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "0171dbf200580843b9bd7770e4f8d61429566ad2"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@0171dbf200580843b9bd7770e4f8d61429566ad2",
"title": "0171dbf200580843b9bd7770e4f8d61429566ad2",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/0171dbf200580843b9bd7770e4f8d61429566ad2"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "4a743e6fd3bed17107e9cfa1bc4d0c280a96c966"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@4a743e6fd3bed17107e9cfa1bc4d0c280a96c966",
"title": "4a743e6fd3bed17107e9cfa1bc4d0c280a96c966",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/4a743e6fd3bed17107e9cfa1bc4d0c280a96c966"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "6a34b9b071ef6c532e8d711fc806791bac568ae6"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@6a34b9b071ef6c532e8d711fc806791bac568ae6",
"title": "6a34b9b071ef6c532e8d711fc806791bac568ae6",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/6a34b9b071ef6c532e8d711fc806791bac568ae6"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "d65cfb71d293ff60f370b907315d62079b23a463"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@d65cfb71d293ff60f370b907315d62079b23a463",
"title": "d65cfb71d293ff60f370b907315d62079b23a463",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/d65cfb71d293ff60f370b907315d62079b23a463"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "599d50f80c756b58c1eec7cc434333d42e6573a2"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@599d50f80c756b58c1eec7cc434333d42e6573a2",
"title": "599d50f80c756b58c1eec7cc434333d42e6573a2",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/599d50f80c756b58c1eec7cc434333d42e6573a2"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "875cdae1ce0c15f465df6a5748fb3e7dd6a1f901"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@875cdae1ce0c15f465df6a5748fb3e7dd6a1f901",
"title": "875cdae1ce0c15f465df6a5748fb3e7dd6a1f901",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/875cdae1ce0c15f465df6a5748fb3e7dd6a1f901"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "1093edd813aa2821f012aeca6746a0d633e18375"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@1093edd813aa2821f012aeca6746a0d633e18375",
"title": "1093edd813aa2821f012aeca6746a0d633e18375",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/1093edd813aa2821f012aeca6746a0d633e18375"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "5ffef4df59c253a0ca73d188ec68ef954b844823"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@5ffef4df59c253a0ca73d188ec68ef954b844823",
"title": "5ffef4df59c253a0ca73d188ec68ef954b844823",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/5ffef4df59c253a0ca73d188ec68ef954b844823"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "05f07498722601b2a9d039dd44a933770331f1de"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@05f07498722601b2a9d039dd44a933770331f1de",
"title": "05f07498722601b2a9d039dd44a933770331f1de",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/05f07498722601b2a9d039dd44a933770331f1de"
}
]
}
},
"gitilesCommit": {
"host": "pigweed.googlesource.com",
"project": "pigweed/pigweed",
"id": "05f07498722601b2a9d039dd44a933770331f1de",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8880279670848084352"
},
{
"key": "scheduler_job_id",
"value": "pigweed/pigweed.dev.ci-pigweed-linux-zephyr"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[00:26:19.216] Scheduled build:
{
"id": "8714849608855442593",
"builder": {
"project": "pigweed",
"bucket": "pigweed.dev.ci",
"builder": "pigweed-linux-zephyr"
},
"createdBy": "project:pigweed",
"createTime": "2025-05-15T00:26:18.899914163Z",
"updateTime": "2025-05-15T00:26:18.899914163Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "pigweed.googlesource.com",
"project": "pigweed/pigweed",
"id": "05f07498722601b2a9d039dd44a933770331f1de",
"ref": "refs/heads/main"
}
}
}
[00:26:19.216] Task URL: https://cr-buildbucket.appspot.com/build/8714849608855442593
[00:26:19.216] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-zephyr:8880279670848084352:2:0) after 2m7s
[00:26:20.221] Received PubSub notification, asking Buildbucket for the build status
[00:26:20.235] Build status: SCHEDULED
[00:28:26.330] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-zephyr:8880279670848084352:2:0)
[00:28:26.377] Build status: SCHEDULED
[00:28:26.377] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-zephyr:8880279670848084352:4:0) after 5m33s
[00:33:59.454] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-zephyr:8880279670848084352:4:0)
[00:33:59.529] Build status: SCHEDULED
[00:33:59.530] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-zephyr:8880279670848084352:5:0) after 7m57s
[00:41:56.546] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-zephyr:8880279670848084352:5:0)
[00:41:56.588] Build status: SCHEDULED
[00:41:56.588] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-zephyr:8880279670848084352:6:0) after 1m29s
[00:43:25.604] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-zephyr:8880279670848084352:6:0)
[00:43:25.629] Build status: SCHEDULED
[00:43:25.629] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-zephyr:8880279670848084352:7:0) after 7m12s
[00:50:37.649] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-zephyr:8880279670848084352:7:0)
[00:50:37.670] Build status: SCHEDULED
[00:50:37.671] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-zephyr:8880279670848084352:8:0) after 2m32s
[00:53:09.796] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-zephyr:8880279670848084352:8:0)
[00:53:09.818] Build status: SCHEDULED
[00:53:09.818] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-zephyr:8880279670848084352:9:0) after 4m51s
[00:58:00.833] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-zephyr:8880279670848084352:9:0)
[00:58:00.859] Build status: SCHEDULED
[00:58:00.859] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-zephyr:8880279670848084352:10:0) after 3m25s
[01:01:25.878] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-zephyr:8880279670848084352:10:0)
[01:01:25.897] Build status: SCHEDULED
[01:01:25.897] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-zephyr:8880279670848084352:11:0) after 5m18s
[01:06:43.949] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-zephyr:8880279670848084352:11:0)
[01:06:43.977] Build status: SCHEDULED
[01:06:43.977] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-zephyr:8880279670848084352:12:0) after 2m11s
[01:08:55.109] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-zephyr:8880279670848084352:12:0)
[01:08:55.168] Build status: SCHEDULED
[01:08:55.168] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-zephyr:8880279670848084352:13:0) after 1m0s
[01:09:55.189] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-zephyr:8880279670848084352:13:0)
[01:09:55.267] Build status: SCHEDULED
[01:09:55.267] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-zephyr:8880279670848084352:14:0) after 5m27s
[01:15:22.305] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-zephyr:8880279670848084352:14:0)
[01:15:22.322] Build status: SCHEDULED
[01:15:22.322] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-zephyr:8880279670848084352:15:0) after 4m57s
[01:20:19.342] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-zephyr:8880279670848084352:15:0)
[01:20:19.366] Build status: SCHEDULED
[01:20:19.366] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-zephyr:8880279670848084352:16:0) after 5m26s
[01:25:45.493] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-zephyr:8880279670848084352:16:0)
[01:25:45.561] Build status: SCHEDULED
[01:25:45.561] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-zephyr:8880279670848084352:17:0) after 7m15s
[01:33:00.587] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-zephyr:8880279670848084352:17:0)
[01:33:00.619] Build status: SCHEDULED
[01:33:00.619] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-zephyr:8880279670848084352:18:0) after 6m31s
[01:39:31.737] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-zephyr:8880279670848084352:18:0)
[01:39:31.767] Build status: SCHEDULED
[01:39:31.767] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-zephyr:8880279670848084352:19:0) after 9m49s
[01:49:20.789] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-zephyr:8880279670848084352:19:0)
[01:49:20.807] Build status: SCHEDULED
[01:49:20.807] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-zephyr:8880279670848084352:20:0) after 9m23s
[01:58:43.993] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-zephyr:8880279670848084352:20:0)
[01:58:44.044] Build status: SCHEDULED
[01:58:44.044] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-zephyr:8880279670848084352:21:0) after 3m35s
[02:02:19.182] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-zephyr:8880279670848084352:21:0)
[02:02:19.200] Build status: SCHEDULED
[02:02:19.200] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-zephyr:8880279670848084352:22:0) after 3m22s
[02:05:41.240] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-zephyr:8880279670848084352:22:0)
[02:05:41.259] Build status: SCHEDULED
[02:05:41.259] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-zephyr:8880279670848084352:23:0) after 5m18s
[02:10:59.286] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-zephyr:8880279670848084352:23:0)
[02:10:59.328] Build status: SCHEDULED
[02:10:59.328] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-zephyr:8880279670848084352:24:0) after 6m48s
[02:17:47.355] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-zephyr:8880279670848084352:24:0)
[02:17:47.373] Build status: SCHEDULED
[02:17:47.373] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-zephyr:8880279670848084352:25:0) after 7m27s
[02:25:14.391] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-zephyr:8880279670848084352:25:0)
[02:25:14.449] Build status: SCHEDULED
[02:25:14.449] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-zephyr:8880279670848084352:26:0) after 9m20s
[02:34:34.493] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-zephyr:8880279670848084352:26:0)
[02:34:34.573] Build status: SCHEDULED
[02:34:34.573] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-zephyr:8880279670848084352:27:0) after 6m46s
[02:41:20.607] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-zephyr:8880279670848084352:27:0)
[02:41:20.626] Build status: SCHEDULED
[02:41:20.626] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-zephyr:8880279670848084352:28:0) after 2m33s
[02:43:53.662] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-zephyr:8880279670848084352:28:0)
[02:43:53.726] Build status: SCHEDULED
[02:43:53.727] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-zephyr:8880279670848084352:29:0) after 1m25s
[02:45:18.784] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-zephyr:8880279670848084352:29:0)
[02:45:18.876] Build status: SCHEDULED
[02:45:18.876] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-zephyr:8880279670848084352:30:0) after 1m8s
[02:46:26.899] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-zephyr:8880279670848084352:30:0)
[02:46:26.935] Build status: SCHEDULED
[02:46:26.935] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-zephyr:8880279670848084352:31:0) after 4m32s
[02:50:59.004] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-zephyr:8880279670848084352:31:0)
[02:50:59.075] Build status: SCHEDULED
[02:50:59.075] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-zephyr:8880279670848084352:32:0) after 7m35s
[02:58:34.138] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-zephyr:8880279670848084352:32:0)
[02:58:34.156] Build status: SCHEDULED
[02:58:34.156] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-zephyr:8880279670848084352:33:0) after 3m9s
[03:01:43.351] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-zephyr:8880279670848084352:33:0)
[03:01:43.368] Build status: SCHEDULED
[03:01:43.368] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-zephyr:8880279670848084352:34:0) after 3m8s
[03:04:51.391] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-zephyr:8880279670848084352:34:0)
[03:04:51.406] Build status: SCHEDULED
[03:04:51.406] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-zephyr:8880279670848084352:35:0) after 8m46s
[03:13:37.453] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-zephyr:8880279670848084352:35:0)
[03:13:37.503] Build status: SCHEDULED
[03:13:37.503] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-zephyr:8880279670848084352:36:0) after 6m39s
[03:20:16.540] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-zephyr:8880279670848084352:36:0)
[03:20:16.565] Build status: SCHEDULED
[03:20:16.565] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-zephyr:8880279670848084352:37:0) after 8m47s
[03:29:03.687] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-zephyr:8880279670848084352:37:0)
[03:29:03.705] Build status: SCHEDULED
[03:29:03.705] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-zephyr:8880279670848084352:38:0) after 2m26s
[03:30:38.800] Received PubSub notification, asking Buildbucket for the build status
[03:30:38.822] Build status: STARTED
[03:31:29.723] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-zephyr:8880279670848084352:38:0)
[03:31:29.745] Build status: STARTED
[03:31:29.745] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-zephyr:8880279670848084352:40:0) after 9m17s
[03:36:26.975] Received PubSub notification, asking Buildbucket for the build status
[03:36:27.006] Build:
{
"id": "8714849608855442593",
"builder": {
"project": "pigweed",
"bucket": "pigweed.dev.ci",
"builder": "pigweed-linux-zephyr"
},
"createdBy": "project:pigweed",
"createTime": "2025-05-15T00:26:18.899914163Z",
"startTime": "2025-05-15T03:30:29.905378637Z",
"endTime": "2025-05-15T03:36:26.095241115Z",
"updateTime": "2025-05-15T03:36:26.095241115Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "pigweed.googlesource.com",
"project": "pigweed/pigweed",
"id": "05f07498722601b2a9d039dd44a933770331f1de",
"ref": "refs/heads/main"
}
}
}
[03:36:27.006] Invocation finished in 3h10m9.391301417s with status SUCCEEDED