[18:29:32.789] New invocation is queued and will start shortly
[18:29:34.014] Starting the invocation (attempt 1)
[18:29:34.042] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/2c354b60446db981c22efda7166143826d903a07"
[18:29:34.042] Popped gitiles commit info from properties and tags
[18:29:34.042] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[18:29:34.042] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[18:29:34.043] Buildbucket request:
{
"requestId": "8863179288790338608",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTWin64"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "2c354b60446db981c22efda7166143826d903a07",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8863179288790338608"
},
{
"key": "scheduler_job_id",
"value": "chromium/ToTWin64"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[18:29:34.529] Scheduled build:
{
"id": "8697749226778875665",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTWin64"
},
"number": 10775,
"createdBy": "project:chromium",
"createTime": "2025-11-19T18:29:34.086800115Z",
"updateTime": "2025-11-19T18:29:34.086800115Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "2c354b60446db981c22efda7166143826d903a07",
"ref": "refs/heads/main"
}
}
}
[18:29:34.529] Task URL: https://cr-buildbucket.appspot.com/build/8697749226778875665
[18:29:34.529] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64:8863179288790338608:2:0) after 8m5s
[18:29:35.470] Received PubSub notification, asking Buildbucket for the build status
[18:29:35.488] Build status: SCHEDULED
[18:31:16.587] Received PubSub notification, asking Buildbucket for the build status
[18:31:16.613] Build status: STARTED
[18:37:39.649] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64:8863179288790338608:2:0)
[18:37:39.665] Build status: STARTED
[18:37:39.665] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64:8863179288790338608:5:0) after 4m32s
[18:42:11.735] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64:8863179288790338608:5:0)
[18:42:11.760] Build status: STARTED
[18:42:11.760] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64:8863179288790338608:6:0) after 7m41s
[18:49:52.851] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64:8863179288790338608:6:0)
[18:49:52.869] Build status: STARTED
[18:49:52.869] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64:8863179288790338608:7:0) after 9m17s
[18:59:09.943] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64:8863179288790338608:7:0)
[18:59:10.003] Build status: STARTED
[18:59:10.003] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64:8863179288790338608:8:0) after 7m5s
[19:06:15.021] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64:8863179288790338608:8:0)
[19:06:15.037] Build status: STARTED
[19:06:15.037] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64:8863179288790338608:9:0) after 7m1s
[19:13:16.117] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64:8863179288790338608:9:0)
[19:13:16.129] Build status: STARTED
[19:13:16.129] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64:8863179288790338608:10:0) after 3m49s
[19:17:05.146] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64:8863179288790338608:10:0)
[19:17:05.164] Build status: STARTED
[19:17:05.164] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64:8863179288790338608:11:0) after 7m56s
[19:25:01.247] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64:8863179288790338608:11:0)
[19:25:01.275] Build status: STARTED
[19:25:01.275] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64:8863179288790338608:12:0) after 5m37s
[19:30:38.325] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64:8863179288790338608:12:0)
[19:30:38.347] Build status: STARTED
[19:30:38.347] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64:8863179288790338608:13:0) after 6m26s
[19:37:04.367] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64:8863179288790338608:13:0)
[19:37:04.388] Build status: STARTED
[19:37:04.388] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64:8863179288790338608:14:0) after 2m20s
[19:39:24.450] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64:8863179288790338608:14:0)
[19:39:24.474] Build status: STARTED
[19:39:24.474] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64:8863179288790338608:15:0) after 8m28s
[19:47:52.506] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64:8863179288790338608:15:0)
[19:47:52.558] Build status: STARTED
[19:47:52.559] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64:8863179288790338608:16:0) after 7m54s
[19:55:46.595] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64:8863179288790338608:16:0)
[19:55:46.617] Build status: STARTED
[19:55:46.617] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64:8863179288790338608:17:0) after 5m10s
[20:00:56.637] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64:8863179288790338608:17:0)
[20:00:56.656] Build status: STARTED
[20:00:56.656] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64:8863179288790338608:18:0) after 8m52s
[20:09:48.677] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64:8863179288790338608:18:0)
[20:09:48.697] Build status: STARTED
[20:09:48.697] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64:8863179288790338608:19:0) after 8m39s
[20:18:27.774] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64:8863179288790338608:19:0)
[20:18:27.787] Build status: STARTED
[20:18:27.787] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64:8863179288790338608:20:0) after 5m16s
[20:23:43.779] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64:8863179288790338608:20:0)
[20:23:43.803] Build status: STARTED
[20:23:43.803] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64:8863179288790338608:21:0) after 9m13s
[20:32:56.822] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64:8863179288790338608:21:0)
[20:32:56.839] Build status: STARTED
[20:32:56.839] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64:8863179288790338608:22:0) after 6m57s
[20:39:53.904] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64:8863179288790338608:22:0)
[20:39:53.930] Build status: STARTED
[20:39:53.930] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64:8863179288790338608:23:0) after 7m52s
[20:47:46.065] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64:8863179288790338608:23:0)
[20:47:46.078] Build status: STARTED
[20:47:46.078] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64:8863179288790338608:24:0) after 9m4s
[20:56:50.100] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64:8863179288790338608:24:0)
[20:56:50.123] Build status: STARTED
[20:56:50.123] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64:8863179288790338608:25:0) after 8m21s
[21:05:11.143] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64:8863179288790338608:25:0)
[21:05:11.234] Build status: STARTED
[21:05:11.234] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64:8863179288790338608:26:0) after 7m15s
[21:12:26.221] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64:8863179288790338608:26:0)
[21:12:26.237] Build status: STARTED
[21:12:26.237] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64:8863179288790338608:27:0) after 7m57s
[21:20:23.255] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64:8863179288790338608:27:0)
[21:20:23.275] Build status: STARTED
[21:20:23.275] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64:8863179288790338608:28:0) after 7m9s
[21:27:32.413] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64:8863179288790338608:28:0)
[21:27:32.429] Build status: STARTED
[21:27:32.429] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64:8863179288790338608:29:0) after 9m31s
[21:37:03.450] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64:8863179288790338608:29:0)
[21:37:03.478] Build status: STARTED
[21:37:03.478] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64:8863179288790338608:30:0) after 2m13s
[21:39:16.498] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64:8863179288790338608:30:0)
[21:39:16.530] Build status: STARTED
[21:39:16.530] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64:8863179288790338608:31:0) after 4m7s
[21:43:23.549] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64:8863179288790338608:31:0)
[21:43:23.572] Build status: STARTED
[21:43:23.572] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64:8863179288790338608:32:0) after 5m48s
[21:49:11.689] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64:8863179288790338608:32:0)
[21:49:11.704] Build status: STARTED
[21:49:11.704] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64:8863179288790338608:33:0) after 4m53s
[21:54:04.777] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64:8863179288790338608:33:0)
[21:54:04.793] Build status: STARTED
[21:54:04.793] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64:8863179288790338608:34:0) after 9m17s
[22:03:21.819] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64:8863179288790338608:34:0)
[22:03:21.835] Build status: STARTED
[22:03:21.835] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64:8863179288790338608:35:0) after 1m25s
[22:04:46.865] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64:8863179288790338608:35:0)
[22:04:46.881] Build status: STARTED
[22:04:46.881] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64:8863179288790338608:36:0) after 7m35s
[22:12:22.005] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64:8863179288790338608:36:0)
[22:12:22.060] Build status: STARTED
[22:12:22.060] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64:8863179288790338608:37:0) after 8m12s
[22:20:34.078] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64:8863179288790338608:37:0)
[22:20:34.091] Build status: STARTED
[22:20:34.091] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64:8863179288790338608:38:0) after 6m17s
[22:26:51.074] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64:8863179288790338608:38:0)
[22:26:51.116] Build status: STARTED
[22:26:51.116] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64:8863179288790338608:39:0) after 6m16s
[22:33:07.134] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64:8863179288790338608:39:0)
[22:33:07.157] Build status: STARTED
[22:33:07.157] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64:8863179288790338608:40:0) after 3m48s
[22:36:55.180] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64:8863179288790338608:40:0)
[22:36:55.197] Build status: STARTED
[22:36:55.197] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64:8863179288790338608:41:0) after 7m1s
[22:43:56.259] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64:8863179288790338608:41:0)
[22:43:56.283] Build status: STARTED
[22:43:56.283] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64:8863179288790338608:42:0) after 7m35s
[22:51:31.324] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64:8863179288790338608:42:0)
[22:51:31.339] Build status: STARTED
[22:51:31.339] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64:8863179288790338608:43:0) after 6m3s
[22:57:34.358] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64:8863179288790338608:43:0)
[22:57:34.390] Build status: STARTED
[22:57:34.390] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64:8863179288790338608:44:0) after 3m16s
[23:00:50.408] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64:8863179288790338608:44:0)
[23:00:50.422] Build status: STARTED
[23:00:50.422] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64:8863179288790338608:45:0) after 8m32s
[23:09:22.438] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64:8863179288790338608:45:0)
[23:09:22.451] Build status: STARTED
[23:09:22.451] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64:8863179288790338608:46:0) after 2m24s
[23:11:46.486] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64:8863179288790338608:46:0)
[23:11:46.505] Build status: STARTED
[23:11:46.505] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64:8863179288790338608:47:0) after 1m48s
[23:13:34.527] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64:8863179288790338608:47:0)
[23:13:34.550] Build status: STARTED
[23:13:34.550] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64:8863179288790338608:48:0) after 6m57s
[23:20:31.576] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64:8863179288790338608:48:0)
[23:20:31.593] Build status: STARTED
[23:20:31.593] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64:8863179288790338608:49:0) after 6m43s
[23:27:14.614] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64:8863179288790338608:49:0)
[23:27:14.632] Build status: STARTED
[23:27:14.632] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64:8863179288790338608:50:0) after 7m24s
[23:34:38.642] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64:8863179288790338608:50:0)
[23:34:38.659] Build status: STARTED
[23:34:38.660] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64:8863179288790338608:51:0) after 5m4s
[23:39:42.678] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64:8863179288790338608:51:0)
[23:39:42.708] Build status: STARTED
[23:39:42.708] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64:8863179288790338608:52:0) after 2m11s
[23:41:53.761] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64:8863179288790338608:52:0)
[23:41:53.777] Build status: STARTED
[23:41:53.777] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64:8863179288790338608:53:0) after 5m3s
[23:46:56.817] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64:8863179288790338608:53:0)
[23:46:56.836] Build status: STARTED
[23:46:56.836] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64:8863179288790338608:54:0) after 9m9s
[23:56:05.887] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64:8863179288790338608:54:0)
[23:56:05.916] Build status: STARTED
[23:56:05.916] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64:8863179288790338608:55:0) after 2m24s
[23:58:29.917] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64:8863179288790338608:55:0)
[23:58:29.932] Build status: STARTED
[23:58:29.932] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64:8863179288790338608:56:0) after 3m3s
[00:01:32.969] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64:8863179288790338608:56:0)
[00:01:32.990] Build status: STARTED
[00:01:32.990] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64:8863179288790338608:57:0) after 9m21s
[00:10:54.025] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64:8863179288790338608:57:0)
[00:10:54.039] Build status: STARTED
[00:10:54.039] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64:8863179288790338608:58:0) after 5m24s
[00:16:18.141] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64:8863179288790338608:58:0)
[00:16:18.205] Build status: STARTED
[00:16:18.205] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64:8863179288790338608:59:0) after 7m26s
[00:23:44.222] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64:8863179288790338608:59:0)
[00:23:44.237] Build status: STARTED
[00:23:44.237] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64:8863179288790338608:60:0) after 3m23s
[00:27:07.273] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64:8863179288790338608:60:0)
[00:27:07.319] Build status: STARTED
[00:27:07.319] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64:8863179288790338608:61:0) after 8m2s
[00:35:09.337] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64:8863179288790338608:61:0)
[00:35:09.359] Build status: STARTED
[00:35:09.359] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64:8863179288790338608:62:0) after 3m56s
[00:39:05.527] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64:8863179288790338608:62:0)
[00:39:05.545] Build status: STARTED
[00:39:05.545] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64:8863179288790338608:63:0) after 4m17s
[00:43:22.567] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64:8863179288790338608:63:0)
[00:43:22.583] Build status: STARTED
[00:43:22.583] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64:8863179288790338608:64:0) after 5m56s
[00:49:18.652] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64:8863179288790338608:64:0)
[00:49:18.675] Build status: STARTED
[00:49:18.676] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64:8863179288790338608:65:0) after 9m24s
[00:58:42.710] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64:8863179288790338608:65:0)
[00:58:42.729] Build status: STARTED
[00:58:42.729] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64:8863179288790338608:66:0) after 7m47s
[01:06:29.836] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64:8863179288790338608:66:0)
[01:06:29.856] Build status: STARTED
[01:06:29.856] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64:8863179288790338608:67:0) after 9m2s
[01:15:31.893] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64:8863179288790338608:67:0)
[01:15:31.945] Build status: STARTED
[01:15:31.945] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64:8863179288790338608:68:0) after 6m59s
[01:20:13.949] Received PubSub notification, asking Buildbucket for the build status
[01:20:14.006] Build:
{
"id": "8697749226778875665",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTWin64"
},
"number": 10775,
"createdBy": "project:chromium",
"createTime": "2025-11-19T18:29:34.086800115Z",
"startTime": "2025-11-19T18:31:15.651575100Z",
"endTime": "2025-11-20T01:20:13.719835408Z",
"updateTime": "2025-11-20T01:20:13.719835408Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "2c354b60446db981c22efda7166143826d903a07",
"ref": "refs/heads/main"
}
}
}
[01:20:14.006] Invocation finished in 6h50m41.221587608s with status SUCCEEDED