[02:03:21.337] New invocation is queued and will start shortly
[02:03:22.825] Starting the invocation (attempt 1)
[02:03:22.866] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[02:03:22.866] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[02:03:22.866] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9068715254363957072",
"parameters_json": "{\"builder_name\":\"Windows deterministic\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src.git\",\"revision\":\"5bab9148b3b179454fddc6491a71416b029ea119\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Windows deterministic",
"scheduler_invocation_id:9068715254363957072",
"scheduler_job_id:chromium/Windows deterministic",
"user_agent:luci-scheduler",
"buildset:commit/git/5bab9148b3b179454fddc6491a71416b029ea119",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/5bab9148b3b179454fddc6491a71416b029ea119",
"gitiles_ref:refs/heads/master"
]
}
[02:03:24.025] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary": true,
"canary_preference": "CANARY",
"created_by": "project:chromium",
"created_ts": "1567562603383610",
"id": "8903285191553080560",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"5bab9148b3b179454fddc6491a71416b029ea119\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1567562603861771",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/14624",
"builder:Windows deterministic",
"buildset:commit/git/5bab9148b3b179454fddc6491a71416b029ea119",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/5bab9148b3b179454fddc6491a71416b029ea119",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9068715254363957072",
"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/8903285191553080560/+/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:",
"user_agent:luci-scheduler"
],
"updated_ts": "1567562603861927",
"url": "https://ci.chromium.org/b/8903285191553080560",
"utcnow_ts": "1567562604008764"
}
}
[02:03:24.025] Task URL: https://ci.chromium.org/b/8903285191553080560
[02:03:24.025] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:2:0) after 1m0s
[02:03:28.936] Received PubSub notification, asking Buildbucket for the build status
[02:03:29.176] Build 8903285191553080560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:04:24.124] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:2:0)
[02:04:24.124] Timer tick, asking Buildbucket for the build status
[02:04:26.852] Build 8903285191553080560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:04:26.852] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:4:0) after 1m0s
[02:05:26.995] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:4:0)
[02:05:26.995] Timer tick, asking Buildbucket for the build status
[02:05:27.262] Build 8903285191553080560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:05:27.262] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:5:0) after 1m0s
[02:06:27.311] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:5:0)
[02:06:27.311] Timer tick, asking Buildbucket for the build status
[02:06:27.563] Build 8903285191553080560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:06:27.563] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:6:0) after 1m0s
[02:07:27.726] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:6:0)
[02:07:27.726] Timer tick, asking Buildbucket for the build status
[02:07:27.987] Build 8903285191553080560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:07:27.987] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:7:0) after 1m0s
[02:08:28.083] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:7:0)
[02:08:28.083] Timer tick, asking Buildbucket for the build status
[02:08:31.152] Build 8903285191553080560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:08:31.152] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:8:0) after 1m0s
[02:09:31.195] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:8:0)
[02:09:31.195] Timer tick, asking Buildbucket for the build status
[02:09:31.851] Build 8903285191553080560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:09:31.851] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:9:0) after 1m0s
[02:10:31.915] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:9:0)
[02:10:31.915] Timer tick, asking Buildbucket for the build status
[02:10:32.197] Build 8903285191553080560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:10:32.197] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:10:0) after 1m0s
[02:11:32.256] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:10:0)
[02:11:32.256] Timer tick, asking Buildbucket for the build status
[02:11:32.927] Build 8903285191553080560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:11:32.927] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:11:0) after 1m0s
[02:12:32.950] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:11:0)
[02:12:32.950] Timer tick, asking Buildbucket for the build status
[02:12:33.454] Build 8903285191553080560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:12:33.454] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:12:0) after 1m0s
[02:13:33.444] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:12:0)
[02:13:33.444] Timer tick, asking Buildbucket for the build status
[02:13:33.626] Build 8903285191553080560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:13:33.626] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:13:0) after 1m0s
[02:14:33.654] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:13:0)
[02:14:33.654] Timer tick, asking Buildbucket for the build status
[02:14:34.156] Build 8903285191553080560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:14:34.156] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:14:0) after 1m0s
[02:15:34.283] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:14:0)
[02:15:34.283] Timer tick, asking Buildbucket for the build status
[02:15:36.325] Build 8903285191553080560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:15:36.325] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:15:0) after 1m0s
[02:16:36.406] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:15:0)
[02:16:36.406] Timer tick, asking Buildbucket for the build status
[02:16:36.962] Build 8903285191553080560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:16:36.962] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:16:0) after 1m0s
[02:17:37.094] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:16:0)
[02:17:37.094] Timer tick, asking Buildbucket for the build status
[02:17:37.315] Build 8903285191553080560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:17:37.315] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:17:0) after 1m0s
[02:18:37.319] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:17:0)
[02:18:37.319] Timer tick, asking Buildbucket for the build status
[02:18:37.506] Build 8903285191553080560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:18:37.506] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:18:0) after 1m0s
[02:19:37.525] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:18:0)
[02:19:37.525] Timer tick, asking Buildbucket for the build status
[02:19:37.740] Build 8903285191553080560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:19:37.740] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:19:0) after 1m0s
[02:20:37.764] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:19:0)
[02:20:37.764] Timer tick, asking Buildbucket for the build status
[02:20:38.108] Build 8903285191553080560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:20:38.108] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:20:0) after 1m0s
[02:21:38.211] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:20:0)
[02:21:38.211] Timer tick, asking Buildbucket for the build status
[02:21:38.779] Build 8903285191553080560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:21:38.779] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:21:0) after 1m0s
[02:22:38.816] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:21:0)
[02:22:38.816] Timer tick, asking Buildbucket for the build status
[02:22:39.117] Build 8903285191553080560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:22:39.117] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:22:0) after 1m0s
[02:23:39.161] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:22:0)
[02:23:39.161] Timer tick, asking Buildbucket for the build status
[02:23:39.594] Build 8903285191553080560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:23:39.594] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:23:0) after 1m0s
[02:24:39.639] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:23:0)
[02:24:39.639] Timer tick, asking Buildbucket for the build status
[02:24:41.513] Build 8903285191553080560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:24:41.513] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:24:0) after 1m0s
[02:25:41.569] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:24:0)
[02:25:41.569] Timer tick, asking Buildbucket for the build status
[02:25:41.768] Build 8903285191553080560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:25:41.768] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:25:0) after 1m0s
[02:26:41.822] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:25:0)
[02:26:41.822] Timer tick, asking Buildbucket for the build status
[02:26:42.051] Build 8903285191553080560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:26:42.052] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:26:0) after 1m0s
[02:27:42.156] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:26:0)
[02:27:42.180] Timer tick, asking Buildbucket for the build status
[02:27:42.352] Build 8903285191553080560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:27:42.352] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:27:0) after 1m0s
[02:28:42.593] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:27:0)
[02:28:42.593] Timer tick, asking Buildbucket for the build status
[02:28:42.950] Build 8903285191553080560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:28:42.950] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:28:0) after 1m0s
[02:29:42.972] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:28:0)
[02:29:42.972] Timer tick, asking Buildbucket for the build status
[02:29:43.402] Build 8903285191553080560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:29:43.402] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:29:0) after 1m0s
[02:30:43.523] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:29:0)
[02:30:43.523] Timer tick, asking Buildbucket for the build status
[02:30:43.825] Build 8903285191553080560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:30:43.825] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:30:0) after 1m0s
[02:31:43.909] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:30:0)
[02:31:43.909] Timer tick, asking Buildbucket for the build status
[02:31:44.378] Build 8903285191553080560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:31:44.378] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:31:0) after 1m0s
[02:32:44.437] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:31:0)
[02:32:44.437] Timer tick, asking Buildbucket for the build status
[02:32:44.614] Build 8903285191553080560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:32:44.614] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:32:0) after 1m0s
[02:33:44.669] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:32:0)
[02:33:44.669] Timer tick, asking Buildbucket for the build status
[02:33:45.414] Build 8903285191553080560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:33:45.414] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:33:0) after 1m0s
[02:34:45.429] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:33:0)
[02:34:45.429] Timer tick, asking Buildbucket for the build status
[02:34:45.605] Build 8903285191553080560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:34:45.605] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:34:0) after 1m0s
[02:35:45.631] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:34:0)
[02:35:45.631] Timer tick, asking Buildbucket for the build status
[02:35:46.514] Build 8903285191553080560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:35:46.514] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:35:0) after 1m0s
[02:36:46.712] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:35:0)
[02:36:46.712] Timer tick, asking Buildbucket for the build status
[02:36:47.108] Build 8903285191553080560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:36:47.108] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:36:0) after 1m0s
[02:37:47.195] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:36:0)
[02:37:47.195] Timer tick, asking Buildbucket for the build status
[02:37:47.341] Build 8903285191553080560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:37:47.341] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:37:0) after 1m0s
[02:38:47.397] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:37:0)
[02:38:47.397] Timer tick, asking Buildbucket for the build status
[02:38:47.679] Build 8903285191553080560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:38:47.679] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:38:0) after 1m0s
[02:39:47.697] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:38:0)
[02:39:47.697] Timer tick, asking Buildbucket for the build status
[02:39:47.955] Build 8903285191553080560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:39:47.955] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:39:0) after 1m0s
[02:40:48.016] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:39:0)
[02:40:48.016] Timer tick, asking Buildbucket for the build status
[02:40:48.138] Build 8903285191553080560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:40:48.138] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:40:0) after 1m0s
[02:41:48.196] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:40:0)
[02:41:48.196] Timer tick, asking Buildbucket for the build status
[02:41:48.787] Build 8903285191553080560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:41:48.787] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:41:0) after 1m0s
[02:42:48.832] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:41:0)
[02:42:48.832] Timer tick, asking Buildbucket for the build status
[02:42:50.955] Build 8903285191553080560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:42:50.955] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:42:0) after 1m0s
[02:43:51.089] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:42:0)
[02:43:51.089] Timer tick, asking Buildbucket for the build status
[02:43:51.330] Build 8903285191553080560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:43:51.330] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:43:0) after 1m0s
[02:44:51.379] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:43:0)
[02:44:51.379] Timer tick, asking Buildbucket for the build status
[02:44:51.894] Build 8903285191553080560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:44:51.894] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:44:0) after 1m0s
[02:45:52.025] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:44:0)
[02:45:52.025] Timer tick, asking Buildbucket for the build status
[02:45:52.657] Build 8903285191553080560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:45:52.657] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:45:0) after 1m0s
[02:46:52.700] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:45:0)
[02:46:52.700] Timer tick, asking Buildbucket for the build status
[02:46:53.046] Build 8903285191553080560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:46:53.046] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:46:0) after 1m0s
[02:47:53.189] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:46:0)
[02:47:53.189] Timer tick, asking Buildbucket for the build status
[02:47:53.484] Build 8903285191553080560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:47:53.484] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:47:0) after 1m0s
[02:48:53.513] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:47:0)
[02:48:53.513] Timer tick, asking Buildbucket for the build status
[02:48:53.742] Build 8903285191553080560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:48:53.742] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:48:0) after 1m0s
[02:49:53.831] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:48:0)
[02:49:53.831] Timer tick, asking Buildbucket for the build status
[02:49:54.269] Build 8903285191553080560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:49:54.269] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:49:0) after 1m0s
[02:50:54.355] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:49:0)
[02:50:54.355] Timer tick, asking Buildbucket for the build status
[02:50:54.577] Build 8903285191553080560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:50:54.577] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:50:0) after 1m0s
[02:51:54.909] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:50:0)
[02:51:54.909] Timer tick, asking Buildbucket for the build status
[02:51:55.110] Build 8903285191553080560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:51:55.111] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:51:0) after 1m0s
[02:52:55.168] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:51:0)
[02:52:55.168] Timer tick, asking Buildbucket for the build status
[02:52:55.658] Build 8903285191553080560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:52:55.658] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:52:0) after 1m0s
[02:53:55.690] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:52:0)
[02:53:55.690] Timer tick, asking Buildbucket for the build status
[02:53:55.847] Build 8903285191553080560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:53:55.847] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:53:0) after 1m0s
[02:54:55.877] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:53:0)
[02:54:55.877] Timer tick, asking Buildbucket for the build status
[02:54:56.028] Build 8903285191553080560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:54:56.028] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:54:0) after 1m0s
[02:55:56.099] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:54:0)
[02:55:56.099] Timer tick, asking Buildbucket for the build status
[02:55:57.142] Build 8903285191553080560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:55:57.142] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:55:0) after 1m0s
[02:56:57.247] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:55:0)
[02:56:57.247] Timer tick, asking Buildbucket for the build status
[02:56:57.933] Build 8903285191553080560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:56:57.933] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:56:0) after 1m0s
[02:57:57.920] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:56:0)
[02:57:57.920] Timer tick, asking Buildbucket for the build status
[02:57:58.197] Build 8903285191553080560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:57:58.197] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:57:0) after 1m0s
[02:58:58.230] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:57:0)
[02:58:58.230] Timer tick, asking Buildbucket for the build status
[02:58:58.464] Build 8903285191553080560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:58:58.464] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:58:0) after 1m0s
[02:59:58.507] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:58:0)
[02:59:58.507] Timer tick, asking Buildbucket for the build status
[02:59:58.947] Build 8903285191553080560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:59:58.947] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:59:0) after 1m0s
[03:00:58.944] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:59:0)
[03:00:58.944] Timer tick, asking Buildbucket for the build status
[03:00:59.453] Build 8903285191553080560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:00:59.453] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:60:0) after 1m0s
[03:01:59.541] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:60:0)
[03:01:59.541] Timer tick, asking Buildbucket for the build status
[03:01:59.764] Build 8903285191553080560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:01:59.764] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:61:0) after 1m0s
[03:02:59.773] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:61:0)
[03:02:59.773] Timer tick, asking Buildbucket for the build status
[03:02:59.984] Build 8903285191553080560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:02:59.984] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:62:0) after 1m0s
[03:04:00.137] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:62:0)
[03:04:00.137] Timer tick, asking Buildbucket for the build status
[03:04:00.387] Build 8903285191553080560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:04:00.387] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:63:0) after 1m0s
[03:05:00.376] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:63:0)
[03:05:00.376] Timer tick, asking Buildbucket for the build status
[03:05:01.204] Build 8903285191553080560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:05:01.205] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:64:0) after 1m0s
[03:06:01.296] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:64:0)
[03:06:01.296] Timer tick, asking Buildbucket for the build status
[03:06:02.395] Build 8903285191553080560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:06:02.395] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:65:0) after 1m0s
[03:07:02.472] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:65:0)
[03:07:02.472] Timer tick, asking Buildbucket for the build status
[03:07:03.275] Build 8903285191553080560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:07:03.275] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:66:0) after 1m0s
[03:08:03.283] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:66:0)
[03:08:03.283] Timer tick, asking Buildbucket for the build status
[03:08:03.485] Build 8903285191553080560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:08:03.485] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:67:0) after 1m0s
[03:09:03.509] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:67:0)
[03:09:03.509] Timer tick, asking Buildbucket for the build status
[03:09:03.714] Build 8903285191553080560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:09:03.714] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:68:0) after 1m0s
[03:10:03.813] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:68:0)
[03:10:03.813] Timer tick, asking Buildbucket for the build status
[03:10:04.204] Build 8903285191553080560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:10:04.204] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:69:0) after 1m0s
[03:11:04.498] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:69:0)
[03:11:04.498] Timer tick, asking Buildbucket for the build status
[03:11:04.672] Build 8903285191553080560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:11:04.672] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:70:0) after 1m0s
[03:12:04.710] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:70:0)
[03:12:04.710] Timer tick, asking Buildbucket for the build status
[03:12:05.304] Build 8903285191553080560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:12:05.304] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:71:0) after 1m0s
[03:13:05.376] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:71:0)
[03:13:05.376] Timer tick, asking Buildbucket for the build status
[03:13:05.770] Build 8903285191553080560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:13:05.770] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:72:0) after 1m0s
[03:14:05.833] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:72:0)
[03:14:05.833] Timer tick, asking Buildbucket for the build status
[03:14:06.047] Build 8903285191553080560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:14:06.047] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:73:0) after 1m0s
[03:15:06.039] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:73:0)
[03:15:06.039] Timer tick, asking Buildbucket for the build status
[03:15:09.032] Build 8903285191553080560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:15:09.032] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:74:0) after 1m0s
[03:16:09.058] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:74:0)
[03:16:09.058] Timer tick, asking Buildbucket for the build status
[03:16:09.328] Build 8903285191553080560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:16:09.328] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:75:0) after 1m0s
[03:17:09.354] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:75:0)
[03:17:09.354] Timer tick, asking Buildbucket for the build status
[03:17:09.558] Build 8903285191553080560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:17:09.558] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:76:0) after 1m0s
[03:18:09.589] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:76:0)
[03:18:09.589] Timer tick, asking Buildbucket for the build status
[03:18:09.960] Build 8903285191553080560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:18:09.960] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:77:0) after 1m0s
[03:19:10.046] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:77:0)
[03:19:10.046] Timer tick, asking Buildbucket for the build status
[03:19:10.287] Build 8903285191553080560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:19:10.287] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:78:0) after 1m0s
[03:20:10.430] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:78:0)
[03:20:10.430] Timer tick, asking Buildbucket for the build status
[03:20:11.015] Build 8903285191553080560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:20:11.015] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:79:0) after 1m0s
[03:21:11.114] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:79:0)
[03:21:11.114] Timer tick, asking Buildbucket for the build status
[03:21:11.518] Build 8903285191553080560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:21:11.518] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:80:0) after 1m0s
[03:22:11.649] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:80:0)
[03:22:11.649] Timer tick, asking Buildbucket for the build status
[03:22:11.882] Build 8903285191553080560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:22:11.882] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:81:0) after 1m0s
[03:23:12.067] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:81:0)
[03:23:12.067] Timer tick, asking Buildbucket for the build status
[03:23:12.512] Build 8903285191553080560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:23:12.512] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:82:0) after 1m0s
[03:24:12.588] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:82:0)
[03:24:12.588] Timer tick, asking Buildbucket for the build status
[03:24:12.835] Build 8903285191553080560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:24:12.835] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:83:0) after 1m0s
[03:25:12.897] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:83:0)
[03:25:12.897] Timer tick, asking Buildbucket for the build status
[03:25:13.295] Build 8903285191553080560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:25:13.295] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:84:0) after 1m0s
[03:26:13.327] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:84:0)
[03:26:13.327] Timer tick, asking Buildbucket for the build status
[03:26:16.257] Build 8903285191553080560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:26:16.257] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:85:0) after 1m0s
[03:27:16.294] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:85:0)
[03:27:16.294] Timer tick, asking Buildbucket for the build status
[03:27:17.343] Build 8903285191553080560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:27:17.343] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:86:0) after 1m0s
[03:28:17.495] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:86:0)
[03:28:17.495] Timer tick, asking Buildbucket for the build status
[03:28:17.918] Build 8903285191553080560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:28:17.919] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:87:0) after 1m0s
[03:29:18.033] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:87:0)
[03:29:18.033] Timer tick, asking Buildbucket for the build status
[03:29:18.262] Build 8903285191553080560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:29:18.262] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:88:0) after 1m0s
[03:30:18.293] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:88:0)
[03:30:18.293] Timer tick, asking Buildbucket for the build status
[03:30:18.797] Build 8903285191553080560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:30:18.797] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:89:0) after 1m0s
[03:31:18.898] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:89:0)
[03:31:18.898] Timer tick, asking Buildbucket for the build status
[03:31:20.233] Build 8903285191553080560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:31:20.233] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:90:0) after 1m0s
[03:32:20.319] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:90:0)
[03:32:20.319] Timer tick, asking Buildbucket for the build status
[03:32:20.540] Build 8903285191553080560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:32:20.540] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:91:0) after 1m0s
[03:33:20.532] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:91:0)
[03:33:20.532] Timer tick, asking Buildbucket for the build status
[03:33:20.765] Build 8903285191553080560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:33:20.765] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:92:0) after 1m0s
[03:34:20.801] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:92:0)
[03:34:20.801] Timer tick, asking Buildbucket for the build status
[03:34:22.378] Build 8903285191553080560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:34:22.378] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:93:0) after 1m0s
[03:35:22.451] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:93:0)
[03:35:22.451] Timer tick, asking Buildbucket for the build status
[03:35:22.928] Build 8903285191553080560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:35:22.928] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:94:0) after 1m0s
[03:36:23.125] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:94:0)
[03:36:23.125] Timer tick, asking Buildbucket for the build status
[03:36:23.517] Build 8903285191553080560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:36:23.517] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:95:0) after 1m0s
[03:37:23.544] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:95:0)
[03:37:23.544] Timer tick, asking Buildbucket for the build status
[03:37:24.422] Build 8903285191553080560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:37:24.422] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:96:0) after 1m0s
[03:38:24.541] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:96:0)
[03:38:24.541] Timer tick, asking Buildbucket for the build status
[03:38:24.771] Build 8903285191553080560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:38:24.771] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:97:0) after 1m0s
[03:39:24.939] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:97:0)
[03:39:24.939] Timer tick, asking Buildbucket for the build status
[03:39:25.160] Build 8903285191553080560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:39:25.160] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:98:0) after 1m0s
[03:40:25.172] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:98:0)
[03:40:25.172] Timer tick, asking Buildbucket for the build status
[03:40:25.394] Build 8903285191553080560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:40:25.394] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:99:0) after 1m0s
[03:41:25.468] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:99:0)
[03:41:25.468] Timer tick, asking Buildbucket for the build status
[03:41:26.221] Build 8903285191553080560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:41:26.221] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:100:0) after 1m0s
[03:42:26.348] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:100:0)
[03:42:26.348] Timer tick, asking Buildbucket for the build status
[03:42:26.506] Build 8903285191553080560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:42:26.506] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:101:0) after 1m0s
[03:43:26.604] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:101:0)
[03:43:26.604] Timer tick, asking Buildbucket for the build status
[03:43:27.041] Build 8903285191553080560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:43:27.041] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:102:0) after 1m0s
[03:44:27.115] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:102:0)
[03:44:27.115] Timer tick, asking Buildbucket for the build status
[03:44:27.328] Build 8903285191553080560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:44:27.328] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:103:0) after 1m0s
[03:45:27.501] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:103:0)
[03:45:27.502] Timer tick, asking Buildbucket for the build status
[03:45:27.675] Build 8903285191553080560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:45:27.675] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:104:0) after 1m0s
[03:46:27.857] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:104:0)
[03:46:27.857] Timer tick, asking Buildbucket for the build status
[03:46:28.206] Build 8903285191553080560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:46:28.206] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:105:0) after 1m0s
[03:47:28.306] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:105:0)
[03:47:28.306] Timer tick, asking Buildbucket for the build status
[03:47:28.704] Build 8903285191553080560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:47:28.704] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:106:0) after 1m0s
[03:48:28.791] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:106:0)
[03:48:28.792] Timer tick, asking Buildbucket for the build status
[03:48:29.118] Build 8903285191553080560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:48:29.118] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:107:0) after 1m0s
[03:49:29.206] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:107:0)
[03:49:29.206] Timer tick, asking Buildbucket for the build status
[03:49:29.386] Build 8903285191553080560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:49:29.386] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:108:0) after 1m0s
[03:50:29.703] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:108:0)
[03:50:29.703] Timer tick, asking Buildbucket for the build status
[03:50:30.066] Build 8903285191553080560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:50:30.066] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:109:0) after 1m0s
[03:51:30.122] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:109:0)
[03:51:30.122] Timer tick, asking Buildbucket for the build status
[03:51:31.232] Build 8903285191553080560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:51:31.232] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:110:0) after 1m0s
[03:52:31.249] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:110:0)
[03:52:31.249] Timer tick, asking Buildbucket for the build status
[03:52:31.567] Build 8903285191553080560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:52:31.567] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:111:0) after 1m0s
[03:53:31.621] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:111:0)
[03:53:31.621] Timer tick, asking Buildbucket for the build status
[03:53:32.303] Build 8903285191553080560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:53:32.303] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:112:0) after 1m0s
[03:54:32.354] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:112:0)
[03:54:32.354] Timer tick, asking Buildbucket for the build status
[03:54:33.702] Build 8903285191553080560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:54:33.702] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:113:0) after 1m0s
[03:55:33.776] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:113:0)
[03:55:33.776] Timer tick, asking Buildbucket for the build status
[03:55:34.234] Build 8903285191553080560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:55:34.234] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:114:0) after 1m0s
[03:56:34.280] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:114:0)
[03:56:34.280] Timer tick, asking Buildbucket for the build status
[03:56:34.901] Build 8903285191553080560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:56:34.901] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:115:0) after 1m0s
[03:57:35.056] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:115:0)
[03:57:35.056] Timer tick, asking Buildbucket for the build status
[03:57:35.304] Build 8903285191553080560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:57:35.304] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:116:0) after 1m0s
[03:58:35.389] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:116:0)
[03:58:35.389] Timer tick, asking Buildbucket for the build status
[03:58:36.104] Build 8903285191553080560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:58:36.104] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:117:0) after 1m0s
[03:59:36.120] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:117:0)
[03:59:36.120] Timer tick, asking Buildbucket for the build status
[03:59:36.264] Build 8903285191553080560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:59:36.264] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:118:0) after 1m0s
[04:00:36.756] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:118:0)
[04:00:36.756] Timer tick, asking Buildbucket for the build status
[04:00:37.138] Build 8903285191553080560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:00:37.138] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:119:0) after 1m0s
[04:01:37.456] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:119:0)
[04:01:37.456] Timer tick, asking Buildbucket for the build status
[04:01:38.363] Build 8903285191553080560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:01:38.363] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:120:0) after 1m0s
[04:02:38.516] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:120:0)
[04:02:38.516] Timer tick, asking Buildbucket for the build status
[04:02:38.696] Build 8903285191553080560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:02:38.696] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:121:0) after 1m0s
[04:03:38.735] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:121:0)
[04:03:38.735] Timer tick, asking Buildbucket for the build status
[04:03:38.918] Build 8903285191553080560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:03:38.918] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:122:0) after 1m0s
[04:04:39.075] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:122:0)
[04:04:39.075] Timer tick, asking Buildbucket for the build status
[04:04:39.278] Build 8903285191553080560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:04:39.278] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:123:0) after 1m0s
[04:05:39.370] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:123:0)
[04:05:39.370] Timer tick, asking Buildbucket for the build status
[04:05:39.537] Build 8903285191553080560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:05:39.537] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:124:0) after 1m0s
[04:06:39.588] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:124:0)
[04:06:39.588] Timer tick, asking Buildbucket for the build status
[04:06:39.765] Build 8903285191553080560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:06:39.765] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:125:0) after 1m0s
[04:07:39.821] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:125:0)
[04:07:39.821] Timer tick, asking Buildbucket for the build status
[04:07:40.600] Build 8903285191553080560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:07:40.600] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:126:0) after 1m0s
[04:08:40.646] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:126:0)
[04:08:40.646] Timer tick, asking Buildbucket for the build status
[04:08:41.080] Build 8903285191553080560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:08:41.080] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:127:0) after 1m0s
[04:09:41.161] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:127:0)
[04:09:41.161] Timer tick, asking Buildbucket for the build status
[04:09:46.158] Build 8903285191553080560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:09:46.158] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:128:0) after 1m0s
[04:10:46.229] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:128:0)
[04:10:46.229] Timer tick, asking Buildbucket for the build status
[04:10:46.401] Build 8903285191553080560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:10:46.401] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:129:0) after 1m0s
[04:11:46.424] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:129:0)
[04:11:46.424] Timer tick, asking Buildbucket for the build status
[04:11:46.803] Build 8903285191553080560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:11:46.803] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:130:0) after 1m0s
[04:12:46.803] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:130:0)
[04:12:46.803] Timer tick, asking Buildbucket for the build status
[04:12:47.017] Build 8903285191553080560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:12:47.017] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:131:0) after 1m0s
[04:13:47.027] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:131:0)
[04:13:47.027] Timer tick, asking Buildbucket for the build status
[04:13:47.234] Build 8903285191553080560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:13:47.234] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:132:0) after 1m0s
[04:14:47.293] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:132:0)
[04:14:47.293] Timer tick, asking Buildbucket for the build status
[04:14:47.481] Build 8903285191553080560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:14:47.481] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:133:0) after 1m0s
[04:15:47.536] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:133:0)
[04:15:47.536] Timer tick, asking Buildbucket for the build status
[04:15:47.642] Build 8903285191553080560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:15:47.642] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:134:0) after 1m0s
[04:16:47.703] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:134:0)
[04:16:47.703] Timer tick, asking Buildbucket for the build status
[04:16:47.856] Build 8903285191553080560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:16:47.856] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:135:0) after 1m0s
[04:17:47.884] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:135:0)
[04:17:47.884] Timer tick, asking Buildbucket for the build status
[04:17:48.619] Build 8903285191553080560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:17:48.619] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:136:0) after 1m0s
[04:18:48.723] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:136:0)
[04:18:48.723] Timer tick, asking Buildbucket for the build status
[04:18:49.026] Build 8903285191553080560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:18:49.026] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:137:0) after 1m0s
[04:19:49.193] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:137:0)
[04:19:49.193] Timer tick, asking Buildbucket for the build status
[04:19:49.471] Build 8903285191553080560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:19:49.471] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:138:0) after 1m0s
[04:20:49.531] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:138:0)
[04:20:49.531] Timer tick, asking Buildbucket for the build status
[04:20:49.776] Build 8903285191553080560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:20:49.776] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068715254363957072:139:0) after 1m0s
[04:21:30.692] Received PubSub notification, asking Buildbucket for the build status
[04:21:30.844] Build 8903285191553080560: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[04:21:30.844] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary": true,
"canary_preference": "CANARY",
"completed_ts": "1567570890034309",
"created_by": "project:chromium",
"created_ts": "1567562603383610",
"id": "8903285191553080560",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"5bab9148b3b179454fddc6491a71416b029ea119\"}}",
"project": "chromium",
"result": "SUCCESS",
"result_details_json": "{\"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}, \"bot_id\": \"windows-deterministic-0-y5mg\", \"branch\": \"refs/heads/master\", \"buildername\": \"Windows deterministic\", \"buildnumber\": 14624, \"got_angle_revision\": \"c3f7873b9a3af998ec6e9132f07f331f3f14d306\", \"got_dawn_revision\": \"086835f3ffbf2cbdb63bafe120f23f36d3a08f35\", \"got_nacl_revision\": \"058a26f57015051953a727add26e7e26348b9558\", \"got_revision\": \"5bab9148b3b179454fddc6491a71416b029ea119\", \"got_revision_cp\": \"refs/heads/master@{#692973}\", \"got_swarming_client_revision\": \"96f125709acfd0b48fc1e5dae7d6ea42291726ac\", \"got_v8_revision\": \"e576e7591361f0f32a9b02c8f67a63ef1963a33d\", \"got_v8_revision_cp\": \"refs/heads/7.8.267@{#1}\", \"got_webrtc_revision\": \"1aa7e2fa2d6de586253d93575b2d3b6ae70cb056\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#29046}\", \"mastername\": \"chromium.win\", \"path_config\": \"generic\", \"recipe\": \"swarming/deterministic_build\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"5bab9148b3b179454fddc6491a71416b029ea119\"}, \"swarming\": {\"bot_dimensions\": {\"builder\": [\"Windows deterministic\"], \"caches\": [\"builder_e8fffaf9fac16164b1ec16ebaa1f377b792d39a582f7640830518d8ff7303d0c_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"8\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\"], \"gce\": [\"1\"], \"gpu\": [\"none\"], \"id\": [\"windows-deterministic-0-y5mg\"], \"image\": [\"chrome-win10-1703-19072400-fedda89f1f1\"], \"integrity\": [\"high\"], \"locale\": [\"en_US.cp1252\"], \"machine_type\": [\"n1-standard-8\"], \"os\": [\"Windows\", \"Windows-10\", \"Windows-10-15063\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.13\"], \"server_version\": [\"4397-8786906\"], \"ssd\": [\"0\"], \"windows_client_version\": [\"10\"], \"zone\": [\"us\", \"us-west\", \"us-west1\", \"us-west1-c\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1567562607564009",
"status": "COMPLETED",
"status_changed_ts": "1567570890436010",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/14624",
"builder:Windows deterministic",
"buildset:commit/git/5bab9148b3b179454fddc6491a71416b029ea119",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/5bab9148b3b179454fddc6491a71416b029ea119",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9068715254363957072",
"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/8903285191553080560/+/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:4712d4a6d0170410",
"user_agent:luci-scheduler"
],
"updated_ts": "1567570890439004",
"url": "https://ci.chromium.org/b/8903285191553080560",
"utcnow_ts": "1567570890832647"
}
}
[04:21:30.846] Invocation finished in 2h18m9.52730792s with status SUCCEEDED