[15:20:57.149] New invocation is queued and will start shortly
[15:20:58.242] Starting the invocation (attempt 1)
[15:20:58.268] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/1e5a2417c71d7d4c73e59f31ecee397feeec4e4b"
[15:20:58.268] Popped gitiles commit info from properties and tags
[15:20:58.268] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[15:20:58.268] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[15:20:58.268] Buildbucket request:
{
"requestId": "8837733406544222176",
"builder": {
"project": "chromium-m153",
"bucket": "ci",
"builder": "win-official"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8837733406544222176",
"job": "chromium-m153/win-official",
"triggers": [
{
"gitiles": {
"ref": "refs/branch-heads/8010",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "3f4c5118e806cec761442821c8f1d8f8c4e29e25"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/8010@3f4c5118e806cec761442821c8f1d8f8c4e29e25",
"title": "3f4c5118e806cec761442821c8f1d8f8c4e29e25",
"url": "https://chromium.googlesource.com/chromium/src/+/3f4c5118e806cec761442821c8f1d8f8c4e29e25"
},
{
"gitiles": {
"ref": "refs/branch-heads/8010",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "1a7b3bbfe020d88cd6c61f8d3977be0d8793c338"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/8010@1a7b3bbfe020d88cd6c61f8d3977be0d8793c338",
"title": "1a7b3bbfe020d88cd6c61f8d3977be0d8793c338",
"url": "https://chromium.googlesource.com/chromium/src/+/1a7b3bbfe020d88cd6c61f8d3977be0d8793c338"
},
{
"gitiles": {
"ref": "refs/branch-heads/8010",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "d5050414bb510efd5553d532fe69dcd87dbd1fbd"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/8010@d5050414bb510efd5553d532fe69dcd87dbd1fbd",
"title": "d5050414bb510efd5553d532fe69dcd87dbd1fbd",
"url": "https://chromium.googlesource.com/chromium/src/+/d5050414bb510efd5553d532fe69dcd87dbd1fbd"
},
{
"gitiles": {
"ref": "refs/branch-heads/8010",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "cb19cf284e177525dc9c5de32184c67fb26141c2"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/8010@cb19cf284e177525dc9c5de32184c67fb26141c2",
"title": "cb19cf284e177525dc9c5de32184c67fb26141c2",
"url": "https://chromium.googlesource.com/chromium/src/+/cb19cf284e177525dc9c5de32184c67fb26141c2"
},
{
"gitiles": {
"ref": "refs/branch-heads/8010",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "817b6c4dadb5d948a16cb901c64d069b730122d3"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/8010@817b6c4dadb5d948a16cb901c64d069b730122d3",
"title": "817b6c4dadb5d948a16cb901c64d069b730122d3",
"url": "https://chromium.googlesource.com/chromium/src/+/817b6c4dadb5d948a16cb901c64d069b730122d3"
},
{
"gitiles": {
"ref": "refs/branch-heads/8010",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "414d0ae6ab601cbe5c516d53860bc71f1c6a7737"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/8010@414d0ae6ab601cbe5c516d53860bc71f1c6a7737",
"title": "414d0ae6ab601cbe5c516d53860bc71f1c6a7737",
"url": "https://chromium.googlesource.com/chromium/src/+/414d0ae6ab601cbe5c516d53860bc71f1c6a7737"
},
{
"gitiles": {
"ref": "refs/branch-heads/8010",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "04330402defca1ab9f88a34041b180a6388676c2"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/8010@04330402defca1ab9f88a34041b180a6388676c2",
"title": "04330402defca1ab9f88a34041b180a6388676c2",
"url": "https://chromium.googlesource.com/chromium/src/+/04330402defca1ab9f88a34041b180a6388676c2"
},
{
"gitiles": {
"ref": "refs/branch-heads/8010",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "207e4110e38291f60e29747281170ac5c0ff7516"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/8010@207e4110e38291f60e29747281170ac5c0ff7516",
"title": "207e4110e38291f60e29747281170ac5c0ff7516",
"url": "https://chromium.googlesource.com/chromium/src/+/207e4110e38291f60e29747281170ac5c0ff7516"
},
{
"gitiles": {
"ref": "refs/branch-heads/8010",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "6f0b055315bde4af98ede2610e17644326b4a194"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/8010@6f0b055315bde4af98ede2610e17644326b4a194",
"title": "6f0b055315bde4af98ede2610e17644326b4a194",
"url": "https://chromium.googlesource.com/chromium/src/+/6f0b055315bde4af98ede2610e17644326b4a194"
},
{
"gitiles": {
"ref": "refs/branch-heads/8010",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "1c87193c6a38a9d70197cd194d99bf9a1d1f7998"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/8010@1c87193c6a38a9d70197cd194d99bf9a1d1f7998",
"title": "1c87193c6a38a9d70197cd194d99bf9a1d1f7998",
"url": "https://chromium.googlesource.com/chromium/src/+/1c87193c6a38a9d70197cd194d99bf9a1d1f7998"
},
{
"gitiles": {
"ref": "refs/branch-heads/8010",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "0b90463d0d263dc4c88dc6dd183fe4f788f44bb2"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/8010@0b90463d0d263dc4c88dc6dd183fe4f788f44bb2",
"title": "0b90463d0d263dc4c88dc6dd183fe4f788f44bb2",
"url": "https://chromium.googlesource.com/chromium/src/+/0b90463d0d263dc4c88dc6dd183fe4f788f44bb2"
},
{
"gitiles": {
"ref": "refs/branch-heads/8010",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "e22937c6469e98c3eb0bd63c18b33cfe55d09214"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/8010@e22937c6469e98c3eb0bd63c18b33cfe55d09214",
"title": "e22937c6469e98c3eb0bd63c18b33cfe55d09214",
"url": "https://chromium.googlesource.com/chromium/src/+/e22937c6469e98c3eb0bd63c18b33cfe55d09214"
},
{
"gitiles": {
"ref": "refs/branch-heads/8010",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "1e5a2417c71d7d4c73e59f31ecee397feeec4e4b"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/8010@1e5a2417c71d7d4c73e59f31ecee397feeec4e4b",
"title": "1e5a2417c71d7d4c73e59f31ecee397feeec4e4b",
"url": "https://chromium.googlesource.com/chromium/src/+/1e5a2417c71d7d4c73e59f31ecee397feeec4e4b"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "1e5a2417c71d7d4c73e59f31ecee397feeec4e4b",
"ref": "refs/branch-heads/8010"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8837733406544222176"
},
{
"key": "scheduler_job_id",
"value": "chromium-m153/win-official"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[15:20:58.914] Scheduled build:
{
"id": "8672303344621491937",
"builder": {
"project": "chromium-m153",
"bucket": "ci",
"builder": "win-official"
},
"number": 49,
"createdBy": "project:chromium-m153",
"createTime": "2026-08-27T15:20:58.358983845Z",
"updateTime": "2026-08-27T15:20:58.358983845Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "1e5a2417c71d7d4c73e59f31ecee397feeec4e4b",
"ref": "refs/branch-heads/8010"
}
}
}
[15:20:58.914] Task URL: https://cr-buildbucket.appspot.com/build/8672303344621491937
[15:20:58.914] Scheduling timer "check-buildbucket-build-status" (chromium-m153/win-official:8837733406544222176:2:0) after 8m27s
[15:20:59.529] Received PubSub notification, asking Buildbucket for the build status
[15:20:59.687] Build status: SCHEDULED
[15:21:06.310] Received PubSub notification, asking Buildbucket for the build status
[15:21:06.337] Build status: STARTED
[15:29:25.958] Handling timer "check-buildbucket-build-status" (chromium-m153/win-official:8837733406544222176:2:0)
[15:29:25.977] Build status: STARTED
[15:29:25.977] Scheduling timer "check-buildbucket-build-status" (chromium-m153/win-official:8837733406544222176:5:0) after 6m51s
[15:36:17.005] Handling timer "check-buildbucket-build-status" (chromium-m153/win-official:8837733406544222176:5:0)
[15:36:17.025] Build status: STARTED
[15:36:17.025] Scheduling timer "check-buildbucket-build-status" (chromium-m153/win-official:8837733406544222176:6:0) after 3m9s
[15:39:26.042] Handling timer "check-buildbucket-build-status" (chromium-m153/win-official:8837733406544222176:6:0)
[15:39:26.072] Build status: STARTED
[15:39:26.072] Scheduling timer "check-buildbucket-build-status" (chromium-m153/win-official:8837733406544222176:7:0) after 1m51s
[15:41:17.053] Handling timer "check-buildbucket-build-status" (chromium-m153/win-official:8837733406544222176:7:0)
[15:41:17.072] Build status: STARTED
[15:41:17.072] Scheduling timer "check-buildbucket-build-status" (chromium-m153/win-official:8837733406544222176:8:0) after 3m29s
[15:44:46.246] Handling timer "check-buildbucket-build-status" (chromium-m153/win-official:8837733406544222176:8:0)
[15:44:46.277] Build status: STARTED
[15:44:46.277] Scheduling timer "check-buildbucket-build-status" (chromium-m153/win-official:8837733406544222176:9:0) after 1m45s
[15:46:31.294] Handling timer "check-buildbucket-build-status" (chromium-m153/win-official:8837733406544222176:9:0)
[15:46:31.321] Build status: STARTED
[15:46:31.321] Scheduling timer "check-buildbucket-build-status" (chromium-m153/win-official:8837733406544222176:10:0) after 7m59s
[15:54:30.610] Handling timer "check-buildbucket-build-status" (chromium-m153/win-official:8837733406544222176:10:0)
[15:54:30.671] Build status: STARTED
[15:54:30.671] Scheduling timer "check-buildbucket-build-status" (chromium-m153/win-official:8837733406544222176:11:0) after 7m10s
[16:01:40.645] Handling timer "check-buildbucket-build-status" (chromium-m153/win-official:8837733406544222176:11:0)
[16:01:40.666] Build status: STARTED
[16:01:40.666] Scheduling timer "check-buildbucket-build-status" (chromium-m153/win-official:8837733406544222176:12:0) after 8m52s
[16:10:32.704] Handling timer "check-buildbucket-build-status" (chromium-m153/win-official:8837733406544222176:12:0)
[16:10:32.725] Build status: STARTED
[16:10:32.725] Scheduling timer "check-buildbucket-build-status" (chromium-m153/win-official:8837733406544222176:13:0) after 9m57s
[16:20:29.780] Handling timer "check-buildbucket-build-status" (chromium-m153/win-official:8837733406544222176:13:0)
[16:20:29.840] Build status: STARTED
[16:20:29.840] Scheduling timer "check-buildbucket-build-status" (chromium-m153/win-official:8837733406544222176:14:0) after 5m45s
[16:26:14.927] Handling timer "check-buildbucket-build-status" (chromium-m153/win-official:8837733406544222176:14:0)
[16:26:14.941] Build status: STARTED
[16:26:14.941] Scheduling timer "check-buildbucket-build-status" (chromium-m153/win-official:8837733406544222176:15:0) after 4m36s
[16:30:50.969] Handling timer "check-buildbucket-build-status" (chromium-m153/win-official:8837733406544222176:15:0)
[16:30:51.006] Build status: STARTED
[16:30:51.006] Scheduling timer "check-buildbucket-build-status" (chromium-m153/win-official:8837733406544222176:16:0) after 6m39s
[16:37:30.169] Handling timer "check-buildbucket-build-status" (chromium-m153/win-official:8837733406544222176:16:0)
[16:37:30.221] Build status: STARTED
[16:37:30.221] Scheduling timer "check-buildbucket-build-status" (chromium-m153/win-official:8837733406544222176:17:0) after 7m49s
[16:45:19.386] Handling timer "check-buildbucket-build-status" (chromium-m153/win-official:8837733406544222176:17:0)
[16:45:19.436] Build status: STARTED
[16:45:19.436] Scheduling timer "check-buildbucket-build-status" (chromium-m153/win-official:8837733406544222176:18:0) after 4m37s
[16:49:56.454] Handling timer "check-buildbucket-build-status" (chromium-m153/win-official:8837733406544222176:18:0)
[16:49:56.612] Build status: STARTED
[16:49:56.612] Scheduling timer "check-buildbucket-build-status" (chromium-m153/win-official:8837733406544222176:19:0) after 9m29s
[16:59:25.729] Handling timer "check-buildbucket-build-status" (chromium-m153/win-official:8837733406544222176:19:0)
[16:59:25.759] Build status: STARTED
[16:59:25.759] Scheduling timer "check-buildbucket-build-status" (chromium-m153/win-official:8837733406544222176:20:0) after 3m45s
[17:03:10.823] Handling timer "check-buildbucket-build-status" (chromium-m153/win-official:8837733406544222176:20:0)
[17:03:10.841] Build status: STARTED
[17:03:10.841] Scheduling timer "check-buildbucket-build-status" (chromium-m153/win-official:8837733406544222176:21:0) after 5m30s
[17:08:40.819] Handling timer "check-buildbucket-build-status" (chromium-m153/win-official:8837733406544222176:21:0)
[17:08:40.949] Build status: STARTED
[17:08:40.949] Scheduling timer "check-buildbucket-build-status" (chromium-m153/win-official:8837733406544222176:22:0) after 2m8s
[17:10:49.014] Handling timer "check-buildbucket-build-status" (chromium-m153/win-official:8837733406544222176:22:0)
[17:10:49.061] Build status: STARTED
[17:10:49.061] Scheduling timer "check-buildbucket-build-status" (chromium-m153/win-official:8837733406544222176:23:0) after 1m16s
[17:12:05.177] Handling timer "check-buildbucket-build-status" (chromium-m153/win-official:8837733406544222176:23:0)
[17:12:05.266] Build status: STARTED
[17:12:05.266] Scheduling timer "check-buildbucket-build-status" (chromium-m153/win-official:8837733406544222176:24:0) after 7m31s
[17:19:36.386] Handling timer "check-buildbucket-build-status" (chromium-m153/win-official:8837733406544222176:24:0)
[17:19:36.413] Build status: STARTED
[17:19:36.414] Scheduling timer "check-buildbucket-build-status" (chromium-m153/win-official:8837733406544222176:25:0) after 6m38s
[17:26:14.512] Handling timer "check-buildbucket-build-status" (chromium-m153/win-official:8837733406544222176:25:0)
[17:26:14.541] Build status: STARTED
[17:26:14.541] Scheduling timer "check-buildbucket-build-status" (chromium-m153/win-official:8837733406544222176:26:0) after 7m32s
[17:33:46.573] Handling timer "check-buildbucket-build-status" (chromium-m153/win-official:8837733406544222176:26:0)
[17:33:46.596] Build status: STARTED
[17:33:46.596] Scheduling timer "check-buildbucket-build-status" (chromium-m153/win-official:8837733406544222176:27:0) after 7m59s
[17:41:45.644] Handling timer "check-buildbucket-build-status" (chromium-m153/win-official:8837733406544222176:27:0)
[17:41:45.667] Build status: STARTED
[17:41:45.667] Scheduling timer "check-buildbucket-build-status" (chromium-m153/win-official:8837733406544222176:28:0) after 4m45s
[17:46:30.752] Handling timer "check-buildbucket-build-status" (chromium-m153/win-official:8837733406544222176:28:0)
[17:46:30.794] Build status: STARTED
[17:46:30.794] Scheduling timer "check-buildbucket-build-status" (chromium-m153/win-official:8837733406544222176:29:0) after 8m13s
[17:54:43.947] Handling timer "check-buildbucket-build-status" (chromium-m153/win-official:8837733406544222176:29:0)
[17:54:43.979] Build status: STARTED
[17:54:43.979] Scheduling timer "check-buildbucket-build-status" (chromium-m153/win-official:8837733406544222176:30:0) after 8m7s
[18:02:51.161] Handling timer "check-buildbucket-build-status" (chromium-m153/win-official:8837733406544222176:30:0)
[18:02:51.193] Build status: STARTED
[18:02:51.193] Scheduling timer "check-buildbucket-build-status" (chromium-m153/win-official:8837733406544222176:31:0) after 5m6s
[18:07:57.217] Handling timer "check-buildbucket-build-status" (chromium-m153/win-official:8837733406544222176:31:0)
[18:07:57.238] Build status: STARTED
[18:07:57.238] Scheduling timer "check-buildbucket-build-status" (chromium-m153/win-official:8837733406544222176:32:0) after 9m11s
[18:17:08.524] Handling timer "check-buildbucket-build-status" (chromium-m153/win-official:8837733406544222176:32:0)
[18:17:08.548] Build status: STARTED
[18:17:08.548] Scheduling timer "check-buildbucket-build-status" (chromium-m153/win-official:8837733406544222176:33:0) after 9m36s
[18:26:44.548] Handling timer "check-buildbucket-build-status" (chromium-m153/win-official:8837733406544222176:33:0)
[18:26:44.567] Build status: STARTED
[18:26:44.567] Scheduling timer "check-buildbucket-build-status" (chromium-m153/win-official:8837733406544222176:34:0) after 2m26s
[18:29:10.600] Handling timer "check-buildbucket-build-status" (chromium-m153/win-official:8837733406544222176:34:0)
[18:29:10.627] Build status: STARTED
[18:29:10.627] Scheduling timer "check-buildbucket-build-status" (chromium-m153/win-official:8837733406544222176:35:0) after 7m50s
[18:37:00.629] Handling timer "check-buildbucket-build-status" (chromium-m153/win-official:8837733406544222176:35:0)
[18:37:00.650] Build status: STARTED
[18:37:00.650] Scheduling timer "check-buildbucket-build-status" (chromium-m153/win-official:8837733406544222176:36:0) after 6m47s
[18:43:47.773] Handling timer "check-buildbucket-build-status" (chromium-m153/win-official:8837733406544222176:36:0)
[18:43:47.796] Build status: STARTED
[18:43:47.796] Scheduling timer "check-buildbucket-build-status" (chromium-m153/win-official:8837733406544222176:37:0) after 1m12s
[18:44:59.874] Handling timer "check-buildbucket-build-status" (chromium-m153/win-official:8837733406544222176:37:0)
[18:44:59.892] Build status: STARTED
[18:44:59.892] Scheduling timer "check-buildbucket-build-status" (chromium-m153/win-official:8837733406544222176:38:0) after 7m57s
[18:47:46.106] Received PubSub notification, asking Buildbucket for the build status
[18:47:46.127] Build:
{
"id": "8672303344621491937",
"builder": {
"project": "chromium-m153",
"bucket": "ci",
"builder": "win-official"
},
"number": 49,
"createdBy": "project:chromium-m153",
"createTime": "2026-08-27T15:20:58.358983845Z",
"startTime": "2026-08-27T15:21:05.831582119Z",
"endTime": "2026-08-27T18:47:45.332382131Z",
"updateTime": "2026-08-27T18:47:45.332382131Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "1e5a2417c71d7d4c73e59f31ecee397feeec4e4b",
"ref": "refs/branch-heads/8010"
}
}
}
[18:47:46.127] Invocation finished in 3h26m48.986222996s with status SUCCEEDED