[02:57:34.467] New invocation is queued and will start shortly
[02:57:35.693] Starting the invocation (attempt 1)
[02:57:35.731] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[02:57:35.731] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[02:57:35.731] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9023050972143255616",
"parameters_json": "{\"builder_name\":\"win-official\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"17801e45e6fead9859bb0f1748afd7efd3e76899\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-official",
"scheduler_invocation_id:9023050972143255616",
"scheduler_job_id:chromium/win-official",
"user_agent:luci-scheduler",
"buildset:commit/git/17801e45e6fead9859bb0f1748afd7efd3e76899",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/17801e45e6fead9859bb0f1748afd7efd3e76899",
"gitiles_ref:refs/heads/master"
]
}
[02:57:36.569] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1611111455810311",
"id": "8857620910070722464",
"parameters_json": "{\"builder_name\": \"win-official\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"17801e45e6fead9859bb0f1748afd7efd3e76899\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1611111456441270",
"tags": [
"build_address:luci.chromium.ci/win-official/1419",
"builder:win-official",
"buildset:commit/git/17801e45e6fead9859bb0f1748afd7efd3e76899",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/17801e45e6fead9859bb0f1748afd7efd3e76899",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9023050972143255616",
"scheduler_job_id:chromium/win-official",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8857620910070722464/+/annotations",
"swarming_tag:luci_project:chromium",
"swarming_tag:recipe_name:chromium",
"swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build",
"swarming_task_id:",
"user_agent:luci-scheduler"
],
"updated_ts": "1611111456441389",
"url": "https://ci.chromium.org/b/8857620910070722464",
"utcnow_ts": "1611111456561715"
}
}
[02:57:36.569] Task URL: https://ci.chromium.org/b/8857620910070722464
[02:57:36.569] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:2:0) after 1m0s
[02:58:19.728] Received PubSub notification, asking Buildbucket for the build status
[02:58:19.924] Build 8857620910070722464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:58:36.661] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:2:0)
[02:58:36.661] Timer tick, asking Buildbucket for the build status
[02:58:36.737] Build 8857620910070722464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:58:36.737] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:4:0) after 1m0s
[02:59:36.833] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:4:0)
[02:59:36.833] Timer tick, asking Buildbucket for the build status
[02:59:36.905] Build 8857620910070722464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:59:36.905] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:5:0) after 1m0s
[03:00:36.941] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:5:0)
[03:00:36.941] Timer tick, asking Buildbucket for the build status
[03:00:37.034] Build 8857620910070722464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:00:37.034] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:6:0) after 1m0s
[03:01:37.060] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:6:0)
[03:01:37.060] Timer tick, asking Buildbucket for the build status
[03:01:37.144] Build 8857620910070722464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:01:37.144] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:7:0) after 1m0s
[03:02:37.209] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:7:0)
[03:02:37.209] Timer tick, asking Buildbucket for the build status
[03:02:37.527] Build 8857620910070722464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:02:37.527] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:8:0) after 1m0s
[03:03:37.527] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:8:0)
[03:03:37.527] Timer tick, asking Buildbucket for the build status
[03:03:37.654] Build 8857620910070722464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:03:37.654] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:9:0) after 1m0s
[03:04:37.686] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:9:0)
[03:04:37.686] Timer tick, asking Buildbucket for the build status
[03:04:37.898] Build 8857620910070722464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:04:37.898] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:10:0) after 1m0s
[03:05:37.925] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:10:0)
[03:05:37.925] Timer tick, asking Buildbucket for the build status
[03:05:38.015] Build 8857620910070722464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:05:38.015] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:11:0) after 1m0s
[03:06:38.037] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:11:0)
[03:06:38.037] Timer tick, asking Buildbucket for the build status
[03:06:38.218] Build 8857620910070722464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:06:38.218] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:12:0) after 1m0s
[03:07:38.253] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:12:0)
[03:07:38.253] Timer tick, asking Buildbucket for the build status
[03:07:38.356] Build 8857620910070722464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:07:38.356] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:13:0) after 1m0s
[03:08:38.378] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:13:0)
[03:08:38.378] Timer tick, asking Buildbucket for the build status
[03:08:38.494] Build 8857620910070722464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:08:38.494] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:14:0) after 1m0s
[03:09:38.554] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:14:0)
[03:09:38.554] Timer tick, asking Buildbucket for the build status
[03:09:38.907] Build 8857620910070722464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:09:38.907] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:15:0) after 1m0s
[03:10:44.228] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:15:0)
[03:10:44.228] Timer tick, asking Buildbucket for the build status
[03:10:44.292] Build 8857620910070722464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:10:44.292] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:16:0) after 1m0s
[03:11:44.330] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:16:0)
[03:11:44.330] Timer tick, asking Buildbucket for the build status
[03:11:44.395] Build 8857620910070722464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:11:44.395] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:17:0) after 1m0s
[03:12:44.482] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:17:0)
[03:12:44.482] Timer tick, asking Buildbucket for the build status
[03:12:44.630] Build 8857620910070722464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:12:44.630] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:18:0) after 1m0s
[03:13:44.765] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:18:0)
[03:13:44.765] Timer tick, asking Buildbucket for the build status
[03:13:45.076] Build 8857620910070722464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:13:45.076] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:19:0) after 1m0s
[03:14:45.140] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:19:0)
[03:14:45.140] Timer tick, asking Buildbucket for the build status
[03:14:45.251] Build 8857620910070722464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:14:45.251] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:20:0) after 1m0s
[03:15:45.297] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:20:0)
[03:15:45.297] Timer tick, asking Buildbucket for the build status
[03:15:45.539] Build 8857620910070722464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:15:45.539] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:21:0) after 1m0s
[03:16:45.570] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:21:0)
[03:16:45.570] Timer tick, asking Buildbucket for the build status
[03:16:45.632] Build 8857620910070722464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:16:45.632] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:22:0) after 1m0s
[03:17:45.651] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:22:0)
[03:17:45.651] Timer tick, asking Buildbucket for the build status
[03:17:45.899] Build 8857620910070722464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:17:45.899] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:23:0) after 1m0s
[03:18:45.957] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:23:0)
[03:18:45.957] Timer tick, asking Buildbucket for the build status
[03:18:46.270] Build 8857620910070722464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:18:46.270] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:24:0) after 1m0s
[03:19:46.328] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:24:0)
[03:19:46.328] Timer tick, asking Buildbucket for the build status
[03:19:46.514] Build 8857620910070722464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:19:46.514] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:25:0) after 1m0s
[03:20:46.539] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:25:0)
[03:20:46.539] Timer tick, asking Buildbucket for the build status
[03:20:46.607] Build 8857620910070722464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:20:46.607] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:26:0) after 1m0s
[03:21:46.662] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:26:0)
[03:21:46.662] Timer tick, asking Buildbucket for the build status
[03:21:46.903] Build 8857620910070722464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:21:46.903] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:27:0) after 1m0s
[03:22:46.948] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:27:0)
[03:22:46.948] Timer tick, asking Buildbucket for the build status
[03:22:47.069] Build 8857620910070722464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:22:47.069] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:28:0) after 1m0s
[03:23:47.094] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:28:0)
[03:23:47.094] Timer tick, asking Buildbucket for the build status
[03:23:47.160] Build 8857620910070722464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:23:47.160] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:29:0) after 1m0s
[03:24:47.202] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:29:0)
[03:24:47.202] Timer tick, asking Buildbucket for the build status
[03:24:47.368] Build 8857620910070722464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:24:47.368] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:30:0) after 1m0s
[03:25:47.396] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:30:0)
[03:25:47.396] Timer tick, asking Buildbucket for the build status
[03:25:47.871] Build 8857620910070722464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:25:47.871] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:31:0) after 1m0s
[03:26:47.943] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:31:0)
[03:26:47.943] Timer tick, asking Buildbucket for the build status
[03:26:48.034] Build 8857620910070722464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:26:48.034] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:32:0) after 1m0s
[03:27:48.044] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:32:0)
[03:27:48.044] Timer tick, asking Buildbucket for the build status
[03:27:48.337] Build 8857620910070722464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:27:48.337] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:33:0) after 1m0s
[03:28:48.430] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:33:0)
[03:28:48.430] Timer tick, asking Buildbucket for the build status
[03:28:48.487] Build 8857620910070722464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:28:48.487] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:34:0) after 1m0s
[03:29:48.597] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:34:0)
[03:29:48.597] Timer tick, asking Buildbucket for the build status
[03:29:48.652] Build 8857620910070722464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:29:48.652] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:35:0) after 1m0s
[03:30:48.749] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:35:0)
[03:30:48.749] Timer tick, asking Buildbucket for the build status
[03:30:49.031] Build 8857620910070722464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:30:49.031] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:36:0) after 1m0s
[03:31:49.126] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:36:0)
[03:31:49.126] Timer tick, asking Buildbucket for the build status
[03:31:49.336] Build 8857620910070722464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:31:49.336] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:37:0) after 1m0s
[03:32:49.368] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:37:0)
[03:32:49.368] Timer tick, asking Buildbucket for the build status
[03:32:49.470] Build 8857620910070722464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:32:49.470] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:38:0) after 1m0s
[03:33:49.565] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:38:0)
[03:33:49.565] Timer tick, asking Buildbucket for the build status
[03:33:49.623] Build 8857620910070722464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:33:49.623] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:39:0) after 1m0s
[03:34:49.651] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:39:0)
[03:34:49.651] Timer tick, asking Buildbucket for the build status
[03:34:49.729] Build 8857620910070722464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:34:49.729] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:40:0) after 1m0s
[03:35:49.795] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:40:0)
[03:35:49.795] Timer tick, asking Buildbucket for the build status
[03:35:49.854] Build 8857620910070722464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:35:49.854] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:41:0) after 1m0s
[03:36:49.910] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:41:0)
[03:36:49.910] Timer tick, asking Buildbucket for the build status
[03:36:50.148] Build 8857620910070722464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:36:50.148] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:42:0) after 1m0s
[03:37:50.257] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:42:0)
[03:37:50.257] Timer tick, asking Buildbucket for the build status
[03:37:50.357] Build 8857620910070722464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:37:50.357] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:43:0) after 1m0s
[03:38:50.413] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:43:0)
[03:38:50.413] Timer tick, asking Buildbucket for the build status
[03:38:50.482] Build 8857620910070722464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:38:50.482] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:44:0) after 1m0s
[03:39:50.537] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:44:0)
[03:39:50.537] Timer tick, asking Buildbucket for the build status
[03:39:50.594] Build 8857620910070722464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:39:50.594] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:45:0) after 1m0s
[03:40:50.646] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:45:0)
[03:40:50.646] Timer tick, asking Buildbucket for the build status
[03:40:50.710] Build 8857620910070722464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:40:50.710] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:46:0) after 1m0s
[03:41:50.734] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:46:0)
[03:41:50.734] Timer tick, asking Buildbucket for the build status
[03:41:50.796] Build 8857620910070722464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:41:50.796] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:47:0) after 1m0s
[03:42:50.849] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:47:0)
[03:42:50.849] Timer tick, asking Buildbucket for the build status
[03:42:50.952] Build 8857620910070722464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:42:50.952] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:48:0) after 1m0s
[03:43:50.974] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:48:0)
[03:43:50.974] Timer tick, asking Buildbucket for the build status
[03:43:51.090] Build 8857620910070722464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:43:51.090] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:49:0) after 1m0s
[03:44:51.147] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:49:0)
[03:44:51.147] Timer tick, asking Buildbucket for the build status
[03:44:51.226] Build 8857620910070722464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:44:51.226] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:50:0) after 1m0s
[03:45:51.263] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:50:0)
[03:45:51.263] Timer tick, asking Buildbucket for the build status
[03:45:51.337] Build 8857620910070722464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:45:51.337] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:51:0) after 1m0s
[03:46:51.497] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:51:0)
[03:46:51.497] Timer tick, asking Buildbucket for the build status
[03:46:51.562] Build 8857620910070722464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:46:51.562] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:52:0) after 1m0s
[03:47:51.637] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:52:0)
[03:47:51.637] Timer tick, asking Buildbucket for the build status
[03:47:51.943] Build 8857620910070722464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:47:51.943] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:53:0) after 1m0s
[03:48:52.024] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:53:0)
[03:48:52.024] Timer tick, asking Buildbucket for the build status
[03:48:52.097] Build 8857620910070722464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:48:52.097] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:54:0) after 1m0s
[03:49:52.093] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:54:0)
[03:49:52.093] Timer tick, asking Buildbucket for the build status
[03:49:52.164] Build 8857620910070722464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:49:52.164] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:55:0) after 1m0s
[03:50:52.229] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:55:0)
[03:50:52.229] Timer tick, asking Buildbucket for the build status
[03:50:52.423] Build 8857620910070722464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:50:52.423] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:56:0) after 1m0s
[03:51:52.453] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:56:0)
[03:51:52.453] Timer tick, asking Buildbucket for the build status
[03:51:52.522] Build 8857620910070722464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:51:52.522] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:57:0) after 1m0s
[03:52:52.494] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:57:0)
[03:52:52.494] Timer tick, asking Buildbucket for the build status
[03:52:52.736] Build 8857620910070722464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:52:52.736] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:58:0) after 1m0s
[03:53:52.747] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:58:0)
[03:53:52.747] Timer tick, asking Buildbucket for the build status
[03:53:52.996] Build 8857620910070722464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:53:52.996] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:59:0) after 1m0s
[03:54:53.056] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:59:0)
[03:54:53.056] Timer tick, asking Buildbucket for the build status
[03:54:53.401] Build 8857620910070722464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:54:53.401] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:60:0) after 1m0s
[03:55:53.527] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:60:0)
[03:55:53.527] Timer tick, asking Buildbucket for the build status
[03:55:53.588] Build 8857620910070722464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:55:53.588] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:61:0) after 1m0s
[03:56:53.671] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:61:0)
[03:56:53.671] Timer tick, asking Buildbucket for the build status
[03:56:53.733] Build 8857620910070722464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:56:53.733] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:62:0) after 1m0s
[03:57:53.827] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:62:0)
[03:57:53.827] Timer tick, asking Buildbucket for the build status
[03:57:53.983] Build 8857620910070722464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:57:53.983] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:63:0) after 1m0s
[03:58:53.976] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:63:0)
[03:58:53.976] Timer tick, asking Buildbucket for the build status
[03:58:54.131] Build 8857620910070722464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:58:54.131] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:64:0) after 1m0s
[03:59:54.161] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:64:0)
[03:59:54.161] Timer tick, asking Buildbucket for the build status
[03:59:54.260] Build 8857620910070722464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:59:54.260] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:65:0) after 1m0s
[04:00:54.289] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:65:0)
[04:00:54.289] Timer tick, asking Buildbucket for the build status
[04:00:54.515] Build 8857620910070722464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:00:54.515] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:66:0) after 1m0s
[04:01:54.560] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:66:0)
[04:01:54.560] Timer tick, asking Buildbucket for the build status
[04:01:54.661] Build 8857620910070722464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:01:54.661] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:67:0) after 1m0s
[04:02:54.689] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:67:0)
[04:02:54.689] Timer tick, asking Buildbucket for the build status
[04:02:54.843] Build 8857620910070722464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:02:54.843] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:68:0) after 1m0s
[04:03:55.187] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:68:0)
[04:03:55.187] Timer tick, asking Buildbucket for the build status
[04:03:55.449] Build 8857620910070722464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:03:55.449] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:69:0) after 1m0s
[04:04:55.512] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:69:0)
[04:04:55.512] Timer tick, asking Buildbucket for the build status
[04:04:55.602] Build 8857620910070722464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:04:55.602] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:70:0) after 1m0s
[04:05:55.590] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:70:0)
[04:05:55.590] Timer tick, asking Buildbucket for the build status
[04:05:55.672] Build 8857620910070722464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:05:55.672] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:71:0) after 1m0s
[04:06:55.661] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:71:0)
[04:06:55.661] Timer tick, asking Buildbucket for the build status
[04:06:55.716] Build 8857620910070722464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:06:55.716] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:72:0) after 1m0s
[04:07:55.731] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:72:0)
[04:07:55.731] Timer tick, asking Buildbucket for the build status
[04:07:55.848] Build 8857620910070722464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:07:55.848] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:73:0) after 1m0s
[04:08:56.328] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:73:0)
[04:08:56.328] Timer tick, asking Buildbucket for the build status
[04:08:57.104] Build 8857620910070722464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:08:57.104] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:74:0) after 1m0s
[04:09:57.221] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:74:0)
[04:09:57.221] Timer tick, asking Buildbucket for the build status
[04:09:57.430] Build 8857620910070722464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:09:57.431] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:75:0) after 1m0s
[04:10:57.622] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:75:0)
[04:10:57.622] Timer tick, asking Buildbucket for the build status
[04:10:57.728] Build 8857620910070722464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:10:57.728] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:76:0) after 1m0s
[04:11:58.073] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:76:0)
[04:11:58.073] Timer tick, asking Buildbucket for the build status
[04:11:58.159] Build 8857620910070722464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:11:58.159] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:77:0) after 1m0s
[04:12:58.327] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:77:0)
[04:12:58.327] Timer tick, asking Buildbucket for the build status
[04:12:58.447] Build 8857620910070722464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:12:58.447] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:78:0) after 1m0s
[04:13:58.555] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:78:0)
[04:13:58.555] Timer tick, asking Buildbucket for the build status
[04:13:58.613] Build 8857620910070722464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:13:58.613] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:79:0) after 1m0s
[04:14:58.637] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:79:0)
[04:14:58.637] Timer tick, asking Buildbucket for the build status
[04:14:58.836] Build 8857620910070722464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:14:58.836] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:80:0) after 1m0s
[04:15:58.922] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:80:0)
[04:15:58.922] Timer tick, asking Buildbucket for the build status
[04:15:59.182] Build 8857620910070722464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:15:59.182] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:81:0) after 1m0s
[04:16:59.217] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:81:0)
[04:16:59.217] Timer tick, asking Buildbucket for the build status
[04:16:59.341] Build 8857620910070722464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:16:59.341] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:82:0) after 1m0s
[04:17:59.472] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:82:0)
[04:17:59.472] Timer tick, asking Buildbucket for the build status
[04:18:00.149] Build 8857620910070722464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:18:00.150] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:83:0) after 1m0s
[04:19:00.218] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:83:0)
[04:19:00.218] Timer tick, asking Buildbucket for the build status
[04:19:00.515] Build 8857620910070722464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:19:00.515] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:84:0) after 1m0s
[04:20:01.571] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:84:0)
[04:20:01.571] Timer tick, asking Buildbucket for the build status
[04:20:01.680] Build 8857620910070722464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:20:01.680] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:85:0) after 1m0s
[04:21:01.768] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:85:0)
[04:21:01.768] Timer tick, asking Buildbucket for the build status
[04:21:01.901] Build 8857620910070722464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:21:01.901] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:86:0) after 1m0s
[04:22:02.071] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:86:0)
[04:22:02.071] Timer tick, asking Buildbucket for the build status
[04:22:02.333] Build 8857620910070722464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:22:02.333] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:87:0) after 1m0s
[04:23:02.431] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:87:0)
[04:23:02.431] Timer tick, asking Buildbucket for the build status
[04:23:04.470] Build 8857620910070722464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:23:04.470] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:88:0) after 1m0s
[04:24:04.476] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:88:0)
[04:24:04.476] Timer tick, asking Buildbucket for the build status
[04:24:04.646] Build 8857620910070722464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:24:04.646] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:89:0) after 1m0s
[04:25:04.805] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:89:0)
[04:25:04.805] Timer tick, asking Buildbucket for the build status
[04:25:05.012] Build 8857620910070722464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:25:05.012] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:90:0) after 1m0s
[04:26:05.066] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:90:0)
[04:26:05.066] Timer tick, asking Buildbucket for the build status
[04:26:05.136] Build 8857620910070722464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:26:05.136] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:91:0) after 1m0s
[04:27:05.184] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:91:0)
[04:27:05.184] Timer tick, asking Buildbucket for the build status
[04:27:05.263] Build 8857620910070722464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:27:05.263] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:92:0) after 1m0s
[04:28:05.292] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:92:0)
[04:28:05.293] Timer tick, asking Buildbucket for the build status
[04:28:05.420] Build 8857620910070722464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:28:05.420] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:93:0) after 1m0s
[04:29:05.514] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:93:0)
[04:29:05.514] Timer tick, asking Buildbucket for the build status
[04:29:06.017] Build 8857620910070722464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:29:06.017] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:94:0) after 1m0s
[04:30:06.106] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:94:0)
[04:30:06.106] Timer tick, asking Buildbucket for the build status
[04:30:06.252] Build 8857620910070722464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:30:06.252] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:95:0) after 1m0s
[04:31:07.019] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:95:0)
[04:31:07.019] Timer tick, asking Buildbucket for the build status
[04:31:07.499] Build 8857620910070722464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:31:07.499] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:96:0) after 1m0s
[04:32:07.683] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:96:0)
[04:32:07.683] Timer tick, asking Buildbucket for the build status
[04:32:07.743] Build 8857620910070722464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:32:07.743] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:97:0) after 1m0s
[04:33:08.732] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:97:0)
[04:33:08.732] Timer tick, asking Buildbucket for the build status
[04:33:08.856] Build 8857620910070722464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:33:08.856] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:98:0) after 1m0s
[04:34:09.366] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:98:0)
[04:34:09.366] Timer tick, asking Buildbucket for the build status
[04:34:09.456] Build 8857620910070722464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:34:09.456] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:99:0) after 1m0s
[04:35:09.537] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:99:0)
[04:35:09.537] Timer tick, asking Buildbucket for the build status
[04:35:09.590] Build 8857620910070722464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:35:09.590] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:100:0) after 1m0s
[04:36:09.685] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:100:0)
[04:36:09.685] Timer tick, asking Buildbucket for the build status
[04:36:09.758] Build 8857620910070722464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:36:09.758] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:101:0) after 1m0s
[04:37:09.895] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:101:0)
[04:37:09.895] Timer tick, asking Buildbucket for the build status
[04:37:10.082] Build 8857620910070722464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:37:10.082] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:102:0) after 1m0s
[04:38:10.272] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:102:0)
[04:38:10.272] Timer tick, asking Buildbucket for the build status
[04:38:10.407] Build 8857620910070722464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:38:10.407] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:103:0) after 1m0s
[04:39:10.545] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:103:0)
[04:39:10.545] Timer tick, asking Buildbucket for the build status
[04:39:10.778] Build 8857620910070722464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:39:10.778] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:104:0) after 1m0s
[04:40:10.832] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:104:0)
[04:40:10.832] Timer tick, asking Buildbucket for the build status
[04:40:10.939] Build 8857620910070722464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:40:10.939] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:105:0) after 1m0s
[04:41:11.031] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:105:0)
[04:41:11.031] Timer tick, asking Buildbucket for the build status
[04:41:11.113] Build 8857620910070722464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:41:11.113] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:106:0) after 1m0s
[04:42:11.225] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:106:0)
[04:42:11.225] Timer tick, asking Buildbucket for the build status
[04:42:11.561] Build 8857620910070722464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:42:11.561] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:107:0) after 1m0s
[04:43:11.796] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:107:0)
[04:43:11.796] Timer tick, asking Buildbucket for the build status
[04:43:12.016] Build 8857620910070722464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:43:12.016] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:108:0) after 1m0s
[04:44:12.085] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:108:0)
[04:44:12.085] Timer tick, asking Buildbucket for the build status
[04:44:12.154] Build 8857620910070722464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:44:12.154] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:109:0) after 1m0s
[04:45:12.226] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:109:0)
[04:45:12.226] Timer tick, asking Buildbucket for the build status
[04:45:12.595] Build 8857620910070722464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:45:12.595] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:110:0) after 1m0s
[04:46:12.641] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:110:0)
[04:46:12.641] Timer tick, asking Buildbucket for the build status
[04:46:12.700] Build 8857620910070722464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:46:12.700] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:111:0) after 1m0s
[04:47:12.734] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:111:0)
[04:47:12.734] Timer tick, asking Buildbucket for the build status
[04:47:12.846] Build 8857620910070722464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:47:12.846] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:112:0) after 1m0s
[04:48:12.877] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:112:0)
[04:48:12.877] Timer tick, asking Buildbucket for the build status
[04:48:12.989] Build 8857620910070722464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:48:12.989] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:113:0) after 1m0s
[04:49:13.117] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:113:0)
[04:49:13.117] Timer tick, asking Buildbucket for the build status
[04:49:13.209] Build 8857620910070722464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:49:13.209] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:114:0) after 1m0s
[04:50:13.485] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:114:0)
[04:50:13.485] Timer tick, asking Buildbucket for the build status
[04:50:13.749] Build 8857620910070722464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:50:13.749] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:115:0) after 1m0s
[04:51:13.830] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:115:0)
[04:51:13.830] Timer tick, asking Buildbucket for the build status
[04:51:13.995] Build 8857620910070722464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:51:13.995] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:116:0) after 1m0s
[04:52:13.991] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:116:0)
[04:52:13.991] Timer tick, asking Buildbucket for the build status
[04:52:14.108] Build 8857620910070722464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:52:14.108] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:117:0) after 1m0s
[04:53:14.135] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:117:0)
[04:53:14.135] Timer tick, asking Buildbucket for the build status
[04:53:14.498] Build 8857620910070722464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:53:14.498] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:118:0) after 1m0s
[04:54:14.721] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:118:0)
[04:54:14.721] Timer tick, asking Buildbucket for the build status
[04:54:14.786] Build 8857620910070722464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:54:14.786] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:119:0) after 1m0s
[04:55:14.988] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:119:0)
[04:55:14.988] Timer tick, asking Buildbucket for the build status
[04:55:15.058] Build 8857620910070722464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:55:15.058] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:120:0) after 1m0s
[04:56:15.119] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:120:0)
[04:56:15.119] Timer tick, asking Buildbucket for the build status
[04:56:15.338] Build 8857620910070722464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:56:15.338] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:121:0) after 1m0s
[04:57:15.315] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:121:0)
[04:57:15.315] Timer tick, asking Buildbucket for the build status
[04:57:15.374] Build 8857620910070722464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:57:15.374] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:122:0) after 1m0s
[04:58:15.395] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:122:0)
[04:58:15.395] Timer tick, asking Buildbucket for the build status
[04:58:15.496] Build 8857620910070722464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:58:15.496] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:123:0) after 1m0s
[04:59:15.648] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:123:0)
[04:59:15.648] Timer tick, asking Buildbucket for the build status
[04:59:15.724] Build 8857620910070722464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:59:15.724] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:124:0) after 1m0s
[05:00:15.897] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:124:0)
[05:00:15.897] Timer tick, asking Buildbucket for the build status
[05:00:16.234] Build 8857620910070722464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:00:16.234] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:125:0) after 1m0s
[05:01:16.356] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:125:0)
[05:01:16.356] Timer tick, asking Buildbucket for the build status
[05:01:17.394] Build 8857620910070722464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:01:17.394] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:126:0) after 1m0s
[05:02:17.401] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:126:0)
[05:02:17.401] Timer tick, asking Buildbucket for the build status
[05:02:17.473] Build 8857620910070722464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:02:17.473] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:127:0) after 1m0s
[05:03:17.516] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:127:0)
[05:03:17.516] Timer tick, asking Buildbucket for the build status
[05:03:17.741] Build 8857620910070722464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:03:17.741] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:128:0) after 1m0s
[05:04:17.779] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:128:0)
[05:04:17.779] Timer tick, asking Buildbucket for the build status
[05:04:17.848] Build 8857620910070722464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:04:17.848] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:129:0) after 1m0s
[05:05:17.941] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:129:0)
[05:05:17.941] Timer tick, asking Buildbucket for the build status
[05:05:18.028] Build 8857620910070722464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:05:18.028] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:130:0) after 1m0s
[05:06:18.148] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:130:0)
[05:06:18.148] Timer tick, asking Buildbucket for the build status
[05:06:18.213] Build 8857620910070722464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:06:18.213] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:131:0) after 1m0s
[05:07:18.465] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:131:0)
[05:07:18.465] Timer tick, asking Buildbucket for the build status
[05:07:18.537] Build 8857620910070722464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:07:18.537] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:132:0) after 1m0s
[05:08:18.574] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:132:0)
[05:08:18.574] Timer tick, asking Buildbucket for the build status
[05:08:18.682] Build 8857620910070722464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:08:18.682] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:133:0) after 1m0s
[05:09:18.748] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:133:0)
[05:09:18.748] Timer tick, asking Buildbucket for the build status
[05:09:18.831] Build 8857620910070722464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:09:18.831] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:134:0) after 1m0s
[05:10:19.018] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:134:0)
[05:10:19.018] Timer tick, asking Buildbucket for the build status
[05:10:19.078] Build 8857620910070722464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:10:19.078] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:135:0) after 1m0s
[05:11:19.141] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:135:0)
[05:11:19.141] Timer tick, asking Buildbucket for the build status
[05:11:19.205] Build 8857620910070722464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:11:19.205] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:136:0) after 1m0s
[05:12:19.289] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:136:0)
[05:12:19.289] Timer tick, asking Buildbucket for the build status
[05:12:19.351] Build 8857620910070722464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:12:19.351] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:137:0) after 1m0s
[05:13:19.432] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:137:0)
[05:13:19.432] Timer tick, asking Buildbucket for the build status
[05:13:19.705] Build 8857620910070722464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:13:19.705] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:138:0) after 1m0s
[05:14:19.737] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:138:0)
[05:14:19.737] Timer tick, asking Buildbucket for the build status
[05:14:20.014] Build 8857620910070722464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:14:20.014] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:139:0) after 1m0s
[05:15:20.047] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:139:0)
[05:15:20.047] Timer tick, asking Buildbucket for the build status
[05:15:20.169] Build 8857620910070722464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:15:20.169] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:140:0) after 1m0s
[05:16:20.230] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:140:0)
[05:16:20.230] Timer tick, asking Buildbucket for the build status
[05:16:20.327] Build 8857620910070722464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:16:20.327] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:141:0) after 1m0s
[05:17:20.357] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:141:0)
[05:17:20.357] Timer tick, asking Buildbucket for the build status
[05:17:20.619] Build 8857620910070722464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:17:20.619] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:142:0) after 1m0s
[05:18:20.661] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:142:0)
[05:18:20.661] Timer tick, asking Buildbucket for the build status
[05:18:20.996] Build 8857620910070722464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:18:20.996] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:143:0) after 1m0s
[05:19:21.081] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:143:0)
[05:19:21.081] Timer tick, asking Buildbucket for the build status
[05:19:21.151] Build 8857620910070722464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:19:21.151] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:144:0) after 1m0s
[05:20:21.237] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:144:0)
[05:20:21.237] Timer tick, asking Buildbucket for the build status
[05:20:21.319] Build 8857620910070722464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:20:21.319] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:145:0) after 1m0s
[05:21:21.354] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:145:0)
[05:21:21.354] Timer tick, asking Buildbucket for the build status
[05:21:21.434] Build 8857620910070722464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:21:21.434] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:146:0) after 1m0s
[05:22:21.453] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:146:0)
[05:22:21.453] Timer tick, asking Buildbucket for the build status
[05:22:21.664] Build 8857620910070722464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:22:21.664] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023050972143255616:147:0) after 1m0s
[05:22:36.952] Received PubSub notification, asking Buildbucket for the build status
[05:22:37.012] Build 8857620910070722464: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[05:22:37.012] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1611120155865561",
"created_by": "project:chromium",
"created_ts": "1611111455810311",
"id": "8857620910070722464",
"parameters_json": "{\"builder_name\": \"win-official\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"17801e45e6fead9859bb0f1748afd7efd3e76899\"}}",
"project": "chromium",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"$build/goma\": {\"enable_ats\": true, \"rpc_extra_params\": \"?prod\", \"server_host\": \"goma.chromium.org\", \"use_luci_auth\": true}, \"$recipe_engine/isolated\": {\"server\": \"https://isolateserver.appspot.com\"}, \"$recipe_engine/milo/blamelist_pins\": [{\"host\": \"chromium.googlesource.com\", \"id\": \"17801e45e6fead9859bb0f1748afd7efd3e76899\", \"project\": \"chromium/src\"}], \"$recipe_engine/path\": {\"cache_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\cache\", \"temp_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\tmp\\\\rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"bot_id\": \"win-dbg-4-5643\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium\", \"buildername\": \"win-official\", \"buildnumber\": 1419, \"got_angle_revision\": \"2e0490eedcc809bcd6359de6afdb9e40bd9f731a\", \"got_dawn_revision\": \"4b316994ad01ab6b87032c5fd9b53c6d18956d8d\", \"got_nacl_revision\": \"cb31feffa500401c95c6c44d88b7c358236bca36\", \"got_revision\": \"17801e45e6fead9859bb0f1748afd7efd3e76899\", \"got_revision_cp\": \"refs/heads/master@{#844986}\", \"got_swarming_client_revision\": \"1a072711d4388c62e02480fabc26c68c24494be9\", \"got_swiftshader_revision\": \"89fd1480b47e8c8668dfd3c24302c53b76ba4c88\", \"got_v8_revision\": \"6181c222ef4e8307540a8b27201a790957a92fdd\", \"got_v8_revision_cp\": \"refs/heads/9.0.17@{#1}\", \"got_webrtc_revision\": \"5eb527cf7fb28e22d0687c0295cb9f1bb458160c\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33034}\", \"mirrored_builders\": [\"tryserver.chromium:win-official\"], \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"17801e45e6fead9859bb0f1748afd7efd3e76899\"}, \"swarming\": {\"bot_dimensions\": {\"builder\": [\"win-archive-dbg\", \"win-archive-rel\", \"win-official\", \"win32-archive-dbg\", \"win32-archive-rel\", \"win32-official\"], \"caches\": [\"builder_9b3ebe5fd1c5be87380c40d216747346a59b28cf26de183f0e8e2b32e944d3c8_v2\"], \"cores\": [\"32\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"win-dbg-4-5643\"], \"image\": [\"chrome-win10-1703-20111600-a937a23cefb\"], \"integrity\": [\"high\"], \"locale\": [\"en_US.cp1252\"], \"machine_type\": [\"e2-standard-32\"], \"os\": [\"Windows\", \"Windows-10\", \"Windows-10-15063\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.13\"], \"server_version\": [\"5666-679c9bd\"], \"ssd\": [\"0\"], \"windows_client_version\": [\"10\"], \"zone\": [\"us\", \"us-west\", \"us-west1\", \"us-west1-b\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1611111499034105",
"status": "COMPLETED",
"status_changed_ts": "1611120156212994",
"tags": [
"build_address:luci.chromium.ci/win-official/1419",
"builder:win-official",
"buildset:commit/git/17801e45e6fead9859bb0f1748afd7efd3e76899",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/17801e45e6fead9859bb0f1748afd7efd3e76899",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9023050972143255616",
"scheduler_job_id:chromium/win-official",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8857620910070722464/+/annotations",
"swarming_tag:luci_project:chromium",
"swarming_tag:os:Windows",
"swarming_tag:recipe_name:chromium",
"swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build",
"swarming_task_id:51368b73c4b23610",
"user_agent:luci-scheduler"
],
"updated_ts": "1611120156227629",
"url": "https://ci.chromium.org/b/8857620910070722464",
"utcnow_ts": "1611120157001495"
}
}
[05:22:37.012] Invocation finished in 2h25m2.5617595s with status SUCCEEDED