[22:53:10.151] New invocation is queued and will start shortly
[22:53:11.199] Starting the invocation (attempt 1)
[22:53:11.235] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[22:53:11.235] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[22:53:11.235] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9084309898078259872",
"parameters_json": "{\"builder_name\":\"Windows deterministic\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src.git\",\"revision\":\"82fb8ada44d5767278bcb0f99c1177cfff54113b\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Windows deterministic",
"scheduler_invocation_id:9084309898078259872",
"scheduler_job_id:chromium/Windows deterministic",
"user_agent:luci-scheduler",
"buildset:commit/git/82fb8ada44d5767278bcb0f99c1177cfff54113b",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/82fb8ada44d5767278bcb0f99c1177cfff54113b",
"gitiles_ref:refs/heads/master"
]
}
[22:53:13.487] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1552690391356601",
"id": "8918879836151629968",
"lease_expiration_ts": "1552809192998308",
"lease_key": "2089122466",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"82fb8ada44d5767278bcb0f99c1177cfff54113b\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1552690393262608",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/12443",
"builder:Windows deterministic",
"buildset:commit/git/82fb8ada44d5767278bcb0f99c1177cfff54113b",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/82fb8ada44d5767278bcb0f99c1177cfff54113b",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9084309898078259872",
"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/8918879836151629968/+/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:439c60b61437f710",
"user_agent:luci-scheduler"
],
"updated_ts": "1552690393262705",
"url": "https://ci.chromium.org/b/8918879836151629968",
"utcnow_ts": "1552690393476080"
}
}
[22:53:13.487] Task URL: https://ci.chromium.org/b/8918879836151629968
[22:53:13.487] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:2:0) after 1m0s
[22:53:14.412] Received PubSub notification, asking Buildbucket for the build status
[22:53:14.514] Build 8918879836151629968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:54:13.505] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:2:0)
[22:54:13.505] Timer tick, asking Buildbucket for the build status
[22:54:13.575] Build 8918879836151629968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:54:13.575] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:4:0) after 1m0s
[22:55:13.586] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:4:0)
[22:55:13.586] Timer tick, asking Buildbucket for the build status
[22:55:13.669] Build 8918879836151629968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:55:13.669] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:5:0) after 1m0s
[22:56:13.689] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:5:0)
[22:56:13.689] Timer tick, asking Buildbucket for the build status
[22:56:13.735] Build 8918879836151629968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:56:13.735] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:6:0) after 1m0s
[22:57:13.727] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:6:0)
[22:57:13.728] Timer tick, asking Buildbucket for the build status
[22:57:13.772] Build 8918879836151629968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:57:13.772] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:7:0) after 1m0s
[22:58:13.778] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:7:0)
[22:58:13.778] Timer tick, asking Buildbucket for the build status
[22:58:13.894] Build 8918879836151629968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:58:13.894] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:8:0) after 1m0s
[22:59:13.908] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:8:0)
[22:59:13.908] Timer tick, asking Buildbucket for the build status
[22:59:14.102] Build 8918879836151629968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:59:14.102] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:9:0) after 1m0s
[23:00:14.131] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:9:0)
[23:00:14.131] Timer tick, asking Buildbucket for the build status
[23:00:14.198] Build 8918879836151629968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:00:14.198] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:10:0) after 1m0s
[23:01:14.178] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:10:0)
[23:01:14.178] Timer tick, asking Buildbucket for the build status
[23:01:14.240] Build 8918879836151629968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:01:14.240] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:11:0) after 1m0s
[23:02:14.266] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:11:0)
[23:02:14.266] Timer tick, asking Buildbucket for the build status
[23:02:14.396] Build 8918879836151629968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:02:14.396] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:12:0) after 1m0s
[23:03:14.397] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:12:0)
[23:03:14.397] Timer tick, asking Buildbucket for the build status
[23:03:14.433] Build 8918879836151629968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:03:14.433] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:13:0) after 1m0s
[23:04:14.459] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:13:0)
[23:04:14.459] Timer tick, asking Buildbucket for the build status
[23:04:14.509] Build 8918879836151629968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:04:14.509] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:14:0) after 1m0s
[23:05:14.489] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:14:0)
[23:05:14.489] Timer tick, asking Buildbucket for the build status
[23:05:14.548] Build 8918879836151629968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:05:14.548] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:15:0) after 1m0s
[23:06:14.545] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:15:0)
[23:06:14.546] Timer tick, asking Buildbucket for the build status
[23:06:14.626] Build 8918879836151629968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:06:14.626] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:16:0) after 1m0s
[23:07:14.599] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:16:0)
[23:07:14.599] Timer tick, asking Buildbucket for the build status
[23:07:14.680] Build 8918879836151629968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:07:14.680] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:17:0) after 1m0s
[23:08:14.698] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:17:0)
[23:08:14.698] Timer tick, asking Buildbucket for the build status
[23:08:14.820] Build 8918879836151629968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:08:14.820] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:18:0) after 1m0s
[23:09:14.830] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:18:0)
[23:09:14.830] Timer tick, asking Buildbucket for the build status
[23:09:14.893] Build 8918879836151629968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:09:14.893] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:19:0) after 1m0s
[23:10:14.905] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:19:0)
[23:10:14.905] Timer tick, asking Buildbucket for the build status
[23:10:15.068] Build 8918879836151629968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:10:15.068] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:20:0) after 1m0s
[23:11:15.079] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:20:0)
[23:11:15.079] Timer tick, asking Buildbucket for the build status
[23:11:15.239] Build 8918879836151629968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:11:15.239] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:21:0) after 1m0s
[23:12:15.252] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:21:0)
[23:12:15.252] Timer tick, asking Buildbucket for the build status
[23:12:15.419] Build 8918879836151629968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:12:15.419] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:22:0) after 1m0s
[23:13:15.444] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:22:0)
[23:13:15.444] Timer tick, asking Buildbucket for the build status
[23:13:15.502] Build 8918879836151629968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:13:15.502] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:23:0) after 1m0s
[23:14:15.480] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:23:0)
[23:14:15.480] Timer tick, asking Buildbucket for the build status
[23:14:15.600] Build 8918879836151629968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:14:15.600] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:24:0) after 1m0s
[23:15:15.623] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:24:0)
[23:15:15.623] Timer tick, asking Buildbucket for the build status
[23:15:15.858] Build 8918879836151629968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:15:15.858] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:25:0) after 1m0s
[23:16:15.870] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:25:0)
[23:16:15.870] Timer tick, asking Buildbucket for the build status
[23:16:15.909] Build 8918879836151629968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:16:15.909] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:26:0) after 1m0s
[23:17:15.985] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:26:0)
[23:17:15.985] Timer tick, asking Buildbucket for the build status
[23:17:16.036] Build 8918879836151629968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:17:16.036] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:27:0) after 1m0s
[23:18:16.117] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:27:0)
[23:18:16.117] Timer tick, asking Buildbucket for the build status
[23:18:16.214] Build 8918879836151629968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:18:16.214] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:28:0) after 1m0s
[23:19:16.248] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:28:0)
[23:19:16.248] Timer tick, asking Buildbucket for the build status
[23:19:16.393] Build 8918879836151629968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:19:16.393] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:29:0) after 1m0s
[23:20:16.398] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:29:0)
[23:20:16.398] Timer tick, asking Buildbucket for the build status
[23:20:16.451] Build 8918879836151629968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:20:16.451] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:30:0) after 1m0s
[23:21:16.477] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:30:0)
[23:21:16.477] Timer tick, asking Buildbucket for the build status
[23:21:16.666] Build 8918879836151629968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:21:16.666] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:31:0) after 1m0s
[23:22:16.664] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:31:0)
[23:22:16.664] Timer tick, asking Buildbucket for the build status
[23:22:16.706] Build 8918879836151629968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:22:16.706] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:32:0) after 1m0s
[23:23:16.726] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:32:0)
[23:23:16.726] Timer tick, asking Buildbucket for the build status
[23:23:16.810] Build 8918879836151629968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:23:16.810] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:33:0) after 1m0s
[23:24:16.810] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:33:0)
[23:24:16.810] Timer tick, asking Buildbucket for the build status
[23:24:16.867] Build 8918879836151629968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:24:16.867] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:34:0) after 1m0s
[23:25:16.862] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:34:0)
[23:25:16.862] Timer tick, asking Buildbucket for the build status
[23:25:16.895] Build 8918879836151629968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:25:16.895] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:35:0) after 1m0s
[23:26:16.869] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:35:0)
[23:26:16.869] Timer tick, asking Buildbucket for the build status
[23:26:16.924] Build 8918879836151629968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:26:16.924] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:36:0) after 1m0s
[23:27:16.922] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:36:0)
[23:27:16.922] Timer tick, asking Buildbucket for the build status
[23:27:16.970] Build 8918879836151629968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:27:16.970] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:37:0) after 1m0s
[23:28:16.953] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:37:0)
[23:28:16.953] Timer tick, asking Buildbucket for the build status
[23:28:16.993] Build 8918879836151629968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:28:16.993] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:38:0) after 1m0s
[23:29:17.016] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:38:0)
[23:29:17.016] Timer tick, asking Buildbucket for the build status
[23:29:17.075] Build 8918879836151629968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:29:17.075] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:39:0) after 1m0s
[23:30:17.052] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:39:0)
[23:30:17.052] Timer tick, asking Buildbucket for the build status
[23:30:17.183] Build 8918879836151629968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:30:17.183] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:40:0) after 1m0s
[23:31:17.182] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:40:0)
[23:31:17.182] Timer tick, asking Buildbucket for the build status
[23:31:17.382] Build 8918879836151629968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:31:17.382] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:41:0) after 1m0s
[23:32:17.367] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:41:0)
[23:32:17.367] Timer tick, asking Buildbucket for the build status
[23:32:17.413] Build 8918879836151629968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:32:17.413] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:42:0) after 1m0s
[23:33:17.417] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:42:0)
[23:33:17.417] Timer tick, asking Buildbucket for the build status
[23:33:17.598] Build 8918879836151629968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:33:17.598] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:43:0) after 1m0s
[23:34:17.578] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:43:0)
[23:34:17.578] Timer tick, asking Buildbucket for the build status
[23:34:17.802] Build 8918879836151629968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:34:17.802] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:44:0) after 1m0s
[23:35:17.775] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:44:0)
[23:35:17.775] Timer tick, asking Buildbucket for the build status
[23:35:17.822] Build 8918879836151629968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:35:17.822] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:45:0) after 1m0s
[23:36:17.848] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:45:0)
[23:36:17.848] Timer tick, asking Buildbucket for the build status
[23:36:17.985] Build 8918879836151629968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:36:17.985] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:46:0) after 1m0s
[23:37:17.990] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:46:0)
[23:37:17.990] Timer tick, asking Buildbucket for the build status
[23:37:18.071] Build 8918879836151629968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:37:18.071] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:47:0) after 1m0s
[23:38:18.089] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:47:0)
[23:38:18.089] Timer tick, asking Buildbucket for the build status
[23:38:18.322] Build 8918879836151629968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:38:18.322] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:48:0) after 1m0s
[23:39:18.314] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:48:0)
[23:39:18.314] Timer tick, asking Buildbucket for the build status
[23:39:18.573] Build 8918879836151629968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:39:18.573] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:49:0) after 1m0s
[23:40:18.575] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:49:0)
[23:40:18.575] Timer tick, asking Buildbucket for the build status
[23:40:18.621] Build 8918879836151629968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:40:18.621] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:50:0) after 1m0s
[23:41:18.665] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:50:0)
[23:41:18.665] Timer tick, asking Buildbucket for the build status
[23:41:18.764] Build 8918879836151629968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:41:18.764] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:51:0) after 1m0s
[23:42:18.759] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:51:0)
[23:42:18.759] Timer tick, asking Buildbucket for the build status
[23:42:18.876] Build 8918879836151629968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:42:18.876] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:52:0) after 1m0s
[23:43:18.927] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:52:0)
[23:43:18.927] Timer tick, asking Buildbucket for the build status
[23:43:19.066] Build 8918879836151629968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:43:19.066] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:53:0) after 1m0s
[23:44:19.048] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:53:0)
[23:44:19.048] Timer tick, asking Buildbucket for the build status
[23:44:19.202] Build 8918879836151629968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:44:19.202] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:54:0) after 1m0s
[23:45:19.211] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:54:0)
[23:45:19.211] Timer tick, asking Buildbucket for the build status
[23:45:19.470] Build 8918879836151629968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:45:19.470] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:55:0) after 1m0s
[23:46:19.480] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:55:0)
[23:46:19.480] Timer tick, asking Buildbucket for the build status
[23:46:19.579] Build 8918879836151629968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:46:19.579] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:56:0) after 1m0s
[23:47:19.564] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:56:0)
[23:47:19.564] Timer tick, asking Buildbucket for the build status
[23:47:19.616] Build 8918879836151629968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:47:19.616] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:57:0) after 1m0s
[23:48:19.615] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:57:0)
[23:48:19.615] Timer tick, asking Buildbucket for the build status
[23:48:19.657] Build 8918879836151629968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:48:19.657] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:58:0) after 1m0s
[23:49:19.674] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:58:0)
[23:49:19.674] Timer tick, asking Buildbucket for the build status
[23:49:19.853] Build 8918879836151629968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:49:19.853] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:59:0) after 1m0s
[23:50:19.872] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:59:0)
[23:50:19.872] Timer tick, asking Buildbucket for the build status
[23:50:19.988] Build 8918879836151629968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:50:19.988] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:60:0) after 1m0s
[23:51:20.022] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:60:0)
[23:51:20.022] Timer tick, asking Buildbucket for the build status
[23:51:20.211] Build 8918879836151629968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:51:20.211] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:61:0) after 1m0s
[23:52:20.258] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:61:0)
[23:52:20.258] Timer tick, asking Buildbucket for the build status
[23:52:20.359] Build 8918879836151629968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:52:20.359] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:62:0) after 1m0s
[23:53:20.391] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:62:0)
[23:53:20.391] Timer tick, asking Buildbucket for the build status
[23:53:20.473] Build 8918879836151629968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:53:20.473] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:63:0) after 1m0s
[23:54:20.490] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:63:0)
[23:54:20.490] Timer tick, asking Buildbucket for the build status
[23:54:20.527] Build 8918879836151629968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:54:20.527] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:64:0) after 1m0s
[23:55:20.529] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:64:0)
[23:55:20.529] Timer tick, asking Buildbucket for the build status
[23:55:20.609] Build 8918879836151629968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:55:20.610] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:65:0) after 1m0s
[23:56:20.611] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:65:0)
[23:56:20.611] Timer tick, asking Buildbucket for the build status
[23:56:20.643] Build 8918879836151629968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:56:20.643] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:66:0) after 1m0s
[23:57:20.669] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:66:0)
[23:57:20.669] Timer tick, asking Buildbucket for the build status
[23:57:20.743] Build 8918879836151629968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:57:20.743] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:67:0) after 1m0s
[23:58:20.738] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:67:0)
[23:58:20.738] Timer tick, asking Buildbucket for the build status
[23:58:20.775] Build 8918879836151629968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:58:20.775] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:68:0) after 1m0s
[23:59:20.795] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:68:0)
[23:59:20.795] Timer tick, asking Buildbucket for the build status
[23:59:20.900] Build 8918879836151629968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:59:20.900] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:69:0) after 1m0s
[00:00:20.923] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:69:0)
[00:00:20.923] Timer tick, asking Buildbucket for the build status
[00:00:21.263] Build 8918879836151629968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:00:21.263] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:70:0) after 1m0s
[00:01:21.288] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:70:0)
[00:01:21.288] Timer tick, asking Buildbucket for the build status
[00:01:21.352] Build 8918879836151629968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:01:21.352] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:71:0) after 1m0s
[00:02:21.381] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:71:0)
[00:02:21.381] Timer tick, asking Buildbucket for the build status
[00:02:21.425] Build 8918879836151629968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:02:21.425] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:72:0) after 1m0s
[00:03:21.448] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:72:0)
[00:03:21.448] Timer tick, asking Buildbucket for the build status
[00:03:21.510] Build 8918879836151629968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:03:21.510] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:73:0) after 1m0s
[00:04:21.530] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:73:0)
[00:04:21.530] Timer tick, asking Buildbucket for the build status
[00:04:21.641] Build 8918879836151629968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:04:21.641] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:74:0) after 1m0s
[00:05:21.674] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:74:0)
[00:05:21.674] Timer tick, asking Buildbucket for the build status
[00:05:21.820] Build 8918879836151629968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:05:21.820] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:75:0) after 1m0s
[00:06:21.819] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:75:0)
[00:06:21.819] Timer tick, asking Buildbucket for the build status
[00:06:21.880] Build 8918879836151629968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:06:21.881] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:76:0) after 1m0s
[00:07:21.905] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:76:0)
[00:07:21.905] Timer tick, asking Buildbucket for the build status
[00:07:21.947] Build 8918879836151629968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:07:21.947] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:77:0) after 1m0s
[00:08:21.961] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:77:0)
[00:08:21.961] Timer tick, asking Buildbucket for the build status
[00:08:22.011] Build 8918879836151629968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:08:22.011] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:78:0) after 1m0s
[00:09:21.999] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:78:0)
[00:09:21.999] Timer tick, asking Buildbucket for the build status
[00:09:22.080] Build 8918879836151629968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:09:22.080] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:79:0) after 1m0s
[00:10:22.124] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:79:0)
[00:10:22.124] Timer tick, asking Buildbucket for the build status
[00:10:22.403] Build 8918879836151629968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:10:22.403] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:80:0) after 1m0s
[00:11:22.411] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:80:0)
[00:11:22.411] Timer tick, asking Buildbucket for the build status
[00:11:22.851] Build 8918879836151629968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:11:22.851] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:81:0) after 1m0s
[00:12:22.873] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:81:0)
[00:12:22.873] Timer tick, asking Buildbucket for the build status
[00:12:22.927] Build 8918879836151629968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:12:22.927] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:82:0) after 1m0s
[00:13:22.955] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:82:0)
[00:13:22.955] Timer tick, asking Buildbucket for the build status
[00:13:23.177] Build 8918879836151629968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:13:23.177] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:83:0) after 1m0s
[00:14:23.159] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:83:0)
[00:14:23.159] Timer tick, asking Buildbucket for the build status
[00:14:23.216] Build 8918879836151629968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:14:23.216] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:84:0) after 1m0s
[00:15:23.202] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:84:0)
[00:15:23.202] Timer tick, asking Buildbucket for the build status
[00:15:23.261] Build 8918879836151629968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:15:23.261] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:85:0) after 1m0s
[00:16:23.262] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:85:0)
[00:16:23.262] Timer tick, asking Buildbucket for the build status
[00:16:23.302] Build 8918879836151629968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:16:23.302] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:86:0) after 1m0s
[00:17:23.349] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:86:0)
[00:17:23.349] Timer tick, asking Buildbucket for the build status
[00:17:23.461] Build 8918879836151629968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:17:23.461] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:87:0) after 1m0s
[00:18:23.483] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:87:0)
[00:18:23.483] Timer tick, asking Buildbucket for the build status
[00:18:23.532] Build 8918879836151629968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:18:23.532] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:88:0) after 1m0s
[00:19:23.591] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:88:0)
[00:19:23.591] Timer tick, asking Buildbucket for the build status
[00:19:23.783] Build 8918879836151629968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:19:23.783] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:89:0) after 1m0s
[00:20:23.800] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:89:0)
[00:20:23.800] Timer tick, asking Buildbucket for the build status
[00:20:23.839] Build 8918879836151629968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:20:23.839] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:90:0) after 1m0s
[00:21:23.851] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:90:0)
[00:21:23.851] Timer tick, asking Buildbucket for the build status
[00:21:23.969] Build 8918879836151629968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:21:23.969] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:91:0) after 1m0s
[00:22:23.997] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:91:0)
[00:22:23.998] Timer tick, asking Buildbucket for the build status
[00:22:24.059] Build 8918879836151629968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:22:24.059] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:92:0) after 1m0s
[00:23:24.107] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:92:0)
[00:23:24.107] Timer tick, asking Buildbucket for the build status
[00:23:24.179] Build 8918879836151629968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:23:24.179] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:93:0) after 1m0s
[00:24:24.219] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:93:0)
[00:24:24.220] Timer tick, asking Buildbucket for the build status
[00:24:24.267] Build 8918879836151629968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:24:24.267] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:94:0) after 1m0s
[00:25:24.266] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:94:0)
[00:25:24.266] Timer tick, asking Buildbucket for the build status
[00:25:24.589] Build 8918879836151629968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:25:24.589] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:95:0) after 1m0s
[00:26:24.600] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:95:0)
[00:26:24.600] Timer tick, asking Buildbucket for the build status
[00:26:24.738] Build 8918879836151629968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:26:24.738] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:96:0) after 1m0s
[00:27:24.754] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:96:0)
[00:27:24.754] Timer tick, asking Buildbucket for the build status
[00:27:25.123] Build 8918879836151629968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:27:25.123] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:97:0) after 1m0s
[00:28:25.124] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:97:0)
[00:28:25.124] Timer tick, asking Buildbucket for the build status
[00:28:25.320] Build 8918879836151629968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:28:25.320] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:98:0) after 1m0s
[00:29:25.346] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:98:0)
[00:29:25.346] Timer tick, asking Buildbucket for the build status
[00:29:25.461] Build 8918879836151629968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:29:25.461] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:99:0) after 1m0s
[00:30:25.476] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:99:0)
[00:30:25.476] Timer tick, asking Buildbucket for the build status
[00:30:25.567] Build 8918879836151629968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:30:25.567] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:100:0) after 1m0s
[00:31:25.591] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:100:0)
[00:31:25.591] Timer tick, asking Buildbucket for the build status
[00:31:26.470] Build 8918879836151629968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:31:26.470] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:101:0) after 1m0s
[00:32:26.519] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:101:0)
[00:32:26.519] Timer tick, asking Buildbucket for the build status
[00:32:26.787] Build 8918879836151629968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:32:26.787] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:102:0) after 1m0s
[00:33:26.816] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:102:0)
[00:33:26.816] Timer tick, asking Buildbucket for the build status
[00:33:27.108] Build 8918879836151629968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:33:27.108] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:103:0) after 1m0s
[00:34:27.099] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:103:0)
[00:34:27.099] Timer tick, asking Buildbucket for the build status
[00:34:27.207] Build 8918879836151629968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:34:27.207] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:104:0) after 1m0s
[00:35:27.214] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:104:0)
[00:35:27.214] Timer tick, asking Buildbucket for the build status
[00:35:27.679] Build 8918879836151629968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:35:27.679] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:105:0) after 1m0s
[00:36:27.706] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:105:0)
[00:36:27.706] Timer tick, asking Buildbucket for the build status
[00:36:27.819] Build 8918879836151629968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:36:27.820] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:106:0) after 1m0s
[00:37:27.834] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:106:0)
[00:37:27.834] Timer tick, asking Buildbucket for the build status
[00:37:27.921] Build 8918879836151629968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:37:27.921] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:107:0) after 1m0s
[00:38:28.015] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:107:0)
[00:38:28.015] Timer tick, asking Buildbucket for the build status
[00:38:28.261] Build 8918879836151629968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:38:28.261] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:108:0) after 1m0s
[00:39:28.290] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:108:0)
[00:39:28.290] Timer tick, asking Buildbucket for the build status
[00:39:28.998] Build 8918879836151629968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:39:28.998] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:109:0) after 1m0s
[00:40:29.016] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:109:0)
[00:40:29.016] Timer tick, asking Buildbucket for the build status
[00:40:29.593] Build 8918879836151629968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:40:29.593] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:110:0) after 1m0s
[00:41:29.620] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:110:0)
[00:41:29.620] Timer tick, asking Buildbucket for the build status
[00:41:30.301] Build 8918879836151629968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:41:30.301] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:111:0) after 1m0s
[00:42:30.331] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:111:0)
[00:42:30.331] Timer tick, asking Buildbucket for the build status
[00:42:30.792] Build 8918879836151629968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:42:30.792] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:112:0) after 1m0s
[00:43:30.799] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:112:0)
[00:43:30.799] Timer tick, asking Buildbucket for the build status
[00:43:30.911] Build 8918879836151629968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:43:30.911] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:113:0) after 1m0s
[00:44:30.923] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:113:0)
[00:44:30.923] Timer tick, asking Buildbucket for the build status
[00:44:31.578] Build 8918879836151629968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:44:31.578] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:114:0) after 1m0s
[00:45:31.660] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:114:0)
[00:45:31.660] Timer tick, asking Buildbucket for the build status
[00:45:31.771] Build 8918879836151629968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:45:31.771] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:115:0) after 1m0s
[00:46:31.773] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:115:0)
[00:46:31.773] Timer tick, asking Buildbucket for the build status
[00:46:31.892] Build 8918879836151629968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:46:31.892] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:116:0) after 1m0s
[00:47:31.884] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:116:0)
[00:47:31.884] Timer tick, asking Buildbucket for the build status
[00:47:31.995] Build 8918879836151629968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:47:31.995] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:117:0) after 1m0s
[00:48:32.011] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:117:0)
[00:48:32.011] Timer tick, asking Buildbucket for the build status
[00:48:32.192] Build 8918879836151629968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:48:32.192] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:118:0) after 1m0s
[00:49:32.197] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:118:0)
[00:49:32.197] Timer tick, asking Buildbucket for the build status
[00:49:32.309] Build 8918879836151629968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:49:32.309] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:119:0) after 1m0s
[00:50:32.320] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:119:0)
[00:50:32.320] Timer tick, asking Buildbucket for the build status
[00:50:33.584] Build 8918879836151629968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:50:33.584] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:120:0) after 1m0s
[00:51:33.614] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:120:0)
[00:51:33.614] Timer tick, asking Buildbucket for the build status
[00:51:34.372] Build 8918879836151629968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:51:34.372] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:121:0) after 1m0s
[00:52:34.398] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:121:0)
[00:52:34.398] Timer tick, asking Buildbucket for the build status
[00:52:34.796] Build 8918879836151629968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:52:34.796] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:122:0) after 1m0s
[00:53:34.801] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:122:0)
[00:53:34.801] Timer tick, asking Buildbucket for the build status
[00:53:34.933] Build 8918879836151629968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:53:34.933] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:123:0) after 1m0s
[00:54:34.918] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:123:0)
[00:54:34.918] Timer tick, asking Buildbucket for the build status
[00:54:35.770] Build 8918879836151629968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:54:35.770] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:124:0) after 1m0s
[00:55:35.772] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:124:0)
[00:55:35.772] Timer tick, asking Buildbucket for the build status
[00:55:36.182] Build 8918879836151629968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:55:36.182] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:125:0) after 1m0s
[00:56:36.209] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:125:0)
[00:56:36.209] Timer tick, asking Buildbucket for the build status
[00:56:36.293] Build 8918879836151629968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:56:36.293] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:126:0) after 1m0s
[00:57:36.318] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:126:0)
[00:57:36.318] Timer tick, asking Buildbucket for the build status
[00:57:36.440] Build 8918879836151629968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:57:36.440] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084309898078259872:127:0) after 1m0s
[00:58:34.856] Received PubSub notification, asking Buildbucket for the build status
[00:58:35.032] Build 8918879836151629968: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[00:58:35.032] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"completed_ts": "1552697914117624",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1552690391356601",
"id": "8918879836151629968",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"82fb8ada44d5767278bcb0f99c1177cfff54113b\"}}",
"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\": [\"windows-deterministic-0-v3qq\"], \"python\": [\"2.7.13\"], \"server_version\": [\"4201-b04f7f8\"], \"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\": {\"$recipe_engine/path\": {\"cache_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\cache\", \"temp_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\tmp\\\\rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"got_nacl_revision\": \"1ed9878b8eb68c1e0d0268754dafcf83e747fa8f\", \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"buildername\": \"Windows deterministic\", \"got_webrtc_revision\": \"35816cc9a150ad6a350bc18e8fe759f501fa3f73\", \"got_revision\": \"82fb8ada44d5767278bcb0f99c1177cfff54113b\", \"got_swarming_client_revision\": \"7a61cf37d6a0163f0ec02d495289a1d038e62457\", \"recipe\": \"swarming/deterministic_build\", \"mastername\": \"chromium.win\", \"got_angle_revision\": \"e41882aaf55d0b240020dbe094cdb5475011b0f3\", \"got_v8_revision\": \"1eab45067108b961de4117fde9584b3099e428e3\", \"got_revision_cp\": \"refs/heads/master@{#641336}\", \"got_dawn_revision\": \"931311700c7c4b76a15c23eee8792dda125fb97e\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#27144}\", \"buildnumber\": 12443, \"path_config\": \"generic\", \"revision\": \"82fb8ada44d5767278bcb0f99c1177cfff54113b\", \"got_v8_revision_cp\": \"refs/heads/7.5.54@{#1}\", \"bot_id\": \"windows-deterministic-0-v3qq\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1552690393470177",
"status": "COMPLETED",
"status_changed_ts": "1552697914546543",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/12443",
"builder:Windows deterministic",
"buildset:commit/git/82fb8ada44d5767278bcb0f99c1177cfff54113b",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/82fb8ada44d5767278bcb0f99c1177cfff54113b",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9084309898078259872",
"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/8918879836151629968/+/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:439c60b61437f710",
"user_agent:luci-scheduler"
],
"updated_ts": "1552697914550587",
"url": "https://ci.chromium.org/b/8918879836151629968",
"utcnow_ts": "1552697915023106"
}
}
[00:58:35.032] Invocation finished in 2h5m24.896707573s with status SUCCEEDED