[23:35:38.112] New invocation is queued and will start shortly
[23:35:39.215] Starting the invocation (attempt 1)
[23:35:39.267] Popped buildset tag "buildset:commit/gitiles/pigweed.googlesource.com/pigweed/pigweed/+/1154ff5dda14d5d45fa9797c14014cebf82e1025"
[23:35:39.267] Popped gitiles commit info from properties and tags
[23:35:39.267] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[23:35:39.267] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[23:35:39.267] Buildbucket request:
{
"requestId": "8915978062767757360",
"builder": {
"project": "pigweed",
"bucket": "pigweed.dev.ci",
"builder": "pigweed-mac-x86-zephyr"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8915978062767757360",
"job": "pigweed/pigweed.dev.ci-pigweed-mac-x86-zephyr",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "53e8f63714ca67b20432eb34bcc98c213a044d28"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@53e8f63714ca67b20432eb34bcc98c213a044d28",
"title": "53e8f63714ca67b20432eb34bcc98c213a044d28",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/53e8f63714ca67b20432eb34bcc98c213a044d28"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "b657245b27e28dbc10d204f41776998006df0b4b"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@b657245b27e28dbc10d204f41776998006df0b4b",
"title": "b657245b27e28dbc10d204f41776998006df0b4b",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/b657245b27e28dbc10d204f41776998006df0b4b"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "b5b08af769c2a3a7d4ffd2e179f939320f6b23aa"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@b5b08af769c2a3a7d4ffd2e179f939320f6b23aa",
"title": "b5b08af769c2a3a7d4ffd2e179f939320f6b23aa",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/b5b08af769c2a3a7d4ffd2e179f939320f6b23aa"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "1154ff5dda14d5d45fa9797c14014cebf82e1025"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@1154ff5dda14d5d45fa9797c14014cebf82e1025",
"title": "1154ff5dda14d5d45fa9797c14014cebf82e1025",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/1154ff5dda14d5d45fa9797c14014cebf82e1025"
}
]
}
},
"gitilesCommit": {
"host": "pigweed.googlesource.com",
"project": "pigweed/pigweed",
"id": "1154ff5dda14d5d45fa9797c14014cebf82e1025",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8915978062767757360"
},
{
"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"
}
}
[23:35:40.446] Scheduled build:
{
"id": "8750548000769305553",
"builder": {
"project": "pigweed",
"bucket": "pigweed.dev.ci",
"builder": "pigweed-mac-x86-zephyr"
},
"createdBy": "project:pigweed",
"createTime": "2024-04-15T23:35:39.394357527Z",
"updateTime": "2024-04-15T23:35:39.394357527Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "pigweed.googlesource.com",
"project": "pigweed/pigweed",
"id": "1154ff5dda14d5d45fa9797c14014cebf82e1025",
"ref": "refs/heads/main"
}
}
}
[23:35:40.446] Task URL: https://cr-buildbucket.appspot.com/build/8750548000769305553
[23:35:40.446] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-zephyr:8915978062767757360:2:0) after 1m49s
[23:35:41.280] Received PubSub notification, asking Buildbucket for the build status
[23:35:41.362] Build status: SCHEDULED
[23:37:29.472] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-zephyr:8915978062767757360:2:0)
[23:37:29.512] Build status: SCHEDULED
[23:37:29.512] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-zephyr:8915978062767757360:4:0) after 7m25s
[23:44:54.572] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-zephyr:8915978062767757360:4:0)
[23:44:54.589] Build status: SCHEDULED
[23:44:54.589] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-zephyr:8915978062767757360:5:0) after 9m2s
[23:53:56.636] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-zephyr:8915978062767757360:5:0)
[23:53:56.675] Build status: SCHEDULED
[23:53:56.675] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-zephyr:8915978062767757360:6:0) after 6m27s
[00:00:23.851] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-zephyr:8915978062767757360:6:0)
[00:00:23.881] Build status: SCHEDULED
[00:00:23.881] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-zephyr:8915978062767757360:7:0) after 3m56s
[00:04:19.929] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-zephyr:8915978062767757360:7:0)
[00:04:20.073] Build status: SCHEDULED
[00:04:20.073] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-zephyr:8915978062767757360:8:0) after 6m23s
[00:10:43.141] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-zephyr:8915978062767757360:8:0)
[00:10:43.166] Build status: SCHEDULED
[00:10:43.166] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-zephyr:8915978062767757360:9:0) after 1m13s
[00:11:56.180] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-zephyr:8915978062767757360:9:0)
[00:11:56.211] Build status: SCHEDULED
[00:11:56.211] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-zephyr:8915978062767757360:10:0) after 9m12s
[00:21:08.237] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-zephyr:8915978062767757360:10:0)
[00:21:08.261] Build status: SCHEDULED
[00:21:08.261] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-zephyr:8915978062767757360:11:0) after 7m27s
[00:28:35.318] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-zephyr:8915978062767757360:11:0)
[00:28:35.352] Build status: SCHEDULED
[00:28:35.352] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-zephyr:8915978062767757360:12:0) after 5m55s
[00:34:30.366] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-zephyr:8915978062767757360:12:0)
[00:34:30.408] Build status: SCHEDULED
[00:34:30.408] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-zephyr:8915978062767757360:13:0) after 3m31s
[00:38:01.408] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-zephyr:8915978062767757360:13:0)
[00:38:01.431] Build status: SCHEDULED
[00:38:01.431] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-zephyr:8915978062767757360:14:0) after 3m18s
[00:41:19.807] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-zephyr:8915978062767757360:14:0)
[00:41:19.846] Build status: SCHEDULED
[00:41:19.846] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-zephyr:8915978062767757360:15:0) after 1m22s
[00:42:42.568] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-zephyr:8915978062767757360:15:0)
[00:42:42.590] Build status: SCHEDULED
[00:42:42.590] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-zephyr:8915978062767757360:16:0) after 7m8s
[00:49:50.662] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-zephyr:8915978062767757360:16:0)
[00:49:50.729] Build status: SCHEDULED
[00:49:50.729] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-zephyr:8915978062767757360:17:0) after 4m35s
[00:54:25.797] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-zephyr:8915978062767757360:17:0)
[00:54:25.833] Build status: SCHEDULED
[00:54:25.833] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-zephyr:8915978062767757360:18:0) after 3m5s
[00:57:31.044] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-zephyr:8915978062767757360:18:0)
[00:57:31.086] Build status: SCHEDULED
[00:57:31.086] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-zephyr:8915978062767757360:19:0) after 1m16s
[00:58:47.247] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-zephyr:8915978062767757360:19:0)
[00:58:47.263] Build status: SCHEDULED
[00:58:47.263] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-zephyr:8915978062767757360:20:0) after 5m21s
[01:04:08.413] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-zephyr:8915978062767757360:20:0)
[01:04:08.487] Build status: SCHEDULED
[01:04:08.487] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-zephyr:8915978062767757360:21:0) after 2m28s
[01:06:36.511] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-zephyr:8915978062767757360:21:0)
[01:06:36.608] Build status: SCHEDULED
[01:06:36.608] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-zephyr:8915978062767757360:22:0) after 9m35s
[01:16:11.935] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-zephyr:8915978062767757360:22:0)
[01:16:11.955] Build status: SCHEDULED
[01:16:11.955] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-zephyr:8915978062767757360:23:0) after 7m14s
[01:23:26.025] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-zephyr:8915978062767757360:23:0)
[01:23:26.063] Build status: SCHEDULED
[01:23:26.063] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-zephyr:8915978062767757360:24:0) after 5m48s
[01:29:14.086] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-zephyr:8915978062767757360:24:0)
[01:29:14.108] Build status: SCHEDULED
[01:29:14.108] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-zephyr:8915978062767757360:25:0) after 7m17s
[01:36:31.128] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-zephyr:8915978062767757360:25:0)
[01:36:31.147] Build status: SCHEDULED
[01:36:31.148] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-zephyr:8915978062767757360:26:0) after 7m42s
[01:44:13.213] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-zephyr:8915978062767757360:26:0)
[01:44:13.234] Build status: SCHEDULED
[01:44:13.234] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-zephyr:8915978062767757360:27:0) after 9m30s
[01:53:43.300] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-zephyr:8915978062767757360:27:0)
[01:53:43.327] Build status: SCHEDULED
[01:53:43.328] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-zephyr:8915978062767757360:28:0) after 5m59s
[01:59:42.398] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-zephyr:8915978062767757360:28:0)
[01:59:42.420] Build status: SCHEDULED
[01:59:42.420] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-zephyr:8915978062767757360:29:0) after 7m7s
[02:06:49.453] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-zephyr:8915978062767757360:29:0)
[02:06:49.473] Build status: SCHEDULED
[02:06:49.473] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-zephyr:8915978062767757360:30:0) after 2m46s
[02:09:35.494] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-zephyr:8915978062767757360:30:0)
[02:09:35.513] Build status: SCHEDULED
[02:09:35.513] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-zephyr:8915978062767757360:31:0) after 2m46s
[02:12:21.588] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-zephyr:8915978062767757360:31:0)
[02:12:21.624] Build status: SCHEDULED
[02:12:21.624] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-zephyr:8915978062767757360:32:0) after 5m0s
[02:17:21.641] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-zephyr:8915978062767757360:32:0)
[02:17:21.675] Build status: SCHEDULED
[02:17:21.675] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-zephyr:8915978062767757360:33:0) after 5m55s
[02:23:16.693] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-zephyr:8915978062767757360:33:0)
[02:23:16.733] Build status: SCHEDULED
[02:23:16.733] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-zephyr:8915978062767757360:34:0) after 8m32s
[02:31:49.390] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-zephyr:8915978062767757360:34:0)
[02:31:49.416] Build status: SCHEDULED
[02:31:49.416] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-zephyr:8915978062767757360:35:0) after 5m55s
[02:37:44.478] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-zephyr:8915978062767757360:35:0)
[02:37:44.506] Build status: SCHEDULED
[02:37:44.506] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-zephyr:8915978062767757360:36:0) after 9m25s
[02:47:09.581] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-zephyr:8915978062767757360:36:0)
[02:47:09.600] Build status: SCHEDULED
[02:47:09.600] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-zephyr:8915978062767757360:37:0) after 4m58s
[02:52:07.747] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-zephyr:8915978062767757360:37:0)
[02:52:07.762] Build status: SCHEDULED
[02:52:07.762] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-zephyr:8915978062767757360:38:0) after 8m14s
[03:00:21.869] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-zephyr:8915978062767757360:38:0)
[03:00:21.928] Build status: SCHEDULED
[03:00:21.928] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-zephyr:8915978062767757360:39:0) after 2m28s
[03:02:49.979] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-zephyr:8915978062767757360:39:0)
[03:02:50.009] Build status: SCHEDULED
[03:02:50.010] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-zephyr:8915978062767757360:40:0) after 6m41s
[03:09:31.132] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-zephyr:8915978062767757360:40:0)
[03:09:31.171] Build status: SCHEDULED
[03:09:31.171] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-zephyr:8915978062767757360:41:0) after 4m56s
[03:14:27.212] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-zephyr:8915978062767757360:41:0)
[03:14:27.233] Build status: SCHEDULED
[03:14:27.233] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-zephyr:8915978062767757360:42:0) after 4m23s
[03:16:23.289] Received PubSub notification, asking Buildbucket for the build status
[03:16:23.314] Build status: STARTED
[03:18:50.373] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-zephyr:8915978062767757360:42:0)
[03:18:50.392] Build status: STARTED
[03:18:50.392] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-mac-x86-zephyr:8915978062767757360:44:0) after 7m33s
[03:24:27.707] Received PubSub notification, asking Buildbucket for the build status
[03:24:27.732] Build:
{
"id": "8750548000769305553",
"builder": {
"project": "pigweed",
"bucket": "pigweed.dev.ci",
"builder": "pigweed-mac-x86-zephyr"
},
"createdBy": "project:pigweed",
"createTime": "2024-04-15T23:35:39.394357527Z",
"startTime": "2024-04-16T03:16:22.959557418Z",
"endTime": "2024-04-16T03:24:27.391493818Z",
"updateTime": "2024-04-16T03:24:27.391493818Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "pigweed.googlesource.com",
"project": "pigweed/pigweed",
"id": "1154ff5dda14d5d45fa9797c14014cebf82e1025",
"ref": "refs/heads/main"
}
}
}
[03:24:27.732] Invocation finished in 3h48m49.628689213s with status SUCCEEDED