[16:14:18.236] New invocation is queued and will start shortly
[16:14:19.532] Starting the invocation (attempt 1)
[16:14:19.569] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[16:14:19.569] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[16:14:19.569] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9093575883082699872",
"parameters_json": "{\"builder_name\":\"Windows deterministic\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src.git\",\"revision\":\"06b8660d287e193fa9bf5e0ae16a13db5b2897a2\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Windows deterministic",
"scheduler_invocation_id:9093575883082699872",
"scheduler_job_id:chromium/Windows deterministic",
"user_agent:luci-scheduler",
"buildset:commit/git/06b8660d287e193fa9bf5e0ae16a13db5b2897a2",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/06b8660d287e193fa9bf5e0ae16a13db5b2897a2",
"gitiles_ref:refs/heads/master"
]
}
[16:14:20.855] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1543853659662044",
"id": "8928145820924046240",
"lease_expiration_ts": "1543972460347857",
"lease_key": "2036541798",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"06b8660d287e193fa9bf5e0ae16a13db5b2897a2\"}}",
"project": "chromium",
"result_details_json": "null",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1543853660681586",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/10946",
"builder:Windows deterministic",
"buildset:commit/git/06b8660d287e193fa9bf5e0ae16a13db5b2897a2",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/06b8660d287e193fa9bf5e0ae16a13db5b2897a2",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9093575883082699872",
"scheduler_job_id:chromium/Windows deterministic",
"swarming_dimension:builder:Windows deterministic",
"swarming_dimension:caches:builder_e8fffaf9fac16164b1ec16ebaa1f377b792d39a582f7640830518d8ff7303d0c_v2",
"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/10946",
"swarming_tag:buildbucket_bucket:chromium/ci",
"swarming_tag:buildbucket_build_id:8928145820924046240",
"swarming_tag:buildbucket_hostname:cr-buildbucket.appspot.com",
"swarming_tag:buildbucket_template_canary:0",
"swarming_tag:buildbucket_template_revision:2f19301aebbc779351dc95ab1dc03603fa761240",
"swarming_tag:builder:Windows deterministic",
"swarming_tag:buildset:commit/git/06b8660d287e193fa9bf5e0ae16a13db5b2897a2",
"swarming_tag:buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/06b8660d287e193fa9bf5e0ae16a13db5b2897a2",
"swarming_tag:caches:builder_e8fffaf9fac16164b1ec16ebaa1f377b792d39a582f7640830518d8ff7303d0c_v2",
"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/8928145820924046240/+/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:9093575883082699872",
"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:730145e9e1d37101d0f6001b294c592ab5c2023e",
"swarming_tag:user:None",
"swarming_tag:user_agent:luci-scheduler",
"swarming_tag:vpython:native-python-wrapper",
"swarming_task_id:418daadfce3d1110",
"user_agent:luci-scheduler"
],
"updated_ts": "1543853660681692",
"url": "https://ci.chromium.org/b/8928145820924046240",
"utcnow_ts": "1543853660841605"
}
}
[16:14:20.855] Task URL: https://ci.chromium.org/b/8928145820924046240
[16:14:20.855] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:2:0) after 1m0s
[16:15:20.871] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:2:0)
[16:15:20.871] Timer tick, asking Buildbucket for the build status
[16:15:20.965] Build 8928145820924046240: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:15:20.965] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:3:0) after 1m0s
[16:16:21.000] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:3:0)
[16:16:21.000] Timer tick, asking Buildbucket for the build status
[16:16:21.044] Build 8928145820924046240: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:16:21.044] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:4:0) after 1m0s
[16:16:37.864] Received PubSub notification, asking Buildbucket for the build status
[16:16:37.962] Build 8928145820924046240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:17:21.123] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:4:0)
[16:17:21.123] Timer tick, asking Buildbucket for the build status
[16:17:21.166] Build 8928145820924046240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:17:21.166] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:6:0) after 1m0s
[16:18:21.198] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:6:0)
[16:18:21.198] Timer tick, asking Buildbucket for the build status
[16:18:21.386] Build 8928145820924046240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:18:21.386] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:7:0) after 1m0s
[16:19:21.403] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:7:0)
[16:19:21.403] Timer tick, asking Buildbucket for the build status
[16:19:21.455] Build 8928145820924046240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:19:21.455] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:8:0) after 1m0s
[16:20:21.474] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:8:0)
[16:20:21.474] Timer tick, asking Buildbucket for the build status
[16:20:21.578] Build 8928145820924046240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:20:21.578] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:9:0) after 1m0s
[16:21:21.617] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:9:0)
[16:21:21.617] Timer tick, asking Buildbucket for the build status
[16:21:21.677] Build 8928145820924046240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:21:21.677] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:10:0) after 1m0s
[16:22:21.701] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:10:0)
[16:22:21.701] Timer tick, asking Buildbucket for the build status
[16:22:21.755] Build 8928145820924046240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:22:21.755] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:11:0) after 1m0s
[16:23:21.778] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:11:0)
[16:23:21.778] Timer tick, asking Buildbucket for the build status
[16:23:21.824] Build 8928145820924046240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:23:21.824] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:12:0) after 1m0s
[16:24:21.918] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:12:0)
[16:24:21.918] Timer tick, asking Buildbucket for the build status
[16:24:21.969] Build 8928145820924046240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:24:21.969] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:13:0) after 1m0s
[16:25:21.986] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:13:0)
[16:25:21.986] Timer tick, asking Buildbucket for the build status
[16:25:22.079] Build 8928145820924046240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:25:22.079] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:14:0) after 1m0s
[16:26:22.097] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:14:0)
[16:26:22.097] Timer tick, asking Buildbucket for the build status
[16:26:22.195] Build 8928145820924046240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:26:22.195] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:15:0) after 1m0s
[16:27:22.217] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:15:0)
[16:27:22.217] Timer tick, asking Buildbucket for the build status
[16:27:22.317] Build 8928145820924046240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:27:22.317] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:16:0) after 1m0s
[16:28:22.335] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:16:0)
[16:28:22.335] Timer tick, asking Buildbucket for the build status
[16:28:22.400] Build 8928145820924046240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:28:22.400] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:17:0) after 1m0s
[16:29:22.428] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:17:0)
[16:29:22.428] Timer tick, asking Buildbucket for the build status
[16:29:22.494] Build 8928145820924046240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:29:22.494] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:18:0) after 1m0s
[16:30:22.514] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:18:0)
[16:30:22.514] Timer tick, asking Buildbucket for the build status
[16:30:22.619] Build 8928145820924046240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:30:22.619] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:19:0) after 1m0s
[16:31:22.640] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:19:0)
[16:31:22.640] Timer tick, asking Buildbucket for the build status
[16:31:22.793] Build 8928145820924046240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:31:22.793] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:20:0) after 1m0s
[16:32:22.818] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:20:0)
[16:32:22.818] Timer tick, asking Buildbucket for the build status
[16:32:22.890] Build 8928145820924046240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:32:22.890] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:21:0) after 1m0s
[16:33:22.910] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:21:0)
[16:33:22.910] Timer tick, asking Buildbucket for the build status
[16:33:22.968] Build 8928145820924046240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:33:22.968] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:22:0) after 1m0s
[16:34:22.995] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:22:0)
[16:34:22.995] Timer tick, asking Buildbucket for the build status
[16:34:23.068] Build 8928145820924046240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:34:23.068] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:23:0) after 1m0s
[16:35:23.088] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:23:0)
[16:35:23.088] Timer tick, asking Buildbucket for the build status
[16:35:23.124] Build 8928145820924046240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:35:23.124] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:24:0) after 1m0s
[16:36:23.192] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:24:0)
[16:36:23.192] Timer tick, asking Buildbucket for the build status
[16:36:23.325] Build 8928145820924046240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:36:23.325] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:25:0) after 1m0s
[16:37:23.356] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:25:0)
[16:37:23.356] Timer tick, asking Buildbucket for the build status
[16:37:23.417] Build 8928145820924046240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:37:23.418] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:26:0) after 1m0s
[16:38:23.446] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:26:0)
[16:38:23.446] Timer tick, asking Buildbucket for the build status
[16:38:23.520] Build 8928145820924046240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:38:23.520] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:27:0) after 1m0s
[16:39:23.542] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:27:0)
[16:39:23.542] Timer tick, asking Buildbucket for the build status
[16:39:23.672] Build 8928145820924046240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:39:23.672] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:28:0) after 1m0s
[16:40:23.747] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:28:0)
[16:40:23.747] Timer tick, asking Buildbucket for the build status
[16:40:23.806] Build 8928145820924046240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:40:23.806] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:29:0) after 1m0s
[16:41:23.826] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:29:0)
[16:41:23.826] Timer tick, asking Buildbucket for the build status
[16:41:23.926] Build 8928145820924046240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:41:23.926] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:30:0) after 1m0s
[16:42:23.946] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:30:0)
[16:42:23.946] Timer tick, asking Buildbucket for the build status
[16:42:24.006] Build 8928145820924046240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:42:24.006] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:31:0) after 1m0s
[16:43:24.086] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:31:0)
[16:43:24.086] Timer tick, asking Buildbucket for the build status
[16:43:24.193] Build 8928145820924046240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:43:24.193] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:32:0) after 1m0s
[16:44:24.241] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:32:0)
[16:44:24.241] Timer tick, asking Buildbucket for the build status
[16:44:24.384] Build 8928145820924046240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:44:24.384] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:33:0) after 1m0s
[16:45:24.416] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:33:0)
[16:45:24.416] Timer tick, asking Buildbucket for the build status
[16:45:24.483] Build 8928145820924046240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:45:24.483] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:34:0) after 1m0s
[16:46:24.501] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:34:0)
[16:46:24.501] Timer tick, asking Buildbucket for the build status
[16:46:24.569] Build 8928145820924046240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:46:24.569] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:35:0) after 1m0s
[16:47:24.591] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:35:0)
[16:47:24.591] Timer tick, asking Buildbucket for the build status
[16:47:24.716] Build 8928145820924046240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:47:24.716] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:36:0) after 1m0s
[16:48:24.736] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:36:0)
[16:48:24.736] Timer tick, asking Buildbucket for the build status
[16:48:24.795] Build 8928145820924046240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:48:24.795] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:37:0) after 1m0s
[16:49:24.815] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:37:0)
[16:49:24.815] Timer tick, asking Buildbucket for the build status
[16:49:24.856] Build 8928145820924046240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:49:24.856] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:38:0) after 1m0s
[16:50:24.874] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:38:0)
[16:50:24.874] Timer tick, asking Buildbucket for the build status
[16:50:24.927] Build 8928145820924046240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:50:24.927] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:39:0) after 1m0s
[16:51:24.945] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:39:0)
[16:51:24.945] Timer tick, asking Buildbucket for the build status
[16:51:24.977] Build 8928145820924046240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:51:24.977] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:40:0) after 1m0s
[16:52:24.996] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:40:0)
[16:52:24.996] Timer tick, asking Buildbucket for the build status
[16:52:25.095] Build 8928145820924046240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:52:25.095] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:41:0) after 1m0s
[16:53:25.119] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:41:0)
[16:53:25.119] Timer tick, asking Buildbucket for the build status
[16:53:25.167] Build 8928145820924046240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:53:25.168] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:42:0) after 1m0s
[16:54:25.186] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:42:0)
[16:54:25.186] Timer tick, asking Buildbucket for the build status
[16:54:25.247] Build 8928145820924046240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:54:25.247] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:43:0) after 1m0s
[16:55:25.269] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:43:0)
[16:55:25.269] Timer tick, asking Buildbucket for the build status
[16:55:25.387] Build 8928145820924046240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:55:25.387] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:44:0) after 1m0s
[16:56:25.407] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:44:0)
[16:56:25.407] Timer tick, asking Buildbucket for the build status
[16:56:25.521] Build 8928145820924046240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:56:25.521] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:45:0) after 1m0s
[16:57:25.543] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:45:0)
[16:57:25.543] Timer tick, asking Buildbucket for the build status
[16:57:25.582] Build 8928145820924046240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:57:25.582] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:46:0) after 1m0s
[16:58:25.622] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:46:0)
[16:58:25.622] Timer tick, asking Buildbucket for the build status
[16:58:25.744] Build 8928145820924046240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:58:25.744] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:47:0) after 1m0s
[16:59:25.762] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:47:0)
[16:59:25.762] Timer tick, asking Buildbucket for the build status
[16:59:25.970] Build 8928145820924046240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:59:25.970] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:48:0) after 1m0s
[17:00:25.990] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:48:0)
[17:00:25.990] Timer tick, asking Buildbucket for the build status
[17:00:26.062] Build 8928145820924046240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:00:26.062] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:49:0) after 1m0s
[17:01:26.085] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:49:0)
[17:01:26.086] Timer tick, asking Buildbucket for the build status
[17:01:26.131] Build 8928145820924046240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:01:26.131] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:50:0) after 1m0s
[17:02:26.154] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:50:0)
[17:02:26.154] Timer tick, asking Buildbucket for the build status
[17:02:26.226] Build 8928145820924046240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:02:26.226] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:51:0) after 1m0s
[17:03:26.254] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:51:0)
[17:03:26.254] Timer tick, asking Buildbucket for the build status
[17:03:26.351] Build 8928145820924046240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:03:26.351] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:52:0) after 1m0s
[17:04:26.400] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:52:0)
[17:04:26.400] Timer tick, asking Buildbucket for the build status
[17:04:26.482] Build 8928145820924046240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:04:26.482] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:53:0) after 1m0s
[17:05:26.520] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:53:0)
[17:05:26.520] Timer tick, asking Buildbucket for the build status
[17:05:26.611] Build 8928145820924046240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:05:26.611] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:54:0) after 1m0s
[17:06:26.682] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:54:0)
[17:06:26.682] Timer tick, asking Buildbucket for the build status
[17:06:26.725] Build 8928145820924046240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:06:26.725] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:55:0) after 1m0s
[17:07:26.745] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:55:0)
[17:07:26.745] Timer tick, asking Buildbucket for the build status
[17:07:26.858] Build 8928145820924046240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:07:26.858] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:56:0) after 1m0s
[17:08:26.891] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:56:0)
[17:08:26.891] Timer tick, asking Buildbucket for the build status
[17:08:26.947] Build 8928145820924046240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:08:26.947] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:57:0) after 1m0s
[17:09:27.010] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:57:0)
[17:09:27.010] Timer tick, asking Buildbucket for the build status
[17:09:27.052] Build 8928145820924046240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:09:27.052] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:58:0) after 1m0s
[17:10:27.071] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:58:0)
[17:10:27.071] Timer tick, asking Buildbucket for the build status
[17:10:27.291] Build 8928145820924046240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:10:27.291] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:59:0) after 1m0s
[17:11:27.312] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:59:0)
[17:11:27.312] Timer tick, asking Buildbucket for the build status
[17:11:27.379] Build 8928145820924046240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:11:27.379] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:60:0) after 1m0s
[17:12:27.409] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:60:0)
[17:12:27.409] Timer tick, asking Buildbucket for the build status
[17:12:27.466] Build 8928145820924046240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:12:27.466] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:61:0) after 1m0s
[17:13:27.486] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:61:0)
[17:13:27.486] Timer tick, asking Buildbucket for the build status
[17:13:27.521] Build 8928145820924046240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:13:27.521] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:62:0) after 1m0s
[17:14:27.549] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:62:0)
[17:14:27.549] Timer tick, asking Buildbucket for the build status
[17:14:27.595] Build 8928145820924046240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:14:27.595] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:63:0) after 1m0s
[17:15:27.616] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:63:0)
[17:15:27.616] Timer tick, asking Buildbucket for the build status
[17:15:27.653] Build 8928145820924046240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:15:27.653] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:64:0) after 1m0s
[17:16:27.672] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:64:0)
[17:16:27.672] Timer tick, asking Buildbucket for the build status
[17:16:27.824] Build 8928145820924046240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:16:27.824] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:65:0) after 1m0s
[17:17:27.861] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:65:0)
[17:17:27.861] Timer tick, asking Buildbucket for the build status
[17:17:27.893] Build 8928145820924046240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:17:27.893] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:66:0) after 1m0s
[17:18:27.914] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:66:0)
[17:18:27.914] Timer tick, asking Buildbucket for the build status
[17:18:27.998] Build 8928145820924046240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:18:27.998] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:67:0) after 1m0s
[17:19:28.022] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:67:0)
[17:19:28.022] Timer tick, asking Buildbucket for the build status
[17:19:28.060] Build 8928145820924046240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:19:28.060] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:68:0) after 1m0s
[17:20:28.078] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:68:0)
[17:20:28.078] Timer tick, asking Buildbucket for the build status
[17:20:28.176] Build 8928145820924046240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:20:28.176] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:69:0) after 1m0s
[17:21:28.200] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:69:0)
[17:21:28.200] Timer tick, asking Buildbucket for the build status
[17:21:28.281] Build 8928145820924046240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:21:28.281] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:70:0) after 1m0s
[17:22:28.340] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:70:0)
[17:22:28.340] Timer tick, asking Buildbucket for the build status
[17:22:28.535] Build 8928145820924046240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:22:28.535] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:71:0) after 1m0s
[17:23:28.553] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:71:0)
[17:23:28.553] Timer tick, asking Buildbucket for the build status
[17:23:28.589] Build 8928145820924046240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:23:28.589] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:72:0) after 1m0s
[17:24:28.618] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:72:0)
[17:24:28.618] Timer tick, asking Buildbucket for the build status
[17:24:28.741] Build 8928145820924046240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:24:28.741] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:73:0) after 1m0s
[17:25:28.760] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:73:0)
[17:25:28.760] Timer tick, asking Buildbucket for the build status
[17:25:28.834] Build 8928145820924046240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:25:28.834] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:74:0) after 1m0s
[17:26:28.905] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:74:0)
[17:26:28.905] Timer tick, asking Buildbucket for the build status
[17:26:29.000] Build 8928145820924046240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:26:29.000] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:75:0) after 1m0s
[17:27:29.020] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:75:0)
[17:27:29.020] Timer tick, asking Buildbucket for the build status
[17:27:29.144] Build 8928145820924046240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:27:29.144] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:76:0) after 1m0s
[17:28:29.167] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:76:0)
[17:28:29.167] Timer tick, asking Buildbucket for the build status
[17:28:29.256] Build 8928145820924046240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:28:29.256] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:77:0) after 1m0s
[17:29:29.326] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:77:0)
[17:29:29.326] Timer tick, asking Buildbucket for the build status
[17:29:29.401] Build 8928145820924046240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:29:29.401] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:78:0) after 1m0s
[17:30:29.419] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:78:0)
[17:30:29.419] Timer tick, asking Buildbucket for the build status
[17:30:29.455] Build 8928145820924046240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:30:29.455] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:79:0) after 1m0s
[17:31:29.506] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:79:0)
[17:31:29.506] Timer tick, asking Buildbucket for the build status
[17:31:29.637] Build 8928145820924046240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:31:29.637] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:80:0) after 1m0s
[17:32:29.667] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:80:0)
[17:32:29.667] Timer tick, asking Buildbucket for the build status
[17:32:29.707] Build 8928145820924046240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:32:29.707] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:81:0) after 1m0s
[17:33:29.805] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:81:0)
[17:33:29.805] Timer tick, asking Buildbucket for the build status
[17:33:29.890] Build 8928145820924046240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:33:29.890] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:82:0) after 1m0s
[17:34:29.910] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:82:0)
[17:34:29.910] Timer tick, asking Buildbucket for the build status
[17:34:29.968] Build 8928145820924046240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:34:29.968] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:83:0) after 1m0s
[17:35:30.005] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:83:0)
[17:35:30.005] Timer tick, asking Buildbucket for the build status
[17:35:30.065] Build 8928145820924046240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:35:30.065] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:84:0) after 1m0s
[17:36:30.210] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:84:0)
[17:36:30.210] Timer tick, asking Buildbucket for the build status
[17:36:30.282] Build 8928145820924046240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:36:30.282] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:85:0) after 1m0s
[17:37:30.354] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:85:0)
[17:37:30.354] Timer tick, asking Buildbucket for the build status
[17:37:30.400] Build 8928145820924046240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:37:30.400] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:86:0) after 1m0s
[17:38:30.424] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:86:0)
[17:38:30.424] Timer tick, asking Buildbucket for the build status
[17:38:30.465] Build 8928145820924046240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:38:30.465] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:87:0) after 1m0s
[17:39:30.548] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:87:0)
[17:39:30.548] Timer tick, asking Buildbucket for the build status
[17:39:30.608] Build 8928145820924046240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:39:30.608] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:88:0) after 1m0s
[17:40:30.640] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:88:0)
[17:40:30.640] Timer tick, asking Buildbucket for the build status
[17:40:30.753] Build 8928145820924046240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:40:30.753] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:89:0) after 1m0s
[17:41:30.792] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:89:0)
[17:41:30.792] Timer tick, asking Buildbucket for the build status
[17:41:30.873] Build 8928145820924046240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:41:30.873] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:90:0) after 1m0s
[17:42:30.892] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:90:0)
[17:42:30.892] Timer tick, asking Buildbucket for the build status
[17:42:31.024] Build 8928145820924046240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:42:31.024] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:91:0) after 1m0s
[17:43:31.043] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:91:0)
[17:43:31.043] Timer tick, asking Buildbucket for the build status
[17:43:31.141] Build 8928145820924046240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:43:31.141] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:92:0) after 1m0s
[17:44:31.165] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:92:0)
[17:44:31.165] Timer tick, asking Buildbucket for the build status
[17:44:31.284] Build 8928145820924046240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:44:31.284] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:93:0) after 1m0s
[17:45:31.307] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:93:0)
[17:45:31.307] Timer tick, asking Buildbucket for the build status
[17:45:31.338] Build 8928145820924046240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:45:31.338] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:94:0) after 1m0s
[17:46:31.426] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:94:0)
[17:46:31.426] Timer tick, asking Buildbucket for the build status
[17:46:31.506] Build 8928145820924046240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:46:31.506] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:95:0) after 1m0s
[17:47:31.532] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:95:0)
[17:47:31.532] Timer tick, asking Buildbucket for the build status
[17:47:31.711] Build 8928145820924046240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:47:31.711] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:96:0) after 1m0s
[17:48:31.757] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:96:0)
[17:48:31.757] Timer tick, asking Buildbucket for the build status
[17:48:31.824] Build 8928145820924046240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:48:31.824] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:97:0) after 1m0s
[17:49:31.844] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:97:0)
[17:49:31.844] Timer tick, asking Buildbucket for the build status
[17:49:31.886] Build 8928145820924046240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:49:31.886] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:98:0) after 1m0s
[17:50:31.905] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:98:0)
[17:50:31.905] Timer tick, asking Buildbucket for the build status
[17:50:31.943] Build 8928145820924046240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:50:31.943] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:99:0) after 1m0s
[17:51:31.963] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:99:0)
[17:51:31.963] Timer tick, asking Buildbucket for the build status
[17:51:32.029] Build 8928145820924046240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:51:32.029] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:100:0) after 1m0s
[17:52:32.049] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:100:0)
[17:52:32.049] Timer tick, asking Buildbucket for the build status
[17:52:32.098] Build 8928145820924046240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:52:32.098] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:101:0) after 1m0s
[17:53:32.132] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:101:0)
[17:53:32.132] Timer tick, asking Buildbucket for the build status
[17:53:32.182] Build 8928145820924046240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:53:32.182] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:102:0) after 1m0s
[17:54:32.205] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:102:0)
[17:54:32.205] Timer tick, asking Buildbucket for the build status
[17:54:32.303] Build 8928145820924046240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:54:32.303] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:103:0) after 1m0s
[17:55:32.325] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:103:0)
[17:55:32.325] Timer tick, asking Buildbucket for the build status
[17:55:32.383] Build 8928145820924046240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:55:32.383] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:104:0) after 1m0s
[17:56:32.405] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:104:0)
[17:56:32.405] Timer tick, asking Buildbucket for the build status
[17:56:32.444] Build 8928145820924046240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:56:32.444] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:105:0) after 1m0s
[17:57:32.463] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:105:0)
[17:57:32.463] Timer tick, asking Buildbucket for the build status
[17:57:32.526] Build 8928145820924046240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:57:32.526] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:106:0) after 1m0s
[17:58:32.548] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:106:0)
[17:58:32.548] Timer tick, asking Buildbucket for the build status
[17:58:32.642] Build 8928145820924046240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:58:32.642] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:107:0) after 1m0s
[17:59:32.686] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:107:0)
[17:59:32.686] Timer tick, asking Buildbucket for the build status
[17:59:32.748] Build 8928145820924046240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:59:32.748] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:108:0) after 1m0s
[18:00:32.768] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:108:0)
[18:00:32.768] Timer tick, asking Buildbucket for the build status
[18:00:32.828] Build 8928145820924046240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:00:32.828] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:109:0) after 1m0s
[18:01:32.850] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:109:0)
[18:01:32.850] Timer tick, asking Buildbucket for the build status
[18:01:32.934] Build 8928145820924046240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:01:32.934] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:110:0) after 1m0s
[18:02:33.020] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:110:0)
[18:02:33.020] Timer tick, asking Buildbucket for the build status
[18:02:33.220] Build 8928145820924046240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:02:33.220] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:111:0) after 1m0s
[18:03:33.240] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:111:0)
[18:03:33.240] Timer tick, asking Buildbucket for the build status
[18:03:33.322] Build 8928145820924046240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:03:33.322] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:112:0) after 1m0s
[18:04:33.505] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:112:0)
[18:04:33.505] Timer tick, asking Buildbucket for the build status
[18:04:33.565] Build 8928145820924046240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:04:33.565] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:113:0) after 1m0s
[18:05:33.678] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:113:0)
[18:05:33.678] Timer tick, asking Buildbucket for the build status
[18:05:33.769] Build 8928145820924046240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:05:33.769] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:114:0) after 1m0s
[18:06:33.792] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:114:0)
[18:06:33.792] Timer tick, asking Buildbucket for the build status
[18:06:33.829] Build 8928145820924046240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:06:33.829] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:115:0) after 1m0s
[18:07:33.945] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:115:0)
[18:07:33.945] Timer tick, asking Buildbucket for the build status
[18:07:33.986] Build 8928145820924046240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:07:33.986] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:116:0) after 1m0s
[18:08:34.085] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:116:0)
[18:08:34.085] Timer tick, asking Buildbucket for the build status
[18:08:34.238] Build 8928145820924046240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:08:34.238] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:117:0) after 1m0s
[18:09:34.319] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:117:0)
[18:09:34.319] Timer tick, asking Buildbucket for the build status
[18:09:34.383] Build 8928145820924046240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:09:34.383] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:118:0) after 1m0s
[18:10:34.515] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:118:0)
[18:10:34.515] Timer tick, asking Buildbucket for the build status
[18:10:34.583] Build 8928145820924046240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:10:34.583] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:119:0) after 1m0s
[18:11:34.710] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:119:0)
[18:11:34.710] Timer tick, asking Buildbucket for the build status
[18:11:34.787] Build 8928145820924046240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:11:34.787] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:120:0) after 1m0s
[18:12:35.122] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:120:0)
[18:12:35.122] Timer tick, asking Buildbucket for the build status
[18:12:35.228] Build 8928145820924046240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:12:35.228] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:121:0) after 1m0s
[18:13:35.252] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:121:0)
[18:13:35.252] Timer tick, asking Buildbucket for the build status
[18:13:35.347] Build 8928145820924046240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:13:35.347] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:122:0) after 1m0s
[18:14:35.367] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:122:0)
[18:14:35.367] Timer tick, asking Buildbucket for the build status
[18:14:35.427] Build 8928145820924046240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:14:35.427] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:123:0) after 1m0s
[18:15:35.450] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:123:0)
[18:15:35.450] Timer tick, asking Buildbucket for the build status
[18:15:35.508] Build 8928145820924046240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:15:35.508] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:124:0) after 1m0s
[18:16:35.530] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:124:0)
[18:16:35.531] Timer tick, asking Buildbucket for the build status
[18:16:35.622] Build 8928145820924046240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:16:35.622] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:125:0) after 1m0s
[18:17:35.645] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:125:0)
[18:17:35.645] Timer tick, asking Buildbucket for the build status
[18:17:35.883] Build 8928145820924046240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:17:35.883] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093575883082699872:126:0) after 1m0s
[18:18:02.447] Received PubSub notification, asking Buildbucket for the build status
[18:18:02.486] Build 8928145820924046240: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[18:18:02.486] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"completed_ts": "1543861080254360",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1543853659662044",
"failure_reason": "BUILD_FAILURE",
"id": "8928145820924046240",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"06b8660d287e193fa9bf5e0ae16a13db5b2897a2\"}}",
"project": "chromium",
"result": "FAILURE",
"result_details_json": "{\"ui\": {\"info\": \"Failure compare_build_artifacts\"}, \"swarming\": {\"bot_dimensions\": {\"gce\": [\"1\"], \"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\"], \"id\": [\"swarm2173-c4\"], \"python\": [\"2.7.6\"], \"server_version\": [\"3901-0f56bf6\"], \"image\": [\"chrome-win10-1703-2018-04-20-61acaf8983f\"], \"gpu\": [\"none\"], \"cores\": [\"8\"], \"integrity\": [\"high\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Haswell_GCE\"], \"pool\": [\"luci.chromium.ci\"], \"caches\": [\"builder_e8fffaf9fac16164b1ec16ebaa1f377b792d39a582f7640830518d8ff7303d0c_v2\", \"git\", \"goma_v2\", \"vpython\"]}}, \"properties\": {\"got_nacl_revision\": \"f701a90597fc85979319447c0cd44c3b52201c78\", \"got_swarming_client_revision\": \"0e3e1c4dc4e79f25a5b58fcbc135dc93183c0c54\", \"got_revision\": \"06b8660d287e193fa9bf5e0ae16a13db5b2897a2\", \"recipe\": \"swarming/deterministic_build\", \"got_dawn_revision\": \"9ca9435087e06b6825c9d78413797e8b2094c411\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#25873}\", \"$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@{#613107}\", \"branch\": \"refs/heads/master\", \"revision\": \"06b8660d287e193fa9bf5e0ae16a13db5b2897a2\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"buildername\": \"Windows deterministic\", \"got_webrtc_revision\": \"9cf6ce3e1bb31c96e48016316e4968fd30562d1a\", \"mastername\": \"chromium.win\", \"got_angle_revision\": \"317a9ebdb019b02fda925072a5572265826676e9\", \"buildbucket\": {\"hostname\": \"cr-buildbucket.appspot.com\", \"build\": {\"created_ts\": 1543853659662044, \"tags\": [\"builder:Windows deterministic\", \"buildset:commit/git/06b8660d287e193fa9bf5e0ae16a13db5b2897a2\", \"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/06b8660d287e193fa9bf5e0ae16a13db5b2897a2\", \"gitiles_ref:refs/heads/master\", \"scheduler_invocation_id:9093575883082699872\", \"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\": \"8928145820924046240\"}}, \"got_v8_revision\": \"e89dd701195dd5b165e3af8d61079c58a0405a59\", \"got_v8_revision_cp\": \"refs/heads/7.3.3@{#1}\", \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"buildnumber\": 10946, \"path_config\": \"generic\", \"bot_id\": \"swarm2173-c4\", \"got_buildtools_revision\": \"04161ec8d7c781e4498c699254c69ba0dd959fde\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1543853796987126",
"status": "COMPLETED",
"status_changed_ts": "1543861081616708",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/10946",
"builder:Windows deterministic",
"buildset:commit/git/06b8660d287e193fa9bf5e0ae16a13db5b2897a2",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/06b8660d287e193fa9bf5e0ae16a13db5b2897a2",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9093575883082699872",
"scheduler_job_id:chromium/Windows deterministic",
"swarming_dimension:builder:Windows deterministic",
"swarming_dimension:caches:builder_e8fffaf9fac16164b1ec16ebaa1f377b792d39a582f7640830518d8ff7303d0c_v2",
"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/10946",
"swarming_tag:buildbucket_bucket:chromium/ci",
"swarming_tag:buildbucket_build_id:8928145820924046240",
"swarming_tag:buildbucket_hostname:cr-buildbucket.appspot.com",
"swarming_tag:buildbucket_template_canary:0",
"swarming_tag:buildbucket_template_revision:2f19301aebbc779351dc95ab1dc03603fa761240",
"swarming_tag:builder:Windows deterministic",
"swarming_tag:buildset:commit/git/06b8660d287e193fa9bf5e0ae16a13db5b2897a2",
"swarming_tag:buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/06b8660d287e193fa9bf5e0ae16a13db5b2897a2",
"swarming_tag:caches:builder_e8fffaf9fac16164b1ec16ebaa1f377b792d39a582f7640830518d8ff7303d0c_v2",
"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/8928145820924046240/+/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:9093575883082699872",
"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:730145e9e1d37101d0f6001b294c592ab5c2023e",
"swarming_tag:user:None",
"swarming_tag:user_agent:luci-scheduler",
"swarming_tag:vpython:native-python-wrapper",
"swarming_task_id:418daadfce3d1110",
"user_agent:luci-scheduler"
],
"updated_ts": "1543861081641353",
"url": "https://ci.chromium.org/b/8928145820924046240",
"utcnow_ts": "1543861082477351"
}
}
[18:18:02.486] Invocation finished in 2h3m44.266232209s with status FAILED