[04:48:26.442] New invocation is queued and will start shortly
[04:48:27.572] Starting the invocation (attempt 1)
[04:48:27.612] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[04:48:27.612] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[04:48:27.612] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9025671309065595504",
"parameters_json": "{\"builder_name\":\"win32-official\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"91a15528a34573d7be11f786cb8cdfcefd40677f\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win32-official",
"scheduler_invocation_id:9025671309065595504",
"scheduler_job_id:chromium/win32-official",
"user_agent:luci-scheduler",
"buildset:commit/git/91a15528a34573d7be11f786cb8cdfcefd40677f",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/91a15528a34573d7be11f786cb8cdfcefd40677f",
"gitiles_ref:refs/heads/master"
]
}
[04:48:28.326] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1608612507695103",
"id": "8860241247089192864",
"parameters_json": "{\"builder_name\": \"win32-official\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"91a15528a34573d7be11f786cb8cdfcefd40677f\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1608612508129528",
"tags": [
"build_address:luci.chromium.ci/win32-official/1164",
"builder:win32-official",
"buildset:commit/git/91a15528a34573d7be11f786cb8cdfcefd40677f",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/91a15528a34573d7be11f786cb8cdfcefd40677f",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9025671309065595504",
"scheduler_job_id:chromium/win32-official",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8860241247089192864/+/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": "1608612508129731",
"url": "https://ci.chromium.org/b/8860241247089192864",
"utcnow_ts": "1608612508317512"
}
}
[04:48:28.326] Task URL: https://ci.chromium.org/b/8860241247089192864
[04:48:28.326] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:2:0) after 1m0s
[04:48:34.470] Received PubSub notification, asking Buildbucket for the build status
[04:48:34.575] Build 8860241247089192864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:49:28.375] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:2:0)
[04:49:28.375] Timer tick, asking Buildbucket for the build status
[04:49:28.691] Build 8860241247089192864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:49:28.691] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:4:0) after 1m0s
[04:50:28.712] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:4:0)
[04:50:28.712] Timer tick, asking Buildbucket for the build status
[04:50:28.817] Build 8860241247089192864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:50:28.817] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:5:0) after 1m0s
[04:51:28.870] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:5:0)
[04:51:28.870] Timer tick, asking Buildbucket for the build status
[04:51:29.312] Build 8860241247089192864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:51:29.312] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:6:0) after 1m0s
[04:52:29.347] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:6:0)
[04:52:29.347] Timer tick, asking Buildbucket for the build status
[04:52:29.407] Build 8860241247089192864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:52:29.407] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:7:0) after 1m0s
[04:53:29.471] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:7:0)
[04:53:29.471] Timer tick, asking Buildbucket for the build status
[04:53:29.603] Build 8860241247089192864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:53:29.603] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:8:0) after 1m0s
[04:54:29.606] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:8:0)
[04:54:29.606] Timer tick, asking Buildbucket for the build status
[04:54:29.820] Build 8860241247089192864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:54:29.820] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:9:0) after 1m0s
[04:55:29.908] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:9:0)
[04:55:29.908] Timer tick, asking Buildbucket for the build status
[04:55:30.322] Build 8860241247089192864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:55:30.322] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:10:0) after 1m0s
[04:56:30.344] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:10:0)
[04:56:30.344] Timer tick, asking Buildbucket for the build status
[04:56:30.863] Build 8860241247089192864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:56:30.863] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:11:0) after 1m0s
[04:57:30.885] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:11:0)
[04:57:30.885] Timer tick, asking Buildbucket for the build status
[04:57:30.997] Build 8860241247089192864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:57:30.997] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:12:0) after 1m0s
[04:58:31.615] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:12:0)
[04:58:31.615] Timer tick, asking Buildbucket for the build status
[04:58:31.748] Build 8860241247089192864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:58:31.748] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:13:0) after 1m0s
[04:59:31.817] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:13:0)
[04:59:31.817] Timer tick, asking Buildbucket for the build status
[04:59:32.443] Build 8860241247089192864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:59:32.443] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:14:0) after 1m0s
[05:00:34.698] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:14:0)
[05:00:34.698] Timer tick, asking Buildbucket for the build status
[05:00:34.850] Build 8860241247089192864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:00:34.850] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:15:0) after 1m0s
[05:01:34.941] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:15:0)
[05:01:34.941] Timer tick, asking Buildbucket for the build status
[05:01:35.275] Build 8860241247089192864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:01:35.275] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:16:0) after 1m0s
[05:02:35.253] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:16:0)
[05:02:35.253] Timer tick, asking Buildbucket for the build status
[05:02:35.492] Build 8860241247089192864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:02:35.492] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:17:0) after 1m0s
[05:03:35.479] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:17:0)
[05:03:35.479] Timer tick, asking Buildbucket for the build status
[05:03:35.628] Build 8860241247089192864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:03:35.628] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:18:0) after 1m0s
[05:04:35.640] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:18:0)
[05:04:35.640] Timer tick, asking Buildbucket for the build status
[05:04:35.831] Build 8860241247089192864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:04:35.831] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:19:0) after 1m0s
[05:05:35.934] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:19:0)
[05:05:35.934] Timer tick, asking Buildbucket for the build status
[05:05:36.138] Build 8860241247089192864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:05:36.138] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:20:0) after 1m0s
[05:06:36.125] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:20:0)
[05:06:36.125] Timer tick, asking Buildbucket for the build status
[05:06:36.354] Build 8860241247089192864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:06:36.354] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:21:0) after 1m0s
[05:07:36.431] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:21:0)
[05:07:36.431] Timer tick, asking Buildbucket for the build status
[05:07:36.581] Build 8860241247089192864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:07:36.581] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:22:0) after 1m0s
[05:08:36.596] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:22:0)
[05:08:36.596] Timer tick, asking Buildbucket for the build status
[05:08:36.738] Build 8860241247089192864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:08:36.738] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:23:0) after 1m0s
[05:09:36.857] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:23:0)
[05:09:36.857] Timer tick, asking Buildbucket for the build status
[05:09:36.981] Build 8860241247089192864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:09:36.981] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:24:0) after 1m0s
[05:10:37.048] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:24:0)
[05:10:37.048] Timer tick, asking Buildbucket for the build status
[05:10:37.133] Build 8860241247089192864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:10:37.133] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:25:0) after 1m0s
[05:11:37.146] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:25:0)
[05:11:37.146] Timer tick, asking Buildbucket for the build status
[05:11:37.307] Build 8860241247089192864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:11:37.307] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:26:0) after 1m0s
[05:12:37.372] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:26:0)
[05:12:37.372] Timer tick, asking Buildbucket for the build status
[05:12:37.430] Build 8860241247089192864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:12:37.430] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:27:0) after 1m0s
[05:13:37.475] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:27:0)
[05:13:37.475] Timer tick, asking Buildbucket for the build status
[05:13:37.627] Build 8860241247089192864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:13:37.627] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:28:0) after 1m0s
[05:14:37.653] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:28:0)
[05:14:37.653] Timer tick, asking Buildbucket for the build status
[05:14:37.723] Build 8860241247089192864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:14:37.723] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:29:0) after 1m0s
[05:15:37.700] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:29:0)
[05:15:37.700] Timer tick, asking Buildbucket for the build status
[05:15:37.809] Build 8860241247089192864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:15:37.809] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:30:0) after 1m0s
[05:16:37.787] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:30:0)
[05:16:37.787] Timer tick, asking Buildbucket for the build status
[05:16:37.880] Build 8860241247089192864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:16:37.880] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:31:0) after 1m0s
[05:17:37.938] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:31:0)
[05:17:37.938] Timer tick, asking Buildbucket for the build status
[05:17:38.017] Build 8860241247089192864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:17:38.017] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:32:0) after 1m0s
[05:18:38.077] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:32:0)
[05:18:38.077] Timer tick, asking Buildbucket for the build status
[05:18:38.173] Build 8860241247089192864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:18:38.173] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:33:0) after 1m0s
[05:19:38.240] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:33:0)
[05:19:38.240] Timer tick, asking Buildbucket for the build status
[05:19:38.321] Build 8860241247089192864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:19:38.321] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:34:0) after 1m0s
[05:20:38.354] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:34:0)
[05:20:38.354] Timer tick, asking Buildbucket for the build status
[05:20:38.527] Build 8860241247089192864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:20:38.527] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:35:0) after 1m0s
[05:21:38.519] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:35:0)
[05:21:38.519] Timer tick, asking Buildbucket for the build status
[05:21:38.974] Build 8860241247089192864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:21:38.974] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:36:0) after 1m0s
[05:22:39.045] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:36:0)
[05:22:39.045] Timer tick, asking Buildbucket for the build status
[05:22:39.372] Build 8860241247089192864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:22:39.372] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:37:0) after 1m0s
[05:23:39.429] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:37:0)
[05:23:39.429] Timer tick, asking Buildbucket for the build status
[05:23:39.684] Build 8860241247089192864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:23:39.684] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:38:0) after 1m0s
[05:24:39.711] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:38:0)
[05:24:39.711] Timer tick, asking Buildbucket for the build status
[05:24:39.815] Build 8860241247089192864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:24:39.815] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:39:0) after 1m0s
[05:25:39.833] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:39:0)
[05:25:39.833] Timer tick, asking Buildbucket for the build status
[05:25:39.960] Build 8860241247089192864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:25:39.960] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:40:0) after 1m0s
[05:26:39.969] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:40:0)
[05:26:39.969] Timer tick, asking Buildbucket for the build status
[05:26:40.042] Build 8860241247089192864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:26:40.042] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:41:0) after 1m0s
[05:27:40.064] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:41:0)
[05:27:40.064] Timer tick, asking Buildbucket for the build status
[05:27:40.162] Build 8860241247089192864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:27:40.162] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:42:0) after 1m0s
[05:28:40.238] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:42:0)
[05:28:40.238] Timer tick, asking Buildbucket for the build status
[05:28:40.339] Build 8860241247089192864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:28:40.339] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:43:0) after 1m0s
[05:29:40.397] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:43:0)
[05:29:40.397] Timer tick, asking Buildbucket for the build status
[05:29:40.447] Build 8860241247089192864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:29:40.447] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:44:0) after 1m0s
[05:30:40.489] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:44:0)
[05:30:40.489] Timer tick, asking Buildbucket for the build status
[05:30:40.590] Build 8860241247089192864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:30:40.590] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:45:0) after 1m0s
[05:31:40.701] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:45:0)
[05:31:40.701] Timer tick, asking Buildbucket for the build status
[05:31:40.811] Build 8860241247089192864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:31:40.811] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:46:0) after 1m0s
[05:32:41.030] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:46:0)
[05:32:41.030] Timer tick, asking Buildbucket for the build status
[05:32:41.087] Build 8860241247089192864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:32:41.087] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:47:0) after 1m0s
[05:33:41.149] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:47:0)
[05:33:41.149] Timer tick, asking Buildbucket for the build status
[05:33:41.412] Build 8860241247089192864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:33:41.412] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:48:0) after 1m0s
[05:34:41.429] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:48:0)
[05:34:41.429] Timer tick, asking Buildbucket for the build status
[05:34:41.515] Build 8860241247089192864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:34:41.515] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:49:0) after 1m0s
[05:35:41.539] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:49:0)
[05:35:41.539] Timer tick, asking Buildbucket for the build status
[05:35:41.643] Build 8860241247089192864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:35:41.643] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:50:0) after 1m0s
[05:36:41.730] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:50:0)
[05:36:41.730] Timer tick, asking Buildbucket for the build status
[05:36:42.016] Build 8860241247089192864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:36:42.016] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:51:0) after 1m0s
[05:37:42.045] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:51:0)
[05:37:42.045] Timer tick, asking Buildbucket for the build status
[05:37:42.422] Build 8860241247089192864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:37:42.422] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:52:0) after 1m0s
[05:38:42.502] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:52:0)
[05:38:42.502] Timer tick, asking Buildbucket for the build status
[05:38:42.681] Build 8860241247089192864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:38:42.681] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:53:0) after 1m0s
[05:39:42.781] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:53:0)
[05:39:42.781] Timer tick, asking Buildbucket for the build status
[05:39:42.852] Build 8860241247089192864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:39:42.852] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:54:0) after 1m0s
[05:40:42.859] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:54:0)
[05:40:42.859] Timer tick, asking Buildbucket for the build status
[05:40:42.945] Build 8860241247089192864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:40:42.945] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:55:0) after 1m0s
[05:41:43.015] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:55:0)
[05:41:43.015] Timer tick, asking Buildbucket for the build status
[05:41:43.118] Build 8860241247089192864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:41:43.118] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:56:0) after 1m0s
[05:42:43.105] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:56:0)
[05:42:43.105] Timer tick, asking Buildbucket for the build status
[05:42:43.172] Build 8860241247089192864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:42:43.172] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:57:0) after 1m0s
[05:43:43.184] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:57:0)
[05:43:43.184] Timer tick, asking Buildbucket for the build status
[05:43:43.320] Build 8860241247089192864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:43:43.320] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:58:0) after 1m0s
[05:44:43.362] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:58:0)
[05:44:43.362] Timer tick, asking Buildbucket for the build status
[05:44:43.480] Build 8860241247089192864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:44:43.480] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:59:0) after 1m0s
[05:45:43.508] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:59:0)
[05:45:43.508] Timer tick, asking Buildbucket for the build status
[05:45:43.591] Build 8860241247089192864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:45:43.591] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:60:0) after 1m0s
[05:46:43.617] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:60:0)
[05:46:43.617] Timer tick, asking Buildbucket for the build status
[05:46:43.810] Build 8860241247089192864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:46:43.810] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:61:0) after 1m0s
[05:47:43.832] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:61:0)
[05:47:43.832] Timer tick, asking Buildbucket for the build status
[05:47:43.919] Build 8860241247089192864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:47:43.920] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:62:0) after 1m0s
[05:48:43.946] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:62:0)
[05:48:43.946] Timer tick, asking Buildbucket for the build status
[05:48:44.065] Build 8860241247089192864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:48:44.065] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:63:0) after 1m0s
[05:49:44.102] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:63:0)
[05:49:44.102] Timer tick, asking Buildbucket for the build status
[05:49:44.161] Build 8860241247089192864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:49:44.161] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:64:0) after 1m0s
[05:50:44.154] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:64:0)
[05:50:44.154] Timer tick, asking Buildbucket for the build status
[05:50:44.233] Build 8860241247089192864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:50:44.233] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:65:0) after 1m0s
[05:51:44.323] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:65:0)
[05:51:44.323] Timer tick, asking Buildbucket for the build status
[05:51:44.418] Build 8860241247089192864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:51:44.418] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:66:0) after 1m0s
[05:52:44.439] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:66:0)
[05:52:44.439] Timer tick, asking Buildbucket for the build status
[05:52:44.535] Build 8860241247089192864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:52:44.535] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:67:0) after 1m0s
[05:53:44.556] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:67:0)
[05:53:44.556] Timer tick, asking Buildbucket for the build status
[05:53:44.640] Build 8860241247089192864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:53:44.640] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:68:0) after 1m0s
[05:54:44.683] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:68:0)
[05:54:44.683] Timer tick, asking Buildbucket for the build status
[05:54:44.764] Build 8860241247089192864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:54:44.764] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:69:0) after 1m0s
[05:55:44.819] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:69:0)
[05:55:44.819] Timer tick, asking Buildbucket for the build status
[05:55:44.885] Build 8860241247089192864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:55:44.885] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:70:0) after 1m0s
[05:56:44.908] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:70:0)
[05:56:44.908] Timer tick, asking Buildbucket for the build status
[05:56:44.969] Build 8860241247089192864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:56:44.969] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:71:0) after 1m0s
[05:57:44.993] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:71:0)
[05:57:44.993] Timer tick, asking Buildbucket for the build status
[05:57:45.071] Build 8860241247089192864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:57:45.071] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:72:0) after 1m0s
[05:58:45.092] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:72:0)
[05:58:45.092] Timer tick, asking Buildbucket for the build status
[05:58:45.227] Build 8860241247089192864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:58:45.227] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:73:0) after 1m0s
[05:59:45.255] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:73:0)
[05:59:45.255] Timer tick, asking Buildbucket for the build status
[05:59:45.337] Build 8860241247089192864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:59:45.337] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:74:0) after 1m0s
[06:00:45.429] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:74:0)
[06:00:45.429] Timer tick, asking Buildbucket for the build status
[06:00:45.577] Build 8860241247089192864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:00:45.577] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:75:0) after 1m0s
[06:01:55.599] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:75:0)
[06:01:55.599] Timer tick, asking Buildbucket for the build status
[06:01:55.651] Build 8860241247089192864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:01:55.651] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:76:0) after 1m0s
[06:02:55.970] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:76:0)
[06:02:55.970] Timer tick, asking Buildbucket for the build status
[06:02:56.049] Build 8860241247089192864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:02:56.049] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:77:0) after 1m0s
[06:03:56.240] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:77:0)
[06:03:56.240] Timer tick, asking Buildbucket for the build status
[06:03:56.610] Build 8860241247089192864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:03:56.610] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:78:0) after 1m0s
[06:04:56.813] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:78:0)
[06:04:56.813] Timer tick, asking Buildbucket for the build status
[06:04:56.901] Build 8860241247089192864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:04:56.901] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:79:0) after 1m0s
[06:05:56.911] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:79:0)
[06:05:56.911] Timer tick, asking Buildbucket for the build status
[06:05:57.009] Build 8860241247089192864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:05:57.009] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:80:0) after 1m0s
[06:06:57.048] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:80:0)
[06:06:57.048] Timer tick, asking Buildbucket for the build status
[06:06:57.139] Build 8860241247089192864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:06:57.139] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:81:0) after 1m0s
[06:07:57.150] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:81:0)
[06:07:57.150] Timer tick, asking Buildbucket for the build status
[06:07:57.234] Build 8860241247089192864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:07:57.234] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:82:0) after 1m0s
[06:08:57.282] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:82:0)
[06:08:57.282] Timer tick, asking Buildbucket for the build status
[06:08:57.401] Build 8860241247089192864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:08:57.401] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:83:0) after 1m0s
[06:09:57.412] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:83:0)
[06:09:57.412] Timer tick, asking Buildbucket for the build status
[06:09:57.516] Build 8860241247089192864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:09:57.516] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:84:0) after 1m0s
[06:10:57.501] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:84:0)
[06:10:57.501] Timer tick, asking Buildbucket for the build status
[06:10:57.629] Build 8860241247089192864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:10:57.629] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:85:0) after 1m0s
[06:11:57.652] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:85:0)
[06:11:57.652] Timer tick, asking Buildbucket for the build status
[06:11:57.793] Build 8860241247089192864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:11:57.793] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:86:0) after 1m0s
[06:12:57.940] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:86:0)
[06:12:57.940] Timer tick, asking Buildbucket for the build status
[06:12:58.115] Build 8860241247089192864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:12:58.115] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:87:0) after 1m0s
[06:13:58.245] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:87:0)
[06:13:58.245] Timer tick, asking Buildbucket for the build status
[06:13:58.528] Build 8860241247089192864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:13:58.528] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:88:0) after 1m0s
[06:14:58.591] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:88:0)
[06:14:58.591] Timer tick, asking Buildbucket for the build status
[06:14:58.765] Build 8860241247089192864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:14:58.765] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:89:0) after 1m0s
[06:15:58.795] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:89:0)
[06:15:58.795] Timer tick, asking Buildbucket for the build status
[06:15:58.942] Build 8860241247089192864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:15:58.942] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:90:0) after 1m0s
[06:16:58.974] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:90:0)
[06:16:58.974] Timer tick, asking Buildbucket for the build status
[06:16:59.204] Build 8860241247089192864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:16:59.204] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:91:0) after 1m0s
[06:17:59.232] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:91:0)
[06:17:59.232] Timer tick, asking Buildbucket for the build status
[06:17:59.378] Build 8860241247089192864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:17:59.378] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:92:0) after 1m0s
[06:18:59.472] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:92:0)
[06:18:59.472] Timer tick, asking Buildbucket for the build status
[06:18:59.604] Build 8860241247089192864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:18:59.604] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:93:0) after 1m0s
[06:19:59.616] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:93:0)
[06:19:59.616] Timer tick, asking Buildbucket for the build status
[06:19:59.868] Build 8860241247089192864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:19:59.868] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:94:0) after 1m0s
[06:20:59.927] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:94:0)
[06:20:59.927] Timer tick, asking Buildbucket for the build status
[06:21:00.206] Build 8860241247089192864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:21:00.206] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:95:0) after 1m0s
[06:22:00.269] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:95:0)
[06:22:00.269] Timer tick, asking Buildbucket for the build status
[06:22:00.334] Build 8860241247089192864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:22:00.334] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:96:0) after 1m0s
[06:23:00.426] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:96:0)
[06:23:00.426] Timer tick, asking Buildbucket for the build status
[06:23:00.491] Build 8860241247089192864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:23:00.491] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:97:0) after 1m0s
[06:24:00.656] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:97:0)
[06:24:00.656] Timer tick, asking Buildbucket for the build status
[06:24:00.838] Build 8860241247089192864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:24:00.838] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:98:0) after 1m0s
[06:25:00.818] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:98:0)
[06:25:00.818] Timer tick, asking Buildbucket for the build status
[06:25:00.901] Build 8860241247089192864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:25:00.901] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:99:0) after 1m0s
[06:26:01.012] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:99:0)
[06:26:01.012] Timer tick, asking Buildbucket for the build status
[06:26:01.342] Build 8860241247089192864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:26:01.342] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:100:0) after 1m0s
[06:27:01.385] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:100:0)
[06:27:01.385] Timer tick, asking Buildbucket for the build status
[06:27:01.451] Build 8860241247089192864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:27:01.451] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:101:0) after 1m0s
[06:28:01.716] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:101:0)
[06:28:01.716] Timer tick, asking Buildbucket for the build status
[06:28:01.793] Build 8860241247089192864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:28:01.793] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:102:0) after 1m0s
[06:29:01.871] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:102:0)
[06:29:01.871] Timer tick, asking Buildbucket for the build status
[06:29:02.118] Build 8860241247089192864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:29:02.118] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:103:0) after 1m0s
[06:30:02.137] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:103:0)
[06:30:02.137] Timer tick, asking Buildbucket for the build status
[06:30:02.202] Build 8860241247089192864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:30:02.202] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:104:0) after 1m0s
[06:31:02.237] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:104:0)
[06:31:02.237] Timer tick, asking Buildbucket for the build status
[06:31:02.327] Build 8860241247089192864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:31:02.327] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:105:0) after 1m0s
[06:32:02.348] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:105:0)
[06:32:02.348] Timer tick, asking Buildbucket for the build status
[06:32:02.402] Build 8860241247089192864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:32:02.402] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:106:0) after 1m0s
[06:33:02.443] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:106:0)
[06:33:02.443] Timer tick, asking Buildbucket for the build status
[06:33:02.558] Build 8860241247089192864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:33:02.558] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:107:0) after 1m0s
[06:34:02.643] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:107:0)
[06:34:02.643] Timer tick, asking Buildbucket for the build status
[06:34:02.930] Build 8860241247089192864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:34:02.930] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:108:0) after 1m0s
[06:35:03.013] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:108:0)
[06:35:03.014] Timer tick, asking Buildbucket for the build status
[06:35:03.095] Build 8860241247089192864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:35:03.095] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:109:0) after 1m0s
[06:36:03.149] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:109:0)
[06:36:03.149] Timer tick, asking Buildbucket for the build status
[06:36:03.411] Build 8860241247089192864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:36:03.411] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:110:0) after 1m0s
[06:37:03.557] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:110:0)
[06:37:03.557] Timer tick, asking Buildbucket for the build status
[06:37:03.644] Build 8860241247089192864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:37:03.644] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:111:0) after 1m0s
[06:38:03.765] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:111:0)
[06:38:03.765] Timer tick, asking Buildbucket for the build status
[06:38:03.825] Build 8860241247089192864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:38:03.825] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:112:0) after 1m0s
[06:39:03.850] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:112:0)
[06:39:03.850] Timer tick, asking Buildbucket for the build status
[06:39:03.964] Build 8860241247089192864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:39:03.964] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:113:0) after 1m0s
[06:40:03.992] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:113:0)
[06:40:03.992] Timer tick, asking Buildbucket for the build status
[06:40:04.067] Build 8860241247089192864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:40:04.067] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:114:0) after 1m0s
[06:41:04.105] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:114:0)
[06:41:04.105] Timer tick, asking Buildbucket for the build status
[06:41:04.164] Build 8860241247089192864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:41:04.164] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:115:0) after 1m0s
[06:42:04.191] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:115:0)
[06:42:04.191] Timer tick, asking Buildbucket for the build status
[06:42:04.325] Build 8860241247089192864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:42:04.325] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:116:0) after 1m0s
[06:43:04.431] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:116:0)
[06:43:04.431] Timer tick, asking Buildbucket for the build status
[06:43:04.493] Build 8860241247089192864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:43:04.493] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:117:0) after 1m0s
[06:44:04.565] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:117:0)
[06:44:04.565] Timer tick, asking Buildbucket for the build status
[06:44:04.692] Build 8860241247089192864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:44:04.692] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:118:0) after 1m0s
[06:45:04.791] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:118:0)
[06:45:04.791] Timer tick, asking Buildbucket for the build status
[06:45:04.848] Build 8860241247089192864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:45:04.848] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:119:0) after 1m0s
[06:46:04.945] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:119:0)
[06:46:04.945] Timer tick, asking Buildbucket for the build status
[06:46:05.188] Build 8860241247089192864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:46:05.188] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:120:0) after 1m0s
[06:47:05.230] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:120:0)
[06:47:05.230] Timer tick, asking Buildbucket for the build status
[06:47:05.294] Build 8860241247089192864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:47:05.294] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:121:0) after 1m0s
[06:48:05.324] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:121:0)
[06:48:05.324] Timer tick, asking Buildbucket for the build status
[06:48:05.389] Build 8860241247089192864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:48:05.389] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:122:0) after 1m0s
[06:49:05.450] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:122:0)
[06:49:05.450] Timer tick, asking Buildbucket for the build status
[06:49:05.561] Build 8860241247089192864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:49:05.561] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:123:0) after 1m0s
[06:50:05.595] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:123:0)
[06:50:05.595] Timer tick, asking Buildbucket for the build status
[06:50:05.664] Build 8860241247089192864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:50:05.664] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:124:0) after 1m0s
[06:51:05.737] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:124:0)
[06:51:05.737] Timer tick, asking Buildbucket for the build status
[06:51:05.800] Build 8860241247089192864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:51:05.800] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:125:0) after 1m0s
[06:52:05.889] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:125:0)
[06:52:05.889] Timer tick, asking Buildbucket for the build status
[06:52:05.997] Build 8860241247089192864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:52:05.997] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:126:0) after 1m0s
[06:53:06.071] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:126:0)
[06:53:06.071] Timer tick, asking Buildbucket for the build status
[06:53:06.140] Build 8860241247089192864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:53:06.140] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:127:0) after 1m0s
[06:54:06.388] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:127:0)
[06:54:06.388] Timer tick, asking Buildbucket for the build status
[06:54:06.527] Build 8860241247089192864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:54:06.527] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:128:0) after 1m0s
[06:55:06.551] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:128:0)
[06:55:06.551] Timer tick, asking Buildbucket for the build status
[06:55:06.618] Build 8860241247089192864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:55:06.618] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:129:0) after 1m0s
[06:56:06.694] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:129:0)
[06:56:06.694] Timer tick, asking Buildbucket for the build status
[06:56:06.929] Build 8860241247089192864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:56:06.929] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:130:0) after 1m0s
[06:57:07.066] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:130:0)
[06:57:07.066] Timer tick, asking Buildbucket for the build status
[06:57:07.188] Build 8860241247089192864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:57:07.188] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:131:0) after 1m0s
[06:58:07.261] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:131:0)
[06:58:07.261] Timer tick, asking Buildbucket for the build status
[06:58:07.474] Build 8860241247089192864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:58:07.474] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:132:0) after 1m0s
[06:59:07.511] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:132:0)
[06:59:07.511] Timer tick, asking Buildbucket for the build status
[06:59:07.989] Build 8860241247089192864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:59:07.989] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:133:0) after 1m0s
[07:00:08.066] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:133:0)
[07:00:08.066] Timer tick, asking Buildbucket for the build status
[07:00:10.745] Build 8860241247089192864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:00:10.745] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:134:0) after 1m0s
[07:01:10.789] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:134:0)
[07:01:10.789] Timer tick, asking Buildbucket for the build status
[07:01:10.873] Build 8860241247089192864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:01:10.873] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:135:0) after 1m0s
[07:02:10.898] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:135:0)
[07:02:10.898] Timer tick, asking Buildbucket for the build status
[07:02:10.956] Build 8860241247089192864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:02:10.956] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:136:0) after 1m0s
[07:03:10.985] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:136:0)
[07:03:10.985] Timer tick, asking Buildbucket for the build status
[07:03:11.067] Build 8860241247089192864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:03:11.067] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:137:0) after 1m0s
[07:04:11.094] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:137:0)
[07:04:11.094] Timer tick, asking Buildbucket for the build status
[07:04:11.328] Build 8860241247089192864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:04:11.329] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:138:0) after 1m0s
[07:05:11.368] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:138:0)
[07:05:11.368] Timer tick, asking Buildbucket for the build status
[07:05:11.444] Build 8860241247089192864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:05:11.444] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:139:0) after 1m0s
[07:06:11.463] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:139:0)
[07:06:11.463] Timer tick, asking Buildbucket for the build status
[07:06:11.542] Build 8860241247089192864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:06:11.542] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:140:0) after 1m0s
[07:07:11.597] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:140:0)
[07:07:11.597] Timer tick, asking Buildbucket for the build status
[07:07:11.745] Build 8860241247089192864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:07:11.745] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:141:0) after 1m0s
[07:08:11.772] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:141:0)
[07:08:11.772] Timer tick, asking Buildbucket for the build status
[07:08:12.173] Build 8860241247089192864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:08:12.173] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:142:0) after 1m0s
[07:09:12.205] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:142:0)
[07:09:12.205] Timer tick, asking Buildbucket for the build status
[07:09:12.365] Build 8860241247089192864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:09:12.365] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:143:0) after 1m0s
[07:10:12.447] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:143:0)
[07:10:12.447] Timer tick, asking Buildbucket for the build status
[07:10:12.519] Build 8860241247089192864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:10:12.519] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:144:0) after 1m0s
[07:11:12.545] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:144:0)
[07:11:12.545] Timer tick, asking Buildbucket for the build status
[07:11:12.682] Build 8860241247089192864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:11:12.682] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:145:0) after 1m0s
[07:12:12.745] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:145:0)
[07:12:12.745] Timer tick, asking Buildbucket for the build status
[07:12:12.828] Build 8860241247089192864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:12:12.828] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:146:0) after 1m0s
[07:13:12.810] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:146:0)
[07:13:12.811] Timer tick, asking Buildbucket for the build status
[07:13:12.939] Build 8860241247089192864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:13:12.939] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:147:0) after 1m0s
[07:14:12.995] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:147:0)
[07:14:12.995] Timer tick, asking Buildbucket for the build status
[07:14:13.139] Build 8860241247089192864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:14:13.139] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:148:0) after 1m0s
[07:15:13.163] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:148:0)
[07:15:13.163] Timer tick, asking Buildbucket for the build status
[07:15:13.248] Build 8860241247089192864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:15:13.248] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:149:0) after 1m0s
[07:16:13.294] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:149:0)
[07:16:13.294] Timer tick, asking Buildbucket for the build status
[07:16:13.367] Build 8860241247089192864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:16:13.367] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:150:0) after 1m0s
[07:17:13.389] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:150:0)
[07:17:13.389] Timer tick, asking Buildbucket for the build status
[07:17:13.455] Build 8860241247089192864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:17:13.455] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:151:0) after 1m0s
[07:18:13.728] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:151:0)
[07:18:13.728] Timer tick, asking Buildbucket for the build status
[07:18:13.810] Build 8860241247089192864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:18:13.810] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:152:0) after 1m0s
[07:19:13.979] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:152:0)
[07:19:13.979] Timer tick, asking Buildbucket for the build status
[07:19:14.116] Build 8860241247089192864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:19:14.116] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:153:0) after 1m0s
[07:20:14.416] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:153:0)
[07:20:14.416] Timer tick, asking Buildbucket for the build status
[07:20:14.654] Build 8860241247089192864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:20:14.654] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:154:0) after 1m0s
[07:21:15.006] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:154:0)
[07:21:15.006] Timer tick, asking Buildbucket for the build status
[07:21:15.065] Build 8860241247089192864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:21:15.065] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:155:0) after 1m0s
[07:22:15.115] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:155:0)
[07:22:15.115] Timer tick, asking Buildbucket for the build status
[07:22:15.309] Build 8860241247089192864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:22:15.309] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:156:0) after 1m0s
[07:23:16.024] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:156:0)
[07:23:16.024] Timer tick, asking Buildbucket for the build status
[07:23:16.186] Build 8860241247089192864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:23:16.186] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:157:0) after 1m0s
[07:24:16.308] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:157:0)
[07:24:16.308] Timer tick, asking Buildbucket for the build status
[07:24:16.428] Build 8860241247089192864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:24:16.428] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:158:0) after 1m0s
[07:25:16.769] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:158:0)
[07:25:16.770] Timer tick, asking Buildbucket for the build status
[07:25:16.872] Build 8860241247089192864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:25:16.872] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:159:0) after 1m0s
[07:26:16.915] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:159:0)
[07:26:16.915] Timer tick, asking Buildbucket for the build status
[07:26:17.320] Build 8860241247089192864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:26:17.320] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:160:0) after 1m0s
[07:27:17.396] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:160:0)
[07:27:17.396] Timer tick, asking Buildbucket for the build status
[07:27:17.467] Build 8860241247089192864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:27:17.467] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:161:0) after 1m0s
[07:28:17.521] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:161:0)
[07:28:17.521] Timer tick, asking Buildbucket for the build status
[07:28:17.609] Build 8860241247089192864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:28:17.609] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:162:0) after 1m0s
[07:29:17.638] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:162:0)
[07:29:17.638] Timer tick, asking Buildbucket for the build status
[07:29:17.823] Build 8860241247089192864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:29:17.823] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:163:0) after 1m0s
[07:30:18.536] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:163:0)
[07:30:18.536] Timer tick, asking Buildbucket for the build status
[07:30:18.655] Build 8860241247089192864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:30:18.655] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:164:0) after 1m0s
[07:31:18.733] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:164:0)
[07:31:18.733] Timer tick, asking Buildbucket for the build status
[07:31:21.063] Build 8860241247089192864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:31:21.063] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:165:0) after 1m0s
[07:32:21.217] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:165:0)
[07:32:21.217] Timer tick, asking Buildbucket for the build status
[07:32:21.517] Build 8860241247089192864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:32:21.517] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:166:0) after 1m0s
[07:33:21.598] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:166:0)
[07:33:21.598] Timer tick, asking Buildbucket for the build status
[07:33:21.859] Build 8860241247089192864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:33:21.859] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:167:0) after 1m0s
[07:34:22.084] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025671309065595504:167:0)
[07:34:22.084] Timer tick, asking Buildbucket for the build status
[07:34:22.165] Build 8860241247089192864: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[07:34:22.165] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1608622461538915",
"created_by": "project:chromium",
"created_ts": "1608612507695103",
"id": "8860241247089192864",
"parameters_json": "{\"builder_name\": \"win32-official\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"91a15528a34573d7be11f786cb8cdfcefd40677f\"}}",
"project": "chromium",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"$build/goma\": {\"enable_ats\": true, \"rpc_extra_params\": \"?prod\", \"server_host\": \"goma.chromium.org\", \"use_luci_auth\": true}, \"$recipe_engine/isolated\": {\"server\": \"https://isolateserver.appspot.com\"}, \"$recipe_engine/milo/blamelist_pins\": [{\"host\": \"chromium.googlesource.com\", \"id\": \"91a15528a34573d7be11f786cb8cdfcefd40677f\", \"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-5-tzuk\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium\", \"buildername\": \"win32-official\", \"buildnumber\": 1164, \"got_angle_revision\": \"11c31e0a4a73d7d62e765dc7ec400560fbb16b5e\", \"got_dawn_revision\": \"ec56b90ceab0c16e3c9f5603cc26562ec7bc2430\", \"got_nacl_revision\": \"299d4780ec86121ff2a5368ed9435ade0e356e5e\", \"got_revision\": \"91a15528a34573d7be11f786cb8cdfcefd40677f\", \"got_revision_cp\": \"refs/heads/master@{#838791}\", \"got_swarming_client_revision\": \"1a072711d4388c62e02480fabc26c68c24494be9\", \"got_swiftshader_revision\": \"1cc5b3357d2ff3c81ef7ec0b07a6660796bde5cd\", \"got_v8_revision\": \"ab90f8251bdc6bd9dc6033afdd2374af4883794f\", \"got_v8_revision_cp\": \"refs/heads/8.9.181@{#1}\", \"got_webrtc_revision\": \"9e63abad22c306ac080816fde7d428b779b5037d\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32870}\", \"mirrored_builders\": [\"tryserver.chromium:win32-official\"], \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"91a15528a34573d7be11f786cb8cdfcefd40677f\"}, \"swarming\": {\"bot_dimensions\": {\"builder\": [\"win-archive-dbg\", \"win-archive-rel\", \"win-official\", \"win32-archive-dbg\", \"win32-archive-rel\", \"win32-official\"], \"caches\": [\"builder_f99b6c393baeca5d0493b8341f177ea0239e45dd6dfe829b6aea97f6876ba96c_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"32\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"win-dbg-5-tzuk\"], \"image\": [\"chrome-win10-1703-20092300-22ba5320847\"], \"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\": [\"5657-0417a3f\"], \"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": "1608612513852252",
"status": "COMPLETED",
"status_changed_ts": "1608622461803272",
"tags": [
"build_address:luci.chromium.ci/win32-official/1164",
"builder:win32-official",
"buildset:commit/git/91a15528a34573d7be11f786cb8cdfcefd40677f",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/91a15528a34573d7be11f786cb8cdfcefd40677f",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9025671309065595504",
"scheduler_job_id:chromium/win32-official",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8860241247089192864/+/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:50a1987c41d02d10",
"user_agent:luci-scheduler"
],
"updated_ts": "1608622461808222",
"url": "https://ci.chromium.org/b/8860241247089192864",
"utcnow_ts": "1608622462156925"
}
}
[07:34:22.165] Invocation finished in 2h45m55.737915763s with status SUCCEEDED