[05:08:35.410] New invocation is queued and will start shortly
[05:08:36.474] Starting the invocation (attempt 1)
[05:08:36.514] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[05:08:36.514] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[05:08:36.514] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9036813468241924400",
"parameters_json": "{\"builder_name\":\"win-official\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"6336337c1d7b1de10a5ed3ce30362b12ba60acf3\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-official",
"scheduler_invocation_id:9036813468241924400",
"scheduler_job_id:chromium/win-official",
"user_agent:luci-scheduler",
"buildset:commit/git/6336337c1d7b1de10a5ed3ce30362b12ba60acf3",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/6336337c1d7b1de10a5ed3ce30362b12ba60acf3",
"gitiles_ref:refs/heads/master"
]
}
[05:08:37.418] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1597986516643231",
"id": "8871383406282877680",
"parameters_json": "{\"builder_name\": \"win-official\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"6336337c1d7b1de10a5ed3ce30362b12ba60acf3\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1597986517249691",
"tags": [
"build_address:luci.chromium.ci/win-official/386",
"builder:win-official",
"buildset:commit/git/6336337c1d7b1de10a5ed3ce30362b12ba60acf3",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/6336337c1d7b1de10a5ed3ce30362b12ba60acf3",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9036813468241924400",
"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/8871383406282877680/+/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": "1597986517249815",
"url": "https://ci.chromium.org/b/8871383406282877680",
"utcnow_ts": "1597986517405803"
}
}
[05:08:37.418] Task URL: https://ci.chromium.org/b/8871383406282877680
[05:08:37.418] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:2:0) after 1m0s
[05:09:20.652] Received PubSub notification, asking Buildbucket for the build status
[05:09:20.745] Build 8871383406282877680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:09:37.491] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:2:0)
[05:09:37.491] Timer tick, asking Buildbucket for the build status
[05:09:37.591] Build 8871383406282877680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:09:37.591] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:4:0) after 1m0s
[05:10:37.597] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:4:0)
[05:10:37.597] Timer tick, asking Buildbucket for the build status
[05:10:37.707] Build 8871383406282877680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:10:37.707] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:5:0) after 1m0s
[05:11:37.728] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:5:0)
[05:11:37.728] Timer tick, asking Buildbucket for the build status
[05:11:41.866] Build 8871383406282877680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:11:41.866] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:6:0) after 1m0s
[05:12:42.218] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:6:0)
[05:12:42.218] Timer tick, asking Buildbucket for the build status
[05:12:42.323] Build 8871383406282877680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:12:42.323] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:7:0) after 1m0s
[05:13:42.367] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:7:0)
[05:13:42.367] Timer tick, asking Buildbucket for the build status
[05:13:42.471] Build 8871383406282877680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:13:42.471] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:8:0) after 1m0s
[05:14:42.579] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:8:0)
[05:14:42.579] Timer tick, asking Buildbucket for the build status
[05:14:42.729] Build 8871383406282877680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:14:42.729] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:9:0) after 1m0s
[05:15:42.818] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:9:0)
[05:15:42.818] Timer tick, asking Buildbucket for the build status
[05:15:42.958] Build 8871383406282877680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:15:42.958] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:10:0) after 1m0s
[05:16:42.979] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:10:0)
[05:16:42.979] Timer tick, asking Buildbucket for the build status
[05:16:43.232] Build 8871383406282877680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:16:43.232] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:11:0) after 1m0s
[05:17:43.262] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:11:0)
[05:17:43.262] Timer tick, asking Buildbucket for the build status
[05:17:48.259] Build 8871383406282877680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:17:48.259] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:12:0) after 1m0s
[05:18:48.347] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:12:0)
[05:18:48.347] Timer tick, asking Buildbucket for the build status
[05:18:48.557] Build 8871383406282877680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:18:48.557] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:13:0) after 1m0s
[05:19:48.657] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:13:0)
[05:19:48.657] Timer tick, asking Buildbucket for the build status
[05:19:48.770] Build 8871383406282877680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:19:48.770] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:14:0) after 1m0s
[05:20:48.789] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:14:0)
[05:20:48.789] Timer tick, asking Buildbucket for the build status
[05:20:49.013] Build 8871383406282877680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:20:49.013] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:15:0) after 1m0s
[05:21:49.108] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:15:0)
[05:21:49.108] Timer tick, asking Buildbucket for the build status
[05:21:49.294] Build 8871383406282877680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:21:49.294] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:16:0) after 1m0s
[05:22:49.387] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:16:0)
[05:22:49.388] Timer tick, asking Buildbucket for the build status
[05:22:49.459] Build 8871383406282877680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:22:49.459] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:17:0) after 1m0s
[05:23:49.539] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:17:0)
[05:23:49.539] Timer tick, asking Buildbucket for the build status
[05:23:49.662] Build 8871383406282877680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:23:49.662] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:18:0) after 1m0s
[05:24:49.839] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:18:0)
[05:24:49.839] Timer tick, asking Buildbucket for the build status
[05:24:49.902] Build 8871383406282877680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:24:49.902] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:19:0) after 1m0s
[05:25:49.918] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:19:0)
[05:25:49.918] Timer tick, asking Buildbucket for the build status
[05:25:49.985] Build 8871383406282877680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:25:49.985] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:20:0) after 1m0s
[05:26:50.066] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:20:0)
[05:26:50.066] Timer tick, asking Buildbucket for the build status
[05:26:50.371] Build 8871383406282877680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:26:50.371] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:21:0) after 1m0s
[05:27:50.442] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:21:0)
[05:27:50.442] Timer tick, asking Buildbucket for the build status
[05:27:50.560] Build 8871383406282877680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:27:50.560] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:22:0) after 1m0s
[05:28:50.641] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:22:0)
[05:28:50.641] Timer tick, asking Buildbucket for the build status
[05:28:50.779] Build 8871383406282877680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:28:50.779] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:23:0) after 1m0s
[05:29:50.804] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:23:0)
[05:29:50.804] Timer tick, asking Buildbucket for the build status
[05:29:50.973] Build 8871383406282877680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:29:50.973] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:24:0) after 1m0s
[05:30:51.000] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:24:0)
[05:30:51.000] Timer tick, asking Buildbucket for the build status
[05:30:51.082] Build 8871383406282877680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:30:51.082] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:25:0) after 1m0s
[05:31:51.139] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:25:0)
[05:31:51.139] Timer tick, asking Buildbucket for the build status
[05:31:51.294] Build 8871383406282877680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:31:51.294] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:26:0) after 1m0s
[05:32:51.443] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:26:0)
[05:32:51.443] Timer tick, asking Buildbucket for the build status
[05:32:51.503] Build 8871383406282877680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:32:51.503] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:27:0) after 1m0s
[05:33:51.626] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:27:0)
[05:33:51.626] Timer tick, asking Buildbucket for the build status
[05:33:51.760] Build 8871383406282877680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:33:51.760] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:28:0) after 1m0s
[05:34:51.851] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:28:0)
[05:34:51.851] Timer tick, asking Buildbucket for the build status
[05:34:51.931] Build 8871383406282877680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:34:51.931] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:29:0) after 1m0s
[05:35:51.981] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:29:0)
[05:35:51.982] Timer tick, asking Buildbucket for the build status
[05:35:52.114] Build 8871383406282877680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:35:52.114] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:30:0) after 1m0s
[05:36:52.138] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:30:0)
[05:36:52.138] Timer tick, asking Buildbucket for the build status
[05:36:52.206] Build 8871383406282877680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:36:52.206] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:31:0) after 1m0s
[05:37:52.223] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:31:0)
[05:37:52.223] Timer tick, asking Buildbucket for the build status
[05:37:52.288] Build 8871383406282877680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:37:52.288] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:32:0) after 1m0s
[05:38:52.393] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:32:0)
[05:38:52.393] Timer tick, asking Buildbucket for the build status
[05:38:52.536] Build 8871383406282877680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:38:52.536] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:33:0) after 1m0s
[05:39:52.652] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:33:0)
[05:39:52.652] Timer tick, asking Buildbucket for the build status
[05:39:52.798] Build 8871383406282877680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:39:52.798] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:34:0) after 1m0s
[05:40:52.912] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:34:0)
[05:40:52.912] Timer tick, asking Buildbucket for the build status
[05:40:53.023] Build 8871383406282877680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:40:53.023] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:35:0) after 1m0s
[05:41:53.089] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:35:0)
[05:41:53.089] Timer tick, asking Buildbucket for the build status
[05:41:53.301] Build 8871383406282877680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:41:53.301] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:36:0) after 1m0s
[05:42:53.418] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:36:0)
[05:42:53.418] Timer tick, asking Buildbucket for the build status
[05:42:54.201] Build 8871383406282877680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:42:54.201] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:37:0) after 1m0s
[05:43:54.330] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:37:0)
[05:43:54.330] Timer tick, asking Buildbucket for the build status
[05:43:54.474] Build 8871383406282877680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:43:54.474] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:38:0) after 1m0s
[05:44:54.553] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:38:0)
[05:44:54.553] Timer tick, asking Buildbucket for the build status
[05:44:54.948] Build 8871383406282877680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:44:54.948] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:39:0) after 1m0s
[05:45:55.050] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:39:0)
[05:45:55.050] Timer tick, asking Buildbucket for the build status
[05:45:55.193] Build 8871383406282877680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:45:55.193] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:40:0) after 1m0s
[05:46:55.456] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:40:0)
[05:46:55.456] Timer tick, asking Buildbucket for the build status
[05:46:55.633] Build 8871383406282877680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:46:55.633] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:41:0) after 1m0s
[05:47:55.797] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:41:0)
[05:47:55.797] Timer tick, asking Buildbucket for the build status
[05:47:56.139] Build 8871383406282877680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:47:56.139] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:42:0) after 1m0s
[05:48:56.259] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:42:0)
[05:48:56.259] Timer tick, asking Buildbucket for the build status
[05:48:56.438] Build 8871383406282877680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:48:56.438] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:43:0) after 1m0s
[05:49:56.486] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:43:0)
[05:49:56.486] Timer tick, asking Buildbucket for the build status
[05:49:57.001] Build 8871383406282877680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:49:57.001] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:44:0) after 1m0s
[05:50:57.061] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:44:0)
[05:50:57.061] Timer tick, asking Buildbucket for the build status
[05:50:57.147] Build 8871383406282877680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:50:57.147] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:45:0) after 1m0s
[05:51:57.235] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:45:0)
[05:51:57.235] Timer tick, asking Buildbucket for the build status
[05:51:57.337] Build 8871383406282877680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:51:57.337] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:46:0) after 1m0s
[05:52:57.369] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:46:0)
[05:52:57.369] Timer tick, asking Buildbucket for the build status
[05:53:00.543] Build 8871383406282877680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:53:00.543] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:47:0) after 1m0s
[05:54:01.238] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:47:0)
[05:54:01.238] Timer tick, asking Buildbucket for the build status
[05:54:02.261] Build 8871383406282877680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:54:02.261] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:48:0) after 1m0s
[05:55:02.336] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:48:0)
[05:55:02.336] Timer tick, asking Buildbucket for the build status
[05:55:02.413] Build 8871383406282877680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:55:02.413] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:49:0) after 1m0s
[05:56:02.418] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:49:0)
[05:56:02.418] Timer tick, asking Buildbucket for the build status
[05:56:02.568] Build 8871383406282877680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:56:02.568] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:50:0) after 1m0s
[05:57:03.038] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:50:0)
[05:57:03.038] Timer tick, asking Buildbucket for the build status
[05:57:03.112] Build 8871383406282877680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:57:03.112] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:51:0) after 1m0s
[05:58:03.183] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:51:0)
[05:58:03.183] Timer tick, asking Buildbucket for the build status
[05:58:03.269] Build 8871383406282877680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:58:03.269] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:52:0) after 1m0s
[05:59:03.487] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:52:0)
[05:59:03.487] Timer tick, asking Buildbucket for the build status
[05:59:04.007] Build 8871383406282877680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:59:04.007] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:53:0) after 1m0s
[06:00:04.957] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:53:0)
[06:00:04.957] Timer tick, asking Buildbucket for the build status
[06:00:05.064] Build 8871383406282877680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:00:05.064] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:54:0) after 1m0s
[06:01:05.136] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:54:0)
[06:01:05.136] Timer tick, asking Buildbucket for the build status
[06:01:08.099] Build 8871383406282877680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:01:08.099] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:55:0) after 1m0s
[06:02:08.336] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:55:0)
[06:02:08.336] Timer tick, asking Buildbucket for the build status
[06:02:08.428] Build 8871383406282877680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:02:08.428] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:56:0) after 1m0s
[06:03:08.564] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:56:0)
[06:03:08.564] Timer tick, asking Buildbucket for the build status
[06:03:08.724] Build 8871383406282877680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:03:08.724] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:57:0) after 1m0s
[06:04:08.803] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:57:0)
[06:04:08.803] Timer tick, asking Buildbucket for the build status
[06:04:08.964] Build 8871383406282877680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:04:08.964] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:58:0) after 1m0s
[06:05:09.015] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:58:0)
[06:05:09.015] Timer tick, asking Buildbucket for the build status
[06:05:09.387] Build 8871383406282877680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:05:09.387] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:59:0) after 1m0s
[06:06:09.544] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:59:0)
[06:06:09.544] Timer tick, asking Buildbucket for the build status
[06:06:09.715] Build 8871383406282877680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:06:09.715] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:60:0) after 1m0s
[06:07:09.918] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:60:0)
[06:07:09.918] Timer tick, asking Buildbucket for the build status
[06:07:09.986] Build 8871383406282877680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:07:09.986] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:61:0) after 1m0s
[06:08:10.191] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:61:0)
[06:08:10.191] Timer tick, asking Buildbucket for the build status
[06:08:10.297] Build 8871383406282877680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:08:10.297] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:62:0) after 1m0s
[06:09:10.411] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:62:0)
[06:09:10.411] Timer tick, asking Buildbucket for the build status
[06:09:10.586] Build 8871383406282877680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:09:10.586] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:63:0) after 1m0s
[06:10:10.769] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:63:0)
[06:10:10.769] Timer tick, asking Buildbucket for the build status
[06:10:10.914] Build 8871383406282877680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:10:10.914] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:64:0) after 1m0s
[06:11:11.071] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:64:0)
[06:11:11.071] Timer tick, asking Buildbucket for the build status
[06:11:11.471] Build 8871383406282877680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:11:11.471] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:65:0) after 1m0s
[06:12:11.574] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:65:0)
[06:12:11.574] Timer tick, asking Buildbucket for the build status
[06:12:12.829] Build 8871383406282877680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:12:12.829] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:66:0) after 1m0s
[06:13:13.041] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:66:0)
[06:13:13.041] Timer tick, asking Buildbucket for the build status
[06:13:13.138] Build 8871383406282877680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:13:13.138] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:67:0) after 1m0s
[06:14:13.198] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:67:0)
[06:14:13.198] Timer tick, asking Buildbucket for the build status
[06:14:13.514] Build 8871383406282877680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:14:13.514] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:68:0) after 1m0s
[06:15:13.537] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:68:0)
[06:15:13.537] Timer tick, asking Buildbucket for the build status
[06:15:13.602] Build 8871383406282877680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:15:13.602] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:69:0) after 1m0s
[06:16:13.631] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:69:0)
[06:16:13.631] Timer tick, asking Buildbucket for the build status
[06:16:13.725] Build 8871383406282877680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:16:13.725] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:70:0) after 1m0s
[06:17:13.800] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:70:0)
[06:17:13.800] Timer tick, asking Buildbucket for the build status
[06:17:13.921] Build 8871383406282877680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:17:13.921] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:71:0) after 1m0s
[06:18:14.022] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:71:0)
[06:18:14.022] Timer tick, asking Buildbucket for the build status
[06:18:14.411] Build 8871383406282877680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:18:14.411] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:72:0) after 1m0s
[06:19:14.482] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:72:0)
[06:19:14.482] Timer tick, asking Buildbucket for the build status
[06:19:14.891] Build 8871383406282877680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:19:14.891] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:73:0) after 1m0s
[06:20:14.974] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:73:0)
[06:20:14.974] Timer tick, asking Buildbucket for the build status
[06:20:15.121] Build 8871383406282877680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:20:15.121] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:74:0) after 1m0s
[06:21:15.205] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:74:0)
[06:21:15.205] Timer tick, asking Buildbucket for the build status
[06:21:15.362] Build 8871383406282877680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:21:15.362] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:75:0) after 1m0s
[06:22:15.432] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:75:0)
[06:22:15.432] Timer tick, asking Buildbucket for the build status
[06:22:15.630] Build 8871383406282877680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:22:15.630] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:76:0) after 1m0s
[06:23:15.781] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:76:0)
[06:23:15.781] Timer tick, asking Buildbucket for the build status
[06:23:15.879] Build 8871383406282877680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:23:15.879] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:77:0) after 1m0s
[06:24:15.942] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:77:0)
[06:24:15.942] Timer tick, asking Buildbucket for the build status
[06:24:16.198] Build 8871383406282877680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:24:16.198] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:78:0) after 1m0s
[06:25:16.189] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:78:0)
[06:25:16.189] Timer tick, asking Buildbucket for the build status
[06:25:16.386] Build 8871383406282877680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:25:16.386] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:79:0) after 1m0s
[06:26:16.434] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:79:0)
[06:26:16.434] Timer tick, asking Buildbucket for the build status
[06:26:16.568] Build 8871383406282877680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:26:16.568] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:80:0) after 1m0s
[06:27:16.604] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:80:0)
[06:27:16.604] Timer tick, asking Buildbucket for the build status
[06:27:16.673] Build 8871383406282877680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:27:16.674] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:81:0) after 1m0s
[06:28:16.726] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:81:0)
[06:28:16.726] Timer tick, asking Buildbucket for the build status
[06:28:17.012] Build 8871383406282877680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:28:17.012] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:82:0) after 1m0s
[06:29:17.083] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:82:0)
[06:29:17.083] Timer tick, asking Buildbucket for the build status
[06:29:17.415] Build 8871383406282877680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:29:17.415] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:83:0) after 1m0s
[06:30:17.444] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:83:0)
[06:30:17.444] Timer tick, asking Buildbucket for the build status
[06:30:17.631] Build 8871383406282877680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:30:17.631] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:84:0) after 1m0s
[06:31:17.690] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:84:0)
[06:31:17.690] Timer tick, asking Buildbucket for the build status
[06:31:17.744] Build 8871383406282877680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:31:17.744] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:85:0) after 1m0s
[06:32:17.791] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:85:0)
[06:32:17.791] Timer tick, asking Buildbucket for the build status
[06:32:18.025] Build 8871383406282877680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:32:18.025] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:86:0) after 1m0s
[06:33:18.074] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:86:0)
[06:33:18.074] Timer tick, asking Buildbucket for the build status
[06:33:18.157] Build 8871383406282877680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:33:18.157] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:87:0) after 1m0s
[06:34:18.217] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:87:0)
[06:34:18.217] Timer tick, asking Buildbucket for the build status
[06:34:18.504] Build 8871383406282877680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:34:18.504] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:88:0) after 1m0s
[06:35:18.636] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:88:0)
[06:35:18.636] Timer tick, asking Buildbucket for the build status
[06:35:21.437] Build 8871383406282877680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:35:21.437] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:89:0) after 1m0s
[06:36:21.453] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:89:0)
[06:36:21.453] Timer tick, asking Buildbucket for the build status
[06:36:21.525] Build 8871383406282877680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:36:21.525] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:90:0) after 1m0s
[06:37:21.555] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:90:0)
[06:37:21.555] Timer tick, asking Buildbucket for the build status
[06:37:21.806] Build 8871383406282877680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:37:21.806] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:91:0) after 1m0s
[06:38:21.888] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:91:0)
[06:38:21.888] Timer tick, asking Buildbucket for the build status
[06:38:21.952] Build 8871383406282877680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:38:21.952] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:92:0) after 1m0s
[06:39:21.990] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:92:0)
[06:39:21.990] Timer tick, asking Buildbucket for the build status
[06:39:22.109] Build 8871383406282877680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:39:22.109] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:93:0) after 1m0s
[06:40:22.140] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:93:0)
[06:40:22.140] Timer tick, asking Buildbucket for the build status
[06:40:22.437] Build 8871383406282877680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:40:22.437] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:94:0) after 1m0s
[06:41:22.439] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:94:0)
[06:41:22.439] Timer tick, asking Buildbucket for the build status
[06:41:22.705] Build 8871383406282877680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:41:22.705] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:95:0) after 1m0s
[06:42:22.738] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:95:0)
[06:42:22.739] Timer tick, asking Buildbucket for the build status
[06:42:22.818] Build 8871383406282877680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:42:22.818] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:96:0) after 1m0s
[06:43:22.797] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:96:0)
[06:43:22.797] Timer tick, asking Buildbucket for the build status
[06:43:22.874] Build 8871383406282877680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:43:22.874] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:97:0) after 1m0s
[06:44:22.944] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:97:0)
[06:44:22.944] Timer tick, asking Buildbucket for the build status
[06:44:23.213] Build 8871383406282877680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:44:23.213] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:98:0) after 1m0s
[06:45:23.279] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:98:0)
[06:45:23.279] Timer tick, asking Buildbucket for the build status
[06:45:23.361] Build 8871383406282877680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:45:23.361] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:99:0) after 1m0s
[06:46:23.455] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:99:0)
[06:46:23.455] Timer tick, asking Buildbucket for the build status
[06:46:23.580] Build 8871383406282877680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:46:23.580] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:100:0) after 1m0s
[06:47:23.693] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:100:0)
[06:47:23.693] Timer tick, asking Buildbucket for the build status
[06:47:23.815] Build 8871383406282877680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:47:23.815] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:101:0) after 1m0s
[06:48:24.139] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:101:0)
[06:48:24.139] Timer tick, asking Buildbucket for the build status
[06:48:24.207] Build 8871383406282877680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:48:24.207] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:102:0) after 1m0s
[06:49:24.363] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:102:0)
[06:49:24.363] Timer tick, asking Buildbucket for the build status
[06:49:24.561] Build 8871383406282877680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:49:24.561] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:103:0) after 1m0s
[06:50:24.603] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:103:0)
[06:50:24.603] Timer tick, asking Buildbucket for the build status
[06:50:24.663] Build 8871383406282877680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:50:24.663] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:104:0) after 1m0s
[06:51:24.827] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:104:0)
[06:51:24.827] Timer tick, asking Buildbucket for the build status
[06:51:24.932] Build 8871383406282877680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:51:24.932] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:105:0) after 1m0s
[06:52:25.054] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:105:0)
[06:52:25.054] Timer tick, asking Buildbucket for the build status
[06:52:25.383] Build 8871383406282877680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:52:25.383] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:106:0) after 1m0s
[06:53:25.515] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:106:0)
[06:53:25.515] Timer tick, asking Buildbucket for the build status
[06:53:25.814] Build 8871383406282877680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:53:25.814] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:107:0) after 1m0s
[06:54:25.848] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:107:0)
[06:54:25.848] Timer tick, asking Buildbucket for the build status
[06:54:26.102] Build 8871383406282877680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:54:26.102] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:108:0) after 1m0s
[06:55:26.147] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:108:0)
[06:55:26.147] Timer tick, asking Buildbucket for the build status
[06:55:26.258] Build 8871383406282877680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:55:26.258] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:109:0) after 1m0s
[06:56:26.291] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:109:0)
[06:56:26.291] Timer tick, asking Buildbucket for the build status
[06:56:26.371] Build 8871383406282877680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:56:26.371] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:110:0) after 1m0s
[06:57:26.452] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:110:0)
[06:57:26.452] Timer tick, asking Buildbucket for the build status
[06:57:26.741] Build 8871383406282877680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:57:26.741] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:111:0) after 1m0s
[06:58:26.829] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:111:0)
[06:58:26.829] Timer tick, asking Buildbucket for the build status
[06:58:27.131] Build 8871383406282877680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:58:27.131] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:112:0) after 1m0s
[06:59:27.254] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:112:0)
[06:59:27.254] Timer tick, asking Buildbucket for the build status
[06:59:27.427] Build 8871383406282877680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:59:27.427] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:113:0) after 1m0s
[07:00:27.471] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:113:0)
[07:00:27.471] Timer tick, asking Buildbucket for the build status
[07:00:27.571] Build 8871383406282877680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:00:27.571] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:114:0) after 1m0s
[07:01:27.687] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:114:0)
[07:01:27.687] Timer tick, asking Buildbucket for the build status
[07:01:27.766] Build 8871383406282877680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:01:27.766] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:115:0) after 1m0s
[07:02:27.793] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:115:0)
[07:02:27.793] Timer tick, asking Buildbucket for the build status
[07:02:27.933] Build 8871383406282877680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:02:27.933] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:116:0) after 1m0s
[07:03:27.991] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:116:0)
[07:03:27.991] Timer tick, asking Buildbucket for the build status
[07:03:30.370] Build 8871383406282877680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:03:30.370] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:117:0) after 1m0s
[07:04:30.638] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:117:0)
[07:04:30.638] Timer tick, asking Buildbucket for the build status
[07:04:31.077] Build 8871383406282877680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:04:31.077] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:118:0) after 1m0s
[07:05:31.068] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:118:0)
[07:05:31.068] Timer tick, asking Buildbucket for the build status
[07:05:31.185] Build 8871383406282877680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:05:31.185] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:119:0) after 1m0s
[07:06:31.273] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:119:0)
[07:06:31.273] Timer tick, asking Buildbucket for the build status
[07:06:31.352] Build 8871383406282877680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:06:31.352] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:120:0) after 1m0s
[07:07:31.521] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:120:0)
[07:07:31.521] Timer tick, asking Buildbucket for the build status
[07:07:31.634] Build 8871383406282877680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:07:31.634] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:121:0) after 1m0s
[07:08:31.754] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:121:0)
[07:08:31.754] Timer tick, asking Buildbucket for the build status
[07:08:31.806] Build 8871383406282877680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:08:31.806] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:122:0) after 1m0s
[07:09:31.868] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:122:0)
[07:09:31.868] Timer tick, asking Buildbucket for the build status
[07:09:32.318] Build 8871383406282877680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:09:32.318] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:123:0) after 1m0s
[07:10:32.375] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:123:0)
[07:10:32.375] Timer tick, asking Buildbucket for the build status
[07:10:32.436] Build 8871383406282877680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:10:32.436] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:124:0) after 1m0s
[07:11:32.665] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:124:0)
[07:11:32.665] Timer tick, asking Buildbucket for the build status
[07:11:32.734] Build 8871383406282877680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:11:32.734] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:125:0) after 1m0s
[07:12:32.871] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:125:0)
[07:12:32.871] Timer tick, asking Buildbucket for the build status
[07:12:32.940] Build 8871383406282877680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:12:32.940] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:126:0) after 1m0s
[07:13:33.069] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:126:0)
[07:13:33.069] Timer tick, asking Buildbucket for the build status
[07:13:33.172] Build 8871383406282877680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:13:33.172] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:127:0) after 1m0s
[07:14:33.336] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:127:0)
[07:14:33.336] Timer tick, asking Buildbucket for the build status
[07:14:33.791] Build 8871383406282877680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:14:33.791] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:128:0) after 1m0s
[07:15:33.902] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:128:0)
[07:15:33.902] Timer tick, asking Buildbucket for the build status
[07:15:34.375] Build 8871383406282877680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:15:34.375] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:129:0) after 1m0s
[07:16:34.506] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:129:0)
[07:16:34.506] Timer tick, asking Buildbucket for the build status
[07:16:34.625] Build 8871383406282877680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:16:34.625] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:130:0) after 1m0s
[07:17:34.667] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:130:0)
[07:17:34.667] Timer tick, asking Buildbucket for the build status
[07:17:34.755] Build 8871383406282877680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:17:34.755] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:131:0) after 1m0s
[07:18:34.808] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:131:0)
[07:18:34.808] Timer tick, asking Buildbucket for the build status
[07:18:34.916] Build 8871383406282877680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:18:34.916] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:132:0) after 1m0s
[07:19:35.052] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:132:0)
[07:19:35.052] Timer tick, asking Buildbucket for the build status
[07:19:35.159] Build 8871383406282877680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:19:35.159] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:133:0) after 1m0s
[07:20:35.198] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:133:0)
[07:20:35.198] Timer tick, asking Buildbucket for the build status
[07:20:35.321] Build 8871383406282877680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:20:35.321] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:134:0) after 1m0s
[07:21:35.337] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:134:0)
[07:21:35.337] Timer tick, asking Buildbucket for the build status
[07:21:35.414] Build 8871383406282877680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:21:35.414] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:135:0) after 1m0s
[07:22:35.438] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:135:0)
[07:22:35.438] Timer tick, asking Buildbucket for the build status
[07:22:35.501] Build 8871383406282877680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:22:35.501] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:136:0) after 1m0s
[07:23:35.539] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:136:0)
[07:23:35.539] Timer tick, asking Buildbucket for the build status
[07:23:35.626] Build 8871383406282877680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:23:35.626] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:137:0) after 1m0s
[07:24:35.651] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:137:0)
[07:24:35.651] Timer tick, asking Buildbucket for the build status
[07:24:35.998] Build 8871383406282877680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:24:35.998] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:138:0) after 1m0s
[07:25:36.037] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:138:0)
[07:25:36.037] Timer tick, asking Buildbucket for the build status
[07:25:36.169] Build 8871383406282877680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:25:36.169] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:139:0) after 1m0s
[07:26:36.247] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:139:0)
[07:26:36.247] Timer tick, asking Buildbucket for the build status
[07:26:36.354] Build 8871383406282877680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:26:36.354] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:140:0) after 1m0s
[07:27:36.382] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:140:0)
[07:27:36.382] Timer tick, asking Buildbucket for the build status
[07:27:36.579] Build 8871383406282877680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:27:36.579] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:141:0) after 1m0s
[07:28:36.746] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:141:0)
[07:28:36.746] Timer tick, asking Buildbucket for the build status
[07:28:37.101] Build 8871383406282877680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:28:37.101] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:142:0) after 1m0s
[07:29:37.127] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:142:0)
[07:29:37.127] Timer tick, asking Buildbucket for the build status
[07:29:37.311] Build 8871383406282877680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:29:37.311] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:143:0) after 1m0s
[07:30:37.371] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:143:0)
[07:30:37.371] Timer tick, asking Buildbucket for the build status
[07:30:37.505] Build 8871383406282877680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:30:37.505] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:144:0) after 1m0s
[07:31:37.596] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:144:0)
[07:31:37.596] Timer tick, asking Buildbucket for the build status
[07:31:37.700] Build 8871383406282877680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:31:37.700] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:145:0) after 1m0s
[07:32:37.804] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:145:0)
[07:32:37.804] Timer tick, asking Buildbucket for the build status
[07:32:37.927] Build 8871383406282877680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:32:37.927] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:146:0) after 1m0s
[07:33:37.952] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:146:0)
[07:33:37.952] Timer tick, asking Buildbucket for the build status
[07:33:38.321] Build 8871383406282877680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:33:38.321] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:147:0) after 1m0s
[07:34:38.418] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:147:0)
[07:34:38.418] Timer tick, asking Buildbucket for the build status
[07:34:38.555] Build 8871383406282877680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:34:38.555] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:148:0) after 1m0s
[07:35:38.656] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:148:0)
[07:35:38.656] Timer tick, asking Buildbucket for the build status
[07:35:38.749] Build 8871383406282877680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:35:38.749] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:149:0) after 1m0s
[07:36:38.817] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:149:0)
[07:36:38.817] Timer tick, asking Buildbucket for the build status
[07:36:39.032] Build 8871383406282877680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:36:39.032] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:150:0) after 1m0s
[07:37:39.064] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:150:0)
[07:37:39.064] Timer tick, asking Buildbucket for the build status
[07:37:39.176] Build 8871383406282877680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:37:39.176] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:151:0) after 1m0s
[07:38:39.242] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:151:0)
[07:38:39.242] Timer tick, asking Buildbucket for the build status
[07:38:39.346] Build 8871383406282877680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:38:39.346] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:152:0) after 1m0s
[07:39:39.451] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:152:0)
[07:39:39.451] Timer tick, asking Buildbucket for the build status
[07:39:39.584] Build 8871383406282877680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:39:39.584] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:153:0) after 1m0s
[07:40:39.627] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:153:0)
[07:40:39.627] Timer tick, asking Buildbucket for the build status
[07:40:39.797] Build 8871383406282877680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:40:39.797] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:154:0) after 1m0s
[07:41:39.893] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:154:0)
[07:41:39.893] Timer tick, asking Buildbucket for the build status
[07:41:40.094] Build 8871383406282877680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:41:40.094] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:155:0) after 1m0s
[07:42:40.175] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:155:0)
[07:42:40.176] Timer tick, asking Buildbucket for the build status
[07:42:40.275] Build 8871383406282877680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:42:40.275] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:156:0) after 1m0s
[07:43:40.371] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:156:0)
[07:43:40.371] Timer tick, asking Buildbucket for the build status
[07:43:40.477] Build 8871383406282877680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:43:40.477] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:157:0) after 1m0s
[07:44:40.601] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:157:0)
[07:44:40.601] Timer tick, asking Buildbucket for the build status
[07:44:40.747] Build 8871383406282877680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:44:40.747] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:158:0) after 1m0s
[07:45:40.823] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:158:0)
[07:45:40.823] Timer tick, asking Buildbucket for the build status
[07:45:40.956] Build 8871383406282877680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:45:40.956] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:159:0) after 1m0s
[07:46:41.095] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:159:0)
[07:46:41.095] Timer tick, asking Buildbucket for the build status
[07:46:41.324] Build 8871383406282877680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:46:41.324] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:160:0) after 1m0s
[07:47:41.359] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:160:0)
[07:47:41.359] Timer tick, asking Buildbucket for the build status
[07:47:41.485] Build 8871383406282877680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:47:41.485] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:161:0) after 1m0s
[07:48:41.546] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:161:0)
[07:48:41.546] Timer tick, asking Buildbucket for the build status
[07:48:41.714] Build 8871383406282877680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:48:41.714] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:162:0) after 1m0s
[07:49:41.748] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:162:0)
[07:49:41.748] Timer tick, asking Buildbucket for the build status
[07:49:41.902] Build 8871383406282877680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:49:41.902] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:163:0) after 1m0s
[07:50:41.934] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:163:0)
[07:50:41.934] Timer tick, asking Buildbucket for the build status
[07:50:42.094] Build 8871383406282877680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:50:42.094] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:164:0) after 1m0s
[07:51:42.160] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:164:0)
[07:51:42.160] Timer tick, asking Buildbucket for the build status
[07:51:42.388] Build 8871383406282877680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:51:42.388] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:165:0) after 1m0s
[07:52:42.483] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:165:0)
[07:52:42.483] Timer tick, asking Buildbucket for the build status
[07:52:42.598] Build 8871383406282877680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:52:42.598] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:166:0) after 1m0s
[07:53:42.644] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:166:0)
[07:53:42.644] Timer tick, asking Buildbucket for the build status
[07:53:42.730] Build 8871383406282877680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:53:42.730] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:167:0) after 1m0s
[07:54:42.719] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:167:0)
[07:54:42.719] Timer tick, asking Buildbucket for the build status
[07:54:43.125] Build 8871383406282877680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:54:43.125] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:168:0) after 1m0s
[07:55:43.247] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:168:0)
[07:55:43.247] Timer tick, asking Buildbucket for the build status
[07:55:43.375] Build 8871383406282877680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:55:43.375] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:169:0) after 1m0s
[07:56:43.471] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:169:0)
[07:56:43.471] Timer tick, asking Buildbucket for the build status
[07:56:43.728] Build 8871383406282877680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:56:43.728] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:170:0) after 1m0s
[07:57:43.756] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:170:0)
[07:57:43.756] Timer tick, asking Buildbucket for the build status
[07:57:43.814] Build 8871383406282877680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:57:43.814] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:171:0) after 1m0s
[07:58:43.864] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:171:0)
[07:58:43.864] Timer tick, asking Buildbucket for the build status
[07:58:43.983] Build 8871383406282877680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:58:43.983] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:172:0) after 1m0s
[07:59:44.033] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:172:0)
[07:59:44.033] Timer tick, asking Buildbucket for the build status
[07:59:46.725] Build 8871383406282877680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:59:46.725] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:173:0) after 1m0s
[08:00:46.845] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:173:0)
[08:00:46.845] Timer tick, asking Buildbucket for the build status
[08:00:46.932] Build 8871383406282877680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:00:46.932] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:174:0) after 1m0s
[08:01:47.065] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:174:0)
[08:01:47.065] Timer tick, asking Buildbucket for the build status
[08:01:47.168] Build 8871383406282877680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:01:47.168] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:175:0) after 1m0s
[08:02:47.322] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:175:0)
[08:02:47.322] Timer tick, asking Buildbucket for the build status
[08:02:47.458] Build 8871383406282877680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:02:47.458] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:176:0) after 1m0s
[08:03:47.556] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:176:0)
[08:03:47.556] Timer tick, asking Buildbucket for the build status
[08:03:47.676] Build 8871383406282877680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:03:47.676] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:177:0) after 1m0s
[08:04:47.794] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:177:0)
[08:04:47.794] Timer tick, asking Buildbucket for the build status
[08:04:47.956] Build 8871383406282877680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:04:47.956] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:178:0) after 1m0s
[08:05:48.064] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:178:0)
[08:05:48.064] Timer tick, asking Buildbucket for the build status
[08:05:48.383] Build 8871383406282877680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:05:48.383] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:179:0) after 1m0s
[08:06:48.505] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:179:0)
[08:06:48.505] Timer tick, asking Buildbucket for the build status
[08:06:48.582] Build 8871383406282877680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:06:48.582] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:180:0) after 1m0s
[08:07:48.694] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:180:0)
[08:07:48.694] Timer tick, asking Buildbucket for the build status
[08:07:48.823] Build 8871383406282877680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:07:48.823] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:181:0) after 1m0s
[08:08:48.861] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:181:0)
[08:08:48.861] Timer tick, asking Buildbucket for the build status
[08:08:49.042] Build 8871383406282877680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:08:49.042] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036813468241924400:182:0) after 1m0s
[08:09:47.804] Received PubSub notification, asking Buildbucket for the build status
[08:09:48.053] Build 8871383406282877680: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[08:09:48.053] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1597997383097376",
"created_by": "project:chromium",
"created_ts": "1597986516643231",
"id": "8871383406282877680",
"parameters_json": "{\"builder_name\": \"win-official\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"6336337c1d7b1de10a5ed3ce30362b12ba60acf3\"}}",
"project": "chromium",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"$build/chromium_tests\": {\"bucketed_triggers\": true}, \"$build/goma\": {\"enable_ats\": true, \"rpc_extra_params\": \"?prod\", \"server_host\": \"goma.chromium.org\"}, \"$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-7-oles\", \"branch\": \"refs/heads/master\", \"buildername\": \"win-official\", \"buildnumber\": 386, \"got_angle_revision\": \"e689d3164f3457ee8dcceb0a129233638b16c32a\", \"got_dawn_revision\": \"e8dc820fbc1e28eba80b8e8c49bb3e566874f4e7\", \"got_nacl_revision\": \"3008f9e6de122325d8f9dbf02f7cdd51fa1ec306\", \"got_revision\": \"6336337c1d7b1de10a5ed3ce30362b12ba60acf3\", \"got_revision_cp\": \"refs/heads/master@{#800465}\", \"got_swarming_client_revision\": \"4c095d04179dc725a300085ae21fe3b79900d072\", \"got_swiftshader_revision\": \"44e1791f100c18aa8cbfc4fef18727f32f6b9eba\", \"got_v8_revision\": \"a64aed2333abf49e494d2a5ce24bbd14fff19f60\", \"got_v8_revision_cp\": \"refs/heads/8.6.395@{#1}\", \"got_webrtc_revision\": \"ea7fbfb9665bfa5e01ea375df94cc4421c8655ef\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#31977}\", \"mastername\": \"chromium\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"6336337c1d7b1de10a5ed3ce30362b12ba60acf3\"}, \"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-7-oles\"], \"image\": [\"chrome-win10-1703-20081700-d9392568d25\"], \"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\": [\"5498-3454aa6\"], \"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": "1597986559777656",
"status": "COMPLETED",
"status_changed_ts": "1597997383391544",
"tags": [
"build_address:luci.chromium.ci/win-official/386",
"builder:win-official",
"buildset:commit/git/6336337c1d7b1de10a5ed3ce30362b12ba60acf3",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/6336337c1d7b1de10a5ed3ce30362b12ba60acf3",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9036813468241924400",
"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/8871383406282877680/+/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:4e283cba9b183e10",
"user_agent:luci-scheduler"
],
"updated_ts": "1597997383407608",
"url": "https://ci.chromium.org/b/8871383406282877680",
"utcnow_ts": "1597997388037577"
}
}
[08:09:48.053] Invocation finished in 3h1m12.662001094s with status SUCCEEDED