[14:22:54.154] New invocation is queued and will start shortly
[14:22:55.529] Starting the invocation (attempt 1)
[14:22:55.597] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[14:22:55.597] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[14:22:55.598] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9107444227711206736",
"parameters_json": "{\"builder_name\":\"Windows deterministic\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src.git\",\"revision\":\"e27679b15b4bf6837ed43675aab3dbbfe0761f9e\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Windows deterministic",
"scheduler_invocation_id:9107444227711206736",
"scheduler_job_id:chromium/Windows deterministic",
"user_agent:luci-scheduler",
"buildset:commit/git/e27679b15b4bf6837ed43675aab3dbbfe0761f9e",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/e27679b15b4bf6837ed43675aab3dbbfe0761f9e",
"gitiles_ref:refs/heads/master"
]
}
[14:22:56.860] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1530627775731760",
"id": "8942014165392870448",
"lease_expiration_ts": "1530688976427840",
"lease_key": "181939358",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"e27679b15b4bf6837ed43675aab3dbbfe0761f9e\"}}",
"project": "chromium",
"result_details_json": "null",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1530627776715710",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/9362",
"builder:Windows deterministic",
"buildset:commit/git/e27679b15b4bf6837ed43675aab3dbbfe0761f9e",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/e27679b15b4bf6837ed43675aab3dbbfe0761f9e",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9107444227711206736",
"scheduler_job_id:chromium/Windows deterministic",
"swarming_dimension:builder:Windows deterministic",
"swarming_dimension:cores:8",
"swarming_dimension:cpu:x86-64",
"swarming_dimension:os:Windows-10",
"swarming_dimension:pool:luci.chromium.ci",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:build_address:luci.chromium.ci/Windows deterministic/9362",
"swarming_tag:buildbucket_bucket:luci.chromium.ci",
"swarming_tag:buildbucket_build_id:8942014165392870448",
"swarming_tag:buildbucket_hostname:cr-buildbucket.appspot.com",
"swarming_tag:buildbucket_template_canary:0",
"swarming_tag:buildbucket_template_revision:4e8cd6081d2622e2214b4bbd0cdf5e2ba5406635",
"swarming_tag:builder:Windows deterministic",
"swarming_tag:buildset:commit/git/e27679b15b4bf6837ed43675aab3dbbfe0761f9e",
"swarming_tag:buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/e27679b15b4bf6837ed43675aab3dbbfe0761f9e",
"swarming_tag:cores:8",
"swarming_tag:cpu:x86-64",
"swarming_tag:gitiles_ref:refs/heads/master",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8942014165392870448/+/annotations",
"swarming_tag:luci_project:chromium",
"swarming_tag:os:Windows-10",
"swarming_tag:pool:luci.chromium.ci",
"swarming_tag:priority:30",
"swarming_tag:recipe_name:swarming/deterministic_build",
"swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build",
"swarming_tag:scheduler_invocation_id:9107444227711206736",
"swarming_tag:scheduler_job_id:chromium/Windows deterministic",
"swarming_tag:service_account:chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"swarming_tag:swarming.pool.template:skip",
"swarming_tag:swarming.pool.version:761be570b9ddc8b12cd9bf031b7ba136a1879f33",
"swarming_tag:user:None",
"swarming_tag:user_agent:luci-scheduler",
"swarming_tag:vpython:native-python-wrapper",
"swarming_task_id:3e7957e708175a10",
"user_agent:luci-scheduler"
],
"updated_ts": "1530627776715810",
"url": "https://ci.chromium.org/p/chromium/builders/luci.chromium.ci/Windows deterministic/9362",
"utcnow_ts": "1530627776833790"
}
}
[14:22:56.860] Task URL: https://ci.chromium.org/p/chromium/builders/luci.chromium.ci/Windows deterministic/9362
[14:22:56.860] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9107444227711206736:2:0) after 1m0s
[14:23:56.892] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9107444227711206736:2:0)
[14:23:56.892] Timer tick, asking Buildbucket for the build status
[14:23:57.018] Build 8942014165392870448: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:23:57.018] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9107444227711206736:3:0) after 1m0s
[14:24:55.645] Received PubSub notification, asking Buildbucket for the build status
[14:24:55.687] Build 8942014165392870448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:24:57.041] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9107444227711206736:3:0)
[14:24:57.041] Timer tick, asking Buildbucket for the build status
[14:24:57.081] Build 8942014165392870448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:24:57.081] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9107444227711206736:5:0) after 1m0s
[14:25:57.128] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9107444227711206736:5:0)
[14:25:57.128] Timer tick, asking Buildbucket for the build status
[14:25:57.180] Build 8942014165392870448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:25:57.180] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9107444227711206736:6:0) after 1m0s
[14:26:57.215] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9107444227711206736:6:0)
[14:26:57.215] Timer tick, asking Buildbucket for the build status
[14:26:57.327] Build 8942014165392870448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:26:57.327] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9107444227711206736:7:0) after 1m0s
[14:27:57.373] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9107444227711206736:7:0)
[14:27:57.373] Timer tick, asking Buildbucket for the build status
[14:27:57.465] Build 8942014165392870448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:27:57.465] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9107444227711206736:8:0) after 1m0s
[14:28:57.482] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9107444227711206736:8:0)
[14:28:57.482] Timer tick, asking Buildbucket for the build status
[14:28:57.567] Build 8942014165392870448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:28:57.567] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9107444227711206736:9:0) after 1m0s
[14:29:57.733] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9107444227711206736:9:0)
[14:29:57.733] Timer tick, asking Buildbucket for the build status
[14:29:57.798] Build 8942014165392870448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:29:57.798] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9107444227711206736:10:0) after 1m0s
[14:30:57.820] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9107444227711206736:10:0)
[14:30:57.820] Timer tick, asking Buildbucket for the build status
[14:30:57.918] Build 8942014165392870448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:30:57.918] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9107444227711206736:11:0) after 1m0s
[14:31:57.950] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9107444227711206736:11:0)
[14:31:57.950] Timer tick, asking Buildbucket for the build status
[14:31:58.181] Build 8942014165392870448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:31:58.181] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9107444227711206736:12:0) after 1m0s
[14:32:58.272] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9107444227711206736:12:0)
[14:32:58.272] Timer tick, asking Buildbucket for the build status
[14:32:58.403] Build 8942014165392870448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:32:58.403] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9107444227711206736:13:0) after 1m0s
[14:33:58.498] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9107444227711206736:13:0)
[14:33:58.498] Timer tick, asking Buildbucket for the build status
[14:33:58.640] Build 8942014165392870448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:33:58.640] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9107444227711206736:14:0) after 1m0s
[14:34:58.658] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9107444227711206736:14:0)
[14:34:58.658] Timer tick, asking Buildbucket for the build status
[14:34:58.804] Build 8942014165392870448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:34:58.804] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9107444227711206736:15:0) after 1m0s
[14:35:58.826] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9107444227711206736:15:0)
[14:35:58.826] Timer tick, asking Buildbucket for the build status
[14:35:58.889] Build 8942014165392870448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:35:58.889] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9107444227711206736:16:0) after 1m0s
[14:36:58.907] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9107444227711206736:16:0)
[14:36:58.907] Timer tick, asking Buildbucket for the build status
[14:36:59.211] Build 8942014165392870448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:36:59.211] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9107444227711206736:17:0) after 1m0s
[14:37:59.355] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9107444227711206736:17:0)
[14:37:59.355] Timer tick, asking Buildbucket for the build status
[14:37:59.441] Build 8942014165392870448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:37:59.441] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9107444227711206736:18:0) after 1m0s
[14:38:59.501] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9107444227711206736:18:0)
[14:38:59.501] Timer tick, asking Buildbucket for the build status
[14:38:59.566] Build 8942014165392870448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:38:59.566] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9107444227711206736:19:0) after 1m0s
[14:39:59.642] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9107444227711206736:19:0)
[14:39:59.642] Timer tick, asking Buildbucket for the build status
[14:39:59.783] Build 8942014165392870448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:39:59.783] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9107444227711206736:20:0) after 1m0s
[14:40:59.804] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9107444227711206736:20:0)
[14:40:59.804] Timer tick, asking Buildbucket for the build status
[14:40:59.922] Build 8942014165392870448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:40:59.922] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9107444227711206736:21:0) after 1m0s
[14:41:59.944] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9107444227711206736:21:0)
[14:41:59.944] Timer tick, asking Buildbucket for the build status
[14:42:00.038] Build 8942014165392870448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:42:00.038] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9107444227711206736:22:0) after 1m0s
[14:43:00.060] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9107444227711206736:22:0)
[14:43:00.060] Timer tick, asking Buildbucket for the build status
[14:43:00.160] Build 8942014165392870448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:43:00.160] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9107444227711206736:23:0) after 1m0s
[14:44:00.179] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9107444227711206736:23:0)
[14:44:00.179] Timer tick, asking Buildbucket for the build status
[14:44:00.413] Build 8942014165392870448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:44:00.414] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9107444227711206736:24:0) after 1m0s
[14:45:00.461] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9107444227711206736:24:0)
[14:45:00.461] Timer tick, asking Buildbucket for the build status
[14:45:00.624] Build 8942014165392870448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:45:00.624] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9107444227711206736:25:0) after 1m0s
[14:46:00.686] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9107444227711206736:25:0)
[14:46:00.686] Timer tick, asking Buildbucket for the build status
[14:46:00.735] Build 8942014165392870448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:46:00.735] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9107444227711206736:26:0) after 1m0s
[14:47:00.836] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9107444227711206736:26:0)
[14:47:00.836] Timer tick, asking Buildbucket for the build status
[14:47:00.959] Build 8942014165392870448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:47:00.959] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9107444227711206736:27:0) after 1m0s
[14:48:00.979] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9107444227711206736:27:0)
[14:48:00.979] Timer tick, asking Buildbucket for the build status
[14:48:01.350] Build 8942014165392870448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:48:01.350] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9107444227711206736:28:0) after 1m0s
[14:49:01.372] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9107444227711206736:28:0)
[14:49:01.372] Timer tick, asking Buildbucket for the build status
[14:49:01.936] Build 8942014165392870448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:49:01.936] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9107444227711206736:29:0) after 1m0s
[14:50:01.959] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9107444227711206736:29:0)
[14:50:01.959] Timer tick, asking Buildbucket for the build status
[14:50:02.036] Build 8942014165392870448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:50:02.036] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9107444227711206736:30:0) after 1m0s
[14:51:02.074] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9107444227711206736:30:0)
[14:51:02.074] Timer tick, asking Buildbucket for the build status
[14:51:02.441] Build 8942014165392870448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:51:02.441] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9107444227711206736:31:0) after 1m0s
[14:52:02.544] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9107444227711206736:31:0)
[14:52:02.544] Timer tick, asking Buildbucket for the build status
[14:52:02.593] Build 8942014165392870448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:52:02.593] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9107444227711206736:32:0) after 1m0s
[14:53:02.615] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9107444227711206736:32:0)
[14:53:02.615] Timer tick, asking Buildbucket for the build status
[14:53:02.676] Build 8942014165392870448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:53:02.676] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9107444227711206736:33:0) after 1m0s
[14:54:02.737] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9107444227711206736:33:0)
[14:54:02.737] Timer tick, asking Buildbucket for the build status
[14:54:04.894] Build 8942014165392870448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:54:04.894] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9107444227711206736:34:0) after 1m0s
[14:55:04.921] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9107444227711206736:34:0)
[14:55:04.921] Timer tick, asking Buildbucket for the build status
[14:55:04.987] Build 8942014165392870448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:55:04.987] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9107444227711206736:35:0) after 1m0s
[14:56:05.118] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9107444227711206736:35:0)
[14:56:05.118] Timer tick, asking Buildbucket for the build status
[14:56:05.280] Build 8942014165392870448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:56:05.280] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9107444227711206736:36:0) after 1m0s
[14:57:05.300] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9107444227711206736:36:0)
[14:57:05.300] Timer tick, asking Buildbucket for the build status
[14:57:05.512] Build 8942014165392870448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:57:05.512] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9107444227711206736:37:0) after 1m0s
[14:58:05.536] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9107444227711206736:37:0)
[14:58:05.536] Timer tick, asking Buildbucket for the build status
[14:58:05.600] Build 8942014165392870448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:58:05.600] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9107444227711206736:38:0) after 1m0s
[14:59:05.620] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9107444227711206736:38:0)
[14:59:05.620] Timer tick, asking Buildbucket for the build status
[14:59:05.707] Build 8942014165392870448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:59:05.707] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9107444227711206736:39:0) after 1m0s
[15:00:05.741] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9107444227711206736:39:0)
[15:00:05.741] Timer tick, asking Buildbucket for the build status
[15:00:05.835] Build 8942014165392870448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:00:05.835] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9107444227711206736:40:0) after 1m0s
[15:01:05.861] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9107444227711206736:40:0)
[15:01:05.861] Timer tick, asking Buildbucket for the build status
[15:01:05.916] Build 8942014165392870448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:01:05.916] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9107444227711206736:41:0) after 1m0s
[15:02:05.937] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9107444227711206736:41:0)
[15:02:05.937] Timer tick, asking Buildbucket for the build status
[15:02:06.083] Build 8942014165392870448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:02:06.083] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9107444227711206736:42:0) after 1m0s
[15:03:06.243] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9107444227711206736:42:0)
[15:03:06.243] Timer tick, asking Buildbucket for the build status
[15:03:06.346] Build 8942014165392870448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:03:06.346] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9107444227711206736:43:0) after 1m0s
[15:04:06.372] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9107444227711206736:43:0)
[15:04:06.372] Timer tick, asking Buildbucket for the build status
[15:04:06.427] Build 8942014165392870448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:04:06.427] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9107444227711206736:44:0) after 1m0s
[15:05:06.448] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9107444227711206736:44:0)
[15:05:06.448] Timer tick, asking Buildbucket for the build status
[15:05:06.732] Build 8942014165392870448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:05:06.732] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9107444227711206736:45:0) after 1m0s
[15:06:06.750] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9107444227711206736:45:0)
[15:06:06.750] Timer tick, asking Buildbucket for the build status
[15:06:07.422] Build 8942014165392870448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:06:07.422] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9107444227711206736:46:0) after 1m0s
[15:07:07.454] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9107444227711206736:46:0)
[15:07:07.454] Timer tick, asking Buildbucket for the build status
[15:07:07.711] Build 8942014165392870448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:07:07.711] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9107444227711206736:47:0) after 1m0s
[15:08:07.732] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9107444227711206736:47:0)
[15:08:07.732] Timer tick, asking Buildbucket for the build status
[15:08:08.302] Build 8942014165392870448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:08:08.302] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9107444227711206736:48:0) after 1m0s
[15:09:08.371] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9107444227711206736:48:0)
[15:09:08.371] Timer tick, asking Buildbucket for the build status
[15:09:08.467] Build 8942014165392870448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:09:08.467] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9107444227711206736:49:0) after 1m0s
[15:10:08.488] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9107444227711206736:49:0)
[15:10:08.488] Timer tick, asking Buildbucket for the build status
[15:10:08.703] Build 8942014165392870448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:10:08.703] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9107444227711206736:50:0) after 1m0s
[15:11:08.776] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9107444227711206736:50:0)
[15:11:08.776] Timer tick, asking Buildbucket for the build status
[15:11:08.880] Build 8942014165392870448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:11:08.880] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9107444227711206736:51:0) after 1m0s
[15:12:08.900] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9107444227711206736:51:0)
[15:12:08.900] Timer tick, asking Buildbucket for the build status
[15:12:09.024] Build 8942014165392870448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:12:09.024] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9107444227711206736:52:0) after 1m0s
[15:13:09.046] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9107444227711206736:52:0)
[15:13:09.046] Timer tick, asking Buildbucket for the build status
[15:13:09.130] Build 8942014165392870448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:13:09.130] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9107444227711206736:53:0) after 1m0s
[15:14:09.152] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9107444227711206736:53:0)
[15:14:09.152] Timer tick, asking Buildbucket for the build status
[15:14:09.201] Build 8942014165392870448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:14:09.201] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9107444227711206736:54:0) after 1m0s
[15:15:09.225] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9107444227711206736:54:0)
[15:15:09.225] Timer tick, asking Buildbucket for the build status
[15:15:09.297] Build 8942014165392870448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:15:09.297] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9107444227711206736:55:0) after 1m0s
[15:16:09.319] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9107444227711206736:55:0)
[15:16:09.319] Timer tick, asking Buildbucket for the build status
[15:16:09.455] Build 8942014165392870448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:16:09.455] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9107444227711206736:56:0) after 1m0s
[15:17:09.492] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9107444227711206736:56:0)
[15:17:09.492] Timer tick, asking Buildbucket for the build status
[15:17:09.551] Build 8942014165392870448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:17:09.551] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9107444227711206736:57:0) after 1m0s
[15:18:09.599] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9107444227711206736:57:0)
[15:18:09.599] Timer tick, asking Buildbucket for the build status
[15:18:09.766] Build 8942014165392870448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:18:09.766] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9107444227711206736:58:0) after 1m0s
[15:19:09.786] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9107444227711206736:58:0)
[15:19:09.786] Timer tick, asking Buildbucket for the build status
[15:19:09.858] Build 8942014165392870448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:19:09.858] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9107444227711206736:59:0) after 1m0s
[15:20:09.882] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9107444227711206736:59:0)
[15:20:09.882] Timer tick, asking Buildbucket for the build status
[15:20:10.027] Build 8942014165392870448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:20:10.027] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9107444227711206736:60:0) after 1m0s
[15:21:10.084] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9107444227711206736:60:0)
[15:21:10.084] Timer tick, asking Buildbucket for the build status
[15:21:10.184] Build 8942014165392870448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:21:10.184] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9107444227711206736:61:0) after 1m0s
[15:22:10.344] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9107444227711206736:61:0)
[15:22:10.344] Timer tick, asking Buildbucket for the build status
[15:22:10.475] Build 8942014165392870448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:22:10.475] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9107444227711206736:62:0) after 1m0s
[15:23:10.534] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9107444227711206736:62:0)
[15:23:10.534] Timer tick, asking Buildbucket for the build status
[15:23:10.599] Build 8942014165392870448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:23:10.599] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9107444227711206736:63:0) after 1m0s
[15:24:10.623] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9107444227711206736:63:0)
[15:24:10.623] Timer tick, asking Buildbucket for the build status
[15:24:10.728] Build 8942014165392870448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:24:10.728] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9107444227711206736:64:0) after 1m0s
[15:25:10.814] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9107444227711206736:64:0)
[15:25:10.814] Timer tick, asking Buildbucket for the build status
[15:25:10.904] Build 8942014165392870448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:25:10.904] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9107444227711206736:65:0) after 1m0s
[15:26:10.956] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9107444227711206736:65:0)
[15:26:10.956] Timer tick, asking Buildbucket for the build status
[15:26:11.037] Build 8942014165392870448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:26:11.037] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9107444227711206736:66:0) after 1m0s
[15:27:11.138] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9107444227711206736:66:0)
[15:27:11.138] Timer tick, asking Buildbucket for the build status
[15:27:11.192] Build 8942014165392870448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:27:11.192] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9107444227711206736:67:0) after 1m0s
[15:28:11.213] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9107444227711206736:67:0)
[15:28:11.213] Timer tick, asking Buildbucket for the build status
[15:28:11.429] Build 8942014165392870448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:28:11.429] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9107444227711206736:68:0) after 1m0s
[15:29:11.455] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9107444227711206736:68:0)
[15:29:11.455] Timer tick, asking Buildbucket for the build status
[15:29:11.510] Build 8942014165392870448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:29:11.510] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9107444227711206736:69:0) after 1m0s
[15:30:11.543] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9107444227711206736:69:0)
[15:30:11.544] Timer tick, asking Buildbucket for the build status
[15:30:11.598] Build 8942014165392870448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:30:11.598] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9107444227711206736:70:0) after 1m0s
[15:31:11.639] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9107444227711206736:70:0)
[15:31:11.639] Timer tick, asking Buildbucket for the build status
[15:31:11.711] Build 8942014165392870448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:31:11.711] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9107444227711206736:71:0) after 1m0s
[15:32:11.742] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9107444227711206736:71:0)
[15:32:11.742] Timer tick, asking Buildbucket for the build status
[15:32:13.939] Build 8942014165392870448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:32:13.939] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9107444227711206736:72:0) after 1m0s
[15:33:13.961] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9107444227711206736:72:0)
[15:33:13.961] Timer tick, asking Buildbucket for the build status
[15:33:14.065] Build 8942014165392870448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:33:14.065] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9107444227711206736:73:0) after 1m0s
[15:34:14.096] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9107444227711206736:73:0)
[15:34:14.096] Timer tick, asking Buildbucket for the build status
[15:34:14.241] Build 8942014165392870448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:34:14.241] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9107444227711206736:74:0) after 1m0s
[15:35:14.267] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9107444227711206736:74:0)
[15:35:14.267] Timer tick, asking Buildbucket for the build status
[15:35:14.379] Build 8942014165392870448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:35:14.379] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9107444227711206736:75:0) after 1m0s
[15:36:14.417] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9107444227711206736:75:0)
[15:36:14.417] Timer tick, asking Buildbucket for the build status
[15:36:14.531] Build 8942014165392870448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:36:14.531] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9107444227711206736:76:0) after 1m0s
[15:37:14.556] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9107444227711206736:76:0)
[15:37:14.556] Timer tick, asking Buildbucket for the build status
[15:37:14.912] Build 8942014165392870448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:37:14.913] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9107444227711206736:77:0) after 1m0s
[15:38:15.004] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9107444227711206736:77:0)
[15:38:15.004] Timer tick, asking Buildbucket for the build status
[15:38:15.126] Build 8942014165392870448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:38:15.126] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9107444227711206736:78:0) after 1m0s
[15:39:15.178] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9107444227711206736:78:0)
[15:39:15.178] Timer tick, asking Buildbucket for the build status
[15:39:15.317] Build 8942014165392870448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:39:15.317] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9107444227711206736:79:0) after 1m0s
[15:40:15.341] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9107444227711206736:79:0)
[15:40:15.341] Timer tick, asking Buildbucket for the build status
[15:40:15.441] Build 8942014165392870448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:40:15.441] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9107444227711206736:80:0) after 1m0s
[15:41:15.470] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9107444227711206736:80:0)
[15:41:15.470] Timer tick, asking Buildbucket for the build status
[15:41:15.653] Build 8942014165392870448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:41:15.653] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9107444227711206736:81:0) after 1m0s
[15:42:15.733] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9107444227711206736:81:0)
[15:42:15.733] Timer tick, asking Buildbucket for the build status
[15:42:15.792] Build 8942014165392870448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:42:15.792] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9107444227711206736:82:0) after 1m0s
[15:43:15.814] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9107444227711206736:82:0)
[15:43:15.815] Timer tick, asking Buildbucket for the build status
[15:43:16.028] Build 8942014165392870448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:43:16.028] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9107444227711206736:83:0) after 1m0s
[15:44:16.051] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9107444227711206736:83:0)
[15:44:16.051] Timer tick, asking Buildbucket for the build status
[15:44:16.141] Build 8942014165392870448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:44:16.141] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9107444227711206736:84:0) after 1m0s
[15:45:16.301] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9107444227711206736:84:0)
[15:45:16.301] Timer tick, asking Buildbucket for the build status
[15:45:16.503] Build 8942014165392870448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:45:16.503] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9107444227711206736:85:0) after 1m0s
[15:46:16.526] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9107444227711206736:85:0)
[15:46:16.526] Timer tick, asking Buildbucket for the build status
[15:46:16.600] Build 8942014165392870448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:46:16.600] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9107444227711206736:86:0) after 1m0s
[15:47:16.626] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9107444227711206736:86:0)
[15:47:16.626] Timer tick, asking Buildbucket for the build status
[15:47:16.874] Build 8942014165392870448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:47:16.874] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9107444227711206736:87:0) after 1m0s
[15:48:16.898] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9107444227711206736:87:0)
[15:48:16.898] Timer tick, asking Buildbucket for the build status
[15:48:17.118] Build 8942014165392870448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:48:17.118] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9107444227711206736:88:0) after 1m0s
[15:49:17.145] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9107444227711206736:88:0)
[15:49:17.145] Timer tick, asking Buildbucket for the build status
[15:49:17.204] Build 8942014165392870448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:49:17.204] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9107444227711206736:89:0) after 1m0s
[15:50:17.227] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9107444227711206736:89:0)
[15:50:17.227] Timer tick, asking Buildbucket for the build status
[15:50:17.271] Build 8942014165392870448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:50:17.271] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9107444227711206736:90:0) after 1m0s
[15:51:17.295] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9107444227711206736:90:0)
[15:51:17.295] Timer tick, asking Buildbucket for the build status
[15:51:17.425] Build 8942014165392870448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:51:17.425] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9107444227711206736:91:0) after 1m0s
[15:52:17.707] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9107444227711206736:91:0)
[15:52:17.707] Timer tick, asking Buildbucket for the build status
[15:52:17.762] Build 8942014165392870448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:52:17.762] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9107444227711206736:92:0) after 1m0s
[15:53:17.784] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9107444227711206736:92:0)
[15:53:17.784] Timer tick, asking Buildbucket for the build status
[15:53:17.902] Build 8942014165392870448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:53:17.902] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9107444227711206736:93:0) after 1m0s
[15:54:17.927] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9107444227711206736:93:0)
[15:54:17.927] Timer tick, asking Buildbucket for the build status
[15:54:18.074] Build 8942014165392870448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:54:18.074] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9107444227711206736:94:0) after 1m0s
[15:55:18.097] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9107444227711206736:94:0)
[15:55:18.097] Timer tick, asking Buildbucket for the build status
[15:55:18.157] Build 8942014165392870448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:55:18.157] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9107444227711206736:95:0) after 1m0s
[15:56:18.321] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9107444227711206736:95:0)
[15:56:18.321] Timer tick, asking Buildbucket for the build status
[15:56:18.435] Build 8942014165392870448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:56:18.435] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9107444227711206736:96:0) after 1m0s
[15:57:18.567] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9107444227711206736:96:0)
[15:57:18.567] Timer tick, asking Buildbucket for the build status
[15:57:18.678] Build 8942014165392870448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:57:18.678] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9107444227711206736:97:0) after 1m0s
[15:58:18.704] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9107444227711206736:97:0)
[15:58:18.704] Timer tick, asking Buildbucket for the build status
[15:58:18.853] Build 8942014165392870448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:58:18.853] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9107444227711206736:98:0) after 1m0s
[15:59:18.876] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9107444227711206736:98:0)
[15:59:18.876] Timer tick, asking Buildbucket for the build status
[15:59:18.932] Build 8942014165392870448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:59:18.932] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9107444227711206736:99:0) after 1m0s
[16:00:18.957] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9107444227711206736:99:0)
[16:00:18.957] Timer tick, asking Buildbucket for the build status
[16:00:19.020] Build 8942014165392870448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:00:19.020] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9107444227711206736:100:0) after 1m0s
[16:01:19.056] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9107444227711206736:100:0)
[16:01:19.057] Timer tick, asking Buildbucket for the build status
[16:01:19.302] Build 8942014165392870448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:01:19.302] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9107444227711206736:101:0) after 1m0s
[16:02:19.325] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9107444227711206736:101:0)
[16:02:19.325] Timer tick, asking Buildbucket for the build status
[16:02:19.456] Build 8942014165392870448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:02:19.456] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9107444227711206736:102:0) after 1m0s
[16:02:28.769] Received PubSub notification, asking Buildbucket for the build status
[16:02:28.861] Build 8942014165392870448: status "COMPLETED", result "FAILURE", failure_reason "INFRA_FAILURE", cancelation_reason ""
[16:02:28.861] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"completed_ts": "1530633747458450",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1530627775731760",
"failure_reason": "INFRA_FAILURE",
"id": "8942014165392870448",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"e27679b15b4bf6837ed43675aab3dbbfe0761f9e\"}}",
"project": "chromium",
"result": "FAILURE",
"result_details_json": "{\"ui\": {\"info\": \"Failure compare_build_artifacts\\nFailure Uncaught Exception\"}, \"build_run_result\": {\"infraFailure\": {\"text\": \"recipe infra failure: Uncaught Exception: AttributeError(\\\"'NoneType' object has no attribute 'itervalues'\\\",)\", \"bootstrapperCallStack\": [], \"type\": \"RECIPE_INFRA_FAILURE\"}, \"recipeResult\": {\"failure\": {\"exception\": {\"traceback\": [\"Traceback (most recent call last):\", \" File \\\"C:\\\\b\\\\swarming\\\\w\\\\ir\\\\kitchen-checkout\\\\recipe_engine\\\\recipe_engine\\\\run.py\\\", line 287, in _new_run\", \" recipe_result = recipe_script.run(api, self.properties, self.environ)\", \" File \\\"C:\\\\b\\\\swarming\\\\w\\\\ir\\\\kitchen-checkout\\\\recipe_engine\\\\recipe_engine\\\\loader.py\\\", line 108, in run\", \" self.run_steps, properties, environ, self.PROPERTIES, api=api)\", \" File \\\"C:\\\\b\\\\swarming\\\\w\\\\ir\\\\kitchen-checkout\\\\recipe_engine\\\\recipe_engine\\\\loader.py\\\", line 636, in invoke_with_properties\", \" arg_names, **additional_args)\", \" File \\\"C:\\\\b\\\\swarming\\\\w\\\\ir\\\\kitchen-checkout\\\\recipe_engine\\\\recipe_engine\\\\loader.py\\\", line 599, in _invoke_with_properties\", \" return callable_obj(*props, **additional_args)\", \" File \\\"C:\\\\b\\\\swarming\\\\w\\\\ir\\\\kitchen-checkout\\\\build\\\\scripts/slave\\\\recipes\\\\swarming/deterministic_build.py\\\", line 194, in RunSteps\", \" str(api.chromium.output_dir).rstrip('\\\\\\\\/') + '.2')\", \" File \\\"C:\\\\b\\\\swarming\\\\w\\\\ir\\\\kitchen-checkout\\\\recipe_engine\\\\recipe_engine\\\\recipe_api.py\\\", line 759, in _inner\", \" return func(*a, **kw)\", \" File \\\"C:\\\\b\\\\swarming\\\\w\\\\ir\\\\kitchen-checkout\\\\build\\\\scripts/slave\\\\recipe_modules\\\\isolate\\\\api.py\\\", line 350, in compare_build_artifacts\", \" self.archive_differences(first_dir, second_dir, step_result.json.output)\", \" File \\\"C:\\\\b\\\\swarming\\\\w\\\\ir\\\\kitchen-checkout\\\\recipe_engine\\\\recipe_engine\\\\recipe_api.py\\\", line 759, in _inner\", \" return func(*a, **kw)\", \" File \\\"C:\\\\b\\\\swarming\\\\w\\\\ir\\\\kitchen-checkout\\\\build\\\\scripts/slave\\\\recipe_modules\\\\isolate\\\\api.py\\\", line 302, in archive_differences\", \" diffs = list(itertools.chain.from_iterable(values.itervalues()))\", \"AttributeError: 'NoneType' object has no attribute 'itervalues'\"]}, \"humanReason\": \"Uncaught Exception: AttributeError(\\\"'NoneType' object has no attribute 'itervalues'\\\",)\"}}, \"recipeExitCode\": {\"value\": 1}, \"recipe\": {\"name\": \"swarming/deterministic_build\", \"repository\": \"\", \"revision\": \"\"}, \"annotationUrl\": \"logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8942014165392870448/+/annotations\"}, \"swarming\": {\"bot_dimensions\": {\"machine_type\": [\"n1-standard-8\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"], \"locale\": [\"en_US.cp1252\"], \"os\": [\"Windows\", \"Windows-10\", \"Windows-10-15063\"], \"builder\": [\"Windows deterministic\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Haswell_GCE\"], \"python\": [\"2.7.6\"], \"server_version\": [\"3675-21ffa58\"], \"image\": [\"chrome-win10-1703-2018-04-20-61acaf8983f\"], \"gpu\": [\"none\"], \"cores\": [\"8\"], \"integrity\": [\"high\"], \"id\": [\"swarm2173-c4\"], \"pool\": [\"luci.chromium.ci\"], \"caches\": [\"git\", \"goma_v2\", \"vpython\"]}}, \"properties\": {\"got_nacl_revision\": \"030b88a205c9ce9a82d68798bfaa059fd55216cd\", \"got_swarming_client_revision\": \"9a518d097dca20b7b00ce3bdfc5d418ccc79893a\", \"got_revision\": \"e27679b15b4bf6837ed43675aab3dbbfe0761f9e\", \"recipe\": \"swarming/deterministic_build\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#23815}\", \"$recipe_engine/path\": {\"cache_dir\": \"C:\\\\b\\\\swarming\\\\w\\\\ir\\\\cache\", \"temp_dir\": \"C:\\\\b\\\\swarming\\\\w\\\\ir\\\\tmp\\\\rt\"}, \"got_revision_cp\": \"refs/heads/master@{#572200}\", \"branch\": \"refs/heads/master\", \"revision\": \"e27679b15b4bf6837ed43675aab3dbbfe0761f9e\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"buildername\": \"Windows deterministic\", \"got_webrtc_revision\": \"46f858a626d53825edde1d74573e4f38195a4546\", \"mastername\": \"chromium.fyi\", \"got_angle_revision\": \"92da844eb7543d2ac39fe2ae9c62edd3a3b6891f\", \"buildbucket\": {\"hostname\": \"cr-buildbucket.appspot.com\", \"build\": {\"created_ts\": 1530627775731760, \"tags\": [\"builder:Windows deterministic\", \"buildset:commit/git/e27679b15b4bf6837ed43675aab3dbbfe0761f9e\", \"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/e27679b15b4bf6837ed43675aab3dbbfe0761f9e\", \"gitiles_ref:refs/heads/master\", \"scheduler_invocation_id:9107444227711206736\", \"scheduler_job_id:chromium/Windows deterministic\", \"user_agent:luci-scheduler\"], \"bucket\": \"luci.chromium.ci\", \"created_by\": \"user:luci-scheduler@appspot.gserviceaccount.com\", \"project\": \"chromium\", \"id\": \"8942014165392870448\"}}, \"got_v8_revision\": \"91a410dd50a2e4059f2ab37c249319ff256b9785\", \"got_v8_revision_cp\": \"refs/heads/6.9.279@{#1}\", \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"buildnumber\": 9362, \"path_config\": \"generic\", \"bot_id\": \"swarm2173-c4\", \"got_buildtools_revision\": \"0dd5c6f980d22be96b728155249df2da355989d9\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1530627894524090",
"status": "COMPLETED",
"status_changed_ts": "1530633748425380",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/9362",
"builder:Windows deterministic",
"buildset:commit/git/e27679b15b4bf6837ed43675aab3dbbfe0761f9e",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/e27679b15b4bf6837ed43675aab3dbbfe0761f9e",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9107444227711206736",
"scheduler_job_id:chromium/Windows deterministic",
"swarming_dimension:builder:Windows deterministic",
"swarming_dimension:cores:8",
"swarming_dimension:cpu:x86-64",
"swarming_dimension:os:Windows-10",
"swarming_dimension:pool:luci.chromium.ci",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:build_address:luci.chromium.ci/Windows deterministic/9362",
"swarming_tag:buildbucket_bucket:luci.chromium.ci",
"swarming_tag:buildbucket_build_id:8942014165392870448",
"swarming_tag:buildbucket_hostname:cr-buildbucket.appspot.com",
"swarming_tag:buildbucket_template_canary:0",
"swarming_tag:buildbucket_template_revision:4e8cd6081d2622e2214b4bbd0cdf5e2ba5406635",
"swarming_tag:builder:Windows deterministic",
"swarming_tag:buildset:commit/git/e27679b15b4bf6837ed43675aab3dbbfe0761f9e",
"swarming_tag:buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/e27679b15b4bf6837ed43675aab3dbbfe0761f9e",
"swarming_tag:cores:8",
"swarming_tag:cpu:x86-64",
"swarming_tag:gitiles_ref:refs/heads/master",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8942014165392870448/+/annotations",
"swarming_tag:luci_project:chromium",
"swarming_tag:os:Windows-10",
"swarming_tag:pool:luci.chromium.ci",
"swarming_tag:priority:30",
"swarming_tag:recipe_name:swarming/deterministic_build",
"swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build",
"swarming_tag:scheduler_invocation_id:9107444227711206736",
"swarming_tag:scheduler_job_id:chromium/Windows deterministic",
"swarming_tag:service_account:chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"swarming_tag:swarming.pool.template:skip",
"swarming_tag:swarming.pool.version:761be570b9ddc8b12cd9bf031b7ba136a1879f33",
"swarming_tag:user:None",
"swarming_tag:user_agent:luci-scheduler",
"swarming_tag:vpython:native-python-wrapper",
"swarming_task_id:3e7957e708175a10",
"user_agent:luci-scheduler"
],
"updated_ts": "1530633748426650",
"url": "https://ci.chromium.org/p/chromium/builders/luci.chromium.ci/Windows deterministic/9362",
"utcnow_ts": "1530633748841160"
}
}
[16:02:28.861] Invocation finished in 1h39m34.723799433s with status FAILED