[18:16:55.414] New invocation is queued and will start shortly
[18:16:56.544] Starting the invocation (attempt 1)
[18:16:56.586] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/4f05db92bfbf146594c6905abb6b93f10f811b71"
[18:16:56.586] Popped gitiles commit info from properties and tags
[18:16:56.586] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[18:16:56.586] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[18:16:56.586] Buildbucket request:
{
"requestId": "8966188833763403968",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "win10-code-coverage"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "4f05db92bfbf146594c6905abb6b93f10f811b71",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8966188833763403968"
},
{
"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"
}
}
[18:16:57.184] Scheduled build:
{
"id": "8800758771774535393",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "win10-code-coverage"
},
"number": 14999,
"createdBy": "project:chromium",
"createTime": "2022-10-09T18:16:56.679649733Z",
"updateTime": "2022-10-09T18:16:56.679649733Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "4f05db92bfbf146594c6905abb6b93f10f811b71",
"ref": "refs/heads/main"
}
}
}
[18:16:57.185] Task URL: https://cr-buildbucket.appspot.com/build/8800758771774535393
[18:16:57.185] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8966188833763403968:2:0) after 2m51s
[18:19:10.970] Received PubSub notification, asking Buildbucket for the build status
[18:19:11.014] Build status: STARTED
[18:19:48.357] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8966188833763403968:2:0)
[18:19:48.399] Build status: STARTED
[18:19:48.399] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8966188833763403968:4:0) after 6m4s
[18:25:52.429] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8966188833763403968:4:0)
[18:25:52.454] Build status: STARTED
[18:25:52.454] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8966188833763403968:5:0) after 3m49s
[18:29:41.478] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8966188833763403968:5:0)
[18:29:41.514] Build status: STARTED
[18:29:41.514] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8966188833763403968:6:0) after 9m36s
[18:39:17.547] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8966188833763403968:6:0)
[18:39:17.578] Build status: STARTED
[18:39:17.580] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8966188833763403968:7:0) after 1m51s
[18:41:08.601] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8966188833763403968:7:0)
[18:41:08.625] Build status: STARTED
[18:41:08.625] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8966188833763403968:8:0) after 9m55s
[18:51:04.126] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8966188833763403968:8:0)
[18:51:04.166] Build status: STARTED
[18:51:04.166] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8966188833763403968:9:0) after 7m41s
[18:58:45.445] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8966188833763403968:9:0)
[18:58:45.503] Build status: STARTED
[18:58:45.503] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8966188833763403968:10:0) after 3m51s
[19:02:36.803] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8966188833763403968:10:0)
[19:02:36.865] Build status: STARTED
[19:02:36.865] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8966188833763403968:11:0) after 7m38s
[19:10:15.110] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8966188833763403968:11:0)
[19:10:15.135] Build status: STARTED
[19:10:15.135] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8966188833763403968:12:0) after 1m32s
[19:11:47.719] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8966188833763403968:12:0)
[19:11:47.750] Build status: STARTED
[19:11:47.750] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8966188833763403968:13:0) after 1m5s
[19:12:52.942] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8966188833763403968:13:0)
[19:12:52.978] Build status: STARTED
[19:12:52.978] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8966188833763403968:14:0) after 4m8s
[19:17:00.998] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8966188833763403968:14:0)
[19:17:01.036] Build status: STARTED
[19:17:01.036] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8966188833763403968:15:0) after 8m54s
[19:25:55.085] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8966188833763403968:15:0)
[19:25:55.106] Build status: STARTED
[19:25:55.106] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8966188833763403968:16:0) after 5m58s
[19:31:53.128] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8966188833763403968:16:0)
[19:31:53.152] Build status: STARTED
[19:31:53.152] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8966188833763403968:17:0) after 3m21s
[19:35:14.178] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8966188833763403968:17:0)
[19:35:14.200] Build status: STARTED
[19:35:14.200] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8966188833763403968:18:0) after 4m51s
[19:40:05.245] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8966188833763403968:18:0)
[19:40:05.317] Build status: STARTED
[19:40:05.318] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8966188833763403968:19:0) after 4m30s
[19:44:35.347] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8966188833763403968:19:0)
[19:44:35.372] Build status: STARTED
[19:44:35.372] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8966188833763403968:20:0) after 4m1s
[19:48:36.629] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8966188833763403968:20:0)
[19:48:36.670] Build status: STARTED
[19:48:36.670] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8966188833763403968:21:0) after 1m22s
[19:49:58.860] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8966188833763403968:21:0)
[19:49:58.892] Build status: STARTED
[19:49:58.892] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8966188833763403968:22:0) after 9m40s
[19:59:38.969] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8966188833763403968:22:0)
[19:59:38.990] Build status: STARTED
[19:59:38.990] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8966188833763403968:23:0) after 5m28s
[20:05:07.012] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8966188833763403968:23:0)
[20:05:07.039] Build status: STARTED
[20:05:07.039] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8966188833763403968:24:0) after 5m41s
[20:10:48.284] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8966188833763403968:24:0)
[20:10:48.310] Build status: STARTED
[20:10:48.310] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8966188833763403968:25:0) after 7m31s
[20:18:19.358] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8966188833763403968:25:0)
[20:18:19.379] Build status: STARTED
[20:18:19.379] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8966188833763403968:26:0) after 9m13s
[20:27:32.526] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8966188833763403968:26:0)
[20:27:32.552] Build status: STARTED
[20:27:32.552] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8966188833763403968:27:0) after 1m40s
[20:29:12.826] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8966188833763403968:27:0)
[20:29:12.853] Build status: STARTED
[20:29:12.853] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8966188833763403968:28:0) after 2m16s
[20:31:28.977] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8966188833763403968:28:0)
[20:31:29.000] Build status: STARTED
[20:31:29.000] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8966188833763403968:29:0) after 1m50s
[20:33:19.137] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8966188833763403968:29:0)
[20:33:19.162] Build status: STARTED
[20:33:19.162] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8966188833763403968:30:0) after 9m41s
[20:43:00.196] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8966188833763403968:30:0)
[20:43:00.229] Build status: STARTED
[20:43:00.229] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8966188833763403968:31:0) after 6m4s
[20:49:04.292] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8966188833763403968:31:0)
[20:49:04.318] Build status: STARTED
[20:49:04.318] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8966188833763403968:32:0) after 5m19s
[20:54:23.428] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8966188833763403968:32:0)
[20:54:23.454] Build status: STARTED
[20:54:23.454] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8966188833763403968:33:0) after 5m26s
[20:59:49.475] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8966188833763403968:33:0)
[20:59:49.498] Build status: STARTED
[20:59:49.498] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8966188833763403968:34:0) after 2m25s
[21:02:14.524] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8966188833763403968:34:0)
[21:02:14.560] Build status: STARTED
[21:02:14.560] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8966188833763403968:35:0) after 8m10s
[21:10:24.686] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8966188833763403968:35:0)
[21:10:24.713] Build status: STARTED
[21:10:24.713] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8966188833763403968:36:0) after 8m45s
[21:19:09.820] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8966188833763403968:36:0)
[21:19:09.842] Build status: STARTED
[21:19:09.842] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8966188833763403968:37:0) after 2m14s
[21:21:23.870] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8966188833763403968:37:0)
[21:21:23.893] Build status: STARTED
[21:21:23.893] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8966188833763403968:38:0) after 8m37s
[21:30:00.966] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8966188833763403968:38:0)
[21:30:00.992] Build status: STARTED
[21:30:00.992] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8966188833763403968:39:0) after 4m44s
[21:34:45.012] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8966188833763403968:39:0)
[21:34:45.034] Build status: STARTED
[21:34:45.034] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8966188833763403968:40:0) after 6m49s
[21:41:34.057] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8966188833763403968:40:0)
[21:41:34.083] Build status: STARTED
[21:41:34.083] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8966188833763403968:41:0) after 7m6s
[21:48:40.108] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8966188833763403968:41:0)
[21:48:40.200] Build status: STARTED
[21:48:40.200] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8966188833763403968:42:0) after 2m25s
[21:51:05.181] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8966188833763403968:42:0)
[21:51:05.207] Build status: STARTED
[21:51:05.207] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8966188833763403968:43:0) after 6m10s
[21:57:15.263] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8966188833763403968:43:0)
[21:57:15.286] Build status: STARTED
[21:57:15.286] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8966188833763403968:44:0) after 4m6s
[22:01:21.309] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8966188833763403968:44:0)
[22:01:21.332] Build status: STARTED
[22:01:21.332] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8966188833763403968:45:0) after 4m42s
[22:06:03.457] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8966188833763403968:45:0)
[22:06:03.487] Build status: STARTED
[22:06:03.487] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8966188833763403968:46:0) after 9m40s
[22:15:43.477] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8966188833763403968:46:0)
[22:15:43.500] Build status: STARTED
[22:15:43.500] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8966188833763403968:47:0) after 8m51s
[22:24:34.605] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8966188833763403968:47:0)
[22:24:34.638] Build status: STARTED
[22:24:34.638] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8966188833763403968:48:0) after 2m42s
[22:27:16.683] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8966188833763403968:48:0)
[22:27:16.704] Build status: STARTED
[22:27:16.704] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8966188833763403968:49:0) after 7m51s
[22:35:07.877] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8966188833763403968:49:0)
[22:35:07.905] Build status: STARTED
[22:35:07.906] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8966188833763403968:50:0) after 8m11s
[22:43:18.958] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8966188833763403968:50:0)
[22:43:18.983] Build status: STARTED
[22:43:18.983] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8966188833763403968:51:0) after 2m43s
[22:46:02.093] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8966188833763403968:51:0)
[22:46:02.114] Build status: STARTED
[22:46:02.114] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8966188833763403968:52:0) after 9m28s
[22:55:30.135] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8966188833763403968:52:0)
[22:55:30.158] Build status: STARTED
[22:55:30.158] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8966188833763403968:53:0) after 1m9s
[22:56:39.180] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8966188833763403968:53:0)
[22:56:39.210] Build status: STARTED
[22:56:39.210] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8966188833763403968:54:0) after 2m31s
[22:59:10.438] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8966188833763403968:54:0)
[22:59:10.590] Build status: STARTED
[22:59:10.590] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8966188833763403968:55:0) after 7m20s
[23:06:30.664] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8966188833763403968:55:0)
[23:06:30.695] Build status: STARTED
[23:06:30.695] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8966188833763403968:56:0) after 3m33s
[23:08:37.478] Received PubSub notification, asking Buildbucket for the build status
[23:08:37.503] Build:
{
"id": "8800758771774535393",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "win10-code-coverage"
},
"number": 14999,
"createdBy": "project:chromium",
"createTime": "2022-10-09T18:16:56.679649733Z",
"startTime": "2022-10-09T18:19:10.271109Z",
"endTime": "2022-10-09T23:08:37.212951975Z",
"updateTime": "2022-10-09T23:08:37.212951975Z",
"status": "FAILURE",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "4f05db92bfbf146594c6905abb6b93f10f811b71",
"ref": "refs/heads/main"
}
}
}
[23:08:37.503] Invocation finished in 4h51m42.104348647s with status FAILED