[17:00:50.382] New invocation is queued and will start shortly
[17:00:51.486] Starting the invocation (attempt 1)
[17:00:51.525] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/ce9b3a6fbecb836fa1f4813b308a530cd55cfe36"
[17:00:51.525] Popped gitiles commit info from properties and tags
[17:00:51.525] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[17:00:51.526] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[17:00:51.526] Buildbucket request:
{
"requestId": "8966556008407334944",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "Win 10 Fast Ring"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "ce9b3a6fbecb836fa1f4813b308a530cd55cfe36",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8966556008407334944"
},
{
"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"
}
}
[17:00:52.169] Scheduled build:
{
"id": "8801125946459203745",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "Win 10 Fast Ring"
},
"number": 11887,
"createdBy": "project:chromium",
"createTime": "2022-10-05T17:00:51.613375655Z",
"updateTime": "2022-10-05T17:00:51.613375655Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "ce9b3a6fbecb836fa1f4813b308a530cd55cfe36",
"ref": "refs/heads/main"
}
}
}
[17:00:52.169] Task URL: https://cr-buildbucket.appspot.com/build/8801125946459203745
[17:00:52.169] Scheduling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8966556008407334944:2:0) after 8m23s
[17:09:15.226] Handling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8966556008407334944:2:0)
[17:09:15.249] Build status: SCHEDULED
[17:09:15.249] Scheduling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8966556008407334944:3:0) after 2m14s
[17:11:29.686] Handling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8966556008407334944:3:0)
[17:11:29.736] Build status: SCHEDULED
[17:11:29.736] Scheduling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8966556008407334944:4:0) after 8m1s
[17:12:03.640] Received PubSub notification, asking Buildbucket for the build status
[17:12:03.666] Build status: STARTED
[17:19:30.852] Handling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8966556008407334944:4:0)
[17:19:30.873] Build status: STARTED
[17:19:30.874] Scheduling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8966556008407334944:6:0) after 1m57s
[17:21:27.985] Handling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8966556008407334944:6:0)
[17:21:28.078] Build status: STARTED
[17:21:28.078] Scheduling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8966556008407334944:7:0) after 8m17s
[17:29:45.973] Handling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8966556008407334944:7:0)
[17:29:45.998] Build status: STARTED
[17:29:45.998] Scheduling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8966556008407334944:8:0) after 1m23s
[17:31:09.078] Handling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8966556008407334944:8:0)
[17:31:09.116] Build status: STARTED
[17:31:09.116] Scheduling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8966556008407334944:9:0) after 1m11s
[17:32:20.220] Handling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8966556008407334944:9:0)
[17:32:20.385] Build status: STARTED
[17:32:20.385] Scheduling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8966556008407334944:10:0) after 2m53s
[17:35:13.670] Handling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8966556008407334944:10:0)
[17:35:13.725] Build status: STARTED
[17:35:13.725] Scheduling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8966556008407334944:11:0) after 5m40s
[17:40:53.731] Handling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8966556008407334944:11:0)
[17:40:53.765] Build status: STARTED
[17:40:53.765] Scheduling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8966556008407334944:12:0) after 1m0s
[17:41:54.032] Handling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8966556008407334944:12:0)
[17:41:54.059] Build status: STARTED
[17:41:54.059] Scheduling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8966556008407334944:13:0) after 1m30s
[17:43:24.161] Handling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8966556008407334944:13:0)
[17:43:24.196] Build status: STARTED
[17:43:24.196] Scheduling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8966556008407334944:14:0) after 8m11s
[17:51:35.339] Handling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8966556008407334944:14:0)
[17:51:35.449] Build status: STARTED
[17:51:35.449] Scheduling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8966556008407334944:15:0) after 9m8s
[18:00:43.741] Handling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8966556008407334944:15:0)
[18:00:43.774] Build status: STARTED
[18:00:43.774] Scheduling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8966556008407334944:16:0) after 7m44s
[18:08:28.069] Handling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8966556008407334944:16:0)
[18:08:28.103] Build status: STARTED
[18:08:28.103] Scheduling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8966556008407334944:17:0) after 6m34s
[18:15:02.139] Handling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8966556008407334944:17:0)
[18:15:02.168] Build status: STARTED
[18:15:02.168] Scheduling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8966556008407334944:18:0) after 1m58s
[18:17:00.537] Handling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8966556008407334944:18:0)
[18:17:00.620] Build status: STARTED
[18:17:00.620] Scheduling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8966556008407334944:19:0) after 3m23s
[18:20:23.711] Handling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8966556008407334944:19:0)
[18:20:23.740] Build status: STARTED
[18:20:23.740] Scheduling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8966556008407334944:20:0) after 3m39s
[18:24:02.802] Handling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8966556008407334944:20:0)
[18:24:02.837] Build status: STARTED
[18:24:02.837] Scheduling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8966556008407334944:21:0) after 6m0s
[18:30:03.072] Handling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8966556008407334944:21:0)
[18:30:03.115] Build status: STARTED
[18:30:03.115] Scheduling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8966556008407334944:22:0) after 7m38s
[18:37:41.274] Handling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8966556008407334944:22:0)
[18:37:41.296] Build status: STARTED
[18:37:41.296] Scheduling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8966556008407334944:23:0) after 8m12s
[18:45:53.398] Handling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8966556008407334944:23:0)
[18:45:53.423] Build status: STARTED
[18:45:53.423] Scheduling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8966556008407334944:24:0) after 4m28s
[18:50:21.479] Handling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8966556008407334944:24:0)
[18:50:21.533] Build status: STARTED
[18:50:21.533] Scheduling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8966556008407334944:25:0) after 1m39s
[18:52:00.559] Handling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8966556008407334944:25:0)
[18:52:00.586] Build status: STARTED
[18:52:00.586] Scheduling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8966556008407334944:26:0) after 3m9s
[18:55:09.912] Handling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8966556008407334944:26:0)
[18:55:09.936] Build status: STARTED
[18:55:09.936] Scheduling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8966556008407334944:27:0) after 2m53s
[18:58:03.145] Handling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8966556008407334944:27:0)
[18:58:03.293] Build status: STARTED
[18:58:03.293] Scheduling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8966556008407334944:28:0) after 3m48s
[19:01:51.322] Handling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8966556008407334944:28:0)
[19:01:51.348] Build status: STARTED
[19:01:51.348] Scheduling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8966556008407334944:29:0) after 5m28s
[19:07:19.382] Handling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8966556008407334944:29:0)
[19:07:19.422] Build status: STARTED
[19:07:19.423] Scheduling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8966556008407334944:30:0) after 5m26s
[19:12:45.519] Handling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8966556008407334944:30:0)
[19:12:45.547] Build status: STARTED
[19:12:45.547] Scheduling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8966556008407334944:31:0) after 7m36s
[19:20:21.871] Handling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8966556008407334944:31:0)
[19:20:21.896] Build status: STARTED
[19:20:21.896] Scheduling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8966556008407334944:32:0) after 2m45s
[19:23:07.170] Handling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8966556008407334944:32:0)
[19:23:07.197] Build status: STARTED
[19:23:07.197] Scheduling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8966556008407334944:33:0) after 3m45s
[19:26:52.244] Handling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8966556008407334944:33:0)
[19:26:52.269] Build status: STARTED
[19:26:52.269] Scheduling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8966556008407334944:34:0) after 2m25s
[19:29:17.353] Handling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8966556008407334944:34:0)
[19:29:17.388] Build status: STARTED
[19:29:17.389] Scheduling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8966556008407334944:35:0) after 9m20s
[19:38:37.553] Handling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8966556008407334944:35:0)
[19:38:37.580] Build status: STARTED
[19:38:37.580] Scheduling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8966556008407334944:36:0) after 3m9s
[19:41:46.624] Handling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8966556008407334944:36:0)
[19:41:46.649] Build status: STARTED
[19:41:46.649] Scheduling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8966556008407334944:37:0) after 5m14s
[19:47:00.739] Handling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8966556008407334944:37:0)
[19:47:00.919] Build status: STARTED
[19:47:00.920] Scheduling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8966556008407334944:38:0) after 9m23s
[19:56:23.966] Handling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8966556008407334944:38:0)
[19:56:24.000] Build status: STARTED
[19:56:24.000] Scheduling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8966556008407334944:39:0) after 1m21s
[19:57:45.058] Handling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8966556008407334944:39:0)
[19:57:45.086] Build status: STARTED
[19:57:45.086] Scheduling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8966556008407334944:40:0) after 2m35s
[20:00:20.218] Handling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8966556008407334944:40:0)
[20:00:20.374] Build status: STARTED
[20:00:20.374] Scheduling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8966556008407334944:41:0) after 2m2s
[20:02:22.411] Handling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8966556008407334944:41:0)
[20:02:22.474] Build status: STARTED
[20:02:22.474] Scheduling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8966556008407334944:42:0) after 2m7s
[20:04:29.509] Handling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8966556008407334944:42:0)
[20:04:29.664] Build status: STARTED
[20:04:29.664] Scheduling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8966556008407334944:43:0) after 2m13s
[20:06:43.144] Handling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8966556008407334944:43:0)
[20:06:43.177] Build status: STARTED
[20:06:43.177] Scheduling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8966556008407334944:44:0) after 2m13s
[20:08:56.339] Handling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8966556008407334944:44:0)
[20:08:56.367] Build status: STARTED
[20:08:56.367] Scheduling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8966556008407334944:45:0) after 2m21s
[20:11:17.418] Handling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8966556008407334944:45:0)
[20:11:17.444] Build status: STARTED
[20:11:17.444] Scheduling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8966556008407334944:46:0) after 6m39s
[20:17:56.481] Handling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8966556008407334944:46:0)
[20:17:56.506] Build status: STARTED
[20:17:56.506] Scheduling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8966556008407334944:47:0) after 1m39s
[20:19:35.536] Handling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8966556008407334944:47:0)
[20:19:35.558] Build status: STARTED
[20:19:35.558] Scheduling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8966556008407334944:48:0) after 6m54s
[20:26:29.585] Handling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8966556008407334944:48:0)
[20:26:29.606] Build status: STARTED
[20:26:29.606] Scheduling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8966556008407334944:49:0) after 4m5s
[20:30:34.779] Handling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8966556008407334944:49:0)
[20:30:34.804] Build status: STARTED
[20:30:34.804] Scheduling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8966556008407334944:50:0) after 8m18s
[20:38:53.016] Handling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8966556008407334944:50:0)
[20:38:53.051] Build status: STARTED
[20:38:53.051] Scheduling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8966556008407334944:51:0) after 6m0s
[20:44:53.087] Handling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8966556008407334944:51:0)
[20:44:53.112] Build status: STARTED
[20:44:53.112] Scheduling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8966556008407334944:52:0) after 5m48s
[20:50:41.163] Handling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8966556008407334944:52:0)
[20:50:41.191] Build status: STARTED
[20:50:41.191] Scheduling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8966556008407334944:53:0) after 3m36s
[20:54:17.208] Handling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8966556008407334944:53:0)
[20:54:17.238] Build status: STARTED
[20:54:17.239] Scheduling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8966556008407334944:54:0) after 5m13s
[20:59:30.280] Handling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8966556008407334944:54:0)
[20:59:30.304] Build status: STARTED
[20:59:30.304] Scheduling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8966556008407334944:55:0) after 9m7s
[21:08:37.319] Handling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8966556008407334944:55:0)
[21:08:37.422] Build status: STARTED
[21:08:37.422] Scheduling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8966556008407334944:56:0) after 7m8s
[21:15:45.737] Handling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8966556008407334944:56:0)
[21:15:45.799] Build status: STARTED
[21:15:45.799] Scheduling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8966556008407334944:57:0) after 9m1s
[21:24:46.932] Handling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8966556008407334944:57:0)
[21:24:47.198] Build status: STARTED
[21:24:47.198] Scheduling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8966556008407334944:58:0) after 4m16s
[21:29:03.238] Handling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8966556008407334944:58:0)
[21:29:03.264] Build status: STARTED
[21:29:03.264] Scheduling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8966556008407334944:59:0) after 1m53s
[21:30:56.372] Handling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8966556008407334944:59:0)
[21:30:56.411] Build status: STARTED
[21:30:56.411] Scheduling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8966556008407334944:60:0) after 2m51s
[21:33:47.440] Handling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8966556008407334944:60:0)
[21:33:47.465] Build status: STARTED
[21:33:47.465] Scheduling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8966556008407334944:61:0) after 6m12s
[21:39:59.572] Handling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8966556008407334944:61:0)
[21:39:59.602] Build status: STARTED
[21:39:59.602] Scheduling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8966556008407334944:62:0) after 3m32s
[21:43:31.728] Handling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8966556008407334944:62:0)
[21:43:31.750] Build status: STARTED
[21:43:31.750] Scheduling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8966556008407334944:63:0) after 3m58s
[21:47:29.778] Handling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8966556008407334944:63:0)
[21:47:29.801] Build status: STARTED
[21:47:29.801] Scheduling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8966556008407334944:64:0) after 7m52s
[21:55:22.338] Handling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8966556008407334944:64:0)
[21:55:22.372] Build status: STARTED
[21:55:22.372] Scheduling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8966556008407334944:65:0) after 8m58s
[22:04:20.418] Handling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8966556008407334944:65:0)
[22:04:20.466] Build status: STARTED
[22:04:20.466] Scheduling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8966556008407334944:66:0) after 6m20s
[22:10:40.496] Handling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8966556008407334944:66:0)
[22:10:40.536] Build status: STARTED
[22:10:40.536] Scheduling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8966556008407334944:67:0) after 2m17s
[22:12:57.561] Handling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8966556008407334944:67:0)
[22:12:57.595] Build status: STARTED
[22:12:57.595] Scheduling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8966556008407334944:68:0) after 6m24s
[22:19:21.632] Handling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8966556008407334944:68:0)
[22:19:21.658] Build status: STARTED
[22:19:21.658] Scheduling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8966556008407334944:69:0) after 1m41s
[22:21:02.686] Handling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8966556008407334944:69:0)
[22:21:02.708] Build status: STARTED
[22:21:02.708] Scheduling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8966556008407334944:70:0) after 1m38s
[22:22:40.745] Handling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8966556008407334944:70:0)
[22:22:40.783] Build status: STARTED
[22:22:40.783] Scheduling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8966556008407334944:71:0) after 8m35s
[22:31:15.959] Handling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8966556008407334944:71:0)
[22:31:15.989] Build status: STARTED
[22:31:15.989] Scheduling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8966556008407334944:72:0) after 6m27s
[22:37:43.224] Handling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8966556008407334944:72:0)
[22:37:43.248] Build status: STARTED
[22:37:43.248] Scheduling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8966556008407334944:73:0) after 1m16s
[22:38:59.297] Handling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8966556008407334944:73:0)
[22:38:59.322] Build status: STARTED
[22:38:59.322] Scheduling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8966556008407334944:74:0) after 7m48s
[22:46:47.443] Handling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8966556008407334944:74:0)
[22:46:47.467] Build status: STARTED
[22:46:47.467] Scheduling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8966556008407334944:75:0) after 9m50s
[22:56:37.679] Handling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8966556008407334944:75:0)
[22:56:37.704] Build status: STARTED
[22:56:37.704] Scheduling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8966556008407334944:76:0) after 9m48s
[23:06:25.730] Handling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8966556008407334944:76:0)
[23:06:25.752] Build status: STARTED
[23:06:25.752] Scheduling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8966556008407334944:77:0) after 8m59s
[23:15:25.226] Handling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8966556008407334944:77:0)
[23:15:25.256] Build status: STARTED
[23:15:25.256] Scheduling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8966556008407334944:78:0) after 9m34s
[23:24:59.291] Handling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8966556008407334944:78:0)
[23:24:59.322] Build status: STARTED
[23:24:59.322] Scheduling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8966556008407334944:79:0) after 3m38s
[23:28:37.431] Handling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8966556008407334944:79:0)
[23:28:37.468] Build status: STARTED
[23:28:37.468] Scheduling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8966556008407334944:80:0) after 8m58s
[23:37:36.401] Handling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8966556008407334944:80:0)
[23:37:36.433] Build status: STARTED
[23:37:36.433] Scheduling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8966556008407334944:81:0) after 5m26s
[23:43:02.961] Handling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8966556008407334944:81:0)
[23:43:03.059] Build status: STARTED
[23:43:03.059] Scheduling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8966556008407334944:82:0) after 1m56s
[23:44:59.196] Handling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8966556008407334944:82:0)
[23:44:59.236] Build status: STARTED
[23:44:59.237] Scheduling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8966556008407334944:83:0) after 2m30s
[23:47:29.275] Handling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8966556008407334944:83:0)
[23:47:29.300] Build status: STARTED
[23:47:29.300] Scheduling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8966556008407334944:84:0) after 1m42s
[23:49:11.831] Handling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8966556008407334944:84:0)
[23:49:11.859] Build status: STARTED
[23:49:11.859] Scheduling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8966556008407334944:85:0) after 4m29s
[23:53:41.158] Handling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8966556008407334944:85:0)
[23:53:41.183] Build status: STARTED
[23:53:41.183] Scheduling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8966556008407334944:86:0) after 9m51s
[00:03:32.429] Handling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8966556008407334944:86:0)
[00:03:32.453] Build status: STARTED
[00:03:32.453] Scheduling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8966556008407334944:87:0) after 9m32s
[00:13:04.637] Handling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8966556008407334944:87:0)
[00:13:04.663] Build status: STARTED
[00:13:04.663] Scheduling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8966556008407334944:88:0) after 3m11s
[00:16:15.692] Handling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8966556008407334944:88:0)
[00:16:15.716] Build status: STARTED
[00:16:15.716] Scheduling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8966556008407334944:89:0) after 1m4s
[00:17:19.907] Handling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8966556008407334944:89:0)
[00:17:19.928] Build status: STARTED
[00:17:19.928] Scheduling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8966556008407334944:90:0) after 9m49s
[00:27:09.154] Handling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8966556008407334944:90:0)
[00:27:09.182] Build status: STARTED
[00:27:09.182] Scheduling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8966556008407334944:91:0) after 5m44s
[00:32:53.588] Handling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8966556008407334944:91:0)
[00:32:53.624] Build status: STARTED
[00:32:53.624] Scheduling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8966556008407334944:92:0) after 1m18s
[00:34:11.705] Handling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8966556008407334944:92:0)
[00:34:11.742] Build status: STARTED
[00:34:11.742] Scheduling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8966556008407334944:93:0) after 7m57s
[00:42:08.859] Handling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8966556008407334944:93:0)
[00:42:08.920] Build status: STARTED
[00:42:08.920] Scheduling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8966556008407334944:94:0) after 8m17s
[00:50:25.977] Handling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8966556008407334944:94:0)
[00:50:26.004] Build status: STARTED
[00:50:26.004] Scheduling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8966556008407334944:95:0) after 4m31s
[00:54:57.155] Handling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8966556008407334944:95:0)
[00:54:57.182] Build status: STARTED
[00:54:57.182] Scheduling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8966556008407334944:96:0) after 2m11s
[00:57:08.207] Handling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8966556008407334944:96:0)
[00:57:08.245] Build status: STARTED
[00:57:08.245] Scheduling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8966556008407334944:97:0) after 9m26s
[01:06:34.380] Handling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8966556008407334944:97:0)
[01:06:34.409] Build status: STARTED
[01:06:34.409] Scheduling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8966556008407334944:98:0) after 2m38s
[01:09:12.618] Handling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8966556008407334944:98:0)
[01:09:12.661] Build status: STARTED
[01:09:12.661] Scheduling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8966556008407334944:99:0) after 5m43s
[01:14:55.647] Handling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8966556008407334944:99:0)
[01:14:55.667] Build status: STARTED
[01:14:55.667] Scheduling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8966556008407334944:100:0) after 7m5s
[01:22:01.168] Handling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8966556008407334944:100:0)
[01:22:01.194] Build status: STARTED
[01:22:01.194] Scheduling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8966556008407334944:101:0) after 3m9s
[01:25:10.322] Handling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8966556008407334944:101:0)
[01:25:10.360] Build status: STARTED
[01:25:10.360] Scheduling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8966556008407334944:102:0) after 1m49s
[01:26:59.529] Handling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8966556008407334944:102:0)
[01:26:59.553] Build status: STARTED
[01:26:59.553] Scheduling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8966556008407334944:103:0) after 7m44s
[01:34:43.591] Handling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8966556008407334944:103:0)
[01:34:43.616] Build status: STARTED
[01:34:43.617] Scheduling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8966556008407334944:104:0) after 3m11s
[01:37:54.816] Handling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8966556008407334944:104:0)
[01:37:54.843] Build status: STARTED
[01:37:54.843] Scheduling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8966556008407334944:105:0) after 8m22s
[01:46:16.875] Handling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8966556008407334944:105:0)
[01:46:16.900] Build status: STARTED
[01:46:16.900] Scheduling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8966556008407334944:106:0) after 6m50s
[01:53:06.950] Handling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8966556008407334944:106:0)
[01:53:06.981] Build status: STARTED
[01:53:06.981] Scheduling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8966556008407334944:107:0) after 4m22s
[01:57:29.177] Handling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8966556008407334944:107:0)
[01:57:29.200] Build status: STARTED
[01:57:29.200] Scheduling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8966556008407334944:108:0) after 2m47s
[02:00:16.333] Handling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8966556008407334944:108:0)
[02:00:16.359] Build status: STARTED
[02:00:16.359] Scheduling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8966556008407334944:109:0) after 3m28s
[02:03:44.404] Handling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8966556008407334944:109:0)
[02:03:44.498] Build status: STARTED
[02:03:44.498] Scheduling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8966556008407334944:110:0) after 7m28s
[02:11:12.741] Handling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8966556008407334944:110:0)
[02:11:12.797] Build status: STARTED
[02:11:12.797] Scheduling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8966556008407334944:111:0) after 4m42s
[02:15:54.928] Handling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8966556008407334944:111:0)
[02:15:54.956] Build status: STARTED
[02:15:54.956] Scheduling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8966556008407334944:112:0) after 9m41s
[02:25:36.199] Handling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8966556008407334944:112:0)
[02:25:36.223] Build status: STARTED
[02:25:36.223] Scheduling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8966556008407334944:113:0) after 9m54s
[02:35:30.255] Handling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8966556008407334944:113:0)
[02:35:30.282] Build status: STARTED
[02:35:30.282] Scheduling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8966556008407334944:114:0) after 1m10s
[02:36:40.507] Handling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8966556008407334944:114:0)
[02:36:40.534] Build status: STARTED
[02:36:40.534] Scheduling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8966556008407334944:115:0) after 6m16s
[02:42:56.564] Handling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8966556008407334944:115:0)
[02:42:56.588] Build status: STARTED
[02:42:56.588] Scheduling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8966556008407334944:116:0) after 7m26s
[02:50:22.669] Handling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8966556008407334944:116:0)
[02:50:22.693] Build status: STARTED
[02:50:22.693] Scheduling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8966556008407334944:117:0) after 2m26s
[02:52:48.759] Handling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8966556008407334944:117:0)
[02:52:48.786] Build status: STARTED
[02:52:48.786] Scheduling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8966556008407334944:118:0) after 3m57s
[02:56:45.823] Handling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8966556008407334944:118:0)
[02:56:45.851] Build status: STARTED
[02:56:45.851] Scheduling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8966556008407334944:119:0) after 2m11s
[02:58:57.114] Handling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8966556008407334944:119:0)
[02:58:57.140] Build status: STARTED
[02:58:57.140] Scheduling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8966556008407334944:120:0) after 7m11s
[03:06:08.229] Handling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8966556008407334944:120:0)
[03:06:08.303] Build status: STARTED
[03:06:08.304] Scheduling timer "check-buildbucket-build-status" (chromium/Win 10 Fast Ring:8966556008407334944:121:0) after 9m29s
[03:12:01.414] Received PubSub notification, asking Buildbucket for the build status
[03:12:01.445] Build:
{
"id": "8801125946459203745",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "Win 10 Fast Ring"
},
"number": 11887,
"createdBy": "project:chromium",
"createTime": "2022-10-05T17:00:51.613375655Z",
"startTime": "2022-10-05T17:11:39.279558Z",
"endTime": "2022-10-06T03:11:59.109321162Z",
"updateTime": "2022-10-06T03:11:59.109321162Z",
"status": "CANCELED",
"statusDetails": {
"timeout": {
}
},
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "ce9b3a6fbecb836fa1f4813b308a530cd55cfe36",
"ref": "refs/heads/main"
}
}
}
[03:12:01.445] Invocation finished in 10h11m11.073893151s with status ABORTED