[02:51:16.619] New invocation is queued and will start shortly
[02:51:17.866] Starting the invocation (attempt 1)
[02:51:17.947] Popped buildset tag "buildset:commit/gitiles/pigweed.googlesource.com/zephyr/zephyr-bazel/+/af96945dd2f6a6b0ff310b051426156fe105b62e"
[02:51:17.947] Popped gitiles commit info from properties and tags
[02:51:17.947] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[02:51:17.947] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[02:51:17.948] Buildbucket request:
{
"requestId": "8865140855905365456",
"builder": {
"project": "pigweed",
"bucket": "zephyr.dev.ci",
"builder": "zephyr-examples-hello-bazel"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8865140855905365456",
"job": "pigweed/zephyr.dev.ci-zephyr-examples-hello-bazel",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/zephyr/zephyr-bazel",
"revision": "af96945dd2f6a6b0ff310b051426156fe105b62e"
},
"id": "https://pigweed.googlesource.com/zephyr/zephyr-bazel/+/refs/heads/main@af96945dd2f6a6b0ff310b051426156fe105b62e",
"title": "af96945dd2f6a6b0ff310b051426156fe105b62e",
"url": "https://pigweed.googlesource.com/zephyr/zephyr-bazel/+/af96945dd2f6a6b0ff310b051426156fe105b62e"
}
]
}
},
"gitilesCommit": {
"host": "pigweed.googlesource.com",
"project": "zephyr/zephyr-bazel",
"id": "af96945dd2f6a6b0ff310b051426156fe105b62e",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8865140855905365456"
},
{
"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"
}
}
[02:51:18.352] Scheduled build:
{
"id": "8699710793759351761",
"builder": {
"project": "pigweed",
"bucket": "zephyr.dev.ci",
"builder": "zephyr-examples-hello-bazel"
},
"createdBy": "project:pigweed",
"createTime": "2025-10-29T02:51:18.037436357Z",
"updateTime": "2025-10-29T02:51:18.037436357Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "pigweed.googlesource.com",
"project": "zephyr/zephyr-bazel",
"id": "af96945dd2f6a6b0ff310b051426156fe105b62e",
"ref": "refs/heads/main"
}
}
}
[02:51:18.352] Task URL: https://cr-buildbucket.appspot.com/build/8699710793759351761
[02:51:18.352] Scheduling timer "check-buildbucket-build-status" (pigweed/zephyr.dev.ci-zephyr-examples-hello-bazel:8865140855905365456:2:0) after 8m31s
[02:51:19.343] Received PubSub notification, asking Buildbucket for the build status
[02:51:19.418] Build status: SCHEDULED
[02:59:49.318] Handling timer "check-buildbucket-build-status" (pigweed/zephyr.dev.ci-zephyr-examples-hello-bazel:8865140855905365456:2:0)
[02:59:49.360] Build status: SCHEDULED
[02:59:49.360] Scheduling timer "check-buildbucket-build-status" (pigweed/zephyr.dev.ci-zephyr-examples-hello-bazel:8865140855905365456:4:0) after 8m27s
[03:08:16.372] Handling timer "check-buildbucket-build-status" (pigweed/zephyr.dev.ci-zephyr-examples-hello-bazel:8865140855905365456:4:0)
[03:08:16.422] Build status: SCHEDULED
[03:08:16.423] Scheduling timer "check-buildbucket-build-status" (pigweed/zephyr.dev.ci-zephyr-examples-hello-bazel:8865140855905365456:5:0) after 2m3s
[03:10:19.438] Handling timer "check-buildbucket-build-status" (pigweed/zephyr.dev.ci-zephyr-examples-hello-bazel:8865140855905365456:5:0)
[03:10:19.523] Build status: SCHEDULED
[03:10:19.523] Scheduling timer "check-buildbucket-build-status" (pigweed/zephyr.dev.ci-zephyr-examples-hello-bazel:8865140855905365456:6:0) after 8m6s
[03:18:25.500] Handling timer "check-buildbucket-build-status" (pigweed/zephyr.dev.ci-zephyr-examples-hello-bazel:8865140855905365456:6:0)
[03:18:25.599] Build status: SCHEDULED
[03:18:25.599] Scheduling timer "check-buildbucket-build-status" (pigweed/zephyr.dev.ci-zephyr-examples-hello-bazel:8865140855905365456:7:0) after 9m7s
[03:27:32.623] Handling timer "check-buildbucket-build-status" (pigweed/zephyr.dev.ci-zephyr-examples-hello-bazel:8865140855905365456:7:0)
[03:27:32.640] Build status: SCHEDULED
[03:27:32.640] Scheduling timer "check-buildbucket-build-status" (pigweed/zephyr.dev.ci-zephyr-examples-hello-bazel:8865140855905365456:8:0) after 9m27s
[03:36:59.700] Handling timer "check-buildbucket-build-status" (pigweed/zephyr.dev.ci-zephyr-examples-hello-bazel:8865140855905365456:8:0)
[03:36:59.772] Build status: SCHEDULED
[03:36:59.772] Scheduling timer "check-buildbucket-build-status" (pigweed/zephyr.dev.ci-zephyr-examples-hello-bazel:8865140855905365456:9:0) after 1m6s
[03:38:05.788] Handling timer "check-buildbucket-build-status" (pigweed/zephyr.dev.ci-zephyr-examples-hello-bazel:8865140855905365456:9:0)
[03:38:05.806] Build status: SCHEDULED
[03:38:05.806] Scheduling timer "check-buildbucket-build-status" (pigweed/zephyr.dev.ci-zephyr-examples-hello-bazel:8865140855905365456:10:0) after 3m54s
[03:41:59.872] Handling timer "check-buildbucket-build-status" (pigweed/zephyr.dev.ci-zephyr-examples-hello-bazel:8865140855905365456:10:0)
[03:41:59.915] Build status: SCHEDULED
[03:41:59.915] Scheduling timer "check-buildbucket-build-status" (pigweed/zephyr.dev.ci-zephyr-examples-hello-bazel:8865140855905365456:11:0) after 8m14s
[03:50:13.927] Handling timer "check-buildbucket-build-status" (pigweed/zephyr.dev.ci-zephyr-examples-hello-bazel:8865140855905365456:11:0)
[03:50:13.953] Build status: SCHEDULED
[03:50:13.953] Scheduling timer "check-buildbucket-build-status" (pigweed/zephyr.dev.ci-zephyr-examples-hello-bazel:8865140855905365456:12:0) after 5m13s
[03:55:26.982] Handling timer "check-buildbucket-build-status" (pigweed/zephyr.dev.ci-zephyr-examples-hello-bazel:8865140855905365456:12:0)
[03:55:27.011] Build status: SCHEDULED
[03:55:27.062] Scheduling timer "check-buildbucket-build-status" (pigweed/zephyr.dev.ci-zephyr-examples-hello-bazel:8865140855905365456:13:0) after 9m0s
[04:04:27.164] Handling timer "check-buildbucket-build-status" (pigweed/zephyr.dev.ci-zephyr-examples-hello-bazel:8865140855905365456:13:0)
[04:04:27.191] Build status: SCHEDULED
[04:04:27.191] Scheduling timer "check-buildbucket-build-status" (pigweed/zephyr.dev.ci-zephyr-examples-hello-bazel:8865140855905365456:14:0) after 6m25s
[04:10:52.221] Handling timer "check-buildbucket-build-status" (pigweed/zephyr.dev.ci-zephyr-examples-hello-bazel:8865140855905365456:14:0)
[04:10:52.249] Build status: SCHEDULED
[04:10:52.249] Scheduling timer "check-buildbucket-build-status" (pigweed/zephyr.dev.ci-zephyr-examples-hello-bazel:8865140855905365456:15:0) after 4m32s
[04:15:24.264] Handling timer "check-buildbucket-build-status" (pigweed/zephyr.dev.ci-zephyr-examples-hello-bazel:8865140855905365456:15:0)
[04:15:24.314] Build status: SCHEDULED
[04:15:24.314] Scheduling timer "check-buildbucket-build-status" (pigweed/zephyr.dev.ci-zephyr-examples-hello-bazel:8865140855905365456:16:0) after 3m53s
[04:19:17.369] Handling timer "check-buildbucket-build-status" (pigweed/zephyr.dev.ci-zephyr-examples-hello-bazel:8865140855905365456:16:0)
[04:19:17.418] Build status: SCHEDULED
[04:19:17.418] Scheduling timer "check-buildbucket-build-status" (pigweed/zephyr.dev.ci-zephyr-examples-hello-bazel:8865140855905365456:17:0) after 4m8s
[04:23:25.711] Handling timer "check-buildbucket-build-status" (pigweed/zephyr.dev.ci-zephyr-examples-hello-bazel:8865140855905365456:17:0)
[04:23:25.729] Build status: SCHEDULED
[04:23:25.729] Scheduling timer "check-buildbucket-build-status" (pigweed/zephyr.dev.ci-zephyr-examples-hello-bazel:8865140855905365456:18:0) after 4m1s
[04:27:26.875] Handling timer "check-buildbucket-build-status" (pigweed/zephyr.dev.ci-zephyr-examples-hello-bazel:8865140855905365456:18:0)
[04:27:26.922] Build status: SCHEDULED
[04:27:26.922] Scheduling timer "check-buildbucket-build-status" (pigweed/zephyr.dev.ci-zephyr-examples-hello-bazel:8865140855905365456:19:0) after 7m21s
[04:34:48.049] Handling timer "check-buildbucket-build-status" (pigweed/zephyr.dev.ci-zephyr-examples-hello-bazel:8865140855905365456:19:0)
[04:34:48.097] Build status: SCHEDULED
[04:34:48.097] Scheduling timer "check-buildbucket-build-status" (pigweed/zephyr.dev.ci-zephyr-examples-hello-bazel:8865140855905365456:20:0) after 4m33s
[04:39:21.112] Handling timer "check-buildbucket-build-status" (pigweed/zephyr.dev.ci-zephyr-examples-hello-bazel:8865140855905365456:20:0)
[04:39:21.146] Build status: SCHEDULED
[04:39:21.146] Scheduling timer "check-buildbucket-build-status" (pigweed/zephyr.dev.ci-zephyr-examples-hello-bazel:8865140855905365456:21:0) after 7m51s
[04:47:12.159] Handling timer "check-buildbucket-build-status" (pigweed/zephyr.dev.ci-zephyr-examples-hello-bazel:8865140855905365456:21:0)
[04:47:12.371] Build status: SCHEDULED
[04:47:12.371] Scheduling timer "check-buildbucket-build-status" (pigweed/zephyr.dev.ci-zephyr-examples-hello-bazel:8865140855905365456:22:0) after 2m55s
[04:50:07.421] Handling timer "check-buildbucket-build-status" (pigweed/zephyr.dev.ci-zephyr-examples-hello-bazel:8865140855905365456:22:0)
[04:50:07.436] Build status: SCHEDULED
[04:50:07.436] Scheduling timer "check-buildbucket-build-status" (pigweed/zephyr.dev.ci-zephyr-examples-hello-bazel:8865140855905365456:23:0) after 9m16s
[04:59:23.602] Handling timer "check-buildbucket-build-status" (pigweed/zephyr.dev.ci-zephyr-examples-hello-bazel:8865140855905365456:23:0)
[04:59:23.627] Build status: SCHEDULED
[04:59:23.627] Scheduling timer "check-buildbucket-build-status" (pigweed/zephyr.dev.ci-zephyr-examples-hello-bazel:8865140855905365456:24:0) after 8m57s
[05:08:20.647] Handling timer "check-buildbucket-build-status" (pigweed/zephyr.dev.ci-zephyr-examples-hello-bazel:8865140855905365456:24:0)
[05:08:21.043] Build status: SCHEDULED
[05:08:21.043] Scheduling timer "check-buildbucket-build-status" (pigweed/zephyr.dev.ci-zephyr-examples-hello-bazel:8865140855905365456:25:0) after 9m30s
[05:17:51.233] Handling timer "check-buildbucket-build-status" (pigweed/zephyr.dev.ci-zephyr-examples-hello-bazel:8865140855905365456:25:0)
[05:17:51.369] Build status: SCHEDULED
[05:17:51.369] Scheduling timer "check-buildbucket-build-status" (pigweed/zephyr.dev.ci-zephyr-examples-hello-bazel:8865140855905365456:26:0) after 4m21s
[05:22:12.387] Handling timer "check-buildbucket-build-status" (pigweed/zephyr.dev.ci-zephyr-examples-hello-bazel:8865140855905365456:26:0)
[05:22:12.403] Build status: SCHEDULED
[05:22:12.403] Scheduling timer "check-buildbucket-build-status" (pigweed/zephyr.dev.ci-zephyr-examples-hello-bazel:8865140855905365456:27:0) after 1m57s
[05:24:09.630] Handling timer "check-buildbucket-build-status" (pigweed/zephyr.dev.ci-zephyr-examples-hello-bazel:8865140855905365456:27:0)
[05:24:09.669] Build status: SCHEDULED
[05:24:09.669] Scheduling timer "check-buildbucket-build-status" (pigweed/zephyr.dev.ci-zephyr-examples-hello-bazel:8865140855905365456:28:0) after 8m35s
[05:32:44.696] Handling timer "check-buildbucket-build-status" (pigweed/zephyr.dev.ci-zephyr-examples-hello-bazel:8865140855905365456:28:0)
[05:32:44.770] Build status: SCHEDULED
[05:32:44.771] Scheduling timer "check-buildbucket-build-status" (pigweed/zephyr.dev.ci-zephyr-examples-hello-bazel:8865140855905365456:29:0) after 2m39s
[05:35:23.797] Handling timer "check-buildbucket-build-status" (pigweed/zephyr.dev.ci-zephyr-examples-hello-bazel:8865140855905365456:29:0)
[05:35:23.818] Build status: SCHEDULED
[05:35:23.818] Scheduling timer "check-buildbucket-build-status" (pigweed/zephyr.dev.ci-zephyr-examples-hello-bazel:8865140855905365456:30:0) after 9m45s
[05:45:08.834] Handling timer "check-buildbucket-build-status" (pigweed/zephyr.dev.ci-zephyr-examples-hello-bazel:8865140855905365456:30:0)
[05:45:08.852] Build status: SCHEDULED
[05:45:08.852] Scheduling timer "check-buildbucket-build-status" (pigweed/zephyr.dev.ci-zephyr-examples-hello-bazel:8865140855905365456:31:0) after 5m1s
[05:50:09.971] Handling timer "check-buildbucket-build-status" (pigweed/zephyr.dev.ci-zephyr-examples-hello-bazel:8865140855905365456:31:0)
[05:50:10.008] Build status: SCHEDULED
[05:50:10.008] Scheduling timer "check-buildbucket-build-status" (pigweed/zephyr.dev.ci-zephyr-examples-hello-bazel:8865140855905365456:32:0) after 8m37s
[05:58:47.048] Handling timer "check-buildbucket-build-status" (pigweed/zephyr.dev.ci-zephyr-examples-hello-bazel:8865140855905365456:32:0)
[05:58:47.116] Build status: SCHEDULED
[05:58:47.116] Scheduling timer "check-buildbucket-build-status" (pigweed/zephyr.dev.ci-zephyr-examples-hello-bazel:8865140855905365456:33:0) after 4m5s
[06:02:52.135] Handling timer "check-buildbucket-build-status" (pigweed/zephyr.dev.ci-zephyr-examples-hello-bazel:8865140855905365456:33:0)
[06:02:52.200] Build status: SCHEDULED
[06:02:52.200] Scheduling timer "check-buildbucket-build-status" (pigweed/zephyr.dev.ci-zephyr-examples-hello-bazel:8865140855905365456:34:0) after 4m54s
[06:07:47.116] Handling timer "check-buildbucket-build-status" (pigweed/zephyr.dev.ci-zephyr-examples-hello-bazel:8865140855905365456:34:0)
[06:07:47.154] Build status: SCHEDULED
[06:07:47.154] Scheduling timer "check-buildbucket-build-status" (pigweed/zephyr.dev.ci-zephyr-examples-hello-bazel:8865140855905365456:35:0) after 2m53s
[06:10:40.168] Handling timer "check-buildbucket-build-status" (pigweed/zephyr.dev.ci-zephyr-examples-hello-bazel:8865140855905365456:35:0)
[06:10:40.185] Build status: SCHEDULED
[06:10:40.185] Scheduling timer "check-buildbucket-build-status" (pigweed/zephyr.dev.ci-zephyr-examples-hello-bazel:8865140855905365456:36:0) after 3m54s
[06:14:34.198] Handling timer "check-buildbucket-build-status" (pigweed/zephyr.dev.ci-zephyr-examples-hello-bazel:8865140855905365456:36:0)
[06:14:34.222] Build status: SCHEDULED
[06:14:34.222] Scheduling timer "check-buildbucket-build-status" (pigweed/zephyr.dev.ci-zephyr-examples-hello-bazel:8865140855905365456:37:0) after 1m20s
[06:15:54.614] Handling timer "check-buildbucket-build-status" (pigweed/zephyr.dev.ci-zephyr-examples-hello-bazel:8865140855905365456:37:0)
[06:15:54.720] Build status: SCHEDULED
[06:15:54.720] Scheduling timer "check-buildbucket-build-status" (pigweed/zephyr.dev.ci-zephyr-examples-hello-bazel:8865140855905365456:38:0) after 1m18s
[06:17:12.940] Handling timer "check-buildbucket-build-status" (pigweed/zephyr.dev.ci-zephyr-examples-hello-bazel:8865140855905365456:38:0)
[06:17:12.957] Build status: SCHEDULED
[06:17:12.957] Scheduling timer "check-buildbucket-build-status" (pigweed/zephyr.dev.ci-zephyr-examples-hello-bazel:8865140855905365456:39:0) after 2m45s
[06:19:58.235] Handling timer "check-buildbucket-build-status" (pigweed/zephyr.dev.ci-zephyr-examples-hello-bazel:8865140855905365456:39:0)
[06:19:58.252] Build status: SCHEDULED
[06:19:58.252] Scheduling timer "check-buildbucket-build-status" (pigweed/zephyr.dev.ci-zephyr-examples-hello-bazel:8865140855905365456:40:0) after 9m17s
[06:29:15.536] Handling timer "check-buildbucket-build-status" (pigweed/zephyr.dev.ci-zephyr-examples-hello-bazel:8865140855905365456:40:0)
[06:29:15.570] Build status: SCHEDULED
[06:29:15.570] Scheduling timer "check-buildbucket-build-status" (pigweed/zephyr.dev.ci-zephyr-examples-hello-bazel:8865140855905365456:41:0) after 9m25s
[06:38:40.687] Handling timer "check-buildbucket-build-status" (pigweed/zephyr.dev.ci-zephyr-examples-hello-bazel:8865140855905365456:41:0)
[06:38:40.702] Build status: SCHEDULED
[06:38:40.702] Scheduling timer "check-buildbucket-build-status" (pigweed/zephyr.dev.ci-zephyr-examples-hello-bazel:8865140855905365456:42:0) after 4m40s
[06:43:20.725] Handling timer "check-buildbucket-build-status" (pigweed/zephyr.dev.ci-zephyr-examples-hello-bazel:8865140855905365456:42:0)
[06:43:20.747] Build status: SCHEDULED
[06:43:20.747] Scheduling timer "check-buildbucket-build-status" (pigweed/zephyr.dev.ci-zephyr-examples-hello-bazel:8865140855905365456:43:0) after 5m15s
[06:48:35.720] Handling timer "check-buildbucket-build-status" (pigweed/zephyr.dev.ci-zephyr-examples-hello-bazel:8865140855905365456:43:0)
[06:48:35.752] Build status: SCHEDULED
[06:48:35.752] Scheduling timer "check-buildbucket-build-status" (pigweed/zephyr.dev.ci-zephyr-examples-hello-bazel:8865140855905365456:44:0) after 2m15s
[06:50:50.905] Handling timer "check-buildbucket-build-status" (pigweed/zephyr.dev.ci-zephyr-examples-hello-bazel:8865140855905365456:44:0)
[06:50:50.919] Build status: SCHEDULED
[06:50:50.919] Scheduling timer "check-buildbucket-build-status" (pigweed/zephyr.dev.ci-zephyr-examples-hello-bazel:8865140855905365456:45:0) after 3m27s
[06:54:18.035] Handling timer "check-buildbucket-build-status" (pigweed/zephyr.dev.ci-zephyr-examples-hello-bazel:8865140855905365456:45:0)
[06:54:18.067] Build status: SCHEDULED
[06:54:18.067] Scheduling timer "check-buildbucket-build-status" (pigweed/zephyr.dev.ci-zephyr-examples-hello-bazel:8865140855905365456:46:0) after 7m31s
[07:01:49.094] Handling timer "check-buildbucket-build-status" (pigweed/zephyr.dev.ci-zephyr-examples-hello-bazel:8865140855905365456:46:0)
[07:01:49.123] Build status: SCHEDULED
[07:01:49.123] Scheduling timer "check-buildbucket-build-status" (pigweed/zephyr.dev.ci-zephyr-examples-hello-bazel:8865140855905365456:47:0) after 1m37s
[07:02:27.046] Received PubSub notification, asking Buildbucket for the build status
[07:02:27.066] Build status: STARTED
[07:03:26.114] Handling timer "check-buildbucket-build-status" (pigweed/zephyr.dev.ci-zephyr-examples-hello-bazel:8865140855905365456:47:0)
[07:03:26.169] Build status: STARTED
[07:03:26.169] Scheduling timer "check-buildbucket-build-status" (pigweed/zephyr.dev.ci-zephyr-examples-hello-bazel:8865140855905365456:49:0) after 1m4s
[07:04:30.185] Handling timer "check-buildbucket-build-status" (pigweed/zephyr.dev.ci-zephyr-examples-hello-bazel:8865140855905365456:49:0)
[07:04:30.278] Build status: STARTED
[07:04:30.278] Scheduling timer "check-buildbucket-build-status" (pigweed/zephyr.dev.ci-zephyr-examples-hello-bazel:8865140855905365456:50:0) after 1m5s
[07:05:35.337] Handling timer "check-buildbucket-build-status" (pigweed/zephyr.dev.ci-zephyr-examples-hello-bazel:8865140855905365456:50:0)
[07:05:35.355] Build status: STARTED
[07:05:35.355] Scheduling timer "check-buildbucket-build-status" (pigweed/zephyr.dev.ci-zephyr-examples-hello-bazel:8865140855905365456:51:0) after 3m9s
[07:05:40.823] Received PubSub notification, asking Buildbucket for the build status
[07:05:40.849] Build:
{
"id": "8699710793759351761",
"builder": {
"project": "pigweed",
"bucket": "zephyr.dev.ci",
"builder": "zephyr-examples-hello-bazel"
},
"createdBy": "project:pigweed",
"createTime": "2025-10-29T02:51:18.037436357Z",
"startTime": "2025-10-29T07:02:23.473382071Z",
"endTime": "2025-10-29T07:05:40.551542140Z",
"updateTime": "2025-10-29T07:05:40.551542140Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "pigweed.googlesource.com",
"project": "zephyr/zephyr-bazel",
"id": "af96945dd2f6a6b0ff310b051426156fe105b62e",
"ref": "refs/heads/main"
}
}
}
[07:05:40.849] Invocation finished in 4h14m24.24160404s with status SUCCEEDED