[19:33:58.847] New invocation is queued and will start shortly
[19:34:00.816] Starting the invocation (attempt 1)
[19:34:00.898] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[19:34:00.898] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[19:34:00.898] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9052613491692077552",
"parameters_json": "{\"builder_name\":\"Windows deterministic\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"4ea5f928dcf9ea8f716609ff0613004a87e726a8\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Windows deterministic",
"scheduler_invocation_id:9052613491692077552",
"scheduler_job_id:chromium/Windows deterministic",
"user_agent:luci-scheduler",
"buildset:commit/git/4ea5f928dcf9ea8f716609ff0613004a87e726a8",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/4ea5f928dcf9ea8f716609ff0613004a87e726a8",
"gitiles_ref:refs/heads/master"
]
}
[19:34:01.542] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1582918441002117",
"id": "8887183428765825792",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"4ea5f928dcf9ea8f716609ff0613004a87e726a8\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1582918441392247",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/15953",
"builder:Windows deterministic",
"buildset:commit/git/4ea5f928dcf9ea8f716609ff0613004a87e726a8",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/4ea5f928dcf9ea8f716609ff0613004a87e726a8",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9052613491692077552",
"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/8887183428765825792/+/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": "1582918441392378",
"url": "https://ci.chromium.org/b/8887183428765825792",
"utcnow_ts": "1582918441524235"
}
}
[19:34:01.542] Task URL: https://ci.chromium.org/b/8887183428765825792
[19:34:01.542] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:2:0) after 1m0s
[19:35:01.657] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:2:0)
[19:35:01.657] Timer tick, asking Buildbucket for the build status
[19:35:01.979] Build 8887183428765825792: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:35:01.979] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:3:0) after 1m0s
[19:36:02.076] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:3:0)
[19:36:02.076] Timer tick, asking Buildbucket for the build status
[19:36:02.263] Build 8887183428765825792: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:36:02.263] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:4:0) after 1m0s
[19:37:02.272] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:4:0)
[19:37:02.272] Timer tick, asking Buildbucket for the build status
[19:37:02.544] Build 8887183428765825792: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:37:02.544] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:5:0) after 1m0s
[19:38:02.977] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:5:0)
[19:38:02.977] Timer tick, asking Buildbucket for the build status
[19:38:03.146] Build 8887183428765825792: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:38:03.146] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:6:0) after 1m0s
[19:39:03.419] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:6:0)
[19:39:03.419] Timer tick, asking Buildbucket for the build status
[19:39:03.596] Build 8887183428765825792: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:39:03.596] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:7:0) after 1m0s
[19:40:03.732] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:7:0)
[19:40:03.732] Timer tick, asking Buildbucket for the build status
[19:40:03.889] Build 8887183428765825792: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:40:03.889] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:8:0) after 1m0s
[19:41:04.040] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:8:0)
[19:41:04.040] Timer tick, asking Buildbucket for the build status
[19:41:09.662] Build 8887183428765825792: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:41:09.662] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:9:0) after 1m0s
[19:42:09.709] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:9:0)
[19:42:09.709] Timer tick, asking Buildbucket for the build status
[19:42:10.302] Build 8887183428765825792: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:42:10.302] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:10:0) after 1m0s
[19:43:10.407] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:10:0)
[19:43:10.407] Timer tick, asking Buildbucket for the build status
[19:43:12.122] Build 8887183428765825792: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:43:12.122] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:11:0) after 1m0s
[19:44:12.235] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:11:0)
[19:44:12.235] Timer tick, asking Buildbucket for the build status
[19:44:12.562] Build 8887183428765825792: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:44:12.562] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:12:0) after 1m0s
[19:45:12.647] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:12:0)
[19:45:12.647] Timer tick, asking Buildbucket for the build status
[19:45:13.108] Build 8887183428765825792: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:45:13.108] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:13:0) after 1m0s
[19:46:13.149] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:13:0)
[19:46:13.149] Timer tick, asking Buildbucket for the build status
[19:46:15.182] Build 8887183428765825792: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:46:15.182] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:14:0) after 1m0s
[19:47:15.298] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:14:0)
[19:47:15.298] Timer tick, asking Buildbucket for the build status
[19:47:15.409] Build 8887183428765825792: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:47:15.409] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:15:0) after 1m0s
[19:48:15.543] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:15:0)
[19:48:15.543] Timer tick, asking Buildbucket for the build status
[19:48:15.785] Build 8887183428765825792: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:48:15.785] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:16:0) after 1m0s
[19:49:15.816] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:16:0)
[19:49:15.816] Timer tick, asking Buildbucket for the build status
[19:49:16.743] Build 8887183428765825792: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:49:16.743] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:17:0) after 1m0s
[19:50:16.886] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:17:0)
[19:50:16.886] Timer tick, asking Buildbucket for the build status
[19:50:17.868] Build 8887183428765825792: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:50:17.868] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:18:0) after 1m0s
[19:51:17.963] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:18:0)
[19:51:17.963] Timer tick, asking Buildbucket for the build status
[19:51:18.219] Build 8887183428765825792: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:51:18.219] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:19:0) after 1m0s
[19:52:18.254] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:19:0)
[19:52:18.254] Timer tick, asking Buildbucket for the build status
[19:52:18.469] Build 8887183428765825792: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:52:18.469] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:20:0) after 1m0s
[19:53:18.497] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:20:0)
[19:53:18.497] Timer tick, asking Buildbucket for the build status
[19:53:19.482] Build 8887183428765825792: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:53:19.482] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:21:0) after 1m0s
[19:54:19.551] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:21:0)
[19:54:19.551] Timer tick, asking Buildbucket for the build status
[19:54:21.082] Build 8887183428765825792: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:54:21.082] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:22:0) after 1m0s
[19:55:21.122] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:22:0)
[19:55:21.122] Timer tick, asking Buildbucket for the build status
[19:55:21.489] Build 8887183428765825792: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:55:21.489] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:23:0) after 1m0s
[19:56:21.525] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:23:0)
[19:56:21.525] Timer tick, asking Buildbucket for the build status
[19:56:22.130] Build 8887183428765825792: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:56:22.130] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:24:0) after 1m0s
[19:57:22.254] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:24:0)
[19:57:22.254] Timer tick, asking Buildbucket for the build status
[19:57:22.417] Build 8887183428765825792: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:57:22.417] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:25:0) after 1m0s
[19:58:22.501] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:25:0)
[19:58:22.501] Timer tick, asking Buildbucket for the build status
[19:58:23.215] Build 8887183428765825792: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:58:23.215] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:26:0) after 1m0s
[19:59:23.217] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:26:0)
[19:59:23.217] Timer tick, asking Buildbucket for the build status
[19:59:23.403] Build 8887183428765825792: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:59:23.403] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:27:0) after 1m0s
[20:00:23.432] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:27:0)
[20:00:23.432] Timer tick, asking Buildbucket for the build status
[20:00:23.584] Build 8887183428765825792: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:00:23.584] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:28:0) after 1m0s
[20:01:04.428] Received PubSub notification, asking Buildbucket for the build status
[20:01:04.997] Build 8887183428765825792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:01:23.646] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:28:0)
[20:01:23.646] Timer tick, asking Buildbucket for the build status
[20:01:26.203] Build 8887183428765825792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:01:26.203] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:30:0) after 1m0s
[20:02:26.239] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:30:0)
[20:02:26.239] Timer tick, asking Buildbucket for the build status
[20:02:26.497] Build 8887183428765825792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:02:26.497] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:31:0) after 1m0s
[20:03:26.551] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:31:0)
[20:03:26.551] Timer tick, asking Buildbucket for the build status
[20:03:27.200] Build 8887183428765825792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:03:27.200] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:32:0) after 1m0s
[20:04:27.232] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:32:0)
[20:04:27.232] Timer tick, asking Buildbucket for the build status
[20:04:28.018] Build 8887183428765825792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:04:28.018] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:33:0) after 1m0s
[20:05:28.113] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:33:0)
[20:05:28.113] Timer tick, asking Buildbucket for the build status
[20:05:28.298] Build 8887183428765825792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:05:28.298] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:34:0) after 1m0s
[20:06:28.355] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:34:0)
[20:06:28.355] Timer tick, asking Buildbucket for the build status
[20:06:29.185] Build 8887183428765825792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:06:29.185] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:35:0) after 1m0s
[20:07:29.218] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:35:0)
[20:07:29.218] Timer tick, asking Buildbucket for the build status
[20:07:29.627] Build 8887183428765825792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:07:29.627] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:36:0) after 1m0s
[20:08:29.658] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:36:0)
[20:08:29.658] Timer tick, asking Buildbucket for the build status
[20:08:30.673] Build 8887183428765825792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:08:30.673] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:37:0) after 1m0s
[20:09:30.761] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:37:0)
[20:09:30.761] Timer tick, asking Buildbucket for the build status
[20:09:31.316] Build 8887183428765825792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:09:31.316] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:38:0) after 1m0s
[20:10:31.354] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:38:0)
[20:10:31.355] Timer tick, asking Buildbucket for the build status
[20:10:31.488] Build 8887183428765825792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:10:31.488] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:39:0) after 1m0s
[20:11:31.557] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:39:0)
[20:11:31.557] Timer tick, asking Buildbucket for the build status
[20:11:31.799] Build 8887183428765825792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:11:31.799] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:40:0) after 1m0s
[20:12:31.856] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:40:0)
[20:12:31.856] Timer tick, asking Buildbucket for the build status
[20:12:32.129] Build 8887183428765825792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:12:32.129] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:41:0) after 1m0s
[20:13:32.156] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:41:0)
[20:13:32.156] Timer tick, asking Buildbucket for the build status
[20:13:33.724] Build 8887183428765825792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:13:33.724] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:42:0) after 1m0s
[20:14:33.846] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:42:0)
[20:14:33.846] Timer tick, asking Buildbucket for the build status
[20:14:34.333] Build 8887183428765825792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:14:34.333] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:43:0) after 1m0s
[20:15:34.387] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:43:0)
[20:15:34.387] Timer tick, asking Buildbucket for the build status
[20:15:34.507] Build 8887183428765825792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:15:34.507] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:44:0) after 1m0s
[20:16:34.614] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:44:0)
[20:16:34.614] Timer tick, asking Buildbucket for the build status
[20:16:34.857] Build 8887183428765825792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:16:34.857] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:45:0) after 1m0s
[20:17:34.905] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:45:0)
[20:17:34.905] Timer tick, asking Buildbucket for the build status
[20:17:35.042] Build 8887183428765825792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:17:35.042] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:46:0) after 1m0s
[20:18:35.068] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:46:0)
[20:18:35.068] Timer tick, asking Buildbucket for the build status
[20:18:36.483] Build 8887183428765825792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:18:36.483] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:47:0) after 1m0s
[20:19:36.662] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:47:0)
[20:19:36.662] Timer tick, asking Buildbucket for the build status
[20:19:36.811] Build 8887183428765825792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:19:36.812] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:48:0) after 1m0s
[20:20:36.973] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:48:0)
[20:20:36.973] Timer tick, asking Buildbucket for the build status
[20:20:37.115] Build 8887183428765825792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:20:37.115] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:49:0) after 1m0s
[20:21:37.273] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:49:0)
[20:21:37.273] Timer tick, asking Buildbucket for the build status
[20:21:37.446] Build 8887183428765825792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:21:37.446] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:50:0) after 1m0s
[20:22:37.497] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:50:0)
[20:22:37.497] Timer tick, asking Buildbucket for the build status
[20:22:37.778] Build 8887183428765825792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:22:37.778] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:51:0) after 1m0s
[20:23:37.876] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:51:0)
[20:23:37.876] Timer tick, asking Buildbucket for the build status
[20:23:38.213] Build 8887183428765825792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:23:38.213] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:52:0) after 1m0s
[20:24:38.274] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:52:0)
[20:24:38.274] Timer tick, asking Buildbucket for the build status
[20:24:38.458] Build 8887183428765825792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:24:38.458] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:53:0) after 1m0s
[20:25:38.543] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:53:0)
[20:25:38.543] Timer tick, asking Buildbucket for the build status
[20:25:38.722] Build 8887183428765825792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:25:38.722] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:54:0) after 1m0s
[20:26:38.727] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:54:0)
[20:26:38.727] Timer tick, asking Buildbucket for the build status
[20:26:39.143] Build 8887183428765825792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:26:39.143] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:55:0) after 1m0s
[20:27:39.172] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:55:0)
[20:27:39.172] Timer tick, asking Buildbucket for the build status
[20:27:39.424] Build 8887183428765825792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:27:39.424] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:56:0) after 1m0s
[20:28:39.462] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:56:0)
[20:28:39.462] Timer tick, asking Buildbucket for the build status
[20:28:40.877] Build 8887183428765825792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:28:40.877] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:57:0) after 1m0s
[20:29:40.902] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:57:0)
[20:29:40.902] Timer tick, asking Buildbucket for the build status
[20:29:41.088] Build 8887183428765825792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:29:41.088] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:58:0) after 1m0s
[20:30:41.172] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:58:0)
[20:30:41.172] Timer tick, asking Buildbucket for the build status
[20:30:42.091] Build 8887183428765825792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:30:42.091] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:59:0) after 1m0s
[20:31:42.186] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:59:0)
[20:31:42.186] Timer tick, asking Buildbucket for the build status
[20:31:42.597] Build 8887183428765825792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:31:42.597] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:60:0) after 1m0s
[20:32:42.727] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:60:0)
[20:32:42.727] Timer tick, asking Buildbucket for the build status
[20:32:42.921] Build 8887183428765825792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:32:42.921] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:61:0) after 1m0s
[20:33:42.945] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:61:0)
[20:33:42.945] Timer tick, asking Buildbucket for the build status
[20:33:43.559] Build 8887183428765825792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:33:43.559] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:62:0) after 1m0s
[20:34:43.587] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:62:0)
[20:34:43.587] Timer tick, asking Buildbucket for the build status
[20:34:43.881] Build 8887183428765825792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:34:43.881] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:63:0) after 1m0s
[20:35:43.913] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:63:0)
[20:35:43.913] Timer tick, asking Buildbucket for the build status
[20:35:44.383] Build 8887183428765825792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:35:44.383] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:64:0) after 1m0s
[20:36:44.452] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:64:0)
[20:36:44.452] Timer tick, asking Buildbucket for the build status
[20:36:44.592] Build 8887183428765825792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:36:44.592] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:65:0) after 1m0s
[20:37:44.622] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:65:0)
[20:37:44.622] Timer tick, asking Buildbucket for the build status
[20:37:44.753] Build 8887183428765825792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:37:44.753] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:66:0) after 1m0s
[20:38:44.811] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:66:0)
[20:38:44.811] Timer tick, asking Buildbucket for the build status
[20:38:45.142] Build 8887183428765825792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:38:45.142] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:67:0) after 1m0s
[20:39:45.459] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:67:0)
[20:39:45.459] Timer tick, asking Buildbucket for the build status
[20:39:45.939] Build 8887183428765825792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:39:45.939] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:68:0) after 1m0s
[20:40:46.259] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:68:0)
[20:40:46.259] Timer tick, asking Buildbucket for the build status
[20:40:46.399] Build 8887183428765825792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:40:46.399] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:69:0) after 1m0s
[20:41:46.551] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:69:0)
[20:41:46.551] Timer tick, asking Buildbucket for the build status
[20:41:46.841] Build 8887183428765825792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:41:46.841] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:70:0) after 1m0s
[20:42:46.881] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:70:0)
[20:42:46.881] Timer tick, asking Buildbucket for the build status
[20:42:46.985] Build 8887183428765825792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:42:46.985] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:71:0) after 1m0s
[20:43:47.218] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:71:0)
[20:43:47.218] Timer tick, asking Buildbucket for the build status
[20:43:47.454] Build 8887183428765825792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:43:47.454] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:72:0) after 1m0s
[20:44:47.447] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:72:0)
[20:44:47.447] Timer tick, asking Buildbucket for the build status
[20:44:47.651] Build 8887183428765825792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:44:47.651] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:73:0) after 1m0s
[20:45:47.874] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:73:0)
[20:45:47.874] Timer tick, asking Buildbucket for the build status
[20:45:48.553] Build 8887183428765825792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:45:48.553] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:74:0) after 1m0s
[20:46:48.580] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:74:0)
[20:46:48.580] Timer tick, asking Buildbucket for the build status
[20:46:49.044] Build 8887183428765825792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:46:49.044] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:75:0) after 1m0s
[20:47:49.078] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:75:0)
[20:47:49.078] Timer tick, asking Buildbucket for the build status
[20:47:49.295] Build 8887183428765825792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:47:49.295] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:76:0) after 1m0s
[20:48:49.314] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:76:0)
[20:48:49.314] Timer tick, asking Buildbucket for the build status
[20:48:50.292] Build 8887183428765825792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:48:50.292] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:77:0) after 1m0s
[20:49:50.328] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:77:0)
[20:49:50.328] Timer tick, asking Buildbucket for the build status
[20:49:50.481] Build 8887183428765825792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:49:50.481] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:78:0) after 1m0s
[20:50:50.572] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:78:0)
[20:50:50.572] Timer tick, asking Buildbucket for the build status
[20:50:51.431] Build 8887183428765825792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:50:51.431] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:79:0) after 1m0s
[20:51:51.479] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:79:0)
[20:51:51.479] Timer tick, asking Buildbucket for the build status
[20:51:51.667] Build 8887183428765825792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:51:51.667] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:80:0) after 1m0s
[20:52:51.759] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:80:0)
[20:52:51.759] Timer tick, asking Buildbucket for the build status
[20:52:51.909] Build 8887183428765825792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:52:51.909] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:81:0) after 1m0s
[20:53:52.060] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:81:0)
[20:53:52.060] Timer tick, asking Buildbucket for the build status
[20:53:52.288] Build 8887183428765825792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:53:52.288] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:82:0) after 1m0s
[20:54:52.341] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:82:0)
[20:54:52.341] Timer tick, asking Buildbucket for the build status
[20:54:53.056] Build 8887183428765825792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:54:53.056] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:83:0) after 1m0s
[20:55:53.136] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:83:0)
[20:55:53.136] Timer tick, asking Buildbucket for the build status
[20:55:53.314] Build 8887183428765825792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:55:53.314] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:84:0) after 1m0s
[20:56:53.365] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:84:0)
[20:56:53.365] Timer tick, asking Buildbucket for the build status
[20:56:53.918] Build 8887183428765825792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:56:53.918] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:85:0) after 1m0s
[20:57:53.957] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:85:0)
[20:57:53.957] Timer tick, asking Buildbucket for the build status
[20:57:54.289] Build 8887183428765825792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:57:54.289] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:86:0) after 1m0s
[20:58:54.352] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:86:0)
[20:58:54.352] Timer tick, asking Buildbucket for the build status
[20:58:54.712] Build 8887183428765825792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:58:54.712] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:87:0) after 1m0s
[20:59:54.775] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:87:0)
[20:59:54.775] Timer tick, asking Buildbucket for the build status
[20:59:54.930] Build 8887183428765825792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:59:54.930] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:88:0) after 1m0s
[21:00:55.160] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:88:0)
[21:00:55.160] Timer tick, asking Buildbucket for the build status
[21:00:55.315] Build 8887183428765825792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:00:55.315] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:89:0) after 1m0s
[21:01:55.500] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:89:0)
[21:01:55.500] Timer tick, asking Buildbucket for the build status
[21:01:56.371] Build 8887183428765825792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:01:56.371] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:90:0) after 1m0s
[21:02:56.464] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:90:0)
[21:02:56.464] Timer tick, asking Buildbucket for the build status
[21:02:56.622] Build 8887183428765825792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:02:56.622] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:91:0) after 1m0s
[21:03:56.842] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:91:0)
[21:03:56.842] Timer tick, asking Buildbucket for the build status
[21:03:56.994] Build 8887183428765825792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:03:56.994] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:92:0) after 1m0s
[21:04:57.108] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:92:0)
[21:04:57.108] Timer tick, asking Buildbucket for the build status
[21:04:57.287] Build 8887183428765825792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:04:57.287] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:93:0) after 1m0s
[21:05:57.478] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:93:0)
[21:05:57.478] Timer tick, asking Buildbucket for the build status
[21:05:57.623] Build 8887183428765825792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:05:57.623] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:94:0) after 1m0s
[21:06:57.638] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:94:0)
[21:06:57.638] Timer tick, asking Buildbucket for the build status
[21:06:58.001] Build 8887183428765825792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:06:58.001] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:95:0) after 1m0s
[21:07:58.071] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:95:0)
[21:07:58.071] Timer tick, asking Buildbucket for the build status
[21:07:58.414] Build 8887183428765825792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:07:58.414] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:96:0) after 1m0s
[21:08:58.476] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:96:0)
[21:08:58.476] Timer tick, asking Buildbucket for the build status
[21:08:58.642] Build 8887183428765825792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:08:58.642] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:97:0) after 1m0s
[21:09:58.835] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:97:0)
[21:09:58.835] Timer tick, asking Buildbucket for the build status
[21:09:58.928] Build 8887183428765825792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:09:58.928] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:98:0) after 1m0s
[21:10:59.098] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:98:0)
[21:10:59.098] Timer tick, asking Buildbucket for the build status
[21:10:59.229] Build 8887183428765825792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:10:59.229] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:99:0) after 1m0s
[21:11:59.280] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:99:0)
[21:11:59.280] Timer tick, asking Buildbucket for the build status
[21:11:59.855] Build 8887183428765825792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:11:59.855] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:100:0) after 1m0s
[21:12:59.921] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:100:0)
[21:12:59.921] Timer tick, asking Buildbucket for the build status
[21:13:00.094] Build 8887183428765825792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:13:00.094] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:101:0) after 1m0s
[21:14:00.265] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:101:0)
[21:14:00.265] Timer tick, asking Buildbucket for the build status
[21:14:00.641] Build 8887183428765825792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:14:00.641] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:102:0) after 1m0s
[21:15:00.654] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:102:0)
[21:15:00.654] Timer tick, asking Buildbucket for the build status
[21:15:00.881] Build 8887183428765825792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:15:00.881] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:103:0) after 1m0s
[21:16:00.937] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:103:0)
[21:16:00.937] Timer tick, asking Buildbucket for the build status
[21:16:01.210] Build 8887183428765825792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:16:01.210] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:104:0) after 1m0s
[21:17:01.303] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:104:0)
[21:17:01.303] Timer tick, asking Buildbucket for the build status
[21:17:01.483] Build 8887183428765825792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:17:01.483] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:105:0) after 1m0s
[21:18:01.495] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:105:0)
[21:18:01.495] Timer tick, asking Buildbucket for the build status
[21:18:02.280] Build 8887183428765825792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:18:02.280] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:106:0) after 1m0s
[21:19:02.287] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:106:0)
[21:19:02.287] Timer tick, asking Buildbucket for the build status
[21:19:02.658] Build 8887183428765825792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:19:02.658] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:107:0) after 1m0s
[21:20:02.739] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:107:0)
[21:20:02.739] Timer tick, asking Buildbucket for the build status
[21:20:03.070] Build 8887183428765825792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:20:03.070] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:108:0) after 1m0s
[21:21:03.161] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:108:0)
[21:21:03.161] Timer tick, asking Buildbucket for the build status
[21:21:03.347] Build 8887183428765825792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:21:03.347] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:109:0) after 1m0s
[21:22:03.458] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:109:0)
[21:22:03.458] Timer tick, asking Buildbucket for the build status
[21:22:03.583] Build 8887183428765825792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:22:03.583] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:110:0) after 1m0s
[21:23:03.645] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:110:0)
[21:23:03.645] Timer tick, asking Buildbucket for the build status
[21:23:03.883] Build 8887183428765825792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:23:03.883] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:111:0) after 1m0s
[21:24:03.959] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:111:0)
[21:24:03.959] Timer tick, asking Buildbucket for the build status
[21:24:04.258] Build 8887183428765825792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:24:04.258] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:112:0) after 1m0s
[21:25:04.444] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:112:0)
[21:25:04.444] Timer tick, asking Buildbucket for the build status
[21:25:04.598] Build 8887183428765825792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:25:04.598] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:113:0) after 1m0s
[21:26:04.774] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:113:0)
[21:26:04.774] Timer tick, asking Buildbucket for the build status
[21:26:04.959] Build 8887183428765825792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:26:04.959] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:114:0) after 1m0s
[21:27:04.956] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:114:0)
[21:27:04.956] Timer tick, asking Buildbucket for the build status
[21:27:05.185] Build 8887183428765825792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:27:05.185] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:115:0) after 1m0s
[21:28:05.383] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:115:0)
[21:28:05.383] Timer tick, asking Buildbucket for the build status
[21:28:05.482] Build 8887183428765825792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:28:05.482] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:116:0) after 1m0s
[21:29:05.551] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:116:0)
[21:29:05.551] Timer tick, asking Buildbucket for the build status
[21:29:05.901] Build 8887183428765825792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:29:05.901] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:117:0) after 1m0s
[21:30:05.935] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:117:0)
[21:30:05.935] Timer tick, asking Buildbucket for the build status
[21:30:06.077] Build 8887183428765825792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:30:06.077] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:118:0) after 1m0s
[21:31:06.163] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:118:0)
[21:31:06.163] Timer tick, asking Buildbucket for the build status
[21:31:06.351] Build 8887183428765825792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:31:06.351] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:119:0) after 1m0s
[21:32:06.388] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:119:0)
[21:32:06.388] Timer tick, asking Buildbucket for the build status
[21:32:06.551] Build 8887183428765825792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:32:06.551] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:120:0) after 1m0s
[21:33:06.668] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:120:0)
[21:33:06.668] Timer tick, asking Buildbucket for the build status
[21:33:06.800] Build 8887183428765825792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:33:06.800] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:121:0) after 1m0s
[21:34:06.915] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:121:0)
[21:34:06.916] Timer tick, asking Buildbucket for the build status
[21:34:07.446] Build 8887183428765825792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:34:07.446] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:122:0) after 1m0s
[21:35:07.629] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:122:0)
[21:35:07.629] Timer tick, asking Buildbucket for the build status
[21:35:07.790] Build 8887183428765825792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:35:07.790] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:123:0) after 1m0s
[21:36:07.946] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:123:0)
[21:36:07.946] Timer tick, asking Buildbucket for the build status
[21:36:08.098] Build 8887183428765825792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:36:08.098] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:124:0) after 1m0s
[21:37:08.283] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:124:0)
[21:37:08.283] Timer tick, asking Buildbucket for the build status
[21:37:08.559] Build 8887183428765825792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:37:08.559] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:125:0) after 1m0s
[21:38:08.742] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:125:0)
[21:38:08.742] Timer tick, asking Buildbucket for the build status
[21:38:08.909] Build 8887183428765825792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:38:08.909] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:126:0) after 1m0s
[21:39:09.039] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:126:0)
[21:39:09.039] Timer tick, asking Buildbucket for the build status
[21:39:09.139] Build 8887183428765825792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:39:09.139] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:127:0) after 1m0s
[21:40:09.331] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:127:0)
[21:40:09.331] Timer tick, asking Buildbucket for the build status
[21:40:09.607] Build 8887183428765825792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:40:09.607] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:128:0) after 1m0s
[21:41:09.733] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:128:0)
[21:41:09.733] Timer tick, asking Buildbucket for the build status
[21:41:09.927] Build 8887183428765825792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:41:09.927] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:129:0) after 1m0s
[21:42:10.018] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:129:0)
[21:42:10.018] Timer tick, asking Buildbucket for the build status
[21:42:10.207] Build 8887183428765825792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:42:10.207] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:130:0) after 1m0s
[21:43:10.257] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:130:0)
[21:43:10.257] Timer tick, asking Buildbucket for the build status
[21:43:10.386] Build 8887183428765825792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:43:10.386] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:131:0) after 1m0s
[21:44:10.433] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:131:0)
[21:44:10.433] Timer tick, asking Buildbucket for the build status
[21:44:10.699] Build 8887183428765825792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:44:10.699] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:132:0) after 1m0s
[21:45:10.722] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:132:0)
[21:45:10.722] Timer tick, asking Buildbucket for the build status
[21:45:10.957] Build 8887183428765825792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:45:10.957] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:133:0) after 1m0s
[21:46:11.016] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:133:0)
[21:46:11.016] Timer tick, asking Buildbucket for the build status
[21:46:11.685] Build 8887183428765825792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:46:11.685] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:134:0) after 1m0s
[21:47:11.726] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:134:0)
[21:47:11.726] Timer tick, asking Buildbucket for the build status
[21:47:11.921] Build 8887183428765825792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:47:11.921] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:135:0) after 1m0s
[21:48:12.027] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:135:0)
[21:48:12.027] Timer tick, asking Buildbucket for the build status
[21:48:12.352] Build 8887183428765825792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:48:12.352] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9052613491692077552:136:0) after 1m0s
[21:48:27.807] Received PubSub notification, asking Buildbucket for the build status
[21:48:28.022] Build 8887183428765825792: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[21:48:28.022] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1582926507485017",
"created_by": "project:chromium",
"created_ts": "1582918441002117",
"failure_reason": "BUILD_FAILURE",
"id": "8887183428765825792",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"4ea5f928dcf9ea8f716609ff0613004a87e726a8\"}}",
"project": "chromium",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"#### Step _First build_ failed. Error logs are shown below:\\n```\\nFailing build because ninja reported work to do.\\nThis means that after completing a compile, another was run and\\nit resulted in still having work to do (that is, a no-op build\\nwasn't a no-op). Consult the first \\\"ninja explain:\\\" line for a\\nlikely culprit.\\n```\\n#### More information in raw_io.output[failure_summary]\"}, \"properties\": {\"$build/goma\": {\"enable_ats\": true, \"rpc_extra_params\": \"?prod\", \"server_host\": \"goma.chromium.org\"}, \"$recipe_engine/path\": {\"cache_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\cache\", \"temp_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\tmp\\\\rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"bot_id\": \"windows-deterministic-0-vu3c\", \"branch\": \"refs/heads/master\", \"buildername\": \"Windows deterministic\", \"buildnumber\": 15953, \"got_angle_revision\": \"fb551728d77bd2fca79d2616edf3e8a064be2352\", \"got_dawn_revision\": \"e299afa9ec114c4a1b94c7c47b8e60271a807fd5\", \"got_nacl_revision\": \"35bcc9f6d5e81c3c39ad57de97ef9cd22301ee9e\", \"got_revision\": \"4ea5f928dcf9ea8f716609ff0613004a87e726a8\", \"got_revision_cp\": \"refs/heads/master@{#745570}\", \"got_swarming_client_revision\": \"0ac28472d073a515124c85b128c4378a914e0a4d\", \"got_swiftshader_revision\": \"416d374522757fc042282b3ae61c53f5510421fc\", \"got_v8_revision\": \"817bf7ab8a7e40c6e0f799cdd0af45b441d3758e\", \"got_v8_revision_cp\": \"refs/heads/8.2.215@{#1}\", \"got_webrtc_revision\": \"cabed431f8c8e29cb6a318805148f9fb141221b5\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#30647}\", \"mastername\": \"chromium.win\", \"path_config\": \"generic\", \"recipe\": \"swarming/deterministic_build\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"4ea5f928dcf9ea8f716609ff0613004a87e726a8\"}, \"swarming\": {\"bot_dimensions\": {\"builder\": [\"Windows deterministic\"], \"caches\": [\"git\", \"goma_v2\"], \"cores\": [\"8\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"windows-deterministic-0-vu3c\"], \"image\": [\"chrome-win10-1703-19112100-3085293b610\"], \"integrity\": [\"high\"], \"locale\": [\"en_US.cp1252\"], \"machine_type\": [\"n1-standard-8\"], \"os\": [\"Windows\", \"Windows-10\", \"Windows-10-15063\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.13\"], \"server_version\": [\"4840-d3c86f9\"], \"ssd\": [\"0\"], \"windows_client_version\": [\"10\"], \"zone\": [\"us\", \"us-west\", \"us-west1\", \"us-west1-c\"]}}, \"ui\": {\"info\": \"#### Step _First build_ failed. Error logs are shown below:\\n```\\nFailing build because ninja reported work to do.\\nThis means that after completing a compile, another was run and\\nit resulted in still having work to do (that is, a no-op build\\nwasn't a no-op). Consult the first \\\"ninja explain:\\\" line for a\\nlikely culprit.\\n```\\n#### More information in raw_io.output[failure_summary]\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1582920063663715",
"status": "COMPLETED",
"status_changed_ts": "1582920064083206",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/15953",
"builder:Windows deterministic",
"buildset:commit/git/4ea5f928dcf9ea8f716609ff0613004a87e726a8",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/4ea5f928dcf9ea8f716609ff0613004a87e726a8",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9052613491692077552",
"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/8887183428765825792/+/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:4aa61c23960f0410",
"user_agent:luci-scheduler"
],
"updated_ts": "1582926507535441",
"url": "https://ci.chromium.org/b/8887183428765825792",
"utcnow_ts": "1582926507979568"
}
}
[21:48:28.022] Invocation finished in 2h14m29.193960831s with status FAILED