[06:56:31.216] New invocation is queued and will start shortly
[06:56:32.560] Starting the invocation (attempt 1)
[06:56:32.585] Ignoring gitiles_ref tag without the buildset tag
[06:56:32.585] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[06:56:32.585] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[06:56:32.585] Buildbucket request:
{
"requestId": "8869292886983358608",
"builder": {
"project": "pigweed",
"bucket": "pigweed.dev.ci",
"builder": "roll-commenter-pigweed"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8869292886983358608",
"job": "pigweed/pigweed.dev.ci-roll-commenter-pigweed",
"triggers": [
{
"cron": {
"generation": "1542"
},
"id": "cron:v1:1542"
}
]
}
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8869292886983358608"
},
{
"key": "scheduler_job_id",
"value": "pigweed/pigweed.dev.ci-roll-commenter-pigweed"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[06:56:33.171] Scheduled build:
{
"id": "8703862824698346401",
"builder": {
"project": "pigweed",
"bucket": "pigweed.dev.ci",
"builder": "roll-commenter-pigweed"
},
"createdBy": "project:pigweed",
"createTime": "2025-09-13T06:56:32.770776799Z",
"updateTime": "2025-09-13T06:56:32.770776799Z",
"status": "SCHEDULED",
"input": {
}
}
[06:56:33.171] Task URL: https://cr-buildbucket.appspot.com/build/8703862824698346401
[06:56:33.171] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-roll-commenter-pigweed:8869292886983358608:2:0) after 8m25s
[06:56:33.638] Received PubSub notification, asking Buildbucket for the build status
[06:56:33.655] Build status: SCHEDULED
[07:04:58.254] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-roll-commenter-pigweed:8869292886983358608:2:0)
[07:04:58.270] Build status: SCHEDULED
[07:04:58.270] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-roll-commenter-pigweed:8869292886983358608:4:0) after 6m45s
[07:11:43.408] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-roll-commenter-pigweed:8869292886983358608:4:0)
[07:11:43.432] Build status: SCHEDULED
[07:11:43.432] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-roll-commenter-pigweed:8869292886983358608:5:0) after 3m11s
[07:14:54.582] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-roll-commenter-pigweed:8869292886983358608:5:0)
[07:14:54.637] Build status: SCHEDULED
[07:14:54.637] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-roll-commenter-pigweed:8869292886983358608:6:0) after 5m54s
[07:20:48.902] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-roll-commenter-pigweed:8869292886983358608:6:0)
[07:20:48.924] Build status: SCHEDULED
[07:20:48.924] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-roll-commenter-pigweed:8869292886983358608:7:0) after 2m13s
[07:23:01.980] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-roll-commenter-pigweed:8869292886983358608:7:0)
[07:23:02.021] Build status: SCHEDULED
[07:23:02.021] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-roll-commenter-pigweed:8869292886983358608:8:0) after 9m19s
[07:32:21.117] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-roll-commenter-pigweed:8869292886983358608:8:0)
[07:32:21.137] Build status: SCHEDULED
[07:32:21.137] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-roll-commenter-pigweed:8869292886983358608:9:0) after 7m9s
[07:39:30.152] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-roll-commenter-pigweed:8869292886983358608:9:0)
[07:39:30.171] Build status: SCHEDULED
[07:39:30.171] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-roll-commenter-pigweed:8869292886983358608:10:0) after 2m37s
[07:42:07.196] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-roll-commenter-pigweed:8869292886983358608:10:0)
[07:42:07.228] Build status: SCHEDULED
[07:42:07.228] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-roll-commenter-pigweed:8869292886983358608:11:0) after 4m20s
[07:46:27.340] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-roll-commenter-pigweed:8869292886983358608:11:0)
[07:46:27.357] Build status: SCHEDULED
[07:46:27.357] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-roll-commenter-pigweed:8869292886983358608:12:0) after 4m52s
[07:51:19.336] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-roll-commenter-pigweed:8869292886983358608:12:0)
[07:51:19.377] Build status: SCHEDULED
[07:51:19.377] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-roll-commenter-pigweed:8869292886983358608:13:0) after 6m22s
[07:57:41.494] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-roll-commenter-pigweed:8869292886983358608:13:0)
[07:57:41.522] Build status: SCHEDULED
[07:57:41.522] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-roll-commenter-pigweed:8869292886983358608:14:0) after 5m24s
[08:03:05.635] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-roll-commenter-pigweed:8869292886983358608:14:0)
[08:03:05.661] Build status: SCHEDULED
[08:03:05.661] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-roll-commenter-pigweed:8869292886983358608:15:0) after 1m25s
[08:04:30.674] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-roll-commenter-pigweed:8869292886983358608:15:0)
[08:04:30.694] Build status: SCHEDULED
[08:04:30.694] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-roll-commenter-pigweed:8869292886983358608:16:0) after 4m44s
[08:09:14.872] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-roll-commenter-pigweed:8869292886983358608:16:0)
[08:09:14.906] Build status: SCHEDULED
[08:09:14.906] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-roll-commenter-pigweed:8869292886983358608:17:0) after 7m29s
[08:16:44.106] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-roll-commenter-pigweed:8869292886983358608:17:0)
[08:16:44.121] Build status: SCHEDULED
[08:16:44.121] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-roll-commenter-pigweed:8869292886983358608:18:0) after 4m34s
[08:21:18.190] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-roll-commenter-pigweed:8869292886983358608:18:0)
[08:21:18.207] Build status: SCHEDULED
[08:21:18.207] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-roll-commenter-pigweed:8869292886983358608:19:0) after 5m50s
[08:27:08.219] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-roll-commenter-pigweed:8869292886983358608:19:0)
[08:27:08.232] Build status: SCHEDULED
[08:27:08.232] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-roll-commenter-pigweed:8869292886983358608:20:0) after 4m17s
[08:31:25.240] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-roll-commenter-pigweed:8869292886983358608:20:0)
[08:31:25.255] Build status: SCHEDULED
[08:31:25.255] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-roll-commenter-pigweed:8869292886983358608:21:0) after 6m50s
[08:38:15.391] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-roll-commenter-pigweed:8869292886983358608:21:0)
[08:38:15.410] Build status: SCHEDULED
[08:38:15.410] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-roll-commenter-pigweed:8869292886983358608:22:0) after 5m52s
[08:44:07.425] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-roll-commenter-pigweed:8869292886983358608:22:0)
[08:44:07.449] Build status: SCHEDULED
[08:44:07.449] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-roll-commenter-pigweed:8869292886983358608:23:0) after 2m13s
[08:46:20.475] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-roll-commenter-pigweed:8869292886983358608:23:0)
[08:46:20.532] Build status: SCHEDULED
[08:46:20.532] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-roll-commenter-pigweed:8869292886983358608:24:0) after 6m14s
[08:52:34.598] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-roll-commenter-pigweed:8869292886983358608:24:0)
[08:52:34.614] Build status: SCHEDULED
[08:52:34.614] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-roll-commenter-pigweed:8869292886983358608:25:0) after 6m12s
[08:58:46.720] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-roll-commenter-pigweed:8869292886983358608:25:0)
[08:58:46.739] Build status: SCHEDULED
[08:58:46.739] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-roll-commenter-pigweed:8869292886983358608:26:0) after 2m21s
[09:01:08.139] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-roll-commenter-pigweed:8869292886983358608:26:0)
[09:01:08.227] Build status: SCHEDULED
[09:01:08.227] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-roll-commenter-pigweed:8869292886983358608:27:0) after 2m56s
[09:04:04.243] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-roll-commenter-pigweed:8869292886983358608:27:0)
[09:04:04.258] Build status: SCHEDULED
[09:04:04.258] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-roll-commenter-pigweed:8869292886983358608:28:0) after 9m35s
[09:13:39.280] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-roll-commenter-pigweed:8869292886983358608:28:0)
[09:13:39.310] Build status: SCHEDULED
[09:13:39.310] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-roll-commenter-pigweed:8869292886983358608:29:0) after 8m59s
[09:22:38.511] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-roll-commenter-pigweed:8869292886983358608:29:0)
[09:22:38.553] Build status: SCHEDULED
[09:22:38.553] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-roll-commenter-pigweed:8869292886983358608:30:0) after 5m39s
[09:28:17.658] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-roll-commenter-pigweed:8869292886983358608:30:0)
[09:28:17.682] Build status: SCHEDULED
[09:28:17.682] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-roll-commenter-pigweed:8869292886983358608:31:0) after 7m28s
[09:35:45.725] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-roll-commenter-pigweed:8869292886983358608:31:0)
[09:35:45.741] Build status: SCHEDULED
[09:35:45.741] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-roll-commenter-pigweed:8869292886983358608:32:0) after 3m57s
[09:39:42.765] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-roll-commenter-pigweed:8869292886983358608:32:0)
[09:39:42.790] Build status: SCHEDULED
[09:39:42.790] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-roll-commenter-pigweed:8869292886983358608:33:0) after 6m21s
[09:46:03.808] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-roll-commenter-pigweed:8869292886983358608:33:0)
[09:46:03.824] Build status: SCHEDULED
[09:46:03.824] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-roll-commenter-pigweed:8869292886983358608:34:0) after 4m7s
[09:50:10.842] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-roll-commenter-pigweed:8869292886983358608:34:0)
[09:50:10.858] Build status: SCHEDULED
[09:50:10.858] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-roll-commenter-pigweed:8869292886983358608:35:0) after 1m48s
[09:51:58.870] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-roll-commenter-pigweed:8869292886983358608:35:0)
[09:51:58.885] Build status: SCHEDULED
[09:51:58.885] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-roll-commenter-pigweed:8869292886983358608:36:0) after 4m24s
[09:56:22.897] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-roll-commenter-pigweed:8869292886983358608:36:0)
[09:56:22.913] Build status: SCHEDULED
[09:56:22.913] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-roll-commenter-pigweed:8869292886983358608:37:0) after 5m47s
[10:02:10.030] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-roll-commenter-pigweed:8869292886983358608:37:0)
[10:02:10.056] Build status: SCHEDULED
[10:02:10.056] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-roll-commenter-pigweed:8869292886983358608:38:0) after 2m50s
[10:05:00.094] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-roll-commenter-pigweed:8869292886983358608:38:0)
[10:05:00.109] Build status: SCHEDULED
[10:05:00.109] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-roll-commenter-pigweed:8869292886983358608:39:0) after 5m17s
[10:10:17.125] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-roll-commenter-pigweed:8869292886983358608:39:0)
[10:10:17.143] Build status: SCHEDULED
[10:10:17.143] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-roll-commenter-pigweed:8869292886983358608:40:0) after 3m52s
[10:14:09.160] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-roll-commenter-pigweed:8869292886983358608:40:0)
[10:14:09.194] Build status: SCHEDULED
[10:14:09.194] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-roll-commenter-pigweed:8869292886983358608:41:0) after 8m32s
[10:22:41.210] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-roll-commenter-pigweed:8869292886983358608:41:0)
[10:22:41.238] Build status: SCHEDULED
[10:22:41.238] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-roll-commenter-pigweed:8869292886983358608:42:0) after 3m58s
[10:26:39.238] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-roll-commenter-pigweed:8869292886983358608:42:0)
[10:26:39.255] Build status: SCHEDULED
[10:26:39.255] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-roll-commenter-pigweed:8869292886983358608:43:0) after 8m47s
[10:35:26.340] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-roll-commenter-pigweed:8869292886983358608:43:0)
[10:35:26.364] Build status: SCHEDULED
[10:35:26.364] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-roll-commenter-pigweed:8869292886983358608:44:0) after 8m40s
[10:44:06.379] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-roll-commenter-pigweed:8869292886983358608:44:0)
[10:44:06.394] Build status: SCHEDULED
[10:44:06.394] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-roll-commenter-pigweed:8869292886983358608:45:0) after 3m43s
[10:47:49.407] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-roll-commenter-pigweed:8869292886983358608:45:0)
[10:47:49.452] Build status: SCHEDULED
[10:47:49.452] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-roll-commenter-pigweed:8869292886983358608:46:0) after 7m23s
[10:55:12.500] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-roll-commenter-pigweed:8869292886983358608:46:0)
[10:55:12.518] Build status: SCHEDULED
[10:55:12.518] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-roll-commenter-pigweed:8869292886983358608:47:0) after 7m19s
[11:02:31.708] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-roll-commenter-pigweed:8869292886983358608:47:0)
[11:02:31.724] Build status: SCHEDULED
[11:02:31.724] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-roll-commenter-pigweed:8869292886983358608:48:0) after 2m0s
[11:04:31.760] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-roll-commenter-pigweed:8869292886983358608:48:0)
[11:04:31.785] Build status: SCHEDULED
[11:04:31.785] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-roll-commenter-pigweed:8869292886983358608:49:0) after 4m43s
[11:09:14.852] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-roll-commenter-pigweed:8869292886983358608:49:0)
[11:09:14.898] Build status: SCHEDULED
[11:09:14.898] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-roll-commenter-pigweed:8869292886983358608:50:0) after 6m0s
[11:15:14.876] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-roll-commenter-pigweed:8869292886983358608:50:0)
[11:15:14.899] Build status: SCHEDULED
[11:15:14.899] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-roll-commenter-pigweed:8869292886983358608:51:0) after 7m54s
[11:21:01.176] Received PubSub notification, asking Buildbucket for the build status
[11:21:01.202] Build status: STARTED
[11:21:40.074] Received PubSub notification, asking Buildbucket for the build status
[11:21:40.099] Build:
{
"id": "8703862824698346401",
"builder": {
"project": "pigweed",
"bucket": "pigweed.dev.ci",
"builder": "roll-commenter-pigweed"
},
"createdBy": "project:pigweed",
"createTime": "2025-09-13T06:56:32.770776799Z",
"startTime": "2025-09-13T11:21:00.954283641Z",
"endTime": "2025-09-13T11:21:39.467347939Z",
"updateTime": "2025-09-13T11:21:39.467347939Z",
"status": "SUCCESS",
"input": {
}
}
[11:21:40.099] Invocation finished in 4h25m8.892853937s with status SUCCEEDED