[06:48:10.287] New invocation is queued and will start shortly
[06:48:11.372] Starting the invocation (attempt 1)
[06:48:11.425] Popped buildset tag "buildset:commit/gitiles/pigweed.googlesource.com/third_party/github/OpenPRoT/openprot/+/36a95f4c0142bd7782080074e938babb40743ce1"
[06:48:11.425] Popped gitiles commit info from properties and tags
[06:48:11.425] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[06:48:11.426] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[06:48:11.426] Buildbucket request:
{
"requestId": "8841480143570430320",
"builder": {
"project": "pigweed",
"bucket": "openprot.ci",
"builder": "openprot-ast10x0-qemu"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8841480143570430320",
"job": "pigweed/openprot.ci-openprot-ast10x0-qemu",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/upstream/main",
"repo": "https://pigweed.googlesource.com/third_party/github/OpenPRoT/openprot",
"revision": "5f5783ef6b819fbeb823765dc70bbc2cf1f2eb24"
},
"id": "https://pigweed.googlesource.com/third_party/github/OpenPRoT/openprot/+/refs/heads/upstream/main@5f5783ef6b819fbeb823765dc70bbc2cf1f2eb24",
"title": "5f5783ef6b819fbeb823765dc70bbc2cf1f2eb24",
"url": "https://pigweed.googlesource.com/third_party/github/OpenPRoT/openprot/+/5f5783ef6b819fbeb823765dc70bbc2cf1f2eb24"
},
{
"gitiles": {
"ref": "refs/heads/upstream/main",
"repo": "https://pigweed.googlesource.com/third_party/github/OpenPRoT/openprot",
"revision": "f2cda8cb93cd7d75de4df907b14610ff842cab3a"
},
"id": "https://pigweed.googlesource.com/third_party/github/OpenPRoT/openprot/+/refs/heads/upstream/main@f2cda8cb93cd7d75de4df907b14610ff842cab3a",
"title": "f2cda8cb93cd7d75de4df907b14610ff842cab3a",
"url": "https://pigweed.googlesource.com/third_party/github/OpenPRoT/openprot/+/f2cda8cb93cd7d75de4df907b14610ff842cab3a"
},
{
"gitiles": {
"ref": "refs/heads/upstream/main",
"repo": "https://pigweed.googlesource.com/third_party/github/OpenPRoT/openprot",
"revision": "e3346f9585980f0474eb0f848f44af858f433b02"
},
"id": "https://pigweed.googlesource.com/third_party/github/OpenPRoT/openprot/+/refs/heads/upstream/main@e3346f9585980f0474eb0f848f44af858f433b02",
"title": "e3346f9585980f0474eb0f848f44af858f433b02",
"url": "https://pigweed.googlesource.com/third_party/github/OpenPRoT/openprot/+/e3346f9585980f0474eb0f848f44af858f433b02"
},
{
"gitiles": {
"ref": "refs/heads/upstream/main",
"repo": "https://pigweed.googlesource.com/third_party/github/OpenPRoT/openprot",
"revision": "02d89f0b57dc52079708c555e8ecfd310dbfb869"
},
"id": "https://pigweed.googlesource.com/third_party/github/OpenPRoT/openprot/+/refs/heads/upstream/main@02d89f0b57dc52079708c555e8ecfd310dbfb869",
"title": "02d89f0b57dc52079708c555e8ecfd310dbfb869",
"url": "https://pigweed.googlesource.com/third_party/github/OpenPRoT/openprot/+/02d89f0b57dc52079708c555e8ecfd310dbfb869"
},
{
"gitiles": {
"ref": "refs/heads/upstream/main",
"repo": "https://pigweed.googlesource.com/third_party/github/OpenPRoT/openprot",
"revision": "981d74b5d64562600f4f9448da0e81002b642789"
},
"id": "https://pigweed.googlesource.com/third_party/github/OpenPRoT/openprot/+/refs/heads/upstream/main@981d74b5d64562600f4f9448da0e81002b642789",
"title": "981d74b5d64562600f4f9448da0e81002b642789",
"url": "https://pigweed.googlesource.com/third_party/github/OpenPRoT/openprot/+/981d74b5d64562600f4f9448da0e81002b642789"
},
{
"gitiles": {
"ref": "refs/heads/upstream/main",
"repo": "https://pigweed.googlesource.com/third_party/github/OpenPRoT/openprot",
"revision": "f3ea573c3b46992f6520568f8bf149478a7aeb2b"
},
"id": "https://pigweed.googlesource.com/third_party/github/OpenPRoT/openprot/+/refs/heads/upstream/main@f3ea573c3b46992f6520568f8bf149478a7aeb2b",
"title": "f3ea573c3b46992f6520568f8bf149478a7aeb2b",
"url": "https://pigweed.googlesource.com/third_party/github/OpenPRoT/openprot/+/f3ea573c3b46992f6520568f8bf149478a7aeb2b"
},
{
"gitiles": {
"ref": "refs/heads/upstream/main",
"repo": "https://pigweed.googlesource.com/third_party/github/OpenPRoT/openprot",
"revision": "4f7c9c149de5b22bdd0209a07a6eb24d8efd304b"
},
"id": "https://pigweed.googlesource.com/third_party/github/OpenPRoT/openprot/+/refs/heads/upstream/main@4f7c9c149de5b22bdd0209a07a6eb24d8efd304b",
"title": "4f7c9c149de5b22bdd0209a07a6eb24d8efd304b",
"url": "https://pigweed.googlesource.com/third_party/github/OpenPRoT/openprot/+/4f7c9c149de5b22bdd0209a07a6eb24d8efd304b"
},
{
"gitiles": {
"ref": "refs/heads/upstream/main",
"repo": "https://pigweed.googlesource.com/third_party/github/OpenPRoT/openprot",
"revision": "a402f48ba9e9571cc36b313de6dc423fd91a82e5"
},
"id": "https://pigweed.googlesource.com/third_party/github/OpenPRoT/openprot/+/refs/heads/upstream/main@a402f48ba9e9571cc36b313de6dc423fd91a82e5",
"title": "a402f48ba9e9571cc36b313de6dc423fd91a82e5",
"url": "https://pigweed.googlesource.com/third_party/github/OpenPRoT/openprot/+/a402f48ba9e9571cc36b313de6dc423fd91a82e5"
},
{
"gitiles": {
"ref": "refs/heads/upstream/main",
"repo": "https://pigweed.googlesource.com/third_party/github/OpenPRoT/openprot",
"revision": "46998483962348586f73ccd8523a3bd41aec6bb8"
},
"id": "https://pigweed.googlesource.com/third_party/github/OpenPRoT/openprot/+/refs/heads/upstream/main@46998483962348586f73ccd8523a3bd41aec6bb8",
"title": "46998483962348586f73ccd8523a3bd41aec6bb8",
"url": "https://pigweed.googlesource.com/third_party/github/OpenPRoT/openprot/+/46998483962348586f73ccd8523a3bd41aec6bb8"
},
{
"gitiles": {
"ref": "refs/heads/upstream/main",
"repo": "https://pigweed.googlesource.com/third_party/github/OpenPRoT/openprot",
"revision": "621955baf464a86ccc688825ffa247d958a6794b"
},
"id": "https://pigweed.googlesource.com/third_party/github/OpenPRoT/openprot/+/refs/heads/upstream/main@621955baf464a86ccc688825ffa247d958a6794b",
"title": "621955baf464a86ccc688825ffa247d958a6794b",
"url": "https://pigweed.googlesource.com/third_party/github/OpenPRoT/openprot/+/621955baf464a86ccc688825ffa247d958a6794b"
},
{
"gitiles": {
"ref": "refs/heads/upstream/main",
"repo": "https://pigweed.googlesource.com/third_party/github/OpenPRoT/openprot",
"revision": "9d84f13662f62163fff81cbfce0fd0e7242c7b6c"
},
"id": "https://pigweed.googlesource.com/third_party/github/OpenPRoT/openprot/+/refs/heads/upstream/main@9d84f13662f62163fff81cbfce0fd0e7242c7b6c",
"title": "9d84f13662f62163fff81cbfce0fd0e7242c7b6c",
"url": "https://pigweed.googlesource.com/third_party/github/OpenPRoT/openprot/+/9d84f13662f62163fff81cbfce0fd0e7242c7b6c"
},
{
"gitiles": {
"ref": "refs/heads/upstream/main",
"repo": "https://pigweed.googlesource.com/third_party/github/OpenPRoT/openprot",
"revision": "4c96cbbb6ac3df8f878c1d41ba626fe764c80fc0"
},
"id": "https://pigweed.googlesource.com/third_party/github/OpenPRoT/openprot/+/refs/heads/upstream/main@4c96cbbb6ac3df8f878c1d41ba626fe764c80fc0",
"title": "4c96cbbb6ac3df8f878c1d41ba626fe764c80fc0",
"url": "https://pigweed.googlesource.com/third_party/github/OpenPRoT/openprot/+/4c96cbbb6ac3df8f878c1d41ba626fe764c80fc0"
},
{
"gitiles": {
"ref": "refs/heads/upstream/main",
"repo": "https://pigweed.googlesource.com/third_party/github/OpenPRoT/openprot",
"revision": "36a95f4c0142bd7782080074e938babb40743ce1"
},
"id": "https://pigweed.googlesource.com/third_party/github/OpenPRoT/openprot/+/refs/heads/upstream/main@36a95f4c0142bd7782080074e938babb40743ce1",
"title": "36a95f4c0142bd7782080074e938babb40743ce1",
"url": "https://pigweed.googlesource.com/third_party/github/OpenPRoT/openprot/+/36a95f4c0142bd7782080074e938babb40743ce1"
}
]
}
},
"gitilesCommit": {
"host": "pigweed.googlesource.com",
"project": "third_party/github/OpenPRoT/openprot",
"id": "36a95f4c0142bd7782080074e938babb40743ce1",
"ref": "refs/heads/upstream/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8841480143570430320"
},
{
"key": "scheduler_job_id",
"value": "pigweed/openprot.ci-openprot-ast10x0-qemu"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[06:48:11.796] Scheduled build:
{
"id": "8676050081641002161",
"builder": {
"project": "pigweed",
"bucket": "openprot.ci",
"builder": "openprot-ast10x0-qemu"
},
"createdBy": "project:pigweed",
"createTime": "2026-07-17T06:48:11.492575620Z",
"updateTime": "2026-07-17T06:48:11.492575620Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "pigweed.googlesource.com",
"project": "third_party/github/OpenPRoT/openprot",
"id": "36a95f4c0142bd7782080074e938babb40743ce1",
"ref": "refs/heads/upstream/main"
}
}
}
[06:48:11.797] Task URL: https://cr-buildbucket.appspot.com/build/8676050081641002161
[06:48:11.797] Scheduling timer "check-buildbucket-build-status" (pigweed/openprot.ci-openprot-ast10x0-qemu:8841480143570430320:2:0) after 5m29s
[06:48:15.665] Received PubSub notification, asking Buildbucket for the build status
[06:48:15.709] Build status: SCHEDULED
[06:53:40.932] Handling timer "check-buildbucket-build-status" (pigweed/openprot.ci-openprot-ast10x0-qemu:8841480143570430320:2:0)
[06:53:40.959] Build status: SCHEDULED
[06:53:40.959] Scheduling timer "check-buildbucket-build-status" (pigweed/openprot.ci-openprot-ast10x0-qemu:8841480143570430320:4:0) after 7m12s
[07:00:53.049] Handling timer "check-buildbucket-build-status" (pigweed/openprot.ci-openprot-ast10x0-qemu:8841480143570430320:4:0)
[07:00:53.124] Build status: SCHEDULED
[07:00:53.124] Scheduling timer "check-buildbucket-build-status" (pigweed/openprot.ci-openprot-ast10x0-qemu:8841480143570430320:5:0) after 1m21s
[07:02:14.122] Handling timer "check-buildbucket-build-status" (pigweed/openprot.ci-openprot-ast10x0-qemu:8841480143570430320:5:0)
[07:02:14.167] Build status: SCHEDULED
[07:02:14.168] Scheduling timer "check-buildbucket-build-status" (pigweed/openprot.ci-openprot-ast10x0-qemu:8841480143570430320:6:0) after 5m18s
[07:07:32.343] Handling timer "check-buildbucket-build-status" (pigweed/openprot.ci-openprot-ast10x0-qemu:8841480143570430320:6:0)
[07:07:32.369] Build status: SCHEDULED
[07:07:32.369] Scheduling timer "check-buildbucket-build-status" (pigweed/openprot.ci-openprot-ast10x0-qemu:8841480143570430320:7:0) after 2m39s
[07:10:11.438] Handling timer "check-buildbucket-build-status" (pigweed/openprot.ci-openprot-ast10x0-qemu:8841480143570430320:7:0)
[07:10:11.457] Build status: SCHEDULED
[07:10:11.457] Scheduling timer "check-buildbucket-build-status" (pigweed/openprot.ci-openprot-ast10x0-qemu:8841480143570430320:8:0) after 5m0s
[07:15:11.546] Handling timer "check-buildbucket-build-status" (pigweed/openprot.ci-openprot-ast10x0-qemu:8841480143570430320:8:0)
[07:15:11.616] Build status: SCHEDULED
[07:15:11.616] Scheduling timer "check-buildbucket-build-status" (pigweed/openprot.ci-openprot-ast10x0-qemu:8841480143570430320:9:0) after 9m32s
[07:24:43.632] Handling timer "check-buildbucket-build-status" (pigweed/openprot.ci-openprot-ast10x0-qemu:8841480143570430320:9:0)
[07:24:43.670] Build status: SCHEDULED
[07:24:43.670] Scheduling timer "check-buildbucket-build-status" (pigweed/openprot.ci-openprot-ast10x0-qemu:8841480143570430320:10:0) after 8m47s
[07:33:30.768] Handling timer "check-buildbucket-build-status" (pigweed/openprot.ci-openprot-ast10x0-qemu:8841480143570430320:10:0)
[07:33:30.793] Build status: SCHEDULED
[07:33:30.793] Scheduling timer "check-buildbucket-build-status" (pigweed/openprot.ci-openprot-ast10x0-qemu:8841480143570430320:11:0) after 1m46s
[07:35:16.818] Handling timer "check-buildbucket-build-status" (pigweed/openprot.ci-openprot-ast10x0-qemu:8841480143570430320:11:0)
[07:35:16.886] Build status: SCHEDULED
[07:35:16.886] Scheduling timer "check-buildbucket-build-status" (pigweed/openprot.ci-openprot-ast10x0-qemu:8841480143570430320:12:0) after 3m21s
[07:38:37.925] Handling timer "check-buildbucket-build-status" (pigweed/openprot.ci-openprot-ast10x0-qemu:8841480143570430320:12:0)
[07:38:37.951] Build status: SCHEDULED
[07:38:37.951] Scheduling timer "check-buildbucket-build-status" (pigweed/openprot.ci-openprot-ast10x0-qemu:8841480143570430320:13:0) after 2m17s
[07:40:54.970] Handling timer "check-buildbucket-build-status" (pigweed/openprot.ci-openprot-ast10x0-qemu:8841480143570430320:13:0)
[07:40:55.047] Build status: SCHEDULED
[07:40:55.047] Scheduling timer "check-buildbucket-build-status" (pigweed/openprot.ci-openprot-ast10x0-qemu:8841480143570430320:14:0) after 7m25s
[07:48:20.067] Handling timer "check-buildbucket-build-status" (pigweed/openprot.ci-openprot-ast10x0-qemu:8841480143570430320:14:0)
[07:48:20.089] Build status: SCHEDULED
[07:48:20.089] Scheduling timer "check-buildbucket-build-status" (pigweed/openprot.ci-openprot-ast10x0-qemu:8841480143570430320:15:0) after 5m33s
[07:53:53.133] Handling timer "check-buildbucket-build-status" (pigweed/openprot.ci-openprot-ast10x0-qemu:8841480143570430320:15:0)
[07:53:53.154] Build status: SCHEDULED
[07:53:53.154] Scheduling timer "check-buildbucket-build-status" (pigweed/openprot.ci-openprot-ast10x0-qemu:8841480143570430320:16:0) after 1m8s
[07:55:01.248] Handling timer "check-buildbucket-build-status" (pigweed/openprot.ci-openprot-ast10x0-qemu:8841480143570430320:16:0)
[07:55:01.265] Build status: SCHEDULED
[07:55:01.265] Scheduling timer "check-buildbucket-build-status" (pigweed/openprot.ci-openprot-ast10x0-qemu:8841480143570430320:17:0) after 1m55s
[07:56:56.295] Handling timer "check-buildbucket-build-status" (pigweed/openprot.ci-openprot-ast10x0-qemu:8841480143570430320:17:0)
[07:56:56.315] Build status: SCHEDULED
[07:56:56.315] Scheduling timer "check-buildbucket-build-status" (pigweed/openprot.ci-openprot-ast10x0-qemu:8841480143570430320:18:0) after 4m35s
[08:01:31.365] Handling timer "check-buildbucket-build-status" (pigweed/openprot.ci-openprot-ast10x0-qemu:8841480143570430320:18:0)
[08:01:31.383] Build status: SCHEDULED
[08:01:31.383] Scheduling timer "check-buildbucket-build-status" (pigweed/openprot.ci-openprot-ast10x0-qemu:8841480143570430320:19:0) after 3m47s
[08:05:18.366] Handling timer "check-buildbucket-build-status" (pigweed/openprot.ci-openprot-ast10x0-qemu:8841480143570430320:19:0)
[08:05:18.383] Build status: SCHEDULED
[08:05:18.383] Scheduling timer "check-buildbucket-build-status" (pigweed/openprot.ci-openprot-ast10x0-qemu:8841480143570430320:20:0) after 6m55s
[08:07:50.483] Received PubSub notification, asking Buildbucket for the build status
[08:07:50.500] Build status: STARTED
[08:12:13.405] Handling timer "check-buildbucket-build-status" (pigweed/openprot.ci-openprot-ast10x0-qemu:8841480143570430320:20:0)
[08:12:13.445] Build status: STARTED
[08:12:13.445] Scheduling timer "check-buildbucket-build-status" (pigweed/openprot.ci-openprot-ast10x0-qemu:8841480143570430320:22:0) after 6m23s
[08:18:36.557] Handling timer "check-buildbucket-build-status" (pigweed/openprot.ci-openprot-ast10x0-qemu:8841480143570430320:22:0)
[08:18:36.586] Build status: STARTED
[08:18:36.586] Scheduling timer "check-buildbucket-build-status" (pigweed/openprot.ci-openprot-ast10x0-qemu:8841480143570430320:23:0) after 6m26s
[08:25:02.684] Handling timer "check-buildbucket-build-status" (pigweed/openprot.ci-openprot-ast10x0-qemu:8841480143570430320:23:0)
[08:25:02.701] Build status: STARTED
[08:25:02.702] Scheduling timer "check-buildbucket-build-status" (pigweed/openprot.ci-openprot-ast10x0-qemu:8841480143570430320:24:0) after 5m26s
[08:30:28.795] Handling timer "check-buildbucket-build-status" (pigweed/openprot.ci-openprot-ast10x0-qemu:8841480143570430320:24:0)
[08:30:28.816] Build status: STARTED
[08:30:28.816] Scheduling timer "check-buildbucket-build-status" (pigweed/openprot.ci-openprot-ast10x0-qemu:8841480143570430320:25:0) after 7m2s
[08:37:31.148] Handling timer "check-buildbucket-build-status" (pigweed/openprot.ci-openprot-ast10x0-qemu:8841480143570430320:25:0)
[08:37:31.171] Build status: STARTED
[08:37:31.171] Scheduling timer "check-buildbucket-build-status" (pigweed/openprot.ci-openprot-ast10x0-qemu:8841480143570430320:26:0) after 8m59s
[08:46:30.300] Handling timer "check-buildbucket-build-status" (pigweed/openprot.ci-openprot-ast10x0-qemu:8841480143570430320:26:0)
[08:46:30.337] Build status: STARTED
[08:46:30.337] Scheduling timer "check-buildbucket-build-status" (pigweed/openprot.ci-openprot-ast10x0-qemu:8841480143570430320:27:0) after 8m10s
[08:54:40.385] Handling timer "check-buildbucket-build-status" (pigweed/openprot.ci-openprot-ast10x0-qemu:8841480143570430320:27:0)
[08:54:40.402] Build status: STARTED
[08:54:40.402] Scheduling timer "check-buildbucket-build-status" (pigweed/openprot.ci-openprot-ast10x0-qemu:8841480143570430320:28:0) after 9m27s
[09:04:07.469] Handling timer "check-buildbucket-build-status" (pigweed/openprot.ci-openprot-ast10x0-qemu:8841480143570430320:28:0)
[09:04:07.486] Build status: STARTED
[09:04:07.486] Scheduling timer "check-buildbucket-build-status" (pigweed/openprot.ci-openprot-ast10x0-qemu:8841480143570430320:29:0) after 4m12s
[09:08:19.534] Handling timer "check-buildbucket-build-status" (pigweed/openprot.ci-openprot-ast10x0-qemu:8841480143570430320:29:0)
[09:08:19.774] Build status: STARTED
[09:08:19.774] Scheduling timer "check-buildbucket-build-status" (pigweed/openprot.ci-openprot-ast10x0-qemu:8841480143570430320:30:0) after 4m58s
[09:13:17.795] Handling timer "check-buildbucket-build-status" (pigweed/openprot.ci-openprot-ast10x0-qemu:8841480143570430320:30:0)
[09:13:17.825] Build status: STARTED
[09:13:17.825] Scheduling timer "check-buildbucket-build-status" (pigweed/openprot.ci-openprot-ast10x0-qemu:8841480143570430320:31:0) after 5m10s
[09:14:33.115] Received PubSub notification, asking Buildbucket for the build status
[09:14:33.159] Build:
{
"id": "8676050081641002161",
"builder": {
"project": "pigweed",
"bucket": "openprot.ci",
"builder": "openprot-ast10x0-qemu"
},
"createdBy": "project:pigweed",
"createTime": "2026-07-17T06:48:11.492575620Z",
"startTime": "2026-07-17T08:07:50.320415847Z",
"endTime": "2026-07-17T09:14:31.871142766Z",
"updateTime": "2026-07-17T09:14:31.871142766Z",
"status": "FAILURE",
"input": {
"gitilesCommit": {
"host": "pigweed.googlesource.com",
"project": "third_party/github/OpenPRoT/openprot",
"id": "36a95f4c0142bd7782080074e938babb40743ce1",
"ref": "refs/heads/upstream/main"
}
}
}
[09:14:33.159] Invocation finished in 2h26m22.890842826s with status FAILED