[18:48:28.141] New invocation is queued and will start shortly
[18:48:29.305] Starting the invocation (attempt 1)
[18:48:29.337] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[18:48:29.337] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[18:48:29.337] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9084506487216717776",
"parameters_json": "{\"builder_name\":\"Windows deterministic\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src.git\",\"revision\":\"5b721b4e26035bfa2192d6103c84504a7dfa6f30\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Windows deterministic",
"scheduler_invocation_id:9084506487216717776",
"scheduler_job_id:chromium/Windows deterministic",
"user_agent:luci-scheduler",
"buildset:commit/git/5b721b4e26035bfa2192d6103c84504a7dfa6f30",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/5b721b4e26035bfa2192d6103c84504a7dfa6f30",
"gitiles_ref:refs/heads/master"
]
}
[18:48:31.272] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1552502909435580",
"id": "8919076425195007056",
"lease_expiration_ts": "1552621711026272",
"lease_key": "838539852",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"5b721b4e26035bfa2192d6103c84504a7dfa6f30\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1552502911142505",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/12415",
"builder:Windows deterministic",
"buildset:commit/git/5b721b4e26035bfa2192d6103c84504a7dfa6f30",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/5b721b4e26035bfa2192d6103c84504a7dfa6f30",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9084506487216717776",
"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/8919076425195007056/+/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:439133f5c6d4fd10",
"user_agent:luci-scheduler"
],
"updated_ts": "1552502911142590",
"url": "https://ci.chromium.org/b/8919076425195007056",
"utcnow_ts": "1552502911254956"
}
}
[18:48:31.272] Task URL: https://ci.chromium.org/b/8919076425195007056
[18:48:31.272] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084506487216717776:2:0) after 1m0s
[18:48:39.688] Received PubSub notification, asking Buildbucket for the build status
[18:48:39.730] Build 8919076425195007056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:49:31.243] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084506487216717776:2:0)
[18:49:31.243] Timer tick, asking Buildbucket for the build status
[18:49:31.307] Build 8919076425195007056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:49:31.307] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084506487216717776:4:0) after 1m0s
[18:50:31.311] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084506487216717776:4:0)
[18:50:31.311] Timer tick, asking Buildbucket for the build status
[18:50:31.458] Build 8919076425195007056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:50:31.458] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084506487216717776:5:0) after 1m0s
[18:51:31.484] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084506487216717776:5:0)
[18:51:31.484] Timer tick, asking Buildbucket for the build status
[18:51:31.544] Build 8919076425195007056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:51:31.544] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084506487216717776:6:0) after 1m0s
[18:52:31.515] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084506487216717776:6:0)
[18:52:31.515] Timer tick, asking Buildbucket for the build status
[18:52:31.556] Build 8919076425195007056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:52:31.556] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084506487216717776:7:0) after 1m0s
[18:53:32.063] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084506487216717776:7:0)
[18:53:32.063] Timer tick, asking Buildbucket for the build status
[18:53:32.214] Build 8919076425195007056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:53:32.214] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084506487216717776:8:0) after 1m0s
[18:54:32.241] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084506487216717776:8:0)
[18:54:32.241] Timer tick, asking Buildbucket for the build status
[18:54:32.355] Build 8919076425195007056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:54:32.355] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084506487216717776:9:0) after 1m0s
[18:55:32.377] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084506487216717776:9:0)
[18:55:32.377] Timer tick, asking Buildbucket for the build status
[18:55:32.484] Build 8919076425195007056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:55:32.484] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084506487216717776:10:0) after 1m0s
[18:56:32.486] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084506487216717776:10:0)
[18:56:32.486] Timer tick, asking Buildbucket for the build status
[18:56:32.545] Build 8919076425195007056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:56:32.545] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084506487216717776:11:0) after 1m0s
[18:57:32.564] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084506487216717776:11:0)
[18:57:32.564] Timer tick, asking Buildbucket for the build status
[18:57:32.607] Build 8919076425195007056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:57:32.607] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084506487216717776:12:0) after 1m0s
[18:58:32.628] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084506487216717776:12:0)
[18:58:32.628] Timer tick, asking Buildbucket for the build status
[18:58:32.672] Build 8919076425195007056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:58:32.672] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084506487216717776:13:0) after 1m0s
[18:59:32.683] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084506487216717776:13:0)
[18:59:32.683] Timer tick, asking Buildbucket for the build status
[18:59:32.734] Build 8919076425195007056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:59:32.734] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084506487216717776:14:0) after 1m0s
[19:00:32.717] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084506487216717776:14:0)
[19:00:32.717] Timer tick, asking Buildbucket for the build status
[19:00:32.815] Build 8919076425195007056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:00:32.815] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084506487216717776:15:0) after 1m0s
[19:01:32.843] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084506487216717776:15:0)
[19:01:32.843] Timer tick, asking Buildbucket for the build status
[19:01:32.916] Build 8919076425195007056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:01:32.916] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084506487216717776:16:0) after 1m0s
[19:02:32.936] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084506487216717776:16:0)
[19:02:32.936] Timer tick, asking Buildbucket for the build status
[19:02:33.025] Build 8919076425195007056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:02:33.025] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084506487216717776:17:0) after 1m0s
[19:03:33.016] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084506487216717776:17:0)
[19:03:33.016] Timer tick, asking Buildbucket for the build status
[19:03:33.107] Build 8919076425195007056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:03:33.107] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084506487216717776:18:0) after 1m0s
[19:04:33.130] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084506487216717776:18:0)
[19:04:33.130] Timer tick, asking Buildbucket for the build status
[19:04:33.229] Build 8919076425195007056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:04:33.229] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084506487216717776:19:0) after 1m0s
[19:05:33.250] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084506487216717776:19:0)
[19:05:33.250] Timer tick, asking Buildbucket for the build status
[19:05:33.388] Build 8919076425195007056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:05:33.388] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084506487216717776:20:0) after 1m0s
[19:06:33.413] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084506487216717776:20:0)
[19:06:33.413] Timer tick, asking Buildbucket for the build status
[19:06:33.540] Build 8919076425195007056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:06:33.540] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084506487216717776:21:0) after 1m0s
[19:07:33.561] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084506487216717776:21:0)
[19:07:33.562] Timer tick, asking Buildbucket for the build status
[19:07:33.679] Build 8919076425195007056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:07:33.679] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084506487216717776:22:0) after 1m0s
[19:08:33.699] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084506487216717776:22:0)
[19:08:33.699] Timer tick, asking Buildbucket for the build status
[19:08:33.759] Build 8919076425195007056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:08:33.759] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084506487216717776:23:0) after 1m0s
[19:09:33.746] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084506487216717776:23:0)
[19:09:33.746] Timer tick, asking Buildbucket for the build status
[19:09:33.785] Build 8919076425195007056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:09:33.785] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084506487216717776:24:0) after 1m0s
[19:10:33.813] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084506487216717776:24:0)
[19:10:33.813] Timer tick, asking Buildbucket for the build status
[19:10:33.846] Build 8919076425195007056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:10:33.846] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084506487216717776:25:0) after 1m0s
[19:11:33.873] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084506487216717776:25:0)
[19:11:33.873] Timer tick, asking Buildbucket for the build status
[19:11:33.989] Build 8919076425195007056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:11:33.989] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084506487216717776:26:0) after 1m0s
[19:12:34.004] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084506487216717776:26:0)
[19:12:34.004] Timer tick, asking Buildbucket for the build status
[19:12:34.094] Build 8919076425195007056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:12:34.094] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084506487216717776:27:0) after 1m0s
[19:13:34.137] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084506487216717776:27:0)
[19:13:34.137] Timer tick, asking Buildbucket for the build status
[19:13:34.173] Build 8919076425195007056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:13:34.173] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084506487216717776:28:0) after 1m0s
[19:14:34.163] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084506487216717776:28:0)
[19:14:34.163] Timer tick, asking Buildbucket for the build status
[19:14:34.235] Build 8919076425195007056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:14:34.235] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084506487216717776:29:0) after 1m0s
[19:15:34.252] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084506487216717776:29:0)
[19:15:34.252] Timer tick, asking Buildbucket for the build status
[19:15:34.517] Build 8919076425195007056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:15:34.517] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084506487216717776:30:0) after 1m0s
[19:16:34.538] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084506487216717776:30:0)
[19:16:34.538] Timer tick, asking Buildbucket for the build status
[19:16:34.578] Build 8919076425195007056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:16:34.578] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084506487216717776:31:0) after 1m0s
[19:17:34.554] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084506487216717776:31:0)
[19:17:34.554] Timer tick, asking Buildbucket for the build status
[19:17:34.685] Build 8919076425195007056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:17:34.685] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084506487216717776:32:0) after 1m0s
[19:18:34.708] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084506487216717776:32:0)
[19:18:34.708] Timer tick, asking Buildbucket for the build status
[19:18:34.781] Build 8919076425195007056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:18:34.781] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084506487216717776:33:0) after 1m0s
[19:19:34.812] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084506487216717776:33:0)
[19:19:34.812] Timer tick, asking Buildbucket for the build status
[19:19:34.849] Build 8919076425195007056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:19:34.849] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084506487216717776:34:0) after 1m0s
[19:20:34.868] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084506487216717776:34:0)
[19:20:34.868] Timer tick, asking Buildbucket for the build status
[19:20:34.952] Build 8919076425195007056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:20:34.952] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084506487216717776:35:0) after 1m0s
[19:21:34.978] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084506487216717776:35:0)
[19:21:34.978] Timer tick, asking Buildbucket for the build status
[19:21:35.049] Build 8919076425195007056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:21:35.049] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084506487216717776:36:0) after 1m0s
[19:22:35.076] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084506487216717776:36:0)
[19:22:35.076] Timer tick, asking Buildbucket for the build status
[19:22:35.288] Build 8919076425195007056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:22:35.288] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084506487216717776:37:0) after 1m0s
[19:23:35.294] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084506487216717776:37:0)
[19:23:35.294] Timer tick, asking Buildbucket for the build status
[19:23:35.476] Build 8919076425195007056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:23:35.476] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084506487216717776:38:0) after 1m0s
[19:24:35.498] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084506487216717776:38:0)
[19:24:35.498] Timer tick, asking Buildbucket for the build status
[19:24:35.701] Build 8919076425195007056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:24:35.701] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084506487216717776:39:0) after 1m0s
[19:25:35.728] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084506487216717776:39:0)
[19:25:35.728] Timer tick, asking Buildbucket for the build status
[19:25:35.774] Build 8919076425195007056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:25:35.774] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084506487216717776:40:0) after 1m0s
[19:26:35.796] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084506487216717776:40:0)
[19:26:35.796] Timer tick, asking Buildbucket for the build status
[19:26:35.876] Build 8919076425195007056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:26:35.876] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084506487216717776:41:0) after 1m0s
[19:27:35.862] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084506487216717776:41:0)
[19:27:35.862] Timer tick, asking Buildbucket for the build status
[19:27:35.940] Build 8919076425195007056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:27:35.940] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084506487216717776:42:0) after 1m0s
[19:28:35.935] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084506487216717776:42:0)
[19:28:35.935] Timer tick, asking Buildbucket for the build status
[19:28:36.015] Build 8919076425195007056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:28:36.015] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084506487216717776:43:0) after 1m0s
[19:29:36.064] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084506487216717776:43:0)
[19:29:36.064] Timer tick, asking Buildbucket for the build status
[19:29:36.246] Build 8919076425195007056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:29:36.246] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084506487216717776:44:0) after 1m0s
[19:30:36.260] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084506487216717776:44:0)
[19:30:36.260] Timer tick, asking Buildbucket for the build status
[19:30:36.326] Build 8919076425195007056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:30:36.326] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084506487216717776:45:0) after 1m0s
[19:31:36.345] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084506487216717776:45:0)
[19:31:36.345] Timer tick, asking Buildbucket for the build status
[19:31:36.524] Build 8919076425195007056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:31:36.524] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084506487216717776:46:0) after 1m0s
[19:32:36.523] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084506487216717776:46:0)
[19:32:36.523] Timer tick, asking Buildbucket for the build status
[19:32:36.572] Build 8919076425195007056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:32:36.572] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084506487216717776:47:0) after 1m0s
[19:33:36.613] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084506487216717776:47:0)
[19:33:36.613] Timer tick, asking Buildbucket for the build status
[19:33:36.653] Build 8919076425195007056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:33:36.653] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084506487216717776:48:0) after 1m0s
[19:34:36.673] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084506487216717776:48:0)
[19:34:36.673] Timer tick, asking Buildbucket for the build status
[19:34:36.773] Build 8919076425195007056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:34:36.773] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084506487216717776:49:0) after 1m0s
[19:35:36.784] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084506487216717776:49:0)
[19:35:36.784] Timer tick, asking Buildbucket for the build status
[19:35:36.899] Build 8919076425195007056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:35:36.899] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084506487216717776:50:0) after 1m0s
[19:36:36.929] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084506487216717776:50:0)
[19:36:36.929] Timer tick, asking Buildbucket for the build status
[19:36:36.960] Build 8919076425195007056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:36:36.960] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084506487216717776:51:0) after 1m0s
[19:37:36.956] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084506487216717776:51:0)
[19:37:36.956] Timer tick, asking Buildbucket for the build status
[19:37:37.048] Build 8919076425195007056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:37:37.048] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084506487216717776:52:0) after 1m0s
[19:38:37.071] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084506487216717776:52:0)
[19:38:37.071] Timer tick, asking Buildbucket for the build status
[19:38:37.135] Build 8919076425195007056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:38:37.135] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084506487216717776:53:0) after 1m0s
[19:39:37.137] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084506487216717776:53:0)
[19:39:37.137] Timer tick, asking Buildbucket for the build status
[19:39:37.182] Build 8919076425195007056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:39:37.182] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084506487216717776:54:0) after 1m0s
[19:40:37.181] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084506487216717776:54:0)
[19:40:37.181] Timer tick, asking Buildbucket for the build status
[19:40:37.264] Build 8919076425195007056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:40:37.264] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084506487216717776:55:0) after 1m0s
[19:41:37.290] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084506487216717776:55:0)
[19:41:37.290] Timer tick, asking Buildbucket for the build status
[19:41:37.330] Build 8919076425195007056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:41:37.330] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084506487216717776:56:0) after 1m0s
[19:42:37.380] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084506487216717776:56:0)
[19:42:37.380] Timer tick, asking Buildbucket for the build status
[19:42:37.566] Build 8919076425195007056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:42:37.566] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084506487216717776:57:0) after 1m0s
[19:43:37.567] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084506487216717776:57:0)
[19:43:37.567] Timer tick, asking Buildbucket for the build status
[19:43:37.658] Build 8919076425195007056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:43:37.658] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084506487216717776:58:0) after 1m0s
[19:44:37.689] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084506487216717776:58:0)
[19:44:37.689] Timer tick, asking Buildbucket for the build status
[19:44:37.732] Build 8919076425195007056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:44:37.732] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084506487216717776:59:0) after 1m0s
[19:45:37.724] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084506487216717776:59:0)
[19:45:37.724] Timer tick, asking Buildbucket for the build status
[19:45:37.762] Build 8919076425195007056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:45:37.763] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084506487216717776:60:0) after 1m0s
[19:46:37.735] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084506487216717776:60:0)
[19:46:37.735] Timer tick, asking Buildbucket for the build status
[19:46:37.807] Build 8919076425195007056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:46:37.807] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084506487216717776:61:0) after 1m0s
[19:47:37.795] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084506487216717776:61:0)
[19:47:37.795] Timer tick, asking Buildbucket for the build status
[19:47:37.861] Build 8919076425195007056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:47:37.861] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084506487216717776:62:0) after 1m0s
[19:48:37.884] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084506487216717776:62:0)
[19:48:37.884] Timer tick, asking Buildbucket for the build status
[19:48:37.933] Build 8919076425195007056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:48:37.933] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084506487216717776:63:0) after 1m0s
[19:49:37.957] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084506487216717776:63:0)
[19:49:37.957] Timer tick, asking Buildbucket for the build status
[19:49:38.055] Build 8919076425195007056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:49:38.055] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084506487216717776:64:0) after 1m0s
[19:50:38.137] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084506487216717776:64:0)
[19:50:38.137] Timer tick, asking Buildbucket for the build status
[19:50:38.176] Build 8919076425195007056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:50:38.176] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084506487216717776:65:0) after 1m0s
[19:51:38.199] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084506487216717776:65:0)
[19:51:38.199] Timer tick, asking Buildbucket for the build status
[19:51:38.253] Build 8919076425195007056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:51:38.253] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084506487216717776:66:0) after 1m0s
[19:52:38.287] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084506487216717776:66:0)
[19:52:38.287] Timer tick, asking Buildbucket for the build status
[19:52:38.658] Build 8919076425195007056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:52:38.658] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084506487216717776:67:0) after 1m0s
[19:53:38.685] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084506487216717776:67:0)
[19:53:38.685] Timer tick, asking Buildbucket for the build status
[19:53:38.738] Build 8919076425195007056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:53:38.738] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084506487216717776:68:0) after 1m0s
[19:54:38.755] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084506487216717776:68:0)
[19:54:38.755] Timer tick, asking Buildbucket for the build status
[19:54:38.808] Build 8919076425195007056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:54:38.808] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084506487216717776:69:0) after 1m0s
[19:55:38.809] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084506487216717776:69:0)
[19:55:38.809] Timer tick, asking Buildbucket for the build status
[19:55:38.846] Build 8919076425195007056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:55:38.846] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084506487216717776:70:0) after 1m0s
[19:56:38.841] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084506487216717776:70:0)
[19:56:38.841] Timer tick, asking Buildbucket for the build status
[19:56:38.959] Build 8919076425195007056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:56:38.959] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084506487216717776:71:0) after 1m0s
[19:57:39.000] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084506487216717776:71:0)
[19:57:39.000] Timer tick, asking Buildbucket for the build status
[19:57:39.166] Build 8919076425195007056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:57:39.166] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084506487216717776:72:0) after 1m0s
[19:58:39.156] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084506487216717776:72:0)
[19:58:39.156] Timer tick, asking Buildbucket for the build status
[19:58:39.189] Build 8919076425195007056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:58:39.189] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084506487216717776:73:0) after 1m0s
[19:59:39.199] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084506487216717776:73:0)
[19:59:39.199] Timer tick, asking Buildbucket for the build status
[19:59:39.252] Build 8919076425195007056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:59:39.252] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084506487216717776:74:0) after 1m0s
[20:00:39.273] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084506487216717776:74:0)
[20:00:39.273] Timer tick, asking Buildbucket for the build status
[20:00:39.334] Build 8919076425195007056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:00:39.334] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084506487216717776:75:0) after 1m0s
[20:01:39.336] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084506487216717776:75:0)
[20:01:39.336] Timer tick, asking Buildbucket for the build status
[20:01:39.507] Build 8919076425195007056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:01:39.507] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084506487216717776:76:0) after 1m0s
[20:02:39.536] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084506487216717776:76:0)
[20:02:39.536] Timer tick, asking Buildbucket for the build status
[20:02:39.640] Build 8919076425195007056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:02:39.640] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084506487216717776:77:0) after 1m0s
[20:03:39.662] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084506487216717776:77:0)
[20:03:39.662] Timer tick, asking Buildbucket for the build status
[20:03:39.812] Build 8919076425195007056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:03:39.812] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084506487216717776:78:0) after 1m0s
[20:04:39.827] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084506487216717776:78:0)
[20:04:39.827] Timer tick, asking Buildbucket for the build status
[20:04:39.876] Build 8919076425195007056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:04:39.876] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084506487216717776:79:0) after 1m0s
[20:05:39.878] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084506487216717776:79:0)
[20:05:39.878] Timer tick, asking Buildbucket for the build status
[20:05:39.931] Build 8919076425195007056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:05:39.931] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084506487216717776:80:0) after 1m0s
[20:06:39.936] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084506487216717776:80:0)
[20:06:39.936] Timer tick, asking Buildbucket for the build status
[20:06:40.072] Build 8919076425195007056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:06:40.072] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084506487216717776:81:0) after 1m0s
[20:07:40.101] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084506487216717776:81:0)
[20:07:40.101] Timer tick, asking Buildbucket for the build status
[20:07:40.393] Build 8919076425195007056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:07:40.393] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084506487216717776:82:0) after 1m0s
[20:08:40.420] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084506487216717776:82:0)
[20:08:40.420] Timer tick, asking Buildbucket for the build status
[20:08:40.491] Build 8919076425195007056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:08:40.491] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084506487216717776:83:0) after 1m0s
[20:09:40.516] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084506487216717776:83:0)
[20:09:40.516] Timer tick, asking Buildbucket for the build status
[20:09:40.612] Build 8919076425195007056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:09:40.612] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084506487216717776:84:0) after 1m0s
[20:10:40.632] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084506487216717776:84:0)
[20:10:40.632] Timer tick, asking Buildbucket for the build status
[20:10:40.678] Build 8919076425195007056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:10:40.678] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084506487216717776:85:0) after 1m0s
[20:11:40.682] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084506487216717776:85:0)
[20:11:40.682] Timer tick, asking Buildbucket for the build status
[20:11:40.715] Build 8919076425195007056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:11:40.715] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084506487216717776:86:0) after 1m0s
[20:12:40.698] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084506487216717776:86:0)
[20:12:40.698] Timer tick, asking Buildbucket for the build status
[20:12:40.756] Build 8919076425195007056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:12:40.756] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084506487216717776:87:0) after 1m0s
[20:13:40.732] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084506487216717776:87:0)
[20:13:40.732] Timer tick, asking Buildbucket for the build status
[20:13:40.890] Build 8919076425195007056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:13:40.890] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084506487216717776:88:0) after 1m0s
[20:14:40.882] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084506487216717776:88:0)
[20:14:40.882] Timer tick, asking Buildbucket for the build status
[20:14:40.952] Build 8919076425195007056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:14:40.952] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084506487216717776:89:0) after 1m0s
[20:15:40.983] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084506487216717776:89:0)
[20:15:40.983] Timer tick, asking Buildbucket for the build status
[20:15:41.096] Build 8919076425195007056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:15:41.096] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084506487216717776:90:0) after 1m0s
[20:16:41.159] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084506487216717776:90:0)
[20:16:41.159] Timer tick, asking Buildbucket for the build status
[20:16:41.197] Build 8919076425195007056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:16:41.197] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084506487216717776:91:0) after 1m0s
[20:17:41.194] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084506487216717776:91:0)
[20:17:41.194] Timer tick, asking Buildbucket for the build status
[20:17:41.359] Build 8919076425195007056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:17:41.359] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084506487216717776:92:0) after 1m0s
[20:18:41.383] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084506487216717776:92:0)
[20:18:41.383] Timer tick, asking Buildbucket for the build status
[20:18:41.420] Build 8919076425195007056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:18:41.420] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084506487216717776:93:0) after 1m0s
[20:19:41.458] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084506487216717776:93:0)
[20:19:41.458] Timer tick, asking Buildbucket for the build status
[20:19:41.494] Build 8919076425195007056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:19:41.494] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084506487216717776:94:0) after 1m0s
[20:20:41.518] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084506487216717776:94:0)
[20:20:41.518] Timer tick, asking Buildbucket for the build status
[20:20:41.689] Build 8919076425195007056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:20:41.689] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084506487216717776:95:0) after 1m0s
[20:21:41.702] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084506487216717776:95:0)
[20:21:41.702] Timer tick, asking Buildbucket for the build status
[20:21:41.795] Build 8919076425195007056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:21:41.795] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084506487216717776:96:0) after 1m0s
[20:22:41.805] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084506487216717776:96:0)
[20:22:41.805] Timer tick, asking Buildbucket for the build status
[20:22:41.929] Build 8919076425195007056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:22:41.929] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084506487216717776:97:0) after 1m0s
[20:23:41.954] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084506487216717776:97:0)
[20:23:41.954] Timer tick, asking Buildbucket for the build status
[20:23:42.056] Build 8919076425195007056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:23:42.056] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084506487216717776:98:0) after 1m0s
[20:24:42.093] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084506487216717776:98:0)
[20:24:42.093] Timer tick, asking Buildbucket for the build status
[20:24:42.191] Build 8919076425195007056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:24:42.191] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084506487216717776:99:0) after 1m0s
[20:25:42.211] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084506487216717776:99:0)
[20:25:42.211] Timer tick, asking Buildbucket for the build status
[20:25:42.381] Build 8919076425195007056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:25:42.382] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084506487216717776:100:0) after 1m0s
[20:26:42.406] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084506487216717776:100:0)
[20:26:42.406] Timer tick, asking Buildbucket for the build status
[20:26:42.446] Build 8919076425195007056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:26:42.446] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084506487216717776:101:0) after 1m0s
[20:27:42.471] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084506487216717776:101:0)
[20:27:42.471] Timer tick, asking Buildbucket for the build status
[20:27:42.526] Build 8919076425195007056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:27:42.526] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084506487216717776:102:0) after 1m0s
[20:28:42.557] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084506487216717776:102:0)
[20:28:42.557] Timer tick, asking Buildbucket for the build status
[20:28:42.668] Build 8919076425195007056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:28:42.668] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084506487216717776:103:0) after 1m0s
[20:29:42.667] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084506487216717776:103:0)
[20:29:42.667] Timer tick, asking Buildbucket for the build status
[20:29:42.736] Build 8919076425195007056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:29:42.736] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084506487216717776:104:0) after 1m0s
[20:30:42.750] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084506487216717776:104:0)
[20:30:42.750] Timer tick, asking Buildbucket for the build status
[20:30:42.867] Build 8919076425195007056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:30:42.867] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084506487216717776:105:0) after 1m0s
[20:31:42.870] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084506487216717776:105:0)
[20:31:42.870] Timer tick, asking Buildbucket for the build status
[20:31:42.921] Build 8919076425195007056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:31:42.921] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084506487216717776:106:0) after 1m0s
[20:32:42.898] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084506487216717776:106:0)
[20:32:42.898] Timer tick, asking Buildbucket for the build status
[20:32:42.974] Build 8919076425195007056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:32:42.974] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084506487216717776:107:0) after 1m0s
[20:33:42.997] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084506487216717776:107:0)
[20:33:42.997] Timer tick, asking Buildbucket for the build status
[20:33:43.068] Build 8919076425195007056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:33:43.068] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084506487216717776:108:0) after 1m0s
[20:34:43.053] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084506487216717776:108:0)
[20:34:43.053] Timer tick, asking Buildbucket for the build status
[20:34:43.092] Build 8919076425195007056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:34:43.092] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084506487216717776:109:0) after 1m0s
[20:35:43.107] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084506487216717776:109:0)
[20:35:43.107] Timer tick, asking Buildbucket for the build status
[20:35:43.193] Build 8919076425195007056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:35:43.193] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084506487216717776:110:0) after 1m0s
[20:36:43.208] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084506487216717776:110:0)
[20:36:43.208] Timer tick, asking Buildbucket for the build status
[20:36:43.254] Build 8919076425195007056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:36:43.254] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084506487216717776:111:0) after 1m0s
[20:37:43.280] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084506487216717776:111:0)
[20:37:43.280] Timer tick, asking Buildbucket for the build status
[20:37:43.315] Build 8919076425195007056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:37:43.315] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084506487216717776:112:0) after 1m0s
[20:38:43.349] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084506487216717776:112:0)
[20:38:43.349] Timer tick, asking Buildbucket for the build status
[20:38:43.469] Build 8919076425195007056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:38:43.469] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084506487216717776:113:0) after 1m0s
[20:39:43.481] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084506487216717776:113:0)
[20:39:43.481] Timer tick, asking Buildbucket for the build status
[20:39:43.691] Build 8919076425195007056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:39:43.691] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084506487216717776:114:0) after 1m0s
[20:40:43.724] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084506487216717776:114:0)
[20:40:43.724] Timer tick, asking Buildbucket for the build status
[20:40:43.859] Build 8919076425195007056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:40:43.859] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084506487216717776:115:0) after 1m0s
[20:41:43.900] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084506487216717776:115:0)
[20:41:43.900] Timer tick, asking Buildbucket for the build status
[20:41:44.054] Build 8919076425195007056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:41:44.054] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084506487216717776:116:0) after 1m0s
[20:42:44.052] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084506487216717776:116:0)
[20:42:44.052] Timer tick, asking Buildbucket for the build status
[20:42:44.175] Build 8919076425195007056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:42:44.175] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084506487216717776:117:0) after 1m0s
[20:43:44.201] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084506487216717776:117:0)
[20:43:44.201] Timer tick, asking Buildbucket for the build status
[20:43:44.233] Build 8919076425195007056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:43:44.233] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084506487216717776:118:0) after 1m0s
[20:44:44.258] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084506487216717776:118:0)
[20:44:44.258] Timer tick, asking Buildbucket for the build status
[20:44:44.296] Build 8919076425195007056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:44:44.296] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084506487216717776:119:0) after 1m0s
[20:45:44.329] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084506487216717776:119:0)
[20:45:44.329] Timer tick, asking Buildbucket for the build status
[20:45:44.435] Build 8919076425195007056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:45:44.435] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084506487216717776:120:0) after 1m0s
[20:46:20.768] Received PubSub notification, asking Buildbucket for the build status
[20:46:20.807] Build 8919076425195007056: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[20:46:20.807] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"completed_ts": "1552509979875202",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1552502909435580",
"id": "8919076425195007056",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"5b721b4e26035bfa2192d6103c84504a7dfa6f30\"}}",
"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-b\"], \"locale\": [\"en_US.cp1252\"], \"os\": [\"Windows\", \"Windows-10\", \"Windows-10-15063\"], \"builder\": [\"Windows deterministic\"], \"id\": [\"win10-727f49a0-us-west1-b-kb2j\"], \"python\": [\"2.7.13\"], \"server_version\": [\"4186-af94c3f\"], \"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\"}, \"buildnumber\": 12415, \"got_dawn_revision\": \"ea2c7f20f2f96e15570f7e9416fb6c826f809747\", \"got_nacl_revision\": \"1ed9878b8eb68c1e0d0268754dafcf83e747fa8f\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#27098}\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"buildername\": \"Windows deterministic\", \"got_webrtc_revision\": \"cc3503248f1dc7edd3ab505b5194c69718d0f711\", \"got_revision\": \"5b721b4e26035bfa2192d6103c84504a7dfa6f30\", \"got_swarming_client_revision\": \"7a61cf37d6a0163f0ec02d495289a1d038e62457\", \"recipe\": \"swarming/deterministic_build\", \"mastername\": \"chromium.win\", \"got_angle_revision\": \"4e87659e289c37602e3c94264e7302fe7a8dfaa7\", \"got_v8_revision\": \"f7cc35d707c060d94ce8d3ef4855055752ceea42\", \"got_revision_cp\": \"refs/heads/master@{#640424}\", \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"branch\": \"refs/heads/master\", \"path_config\": \"generic\", \"revision\": \"5b721b4e26035bfa2192d6103c84504a7dfa6f30\", \"got_v8_revision_cp\": \"refs/heads/7.5.29@{#1}\", \"bot_id\": \"win10-727f49a0-us-west1-b-kb2j\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1552502918574061",
"status": "COMPLETED",
"status_changed_ts": "1552509980463687",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/12415",
"builder:Windows deterministic",
"buildset:commit/git/5b721b4e26035bfa2192d6103c84504a7dfa6f30",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/5b721b4e26035bfa2192d6103c84504a7dfa6f30",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9084506487216717776",
"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/8919076425195007056/+/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:439133f5c6d4fd10",
"user_agent:luci-scheduler"
],
"updated_ts": "1552509980466621",
"url": "https://ci.chromium.org/b/8919076425195007056",
"utcnow_ts": "1552509980796937"
}
}
[20:46:20.807] Invocation finished in 1h57m52.684174652s with status SUCCEEDED