[23:08:12.185] New invocation is queued and will start shortly
[23:08:13.291] Starting the invocation (attempt 1)
[23:08:13.356] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[23:08:13.356] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[23:08:13.356] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9067457916484617600",
"parameters_json": "{\"builder_name\":\"Windows deterministic\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src.git\",\"revision\":\"e40444831ecd125cb9f79adcd32d04736227b1b8\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Windows deterministic",
"scheduler_invocation_id:9067457916484617600",
"scheduler_job_id:chromium/Windows deterministic",
"user_agent:luci-scheduler",
"buildset:commit/git/e40444831ecd125cb9f79adcd32d04736227b1b8",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/e40444831ecd125cb9f79adcd32d04736227b1b8",
"gitiles_ref:refs/heads/master"
]
}
[23:08:14.158] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1568761693604189",
"id": "8902027854325404976",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"e40444831ecd125cb9f79adcd32d04736227b1b8\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1568761693996946",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/14745",
"builder:Windows deterministic",
"buildset:commit/git/e40444831ecd125cb9f79adcd32d04736227b1b8",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/e40444831ecd125cb9f79adcd32d04736227b1b8",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9067457916484617600",
"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/8902027854325404976/+/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": "1568761693997098",
"url": "https://ci.chromium.org/b/8902027854325404976",
"utcnow_ts": "1568761694148541"
}
}
[23:08:14.158] Task URL: https://ci.chromium.org/b/8902027854325404976
[23:08:14.158] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:2:0) after 1m0s
[23:08:56.501] Received PubSub notification, asking Buildbucket for the build status
[23:08:57.601] Build 8902027854325404976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:09:14.190] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:2:0)
[23:09:14.190] Timer tick, asking Buildbucket for the build status
[23:09:14.900] Build 8902027854325404976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:09:14.900] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:4:0) after 1m0s
[23:10:14.987] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:4:0)
[23:10:14.987] Timer tick, asking Buildbucket for the build status
[23:10:15.359] Build 8902027854325404976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:10:15.359] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:5:0) after 1m0s
[23:11:15.339] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:5:0)
[23:11:15.339] Timer tick, asking Buildbucket for the build status
[23:11:15.552] Build 8902027854325404976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:11:15.552] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:6:0) after 1m0s
[23:12:15.825] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:6:0)
[23:12:15.825] Timer tick, asking Buildbucket for the build status
[23:12:19.417] Build 8902027854325404976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:12:19.417] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:7:0) after 1m0s
[23:13:19.522] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:7:0)
[23:13:19.522] Timer tick, asking Buildbucket for the build status
[23:13:22.605] Build 8902027854325404976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:13:22.605] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:8:0) after 1m0s
[23:14:22.677] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:8:0)
[23:14:22.677] Timer tick, asking Buildbucket for the build status
[23:14:24.948] Build 8902027854325404976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:14:24.948] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:9:0) after 1m0s
[23:15:25.038] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:9:0)
[23:15:25.038] Timer tick, asking Buildbucket for the build status
[23:15:25.292] Build 8902027854325404976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:15:25.292] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:10:0) after 1m0s
[23:16:25.333] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:10:0)
[23:16:25.333] Timer tick, asking Buildbucket for the build status
[23:16:25.534] Build 8902027854325404976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:16:25.534] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:11:0) after 1m0s
[23:17:25.522] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:11:0)
[23:17:25.522] Timer tick, asking Buildbucket for the build status
[23:17:25.991] Build 8902027854325404976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:17:25.991] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:12:0) after 1m0s
[23:18:26.049] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:12:0)
[23:18:26.049] Timer tick, asking Buildbucket for the build status
[23:18:26.547] Build 8902027854325404976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:18:26.547] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:13:0) after 1m0s
[23:19:26.571] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:13:0)
[23:19:26.571] Timer tick, asking Buildbucket for the build status
[23:19:26.797] Build 8902027854325404976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:19:26.797] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:14:0) after 1m0s
[23:20:26.897] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:14:0)
[23:20:26.897] Timer tick, asking Buildbucket for the build status
[23:20:27.504] Build 8902027854325404976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:20:27.504] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:15:0) after 1m0s
[23:21:27.549] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:15:0)
[23:21:27.549] Timer tick, asking Buildbucket for the build status
[23:21:27.859] Build 8902027854325404976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:21:27.859] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:16:0) after 1m0s
[23:22:27.987] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:16:0)
[23:22:27.987] Timer tick, asking Buildbucket for the build status
[23:22:30.919] Build 8902027854325404976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:22:30.919] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:17:0) after 1m0s
[23:23:30.948] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:17:0)
[23:23:30.948] Timer tick, asking Buildbucket for the build status
[23:23:31.426] Build 8902027854325404976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:23:31.426] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:18:0) after 1m0s
[23:24:31.465] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:18:0)
[23:24:31.465] Timer tick, asking Buildbucket for the build status
[23:24:32.135] Build 8902027854325404976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:24:32.135] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:19:0) after 1m0s
[23:25:32.179] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:19:0)
[23:25:32.179] Timer tick, asking Buildbucket for the build status
[23:25:38.304] Build 8902027854325404976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:25:38.304] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:20:0) after 1m0s
[23:26:38.352] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:20:0)
[23:26:38.352] Timer tick, asking Buildbucket for the build status
[23:26:38.602] Build 8902027854325404976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:26:38.602] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:21:0) after 1m0s
[23:27:38.656] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:21:0)
[23:27:38.656] Timer tick, asking Buildbucket for the build status
[23:27:40.164] Build 8902027854325404976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:27:40.164] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:22:0) after 1m0s
[23:28:40.194] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:22:0)
[23:28:40.194] Timer tick, asking Buildbucket for the build status
[23:28:42.741] Build 8902027854325404976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:28:42.741] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:23:0) after 1m0s
[23:29:42.837] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:23:0)
[23:29:42.837] Timer tick, asking Buildbucket for the build status
[23:29:43.119] Build 8902027854325404976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:29:43.119] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:24:0) after 1m0s
[23:30:43.227] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:24:0)
[23:30:43.227] Timer tick, asking Buildbucket for the build status
[23:30:43.882] Build 8902027854325404976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:30:43.882] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:25:0) after 1m0s
[23:31:43.882] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:25:0)
[23:31:43.882] Timer tick, asking Buildbucket for the build status
[23:31:50.726] Build 8902027854325404976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:31:50.726] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:26:0) after 1m0s
[23:32:50.831] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:26:0)
[23:32:50.831] Timer tick, asking Buildbucket for the build status
[23:32:50.993] Build 8902027854325404976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:32:50.993] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:27:0) after 1m0s
[23:33:51.037] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:27:0)
[23:33:51.037] Timer tick, asking Buildbucket for the build status
[23:33:51.537] Build 8902027854325404976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:33:51.537] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:28:0) after 1m0s
[23:34:51.581] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:28:0)
[23:34:51.581] Timer tick, asking Buildbucket for the build status
[23:34:52.183] Build 8902027854325404976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:34:52.183] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:29:0) after 1m0s
[23:35:52.239] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:29:0)
[23:35:52.239] Timer tick, asking Buildbucket for the build status
[23:35:52.553] Build 8902027854325404976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:35:52.553] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:30:0) after 1m0s
[23:36:52.604] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:30:0)
[23:36:52.604] Timer tick, asking Buildbucket for the build status
[23:36:53.103] Build 8902027854325404976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:36:53.103] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:31:0) after 1m0s
[23:37:53.186] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:31:0)
[23:37:53.186] Timer tick, asking Buildbucket for the build status
[23:37:53.395] Build 8902027854325404976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:37:53.395] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:32:0) after 1m0s
[23:38:53.437] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:32:0)
[23:38:53.437] Timer tick, asking Buildbucket for the build status
[23:38:53.919] Build 8902027854325404976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:38:53.919] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:33:0) after 1m0s
[23:39:53.989] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:33:0)
[23:39:53.989] Timer tick, asking Buildbucket for the build status
[23:39:55.114] Build 8902027854325404976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:39:55.115] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:34:0) after 1m0s
[23:40:55.173] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:34:0)
[23:40:55.173] Timer tick, asking Buildbucket for the build status
[23:40:58.294] Build 8902027854325404976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:40:58.294] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:35:0) after 1m0s
[23:41:58.292] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:35:0)
[23:41:58.292] Timer tick, asking Buildbucket for the build status
[23:41:59.116] Build 8902027854325404976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:41:59.116] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:36:0) after 1m0s
[23:42:59.169] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:36:0)
[23:42:59.169] Timer tick, asking Buildbucket for the build status
[23:42:59.417] Build 8902027854325404976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:42:59.417] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:37:0) after 1m0s
[23:43:59.470] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:37:0)
[23:43:59.470] Timer tick, asking Buildbucket for the build status
[23:43:59.841] Build 8902027854325404976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:43:59.841] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:38:0) after 1m0s
[23:44:59.848] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:38:0)
[23:44:59.848] Timer tick, asking Buildbucket for the build status
[23:45:00.753] Build 8902027854325404976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:45:00.753] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:39:0) after 1m0s
[23:46:00.778] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:39:0)
[23:46:00.778] Timer tick, asking Buildbucket for the build status
[23:46:01.556] Build 8902027854325404976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:46:01.556] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:40:0) after 1m0s
[23:47:01.608] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:40:0)
[23:47:01.608] Timer tick, asking Buildbucket for the build status
[23:47:01.774] Build 8902027854325404976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:47:01.774] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:41:0) after 1m0s
[23:48:01.852] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:41:0)
[23:48:01.852] Timer tick, asking Buildbucket for the build status
[23:48:02.319] Build 8902027854325404976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:48:02.319] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:42:0) after 1m0s
[23:49:02.368] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:42:0)
[23:49:02.368] Timer tick, asking Buildbucket for the build status
[23:49:02.701] Build 8902027854325404976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:49:02.701] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:43:0) after 1m0s
[23:50:02.771] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:43:0)
[23:50:02.771] Timer tick, asking Buildbucket for the build status
[23:50:03.706] Build 8902027854325404976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:50:03.706] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:44:0) after 1m0s
[23:51:03.769] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:44:0)
[23:51:03.769] Timer tick, asking Buildbucket for the build status
[23:51:04.425] Build 8902027854325404976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:51:04.425] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:45:0) after 1m0s
[23:52:04.544] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:45:0)
[23:52:04.544] Timer tick, asking Buildbucket for the build status
[23:52:05.710] Build 8902027854325404976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:52:05.710] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:46:0) after 1m0s
[23:53:05.763] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:46:0)
[23:53:05.763] Timer tick, asking Buildbucket for the build status
[23:53:05.943] Build 8902027854325404976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:53:05.943] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:47:0) after 1m0s
[23:54:05.971] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:47:0)
[23:54:05.971] Timer tick, asking Buildbucket for the build status
[23:54:06.666] Build 8902027854325404976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:54:06.666] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:48:0) after 1m0s
[23:55:06.751] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:48:0)
[23:55:06.751] Timer tick, asking Buildbucket for the build status
[23:55:07.040] Build 8902027854325404976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:55:07.040] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:49:0) after 1m0s
[23:56:07.085] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:49:0)
[23:56:07.086] Timer tick, asking Buildbucket for the build status
[23:56:07.226] Build 8902027854325404976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:56:07.226] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:50:0) after 1m0s
[23:57:07.263] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:50:0)
[23:57:07.263] Timer tick, asking Buildbucket for the build status
[23:57:07.714] Build 8902027854325404976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:57:07.714] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:51:0) after 1m0s
[23:58:07.756] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:51:0)
[23:58:07.756] Timer tick, asking Buildbucket for the build status
[23:58:14.389] Build 8902027854325404976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:58:14.389] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:52:0) after 1m0s
[23:59:14.811] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:52:0)
[23:59:14.811] Timer tick, asking Buildbucket for the build status
[23:59:15.108] Build 8902027854325404976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:59:15.108] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:53:0) after 1m0s
[00:00:15.346] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:53:0)
[00:00:15.346] Timer tick, asking Buildbucket for the build status
[00:00:15.569] Build 8902027854325404976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:00:15.569] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:54:0) after 1m0s
[00:01:15.862] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:54:0)
[00:01:15.862] Timer tick, asking Buildbucket for the build status
[00:01:16.465] Build 8902027854325404976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:01:16.465] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:55:0) after 1m0s
[00:02:16.643] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:55:0)
[00:02:16.643] Timer tick, asking Buildbucket for the build status
[00:02:17.198] Build 8902027854325404976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:02:17.198] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:56:0) after 1m0s
[00:03:17.227] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:56:0)
[00:03:17.228] Timer tick, asking Buildbucket for the build status
[00:03:20.201] Build 8902027854325404976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:03:20.201] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:57:0) after 1m0s
[00:04:20.406] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:57:0)
[00:04:20.406] Timer tick, asking Buildbucket for the build status
[00:04:23.994] Build 8902027854325404976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:04:23.994] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:58:0) after 1m0s
[00:05:24.186] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:58:0)
[00:05:24.186] Timer tick, asking Buildbucket for the build status
[00:05:25.106] Build 8902027854325404976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:05:25.106] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:59:0) after 1m0s
[00:06:25.316] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:59:0)
[00:06:25.316] Timer tick, asking Buildbucket for the build status
[00:06:31.093] Build 8902027854325404976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:06:31.093] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:60:0) after 1m0s
[00:07:31.150] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:60:0)
[00:07:31.150] Timer tick, asking Buildbucket for the build status
[00:07:34.303] Build 8902027854325404976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:07:34.303] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:61:0) after 1m0s
[00:08:34.375] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:61:0)
[00:08:34.375] Timer tick, asking Buildbucket for the build status
[00:08:36.306] Build 8902027854325404976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:08:36.307] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:62:0) after 1m0s
[00:09:36.422] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:62:0)
[00:09:36.422] Timer tick, asking Buildbucket for the build status
[00:09:36.601] Build 8902027854325404976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:09:36.601] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:63:0) after 1m0s
[00:10:36.807] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:63:0)
[00:10:36.807] Timer tick, asking Buildbucket for the build status
[00:10:37.146] Build 8902027854325404976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:10:37.146] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:64:0) after 1m0s
[00:11:37.184] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:64:0)
[00:11:37.184] Timer tick, asking Buildbucket for the build status
[00:11:37.513] Build 8902027854325404976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:11:37.513] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:65:0) after 1m0s
[00:12:37.848] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:65:0)
[00:12:37.848] Timer tick, asking Buildbucket for the build status
[00:12:38.354] Build 8902027854325404976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:12:38.354] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:66:0) after 1m0s
[00:13:38.441] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:66:0)
[00:13:38.441] Timer tick, asking Buildbucket for the build status
[00:13:45.823] Build 8902027854325404976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:13:45.823] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:67:0) after 1m0s
[00:14:45.821] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:67:0)
[00:14:45.821] Timer tick, asking Buildbucket for the build status
[00:14:46.538] Build 8902027854325404976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:14:46.538] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:68:0) after 1m0s
[00:15:46.678] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:68:0)
[00:15:46.678] Timer tick, asking Buildbucket for the build status
[00:15:46.963] Build 8902027854325404976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:15:46.963] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:69:0) after 1m0s
[00:16:47.091] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:69:0)
[00:16:47.091] Timer tick, asking Buildbucket for the build status
[00:16:49.590] Build 8902027854325404976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:16:49.590] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:70:0) after 1m0s
[00:17:49.583] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:70:0)
[00:17:49.583] Timer tick, asking Buildbucket for the build status
[00:17:55.805] Build 8902027854325404976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:17:55.805] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:71:0) after 1m0s
[00:18:55.892] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:71:0)
[00:18:55.892] Timer tick, asking Buildbucket for the build status
[00:18:57.270] Build 8902027854325404976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:18:57.270] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:72:0) after 1m0s
[00:19:57.272] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:72:0)
[00:19:57.272] Timer tick, asking Buildbucket for the build status
[00:19:58.303] Build 8902027854325404976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:19:58.303] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:73:0) after 1m0s
[00:20:58.424] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:73:0)
[00:20:58.424] Timer tick, asking Buildbucket for the build status
[00:21:03.588] Build 8902027854325404976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:21:03.588] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:74:0) after 1m0s
[00:22:03.653] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:74:0)
[00:22:03.653] Timer tick, asking Buildbucket for the build status
[00:22:07.056] Build 8902027854325404976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:22:07.056] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:75:0) after 1m0s
[00:23:07.366] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:75:0)
[00:23:07.366] Timer tick, asking Buildbucket for the build status
[00:23:07.674] Build 8902027854325404976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:23:07.674] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:76:0) after 1m0s
[00:24:07.725] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:76:0)
[00:24:07.725] Timer tick, asking Buildbucket for the build status
[00:24:08.003] Build 8902027854325404976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:24:08.003] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:77:0) after 1m0s
[00:25:08.004] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:77:0)
[00:25:08.004] Timer tick, asking Buildbucket for the build status
[00:25:08.233] Build 8902027854325404976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:25:08.233] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:78:0) after 1m0s
[00:26:08.269] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:78:0)
[00:26:08.269] Timer tick, asking Buildbucket for the build status
[00:26:11.217] Build 8902027854325404976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:26:11.217] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:79:0) after 1m0s
[00:27:11.347] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:79:0)
[00:27:11.347] Timer tick, asking Buildbucket for the build status
[00:27:14.300] Build 8902027854325404976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:27:14.300] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:80:0) after 1m0s
[00:28:14.550] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:80:0)
[00:28:14.550] Timer tick, asking Buildbucket for the build status
[00:28:17.232] Build 8902027854325404976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:28:17.232] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:81:0) after 1m0s
[00:29:17.345] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:81:0)
[00:29:17.345] Timer tick, asking Buildbucket for the build status
[00:29:18.214] Build 8902027854325404976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:29:18.214] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:82:0) after 1m0s
[00:30:18.249] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:82:0)
[00:30:18.249] Timer tick, asking Buildbucket for the build status
[00:30:22.845] Build 8902027854325404976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:30:22.845] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:83:0) after 1m0s
[00:31:22.916] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:83:0)
[00:31:22.916] Timer tick, asking Buildbucket for the build status
[00:31:23.664] Build 8902027854325404976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:31:23.664] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:84:0) after 1m0s
[00:32:23.811] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:84:0)
[00:32:23.811] Timer tick, asking Buildbucket for the build status
[00:32:31.654] Build 8902027854325404976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:32:31.654] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:85:0) after 1m0s
[00:33:31.771] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:85:0)
[00:33:31.771] Timer tick, asking Buildbucket for the build status
[00:33:34.882] Build 8902027854325404976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:33:34.882] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:86:0) after 1m0s
[00:34:35.015] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:86:0)
[00:34:35.015] Timer tick, asking Buildbucket for the build status
[00:34:36.566] Build 8902027854325404976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:34:36.566] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:87:0) after 1m0s
[00:35:36.685] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:87:0)
[00:35:36.685] Timer tick, asking Buildbucket for the build status
[00:35:40.820] Build 8902027854325404976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:35:40.820] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:88:0) after 1m0s
[00:36:41.069] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:88:0)
[00:36:41.069] Timer tick, asking Buildbucket for the build status
[00:36:41.821] Build 8902027854325404976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:36:41.821] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:89:0) after 1m0s
[00:37:42.030] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:89:0)
[00:37:42.030] Timer tick, asking Buildbucket for the build status
[00:37:47.511] Build 8902027854325404976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:37:47.511] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:90:0) after 1m0s
[00:38:47.707] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:90:0)
[00:38:47.707] Timer tick, asking Buildbucket for the build status
[00:38:50.341] Build 8902027854325404976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:38:50.341] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:91:0) after 1m0s
[00:39:50.582] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:91:0)
[00:39:50.582] Timer tick, asking Buildbucket for the build status
[00:39:51.136] Build 8902027854325404976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:39:51.136] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:92:0) after 1m0s
[00:40:51.283] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:92:0)
[00:40:51.283] Timer tick, asking Buildbucket for the build status
[00:40:51.560] Build 8902027854325404976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:40:51.560] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:93:0) after 1m0s
[00:41:51.777] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:93:0)
[00:41:51.777] Timer tick, asking Buildbucket for the build status
[00:41:51.974] Build 8902027854325404976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:41:51.974] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:94:0) after 1m0s
[00:42:52.049] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:94:0)
[00:42:52.049] Timer tick, asking Buildbucket for the build status
[00:42:52.511] Build 8902027854325404976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:42:52.511] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:95:0) after 1m0s
[00:43:52.546] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:95:0)
[00:43:52.546] Timer tick, asking Buildbucket for the build status
[00:43:55.941] Build 8902027854325404976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:43:55.941] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:96:0) after 1m0s
[00:44:56.017] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:96:0)
[00:44:56.017] Timer tick, asking Buildbucket for the build status
[00:44:56.424] Build 8902027854325404976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:44:56.424] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:97:0) after 1m0s
[00:45:56.553] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:97:0)
[00:45:56.553] Timer tick, asking Buildbucket for the build status
[00:45:59.587] Build 8902027854325404976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:45:59.587] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:98:0) after 1m0s
[00:46:59.715] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:98:0)
[00:46:59.715] Timer tick, asking Buildbucket for the build status
[00:47:02.837] Build 8902027854325404976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:47:02.837] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:99:0) after 1m0s
[00:48:02.864] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:99:0)
[00:48:02.864] Timer tick, asking Buildbucket for the build status
[00:48:03.097] Build 8902027854325404976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:48:03.097] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:100:0) after 1m0s
[00:49:03.118] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:100:0)
[00:49:03.118] Timer tick, asking Buildbucket for the build status
[00:49:03.646] Build 8902027854325404976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:49:03.646] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:101:0) after 1m0s
[00:50:03.719] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:101:0)
[00:50:03.719] Timer tick, asking Buildbucket for the build status
[00:50:04.915] Build 8902027854325404976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:50:04.915] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:102:0) after 1m0s
[00:51:04.936] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:102:0)
[00:51:04.936] Timer tick, asking Buildbucket for the build status
[00:51:05.095] Build 8902027854325404976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:51:05.095] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:103:0) after 1m0s
[00:52:05.271] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:103:0)
[00:52:05.271] Timer tick, asking Buildbucket for the build status
[00:52:06.064] Build 8902027854325404976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:52:06.064] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:104:0) after 1m0s
[00:53:06.134] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:104:0)
[00:53:06.134] Timer tick, asking Buildbucket for the build status
[00:53:08.085] Build 8902027854325404976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:53:08.085] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:105:0) after 1m0s
[00:54:08.151] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:105:0)
[00:54:08.151] Timer tick, asking Buildbucket for the build status
[00:54:09.267] Build 8902027854325404976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:54:09.267] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:106:0) after 1m0s
[00:55:09.425] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:106:0)
[00:55:09.425] Timer tick, asking Buildbucket for the build status
[00:55:10.048] Build 8902027854325404976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:55:10.048] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:107:0) after 1m0s
[00:56:10.150] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:107:0)
[00:56:10.150] Timer tick, asking Buildbucket for the build status
[00:56:12.851] Build 8902027854325404976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:56:12.851] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:108:0) after 1m0s
[00:57:12.965] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:108:0)
[00:57:12.965] Timer tick, asking Buildbucket for the build status
[00:57:13.293] Build 8902027854325404976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:57:13.293] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:109:0) after 1m0s
[00:58:13.340] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:109:0)
[00:58:13.340] Timer tick, asking Buildbucket for the build status
[00:58:13.558] Build 8902027854325404976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:58:13.558] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:110:0) after 1m0s
[00:59:13.728] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:110:0)
[00:59:13.728] Timer tick, asking Buildbucket for the build status
[00:59:17.191] Build 8902027854325404976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:59:17.191] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:111:0) after 1m0s
[01:00:17.352] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:111:0)
[01:00:17.352] Timer tick, asking Buildbucket for the build status
[01:00:17.554] Build 8902027854325404976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:00:17.554] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:112:0) after 1m0s
[01:01:17.715] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:112:0)
[01:01:17.715] Timer tick, asking Buildbucket for the build status
[01:01:19.837] Build 8902027854325404976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:01:19.837] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:113:0) after 1m0s
[01:02:19.921] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:113:0)
[01:02:19.921] Timer tick, asking Buildbucket for the build status
[01:02:20.697] Build 8902027854325404976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:02:20.697] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:114:0) after 1m0s
[01:03:20.787] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:114:0)
[01:03:20.787] Timer tick, asking Buildbucket for the build status
[01:03:21.567] Build 8902027854325404976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:03:21.567] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:115:0) after 1m0s
[01:04:21.593] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:115:0)
[01:04:21.593] Timer tick, asking Buildbucket for the build status
[01:04:24.495] Build 8902027854325404976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:04:24.495] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:116:0) after 1m0s
[01:05:24.608] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:116:0)
[01:05:24.608] Timer tick, asking Buildbucket for the build status
[01:05:25.036] Build 8902027854325404976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:05:25.036] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:117:0) after 1m0s
[01:06:25.102] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:117:0)
[01:06:25.102] Timer tick, asking Buildbucket for the build status
[01:06:29.221] Build 8902027854325404976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:06:29.221] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:118:0) after 1m0s
[01:07:29.284] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:118:0)
[01:07:29.284] Timer tick, asking Buildbucket for the build status
[01:07:33.109] Build 8902027854325404976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:07:33.109] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:119:0) after 1m0s
[01:08:33.221] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:119:0)
[01:08:33.221] Timer tick, asking Buildbucket for the build status
[01:08:33.480] Build 8902027854325404976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:08:33.480] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:120:0) after 1m0s
[01:09:33.612] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:120:0)
[01:09:33.612] Timer tick, asking Buildbucket for the build status
[01:09:36.769] Build 8902027854325404976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:09:36.769] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:121:0) after 1m0s
[01:10:36.823] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:121:0)
[01:10:36.823] Timer tick, asking Buildbucket for the build status
[01:10:37.205] Build 8902027854325404976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:10:37.205] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:122:0) after 1m0s
[01:11:37.263] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:122:0)
[01:11:37.263] Timer tick, asking Buildbucket for the build status
[01:11:37.573] Build 8902027854325404976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:11:37.573] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:123:0) after 1m0s
[01:12:37.602] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:123:0)
[01:12:37.602] Timer tick, asking Buildbucket for the build status
[01:12:38.949] Build 8902027854325404976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:12:38.949] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:124:0) after 1m0s
[01:13:38.978] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:124:0)
[01:13:38.978] Timer tick, asking Buildbucket for the build status
[01:13:40.307] Build 8902027854325404976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:13:40.307] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:125:0) after 1m0s
[01:14:40.465] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:125:0)
[01:14:40.465] Timer tick, asking Buildbucket for the build status
[01:14:41.923] Build 8902027854325404976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:14:41.923] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:126:0) after 1m0s
[01:15:41.992] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:126:0)
[01:15:41.992] Timer tick, asking Buildbucket for the build status
[01:15:42.235] Build 8902027854325404976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:15:42.235] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:127:0) after 1m0s
[01:16:42.250] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:127:0)
[01:16:42.250] Timer tick, asking Buildbucket for the build status
[01:16:43.221] Build 8902027854325404976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:16:43.221] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:128:0) after 1m0s
[01:17:43.313] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:128:0)
[01:17:43.314] Timer tick, asking Buildbucket for the build status
[01:17:44.528] Build 8902027854325404976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:17:44.528] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:129:0) after 1m0s
[01:18:44.566] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:129:0)
[01:18:44.566] Timer tick, asking Buildbucket for the build status
[01:18:45.061] Build 8902027854325404976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:18:45.061] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:130:0) after 1m0s
[01:19:45.236] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:130:0)
[01:19:45.236] Timer tick, asking Buildbucket for the build status
[01:19:46.015] Build 8902027854325404976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:19:46.015] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:131:0) after 1m0s
[01:20:46.150] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:131:0)
[01:20:46.150] Timer tick, asking Buildbucket for the build status
[01:20:46.640] Build 8902027854325404976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:20:46.640] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:132:0) after 1m0s
[01:21:46.690] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:132:0)
[01:21:46.690] Timer tick, asking Buildbucket for the build status
[01:21:47.151] Build 8902027854325404976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:21:47.151] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:133:0) after 1m0s
[01:22:47.483] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:133:0)
[01:22:47.483] Timer tick, asking Buildbucket for the build status
[01:22:50.054] Build 8902027854325404976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:22:50.054] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:134:0) after 1m0s
[01:23:50.137] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:134:0)
[01:23:50.138] Timer tick, asking Buildbucket for the build status
[01:23:50.427] Build 8902027854325404976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:23:50.427] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:135:0) after 1m0s
[01:24:50.615] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:135:0)
[01:24:50.615] Timer tick, asking Buildbucket for the build status
[01:24:51.073] Build 8902027854325404976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:24:51.073] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:136:0) after 1m0s
[01:25:51.102] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:136:0)
[01:25:51.102] Timer tick, asking Buildbucket for the build status
[01:25:51.308] Build 8902027854325404976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:25:51.308] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:137:0) after 1m0s
[01:26:51.389] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:137:0)
[01:26:51.389] Timer tick, asking Buildbucket for the build status
[01:26:51.931] Build 8902027854325404976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:26:51.931] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:138:0) after 1m0s
[01:27:52.031] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:138:0)
[01:27:52.031] Timer tick, asking Buildbucket for the build status
[01:27:52.648] Build 8902027854325404976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:27:52.648] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:139:0) after 1m0s
[01:28:52.724] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:139:0)
[01:28:52.724] Timer tick, asking Buildbucket for the build status
[01:28:53.069] Build 8902027854325404976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:28:53.069] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:140:0) after 1m0s
[01:29:53.261] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:140:0)
[01:29:53.261] Timer tick, asking Buildbucket for the build status
[01:29:56.542] Build 8902027854325404976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:29:56.542] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:141:0) after 1m0s
[01:30:56.563] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:141:0)
[01:30:56.563] Timer tick, asking Buildbucket for the build status
[01:30:59.176] Build 8902027854325404976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:30:59.176] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:142:0) after 1m0s
[01:31:59.208] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:142:0)
[01:31:59.208] Timer tick, asking Buildbucket for the build status
[01:31:59.541] Build 8902027854325404976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:31:59.541] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:143:0) after 1m0s
[01:32:59.535] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:143:0)
[01:32:59.535] Timer tick, asking Buildbucket for the build status
[01:33:02.053] Build 8902027854325404976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:33:02.053] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:144:0) after 1m0s
[01:34:02.089] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:144:0)
[01:34:02.089] Timer tick, asking Buildbucket for the build status
[01:34:02.547] Build 8902027854325404976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:34:02.547] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:145:0) after 1m0s
[01:35:02.588] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:145:0)
[01:35:02.588] Timer tick, asking Buildbucket for the build status
[01:35:03.191] Build 8902027854325404976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:35:03.191] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:146:0) after 1m0s
[01:36:03.264] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:146:0)
[01:36:03.264] Timer tick, asking Buildbucket for the build status
[01:36:04.554] Build 8902027854325404976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:36:04.554] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:147:0) after 1m0s
[01:37:04.572] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:147:0)
[01:37:04.572] Timer tick, asking Buildbucket for the build status
[01:37:07.020] Build 8902027854325404976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:37:07.020] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:148:0) after 1m0s
[01:38:07.027] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:148:0)
[01:38:07.027] Timer tick, asking Buildbucket for the build status
[01:38:08.811] Build 8902027854325404976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:38:08.811] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:149:0) after 1m0s
[01:39:08.947] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:149:0)
[01:39:08.947] Timer tick, asking Buildbucket for the build status
[01:39:11.258] Build 8902027854325404976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:39:11.258] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:150:0) after 1m0s
[01:40:11.385] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:150:0)
[01:40:11.385] Timer tick, asking Buildbucket for the build status
[01:40:11.811] Build 8902027854325404976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:40:11.811] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:151:0) after 1m0s
[01:41:11.880] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:151:0)
[01:41:11.880] Timer tick, asking Buildbucket for the build status
[01:41:12.144] Build 8902027854325404976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:41:12.144] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:152:0) after 1m0s
[01:42:12.173] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:152:0)
[01:42:12.173] Timer tick, asking Buildbucket for the build status
[01:42:14.773] Build 8902027854325404976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:42:14.773] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:153:0) after 1m0s
[01:43:14.802] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:153:0)
[01:43:14.802] Timer tick, asking Buildbucket for the build status
[01:43:15.182] Build 8902027854325404976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:43:15.182] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:154:0) after 1m0s
[01:44:15.252] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:154:0)
[01:44:15.252] Timer tick, asking Buildbucket for the build status
[01:44:17.079] Build 8902027854325404976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:44:17.079] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:155:0) after 1m0s
[01:45:17.143] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:155:0)
[01:45:17.143] Timer tick, asking Buildbucket for the build status
[01:45:17.759] Build 8902027854325404976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:45:17.759] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:156:0) after 1m0s
[01:46:17.832] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:156:0)
[01:46:17.832] Timer tick, asking Buildbucket for the build status
[01:46:18.107] Build 8902027854325404976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:46:18.107] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:157:0) after 1m0s
[01:47:18.097] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:157:0)
[01:47:18.097] Timer tick, asking Buildbucket for the build status
[01:47:18.476] Build 8902027854325404976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:47:18.477] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:158:0) after 1m0s
[01:48:18.514] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:158:0)
[01:48:18.514] Timer tick, asking Buildbucket for the build status
[01:48:19.271] Build 8902027854325404976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:48:19.271] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:159:0) after 1m0s
[01:49:19.289] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:159:0)
[01:49:19.289] Timer tick, asking Buildbucket for the build status
[01:49:21.415] Build 8902027854325404976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:49:21.415] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:160:0) after 1m0s
[01:50:21.497] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:160:0)
[01:50:21.497] Timer tick, asking Buildbucket for the build status
[01:50:22.395] Build 8902027854325404976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:50:22.395] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:161:0) after 1m0s
[01:51:22.395] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:161:0)
[01:51:22.395] Timer tick, asking Buildbucket for the build status
[01:51:22.708] Build 8902027854325404976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:51:22.708] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:162:0) after 1m0s
[01:52:22.869] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:162:0)
[01:52:22.869] Timer tick, asking Buildbucket for the build status
[01:52:25.146] Build 8902027854325404976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:52:25.146] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:163:0) after 1m0s
[01:53:25.176] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:163:0)
[01:53:25.176] Timer tick, asking Buildbucket for the build status
[01:53:25.824] Build 8902027854325404976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:53:25.824] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:164:0) after 1m0s
[01:54:25.879] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:164:0)
[01:54:25.879] Timer tick, asking Buildbucket for the build status
[01:54:26.386] Build 8902027854325404976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:54:26.386] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:165:0) after 1m0s
[01:55:26.418] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:165:0)
[01:55:26.418] Timer tick, asking Buildbucket for the build status
[01:55:27.054] Build 8902027854325404976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:55:27.054] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:166:0) after 1m0s
[01:56:27.100] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:166:0)
[01:56:27.100] Timer tick, asking Buildbucket for the build status
[01:56:27.970] Build 8902027854325404976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:56:27.970] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:167:0) after 1m0s
[01:57:28.201] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:167:0)
[01:57:28.201] Timer tick, asking Buildbucket for the build status
[01:57:28.609] Build 8902027854325404976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:57:28.609] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:168:0) after 1m0s
[01:58:28.678] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:168:0)
[01:58:28.678] Timer tick, asking Buildbucket for the build status
[01:58:29.878] Build 8902027854325404976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:58:29.878] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:169:0) after 1m0s
[01:59:29.908] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:169:0)
[01:59:29.908] Timer tick, asking Buildbucket for the build status
[01:59:31.211] Build 8902027854325404976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:59:31.211] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:170:0) after 1m0s
[02:00:31.370] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:170:0)
[02:00:31.370] Timer tick, asking Buildbucket for the build status
[02:00:31.552] Build 8902027854325404976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:00:31.552] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:171:0) after 1m0s
[02:01:31.584] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:171:0)
[02:01:31.584] Timer tick, asking Buildbucket for the build status
[02:01:32.489] Build 8902027854325404976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:01:32.489] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:172:0) after 1m0s
[02:02:32.586] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:172:0)
[02:02:32.586] Timer tick, asking Buildbucket for the build status
[02:02:32.880] Build 8902027854325404976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:02:32.880] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:173:0) after 1m0s
[02:03:32.931] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:173:0)
[02:03:32.931] Timer tick, asking Buildbucket for the build status
[02:03:33.406] Build 8902027854325404976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:03:33.406] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:174:0) after 1m0s
[02:04:33.475] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:174:0)
[02:04:33.475] Timer tick, asking Buildbucket for the build status
[02:04:33.681] Build 8902027854325404976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:04:33.681] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:175:0) after 1m0s
[02:05:33.699] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:175:0)
[02:05:33.699] Timer tick, asking Buildbucket for the build status
[02:05:34.322] Build 8902027854325404976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:05:34.322] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:176:0) after 1m0s
[02:06:34.386] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:176:0)
[02:06:34.386] Timer tick, asking Buildbucket for the build status
[02:06:34.802] Build 8902027854325404976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:06:34.802] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:177:0) after 1m0s
[02:07:34.823] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:177:0)
[02:07:34.823] Timer tick, asking Buildbucket for the build status
[02:07:36.022] Build 8902027854325404976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:07:36.022] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:178:0) after 1m0s
[02:08:36.058] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:178:0)
[02:08:36.058] Timer tick, asking Buildbucket for the build status
[02:08:36.313] Build 8902027854325404976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:08:36.313] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:179:0) after 1m0s
[02:09:36.444] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:179:0)
[02:09:36.444] Timer tick, asking Buildbucket for the build status
[02:09:36.622] Build 8902027854325404976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:09:36.622] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:180:0) after 1m0s
[02:10:36.660] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:180:0)
[02:10:36.661] Timer tick, asking Buildbucket for the build status
[02:10:36.838] Build 8902027854325404976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:10:36.838] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:181:0) after 1m0s
[02:11:36.875] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:181:0)
[02:11:36.875] Timer tick, asking Buildbucket for the build status
[02:11:37.092] Build 8902027854325404976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:11:37.092] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:182:0) after 1m0s
[02:12:37.243] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:182:0)
[02:12:37.243] Timer tick, asking Buildbucket for the build status
[02:12:37.484] Build 8902027854325404976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:12:37.484] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:183:0) after 1m0s
[02:13:37.521] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:183:0)
[02:13:37.521] Timer tick, asking Buildbucket for the build status
[02:13:38.481] Build 8902027854325404976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:13:38.481] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9067457916484617600:184:0) after 1m0s
[02:13:47.750] Received PubSub notification, asking Buildbucket for the build status
[02:13:47.884] Build 8902027854325404976: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[02:13:47.884] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1568772826994952",
"created_by": "project:chromium",
"created_ts": "1568761693604189",
"id": "8902027854325404976",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"e40444831ecd125cb9f79adcd32d04736227b1b8\"}}",
"project": "chromium",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"$recipe_engine/path\": {\"cache_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\cache\", \"temp_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\tmp\\\\rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"bot_id\": \"windows-deterministic-0-59e5\", \"branch\": \"refs/heads/master\", \"buildername\": \"Windows deterministic\", \"buildnumber\": 14745, \"got_angle_revision\": \"65a5036eb0419047789d5ee66c02577ef251e07b\", \"got_dawn_revision\": \"785ec66b66a3577e4f17306a10ab30b7f92c49ae\", \"got_nacl_revision\": \"a9e27d5c7f1b6edf7371edab79e104578d62c4f5\", \"got_revision\": \"e40444831ecd125cb9f79adcd32d04736227b1b8\", \"got_revision_cp\": \"refs/heads/master@{#697393}\", \"got_swarming_client_revision\": \"96f125709acfd0b48fc1e5dae7d6ea42291726ac\", \"got_v8_revision\": \"84da790b879ff03a75ea94c6de263704f04658fe\", \"got_v8_revision_cp\": \"refs/heads/7.9.56@{#1}\", \"got_webrtc_revision\": \"368d002e489eee4797d1f876f6a24d75d7ce8016\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#29211}\", \"mastername\": \"chromium.win\", \"path_config\": \"generic\", \"recipe\": \"swarming/deterministic_build\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"e40444831ecd125cb9f79adcd32d04736227b1b8\"}, \"swarming\": {\"bot_dimensions\": {\"builder\": [\"Windows deterministic\"], \"caches\": [\"builder_e8fffaf9fac16164b1ec16ebaa1f377b792d39a582f7640830518d8ff7303d0c_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"8\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\"], \"gce\": [\"1\"], \"gpu\": [\"none\"], \"id\": [\"windows-deterministic-0-59e5\"], \"image\": [\"chrome-win10-1703-19072400-fedda89f1f1\"], \"integrity\": [\"high\"], \"locale\": [\"en_US.cp1252\"], \"machine_type\": [\"n1-standard-8\"], \"os\": [\"Windows\", \"Windows-10\", \"Windows-10-15063\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.13\"], \"server_version\": [\"4436-e523514\"], \"ssd\": [\"0\"], \"windows_client_version\": [\"10\"], \"zone\": [\"us\", \"us-west\", \"us-west1\", \"us-west1-c\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1568761728699813",
"status": "COMPLETED",
"status_changed_ts": "1568772827268226",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/14745",
"builder:Windows deterministic",
"buildset:commit/git/e40444831ecd125cb9f79adcd32d04736227b1b8",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/e40444831ecd125cb9f79adcd32d04736227b1b8",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9067457916484617600",
"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/8902027854325404976/+/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:475a4da7e16c0810",
"user_agent:luci-scheduler"
],
"updated_ts": "1568772827273248",
"url": "https://ci.chromium.org/b/8902027854325404976",
"utcnow_ts": "1568772827864638"
}
}
[02:13:47.884] Invocation finished in 3h5m35.721931385s with status SUCCEEDED