[19:55:17.579] New invocation is queued and will start shortly
[19:55:19.373] Starting the invocation (attempt 1)
[19:55:19.418] Popped buildset tag "buildset:commit/gitiles/pigweed.googlesource.com/pigweed/pigweed/+/320427e20c5756f178239373d4a28ede70017ce8"
[19:55:19.418] Popped gitiles commit info from properties and tags
[19:55:19.418] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[19:55:19.418] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[19:55:19.419] Buildbucket request:
{
"requestId": "8921337146522913600",
"builder": {
"project": "pigweed",
"bucket": "pigweed.dev.ci",
"builder": "pigweed-linux-gn-main"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8921337146522913600",
"job": "pigweed/pigweed.dev.ci-pigweed-linux-gn-main",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "e8de610d576dd4a92564fbbc0bc2ff9a9d228627"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@e8de610d576dd4a92564fbbc0bc2ff9a9d228627",
"title": "e8de610d576dd4a92564fbbc0bc2ff9a9d228627",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/e8de610d576dd4a92564fbbc0bc2ff9a9d228627"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "a27434745dc5cfd170f31d8fafc3e83ba292aaa7"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@a27434745dc5cfd170f31d8fafc3e83ba292aaa7",
"title": "a27434745dc5cfd170f31d8fafc3e83ba292aaa7",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/a27434745dc5cfd170f31d8fafc3e83ba292aaa7"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "c050599082a7277ba7b7260f817817ca39cfa820"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@c050599082a7277ba7b7260f817817ca39cfa820",
"title": "c050599082a7277ba7b7260f817817ca39cfa820",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/c050599082a7277ba7b7260f817817ca39cfa820"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "0d0b9919b7c38f6cb2a1c0a0f7198b6282aa208e"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@0d0b9919b7c38f6cb2a1c0a0f7198b6282aa208e",
"title": "0d0b9919b7c38f6cb2a1c0a0f7198b6282aa208e",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/0d0b9919b7c38f6cb2a1c0a0f7198b6282aa208e"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "33cd552638c42ecd68d4ad03bf987fe6b1382526"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@33cd552638c42ecd68d4ad03bf987fe6b1382526",
"title": "33cd552638c42ecd68d4ad03bf987fe6b1382526",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/33cd552638c42ecd68d4ad03bf987fe6b1382526"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "320427e20c5756f178239373d4a28ede70017ce8"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@320427e20c5756f178239373d4a28ede70017ce8",
"title": "320427e20c5756f178239373d4a28ede70017ce8",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/320427e20c5756f178239373d4a28ede70017ce8"
}
]
}
},
"gitilesCommit": {
"host": "pigweed.googlesource.com",
"project": "pigweed/pigweed",
"id": "320427e20c5756f178239373d4a28ede70017ce8",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8921337146522913600"
},
{
"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"
}
}
[19:55:20.002] Scheduled build:
{
"id": "8755907083867995793",
"builder": {
"project": "pigweed",
"bucket": "pigweed.dev.ci",
"builder": "pigweed-linux-gn-main"
},
"createdBy": "project:pigweed",
"createTime": "2024-02-16T19:55:19.483709794Z",
"updateTime": "2024-02-16T19:55:19.483709794Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "pigweed.googlesource.com",
"project": "pigweed/pigweed",
"id": "320427e20c5756f178239373d4a28ede70017ce8",
"ref": "refs/heads/main"
}
}
}
[19:55:20.002] Task URL: https://cr-buildbucket.appspot.com/build/8755907083867995793
[19:55:20.002] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8921337146522913600:2:0) after 7m5s
[19:55:20.877] Received PubSub notification, asking Buildbucket for the build status
[19:55:20.932] Build status: SCHEDULED
[20:02:25.031] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8921337146522913600:2:0)
[20:02:25.064] Build status: SCHEDULED
[20:02:25.064] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8921337146522913600:4:0) after 5m14s
[20:07:39.090] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8921337146522913600:4:0)
[20:07:39.132] Build status: SCHEDULED
[20:07:39.132] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8921337146522913600:5:0) after 9m4s
[20:16:43.244] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8921337146522913600:5:0)
[20:16:43.397] Build status: SCHEDULED
[20:16:43.397] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8921337146522913600:6:0) after 8m42s
[20:25:25.452] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8921337146522913600:6:0)
[20:25:25.515] Build status: SCHEDULED
[20:25:25.517] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8921337146522913600:7:0) after 6m35s
[20:32:00.538] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8921337146522913600:7:0)
[20:32:00.603] Build status: SCHEDULED
[20:32:00.603] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8921337146522913600:8:0) after 6m36s
[20:38:36.687] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8921337146522913600:8:0)
[20:38:36.705] Build status: SCHEDULED
[20:38:36.705] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8921337146522913600:9:0) after 5m12s
[20:43:48.730] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8921337146522913600:9:0)
[20:43:48.757] Build status: SCHEDULED
[20:43:48.757] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8921337146522913600:10:0) after 9m57s
[20:53:45.812] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8921337146522913600:10:0)
[20:53:45.832] Build status: SCHEDULED
[20:53:45.832] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8921337146522913600:11:0) after 6m25s
[21:00:10.860] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8921337146522913600:11:0)
[21:00:10.907] Build status: SCHEDULED
[21:00:10.907] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8921337146522913600:12:0) after 5m45s
[21:05:55.944] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8921337146522913600:12:0)
[21:05:56.015] Build status: SCHEDULED
[21:05:56.015] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8921337146522913600:13:0) after 6m29s
[21:12:25.100] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8921337146522913600:13:0)
[21:12:25.284] Build status: SCHEDULED
[21:12:25.284] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8921337146522913600:14:0) after 1m35s
[21:14:00.304] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8921337146522913600:14:0)
[21:14:00.342] Build status: SCHEDULED
[21:14:00.342] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8921337146522913600:15:0) after 2m50s
[21:16:50.390] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8921337146522913600:15:0)
[21:16:50.408] Build status: SCHEDULED
[21:16:50.408] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8921337146522913600:16:0) after 3m38s
[21:20:28.438] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8921337146522913600:16:0)
[21:20:28.476] Build status: SCHEDULED
[21:20:28.476] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8921337146522913600:17:0) after 4m0s
[21:24:28.505] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8921337146522913600:17:0)
[21:24:28.529] Build status: SCHEDULED
[21:24:28.529] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8921337146522913600:18:0) after 2m40s
[21:27:08.560] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8921337146522913600:18:0)
[21:27:08.584] Build status: SCHEDULED
[21:27:08.584] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8921337146522913600:19:0) after 2m30s
[21:29:38.601] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8921337146522913600:19:0)
[21:29:38.619] Build status: SCHEDULED
[21:29:38.619] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8921337146522913600:20:0) after 8m12s
[21:37:50.634] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8921337146522913600:20:0)
[21:37:50.657] Build status: SCHEDULED
[21:37:50.657] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8921337146522913600:21:0) after 5m27s
[21:43:17.675] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8921337146522913600:21:0)
[21:43:17.727] Build status: SCHEDULED
[21:43:17.727] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8921337146522913600:22:0) after 2m0s
[21:45:17.744] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8921337146522913600:22:0)
[21:45:17.760] Build status: SCHEDULED
[21:45:17.760] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8921337146522913600:23:0) after 9m37s
[21:54:54.799] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8921337146522913600:23:0)
[21:54:54.834] Build status: SCHEDULED
[21:54:54.834] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8921337146522913600:24:0) after 8m40s
[22:03:34.932] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8921337146522913600:24:0)
[22:03:34.953] Build status: SCHEDULED
[22:03:34.953] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8921337146522913600:25:0) after 2m32s
[22:06:06.981] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8921337146522913600:25:0)
[22:06:07.006] Build status: SCHEDULED
[22:06:07.006] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8921337146522913600:26:0) after 6m18s
[22:07:19.148] Received PubSub notification, asking Buildbucket for the build status
[22:07:19.187] Build status: STARTED
[22:12:25.202] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8921337146522913600:26:0)
[22:12:25.233] Build status: STARTED
[22:12:25.233] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8921337146522913600:28:0) after 3m42s
[22:16:07.252] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8921337146522913600:28:0)
[22:16:07.284] Build status: STARTED
[22:16:07.284] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8921337146522913600:29:0) after 1m22s
[22:17:29.400] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8921337146522913600:29:0)
[22:17:29.423] Build status: STARTED
[22:17:29.424] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8921337146522913600:30:0) after 3m4s
[22:20:33.447] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8921337146522913600:30:0)
[22:20:33.468] Build status: STARTED
[22:20:33.468] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8921337146522913600:31:0) after 1m7s
[22:21:40.478] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8921337146522913600:31:0)
[22:21:40.506] Build status: STARTED
[22:21:40.506] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8921337146522913600:32:0) after 5m43s
[22:27:23.539] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8921337146522913600:32:0)
[22:27:23.793] Build status: STARTED
[22:27:23.793] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8921337146522913600:33:0) after 4m47s
[22:32:10.854] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8921337146522913600:33:0)
[22:32:10.876] Build status: STARTED
[22:32:10.876] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8921337146522913600:34:0) after 9m17s
[22:34:20.891] Received PubSub notification, asking Buildbucket for the build status
[22:34:20.912] Build:
{
"id": "8755907083867995793",
"builder": {
"project": "pigweed",
"bucket": "pigweed.dev.ci",
"builder": "pigweed-linux-gn-main"
},
"createdBy": "project:pigweed",
"createTime": "2024-02-16T19:55:19.483709794Z",
"startTime": "2024-02-16T22:07:18.405638Z",
"endTime": "2024-02-16T22:34:20.432735Z",
"updateTime": "2024-02-16T22:34:20.432735Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "pigweed.googlesource.com",
"project": "pigweed/pigweed",
"id": "320427e20c5756f178239373d4a28ede70017ce8",
"ref": "refs/heads/main"
}
}
}
[22:34:20.912] Invocation finished in 2h39m3.343513456s with status SUCCEEDED