[06:54:50.716] New invocation is queued and will start shortly
[06:54:51.843] Starting the invocation (attempt 1)
[06:54:51.940] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[06:54:51.940] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[06:54:51.940] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9070508854762586480",
"parameters_json": "{\"builder_name\":\"Windows deterministic\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src.git\",\"revision\":\"2df6cf8306a859c7d8ae9ebbdf9beb056e4bd054\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Windows deterministic",
"scheduler_invocation_id:9070508854762586480",
"scheduler_job_id:chromium/Windows deterministic",
"user_agent:luci-scheduler",
"buildset:commit/git/2df6cf8306a859c7d8ae9ebbdf9beb056e4bd054",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/2df6cf8306a859c7d8ae9ebbdf9beb056e4bd054",
"gitiles_ref:refs/heads/master"
]
}
[06:54:52.766] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1565852092137485",
"id": "8905078792594073536",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"2df6cf8306a859c7d8ae9ebbdf9beb056e4bd054\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1565852092609406",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/14427",
"builder:Windows deterministic",
"buildset:commit/git/2df6cf8306a859c7d8ae9ebbdf9beb056e4bd054",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/2df6cf8306a859c7d8ae9ebbdf9beb056e4bd054",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9070508854762586480",
"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/8905078792594073536/+/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": "1565852092609525",
"url": "https://ci.chromium.org/b/8905078792594073536",
"utcnow_ts": "1565852092751925"
}
}
[06:54:52.766] Task URL: https://ci.chromium.org/b/8905078792594073536
[06:54:52.766] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9070508854762586480:2:0) after 1m0s
[06:55:53.030] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9070508854762586480:2:0)
[06:55:53.030] Timer tick, asking Buildbucket for the build status
[06:55:53.171] Build 8905078792594073536: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:55:53.171] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9070508854762586480:3:0) after 1m0s
[06:56:53.335] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9070508854762586480:3:0)
[06:56:53.335] Timer tick, asking Buildbucket for the build status
[06:56:53.522] Build 8905078792594073536: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:56:53.522] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9070508854762586480:4:0) after 1m0s
[06:57:53.648] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9070508854762586480:4:0)
[06:57:53.648] Timer tick, asking Buildbucket for the build status
[06:57:54.044] Build 8905078792594073536: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:57:54.044] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9070508854762586480:5:0) after 1m0s
[06:58:54.105] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9070508854762586480:5:0)
[06:58:54.105] Timer tick, asking Buildbucket for the build status
[06:58:54.930] Build 8905078792594073536: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:58:54.930] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9070508854762586480:6:0) after 1m0s
[06:59:55.321] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9070508854762586480:6:0)
[06:59:55.321] Timer tick, asking Buildbucket for the build status
[06:59:55.953] Build 8905078792594073536: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:59:55.953] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9070508854762586480:7:0) after 1m0s
[07:00:56.117] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9070508854762586480:7:0)
[07:00:56.117] Timer tick, asking Buildbucket for the build status
[07:00:56.343] Build 8905078792594073536: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:00:56.343] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9070508854762586480:8:0) after 1m0s
[07:01:56.383] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9070508854762586480:8:0)
[07:01:56.383] Timer tick, asking Buildbucket for the build status
[07:01:56.533] Build 8905078792594073536: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:01:56.533] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9070508854762586480:9:0) after 1m0s
[07:02:56.781] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9070508854762586480:9:0)
[07:02:56.781] Timer tick, asking Buildbucket for the build status
[07:02:57.175] Build 8905078792594073536: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:02:57.175] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9070508854762586480:10:0) after 1m0s
[07:03:57.249] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9070508854762586480:10:0)
[07:03:57.249] Timer tick, asking Buildbucket for the build status
[07:03:58.113] Build 8905078792594073536: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:03:58.113] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9070508854762586480:11:0) after 1m0s
[07:04:58.187] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9070508854762586480:11:0)
[07:04:58.187] Timer tick, asking Buildbucket for the build status
[07:04:58.960] Build 8905078792594073536: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:04:58.960] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9070508854762586480:12:0) after 1m0s
[07:05:59.174] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9070508854762586480:12:0)
[07:05:59.174] Timer tick, asking Buildbucket for the build status
[07:05:59.354] Build 8905078792594073536: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:05:59.354] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9070508854762586480:13:0) after 1m0s
[07:06:59.422] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9070508854762586480:13:0)
[07:06:59.422] Timer tick, asking Buildbucket for the build status
[07:06:59.771] Build 8905078792594073536: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:06:59.772] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9070508854762586480:14:0) after 1m0s
[07:07:25.503] Received PubSub notification, asking Buildbucket for the build status
[07:07:25.662] Build 8905078792594073536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:07:59.838] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9070508854762586480:14:0)
[07:07:59.838] Timer tick, asking Buildbucket for the build status
[07:08:00.111] Build 8905078792594073536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:08:00.111] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9070508854762586480:16:0) after 1m0s
[07:09:00.225] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9070508854762586480:16:0)
[07:09:00.225] Timer tick, asking Buildbucket for the build status
[07:09:00.828] Build 8905078792594073536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:09:00.828] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9070508854762586480:17:0) after 1m0s
[07:10:00.900] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9070508854762586480:17:0)
[07:10:00.900] Timer tick, asking Buildbucket for the build status
[07:10:02.237] Build 8905078792594073536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:10:02.237] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9070508854762586480:18:0) after 1m0s
[07:11:02.283] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9070508854762586480:18:0)
[07:11:02.283] Timer tick, asking Buildbucket for the build status
[07:11:02.967] Build 8905078792594073536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:11:02.967] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9070508854762586480:19:0) after 1m0s
[07:12:03.002] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9070508854762586480:19:0)
[07:12:03.002] Timer tick, asking Buildbucket for the build status
[07:12:04.142] Build 8905078792594073536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:12:04.143] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9070508854762586480:20:0) after 1m0s
[07:13:04.196] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9070508854762586480:20:0)
[07:13:04.196] Timer tick, asking Buildbucket for the build status
[07:13:05.029] Build 8905078792594073536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:13:05.029] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9070508854762586480:21:0) after 1m0s
[07:14:05.188] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9070508854762586480:21:0)
[07:14:05.188] Timer tick, asking Buildbucket for the build status
[07:14:05.928] Build 8905078792594073536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:14:05.928] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9070508854762586480:22:0) after 1m0s
[07:15:06.017] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9070508854762586480:22:0)
[07:15:06.017] Timer tick, asking Buildbucket for the build status
[07:15:06.396] Build 8905078792594073536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:15:06.396] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9070508854762586480:23:0) after 1m0s
[07:16:06.476] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9070508854762586480:23:0)
[07:16:06.476] Timer tick, asking Buildbucket for the build status
[07:16:07.673] Build 8905078792594073536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:16:07.673] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9070508854762586480:24:0) after 1m0s
[07:17:07.780] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9070508854762586480:24:0)
[07:17:07.781] Timer tick, asking Buildbucket for the build status
[07:17:08.425] Build 8905078792594073536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:17:08.425] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9070508854762586480:25:0) after 1m0s
[07:18:08.482] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9070508854762586480:25:0)
[07:18:08.482] Timer tick, asking Buildbucket for the build status
[07:18:08.729] Build 8905078792594073536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:18:08.729] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9070508854762586480:26:0) after 1m0s
[07:19:08.840] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9070508854762586480:26:0)
[07:19:08.840] Timer tick, asking Buildbucket for the build status
[07:19:09.336] Build 8905078792594073536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:19:09.336] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9070508854762586480:27:0) after 1m0s
[07:20:09.377] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9070508854762586480:27:0)
[07:20:09.377] Timer tick, asking Buildbucket for the build status
[07:20:10.003] Build 8905078792594073536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:20:10.003] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9070508854762586480:28:0) after 1m0s
[07:21:10.041] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9070508854762586480:28:0)
[07:21:10.041] Timer tick, asking Buildbucket for the build status
[07:21:10.215] Build 8905078792594073536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:21:10.215] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9070508854762586480:29:0) after 1m0s
[07:22:10.241] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9070508854762586480:29:0)
[07:22:10.241] Timer tick, asking Buildbucket for the build status
[07:22:10.935] Build 8905078792594073536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:22:10.935] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9070508854762586480:30:0) after 1m0s
[07:23:10.980] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9070508854762586480:30:0)
[07:23:10.980] Timer tick, asking Buildbucket for the build status
[07:23:11.145] Build 8905078792594073536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:23:11.145] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9070508854762586480:31:0) after 1m0s
[07:24:11.206] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9070508854762586480:31:0)
[07:24:11.206] Timer tick, asking Buildbucket for the build status
[07:24:12.993] Build 8905078792594073536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:24:12.993] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9070508854762586480:32:0) after 1m0s
[07:25:13.026] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9070508854762586480:32:0)
[07:25:13.026] Timer tick, asking Buildbucket for the build status
[07:25:13.441] Build 8905078792594073536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:25:13.441] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9070508854762586480:33:0) after 1m0s
[07:26:13.558] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9070508854762586480:33:0)
[07:26:13.558] Timer tick, asking Buildbucket for the build status
[07:26:13.776] Build 8905078792594073536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:26:13.776] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9070508854762586480:34:0) after 1m0s
[07:27:13.904] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9070508854762586480:34:0)
[07:27:13.904] Timer tick, asking Buildbucket for the build status
[07:27:14.647] Build 8905078792594073536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:27:14.647] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9070508854762586480:35:0) after 1m0s
[07:28:14.719] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9070508854762586480:35:0)
[07:28:14.719] Timer tick, asking Buildbucket for the build status
[07:28:15.948] Build 8905078792594073536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:28:15.948] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9070508854762586480:36:0) after 1m0s
[07:29:15.973] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9070508854762586480:36:0)
[07:29:15.973] Timer tick, asking Buildbucket for the build status
[07:29:16.143] Build 8905078792594073536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:29:16.143] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9070508854762586480:37:0) after 1m0s
[07:30:16.172] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9070508854762586480:37:0)
[07:30:16.172] Timer tick, asking Buildbucket for the build status
[07:30:16.652] Build 8905078792594073536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:30:16.652] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9070508854762586480:38:0) after 1m0s
[07:31:16.729] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9070508854762586480:38:0)
[07:31:16.729] Timer tick, asking Buildbucket for the build status
[07:31:16.887] Build 8905078792594073536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:31:16.887] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9070508854762586480:39:0) after 1m0s
[07:32:16.918] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9070508854762586480:39:0)
[07:32:16.918] Timer tick, asking Buildbucket for the build status
[07:32:17.188] Build 8905078792594073536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:32:17.188] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9070508854762586480:40:0) after 1m0s
[07:33:17.284] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9070508854762586480:40:0)
[07:33:17.284] Timer tick, asking Buildbucket for the build status
[07:33:17.581] Build 8905078792594073536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:33:17.581] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9070508854762586480:41:0) after 1m0s
[07:34:17.625] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9070508854762586480:41:0)
[07:34:17.625] Timer tick, asking Buildbucket for the build status
[07:34:18.368] Build 8905078792594073536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:34:18.368] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9070508854762586480:42:0) after 1m0s
[07:35:18.429] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9070508854762586480:42:0)
[07:35:18.429] Timer tick, asking Buildbucket for the build status
[07:35:18.601] Build 8905078792594073536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:35:18.601] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9070508854762586480:43:0) after 1m0s
[07:36:18.637] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9070508854762586480:43:0)
[07:36:18.637] Timer tick, asking Buildbucket for the build status
[07:36:19.666] Build 8905078792594073536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:36:19.666] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9070508854762586480:44:0) after 1m0s
[07:37:19.706] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9070508854762586480:44:0)
[07:37:19.706] Timer tick, asking Buildbucket for the build status
[07:37:20.007] Build 8905078792594073536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:37:20.007] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9070508854762586480:45:0) after 1m0s
[07:38:20.130] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9070508854762586480:45:0)
[07:38:20.130] Timer tick, asking Buildbucket for the build status
[07:38:21.045] Build 8905078792594073536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:38:21.045] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9070508854762586480:46:0) after 1m0s
[07:39:21.160] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9070508854762586480:46:0)
[07:39:21.160] Timer tick, asking Buildbucket for the build status
[07:39:21.331] Build 8905078792594073536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:39:21.331] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9070508854762586480:47:0) after 1m0s
[07:40:21.514] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9070508854762586480:47:0)
[07:40:21.514] Timer tick, asking Buildbucket for the build status
[07:40:21.798] Build 8905078792594073536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:40:21.798] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9070508854762586480:48:0) after 1m0s
[07:41:21.904] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9070508854762586480:48:0)
[07:41:21.904] Timer tick, asking Buildbucket for the build status
[07:41:22.126] Build 8905078792594073536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:41:22.126] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9070508854762586480:49:0) after 1m0s
[07:42:22.232] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9070508854762586480:49:0)
[07:42:22.232] Timer tick, asking Buildbucket for the build status
[07:42:22.709] Build 8905078792594073536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:42:22.709] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9070508854762586480:50:0) after 1m0s
[07:43:22.799] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9070508854762586480:50:0)
[07:43:22.799] Timer tick, asking Buildbucket for the build status
[07:43:23.096] Build 8905078792594073536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:43:23.096] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9070508854762586480:51:0) after 1m0s
[07:44:23.136] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9070508854762586480:51:0)
[07:44:23.137] Timer tick, asking Buildbucket for the build status
[07:44:23.565] Build 8905078792594073536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:44:23.565] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9070508854762586480:52:0) after 1m0s
[07:45:23.752] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9070508854762586480:52:0)
[07:45:23.752] Timer tick, asking Buildbucket for the build status
[07:45:23.906] Build 8905078792594073536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:45:23.906] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9070508854762586480:53:0) after 1m0s
[07:46:24.035] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9070508854762586480:53:0)
[07:46:24.035] Timer tick, asking Buildbucket for the build status
[07:46:24.548] Build 8905078792594073536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:46:24.548] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9070508854762586480:54:0) after 1m0s
[07:47:24.619] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9070508854762586480:54:0)
[07:47:24.619] Timer tick, asking Buildbucket for the build status
[07:47:25.180] Build 8905078792594073536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:47:25.180] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9070508854762586480:55:0) after 1m0s
[07:48:25.237] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9070508854762586480:55:0)
[07:48:25.237] Timer tick, asking Buildbucket for the build status
[07:48:25.664] Build 8905078792594073536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:48:25.664] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9070508854762586480:56:0) after 1m0s
[07:49:25.722] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9070508854762586480:56:0)
[07:49:25.723] Timer tick, asking Buildbucket for the build status
[07:49:25.961] Build 8905078792594073536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:49:25.961] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9070508854762586480:57:0) after 1m0s
[07:50:25.988] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9070508854762586480:57:0)
[07:50:25.988] Timer tick, asking Buildbucket for the build status
[07:50:26.137] Build 8905078792594073536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:50:26.137] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9070508854762586480:58:0) after 1m0s
[07:51:26.230] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9070508854762586480:58:0)
[07:51:26.230] Timer tick, asking Buildbucket for the build status
[07:51:26.440] Build 8905078792594073536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:51:26.440] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9070508854762586480:59:0) after 1m0s
[07:52:26.513] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9070508854762586480:59:0)
[07:52:26.513] Timer tick, asking Buildbucket for the build status
[07:52:27.484] Build 8905078792594073536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:52:27.484] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9070508854762586480:60:0) after 1m0s
[07:53:27.514] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9070508854762586480:60:0)
[07:53:27.514] Timer tick, asking Buildbucket for the build status
[07:53:27.665] Build 8905078792594073536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:53:27.665] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9070508854762586480:61:0) after 1m0s
[07:54:27.706] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9070508854762586480:61:0)
[07:54:27.706] Timer tick, asking Buildbucket for the build status
[07:54:27.869] Build 8905078792594073536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:54:27.869] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9070508854762586480:62:0) after 1m0s
[07:55:27.970] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9070508854762586480:62:0)
[07:55:27.970] Timer tick, asking Buildbucket for the build status
[07:55:28.170] Build 8905078792594073536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:55:28.170] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9070508854762586480:63:0) after 1m0s
[07:56:28.199] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9070508854762586480:63:0)
[07:56:28.199] Timer tick, asking Buildbucket for the build status
[07:56:28.415] Build 8905078792594073536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:56:28.415] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9070508854762586480:64:0) after 1m0s
[07:57:28.548] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9070508854762586480:64:0)
[07:57:28.548] Timer tick, asking Buildbucket for the build status
[07:57:29.096] Build 8905078792594073536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:57:29.096] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9070508854762586480:65:0) after 1m0s
[07:58:29.174] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9070508854762586480:65:0)
[07:58:29.174] Timer tick, asking Buildbucket for the build status
[07:58:29.340] Build 8905078792594073536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:58:29.340] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9070508854762586480:66:0) after 1m0s
[07:59:29.452] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9070508854762586480:66:0)
[07:59:29.452] Timer tick, asking Buildbucket for the build status
[07:59:29.659] Build 8905078792594073536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:59:29.659] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9070508854762586480:67:0) after 1m0s
[08:00:29.822] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9070508854762586480:67:0)
[08:00:29.823] Timer tick, asking Buildbucket for the build status
[08:00:30.482] Build 8905078792594073536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:00:30.482] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9070508854762586480:68:0) after 1m0s
[08:01:30.562] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9070508854762586480:68:0)
[08:01:30.562] Timer tick, asking Buildbucket for the build status
[08:01:30.727] Build 8905078792594073536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:01:30.727] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9070508854762586480:69:0) after 1m0s
[08:02:30.868] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9070508854762586480:69:0)
[08:02:30.868] Timer tick, asking Buildbucket for the build status
[08:02:31.131] Build 8905078792594073536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:02:31.131] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9070508854762586480:70:0) after 1m0s
[08:03:31.118] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9070508854762586480:70:0)
[08:03:31.118] Timer tick, asking Buildbucket for the build status
[08:03:31.596] Build 8905078792594073536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:03:31.596] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9070508854762586480:71:0) after 1m0s
[08:04:31.683] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9070508854762586480:71:0)
[08:04:31.683] Timer tick, asking Buildbucket for the build status
[08:04:31.934] Build 8905078792594073536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:04:31.934] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9070508854762586480:72:0) after 1m0s
[08:05:31.976] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9070508854762586480:72:0)
[08:05:31.976] Timer tick, asking Buildbucket for the build status
[08:05:32.423] Build 8905078792594073536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:05:32.423] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9070508854762586480:73:0) after 1m0s
[08:06:32.477] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9070508854762586480:73:0)
[08:06:32.477] Timer tick, asking Buildbucket for the build status
[08:06:32.677] Build 8905078792594073536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:06:32.677] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9070508854762586480:74:0) after 1m0s
[08:07:32.717] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9070508854762586480:74:0)
[08:07:32.717] Timer tick, asking Buildbucket for the build status
[08:07:33.017] Build 8905078792594073536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:07:33.017] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9070508854762586480:75:0) after 1m0s
[08:08:33.079] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9070508854762586480:75:0)
[08:08:33.079] Timer tick, asking Buildbucket for the build status
[08:08:34.443] Build 8905078792594073536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:08:34.443] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9070508854762586480:76:0) after 1m0s
[08:09:34.477] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9070508854762586480:76:0)
[08:09:34.477] Timer tick, asking Buildbucket for the build status
[08:09:34.877] Build 8905078792594073536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:09:34.877] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9070508854762586480:77:0) after 1m0s
[08:10:34.977] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9070508854762586480:77:0)
[08:10:34.978] Timer tick, asking Buildbucket for the build status
[08:10:35.294] Build 8905078792594073536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:10:35.294] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9070508854762586480:78:0) after 1m0s
[08:11:35.378] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9070508854762586480:78:0)
[08:11:35.378] Timer tick, asking Buildbucket for the build status
[08:11:36.121] Build 8905078792594073536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:11:36.121] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9070508854762586480:79:0) after 1m0s
[08:12:36.147] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9070508854762586480:79:0)
[08:12:36.147] Timer tick, asking Buildbucket for the build status
[08:12:36.463] Build 8905078792594073536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:12:36.463] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9070508854762586480:80:0) after 1m0s
[08:13:36.496] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9070508854762586480:80:0)
[08:13:36.496] Timer tick, asking Buildbucket for the build status
[08:13:36.715] Build 8905078792594073536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:13:36.716] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9070508854762586480:81:0) after 1m0s
[08:14:36.912] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9070508854762586480:81:0)
[08:14:36.912] Timer tick, asking Buildbucket for the build status
[08:14:38.274] Build 8905078792594073536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:14:38.274] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9070508854762586480:82:0) after 1m0s
[08:15:38.335] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9070508854762586480:82:0)
[08:15:38.335] Timer tick, asking Buildbucket for the build status
[08:15:40.082] Build 8905078792594073536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:15:40.082] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9070508854762586480:83:0) after 1m0s
[08:16:43.922] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9070508854762586480:83:0)
[08:16:43.922] Timer tick, asking Buildbucket for the build status
[08:16:44.124] Build 8905078792594073536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:16:44.124] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9070508854762586480:84:0) after 1m0s
[08:17:44.237] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9070508854762586480:84:0)
[08:17:44.237] Timer tick, asking Buildbucket for the build status
[08:17:45.394] Build 8905078792594073536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:17:45.394] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9070508854762586480:85:0) after 1m0s
[08:18:45.768] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9070508854762586480:85:0)
[08:18:45.768] Timer tick, asking Buildbucket for the build status
[08:18:45.983] Build 8905078792594073536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:18:45.983] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9070508854762586480:86:0) after 1m0s
[08:19:46.336] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9070508854762586480:86:0)
[08:19:46.336] Timer tick, asking Buildbucket for the build status
[08:19:46.717] Build 8905078792594073536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:19:46.717] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9070508854762586480:87:0) after 1m0s
[08:20:46.790] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9070508854762586480:87:0)
[08:20:46.790] Timer tick, asking Buildbucket for the build status
[08:20:47.020] Build 8905078792594073536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:20:47.020] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9070508854762586480:88:0) after 1m0s
[08:21:47.132] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9070508854762586480:88:0)
[08:21:47.132] Timer tick, asking Buildbucket for the build status
[08:21:47.408] Build 8905078792594073536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:21:47.408] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9070508854762586480:89:0) after 1m0s
[08:22:47.519] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9070508854762586480:89:0)
[08:22:47.519] Timer tick, asking Buildbucket for the build status
[08:22:50.151] Build 8905078792594073536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:22:50.151] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9070508854762586480:90:0) after 1m0s
[08:23:50.262] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9070508854762586480:90:0)
[08:23:50.262] Timer tick, asking Buildbucket for the build status
[08:23:50.420] Build 8905078792594073536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:23:50.420] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9070508854762586480:91:0) after 1m0s
[08:24:50.559] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9070508854762586480:91:0)
[08:24:50.559] Timer tick, asking Buildbucket for the build status
[08:24:51.020] Build 8905078792594073536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:24:51.020] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9070508854762586480:92:0) after 1m0s
[08:25:51.178] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9070508854762586480:92:0)
[08:25:51.178] Timer tick, asking Buildbucket for the build status
[08:25:51.465] Build 8905078792594073536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:25:51.465] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9070508854762586480:93:0) after 1m0s
[08:26:51.598] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9070508854762586480:93:0)
[08:26:51.598] Timer tick, asking Buildbucket for the build status
[08:26:52.133] Build 8905078792594073536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:26:52.133] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9070508854762586480:94:0) after 1m0s
[08:27:52.364] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9070508854762586480:94:0)
[08:27:52.364] Timer tick, asking Buildbucket for the build status
[08:27:52.833] Build 8905078792594073536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:27:52.834] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9070508854762586480:95:0) after 1m0s
[08:28:53.848] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9070508854762586480:95:0)
[08:28:53.848] Timer tick, asking Buildbucket for the build status
[08:28:54.089] Build 8905078792594073536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:28:54.089] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9070508854762586480:96:0) after 1m0s
[08:29:54.701] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9070508854762586480:96:0)
[08:29:54.701] Timer tick, asking Buildbucket for the build status
[08:29:54.884] Build 8905078792594073536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:29:54.884] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9070508854762586480:97:0) after 1m0s
[08:30:55.172] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9070508854762586480:97:0)
[08:30:55.172] Timer tick, asking Buildbucket for the build status
[08:30:55.792] Build 8905078792594073536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:30:55.792] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9070508854762586480:98:0) after 1m0s
[08:31:55.888] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9070508854762586480:98:0)
[08:31:55.888] Timer tick, asking Buildbucket for the build status
[08:31:56.912] Build 8905078792594073536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:31:56.912] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9070508854762586480:99:0) after 1m0s
[08:32:57.259] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9070508854762586480:99:0)
[08:32:57.259] Timer tick, asking Buildbucket for the build status
[08:32:58.704] Build 8905078792594073536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:32:58.704] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9070508854762586480:100:0) after 1m0s
[08:33:58.782] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9070508854762586480:100:0)
[08:33:58.782] Timer tick, asking Buildbucket for the build status
[08:33:59.046] Build 8905078792594073536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:33:59.046] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9070508854762586480:101:0) after 1m0s
[08:34:59.164] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9070508854762586480:101:0)
[08:34:59.164] Timer tick, asking Buildbucket for the build status
[08:34:59.291] Build 8905078792594073536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:34:59.291] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9070508854762586480:102:0) after 1m0s
[08:35:59.528] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9070508854762586480:102:0)
[08:35:59.528] Timer tick, asking Buildbucket for the build status
[08:35:59.885] Build 8905078792594073536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:35:59.885] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9070508854762586480:103:0) after 1m0s
[08:36:59.902] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9070508854762586480:103:0)
[08:36:59.902] Timer tick, asking Buildbucket for the build status
[08:37:00.065] Build 8905078792594073536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:37:00.065] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9070508854762586480:104:0) after 1m0s
[08:38:00.163] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9070508854762586480:104:0)
[08:38:00.163] Timer tick, asking Buildbucket for the build status
[08:38:00.576] Build 8905078792594073536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:38:00.576] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9070508854762586480:105:0) after 1m0s
[08:39:00.716] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9070508854762586480:105:0)
[08:39:00.716] Timer tick, asking Buildbucket for the build status
[08:39:01.305] Build 8905078792594073536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:39:01.305] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9070508854762586480:106:0) after 1m0s
[08:40:01.567] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9070508854762586480:106:0)
[08:40:01.567] Timer tick, asking Buildbucket for the build status
[08:40:02.009] Build 8905078792594073536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:40:02.010] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9070508854762586480:107:0) after 1m0s
[08:41:02.105] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9070508854762586480:107:0)
[08:41:02.105] Timer tick, asking Buildbucket for the build status
[08:41:02.984] Build 8905078792594073536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:41:02.984] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9070508854762586480:108:0) after 1m0s
[08:42:03.154] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9070508854762586480:108:0)
[08:42:03.154] Timer tick, asking Buildbucket for the build status
[08:42:03.563] Build 8905078792594073536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:42:03.563] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9070508854762586480:109:0) after 1m0s
[08:43:03.633] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9070508854762586480:109:0)
[08:43:03.633] Timer tick, asking Buildbucket for the build status
[08:43:04.682] Build 8905078792594073536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:43:04.682] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9070508854762586480:110:0) after 1m0s
[08:43:08.798] Received PubSub notification, asking Buildbucket for the build status
[08:43:09.089] Build 8905078792594073536: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[08:43:09.089] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1565858586701291",
"created_by": "project:chromium",
"created_ts": "1565852092137485",
"failure_reason": "BUILD_FAILURE",
"id": "8905078792594073536",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"2df6cf8306a859c7d8ae9ebbdf9beb056e4bd054\"}}",
"project": "chromium",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"```\\n[83702/83709] LINK browser_tests.exe browser_tests.exe.pdb\\nFAILED: browser_tests.exe browser_tests.exe.pdb\\nninja -t msvc -e environment.x86 -- ../../third_party/llvm-build/Release+Asserts/bin/lld-link.exe /nologo /OUT:./browser_tests.exe /PDB:./browser_tests.exe.pdb @./browser_tests.exe.rsp\\nlld-link: error: could not get the buffer for the member defining symbol public: void __thiscall base::MetadataRecorder::Set(unsigned __int64, __int64): obj/base/base.lib(obj/base\\\\base/metadata_recorder.obj): Insufficient system resources exist to complete the requested service.\\n\\n\\n```\"}, \"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-q2vi\", \"branch\": \"refs/heads/master\", \"buildername\": \"Windows deterministic\", \"buildnumber\": 14427, \"got_angle_revision\": \"3ea90d609720b7b9b9d05ca094860382f2425294\", \"got_dawn_revision\": \"5f8a8aadb96b5ac0302836de219943178f240c20\", \"got_nacl_revision\": \"058a26f57015051953a727add26e7e26348b9558\", \"got_revision\": \"2df6cf8306a859c7d8ae9ebbdf9beb056e4bd054\", \"got_revision_cp\": \"refs/heads/master@{#687182}\", \"got_swarming_client_revision\": \"96f125709acfd0b48fc1e5dae7d6ea42291726ac\", \"got_v8_revision\": \"f46449fa2ba524d041e0977c4c718ebe6df9d435\", \"got_v8_revision_cp\": \"refs/heads/7.8.110@{#1}\", \"got_webrtc_revision\": \"7c6f74ab0344e9c6201de711d54026e9990b8e6c\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#28844}\", \"mastername\": \"chromium.win\", \"path_config\": \"generic\", \"recipe\": \"swarming/deterministic_build\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"2df6cf8306a859c7d8ae9ebbdf9beb056e4bd054\"}, \"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-q2vi\"], \"image\": [\"chrome-win10-1703-19072400-fedda89f1f1\"], \"integrity\": [\"high\"], \"locale\": [\"en_US.cp1252\"], \"machine_type\": [\"n1-standard-8\"], \"os\": [\"Windows\", \"Windows-10\", \"Windows-10-15063\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.13\"], \"server_version\": [\"4397-8786906\"], \"ssd\": [\"0\"], \"windows_client_version\": [\"10\"], \"zone\": [\"us\", \"us-west\", \"us-west1\", \"us-west1-c\"]}}, \"ui\": {\"info\": \"```\\n[83702/83709] LINK browser_tests.exe browser_tests.exe.pdb\\nFAILED: browser_tests.exe browser_tests.exe.pdb\\nninja -t msvc -e environment.x86 -- ../../third_party/llvm-build/Release+Asserts/bin/lld-link.exe /nologo /OUT:./browser_tests.exe /PDB:./browser_tests.exe.pdb @./browser_tests.exe.rsp\\nlld-link: error: could not get the buffer for the member defining symbol public: void __thiscall base::MetadataRecorder::Set(unsigned __int64, __int64): obj/base/base.lib(obj/base\\\\base/metadata_recorder.obj): Insufficient system resources exist to complete the requested service.\\n\\n\\n```\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1565852844126474",
"status": "COMPLETED",
"status_changed_ts": "1565852845018980",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/14427",
"builder:Windows deterministic",
"buildset:commit/git/2df6cf8306a859c7d8ae9ebbdf9beb056e4bd054",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/2df6cf8306a859c7d8ae9ebbdf9beb056e4bd054",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9070508854762586480",
"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/8905078792594073536/+/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:46ace051b8f66d10",
"user_agent:luci-scheduler"
],
"updated_ts": "1565858587278257",
"url": "https://ci.chromium.org/b/8905078792594073536",
"utcnow_ts": "1565858589072825"
}
}
[08:43:09.089] Invocation finished in 1h48m18.404470249s with status FAILED