[14:21:56.060] New invocation is queued and will start shortly
[14:21:57.115] Starting the invocation (attempt 1)
[14:21:57.140] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/11b8cca51077fbd1c22bf7c73e61579bbbe92eba"
[14:21:57.140] Popped gitiles commit info from properties and tags
[14:21:57.140] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[14:21:57.141] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[14:21:57.141] Buildbucket request:
{
"requestId": "8857124870457292160",
"builder": {
"project": "chromium-m145",
"bucket": "ci",
"builder": "win32-official"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8857124870457292160",
"job": "chromium-m145/win32-official",
"triggers": [
{
"gitiles": {
"ref": "refs/branch-heads/7632",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "61866f8433c321718d030d1618bf2f4ff27f9930"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7632@61866f8433c321718d030d1618bf2f4ff27f9930",
"title": "61866f8433c321718d030d1618bf2f4ff27f9930",
"url": "https://chromium.googlesource.com/chromium/src/+/61866f8433c321718d030d1618bf2f4ff27f9930"
},
{
"gitiles": {
"ref": "refs/branch-heads/7632",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "3c06f3a2b359473b60eeff94cf4865bacca6d688"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7632@3c06f3a2b359473b60eeff94cf4865bacca6d688",
"title": "3c06f3a2b359473b60eeff94cf4865bacca6d688",
"url": "https://chromium.googlesource.com/chromium/src/+/3c06f3a2b359473b60eeff94cf4865bacca6d688"
},
{
"gitiles": {
"ref": "refs/branch-heads/7632",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "fac445b07cd176dfc986d936e26d30946b80f47b"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7632@fac445b07cd176dfc986d936e26d30946b80f47b",
"title": "fac445b07cd176dfc986d936e26d30946b80f47b",
"url": "https://chromium.googlesource.com/chromium/src/+/fac445b07cd176dfc986d936e26d30946b80f47b"
},
{
"gitiles": {
"ref": "refs/branch-heads/7632",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "78a9ce54c037648d260d559e7cc82c03e83916f9"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7632@78a9ce54c037648d260d559e7cc82c03e83916f9",
"title": "78a9ce54c037648d260d559e7cc82c03e83916f9",
"url": "https://chromium.googlesource.com/chromium/src/+/78a9ce54c037648d260d559e7cc82c03e83916f9"
},
{
"gitiles": {
"ref": "refs/branch-heads/7632",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "8d88c29b3d02c8e045540ebb99e3fab1deece6aa"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7632@8d88c29b3d02c8e045540ebb99e3fab1deece6aa",
"title": "8d88c29b3d02c8e045540ebb99e3fab1deece6aa",
"url": "https://chromium.googlesource.com/chromium/src/+/8d88c29b3d02c8e045540ebb99e3fab1deece6aa"
},
{
"gitiles": {
"ref": "refs/branch-heads/7632",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "0c189aa23c5b5b9c0fd7c8a0f6a121bbdfd5e470"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7632@0c189aa23c5b5b9c0fd7c8a0f6a121bbdfd5e470",
"title": "0c189aa23c5b5b9c0fd7c8a0f6a121bbdfd5e470",
"url": "https://chromium.googlesource.com/chromium/src/+/0c189aa23c5b5b9c0fd7c8a0f6a121bbdfd5e470"
},
{
"gitiles": {
"ref": "refs/branch-heads/7632",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "e91b0f3b578e555899b9515f52f254b3d302a7bd"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7632@e91b0f3b578e555899b9515f52f254b3d302a7bd",
"title": "e91b0f3b578e555899b9515f52f254b3d302a7bd",
"url": "https://chromium.googlesource.com/chromium/src/+/e91b0f3b578e555899b9515f52f254b3d302a7bd"
},
{
"gitiles": {
"ref": "refs/branch-heads/7632",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "44a2689608d613d1aa2188c29d438ccc242f37f1"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7632@44a2689608d613d1aa2188c29d438ccc242f37f1",
"title": "44a2689608d613d1aa2188c29d438ccc242f37f1",
"url": "https://chromium.googlesource.com/chromium/src/+/44a2689608d613d1aa2188c29d438ccc242f37f1"
},
{
"gitiles": {
"ref": "refs/branch-heads/7632",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "31b8cfe6dfc8248ad174fa0ee2beb9e6edef2d80"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7632@31b8cfe6dfc8248ad174fa0ee2beb9e6edef2d80",
"title": "31b8cfe6dfc8248ad174fa0ee2beb9e6edef2d80",
"url": "https://chromium.googlesource.com/chromium/src/+/31b8cfe6dfc8248ad174fa0ee2beb9e6edef2d80"
},
{
"gitiles": {
"ref": "refs/branch-heads/7632",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "ba1329b2a52e48879b69babf6882ad824ec01361"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7632@ba1329b2a52e48879b69babf6882ad824ec01361",
"title": "ba1329b2a52e48879b69babf6882ad824ec01361",
"url": "https://chromium.googlesource.com/chromium/src/+/ba1329b2a52e48879b69babf6882ad824ec01361"
},
{
"gitiles": {
"ref": "refs/branch-heads/7632",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "11b8cca51077fbd1c22bf7c73e61579bbbe92eba"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7632@11b8cca51077fbd1c22bf7c73e61579bbbe92eba",
"title": "11b8cca51077fbd1c22bf7c73e61579bbbe92eba",
"url": "https://chromium.googlesource.com/chromium/src/+/11b8cca51077fbd1c22bf7c73e61579bbbe92eba"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "11b8cca51077fbd1c22bf7c73e61579bbbe92eba",
"ref": "refs/branch-heads/7632"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8857124870457292160"
},
{
"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"
}
}
[14:21:57.771] Scheduled build:
{
"id": "8691694808558479057",
"builder": {
"project": "chromium-m145",
"bucket": "ci",
"builder": "win32-official"
},
"number": 68,
"createdBy": "project:chromium-m145",
"createTime": "2026-01-25T14:21:57.244860459Z",
"updateTime": "2026-01-25T14:21:57.244860459Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "11b8cca51077fbd1c22bf7c73e61579bbbe92eba",
"ref": "refs/branch-heads/7632"
}
}
}
[14:21:57.771] Task URL: https://cr-buildbucket.appspot.com/build/8691694808558479057
[14:21:57.771] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8857124870457292160:2:0) after 1m50s
[14:21:58.775] Received PubSub notification, asking Buildbucket for the build status
[14:21:58.792] Build status: SCHEDULED
[14:22:02.716] Received PubSub notification, asking Buildbucket for the build status
[14:22:02.734] Build status: STARTED
[14:23:47.860] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8857124870457292160:2:0)
[14:23:47.884] Build status: STARTED
[14:23:47.884] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8857124870457292160:5:0) after 8m45s
[14:32:32.901] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8857124870457292160:5:0)
[14:32:32.962] Build status: STARTED
[14:32:32.962] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8857124870457292160:6:0) after 4m53s
[14:37:25.989] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8857124870457292160:6:0)
[14:37:26.004] Build status: STARTED
[14:37:26.004] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8857124870457292160:7:0) after 5m17s
[14:42:43.020] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8857124870457292160:7:0)
[14:42:43.041] Build status: STARTED
[14:42:43.041] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8857124870457292160:8:0) after 5m41s
[14:48:24.361] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8857124870457292160:8:0)
[14:48:24.386] Build status: STARTED
[14:48:24.386] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8857124870457292160:9:0) after 8m15s
[14:56:39.444] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8857124870457292160:9:0)
[14:56:39.505] Build status: STARTED
[14:56:39.505] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8857124870457292160:10:0) after 5m20s
[15:01:59.530] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8857124870457292160:10:0)
[15:01:59.547] Build status: STARTED
[15:01:59.547] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8857124870457292160:11:0) after 3m18s
[15:05:17.625] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8857124870457292160:11:0)
[15:05:17.642] Build status: STARTED
[15:05:17.642] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8857124870457292160:12:0) after 2m17s
[15:07:34.966] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8857124870457292160:12:0)
[15:07:34.985] Build status: STARTED
[15:07:34.985] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8857124870457292160:13:0) after 7m35s
[15:15:10.003] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8857124870457292160:13:0)
[15:15:10.043] Build status: STARTED
[15:15:10.043] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8857124870457292160:14:0) after 1m6s
[15:16:16.101] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8857124870457292160:14:0)
[15:16:16.119] Build status: STARTED
[15:16:16.119] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8857124870457292160:15:0) after 6m48s
[15:23:04.214] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8857124870457292160:15:0)
[15:23:04.231] Build status: STARTED
[15:23:04.231] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8857124870457292160:16:0) after 2m13s
[15:25:17.248] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8857124870457292160:16:0)
[15:25:17.269] Build status: STARTED
[15:25:17.269] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8857124870457292160:17:0) after 6m28s
[15:31:45.385] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8857124870457292160:17:0)
[15:31:45.405] Build status: STARTED
[15:31:45.405] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8857124870457292160:18:0) after 6m57s
[15:38:42.518] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8857124870457292160:18:0)
[15:38:42.542] Build status: STARTED
[15:38:42.542] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8857124870457292160:19:0) after 1m55s
[15:40:37.931] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8857124870457292160:19:0)
[15:40:37.944] Build status: STARTED
[15:40:37.944] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8857124870457292160:20:0) after 6m56s
[15:47:33.958] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8857124870457292160:20:0)
[15:47:33.977] Build status: STARTED
[15:47:33.977] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8857124870457292160:21:0) after 6m16s
[15:53:50.033] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8857124870457292160:21:0)
[15:53:50.049] Build status: STARTED
[15:53:50.049] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8857124870457292160:22:0) after 6m20s
[16:00:10.171] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8857124870457292160:22:0)
[16:00:10.187] Build status: STARTED
[16:00:10.187] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8857124870457292160:23:0) after 2m10s
[16:02:20.206] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8857124870457292160:23:0)
[16:02:20.224] Build status: STARTED
[16:02:20.224] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8857124870457292160:24:0) after 7m20s
[16:09:40.436] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8857124870457292160:24:0)
[16:09:40.464] Build status: STARTED
[16:09:40.464] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8857124870457292160:25:0) after 3m14s
[16:12:54.572] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8857124870457292160:25:0)
[16:12:54.589] Build status: STARTED
[16:12:54.589] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8857124870457292160:26:0) after 7m56s
[16:20:50.665] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8857124870457292160:26:0)
[16:20:50.679] Build status: STARTED
[16:20:50.679] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8857124870457292160:27:0) after 1m34s
[16:22:24.746] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8857124870457292160:27:0)
[16:22:24.768] Build status: STARTED
[16:22:24.768] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8857124870457292160:28:0) after 2m25s
[16:24:49.754] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8857124870457292160:28:0)
[16:24:49.771] Build status: STARTED
[16:24:49.771] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8857124870457292160:29:0) after 4m59s
[16:29:49.010] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8857124870457292160:29:0)
[16:29:49.035] Build status: STARTED
[16:29:49.035] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8857124870457292160:30:0) after 7m9s
[16:36:58.129] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8857124870457292160:30:0)
[16:36:58.149] Build status: STARTED
[16:36:58.149] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8857124870457292160:31:0) after 7m27s
[16:44:25.165] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8857124870457292160:31:0)
[16:44:25.182] Build status: STARTED
[16:44:25.182] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8857124870457292160:32:0) after 6m35s
[16:51:00.203] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8857124870457292160:32:0)
[16:51:00.274] Build status: STARTED
[16:51:00.274] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8857124870457292160:33:0) after 6m15s
[16:57:15.430] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8857124870457292160:33:0)
[16:57:15.453] Build status: STARTED
[16:57:15.454] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8857124870457292160:34:0) after 4m54s
[17:02:09.567] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8857124870457292160:34:0)
[17:02:09.583] Build status: STARTED
[17:02:09.583] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8857124870457292160:35:0) after 9m49s
[17:11:58.697] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8857124870457292160:35:0)
[17:11:58.719] Build status: STARTED
[17:11:58.719] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8857124870457292160:36:0) after 5m27s
[17:17:25.939] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8857124870457292160:36:0)
[17:17:25.953] Build status: STARTED
[17:17:25.954] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8857124870457292160:37:0) after 9m48s
[17:27:14.543] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8857124870457292160:37:0)
[17:27:14.557] Build status: STARTED
[17:27:14.557] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8857124870457292160:38:0) after 9m54s
[17:37:08.573] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8857124870457292160:38:0)
[17:37:08.594] Build status: STARTED
[17:37:08.594] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8857124870457292160:39:0) after 5m17s
[17:42:25.610] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8857124870457292160:39:0)
[17:42:25.623] Build status: STARTED
[17:42:25.623] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8857124870457292160:40:0) after 4m30s
[17:46:55.712] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8857124870457292160:40:0)
[17:46:55.746] Build status: STARTED
[17:46:55.746] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8857124870457292160:41:0) after 9m19s
[17:56:14.963] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8857124870457292160:41:0)
[17:56:14.984] Build status: STARTED
[17:56:14.984] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8857124870457292160:42:0) after 8m11s
[18:04:26.009] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8857124870457292160:42:0)
[18:04:26.041] Build status: STARTED
[18:04:26.041] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8857124870457292160:43:0) after 1m45s
[18:06:11.059] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8857124870457292160:43:0)
[18:06:11.077] Build status: STARTED
[18:06:11.077] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8857124870457292160:44:0) after 9m22s
[18:15:33.225] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8857124870457292160:44:0)
[18:15:33.261] Build status: STARTED
[18:15:33.261] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8857124870457292160:45:0) after 6m39s
[18:22:12.501] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8857124870457292160:45:0)
[18:22:12.558] Build status: STARTED
[18:22:12.558] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8857124870457292160:46:0) after 3m29s
[18:25:41.734] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8857124870457292160:46:0)
[18:25:41.754] Build status: STARTED
[18:25:41.754] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8857124870457292160:47:0) after 7m18s
[18:32:59.821] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8857124870457292160:47:0)
[18:32:59.874] Build status: STARTED
[18:32:59.874] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8857124870457292160:48:0) after 7m55s
[18:33:11.426] Received PubSub notification, asking Buildbucket for the build status
[18:33:11.498] Build:
{
"id": "8691694808558479057",
"builder": {
"project": "chromium-m145",
"bucket": "ci",
"builder": "win32-official"
},
"number": 68,
"createdBy": "project:chromium-m145",
"createTime": "2026-01-25T14:21:57.244860459Z",
"startTime": "2026-01-25T14:22:02.358721236Z",
"endTime": "2026-01-25T18:33:07.423917576Z",
"updateTime": "2026-01-25T18:33:07.423917576Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "11b8cca51077fbd1c22bf7c73e61579bbbe92eba",
"ref": "refs/branch-heads/7632"
}
}
}
[18:33:11.498] Invocation finished in 4h11m15.448549228s with status SUCCEEDED