[01:53:43.282] New invocation is queued and will start shortly
[01:53:44.611] Starting the invocation (attempt 1)
[01:53:44.639] Popped buildset tag "buildset:commit/gitiles/pigweed.googlesource.com/pigweed/pigweed/+/8df80e6d1b10d5abbe75463a845c5b737518158e"
[01:53:44.639] Popped gitiles commit info from properties and tags
[01:53:44.639] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[01:53:44.639] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[01:53:44.639] Buildbucket request:
{
"requestId": "8870036713169162240",
"builder": {
"project": "pigweed",
"bucket": "pigweed.dev.ci",
"builder": "pigweed-linux-static-analysis"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8870036713169162240",
"job": "pigweed/pigweed.dev.ci-pigweed-linux-static-analysis",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "4394dbcd86274f1ab024764b8cd3e22109d4bee2"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@4394dbcd86274f1ab024764b8cd3e22109d4bee2",
"title": "4394dbcd86274f1ab024764b8cd3e22109d4bee2",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/4394dbcd86274f1ab024764b8cd3e22109d4bee2"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "8df80e6d1b10d5abbe75463a845c5b737518158e"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@8df80e6d1b10d5abbe75463a845c5b737518158e",
"title": "8df80e6d1b10d5abbe75463a845c5b737518158e",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/8df80e6d1b10d5abbe75463a845c5b737518158e"
}
]
}
},
"gitilesCommit": {
"host": "pigweed.googlesource.com",
"project": "pigweed/pigweed",
"id": "8df80e6d1b10d5abbe75463a845c5b737518158e",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8870036713169162240"
},
{
"key": "scheduler_job_id",
"value": "pigweed/pigweed.dev.ci-pigweed-linux-static-analysis"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[01:53:45.283] Scheduled build:
{
"id": "8704606651016306817",
"builder": {
"project": "pigweed",
"bucket": "pigweed.dev.ci",
"builder": "pigweed-linux-static-analysis"
},
"createdBy": "project:pigweed",
"createTime": "2025-09-05T01:53:44.714927721Z",
"updateTime": "2025-09-05T01:53:44.714927721Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "pigweed.googlesource.com",
"project": "pigweed/pigweed",
"id": "8df80e6d1b10d5abbe75463a845c5b737518158e",
"ref": "refs/heads/main"
}
},
"canary": true
}
[01:53:45.283] Task URL: https://cr-buildbucket.appspot.com/build/8704606651016306817
[01:53:45.283] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-static-analysis:8870036713169162240:2:0) after 3m21s
[01:53:46.006] Received PubSub notification, asking Buildbucket for the build status
[01:53:46.033] Build status: SCHEDULED
[01:57:06.477] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-static-analysis:8870036713169162240:2:0)
[01:57:06.556] Build status: SCHEDULED
[01:57:06.556] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-static-analysis:8870036713169162240:4:0) after 4m30s
[02:01:36.687] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-static-analysis:8870036713169162240:4:0)
[02:01:36.707] Build status: SCHEDULED
[02:01:36.707] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-static-analysis:8870036713169162240:5:0) after 6m24s
[02:08:00.726] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-static-analysis:8870036713169162240:5:0)
[02:08:00.746] Build status: SCHEDULED
[02:08:00.746] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-static-analysis:8870036713169162240:6:0) after 8m45s
[02:16:45.832] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-static-analysis:8870036713169162240:6:0)
[02:16:45.852] Build status: SCHEDULED
[02:16:45.852] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-static-analysis:8870036713169162240:7:0) after 2m50s
[02:19:35.868] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-static-analysis:8870036713169162240:7:0)
[02:19:35.887] Build status: SCHEDULED
[02:19:35.887] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-static-analysis:8870036713169162240:8:0) after 4m54s
[02:24:29.901] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-static-analysis:8870036713169162240:8:0)
[02:24:29.943] Build status: SCHEDULED
[02:24:29.943] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-static-analysis:8870036713169162240:9:0) after 4m19s
[02:28:48.965] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-static-analysis:8870036713169162240:9:0)
[02:28:49.005] Build status: SCHEDULED
[02:28:49.005] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-static-analysis:8870036713169162240:10:0) after 1m37s
[02:30:26.024] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-static-analysis:8870036713169162240:10:0)
[02:30:26.045] Build status: SCHEDULED
[02:30:26.045] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-static-analysis:8870036713169162240:11:0) after 6m53s
[02:37:19.159] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-static-analysis:8870036713169162240:11:0)
[02:37:19.182] Build status: SCHEDULED
[02:37:19.182] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-static-analysis:8870036713169162240:12:0) after 2m30s
[02:39:49.221] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-static-analysis:8870036713169162240:12:0)
[02:39:49.238] Build status: SCHEDULED
[02:39:49.238] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-static-analysis:8870036713169162240:13:0) after 3m47s
[02:43:36.250] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-static-analysis:8870036713169162240:13:0)
[02:43:36.268] Build status: SCHEDULED
[02:43:36.268] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-static-analysis:8870036713169162240:14:0) after 4m10s
[02:47:46.279] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-static-analysis:8870036713169162240:14:0)
[02:47:46.296] Build status: SCHEDULED
[02:47:46.296] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-static-analysis:8870036713169162240:15:0) after 7m7s
[02:54:53.360] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-static-analysis:8870036713169162240:15:0)
[02:54:53.408] Build status: SCHEDULED
[02:54:53.408] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-static-analysis:8870036713169162240:16:0) after 4m48s
[02:59:41.461] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-static-analysis:8870036713169162240:16:0)
[02:59:41.515] Build status: SCHEDULED
[02:59:41.515] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-static-analysis:8870036713169162240:17:0) after 2m56s
[03:02:37.571] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-static-analysis:8870036713169162240:17:0)
[03:02:37.591] Build status: SCHEDULED
[03:02:37.591] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-static-analysis:8870036713169162240:18:0) after 4m59s
[03:07:36.846] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-static-analysis:8870036713169162240:18:0)
[03:07:36.872] Build status: SCHEDULED
[03:07:36.872] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-static-analysis:8870036713169162240:19:0) after 3m49s
[03:11:25.982] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-static-analysis:8870036713169162240:19:0)
[03:11:26.006] Build status: SCHEDULED
[03:11:26.006] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-static-analysis:8870036713169162240:20:0) after 4m2s
[03:15:28.013] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-static-analysis:8870036713169162240:20:0)
[03:15:28.037] Build status: SCHEDULED
[03:15:28.037] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-static-analysis:8870036713169162240:21:0) after 2m1s
[03:17:29.052] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-static-analysis:8870036713169162240:21:0)
[03:17:29.071] Build status: SCHEDULED
[03:17:29.071] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-static-analysis:8870036713169162240:22:0) after 8m22s
[03:25:51.143] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-static-analysis:8870036713169162240:22:0)
[03:25:51.160] Build status: SCHEDULED
[03:25:51.160] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-static-analysis:8870036713169162240:23:0) after 3m20s
[03:29:11.142] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-static-analysis:8870036713169162240:23:0)
[03:29:11.168] Build status: SCHEDULED
[03:29:11.168] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-static-analysis:8870036713169162240:24:0) after 6m55s
[03:36:06.213] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-static-analysis:8870036713169162240:24:0)
[03:36:06.237] Build status: SCHEDULED
[03:36:06.237] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-static-analysis:8870036713169162240:25:0) after 7m23s
[03:43:29.272] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-static-analysis:8870036713169162240:25:0)
[03:43:29.291] Build status: SCHEDULED
[03:43:29.291] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-static-analysis:8870036713169162240:26:0) after 4m6s
[03:47:35.810] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-static-analysis:8870036713169162240:26:0)
[03:47:35.827] Build status: SCHEDULED
[03:47:35.827] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-static-analysis:8870036713169162240:27:0) after 4m18s
[03:51:53.827] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-static-analysis:8870036713169162240:27:0)
[03:51:53.860] Build status: SCHEDULED
[03:51:53.860] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-static-analysis:8870036713169162240:28:0) after 4m56s
[03:56:49.849] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-static-analysis:8870036713169162240:28:0)
[03:56:49.864] Build status: SCHEDULED
[03:56:49.864] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-static-analysis:8870036713169162240:29:0) after 8m11s
[04:05:00.882] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-static-analysis:8870036713169162240:29:0)
[04:05:01.114] Build status: SCHEDULED
[04:05:01.114] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-static-analysis:8870036713169162240:30:0) after 3m44s
[04:08:45.135] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-static-analysis:8870036713169162240:30:0)
[04:08:45.150] Build status: SCHEDULED
[04:08:45.150] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-static-analysis:8870036713169162240:31:0) after 3m7s
[04:11:52.171] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-static-analysis:8870036713169162240:31:0)
[04:11:52.256] Build status: SCHEDULED
[04:11:52.256] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-static-analysis:8870036713169162240:32:0) after 6m29s
[04:18:21.354] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-static-analysis:8870036713169162240:32:0)
[04:18:21.378] Build status: SCHEDULED
[04:18:21.378] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-static-analysis:8870036713169162240:33:0) after 7m13s
[04:25:35.122] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-static-analysis:8870036713169162240:33:0)
[04:25:35.140] Build status: SCHEDULED
[04:25:35.140] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-static-analysis:8870036713169162240:34:0) after 8m45s
[04:34:20.206] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-static-analysis:8870036713169162240:34:0)
[04:34:20.242] Build status: SCHEDULED
[04:34:20.242] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-static-analysis:8870036713169162240:35:0) after 4m9s
[04:38:29.255] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-static-analysis:8870036713169162240:35:0)
[04:38:29.273] Build status: SCHEDULED
[04:38:29.273] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-static-analysis:8870036713169162240:36:0) after 8m38s
[04:47:07.289] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-static-analysis:8870036713169162240:36:0)
[04:47:07.307] Build status: SCHEDULED
[04:47:07.307] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-static-analysis:8870036713169162240:37:0) after 1m22s
[04:48:29.378] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-static-analysis:8870036713169162240:37:0)
[04:48:29.397] Build status: SCHEDULED
[04:48:29.397] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-static-analysis:8870036713169162240:38:0) after 3m56s
[04:52:25.485] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-static-analysis:8870036713169162240:38:0)
[04:52:25.510] Build status: SCHEDULED
[04:52:25.510] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-static-analysis:8870036713169162240:39:0) after 6m6s
[04:58:31.564] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-static-analysis:8870036713169162240:39:0)
[04:58:31.580] Build status: SCHEDULED
[04:58:31.581] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-static-analysis:8870036713169162240:40:0) after 2m15s
[05:00:46.605] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-static-analysis:8870036713169162240:40:0)
[05:00:46.622] Build status: SCHEDULED
[05:00:46.622] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-static-analysis:8870036713169162240:41:0) after 8m25s
[05:09:11.636] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-static-analysis:8870036713169162240:41:0)
[05:09:11.657] Build status: SCHEDULED
[05:09:11.657] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-static-analysis:8870036713169162240:42:0) after 9m29s
[05:18:40.723] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-static-analysis:8870036713169162240:42:0)
[05:18:40.741] Build status: SCHEDULED
[05:18:40.741] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-static-analysis:8870036713169162240:43:0) after 3m56s
[05:22:36.806] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-static-analysis:8870036713169162240:43:0)
[05:22:36.877] Build status: SCHEDULED
[05:22:36.877] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-static-analysis:8870036713169162240:44:0) after 2m4s
[05:24:41.002] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-static-analysis:8870036713169162240:44:0)
[05:24:41.018] Build status: SCHEDULED
[05:24:41.018] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-static-analysis:8870036713169162240:45:0) after 5m57s
[05:25:16.919] Received PubSub notification, asking Buildbucket for the build status
[05:25:16.935] Build status: STARTED
[05:30:38.042] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-static-analysis:8870036713169162240:45:0)
[05:30:38.226] Build status: STARTED
[05:30:38.226] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-static-analysis:8870036713169162240:47:0) after 6m34s
[05:34:23.532] Received PubSub notification, asking Buildbucket for the build status
[05:34:23.548] Build:
{
"id": "8704606651016306817",
"builder": {
"project": "pigweed",
"bucket": "pigweed.dev.ci",
"builder": "pigweed-linux-static-analysis"
},
"createdBy": "project:pigweed",
"createTime": "2025-09-05T01:53:44.714927721Z",
"startTime": "2025-09-05T05:25:16.667781801Z",
"endTime": "2025-09-05T05:34:17.721497147Z",
"updateTime": "2025-09-05T05:34:17.721497147Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "pigweed.googlesource.com",
"project": "pigweed/pigweed",
"id": "8df80e6d1b10d5abbe75463a845c5b737518158e",
"ref": "refs/heads/main"
}
},
"canary": true
}
[05:34:23.548] Invocation finished in 3h40m40.269915425s with status SUCCEEDED