[02:08:08.136] New invocation is queued and will start shortly
[02:08:09.186] Starting the invocation (attempt 1)
[02:08:09.213] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/203cef6116b2454b98199fd2804e1a5395bd7f83"
[02:08:09.213] Popped gitiles commit info from properties and tags
[02:08:09.213] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[02:08:09.214] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[02:08:09.214] Buildbucket request:
{
"requestId": "8856536858324904000",
"builder": {
"project": "chromium-m145",
"bucket": "ci",
"builder": "win32-official"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8856536858324904000",
"job": "chromium-m145/win32-official",
"triggers": [
{
"gitiles": {
"ref": "refs/branch-heads/7632",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "a335c50cb74b97798553547a1d14429e9b0fd90d"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7632@a335c50cb74b97798553547a1d14429e9b0fd90d",
"title": "a335c50cb74b97798553547a1d14429e9b0fd90d",
"url": "https://chromium.googlesource.com/chromium/src/+/a335c50cb74b97798553547a1d14429e9b0fd90d"
},
{
"gitiles": {
"ref": "refs/branch-heads/7632",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "26104edcc9c3eb59624d2c9ca22e1290d5cc470a"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7632@26104edcc9c3eb59624d2c9ca22e1290d5cc470a",
"title": "26104edcc9c3eb59624d2c9ca22e1290d5cc470a",
"url": "https://chromium.googlesource.com/chromium/src/+/26104edcc9c3eb59624d2c9ca22e1290d5cc470a"
},
{
"gitiles": {
"ref": "refs/branch-heads/7632",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "85873d72d762f5681c3d23fdbe5dce6917d9a33c"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7632@85873d72d762f5681c3d23fdbe5dce6917d9a33c",
"title": "85873d72d762f5681c3d23fdbe5dce6917d9a33c",
"url": "https://chromium.googlesource.com/chromium/src/+/85873d72d762f5681c3d23fdbe5dce6917d9a33c"
},
{
"gitiles": {
"ref": "refs/branch-heads/7632",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "7b5cf4b1e4c929298abb872019880377cafb7a03"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7632@7b5cf4b1e4c929298abb872019880377cafb7a03",
"title": "7b5cf4b1e4c929298abb872019880377cafb7a03",
"url": "https://chromium.googlesource.com/chromium/src/+/7b5cf4b1e4c929298abb872019880377cafb7a03"
},
{
"gitiles": {
"ref": "refs/branch-heads/7632",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "87fcc5038fa4706eb57ab01489ec9d6156e935c0"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7632@87fcc5038fa4706eb57ab01489ec9d6156e935c0",
"title": "87fcc5038fa4706eb57ab01489ec9d6156e935c0",
"url": "https://chromium.googlesource.com/chromium/src/+/87fcc5038fa4706eb57ab01489ec9d6156e935c0"
},
{
"gitiles": {
"ref": "refs/branch-heads/7632",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "75a533e23d391e0d1dab8c38038170c1230a834b"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7632@75a533e23d391e0d1dab8c38038170c1230a834b",
"title": "75a533e23d391e0d1dab8c38038170c1230a834b",
"url": "https://chromium.googlesource.com/chromium/src/+/75a533e23d391e0d1dab8c38038170c1230a834b"
},
{
"gitiles": {
"ref": "refs/branch-heads/7632",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "577f2fb11e321f4258b81512bd38e50448de6c04"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7632@577f2fb11e321f4258b81512bd38e50448de6c04",
"title": "577f2fb11e321f4258b81512bd38e50448de6c04",
"url": "https://chromium.googlesource.com/chromium/src/+/577f2fb11e321f4258b81512bd38e50448de6c04"
},
{
"gitiles": {
"ref": "refs/branch-heads/7632",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "e90fe9923a95cb206013e87a291eb7115d0aa891"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7632@e90fe9923a95cb206013e87a291eb7115d0aa891",
"title": "e90fe9923a95cb206013e87a291eb7115d0aa891",
"url": "https://chromium.googlesource.com/chromium/src/+/e90fe9923a95cb206013e87a291eb7115d0aa891"
},
{
"gitiles": {
"ref": "refs/branch-heads/7632",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "04c629fe99e51225812d40fe74fd41269189e564"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7632@04c629fe99e51225812d40fe74fd41269189e564",
"title": "04c629fe99e51225812d40fe74fd41269189e564",
"url": "https://chromium.googlesource.com/chromium/src/+/04c629fe99e51225812d40fe74fd41269189e564"
},
{
"gitiles": {
"ref": "refs/branch-heads/7632",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "ca06b01b8223f1f8f6b400253fc4b3b58f72e8e8"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7632@ca06b01b8223f1f8f6b400253fc4b3b58f72e8e8",
"title": "ca06b01b8223f1f8f6b400253fc4b3b58f72e8e8",
"url": "https://chromium.googlesource.com/chromium/src/+/ca06b01b8223f1f8f6b400253fc4b3b58f72e8e8"
},
{
"gitiles": {
"ref": "refs/branch-heads/7632",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "203cef6116b2454b98199fd2804e1a5395bd7f83"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7632@203cef6116b2454b98199fd2804e1a5395bd7f83",
"title": "203cef6116b2454b98199fd2804e1a5395bd7f83",
"url": "https://chromium.googlesource.com/chromium/src/+/203cef6116b2454b98199fd2804e1a5395bd7f83"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "203cef6116b2454b98199fd2804e1a5395bd7f83",
"ref": "refs/branch-heads/7632"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8856536858324904000"
},
{
"key": "scheduler_job_id",
"value": "chromium-m145/win32-official"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[02:08:09.746] Scheduled build:
{
"id": "8691106796434618273",
"builder": {
"project": "chromium-m145",
"bucket": "ci",
"builder": "win32-official"
},
"number": 105,
"createdBy": "project:chromium-m145",
"createTime": "2026-02-01T02:08:09.304284557Z",
"updateTime": "2026-02-01T02:08:09.304284557Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "203cef6116b2454b98199fd2804e1a5395bd7f83",
"ref": "refs/branch-heads/7632"
}
}
}
[02:08:09.746] Task URL: https://cr-buildbucket.appspot.com/build/8691106796434618273
[02:08:09.747] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8856536858324904000:2:0) after 5m29s
[02:08:10.713] Received PubSub notification, asking Buildbucket for the build status
[02:08:10.729] Build status: SCHEDULED
[02:09:32.567] Received PubSub notification, asking Buildbucket for the build status
[02:09:32.599] Build status: STARTED
[02:13:38.765] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8856536858324904000:2:0)
[02:13:38.804] Build status: STARTED
[02:13:38.804] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8856536858324904000:5:0) after 4m28s
[02:18:06.901] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8856536858324904000:5:0)
[02:18:06.920] Build status: STARTED
[02:18:06.920] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8856536858324904000:6:0) after 3m44s
[02:21:51.019] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8856536858324904000:6:0)
[02:21:51.043] Build status: STARTED
[02:21:51.043] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8856536858324904000:7:0) after 2m27s
[02:24:18.065] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8856536858324904000:7:0)
[02:24:18.079] Build status: STARTED
[02:24:18.079] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8856536858324904000:8:0) after 4m6s
[02:28:24.094] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8856536858324904000:8:0)
[02:28:24.116] Build status: STARTED
[02:28:24.116] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8856536858324904000:9:0) after 7m33s
[02:35:57.256] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8856536858324904000:9:0)
[02:35:57.304] Build status: STARTED
[02:35:57.304] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8856536858324904000:10:0) after 8m13s
[02:44:10.665] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8856536858324904000:10:0)
[02:44:10.703] Build status: STARTED
[02:44:10.703] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8856536858324904000:11:0) after 6m1s
[02:50:11.716] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8856536858324904000:11:0)
[02:50:11.755] Build status: STARTED
[02:50:11.755] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8856536858324904000:12:0) after 2m2s
[02:52:13.775] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8856536858324904000:12:0)
[02:52:13.821] Build status: STARTED
[02:52:13.821] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8856536858324904000:13:0) after 5m2s
[02:57:15.932] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8856536858324904000:13:0)
[02:57:15.979] Build status: STARTED
[02:57:15.979] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8856536858324904000:14:0) after 8m50s
[03:06:05.994] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8856536858324904000:14:0)
[03:06:06.016] Build status: STARTED
[03:06:06.016] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8856536858324904000:15:0) after 2m41s
[03:08:48.025] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8856536858324904000:15:0)
[03:08:48.040] Build status: STARTED
[03:08:48.040] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8856536858324904000:16:0) after 6m33s
[03:15:21.942] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8856536858324904000:16:0)
[03:15:21.970] Build status: STARTED
[03:15:21.971] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8856536858324904000:17:0) after 4m45s
[03:20:07.506] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8856536858324904000:17:0)
[03:20:07.590] Build status: STARTED
[03:20:07.590] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8856536858324904000:18:0) after 9m4s
[03:29:12.010] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8856536858324904000:18:0)
[03:29:12.046] Build status: STARTED
[03:29:12.046] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8856536858324904000:19:0) after 9m39s
[03:38:51.175] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8856536858324904000:19:0)
[03:38:51.253] Build status: STARTED
[03:38:51.253] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8856536858324904000:20:0) after 2m24s
[03:41:15.265] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8856536858324904000:20:0)
[03:41:15.320] Build status: STARTED
[03:41:15.320] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8856536858324904000:21:0) after 9m40s
[03:50:55.435] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8856536858324904000:21:0)
[03:50:55.462] Build status: STARTED
[03:50:55.462] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8856536858324904000:22:0) after 4m52s
[03:55:47.441] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8856536858324904000:22:0)
[03:55:47.508] Build status: STARTED
[03:55:47.508] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8856536858324904000:23:0) after 4m16s
[04:00:03.625] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8856536858324904000:23:0)
[04:00:03.735] Build status: STARTED
[04:00:03.735] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8856536858324904000:24:0) after 5m8s
[04:05:11.968] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8856536858324904000:24:0)
[04:05:11.993] Build status: STARTED
[04:05:11.993] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8856536858324904000:25:0) after 4m5s
[04:09:17.083] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8856536858324904000:25:0)
[04:09:17.105] Build status: STARTED
[04:09:17.105] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8856536858324904000:26:0) after 7m14s
[04:16:31.244] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8856536858324904000:26:0)
[04:16:31.280] Build status: STARTED
[04:16:31.280] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8856536858324904000:27:0) after 7m56s
[04:24:27.386] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8856536858324904000:27:0)
[04:24:27.415] Build status: STARTED
[04:24:27.415] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8856536858324904000:28:0) after 5m48s
[04:30:15.715] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8856536858324904000:28:0)
[04:30:15.734] Build status: STARTED
[04:30:15.734] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8856536858324904000:29:0) after 4m43s
[04:34:58.768] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8856536858324904000:29:0)
[04:34:58.836] Build status: STARTED
[04:34:58.836] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8856536858324904000:30:0) after 4m57s
[04:39:55.900] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8856536858324904000:30:0)
[04:39:55.931] Build status: STARTED
[04:39:55.931] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8856536858324904000:31:0) after 4m29s
[04:44:24.957] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8856536858324904000:31:0)
[04:44:24.987] Build status: STARTED
[04:44:24.987] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8856536858324904000:32:0) after 2m52s
[04:47:17.232] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8856536858324904000:32:0)
[04:47:17.250] Build status: STARTED
[04:47:17.250] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8856536858324904000:33:0) after 5m32s
[04:52:49.273] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8856536858324904000:33:0)
[04:52:49.297] Build status: STARTED
[04:52:49.297] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8856536858324904000:34:0) after 1m53s
[04:54:42.320] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8856536858324904000:34:0)
[04:54:42.347] Build status: STARTED
[04:54:42.347] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8856536858324904000:35:0) after 2m25s
[04:57:07.375] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8856536858324904000:35:0)
[04:57:07.398] Build status: STARTED
[04:57:07.398] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8856536858324904000:36:0) after 4m38s
[05:01:45.594] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8856536858324904000:36:0)
[05:01:45.617] Build status: STARTED
[05:01:45.617] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8856536858324904000:37:0) after 4m13s
[05:05:58.662] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8856536858324904000:37:0)
[05:05:58.682] Build status: STARTED
[05:05:58.683] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8856536858324904000:38:0) after 8m45s
[05:14:43.707] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8856536858324904000:38:0)
[05:14:43.733] Build status: STARTED
[05:14:43.733] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8856536858324904000:39:0) after 9m28s
[05:24:11.766] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8856536858324904000:39:0)
[05:24:11.791] Build status: STARTED
[05:24:11.791] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8856536858324904000:40:0) after 2m57s
[05:27:09.046] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8856536858324904000:40:0)
[05:27:09.164] Build status: STARTED
[05:27:09.164] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8856536858324904000:41:0) after 7m0s
[05:34:09.429] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8856536858324904000:41:0)
[05:34:09.447] Build status: STARTED
[05:34:09.447] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8856536858324904000:42:0) after 5m52s
[05:40:01.554] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8856536858324904000:42:0)
[05:40:01.623] Build status: STARTED
[05:40:01.623] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8856536858324904000:43:0) after 5m54s
[05:45:55.783] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8856536858324904000:43:0)
[05:45:55.812] Build status: STARTED
[05:45:55.812] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8856536858324904000:44:0) after 9m4s
[05:54:59.927] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8856536858324904000:44:0)
[05:55:00.031] Build status: STARTED
[05:55:00.031] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8856536858324904000:45:0) after 8m24s
[06:03:24.201] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8856536858324904000:45:0)
[06:03:24.219] Build status: STARTED
[06:03:24.219] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8856536858324904000:46:0) after 3m54s
[06:07:18.243] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8856536858324904000:46:0)
[06:07:18.316] Build status: STARTED
[06:07:18.316] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8856536858324904000:47:0) after 3m8s
[06:10:26.558] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8856536858324904000:47:0)
[06:10:26.576] Build status: STARTED
[06:10:26.576] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8856536858324904000:48:0) after 2m28s
[06:12:54.805] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8856536858324904000:48:0)
[06:12:54.822] Build status: STARTED
[06:12:54.822] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8856536858324904000:49:0) after 5m29s
[06:18:23.862] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8856536858324904000:49:0)
[06:18:24.001] Build status: STARTED
[06:18:24.001] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8856536858324904000:50:0) after 6m37s
[06:24:33.430] Received PubSub notification, asking Buildbucket for the build status
[06:24:33.459] Build:
{
"id": "8691106796434618273",
"builder": {
"project": "chromium-m145",
"bucket": "ci",
"builder": "win32-official"
},
"number": 105,
"createdBy": "project:chromium-m145",
"createTime": "2026-02-01T02:08:09.304284557Z",
"startTime": "2026-02-01T02:09:32.390229106Z",
"endTime": "2026-02-01T06:24:33.165668670Z",
"updateTime": "2026-02-01T06:24:33.165668670Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "203cef6116b2454b98199fd2804e1a5395bd7f83",
"ref": "refs/branch-heads/7632"
}
}
}
[06:24:33.459] Invocation finished in 4h16m25.34165991s with status SUCCEEDED