[00:12:56.139] New invocation is queued and will start shortly
[00:12:57.235] Starting the invocation (attempt 1)
[00:12:57.260] Popped buildset tag "buildset:commit/gitiles/pigweed.googlesource.com/pigweed/examples/+/14e567acfb8235b2974a94ea51a086f781c18c33"
[00:12:57.260] Popped gitiles commit info from properties and tags
[00:12:57.260] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[00:12:57.260] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[00:12:57.261] Buildbucket request:
{
"requestId": "8879646332502724928",
"builder": {
"project": "pigweed",
"bucket": "examples.dev.ci",
"builder": "examples-lintformat-full"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8879646332502724928",
"job": "pigweed/examples.dev.ci-examples-lintformat-full",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/examples",
"revision": "14e567acfb8235b2974a94ea51a086f781c18c33"
},
"id": "https://pigweed.googlesource.com/pigweed/examples/+/refs/heads/main@14e567acfb8235b2974a94ea51a086f781c18c33",
"title": "14e567acfb8235b2974a94ea51a086f781c18c33",
"url": "https://pigweed.googlesource.com/pigweed/examples/+/14e567acfb8235b2974a94ea51a086f781c18c33"
}
]
}
},
"gitilesCommit": {
"host": "pigweed.googlesource.com",
"project": "pigweed/examples",
"id": "14e567acfb8235b2974a94ea51a086f781c18c33",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8879646332502724928"
},
{
"key": "scheduler_job_id",
"value": "pigweed/examples.dev.ci-examples-lintformat-full"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[00:12:57.632] Scheduled build:
{
"id": "8714216270615210817",
"builder": {
"project": "pigweed",
"bucket": "examples.dev.ci",
"builder": "examples-lintformat-full"
},
"createdBy": "project:pigweed",
"createTime": "2025-05-22T00:12:57.312433094Z",
"updateTime": "2025-05-22T00:12:57.312433094Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "pigweed.googlesource.com",
"project": "pigweed/examples",
"id": "14e567acfb8235b2974a94ea51a086f781c18c33",
"ref": "refs/heads/main"
}
},
"canary": true
}
[00:12:57.632] Task URL: https://cr-buildbucket.appspot.com/build/8714216270615210817
[00:12:57.632] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-lintformat-full:8879646332502724928:2:0) after 2m37s
[00:12:58.725] Received PubSub notification, asking Buildbucket for the build status
[00:12:58.762] Build status: SCHEDULED
[00:15:34.719] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-lintformat-full:8879646332502724928:2:0)
[00:15:34.745] Build status: SCHEDULED
[00:15:34.745] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-lintformat-full:8879646332502724928:4:0) after 2m13s
[00:17:47.787] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-lintformat-full:8879646332502724928:4:0)
[00:17:47.818] Build status: SCHEDULED
[00:17:47.818] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-lintformat-full:8879646332502724928:5:0) after 5m31s
[00:23:18.861] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-lintformat-full:8879646332502724928:5:0)
[00:23:18.943] Build status: SCHEDULED
[00:23:18.943] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-lintformat-full:8879646332502724928:6:0) after 9m57s
[00:33:15.962] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-lintformat-full:8879646332502724928:6:0)
[00:33:16.005] Build status: SCHEDULED
[00:33:16.005] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-lintformat-full:8879646332502724928:7:0) after 5m44s
[00:39:00.026] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-lintformat-full:8879646332502724928:7:0)
[00:39:00.058] Build status: SCHEDULED
[00:39:00.058] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-lintformat-full:8879646332502724928:8:0) after 4m56s
[00:43:56.102] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-lintformat-full:8879646332502724928:8:0)
[00:43:59.796] Build status: SCHEDULED
[00:43:59.796] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-lintformat-full:8879646332502724928:9:0) after 3m0s
[00:46:59.814] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-lintformat-full:8879646332502724928:9:0)
[00:46:59.832] Build status: SCHEDULED
[00:46:59.832] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-lintformat-full:8879646332502724928:10:0) after 5m21s
[00:52:20.840] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-lintformat-full:8879646332502724928:10:0)
[00:52:20.867] Build status: SCHEDULED
[00:52:20.867] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-lintformat-full:8879646332502724928:11:0) after 2m12s
[00:54:32.898] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-lintformat-full:8879646332502724928:11:0)
[00:54:32.922] Build status: SCHEDULED
[00:54:32.922] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-lintformat-full:8879646332502724928:12:0) after 6m8s
[01:00:40.951] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-lintformat-full:8879646332502724928:12:0)
[01:00:40.974] Build status: SCHEDULED
[01:00:40.974] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-lintformat-full:8879646332502724928:13:0) after 4m19s
[01:05:00.077] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-lintformat-full:8879646332502724928:13:0)
[01:05:00.107] Build status: SCHEDULED
[01:05:00.107] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-lintformat-full:8879646332502724928:14:0) after 4m51s
[01:09:51.131] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-lintformat-full:8879646332502724928:14:0)
[01:09:51.164] Build status: SCHEDULED
[01:09:51.164] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-lintformat-full:8879646332502724928:15:0) after 8m13s
[01:18:04.184] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-lintformat-full:8879646332502724928:15:0)
[01:18:04.215] Build status: SCHEDULED
[01:18:04.215] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-lintformat-full:8879646332502724928:16:0) after 5m58s
[01:24:02.287] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-lintformat-full:8879646332502724928:16:0)
[01:24:02.339] Build status: SCHEDULED
[01:24:02.339] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-lintformat-full:8879646332502724928:17:0) after 2m15s
[01:26:17.365] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-lintformat-full:8879646332502724928:17:0)
[01:26:17.459] Build status: SCHEDULED
[01:26:17.459] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-lintformat-full:8879646332502724928:18:0) after 7m5s
[01:33:22.486] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-lintformat-full:8879646332502724928:18:0)
[01:33:22.506] Build status: SCHEDULED
[01:33:22.506] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-lintformat-full:8879646332502724928:19:0) after 5m14s
[01:38:36.504] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-lintformat-full:8879646332502724928:19:0)
[01:38:36.531] Build status: SCHEDULED
[01:38:36.531] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-lintformat-full:8879646332502724928:20:0) after 9m22s
[01:47:58.581] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-lintformat-full:8879646332502724928:20:0)
[01:47:58.610] Build status: SCHEDULED
[01:47:58.610] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-lintformat-full:8879646332502724928:21:0) after 9m26s
[01:57:24.707] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-lintformat-full:8879646332502724928:21:0)
[01:57:24.736] Build status: SCHEDULED
[01:57:24.736] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-lintformat-full:8879646332502724928:22:0) after 3m18s
[02:00:42.762] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-lintformat-full:8879646332502724928:22:0)
[02:00:42.782] Build status: SCHEDULED
[02:00:42.782] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-lintformat-full:8879646332502724928:23:0) after 7m49s
[02:08:31.800] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-lintformat-full:8879646332502724928:23:0)
[02:08:31.847] Build status: SCHEDULED
[02:08:31.848] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-lintformat-full:8879646332502724928:24:0) after 2m12s
[02:10:43.980] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-lintformat-full:8879646332502724928:24:0)
[02:10:44.006] Build status: SCHEDULED
[02:10:44.006] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-lintformat-full:8879646332502724928:25:0) after 1m20s
[02:12:04.083] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-lintformat-full:8879646332502724928:25:0)
[02:12:04.100] Build status: SCHEDULED
[02:12:04.100] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-lintformat-full:8879646332502724928:26:0) after 2m2s
[02:14:06.132] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-lintformat-full:8879646332502724928:26:0)
[02:14:06.150] Build status: SCHEDULED
[02:14:06.150] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-lintformat-full:8879646332502724928:27:0) after 4m0s
[02:18:06.174] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-lintformat-full:8879646332502724928:27:0)
[02:18:06.217] Build status: SCHEDULED
[02:18:06.217] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-lintformat-full:8879646332502724928:28:0) after 2m25s
[02:20:31.242] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-lintformat-full:8879646332502724928:28:0)
[02:20:31.276] Build status: SCHEDULED
[02:20:31.276] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-lintformat-full:8879646332502724928:29:0) after 6m15s
[02:26:46.441] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-lintformat-full:8879646332502724928:29:0)
[02:26:46.529] Build status: SCHEDULED
[02:26:46.529] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-lintformat-full:8879646332502724928:30:0) after 1m51s
[02:28:37.575] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-lintformat-full:8879646332502724928:30:0)
[02:28:37.598] Build status: SCHEDULED
[02:28:37.598] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-lintformat-full:8879646332502724928:31:0) after 9m4s
[02:37:41.623] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-lintformat-full:8879646332502724928:31:0)
[02:37:41.645] Build status: SCHEDULED
[02:37:41.645] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-lintformat-full:8879646332502724928:32:0) after 9m37s
[02:47:18.669] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-lintformat-full:8879646332502724928:32:0)
[02:47:18.689] Build status: SCHEDULED
[02:47:18.689] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-lintformat-full:8879646332502724928:33:0) after 6m41s
[02:53:59.708] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-lintformat-full:8879646332502724928:33:0)
[02:53:59.726] Build status: SCHEDULED
[02:53:59.726] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-lintformat-full:8879646332502724928:34:0) after 1m21s
[02:55:20.885] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-lintformat-full:8879646332502724928:34:0)
[02:55:20.931] Build status: SCHEDULED
[02:55:20.931] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-lintformat-full:8879646332502724928:35:0) after 7m7s
[03:02:28.072] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-lintformat-full:8879646332502724928:35:0)
[03:02:28.097] Build status: SCHEDULED
[03:02:28.097] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-lintformat-full:8879646332502724928:36:0) after 6m26s
[03:08:54.149] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-lintformat-full:8879646332502724928:36:0)
[03:08:54.183] Build status: SCHEDULED
[03:08:54.183] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-lintformat-full:8879646332502724928:37:0) after 9m12s
[03:11:24.497] Received PubSub notification, asking Buildbucket for the build status
[03:11:24.520] Build status: STARTED
[03:16:27.801] Received PubSub notification, asking Buildbucket for the build status
[03:16:27.829] Build:
{
"id": "8714216270615210817",
"builder": {
"project": "pigweed",
"bucket": "examples.dev.ci",
"builder": "examples-lintformat-full"
},
"createdBy": "project:pigweed",
"createTime": "2025-05-22T00:12:57.312433094Z",
"startTime": "2025-05-22T03:11:24.244738186Z",
"endTime": "2025-05-22T03:16:26.899744142Z",
"updateTime": "2025-05-22T03:16:26.899744142Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "pigweed.googlesource.com",
"project": "pigweed/examples",
"id": "14e567acfb8235b2974a94ea51a086f781c18c33",
"ref": "refs/heads/main"
}
},
"canary": true
}
[03:16:27.829] Invocation finished in 3h3m31.700264286s with status SUCCEEDED