[14:54:27.374] New invocation is queued and will start shortly
[14:54:28.734] Starting the invocation (attempt 1)
[14:54:28.767] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/b59da2505ee72b9cce54021f2c6bee4ba8f5658b"
[14:54:28.767] Popped gitiles commit info from properties and tags
[14:54:28.767] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[14:54:28.767] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[14:54:28.767] Buildbucket request:
{
"requestId": "8866544908856230160",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "win32-official"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "b59da2505ee72b9cce54021f2c6bee4ba8f5658b",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8866544908856230160"
},
{
"key": "scheduler_job_id",
"value": "chromium/win32-official"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[14:54:29.587] Scheduled build:
{
"id": "8701114846678858529",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "win32-official"
},
"number": 10663,
"createdBy": "project:chromium",
"createTime": "2025-10-13T14:54:28.829108383Z",
"updateTime": "2025-10-13T14:54:28.829108383Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "b59da2505ee72b9cce54021f2c6bee4ba8f5658b",
"ref": "refs/heads/main"
}
}
}
[14:54:29.587] Task URL: https://cr-buildbucket.appspot.com/build/8701114846678858529
[14:54:29.587] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:8866544908856230160:2:0) after 8m27s
[14:54:30.326] Received PubSub notification, asking Buildbucket for the build status
[14:54:30.363] Build status: SCHEDULED
[14:55:48.373] Received PubSub notification, asking Buildbucket for the build status
[14:55:48.424] Build status: STARTED
[15:02:56.603] Handling timer "check-buildbucket-build-status" (chromium/win32-official:8866544908856230160:2:0)
[15:02:56.623] Build status: STARTED
[15:02:56.623] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:8866544908856230160:5:0) after 2m3s
[15:04:59.662] Handling timer "check-buildbucket-build-status" (chromium/win32-official:8866544908856230160:5:0)
[15:04:59.703] Build status: STARTED
[15:04:59.703] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:8866544908856230160:6:0) after 9m59s
[15:14:58.673] Handling timer "check-buildbucket-build-status" (chromium/win32-official:8866544908856230160:6:0)
[15:14:58.700] Build status: STARTED
[15:14:58.700] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:8866544908856230160:7:0) after 9m32s
[15:24:30.945] Handling timer "check-buildbucket-build-status" (chromium/win32-official:8866544908856230160:7:0)
[15:24:30.973] Build status: STARTED
[15:24:30.973] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:8866544908856230160:8:0) after 9m5s
[15:33:35.967] Handling timer "check-buildbucket-build-status" (chromium/win32-official:8866544908856230160:8:0)
[15:33:36.020] Build status: STARTED
[15:33:36.020] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:8866544908856230160:9:0) after 6m15s
[15:39:50.996] Handling timer "check-buildbucket-build-status" (chromium/win32-official:8866544908856230160:9:0)
[15:39:51.126] Build status: STARTED
[15:39:51.126] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:8866544908856230160:10:0) after 7m47s
[15:47:38.145] Handling timer "check-buildbucket-build-status" (chromium/win32-official:8866544908856230160:10:0)
[15:47:38.174] Build status: STARTED
[15:47:38.174] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:8866544908856230160:11:0) after 7m9s
[15:54:47.300] Handling timer "check-buildbucket-build-status" (chromium/win32-official:8866544908856230160:11:0)
[15:54:47.315] Build status: STARTED
[15:54:47.315] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:8866544908856230160:12:0) after 3m1s
[15:57:48.375] Handling timer "check-buildbucket-build-status" (chromium/win32-official:8866544908856230160:12:0)
[15:57:48.391] Build status: STARTED
[15:57:48.391] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:8866544908856230160:13:0) after 4m52s
[16:02:40.557] Handling timer "check-buildbucket-build-status" (chromium/win32-official:8866544908856230160:13:0)
[16:02:40.614] Build status: STARTED
[16:02:40.614] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:8866544908856230160:14:0) after 6m42s
[16:09:22.733] Handling timer "check-buildbucket-build-status" (chromium/win32-official:8866544908856230160:14:0)
[16:09:22.752] Build status: STARTED
[16:09:22.752] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:8866544908856230160:15:0) after 5m2s
[16:14:24.771] Handling timer "check-buildbucket-build-status" (chromium/win32-official:8866544908856230160:15:0)
[16:14:24.792] Build status: STARTED
[16:14:24.792] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:8866544908856230160:16:0) after 1m47s
[16:16:11.811] Handling timer "check-buildbucket-build-status" (chromium/win32-official:8866544908856230160:16:0)
[16:16:11.825] Build status: STARTED
[16:16:11.825] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:8866544908856230160:17:0) after 6m9s
[16:22:20.843] Handling timer "check-buildbucket-build-status" (chromium/win32-official:8866544908856230160:17:0)
[16:22:20.859] Build status: STARTED
[16:22:20.859] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:8866544908856230160:18:0) after 2m8s
[16:24:28.890] Handling timer "check-buildbucket-build-status" (chromium/win32-official:8866544908856230160:18:0)
[16:24:28.906] Build status: STARTED
[16:24:28.906] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:8866544908856230160:19:0) after 3m17s
[16:27:45.975] Handling timer "check-buildbucket-build-status" (chromium/win32-official:8866544908856230160:19:0)
[16:27:45.999] Build status: STARTED
[16:27:46.000] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:8866544908856230160:20:0) after 4m39s
[16:32:25.062] Handling timer "check-buildbucket-build-status" (chromium/win32-official:8866544908856230160:20:0)
[16:32:25.082] Build status: STARTED
[16:32:25.082] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:8866544908856230160:21:0) after 3m6s
[16:35:31.098] Handling timer "check-buildbucket-build-status" (chromium/win32-official:8866544908856230160:21:0)
[16:35:31.118] Build status: STARTED
[16:35:31.118] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:8866544908856230160:22:0) after 8m30s
[16:44:01.131] Handling timer "check-buildbucket-build-status" (chromium/win32-official:8866544908856230160:22:0)
[16:44:01.148] Build status: STARTED
[16:44:01.148] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:8866544908856230160:23:0) after 8m39s
[16:52:40.167] Handling timer "check-buildbucket-build-status" (chromium/win32-official:8866544908856230160:23:0)
[16:52:40.194] Build status: STARTED
[16:52:40.194] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:8866544908856230160:24:0) after 2m6s
[16:54:46.208] Handling timer "check-buildbucket-build-status" (chromium/win32-official:8866544908856230160:24:0)
[16:54:46.227] Build status: STARTED
[16:54:46.227] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:8866544908856230160:25:0) after 8m38s
[17:03:24.295] Handling timer "check-buildbucket-build-status" (chromium/win32-official:8866544908856230160:25:0)
[17:03:24.311] Build status: STARTED
[17:03:24.311] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:8866544908856230160:26:0) after 4m58s
[17:08:22.337] Handling timer "check-buildbucket-build-status" (chromium/win32-official:8866544908856230160:26:0)
[17:08:22.371] Build status: STARTED
[17:08:22.371] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:8866544908856230160:27:0) after 4m16s
[17:12:38.465] Handling timer "check-buildbucket-build-status" (chromium/win32-official:8866544908856230160:27:0)
[17:12:38.480] Build status: STARTED
[17:12:38.480] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:8866544908856230160:28:0) after 1m40s
[17:14:18.498] Handling timer "check-buildbucket-build-status" (chromium/win32-official:8866544908856230160:28:0)
[17:14:18.513] Build status: STARTED
[17:14:18.513] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:8866544908856230160:29:0) after 5m10s
[17:19:28.580] Handling timer "check-buildbucket-build-status" (chromium/win32-official:8866544908856230160:29:0)
[17:19:28.605] Build status: STARTED
[17:19:28.605] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:8866544908856230160:30:0) after 3m59s
[17:23:27.641] Handling timer "check-buildbucket-build-status" (chromium/win32-official:8866544908856230160:30:0)
[17:23:27.657] Build status: STARTED
[17:23:27.657] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:8866544908856230160:31:0) after 2m30s
[17:25:57.795] Handling timer "check-buildbucket-build-status" (chromium/win32-official:8866544908856230160:31:0)
[17:25:57.809] Build status: STARTED
[17:25:57.809] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:8866544908856230160:32:0) after 1m51s
[17:27:48.929] Handling timer "check-buildbucket-build-status" (chromium/win32-official:8866544908856230160:32:0)
[17:27:48.945] Build status: STARTED
[17:27:48.945] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:8866544908856230160:33:0) after 1m16s
[17:29:05.100] Handling timer "check-buildbucket-build-status" (chromium/win32-official:8866544908856230160:33:0)
[17:29:05.117] Build status: STARTED
[17:29:05.117] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:8866544908856230160:34:0) after 9m37s
[17:38:42.177] Handling timer "check-buildbucket-build-status" (chromium/win32-official:8866544908856230160:34:0)
[17:38:42.230] Build status: STARTED
[17:38:42.230] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:8866544908856230160:35:0) after 5m30s
[17:44:12.230] Handling timer "check-buildbucket-build-status" (chromium/win32-official:8866544908856230160:35:0)
[17:44:12.328] Build status: STARTED
[17:44:12.328] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:8866544908856230160:36:0) after 4m48s
[17:49:00.404] Handling timer "check-buildbucket-build-status" (chromium/win32-official:8866544908856230160:36:0)
[17:49:00.421] Build status: STARTED
[17:49:00.421] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:8866544908856230160:37:0) after 9m40s
[17:58:40.477] Handling timer "check-buildbucket-build-status" (chromium/win32-official:8866544908856230160:37:0)
[17:58:40.488] Build status: STARTED
[17:58:40.488] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:8866544908856230160:38:0) after 5m6s
[18:03:46.646] Handling timer "check-buildbucket-build-status" (chromium/win32-official:8866544908856230160:38:0)
[18:03:46.662] Build status: STARTED
[18:03:46.662] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:8866544908856230160:39:0) after 9m6s
[18:12:52.716] Handling timer "check-buildbucket-build-status" (chromium/win32-official:8866544908856230160:39:0)
[18:12:52.735] Build status: STARTED
[18:12:52.735] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:8866544908856230160:40:0) after 4m56s
[18:17:48.757] Handling timer "check-buildbucket-build-status" (chromium/win32-official:8866544908856230160:40:0)
[18:17:48.778] Build status: STARTED
[18:17:48.778] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:8866544908856230160:41:0) after 6m7s
[18:23:55.869] Handling timer "check-buildbucket-build-status" (chromium/win32-official:8866544908856230160:41:0)
[18:23:55.884] Build status: STARTED
[18:23:55.885] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:8866544908856230160:42:0) after 6m48s
[18:30:43.870] Handling timer "check-buildbucket-build-status" (chromium/win32-official:8866544908856230160:42:0)
[18:30:43.891] Build status: STARTED
[18:30:43.891] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:8866544908856230160:43:0) after 2m26s
[18:33:09.947] Handling timer "check-buildbucket-build-status" (chromium/win32-official:8866544908856230160:43:0)
[18:33:09.963] Build status: STARTED
[18:33:09.963] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:8866544908856230160:44:0) after 8m17s
[18:41:26.991] Handling timer "check-buildbucket-build-status" (chromium/win32-official:8866544908856230160:44:0)
[18:41:27.010] Build status: STARTED
[18:41:27.010] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:8866544908856230160:45:0) after 9m8s
[18:50:35.078] Handling timer "check-buildbucket-build-status" (chromium/win32-official:8866544908856230160:45:0)
[18:50:35.095] Build status: STARTED
[18:50:35.095] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:8866544908856230160:46:0) after 1m33s
[18:52:08.161] Handling timer "check-buildbucket-build-status" (chromium/win32-official:8866544908856230160:46:0)
[18:52:08.224] Build status: STARTED
[18:52:08.224] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:8866544908856230160:47:0) after 1m27s
[18:53:35.242] Handling timer "check-buildbucket-build-status" (chromium/win32-official:8866544908856230160:47:0)
[18:53:35.262] Build status: STARTED
[18:53:35.262] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:8866544908856230160:48:0) after 3m53s
[18:57:15.644] Received PubSub notification, asking Buildbucket for the build status
[18:57:15.659] Build:
{
"id": "8701114846678858529",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "win32-official"
},
"number": 10663,
"createdBy": "project:chromium",
"createTime": "2025-10-13T14:54:28.829108383Z",
"startTime": "2025-10-13T14:55:46.398876Z",
"endTime": "2025-10-13T18:57:14.538212125Z",
"updateTime": "2025-10-13T18:57:14.538212125Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "b59da2505ee72b9cce54021f2c6bee4ba8f5658b",
"ref": "refs/heads/main"
}
}
}
[18:57:15.659] Invocation finished in 4h2m48.289353816s with status SUCCEEDED