[19:42:32.492] New invocation is queued and will start shortly
[19:42:33.591] Starting the invocation (attempt 1)
[19:42:33.618] Popped buildset tag "buildset:commit/gitiles/pigweed.googlesource.com/pigweed/pigweed/+/320427e20c5756f178239373d4a28ede70017ce8"
[19:42:33.618] Popped gitiles commit info from properties and tags
[19:42:33.618] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[19:42:33.618] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[19:42:33.619] Buildbucket request:
{
"requestId": "8921337948781084640",
"builder": {
"project": "pigweed",
"bucket": "pigweed.dev.ci",
"builder": "pigweed-linux-gn-san"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8921337948781084640",
"job": "pigweed/pigweed.dev.ci-pigweed-linux-gn-san",
"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": "8921337948781084640"
},
{
"key": "scheduler_job_id",
"value": "pigweed/pigweed.dev.ci-pigweed-linux-gn-san"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[19:42:34.166] Scheduled build:
{
"id": "8755907886865688049",
"builder": {
"project": "pigweed",
"bucket": "pigweed.dev.ci",
"builder": "pigweed-linux-gn-san"
},
"createdBy": "project:pigweed",
"createTime": "2024-02-16T19:42:33.685505855Z",
"updateTime": "2024-02-16T19:42:33.685505855Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "pigweed.googlesource.com",
"project": "pigweed/pigweed",
"id": "320427e20c5756f178239373d4a28ede70017ce8",
"ref": "refs/heads/main"
}
}
}
[19:42:34.166] Task URL: https://cr-buildbucket.appspot.com/build/8755907886865688049
[19:42:34.166] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8921337948781084640:2:0) after 2m46s
[19:42:34.993] Received PubSub notification, asking Buildbucket for the build status
[19:42:35.094] Build status: SCHEDULED
[19:45:20.194] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8921337948781084640:2:0)
[19:45:20.267] Build status: SCHEDULED
[19:45:20.267] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8921337948781084640:4:0) after 7m12s
[19:52:32.343] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8921337948781084640:4:0)
[19:52:32.372] Build status: SCHEDULED
[19:52:32.373] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8921337948781084640:5:0) after 7m18s
[19:59:50.416] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8921337948781084640:5:0)
[19:59:50.436] Build status: SCHEDULED
[19:59:50.436] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8921337948781084640:6:0) after 2m39s
[20:02:29.458] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8921337948781084640:6:0)
[20:02:29.490] Build status: SCHEDULED
[20:02:29.490] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8921337948781084640:7:0) after 6m40s
[20:09:09.539] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8921337948781084640:7:0)
[20:09:09.580] Build status: SCHEDULED
[20:09:09.580] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8921337948781084640:8:0) after 5m45s
[20:14:54.628] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8921337948781084640:8:0)
[20:14:54.648] Build status: SCHEDULED
[20:14:54.648] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8921337948781084640:9:0) after 8m11s
[20:23:05.666] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8921337948781084640:9:0)
[20:23:05.695] Build status: SCHEDULED
[20:23:05.695] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8921337948781084640:10:0) after 3m26s
[20:26:31.725] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8921337948781084640:10:0)
[20:26:31.747] Build status: SCHEDULED
[20:26:31.747] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8921337948781084640:11:0) after 8m50s
[20:35:21.763] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8921337948781084640:11:0)
[20:35:21.786] Build status: SCHEDULED
[20:35:21.786] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8921337948781084640:12:0) after 8m8s
[20:43:29.848] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8921337948781084640:12:0)
[20:43:29.863] Build status: SCHEDULED
[20:43:29.863] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8921337948781084640:13:0) after 3m35s
[20:47:04.929] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8921337948781084640:13:0)
[20:47:05.030] Build status: SCHEDULED
[20:47:05.030] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8921337948781084640:14:0) after 7m0s
[20:54:05.171] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8921337948781084640:14:0)
[20:54:05.219] Build status: SCHEDULED
[20:54:05.220] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8921337948781084640:15:0) after 2m13s
[20:56:18.345] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8921337948781084640:15:0)
[20:56:18.377] Build status: SCHEDULED
[20:56:18.377] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8921337948781084640:16:0) after 4m53s
[21:01:11.429] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8921337948781084640:16:0)
[21:01:11.720] Build status: SCHEDULED
[21:01:11.720] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8921337948781084640:17:0) after 4m29s
[21:05:40.740] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8921337948781084640:17:0)
[21:05:40.761] Build status: SCHEDULED
[21:05:40.761] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8921337948781084640:18:0) after 3m14s
[21:08:54.774] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8921337948781084640:18:0)
[21:08:55.048] Build status: SCHEDULED
[21:08:55.048] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8921337948781084640:19:0) after 7m34s
[21:16:29.133] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8921337948781084640:19:0)
[21:16:29.154] Build status: SCHEDULED
[21:16:29.154] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8921337948781084640:20:0) after 1m27s
[21:17:56.275] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8921337948781084640:20:0)
[21:17:56.294] Build status: SCHEDULED
[21:17:56.294] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8921337948781084640:21:0) after 3m36s
[21:21:32.574] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8921337948781084640:21:0)
[21:21:32.607] Build status: SCHEDULED
[21:21:32.607] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8921337948781084640:22:0) after 1m55s
[21:23:27.682] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8921337948781084640:22:0)
[21:23:27.699] Build status: SCHEDULED
[21:23:27.699] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8921337948781084640:23:0) after 1m56s
[21:25:23.741] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8921337948781084640:23:0)
[21:25:23.802] Build status: SCHEDULED
[21:25:23.802] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8921337948781084640:24:0) after 6m50s
[21:32:13.836] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8921337948781084640:24:0)
[21:32:13.858] Build status: SCHEDULED
[21:32:13.858] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8921337948781084640:25:0) after 4m16s
[21:36:29.927] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8921337948781084640:25:0)
[21:36:29.968] Build status: SCHEDULED
[21:36:29.968] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8921337948781084640:26:0) after 6m44s
[21:43:13.982] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8921337948781084640:26:0)
[21:43:14.055] Build status: SCHEDULED
[21:43:14.055] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8921337948781084640:27:0) after 6m38s
[21:46:07.985] Received PubSub notification, asking Buildbucket for the build status
[21:46:08.022] Build status: STARTED
[21:49:52.091] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8921337948781084640:27:0)
[21:49:52.122] Build status: STARTED
[21:49:52.122] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8921337948781084640:29:0) after 1m1s
[21:50:53.148] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8921337948781084640:29:0)
[21:50:53.174] Build status: STARTED
[21:50:53.174] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8921337948781084640:30:0) after 1m54s
[21:52:47.206] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8921337948781084640:30:0)
[21:52:47.245] Build status: STARTED
[21:52:47.245] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8921337948781084640:31:0) after 2m48s
[21:55:35.315] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8921337948781084640:31:0)
[21:55:35.344] Build status: STARTED
[21:55:35.344] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8921337948781084640:32:0) after 8m16s
[22:03:51.426] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8921337948781084640:32:0)
[22:03:51.449] Build status: STARTED
[22:03:51.449] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8921337948781084640:33:0) after 1m16s
[22:04:12.072] Received PubSub notification, asking Buildbucket for the build status
[22:04:12.092] Build:
{
"id": "8755907886865688049",
"builder": {
"project": "pigweed",
"bucket": "pigweed.dev.ci",
"builder": "pigweed-linux-gn-san"
},
"createdBy": "project:pigweed",
"createTime": "2024-02-16T19:42:33.685505855Z",
"startTime": "2024-02-16T21:46:06.952981Z",
"endTime": "2024-02-16T22:04:11.346086Z",
"updateTime": "2024-02-16T22:04:11.346086Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "pigweed.googlesource.com",
"project": "pigweed/pigweed",
"id": "320427e20c5756f178239373d4a28ede70017ce8",
"ref": "refs/heads/main"
}
}
}
[22:04:12.092] Invocation finished in 2h21m39.616240159s with status SUCCEEDED