[10:56:29.451] New invocation is queued and will start shortly
[10:56:30.588] Starting the invocation (attempt 1)
[10:56:30.618] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/bcff0304354c6b93d26867679a4366acb3e8ea8f"
[10:56:30.618] Popped gitiles commit info from properties and tags
[10:56:30.618] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[10:56:30.618] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[10:56:30.618] Buildbucket request:
{
"requestId": "8869730774165640672",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "CrWinAsan(dll)"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "bcff0304354c6b93d26867679a4366acb3e8ea8f",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8869730774165640672"
},
{
"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"
}
}
[10:56:31.176] Scheduled build:
{
"id": "8704300712237146881",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "CrWinAsan(dll)"
},
"number": 25935,
"createdBy": "project:chromium",
"createTime": "2025-09-08T10:56:30.670518546Z",
"updateTime": "2025-09-08T10:56:30.670518546Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "bcff0304354c6b93d26867679a4366acb3e8ea8f",
"ref": "refs/heads/main"
}
}
}
[10:56:31.176] Task URL: https://cr-buildbucket.appspot.com/build/8704300712237146881
[10:56:31.176] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan(dll):8869730774165640672:2:0) after 9m40s
[10:56:31.287] Received PubSub notification, asking Buildbucket for the build status
[10:56:31.320] Build status: SCHEDULED
[11:06:11.188] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan(dll):8869730774165640672:2:0)
[11:06:11.324] Build status: SCHEDULED
[11:06:11.324] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan(dll):8869730774165640672:4:0) after 5m32s
[11:11:43.376] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan(dll):8869730774165640672:4:0)
[11:11:43.393] Build status: SCHEDULED
[11:11:43.393] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan(dll):8869730774165640672:5:0) after 4m37s
[11:16:20.416] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan(dll):8869730774165640672:5:0)
[11:16:20.477] Build status: SCHEDULED
[11:16:20.477] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan(dll):8869730774165640672:6:0) after 4m42s
[11:21:02.525] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan(dll):8869730774165640672:6:0)
[11:21:02.602] Build status: SCHEDULED
[11:21:02.602] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan(dll):8869730774165640672:7:0) after 7m41s
[11:28:43.630] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan(dll):8869730774165640672:7:0)
[11:28:43.649] Build status: SCHEDULED
[11:28:43.649] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan(dll):8869730774165640672:8:0) after 9m44s
[11:38:27.664] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan(dll):8869730774165640672:8:0)
[11:38:27.679] Build status: SCHEDULED
[11:38:27.679] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan(dll):8869730774165640672:9:0) after 7m13s
[11:45:40.782] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan(dll):8869730774165640672:9:0)
[11:45:40.801] Build status: SCHEDULED
[11:45:40.801] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan(dll):8869730774165640672:10:0) after 1m29s
[11:47:09.875] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan(dll):8869730774165640672:10:0)
[11:47:09.892] Build status: SCHEDULED
[11:47:09.892] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan(dll):8869730774165640672:11:0) after 5m57s
[11:47:57.607] Received PubSub notification, asking Buildbucket for the build status
[11:47:57.634] Build status: STARTED
[11:53:06.941] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan(dll):8869730774165640672:11:0)
[11:53:06.961] Build status: STARTED
[11:53:06.961] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan(dll):8869730774165640672:13:0) after 5m43s
[11:58:49.987] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan(dll):8869730774165640672:13:0)
[11:58:50.013] Build status: STARTED
[11:58:50.013] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan(dll):8869730774165640672:14:0) after 7m1s
[12:05:51.087] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan(dll):8869730774165640672:14:0)
[12:05:51.106] Build status: STARTED
[12:05:51.106] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan(dll):8869730774165640672:15:0) after 7m53s
[12:13:44.245] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan(dll):8869730774165640672:15:0)
[12:13:44.266] Build status: STARTED
[12:13:44.266] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan(dll):8869730774165640672:16:0) after 5m18s
[12:19:02.286] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan(dll):8869730774165640672:16:0)
[12:19:02.313] Build status: STARTED
[12:19:02.313] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan(dll):8869730774165640672:17:0) after 9m2s
[12:28:04.503] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan(dll):8869730774165640672:17:0)
[12:28:04.518] Build status: STARTED
[12:28:04.518] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan(dll):8869730774165640672:18:0) after 3m59s
[12:32:03.550] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan(dll):8869730774165640672:18:0)
[12:32:03.586] Build status: STARTED
[12:32:03.586] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan(dll):8869730774165640672:19:0) after 3m52s
[12:33:31.565] Received PubSub notification, asking Buildbucket for the build status
[12:33:31.584] Build:
{
"id": "8704300712237146881",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "CrWinAsan(dll)"
},
"number": 25935,
"createdBy": "project:chromium",
"createTime": "2025-09-08T10:56:30.670518546Z",
"startTime": "2025-09-08T11:47:57.443725811Z",
"endTime": "2025-09-08T12:33:31.084485501Z",
"updateTime": "2025-09-08T12:33:31.084485501Z",
"status": "FAILURE",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "bcff0304354c6b93d26867679a4366acb3e8ea8f",
"ref": "refs/heads/main"
}
}
}
[12:33:31.584] Invocation finished in 1h37m2.135943865s with status FAILED