[06:47:46.529] New invocation is queued and will start shortly
[06:47:47.603] Starting the invocation (attempt 1)
[06:47:47.629] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/317073632a3b0ede745928ca14830fce0c962f73"
[06:47:47.629] Popped gitiles commit info from properties and tags
[06:47:47.629] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[06:47:47.629] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[06:47:47.630] Buildbucket request:
{
"requestId": "8867028513002028912",
"builder": {
"project": "chromium-m140",
"bucket": "ci",
"builder": "win32-official"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8867028513002028912",
"job": "chromium-m140/win32-official",
"triggers": [
{
"gitiles": {
"ref": "refs/branch-heads/7339",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "55dcdbfd582163fb52dbc7e4943dcf130da2a7da"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7339@55dcdbfd582163fb52dbc7e4943dcf130da2a7da",
"title": "55dcdbfd582163fb52dbc7e4943dcf130da2a7da",
"url": "https://chromium.googlesource.com/chromium/src/+/55dcdbfd582163fb52dbc7e4943dcf130da2a7da"
},
{
"gitiles": {
"ref": "refs/branch-heads/7339",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "f08a3680895d0adf9ed7d39d97fcba821554aa38"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7339@f08a3680895d0adf9ed7d39d97fcba821554aa38",
"title": "f08a3680895d0adf9ed7d39d97fcba821554aa38",
"url": "https://chromium.googlesource.com/chromium/src/+/f08a3680895d0adf9ed7d39d97fcba821554aa38"
},
{
"gitiles": {
"ref": "refs/branch-heads/7339",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "8b19441dd872100138cd9a53e6d3f8ae748ce980"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7339@8b19441dd872100138cd9a53e6d3f8ae748ce980",
"title": "8b19441dd872100138cd9a53e6d3f8ae748ce980",
"url": "https://chromium.googlesource.com/chromium/src/+/8b19441dd872100138cd9a53e6d3f8ae748ce980"
},
{
"gitiles": {
"ref": "refs/branch-heads/7339",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "d4e838dc0438a1be21f8a2b59c3c716630c94e40"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7339@d4e838dc0438a1be21f8a2b59c3c716630c94e40",
"title": "d4e838dc0438a1be21f8a2b59c3c716630c94e40",
"url": "https://chromium.googlesource.com/chromium/src/+/d4e838dc0438a1be21f8a2b59c3c716630c94e40"
},
{
"gitiles": {
"ref": "refs/branch-heads/7339",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "9b217d6f2a8941885c50bbc3d35b7195e8b1e84b"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7339@9b217d6f2a8941885c50bbc3d35b7195e8b1e84b",
"title": "9b217d6f2a8941885c50bbc3d35b7195e8b1e84b",
"url": "https://chromium.googlesource.com/chromium/src/+/9b217d6f2a8941885c50bbc3d35b7195e8b1e84b"
},
{
"gitiles": {
"ref": "refs/branch-heads/7339",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "1206b92356b935c9d5a72100ef05b05c0130bce8"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7339@1206b92356b935c9d5a72100ef05b05c0130bce8",
"title": "1206b92356b935c9d5a72100ef05b05c0130bce8",
"url": "https://chromium.googlesource.com/chromium/src/+/1206b92356b935c9d5a72100ef05b05c0130bce8"
},
{
"gitiles": {
"ref": "refs/branch-heads/7339",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "c7b2195097471d20a5b2b8c1774100465c3665c8"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7339@c7b2195097471d20a5b2b8c1774100465c3665c8",
"title": "c7b2195097471d20a5b2b8c1774100465c3665c8",
"url": "https://chromium.googlesource.com/chromium/src/+/c7b2195097471d20a5b2b8c1774100465c3665c8"
},
{
"gitiles": {
"ref": "refs/branch-heads/7339",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "317073632a3b0ede745928ca14830fce0c962f73"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7339@317073632a3b0ede745928ca14830fce0c962f73",
"title": "317073632a3b0ede745928ca14830fce0c962f73",
"url": "https://chromium.googlesource.com/chromium/src/+/317073632a3b0ede745928ca14830fce0c962f73"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "317073632a3b0ede745928ca14830fce0c962f73",
"ref": "refs/branch-heads/7339"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8867028513002028912"
},
{
"key": "scheduler_job_id",
"value": "chromium-m140/win32-official"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[06:47:48.198] Scheduled build:
{
"id": "8701598451129446881",
"builder": {
"project": "chromium-m140",
"bucket": "ci",
"builder": "win32-official"
},
"number": 399,
"createdBy": "project:chromium-m140",
"createTime": "2025-10-08T06:47:47.685915458Z",
"updateTime": "2025-10-08T06:47:47.685915458Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "317073632a3b0ede745928ca14830fce0c962f73",
"ref": "refs/branch-heads/7339"
}
}
}
[06:47:48.198] Task URL: https://cr-buildbucket.appspot.com/build/8701598451129446881
[06:47:48.198] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8867028513002028912:2:0) after 7m23s
[06:47:49.123] Received PubSub notification, asking Buildbucket for the build status
[06:47:49.160] Build status: SCHEDULED
[06:47:53.042] Received PubSub notification, asking Buildbucket for the build status
[06:47:53.064] Build status: STARTED
[06:55:11.213] Handling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8867028513002028912:2:0)
[06:55:11.251] Build status: STARTED
[06:55:11.251] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8867028513002028912:5:0) after 9m11s
[07:04:22.305] Handling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8867028513002028912:5:0)
[07:04:22.328] Build status: STARTED
[07:04:22.328] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8867028513002028912:6:0) after 7m44s
[07:12:06.427] Handling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8867028513002028912:6:0)
[07:12:06.492] Build status: STARTED
[07:12:06.492] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8867028513002028912:7:0) after 1m25s
[07:13:31.510] Handling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8867028513002028912:7:0)
[07:13:31.698] Build status: STARTED
[07:13:31.698] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8867028513002028912:8:0) after 9m8s
[07:22:39.748] Handling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8867028513002028912:8:0)
[07:22:39.784] Build status: STARTED
[07:22:39.784] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8867028513002028912:9:0) after 8m28s
[07:31:07.863] Handling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8867028513002028912:9:0)
[07:31:07.947] Build status: STARTED
[07:31:07.947] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8867028513002028912:10:0) after 7m16s
[07:38:24.012] Handling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8867028513002028912:10:0)
[07:38:24.112] Build status: STARTED
[07:38:24.112] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8867028513002028912:11:0) after 8m41s
[07:47:05.129] Handling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8867028513002028912:11:0)
[07:47:05.145] Build status: STARTED
[07:47:05.145] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8867028513002028912:12:0) after 4m2s
[07:51:07.186] Handling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8867028513002028912:12:0)
[07:51:07.273] Build status: STARTED
[07:51:07.273] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8867028513002028912:13:0) after 5m39s
[07:56:46.468] Handling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8867028513002028912:13:0)
[07:56:46.485] Build status: STARTED
[07:56:46.485] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8867028513002028912:14:0) after 2m7s
[07:58:53.638] Handling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8867028513002028912:14:0)
[07:58:53.741] Build status: STARTED
[07:58:53.741] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8867028513002028912:15:0) after 5m5s
[08:03:58.861] Handling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8867028513002028912:15:0)
[08:03:58.883] Build status: STARTED
[08:03:58.883] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8867028513002028912:16:0) after 9m56s
[08:13:55.031] Handling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8867028513002028912:16:0)
[08:13:55.059] Build status: STARTED
[08:13:55.059] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8867028513002028912:17:0) after 7m23s
[08:21:18.079] Handling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8867028513002028912:17:0)
[08:21:18.116] Build status: STARTED
[08:21:18.116] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8867028513002028912:18:0) after 6m26s
[08:27:44.136] Handling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8867028513002028912:18:0)
[08:27:44.158] Build status: STARTED
[08:27:44.158] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8867028513002028912:19:0) after 4m26s
[08:32:10.380] Handling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8867028513002028912:19:0)
[08:32:10.400] Build status: STARTED
[08:32:10.400] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8867028513002028912:20:0) after 8m27s
[08:40:37.415] Handling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8867028513002028912:20:0)
[08:40:37.429] Build status: STARTED
[08:40:37.429] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8867028513002028912:21:0) after 7m2s
[08:47:39.446] Handling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8867028513002028912:21:0)
[08:47:39.468] Build status: STARTED
[08:47:39.468] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8867028513002028912:22:0) after 2m53s
[08:50:32.532] Handling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8867028513002028912:22:0)
[08:50:32.549] Build status: STARTED
[08:50:32.549] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8867028513002028912:23:0) after 1m55s
[08:52:27.645] Handling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8867028513002028912:23:0)
[08:52:27.706] Build status: STARTED
[08:52:27.706] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8867028513002028912:24:0) after 6m7s
[08:58:34.825] Handling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8867028513002028912:24:0)
[08:58:34.837] Build status: STARTED
[08:58:34.837] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8867028513002028912:25:0) after 8m7s
[09:06:41.903] Handling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8867028513002028912:25:0)
[09:06:41.921] Build status: STARTED
[09:06:41.921] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8867028513002028912:26:0) after 3m48s
[09:10:30.038] Handling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8867028513002028912:26:0)
[09:10:30.065] Build status: STARTED
[09:10:30.065] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8867028513002028912:27:0) after 4m18s
[09:14:48.209] Handling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8867028513002028912:27:0)
[09:14:48.225] Build status: STARTED
[09:14:48.225] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8867028513002028912:28:0) after 8m15s
[09:23:03.342] Handling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8867028513002028912:28:0)
[09:23:03.375] Build status: STARTED
[09:23:03.375] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8867028513002028912:29:0) after 5m59s
[09:29:02.392] Handling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8867028513002028912:29:0)
[09:29:02.410] Build status: STARTED
[09:29:02.410] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8867028513002028912:30:0) after 5m47s
[09:34:49.473] Handling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8867028513002028912:30:0)
[09:34:49.495] Build status: STARTED
[09:34:49.495] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8867028513002028912:31:0) after 5m5s
[09:39:54.510] Handling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8867028513002028912:31:0)
[09:39:54.528] Build status: STARTED
[09:39:54.528] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8867028513002028912:32:0) after 8m13s
[09:48:07.595] Handling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8867028513002028912:32:0)
[09:48:07.622] Build status: STARTED
[09:48:07.622] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8867028513002028912:33:0) after 4m37s
[09:52:44.635] Handling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8867028513002028912:33:0)
[09:52:44.652] Build status: STARTED
[09:52:44.652] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8867028513002028912:34:0) after 5m36s
[09:58:20.793] Handling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8867028513002028912:34:0)
[09:58:20.813] Build status: STARTED
[09:58:20.813] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8867028513002028912:35:0) after 5m45s
[10:04:05.879] Handling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8867028513002028912:35:0)
[10:04:05.894] Build status: STARTED
[10:04:05.894] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8867028513002028912:36:0) after 8m45s
[10:12:50.944] Handling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8867028513002028912:36:0)
[10:12:50.961] Build status: STARTED
[10:12:50.961] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8867028513002028912:37:0) after 9m21s
[10:22:12.026] Handling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8867028513002028912:37:0)
[10:22:12.041] Build status: STARTED
[10:22:12.042] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8867028513002028912:38:0) after 6m53s
[10:29:05.119] Handling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8867028513002028912:38:0)
[10:29:05.138] Build status: STARTED
[10:29:05.138] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8867028513002028912:39:0) after 1m8s
[10:30:13.170] Handling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8867028513002028912:39:0)
[10:30:13.217] Build status: STARTED
[10:30:13.217] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win32-official:8867028513002028912:40:0) after 9m59s
[10:32:09.671] Received PubSub notification, asking Buildbucket for the build status
[10:32:09.687] Build:
{
"id": "8701598451129446881",
"builder": {
"project": "chromium-m140",
"bucket": "ci",
"builder": "win32-official"
},
"number": 399,
"createdBy": "project:chromium-m140",
"createTime": "2025-10-08T06:47:47.685915458Z",
"startTime": "2025-10-08T06:47:52.822114669Z",
"endTime": "2025-10-08T10:31:47.626443268Z",
"updateTime": "2025-10-08T10:31:47.626443268Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "317073632a3b0ede745928ca14830fce0c962f73",
"ref": "refs/branch-heads/7339"
}
}
}
[10:32:09.687] Invocation finished in 3h44m23.170279515s with status SUCCEEDED