[06:35:03.482] New invocation is queued and will start shortly
[06:35:05.097] Starting the invocation (attempt 1)
[06:35:05.121] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/9c61cc5ac8a10d7bad5e05024632a9e7d031a0aa"
[06:35:05.121] Popped gitiles commit info from properties and tags
[06:35:05.121] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[06:35:05.122] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[06:35:05.122] Buildbucket request:
{
"requestId": "8879078709643463744",
"builder": {
"project": "chromium-m136",
"bucket": "ci",
"builder": "win32-official"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8879078709643463744",
"job": "chromium-m136/win32-official",
"triggers": [
{
"gitiles": {
"ref": "refs/branch-heads/7103",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "dfabb4cd0195c0dd8eff30f137290d5139fa431b"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7103@dfabb4cd0195c0dd8eff30f137290d5139fa431b",
"title": "dfabb4cd0195c0dd8eff30f137290d5139fa431b",
"url": "https://chromium.googlesource.com/chromium/src/+/dfabb4cd0195c0dd8eff30f137290d5139fa431b"
},
{
"gitiles": {
"ref": "refs/branch-heads/7103",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "33d841cf5013cb4151c10d070bf507e4e809732b"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7103@33d841cf5013cb4151c10d070bf507e4e809732b",
"title": "33d841cf5013cb4151c10d070bf507e4e809732b",
"url": "https://chromium.googlesource.com/chromium/src/+/33d841cf5013cb4151c10d070bf507e4e809732b"
},
{
"gitiles": {
"ref": "refs/branch-heads/7103",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "3084b47988cbf7d9e23effdbfef3ab409879ac88"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7103@3084b47988cbf7d9e23effdbfef3ab409879ac88",
"title": "3084b47988cbf7d9e23effdbfef3ab409879ac88",
"url": "https://chromium.googlesource.com/chromium/src/+/3084b47988cbf7d9e23effdbfef3ab409879ac88"
},
{
"gitiles": {
"ref": "refs/branch-heads/7103",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "55445b1ef4e097f1c1340dbc5f920a863df76428"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7103@55445b1ef4e097f1c1340dbc5f920a863df76428",
"title": "55445b1ef4e097f1c1340dbc5f920a863df76428",
"url": "https://chromium.googlesource.com/chromium/src/+/55445b1ef4e097f1c1340dbc5f920a863df76428"
},
{
"gitiles": {
"ref": "refs/branch-heads/7103",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "2bf7fa1ffb8872ea80ce38aa5d284a904da021d9"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7103@2bf7fa1ffb8872ea80ce38aa5d284a904da021d9",
"title": "2bf7fa1ffb8872ea80ce38aa5d284a904da021d9",
"url": "https://chromium.googlesource.com/chromium/src/+/2bf7fa1ffb8872ea80ce38aa5d284a904da021d9"
},
{
"gitiles": {
"ref": "refs/branch-heads/7103",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "fe1089a8006f252c480c76748c3a547793b1eab3"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7103@fe1089a8006f252c480c76748c3a547793b1eab3",
"title": "fe1089a8006f252c480c76748c3a547793b1eab3",
"url": "https://chromium.googlesource.com/chromium/src/+/fe1089a8006f252c480c76748c3a547793b1eab3"
},
{
"gitiles": {
"ref": "refs/branch-heads/7103",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "90c38e752ca6ffbe4022d8bb839c3895f7672330"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7103@90c38e752ca6ffbe4022d8bb839c3895f7672330",
"title": "90c38e752ca6ffbe4022d8bb839c3895f7672330",
"url": "https://chromium.googlesource.com/chromium/src/+/90c38e752ca6ffbe4022d8bb839c3895f7672330"
},
{
"gitiles": {
"ref": "refs/branch-heads/7103",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "9c61cc5ac8a10d7bad5e05024632a9e7d031a0aa"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7103@9c61cc5ac8a10d7bad5e05024632a9e7d031a0aa",
"title": "9c61cc5ac8a10d7bad5e05024632a9e7d031a0aa",
"url": "https://chromium.googlesource.com/chromium/src/+/9c61cc5ac8a10d7bad5e05024632a9e7d031a0aa"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "9c61cc5ac8a10d7bad5e05024632a9e7d031a0aa",
"ref": "refs/branch-heads/7103"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8879078709643463744"
},
{
"key": "scheduler_job_id",
"value": "chromium-m136/win32-official"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[06:35:06.025] Scheduled build:
{
"id": "8713648647082374401",
"builder": {
"project": "chromium-m136",
"bucket": "ci",
"builder": "win32-official"
},
"number": 350,
"createdBy": "project:chromium-m136",
"createTime": "2025-05-28T06:35:05.297442238Z",
"updateTime": "2025-05-28T06:35:05.297442238Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "9c61cc5ac8a10d7bad5e05024632a9e7d031a0aa",
"ref": "refs/branch-heads/7103"
}
}
}
[06:35:06.025] Task URL: https://cr-buildbucket.appspot.com/build/8713648647082374401
[06:35:06.025] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8879078709643463744:2:0) after 5m26s
[06:35:06.867] Received PubSub notification, asking Buildbucket for the build status
[06:35:06.883] Build status: SCHEDULED
[06:35:10.383] Received PubSub notification, asking Buildbucket for the build status
[06:35:10.410] Build status: STARTED
[06:40:32.111] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8879078709643463744:2:0)
[06:40:32.188] Build status: STARTED
[06:40:32.188] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8879078709643463744:5:0) after 3m38s
[06:44:10.208] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8879078709643463744:5:0)
[06:44:10.233] Build status: STARTED
[06:44:10.233] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8879078709643463744:6:0) after 1m22s
[06:45:32.254] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8879078709643463744:6:0)
[06:45:32.276] Build status: STARTED
[06:45:32.277] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8879078709643463744:7:0) after 2m36s
[06:48:08.386] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8879078709643463744:7:0)
[06:48:08.409] Build status: STARTED
[06:48:08.409] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8879078709643463744:8:0) after 3m12s
[06:51:20.675] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8879078709643463744:8:0)
[06:51:20.698] Build status: STARTED
[06:51:20.698] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8879078709643463744:9:0) after 3m26s
[06:54:46.807] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8879078709643463744:9:0)
[06:54:46.985] Build status: STARTED
[06:54:46.985] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8879078709643463744:10:0) after 5m57s
[07:00:44.055] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8879078709643463744:10:0)
[07:00:44.085] Build status: STARTED
[07:00:44.085] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8879078709643463744:11:0) after 2m5s
[07:02:49.207] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8879078709643463744:11:0)
[07:02:49.244] Build status: STARTED
[07:02:49.244] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8879078709643463744:12:0) after 5m28s
[07:08:17.318] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8879078709643463744:12:0)
[07:08:17.356] Build status: STARTED
[07:08:17.356] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8879078709643463744:13:0) after 3m48s
[07:12:05.400] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8879078709643463744:13:0)
[07:12:05.509] Build status: STARTED
[07:12:05.509] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8879078709643463744:14:0) after 6m30s
[07:18:35.576] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8879078709643463744:14:0)
[07:18:35.601] Build status: STARTED
[07:18:35.601] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8879078709643463744:15:0) after 3m18s
[07:21:53.599] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8879078709643463744:15:0)
[07:21:53.626] Build status: STARTED
[07:21:53.626] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8879078709643463744:16:0) after 8m44s
[07:30:37.671] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8879078709643463744:16:0)
[07:30:37.706] Build status: STARTED
[07:30:37.706] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8879078709643463744:17:0) after 3m50s
[07:34:27.738] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8879078709643463744:17:0)
[07:34:27.763] Build status: STARTED
[07:34:27.763] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8879078709643463744:18:0) after 9m52s
[07:44:19.807] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8879078709643463744:18:0)
[07:44:19.840] Build status: STARTED
[07:44:19.840] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8879078709643463744:19:0) after 9m16s
[07:53:35.865] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8879078709643463744:19:0)
[07:53:35.920] Build status: STARTED
[07:53:35.920] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8879078709643463744:20:0) after 6m13s
[07:59:48.969] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8879078709643463744:20:0)
[07:59:48.996] Build status: STARTED
[07:59:48.996] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8879078709643463744:21:0) after 3m2s
[08:02:51.090] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8879078709643463744:21:0)
[08:02:51.139] Build status: STARTED
[08:02:51.139] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8879078709643463744:22:0) after 6m20s
[08:09:11.266] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8879078709643463744:22:0)
[08:09:11.308] Build status: STARTED
[08:09:11.308] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8879078709643463744:23:0) after 7m41s
[08:16:52.330] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8879078709643463744:23:0)
[08:16:52.356] Build status: STARTED
[08:16:52.356] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8879078709643463744:24:0) after 4m50s
[08:21:42.445] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8879078709643463744:24:0)
[08:21:42.470] Build status: STARTED
[08:21:42.470] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8879078709643463744:25:0) after 4m58s
[08:26:40.494] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8879078709643463744:25:0)
[08:26:40.515] Build status: STARTED
[08:26:40.515] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8879078709643463744:26:0) after 3m57s
[08:30:37.557] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8879078709643463744:26:0)
[08:30:37.584] Build status: STARTED
[08:30:37.584] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8879078709643463744:27:0) after 4m2s
[08:34:39.598] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8879078709643463744:27:0)
[08:34:39.639] Build status: STARTED
[08:34:39.639] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8879078709643463744:28:0) after 1m40s
[08:36:19.649] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8879078709643463744:28:0)
[08:36:19.678] Build status: STARTED
[08:36:19.678] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8879078709643463744:29:0) after 7m48s
[08:44:07.785] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8879078709643463744:29:0)
[08:44:07.812] Build status: STARTED
[08:44:07.812] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8879078709643463744:30:0) after 5m33s
[08:49:40.840] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8879078709643463744:30:0)
[08:49:40.894] Build status: STARTED
[08:49:40.894] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8879078709643463744:31:0) after 7m40s
[08:57:20.882] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8879078709643463744:31:0)
[08:57:20.905] Build status: STARTED
[08:57:20.905] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8879078709643463744:32:0) after 8m13s
[09:05:33.899] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8879078709643463744:32:0)
[09:05:33.924] Build status: STARTED
[09:05:33.924] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8879078709643463744:33:0) after 3m0s
[09:08:33.948] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8879078709643463744:33:0)
[09:08:33.969] Build status: STARTED
[09:08:33.969] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8879078709643463744:34:0) after 4m17s
[09:12:51.032] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8879078709643463744:34:0)
[09:12:51.083] Build status: STARTED
[09:12:51.083] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8879078709643463744:35:0) after 4m2s
[09:16:53.225] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8879078709643463744:35:0)
[09:16:53.246] Build status: STARTED
[09:16:53.246] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8879078709643463744:36:0) after 7m46s
[09:24:39.317] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8879078709643463744:36:0)
[09:24:39.344] Build status: STARTED
[09:24:39.344] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8879078709643463744:37:0) after 5m25s
[09:30:05.171] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8879078709643463744:37:0)
[09:30:05.196] Build status: STARTED
[09:30:05.196] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8879078709643463744:38:0) after 5m40s
[09:35:45.215] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8879078709643463744:38:0)
[09:35:45.296] Build status: STARTED
[09:35:45.296] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8879078709643463744:39:0) after 6m59s
[09:42:44.341] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8879078709643463744:39:0)
[09:42:44.367] Build status: STARTED
[09:42:44.367] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8879078709643463744:40:0) after 1m58s
[09:44:42.564] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8879078709643463744:40:0)
[09:44:42.590] Build status: STARTED
[09:44:42.590] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8879078709643463744:41:0) after 4m41s
[09:49:23.839] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8879078709643463744:41:0)
[09:49:23.909] Build status: STARTED
[09:49:23.909] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8879078709643463744:42:0) after 9m9s
[09:58:32.978] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8879078709643463744:42:0)
[09:58:33.017] Build status: STARTED
[09:58:33.017] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8879078709643463744:43:0) after 2m22s
[10:00:55.052] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8879078709643463744:43:0)
[10:00:55.070] Build status: STARTED
[10:00:55.070] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8879078709643463744:44:0) after 6m32s
[10:07:27.091] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8879078709643463744:44:0)
[10:07:27.110] Build status: STARTED
[10:07:27.110] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8879078709643463744:45:0) after 2m51s
[10:10:18.136] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8879078709643463744:45:0)
[10:10:18.247] Build status: STARTED
[10:10:18.247] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8879078709643463744:46:0) after 7m28s
[10:17:46.286] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8879078709643463744:46:0)
[10:17:46.308] Build status: STARTED
[10:17:46.308] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8879078709643463744:47:0) after 8m9s
[10:18:57.582] Received PubSub notification, asking Buildbucket for the build status
[10:18:57.602] Build:
{
"id": "8713648647082374401",
"builder": {
"project": "chromium-m136",
"bucket": "ci",
"builder": "win32-official"
},
"number": 350,
"createdBy": "project:chromium-m136",
"createTime": "2025-05-28T06:35:05.297442238Z",
"startTime": "2025-05-28T06:35:09.957074750Z",
"endTime": "2025-05-28T10:18:56.837121123Z",
"updateTime": "2025-05-28T10:18:56.837121123Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "9c61cc5ac8a10d7bad5e05024632a9e7d031a0aa",
"ref": "refs/branch-heads/7103"
}
}
}
[10:18:57.602] Invocation finished in 3h43m54.130715221s with status SUCCEEDED