[11:36:47.071] New invocation is queued and will start shortly
[11:36:48.162] Starting the invocation (attempt 1)
[11:36:48.185] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/509c4ceb1812f241204ab1c3df506a95dc3048c6"
[11:36:48.185] Popped gitiles commit info from properties and tags
[11:36:48.185] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[11:36:48.185] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[11:36:48.185] Buildbucket request:
{
"requestId": "8886307483966200432",
"builder": {
"project": "chromium-m135",
"bucket": "ci",
"builder": "win32-official"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8886307483966200432",
"job": "chromium-m135/win32-official",
"triggers": [
{
"gitiles": {
"ref": "refs/branch-heads/7049",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "d725d435187b4bca74a7163abbd0b02bc913f914"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7049@d725d435187b4bca74a7163abbd0b02bc913f914",
"title": "d725d435187b4bca74a7163abbd0b02bc913f914",
"url": "https://chromium.googlesource.com/chromium/src/+/d725d435187b4bca74a7163abbd0b02bc913f914"
},
{
"gitiles": {
"ref": "refs/branch-heads/7049",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "52157a6b7adab7b367bae72443a97980ad673866"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7049@52157a6b7adab7b367bae72443a97980ad673866",
"title": "52157a6b7adab7b367bae72443a97980ad673866",
"url": "https://chromium.googlesource.com/chromium/src/+/52157a6b7adab7b367bae72443a97980ad673866"
},
{
"gitiles": {
"ref": "refs/branch-heads/7049",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "62df5c22a6a6847dd79445ce6f13e663e70fb2ef"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7049@62df5c22a6a6847dd79445ce6f13e663e70fb2ef",
"title": "62df5c22a6a6847dd79445ce6f13e663e70fb2ef",
"url": "https://chromium.googlesource.com/chromium/src/+/62df5c22a6a6847dd79445ce6f13e663e70fb2ef"
},
{
"gitiles": {
"ref": "refs/branch-heads/7049",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "4715369fba087e579675fc8878ca17da6be3542c"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7049@4715369fba087e579675fc8878ca17da6be3542c",
"title": "4715369fba087e579675fc8878ca17da6be3542c",
"url": "https://chromium.googlesource.com/chromium/src/+/4715369fba087e579675fc8878ca17da6be3542c"
},
{
"gitiles": {
"ref": "refs/branch-heads/7049",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "4adf4f3928c3dd30e6e08a0a5f2d8e45cf866222"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7049@4adf4f3928c3dd30e6e08a0a5f2d8e45cf866222",
"title": "4adf4f3928c3dd30e6e08a0a5f2d8e45cf866222",
"url": "https://chromium.googlesource.com/chromium/src/+/4adf4f3928c3dd30e6e08a0a5f2d8e45cf866222"
},
{
"gitiles": {
"ref": "refs/branch-heads/7049",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "53adab1ce2e2055f5a003996c5bf04d2c63b87e5"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7049@53adab1ce2e2055f5a003996c5bf04d2c63b87e5",
"title": "53adab1ce2e2055f5a003996c5bf04d2c63b87e5",
"url": "https://chromium.googlesource.com/chromium/src/+/53adab1ce2e2055f5a003996c5bf04d2c63b87e5"
},
{
"gitiles": {
"ref": "refs/branch-heads/7049",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "509c4ceb1812f241204ab1c3df506a95dc3048c6"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7049@509c4ceb1812f241204ab1c3df506a95dc3048c6",
"title": "509c4ceb1812f241204ab1c3df506a95dc3048c6",
"url": "https://chromium.googlesource.com/chromium/src/+/509c4ceb1812f241204ab1c3df506a95dc3048c6"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "509c4ceb1812f241204ab1c3df506a95dc3048c6",
"ref": "refs/branch-heads/7049"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8886307483966200432"
},
{
"key": "scheduler_job_id",
"value": "chromium-m135/win32-official"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[11:36:48.753] Scheduled build:
{
"id": "8720877422069918753",
"builder": {
"project": "chromium-m135",
"bucket": "ci",
"builder": "win32-official"
},
"number": 33,
"createdBy": "project:chromium-m135",
"createTime": "2025-03-09T11:36:48.252194674Z",
"updateTime": "2025-03-09T11:36:48.252194674Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "509c4ceb1812f241204ab1c3df506a95dc3048c6",
"ref": "refs/branch-heads/7049"
}
}
}
[11:36:48.753] Task URL: https://cr-buildbucket.appspot.com/build/8720877422069918753
[11:36:48.753] Scheduling timer "check-buildbucket-build-status" (chromium-m135/win32-official:8886307483966200432:2:0) after 7m40s
[11:36:49.746] Received PubSub notification, asking Buildbucket for the build status
[11:36:49.763] Build status: SCHEDULED
[11:36:53.889] Received PubSub notification, asking Buildbucket for the build status
[11:36:53.909] Build status: STARTED
[11:44:28.790] Handling timer "check-buildbucket-build-status" (chromium-m135/win32-official:8886307483966200432:2:0)
[11:44:28.812] Build status: STARTED
[11:44:28.812] Scheduling timer "check-buildbucket-build-status" (chromium-m135/win32-official:8886307483966200432:5:0) after 4m3s
[11:48:31.926] Handling timer "check-buildbucket-build-status" (chromium-m135/win32-official:8886307483966200432:5:0)
[11:48:31.950] Build status: STARTED
[11:48:31.950] Scheduling timer "check-buildbucket-build-status" (chromium-m135/win32-official:8886307483966200432:6:0) after 4m16s
[11:52:47.997] Handling timer "check-buildbucket-build-status" (chromium-m135/win32-official:8886307483966200432:6:0)
[11:52:48.087] Build status: STARTED
[11:52:48.087] Scheduling timer "check-buildbucket-build-status" (chromium-m135/win32-official:8886307483966200432:7:0) after 6m15s
[11:59:03.099] Handling timer "check-buildbucket-build-status" (chromium-m135/win32-official:8886307483966200432:7:0)
[11:59:03.124] Build status: STARTED
[11:59:03.124] Scheduling timer "check-buildbucket-build-status" (chromium-m135/win32-official:8886307483966200432:8:0) after 6m39s
[12:05:42.240] Handling timer "check-buildbucket-build-status" (chromium-m135/win32-official:8886307483966200432:8:0)
[12:05:42.260] Build status: STARTED
[12:05:42.260] Scheduling timer "check-buildbucket-build-status" (chromium-m135/win32-official:8886307483966200432:9:0) after 2m24s
[12:08:06.420] Handling timer "check-buildbucket-build-status" (chromium-m135/win32-official:8886307483966200432:9:0)
[12:08:06.442] Build status: STARTED
[12:08:06.442] Scheduling timer "check-buildbucket-build-status" (chromium-m135/win32-official:8886307483966200432:10:0) after 4m54s
[12:13:00.615] Handling timer "check-buildbucket-build-status" (chromium-m135/win32-official:8886307483966200432:10:0)
[12:13:00.634] Build status: STARTED
[12:13:00.634] Scheduling timer "check-buildbucket-build-status" (chromium-m135/win32-official:8886307483966200432:11:0) after 7m23s
[12:20:23.793] Handling timer "check-buildbucket-build-status" (chromium-m135/win32-official:8886307483966200432:11:0)
[12:20:23.813] Build status: STARTED
[12:20:23.813] Scheduling timer "check-buildbucket-build-status" (chromium-m135/win32-official:8886307483966200432:12:0) after 4m21s
[12:24:44.873] Handling timer "check-buildbucket-build-status" (chromium-m135/win32-official:8886307483966200432:12:0)
[12:24:44.893] Build status: STARTED
[12:24:44.893] Scheduling timer "check-buildbucket-build-status" (chromium-m135/win32-official:8886307483966200432:13:0) after 4m56s
[12:29:40.994] Handling timer "check-buildbucket-build-status" (chromium-m135/win32-official:8886307483966200432:13:0)
[12:29:41.019] Build status: STARTED
[12:29:41.019] Scheduling timer "check-buildbucket-build-status" (chromium-m135/win32-official:8886307483966200432:14:0) after 1m45s
[12:31:26.041] Handling timer "check-buildbucket-build-status" (chromium-m135/win32-official:8886307483966200432:14:0)
[12:31:26.067] Build status: STARTED
[12:31:26.068] Scheduling timer "check-buildbucket-build-status" (chromium-m135/win32-official:8886307483966200432:15:0) after 7m21s
[12:38:47.261] Handling timer "check-buildbucket-build-status" (chromium-m135/win32-official:8886307483966200432:15:0)
[12:38:47.290] Build status: STARTED
[12:38:47.290] Scheduling timer "check-buildbucket-build-status" (chromium-m135/win32-official:8886307483966200432:16:0) after 9m24s
[12:48:11.430] Handling timer "check-buildbucket-build-status" (chromium-m135/win32-official:8886307483966200432:16:0)
[12:48:11.454] Build status: STARTED
[12:48:11.454] Scheduling timer "check-buildbucket-build-status" (chromium-m135/win32-official:8886307483966200432:17:0) after 1m51s
[12:50:02.579] Handling timer "check-buildbucket-build-status" (chromium-m135/win32-official:8886307483966200432:17:0)
[12:50:02.598] Build status: STARTED
[12:50:02.598] Scheduling timer "check-buildbucket-build-status" (chromium-m135/win32-official:8886307483966200432:18:0) after 9m53s
[12:59:55.722] Handling timer "check-buildbucket-build-status" (chromium-m135/win32-official:8886307483966200432:18:0)
[12:59:55.776] Build status: STARTED
[12:59:55.776] Scheduling timer "check-buildbucket-build-status" (chromium-m135/win32-official:8886307483966200432:19:0) after 7m37s
[13:07:32.851] Handling timer "check-buildbucket-build-status" (chromium-m135/win32-official:8886307483966200432:19:0)
[13:07:32.869] Build status: STARTED
[13:07:32.869] Scheduling timer "check-buildbucket-build-status" (chromium-m135/win32-official:8886307483966200432:20:0) after 8m40s
[13:16:12.893] Handling timer "check-buildbucket-build-status" (chromium-m135/win32-official:8886307483966200432:20:0)
[13:16:12.917] Build status: STARTED
[13:16:12.917] Scheduling timer "check-buildbucket-build-status" (chromium-m135/win32-official:8886307483966200432:21:0) after 2m22s
[13:18:35.253] Handling timer "check-buildbucket-build-status" (chromium-m135/win32-official:8886307483966200432:21:0)
[13:18:35.333] Build status: STARTED
[13:18:35.333] Scheduling timer "check-buildbucket-build-status" (chromium-m135/win32-official:8886307483966200432:22:0) after 2m51s
[13:21:26.454] Handling timer "check-buildbucket-build-status" (chromium-m135/win32-official:8886307483966200432:22:0)
[13:21:26.496] Build status: STARTED
[13:21:26.496] Scheduling timer "check-buildbucket-build-status" (chromium-m135/win32-official:8886307483966200432:23:0) after 3m42s
[13:25:08.518] Handling timer "check-buildbucket-build-status" (chromium-m135/win32-official:8886307483966200432:23:0)
[13:25:08.567] Build status: STARTED
[13:25:08.567] Scheduling timer "check-buildbucket-build-status" (chromium-m135/win32-official:8886307483966200432:24:0) after 6m30s
[13:31:38.594] Handling timer "check-buildbucket-build-status" (chromium-m135/win32-official:8886307483966200432:24:0)
[13:31:38.614] Build status: STARTED
[13:31:38.614] Scheduling timer "check-buildbucket-build-status" (chromium-m135/win32-official:8886307483966200432:25:0) after 8m25s
[13:40:03.647] Handling timer "check-buildbucket-build-status" (chromium-m135/win32-official:8886307483966200432:25:0)
[13:40:03.665] Build status: STARTED
[13:40:03.665] Scheduling timer "check-buildbucket-build-status" (chromium-m135/win32-official:8886307483966200432:26:0) after 8m2s
[13:48:05.785] Handling timer "check-buildbucket-build-status" (chromium-m135/win32-official:8886307483966200432:26:0)
[13:48:05.805] Build status: STARTED
[13:48:05.805] Scheduling timer "check-buildbucket-build-status" (chromium-m135/win32-official:8886307483966200432:27:0) after 1m51s
[13:49:56.784] Handling timer "check-buildbucket-build-status" (chromium-m135/win32-official:8886307483966200432:27:0)
[13:49:56.877] Build status: STARTED
[13:49:56.877] Scheduling timer "check-buildbucket-build-status" (chromium-m135/win32-official:8886307483966200432:28:0) after 9m7s
[13:59:04.058] Handling timer "check-buildbucket-build-status" (chromium-m135/win32-official:8886307483966200432:28:0)
[13:59:04.080] Build status: STARTED
[13:59:04.080] Scheduling timer "check-buildbucket-build-status" (chromium-m135/win32-official:8886307483966200432:29:0) after 9m29s
[14:08:33.108] Handling timer "check-buildbucket-build-status" (chromium-m135/win32-official:8886307483966200432:29:0)
[14:08:33.123] Build status: STARTED
[14:08:33.123] Scheduling timer "check-buildbucket-build-status" (chromium-m135/win32-official:8886307483966200432:30:0) after 6m45s
[14:15:18.142] Handling timer "check-buildbucket-build-status" (chromium-m135/win32-official:8886307483966200432:30:0)
[14:15:18.159] Build status: STARTED
[14:15:18.159] Scheduling timer "check-buildbucket-build-status" (chromium-m135/win32-official:8886307483966200432:31:0) after 3m56s
[14:19:14.311] Handling timer "check-buildbucket-build-status" (chromium-m135/win32-official:8886307483966200432:31:0)
[14:19:14.327] Build status: STARTED
[14:19:14.327] Scheduling timer "check-buildbucket-build-status" (chromium-m135/win32-official:8886307483966200432:32:0) after 6m10s
[14:25:24.351] Handling timer "check-buildbucket-build-status" (chromium-m135/win32-official:8886307483966200432:32:0)
[14:25:24.449] Build status: STARTED
[14:25:24.449] Scheduling timer "check-buildbucket-build-status" (chromium-m135/win32-official:8886307483966200432:33:0) after 9m44s
[14:35:08.516] Handling timer "check-buildbucket-build-status" (chromium-m135/win32-official:8886307483966200432:33:0)
[14:35:08.535] Build status: STARTED
[14:35:08.535] Scheduling timer "check-buildbucket-build-status" (chromium-m135/win32-official:8886307483966200432:34:0) after 4m12s
[14:39:20.715] Handling timer "check-buildbucket-build-status" (chromium-m135/win32-official:8886307483966200432:34:0)
[14:39:20.757] Build status: STARTED
[14:39:20.757] Scheduling timer "check-buildbucket-build-status" (chromium-m135/win32-official:8886307483966200432:35:0) after 4m5s
[14:43:26.041] Handling timer "check-buildbucket-build-status" (chromium-m135/win32-official:8886307483966200432:35:0)
[14:43:26.061] Build status: STARTED
[14:43:26.061] Scheduling timer "check-buildbucket-build-status" (chromium-m135/win32-official:8886307483966200432:36:0) after 5m43s
[14:49:09.087] Handling timer "check-buildbucket-build-status" (chromium-m135/win32-official:8886307483966200432:36:0)
[14:49:09.116] Build status: STARTED
[14:49:09.116] Scheduling timer "check-buildbucket-build-status" (chromium-m135/win32-official:8886307483966200432:37:0) after 6m14s
[14:55:23.231] Handling timer "check-buildbucket-build-status" (chromium-m135/win32-official:8886307483966200432:37:0)
[14:55:23.251] Build status: STARTED
[14:55:23.251] Scheduling timer "check-buildbucket-build-status" (chromium-m135/win32-official:8886307483966200432:38:0) after 7m37s
[15:03:00.358] Handling timer "check-buildbucket-build-status" (chromium-m135/win32-official:8886307483966200432:38:0)
[15:03:00.377] Build status: STARTED
[15:03:00.377] Scheduling timer "check-buildbucket-build-status" (chromium-m135/win32-official:8886307483966200432:39:0) after 5m49s
[15:08:49.445] Handling timer "check-buildbucket-build-status" (chromium-m135/win32-official:8886307483966200432:39:0)
[15:08:49.463] Build status: STARTED
[15:08:49.463] Scheduling timer "check-buildbucket-build-status" (chromium-m135/win32-official:8886307483966200432:40:0) after 6m3s
[15:10:04.701] Received PubSub notification, asking Buildbucket for the build status
[15:10:04.751] Build:
{
"id": "8720877422069918753",
"builder": {
"project": "chromium-m135",
"bucket": "ci",
"builder": "win32-official"
},
"number": 33,
"createdBy": "project:chromium-m135",
"createTime": "2025-03-09T11:36:48.252194674Z",
"startTime": "2025-03-09T11:36:53.664029309Z",
"endTime": "2025-03-09T15:10:04.365408401Z",
"updateTime": "2025-03-09T15:10:04.365408401Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "509c4ceb1812f241204ab1c3df506a95dc3048c6",
"ref": "refs/branch-heads/7049"
}
}
}
[15:10:04.751] Invocation finished in 3h33m17.690901949s with status SUCCEEDED