[23:39:01.475] New invocation is queued and will start shortly
[23:39:02.576] Starting the invocation (attempt 1)
[23:39:02.600] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/3279d0def7a0703566ad282c5169d3cda0a277ad"
[23:39:02.600] Popped gitiles commit info from properties and tags
[23:39:02.600] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[23:39:02.600] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[23:39:02.601] Buildbucket request:
{
"requestId": "8857814597100749968",
"builder": {
"project": "chromium-m142",
"bucket": "ci",
"builder": "win32-official"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8857814597100749968",
"job": "chromium-m142/win32-official",
"triggers": [
{
"gitiles": {
"ref": "refs/branch-heads/7444",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "f120f3199550f5be3f8d9daab72ba05331d69dee"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7444@f120f3199550f5be3f8d9daab72ba05331d69dee",
"title": "f120f3199550f5be3f8d9daab72ba05331d69dee",
"url": "https://chromium.googlesource.com/chromium/src/+/f120f3199550f5be3f8d9daab72ba05331d69dee"
},
{
"gitiles": {
"ref": "refs/branch-heads/7444",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "0f27991d438e99989387e63f6430cd9887c9dd3e"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7444@0f27991d438e99989387e63f6430cd9887c9dd3e",
"title": "0f27991d438e99989387e63f6430cd9887c9dd3e",
"url": "https://chromium.googlesource.com/chromium/src/+/0f27991d438e99989387e63f6430cd9887c9dd3e"
},
{
"gitiles": {
"ref": "refs/branch-heads/7444",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "3279d0def7a0703566ad282c5169d3cda0a277ad"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7444@3279d0def7a0703566ad282c5169d3cda0a277ad",
"title": "3279d0def7a0703566ad282c5169d3cda0a277ad",
"url": "https://chromium.googlesource.com/chromium/src/+/3279d0def7a0703566ad282c5169d3cda0a277ad"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "3279d0def7a0703566ad282c5169d3cda0a277ad",
"ref": "refs/branch-heads/7444"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8857814597100749968"
},
{
"key": "scheduler_job_id",
"value": "chromium-m142/win32-official"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[23:39:03.075] Scheduled build:
{
"id": "8692384535194929137",
"builder": {
"project": "chromium-m142",
"bucket": "ci",
"builder": "win32-official"
},
"number": 678,
"createdBy": "project:chromium-m142",
"createTime": "2026-01-17T23:39:02.665204291Z",
"updateTime": "2026-01-17T23:39:02.665204291Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "3279d0def7a0703566ad282c5169d3cda0a277ad",
"ref": "refs/branch-heads/7444"
}
}
}
[23:39:03.075] Task URL: https://cr-buildbucket.appspot.com/build/8692384535194929137
[23:39:03.075] Scheduling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8857814597100749968:2:0) after 1m14s
[23:39:04.061] Received PubSub notification, asking Buildbucket for the build status
[23:39:04.097] Build status: SCHEDULED
[23:40:17.092] Handling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8857814597100749968:2:0)
[23:40:17.112] Build status: SCHEDULED
[23:40:17.112] Scheduling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8857814597100749968:4:0) after 8m21s
[23:42:28.819] Received PubSub notification, asking Buildbucket for the build status
[23:42:28.882] Build status: STARTED
[23:48:38.386] Handling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8857814597100749968:4:0)
[23:48:38.403] Build status: STARTED
[23:48:38.403] Scheduling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8857814597100749968:6:0) after 3m49s
[23:52:27.538] Handling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8857814597100749968:6:0)
[23:52:27.557] Build status: STARTED
[23:52:27.557] Scheduling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8857814597100749968:7:0) after 6m30s
[23:58:57.573] Handling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8857814597100749968:7:0)
[23:58:57.587] Build status: STARTED
[23:58:57.587] Scheduling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8857814597100749968:8:0) after 1m36s
[00:00:33.602] Handling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8857814597100749968:8:0)
[00:00:33.618] Build status: STARTED
[00:00:33.618] Scheduling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8857814597100749968:9:0) after 2m48s
[00:03:21.784] Handling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8857814597100749968:9:0)
[00:03:21.798] Build status: STARTED
[00:03:21.798] Scheduling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8857814597100749968:10:0) after 6m11s
[00:09:32.810] Handling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8857814597100749968:10:0)
[00:09:33.019] Build status: STARTED
[00:09:33.019] Scheduling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8857814597100749968:11:0) after 9m54s
[00:19:27.234] Handling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8857814597100749968:11:0)
[00:19:27.251] Build status: STARTED
[00:19:27.251] Scheduling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8857814597100749968:12:0) after 4m6s
[00:23:33.490] Handling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8857814597100749968:12:0)
[00:23:33.524] Build status: STARTED
[00:23:33.524] Scheduling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8857814597100749968:13:0) after 2m45s
[00:26:18.738] Handling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8857814597100749968:13:0)
[00:26:18.762] Build status: STARTED
[00:26:18.762] Scheduling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8857814597100749968:14:0) after 5m39s
[00:31:57.908] Handling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8857814597100749968:14:0)
[00:31:58.018] Build status: STARTED
[00:31:58.018] Scheduling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8857814597100749968:15:0) after 7m37s
[00:39:35.086] Handling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8857814597100749968:15:0)
[00:39:35.115] Build status: STARTED
[00:39:35.115] Scheduling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8857814597100749968:16:0) after 4m23s
[00:43:58.242] Handling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8857814597100749968:16:0)
[00:43:58.273] Build status: STARTED
[00:43:58.273] Scheduling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8857814597100749968:17:0) after 4m30s
[00:48:28.287] Handling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8857814597100749968:17:0)
[00:48:28.337] Build status: STARTED
[00:48:28.337] Scheduling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8857814597100749968:18:0) after 6m10s
[00:54:38.439] Handling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8857814597100749968:18:0)
[00:54:38.457] Build status: STARTED
[00:54:38.458] Scheduling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8857814597100749968:19:0) after 3m24s
[00:58:02.744] Handling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8857814597100749968:19:0)
[00:58:02.775] Build status: STARTED
[00:58:02.775] Scheduling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8857814597100749968:20:0) after 5m12s
[01:03:15.511] Handling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8857814597100749968:20:0)
[01:03:15.560] Build status: STARTED
[01:03:15.560] Scheduling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8857814597100749968:21:0) after 6m31s
[01:09:46.575] Handling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8857814597100749968:21:0)
[01:09:46.599] Build status: STARTED
[01:09:46.599] Scheduling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8857814597100749968:22:0) after 1m48s
[01:11:34.611] Handling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8857814597100749968:22:0)
[01:11:34.668] Build status: STARTED
[01:11:34.668] Scheduling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8857814597100749968:23:0) after 2m9s
[01:13:43.694] Handling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8857814597100749968:23:0)
[01:13:43.715] Build status: STARTED
[01:13:43.715] Scheduling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8857814597100749968:24:0) after 2m8s
[01:15:51.765] Handling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8857814597100749968:24:0)
[01:15:51.782] Build status: STARTED
[01:15:51.782] Scheduling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8857814597100749968:25:0) after 1m36s
[01:17:27.796] Handling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8857814597100749968:25:0)
[01:17:27.945] Build status: STARTED
[01:17:27.945] Scheduling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8857814597100749968:26:0) after 8m12s
[01:25:40.156] Handling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8857814597100749968:26:0)
[01:25:40.175] Build status: STARTED
[01:25:40.175] Scheduling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8857814597100749968:27:0) after 1m39s
[01:27:19.210] Handling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8857814597100749968:27:0)
[01:27:19.226] Build status: STARTED
[01:27:19.226] Scheduling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8857814597100749968:28:0) after 5m43s
[01:33:02.394] Handling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8857814597100749968:28:0)
[01:33:02.409] Build status: STARTED
[01:33:02.409] Scheduling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8857814597100749968:29:0) after 4m52s
[01:37:54.424] Handling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8857814597100749968:29:0)
[01:37:54.444] Build status: STARTED
[01:37:54.444] Scheduling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8857814597100749968:30:0) after 1m38s
[01:39:32.558] Handling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8857814597100749968:30:0)
[01:39:32.589] Build status: STARTED
[01:39:32.589] Scheduling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8857814597100749968:31:0) after 3m43s
[01:43:15.989] Handling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8857814597100749968:31:0)
[01:43:16.033] Build status: STARTED
[01:43:16.033] Scheduling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8857814597100749968:32:0) after 1m6s
[01:44:22.079] Handling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8857814597100749968:32:0)
[01:44:22.097] Build status: STARTED
[01:44:22.097] Scheduling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8857814597100749968:33:0) after 4m32s
[01:48:54.270] Handling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8857814597100749968:33:0)
[01:48:55.371] Build status: STARTED
[01:48:55.371] Scheduling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8857814597100749968:34:0) after 9m48s
[01:58:43.629] Handling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8857814597100749968:34:0)
[01:58:43.646] Build status: STARTED
[01:58:43.646] Scheduling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8857814597100749968:35:0) after 8m14s
[02:06:57.689] Handling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8857814597100749968:35:0)
[02:06:57.705] Build status: STARTED
[02:06:57.705] Scheduling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8857814597100749968:36:0) after 1m52s
[02:08:49.799] Handling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8857814597100749968:36:0)
[02:08:49.816] Build status: STARTED
[02:08:49.816] Scheduling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8857814597100749968:37:0) after 1m6s
[02:09:55.800] Handling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8857814597100749968:37:0)
[02:09:55.817] Build status: STARTED
[02:09:55.817] Scheduling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8857814597100749968:38:0) after 5m16s
[02:15:11.994] Handling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8857814597100749968:38:0)
[02:15:12.012] Build status: STARTED
[02:15:12.012] Scheduling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8857814597100749968:39:0) after 5m1s
[02:20:13.261] Handling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8857814597100749968:39:0)
[02:20:13.273] Build status: STARTED
[02:20:13.273] Scheduling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8857814597100749968:40:0) after 8m16s
[02:28:29.289] Handling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8857814597100749968:40:0)
[02:28:29.305] Build status: STARTED
[02:28:29.305] Scheduling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8857814597100749968:41:0) after 1m24s
[02:29:53.548] Handling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8857814597100749968:41:0)
[02:29:53.565] Build status: STARTED
[02:29:53.565] Scheduling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8857814597100749968:42:0) after 1m48s
[02:31:41.837] Handling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8857814597100749968:42:0)
[02:31:41.852] Build status: STARTED
[02:31:41.852] Scheduling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8857814597100749968:43:0) after 9m46s
[02:41:27.898] Handling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8857814597100749968:43:0)
[02:41:27.917] Build status: STARTED
[02:41:27.917] Scheduling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8857814597100749968:44:0) after 6m35s
[02:48:02.927] Handling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8857814597100749968:44:0)
[02:48:02.941] Build status: STARTED
[02:48:02.941] Scheduling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8857814597100749968:45:0) after 1m41s
[02:49:43.963] Handling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8857814597100749968:45:0)
[02:49:44.021] Build status: STARTED
[02:49:44.021] Scheduling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8857814597100749968:46:0) after 1m48s
[02:51:32.202] Handling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8857814597100749968:46:0)
[02:51:32.227] Build status: STARTED
[02:51:32.227] Scheduling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8857814597100749968:47:0) after 3m2s
[02:54:34.282] Handling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8857814597100749968:47:0)
[02:54:34.306] Build status: STARTED
[02:54:34.306] Scheduling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8857814597100749968:48:0) after 4m12s
[02:58:46.301] Handling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8857814597100749968:48:0)
[02:58:46.326] Build status: STARTED
[02:58:46.326] Scheduling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8857814597100749968:49:0) after 8m44s
[03:07:30.866] Handling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8857814597100749968:49:0)
[03:07:30.896] Build status: STARTED
[03:07:30.896] Scheduling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8857814597100749968:50:0) after 4m17s
[03:11:47.969] Handling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8857814597100749968:50:0)
[03:11:48.102] Build status: STARTED
[03:11:48.102] Scheduling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8857814597100749968:51:0) after 8m4s
[03:14:36.403] Received PubSub notification, asking Buildbucket for the build status
[03:14:36.438] Build:
{
"id": "8692384535194929137",
"builder": {
"project": "chromium-m142",
"bucket": "ci",
"builder": "win32-official"
},
"number": 678,
"createdBy": "project:chromium-m142",
"createTime": "2026-01-17T23:39:02.665204291Z",
"startTime": "2026-01-17T23:42:28.681657527Z",
"endTime": "2026-01-18T03:14:36.003584552Z",
"updateTime": "2026-01-18T03:14:36.003584552Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "3279d0def7a0703566ad282c5169d3cda0a277ad",
"ref": "refs/branch-heads/7444"
}
}
}
[03:14:36.438] Invocation finished in 3h35m34.974584734s with status SUCCEEDED