[11:01:32.773] New invocation is queued and will start shortly
[11:01:33.883] Starting the invocation (attempt 1)
[11:01:33.922] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[11:01:33.923] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[11:01:33.923] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9033710965308129344",
"parameters_json": "{\"builder_name\":\"win-official\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"f40b6a196819e0f4ad86e6f187081b7d882d9b04\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-official",
"scheduler_invocation_id:9033710965308129344",
"scheduler_job_id:chromium/win-official",
"user_agent:luci-scheduler",
"buildset:commit/git/f40b6a196819e0f4ad86e6f187081b7d882d9b04",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/f40b6a196819e0f4ad86e6f187081b7d882d9b04",
"gitiles_ref:refs/heads/master"
]
}
[11:01:34.673] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1600945294009674",
"id": "8868280903347603808",
"parameters_json": "{\"builder_name\": \"win-official\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"f40b6a196819e0f4ad86e6f187081b7d882d9b04\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1600945294452661",
"tags": [
"build_address:luci.chromium.ci/win-official/649",
"builder:win-official",
"buildset:commit/git/f40b6a196819e0f4ad86e6f187081b7d882d9b04",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/f40b6a196819e0f4ad86e6f187081b7d882d9b04",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9033710965308129344",
"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/8868280903347603808/+/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": "1600945294452779",
"url": "https://ci.chromium.org/b/8868280903347603808",
"utcnow_ts": "1600945294664019"
}
}
[11:01:34.673] Task URL: https://ci.chromium.org/b/8868280903347603808
[11:01:34.673] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:2:0) after 1m0s
[11:02:10.844] Received PubSub notification, asking Buildbucket for the build status
[11:02:11.111] Build 8868280903347603808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:02:34.703] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:2:0)
[11:02:34.703] Timer tick, asking Buildbucket for the build status
[11:02:34.895] Build 8868280903347603808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:02:34.895] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:4:0) after 1m0s
[11:03:34.946] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:4:0)
[11:03:34.946] Timer tick, asking Buildbucket for the build status
[11:03:35.056] Build 8868280903347603808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:03:35.056] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:5:0) after 1m0s
[11:04:35.100] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:5:0)
[11:04:35.100] Timer tick, asking Buildbucket for the build status
[11:04:35.397] Build 8868280903347603808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:04:35.397] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:6:0) after 1m0s
[11:05:35.459] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:6:0)
[11:05:35.459] Timer tick, asking Buildbucket for the build status
[11:05:35.772] Build 8868280903347603808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:05:35.772] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:7:0) after 1m0s
[11:06:35.803] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:7:0)
[11:06:35.803] Timer tick, asking Buildbucket for the build status
[11:06:36.052] Build 8868280903347603808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:06:36.052] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:8:0) after 1m0s
[11:07:36.115] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:8:0)
[11:07:36.115] Timer tick, asking Buildbucket for the build status
[11:07:36.263] Build 8868280903347603808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:07:36.263] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:9:0) after 1m0s
[11:08:36.286] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:9:0)
[11:08:36.286] Timer tick, asking Buildbucket for the build status
[11:08:36.388] Build 8868280903347603808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:08:36.388] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:10:0) after 1m0s
[11:09:36.516] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:10:0)
[11:09:36.516] Timer tick, asking Buildbucket for the build status
[11:09:36.610] Build 8868280903347603808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:09:36.610] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:11:0) after 1m0s
[11:10:36.704] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:11:0)
[11:10:36.704] Timer tick, asking Buildbucket for the build status
[11:10:36.798] Build 8868280903347603808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:10:36.798] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:12:0) after 1m0s
[11:11:36.770] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:12:0)
[11:11:36.770] Timer tick, asking Buildbucket for the build status
[11:11:36.860] Build 8868280903347603808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:11:36.860] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:13:0) after 1m0s
[11:12:37.102] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:13:0)
[11:12:37.102] Timer tick, asking Buildbucket for the build status
[11:12:39.057] Build 8868280903347603808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:12:39.057] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:14:0) after 1m0s
[11:13:39.140] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:14:0)
[11:13:39.140] Timer tick, asking Buildbucket for the build status
[11:13:39.221] Build 8868280903347603808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:13:39.221] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:15:0) after 1m0s
[11:14:39.259] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:15:0)
[11:14:39.259] Timer tick, asking Buildbucket for the build status
[11:14:39.342] Build 8868280903347603808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:14:39.342] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:16:0) after 1m0s
[11:15:39.408] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:16:0)
[11:15:39.408] Timer tick, asking Buildbucket for the build status
[11:15:39.472] Build 8868280903347603808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:15:39.472] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:17:0) after 1m0s
[11:16:39.530] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:17:0)
[11:16:39.530] Timer tick, asking Buildbucket for the build status
[11:16:39.701] Build 8868280903347603808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:16:39.701] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:18:0) after 1m0s
[11:17:39.723] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:18:0)
[11:17:39.723] Timer tick, asking Buildbucket for the build status
[11:17:39.822] Build 8868280903347603808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:17:39.822] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:19:0) after 1m0s
[11:18:39.900] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:19:0)
[11:18:39.900] Timer tick, asking Buildbucket for the build status
[11:18:39.985] Build 8868280903347603808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:18:39.985] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:20:0) after 1m0s
[11:19:40.027] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:20:0)
[11:19:40.027] Timer tick, asking Buildbucket for the build status
[11:19:40.112] Build 8868280903347603808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:19:40.112] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:21:0) after 1m0s
[11:20:40.200] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:21:0)
[11:20:40.200] Timer tick, asking Buildbucket for the build status
[11:20:40.305] Build 8868280903347603808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:20:40.305] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:22:0) after 1m0s
[11:21:40.391] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:22:0)
[11:21:40.391] Timer tick, asking Buildbucket for the build status
[11:21:40.556] Build 8868280903347603808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:21:40.556] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:23:0) after 1m0s
[11:22:40.589] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:23:0)
[11:22:40.589] Timer tick, asking Buildbucket for the build status
[11:22:40.706] Build 8868280903347603808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:22:40.706] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:24:0) after 1m0s
[11:23:41.239] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:24:0)
[11:23:41.239] Timer tick, asking Buildbucket for the build status
[11:23:41.318] Build 8868280903347603808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:23:41.318] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:25:0) after 1m0s
[11:24:41.428] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:25:0)
[11:24:41.428] Timer tick, asking Buildbucket for the build status
[11:24:41.624] Build 8868280903347603808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:24:41.624] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:26:0) after 1m0s
[11:25:41.658] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:26:0)
[11:25:41.658] Timer tick, asking Buildbucket for the build status
[11:25:41.824] Build 8868280903347603808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:25:41.824] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:27:0) after 1m0s
[11:26:41.843] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:27:0)
[11:26:41.843] Timer tick, asking Buildbucket for the build status
[11:26:42.015] Build 8868280903347603808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:26:42.015] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:28:0) after 1m0s
[11:27:42.044] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:28:0)
[11:27:42.044] Timer tick, asking Buildbucket for the build status
[11:27:42.121] Build 8868280903347603808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:27:42.121] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:29:0) after 1m0s
[11:28:42.231] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:29:0)
[11:28:42.231] Timer tick, asking Buildbucket for the build status
[11:28:42.295] Build 8868280903347603808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:28:42.295] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:30:0) after 1m0s
[11:29:42.387] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:30:0)
[11:29:42.387] Timer tick, asking Buildbucket for the build status
[11:29:42.449] Build 8868280903347603808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:29:42.449] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:31:0) after 1m0s
[11:30:42.470] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:31:0)
[11:30:42.470] Timer tick, asking Buildbucket for the build status
[11:30:42.554] Build 8868280903347603808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:30:42.554] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:32:0) after 1m0s
[11:31:42.600] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:32:0)
[11:31:42.600] Timer tick, asking Buildbucket for the build status
[11:31:42.806] Build 8868280903347603808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:31:42.806] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:33:0) after 1m0s
[11:32:42.857] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:33:0)
[11:32:42.857] Timer tick, asking Buildbucket for the build status
[11:32:42.920] Build 8868280903347603808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:32:42.920] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:34:0) after 1m0s
[11:33:42.941] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:34:0)
[11:33:42.941] Timer tick, asking Buildbucket for the build status
[11:33:43.133] Build 8868280903347603808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:33:43.133] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:35:0) after 1m0s
[11:34:43.117] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:35:0)
[11:34:43.117] Timer tick, asking Buildbucket for the build status
[11:34:43.180] Build 8868280903347603808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:34:43.180] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:36:0) after 1m0s
[11:35:44.248] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:36:0)
[11:35:44.248] Timer tick, asking Buildbucket for the build status
[11:35:44.317] Build 8868280903347603808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:35:44.317] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:37:0) after 1m0s
[11:36:44.614] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:37:0)
[11:36:44.614] Timer tick, asking Buildbucket for the build status
[11:36:44.767] Build 8868280903347603808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:36:44.767] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:38:0) after 1m0s
[11:37:44.905] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:38:0)
[11:37:44.905] Timer tick, asking Buildbucket for the build status
[11:37:45.109] Build 8868280903347603808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:37:45.109] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:39:0) after 1m0s
[11:38:45.184] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:39:0)
[11:38:45.184] Timer tick, asking Buildbucket for the build status
[11:38:45.270] Build 8868280903347603808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:38:45.270] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:40:0) after 1m0s
[11:39:45.465] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:40:0)
[11:39:45.465] Timer tick, asking Buildbucket for the build status
[11:39:45.662] Build 8868280903347603808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:39:45.662] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:41:0) after 1m0s
[11:40:45.773] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:41:0)
[11:40:45.773] Timer tick, asking Buildbucket for the build status
[11:40:45.923] Build 8868280903347603808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:40:45.923] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:42:0) after 1m0s
[11:41:46.129] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:42:0)
[11:41:46.129] Timer tick, asking Buildbucket for the build status
[11:41:46.341] Build 8868280903347603808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:41:46.342] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:43:0) after 1m0s
[11:42:46.428] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:43:0)
[11:42:46.428] Timer tick, asking Buildbucket for the build status
[11:42:46.700] Build 8868280903347603808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:42:46.700] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:44:0) after 1m0s
[11:43:46.861] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:44:0)
[11:43:46.861] Timer tick, asking Buildbucket for the build status
[11:43:47.214] Build 8868280903347603808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:43:47.214] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:45:0) after 1m0s
[11:44:47.247] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:45:0)
[11:44:47.247] Timer tick, asking Buildbucket for the build status
[11:44:47.559] Build 8868280903347603808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:44:47.559] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:46:0) after 1m0s
[11:45:47.974] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:46:0)
[11:45:47.974] Timer tick, asking Buildbucket for the build status
[11:45:48.082] Build 8868280903347603808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:45:48.082] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:47:0) after 1m0s
[11:46:49.029] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:47:0)
[11:46:49.029] Timer tick, asking Buildbucket for the build status
[11:46:49.231] Build 8868280903347603808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:46:49.231] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:48:0) after 1m0s
[11:47:49.375] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:48:0)
[11:47:49.375] Timer tick, asking Buildbucket for the build status
[11:47:49.462] Build 8868280903347603808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:47:49.462] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:49:0) after 1m0s
[11:48:49.592] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:49:0)
[11:48:49.592] Timer tick, asking Buildbucket for the build status
[11:48:49.785] Build 8868280903347603808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:48:49.785] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:50:0) after 1m0s
[11:49:49.936] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:50:0)
[11:49:49.936] Timer tick, asking Buildbucket for the build status
[11:49:50.032] Build 8868280903347603808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:49:50.032] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:51:0) after 1m0s
[11:50:50.123] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:51:0)
[11:50:50.123] Timer tick, asking Buildbucket for the build status
[11:50:50.184] Build 8868280903347603808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:50:50.184] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:52:0) after 1m0s
[11:51:50.447] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:52:0)
[11:51:50.447] Timer tick, asking Buildbucket for the build status
[11:51:50.586] Build 8868280903347603808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:51:50.586] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:53:0) after 1m0s
[11:52:50.614] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:53:0)
[11:52:50.614] Timer tick, asking Buildbucket for the build status
[11:52:50.730] Build 8868280903347603808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:52:50.731] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:54:0) after 1m0s
[11:53:50.759] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:54:0)
[11:53:50.759] Timer tick, asking Buildbucket for the build status
[11:53:50.819] Build 8868280903347603808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:53:50.819] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:55:0) after 1m0s
[11:54:51.025] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:55:0)
[11:54:51.025] Timer tick, asking Buildbucket for the build status
[11:54:51.143] Build 8868280903347603808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:54:51.144] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:56:0) after 1m0s
[11:55:51.285] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:56:0)
[11:55:51.285] Timer tick, asking Buildbucket for the build status
[11:55:51.365] Build 8868280903347603808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:55:51.365] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:57:0) after 1m0s
[11:56:51.548] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:57:0)
[11:56:51.548] Timer tick, asking Buildbucket for the build status
[11:56:51.872] Build 8868280903347603808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:56:51.872] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:58:0) after 1m0s
[11:57:52.097] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:58:0)
[11:57:52.097] Timer tick, asking Buildbucket for the build status
[11:57:52.212] Build 8868280903347603808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:57:52.219] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:59:0) after 1m0s
[11:58:52.366] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:59:0)
[11:58:52.366] Timer tick, asking Buildbucket for the build status
[11:58:52.527] Build 8868280903347603808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:58:52.527] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:60:0) after 1m0s
[11:59:52.605] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:60:0)
[11:59:52.605] Timer tick, asking Buildbucket for the build status
[11:59:52.794] Build 8868280903347603808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:59:52.794] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:61:0) after 1m0s
[12:00:52.895] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:61:0)
[12:00:52.895] Timer tick, asking Buildbucket for the build status
[12:00:52.981] Build 8868280903347603808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:00:52.981] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:62:0) after 1m0s
[12:01:53.035] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:62:0)
[12:01:53.035] Timer tick, asking Buildbucket for the build status
[12:01:53.503] Build 8868280903347603808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:01:53.503] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:63:0) after 1m0s
[12:02:53.515] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:63:0)
[12:02:53.515] Timer tick, asking Buildbucket for the build status
[12:02:53.833] Build 8868280903347603808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:02:53.833] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:64:0) after 1m0s
[12:03:54.139] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:64:0)
[12:03:54.143] Timer tick, asking Buildbucket for the build status
[12:03:54.254] Build 8868280903347603808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:03:54.254] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:65:0) after 1m0s
[12:04:54.317] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:65:0)
[12:04:54.317] Timer tick, asking Buildbucket for the build status
[12:04:54.428] Build 8868280903347603808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:04:54.428] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:66:0) after 1m0s
[12:05:54.497] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:66:0)
[12:05:54.497] Timer tick, asking Buildbucket for the build status
[12:05:54.704] Build 8868280903347603808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:05:54.704] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:67:0) after 1m0s
[12:06:54.956] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:67:0)
[12:06:54.956] Timer tick, asking Buildbucket for the build status
[12:06:55.084] Build 8868280903347603808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:06:55.084] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:68:0) after 1m0s
[12:07:55.516] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:68:0)
[12:07:55.516] Timer tick, asking Buildbucket for the build status
[12:07:55.648] Build 8868280903347603808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:07:55.648] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:69:0) after 1m0s
[12:08:55.851] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:69:0)
[12:08:55.851] Timer tick, asking Buildbucket for the build status
[12:08:55.958] Build 8868280903347603808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:08:55.958] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:70:0) after 1m0s
[12:09:56.155] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:70:0)
[12:09:56.155] Timer tick, asking Buildbucket for the build status
[12:09:56.241] Build 8868280903347603808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:09:56.241] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:71:0) after 1m0s
[12:10:56.306] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:71:0)
[12:10:56.306] Timer tick, asking Buildbucket for the build status
[12:10:56.393] Build 8868280903347603808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:10:56.393] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:72:0) after 1m0s
[12:11:56.518] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:72:0)
[12:11:56.518] Timer tick, asking Buildbucket for the build status
[12:11:56.680] Build 8868280903347603808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:11:56.681] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:73:0) after 1m0s
[12:12:56.814] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:73:0)
[12:12:56.814] Timer tick, asking Buildbucket for the build status
[12:12:56.918] Build 8868280903347603808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:12:56.919] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:74:0) after 1m0s
[12:13:57.047] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:74:0)
[12:13:57.047] Timer tick, asking Buildbucket for the build status
[12:13:58.473] Build 8868280903347603808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:13:58.473] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:75:0) after 1m0s
[12:14:58.552] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:75:0)
[12:14:58.552] Timer tick, asking Buildbucket for the build status
[12:14:58.635] Build 8868280903347603808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:14:58.635] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:76:0) after 1m0s
[12:15:58.803] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:76:0)
[12:15:58.803] Timer tick, asking Buildbucket for the build status
[12:15:58.894] Build 8868280903347603808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:15:58.894] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:77:0) after 1m0s
[12:16:59.065] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:77:0)
[12:16:59.065] Timer tick, asking Buildbucket for the build status
[12:16:59.334] Build 8868280903347603808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:16:59.335] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:78:0) after 1m0s
[12:17:59.514] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:78:0)
[12:17:59.514] Timer tick, asking Buildbucket for the build status
[12:17:59.600] Build 8868280903347603808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:17:59.600] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:79:0) after 1m0s
[12:18:59.683] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:79:0)
[12:18:59.683] Timer tick, asking Buildbucket for the build status
[12:18:59.936] Build 8868280903347603808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:18:59.936] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:80:0) after 1m0s
[12:19:59.987] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:80:0)
[12:19:59.987] Timer tick, asking Buildbucket for the build status
[12:20:00.183] Build 8868280903347603808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:20:00.183] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:81:0) after 1m0s
[12:21:00.244] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:81:0)
[12:21:00.244] Timer tick, asking Buildbucket for the build status
[12:21:00.354] Build 8868280903347603808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:21:00.354] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:82:0) after 1m0s
[12:22:00.387] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:82:0)
[12:22:00.387] Timer tick, asking Buildbucket for the build status
[12:22:00.844] Build 8868280903347603808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:22:00.844] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:83:0) after 1m0s
[12:23:01.831] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:83:0)
[12:23:01.831] Timer tick, asking Buildbucket for the build status
[12:23:02.211] Build 8868280903347603808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:23:02.211] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:84:0) after 1m0s
[12:24:02.305] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:84:0)
[12:24:02.305] Timer tick, asking Buildbucket for the build status
[12:24:02.478] Build 8868280903347603808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:24:02.478] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:85:0) after 1m0s
[12:25:03.041] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:85:0)
[12:25:03.041] Timer tick, asking Buildbucket for the build status
[12:25:03.146] Build 8868280903347603808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:25:03.146] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:86:0) after 1m0s
[12:26:03.214] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:86:0)
[12:26:03.214] Timer tick, asking Buildbucket for the build status
[12:26:03.383] Build 8868280903347603808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:26:03.383] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:87:0) after 1m0s
[12:27:03.431] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:87:0)
[12:27:03.431] Timer tick, asking Buildbucket for the build status
[12:27:03.637] Build 8868280903347603808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:27:03.637] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:88:0) after 1m0s
[12:28:03.784] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:88:0)
[12:28:03.784] Timer tick, asking Buildbucket for the build status
[12:28:03.861] Build 8868280903347603808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:28:03.861] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:89:0) after 1m0s
[12:29:04.033] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:89:0)
[12:29:04.033] Timer tick, asking Buildbucket for the build status
[12:29:04.122] Build 8868280903347603808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:29:04.122] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:90:0) after 1m0s
[12:30:04.319] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:90:0)
[12:30:04.319] Timer tick, asking Buildbucket for the build status
[12:30:04.631] Build 8868280903347603808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:30:04.631] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:91:0) after 1m0s
[12:31:05.057] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:91:0)
[12:31:05.057] Timer tick, asking Buildbucket for the build status
[12:31:05.316] Build 8868280903347603808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:31:05.316] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:92:0) after 1m0s
[12:32:05.491] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:92:0)
[12:32:05.491] Timer tick, asking Buildbucket for the build status
[12:32:08.567] Build 8868280903347603808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:32:08.567] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:93:0) after 1m0s
[12:33:08.666] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:93:0)
[12:33:08.666] Timer tick, asking Buildbucket for the build status
[12:33:08.787] Build 8868280903347603808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:33:08.787] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:94:0) after 1m0s
[12:34:08.808] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:94:0)
[12:34:08.808] Timer tick, asking Buildbucket for the build status
[12:34:08.994] Build 8868280903347603808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:34:08.994] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:95:0) after 1m0s
[12:35:09.225] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:95:0)
[12:35:09.225] Timer tick, asking Buildbucket for the build status
[12:35:09.310] Build 8868280903347603808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:35:09.310] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:96:0) after 1m0s
[12:36:09.409] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:96:0)
[12:36:09.409] Timer tick, asking Buildbucket for the build status
[12:36:09.722] Build 8868280903347603808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:36:09.722] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:97:0) after 1m0s
[12:37:09.820] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:97:0)
[12:37:09.820] Timer tick, asking Buildbucket for the build status
[12:37:10.059] Build 8868280903347603808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:37:10.059] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:98:0) after 1m0s
[12:38:10.260] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:98:0)
[12:38:10.260] Timer tick, asking Buildbucket for the build status
[12:38:10.384] Build 8868280903347603808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:38:10.384] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:99:0) after 1m0s
[12:39:10.711] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:99:0)
[12:39:10.711] Timer tick, asking Buildbucket for the build status
[12:39:11.038] Build 8868280903347603808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:39:11.038] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:100:0) after 1m0s
[12:40:11.262] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:100:0)
[12:40:11.262] Timer tick, asking Buildbucket for the build status
[12:40:11.380] Build 8868280903347603808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:40:11.380] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:101:0) after 1m0s
[12:41:11.553] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:101:0)
[12:41:11.553] Timer tick, asking Buildbucket for the build status
[12:41:11.627] Build 8868280903347603808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:41:11.627] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:102:0) after 1m0s
[12:42:12.174] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:102:0)
[12:42:12.174] Timer tick, asking Buildbucket for the build status
[12:42:12.445] Build 8868280903347603808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:42:12.445] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:103:0) after 1m0s
[12:43:12.524] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:103:0)
[12:43:12.524] Timer tick, asking Buildbucket for the build status
[12:43:12.608] Build 8868280903347603808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:43:12.608] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:104:0) after 1m0s
[12:44:12.657] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:104:0)
[12:44:12.657] Timer tick, asking Buildbucket for the build status
[12:44:12.743] Build 8868280903347603808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:44:12.743] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:105:0) after 1m0s
[12:45:12.821] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:105:0)
[12:45:12.821] Timer tick, asking Buildbucket for the build status
[12:45:13.093] Build 8868280903347603808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:45:13.093] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:106:0) after 1m0s
[12:46:13.195] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:106:0)
[12:46:13.195] Timer tick, asking Buildbucket for the build status
[12:46:13.349] Build 8868280903347603808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:46:13.349] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:107:0) after 1m0s
[12:47:13.384] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:107:0)
[12:47:13.384] Timer tick, asking Buildbucket for the build status
[12:47:13.497] Build 8868280903347603808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:47:13.497] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:108:0) after 1m0s
[12:48:13.551] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:108:0)
[12:48:13.551] Timer tick, asking Buildbucket for the build status
[12:48:13.717] Build 8868280903347603808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:48:13.717] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:109:0) after 1m0s
[12:49:13.814] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:109:0)
[12:49:13.814] Timer tick, asking Buildbucket for the build status
[12:49:13.894] Build 8868280903347603808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:49:13.894] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:110:0) after 1m0s
[12:50:14.041] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:110:0)
[12:50:14.041] Timer tick, asking Buildbucket for the build status
[12:50:15.451] Build 8868280903347603808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:50:15.451] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:111:0) after 1m0s
[12:51:15.575] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:111:0)
[12:51:15.575] Timer tick, asking Buildbucket for the build status
[12:51:16.287] Build 8868280903347603808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:51:16.287] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:112:0) after 1m0s
[12:52:16.412] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:112:0)
[12:52:16.412] Timer tick, asking Buildbucket for the build status
[12:52:16.565] Build 8868280903347603808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:52:16.565] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:113:0) after 1m0s
[12:53:16.621] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:113:0)
[12:53:16.621] Timer tick, asking Buildbucket for the build status
[12:53:16.688] Build 8868280903347603808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:53:16.688] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:114:0) after 1m0s
[12:54:16.732] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:114:0)
[12:54:16.732] Timer tick, asking Buildbucket for the build status
[12:54:16.863] Build 8868280903347603808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:54:16.863] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:115:0) after 1m0s
[12:55:16.911] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:115:0)
[12:55:16.911] Timer tick, asking Buildbucket for the build status
[12:55:17.282] Build 8868280903347603808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:55:17.282] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:116:0) after 1m0s
[12:56:17.392] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:116:0)
[12:56:17.392] Timer tick, asking Buildbucket for the build status
[12:56:17.660] Build 8868280903347603808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:56:17.660] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:117:0) after 1m0s
[12:57:18.249] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:117:0)
[12:57:18.249] Timer tick, asking Buildbucket for the build status
[12:57:18.414] Build 8868280903347603808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:57:18.415] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:118:0) after 1m0s
[12:58:18.415] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:118:0)
[12:58:18.415] Timer tick, asking Buildbucket for the build status
[12:58:18.478] Build 8868280903347603808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:58:18.478] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:119:0) after 1m0s
[12:59:18.534] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:119:0)
[12:59:18.534] Timer tick, asking Buildbucket for the build status
[12:59:18.640] Build 8868280903347603808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:59:18.640] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:120:0) after 1m0s
[13:00:18.714] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:120:0)
[13:00:18.714] Timer tick, asking Buildbucket for the build status
[13:00:18.990] Build 8868280903347603808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:00:18.990] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:121:0) after 1m0s
[13:01:19.080] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:121:0)
[13:01:19.080] Timer tick, asking Buildbucket for the build status
[13:01:19.164] Build 8868280903347603808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:01:19.164] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:122:0) after 1m0s
[13:02:19.199] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:122:0)
[13:02:19.199] Timer tick, asking Buildbucket for the build status
[13:02:19.912] Build 8868280903347603808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:02:19.912] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:123:0) after 1m0s
[13:03:19.953] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:123:0)
[13:03:19.953] Timer tick, asking Buildbucket for the build status
[13:03:20.109] Build 8868280903347603808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:03:20.109] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:124:0) after 1m0s
[13:04:20.134] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:124:0)
[13:04:20.134] Timer tick, asking Buildbucket for the build status
[13:04:20.207] Build 8868280903347603808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:04:20.207] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:125:0) after 1m0s
[13:05:20.273] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:125:0)
[13:05:20.273] Timer tick, asking Buildbucket for the build status
[13:05:20.472] Build 8868280903347603808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:05:20.472] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:126:0) after 1m0s
[13:06:20.546] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:126:0)
[13:06:20.546] Timer tick, asking Buildbucket for the build status
[13:06:20.650] Build 8868280903347603808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:06:20.650] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:127:0) after 1m0s
[13:07:20.677] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:127:0)
[13:07:20.677] Timer tick, asking Buildbucket for the build status
[13:07:20.842] Build 8868280903347603808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:07:20.842] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:128:0) after 1m0s
[13:08:20.918] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:128:0)
[13:08:20.918] Timer tick, asking Buildbucket for the build status
[13:08:21.066] Build 8868280903347603808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:08:21.066] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:129:0) after 1m0s
[13:09:21.245] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:129:0)
[13:09:21.245] Timer tick, asking Buildbucket for the build status
[13:09:21.337] Build 8868280903347603808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:09:21.337] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:130:0) after 1m0s
[13:10:21.406] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:130:0)
[13:10:21.406] Timer tick, asking Buildbucket for the build status
[13:10:21.543] Build 8868280903347603808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:10:21.543] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:131:0) after 1m0s
[13:11:21.581] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:131:0)
[13:11:21.581] Timer tick, asking Buildbucket for the build status
[13:11:21.698] Build 8868280903347603808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:11:21.698] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:132:0) after 1m0s
[13:12:21.734] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:132:0)
[13:12:21.734] Timer tick, asking Buildbucket for the build status
[13:12:21.881] Build 8868280903347603808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:12:21.881] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:133:0) after 1m0s
[13:13:21.930] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:133:0)
[13:13:21.930] Timer tick, asking Buildbucket for the build status
[13:13:22.013] Build 8868280903347603808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:13:22.013] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:134:0) after 1m0s
[13:14:22.075] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:134:0)
[13:14:22.075] Timer tick, asking Buildbucket for the build status
[13:14:22.406] Build 8868280903347603808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:14:22.406] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:135:0) after 1m0s
[13:15:22.476] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:135:0)
[13:15:22.476] Timer tick, asking Buildbucket for the build status
[13:15:22.635] Build 8868280903347603808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:15:22.635] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:136:0) after 1m0s
[13:16:22.681] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:136:0)
[13:16:22.681] Timer tick, asking Buildbucket for the build status
[13:16:22.881] Build 8868280903347603808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:16:22.881] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:137:0) after 1m0s
[13:17:22.957] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:137:0)
[13:17:22.957] Timer tick, asking Buildbucket for the build status
[13:17:23.180] Build 8868280903347603808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:17:23.180] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033710965308129344:138:0) after 1m0s
[13:18:18.742] Received PubSub notification, asking Buildbucket for the build status
[13:18:18.935] Build 8868280903347603808: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[13:18:18.935] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1600953498198913",
"created_by": "project:chromium",
"created_ts": "1600945294009674",
"failure_reason": "BUILD_FAILURE",
"id": "8868280903347603808",
"parameters_json": "{\"builder_name\": \"win-official\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"f40b6a196819e0f4ad86e6f187081b7d882d9b04\"}}",
"project": "chromium",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"#### Step _compile_ failed. Error logs are shown below:\\n```\\n[82492/84133] LINK blink_perf_tests.exe blink_perf_tests.exe.pdb\\nFAILED: blink_perf_tests.exe blink_perf_tests.exe.pdb\\n```\\n##### ...The message was too long...\\n#### More information in raw_io.output[failure_summary]\"}, \"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\": \"f40b6a196819e0f4ad86e6f187081b7d882d9b04\", \"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-2-fpn1\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium\", \"buildername\": \"win-official\", \"buildnumber\": 649, \"got_angle_revision\": \"6c3942200a20a6609f212e9833b2bc81bdb14f59\", \"got_dawn_revision\": \"75e5ed6161fcb3a79cbf584c273ff7eac84956a9\", \"got_nacl_revision\": \"69a0d6e8affc94187af10e0673592d1b238c6eb0\", \"got_revision\": \"f40b6a196819e0f4ad86e6f187081b7d882d9b04\", \"got_revision_cp\": \"refs/heads/master@{#810128}\", \"got_swarming_client_revision\": \"44c13d73156581ea09b9389001e58c23a4b8d70a\", \"got_swiftshader_revision\": \"6aadd31a5f988b254046addce6aadaa650f098e9\", \"got_v8_revision\": \"4ee4324d55d9038d34bcf3e70d7951ab5720b700\", \"got_v8_revision_cp\": \"refs/heads/8.7.176@{#1}\", \"got_webrtc_revision\": \"5e85c8bc3139cff623f7b7724bb56529ce11d3ec\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32179}\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"f40b6a196819e0f4ad86e6f187081b7d882d9b04\"}, \"swarming\": {\"bot_dimensions\": {\"builder\": [\"win-archive-dbg\", \"win-archive-rel\", \"win-official\", \"win32-archive-dbg\", \"win32-archive-rel\", \"win32-official\"], \"caches\": [\"builder_9b3ebe5fd1c5be87380c40d216747346a59b28cf26de183f0e8e2b32e944d3c8_v2\", \"git\", \"goma_v2\"], \"cores\": [\"32\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"win-dbg-2-fpn1\"], \"image\": [\"chrome-win10-1703-20091800-1bf418dc3b5\"], \"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\": [\"5531-8ab19d9\"], \"ssd\": [\"0\"], \"windows_client_version\": [\"10\"], \"zone\": [\"us\", \"us-west\", \"us-west1\", \"us-west1-b\"]}}, \"ui\": {\"info\": \"#### Step _compile_ failed. Error logs are shown below:\\n```\\n[82492/84133] LINK blink_perf_tests.exe blink_perf_tests.exe.pdb\\nFAILED: blink_perf_tests.exe blink_perf_tests.exe.pdb\\n```\\n##### ...The message was too long...\\n#### More information in raw_io.output[failure_summary]\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1600945329690715",
"status": "COMPLETED",
"status_changed_ts": "1600945330136079",
"tags": [
"build_address:luci.chromium.ci/win-official/649",
"builder:win-official",
"buildset:commit/git/f40b6a196819e0f4ad86e6f187081b7d882d9b04",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/f40b6a196819e0f4ad86e6f187081b7d882d9b04",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9033710965308129344",
"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/8868280903347603808/+/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:4ed898164286da10",
"user_agent:luci-scheduler"
],
"updated_ts": "1600953498415806",
"url": "https://ci.chromium.org/b/8868280903347603808",
"utcnow_ts": "1600953498918165"
}
}
[13:18:18.935] Invocation finished in 2h16m46.179486312s with status FAILED