[07:54:42.932] New invocation is queued and will start shortly
[07:54:44.010] Starting the invocation (attempt 1)
[07:54:44.043] Popped buildset tag "buildset:commit/gitiles/pigweed.googlesource.com/pigweed/examples/+/e9f2c907ddfc5076f2719454cb1b30b19c657600"
[07:54:44.043] Popped gitiles commit info from properties and tags
[07:54:44.043] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[07:54:44.043] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[07:54:44.043] Buildbucket request:
{
"requestId": "8880160861626860096",
"builder": {
"project": "pigweed",
"bucket": "examples.dev.ci",
"builder": "examples-bazel-linux"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8880160861626860096",
"job": "pigweed/examples.dev.ci-examples-bazel-linux",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/examples",
"revision": "e9f2c907ddfc5076f2719454cb1b30b19c657600"
},
"id": "https://pigweed.googlesource.com/pigweed/examples/+/refs/heads/main@e9f2c907ddfc5076f2719454cb1b30b19c657600",
"title": "e9f2c907ddfc5076f2719454cb1b30b19c657600",
"url": "https://pigweed.googlesource.com/pigweed/examples/+/e9f2c907ddfc5076f2719454cb1b30b19c657600"
}
]
}
},
"gitilesCommit": {
"host": "pigweed.googlesource.com",
"project": "pigweed/examples",
"id": "e9f2c907ddfc5076f2719454cb1b30b19c657600",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8880160861626860096"
},
{
"key": "scheduler_job_id",
"value": "pigweed/examples.dev.ci-examples-bazel-linux"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[07:54:44.408] Scheduled build:
{
"id": "8714730799740035953",
"builder": {
"project": "pigweed",
"bucket": "examples.dev.ci",
"builder": "examples-bazel-linux"
},
"createdBy": "project:pigweed",
"createTime": "2025-05-16T07:54:44.101982453Z",
"updateTime": "2025-05-16T07:54:44.101982453Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "pigweed.googlesource.com",
"project": "pigweed/examples",
"id": "e9f2c907ddfc5076f2719454cb1b30b19c657600",
"ref": "refs/heads/main"
}
}
}
[07:54:44.408] Task URL: https://cr-buildbucket.appspot.com/build/8714730799740035953
[07:54:44.408] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-bazel-linux:8880160861626860096:2:0) after 1m49s
[07:54:45.411] Received PubSub notification, asking Buildbucket for the build status
[07:54:45.453] Build status: SCHEDULED
[07:56:33.515] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-bazel-linux:8880160861626860096:2:0)
[07:56:33.541] Build status: SCHEDULED
[07:56:33.541] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-bazel-linux:8880160861626860096:4:0) after 3m44s
[08:00:17.560] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-bazel-linux:8880160861626860096:4:0)
[08:00:17.585] Build status: SCHEDULED
[08:00:17.585] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-bazel-linux:8880160861626860096:5:0) after 3m18s
[08:03:35.576] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-bazel-linux:8880160861626860096:5:0)
[08:03:35.609] Build status: SCHEDULED
[08:03:35.610] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-bazel-linux:8880160861626860096:6:0) after 7m9s
[08:10:44.627] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-bazel-linux:8880160861626860096:6:0)
[08:10:44.740] Build status: SCHEDULED
[08:10:44.740] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-bazel-linux:8880160861626860096:7:0) after 1m3s
[08:11:47.751] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-bazel-linux:8880160861626860096:7:0)
[08:11:47.791] Build status: SCHEDULED
[08:11:47.791] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-bazel-linux:8880160861626860096:8:0) after 4m16s
[08:16:03.814] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-bazel-linux:8880160861626860096:8:0)
[08:16:03.843] Build status: SCHEDULED
[08:16:03.843] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-bazel-linux:8880160861626860096:9:0) after 5m42s
[08:21:45.875] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-bazel-linux:8880160861626860096:9:0)
[08:21:45.900] Build status: SCHEDULED
[08:21:45.900] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-bazel-linux:8880160861626860096:10:0) after 7m54s
[08:29:39.916] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-bazel-linux:8880160861626860096:10:0)
[08:29:39.957] Build status: SCHEDULED
[08:29:39.957] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-bazel-linux:8880160861626860096:11:0) after 6m10s
[08:35:49.948] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-bazel-linux:8880160861626860096:11:0)
[08:35:49.974] Build status: SCHEDULED
[08:35:49.974] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-bazel-linux:8880160861626860096:12:0) after 8m53s
[08:44:43.148] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-bazel-linux:8880160861626860096:12:0)
[08:44:43.182] Build status: SCHEDULED
[08:44:43.182] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-bazel-linux:8880160861626860096:13:0) after 8m38s
[08:53:21.251] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-bazel-linux:8880160861626860096:13:0)
[08:53:21.268] Build status: SCHEDULED
[08:53:21.268] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-bazel-linux:8880160861626860096:14:0) after 5m12s
[08:58:33.404] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-bazel-linux:8880160861626860096:14:0)
[08:58:33.441] Build status: SCHEDULED
[08:58:33.441] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-bazel-linux:8880160861626860096:15:0) after 2m20s
[09:00:53.462] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-bazel-linux:8880160861626860096:15:0)
[09:00:53.493] Build status: SCHEDULED
[09:00:53.493] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-bazel-linux:8880160861626860096:16:0) after 4m52s
[09:05:45.609] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-bazel-linux:8880160861626860096:16:0)
[09:05:45.652] Build status: SCHEDULED
[09:05:45.652] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-bazel-linux:8880160861626860096:17:0) after 5m15s
[09:11:00.755] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-bazel-linux:8880160861626860096:17:0)
[09:11:00.772] Build status: SCHEDULED
[09:11:00.772] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-bazel-linux:8880160861626860096:18:0) after 3m49s
[09:14:49.996] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-bazel-linux:8880160861626860096:18:0)
[09:14:50.027] Build status: SCHEDULED
[09:14:50.027] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-bazel-linux:8880160861626860096:19:0) after 9m18s
[09:24:08.520] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-bazel-linux:8880160861626860096:19:0)
[09:24:08.537] Build status: SCHEDULED
[09:24:08.537] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-bazel-linux:8880160861626860096:20:0) after 4m9s
[09:28:17.597] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-bazel-linux:8880160861626860096:20:0)
[09:28:17.640] Build status: SCHEDULED
[09:28:17.640] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-bazel-linux:8880160861626860096:21:0) after 9m5s
[09:37:23.600] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-bazel-linux:8880160861626860096:21:0)
[09:37:23.628] Build status: SCHEDULED
[09:37:23.628] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-bazel-linux:8880160861626860096:22:0) after 2m7s
[09:39:30.786] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-bazel-linux:8880160861626860096:22:0)
[09:39:30.814] Build status: SCHEDULED
[09:39:30.814] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-bazel-linux:8880160861626860096:23:0) after 3m32s
[09:43:03.209] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-bazel-linux:8880160861626860096:23:0)
[09:43:03.233] Build status: SCHEDULED
[09:43:03.233] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-bazel-linux:8880160861626860096:24:0) after 9m21s
[09:52:24.239] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-bazel-linux:8880160861626860096:24:0)
[09:52:24.254] Build status: SCHEDULED
[09:52:24.254] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-bazel-linux:8880160861626860096:25:0) after 1m14s
[09:53:38.262] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-bazel-linux:8880160861626860096:25:0)
[09:53:38.285] Build status: SCHEDULED
[09:53:38.285] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-bazel-linux:8880160861626860096:26:0) after 1m8s
[09:54:46.338] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-bazel-linux:8880160861626860096:26:0)
[09:54:46.359] Build status: SCHEDULED
[09:54:46.359] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-bazel-linux:8880160861626860096:27:0) after 8m26s
[10:03:12.736] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-bazel-linux:8880160861626860096:27:0)
[10:03:12.779] Build status: SCHEDULED
[10:03:12.779] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-bazel-linux:8880160861626860096:28:0) after 2m17s
[10:05:29.914] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-bazel-linux:8880160861626860096:28:0)
[10:05:29.961] Build status: SCHEDULED
[10:05:29.961] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-bazel-linux:8880160861626860096:29:0) after 1m36s
[10:07:06.012] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-bazel-linux:8880160861626860096:29:0)
[10:07:06.038] Build status: SCHEDULED
[10:07:06.038] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-bazel-linux:8880160861626860096:30:0) after 8m58s
[10:16:04.213] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-bazel-linux:8880160861626860096:30:0)
[10:16:04.237] Build status: SCHEDULED
[10:16:04.237] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-bazel-linux:8880160861626860096:31:0) after 7m32s
[10:23:36.254] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-bazel-linux:8880160861626860096:31:0)
[10:23:36.320] Build status: SCHEDULED
[10:23:36.320] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-bazel-linux:8880160861626860096:32:0) after 6m48s
[10:30:24.339] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-bazel-linux:8880160861626860096:32:0)
[10:30:24.371] Build status: SCHEDULED
[10:30:24.372] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-bazel-linux:8880160861626860096:33:0) after 4m27s
[10:34:51.586] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-bazel-linux:8880160861626860096:33:0)
[10:34:51.605] Build status: SCHEDULED
[10:34:51.605] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-bazel-linux:8880160861626860096:34:0) after 8m16s
[10:43:07.622] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-bazel-linux:8880160861626860096:34:0)
[10:43:07.662] Build status: SCHEDULED
[10:43:07.662] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-bazel-linux:8880160861626860096:35:0) after 1m24s
[10:44:31.714] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-bazel-linux:8880160861626860096:35:0)
[10:44:31.734] Build status: SCHEDULED
[10:44:31.734] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-bazel-linux:8880160861626860096:36:0) after 8m6s
[10:52:37.735] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-bazel-linux:8880160861626860096:36:0)
[10:52:37.809] Build status: SCHEDULED
[10:52:37.809] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-bazel-linux:8880160861626860096:37:0) after 3m30s
[10:56:07.881] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-bazel-linux:8880160861626860096:37:0)
[10:56:07.913] Build status: SCHEDULED
[10:56:07.913] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-bazel-linux:8880160861626860096:38:0) after 4m43s
[11:00:50.934] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-bazel-linux:8880160861626860096:38:0)
[11:00:50.957] Build status: SCHEDULED
[11:00:50.957] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-bazel-linux:8880160861626860096:39:0) after 2m3s
[11:02:21.626] Received PubSub notification, asking Buildbucket for the build status
[11:02:21.648] Build status: STARTED
[11:02:53.977] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-bazel-linux:8880160861626860096:39:0)
[11:02:54.026] Build status: STARTED
[11:02:54.026] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-bazel-linux:8880160861626860096:41:0) after 5m38s
[11:06:04.163] Received PubSub notification, asking Buildbucket for the build status
[11:06:04.180] Build:
{
"id": "8714730799740035953",
"builder": {
"project": "pigweed",
"bucket": "examples.dev.ci",
"builder": "examples-bazel-linux"
},
"createdBy": "project:pigweed",
"createTime": "2025-05-16T07:54:44.101982453Z",
"startTime": "2025-05-16T11:02:21.345376029Z",
"endTime": "2025-05-16T11:06:03.859794652Z",
"updateTime": "2025-05-16T11:06:03.859794652Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "pigweed.googlesource.com",
"project": "pigweed/examples",
"id": "e9f2c907ddfc5076f2719454cb1b30b19c657600",
"ref": "refs/heads/main"
}
}
}
[11:06:04.180] Invocation finished in 3h11m21.261873128s with status SUCCEEDED