[22:26:51.511] New invocation is queued and will start shortly
[22:26:52.572] Starting the invocation (attempt 1)
[22:26:52.612] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/c3b569795ec8427d7ab58e8e48addca20bfc3f2e"
[22:26:52.612] Popped gitiles commit info from properties and tags
[22:26:52.612] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[22:26:52.613] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[22:26:52.613] Buildbucket request:
{
"requestId": "8858272122225139344",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "Win ASan Release"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "c3b569795ec8427d7ab58e8e48addca20bfc3f2e",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8858272122225139344"
},
{
"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"
}
}
[22:26:53.093] Scheduled build:
{
"id": "8692842060350517009",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "Win ASan Release"
},
"number": 396313,
"createdBy": "project:chromium",
"createTime": "2026-01-12T22:26:52.675891263Z",
"updateTime": "2026-01-12T22:26:52.675891263Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "c3b569795ec8427d7ab58e8e48addca20bfc3f2e",
"ref": "refs/heads/main"
}
}
}
[22:26:53.093] Task URL: https://cr-buildbucket.appspot.com/build/8692842060350517009
[22:26:53.093] Scheduling timer "check-buildbucket-build-status" (chromium/Win ASan Release:8858272122225139344:2:0) after 8m30s
[22:26:54.049] Received PubSub notification, asking Buildbucket for the build status
[22:26:54.224] Build status: SCHEDULED
[22:27:02.714] Received PubSub notification, asking Buildbucket for the build status
[22:27:02.732] Build status: STARTED
[22:35:23.249] Handling timer "check-buildbucket-build-status" (chromium/Win ASan Release:8858272122225139344:2:0)
[22:35:23.274] Build status: STARTED
[22:35:23.274] Scheduling timer "check-buildbucket-build-status" (chromium/Win ASan Release:8858272122225139344:5:0) after 3m59s
[22:39:22.355] Handling timer "check-buildbucket-build-status" (chromium/Win ASan Release:8858272122225139344:5:0)
[22:39:22.382] Build status: STARTED
[22:39:22.382] Scheduling timer "check-buildbucket-build-status" (chromium/Win ASan Release:8858272122225139344:6:0) after 8m38s
[22:48:00.443] Handling timer "check-buildbucket-build-status" (chromium/Win ASan Release:8858272122225139344:6:0)
[22:48:00.470] Build status: STARTED
[22:48:00.470] Scheduling timer "check-buildbucket-build-status" (chromium/Win ASan Release:8858272122225139344:7:0) after 6m5s
[22:54:05.532] Handling timer "check-buildbucket-build-status" (chromium/Win ASan Release:8858272122225139344:7:0)
[22:54:05.560] Build status: STARTED
[22:54:05.560] Scheduling timer "check-buildbucket-build-status" (chromium/Win ASan Release:8858272122225139344:8:0) after 6m36s
[23:00:41.576] Handling timer "check-buildbucket-build-status" (chromium/Win ASan Release:8858272122225139344:8:0)
[23:00:41.593] Build status: STARTED
[23:00:41.593] Scheduling timer "check-buildbucket-build-status" (chromium/Win ASan Release:8858272122225139344:9:0) after 2m33s
[23:03:14.607] Handling timer "check-buildbucket-build-status" (chromium/Win ASan Release:8858272122225139344:9:0)
[23:03:14.641] Build status: STARTED
[23:03:14.641] Scheduling timer "check-buildbucket-build-status" (chromium/Win ASan Release:8858272122225139344:10:0) after 1m28s
[23:04:42.660] Handling timer "check-buildbucket-build-status" (chromium/Win ASan Release:8858272122225139344:10:0)
[23:04:42.679] Build status: STARTED
[23:04:42.679] Scheduling timer "check-buildbucket-build-status" (chromium/Win ASan Release:8858272122225139344:11:0) after 2m27s
[23:07:09.771] Handling timer "check-buildbucket-build-status" (chromium/Win ASan Release:8858272122225139344:11:0)
[23:07:09.787] Build status: STARTED
[23:07:09.787] Scheduling timer "check-buildbucket-build-status" (chromium/Win ASan Release:8858272122225139344:12:0) after 5m25s
[23:12:34.825] Handling timer "check-buildbucket-build-status" (chromium/Win ASan Release:8858272122225139344:12:0)
[23:12:34.855] Build status: STARTED
[23:12:34.855] Scheduling timer "check-buildbucket-build-status" (chromium/Win ASan Release:8858272122225139344:13:0) after 8m0s
[23:20:34.867] Handling timer "check-buildbucket-build-status" (chromium/Win ASan Release:8858272122225139344:13:0)
[23:20:34.909] Build status: STARTED
[23:20:34.909] Scheduling timer "check-buildbucket-build-status" (chromium/Win ASan Release:8858272122225139344:14:0) after 2m34s
[23:23:08.941] Handling timer "check-buildbucket-build-status" (chromium/Win ASan Release:8858272122225139344:14:0)
[23:23:08.959] Build status: STARTED
[23:23:08.959] Scheduling timer "check-buildbucket-build-status" (chromium/Win ASan Release:8858272122225139344:15:0) after 2m7s
[23:25:15.962] Handling timer "check-buildbucket-build-status" (chromium/Win ASan Release:8858272122225139344:15:0)
[23:25:15.998] Build status: STARTED
[23:25:15.998] Scheduling timer "check-buildbucket-build-status" (chromium/Win ASan Release:8858272122225139344:16:0) after 2m34s
[23:27:50.050] Handling timer "check-buildbucket-build-status" (chromium/Win ASan Release:8858272122225139344:16:0)
[23:27:50.122] Build status: STARTED
[23:27:50.122] Scheduling timer "check-buildbucket-build-status" (chromium/Win ASan Release:8858272122225139344:17:0) after 7m28s
[23:35:18.331] Handling timer "check-buildbucket-build-status" (chromium/Win ASan Release:8858272122225139344:17:0)
[23:35:18.357] Build status: STARTED
[23:35:18.357] Scheduling timer "check-buildbucket-build-status" (chromium/Win ASan Release:8858272122225139344:18:0) after 2m50s
[23:38:08.407] Handling timer "check-buildbucket-build-status" (chromium/Win ASan Release:8858272122225139344:18:0)
[23:38:08.426] Build status: STARTED
[23:38:08.426] Scheduling timer "check-buildbucket-build-status" (chromium/Win ASan Release:8858272122225139344:19:0) after 3m51s
[23:41:59.605] Handling timer "check-buildbucket-build-status" (chromium/Win ASan Release:8858272122225139344:19:0)
[23:41:59.618] Build status: STARTED
[23:41:59.618] Scheduling timer "check-buildbucket-build-status" (chromium/Win ASan Release:8858272122225139344:20:0) after 8m4s
[23:50:03.630] Handling timer "check-buildbucket-build-status" (chromium/Win ASan Release:8858272122225139344:20:0)
[23:50:03.641] Build status: STARTED
[23:50:03.641] Scheduling timer "check-buildbucket-build-status" (chromium/Win ASan Release:8858272122225139344:21:0) after 1m28s
[23:51:11.242] Received PubSub notification, asking Buildbucket for the build status
[23:51:11.278] Build:
{
"id": "8692842060350517009",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "Win ASan Release"
},
"number": 396313,
"createdBy": "project:chromium",
"createTime": "2026-01-12T22:26:52.675891263Z",
"startTime": "2026-01-12T22:27:02.459929494Z",
"endTime": "2026-01-12T23:51:10.882418744Z",
"updateTime": "2026-01-12T23:51:10.882418744Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "c3b569795ec8427d7ab58e8e48addca20bfc3f2e",
"ref": "refs/heads/main"
}
}
}
[23:51:11.278] Invocation finished in 1h24m19.774189687s with status SUCCEEDED