[11:34:45.411] New invocation is queued and will start shortly
[11:34:46.510] Starting the invocation (attempt 1)
[11:34:46.557] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/f85c5f14a70bc44982eb20e800e6f9dbe0887ffa"
[11:34:46.557] Popped gitiles commit info from properties and tags
[11:34:46.557] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[11:34:46.557] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[11:34:46.558] Buildbucket request:
{
"requestId": "8968479060069848448",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "win-asan"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "f85c5f14a70bc44982eb20e800e6f9dbe0887ffa",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8968479060069848448"
},
{
"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"
}
}
[11:34:47.354] Scheduled build:
{
"id": "8803048998125948561",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "win-asan"
},
"number": 23623,
"createdBy": "project:chromium",
"createTime": "2022-09-14T11:34:46.629747223Z",
"updateTime": "2022-09-14T11:34:46.629747223Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "f85c5f14a70bc44982eb20e800e6f9dbe0887ffa",
"ref": "refs/heads/main"
}
}
}
[11:34:47.354] Task URL: https://cr-buildbucket.appspot.com/build/8803048998125948561
[11:34:47.354] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:8968479060069848448:2:0) after 1m11s
[11:35:58.375] Handling timer "check-buildbucket-build-status" (chromium/win-asan:8968479060069848448:2:0)
[11:35:58.405] Build status: SCHEDULED
[11:35:58.405] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:8968479060069848448:3:0) after 5m57s
[11:35:58.868] Received PubSub notification, asking Buildbucket for the build status
[11:35:58.893] Build status: STARTED
[11:41:55.427] Handling timer "check-buildbucket-build-status" (chromium/win-asan:8968479060069848448:3:0)
[11:41:55.450] Build status: STARTED
[11:41:55.450] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:8968479060069848448:5:0) after 8m4s
[11:49:59.587] Handling timer "check-buildbucket-build-status" (chromium/win-asan:8968479060069848448:5:0)
[11:49:59.608] Build status: STARTED
[11:49:59.609] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:8968479060069848448:6:0) after 7m31s
[11:57:30.639] Handling timer "check-buildbucket-build-status" (chromium/win-asan:8968479060069848448:6:0)
[11:57:30.666] Build status: STARTED
[11:57:30.666] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:8968479060069848448:7:0) after 7m57s
[12:05:27.691] Handling timer "check-buildbucket-build-status" (chromium/win-asan:8968479060069848448:7:0)
[12:05:27.720] Build status: STARTED
[12:05:27.720] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:8968479060069848448:8:0) after 5m45s
[12:11:12.741] Handling timer "check-buildbucket-build-status" (chromium/win-asan:8968479060069848448:8:0)
[12:11:12.765] Build status: STARTED
[12:11:12.765] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:8968479060069848448:9:0) after 4m18s
[12:15:30.743] Handling timer "check-buildbucket-build-status" (chromium/win-asan:8968479060069848448:9:0)
[12:15:30.871] Build status: STARTED
[12:15:30.871] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:8968479060069848448:10:0) after 5m22s
[12:20:52.894] Handling timer "check-buildbucket-build-status" (chromium/win-asan:8968479060069848448:10:0)
[12:20:52.921] Build status: STARTED
[12:20:52.921] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:8968479060069848448:11:0) after 7m44s
[12:28:37.053] Handling timer "check-buildbucket-build-status" (chromium/win-asan:8968479060069848448:11:0)
[12:28:37.083] Build status: STARTED
[12:28:37.083] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:8968479060069848448:12:0) after 6m15s
[12:34:52.121] Handling timer "check-buildbucket-build-status" (chromium/win-asan:8968479060069848448:12:0)
[12:34:52.147] Build status: STARTED
[12:34:52.147] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:8968479060069848448:13:0) after 9m43s
[12:44:35.273] Handling timer "check-buildbucket-build-status" (chromium/win-asan:8968479060069848448:13:0)
[12:44:35.301] Build status: STARTED
[12:44:35.301] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:8968479060069848448:14:0) after 2m9s
[12:46:44.329] Handling timer "check-buildbucket-build-status" (chromium/win-asan:8968479060069848448:14:0)
[12:46:44.358] Build status: STARTED
[12:46:44.358] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:8968479060069848448:15:0) after 7m9s
[12:53:53.415] Handling timer "check-buildbucket-build-status" (chromium/win-asan:8968479060069848448:15:0)
[12:53:53.457] Build status: STARTED
[12:53:53.457] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:8968479060069848448:16:0) after 4m35s
[12:58:28.624] Handling timer "check-buildbucket-build-status" (chromium/win-asan:8968479060069848448:16:0)
[12:58:28.656] Build status: STARTED
[12:58:28.656] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:8968479060069848448:17:0) after 9m29s
[13:07:57.756] Handling timer "check-buildbucket-build-status" (chromium/win-asan:8968479060069848448:17:0)
[13:07:57.781] Build status: STARTED
[13:07:57.781] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:8968479060069848448:18:0) after 9m17s
[13:17:14.955] Handling timer "check-buildbucket-build-status" (chromium/win-asan:8968479060069848448:18:0)
[13:17:14.980] Build status: STARTED
[13:17:14.980] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:8968479060069848448:19:0) after 9m42s
[13:23:27.664] Received PubSub notification, asking Buildbucket for the build status
[13:23:27.695] Build:
{
"id": "8803048998125948561",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "win-asan"
},
"number": 23623,
"createdBy": "project:chromium",
"createTime": "2022-09-14T11:34:46.629747223Z",
"startTime": "2022-09-14T11:35:58.303741Z",
"endTime": "2022-09-14T13:23:27.318453870Z",
"updateTime": "2022-09-14T13:23:27.318453870Z",
"status": "FAILURE",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "f85c5f14a70bc44982eb20e800e6f9dbe0887ffa",
"ref": "refs/heads/main"
}
}
}
[13:23:27.695] Invocation finished in 1h48m42.303123579s with status FAILED