[07:45:18.803] New invocation is queued and will start shortly
[07:45:20.009] Starting the invocation (attempt 1)
[07:45:20.034] Popped buildset tag "buildset:commit/gitiles/pigweed.googlesource.com/pigweed/examples/+/68c034b71d93281326f3c3771a0471056d67f5ab"
[07:45:20.034] Popped gitiles commit info from properties and tags
[07:45:20.034] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[07:45:20.034] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[07:45:20.034] Buildbucket request:
{
"requestId": "8882698168213791200",
"builder": {
"project": "pigweed",
"bucket": "examples.dev.ci",
"builder": "examples-envtest-linux"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8882698168213791200",
"job": "pigweed/examples.dev.ci-examples-envtest-linux",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/examples",
"revision": "68c034b71d93281326f3c3771a0471056d67f5ab"
},
"id": "https://pigweed.googlesource.com/pigweed/examples/+/refs/heads/main@68c034b71d93281326f3c3771a0471056d67f5ab",
"title": "68c034b71d93281326f3c3771a0471056d67f5ab",
"url": "https://pigweed.googlesource.com/pigweed/examples/+/68c034b71d93281326f3c3771a0471056d67f5ab"
}
]
}
},
"gitilesCommit": {
"host": "pigweed.googlesource.com",
"project": "pigweed/examples",
"id": "68c034b71d93281326f3c3771a0471056d67f5ab",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8882698168213791200"
},
{
"key": "scheduler_job_id",
"value": "pigweed/examples.dev.ci-examples-envtest-linux"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[07:45:20.452] Scheduled build:
{
"id": "8717268106213237873",
"builder": {
"project": "pigweed",
"bucket": "examples.dev.ci",
"builder": "examples-envtest-linux"
},
"createdBy": "project:pigweed",
"createTime": "2025-04-18T07:45:20.085442680Z",
"updateTime": "2025-04-18T07:45:20.085442680Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "pigweed.googlesource.com",
"project": "pigweed/examples",
"id": "68c034b71d93281326f3c3771a0471056d67f5ab",
"ref": "refs/heads/main"
}
}
}
[07:45:20.452] Task URL: https://cr-buildbucket.appspot.com/build/8717268106213237873
[07:45:20.452] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-linux:8882698168213791200:2:0) after 4m48s
[07:45:21.452] Received PubSub notification, asking Buildbucket for the build status
[07:45:21.482] Build status: SCHEDULED
[07:50:08.479] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-linux:8882698168213791200:2:0)
[07:50:08.509] Build status: SCHEDULED
[07:50:08.509] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-linux:8882698168213791200:4:0) after 3m27s
[07:53:35.787] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-linux:8882698168213791200:4:0)
[07:53:36.059] Build status: SCHEDULED
[07:53:36.059] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-linux:8882698168213791200:5:0) after 1m56s
[07:55:32.206] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-linux:8882698168213791200:5:0)
[07:55:32.232] Build status: SCHEDULED
[07:55:32.232] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-linux:8882698168213791200:6:0) after 9m11s
[08:04:43.250] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-linux:8882698168213791200:6:0)
[08:04:43.284] Build status: SCHEDULED
[08:04:43.284] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-linux:8882698168213791200:7:0) after 1m25s
[08:06:08.305] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-linux:8882698168213791200:7:0)
[08:06:08.338] Build status: SCHEDULED
[08:06:08.338] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-linux:8882698168213791200:8:0) after 4m45s
[08:10:53.360] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-linux:8882698168213791200:8:0)
[08:10:53.379] Build status: SCHEDULED
[08:10:53.379] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-linux:8882698168213791200:9:0) after 3m35s
[08:14:28.403] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-linux:8882698168213791200:9:0)
[08:14:28.429] Build status: SCHEDULED
[08:14:28.430] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-linux:8882698168213791200:10:0) after 7m5s
[08:21:33.474] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-linux:8882698168213791200:10:0)
[08:21:33.579] Build status: SCHEDULED
[08:21:33.579] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-linux:8882698168213791200:11:0) after 6m43s
[08:28:16.645] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-linux:8882698168213791200:11:0)
[08:28:16.678] Build status: SCHEDULED
[08:28:16.678] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-linux:8882698168213791200:12:0) after 9m23s
[08:37:39.775] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-linux:8882698168213791200:12:0)
[08:37:39.795] Build status: SCHEDULED
[08:37:39.796] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-linux:8882698168213791200:13:0) after 1m26s
[08:39:05.866] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-linux:8882698168213791200:13:0)
[08:39:05.895] Build status: SCHEDULED
[08:39:05.895] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-linux:8882698168213791200:14:0) after 4m48s
[08:43:54.318] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-linux:8882698168213791200:14:0)
[08:43:54.343] Build status: SCHEDULED
[08:43:54.343] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-linux:8882698168213791200:15:0) after 9m33s
[08:53:27.362] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-linux:8882698168213791200:15:0)
[08:53:27.378] Build status: SCHEDULED
[08:53:27.378] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-linux:8882698168213791200:16:0) after 5m33s
[08:59:00.400] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-linux:8882698168213791200:16:0)
[08:59:00.419] Build status: SCHEDULED
[08:59:00.419] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-linux:8882698168213791200:17:0) after 5m21s
[09:04:21.628] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-linux:8882698168213791200:17:0)
[09:04:21.656] Build status: SCHEDULED
[09:04:21.656] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-linux:8882698168213791200:18:0) after 6m48s
[09:11:09.780] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-linux:8882698168213791200:18:0)
[09:11:09.817] Build status: SCHEDULED
[09:11:09.817] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-linux:8882698168213791200:19:0) after 1m55s
[09:13:04.853] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-linux:8882698168213791200:19:0)
[09:13:04.892] Build status: SCHEDULED
[09:13:04.892] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-linux:8882698168213791200:20:0) after 1m43s
[09:14:48.078] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-linux:8882698168213791200:20:0)
[09:14:48.125] Build status: SCHEDULED
[09:14:48.125] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-linux:8882698168213791200:21:0) after 4m12s
[09:19:00.352] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-linux:8882698168213791200:21:0)
[09:19:00.371] Build status: SCHEDULED
[09:19:00.371] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-linux:8882698168213791200:22:0) after 4m32s
[09:23:32.497] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-linux:8882698168213791200:22:0)
[09:23:32.513] Build status: SCHEDULED
[09:23:32.513] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-linux:8882698168213791200:23:0) after 6m51s
[09:30:23.531] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-linux:8882698168213791200:23:0)
[09:30:23.549] Build status: SCHEDULED
[09:30:23.549] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-linux:8882698168213791200:24:0) after 1m55s
[09:32:18.544] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-linux:8882698168213791200:24:0)
[09:32:18.575] Build status: SCHEDULED
[09:32:18.575] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-linux:8882698168213791200:25:0) after 2m53s
[09:35:11.678] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-linux:8882698168213791200:25:0)
[09:35:11.696] Build status: SCHEDULED
[09:35:11.696] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-linux:8882698168213791200:26:0) after 5m10s
[09:40:22.045] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-linux:8882698168213791200:26:0)
[09:40:22.067] Build status: SCHEDULED
[09:40:22.067] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-linux:8882698168213791200:27:0) after 5m9s
[09:45:31.139] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-linux:8882698168213791200:27:0)
[09:45:31.159] Build status: SCHEDULED
[09:45:31.159] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-linux:8882698168213791200:28:0) after 6m28s
[09:51:59.176] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-linux:8882698168213791200:28:0)
[09:51:59.199] Build status: SCHEDULED
[09:51:59.199] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-linux:8882698168213791200:29:0) after 3m55s
[09:55:54.221] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-linux:8882698168213791200:29:0)
[09:55:54.270] Build status: SCHEDULED
[09:55:54.270] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-linux:8882698168213791200:30:0) after 4m53s
[10:00:47.339] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-linux:8882698168213791200:30:0)
[10:00:47.369] Build status: SCHEDULED
[10:00:47.370] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-linux:8882698168213791200:31:0) after 9m59s
[10:10:46.438] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-linux:8882698168213791200:31:0)
[10:10:46.460] Build status: SCHEDULED
[10:10:46.460] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-linux:8882698168213791200:32:0) after 8m20s
[10:19:06.896] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-linux:8882698168213791200:32:0)
[10:19:06.923] Build status: SCHEDULED
[10:19:06.923] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-linux:8882698168213791200:33:0) after 5m34s
[10:24:41.230] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-linux:8882698168213791200:33:0)
[10:24:41.284] Build status: SCHEDULED
[10:24:41.285] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-linux:8882698168213791200:34:0) after 8m2s
[10:32:43.307] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-linux:8882698168213791200:34:0)
[10:32:43.335] Build status: SCHEDULED
[10:32:43.336] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-linux:8882698168213791200:35:0) after 9m14s
[10:41:57.356] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-linux:8882698168213791200:35:0)
[10:41:57.388] Build status: SCHEDULED
[10:41:57.388] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-linux:8882698168213791200:36:0) after 6m9s
[10:48:06.414] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-linux:8882698168213791200:36:0)
[10:48:06.440] Build status: SCHEDULED
[10:48:06.440] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-linux:8882698168213791200:37:0) after 7m50s
[10:55:56.460] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-linux:8882698168213791200:37:0)
[10:55:56.475] Build status: SCHEDULED
[10:55:56.475] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-linux:8882698168213791200:38:0) after 8m38s
[11:04:34.496] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-linux:8882698168213791200:38:0)
[11:04:34.587] Build status: SCHEDULED
[11:04:34.587] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-linux:8882698168213791200:39:0) after 4m25s
[11:08:59.823] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-linux:8882698168213791200:39:0)
[11:08:59.845] Build status: SCHEDULED
[11:08:59.845] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-linux:8882698168213791200:40:0) after 8m46s
[11:16:39.056] Received PubSub notification, asking Buildbucket for the build status
[11:16:39.084] Build status: STARTED
[11:17:45.965] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-linux:8882698168213791200:40:0)
[11:17:45.995] Build status: STARTED
[11:17:45.995] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-linux:8882698168213791200:42:0) after 5m13s
[11:21:21.939] Received PubSub notification, asking Buildbucket for the build status
[11:21:21.972] Build:
{
"id": "8717268106213237873",
"builder": {
"project": "pigweed",
"bucket": "examples.dev.ci",
"builder": "examples-envtest-linux"
},
"createdBy": "project:pigweed",
"createTime": "2025-04-18T07:45:20.085442680Z",
"startTime": "2025-04-18T11:16:37.775946740Z",
"endTime": "2025-04-18T11:21:21.433197771Z",
"updateTime": "2025-04-18T11:21:21.433197771Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "pigweed.googlesource.com",
"project": "pigweed/examples",
"id": "68c034b71d93281326f3c3771a0471056d67f5ab",
"ref": "refs/heads/main"
}
}
}
[11:21:21.972] Invocation finished in 3h36m3.178879809s with status SUCCEEDED