[17:32:14.549] New invocation is queued and will start shortly
[17:32:15.682] Starting the invocation (attempt 1)
[17:32:15.712] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/39ea658589f89406f11f4cf55c07d6a2795b2cbe"
[17:32:15.712] Popped gitiles commit info from properties and tags
[17:32:15.712] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[17:32:15.713] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[17:32:15.713] Buildbucket request:
{
"requestId": "8851586482355699728",
"builder": {
"project": "chromium-m147",
"bucket": "ci",
"builder": "win-arm64-rel"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8851586482355699728",
"job": "chromium-m147/win-arm64-rel",
"triggers": [
{
"gitiles": {
"ref": "refs/branch-heads/7727",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "7281bd8caf759fcd3a48ef6de4d29fab578fe1e6"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7727@7281bd8caf759fcd3a48ef6de4d29fab578fe1e6",
"title": "7281bd8caf759fcd3a48ef6de4d29fab578fe1e6",
"url": "https://chromium.googlesource.com/chromium/src/+/7281bd8caf759fcd3a48ef6de4d29fab578fe1e6"
},
{
"gitiles": {
"ref": "refs/branch-heads/7727",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "39aa8de2dff8df90f81d3eb4430c69402d20c3f4"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7727@39aa8de2dff8df90f81d3eb4430c69402d20c3f4",
"title": "39aa8de2dff8df90f81d3eb4430c69402d20c3f4",
"url": "https://chromium.googlesource.com/chromium/src/+/39aa8de2dff8df90f81d3eb4430c69402d20c3f4"
},
{
"gitiles": {
"ref": "refs/branch-heads/7727",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "259aece361cca8be77017c063bda328787d5f904"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7727@259aece361cca8be77017c063bda328787d5f904",
"title": "259aece361cca8be77017c063bda328787d5f904",
"url": "https://chromium.googlesource.com/chromium/src/+/259aece361cca8be77017c063bda328787d5f904"
},
{
"gitiles": {
"ref": "refs/branch-heads/7727",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "1ede9b214a2049d30da47b84be535cf9951df48e"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7727@1ede9b214a2049d30da47b84be535cf9951df48e",
"title": "1ede9b214a2049d30da47b84be535cf9951df48e",
"url": "https://chromium.googlesource.com/chromium/src/+/1ede9b214a2049d30da47b84be535cf9951df48e"
},
{
"gitiles": {
"ref": "refs/branch-heads/7727",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "b14db777227d988a341ffe29692b022d11929145"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7727@b14db777227d988a341ffe29692b022d11929145",
"title": "b14db777227d988a341ffe29692b022d11929145",
"url": "https://chromium.googlesource.com/chromium/src/+/b14db777227d988a341ffe29692b022d11929145"
},
{
"gitiles": {
"ref": "refs/branch-heads/7727",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "a6a3320e9d8eae61ec13c61021dc7a070bc333e6"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7727@a6a3320e9d8eae61ec13c61021dc7a070bc333e6",
"title": "a6a3320e9d8eae61ec13c61021dc7a070bc333e6",
"url": "https://chromium.googlesource.com/chromium/src/+/a6a3320e9d8eae61ec13c61021dc7a070bc333e6"
},
{
"gitiles": {
"ref": "refs/branch-heads/7727",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "4dbcbd7907c323f12fa6d9ae2a2555678c8b819a"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7727@4dbcbd7907c323f12fa6d9ae2a2555678c8b819a",
"title": "4dbcbd7907c323f12fa6d9ae2a2555678c8b819a",
"url": "https://chromium.googlesource.com/chromium/src/+/4dbcbd7907c323f12fa6d9ae2a2555678c8b819a"
},
{
"gitiles": {
"ref": "refs/branch-heads/7727",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "2ce6cdcc8a0d859221a0768fb06ddc59ca3f89dc"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7727@2ce6cdcc8a0d859221a0768fb06ddc59ca3f89dc",
"title": "2ce6cdcc8a0d859221a0768fb06ddc59ca3f89dc",
"url": "https://chromium.googlesource.com/chromium/src/+/2ce6cdcc8a0d859221a0768fb06ddc59ca3f89dc"
},
{
"gitiles": {
"ref": "refs/branch-heads/7727",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "16689e48e9bd843c1f1750f97afc94c0f81b8d27"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7727@16689e48e9bd843c1f1750f97afc94c0f81b8d27",
"title": "16689e48e9bd843c1f1750f97afc94c0f81b8d27",
"url": "https://chromium.googlesource.com/chromium/src/+/16689e48e9bd843c1f1750f97afc94c0f81b8d27"
},
{
"gitiles": {
"ref": "refs/branch-heads/7727",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "d2a6aae18004f9111859bee38a81e857b794bbc7"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7727@d2a6aae18004f9111859bee38a81e857b794bbc7",
"title": "d2a6aae18004f9111859bee38a81e857b794bbc7",
"url": "https://chromium.googlesource.com/chromium/src/+/d2a6aae18004f9111859bee38a81e857b794bbc7"
},
{
"gitiles": {
"ref": "refs/branch-heads/7727",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "8395259a653d893eacc6f9e5e1c627e70fb9b1a1"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7727@8395259a653d893eacc6f9e5e1c627e70fb9b1a1",
"title": "8395259a653d893eacc6f9e5e1c627e70fb9b1a1",
"url": "https://chromium.googlesource.com/chromium/src/+/8395259a653d893eacc6f9e5e1c627e70fb9b1a1"
},
{
"gitiles": {
"ref": "refs/branch-heads/7727",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "7c16e8428036d07088c5b7ed3db64e1b7e04b701"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7727@7c16e8428036d07088c5b7ed3db64e1b7e04b701",
"title": "7c16e8428036d07088c5b7ed3db64e1b7e04b701",
"url": "https://chromium.googlesource.com/chromium/src/+/7c16e8428036d07088c5b7ed3db64e1b7e04b701"
},
{
"gitiles": {
"ref": "refs/branch-heads/7727",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "566cf2c2a7e21f0daaf821afcbf8b8e4df4a97f4"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7727@566cf2c2a7e21f0daaf821afcbf8b8e4df4a97f4",
"title": "566cf2c2a7e21f0daaf821afcbf8b8e4df4a97f4",
"url": "https://chromium.googlesource.com/chromium/src/+/566cf2c2a7e21f0daaf821afcbf8b8e4df4a97f4"
},
{
"gitiles": {
"ref": "refs/branch-heads/7727",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "39ea658589f89406f11f4cf55c07d6a2795b2cbe"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7727@39ea658589f89406f11f4cf55c07d6a2795b2cbe",
"title": "39ea658589f89406f11f4cf55c07d6a2795b2cbe",
"url": "https://chromium.googlesource.com/chromium/src/+/39ea658589f89406f11f4cf55c07d6a2795b2cbe"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "39ea658589f89406f11f4cf55c07d6a2795b2cbe",
"ref": "refs/branch-heads/7727"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8851586482355699728"
},
{
"key": "scheduler_job_id",
"value": "chromium-m147/win-arm64-rel"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[17:32:16.346] Scheduled build:
{
"id": "8686156420416941489",
"builder": {
"project": "chromium-m147",
"bucket": "ci",
"builder": "win-arm64-rel"
},
"number": 161,
"createdBy": "project:chromium-m147",
"createTime": "2026-03-27T17:32:15.769188064Z",
"updateTime": "2026-03-27T17:32:15.769188064Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "39ea658589f89406f11f4cf55c07d6a2795b2cbe",
"ref": "refs/branch-heads/7727"
}
}
}
[17:32:16.347] Task URL: https://cr-buildbucket.appspot.com/build/8686156420416941489
[17:32:16.347] Scheduling timer "check-buildbucket-build-status" (chromium-m147/win-arm64-rel:8851586482355699728:2:0) after 9m38s
[17:32:16.552] Received PubSub notification, asking Buildbucket for the build status
[17:32:16.583] Build status: SCHEDULED
[17:35:41.256] Received PubSub notification, asking Buildbucket for the build status
[17:35:41.279] Build status: STARTED
[17:41:54.357] Handling timer "check-buildbucket-build-status" (chromium-m147/win-arm64-rel:8851586482355699728:2:0)
[17:41:54.416] Build status: STARTED
[17:41:54.416] Scheduling timer "check-buildbucket-build-status" (chromium-m147/win-arm64-rel:8851586482355699728:5:0) after 2m28s
[17:44:22.538] Handling timer "check-buildbucket-build-status" (chromium-m147/win-arm64-rel:8851586482355699728:5:0)
[17:44:22.562] Build status: STARTED
[17:44:22.562] Scheduling timer "check-buildbucket-build-status" (chromium-m147/win-arm64-rel:8851586482355699728:6:0) after 9m13s
[17:53:35.577] Handling timer "check-buildbucket-build-status" (chromium-m147/win-arm64-rel:8851586482355699728:6:0)
[17:53:35.594] Build status: STARTED
[17:53:35.594] Scheduling timer "check-buildbucket-build-status" (chromium-m147/win-arm64-rel:8851586482355699728:7:0) after 6m32s
[18:00:07.640] Handling timer "check-buildbucket-build-status" (chromium-m147/win-arm64-rel:8851586482355699728:7:0)
[18:00:07.656] Build status: STARTED
[18:00:07.656] Scheduling timer "check-buildbucket-build-status" (chromium-m147/win-arm64-rel:8851586482355699728:8:0) after 6m56s
[18:07:03.811] Handling timer "check-buildbucket-build-status" (chromium-m147/win-arm64-rel:8851586482355699728:8:0)
[18:07:03.844] Build status: STARTED
[18:07:03.844] Scheduling timer "check-buildbucket-build-status" (chromium-m147/win-arm64-rel:8851586482355699728:9:0) after 5m20s
[18:12:23.979] Handling timer "check-buildbucket-build-status" (chromium-m147/win-arm64-rel:8851586482355699728:9:0)
[18:12:24.007] Build status: STARTED
[18:12:24.007] Scheduling timer "check-buildbucket-build-status" (chromium-m147/win-arm64-rel:8851586482355699728:10:0) after 2m1s
[18:14:24.998] Handling timer "check-buildbucket-build-status" (chromium-m147/win-arm64-rel:8851586482355699728:10:0)
[18:14:25.029] Build status: STARTED
[18:14:25.029] Scheduling timer "check-buildbucket-build-status" (chromium-m147/win-arm64-rel:8851586482355699728:11:0) after 4m58s
[18:19:23.098] Handling timer "check-buildbucket-build-status" (chromium-m147/win-arm64-rel:8851586482355699728:11:0)
[18:19:23.114] Build status: STARTED
[18:19:23.114] Scheduling timer "check-buildbucket-build-status" (chromium-m147/win-arm64-rel:8851586482355699728:12:0) after 1m33s
[18:20:56.181] Handling timer "check-buildbucket-build-status" (chromium-m147/win-arm64-rel:8851586482355699728:12:0)
[18:20:56.208] Build status: STARTED
[18:20:56.208] Scheduling timer "check-buildbucket-build-status" (chromium-m147/win-arm64-rel:8851586482355699728:13:0) after 5m42s
[18:26:38.290] Handling timer "check-buildbucket-build-status" (chromium-m147/win-arm64-rel:8851586482355699728:13:0)
[18:26:38.359] Build status: STARTED
[18:26:38.359] Scheduling timer "check-buildbucket-build-status" (chromium-m147/win-arm64-rel:8851586482355699728:14:0) after 6m42s
[18:33:20.542] Handling timer "check-buildbucket-build-status" (chromium-m147/win-arm64-rel:8851586482355699728:14:0)
[18:33:20.660] Build status: STARTED
[18:33:20.660] Scheduling timer "check-buildbucket-build-status" (chromium-m147/win-arm64-rel:8851586482355699728:15:0) after 6m25s
[18:39:45.849] Handling timer "check-buildbucket-build-status" (chromium-m147/win-arm64-rel:8851586482355699728:15:0)
[18:39:45.870] Build status: STARTED
[18:39:45.870] Scheduling timer "check-buildbucket-build-status" (chromium-m147/win-arm64-rel:8851586482355699728:16:0) after 7m0s
[18:46:46.137] Handling timer "check-buildbucket-build-status" (chromium-m147/win-arm64-rel:8851586482355699728:16:0)
[18:46:46.198] Build status: STARTED
[18:46:46.198] Scheduling timer "check-buildbucket-build-status" (chromium-m147/win-arm64-rel:8851586482355699728:17:0) after 1m42s
[18:48:28.288] Handling timer "check-buildbucket-build-status" (chromium-m147/win-arm64-rel:8851586482355699728:17:0)
[18:48:28.304] Build status: STARTED
[18:48:28.304] Scheduling timer "check-buildbucket-build-status" (chromium-m147/win-arm64-rel:8851586482355699728:18:0) after 5m10s
[18:53:38.410] Handling timer "check-buildbucket-build-status" (chromium-m147/win-arm64-rel:8851586482355699728:18:0)
[18:53:38.437] Build status: STARTED
[18:53:38.437] Scheduling timer "check-buildbucket-build-status" (chromium-m147/win-arm64-rel:8851586482355699728:19:0) after 5m9s
[18:58:47.447] Handling timer "check-buildbucket-build-status" (chromium-m147/win-arm64-rel:8851586482355699728:19:0)
[18:58:47.475] Build status: STARTED
[18:58:47.475] Scheduling timer "check-buildbucket-build-status" (chromium-m147/win-arm64-rel:8851586482355699728:20:0) after 9m56s
[19:08:43.495] Handling timer "check-buildbucket-build-status" (chromium-m147/win-arm64-rel:8851586482355699728:20:0)
[19:08:43.513] Build status: STARTED
[19:08:43.513] Scheduling timer "check-buildbucket-build-status" (chromium-m147/win-arm64-rel:8851586482355699728:21:0) after 2m26s
[19:11:09.538] Handling timer "check-buildbucket-build-status" (chromium-m147/win-arm64-rel:8851586482355699728:21:0)
[19:11:09.556] Build status: STARTED
[19:11:09.556] Scheduling timer "check-buildbucket-build-status" (chromium-m147/win-arm64-rel:8851586482355699728:22:0) after 5m54s
[19:17:03.634] Handling timer "check-buildbucket-build-status" (chromium-m147/win-arm64-rel:8851586482355699728:22:0)
[19:17:03.674] Build status: STARTED
[19:17:03.674] Scheduling timer "check-buildbucket-build-status" (chromium-m147/win-arm64-rel:8851586482355699728:23:0) after 4m37s
[19:21:40.864] Handling timer "check-buildbucket-build-status" (chromium-m147/win-arm64-rel:8851586482355699728:23:0)
[19:21:40.935] Build status: STARTED
[19:21:40.935] Scheduling timer "check-buildbucket-build-status" (chromium-m147/win-arm64-rel:8851586482355699728:24:0) after 8m12s
[19:29:52.952] Handling timer "check-buildbucket-build-status" (chromium-m147/win-arm64-rel:8851586482355699728:24:0)
[19:29:52.969] Build status: STARTED
[19:29:52.969] Scheduling timer "check-buildbucket-build-status" (chromium-m147/win-arm64-rel:8851586482355699728:25:0) after 1m10s
[19:31:03.318] Handling timer "check-buildbucket-build-status" (chromium-m147/win-arm64-rel:8851586482355699728:25:0)
[19:31:03.342] Build status: STARTED
[19:31:03.342] Scheduling timer "check-buildbucket-build-status" (chromium-m147/win-arm64-rel:8851586482355699728:26:0) after 3m58s
[19:35:01.439] Handling timer "check-buildbucket-build-status" (chromium-m147/win-arm64-rel:8851586482355699728:26:0)
[19:35:01.466] Build status: STARTED
[19:35:01.466] Scheduling timer "check-buildbucket-build-status" (chromium-m147/win-arm64-rel:8851586482355699728:27:0) after 8m51s
[19:43:52.514] Handling timer "check-buildbucket-build-status" (chromium-m147/win-arm64-rel:8851586482355699728:27:0)
[19:43:52.542] Build status: STARTED
[19:43:52.542] Scheduling timer "check-buildbucket-build-status" (chromium-m147/win-arm64-rel:8851586482355699728:28:0) after 3m6s
[19:46:58.650] Handling timer "check-buildbucket-build-status" (chromium-m147/win-arm64-rel:8851586482355699728:28:0)
[19:46:58.672] Build status: STARTED
[19:46:58.672] Scheduling timer "check-buildbucket-build-status" (chromium-m147/win-arm64-rel:8851586482355699728:29:0) after 2m9s
[19:49:07.689] Handling timer "check-buildbucket-build-status" (chromium-m147/win-arm64-rel:8851586482355699728:29:0)
[19:49:07.709] Build status: STARTED
[19:49:07.709] Scheduling timer "check-buildbucket-build-status" (chromium-m147/win-arm64-rel:8851586482355699728:30:0) after 8m6s
[19:57:13.824] Handling timer "check-buildbucket-build-status" (chromium-m147/win-arm64-rel:8851586482355699728:30:0)
[19:57:13.852] Build status: STARTED
[19:57:13.853] Scheduling timer "check-buildbucket-build-status" (chromium-m147/win-arm64-rel:8851586482355699728:31:0) after 7m19s
[20:04:32.914] Handling timer "check-buildbucket-build-status" (chromium-m147/win-arm64-rel:8851586482355699728:31:0)
[20:04:32.938] Build status: STARTED
[20:04:32.938] Scheduling timer "check-buildbucket-build-status" (chromium-m147/win-arm64-rel:8851586482355699728:32:0) after 6m18s
[20:10:51.101] Handling timer "check-buildbucket-build-status" (chromium-m147/win-arm64-rel:8851586482355699728:32:0)
[20:10:51.119] Build status: STARTED
[20:10:51.119] Scheduling timer "check-buildbucket-build-status" (chromium-m147/win-arm64-rel:8851586482355699728:33:0) after 2m6s
[20:12:57.138] Handling timer "check-buildbucket-build-status" (chromium-m147/win-arm64-rel:8851586482355699728:33:0)
[20:12:57.174] Build status: STARTED
[20:12:57.174] Scheduling timer "check-buildbucket-build-status" (chromium-m147/win-arm64-rel:8851586482355699728:34:0) after 8m46s
[20:21:43.246] Handling timer "check-buildbucket-build-status" (chromium-m147/win-arm64-rel:8851586482355699728:34:0)
[20:21:43.264] Build status: STARTED
[20:21:43.264] Scheduling timer "check-buildbucket-build-status" (chromium-m147/win-arm64-rel:8851586482355699728:35:0) after 8m36s
[20:30:19.340] Handling timer "check-buildbucket-build-status" (chromium-m147/win-arm64-rel:8851586482355699728:35:0)
[20:30:19.367] Build status: STARTED
[20:30:19.367] Scheduling timer "check-buildbucket-build-status" (chromium-m147/win-arm64-rel:8851586482355699728:36:0) after 9m51s
[20:40:10.428] Handling timer "check-buildbucket-build-status" (chromium-m147/win-arm64-rel:8851586482355699728:36:0)
[20:40:10.447] Build status: STARTED
[20:40:10.447] Scheduling timer "check-buildbucket-build-status" (chromium-m147/win-arm64-rel:8851586482355699728:37:0) after 8m9s
[20:40:11.077] Received PubSub notification, asking Buildbucket for the build status
[20:40:11.104] Build:
{
"id": "8686156420416941489",
"builder": {
"project": "chromium-m147",
"bucket": "ci",
"builder": "win-arm64-rel"
},
"number": 161,
"createdBy": "project:chromium-m147",
"createTime": "2026-03-27T17:32:15.769188064Z",
"startTime": "2026-03-27T17:35:40.991322408Z",
"endTime": "2026-03-27T20:40:10.531126667Z",
"updateTime": "2026-03-27T20:40:10.531126667Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "39ea658589f89406f11f4cf55c07d6a2795b2cbe",
"ref": "refs/branch-heads/7727"
}
}
}
[20:40:11.104] Invocation finished in 3h7m56.567428482s with status SUCCEEDED