[18:42:34.380] New invocation is queued and will start shortly
[18:42:35.504] Starting the invocation (attempt 1)
[18:42:35.605] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[18:42:35.605] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[18:42:35.605] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9085050439961814320",
"parameters_json": "{\"builder_name\":\"Windows deterministic\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src.git\",\"revision\":\"cd13225a70402e548c5feacde91f0acbfd7f927f\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Windows deterministic",
"scheduler_invocation_id:9085050439961814320",
"scheduler_job_id:chromium/Windows deterministic",
"user_agent:luci-scheduler",
"buildset:commit/git/cd13225a70402e548c5feacde91f0acbfd7f927f",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/cd13225a70402e548c5feacde91f0acbfd7f927f",
"gitiles_ref:refs/heads/master"
]
}
[18:42:38.365] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1551984155754057",
"id": "8919620377854377792",
"lease_expiration_ts": "1552102957803711",
"lease_key": "1779164259",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"cd13225a70402e548c5feacde91f0acbfd7f927f\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1551984158159766",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/12337",
"builder:Windows deterministic",
"buildset:commit/git/cd13225a70402e548c5feacde91f0acbfd7f927f",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/cd13225a70402e548c5feacde91f0acbfd7f927f",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9085050439961814320",
"scheduler_job_id:chromium/Windows deterministic",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8919620377854377792/+/annotations",
"swarming_tag:luci_project:chromium",
"swarming_tag:recipe_name:swarming/deterministic_build",
"swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build",
"swarming_task_id:4372486a0ca47210",
"user_agent:luci-scheduler"
],
"updated_ts": "1551984158159894",
"url": "https://ci.chromium.org/b/8919620377854377792",
"utcnow_ts": "1551984158352559"
}
}
[18:42:38.365] Task URL: https://ci.chromium.org/b/8919620377854377792
[18:42:38.365] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085050439961814320:2:0) after 1m0s
[18:43:38.394] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085050439961814320:2:0)
[18:43:38.394] Timer tick, asking Buildbucket for the build status
[18:43:38.534] Build 8919620377854377792: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:43:38.534] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085050439961814320:3:0) after 1m0s
[18:44:25.660] Received PubSub notification, asking Buildbucket for the build status
[18:44:25.783] Build 8919620377854377792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:44:38.549] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085050439961814320:3:0)
[18:44:38.549] Timer tick, asking Buildbucket for the build status
[18:44:38.599] Build 8919620377854377792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:44:38.599] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085050439961814320:5:0) after 1m0s
[18:45:38.598] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085050439961814320:5:0)
[18:45:38.598] Timer tick, asking Buildbucket for the build status
[18:45:38.700] Build 8919620377854377792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:45:38.700] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085050439961814320:6:0) after 1m0s
[18:46:38.687] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085050439961814320:6:0)
[18:46:38.687] Timer tick, asking Buildbucket for the build status
[18:46:38.791] Build 8919620377854377792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:46:38.791] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085050439961814320:7:0) after 1m0s
[18:47:38.799] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085050439961814320:7:0)
[18:47:38.799] Timer tick, asking Buildbucket for the build status
[18:47:38.919] Build 8919620377854377792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:47:38.919] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085050439961814320:8:0) after 1m0s
[18:48:38.908] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085050439961814320:8:0)
[18:48:38.908] Timer tick, asking Buildbucket for the build status
[18:48:38.951] Build 8919620377854377792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:48:38.951] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085050439961814320:9:0) after 1m0s
[18:49:38.950] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085050439961814320:9:0)
[18:49:38.950] Timer tick, asking Buildbucket for the build status
[18:49:39.001] Build 8919620377854377792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:49:39.001] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085050439961814320:10:0) after 1m0s
[18:50:38.990] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085050439961814320:10:0)
[18:50:38.990] Timer tick, asking Buildbucket for the build status
[18:50:39.030] Build 8919620377854377792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:50:39.030] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085050439961814320:11:0) after 1m0s
[18:51:39.048] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085050439961814320:11:0)
[18:51:39.048] Timer tick, asking Buildbucket for the build status
[18:51:39.132] Build 8919620377854377792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:51:39.132] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085050439961814320:12:0) after 1m0s
[18:52:39.117] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085050439961814320:12:0)
[18:52:39.117] Timer tick, asking Buildbucket for the build status
[18:52:39.155] Build 8919620377854377792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:52:39.155] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085050439961814320:13:0) after 1m0s
[18:53:39.174] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085050439961814320:13:0)
[18:53:39.174] Timer tick, asking Buildbucket for the build status
[18:53:39.425] Build 8919620377854377792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:53:39.425] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085050439961814320:14:0) after 1m0s
[18:54:39.437] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085050439961814320:14:0)
[18:54:39.437] Timer tick, asking Buildbucket for the build status
[18:54:39.520] Build 8919620377854377792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:54:39.520] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085050439961814320:15:0) after 1m0s
[18:55:39.503] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085050439961814320:15:0)
[18:55:39.503] Timer tick, asking Buildbucket for the build status
[18:55:39.543] Build 8919620377854377792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:55:39.543] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085050439961814320:16:0) after 1m0s
[18:56:39.565] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085050439961814320:16:0)
[18:56:39.565] Timer tick, asking Buildbucket for the build status
[18:56:39.768] Build 8919620377854377792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:56:39.768] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085050439961814320:17:0) after 1m0s
[18:57:39.855] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085050439961814320:17:0)
[18:57:39.855] Timer tick, asking Buildbucket for the build status
[18:57:40.026] Build 8919620377854377792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:57:40.026] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085050439961814320:18:0) after 1m0s
[18:58:40.003] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085050439961814320:18:0)
[18:58:40.003] Timer tick, asking Buildbucket for the build status
[18:58:40.110] Build 8919620377854377792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:58:40.110] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085050439961814320:19:0) after 1m0s
[18:59:40.131] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085050439961814320:19:0)
[18:59:40.131] Timer tick, asking Buildbucket for the build status
[18:59:40.265] Build 8919620377854377792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:59:40.265] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085050439961814320:20:0) after 1m0s
[19:00:40.275] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085050439961814320:20:0)
[19:00:40.275] Timer tick, asking Buildbucket for the build status
[19:00:40.393] Build 8919620377854377792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:00:40.393] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085050439961814320:21:0) after 1m0s
[19:01:40.413] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085050439961814320:21:0)
[19:01:40.413] Timer tick, asking Buildbucket for the build status
[19:01:40.469] Build 8919620377854377792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:01:40.469] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085050439961814320:22:0) after 1m0s
[19:02:40.520] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085050439961814320:22:0)
[19:02:40.520] Timer tick, asking Buildbucket for the build status
[19:02:40.627] Build 8919620377854377792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:02:40.627] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085050439961814320:23:0) after 1m0s
[19:03:40.608] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085050439961814320:23:0)
[19:03:40.608] Timer tick, asking Buildbucket for the build status
[19:03:40.887] Build 8919620377854377792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:03:40.887] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085050439961814320:24:0) after 1m0s
[19:04:40.856] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085050439961814320:24:0)
[19:04:40.856] Timer tick, asking Buildbucket for the build status
[19:04:41.095] Build 8919620377854377792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:04:41.095] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085050439961814320:25:0) after 1m0s
[19:05:41.116] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085050439961814320:25:0)
[19:05:41.116] Timer tick, asking Buildbucket for the build status
[19:05:41.152] Build 8919620377854377792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:05:41.152] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085050439961814320:26:0) after 1m0s
[19:06:41.172] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085050439961814320:26:0)
[19:06:41.172] Timer tick, asking Buildbucket for the build status
[19:06:41.321] Build 8919620377854377792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:06:41.321] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085050439961814320:27:0) after 1m0s
[19:07:41.308] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085050439961814320:27:0)
[19:07:41.308] Timer tick, asking Buildbucket for the build status
[19:07:41.468] Build 8919620377854377792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:07:41.468] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085050439961814320:28:0) after 1m0s
[19:08:41.477] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085050439961814320:28:0)
[19:08:41.477] Timer tick, asking Buildbucket for the build status
[19:08:41.543] Build 8919620377854377792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:08:41.543] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085050439961814320:29:0) after 1m0s
[19:09:41.532] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085050439961814320:29:0)
[19:09:41.532] Timer tick, asking Buildbucket for the build status
[19:09:41.578] Build 8919620377854377792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:09:41.578] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085050439961814320:30:0) after 1m0s
[19:10:41.612] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085050439961814320:30:0)
[19:10:41.612] Timer tick, asking Buildbucket for the build status
[19:10:42.156] Build 8919620377854377792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:10:42.156] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085050439961814320:31:0) after 1m0s
[19:11:42.166] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085050439961814320:31:0)
[19:11:42.166] Timer tick, asking Buildbucket for the build status
[19:11:42.296] Build 8919620377854377792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:11:42.296] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085050439961814320:32:0) after 1m0s
[19:12:42.366] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085050439961814320:32:0)
[19:12:42.366] Timer tick, asking Buildbucket for the build status
[19:12:42.548] Build 8919620377854377792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:12:42.548] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085050439961814320:33:0) after 1m0s
[19:13:42.569] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085050439961814320:33:0)
[19:13:42.569] Timer tick, asking Buildbucket for the build status
[19:13:42.797] Build 8919620377854377792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:13:42.797] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085050439961814320:34:0) after 1m0s
[19:14:42.772] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085050439961814320:34:0)
[19:14:42.772] Timer tick, asking Buildbucket for the build status
[19:14:42.820] Build 8919620377854377792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:14:42.820] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085050439961814320:35:0) after 1m0s
[19:15:42.844] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085050439961814320:35:0)
[19:15:42.844] Timer tick, asking Buildbucket for the build status
[19:15:42.894] Build 8919620377854377792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:15:42.894] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085050439961814320:36:0) after 1m0s
[19:16:42.915] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085050439961814320:36:0)
[19:16:42.915] Timer tick, asking Buildbucket for the build status
[19:16:42.950] Build 8919620377854377792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:16:42.950] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085050439961814320:37:0) after 1m0s
[19:17:42.930] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085050439961814320:37:0)
[19:17:42.930] Timer tick, asking Buildbucket for the build status
[19:17:43.078] Build 8919620377854377792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:17:43.078] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085050439961814320:38:0) after 1m0s
[19:18:43.049] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085050439961814320:38:0)
[19:18:43.049] Timer tick, asking Buildbucket for the build status
[19:18:43.091] Build 8919620377854377792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:18:43.091] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085050439961814320:39:0) after 1m0s
[19:19:43.064] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085050439961814320:39:0)
[19:19:43.064] Timer tick, asking Buildbucket for the build status
[19:19:43.214] Build 8919620377854377792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:19:43.214] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085050439961814320:40:0) after 1m0s
[19:20:43.221] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085050439961814320:40:0)
[19:20:43.221] Timer tick, asking Buildbucket for the build status
[19:20:43.304] Build 8919620377854377792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:20:43.304] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085050439961814320:41:0) after 1m0s
[19:21:43.290] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085050439961814320:41:0)
[19:21:43.290] Timer tick, asking Buildbucket for the build status
[19:21:43.376] Build 8919620377854377792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:21:43.376] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085050439961814320:42:0) after 1m0s
[19:22:43.391] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085050439961814320:42:0)
[19:22:43.391] Timer tick, asking Buildbucket for the build status
[19:22:43.524] Build 8919620377854377792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:22:43.524] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085050439961814320:43:0) after 1m0s
[19:23:43.535] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085050439961814320:43:0)
[19:23:43.535] Timer tick, asking Buildbucket for the build status
[19:23:43.575] Build 8919620377854377792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:23:43.575] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085050439961814320:44:0) after 1m0s
[19:24:43.606] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085050439961814320:44:0)
[19:24:43.606] Timer tick, asking Buildbucket for the build status
[19:24:43.653] Build 8919620377854377792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:24:43.653] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085050439961814320:45:0) after 1m0s
[19:25:43.673] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085050439961814320:45:0)
[19:25:43.673] Timer tick, asking Buildbucket for the build status
[19:25:43.877] Build 8919620377854377792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:25:43.877] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085050439961814320:46:0) after 1m0s
[19:26:43.880] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085050439961814320:46:0)
[19:26:43.880] Timer tick, asking Buildbucket for the build status
[19:26:44.133] Build 8919620377854377792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:26:44.133] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085050439961814320:47:0) after 1m0s
[19:27:44.130] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085050439961814320:47:0)
[19:27:44.130] Timer tick, asking Buildbucket for the build status
[19:27:44.378] Build 8919620377854377792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:27:44.378] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085050439961814320:48:0) after 1m0s
[19:28:44.365] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085050439961814320:48:0)
[19:28:44.365] Timer tick, asking Buildbucket for the build status
[19:28:44.495] Build 8919620377854377792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:28:44.495] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085050439961814320:49:0) after 1m0s
[19:29:44.517] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085050439961814320:49:0)
[19:29:44.517] Timer tick, asking Buildbucket for the build status
[19:29:44.557] Build 8919620377854377792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:29:44.557] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085050439961814320:50:0) after 1m0s
[19:30:44.556] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085050439961814320:50:0)
[19:30:44.556] Timer tick, asking Buildbucket for the build status
[19:30:44.600] Build 8919620377854377792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:30:44.600] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085050439961814320:51:0) after 1m0s
[19:31:44.622] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085050439961814320:51:0)
[19:31:44.622] Timer tick, asking Buildbucket for the build status
[19:31:44.657] Build 8919620377854377792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:31:44.657] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085050439961814320:52:0) after 1m0s
[19:32:44.694] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085050439961814320:52:0)
[19:32:44.694] Timer tick, asking Buildbucket for the build status
[19:32:44.728] Build 8919620377854377792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:32:44.728] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085050439961814320:53:0) after 1m0s
[19:33:44.750] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085050439961814320:53:0)
[19:33:44.750] Timer tick, asking Buildbucket for the build status
[19:33:44.816] Build 8919620377854377792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:33:44.816] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085050439961814320:54:0) after 1m0s
[19:34:44.803] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085050439961814320:54:0)
[19:34:44.803] Timer tick, asking Buildbucket for the build status
[19:34:44.840] Build 8919620377854377792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:34:44.840] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085050439961814320:55:0) after 1m0s
[19:35:44.845] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085050439961814320:55:0)
[19:35:44.845] Timer tick, asking Buildbucket for the build status
[19:35:44.988] Build 8919620377854377792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:35:44.988] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085050439961814320:56:0) after 1m0s
[19:36:44.974] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085050439961814320:56:0)
[19:36:44.974] Timer tick, asking Buildbucket for the build status
[19:36:45.018] Build 8919620377854377792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:36:45.018] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085050439961814320:57:0) after 1m0s
[19:37:45.048] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085050439961814320:57:0)
[19:37:45.048] Timer tick, asking Buildbucket for the build status
[19:37:45.167] Build 8919620377854377792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:37:45.167] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085050439961814320:58:0) after 1m0s
[19:38:45.170] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085050439961814320:58:0)
[19:38:45.170] Timer tick, asking Buildbucket for the build status
[19:38:45.351] Build 8919620377854377792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:38:45.351] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085050439961814320:59:0) after 1m0s
[19:39:45.374] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085050439961814320:59:0)
[19:39:45.374] Timer tick, asking Buildbucket for the build status
[19:39:45.423] Build 8919620377854377792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:39:45.423] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085050439961814320:60:0) after 1m0s
[19:40:45.409] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085050439961814320:60:0)
[19:40:45.409] Timer tick, asking Buildbucket for the build status
[19:40:45.490] Build 8919620377854377792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:40:45.490] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085050439961814320:61:0) after 1m0s
[19:41:45.517] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085050439961814320:61:0)
[19:41:45.517] Timer tick, asking Buildbucket for the build status
[19:41:45.583] Build 8919620377854377792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:41:45.583] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085050439961814320:62:0) after 1m0s
[19:42:45.562] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085050439961814320:62:0)
[19:42:45.562] Timer tick, asking Buildbucket for the build status
[19:42:45.619] Build 8919620377854377792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:42:45.619] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085050439961814320:63:0) after 1m0s
[19:43:45.621] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085050439961814320:63:0)
[19:43:45.621] Timer tick, asking Buildbucket for the build status
[19:43:45.660] Build 8919620377854377792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:43:45.660] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085050439961814320:64:0) after 1m0s
[19:44:45.647] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085050439961814320:64:0)
[19:44:45.647] Timer tick, asking Buildbucket for the build status
[19:44:45.690] Build 8919620377854377792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:44:45.690] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085050439961814320:65:0) after 1m0s
[19:45:45.693] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085050439961814320:65:0)
[19:45:45.693] Timer tick, asking Buildbucket for the build status
[19:45:45.738] Build 8919620377854377792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:45:45.738] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085050439961814320:66:0) after 1m0s
[19:46:45.767] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085050439961814320:66:0)
[19:46:45.767] Timer tick, asking Buildbucket for the build status
[19:46:45.893] Build 8919620377854377792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:46:45.893] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085050439961814320:67:0) after 1m0s
[19:47:45.938] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085050439961814320:67:0)
[19:47:45.938] Timer tick, asking Buildbucket for the build status
[19:47:46.022] Build 8919620377854377792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:47:46.022] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085050439961814320:68:0) after 1m0s
[19:48:45.999] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085050439961814320:68:0)
[19:48:45.999] Timer tick, asking Buildbucket for the build status
[19:48:46.101] Build 8919620377854377792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:48:46.101] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085050439961814320:69:0) after 1m0s
[19:49:46.091] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085050439961814320:69:0)
[19:49:46.091] Timer tick, asking Buildbucket for the build status
[19:49:46.170] Build 8919620377854377792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:49:46.170] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085050439961814320:70:0) after 1m0s
[19:50:46.169] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085050439961814320:70:0)
[19:50:46.169] Timer tick, asking Buildbucket for the build status
[19:50:46.299] Build 8919620377854377792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:50:46.299] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085050439961814320:71:0) after 1m0s
[19:51:46.345] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085050439961814320:71:0)
[19:51:46.345] Timer tick, asking Buildbucket for the build status
[19:51:50.456] Build 8919620377854377792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:51:50.456] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085050439961814320:72:0) after 1m0s
[19:52:50.482] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085050439961814320:72:0)
[19:52:50.482] Timer tick, asking Buildbucket for the build status
[19:52:50.550] Build 8919620377854377792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:52:50.550] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085050439961814320:73:0) after 1m0s
[19:53:50.576] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085050439961814320:73:0)
[19:53:50.576] Timer tick, asking Buildbucket for the build status
[19:53:50.685] Build 8919620377854377792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:53:50.685] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085050439961814320:74:0) after 1m0s
[19:54:50.706] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085050439961814320:74:0)
[19:54:50.706] Timer tick, asking Buildbucket for the build status
[19:54:50.752] Build 8919620377854377792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:54:50.752] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085050439961814320:75:0) after 1m0s
[19:55:50.784] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085050439961814320:75:0)
[19:55:50.784] Timer tick, asking Buildbucket for the build status
[19:55:50.833] Build 8919620377854377792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:55:50.833] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085050439961814320:76:0) after 1m0s
[19:56:50.860] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085050439961814320:76:0)
[19:56:50.860] Timer tick, asking Buildbucket for the build status
[19:56:50.899] Build 8919620377854377792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:56:50.899] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085050439961814320:77:0) after 1m0s
[19:57:50.893] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085050439961814320:77:0)
[19:57:50.893] Timer tick, asking Buildbucket for the build status
[19:57:50.986] Build 8919620377854377792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:57:50.986] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085050439961814320:78:0) after 1m0s
[19:58:50.984] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085050439961814320:78:0)
[19:58:50.984] Timer tick, asking Buildbucket for the build status
[19:58:51.026] Build 8919620377854377792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:58:51.026] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085050439961814320:79:0) after 1m0s
[19:59:51.047] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085050439961814320:79:0)
[19:59:51.047] Timer tick, asking Buildbucket for the build status
[19:59:51.087] Build 8919620377854377792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:59:51.087] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085050439961814320:80:0) after 1m0s
[20:00:51.101] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085050439961814320:80:0)
[20:00:51.101] Timer tick, asking Buildbucket for the build status
[20:00:51.180] Build 8919620377854377792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:00:51.180] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085050439961814320:81:0) after 1m0s
[20:01:51.195] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085050439961814320:81:0)
[20:01:51.195] Timer tick, asking Buildbucket for the build status
[20:01:51.386] Build 8919620377854377792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:01:51.386] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085050439961814320:82:0) after 1m0s
[20:02:51.409] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085050439961814320:82:0)
[20:02:51.409] Timer tick, asking Buildbucket for the build status
[20:02:51.449] Build 8919620377854377792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:02:51.449] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085050439961814320:83:0) after 1m0s
[20:03:51.466] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085050439961814320:83:0)
[20:03:51.466] Timer tick, asking Buildbucket for the build status
[20:03:51.659] Build 8919620377854377792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:03:51.659] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085050439961814320:84:0) after 1m0s
[20:04:51.643] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085050439961814320:84:0)
[20:04:51.643] Timer tick, asking Buildbucket for the build status
[20:04:51.705] Build 8919620377854377792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:04:51.705] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085050439961814320:85:0) after 1m0s
[20:05:51.714] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085050439961814320:85:0)
[20:05:51.714] Timer tick, asking Buildbucket for the build status
[20:05:51.754] Build 8919620377854377792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:05:51.754] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085050439961814320:86:0) after 1m0s
[20:06:51.746] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085050439961814320:86:0)
[20:06:51.746] Timer tick, asking Buildbucket for the build status
[20:06:51.781] Build 8919620377854377792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:06:51.781] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085050439961814320:87:0) after 1m0s
[20:07:51.830] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085050439961814320:87:0)
[20:07:51.830] Timer tick, asking Buildbucket for the build status
[20:07:51.908] Build 8919620377854377792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:07:51.908] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085050439961814320:88:0) after 1m0s
[20:08:51.935] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085050439961814320:88:0)
[20:08:51.935] Timer tick, asking Buildbucket for the build status
[20:08:51.981] Build 8919620377854377792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:08:51.981] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085050439961814320:89:0) after 1m0s
[20:09:52.000] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085050439961814320:89:0)
[20:09:52.000] Timer tick, asking Buildbucket for the build status
[20:09:52.105] Build 8919620377854377792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:09:52.105] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085050439961814320:90:0) after 1m0s
[20:10:52.146] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085050439961814320:90:0)
[20:10:52.146] Timer tick, asking Buildbucket for the build status
[20:10:52.190] Build 8919620377854377792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:10:52.190] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085050439961814320:91:0) after 1m0s
[20:11:52.185] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085050439961814320:91:0)
[20:11:52.185] Timer tick, asking Buildbucket for the build status
[20:11:52.228] Build 8919620377854377792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:11:52.228] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085050439961814320:92:0) after 1m0s
[20:12:52.238] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085050439961814320:92:0)
[20:12:52.238] Timer tick, asking Buildbucket for the build status
[20:12:52.291] Build 8919620377854377792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:12:52.291] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085050439961814320:93:0) after 1m0s
[20:13:52.281] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085050439961814320:93:0)
[20:13:52.281] Timer tick, asking Buildbucket for the build status
[20:13:52.319] Build 8919620377854377792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:13:52.319] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085050439961814320:94:0) after 1m0s
[20:14:52.349] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085050439961814320:94:0)
[20:14:52.349] Timer tick, asking Buildbucket for the build status
[20:14:52.872] Build 8919620377854377792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:14:52.872] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085050439961814320:95:0) after 1m0s
[20:15:52.894] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085050439961814320:95:0)
[20:15:52.894] Timer tick, asking Buildbucket for the build status
[20:15:52.932] Build 8919620377854377792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:15:52.932] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085050439961814320:96:0) after 1m0s
[20:16:52.956] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085050439961814320:96:0)
[20:16:52.956] Timer tick, asking Buildbucket for the build status
[20:16:53.074] Build 8919620377854377792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:16:53.074] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085050439961814320:97:0) after 1m0s
[20:17:53.089] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085050439961814320:97:0)
[20:17:53.089] Timer tick, asking Buildbucket for the build status
[20:17:53.160] Build 8919620377854377792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:17:53.160] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085050439961814320:98:0) after 1m0s
[20:18:53.163] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085050439961814320:98:0)
[20:18:53.163] Timer tick, asking Buildbucket for the build status
[20:18:53.265] Build 8919620377854377792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:18:53.266] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085050439961814320:99:0) after 1m0s
[20:19:53.273] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085050439961814320:99:0)
[20:19:53.273] Timer tick, asking Buildbucket for the build status
[20:19:53.360] Build 8919620377854377792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:19:53.360] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085050439961814320:100:0) after 1m0s
[20:20:53.363] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085050439961814320:100:0)
[20:20:53.363] Timer tick, asking Buildbucket for the build status
[20:20:53.488] Build 8919620377854377792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:20:53.488] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085050439961814320:101:0) after 1m0s
[20:21:53.513] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085050439961814320:101:0)
[20:21:53.513] Timer tick, asking Buildbucket for the build status
[20:21:53.806] Build 8919620377854377792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:21:53.806] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085050439961814320:102:0) after 1m0s
[20:22:53.801] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085050439961814320:102:0)
[20:22:53.801] Timer tick, asking Buildbucket for the build status
[20:22:53.981] Build 8919620377854377792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:22:53.981] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085050439961814320:103:0) after 1m0s
[20:23:54.004] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085050439961814320:103:0)
[20:23:54.004] Timer tick, asking Buildbucket for the build status
[20:23:54.058] Build 8919620377854377792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:23:54.058] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085050439961814320:104:0) after 1m0s
[20:24:54.086] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085050439961814320:104:0)
[20:24:54.086] Timer tick, asking Buildbucket for the build status
[20:24:54.123] Build 8919620377854377792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:24:54.123] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085050439961814320:105:0) after 1m0s
[20:25:54.148] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085050439961814320:105:0)
[20:25:54.148] Timer tick, asking Buildbucket for the build status
[20:25:54.192] Build 8919620377854377792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:25:54.192] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085050439961814320:106:0) after 1m0s
[20:26:54.203] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085050439961814320:106:0)
[20:26:54.203] Timer tick, asking Buildbucket for the build status
[20:26:54.304] Build 8919620377854377792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:26:54.304] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085050439961814320:107:0) after 1m0s
[20:27:54.332] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085050439961814320:107:0)
[20:27:54.332] Timer tick, asking Buildbucket for the build status
[20:27:54.606] Build 8919620377854377792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:27:54.606] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085050439961814320:108:0) after 1m0s
[20:28:54.630] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085050439961814320:108:0)
[20:28:54.630] Timer tick, asking Buildbucket for the build status
[20:28:54.672] Build 8919620377854377792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:28:54.672] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085050439961814320:109:0) after 1m0s
[20:29:54.719] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085050439961814320:109:0)
[20:29:54.719] Timer tick, asking Buildbucket for the build status
[20:29:54.784] Build 8919620377854377792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:29:54.784] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085050439961814320:110:0) after 1m0s
[20:30:14.339] Received PubSub notification, asking Buildbucket for the build status
[20:30:14.376] Build 8919620377854377792: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[20:30:14.376] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"completed_ts": "1551990612953086",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1551984155754057",
"id": "8919620377854377792",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"cd13225a70402e548c5feacde91f0acbfd7f927f\"}}",
"project": "chromium",
"result": "SUCCESS",
"result_details_json": "{\"swarming\": {\"bot_dimensions\": {\"gce\": [\"1\"], \"machine_type\": [\"n1-standard-8\"], \"zone\": [\"us\", \"us-west\", \"us-west1\", \"us-west1-b\"], \"locale\": [\"en_US.cp1252\"], \"os\": [\"Windows\", \"Windows-10\", \"Windows-10-15063\"], \"builder\": [\"Windows deterministic\"], \"id\": [\"win10-727f49a0-us-west1-b-kb2j\"], \"python\": [\"2.7.13\"], \"server_version\": [\"4157-fb06213\"], \"image\": [\"chrome-win10-1703-2018-04-20-61acaf8983f\"], \"gpu\": [\"none\"], \"cores\": [\"8\"], \"integrity\": [\"high\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\"], \"pool\": [\"luci.chromium.ci\"], \"caches\": [\"builder_e8fffaf9fac16164b1ec16ebaa1f377b792d39a582f7640830518d8ff7303d0c_v2\", \"git\", \"goma_v2\", \"vpython\"]}}, \"ui\": {\"info\": \"\"}, \"properties\": {\"$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}, \"got_nacl_revision\": \"1ed9878b8eb68c1e0d0268754dafcf83e747fa8f\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#26981}\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"buildername\": \"Windows deterministic\", \"got_webrtc_revision\": \"be7af9399ceb88171bf60b50419ff2dec8184fb9\", \"got_revision\": \"cd13225a70402e548c5feacde91f0acbfd7f927f\", \"got_swarming_client_revision\": \"7a61cf37d6a0163f0ec02d495289a1d038e62457\", \"recipe\": \"swarming/deterministic_build\", \"mastername\": \"chromium.win\", \"got_angle_revision\": \"14126505b237ed3f56b7a3860fef43403e0accd9\", \"got_v8_revision\": \"3e8a733af17a7812eba188dad612be503bd45c57\", \"got_revision_cp\": \"refs/heads/master@{#638657}\", \"got_dawn_revision\": \"aa9d6ad09d0d43ad2a35263fb45c272f40f95937\", \"branch\": \"refs/heads/master\", \"buildnumber\": 12337, \"path_config\": \"generic\", \"bot_id\": \"win10-727f49a0-us-west1-b-kb2j\", \"got_v8_revision_cp\": \"refs/heads/7.4.288@{#1}\", \"revision\": \"cd13225a70402e548c5feacde91f0acbfd7f927f\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1551984265020858",
"status": "COMPLETED",
"status_changed_ts": "1551990614052765",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/12337",
"builder:Windows deterministic",
"buildset:commit/git/cd13225a70402e548c5feacde91f0acbfd7f927f",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/cd13225a70402e548c5feacde91f0acbfd7f927f",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9085050439961814320",
"scheduler_job_id:chromium/Windows deterministic",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8919620377854377792/+/annotations",
"swarming_tag:luci_project:chromium",
"swarming_tag:os:Windows",
"swarming_tag:recipe_name:swarming/deterministic_build",
"swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build",
"swarming_task_id:4372486a0ca47210",
"user_agent:luci-scheduler"
],
"updated_ts": "1551990614054133",
"url": "https://ci.chromium.org/b/8919620377854377792",
"utcnow_ts": "1551990614367955"
}
}
[20:30:14.376] Invocation finished in 1h47m40.014143325s with status SUCCEEDED