[21:04:32.658] New invocation is queued and will start shortly
[21:04:34.641] Starting the invocation (attempt 1)
[21:04:34.666] Popped buildset tag "buildset:commit/gitiles/pigweed.googlesource.com/pigweed/pigweed/+/f52ea2994b98edb656dcb01a705786936c437372"
[21:04:34.666] Popped gitiles commit info from properties and tags
[21:04:34.666] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[21:04:34.666] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[21:04:34.666] Buildbucket request:
{
"requestId": "8894878475420635344",
"builder": {
"project": "pigweed",
"bucket": "pigweed.dev.ci",
"builder": "pigweed-linux-gn-main"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8894878475420635344",
"job": "pigweed/pigweed.dev.ci-pigweed-linux-gn-main",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "534e65d38e28f58c30f7239b3e431a388a22961e"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@534e65d38e28f58c30f7239b3e431a388a22961e",
"title": "534e65d38e28f58c30f7239b3e431a388a22961e",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/534e65d38e28f58c30f7239b3e431a388a22961e"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "23370ed5ed0e01273080c231ee3475916dc74fba"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@23370ed5ed0e01273080c231ee3475916dc74fba",
"title": "23370ed5ed0e01273080c231ee3475916dc74fba",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/23370ed5ed0e01273080c231ee3475916dc74fba"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "53c440bccec3a8c2e726f77e77b7b59d4bb4c1a5"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@53c440bccec3a8c2e726f77e77b7b59d4bb4c1a5",
"title": "53c440bccec3a8c2e726f77e77b7b59d4bb4c1a5",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/53c440bccec3a8c2e726f77e77b7b59d4bb4c1a5"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "a4d795f13b833950d14ff9803d43e49ec1fb5e47"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@a4d795f13b833950d14ff9803d43e49ec1fb5e47",
"title": "a4d795f13b833950d14ff9803d43e49ec1fb5e47",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/a4d795f13b833950d14ff9803d43e49ec1fb5e47"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "f52ea2994b98edb656dcb01a705786936c437372"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@f52ea2994b98edb656dcb01a705786936c437372",
"title": "f52ea2994b98edb656dcb01a705786936c437372",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/f52ea2994b98edb656dcb01a705786936c437372"
}
]
}
},
"gitilesCommit": {
"host": "pigweed.googlesource.com",
"project": "pigweed/pigweed",
"id": "f52ea2994b98edb656dcb01a705786936c437372",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8894878475420635344"
},
{
"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"
}
}
[21:04:35.517] Scheduled build:
{
"id": "8729448412544366817",
"builder": {
"project": "pigweed",
"bucket": "pigweed.dev.ci",
"builder": "pigweed-linux-gn-main"
},
"createdBy": "project:pigweed",
"createTime": "2024-12-04T21:04:34.771888521Z",
"updateTime": "2024-12-04T21:04:34.771888521Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "pigweed.googlesource.com",
"project": "pigweed/pigweed",
"id": "f52ea2994b98edb656dcb01a705786936c437372",
"ref": "refs/heads/main"
}
}
}
[21:04:35.517] Task URL: https://cr-buildbucket.appspot.com/build/8729448412544366817
[21:04:35.517] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8894878475420635344:2:0) after 6m8s
[21:04:36.306] Received PubSub notification, asking Buildbucket for the build status
[21:04:36.328] Build status: SCHEDULED
[21:10:43.526] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8894878475420635344:2:0)
[21:10:43.547] Build status: SCHEDULED
[21:10:43.547] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8894878475420635344:4:0) after 6m33s
[21:17:16.646] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8894878475420635344:4:0)
[21:17:16.709] Build status: SCHEDULED
[21:17:16.709] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8894878475420635344:5:0) after 3m40s
[21:20:56.807] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8894878475420635344:5:0)
[21:20:56.834] Build status: SCHEDULED
[21:20:56.834] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8894878475420635344:6:0) after 6m16s
[21:27:12.814] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8894878475420635344:6:0)
[21:27:12.839] Build status: SCHEDULED
[21:27:12.839] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8894878475420635344:7:0) after 4m42s
[21:31:54.853] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8894878475420635344:7:0)
[21:31:54.876] Build status: SCHEDULED
[21:31:54.876] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8894878475420635344:8:0) after 1m1s
[21:32:55.941] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8894878475420635344:8:0)
[21:32:56.056] Build status: SCHEDULED
[21:32:56.056] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8894878475420635344:9:0) after 9m56s
[21:42:52.110] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8894878475420635344:9:0)
[21:42:52.139] Build status: SCHEDULED
[21:42:52.139] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8894878475420635344:10:0) after 5m35s
[21:48:27.177] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8894878475420635344:10:0)
[21:48:27.197] Build status: SCHEDULED
[21:48:27.197] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8894878475420635344:11:0) after 3m3s
[21:51:30.214] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8894878475420635344:11:0)
[21:51:30.230] Build status: SCHEDULED
[21:51:30.230] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8894878475420635344:12:0) after 3m42s
[21:55:12.246] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8894878475420635344:12:0)
[21:55:12.264] Build status: SCHEDULED
[21:55:12.264] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8894878475420635344:13:0) after 5m41s
[22:00:53.388] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8894878475420635344:13:0)
[22:00:53.408] Build status: SCHEDULED
[22:00:53.408] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8894878475420635344:14:0) after 8m9s
[22:09:02.520] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8894878475420635344:14:0)
[22:09:02.552] Build status: SCHEDULED
[22:09:02.552] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8894878475420635344:15:0) after 8m33s
[22:17:35.569] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8894878475420635344:15:0)
[22:17:35.602] Build status: SCHEDULED
[22:17:35.602] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8894878475420635344:16:0) after 5m29s
[22:23:04.628] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8894878475420635344:16:0)
[22:23:04.703] Build status: SCHEDULED
[22:23:04.703] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8894878475420635344:17:0) after 9m21s
[22:32:25.774] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8894878475420635344:17:0)
[22:32:25.796] Build status: SCHEDULED
[22:32:25.796] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8894878475420635344:18:0) after 1m13s
[22:33:38.863] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8894878475420635344:18:0)
[22:33:38.884] Build status: SCHEDULED
[22:33:38.884] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8894878475420635344:19:0) after 7m42s
[22:41:21.116] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8894878475420635344:19:0)
[22:41:21.206] Build status: SCHEDULED
[22:41:21.206] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8894878475420635344:20:0) after 9m10s
[22:50:31.233] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8894878475420635344:20:0)
[22:50:31.257] Build status: SCHEDULED
[22:50:31.257] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8894878475420635344:21:0) after 1m59s
[22:52:30.300] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8894878475420635344:21:0)
[22:52:30.332] Build status: SCHEDULED
[22:52:30.332] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8894878475420635344:22:0) after 7m15s
[22:59:45.348] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8894878475420635344:22:0)
[22:59:45.425] Build status: SCHEDULED
[22:59:45.425] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8894878475420635344:23:0) after 6m42s
[23:06:27.490] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8894878475420635344:23:0)
[23:06:27.536] Build status: SCHEDULED
[23:06:27.536] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8894878475420635344:24:0) after 4m36s
[23:11:03.554] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8894878475420635344:24:0)
[23:11:03.571] Build status: SCHEDULED
[23:11:03.571] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8894878475420635344:25:0) after 8m9s
[23:19:12.571] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8894878475420635344:25:0)
[23:19:12.595] Build status: SCHEDULED
[23:19:12.595] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8894878475420635344:26:0) after 5m57s
[23:25:09.613] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8894878475420635344:26:0)
[23:25:09.633] Build status: SCHEDULED
[23:25:09.633] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8894878475420635344:27:0) after 5m42s
[23:30:51.649] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8894878475420635344:27:0)
[23:30:51.671] Build status: SCHEDULED
[23:30:51.671] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8894878475420635344:28:0) after 6m18s
[23:37:09.642] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8894878475420635344:28:0)
[23:37:09.666] Build status: SCHEDULED
[23:37:09.666] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8894878475420635344:29:0) after 4m58s
[23:42:07.685] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8894878475420635344:29:0)
[23:42:07.704] Build status: SCHEDULED
[23:42:07.704] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8894878475420635344:30:0) after 7m42s
[23:49:49.772] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8894878475420635344:30:0)
[23:49:49.795] Build status: SCHEDULED
[23:49:49.795] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8894878475420635344:31:0) after 8m25s
[23:53:56.062] Received PubSub notification, asking Buildbucket for the build status
[23:53:56.081] Build status: STARTED
[23:58:14.840] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8894878475420635344:31:0)
[23:58:14.861] Build status: STARTED
[23:58:14.861] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8894878475420635344:33:0) after 1m38s
[23:59:52.882] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8894878475420635344:33:0)
[23:59:52.901] Build status: STARTED
[23:59:52.901] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8894878475420635344:34:0) after 3m40s
[00:03:32.965] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8894878475420635344:34:0)
[00:03:33.120] Build status: STARTED
[00:03:33.120] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8894878475420635344:35:0) after 9m39s
[00:13:12.138] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8894878475420635344:35:0)
[00:13:12.211] Build status: STARTED
[00:13:12.211] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8894878475420635344:36:0) after 2m57s
[00:16:07.845] Received PubSub notification, asking Buildbucket for the build status
[00:16:07.894] Build:
{
"id": "8729448412544366817",
"builder": {
"project": "pigweed",
"bucket": "pigweed.dev.ci",
"builder": "pigweed-linux-gn-main"
},
"createdBy": "project:pigweed",
"createTime": "2024-12-04T21:04:34.771888521Z",
"startTime": "2024-12-04T23:53:55.875559802Z",
"endTime": "2024-12-05T00:16:07.559041686Z",
"updateTime": "2024-12-05T00:16:07.559041686Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "pigweed.googlesource.com",
"project": "pigweed/pigweed",
"id": "f52ea2994b98edb656dcb01a705786936c437372",
"ref": "refs/heads/main"
}
}
}
[00:16:07.894] Invocation finished in 3h11m35.249626114s with status SUCCEEDED