[02:15:04.389] New invocation is queued and will start shortly
[02:15:05.511] Starting the invocation (attempt 1)
[02:15:05.546] Popped buildset tag "buildset:commit/gitiles/pigweed.googlesource.com/pigweed/pigweed/+/2d65e87e608647405788400aad243b94010cf132"
[02:15:05.546] Popped gitiles commit info from properties and tags
[02:15:05.547] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[02:15:05.549] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[02:15:05.549] Buildbucket request:
{
"requestId": "8914971465174652256",
"builder": {
"project": "pigweed",
"bucket": "pigweed.dev.ci",
"builder": "pigweed-mac-x86-zephyr"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8914971465174652256",
"job": "pigweed/pigweed.dev.ci-pigweed-mac-x86-zephyr",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "ae56930feef94265feb28f39af7e9ca9ad4f1f0c"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@ae56930feef94265feb28f39af7e9ca9ad4f1f0c",
"title": "ae56930feef94265feb28f39af7e9ca9ad4f1f0c",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/ae56930feef94265feb28f39af7e9ca9ad4f1f0c"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "74004ce5958687a2f23169c8baae6aa3f45eef5b"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@74004ce5958687a2f23169c8baae6aa3f45eef5b",
"title": "74004ce5958687a2f23169c8baae6aa3f45eef5b",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/74004ce5958687a2f23169c8baae6aa3f45eef5b"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "188bd4726e0eca3c7cad7fa8f7c8b76c1e12cc38"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@188bd4726e0eca3c7cad7fa8f7c8b76c1e12cc38",
"title": "188bd4726e0eca3c7cad7fa8f7c8b76c1e12cc38",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/188bd4726e0eca3c7cad7fa8f7c8b76c1e12cc38"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "2d65e87e608647405788400aad243b94010cf132"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@2d65e87e608647405788400aad243b94010cf132",
"title": "2d65e87e608647405788400aad243b94010cf132",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/2d65e87e608647405788400aad243b94010cf132"
}
]
}
},
"gitilesCommit": {
"host": "pigweed.googlesource.com",
"project": "pigweed/pigweed",
"id": "2d65e87e608647405788400aad243b94010cf132",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8914971465174652256"
},
{
"key": "scheduler_job_id",
"value": "pigweed/pigweed.dev.ci-pigweed-mac-x86-zephyr"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[02:15:06.018] Scheduled build:
{
"id": "8749541403214164577",
"builder": {
"project": "pigweed",
"bucket": "pigweed.dev.ci",
"builder": "pigweed-mac-x86-zephyr"
},
"createdBy": "project:pigweed",
"createTime": "2024-04-27T02:15:05.629523375Z",
"updateTime": "2024-04-27T02:15:05.629523375Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "pigweed.googlesource.com",
"project": "pigweed/pigweed",
"id": "2d65e87e608647405788400aad243b94010cf132",
"ref": "refs/heads/main"
}
}
}
[02:15:06.018] Task URL: https://cr-buildbucket.appspot.com/build/8749541403214164577
[02:15:06.018] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-zephyr:8914971465174652256:2:0) after 9m9s
[02:15:07.046] Received PubSub notification, asking Buildbucket for the build status
[02:15:07.076] Build status: SCHEDULED
[02:24:15.200] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-zephyr:8914971465174652256:2:0)
[02:24:15.236] Build status: SCHEDULED
[02:24:15.236] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-zephyr:8914971465174652256:4:0) after 7m14s
[02:31:29.264] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-zephyr:8914971465174652256:4:0)
[02:31:29.296] Build status: SCHEDULED
[02:31:29.296] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-zephyr:8914971465174652256:5:0) after 9m18s
[02:40:47.334] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-zephyr:8914971465174652256:5:0)
[02:40:47.359] Build status: SCHEDULED
[02:40:47.359] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-zephyr:8914971465174652256:6:0) after 1m46s
[02:42:33.675] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-zephyr:8914971465174652256:6:0)
[02:42:33.762] Build status: SCHEDULED
[02:42:33.762] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-zephyr:8914971465174652256:7:0) after 8m19s
[02:50:52.951] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-zephyr:8914971465174652256:7:0)
[02:50:52.974] Build status: SCHEDULED
[02:50:52.974] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-zephyr:8914971465174652256:8:0) after 2m8s
[02:53:01.029] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-zephyr:8914971465174652256:8:0)
[02:53:01.050] Build status: SCHEDULED
[02:53:01.050] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-zephyr:8914971465174652256:9:0) after 9m44s
[03:02:45.067] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-zephyr:8914971465174652256:9:0)
[03:02:45.089] Build status: SCHEDULED
[03:02:45.089] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-zephyr:8914971465174652256:10:0) after 1m56s
[03:04:41.175] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-zephyr:8914971465174652256:10:0)
[03:04:41.205] Build status: SCHEDULED
[03:04:41.205] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-zephyr:8914971465174652256:11:0) after 3m45s
[03:08:26.228] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-zephyr:8914971465174652256:11:0)
[03:08:26.255] Build status: SCHEDULED
[03:08:26.255] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-zephyr:8914971465174652256:12:0) after 5m10s
[03:13:36.755] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-zephyr:8914971465174652256:12:0)
[03:13:36.773] Build status: SCHEDULED
[03:13:36.773] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-zephyr:8914971465174652256:13:0) after 6m25s
[03:20:01.844] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-zephyr:8914971465174652256:13:0)
[03:20:01.866] Build status: SCHEDULED
[03:20:01.866] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-zephyr:8914971465174652256:14:0) after 4m35s
[03:24:37.034] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-zephyr:8914971465174652256:14:0)
[03:24:37.055] Build status: SCHEDULED
[03:24:37.055] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-zephyr:8914971465174652256:15:0) after 7m20s
[03:31:57.071] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-zephyr:8914971465174652256:15:0)
[03:31:57.092] Build status: SCHEDULED
[03:31:57.092] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-zephyr:8914971465174652256:16:0) after 8m57s
[03:40:54.226] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-zephyr:8914971465174652256:16:0)
[03:40:54.304] Build status: SCHEDULED
[03:40:54.304] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-zephyr:8914971465174652256:17:0) after 5m29s
[03:46:23.450] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-zephyr:8914971465174652256:17:0)
[03:46:23.481] Build status: SCHEDULED
[03:46:23.481] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-zephyr:8914971465174652256:18:0) after 4m49s
[03:51:12.497] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-zephyr:8914971465174652256:18:0)
[03:51:12.520] Build status: SCHEDULED
[03:51:12.520] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-zephyr:8914971465174652256:19:0) after 1m28s
[03:52:40.561] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-zephyr:8914971465174652256:19:0)
[03:52:40.580] Build status: SCHEDULED
[03:52:40.580] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-zephyr:8914971465174652256:20:0) after 3m7s
[03:55:47.775] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-zephyr:8914971465174652256:20:0)
[03:55:47.795] Build status: SCHEDULED
[03:55:47.795] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-zephyr:8914971465174652256:21:0) after 8m50s
[04:04:37.854] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-zephyr:8914971465174652256:21:0)
[04:04:37.965] Build status: SCHEDULED
[04:04:37.965] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-zephyr:8914971465174652256:22:0) after 5m13s
[04:09:51.229] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-zephyr:8914971465174652256:22:0)
[04:09:51.270] Build status: SCHEDULED
[04:09:51.270] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-zephyr:8914971465174652256:23:0) after 8m41s
[04:18:32.298] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-zephyr:8914971465174652256:23:0)
[04:18:32.335] Build status: SCHEDULED
[04:18:32.335] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-zephyr:8914971465174652256:24:0) after 4m40s
[04:23:12.639] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-zephyr:8914971465174652256:24:0)
[04:23:12.656] Build status: SCHEDULED
[04:23:12.656] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-zephyr:8914971465174652256:25:0) after 2m37s
[04:25:49.885] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-zephyr:8914971465174652256:25:0)
[04:25:49.907] Build status: SCHEDULED
[04:25:49.907] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-zephyr:8914971465174652256:26:0) after 4m22s
[04:30:12.236] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-zephyr:8914971465174652256:26:0)
[04:30:12.255] Build status: SCHEDULED
[04:30:12.255] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-zephyr:8914971465174652256:27:0) after 7m36s
[04:37:48.276] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-zephyr:8914971465174652256:27:0)
[04:37:48.291] Build status: SCHEDULED
[04:37:48.291] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-zephyr:8914971465174652256:28:0) after 5m37s
[04:43:25.351] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-zephyr:8914971465174652256:28:0)
[04:43:25.384] Build status: SCHEDULED
[04:43:25.384] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-zephyr:8914971465174652256:29:0) after 7m35s
[04:51:00.527] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-zephyr:8914971465174652256:29:0)
[04:51:00.560] Build status: SCHEDULED
[04:51:00.560] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-zephyr:8914971465174652256:30:0) after 3m20s
[04:54:20.704] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-zephyr:8914971465174652256:30:0)
[04:54:20.745] Build status: SCHEDULED
[04:54:20.746] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-zephyr:8914971465174652256:31:0) after 4m28s
[04:58:48.766] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-zephyr:8914971465174652256:31:0)
[04:58:48.802] Build status: SCHEDULED
[04:58:48.802] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-zephyr:8914971465174652256:32:0) after 9m38s
[05:08:26.865] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-zephyr:8914971465174652256:32:0)
[05:08:27.002] Build status: SCHEDULED
[05:08:27.003] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-zephyr:8914971465174652256:33:0) after 7m6s
[05:15:33.146] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-zephyr:8914971465174652256:33:0)
[05:15:33.192] Build status: SCHEDULED
[05:15:33.192] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-zephyr:8914971465174652256:34:0) after 3m33s
[05:19:06.207] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-zephyr:8914971465174652256:34:0)
[05:19:06.227] Build status: SCHEDULED
[05:19:06.227] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-zephyr:8914971465174652256:35:0) after 4m40s
[05:23:46.318] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-zephyr:8914971465174652256:35:0)
[05:23:46.339] Build status: SCHEDULED
[05:23:46.339] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-zephyr:8914971465174652256:36:0) after 6m39s
[05:30:25.354] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-zephyr:8914971465174652256:36:0)
[05:30:25.410] Build status: SCHEDULED
[05:30:25.410] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-zephyr:8914971465174652256:37:0) after 5m40s
[05:36:05.545] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-zephyr:8914971465174652256:37:0)
[05:36:05.571] Build status: SCHEDULED
[05:36:05.571] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-zephyr:8914971465174652256:38:0) after 6m25s
[05:42:30.687] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-zephyr:8914971465174652256:38:0)
[05:42:30.733] Build status: SCHEDULED
[05:42:30.733] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-zephyr:8914971465174652256:39:0) after 3m48s
[05:46:18.781] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-zephyr:8914971465174652256:39:0)
[05:46:18.807] Build status: SCHEDULED
[05:46:18.807] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-zephyr:8914971465174652256:40:0) after 5m33s
[05:51:51.825] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-zephyr:8914971465174652256:40:0)
[05:51:51.864] Build status: SCHEDULED
[05:51:51.864] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-zephyr:8914971465174652256:41:0) after 2m48s
[05:54:39.960] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-zephyr:8914971465174652256:41:0)
[05:54:39.986] Build status: SCHEDULED
[05:54:39.986] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-zephyr:8914971465174652256:42:0) after 5m45s
[05:55:52.443] Received PubSub notification, asking Buildbucket for the build status
[05:55:52.464] Build status: STARTED
[06:00:25.019] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-zephyr:8914971465174652256:42:0)
[06:00:25.100] Build status: STARTED
[06:00:25.100] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-zephyr:8914971465174652256:44:0) after 4m6s
[06:04:22.517] Received PubSub notification, asking Buildbucket for the build status
[06:04:22.538] Build:
{
"id": "8749541403214164577",
"builder": {
"project": "pigweed",
"bucket": "pigweed.dev.ci",
"builder": "pigweed-mac-x86-zephyr"
},
"createdBy": "project:pigweed",
"createTime": "2024-04-27T02:15:05.629523375Z",
"startTime": "2024-04-27T05:55:51.917132605Z",
"endTime": "2024-04-27T06:04:19.594487578Z",
"updateTime": "2024-04-27T06:04:19.594487578Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "pigweed.googlesource.com",
"project": "pigweed/pigweed",
"id": "2d65e87e608647405788400aad243b94010cf132",
"ref": "refs/heads/main"
}
}
}
[06:04:22.538] Invocation finished in 3h49m18.162615278s with status SUCCEEDED