[22:15:00.938] New invocation is queued and will start shortly
[22:15:02.025] Starting the invocation (attempt 1)
[22:15:02.057] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/c5c394a60d716c2bba007dc3427fa1c8ef03e9f3"
[22:15:02.057] Popped gitiles commit info from properties and tags
[22:15:02.057] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[22:15:02.057] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[22:15:02.057] Buildbucket request:
{
"requestId": "8836076610544666352",
"builder": {
"project": "chromium-m153",
"bucket": "ci",
"builder": "chromeos-amd64-generic-rel"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8836076610544666352",
"job": "chromium-m153/chromeos-amd64-generic-rel",
"triggers": [
{
"gitiles": {
"ref": "refs/branch-heads/8010",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "415ed38aeb335bec0ae0bf990052ccc8837edbcf"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/8010@415ed38aeb335bec0ae0bf990052ccc8837edbcf",
"title": "415ed38aeb335bec0ae0bf990052ccc8837edbcf",
"url": "https://chromium.googlesource.com/chromium/src/+/415ed38aeb335bec0ae0bf990052ccc8837edbcf"
},
{
"gitiles": {
"ref": "refs/branch-heads/8010",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "e78dd4d022ea49bd1fa8f71556154085cd7638ac"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/8010@e78dd4d022ea49bd1fa8f71556154085cd7638ac",
"title": "e78dd4d022ea49bd1fa8f71556154085cd7638ac",
"url": "https://chromium.googlesource.com/chromium/src/+/e78dd4d022ea49bd1fa8f71556154085cd7638ac"
},
{
"gitiles": {
"ref": "refs/branch-heads/8010",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "deecf95b0c0a2448136253298149fbc74d88e732"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/8010@deecf95b0c0a2448136253298149fbc74d88e732",
"title": "deecf95b0c0a2448136253298149fbc74d88e732",
"url": "https://chromium.googlesource.com/chromium/src/+/deecf95b0c0a2448136253298149fbc74d88e732"
},
{
"gitiles": {
"ref": "refs/branch-heads/8010",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "0de35d9225a79295d998eb087e7184273434c85e"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/8010@0de35d9225a79295d998eb087e7184273434c85e",
"title": "0de35d9225a79295d998eb087e7184273434c85e",
"url": "https://chromium.googlesource.com/chromium/src/+/0de35d9225a79295d998eb087e7184273434c85e"
},
{
"gitiles": {
"ref": "refs/branch-heads/8010",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "643610e9d853c5ff3822f957244d23362ee85379"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/8010@643610e9d853c5ff3822f957244d23362ee85379",
"title": "643610e9d853c5ff3822f957244d23362ee85379",
"url": "https://chromium.googlesource.com/chromium/src/+/643610e9d853c5ff3822f957244d23362ee85379"
},
{
"gitiles": {
"ref": "refs/branch-heads/8010",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "7085cc5c0fa2cd23b9c5d2f85668ceddcb3195b9"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/8010@7085cc5c0fa2cd23b9c5d2f85668ceddcb3195b9",
"title": "7085cc5c0fa2cd23b9c5d2f85668ceddcb3195b9",
"url": "https://chromium.googlesource.com/chromium/src/+/7085cc5c0fa2cd23b9c5d2f85668ceddcb3195b9"
},
{
"gitiles": {
"ref": "refs/branch-heads/8010",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "d1595c8c4bd7b5071ed789749fd258f431e5f840"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/8010@d1595c8c4bd7b5071ed789749fd258f431e5f840",
"title": "d1595c8c4bd7b5071ed789749fd258f431e5f840",
"url": "https://chromium.googlesource.com/chromium/src/+/d1595c8c4bd7b5071ed789749fd258f431e5f840"
},
{
"gitiles": {
"ref": "refs/branch-heads/8010",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "c5c394a60d716c2bba007dc3427fa1c8ef03e9f3"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/8010@c5c394a60d716c2bba007dc3427fa1c8ef03e9f3",
"title": "c5c394a60d716c2bba007dc3427fa1c8ef03e9f3",
"url": "https://chromium.googlesource.com/chromium/src/+/c5c394a60d716c2bba007dc3427fa1c8ef03e9f3"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "c5c394a60d716c2bba007dc3427fa1c8ef03e9f3",
"ref": "refs/branch-heads/8010"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8836076610544666352"
},
{
"key": "scheduler_job_id",
"value": "chromium-m153/chromeos-amd64-generic-rel"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[22:15:02.662] Scheduled build:
{
"id": "8670646548634349025",
"builder": {
"project": "chromium-m153",
"bucket": "ci",
"builder": "chromeos-amd64-generic-rel"
},
"number": 846,
"createdBy": "project:chromium-m153",
"createTime": "2026-09-14T22:15:02.138938853Z",
"updateTime": "2026-09-14T22:15:02.138938853Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "c5c394a60d716c2bba007dc3427fa1c8ef03e9f3",
"ref": "refs/branch-heads/8010"
}
}
}
[22:15:02.662] Task URL: https://cr-buildbucket.appspot.com/build/8670646548634349025
[22:15:02.662] Scheduling timer "check-buildbucket-build-status" (chromium-m153/chromeos-amd64-generic-rel:8836076610544666352:2:0) after 2m36s
[22:15:02.755] Received PubSub notification, asking Buildbucket for the build status
[22:15:03.048] Build status: SCHEDULED
[22:15:13.789] Received PubSub notification, asking Buildbucket for the build status
[22:15:13.811] Build status: STARTED
[22:17:38.719] Handling timer "check-buildbucket-build-status" (chromium-m153/chromeos-amd64-generic-rel:8836076610544666352:2:0)
[22:17:38.739] Build status: STARTED
[22:17:38.740] Scheduling timer "check-buildbucket-build-status" (chromium-m153/chromeos-amd64-generic-rel:8836076610544666352:5:0) after 7m39s
[22:25:17.731] Handling timer "check-buildbucket-build-status" (chromium-m153/chromeos-amd64-generic-rel:8836076610544666352:5:0)
[22:25:17.750] Build status: STARTED
[22:25:17.751] Scheduling timer "check-buildbucket-build-status" (chromium-m153/chromeos-amd64-generic-rel:8836076610544666352:6:0) after 2m42s
[22:27:59.765] Handling timer "check-buildbucket-build-status" (chromium-m153/chromeos-amd64-generic-rel:8836076610544666352:6:0)
[22:27:59.785] Build status: STARTED
[22:27:59.785] Scheduling timer "check-buildbucket-build-status" (chromium-m153/chromeos-amd64-generic-rel:8836076610544666352:7:0) after 2m17s
[22:30:16.857] Handling timer "check-buildbucket-build-status" (chromium-m153/chromeos-amd64-generic-rel:8836076610544666352:7:0)
[22:30:16.884] Build status: STARTED
[22:30:16.884] Scheduling timer "check-buildbucket-build-status" (chromium-m153/chromeos-amd64-generic-rel:8836076610544666352:8:0) after 2m14s
[22:32:30.885] Handling timer "check-buildbucket-build-status" (chromium-m153/chromeos-amd64-generic-rel:8836076610544666352:8:0)
[22:32:30.943] Build status: STARTED
[22:32:30.943] Scheduling timer "check-buildbucket-build-status" (chromium-m153/chromeos-amd64-generic-rel:8836076610544666352:9:0) after 6m0s
[22:38:01.841] Received PubSub notification, asking Buildbucket for the build status
[22:38:01.869] Build:
{
"id": "8670646548634349025",
"builder": {
"project": "chromium-m153",
"bucket": "ci",
"builder": "chromeos-amd64-generic-rel"
},
"number": 846,
"createdBy": "project:chromium-m153",
"createTime": "2026-09-14T22:15:02.138938853Z",
"startTime": "2026-09-14T22:15:13.512705568Z",
"endTime": "2026-09-14T22:38:01.312452891Z",
"updateTime": "2026-09-14T22:38:01.312452891Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "c5c394a60d716c2bba007dc3427fa1c8ef03e9f3",
"ref": "refs/branch-heads/8010"
}
}
}
[22:38:01.869] Invocation finished in 23m0.936462478s with status SUCCEEDED