[17:16:50.106] New invocation is queued and will start shortly
[17:16:51.201] Starting the invocation (attempt 1)
[17:16:51.231] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/57ee9363453281a7c06950e5883ee8205acd42ad"
[17:16:51.231] Popped gitiles commit info from properties and tags
[17:16:51.231] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[17:16:51.231] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[17:16:51.231] Buildbucket request:
{
"requestId": "8874780275100195488",
"builder": {
"project": "chromium-m137",
"bucket": "ci",
"builder": "win32-official"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8874780275100195488",
"job": "chromium-m137/win32-official",
"triggers": [
{
"gitiles": {
"ref": "refs/branch-heads/7151",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "d119f7c096a0c3e2e8f48a18546dfc80b63cd6f6"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7151@d119f7c096a0c3e2e8f48a18546dfc80b63cd6f6",
"title": "d119f7c096a0c3e2e8f48a18546dfc80b63cd6f6",
"url": "https://chromium.googlesource.com/chromium/src/+/d119f7c096a0c3e2e8f48a18546dfc80b63cd6f6"
},
{
"gitiles": {
"ref": "refs/branch-heads/7151",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "8b7ce537ccfe21619f9eaec346d621e00e6ea435"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7151@8b7ce537ccfe21619f9eaec346d621e00e6ea435",
"title": "8b7ce537ccfe21619f9eaec346d621e00e6ea435",
"url": "https://chromium.googlesource.com/chromium/src/+/8b7ce537ccfe21619f9eaec346d621e00e6ea435"
},
{
"gitiles": {
"ref": "refs/branch-heads/7151",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "b9dc2cadda34c98fad058255b18cb5f9b4d7086e"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7151@b9dc2cadda34c98fad058255b18cb5f9b4d7086e",
"title": "b9dc2cadda34c98fad058255b18cb5f9b4d7086e",
"url": "https://chromium.googlesource.com/chromium/src/+/b9dc2cadda34c98fad058255b18cb5f9b4d7086e"
},
{
"gitiles": {
"ref": "refs/branch-heads/7151",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "2471554a3128b112fb93462ecafa9b8582e6e66c"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7151@2471554a3128b112fb93462ecafa9b8582e6e66c",
"title": "2471554a3128b112fb93462ecafa9b8582e6e66c",
"url": "https://chromium.googlesource.com/chromium/src/+/2471554a3128b112fb93462ecafa9b8582e6e66c"
},
{
"gitiles": {
"ref": "refs/branch-heads/7151",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "9730b59dd396b4d4287581a34283d8252d99b081"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7151@9730b59dd396b4d4287581a34283d8252d99b081",
"title": "9730b59dd396b4d4287581a34283d8252d99b081",
"url": "https://chromium.googlesource.com/chromium/src/+/9730b59dd396b4d4287581a34283d8252d99b081"
},
{
"gitiles": {
"ref": "refs/branch-heads/7151",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "f6fca67d04a91e6a1498aaea42d7a759b70c2d03"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7151@f6fca67d04a91e6a1498aaea42d7a759b70c2d03",
"title": "f6fca67d04a91e6a1498aaea42d7a759b70c2d03",
"url": "https://chromium.googlesource.com/chromium/src/+/f6fca67d04a91e6a1498aaea42d7a759b70c2d03"
},
{
"gitiles": {
"ref": "refs/branch-heads/7151",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "51ce415faccb954e2292a5cf6e728420aeff27ad"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7151@51ce415faccb954e2292a5cf6e728420aeff27ad",
"title": "51ce415faccb954e2292a5cf6e728420aeff27ad",
"url": "https://chromium.googlesource.com/chromium/src/+/51ce415faccb954e2292a5cf6e728420aeff27ad"
},
{
"gitiles": {
"ref": "refs/branch-heads/7151",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "8afcce33fe8b6b9802adcfcec8b535e6771dde92"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7151@8afcce33fe8b6b9802adcfcec8b535e6771dde92",
"title": "8afcce33fe8b6b9802adcfcec8b535e6771dde92",
"url": "https://chromium.googlesource.com/chromium/src/+/8afcce33fe8b6b9802adcfcec8b535e6771dde92"
},
{
"gitiles": {
"ref": "refs/branch-heads/7151",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "2a14e2bd9bd3d97918a2b186fb4a66ade057fb54"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7151@2a14e2bd9bd3d97918a2b186fb4a66ade057fb54",
"title": "2a14e2bd9bd3d97918a2b186fb4a66ade057fb54",
"url": "https://chromium.googlesource.com/chromium/src/+/2a14e2bd9bd3d97918a2b186fb4a66ade057fb54"
},
{
"gitiles": {
"ref": "refs/branch-heads/7151",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "57ee9363453281a7c06950e5883ee8205acd42ad"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7151@57ee9363453281a7c06950e5883ee8205acd42ad",
"title": "57ee9363453281a7c06950e5883ee8205acd42ad",
"url": "https://chromium.googlesource.com/chromium/src/+/57ee9363453281a7c06950e5883ee8205acd42ad"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "57ee9363453281a7c06950e5883ee8205acd42ad",
"ref": "refs/branch-heads/7151"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8874780275100195488"
},
{
"key": "scheduler_job_id",
"value": "chromium-m137/win32-official"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[17:16:51.811] Scheduled build:
{
"id": "8709350213151101281",
"builder": {
"project": "chromium-m137",
"bucket": "ci",
"builder": "win32-official"
},
"number": 481,
"createdBy": "project:chromium-m137",
"createTime": "2025-07-14T17:16:51.338950402Z",
"updateTime": "2025-07-14T17:16:51.338950402Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "57ee9363453281a7c06950e5883ee8205acd42ad",
"ref": "refs/branch-heads/7151"
}
}
}
[17:16:51.811] Task URL: https://cr-buildbucket.appspot.com/build/8709350213151101281
[17:16:51.811] Scheduling timer "check-buildbucket-build-status" (chromium-m137/win32-official:8874780275100195488:2:0) after 7m43s
[17:16:53.771] Received PubSub notification, asking Buildbucket for the build status
[17:16:53.805] Build status: SCHEDULED
[17:16:57.459] Received PubSub notification, asking Buildbucket for the build status
[17:16:57.493] Build status: STARTED
[17:24:34.868] Handling timer "check-buildbucket-build-status" (chromium-m137/win32-official:8874780275100195488:2:0)
[17:24:34.893] Build status: STARTED
[17:24:34.893] Scheduling timer "check-buildbucket-build-status" (chromium-m137/win32-official:8874780275100195488:5:0) after 2m28s
[17:27:02.970] Handling timer "check-buildbucket-build-status" (chromium-m137/win32-official:8874780275100195488:5:0)
[17:27:03.009] Build status: STARTED
[17:27:03.009] Scheduling timer "check-buildbucket-build-status" (chromium-m137/win32-official:8874780275100195488:6:0) after 3m42s
[17:30:45.032] Handling timer "check-buildbucket-build-status" (chromium-m137/win32-official:8874780275100195488:6:0)
[17:30:45.055] Build status: STARTED
[17:30:45.055] Scheduling timer "check-buildbucket-build-status" (chromium-m137/win32-official:8874780275100195488:7:0) after 1m20s
[17:32:05.093] Handling timer "check-buildbucket-build-status" (chromium-m137/win32-official:8874780275100195488:7:0)
[17:32:05.131] Build status: STARTED
[17:32:05.131] Scheduling timer "check-buildbucket-build-status" (chromium-m137/win32-official:8874780275100195488:8:0) after 7m53s
[17:39:58.158] Handling timer "check-buildbucket-build-status" (chromium-m137/win32-official:8874780275100195488:8:0)
[17:39:58.185] Build status: STARTED
[17:39:58.185] Scheduling timer "check-buildbucket-build-status" (chromium-m137/win32-official:8874780275100195488:9:0) after 7m55s
[17:47:53.189] Handling timer "check-buildbucket-build-status" (chromium-m137/win32-official:8874780275100195488:9:0)
[17:47:53.274] Build status: STARTED
[17:47:53.274] Scheduling timer "check-buildbucket-build-status" (chromium-m137/win32-official:8874780275100195488:10:0) after 2m51s
[17:50:44.298] Handling timer "check-buildbucket-build-status" (chromium-m137/win32-official:8874780275100195488:10:0)
[17:50:44.322] Build status: STARTED
[17:50:44.322] Scheduling timer "check-buildbucket-build-status" (chromium-m137/win32-official:8874780275100195488:11:0) after 1m13s
[17:51:57.343] Handling timer "check-buildbucket-build-status" (chromium-m137/win32-official:8874780275100195488:11:0)
[17:51:57.372] Build status: STARTED
[17:51:57.372] Scheduling timer "check-buildbucket-build-status" (chromium-m137/win32-official:8874780275100195488:12:0) after 6m33s
[17:58:30.421] Handling timer "check-buildbucket-build-status" (chromium-m137/win32-official:8874780275100195488:12:0)
[17:58:30.441] Build status: STARTED
[17:58:30.441] Scheduling timer "check-buildbucket-build-status" (chromium-m137/win32-official:8874780275100195488:13:0) after 6m17s
[18:04:47.526] Handling timer "check-buildbucket-build-status" (chromium-m137/win32-official:8874780275100195488:13:0)
[18:04:47.543] Build status: STARTED
[18:04:47.543] Scheduling timer "check-buildbucket-build-status" (chromium-m137/win32-official:8874780275100195488:14:0) after 4m17s
[18:09:04.681] Handling timer "check-buildbucket-build-status" (chromium-m137/win32-official:8874780275100195488:14:0)
[18:09:04.750] Build status: STARTED
[18:09:04.750] Scheduling timer "check-buildbucket-build-status" (chromium-m137/win32-official:8874780275100195488:15:0) after 3m17s
[18:12:21.815] Handling timer "check-buildbucket-build-status" (chromium-m137/win32-official:8874780275100195488:15:0)
[18:12:21.833] Build status: STARTED
[18:12:21.833] Scheduling timer "check-buildbucket-build-status" (chromium-m137/win32-official:8874780275100195488:16:0) after 6m9s
[18:18:30.925] Handling timer "check-buildbucket-build-status" (chromium-m137/win32-official:8874780275100195488:16:0)
[18:18:30.944] Build status: STARTED
[18:18:30.944] Scheduling timer "check-buildbucket-build-status" (chromium-m137/win32-official:8874780275100195488:17:0) after 7m19s
[18:25:50.015] Handling timer "check-buildbucket-build-status" (chromium-m137/win32-official:8874780275100195488:17:0)
[18:25:50.039] Build status: STARTED
[18:25:50.039] Scheduling timer "check-buildbucket-build-status" (chromium-m137/win32-official:8874780275100195488:18:0) after 8m25s
[18:34:15.060] Handling timer "check-buildbucket-build-status" (chromium-m137/win32-official:8874780275100195488:18:0)
[18:34:15.106] Build status: STARTED
[18:34:15.106] Scheduling timer "check-buildbucket-build-status" (chromium-m137/win32-official:8874780275100195488:19:0) after 8m47s
[18:43:02.102] Handling timer "check-buildbucket-build-status" (chromium-m137/win32-official:8874780275100195488:19:0)
[18:43:02.133] Build status: STARTED
[18:43:02.133] Scheduling timer "check-buildbucket-build-status" (chromium-m137/win32-official:8874780275100195488:20:0) after 7m26s
[18:50:28.146] Handling timer "check-buildbucket-build-status" (chromium-m137/win32-official:8874780275100195488:20:0)
[18:50:28.226] Build status: STARTED
[18:50:28.227] Scheduling timer "check-buildbucket-build-status" (chromium-m137/win32-official:8874780275100195488:21:0) after 8m44s
[18:59:12.308] Handling timer "check-buildbucket-build-status" (chromium-m137/win32-official:8874780275100195488:21:0)
[18:59:12.323] Build status: STARTED
[18:59:12.323] Scheduling timer "check-buildbucket-build-status" (chromium-m137/win32-official:8874780275100195488:22:0) after 2m56s
[19:02:08.429] Handling timer "check-buildbucket-build-status" (chromium-m137/win32-official:8874780275100195488:22:0)
[19:02:08.488] Build status: STARTED
[19:02:08.488] Scheduling timer "check-buildbucket-build-status" (chromium-m137/win32-official:8874780275100195488:23:0) after 7m51s
[19:09:59.516] Handling timer "check-buildbucket-build-status" (chromium-m137/win32-official:8874780275100195488:23:0)
[19:09:59.533] Build status: STARTED
[19:09:59.533] Scheduling timer "check-buildbucket-build-status" (chromium-m137/win32-official:8874780275100195488:24:0) after 1m40s
[19:11:39.604] Handling timer "check-buildbucket-build-status" (chromium-m137/win32-official:8874780275100195488:24:0)
[19:11:39.667] Build status: STARTED
[19:11:39.667] Scheduling timer "check-buildbucket-build-status" (chromium-m137/win32-official:8874780275100195488:25:0) after 2m34s
[19:14:13.684] Handling timer "check-buildbucket-build-status" (chromium-m137/win32-official:8874780275100195488:25:0)
[19:14:13.700] Build status: STARTED
[19:14:13.700] Scheduling timer "check-buildbucket-build-status" (chromium-m137/win32-official:8874780275100195488:26:0) after 2m52s
[19:17:05.735] Handling timer "check-buildbucket-build-status" (chromium-m137/win32-official:8874780275100195488:26:0)
[19:17:05.770] Build status: STARTED
[19:17:05.770] Scheduling timer "check-buildbucket-build-status" (chromium-m137/win32-official:8874780275100195488:27:0) after 1m24s
[19:18:29.792] Handling timer "check-buildbucket-build-status" (chromium-m137/win32-official:8874780275100195488:27:0)
[19:18:29.838] Build status: STARTED
[19:18:29.838] Scheduling timer "check-buildbucket-build-status" (chromium-m137/win32-official:8874780275100195488:28:0) after 1m45s
[19:20:14.861] Handling timer "check-buildbucket-build-status" (chromium-m137/win32-official:8874780275100195488:28:0)
[19:20:14.883] Build status: STARTED
[19:20:14.883] Scheduling timer "check-buildbucket-build-status" (chromium-m137/win32-official:8874780275100195488:29:0) after 8m38s
[19:28:53.000] Handling timer "check-buildbucket-build-status" (chromium-m137/win32-official:8874780275100195488:29:0)
[19:28:53.017] Build status: STARTED
[19:28:53.017] Scheduling timer "check-buildbucket-build-status" (chromium-m137/win32-official:8874780275100195488:30:0) after 3m14s
[19:32:07.136] Handling timer "check-buildbucket-build-status" (chromium-m137/win32-official:8874780275100195488:30:0)
[19:32:07.171] Build status: STARTED
[19:32:07.171] Scheduling timer "check-buildbucket-build-status" (chromium-m137/win32-official:8874780275100195488:31:0) after 6m22s
[19:38:29.191] Handling timer "check-buildbucket-build-status" (chromium-m137/win32-official:8874780275100195488:31:0)
[19:38:29.213] Build status: STARTED
[19:38:29.213] Scheduling timer "check-buildbucket-build-status" (chromium-m137/win32-official:8874780275100195488:32:0) after 8m5s
[19:46:34.336] Handling timer "check-buildbucket-build-status" (chromium-m137/win32-official:8874780275100195488:32:0)
[19:46:34.360] Build status: STARTED
[19:46:34.360] Scheduling timer "check-buildbucket-build-status" (chromium-m137/win32-official:8874780275100195488:33:0) after 2m9s
[19:48:43.466] Handling timer "check-buildbucket-build-status" (chromium-m137/win32-official:8874780275100195488:33:0)
[19:48:43.487] Build status: STARTED
[19:48:43.487] Scheduling timer "check-buildbucket-build-status" (chromium-m137/win32-official:8874780275100195488:34:0) after 7m38s
[19:56:21.507] Handling timer "check-buildbucket-build-status" (chromium-m137/win32-official:8874780275100195488:34:0)
[19:56:21.527] Build status: STARTED
[19:56:21.527] Scheduling timer "check-buildbucket-build-status" (chromium-m137/win32-official:8874780275100195488:35:0) after 9m18s
[20:05:39.528] Handling timer "check-buildbucket-build-status" (chromium-m137/win32-official:8874780275100195488:35:0)
[20:05:39.555] Build status: STARTED
[20:05:39.556] Scheduling timer "check-buildbucket-build-status" (chromium-m137/win32-official:8874780275100195488:36:0) after 5m1s
[20:10:40.575] Handling timer "check-buildbucket-build-status" (chromium-m137/win32-official:8874780275100195488:36:0)
[20:10:40.596] Build status: STARTED
[20:10:40.596] Scheduling timer "check-buildbucket-build-status" (chromium-m137/win32-official:8874780275100195488:37:0) after 6m24s
[20:17:04.661] Handling timer "check-buildbucket-build-status" (chromium-m137/win32-official:8874780275100195488:37:0)
[20:17:04.683] Build status: STARTED
[20:17:04.683] Scheduling timer "check-buildbucket-build-status" (chromium-m137/win32-official:8874780275100195488:38:0) after 2m44s
[20:19:48.701] Handling timer "check-buildbucket-build-status" (chromium-m137/win32-official:8874780275100195488:38:0)
[20:19:48.720] Build status: STARTED
[20:19:48.720] Scheduling timer "check-buildbucket-build-status" (chromium-m137/win32-official:8874780275100195488:39:0) after 5m41s
[20:25:29.734] Handling timer "check-buildbucket-build-status" (chromium-m137/win32-official:8874780275100195488:39:0)
[20:25:29.759] Build status: STARTED
[20:25:29.759] Scheduling timer "check-buildbucket-build-status" (chromium-m137/win32-official:8874780275100195488:40:0) after 3m5s
[20:28:34.790] Handling timer "check-buildbucket-build-status" (chromium-m137/win32-official:8874780275100195488:40:0)
[20:28:34.819] Build status: STARTED
[20:28:34.820] Scheduling timer "check-buildbucket-build-status" (chromium-m137/win32-official:8874780275100195488:41:0) after 5m46s
[20:34:20.937] Handling timer "check-buildbucket-build-status" (chromium-m137/win32-official:8874780275100195488:41:0)
[20:34:20.963] Build status: STARTED
[20:34:20.963] Scheduling timer "check-buildbucket-build-status" (chromium-m137/win32-official:8874780275100195488:42:0) after 4m16s
[20:38:36.990] Handling timer "check-buildbucket-build-status" (chromium-m137/win32-official:8874780275100195488:42:0)
[20:38:37.008] Build status: STARTED
[20:38:37.008] Scheduling timer "check-buildbucket-build-status" (chromium-m137/win32-official:8874780275100195488:43:0) after 8m22s
[20:46:59.132] Handling timer "check-buildbucket-build-status" (chromium-m137/win32-official:8874780275100195488:43:0)
[20:46:59.149] Build status: STARTED
[20:46:59.149] Scheduling timer "check-buildbucket-build-status" (chromium-m137/win32-official:8874780275100195488:44:0) after 6m13s
[20:53:12.174] Handling timer "check-buildbucket-build-status" (chromium-m137/win32-official:8874780275100195488:44:0)
[20:53:12.207] Build status: STARTED
[20:53:12.207] Scheduling timer "check-buildbucket-build-status" (chromium-m137/win32-official:8874780275100195488:45:0) after 1m9s
[20:54:21.269] Handling timer "check-buildbucket-build-status" (chromium-m137/win32-official:8874780275100195488:45:0)
[20:54:21.299] Build status: STARTED
[20:54:21.299] Scheduling timer "check-buildbucket-build-status" (chromium-m137/win32-official:8874780275100195488:46:0) after 7m32s
[21:01:53.332] Handling timer "check-buildbucket-build-status" (chromium-m137/win32-official:8874780275100195488:46:0)
[21:01:53.361] Build status: STARTED
[21:01:53.361] Scheduling timer "check-buildbucket-build-status" (chromium-m137/win32-official:8874780275100195488:47:0) after 6m35s
[21:08:28.429] Handling timer "check-buildbucket-build-status" (chromium-m137/win32-official:8874780275100195488:47:0)
[21:08:28.452] Build status: STARTED
[21:08:28.452] Scheduling timer "check-buildbucket-build-status" (chromium-m137/win32-official:8874780275100195488:48:0) after 8m26s
[21:15:10.594] Received PubSub notification, asking Buildbucket for the build status
[21:15:10.608] Build:
{
"id": "8709350213151101281",
"builder": {
"project": "chromium-m137",
"bucket": "ci",
"builder": "win32-official"
},
"number": 481,
"createdBy": "project:chromium-m137",
"createTime": "2025-07-14T17:16:51.338950402Z",
"startTime": "2025-07-14T17:16:57.236840877Z",
"endTime": "2025-07-14T21:15:09.331181747Z",
"updateTime": "2025-07-14T21:15:09.331181747Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "57ee9363453281a7c06950e5883ee8205acd42ad",
"ref": "refs/branch-heads/7151"
}
}
}
[21:15:10.608] Invocation finished in 3h58m20.51276286s with status SUCCEEDED