[13:14:50.135] New invocation is queued and will start shortly
[13:14:51.290] Starting the invocation (attempt 1)
[13:14:51.334] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[13:14:51.335] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[13:14:51.335] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9106723734383701584",
"parameters_json": "{\"builder_name\":\"Windows deterministic\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src.git\",\"revision\":\"0ee8d2d8fa797bf1da0d7909ec2a05b0d63e1620\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Windows deterministic",
"scheduler_invocation_id:9106723734383701584",
"scheduler_job_id:chromium/Windows deterministic",
"user_agent:luci-scheduler",
"buildset:commit/git/0ee8d2d8fa797bf1da0d7909ec2a05b0d63e1620",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/0ee8d2d8fa797bf1da0d7909ec2a05b0d63e1620",
"gitiles_ref:refs/heads/master"
]
}
[13:14:53.035] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1531314891434510",
"id": "8941293672358361168",
"lease_expiration_ts": "1531376092576180",
"lease_key": "668960948",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"0ee8d2d8fa797bf1da0d7909ec2a05b0d63e1620\"}}",
"project": "chromium",
"result_details_json": "null",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1531314892916060",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/9470",
"builder:Windows deterministic",
"buildset:commit/git/0ee8d2d8fa797bf1da0d7909ec2a05b0d63e1620",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/0ee8d2d8fa797bf1da0d7909ec2a05b0d63e1620",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9106723734383701584",
"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/9470",
"swarming_tag:buildbucket_bucket:luci.chromium.ci",
"swarming_tag:buildbucket_build_id:8941293672358361168",
"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/0ee8d2d8fa797bf1da0d7909ec2a05b0d63e1620",
"swarming_tag:buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/0ee8d2d8fa797bf1da0d7909ec2a05b0d63e1620",
"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/8941293672358361168/+/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:9106723734383701584",
"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:da7d455add6a42e0e621c129fc41b6a00fdc4716",
"swarming_tag:user:None",
"swarming_tag:user_agent:luci-scheduler",
"swarming_tag:vpython:native-python-wrapper",
"swarming_task_id:3ea24c763d6d8c10",
"user_agent:luci-scheduler"
],
"updated_ts": "1531314892916710",
"url": "https://ci.chromium.org/p/chromium/builders/luci.chromium.ci/Windows deterministic/9470",
"utcnow_ts": "1531314893012360"
}
}
[13:14:53.035] Task URL: https://ci.chromium.org/p/chromium/builders/luci.chromium.ci/Windows deterministic/9470
[13:14:53.035] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106723734383701584:2:0) after 1m0s
[13:15:53.052] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106723734383701584:2:0)
[13:15:53.052] Timer tick, asking Buildbucket for the build status
[13:15:53.166] Build 8941293672358361168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:15:53.166] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106723734383701584:3:0) after 1m0s
[13:16:49.601] Received PubSub notification, asking Buildbucket for the build status
[13:16:49.783] Build 8941293672358361168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:16:53.267] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106723734383701584:3:0)
[13:16:53.267] Timer tick, asking Buildbucket for the build status
[13:16:53.326] Build 8941293672358361168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:16:53.326] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106723734383701584:5:0) after 1m0s
[13:17:53.341] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106723734383701584:5:0)
[13:17:53.341] Timer tick, asking Buildbucket for the build status
[13:17:53.395] Build 8941293672358361168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:17:53.395] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106723734383701584:6:0) after 1m0s
[13:18:53.521] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106723734383701584:6:0)
[13:18:53.521] Timer tick, asking Buildbucket for the build status
[13:18:53.867] Build 8941293672358361168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:18:53.867] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106723734383701584:7:0) after 1m0s
[13:19:53.961] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106723734383701584:7:0)
[13:19:53.961] Timer tick, asking Buildbucket for the build status
[13:19:54.078] Build 8941293672358361168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:19:54.078] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106723734383701584:8:0) after 1m0s
[13:20:54.097] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106723734383701584:8:0)
[13:20:54.098] Timer tick, asking Buildbucket for the build status
[13:20:54.168] Build 8941293672358361168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:20:54.168] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106723734383701584:9:0) after 1m0s
[13:21:54.184] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106723734383701584:9:0)
[13:21:54.184] Timer tick, asking Buildbucket for the build status
[13:21:54.264] Build 8941293672358361168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:21:54.264] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106723734383701584:10:0) after 1m0s
[13:22:54.283] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106723734383701584:10:0)
[13:22:54.283] Timer tick, asking Buildbucket for the build status
[13:22:54.321] Build 8941293672358361168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:22:54.321] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106723734383701584:11:0) after 1m0s
[13:23:54.450] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106723734383701584:11:0)
[13:23:54.450] Timer tick, asking Buildbucket for the build status
[13:23:54.546] Build 8941293672358361168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:23:54.547] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106723734383701584:12:0) after 1m0s
[13:24:54.565] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106723734383701584:12:0)
[13:24:54.565] Timer tick, asking Buildbucket for the build status
[13:24:54.654] Build 8941293672358361168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:24:54.654] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106723734383701584:13:0) after 1m0s
[13:25:54.673] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106723734383701584:13:0)
[13:25:54.673] Timer tick, asking Buildbucket for the build status
[13:25:54.766] Build 8941293672358361168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:25:54.767] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106723734383701584:14:0) after 1m0s
[13:26:54.784] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106723734383701584:14:0)
[13:26:54.784] Timer tick, asking Buildbucket for the build status
[13:26:54.842] Build 8941293672358361168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:26:54.842] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106723734383701584:15:0) after 1m0s
[13:27:54.859] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106723734383701584:15:0)
[13:27:54.859] Timer tick, asking Buildbucket for the build status
[13:27:54.897] Build 8941293672358361168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:27:54.897] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106723734383701584:16:0) after 1m0s
[13:28:54.950] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106723734383701584:16:0)
[13:28:54.950] Timer tick, asking Buildbucket for the build status
[13:28:55.002] Build 8941293672358361168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:28:55.002] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106723734383701584:17:0) after 1m0s
[13:29:55.039] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106723734383701584:17:0)
[13:29:55.039] Timer tick, asking Buildbucket for the build status
[13:29:55.267] Build 8941293672358361168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:29:55.267] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106723734383701584:18:0) after 1m0s
[13:30:55.322] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106723734383701584:18:0)
[13:30:55.322] Timer tick, asking Buildbucket for the build status
[13:30:55.434] Build 8941293672358361168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:30:55.434] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106723734383701584:19:0) after 1m0s
[13:31:55.450] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106723734383701584:19:0)
[13:31:55.450] Timer tick, asking Buildbucket for the build status
[13:31:55.490] Build 8941293672358361168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:31:55.490] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106723734383701584:20:0) after 1m0s
[13:32:55.507] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106723734383701584:20:0)
[13:32:55.507] Timer tick, asking Buildbucket for the build status
[13:32:55.552] Build 8941293672358361168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:32:55.552] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106723734383701584:21:0) after 1m0s
[13:33:55.579] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106723734383701584:21:0)
[13:33:55.579] Timer tick, asking Buildbucket for the build status
[13:33:55.693] Build 8941293672358361168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:33:55.693] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106723734383701584:22:0) after 1m0s
[13:34:55.708] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106723734383701584:22:0)
[13:34:55.708] Timer tick, asking Buildbucket for the build status
[13:34:55.777] Build 8941293672358361168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:34:55.777] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106723734383701584:23:0) after 1m0s
[13:35:55.792] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106723734383701584:23:0)
[13:35:55.792] Timer tick, asking Buildbucket for the build status
[13:35:55.831] Build 8941293672358361168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:35:55.831] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106723734383701584:24:0) after 1m0s
[13:36:55.867] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106723734383701584:24:0)
[13:36:55.867] Timer tick, asking Buildbucket for the build status
[13:36:55.897] Build 8941293672358361168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:36:55.897] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106723734383701584:25:0) after 1m0s
[13:37:55.916] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106723734383701584:25:0)
[13:37:55.916] Timer tick, asking Buildbucket for the build status
[13:37:55.947] Build 8941293672358361168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:37:55.947] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106723734383701584:26:0) after 1m0s
[13:38:55.980] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106723734383701584:26:0)
[13:38:55.980] Timer tick, asking Buildbucket for the build status
[13:38:56.020] Build 8941293672358361168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:38:56.020] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106723734383701584:27:0) after 1m0s
[13:39:56.148] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106723734383701584:27:0)
[13:39:56.148] Timer tick, asking Buildbucket for the build status
[13:39:56.205] Build 8941293672358361168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:39:56.205] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106723734383701584:28:0) after 1m0s
[13:40:56.342] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106723734383701584:28:0)
[13:40:56.342] Timer tick, asking Buildbucket for the build status
[13:40:56.485] Build 8941293672358361168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:40:56.485] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106723734383701584:29:0) after 1m0s
[13:41:56.503] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106723734383701584:29:0)
[13:41:56.503] Timer tick, asking Buildbucket for the build status
[13:41:56.533] Build 8941293672358361168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:41:56.533] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106723734383701584:30:0) after 1m0s
[13:42:56.662] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106723734383701584:30:0)
[13:42:56.662] Timer tick, asking Buildbucket for the build status
[13:42:56.711] Build 8941293672358361168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:42:56.711] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106723734383701584:31:0) after 1m0s
[13:43:56.820] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106723734383701584:31:0)
[13:43:56.820] Timer tick, asking Buildbucket for the build status
[13:43:56.873] Build 8941293672358361168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:43:56.873] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106723734383701584:32:0) after 1m0s
[13:44:56.890] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106723734383701584:32:0)
[13:44:56.890] Timer tick, asking Buildbucket for the build status
[13:44:56.936] Build 8941293672358361168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:44:56.936] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106723734383701584:33:0) after 1m0s
[13:45:56.954] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106723734383701584:33:0)
[13:45:56.954] Timer tick, asking Buildbucket for the build status
[13:45:57.028] Build 8941293672358361168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:45:57.028] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106723734383701584:34:0) after 1m0s
[13:46:57.154] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106723734383701584:34:0)
[13:46:57.154] Timer tick, asking Buildbucket for the build status
[13:46:57.215] Build 8941293672358361168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:46:57.215] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106723734383701584:35:0) after 1m0s
[13:47:57.341] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106723734383701584:35:0)
[13:47:57.341] Timer tick, asking Buildbucket for the build status
[13:47:57.386] Build 8941293672358361168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:47:57.386] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106723734383701584:36:0) after 1m0s
[13:48:57.510] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106723734383701584:36:0)
[13:48:57.510] Timer tick, asking Buildbucket for the build status
[13:48:57.573] Build 8941293672358361168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:48:57.573] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106723734383701584:37:0) after 1m0s
[13:49:57.591] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106723734383701584:37:0)
[13:49:57.591] Timer tick, asking Buildbucket for the build status
[13:49:57.620] Build 8941293672358361168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:49:57.620] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106723734383701584:38:0) after 1m0s
[13:50:57.647] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106723734383701584:38:0)
[13:50:57.647] Timer tick, asking Buildbucket for the build status
[13:50:57.682] Build 8941293672358361168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:50:57.682] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106723734383701584:39:0) after 1m0s
[13:51:57.702] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106723734383701584:39:0)
[13:51:57.702] Timer tick, asking Buildbucket for the build status
[13:51:57.762] Build 8941293672358361168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:51:57.762] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106723734383701584:40:0) after 1m0s
[13:52:57.796] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106723734383701584:40:0)
[13:52:57.796] Timer tick, asking Buildbucket for the build status
[13:52:57.838] Build 8941293672358361168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:52:57.838] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106723734383701584:41:0) after 1m0s
[13:53:57.861] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106723734383701584:41:0)
[13:53:57.861] Timer tick, asking Buildbucket for the build status
[13:53:58.055] Build 8941293672358361168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:53:58.055] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106723734383701584:42:0) after 1m0s
[13:54:58.072] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106723734383701584:42:0)
[13:54:58.072] Timer tick, asking Buildbucket for the build status
[13:54:58.146] Build 8941293672358361168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:54:58.146] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106723734383701584:43:0) after 1m0s
[13:55:58.200] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106723734383701584:43:0)
[13:55:58.200] Timer tick, asking Buildbucket for the build status
[13:55:58.308] Build 8941293672358361168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:55:58.308] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106723734383701584:44:0) after 1m0s
[13:56:58.341] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106723734383701584:44:0)
[13:56:58.341] Timer tick, asking Buildbucket for the build status
[13:56:58.436] Build 8941293672358361168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:56:58.436] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106723734383701584:45:0) after 1m0s
[13:57:58.454] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106723734383701584:45:0)
[13:57:58.454] Timer tick, asking Buildbucket for the build status
[13:57:58.496] Build 8941293672358361168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:57:58.496] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106723734383701584:46:0) after 1m0s
[13:58:58.528] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106723734383701584:46:0)
[13:58:58.529] Timer tick, asking Buildbucket for the build status
[13:58:58.574] Build 8941293672358361168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:58:58.574] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106723734383701584:47:0) after 1m0s
[13:59:58.591] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106723734383701584:47:0)
[13:59:58.591] Timer tick, asking Buildbucket for the build status
[13:59:58.973] Build 8941293672358361168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:59:58.973] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106723734383701584:48:0) after 1m0s
[14:00:58.998] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106723734383701584:48:0)
[14:00:58.998] Timer tick, asking Buildbucket for the build status
[14:00:59.036] Build 8941293672358361168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:00:59.036] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106723734383701584:49:0) after 1m0s
[14:01:59.058] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106723734383701584:49:0)
[14:01:59.058] Timer tick, asking Buildbucket for the build status
[14:01:59.239] Build 8941293672358361168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:01:59.239] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106723734383701584:50:0) after 1m0s
[14:02:59.269] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106723734383701584:50:0)
[14:02:59.269] Timer tick, asking Buildbucket for the build status
[14:02:59.459] Build 8941293672358361168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:02:59.459] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106723734383701584:51:0) after 1m0s
[14:03:59.479] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106723734383701584:51:0)
[14:03:59.479] Timer tick, asking Buildbucket for the build status
[14:03:59.724] Build 8941293672358361168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:03:59.724] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106723734383701584:52:0) after 1m0s
[14:04:59.765] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106723734383701584:52:0)
[14:04:59.765] Timer tick, asking Buildbucket for the build status
[14:04:59.921] Build 8941293672358361168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:04:59.921] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106723734383701584:53:0) after 1m0s
[14:05:59.991] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106723734383701584:53:0)
[14:05:59.991] Timer tick, asking Buildbucket for the build status
[14:06:00.482] Build 8941293672358361168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:06:00.482] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106723734383701584:54:0) after 1m0s
[14:07:00.511] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106723734383701584:54:0)
[14:07:00.511] Timer tick, asking Buildbucket for the build status
[14:07:00.582] Build 8941293672358361168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:07:00.582] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106723734383701584:55:0) after 1m0s
[14:08:00.748] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106723734383701584:55:0)
[14:08:00.748] Timer tick, asking Buildbucket for the build status
[14:08:00.838] Build 8941293672358361168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:08:00.838] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106723734383701584:56:0) after 1m0s
[14:09:00.860] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106723734383701584:56:0)
[14:09:00.860] Timer tick, asking Buildbucket for the build status
[14:09:01.232] Build 8941293672358361168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:09:01.232] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106723734383701584:57:0) after 1m0s
[14:10:01.253] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106723734383701584:57:0)
[14:10:01.253] Timer tick, asking Buildbucket for the build status
[14:10:01.291] Build 8941293672358361168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:10:01.291] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106723734383701584:58:0) after 1m0s
[14:11:01.339] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106723734383701584:58:0)
[14:11:01.339] Timer tick, asking Buildbucket for the build status
[14:11:01.380] Build 8941293672358361168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:11:01.380] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106723734383701584:59:0) after 1m0s
[14:12:01.399] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106723734383701584:59:0)
[14:12:01.399] Timer tick, asking Buildbucket for the build status
[14:12:01.732] Build 8941293672358361168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:12:01.732] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106723734383701584:60:0) after 1m0s
[14:13:01.758] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106723734383701584:60:0)
[14:13:01.758] Timer tick, asking Buildbucket for the build status
[14:13:01.905] Build 8941293672358361168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:13:01.905] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106723734383701584:61:0) after 1m0s
[14:14:01.932] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106723734383701584:61:0)
[14:14:01.932] Timer tick, asking Buildbucket for the build status
[14:14:02.030] Build 8941293672358361168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:14:02.030] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106723734383701584:62:0) after 1m0s
[14:15:02.059] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106723734383701584:62:0)
[14:15:02.059] Timer tick, asking Buildbucket for the build status
[14:15:03.229] Build 8941293672358361168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:15:03.229] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106723734383701584:63:0) after 1m0s
[14:16:03.251] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106723734383701584:63:0)
[14:16:03.251] Timer tick, asking Buildbucket for the build status
[14:16:03.364] Build 8941293672358361168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:16:03.364] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106723734383701584:64:0) after 1m0s
[14:17:03.382] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106723734383701584:64:0)
[14:17:03.382] Timer tick, asking Buildbucket for the build status
[14:17:03.487] Build 8941293672358361168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:17:03.487] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106723734383701584:65:0) after 1m0s
[14:18:03.508] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106723734383701584:65:0)
[14:18:03.508] Timer tick, asking Buildbucket for the build status
[14:18:03.662] Build 8941293672358361168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:18:03.662] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106723734383701584:66:0) after 1m0s
[14:19:03.698] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106723734383701584:66:0)
[14:19:03.698] Timer tick, asking Buildbucket for the build status
[14:19:03.823] Build 8941293672358361168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:19:03.823] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106723734383701584:67:0) after 1m0s
[14:20:03.845] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106723734383701584:67:0)
[14:20:03.845] Timer tick, asking Buildbucket for the build status
[14:20:03.893] Build 8941293672358361168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:20:03.893] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106723734383701584:68:0) after 1m0s
[14:21:03.912] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106723734383701584:68:0)
[14:21:03.912] Timer tick, asking Buildbucket for the build status
[14:21:04.262] Build 8941293672358361168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:21:04.262] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106723734383701584:69:0) after 1m0s
[14:22:04.309] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106723734383701584:69:0)
[14:22:04.309] Timer tick, asking Buildbucket for the build status
[14:22:04.772] Build 8941293672358361168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:22:04.772] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106723734383701584:70:0) after 1m0s
[14:23:04.880] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106723734383701584:70:0)
[14:23:04.880] Timer tick, asking Buildbucket for the build status
[14:23:04.954] Build 8941293672358361168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:23:04.954] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106723734383701584:71:0) after 1m0s
[14:24:04.977] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106723734383701584:71:0)
[14:24:04.977] Timer tick, asking Buildbucket for the build status
[14:24:05.022] Build 8941293672358361168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:24:05.022] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106723734383701584:72:0) after 1m0s
[14:25:05.048] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106723734383701584:72:0)
[14:25:05.048] Timer tick, asking Buildbucket for the build status
[14:25:05.124] Build 8941293672358361168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:25:05.124] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106723734383701584:73:0) after 1m0s
[14:26:05.223] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106723734383701584:73:0)
[14:26:05.223] Timer tick, asking Buildbucket for the build status
[14:26:05.283] Build 8941293672358361168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:26:05.283] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106723734383701584:74:0) after 1m0s
[14:27:05.377] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106723734383701584:74:0)
[14:27:05.377] Timer tick, asking Buildbucket for the build status
[14:27:05.500] Build 8941293672358361168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:27:05.500] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106723734383701584:75:0) after 1m0s
[14:28:05.522] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106723734383701584:75:0)
[14:28:05.522] Timer tick, asking Buildbucket for the build status
[14:28:05.597] Build 8941293672358361168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:28:05.597] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106723734383701584:76:0) after 1m0s
[14:29:05.618] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106723734383701584:76:0)
[14:29:05.618] Timer tick, asking Buildbucket for the build status
[14:29:05.688] Build 8941293672358361168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:29:05.688] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106723734383701584:77:0) after 1m0s
[14:30:05.724] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106723734383701584:77:0)
[14:30:05.724] Timer tick, asking Buildbucket for the build status
[14:30:05.783] Build 8941293672358361168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:30:05.783] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106723734383701584:78:0) after 1m0s
[14:31:05.818] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106723734383701584:78:0)
[14:31:05.818] Timer tick, asking Buildbucket for the build status
[14:31:05.868] Build 8941293672358361168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:31:05.868] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106723734383701584:79:0) after 1m0s
[14:32:05.895] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106723734383701584:79:0)
[14:32:05.895] Timer tick, asking Buildbucket for the build status
[14:32:05.949] Build 8941293672358361168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:32:05.949] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106723734383701584:80:0) after 1m0s
[14:33:05.975] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106723734383701584:80:0)
[14:33:05.975] Timer tick, asking Buildbucket for the build status
[14:33:06.076] Build 8941293672358361168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:33:06.077] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106723734383701584:81:0) after 1m0s
[14:34:06.098] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106723734383701584:81:0)
[14:34:06.098] Timer tick, asking Buildbucket for the build status
[14:34:06.199] Build 8941293672358361168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:34:06.199] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106723734383701584:82:0) after 1m0s
[14:35:06.219] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106723734383701584:82:0)
[14:35:06.219] Timer tick, asking Buildbucket for the build status
[14:35:06.426] Build 8941293672358361168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:35:06.426] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106723734383701584:83:0) after 1m0s
[14:36:06.449] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106723734383701584:83:0)
[14:36:06.449] Timer tick, asking Buildbucket for the build status
[14:36:06.511] Build 8941293672358361168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:36:06.511] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106723734383701584:84:0) after 1m0s
[14:37:06.533] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106723734383701584:84:0)
[14:37:06.533] Timer tick, asking Buildbucket for the build status
[14:37:06.951] Build 8941293672358361168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:37:06.951] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106723734383701584:85:0) after 1m0s
[14:38:06.976] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106723734383701584:85:0)
[14:38:06.976] Timer tick, asking Buildbucket for the build status
[14:38:07.017] Build 8941293672358361168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:38:07.018] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106723734383701584:86:0) after 1m0s
[14:39:07.035] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106723734383701584:86:0)
[14:39:07.035] Timer tick, asking Buildbucket for the build status
[14:39:07.171] Build 8941293672358361168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:39:07.171] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106723734383701584:87:0) after 1m0s
[14:40:07.247] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106723734383701584:87:0)
[14:40:07.247] Timer tick, asking Buildbucket for the build status
[14:40:07.328] Build 8941293672358361168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:40:07.328] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106723734383701584:88:0) after 1m0s
[14:41:07.349] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106723734383701584:88:0)
[14:41:07.349] Timer tick, asking Buildbucket for the build status
[14:41:07.408] Build 8941293672358361168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:41:07.408] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106723734383701584:89:0) after 1m0s
[14:42:07.428] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106723734383701584:89:0)
[14:42:07.428] Timer tick, asking Buildbucket for the build status
[14:42:07.510] Build 8941293672358361168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:42:07.510] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106723734383701584:90:0) after 1m0s
[14:43:07.531] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106723734383701584:90:0)
[14:43:07.531] Timer tick, asking Buildbucket for the build status
[14:43:07.600] Build 8941293672358361168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:43:07.600] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106723734383701584:91:0) after 1m0s
[14:44:07.638] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106723734383701584:91:0)
[14:44:07.638] Timer tick, asking Buildbucket for the build status
[14:44:07.694] Build 8941293672358361168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:44:07.694] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106723734383701584:92:0) after 1m0s
[14:45:07.790] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106723734383701584:92:0)
[14:45:07.790] Timer tick, asking Buildbucket for the build status
[14:45:07.929] Build 8941293672358361168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:45:07.929] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106723734383701584:93:0) after 1m0s
[14:46:08.029] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106723734383701584:93:0)
[14:46:08.029] Timer tick, asking Buildbucket for the build status
[14:46:08.060] Build 8941293672358361168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:46:08.060] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106723734383701584:94:0) after 1m0s
[14:47:08.100] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106723734383701584:94:0)
[14:47:08.100] Timer tick, asking Buildbucket for the build status
[14:47:08.262] Build 8941293672358361168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:47:08.262] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106723734383701584:95:0) after 1m0s
[14:48:08.284] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106723734383701584:95:0)
[14:48:08.284] Timer tick, asking Buildbucket for the build status
[14:48:08.318] Build 8941293672358361168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:48:08.318] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106723734383701584:96:0) after 1m0s
[14:49:08.337] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106723734383701584:96:0)
[14:49:08.337] Timer tick, asking Buildbucket for the build status
[14:49:08.382] Build 8941293672358361168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:49:08.382] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106723734383701584:97:0) after 1m0s
[14:50:08.403] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106723734383701584:97:0)
[14:50:08.403] Timer tick, asking Buildbucket for the build status
[14:50:08.442] Build 8941293672358361168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:50:08.442] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106723734383701584:98:0) after 1m0s
[14:51:08.462] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106723734383701584:98:0)
[14:51:08.462] Timer tick, asking Buildbucket for the build status
[14:51:10.931] Build 8941293672358361168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:51:10.931] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106723734383701584:99:0) after 1m0s
[14:52:10.963] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106723734383701584:99:0)
[14:52:10.963] Timer tick, asking Buildbucket for the build status
[14:52:11.274] Build 8941293672358361168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:52:11.274] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106723734383701584:100:0) after 1m0s
[14:53:11.295] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106723734383701584:100:0)
[14:53:11.295] Timer tick, asking Buildbucket for the build status
[14:53:11.363] Build 8941293672358361168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:53:11.363] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106723734383701584:101:0) after 1m0s
[14:54:11.385] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106723734383701584:101:0)
[14:54:11.385] Timer tick, asking Buildbucket for the build status
[14:54:11.506] Build 8941293672358361168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:54:11.506] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106723734383701584:102:0) after 1m0s
[14:55:11.530] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106723734383701584:102:0)
[14:55:11.530] Timer tick, asking Buildbucket for the build status
[14:55:11.594] Build 8941293672358361168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:55:11.594] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106723734383701584:103:0) after 1m0s
[14:56:11.617] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106723734383701584:103:0)
[14:56:11.617] Timer tick, asking Buildbucket for the build status
[14:56:11.653] Build 8941293672358361168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:56:11.653] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106723734383701584:104:0) after 1m0s
[14:57:11.701] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106723734383701584:104:0)
[14:57:11.701] Timer tick, asking Buildbucket for the build status
[14:57:11.796] Build 8941293672358361168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:57:11.796] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106723734383701584:105:0) after 1m0s
[14:58:11.827] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106723734383701584:105:0)
[14:58:11.827] Timer tick, asking Buildbucket for the build status
[14:58:11.887] Build 8941293672358361168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:58:11.887] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106723734383701584:106:0) after 1m0s
[14:59:11.908] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106723734383701584:106:0)
[14:59:11.908] Timer tick, asking Buildbucket for the build status
[14:59:11.975] Build 8941293672358361168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:59:11.975] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106723734383701584:107:0) after 1m0s
[15:00:01.827] Received PubSub notification, asking Buildbucket for the build status
[15:00:03.635] Build 8941293672358361168: status "COMPLETED", result "FAILURE", failure_reason "INFRA_FAILURE", cancelation_reason ""
[15:00:03.635] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"completed_ts": "1531321199124690",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1531314891434510",
"failure_reason": "INFRA_FAILURE",
"id": "8941293672358361168",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"0ee8d2d8fa797bf1da0d7909ec2a05b0d63e1620\"}}",
"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/8941293672358361168/+/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\": \"0ba35b21089a442dd4f25c35fd571d46cb4aa199\", \"got_swarming_client_revision\": \"9a518d097dca20b7b00ce3bdfc5d418ccc79893a\", \"got_revision\": \"0ee8d2d8fa797bf1da0d7909ec2a05b0d63e1620\", \"recipe\": \"swarming/deterministic_build\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#23896}\", \"$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@{#574137}\", \"branch\": \"refs/heads/master\", \"revision\": \"0ee8d2d8fa797bf1da0d7909ec2a05b0d63e1620\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"buildername\": \"Windows deterministic\", \"got_webrtc_revision\": \"ef43aafcf5953845b71840e14c94942d60c541c1\", \"mastername\": \"chromium.fyi\", \"got_angle_revision\": \"a6a7842fdbf5e5781786a82ede91771fe5d2c31c\", \"buildbucket\": {\"hostname\": \"cr-buildbucket.appspot.com\", \"build\": {\"created_ts\": 1531314891434510, \"tags\": [\"builder:Windows deterministic\", \"buildset:commit/git/0ee8d2d8fa797bf1da0d7909ec2a05b0d63e1620\", \"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/0ee8d2d8fa797bf1da0d7909ec2a05b0d63e1620\", \"gitiles_ref:refs/heads/master\", \"scheduler_invocation_id:9106723734383701584\", \"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\": \"8941293672358361168\"}}, \"got_v8_revision\": \"8885d8b260efe7b15872bef307567a4ee6d5a7cb\", \"got_v8_revision_cp\": \"refs/heads/6.9.345@{#1}\", \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"buildnumber\": 9470, \"path_config\": \"generic\", \"bot_id\": \"swarm2173-c4\", \"got_buildtools_revision\": \"0dd5c6f980d22be96b728155249df2da355989d9\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1531315008769640",
"status": "COMPLETED",
"status_changed_ts": "1531321201067940",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/9470",
"builder:Windows deterministic",
"buildset:commit/git/0ee8d2d8fa797bf1da0d7909ec2a05b0d63e1620",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/0ee8d2d8fa797bf1da0d7909ec2a05b0d63e1620",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9106723734383701584",
"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/9470",
"swarming_tag:buildbucket_bucket:luci.chromium.ci",
"swarming_tag:buildbucket_build_id:8941293672358361168",
"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/0ee8d2d8fa797bf1da0d7909ec2a05b0d63e1620",
"swarming_tag:buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/0ee8d2d8fa797bf1da0d7909ec2a05b0d63e1620",
"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/8941293672358361168/+/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:9106723734383701584",
"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:da7d455add6a42e0e621c129fc41b6a00fdc4716",
"swarming_tag:user:None",
"swarming_tag:user_agent:luci-scheduler",
"swarming_tag:vpython:native-python-wrapper",
"swarming_task_id:3ea24c763d6d8c10",
"user_agent:luci-scheduler"
],
"updated_ts": "1531321201072930",
"url": "https://ci.chromium.org/p/chromium/builders/luci.chromium.ci/Windows deterministic/9470",
"utcnow_ts": "1531321203557520"
}
}
[15:00:03.635] Invocation finished in 1h45m13.516249158s with status FAILED