[05:54:34.441] New invocation is queued and will start shortly
[05:54:35.509] Starting the invocation (attempt 1)
[05:54:35.534] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/2ad69ab66e594ffd1ec958781b1d019b8a671579"
[05:54:35.534] Popped gitiles commit info from properties and tags
[05:54:35.535] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[05:54:35.535] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[05:54:35.535] Buildbucket request:
{
"requestId": "8870383947902973280",
"builder": {
"project": "chromium-m140",
"bucket": "ci",
"builder": "win32-official"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8870383947902973280",
"job": "chromium-m140/win32-official",
"triggers": [
{
"gitiles": {
"ref": "refs/branch-heads/7339",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "66a91e0f23ddd6ad8ecacc80863739c3ed1f28fb"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7339@66a91e0f23ddd6ad8ecacc80863739c3ed1f28fb",
"title": "66a91e0f23ddd6ad8ecacc80863739c3ed1f28fb",
"url": "https://chromium.googlesource.com/chromium/src/+/66a91e0f23ddd6ad8ecacc80863739c3ed1f28fb"
},
{
"gitiles": {
"ref": "refs/branch-heads/7339",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "94330434f79749aafd6dc8e472a49d08c510e77a"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7339@94330434f79749aafd6dc8e472a49d08c510e77a",
"title": "94330434f79749aafd6dc8e472a49d08c510e77a",
"url": "https://chromium.googlesource.com/chromium/src/+/94330434f79749aafd6dc8e472a49d08c510e77a"
},
{
"gitiles": {
"ref": "refs/branch-heads/7339",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "8317b2dbd84fe0412fce38f73c63006bf09b76a4"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7339@8317b2dbd84fe0412fce38f73c63006bf09b76a4",
"title": "8317b2dbd84fe0412fce38f73c63006bf09b76a4",
"url": "https://chromium.googlesource.com/chromium/src/+/8317b2dbd84fe0412fce38f73c63006bf09b76a4"
},
{
"gitiles": {
"ref": "refs/branch-heads/7339",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "ed5e25f8edcf88051b1200b1ec1f2b097ad40e90"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7339@ed5e25f8edcf88051b1200b1ec1f2b097ad40e90",
"title": "ed5e25f8edcf88051b1200b1ec1f2b097ad40e90",
"url": "https://chromium.googlesource.com/chromium/src/+/ed5e25f8edcf88051b1200b1ec1f2b097ad40e90"
},
{
"gitiles": {
"ref": "refs/branch-heads/7339",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "92bf574fd9b4d0c707c3f593ff2c27ef84bb8a12"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7339@92bf574fd9b4d0c707c3f593ff2c27ef84bb8a12",
"title": "92bf574fd9b4d0c707c3f593ff2c27ef84bb8a12",
"url": "https://chromium.googlesource.com/chromium/src/+/92bf574fd9b4d0c707c3f593ff2c27ef84bb8a12"
},
{
"gitiles": {
"ref": "refs/branch-heads/7339",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "2da7de447729dfd06ff9d9e9d7b8f927a97b497e"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7339@2da7de447729dfd06ff9d9e9d7b8f927a97b497e",
"title": "2da7de447729dfd06ff9d9e9d7b8f927a97b497e",
"url": "https://chromium.googlesource.com/chromium/src/+/2da7de447729dfd06ff9d9e9d7b8f927a97b497e"
},
{
"gitiles": {
"ref": "refs/branch-heads/7339",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "d97a42fcd3a0503142e34aed1ff2641b6efa3cfc"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7339@d97a42fcd3a0503142e34aed1ff2641b6efa3cfc",
"title": "d97a42fcd3a0503142e34aed1ff2641b6efa3cfc",
"url": "https://chromium.googlesource.com/chromium/src/+/d97a42fcd3a0503142e34aed1ff2641b6efa3cfc"
},
{
"gitiles": {
"ref": "refs/branch-heads/7339",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "3d6123a68f60f34f76fd239868f0d1c40c21dd77"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7339@3d6123a68f60f34f76fd239868f0d1c40c21dd77",
"title": "3d6123a68f60f34f76fd239868f0d1c40c21dd77",
"url": "https://chromium.googlesource.com/chromium/src/+/3d6123a68f60f34f76fd239868f0d1c40c21dd77"
},
{
"gitiles": {
"ref": "refs/branch-heads/7339",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "63bd9f0a46af33f4824d429c9fb0420500c78d73"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7339@63bd9f0a46af33f4824d429c9fb0420500c78d73",
"title": "63bd9f0a46af33f4824d429c9fb0420500c78d73",
"url": "https://chromium.googlesource.com/chromium/src/+/63bd9f0a46af33f4824d429c9fb0420500c78d73"
},
{
"gitiles": {
"ref": "refs/branch-heads/7339",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "73225d49826c7ed85c438901684fc5cb14534a58"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7339@73225d49826c7ed85c438901684fc5cb14534a58",
"title": "73225d49826c7ed85c438901684fc5cb14534a58",
"url": "https://chromium.googlesource.com/chromium/src/+/73225d49826c7ed85c438901684fc5cb14534a58"
},
{
"gitiles": {
"ref": "refs/branch-heads/7339",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "2ad69ab66e594ffd1ec958781b1d019b8a671579"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7339@2ad69ab66e594ffd1ec958781b1d019b8a671579",
"title": "2ad69ab66e594ffd1ec958781b1d019b8a671579",
"url": "https://chromium.googlesource.com/chromium/src/+/2ad69ab66e594ffd1ec958781b1d019b8a671579"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "2ad69ab66e594ffd1ec958781b1d019b8a671579",
"ref": "refs/branch-heads/7339"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8870383947902973280"
},
{
"key": "scheduler_job_id",
"value": "chromium-m140/win32-official"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[05:54:36.132] Scheduled build:
{
"id": "8704953886032600001",
"builder": {
"project": "chromium-m140",
"bucket": "ci",
"builder": "win32-official"
},
"number": 176,
"createdBy": "project:chromium-m140",
"createTime": "2025-09-01T05:54:35.597617404Z",
"updateTime": "2025-09-01T05:54:35.597617404Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "2ad69ab66e594ffd1ec958781b1d019b8a671579",
"ref": "refs/branch-heads/7339"
}
},
"canary": true
}
[05:54:36.132] Task URL: https://cr-buildbucket.appspot.com/build/8704953886032600001
[05:54:36.132] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8870383947902973280:2:0) after 3m11s
[05:54:37.162] Received PubSub notification, asking Buildbucket for the build status
[05:54:37.181] Build status: SCHEDULED
[05:54:40.444] Received PubSub notification, asking Buildbucket for the build status
[05:54:40.466] Build status: STARTED
[05:57:47.250] Handling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8870383947902973280:2:0)
[05:57:47.268] Build status: STARTED
[05:57:47.268] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8870383947902973280:5:0) after 4m44s
[06:02:31.284] Handling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8870383947902973280:5:0)
[06:02:31.305] Build status: STARTED
[06:02:31.305] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8870383947902973280:6:0) after 5m57s
[06:08:28.419] Handling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8870383947902973280:6:0)
[06:08:28.488] Build status: STARTED
[06:08:28.488] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8870383947902973280:7:0) after 1m49s
[06:10:17.517] Handling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8870383947902973280:7:0)
[06:10:17.533] Build status: STARTED
[06:10:17.533] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8870383947902973280:8:0) after 3m22s
[06:13:39.553] Handling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8870383947902973280:8:0)
[06:13:39.581] Build status: STARTED
[06:13:39.581] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8870383947902973280:9:0) after 7m44s
[06:21:23.605] Handling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8870383947902973280:9:0)
[06:21:23.635] Build status: STARTED
[06:21:23.635] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8870383947902973280:10:0) after 1m27s
[06:22:50.771] Handling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8870383947902973280:10:0)
[06:22:50.855] Build status: STARTED
[06:22:50.856] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8870383947902973280:11:0) after 7m2s
[06:29:53.043] Handling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8870383947902973280:11:0)
[06:29:53.060] Build status: STARTED
[06:29:53.060] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8870383947902973280:12:0) after 7m28s
[06:37:21.132] Handling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8870383947902973280:12:0)
[06:37:21.149] Build status: STARTED
[06:37:21.149] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8870383947902973280:13:0) after 3m48s
[06:41:09.329] Handling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8870383947902973280:13:0)
[06:41:09.519] Build status: STARTED
[06:41:09.519] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8870383947902973280:14:0) after 4m4s
[06:45:13.672] Handling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8870383947902973280:14:0)
[06:45:13.693] Build status: STARTED
[06:45:13.693] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8870383947902973280:15:0) after 6m7s
[06:51:20.859] Handling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8870383947902973280:15:0)
[06:51:20.946] Build status: STARTED
[06:51:20.947] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8870383947902973280:16:0) after 2m20s
[06:53:40.965] Handling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8870383947902973280:16:0)
[06:53:40.987] Build status: STARTED
[06:53:40.987] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8870383947902973280:17:0) after 7m55s
[07:01:36.003] Handling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8870383947902973280:17:0)
[07:01:36.025] Build status: STARTED
[07:01:36.025] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8870383947902973280:18:0) after 9m31s
[07:11:07.144] Handling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8870383947902973280:18:0)
[07:11:07.176] Build status: STARTED
[07:11:07.176] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8870383947902973280:19:0) after 6m52s
[07:17:59.345] Handling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8870383947902973280:19:0)
[07:17:59.365] Build status: STARTED
[07:17:59.365] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8870383947902973280:20:0) after 5m21s
[07:23:20.378] Handling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8870383947902973280:20:0)
[07:23:20.435] Build status: STARTED
[07:23:20.435] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8870383947902973280:21:0) after 5m2s
[07:28:22.451] Handling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8870383947902973280:21:0)
[07:28:22.479] Build status: STARTED
[07:28:22.479] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8870383947902973280:22:0) after 9m59s
[07:38:21.519] Handling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8870383947902973280:22:0)
[07:38:21.558] Build status: STARTED
[07:38:21.558] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8870383947902973280:23:0) after 1m2s
[07:39:23.572] Handling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8870383947902973280:23:0)
[07:39:23.593] Build status: STARTED
[07:39:23.594] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8870383947902973280:24:0) after 2m51s
[07:42:14.660] Handling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8870383947902973280:24:0)
[07:42:14.680] Build status: STARTED
[07:42:14.680] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8870383947902973280:25:0) after 5m7s
[07:47:21.687] Handling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8870383947902973280:25:0)
[07:47:21.713] Build status: STARTED
[07:47:21.713] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8870383947902973280:26:0) after 7m47s
[07:55:08.727] Handling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8870383947902973280:26:0)
[07:55:08.745] Build status: STARTED
[07:55:08.745] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8870383947902973280:27:0) after 7m10s
[08:02:18.810] Handling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8870383947902973280:27:0)
[08:02:18.873] Build status: STARTED
[08:02:18.873] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8870383947902973280:28:0) after 3m24s
[08:05:42.890] Handling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8870383947902973280:28:0)
[08:05:42.908] Build status: STARTED
[08:05:42.908] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8870383947902973280:29:0) after 4m23s
[08:10:05.925] Handling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8870383947902973280:29:0)
[08:10:05.945] Build status: STARTED
[08:10:05.945] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8870383947902973280:30:0) after 9m53s
[08:19:59.004] Handling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8870383947902973280:30:0)
[08:19:59.018] Build status: STARTED
[08:19:59.018] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8870383947902973280:31:0) after 7m30s
[08:27:29.035] Handling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8870383947902973280:31:0)
[08:27:29.052] Build status: STARTED
[08:27:29.052] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8870383947902973280:32:0) after 4m8s
[08:31:37.174] Handling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8870383947902973280:32:0)
[08:31:37.191] Build status: STARTED
[08:31:37.191] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8870383947902973280:33:0) after 1m0s
[08:32:37.167] Handling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8870383947902973280:33:0)
[08:32:37.187] Build status: STARTED
[08:32:37.188] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8870383947902973280:34:0) after 1m49s
[08:34:26.271] Handling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8870383947902973280:34:0)
[08:34:26.295] Build status: STARTED
[08:34:26.295] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8870383947902973280:35:0) after 4m25s
[08:38:51.315] Handling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8870383947902973280:35:0)
[08:38:51.332] Build status: STARTED
[08:38:51.332] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8870383947902973280:36:0) after 9m52s
[08:48:43.350] Handling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8870383947902973280:36:0)
[08:48:43.365] Build status: STARTED
[08:48:43.365] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8870383947902973280:37:0) after 9m36s
[08:58:19.506] Handling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8870383947902973280:37:0)
[08:58:19.522] Build status: STARTED
[08:58:19.522] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8870383947902973280:38:0) after 4m11s
[09:02:30.511] Handling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8870383947902973280:38:0)
[09:02:30.536] Build status: STARTED
[09:02:30.536] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8870383947902973280:39:0) after 4m2s
[09:06:32.609] Handling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8870383947902973280:39:0)
[09:06:32.628] Build status: STARTED
[09:06:32.628] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8870383947902973280:40:0) after 7m14s
[09:13:46.646] Handling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8870383947902973280:40:0)
[09:13:46.728] Build status: STARTED
[09:13:46.728] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8870383947902973280:41:0) after 6m53s
[09:20:39.790] Handling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8870383947902973280:41:0)
[09:20:39.809] Build status: STARTED
[09:20:39.809] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8870383947902973280:42:0) after 5m52s
[09:26:31.829] Handling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8870383947902973280:42:0)
[09:26:31.847] Build status: STARTED
[09:26:31.847] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8870383947902973280:43:0) after 2m12s
[09:28:43.848] Handling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8870383947902973280:43:0)
[09:28:43.868] Build status: STARTED
[09:28:43.868] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8870383947902973280:44:0) after 6m21s
[09:35:04.892] Handling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8870383947902973280:44:0)
[09:35:04.910] Build status: STARTED
[09:35:04.910] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8870383947902973280:45:0) after 3m12s
[09:35:08.935] Received PubSub notification, asking Buildbucket for the build status
[09:35:08.981] Build:
{
"id": "8704953886032600001",
"builder": {
"project": "chromium-m140",
"bucket": "ci",
"builder": "win32-official"
},
"number": 176,
"createdBy": "project:chromium-m140",
"createTime": "2025-09-01T05:54:35.597617404Z",
"startTime": "2025-09-01T05:54:40.235402223Z",
"endTime": "2025-09-01T09:35:08.153647843Z",
"updateTime": "2025-09-01T09:35:08.153647843Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "2ad69ab66e594ffd1ec958781b1d019b8a671579",
"ref": "refs/branch-heads/7339"
}
},
"canary": true
}
[09:35:08.981] Invocation finished in 3h40m34.550931261s with status SUCCEEDED