[12:00:40.386] New invocation is queued and will start shortly
[12:00:41.483] Starting the invocation (attempt 1)
[12:00:41.523] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[12:00:41.523] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[12:00:41.523] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9052460817412222480",
"parameters_json": "{\"builder_name\":\"Windows deterministic\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"2f7180bd6ca4710a80d48d99f00f1deae0426bed\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Windows deterministic",
"scheduler_invocation_id:9052460817412222480",
"scheduler_job_id:chromium/Windows deterministic",
"user_agent:luci-scheduler",
"buildset:commit/git/2f7180bd6ca4710a80d48d99f00f1deae0426bed",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/2f7180bd6ca4710a80d48d99f00f1deae0426bed",
"gitiles_ref:refs/heads/master"
]
}
[12:00:43.337] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1583064041618901",
"id": "8887030755454844448",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"2f7180bd6ca4710a80d48d99f00f1deae0426bed\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1583064042123183",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/15964",
"builder:Windows deterministic",
"buildset:commit/git/2f7180bd6ca4710a80d48d99f00f1deae0426bed",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/2f7180bd6ca4710a80d48d99f00f1deae0426bed",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9052460817412222480",
"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/8887030755454844448/+/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": "1583064042123285",
"url": "https://ci.chromium.org/b/8887030755454844448",
"utcnow_ts": "1583064043236594"
}
}
[12:00:43.337] Task URL: https://ci.chromium.org/b/8887030755454844448
[12:00:43.337] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:2:0) after 1m0s
[12:01:43.370] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:2:0)
[12:01:43.370] Timer tick, asking Buildbucket for the build status
[12:01:43.527] Build 8887030755454844448: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:01:43.527] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:3:0) after 1m0s
[12:02:43.589] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:3:0)
[12:02:43.589] Timer tick, asking Buildbucket for the build status
[12:02:43.716] Build 8887030755454844448: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:02:43.716] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:4:0) after 1m0s
[12:03:43.797] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:4:0)
[12:03:43.797] Timer tick, asking Buildbucket for the build status
[12:03:43.955] Build 8887030755454844448: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:03:43.955] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:5:0) after 1m0s
[12:04:43.985] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:5:0)
[12:04:43.985] Timer tick, asking Buildbucket for the build status
[12:04:44.168] Build 8887030755454844448: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:04:44.168] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:6:0) after 1m0s
[12:05:44.211] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:6:0)
[12:05:44.211] Timer tick, asking Buildbucket for the build status
[12:05:44.326] Build 8887030755454844448: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:05:44.326] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:7:0) after 1m0s
[12:06:44.355] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:7:0)
[12:06:44.355] Timer tick, asking Buildbucket for the build status
[12:06:44.778] Build 8887030755454844448: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:06:44.778] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:8:0) after 1m0s
[12:07:44.794] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:8:0)
[12:07:44.794] Timer tick, asking Buildbucket for the build status
[12:07:44.880] Build 8887030755454844448: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:07:44.881] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:9:0) after 1m0s
[12:08:44.899] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:9:0)
[12:08:44.899] Timer tick, asking Buildbucket for the build status
[12:08:45.057] Build 8887030755454844448: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:08:45.057] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:10:0) after 1m0s
[12:09:45.074] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:10:0)
[12:09:45.074] Timer tick, asking Buildbucket for the build status
[12:09:45.165] Build 8887030755454844448: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:09:45.165] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:11:0) after 1m0s
[12:10:45.423] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:11:0)
[12:10:45.423] Timer tick, asking Buildbucket for the build status
[12:10:45.545] Build 8887030755454844448: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:10:45.545] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:12:0) after 1m0s
[12:11:45.745] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:12:0)
[12:11:45.745] Timer tick, asking Buildbucket for the build status
[12:11:46.195] Build 8887030755454844448: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:11:46.195] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:13:0) after 1m0s
[12:12:46.342] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:13:0)
[12:12:46.342] Timer tick, asking Buildbucket for the build status
[12:12:46.504] Build 8887030755454844448: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:12:46.504] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:14:0) after 1m0s
[12:13:46.603] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:14:0)
[12:13:46.603] Timer tick, asking Buildbucket for the build status
[12:13:46.748] Build 8887030755454844448: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:13:46.748] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:15:0) after 1m0s
[12:14:46.775] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:15:0)
[12:14:46.775] Timer tick, asking Buildbucket for the build status
[12:14:46.930] Build 8887030755454844448: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:14:46.930] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:16:0) after 1m0s
[12:15:46.958] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:16:0)
[12:15:46.958] Timer tick, asking Buildbucket for the build status
[12:15:47.163] Build 8887030755454844448: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:15:47.163] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:17:0) after 1m0s
[12:16:20.316] Received PubSub notification, asking Buildbucket for the build status
[12:16:20.455] Build 8887030755454844448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:16:47.191] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:17:0)
[12:16:47.191] Timer tick, asking Buildbucket for the build status
[12:16:47.346] Build 8887030755454844448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:16:47.346] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:19:0) after 1m0s
[12:17:47.365] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:19:0)
[12:17:47.365] Timer tick, asking Buildbucket for the build status
[12:17:47.536] Build 8887030755454844448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:17:47.536] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:20:0) after 1m0s
[12:18:47.560] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:20:0)
[12:18:47.560] Timer tick, asking Buildbucket for the build status
[12:18:47.696] Build 8887030755454844448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:18:47.696] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:21:0) after 1m0s
[12:19:47.740] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:21:0)
[12:19:47.740] Timer tick, asking Buildbucket for the build status
[12:19:47.877] Build 8887030755454844448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:19:47.878] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:22:0) after 1m0s
[12:20:47.925] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:22:0)
[12:20:47.925] Timer tick, asking Buildbucket for the build status
[12:20:48.092] Build 8887030755454844448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:20:48.092] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:23:0) after 1m0s
[12:21:48.264] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:23:0)
[12:21:48.264] Timer tick, asking Buildbucket for the build status
[12:21:48.432] Build 8887030755454844448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:21:48.432] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:24:0) after 1m0s
[12:22:48.485] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:24:0)
[12:22:48.485] Timer tick, asking Buildbucket for the build status
[12:22:48.635] Build 8887030755454844448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:22:48.635] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:25:0) after 1m0s
[12:23:48.810] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:25:0)
[12:23:48.810] Timer tick, asking Buildbucket for the build status
[12:23:48.957] Build 8887030755454844448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:23:48.957] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:26:0) after 1m0s
[12:24:49.091] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:26:0)
[12:24:49.091] Timer tick, asking Buildbucket for the build status
[12:24:49.330] Build 8887030755454844448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:24:49.330] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:27:0) after 1m0s
[12:25:49.493] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:27:0)
[12:25:49.493] Timer tick, asking Buildbucket for the build status
[12:25:49.633] Build 8887030755454844448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:25:49.633] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:28:0) after 1m0s
[12:26:49.728] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:28:0)
[12:26:49.728] Timer tick, asking Buildbucket for the build status
[12:26:49.863] Build 8887030755454844448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:26:49.863] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:29:0) after 1m0s
[12:27:49.998] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:29:0)
[12:27:49.998] Timer tick, asking Buildbucket for the build status
[12:27:50.281] Build 8887030755454844448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:27:50.281] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:30:0) after 1m0s
[12:28:50.330] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:30:0)
[12:28:50.330] Timer tick, asking Buildbucket for the build status
[12:28:50.571] Build 8887030755454844448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:28:50.571] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:31:0) after 1m0s
[12:29:50.658] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:31:0)
[12:29:50.658] Timer tick, asking Buildbucket for the build status
[12:29:50.991] Build 8887030755454844448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:29:50.991] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:32:0) after 1m0s
[12:30:50.994] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:32:0)
[12:30:50.994] Timer tick, asking Buildbucket for the build status
[12:30:51.169] Build 8887030755454844448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:30:51.169] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:33:0) after 1m0s
[12:31:51.348] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:33:0)
[12:31:51.348] Timer tick, asking Buildbucket for the build status
[12:31:51.575] Build 8887030755454844448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:31:51.575] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:34:0) after 1m0s
[12:32:51.609] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:34:0)
[12:32:51.609] Timer tick, asking Buildbucket for the build status
[12:32:51.733] Build 8887030755454844448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:32:51.733] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:35:0) after 1m0s
[12:33:51.759] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:35:0)
[12:33:51.759] Timer tick, asking Buildbucket for the build status
[12:33:51.934] Build 8887030755454844448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:33:51.934] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:36:0) after 1m0s
[12:34:51.967] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:36:0)
[12:34:51.967] Timer tick, asking Buildbucket for the build status
[12:34:52.172] Build 8887030755454844448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:34:52.172] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:37:0) after 1m0s
[12:35:52.239] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:37:0)
[12:35:52.239] Timer tick, asking Buildbucket for the build status
[12:35:52.541] Build 8887030755454844448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:35:52.541] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:38:0) after 1m0s
[12:36:52.627] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:38:0)
[12:36:52.627] Timer tick, asking Buildbucket for the build status
[12:36:53.395] Build 8887030755454844448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:36:53.395] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:39:0) after 1m0s
[12:37:53.427] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:39:0)
[12:37:53.427] Timer tick, asking Buildbucket for the build status
[12:37:53.762] Build 8887030755454844448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:37:53.762] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:40:0) after 1m0s
[12:38:53.786] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:40:0)
[12:38:53.786] Timer tick, asking Buildbucket for the build status
[12:38:53.986] Build 8887030755454844448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:38:53.986] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:41:0) after 1m0s
[12:39:54.120] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:41:0)
[12:39:54.120] Timer tick, asking Buildbucket for the build status
[12:39:54.409] Build 8887030755454844448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:39:54.409] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:42:0) after 1m0s
[12:40:54.516] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:42:0)
[12:40:54.516] Timer tick, asking Buildbucket for the build status
[12:40:54.667] Build 8887030755454844448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:40:54.667] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:43:0) after 1m0s
[12:41:54.684] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:43:0)
[12:41:54.684] Timer tick, asking Buildbucket for the build status
[12:41:54.786] Build 8887030755454844448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:41:54.786] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:44:0) after 1m0s
[12:42:54.913] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:44:0)
[12:42:54.913] Timer tick, asking Buildbucket for the build status
[12:42:55.253] Build 8887030755454844448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:42:55.253] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:45:0) after 1m0s
[12:43:55.272] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:45:0)
[12:43:55.272] Timer tick, asking Buildbucket for the build status
[12:43:55.559] Build 8887030755454844448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:43:55.559] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:46:0) after 1m0s
[12:44:55.598] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:46:0)
[12:44:55.598] Timer tick, asking Buildbucket for the build status
[12:44:55.720] Build 8887030755454844448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:44:55.720] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:47:0) after 1m0s
[12:45:55.745] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:47:0)
[12:45:55.746] Timer tick, asking Buildbucket for the build status
[12:45:55.907] Build 8887030755454844448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:45:55.907] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:48:0) after 1m0s
[12:46:55.944] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:48:0)
[12:46:55.944] Timer tick, asking Buildbucket for the build status
[12:46:56.302] Build 8887030755454844448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:46:56.302] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:49:0) after 1m0s
[12:47:56.458] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:49:0)
[12:47:56.458] Timer tick, asking Buildbucket for the build status
[12:47:56.608] Build 8887030755454844448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:47:56.608] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:50:0) after 1m0s
[12:48:56.728] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:50:0)
[12:48:56.728] Timer tick, asking Buildbucket for the build status
[12:48:56.904] Build 8887030755454844448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:48:56.904] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:51:0) after 1m0s
[12:49:57.002] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:51:0)
[12:49:57.002] Timer tick, asking Buildbucket for the build status
[12:49:57.150] Build 8887030755454844448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:49:57.150] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:52:0) after 1m0s
[12:50:57.209] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:52:0)
[12:50:57.209] Timer tick, asking Buildbucket for the build status
[12:50:57.321] Build 8887030755454844448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:50:57.321] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:53:0) after 1m0s
[12:51:57.369] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:53:0)
[12:51:57.369] Timer tick, asking Buildbucket for the build status
[12:51:57.503] Build 8887030755454844448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:51:57.503] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:54:0) after 1m0s
[12:52:57.544] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:54:0)
[12:52:57.544] Timer tick, asking Buildbucket for the build status
[12:52:57.678] Build 8887030755454844448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:52:57.678] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:55:0) after 1m0s
[12:53:57.700] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:55:0)
[12:53:57.700] Timer tick, asking Buildbucket for the build status
[12:53:57.812] Build 8887030755454844448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:53:57.812] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:56:0) after 1m0s
[12:54:57.942] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:56:0)
[12:54:57.942] Timer tick, asking Buildbucket for the build status
[12:54:58.177] Build 8887030755454844448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:54:58.177] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:57:0) after 1m0s
[12:55:58.187] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:57:0)
[12:55:58.187] Timer tick, asking Buildbucket for the build status
[12:55:58.320] Build 8887030755454844448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:55:58.320] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:58:0) after 1m0s
[12:56:58.342] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:58:0)
[12:56:58.342] Timer tick, asking Buildbucket for the build status
[12:56:58.480] Build 8887030755454844448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:56:58.480] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:59:0) after 1m0s
[12:57:58.528] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:59:0)
[12:57:58.528] Timer tick, asking Buildbucket for the build status
[12:57:58.678] Build 8887030755454844448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:57:58.679] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:60:0) after 1m0s
[12:58:58.789] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:60:0)
[12:58:58.789] Timer tick, asking Buildbucket for the build status
[12:58:58.984] Build 8887030755454844448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:58:58.984] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:61:0) after 1m0s
[12:59:59.069] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:61:0)
[12:59:59.069] Timer tick, asking Buildbucket for the build status
[12:59:59.582] Build 8887030755454844448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:59:59.582] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:62:0) after 1m0s
[13:00:59.629] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:62:0)
[13:00:59.629] Timer tick, asking Buildbucket for the build status
[13:00:59.757] Build 8887030755454844448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:00:59.757] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:63:0) after 1m0s
[13:01:59.819] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:63:0)
[13:01:59.819] Timer tick, asking Buildbucket for the build status
[13:01:59.918] Build 8887030755454844448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:01:59.918] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:64:0) after 1m0s
[13:02:59.995] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:64:0)
[13:02:59.995] Timer tick, asking Buildbucket for the build status
[13:03:00.166] Build 8887030755454844448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:03:00.166] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:65:0) after 1m0s
[13:04:00.266] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:65:0)
[13:04:00.266] Timer tick, asking Buildbucket for the build status
[13:04:00.443] Build 8887030755454844448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:04:00.443] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:66:0) after 1m0s
[13:05:00.692] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:66:0)
[13:05:00.692] Timer tick, asking Buildbucket for the build status
[13:05:01.317] Build 8887030755454844448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:05:01.317] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:67:0) after 1m0s
[13:06:01.517] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:67:0)
[13:06:01.517] Timer tick, asking Buildbucket for the build status
[13:06:01.636] Build 8887030755454844448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:06:01.636] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:68:0) after 1m0s
[13:07:01.682] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:68:0)
[13:07:01.682] Timer tick, asking Buildbucket for the build status
[13:07:01.802] Build 8887030755454844448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:07:01.803] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:69:0) after 1m0s
[13:08:01.900] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:69:0)
[13:08:01.900] Timer tick, asking Buildbucket for the build status
[13:08:02.195] Build 8887030755454844448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:08:02.195] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:70:0) after 1m0s
[13:09:02.408] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:70:0)
[13:09:02.408] Timer tick, asking Buildbucket for the build status
[13:09:02.634] Build 8887030755454844448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:09:02.634] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:71:0) after 1m0s
[13:10:02.755] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:71:0)
[13:10:02.755] Timer tick, asking Buildbucket for the build status
[13:10:02.949] Build 8887030755454844448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:10:02.949] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:72:0) after 1m0s
[13:11:03.000] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:72:0)
[13:11:03.000] Timer tick, asking Buildbucket for the build status
[13:11:03.464] Build 8887030755454844448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:11:03.464] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:73:0) after 1m0s
[13:12:03.552] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:73:0)
[13:12:03.552] Timer tick, asking Buildbucket for the build status
[13:12:03.857] Build 8887030755454844448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:12:03.857] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:74:0) after 1m0s
[13:13:03.910] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:74:0)
[13:13:03.910] Timer tick, asking Buildbucket for the build status
[13:13:04.024] Build 8887030755454844448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:13:04.024] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:75:0) after 1m0s
[13:14:04.162] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:75:0)
[13:14:04.162] Timer tick, asking Buildbucket for the build status
[13:14:04.306] Build 8887030755454844448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:14:04.306] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:76:0) after 1m0s
[13:15:04.373] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:76:0)
[13:15:04.373] Timer tick, asking Buildbucket for the build status
[13:15:04.504] Build 8887030755454844448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:15:04.504] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:77:0) after 1m0s
[13:16:04.698] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:77:0)
[13:16:04.698] Timer tick, asking Buildbucket for the build status
[13:16:06.235] Build 8887030755454844448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:16:06.235] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:78:0) after 1m0s
[13:17:06.391] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:78:0)
[13:17:06.391] Timer tick, asking Buildbucket for the build status
[13:17:06.531] Build 8887030755454844448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:17:06.531] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:79:0) after 1m0s
[13:18:06.583] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:79:0)
[13:18:06.583] Timer tick, asking Buildbucket for the build status
[13:18:06.776] Build 8887030755454844448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:18:06.776] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:80:0) after 1m0s
[13:19:06.822] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:80:0)
[13:19:06.822] Timer tick, asking Buildbucket for the build status
[13:19:07.012] Build 8887030755454844448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:19:07.012] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:81:0) after 1m0s
[13:20:07.067] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:81:0)
[13:20:07.067] Timer tick, asking Buildbucket for the build status
[13:20:07.280] Build 8887030755454844448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:20:07.280] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:82:0) after 1m0s
[13:21:07.322] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:82:0)
[13:21:07.322] Timer tick, asking Buildbucket for the build status
[13:21:07.542] Build 8887030755454844448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:21:07.542] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:83:0) after 1m0s
[13:22:07.655] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:83:0)
[13:22:07.660] Timer tick, asking Buildbucket for the build status
[13:22:07.828] Build 8887030755454844448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:22:07.828] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:84:0) after 1m0s
[13:23:07.902] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:84:0)
[13:23:07.902] Timer tick, asking Buildbucket for the build status
[13:23:08.512] Build 8887030755454844448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:23:08.512] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:85:0) after 1m0s
[13:24:08.621] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:85:0)
[13:24:08.621] Timer tick, asking Buildbucket for the build status
[13:24:08.901] Build 8887030755454844448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:24:08.901] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:86:0) after 1m0s
[13:25:09.063] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:86:0)
[13:25:09.063] Timer tick, asking Buildbucket for the build status
[13:25:09.286] Build 8887030755454844448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:25:09.286] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:87:0) after 1m0s
[13:26:09.357] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:87:0)
[13:26:09.357] Timer tick, asking Buildbucket for the build status
[13:26:09.455] Build 8887030755454844448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:26:09.455] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:88:0) after 1m0s
[13:27:09.521] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:88:0)
[13:27:09.521] Timer tick, asking Buildbucket for the build status
[13:27:09.689] Build 8887030755454844448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:27:09.689] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:89:0) after 1m0s
[13:28:09.714] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:89:0)
[13:28:09.714] Timer tick, asking Buildbucket for the build status
[13:28:09.890] Build 8887030755454844448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:28:09.890] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:90:0) after 1m0s
[13:29:09.928] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:90:0)
[13:29:09.928] Timer tick, asking Buildbucket for the build status
[13:29:10.237] Build 8887030755454844448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:29:10.237] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:91:0) after 1m0s
[13:30:10.360] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:91:0)
[13:30:10.360] Timer tick, asking Buildbucket for the build status
[13:30:10.488] Build 8887030755454844448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:30:10.488] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:92:0) after 1m0s
[13:31:10.601] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:92:0)
[13:31:10.601] Timer tick, asking Buildbucket for the build status
[13:31:10.874] Build 8887030755454844448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:31:10.874] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:93:0) after 1m0s
[13:32:10.894] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:93:0)
[13:32:10.894] Timer tick, asking Buildbucket for the build status
[13:32:11.346] Build 8887030755454844448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:32:11.346] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:94:0) after 1m0s
[13:33:11.494] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:94:0)
[13:33:11.494] Timer tick, asking Buildbucket for the build status
[13:33:11.945] Build 8887030755454844448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:33:11.945] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:95:0) after 1m0s
[13:34:11.979] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:95:0)
[13:34:11.979] Timer tick, asking Buildbucket for the build status
[13:34:12.322] Build 8887030755454844448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:34:12.322] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:96:0) after 1m0s
[13:35:12.395] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:96:0)
[13:35:12.395] Timer tick, asking Buildbucket for the build status
[13:35:12.521] Build 8887030755454844448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:35:12.521] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:97:0) after 1m0s
[13:36:12.579] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:97:0)
[13:36:12.579] Timer tick, asking Buildbucket for the build status
[13:36:12.707] Build 8887030755454844448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:36:12.707] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:98:0) after 1m0s
[13:37:12.762] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:98:0)
[13:37:12.762] Timer tick, asking Buildbucket for the build status
[13:37:13.099] Build 8887030755454844448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:37:13.099] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:99:0) after 1m0s
[13:38:13.157] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:99:0)
[13:38:13.157] Timer tick, asking Buildbucket for the build status
[13:38:13.379] Build 8887030755454844448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:38:13.379] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:100:0) after 1m0s
[13:39:13.525] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:100:0)
[13:39:13.526] Timer tick, asking Buildbucket for the build status
[13:39:13.767] Build 8887030755454844448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:39:13.767] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:101:0) after 1m0s
[13:40:13.820] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:101:0)
[13:40:13.820] Timer tick, asking Buildbucket for the build status
[13:40:13.914] Build 8887030755454844448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:40:13.914] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:102:0) after 1m0s
[13:41:13.967] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:102:0)
[13:41:13.967] Timer tick, asking Buildbucket for the build status
[13:41:14.326] Build 8887030755454844448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:41:14.327] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:103:0) after 1m0s
[13:42:14.373] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:103:0)
[13:42:14.373] Timer tick, asking Buildbucket for the build status
[13:42:14.930] Build 8887030755454844448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:42:14.931] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:104:0) after 1m0s
[13:43:14.996] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:104:0)
[13:43:14.996] Timer tick, asking Buildbucket for the build status
[13:43:15.129] Build 8887030755454844448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:43:15.129] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:105:0) after 1m0s
[13:44:15.262] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:105:0)
[13:44:15.262] Timer tick, asking Buildbucket for the build status
[13:44:15.575] Build 8887030755454844448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:44:15.575] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:106:0) after 1m0s
[13:45:15.632] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:106:0)
[13:45:15.632] Timer tick, asking Buildbucket for the build status
[13:45:15.993] Build 8887030755454844448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:45:15.993] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:107:0) after 1m0s
[13:46:16.135] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:107:0)
[13:46:16.135] Timer tick, asking Buildbucket for the build status
[13:46:16.591] Build 8887030755454844448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:46:16.591] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:108:0) after 1m0s
[13:47:16.621] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:108:0)
[13:47:16.621] Timer tick, asking Buildbucket for the build status
[13:47:16.734] Build 8887030755454844448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:47:16.734] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:109:0) after 1m0s
[13:48:16.798] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:109:0)
[13:48:16.798] Timer tick, asking Buildbucket for the build status
[13:48:17.240] Build 8887030755454844448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:48:17.240] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:110:0) after 1m0s
[13:49:17.338] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:110:0)
[13:49:17.338] Timer tick, asking Buildbucket for the build status
[13:49:17.504] Build 8887030755454844448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:49:17.504] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:111:0) after 1m0s
[13:50:17.582] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:111:0)
[13:50:17.582] Timer tick, asking Buildbucket for the build status
[13:50:17.889] Build 8887030755454844448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:50:17.889] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:112:0) after 1m0s
[13:51:17.909] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:112:0)
[13:51:17.909] Timer tick, asking Buildbucket for the build status
[13:51:18.222] Build 8887030755454844448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:51:18.222] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:113:0) after 1m0s
[13:52:18.343] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:113:0)
[13:52:18.343] Timer tick, asking Buildbucket for the build status
[13:52:18.519] Build 8887030755454844448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:52:18.519] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:114:0) after 1m0s
[13:53:18.678] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:114:0)
[13:53:18.678] Timer tick, asking Buildbucket for the build status
[13:53:18.953] Build 8887030755454844448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:53:18.953] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:115:0) after 1m0s
[13:54:19.077] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:115:0)
[13:54:19.078] Timer tick, asking Buildbucket for the build status
[13:54:19.213] Build 8887030755454844448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:54:19.213] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:116:0) after 1m0s
[13:55:19.236] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:116:0)
[13:55:19.236] Timer tick, asking Buildbucket for the build status
[13:55:19.390] Build 8887030755454844448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:55:19.390] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:117:0) after 1m0s
[13:56:22.392] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:117:0)
[13:56:22.392] Timer tick, asking Buildbucket for the build status
[13:56:22.529] Build 8887030755454844448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:56:22.529] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:118:0) after 1m0s
[13:57:22.592] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:118:0)
[13:57:22.592] Timer tick, asking Buildbucket for the build status
[13:57:23.093] Build 8887030755454844448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:57:23.093] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:119:0) after 1m0s
[13:58:23.204] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:119:0)
[13:58:23.204] Timer tick, asking Buildbucket for the build status
[13:58:23.472] Build 8887030755454844448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:58:23.472] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:120:0) after 1m0s
[13:59:23.563] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:120:0)
[13:59:23.563] Timer tick, asking Buildbucket for the build status
[13:59:23.756] Build 8887030755454844448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:59:23.756] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:121:0) after 1m0s
[14:00:23.778] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:121:0)
[14:00:23.778] Timer tick, asking Buildbucket for the build status
[14:00:23.921] Build 8887030755454844448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:00:23.921] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:122:0) after 1m0s
[14:01:23.987] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:122:0)
[14:01:23.987] Timer tick, asking Buildbucket for the build status
[14:01:24.167] Build 8887030755454844448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:01:24.167] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:123:0) after 1m0s
[14:02:24.331] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:123:0)
[14:02:24.331] Timer tick, asking Buildbucket for the build status
[14:02:24.472] Build 8887030755454844448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:02:24.472] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:124:0) after 1m0s
[14:03:24.499] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:124:0)
[14:03:24.499] Timer tick, asking Buildbucket for the build status
[14:03:25.000] Build 8887030755454844448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:03:25.000] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:125:0) after 1m0s
[14:04:24.987] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:125:0)
[14:04:24.987] Timer tick, asking Buildbucket for the build status
[14:04:25.218] Build 8887030755454844448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:04:25.218] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:126:0) after 1m0s
[14:05:25.257] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:126:0)
[14:05:25.257] Timer tick, asking Buildbucket for the build status
[14:05:25.546] Build 8887030755454844448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:05:25.546] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:127:0) after 1m0s
[14:06:25.575] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:127:0)
[14:06:25.575] Timer tick, asking Buildbucket for the build status
[14:06:25.736] Build 8887030755454844448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:06:25.736] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:128:0) after 1m0s
[14:07:25.810] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:128:0)
[14:07:25.810] Timer tick, asking Buildbucket for the build status
[14:07:26.142] Build 8887030755454844448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:07:26.142] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:129:0) after 1m0s
[14:08:26.274] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:129:0)
[14:08:26.274] Timer tick, asking Buildbucket for the build status
[14:08:26.415] Build 8887030755454844448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:08:26.415] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:130:0) after 1m0s
[14:09:26.526] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:130:0)
[14:09:26.526] Timer tick, asking Buildbucket for the build status
[14:09:26.735] Build 8887030755454844448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:09:26.735] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:131:0) after 1m0s
[14:10:26.820] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:131:0)
[14:10:26.820] Timer tick, asking Buildbucket for the build status
[14:10:27.005] Build 8887030755454844448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:10:27.005] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:132:0) after 1m0s
[14:11:27.150] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:132:0)
[14:11:27.150] Timer tick, asking Buildbucket for the build status
[14:11:27.359] Build 8887030755454844448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:11:27.359] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:133:0) after 1m0s
[14:12:27.490] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:133:0)
[14:12:27.490] Timer tick, asking Buildbucket for the build status
[14:12:27.618] Build 8887030755454844448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:12:27.618] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:134:0) after 1m0s
[14:13:27.733] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:134:0)
[14:13:27.733] Timer tick, asking Buildbucket for the build status
[14:13:27.878] Build 8887030755454844448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:13:27.878] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:135:0) after 1m0s
[14:14:27.911] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:135:0)
[14:14:27.911] Timer tick, asking Buildbucket for the build status
[14:14:28.114] Build 8887030755454844448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:14:28.114] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:136:0) after 1m0s
[14:15:28.183] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:136:0)
[14:15:28.183] Timer tick, asking Buildbucket for the build status
[14:15:28.319] Build 8887030755454844448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:15:28.319] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:137:0) after 1m0s
[14:16:28.516] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:137:0)
[14:16:28.516] Timer tick, asking Buildbucket for the build status
[14:16:28.811] Build 8887030755454844448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:16:28.811] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:138:0) after 1m0s
[14:17:28.879] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:138:0)
[14:17:28.879] Timer tick, asking Buildbucket for the build status
[14:17:29.164] Build 8887030755454844448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:17:29.164] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:139:0) after 1m0s
[14:18:29.176] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:139:0)
[14:18:29.176] Timer tick, asking Buildbucket for the build status
[14:18:29.307] Build 8887030755454844448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:18:29.307] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:140:0) after 1m0s
[14:19:29.422] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:140:0)
[14:19:29.422] Timer tick, asking Buildbucket for the build status
[14:19:29.576] Build 8887030755454844448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:19:29.576] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:141:0) after 1m0s
[14:20:29.750] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:141:0)
[14:20:29.750] Timer tick, asking Buildbucket for the build status
[14:20:30.091] Build 8887030755454844448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:20:30.091] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:142:0) after 1m0s
[14:21:30.305] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:142:0)
[14:21:30.306] Timer tick, asking Buildbucket for the build status
[14:21:30.483] Build 8887030755454844448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:21:30.483] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:143:0) after 1m0s
[14:22:30.533] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:143:0)
[14:22:30.533] Timer tick, asking Buildbucket for the build status
[14:22:30.765] Build 8887030755454844448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:22:30.765] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:144:0) after 1m0s
[14:23:30.881] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:144:0)
[14:23:30.881] Timer tick, asking Buildbucket for the build status
[14:23:31.092] Build 8887030755454844448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:23:31.092] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:145:0) after 1m0s
[14:24:31.127] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:145:0)
[14:24:31.127] Timer tick, asking Buildbucket for the build status
[14:24:31.236] Build 8887030755454844448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:24:31.236] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:146:0) after 1m0s
[14:25:31.324] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:146:0)
[14:25:31.324] Timer tick, asking Buildbucket for the build status
[14:25:31.542] Build 8887030755454844448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:25:31.542] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:147:0) after 1m0s
[14:26:31.767] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:147:0)
[14:26:31.767] Timer tick, asking Buildbucket for the build status
[14:26:31.929] Build 8887030755454844448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:26:31.929] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:148:0) after 1m0s
[14:27:32.062] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:148:0)
[14:27:32.062] Timer tick, asking Buildbucket for the build status
[14:27:32.429] Build 8887030755454844448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:27:32.429] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:149:0) after 1m0s
[14:28:32.652] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:149:0)
[14:28:32.652] Timer tick, asking Buildbucket for the build status
[14:28:32.819] Build 8887030755454844448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:28:32.819] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:150:0) after 1m0s
[14:29:32.870] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:150:0)
[14:29:32.870] Timer tick, asking Buildbucket for the build status
[14:29:33.203] Build 8887030755454844448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:29:33.203] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:151:0) after 1m0s
[14:30:33.237] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:151:0)
[14:30:33.237] Timer tick, asking Buildbucket for the build status
[14:30:33.409] Build 8887030755454844448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:30:33.409] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:152:0) after 1m0s
[14:31:33.552] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:152:0)
[14:31:33.552] Timer tick, asking Buildbucket for the build status
[14:31:33.894] Build 8887030755454844448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:31:33.894] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:153:0) after 1m0s
[14:32:34.101] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:153:0)
[14:32:34.101] Timer tick, asking Buildbucket for the build status
[14:32:34.244] Build 8887030755454844448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:32:34.244] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:154:0) after 1m0s
[14:33:34.271] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:154:0)
[14:33:34.271] Timer tick, asking Buildbucket for the build status
[14:33:34.530] Build 8887030755454844448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:33:34.530] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:155:0) after 1m0s
[14:34:34.648] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:155:0)
[14:34:34.648] Timer tick, asking Buildbucket for the build status
[14:34:34.901] Build 8887030755454844448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:34:34.901] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:156:0) after 1m0s
[14:35:35.024] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:156:0)
[14:35:35.024] Timer tick, asking Buildbucket for the build status
[14:35:35.142] Build 8887030755454844448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:35:35.142] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:157:0) after 1m0s
[14:36:35.145] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:157:0)
[14:36:35.145] Timer tick, asking Buildbucket for the build status
[14:36:35.452] Build 8887030755454844448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:36:35.452] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:158:0) after 1m0s
[14:37:35.514] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:158:0)
[14:37:35.517] Timer tick, asking Buildbucket for the build status
[14:37:35.799] Build 8887030755454844448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:37:35.799] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:159:0) after 1m0s
[14:38:35.884] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:159:0)
[14:38:35.884] Timer tick, asking Buildbucket for the build status
[14:38:36.015] Build 8887030755454844448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:38:36.015] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:160:0) after 1m0s
[14:39:36.055] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:160:0)
[14:39:36.055] Timer tick, asking Buildbucket for the build status
[14:39:36.211] Build 8887030755454844448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:39:36.211] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:161:0) after 1m0s
[14:40:36.349] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:161:0)
[14:40:36.349] Timer tick, asking Buildbucket for the build status
[14:40:36.543] Build 8887030755454844448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:40:36.543] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:162:0) after 1m0s
[14:41:36.572] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:162:0)
[14:41:36.572] Timer tick, asking Buildbucket for the build status
[14:41:36.799] Build 8887030755454844448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:41:36.799] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:163:0) after 1m0s
[14:42:36.938] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:163:0)
[14:42:36.938] Timer tick, asking Buildbucket for the build status
[14:42:37.253] Build 8887030755454844448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:42:37.253] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:164:0) after 1m0s
[14:43:37.356] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:164:0)
[14:43:37.356] Timer tick, asking Buildbucket for the build status
[14:43:37.685] Build 8887030755454844448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:43:37.685] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052460817412222480:165:0) after 1m0s
[14:44:00.533] Received PubSub notification, asking Buildbucket for the build status
[14:44:00.618] Build 8887030755454844448: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[14:44:00.618] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1583073839843411",
"created_by": "project:chromium",
"created_ts": "1583064041618901",
"failure_reason": "BUILD_FAILURE",
"id": "8887030755454844448",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"2f7180bd6ca4710a80d48d99f00f1deae0426bed\"}}",
"project": "chromium",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"#### Step _Second build_ failed. Error logs are shown below:\\n```\\nFailing build because ninja reported work to do.\\nThis means that after completing a compile, another was run and\\nit resulted in still having work to do (that is, a no-op build\\nwasn't a no-op). Consult the first \\\"ninja explain:\\\" line for a\\nlikely culprit.\\n```\\n#### More information in raw_io.output[failure_summary]\"}, \"properties\": {\"$build/goma\": {\"enable_ats\": true, \"rpc_extra_params\": \"?prod\", \"server_host\": \"goma.chromium.org\"}, \"$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-vu3c\", \"branch\": \"refs/heads/master\", \"buildername\": \"Windows deterministic\", \"buildnumber\": 15964, \"got_angle_revision\": \"daf6e47111c3fa7223d512de695874c8f1c6a77e\", \"got_dawn_revision\": \"3ded65e8072e0cdccd0d0edebccffde37d4428b0\", \"got_nacl_revision\": \"35bcc9f6d5e81c3c39ad57de97ef9cd22301ee9e\", \"got_revision\": \"2f7180bd6ca4710a80d48d99f00f1deae0426bed\", \"got_revision_cp\": \"refs/heads/master@{#745792}\", \"got_swarming_client_revision\": \"0ac28472d073a515124c85b128c4378a914e0a4d\", \"got_swiftshader_revision\": \"416d374522757fc042282b3ae61c53f5510421fc\", \"got_v8_revision\": \"939dfd0598dd76cd40563054f32575191aa3be40\", \"got_v8_revision_cp\": \"refs/heads/8.2.222@{#1}\", \"got_webrtc_revision\": \"a08b2384a3245082cd40e8682676252ebebb1fe8\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#30652}\", \"mastername\": \"chromium.win\", \"path_config\": \"generic\", \"recipe\": \"swarming/deterministic_build\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"2f7180bd6ca4710a80d48d99f00f1deae0426bed\"}, \"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\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"windows-deterministic-0-vu3c\"], \"image\": [\"chrome-win10-1703-19112100-3085293b610\"], \"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\": [\"4840-d3c86f9\"], \"ssd\": [\"0\"], \"windows_client_version\": [\"10\"], \"zone\": [\"us\", \"us-west\", \"us-west1\", \"us-west1-c\"]}}, \"ui\": {\"info\": \"#### Step _Second build_ failed. Error logs are shown below:\\n```\\nFailing build because ninja reported work to do.\\nThis means that after completing a compile, another was run and\\nit resulted in still having work to do (that is, a no-op build\\nwasn't a no-op). Consult the first \\\"ninja explain:\\\" line for a\\nlikely culprit.\\n```\\n#### More information in raw_io.output[failure_summary]\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1583064979401536",
"status": "COMPLETED",
"status_changed_ts": "1583064979767927",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/15964",
"builder:Windows deterministic",
"buildset:commit/git/2f7180bd6ca4710a80d48d99f00f1deae0426bed",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/2f7180bd6ca4710a80d48d99f00f1deae0426bed",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9052460817412222480",
"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/8887030755454844448/+/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:4aaec9d741d67d10",
"user_agent:luci-scheduler"
],
"updated_ts": "1583073840038930",
"url": "https://ci.chromium.org/b/8887030755454844448",
"utcnow_ts": "1583073840610219"
}
}
[14:44:00.618] Invocation finished in 2h43m20.250549081s with status FAILED