[00:16:38.793] New invocation is queued and will start shortly
[00:16:40.312] Starting the invocation (attempt 1)
[00:16:40.345] Popped buildset tag "buildset:commit/gitiles/pigweed.googlesource.com/pigweed/pigweed/+/776a399e4e057690144a619d5d65c1b1cb79e94a"
[00:16:40.345] Popped gitiles commit info from properties and tags
[00:16:40.345] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[00:16:40.345] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[00:16:40.345] Buildbucket request:
{
"requestId": "8873847893178943136",
"builder": {
"project": "pigweed",
"bucket": "pigweed.dev.ci",
"builder": "pigweed-linux-gn-arduino-pico"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8873847893178943136",
"job": "pigweed/pigweed.dev.ci-pigweed-linux-gn-arduino-pico",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "7006d2857e5a5156e56fe2d1c198db39891b99fc"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@7006d2857e5a5156e56fe2d1c198db39891b99fc",
"title": "7006d2857e5a5156e56fe2d1c198db39891b99fc",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/7006d2857e5a5156e56fe2d1c198db39891b99fc"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "4103190e63aafb4e0cb734be6c5d3dedcafc1133"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@4103190e63aafb4e0cb734be6c5d3dedcafc1133",
"title": "4103190e63aafb4e0cb734be6c5d3dedcafc1133",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/4103190e63aafb4e0cb734be6c5d3dedcafc1133"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "2434409a156d5734bf3cd18eeb5710eb0b5706f3"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@2434409a156d5734bf3cd18eeb5710eb0b5706f3",
"title": "2434409a156d5734bf3cd18eeb5710eb0b5706f3",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/2434409a156d5734bf3cd18eeb5710eb0b5706f3"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "e8d2f96936bd5f34ecd921dc16117d9e0fab52f1"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@e8d2f96936bd5f34ecd921dc16117d9e0fab52f1",
"title": "e8d2f96936bd5f34ecd921dc16117d9e0fab52f1",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/e8d2f96936bd5f34ecd921dc16117d9e0fab52f1"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "90ae93f3c07fc3a02bd496b0b5d45b64ce8b7b66"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@90ae93f3c07fc3a02bd496b0b5d45b64ce8b7b66",
"title": "90ae93f3c07fc3a02bd496b0b5d45b64ce8b7b66",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/90ae93f3c07fc3a02bd496b0b5d45b64ce8b7b66"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "e090efdf7c739ca1b5dbeda1ea7ecbd4c87298b3"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@e090efdf7c739ca1b5dbeda1ea7ecbd4c87298b3",
"title": "e090efdf7c739ca1b5dbeda1ea7ecbd4c87298b3",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/e090efdf7c739ca1b5dbeda1ea7ecbd4c87298b3"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "70d4a628e9c49321b4e56519887b8a9965715dab"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@70d4a628e9c49321b4e56519887b8a9965715dab",
"title": "70d4a628e9c49321b4e56519887b8a9965715dab",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/70d4a628e9c49321b4e56519887b8a9965715dab"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "cc32f808758d59b81de771643764e92372d7c71a"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@cc32f808758d59b81de771643764e92372d7c71a",
"title": "cc32f808758d59b81de771643764e92372d7c71a",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/cc32f808758d59b81de771643764e92372d7c71a"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "776a399e4e057690144a619d5d65c1b1cb79e94a"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@776a399e4e057690144a619d5d65c1b1cb79e94a",
"title": "776a399e4e057690144a619d5d65c1b1cb79e94a",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/776a399e4e057690144a619d5d65c1b1cb79e94a"
}
]
}
},
"gitilesCommit": {
"host": "pigweed.googlesource.com",
"project": "pigweed/pigweed",
"id": "776a399e4e057690144a619d5d65c1b1cb79e94a",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8873847893178943136"
},
{
"key": "scheduler_job_id",
"value": "pigweed/pigweed.dev.ci-pigweed-linux-gn-arduino-pico"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[00:16:40.771] Scheduled build:
{
"id": "8708417830832203137",
"builder": {
"project": "pigweed",
"bucket": "pigweed.dev.ci",
"builder": "pigweed-linux-gn-arduino-pico"
},
"createdBy": "project:pigweed",
"createTime": "2025-07-25T00:16:40.409013585Z",
"updateTime": "2025-07-25T00:16:40.409013585Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "pigweed.googlesource.com",
"project": "pigweed/pigweed",
"id": "776a399e4e057690144a619d5d65c1b1cb79e94a",
"ref": "refs/heads/main"
}
},
"canary": true
}
[00:16:40.771] Task URL: https://cr-buildbucket.appspot.com/build/8708417830832203137
[00:16:40.771] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-arduino-pico:8873847893178943136:2:0) after 3m54s
[00:16:41.809] Received PubSub notification, asking Buildbucket for the build status
[00:16:41.834] Build status: SCHEDULED
[00:20:34.850] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-arduino-pico:8873847893178943136:2:0)
[00:20:34.884] Build status: SCHEDULED
[00:20:34.884] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-arduino-pico:8873847893178943136:4:0) after 4m20s
[00:24:54.958] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-arduino-pico:8873847893178943136:4:0)
[00:24:54.986] Build status: SCHEDULED
[00:24:54.986] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-arduino-pico:8873847893178943136:5:0) after 4m21s
[00:29:16.011] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-arduino-pico:8873847893178943136:5:0)
[00:29:16.044] Build status: SCHEDULED
[00:29:16.044] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-arduino-pico:8873847893178943136:6:0) after 8m25s
[00:37:41.186] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-arduino-pico:8873847893178943136:6:0)
[00:37:41.208] Build status: SCHEDULED
[00:37:41.208] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-arduino-pico:8873847893178943136:7:0) after 2m20s
[00:40:01.352] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-arduino-pico:8873847893178943136:7:0)
[00:40:01.398] Build status: SCHEDULED
[00:40:01.398] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-arduino-pico:8873847893178943136:8:0) after 9m37s
[00:49:38.380] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-arduino-pico:8873847893178943136:8:0)
[00:49:38.407] Build status: SCHEDULED
[00:49:38.407] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-arduino-pico:8873847893178943136:9:0) after 4m25s
[00:54:03.479] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-arduino-pico:8873847893178943136:9:0)
[00:54:03.505] Build status: SCHEDULED
[00:54:03.505] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-arduino-pico:8873847893178943136:10:0) after 8m20s
[01:02:23.523] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-arduino-pico:8873847893178943136:10:0)
[01:02:23.542] Build status: SCHEDULED
[01:02:23.542] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-arduino-pico:8873847893178943136:11:0) after 4m23s
[01:06:46.569] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-arduino-pico:8873847893178943136:11:0)
[01:06:46.593] Build status: SCHEDULED
[01:06:46.593] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-arduino-pico:8873847893178943136:12:0) after 4m10s
[01:10:56.638] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-arduino-pico:8873847893178943136:12:0)
[01:10:56.666] Build status: SCHEDULED
[01:10:56.666] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-arduino-pico:8873847893178943136:13:0) after 6m10s
[01:17:06.701] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-arduino-pico:8873847893178943136:13:0)
[01:17:06.745] Build status: SCHEDULED
[01:17:06.745] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-arduino-pico:8873847893178943136:14:0) after 6m24s
[01:23:30.895] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-arduino-pico:8873847893178943136:14:0)
[01:23:30.915] Build status: SCHEDULED
[01:23:30.915] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-arduino-pico:8873847893178943136:15:0) after 8m32s
[01:32:02.940] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-arduino-pico:8873847893178943136:15:0)
[01:32:02.957] Build status: SCHEDULED
[01:32:02.957] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-arduino-pico:8873847893178943136:16:0) after 4m22s
[01:36:24.995] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-arduino-pico:8873847893178943136:16:0)
[01:36:25.463] Build status: SCHEDULED
[01:36:25.463] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-arduino-pico:8873847893178943136:17:0) after 5m40s
[01:42:05.478] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-arduino-pico:8873847893178943136:17:0)
[01:42:05.553] Build status: SCHEDULED
[01:42:05.553] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-arduino-pico:8873847893178943136:18:0) after 5m37s
[01:47:42.672] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-arduino-pico:8873847893178943136:18:0)
[01:47:42.689] Build status: SCHEDULED
[01:47:42.689] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-arduino-pico:8873847893178943136:19:0) after 3m54s
[01:51:36.765] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-arduino-pico:8873847893178943136:19:0)
[01:51:36.793] Build status: SCHEDULED
[01:51:36.793] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-arduino-pico:8873847893178943136:20:0) after 3m42s
[01:55:18.809] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-arduino-pico:8873847893178943136:20:0)
[01:55:18.938] Build status: SCHEDULED
[01:55:18.938] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-arduino-pico:8873847893178943136:21:0) after 1m21s
[01:56:39.970] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-arduino-pico:8873847893178943136:21:0)
[01:56:40.125] Build status: SCHEDULED
[01:56:40.125] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-arduino-pico:8873847893178943136:22:0) after 4m58s
[02:01:38.146] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-arduino-pico:8873847893178943136:22:0)
[02:01:38.181] Build status: SCHEDULED
[02:01:38.181] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-arduino-pico:8873847893178943136:23:0) after 6m21s
[02:07:59.207] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-arduino-pico:8873847893178943136:23:0)
[02:07:59.248] Build status: SCHEDULED
[02:07:59.249] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-arduino-pico:8873847893178943136:24:0) after 8m41s
[02:16:40.273] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-arduino-pico:8873847893178943136:24:0)
[02:16:40.305] Build status: SCHEDULED
[02:16:40.305] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-arduino-pico:8873847893178943136:25:0) after 4m57s
[02:21:37.384] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-arduino-pico:8873847893178943136:25:0)
[02:21:37.445] Build status: SCHEDULED
[02:21:37.445] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-arduino-pico:8873847893178943136:26:0) after 9m50s
[02:31:27.422] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-arduino-pico:8873847893178943136:26:0)
[02:31:27.496] Build status: SCHEDULED
[02:31:27.496] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-arduino-pico:8873847893178943136:27:0) after 6m29s
[02:37:56.518] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-arduino-pico:8873847893178943136:27:0)
[02:37:56.537] Build status: SCHEDULED
[02:37:56.537] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-arduino-pico:8873847893178943136:28:0) after 5m13s
[02:43:09.556] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-arduino-pico:8873847893178943136:28:0)
[02:43:09.576] Build status: SCHEDULED
[02:43:09.577] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-arduino-pico:8873847893178943136:29:0) after 4m16s
[02:47:25.663] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-arduino-pico:8873847893178943136:29:0)
[02:47:25.685] Build status: SCHEDULED
[02:47:25.685] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-arduino-pico:8873847893178943136:30:0) after 8m42s
[02:56:07.726] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-arduino-pico:8873847893178943136:30:0)
[02:56:07.748] Build status: SCHEDULED
[02:56:07.748] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-arduino-pico:8873847893178943136:31:0) after 9m32s
[03:05:39.828] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-arduino-pico:8873847893178943136:31:0)
[03:05:39.854] Build status: SCHEDULED
[03:05:39.854] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-arduino-pico:8873847893178943136:32:0) after 8m23s
[03:14:02.903] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-arduino-pico:8873847893178943136:32:0)
[03:14:03.036] Build status: SCHEDULED
[03:14:03.036] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-arduino-pico:8873847893178943136:33:0) after 6m57s
[03:21:00.062] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-arduino-pico:8873847893178943136:33:0)
[03:21:00.081] Build status: SCHEDULED
[03:21:00.081] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-arduino-pico:8873847893178943136:34:0) after 5m7s
[03:26:07.135] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-arduino-pico:8873847893178943136:34:0)
[03:26:07.168] Build status: SCHEDULED
[03:26:07.168] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-arduino-pico:8873847893178943136:35:0) after 9m4s
[03:31:10.763] Received PubSub notification, asking Buildbucket for the build status
[03:31:10.790] Build status: STARTED
[03:35:11.325] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-arduino-pico:8873847893178943136:35:0)
[03:35:11.344] Build status: STARTED
[03:35:11.345] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-arduino-pico:8873847893178943136:37:0) after 9m34s
[03:44:45.355] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-arduino-pico:8873847893178943136:37:0)
[03:44:45.381] Build status: STARTED
[03:44:45.381] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-arduino-pico:8873847893178943136:38:0) after 4m20s
[03:47:48.307] Received PubSub notification, asking Buildbucket for the build status
[03:47:48.337] Build:
{
"id": "8708417830832203137",
"builder": {
"project": "pigweed",
"bucket": "pigweed.dev.ci",
"builder": "pigweed-linux-gn-arduino-pico"
},
"createdBy": "project:pigweed",
"createTime": "2025-07-25T00:16:40.409013585Z",
"startTime": "2025-07-25T03:31:10.510703083Z",
"endTime": "2025-07-25T03:47:48.012724172Z",
"updateTime": "2025-07-25T03:47:48.012724172Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "pigweed.googlesource.com",
"project": "pigweed/pigweed",
"id": "776a399e4e057690144a619d5d65c1b1cb79e94a",
"ref": "refs/heads/main"
}
},
"canary": true
}
[03:47:48.337] Invocation finished in 3h31m9.549522984s with status SUCCEEDED