[05:33:58.164] New invocation is queued and will start shortly
[05:33:59.427] Starting the invocation (attempt 1)
[05:33:59.465] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/415107e54e29af6d6ac089a7803439c39287ff3f"
[05:33:59.465] Popped gitiles commit info from properties and tags
[05:33:59.465] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[05:33:59.465] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[05:33:59.465] Buildbucket request:
{
"requestId": "9013890839016373072",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "win-asan"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "415107e54e29af6d6ac089a7803439c39287ff3f",
"ref": "refs/heads/master"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "9013890839016373072"
},
{
"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"
}
}
[05:34:00.654] Scheduled build:
{
"id": "8848460776896464464",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "win-asan"
},
"number": 18495,
"createdBy": "project:chromium",
"createTime": "2021-05-01T05:33:59.553657Z",
"updateTime": "2021-05-01T05:34:00.452804Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "415107e54e29af6d6ac089a7803439c39287ff3f",
"ref": "refs/heads/master"
}
}
}
[05:34:00.654] Task URL: https://cr-buildbucket.appspot.com/build/8848460776896464464
[05:34:00.654] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9013890839016373072:2:0) after 2m20s
[05:34:22.907] Received PubSub notification, asking Buildbucket for the build status
[05:34:22.931] Build status: STARTED
[05:36:20.671] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9013890839016373072:2:0)
[05:36:20.694] Build status: STARTED
[05:36:20.694] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9013890839016373072:4:0) after 9m16s
[05:45:36.726] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9013890839016373072:4:0)
[05:45:36.753] Build status: STARTED
[05:45:36.753] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9013890839016373072:5:0) after 4m35s
[05:50:11.770] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9013890839016373072:5:0)
[05:50:11.799] Build status: STARTED
[05:50:11.799] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9013890839016373072:6:0) after 9m28s
[05:59:39.816] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9013890839016373072:6:0)
[05:59:39.845] Build status: STARTED
[05:59:39.845] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9013890839016373072:7:0) after 9m43s
[06:09:22.860] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9013890839016373072:7:0)
[06:09:22.957] Build status: STARTED
[06:09:22.957] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9013890839016373072:8:0) after 9m48s
[06:19:11.019] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9013890839016373072:8:0)
[06:19:11.047] Build status: STARTED
[06:19:11.047] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9013890839016373072:9:0) after 7m21s
[06:26:32.075] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9013890839016373072:9:0)
[06:26:32.109] Build status: STARTED
[06:26:32.109] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9013890839016373072:10:0) after 8m45s
[06:35:17.528] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9013890839016373072:10:0)
[06:35:17.553] Build status: STARTED
[06:35:17.553] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9013890839016373072:11:0) after 6m28s
[06:41:45.660] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9013890839016373072:11:0)
[06:41:45.686] Build status: STARTED
[06:41:45.686] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9013890839016373072:12:0) after 1m1s
[06:42:46.886] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9013890839016373072:12:0)
[06:42:46.918] Build status: STARTED
[06:42:46.918] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9013890839016373072:13:0) after 3m13s
[06:45:59.938] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9013890839016373072:13:0)
[06:45:59.975] Build status: STARTED
[06:45:59.975] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9013890839016373072:14:0) after 2m29s
[06:48:28.986] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9013890839016373072:14:0)
[06:48:29.013] Build status: STARTED
[06:48:29.013] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9013890839016373072:15:0) after 3m22s
[06:51:51.033] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9013890839016373072:15:0)
[06:51:51.060] Build status: STARTED
[06:51:51.060] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9013890839016373072:16:0) after 7m29s
[06:59:20.098] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9013890839016373072:16:0)
[06:59:20.128] Build status: STARTED
[06:59:20.128] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9013890839016373072:17:0) after 5m16s
[07:04:36.176] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9013890839016373072:17:0)
[07:04:36.208] Build status: STARTED
[07:04:36.208] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9013890839016373072:18:0) after 5m22s
[07:09:58.325] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9013890839016373072:18:0)
[07:09:58.352] Build status: STARTED
[07:09:58.352] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9013890839016373072:19:0) after 5m59s
[07:15:57.417] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9013890839016373072:19:0)
[07:15:57.444] Build status: STARTED
[07:15:57.444] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9013890839016373072:20:0) after 7m51s
[07:18:09.695] Received PubSub notification, asking Buildbucket for the build status
[07:18:09.723] Build:
{
"id": "8848460776896464464",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "win-asan"
},
"number": 18495,
"createdBy": "project:chromium",
"createTime": "2021-05-01T05:33:59.553657Z",
"startTime": "2021-05-01T05:34:20.745871Z",
"endTime": "2021-05-01T07:18:09.366170909Z",
"updateTime": "2021-05-01T07:18:09.366539896Z",
"status": "FAILURE",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "415107e54e29af6d6ac089a7803439c39287ff3f",
"ref": "refs/heads/master"
}
}
}
[07:18:09.723] Invocation finished in 1h44m11.574734122s with status FAILED