[07:28:44.288] New invocation is queued and will start shortly
[07:28:44.288] Triggered by user:rerunner@pigweed-service-accounts.iam.gserviceaccount.com
[07:28:45.419] Starting the invocation (attempt 1)
[07:28:45.441] Ignoring gitiles_ref tag without the buildset tag
[07:28:45.441] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[07:28:45.441] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[07:28:45.441] Buildbucket request:
{
"requestId": "8868022502488065248",
"builder": {
"project": "pigweed",
"bucket": "experimental.dev.ci",
"builder": "experimental-envtest"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8868022502488065248",
"job": "pigweed/experimental.dev.ci-experimental-envtest",
"triggers": [
{
"buildbucket": {
"tags": [
"parent_buildername:rerunner",
"user_agent:rerunner"
]
},
"id": "0c0375f9-23ea-4cee-b5f8-c496b75f1faa",
"title": "rerunner/0",
"url": "https://cr-buildbucket.appspot.com/build/8702592622926920065"
}
]
}
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8868022502488065248"
},
{
"key": "scheduler_job_id",
"value": "pigweed/experimental.dev.ci-experimental-envtest"
},
{
"key": "user_agent",
"value": "luci-scheduler"
},
{
"key": "parent_buildername",
"value": "rerunner"
},
{
"key": "user_agent",
"value": "rerunner"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[07:28:45.818] Scheduled build:
{
"id": "8702592440524836433",
"builder": {
"project": "pigweed",
"bucket": "experimental.dev.ci",
"builder": "experimental-envtest"
},
"createdBy": "project:pigweed",
"createTime": "2025-09-27T07:28:45.528820277Z",
"updateTime": "2025-09-27T07:28:45.528820277Z",
"status": "SCHEDULED",
"input": {
}
}
[07:28:45.818] Task URL: https://cr-buildbucket.appspot.com/build/8702592440524836433
[07:28:45.818] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-envtest:8868022502488065248:2:0) after 3m25s
[07:28:46.774] Received PubSub notification, asking Buildbucket for the build status
[07:28:46.789] Build status: SCHEDULED
[07:32:11.030] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-envtest:8868022502488065248:2:0)
[07:32:11.059] Build status: SCHEDULED
[07:32:11.060] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-envtest:8868022502488065248:4:0) after 2m13s
[07:34:24.405] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-envtest:8868022502488065248:4:0)
[07:34:24.465] Build status: SCHEDULED
[07:34:24.465] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-envtest:8868022502488065248:5:0) after 4m39s
[07:39:03.764] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-envtest:8868022502488065248:5:0)
[07:39:03.789] Build status: SCHEDULED
[07:39:03.789] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-envtest:8868022502488065248:6:0) after 2m15s
[07:41:18.803] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-envtest:8868022502488065248:6:0)
[07:41:18.824] Build status: SCHEDULED
[07:41:18.825] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-envtest:8868022502488065248:7:0) after 1m57s
[07:43:15.961] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-envtest:8868022502488065248:7:0)
[07:43:15.991] Build status: SCHEDULED
[07:43:15.991] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-envtest:8868022502488065248:8:0) after 9m22s
[07:52:38.162] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-envtest:8868022502488065248:8:0)
[07:52:38.225] Build status: SCHEDULED
[07:52:38.225] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-envtest:8868022502488065248:9:0) after 3m1s
[07:55:39.433] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-envtest:8868022502488065248:9:0)
[07:55:39.614] Build status: SCHEDULED
[07:55:39.614] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-envtest:8868022502488065248:10:0) after 7m53s
[08:03:32.629] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-envtest:8868022502488065248:10:0)
[08:03:32.658] Build status: SCHEDULED
[08:03:32.659] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-envtest:8868022502488065248:11:0) after 6m14s
[08:09:46.785] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-envtest:8868022502488065248:11:0)
[08:09:46.810] Build status: SCHEDULED
[08:09:46.810] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-envtest:8868022502488065248:12:0) after 2m25s
[08:12:11.823] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-envtest:8868022502488065248:12:0)
[08:12:11.975] Build status: SCHEDULED
[08:12:11.975] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-envtest:8868022502488065248:13:0) after 1m11s
[08:13:23.258] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-envtest:8868022502488065248:13:0)
[08:13:23.299] Build status: SCHEDULED
[08:13:23.299] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-envtest:8868022502488065248:14:0) after 4m3s
[08:17:26.430] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-envtest:8868022502488065248:14:0)
[08:17:26.448] Build status: SCHEDULED
[08:17:26.448] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-envtest:8868022502488065248:15:0) after 9m11s
[08:26:37.637] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-envtest:8868022502488065248:15:0)
[08:26:37.654] Build status: SCHEDULED
[08:26:37.654] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-envtest:8868022502488065248:16:0) after 4m43s
[08:31:21.006] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-envtest:8868022502488065248:16:0)
[08:31:21.027] Build status: SCHEDULED
[08:31:21.027] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-envtest:8868022502488065248:17:0) after 1m6s
[08:32:27.157] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-envtest:8868022502488065248:17:0)
[08:32:27.176] Build status: SCHEDULED
[08:32:27.176] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-envtest:8868022502488065248:18:0) after 4m1s
[08:36:28.189] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-envtest:8868022502488065248:18:0)
[08:36:28.207] Build status: SCHEDULED
[08:36:28.207] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-envtest:8868022502488065248:19:0) after 9m2s
[08:45:30.222] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-envtest:8868022502488065248:19:0)
[08:45:30.243] Build status: SCHEDULED
[08:45:30.243] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-envtest:8868022502488065248:20:0) after 5m34s
[08:51:04.353] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-envtest:8868022502488065248:20:0)
[08:51:04.374] Build status: SCHEDULED
[08:51:04.374] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-envtest:8868022502488065248:21:0) after 1m7s
[08:52:11.580] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-envtest:8868022502488065248:21:0)
[08:52:11.598] Build status: SCHEDULED
[08:52:11.598] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-envtest:8868022502488065248:22:0) after 6m27s
[08:58:38.701] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-envtest:8868022502488065248:22:0)
[08:58:38.726] Build status: SCHEDULED
[08:58:38.727] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-envtest:8868022502488065248:23:0) after 2m24s
[08:58:57.203] Received PubSub notification, asking Buildbucket for the build status
[08:58:57.225] Build status: STARTED
[09:01:02.868] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-envtest:8868022502488065248:23:0)
[09:01:02.888] Build status: STARTED
[09:01:02.888] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-envtest:8868022502488065248:25:0) after 1m37s
[09:02:39.997] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-envtest:8868022502488065248:25:0)
[09:02:40.023] Build status: STARTED
[09:02:40.023] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-envtest:8868022502488065248:26:0) after 9m55s
[09:03:24.767] Received PubSub notification, asking Buildbucket for the build status
[09:03:24.804] Build:
{
"id": "8702592440524836433",
"builder": {
"project": "pigweed",
"bucket": "experimental.dev.ci",
"builder": "experimental-envtest"
},
"createdBy": "project:pigweed",
"createTime": "2025-09-27T07:28:45.528820277Z",
"startTime": "2025-09-27T08:58:56.962887595Z",
"endTime": "2025-09-27T09:03:24.514127930Z",
"updateTime": "2025-09-27T09:03:24.514127930Z",
"status": "SUCCESS",
"input": {
}
}
[09:03:24.804] Invocation finished in 1h34m40.531731088s with status SUCCEEDED