[21:39:16.241] New invocation is queued and will start shortly
[21:39:17.364] Starting the invocation (attempt 1)
[21:39:17.387] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/800ece354d0a72cc05bd17ba2dc294a2fc85309b"
[21:39:17.387] Popped gitiles commit info from properties and tags
[21:39:17.387] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[21:39:17.387] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[21:39:17.387] Buildbucket request:
{
"requestId": "8847312883254769232",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "win-asan"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "800ece354d0a72cc05bd17ba2dc294a2fc85309b",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8847312883254769232"
},
{
"key": "scheduler_job_id",
"value": "chromium/win-asan"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[21:39:17.929] Scheduled build:
{
"id": "8681882821326676513",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "win-asan"
},
"number": 39507,
"createdBy": "project:chromium",
"createTime": "2026-05-13T21:39:17.460981079Z",
"updateTime": "2026-05-13T21:39:17.460981079Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "800ece354d0a72cc05bd17ba2dc294a2fc85309b",
"ref": "refs/heads/main"
}
}
}
[21:39:17.929] Task URL: https://cr-buildbucket.appspot.com/build/8681882821326676513
[21:39:17.929] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:8847312883254769232:2:0) after 4m56s
[21:39:33.664] Received PubSub notification, asking Buildbucket for the build status
[21:39:33.690] Build status: SCHEDULED
[21:39:53.012] Received PubSub notification, asking Buildbucket for the build status
[21:39:53.034] Build status: STARTED
[21:44:13.999] Handling timer "check-buildbucket-build-status" (chromium/win-asan:8847312883254769232:2:0)
[21:44:14.024] Build status: STARTED
[21:44:14.024] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:8847312883254769232:5:0) after 4m22s
[21:48:36.040] Handling timer "check-buildbucket-build-status" (chromium/win-asan:8847312883254769232:5:0)
[21:48:36.054] Build status: STARTED
[21:48:36.055] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:8847312883254769232:6:0) after 6m48s
[21:55:24.118] Handling timer "check-buildbucket-build-status" (chromium/win-asan:8847312883254769232:6:0)
[21:55:24.136] Build status: STARTED
[21:55:24.136] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:8847312883254769232:7:0) after 9m44s
[22:05:08.283] Handling timer "check-buildbucket-build-status" (chromium/win-asan:8847312883254769232:7:0)
[22:05:08.309] Build status: STARTED
[22:05:08.309] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:8847312883254769232:8:0) after 4m14s
[22:09:22.337] Handling timer "check-buildbucket-build-status" (chromium/win-asan:8847312883254769232:8:0)
[22:09:22.354] Build status: STARTED
[22:09:22.354] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:8847312883254769232:9:0) after 2m36s
[22:11:58.469] Handling timer "check-buildbucket-build-status" (chromium/win-asan:8847312883254769232:9:0)
[22:11:58.486] Build status: STARTED
[22:11:58.486] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:8847312883254769232:10:0) after 9m28s
[22:21:26.512] Handling timer "check-buildbucket-build-status" (chromium/win-asan:8847312883254769232:10:0)
[22:21:26.532] Build status: STARTED
[22:21:26.532] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:8847312883254769232:11:0) after 5m1s
[22:26:27.514] Handling timer "check-buildbucket-build-status" (chromium/win-asan:8847312883254769232:11:0)
[22:26:27.548] Build status: STARTED
[22:26:27.548] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:8847312883254769232:12:0) after 1m30s
[22:27:57.674] Handling timer "check-buildbucket-build-status" (chromium/win-asan:8847312883254769232:12:0)
[22:27:57.690] Build status: STARTED
[22:27:57.690] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:8847312883254769232:13:0) after 2m35s
[22:30:32.815] Handling timer "check-buildbucket-build-status" (chromium/win-asan:8847312883254769232:13:0)
[22:30:32.832] Build status: STARTED
[22:30:32.832] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:8847312883254769232:14:0) after 8m42s
[22:39:14.893] Handling timer "check-buildbucket-build-status" (chromium/win-asan:8847312883254769232:14:0)
[22:39:14.969] Build status: STARTED
[22:39:14.969] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:8847312883254769232:15:0) after 3m59s
[22:43:14.062] Handling timer "check-buildbucket-build-status" (chromium/win-asan:8847312883254769232:15:0)
[22:43:14.078] Build status: STARTED
[22:43:14.078] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:8847312883254769232:16:0) after 1m54s
[22:45:08.101] Handling timer "check-buildbucket-build-status" (chromium/win-asan:8847312883254769232:16:0)
[22:45:08.124] Build status: STARTED
[22:45:08.124] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:8847312883254769232:17:0) after 2m15s
[22:47:23.195] Handling timer "check-buildbucket-build-status" (chromium/win-asan:8847312883254769232:17:0)
[22:47:23.212] Build status: STARTED
[22:47:23.212] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:8847312883254769232:18:0) after 4m19s
[22:51:42.205] Handling timer "check-buildbucket-build-status" (chromium/win-asan:8847312883254769232:18:0)
[22:51:42.337] Build status: STARTED
[22:51:42.337] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:8847312883254769232:19:0) after 9m55s
[23:01:37.361] Handling timer "check-buildbucket-build-status" (chromium/win-asan:8847312883254769232:19:0)
[23:01:37.380] Build status: STARTED
[23:01:37.380] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:8847312883254769232:20:0) after 2m37s
[23:04:14.428] Handling timer "check-buildbucket-build-status" (chromium/win-asan:8847312883254769232:20:0)
[23:04:14.446] Build status: STARTED
[23:04:14.446] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:8847312883254769232:21:0) after 3m56s
[23:08:10.507] Handling timer "check-buildbucket-build-status" (chromium/win-asan:8847312883254769232:21:0)
[23:08:10.535] Build status: STARTED
[23:08:10.535] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:8847312883254769232:22:0) after 8m4s
[23:16:14.556] Handling timer "check-buildbucket-build-status" (chromium/win-asan:8847312883254769232:22:0)
[23:16:14.574] Build status: STARTED
[23:16:14.574] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:8847312883254769232:23:0) after 4m5s
[23:20:19.554] Handling timer "check-buildbucket-build-status" (chromium/win-asan:8847312883254769232:23:0)
[23:20:19.575] Build status: STARTED
[23:20:19.575] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:8847312883254769232:24:0) after 8m30s
[23:28:49.642] Handling timer "check-buildbucket-build-status" (chromium/win-asan:8847312883254769232:24:0)
[23:28:49.685] Build status: STARTED
[23:28:49.685] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:8847312883254769232:25:0) after 6m48s
[23:35:37.764] Handling timer "check-buildbucket-build-status" (chromium/win-asan:8847312883254769232:25:0)
[23:35:37.826] Build status: STARTED
[23:35:37.826] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:8847312883254769232:26:0) after 5m37s
[23:41:14.929] Handling timer "check-buildbucket-build-status" (chromium/win-asan:8847312883254769232:26:0)
[23:41:14.945] Build status: STARTED
[23:41:14.945] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:8847312883254769232:27:0) after 5m13s
[23:46:27.963] Handling timer "check-buildbucket-build-status" (chromium/win-asan:8847312883254769232:27:0)
[23:46:27.983] Build status: STARTED
[23:46:27.983] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:8847312883254769232:28:0) after 6m5s
[23:52:33.004] Handling timer "check-buildbucket-build-status" (chromium/win-asan:8847312883254769232:28:0)
[23:52:33.020] Build status: STARTED
[23:52:33.020] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:8847312883254769232:29:0) after 5m53s
[23:58:26.027] Handling timer "check-buildbucket-build-status" (chromium/win-asan:8847312883254769232:29:0)
[23:58:26.046] Build status: STARTED
[23:58:26.046] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:8847312883254769232:30:0) after 9m52s
[00:08:18.126] Handling timer "check-buildbucket-build-status" (chromium/win-asan:8847312883254769232:30:0)
[00:08:18.148] Build status: STARTED
[00:08:18.148] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:8847312883254769232:31:0) after 1m53s
[00:10:11.200] Handling timer "check-buildbucket-build-status" (chromium/win-asan:8847312883254769232:31:0)
[00:10:11.217] Build status: STARTED
[00:10:11.219] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:8847312883254769232:32:0) after 6m52s
[00:17:03.305] Handling timer "check-buildbucket-build-status" (chromium/win-asan:8847312883254769232:32:0)
[00:17:03.324] Build status: STARTED
[00:17:03.324] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:8847312883254769232:33:0) after 1m43s
[00:18:46.366] Handling timer "check-buildbucket-build-status" (chromium/win-asan:8847312883254769232:33:0)
[00:18:46.392] Build status: STARTED
[00:18:46.392] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:8847312883254769232:34:0) after 4m5s
[00:22:51.450] Handling timer "check-buildbucket-build-status" (chromium/win-asan:8847312883254769232:34:0)
[00:22:51.473] Build status: STARTED
[00:22:51.473] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:8847312883254769232:35:0) after 9m22s
[00:32:13.539] Handling timer "check-buildbucket-build-status" (chromium/win-asan:8847312883254769232:35:0)
[00:32:13.559] Build status: STARTED
[00:32:13.559] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:8847312883254769232:36:0) after 9m58s
[00:42:11.596] Handling timer "check-buildbucket-build-status" (chromium/win-asan:8847312883254769232:36:0)
[00:42:11.615] Build status: STARTED
[00:42:11.615] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:8847312883254769232:37:0) after 1m20s
[00:43:31.603] Handling timer "check-buildbucket-build-status" (chromium/win-asan:8847312883254769232:37:0)
[00:43:31.622] Build status: STARTED
[00:43:31.622] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:8847312883254769232:38:0) after 6m25s
[00:49:56.690] Handling timer "check-buildbucket-build-status" (chromium/win-asan:8847312883254769232:38:0)
[00:49:56.707] Build status: STARTED
[00:49:56.707] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:8847312883254769232:39:0) after 4m9s
[00:54:05.728] Handling timer "check-buildbucket-build-status" (chromium/win-asan:8847312883254769232:39:0)
[00:54:05.749] Build status: STARTED
[00:54:05.749] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:8847312883254769232:40:0) after 1m28s
[00:55:33.764] Handling timer "check-buildbucket-build-status" (chromium/win-asan:8847312883254769232:40:0)
[00:55:33.786] Build status: STARTED
[00:55:33.786] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:8847312883254769232:41:0) after 6m49s
[01:02:22.796] Handling timer "check-buildbucket-build-status" (chromium/win-asan:8847312883254769232:41:0)
[01:02:22.819] Build status: STARTED
[01:02:22.819] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:8847312883254769232:42:0) after 6m17s
[01:08:39.838] Handling timer "check-buildbucket-build-status" (chromium/win-asan:8847312883254769232:42:0)
[01:08:39.858] Build status: STARTED
[01:08:39.858] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:8847312883254769232:43:0) after 5m36s
[01:14:15.982] Handling timer "check-buildbucket-build-status" (chromium/win-asan:8847312883254769232:43:0)
[01:14:16.001] Build status: STARTED
[01:14:16.001] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:8847312883254769232:44:0) after 2m10s
[01:16:25.986] Handling timer "check-buildbucket-build-status" (chromium/win-asan:8847312883254769232:44:0)
[01:16:26.011] Build status: STARTED
[01:16:26.011] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:8847312883254769232:45:0) after 6m40s
[01:17:00.455] Received PubSub notification, asking Buildbucket for the build status
[01:17:00.468] Build:
{
"id": "8681882821326676513",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "win-asan"
},
"number": 39507,
"createdBy": "project:chromium",
"createTime": "2026-05-13T21:39:17.460981079Z",
"startTime": "2026-05-13T21:39:47.360379646Z",
"endTime": "2026-05-14T01:17:00.123751965Z",
"updateTime": "2026-05-14T01:17:00.123751965Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "800ece354d0a72cc05bd17ba2dc294a2fc85309b",
"ref": "refs/heads/main"
}
}
}
[01:17:00.468] Invocation finished in 3h37m44.23080263s with status SUCCEEDED