[00:02:32.240] New invocation is queued and will start shortly
[00:02:33.315] Starting the invocation (attempt 1)
[00:02:33.352] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[00:02:33.352] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[00:02:33.352] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9074611658412745824",
"parameters_json": "{\"builder_name\":\"Windows deterministic\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src.git\",\"revision\":\"08677dd58641b5fc8b115bd455551cfb13628381\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Windows deterministic",
"scheduler_invocation_id:9074611658412745824",
"scheduler_job_id:chromium/Windows deterministic",
"user_agent:luci-scheduler",
"buildset:commit/git/08677dd58641b5fc8b115bd455551cfb13628381",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/08677dd58641b5fc8b115bd455551cfb13628381",
"gitiles_ref:refs/heads/master"
]
}
[00:02:39.909] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1561939355038039",
"id": "8909181594809768736",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"08677dd58641b5fc8b115bd455551cfb13628381\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1561939359030093",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/13985",
"builder:Windows deterministic",
"buildset:commit/git/08677dd58641b5fc8b115bd455551cfb13628381",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/08677dd58641b5fc8b115bd455551cfb13628381",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9074611658412745824",
"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/8909181594809768736/+/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": "1561939359038033",
"url": "https://ci.chromium.org/b/8909181594809768736",
"utcnow_ts": "1561939359900710"
}
}
[00:02:39.909] Task URL: https://ci.chromium.org/b/8909181594809768736
[00:02:39.909] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:2:0) after 1m0s
[00:02:46.639] Received PubSub notification, asking Buildbucket for the build status
[00:02:46.819] Build 8909181594809768736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:03:39.927] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:2:0)
[00:03:39.927] Timer tick, asking Buildbucket for the build status
[00:03:40.170] Build 8909181594809768736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:03:40.170] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:4:0) after 1m0s
[00:04:40.222] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:4:0)
[00:04:40.222] Timer tick, asking Buildbucket for the build status
[00:04:40.459] Build 8909181594809768736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:04:40.459] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:5:0) after 1m0s
[00:05:40.478] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:5:0)
[00:05:40.478] Timer tick, asking Buildbucket for the build status
[00:05:40.635] Build 8909181594809768736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:05:40.635] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:6:0) after 1m0s
[00:06:40.706] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:6:0)
[00:06:40.706] Timer tick, asking Buildbucket for the build status
[00:06:41.355] Build 8909181594809768736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:06:41.355] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:7:0) after 1m0s
[00:07:41.396] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:7:0)
[00:07:41.396] Timer tick, asking Buildbucket for the build status
[00:07:41.576] Build 8909181594809768736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:07:41.576] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:8:0) after 1m0s
[00:08:41.598] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:8:0)
[00:08:41.598] Timer tick, asking Buildbucket for the build status
[00:08:42.106] Build 8909181594809768736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:08:42.106] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:9:0) after 1m0s
[00:09:42.135] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:9:0)
[00:09:42.135] Timer tick, asking Buildbucket for the build status
[00:09:42.648] Build 8909181594809768736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:09:42.648] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:10:0) after 1m0s
[00:10:42.670] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:10:0)
[00:10:42.670] Timer tick, asking Buildbucket for the build status
[00:10:44.739] Build 8909181594809768736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:10:44.739] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:11:0) after 1m0s
[00:11:44.814] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:11:0)
[00:11:44.814] Timer tick, asking Buildbucket for the build status
[00:11:45.056] Build 8909181594809768736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:11:45.056] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:12:0) after 1m0s
[00:12:45.075] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:12:0)
[00:12:45.075] Timer tick, asking Buildbucket for the build status
[00:12:45.442] Build 8909181594809768736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:12:45.442] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:13:0) after 1m0s
[00:13:45.520] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:13:0)
[00:13:45.520] Timer tick, asking Buildbucket for the build status
[00:13:45.719] Build 8909181594809768736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:13:45.719] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:14:0) after 1m0s
[00:14:45.738] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:14:0)
[00:14:45.738] Timer tick, asking Buildbucket for the build status
[00:14:49.338] Build 8909181594809768736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:14:49.338] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:15:0) after 1m0s
[00:15:49.352] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:15:0)
[00:15:49.352] Timer tick, asking Buildbucket for the build status
[00:15:52.304] Build 8909181594809768736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:15:52.304] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:16:0) after 1m0s
[00:16:52.378] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:16:0)
[00:16:52.378] Timer tick, asking Buildbucket for the build status
[00:16:53.817] Build 8909181594809768736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:16:53.817] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:17:0) after 1m0s
[00:17:53.983] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:17:0)
[00:17:53.983] Timer tick, asking Buildbucket for the build status
[00:17:54.465] Build 8909181594809768736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:17:54.465] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:18:0) after 1m0s
[00:18:54.554] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:18:0)
[00:18:54.554] Timer tick, asking Buildbucket for the build status
[00:18:55.135] Build 8909181594809768736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:18:55.135] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:19:0) after 1m0s
[00:19:55.169] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:19:0)
[00:19:55.169] Timer tick, asking Buildbucket for the build status
[00:19:56.288] Build 8909181594809768736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:19:56.288] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:20:0) after 1m0s
[00:20:56.322] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:20:0)
[00:20:56.322] Timer tick, asking Buildbucket for the build status
[00:20:57.299] Build 8909181594809768736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:20:57.299] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:21:0) after 1m0s
[00:21:57.270] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:21:0)
[00:21:57.270] Timer tick, asking Buildbucket for the build status
[00:21:57.806] Build 8909181594809768736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:21:57.806] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:22:0) after 1m0s
[00:22:57.889] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:22:0)
[00:22:57.889] Timer tick, asking Buildbucket for the build status
[00:22:58.045] Build 8909181594809768736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:22:58.045] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:23:0) after 1m0s
[00:23:58.189] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:23:0)
[00:23:58.189] Timer tick, asking Buildbucket for the build status
[00:23:58.787] Build 8909181594809768736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:23:58.787] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:24:0) after 1m0s
[00:24:58.855] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:24:0)
[00:24:58.855] Timer tick, asking Buildbucket for the build status
[00:24:59.503] Build 8909181594809768736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:24:59.503] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:25:0) after 1m0s
[00:25:59.625] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:25:0)
[00:25:59.625] Timer tick, asking Buildbucket for the build status
[00:26:00.318] Build 8909181594809768736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:26:00.318] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:26:0) after 1m0s
[00:27:00.357] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:26:0)
[00:27:00.357] Timer tick, asking Buildbucket for the build status
[00:27:00.473] Build 8909181594809768736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:27:00.473] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:27:0) after 1m0s
[00:28:00.567] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:27:0)
[00:28:00.567] Timer tick, asking Buildbucket for the build status
[00:28:01.724] Build 8909181594809768736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:28:01.724] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:28:0) after 1m0s
[00:29:01.753] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:28:0)
[00:29:01.753] Timer tick, asking Buildbucket for the build status
[00:29:03.712] Build 8909181594809768736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:29:03.712] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:29:0) after 1m0s
[00:30:03.736] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:29:0)
[00:30:03.736] Timer tick, asking Buildbucket for the build status
[00:30:06.188] Build 8909181594809768736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:30:06.188] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:30:0) after 1m0s
[00:31:06.207] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:30:0)
[00:31:06.207] Timer tick, asking Buildbucket for the build status
[00:31:06.354] Build 8909181594809768736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:31:06.354] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:31:0) after 1m0s
[00:32:06.346] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:31:0)
[00:32:06.346] Timer tick, asking Buildbucket for the build status
[00:32:07.116] Build 8909181594809768736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:32:07.116] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:32:0) after 1m0s
[00:33:07.114] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:32:0)
[00:33:07.114] Timer tick, asking Buildbucket for the build status
[00:33:07.496] Build 8909181594809768736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:33:07.496] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:33:0) after 1m0s
[00:34:07.517] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:33:0)
[00:34:07.517] Timer tick, asking Buildbucket for the build status
[00:34:09.768] Build 8909181594809768736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:34:09.768] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:34:0) after 1m0s
[00:35:09.787] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:34:0)
[00:35:09.787] Timer tick, asking Buildbucket for the build status
[00:35:11.130] Build 8909181594809768736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:35:11.130] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:35:0) after 1m0s
[00:36:11.154] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:35:0)
[00:36:11.154] Timer tick, asking Buildbucket for the build status
[00:36:12.304] Build 8909181594809768736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:36:12.304] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:36:0) after 1m0s
[00:37:12.471] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:36:0)
[00:37:12.471] Timer tick, asking Buildbucket for the build status
[00:37:13.796] Build 8909181594809768736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:37:13.796] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:37:0) after 1m0s
[00:38:13.853] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:37:0)
[00:38:13.853] Timer tick, asking Buildbucket for the build status
[00:38:14.017] Build 8909181594809768736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:38:14.017] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:38:0) after 1m0s
[00:39:14.043] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:38:0)
[00:39:14.043] Timer tick, asking Buildbucket for the build status
[00:39:14.744] Build 8909181594809768736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:39:14.744] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:39:0) after 1m0s
[00:40:14.969] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:39:0)
[00:40:14.969] Timer tick, asking Buildbucket for the build status
[00:40:15.507] Build 8909181594809768736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:40:15.507] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:40:0) after 1m0s
[00:41:15.528] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:40:0)
[00:41:15.528] Timer tick, asking Buildbucket for the build status
[00:41:15.844] Build 8909181594809768736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:41:15.844] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:41:0) after 1m0s
[00:42:15.863] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:41:0)
[00:42:15.863] Timer tick, asking Buildbucket for the build status
[00:42:16.112] Build 8909181594809768736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:42:16.112] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:42:0) after 1m0s
[00:43:16.158] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:42:0)
[00:43:16.158] Timer tick, asking Buildbucket for the build status
[00:43:16.483] Build 8909181594809768736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:43:16.483] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:43:0) after 1m0s
[00:44:16.491] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:43:0)
[00:44:16.491] Timer tick, asking Buildbucket for the build status
[00:44:17.924] Build 8909181594809768736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:44:17.924] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:44:0) after 1m0s
[00:45:17.985] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:44:0)
[00:45:17.985] Timer tick, asking Buildbucket for the build status
[00:45:18.169] Build 8909181594809768736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:45:18.169] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:45:0) after 1m0s
[00:46:18.216] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:45:0)
[00:46:18.216] Timer tick, asking Buildbucket for the build status
[00:46:18.469] Build 8909181594809768736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:46:18.469] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:46:0) after 1m0s
[00:47:18.544] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:46:0)
[00:47:18.544] Timer tick, asking Buildbucket for the build status
[00:47:18.683] Build 8909181594809768736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:47:18.683] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:47:0) after 1m0s
[00:48:18.758] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:47:0)
[00:48:18.758] Timer tick, asking Buildbucket for the build status
[00:48:19.850] Build 8909181594809768736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:48:19.850] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:48:0) after 1m0s
[00:49:19.953] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:48:0)
[00:49:19.953] Timer tick, asking Buildbucket for the build status
[00:49:20.119] Build 8909181594809768736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:49:20.119] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:49:0) after 1m0s
[00:50:20.228] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:49:0)
[00:50:20.228] Timer tick, asking Buildbucket for the build status
[00:50:20.393] Build 8909181594809768736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:50:20.393] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:50:0) after 1m0s
[00:51:20.455] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:50:0)
[00:51:20.455] Timer tick, asking Buildbucket for the build status
[00:51:20.947] Build 8909181594809768736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:51:20.947] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:51:0) after 1m0s
[00:52:20.990] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:51:0)
[00:52:20.990] Timer tick, asking Buildbucket for the build status
[00:52:21.266] Build 8909181594809768736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:52:21.266] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:52:0) after 1m0s
[00:53:21.985] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:52:0)
[00:53:21.985] Timer tick, asking Buildbucket for the build status
[00:53:22.253] Build 8909181594809768736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:53:22.253] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:53:0) after 1m0s
[00:54:22.503] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:53:0)
[00:54:22.503] Timer tick, asking Buildbucket for the build status
[00:54:22.675] Build 8909181594809768736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:54:22.675] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:54:0) after 1m0s
[00:55:22.785] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:54:0)
[00:55:22.785] Timer tick, asking Buildbucket for the build status
[00:55:23.302] Build 8909181594809768736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:55:23.302] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:55:0) after 1m0s
[00:56:23.410] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:55:0)
[00:56:23.410] Timer tick, asking Buildbucket for the build status
[00:56:24.944] Build 8909181594809768736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:56:24.944] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:56:0) after 1m0s
[00:57:25.881] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:56:0)
[00:57:25.881] Timer tick, asking Buildbucket for the build status
[00:57:26.636] Build 8909181594809768736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:57:26.636] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:57:0) after 1m0s
[00:58:26.848] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:57:0)
[00:58:26.848] Timer tick, asking Buildbucket for the build status
[00:58:27.004] Build 8909181594809768736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:58:27.004] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:58:0) after 1m0s
[00:59:27.337] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:58:0)
[00:59:27.338] Timer tick, asking Buildbucket for the build status
[00:59:27.821] Build 8909181594809768736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:59:27.821] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:59:0) after 1m0s
[01:00:27.908] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:59:0)
[01:00:27.908] Timer tick, asking Buildbucket for the build status
[01:00:28.107] Build 8909181594809768736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:00:28.107] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:60:0) after 1m0s
[01:01:28.203] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:60:0)
[01:01:28.203] Timer tick, asking Buildbucket for the build status
[01:01:28.401] Build 8909181594809768736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:01:28.401] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:61:0) after 1m0s
[01:02:28.397] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:61:0)
[01:02:28.397] Timer tick, asking Buildbucket for the build status
[01:02:28.718] Build 8909181594809768736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:02:28.718] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:62:0) after 1m0s
[01:03:29.197] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:62:0)
[01:03:29.197] Timer tick, asking Buildbucket for the build status
[01:03:29.703] Build 8909181594809768736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:03:29.703] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:63:0) after 1m0s
[01:04:29.962] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:63:0)
[01:04:29.962] Timer tick, asking Buildbucket for the build status
[01:04:30.063] Build 8909181594809768736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:04:30.063] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:64:0) after 1m0s
[01:05:30.078] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:64:0)
[01:05:30.078] Timer tick, asking Buildbucket for the build status
[01:05:30.363] Build 8909181594809768736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:05:30.363] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:65:0) after 1m0s
[01:06:30.386] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:65:0)
[01:06:30.386] Timer tick, asking Buildbucket for the build status
[01:06:30.893] Build 8909181594809768736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:06:30.893] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:66:0) after 1m0s
[01:07:30.906] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:66:0)
[01:07:30.906] Timer tick, asking Buildbucket for the build status
[01:07:31.165] Build 8909181594809768736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:07:31.166] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:67:0) after 1m0s
[01:08:31.346] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:67:0)
[01:08:31.346] Timer tick, asking Buildbucket for the build status
[01:08:31.533] Build 8909181594809768736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:08:31.533] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:68:0) after 1m0s
[01:09:31.598] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:68:0)
[01:09:31.598] Timer tick, asking Buildbucket for the build status
[01:09:32.082] Build 8909181594809768736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:09:32.082] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:69:0) after 1m0s
[01:10:32.258] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:69:0)
[01:10:32.258] Timer tick, asking Buildbucket for the build status
[01:10:32.381] Build 8909181594809768736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:10:32.381] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:70:0) after 1m0s
[01:11:32.498] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:70:0)
[01:11:32.498] Timer tick, asking Buildbucket for the build status
[01:11:34.669] Build 8909181594809768736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:11:34.669] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:71:0) after 1m0s
[01:12:34.677] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:71:0)
[01:12:34.677] Timer tick, asking Buildbucket for the build status
[01:12:34.871] Build 8909181594809768736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:12:34.871] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:72:0) after 1m0s
[01:13:34.908] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:72:0)
[01:13:34.908] Timer tick, asking Buildbucket for the build status
[01:13:35.193] Build 8909181594809768736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:13:35.193] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:73:0) after 1m0s
[01:14:35.331] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:73:0)
[01:14:35.331] Timer tick, asking Buildbucket for the build status
[01:14:35.494] Build 8909181594809768736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:14:35.494] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:74:0) after 1m0s
[01:15:35.609] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:74:0)
[01:15:35.609] Timer tick, asking Buildbucket for the build status
[01:15:36.196] Build 8909181594809768736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:15:36.196] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:75:0) after 1m0s
[01:16:36.318] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:75:0)
[01:16:36.318] Timer tick, asking Buildbucket for the build status
[01:16:36.463] Build 8909181594809768736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:16:36.463] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:76:0) after 1m0s
[01:17:36.485] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:76:0)
[01:17:36.485] Timer tick, asking Buildbucket for the build status
[01:17:36.826] Build 8909181594809768736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:17:36.826] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:77:0) after 1m0s
[01:18:37.067] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:77:0)
[01:18:37.067] Timer tick, asking Buildbucket for the build status
[01:18:37.196] Build 8909181594809768736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:18:37.196] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:78:0) after 1m0s
[01:19:37.271] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:78:0)
[01:19:37.271] Timer tick, asking Buildbucket for the build status
[01:19:37.887] Build 8909181594809768736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:19:37.887] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:79:0) after 1m0s
[01:20:37.871] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:79:0)
[01:20:37.871] Timer tick, asking Buildbucket for the build status
[01:20:38.406] Build 8909181594809768736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:20:38.406] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:80:0) after 1m0s
[01:21:38.448] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:80:0)
[01:21:38.448] Timer tick, asking Buildbucket for the build status
[01:21:38.594] Build 8909181594809768736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:21:38.594] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:81:0) after 1m0s
[01:22:38.676] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:81:0)
[01:22:38.676] Timer tick, asking Buildbucket for the build status
[01:22:38.820] Build 8909181594809768736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:22:38.820] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:82:0) after 1m0s
[01:23:38.900] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:82:0)
[01:23:38.900] Timer tick, asking Buildbucket for the build status
[01:23:39.109] Build 8909181594809768736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:23:39.109] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:83:0) after 1m0s
[01:24:39.175] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:83:0)
[01:24:39.175] Timer tick, asking Buildbucket for the build status
[01:24:39.598] Build 8909181594809768736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:24:39.598] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:84:0) after 1m0s
[01:25:39.686] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:84:0)
[01:25:39.686] Timer tick, asking Buildbucket for the build status
[01:25:40.115] Build 8909181594809768736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:25:40.115] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:85:0) after 1m0s
[01:26:40.147] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:85:0)
[01:26:40.147] Timer tick, asking Buildbucket for the build status
[01:26:40.316] Build 8909181594809768736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:26:40.316] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:86:0) after 1m0s
[01:27:40.436] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:86:0)
[01:27:40.436] Timer tick, asking Buildbucket for the build status
[01:27:41.014] Build 8909181594809768736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:27:41.014] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:87:0) after 1m0s
[01:28:41.268] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:87:0)
[01:28:41.268] Timer tick, asking Buildbucket for the build status
[01:28:41.444] Build 8909181594809768736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:28:41.444] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:88:0) after 1m0s
[01:29:41.467] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:88:0)
[01:29:41.467] Timer tick, asking Buildbucket for the build status
[01:29:41.632] Build 8909181594809768736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:29:41.632] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:89:0) after 1m0s
[01:30:41.652] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:89:0)
[01:30:41.652] Timer tick, asking Buildbucket for the build status
[01:30:42.109] Build 8909181594809768736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:30:42.109] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:90:0) after 1m0s
[01:31:42.282] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:90:0)
[01:31:42.282] Timer tick, asking Buildbucket for the build status
[01:31:42.472] Build 8909181594809768736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:31:42.472] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:91:0) after 1m0s
[01:32:42.570] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:91:0)
[01:32:42.570] Timer tick, asking Buildbucket for the build status
[01:32:45.612] Build 8909181594809768736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:32:45.612] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:92:0) after 1m0s
[01:33:45.749] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:92:0)
[01:33:45.749] Timer tick, asking Buildbucket for the build status
[01:33:45.897] Build 8909181594809768736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:33:45.897] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:93:0) after 1m0s
[01:34:46.037] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:93:0)
[01:34:46.037] Timer tick, asking Buildbucket for the build status
[01:34:46.414] Build 8909181594809768736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:34:46.414] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:94:0) after 1m0s
[01:35:46.434] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:94:0)
[01:35:46.434] Timer tick, asking Buildbucket for the build status
[01:35:46.569] Build 8909181594809768736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:35:46.569] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:95:0) after 1m0s
[01:36:46.669] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:95:0)
[01:36:46.669] Timer tick, asking Buildbucket for the build status
[01:36:48.627] Build 8909181594809768736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:36:48.628] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:96:0) after 1m0s
[01:37:48.654] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:96:0)
[01:37:48.654] Timer tick, asking Buildbucket for the build status
[01:37:49.604] Build 8909181594809768736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:37:49.604] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:97:0) after 1m0s
[01:38:49.726] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:97:0)
[01:38:49.726] Timer tick, asking Buildbucket for the build status
[01:38:50.308] Build 8909181594809768736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:38:50.308] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:98:0) after 1m0s
[01:39:50.332] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:98:0)
[01:39:50.332] Timer tick, asking Buildbucket for the build status
[01:39:50.498] Build 8909181594809768736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:39:50.498] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:99:0) after 1m0s
[01:40:50.746] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:99:0)
[01:40:50.746] Timer tick, asking Buildbucket for the build status
[01:40:51.322] Build 8909181594809768736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:40:51.322] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:100:0) after 1m0s
[01:41:51.345] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:100:0)
[01:41:51.345] Timer tick, asking Buildbucket for the build status
[01:41:51.497] Build 8909181594809768736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:41:51.497] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:101:0) after 1m0s
[01:42:51.722] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:101:0)
[01:42:51.722] Timer tick, asking Buildbucket for the build status
[01:42:51.891] Build 8909181594809768736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:42:51.891] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:102:0) after 1m0s
[01:43:51.988] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:102:0)
[01:43:51.988] Timer tick, asking Buildbucket for the build status
[01:43:52.525] Build 8909181594809768736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:43:52.525] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:103:0) after 1m0s
[01:44:52.662] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:103:0)
[01:44:52.662] Timer tick, asking Buildbucket for the build status
[01:44:52.796] Build 8909181594809768736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:44:52.796] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:104:0) after 1m0s
[01:45:52.989] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:104:0)
[01:45:52.989] Timer tick, asking Buildbucket for the build status
[01:45:56.652] Build 8909181594809768736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:45:56.652] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:105:0) after 1m0s
[01:46:56.687] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:105:0)
[01:46:56.687] Timer tick, asking Buildbucket for the build status
[01:46:59.675] Build 8909181594809768736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:46:59.675] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:106:0) after 1m0s
[01:47:59.813] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:106:0)
[01:47:59.813] Timer tick, asking Buildbucket for the build status
[01:48:05.446] Build 8909181594809768736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:48:05.446] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:107:0) after 1m0s
[01:49:05.509] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:107:0)
[01:49:05.509] Timer tick, asking Buildbucket for the build status
[01:49:05.923] Build 8909181594809768736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:49:05.923] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:108:0) after 1m0s
[01:50:06.004] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:108:0)
[01:50:06.004] Timer tick, asking Buildbucket for the build status
[01:50:06.617] Build 8909181594809768736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:50:06.617] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:109:0) after 1m0s
[01:51:06.740] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:109:0)
[01:51:06.740] Timer tick, asking Buildbucket for the build status
[01:51:07.061] Build 8909181594809768736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:51:07.061] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:110:0) after 1m0s
[01:52:07.094] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:110:0)
[01:52:07.094] Timer tick, asking Buildbucket for the build status
[01:52:07.430] Build 8909181594809768736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:52:07.430] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:111:0) after 1m0s
[01:53:08.037] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:111:0)
[01:53:08.037] Timer tick, asking Buildbucket for the build status
[01:53:08.247] Build 8909181594809768736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:53:08.247] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:112:0) after 1m0s
[01:54:08.260] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:112:0)
[01:54:08.261] Timer tick, asking Buildbucket for the build status
[01:54:09.733] Build 8909181594809768736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:54:09.733] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:113:0) after 1m0s
[01:55:09.780] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:113:0)
[01:55:09.780] Timer tick, asking Buildbucket for the build status
[01:55:09.924] Build 8909181594809768736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:55:09.924] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:114:0) after 1m0s
[01:56:09.968] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:114:0)
[01:56:09.968] Timer tick, asking Buildbucket for the build status
[01:56:10.718] Build 8909181594809768736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:56:10.718] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:115:0) after 1m0s
[01:57:10.759] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:115:0)
[01:57:10.759] Timer tick, asking Buildbucket for the build status
[01:57:11.158] Build 8909181594809768736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:57:11.158] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:116:0) after 1m0s
[01:58:11.378] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:116:0)
[01:58:11.378] Timer tick, asking Buildbucket for the build status
[01:58:11.625] Build 8909181594809768736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:58:11.625] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:117:0) after 1m0s
[01:59:12.123] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:117:0)
[01:59:12.123] Timer tick, asking Buildbucket for the build status
[01:59:12.321] Build 8909181594809768736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:59:12.321] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:118:0) after 1m0s
[02:00:12.455] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:118:0)
[02:00:12.455] Timer tick, asking Buildbucket for the build status
[02:00:12.897] Build 8909181594809768736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:00:12.897] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:119:0) after 1m0s
[02:01:13.209] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:119:0)
[02:01:13.209] Timer tick, asking Buildbucket for the build status
[02:01:13.466] Build 8909181594809768736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:01:13.466] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:120:0) after 1m0s
[02:02:13.563] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:120:0)
[02:02:13.563] Timer tick, asking Buildbucket for the build status
[02:02:13.780] Build 8909181594809768736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:02:13.780] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:121:0) after 1m0s
[02:03:13.935] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:121:0)
[02:03:13.935] Timer tick, asking Buildbucket for the build status
[02:03:19.813] Build 8909181594809768736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:03:19.813] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:122:0) after 1m0s
[02:04:21.256] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:122:0)
[02:04:21.256] Timer tick, asking Buildbucket for the build status
[02:04:21.394] Build 8909181594809768736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:04:21.394] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:123:0) after 1m0s
[02:05:21.669] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:123:0)
[02:05:21.669] Timer tick, asking Buildbucket for the build status
[02:05:22.204] Build 8909181594809768736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:05:22.204] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:124:0) after 1m0s
[02:06:22.309] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:124:0)
[02:06:22.309] Timer tick, asking Buildbucket for the build status
[02:06:23.068] Build 8909181594809768736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:06:23.068] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:125:0) after 1m0s
[02:07:23.480] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:125:0)
[02:07:23.480] Timer tick, asking Buildbucket for the build status
[02:07:23.686] Build 8909181594809768736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:07:23.686] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:126:0) after 1m0s
[02:08:23.985] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:126:0)
[02:08:23.985] Timer tick, asking Buildbucket for the build status
[02:08:24.424] Build 8909181594809768736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:08:24.424] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:127:0) after 1m0s
[02:09:24.961] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:127:0)
[02:09:24.961] Timer tick, asking Buildbucket for the build status
[02:09:25.126] Build 8909181594809768736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:09:25.126] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:128:0) after 1m0s
[02:10:25.148] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:128:0)
[02:10:25.148] Timer tick, asking Buildbucket for the build status
[02:10:25.350] Build 8909181594809768736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:10:25.350] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:129:0) after 1m0s
[02:11:25.599] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:129:0)
[02:11:25.599] Timer tick, asking Buildbucket for the build status
[02:11:26.471] Build 8909181594809768736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:11:26.471] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:130:0) after 1m0s
[02:12:26.687] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:130:0)
[02:12:26.687] Timer tick, asking Buildbucket for the build status
[02:12:26.803] Build 8909181594809768736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:12:26.803] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:131:0) after 1m0s
[02:13:27.002] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:131:0)
[02:13:27.002] Timer tick, asking Buildbucket for the build status
[02:13:27.196] Build 8909181594809768736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:13:27.196] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:132:0) after 1m0s
[02:14:27.306] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:132:0)
[02:14:27.306] Timer tick, asking Buildbucket for the build status
[02:14:27.849] Build 8909181594809768736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:14:27.849] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:133:0) after 1m0s
[02:15:27.969] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:133:0)
[02:15:27.969] Timer tick, asking Buildbucket for the build status
[02:15:28.495] Build 8909181594809768736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:15:28.495] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:134:0) after 1m0s
[02:16:28.597] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:134:0)
[02:16:28.597] Timer tick, asking Buildbucket for the build status
[02:16:29.003] Build 8909181594809768736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:16:29.003] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:135:0) after 1m0s
[02:17:29.149] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:135:0)
[02:17:29.149] Timer tick, asking Buildbucket for the build status
[02:17:29.341] Build 8909181594809768736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:17:29.341] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074611658412745824:136:0) after 1m0s
[02:17:50.142] Received PubSub notification, asking Buildbucket for the build status
[02:17:50.354] Build 8909181594809768736: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[02:17:50.355] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1561947469513201",
"created_by": "project:chromium",
"created_ts": "1561939355038039",
"id": "8909181594809768736",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"08677dd58641b5fc8b115bd455551cfb13628381\"}}",
"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-xbv6\", \"branch\": \"refs/heads/master\", \"buildername\": \"Windows deterministic\", \"buildnumber\": 13985, \"got_angle_revision\": \"f116aa9c158b91bc966ef0394d666da4b3e7bcb6\", \"got_dawn_revision\": \"eee3e41c554257a0ece66e70b2b4e1390ffa6ab0\", \"got_nacl_revision\": \"c4cf6f0621b94cca4053b19fa01ef329f5663880\", \"got_revision\": \"08677dd58641b5fc8b115bd455551cfb13628381\", \"got_revision_cp\": \"refs/heads/master@{#673622}\", \"got_swarming_client_revision\": \"96f125709acfd0b48fc1e5dae7d6ea42291726ac\", \"got_v8_revision\": \"45ddaacef4d3cd90c58d766380fc1f418f998cd2\", \"got_v8_revision_cp\": \"refs/heads/7.7.166@{#1}\", \"got_webrtc_revision\": \"2ce1da53283cd87219fcd111dcf2c60eb613c79f\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#28422}\", \"mastername\": \"chromium.win\", \"path_config\": \"generic\", \"recipe\": \"swarming/deterministic_build\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"08677dd58641b5fc8b115bd455551cfb13628381\"}, \"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-xbv6\"], \"image\": [\"chrome-win10-1703-2018-04-20-61acaf8983f\"], \"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\": [\"4351-b85b41c\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-west\", \"us-west1\", \"us-west1-c\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1561939363773824",
"status": "COMPLETED",
"status_changed_ts": "1561947469864249",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/13985",
"builder:Windows deterministic",
"buildset:commit/git/08677dd58641b5fc8b115bd455551cfb13628381",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/08677dd58641b5fc8b115bd455551cfb13628381",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9074611658412745824",
"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/8909181594809768736/+/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:45c3a8c94ce8c810",
"user_agent:luci-scheduler"
],
"updated_ts": "1561947469869317",
"url": "https://ci.chromium.org/b/8909181594809768736",
"utcnow_ts": "1561947470344382"
}
}
[02:17:50.355] Invocation finished in 2h15m18.135519781s with status SUCCEEDED