[20:41:59.077] New invocation is queued and will start shortly
[20:42:00.820] Starting the invocation (attempt 1)
[20:42:00.847] Popped buildset tag "buildset:commit/gitiles/pigweed.googlesource.com/zephyr/zephyr-bazel/+/a3e48e4f286a86a3d99ee5c6bebf456826dccbd7"
[20:42:00.847] Popped gitiles commit info from properties and tags
[20:42:00.847] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[20:42:00.847] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[20:42:00.847] Buildbucket request:
{
"requestId": "8843420817828672880",
"builder": {
"project": "pigweed",
"bucket": "zephyr.dev.ci",
"builder": "zephyr-ztests"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8843420817828672880",
"job": "pigweed/zephyr.dev.ci-zephyr-ztests",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/zephyr/zephyr-bazel",
"revision": "31ae13a300be18bcf96fcb6c8aba4de83c0eb885"
},
"id": "https://pigweed.googlesource.com/zephyr/zephyr-bazel/+/refs/heads/main@31ae13a300be18bcf96fcb6c8aba4de83c0eb885",
"title": "31ae13a300be18bcf96fcb6c8aba4de83c0eb885",
"url": "https://pigweed.googlesource.com/zephyr/zephyr-bazel/+/31ae13a300be18bcf96fcb6c8aba4de83c0eb885"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/zephyr/zephyr-bazel",
"revision": "a3e48e4f286a86a3d99ee5c6bebf456826dccbd7"
},
"id": "https://pigweed.googlesource.com/zephyr/zephyr-bazel/+/refs/heads/main@a3e48e4f286a86a3d99ee5c6bebf456826dccbd7",
"title": "a3e48e4f286a86a3d99ee5c6bebf456826dccbd7",
"url": "https://pigweed.googlesource.com/zephyr/zephyr-bazel/+/a3e48e4f286a86a3d99ee5c6bebf456826dccbd7"
}
]
}
},
"gitilesCommit": {
"host": "pigweed.googlesource.com",
"project": "zephyr/zephyr-bazel",
"id": "a3e48e4f286a86a3d99ee5c6bebf456826dccbd7",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8843420817828672880"
},
{
"key": "scheduler_job_id",
"value": "pigweed/zephyr.dev.ci-zephyr-ztests"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[20:42:01.667] Scheduled build:
{
"id": "8677990755071361009",
"builder": {
"project": "pigweed",
"bucket": "zephyr.dev.ci",
"builder": "zephyr-ztests"
},
"createdBy": "project:pigweed",
"createTime": "2026-06-25T20:42:01.085738628Z",
"updateTime": "2026-06-25T20:42:01.085738628Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "pigweed.googlesource.com",
"project": "zephyr/zephyr-bazel",
"id": "a3e48e4f286a86a3d99ee5c6bebf456826dccbd7",
"ref": "refs/heads/main"
}
}
}
[20:42:01.667] Task URL: https://cr-buildbucket.appspot.com/build/8677990755071361009
[20:42:01.667] Scheduling timer "check-buildbucket-build-status" (pigweed/zephyr.dev.ci-zephyr-ztests:8843420817828672880:2:0) after 7m2s
[20:42:02.571] Received PubSub notification, asking Buildbucket for the build status
[20:42:02.598] Build status: SCHEDULED
[20:49:03.683] Handling timer "check-buildbucket-build-status" (pigweed/zephyr.dev.ci-zephyr-ztests:8843420817828672880:2:0)
[20:49:03.711] Build status: SCHEDULED
[20:49:03.711] Scheduling timer "check-buildbucket-build-status" (pigweed/zephyr.dev.ci-zephyr-ztests:8843420817828672880:4:0) after 3m39s
[20:52:42.787] Handling timer "check-buildbucket-build-status" (pigweed/zephyr.dev.ci-zephyr-ztests:8843420817828672880:4:0)
[20:52:42.818] Build status: SCHEDULED
[20:52:42.818] Scheduling timer "check-buildbucket-build-status" (pigweed/zephyr.dev.ci-zephyr-ztests:8843420817828672880:5:0) after 1m56s
[20:54:38.911] Handling timer "check-buildbucket-build-status" (pigweed/zephyr.dev.ci-zephyr-ztests:8843420817828672880:5:0)
[20:54:38.933] Build status: SCHEDULED
[20:54:38.933] Scheduling timer "check-buildbucket-build-status" (pigweed/zephyr.dev.ci-zephyr-ztests:8843420817828672880:6:0) after 3m57s
[20:58:36.043] Handling timer "check-buildbucket-build-status" (pigweed/zephyr.dev.ci-zephyr-ztests:8843420817828672880:6:0)
[20:58:36.060] Build status: SCHEDULED
[20:58:36.060] Scheduling timer "check-buildbucket-build-status" (pigweed/zephyr.dev.ci-zephyr-ztests:8843420817828672880:7:0) after 9m19s
[21:07:55.130] Handling timer "check-buildbucket-build-status" (pigweed/zephyr.dev.ci-zephyr-ztests:8843420817828672880:7:0)
[21:07:55.180] Build status: SCHEDULED
[21:07:55.180] Scheduling timer "check-buildbucket-build-status" (pigweed/zephyr.dev.ci-zephyr-ztests:8843420817828672880:8:0) after 3m18s
[21:11:13.221] Handling timer "check-buildbucket-build-status" (pigweed/zephyr.dev.ci-zephyr-ztests:8843420817828672880:8:0)
[21:11:13.244] Build status: SCHEDULED
[21:11:13.244] Scheduling timer "check-buildbucket-build-status" (pigweed/zephyr.dev.ci-zephyr-ztests:8843420817828672880:9:0) after 7m31s
[21:18:44.259] Handling timer "check-buildbucket-build-status" (pigweed/zephyr.dev.ci-zephyr-ztests:8843420817828672880:9:0)
[21:18:44.295] Build status: SCHEDULED
[21:18:44.295] Scheduling timer "check-buildbucket-build-status" (pigweed/zephyr.dev.ci-zephyr-ztests:8843420817828672880:10:0) after 1m4s
[21:19:48.309] Handling timer "check-buildbucket-build-status" (pigweed/zephyr.dev.ci-zephyr-ztests:8843420817828672880:10:0)
[21:19:48.357] Build status: SCHEDULED
[21:19:48.357] Scheduling timer "check-buildbucket-build-status" (pigweed/zephyr.dev.ci-zephyr-ztests:8843420817828672880:11:0) after 3m58s
[21:23:46.430] Handling timer "check-buildbucket-build-status" (pigweed/zephyr.dev.ci-zephyr-ztests:8843420817828672880:11:0)
[21:23:46.451] Build status: SCHEDULED
[21:23:46.451] Scheduling timer "check-buildbucket-build-status" (pigweed/zephyr.dev.ci-zephyr-ztests:8843420817828672880:12:0) after 6m15s
[21:30:01.635] Handling timer "check-buildbucket-build-status" (pigweed/zephyr.dev.ci-zephyr-ztests:8843420817828672880:12:0)
[21:30:01.674] Build status: SCHEDULED
[21:30:01.674] Scheduling timer "check-buildbucket-build-status" (pigweed/zephyr.dev.ci-zephyr-ztests:8843420817828672880:13:0) after 6m30s
[21:36:31.724] Handling timer "check-buildbucket-build-status" (pigweed/zephyr.dev.ci-zephyr-ztests:8843420817828672880:13:0)
[21:36:31.770] Build status: SCHEDULED
[21:36:31.770] Scheduling timer "check-buildbucket-build-status" (pigweed/zephyr.dev.ci-zephyr-ztests:8843420817828672880:14:0) after 7m46s
[21:44:17.795] Handling timer "check-buildbucket-build-status" (pigweed/zephyr.dev.ci-zephyr-ztests:8843420817828672880:14:0)
[21:44:17.813] Build status: SCHEDULED
[21:44:17.813] Scheduling timer "check-buildbucket-build-status" (pigweed/zephyr.dev.ci-zephyr-ztests:8843420817828672880:15:0) after 7m37s
[21:51:54.822] Handling timer "check-buildbucket-build-status" (pigweed/zephyr.dev.ci-zephyr-ztests:8843420817828672880:15:0)
[21:51:54.841] Build status: SCHEDULED
[21:51:54.842] Scheduling timer "check-buildbucket-build-status" (pigweed/zephyr.dev.ci-zephyr-ztests:8843420817828672880:16:0) after 3m19s
[21:55:13.888] Handling timer "check-buildbucket-build-status" (pigweed/zephyr.dev.ci-zephyr-ztests:8843420817828672880:16:0)
[21:55:13.945] Build status: SCHEDULED
[21:55:13.945] Scheduling timer "check-buildbucket-build-status" (pigweed/zephyr.dev.ci-zephyr-ztests:8843420817828672880:17:0) after 3m51s
[21:59:04.961] Handling timer "check-buildbucket-build-status" (pigweed/zephyr.dev.ci-zephyr-ztests:8843420817828672880:17:0)
[21:59:04.978] Build status: SCHEDULED
[21:59:04.978] Scheduling timer "check-buildbucket-build-status" (pigweed/zephyr.dev.ci-zephyr-ztests:8843420817828672880:18:0) after 2m56s
[22:02:01.054] Handling timer "check-buildbucket-build-status" (pigweed/zephyr.dev.ci-zephyr-ztests:8843420817828672880:18:0)
[22:02:01.082] Build status: SCHEDULED
[22:02:01.082] Scheduling timer "check-buildbucket-build-status" (pigweed/zephyr.dev.ci-zephyr-ztests:8843420817828672880:19:0) after 1m31s
[22:03:32.096] Handling timer "check-buildbucket-build-status" (pigweed/zephyr.dev.ci-zephyr-ztests:8843420817828672880:19:0)
[22:03:32.155] Build status: SCHEDULED
[22:03:32.155] Scheduling timer "check-buildbucket-build-status" (pigweed/zephyr.dev.ci-zephyr-ztests:8843420817828672880:20:0) after 7m4s
[22:10:36.200] Handling timer "check-buildbucket-build-status" (pigweed/zephyr.dev.ci-zephyr-ztests:8843420817828672880:20:0)
[22:10:36.237] Build status: SCHEDULED
[22:10:36.237] Scheduling timer "check-buildbucket-build-status" (pigweed/zephyr.dev.ci-zephyr-ztests:8843420817828672880:21:0) after 1m8s
[22:11:44.357] Handling timer "check-buildbucket-build-status" (pigweed/zephyr.dev.ci-zephyr-ztests:8843420817828672880:21:0)
[22:11:44.493] Build status: SCHEDULED
[22:11:44.493] Scheduling timer "check-buildbucket-build-status" (pigweed/zephyr.dev.ci-zephyr-ztests:8843420817828672880:22:0) after 8m56s
[22:20:40.639] Handling timer "check-buildbucket-build-status" (pigweed/zephyr.dev.ci-zephyr-ztests:8843420817828672880:22:0)
[22:20:40.673] Build status: SCHEDULED
[22:20:40.673] Scheduling timer "check-buildbucket-build-status" (pigweed/zephyr.dev.ci-zephyr-ztests:8843420817828672880:23:0) after 4m34s
[22:25:14.708] Handling timer "check-buildbucket-build-status" (pigweed/zephyr.dev.ci-zephyr-ztests:8843420817828672880:23:0)
[22:25:14.726] Build status: SCHEDULED
[22:25:14.726] Scheduling timer "check-buildbucket-build-status" (pigweed/zephyr.dev.ci-zephyr-ztests:8843420817828672880:24:0) after 1m42s
[22:26:56.743] Handling timer "check-buildbucket-build-status" (pigweed/zephyr.dev.ci-zephyr-ztests:8843420817828672880:24:0)
[22:26:56.868] Build status: SCHEDULED
[22:26:56.868] Scheduling timer "check-buildbucket-build-status" (pigweed/zephyr.dev.ci-zephyr-ztests:8843420817828672880:25:0) after 8m3s
[22:34:59.958] Handling timer "check-buildbucket-build-status" (pigweed/zephyr.dev.ci-zephyr-ztests:8843420817828672880:25:0)
[22:35:00.044] Build status: SCHEDULED
[22:35:00.044] Scheduling timer "check-buildbucket-build-status" (pigweed/zephyr.dev.ci-zephyr-ztests:8843420817828672880:26:0) after 1m18s
[22:36:18.061] Handling timer "check-buildbucket-build-status" (pigweed/zephyr.dev.ci-zephyr-ztests:8843420817828672880:26:0)
[22:36:18.188] Build status: SCHEDULED
[22:36:18.188] Scheduling timer "check-buildbucket-build-status" (pigweed/zephyr.dev.ci-zephyr-ztests:8843420817828672880:27:0) after 6m14s
[22:42:32.314] Handling timer "check-buildbucket-build-status" (pigweed/zephyr.dev.ci-zephyr-ztests:8843420817828672880:27:0)
[22:42:32.334] Build status: SCHEDULED
[22:42:32.335] Scheduling timer "check-buildbucket-build-status" (pigweed/zephyr.dev.ci-zephyr-ztests:8843420817828672880:28:0) after 7m42s
[22:50:14.366] Handling timer "check-buildbucket-build-status" (pigweed/zephyr.dev.ci-zephyr-ztests:8843420817828672880:28:0)
[22:50:14.388] Build status: SCHEDULED
[22:50:14.388] Scheduling timer "check-buildbucket-build-status" (pigweed/zephyr.dev.ci-zephyr-ztests:8843420817828672880:29:0) after 6m12s
[22:56:26.504] Handling timer "check-buildbucket-build-status" (pigweed/zephyr.dev.ci-zephyr-ztests:8843420817828672880:29:0)
[22:56:26.523] Build status: SCHEDULED
[22:56:26.523] Scheduling timer "check-buildbucket-build-status" (pigweed/zephyr.dev.ci-zephyr-ztests:8843420817828672880:30:0) after 7m6s
[23:03:32.638] Handling timer "check-buildbucket-build-status" (pigweed/zephyr.dev.ci-zephyr-ztests:8843420817828672880:30:0)
[23:03:32.660] Build status: SCHEDULED
[23:03:32.660] Scheduling timer "check-buildbucket-build-status" (pigweed/zephyr.dev.ci-zephyr-ztests:8843420817828672880:31:0) after 8m45s
[23:12:17.726] Handling timer "check-buildbucket-build-status" (pigweed/zephyr.dev.ci-zephyr-ztests:8843420817828672880:31:0)
[23:12:17.744] Build status: SCHEDULED
[23:12:17.744] Scheduling timer "check-buildbucket-build-status" (pigweed/zephyr.dev.ci-zephyr-ztests:8843420817828672880:32:0) after 9m8s
[23:21:25.745] Handling timer "check-buildbucket-build-status" (pigweed/zephyr.dev.ci-zephyr-ztests:8843420817828672880:32:0)
[23:21:26.047] Build status: SCHEDULED
[23:21:26.047] Scheduling timer "check-buildbucket-build-status" (pigweed/zephyr.dev.ci-zephyr-ztests:8843420817828672880:33:0) after 6m41s
[23:28:07.114] Handling timer "check-buildbucket-build-status" (pigweed/zephyr.dev.ci-zephyr-ztests:8843420817828672880:33:0)
[23:28:07.135] Build status: SCHEDULED
[23:28:07.135] Scheduling timer "check-buildbucket-build-status" (pigweed/zephyr.dev.ci-zephyr-ztests:8843420817828672880:34:0) after 7m21s
[23:33:37.918] Received PubSub notification, asking Buildbucket for the build status
[23:33:37.938] Build status: STARTED
[23:35:28.189] Handling timer "check-buildbucket-build-status" (pigweed/zephyr.dev.ci-zephyr-ztests:8843420817828672880:34:0)
[23:35:28.204] Build status: STARTED
[23:35:28.204] Scheduling timer "check-buildbucket-build-status" (pigweed/zephyr.dev.ci-zephyr-ztests:8843420817828672880:36:0) after 1m52s
[23:37:20.325] Handling timer "check-buildbucket-build-status" (pigweed/zephyr.dev.ci-zephyr-ztests:8843420817828672880:36:0)
[23:37:20.351] Build status: STARTED
[23:37:20.351] Scheduling timer "check-buildbucket-build-status" (pigweed/zephyr.dev.ci-zephyr-ztests:8843420817828672880:37:0) after 7m26s
[23:39:59.250] Received PubSub notification, asking Buildbucket for the build status
[23:39:59.293] Build:
{
"id": "8677990755071361009",
"builder": {
"project": "pigweed",
"bucket": "zephyr.dev.ci",
"builder": "zephyr-ztests"
},
"createdBy": "project:pigweed",
"createTime": "2026-06-25T20:42:01.085738628Z",
"startTime": "2026-06-25T23:33:37.006597713Z",
"endTime": "2026-06-25T23:39:57.910666716Z",
"updateTime": "2026-06-25T23:39:57.910666716Z",
"status": "FAILURE",
"input": {
"gitilesCommit": {
"host": "pigweed.googlesource.com",
"project": "zephyr/zephyr-bazel",
"id": "a3e48e4f286a86a3d99ee5c6bebf456826dccbd7",
"ref": "refs/heads/main"
}
}
}
[23:39:59.293] Invocation finished in 2h58m0.220421593s with status FAILED