[04:49:54.958] New invocation is queued and will start shortly
[04:49:56.022] Starting the invocation (attempt 1)
[04:49:56.049] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/3f50a97b7a3a8e540eff9e4174439f1ecd487634"
[04:49:56.050] Popped gitiles commit info from properties and tags
[04:49:56.050] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[04:49:56.050] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[04:49:56.050] Buildbucket request:
{
"requestId": "8851815637717585760",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "Win ASan Release"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "3f50a97b7a3a8e540eff9e4174439f1ecd487634",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8851815637717585760"
},
{
"key": "scheduler_job_id",
"value": "chromium/Win ASan Release"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[04:49:56.575] Scheduled build:
{
"id": "8686385575873440145",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "Win ASan Release"
},
"number": 403840,
"createdBy": "project:chromium",
"createTime": "2026-03-25T04:49:56.095613092Z",
"updateTime": "2026-03-25T04:49:56.095613092Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "3f50a97b7a3a8e540eff9e4174439f1ecd487634",
"ref": "refs/heads/main"
}
},
"canary": true
}
[04:49:56.575] Task URL: https://cr-buildbucket.appspot.com/build/8686385575873440145
[04:49:56.575] Scheduling timer "check-buildbucket-build-status" (chromium/Win ASan Release:8851815637717585760:2:0) after 6m24s
[04:49:57.493] Received PubSub notification, asking Buildbucket for the build status
[04:49:57.527] Build status: SCHEDULED
[04:50:03.113] Received PubSub notification, asking Buildbucket for the build status
[04:50:03.130] Build status: STARTED
[04:56:20.593] Handling timer "check-buildbucket-build-status" (chromium/Win ASan Release:8851815637717585760:2:0)
[04:56:20.621] Build status: STARTED
[04:56:20.621] Scheduling timer "check-buildbucket-build-status" (chromium/Win ASan Release:8851815637717585760:5:0) after 5m14s
[05:01:34.675] Handling timer "check-buildbucket-build-status" (chromium/Win ASan Release:8851815637717585760:5:0)
[05:01:34.702] Build status: STARTED
[05:01:34.702] Scheduling timer "check-buildbucket-build-status" (chromium/Win ASan Release:8851815637717585760:6:0) after 6m2s
[05:07:36.724] Handling timer "check-buildbucket-build-status" (chromium/Win ASan Release:8851815637717585760:6:0)
[05:07:36.738] Build status: STARTED
[05:07:36.738] Scheduling timer "check-buildbucket-build-status" (chromium/Win ASan Release:8851815637717585760:7:0) after 9m42s
[05:17:18.838] Handling timer "check-buildbucket-build-status" (chromium/Win ASan Release:8851815637717585760:7:0)
[05:17:18.875] Build status: STARTED
[05:17:18.875] Scheduling timer "check-buildbucket-build-status" (chromium/Win ASan Release:8851815637717585760:8:0) after 1m6s
[05:18:24.893] Handling timer "check-buildbucket-build-status" (chromium/Win ASan Release:8851815637717585760:8:0)
[05:18:24.916] Build status: STARTED
[05:18:24.916] Scheduling timer "check-buildbucket-build-status" (chromium/Win ASan Release:8851815637717585760:9:0) after 4m35s
[05:22:59.984] Handling timer "check-buildbucket-build-status" (chromium/Win ASan Release:8851815637717585760:9:0)
[05:23:00.021] Build status: STARTED
[05:23:00.021] Scheduling timer "check-buildbucket-build-status" (chromium/Win ASan Release:8851815637717585760:10:0) after 5m13s
[05:28:13.127] Handling timer "check-buildbucket-build-status" (chromium/Win ASan Release:8851815637717585760:10:0)
[05:28:13.232] Build status: STARTED
[05:28:13.232] Scheduling timer "check-buildbucket-build-status" (chromium/Win ASan Release:8851815637717585760:11:0) after 2m26s
[05:30:39.453] Handling timer "check-buildbucket-build-status" (chromium/Win ASan Release:8851815637717585760:11:0)
[05:30:39.520] Build status: STARTED
[05:30:39.520] Scheduling timer "check-buildbucket-build-status" (chromium/Win ASan Release:8851815637717585760:12:0) after 8m58s
[05:39:37.533] Handling timer "check-buildbucket-build-status" (chromium/Win ASan Release:8851815637717585760:12:0)
[05:39:37.603] Build status: STARTED
[05:39:37.603] Scheduling timer "check-buildbucket-build-status" (chromium/Win ASan Release:8851815637717585760:13:0) after 1m13s
[05:40:50.619] Handling timer "check-buildbucket-build-status" (chromium/Win ASan Release:8851815637717585760:13:0)
[05:40:50.753] Build status: STARTED
[05:40:50.753] Scheduling timer "check-buildbucket-build-status" (chromium/Win ASan Release:8851815637717585760:14:0) after 7m4s
[05:47:54.766] Handling timer "check-buildbucket-build-status" (chromium/Win ASan Release:8851815637717585760:14:0)
[05:47:54.783] Build status: STARTED
[05:47:54.783] Scheduling timer "check-buildbucket-build-status" (chromium/Win ASan Release:8851815637717585760:15:0) after 4m38s
[05:52:32.798] Handling timer "check-buildbucket-build-status" (chromium/Win ASan Release:8851815637717585760:15:0)
[05:52:32.859] Build status: STARTED
[05:52:32.859] Scheduling timer "check-buildbucket-build-status" (chromium/Win ASan Release:8851815637717585760:16:0) after 7m0s
[05:59:33.126] Handling timer "check-buildbucket-build-status" (chromium/Win ASan Release:8851815637717585760:16:0)
[05:59:33.144] Build status: STARTED
[05:59:33.144] Scheduling timer "check-buildbucket-build-status" (chromium/Win ASan Release:8851815637717585760:17:0) after 5m45s
[06:05:18.210] Handling timer "check-buildbucket-build-status" (chromium/Win ASan Release:8851815637717585760:17:0)
[06:05:18.227] Build status: STARTED
[06:05:18.227] Scheduling timer "check-buildbucket-build-status" (chromium/Win ASan Release:8851815637717585760:18:0) after 8m22s
[06:13:40.209] Handling timer "check-buildbucket-build-status" (chromium/Win ASan Release:8851815637717585760:18:0)
[06:13:40.225] Build status: STARTED
[06:13:40.225] Scheduling timer "check-buildbucket-build-status" (chromium/Win ASan Release:8851815637717585760:19:0) after 8m0s
[06:21:40.293] Handling timer "check-buildbucket-build-status" (chromium/Win ASan Release:8851815637717585760:19:0)
[06:21:40.323] Build status: STARTED
[06:21:40.323] Scheduling timer "check-buildbucket-build-status" (chromium/Win ASan Release:8851815637717585760:20:0) after 7m41s
[06:29:21.338] Handling timer "check-buildbucket-build-status" (chromium/Win ASan Release:8851815637717585760:20:0)
[06:29:21.367] Build status: STARTED
[06:29:21.367] Scheduling timer "check-buildbucket-build-status" (chromium/Win ASan Release:8851815637717585760:21:0) after 3m41s
[06:32:41.470] Received PubSub notification, asking Buildbucket for the build status
[06:32:41.540] Build:
{
"id": "8686385575873440145",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "Win ASan Release"
},
"number": 403840,
"createdBy": "project:chromium",
"createTime": "2026-03-25T04:49:56.095613092Z",
"startTime": "2026-03-25T04:50:02.978282750Z",
"endTime": "2026-03-25T06:32:10.967591714Z",
"updateTime": "2026-03-25T06:32:10.967591714Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "3f50a97b7a3a8e540eff9e4174439f1ecd487634",
"ref": "refs/heads/main"
}
},
"canary": true
}
[06:32:41.540] Invocation finished in 1h42m46.587394394s with status SUCCEEDED