[09:03:40.122] New invocation is queued and will start shortly
[09:03:41.456] Starting the invocation (attempt 1)
[09:03:41.500] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[09:03:41.500] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[09:03:41.500] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9103387448680185664",
"parameters_json": "{\"builder_name\":\"Windows deterministic\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src.git\",\"revision\":\"223e6a75430d6df7a257ab9107d3f378e0cfaf97\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Windows deterministic",
"scheduler_invocation_id:9103387448680185664",
"scheduler_job_id:chromium/Windows deterministic",
"user_agent:luci-scheduler",
"buildset:commit/git/223e6a75430d6df7a257ab9107d3f378e0cfaf97",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/223e6a75430d6df7a257ab9107d3f378e0cfaf97",
"gitiles_ref:refs/heads/master"
]
}
[09:03:44.307] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1534496621642330",
"id": "8937957386423584320",
"lease_expiration_ts": "1534640624037080",
"lease_key": "53581566",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"223e6a75430d6df7a257ab9107d3f378e0cfaf97\"}}",
"project": "chromium",
"result_details_json": "null",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1534496624227220",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/9748",
"builder:Windows deterministic",
"buildset:commit/git/223e6a75430d6df7a257ab9107d3f378e0cfaf97",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/223e6a75430d6df7a257ab9107d3f378e0cfaf97",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9103387448680185664",
"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/9748",
"swarming_tag:buildbucket_bucket:luci.chromium.ci",
"swarming_tag:buildbucket_build_id:8937957386423584320",
"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/223e6a75430d6df7a257ab9107d3f378e0cfaf97",
"swarming_tag:buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/223e6a75430d6df7a257ab9107d3f378e0cfaf97",
"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/8937957386423584320/+/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:9103387448680185664",
"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:17bdfb7f31d73ead33616eedcfb574615cb3a2bc",
"swarming_tag:user:None",
"swarming_tag:user_agent:luci-scheduler",
"swarming_tag:vpython:native-python-wrapper",
"swarming_task_id:3f5ff1d3ff924510",
"user_agent:luci-scheduler"
],
"updated_ts": "1534496624227390",
"url": "https://ci.chromium.org/p/chromium/builders/luci.chromium.ci/Windows deterministic/9748",
"utcnow_ts": "1534496624296220"
}
}
[09:03:44.307] Task URL: https://ci.chromium.org/p/chromium/builders/luci.chromium.ci/Windows deterministic/9748
[09:03:44.307] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9103387448680185664:2:0) after 1m0s
[09:04:44.324] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9103387448680185664:2:0)
[09:04:44.324] Timer tick, asking Buildbucket for the build status
[09:04:44.531] Build 8937957386423584320: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:04:44.531] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9103387448680185664:3:0) after 1m0s
[09:05:44.555] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9103387448680185664:3:0)
[09:05:44.555] Timer tick, asking Buildbucket for the build status
[09:05:44.737] Build 8937957386423584320: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:05:44.737] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9103387448680185664:4:0) after 1m0s
[09:05:51.189] Received PubSub notification, asking Buildbucket for the build status
[09:05:51.226] Build 8937957386423584320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:06:44.759] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9103387448680185664:4:0)
[09:06:44.759] Timer tick, asking Buildbucket for the build status
[09:06:46.116] Build 8937957386423584320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:06:46.116] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9103387448680185664:6:0) after 1m0s
[09:07:46.139] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9103387448680185664:6:0)
[09:07:46.139] Timer tick, asking Buildbucket for the build status
[09:07:46.183] Build 8937957386423584320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:07:46.183] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9103387448680185664:7:0) after 1m0s
[09:08:46.229] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9103387448680185664:7:0)
[09:08:46.229] Timer tick, asking Buildbucket for the build status
[09:08:46.420] Build 8937957386423584320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:08:46.420] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9103387448680185664:8:0) after 1m0s
[09:09:46.446] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9103387448680185664:8:0)
[09:09:46.446] Timer tick, asking Buildbucket for the build status
[09:09:46.509] Build 8937957386423584320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:09:46.509] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9103387448680185664:9:0) after 1m0s
[09:10:46.588] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9103387448680185664:9:0)
[09:10:46.588] Timer tick, asking Buildbucket for the build status
[09:10:46.646] Build 8937957386423584320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:10:46.646] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9103387448680185664:10:0) after 1m0s
[09:11:46.669] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9103387448680185664:10:0)
[09:11:46.669] Timer tick, asking Buildbucket for the build status
[09:11:46.793] Build 8937957386423584320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:11:46.793] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9103387448680185664:11:0) after 1m0s
[09:12:46.815] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9103387448680185664:11:0)
[09:12:46.815] Timer tick, asking Buildbucket for the build status
[09:12:47.005] Build 8937957386423584320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:12:47.005] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9103387448680185664:12:0) after 1m0s
[09:13:47.025] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9103387448680185664:12:0)
[09:13:47.025] Timer tick, asking Buildbucket for the build status
[09:13:47.064] Build 8937957386423584320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:13:47.064] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9103387448680185664:13:0) after 1m0s
[09:14:47.170] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9103387448680185664:13:0)
[09:14:47.170] Timer tick, asking Buildbucket for the build status
[09:14:47.225] Build 8937957386423584320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:14:47.225] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9103387448680185664:14:0) after 1m0s
[09:15:47.242] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9103387448680185664:14:0)
[09:15:47.242] Timer tick, asking Buildbucket for the build status
[09:15:47.406] Build 8937957386423584320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:15:47.406] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9103387448680185664:15:0) after 1m0s
[09:16:47.438] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9103387448680185664:15:0)
[09:16:47.438] Timer tick, asking Buildbucket for the build status
[09:16:47.485] Build 8937957386423584320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:16:47.485] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9103387448680185664:16:0) after 1m0s
[09:17:47.526] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9103387448680185664:16:0)
[09:17:47.526] Timer tick, asking Buildbucket for the build status
[09:17:47.603] Build 8937957386423584320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:17:47.603] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9103387448680185664:17:0) after 1m0s
[09:18:47.637] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9103387448680185664:17:0)
[09:18:47.637] Timer tick, asking Buildbucket for the build status
[09:18:47.722] Build 8937957386423584320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:18:47.722] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9103387448680185664:18:0) after 1m0s
[09:19:47.742] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9103387448680185664:18:0)
[09:19:47.742] Timer tick, asking Buildbucket for the build status
[09:19:47.858] Build 8937957386423584320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:19:47.858] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9103387448680185664:19:0) after 1m0s
[09:20:47.889] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9103387448680185664:19:0)
[09:20:47.889] Timer tick, asking Buildbucket for the build status
[09:20:47.952] Build 8937957386423584320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:20:47.952] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9103387448680185664:20:0) after 1m0s
[09:21:47.987] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9103387448680185664:20:0)
[09:21:47.987] Timer tick, asking Buildbucket for the build status
[09:21:48.056] Build 8937957386423584320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:21:48.056] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9103387448680185664:21:0) after 1m0s
[09:22:48.076] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9103387448680185664:21:0)
[09:22:48.076] Timer tick, asking Buildbucket for the build status
[09:22:48.145] Build 8937957386423584320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:22:48.145] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9103387448680185664:22:0) after 1m0s
[09:23:48.170] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9103387448680185664:22:0)
[09:23:48.170] Timer tick, asking Buildbucket for the build status
[09:23:48.226] Build 8937957386423584320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:23:48.226] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9103387448680185664:23:0) after 1m0s
[09:24:48.279] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9103387448680185664:23:0)
[09:24:48.279] Timer tick, asking Buildbucket for the build status
[09:24:48.357] Build 8937957386423584320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:24:48.357] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9103387448680185664:24:0) after 1m0s
[09:25:48.379] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9103387448680185664:24:0)
[09:25:48.379] Timer tick, asking Buildbucket for the build status
[09:25:48.446] Build 8937957386423584320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:25:48.446] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9103387448680185664:25:0) after 1m0s
[09:26:48.469] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9103387448680185664:25:0)
[09:26:48.469] Timer tick, asking Buildbucket for the build status
[09:26:48.520] Build 8937957386423584320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:26:48.520] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9103387448680185664:26:0) after 1m0s
[09:27:48.542] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9103387448680185664:26:0)
[09:27:48.542] Timer tick, asking Buildbucket for the build status
[09:27:48.610] Build 8937957386423584320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:27:48.610] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9103387448680185664:27:0) after 1m0s
[09:28:48.640] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9103387448680185664:27:0)
[09:28:48.640] Timer tick, asking Buildbucket for the build status
[09:28:48.685] Build 8937957386423584320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:28:48.685] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9103387448680185664:28:0) after 1m0s
[09:29:48.737] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9103387448680185664:28:0)
[09:29:48.737] Timer tick, asking Buildbucket for the build status
[09:29:48.815] Build 8937957386423584320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:29:48.815] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9103387448680185664:29:0) after 1m0s
[09:30:48.898] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9103387448680185664:29:0)
[09:30:48.898] Timer tick, asking Buildbucket for the build status
[09:30:49.048] Build 8937957386423584320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:30:49.048] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9103387448680185664:30:0) after 1m0s
[09:31:49.070] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9103387448680185664:30:0)
[09:31:49.070] Timer tick, asking Buildbucket for the build status
[09:31:49.131] Build 8937957386423584320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:31:49.131] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9103387448680185664:31:0) after 1m0s
[09:32:49.203] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9103387448680185664:31:0)
[09:32:49.203] Timer tick, asking Buildbucket for the build status
[09:32:49.519] Build 8937957386423584320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:32:49.519] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9103387448680185664:32:0) after 1m0s
[09:33:49.542] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9103387448680185664:32:0)
[09:33:49.542] Timer tick, asking Buildbucket for the build status
[09:33:49.587] Build 8937957386423584320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:33:49.587] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9103387448680185664:33:0) after 1m0s
[09:34:49.623] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9103387448680185664:33:0)
[09:34:49.623] Timer tick, asking Buildbucket for the build status
[09:34:49.658] Build 8937957386423584320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:34:49.658] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9103387448680185664:34:0) after 1m0s
[09:35:49.683] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9103387448680185664:34:0)
[09:35:49.683] Timer tick, asking Buildbucket for the build status
[09:35:49.741] Build 8937957386423584320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:35:49.741] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9103387448680185664:35:0) after 1m0s
[09:36:49.768] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9103387448680185664:35:0)
[09:36:49.768] Timer tick, asking Buildbucket for the build status
[09:36:49.827] Build 8937957386423584320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:36:49.827] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9103387448680185664:36:0) after 1m0s
[09:37:49.897] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9103387448680185664:36:0)
[09:37:49.897] Timer tick, asking Buildbucket for the build status
[09:37:49.954] Build 8937957386423584320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:37:49.954] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9103387448680185664:37:0) after 1m0s
[09:38:49.977] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9103387448680185664:37:0)
[09:38:49.977] Timer tick, asking Buildbucket for the build status
[09:38:50.384] Build 8937957386423584320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:38:50.384] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9103387448680185664:38:0) after 1m0s
[09:39:50.405] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9103387448680185664:38:0)
[09:39:50.405] Timer tick, asking Buildbucket for the build status
[09:39:50.460] Build 8937957386423584320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:39:50.460] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9103387448680185664:39:0) after 1m0s
[09:40:50.512] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9103387448680185664:39:0)
[09:40:50.512] Timer tick, asking Buildbucket for the build status
[09:40:50.694] Build 8937957386423584320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:40:50.694] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9103387448680185664:40:0) after 1m0s
[09:41:50.716] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9103387448680185664:40:0)
[09:41:50.716] Timer tick, asking Buildbucket for the build status
[09:41:50.904] Build 8937957386423584320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:41:50.904] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9103387448680185664:41:0) after 1m0s
[09:42:50.925] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9103387448680185664:41:0)
[09:42:50.925] Timer tick, asking Buildbucket for the build status
[09:42:50.999] Build 8937957386423584320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:42:50.999] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9103387448680185664:42:0) after 1m0s
[09:43:51.022] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9103387448680185664:42:0)
[09:43:51.022] Timer tick, asking Buildbucket for the build status
[09:43:51.176] Build 8937957386423584320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:43:51.176] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9103387448680185664:43:0) after 1m0s
[09:44:51.197] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9103387448680185664:43:0)
[09:44:51.197] Timer tick, asking Buildbucket for the build status
[09:44:51.259] Build 8937957386423584320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:44:51.259] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9103387448680185664:44:0) after 1m0s
[09:45:51.281] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9103387448680185664:44:0)
[09:45:51.281] Timer tick, asking Buildbucket for the build status
[09:45:51.382] Build 8937957386423584320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:45:51.382] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9103387448680185664:45:0) after 1m0s
[09:46:51.409] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9103387448680185664:45:0)
[09:46:51.409] Timer tick, asking Buildbucket for the build status
[09:46:51.527] Build 8937957386423584320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:46:51.527] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9103387448680185664:46:0) after 1m0s
[09:47:51.551] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9103387448680185664:46:0)
[09:47:51.551] Timer tick, asking Buildbucket for the build status
[09:47:51.688] Build 8937957386423584320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:47:51.688] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9103387448680185664:47:0) after 1m0s
[09:48:51.714] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9103387448680185664:47:0)
[09:48:51.714] Timer tick, asking Buildbucket for the build status
[09:48:51.743] Build 8937957386423584320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:48:51.743] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9103387448680185664:48:0) after 1m0s
[09:49:51.767] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9103387448680185664:48:0)
[09:49:51.767] Timer tick, asking Buildbucket for the build status
[09:49:51.821] Build 8937957386423584320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:49:51.821] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9103387448680185664:49:0) after 1m0s
[09:50:51.892] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9103387448680185664:49:0)
[09:50:51.892] Timer tick, asking Buildbucket for the build status
[09:50:51.952] Build 8937957386423584320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:50:51.952] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9103387448680185664:50:0) after 1m0s
[09:51:52.083] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9103387448680185664:50:0)
[09:51:52.083] Timer tick, asking Buildbucket for the build status
[09:51:52.308] Build 8937957386423584320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:51:52.308] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9103387448680185664:51:0) after 1m0s
[09:52:52.340] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9103387448680185664:51:0)
[09:52:52.340] Timer tick, asking Buildbucket for the build status
[09:52:52.392] Build 8937957386423584320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:52:52.392] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9103387448680185664:52:0) after 1m0s
[09:53:52.414] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9103387448680185664:52:0)
[09:53:52.414] Timer tick, asking Buildbucket for the build status
[09:53:52.591] Build 8937957386423584320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:53:52.591] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9103387448680185664:53:0) after 1m0s
[09:54:52.619] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9103387448680185664:53:0)
[09:54:52.619] Timer tick, asking Buildbucket for the build status
[09:54:52.732] Build 8937957386423584320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:54:52.732] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9103387448680185664:54:0) after 1m0s
[09:55:52.756] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9103387448680185664:54:0)
[09:55:52.756] Timer tick, asking Buildbucket for the build status
[09:55:52.861] Build 8937957386423584320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:55:52.861] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9103387448680185664:55:0) after 1m0s
[09:56:52.880] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9103387448680185664:55:0)
[09:56:52.880] Timer tick, asking Buildbucket for the build status
[09:56:52.930] Build 8937957386423584320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:56:52.930] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9103387448680185664:56:0) after 1m0s
[09:57:52.954] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9103387448680185664:56:0)
[09:57:52.954] Timer tick, asking Buildbucket for the build status
[09:57:53.139] Build 8937957386423584320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:57:53.139] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9103387448680185664:57:0) after 1m0s
[09:58:53.166] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9103387448680185664:57:0)
[09:58:53.166] Timer tick, asking Buildbucket for the build status
[09:58:53.202] Build 8937957386423584320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:58:53.202] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9103387448680185664:58:0) after 1m0s
[09:59:53.227] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9103387448680185664:58:0)
[09:59:53.227] Timer tick, asking Buildbucket for the build status
[09:59:53.292] Build 8937957386423584320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:59:53.292] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9103387448680185664:59:0) after 1m0s
[10:00:53.313] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9103387448680185664:59:0)
[10:00:53.313] Timer tick, asking Buildbucket for the build status
[10:00:53.371] Build 8937957386423584320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:00:53.371] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9103387448680185664:60:0) after 1m0s
[10:01:53.397] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9103387448680185664:60:0)
[10:01:53.397] Timer tick, asking Buildbucket for the build status
[10:01:53.460] Build 8937957386423584320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:01:53.460] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9103387448680185664:61:0) after 1m0s
[10:02:53.482] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9103387448680185664:61:0)
[10:02:53.482] Timer tick, asking Buildbucket for the build status
[10:02:53.590] Build 8937957386423584320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:02:53.590] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9103387448680185664:62:0) after 1m0s
[10:03:53.608] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9103387448680185664:62:0)
[10:03:53.608] Timer tick, asking Buildbucket for the build status
[10:03:53.669] Build 8937957386423584320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:03:53.669] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9103387448680185664:63:0) after 1m0s
[10:04:53.692] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9103387448680185664:63:0)
[10:04:53.692] Timer tick, asking Buildbucket for the build status
[10:04:53.851] Build 8937957386423584320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:04:53.851] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9103387448680185664:64:0) after 1m0s
[10:05:53.872] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9103387448680185664:64:0)
[10:05:53.872] Timer tick, asking Buildbucket for the build status
[10:05:53.936] Build 8937957386423584320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:05:53.936] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9103387448680185664:65:0) after 1m0s
[10:06:53.959] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9103387448680185664:65:0)
[10:06:53.959] Timer tick, asking Buildbucket for the build status
[10:06:54.083] Build 8937957386423584320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:06:54.083] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9103387448680185664:66:0) after 1m0s
[10:07:54.109] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9103387448680185664:66:0)
[10:07:54.109] Timer tick, asking Buildbucket for the build status
[10:07:54.144] Build 8937957386423584320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:07:54.144] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9103387448680185664:67:0) after 1m0s
[10:08:54.252] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9103387448680185664:67:0)
[10:08:54.252] Timer tick, asking Buildbucket for the build status
[10:08:54.308] Build 8937957386423584320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:08:54.308] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9103387448680185664:68:0) after 1m0s
[10:09:54.330] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9103387448680185664:68:0)
[10:09:54.330] Timer tick, asking Buildbucket for the build status
[10:09:54.447] Build 8937957386423584320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:09:54.448] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9103387448680185664:69:0) after 1m0s
[10:10:54.493] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9103387448680185664:69:0)
[10:10:54.493] Timer tick, asking Buildbucket for the build status
[10:10:54.604] Build 8937957386423584320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:10:54.604] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9103387448680185664:70:0) after 1m0s
[10:11:54.626] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9103387448680185664:70:0)
[10:11:54.626] Timer tick, asking Buildbucket for the build status
[10:11:54.739] Build 8937957386423584320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:11:54.739] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9103387448680185664:71:0) after 1m0s
[10:12:54.762] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9103387448680185664:71:0)
[10:12:54.762] Timer tick, asking Buildbucket for the build status
[10:12:54.800] Build 8937957386423584320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:12:54.800] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9103387448680185664:72:0) after 1m0s
[10:13:54.821] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9103387448680185664:72:0)
[10:13:54.821] Timer tick, asking Buildbucket for the build status
[10:13:54.862] Build 8937957386423584320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:13:54.862] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9103387448680185664:73:0) after 1m0s
[10:14:54.884] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9103387448680185664:73:0)
[10:14:54.884] Timer tick, asking Buildbucket for the build status
[10:14:55.023] Build 8937957386423584320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:14:55.023] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9103387448680185664:74:0) after 1m0s
[10:15:55.045] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9103387448680185664:74:0)
[10:15:55.045] Timer tick, asking Buildbucket for the build status
[10:15:55.128] Build 8937957386423584320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:15:55.128] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9103387448680185664:75:0) after 1m0s
[10:16:55.149] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9103387448680185664:75:0)
[10:16:55.149] Timer tick, asking Buildbucket for the build status
[10:16:55.211] Build 8937957386423584320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:16:55.211] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9103387448680185664:76:0) after 1m0s
[10:17:55.232] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9103387448680185664:76:0)
[10:17:55.232] Timer tick, asking Buildbucket for the build status
[10:17:55.294] Build 8937957386423584320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:17:55.294] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9103387448680185664:77:0) after 1m0s
[10:18:55.329] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9103387448680185664:77:0)
[10:18:55.329] Timer tick, asking Buildbucket for the build status
[10:18:55.406] Build 8937957386423584320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:18:55.406] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9103387448680185664:78:0) after 1m0s
[10:19:55.427] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9103387448680185664:78:0)
[10:19:55.427] Timer tick, asking Buildbucket for the build status
[10:19:55.627] Build 8937957386423584320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:19:55.627] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9103387448680185664:79:0) after 1m0s
[10:20:55.650] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9103387448680185664:79:0)
[10:20:55.650] Timer tick, asking Buildbucket for the build status
[10:20:55.690] Build 8937957386423584320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:20:55.690] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9103387448680185664:80:0) after 1m0s
[10:21:55.713] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9103387448680185664:80:0)
[10:21:55.713] Timer tick, asking Buildbucket for the build status
[10:21:55.772] Build 8937957386423584320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:21:55.772] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9103387448680185664:81:0) after 1m0s
[10:22:55.795] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9103387448680185664:81:0)
[10:22:55.795] Timer tick, asking Buildbucket for the build status
[10:22:55.860] Build 8937957386423584320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:22:55.860] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9103387448680185664:82:0) after 1m0s
[10:23:55.883] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9103387448680185664:82:0)
[10:23:55.883] Timer tick, asking Buildbucket for the build status
[10:23:56.001] Build 8937957386423584320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:23:56.001] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9103387448680185664:83:0) after 1m0s
[10:24:56.036] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9103387448680185664:83:0)
[10:24:56.036] Timer tick, asking Buildbucket for the build status
[10:24:56.095] Build 8937957386423584320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:24:56.095] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9103387448680185664:84:0) after 1m0s
[10:25:56.132] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9103387448680185664:84:0)
[10:25:56.132] Timer tick, asking Buildbucket for the build status
[10:25:56.185] Build 8937957386423584320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:25:56.185] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9103387448680185664:85:0) after 1m0s
[10:26:56.205] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9103387448680185664:85:0)
[10:26:56.205] Timer tick, asking Buildbucket for the build status
[10:26:56.268] Build 8937957386423584320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:26:56.268] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9103387448680185664:86:0) after 1m0s
[10:27:56.292] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9103387448680185664:86:0)
[10:27:56.292] Timer tick, asking Buildbucket for the build status
[10:27:56.322] Build 8937957386423584320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:27:56.322] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9103387448680185664:87:0) after 1m0s
[10:28:56.342] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9103387448680185664:87:0)
[10:28:56.342] Timer tick, asking Buildbucket for the build status
[10:28:56.382] Build 8937957386423584320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:28:56.382] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9103387448680185664:88:0) after 1m0s
[10:29:56.410] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9103387448680185664:88:0)
[10:29:56.410] Timer tick, asking Buildbucket for the build status
[10:29:56.464] Build 8937957386423584320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:29:56.464] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9103387448680185664:89:0) after 1m0s
[10:30:56.498] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9103387448680185664:89:0)
[10:30:56.498] Timer tick, asking Buildbucket for the build status
[10:30:56.537] Build 8937957386423584320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:30:56.537] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9103387448680185664:90:0) after 1m0s
[10:31:56.697] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9103387448680185664:90:0)
[10:31:56.697] Timer tick, asking Buildbucket for the build status
[10:31:56.795] Build 8937957386423584320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:31:56.795] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9103387448680185664:91:0) after 1m0s
[10:32:56.815] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9103387448680185664:91:0)
[10:32:56.815] Timer tick, asking Buildbucket for the build status
[10:32:56.944] Build 8937957386423584320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:32:56.944] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9103387448680185664:92:0) after 1m0s
[10:33:56.965] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9103387448680185664:92:0)
[10:33:56.965] Timer tick, asking Buildbucket for the build status
[10:33:57.029] Build 8937957386423584320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:33:57.029] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9103387448680185664:93:0) after 1m0s
[10:34:57.213] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9103387448680185664:93:0)
[10:34:57.213] Timer tick, asking Buildbucket for the build status
[10:34:57.622] Build 8937957386423584320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:34:57.622] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9103387448680185664:94:0) after 1m0s
[10:35:57.645] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9103387448680185664:94:0)
[10:35:57.645] Timer tick, asking Buildbucket for the build status
[10:35:57.700] Build 8937957386423584320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:35:57.700] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9103387448680185664:95:0) after 1m0s
[10:36:57.721] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9103387448680185664:95:0)
[10:36:57.721] Timer tick, asking Buildbucket for the build status
[10:36:57.757] Build 8937957386423584320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:36:57.757] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9103387448680185664:96:0) after 1m0s
[10:37:57.778] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9103387448680185664:96:0)
[10:37:57.778] Timer tick, asking Buildbucket for the build status
[10:37:57.858] Build 8937957386423584320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:37:57.858] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9103387448680185664:97:0) after 1m0s
[10:38:57.960] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9103387448680185664:97:0)
[10:38:57.960] Timer tick, asking Buildbucket for the build status
[10:38:57.996] Build 8937957386423584320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:38:57.996] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9103387448680185664:98:0) after 1m0s
[10:39:58.027] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9103387448680185664:98:0)
[10:39:58.027] Timer tick, asking Buildbucket for the build status
[10:39:58.209] Build 8937957386423584320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:39:58.209] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9103387448680185664:99:0) after 1m0s
[10:40:58.342] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9103387448680185664:99:0)
[10:40:58.342] Timer tick, asking Buildbucket for the build status
[10:40:58.471] Build 8937957386423584320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:40:58.471] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9103387448680185664:100:0) after 1m0s
[10:41:58.516] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9103387448680185664:100:0)
[10:41:58.516] Timer tick, asking Buildbucket for the build status
[10:41:58.609] Build 8937957386423584320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:41:58.609] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9103387448680185664:101:0) after 1m0s
[10:42:58.724] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9103387448680185664:101:0)
[10:42:58.724] Timer tick, asking Buildbucket for the build status
[10:42:58.758] Build 8937957386423584320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:42:58.758] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9103387448680185664:102:0) after 1m0s
[10:43:58.869] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9103387448680185664:102:0)
[10:43:58.869] Timer tick, asking Buildbucket for the build status
[10:43:58.970] Build 8937957386423584320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:43:58.970] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9103387448680185664:103:0) after 1m0s
[10:44:58.993] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9103387448680185664:103:0)
[10:44:58.993] Timer tick, asking Buildbucket for the build status
[10:44:59.115] Build 8937957386423584320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:44:59.115] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9103387448680185664:104:0) after 1m0s
[10:45:59.202] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9103387448680185664:104:0)
[10:45:59.202] Timer tick, asking Buildbucket for the build status
[10:45:59.234] Build 8937957386423584320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:45:59.234] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9103387448680185664:105:0) after 1m0s
[10:46:59.256] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9103387448680185664:105:0)
[10:46:59.256] Timer tick, asking Buildbucket for the build status
[10:46:59.307] Build 8937957386423584320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:46:59.307] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9103387448680185664:106:0) after 1m0s
[10:47:59.330] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9103387448680185664:106:0)
[10:47:59.330] Timer tick, asking Buildbucket for the build status
[10:47:59.383] Build 8937957386423584320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:47:59.383] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9103387448680185664:107:0) after 1m0s
[10:48:59.508] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9103387448680185664:107:0)
[10:48:59.508] Timer tick, asking Buildbucket for the build status
[10:48:59.743] Build 8937957386423584320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:48:59.743] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9103387448680185664:108:0) after 1m0s
[10:49:59.766] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9103387448680185664:108:0)
[10:49:59.766] Timer tick, asking Buildbucket for the build status
[10:49:59.875] Build 8937957386423584320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:49:59.875] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9103387448680185664:109:0) after 1m0s
[10:50:59.897] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9103387448680185664:109:0)
[10:50:59.897] Timer tick, asking Buildbucket for the build status
[10:51:00.082] Build 8937957386423584320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:51:00.082] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9103387448680185664:110:0) after 1m0s
[10:52:00.110] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9103387448680185664:110:0)
[10:52:00.110] Timer tick, asking Buildbucket for the build status
[10:52:00.252] Build 8937957386423584320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:52:00.252] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9103387448680185664:111:0) after 1m0s
[10:53:00.361] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9103387448680185664:111:0)
[10:53:00.361] Timer tick, asking Buildbucket for the build status
[10:53:00.410] Build 8937957386423584320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:53:00.410] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9103387448680185664:112:0) after 1m0s
[10:54:00.495] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9103387448680185664:112:0)
[10:54:00.495] Timer tick, asking Buildbucket for the build status
[10:54:00.613] Build 8937957386423584320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:54:00.613] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9103387448680185664:113:0) after 1m0s
[10:55:00.698] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9103387448680185664:113:0)
[10:55:00.698] Timer tick, asking Buildbucket for the build status
[10:55:00.835] Build 8937957386423584320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:55:00.835] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9103387448680185664:114:0) after 1m0s
[10:56:00.861] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9103387448680185664:114:0)
[10:56:00.861] Timer tick, asking Buildbucket for the build status
[10:56:00.941] Build 8937957386423584320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:56:00.941] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9103387448680185664:115:0) after 1m0s
[10:57:00.964] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9103387448680185664:115:0)
[10:57:00.964] Timer tick, asking Buildbucket for the build status
[10:57:01.045] Build 8937957386423584320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:57:01.045] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9103387448680185664:116:0) after 1m0s
[10:58:01.065] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9103387448680185664:116:0)
[10:58:01.065] Timer tick, asking Buildbucket for the build status
[10:58:01.204] Build 8937957386423584320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:58:01.204] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9103387448680185664:117:0) after 1m0s
[10:58:22.367] Received PubSub notification, asking Buildbucket for the build status
[10:58:22.582] Build 8937957386423584320: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[10:58:22.582] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"completed_ts": "1534503501341520",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1534496621642330",
"failure_reason": "BUILD_FAILURE",
"id": "8937957386423584320",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"223e6a75430d6df7a257ab9107d3f378e0cfaf97\"}}",
"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/8937957386423584320/+/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\": \"6e5fd1672be57aafb05bf0a1f1bbdc9107e0c58b\", \"got_swarming_client_revision\": \"486c9b53c4d54dd4b95bb6ce0e31160e600dfc11\", \"got_revision\": \"223e6a75430d6df7a257ab9107d3f378e0cfaf97\", \"recipe\": \"swarming/deterministic_build\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#24321}\", \"$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@{#583996}\", \"branch\": \"refs/heads/master\", \"revision\": \"223e6a75430d6df7a257ab9107d3f378e0cfaf97\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"buildername\": \"Windows deterministic\", \"got_webrtc_revision\": \"984f1a80c00105b9c9e19765fb6118d49ce9f34e\", \"mastername\": \"chromium.fyi\", \"got_angle_revision\": \"3394a73db973081e6ab86ba51c6eccc7ee9826d0\", \"buildbucket\": {\"hostname\": \"cr-buildbucket.appspot.com\", \"build\": {\"created_ts\": 1534496621642330, \"tags\": [\"builder:Windows deterministic\", \"buildset:commit/git/223e6a75430d6df7a257ab9107d3f378e0cfaf97\", \"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/223e6a75430d6df7a257ab9107d3f378e0cfaf97\", \"gitiles_ref:refs/heads/master\", \"scheduler_invocation_id:9103387448680185664\", \"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\": \"8937957386423584320\"}}, \"got_v8_revision\": \"091d6f21ab1719f42c4ac763d89d5d754f07046c\", \"got_v8_revision_cp\": \"refs/heads/7.0.236@{#1}\", \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"buildnumber\": 9748, \"path_config\": \"generic\", \"bot_id\": \"swarm2173-c4\", \"got_buildtools_revision\": \"2dff9c9c74e9d732e6fe57c84ef7fd044cc45d96\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1534496750459510",
"status": "COMPLETED",
"status_changed_ts": "1534503501935030",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/9748",
"builder:Windows deterministic",
"buildset:commit/git/223e6a75430d6df7a257ab9107d3f378e0cfaf97",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/223e6a75430d6df7a257ab9107d3f378e0cfaf97",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9103387448680185664",
"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/9748",
"swarming_tag:buildbucket_bucket:luci.chromium.ci",
"swarming_tag:buildbucket_build_id:8937957386423584320",
"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/223e6a75430d6df7a257ab9107d3f378e0cfaf97",
"swarming_tag:buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/223e6a75430d6df7a257ab9107d3f378e0cfaf97",
"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/8937957386423584320/+/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:9103387448680185664",
"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:17bdfb7f31d73ead33616eedcfb574615cb3a2bc",
"swarming_tag:user:None",
"swarming_tag:user_agent:luci-scheduler",
"swarming_tag:vpython:native-python-wrapper",
"swarming_task_id:3f5ff1d3ff924510",
"user_agent:luci-scheduler"
],
"updated_ts": "1534503501937360",
"url": "https://ci.chromium.org/p/chromium/builders/luci.chromium.ci/Windows deterministic/9748",
"utcnow_ts": "1534503502475090"
}
}
[10:58:22.582] Invocation finished in 1h54m42.476202317s with status FAILED