[22:08:34.190] New invocation is queued and will start shortly
[22:08:35.337] Starting the invocation (attempt 1)
[22:08:35.407] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[22:08:35.407] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[22:08:35.407] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9065740325919452912",
"parameters_json": "{\"builder_name\":\"Windows deterministic\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src.git\",\"revision\":\"0f7fd04763a237291dd3530bc212de8b10e4a613\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Windows deterministic",
"scheduler_invocation_id:9065740325919452912",
"scheduler_job_id:chromium/Windows deterministic",
"user_agent:luci-scheduler",
"buildset:commit/git/0f7fd04763a237291dd3530bc212de8b10e4a613",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/0f7fd04763a237291dd3530bc212de8b10e4a613",
"gitiles_ref:refs/heads/master"
]
}
[22:08:38.063] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1570399715938830",
"id": "8900310263419204672",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"0f7fd04763a237291dd3530bc212de8b10e4a613\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1570399717529477",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/14906",
"builder:Windows deterministic",
"buildset:commit/git/0f7fd04763a237291dd3530bc212de8b10e4a613",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/0f7fd04763a237291dd3530bc212de8b10e4a613",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9065740325919452912",
"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/8900310263419204672/+/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": "1570399717529614",
"url": "https://ci.chromium.org/b/8900310263419204672",
"utcnow_ts": "1570399718030520"
}
}
[22:08:38.063] Task URL: https://ci.chromium.org/b/8900310263419204672
[22:08:38.063] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:2:0) after 1m0s
[22:08:44.324] Received PubSub notification, asking Buildbucket for the build status
[22:08:44.462] Build 8900310263419204672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:09:38.324] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:2:0)
[22:09:38.324] Timer tick, asking Buildbucket for the build status
[22:09:38.953] Build 8900310263419204672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:09:38.954] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:4:0) after 1m0s
[22:10:39.151] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:4:0)
[22:10:39.151] Timer tick, asking Buildbucket for the build status
[22:10:39.337] Build 8900310263419204672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:10:39.337] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:5:0) after 1m0s
[22:11:39.394] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:5:0)
[22:11:39.394] Timer tick, asking Buildbucket for the build status
[22:11:39.639] Build 8900310263419204672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:11:39.639] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:6:0) after 1m0s
[22:12:39.934] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:6:0)
[22:12:39.934] Timer tick, asking Buildbucket for the build status
[22:12:40.253] Build 8900310263419204672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:12:40.253] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:7:0) after 1m0s
[22:13:40.457] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:7:0)
[22:13:40.457] Timer tick, asking Buildbucket for the build status
[22:13:40.646] Build 8900310263419204672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:13:40.646] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:8:0) after 1m0s
[22:14:40.968] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:8:0)
[22:14:40.969] Timer tick, asking Buildbucket for the build status
[22:14:42.205] Build 8900310263419204672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:14:42.205] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:9:0) after 1m0s
[22:15:42.445] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:9:0)
[22:15:42.445] Timer tick, asking Buildbucket for the build status
[22:15:43.351] Build 8900310263419204672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:15:43.351] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:10:0) after 1m0s
[22:16:43.664] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:10:0)
[22:16:43.664] Timer tick, asking Buildbucket for the build status
[22:16:43.915] Build 8900310263419204672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:16:43.915] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:11:0) after 1m0s
[22:17:43.925] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:11:0)
[22:17:43.925] Timer tick, asking Buildbucket for the build status
[22:17:44.092] Build 8900310263419204672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:17:44.092] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:12:0) after 1m0s
[22:18:44.115] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:12:0)
[22:18:44.115] Timer tick, asking Buildbucket for the build status
[22:18:44.316] Build 8900310263419204672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:18:44.316] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:13:0) after 1m0s
[22:19:44.350] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:13:0)
[22:19:44.350] Timer tick, asking Buildbucket for the build status
[22:19:44.914] Build 8900310263419204672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:19:44.914] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:14:0) after 1m0s
[22:20:44.965] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:14:0)
[22:20:44.965] Timer tick, asking Buildbucket for the build status
[22:20:45.356] Build 8900310263419204672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:20:45.356] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:15:0) after 1m0s
[22:21:45.377] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:15:0)
[22:21:45.377] Timer tick, asking Buildbucket for the build status
[22:21:46.086] Build 8900310263419204672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:21:46.086] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:16:0) after 1m0s
[22:22:46.183] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:16:0)
[22:22:46.183] Timer tick, asking Buildbucket for the build status
[22:22:46.533] Build 8900310263419204672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:22:46.533] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:17:0) after 1m0s
[22:23:46.655] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:17:0)
[22:23:46.655] Timer tick, asking Buildbucket for the build status
[22:23:46.911] Build 8900310263419204672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:23:46.911] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:18:0) after 1m0s
[22:24:46.946] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:18:0)
[22:24:46.946] Timer tick, asking Buildbucket for the build status
[22:24:47.437] Build 8900310263419204672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:24:47.437] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:19:0) after 1m0s
[22:25:47.597] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:19:0)
[22:25:47.597] Timer tick, asking Buildbucket for the build status
[22:25:47.792] Build 8900310263419204672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:25:47.792] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:20:0) after 1m0s
[22:26:47.814] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:20:0)
[22:26:47.814] Timer tick, asking Buildbucket for the build status
[22:26:48.529] Build 8900310263419204672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:26:48.529] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:21:0) after 1m0s
[22:27:48.579] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:21:0)
[22:27:48.579] Timer tick, asking Buildbucket for the build status
[22:27:48.731] Build 8900310263419204672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:27:48.731] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:22:0) after 1m0s
[22:28:48.843] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:22:0)
[22:28:48.843] Timer tick, asking Buildbucket for the build status
[22:28:49.514] Build 8900310263419204672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:28:49.514] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:23:0) after 1m0s
[22:29:49.824] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:23:0)
[22:29:49.824] Timer tick, asking Buildbucket for the build status
[22:29:50.135] Build 8900310263419204672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:29:50.135] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:24:0) after 1m0s
[22:30:50.180] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:24:0)
[22:30:50.180] Timer tick, asking Buildbucket for the build status
[22:30:50.356] Build 8900310263419204672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:30:50.356] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:25:0) after 1m0s
[22:31:50.399] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:25:0)
[22:31:50.399] Timer tick, asking Buildbucket for the build status
[22:31:50.948] Build 8900310263419204672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:31:50.948] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:26:0) after 1m0s
[22:32:51.073] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:26:0)
[22:32:51.073] Timer tick, asking Buildbucket for the build status
[22:32:51.530] Build 8900310263419204672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:32:51.530] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:27:0) after 1m0s
[22:33:51.553] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:27:0)
[22:33:51.553] Timer tick, asking Buildbucket for the build status
[22:33:51.699] Build 8900310263419204672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:33:51.699] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:28:0) after 1m0s
[22:34:51.726] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:28:0)
[22:34:51.726] Timer tick, asking Buildbucket for the build status
[22:34:52.417] Build 8900310263419204672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:34:52.417] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:29:0) after 1m0s
[22:35:52.440] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:29:0)
[22:35:52.440] Timer tick, asking Buildbucket for the build status
[22:35:52.625] Build 8900310263419204672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:35:52.625] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:30:0) after 1m0s
[22:36:52.665] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:30:0)
[22:36:52.665] Timer tick, asking Buildbucket for the build status
[22:36:52.966] Build 8900310263419204672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:36:52.966] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:31:0) after 1m0s
[22:37:52.987] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:31:0)
[22:37:52.987] Timer tick, asking Buildbucket for the build status
[22:37:53.282] Build 8900310263419204672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:37:53.282] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:32:0) after 1m0s
[22:38:53.305] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:32:0)
[22:38:53.305] Timer tick, asking Buildbucket for the build status
[22:38:53.827] Build 8900310263419204672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:38:53.827] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:33:0) after 1m0s
[22:39:53.848] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:33:0)
[22:39:53.848] Timer tick, asking Buildbucket for the build status
[22:39:54.241] Build 8900310263419204672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:39:54.241] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:34:0) after 1m0s
[22:40:54.376] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:34:0)
[22:40:54.376] Timer tick, asking Buildbucket for the build status
[22:40:54.662] Build 8900310263419204672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:40:54.662] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:35:0) after 1m0s
[22:41:54.843] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:35:0)
[22:41:54.843] Timer tick, asking Buildbucket for the build status
[22:41:55.050] Build 8900310263419204672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:41:55.050] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:36:0) after 1m0s
[22:42:55.303] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:36:0)
[22:42:55.303] Timer tick, asking Buildbucket for the build status
[22:42:55.942] Build 8900310263419204672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:42:55.942] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:37:0) after 1m0s
[22:43:55.961] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:37:0)
[22:43:55.961] Timer tick, asking Buildbucket for the build status
[22:43:56.684] Build 8900310263419204672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:43:56.684] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:38:0) after 1m0s
[22:44:56.777] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:38:0)
[22:44:56.777] Timer tick, asking Buildbucket for the build status
[22:44:57.217] Build 8900310263419204672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:44:57.217] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:39:0) after 1m0s
[22:45:57.248] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:39:0)
[22:45:57.248] Timer tick, asking Buildbucket for the build status
[22:45:57.714] Build 8900310263419204672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:45:57.714] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:40:0) after 1m0s
[22:46:57.871] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:40:0)
[22:46:57.871] Timer tick, asking Buildbucket for the build status
[22:46:58.084] Build 8900310263419204672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:46:58.084] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:41:0) after 1m0s
[22:47:58.113] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:41:0)
[22:47:58.113] Timer tick, asking Buildbucket for the build status
[22:47:58.538] Build 8900310263419204672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:47:58.538] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:42:0) after 1m0s
[22:48:59.464] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:42:0)
[22:48:59.464] Timer tick, asking Buildbucket for the build status
[22:48:59.628] Build 8900310263419204672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:48:59.628] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:43:0) after 1m0s
[22:50:00.282] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:43:0)
[22:50:00.282] Timer tick, asking Buildbucket for the build status
[22:50:00.481] Build 8900310263419204672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:50:00.481] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:44:0) after 1m0s
[22:51:00.640] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:44:0)
[22:51:00.640] Timer tick, asking Buildbucket for the build status
[22:51:00.832] Build 8900310263419204672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:51:00.832] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:45:0) after 1m0s
[22:52:00.847] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:45:0)
[22:52:00.847] Timer tick, asking Buildbucket for the build status
[22:52:01.449] Build 8900310263419204672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:52:01.449] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:46:0) after 1m0s
[22:53:01.812] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:46:0)
[22:53:01.812] Timer tick, asking Buildbucket for the build status
[22:53:02.054] Build 8900310263419204672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:53:02.054] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:47:0) after 1m0s
[22:54:02.658] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:47:0)
[22:54:02.658] Timer tick, asking Buildbucket for the build status
[22:54:03.625] Build 8900310263419204672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:54:03.625] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:48:0) after 1m0s
[22:55:03.875] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:48:0)
[22:55:03.875] Timer tick, asking Buildbucket for the build status
[22:55:04.273] Build 8900310263419204672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:55:04.273] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:49:0) after 1m0s
[22:56:04.825] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:49:0)
[22:56:04.825] Timer tick, asking Buildbucket for the build status
[22:56:05.035] Build 8900310263419204672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:56:05.035] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:50:0) after 1m0s
[22:57:05.609] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:50:0)
[22:57:05.609] Timer tick, asking Buildbucket for the build status
[22:57:08.261] Build 8900310263419204672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:57:08.261] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:51:0) after 1m0s
[22:58:08.658] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:51:0)
[22:58:08.658] Timer tick, asking Buildbucket for the build status
[22:58:08.969] Build 8900310263419204672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:58:08.969] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:52:0) after 1m0s
[22:59:09.002] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:52:0)
[22:59:09.002] Timer tick, asking Buildbucket for the build status
[22:59:09.907] Build 8900310263419204672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:59:09.907] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:53:0) after 1m0s
[23:00:09.934] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:53:0)
[23:00:09.934] Timer tick, asking Buildbucket for the build status
[23:00:10.162] Build 8900310263419204672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:00:10.162] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:54:0) after 1m0s
[23:01:11.469] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:54:0)
[23:01:11.469] Timer tick, asking Buildbucket for the build status
[23:01:11.700] Build 8900310263419204672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:01:11.700] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:55:0) after 1m0s
[23:02:12.181] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:55:0)
[23:02:12.181] Timer tick, asking Buildbucket for the build status
[23:02:12.350] Build 8900310263419204672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:02:12.350] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:56:0) after 1m0s
[23:03:12.536] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:56:0)
[23:03:12.536] Timer tick, asking Buildbucket for the build status
[23:03:14.198] Build 8900310263419204672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:03:14.198] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:57:0) after 1m0s
[23:04:14.578] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:57:0)
[23:04:14.578] Timer tick, asking Buildbucket for the build status
[23:04:15.186] Build 8900310263419204672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:04:15.186] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:58:0) after 1m0s
[23:05:15.363] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:58:0)
[23:05:15.364] Timer tick, asking Buildbucket for the build status
[23:05:16.208] Build 8900310263419204672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:05:16.208] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:59:0) after 1m0s
[23:06:16.472] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:59:0)
[23:06:16.472] Timer tick, asking Buildbucket for the build status
[23:06:16.683] Build 8900310263419204672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:06:16.683] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:60:0) after 1m0s
[23:07:16.759] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:60:0)
[23:07:16.759] Timer tick, asking Buildbucket for the build status
[23:07:17.012] Build 8900310263419204672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:07:17.012] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:61:0) after 1m0s
[23:08:17.056] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:61:0)
[23:08:17.056] Timer tick, asking Buildbucket for the build status
[23:08:17.253] Build 8900310263419204672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:08:17.253] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:62:0) after 1m0s
[23:09:17.341] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:62:0)
[23:09:17.341] Timer tick, asking Buildbucket for the build status
[23:09:17.888] Build 8900310263419204672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:09:17.888] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:63:0) after 1m0s
[23:10:17.922] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:63:0)
[23:10:17.922] Timer tick, asking Buildbucket for the build status
[23:10:18.379] Build 8900310263419204672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:10:18.379] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:64:0) after 1m0s
[23:11:18.470] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:64:0)
[23:11:18.470] Timer tick, asking Buildbucket for the build status
[23:11:19.675] Build 8900310263419204672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:11:19.675] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:65:0) after 1m0s
[23:12:19.689] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:65:0)
[23:12:19.689] Timer tick, asking Buildbucket for the build status
[23:12:19.825] Build 8900310263419204672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:12:19.825] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:66:0) after 1m0s
[23:13:19.899] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:66:0)
[23:13:19.899] Timer tick, asking Buildbucket for the build status
[23:13:20.052] Build 8900310263419204672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:13:20.052] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:67:0) after 1m0s
[23:14:20.083] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:67:0)
[23:14:20.083] Timer tick, asking Buildbucket for the build status
[23:14:20.247] Build 8900310263419204672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:14:20.247] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:68:0) after 1m0s
[23:15:20.277] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:68:0)
[23:15:20.277] Timer tick, asking Buildbucket for the build status
[23:15:20.504] Build 8900310263419204672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:15:20.504] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:69:0) after 1m0s
[23:16:20.526] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:69:0)
[23:16:20.526] Timer tick, asking Buildbucket for the build status
[23:16:22.035] Build 8900310263419204672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:16:22.035] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:70:0) after 1m0s
[23:17:22.166] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:70:0)
[23:17:22.166] Timer tick, asking Buildbucket for the build status
[23:17:22.339] Build 8900310263419204672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:17:22.340] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:71:0) after 1m0s
[23:18:22.414] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:71:0)
[23:18:22.414] Timer tick, asking Buildbucket for the build status
[23:18:22.555] Build 8900310263419204672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:18:22.555] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:72:0) after 1m0s
[23:19:22.640] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:72:0)
[23:19:22.640] Timer tick, asking Buildbucket for the build status
[23:19:23.815] Build 8900310263419204672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:19:23.815] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:73:0) after 1m0s
[23:20:23.848] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:73:0)
[23:20:23.848] Timer tick, asking Buildbucket for the build status
[23:20:24.088] Build 8900310263419204672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:20:24.088] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:74:0) after 1m0s
[23:21:24.136] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:74:0)
[23:21:24.136] Timer tick, asking Buildbucket for the build status
[23:21:24.316] Build 8900310263419204672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:21:24.316] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:75:0) after 1m0s
[23:22:24.361] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:75:0)
[23:22:24.361] Timer tick, asking Buildbucket for the build status
[23:22:24.601] Build 8900310263419204672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:22:24.601] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:76:0) after 1m0s
[23:23:24.634] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:76:0)
[23:23:24.634] Timer tick, asking Buildbucket for the build status
[23:23:25.145] Build 8900310263419204672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:23:25.145] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:77:0) after 1m0s
[23:24:25.172] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:77:0)
[23:24:25.172] Timer tick, asking Buildbucket for the build status
[23:24:25.515] Build 8900310263419204672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:24:25.515] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:78:0) after 1m0s
[23:25:25.559] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:78:0)
[23:25:25.559] Timer tick, asking Buildbucket for the build status
[23:25:25.752] Build 8900310263419204672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:25:25.752] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:79:0) after 1m0s
[23:26:25.882] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:79:0)
[23:26:25.882] Timer tick, asking Buildbucket for the build status
[23:26:26.051] Build 8900310263419204672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:26:26.051] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:80:0) after 1m0s
[23:27:26.135] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:80:0)
[23:27:26.135] Timer tick, asking Buildbucket for the build status
[23:27:26.573] Build 8900310263419204672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:27:26.573] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:81:0) after 1m0s
[23:28:26.608] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:81:0)
[23:28:26.608] Timer tick, asking Buildbucket for the build status
[23:28:26.782] Build 8900310263419204672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:28:26.782] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:82:0) after 1m0s
[23:29:26.808] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:82:0)
[23:29:26.808] Timer tick, asking Buildbucket for the build status
[23:29:28.884] Build 8900310263419204672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:29:28.884] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:83:0) after 1m0s
[23:30:28.993] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:83:0)
[23:30:28.993] Timer tick, asking Buildbucket for the build status
[23:30:29.197] Build 8900310263419204672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:30:29.197] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:84:0) after 1m0s
[23:31:29.231] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:84:0)
[23:31:29.231] Timer tick, asking Buildbucket for the build status
[23:31:29.407] Build 8900310263419204672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:31:29.407] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:85:0) after 1m0s
[23:32:29.459] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:85:0)
[23:32:29.459] Timer tick, asking Buildbucket for the build status
[23:32:30.076] Build 8900310263419204672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:32:30.076] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:86:0) after 1m0s
[23:33:30.221] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:86:0)
[23:33:30.221] Timer tick, asking Buildbucket for the build status
[23:33:31.364] Build 8900310263419204672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:33:31.364] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:87:0) after 1m0s
[23:34:31.526] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:87:0)
[23:34:31.526] Timer tick, asking Buildbucket for the build status
[23:34:31.704] Build 8900310263419204672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:34:31.704] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:88:0) after 1m0s
[23:35:32.055] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:88:0)
[23:35:32.055] Timer tick, asking Buildbucket for the build status
[23:35:32.394] Build 8900310263419204672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:35:32.394] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:89:0) after 1m0s
[23:36:32.440] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:89:0)
[23:36:32.440] Timer tick, asking Buildbucket for the build status
[23:36:32.802] Build 8900310263419204672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:36:32.802] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:90:0) after 1m0s
[23:37:32.950] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:90:0)
[23:37:32.950] Timer tick, asking Buildbucket for the build status
[23:37:33.131] Build 8900310263419204672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:37:33.132] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:91:0) after 1m0s
[23:38:33.167] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:91:0)
[23:38:33.167] Timer tick, asking Buildbucket for the build status
[23:38:33.677] Build 8900310263419204672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:38:33.677] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:92:0) after 1m0s
[23:39:33.705] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:92:0)
[23:39:33.705] Timer tick, asking Buildbucket for the build status
[23:39:33.888] Build 8900310263419204672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:39:33.888] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:93:0) after 1m0s
[23:40:33.911] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:93:0)
[23:40:33.911] Timer tick, asking Buildbucket for the build status
[23:40:34.518] Build 8900310263419204672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:40:34.518] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:94:0) after 1m0s
[23:41:34.550] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:94:0)
[23:41:34.550] Timer tick, asking Buildbucket for the build status
[23:41:35.811] Build 8900310263419204672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:41:35.811] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:95:0) after 1m0s
[23:42:35.845] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:95:0)
[23:42:35.845] Timer tick, asking Buildbucket for the build status
[23:42:36.385] Build 8900310263419204672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:42:36.385] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:96:0) after 1m0s
[23:43:36.504] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:96:0)
[23:43:36.504] Timer tick, asking Buildbucket for the build status
[23:43:36.685] Build 8900310263419204672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:43:36.685] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:97:0) after 1m0s
[23:44:36.818] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:97:0)
[23:44:36.818] Timer tick, asking Buildbucket for the build status
[23:44:37.047] Build 8900310263419204672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:44:37.047] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:98:0) after 1m0s
[23:45:37.148] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:98:0)
[23:45:37.148] Timer tick, asking Buildbucket for the build status
[23:45:37.411] Build 8900310263419204672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:45:37.411] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:99:0) after 1m0s
[23:46:37.504] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:99:0)
[23:46:37.504] Timer tick, asking Buildbucket for the build status
[23:46:38.011] Build 8900310263419204672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:46:38.011] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:100:0) after 1m0s
[23:47:38.230] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:100:0)
[23:47:38.230] Timer tick, asking Buildbucket for the build status
[23:47:38.779] Build 8900310263419204672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:47:38.779] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:101:0) after 1m0s
[23:48:38.835] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:101:0)
[23:48:38.835] Timer tick, asking Buildbucket for the build status
[23:48:39.967] Build 8900310263419204672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:48:39.967] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:102:0) after 1m0s
[23:49:40.009] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:102:0)
[23:49:40.009] Timer tick, asking Buildbucket for the build status
[23:49:40.257] Build 8900310263419204672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:49:40.257] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:103:0) after 1m0s
[23:50:40.343] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:103:0)
[23:50:40.343] Timer tick, asking Buildbucket for the build status
[23:50:40.615] Build 8900310263419204672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:50:40.615] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:104:0) after 1m0s
[23:51:40.717] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:104:0)
[23:51:40.717] Timer tick, asking Buildbucket for the build status
[23:51:41.224] Build 8900310263419204672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:51:41.224] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:105:0) after 1m0s
[23:52:41.282] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:105:0)
[23:52:41.282] Timer tick, asking Buildbucket for the build status
[23:52:44.124] Build 8900310263419204672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:52:44.124] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:106:0) after 1m0s
[23:53:44.178] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:106:0)
[23:53:44.178] Timer tick, asking Buildbucket for the build status
[23:53:44.414] Build 8900310263419204672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:53:44.414] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:107:0) after 1m0s
[23:54:44.448] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:107:0)
[23:54:44.448] Timer tick, asking Buildbucket for the build status
[23:54:45.554] Build 8900310263419204672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:54:45.554] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:108:0) after 1m0s
[23:55:45.632] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:108:0)
[23:55:45.632] Timer tick, asking Buildbucket for the build status
[23:55:46.017] Build 8900310263419204672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:55:46.017] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:109:0) after 1m0s
[23:56:46.146] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:109:0)
[23:56:46.146] Timer tick, asking Buildbucket for the build status
[23:56:47.129] Build 8900310263419204672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:56:47.129] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:110:0) after 1m0s
[23:57:47.159] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:110:0)
[23:57:47.159] Timer tick, asking Buildbucket for the build status
[23:57:47.534] Build 8900310263419204672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:57:47.534] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:111:0) after 1m0s
[23:58:47.565] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:111:0)
[23:58:47.565] Timer tick, asking Buildbucket for the build status
[23:58:48.100] Build 8900310263419204672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:58:48.100] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:112:0) after 1m0s
[23:59:48.141] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:112:0)
[23:59:48.141] Timer tick, asking Buildbucket for the build status
[23:59:48.380] Build 8900310263419204672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:59:48.380] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:113:0) after 1m0s
[00:00:48.630] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:113:0)
[00:00:48.630] Timer tick, asking Buildbucket for the build status
[00:00:48.982] Build 8900310263419204672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:00:48.982] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:114:0) after 1m0s
[00:01:49.037] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:114:0)
[00:01:49.038] Timer tick, asking Buildbucket for the build status
[00:01:49.352] Build 8900310263419204672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:01:49.353] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:115:0) after 1m0s
[00:02:49.347] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:115:0)
[00:02:49.347] Timer tick, asking Buildbucket for the build status
[00:02:49.521] Build 8900310263419204672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:02:49.521] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:116:0) after 1m0s
[00:03:49.762] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:116:0)
[00:03:49.762] Timer tick, asking Buildbucket for the build status
[00:03:53.464] Build 8900310263419204672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:03:53.464] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:117:0) after 1m0s
[00:04:53.492] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:117:0)
[00:04:53.492] Timer tick, asking Buildbucket for the build status
[00:04:53.693] Build 8900310263419204672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:04:53.693] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:118:0) after 1m0s
[00:05:53.773] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:118:0)
[00:05:53.773] Timer tick, asking Buildbucket for the build status
[00:05:53.929] Build 8900310263419204672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:05:53.929] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:119:0) after 1m0s
[00:06:53.964] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:119:0)
[00:06:53.964] Timer tick, asking Buildbucket for the build status
[00:06:54.733] Build 8900310263419204672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:06:54.733] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:120:0) after 1m0s
[00:07:54.771] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:120:0)
[00:07:54.771] Timer tick, asking Buildbucket for the build status
[00:07:54.984] Build 8900310263419204672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:07:54.984] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:121:0) after 1m0s
[00:08:55.136] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:121:0)
[00:08:55.136] Timer tick, asking Buildbucket for the build status
[00:08:55.286] Build 8900310263419204672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:08:55.286] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:122:0) after 1m0s
[00:09:55.319] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:122:0)
[00:09:55.319] Timer tick, asking Buildbucket for the build status
[00:09:55.609] Build 8900310263419204672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:09:55.609] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:123:0) after 1m0s
[00:10:55.635] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:123:0)
[00:10:55.635] Timer tick, asking Buildbucket for the build status
[00:10:55.997] Build 8900310263419204672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:10:55.998] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:124:0) after 1m0s
[00:11:56.023] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:124:0)
[00:11:56.023] Timer tick, asking Buildbucket for the build status
[00:11:56.553] Build 8900310263419204672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:11:56.553] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:125:0) after 1m0s
[00:12:56.621] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:125:0)
[00:12:56.621] Timer tick, asking Buildbucket for the build status
[00:12:56.861] Build 8900310263419204672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:12:56.861] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:126:0) after 1m0s
[00:13:56.892] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:126:0)
[00:13:56.892] Timer tick, asking Buildbucket for the build status
[00:13:57.082] Build 8900310263419204672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:13:57.082] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:127:0) after 1m0s
[00:14:57.115] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:127:0)
[00:14:57.115] Timer tick, asking Buildbucket for the build status
[00:14:57.421] Build 8900310263419204672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:14:57.421] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:128:0) after 1m0s
[00:15:57.450] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:128:0)
[00:15:57.450] Timer tick, asking Buildbucket for the build status
[00:15:57.946] Build 8900310263419204672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:15:57.946] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:129:0) after 1m0s
[00:16:57.999] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:129:0)
[00:16:57.999] Timer tick, asking Buildbucket for the build status
[00:16:58.852] Build 8900310263419204672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:16:58.852] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:130:0) after 1m0s
[00:17:58.874] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:130:0)
[00:17:58.874] Timer tick, asking Buildbucket for the build status
[00:18:00.164] Build 8900310263419204672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:18:00.164] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:131:0) after 1m0s
[00:19:00.205] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:131:0)
[00:19:00.205] Timer tick, asking Buildbucket for the build status
[00:19:01.565] Build 8900310263419204672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:19:01.565] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:132:0) after 1m0s
[00:20:01.592] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:132:0)
[00:20:01.592] Timer tick, asking Buildbucket for the build status
[00:20:01.880] Build 8900310263419204672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:20:01.880] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:133:0) after 1m0s
[00:21:01.911] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:133:0)
[00:21:01.911] Timer tick, asking Buildbucket for the build status
[00:21:02.200] Build 8900310263419204672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:21:02.200] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:134:0) after 1m0s
[00:22:02.228] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:134:0)
[00:22:02.228] Timer tick, asking Buildbucket for the build status
[00:22:03.596] Build 8900310263419204672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:22:03.596] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:135:0) after 1m0s
[00:23:03.629] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:135:0)
[00:23:03.629] Timer tick, asking Buildbucket for the build status
[00:23:03.924] Build 8900310263419204672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:23:03.924] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:136:0) after 1m0s
[00:24:03.969] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:136:0)
[00:24:03.969] Timer tick, asking Buildbucket for the build status
[00:24:05.349] Build 8900310263419204672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:24:05.349] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:137:0) after 1m0s
[00:25:05.384] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:137:0)
[00:25:05.384] Timer tick, asking Buildbucket for the build status
[00:25:05.712] Build 8900310263419204672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:25:05.712] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:138:0) after 1m0s
[00:26:05.742] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:138:0)
[00:26:05.742] Timer tick, asking Buildbucket for the build status
[00:26:07.858] Build 8900310263419204672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:26:07.858] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:139:0) after 1m0s
[00:27:07.986] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:139:0)
[00:27:07.986] Timer tick, asking Buildbucket for the build status
[00:27:08.414] Build 8900310263419204672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:27:08.414] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:140:0) after 1m0s
[00:28:08.438] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:140:0)
[00:28:08.438] Timer tick, asking Buildbucket for the build status
[00:28:08.674] Build 8900310263419204672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:28:08.674] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:141:0) after 1m0s
[00:29:08.707] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:141:0)
[00:29:08.707] Timer tick, asking Buildbucket for the build status
[00:29:09.018] Build 8900310263419204672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:29:09.018] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:142:0) after 1m0s
[00:30:09.071] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:142:0)
[00:30:09.071] Timer tick, asking Buildbucket for the build status
[00:30:09.340] Build 8900310263419204672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:30:09.340] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:143:0) after 1m0s
[00:31:09.382] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:143:0)
[00:31:09.382] Timer tick, asking Buildbucket for the build status
[00:31:10.462] Build 8900310263419204672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:31:10.462] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9065740325919452912:144:0) after 1m0s
[00:31:58.470] Received PubSub notification, asking Buildbucket for the build status
[00:32:00.799] Build 8900310263419204672: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[00:32:00.799] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1570408317225512",
"created_by": "project:chromium",
"created_ts": "1570399715938830",
"id": "8900310263419204672",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"0f7fd04763a237291dd3530bc212de8b10e4a613\"}}",
"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-kun4\", \"branch\": \"refs/heads/master\", \"buildername\": \"Windows deterministic\", \"buildnumber\": 14906, \"got_angle_revision\": \"5e9d4ee99ea11265ac48cdc41cc60480f37e4f02\", \"got_dawn_revision\": \"21dfc919547fb3b139b56271d1aa09518bd75801\", \"got_nacl_revision\": \"3651f6c52037c96e034cc422746768d59182bff3\", \"got_revision\": \"0f7fd04763a237291dd3530bc212de8b10e4a613\", \"got_revision_cp\": \"refs/heads/master@{#703192}\", \"got_swarming_client_revision\": \"885b3febcc170a60f25795304e60927b77d1e92d\", \"got_v8_revision\": \"7eb4caf58996f5a3a7dad917c814194f59211ae4\", \"got_v8_revision_cp\": \"refs/heads/7.9.219@{#1}\", \"got_webrtc_revision\": \"b441acf656520aa6ff9d8864b0aa1b065f1771c8\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#29389}\", \"mastername\": \"chromium.win\", \"path_config\": \"generic\", \"recipe\": \"swarming/deterministic_build\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"0f7fd04763a237291dd3530bc212de8b10e4a613\"}, \"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-kun4\"], \"image\": [\"chrome-win10-1703-19091300-a244b1e46d2\"], \"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\": [\"4466-755d6a2\"], \"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": "1570399722584822",
"status": "COMPLETED",
"status_changed_ts": "1570408317837831",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/14906",
"builder:Windows deterministic",
"buildset:commit/git/0f7fd04763a237291dd3530bc212de8b10e4a613",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/0f7fd04763a237291dd3530bc212de8b10e4a613",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9065740325919452912",
"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/8900310263419204672/+/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:47bbef9420d44d10",
"user_agent:luci-scheduler"
],
"updated_ts": "1570408317879573",
"url": "https://ci.chromium.org/b/8900310263419204672",
"utcnow_ts": "1570408320744682"
}
}
[00:32:00.799] Invocation finished in 2h23m26.629865592s with status SUCCEEDED