[01:58:54.088] New invocation is queued and will start shortly
[01:58:55.141] Starting the invocation (attempt 1)
[01:58:55.178] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/08a1dcc3f8115163d15d56b40c7f3722b47225e6"
[01:58:55.178] Popped gitiles commit info from properties and tags
[01:58:55.178] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[01:58:55.178] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[01:58:55.178] Buildbucket request:
{
"requestId": "8993157664614685456",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "win10-code-coverage"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "08a1dcc3f8115163d15d56b40c7f3722b47225e6",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8993157664614685456"
},
{
"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"
}
}
[01:58:55.685] Scheduled build:
{
"id": "8827727602753289233",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "win10-code-coverage"
},
"number": 13079,
"createdBy": "project:chromium",
"createTime": "2021-12-16T01:58:55.231386255Z",
"updateTime": "2021-12-16T01:58:55.231386255Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "08a1dcc3f8115163d15d56b40c7f3722b47225e6",
"ref": "refs/heads/main"
}
}
}
[01:58:55.685] Task URL: https://cr-buildbucket.appspot.com/build/8827727602753289233
[01:58:55.685] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8993157664614685456:2:0) after 7m46s
[02:01:03.893] Received PubSub notification, asking Buildbucket for the build status
[02:01:04.669] Build status: STARTED
[02:06:41.962] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8993157664614685456:2:0)
[02:06:41.983] Build status: STARTED
[02:06:41.983] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8993157664614685456:4:0) after 4m24s
[02:11:06.174] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8993157664614685456:4:0)
[02:11:06.286] Build status: STARTED
[02:11:06.286] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8993157664614685456:5:0) after 1m3s
[02:12:09.408] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8993157664614685456:5:0)
[02:12:09.425] Build status: STARTED
[02:12:09.425] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8993157664614685456:6:0) after 8m51s
[02:21:00.449] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8993157664614685456:6:0)
[02:21:00.468] Build status: STARTED
[02:21:00.468] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8993157664614685456:7:0) after 1m52s
[02:22:52.544] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8993157664614685456:7:0)
[02:22:52.566] Build status: STARTED
[02:22:52.566] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8993157664614685456:8:0) after 2m3s
[02:24:55.682] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8993157664614685456:8:0)
[02:24:55.701] Build status: STARTED
[02:24:55.701] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8993157664614685456:9:0) after 9m58s
[02:34:53.753] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8993157664614685456:9:0)
[02:34:53.780] Build status: STARTED
[02:34:53.780] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8993157664614685456:10:0) after 5m19s
[02:40:12.899] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8993157664614685456:10:0)
[02:40:12.929] Build status: STARTED
[02:40:12.929] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8993157664614685456:11:0) after 9m44s
[02:49:57.012] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8993157664614685456:11:0)
[02:49:57.048] Build status: STARTED
[02:49:57.048] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8993157664614685456:12:0) after 1m51s
[02:51:48.073] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8993157664614685456:12:0)
[02:51:48.095] Build status: STARTED
[02:51:48.095] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8993157664614685456:13:0) after 9m42s
[03:01:30.119] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8993157664614685456:13:0)
[03:01:30.159] Build status: STARTED
[03:01:30.159] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8993157664614685456:14:0) after 4m37s
[03:06:07.299] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8993157664614685456:14:0)
[03:06:07.366] Build status: STARTED
[03:06:07.366] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8993157664614685456:15:0) after 8m14s
[03:14:21.585] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8993157664614685456:15:0)
[03:14:21.611] Build status: STARTED
[03:14:21.611] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8993157664614685456:16:0) after 6m45s
[03:21:06.722] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8993157664614685456:16:0)
[03:21:06.759] Build status: STARTED
[03:21:06.759] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8993157664614685456:17:0) after 3m15s
[03:24:21.784] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8993157664614685456:17:0)
[03:24:21.824] Build status: STARTED
[03:24:21.824] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8993157664614685456:18:0) after 9m12s
[03:33:33.981] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8993157664614685456:18:0)
[03:33:34.007] Build status: STARTED
[03:33:34.007] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8993157664614685456:19:0) after 1m7s
[03:34:41.057] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8993157664614685456:19:0)
[03:34:41.092] Build status: STARTED
[03:34:41.092] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8993157664614685456:20:0) after 3m58s
[03:38:39.221] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8993157664614685456:20:0)
[03:38:39.276] Build status: STARTED
[03:38:39.276] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8993157664614685456:21:0) after 7m50s
[03:46:29.317] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8993157664614685456:21:0)
[03:46:29.333] Build status: STARTED
[03:46:29.333] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8993157664614685456:22:0) after 3m17s
[03:49:46.394] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8993157664614685456:22:0)
[03:49:46.417] Build status: STARTED
[03:49:46.417] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8993157664614685456:23:0) after 2m0s
[03:51:46.402] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8993157664614685456:23:0)
[03:51:46.438] Build status: STARTED
[03:51:46.438] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8993157664614685456:24:0) after 7m50s
[03:59:36.470] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8993157664614685456:24:0)
[03:59:36.494] Build status: STARTED
[03:59:36.494] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8993157664614685456:25:0) after 5m10s
[04:04:46.615] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8993157664614685456:25:0)
[04:04:46.649] Build status: STARTED
[04:04:46.649] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8993157664614685456:26:0) after 2m47s
[04:07:33.821] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8993157664614685456:26:0)
[04:07:33.861] Build status: STARTED
[04:07:33.861] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8993157664614685456:27:0) after 1m1s
[04:08:34.884] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8993157664614685456:27:0)
[04:08:34.921] Build status: STARTED
[04:08:34.921] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8993157664614685456:28:0) after 6m9s
[04:14:44.051] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8993157664614685456:28:0)
[04:14:44.074] Build status: STARTED
[04:14:44.074] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8993157664614685456:29:0) after 8m46s
[04:23:30.284] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8993157664614685456:29:0)
[04:23:30.306] Build status: STARTED
[04:23:30.307] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8993157664614685456:30:0) after 5m11s
[04:28:41.326] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8993157664614685456:30:0)
[04:28:41.351] Build status: STARTED
[04:28:41.351] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8993157664614685456:31:0) after 7m0s
[04:35:41.574] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8993157664614685456:31:0)
[04:35:41.607] Build status: STARTED
[04:35:41.607] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8993157664614685456:32:0) after 2m24s
[04:38:05.649] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8993157664614685456:32:0)
[04:38:05.674] Build status: STARTED
[04:38:05.674] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8993157664614685456:33:0) after 8m55s
[04:47:00.697] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8993157664614685456:33:0)
[04:47:00.735] Build status: STARTED
[04:47:00.735] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8993157664614685456:34:0) after 2m56s
[04:49:56.860] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8993157664614685456:34:0)
[04:49:56.903] Build status: STARTED
[04:49:56.903] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8993157664614685456:35:0) after 5m53s
[04:55:50.051] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8993157664614685456:35:0)
[04:55:50.094] Build status: STARTED
[04:55:50.094] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8993157664614685456:36:0) after 1m23s
[04:57:13.116] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8993157664614685456:36:0)
[04:57:13.138] Build status: STARTED
[04:57:13.138] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8993157664614685456:37:0) after 6m24s
[05:03:37.276] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8993157664614685456:37:0)
[05:03:37.300] Build status: STARTED
[05:03:37.300] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8993157664614685456:38:0) after 6m51s
[05:10:28.323] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8993157664614685456:38:0)
[05:10:28.359] Build status: STARTED
[05:10:28.359] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8993157664614685456:39:0) after 1m9s
[05:11:37.432] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8993157664614685456:39:0)
[05:11:37.464] Build status: STARTED
[05:11:37.464] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8993157664614685456:40:0) after 8m47s
[05:20:24.487] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8993157664614685456:40:0)
[05:20:24.508] Build status: STARTED
[05:20:24.508] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8993157664614685456:41:0) after 3m51s
[05:24:15.531] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8993157664614685456:41:0)
[05:24:15.574] Build status: STARTED
[05:24:15.574] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8993157664614685456:42:0) after 9m21s
[05:33:36.630] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8993157664614685456:42:0)
[05:33:36.666] Build status: STARTED
[05:33:36.666] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8993157664614685456:43:0) after 4m48s
[05:38:24.693] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8993157664614685456:43:0)
[05:38:24.727] Build status: STARTED
[05:38:24.727] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8993157664614685456:44:0) after 6m17s
[05:44:41.750] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8993157664614685456:44:0)
[05:44:41.784] Build status: STARTED
[05:44:41.784] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8993157664614685456:45:0) after 6m50s
[05:51:31.818] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8993157664614685456:45:0)
[05:51:31.862] Build status: STARTED
[05:51:31.862] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8993157664614685456:46:0) after 9m30s
[06:01:01.985] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8993157664614685456:46:0)
[06:01:02.006] Build status: STARTED
[06:01:02.006] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8993157664614685456:47:0) after 6m54s
[06:07:56.397] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8993157664614685456:47:0)
[06:07:56.433] Build status: STARTED
[06:07:56.433] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8993157664614685456:48:0) after 8m27s
[06:16:23.456] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8993157664614685456:48:0)
[06:16:23.478] Build status: STARTED
[06:16:23.478] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8993157664614685456:49:0) after 2m29s
[06:18:52.626] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8993157664614685456:49:0)
[06:18:52.660] Build status: STARTED
[06:18:52.660] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8993157664614685456:50:0) after 6m14s
[06:25:06.700] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8993157664614685456:50:0)
[06:25:06.721] Build status: STARTED
[06:25:06.721] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8993157664614685456:51:0) after 1m11s
[06:26:17.773] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8993157664614685456:51:0)
[06:26:17.796] Build status: STARTED
[06:26:17.796] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8993157664614685456:52:0) after 2m53s
[06:29:10.926] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8993157664614685456:52:0)
[06:29:10.945] Build status: STARTED
[06:29:10.945] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8993157664614685456:53:0) after 1m47s
[06:30:57.966] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8993157664614685456:53:0)
[06:30:58.046] Build status: STARTED
[06:30:58.046] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8993157664614685456:54:0) after 2m8s
[06:33:06.107] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8993157664614685456:54:0)
[06:33:06.129] Build status: STARTED
[06:33:06.129] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8993157664614685456:55:0) after 1m3s
[06:34:09.151] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8993157664614685456:55:0)
[06:34:09.184] Build status: STARTED
[06:34:09.184] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8993157664614685456:56:0) after 7m53s
[06:38:01.904] Received PubSub notification, asking Buildbucket for the build status
[06:38:01.996] Build:
{
"id": "8827727602753289233",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "win10-code-coverage"
},
"number": 13079,
"createdBy": "project:chromium",
"createTime": "2021-12-16T01:58:55.231386255Z",
"startTime": "2021-12-16T02:01:02.799939Z",
"endTime": "2021-12-16T06:38:01.268565596Z",
"updateTime": "2021-12-16T06:38:01.268565596Z",
"status": "FAILURE",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "08a1dcc3f8115163d15d56b40c7f3722b47225e6",
"ref": "refs/heads/main"
}
}
}
[06:38:01.996] Invocation finished in 4h39m7.923888144s with status FAILED