[18:18:05.142] New invocation is queued and will start shortly
[18:18:06.329] Starting the invocation (attempt 1)
[18:18:06.405] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[18:18:06.405] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[18:18:06.405] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9093568095408310448",
"parameters_json": "{\"builder_name\":\"Windows deterministic\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src.git\",\"revision\":\"7e4b519f55cbd40279f0b81c446ae1ec8c91cef0\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Windows deterministic",
"scheduler_invocation_id:9093568095408310448",
"scheduler_job_id:chromium/Windows deterministic",
"user_agent:luci-scheduler",
"buildset:commit/git/7e4b519f55cbd40279f0b81c446ae1ec8c91cef0",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/7e4b519f55cbd40279f0b81c446ae1ec8c91cef0",
"gitiles_ref:refs/heads/master"
]
}
[18:18:08.091] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1543861086559910",
"id": "8928138033258634448",
"lease_expiration_ts": "1543979887755693",
"lease_key": "1668820625",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"7e4b519f55cbd40279f0b81c446ae1ec8c91cef0\"}}",
"project": "chromium",
"result_details_json": "null",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1543861087924040",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/10947",
"builder:Windows deterministic",
"buildset:commit/git/7e4b519f55cbd40279f0b81c446ae1ec8c91cef0",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/7e4b519f55cbd40279f0b81c446ae1ec8c91cef0",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9093568095408310448",
"scheduler_job_id:chromium/Windows deterministic",
"swarming_dimension:builder:Windows deterministic",
"swarming_dimension:caches:builder_e8fffaf9fac16164b1ec16ebaa1f377b792d39a582f7640830518d8ff7303d0c_v2",
"swarming_dimension:cores:8",
"swarming_dimension:cpu:x86-64",
"swarming_dimension:os:Windows-10",
"swarming_dimension:pool:luci.chromium.ci",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:build_address:luci.chromium.ci/Windows deterministic/10947",
"swarming_tag:buildbucket_bucket:chromium/ci",
"swarming_tag:buildbucket_build_id:8928138033258634448",
"swarming_tag:buildbucket_hostname:cr-buildbucket.appspot.com",
"swarming_tag:buildbucket_template_canary:0",
"swarming_tag:buildbucket_template_revision:2f19301aebbc779351dc95ab1dc03603fa761240",
"swarming_tag:builder:Windows deterministic",
"swarming_tag:buildset:commit/git/7e4b519f55cbd40279f0b81c446ae1ec8c91cef0",
"swarming_tag:buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/7e4b519f55cbd40279f0b81c446ae1ec8c91cef0",
"swarming_tag:caches:builder_e8fffaf9fac16164b1ec16ebaa1f377b792d39a582f7640830518d8ff7303d0c_v2",
"swarming_tag:cores:8",
"swarming_tag:cpu:x86-64",
"swarming_tag:gitiles_ref:refs/heads/master",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8928138033258634448/+/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:9093568095408310448",
"swarming_tag:scheduler_job_id:chromium/Windows deterministic",
"swarming_tag:service_account:chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"swarming_tag:swarming.pool.template:skip",
"swarming_tag:swarming.pool.version:730145e9e1d37101d0f6001b294c592ab5c2023e",
"swarming_tag:user:None",
"swarming_tag:user_agent:luci-scheduler",
"swarming_tag:vpython:native-python-wrapper",
"swarming_task_id:418e1c34c1575b10",
"user_agent:luci-scheduler"
],
"updated_ts": "1543861087924134",
"url": "https://ci.chromium.org/b/8928138033258634448",
"utcnow_ts": "1543861088074447"
}
}
[18:18:08.091] Task URL: https://ci.chromium.org/b/8928138033258634448
[18:18:08.091] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093568095408310448:2:0) after 1m0s
[18:19:08.110] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093568095408310448:2:0)
[18:19:08.111] Timer tick, asking Buildbucket for the build status
[18:19:08.168] Build 8928138033258634448: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:19:08.168] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093568095408310448:3:0) after 1m0s
[18:20:08.228] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093568095408310448:3:0)
[18:20:08.228] Timer tick, asking Buildbucket for the build status
[18:20:08.268] Build 8928138033258634448: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:20:08.268] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093568095408310448:4:0) after 1m0s
[18:20:21.239] Received PubSub notification, asking Buildbucket for the build status
[18:20:21.280] Build 8928138033258634448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:21:08.290] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093568095408310448:4:0)
[18:21:08.290] Timer tick, asking Buildbucket for the build status
[18:21:08.372] Build 8928138033258634448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:21:08.372] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093568095408310448:6:0) after 1m0s
[18:22:08.404] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093568095408310448:6:0)
[18:22:08.404] Timer tick, asking Buildbucket for the build status
[18:22:08.572] Build 8928138033258634448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:22:08.572] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093568095408310448:7:0) after 1m0s
[18:23:08.592] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093568095408310448:7:0)
[18:23:08.592] Timer tick, asking Buildbucket for the build status
[18:23:08.752] Build 8928138033258634448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:23:08.752] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093568095408310448:8:0) after 1m0s
[18:24:08.773] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093568095408310448:8:0)
[18:24:08.773] Timer tick, asking Buildbucket for the build status
[18:24:08.826] Build 8928138033258634448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:24:08.826] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093568095408310448:9:0) after 1m0s
[18:25:08.844] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093568095408310448:9:0)
[18:25:08.844] Timer tick, asking Buildbucket for the build status
[18:25:08.906] Build 8928138033258634448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:25:08.906] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093568095408310448:10:0) after 1m0s
[18:26:09.007] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093568095408310448:10:0)
[18:26:09.007] Timer tick, asking Buildbucket for the build status
[18:26:09.104] Build 8928138033258634448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:26:09.104] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093568095408310448:11:0) after 1m0s
[18:27:09.143] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093568095408310448:11:0)
[18:27:09.143] Timer tick, asking Buildbucket for the build status
[18:27:09.184] Build 8928138033258634448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:27:09.184] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093568095408310448:12:0) after 1m0s
[18:28:09.202] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093568095408310448:12:0)
[18:28:09.202] Timer tick, asking Buildbucket for the build status
[18:28:09.283] Build 8928138033258634448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:28:09.283] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093568095408310448:13:0) after 1m0s
[18:29:09.315] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093568095408310448:13:0)
[18:29:09.315] Timer tick, asking Buildbucket for the build status
[18:29:09.354] Build 8928138033258634448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:29:09.354] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093568095408310448:14:0) after 1m0s
[18:30:09.397] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093568095408310448:14:0)
[18:30:09.397] Timer tick, asking Buildbucket for the build status
[18:30:09.438] Build 8928138033258634448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:30:09.438] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093568095408310448:15:0) after 1m0s
[18:31:09.456] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093568095408310448:15:0)
[18:31:09.456] Timer tick, asking Buildbucket for the build status
[18:31:09.522] Build 8928138033258634448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:31:09.522] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093568095408310448:16:0) after 1m0s
[18:32:09.656] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093568095408310448:16:0)
[18:32:09.656] Timer tick, asking Buildbucket for the build status
[18:32:10.045] Build 8928138033258634448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:32:10.045] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093568095408310448:17:0) after 1m0s
[18:33:10.063] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093568095408310448:17:0)
[18:33:10.063] Timer tick, asking Buildbucket for the build status
[18:33:10.094] Build 8928138033258634448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:33:10.094] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093568095408310448:18:0) after 1m0s
[18:34:10.231] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093568095408310448:18:0)
[18:34:10.231] Timer tick, asking Buildbucket for the build status
[18:34:10.287] Build 8928138033258634448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:34:10.287] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093568095408310448:19:0) after 1m0s
[18:35:10.314] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093568095408310448:19:0)
[18:35:10.314] Timer tick, asking Buildbucket for the build status
[18:35:10.350] Build 8928138033258634448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:35:10.350] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093568095408310448:20:0) after 1m0s
[18:36:10.369] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093568095408310448:20:0)
[18:36:10.369] Timer tick, asking Buildbucket for the build status
[18:36:10.522] Build 8928138033258634448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:36:10.522] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093568095408310448:21:0) after 1m0s
[18:37:10.541] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093568095408310448:21:0)
[18:37:10.541] Timer tick, asking Buildbucket for the build status
[18:37:10.618] Build 8928138033258634448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:37:10.618] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093568095408310448:22:0) after 1m0s
[18:38:10.642] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093568095408310448:22:0)
[18:38:10.642] Timer tick, asking Buildbucket for the build status
[18:38:10.732] Build 8928138033258634448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:38:10.732] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093568095408310448:23:0) after 1m0s
[18:39:10.750] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093568095408310448:23:0)
[18:39:10.750] Timer tick, asking Buildbucket for the build status
[18:39:10.791] Build 8928138033258634448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:39:10.791] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093568095408310448:24:0) after 1m0s
[18:40:10.832] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093568095408310448:24:0)
[18:40:10.832] Timer tick, asking Buildbucket for the build status
[18:40:10.895] Build 8928138033258634448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:40:10.895] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093568095408310448:25:0) after 1m0s
[18:41:10.917] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093568095408310448:25:0)
[18:41:10.917] Timer tick, asking Buildbucket for the build status
[18:41:10.956] Build 8928138033258634448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:41:10.956] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093568095408310448:26:0) after 1m0s
[18:42:10.974] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093568095408310448:26:0)
[18:42:10.974] Timer tick, asking Buildbucket for the build status
[18:42:11.123] Build 8928138033258634448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:42:11.123] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093568095408310448:27:0) after 1m0s
[18:43:11.277] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093568095408310448:27:0)
[18:43:11.277] Timer tick, asking Buildbucket for the build status
[18:43:11.443] Build 8928138033258634448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:43:11.443] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093568095408310448:28:0) after 1m0s
[18:44:11.461] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093568095408310448:28:0)
[18:44:11.461] Timer tick, asking Buildbucket for the build status
[18:44:11.588] Build 8928138033258634448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:44:11.588] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093568095408310448:29:0) after 1m0s
[18:45:11.632] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093568095408310448:29:0)
[18:45:11.632] Timer tick, asking Buildbucket for the build status
[18:45:11.667] Build 8928138033258634448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:45:11.667] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093568095408310448:30:0) after 1m0s
[18:46:11.688] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093568095408310448:30:0)
[18:46:11.688] Timer tick, asking Buildbucket for the build status
[18:46:11.759] Build 8928138033258634448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:46:11.759] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093568095408310448:31:0) after 1m0s
[18:47:11.780] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093568095408310448:31:0)
[18:47:11.780] Timer tick, asking Buildbucket for the build status
[18:47:11.875] Build 8928138033258634448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:47:11.875] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093568095408310448:32:0) after 1m0s
[18:48:11.897] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093568095408310448:32:0)
[18:48:11.897] Timer tick, asking Buildbucket for the build status
[18:48:11.989] Build 8928138033258634448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:48:11.989] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093568095408310448:33:0) after 1m0s
[18:49:12.013] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093568095408310448:33:0)
[18:49:12.013] Timer tick, asking Buildbucket for the build status
[18:49:12.089] Build 8928138033258634448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:49:12.089] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093568095408310448:34:0) after 1m0s
[18:50:12.111] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093568095408310448:34:0)
[18:50:12.111] Timer tick, asking Buildbucket for the build status
[18:50:12.159] Build 8928138033258634448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:50:12.159] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093568095408310448:35:0) after 1m0s
[18:51:12.201] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093568095408310448:35:0)
[18:51:12.201] Timer tick, asking Buildbucket for the build status
[18:51:12.251] Build 8928138033258634448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:51:12.251] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093568095408310448:36:0) after 1m0s
[18:52:12.317] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093568095408310448:36:0)
[18:52:12.317] Timer tick, asking Buildbucket for the build status
[18:52:12.393] Build 8928138033258634448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:52:12.393] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093568095408310448:37:0) after 1m0s
[18:53:12.421] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093568095408310448:37:0)
[18:53:12.421] Timer tick, asking Buildbucket for the build status
[18:53:12.507] Build 8928138033258634448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:53:12.507] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093568095408310448:38:0) after 1m0s
[18:54:12.527] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093568095408310448:38:0)
[18:54:12.527] Timer tick, asking Buildbucket for the build status
[18:54:12.618] Build 8928138033258634448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:54:12.618] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093568095408310448:39:0) after 1m0s
[18:55:12.638] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093568095408310448:39:0)
[18:55:12.638] Timer tick, asking Buildbucket for the build status
[18:55:12.778] Build 8928138033258634448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:55:12.778] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093568095408310448:40:0) after 1m0s
[18:56:12.798] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093568095408310448:40:0)
[18:56:12.798] Timer tick, asking Buildbucket for the build status
[18:56:12.850] Build 8928138033258634448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:56:12.850] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093568095408310448:41:0) after 1m0s
[18:57:12.868] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093568095408310448:41:0)
[18:57:12.868] Timer tick, asking Buildbucket for the build status
[18:57:12.904] Build 8928138033258634448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:57:12.904] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093568095408310448:42:0) after 1m0s
[18:58:13.018] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093568095408310448:42:0)
[18:58:13.018] Timer tick, asking Buildbucket for the build status
[18:58:13.049] Build 8928138033258634448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:58:13.049] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093568095408310448:43:0) after 1m0s
[18:59:13.065] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093568095408310448:43:0)
[18:59:13.065] Timer tick, asking Buildbucket for the build status
[18:59:13.164] Build 8928138033258634448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:59:13.164] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093568095408310448:44:0) after 1m0s
[19:00:13.185] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093568095408310448:44:0)
[19:00:13.185] Timer tick, asking Buildbucket for the build status
[19:00:13.250] Build 8928138033258634448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:00:13.250] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093568095408310448:45:0) after 1m0s
[19:01:13.270] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093568095408310448:45:0)
[19:01:13.270] Timer tick, asking Buildbucket for the build status
[19:01:13.376] Build 8928138033258634448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:01:13.376] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093568095408310448:46:0) after 1m0s
[19:02:13.403] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093568095408310448:46:0)
[19:02:13.403] Timer tick, asking Buildbucket for the build status
[19:02:13.471] Build 8928138033258634448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:02:13.471] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093568095408310448:47:0) after 1m0s
[19:03:13.534] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093568095408310448:47:0)
[19:03:13.534] Timer tick, asking Buildbucket for the build status
[19:03:13.597] Build 8928138033258634448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:03:13.597] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093568095408310448:48:0) after 1m0s
[19:04:13.617] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093568095408310448:48:0)
[19:04:13.617] Timer tick, asking Buildbucket for the build status
[19:04:13.709] Build 8928138033258634448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:04:13.709] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093568095408310448:49:0) after 1m0s
[19:05:13.728] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093568095408310448:49:0)
[19:05:13.728] Timer tick, asking Buildbucket for the build status
[19:05:13.933] Build 8928138033258634448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:05:13.934] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093568095408310448:50:0) after 1m0s
[19:06:13.951] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093568095408310448:50:0)
[19:06:13.951] Timer tick, asking Buildbucket for the build status
[19:06:14.028] Build 8928138033258634448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:06:14.028] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093568095408310448:51:0) after 1m0s
[19:07:14.048] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093568095408310448:51:0)
[19:07:14.048] Timer tick, asking Buildbucket for the build status
[19:07:14.084] Build 8928138033258634448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:07:14.084] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093568095408310448:52:0) after 1m0s
[19:08:14.105] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093568095408310448:52:0)
[19:08:14.105] Timer tick, asking Buildbucket for the build status
[19:08:14.144] Build 8928138033258634448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:08:14.144] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093568095408310448:53:0) after 1m0s
[19:09:14.175] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093568095408310448:53:0)
[19:09:14.175] Timer tick, asking Buildbucket for the build status
[19:09:14.270] Build 8928138033258634448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:09:14.270] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093568095408310448:54:0) after 1m0s
[19:10:14.292] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093568095408310448:54:0)
[19:10:14.292] Timer tick, asking Buildbucket for the build status
[19:10:14.345] Build 8928138033258634448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:10:14.345] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093568095408310448:55:0) after 1m0s
[19:11:14.441] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093568095408310448:55:0)
[19:11:14.441] Timer tick, asking Buildbucket for the build status
[19:11:14.503] Build 8928138033258634448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:11:14.503] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093568095408310448:56:0) after 1m0s
[19:12:14.537] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093568095408310448:56:0)
[19:12:14.537] Timer tick, asking Buildbucket for the build status
[19:12:14.735] Build 8928138033258634448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:12:14.735] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093568095408310448:57:0) after 1m0s
[19:13:14.756] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093568095408310448:57:0)
[19:13:14.756] Timer tick, asking Buildbucket for the build status
[19:13:14.791] Build 8928138033258634448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:13:14.791] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093568095408310448:58:0) after 1m0s
[19:14:14.811] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093568095408310448:58:0)
[19:14:14.812] Timer tick, asking Buildbucket for the build status
[19:14:14.993] Build 8928138033258634448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:14:14.993] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093568095408310448:59:0) after 1m0s
[19:15:15.023] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093568095408310448:59:0)
[19:15:15.023] Timer tick, asking Buildbucket for the build status
[19:15:15.163] Build 8928138033258634448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:15:15.163] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093568095408310448:60:0) after 1m0s
[19:16:15.196] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093568095408310448:60:0)
[19:16:15.196] Timer tick, asking Buildbucket for the build status
[19:16:15.232] Build 8928138033258634448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:16:15.232] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093568095408310448:61:0) after 1m0s
[19:17:15.253] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093568095408310448:61:0)
[19:17:15.253] Timer tick, asking Buildbucket for the build status
[19:17:15.340] Build 8928138033258634448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:17:15.340] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093568095408310448:62:0) after 1m0s
[19:18:15.374] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093568095408310448:62:0)
[19:18:15.374] Timer tick, asking Buildbucket for the build status
[19:18:15.426] Build 8928138033258634448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:18:15.426] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093568095408310448:63:0) after 1m0s
[19:19:15.509] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093568095408310448:63:0)
[19:19:15.509] Timer tick, asking Buildbucket for the build status
[19:19:15.626] Build 8928138033258634448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:19:15.626] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093568095408310448:64:0) after 1m0s
[19:20:15.651] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093568095408310448:64:0)
[19:20:15.651] Timer tick, asking Buildbucket for the build status
[19:20:15.690] Build 8928138033258634448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:20:15.690] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093568095408310448:65:0) after 1m0s
[19:21:15.799] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093568095408310448:65:0)
[19:21:15.799] Timer tick, asking Buildbucket for the build status
[19:21:15.901] Build 8928138033258634448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:21:15.901] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093568095408310448:66:0) after 1m0s
[19:22:15.925] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093568095408310448:66:0)
[19:22:15.925] Timer tick, asking Buildbucket for the build status
[19:22:15.965] Build 8928138033258634448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:22:15.965] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093568095408310448:67:0) after 1m0s
[19:23:16.002] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093568095408310448:67:0)
[19:23:16.002] Timer tick, asking Buildbucket for the build status
[19:23:16.039] Build 8928138033258634448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:23:16.039] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093568095408310448:68:0) after 1m0s
[19:24:16.065] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093568095408310448:68:0)
[19:24:16.065] Timer tick, asking Buildbucket for the build status
[19:24:16.131] Build 8928138033258634448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:24:16.131] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093568095408310448:69:0) after 1m0s
[19:25:16.158] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093568095408310448:69:0)
[19:25:16.158] Timer tick, asking Buildbucket for the build status
[19:25:16.271] Build 8928138033258634448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:25:16.271] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093568095408310448:70:0) after 1m0s
[19:26:16.293] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093568095408310448:70:0)
[19:26:16.293] Timer tick, asking Buildbucket for the build status
[19:26:16.326] Build 8928138033258634448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:26:16.326] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093568095408310448:71:0) after 1m0s
[19:27:16.445] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093568095408310448:71:0)
[19:27:16.445] Timer tick, asking Buildbucket for the build status
[19:27:16.493] Build 8928138033258634448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:27:16.493] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093568095408310448:72:0) after 1m0s
[19:28:16.518] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093568095408310448:72:0)
[19:28:16.518] Timer tick, asking Buildbucket for the build status
[19:28:16.590] Build 8928138033258634448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:28:16.590] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093568095408310448:73:0) after 1m0s
[19:29:16.669] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093568095408310448:73:0)
[19:29:16.669] Timer tick, asking Buildbucket for the build status
[19:29:16.735] Build 8928138033258634448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:29:16.735] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093568095408310448:74:0) after 1m0s
[19:30:16.786] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093568095408310448:74:0)
[19:30:16.786] Timer tick, asking Buildbucket for the build status
[19:30:16.826] Build 8928138033258634448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:30:16.826] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093568095408310448:75:0) after 1m0s
[19:31:16.850] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093568095408310448:75:0)
[19:31:16.850] Timer tick, asking Buildbucket for the build status
[19:31:16.923] Build 8928138033258634448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:31:16.923] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093568095408310448:76:0) after 1m0s
[19:32:16.945] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093568095408310448:76:0)
[19:32:16.945] Timer tick, asking Buildbucket for the build status
[19:32:17.005] Build 8928138033258634448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:32:17.005] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093568095408310448:77:0) after 1m0s
[19:33:17.028] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093568095408310448:77:0)
[19:33:17.028] Timer tick, asking Buildbucket for the build status
[19:33:17.098] Build 8928138033258634448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:33:17.098] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093568095408310448:78:0) after 1m0s
[19:34:17.126] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093568095408310448:78:0)
[19:34:17.127] Timer tick, asking Buildbucket for the build status
[19:34:17.199] Build 8928138033258634448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:34:17.199] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093568095408310448:79:0) after 1m0s
[19:35:17.223] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093568095408310448:79:0)
[19:35:17.223] Timer tick, asking Buildbucket for the build status
[19:35:17.301] Build 8928138033258634448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:35:17.301] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093568095408310448:80:0) after 1m0s
[19:36:17.355] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093568095408310448:80:0)
[19:36:17.355] Timer tick, asking Buildbucket for the build status
[19:36:17.408] Build 8928138033258634448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:36:17.408] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093568095408310448:81:0) after 1m0s
[19:37:17.429] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093568095408310448:81:0)
[19:37:17.429] Timer tick, asking Buildbucket for the build status
[19:37:17.794] Build 8928138033258634448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:37:17.794] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093568095408310448:82:0) after 1m0s
[19:38:17.814] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093568095408310448:82:0)
[19:38:17.814] Timer tick, asking Buildbucket for the build status
[19:38:17.881] Build 8928138033258634448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:38:17.881] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093568095408310448:83:0) after 1m0s
[19:39:17.919] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093568095408310448:83:0)
[19:39:17.919] Timer tick, asking Buildbucket for the build status
[19:39:18.065] Build 8928138033258634448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:39:18.065] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093568095408310448:84:0) after 1m0s
[19:40:18.086] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093568095408310448:84:0)
[19:40:18.086] Timer tick, asking Buildbucket for the build status
[19:40:18.135] Build 8928138033258634448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:40:18.135] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093568095408310448:85:0) after 1m0s
[19:41:18.162] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093568095408310448:85:0)
[19:41:18.162] Timer tick, asking Buildbucket for the build status
[19:41:18.321] Build 8928138033258634448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:41:18.321] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093568095408310448:86:0) after 1m0s
[19:42:18.343] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093568095408310448:86:0)
[19:42:18.343] Timer tick, asking Buildbucket for the build status
[19:42:18.429] Build 8928138033258634448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:42:18.429] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093568095408310448:87:0) after 1m0s
[19:43:18.455] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093568095408310448:87:0)
[19:43:18.455] Timer tick, asking Buildbucket for the build status
[19:43:18.520] Build 8928138033258634448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:43:18.520] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093568095408310448:88:0) after 1m0s
[19:44:18.548] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093568095408310448:88:0)
[19:44:18.548] Timer tick, asking Buildbucket for the build status
[19:44:18.599] Build 8928138033258634448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:44:18.599] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093568095408310448:89:0) after 1m0s
[19:45:18.630] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093568095408310448:89:0)
[19:45:18.630] Timer tick, asking Buildbucket for the build status
[19:45:18.770] Build 8928138033258634448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:45:18.770] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093568095408310448:90:0) after 1m0s
[19:46:18.801] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093568095408310448:90:0)
[19:46:18.801] Timer tick, asking Buildbucket for the build status
[19:46:18.844] Build 8928138033258634448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:46:18.844] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093568095408310448:91:0) after 1m0s
[19:47:18.862] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093568095408310448:91:0)
[19:47:18.862] Timer tick, asking Buildbucket for the build status
[19:47:18.920] Build 8928138033258634448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:47:18.920] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093568095408310448:92:0) after 1m0s
[19:48:19.041] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093568095408310448:92:0)
[19:48:19.041] Timer tick, asking Buildbucket for the build status
[19:48:19.099] Build 8928138033258634448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:48:19.099] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093568095408310448:93:0) after 1m0s
[19:49:19.119] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093568095408310448:93:0)
[19:49:19.119] Timer tick, asking Buildbucket for the build status
[19:49:19.155] Build 8928138033258634448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:49:19.155] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093568095408310448:94:0) after 1m0s
[19:50:19.179] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093568095408310448:94:0)
[19:50:19.179] Timer tick, asking Buildbucket for the build status
[19:50:19.274] Build 8928138033258634448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:50:19.274] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093568095408310448:95:0) after 1m0s
[19:51:19.304] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093568095408310448:95:0)
[19:51:19.304] Timer tick, asking Buildbucket for the build status
[19:51:19.333] Build 8928138033258634448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:51:19.333] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093568095408310448:96:0) after 1m0s
[19:52:19.355] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093568095408310448:96:0)
[19:52:19.355] Timer tick, asking Buildbucket for the build status
[19:52:19.420] Build 8928138033258634448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:52:19.420] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093568095408310448:97:0) after 1m0s
[19:53:19.445] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093568095408310448:97:0)
[19:53:19.445] Timer tick, asking Buildbucket for the build status
[19:53:19.623] Build 8928138033258634448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:53:19.623] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093568095408310448:98:0) after 1m0s
[19:54:19.642] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093568095408310448:98:0)
[19:54:19.642] Timer tick, asking Buildbucket for the build status
[19:54:19.988] Build 8928138033258634448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:54:19.988] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093568095408310448:99:0) after 1m0s
[19:55:20.008] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093568095408310448:99:0)
[19:55:20.008] Timer tick, asking Buildbucket for the build status
[19:55:20.072] Build 8928138033258634448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:55:20.072] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093568095408310448:100:0) after 1m0s
[19:56:20.112] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093568095408310448:100:0)
[19:56:20.112] Timer tick, asking Buildbucket for the build status
[19:56:20.159] Build 8928138033258634448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:56:20.159] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093568095408310448:101:0) after 1m0s
[19:57:20.202] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093568095408310448:101:0)
[19:57:20.202] Timer tick, asking Buildbucket for the build status
[19:57:20.286] Build 8928138033258634448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:57:20.286] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093568095408310448:102:0) after 1m0s
[19:58:20.308] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093568095408310448:102:0)
[19:58:20.308] Timer tick, asking Buildbucket for the build status
[19:58:20.351] Build 8928138033258634448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:58:20.351] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093568095408310448:103:0) after 1m0s
[19:59:20.374] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093568095408310448:103:0)
[19:59:20.374] Timer tick, asking Buildbucket for the build status
[19:59:20.452] Build 8928138033258634448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:59:20.469] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093568095408310448:104:0) after 1m0s
[20:00:20.493] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093568095408310448:104:0)
[20:00:20.493] Timer tick, asking Buildbucket for the build status
[20:00:20.573] Build 8928138033258634448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:00:20.573] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093568095408310448:105:0) after 1m0s
[20:01:20.694] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093568095408310448:105:0)
[20:01:20.694] Timer tick, asking Buildbucket for the build status
[20:01:20.822] Build 8928138033258634448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:01:20.822] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093568095408310448:106:0) after 1m0s
[20:02:20.843] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093568095408310448:106:0)
[20:02:20.843] Timer tick, asking Buildbucket for the build status
[20:02:20.888] Build 8928138033258634448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:02:20.888] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093568095408310448:107:0) after 1m0s
[20:03:20.911] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093568095408310448:107:0)
[20:03:20.911] Timer tick, asking Buildbucket for the build status
[20:03:21.047] Build 8928138033258634448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:03:21.047] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093568095408310448:108:0) after 1m0s
[20:04:21.100] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093568095408310448:108:0)
[20:04:21.100] Timer tick, asking Buildbucket for the build status
[20:04:21.150] Build 8928138033258634448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:04:21.151] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093568095408310448:109:0) after 1m0s
[20:05:21.179] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093568095408310448:109:0)
[20:05:21.179] Timer tick, asking Buildbucket for the build status
[20:05:21.313] Build 8928138033258634448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:05:21.314] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093568095408310448:110:0) after 1m0s
[20:06:21.375] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093568095408310448:110:0)
[20:06:21.375] Timer tick, asking Buildbucket for the build status
[20:06:21.513] Build 8928138033258634448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:06:21.513] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093568095408310448:111:0) after 1m0s
[20:07:21.533] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093568095408310448:111:0)
[20:07:21.533] Timer tick, asking Buildbucket for the build status
[20:07:21.569] Build 8928138033258634448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:07:21.569] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093568095408310448:112:0) after 1m0s
[20:08:21.591] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093568095408310448:112:0)
[20:08:21.591] Timer tick, asking Buildbucket for the build status
[20:08:21.698] Build 8928138033258634448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:08:21.698] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093568095408310448:113:0) after 1m0s
[20:09:21.728] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093568095408310448:113:0)
[20:09:21.728] Timer tick, asking Buildbucket for the build status
[20:09:21.762] Build 8928138033258634448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:09:21.762] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093568095408310448:114:0) after 1m0s
[20:10:21.844] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093568095408310448:114:0)
[20:10:21.844] Timer tick, asking Buildbucket for the build status
[20:10:21.936] Build 8928138033258634448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:10:21.936] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093568095408310448:115:0) after 1m0s
[20:11:21.967] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093568095408310448:115:0)
[20:11:21.967] Timer tick, asking Buildbucket for the build status
[20:11:22.072] Build 8928138033258634448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:11:22.072] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093568095408310448:116:0) after 1m0s
[20:12:22.101] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093568095408310448:116:0)
[20:12:22.101] Timer tick, asking Buildbucket for the build status
[20:12:22.164] Build 8928138033258634448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:12:22.164] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093568095408310448:117:0) after 1m0s
[20:13:22.186] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093568095408310448:117:0)
[20:13:22.186] Timer tick, asking Buildbucket for the build status
[20:13:22.267] Build 8928138033258634448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:13:22.267] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093568095408310448:118:0) after 1m0s
[20:14:22.305] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093568095408310448:118:0)
[20:14:22.305] Timer tick, asking Buildbucket for the build status
[20:14:22.355] Build 8928138033258634448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:14:22.355] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093568095408310448:119:0) after 1m0s
[20:15:22.434] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093568095408310448:119:0)
[20:15:22.434] Timer tick, asking Buildbucket for the build status
[20:15:22.495] Build 8928138033258634448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:15:22.495] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093568095408310448:120:0) after 1m0s
[20:16:22.523] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093568095408310448:120:0)
[20:16:22.523] Timer tick, asking Buildbucket for the build status
[20:16:22.584] Build 8928138033258634448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:16:22.584] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093568095408310448:121:0) after 1m0s
[20:17:22.606] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093568095408310448:121:0)
[20:17:22.606] Timer tick, asking Buildbucket for the build status
[20:17:22.645] Build 8928138033258634448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:17:22.645] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093568095408310448:122:0) after 1m0s
[20:18:22.681] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093568095408310448:122:0)
[20:18:22.681] Timer tick, asking Buildbucket for the build status
[20:18:22.752] Build 8928138033258634448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:18:22.752] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093568095408310448:123:0) after 1m0s
[20:18:37.182] Received PubSub notification, asking Buildbucket for the build status
[20:18:37.217] Build 8928138033258634448: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[20:18:37.217] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"completed_ts": "1543868316493724",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1543861086559910",
"failure_reason": "BUILD_FAILURE",
"id": "8928138033258634448",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"7e4b519f55cbd40279f0b81c446ae1ec8c91cef0\"}}",
"project": "chromium",
"result": "FAILURE",
"result_details_json": "{\"ui\": {\"info\": \"Failure compare_build_artifacts\"}, \"swarming\": {\"bot_dimensions\": {\"gce\": [\"1\"], \"machine_type\": [\"n1-standard-8\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"], \"locale\": [\"en_US.cp1252\"], \"os\": [\"Windows\", \"Windows-10\", \"Windows-10-15063\"], \"builder\": [\"Windows deterministic\"], \"id\": [\"swarm2173-c4\"], \"python\": [\"2.7.6\"], \"server_version\": [\"3901-0f56bf6\"], \"image\": [\"chrome-win10-1703-2018-04-20-61acaf8983f\"], \"gpu\": [\"none\"], \"cores\": [\"8\"], \"integrity\": [\"high\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Haswell_GCE\"], \"pool\": [\"luci.chromium.ci\"], \"caches\": [\"builder_e8fffaf9fac16164b1ec16ebaa1f377b792d39a582f7640830518d8ff7303d0c_v2\", \"git\", \"goma_v2\", \"vpython\"]}}, \"properties\": {\"got_nacl_revision\": \"f701a90597fc85979319447c0cd44c3b52201c78\", \"got_swarming_client_revision\": \"0e3e1c4dc4e79f25a5b58fcbc135dc93183c0c54\", \"got_revision\": \"7e4b519f55cbd40279f0b81c446ae1ec8c91cef0\", \"recipe\": \"swarming/deterministic_build\", \"got_dawn_revision\": \"d758e32610cb8b8b8792570d0d0a7b44b530ff03\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#25876}\", \"$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@{#613160}\", \"branch\": \"refs/heads/master\", \"revision\": \"7e4b519f55cbd40279f0b81c446ae1ec8c91cef0\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"buildername\": \"Windows deterministic\", \"got_webrtc_revision\": \"aa3d8ad71b9d1022a94091469e2f457deb7fafc5\", \"mastername\": \"chromium.win\", \"got_angle_revision\": \"317a9ebdb019b02fda925072a5572265826676e9\", \"buildbucket\": {\"hostname\": \"cr-buildbucket.appspot.com\", \"build\": {\"created_ts\": 1543861086559910, \"tags\": [\"builder:Windows deterministic\", \"buildset:commit/git/7e4b519f55cbd40279f0b81c446ae1ec8c91cef0\", \"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/7e4b519f55cbd40279f0b81c446ae1ec8c91cef0\", \"gitiles_ref:refs/heads/master\", \"scheduler_invocation_id:9093568095408310448\", \"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\": \"8928138033258634448\"}}, \"got_v8_revision\": \"20317f4da47d33633acaec2e3a2781ddca0ea76c\", \"got_v8_revision_cp\": \"refs/heads/7.3.4@{#1}\", \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"buildnumber\": 10947, \"path_config\": \"generic\", \"bot_id\": \"swarm2173-c4\", \"got_buildtools_revision\": \"04161ec8d7c781e4498c699254c69ba0dd959fde\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1543861220464929",
"status": "COMPLETED",
"status_changed_ts": "1543868316985282",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/10947",
"builder:Windows deterministic",
"buildset:commit/git/7e4b519f55cbd40279f0b81c446ae1ec8c91cef0",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/7e4b519f55cbd40279f0b81c446ae1ec8c91cef0",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9093568095408310448",
"scheduler_job_id:chromium/Windows deterministic",
"swarming_dimension:builder:Windows deterministic",
"swarming_dimension:caches:builder_e8fffaf9fac16164b1ec16ebaa1f377b792d39a582f7640830518d8ff7303d0c_v2",
"swarming_dimension:cores:8",
"swarming_dimension:cpu:x86-64",
"swarming_dimension:os:Windows-10",
"swarming_dimension:pool:luci.chromium.ci",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:build_address:luci.chromium.ci/Windows deterministic/10947",
"swarming_tag:buildbucket_bucket:chromium/ci",
"swarming_tag:buildbucket_build_id:8928138033258634448",
"swarming_tag:buildbucket_hostname:cr-buildbucket.appspot.com",
"swarming_tag:buildbucket_template_canary:0",
"swarming_tag:buildbucket_template_revision:2f19301aebbc779351dc95ab1dc03603fa761240",
"swarming_tag:builder:Windows deterministic",
"swarming_tag:buildset:commit/git/7e4b519f55cbd40279f0b81c446ae1ec8c91cef0",
"swarming_tag:buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/7e4b519f55cbd40279f0b81c446ae1ec8c91cef0",
"swarming_tag:caches:builder_e8fffaf9fac16164b1ec16ebaa1f377b792d39a582f7640830518d8ff7303d0c_v2",
"swarming_tag:cores:8",
"swarming_tag:cpu:x86-64",
"swarming_tag:gitiles_ref:refs/heads/master",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8928138033258634448/+/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:9093568095408310448",
"swarming_tag:scheduler_job_id:chromium/Windows deterministic",
"swarming_tag:service_account:chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"swarming_tag:swarming.pool.template:skip",
"swarming_tag:swarming.pool.version:730145e9e1d37101d0f6001b294c592ab5c2023e",
"swarming_tag:user:None",
"swarming_tag:user_agent:luci-scheduler",
"swarming_tag:vpython:native-python-wrapper",
"swarming_task_id:418e1c34c1575b10",
"user_agent:luci-scheduler"
],
"updated_ts": "1543868316986191",
"url": "https://ci.chromium.org/b/8928138033258634448",
"utcnow_ts": "1543868317207977"
}
}
[20:18:37.217] Invocation finished in 2h0m32.091962894s with status FAILED