[18:38:34.926] New invocation is queued and will start shortly
[18:38:35.985] Starting the invocation (attempt 1)
[18:38:36.010] Popped buildset tag "buildset:commit/gitiles/pigweed.googlesource.com/pigweed/pigweed/+/77f3436156dc56f94aa3e37dca47b8d7fde3aafd"
[18:38:36.010] Popped gitiles commit info from properties and tags
[18:38:36.010] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[18:38:36.011] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[18:38:36.011] Buildbucket request:
{
"requestId": "8880845128766272592",
"builder": {
"project": "pigweed",
"bucket": "pigweed.dev.ci",
"builder": "pigweed-linux-gn-main"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8880845128766272592",
"job": "pigweed/pigweed.dev.ci-pigweed-linux-gn-main",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "c8ac4d8be7262f25c0a6252dea7f388c69d94659"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@c8ac4d8be7262f25c0a6252dea7f388c69d94659",
"title": "c8ac4d8be7262f25c0a6252dea7f388c69d94659",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/c8ac4d8be7262f25c0a6252dea7f388c69d94659"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "e6f911b0b7d8e92df1b2cd2351fceb372832ea94"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@e6f911b0b7d8e92df1b2cd2351fceb372832ea94",
"title": "e6f911b0b7d8e92df1b2cd2351fceb372832ea94",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/e6f911b0b7d8e92df1b2cd2351fceb372832ea94"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "77f3436156dc56f94aa3e37dca47b8d7fde3aafd"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@77f3436156dc56f94aa3e37dca47b8d7fde3aafd",
"title": "77f3436156dc56f94aa3e37dca47b8d7fde3aafd",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/77f3436156dc56f94aa3e37dca47b8d7fde3aafd"
}
]
}
},
"gitilesCommit": {
"host": "pigweed.googlesource.com",
"project": "pigweed/pigweed",
"id": "77f3436156dc56f94aa3e37dca47b8d7fde3aafd",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8880845128766272592"
},
{
"key": "scheduler_job_id",
"value": "pigweed/pigweed.dev.ci-pigweed-linux-gn-main"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[18:38:37.002] Scheduled build:
{
"id": "8715415066812108913",
"builder": {
"project": "pigweed",
"bucket": "pigweed.dev.ci",
"builder": "pigweed-linux-gn-main"
},
"createdBy": "project:pigweed",
"createTime": "2025-05-08T18:38:36.169557977Z",
"updateTime": "2025-05-08T18:38:36.169557977Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "pigweed.googlesource.com",
"project": "pigweed/pigweed",
"id": "77f3436156dc56f94aa3e37dca47b8d7fde3aafd",
"ref": "refs/heads/main"
}
}
}
[18:38:37.002] Task URL: https://cr-buildbucket.appspot.com/build/8715415066812108913
[18:38:37.002] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8880845128766272592:2:0) after 7m32s
[18:38:38.009] Received PubSub notification, asking Buildbucket for the build status
[18:38:38.033] Build status: SCHEDULED
[18:46:09.025] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8880845128766272592:2:0)
[18:46:09.092] Build status: SCHEDULED
[18:46:09.092] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8880845128766272592:4:0) after 1m19s
[18:47:28.110] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8880845128766272592:4:0)
[18:47:28.134] Build status: SCHEDULED
[18:47:28.134] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8880845128766272592:5:0) after 5m27s
[18:52:55.155] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8880845128766272592:5:0)
[18:52:55.183] Build status: SCHEDULED
[18:52:55.183] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8880845128766272592:6:0) after 1m54s
[18:54:49.203] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8880845128766272592:6:0)
[18:54:49.230] Build status: SCHEDULED
[18:54:49.230] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8880845128766272592:7:0) after 9m7s
[19:03:56.251] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8880845128766272592:7:0)
[19:03:56.282] Build status: SCHEDULED
[19:03:56.282] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8880845128766272592:8:0) after 1m12s
[19:05:08.374] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8880845128766272592:8:0)
[19:05:08.394] Build status: SCHEDULED
[19:05:08.394] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8880845128766272592:9:0) after 5m5s
[19:10:13.466] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8880845128766272592:9:0)
[19:10:13.488] Build status: SCHEDULED
[19:10:13.488] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8880845128766272592:10:0) after 5m30s
[19:15:43.535] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8880845128766272592:10:0)
[19:15:43.556] Build status: SCHEDULED
[19:15:43.556] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8880845128766272592:11:0) after 9m49s
[19:25:32.598] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8880845128766272592:11:0)
[19:25:32.616] Build status: SCHEDULED
[19:25:32.616] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8880845128766272592:12:0) after 3m3s
[19:28:35.638] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8880845128766272592:12:0)
[19:28:35.656] Build status: SCHEDULED
[19:28:35.656] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8880845128766272592:13:0) after 3m53s
[19:32:28.704] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8880845128766272592:13:0)
[19:32:28.722] Build status: SCHEDULED
[19:32:28.722] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8880845128766272592:14:0) after 2m37s
[19:35:05.902] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8880845128766272592:14:0)
[19:35:05.935] Build status: SCHEDULED
[19:35:05.935] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8880845128766272592:15:0) after 5m14s
[19:40:20.008] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8880845128766272592:15:0)
[19:40:20.050] Build status: SCHEDULED
[19:40:20.050] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8880845128766272592:16:0) after 9m45s
[19:50:05.115] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8880845128766272592:16:0)
[19:50:05.136] Build status: SCHEDULED
[19:50:05.136] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8880845128766272592:17:0) after 3m46s
[19:53:51.187] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8880845128766272592:17:0)
[19:53:51.250] Build status: SCHEDULED
[19:53:51.250] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8880845128766272592:18:0) after 1m17s
[19:55:08.272] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8880845128766272592:18:0)
[19:55:08.290] Build status: SCHEDULED
[19:55:08.290] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8880845128766272592:19:0) after 5m49s
[20:00:57.309] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8880845128766272592:19:0)
[20:00:57.324] Build status: SCHEDULED
[20:00:57.324] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8880845128766272592:20:0) after 1m14s
[20:02:11.416] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8880845128766272592:20:0)
[20:02:11.438] Build status: SCHEDULED
[20:02:11.438] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8880845128766272592:21:0) after 6m33s
[20:08:44.464] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8880845128766272592:21:0)
[20:08:44.642] Build status: SCHEDULED
[20:08:44.643] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8880845128766272592:22:0) after 8m9s
[20:16:53.703] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8880845128766272592:22:0)
[20:16:53.722] Build status: SCHEDULED
[20:16:53.722] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8880845128766272592:23:0) after 4m42s
[20:21:35.695] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8880845128766272592:23:0)
[20:21:35.711] Build status: SCHEDULED
[20:21:35.711] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8880845128766272592:24:0) after 5m17s
[20:26:52.726] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8880845128766272592:24:0)
[20:26:52.745] Build status: SCHEDULED
[20:26:52.745] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8880845128766272592:25:0) after 1m24s
[20:28:16.889] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8880845128766272592:25:0)
[20:28:16.906] Build status: SCHEDULED
[20:28:16.906] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8880845128766272592:26:0) after 9m42s
[20:37:58.924] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8880845128766272592:26:0)
[20:37:58.949] Build status: SCHEDULED
[20:37:58.949] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8880845128766272592:27:0) after 6m55s
[20:44:54.107] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8880845128766272592:27:0)
[20:44:54.123] Build status: SCHEDULED
[20:44:54.123] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8880845128766272592:28:0) after 2m5s
[20:46:59.124] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8880845128766272592:28:0)
[20:46:59.143] Build status: SCHEDULED
[20:46:59.143] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8880845128766272592:29:0) after 3m21s
[20:50:20.214] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8880845128766272592:29:0)
[20:50:20.230] Build status: SCHEDULED
[20:50:20.230] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8880845128766272592:30:0) after 7m11s
[20:57:31.260] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8880845128766272592:30:0)
[20:57:31.279] Build status: SCHEDULED
[20:57:31.279] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8880845128766272592:31:0) after 3m3s
[21:00:34.293] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8880845128766272592:31:0)
[21:00:34.355] Build status: SCHEDULED
[21:00:34.355] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8880845128766272592:32:0) after 3m18s
[21:03:52.353] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8880845128766272592:32:0)
[21:03:52.370] Build status: SCHEDULED
[21:03:52.370] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8880845128766272592:33:0) after 3m47s
[21:07:39.385] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8880845128766272592:33:0)
[21:07:39.402] Build status: SCHEDULED
[21:07:39.402] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8880845128766272592:34:0) after 8m15s
[21:15:54.425] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8880845128766272592:34:0)
[21:15:54.449] Build status: SCHEDULED
[21:15:54.449] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8880845128766272592:35:0) after 5m41s
[21:21:35.586] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8880845128766272592:35:0)
[21:21:35.612] Build status: SCHEDULED
[21:21:35.612] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8880845128766272592:36:0) after 9m30s
[21:31:05.670] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8880845128766272592:36:0)
[21:31:05.697] Build status: SCHEDULED
[21:31:05.697] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8880845128766272592:37:0) after 4m46s
[21:35:51.696] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8880845128766272592:37:0)
[21:35:51.721] Build status: SCHEDULED
[21:35:51.721] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8880845128766272592:38:0) after 7m15s
[21:43:06.742] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8880845128766272592:38:0)
[21:43:06.762] Build status: SCHEDULED
[21:43:06.762] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8880845128766272592:39:0) after 7m16s
[21:50:22.827] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8880845128766272592:39:0)
[21:50:22.889] Build status: SCHEDULED
[21:50:22.889] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8880845128766272592:40:0) after 7m22s
[21:53:02.683] Received PubSub notification, asking Buildbucket for the build status
[21:53:02.704] Build status: STARTED
[21:57:44.978] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8880845128766272592:40:0)
[21:57:45.002] Build status: STARTED
[21:57:45.002] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8880845128766272592:42:0) after 1m12s
[21:58:57.023] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8880845128766272592:42:0)
[21:58:57.049] Build status: STARTED
[21:58:57.049] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8880845128766272592:43:0) after 8m43s
[22:07:40.064] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8880845128766272592:43:0)
[22:07:40.083] Build status: STARTED
[22:07:40.083] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8880845128766272592:44:0) after 5m34s
[22:13:14.128] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8880845128766272592:44:0)
[22:13:14.152] Build status: STARTED
[22:13:14.152] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8880845128766272592:45:0) after 7m27s
[22:15:42.187] Received PubSub notification, asking Buildbucket for the build status
[22:15:42.204] Build:
{
"id": "8715415066812108913",
"builder": {
"project": "pigweed",
"bucket": "pigweed.dev.ci",
"builder": "pigweed-linux-gn-main"
},
"createdBy": "project:pigweed",
"createTime": "2025-05-08T18:38:36.169557977Z",
"startTime": "2025-05-08T21:53:02.537754619Z",
"endTime": "2025-05-08T22:15:41.615288512Z",
"updateTime": "2025-05-08T22:15:41.615288512Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "pigweed.googlesource.com",
"project": "pigweed/pigweed",
"id": "77f3436156dc56f94aa3e37dca47b8d7fde3aafd",
"ref": "refs/heads/main"
}
}
}
[22:15:42.204] Invocation finished in 3h37m7.281715073s with status SUCCEEDED