[19:03:18.468] New invocation is queued and will start shortly
[19:03:19.523] Starting the invocation (attempt 1)
[19:03:19.567] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[19:03:19.567] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[19:03:19.567] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9085864508137226960",
"parameters_json": "{\"builder_name\":\"Windows deterministic\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src.git\",\"revision\":\"3fb9033263d13bd429cde695f97a9a829b98b24e\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Windows deterministic",
"scheduler_invocation_id:9085864508137226960",
"scheduler_job_id:chromium/Windows deterministic",
"user_agent:luci-scheduler",
"buildset:commit/git/3fb9033263d13bd429cde695f97a9a829b98b24e",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/3fb9033263d13bd429cde695f97a9a829b98b24e",
"gitiles_ref:refs/heads/master"
]
}
[19:03:20.829] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1551207799686921",
"id": "8920434446194379584",
"lease_expiration_ts": "1551326600476546",
"lease_key": "2142018880",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"3fb9033263d13bd429cde695f97a9a829b98b24e\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1551207800681304",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/12217",
"builder:Windows deterministic",
"buildset:commit/git/3fb9033263d13bd429cde695f97a9a829b98b24e",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/3fb9033263d13bd429cde695f97a9a829b98b24e",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9085864508137226960",
"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/8920434446194379584/+/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:4344022543ebdc10",
"user_agent:luci-scheduler"
],
"updated_ts": "1551207800681417",
"url": "https://ci.chromium.org/b/8920434446194379584",
"utcnow_ts": "1551207800818763"
}
}
[19:03:20.829] Task URL: https://ci.chromium.org/b/8920434446194379584
[19:03:20.829] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085864508137226960:2:0) after 1m0s
[19:03:21.934] Received PubSub notification, asking Buildbucket for the build status
[19:03:21.976] Build 8920434446194379584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:04:20.852] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085864508137226960:2:0)
[19:04:20.852] Timer tick, asking Buildbucket for the build status
[19:04:20.922] Build 8920434446194379584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:04:20.922] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085864508137226960:4:0) after 1m0s
[19:05:20.921] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085864508137226960:4:0)
[19:05:20.921] Timer tick, asking Buildbucket for the build status
[19:05:21.165] Build 8920434446194379584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:05:21.165] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085864508137226960:5:0) after 1m0s
[19:06:21.167] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085864508137226960:5:0)
[19:06:21.167] Timer tick, asking Buildbucket for the build status
[19:06:21.221] Build 8920434446194379584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:06:21.221] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085864508137226960:6:0) after 1m0s
[19:07:21.203] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085864508137226960:6:0)
[19:07:21.203] Timer tick, asking Buildbucket for the build status
[19:07:21.571] Build 8920434446194379584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:07:21.571] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085864508137226960:7:0) after 1m0s
[19:08:21.596] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085864508137226960:7:0)
[19:08:21.596] Timer tick, asking Buildbucket for the build status
[19:08:21.641] Build 8920434446194379584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:08:21.641] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085864508137226960:8:0) after 1m0s
[19:09:21.659] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085864508137226960:8:0)
[19:09:21.659] Timer tick, asking Buildbucket for the build status
[19:09:21.708] Build 8920434446194379584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:09:21.709] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085864508137226960:9:0) after 1m0s
[19:10:21.726] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085864508137226960:9:0)
[19:10:21.726] Timer tick, asking Buildbucket for the build status
[19:10:21.806] Build 8920434446194379584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:10:21.806] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085864508137226960:10:0) after 1m0s
[19:11:21.824] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085864508137226960:10:0)
[19:11:21.824] Timer tick, asking Buildbucket for the build status
[19:11:21.871] Build 8920434446194379584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:11:21.871] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085864508137226960:11:0) after 1m0s
[19:12:21.860] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085864508137226960:11:0)
[19:12:21.860] Timer tick, asking Buildbucket for the build status
[19:12:21.918] Build 8920434446194379584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:12:21.918] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085864508137226960:12:0) after 1m0s
[19:13:21.894] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085864508137226960:12:0)
[19:13:21.894] Timer tick, asking Buildbucket for the build status
[19:13:22.014] Build 8920434446194379584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:13:22.014] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085864508137226960:13:0) after 1m0s
[19:14:22.040] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085864508137226960:13:0)
[19:14:22.040] Timer tick, asking Buildbucket for the build status
[19:14:22.093] Build 8920434446194379584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:14:22.093] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085864508137226960:14:0) after 1m0s
[19:15:22.126] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085864508137226960:14:0)
[19:15:22.126] Timer tick, asking Buildbucket for the build status
[19:15:22.498] Build 8920434446194379584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:15:22.498] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085864508137226960:15:0) after 1m0s
[19:16:22.500] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085864508137226960:15:0)
[19:16:22.500] Timer tick, asking Buildbucket for the build status
[19:16:22.597] Build 8920434446194379584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:16:22.598] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085864508137226960:16:0) after 1m0s
[19:17:22.611] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085864508137226960:16:0)
[19:17:22.611] Timer tick, asking Buildbucket for the build status
[19:17:22.698] Build 8920434446194379584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:17:22.698] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085864508137226960:17:0) after 1m0s
[19:18:22.708] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085864508137226960:17:0)
[19:18:22.708] Timer tick, asking Buildbucket for the build status
[19:18:22.902] Build 8920434446194379584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:18:22.902] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085864508137226960:18:0) after 1m0s
[19:19:22.882] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085864508137226960:18:0)
[19:19:22.882] Timer tick, asking Buildbucket for the build status
[19:19:22.934] Build 8920434446194379584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:19:22.934] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085864508137226960:19:0) after 1m0s
[19:20:22.953] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085864508137226960:19:0)
[19:20:22.953] Timer tick, asking Buildbucket for the build status
[19:20:23.048] Build 8920434446194379584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:20:23.048] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085864508137226960:20:0) after 1m0s
[19:21:23.059] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085864508137226960:20:0)
[19:21:23.059] Timer tick, asking Buildbucket for the build status
[19:21:23.285] Build 8920434446194379584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:21:23.285] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085864508137226960:21:0) after 1m0s
[19:22:23.307] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085864508137226960:21:0)
[19:22:23.307] Timer tick, asking Buildbucket for the build status
[19:22:23.366] Build 8920434446194379584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:22:23.366] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085864508137226960:22:0) after 1m0s
[19:23:23.388] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085864508137226960:22:0)
[19:23:23.388] Timer tick, asking Buildbucket for the build status
[19:23:23.499] Build 8920434446194379584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:23:23.499] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085864508137226960:23:0) after 1m0s
[19:24:23.494] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085864508137226960:23:0)
[19:24:23.494] Timer tick, asking Buildbucket for the build status
[19:24:23.681] Build 8920434446194379584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:24:23.682] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085864508137226960:24:0) after 1m0s
[19:25:23.700] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085864508137226960:24:0)
[19:25:23.700] Timer tick, asking Buildbucket for the build status
[19:25:23.749] Build 8920434446194379584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:25:23.749] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085864508137226960:25:0) after 1m0s
[19:26:23.768] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085864508137226960:25:0)
[19:26:23.768] Timer tick, asking Buildbucket for the build status
[19:26:23.961] Build 8920434446194379584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:26:23.961] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085864508137226960:26:0) after 1m0s
[19:27:24.051] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085864508137226960:26:0)
[19:27:24.051] Timer tick, asking Buildbucket for the build status
[19:27:24.333] Build 8920434446194379584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:27:24.333] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085864508137226960:27:0) after 1m0s
[19:28:24.336] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085864508137226960:27:0)
[19:28:24.336] Timer tick, asking Buildbucket for the build status
[19:28:24.484] Build 8920434446194379584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:28:24.484] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085864508137226960:28:0) after 1m0s
[19:29:24.457] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085864508137226960:28:0)
[19:29:24.457] Timer tick, asking Buildbucket for the build status
[19:29:24.645] Build 8920434446194379584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:29:24.645] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085864508137226960:29:0) after 1m0s
[19:30:24.671] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085864508137226960:29:0)
[19:30:24.671] Timer tick, asking Buildbucket for the build status
[19:30:24.735] Build 8920434446194379584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:30:24.735] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085864508137226960:30:0) after 1m0s
[19:31:24.754] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085864508137226960:30:0)
[19:31:24.754] Timer tick, asking Buildbucket for the build status
[19:31:24.913] Build 8920434446194379584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:31:24.913] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085864508137226960:31:0) after 1m0s
[19:32:24.917] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085864508137226960:31:0)
[19:32:24.917] Timer tick, asking Buildbucket for the build status
[19:32:24.965] Build 8920434446194379584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:32:24.965] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085864508137226960:32:0) after 1m0s
[19:33:24.968] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085864508137226960:32:0)
[19:33:24.968] Timer tick, asking Buildbucket for the build status
[19:33:25.079] Build 8920434446194379584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:33:25.079] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085864508137226960:33:0) after 1m0s
[19:34:25.108] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085864508137226960:33:0)
[19:34:25.108] Timer tick, asking Buildbucket for the build status
[19:34:25.286] Build 8920434446194379584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:34:25.286] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085864508137226960:34:0) after 1m0s
[19:35:25.315] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085864508137226960:34:0)
[19:35:25.315] Timer tick, asking Buildbucket for the build status
[19:35:25.435] Build 8920434446194379584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:35:25.435] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085864508137226960:35:0) after 1m0s
[19:36:25.474] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085864508137226960:35:0)
[19:36:25.474] Timer tick, asking Buildbucket for the build status
[19:36:25.589] Build 8920434446194379584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:36:25.589] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085864508137226960:36:0) after 1m0s
[19:37:25.594] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085864508137226960:36:0)
[19:37:25.594] Timer tick, asking Buildbucket for the build status
[19:37:25.672] Build 8920434446194379584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:37:25.672] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085864508137226960:37:0) after 1m0s
[19:38:25.660] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085864508137226960:37:0)
[19:38:25.660] Timer tick, asking Buildbucket for the build status
[19:38:25.796] Build 8920434446194379584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:38:25.796] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085864508137226960:38:0) after 1m0s
[19:39:25.843] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085864508137226960:38:0)
[19:39:25.843] Timer tick, asking Buildbucket for the build status
[19:39:25.880] Build 8920434446194379584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:39:25.880] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085864508137226960:39:0) after 1m0s
[19:40:25.924] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085864508137226960:39:0)
[19:40:25.924] Timer tick, asking Buildbucket for the build status
[19:40:26.140] Build 8920434446194379584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:40:26.140] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085864508137226960:40:0) after 1m0s
[19:41:26.161] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085864508137226960:40:0)
[19:41:26.161] Timer tick, asking Buildbucket for the build status
[19:41:26.213] Build 8920434446194379584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:41:26.213] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085864508137226960:41:0) after 1m0s
[19:42:26.244] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085864508137226960:41:0)
[19:42:26.244] Timer tick, asking Buildbucket for the build status
[19:42:26.297] Build 8920434446194379584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:42:26.297] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085864508137226960:42:0) after 1m0s
[19:43:26.297] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085864508137226960:42:0)
[19:43:26.297] Timer tick, asking Buildbucket for the build status
[19:43:26.379] Build 8920434446194379584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:43:26.379] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085864508137226960:43:0) after 1m0s
[19:44:26.424] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085864508137226960:43:0)
[19:44:26.424] Timer tick, asking Buildbucket for the build status
[19:44:26.474] Build 8920434446194379584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:44:26.474] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085864508137226960:44:0) after 1m0s
[19:45:26.481] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085864508137226960:44:0)
[19:45:26.481] Timer tick, asking Buildbucket for the build status
[19:45:26.607] Build 8920434446194379584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:45:26.607] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085864508137226960:45:0) after 1m0s
[19:46:26.605] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085864508137226960:45:0)
[19:46:26.605] Timer tick, asking Buildbucket for the build status
[19:46:26.670] Build 8920434446194379584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:46:26.671] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085864508137226960:46:0) after 1m0s
[19:47:26.689] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085864508137226960:46:0)
[19:47:26.689] Timer tick, asking Buildbucket for the build status
[19:47:26.734] Build 8920434446194379584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:47:26.734] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085864508137226960:47:0) after 1m0s
[19:48:26.756] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085864508137226960:47:0)
[19:48:26.756] Timer tick, asking Buildbucket for the build status
[19:48:26.819] Build 8920434446194379584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:48:26.819] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085864508137226960:48:0) after 1m0s
[19:49:26.884] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085864508137226960:48:0)
[19:49:26.884] Timer tick, asking Buildbucket for the build status
[19:49:26.988] Build 8920434446194379584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:49:26.988] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085864508137226960:49:0) after 1m0s
[19:50:27.012] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085864508137226960:49:0)
[19:50:27.012] Timer tick, asking Buildbucket for the build status
[19:50:27.103] Build 8920434446194379584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:50:27.103] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085864508137226960:50:0) after 1m0s
[19:51:27.108] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085864508137226960:50:0)
[19:51:27.108] Timer tick, asking Buildbucket for the build status
[19:51:27.218] Build 8920434446194379584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:51:27.218] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085864508137226960:51:0) after 1m0s
[19:52:27.308] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085864508137226960:51:0)
[19:52:27.308] Timer tick, asking Buildbucket for the build status
[19:52:27.393] Build 8920434446194379584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:52:27.393] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085864508137226960:52:0) after 1m0s
[19:53:27.413] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085864508137226960:52:0)
[19:53:27.413] Timer tick, asking Buildbucket for the build status
[19:53:27.496] Build 8920434446194379584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:53:27.496] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085864508137226960:53:0) after 1m0s
[19:54:27.493] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085864508137226960:53:0)
[19:54:27.493] Timer tick, asking Buildbucket for the build status
[19:54:27.680] Build 8920434446194379584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:54:27.680] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085864508137226960:54:0) after 1m0s
[19:55:27.687] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085864508137226960:54:0)
[19:55:27.687] Timer tick, asking Buildbucket for the build status
[19:55:27.761] Build 8920434446194379584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:55:27.761] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085864508137226960:55:0) after 1m0s
[19:56:27.739] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085864508137226960:55:0)
[19:56:27.739] Timer tick, asking Buildbucket for the build status
[19:56:27.811] Build 8920434446194379584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:56:27.811] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085864508137226960:56:0) after 1m0s
[19:57:27.805] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085864508137226960:56:0)
[19:57:27.805] Timer tick, asking Buildbucket for the build status
[19:57:27.881] Build 8920434446194379584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:57:27.881] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085864508137226960:57:0) after 1m0s
[19:58:27.902] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085864508137226960:57:0)
[19:58:27.902] Timer tick, asking Buildbucket for the build status
[19:58:27.956] Build 8920434446194379584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:58:27.956] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085864508137226960:58:0) after 1m0s
[19:59:27.976] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085864508137226960:58:0)
[19:59:27.976] Timer tick, asking Buildbucket for the build status
[19:59:28.188] Build 8920434446194379584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:59:28.188] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085864508137226960:59:0) after 1m0s
[20:00:28.249] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085864508137226960:59:0)
[20:00:28.249] Timer tick, asking Buildbucket for the build status
[20:00:28.299] Build 8920434446194379584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:00:28.299] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085864508137226960:60:0) after 1m0s
[20:01:28.285] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085864508137226960:60:0)
[20:01:28.285] Timer tick, asking Buildbucket for the build status
[20:01:28.331] Build 8920434446194379584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:01:28.331] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085864508137226960:61:0) after 1m0s
[20:02:28.396] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085864508137226960:61:0)
[20:02:28.396] Timer tick, asking Buildbucket for the build status
[20:02:28.500] Build 8920434446194379584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:02:28.500] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085864508137226960:62:0) after 1m0s
[20:03:28.514] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085864508137226960:62:0)
[20:03:28.514] Timer tick, asking Buildbucket for the build status
[20:03:28.726] Build 8920434446194379584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:03:28.726] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085864508137226960:63:0) after 1m0s
[20:04:28.715] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085864508137226960:63:0)
[20:04:28.715] Timer tick, asking Buildbucket for the build status
[20:04:28.759] Build 8920434446194379584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:04:28.759] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085864508137226960:64:0) after 1m0s
[20:05:28.773] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085864508137226960:64:0)
[20:05:28.773] Timer tick, asking Buildbucket for the build status
[20:05:28.862] Build 8920434446194379584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:05:28.862] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085864508137226960:65:0) after 1m0s
[20:06:28.889] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085864508137226960:65:0)
[20:06:28.889] Timer tick, asking Buildbucket for the build status
[20:06:28.978] Build 8920434446194379584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:06:28.978] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085864508137226960:66:0) after 1m0s
[20:07:29.026] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085864508137226960:66:0)
[20:07:29.026] Timer tick, asking Buildbucket for the build status
[20:07:29.316] Build 8920434446194379584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:07:29.316] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085864508137226960:67:0) after 1m0s
[20:08:29.339] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085864508137226960:67:0)
[20:08:29.339] Timer tick, asking Buildbucket for the build status
[20:08:29.476] Build 8920434446194379584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:08:29.476] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085864508137226960:68:0) after 1m0s
[20:09:29.531] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085864508137226960:68:0)
[20:09:29.531] Timer tick, asking Buildbucket for the build status
[20:09:29.810] Build 8920434446194379584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:09:29.810] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085864508137226960:69:0) after 1m0s
[20:10:29.861] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085864508137226960:69:0)
[20:10:29.861] Timer tick, asking Buildbucket for the build status
[20:10:29.905] Build 8920434446194379584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:10:29.905] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085864508137226960:70:0) after 1m0s
[20:11:29.936] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085864508137226960:70:0)
[20:11:29.936] Timer tick, asking Buildbucket for the build status
[20:11:29.988] Build 8920434446194379584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:11:29.988] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085864508137226960:71:0) after 1m0s
[20:12:30.018] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085864508137226960:71:0)
[20:12:30.018] Timer tick, asking Buildbucket for the build status
[20:12:30.111] Build 8920434446194379584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:12:30.111] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085864508137226960:72:0) after 1m0s
[20:13:30.135] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085864508137226960:72:0)
[20:13:30.135] Timer tick, asking Buildbucket for the build status
[20:13:30.209] Build 8920434446194379584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:13:30.209] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085864508137226960:73:0) after 1m0s
[20:14:30.217] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085864508137226960:73:0)
[20:14:30.217] Timer tick, asking Buildbucket for the build status
[20:14:30.277] Build 8920434446194379584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:14:30.277] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085864508137226960:74:0) after 1m0s
[20:15:30.299] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085864508137226960:74:0)
[20:15:30.299] Timer tick, asking Buildbucket for the build status
[20:15:30.398] Build 8920434446194379584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:15:30.398] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085864508137226960:75:0) after 1m0s
[20:16:30.461] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085864508137226960:75:0)
[20:16:30.461] Timer tick, asking Buildbucket for the build status
[20:16:30.526] Build 8920434446194379584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:16:30.526] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085864508137226960:76:0) after 1m0s
[20:17:30.579] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085864508137226960:76:0)
[20:17:30.579] Timer tick, asking Buildbucket for the build status
[20:17:30.708] Build 8920434446194379584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:17:30.708] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085864508137226960:77:0) after 1m0s
[20:18:30.717] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085864508137226960:77:0)
[20:18:30.717] Timer tick, asking Buildbucket for the build status
[20:18:30.891] Build 8920434446194379584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:18:30.891] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085864508137226960:78:0) after 1m0s
[20:19:30.861] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085864508137226960:78:0)
[20:19:30.861] Timer tick, asking Buildbucket for the build status
[20:19:30.916] Build 8920434446194379584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:19:30.916] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085864508137226960:79:0) after 1m0s
[20:20:30.938] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085864508137226960:79:0)
[20:20:30.938] Timer tick, asking Buildbucket for the build status
[20:20:31.008] Build 8920434446194379584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:20:31.008] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085864508137226960:80:0) after 1m0s
[20:21:31.075] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085864508137226960:80:0)
[20:21:31.075] Timer tick, asking Buildbucket for the build status
[20:21:31.155] Build 8920434446194379584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:21:31.155] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085864508137226960:81:0) after 1m0s
[20:22:31.157] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085864508137226960:81:0)
[20:22:31.157] Timer tick, asking Buildbucket for the build status
[20:22:31.280] Build 8920434446194379584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:22:31.280] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085864508137226960:82:0) after 1m0s
[20:23:31.294] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085864508137226960:82:0)
[20:23:31.294] Timer tick, asking Buildbucket for the build status
[20:23:31.408] Build 8920434446194379584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:23:31.408] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085864508137226960:83:0) after 1m0s
[20:24:31.451] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085864508137226960:83:0)
[20:24:31.451] Timer tick, asking Buildbucket for the build status
[20:24:31.602] Build 8920434446194379584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:24:31.602] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085864508137226960:84:0) after 1m0s
[20:25:31.644] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085864508137226960:84:0)
[20:25:31.644] Timer tick, asking Buildbucket for the build status
[20:25:31.691] Build 8920434446194379584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:25:31.691] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085864508137226960:85:0) after 1m0s
[20:26:31.712] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085864508137226960:85:0)
[20:26:31.712] Timer tick, asking Buildbucket for the build status
[20:26:31.794] Build 8920434446194379584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:26:31.794] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085864508137226960:86:0) after 1m0s
[20:27:31.785] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085864508137226960:86:0)
[20:27:31.785] Timer tick, asking Buildbucket for the build status
[20:27:31.917] Build 8920434446194379584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:27:31.917] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085864508137226960:87:0) after 1m0s
[20:28:31.930] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085864508137226960:87:0)
[20:28:31.930] Timer tick, asking Buildbucket for the build status
[20:28:31.972] Build 8920434446194379584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:28:31.972] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085864508137226960:88:0) after 1m0s
[20:29:31.982] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085864508137226960:88:0)
[20:29:31.982] Timer tick, asking Buildbucket for the build status
[20:29:32.123] Build 8920434446194379584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:29:32.123] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085864508137226960:89:0) after 1m0s
[20:30:32.147] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085864508137226960:89:0)
[20:30:32.147] Timer tick, asking Buildbucket for the build status
[20:30:32.199] Build 8920434446194379584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:30:32.199] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085864508137226960:90:0) after 1m0s
[20:31:32.229] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085864508137226960:90:0)
[20:31:32.229] Timer tick, asking Buildbucket for the build status
[20:31:32.498] Build 8920434446194379584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:31:32.498] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085864508137226960:91:0) after 1m0s
[20:32:32.494] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085864508137226960:91:0)
[20:32:32.494] Timer tick, asking Buildbucket for the build status
[20:32:32.554] Build 8920434446194379584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:32:32.554] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085864508137226960:92:0) after 1m0s
[20:33:32.565] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085864508137226960:92:0)
[20:33:32.565] Timer tick, asking Buildbucket for the build status
[20:33:32.614] Build 8920434446194379584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:33:32.614] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085864508137226960:93:0) after 1m0s
[20:34:32.658] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085864508137226960:93:0)
[20:34:32.658] Timer tick, asking Buildbucket for the build status
[20:34:32.726] Build 8920434446194379584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:34:32.726] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085864508137226960:94:0) after 1m0s
[20:35:32.762] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085864508137226960:94:0)
[20:35:32.762] Timer tick, asking Buildbucket for the build status
[20:35:32.819] Build 8920434446194379584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:35:32.819] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085864508137226960:95:0) after 1m0s
[20:36:32.858] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085864508137226960:95:0)
[20:36:32.858] Timer tick, asking Buildbucket for the build status
[20:36:32.965] Build 8920434446194379584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:36:32.965] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085864508137226960:96:0) after 1m0s
[20:37:32.977] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085864508137226960:96:0)
[20:37:32.977] Timer tick, asking Buildbucket for the build status
[20:37:33.092] Build 8920434446194379584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:37:33.092] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085864508137226960:97:0) after 1m0s
[20:38:33.118] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085864508137226960:97:0)
[20:38:33.118] Timer tick, asking Buildbucket for the build status
[20:38:33.191] Build 8920434446194379584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:38:33.191] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085864508137226960:98:0) after 1m0s
[20:39:33.243] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085864508137226960:98:0)
[20:39:33.243] Timer tick, asking Buildbucket for the build status
[20:39:33.344] Build 8920434446194379584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:39:33.344] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085864508137226960:99:0) after 1m0s
[20:40:33.370] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085864508137226960:99:0)
[20:40:33.370] Timer tick, asking Buildbucket for the build status
[20:40:33.636] Build 8920434446194379584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:40:33.636] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085864508137226960:100:0) after 1m0s
[20:41:33.612] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085864508137226960:100:0)
[20:41:33.612] Timer tick, asking Buildbucket for the build status
[20:41:33.654] Build 8920434446194379584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:41:33.654] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085864508137226960:101:0) after 1m0s
[20:42:33.720] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085864508137226960:101:0)
[20:42:33.721] Timer tick, asking Buildbucket for the build status
[20:42:33.763] Build 8920434446194379584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:42:33.763] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085864508137226960:102:0) after 1m0s
[20:43:33.786] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085864508137226960:102:0)
[20:43:33.786] Timer tick, asking Buildbucket for the build status
[20:43:33.902] Build 8920434446194379584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:43:33.902] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085864508137226960:103:0) after 1m0s
[20:44:33.924] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085864508137226960:103:0)
[20:44:33.924] Timer tick, asking Buildbucket for the build status
[20:44:33.994] Build 8920434446194379584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:44:33.994] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085864508137226960:104:0) after 1m0s
[20:45:33.978] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085864508137226960:104:0)
[20:45:33.978] Timer tick, asking Buildbucket for the build status
[20:45:34.278] Build 8920434446194379584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:45:34.278] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085864508137226960:105:0) after 1m0s
[20:46:34.283] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085864508137226960:105:0)
[20:46:34.283] Timer tick, asking Buildbucket for the build status
[20:46:34.346] Build 8920434446194379584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:46:34.346] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085864508137226960:106:0) after 1m0s
[20:47:34.365] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085864508137226960:106:0)
[20:47:34.365] Timer tick, asking Buildbucket for the build status
[20:47:34.485] Build 8920434446194379584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:47:34.485] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085864508137226960:107:0) after 1m0s
[20:48:34.619] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085864508137226960:107:0)
[20:48:34.619] Timer tick, asking Buildbucket for the build status
[20:48:34.692] Build 8920434446194379584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:48:34.692] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085864508137226960:108:0) after 1m0s
[20:49:34.698] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085864508137226960:108:0)
[20:49:34.698] Timer tick, asking Buildbucket for the build status
[20:49:34.778] Build 8920434446194379584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:49:34.779] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085864508137226960:109:0) after 1m0s
[20:50:34.774] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085864508137226960:109:0)
[20:50:34.774] Timer tick, asking Buildbucket for the build status
[20:50:34.849] Build 8920434446194379584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:50:34.849] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085864508137226960:110:0) after 1m0s
[20:51:34.872] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085864508137226960:110:0)
[20:51:34.872] Timer tick, asking Buildbucket for the build status
[20:51:34.984] Build 8920434446194379584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:51:34.984] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085864508137226960:111:0) after 1m0s
[20:52:23.449] Received PubSub notification, asking Buildbucket for the build status
[20:52:23.490] Build 8920434446194379584: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[20:52:23.490] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"completed_ts": "1551214342487529",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1551207799686921",
"id": "8920434446194379584",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"3fb9033263d13bd429cde695f97a9a829b98b24e\"}}",
"project": "chromium",
"result": "SUCCESS",
"result_details_json": "{\"swarming\": {\"bot_dimensions\": {\"gce\": [\"1\"], \"machine_type\": [\"n1-standard-8\"], \"zone\": [\"us\", \"us-west\", \"us-west1\", \"us-west1-c\"], \"locale\": [\"en_US.cp1252\"], \"os\": [\"Windows\", \"Windows-10\", \"Windows-10-15063\"], \"builder\": [\"Windows deterministic\"], \"id\": [\"win10-727f49a0-us-west1-c-gw0h\"], \"python\": [\"2.7.13\"], \"server_version\": [\"4129-0985e08\"], \"image\": [\"chrome-win10-1703-2018-04-20-61acaf8983f\"], \"gpu\": [\"none\"], \"cores\": [\"8\"], \"integrity\": [\"high\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\"], \"pool\": [\"luci.chromium.ci\"], \"caches\": [\"builder_e8fffaf9fac16164b1ec16ebaa1f377b792d39a582f7640830518d8ff7303d0c_v2\", \"git\", \"goma_v2\", \"vpython\"]}}, \"ui\": {\"info\": \"\"}, \"properties\": {\"got_nacl_revision\": \"1ed9878b8eb68c1e0d0268754dafcf83e747fa8f\", \"got_swarming_client_revision\": \"d50a88f50782ba29076061b94c7b9d08a6c7e424\", \"got_revision\": \"3fb9033263d13bd429cde695f97a9a829b98b24e\", \"recipe\": \"swarming/deterministic_build\", \"got_dawn_revision\": \"d56f8d2e050140d4f0bd5e4b33c9eabbfafaabef\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#26854}\", \"$recipe_engine/path\": {\"cache_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\cache\", \"temp_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\tmp\\\\rt\"}, \"got_revision_cp\": \"refs/heads/master@{#635627}\", \"branch\": \"refs/heads/master\", \"revision\": \"3fb9033263d13bd429cde695f97a9a829b98b24e\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"buildername\": \"Windows deterministic\", \"got_webrtc_revision\": \"12ae4f4d50b0ba6f48b20aa388ac6aea54a72668\", \"mastername\": \"chromium.win\", \"got_angle_revision\": \"0546b5380780a5ae12db34a9ef4fb310935ef35b\", \"buildbucket\": {\"hostname\": \"cr-buildbucket.appspot.com\", \"build\": {\"created_ts\": 1551207799686921, \"tags\": [\"build_address:luci.chromium.ci/Windows deterministic/12217\", \"builder:Windows deterministic\", \"buildset:commit/git/3fb9033263d13bd429cde695f97a9a829b98b24e\", \"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/3fb9033263d13bd429cde695f97a9a829b98b24e\", \"gitiles_ref:refs/heads/master\", \"scheduler_invocation_id:9085864508137226960\", \"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\": \"8920434446194379584\"}}, \"got_v8_revision\": \"f073572f882e5a3f945cfdb12b8949d95a3ccf25\", \"got_v8_revision_cp\": \"refs/heads/7.4.222@{#1}\", \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"buildnumber\": 12217, \"$recipe_engine/buildbucket\": {\"build\": {\"tags\": [{\"value\": \"Windows deterministic\", \"key\": \"builder\"}, {\"value\": \"commit/git/3fb9033263d13bd429cde695f97a9a829b98b24e\", \"key\": \"buildset\"}, {\"value\": \"commit/gitiles/chromium.googlesource.com/chromium/src/+/3fb9033263d13bd429cde695f97a9a829b98b24e\", \"key\": \"buildset\"}, {\"value\": \"9085864508137226960\", \"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\": 12217, \"infra\": {\"buildbucket\": {\"serviceConfigRevision\": \"aed94bdb6fa0b2abb2bed1ef8ed7b1a334781d31\"}, \"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\": \"8920434446194379584\", \"input\": {\"gitilesCommit\": {\"project\": \"chromium/src\", \"host\": \"chromium.googlesource.com\", \"ref\": \"refs/heads/master\", \"id\": \"3fb9033263d13bd429cde695f97a9a829b98b24e\"}}, \"createTime\": \"2019-02-26T19:03:19.686921Z\"}}, \"path_config\": \"generic\", \"bot_id\": \"win10-727f49a0-us-west1-c-gw0h\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1551207800846453",
"status": "COMPLETED",
"status_changed_ts": "1551214343006699",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/12217",
"builder:Windows deterministic",
"buildset:commit/git/3fb9033263d13bd429cde695f97a9a829b98b24e",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/3fb9033263d13bd429cde695f97a9a829b98b24e",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9085864508137226960",
"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/8920434446194379584/+/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:4344022543ebdc10",
"user_agent:luci-scheduler"
],
"updated_ts": "1551214343007903",
"url": "https://ci.chromium.org/b/8920434446194379584",
"utcnow_ts": "1551214343476888"
}
}
[20:52:23.490] Invocation finished in 1h49m5.040207043s with status SUCCEEDED