[15:18:36.261] New invocation is queued and will start shortly
[15:18:37.502] Starting the invocation (attempt 1)
[15:18:37.541] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[15:18:37.541] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[15:18:37.541] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9104269829448987360",
"parameters_json": "{\"builder_name\":\"Windows deterministic\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src.git\",\"revision\":\"167e27f010746fde0f052d6868396f23b205502d\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Windows deterministic",
"scheduler_invocation_id:9104269829448987360",
"scheduler_job_id:chromium/Windows deterministic",
"user_agent:luci-scheduler",
"buildset:commit/git/167e27f010746fde0f052d6868396f23b205502d",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/167e27f010746fde0f052d6868396f23b205502d",
"gitiles_ref:refs/heads/master"
]
}
[15:18:39.130] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1533655117648100",
"id": "8938839767315007152",
"lease_expiration_ts": "1533799118737020",
"lease_key": "2086550291",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"167e27f010746fde0f052d6868396f23b205502d\"}}",
"project": "chromium",
"result_details_json": "null",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1533655119011990",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/9679",
"builder:Windows deterministic",
"buildset:commit/git/167e27f010746fde0f052d6868396f23b205502d",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/167e27f010746fde0f052d6868396f23b205502d",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9104269829448987360",
"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/9679",
"swarming_tag:buildbucket_bucket:luci.chromium.ci",
"swarming_tag:buildbucket_build_id:8938839767315007152",
"swarming_tag:buildbucket_hostname:cr-buildbucket.appspot.com",
"swarming_tag:buildbucket_template_canary:0",
"swarming_tag:buildbucket_template_revision:1843d7ecb7176e182855328359db08c38323a012",
"swarming_tag:builder:Windows deterministic",
"swarming_tag:buildset:commit/git/167e27f010746fde0f052d6868396f23b205502d",
"swarming_tag:buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/167e27f010746fde0f052d6868396f23b205502d",
"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/8938839767315007152/+/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:9104269829448987360",
"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:5989837a4b2b2033290aa8856271cc05cee4c0eb",
"swarming_tag:user:None",
"swarming_tag:user_agent:luci-scheduler",
"swarming_tag:vpython:native-python-wrapper",
"swarming_task_id:3f2dc97ae32b4710",
"user_agent:luci-scheduler"
],
"updated_ts": "1533655119012110",
"url": "https://ci.chromium.org/p/chromium/builders/luci.chromium.ci/Windows deterministic/9679",
"utcnow_ts": "1533655119113640"
}
}
[15:18:39.130] Task URL: https://ci.chromium.org/p/chromium/builders/luci.chromium.ci/Windows deterministic/9679
[15:18:39.130] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:2:0) after 1m0s
[15:19:39.168] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:2:0)
[15:19:39.168] Timer tick, asking Buildbucket for the build status
[15:19:39.337] Build 8938839767315007152: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:19:39.337] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:3:0) after 1m0s
[15:20:39.363] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:3:0)
[15:20:39.363] Timer tick, asking Buildbucket for the build status
[15:20:39.395] Build 8938839767315007152: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:20:39.395] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:4:0) after 1m0s
[15:20:48.568] Received PubSub notification, asking Buildbucket for the build status
[15:20:48.648] Build 8938839767315007152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:21:39.415] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:4:0)
[15:21:39.415] Timer tick, asking Buildbucket for the build status
[15:21:39.449] Build 8938839767315007152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:21:39.449] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:6:0) after 1m0s
[15:22:39.500] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:6:0)
[15:22:39.500] Timer tick, asking Buildbucket for the build status
[15:22:39.575] Build 8938839767315007152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:22:39.575] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:7:0) after 1m0s
[15:23:39.598] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:7:0)
[15:23:39.598] Timer tick, asking Buildbucket for the build status
[15:23:39.636] Build 8938839767315007152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:23:39.636] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:8:0) after 1m0s
[15:24:39.657] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:8:0)
[15:24:39.657] Timer tick, asking Buildbucket for the build status
[15:24:39.783] Build 8938839767315007152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:24:39.783] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:9:0) after 1m0s
[15:25:39.813] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:9:0)
[15:25:39.813] Timer tick, asking Buildbucket for the build status
[15:25:39.896] Build 8938839767315007152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:25:39.896] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:10:0) after 1m0s
[15:26:39.915] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:10:0)
[15:26:39.915] Timer tick, asking Buildbucket for the build status
[15:26:39.949] Build 8938839767315007152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:26:39.950] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:11:0) after 1m0s
[15:27:39.978] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:11:0)
[15:27:39.978] Timer tick, asking Buildbucket for the build status
[15:27:40.033] Build 8938839767315007152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:27:40.033] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:12:0) after 1m0s
[15:28:40.052] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:12:0)
[15:28:40.052] Timer tick, asking Buildbucket for the build status
[15:28:40.244] Build 8938839767315007152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:28:40.244] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:13:0) after 1m0s
[15:29:40.277] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:13:0)
[15:29:40.277] Timer tick, asking Buildbucket for the build status
[15:29:40.318] Build 8938839767315007152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:29:40.318] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:14:0) after 1m0s
[15:30:40.358] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:14:0)
[15:30:40.358] Timer tick, asking Buildbucket for the build status
[15:30:40.440] Build 8938839767315007152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:30:40.440] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:15:0) after 1m0s
[15:31:40.467] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:15:0)
[15:31:40.467] Timer tick, asking Buildbucket for the build status
[15:31:40.516] Build 8938839767315007152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:31:40.516] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:16:0) after 1m0s
[15:32:40.547] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:16:0)
[15:32:40.547] Timer tick, asking Buildbucket for the build status
[15:32:40.642] Build 8938839767315007152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:32:40.642] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:17:0) after 1m0s
[15:33:40.661] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:17:0)
[15:33:40.661] Timer tick, asking Buildbucket for the build status
[15:33:40.807] Build 8938839767315007152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:33:40.807] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:18:0) after 1m0s
[15:34:40.832] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:18:0)
[15:34:40.832] Timer tick, asking Buildbucket for the build status
[15:34:40.921] Build 8938839767315007152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:34:40.921] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:19:0) after 1m0s
[15:35:40.942] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:19:0)
[15:35:40.942] Timer tick, asking Buildbucket for the build status
[15:35:40.986] Build 8938839767315007152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:35:40.986] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:20:0) after 1m0s
[15:36:41.008] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:20:0)
[15:36:41.008] Timer tick, asking Buildbucket for the build status
[15:36:41.150] Build 8938839767315007152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:36:41.150] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:21:0) after 1m0s
[15:37:41.171] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:21:0)
[15:37:41.171] Timer tick, asking Buildbucket for the build status
[15:37:41.206] Build 8938839767315007152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:37:41.206] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:22:0) after 1m0s
[15:38:41.284] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:22:0)
[15:38:41.284] Timer tick, asking Buildbucket for the build status
[15:38:41.342] Build 8938839767315007152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:38:41.342] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:23:0) after 1m0s
[15:39:41.366] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:23:0)
[15:39:41.366] Timer tick, asking Buildbucket for the build status
[15:39:41.650] Build 8938839767315007152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:39:41.650] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:24:0) after 1m0s
[15:40:41.669] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:24:0)
[15:40:41.669] Timer tick, asking Buildbucket for the build status
[15:40:41.715] Build 8938839767315007152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:40:41.715] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:25:0) after 1m0s
[15:41:41.732] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:25:0)
[15:41:41.732] Timer tick, asking Buildbucket for the build status
[15:41:41.804] Build 8938839767315007152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:41:41.804] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:26:0) after 1m0s
[15:42:41.827] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:26:0)
[15:42:41.827] Timer tick, asking Buildbucket for the build status
[15:42:41.885] Build 8938839767315007152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:42:41.885] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:27:0) after 1m0s
[15:43:41.908] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:27:0)
[15:43:41.908] Timer tick, asking Buildbucket for the build status
[15:43:41.958] Build 8938839767315007152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:43:41.958] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:28:0) after 1m0s
[15:44:41.980] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:28:0)
[15:44:41.980] Timer tick, asking Buildbucket for the build status
[15:44:42.054] Build 8938839767315007152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:44:42.054] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:29:0) after 1m0s
[15:45:42.078] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:29:0)
[15:45:42.078] Timer tick, asking Buildbucket for the build status
[15:45:42.192] Build 8938839767315007152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:45:42.192] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:30:0) after 1m0s
[15:46:42.218] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:30:0)
[15:46:42.218] Timer tick, asking Buildbucket for the build status
[15:46:42.345] Build 8938839767315007152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:46:42.345] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:31:0) after 1m0s
[15:47:42.377] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:31:0)
[15:47:42.377] Timer tick, asking Buildbucket for the build status
[15:47:42.439] Build 8938839767315007152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:47:42.439] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:32:0) after 1m0s
[15:48:42.461] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:32:0)
[15:48:42.461] Timer tick, asking Buildbucket for the build status
[15:48:42.580] Build 8938839767315007152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:48:42.580] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:33:0) after 1m0s
[15:49:42.588] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:33:0)
[15:49:42.588] Timer tick, asking Buildbucket for the build status
[15:49:42.806] Build 8938839767315007152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:49:42.806] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:34:0) after 1m0s
[15:50:42.827] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:34:0)
[15:50:42.827] Timer tick, asking Buildbucket for the build status
[15:50:42.966] Build 8938839767315007152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:50:42.966] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:35:0) after 1m0s
[15:51:42.983] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:35:0)
[15:51:42.983] Timer tick, asking Buildbucket for the build status
[15:51:43.031] Build 8938839767315007152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:51:43.031] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:36:0) after 1m0s
[15:52:43.126] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:36:0)
[15:52:43.126] Timer tick, asking Buildbucket for the build status
[15:52:43.185] Build 8938839767315007152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:52:43.185] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:37:0) after 1m0s
[15:53:43.204] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:37:0)
[15:53:43.204] Timer tick, asking Buildbucket for the build status
[15:53:43.262] Build 8938839767315007152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:53:43.262] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:38:0) after 1m0s
[15:54:43.281] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:38:0)
[15:54:43.281] Timer tick, asking Buildbucket for the build status
[15:54:43.351] Build 8938839767315007152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:54:43.351] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:39:0) after 1m0s
[15:55:43.390] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:39:0)
[15:55:43.390] Timer tick, asking Buildbucket for the build status
[15:55:43.425] Build 8938839767315007152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:55:43.425] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:40:0) after 1m0s
[15:56:43.624] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:40:0)
[15:56:43.624] Timer tick, asking Buildbucket for the build status
[15:56:43.681] Build 8938839767315007152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:56:43.681] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:41:0) after 1m0s
[15:57:43.899] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:41:0)
[15:57:43.899] Timer tick, asking Buildbucket for the build status
[15:57:43.987] Build 8938839767315007152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:57:43.987] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:42:0) after 1m0s
[15:58:44.040] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:42:0)
[15:58:44.040] Timer tick, asking Buildbucket for the build status
[15:58:44.286] Build 8938839767315007152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:58:44.286] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:43:0) after 1m0s
[15:59:44.308] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:43:0)
[15:59:44.308] Timer tick, asking Buildbucket for the build status
[15:59:44.774] Build 8938839767315007152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:59:44.774] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:44:0) after 1m0s
[16:00:44.796] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:44:0)
[16:00:44.796] Timer tick, asking Buildbucket for the build status
[16:00:44.843] Build 8938839767315007152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:00:44.843] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:45:0) after 1m0s
[16:01:44.865] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:45:0)
[16:01:44.865] Timer tick, asking Buildbucket for the build status
[16:01:44.908] Build 8938839767315007152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:01:44.908] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:46:0) after 1m0s
[16:02:44.944] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:46:0)
[16:02:44.944] Timer tick, asking Buildbucket for the build status
[16:02:45.002] Build 8938839767315007152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:02:45.002] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:47:0) after 1m0s
[16:03:45.024] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:47:0)
[16:03:45.024] Timer tick, asking Buildbucket for the build status
[16:03:45.062] Build 8938839767315007152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:03:45.062] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:48:0) after 1m0s
[16:04:45.163] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:48:0)
[16:04:45.163] Timer tick, asking Buildbucket for the build status
[16:04:45.204] Build 8938839767315007152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:04:45.204] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:49:0) after 1m0s
[16:05:45.222] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:49:0)
[16:05:45.222] Timer tick, asking Buildbucket for the build status
[16:05:45.262] Build 8938839767315007152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:05:45.262] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:50:0) after 1m0s
[16:06:45.287] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:50:0)
[16:06:45.287] Timer tick, asking Buildbucket for the build status
[16:06:45.325] Build 8938839767315007152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:06:45.325] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:51:0) after 1m0s
[16:07:45.350] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:51:0)
[16:07:45.350] Timer tick, asking Buildbucket for the build status
[16:07:45.391] Build 8938839767315007152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:07:45.391] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:52:0) after 1m0s
[16:08:45.454] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:52:0)
[16:08:45.454] Timer tick, asking Buildbucket for the build status
[16:08:45.488] Build 8938839767315007152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:08:45.488] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:53:0) after 1m0s
[16:09:45.509] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:53:0)
[16:09:45.509] Timer tick, asking Buildbucket for the build status
[16:09:45.653] Build 8938839767315007152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:09:45.653] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:54:0) after 1m0s
[16:10:45.682] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:54:0)
[16:10:45.682] Timer tick, asking Buildbucket for the build status
[16:10:45.744] Build 8938839767315007152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:10:45.744] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:55:0) after 1m0s
[16:11:45.767] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:55:0)
[16:11:45.767] Timer tick, asking Buildbucket for the build status
[16:11:45.833] Build 8938839767315007152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:11:45.833] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:56:0) after 1m0s
[16:12:45.858] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:56:0)
[16:12:45.858] Timer tick, asking Buildbucket for the build status
[16:12:45.945] Build 8938839767315007152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:12:45.945] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:57:0) after 1m0s
[16:13:45.964] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:57:0)
[16:13:45.964] Timer tick, asking Buildbucket for the build status
[16:13:46.010] Build 8938839767315007152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:13:46.010] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:58:0) after 1m0s
[16:14:46.031] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:58:0)
[16:14:46.031] Timer tick, asking Buildbucket for the build status
[16:14:46.072] Build 8938839767315007152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:14:46.072] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:59:0) after 1m0s
[16:15:46.092] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:59:0)
[16:15:46.092] Timer tick, asking Buildbucket for the build status
[16:15:46.179] Build 8938839767315007152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:15:46.179] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:60:0) after 1m0s
[16:16:46.203] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:60:0)
[16:16:46.203] Timer tick, asking Buildbucket for the build status
[16:16:46.254] Build 8938839767315007152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:16:46.254] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:61:0) after 1m0s
[16:17:46.276] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:61:0)
[16:17:46.276] Timer tick, asking Buildbucket for the build status
[16:17:46.320] Build 8938839767315007152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:17:46.320] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:62:0) after 1m0s
[16:18:46.448] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:62:0)
[16:18:46.448] Timer tick, asking Buildbucket for the build status
[16:18:46.491] Build 8938839767315007152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:18:46.491] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:63:0) after 1m0s
[16:19:46.541] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:63:0)
[16:19:46.541] Timer tick, asking Buildbucket for the build status
[16:19:46.623] Build 8938839767315007152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:19:46.623] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:64:0) after 1m0s
[16:20:46.642] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:64:0)
[16:20:46.642] Timer tick, asking Buildbucket for the build status
[16:20:46.797] Build 8938839767315007152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:20:46.798] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:65:0) after 1m0s
[16:21:46.816] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:65:0)
[16:21:46.816] Timer tick, asking Buildbucket for the build status
[16:21:46.866] Build 8938839767315007152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:21:46.866] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:66:0) after 1m0s
[16:22:46.886] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:66:0)
[16:22:46.886] Timer tick, asking Buildbucket for the build status
[16:22:46.936] Build 8938839767315007152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:22:46.936] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:67:0) after 1m0s
[16:23:46.964] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:67:0)
[16:23:46.964] Timer tick, asking Buildbucket for the build status
[16:23:47.009] Build 8938839767315007152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:23:47.009] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:68:0) after 1m0s
[16:24:47.031] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:68:0)
[16:24:47.031] Timer tick, asking Buildbucket for the build status
[16:24:47.213] Build 8938839767315007152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:24:47.213] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:69:0) after 1m0s
[16:25:47.256] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:69:0)
[16:25:47.256] Timer tick, asking Buildbucket for the build status
[16:25:47.504] Build 8938839767315007152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:25:47.504] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:70:0) after 1m0s
[16:26:47.531] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:70:0)
[16:26:47.531] Timer tick, asking Buildbucket for the build status
[16:26:47.579] Build 8938839767315007152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:26:47.579] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:71:0) after 1m0s
[16:27:47.601] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:71:0)
[16:27:47.601] Timer tick, asking Buildbucket for the build status
[16:27:47.661] Build 8938839767315007152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:27:47.661] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:72:0) after 1m0s
[16:28:47.684] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:72:0)
[16:28:47.684] Timer tick, asking Buildbucket for the build status
[16:28:47.720] Build 8938839767315007152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:28:47.720] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:73:0) after 1m0s
[16:29:47.744] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:73:0)
[16:29:47.744] Timer tick, asking Buildbucket for the build status
[16:29:47.797] Build 8938839767315007152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:29:47.797] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:74:0) after 1m0s
[16:30:47.845] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:74:0)
[16:30:47.845] Timer tick, asking Buildbucket for the build status
[16:30:47.926] Build 8938839767315007152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:30:47.926] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:75:0) after 1m0s
[16:31:47.999] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:75:0)
[16:31:47.999] Timer tick, asking Buildbucket for the build status
[16:31:48.038] Build 8938839767315007152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:31:48.038] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:76:0) after 1m0s
[16:32:48.109] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:76:0)
[16:32:48.109] Timer tick, asking Buildbucket for the build status
[16:32:48.158] Build 8938839767315007152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:32:48.158] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:77:0) after 1m0s
[16:33:48.183] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:77:0)
[16:33:48.183] Timer tick, asking Buildbucket for the build status
[16:33:48.281] Build 8938839767315007152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:33:48.281] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:78:0) after 1m0s
[16:34:48.305] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:78:0)
[16:34:48.305] Timer tick, asking Buildbucket for the build status
[16:34:48.471] Build 8938839767315007152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:34:48.471] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:79:0) after 1m0s
[16:35:48.510] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:79:0)
[16:35:48.510] Timer tick, asking Buildbucket for the build status
[16:35:48.551] Build 8938839767315007152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:35:48.551] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:80:0) after 1m0s
[16:36:48.582] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:80:0)
[16:36:48.582] Timer tick, asking Buildbucket for the build status
[16:36:48.733] Build 8938839767315007152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:36:48.733] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:81:0) after 1m0s
[16:37:50.207] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:81:0)
[16:37:50.207] Timer tick, asking Buildbucket for the build status
[16:37:50.338] Build 8938839767315007152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:37:50.338] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:82:0) after 1m0s
[16:38:50.360] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:82:0)
[16:38:50.360] Timer tick, asking Buildbucket for the build status
[16:38:50.408] Build 8938839767315007152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:38:50.408] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:83:0) after 1m0s
[16:39:50.520] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:83:0)
[16:39:50.520] Timer tick, asking Buildbucket for the build status
[16:39:50.739] Build 8938839767315007152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:39:50.739] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:84:0) after 1m0s
[16:40:50.782] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:84:0)
[16:40:50.782] Timer tick, asking Buildbucket for the build status
[16:40:50.829] Build 8938839767315007152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:40:50.829] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:85:0) after 1m0s
[16:41:50.854] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:85:0)
[16:41:50.854] Timer tick, asking Buildbucket for the build status
[16:41:50.916] Build 8938839767315007152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:41:50.916] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:86:0) after 1m0s
[16:42:50.961] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:86:0)
[16:42:50.961] Timer tick, asking Buildbucket for the build status
[16:42:51.012] Build 8938839767315007152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:42:51.012] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:87:0) after 1m0s
[16:43:51.032] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:87:0)
[16:43:51.032] Timer tick, asking Buildbucket for the build status
[16:43:51.237] Build 8938839767315007152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:43:51.237] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:88:0) after 1m0s
[16:44:51.263] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:88:0)
[16:44:51.263] Timer tick, asking Buildbucket for the build status
[16:44:51.421] Build 8938839767315007152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:44:51.421] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:89:0) after 1m0s
[16:45:51.444] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:89:0)
[16:45:51.444] Timer tick, asking Buildbucket for the build status
[16:45:51.558] Build 8938839767315007152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:45:51.558] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:90:0) after 1m0s
[16:46:51.597] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:90:0)
[16:46:51.597] Timer tick, asking Buildbucket for the build status
[16:46:51.641] Build 8938839767315007152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:46:51.641] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:91:0) after 1m0s
[16:47:51.666] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:91:0)
[16:47:51.666] Timer tick, asking Buildbucket for the build status
[16:47:51.728] Build 8938839767315007152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:47:51.728] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:92:0) after 1m0s
[16:48:51.755] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:92:0)
[16:48:51.755] Timer tick, asking Buildbucket for the build status
[16:48:51.788] Build 8938839767315007152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:48:51.788] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:93:0) after 1m0s
[16:49:51.813] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:93:0)
[16:49:51.813] Timer tick, asking Buildbucket for the build status
[16:49:51.903] Build 8938839767315007152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:49:51.903] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:94:0) after 1m0s
[16:50:51.954] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:94:0)
[16:50:51.954] Timer tick, asking Buildbucket for the build status
[16:50:52.013] Build 8938839767315007152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:50:52.013] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:95:0) after 1m0s
[16:51:52.116] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:95:0)
[16:51:52.116] Timer tick, asking Buildbucket for the build status
[16:51:52.157] Build 8938839767315007152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:51:52.157] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:96:0) after 1m0s
[16:52:52.228] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:96:0)
[16:52:52.228] Timer tick, asking Buildbucket for the build status
[16:52:52.333] Build 8938839767315007152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:52:52.333] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:97:0) after 1m0s
[16:53:52.356] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:97:0)
[16:53:52.356] Timer tick, asking Buildbucket for the build status
[16:53:52.575] Build 8938839767315007152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:53:52.575] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:98:0) after 1m0s
[16:54:52.620] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:98:0)
[16:54:52.620] Timer tick, asking Buildbucket for the build status
[16:54:52.724] Build 8938839767315007152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:54:52.724] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:99:0) after 1m0s
[16:55:52.750] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:99:0)
[16:55:52.750] Timer tick, asking Buildbucket for the build status
[16:55:53.066] Build 8938839767315007152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:55:53.066] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:100:0) after 1m0s
[16:56:53.104] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:100:0)
[16:56:53.104] Timer tick, asking Buildbucket for the build status
[16:56:53.142] Build 8938839767315007152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:56:53.142] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:101:0) after 1m0s
[16:57:53.169] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:101:0)
[16:57:53.169] Timer tick, asking Buildbucket for the build status
[16:57:53.303] Build 8938839767315007152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:57:53.303] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:102:0) after 1m0s
[16:58:53.326] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:102:0)
[16:58:53.326] Timer tick, asking Buildbucket for the build status
[16:58:53.453] Build 8938839767315007152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:58:53.453] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:103:0) after 1m0s
[16:59:53.480] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:103:0)
[16:59:53.480] Timer tick, asking Buildbucket for the build status
[16:59:53.521] Build 8938839767315007152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:59:53.521] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:104:0) after 1m0s
[17:00:53.546] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:104:0)
[17:00:53.546] Timer tick, asking Buildbucket for the build status
[17:00:53.586] Build 8938839767315007152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:00:53.586] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:105:0) after 1m0s
[17:01:53.618] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:105:0)
[17:01:53.618] Timer tick, asking Buildbucket for the build status
[17:01:53.774] Build 8938839767315007152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:01:53.774] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:106:0) after 1m0s
[17:02:53.803] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:106:0)
[17:02:53.803] Timer tick, asking Buildbucket for the build status
[17:02:53.863] Build 8938839767315007152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:02:53.863] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:107:0) after 1m0s
[17:03:53.889] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:107:0)
[17:03:53.889] Timer tick, asking Buildbucket for the build status
[17:03:53.985] Build 8938839767315007152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:03:53.985] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:108:0) after 1m0s
[17:04:54.015] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:108:0)
[17:04:54.015] Timer tick, asking Buildbucket for the build status
[17:04:54.084] Build 8938839767315007152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:04:54.084] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:109:0) after 1m0s
[17:05:54.111] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:109:0)
[17:05:54.111] Timer tick, asking Buildbucket for the build status
[17:05:54.153] Build 8938839767315007152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:05:54.153] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:110:0) after 1m0s
[17:06:54.230] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:110:0)
[17:06:54.230] Timer tick, asking Buildbucket for the build status
[17:06:54.520] Build 8938839767315007152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:06:54.520] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:111:0) after 1m0s
[17:07:54.547] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:111:0)
[17:07:54.547] Timer tick, asking Buildbucket for the build status
[17:07:54.646] Build 8938839767315007152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:07:54.646] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:112:0) after 1m0s
[17:08:54.675] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:112:0)
[17:08:54.675] Timer tick, asking Buildbucket for the build status
[17:08:54.717] Build 8938839767315007152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:08:54.717] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:113:0) after 1m0s
[17:09:54.824] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:113:0)
[17:09:54.824] Timer tick, asking Buildbucket for the build status
[17:09:54.994] Build 8938839767315007152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:09:54.994] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:114:0) after 1m0s
[17:10:55.029] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:114:0)
[17:10:55.029] Timer tick, asking Buildbucket for the build status
[17:10:55.083] Build 8938839767315007152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:10:55.083] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:115:0) after 1m0s
[17:11:55.107] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:115:0)
[17:11:55.107] Timer tick, asking Buildbucket for the build status
[17:11:55.142] Build 8938839767315007152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:11:55.142] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:116:0) after 1m0s
[17:12:55.171] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:116:0)
[17:12:55.171] Timer tick, asking Buildbucket for the build status
[17:12:55.244] Build 8938839767315007152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:12:55.244] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:117:0) after 1m0s
[17:13:55.275] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:117:0)
[17:13:55.275] Timer tick, asking Buildbucket for the build status
[17:13:55.333] Build 8938839767315007152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:13:55.333] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:118:0) after 1m0s
[17:14:55.361] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:118:0)
[17:14:55.361] Timer tick, asking Buildbucket for the build status
[17:14:55.423] Build 8938839767315007152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:14:55.423] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:119:0) after 1m0s
[17:15:55.572] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:119:0)
[17:15:55.572] Timer tick, asking Buildbucket for the build status
[17:15:55.611] Build 8938839767315007152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:15:55.611] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:120:0) after 1m0s
[17:16:55.638] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:120:0)
[17:16:55.638] Timer tick, asking Buildbucket for the build status
[17:16:55.757] Build 8938839767315007152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:16:55.757] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:121:0) after 1m0s
[17:17:55.817] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:121:0)
[17:17:55.817] Timer tick, asking Buildbucket for the build status
[17:17:55.873] Build 8938839767315007152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:17:55.873] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:122:0) after 1m0s
[17:18:55.912] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:122:0)
[17:18:55.912] Timer tick, asking Buildbucket for the build status
[17:18:56.056] Build 8938839767315007152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:18:56.056] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:123:0) after 1m0s
[17:19:56.085] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:123:0)
[17:19:56.085] Timer tick, asking Buildbucket for the build status
[17:19:56.133] Build 8938839767315007152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:19:56.133] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:124:0) after 1m0s
[17:20:56.159] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:124:0)
[17:20:56.159] Timer tick, asking Buildbucket for the build status
[17:20:56.199] Build 8938839767315007152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:20:56.199] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:125:0) after 1m0s
[17:21:56.228] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:125:0)
[17:21:56.228] Timer tick, asking Buildbucket for the build status
[17:21:56.615] Build 8938839767315007152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:21:56.615] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:126:0) after 1m0s
[17:22:56.692] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:126:0)
[17:22:56.692] Timer tick, asking Buildbucket for the build status
[17:22:56.832] Build 8938839767315007152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:22:56.832] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:127:0) after 1m0s
[17:23:56.867] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:127:0)
[17:23:56.867] Timer tick, asking Buildbucket for the build status
[17:23:56.910] Build 8938839767315007152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:23:56.910] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:128:0) after 1m0s
[17:24:56.938] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:128:0)
[17:24:56.938] Timer tick, asking Buildbucket for the build status
[17:24:57.016] Build 8938839767315007152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:24:57.016] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:129:0) after 1m0s
[17:25:57.069] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:129:0)
[17:25:57.069] Timer tick, asking Buildbucket for the build status
[17:25:57.178] Build 8938839767315007152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:25:57.178] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104269829448987360:130:0) after 1m0s
[17:26:36.312] Received PubSub notification, asking Buildbucket for the build status
[17:26:36.423] Build 8938839767315007152: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[17:26:36.423] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"completed_ts": "1533662795012560",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1533655117648100",
"failure_reason": "BUILD_FAILURE",
"id": "8938839767315007152",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"167e27f010746fde0f052d6868396f23b205502d\"}}",
"project": "chromium",
"result": "FAILURE",
"result_details_json": "{\"ui\": {\"info\": \"Failure compare_build_artifacts\"}, \"build_run_result\": {\"infraFailure\": null, \"recipeResult\": {\"failure\": {\"failure\": {\"step\": \"compare_build_artifacts\"}, \"humanReason\": \"Step('compare_build_artifacts') failed with return_code 1\"}}, \"recipeExitCode\": {\"value\": 1}, \"recipe\": {\"name\": \"swarming/deterministic_build\", \"repository\": \"\", \"revision\": \"\"}, \"annotationUrl\": \"logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8938839767315007152/+/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\": [\"3712-19aca72\"], \"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\": \"821833667ccccf83b96b4fdaf2074c6e71f87544\", \"got_swarming_client_revision\": \"486c9b53c4d54dd4b95bb6ce0e31160e600dfc11\", \"got_revision\": \"167e27f010746fde0f052d6868396f23b205502d\", \"recipe\": \"swarming/deterministic_build\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#24204}\", \"$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@{#581228}\", \"branch\": \"refs/heads/master\", \"revision\": \"167e27f010746fde0f052d6868396f23b205502d\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"buildername\": \"Windows deterministic\", \"got_webrtc_revision\": \"7008287219b6bc45465c409323bda75f4788a711\", \"mastername\": \"chromium.fyi\", \"got_angle_revision\": \"6ba9754f4a5668936976ca7d8f5ec69de30f9aee\", \"buildbucket\": {\"hostname\": \"cr-buildbucket.appspot.com\", \"build\": {\"created_ts\": 1533655117648100, \"tags\": [\"builder:Windows deterministic\", \"buildset:commit/git/167e27f010746fde0f052d6868396f23b205502d\", \"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/167e27f010746fde0f052d6868396f23b205502d\", \"gitiles_ref:refs/heads/master\", \"scheduler_invocation_id:9104269829448987360\", \"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\": \"8938839767315007152\"}}, \"got_v8_revision\": \"67c8e92b654133018c8b5ed83b10a0494068491b\", \"got_v8_revision_cp\": \"refs/heads/7.0.163@{#2}\", \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"buildnumber\": 9679, \"path_config\": \"generic\", \"bot_id\": \"swarm2173-c4\", \"got_buildtools_revision\": \"9a90d9aaadeb5e04327ed05775f45132e4b3523f\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1533655247260900",
"status": "COMPLETED",
"status_changed_ts": "1533662795757430",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/9679",
"builder:Windows deterministic",
"buildset:commit/git/167e27f010746fde0f052d6868396f23b205502d",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/167e27f010746fde0f052d6868396f23b205502d",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9104269829448987360",
"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/9679",
"swarming_tag:buildbucket_bucket:luci.chromium.ci",
"swarming_tag:buildbucket_build_id:8938839767315007152",
"swarming_tag:buildbucket_hostname:cr-buildbucket.appspot.com",
"swarming_tag:buildbucket_template_canary:0",
"swarming_tag:buildbucket_template_revision:1843d7ecb7176e182855328359db08c38323a012",
"swarming_tag:builder:Windows deterministic",
"swarming_tag:buildset:commit/git/167e27f010746fde0f052d6868396f23b205502d",
"swarming_tag:buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/167e27f010746fde0f052d6868396f23b205502d",
"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/8938839767315007152/+/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:9104269829448987360",
"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:5989837a4b2b2033290aa8856271cc05cee4c0eb",
"swarming_tag:user:None",
"swarming_tag:user_agent:luci-scheduler",
"swarming_tag:vpython:native-python-wrapper",
"swarming_task_id:3f2dc97ae32b4710",
"user_agent:luci-scheduler"
],
"updated_ts": "1533662795759280",
"url": "https://ci.chromium.org/p/chromium/builders/luci.chromium.ci/Windows deterministic/9679",
"utcnow_ts": "1533662796393920"
}
}
[17:26:36.423] Invocation finished in 2h8m0.193075396s with status FAILED