[02:02:48.154] New invocation is queued and will start shortly
[02:02:49.254] Starting the invocation (attempt 1)
[02:02:49.288] Popped buildset tag "buildset:commit/gitiles/pigweed.googlesource.com/pigweed/examples/+/a76ed6912e15d41f3c8592106baaeb2bc263d692"
[02:02:49.288] Popped gitiles commit info from properties and tags
[02:02:49.288] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[02:02:49.290] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[02:02:49.290] Buildbucket request:
{
"requestId": "8887793147250907824",
"builder": {
"project": "pigweed",
"bucket": "examples.dev.ci",
"builder": "examples-envtest-linux"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8887793147250907824",
"job": "pigweed/examples.dev.ci-examples-envtest-linux",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/examples",
"revision": "a76ed6912e15d41f3c8592106baaeb2bc263d692"
},
"id": "https://pigweed.googlesource.com/pigweed/examples/+/refs/heads/main@a76ed6912e15d41f3c8592106baaeb2bc263d692",
"title": "a76ed6912e15d41f3c8592106baaeb2bc263d692",
"url": "https://pigweed.googlesource.com/pigweed/examples/+/a76ed6912e15d41f3c8592106baaeb2bc263d692"
}
]
}
},
"gitilesCommit": {
"host": "pigweed.googlesource.com",
"project": "pigweed/examples",
"id": "a76ed6912e15d41f3c8592106baaeb2bc263d692",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8887793147250907824"
},
{
"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"
}
}
[02:02:49.670] Scheduled build:
{
"id": "8722363085329781649",
"builder": {
"project": "pigweed",
"bucket": "examples.dev.ci",
"builder": "examples-envtest-linux"
},
"createdBy": "project:pigweed",
"createTime": "2025-02-21T02:02:49.359044726Z",
"updateTime": "2025-02-21T02:02:49.359044726Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "pigweed.googlesource.com",
"project": "pigweed/examples",
"id": "a76ed6912e15d41f3c8592106baaeb2bc263d692",
"ref": "refs/heads/main"
}
},
"canary": true
}
[02:02:49.670] Task URL: https://cr-buildbucket.appspot.com/build/8722363085329781649
[02:02:49.671] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-linux:8887793147250907824:2:0) after 5m13s
[02:02:49.736] Received PubSub notification, asking Buildbucket for the build status
[02:02:49.763] Build status: SCHEDULED
[02:08:02.692] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-linux:8887793147250907824:2:0)
[02:08:02.713] Build status: SCHEDULED
[02:08:02.713] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-linux:8887793147250907824:4:0) after 5m14s
[02:13:16.728] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-linux:8887793147250907824:4:0)
[02:13:16.758] Build status: SCHEDULED
[02:13:16.758] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-linux:8887793147250907824:5:0) after 5m30s
[02:18:46.763] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-linux:8887793147250907824:5:0)
[02:18:46.797] Build status: SCHEDULED
[02:18:46.797] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-linux:8887793147250907824:6:0) after 5m35s
[02:24:21.820] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-linux:8887793147250907824:6:0)
[02:24:21.848] Build status: SCHEDULED
[02:24:21.848] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-linux:8887793147250907824:7:0) after 6m48s
[02:31:09.893] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-linux:8887793147250907824:7:0)
[02:31:09.938] Build status: SCHEDULED
[02:31:09.938] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-linux:8887793147250907824:8:0) after 2m2s
[02:33:11.991] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-linux:8887793147250907824:8:0)
[02:33:12.019] Build status: SCHEDULED
[02:33:12.019] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-linux:8887793147250907824:9:0) after 9m6s
[02:42:18.316] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-linux:8887793147250907824:9:0)
[02:42:18.370] Build status: SCHEDULED
[02:42:18.370] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-linux:8887793147250907824:10:0) after 5m22s
[02:47:40.396] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-linux:8887793147250907824:10:0)
[02:47:40.462] Build status: SCHEDULED
[02:47:40.462] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-linux:8887793147250907824:11:0) after 4m50s
[02:52:30.538] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-linux:8887793147250907824:11:0)
[02:52:30.587] Build status: SCHEDULED
[02:52:30.587] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-linux:8887793147250907824:12:0) after 2m56s
[02:55:26.605] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-linux:8887793147250907824:12:0)
[02:55:26.629] Build status: SCHEDULED
[02:55:26.629] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-linux:8887793147250907824:13:0) after 3m28s
[02:58:54.648] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-linux:8887793147250907824:13:0)
[02:58:54.673] Build status: SCHEDULED
[02:58:54.674] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-linux:8887793147250907824:14:0) after 8m11s
[03:07:05.697] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-linux:8887793147250907824:14:0)
[03:07:05.738] Build status: SCHEDULED
[03:07:05.738] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-linux:8887793147250907824:15:0) after 7m12s
[03:14:17.917] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-linux:8887793147250907824:15:0)
[03:14:17.932] Build status: SCHEDULED
[03:14:17.932] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-linux:8887793147250907824:16:0) after 1m51s
[03:16:09.080] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-linux:8887793147250907824:16:0)
[03:16:09.097] Build status: SCHEDULED
[03:16:09.097] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-linux:8887793147250907824:17:0) after 7m52s
[03:24:01.195] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-linux:8887793147250907824:17:0)
[03:24:01.220] Build status: SCHEDULED
[03:24:01.220] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-linux:8887793147250907824:18:0) after 7m20s
[03:31:21.444] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-linux:8887793147250907824:18:0)
[03:31:21.537] Build status: SCHEDULED
[03:31:21.537] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-linux:8887793147250907824:19:0) after 6m38s
[03:37:59.565] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-linux:8887793147250907824:19:0)
[03:37:59.602] Build status: SCHEDULED
[03:37:59.602] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-linux:8887793147250907824:20:0) after 5m4s
[03:43:03.815] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-linux:8887793147250907824:20:0)
[03:43:03.915] Build status: SCHEDULED
[03:43:03.915] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-linux:8887793147250907824:21:0) after 3m29s
[03:46:32.930] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-linux:8887793147250907824:21:0)
[03:46:32.962] Build status: SCHEDULED
[03:46:32.962] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-linux:8887793147250907824:22:0) after 9m41s
[03:56:14.002] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-linux:8887793147250907824:22:0)
[03:56:14.088] Build status: SCHEDULED
[03:56:14.088] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-linux:8887793147250907824:23:0) after 9m35s
[04:05:49.409] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-linux:8887793147250907824:23:0)
[04:05:49.425] Build status: SCHEDULED
[04:05:49.425] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-linux:8887793147250907824:24:0) after 1m33s
[04:07:22.761] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-linux:8887793147250907824:24:0)
[04:07:22.794] Build status: SCHEDULED
[04:07:22.794] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-linux:8887793147250907824:25:0) after 3m12s
[04:10:35.254] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-linux:8887793147250907824:25:0)
[04:10:35.299] Build status: SCHEDULED
[04:10:35.299] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-linux:8887793147250907824:26:0) after 5m34s
[04:16:09.514] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-linux:8887793147250907824:26:0)
[04:16:09.531] Build status: SCHEDULED
[04:16:09.531] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-linux:8887793147250907824:27:0) after 2m46s
[04:18:55.525] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-linux:8887793147250907824:27:0)
[04:18:55.551] Build status: SCHEDULED
[04:18:55.551] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-linux:8887793147250907824:28:0) after 9m27s
[04:28:22.927] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-linux:8887793147250907824:28:0)
[04:28:22.948] Build status: SCHEDULED
[04:28:22.948] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-linux:8887793147250907824:29:0) after 8m20s
[04:36:43.051] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-linux:8887793147250907824:29:0)
[04:36:43.075] Build status: SCHEDULED
[04:36:43.075] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-linux:8887793147250907824:30:0) after 8m41s
[04:45:24.186] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-linux:8887793147250907824:30:0)
[04:45:24.210] Build status: SCHEDULED
[04:45:24.210] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-linux:8887793147250907824:31:0) after 1m25s
[04:46:49.394] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-linux:8887793147250907824:31:0)
[04:46:49.413] Build status: SCHEDULED
[04:46:49.413] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-linux:8887793147250907824:32:0) after 2m49s
[04:49:38.502] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-linux:8887793147250907824:32:0)
[04:49:38.524] Build status: SCHEDULED
[04:49:38.524] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-linux:8887793147250907824:33:0) after 3m45s
[04:53:23.571] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-linux:8887793147250907824:33:0)
[04:53:23.589] Build status: SCHEDULED
[04:53:23.589] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-linux:8887793147250907824:34:0) after 3m4s
[04:56:28.006] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-linux:8887793147250907824:34:0)
[04:56:28.023] Build status: SCHEDULED
[04:56:28.023] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-linux:8887793147250907824:35:0) after 4m50s
[05:01:18.257] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-linux:8887793147250907824:35:0)
[05:01:18.301] Build status: SCHEDULED
[05:01:18.301] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-linux:8887793147250907824:36:0) after 7m15s
[05:08:33.421] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-linux:8887793147250907824:36:0)
[05:08:33.456] Build status: SCHEDULED
[05:08:33.456] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-linux:8887793147250907824:37:0) after 8m15s
[05:10:57.774] Received PubSub notification, asking Buildbucket for the build status
[05:10:57.793] Build status: STARTED
[05:15:39.100] Received PubSub notification, asking Buildbucket for the build status
[05:15:39.120] Build:
{
"id": "8722363085329781649",
"builder": {
"project": "pigweed",
"bucket": "examples.dev.ci",
"builder": "examples-envtest-linux"
},
"createdBy": "project:pigweed",
"createTime": "2025-02-21T02:02:49.359044726Z",
"startTime": "2025-02-21T05:10:57.586422998Z",
"endTime": "2025-02-21T05:15:38.822013381Z",
"updateTime": "2025-02-21T05:15:38.822013381Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "pigweed.googlesource.com",
"project": "pigweed/examples",
"id": "a76ed6912e15d41f3c8592106baaeb2bc263d692",
"ref": "refs/heads/main"
}
},
"canary": true
}
[05:15:39.120] Invocation finished in 3h12m50.976705645s with status SUCCEEDED