[22:52:32.864] New invocation is queued and will start shortly
[22:52:33.999] Starting the invocation (attempt 1)
[22:52:34.037] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/65444518f8b2c488969978a14c039ae2defaea3a"
[22:52:34.037] Popped gitiles commit info from properties and tags
[22:52:34.037] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[22:52:34.037] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[22:52:34.037] Buildbucket request:
{
"requestId": "8836889621926435040",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "CrWinAsan(dll)"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "65444518f8b2c488969978a14c039ae2defaea3a",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8836889621926435040"
},
{
"key": "scheduler_job_id",
"value": "chromium/CrWinAsan(dll)"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[22:52:34.527] Scheduled build:
{
"id": "8671459559966727793",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "CrWinAsan(dll)"
},
"number": 32121,
"createdBy": "project:chromium",
"createTime": "2026-09-05T22:52:34.112363140Z",
"updateTime": "2026-09-05T22:52:34.112363140Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "65444518f8b2c488969978a14c039ae2defaea3a",
"ref": "refs/heads/main"
}
},
"canary": true
}
[22:52:34.527] Task URL: https://cr-buildbucket.appspot.com/build/8671459559966727793
[22:52:34.527] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan(dll):8836889621926435040:2:0) after 1m50s
[22:52:35.520] Received PubSub notification, asking Buildbucket for the build status
[22:52:35.541] Build status: SCHEDULED
[22:52:48.760] Received PubSub notification, asking Buildbucket for the build status
[22:52:48.824] Build status: STARTED
[22:54:24.532] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan(dll):8836889621926435040:2:0)
[22:54:24.600] Build status: STARTED
[22:54:24.600] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan(dll):8836889621926435040:5:0) after 6m41s
[23:01:05.578] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan(dll):8836889621926435040:5:0)
[23:01:05.667] Build status: STARTED
[23:01:05.667] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan(dll):8836889621926435040:6:0) after 4m24s
[23:05:29.680] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan(dll):8836889621926435040:6:0)
[23:05:29.698] Build status: STARTED
[23:05:29.698] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan(dll):8836889621926435040:7:0) after 4m0s
[23:09:29.760] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan(dll):8836889621926435040:7:0)
[23:09:29.793] Build status: STARTED
[23:09:29.793] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan(dll):8836889621926435040:8:0) after 3m47s
[23:13:16.811] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan(dll):8836889621926435040:8:0)
[23:13:16.835] Build status: STARTED
[23:13:16.835] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan(dll):8836889621926435040:9:0) after 4m28s
[23:17:44.875] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan(dll):8836889621926435040:9:0)
[23:17:44.892] Build status: STARTED
[23:17:44.892] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan(dll):8836889621926435040:10:0) after 1m0s
[23:18:44.908] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan(dll):8836889621926435040:10:0)
[23:18:44.931] Build status: STARTED
[23:18:44.931] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan(dll):8836889621926435040:11:0) after 1m58s
[23:20:42.976] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan(dll):8836889621926435040:11:0)
[23:20:42.997] Build status: STARTED
[23:20:42.997] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan(dll):8836889621926435040:12:0) after 8m42s
[23:29:25.016] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan(dll):8836889621926435040:12:0)
[23:29:25.039] Build status: STARTED
[23:29:25.039] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan(dll):8836889621926435040:13:0) after 1m56s
[23:31:21.055] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan(dll):8836889621926435040:13:0)
[23:31:21.079] Build status: STARTED
[23:31:21.079] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan(dll):8836889621926435040:14:0) after 8m30s
[23:39:51.228] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan(dll):8836889621926435040:14:0)
[23:39:51.279] Build status: STARTED
[23:39:51.279] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan(dll):8836889621926435040:15:0) after 5m3s
[23:44:54.294] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan(dll):8836889621926435040:15:0)
[23:44:54.313] Build status: STARTED
[23:44:54.313] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan(dll):8836889621926435040:16:0) after 9m28s
[23:54:22.334] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan(dll):8836889621926435040:16:0)
[23:54:22.358] Build status: STARTED
[23:54:22.358] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan(dll):8836889621926435040:17:0) after 2m45s
[23:57:07.375] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan(dll):8836889621926435040:17:0)
[23:57:07.391] Build status: STARTED
[23:57:07.391] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan(dll):8836889621926435040:18:0) after 1m14s
[23:58:21.439] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan(dll):8836889621926435040:18:0)
[23:58:21.458] Build status: STARTED
[23:58:21.458] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan(dll):8836889621926435040:19:0) after 8m4s
[00:06:25.524] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan(dll):8836889621926435040:19:0)
[00:06:25.544] Build status: STARTED
[00:06:25.544] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan(dll):8836889621926435040:20:0) after 5m20s
[00:11:45.576] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan(dll):8836889621926435040:20:0)
[00:11:45.593] Build status: STARTED
[00:11:45.593] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan(dll):8836889621926435040:21:0) after 9m27s
[00:21:12.609] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan(dll):8836889621926435040:21:0)
[00:21:12.648] Build status: STARTED
[00:21:12.648] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan(dll):8836889621926435040:22:0) after 5m49s
[00:27:01.664] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan(dll):8836889621926435040:22:0)
[00:27:01.682] Build status: STARTED
[00:27:01.682] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan(dll):8836889621926435040:23:0) after 1m48s
[00:28:49.738] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan(dll):8836889621926435040:23:0)
[00:28:49.762] Build status: STARTED
[00:28:49.762] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan(dll):8836889621926435040:24:0) after 3m12s
[00:32:01.766] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan(dll):8836889621926435040:24:0)
[00:32:01.784] Build status: STARTED
[00:32:01.784] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan(dll):8836889621926435040:25:0) after 8m24s
[00:40:25.853] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan(dll):8836889621926435040:25:0)
[00:40:25.882] Build status: STARTED
[00:40:25.882] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan(dll):8836889621926435040:26:0) after 7m53s
[00:48:19.089] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan(dll):8836889621926435040:26:0)
[00:48:19.111] Build status: STARTED
[00:48:19.111] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan(dll):8836889621926435040:27:0) after 5m50s
[00:54:09.163] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan(dll):8836889621926435040:27:0)
[00:54:09.181] Build status: STARTED
[00:54:09.181] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan(dll):8836889621926435040:28:0) after 5m48s
[00:59:57.290] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan(dll):8836889621926435040:28:0)
[00:59:57.317] Build status: STARTED
[00:59:57.317] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan(dll):8836889621926435040:29:0) after 5m47s
[01:05:44.386] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan(dll):8836889621926435040:29:0)
[01:05:44.403] Build status: STARTED
[01:05:44.403] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan(dll):8836889621926435040:30:0) after 2m36s
[01:08:20.418] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan(dll):8836889621926435040:30:0)
[01:08:20.443] Build status: STARTED
[01:08:20.443] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan(dll):8836889621926435040:31:0) after 6m35s
[01:14:55.489] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan(dll):8836889621926435040:31:0)
[01:14:55.513] Build status: STARTED
[01:14:55.513] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan(dll):8836889621926435040:32:0) after 1m55s
[01:16:50.537] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan(dll):8836889621926435040:32:0)
[01:16:50.561] Build status: STARTED
[01:16:50.561] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan(dll):8836889621926435040:33:0) after 1m55s
[01:18:45.579] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan(dll):8836889621926435040:33:0)
[01:18:45.595] Build status: STARTED
[01:18:45.595] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan(dll):8836889621926435040:34:0) after 9m23s
[01:28:08.606] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan(dll):8836889621926435040:34:0)
[01:28:08.642] Build status: STARTED
[01:28:08.642] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan(dll):8836889621926435040:35:0) after 4m8s
[01:32:16.660] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan(dll):8836889621926435040:35:0)
[01:32:16.682] Build status: STARTED
[01:32:16.682] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan(dll):8836889621926435040:36:0) after 8m40s
[01:40:56.730] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan(dll):8836889621926435040:36:0)
[01:40:56.750] Build status: STARTED
[01:40:56.750] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan(dll):8836889621926435040:37:0) after 6m45s
[01:47:41.735] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan(dll):8836889621926435040:37:0)
[01:47:41.755] Build status: STARTED
[01:47:41.755] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan(dll):8836889621926435040:38:0) after 9m50s
[01:57:31.774] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan(dll):8836889621926435040:38:0)
[01:57:31.797] Build status: STARTED
[01:57:31.798] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan(dll):8836889621926435040:39:0) after 7m49s
[02:05:20.813] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan(dll):8836889621926435040:39:0)
[02:05:20.834] Build status: STARTED
[02:05:20.834] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan(dll):8836889621926435040:40:0) after 9m54s
[02:15:14.931] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan(dll):8836889621926435040:40:0)
[02:15:14.950] Build status: STARTED
[02:15:14.950] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan(dll):8836889621926435040:41:0) after 1m30s
[02:16:44.922] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan(dll):8836889621926435040:41:0)
[02:16:44.941] Build status: STARTED
[02:16:44.941] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan(dll):8836889621926435040:42:0) after 8m0s
[02:24:45.106] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan(dll):8836889621926435040:42:0)
[02:24:45.125] Build status: STARTED
[02:24:45.125] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan(dll):8836889621926435040:43:0) after 8m8s
[02:32:53.142] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan(dll):8836889621926435040:43:0)
[02:32:53.159] Build status: STARTED
[02:32:53.159] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan(dll):8836889621926435040:44:0) after 1m35s
[02:33:27.990] Received PubSub notification, asking Buildbucket for the build status
[02:33:28.009] Build:
{
"id": "8671459559966727793",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "CrWinAsan(dll)"
},
"number": 32121,
"createdBy": "project:chromium",
"createTime": "2026-09-05T22:52:34.112363140Z",
"startTime": "2026-09-05T22:52:48.434878865Z",
"endTime": "2026-09-06T02:33:27.620670270Z",
"updateTime": "2026-09-06T02:33:27.620670270Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "65444518f8b2c488969978a14c039ae2defaea3a",
"ref": "refs/heads/main"
}
},
"canary": true
}
[02:33:28.009] Invocation finished in 3h40m55.149996613s with status SUCCEEDED