[04:15:02.436] New invocation is queued and will start shortly
[04:15:03.560] Starting the invocation (attempt 1)
[04:15:03.585] Popped buildset tag "buildset:commit/gitiles/pigweed.googlesource.com/pigweed/pigweed/+/f20a2dc6ded67a50659c66d8d860ae338d4058c8"
[04:15:03.585] Popped gitiles commit info from properties and tags
[04:15:03.585] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[04:15:03.585] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[04:15:03.586] Buildbucket request:
{
"requestId": "8911249441867497600",
"builder": {
"project": "pigweed",
"bucket": "pigweed.dev.ci",
"builder": "pigweed-mac-x86-bazel-build-host-clang"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8911249441867497600",
"job": "pigweed/pigweed.dev.ci-pigweed-mac-x86-bazel-build-host-clang",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "3d3e59c25557e050da028273db776ba854182fe0"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@3d3e59c25557e050da028273db776ba854182fe0",
"title": "3d3e59c25557e050da028273db776ba854182fe0",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/3d3e59c25557e050da028273db776ba854182fe0"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "d3fdc8b3a0e8d99b0bdc5ef31eb482bc3e8a028c"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@d3fdc8b3a0e8d99b0bdc5ef31eb482bc3e8a028c",
"title": "d3fdc8b3a0e8d99b0bdc5ef31eb482bc3e8a028c",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/d3fdc8b3a0e8d99b0bdc5ef31eb482bc3e8a028c"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "13815f73d4aa8934cbd834f76b4f12b822a3128d"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@13815f73d4aa8934cbd834f76b4f12b822a3128d",
"title": "13815f73d4aa8934cbd834f76b4f12b822a3128d",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/13815f73d4aa8934cbd834f76b4f12b822a3128d"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "f20a2dc6ded67a50659c66d8d860ae338d4058c8"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@f20a2dc6ded67a50659c66d8d860ae338d4058c8",
"title": "f20a2dc6ded67a50659c66d8d860ae338d4058c8",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/f20a2dc6ded67a50659c66d8d860ae338d4058c8"
}
]
}
},
"gitilesCommit": {
"host": "pigweed.googlesource.com",
"project": "pigweed/pigweed",
"id": "f20a2dc6ded67a50659c66d8d860ae338d4058c8",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8911249441867497600"
},
{
"key": "scheduler_job_id",
"value": "pigweed/pigweed.dev.ci-pigweed-mac-x86-bazel-build-host-clang"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[04:15:04.533] Scheduled build:
{
"id": "8745819379903878801",
"builder": {
"project": "pigweed",
"bucket": "pigweed.dev.ci",
"builder": "pigweed-mac-x86-bazel-build-host-clang"
},
"createdBy": "project:pigweed",
"createTime": "2024-06-07T04:15:03.665318449Z",
"updateTime": "2024-06-07T04:15:03.665318449Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "pigweed.googlesource.com",
"project": "pigweed/pigweed",
"id": "f20a2dc6ded67a50659c66d8d860ae338d4058c8",
"ref": "refs/heads/main"
}
}
}
[04:15:04.533] Task URL: https://cr-buildbucket.appspot.com/build/8745819379903878801
[04:15:04.533] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-bazel-build-host-clang:8911249441867497600:2:0) after 3m30s
[04:15:05.195] Received PubSub notification, asking Buildbucket for the build status
[04:15:05.256] Build status: SCHEDULED
[04:18:34.655] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-bazel-build-host-clang:8911249441867497600:2:0)
[04:18:34.677] Build status: SCHEDULED
[04:18:34.677] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-bazel-build-host-clang:8911249441867497600:4:0) after 9m13s
[04:27:47.978] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-bazel-build-host-clang:8911249441867497600:4:0)
[04:27:47.997] Build status: SCHEDULED
[04:27:47.997] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-bazel-build-host-clang:8911249441867497600:5:0) after 2m49s
[04:30:37.253] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-bazel-build-host-clang:8911249441867497600:5:0)
[04:30:37.294] Build status: SCHEDULED
[04:30:37.294] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-bazel-build-host-clang:8911249441867497600:6:0) after 9m43s
[04:40:20.410] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-bazel-build-host-clang:8911249441867497600:6:0)
[04:40:20.433] Build status: SCHEDULED
[04:40:20.433] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-bazel-build-host-clang:8911249441867497600:7:0) after 3m46s
[04:44:06.506] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-bazel-build-host-clang:8911249441867497600:7:0)
[04:44:06.529] Build status: SCHEDULED
[04:44:06.529] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-bazel-build-host-clang:8911249441867497600:8:0) after 1m51s
[04:45:57.584] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-bazel-build-host-clang:8911249441867497600:8:0)
[04:45:57.612] Build status: SCHEDULED
[04:45:57.612] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-bazel-build-host-clang:8911249441867497600:9:0) after 2m42s
[04:48:39.847] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-bazel-build-host-clang:8911249441867497600:9:0)
[04:48:39.923] Build status: SCHEDULED
[04:48:39.923] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-bazel-build-host-clang:8911249441867497600:10:0) after 3m46s
[04:52:25.940] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-bazel-build-host-clang:8911249441867497600:10:0)
[04:52:25.959] Build status: SCHEDULED
[04:52:25.959] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-bazel-build-host-clang:8911249441867497600:11:0) after 8m14s
[05:00:40.394] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-bazel-build-host-clang:8911249441867497600:11:0)
[05:00:40.417] Build status: SCHEDULED
[05:00:40.418] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-bazel-build-host-clang:8911249441867497600:12:0) after 1m52s
[05:02:32.432] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-bazel-build-host-clang:8911249441867497600:12:0)
[05:02:32.456] Build status: SCHEDULED
[05:02:32.456] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-bazel-build-host-clang:8911249441867497600:13:0) after 8m4s
[05:10:36.488] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-bazel-build-host-clang:8911249441867497600:13:0)
[05:10:36.509] Build status: SCHEDULED
[05:10:36.509] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-bazel-build-host-clang:8911249441867497600:14:0) after 1m6s
[05:11:42.703] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-bazel-build-host-clang:8911249441867497600:14:0)
[05:11:42.720] Build status: SCHEDULED
[05:11:42.721] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-bazel-build-host-clang:8911249441867497600:15:0) after 9m28s
[05:21:10.740] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-bazel-build-host-clang:8911249441867497600:15:0)
[05:21:10.793] Build status: SCHEDULED
[05:21:10.793] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-bazel-build-host-clang:8911249441867497600:16:0) after 6m17s
[05:27:27.815] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-bazel-build-host-clang:8911249441867497600:16:0)
[05:27:27.896] Build status: SCHEDULED
[05:27:27.896] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-bazel-build-host-clang:8911249441867497600:17:0) after 5m56s
[05:33:23.922] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-bazel-build-host-clang:8911249441867497600:17:0)
[05:33:23.940] Build status: SCHEDULED
[05:33:23.940] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-bazel-build-host-clang:8911249441867497600:18:0) after 3m27s
[05:36:50.964] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-bazel-build-host-clang:8911249441867497600:18:0)
[05:36:50.990] Build status: SCHEDULED
[05:36:50.990] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-bazel-build-host-clang:8911249441867497600:19:0) after 1m22s
[05:38:13.085] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-bazel-build-host-clang:8911249441867497600:19:0)
[05:38:13.104] Build status: SCHEDULED
[05:38:13.104] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-bazel-build-host-clang:8911249441867497600:20:0) after 9m6s
[05:47:19.118] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-bazel-build-host-clang:8911249441867497600:20:0)
[05:47:19.147] Build status: SCHEDULED
[05:47:19.147] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-bazel-build-host-clang:8911249441867497600:21:0) after 7m45s
[05:55:04.421] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-bazel-build-host-clang:8911249441867497600:21:0)
[05:55:04.459] Build status: SCHEDULED
[05:55:04.459] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-bazel-build-host-clang:8911249441867497600:22:0) after 7m54s
[06:02:58.502] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-bazel-build-host-clang:8911249441867497600:22:0)
[06:02:58.521] Build status: SCHEDULED
[06:02:58.522] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-bazel-build-host-clang:8911249441867497600:23:0) after 5m12s
[06:08:10.663] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-bazel-build-host-clang:8911249441867497600:23:0)
[06:08:10.712] Build status: SCHEDULED
[06:08:10.712] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-bazel-build-host-clang:8911249441867497600:24:0) after 5m58s
[06:14:08.739] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-bazel-build-host-clang:8911249441867497600:24:0)
[06:14:08.762] Build status: SCHEDULED
[06:14:08.762] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-bazel-build-host-clang:8911249441867497600:25:0) after 3m8s
[06:17:16.995] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-bazel-build-host-clang:8911249441867497600:25:0)
[06:17:17.024] Build status: SCHEDULED
[06:17:17.024] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-bazel-build-host-clang:8911249441867497600:26:0) after 9m4s
[06:26:21.042] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-bazel-build-host-clang:8911249441867497600:26:0)
[06:26:21.062] Build status: SCHEDULED
[06:26:21.062] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-bazel-build-host-clang:8911249441867497600:27:0) after 7m37s
[06:33:58.114] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-bazel-build-host-clang:8911249441867497600:27:0)
[06:33:58.132] Build status: SCHEDULED
[06:33:58.132] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-bazel-build-host-clang:8911249441867497600:28:0) after 4m55s
[06:38:53.346] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-bazel-build-host-clang:8911249441867497600:28:0)
[06:38:53.363] Build status: SCHEDULED
[06:38:53.364] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-bazel-build-host-clang:8911249441867497600:29:0) after 7m50s
[06:46:43.403] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-bazel-build-host-clang:8911249441867497600:29:0)
[06:46:43.429] Build status: SCHEDULED
[06:46:43.429] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-bazel-build-host-clang:8911249441867497600:30:0) after 5m40s
[06:52:23.448] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-bazel-build-host-clang:8911249441867497600:30:0)
[06:52:23.560] Build status: SCHEDULED
[06:52:23.560] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-bazel-build-host-clang:8911249441867497600:31:0) after 5m59s
[06:58:22.582] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-bazel-build-host-clang:8911249441867497600:31:0)
[06:58:22.600] Build status: SCHEDULED
[06:58:22.600] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-bazel-build-host-clang:8911249441867497600:32:0) after 3m23s
[07:01:45.680] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-bazel-build-host-clang:8911249441867497600:32:0)
[07:01:45.722] Build status: SCHEDULED
[07:01:45.722] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-bazel-build-host-clang:8911249441867497600:33:0) after 7m46s
[07:09:31.747] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-bazel-build-host-clang:8911249441867497600:33:0)
[07:09:31.772] Build status: SCHEDULED
[07:09:31.772] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-bazel-build-host-clang:8911249441867497600:34:0) after 8m22s
[07:17:53.789] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-bazel-build-host-clang:8911249441867497600:34:0)
[07:17:53.817] Build status: SCHEDULED
[07:17:53.817] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-bazel-build-host-clang:8911249441867497600:35:0) after 1m30s
[07:19:23.838] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-bazel-build-host-clang:8911249441867497600:35:0)
[07:19:23.869] Build status: SCHEDULED
[07:19:23.870] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-bazel-build-host-clang:8911249441867497600:36:0) after 5m50s
[07:25:13.888] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-bazel-build-host-clang:8911249441867497600:36:0)
[07:25:13.910] Build status: SCHEDULED
[07:25:13.910] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-bazel-build-host-clang:8911249441867497600:37:0) after 5m47s
[07:31:01.218] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-bazel-build-host-clang:8911249441867497600:37:0)
[07:31:01.254] Build status: SCHEDULED
[07:31:01.254] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-bazel-build-host-clang:8911249441867497600:38:0) after 3m15s
[07:34:16.281] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-bazel-build-host-clang:8911249441867497600:38:0)
[07:34:16.380] Build status: SCHEDULED
[07:34:16.380] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-bazel-build-host-clang:8911249441867497600:39:0) after 9m59s
[07:44:15.840] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-bazel-build-host-clang:8911249441867497600:39:0)
[07:44:15.866] Build status: SCHEDULED
[07:44:15.866] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-bazel-build-host-clang:8911249441867497600:40:0) after 8m40s
[07:50:49.449] Received PubSub notification, asking Buildbucket for the build status
[07:50:49.487] Build status: STARTED
[07:52:55.914] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-bazel-build-host-clang:8911249441867497600:40:0)
[07:52:55.947] Build status: STARTED
[07:52:55.947] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-bazel-build-host-clang:8911249441867497600:42:0) after 8m43s
[08:01:20.199] Received PubSub notification, asking Buildbucket for the build status
[08:01:20.228] Build:
{
"id": "8745819379903878801",
"builder": {
"project": "pigweed",
"bucket": "pigweed.dev.ci",
"builder": "pigweed-mac-x86-bazel-build-host-clang"
},
"createdBy": "project:pigweed",
"createTime": "2024-06-07T04:15:03.665318449Z",
"startTime": "2024-06-07T07:50:49.107855627Z",
"endTime": "2024-06-07T08:01:19.638292078Z",
"updateTime": "2024-06-07T08:01:19.638292078Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "pigweed.googlesource.com",
"project": "pigweed/pigweed",
"id": "f20a2dc6ded67a50659c66d8d860ae338d4058c8",
"ref": "refs/heads/main"
}
}
}
[08:01:20.228] Invocation finished in 3h46m17.818957338s with status SUCCEEDED