[19:16:35.480] New invocation is queued and will start shortly
[19:16:36.645] Starting the invocation (attempt 1)
[19:16:36.690] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/951cab0bb58a012ff76196689f572ceef7c854a2"
[19:16:36.690] Popped gitiles commit info from properties and tags
[19:16:36.690] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[19:16:36.690] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[19:16:36.691] Buildbucket request:
{
"requestId": "8871692443831070336",
"builder": {
"project": "chromium-m140",
"bucket": "ci",
"builder": "win32-official"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8871692443831070336",
"job": "chromium-m140/win32-official",
"triggers": [
{
"gitiles": {
"ref": "refs/branch-heads/7339",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "85b3b847ae7a1f228208f8d2f4add5449864a212"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7339@85b3b847ae7a1f228208f8d2f4add5449864a212",
"title": "85b3b847ae7a1f228208f8d2f4add5449864a212",
"url": "https://chromium.googlesource.com/chromium/src/+/85b3b847ae7a1f228208f8d2f4add5449864a212"
},
{
"gitiles": {
"ref": "refs/branch-heads/7339",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "414e30d03689266111f8abcc29f6643f54fc8631"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7339@414e30d03689266111f8abcc29f6643f54fc8631",
"title": "414e30d03689266111f8abcc29f6643f54fc8631",
"url": "https://chromium.googlesource.com/chromium/src/+/414e30d03689266111f8abcc29f6643f54fc8631"
},
{
"gitiles": {
"ref": "refs/branch-heads/7339",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "1093395427ce8e817ef4a29b24269fa16b96b837"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7339@1093395427ce8e817ef4a29b24269fa16b96b837",
"title": "1093395427ce8e817ef4a29b24269fa16b96b837",
"url": "https://chromium.googlesource.com/chromium/src/+/1093395427ce8e817ef4a29b24269fa16b96b837"
},
{
"gitiles": {
"ref": "refs/branch-heads/7339",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "51f9ce92599b31713bc11a0d57bc7a892f74763f"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7339@51f9ce92599b31713bc11a0d57bc7a892f74763f",
"title": "51f9ce92599b31713bc11a0d57bc7a892f74763f",
"url": "https://chromium.googlesource.com/chromium/src/+/51f9ce92599b31713bc11a0d57bc7a892f74763f"
},
{
"gitiles": {
"ref": "refs/branch-heads/7339",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "63f1dc538f1ad43cf4c96fa175626642eb9bd5ea"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7339@63f1dc538f1ad43cf4c96fa175626642eb9bd5ea",
"title": "63f1dc538f1ad43cf4c96fa175626642eb9bd5ea",
"url": "https://chromium.googlesource.com/chromium/src/+/63f1dc538f1ad43cf4c96fa175626642eb9bd5ea"
},
{
"gitiles": {
"ref": "refs/branch-heads/7339",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "951cab0bb58a012ff76196689f572ceef7c854a2"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7339@951cab0bb58a012ff76196689f572ceef7c854a2",
"title": "951cab0bb58a012ff76196689f572ceef7c854a2",
"url": "https://chromium.googlesource.com/chromium/src/+/951cab0bb58a012ff76196689f572ceef7c854a2"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "951cab0bb58a012ff76196689f572ceef7c854a2",
"ref": "refs/branch-heads/7339"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8871692443831070336"
},
{
"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"
}
}
[19:16:37.886] Scheduled build:
{
"id": "8706262381622528529",
"builder": {
"project": "chromium-m140",
"bucket": "ci",
"builder": "win32-official"
},
"number": 82,
"createdBy": "project:chromium-m140",
"createTime": "2025-08-17T19:16:36.960494142Z",
"updateTime": "2025-08-17T19:16:36.960494142Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "951cab0bb58a012ff76196689f572ceef7c854a2",
"ref": "refs/branch-heads/7339"
}
}
}
[19:16:37.886] Task URL: https://cr-buildbucket.appspot.com/build/8706262381622528529
[19:16:37.886] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8871692443831070336:2:0) after 3m40s
[19:16:38.233] Received PubSub notification, asking Buildbucket for the build status
[19:16:38.259] Build status: SCHEDULED
[19:18:00.624] Received PubSub notification, asking Buildbucket for the build status
[19:18:00.648] Build status: STARTED
[19:20:17.963] Handling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8871692443831070336:2:0)
[19:20:17.980] Build status: STARTED
[19:20:17.980] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8871692443831070336:5:0) after 1m18s
[19:21:35.996] Handling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8871692443831070336:5:0)
[19:21:36.018] Build status: STARTED
[19:21:36.018] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8871692443831070336:6:0) after 2m16s
[19:23:52.546] Handling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8871692443831070336:6:0)
[19:23:52.566] Build status: STARTED
[19:23:52.566] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8871692443831070336:7:0) after 2m26s
[19:26:18.580] Handling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8871692443831070336:7:0)
[19:26:18.744] Build status: STARTED
[19:26:18.744] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8871692443831070336:8:0) after 7m5s
[19:33:23.904] Handling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8871692443831070336:8:0)
[19:33:24.027] Build status: STARTED
[19:33:24.027] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8871692443831070336:9:0) after 5m44s
[19:39:08.043] Handling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8871692443831070336:9:0)
[19:39:08.067] Build status: STARTED
[19:39:08.067] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8871692443831070336:10:0) after 3m37s
[19:42:45.080] Handling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8871692443831070336:10:0)
[19:42:45.130] Build status: STARTED
[19:42:45.130] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8871692443831070336:11:0) after 4m36s
[19:47:21.234] Handling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8871692443831070336:11:0)
[19:47:21.252] Build status: STARTED
[19:47:21.252] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8871692443831070336:12:0) after 7m1s
[19:54:22.287] Handling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8871692443831070336:12:0)
[19:54:22.314] Build status: STARTED
[19:54:22.314] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8871692443831070336:13:0) after 7m18s
[20:01:40.431] Handling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8871692443831070336:13:0)
[20:01:40.461] Build status: STARTED
[20:01:40.461] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8871692443831070336:14:0) after 1m8s
[20:02:48.508] Handling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8871692443831070336:14:0)
[20:02:48.532] Build status: STARTED
[20:02:48.532] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8871692443831070336:15:0) after 5m58s
[20:08:46.558] Handling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8871692443831070336:15:0)
[20:08:46.590] Build status: STARTED
[20:08:46.590] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8871692443831070336:16:0) after 5m46s
[20:14:32.606] Handling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8871692443831070336:16:0)
[20:14:32.763] Build status: STARTED
[20:14:32.763] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8871692443831070336:17:0) after 3m58s
[20:18:30.809] Handling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8871692443831070336:17:0)
[20:18:30.867] Build status: STARTED
[20:18:30.867] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8871692443831070336:18:0) after 9m15s
[20:27:45.885] Handling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8871692443831070336:18:0)
[20:27:45.904] Build status: STARTED
[20:27:45.904] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8871692443831070336:19:0) after 9m37s
[20:37:23.024] Handling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8871692443831070336:19:0)
[20:37:23.043] Build status: STARTED
[20:37:23.043] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8871692443831070336:20:0) after 2m17s
[20:39:40.183] Handling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8871692443831070336:20:0)
[20:39:40.210] Build status: STARTED
[20:39:40.210] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8871692443831070336:21:0) after 1m56s
[20:41:36.228] Handling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8871692443831070336:21:0)
[20:41:36.259] Build status: STARTED
[20:41:36.259] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8871692443831070336:22:0) after 2m6s
[20:43:42.281] Handling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8871692443831070336:22:0)
[20:43:42.303] Build status: STARTED
[20:43:42.303] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8871692443831070336:23:0) after 1m17s
[20:44:59.486] Handling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8871692443831070336:23:0)
[20:44:59.506] Build status: STARTED
[20:44:59.506] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8871692443831070336:24:0) after 1m25s
[20:46:24.606] Handling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8871692443831070336:24:0)
[20:46:24.637] Build status: STARTED
[20:46:24.637] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8871692443831070336:25:0) after 2m38s
[20:49:02.783] Handling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8871692443831070336:25:0)
[20:49:02.803] Build status: STARTED
[20:49:02.803] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8871692443831070336:26:0) after 4m44s
[20:53:46.837] Handling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8871692443831070336:26:0)
[20:53:46.860] Build status: STARTED
[20:53:46.861] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8871692443831070336:27:0) after 4m6s
[20:57:52.877] Handling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8871692443831070336:27:0)
[20:57:52.899] Build status: STARTED
[20:57:52.899] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8871692443831070336:28:0) after 8m37s
[21:06:29.915] Handling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8871692443831070336:28:0)
[21:06:29.936] Build status: STARTED
[21:06:29.936] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8871692443831070336:29:0) after 4m3s
[21:10:33.060] Handling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8871692443831070336:29:0)
[21:10:33.083] Build status: STARTED
[21:10:33.083] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8871692443831070336:30:0) after 5m24s
[21:15:57.101] Handling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8871692443831070336:30:0)
[21:15:57.163] Build status: STARTED
[21:15:57.164] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8871692443831070336:31:0) after 8m14s
[21:24:11.285] Handling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8871692443831070336:31:0)
[21:24:11.300] Build status: STARTED
[21:24:11.300] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8871692443831070336:32:0) after 7m5s
[21:31:16.523] Handling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8871692443831070336:32:0)
[21:31:16.539] Build status: STARTED
[21:31:16.539] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8871692443831070336:33:0) after 3m38s
[21:34:54.554] Handling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8871692443831070336:33:0)
[21:34:54.575] Build status: STARTED
[21:34:54.575] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8871692443831070336:34:0) after 3m35s
[21:38:29.590] Handling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8871692443831070336:34:0)
[21:38:29.606] Build status: STARTED
[21:38:29.606] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8871692443831070336:35:0) after 9m5s
[21:47:34.619] Handling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8871692443831070336:35:0)
[21:47:34.642] Build status: STARTED
[21:47:34.642] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8871692443831070336:36:0) after 2m19s
[21:49:53.761] Handling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8871692443831070336:36:0)
[21:49:53.976] Build status: STARTED
[21:49:53.976] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8871692443831070336:37:0) after 4m15s
[21:54:08.995] Handling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8871692443831070336:37:0)
[21:54:09.014] Build status: STARTED
[21:54:09.014] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8871692443831070336:38:0) after 2m2s
[21:56:11.131] Handling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8871692443831070336:38:0)
[21:56:11.155] Build status: STARTED
[21:56:11.155] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8871692443831070336:39:0) after 1m56s
[21:58:07.432] Handling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8871692443831070336:39:0)
[21:58:07.497] Build status: STARTED
[21:58:07.497] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8871692443831070336:40:0) after 8m55s
[22:07:02.515] Handling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8871692443831070336:40:0)
[22:07:02.539] Build status: STARTED
[22:07:02.539] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8871692443831070336:41:0) after 2m55s
[22:09:57.594] Handling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8871692443831070336:41:0)
[22:09:57.692] Build status: STARTED
[22:09:57.692] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8871692443831070336:42:0) after 1m7s
[22:11:04.774] Handling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8871692443831070336:42:0)
[22:11:04.790] Build status: STARTED
[22:11:04.790] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8871692443831070336:43:0) after 1m11s
[22:12:15.813] Handling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8871692443831070336:43:0)
[22:12:15.871] Build status: STARTED
[22:12:15.871] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8871692443831070336:44:0) after 7m39s
[22:19:55.125] Handling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8871692443831070336:44:0)
[22:19:55.142] Build status: STARTED
[22:19:55.142] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8871692443831070336:45:0) after 6m53s
[22:26:48.273] Handling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8871692443831070336:45:0)
[22:26:48.297] Build status: STARTED
[22:26:48.297] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8871692443831070336:46:0) after 2m35s
[22:29:23.347] Handling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8871692443831070336:46:0)
[22:29:23.401] Build status: STARTED
[22:29:23.401] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8871692443831070336:47:0) after 7m7s
[22:36:30.491] Handling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8871692443831070336:47:0)
[22:36:30.508] Build status: STARTED
[22:36:30.508] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8871692443831070336:48:0) after 8m46s
[22:45:16.529] Handling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8871692443831070336:48:0)
[22:45:16.554] Build status: STARTED
[22:45:16.554] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8871692443831070336:49:0) after 8m5s
[22:53:21.688] Handling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8871692443831070336:49:0)
[22:53:21.718] Build status: STARTED
[22:53:21.718] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8871692443831070336:50:0) after 7m28s
[23:00:08.356] Received PubSub notification, asking Buildbucket for the build status
[23:00:08.379] Build:
{
"id": "8706262381622528529",
"builder": {
"project": "chromium-m140",
"bucket": "ci",
"builder": "win32-official"
},
"number": 82,
"createdBy": "project:chromium-m140",
"createTime": "2025-08-17T19:16:36.960494142Z",
"startTime": "2025-08-17T19:18:00.139300573Z",
"endTime": "2025-08-17T23:00:08.047318833Z",
"updateTime": "2025-08-17T23:00:08.047318833Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "951cab0bb58a012ff76196689f572ceef7c854a2",
"ref": "refs/branch-heads/7339"
}
}
}
[23:00:08.379] Invocation finished in 3h43m32.908899532s with status SUCCEEDED