[22:49:47.188] New invocation is queued and will start shortly
[22:49:48.275] Starting the invocation (attempt 1)
[22:49:48.304] Popped buildset tag "buildset:commit/gitiles/pigweed.googlesource.com/pigweed/experimental/+/82a31be437e90476615ab8f3cc1b507de055bb74"
[22:49:48.304] Popped gitiles commit info from properties and tags
[22:49:48.304] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[22:49:48.304] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[22:49:48.304] Buildbucket request:
{
"requestId": "8927758552961649840",
"builder": {
"project": "pigweed",
"bucket": "experimental.dev.ci",
"builder": "experimental-lintformat-full"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8927758552961649840",
"job": "pigweed/experimental.dev.ci-experimental-lintformat-full",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/experimental",
"revision": "f72f9c67e12226b2e90ace62f19093c61e62efb0"
},
"id": "https://pigweed.googlesource.com/pigweed/experimental/+/refs/heads/main@f72f9c67e12226b2e90ace62f19093c61e62efb0",
"title": "f72f9c67e12226b2e90ace62f19093c61e62efb0",
"url": "https://pigweed.googlesource.com/pigweed/experimental/+/f72f9c67e12226b2e90ace62f19093c61e62efb0"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/experimental",
"revision": "4e7f12fc1cb05b95d293c6e1c60572b7644d2cdc"
},
"id": "https://pigweed.googlesource.com/pigweed/experimental/+/refs/heads/main@4e7f12fc1cb05b95d293c6e1c60572b7644d2cdc",
"title": "4e7f12fc1cb05b95d293c6e1c60572b7644d2cdc",
"url": "https://pigweed.googlesource.com/pigweed/experimental/+/4e7f12fc1cb05b95d293c6e1c60572b7644d2cdc"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/experimental",
"revision": "96ef61807bee8376d63196174bbe8f839fbaec6d"
},
"id": "https://pigweed.googlesource.com/pigweed/experimental/+/refs/heads/main@96ef61807bee8376d63196174bbe8f839fbaec6d",
"title": "96ef61807bee8376d63196174bbe8f839fbaec6d",
"url": "https://pigweed.googlesource.com/pigweed/experimental/+/96ef61807bee8376d63196174bbe8f839fbaec6d"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/experimental",
"revision": "29bcdd7d4f815e02552935ca807b9d3a7abebb62"
},
"id": "https://pigweed.googlesource.com/pigweed/experimental/+/refs/heads/main@29bcdd7d4f815e02552935ca807b9d3a7abebb62",
"title": "29bcdd7d4f815e02552935ca807b9d3a7abebb62",
"url": "https://pigweed.googlesource.com/pigweed/experimental/+/29bcdd7d4f815e02552935ca807b9d3a7abebb62"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/experimental",
"revision": "f3faa750eea25858a18604282ad2c4ba750a54f5"
},
"id": "https://pigweed.googlesource.com/pigweed/experimental/+/refs/heads/main@f3faa750eea25858a18604282ad2c4ba750a54f5",
"title": "f3faa750eea25858a18604282ad2c4ba750a54f5",
"url": "https://pigweed.googlesource.com/pigweed/experimental/+/f3faa750eea25858a18604282ad2c4ba750a54f5"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/experimental",
"revision": "0d83b4bb9dc60f0c02831611be6705584b75787d"
},
"id": "https://pigweed.googlesource.com/pigweed/experimental/+/refs/heads/main@0d83b4bb9dc60f0c02831611be6705584b75787d",
"title": "0d83b4bb9dc60f0c02831611be6705584b75787d",
"url": "https://pigweed.googlesource.com/pigweed/experimental/+/0d83b4bb9dc60f0c02831611be6705584b75787d"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/experimental",
"revision": "4e99363913b3868b7033e5313107aa02c2007828"
},
"id": "https://pigweed.googlesource.com/pigweed/experimental/+/refs/heads/main@4e99363913b3868b7033e5313107aa02c2007828",
"title": "4e99363913b3868b7033e5313107aa02c2007828",
"url": "https://pigweed.googlesource.com/pigweed/experimental/+/4e99363913b3868b7033e5313107aa02c2007828"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/experimental",
"revision": "2a89ca8fe23634777bdb187e67f08f40cc378de0"
},
"id": "https://pigweed.googlesource.com/pigweed/experimental/+/refs/heads/main@2a89ca8fe23634777bdb187e67f08f40cc378de0",
"title": "2a89ca8fe23634777bdb187e67f08f40cc378de0",
"url": "https://pigweed.googlesource.com/pigweed/experimental/+/2a89ca8fe23634777bdb187e67f08f40cc378de0"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/experimental",
"revision": "acf540f48d5c572fba07e8c013e0f0d09bd507eb"
},
"id": "https://pigweed.googlesource.com/pigweed/experimental/+/refs/heads/main@acf540f48d5c572fba07e8c013e0f0d09bd507eb",
"title": "acf540f48d5c572fba07e8c013e0f0d09bd507eb",
"url": "https://pigweed.googlesource.com/pigweed/experimental/+/acf540f48d5c572fba07e8c013e0f0d09bd507eb"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/experimental",
"revision": "82a31be437e90476615ab8f3cc1b507de055bb74"
},
"id": "https://pigweed.googlesource.com/pigweed/experimental/+/refs/heads/main@82a31be437e90476615ab8f3cc1b507de055bb74",
"title": "82a31be437e90476615ab8f3cc1b507de055bb74",
"url": "https://pigweed.googlesource.com/pigweed/experimental/+/82a31be437e90476615ab8f3cc1b507de055bb74"
}
]
}
},
"gitilesCommit": {
"host": "pigweed.googlesource.com",
"project": "pigweed/experimental",
"id": "82a31be437e90476615ab8f3cc1b507de055bb74",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8927758552961649840"
},
{
"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"
}
}
[22:49:48.852] Scheduled build:
{
"id": "8762328491053410833",
"builder": {
"project": "pigweed",
"bucket": "experimental.dev.ci",
"builder": "experimental-lintformat-full"
},
"createdBy": "project:pigweed",
"createTime": "2023-12-07T22:49:48.375552372Z",
"updateTime": "2023-12-07T22:49:48.375552372Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "pigweed.googlesource.com",
"project": "pigweed/experimental",
"id": "82a31be437e90476615ab8f3cc1b507de055bb74",
"ref": "refs/heads/main"
}
}
}
[22:49:48.852] Task URL: https://cr-buildbucket.appspot.com/build/8762328491053410833
[22:49:48.852] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-lintformat-full:8927758552961649840:2:0) after 6m52s
[22:49:49.700] Received PubSub notification, asking Buildbucket for the build status
[22:49:49.746] Build status: SCHEDULED
[22:56:40.873] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-lintformat-full:8927758552961649840:2:0)
[22:56:40.905] Build status: SCHEDULED
[22:56:40.905] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-lintformat-full:8927758552961649840:4:0) after 1m59s
[22:58:39.957] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-lintformat-full:8927758552961649840:4:0)
[22:58:39.998] Build status: SCHEDULED
[22:58:39.998] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-lintformat-full:8927758552961649840:5:0) after 2m39s
[23:01:19.239] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-lintformat-full:8927758552961649840:5:0)
[23:01:19.307] Build status: SCHEDULED
[23:01:19.307] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-lintformat-full:8927758552961649840:6:0) after 7m16s
[23:08:35.327] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-lintformat-full:8927758552961649840:6:0)
[23:08:35.368] Build status: SCHEDULED
[23:08:35.368] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-lintformat-full:8927758552961649840:7:0) after 5m12s
[23:13:47.392] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-lintformat-full:8927758552961649840:7:0)
[23:13:47.422] Build status: SCHEDULED
[23:13:47.422] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-lintformat-full:8927758552961649840:8:0) after 3m8s
[23:16:55.465] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-lintformat-full:8927758552961649840:8:0)
[23:16:55.505] Build status: SCHEDULED
[23:16:55.505] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-lintformat-full:8927758552961649840:9:0) after 3m1s
[23:19:56.679] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-lintformat-full:8927758552961649840:9:0)
[23:19:56.713] Build status: SCHEDULED
[23:19:56.713] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-lintformat-full:8927758552961649840:10:0) after 4m29s
[23:24:25.733] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-lintformat-full:8927758552961649840:10:0)
[23:24:25.767] Build status: SCHEDULED
[23:24:25.767] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-lintformat-full:8927758552961649840:11:0) after 5m34s
[23:29:59.883] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-lintformat-full:8927758552961649840:11:0)
[23:29:59.913] Build status: SCHEDULED
[23:29:59.913] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-lintformat-full:8927758552961649840:12:0) after 6m37s
[23:36:37.045] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-lintformat-full:8927758552961649840:12:0)
[23:36:37.086] Build status: SCHEDULED
[23:36:37.086] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-lintformat-full:8927758552961649840:13:0) after 5m52s
[23:42:29.229] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-lintformat-full:8927758552961649840:13:0)
[23:42:29.258] Build status: SCHEDULED
[23:42:29.258] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-lintformat-full:8927758552961649840:14:0) after 7m29s
[23:49:58.304] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-lintformat-full:8927758552961649840:14:0)
[23:49:58.433] Build status: SCHEDULED
[23:49:58.433] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-lintformat-full:8927758552961649840:15:0) after 3m53s
[23:53:51.511] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-lintformat-full:8927758552961649840:15:0)
[23:53:51.545] Build status: SCHEDULED
[23:53:51.545] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-lintformat-full:8927758552961649840:16:0) after 4m31s
[23:58:22.591] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-lintformat-full:8927758552961649840:16:0)
[23:58:22.632] Build status: SCHEDULED
[23:58:22.632] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-lintformat-full:8927758552961649840:17:0) after 9m29s
[00:07:51.790] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-lintformat-full:8927758552961649840:17:0)
[00:07:51.824] Build status: SCHEDULED
[00:07:51.824] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-lintformat-full:8927758552961649840:18:0) after 1m40s
[00:09:31.861] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-lintformat-full:8927758552961649840:18:0)
[00:09:31.902] Build status: SCHEDULED
[00:09:31.902] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-lintformat-full:8927758552961649840:19:0) after 6m37s
[00:16:08.929] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-lintformat-full:8927758552961649840:19:0)
[00:16:08.948] Build status: SCHEDULED
[00:16:08.948] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-lintformat-full:8927758552961649840:20:0) after 5m33s
[00:21:41.991] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-lintformat-full:8927758552961649840:20:0)
[00:21:42.039] Build status: SCHEDULED
[00:21:42.040] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-lintformat-full:8927758552961649840:21:0) after 3m33s
[00:25:15.582] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-lintformat-full:8927758552961649840:21:0)
[00:25:15.647] Build status: SCHEDULED
[00:25:15.647] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-lintformat-full:8927758552961649840:22:0) after 3m19s
[00:28:34.673] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-lintformat-full:8927758552961649840:22:0)
[00:28:34.762] Build status: SCHEDULED
[00:28:34.762] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-lintformat-full:8927758552961649840:23:0) after 1m46s
[00:30:20.853] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-lintformat-full:8927758552961649840:23:0)
[00:30:20.882] Build status: SCHEDULED
[00:30:20.882] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-lintformat-full:8927758552961649840:24:0) after 1m25s
[00:31:45.906] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-lintformat-full:8927758552961649840:24:0)
[00:31:45.943] Build status: SCHEDULED
[00:31:45.943] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-lintformat-full:8927758552961649840:25:0) after 5m13s
[00:36:58.967] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-lintformat-full:8927758552961649840:25:0)
[00:36:59.006] Build status: SCHEDULED
[00:36:59.006] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-lintformat-full:8927758552961649840:26:0) after 9m53s
[00:46:52.025] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-lintformat-full:8927758552961649840:26:0)
[00:46:52.076] Build status: SCHEDULED
[00:46:52.076] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-lintformat-full:8927758552961649840:27:0) after 3m42s
[00:50:34.095] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-lintformat-full:8927758552961649840:27:0)
[00:50:34.128] Build status: SCHEDULED
[00:50:34.128] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-lintformat-full:8927758552961649840:28:0) after 9m51s
[01:00:25.166] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-lintformat-full:8927758552961649840:28:0)
[01:00:25.227] Build status: SCHEDULED
[01:00:25.227] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-lintformat-full:8927758552961649840:29:0) after 4m51s
[01:05:16.302] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-lintformat-full:8927758552961649840:29:0)
[01:05:16.332] Build status: SCHEDULED
[01:05:16.332] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-lintformat-full:8927758552961649840:30:0) after 5m37s
[01:10:53.353] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-lintformat-full:8927758552961649840:30:0)
[01:10:53.456] Build status: SCHEDULED
[01:10:53.456] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-lintformat-full:8927758552961649840:31:0) after 5m45s
[01:16:38.528] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-lintformat-full:8927758552961649840:31:0)
[01:16:38.708] Build status: SCHEDULED
[01:16:38.708] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-lintformat-full:8927758552961649840:32:0) after 9m23s
[01:26:01.745] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-lintformat-full:8927758552961649840:32:0)
[01:26:01.790] Build status: SCHEDULED
[01:26:01.790] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-lintformat-full:8927758552961649840:33:0) after 3m48s
[01:29:49.806] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-lintformat-full:8927758552961649840:33:0)
[01:29:49.900] Build status: SCHEDULED
[01:29:49.900] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-lintformat-full:8927758552961649840:34:0) after 8m51s
[01:33:29.604] Received PubSub notification, asking Buildbucket for the build status
[01:33:29.627] Build status: STARTED
[01:38:40.968] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-lintformat-full:8927758552961649840:34:0)
[01:38:41.002] Build status: STARTED
[01:38:41.002] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-lintformat-full:8927758552961649840:36:0) after 8m38s
[01:39:42.232] Received PubSub notification, asking Buildbucket for the build status
[01:39:42.265] Build:
{
"id": "8762328491053410833",
"builder": {
"project": "pigweed",
"bucket": "experimental.dev.ci",
"builder": "experimental-lintformat-full"
},
"createdBy": "project:pigweed",
"createTime": "2023-12-07T22:49:48.375552372Z",
"startTime": "2023-12-08T01:33:28.945706Z",
"endTime": "2023-12-08T01:39:41.476864Z",
"updateTime": "2023-12-08T01:39:41.476864Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "pigweed.googlesource.com",
"project": "pigweed/experimental",
"id": "82a31be437e90476615ab8f3cc1b507de055bb74",
"ref": "refs/heads/main"
}
}
}
[01:39:42.265] Invocation finished in 2h49m55.093279237s with status SUCCEEDED