[20:43:24.092] New invocation is queued and will start shortly
[20:43:25.172] Starting the invocation (attempt 1)
[20:43:25.212] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/0cb6ab056255039293cda77bd9841872ec47b616"
[20:43:25.212] Popped gitiles commit info from properties and tags
[20:43:25.212] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[20:43:25.212] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[20:43:25.212] Buildbucket request:
{
"requestId": "8985114384144273904",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "win10-code-coverage"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "0cb6ab056255039293cda77bd9841872ec47b616",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8985114384144273904"
},
{
"key": "scheduler_job_id",
"value": "chromium/win10-code-coverage"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[20:43:25.760] Scheduled build:
{
"id": "8819684322206731169",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "win10-code-coverage"
},
"number": 13509,
"createdBy": "project:chromium",
"createTime": "2022-03-14T20:43:25.308014729Z",
"updateTime": "2022-03-14T20:43:25.308014729Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "0cb6ab056255039293cda77bd9841872ec47b616",
"ref": "refs/heads/main"
}
}
}
[20:43:25.760] Task URL: https://cr-buildbucket.appspot.com/build/8819684322206731169
[20:43:25.760] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8985114384144273904:2:0) after 9m20s
[20:48:38.755] Received PubSub notification, asking Buildbucket for the build status
[20:48:38.776] Build status: STARTED
[20:52:45.888] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8985114384144273904:2:0)
[20:52:45.918] Build status: STARTED
[20:52:45.918] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8985114384144273904:4:0) after 7m0s
[20:59:45.944] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8985114384144273904:4:0)
[20:59:45.976] Build status: STARTED
[20:59:45.976] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8985114384144273904:5:0) after 6m22s
[21:06:08.012] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8985114384144273904:5:0)
[21:06:08.046] Build status: STARTED
[21:06:08.046] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8985114384144273904:6:0) after 2m31s
[21:08:39.270] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8985114384144273904:6:0)
[21:08:39.297] Build status: STARTED
[21:08:39.297] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8985114384144273904:7:0) after 9m8s
[21:17:47.362] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8985114384144273904:7:0)
[21:17:47.406] Build status: STARTED
[21:17:47.406] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8985114384144273904:8:0) after 1m10s
[21:18:58.038] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8985114384144273904:8:0)
[21:18:58.062] Build status: STARTED
[21:18:58.062] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8985114384144273904:9:0) after 3m33s
[21:22:31.088] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8985114384144273904:9:0)
[21:22:31.112] Build status: STARTED
[21:22:31.112] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8985114384144273904:10:0) after 3m29s
[21:26:00.138] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8985114384144273904:10:0)
[21:26:00.157] Build status: STARTED
[21:26:00.157] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8985114384144273904:11:0) after 5m59s
[21:31:59.187] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8985114384144273904:11:0)
[21:31:59.216] Build status: STARTED
[21:31:59.216] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8985114384144273904:12:0) after 1m54s
[21:33:53.297] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8985114384144273904:12:0)
[21:33:53.362] Build status: STARTED
[21:33:53.362] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8985114384144273904:13:0) after 3m58s
[21:37:51.391] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8985114384144273904:13:0)
[21:37:51.422] Build status: STARTED
[21:37:51.422] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8985114384144273904:14:0) after 4m0s
[21:41:51.773] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8985114384144273904:14:0)
[21:41:51.794] Build status: STARTED
[21:41:51.794] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8985114384144273904:15:0) after 6m26s
[21:48:17.878] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8985114384144273904:15:0)
[21:48:17.897] Build status: STARTED
[21:48:17.897] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8985114384144273904:16:0) after 6m31s
[21:54:49.107] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8985114384144273904:16:0)
[21:54:49.189] Build status: STARTED
[21:54:49.189] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8985114384144273904:17:0) after 8m34s
[22:03:23.573] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8985114384144273904:17:0)
[22:03:23.656] Build status: STARTED
[22:03:23.656] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8985114384144273904:18:0) after 5m14s
[22:08:37.688] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8985114384144273904:18:0)
[22:08:37.709] Build status: STARTED
[22:08:37.709] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8985114384144273904:19:0) after 1m26s
[22:10:03.776] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8985114384144273904:19:0)
[22:10:03.811] Build status: STARTED
[22:10:03.811] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8985114384144273904:20:0) after 5m20s
[22:15:23.894] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8985114384144273904:20:0)
[22:15:23.916] Build status: STARTED
[22:15:23.916] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8985114384144273904:21:0) after 8m3s
[22:23:27.271] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8985114384144273904:21:0)
[22:23:27.296] Build status: STARTED
[22:23:27.296] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8985114384144273904:22:0) after 3m4s
[22:26:31.379] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8985114384144273904:22:0)
[22:26:31.409] Build status: STARTED
[22:26:31.409] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8985114384144273904:23:0) after 9m35s
[22:36:06.438] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8985114384144273904:23:0)
[22:36:06.460] Build status: STARTED
[22:36:06.460] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8985114384144273904:24:0) after 9m18s
[22:45:24.716] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8985114384144273904:24:0)
[22:45:24.751] Build status: STARTED
[22:45:24.751] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8985114384144273904:25:0) after 6m58s
[22:52:22.880] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8985114384144273904:25:0)
[22:52:22.905] Build status: STARTED
[22:52:22.905] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8985114384144273904:26:0) after 3m20s
[22:55:42.934] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8985114384144273904:26:0)
[22:55:42.962] Build status: STARTED
[22:55:42.962] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8985114384144273904:27:0) after 5m34s
[23:01:16.997] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8985114384144273904:27:0)
[23:01:17.070] Build status: STARTED
[23:01:17.070] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8985114384144273904:28:0) after 3m34s
[23:04:51.275] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8985114384144273904:28:0)
[23:04:51.306] Build status: STARTED
[23:04:51.306] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8985114384144273904:29:0) after 4m19s
[23:09:10.332] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8985114384144273904:29:0)
[23:09:10.359] Build status: STARTED
[23:09:10.359] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8985114384144273904:30:0) after 3m25s
[23:12:36.079] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8985114384144273904:30:0)
[23:12:36.099] Build status: STARTED
[23:12:36.099] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8985114384144273904:31:0) after 6m46s
[23:19:22.398] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8985114384144273904:31:0)
[23:19:22.427] Build status: STARTED
[23:19:22.427] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8985114384144273904:32:0) after 1m35s
[23:20:57.720] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8985114384144273904:32:0)
[23:20:57.747] Build status: STARTED
[23:20:57.747] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8985114384144273904:33:0) after 6m48s
[23:27:45.823] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8985114384144273904:33:0)
[23:27:45.844] Build status: STARTED
[23:27:45.844] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8985114384144273904:34:0) after 3m33s
[23:31:18.906] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8985114384144273904:34:0)
[23:31:18.936] Build status: STARTED
[23:31:18.936] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8985114384144273904:35:0) after 4m50s
[23:36:09.061] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8985114384144273904:35:0)
[23:36:09.092] Build status: STARTED
[23:36:09.092] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8985114384144273904:36:0) after 6m33s
[23:42:42.881] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8985114384144273904:36:0)
[23:42:42.904] Build status: STARTED
[23:42:42.904] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8985114384144273904:37:0) after 8m1s
[23:50:44.164] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8985114384144273904:37:0)
[23:50:44.187] Build status: STARTED
[23:50:44.187] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8985114384144273904:38:0) after 2m44s
[23:53:28.313] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8985114384144273904:38:0)
[23:53:28.336] Build status: STARTED
[23:53:28.336] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8985114384144273904:39:0) after 7m1s
[00:00:30.015] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8985114384144273904:39:0)
[00:00:30.148] Build status: STARTED
[00:00:30.148] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8985114384144273904:40:0) after 8m47s
[00:09:17.180] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8985114384144273904:40:0)
[00:09:17.201] Build status: STARTED
[00:09:17.202] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8985114384144273904:41:0) after 3m24s
[00:12:41.259] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8985114384144273904:41:0)
[00:12:41.348] Build status: STARTED
[00:12:41.348] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8985114384144273904:42:0) after 8m17s
[00:20:58.626] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8985114384144273904:42:0)
[00:20:58.655] Build status: STARTED
[00:20:58.655] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8985114384144273904:43:0) after 2m37s
[00:23:35.654] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8985114384144273904:43:0)
[00:23:35.674] Build status: STARTED
[00:23:35.674] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8985114384144273904:44:0) after 9m34s
[00:33:09.957] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8985114384144273904:44:0)
[00:33:09.997] Build status: STARTED
[00:33:09.997] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8985114384144273904:45:0) after 7m58s
[00:41:08.324] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8985114384144273904:45:0)
[00:41:08.352] Build status: STARTED
[00:41:08.352] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8985114384144273904:46:0) after 4m12s
[00:45:20.494] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8985114384144273904:46:0)
[00:45:20.533] Build status: STARTED
[00:45:20.533] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8985114384144273904:47:0) after 7m4s
[00:52:24.563] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8985114384144273904:47:0)
[00:52:24.584] Build status: STARTED
[00:52:24.584] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8985114384144273904:48:0) after 3m14s
[00:55:38.627] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8985114384144273904:48:0)
[00:55:38.648] Build status: STARTED
[00:55:38.648] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8985114384144273904:49:0) after 7m46s
[01:03:24.721] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8985114384144273904:49:0)
[01:03:24.768] Build status: STARTED
[01:03:24.768] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8985114384144273904:50:0) after 9m7s
[01:12:31.924] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8985114384144273904:50:0)
[01:12:31.943] Build status: STARTED
[01:12:31.943] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8985114384144273904:51:0) after 8m39s
[01:21:11.241] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8985114384144273904:51:0)
[01:21:11.270] Build status: STARTED
[01:21:11.270] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8985114384144273904:52:0) after 6m21s
[01:27:32.402] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8985114384144273904:52:0)
[01:27:32.462] Build status: STARTED
[01:27:32.462] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8985114384144273904:53:0) after 3m4s
[01:30:36.537] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8985114384144273904:53:0)
[01:30:36.564] Build status: STARTED
[01:30:36.564] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8985114384144273904:54:0) after 2m10s
[01:32:46.712] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8985114384144273904:54:0)
[01:32:46.740] Build status: STARTED
[01:32:46.740] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8985114384144273904:55:0) after 7m9s
[01:39:55.784] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8985114384144273904:55:0)
[01:39:55.823] Build status: STARTED
[01:39:55.823] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8985114384144273904:56:0) after 9m9s
[01:49:05.326] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8985114384144273904:56:0)
[01:49:05.359] Build status: STARTED
[01:49:05.359] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8985114384144273904:57:0) after 9m10s
[01:58:15.754] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8985114384144273904:57:0)
[01:58:15.776] Build status: STARTED
[01:58:15.776] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8985114384144273904:58:0) after 3m32s
[02:01:47.811] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8985114384144273904:58:0)
[02:01:47.840] Build status: STARTED
[02:01:47.840] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8985114384144273904:59:0) after 5m48s
[02:07:35.862] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8985114384144273904:59:0)
[02:07:35.893] Build status: STARTED
[02:07:35.893] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8985114384144273904:60:0) after 5m38s
[02:13:13.920] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8985114384144273904:60:0)
[02:13:13.945] Build status: STARTED
[02:13:13.945] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8985114384144273904:61:0) after 8m55s
[02:22:09.056] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8985114384144273904:61:0)
[02:22:09.118] Build status: STARTED
[02:22:09.118] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8985114384144273904:62:0) after 4m19s
[02:26:28.482] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8985114384144273904:62:0)
[02:26:28.506] Build status: STARTED
[02:26:28.506] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8985114384144273904:63:0) after 6m44s
[02:33:12.536] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8985114384144273904:63:0)
[02:33:12.560] Build status: STARTED
[02:33:12.560] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8985114384144273904:64:0) after 9m2s
[02:42:14.605] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8985114384144273904:64:0)
[02:42:14.631] Build status: STARTED
[02:42:14.631] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8985114384144273904:65:0) after 8m57s
[02:51:11.658] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8985114384144273904:65:0)
[02:51:11.686] Build status: STARTED
[02:51:11.686] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8985114384144273904:66:0) after 9m42s
[03:00:53.756] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8985114384144273904:66:0)
[03:00:53.799] Build status: STARTED
[03:00:53.799] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8985114384144273904:67:0) after 1m19s
[03:02:12.903] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8985114384144273904:67:0)
[03:02:12.937] Build status: STARTED
[03:02:12.937] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8985114384144273904:68:0) after 4m13s
[03:06:26.004] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8985114384144273904:68:0)
[03:06:26.045] Build status: STARTED
[03:06:26.045] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8985114384144273904:69:0) after 9m42s
[03:08:25.652] Received PubSub notification, asking Buildbucket for the build status
[03:08:25.679] Build:
{
"id": "8819684322206731169",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "win10-code-coverage"
},
"number": 13509,
"createdBy": "project:chromium",
"createTime": "2022-03-14T20:43:25.308014729Z",
"startTime": "2022-03-14T20:48:37.911316Z",
"endTime": "2022-03-15T03:08:25.295606202Z",
"updateTime": "2022-03-15T03:08:25.295606202Z",
"status": "FAILURE",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "0cb6ab056255039293cda77bd9841872ec47b616",
"ref": "refs/heads/main"
}
}
}
[03:08:25.679] Invocation finished in 6h25m1.601266336s with status FAILED