[01:07:21.491] New invocation is queued and will start shortly
[01:07:22.741] Starting the invocation (attempt 1)
[01:07:22.767] Popped buildset tag "buildset:commit/gitiles/pigweed.googlesource.com/pigweed/experimental/+/c5fc0fa27bb61e717048fafaf49a174943750d2c"
[01:07:22.767] Popped gitiles commit info from properties and tags
[01:07:22.768] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[01:07:22.768] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[01:07:22.768] Buildbucket request:
{
"requestId": "8865781572669235040",
"builder": {
"project": "pigweed",
"bucket": "experimental.dev.ci",
"builder": "experimental-lintformat-full"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8865781572669235040",
"job": "pigweed/experimental.dev.ci-experimental-lintformat-full",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/experimental",
"revision": "c5fc0fa27bb61e717048fafaf49a174943750d2c"
},
"id": "https://pigweed.googlesource.com/pigweed/experimental/+/refs/heads/main@c5fc0fa27bb61e717048fafaf49a174943750d2c",
"title": "c5fc0fa27bb61e717048fafaf49a174943750d2c",
"url": "https://pigweed.googlesource.com/pigweed/experimental/+/c5fc0fa27bb61e717048fafaf49a174943750d2c"
}
]
}
},
"gitilesCommit": {
"host": "pigweed.googlesource.com",
"project": "pigweed/experimental",
"id": "c5fc0fa27bb61e717048fafaf49a174943750d2c",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8865781572669235040"
},
{
"key": "scheduler_job_id",
"value": "pigweed/experimental.dev.ci-experimental-lintformat-full"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[01:07:23.199] Scheduled build:
{
"id": "8700351510624480273",
"builder": {
"project": "pigweed",
"bucket": "experimental.dev.ci",
"builder": "experimental-lintformat-full"
},
"createdBy": "project:pigweed",
"createTime": "2025-10-22T01:07:22.819268801Z",
"updateTime": "2025-10-22T01:07:22.819268801Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "pigweed.googlesource.com",
"project": "pigweed/experimental",
"id": "c5fc0fa27bb61e717048fafaf49a174943750d2c",
"ref": "refs/heads/main"
}
},
"canary": true
}
[01:07:23.200] Task URL: https://cr-buildbucket.appspot.com/build/8700351510624480273
[01:07:23.200] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-lintformat-full:8865781572669235040:2:0) after 6m7s
[01:07:23.261] Received PubSub notification, asking Buildbucket for the build status
[01:07:23.277] Build status: SCHEDULED
[01:13:30.215] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-lintformat-full:8865781572669235040:2:0)
[01:13:30.251] Build status: SCHEDULED
[01:13:30.251] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-lintformat-full:8865781572669235040:4:0) after 5m7s
[01:18:37.263] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-lintformat-full:8865781572669235040:4:0)
[01:18:37.282] Build status: SCHEDULED
[01:18:37.282] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-lintformat-full:8865781572669235040:5:0) after 6m6s
[01:24:43.327] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-lintformat-full:8865781572669235040:5:0)
[01:24:43.349] Build status: SCHEDULED
[01:24:43.349] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-lintformat-full:8865781572669235040:6:0) after 1m50s
[01:26:33.365] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-lintformat-full:8865781572669235040:6:0)
[01:26:33.388] Build status: SCHEDULED
[01:26:33.388] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-lintformat-full:8865781572669235040:7:0) after 3m18s
[01:29:51.500] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-lintformat-full:8865781572669235040:7:0)
[01:29:51.539] Build status: SCHEDULED
[01:29:51.539] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-lintformat-full:8865781572669235040:8:0) after 8m9s
[01:38:00.554] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-lintformat-full:8865781572669235040:8:0)
[01:38:00.584] Build status: SCHEDULED
[01:38:00.584] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-lintformat-full:8865781572669235040:9:0) after 7m50s
[01:45:50.553] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-lintformat-full:8865781572669235040:9:0)
[01:45:50.575] Build status: SCHEDULED
[01:45:50.575] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-lintformat-full:8865781572669235040:10:0) after 7m24s
[01:53:14.638] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-lintformat-full:8865781572669235040:10:0)
[01:53:14.658] Build status: SCHEDULED
[01:53:14.659] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-lintformat-full:8865781572669235040:11:0) after 6m57s
[02:00:11.689] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-lintformat-full:8865781572669235040:11:0)
[02:00:11.708] Build status: SCHEDULED
[02:00:11.708] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-lintformat-full:8865781572669235040:12:0) after 3m46s
[02:03:57.722] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-lintformat-full:8865781572669235040:12:0)
[02:03:57.737] Build status: SCHEDULED
[02:03:57.737] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-lintformat-full:8865781572669235040:13:0) after 7m34s
[02:11:31.747] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-lintformat-full:8865781572669235040:13:0)
[02:11:31.764] Build status: SCHEDULED
[02:11:31.764] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-lintformat-full:8865781572669235040:14:0) after 9m52s
[02:21:23.813] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-lintformat-full:8865781572669235040:14:0)
[02:21:23.829] Build status: SCHEDULED
[02:21:23.829] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-lintformat-full:8865781572669235040:15:0) after 9m1s
[02:30:24.846] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-lintformat-full:8865781572669235040:15:0)
[02:30:24.866] Build status: SCHEDULED
[02:30:24.866] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-lintformat-full:8865781572669235040:16:0) after 4m38s
[02:35:02.905] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-lintformat-full:8865781572669235040:16:0)
[02:35:02.934] Build status: SCHEDULED
[02:35:02.934] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-lintformat-full:8865781572669235040:17:0) after 2m8s
[02:37:10.906] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-lintformat-full:8865781572669235040:17:0)
[02:37:10.921] Build status: SCHEDULED
[02:37:10.921] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-lintformat-full:8865781572669235040:18:0) after 1m40s
[02:38:50.936] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-lintformat-full:8865781572669235040:18:0)
[02:38:50.951] Build status: SCHEDULED
[02:38:50.951] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-lintformat-full:8865781572669235040:19:0) after 3m46s
[02:42:37.017] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-lintformat-full:8865781572669235040:19:0)
[02:42:37.054] Build status: SCHEDULED
[02:42:37.054] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-lintformat-full:8865781572669235040:20:0) after 6m42s
[02:49:19.066] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-lintformat-full:8865781572669235040:20:0)
[02:49:19.096] Build status: SCHEDULED
[02:49:19.096] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-lintformat-full:8865781572669235040:21:0) after 6m37s
[02:55:56.110] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-lintformat-full:8865781572669235040:21:0)
[02:55:56.124] Build status: SCHEDULED
[02:55:56.124] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-lintformat-full:8865781572669235040:22:0) after 6m57s
[03:02:53.138] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-lintformat-full:8865781572669235040:22:0)
[03:02:53.156] Build status: SCHEDULED
[03:02:53.156] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-lintformat-full:8865781572669235040:23:0) after 4m24s
[03:07:17.205] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-lintformat-full:8865781572669235040:23:0)
[03:07:17.227] Build status: SCHEDULED
[03:07:17.227] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-lintformat-full:8865781572669235040:24:0) after 9m32s
[03:16:49.243] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-lintformat-full:8865781572669235040:24:0)
[03:16:49.258] Build status: SCHEDULED
[03:16:49.258] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-lintformat-full:8865781572669235040:25:0) after 8m57s
[03:25:46.284] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-lintformat-full:8865781572669235040:25:0)
[03:25:46.320] Build status: SCHEDULED
[03:25:46.320] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-lintformat-full:8865781572669235040:26:0) after 5m38s
[03:31:24.358] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-lintformat-full:8865781572669235040:26:0)
[03:31:24.403] Build status: SCHEDULED
[03:31:24.403] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-lintformat-full:8865781572669235040:27:0) after 7m35s
[03:38:59.520] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-lintformat-full:8865781572669235040:27:0)
[03:38:59.536] Build status: SCHEDULED
[03:38:59.536] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-lintformat-full:8865781572669235040:28:0) after 2m38s
[03:41:37.588] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-lintformat-full:8865781572669235040:28:0)
[03:41:37.613] Build status: SCHEDULED
[03:41:37.613] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-lintformat-full:8865781572669235040:29:0) after 1m33s
[03:43:11.280] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-lintformat-full:8865781572669235040:29:0)
[03:43:11.297] Build status: SCHEDULED
[03:43:11.297] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-lintformat-full:8865781572669235040:30:0) after 3m39s
[03:46:50.311] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-lintformat-full:8865781572669235040:30:0)
[03:46:50.329] Build status: SCHEDULED
[03:46:50.329] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-lintformat-full:8865781572669235040:31:0) after 6m22s
[03:53:12.333] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-lintformat-full:8865781572669235040:31:0)
[03:53:12.350] Build status: SCHEDULED
[03:53:12.350] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-lintformat-full:8865781572669235040:32:0) after 5m35s
[03:58:47.370] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-lintformat-full:8865781572669235040:32:0)
[03:58:47.405] Build status: SCHEDULED
[03:58:47.405] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-lintformat-full:8865781572669235040:33:0) after 4m49s
[04:03:36.477] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-lintformat-full:8865781572669235040:33:0)
[04:03:36.491] Build status: SCHEDULED
[04:03:36.491] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-lintformat-full:8865781572669235040:34:0) after 4m12s
[04:07:48.504] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-lintformat-full:8865781572669235040:34:0)
[04:07:48.530] Build status: SCHEDULED
[04:07:48.530] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-lintformat-full:8865781572669235040:35:0) after 8m40s
[04:16:28.529] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-lintformat-full:8865781572669235040:35:0)
[04:16:28.551] Build status: SCHEDULED
[04:16:28.551] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-lintformat-full:8865781572669235040:36:0) after 5m39s
[04:22:07.566] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-lintformat-full:8865781572669235040:36:0)
[04:22:07.760] Build status: SCHEDULED
[04:22:07.760] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-lintformat-full:8865781572669235040:37:0) after 9m38s
[04:31:46.418] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-lintformat-full:8865781572669235040:37:0)
[04:31:46.441] Build status: SCHEDULED
[04:31:46.441] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-lintformat-full:8865781572669235040:38:0) after 8m58s
[04:40:44.460] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-lintformat-full:8865781572669235040:38:0)
[04:40:44.474] Build status: SCHEDULED
[04:40:44.474] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-lintformat-full:8865781572669235040:39:0) after 9m31s
[04:50:15.571] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-lintformat-full:8865781572669235040:39:0)
[04:50:15.601] Build status: SCHEDULED
[04:50:15.601] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-lintformat-full:8865781572669235040:40:0) after 1m43s
[04:51:58.614] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-lintformat-full:8865781572669235040:40:0)
[04:51:58.645] Build status: SCHEDULED
[04:51:58.645] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-lintformat-full:8865781572669235040:41:0) after 9m2s
[05:01:00.723] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-lintformat-full:8865781572669235040:41:0)
[05:01:00.774] Build status: SCHEDULED
[05:01:00.774] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-lintformat-full:8865781572669235040:42:0) after 9m41s
[05:10:41.939] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-lintformat-full:8865781572669235040:42:0)
[05:10:41.954] Build status: SCHEDULED
[05:10:41.954] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-lintformat-full:8865781572669235040:43:0) after 5m35s
[05:16:16.966] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-lintformat-full:8865781572669235040:43:0)
[05:16:16.990] Build status: SCHEDULED
[05:16:16.990] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-lintformat-full:8865781572669235040:44:0) after 5m29s
[05:19:13.770] Received PubSub notification, asking Buildbucket for the build status
[05:19:13.841] Build status: STARTED
[05:21:45.982] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-lintformat-full:8865781572669235040:44:0)
[05:21:45.997] Build status: STARTED
[05:21:45.997] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-lintformat-full:8865781572669235040:46:0) after 4m23s
[05:26:09.013] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-lintformat-full:8865781572669235040:46:0)
[05:26:09.090] Build status: STARTED
[05:26:09.090] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-lintformat-full:8865781572669235040:47:0) after 1m1s
[05:26:33.992] Received PubSub notification, asking Buildbucket for the build status
[05:26:34.009] Build:
{
"id": "8700351510624480273",
"builder": {
"project": "pigweed",
"bucket": "experimental.dev.ci",
"builder": "experimental-lintformat-full"
},
"createdBy": "project:pigweed",
"createTime": "2025-10-22T01:07:22.819268801Z",
"startTime": "2025-10-22T05:19:13.556298366Z",
"endTime": "2025-10-22T05:26:33.638355241Z",
"updateTime": "2025-10-22T05:26:33.638355241Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "pigweed.googlesource.com",
"project": "pigweed/experimental",
"id": "c5fc0fa27bb61e717048fafaf49a174943750d2c",
"ref": "refs/heads/main"
}
},
"canary": true
}
[05:26:34.009] Invocation finished in 4h19m12.523601572s with status SUCCEEDED