[05:52:16.153] New invocation is queued and will start shortly
[05:52:17.421] Starting the invocation (attempt 1)
[05:52:17.455] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[05:52:17.455] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[05:52:17.455] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9101859342065000400",
"parameters_json": "{\"builder_name\":\"Windows deterministic\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src.git\",\"revision\":\"2976ea78bbec3db9187f8106848776d180310807\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Windows deterministic",
"scheduler_invocation_id:9101859342065000400",
"scheduler_job_id:chromium/Windows deterministic",
"user_agent:luci-scheduler",
"buildset:commit/git/2976ea78bbec3db9187f8106848776d180310807",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/2976ea78bbec3db9187f8106848776d180310807",
"gitiles_ref:refs/heads/master"
]
}
[05:52:18.887] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1535953937575520",
"id": "8936429279912082032",
"lease_expiration_ts": "1536097938527090",
"lease_key": "1617743453",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"2976ea78bbec3db9187f8106848776d180310807\"}}",
"project": "chromium",
"result_details_json": "null",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1535953938734740",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/9956",
"builder:Windows deterministic",
"buildset:commit/git/2976ea78bbec3db9187f8106848776d180310807",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/2976ea78bbec3db9187f8106848776d180310807",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9101859342065000400",
"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/9956",
"swarming_tag:buildbucket_bucket:luci.chromium.ci",
"swarming_tag:buildbucket_build_id:8936429279912082032",
"swarming_tag:buildbucket_hostname:cr-buildbucket.appspot.com",
"swarming_tag:buildbucket_template_canary:0",
"swarming_tag:buildbucket_template_revision:ca2c7a741e04d0385e2bf62941ac932dc0521af3",
"swarming_tag:builder:Windows deterministic",
"swarming_tag:buildset:commit/git/2976ea78bbec3db9187f8106848776d180310807",
"swarming_tag:buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/2976ea78bbec3db9187f8106848776d180310807",
"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/8936429279912082032/+/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:9101859342065000400",
"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:b9015ab329c28cd84b28ec0e12b4612663b264bc",
"swarming_tag:user:None",
"swarming_tag:user_agent:luci-scheduler",
"swarming_tag:vpython:native-python-wrapper",
"swarming_task_id:3fb6ceaf37cbb510",
"user_agent:luci-scheduler"
],
"updated_ts": "1535953938734830",
"url": "https://ci.chromium.org/p/chromium/builders/luci.chromium.ci/Windows deterministic/9956",
"utcnow_ts": "1535953938865920"
}
}
[05:52:18.887] Task URL: https://ci.chromium.org/p/chromium/builders/luci.chromium.ci/Windows deterministic/9956
[05:52:18.887] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101859342065000400:2:0) after 1m0s
[05:53:19.107] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101859342065000400:2:0)
[05:53:19.107] Timer tick, asking Buildbucket for the build status
[05:53:19.170] Build 8936429279912082032: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:53:19.170] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101859342065000400:3:0) after 1m0s
[05:53:41.983] Received PubSub notification, asking Buildbucket for the build status
[05:53:42.054] Build 8936429279912082032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:54:19.787] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101859342065000400:3:0)
[05:54:19.787] Timer tick, asking Buildbucket for the build status
[05:54:19.823] Build 8936429279912082032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:54:19.823] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101859342065000400:5:0) after 1m0s
[05:55:19.876] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101859342065000400:5:0)
[05:55:19.876] Timer tick, asking Buildbucket for the build status
[05:55:20.105] Build 8936429279912082032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:55:20.105] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101859342065000400:6:0) after 1m0s
[05:56:20.521] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101859342065000400:6:0)
[05:56:20.521] Timer tick, asking Buildbucket for the build status
[05:56:20.560] Build 8936429279912082032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:56:20.560] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101859342065000400:7:0) after 1m0s
[05:57:21.005] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101859342065000400:7:0)
[05:57:21.005] Timer tick, asking Buildbucket for the build status
[05:57:21.153] Build 8936429279912082032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:57:21.154] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101859342065000400:8:0) after 1m0s
[05:58:21.402] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101859342065000400:8:0)
[05:58:21.402] Timer tick, asking Buildbucket for the build status
[05:58:21.483] Build 8936429279912082032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:58:21.483] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101859342065000400:9:0) after 1m0s
[05:59:21.585] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101859342065000400:9:0)
[05:59:21.585] Timer tick, asking Buildbucket for the build status
[05:59:21.686] Build 8936429279912082032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:59:21.686] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101859342065000400:10:0) after 1m0s
[06:00:22.196] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101859342065000400:10:0)
[06:00:22.196] Timer tick, asking Buildbucket for the build status
[06:00:22.590] Build 8936429279912082032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:00:22.590] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101859342065000400:11:0) after 1m0s
[06:01:23.171] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101859342065000400:11:0)
[06:01:23.171] Timer tick, asking Buildbucket for the build status
[06:01:23.306] Build 8936429279912082032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:01:23.306] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101859342065000400:12:0) after 1m0s
[06:02:23.379] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101859342065000400:12:0)
[06:02:23.379] Timer tick, asking Buildbucket for the build status
[06:02:23.491] Build 8936429279912082032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:02:23.491] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101859342065000400:13:0) after 1m0s
[06:03:23.548] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101859342065000400:13:0)
[06:03:23.548] Timer tick, asking Buildbucket for the build status
[06:03:23.623] Build 8936429279912082032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:03:23.623] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101859342065000400:14:0) after 1m0s
[06:04:23.682] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101859342065000400:14:0)
[06:04:23.682] Timer tick, asking Buildbucket for the build status
[06:04:23.745] Build 8936429279912082032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:04:23.745] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101859342065000400:15:0) after 1m0s
[06:05:23.761] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101859342065000400:15:0)
[06:05:23.761] Timer tick, asking Buildbucket for the build status
[06:05:23.837] Build 8936429279912082032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:05:23.837] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101859342065000400:16:0) after 1m0s
[06:06:23.870] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101859342065000400:16:0)
[06:06:23.870] Timer tick, asking Buildbucket for the build status
[06:06:23.980] Build 8936429279912082032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:06:23.980] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101859342065000400:17:0) after 1m0s
[06:07:23.999] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101859342065000400:17:0)
[06:07:23.999] Timer tick, asking Buildbucket for the build status
[06:07:24.225] Build 8936429279912082032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:07:24.225] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101859342065000400:18:0) after 1m0s
[06:08:24.245] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101859342065000400:18:0)
[06:08:24.245] Timer tick, asking Buildbucket for the build status
[06:08:24.378] Build 8936429279912082032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:08:24.378] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101859342065000400:19:0) after 1m0s
[06:09:24.968] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101859342065000400:19:0)
[06:09:24.968] Timer tick, asking Buildbucket for the build status
[06:09:25.163] Build 8936429279912082032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:09:25.163] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101859342065000400:20:0) after 1m0s
[06:10:25.768] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101859342065000400:20:0)
[06:10:25.768] Timer tick, asking Buildbucket for the build status
[06:10:25.826] Build 8936429279912082032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:10:25.826] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101859342065000400:21:0) after 1m0s
[06:11:26.427] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101859342065000400:21:0)
[06:11:26.427] Timer tick, asking Buildbucket for the build status
[06:11:26.612] Build 8936429279912082032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:11:26.612] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101859342065000400:22:0) after 1m0s
[06:12:26.654] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101859342065000400:22:0)
[06:12:26.654] Timer tick, asking Buildbucket for the build status
[06:12:26.707] Build 8936429279912082032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:12:26.707] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101859342065000400:23:0) after 1m0s
[06:13:27.353] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101859342065000400:23:0)
[06:13:27.353] Timer tick, asking Buildbucket for the build status
[06:13:27.405] Build 8936429279912082032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:13:27.405] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101859342065000400:24:0) after 1m0s
[06:14:27.844] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101859342065000400:24:0)
[06:14:27.844] Timer tick, asking Buildbucket for the build status
[06:14:27.887] Build 8936429279912082032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:14:27.887] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101859342065000400:25:0) after 1m0s
[06:15:27.906] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101859342065000400:25:0)
[06:15:27.906] Timer tick, asking Buildbucket for the build status
[06:15:27.975] Build 8936429279912082032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:15:27.975] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101859342065000400:26:0) after 1m0s
[06:16:28.373] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101859342065000400:26:0)
[06:16:28.373] Timer tick, asking Buildbucket for the build status
[06:16:28.471] Build 8936429279912082032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:16:28.471] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101859342065000400:27:0) after 1m0s
[06:17:29.082] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101859342065000400:27:0)
[06:17:29.082] Timer tick, asking Buildbucket for the build status
[06:17:29.116] Build 8936429279912082032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:17:29.116] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101859342065000400:28:0) after 1m0s
[06:18:29.134] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101859342065000400:28:0)
[06:18:29.134] Timer tick, asking Buildbucket for the build status
[06:18:29.222] Build 8936429279912082032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:18:29.222] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101859342065000400:29:0) after 1m0s
[06:19:29.238] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101859342065000400:29:0)
[06:19:29.238] Timer tick, asking Buildbucket for the build status
[06:19:30.115] Build 8936429279912082032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:19:30.115] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101859342065000400:30:0) after 1m0s
[06:20:30.784] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101859342065000400:30:0)
[06:20:30.784] Timer tick, asking Buildbucket for the build status
[06:20:30.821] Build 8936429279912082032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:20:30.821] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101859342065000400:31:0) after 1m0s
[06:21:31.533] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101859342065000400:31:0)
[06:21:31.533] Timer tick, asking Buildbucket for the build status
[06:21:31.585] Build 8936429279912082032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:21:31.585] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101859342065000400:32:0) after 1m0s
[06:22:31.605] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101859342065000400:32:0)
[06:22:31.605] Timer tick, asking Buildbucket for the build status
[06:22:31.650] Build 8936429279912082032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:22:31.650] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101859342065000400:33:0) after 1m0s
[06:23:32.427] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101859342065000400:33:0)
[06:23:32.427] Timer tick, asking Buildbucket for the build status
[06:23:32.472] Build 8936429279912082032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:23:32.472] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101859342065000400:34:0) after 1m0s
[06:24:33.171] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101859342065000400:34:0)
[06:24:33.171] Timer tick, asking Buildbucket for the build status
[06:24:33.309] Build 8936429279912082032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:24:33.309] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101859342065000400:35:0) after 1m0s
[06:25:33.933] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101859342065000400:35:0)
[06:25:33.933] Timer tick, asking Buildbucket for the build status
[06:25:34.030] Build 8936429279912082032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:25:34.030] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101859342065000400:36:0) after 1m0s
[06:26:34.529] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101859342065000400:36:0)
[06:26:34.529] Timer tick, asking Buildbucket for the build status
[06:26:34.622] Build 8936429279912082032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:26:34.622] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101859342065000400:37:0) after 1m0s
[06:27:35.273] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101859342065000400:37:0)
[06:27:35.273] Timer tick, asking Buildbucket for the build status
[06:27:35.326] Build 8936429279912082032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:27:35.326] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101859342065000400:38:0) after 1m0s
[06:28:35.868] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101859342065000400:38:0)
[06:28:35.868] Timer tick, asking Buildbucket for the build status
[06:28:35.899] Build 8936429279912082032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:28:35.899] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101859342065000400:39:0) after 1m0s
[06:29:36.608] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101859342065000400:39:0)
[06:29:36.608] Timer tick, asking Buildbucket for the build status
[06:29:36.679] Build 8936429279912082032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:29:36.679] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101859342065000400:40:0) after 1m0s
[06:30:36.702] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101859342065000400:40:0)
[06:30:36.702] Timer tick, asking Buildbucket for the build status
[06:30:36.799] Build 8936429279912082032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:30:36.799] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101859342065000400:41:0) after 1m0s
[06:31:36.820] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101859342065000400:41:0)
[06:31:36.820] Timer tick, asking Buildbucket for the build status
[06:31:36.855] Build 8936429279912082032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:31:36.855] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101859342065000400:42:0) after 1m0s
[06:32:37.699] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101859342065000400:42:0)
[06:32:37.699] Timer tick, asking Buildbucket for the build status
[06:32:37.853] Build 8936429279912082032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:32:37.853] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101859342065000400:43:0) after 1m0s
[06:33:38.494] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101859342065000400:43:0)
[06:33:38.494] Timer tick, asking Buildbucket for the build status
[06:33:38.744] Build 8936429279912082032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:33:38.745] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101859342065000400:44:0) after 1m0s
[06:34:39.326] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101859342065000400:44:0)
[06:34:39.326] Timer tick, asking Buildbucket for the build status
[06:34:39.403] Build 8936429279912082032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:34:39.403] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101859342065000400:45:0) after 1m0s
[06:35:40.141] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101859342065000400:45:0)
[06:35:40.141] Timer tick, asking Buildbucket for the build status
[06:35:40.377] Build 8936429279912082032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:35:40.377] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101859342065000400:46:0) after 1m0s
[06:36:40.809] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101859342065000400:46:0)
[06:36:40.809] Timer tick, asking Buildbucket for the build status
[06:36:41.084] Build 8936429279912082032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:36:41.084] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101859342065000400:47:0) after 1m0s
[06:37:41.103] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101859342065000400:47:0)
[06:37:41.103] Timer tick, asking Buildbucket for the build status
[06:37:41.196] Build 8936429279912082032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:37:41.196] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101859342065000400:48:0) after 1m0s
[06:38:41.213] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101859342065000400:48:0)
[06:38:41.213] Timer tick, asking Buildbucket for the build status
[06:38:41.419] Build 8936429279912082032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:38:41.419] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101859342065000400:49:0) after 1m0s
[06:39:41.437] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101859342065000400:49:0)
[06:39:41.437] Timer tick, asking Buildbucket for the build status
[06:39:41.540] Build 8936429279912082032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:39:41.540] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101859342065000400:50:0) after 1m0s
[06:40:41.834] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101859342065000400:50:0)
[06:40:41.834] Timer tick, asking Buildbucket for the build status
[06:40:42.737] Build 8936429279912082032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:40:42.737] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101859342065000400:51:0) after 1m0s
[06:41:43.446] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101859342065000400:51:0)
[06:41:43.446] Timer tick, asking Buildbucket for the build status
[06:41:43.485] Build 8936429279912082032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:41:43.485] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101859342065000400:52:0) after 1m0s
[06:42:44.275] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101859342065000400:52:0)
[06:42:44.275] Timer tick, asking Buildbucket for the build status
[06:42:44.467] Build 8936429279912082032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:42:44.467] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101859342065000400:53:0) after 1m0s
[06:43:44.585] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101859342065000400:53:0)
[06:43:44.585] Timer tick, asking Buildbucket for the build status
[06:43:44.650] Build 8936429279912082032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:43:44.650] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101859342065000400:54:0) after 1m0s
[06:44:45.232] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101859342065000400:54:0)
[06:44:45.232] Timer tick, asking Buildbucket for the build status
[06:44:45.320] Build 8936429279912082032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:44:45.320] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101859342065000400:55:0) after 1m0s
[06:45:46.005] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101859342065000400:55:0)
[06:45:46.005] Timer tick, asking Buildbucket for the build status
[06:45:46.168] Build 8936429279912082032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:45:46.168] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101859342065000400:56:0) after 1m0s
[06:46:47.051] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101859342065000400:56:0)
[06:46:47.051] Timer tick, asking Buildbucket for the build status
[06:46:47.130] Build 8936429279912082032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:46:47.130] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101859342065000400:57:0) after 1m0s
[06:47:47.167] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101859342065000400:57:0)
[06:47:47.167] Timer tick, asking Buildbucket for the build status
[06:47:47.405] Build 8936429279912082032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:47:47.405] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101859342065000400:58:0) after 1m0s
[06:48:48.211] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101859342065000400:58:0)
[06:48:48.211] Timer tick, asking Buildbucket for the build status
[06:48:48.281] Build 8936429279912082032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:48:48.281] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101859342065000400:59:0) after 1m0s
[06:49:48.308] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101859342065000400:59:0)
[06:49:48.308] Timer tick, asking Buildbucket for the build status
[06:49:48.561] Build 8936429279912082032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:49:48.562] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101859342065000400:60:0) after 1m0s
[06:50:48.739] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101859342065000400:60:0)
[06:50:48.739] Timer tick, asking Buildbucket for the build status
[06:50:48.869] Build 8936429279912082032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:50:48.869] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101859342065000400:61:0) after 1m0s
[06:51:49.610] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101859342065000400:61:0)
[06:51:49.610] Timer tick, asking Buildbucket for the build status
[06:51:49.795] Build 8936429279912082032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:51:49.795] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101859342065000400:62:0) after 1m0s
[06:52:50.425] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101859342065000400:62:0)
[06:52:50.425] Timer tick, asking Buildbucket for the build status
[06:52:50.820] Build 8936429279912082032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:52:50.820] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101859342065000400:63:0) after 1m0s
[06:53:51.291] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101859342065000400:63:0)
[06:53:51.291] Timer tick, asking Buildbucket for the build status
[06:53:51.398] Build 8936429279912082032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:53:51.398] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101859342065000400:64:0) after 1m0s
[06:54:52.061] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101859342065000400:64:0)
[06:54:52.061] Timer tick, asking Buildbucket for the build status
[06:54:52.132] Build 8936429279912082032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:54:52.132] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101859342065000400:65:0) after 1m0s
[06:55:52.185] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101859342065000400:65:0)
[06:55:52.185] Timer tick, asking Buildbucket for the build status
[06:55:53.143] Build 8936429279912082032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:55:53.143] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101859342065000400:66:0) after 1m0s
[06:56:53.298] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101859342065000400:66:0)
[06:56:53.298] Timer tick, asking Buildbucket for the build status
[06:56:53.392] Build 8936429279912082032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:56:53.392] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101859342065000400:67:0) after 1m0s
[06:57:53.498] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101859342065000400:67:0)
[06:57:53.498] Timer tick, asking Buildbucket for the build status
[06:57:53.578] Build 8936429279912082032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:57:53.578] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101859342065000400:68:0) after 1m0s
[06:58:53.598] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101859342065000400:68:0)
[06:58:53.598] Timer tick, asking Buildbucket for the build status
[06:58:53.690] Build 8936429279912082032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:58:53.690] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101859342065000400:69:0) after 1m0s
[06:59:53.713] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101859342065000400:69:0)
[06:59:53.713] Timer tick, asking Buildbucket for the build status
[06:59:53.771] Build 8936429279912082032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:59:53.771] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101859342065000400:70:0) after 1m0s
[07:00:53.796] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101859342065000400:70:0)
[07:00:53.796] Timer tick, asking Buildbucket for the build status
[07:00:53.856] Build 8936429279912082032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:00:53.856] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101859342065000400:71:0) after 1m0s
[07:01:54.397] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101859342065000400:71:0)
[07:01:54.397] Timer tick, asking Buildbucket for the build status
[07:01:54.446] Build 8936429279912082032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:01:54.446] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101859342065000400:72:0) after 1m0s
[07:02:55.095] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101859342065000400:72:0)
[07:02:55.095] Timer tick, asking Buildbucket for the build status
[07:02:55.150] Build 8936429279912082032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:02:55.150] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101859342065000400:73:0) after 1m0s
[07:03:55.968] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101859342065000400:73:0)
[07:03:55.968] Timer tick, asking Buildbucket for the build status
[07:03:56.022] Build 8936429279912082032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:03:56.022] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101859342065000400:74:0) after 1m0s
[07:04:56.767] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101859342065000400:74:0)
[07:04:56.767] Timer tick, asking Buildbucket for the build status
[07:04:56.870] Build 8936429279912082032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:04:56.870] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101859342065000400:75:0) after 1m0s
[07:05:56.892] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101859342065000400:75:0)
[07:05:56.892] Timer tick, asking Buildbucket for the build status
[07:05:56.928] Build 8936429279912082032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:05:56.928] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101859342065000400:76:0) after 1m0s
[07:06:57.251] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101859342065000400:76:0)
[07:06:57.251] Timer tick, asking Buildbucket for the build status
[07:06:57.354] Build 8936429279912082032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:06:57.354] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101859342065000400:77:0) after 1m0s
[07:07:58.029] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101859342065000400:77:0)
[07:07:58.029] Timer tick, asking Buildbucket for the build status
[07:07:58.167] Build 8936429279912082032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:07:58.167] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101859342065000400:78:0) after 1m0s
[07:08:58.742] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101859342065000400:78:0)
[07:08:58.742] Timer tick, asking Buildbucket for the build status
[07:08:58.820] Build 8936429279912082032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:08:58.820] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101859342065000400:79:0) after 1m0s
[07:09:59.448] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101859342065000400:79:0)
[07:09:59.448] Timer tick, asking Buildbucket for the build status
[07:09:59.740] Build 8936429279912082032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:09:59.740] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101859342065000400:80:0) after 1m0s
[07:11:00.361] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101859342065000400:80:0)
[07:11:00.361] Timer tick, asking Buildbucket for the build status
[07:11:00.428] Build 8936429279912082032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:11:00.428] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101859342065000400:81:0) after 1m0s
[07:12:01.218] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101859342065000400:81:0)
[07:12:01.218] Timer tick, asking Buildbucket for the build status
[07:12:01.386] Build 8936429279912082032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:12:01.386] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101859342065000400:82:0) after 1m0s
[07:13:01.411] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101859342065000400:82:0)
[07:13:01.411] Timer tick, asking Buildbucket for the build status
[07:13:01.500] Build 8936429279912082032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:13:01.500] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101859342065000400:83:0) after 1m0s
[07:14:01.538] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101859342065000400:83:0)
[07:14:01.538] Timer tick, asking Buildbucket for the build status
[07:14:01.615] Build 8936429279912082032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:14:01.615] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101859342065000400:84:0) after 1m0s
[07:15:01.718] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101859342065000400:84:0)
[07:15:01.718] Timer tick, asking Buildbucket for the build status
[07:15:01.839] Build 8936429279912082032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:15:01.839] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101859342065000400:85:0) after 1m0s
[07:16:01.857] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101859342065000400:85:0)
[07:16:01.857] Timer tick, asking Buildbucket for the build status
[07:16:01.947] Build 8936429279912082032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:16:01.947] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101859342065000400:86:0) after 1m0s
[07:17:02.214] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101859342065000400:86:0)
[07:17:02.214] Timer tick, asking Buildbucket for the build status
[07:17:02.326] Build 8936429279912082032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:17:02.326] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101859342065000400:87:0) after 1m0s
[07:18:02.758] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101859342065000400:87:0)
[07:18:02.758] Timer tick, asking Buildbucket for the build status
[07:18:02.921] Build 8936429279912082032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:18:02.921] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101859342065000400:88:0) after 1m0s
[07:19:03.478] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101859342065000400:88:0)
[07:19:03.478] Timer tick, asking Buildbucket for the build status
[07:19:03.535] Build 8936429279912082032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:19:03.535] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101859342065000400:89:0) after 1m0s
[07:20:04.231] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101859342065000400:89:0)
[07:20:04.231] Timer tick, asking Buildbucket for the build status
[07:20:04.413] Build 8936429279912082032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:20:04.413] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101859342065000400:90:0) after 1m0s
[07:21:04.919] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101859342065000400:90:0)
[07:21:04.919] Timer tick, asking Buildbucket for the build status
[07:21:05.286] Build 8936429279912082032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:21:05.287] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101859342065000400:91:0) after 1m0s
[07:22:05.366] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101859342065000400:91:0)
[07:22:05.366] Timer tick, asking Buildbucket for the build status
[07:22:05.534] Build 8936429279912082032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:22:05.534] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101859342065000400:92:0) after 1m0s
[07:23:05.844] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101859342065000400:92:0)
[07:23:05.844] Timer tick, asking Buildbucket for the build status
[07:23:05.944] Build 8936429279912082032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:23:05.944] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101859342065000400:93:0) after 1m0s
[07:24:05.966] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101859342065000400:93:0)
[07:24:05.966] Timer tick, asking Buildbucket for the build status
[07:24:06.045] Build 8936429279912082032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:24:06.045] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101859342065000400:94:0) after 1m0s
[07:25:06.067] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101859342065000400:94:0)
[07:25:06.067] Timer tick, asking Buildbucket for the build status
[07:25:06.148] Build 8936429279912082032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:25:06.148] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101859342065000400:95:0) after 1m0s
[07:26:06.290] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101859342065000400:95:0)
[07:26:06.290] Timer tick, asking Buildbucket for the build status
[07:26:06.420] Build 8936429279912082032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:26:06.420] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101859342065000400:96:0) after 1m0s
[07:27:06.904] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101859342065000400:96:0)
[07:27:06.904] Timer tick, asking Buildbucket for the build status
[07:27:06.985] Build 8936429279912082032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:27:06.985] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101859342065000400:97:0) after 1m0s
[07:28:07.016] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101859342065000400:97:0)
[07:28:07.016] Timer tick, asking Buildbucket for the build status
[07:28:07.133] Build 8936429279912082032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:28:07.133] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101859342065000400:98:0) after 1m0s
[07:29:07.168] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101859342065000400:98:0)
[07:29:07.168] Timer tick, asking Buildbucket for the build status
[07:29:07.316] Build 8936429279912082032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:29:07.316] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101859342065000400:99:0) after 1m0s
[07:30:07.341] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101859342065000400:99:0)
[07:30:07.341] Timer tick, asking Buildbucket for the build status
[07:30:07.490] Build 8936429279912082032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:30:07.490] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101859342065000400:100:0) after 1m0s
[07:31:07.514] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101859342065000400:100:0)
[07:31:07.514] Timer tick, asking Buildbucket for the build status
[07:31:07.614] Build 8936429279912082032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:31:07.614] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101859342065000400:101:0) after 1m0s
[07:32:07.661] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101859342065000400:101:0)
[07:32:07.661] Timer tick, asking Buildbucket for the build status
[07:32:07.762] Build 8936429279912082032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:32:07.762] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101859342065000400:102:0) after 1m0s
[07:33:07.786] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101859342065000400:102:0)
[07:33:07.786] Timer tick, asking Buildbucket for the build status
[07:33:07.844] Build 8936429279912082032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:33:07.845] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101859342065000400:103:0) after 1m0s
[07:34:02.204] Received PubSub notification, asking Buildbucket for the build status
[07:34:02.328] Build 8936429279912082032: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[07:34:02.328] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"completed_ts": "1535960040860170",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1535953937575520",
"failure_reason": "BUILD_FAILURE",
"id": "8936429279912082032",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"2976ea78bbec3db9187f8106848776d180310807\"}}",
"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/8936429279912082032/+/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\": [\"3740-90b257a\"], \"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\": \"cb6ec4b1f91af259cbdfd1bd22bb98ed5551955e\", \"got_swarming_client_revision\": \"486c9b53c4d54dd4b95bb6ce0e31160e600dfc11\", \"got_revision\": \"2976ea78bbec3db9187f8106848776d180310807\", \"recipe\": \"swarming/deterministic_build\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#24472}\", \"$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@{#588332}\", \"branch\": \"refs/heads/master\", \"revision\": \"2976ea78bbec3db9187f8106848776d180310807\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"buildername\": \"Windows deterministic\", \"got_webrtc_revision\": \"f18b35284288ac851b77db88df3e2e8d2273db97\", \"mastername\": \"chromium.fyi\", \"got_angle_revision\": \"9808d38c032a49476dcb4ce3891d6b8bd2128803\", \"buildbucket\": {\"hostname\": \"cr-buildbucket.appspot.com\", \"build\": {\"created_ts\": 1535953937575520, \"tags\": [\"builder:Windows deterministic\", \"buildset:commit/git/2976ea78bbec3db9187f8106848776d180310807\", \"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/2976ea78bbec3db9187f8106848776d180310807\", \"gitiles_ref:refs/heads/master\", \"scheduler_invocation_id:9101859342065000400\", \"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\": \"8936429279912082032\"}}, \"got_v8_revision\": \"9c8d1a1ebea28c7e3a5a4f79ed6e4997fa41ab4a\", \"got_v8_revision_cp\": \"refs/heads/7.1.8@{#1}\", \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"buildnumber\": 9956, \"swarm_hashes\": {\"shell_dialogs_unittests\": \"f26b90fb2266a0e48ef46a587a3266564a712871\", \"notification_helper_unittests\": \"84c3d029149d6f1341bbca8cbac28a1ab1d5be7a\", \"gpu_perftests\": \"06cc38e2619ec8b631b706ae87c7841836e9ffdc\", \"vr_common_unittests\": \"cf69ee1e861ef856a0fda64a37f8b98e6f75bad3\", \"telemetry_unittests\": \"93c7a59f189a0b8919b801c44a918e743930a66a\", \"load_library_perf_tests\": \"d8e212eecfa3b7073694059bff96aeb923e4b81d\", \"devtools_closure_compile\": \"b34cca3078dd0fb087691b975f49b95513d53f95\", \"chromedriver_py_tests\": \"13afbd99545a578939d355124a14b515655edf94\", \"mojo_core_unittests\": \"9074eb1cf98f1cbe0c2249fed4d6e20701b921cb\", \"install_static_unittests\": \"4df4f7e0f510d09a314c5990ab5df9f9a2bb5ba2\", \"leveldb_service_unittests\": \"7af868414687eac65665affbe2fc9f1fd330dc46\", \"mini_installer_tests\": \"8d60ce84f294ae1f27c904d0ba62244ba0c0b96f\", \"jingle_unittests\": \"5f305abe6311c4553374e6ab069caea48262f81f\", \"sbox_integration_tests\": \"0d1a0653678b35bebcf57b207185ab2a9ab6c572\", \"angle_unittests\": \"612dec63084966385c860e1cbed9fb809190d4ac\", \"cast_unittests\": \"c55e6027fac591346216d6459bcf8050e641445e\", \"cc_unittests\": \"bd53491b04da7918f2b7a5945585f0eb108c463b\", \"blink_common_unittests\": \"1433a332b074d923b505d6de9af641d203cd374d\", \"crashpad_tests\": \"1d2f81879a23c02c0d399123175859e6a0243660\", \"telemetry_perf_tests_without_chrome\": \"1d8d28c7118857d2dbdd7787b93f65b67791b8f8\", \"metrics_python_tests\": \"a54ccbdcb57a0d4e104d4d9469fa880fb724183b\", \"message_center_unittests\": \"837ba5e7124a589487a6c992876ce51892314d76\", \"ppapi_unittests\": \"3778b6a504ff3066ff61f2b39961b96860d161a8\", \"extensions_unittests\": \"78f69a72564b20965b7955d08296c0302ded91d6\", \"gl_unittests_ozone\": \"0341a967ac17b2ba2ff7e7f0dc2b60540e1dd59c\", \"app_shell_unittests\": \"05b0d32784abab8a797cd7bc83edd79ecfc984f5\", \"chrome_app_unittests\": \"3425e4bf22305012154383ab16a5b998bb3cdc5c\", \"gl_unittests\": \"0341a967ac17b2ba2ff7e7f0dc2b60540e1dd59c\", \"gcm_unit_tests\": \"2e579b4e7a1ece11f3c54d694b7a06641dd4744c\", \"views_unittests\": \"001a5d29782704f82fd53457b603bed7c891d3b1\", \"content_shell_crash_test\": \"27ad9e5dfda9b25e8d994040a411c15da1bc56c4\", \"ui_base_unittests\": \"8022a48608967069a91e516564cb52257ac04326\", \"gles2_conform_test\": \"6cbc6e079fea85f0575492df8ba14489022a371a\", \"cronet_unittests\": \"a77cb65003077dec2ad6e000b4affe5389698f93\", \"performance_test_suite\": \"c5db83203744a775d057fbe8c57ba2c0cf5d1165\", \"wm_unittests\": \"69a01905ca0474de4fddfff74d56586900d091a6\", \"boringssl_crypto_tests\": \"e663226a1d085852b76870625357cf2989b3f162\", \"gin_unittests\": \"4794f5925428c58d434d7af9a846524648463646\", \"performance_browser_tests\": \"008075c62dd64b597689eea82a6a0002634a197a\", \"extensions_browsertests\": \"ca49e320e0e4fd8cb86a291332acb3882f885559\", \"webkit_python_tests\": \"acc53a78d44fcbdeb97af6a8be4ffaab8086f5c1\", \"headless_unittests\": \"bb68f548e31e7fee7524810d58b5a8f4316dc192\", \"webkit_layout_tests\": \"f6004cccfbbf0eecb2824306256e28f30a1c3f1c\", \"vr_pixeltests\": \"019c7df1e487cb681a9fa8a6a5d44b03ea225b3f\", \"setup_unittests\": \"3955e11fbdeac8d4f784325d9a3ec06f7aefca1f\", \"webkit_unit_tests\": \"a753a6590c7db8026b0c968ebd0403f83856d53a\", \"content_unittests\": \"f013bcbd571841a42d59f204aa3059348efa3eda\", \"performance_webview_test_suite\": \"6be31c03037470bbf0c39e485dbfcbaedb192d55\", \"wtf_unittests\": \"8601851b1c7b046b9f0fea9af2265d885a9cc38e\", \"webkit_layout_tests_exparchive\": \"ab432c341d3eed7ca8d97dfb72f50b70ab60e140\", \"chrome_cleaner_unittests\": \"b85a986c66fd3b13cf8c3cf9cd09b94ccdaa3383\", \"traffic_annotation_auditor_unittests\": \"a0e95081ff75d6db0013a369e12f5860a404f0f3\", \"libjingle_xmpp_unittests\": \"ba3e9285e382c0e12ef54ab18e93c6f6582e1253\", \"remoting_unittests\": \"b686a9a1be8080ff23d9d4df25102da20fd71862\", \"vr_common_perftests\": \"44d778b9ccf07514236b80afa02b45ce3c2c6cab\", \"filesystem_service_unittests\": \"e82c5ffee7cf9fea4ce8b2c77be257842b394839\", \"chrome_elf_import_unittests\": \"b68cc66c9ca56a8070dab2c533c2835d840a9fcc\", \"installer_util_unittests\": \"5f680a5e35f493abda77133e9df1a8fe5c9a112f\", \"gfx_unittests\": \"4dd464ffcc44f24a8f21080a40de0fddd02ff738\", \"headless_browsertests\": \"7fe1ccd2e5cd0c94d0819234297115a6618fbf1e\", \"capture_unittests\": \"d61c26de4b5c0ef52a6840203d15309c8715630a\", \"chrome_elf_unittests\": \"44fb4d7323aff7e4fdd7e32b1186064b6c6c1474\", \"telemetry_gpu_unittests\": \"779ed602c7d9ab9f46e9b333bf61c506c6413751\", \"command_buffer_perftests\": \"904823f9a2f293aebcdb46c1f92413e49e256f78\", \"media_unittests\": \"0d74d23f09b8920adba9a84851e4415c25018728\", \"chromedriver_unittests\": \"6445b2542cd013f08abaac8c0bd6f525383a2df4\", \"views_mus_interactive_ui_tests\": \"b0f8a08e5fb06ec80df518ed217a2d335e94678c\", \"storage_unittests\": \"5b926ee639ae4cbe38658b5746dfb18fb1c434ad\", \"media_service_unittests\": \"6c6d821b07587eb166d9270ec9e8d185f8b0db5f\", \"compositor_unittests\": \"649654cf54c689ede8a6453dc4d35c6ac05b5570\", \"latency_unittests\": \"ea702ef49436e5f2530db72d0f7a3a472b57606d\", \"video_decode_accelerator_unittest\": \"7bd7e08aa2c7b0b20b6733e0a53a482a2fe44aac\", \"swiftshader_unittests\": \"45110c2ef5f22ea4a56bd6cdcb5abc6099026537\", \"telemetry_perf_unittests\": \"28e8ec2fd51e31e0f42be6adba7635e40a08a197\", \"media_blink_unittests\": \"a241afba3b4df12c51af7b15f719ccc110fa61f2\", \"views_mus_unittests\": \"88e11c513fbb29246742065202809dcf2f7b9c66\", \"components_unittests\": \"bedb6b9bad6b850712127a5c567e0c7450c98435\", \"snapshot_unittests\": \"92a9794f318ca5f6725d8b0a29414ee0530b1b96\", \"accessibility_unittests\": \"97840e329e496c73214f9d4a42ed875149fcef87\", \"net_perftests\": \"9531270d550635392d9caba60113179e335af117\", \"aura_unittests\": \"b63da577111aa73c78998bc945b877ec8d6286ab\", \"services_unittests\": \"d25ae13de073c71f00e4cbbb4bf7e44992e35c83\", \"components_perftests\": \"b176cc175ec4893b6d761f4880623da48156d5ff\", \"events_unittests\": \"01434a357ed87048210a9b2cb48466ecfa909933\", \"tracing_perftests\": \"79f51222655c115538ba2b5f13cba61ffc288086\", \"chromedriver_replay_unittests\": \"e2ec5b5deadfbf1a0e00246e86e8f3e11e9cace0\", \"ui_touch_selection_unittests\": \"1a09721368aba6e5c9413b445b292e686b74e231\", \"cacheinvalidation_unittests\": \"1f7aa5a45c86b19eba9d028c5357beb874dc4bd2\", \"viz_unittests\": \"f281bbf378957435810dc37a8d1f3f861a2f9689\", \"browser_tests\": \"3a9413c99111551a9fbc7f85e577c41d35b78f50\", \"net_unittests\": \"a9aa292de0d35b17aa5825091cf0c0891c1f28e0\", \"midi_unittests\": \"e2837302914e45da40be15cfa6e70daf8a855c50\", \"boringssl_ssl_tests\": \"28bf2836329b1fef0e682d5670280aec182157f0\", \"sbox_validation_tests\": \"b3601dda11ee180b46c63ced23411e9512dd614e\", \"elevation_service_unittests\": \"6ab8bd1ec2c3f96e789f26f1c04aa95480ae70c5\", \"gpu_unittests\": \"b6a8c46a092f3fe190510f410a83c57ff4a8392c\", \"native_theme_unittests\": \"2e0418318cb8b9dc6dafa8a464bcea5f19b968ef\", \"interactive_ui_tests\": \"b61d2ac73d292d8b4c26c8877815b6cf8ac076d0\", \"mojo_unittests\": \"5a6342e7fcd23b78a8ccd9d37ec303d28d61aada\", \"url_unittests\": \"39bba0c2790ec91b5f21d275730f467980cc04a6\", \"media_perftests\": \"3ef48f8769fa85c1ec5510f5bbac2ba3ffba9a66\", \"cronet_tests\": \"e8f8417f350334e0db53b7112e955c990395fd75\", \"google_apis_unittests\": \"8fdd19f1111449762df17f9deb0f3a349533ea77\", \"telemetry_gpu_integration_test\": \"826ed2dbe0fa81af7a3fabeaf06eb205d2cd236b\", \"blink_heap_unittests\": \"24353fb89c2653d8865ad801ce4b4990bf7e743b\", \"base_unittests\": \"0b1b85678015440e0a72ed6ab082a61a1d124da9\", \"sync_integration_tests\": \"9b570f1087eac2741814105ff4473371d4784bcc\", \"sql_unittests\": \"0cfd034875754da5b8967eac0cfc09cfe8ddb120\", \"courgette_unittests\": \"8ef4c64afe7bb326492803df390ec9522b579ba5\", \"blink_fuzzer_unittests\": \"b2037b674e06c95b22ff9a17577adddbba327322\", \"gl_tests\": \"421eb919ba8bc4a2e088767ef7bbce3efb7b08e6\", \"zucchini_unittests\": \"1986fb3812c179bbaf8ae5011785ce6f8c457e6e\", \"ipc_tests\": \"8b043919dbb9d9ad3eeaa5216bf55d535a62780c\", \"angle_end2end_tests\": \"d4f5a6c8dac90f756d647261ba18c6e8bed4f9fc\", \"blink_platform_unittests\": \"26b4e47acda1f82e95761a38252e16513c9e8248\", \"components_browsertests\": \"920b943ea3d16f690cd2e0353956c5e2233d5dfb\", \"views_perftests\": \"7809326e93bd53d79f8744dd9bc10a42bd4d727c\", \"angle_perftests\": \"3899a9bffcf3b087dd2b236ecb2e2091416d8ec2\", \"display_unittests\": \"8c25fdde36ccde74970405e0f334dddcfa7b7163\", \"sbox_unittests\": \"7b5bbb2d16825cf19436e8a6d7d600a207f05723\", \"service_manager_unittests\": \"9bbb8f19ce0a4d89cdb6fd9e24d84de803c2210e\", \"pdf_unittests\": \"841d7a0c6f4c55e8ee75f34ad4a94ba52a9fbc86\", \"skia_unittests\": \"bf8da3daa4851489674c0b1fd3edf8d21167aa80\", \"angle_white_box_tests\": \"3bb1b82343406a8311f2a7d4ebf46e46765b6c0a\", \"crypto_unittests\": \"8cd641bee7ea28308259e2d3d8f238afcbfeb028\", \"nacl_loader_unittests\": \"e746750b2f68b501b6ab5bd8a4564791ce45ebf2\", \"unit_tests\": \"59399ccabf0b277c05e2a47f440a18209cc9e514\", \"content_browsertests\": \"614e2878c2895f20482d1e42bac6a4fbe4117349\", \"printing_unittests\": \"93b77b35412e3f331fec0b96b7a83e48492dcdbb\", \"device_unittests\": \"7c86376c68f443cf461e797b0e4f2254f659e58a\"}, \"path_config\": \"generic\", \"bot_id\": \"swarm2173-c4\", \"got_buildtools_revision\": \"2dff9c9c74e9d732e6fe57c84ef7fd044cc45d96\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1535954021031810",
"status": "COMPLETED",
"status_changed_ts": "1535960041744420",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/9956",
"builder:Windows deterministic",
"buildset:commit/git/2976ea78bbec3db9187f8106848776d180310807",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/2976ea78bbec3db9187f8106848776d180310807",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9101859342065000400",
"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/9956",
"swarming_tag:buildbucket_bucket:luci.chromium.ci",
"swarming_tag:buildbucket_build_id:8936429279912082032",
"swarming_tag:buildbucket_hostname:cr-buildbucket.appspot.com",
"swarming_tag:buildbucket_template_canary:0",
"swarming_tag:buildbucket_template_revision:ca2c7a741e04d0385e2bf62941ac932dc0521af3",
"swarming_tag:builder:Windows deterministic",
"swarming_tag:buildset:commit/git/2976ea78bbec3db9187f8106848776d180310807",
"swarming_tag:buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/2976ea78bbec3db9187f8106848776d180310807",
"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/8936429279912082032/+/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:9101859342065000400",
"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:b9015ab329c28cd84b28ec0e12b4612663b264bc",
"swarming_tag:user:None",
"swarming_tag:user_agent:luci-scheduler",
"swarming_tag:vpython:native-python-wrapper",
"swarming_task_id:3fb6ceaf37cbb510",
"user_agent:luci-scheduler"
],
"updated_ts": "1535960041748170",
"url": "https://ci.chromium.org/p/chromium/builders/luci.chromium.ci/Windows deterministic/9956",
"utcnow_ts": "1535960042298410"
}
}
[07:34:02.328] Invocation finished in 1h41m46.19013766s with status FAILED