[21:29:00.946] New invocation is queued and will start shortly
[21:29:02.363] Starting the invocation (attempt 1)
[21:29:02.401] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/709768ff1ca196cd0352faeb1fbaa968c785054c"
[21:29:02.401] Popped gitiles commit info from properties and tags
[21:29:02.401] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[21:29:02.401] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[21:29:02.401] Buildbucket request:
{
"requestId": "9002143744031237264",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "Windows deterministic"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "709768ff1ca196cd0352faeb1fbaa968c785054c",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "9002143744031237264"
},
{
"key": "scheduler_job_id",
"value": "chromium/Windows deterministic"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[21:29:03.024] Scheduled build:
{
"id": "8836713681758466881",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "Windows deterministic"
},
"number": 19075,
"createdBy": "project:chromium",
"createTime": "2021-09-07T21:29:02.479938649Z",
"updateTime": "2021-09-07T21:29:02.753794068Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "709768ff1ca196cd0352faeb1fbaa968c785054c",
"ref": "refs/heads/main"
}
}
}
[21:29:03.024] Task URL: https://cr-buildbucket.appspot.com/build/8836713681758466881
[21:29:03.024] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:2:0) after 4m7s
[21:33:10.062] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:2:0)
[21:33:10.224] Build status: SCHEDULED
[21:33:10.224] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:3:0) after 4m39s
[21:37:49.275] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:3:0)
[21:37:49.341] Build status: SCHEDULED
[21:37:49.341] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:4:0) after 3m32s
[21:41:02.193] Received PubSub notification, asking Buildbucket for the build status
[21:41:02.232] Build status: STARTED
[21:41:21.378] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:4:0)
[21:41:21.409] Build status: STARTED
[21:41:21.409] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:6:0) after 6m53s
[21:48:14.467] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:6:0)
[21:48:14.529] Build status: STARTED
[21:48:14.529] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:7:0) after 2m37s
[21:50:51.604] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:7:0)
[21:50:51.634] Build status: STARTED
[21:50:51.634] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:8:0) after 2m6s
[21:52:58.192] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:8:0)
[21:52:58.256] Build status: STARTED
[21:52:58.256] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:9:0) after 5m18s
[21:58:16.666] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:9:0)
[21:58:16.696] Build status: STARTED
[21:58:16.696] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:10:0) after 8m27s
[22:06:43.721] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:10:0)
[22:06:43.771] Build status: STARTED
[22:06:43.771] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:11:0) after 5m2s
[22:11:46.288] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:11:0)
[22:11:46.341] Build status: STARTED
[22:11:46.341] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:12:0) after 4m47s
[22:16:33.456] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:12:0)
[22:16:33.503] Build status: STARTED
[22:16:33.503] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:13:0) after 1m25s
[22:17:58.579] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:13:0)
[22:17:58.670] Build status: STARTED
[22:17:58.670] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:14:0) after 6m48s
[22:24:46.830] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:14:0)
[22:24:47.065] Build status: STARTED
[22:24:47.065] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:15:0) after 6m22s
[22:31:09.238] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:15:0)
[22:31:09.293] Build status: STARTED
[22:31:09.293] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:16:0) after 4m17s
[22:35:26.322] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:16:0)
[22:35:26.356] Build status: STARTED
[22:35:26.356] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:17:0) after 8m34s
[22:44:00.381] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:17:0)
[22:44:00.415] Build status: STARTED
[22:44:00.415] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:18:0) after 8m22s
[22:52:22.466] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:18:0)
[22:52:22.498] Build status: STARTED
[22:52:22.498] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:19:0) after 2m17s
[22:54:39.625] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:19:0)
[22:54:39.678] Build status: STARTED
[22:54:39.678] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:20:0) after 4m50s
[22:59:29.716] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:20:0)
[22:59:29.740] Build status: STARTED
[22:59:29.740] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:21:0) after 5m9s
[23:04:38.773] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:21:0)
[23:04:38.817] Build status: STARTED
[23:04:38.817] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:22:0) after 8m11s
[23:12:49.862] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:22:0)
[23:12:49.890] Build status: STARTED
[23:12:49.890] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:23:0) after 2m41s
[23:15:31.012] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:23:0)
[23:15:31.041] Build status: STARTED
[23:15:31.041] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:24:0) after 8m19s
[23:23:50.320] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:24:0)
[23:23:50.356] Build status: STARTED
[23:23:50.356] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:25:0) after 7m47s
[23:31:37.368] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:25:0)
[23:31:37.480] Build status: STARTED
[23:31:37.480] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:26:0) after 7m20s
[23:38:57.582] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:26:0)
[23:38:57.690] Build status: STARTED
[23:38:57.690] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:27:0) after 3m5s
[23:42:02.732] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:27:0)
[23:42:02.781] Build status: STARTED
[23:42:02.781] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:28:0) after 8m58s
[23:51:00.910] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:28:0)
[23:51:00.944] Build status: STARTED
[23:51:00.944] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:29:0) after 7m51s
[23:58:51.995] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:29:0)
[23:58:52.029] Build status: STARTED
[23:58:52.029] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:30:0) after 5m26s
[00:04:18.216] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:30:0)
[00:04:18.259] Build status: STARTED
[00:04:18.259] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:31:0) after 1m55s
[00:06:13.431] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:31:0)
[00:06:13.484] Build status: STARTED
[00:06:13.484] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:32:0) after 5m57s
[00:12:10.515] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:32:0)
[00:12:10.559] Build status: STARTED
[00:12:10.559] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:33:0) after 2m13s
[00:14:23.650] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:33:0)
[00:14:23.684] Build status: STARTED
[00:14:23.684] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:34:0) after 8m13s
[00:22:36.841] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:34:0)
[00:22:36.973] Build status: STARTED
[00:22:36.973] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:35:0) after 7m36s
[00:30:13.005] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:35:0)
[00:30:13.049] Build status: STARTED
[00:30:13.049] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:36:0) after 8m3s
[00:38:16.397] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:36:0)
[00:38:16.449] Build status: STARTED
[00:38:16.449] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:37:0) after 6m43s
[00:44:59.840] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:37:0)
[00:44:59.889] Build status: STARTED
[00:44:59.889] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:38:0) after 9m33s
[00:54:33.299] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:38:0)
[00:54:33.335] Build status: STARTED
[00:54:33.335] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:39:0) after 7m5s
[01:01:38.607] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:39:0)
[01:01:38.695] Build status: STARTED
[01:01:38.695] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:40:0) after 7m17s
[01:08:55.744] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:40:0)
[01:08:55.801] Build status: STARTED
[01:08:55.801] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:41:0) after 8m41s
[01:17:36.828] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:41:0)
[01:17:36.868] Build status: STARTED
[01:17:36.868] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:42:0) after 2m11s
[01:19:47.931] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:42:0)
[01:19:47.964] Build status: STARTED
[01:19:47.964] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:43:0) after 3m1s
[01:22:49.176] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:43:0)
[01:22:49.265] Build status: STARTED
[01:22:49.265] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:44:0) after 1m35s
[01:24:24.308] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:44:0)
[01:24:24.345] Build status: STARTED
[01:24:24.345] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:45:0) after 5m21s
[01:29:45.376] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:45:0)
[01:29:45.405] Build status: STARTED
[01:29:45.405] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:46:0) after 8m27s
[01:38:12.494] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:46:0)
[01:38:12.527] Build status: STARTED
[01:38:12.527] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:47:0) after 6m19s
[01:44:31.510] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:47:0)
[01:44:31.547] Build status: STARTED
[01:44:31.547] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:48:0) after 1m47s
[01:46:18.592] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:48:0)
[01:46:18.634] Build status: STARTED
[01:46:18.634] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:49:0) after 2m1s
[01:48:20.033] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:49:0)
[01:48:20.145] Build status: STARTED
[01:48:20.145] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:50:0) after 7m16s
[01:55:36.123] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:50:0)
[01:55:36.157] Build status: STARTED
[01:55:36.157] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:51:0) after 7m7s
[02:02:43.267] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:51:0)
[02:02:43.310] Build status: STARTED
[02:02:43.310] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:52:0) after 7m56s
[02:10:39.727] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:52:0)
[02:10:39.758] Build status: STARTED
[02:10:39.758] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:53:0) after 5m2s
[02:15:41.866] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:53:0)
[02:15:41.976] Build status: STARTED
[02:15:41.976] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:54:0) after 8m17s
[02:23:59.033] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:54:0)
[02:23:59.072] Build status: STARTED
[02:23:59.072] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:55:0) after 3m32s
[02:27:31.617] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:55:0)
[02:27:31.684] Build status: STARTED
[02:27:31.684] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:56:0) after 3m27s
[02:30:58.790] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:56:0)
[02:30:58.826] Build status: STARTED
[02:30:58.826] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:57:0) after 2m1s
[02:32:59.926] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:57:0)
[02:32:59.969] Build status: STARTED
[02:32:59.970] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:58:0) after 9m59s
[02:42:59.083] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:58:0)
[02:42:59.114] Build status: STARTED
[02:42:59.114] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:59:0) after 3m13s
[02:46:12.143] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:59:0)
[02:46:12.196] Build status: STARTED
[02:46:12.196] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:60:0) after 3m26s
[02:49:38.373] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:60:0)
[02:49:38.422] Build status: STARTED
[02:49:38.422] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:61:0) after 5m40s
[02:55:18.494] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:61:0)
[02:55:18.693] Build status: STARTED
[02:55:18.693] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:62:0) after 7m20s
[03:02:39.030] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:62:0)
[03:02:39.065] Build status: STARTED
[03:02:39.065] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:63:0) after 7m40s
[03:10:19.066] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:63:0)
[03:10:19.140] Build status: STARTED
[03:10:19.140] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:64:0) after 8m21s
[03:18:40.233] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:64:0)
[03:18:40.317] Build status: STARTED
[03:18:40.317] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:65:0) after 1m44s
[03:20:24.562] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:65:0)
[03:20:24.640] Build status: STARTED
[03:20:24.640] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:66:0) after 6m27s
[03:26:51.742] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:66:0)
[03:26:51.774] Build status: STARTED
[03:26:51.774] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:67:0) after 2m4s
[03:28:55.800] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:67:0)
[03:28:55.844] Build status: STARTED
[03:28:55.844] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:68:0) after 3m8s
[03:32:03.943] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:68:0)
[03:32:03.981] Build status: STARTED
[03:32:03.981] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:69:0) after 7m39s
[03:39:43.118] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:69:0)
[03:39:43.181] Build status: STARTED
[03:39:43.181] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:70:0) after 4m39s
[03:44:22.207] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:70:0)
[03:44:22.317] Build status: STARTED
[03:44:22.317] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:71:0) after 1m5s
[03:45:27.361] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:71:0)
[03:45:27.390] Build status: STARTED
[03:45:27.390] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:72:0) after 6m0s
[03:51:27.417] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:72:0)
[03:51:27.456] Build status: STARTED
[03:51:27.456] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:73:0) after 9m41s
[04:01:29.824] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:73:0)
[04:01:29.896] Build status: STARTED
[04:01:29.896] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:74:0) after 8m34s
[04:10:03.980] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:74:0)
[04:10:04.015] Build status: STARTED
[04:10:04.015] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:75:0) after 5m42s
[04:15:46.143] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:75:0)
[04:15:46.282] Build status: STARTED
[04:15:46.282] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:76:0) after 4m22s
[04:20:08.537] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:76:0)
[04:20:08.680] Build status: STARTED
[04:20:08.680] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:77:0) after 4m35s
[04:24:43.816] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:77:0)
[04:24:43.843] Build status: STARTED
[04:24:43.843] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:78:0) after 3m27s
[04:28:10.896] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:78:0)
[04:28:10.929] Build status: STARTED
[04:28:10.929] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:79:0) after 3m46s
[04:31:57.253] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:79:0)
[04:31:57.453] Build status: STARTED
[04:31:57.453] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:80:0) after 7m10s
[04:39:07.489] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:80:0)
[04:39:07.525] Build status: STARTED
[04:39:07.525] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:81:0) after 7m9s
[04:46:16.552] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:81:0)
[04:46:16.600] Build status: STARTED
[04:46:16.600] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:82:0) after 8m21s
[04:54:37.872] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:82:0)
[04:54:37.921] Build status: STARTED
[04:54:37.921] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:83:0) after 3m52s
[04:58:30.037] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:83:0)
[04:58:30.097] Build status: STARTED
[04:58:30.097] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:84:0) after 1m0s
[04:59:30.214] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:84:0)
[04:59:30.243] Build status: STARTED
[04:59:30.243] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:85:0) after 2m25s
[05:01:55.305] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:85:0)
[05:01:55.345] Build status: STARTED
[05:01:55.345] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:86:0) after 9m4s
[05:10:59.458] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:86:0)
[05:10:59.505] Build status: STARTED
[05:10:59.505] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:87:0) after 5m56s
[05:16:55.535] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:87:0)
[05:16:55.604] Build status: STARTED
[05:16:55.604] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:88:0) after 4m32s
[05:21:27.704] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:88:0)
[05:21:27.729] Build status: STARTED
[05:21:27.729] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:89:0) after 1m57s
[05:23:24.789] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:89:0)
[05:23:24.838] Build status: STARTED
[05:23:24.838] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:90:0) after 2m23s
[05:25:47.867] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:90:0)
[05:25:47.907] Build status: STARTED
[05:25:47.907] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:91:0) after 4m23s
[05:30:11.037] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:91:0)
[05:30:11.072] Build status: STARTED
[05:30:11.072] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:92:0) after 2m59s
[05:33:10.136] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:92:0)
[05:33:10.164] Build status: STARTED
[05:33:10.164] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:93:0) after 2m6s
[05:35:16.268] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:93:0)
[05:35:16.296] Build status: STARTED
[05:35:16.296] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:94:0) after 2m6s
[05:37:22.409] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:94:0)
[05:37:22.471] Build status: STARTED
[05:37:22.471] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:95:0) after 9m34s
[05:46:56.579] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:95:0)
[05:46:56.617] Build status: STARTED
[05:46:56.617] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:96:0) after 5m32s
[05:52:28.763] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:96:0)
[05:52:28.794] Build status: STARTED
[05:52:28.794] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:97:0) after 6m18s
[05:58:46.819] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:97:0)
[05:58:46.868] Build status: STARTED
[05:58:46.868] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:98:0) after 3m14s
[06:02:00.928] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:98:0)
[06:02:00.970] Build status: STARTED
[06:02:00.970] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:99:0) after 1m34s
[06:03:35.002] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:99:0)
[06:03:35.030] Build status: STARTED
[06:03:35.030] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:100:0) after 5m13s
[06:08:48.372] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:100:0)
[06:08:48.454] Build status: STARTED
[06:08:48.454] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:101:0) after 6m0s
[06:14:48.582] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:101:0)
[06:14:48.619] Build status: STARTED
[06:14:48.619] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:102:0) after 6m26s
[06:21:14.710] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:102:0)
[06:21:14.739] Build status: STARTED
[06:21:14.739] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:103:0) after 4m56s
[06:26:10.787] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:103:0)
[06:26:10.965] Build status: STARTED
[06:26:10.965] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:104:0) after 7m30s
[06:33:41.178] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:104:0)
[06:33:41.210] Build status: STARTED
[06:33:41.210] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:105:0) after 4m2s
[06:37:43.234] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:105:0)
[06:37:43.267] Build status: STARTED
[06:37:43.267] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:106:0) after 8m19s
[06:46:02.286] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:106:0)
[06:46:02.331] Build status: STARTED
[06:46:02.331] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:107:0) after 9m18s
[06:55:20.439] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:107:0)
[06:55:20.466] Build status: STARTED
[06:55:20.466] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:108:0) after 9m50s
[07:05:10.493] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:108:0)
[07:05:10.535] Build status: STARTED
[07:05:10.535] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:109:0) after 7m46s
[07:12:56.633] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:109:0)
[07:12:56.672] Build status: STARTED
[07:12:56.672] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:110:0) after 9m26s
[07:22:22.807] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:110:0)
[07:22:22.853] Build status: STARTED
[07:22:22.853] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:111:0) after 1m18s
[07:23:40.890] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:111:0)
[07:23:40.944] Build status: STARTED
[07:23:40.944] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:112:0) after 2m9s
[07:25:50.156] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:112:0)
[07:25:50.189] Build status: STARTED
[07:25:50.189] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:113:0) after 1m37s
[07:27:27.235] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:113:0)
[07:27:27.268] Build status: STARTED
[07:27:27.268] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:114:0) after 2m10s
[07:29:37.290] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:114:0)
[07:29:37.359] Build status: STARTED
[07:29:37.359] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:115:0) after 3m9s
[07:32:46.385] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:115:0)
[07:32:46.559] Build status: STARTED
[07:32:46.559] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:116:0) after 9m53s
[07:42:39.590] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:116:0)
[07:42:39.634] Build status: STARTED
[07:42:39.634] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:117:0) after 2m52s
[07:45:31.770] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:117:0)
[07:45:31.862] Build status: STARTED
[07:45:31.862] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:118:0) after 1m55s
[07:47:26.988] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:118:0)
[07:47:27.014] Build status: STARTED
[07:47:27.014] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:119:0) after 7m11s
[07:54:38.055] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:119:0)
[07:54:38.109] Build status: STARTED
[07:54:38.109] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:120:0) after 3m46s
[07:58:24.151] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:120:0)
[07:58:24.202] Build status: STARTED
[07:58:24.202] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:121:0) after 9m9s
[08:07:33.397] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:121:0)
[08:07:33.425] Build status: STARTED
[08:07:33.425] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:122:0) after 6m9s
[08:13:42.741] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:122:0)
[08:13:42.768] Build status: STARTED
[08:13:42.768] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:123:0) after 7m1s
[08:20:43.834] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:123:0)
[08:20:43.865] Build status: STARTED
[08:20:43.865] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:124:0) after 2m4s
[08:22:47.979] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:124:0)
[08:22:48.034] Build status: STARTED
[08:22:48.034] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:125:0) after 2m11s
[08:24:59.194] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:125:0)
[08:24:59.231] Build status: STARTED
[08:24:59.231] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:126:0) after 9m15s
[08:34:14.452] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:126:0)
[08:34:14.487] Build status: STARTED
[08:34:14.487] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:127:0) after 9m33s
[08:43:47.548] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:127:0)
[08:43:47.665] Build status: STARTED
[08:43:47.665] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:128:0) after 4m59s
[08:48:46.940] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:128:0)
[08:48:46.983] Build status: STARTED
[08:48:46.983] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:129:0) after 5m47s
[08:54:34.253] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:129:0)
[08:54:34.295] Build status: STARTED
[08:54:34.295] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:130:0) after 5m2s
[08:59:36.927] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:130:0)
[08:59:36.974] Build status: STARTED
[08:59:36.974] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:131:0) after 3m0s
[09:02:37.012] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:131:0)
[09:02:37.061] Build status: STARTED
[09:02:37.062] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:132:0) after 6m54s
[09:09:31.192] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:132:0)
[09:09:31.226] Build status: STARTED
[09:09:31.226] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:133:0) after 5m9s
[09:14:40.376] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:133:0)
[09:14:40.486] Build status: STARTED
[09:14:40.486] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:134:0) after 3m22s
[09:18:02.771] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:134:0)
[09:18:02.804] Build status: STARTED
[09:18:02.804] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:135:0) after 3m59s
[09:22:01.935] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:135:0)
[09:22:01.975] Build status: STARTED
[09:22:01.975] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:136:0) after 9m3s
[09:31:05.054] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:136:0)
[09:31:05.102] Build status: STARTED
[09:31:05.102] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:137:0) after 4m33s
[09:35:38.171] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:137:0)
[09:35:38.221] Build status: STARTED
[09:35:38.221] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:138:0) after 8m40s
[09:44:18.259] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:138:0)
[09:44:18.347] Build status: STARTED
[09:44:18.347] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:139:0) after 5m2s
[09:49:20.376] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:139:0)
[09:49:20.439] Build status: STARTED
[09:49:20.439] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:140:0) after 7m33s
[09:56:53.484] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:140:0)
[09:56:53.514] Build status: STARTED
[09:56:53.514] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9002143744031237264:141:0) after 7m50s
[10:04:30.826] Received PubSub notification, asking Buildbucket for the build status
[10:04:30.869] Build:
{
"id": "8836713681758466881",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "Windows deterministic"
},
"number": 19075,
"createdBy": "project:chromium",
"createTime": "2021-09-07T21:29:02.479938649Z",
"startTime": "2021-09-07T21:41:00.960314Z",
"endTime": "2021-09-08T10:04:28.102012Z",
"updateTime": "2021-09-08T10:04:30.087705Z",
"status": "INFRA_FAILURE",
"statusDetails": {
"timeout": {
}
},
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "709768ff1ca196cd0352faeb1fbaa968c785054c",
"ref": "refs/heads/main"
}
}
}
[10:04:30.869] Invocation finished in 12h35m29.941135663s with status FAILED