[03:10:40.600] New invocation is queued and will start shortly
[03:10:41.714] Starting the invocation (attempt 1)
[03:10:41.740] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/22113641da3c90bbe8f4dfe0ff58cca92a1e862e"
[03:10:41.740] Popped gitiles commit info from properties and tags
[03:10:41.740] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[03:10:41.740] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[03:10:41.741] Buildbucket request:
{
"requestId": "8868129335272633632",
"builder": {
"project": "chromium-m140",
"bucket": "ci",
"builder": "win-official"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8868129335272633632",
"job": "chromium-m140/win-official",
"triggers": [
{
"gitiles": {
"ref": "refs/branch-heads/7339",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "0075f403fc8837210c4aeab01500432ba98ee7bb"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7339@0075f403fc8837210c4aeab01500432ba98ee7bb",
"title": "0075f403fc8837210c4aeab01500432ba98ee7bb",
"url": "https://chromium.googlesource.com/chromium/src/+/0075f403fc8837210c4aeab01500432ba98ee7bb"
},
{
"gitiles": {
"ref": "refs/branch-heads/7339",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "26eb079bfaade3b1a4b9f90819deec132d71221e"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7339@26eb079bfaade3b1a4b9f90819deec132d71221e",
"title": "26eb079bfaade3b1a4b9f90819deec132d71221e",
"url": "https://chromium.googlesource.com/chromium/src/+/26eb079bfaade3b1a4b9f90819deec132d71221e"
},
{
"gitiles": {
"ref": "refs/branch-heads/7339",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "6769d164d4855e1c526648e2bfba7a204ebea251"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7339@6769d164d4855e1c526648e2bfba7a204ebea251",
"title": "6769d164d4855e1c526648e2bfba7a204ebea251",
"url": "https://chromium.googlesource.com/chromium/src/+/6769d164d4855e1c526648e2bfba7a204ebea251"
},
{
"gitiles": {
"ref": "refs/branch-heads/7339",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "815cb8c20491483b432d024135f598aee8a2c7dc"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7339@815cb8c20491483b432d024135f598aee8a2c7dc",
"title": "815cb8c20491483b432d024135f598aee8a2c7dc",
"url": "https://chromium.googlesource.com/chromium/src/+/815cb8c20491483b432d024135f598aee8a2c7dc"
},
{
"gitiles": {
"ref": "refs/branch-heads/7339",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "68ec8f32fb52895f382eaf321b8f0bdbd778bab9"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7339@68ec8f32fb52895f382eaf321b8f0bdbd778bab9",
"title": "68ec8f32fb52895f382eaf321b8f0bdbd778bab9",
"url": "https://chromium.googlesource.com/chromium/src/+/68ec8f32fb52895f382eaf321b8f0bdbd778bab9"
},
{
"gitiles": {
"ref": "refs/branch-heads/7339",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "3d2efbb980e0e1ff2814827284fbc1482acc6686"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7339@3d2efbb980e0e1ff2814827284fbc1482acc6686",
"title": "3d2efbb980e0e1ff2814827284fbc1482acc6686",
"url": "https://chromium.googlesource.com/chromium/src/+/3d2efbb980e0e1ff2814827284fbc1482acc6686"
},
{
"gitiles": {
"ref": "refs/branch-heads/7339",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "cf4c8fae34910b08effa45a6247e1b7f5bc55b35"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7339@cf4c8fae34910b08effa45a6247e1b7f5bc55b35",
"title": "cf4c8fae34910b08effa45a6247e1b7f5bc55b35",
"url": "https://chromium.googlesource.com/chromium/src/+/cf4c8fae34910b08effa45a6247e1b7f5bc55b35"
},
{
"gitiles": {
"ref": "refs/branch-heads/7339",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "22113641da3c90bbe8f4dfe0ff58cca92a1e862e"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7339@22113641da3c90bbe8f4dfe0ff58cca92a1e862e",
"title": "22113641da3c90bbe8f4dfe0ff58cca92a1e862e",
"url": "https://chromium.googlesource.com/chromium/src/+/22113641da3c90bbe8f4dfe0ff58cca92a1e862e"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "22113641da3c90bbe8f4dfe0ff58cca92a1e862e",
"ref": "refs/branch-heads/7339"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8868129335272633632"
},
{
"key": "scheduler_job_id",
"value": "chromium-m140/win-official"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[03:10:42.417] Scheduled build:
{
"id": "8702699273344532785",
"builder": {
"project": "chromium-m140",
"bucket": "ci",
"builder": "win-official"
},
"number": 400,
"createdBy": "project:chromium-m140",
"createTime": "2025-09-26T03:10:41.811432879Z",
"updateTime": "2025-09-26T03:10:41.811432879Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "22113641da3c90bbe8f4dfe0ff58cca92a1e862e",
"ref": "refs/branch-heads/7339"
}
}
}
[03:10:42.417] Task URL: https://cr-buildbucket.appspot.com/build/8702699273344532785
[03:10:42.417] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win-official:8868129335272633632:2:0) after 2m23s
[03:10:43.363] Received PubSub notification, asking Buildbucket for the build status
[03:10:43.388] Build status: SCHEDULED
[03:10:46.868] Received PubSub notification, asking Buildbucket for the build status
[03:10:46.902] Build status: STARTED
[03:13:05.431] Handling timer "check-buildbucket-build-status" (chromium-m140/win-official:8868129335272633632:2:0)
[03:13:05.459] Build status: STARTED
[03:13:05.459] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win-official:8868129335272633632:5:0) after 8m51s
[03:21:56.612] Handling timer "check-buildbucket-build-status" (chromium-m140/win-official:8868129335272633632:5:0)
[03:21:56.633] Build status: STARTED
[03:21:56.633] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win-official:8868129335272633632:6:0) after 5m34s
[03:27:30.637] Handling timer "check-buildbucket-build-status" (chromium-m140/win-official:8868129335272633632:6:0)
[03:27:30.672] Build status: STARTED
[03:27:30.672] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win-official:8868129335272633632:7:0) after 7m43s
[03:35:13.692] Handling timer "check-buildbucket-build-status" (chromium-m140/win-official:8868129335272633632:7:0)
[03:35:13.719] Build status: STARTED
[03:35:13.719] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win-official:8868129335272633632:8:0) after 7m6s
[03:42:19.735] Handling timer "check-buildbucket-build-status" (chromium-m140/win-official:8868129335272633632:8:0)
[03:42:19.754] Build status: STARTED
[03:42:19.754] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win-official:8868129335272633632:9:0) after 6m13s
[03:48:32.768] Handling timer "check-buildbucket-build-status" (chromium-m140/win-official:8868129335272633632:9:0)
[03:48:32.784] Build status: STARTED
[03:48:32.784] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win-official:8868129335272633632:10:0) after 1m38s
[03:50:10.852] Handling timer "check-buildbucket-build-status" (chromium-m140/win-official:8868129335272633632:10:0)
[03:50:10.895] Build status: STARTED
[03:50:10.895] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win-official:8868129335272633632:11:0) after 7m44s
[03:57:54.909] Handling timer "check-buildbucket-build-status" (chromium-m140/win-official:8868129335272633632:11:0)
[03:57:54.937] Build status: STARTED
[03:57:54.937] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win-official:8868129335272633632:12:0) after 9m50s
[04:07:44.952] Handling timer "check-buildbucket-build-status" (chromium-m140/win-official:8868129335272633632:12:0)
[04:07:44.967] Build status: STARTED
[04:07:44.967] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win-official:8868129335272633632:13:0) after 1m48s
[04:09:32.985] Handling timer "check-buildbucket-build-status" (chromium-m140/win-official:8868129335272633632:13:0)
[04:09:33.022] Build status: STARTED
[04:09:33.022] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win-official:8868129335272633632:14:0) after 7m46s
[04:17:19.073] Handling timer "check-buildbucket-build-status" (chromium-m140/win-official:8868129335272633632:14:0)
[04:17:19.090] Build status: STARTED
[04:17:19.090] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win-official:8868129335272633632:15:0) after 7m8s
[04:24:27.158] Handling timer "check-buildbucket-build-status" (chromium-m140/win-official:8868129335272633632:15:0)
[04:24:27.177] Build status: STARTED
[04:24:27.177] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win-official:8868129335272633632:16:0) after 6m18s
[04:30:45.200] Handling timer "check-buildbucket-build-status" (chromium-m140/win-official:8868129335272633632:16:0)
[04:30:45.228] Build status: STARTED
[04:30:45.228] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win-official:8868129335272633632:17:0) after 8m44s
[04:39:29.223] Handling timer "check-buildbucket-build-status" (chromium-m140/win-official:8868129335272633632:17:0)
[04:39:29.245] Build status: STARTED
[04:39:29.245] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win-official:8868129335272633632:18:0) after 9m58s
[04:49:27.260] Handling timer "check-buildbucket-build-status" (chromium-m140/win-official:8868129335272633632:18:0)
[04:49:27.540] Build status: STARTED
[04:49:27.540] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win-official:8868129335272633632:19:0) after 9m15s
[04:58:42.596] Handling timer "check-buildbucket-build-status" (chromium-m140/win-official:8868129335272633632:19:0)
[04:58:42.613] Build status: STARTED
[04:58:42.614] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win-official:8868129335272633632:20:0) after 1m43s
[05:00:25.620] Handling timer "check-buildbucket-build-status" (chromium-m140/win-official:8868129335272633632:20:0)
[05:00:25.636] Build status: STARTED
[05:00:25.636] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win-official:8868129335272633632:21:0) after 5m28s
[05:05:53.659] Handling timer "check-buildbucket-build-status" (chromium-m140/win-official:8868129335272633632:21:0)
[05:05:53.677] Build status: STARTED
[05:05:53.677] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win-official:8868129335272633632:22:0) after 1m57s
[05:07:50.713] Handling timer "check-buildbucket-build-status" (chromium-m140/win-official:8868129335272633632:22:0)
[05:07:50.732] Build status: STARTED
[05:07:50.732] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win-official:8868129335272633632:23:0) after 3m24s
[05:11:14.772] Handling timer "check-buildbucket-build-status" (chromium-m140/win-official:8868129335272633632:23:0)
[05:11:14.862] Build status: STARTED
[05:11:14.862] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win-official:8868129335272633632:24:0) after 5m29s
[05:16:43.880] Handling timer "check-buildbucket-build-status" (chromium-m140/win-official:8868129335272633632:24:0)
[05:16:43.939] Build status: STARTED
[05:16:43.939] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win-official:8868129335272633632:25:0) after 7m58s
[05:24:41.965] Handling timer "check-buildbucket-build-status" (chromium-m140/win-official:8868129335272633632:25:0)
[05:24:41.986] Build status: STARTED
[05:24:41.986] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win-official:8868129335272633632:26:0) after 8m11s
[05:32:53.006] Handling timer "check-buildbucket-build-status" (chromium-m140/win-official:8868129335272633632:26:0)
[05:32:53.024] Build status: STARTED
[05:32:53.024] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win-official:8868129335272633632:27:0) after 1m0s
[05:33:53.046] Handling timer "check-buildbucket-build-status" (chromium-m140/win-official:8868129335272633632:27:0)
[05:33:53.065] Build status: STARTED
[05:33:53.065] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win-official:8868129335272633632:28:0) after 6m26s
[05:40:19.154] Handling timer "check-buildbucket-build-status" (chromium-m140/win-official:8868129335272633632:28:0)
[05:40:19.180] Build status: STARTED
[05:40:19.180] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win-official:8868129335272633632:29:0) after 9m20s
[05:49:39.194] Handling timer "check-buildbucket-build-status" (chromium-m140/win-official:8868129335272633632:29:0)
[05:49:39.210] Build status: STARTED
[05:49:39.210] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win-official:8868129335272633632:30:0) after 1m51s
[05:50:23.308] Received PubSub notification, asking Buildbucket for the build status
[05:50:23.331] Build:
{
"id": "8702699273344532785",
"builder": {
"project": "chromium-m140",
"bucket": "ci",
"builder": "win-official"
},
"number": 400,
"createdBy": "project:chromium-m140",
"createTime": "2025-09-26T03:10:41.811432879Z",
"startTime": "2025-09-26T03:10:46.387673048Z",
"endTime": "2025-09-26T05:50:22.947083658Z",
"updateTime": "2025-09-26T05:50:22.947083658Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "22113641da3c90bbe8f4dfe0ff58cca92a1e862e",
"ref": "refs/branch-heads/7339"
}
}
}
[05:50:23.331] Invocation finished in 2h39m42.742224082s with status SUCCEEDED