[01:26:22.051] New invocation is queued and will start shortly
[01:26:23.128] Starting the invocation (attempt 1)
[01:26:23.157] Popped buildset tag "buildset:commit/gitiles/pigweed.googlesource.com/pigweed/pigweed/+/0027f9b229f7a48ba5160913a3ed3c1e8e8cb1f5"
[01:26:23.157] Popped gitiles commit info from properties and tags
[01:26:23.157] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[01:26:23.157] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[01:26:23.157] Buildbucket request:
{
"requestId": "8872212761324849024",
"builder": {
"project": "pigweed",
"bucket": "pigweed.dev.ci",
"builder": "pigweed-linux-gn-main"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8872212761324849024",
"job": "pigweed/pigweed.dev.ci-pigweed-linux-gn-main",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "652d07ce5457ca1c6be4d889037a961effbf8e67"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@652d07ce5457ca1c6be4d889037a961effbf8e67",
"title": "652d07ce5457ca1c6be4d889037a961effbf8e67",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/652d07ce5457ca1c6be4d889037a961effbf8e67"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "913b3b28a206cf785d4885f91a19397f1ee4d0f3"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@913b3b28a206cf785d4885f91a19397f1ee4d0f3",
"title": "913b3b28a206cf785d4885f91a19397f1ee4d0f3",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/913b3b28a206cf785d4885f91a19397f1ee4d0f3"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "93ebb0136a8706c7af5d183ced7ba605238bd8fd"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@93ebb0136a8706c7af5d183ced7ba605238bd8fd",
"title": "93ebb0136a8706c7af5d183ced7ba605238bd8fd",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/93ebb0136a8706c7af5d183ced7ba605238bd8fd"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "4cefbefa935422f8a394c243682b54c034c9d9b8"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@4cefbefa935422f8a394c243682b54c034c9d9b8",
"title": "4cefbefa935422f8a394c243682b54c034c9d9b8",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/4cefbefa935422f8a394c243682b54c034c9d9b8"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "0027f9b229f7a48ba5160913a3ed3c1e8e8cb1f5"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@0027f9b229f7a48ba5160913a3ed3c1e8e8cb1f5",
"title": "0027f9b229f7a48ba5160913a3ed3c1e8e8cb1f5",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/0027f9b229f7a48ba5160913a3ed3c1e8e8cb1f5"
}
]
}
},
"gitilesCommit": {
"host": "pigweed.googlesource.com",
"project": "pigweed/pigweed",
"id": "0027f9b229f7a48ba5160913a3ed3c1e8e8cb1f5",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8872212761324849024"
},
{
"key": "scheduler_job_id",
"value": "pigweed/pigweed.dev.ci-pigweed-linux-gn-main"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[01:26:23.526] Scheduled build:
{
"id": "8706782699438396081",
"builder": {
"project": "pigweed",
"bucket": "pigweed.dev.ci",
"builder": "pigweed-linux-gn-main"
},
"createdBy": "project:pigweed",
"createTime": "2025-08-12T01:26:23.223507766Z",
"updateTime": "2025-08-12T01:26:23.223507766Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "pigweed.googlesource.com",
"project": "pigweed/pigweed",
"id": "0027f9b229f7a48ba5160913a3ed3c1e8e8cb1f5",
"ref": "refs/heads/main"
}
}
}
[01:26:23.526] Task URL: https://cr-buildbucket.appspot.com/build/8706782699438396081
[01:26:23.526] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8872212761324849024:2:0) after 6m41s
[01:26:23.977] Received PubSub notification, asking Buildbucket for the build status
[01:26:23.992] Build status: SCHEDULED
[01:33:04.627] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8872212761324849024:2:0)
[01:33:04.648] Build status: SCHEDULED
[01:33:04.648] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8872212761324849024:4:0) after 4m59s
[01:38:03.764] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8872212761324849024:4:0)
[01:38:03.792] Build status: SCHEDULED
[01:38:03.792] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8872212761324849024:5:0) after 1m24s
[01:39:27.807] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8872212761324849024:5:0)
[01:39:27.822] Build status: SCHEDULED
[01:39:27.822] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8872212761324849024:6:0) after 7m49s
[01:47:16.874] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8872212761324849024:6:0)
[01:47:16.889] Build status: SCHEDULED
[01:47:16.890] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8872212761324849024:7:0) after 6m3s
[01:53:19.907] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8872212761324849024:7:0)
[01:53:19.958] Build status: SCHEDULED
[01:53:19.958] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8872212761324849024:8:0) after 9m43s
[02:03:02.973] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8872212761324849024:8:0)
[02:03:02.990] Build status: SCHEDULED
[02:03:02.990] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8872212761324849024:9:0) after 6m28s
[02:09:31.056] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8872212761324849024:9:0)
[02:09:31.076] Build status: SCHEDULED
[02:09:31.076] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8872212761324849024:10:0) after 6m57s
[02:16:28.094] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8872212761324849024:10:0)
[02:16:28.128] Build status: SCHEDULED
[02:16:28.128] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8872212761324849024:11:0) after 1m12s
[02:17:40.140] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8872212761324849024:11:0)
[02:17:40.167] Build status: SCHEDULED
[02:17:40.167] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8872212761324849024:12:0) after 7m7s
[02:24:47.180] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8872212761324849024:12:0)
[02:24:47.207] Build status: SCHEDULED
[02:24:47.207] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8872212761324849024:13:0) after 4m27s
[02:29:14.220] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8872212761324849024:13:0)
[02:29:14.245] Build status: SCHEDULED
[02:29:14.245] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8872212761324849024:14:0) after 1m40s
[02:30:54.259] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8872212761324849024:14:0)
[02:30:54.276] Build status: SCHEDULED
[02:30:54.276] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8872212761324849024:15:0) after 7m52s
[02:38:46.311] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8872212761324849024:15:0)
[02:38:46.331] Build status: SCHEDULED
[02:38:46.331] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8872212761324849024:16:0) after 9m27s
[02:48:13.322] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8872212761324849024:16:0)
[02:48:13.343] Build status: SCHEDULED
[02:48:13.343] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8872212761324849024:17:0) after 5m41s
[02:53:54.464] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8872212761324849024:17:0)
[02:53:54.485] Build status: SCHEDULED
[02:53:54.486] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8872212761324849024:18:0) after 2m18s
[02:56:12.796] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8872212761324849024:18:0)
[02:56:12.840] Build status: SCHEDULED
[02:56:12.840] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8872212761324849024:19:0) after 8m1s
[03:04:13.845] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8872212761324849024:19:0)
[03:04:13.863] Build status: SCHEDULED
[03:04:13.863] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8872212761324849024:20:0) after 2m5s
[03:06:18.932] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8872212761324849024:20:0)
[03:06:18.950] Build status: SCHEDULED
[03:06:18.950] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8872212761324849024:21:0) after 7m26s
[03:13:44.965] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8872212761324849024:21:0)
[03:13:45.055] Build status: SCHEDULED
[03:13:45.055] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8872212761324849024:22:0) after 5m43s
[03:19:28.059] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8872212761324849024:22:0)
[03:19:28.083] Build status: SCHEDULED
[03:19:28.083] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8872212761324849024:23:0) after 5m7s
[03:24:36.006] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8872212761324849024:23:0)
[03:24:36.030] Build status: SCHEDULED
[03:24:36.030] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8872212761324849024:24:0) after 1m57s
[03:26:33.052] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8872212761324849024:24:0)
[03:26:33.079] Build status: SCHEDULED
[03:26:33.079] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8872212761324849024:25:0) after 8m36s
[03:35:09.158] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8872212761324849024:25:0)
[03:35:09.177] Build status: SCHEDULED
[03:35:09.177] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8872212761324849024:26:0) after 3m53s
[03:39:02.272] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8872212761324849024:26:0)
[03:39:02.289] Build status: SCHEDULED
[03:39:02.289] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8872212761324849024:27:0) after 5m39s
[03:44:41.376] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8872212761324849024:27:0)
[03:44:41.444] Build status: SCHEDULED
[03:44:41.444] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8872212761324849024:28:0) after 3m40s
[03:48:21.457] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8872212761324849024:28:0)
[03:48:21.848] Build status: SCHEDULED
[03:48:21.848] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8872212761324849024:29:0) after 6m40s
[03:55:01.883] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8872212761324849024:29:0)
[03:55:01.898] Build status: SCHEDULED
[03:55:01.898] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8872212761324849024:30:0) after 7m51s
[04:02:52.922] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8872212761324849024:30:0)
[04:02:53.057] Build status: SCHEDULED
[04:02:53.057] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8872212761324849024:31:0) after 6m5s
[04:08:58.067] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8872212761324849024:31:0)
[04:08:58.100] Build status: SCHEDULED
[04:08:58.100] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8872212761324849024:32:0) after 7m28s
[04:16:26.115] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8872212761324849024:32:0)
[04:16:26.142] Build status: SCHEDULED
[04:16:26.142] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8872212761324849024:33:0) after 6m34s
[04:23:00.158] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8872212761324849024:33:0)
[04:23:00.177] Build status: SCHEDULED
[04:23:00.177] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8872212761324849024:34:0) after 1m38s
[04:24:38.190] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8872212761324849024:34:0)
[04:24:38.211] Build status: SCHEDULED
[04:24:38.211] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8872212761324849024:35:0) after 2m5s
[04:26:43.240] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8872212761324849024:35:0)
[04:26:43.275] Build status: SCHEDULED
[04:26:43.276] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8872212761324849024:36:0) after 5m16s
[04:31:59.291] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8872212761324849024:36:0)
[04:31:59.311] Build status: SCHEDULED
[04:31:59.311] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8872212761324849024:37:0) after 6m25s
[04:38:24.516] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8872212761324849024:37:0)
[04:38:24.539] Build status: SCHEDULED
[04:38:24.539] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8872212761324849024:38:0) after 5m1s
[04:43:25.825] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8872212761324849024:38:0)
[04:43:25.846] Build status: SCHEDULED
[04:43:25.846] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8872212761324849024:39:0) after 7m50s
[04:44:56.706] Received PubSub notification, asking Buildbucket for the build status
[04:44:56.730] Build status: STARTED
[04:51:15.865] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8872212761324849024:39:0)
[04:51:15.921] Build status: STARTED
[04:51:15.921] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8872212761324849024:41:0) after 2m25s
[04:53:41.330] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8872212761324849024:41:0)
[04:53:41.348] Build status: STARTED
[04:53:41.348] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8872212761324849024:42:0) after 9m22s
[05:03:03.366] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8872212761324849024:42:0)
[05:03:03.439] Build status: STARTED
[05:03:03.439] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8872212761324849024:43:0) after 4m10s
[05:07:13.463] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8872212761324849024:43:0)
[05:07:13.478] Build status: STARTED
[05:07:13.478] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-main:8872212761324849024:44:0) after 8m15s
[05:14:03.013] Received PubSub notification, asking Buildbucket for the build status
[05:14:03.034] Build:
{
"id": "8706782699438396081",
"builder": {
"project": "pigweed",
"bucket": "pigweed.dev.ci",
"builder": "pigweed-linux-gn-main"
},
"createdBy": "project:pigweed",
"createTime": "2025-08-12T01:26:23.223507766Z",
"startTime": "2025-08-12T04:44:16.570478339Z",
"endTime": "2025-08-12T05:14:02.560704651Z",
"updateTime": "2025-08-12T05:14:02.560704651Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "pigweed.googlesource.com",
"project": "pigweed/pigweed",
"id": "0027f9b229f7a48ba5160913a3ed3c1e8e8cb1f5",
"ref": "refs/heads/main"
}
}
}
[05:14:03.034] Invocation finished in 3h47m40.993827019s with status SUCCEEDED