[23:56:28.812] New invocation is queued and will start shortly
[23:56:29.896] Starting the invocation (attempt 1)
[23:56:29.922] Popped buildset tag "buildset:commit/gitiles/pigweed.googlesource.com/pigweed/examples/+/7ba08588b5d408233969ae22e8f68946460f9715"
[23:56:29.922] Popped gitiles commit info from properties and tags
[23:56:29.923] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[23:56:29.923] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[23:56:29.923] Buildbucket request:
{
"requestId": "8872218416547048448",
"builder": {
"project": "pigweed",
"bucket": "examples.dev.ci",
"builder": "examples-default-build-linux"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8872218416547048448",
"job": "pigweed/examples.dev.ci-examples-default-build-linux",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/examples",
"revision": "7ba08588b5d408233969ae22e8f68946460f9715"
},
"id": "https://pigweed.googlesource.com/pigweed/examples/+/refs/heads/main@7ba08588b5d408233969ae22e8f68946460f9715",
"title": "7ba08588b5d408233969ae22e8f68946460f9715",
"url": "https://pigweed.googlesource.com/pigweed/examples/+/7ba08588b5d408233969ae22e8f68946460f9715"
}
]
}
},
"gitilesCommit": {
"host": "pigweed.googlesource.com",
"project": "pigweed/examples",
"id": "7ba08588b5d408233969ae22e8f68946460f9715",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8872218416547048448"
},
{
"key": "scheduler_job_id",
"value": "pigweed/examples.dev.ci-examples-default-build-linux"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[23:56:30.359] Scheduled build:
{
"id": "8706788354666612033",
"builder": {
"project": "pigweed",
"bucket": "examples.dev.ci",
"builder": "examples-default-build-linux"
},
"createdBy": "project:pigweed",
"createTime": "2025-08-11T23:56:29.977895744Z",
"updateTime": "2025-08-11T23:56:29.977895744Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "pigweed.googlesource.com",
"project": "pigweed/examples",
"id": "7ba08588b5d408233969ae22e8f68946460f9715",
"ref": "refs/heads/main"
}
}
}
[23:56:30.359] Task URL: https://cr-buildbucket.appspot.com/build/8706788354666612033
[23:56:30.359] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-linux:8872218416547048448:2:0) after 7m43s
[23:56:30.621] Received PubSub notification, asking Buildbucket for the build status
[23:56:30.649] Build status: SCHEDULED
[00:04:13.426] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-linux:8872218416547048448:2:0)
[00:04:13.447] Build status: SCHEDULED
[00:04:13.447] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-linux:8872218416547048448:4:0) after 4m30s
[00:08:43.503] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-linux:8872218416547048448:4:0)
[00:08:43.527] Build status: SCHEDULED
[00:08:43.527] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-linux:8872218416547048448:5:0) after 2m34s
[00:11:17.570] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-linux:8872218416547048448:5:0)
[00:11:17.592] Build status: SCHEDULED
[00:11:17.592] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-linux:8872218416547048448:6:0) after 2m13s
[00:13:30.624] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-linux:8872218416547048448:6:0)
[00:13:30.645] Build status: SCHEDULED
[00:13:30.645] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-linux:8872218416547048448:7:0) after 3m17s
[00:16:47.746] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-linux:8872218416547048448:7:0)
[00:16:47.769] Build status: SCHEDULED
[00:16:47.769] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-linux:8872218416547048448:8:0) after 4m27s
[00:21:14.907] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-linux:8872218416547048448:8:0)
[00:21:14.934] Build status: SCHEDULED
[00:21:14.934] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-linux:8872218416547048448:9:0) after 5m2s
[00:26:17.043] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-linux:8872218416547048448:9:0)
[00:26:17.059] Build status: SCHEDULED
[00:26:17.059] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-linux:8872218416547048448:10:0) after 2m44s
[00:29:01.125] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-linux:8872218416547048448:10:0)
[00:29:01.157] Build status: SCHEDULED
[00:29:01.157] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-linux:8872218416547048448:11:0) after 3m52s
[00:32:53.255] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-linux:8872218416547048448:11:0)
[00:32:53.340] Build status: SCHEDULED
[00:32:53.340] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-linux:8872218416547048448:12:0) after 1m11s
[00:34:04.360] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-linux:8872218416547048448:12:0)
[00:34:04.378] Build status: SCHEDULED
[00:34:04.378] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-linux:8872218416547048448:13:0) after 8m20s
[00:42:24.394] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-linux:8872218416547048448:13:0)
[00:42:24.420] Build status: SCHEDULED
[00:42:24.420] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-linux:8872218416547048448:14:0) after 5m10s
[00:47:34.403] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-linux:8872218416547048448:14:0)
[00:47:34.421] Build status: SCHEDULED
[00:47:34.421] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-linux:8872218416547048448:15:0) after 2m28s
[00:50:02.509] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-linux:8872218416547048448:15:0)
[00:50:02.539] Build status: SCHEDULED
[00:50:02.539] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-linux:8872218416547048448:16:0) after 9m59s
[01:00:01.568] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-linux:8872218416547048448:16:0)
[01:00:01.595] Build status: SCHEDULED
[01:00:01.595] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-linux:8872218416547048448:17:0) after 8m43s
[01:08:44.746] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-linux:8872218416547048448:17:0)
[01:08:44.767] Build status: SCHEDULED
[01:08:44.767] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-linux:8872218416547048448:18:0) after 4m39s
[01:13:23.869] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-linux:8872218416547048448:18:0)
[01:13:23.902] Build status: SCHEDULED
[01:13:23.902] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-linux:8872218416547048448:19:0) after 4m29s
[01:17:52.917] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-linux:8872218416547048448:19:0)
[01:17:52.934] Build status: SCHEDULED
[01:17:52.934] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-linux:8872218416547048448:20:0) after 2m26s
[01:20:19.031] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-linux:8872218416547048448:20:0)
[01:20:19.049] Build status: SCHEDULED
[01:20:19.049] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-linux:8872218416547048448:21:0) after 9m40s
[01:29:59.213] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-linux:8872218416547048448:21:0)
[01:29:59.251] Build status: SCHEDULED
[01:29:59.251] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-linux:8872218416547048448:22:0) after 8m14s
[01:38:13.269] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-linux:8872218416547048448:22:0)
[01:38:13.304] Build status: SCHEDULED
[01:38:13.304] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-linux:8872218416547048448:23:0) after 5m40s
[01:43:53.393] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-linux:8872218416547048448:23:0)
[01:43:53.462] Build status: SCHEDULED
[01:43:53.462] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-linux:8872218416547048448:24:0) after 4m26s
[01:48:19.430] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-linux:8872218416547048448:24:0)
[01:48:19.457] Build status: SCHEDULED
[01:48:19.457] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-linux:8872218416547048448:25:0) after 3m20s
[01:51:39.495] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-linux:8872218416547048448:25:0)
[01:51:39.529] Build status: SCHEDULED
[01:51:39.529] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-linux:8872218416547048448:26:0) after 9m4s
[02:00:43.543] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-linux:8872218416547048448:26:0)
[02:00:43.560] Build status: SCHEDULED
[02:00:43.560] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-linux:8872218416547048448:27:0) after 4m9s
[02:04:52.626] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-linux:8872218416547048448:27:0)
[02:04:52.646] Build status: SCHEDULED
[02:04:52.646] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-linux:8872218416547048448:28:0) after 5m17s
[02:10:09.660] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-linux:8872218416547048448:28:0)
[02:10:09.677] Build status: SCHEDULED
[02:10:09.677] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-linux:8872218416547048448:29:0) after 2m41s
[02:12:50.784] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-linux:8872218416547048448:29:0)
[02:12:50.802] Build status: SCHEDULED
[02:12:50.802] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-linux:8872218416547048448:30:0) after 4m53s
[02:17:43.818] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-linux:8872218416547048448:30:0)
[02:17:43.836] Build status: SCHEDULED
[02:17:43.836] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-linux:8872218416547048448:31:0) after 7m57s
[02:25:40.859] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-linux:8872218416547048448:31:0)
[02:25:41.014] Build status: SCHEDULED
[02:25:41.014] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-linux:8872218416547048448:32:0) after 5m34s
[02:31:15.082] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-linux:8872218416547048448:32:0)
[02:31:15.106] Build status: SCHEDULED
[02:31:15.106] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-linux:8872218416547048448:33:0) after 6m24s
[02:37:39.138] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-linux:8872218416547048448:33:0)
[02:37:39.163] Build status: SCHEDULED
[02:37:39.163] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-linux:8872218416547048448:34:0) after 1m10s
[02:38:49.184] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-linux:8872218416547048448:34:0)
[02:38:49.263] Build status: SCHEDULED
[02:38:49.263] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-linux:8872218416547048448:35:0) after 7m30s
[02:46:19.414] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-linux:8872218416547048448:35:0)
[02:46:19.433] Build status: SCHEDULED
[02:46:19.433] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-linux:8872218416547048448:36:0) after 4m9s
[02:50:28.449] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-linux:8872218416547048448:36:0)
[02:50:28.475] Build status: SCHEDULED
[02:50:28.475] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-linux:8872218416547048448:37:0) after 7m50s
[02:58:18.488] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-linux:8872218416547048448:37:0)
[02:58:18.512] Build status: SCHEDULED
[02:58:18.512] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-linux:8872218416547048448:38:0) after 3m7s
[03:01:25.527] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-linux:8872218416547048448:38:0)
[03:01:25.576] Build status: SCHEDULED
[03:01:25.576] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-linux:8872218416547048448:39:0) after 8m23s
[03:09:48.592] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-linux:8872218416547048448:39:0)
[03:09:48.646] Build status: SCHEDULED
[03:09:48.646] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-linux:8872218416547048448:40:0) after 1m47s
[03:11:35.661] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-linux:8872218416547048448:40:0)
[03:11:35.679] Build status: SCHEDULED
[03:11:35.679] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-linux:8872218416547048448:41:0) after 3m35s
[03:15:10.745] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-linux:8872218416547048448:41:0)
[03:15:10.768] Build status: SCHEDULED
[03:15:10.768] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-linux:8872218416547048448:42:0) after 7m8s
[03:22:18.895] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-linux:8872218416547048448:42:0)
[03:22:18.911] Build status: SCHEDULED
[03:22:18.911] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-linux:8872218416547048448:43:0) after 5m17s
[03:27:35.954] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-linux:8872218416547048448:43:0)
[03:27:35.972] Build status: SCHEDULED
[03:27:35.972] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-linux:8872218416547048448:44:0) after 6m49s
[03:32:46.530] Received PubSub notification, asking Buildbucket for the build status
[03:32:46.546] Build status: STARTED
[03:34:25.032] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-linux:8872218416547048448:44:0)
[03:34:25.050] Build status: STARTED
[03:34:25.050] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-linux:8872218416547048448:46:0) after 4m0s
[03:38:25.066] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-linux:8872218416547048448:46:0)
[03:38:25.083] Build:
{
"id": "8706788354666612033",
"builder": {
"project": "pigweed",
"bucket": "examples.dev.ci",
"builder": "examples-default-build-linux"
},
"createdBy": "project:pigweed",
"createTime": "2025-08-11T23:56:29.977895744Z",
"startTime": "2025-08-12T03:32:46.318238874Z",
"endTime": "2025-08-12T03:38:22.284007800Z",
"updateTime": "2025-08-12T03:38:22.284007800Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "pigweed.googlesource.com",
"project": "pigweed/examples",
"id": "7ba08588b5d408233969ae22e8f68946460f9715",
"ref": "refs/heads/main"
}
}
}
[03:38:25.083] Invocation finished in 3h41m56.283216209s with status SUCCEEDED