[08:59:05.408] New invocation is queued and will start shortly
[08:59:07.107] Starting the invocation (attempt 1)
[08:59:07.166] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/aaa4498aa55defd467784017a77e858b315fb2f1"
[08:59:07.166] Popped gitiles commit info from properties and tags
[08:59:07.166] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[08:59:07.166] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[08:59:07.166] Buildbucket request:
{
"requestId": "8988691977274908208",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "win10-code-coverage"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "aaa4498aa55defd467784017a77e858b315fb2f1",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8988691977274908208"
},
{
"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"
}
}
[08:59:07.743] Scheduled build:
{
"id": "8823261914672238529",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "win10-code-coverage"
},
"number": 13274,
"createdBy": "project:chromium",
"createTime": "2022-02-03T08:59:07.259459597Z",
"updateTime": "2022-02-03T08:59:07.259459597Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "aaa4498aa55defd467784017a77e858b315fb2f1",
"ref": "refs/heads/main"
}
}
}
[08:59:07.743] Task URL: https://cr-buildbucket.appspot.com/build/8823261914672238529
[08:59:07.743] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8988691977274908208:2:0) after 7m12s
[09:01:16.691] Received PubSub notification, asking Buildbucket for the build status
[09:01:16.720] Build status: STARTED
[09:06:19.763] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8988691977274908208:2:0)
[09:06:19.785] Build status: STARTED
[09:06:19.785] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8988691977274908208:4:0) after 3m56s
[09:10:15.805] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8988691977274908208:4:0)
[09:10:15.834] Build status: STARTED
[09:10:15.834] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8988691977274908208:5:0) after 3m17s
[09:13:32.877] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8988691977274908208:5:0)
[09:13:32.907] Build status: STARTED
[09:13:32.907] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8988691977274908208:6:0) after 4m42s
[09:18:14.998] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8988691977274908208:6:0)
[09:18:15.033] Build status: STARTED
[09:18:15.033] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8988691977274908208:7:0) after 4m44s
[09:22:59.028] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8988691977274908208:7:0)
[09:22:59.207] Build status: STARTED
[09:22:59.207] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8988691977274908208:8:0) after 4m1s
[09:27:00.267] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8988691977274908208:8:0)
[09:27:00.288] Build status: STARTED
[09:27:00.288] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8988691977274908208:9:0) after 1m29s
[09:28:29.328] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8988691977274908208:9:0)
[09:28:29.365] Build status: STARTED
[09:28:29.365] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8988691977274908208:10:0) after 2m26s
[09:30:55.384] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8988691977274908208:10:0)
[09:30:55.405] Build status: STARTED
[09:30:55.405] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8988691977274908208:11:0) after 3m25s
[09:34:20.424] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8988691977274908208:11:0)
[09:34:20.462] Build status: STARTED
[09:34:20.462] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8988691977274908208:12:0) after 7m51s
[09:42:11.581] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8988691977274908208:12:0)
[09:42:11.602] Build status: STARTED
[09:42:11.602] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8988691977274908208:13:0) after 9m16s
[09:51:27.622] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8988691977274908208:13:0)
[09:51:27.642] Build status: STARTED
[09:51:27.642] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8988691977274908208:14:0) after 2m51s
[09:54:18.675] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8988691977274908208:14:0)
[09:54:18.707] Build status: STARTED
[09:54:18.707] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8988691977274908208:15:0) after 8m7s
[10:02:25.703] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8988691977274908208:15:0)
[10:02:25.733] Build status: STARTED
[10:02:25.733] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8988691977274908208:16:0) after 1m13s
[10:03:38.857] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8988691977274908208:16:0)
[10:03:38.876] Build status: STARTED
[10:03:38.876] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8988691977274908208:17:0) after 4m24s
[10:08:02.897] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8988691977274908208:17:0)
[10:08:02.918] Build status: STARTED
[10:08:02.918] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8988691977274908208:18:0) after 5m2s
[10:13:04.945] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8988691977274908208:18:0)
[10:13:04.980] Build status: STARTED
[10:13:04.980] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8988691977274908208:19:0) after 8m4s
[10:21:09.089] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8988691977274908208:19:0)
[10:21:09.111] Build status: STARTED
[10:21:09.111] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8988691977274908208:20:0) after 8m19s
[10:29:28.270] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8988691977274908208:20:0)
[10:29:28.306] Build status: STARTED
[10:29:28.306] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8988691977274908208:21:0) after 2m55s
[10:32:23.325] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8988691977274908208:21:0)
[10:32:23.353] Build status: STARTED
[10:32:23.353] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8988691977274908208:22:0) after 5m15s
[10:37:38.564] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8988691977274908208:22:0)
[10:37:38.594] Build status: STARTED
[10:37:38.594] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8988691977274908208:23:0) after 2m34s
[10:40:12.871] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8988691977274908208:23:0)
[10:40:12.894] Build status: STARTED
[10:40:12.894] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8988691977274908208:24:0) after 6m5s
[10:46:18.536] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8988691977274908208:24:0)
[10:46:18.558] Build status: STARTED
[10:46:18.558] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8988691977274908208:25:0) after 1m30s
[10:47:48.576] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8988691977274908208:25:0)
[10:47:48.596] Build status: STARTED
[10:47:48.596] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8988691977274908208:26:0) after 6m1s
[10:53:49.590] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8988691977274908208:26:0)
[10:53:49.613] Build status: STARTED
[10:53:49.613] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8988691977274908208:27:0) after 7m57s
[11:01:46.640] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8988691977274908208:27:0)
[11:01:46.683] Build status: STARTED
[11:01:46.683] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8988691977274908208:28:0) after 1m0s
[11:02:46.691] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8988691977274908208:28:0)
[11:02:46.748] Build status: STARTED
[11:02:46.748] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8988691977274908208:29:0) after 7m27s
[11:10:14.033] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8988691977274908208:29:0)
[11:10:14.097] Build status: STARTED
[11:10:14.097] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8988691977274908208:30:0) after 7m27s
[11:17:41.132] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8988691977274908208:30:0)
[11:17:41.188] Build status: STARTED
[11:17:41.188] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8988691977274908208:31:0) after 3m21s
[11:21:02.303] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8988691977274908208:31:0)
[11:21:02.327] Build status: STARTED
[11:21:02.327] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8988691977274908208:32:0) after 6m26s
[11:27:28.593] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8988691977274908208:32:0)
[11:27:28.625] Build status: STARTED
[11:27:28.626] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8988691977274908208:33:0) after 3m35s
[11:31:03.835] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8988691977274908208:33:0)
[11:31:03.858] Build status: STARTED
[11:31:03.858] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8988691977274908208:34:0) after 7m7s
[11:38:10.881] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8988691977274908208:34:0)
[11:38:10.911] Build status: STARTED
[11:38:10.911] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8988691977274908208:35:0) after 2m32s
[11:40:42.929] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8988691977274908208:35:0)
[11:40:42.950] Build status: STARTED
[11:40:42.950] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8988691977274908208:36:0) after 7m54s
[11:48:37.103] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8988691977274908208:36:0)
[11:48:37.138] Build status: STARTED
[11:48:37.138] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8988691977274908208:37:0) after 7m30s
[11:56:07.183] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8988691977274908208:37:0)
[11:56:07.212] Build status: STARTED
[11:56:07.212] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8988691977274908208:38:0) after 6m35s
[12:02:42.433] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8988691977274908208:38:0)
[12:02:42.453] Build status: STARTED
[12:02:42.453] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8988691977274908208:39:0) after 8m12s
[12:10:54.641] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8988691977274908208:39:0)
[12:10:54.673] Build status: STARTED
[12:10:54.673] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8988691977274908208:40:0) after 2m59s
[12:13:53.794] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8988691977274908208:40:0)
[12:13:53.836] Build status: STARTED
[12:13:53.836] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8988691977274908208:41:0) after 4m13s
[12:18:06.884] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8988691977274908208:41:0)
[12:18:06.913] Build status: STARTED
[12:18:06.913] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8988691977274908208:42:0) after 4m56s
[12:23:02.933] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8988691977274908208:42:0)
[12:23:02.990] Build status: STARTED
[12:23:02.990] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8988691977274908208:43:0) after 6m38s
[12:29:41.012] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8988691977274908208:43:0)
[12:29:41.049] Build status: STARTED
[12:29:41.049] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8988691977274908208:44:0) after 4m58s
[12:34:39.101] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8988691977274908208:44:0)
[12:34:39.133] Build status: STARTED
[12:34:39.133] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8988691977274908208:45:0) after 8m33s
[12:43:12.121] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8988691977274908208:45:0)
[12:43:12.140] Build status: STARTED
[12:43:12.140] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8988691977274908208:46:0) after 7m57s
[12:51:09.262] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8988691977274908208:46:0)
[12:51:09.285] Build status: STARTED
[12:51:09.285] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8988691977274908208:47:0) after 2m35s
[12:53:44.305] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8988691977274908208:47:0)
[12:53:44.338] Build status: STARTED
[12:53:44.338] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8988691977274908208:48:0) after 2m2s
[12:55:46.524] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8988691977274908208:48:0)
[12:55:46.545] Build status: STARTED
[12:55:46.545] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8988691977274908208:49:0) after 1m18s
[12:57:04.636] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8988691977274908208:49:0)
[12:57:04.655] Build status: STARTED
[12:57:04.655] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8988691977274908208:50:0) after 7m57s
[13:05:01.695] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8988691977274908208:50:0)
[13:05:01.725] Build status: STARTED
[13:05:01.725] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8988691977274908208:51:0) after 3m49s
[13:08:50.750] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8988691977274908208:51:0)
[13:08:50.780] Build status: STARTED
[13:08:50.780] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8988691977274908208:52:0) after 5m25s
[13:14:15.804] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8988691977274908208:52:0)
[13:14:15.829] Build status: STARTED
[13:14:15.829] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8988691977274908208:53:0) after 4m33s
[13:18:48.848] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8988691977274908208:53:0)
[13:18:48.878] Build status: STARTED
[13:18:48.878] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8988691977274908208:54:0) after 1m12s
[13:20:01.063] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8988691977274908208:54:0)
[13:20:01.087] Build status: STARTED
[13:20:01.087] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8988691977274908208:55:0) after 5m21s
[13:25:22.098] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8988691977274908208:55:0)
[13:25:22.118] Build status: STARTED
[13:25:22.118] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8988691977274908208:56:0) after 5m55s
[13:31:17.281] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8988691977274908208:56:0)
[13:31:17.321] Build status: STARTED
[13:31:17.321] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8988691977274908208:57:0) after 4m38s
[13:35:55.582] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8988691977274908208:57:0)
[13:35:55.654] Build status: STARTED
[13:35:55.654] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8988691977274908208:58:0) after 7m37s
[13:43:32.673] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8988691977274908208:58:0)
[13:43:32.721] Build status: STARTED
[13:43:32.721] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8988691977274908208:59:0) after 3m54s
[13:47:26.744] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8988691977274908208:59:0)
[13:47:26.778] Build status: STARTED
[13:47:26.778] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8988691977274908208:60:0) after 5m59s
[13:53:25.835] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8988691977274908208:60:0)
[13:53:25.858] Build status: STARTED
[13:53:25.858] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8988691977274908208:61:0) after 1m53s
[13:55:19.048] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8988691977274908208:61:0)
[13:55:19.068] Build status: STARTED
[13:55:19.068] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8988691977274908208:62:0) after 7m41s
[14:03:00.112] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8988691977274908208:62:0)
[14:03:00.133] Build status: STARTED
[14:03:00.133] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8988691977274908208:63:0) after 9m45s
[14:12:45.158] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8988691977274908208:63:0)
[14:12:45.177] Build status: STARTED
[14:12:45.177] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8988691977274908208:64:0) after 9m42s
[14:22:27.212] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8988691977274908208:64:0)
[14:22:27.235] Build status: STARTED
[14:22:27.236] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8988691977274908208:65:0) after 8m12s
[14:30:39.258] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8988691977274908208:65:0)
[14:30:39.283] Build status: STARTED
[14:30:39.283] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8988691977274908208:66:0) after 2m15s
[14:32:54.273] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8988691977274908208:66:0)
[14:32:54.307] Build status: STARTED
[14:32:54.307] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8988691977274908208:67:0) after 9m48s
[14:42:42.615] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8988691977274908208:67:0)
[14:42:42.636] Build status: STARTED
[14:42:42.636] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8988691977274908208:68:0) after 9m34s
[14:44:48.298] Received PubSub notification, asking Buildbucket for the build status
[14:44:48.318] Build:
{
"id": "8823261914672238529",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "win10-code-coverage"
},
"number": 13274,
"createdBy": "project:chromium",
"createTime": "2022-02-03T08:59:07.259459597Z",
"startTime": "2022-02-03T09:01:15.826449Z",
"endTime": "2022-02-03T14:44:47.956821975Z",
"updateTime": "2022-02-03T14:44:47.956821975Z",
"status": "FAILURE",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "aaa4498aa55defd467784017a77e858b315fb2f1",
"ref": "refs/heads/main"
}
}
}
[14:44:48.318] Invocation finished in 5h45m42.924155469s with status FAILED