[08:05:08.177] New invocation is queued and will start shortly
[08:05:09.387] Starting the invocation (attempt 1)
[08:05:09.423] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[08:05:09.423] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[08:05:09.423] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9086993080304122992",
"parameters_json": "{\"builder_name\":\"Windows deterministic\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src.git\",\"revision\":\"6c7bf9286338957ec33f3a83635bf370e6384e6b\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Windows deterministic",
"scheduler_invocation_id:9086993080304122992",
"scheduler_job_id:chromium/Windows deterministic",
"user_agent:luci-scheduler",
"buildset:commit/git/6c7bf9286338957ec33f3a83635bf370e6384e6b",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/6c7bf9286338957ec33f3a83635bf370e6384e6b",
"gitiles_ref:refs/heads/master"
]
}
[08:05:11.555] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1550131509753539",
"id": "8921563017987862816",
"lease_expiration_ts": "1550250311107121",
"lease_key": "425096439",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"6c7bf9286338957ec33f3a83635bf370e6384e6b\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1550131511286874",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/11963",
"builder:Windows deterministic",
"buildset:commit/git/6c7bf9286338957ec33f3a83635bf370e6384e6b",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/6c7bf9286338957ec33f3a83635bf370e6384e6b",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9086993080304122992",
"scheduler_job_id:chromium/Windows deterministic",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8921563017987862816/+/annotations",
"swarming_tag:luci_project:chromium",
"swarming_tag:recipe_name:swarming/deterministic_build",
"swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build",
"swarming_task_id:4303db466a40c010",
"user_agent:luci-scheduler"
],
"updated_ts": "1550131511287063",
"url": "https://ci.chromium.org/b/8921563017987862816",
"utcnow_ts": "1550131511534346"
}
}
[08:05:11.555] Task URL: https://ci.chromium.org/b/8921563017987862816
[08:05:11.555] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086993080304122992:2:0) after 1m0s
[08:05:15.314] Received PubSub notification, asking Buildbucket for the build status
[08:05:15.405] Build 8921563017987862816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:06:11.571] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086993080304122992:2:0)
[08:06:11.571] Timer tick, asking Buildbucket for the build status
[08:06:11.680] Build 8921563017987862816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:06:11.680] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086993080304122992:4:0) after 1m0s
[08:07:11.767] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086993080304122992:4:0)
[08:07:11.767] Timer tick, asking Buildbucket for the build status
[08:07:11.874] Build 8921563017987862816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:07:11.874] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086993080304122992:5:0) after 1m0s
[08:08:11.893] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086993080304122992:5:0)
[08:08:11.893] Timer tick, asking Buildbucket for the build status
[08:08:11.965] Build 8921563017987862816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:08:11.965] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086993080304122992:6:0) after 1m0s
[08:09:11.978] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086993080304122992:6:0)
[08:09:11.978] Timer tick, asking Buildbucket for the build status
[08:09:12.094] Build 8921563017987862816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:09:12.094] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086993080304122992:7:0) after 1m0s
[08:10:12.194] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086993080304122992:7:0)
[08:10:12.194] Timer tick, asking Buildbucket for the build status
[08:10:12.696] Build 8921563017987862816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:10:12.696] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086993080304122992:8:0) after 1m0s
[08:11:12.716] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086993080304122992:8:0)
[08:11:12.716] Timer tick, asking Buildbucket for the build status
[08:11:12.763] Build 8921563017987862816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:11:12.763] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086993080304122992:9:0) after 1m0s
[08:12:12.781] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086993080304122992:9:0)
[08:12:12.781] Timer tick, asking Buildbucket for the build status
[08:12:12.875] Build 8921563017987862816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:12:12.875] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086993080304122992:10:0) after 1m0s
[08:13:12.891] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086993080304122992:10:0)
[08:13:12.891] Timer tick, asking Buildbucket for the build status
[08:13:12.940] Build 8921563017987862816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:13:12.940] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086993080304122992:11:0) after 1m0s
[08:14:12.959] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086993080304122992:11:0)
[08:14:12.959] Timer tick, asking Buildbucket for the build status
[08:14:13.002] Build 8921563017987862816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:14:13.002] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086993080304122992:12:0) after 1m0s
[08:15:13.023] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086993080304122992:12:0)
[08:15:13.023] Timer tick, asking Buildbucket for the build status
[08:15:13.081] Build 8921563017987862816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:15:13.081] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086993080304122992:13:0) after 1m0s
[08:16:13.107] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086993080304122992:13:0)
[08:16:13.107] Timer tick, asking Buildbucket for the build status
[08:16:13.163] Build 8921563017987862816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:16:13.163] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086993080304122992:14:0) after 1m0s
[08:17:13.184] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086993080304122992:14:0)
[08:17:13.184] Timer tick, asking Buildbucket for the build status
[08:17:13.317] Build 8921563017987862816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:17:13.317] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086993080304122992:15:0) after 1m0s
[08:18:13.334] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086993080304122992:15:0)
[08:18:13.334] Timer tick, asking Buildbucket for the build status
[08:18:13.576] Build 8921563017987862816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:18:13.576] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086993080304122992:16:0) after 1m0s
[08:19:13.613] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086993080304122992:16:0)
[08:19:13.613] Timer tick, asking Buildbucket for the build status
[08:19:13.928] Build 8921563017987862816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:19:13.928] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086993080304122992:17:0) after 1m0s
[08:20:13.956] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086993080304122992:17:0)
[08:20:13.956] Timer tick, asking Buildbucket for the build status
[08:20:14.164] Build 8921563017987862816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:20:14.164] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086993080304122992:18:0) after 1m0s
[08:21:14.216] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086993080304122992:18:0)
[08:21:14.216] Timer tick, asking Buildbucket for the build status
[08:21:14.282] Build 8921563017987862816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:21:14.282] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086993080304122992:19:0) after 1m0s
[08:22:14.301] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086993080304122992:19:0)
[08:22:14.301] Timer tick, asking Buildbucket for the build status
[08:22:14.471] Build 8921563017987862816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:22:14.471] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086993080304122992:20:0) after 1m0s
[08:23:14.493] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086993080304122992:20:0)
[08:23:14.493] Timer tick, asking Buildbucket for the build status
[08:23:14.606] Build 8921563017987862816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:23:14.606] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086993080304122992:21:0) after 1m0s
[08:24:14.628] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086993080304122992:21:0)
[08:24:14.628] Timer tick, asking Buildbucket for the build status
[08:24:14.771] Build 8921563017987862816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:24:14.771] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086993080304122992:22:0) after 1m0s
[08:25:14.820] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086993080304122992:22:0)
[08:25:14.820] Timer tick, asking Buildbucket for the build status
[08:25:14.912] Build 8921563017987862816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:25:14.912] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086993080304122992:23:0) after 1m0s
[08:26:14.931] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086993080304122992:23:0)
[08:26:14.931] Timer tick, asking Buildbucket for the build status
[08:26:15.069] Build 8921563017987862816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:26:15.069] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086993080304122992:24:0) after 1m0s
[08:27:15.086] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086993080304122992:24:0)
[08:27:15.086] Timer tick, asking Buildbucket for the build status
[08:27:15.136] Build 8921563017987862816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:27:15.136] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086993080304122992:25:0) after 1m0s
[08:28:15.157] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086993080304122992:25:0)
[08:28:15.157] Timer tick, asking Buildbucket for the build status
[08:28:15.223] Build 8921563017987862816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:28:15.223] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086993080304122992:26:0) after 1m0s
[08:29:15.300] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086993080304122992:26:0)
[08:29:15.300] Timer tick, asking Buildbucket for the build status
[08:29:15.425] Build 8921563017987862816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:29:15.425] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086993080304122992:27:0) after 1m0s
[08:30:15.589] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086993080304122992:27:0)
[08:30:15.589] Timer tick, asking Buildbucket for the build status
[08:30:15.681] Build 8921563017987862816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:30:15.681] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086993080304122992:28:0) after 1m0s
[08:31:15.795] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086993080304122992:28:0)
[08:31:15.795] Timer tick, asking Buildbucket for the build status
[08:31:15.925] Build 8921563017987862816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:31:15.925] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086993080304122992:29:0) after 1m0s
[08:32:15.955] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086993080304122992:29:0)
[08:32:15.955] Timer tick, asking Buildbucket for the build status
[08:32:16.055] Build 8921563017987862816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:32:16.055] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086993080304122992:30:0) after 1m0s
[08:33:16.095] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086993080304122992:30:0)
[08:33:16.095] Timer tick, asking Buildbucket for the build status
[08:33:16.202] Build 8921563017987862816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:33:16.202] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086993080304122992:31:0) after 1m0s
[08:34:16.324] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086993080304122992:31:0)
[08:34:16.324] Timer tick, asking Buildbucket for the build status
[08:34:16.425] Build 8921563017987862816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:34:16.425] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086993080304122992:32:0) after 1m0s
[08:35:16.470] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086993080304122992:32:0)
[08:35:16.470] Timer tick, asking Buildbucket for the build status
[08:35:16.587] Build 8921563017987862816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:35:16.587] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086993080304122992:33:0) after 1m0s
[08:36:16.611] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086993080304122992:33:0)
[08:36:16.611] Timer tick, asking Buildbucket for the build status
[08:36:17.340] Build 8921563017987862816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:36:17.340] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086993080304122992:34:0) after 1m0s
[08:37:17.386] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086993080304122992:34:0)
[08:37:17.386] Timer tick, asking Buildbucket for the build status
[08:37:17.453] Build 8921563017987862816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:37:17.453] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086993080304122992:35:0) after 1m0s
[08:38:17.474] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086993080304122992:35:0)
[08:38:17.474] Timer tick, asking Buildbucket for the build status
[08:38:17.544] Build 8921563017987862816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:38:17.544] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086993080304122992:36:0) after 1m0s
[08:39:17.582] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086993080304122992:36:0)
[08:39:17.582] Timer tick, asking Buildbucket for the build status
[08:39:17.730] Build 8921563017987862816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:39:17.730] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086993080304122992:37:0) after 1m0s
[08:40:17.751] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086993080304122992:37:0)
[08:40:17.751] Timer tick, asking Buildbucket for the build status
[08:40:17.912] Build 8921563017987862816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:40:17.912] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086993080304122992:38:0) after 1m0s
[08:41:17.939] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086993080304122992:38:0)
[08:41:17.939] Timer tick, asking Buildbucket for the build status
[08:41:18.088] Build 8921563017987862816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:41:18.088] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086993080304122992:39:0) after 1m0s
[08:42:18.109] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086993080304122992:39:0)
[08:42:18.109] Timer tick, asking Buildbucket for the build status
[08:42:18.194] Build 8921563017987862816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:42:18.194] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086993080304122992:40:0) after 1m0s
[08:43:18.212] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086993080304122992:40:0)
[08:43:18.212] Timer tick, asking Buildbucket for the build status
[08:43:18.266] Build 8921563017987862816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:43:18.266] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086993080304122992:41:0) after 1m0s
[08:44:18.283] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086993080304122992:41:0)
[08:44:18.283] Timer tick, asking Buildbucket for the build status
[08:44:18.484] Build 8921563017987862816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:44:18.484] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086993080304122992:42:0) after 1m0s
[08:45:18.507] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086993080304122992:42:0)
[08:45:18.507] Timer tick, asking Buildbucket for the build status
[08:45:18.689] Build 8921563017987862816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:45:18.689] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086993080304122992:43:0) after 1m0s
[08:46:18.707] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086993080304122992:43:0)
[08:46:18.707] Timer tick, asking Buildbucket for the build status
[08:46:18.779] Build 8921563017987862816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:46:18.779] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086993080304122992:44:0) after 1m0s
[08:47:18.880] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086993080304122992:44:0)
[08:47:18.880] Timer tick, asking Buildbucket for the build status
[08:47:18.933] Build 8921563017987862816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:47:18.933] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086993080304122992:45:0) after 1m0s
[08:48:18.955] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086993080304122992:45:0)
[08:48:18.956] Timer tick, asking Buildbucket for the build status
[08:48:19.177] Build 8921563017987862816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:48:19.177] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086993080304122992:46:0) after 1m0s
[08:49:19.202] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086993080304122992:46:0)
[08:49:19.202] Timer tick, asking Buildbucket for the build status
[08:49:19.269] Build 8921563017987862816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:49:19.269] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086993080304122992:47:0) after 1m0s
[08:50:19.355] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086993080304122992:47:0)
[08:50:19.355] Timer tick, asking Buildbucket for the build status
[08:50:19.499] Build 8921563017987862816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:50:19.499] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086993080304122992:48:0) after 1m0s
[08:51:19.519] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086993080304122992:48:0)
[08:51:19.519] Timer tick, asking Buildbucket for the build status
[08:51:19.789] Build 8921563017987862816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:51:19.789] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086993080304122992:49:0) after 1m0s
[08:52:19.824] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086993080304122992:49:0)
[08:52:19.824] Timer tick, asking Buildbucket for the build status
[08:52:19.988] Build 8921563017987862816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:52:19.988] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086993080304122992:50:0) after 1m0s
[08:53:20.008] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086993080304122992:50:0)
[08:53:20.008] Timer tick, asking Buildbucket for the build status
[08:53:20.063] Build 8921563017987862816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:53:20.063] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086993080304122992:51:0) after 1m0s
[08:54:20.082] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086993080304122992:51:0)
[08:54:20.082] Timer tick, asking Buildbucket for the build status
[08:54:20.190] Build 8921563017987862816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:54:20.190] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086993080304122992:52:0) after 1m0s
[08:55:20.214] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086993080304122992:52:0)
[08:55:20.214] Timer tick, asking Buildbucket for the build status
[08:55:20.282] Build 8921563017987862816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:55:20.282] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086993080304122992:53:0) after 1m0s
[08:56:20.334] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086993080304122992:53:0)
[08:56:20.334] Timer tick, asking Buildbucket for the build status
[08:56:20.393] Build 8921563017987862816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:56:20.393] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086993080304122992:54:0) after 1m0s
[08:57:20.411] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086993080304122992:54:0)
[08:57:20.411] Timer tick, asking Buildbucket for the build status
[08:57:20.643] Build 8921563017987862816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:57:20.643] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086993080304122992:55:0) after 1m0s
[08:58:20.670] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086993080304122992:55:0)
[08:58:20.670] Timer tick, asking Buildbucket for the build status
[08:58:20.814] Build 8921563017987862816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:58:20.814] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086993080304122992:56:0) after 1m0s
[08:59:20.839] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086993080304122992:56:0)
[08:59:20.839] Timer tick, asking Buildbucket for the build status
[08:59:20.927] Build 8921563017987862816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:59:20.927] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086993080304122992:57:0) after 1m0s
[09:00:20.946] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086993080304122992:57:0)
[09:00:20.946] Timer tick, asking Buildbucket for the build status
[09:00:21.047] Build 8921563017987862816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:00:21.048] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086993080304122992:58:0) after 1m0s
[09:01:21.069] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086993080304122992:58:0)
[09:01:21.069] Timer tick, asking Buildbucket for the build status
[09:01:21.187] Build 8921563017987862816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:01:21.187] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086993080304122992:59:0) after 1m0s
[09:02:21.214] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086993080304122992:59:0)
[09:02:21.214] Timer tick, asking Buildbucket for the build status
[09:02:21.382] Build 8921563017987862816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:02:21.382] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086993080304122992:60:0) after 1m0s
[09:03:21.409] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086993080304122992:60:0)
[09:03:21.409] Timer tick, asking Buildbucket for the build status
[09:03:21.671] Build 8921563017987862816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:03:21.671] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086993080304122992:61:0) after 1m0s
[09:04:21.692] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086993080304122992:61:0)
[09:04:21.692] Timer tick, asking Buildbucket for the build status
[09:04:21.757] Build 8921563017987862816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:04:21.757] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086993080304122992:62:0) after 1m0s
[09:05:21.776] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086993080304122992:62:0)
[09:05:21.776] Timer tick, asking Buildbucket for the build status
[09:05:21.833] Build 8921563017987862816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:05:21.833] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086993080304122992:63:0) after 1m0s
[09:06:21.840] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086993080304122992:63:0)
[09:06:21.840] Timer tick, asking Buildbucket for the build status
[09:06:22.246] Build 8921563017987862816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:06:22.246] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086993080304122992:64:0) after 1m0s
[09:07:22.326] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086993080304122992:64:0)
[09:07:22.326] Timer tick, asking Buildbucket for the build status
[09:07:22.401] Build 8921563017987862816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:07:22.401] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086993080304122992:65:0) after 1m0s
[09:08:22.426] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086993080304122992:65:0)
[09:08:22.426] Timer tick, asking Buildbucket for the build status
[09:08:22.508] Build 8921563017987862816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:08:22.508] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086993080304122992:66:0) after 1m0s
[09:09:22.535] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086993080304122992:66:0)
[09:09:22.535] Timer tick, asking Buildbucket for the build status
[09:09:22.709] Build 8921563017987862816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:09:22.709] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086993080304122992:67:0) after 1m0s
[09:10:22.730] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086993080304122992:67:0)
[09:10:22.730] Timer tick, asking Buildbucket for the build status
[09:10:22.816] Build 8921563017987862816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:10:22.816] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086993080304122992:68:0) after 1m0s
[09:11:22.892] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086993080304122992:68:0)
[09:11:22.892] Timer tick, asking Buildbucket for the build status
[09:11:22.943] Build 8921563017987862816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:11:22.944] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086993080304122992:69:0) after 1m0s
[09:12:23.006] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086993080304122992:69:0)
[09:12:23.006] Timer tick, asking Buildbucket for the build status
[09:12:23.257] Build 8921563017987862816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:12:23.257] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086993080304122992:70:0) after 1m0s
[09:13:23.277] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086993080304122992:70:0)
[09:13:23.277] Timer tick, asking Buildbucket for the build status
[09:13:23.329] Build 8921563017987862816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:13:23.329] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086993080304122992:71:0) after 1m0s
[09:14:23.351] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086993080304122992:71:0)
[09:14:23.351] Timer tick, asking Buildbucket for the build status
[09:14:23.421] Build 8921563017987862816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:14:23.421] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086993080304122992:72:0) after 1m0s
[09:15:23.463] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086993080304122992:72:0)
[09:15:23.463] Timer tick, asking Buildbucket for the build status
[09:15:23.515] Build 8921563017987862816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:15:23.515] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086993080304122992:73:0) after 1m0s
[09:16:23.634] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086993080304122992:73:0)
[09:16:23.634] Timer tick, asking Buildbucket for the build status
[09:16:23.748] Build 8921563017987862816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:16:23.748] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086993080304122992:74:0) after 1m0s
[09:17:23.770] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086993080304122992:74:0)
[09:17:23.770] Timer tick, asking Buildbucket for the build status
[09:17:23.823] Build 8921563017987862816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:17:23.823] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086993080304122992:75:0) after 1m0s
[09:18:23.844] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086993080304122992:75:0)
[09:18:23.844] Timer tick, asking Buildbucket for the build status
[09:18:23.911] Build 8921563017987862816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:18:23.911] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086993080304122992:76:0) after 1m0s
[09:19:23.934] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086993080304122992:76:0)
[09:19:23.934] Timer tick, asking Buildbucket for the build status
[09:19:23.983] Build 8921563017987862816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:19:23.983] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086993080304122992:77:0) after 1m0s
[09:20:24.059] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086993080304122992:77:0)
[09:20:24.059] Timer tick, asking Buildbucket for the build status
[09:20:24.130] Build 8921563017987862816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:20:24.130] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086993080304122992:78:0) after 1m0s
[09:21:24.154] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086993080304122992:78:0)
[09:21:24.154] Timer tick, asking Buildbucket for the build status
[09:21:24.235] Build 8921563017987862816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:21:24.235] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086993080304122992:79:0) after 1m0s
[09:22:24.416] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086993080304122992:79:0)
[09:22:24.416] Timer tick, asking Buildbucket for the build status
[09:22:24.989] Build 8921563017987862816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:22:24.989] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086993080304122992:80:0) after 1m0s
[09:23:25.012] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086993080304122992:80:0)
[09:23:25.012] Timer tick, asking Buildbucket for the build status
[09:23:25.473] Build 8921563017987862816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:23:25.473] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086993080304122992:81:0) after 1m0s
[09:24:25.495] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086993080304122992:81:0)
[09:24:25.495] Timer tick, asking Buildbucket for the build status
[09:24:25.546] Build 8921563017987862816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:24:25.546] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086993080304122992:82:0) after 1m0s
[09:25:25.571] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086993080304122992:82:0)
[09:25:25.571] Timer tick, asking Buildbucket for the build status
[09:25:25.671] Build 8921563017987862816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:25:25.671] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086993080304122992:83:0) after 1m0s
[09:26:25.692] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086993080304122992:83:0)
[09:26:25.692] Timer tick, asking Buildbucket for the build status
[09:26:25.764] Build 8921563017987862816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:26:25.764] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086993080304122992:84:0) after 1m0s
[09:27:25.803] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086993080304122992:84:0)
[09:27:25.803] Timer tick, asking Buildbucket for the build status
[09:27:25.869] Build 8921563017987862816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:27:25.869] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086993080304122992:85:0) after 1m0s
[09:28:25.892] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086993080304122992:85:0)
[09:28:25.892] Timer tick, asking Buildbucket for the build status
[09:28:25.976] Build 8921563017987862816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:28:25.976] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086993080304122992:86:0) after 1m0s
[09:29:26.007] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086993080304122992:86:0)
[09:29:26.007] Timer tick, asking Buildbucket for the build status
[09:29:26.112] Build 8921563017987862816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:29:26.112] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086993080304122992:87:0) after 1m0s
[09:30:26.146] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086993080304122992:87:0)
[09:30:26.146] Timer tick, asking Buildbucket for the build status
[09:30:26.271] Build 8921563017987862816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:30:26.271] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086993080304122992:88:0) after 1m0s
[09:31:26.295] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086993080304122992:88:0)
[09:31:26.295] Timer tick, asking Buildbucket for the build status
[09:31:26.381] Build 8921563017987862816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:31:26.381] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086993080304122992:89:0) after 1m0s
[09:32:26.404] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086993080304122992:89:0)
[09:32:26.404] Timer tick, asking Buildbucket for the build status
[09:32:26.481] Build 8921563017987862816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:32:26.481] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086993080304122992:90:0) after 1m0s
[09:33:26.581] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086993080304122992:90:0)
[09:33:26.581] Timer tick, asking Buildbucket for the build status
[09:33:26.745] Build 8921563017987862816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:33:26.745] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086993080304122992:91:0) after 1m0s
[09:34:26.777] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086993080304122992:91:0)
[09:34:26.777] Timer tick, asking Buildbucket for the build status
[09:34:26.828] Build 8921563017987862816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:34:26.828] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086993080304122992:92:0) after 1m0s
[09:35:26.849] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086993080304122992:92:0)
[09:35:26.849] Timer tick, asking Buildbucket for the build status
[09:35:26.922] Build 8921563017987862816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:35:26.922] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086993080304122992:93:0) after 1m0s
[09:36:26.961] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086993080304122992:93:0)
[09:36:26.961] Timer tick, asking Buildbucket for the build status
[09:36:27.066] Build 8921563017987862816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:36:27.066] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086993080304122992:94:0) after 1m0s
[09:37:27.094] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086993080304122992:94:0)
[09:37:27.094] Timer tick, asking Buildbucket for the build status
[09:37:27.213] Build 8921563017987862816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:37:27.213] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086993080304122992:95:0) after 1m0s
[09:38:27.249] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086993080304122992:95:0)
[09:38:27.249] Timer tick, asking Buildbucket for the build status
[09:38:27.328] Build 8921563017987862816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:38:27.328] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086993080304122992:96:0) after 1m0s
[09:39:27.352] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086993080304122992:96:0)
[09:39:27.353] Timer tick, asking Buildbucket for the build status
[09:39:27.686] Build 8921563017987862816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:39:27.686] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086993080304122992:97:0) after 1m0s
[09:40:27.723] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086993080304122992:97:0)
[09:40:27.723] Timer tick, asking Buildbucket for the build status
[09:40:27.772] Build 8921563017987862816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:40:27.772] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086993080304122992:98:0) after 1m0s
[09:41:27.793] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086993080304122992:98:0)
[09:41:27.793] Timer tick, asking Buildbucket for the build status
[09:41:27.893] Build 8921563017987862816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:41:27.893] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086993080304122992:99:0) after 1m0s
[09:42:27.960] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086993080304122992:99:0)
[09:42:27.960] Timer tick, asking Buildbucket for the build status
[09:42:28.028] Build 8921563017987862816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:42:28.028] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086993080304122992:100:0) after 1m0s
[09:43:28.084] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086993080304122992:100:0)
[09:43:28.084] Timer tick, asking Buildbucket for the build status
[09:43:28.180] Build 8921563017987862816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:43:28.180] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086993080304122992:101:0) after 1m0s
[09:44:28.215] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086993080304122992:101:0)
[09:44:28.215] Timer tick, asking Buildbucket for the build status
[09:44:28.286] Build 8921563017987862816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:44:28.286] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086993080304122992:102:0) after 1m0s
[09:45:28.310] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086993080304122992:102:0)
[09:45:28.310] Timer tick, asking Buildbucket for the build status
[09:45:28.358] Build 8921563017987862816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:45:28.358] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086993080304122992:103:0) after 1m0s
[09:46:28.386] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086993080304122992:103:0)
[09:46:28.386] Timer tick, asking Buildbucket for the build status
[09:46:28.519] Build 8921563017987862816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:46:28.519] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086993080304122992:104:0) after 1m0s
[09:47:28.543] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086993080304122992:104:0)
[09:47:28.543] Timer tick, asking Buildbucket for the build status
[09:47:28.595] Build 8921563017987862816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:47:28.595] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086993080304122992:105:0) after 1m0s
[09:48:28.627] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086993080304122992:105:0)
[09:48:28.627] Timer tick, asking Buildbucket for the build status
[09:48:28.752] Build 8921563017987862816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:48:28.752] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086993080304122992:106:0) after 1m0s
[09:49:28.781] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086993080304122992:106:0)
[09:49:28.781] Timer tick, asking Buildbucket for the build status
[09:49:29.428] Build 8921563017987862816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:49:29.428] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086993080304122992:107:0) after 1m0s
[09:50:28.982] Received PubSub notification, asking Buildbucket for the build status
[09:50:29.055] Build 8921563017987862816: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[09:50:29.055] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"completed_ts": "1550137828074899",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1550131509753539",
"id": "8921563017987862816",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"6c7bf9286338957ec33f3a83635bf370e6384e6b\"}}",
"project": "chromium",
"result": "SUCCESS",
"result_details_json": "{\"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\": [\"4098-34330fc\"], \"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\"]}}, \"ui\": {\"info\": \"\"}, \"properties\": {\"got_nacl_revision\": \"ff15c51d20785fa51c0ef03ccb480b87299acbeb\", \"got_swarming_client_revision\": \"0e3e1c4dc4e79f25a5b58fcbc135dc93183c0c54\", \"got_revision\": \"6c7bf9286338957ec33f3a83635bf370e6384e6b\", \"recipe\": \"swarming/deterministic_build\", \"got_dawn_revision\": \"b2207737abd32e20e39a1ac00987179626f050c7\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#26675}\", \"$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@{#632127}\", \"branch\": \"refs/heads/master\", \"revision\": \"6c7bf9286338957ec33f3a83635bf370e6384e6b\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"buildername\": \"Windows deterministic\", \"got_webrtc_revision\": \"b1ea48c2e8682e9c2f67ff3cfa4e9dbaebbb8cc7\", \"mastername\": \"chromium.win\", \"got_angle_revision\": \"52047de4d41f6861e35b7073dd180edffd64c540\", \"buildbucket\": {\"hostname\": \"cr-buildbucket.appspot.com\", \"build\": {\"created_ts\": 1550131509753539, \"tags\": [\"build_address:luci.chromium.ci/Windows deterministic/11963\", \"builder:Windows deterministic\", \"buildset:commit/git/6c7bf9286338957ec33f3a83635bf370e6384e6b\", \"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/6c7bf9286338957ec33f3a83635bf370e6384e6b\", \"gitiles_ref:refs/heads/master\", \"scheduler_invocation_id:9086993080304122992\", \"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\": \"8921563017987862816\"}}, \"got_v8_revision\": \"d34a141dae9be2c1f66509c7c449992ea34d058a\", \"got_v8_revision_cp\": \"refs/heads/7.4.166@{#1}\", \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"buildnumber\": 11963, \"$recipe_engine/buildbucket\": {\"build\": {\"tags\": [{\"value\": \"Windows deterministic\", \"key\": \"builder\"}, {\"value\": \"commit/git/6c7bf9286338957ec33f3a83635bf370e6384e6b\", \"key\": \"buildset\"}, {\"value\": \"commit/gitiles/chromium.googlesource.com/chromium/src/+/6c7bf9286338957ec33f3a83635bf370e6384e6b\", \"key\": \"buildset\"}, {\"value\": \"9086993080304122992\", \"key\": \"scheduler_invocation_id\"}, {\"value\": \"chromium/Windows deterministic\", \"key\": \"scheduler_job_id\"}, {\"value\": \"luci-scheduler\", \"key\": \"user_agent\"}], \"builder\": {\"project\": \"chromium\", \"builder\": \"Windows deterministic\", \"bucket\": \"ci\"}, \"number\": 11963, \"infra\": {\"buildbucket\": {\"serviceConfigRevision\": \"6aef5327da3458f30853917b657b22c0910618fa\"}, \"recipe\": {\"name\": \"swarming/deterministic_build\", \"cipdPackage\": \"infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build\"}, \"swarming\": {\"hostname\": \"chromium-swarm.appspot.com\"}}, \"createdBy\": \"user:luci-scheduler@appspot.gserviceaccount.com\", \"id\": \"8921563017987862816\", \"input\": {\"gitilesCommit\": {\"project\": \"chromium/src\", \"host\": \"chromium.googlesource.com\", \"ref\": \"refs/heads/master\", \"id\": \"6c7bf9286338957ec33f3a83635bf370e6384e6b\"}}, \"createTime\": \"2019-02-14T08:05:09.753539Z\"}}, \"path_config\": \"generic\", \"bot_id\": \"swarm2173-c4\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1550131514490414",
"status": "COMPLETED",
"status_changed_ts": "1550137828586984",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/11963",
"builder:Windows deterministic",
"buildset:commit/git/6c7bf9286338957ec33f3a83635bf370e6384e6b",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/6c7bf9286338957ec33f3a83635bf370e6384e6b",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9086993080304122992",
"scheduler_job_id:chromium/Windows deterministic",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8921563017987862816/+/annotations",
"swarming_tag:luci_project:chromium",
"swarming_tag:os:Windows",
"swarming_tag:recipe_name:swarming/deterministic_build",
"swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build",
"swarming_task_id:4303db466a40c010",
"user_agent:luci-scheduler"
],
"updated_ts": "1550137828588879",
"url": "https://ci.chromium.org/b/8921563017987862816",
"utcnow_ts": "1550137829036552"
}
}
[09:50:29.056] Invocation finished in 1h45m20.895989354s with status SUCCEEDED