[22:22:18.933] New invocation is queued and will start shortly
[22:22:20.232] Starting the invocation (attempt 1)
[22:22:20.254] Popped buildset tag "buildset:commit/gitiles/pigweed.googlesource.com/pigweed/pigweed/+/0940153895a1af08bc35bc9f8e0b2640167e1229"
[22:22:20.254] Popped gitiles commit info from properties and tags
[22:22:20.255] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[22:22:20.255] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[22:22:20.255] Buildbucket request:
{
"requestId": "8867694492553066336",
"builder": {
"project": "pigweed",
"bucket": "pigweed.dev.ci",
"builder": "pigweed-linux-cmake-host-clang"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8867694492553066336",
"job": "pigweed/pigweed.dev.ci-pigweed-linux-cmake-host-clang",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "421eeee13eaf62e92a54ecc7fa8d055ffc581887"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@421eeee13eaf62e92a54ecc7fa8d055ffc581887",
"title": "421eeee13eaf62e92a54ecc7fa8d055ffc581887",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/421eeee13eaf62e92a54ecc7fa8d055ffc581887"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "a4815ead062b941992c8acbe5ca64797acdcfb15"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@a4815ead062b941992c8acbe5ca64797acdcfb15",
"title": "a4815ead062b941992c8acbe5ca64797acdcfb15",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/a4815ead062b941992c8acbe5ca64797acdcfb15"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "ed2536e014797306ce97b2f3cf6ed82a81837f09"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@ed2536e014797306ce97b2f3cf6ed82a81837f09",
"title": "ed2536e014797306ce97b2f3cf6ed82a81837f09",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/ed2536e014797306ce97b2f3cf6ed82a81837f09"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "a359461453bb48fd2519389b47578e1846e7f863"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@a359461453bb48fd2519389b47578e1846e7f863",
"title": "a359461453bb48fd2519389b47578e1846e7f863",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/a359461453bb48fd2519389b47578e1846e7f863"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "d8bcd592fef64ac4565485af878c534088ca5a33"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@d8bcd592fef64ac4565485af878c534088ca5a33",
"title": "d8bcd592fef64ac4565485af878c534088ca5a33",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/d8bcd592fef64ac4565485af878c534088ca5a33"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "0940153895a1af08bc35bc9f8e0b2640167e1229"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@0940153895a1af08bc35bc9f8e0b2640167e1229",
"title": "0940153895a1af08bc35bc9f8e0b2640167e1229",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/0940153895a1af08bc35bc9f8e0b2640167e1229"
}
]
}
},
"gitilesCommit": {
"host": "pigweed.googlesource.com",
"project": "pigweed/pigweed",
"id": "0940153895a1af08bc35bc9f8e0b2640167e1229",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8867694492553066336"
},
{
"key": "scheduler_job_id",
"value": "pigweed/pigweed.dev.ci-pigweed-linux-cmake-host-clang"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[22:22:20.628] Scheduled build:
{
"id": "8702264430450513409",
"builder": {
"project": "pigweed",
"bucket": "pigweed.dev.ci",
"builder": "pigweed-linux-cmake-host-clang"
},
"createdBy": "project:pigweed",
"createTime": "2025-09-30T22:22:20.311361190Z",
"updateTime": "2025-09-30T22:22:20.311361190Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "pigweed.googlesource.com",
"project": "pigweed/pigweed",
"id": "0940153895a1af08bc35bc9f8e0b2640167e1229",
"ref": "refs/heads/main"
}
},
"canary": true
}
[22:22:20.628] Task URL: https://cr-buildbucket.appspot.com/build/8702264430450513409
[22:22:20.629] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-cmake-host-clang:8867694492553066336:2:0) after 5m40s
[22:22:21.004] Received PubSub notification, asking Buildbucket for the build status
[22:22:21.024] Build status: SCHEDULED
[22:28:00.600] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-cmake-host-clang:8867694492553066336:2:0)
[22:28:00.615] Build status: SCHEDULED
[22:28:00.615] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-cmake-host-clang:8867694492553066336:4:0) after 3m33s
[22:31:33.596] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-cmake-host-clang:8867694492553066336:4:0)
[22:31:33.619] Build status: SCHEDULED
[22:31:33.619] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-cmake-host-clang:8867694492553066336:5:0) after 7m38s
[22:39:11.729] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-cmake-host-clang:8867694492553066336:5:0)
[22:39:11.743] Build status: SCHEDULED
[22:39:11.743] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-cmake-host-clang:8867694492553066336:6:0) after 6m38s
[22:45:49.758] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-cmake-host-clang:8867694492553066336:6:0)
[22:45:49.775] Build status: SCHEDULED
[22:45:49.775] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-cmake-host-clang:8867694492553066336:7:0) after 6m2s
[22:51:51.788] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-cmake-host-clang:8867694492553066336:7:0)
[22:51:51.855] Build status: SCHEDULED
[22:51:51.855] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-cmake-host-clang:8867694492553066336:8:0) after 6m21s
[22:58:12.911] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-cmake-host-clang:8867694492553066336:8:0)
[22:58:13.039] Build status: SCHEDULED
[22:58:13.039] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-cmake-host-clang:8867694492553066336:9:0) after 6m4s
[23:04:17.027] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-cmake-host-clang:8867694492553066336:9:0)
[23:04:17.053] Build status: SCHEDULED
[23:04:17.053] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-cmake-host-clang:8867694492553066336:10:0) after 6m53s
[23:11:10.072] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-cmake-host-clang:8867694492553066336:10:0)
[23:11:10.100] Build status: SCHEDULED
[23:11:10.100] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-cmake-host-clang:8867694492553066336:11:0) after 8m22s
[23:19:32.170] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-cmake-host-clang:8867694492553066336:11:0)
[23:19:32.200] Build status: SCHEDULED
[23:19:32.200] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-cmake-host-clang:8867694492553066336:12:0) after 8m10s
[23:27:42.314] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-cmake-host-clang:8867694492553066336:12:0)
[23:27:42.382] Build status: SCHEDULED
[23:27:42.382] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-cmake-host-clang:8867694492553066336:13:0) after 3m39s
[23:31:21.558] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-cmake-host-clang:8867694492553066336:13:0)
[23:31:21.577] Build status: SCHEDULED
[23:31:21.577] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-cmake-host-clang:8867694492553066336:14:0) after 9m4s
[23:40:25.640] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-cmake-host-clang:8867694492553066336:14:0)
[23:40:25.662] Build status: SCHEDULED
[23:40:25.662] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-cmake-host-clang:8867694492553066336:15:0) after 6m26s
[23:46:51.677] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-cmake-host-clang:8867694492553066336:15:0)
[23:46:51.695] Build status: SCHEDULED
[23:46:51.695] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-cmake-host-clang:8867694492553066336:16:0) after 8m11s
[23:55:02.711] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-cmake-host-clang:8867694492553066336:16:0)
[23:55:02.729] Build status: SCHEDULED
[23:55:02.729] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-cmake-host-clang:8867694492553066336:17:0) after 8m49s
[00:03:51.890] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-cmake-host-clang:8867694492553066336:17:0)
[00:03:51.907] Build status: SCHEDULED
[00:03:51.907] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-cmake-host-clang:8867694492553066336:18:0) after 6m31s
[00:10:22.940] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-cmake-host-clang:8867694492553066336:18:0)
[00:10:22.969] Build status: SCHEDULED
[00:10:22.970] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-cmake-host-clang:8867694492553066336:19:0) after 4m28s
[00:14:51.038] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-cmake-host-clang:8867694492553066336:19:0)
[00:14:51.052] Build status: SCHEDULED
[00:14:51.052] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-cmake-host-clang:8867694492553066336:20:0) after 9m23s
[00:24:14.120] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-cmake-host-clang:8867694492553066336:20:0)
[00:24:14.134] Build status: SCHEDULED
[00:24:14.134] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-cmake-host-clang:8867694492553066336:21:0) after 3m52s
[00:28:06.114] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-cmake-host-clang:8867694492553066336:21:0)
[00:28:06.133] Build status: SCHEDULED
[00:28:06.133] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-cmake-host-clang:8867694492553066336:22:0) after 4m38s
[00:32:44.197] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-cmake-host-clang:8867694492553066336:22:0)
[00:32:44.216] Build status: SCHEDULED
[00:32:44.216] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-cmake-host-clang:8867694492553066336:23:0) after 7m59s
[00:40:43.249] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-cmake-host-clang:8867694492553066336:23:0)
[00:40:43.265] Build status: SCHEDULED
[00:40:43.265] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-cmake-host-clang:8867694492553066336:24:0) after 6m49s
[00:47:32.351] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-cmake-host-clang:8867694492553066336:24:0)
[00:47:32.366] Build status: SCHEDULED
[00:47:32.366] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-cmake-host-clang:8867694492553066336:25:0) after 7m19s
[00:54:51.409] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-cmake-host-clang:8867694492553066336:25:0)
[00:54:51.450] Build status: SCHEDULED
[00:54:51.450] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-cmake-host-clang:8867694492553066336:26:0) after 1m53s
[00:56:44.465] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-cmake-host-clang:8867694492553066336:26:0)
[00:56:44.482] Build status: SCHEDULED
[00:56:44.482] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-cmake-host-clang:8867694492553066336:27:0) after 3m17s
[01:00:01.533] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-cmake-host-clang:8867694492553066336:27:0)
[01:00:01.548] Build status: SCHEDULED
[01:00:01.548] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-cmake-host-clang:8867694492553066336:28:0) after 4m11s
[01:04:12.572] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-cmake-host-clang:8867694492553066336:28:0)
[01:04:12.593] Build status: SCHEDULED
[01:04:12.593] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-cmake-host-clang:8867694492553066336:29:0) after 2m3s
[01:06:15.654] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-cmake-host-clang:8867694492553066336:29:0)
[01:06:15.671] Build status: SCHEDULED
[01:06:15.672] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-cmake-host-clang:8867694492553066336:30:0) after 8m32s
[01:14:47.671] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-cmake-host-clang:8867694492553066336:30:0)
[01:14:47.688] Build status: SCHEDULED
[01:14:47.688] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-cmake-host-clang:8867694492553066336:31:0) after 8m2s
[01:22:49.704] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-cmake-host-clang:8867694492553066336:31:0)
[01:22:49.724] Build status: SCHEDULED
[01:22:49.724] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-cmake-host-clang:8867694492553066336:32:0) after 9m39s
[01:32:28.738] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-cmake-host-clang:8867694492553066336:32:0)
[01:32:28.756] Build status: SCHEDULED
[01:32:28.756] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-cmake-host-clang:8867694492553066336:33:0) after 4m30s
[01:36:58.792] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-cmake-host-clang:8867694492553066336:33:0)
[01:36:58.811] Build status: SCHEDULED
[01:36:58.811] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-cmake-host-clang:8867694492553066336:34:0) after 4m12s
[01:41:10.836] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-cmake-host-clang:8867694492553066336:34:0)
[01:41:10.912] Build status: SCHEDULED
[01:41:10.913] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-cmake-host-clang:8867694492553066336:35:0) after 2m45s
[01:43:55.933] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-cmake-host-clang:8867694492553066336:35:0)
[01:43:55.949] Build status: SCHEDULED
[01:43:55.949] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-cmake-host-clang:8867694492553066336:36:0) after 5m56s
[01:49:52.021] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-cmake-host-clang:8867694492553066336:36:0)
[01:49:52.036] Build status: SCHEDULED
[01:49:52.036] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-cmake-host-clang:8867694492553066336:37:0) after 4m28s
[01:54:20.102] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-cmake-host-clang:8867694492553066336:37:0)
[01:54:20.121] Build status: SCHEDULED
[01:54:20.121] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-cmake-host-clang:8867694492553066336:38:0) after 2m56s
[01:57:16.218] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-cmake-host-clang:8867694492553066336:38:0)
[01:57:16.237] Build status: SCHEDULED
[01:57:16.237] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-cmake-host-clang:8867694492553066336:39:0) after 8m19s
[01:58:54.521] Received PubSub notification, asking Buildbucket for the build status
[01:58:54.544] Build status: STARTED
[02:05:14.705] Received PubSub notification, asking Buildbucket for the build status
[02:05:14.723] Build:
{
"id": "8702264430450513409",
"builder": {
"project": "pigweed",
"bucket": "pigweed.dev.ci",
"builder": "pigweed-linux-cmake-host-clang"
},
"createdBy": "project:pigweed",
"createTime": "2025-09-30T22:22:20.311361190Z",
"startTime": "2025-10-01T01:58:52.236476303Z",
"endTime": "2025-10-01T02:05:14.410575489Z",
"updateTime": "2025-10-01T02:05:14.410575489Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "pigweed.googlesource.com",
"project": "pigweed/pigweed",
"id": "0940153895a1af08bc35bc9f8e0b2640167e1229",
"ref": "refs/heads/main"
}
},
"canary": true
}
[02:05:14.723] Invocation finished in 3h42m55.800682978s with status SUCCEEDED