[04:00:10.366] New invocation is queued and will start shortly
[04:00:11.566] Starting the invocation (attempt 1)
[04:00:11.594] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/7ac13c14af27065e9af4d1560cad39be17eddb87"
[04:00:11.594] Popped gitiles commit info from properties and tags
[04:00:11.594] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[04:00:11.594] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[04:00:11.594] Buildbucket request:
{
"requestId": "8926833055477256400",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "win10-code-coverage"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "7ac13c14af27065e9af4d1560cad39be17eddb87",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8926833055477256400"
},
{
"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"
}
}
[04:00:12.277] Scheduled build:
{
"id": "8761402993452702913",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "win10-code-coverage"
},
"number": 16281,
"createdBy": "project:chromium",
"createTime": "2023-12-18T04:00:11.667506818Z",
"updateTime": "2023-12-18T04:00:11.667506818Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "7ac13c14af27065e9af4d1560cad39be17eddb87",
"ref": "refs/heads/main"
}
}
}
[04:00:12.277] Task URL: https://cr-buildbucket.appspot.com/build/8761402993452702913
[04:00:12.277] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8926833055477256400:2:0) after 2m7s
[04:00:12.791] Received PubSub notification, asking Buildbucket for the build status
[04:00:12.858] Build status: SCHEDULED
[04:00:21.885] Received PubSub notification, asking Buildbucket for the build status
[04:00:22.033] Build status: STARTED
[04:02:19.668] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8926833055477256400:2:0)
[04:02:19.685] Build status: STARTED
[04:02:19.685] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8926833055477256400:5:0) after 6m41s
[04:09:00.706] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8926833055477256400:5:0)
[04:09:00.743] Build status: STARTED
[04:09:00.743] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8926833055477256400:6:0) after 6m8s
[04:15:08.813] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8926833055477256400:6:0)
[04:15:08.896] Build status: STARTED
[04:15:08.896] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8926833055477256400:7:0) after 5m6s
[04:20:14.938] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8926833055477256400:7:0)
[04:20:14.954] Build status: STARTED
[04:20:14.954] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8926833055477256400:8:0) after 8m34s
[04:28:48.976] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8926833055477256400:8:0)
[04:28:49.009] Build status: STARTED
[04:28:49.009] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8926833055477256400:9:0) after 9m53s
[04:38:42.036] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8926833055477256400:9:0)
[04:38:42.056] Build status: STARTED
[04:38:42.056] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8926833055477256400:10:0) after 1m39s
[04:40:21.176] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8926833055477256400:10:0)
[04:40:21.228] Build status: STARTED
[04:40:21.228] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8926833055477256400:11:0) after 6m9s
[04:46:30.257] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8926833055477256400:11:0)
[04:46:30.275] Build status: STARTED
[04:46:30.275] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8926833055477256400:12:0) after 6m36s
[04:53:06.292] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8926833055477256400:12:0)
[04:53:06.318] Build status: STARTED
[04:53:06.318] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8926833055477256400:13:0) after 6m6s
[04:59:12.504] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8926833055477256400:13:0)
[04:59:12.524] Build status: STARTED
[04:59:12.524] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8926833055477256400:14:0) after 7m24s
[05:06:38.004] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8926833055477256400:14:0)
[05:06:38.027] Build status: STARTED
[05:06:38.027] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8926833055477256400:15:0) after 2m42s
[05:09:20.051] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8926833055477256400:15:0)
[05:09:20.094] Build status: STARTED
[05:09:20.094] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8926833055477256400:16:0) after 5m33s
[05:14:53.112] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8926833055477256400:16:0)
[05:14:53.129] Build status: STARTED
[05:14:53.129] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8926833055477256400:17:0) after 5m31s
[05:20:24.149] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8926833055477256400:17:0)
[05:20:24.206] Build status: STARTED
[05:20:24.206] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8926833055477256400:18:0) after 3m32s
[05:23:56.235] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8926833055477256400:18:0)
[05:23:56.255] Build status: STARTED
[05:23:56.255] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8926833055477256400:19:0) after 4m56s
[05:28:52.430] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8926833055477256400:19:0)
[05:28:52.449] Build status: STARTED
[05:28:52.449] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8926833055477256400:20:0) after 4m13s
[05:33:05.768] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8926833055477256400:20:0)
[05:33:05.866] Build status: STARTED
[05:33:05.866] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8926833055477256400:21:0) after 3m41s
[05:36:47.015] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8926833055477256400:21:0)
[05:36:47.041] Build status: STARTED
[05:36:47.041] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8926833055477256400:22:0) after 2m6s
[05:38:53.057] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8926833055477256400:22:0)
[05:38:53.076] Build status: STARTED
[05:38:53.076] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8926833055477256400:23:0) after 5m9s
[05:44:02.206] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8926833055477256400:23:0)
[05:44:02.241] Build status: STARTED
[05:44:02.241] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8926833055477256400:24:0) after 6m3s
[05:50:05.271] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8926833055477256400:24:0)
[05:50:05.296] Build status: STARTED
[05:50:05.296] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8926833055477256400:25:0) after 4m22s
[05:54:27.959] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8926833055477256400:25:0)
[05:54:27.981] Build status: STARTED
[05:54:27.981] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8926833055477256400:26:0) after 3m16s
[05:57:44.001] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8926833055477256400:26:0)
[05:57:44.020] Build status: STARTED
[05:57:44.020] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8926833055477256400:27:0) after 4m18s
[06:02:02.057] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8926833055477256400:27:0)
[06:02:02.163] Build status: STARTED
[06:02:02.163] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8926833055477256400:28:0) after 3m10s
[06:05:12.229] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8926833055477256400:28:0)
[06:05:12.295] Build status: STARTED
[06:05:12.295] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8926833055477256400:29:0) after 3m50s
[06:09:02.311] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8926833055477256400:29:0)
[06:09:02.335] Build status: STARTED
[06:09:02.335] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8926833055477256400:30:0) after 5m24s
[06:14:26.355] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8926833055477256400:30:0)
[06:14:26.374] Build status: STARTED
[06:14:26.374] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8926833055477256400:31:0) after 3m50s
[06:18:16.403] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8926833055477256400:31:0)
[06:18:16.422] Build status: STARTED
[06:18:16.422] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8926833055477256400:32:0) after 2m29s
[06:20:46.070] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8926833055477256400:32:0)
[06:20:46.092] Build status: STARTED
[06:20:46.092] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8926833055477256400:33:0) after 4m21s
[06:25:07.110] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8926833055477256400:33:0)
[06:25:07.168] Build status: STARTED
[06:25:07.168] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8926833055477256400:34:0) after 6m5s
[06:31:12.490] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8926833055477256400:34:0)
[06:31:12.523] Build status: STARTED
[06:31:12.523] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8926833055477256400:35:0) after 1m37s
[06:32:49.747] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8926833055477256400:35:0)
[06:32:49.768] Build status: STARTED
[06:32:49.768] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8926833055477256400:36:0) after 7m15s
[06:40:04.913] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8926833055477256400:36:0)
[06:40:05.213] Build status: STARTED
[06:40:05.213] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8926833055477256400:37:0) after 9m27s
[06:49:32.291] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8926833055477256400:37:0)
[06:49:32.307] Build status: STARTED
[06:49:32.307] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8926833055477256400:38:0) after 5m16s
[06:54:48.299] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8926833055477256400:38:0)
[06:54:48.318] Build status: STARTED
[06:54:48.318] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8926833055477256400:39:0) after 8m45s
[07:03:33.342] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8926833055477256400:39:0)
[07:03:33.468] Build status: STARTED
[07:03:33.468] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8926833055477256400:40:0) after 1m41s
[07:05:14.517] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8926833055477256400:40:0)
[07:05:14.555] Build status: STARTED
[07:05:14.555] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8926833055477256400:41:0) after 1m49s
[07:07:03.666] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8926833055477256400:41:0)
[07:07:03.686] Build status: STARTED
[07:07:03.686] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8926833055477256400:42:0) after 9m46s
[07:16:49.670] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8926833055477256400:42:0)
[07:16:49.689] Build status: STARTED
[07:16:49.689] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8926833055477256400:43:0) after 9m37s
[07:26:26.709] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8926833055477256400:43:0)
[07:26:26.730] Build status: STARTED
[07:26:26.730] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8926833055477256400:44:0) after 8m29s
[07:34:55.751] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8926833055477256400:44:0)
[07:34:55.802] Build status: STARTED
[07:34:55.803] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8926833055477256400:45:0) after 3m5s
[07:38:00.991] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8926833055477256400:45:0)
[07:38:01.017] Build status: STARTED
[07:38:01.017] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8926833055477256400:46:0) after 2m35s
[07:40:36.146] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8926833055477256400:46:0)
[07:40:36.170] Build status: STARTED
[07:40:36.170] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8926833055477256400:47:0) after 1m39s
[07:42:15.175] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8926833055477256400:47:0)
[07:42:15.194] Build status: STARTED
[07:42:15.194] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8926833055477256400:48:0) after 6m38s
[07:48:53.280] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8926833055477256400:48:0)
[07:48:53.416] Build status: STARTED
[07:48:53.416] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8926833055477256400:49:0) after 3m48s
[07:52:41.558] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8926833055477256400:49:0)
[07:52:41.582] Build status: STARTED
[07:52:41.582] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8926833055477256400:50:0) after 2m57s
[07:55:38.651] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8926833055477256400:50:0)
[07:55:38.674] Build status: STARTED
[07:55:38.675] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8926833055477256400:51:0) after 4m1s
[07:59:39.697] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8926833055477256400:51:0)
[07:59:39.722] Build status: STARTED
[07:59:39.722] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8926833055477256400:52:0) after 4m32s
[08:04:11.844] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8926833055477256400:52:0)
[08:04:11.864] Build status: STARTED
[08:04:11.864] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8926833055477256400:53:0) after 5m6s
[08:09:17.908] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8926833055477256400:53:0)
[08:09:17.965] Build status: STARTED
[08:09:17.965] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8926833055477256400:54:0) after 8m46s
[08:18:04.044] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8926833055477256400:54:0)
[08:18:04.064] Build status: STARTED
[08:18:04.064] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8926833055477256400:55:0) after 8m43s
[08:26:47.081] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8926833055477256400:55:0)
[08:26:47.098] Build status: STARTED
[08:26:47.098] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8926833055477256400:56:0) after 9m39s
[08:36:26.118] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8926833055477256400:56:0)
[08:36:26.133] Build status: STARTED
[08:36:26.133] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8926833055477256400:57:0) after 9m48s
[08:46:14.162] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8926833055477256400:57:0)
[08:46:14.184] Build status: STARTED
[08:46:14.184] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8926833055477256400:58:0) after 9m48s
[08:56:02.301] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8926833055477256400:58:0)
[08:56:02.329] Build status: STARTED
[08:56:02.329] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8926833055477256400:59:0) after 8m10s
[09:04:12.412] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8926833055477256400:59:0)
[09:04:12.435] Build status: STARTED
[09:04:12.435] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8926833055477256400:60:0) after 5m18s
[09:09:30.452] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8926833055477256400:60:0)
[09:09:30.473] Build status: STARTED
[09:09:30.473] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8926833055477256400:61:0) after 4m41s
[09:14:11.497] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8926833055477256400:61:0)
[09:14:11.516] Build status: STARTED
[09:14:11.516] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8926833055477256400:62:0) after 1m43s
[09:15:54.543] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8926833055477256400:62:0)
[09:15:54.576] Build status: STARTED
[09:15:54.576] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8926833055477256400:63:0) after 2m46s
[09:18:40.555] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8926833055477256400:63:0)
[09:18:40.573] Build status: STARTED
[09:18:40.574] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8926833055477256400:64:0) after 2m0s
[09:20:40.647] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8926833055477256400:64:0)
[09:20:40.662] Build status: STARTED
[09:20:40.662] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8926833055477256400:65:0) after 1m13s
[09:21:53.692] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8926833055477256400:65:0)
[09:21:53.710] Build status: STARTED
[09:21:53.710] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8926833055477256400:66:0) after 7m54s
[09:29:48.015] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8926833055477256400:66:0)
[09:29:48.043] Build status: STARTED
[09:29:48.043] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8926833055477256400:67:0) after 1m38s
[09:31:26.059] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8926833055477256400:67:0)
[09:31:26.080] Build status: STARTED
[09:31:26.080] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8926833055477256400:68:0) after 4m14s
[09:35:40.255] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8926833055477256400:68:0)
[09:35:40.280] Build status: STARTED
[09:35:40.280] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8926833055477256400:69:0) after 6m59s
[09:42:39.399] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8926833055477256400:69:0)
[09:42:39.419] Build status: STARTED
[09:42:39.419] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8926833055477256400:70:0) after 5m14s
[09:47:53.446] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8926833055477256400:70:0)
[09:47:53.465] Build status: STARTED
[09:47:53.465] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8926833055477256400:71:0) after 9m5s
[09:56:58.577] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8926833055477256400:71:0)
[09:56:58.599] Build status: STARTED
[09:56:58.599] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8926833055477256400:72:0) after 7m8s
[10:04:06.849] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8926833055477256400:72:0)
[10:04:06.888] Build status: STARTED
[10:04:06.888] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8926833055477256400:73:0) after 1m21s
[10:05:27.999] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8926833055477256400:73:0)
[10:05:28.026] Build status: STARTED
[10:05:28.026] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8926833055477256400:74:0) after 9m28s
[10:14:56.154] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8926833055477256400:74:0)
[10:14:56.178] Build status: STARTED
[10:14:56.178] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8926833055477256400:75:0) after 4m58s
[10:19:54.231] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8926833055477256400:75:0)
[10:19:54.257] Build status: STARTED
[10:19:54.257] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8926833055477256400:76:0) after 4m25s
[10:24:19.258] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8926833055477256400:76:0)
[10:24:19.278] Build status: STARTED
[10:24:19.278] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8926833055477256400:77:0) after 8m43s
[10:33:02.304] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8926833055477256400:77:0)
[10:33:02.321] Build status: STARTED
[10:33:02.321] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8926833055477256400:78:0) after 5m24s
[10:38:26.319] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8926833055477256400:78:0)
[10:38:26.334] Build status: STARTED
[10:38:26.334] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8926833055477256400:79:0) after 4m29s
[10:42:55.435] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8926833055477256400:79:0)
[10:42:55.475] Build status: STARTED
[10:42:55.475] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8926833055477256400:80:0) after 8m11s
[10:51:06.505] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8926833055477256400:80:0)
[10:51:06.522] Build status: STARTED
[10:51:06.522] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8926833055477256400:81:0) after 5m28s
[10:56:34.544] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8926833055477256400:81:0)
[10:56:34.561] Build status: STARTED
[10:56:34.561] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8926833055477256400:82:0) after 3m2s
[10:59:36.727] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8926833055477256400:82:0)
[10:59:36.748] Build status: STARTED
[10:59:36.748] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8926833055477256400:83:0) after 1m57s
[11:01:33.770] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8926833055477256400:83:0)
[11:01:33.789] Build status: STARTED
[11:01:33.789] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8926833055477256400:84:0) after 9m53s
[11:11:26.852] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8926833055477256400:84:0)
[11:11:26.893] Build status: STARTED
[11:11:26.893] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8926833055477256400:85:0) after 3m34s
[11:15:00.989] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8926833055477256400:85:0)
[11:15:01.006] Build status: STARTED
[11:15:01.006] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8926833055477256400:86:0) after 4m19s
[11:19:20.028] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8926833055477256400:86:0)
[11:19:20.051] Build status: STARTED
[11:19:20.051] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8926833055477256400:87:0) after 7m9s
[11:26:29.083] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8926833055477256400:87:0)
[11:26:29.144] Build status: STARTED
[11:26:29.144] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8926833055477256400:88:0) after 9m32s
[11:36:01.312] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8926833055477256400:88:0)
[11:36:01.333] Build status: STARTED
[11:36:01.333] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8926833055477256400:89:0) after 2m34s
[11:38:35.355] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8926833055477256400:89:0)
[11:38:35.375] Build status: STARTED
[11:38:35.375] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8926833055477256400:90:0) after 1m49s
[11:40:24.469] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8926833055477256400:90:0)
[11:40:24.497] Build status: STARTED
[11:40:24.497] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8926833055477256400:91:0) after 6m41s
[11:47:05.661] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8926833055477256400:91:0)
[11:47:05.684] Build status: STARTED
[11:47:05.684] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8926833055477256400:92:0) after 3m30s
[11:50:35.715] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8926833055477256400:92:0)
[11:50:35.734] Build status: STARTED
[11:50:35.734] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8926833055477256400:93:0) after 3m15s
[11:53:50.842] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8926833055477256400:93:0)
[11:53:50.863] Build status: STARTED
[11:53:50.863] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8926833055477256400:94:0) after 3m44s
[11:57:34.887] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8926833055477256400:94:0)
[11:57:34.912] Build status: STARTED
[11:57:34.912] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8926833055477256400:95:0) after 3m54s
[12:01:29.028] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8926833055477256400:95:0)
[12:01:29.082] Build status: STARTED
[12:01:29.082] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8926833055477256400:96:0) after 2m27s
[12:03:56.110] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8926833055477256400:96:0)
[12:03:56.143] Build status: STARTED
[12:03:56.143] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8926833055477256400:97:0) after 7m37s
[12:11:33.367] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8926833055477256400:97:0)
[12:11:33.383] Build status: STARTED
[12:11:33.383] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8926833055477256400:98:0) after 5m19s
[12:16:52.497] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8926833055477256400:98:0)
[12:16:52.520] Build status: STARTED
[12:16:52.520] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8926833055477256400:99:0) after 9m38s
[12:26:30.563] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8926833055477256400:99:0)
[12:26:30.613] Build status: STARTED
[12:26:30.614] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8926833055477256400:100:0) after 1m59s
[12:28:29.645] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8926833055477256400:100:0)
[12:28:29.693] Build status: STARTED
[12:28:29.693] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8926833055477256400:101:0) after 1m59s
[12:30:28.833] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8926833055477256400:101:0)
[12:30:28.949] Build status: STARTED
[12:30:28.949] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8926833055477256400:102:0) after 8m46s
[12:39:15.011] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8926833055477256400:102:0)
[12:39:15.032] Build status: STARTED
[12:39:15.032] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8926833055477256400:103:0) after 9m14s
[12:48:29.094] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8926833055477256400:103:0)
[12:48:29.124] Build status: STARTED
[12:48:29.124] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8926833055477256400:104:0) after 5m50s
[12:54:19.200] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8926833055477256400:104:0)
[12:54:19.220] Build status: STARTED
[12:54:19.220] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8926833055477256400:105:0) after 8m39s
[13:02:58.340] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8926833055477256400:105:0)
[13:02:58.402] Build status: STARTED
[13:02:58.402] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8926833055477256400:106:0) after 5m22s
[13:08:20.428] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8926833055477256400:106:0)
[13:08:20.458] Build status: STARTED
[13:08:20.458] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8926833055477256400:107:0) after 9m27s
[13:17:47.441] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8926833055477256400:107:0)
[13:17:47.502] Build status: STARTED
[13:17:47.502] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8926833055477256400:108:0) after 6m14s
[13:24:01.535] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8926833055477256400:108:0)
[13:24:01.561] Build status: STARTED
[13:24:01.561] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8926833055477256400:109:0) after 6m28s
[13:30:29.868] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8926833055477256400:109:0)
[13:30:29.886] Build status: STARTED
[13:30:29.886] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8926833055477256400:110:0) after 8m1s
[13:38:31.030] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8926833055477256400:110:0)
[13:38:31.049] Build status: STARTED
[13:38:31.049] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8926833055477256400:111:0) after 1m22s
[13:39:53.067] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8926833055477256400:111:0)
[13:39:53.150] Build status: STARTED
[13:39:53.150] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8926833055477256400:112:0) after 8m50s
[13:48:43.469] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8926833055477256400:112:0)
[13:48:43.637] Build status: STARTED
[13:48:43.637] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8926833055477256400:113:0) after 5m53s
[13:54:36.655] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8926833055477256400:113:0)
[13:54:36.689] Build status: STARTED
[13:54:36.689] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8926833055477256400:114:0) after 4m8s
[13:58:44.818] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8926833055477256400:114:0)
[13:58:44.917] Build status: STARTED
[13:58:44.917] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8926833055477256400:115:0) after 6m1s
[14:04:45.952] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8926833055477256400:115:0)
[14:04:45.979] Build status: STARTED
[14:04:45.979] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8926833055477256400:116:0) after 6m16s
[14:11:02.015] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8926833055477256400:116:0)
[14:11:02.047] Build status: STARTED
[14:11:02.048] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8926833055477256400:117:0) after 7m26s
[14:18:28.101] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8926833055477256400:117:0)
[14:18:28.126] Build status: STARTED
[14:18:28.126] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8926833055477256400:118:0) after 6m46s
[14:25:14.165] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8926833055477256400:118:0)
[14:25:14.190] Build status: STARTED
[14:25:14.190] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8926833055477256400:119:0) after 2m12s
[14:27:26.316] Handling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8926833055477256400:119:0)
[14:27:26.343] Build status: STARTED
[14:27:26.343] Scheduling timer "check-buildbucket-build-status" (chromium/win10-code-coverage:8926833055477256400:120:0) after 9m24s
[14:30:34.892] Received PubSub notification, asking Buildbucket for the build status
[14:30:34.962] Build:
{
"id": "8761402993452702913",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "win10-code-coverage"
},
"number": 16281,
"createdBy": "project:chromium",
"createTime": "2023-12-18T04:00:11.667506818Z",
"startTime": "2023-12-18T04:00:20.510859Z",
"endTime": "2023-12-18T14:30:18.922519Z",
"updateTime": "2023-12-18T14:30:18.922519Z",
"status": "INFRA_FAILURE",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "7ac13c14af27065e9af4d1560cad39be17eddb87",
"ref": "refs/heads/main"
}
}
}
[14:30:34.962] Invocation finished in 10h30m24.608604145s with status FAILED