[03:18:28.961] New invocation is queued and will start shortly
[03:18:30.282] Starting the invocation (attempt 1)
[03:18:30.307] Popped buildset tag "buildset:commit/gitiles/pigweed.googlesource.com/pigweed/pigweed/+/1e1f16e0eaf1849b7241ef91e0ef2bd17a12af58"
[03:18:30.307] Popped gitiles commit info from properties and tags
[03:18:30.307] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[03:18:30.307] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[03:18:30.308] Buildbucket request:
{
"requestId": "8870665559218356208",
"builder": {
"project": "pigweed",
"bucket": "pigweed.dev.ci",
"builder": "docs-builder"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8870665559218356208",
"job": "pigweed/pigweed.dev.ci-docs-builder",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "22022489a6742d38ddd821fb814a7aee35094256"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@22022489a6742d38ddd821fb814a7aee35094256",
"title": "22022489a6742d38ddd821fb814a7aee35094256",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/22022489a6742d38ddd821fb814a7aee35094256"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "b9d49277c0c3952873452101a964715d77087614"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@b9d49277c0c3952873452101a964715d77087614",
"title": "b9d49277c0c3952873452101a964715d77087614",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/b9d49277c0c3952873452101a964715d77087614"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "0113cf072d001eaefa848f24409855b33427259e"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@0113cf072d001eaefa848f24409855b33427259e",
"title": "0113cf072d001eaefa848f24409855b33427259e",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/0113cf072d001eaefa848f24409855b33427259e"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "4587c394bd1d8fcb01ba5c801ce486e87566617c"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@4587c394bd1d8fcb01ba5c801ce486e87566617c",
"title": "4587c394bd1d8fcb01ba5c801ce486e87566617c",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/4587c394bd1d8fcb01ba5c801ce486e87566617c"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "5468890445c8be4974e658e37f4e7544e3874042"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@5468890445c8be4974e658e37f4e7544e3874042",
"title": "5468890445c8be4974e658e37f4e7544e3874042",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/5468890445c8be4974e658e37f4e7544e3874042"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "9e9c54dee6ce0df6be063f26dc3a9a1566698fb1"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@9e9c54dee6ce0df6be063f26dc3a9a1566698fb1",
"title": "9e9c54dee6ce0df6be063f26dc3a9a1566698fb1",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/9e9c54dee6ce0df6be063f26dc3a9a1566698fb1"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "dd63e84d7627b190aa0f1a6c8de276b9c9cb6b9c"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@dd63e84d7627b190aa0f1a6c8de276b9c9cb6b9c",
"title": "dd63e84d7627b190aa0f1a6c8de276b9c9cb6b9c",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/dd63e84d7627b190aa0f1a6c8de276b9c9cb6b9c"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "788829b17430e7733fec6014063eed5ee1620f90"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@788829b17430e7733fec6014063eed5ee1620f90",
"title": "788829b17430e7733fec6014063eed5ee1620f90",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/788829b17430e7733fec6014063eed5ee1620f90"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "fcca8df84816b702410402948d0c68c6e5b42abc"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@fcca8df84816b702410402948d0c68c6e5b42abc",
"title": "fcca8df84816b702410402948d0c68c6e5b42abc",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/fcca8df84816b702410402948d0c68c6e5b42abc"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "5d550d2fa57d177d541a7f02816a2919917bb914"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@5d550d2fa57d177d541a7f02816a2919917bb914",
"title": "5d550d2fa57d177d541a7f02816a2919917bb914",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/5d550d2fa57d177d541a7f02816a2919917bb914"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "902cb0b72ecddffcb5f6b42e320bce4971e880d9"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@902cb0b72ecddffcb5f6b42e320bce4971e880d9",
"title": "902cb0b72ecddffcb5f6b42e320bce4971e880d9",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/902cb0b72ecddffcb5f6b42e320bce4971e880d9"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "1e1f16e0eaf1849b7241ef91e0ef2bd17a12af58"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@1e1f16e0eaf1849b7241ef91e0ef2bd17a12af58",
"title": "1e1f16e0eaf1849b7241ef91e0ef2bd17a12af58",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/1e1f16e0eaf1849b7241ef91e0ef2bd17a12af58"
}
]
}
},
"gitilesCommit": {
"host": "pigweed.googlesource.com",
"project": "pigweed/pigweed",
"id": "1e1f16e0eaf1849b7241ef91e0ef2bd17a12af58",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8870665559218356208"
},
{
"key": "scheduler_job_id",
"value": "pigweed/pigweed.dev.ci-docs-builder"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[03:18:30.731] Scheduled build:
{
"id": "8705235497030026145",
"builder": {
"project": "pigweed",
"bucket": "pigweed.dev.ci",
"builder": "docs-builder"
},
"createdBy": "project:pigweed",
"createTime": "2025-08-29T03:18:30.422901897Z",
"updateTime": "2025-08-29T03:18:30.422901897Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "pigweed.googlesource.com",
"project": "pigweed/pigweed",
"id": "1e1f16e0eaf1849b7241ef91e0ef2bd17a12af58",
"ref": "refs/heads/main"
}
}
}
[03:18:30.731] Task URL: https://cr-buildbucket.appspot.com/build/8705235497030026145
[03:18:30.731] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-docs-builder:8870665559218356208:2:0) after 6m32s
[03:18:31.716] Received PubSub notification, asking Buildbucket for the build status
[03:18:31.813] Build status: SCHEDULED
[03:25:02.767] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-docs-builder:8870665559218356208:2:0)
[03:25:02.806] Build status: SCHEDULED
[03:25:02.806] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-docs-builder:8870665559218356208:4:0) after 7m26s
[03:32:28.819] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-docs-builder:8870665559218356208:4:0)
[03:32:28.847] Build status: SCHEDULED
[03:32:28.847] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-docs-builder:8870665559218356208:5:0) after 7m33s
[03:40:01.867] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-docs-builder:8870665559218356208:5:0)
[03:40:01.888] Build status: SCHEDULED
[03:40:01.888] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-docs-builder:8870665559218356208:6:0) after 7m40s
[03:47:42.049] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-docs-builder:8870665559218356208:6:0)
[03:47:42.073] Build status: SCHEDULED
[03:47:42.075] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-docs-builder:8870665559218356208:7:0) after 3m21s
[03:51:03.101] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-docs-builder:8870665559218356208:7:0)
[03:51:03.116] Build status: SCHEDULED
[03:51:03.116] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-docs-builder:8870665559218356208:8:0) after 7m1s
[03:58:04.132] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-docs-builder:8870665559218356208:8:0)
[03:58:04.152] Build status: SCHEDULED
[03:58:04.152] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-docs-builder:8870665559218356208:9:0) after 1m15s
[03:59:19.207] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-docs-builder:8870665559218356208:9:0)
[03:59:19.253] Build status: SCHEDULED
[03:59:19.253] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-docs-builder:8870665559218356208:10:0) after 7m11s
[04:06:30.553] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-docs-builder:8870665559218356208:10:0)
[04:06:30.570] Build status: SCHEDULED
[04:06:30.570] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-docs-builder:8870665559218356208:11:0) after 5m39s
[04:12:09.664] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-docs-builder:8870665559218356208:11:0)
[04:12:09.683] Build status: SCHEDULED
[04:12:09.683] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-docs-builder:8870665559218356208:12:0) after 4m19s
[04:16:28.704] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-docs-builder:8870665559218356208:12:0)
[04:16:28.726] Build status: SCHEDULED
[04:16:28.726] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-docs-builder:8870665559218356208:13:0) after 1m6s
[04:17:34.940] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-docs-builder:8870665559218356208:13:0)
[04:17:34.979] Build status: SCHEDULED
[04:17:34.979] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-docs-builder:8870665559218356208:14:0) after 7m33s
[04:25:08.063] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-docs-builder:8870665559218356208:14:0)
[04:25:08.086] Build status: SCHEDULED
[04:25:08.086] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-docs-builder:8870665559218356208:15:0) after 7m46s
[04:32:54.060] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-docs-builder:8870665559218356208:15:0)
[04:32:54.078] Build status: SCHEDULED
[04:32:54.078] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-docs-builder:8870665559218356208:16:0) after 7m48s
[04:40:42.147] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-docs-builder:8870665559218356208:16:0)
[04:40:42.167] Build status: SCHEDULED
[04:40:42.167] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-docs-builder:8870665559218356208:17:0) after 5m6s
[04:45:48.295] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-docs-builder:8870665559218356208:17:0)
[04:45:48.313] Build status: SCHEDULED
[04:45:48.313] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-docs-builder:8870665559218356208:18:0) after 5m47s
[04:51:35.337] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-docs-builder:8870665559218356208:18:0)
[04:51:35.359] Build status: SCHEDULED
[04:51:35.359] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-docs-builder:8870665559218356208:19:0) after 8m8s
[04:59:43.390] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-docs-builder:8870665559218356208:19:0)
[04:59:43.413] Build status: SCHEDULED
[04:59:43.413] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-docs-builder:8870665559218356208:20:0) after 8m27s
[05:08:10.522] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-docs-builder:8870665559218356208:20:0)
[05:08:10.613] Build status: SCHEDULED
[05:08:10.613] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-docs-builder:8870665559218356208:21:0) after 1m1s
[05:09:11.635] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-docs-builder:8870665559218356208:21:0)
[05:09:11.659] Build status: SCHEDULED
[05:09:11.659] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-docs-builder:8870665559218356208:22:0) after 9m21s
[05:18:32.720] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-docs-builder:8870665559218356208:22:0)
[05:18:32.741] Build status: SCHEDULED
[05:18:32.741] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-docs-builder:8870665559218356208:23:0) after 8m37s
[05:27:09.790] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-docs-builder:8870665559218356208:23:0)
[05:27:09.805] Build status: SCHEDULED
[05:27:09.805] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-docs-builder:8870665559218356208:24:0) after 6m19s
[05:33:28.828] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-docs-builder:8870665559218356208:24:0)
[05:33:28.846] Build status: SCHEDULED
[05:33:28.846] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-docs-builder:8870665559218356208:25:0) after 2m28s
[05:35:56.936] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-docs-builder:8870665559218356208:25:0)
[05:35:56.968] Build status: SCHEDULED
[05:35:56.968] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-docs-builder:8870665559218356208:26:0) after 1m13s
[05:37:10.034] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-docs-builder:8870665559218356208:26:0)
[05:37:10.057] Build status: SCHEDULED
[05:37:10.057] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-docs-builder:8870665559218356208:27:0) after 4m55s
[05:42:05.085] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-docs-builder:8870665559218356208:27:0)
[05:42:05.103] Build status: SCHEDULED
[05:42:05.103] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-docs-builder:8870665559218356208:28:0) after 3m48s
[05:45:53.155] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-docs-builder:8870665559218356208:28:0)
[05:45:53.176] Build status: SCHEDULED
[05:45:53.176] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-docs-builder:8870665559218356208:29:0) after 5m13s
[05:51:06.190] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-docs-builder:8870665559218356208:29:0)
[05:51:06.212] Build status: SCHEDULED
[05:51:06.212] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-docs-builder:8870665559218356208:30:0) after 9m19s
[06:00:25.308] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-docs-builder:8870665559218356208:30:0)
[06:00:25.337] Build status: SCHEDULED
[06:00:25.337] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-docs-builder:8870665559218356208:31:0) after 6m30s
[06:06:55.396] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-docs-builder:8870665559218356208:31:0)
[06:06:55.416] Build status: SCHEDULED
[06:06:55.416] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-docs-builder:8870665559218356208:32:0) after 8m37s
[06:15:32.482] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-docs-builder:8870665559218356208:32:0)
[06:15:32.516] Build status: SCHEDULED
[06:15:32.516] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-docs-builder:8870665559218356208:33:0) after 9m7s
[06:24:39.585] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-docs-builder:8870665559218356208:33:0)
[06:24:39.606] Build status: SCHEDULED
[06:24:39.606] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-docs-builder:8870665559218356208:34:0) after 8m7s
[06:32:46.619] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-docs-builder:8870665559218356208:34:0)
[06:32:46.638] Build status: SCHEDULED
[06:32:46.638] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-docs-builder:8870665559218356208:35:0) after 4m47s
[06:34:28.449] Received PubSub notification, asking Buildbucket for the build status
[06:34:28.516] Build status: STARTED
[06:35:29.749] Received PubSub notification, asking Buildbucket for the build status
[06:35:29.835] Build:
{
"id": "8705235497030026145",
"builder": {
"project": "pigweed",
"bucket": "pigweed.dev.ci",
"builder": "docs-builder"
},
"createdBy": "project:pigweed",
"createTime": "2025-08-29T03:18:30.422901897Z",
"startTime": "2025-08-29T06:34:28.257942080Z",
"endTime": "2025-08-29T06:35:29.388117797Z",
"updateTime": "2025-08-29T06:35:29.388117797Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "pigweed.googlesource.com",
"project": "pigweed/pigweed",
"id": "1e1f16e0eaf1849b7241ef91e0ef2bd17a12af58",
"ref": "refs/heads/main"
}
}
}
[06:35:29.835] Invocation finished in 3h17m0.883902981s with status SUCCEEDED