[08:43:55.508] New invocation is queued and will start shortly
[08:43:56.844] Starting the invocation (attempt 1)
[08:43:56.885] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/2ed2602e39405f39593524db73596341f25b822f"
[08:43:56.885] Popped gitiles commit info from properties and tags
[08:43:56.885] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[08:43:56.885] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[08:43:56.885] Buildbucket request:
{
"requestId": "8962782198983445728",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "Win 10 Fast Ring"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "2ed2602e39405f39593524db73596341f25b822f",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8962782198983445728"
},
{
"key": "scheduler_job_id",
"value": "chromium/Win 10 Fast Ring"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[08:43:57.339] Scheduled build:
{
"id": "8797352136797070753",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "Win 10 Fast Ring"
},
"number": 11987,
"createdBy": "project:chromium",
"createTime": "2022-11-16T08:43:56.962906225Z",
"updateTime": "2022-11-16T08:43:56.962906225Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "2ed2602e39405f39593524db73596341f25b822f",
"ref": "refs/heads/main"
}
}
}
[08:43:57.339] Task URL: https://cr-buildbucket.appspot.com/build/8797352136797070753
[08:43:57.339] Scheduling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8962782198983445728:2:0) after 6m18s
[08:50:15.369] Handling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8962782198983445728:2:0)
[08:50:15.394] Build status: SCHEDULED
[08:50:15.394] Scheduling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8962782198983445728:3:0) after 5m20s
[08:50:33.380] Received PubSub notification, asking Buildbucket for the build status
[08:50:33.426] Build status: STARTED
[08:55:35.660] Handling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8962782198983445728:3:0)
[08:55:35.696] Build status: STARTED
[08:55:35.696] Scheduling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8962782198983445728:5:0) after 1m49s
[08:57:24.742] Handling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8962782198983445728:5:0)
[08:57:24.790] Build status: STARTED
[08:57:24.790] Scheduling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8962782198983445728:6:0) after 5m4s
[09:02:28.879] Handling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8962782198983445728:6:0)
[09:02:28.904] Build status: STARTED
[09:02:28.904] Scheduling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8962782198983445728:7:0) after 3m11s
[09:05:40.043] Handling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8962782198983445728:7:0)
[09:05:40.090] Build status: STARTED
[09:05:40.090] Scheduling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8962782198983445728:8:0) after 1m6s
[09:06:46.228] Handling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8962782198983445728:8:0)
[09:06:46.251] Build status: STARTED
[09:06:46.251] Scheduling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8962782198983445728:9:0) after 7m28s
[09:14:14.339] Handling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8962782198983445728:9:0)
[09:14:14.362] Build status: STARTED
[09:14:14.362] Scheduling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8962782198983445728:10:0) after 8m23s
[09:22:37.470] Handling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8962782198983445728:10:0)
[09:22:37.499] Build status: STARTED
[09:22:37.499] Scheduling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8962782198983445728:11:0) after 5m27s
[09:28:04.624] Handling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8962782198983445728:11:0)
[09:28:04.648] Build status: STARTED
[09:28:04.648] Scheduling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8962782198983445728:12:0) after 1m49s
[09:29:53.838] Handling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8962782198983445728:12:0)
[09:29:53.863] Build status: STARTED
[09:29:53.863] Scheduling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8962782198983445728:13:0) after 6m0s
[09:35:53.910] Handling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8962782198983445728:13:0)
[09:35:53.936] Build status: STARTED
[09:35:53.936] Scheduling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8962782198983445728:14:0) after 8m5s
[09:43:59.008] Handling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8962782198983445728:14:0)
[09:43:59.029] Build status: STARTED
[09:43:59.029] Scheduling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8962782198983445728:15:0) after 9m15s
[09:53:14.105] Handling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8962782198983445728:15:0)
[09:53:14.128] Build status: STARTED
[09:53:14.128] Scheduling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8962782198983445728:16:0) after 9m11s
[10:02:25.276] Handling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8962782198983445728:16:0)
[10:02:25.414] Build status: STARTED
[10:02:25.414] Scheduling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8962782198983445728:17:0) after 6m38s
[10:09:03.475] Handling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8962782198983445728:17:0)
[10:09:03.501] Build status: STARTED
[10:09:03.501] Scheduling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8962782198983445728:18:0) after 3m6s
[10:12:09.524] Handling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8962782198983445728:18:0)
[10:12:09.552] Build status: STARTED
[10:12:09.552] Scheduling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8962782198983445728:19:0) after 1m2s
[10:13:11.588] Handling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8962782198983445728:19:0)
[10:13:11.612] Build status: STARTED
[10:13:11.612] Scheduling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8962782198983445728:20:0) after 6m13s
[10:19:24.769] Handling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8962782198983445728:20:0)
[10:19:24.788] Build status: STARTED
[10:19:24.788] Scheduling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8962782198983445728:21:0) after 8m43s
[10:28:07.875] Handling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8962782198983445728:21:0)
[10:28:08.050] Build status: STARTED
[10:28:08.050] Scheduling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8962782198983445728:22:0) after 3m20s
[10:31:28.076] Handling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8962782198983445728:22:0)
[10:31:28.120] Build status: STARTED
[10:31:28.120] Scheduling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8962782198983445728:23:0) after 1m24s
[10:32:52.147] Handling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8962782198983445728:23:0)
[10:32:52.177] Build status: STARTED
[10:32:52.177] Scheduling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8962782198983445728:24:0) after 3m11s
[10:36:03.419] Handling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8962782198983445728:24:0)
[10:36:03.446] Build status: STARTED
[10:36:03.446] Scheduling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8962782198983445728:25:0) after 8m59s
[10:45:02.481] Handling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8962782198983445728:25:0)
[10:45:02.540] Build status: STARTED
[10:45:02.540] Scheduling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8962782198983445728:26:0) after 4m40s
[10:49:42.783] Handling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8962782198983445728:26:0)
[10:49:42.832] Build status: STARTED
[10:49:42.832] Scheduling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8962782198983445728:27:0) after 6m27s
[10:56:09.952] Handling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8962782198983445728:27:0)
[10:56:09.994] Build status: STARTED
[10:56:09.994] Scheduling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8962782198983445728:28:0) after 8m18s
[11:04:28.184] Handling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8962782198983445728:28:0)
[11:04:28.204] Build status: STARTED
[11:04:28.204] Scheduling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8962782198983445728:29:0) after 9m39s
[11:14:07.233] Handling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8962782198983445728:29:0)
[11:14:07.266] Build status: STARTED
[11:14:07.266] Scheduling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8962782198983445728:30:0) after 7m44s
[11:21:51.300] Handling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8962782198983445728:30:0)
[11:21:51.324] Build status: STARTED
[11:21:51.324] Scheduling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8962782198983445728:31:0) after 2m47s
[11:24:38.578] Handling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8962782198983445728:31:0)
[11:24:38.606] Build status: STARTED
[11:24:38.606] Scheduling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8962782198983445728:32:0) after 2m39s
[11:27:17.737] Handling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8962782198983445728:32:0)
[11:27:17.827] Build status: STARTED
[11:27:17.827] Scheduling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8962782198983445728:33:0) after 3m28s
[11:30:45.994] Handling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8962782198983445728:33:0)
[11:30:46.017] Build status: STARTED
[11:30:46.017] Scheduling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8962782198983445728:34:0) after 6m41s
[11:37:27.160] Handling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8962782198983445728:34:0)
[11:37:27.183] Build status: STARTED
[11:37:27.183] Scheduling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8962782198983445728:35:0) after 5m5s
[11:42:32.215] Handling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8962782198983445728:35:0)
[11:42:32.242] Build status: STARTED
[11:42:32.242] Scheduling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8962782198983445728:36:0) after 5m31s
[11:48:03.370] Handling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8962782198983445728:36:0)
[11:48:03.447] Build status: STARTED
[11:48:03.447] Scheduling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8962782198983445728:37:0) after 4m13s
[11:52:16.700] Handling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8962782198983445728:37:0)
[11:52:16.725] Build status: STARTED
[11:52:16.725] Scheduling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8962782198983445728:38:0) after 2m4s
[11:54:20.931] Handling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8962782198983445728:38:0)
[11:54:20.956] Build status: STARTED
[11:54:20.956] Scheduling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8962782198983445728:39:0) after 2m50s
[11:57:11.128] Handling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8962782198983445728:39:0)
[11:57:11.173] Build status: STARTED
[11:57:11.173] Scheduling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8962782198983445728:40:0) after 6m51s
[12:04:02.177] Handling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8962782198983445728:40:0)
[12:04:02.206] Build status: STARTED
[12:04:02.206] Scheduling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8962782198983445728:41:0) after 8m1s
[12:12:03.240] Handling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8962782198983445728:41:0)
[12:12:03.262] Build status: STARTED
[12:12:03.262] Scheduling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8962782198983445728:42:0) after 7m52s
[12:19:55.293] Handling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8962782198983445728:42:0)
[12:19:55.314] Build status: STARTED
[12:19:55.314] Scheduling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8962782198983445728:43:0) after 3m45s
[12:23:40.351] Handling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8962782198983445728:43:0)
[12:23:40.374] Build status: STARTED
[12:23:40.374] Scheduling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8962782198983445728:44:0) after 9m11s
[12:32:51.404] Handling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8962782198983445728:44:0)
[12:32:51.462] Build status: STARTED
[12:32:51.462] Scheduling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8962782198983445728:45:0) after 9m36s
[12:42:27.614] Handling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8962782198983445728:45:0)
[12:42:27.637] Build status: STARTED
[12:42:27.637] Scheduling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8962782198983445728:46:0) after 8m59s
[12:51:26.663] Handling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8962782198983445728:46:0)
[12:51:26.685] Build status: STARTED
[12:51:26.685] Scheduling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8962782198983445728:47:0) after 1m23s
[12:52:49.867] Handling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8962782198983445728:47:0)
[12:52:49.891] Build status: STARTED
[12:52:49.891] Scheduling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8962782198983445728:48:0) after 5m28s
[12:58:18.036] Handling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8962782198983445728:48:0)
[12:58:18.143] Build status: STARTED
[12:58:18.143] Scheduling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8962782198983445728:49:0) after 6m16s
[13:04:34.273] Handling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8962782198983445728:49:0)
[13:04:34.467] Build status: STARTED
[13:04:34.467] Scheduling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8962782198983445728:50:0) after 8m1s
[13:12:35.511] Handling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8962782198983445728:50:0)
[13:12:35.534] Build status: STARTED
[13:12:35.534] Scheduling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8962782198983445728:51:0) after 4m38s
[13:17:13.784] Handling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8962782198983445728:51:0)
[13:17:13.806] Build status: STARTED
[13:17:13.806] Scheduling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8962782198983445728:52:0) after 4m37s
[13:21:51.441] Handling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8962782198983445728:52:0)
[13:21:51.472] Build status: STARTED
[13:21:51.472] Scheduling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8962782198983445728:53:0) after 7m1s
[13:28:52.497] Handling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8962782198983445728:53:0)
[13:28:52.554] Build status: STARTED
[13:28:52.554] Scheduling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8962782198983445728:54:0) after 6m29s
[13:35:21.579] Handling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8962782198983445728:54:0)
[13:35:21.602] Build status: STARTED
[13:35:21.602] Scheduling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8962782198983445728:55:0) after 1m25s
[13:36:46.673] Handling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8962782198983445728:55:0)
[13:36:46.698] Build status: STARTED
[13:36:46.698] Scheduling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8962782198983445728:56:0) after 8m28s
[13:45:14.894] Handling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8962782198983445728:56:0)
[13:45:14.931] Build status: STARTED
[13:45:14.931] Scheduling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8962782198983445728:57:0) after 3m23s
[13:48:38.249] Handling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8962782198983445728:57:0)
[13:48:38.272] Build status: STARTED
[13:48:38.272] Scheduling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8962782198983445728:58:0) after 8m52s
[13:57:30.335] Handling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8962782198983445728:58:0)
[13:57:30.354] Build status: STARTED
[13:57:30.354] Scheduling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8962782198983445728:59:0) after 6m13s
[14:03:43.744] Handling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8962782198983445728:59:0)
[14:03:43.772] Build status: STARTED
[14:03:43.772] Scheduling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8962782198983445728:60:0) after 3m40s
[14:07:23.804] Handling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8962782198983445728:60:0)
[14:07:23.851] Build status: STARTED
[14:07:23.851] Scheduling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8962782198983445728:61:0) after 6m16s
[14:13:40.057] Handling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8962782198983445728:61:0)
[14:13:40.079] Build status: STARTED
[14:13:40.079] Scheduling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8962782198983445728:62:0) after 7m51s
[14:21:31.166] Handling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8962782198983445728:62:0)
[14:21:31.189] Build status: STARTED
[14:21:31.189] Scheduling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8962782198983445728:63:0) after 6m57s
[14:28:28.214] Handling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8962782198983445728:63:0)
[14:28:28.242] Build status: STARTED
[14:28:28.242] Scheduling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8962782198983445728:64:0) after 5m44s
[14:34:12.335] Handling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8962782198983445728:64:0)
[14:34:12.375] Build status: STARTED
[14:34:12.375] Scheduling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8962782198983445728:65:0) after 9m15s
[14:43:27.453] Handling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8962782198983445728:65:0)
[14:43:27.477] Build status: STARTED
[14:43:27.477] Scheduling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8962782198983445728:66:0) after 1m50s
[14:45:17.621] Handling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8962782198983445728:66:0)
[14:45:17.662] Build status: STARTED
[14:45:17.662] Scheduling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8962782198983445728:67:0) after 8m47s
[14:54:04.827] Handling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8962782198983445728:67:0)
[14:54:04.855] Build status: STARTED
[14:54:04.855] Scheduling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8962782198983445728:68:0) after 9m33s
[15:03:38.156] Handling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8962782198983445728:68:0)
[15:03:38.195] Build status: STARTED
[15:03:38.195] Scheduling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8962782198983445728:69:0) after 2m2s
[15:05:40.294] Handling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8962782198983445728:69:0)
[15:05:40.333] Build status: STARTED
[15:05:40.333] Scheduling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8962782198983445728:70:0) after 1m53s
[15:07:33.529] Handling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8962782198983445728:70:0)
[15:07:33.557] Build status: STARTED
[15:07:33.557] Scheduling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8962782198983445728:71:0) after 9m33s
[15:17:06.663] Handling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8962782198983445728:71:0)
[15:17:06.684] Build status: STARTED
[15:17:06.684] Scheduling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8962782198983445728:72:0) after 1m20s
[15:18:26.717] Handling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8962782198983445728:72:0)
[15:18:26.740] Build status: STARTED
[15:18:26.740] Scheduling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8962782198983445728:73:0) after 1m15s
[15:19:41.961] Handling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8962782198983445728:73:0)
[15:19:42.008] Build status: STARTED
[15:19:42.008] Scheduling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8962782198983445728:74:0) after 9m52s
[15:29:34.038] Handling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8962782198983445728:74:0)
[15:29:34.061] Build status: STARTED
[15:29:34.061] Scheduling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8962782198983445728:75:0) after 2m2s
[15:31:36.359] Handling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8962782198983445728:75:0)
[15:31:36.437] Build status: STARTED
[15:31:36.437] Scheduling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8962782198983445728:76:0) after 6m49s
[15:38:25.640] Handling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8962782198983445728:76:0)
[15:38:25.669] Build status: STARTED
[15:38:25.669] Scheduling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8962782198983445728:77:0) after 9m26s
[15:47:51.863] Handling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8962782198983445728:77:0)
[15:47:51.977] Build status: STARTED
[15:47:51.977] Scheduling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8962782198983445728:78:0) after 5m52s
[15:53:44.088] Handling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8962782198983445728:78:0)
[15:53:44.119] Build status: STARTED
[15:53:44.119] Scheduling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8962782198983445728:79:0) after 3m11s
[15:56:55.261] Handling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8962782198983445728:79:0)
[15:56:55.282] Build status: STARTED
[15:56:55.282] Scheduling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8962782198983445728:80:0) after 4m24s
[16:01:19.414] Handling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8962782198983445728:80:0)
[16:01:19.444] Build status: STARTED
[16:01:19.444] Scheduling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8962782198983445728:81:0) after 7m56s
[16:09:15.634] Handling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8962782198983445728:81:0)
[16:09:15.667] Build status: STARTED
[16:09:15.667] Scheduling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8962782198983445728:82:0) after 5m47s
[16:15:02.905] Handling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8962782198983445728:82:0)
[16:15:02.931] Build status: STARTED
[16:15:02.931] Scheduling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8962782198983445728:83:0) after 9m39s
[16:24:42.437] Handling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8962782198983445728:83:0)
[16:24:42.459] Build status: STARTED
[16:24:42.460] Scheduling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8962782198983445728:84:0) after 4m28s
[16:29:10.719] Handling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8962782198983445728:84:0)
[16:29:10.749] Build status: STARTED
[16:29:10.749] Scheduling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8962782198983445728:85:0) after 5m0s
[16:34:10.948] Handling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8962782198983445728:85:0)
[16:34:10.973] Build status: STARTED
[16:34:10.973] Scheduling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8962782198983445728:86:0) after 4m48s
[16:38:59.082] Handling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8962782198983445728:86:0)
[16:38:59.104] Build status: STARTED
[16:38:59.104] Scheduling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8962782198983445728:87:0) after 9m57s
[16:48:56.425] Handling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8962782198983445728:87:0)
[16:48:56.446] Build status: STARTED
[16:48:56.446] Scheduling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8962782198983445728:88:0) after 7m31s
[16:56:27.880] Handling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8962782198983445728:88:0)
[16:56:27.899] Build status: STARTED
[16:56:27.899] Scheduling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8962782198983445728:89:0) after 8m4s
[17:04:32.228] Handling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8962782198983445728:89:0)
[17:04:32.270] Build status: STARTED
[17:04:32.270] Scheduling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8962782198983445728:90:0) after 6m37s
[17:11:09.547] Handling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8962782198983445728:90:0)
[17:11:09.574] Build status: STARTED
[17:11:09.574] Scheduling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8962782198983445728:91:0) after 6m53s
[17:18:02.659] Handling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8962782198983445728:91:0)
[17:18:02.679] Build status: STARTED
[17:18:02.679] Scheduling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8962782198983445728:92:0) after 7m17s
[17:25:19.765] Handling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8962782198983445728:92:0)
[17:25:19.817] Build status: STARTED
[17:25:19.817] Scheduling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8962782198983445728:93:0) after 4m46s
[17:30:06.216] Handling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8962782198983445728:93:0)
[17:30:06.247] Build status: STARTED
[17:30:06.247] Scheduling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8962782198983445728:94:0) after 1m33s
[17:31:39.429] Handling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8962782198983445728:94:0)
[17:31:39.453] Build status: STARTED
[17:31:39.453] Scheduling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8962782198983445728:95:0) after 3m7s
[17:34:46.625] Handling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8962782198983445728:95:0)
[17:34:46.646] Build status: STARTED
[17:34:46.646] Scheduling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8962782198983445728:96:0) after 5m3s
[17:39:49.998] Handling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8962782198983445728:96:0)
[17:39:50.033] Build status: STARTED
[17:39:50.033] Scheduling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8962782198983445728:97:0) after 2m51s
[17:42:41.364] Handling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8962782198983445728:97:0)
[17:42:41.531] Build status: STARTED
[17:42:41.531] Scheduling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8962782198983445728:98:0) after 1m51s
[17:44:32.556] Handling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8962782198983445728:98:0)
[17:44:32.577] Build status: STARTED
[17:44:32.577] Scheduling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8962782198983445728:99:0) after 3m56s
[17:48:29.108] Handling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8962782198983445728:99:0)
[17:48:29.130] Build status: STARTED
[17:48:29.130] Scheduling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8962782198983445728:100:0) after 8m55s
[17:57:24.335] Handling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8962782198983445728:100:0)
[17:57:24.364] Build status: STARTED
[17:57:24.364] Scheduling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8962782198983445728:101:0) after 3m25s
[18:00:49.423] Handling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8962782198983445728:101:0)
[18:00:49.448] Build status: STARTED
[18:00:49.448] Scheduling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8962782198983445728:102:0) after 2m35s
[18:03:24.795] Handling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8962782198983445728:102:0)
[18:03:24.835] Build status: STARTED
[18:03:24.835] Scheduling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8962782198983445728:103:0) after 9m51s
[18:13:15.959] Handling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8962782198983445728:103:0)
[18:13:15.983] Build status: STARTED
[18:13:15.983] Scheduling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8962782198983445728:104:0) after 2m54s
[18:16:10.009] Handling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8962782198983445728:104:0)
[18:16:10.044] Build status: STARTED
[18:16:10.044] Scheduling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8962782198983445728:105:0) after 1m56s
[18:18:06.130] Handling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8962782198983445728:105:0)
[18:18:06.159] Build status: STARTED
[18:18:06.159] Scheduling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8962782198983445728:106:0) after 6m31s
[18:24:37.439] Handling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8962782198983445728:106:0)
[18:24:37.472] Build status: STARTED
[18:24:37.472] Scheduling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8962782198983445728:107:0) after 2m30s
[18:27:07.479] Handling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8962782198983445728:107:0)
[18:27:07.502] Build status: STARTED
[18:27:07.502] Scheduling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8962782198983445728:108:0) after 9m37s
[18:36:45.113] Handling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8962782198983445728:108:0)
[18:36:45.137] Build status: STARTED
[18:36:45.137] Scheduling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8962782198983445728:109:0) after 3m34s
[18:40:19.540] Handling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8962782198983445728:109:0)
[18:40:19.616] Build status: STARTED
[18:40:19.616] Scheduling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8962782198983445728:110:0) after 7m12s
[18:47:31.752] Handling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8962782198983445728:110:0)
[18:47:31.771] Build status: STARTED
[18:47:31.771] Scheduling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8962782198983445728:111:0) after 4m51s
[18:50:58.429] Received PubSub notification, asking Buildbucket for the build status
[18:50:58.452] Build:
{
"id": "8797352136797070753",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "Win 10 Fast Ring"
},
"number": 11987,
"createdBy": "project:chromium",
"createTime": "2022-11-16T08:43:56.962906225Z",
"startTime": "2022-11-16T08:50:32.772400Z",
"endTime": "2022-11-16T18:50:58.014596770Z",
"updateTime": "2022-11-16T18:50:58.014596770Z",
"status": "CANCELED",
"statusDetails": {
"timeout": {
}
},
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "2ed2602e39405f39593524db73596341f25b822f",
"ref": "refs/heads/main"
}
}
}
[18:50:58.452] Invocation finished in 10h7m2.957420818s with status ABORTED