[00:41:28.773] New invocation is queued and will start shortly
[00:41:29.942] Starting the invocation (attempt 1)
[00:41:29.975] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/51803bcb8afe4fc50565cb6261269e854d704779"
[00:41:29.975] Popped gitiles commit info from properties and tags
[00:41:29.975] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[00:41:29.975] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[00:41:29.979] Buildbucket request:
{
"requestId": "8869860064350248496",
"builder": {
"project": "chromium-m141",
"bucket": "ci",
"builder": "win-official"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8869860064350248496",
"job": "chromium-m141/win-official",
"triggers": [
{
"gitiles": {
"ref": "refs/branch-heads/7390",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "e76992f1efa3b0ad9c36570303be7cfd735bab7e"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7390@e76992f1efa3b0ad9c36570303be7cfd735bab7e",
"title": "e76992f1efa3b0ad9c36570303be7cfd735bab7e",
"url": "https://chromium.googlesource.com/chromium/src/+/e76992f1efa3b0ad9c36570303be7cfd735bab7e"
},
{
"gitiles": {
"ref": "refs/branch-heads/7390",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "cf1a78924dab7ee221b3fed061873a547f2ee65e"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7390@cf1a78924dab7ee221b3fed061873a547f2ee65e",
"title": "cf1a78924dab7ee221b3fed061873a547f2ee65e",
"url": "https://chromium.googlesource.com/chromium/src/+/cf1a78924dab7ee221b3fed061873a547f2ee65e"
},
{
"gitiles": {
"ref": "refs/branch-heads/7390",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "16d7a5236ccbe48e35d04df65823d89b6da20cc2"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7390@16d7a5236ccbe48e35d04df65823d89b6da20cc2",
"title": "16d7a5236ccbe48e35d04df65823d89b6da20cc2",
"url": "https://chromium.googlesource.com/chromium/src/+/16d7a5236ccbe48e35d04df65823d89b6da20cc2"
},
{
"gitiles": {
"ref": "refs/branch-heads/7390",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "a92da2f89351f549ed773f0e5cd0aee1234f3c42"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7390@a92da2f89351f549ed773f0e5cd0aee1234f3c42",
"title": "a92da2f89351f549ed773f0e5cd0aee1234f3c42",
"url": "https://chromium.googlesource.com/chromium/src/+/a92da2f89351f549ed773f0e5cd0aee1234f3c42"
},
{
"gitiles": {
"ref": "refs/branch-heads/7390",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "5c39982fefd39474a3f4cc5b22627e6cc310a1d8"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7390@5c39982fefd39474a3f4cc5b22627e6cc310a1d8",
"title": "5c39982fefd39474a3f4cc5b22627e6cc310a1d8",
"url": "https://chromium.googlesource.com/chromium/src/+/5c39982fefd39474a3f4cc5b22627e6cc310a1d8"
},
{
"gitiles": {
"ref": "refs/branch-heads/7390",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "3234efb2c03857f1cc5bcda2c803ad1a883dbae1"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7390@3234efb2c03857f1cc5bcda2c803ad1a883dbae1",
"title": "3234efb2c03857f1cc5bcda2c803ad1a883dbae1",
"url": "https://chromium.googlesource.com/chromium/src/+/3234efb2c03857f1cc5bcda2c803ad1a883dbae1"
},
{
"gitiles": {
"ref": "refs/branch-heads/7390",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "5a6e039c9a5bb87398e507fcef6b2eec96ffe003"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7390@5a6e039c9a5bb87398e507fcef6b2eec96ffe003",
"title": "5a6e039c9a5bb87398e507fcef6b2eec96ffe003",
"url": "https://chromium.googlesource.com/chromium/src/+/5a6e039c9a5bb87398e507fcef6b2eec96ffe003"
},
{
"gitiles": {
"ref": "refs/branch-heads/7390",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "06837f6ac3e0218ad23a4b0c9894e7499febd158"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7390@06837f6ac3e0218ad23a4b0c9894e7499febd158",
"title": "06837f6ac3e0218ad23a4b0c9894e7499febd158",
"url": "https://chromium.googlesource.com/chromium/src/+/06837f6ac3e0218ad23a4b0c9894e7499febd158"
},
{
"gitiles": {
"ref": "refs/branch-heads/7390",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "4ae2d726020a75dfb2332586af568cec35b7047d"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7390@4ae2d726020a75dfb2332586af568cec35b7047d",
"title": "4ae2d726020a75dfb2332586af568cec35b7047d",
"url": "https://chromium.googlesource.com/chromium/src/+/4ae2d726020a75dfb2332586af568cec35b7047d"
},
{
"gitiles": {
"ref": "refs/branch-heads/7390",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "a12eee8b6500fff352b579c408264c2542900041"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7390@a12eee8b6500fff352b579c408264c2542900041",
"title": "a12eee8b6500fff352b579c408264c2542900041",
"url": "https://chromium.googlesource.com/chromium/src/+/a12eee8b6500fff352b579c408264c2542900041"
},
{
"gitiles": {
"ref": "refs/branch-heads/7390",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "51803bcb8afe4fc50565cb6261269e854d704779"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7390@51803bcb8afe4fc50565cb6261269e854d704779",
"title": "51803bcb8afe4fc50565cb6261269e854d704779",
"url": "https://chromium.googlesource.com/chromium/src/+/51803bcb8afe4fc50565cb6261269e854d704779"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "51803bcb8afe4fc50565cb6261269e854d704779",
"ref": "refs/branch-heads/7390"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8869860064350248496"
},
{
"key": "scheduler_job_id",
"value": "chromium-m141/win-official"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[00:41:30.489] Scheduled build:
{
"id": "8704430002305336753",
"builder": {
"project": "chromium-m141",
"bucket": "ci",
"builder": "win-official"
},
"number": 38,
"createdBy": "project:chromium-m141",
"createTime": "2025-09-07T00:41:30.053639183Z",
"updateTime": "2025-09-07T00:41:30.053639183Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "51803bcb8afe4fc50565cb6261269e854d704779",
"ref": "refs/branch-heads/7390"
}
}
}
[00:41:30.489] Task URL: https://cr-buildbucket.appspot.com/build/8704430002305336753
[00:41:30.489] Scheduling timer "check-buildbucket-build-status" (chromium-m141/win-official:8869860064350248496:2:0) after 1m39s
[00:41:31.549] Received PubSub notification, asking Buildbucket for the build status
[00:41:31.593] Build status: SCHEDULED
[00:41:35.543] Received PubSub notification, asking Buildbucket for the build status
[00:41:35.627] Build status: STARTED
[00:43:09.946] Handling timer "check-buildbucket-build-status" (chromium-m141/win-official:8869860064350248496:2:0)
[00:43:09.969] Build status: STARTED
[00:43:09.970] Scheduling timer "check-buildbucket-build-status" (chromium-m141/win-official:8869860064350248496:5:0) after 5m7s
[00:48:17.244] Handling timer "check-buildbucket-build-status" (chromium-m141/win-official:8869860064350248496:5:0)
[00:48:17.266] Build status: STARTED
[00:48:17.266] Scheduling timer "check-buildbucket-build-status" (chromium-m141/win-official:8869860064350248496:6:0) after 8m19s
[00:56:36.333] Handling timer "check-buildbucket-build-status" (chromium-m141/win-official:8869860064350248496:6:0)
[00:56:36.375] Build status: STARTED
[00:56:36.375] Scheduling timer "check-buildbucket-build-status" (chromium-m141/win-official:8869860064350248496:7:0) after 4m45s
[01:01:21.394] Handling timer "check-buildbucket-build-status" (chromium-m141/win-official:8869860064350248496:7:0)
[01:01:21.411] Build status: STARTED
[01:01:21.411] Scheduling timer "check-buildbucket-build-status" (chromium-m141/win-official:8869860064350248496:8:0) after 8m47s
[01:10:08.819] Handling timer "check-buildbucket-build-status" (chromium-m141/win-official:8869860064350248496:8:0)
[01:10:08.836] Build status: STARTED
[01:10:08.836] Scheduling timer "check-buildbucket-build-status" (chromium-m141/win-official:8869860064350248496:9:0) after 8m58s
[01:19:07.434] Handling timer "check-buildbucket-build-status" (chromium-m141/win-official:8869860064350248496:9:0)
[01:19:07.470] Build status: STARTED
[01:19:07.470] Scheduling timer "check-buildbucket-build-status" (chromium-m141/win-official:8869860064350248496:10:0) after 3m13s
[01:22:20.711] Handling timer "check-buildbucket-build-status" (chromium-m141/win-official:8869860064350248496:10:0)
[01:22:20.755] Build status: STARTED
[01:22:20.755] Scheduling timer "check-buildbucket-build-status" (chromium-m141/win-official:8869860064350248496:11:0) after 4m13s
[01:26:33.986] Handling timer "check-buildbucket-build-status" (chromium-m141/win-official:8869860064350248496:11:0)
[01:26:34.011] Build status: STARTED
[01:26:34.011] Scheduling timer "check-buildbucket-build-status" (chromium-m141/win-official:8869860064350248496:12:0) after 8m43s
[01:35:17.013] Handling timer "check-buildbucket-build-status" (chromium-m141/win-official:8869860064350248496:12:0)
[01:35:17.086] Build status: STARTED
[01:35:17.086] Scheduling timer "check-buildbucket-build-status" (chromium-m141/win-official:8869860064350248496:13:0) after 5m52s
[01:41:09.206] Handling timer "check-buildbucket-build-status" (chromium-m141/win-official:8869860064350248496:13:0)
[01:41:09.225] Build status: STARTED
[01:41:09.225] Scheduling timer "check-buildbucket-build-status" (chromium-m141/win-official:8869860064350248496:14:0) after 5m51s
[01:47:00.238] Handling timer "check-buildbucket-build-status" (chromium-m141/win-official:8869860064350248496:14:0)
[01:47:00.259] Build status: STARTED
[01:47:00.259] Scheduling timer "check-buildbucket-build-status" (chromium-m141/win-official:8869860064350248496:15:0) after 4m40s
[01:51:40.619] Handling timer "check-buildbucket-build-status" (chromium-m141/win-official:8869860064350248496:15:0)
[01:51:40.635] Build status: STARTED
[01:51:40.635] Scheduling timer "check-buildbucket-build-status" (chromium-m141/win-official:8869860064350248496:16:0) after 6m24s
[01:58:04.731] Handling timer "check-buildbucket-build-status" (chromium-m141/win-official:8869860064350248496:16:0)
[01:58:04.756] Build status: STARTED
[01:58:04.756] Scheduling timer "check-buildbucket-build-status" (chromium-m141/win-official:8869860064350248496:17:0) after 3m9s
[02:01:13.808] Handling timer "check-buildbucket-build-status" (chromium-m141/win-official:8869860064350248496:17:0)
[02:01:13.889] Build status: STARTED
[02:01:13.889] Scheduling timer "check-buildbucket-build-status" (chromium-m141/win-official:8869860064350248496:18:0) after 3m54s
[02:05:08.179] Handling timer "check-buildbucket-build-status" (chromium-m141/win-official:8869860064350248496:18:0)
[02:05:08.201] Build status: STARTED
[02:05:08.201] Scheduling timer "check-buildbucket-build-status" (chromium-m141/win-official:8869860064350248496:19:0) after 5m43s
[02:10:51.535] Handling timer "check-buildbucket-build-status" (chromium-m141/win-official:8869860064350248496:19:0)
[02:10:51.566] Build status: STARTED
[02:10:51.566] Scheduling timer "check-buildbucket-build-status" (chromium-m141/win-official:8869860064350248496:20:0) after 8m1s
[02:18:52.583] Handling timer "check-buildbucket-build-status" (chromium-m141/win-official:8869860064350248496:20:0)
[02:18:52.660] Build status: STARTED
[02:18:52.660] Scheduling timer "check-buildbucket-build-status" (chromium-m141/win-official:8869860064350248496:21:0) after 2m42s
[02:21:34.760] Handling timer "check-buildbucket-build-status" (chromium-m141/win-official:8869860064350248496:21:0)
[02:21:34.778] Build status: STARTED
[02:21:34.778] Scheduling timer "check-buildbucket-build-status" (chromium-m141/win-official:8869860064350248496:22:0) after 5m22s
[02:26:56.902] Handling timer "check-buildbucket-build-status" (chromium-m141/win-official:8869860064350248496:22:0)
[02:26:56.919] Build status: STARTED
[02:26:56.919] Scheduling timer "check-buildbucket-build-status" (chromium-m141/win-official:8869860064350248496:23:0) after 6m26s
[02:33:22.934] Handling timer "check-buildbucket-build-status" (chromium-m141/win-official:8869860064350248496:23:0)
[02:33:22.956] Build status: STARTED
[02:33:22.956] Scheduling timer "check-buildbucket-build-status" (chromium-m141/win-official:8869860064350248496:24:0) after 6m5s
[02:39:28.067] Handling timer "check-buildbucket-build-status" (chromium-m141/win-official:8869860064350248496:24:0)
[02:39:28.117] Build status: STARTED
[02:39:28.117] Scheduling timer "check-buildbucket-build-status" (chromium-m141/win-official:8869860064350248496:25:0) after 4m9s
[02:43:37.235] Handling timer "check-buildbucket-build-status" (chromium-m141/win-official:8869860064350248496:25:0)
[02:43:37.251] Build status: STARTED
[02:43:37.251] Scheduling timer "check-buildbucket-build-status" (chromium-m141/win-official:8869860064350248496:26:0) after 9m35s
[02:53:12.293] Handling timer "check-buildbucket-build-status" (chromium-m141/win-official:8869860064350248496:26:0)
[02:53:12.386] Build status: STARTED
[02:53:12.387] Scheduling timer "check-buildbucket-build-status" (chromium-m141/win-official:8869860064350248496:27:0) after 3m15s
[02:56:27.462] Handling timer "check-buildbucket-build-status" (chromium-m141/win-official:8869860064350248496:27:0)
[02:56:27.481] Build status: STARTED
[02:56:27.481] Scheduling timer "check-buildbucket-build-status" (chromium-m141/win-official:8869860064350248496:28:0) after 4m29s
[03:00:56.639] Handling timer "check-buildbucket-build-status" (chromium-m141/win-official:8869860064350248496:28:0)
[03:00:56.659] Build status: STARTED
[03:00:56.659] Scheduling timer "check-buildbucket-build-status" (chromium-m141/win-official:8869860064350248496:29:0) after 4m27s
[03:05:23.910] Handling timer "check-buildbucket-build-status" (chromium-m141/win-official:8869860064350248496:29:0)
[03:05:23.927] Build status: STARTED
[03:05:23.927] Scheduling timer "check-buildbucket-build-status" (chromium-m141/win-official:8869860064350248496:30:0) after 6m52s
[03:12:15.960] Handling timer "check-buildbucket-build-status" (chromium-m141/win-official:8869860064350248496:30:0)
[03:12:15.980] Build status: STARTED
[03:12:15.980] Scheduling timer "check-buildbucket-build-status" (chromium-m141/win-official:8869860064350248496:31:0) after 4m53s
[03:17:08.996] Handling timer "check-buildbucket-build-status" (chromium-m141/win-official:8869860064350248496:31:0)
[03:17:09.101] Build status: STARTED
[03:17:09.101] Scheduling timer "check-buildbucket-build-status" (chromium-m141/win-official:8869860064350248496:32:0) after 6m54s
[03:24:04.076] Handling timer "check-buildbucket-build-status" (chromium-m141/win-official:8869860064350248496:32:0)
[03:24:04.098] Build status: STARTED
[03:24:04.098] Scheduling timer "check-buildbucket-build-status" (chromium-m141/win-official:8869860064350248496:33:0) after 8m1s
[03:32:05.151] Handling timer "check-buildbucket-build-status" (chromium-m141/win-official:8869860064350248496:33:0)
[03:32:05.168] Build status: STARTED
[03:32:05.168] Scheduling timer "check-buildbucket-build-status" (chromium-m141/win-official:8869860064350248496:34:0) after 2m33s
[03:34:38.280] Handling timer "check-buildbucket-build-status" (chromium-m141/win-official:8869860064350248496:34:0)
[03:34:38.299] Build status: STARTED
[03:34:38.299] Scheduling timer "check-buildbucket-build-status" (chromium-m141/win-official:8869860064350248496:35:0) after 9m30s
[03:34:54.253] Received PubSub notification, asking Buildbucket for the build status
[03:34:54.271] Build:
{
"id": "8704430002305336753",
"builder": {
"project": "chromium-m141",
"bucket": "ci",
"builder": "win-official"
},
"number": 38,
"createdBy": "project:chromium-m141",
"createTime": "2025-09-07T00:41:30.053639183Z",
"startTime": "2025-09-07T00:41:35.350408152Z",
"endTime": "2025-09-07T03:34:53.891207763Z",
"updateTime": "2025-09-07T03:34:53.891207763Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "51803bcb8afe4fc50565cb6261269e854d704779",
"ref": "refs/branch-heads/7390"
}
}
}
[03:34:54.271] Invocation finished in 2h53m25.551145562s with status SUCCEEDED