[23:19:01.166] New invocation is queued and will start shortly
[23:19:05.061] Starting the invocation (attempt 1)
[23:19:05.088] Popped buildset tag "buildset:commit/gitiles/pigweed.googlesource.com/pigweed/sandbox/+/1a3fe751061fec83b3f360627d5adc66f0d71de3"
[23:19:05.088] Popped gitiles commit info from properties and tags
[23:19:05.088] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[23:19:05.088] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[23:19:05.089] Buildbucket request:
{
"requestId": "8842414371420469968",
"builder": {
"project": "pigweed",
"bucket": "pigweed.sandbox.dev.ci",
"builder": "pigweed-linux-sandbox"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8842414371420469968",
"job": "pigweed/pigweed.sandbox.dev.ci-pigweed-linux-sandbox",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/rpc2",
"repo": "https://pigweed.googlesource.com/pigweed/sandbox",
"revision": "6d383999c3526e8554d5366d2b04d94ef0719ba7"
},
"id": "https://pigweed.googlesource.com/pigweed/sandbox/+/refs/heads/rpc2@6d383999c3526e8554d5366d2b04d94ef0719ba7",
"title": "6d383999c3526e8554d5366d2b04d94ef0719ba7",
"url": "https://pigweed.googlesource.com/pigweed/sandbox/+/6d383999c3526e8554d5366d2b04d94ef0719ba7"
},
{
"gitiles": {
"ref": "refs/heads/upstream/main",
"repo": "https://pigweed.googlesource.com/pigweed/sandbox",
"revision": "81bf56ea9f8a2887dc6312aeed7a7824c75daffa"
},
"id": "https://pigweed.googlesource.com/pigweed/sandbox/+/refs/heads/upstream/main@81bf56ea9f8a2887dc6312aeed7a7824c75daffa",
"title": "81bf56ea9f8a2887dc6312aeed7a7824c75daffa",
"url": "https://pigweed.googlesource.com/pigweed/sandbox/+/81bf56ea9f8a2887dc6312aeed7a7824c75daffa"
},
{
"gitiles": {
"ref": "refs/heads/upstream/main",
"repo": "https://pigweed.googlesource.com/pigweed/sandbox",
"revision": "1a3fe751061fec83b3f360627d5adc66f0d71de3"
},
"id": "https://pigweed.googlesource.com/pigweed/sandbox/+/refs/heads/upstream/main@1a3fe751061fec83b3f360627d5adc66f0d71de3",
"title": "1a3fe751061fec83b3f360627d5adc66f0d71de3",
"url": "https://pigweed.googlesource.com/pigweed/sandbox/+/1a3fe751061fec83b3f360627d5adc66f0d71de3"
}
]
}
},
"gitilesCommit": {
"host": "pigweed.googlesource.com",
"project": "pigweed/sandbox",
"id": "1a3fe751061fec83b3f360627d5adc66f0d71de3",
"ref": "refs/heads/upstream/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8842414371420469968"
},
{
"key": "scheduler_job_id",
"value": "pigweed/pigweed.sandbox.dev.ci-pigweed-linux-sandbox"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[23:19:06.130] Scheduled build:
{
"id": "8676984306555899825",
"builder": {
"project": "pigweed",
"bucket": "pigweed.sandbox.dev.ci",
"builder": "pigweed-linux-sandbox"
},
"createdBy": "project:pigweed",
"createTime": "2026-07-06T23:19:05.185199317Z",
"updateTime": "2026-07-06T23:19:05.185199317Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "pigweed.googlesource.com",
"project": "pigweed/sandbox",
"id": "1a3fe751061fec83b3f360627d5adc66f0d71de3",
"ref": "refs/heads/upstream/main"
}
}
}
[23:19:06.130] Task URL: https://cr-buildbucket.appspot.com/build/8676984306555899825
[23:19:06.130] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.sandbox.dev.ci-pigweed-linux-sandbox:8842414371420469968:2:0) after 4m27s
[23:19:06.925] Received PubSub notification, asking Buildbucket for the build status
[23:19:06.941] Build status: SCHEDULED
[23:23:33.202] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.sandbox.dev.ci-pigweed-linux-sandbox:8842414371420469968:2:0)
[23:23:33.233] Build status: SCHEDULED
[23:23:33.233] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.sandbox.dev.ci-pigweed-linux-sandbox:8842414371420469968:4:0) after 8m55s
[23:32:28.342] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.sandbox.dev.ci-pigweed-linux-sandbox:8842414371420469968:4:0)
[23:32:28.396] Build status: SCHEDULED
[23:32:28.396] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.sandbox.dev.ci-pigweed-linux-sandbox:8842414371420469968:5:0) after 6m42s
[23:39:10.413] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.sandbox.dev.ci-pigweed-linux-sandbox:8842414371420469968:5:0)
[23:39:10.441] Build status: SCHEDULED
[23:39:10.441] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.sandbox.dev.ci-pigweed-linux-sandbox:8842414371420469968:6:0) after 2m31s
[23:41:41.506] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.sandbox.dev.ci-pigweed-linux-sandbox:8842414371420469968:6:0)
[23:41:41.526] Build status: SCHEDULED
[23:41:41.526] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.sandbox.dev.ci-pigweed-linux-sandbox:8842414371420469968:7:0) after 6m4s
[23:47:45.604] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.sandbox.dev.ci-pigweed-linux-sandbox:8842414371420469968:7:0)
[23:47:45.674] Build status: SCHEDULED
[23:47:45.674] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.sandbox.dev.ci-pigweed-linux-sandbox:8842414371420469968:8:0) after 6m5s
[23:53:50.828] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.sandbox.dev.ci-pigweed-linux-sandbox:8842414371420469968:8:0)
[23:53:50.885] Build status: SCHEDULED
[23:53:50.885] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.sandbox.dev.ci-pigweed-linux-sandbox:8842414371420469968:9:0) after 9m56s
[00:03:46.913] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.sandbox.dev.ci-pigweed-linux-sandbox:8842414371420469968:9:0)
[00:03:47.020] Build status: SCHEDULED
[00:03:47.020] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.sandbox.dev.ci-pigweed-linux-sandbox:8842414371420469968:10:0) after 5m10s
[00:08:57.157] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.sandbox.dev.ci-pigweed-linux-sandbox:8842414371420469968:10:0)
[00:08:57.231] Build status: SCHEDULED
[00:08:57.231] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.sandbox.dev.ci-pigweed-linux-sandbox:8842414371420469968:11:0) after 7m20s
[00:16:17.242] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.sandbox.dev.ci-pigweed-linux-sandbox:8842414371420469968:11:0)
[00:16:17.261] Build status: SCHEDULED
[00:16:17.261] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.sandbox.dev.ci-pigweed-linux-sandbox:8842414371420469968:12:0) after 9m37s
[00:25:54.325] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.sandbox.dev.ci-pigweed-linux-sandbox:8842414371420469968:12:0)
[00:25:54.351] Build status: SCHEDULED
[00:25:54.351] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.sandbox.dev.ci-pigweed-linux-sandbox:8842414371420469968:13:0) after 9m34s
[00:35:28.376] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.sandbox.dev.ci-pigweed-linux-sandbox:8842414371420469968:13:0)
[00:35:28.399] Build status: SCHEDULED
[00:35:28.399] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.sandbox.dev.ci-pigweed-linux-sandbox:8842414371420469968:14:0) after 3m29s
[00:38:57.569] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.sandbox.dev.ci-pigweed-linux-sandbox:8842414371420469968:14:0)
[00:38:57.588] Build status: SCHEDULED
[00:38:57.588] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.sandbox.dev.ci-pigweed-linux-sandbox:8842414371420469968:15:0) after 3m55s
[00:42:52.577] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.sandbox.dev.ci-pigweed-linux-sandbox:8842414371420469968:15:0)
[00:42:52.637] Build status: SCHEDULED
[00:42:52.637] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.sandbox.dev.ci-pigweed-linux-sandbox:8842414371420469968:16:0) after 1m45s
[00:44:37.653] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.sandbox.dev.ci-pigweed-linux-sandbox:8842414371420469968:16:0)
[00:44:37.677] Build status: SCHEDULED
[00:44:37.677] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.sandbox.dev.ci-pigweed-linux-sandbox:8842414371420469968:17:0) after 6m24s
[00:51:01.693] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.sandbox.dev.ci-pigweed-linux-sandbox:8842414371420469968:17:0)
[00:51:01.728] Build status: SCHEDULED
[00:51:01.728] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.sandbox.dev.ci-pigweed-linux-sandbox:8842414371420469968:18:0) after 1m41s
[00:52:42.933] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.sandbox.dev.ci-pigweed-linux-sandbox:8842414371420469968:18:0)
[00:52:42.952] Build status: SCHEDULED
[00:52:42.952] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.sandbox.dev.ci-pigweed-linux-sandbox:8842414371420469968:19:0) after 8m55s
[01:01:38.091] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.sandbox.dev.ci-pigweed-linux-sandbox:8842414371420469968:19:0)
[01:01:38.110] Build status: SCHEDULED
[01:01:38.110] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.sandbox.dev.ci-pigweed-linux-sandbox:8842414371420469968:20:0) after 9m23s
[01:11:01.146] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.sandbox.dev.ci-pigweed-linux-sandbox:8842414371420469968:20:0)
[01:11:01.203] Build status: SCHEDULED
[01:11:01.203] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.sandbox.dev.ci-pigweed-linux-sandbox:8842414371420469968:21:0) after 5m14s
[01:16:15.639] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.sandbox.dev.ci-pigweed-linux-sandbox:8842414371420469968:21:0)
[01:16:15.661] Build status: SCHEDULED
[01:16:15.661] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.sandbox.dev.ci-pigweed-linux-sandbox:8842414371420469968:22:0) after 5m41s
[01:21:56.675] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.sandbox.dev.ci-pigweed-linux-sandbox:8842414371420469968:22:0)
[01:21:56.722] Build status: SCHEDULED
[01:21:56.722] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.sandbox.dev.ci-pigweed-linux-sandbox:8842414371420469968:23:0) after 4m46s
[01:26:42.804] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.sandbox.dev.ci-pigweed-linux-sandbox:8842414371420469968:23:0)
[01:26:42.835] Build status: SCHEDULED
[01:26:42.835] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.sandbox.dev.ci-pigweed-linux-sandbox:8842414371420469968:24:0) after 5m59s
[01:32:41.994] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.sandbox.dev.ci-pigweed-linux-sandbox:8842414371420469968:24:0)
[01:32:42.014] Build status: SCHEDULED
[01:32:42.014] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.sandbox.dev.ci-pigweed-linux-sandbox:8842414371420469968:25:0) after 4m43s
[01:37:25.103] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.sandbox.dev.ci-pigweed-linux-sandbox:8842414371420469968:25:0)
[01:37:25.119] Build status: SCHEDULED
[01:37:25.119] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.sandbox.dev.ci-pigweed-linux-sandbox:8842414371420469968:26:0) after 2m13s
[01:39:38.166] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.sandbox.dev.ci-pigweed-linux-sandbox:8842414371420469968:26:0)
[01:39:38.194] Build status: SCHEDULED
[01:39:38.194] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.sandbox.dev.ci-pigweed-linux-sandbox:8842414371420469968:27:0) after 8m20s
[01:47:58.328] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.sandbox.dev.ci-pigweed-linux-sandbox:8842414371420469968:27:0)
[01:47:58.348] Build status: SCHEDULED
[01:47:58.348] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.sandbox.dev.ci-pigweed-linux-sandbox:8842414371420469968:28:0) after 1m40s
[01:49:38.411] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.sandbox.dev.ci-pigweed-linux-sandbox:8842414371420469968:28:0)
[01:49:38.447] Build status: SCHEDULED
[01:49:38.447] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.sandbox.dev.ci-pigweed-linux-sandbox:8842414371420469968:29:0) after 8m9s
[01:52:42.237] Received PubSub notification, asking Buildbucket for the build status
[01:52:42.255] Build status: STARTED
[01:52:57.494] Received PubSub notification, asking Buildbucket for the build status
[01:52:57.511] Build:
{
"id": "8676984306555899825",
"builder": {
"project": "pigweed",
"bucket": "pigweed.sandbox.dev.ci",
"builder": "pigweed-linux-sandbox"
},
"createdBy": "project:pigweed",
"createTime": "2026-07-06T23:19:05.185199317Z",
"startTime": "2026-07-07T01:52:42.048252888Z",
"endTime": "2026-07-07T01:52:57.269955058Z",
"updateTime": "2026-07-07T01:52:57.269955058Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "pigweed.googlesource.com",
"project": "pigweed/sandbox",
"id": "1a3fe751061fec83b3f360627d5adc66f0d71de3",
"ref": "refs/heads/upstream/main"
}
}
}
[01:52:57.511] Invocation finished in 2h33m56.349434966s with status SUCCEEDED