[23:47:59.024] New invocation is queued and will start shortly
[23:48:00.098] Starting the invocation (attempt 1)
[23:48:00.121] Popped buildset tag "buildset:commit/gitiles/pigweed.googlesource.com/zephyr/zephyr-bazel/+/e41497d078ddfea7665f50f0854ae2ccc1837962"
[23:48:00.121] Popped gitiles commit info from properties and tags
[23:48:00.121] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[23:48:00.122] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[23:48:00.122] Buildbucket request:
{
"requestId": "8858538809166619616",
"builder": {
"project": "pigweed",
"bucket": "zephyr.dev.ci",
"builder": "zephyr-examples-hello-bazel"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8858538809166619616",
"job": "pigweed/zephyr.dev.ci-zephyr-examples-hello-bazel",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/zephyr/zephyr-bazel",
"revision": "e41497d078ddfea7665f50f0854ae2ccc1837962"
},
"id": "https://pigweed.googlesource.com/zephyr/zephyr-bazel/+/refs/heads/main@e41497d078ddfea7665f50f0854ae2ccc1837962",
"title": "e41497d078ddfea7665f50f0854ae2ccc1837962",
"url": "https://pigweed.googlesource.com/zephyr/zephyr-bazel/+/e41497d078ddfea7665f50f0854ae2ccc1837962"
}
]
}
},
"gitilesCommit": {
"host": "pigweed.googlesource.com",
"project": "zephyr/zephyr-bazel",
"id": "e41497d078ddfea7665f50f0854ae2ccc1837962",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8858538809166619616"
},
{
"key": "scheduler_job_id",
"value": "pigweed/zephyr.dev.ci-zephyr-examples-hello-bazel"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[23:48:00.497] Scheduled build:
{
"id": "8693108747278473121",
"builder": {
"project": "pigweed",
"bucket": "zephyr.dev.ci",
"builder": "zephyr-examples-hello-bazel"
},
"createdBy": "project:pigweed",
"createTime": "2026-01-09T23:48:00.202439734Z",
"updateTime": "2026-01-09T23:48:00.202439734Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "pigweed.googlesource.com",
"project": "zephyr/zephyr-bazel",
"id": "e41497d078ddfea7665f50f0854ae2ccc1837962",
"ref": "refs/heads/main"
}
}
}
[23:48:00.497] Task URL: https://cr-buildbucket.appspot.com/build/8693108747278473121
[23:48:00.497] Scheduling timer "check-buildbucket-build-status" (pigweed/zephyr.dev.ci-zephyr-examples-hello-bazel:8858538809166619616:2:0) after 4m16s
[23:48:03.084] Received PubSub notification, asking Buildbucket for the build status
[23:48:03.100] Build status: SCHEDULED
[23:52:16.508] Handling timer "check-buildbucket-build-status" (pigweed/zephyr.dev.ci-zephyr-examples-hello-bazel:8858538809166619616:2:0)
[23:52:16.525] Build status: SCHEDULED
[23:52:16.525] Scheduling timer "check-buildbucket-build-status" (pigweed/zephyr.dev.ci-zephyr-examples-hello-bazel:8858538809166619616:4:0) after 4m17s
[23:56:33.638] Handling timer "check-buildbucket-build-status" (pigweed/zephyr.dev.ci-zephyr-examples-hello-bazel:8858538809166619616:4:0)
[23:56:33.673] Build status: SCHEDULED
[23:56:33.673] Scheduling timer "check-buildbucket-build-status" (pigweed/zephyr.dev.ci-zephyr-examples-hello-bazel:8858538809166619616:5:0) after 5m52s
[00:02:25.687] Handling timer "check-buildbucket-build-status" (pigweed/zephyr.dev.ci-zephyr-examples-hello-bazel:8858538809166619616:5:0)
[00:02:25.708] Build status: SCHEDULED
[00:02:25.708] Scheduling timer "check-buildbucket-build-status" (pigweed/zephyr.dev.ci-zephyr-examples-hello-bazel:8858538809166619616:6:0) after 2m42s
[00:05:07.770] Handling timer "check-buildbucket-build-status" (pigweed/zephyr.dev.ci-zephyr-examples-hello-bazel:8858538809166619616:6:0)
[00:05:07.786] Build status: SCHEDULED
[00:05:07.786] Scheduling timer "check-buildbucket-build-status" (pigweed/zephyr.dev.ci-zephyr-examples-hello-bazel:8858538809166619616:7:0) after 2m34s
[00:07:41.860] Handling timer "check-buildbucket-build-status" (pigweed/zephyr.dev.ci-zephyr-examples-hello-bazel:8858538809166619616:7:0)
[00:07:41.885] Build status: SCHEDULED
[00:07:41.885] Scheduling timer "check-buildbucket-build-status" (pigweed/zephyr.dev.ci-zephyr-examples-hello-bazel:8858538809166619616:8:0) after 7m0s
[00:14:42.008] Handling timer "check-buildbucket-build-status" (pigweed/zephyr.dev.ci-zephyr-examples-hello-bazel:8858538809166619616:8:0)
[00:14:42.027] Build status: SCHEDULED
[00:14:42.027] Scheduling timer "check-buildbucket-build-status" (pigweed/zephyr.dev.ci-zephyr-examples-hello-bazel:8858538809166619616:9:0) after 3m11s
[00:17:53.200] Handling timer "check-buildbucket-build-status" (pigweed/zephyr.dev.ci-zephyr-examples-hello-bazel:8858538809166619616:9:0)
[00:17:53.252] Build status: SCHEDULED
[00:17:53.252] Scheduling timer "check-buildbucket-build-status" (pigweed/zephyr.dev.ci-zephyr-examples-hello-bazel:8858538809166619616:10:0) after 9m45s
[00:27:38.218] Handling timer "check-buildbucket-build-status" (pigweed/zephyr.dev.ci-zephyr-examples-hello-bazel:8858538809166619616:10:0)
[00:27:38.241] Build status: SCHEDULED
[00:27:38.241] Scheduling timer "check-buildbucket-build-status" (pigweed/zephyr.dev.ci-zephyr-examples-hello-bazel:8858538809166619616:11:0) after 1m5s
[00:28:43.319] Handling timer "check-buildbucket-build-status" (pigweed/zephyr.dev.ci-zephyr-examples-hello-bazel:8858538809166619616:11:0)
[00:28:43.360] Build status: SCHEDULED
[00:28:43.360] Scheduling timer "check-buildbucket-build-status" (pigweed/zephyr.dev.ci-zephyr-examples-hello-bazel:8858538809166619616:12:0) after 2m18s
[00:31:01.424] Handling timer "check-buildbucket-build-status" (pigweed/zephyr.dev.ci-zephyr-examples-hello-bazel:8858538809166619616:12:0)
[00:31:01.446] Build status: SCHEDULED
[00:31:01.446] Scheduling timer "check-buildbucket-build-status" (pigweed/zephyr.dev.ci-zephyr-examples-hello-bazel:8858538809166619616:13:0) after 3m55s
[00:34:56.539] Handling timer "check-buildbucket-build-status" (pigweed/zephyr.dev.ci-zephyr-examples-hello-bazel:8858538809166619616:13:0)
[00:34:56.554] Build status: SCHEDULED
[00:34:56.554] Scheduling timer "check-buildbucket-build-status" (pigweed/zephyr.dev.ci-zephyr-examples-hello-bazel:8858538809166619616:14:0) after 7m6s
[00:42:02.567] Handling timer "check-buildbucket-build-status" (pigweed/zephyr.dev.ci-zephyr-examples-hello-bazel:8858538809166619616:14:0)
[00:42:02.587] Build status: SCHEDULED
[00:42:02.587] Scheduling timer "check-buildbucket-build-status" (pigweed/zephyr.dev.ci-zephyr-examples-hello-bazel:8858538809166619616:15:0) after 6m41s
[00:48:43.600] Handling timer "check-buildbucket-build-status" (pigweed/zephyr.dev.ci-zephyr-examples-hello-bazel:8858538809166619616:15:0)
[00:48:43.637] Build status: SCHEDULED
[00:48:43.637] Scheduling timer "check-buildbucket-build-status" (pigweed/zephyr.dev.ci-zephyr-examples-hello-bazel:8858538809166619616:16:0) after 8m0s
[00:56:43.689] Handling timer "check-buildbucket-build-status" (pigweed/zephyr.dev.ci-zephyr-examples-hello-bazel:8858538809166619616:16:0)
[00:56:43.733] Build status: SCHEDULED
[00:56:43.733] Scheduling timer "check-buildbucket-build-status" (pigweed/zephyr.dev.ci-zephyr-examples-hello-bazel:8858538809166619616:17:0) after 5m41s
[01:02:24.748] Handling timer "check-buildbucket-build-status" (pigweed/zephyr.dev.ci-zephyr-examples-hello-bazel:8858538809166619616:17:0)
[01:02:24.766] Build status: SCHEDULED
[01:02:24.766] Scheduling timer "check-buildbucket-build-status" (pigweed/zephyr.dev.ci-zephyr-examples-hello-bazel:8858538809166619616:18:0) after 7m12s
[01:09:36.780] Handling timer "check-buildbucket-build-status" (pigweed/zephyr.dev.ci-zephyr-examples-hello-bazel:8858538809166619616:18:0)
[01:09:36.797] Build status: SCHEDULED
[01:09:36.797] Scheduling timer "check-buildbucket-build-status" (pigweed/zephyr.dev.ci-zephyr-examples-hello-bazel:8858538809166619616:19:0) after 5m49s
[01:15:25.865] Handling timer "check-buildbucket-build-status" (pigweed/zephyr.dev.ci-zephyr-examples-hello-bazel:8858538809166619616:19:0)
[01:15:25.882] Build status: SCHEDULED
[01:15:25.883] Scheduling timer "check-buildbucket-build-status" (pigweed/zephyr.dev.ci-zephyr-examples-hello-bazel:8858538809166619616:20:0) after 5m49s
[01:21:14.919] Handling timer "check-buildbucket-build-status" (pigweed/zephyr.dev.ci-zephyr-examples-hello-bazel:8858538809166619616:20:0)
[01:21:14.935] Build status: SCHEDULED
[01:21:14.935] Scheduling timer "check-buildbucket-build-status" (pigweed/zephyr.dev.ci-zephyr-examples-hello-bazel:8858538809166619616:21:0) after 9m2s
[01:30:16.947] Handling timer "check-buildbucket-build-status" (pigweed/zephyr.dev.ci-zephyr-examples-hello-bazel:8858538809166619616:21:0)
[01:30:17.009] Build status: SCHEDULED
[01:30:17.009] Scheduling timer "check-buildbucket-build-status" (pigweed/zephyr.dev.ci-zephyr-examples-hello-bazel:8858538809166619616:22:0) after 5m54s
[01:36:11.037] Handling timer "check-buildbucket-build-status" (pigweed/zephyr.dev.ci-zephyr-examples-hello-bazel:8858538809166619616:22:0)
[01:36:11.061] Build status: SCHEDULED
[01:36:11.061] Scheduling timer "check-buildbucket-build-status" (pigweed/zephyr.dev.ci-zephyr-examples-hello-bazel:8858538809166619616:23:0) after 2m1s
[01:38:12.078] Handling timer "check-buildbucket-build-status" (pigweed/zephyr.dev.ci-zephyr-examples-hello-bazel:8858538809166619616:23:0)
[01:38:12.099] Build status: SCHEDULED
[01:38:12.099] Scheduling timer "check-buildbucket-build-status" (pigweed/zephyr.dev.ci-zephyr-examples-hello-bazel:8858538809166619616:24:0) after 5m25s
[01:43:37.093] Handling timer "check-buildbucket-build-status" (pigweed/zephyr.dev.ci-zephyr-examples-hello-bazel:8858538809166619616:24:0)
[01:43:37.108] Build status: SCHEDULED
[01:43:37.108] Scheduling timer "check-buildbucket-build-status" (pigweed/zephyr.dev.ci-zephyr-examples-hello-bazel:8858538809166619616:25:0) after 4m15s
[01:47:52.124] Handling timer "check-buildbucket-build-status" (pigweed/zephyr.dev.ci-zephyr-examples-hello-bazel:8858538809166619616:25:0)
[01:47:52.155] Build status: SCHEDULED
[01:47:52.155] Scheduling timer "check-buildbucket-build-status" (pigweed/zephyr.dev.ci-zephyr-examples-hello-bazel:8858538809166619616:26:0) after 9m56s
[01:57:48.221] Handling timer "check-buildbucket-build-status" (pigweed/zephyr.dev.ci-zephyr-examples-hello-bazel:8858538809166619616:26:0)
[01:57:48.248] Build status: SCHEDULED
[01:57:48.248] Scheduling timer "check-buildbucket-build-status" (pigweed/zephyr.dev.ci-zephyr-examples-hello-bazel:8858538809166619616:27:0) after 7m15s
[02:05:03.348] Handling timer "check-buildbucket-build-status" (pigweed/zephyr.dev.ci-zephyr-examples-hello-bazel:8858538809166619616:27:0)
[02:05:03.364] Build status: SCHEDULED
[02:05:03.364] Scheduling timer "check-buildbucket-build-status" (pigweed/zephyr.dev.ci-zephyr-examples-hello-bazel:8858538809166619616:28:0) after 6m33s
[02:11:36.392] Handling timer "check-buildbucket-build-status" (pigweed/zephyr.dev.ci-zephyr-examples-hello-bazel:8858538809166619616:28:0)
[02:11:36.426] Build status: SCHEDULED
[02:11:36.426] Scheduling timer "check-buildbucket-build-status" (pigweed/zephyr.dev.ci-zephyr-examples-hello-bazel:8858538809166619616:29:0) after 2m14s
[02:13:50.451] Handling timer "check-buildbucket-build-status" (pigweed/zephyr.dev.ci-zephyr-examples-hello-bazel:8858538809166619616:29:0)
[02:13:50.471] Build status: SCHEDULED
[02:13:50.471] Scheduling timer "check-buildbucket-build-status" (pigweed/zephyr.dev.ci-zephyr-examples-hello-bazel:8858538809166619616:30:0) after 6m39s
[02:20:29.485] Handling timer "check-buildbucket-build-status" (pigweed/zephyr.dev.ci-zephyr-examples-hello-bazel:8858538809166619616:30:0)
[02:20:29.507] Build status: SCHEDULED
[02:20:29.507] Scheduling timer "check-buildbucket-build-status" (pigweed/zephyr.dev.ci-zephyr-examples-hello-bazel:8858538809166619616:31:0) after 1m33s
[02:22:02.510] Handling timer "check-buildbucket-build-status" (pigweed/zephyr.dev.ci-zephyr-examples-hello-bazel:8858538809166619616:31:0)
[02:22:02.531] Build status: SCHEDULED
[02:22:02.531] Scheduling timer "check-buildbucket-build-status" (pigweed/zephyr.dev.ci-zephyr-examples-hello-bazel:8858538809166619616:32:0) after 7m12s
[02:29:14.545] Handling timer "check-buildbucket-build-status" (pigweed/zephyr.dev.ci-zephyr-examples-hello-bazel:8858538809166619616:32:0)
[02:29:14.559] Build status: SCHEDULED
[02:29:14.559] Scheduling timer "check-buildbucket-build-status" (pigweed/zephyr.dev.ci-zephyr-examples-hello-bazel:8858538809166619616:33:0) after 5m38s
[02:34:52.604] Handling timer "check-buildbucket-build-status" (pigweed/zephyr.dev.ci-zephyr-examples-hello-bazel:8858538809166619616:33:0)
[02:34:52.639] Build status: SCHEDULED
[02:34:52.639] Scheduling timer "check-buildbucket-build-status" (pigweed/zephyr.dev.ci-zephyr-examples-hello-bazel:8858538809166619616:34:0) after 2m19s
[02:37:11.655] Handling timer "check-buildbucket-build-status" (pigweed/zephyr.dev.ci-zephyr-examples-hello-bazel:8858538809166619616:34:0)
[02:37:11.671] Build status: SCHEDULED
[02:37:11.671] Scheduling timer "check-buildbucket-build-status" (pigweed/zephyr.dev.ci-zephyr-examples-hello-bazel:8858538809166619616:35:0) after 2m28s
[02:39:39.717] Handling timer "check-buildbucket-build-status" (pigweed/zephyr.dev.ci-zephyr-examples-hello-bazel:8858538809166619616:35:0)
[02:39:39.736] Build status: SCHEDULED
[02:39:39.736] Scheduling timer "check-buildbucket-build-status" (pigweed/zephyr.dev.ci-zephyr-examples-hello-bazel:8858538809166619616:36:0) after 5m31s
[02:45:10.751] Handling timer "check-buildbucket-build-status" (pigweed/zephyr.dev.ci-zephyr-examples-hello-bazel:8858538809166619616:36:0)
[02:45:10.766] Build status: SCHEDULED
[02:45:10.767] Scheduling timer "check-buildbucket-build-status" (pigweed/zephyr.dev.ci-zephyr-examples-hello-bazel:8858538809166619616:37:0) after 5m34s
[02:50:44.781] Handling timer "check-buildbucket-build-status" (pigweed/zephyr.dev.ci-zephyr-examples-hello-bazel:8858538809166619616:37:0)
[02:50:44.795] Build status: SCHEDULED
[02:50:44.795] Scheduling timer "check-buildbucket-build-status" (pigweed/zephyr.dev.ci-zephyr-examples-hello-bazel:8858538809166619616:38:0) after 6m37s
[02:57:21.863] Handling timer "check-buildbucket-build-status" (pigweed/zephyr.dev.ci-zephyr-examples-hello-bazel:8858538809166619616:38:0)
[02:57:21.883] Build status: SCHEDULED
[02:57:21.883] Scheduling timer "check-buildbucket-build-status" (pigweed/zephyr.dev.ci-zephyr-examples-hello-bazel:8858538809166619616:39:0) after 6m49s
[03:04:10.957] Handling timer "check-buildbucket-build-status" (pigweed/zephyr.dev.ci-zephyr-examples-hello-bazel:8858538809166619616:39:0)
[03:04:10.971] Build status: SCHEDULED
[03:04:10.971] Scheduling timer "check-buildbucket-build-status" (pigweed/zephyr.dev.ci-zephyr-examples-hello-bazel:8858538809166619616:40:0) after 7m59s
[03:09:28.306] Received PubSub notification, asking Buildbucket for the build status
[03:09:28.322] Build status: STARTED
[03:10:41.003] Received PubSub notification, asking Buildbucket for the build status
[03:10:41.037] Build:
{
"id": "8693108747278473121",
"builder": {
"project": "pigweed",
"bucket": "zephyr.dev.ci",
"builder": "zephyr-examples-hello-bazel"
},
"createdBy": "project:pigweed",
"createTime": "2026-01-09T23:48:00.202439734Z",
"startTime": "2026-01-10T03:09:28.115612673Z",
"endTime": "2026-01-10T03:10:40.746468255Z",
"updateTime": "2026-01-10T03:10:40.746468255Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "pigweed.googlesource.com",
"project": "zephyr/zephyr-bazel",
"id": "e41497d078ddfea7665f50f0854ae2ccc1837962",
"ref": "refs/heads/main"
}
}
}
[03:10:41.037] Invocation finished in 3h22m42.019864143s with status SUCCEEDED