[23:27:15.419] New invocation is queued and will start shortly
[23:27:16.814] Starting the invocation (attempt 1)
[23:27:16.842] Popped buildset tag "buildset:commit/gitiles/pigweed.googlesource.com/pigweed/pigweed/+/4cefbefa935422f8a394c243682b54c034c9d9b8"
[23:27:16.842] Popped gitiles commit info from properties and tags
[23:27:16.842] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[23:27:16.843] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[23:27:16.844] Buildbucket request:
{
"requestId": "8872220255115946576",
"builder": {
"project": "pigweed",
"bucket": "pigweed.dev.ci",
"builder": "pigweed-linux-gn-san-msan"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8872220255115946576",
"job": "pigweed/pigweed.dev.ci-pigweed-linux-gn-san-msan",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "d601bc1ec186b03fa5cdfcf3a1311608a23ee3e8"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@d601bc1ec186b03fa5cdfcf3a1311608a23ee3e8",
"title": "d601bc1ec186b03fa5cdfcf3a1311608a23ee3e8",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/d601bc1ec186b03fa5cdfcf3a1311608a23ee3e8"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "652d07ce5457ca1c6be4d889037a961effbf8e67"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@652d07ce5457ca1c6be4d889037a961effbf8e67",
"title": "652d07ce5457ca1c6be4d889037a961effbf8e67",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/652d07ce5457ca1c6be4d889037a961effbf8e67"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "913b3b28a206cf785d4885f91a19397f1ee4d0f3"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@913b3b28a206cf785d4885f91a19397f1ee4d0f3",
"title": "913b3b28a206cf785d4885f91a19397f1ee4d0f3",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/913b3b28a206cf785d4885f91a19397f1ee4d0f3"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "93ebb0136a8706c7af5d183ced7ba605238bd8fd"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@93ebb0136a8706c7af5d183ced7ba605238bd8fd",
"title": "93ebb0136a8706c7af5d183ced7ba605238bd8fd",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/93ebb0136a8706c7af5d183ced7ba605238bd8fd"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "4cefbefa935422f8a394c243682b54c034c9d9b8"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@4cefbefa935422f8a394c243682b54c034c9d9b8",
"title": "4cefbefa935422f8a394c243682b54c034c9d9b8",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/4cefbefa935422f8a394c243682b54c034c9d9b8"
}
]
}
},
"gitilesCommit": {
"host": "pigweed.googlesource.com",
"project": "pigweed/pigweed",
"id": "4cefbefa935422f8a394c243682b54c034c9d9b8",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8872220255115946576"
},
{
"key": "scheduler_job_id",
"value": "pigweed/pigweed.dev.ci-pigweed-linux-gn-san-msan"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[23:27:17.218] Scheduled build:
{
"id": "8706790192903187537",
"builder": {
"project": "pigweed",
"bucket": "pigweed.dev.ci",
"builder": "pigweed-linux-gn-san-msan"
},
"createdBy": "project:pigweed",
"createTime": "2025-08-11T23:27:16.898851899Z",
"updateTime": "2025-08-11T23:27:16.898851899Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "pigweed.googlesource.com",
"project": "pigweed/pigweed",
"id": "4cefbefa935422f8a394c243682b54c034c9d9b8",
"ref": "refs/heads/main"
}
}
}
[23:27:17.218] Task URL: https://cr-buildbucket.appspot.com/build/8706790192903187537
[23:27:17.218] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san-msan:8872220255115946576:2:0) after 6m44s
[23:27:18.181] Received PubSub notification, asking Buildbucket for the build status
[23:27:18.200] Build status: SCHEDULED
[23:34:01.230] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san-msan:8872220255115946576:2:0)
[23:34:01.252] Build status: SCHEDULED
[23:34:01.252] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san-msan:8872220255115946576:4:0) after 1m29s
[23:35:30.320] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san-msan:8872220255115946576:4:0)
[23:35:30.343] Build status: SCHEDULED
[23:35:30.343] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san-msan:8872220255115946576:5:0) after 7m45s
[23:43:15.414] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san-msan:8872220255115946576:5:0)
[23:43:15.464] Build status: SCHEDULED
[23:43:15.464] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san-msan:8872220255115946576:6:0) after 6m16s
[23:49:31.485] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san-msan:8872220255115946576:6:0)
[23:49:31.512] Build status: SCHEDULED
[23:49:31.512] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san-msan:8872220255115946576:7:0) after 7m11s
[23:56:42.527] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san-msan:8872220255115946576:7:0)
[23:56:42.545] Build status: SCHEDULED
[23:56:42.545] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san-msan:8872220255115946576:8:0) after 3m8s
[23:59:50.709] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san-msan:8872220255115946576:8:0)
[23:59:50.776] Build status: SCHEDULED
[23:59:50.776] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san-msan:8872220255115946576:9:0) after 7m7s
[00:06:57.899] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san-msan:8872220255115946576:9:0)
[00:06:57.974] Build status: SCHEDULED
[00:06:57.974] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san-msan:8872220255115946576:10:0) after 5m10s
[00:12:07.963] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san-msan:8872220255115946576:10:0)
[00:12:08.015] Build status: SCHEDULED
[00:12:08.015] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san-msan:8872220255115946576:11:0) after 6m6s
[00:18:13.998] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san-msan:8872220255115946576:11:0)
[00:18:14.021] Build status: SCHEDULED
[00:18:14.021] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san-msan:8872220255115946576:12:0) after 5m9s
[00:23:23.085] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san-msan:8872220255115946576:12:0)
[00:23:23.142] Build status: SCHEDULED
[00:23:23.142] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san-msan:8872220255115946576:13:0) after 7m54s
[00:31:17.239] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san-msan:8872220255115946576:13:0)
[00:31:17.264] Build status: SCHEDULED
[00:31:17.264] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san-msan:8872220255115946576:14:0) after 3m20s
[00:34:37.285] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san-msan:8872220255115946576:14:0)
[00:34:37.302] Build status: SCHEDULED
[00:34:37.302] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san-msan:8872220255115946576:15:0) after 1m59s
[00:36:36.429] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san-msan:8872220255115946576:15:0)
[00:36:36.451] Build status: SCHEDULED
[00:36:36.451] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san-msan:8872220255115946576:16:0) after 4m9s
[00:40:45.473] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san-msan:8872220255115946576:16:0)
[00:40:45.495] Build status: SCHEDULED
[00:40:45.496] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san-msan:8872220255115946576:17:0) after 5m17s
[00:46:02.508] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san-msan:8872220255115946576:17:0)
[00:46:02.526] Build status: SCHEDULED
[00:46:02.526] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san-msan:8872220255115946576:18:0) after 2m30s
[00:48:32.527] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san-msan:8872220255115946576:18:0)
[00:48:32.546] Build status: SCHEDULED
[00:48:32.546] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san-msan:8872220255115946576:19:0) after 8m34s
[00:57:06.621] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san-msan:8872220255115946576:19:0)
[00:57:06.662] Build status: SCHEDULED
[00:57:06.662] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san-msan:8872220255115946576:20:0) after 5m10s
[01:02:16.703] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san-msan:8872220255115946576:20:0)
[01:02:16.721] Build status: SCHEDULED
[01:02:16.721] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san-msan:8872220255115946576:21:0) after 5m44s
[01:08:00.742] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san-msan:8872220255115946576:21:0)
[01:08:00.795] Build status: SCHEDULED
[01:08:00.795] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san-msan:8872220255115946576:22:0) after 1m47s
[01:09:47.817] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san-msan:8872220255115946576:22:0)
[01:09:47.848] Build status: SCHEDULED
[01:09:47.848] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san-msan:8872220255115946576:23:0) after 7m54s
[01:17:41.870] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san-msan:8872220255115946576:23:0)
[01:17:41.921] Build status: SCHEDULED
[01:17:41.921] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san-msan:8872220255115946576:24:0) after 1m42s
[01:19:23.938] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san-msan:8872220255115946576:24:0)
[01:19:23.960] Build status: SCHEDULED
[01:19:23.960] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san-msan:8872220255115946576:25:0) after 4m59s
[01:24:22.977] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san-msan:8872220255115946576:25:0)
[01:24:23.002] Build status: SCHEDULED
[01:24:23.002] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san-msan:8872220255115946576:26:0) after 9m29s
[01:33:52.036] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san-msan:8872220255115946576:26:0)
[01:33:52.056] Build status: SCHEDULED
[01:33:52.056] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san-msan:8872220255115946576:27:0) after 5m46s
[01:39:38.042] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san-msan:8872220255115946576:27:0)
[01:39:38.070] Build status: SCHEDULED
[01:39:38.070] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san-msan:8872220255115946576:28:0) after 6m18s
[01:45:56.090] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san-msan:8872220255115946576:28:0)
[01:45:56.106] Build status: SCHEDULED
[01:45:56.106] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san-msan:8872220255115946576:29:0) after 4m54s
[01:50:50.105] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san-msan:8872220255115946576:29:0)
[01:50:50.126] Build status: SCHEDULED
[01:50:50.126] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san-msan:8872220255115946576:30:0) after 2m31s
[01:53:21.196] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san-msan:8872220255115946576:30:0)
[01:53:21.217] Build status: SCHEDULED
[01:53:21.217] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san-msan:8872220255115946576:31:0) after 3m22s
[01:56:43.241] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san-msan:8872220255115946576:31:0)
[01:56:43.260] Build status: SCHEDULED
[01:56:43.260] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san-msan:8872220255115946576:32:0) after 7m56s
[02:04:39.275] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san-msan:8872220255115946576:32:0)
[02:04:39.298] Build status: SCHEDULED
[02:04:39.298] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san-msan:8872220255115946576:33:0) after 1m39s
[02:06:18.416] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san-msan:8872220255115946576:33:0)
[02:06:18.439] Build status: SCHEDULED
[02:06:18.439] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san-msan:8872220255115946576:34:0) after 3m19s
[02:09:37.459] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san-msan:8872220255115946576:34:0)
[02:09:37.480] Build status: SCHEDULED
[02:09:37.480] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san-msan:8872220255115946576:35:0) after 7m12s
[02:16:49.503] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san-msan:8872220255115946576:35:0)
[02:16:49.654] Build status: SCHEDULED
[02:16:49.654] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san-msan:8872220255115946576:36:0) after 1m39s
[02:18:28.631] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san-msan:8872220255115946576:36:0)
[02:18:28.650] Build status: SCHEDULED
[02:18:28.650] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san-msan:8872220255115946576:37:0) after 3m52s
[02:22:20.674] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san-msan:8872220255115946576:37:0)
[02:22:20.691] Build status: SCHEDULED
[02:22:20.691] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san-msan:8872220255115946576:38:0) after 1m2s
[02:23:22.708] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san-msan:8872220255115946576:38:0)
[02:23:22.726] Build status: SCHEDULED
[02:23:22.726] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san-msan:8872220255115946576:39:0) after 5m30s
[02:28:52.801] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san-msan:8872220255115946576:39:0)
[02:28:52.820] Build status: SCHEDULED
[02:28:52.820] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san-msan:8872220255115946576:40:0) after 7m46s
[02:36:38.888] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san-msan:8872220255115946576:40:0)
[02:36:38.904] Build status: SCHEDULED
[02:36:38.904] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san-msan:8872220255115946576:41:0) after 9m7s
[02:45:45.972] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san-msan:8872220255115946576:41:0)
[02:45:46.005] Build status: SCHEDULED
[02:45:46.005] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san-msan:8872220255115946576:42:0) after 5m59s
[02:51:45.114] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san-msan:8872220255115946576:42:0)
[02:51:45.142] Build status: SCHEDULED
[02:51:45.142] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san-msan:8872220255115946576:43:0) after 3m18s
[02:55:03.542] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san-msan:8872220255115946576:43:0)
[02:55:03.610] Build status: SCHEDULED
[02:55:03.610] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san-msan:8872220255115946576:44:0) after 7m56s
[02:56:15.381] Received PubSub notification, asking Buildbucket for the build status
[02:56:15.403] Build status: STARTED
[03:00:17.616] Received PubSub notification, asking Buildbucket for the build status
[03:00:17.649] Build:
{
"id": "8706790192903187537",
"builder": {
"project": "pigweed",
"bucket": "pigweed.dev.ci",
"builder": "pigweed-linux-gn-san-msan"
},
"createdBy": "project:pigweed",
"createTime": "2025-08-11T23:27:16.898851899Z",
"startTime": "2025-08-12T02:56:15.217915322Z",
"endTime": "2025-08-12T03:00:12.027669833Z",
"updateTime": "2025-08-12T03:00:12.027669833Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "pigweed.googlesource.com",
"project": "pigweed/pigweed",
"id": "4cefbefa935422f8a394c243682b54c034c9d9b8",
"ref": "refs/heads/main"
}
}
}
[03:00:17.649] Invocation finished in 3h33m2.245628404s with status SUCCEEDED