[17:58:14.170] New invocation is queued and will start shortly
[17:58:15.453] Starting the invocation (attempt 1)
[17:58:15.486] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[17:58:15.486] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[17:58:15.486] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9100092325812678160",
"parameters_json": "{\"builder_name\":\"Windows deterministic\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src.git\",\"revision\":\"516840902587453313f53e6b0b45442bebc5b8e8\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Windows deterministic",
"scheduler_invocation_id:9100092325812678160",
"scheduler_job_id:chromium/Windows deterministic",
"user_agent:luci-scheduler",
"buildset:commit/git/516840902587453313f53e6b0b45442bebc5b8e8",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/516840902587453313f53e6b0b45442bebc5b8e8",
"gitiles_ref:refs/heads/master"
]
}
[17:58:16.598] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1537639095570499",
"id": "8934662263681467728",
"lease_expiration_ts": "1537783096372318",
"lease_key": "1864287729",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"516840902587453313f53e6b0b45442bebc5b8e8\"}}",
"project": "chromium",
"result_details_json": "null",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1537639096492030",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/10170",
"builder:Windows deterministic",
"buildset:commit/git/516840902587453313f53e6b0b45442bebc5b8e8",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/516840902587453313f53e6b0b45442bebc5b8e8",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9100092325812678160",
"scheduler_job_id:chromium/Windows deterministic",
"swarming_dimension:builder:Windows deterministic",
"swarming_dimension:caches:builder_d795d6e07fe37a299fcfc0e872e48757ad306282f024125f803c0465bfd1eeae_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/10170",
"swarming_tag:buildbucket_bucket:luci.chromium.ci",
"swarming_tag:buildbucket_build_id:8934662263681467728",
"swarming_tag:buildbucket_hostname:cr-buildbucket.appspot.com",
"swarming_tag:buildbucket_template_canary:0",
"swarming_tag:buildbucket_template_revision:4c790cbde9342069ed9b59b6709c8807b14f2913",
"swarming_tag:builder:Windows deterministic",
"swarming_tag:buildset:commit/git/516840902587453313f53e6b0b45442bebc5b8e8",
"swarming_tag:buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/516840902587453313f53e6b0b45442bebc5b8e8",
"swarming_tag:caches:builder_d795d6e07fe37a299fcfc0e872e48757ad306282f024125f803c0465bfd1eeae_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/8934662263681467728/+/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:9100092325812678160",
"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:ded6e667eedfba9ca83cacf95f0d2164f0246aeb",
"swarming_tag:user:None",
"swarming_tag:user_agent:luci-scheduler",
"swarming_tag:vpython:native-python-wrapper",
"swarming_task_id:401b4027767d9510",
"user_agent:luci-scheduler"
],
"updated_ts": "1537639096492132",
"url": "https://ci.chromium.org/p/chromium/builders/luci.chromium.ci/Windows deterministic/10170",
"utcnow_ts": "1537639096577109"
}
}
[17:58:16.598] Task URL: https://ci.chromium.org/p/chromium/builders/luci.chromium.ci/Windows deterministic/10170
[17:58:16.598] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100092325812678160:2:0) after 1m0s
[17:58:19.791] Received PubSub notification, asking Buildbucket for the build status
[17:58:19.838] Build 8934662263681467728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:59:16.635] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100092325812678160:2:0)
[17:59:16.635] Timer tick, asking Buildbucket for the build status
[17:59:16.691] Build 8934662263681467728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:59:16.691] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100092325812678160:4:0) after 1m0s
[18:00:16.734] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100092325812678160:4:0)
[18:00:16.734] Timer tick, asking Buildbucket for the build status
[18:00:16.879] Build 8934662263681467728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:00:16.879] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100092325812678160:5:0) after 1m0s
[18:01:17.028] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100092325812678160:5:0)
[18:01:17.028] Timer tick, asking Buildbucket for the build status
[18:01:17.323] Build 8934662263681467728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:01:17.323] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100092325812678160:6:0) after 1m0s
[18:02:17.405] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100092325812678160:6:0)
[18:02:17.405] Timer tick, asking Buildbucket for the build status
[18:02:17.501] Build 8934662263681467728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:02:17.501] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100092325812678160:7:0) after 1m0s
[18:03:17.646] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100092325812678160:7:0)
[18:03:17.646] Timer tick, asking Buildbucket for the build status
[18:03:17.826] Build 8934662263681467728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:03:17.826] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100092325812678160:8:0) after 1m0s
[18:04:17.857] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100092325812678160:8:0)
[18:04:17.857] Timer tick, asking Buildbucket for the build status
[18:04:17.947] Build 8934662263681467728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:04:17.947] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100092325812678160:9:0) after 1m0s
[18:05:18.036] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100092325812678160:9:0)
[18:05:18.036] Timer tick, asking Buildbucket for the build status
[18:05:18.154] Build 8934662263681467728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:05:18.155] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100092325812678160:10:0) after 1m0s
[18:06:18.238] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100092325812678160:10:0)
[18:06:18.238] Timer tick, asking Buildbucket for the build status
[18:06:18.415] Build 8934662263681467728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:06:18.415] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100092325812678160:11:0) after 1m0s
[18:07:18.439] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100092325812678160:11:0)
[18:07:18.439] Timer tick, asking Buildbucket for the build status
[18:07:18.600] Build 8934662263681467728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:07:18.600] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100092325812678160:12:0) after 1m0s
[18:08:18.617] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100092325812678160:12:0)
[18:08:18.617] Timer tick, asking Buildbucket for the build status
[18:08:18.652] Build 8934662263681467728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:08:18.652] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100092325812678160:13:0) after 1m0s
[18:09:18.695] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100092325812678160:13:0)
[18:09:18.695] Timer tick, asking Buildbucket for the build status
[18:09:18.762] Build 8934662263681467728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:09:18.762] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100092325812678160:14:0) after 1m0s
[18:10:18.870] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100092325812678160:14:0)
[18:10:18.870] Timer tick, asking Buildbucket for the build status
[18:10:18.927] Build 8934662263681467728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:10:18.927] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100092325812678160:15:0) after 1m0s
[18:11:18.993] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100092325812678160:15:0)
[18:11:18.993] Timer tick, asking Buildbucket for the build status
[18:11:19.064] Build 8934662263681467728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:11:19.064] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100092325812678160:16:0) after 1m0s
[18:12:19.106] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100092325812678160:16:0)
[18:12:19.106] Timer tick, asking Buildbucket for the build status
[18:12:19.159] Build 8934662263681467728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:12:19.159] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100092325812678160:17:0) after 1m0s
[18:13:19.293] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100092325812678160:17:0)
[18:13:19.293] Timer tick, asking Buildbucket for the build status
[18:13:19.375] Build 8934662263681467728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:13:19.375] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100092325812678160:18:0) after 1m0s
[18:14:19.484] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100092325812678160:18:0)
[18:14:19.484] Timer tick, asking Buildbucket for the build status
[18:14:19.598] Build 8934662263681467728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:14:19.598] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100092325812678160:19:0) after 1m0s
[18:15:19.616] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100092325812678160:19:0)
[18:15:19.616] Timer tick, asking Buildbucket for the build status
[18:15:19.683] Build 8934662263681467728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:15:19.683] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100092325812678160:20:0) after 1m0s
[18:16:19.733] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100092325812678160:20:0)
[18:16:19.733] Timer tick, asking Buildbucket for the build status
[18:16:19.899] Build 8934662263681467728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:16:19.899] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100092325812678160:21:0) after 1m0s
[18:17:20.034] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100092325812678160:21:0)
[18:17:20.034] Timer tick, asking Buildbucket for the build status
[18:17:20.092] Build 8934662263681467728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:17:20.092] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100092325812678160:22:0) after 1m0s
[18:18:20.113] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100092325812678160:22:0)
[18:18:20.113] Timer tick, asking Buildbucket for the build status
[18:18:20.183] Build 8934662263681467728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:18:20.183] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100092325812678160:23:0) after 1m0s
[18:19:20.311] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100092325812678160:23:0)
[18:19:20.311] Timer tick, asking Buildbucket for the build status
[18:19:20.371] Build 8934662263681467728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:19:20.371] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100092325812678160:24:0) after 1m0s
[18:20:20.414] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100092325812678160:24:0)
[18:20:20.414] Timer tick, asking Buildbucket for the build status
[18:20:20.475] Build 8934662263681467728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:20:20.475] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100092325812678160:25:0) after 1m0s
[18:21:20.528] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100092325812678160:25:0)
[18:21:20.528] Timer tick, asking Buildbucket for the build status
[18:21:20.571] Build 8934662263681467728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:21:20.571] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100092325812678160:26:0) after 1m0s
[18:22:20.593] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100092325812678160:26:0)
[18:22:20.593] Timer tick, asking Buildbucket for the build status
[18:22:20.668] Build 8934662263681467728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:22:20.668] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100092325812678160:27:0) after 1m0s
[18:23:20.754] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100092325812678160:27:0)
[18:23:20.754] Timer tick, asking Buildbucket for the build status
[18:23:21.075] Build 8934662263681467728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:23:21.075] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100092325812678160:28:0) after 1m0s
[18:24:21.094] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100092325812678160:28:0)
[18:24:21.094] Timer tick, asking Buildbucket for the build status
[18:24:21.473] Build 8934662263681467728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:24:21.473] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100092325812678160:29:0) after 1m0s
[18:25:21.490] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100092325812678160:29:0)
[18:25:21.490] Timer tick, asking Buildbucket for the build status
[18:25:21.559] Build 8934662263681467728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:25:21.559] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100092325812678160:30:0) after 1m0s
[18:26:21.578] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100092325812678160:30:0)
[18:26:21.578] Timer tick, asking Buildbucket for the build status
[18:26:21.709] Build 8934662263681467728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:26:21.709] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100092325812678160:31:0) after 1m0s
[18:27:21.750] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100092325812678160:31:0)
[18:27:21.750] Timer tick, asking Buildbucket for the build status
[18:27:21.909] Build 8934662263681467728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:27:21.909] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100092325812678160:32:0) after 1m0s
[18:28:21.928] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100092325812678160:32:0)
[18:28:21.928] Timer tick, asking Buildbucket for the build status
[18:28:21.995] Build 8934662263681467728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:28:21.995] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100092325812678160:33:0) after 1m0s
[18:29:22.015] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100092325812678160:33:0)
[18:29:22.015] Timer tick, asking Buildbucket for the build status
[18:29:22.047] Build 8934662263681467728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:29:22.047] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100092325812678160:34:0) after 1m0s
[18:30:22.071] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100092325812678160:34:0)
[18:30:22.071] Timer tick, asking Buildbucket for the build status
[18:30:22.181] Build 8934662263681467728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:30:22.181] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100092325812678160:35:0) after 1m0s
[18:31:22.203] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100092325812678160:35:0)
[18:31:22.203] Timer tick, asking Buildbucket for the build status
[18:31:22.252] Build 8934662263681467728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:31:22.252] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100092325812678160:36:0) after 1m0s
[18:32:22.272] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100092325812678160:36:0)
[18:32:22.272] Timer tick, asking Buildbucket for the build status
[18:32:22.329] Build 8934662263681467728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:32:22.329] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100092325812678160:37:0) after 1m0s
[18:33:22.358] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100092325812678160:37:0)
[18:33:22.358] Timer tick, asking Buildbucket for the build status
[18:33:22.503] Build 8934662263681467728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:33:22.503] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100092325812678160:38:0) after 1m0s
[18:34:22.523] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100092325812678160:38:0)
[18:34:22.523] Timer tick, asking Buildbucket for the build status
[18:34:22.703] Build 8934662263681467728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:34:22.703] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100092325812678160:39:0) after 1m0s
[18:35:22.723] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100092325812678160:39:0)
[18:35:22.723] Timer tick, asking Buildbucket for the build status
[18:35:22.763] Build 8934662263681467728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:35:22.763] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100092325812678160:40:0) after 1m0s
[18:36:22.788] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100092325812678160:40:0)
[18:36:22.788] Timer tick, asking Buildbucket for the build status
[18:36:22.843] Build 8934662263681467728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:36:22.843] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100092325812678160:41:0) after 1m0s
[18:37:22.908] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100092325812678160:41:0)
[18:37:22.908] Timer tick, asking Buildbucket for the build status
[18:37:22.981] Build 8934662263681467728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:37:22.981] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100092325812678160:42:0) after 1m0s
[18:38:23.003] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100092325812678160:42:0)
[18:38:23.003] Timer tick, asking Buildbucket for the build status
[18:38:23.268] Build 8934662263681467728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:38:23.268] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100092325812678160:43:0) after 1m0s
[18:39:23.288] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100092325812678160:43:0)
[18:39:23.288] Timer tick, asking Buildbucket for the build status
[18:39:23.366] Build 8934662263681467728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:39:23.366] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100092325812678160:44:0) after 1m0s
[18:40:23.398] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100092325812678160:44:0)
[18:40:23.398] Timer tick, asking Buildbucket for the build status
[18:40:23.671] Build 8934662263681467728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:40:23.671] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100092325812678160:45:0) after 1m0s
[18:41:23.689] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100092325812678160:45:0)
[18:41:23.689] Timer tick, asking Buildbucket for the build status
[18:41:23.753] Build 8934662263681467728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:41:23.753] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100092325812678160:46:0) after 1m0s
[18:42:23.775] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100092325812678160:46:0)
[18:42:23.775] Timer tick, asking Buildbucket for the build status
[18:42:23.895] Build 8934662263681467728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:42:23.895] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100092325812678160:47:0) after 1m0s
[18:43:23.914] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100092325812678160:47:0)
[18:43:23.914] Timer tick, asking Buildbucket for the build status
[18:43:24.003] Build 8934662263681467728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:43:24.003] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100092325812678160:48:0) after 1m0s
[18:44:24.019] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100092325812678160:48:0)
[18:44:24.019] Timer tick, asking Buildbucket for the build status
[18:44:24.091] Build 8934662263681467728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:44:24.091] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100092325812678160:49:0) after 1m0s
[18:45:24.113] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100092325812678160:49:0)
[18:45:24.113] Timer tick, asking Buildbucket for the build status
[18:45:24.149] Build 8934662263681467728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:45:24.149] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100092325812678160:50:0) after 1m0s
[18:46:24.248] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100092325812678160:50:0)
[18:46:24.248] Timer tick, asking Buildbucket for the build status
[18:46:24.312] Build 8934662263681467728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:46:24.312] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100092325812678160:51:0) after 1m0s
[18:47:24.341] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100092325812678160:51:0)
[18:47:24.341] Timer tick, asking Buildbucket for the build status
[18:47:24.397] Build 8934662263681467728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:47:24.397] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100092325812678160:52:0) after 1m0s
[18:48:24.451] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100092325812678160:52:0)
[18:48:24.451] Timer tick, asking Buildbucket for the build status
[18:48:24.589] Build 8934662263681467728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:48:24.589] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100092325812678160:53:0) after 1m0s
[18:49:24.610] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100092325812678160:53:0)
[18:49:24.610] Timer tick, asking Buildbucket for the build status
[18:49:24.734] Build 8934662263681467728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:49:24.734] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100092325812678160:54:0) after 1m0s
[18:50:24.753] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100092325812678160:54:0)
[18:50:24.753] Timer tick, asking Buildbucket for the build status
[18:50:24.802] Build 8934662263681467728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:50:24.802] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100092325812678160:55:0) after 1m0s
[18:51:24.829] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100092325812678160:55:0)
[18:51:24.829] Timer tick, asking Buildbucket for the build status
[18:51:24.937] Build 8934662263681467728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:51:24.937] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100092325812678160:56:0) after 1m0s
[18:52:24.974] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100092325812678160:56:0)
[18:52:24.974] Timer tick, asking Buildbucket for the build status
[18:52:25.031] Build 8934662263681467728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:52:25.031] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100092325812678160:57:0) after 1m0s
[18:53:25.054] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100092325812678160:57:0)
[18:53:25.054] Timer tick, asking Buildbucket for the build status
[18:53:25.129] Build 8934662263681467728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:53:25.129] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100092325812678160:58:0) after 1m0s
[18:54:25.149] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100092325812678160:58:0)
[18:54:25.149] Timer tick, asking Buildbucket for the build status
[18:54:25.184] Build 8934662263681467728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:54:25.184] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100092325812678160:59:0) after 1m0s
[18:55:25.202] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100092325812678160:59:0)
[18:55:25.202] Timer tick, asking Buildbucket for the build status
[18:55:25.266] Build 8934662263681467728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:55:25.266] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100092325812678160:60:0) after 1m0s
[18:56:25.298] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100092325812678160:60:0)
[18:56:25.298] Timer tick, asking Buildbucket for the build status
[18:56:25.365] Build 8934662263681467728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:56:25.365] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100092325812678160:61:0) after 1m0s
[18:57:25.385] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100092325812678160:61:0)
[18:57:25.385] Timer tick, asking Buildbucket for the build status
[18:57:25.449] Build 8934662263681467728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:57:25.449] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100092325812678160:62:0) after 1m0s
[18:58:25.505] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100092325812678160:62:0)
[18:58:25.505] Timer tick, asking Buildbucket for the build status
[18:58:25.575] Build 8934662263681467728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:58:25.575] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100092325812678160:63:0) after 1m0s
[18:59:25.595] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100092325812678160:63:0)
[18:59:25.595] Timer tick, asking Buildbucket for the build status
[18:59:25.814] Build 8934662263681467728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:59:25.814] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100092325812678160:64:0) after 1m0s
[19:00:25.840] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100092325812678160:64:0)
[19:00:25.840] Timer tick, asking Buildbucket for the build status
[19:00:25.898] Build 8934662263681467728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:00:25.898] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100092325812678160:65:0) after 1m0s
[19:01:25.916] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100092325812678160:65:0)
[19:01:25.916] Timer tick, asking Buildbucket for the build status
[19:01:25.985] Build 8934662263681467728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:01:25.985] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100092325812678160:66:0) after 1m0s
[19:02:26.012] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100092325812678160:66:0)
[19:02:26.012] Timer tick, asking Buildbucket for the build status
[19:02:26.083] Build 8934662263681467728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:02:26.083] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100092325812678160:67:0) after 1m0s
[19:03:26.150] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100092325812678160:67:0)
[19:03:26.150] Timer tick, asking Buildbucket for the build status
[19:03:27.196] Build 8934662263681467728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:03:27.196] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100092325812678160:68:0) after 1m0s
[19:04:27.221] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100092325812678160:68:0)
[19:04:27.221] Timer tick, asking Buildbucket for the build status
[19:04:27.365] Build 8934662263681467728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:04:27.366] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100092325812678160:69:0) after 1m0s
[19:05:27.387] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100092325812678160:69:0)
[19:05:27.387] Timer tick, asking Buildbucket for the build status
[19:05:27.629] Build 8934662263681467728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:05:27.629] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100092325812678160:70:0) after 1m0s
[19:06:27.693] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100092325812678160:70:0)
[19:06:27.693] Timer tick, asking Buildbucket for the build status
[19:06:28.088] Build 8934662263681467728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:06:28.088] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100092325812678160:71:0) after 1m0s
[19:07:28.118] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100092325812678160:71:0)
[19:07:28.118] Timer tick, asking Buildbucket for the build status
[19:07:28.258] Build 8934662263681467728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:07:28.258] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100092325812678160:72:0) after 1m0s
[19:08:28.281] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100092325812678160:72:0)
[19:08:28.281] Timer tick, asking Buildbucket for the build status
[19:08:28.821] Build 8934662263681467728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:08:28.821] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100092325812678160:73:0) after 1m0s
[19:09:28.894] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100092325812678160:73:0)
[19:09:28.894] Timer tick, asking Buildbucket for the build status
[19:09:30.010] Build 8934662263681467728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:09:30.010] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100092325812678160:74:0) after 1m0s
[19:10:30.074] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100092325812678160:74:0)
[19:10:30.074] Timer tick, asking Buildbucket for the build status
[19:10:30.161] Build 8934662263681467728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:10:30.161] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100092325812678160:75:0) after 1m0s
[19:11:30.257] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100092325812678160:75:0)
[19:11:30.257] Timer tick, asking Buildbucket for the build status
[19:11:30.310] Build 8934662263681467728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:11:30.310] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100092325812678160:76:0) after 1m0s
[19:12:30.399] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100092325812678160:76:0)
[19:12:30.399] Timer tick, asking Buildbucket for the build status
[19:12:30.495] Build 8934662263681467728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:12:30.495] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100092325812678160:77:0) after 1m0s
[19:13:30.518] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100092325812678160:77:0)
[19:13:30.518] Timer tick, asking Buildbucket for the build status
[19:13:30.589] Build 8934662263681467728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:13:30.589] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100092325812678160:78:0) after 1m0s
[19:14:30.620] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100092325812678160:78:0)
[19:14:30.620] Timer tick, asking Buildbucket for the build status
[19:14:30.793] Build 8934662263681467728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:14:30.793] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100092325812678160:79:0) after 1m0s
[19:15:30.831] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100092325812678160:79:0)
[19:15:30.831] Timer tick, asking Buildbucket for the build status
[19:15:30.890] Build 8934662263681467728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:15:30.890] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100092325812678160:80:0) after 1m0s
[19:16:30.913] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100092325812678160:80:0)
[19:16:30.913] Timer tick, asking Buildbucket for the build status
[19:16:30.967] Build 8934662263681467728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:16:30.968] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100092325812678160:81:0) after 1m0s
[19:17:30.989] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100092325812678160:81:0)
[19:17:30.989] Timer tick, asking Buildbucket for the build status
[19:17:31.046] Build 8934662263681467728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:17:31.046] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100092325812678160:82:0) after 1m0s
[19:18:31.076] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100092325812678160:82:0)
[19:18:31.076] Timer tick, asking Buildbucket for the build status
[19:18:31.145] Build 8934662263681467728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:18:31.145] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100092325812678160:83:0) after 1m0s
[19:19:31.166] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100092325812678160:83:0)
[19:19:31.166] Timer tick, asking Buildbucket for the build status
[19:19:31.237] Build 8934662263681467728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:19:31.237] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100092325812678160:84:0) after 1m0s
[19:20:31.268] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100092325812678160:84:0)
[19:20:31.268] Timer tick, asking Buildbucket for the build status
[19:20:31.326] Build 8934662263681467728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:20:31.326] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100092325812678160:85:0) after 1m0s
[19:21:31.364] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100092325812678160:85:0)
[19:21:31.364] Timer tick, asking Buildbucket for the build status
[19:21:31.521] Build 8934662263681467728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:21:31.521] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100092325812678160:86:0) after 1m0s
[19:22:31.663] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100092325812678160:86:0)
[19:22:31.663] Timer tick, asking Buildbucket for the build status
[19:22:31.792] Build 8934662263681467728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:22:31.792] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100092325812678160:87:0) after 1m0s
[19:23:31.846] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100092325812678160:87:0)
[19:23:31.846] Timer tick, asking Buildbucket for the build status
[19:23:32.022] Build 8934662263681467728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:23:32.022] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100092325812678160:88:0) after 1m0s
[19:24:32.090] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100092325812678160:88:0)
[19:24:32.090] Timer tick, asking Buildbucket for the build status
[19:24:32.228] Build 8934662263681467728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:24:32.228] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100092325812678160:89:0) after 1m0s
[19:25:32.409] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100092325812678160:89:0)
[19:25:32.409] Timer tick, asking Buildbucket for the build status
[19:25:32.598] Build 8934662263681467728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:25:32.598] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100092325812678160:90:0) after 1m0s
[19:26:32.666] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100092325812678160:90:0)
[19:26:32.666] Timer tick, asking Buildbucket for the build status
[19:26:33.655] Build 8934662263681467728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:26:33.655] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100092325812678160:91:0) after 1m0s
[19:27:33.679] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100092325812678160:91:0)
[19:27:33.679] Timer tick, asking Buildbucket for the build status
[19:27:33.833] Build 8934662263681467728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:27:33.833] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100092325812678160:92:0) after 1m0s
[19:28:33.855] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100092325812678160:92:0)
[19:28:33.855] Timer tick, asking Buildbucket for the build status
[19:28:33.932] Build 8934662263681467728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:28:33.932] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100092325812678160:93:0) after 1m0s
[19:29:33.956] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100092325812678160:93:0)
[19:29:33.956] Timer tick, asking Buildbucket for the build status
[19:29:34.012] Build 8934662263681467728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:29:34.012] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100092325812678160:94:0) after 1m0s
[19:30:34.057] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100092325812678160:94:0)
[19:30:34.057] Timer tick, asking Buildbucket for the build status
[19:30:34.095] Build 8934662263681467728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:30:34.095] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100092325812678160:95:0) after 1m0s
[19:31:34.128] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100092325812678160:95:0)
[19:31:34.128] Timer tick, asking Buildbucket for the build status
[19:31:34.331] Build 8934662263681467728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:31:34.331] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100092325812678160:96:0) after 1m0s
[19:32:34.360] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100092325812678160:96:0)
[19:32:34.360] Timer tick, asking Buildbucket for the build status
[19:32:34.492] Build 8934662263681467728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:32:34.492] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100092325812678160:97:0) after 1m0s
[19:33:34.518] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100092325812678160:97:0)
[19:33:34.518] Timer tick, asking Buildbucket for the build status
[19:33:34.759] Build 8934662263681467728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:33:34.759] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100092325812678160:98:0) after 1m0s
[19:34:34.785] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100092325812678160:98:0)
[19:34:34.785] Timer tick, asking Buildbucket for the build status
[19:34:34.845] Build 8934662263681467728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:34:34.845] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100092325812678160:99:0) after 1m0s
[19:35:34.888] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100092325812678160:99:0)
[19:35:34.888] Timer tick, asking Buildbucket for the build status
[19:35:34.930] Build 8934662263681467728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:35:34.930] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9100092325812678160:100:0) after 1m0s
[19:36:02.932] Received PubSub notification, asking Buildbucket for the build status
[19:36:02.987] Build 8934662263681467728: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[19:36:02.987] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"completed_ts": "1537644960488508",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1537639095570499",
"failure_reason": "BUILD_FAILURE",
"id": "8934662263681467728",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"516840902587453313f53e6b0b45442bebc5b8e8\"}}",
"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/8934662263681467728/+/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\": [\"3783-5e6adab\"], \"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\": \"9f85491d8830fe6f3b8e0efa2e954f71bd9c64af\", \"got_swarming_client_revision\": \"486c9b53c4d54dd4b95bb6ce0e31160e600dfc11\", \"got_revision\": \"516840902587453313f53e6b0b45442bebc5b8e8\", \"recipe\": \"swarming/deterministic_build\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#24780}\", \"$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@{#593444}\", \"branch\": \"refs/heads/master\", \"revision\": \"516840902587453313f53e6b0b45442bebc5b8e8\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"buildername\": \"Windows deterministic\", \"got_webrtc_revision\": \"0ad0c27a0bcee24ec9dc5c27df090034e152bd65\", \"mastername\": \"chromium.fyi\", \"got_angle_revision\": \"55e57f96fbf84a7d42e807d25a304362b76da917\", \"buildbucket\": {\"hostname\": \"cr-buildbucket.appspot.com\", \"build\": {\"created_ts\": 1537639095570499, \"tags\": [\"builder:Windows deterministic\", \"buildset:commit/git/516840902587453313f53e6b0b45442bebc5b8e8\", \"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/516840902587453313f53e6b0b45442bebc5b8e8\", \"gitiles_ref:refs/heads/master\", \"scheduler_invocation_id:9100092325812678160\", \"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\": \"8934662263681467728\"}}, \"got_v8_revision\": \"2f22d11156ccb1f88587896c27151944cad48cba\", \"got_v8_revision_cp\": \"refs/heads/7.1.163@{#1}\", \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"buildnumber\": 10170, \"swarm_hashes\": {\"shell_dialogs_unittests\": \"314761de097e67d00ada5f4aabf483290a4098d9\", \"notification_helper_unittests\": \"216e1613f61e368cd990463c1667b4440203f996\", \"gpu_perftests\": \"75887b9f09125b71b6706cf008b1414929b89a39\", \"vr_common_unittests\": \"7398aaf9402fd4278cda54f0f4bc88b38330416d\", \"telemetry_unittests\": \"45b2052cc3638debf4f15949379eed3abdf170ac\", \"load_library_perf_tests\": \"8d4762b7a4a084b6f986a705050f6849dcce96e3\", \"devtools_closure_compile\": \"4c14287de95e3ba0fa6108ba6cb04b37f0119f15\", \"chromedriver_py_tests\": \"67af966f49b8bedfa99befc398d200f743e417d4\", \"mojo_core_unittests\": \"02df1ba071024bcd3fe54c557a7cfa47b55953a0\", \"install_static_unittests\": \"db30dd750715021e0d3ad0427f8c3c0e883f1ba2\", \"leveldb_service_unittests\": \"60d2d8ebd93e6a89baf85c42902c7ef909d5e9a4\", \"mini_installer_tests\": \"1c64cb9065a661fd23133f606034732c3414705f\", \"jingle_unittests\": \"9a5e17036d95078b248f5658c58deea76da8d249\", \"sbox_integration_tests\": \"4a923b829898ab00ffdc84750a1e0beb2c6ca19a\", \"angle_unittests\": \"36283d923d4014be8d56671705c88c87ad601a01\", \"cast_unittests\": \"e1b1f30de57255190d2ed1315600ade46dcda94e\", \"cc_unittests\": \"90f1f0cd53c6120716dd4229eba2105efbdd7f0b\", \"blink_common_unittests\": \"bd44ff080b86b5da281ea5ad0fd5e026dddaa2fc\", \"crashpad_tests\": \"cb61d015b365751d1a356bd65f7cd1adfa4598b8\", \"telemetry_perf_tests_without_chrome\": \"6b4fd283f83e607df829fa7fb7188d079d42b175\", \"metrics_python_tests\": \"e95e6ad6e22e16e8380bffda0940094e78415b5f\", \"message_center_unittests\": \"090b5077cde1f7b6578b53c65c3894241dfaf3fd\", \"ppapi_unittests\": \"cc8dcfb37a6a92c3e495d4033e88e30ef516691f\", \"extensions_unittests\": \"d5d5ea6d5b6aa5245a4f74398422a6b26ebdbc05\", \"gl_unittests_ozone\": \"cb33148ad4c6dfa98676664abc7a76028a7bdb44\", \"app_shell_unittests\": \"76799e70a14da17b869701318cbe058ecd24c2b8\", \"chrome_app_unittests\": \"5a5f80452d326cf2add4f2e96f9c44afe4b7a0b3\", \"gl_unittests\": \"cb33148ad4c6dfa98676664abc7a76028a7bdb44\", \"gcm_unit_tests\": \"43ee812707947794de0fcc11f101d502c43340e8\", \"views_unittests\": \"ac2461553201709a275d2f78a2be5f3cf2267cdf\", \"content_shell_crash_test\": \"3ed5e7a626cb91966c9ed5a7a5b8bcf6f4493336\", \"ui_base_unittests\": \"571ebd402575382f175e706dd6fd2362fd7920c0\", \"gles2_conform_test\": \"44e5605930fc986aa3d998038c43777ce4594430\", \"cronet_unittests\": \"bca59d50526c2b272553e57d3c9c64f066f561ef\", \"performance_test_suite\": \"46e84bc975fc4975f1ac256dfc7913bf0029b45a\", \"wm_unittests\": \"6d81ccef56cbe8fc628870ba2ccbacb62a745030\", \"boringssl_crypto_tests\": \"42f0af3f82979a9893eac3c3643f76b84529b018\", \"gin_unittests\": \"a9bca8f65da54e4ef07dc7929b6fc4c8605b1f47\", \"performance_browser_tests\": \"5fc9397ec8b499749db57ba689f2aa79cb13d289\", \"extensions_browsertests\": \"933b0f8506ebc8609ec89c0f4df6026a035f27e7\", \"webkit_python_tests\": \"d158224fa80fea9494613a4e2cc83c3710e1a2b2\", \"headless_unittests\": \"553551198a3402aa81238d20dfb0c4cc4f63c10f\", \"webkit_layout_tests\": \"fda6a0d2a10c9ea7af110cf552536d9cdd550286\", \"vr_pixeltests\": \"1718382e4ef668f2573a387b56af02e9daf34190\", \"setup_unittests\": \"56b710e3858f41e578d21cafc12288fe8cede364\", \"webkit_unit_tests\": \"448ac2347989a8064c55ab4a11b2b9250eb17c74\", \"content_unittests\": \"dc3f02204218dfb438656ce713a245ba7277ad8a\", \"performance_webview_test_suite\": \"186b07e1fd3750042eb5b827cff2ce7f687418b5\", \"wtf_unittests\": \"6330a5dd1e366167efe69357e7de6901969305ce\", \"webkit_layout_tests_exparchive\": \"2956b57df912911ed7b2bb5498aa4c1349611373\", \"chrome_cleaner_unittests\": \"895c762be20a6b294ed8cb9d41bed47a92c95fe6\", \"traffic_annotation_auditor_unittests\": \"84f8f55a7ce53e8d25db995da87ee68e7f3a085e\", \"libjingle_xmpp_unittests\": \"c49e03c8a53c9da5f9ca94a7107852ee8b304bf9\", \"remoting_unittests\": \"a54924dde541ae5d616ed113b8ad0f8fa0738c02\", \"vr_common_perftests\": \"07e7720ba925edfce059ff2030cd462ca25e6bb1\", \"filesystem_service_unittests\": \"610cb3d5b1811147f3b61b816795fecc7356feb7\", \"chrome_elf_import_unittests\": \"ddfd1b48f61d402bdc0cdb64689da08fe66462b3\", \"installer_util_unittests\": \"23e67cc0d1cc8e39f44c2ce75aef4907f15d2402\", \"gfx_unittests\": \"7fd8dd90526223ea5785a35af0b15416cdb84d12\", \"headless_browsertests\": \"2a4e4e81435e0018085b7ef2bf1e3f3798b2dc60\", \"capture_unittests\": \"518d4b9475a17da1cde25e23e318b02a66eb4858\", \"chrome_elf_unittests\": \"ccb50a3539accceb1ecfcd92253c39a08c7f603c\", \"telemetry_gpu_unittests\": \"4ac9381e6a8d96a78b0c3b3c7c9fa029d5f40814\", \"command_buffer_perftests\": \"b97d04e37c33e2ae8740760b1e34f1de506c567e\", \"media_unittests\": \"92ad1c5fb53859e8a622d3f3ac8bc976a04969a1\", \"chromedriver_unittests\": \"bc8a4b812a3c717acd56ac793233aa4d98260184\", \"views_mus_interactive_ui_tests\": \"8e23eda652cb335c6e7bae536e5bc27fd6e7b523\", \"storage_unittests\": \"76b0f740408ff5af8eaf808a2993fcefb8180b6c\", \"media_service_unittests\": \"50f178e9d9b1242c24f9b6ccb84115fe5a70b37d\", \"compositor_unittests\": \"097352350326634427c0b03fc5b1abe842e9abf5\", \"latency_unittests\": \"69e025be9e309fddee93bf6d3b8c749fd19823ff\", \"video_decode_accelerator_unittest\": \"dc6a0e16949c563d8ebaf3df4a8609e283512761\", \"swiftshader_unittests\": \"878de22b3275f0ccb373e993ceeba1231407f3dd\", \"telemetry_perf_unittests\": \"6e2e6c42855165535545f40520f63e052d5c7d12\", \"media_blink_unittests\": \"9b5f37d9f5ff400dcf5865ff175faecb028c12d7\", \"views_mus_unittests\": \"3c2be05d1baf6e09bcfb6842ef2bdb664efb3c60\", \"components_unittests\": \"4b6a24c08c3eb1afa3337bcf00f214616ef5cacd\", \"snapshot_unittests\": \"0c39568ec9a82d1c59378202eb8c11557f4e7f2b\", \"accessibility_unittests\": \"8de3a8d37183aaae9838af4fc583c72b05c691d7\", \"net_perftests\": \"40ec6344ecd55d2db0b2cea9826770b5078170e2\", \"aura_unittests\": \"92c031e2a5bb760fb96150861f23e257ac994d70\", \"services_unittests\": \"d8306a7358090724990b8df359a81fa5f8422679\", \"components_perftests\": \"6862dd1710721a83f8eb138419db81c12fe440ff\", \"events_unittests\": \"61478936914c00e63f29d019719fa99c2af95c9d\", \"tracing_perftests\": \"2e0057526c5b177d2e565b17036ddb960a1e0416\", \"chromedriver_replay_unittests\": \"3d75567c4cb5be67d5cf025731258bcd9f3d4b8a\", \"ui_touch_selection_unittests\": \"45db612be15175e731ce5d4e37e20b838e63b5ed\", \"cacheinvalidation_unittests\": \"470ce1496e645fb24338f72c0841750602a6619d\", \"viz_unittests\": \"19c20fc044f39caf1d775769d2832a01915bae0e\", \"browser_tests\": \"fe00397a3355d621b56ee0fc71088ab0bf8949d2\", \"net_unittests\": \"4bbeec85fd7ae0ed2f8f7d9da683b137dc70fcd5\", \"midi_unittests\": \"9aae1a581541c8ef687cb66dae0e1ee7181debc8\", \"boringssl_ssl_tests\": \"0ba0fcaff6f265a48cc1c1f2b1babea798fea781\", \"sbox_validation_tests\": \"d15adb290a5bce94bc16392b15c5393ca62e6f1e\", \"elevation_service_unittests\": \"dcefab2288fa7bfdd156ef232315f094dfb89371\", \"gpu_unittests\": \"ea28aee3e71ff16fab6e13a05beb3c6c50f6dbcb\", \"native_theme_unittests\": \"253f2428c006be9f266ff2fa78e9e7485a3f8b36\", \"interactive_ui_tests\": \"b0feda472975d186e14fbaff5491fdde4d4e53a3\", \"mojo_unittests\": \"33620517a98a1b536e7e1fc928570830aa9e3d2b\", \"url_unittests\": \"09a06ab47fc2e88ae8de2230f772ff9ed5349be5\", \"media_perftests\": \"474fa4269211c0945306d30ce60507726c858dd0\", \"cronet_tests\": \"e7929eb7e48e0083193b7b9e67369af246b59f45\", \"google_apis_unittests\": \"5c675c442c67c89f956b23552d1b63cd9cd6c58d\", \"telemetry_gpu_integration_test\": \"1316bc191f4e702976899440fb689bdc80a17684\", \"blink_heap_unittests\": \"d12895c39dd19c0d8bd54b59aa94e97620661d67\", \"base_unittests\": \"cf3bad3f121be6c336ab44a35009fc651d1661b1\", \"sync_integration_tests\": \"5c44e4d0d1df3f15d0beaaeb0d6cf46456af36ab\", \"sql_unittests\": \"0c82cf8097d8ff4c73c58d453cda44d80ed15df7\", \"courgette_unittests\": \"82303ce9819980655b6189049f61d94926da0782\", \"blink_fuzzer_unittests\": \"52c3eba263d413c6a6f18bc82ed604578ebaae80\", \"gl_tests\": \"aad1fc63e0028fc93a81356594b16b19522e17cf\", \"zucchini_unittests\": \"5da8593b94100e441824f2ae1a55abfdbc07b9e4\", \"ipc_tests\": \"de87119a47f2b478e95081b6880e8a2e279c43a8\", \"angle_end2end_tests\": \"a8119e8ea4d599c672434baccf7f71381995a99a\", \"blink_platform_unittests\": \"d0e555f1863ba3052d8752e558b87487bd1684c3\", \"components_browsertests\": \"623b4592035fe35dec826dc25287607d6f639cc1\", \"views_perftests\": \"1543fe993137261dd7325927e2b7e2b4c925866f\", \"angle_perftests\": \"55ec85e31e7aee4b59de1628a993aa9eff53b09a\", \"display_unittests\": \"5359341a8c6fa449ef4401f9abfb449039549f3b\", \"sbox_unittests\": \"258c2a9ba87697429803db4247c29be882a5b0d1\", \"service_manager_unittests\": \"7651c1a43b63d0f33b26cf2293b86a03c97b407a\", \"pdf_unittests\": \"f4952687970300711de95ad67916b95e2a7de426\", \"skia_unittests\": \"053a3987484fb4c0e54ec550e44662a99f84edd5\", \"angle_white_box_tests\": \"e167a97d329013427fd9085d2394caf7ef166b8d\", \"crypto_unittests\": \"6426c53042791fd08bea24507d020c953d5a5c62\", \"nacl_loader_unittests\": \"3d5244a261bacbf815da1ce11d4c85d2453f1f04\", \"unit_tests\": \"a5940ce162c44627ee56fd40cf3d2aea20a20d90\", \"content_browsertests\": \"78b6903ec893f9c907269a5903910818d5621877\", \"printing_unittests\": \"88de371e60dd22e5eaf94b4d3d7cc0b6dc35699f\", \"device_unittests\": \"46b3d894cc22f1ccd87081e31ca9f5778ba6a971\"}, \"path_config\": \"generic\", \"bot_id\": \"swarm2173-c4\", \"got_buildtools_revision\": \"2dff9c9c74e9d732e6fe57c84ef7fd044cc45d96\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1537639098858481",
"status": "COMPLETED",
"status_changed_ts": "1537644961626365",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/10170",
"builder:Windows deterministic",
"buildset:commit/git/516840902587453313f53e6b0b45442bebc5b8e8",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/516840902587453313f53e6b0b45442bebc5b8e8",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9100092325812678160",
"scheduler_job_id:chromium/Windows deterministic",
"swarming_dimension:builder:Windows deterministic",
"swarming_dimension:caches:builder_d795d6e07fe37a299fcfc0e872e48757ad306282f024125f803c0465bfd1eeae_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/10170",
"swarming_tag:buildbucket_bucket:luci.chromium.ci",
"swarming_tag:buildbucket_build_id:8934662263681467728",
"swarming_tag:buildbucket_hostname:cr-buildbucket.appspot.com",
"swarming_tag:buildbucket_template_canary:0",
"swarming_tag:buildbucket_template_revision:4c790cbde9342069ed9b59b6709c8807b14f2913",
"swarming_tag:builder:Windows deterministic",
"swarming_tag:buildset:commit/git/516840902587453313f53e6b0b45442bebc5b8e8",
"swarming_tag:buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/516840902587453313f53e6b0b45442bebc5b8e8",
"swarming_tag:caches:builder_d795d6e07fe37a299fcfc0e872e48757ad306282f024125f803c0465bfd1eeae_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/8934662263681467728/+/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:9100092325812678160",
"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:ded6e667eedfba9ca83cacf95f0d2164f0246aeb",
"swarming_tag:user:None",
"swarming_tag:user_agent:luci-scheduler",
"swarming_tag:vpython:native-python-wrapper",
"swarming_task_id:401b4027767d9510",
"user_agent:luci-scheduler"
],
"updated_ts": "1537644961631738",
"url": "https://ci.chromium.org/p/chromium/builders/luci.chromium.ci/Windows deterministic/10170",
"utcnow_ts": "1537644962972383"
}
}
[19:36:02.987] Invocation finished in 1h37m48.832848366s with status FAILED