[07:47:43.012] New invocation is queued and will start shortly
[07:47:44.641] Starting the invocation (attempt 1)
[07:47:44.670] Popped buildset tag "buildset:commit/gitiles/pigweed.googlesource.com/pigweed/pigweed/+/7b6c0f6c01c758442ce1e010382608db369071dd"
[07:47:44.670] Popped gitiles commit info from properties and tags
[07:47:44.670] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[07:47:44.670] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[07:47:44.671] Buildbucket request:
{
"requestId": "8909333525122905856",
"builder": {
"project": "pigweed",
"bucket": "pigweed.dev.ci",
"builder": "pigweed-mac-x86-gn-arduino-pico"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8909333525122905856",
"job": "pigweed/pigweed.dev.ci-pigweed-mac-x86-gn-arduino-pico",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "4c71a9ac34f060727e7d8eb49c17bb72e2f31a29"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@4c71a9ac34f060727e7d8eb49c17bb72e2f31a29",
"title": "4c71a9ac34f060727e7d8eb49c17bb72e2f31a29",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/4c71a9ac34f060727e7d8eb49c17bb72e2f31a29"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "14b2e4c65d8b10d070112602b94e9e749a6a61d4"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@14b2e4c65d8b10d070112602b94e9e749a6a61d4",
"title": "14b2e4c65d8b10d070112602b94e9e749a6a61d4",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/14b2e4c65d8b10d070112602b94e9e749a6a61d4"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "0d8e8401d47fe1230d98c9152a10dc551fc3af8d"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@0d8e8401d47fe1230d98c9152a10dc551fc3af8d",
"title": "0d8e8401d47fe1230d98c9152a10dc551fc3af8d",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/0d8e8401d47fe1230d98c9152a10dc551fc3af8d"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "7b6c0f6c01c758442ce1e010382608db369071dd"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@7b6c0f6c01c758442ce1e010382608db369071dd",
"title": "7b6c0f6c01c758442ce1e010382608db369071dd",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/7b6c0f6c01c758442ce1e010382608db369071dd"
}
]
}
},
"gitilesCommit": {
"host": "pigweed.googlesource.com",
"project": "pigweed/pigweed",
"id": "7b6c0f6c01c758442ce1e010382608db369071dd",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8909333525122905856"
},
{
"key": "scheduler_job_id",
"value": "pigweed/pigweed.dev.ci-pigweed-mac-x86-gn-arduino-pico"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[07:47:45.516] Scheduled build:
{
"id": "8743903462660781025",
"builder": {
"project": "pigweed",
"bucket": "pigweed.dev.ci",
"builder": "pigweed-mac-x86-gn-arduino-pico"
},
"createdBy": "project:pigweed",
"createTime": "2024-06-28T07:47:44.732266422Z",
"updateTime": "2024-06-28T07:47:44.732266422Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "pigweed.googlesource.com",
"project": "pigweed/pigweed",
"id": "7b6c0f6c01c758442ce1e010382608db369071dd",
"ref": "refs/heads/main"
}
}
}
[07:47:45.516] Task URL: https://cr-buildbucket.appspot.com/build/8743903462660781025
[07:47:45.516] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-gn-arduino-pico:8909333525122905856:2:0) after 8m17s
[07:47:46.465] Received PubSub notification, asking Buildbucket for the build status
[07:47:46.494] Build status: SCHEDULED
[07:56:02.580] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-gn-arduino-pico:8909333525122905856:2:0)
[07:56:02.646] Build status: SCHEDULED
[07:56:02.646] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-gn-arduino-pico:8909333525122905856:4:0) after 4m50s
[08:00:52.623] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-gn-arduino-pico:8909333525122905856:4:0)
[08:00:52.639] Build status: SCHEDULED
[08:00:52.639] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-gn-arduino-pico:8909333525122905856:5:0) after 4m7s
[08:04:59.822] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-gn-arduino-pico:8909333525122905856:5:0)
[08:04:59.842] Build status: SCHEDULED
[08:04:59.842] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-gn-arduino-pico:8909333525122905856:6:0) after 6m39s
[08:11:38.975] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-gn-arduino-pico:8909333525122905856:6:0)
[08:11:39.001] Build status: SCHEDULED
[08:11:39.001] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-gn-arduino-pico:8909333525122905856:7:0) after 3m47s
[08:15:26.084] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-gn-arduino-pico:8909333525122905856:7:0)
[08:15:26.112] Build status: SCHEDULED
[08:15:26.112] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-gn-arduino-pico:8909333525122905856:8:0) after 7m29s
[08:22:55.319] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-gn-arduino-pico:8909333525122905856:8:0)
[08:22:55.400] Build status: SCHEDULED
[08:22:55.400] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-gn-arduino-pico:8909333525122905856:9:0) after 7m49s
[08:30:44.415] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-gn-arduino-pico:8909333525122905856:9:0)
[08:30:44.673] Build status: SCHEDULED
[08:30:44.673] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-gn-arduino-pico:8909333525122905856:10:0) after 5m36s
[08:36:20.688] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-gn-arduino-pico:8909333525122905856:10:0)
[08:36:20.711] Build status: SCHEDULED
[08:36:20.711] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-gn-arduino-pico:8909333525122905856:11:0) after 2m53s
[08:39:13.736] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-gn-arduino-pico:8909333525122905856:11:0)
[08:39:13.761] Build status: SCHEDULED
[08:39:13.761] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-gn-arduino-pico:8909333525122905856:12:0) after 9m27s
[08:48:40.856] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-gn-arduino-pico:8909333525122905856:12:0)
[08:48:40.886] Build status: SCHEDULED
[08:48:40.886] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-gn-arduino-pico:8909333525122905856:13:0) after 7m49s
[08:56:30.026] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-gn-arduino-pico:8909333525122905856:13:0)
[08:56:30.081] Build status: SCHEDULED
[08:56:30.082] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-gn-arduino-pico:8909333525122905856:14:0) after 5m38s
[09:02:08.199] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-gn-arduino-pico:8909333525122905856:14:0)
[09:02:08.221] Build status: SCHEDULED
[09:02:08.221] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-gn-arduino-pico:8909333525122905856:15:0) after 1m3s
[09:03:11.210] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-gn-arduino-pico:8909333525122905856:15:0)
[09:03:11.238] Build status: SCHEDULED
[09:03:11.238] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-gn-arduino-pico:8909333525122905856:16:0) after 8m3s
[09:11:14.533] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-gn-arduino-pico:8909333525122905856:16:0)
[09:11:14.549] Build status: SCHEDULED
[09:11:14.549] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-gn-arduino-pico:8909333525122905856:17:0) after 7m48s
[09:19:03.088] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-gn-arduino-pico:8909333525122905856:17:0)
[09:19:03.108] Build status: SCHEDULED
[09:19:03.108] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-gn-arduino-pico:8909333525122905856:18:0) after 9m13s
[09:28:16.539] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-gn-arduino-pico:8909333525122905856:18:0)
[09:28:16.561] Build status: SCHEDULED
[09:28:16.561] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-gn-arduino-pico:8909333525122905856:19:0) after 2m59s
[09:31:16.068] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-gn-arduino-pico:8909333525122905856:19:0)
[09:31:16.094] Build status: SCHEDULED
[09:31:16.094] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-gn-arduino-pico:8909333525122905856:20:0) after 1m57s
[09:33:13.269] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-gn-arduino-pico:8909333525122905856:20:0)
[09:33:13.289] Build status: SCHEDULED
[09:33:13.289] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-gn-arduino-pico:8909333525122905856:21:0) after 8m41s
[09:41:54.375] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-gn-arduino-pico:8909333525122905856:21:0)
[09:41:54.396] Build status: SCHEDULED
[09:41:54.396] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-gn-arduino-pico:8909333525122905856:22:0) after 2m46s
[09:44:40.453] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-gn-arduino-pico:8909333525122905856:22:0)
[09:44:40.482] Build status: SCHEDULED
[09:44:40.482] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-gn-arduino-pico:8909333525122905856:23:0) after 3m5s
[09:47:45.478] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-gn-arduino-pico:8909333525122905856:23:0)
[09:47:45.500] Build status: SCHEDULED
[09:47:45.500] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-gn-arduino-pico:8909333525122905856:24:0) after 6m37s
[09:54:22.518] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-gn-arduino-pico:8909333525122905856:24:0)
[09:54:22.539] Build status: SCHEDULED
[09:54:22.539] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-gn-arduino-pico:8909333525122905856:25:0) after 5m24s
[09:59:46.554] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-gn-arduino-pico:8909333525122905856:25:0)
[09:59:46.638] Build status: SCHEDULED
[09:59:46.638] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-gn-arduino-pico:8909333525122905856:26:0) after 5m26s
[10:05:12.757] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-gn-arduino-pico:8909333525122905856:26:0)
[10:05:12.787] Build status: SCHEDULED
[10:05:12.787] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-gn-arduino-pico:8909333525122905856:27:0) after 5m42s
[10:10:54.819] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-gn-arduino-pico:8909333525122905856:27:0)
[10:10:54.845] Build status: SCHEDULED
[10:10:54.845] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-gn-arduino-pico:8909333525122905856:28:0) after 2m21s
[10:13:15.948] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-gn-arduino-pico:8909333525122905856:28:0)
[10:13:15.967] Build status: SCHEDULED
[10:13:15.967] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-gn-arduino-pico:8909333525122905856:29:0) after 7m45s
[10:21:01.113] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-gn-arduino-pico:8909333525122905856:29:0)
[10:21:01.135] Build status: SCHEDULED
[10:21:01.135] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-gn-arduino-pico:8909333525122905856:30:0) after 1m3s
[10:22:04.182] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-gn-arduino-pico:8909333525122905856:30:0)
[10:22:04.216] Build status: SCHEDULED
[10:22:04.216] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-gn-arduino-pico:8909333525122905856:31:0) after 2m26s
[10:24:30.524] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-gn-arduino-pico:8909333525122905856:31:0)
[10:24:30.544] Build status: SCHEDULED
[10:24:30.544] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-gn-arduino-pico:8909333525122905856:32:0) after 6m45s
[10:31:15.573] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-gn-arduino-pico:8909333525122905856:32:0)
[10:31:15.593] Build status: SCHEDULED
[10:31:15.593] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-gn-arduino-pico:8909333525122905856:33:0) after 7m17s
[10:38:32.885] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-gn-arduino-pico:8909333525122905856:33:0)
[10:38:32.915] Build status: SCHEDULED
[10:38:32.915] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-gn-arduino-pico:8909333525122905856:34:0) after 1m28s
[10:40:00.991] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-gn-arduino-pico:8909333525122905856:34:0)
[10:40:01.013] Build status: SCHEDULED
[10:40:01.013] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-gn-arduino-pico:8909333525122905856:35:0) after 4m32s
[10:44:33.030] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-gn-arduino-pico:8909333525122905856:35:0)
[10:44:33.058] Build status: SCHEDULED
[10:44:33.058] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-gn-arduino-pico:8909333525122905856:36:0) after 3m41s
[10:48:14.197] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-gn-arduino-pico:8909333525122905856:36:0)
[10:48:14.215] Build status: SCHEDULED
[10:48:14.215] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-gn-arduino-pico:8909333525122905856:37:0) after 5m45s
[10:53:59.437] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-gn-arduino-pico:8909333525122905856:37:0)
[10:53:59.460] Build status: SCHEDULED
[10:53:59.460] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-gn-arduino-pico:8909333525122905856:38:0) after 2m18s
[10:56:17.477] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-gn-arduino-pico:8909333525122905856:38:0)
[10:56:17.544] Build status: STARTED
[10:56:17.544] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-gn-arduino-pico:8909333525122905856:39:0) after 7m41s
[10:56:22.185] Received PubSub notification, asking Buildbucket for the build status
[10:56:22.246] Build status: STARTED
[11:03:58.718] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-gn-arduino-pico:8909333525122905856:39:0)
[11:03:58.736] Build status: STARTED
[11:03:58.736] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-gn-arduino-pico:8909333525122905856:41:0) after 4m43s
[11:08:41.989] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-gn-arduino-pico:8909333525122905856:41:0)
[11:08:42.010] Build status: STARTED
[11:08:42.010] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-gn-arduino-pico:8909333525122905856:42:0) after 6m12s
[11:14:54.282] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-gn-arduino-pico:8909333525122905856:42:0)
[11:14:54.301] Build status: STARTED
[11:14:54.301] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-gn-arduino-pico:8909333525122905856:43:0) after 2m33s
[11:17:27.429] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-gn-arduino-pico:8909333525122905856:43:0)
[11:17:27.446] Build status: STARTED
[11:17:27.447] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-gn-arduino-pico:8909333525122905856:44:0) after 6m53s
[11:21:23.759] Received PubSub notification, asking Buildbucket for the build status
[11:21:23.779] Build:
{
"id": "8743903462660781025",
"builder": {
"project": "pigweed",
"bucket": "pigweed.dev.ci",
"builder": "pigweed-mac-x86-gn-arduino-pico"
},
"createdBy": "project:pigweed",
"createTime": "2024-06-28T07:47:44.732266422Z",
"startTime": "2024-06-28T10:54:58.711265989Z",
"endTime": "2024-06-28T11:21:23.499859532Z",
"updateTime": "2024-06-28T11:21:23.499859532Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "pigweed.googlesource.com",
"project": "pigweed/pigweed",
"id": "7b6c0f6c01c758442ce1e010382608db369071dd",
"ref": "refs/heads/main"
}
}
}
[11:21:23.779] Invocation finished in 3h33m40.778368178s with status SUCCEEDED