[18:46:30.365] New invocation is queued and will start shortly
[18:46:31.575] Starting the invocation (attempt 1)
[18:46:31.677] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[18:46:31.677] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[18:46:31.677] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9069558111260066704",
"parameters_json": "{\"builder_name\":\"Windows deterministic\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src.git\",\"revision\":\"8cbc7f21c51c7e9050da5b8459ca8f37d522d6a9\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Windows deterministic",
"scheduler_invocation_id:9069558111260066704",
"scheduler_job_id:chromium/Windows deterministic",
"user_agent:luci-scheduler",
"buildset:commit/git/8cbc7f21c51c7e9050da5b8459ca8f37d522d6a9",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/8cbc7f21c51c7e9050da5b8459ca8f37d522d6a9",
"gitiles_ref:refs/heads/master"
]
}
[18:46:33.223] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1566758792134888",
"id": "8904128048737902992",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"8cbc7f21c51c7e9050da5b8459ca8f37d522d6a9\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1566758792760911",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/14533",
"builder:Windows deterministic",
"buildset:commit/git/8cbc7f21c51c7e9050da5b8459ca8f37d522d6a9",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/8cbc7f21c51c7e9050da5b8459ca8f37d522d6a9",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9069558111260066704",
"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/8904128048737902992/+/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": "1566758792764212",
"url": "https://ci.chromium.org/b/8904128048737902992",
"utcnow_ts": "1566758793211534"
}
}
[18:46:33.223] Task URL: https://ci.chromium.org/b/8904128048737902992
[18:46:33.223] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:2:0) after 1m0s
[18:46:36.688] Received PubSub notification, asking Buildbucket for the build status
[18:46:36.867] Build 8904128048737902992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:47:33.510] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:2:0)
[18:47:33.510] Timer tick, asking Buildbucket for the build status
[18:47:34.640] Build 8904128048737902992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:47:34.641] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:4:0) after 1m0s
[18:48:35.005] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:4:0)
[18:48:35.005] Timer tick, asking Buildbucket for the build status
[18:48:35.953] Build 8904128048737902992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:48:35.953] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:5:0) after 1m0s
[18:49:36.192] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:5:0)
[18:49:36.192] Timer tick, asking Buildbucket for the build status
[18:49:36.524] Build 8904128048737902992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:49:36.524] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:6:0) after 1m0s
[18:50:36.703] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:6:0)
[18:50:36.703] Timer tick, asking Buildbucket for the build status
[18:50:36.874] Build 8904128048737902992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:50:36.874] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:7:0) after 1m0s
[18:51:37.015] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:7:0)
[18:51:37.015] Timer tick, asking Buildbucket for the build status
[18:51:37.962] Build 8904128048737902992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:51:37.962] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:8:0) after 1m0s
[18:52:38.184] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:8:0)
[18:52:38.184] Timer tick, asking Buildbucket for the build status
[18:52:38.416] Build 8904128048737902992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:52:38.416] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:9:0) after 1m0s
[18:53:38.438] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:9:0)
[18:53:38.438] Timer tick, asking Buildbucket for the build status
[18:53:38.624] Build 8904128048737902992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:53:38.624] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:10:0) after 1m0s
[18:54:38.679] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:10:0)
[18:54:38.679] Timer tick, asking Buildbucket for the build status
[18:54:39.150] Build 8904128048737902992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:54:39.150] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:11:0) after 1m0s
[18:55:39.174] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:11:0)
[18:55:39.174] Timer tick, asking Buildbucket for the build status
[18:55:39.401] Build 8904128048737902992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:55:39.401] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:12:0) after 1m0s
[18:56:39.467] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:12:0)
[18:56:39.467] Timer tick, asking Buildbucket for the build status
[18:56:40.163] Build 8904128048737902992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:56:40.163] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:13:0) after 1m0s
[18:57:40.287] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:13:0)
[18:57:40.287] Timer tick, asking Buildbucket for the build status
[18:57:40.554] Build 8904128048737902992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:57:40.554] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:14:0) after 1m0s
[18:58:40.685] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:14:0)
[18:58:40.685] Timer tick, asking Buildbucket for the build status
[18:58:40.848] Build 8904128048737902992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:58:40.848] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:15:0) after 1m0s
[18:59:40.944] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:15:0)
[18:59:40.944] Timer tick, asking Buildbucket for the build status
[18:59:41.152] Build 8904128048737902992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:59:41.152] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:16:0) after 1m0s
[19:00:41.173] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:16:0)
[19:00:41.173] Timer tick, asking Buildbucket for the build status
[19:00:41.392] Build 8904128048737902992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:00:41.392] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:17:0) after 1m0s
[19:01:41.419] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:17:0)
[19:01:41.419] Timer tick, asking Buildbucket for the build status
[19:01:41.568] Build 8904128048737902992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:01:41.568] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:18:0) after 1m0s
[19:02:41.657] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:18:0)
[19:02:41.657] Timer tick, asking Buildbucket for the build status
[19:02:41.887] Build 8904128048737902992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:02:41.887] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:19:0) after 1m0s
[19:03:42.074] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:19:0)
[19:03:42.074] Timer tick, asking Buildbucket for the build status
[19:03:42.259] Build 8904128048737902992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:03:42.259] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:20:0) after 1m0s
[19:04:42.367] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:20:0)
[19:04:42.367] Timer tick, asking Buildbucket for the build status
[19:04:42.828] Build 8904128048737902992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:04:42.828] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:21:0) after 1m0s
[19:05:42.992] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:21:0)
[19:05:42.992] Timer tick, asking Buildbucket for the build status
[19:05:43.149] Build 8904128048737902992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:05:43.149] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:22:0) after 1m0s
[19:06:43.382] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:22:0)
[19:06:43.382] Timer tick, asking Buildbucket for the build status
[19:06:43.551] Build 8904128048737902992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:06:43.551] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:23:0) after 1m0s
[19:07:43.687] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:23:0)
[19:07:43.687] Timer tick, asking Buildbucket for the build status
[19:07:46.830] Build 8904128048737902992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:07:46.830] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:24:0) after 1m0s
[19:08:46.893] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:24:0)
[19:08:46.893] Timer tick, asking Buildbucket for the build status
[19:08:47.146] Build 8904128048737902992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:08:47.146] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:25:0) after 1m0s
[19:09:47.177] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:25:0)
[19:09:47.177] Timer tick, asking Buildbucket for the build status
[19:09:47.398] Build 8904128048737902992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:09:47.398] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:26:0) after 1m0s
[19:10:47.487] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:26:0)
[19:10:47.487] Timer tick, asking Buildbucket for the build status
[19:10:47.878] Build 8904128048737902992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:10:47.878] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:27:0) after 1m0s
[19:11:47.905] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:27:0)
[19:11:47.905] Timer tick, asking Buildbucket for the build status
[19:11:48.520] Build 8904128048737902992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:11:48.520] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:28:0) after 1m0s
[19:12:48.543] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:28:0)
[19:12:48.543] Timer tick, asking Buildbucket for the build status
[19:12:48.737] Build 8904128048737902992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:12:48.737] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:29:0) after 1m0s
[19:13:48.853] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:29:0)
[19:13:48.853] Timer tick, asking Buildbucket for the build status
[19:13:49.258] Build 8904128048737902992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:13:49.258] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:30:0) after 1m0s
[19:14:49.255] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:30:0)
[19:14:49.255] Timer tick, asking Buildbucket for the build status
[19:14:49.432] Build 8904128048737902992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:14:49.432] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:31:0) after 1m0s
[19:15:49.453] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:31:0)
[19:15:49.453] Timer tick, asking Buildbucket for the build status
[19:15:49.916] Build 8904128048737902992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:15:49.916] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:32:0) after 1m0s
[19:16:49.982] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:32:0)
[19:16:49.982] Timer tick, asking Buildbucket for the build status
[19:16:50.615] Build 8904128048737902992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:16:50.615] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:33:0) after 1m0s
[19:17:50.648] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:33:0)
[19:17:50.648] Timer tick, asking Buildbucket for the build status
[19:17:50.882] Build 8904128048737902992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:17:50.882] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:34:0) after 1m0s
[19:18:51.003] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:34:0)
[19:18:51.003] Timer tick, asking Buildbucket for the build status
[19:18:51.842] Build 8904128048737902992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:18:51.842] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:35:0) after 1m0s
[19:19:51.875] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:35:0)
[19:19:51.875] Timer tick, asking Buildbucket for the build status
[19:19:52.080] Build 8904128048737902992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:19:52.080] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:36:0) after 1m0s
[19:20:52.209] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:36:0)
[19:20:52.209] Timer tick, asking Buildbucket for the build status
[19:20:52.534] Build 8904128048737902992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:20:52.534] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:37:0) after 1m0s
[19:21:52.600] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:37:0)
[19:21:52.600] Timer tick, asking Buildbucket for the build status
[19:21:52.811] Build 8904128048737902992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:21:52.811] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:38:0) after 1m0s
[19:22:52.838] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:38:0)
[19:22:52.838] Timer tick, asking Buildbucket for the build status
[19:22:53.162] Build 8904128048737902992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:22:53.162] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:39:0) after 1m0s
[19:23:53.243] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:39:0)
[19:23:53.243] Timer tick, asking Buildbucket for the build status
[19:23:53.693] Build 8904128048737902992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:23:53.693] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:40:0) after 1m0s
[19:24:53.709] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:40:0)
[19:24:53.709] Timer tick, asking Buildbucket for the build status
[19:24:54.449] Build 8904128048737902992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:24:54.449] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:41:0) after 1m0s
[19:25:54.491] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:41:0)
[19:25:54.491] Timer tick, asking Buildbucket for the build status
[19:25:55.501] Build 8904128048737902992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:25:55.501] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:42:0) after 1m0s
[19:26:55.626] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:42:0)
[19:26:55.626] Timer tick, asking Buildbucket for the build status
[19:26:55.826] Build 8904128048737902992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:26:55.826] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:43:0) after 1m0s
[19:27:55.936] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:43:0)
[19:27:55.936] Timer tick, asking Buildbucket for the build status
[19:27:56.280] Build 8904128048737902992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:27:56.280] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:44:0) after 1m0s
[19:28:56.268] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:44:0)
[19:28:56.268] Timer tick, asking Buildbucket for the build status
[19:28:56.655] Build 8904128048737902992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:28:56.655] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:45:0) after 1m0s
[19:29:56.684] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:45:0)
[19:29:56.684] Timer tick, asking Buildbucket for the build status
[19:29:56.848] Build 8904128048737902992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:29:56.848] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:46:0) after 1m0s
[19:30:56.971] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:46:0)
[19:30:56.971] Timer tick, asking Buildbucket for the build status
[19:30:58.007] Build 8904128048737902992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:30:58.007] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:47:0) after 1m0s
[19:31:58.095] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:47:0)
[19:31:58.095] Timer tick, asking Buildbucket for the build status
[19:31:58.341] Build 8904128048737902992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:31:58.341] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:48:0) after 1m0s
[19:33:01.923] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:48:0)
[19:33:01.925] Timer tick, asking Buildbucket for the build status
[19:33:03.626] Build 8904128048737902992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:33:03.626] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:49:0) after 1m0s
[19:34:03.655] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:49:0)
[19:34:03.655] Timer tick, asking Buildbucket for the build status
[19:34:04.366] Build 8904128048737902992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:34:04.366] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:50:0) after 1m0s
[19:35:04.444] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:50:0)
[19:35:04.444] Timer tick, asking Buildbucket for the build status
[19:35:05.161] Build 8904128048737902992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:35:05.161] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:51:0) after 1m0s
[19:36:05.186] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:51:0)
[19:36:05.186] Timer tick, asking Buildbucket for the build status
[19:36:06.452] Build 8904128048737902992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:36:06.452] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:52:0) after 1m0s
[19:37:06.517] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:52:0)
[19:37:06.517] Timer tick, asking Buildbucket for the build status
[19:37:06.738] Build 8904128048737902992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:37:06.738] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:53:0) after 1m0s
[19:38:06.783] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:53:0)
[19:38:06.783] Timer tick, asking Buildbucket for the build status
[19:38:06.941] Build 8904128048737902992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:38:06.941] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:54:0) after 1m0s
[19:39:06.969] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:54:0)
[19:39:06.969] Timer tick, asking Buildbucket for the build status
[19:39:07.821] Build 8904128048737902992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:39:07.821] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:55:0) after 1m0s
[19:40:07.901] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:55:0)
[19:40:07.901] Timer tick, asking Buildbucket for the build status
[19:40:08.086] Build 8904128048737902992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:40:08.086] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:56:0) after 1m0s
[19:41:08.134] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:56:0)
[19:41:08.134] Timer tick, asking Buildbucket for the build status
[19:41:08.323] Build 8904128048737902992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:41:08.323] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:57:0) after 1m0s
[19:42:08.378] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:57:0)
[19:42:08.378] Timer tick, asking Buildbucket for the build status
[19:42:08.718] Build 8904128048737902992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:42:08.718] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:58:0) after 1m0s
[19:43:08.748] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:58:0)
[19:43:08.748] Timer tick, asking Buildbucket for the build status
[19:43:09.001] Build 8904128048737902992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:43:09.001] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:59:0) after 1m0s
[19:44:09.033] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:59:0)
[19:44:09.033] Timer tick, asking Buildbucket for the build status
[19:44:09.325] Build 8904128048737902992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:44:09.325] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:60:0) after 1m0s
[19:45:09.483] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:60:0)
[19:45:09.483] Timer tick, asking Buildbucket for the build status
[19:45:10.193] Build 8904128048737902992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:45:10.193] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:61:0) after 1m0s
[19:46:10.310] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:61:0)
[19:46:10.310] Timer tick, asking Buildbucket for the build status
[19:46:11.414] Build 8904128048737902992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:46:11.414] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:62:0) after 1m0s
[19:47:11.539] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:62:0)
[19:47:11.540] Timer tick, asking Buildbucket for the build status
[19:47:11.685] Build 8904128048737902992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:47:11.685] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:63:0) after 1m0s
[19:48:11.667] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:63:0)
[19:48:11.667] Timer tick, asking Buildbucket for the build status
[19:48:11.862] Build 8904128048737902992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:48:11.862] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:64:0) after 1m0s
[19:49:11.980] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:64:0)
[19:49:11.980] Timer tick, asking Buildbucket for the build status
[19:49:12.281] Build 8904128048737902992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:49:12.281] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:65:0) after 1m0s
[19:50:12.441] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:65:0)
[19:50:12.441] Timer tick, asking Buildbucket for the build status
[19:50:12.606] Build 8904128048737902992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:50:12.606] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:66:0) after 1m0s
[19:51:12.740] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:66:0)
[19:51:12.740] Timer tick, asking Buildbucket for the build status
[19:51:13.166] Build 8904128048737902992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:51:13.166] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:67:0) after 1m0s
[19:52:13.194] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:67:0)
[19:52:13.194] Timer tick, asking Buildbucket for the build status
[19:52:13.529] Build 8904128048737902992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:52:13.529] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:68:0) after 1m0s
[19:53:13.711] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:68:0)
[19:53:13.711] Timer tick, asking Buildbucket for the build status
[19:53:14.434] Build 8904128048737902992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:53:14.434] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:69:0) after 1m0s
[19:54:14.465] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:69:0)
[19:54:14.465] Timer tick, asking Buildbucket for the build status
[19:54:15.524] Build 8904128048737902992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:54:15.524] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:70:0) after 1m0s
[19:55:15.649] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:70:0)
[19:55:15.649] Timer tick, asking Buildbucket for the build status
[19:55:15.939] Build 8904128048737902992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:55:15.939] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:71:0) after 1m0s
[19:56:16.088] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:71:0)
[19:56:16.088] Timer tick, asking Buildbucket for the build status
[19:56:16.447] Build 8904128048737902992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:56:16.447] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:72:0) after 1m0s
[19:57:16.468] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:72:0)
[19:57:16.468] Timer tick, asking Buildbucket for the build status
[19:57:16.641] Build 8904128048737902992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:57:16.641] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:73:0) after 1m0s
[19:58:16.664] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:73:0)
[19:58:16.665] Timer tick, asking Buildbucket for the build status
[19:58:16.920] Build 8904128048737902992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:58:16.920] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:74:0) after 1m0s
[19:59:17.108] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:74:0)
[19:59:17.108] Timer tick, asking Buildbucket for the build status
[19:59:17.308] Build 8904128048737902992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:59:17.311] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:75:0) after 1m0s
[20:00:17.337] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:75:0)
[20:00:17.337] Timer tick, asking Buildbucket for the build status
[20:00:17.618] Build 8904128048737902992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:00:17.618] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:76:0) after 1m0s
[20:01:17.667] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:76:0)
[20:01:17.667] Timer tick, asking Buildbucket for the build status
[20:01:17.834] Build 8904128048737902992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:01:17.834] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:77:0) after 1m0s
[20:02:17.926] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:77:0)
[20:02:17.926] Timer tick, asking Buildbucket for the build status
[20:02:18.185] Build 8904128048737902992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:02:18.185] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:78:0) after 1m0s
[20:03:18.227] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:78:0)
[20:03:18.227] Timer tick, asking Buildbucket for the build status
[20:03:19.018] Build 8904128048737902992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:03:19.018] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:79:0) after 1m0s
[20:04:19.091] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:79:0)
[20:04:19.091] Timer tick, asking Buildbucket for the build status
[20:04:19.333] Build 8904128048737902992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:04:19.333] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:80:0) after 1m0s
[20:05:19.417] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:80:0)
[20:05:19.417] Timer tick, asking Buildbucket for the build status
[20:05:20.221] Build 8904128048737902992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:05:20.221] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:81:0) after 1m0s
[20:06:20.250] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:81:0)
[20:06:20.250] Timer tick, asking Buildbucket for the build status
[20:06:20.835] Build 8904128048737902992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:06:20.835] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:82:0) after 1m0s
[20:07:20.875] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:82:0)
[20:07:20.875] Timer tick, asking Buildbucket for the build status
[20:07:21.302] Build 8904128048737902992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:07:21.302] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:83:0) after 1m0s
[20:08:21.338] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:83:0)
[20:08:21.338] Timer tick, asking Buildbucket for the build status
[20:08:22.177] Build 8904128048737902992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:08:22.177] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:84:0) after 1m0s
[20:09:22.210] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:84:0)
[20:09:22.210] Timer tick, asking Buildbucket for the build status
[20:09:22.579] Build 8904128048737902992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:09:22.579] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:85:0) after 1m0s
[20:10:22.634] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:85:0)
[20:10:22.634] Timer tick, asking Buildbucket for the build status
[20:10:22.968] Build 8904128048737902992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:10:22.968] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:86:0) after 1m0s
[20:11:23.002] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:86:0)
[20:11:23.002] Timer tick, asking Buildbucket for the build status
[20:11:23.701] Build 8904128048737902992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:11:23.701] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:87:0) after 1m0s
[20:12:23.968] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:87:0)
[20:12:23.968] Timer tick, asking Buildbucket for the build status
[20:12:24.223] Build 8904128048737902992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:12:24.223] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:88:0) after 1m0s
[20:13:24.331] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:88:0)
[20:13:24.331] Timer tick, asking Buildbucket for the build status
[20:13:25.849] Build 8904128048737902992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:13:25.849] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:89:0) after 1m0s
[20:14:25.966] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:89:0)
[20:14:25.966] Timer tick, asking Buildbucket for the build status
[20:14:26.309] Build 8904128048737902992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:14:26.309] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:90:0) after 1m0s
[20:15:26.369] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:90:0)
[20:15:26.369] Timer tick, asking Buildbucket for the build status
[20:15:26.949] Build 8904128048737902992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:15:26.949] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:91:0) after 1m0s
[20:16:27.054] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:91:0)
[20:16:27.054] Timer tick, asking Buildbucket for the build status
[20:16:27.210] Build 8904128048737902992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:16:27.210] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:92:0) after 1m0s
[20:17:27.250] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:92:0)
[20:17:27.250] Timer tick, asking Buildbucket for the build status
[20:17:27.607] Build 8904128048737902992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:17:27.607] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:93:0) after 1m0s
[20:18:27.679] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:93:0)
[20:18:27.679] Timer tick, asking Buildbucket for the build status
[20:18:28.077] Build 8904128048737902992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:18:28.077] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:94:0) after 1m0s
[20:19:28.192] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:94:0)
[20:19:28.192] Timer tick, asking Buildbucket for the build status
[20:19:28.481] Build 8904128048737902992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:19:28.481] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:95:0) after 1m0s
[20:20:28.639] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:95:0)
[20:20:28.639] Timer tick, asking Buildbucket for the build status
[20:20:28.995] Build 8904128048737902992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:20:28.995] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:96:0) after 1m0s
[20:21:29.119] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:96:0)
[20:21:29.119] Timer tick, asking Buildbucket for the build status
[20:21:29.524] Build 8904128048737902992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:21:29.524] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:97:0) after 1m0s
[20:22:29.557] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:97:0)
[20:22:29.557] Timer tick, asking Buildbucket for the build status
[20:22:30.402] Build 8904128048737902992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:22:30.402] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:98:0) after 1m0s
[20:23:30.489] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:98:0)
[20:23:30.489] Timer tick, asking Buildbucket for the build status
[20:23:30.949] Build 8904128048737902992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:23:30.949] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:99:0) after 1m0s
[20:24:31.055] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:99:0)
[20:24:31.055] Timer tick, asking Buildbucket for the build status
[20:24:31.294] Build 8904128048737902992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:24:31.294] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:100:0) after 1m0s
[20:25:31.378] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:100:0)
[20:25:31.378] Timer tick, asking Buildbucket for the build status
[20:25:31.878] Build 8904128048737902992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:25:31.878] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:101:0) after 1m0s
[20:26:31.929] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:101:0)
[20:26:31.929] Timer tick, asking Buildbucket for the build status
[20:26:32.133] Build 8904128048737902992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:26:32.133] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:102:0) after 1m0s
[20:27:32.243] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:102:0)
[20:27:32.243] Timer tick, asking Buildbucket for the build status
[20:27:33.038] Build 8904128048737902992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:27:33.038] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:103:0) after 1m0s
[20:28:33.061] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:103:0)
[20:28:33.061] Timer tick, asking Buildbucket for the build status
[20:28:33.285] Build 8904128048737902992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:28:33.285] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:104:0) after 1m0s
[20:29:33.330] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:104:0)
[20:29:33.330] Timer tick, asking Buildbucket for the build status
[20:29:33.477] Build 8904128048737902992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:29:33.477] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:105:0) after 1m0s
[20:30:33.531] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:105:0)
[20:30:33.531] Timer tick, asking Buildbucket for the build status
[20:30:33.798] Build 8904128048737902992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:30:33.798] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:106:0) after 1m0s
[20:31:33.828] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:106:0)
[20:31:33.828] Timer tick, asking Buildbucket for the build status
[20:31:34.003] Build 8904128048737902992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:31:34.003] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:107:0) after 1m0s
[20:32:34.034] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:107:0)
[20:32:34.034] Timer tick, asking Buildbucket for the build status
[20:32:34.208] Build 8904128048737902992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:32:34.208] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:108:0) after 1m0s
[20:33:34.263] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:108:0)
[20:33:34.263] Timer tick, asking Buildbucket for the build status
[20:33:34.500] Build 8904128048737902992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:33:34.500] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:109:0) after 1m0s
[20:34:34.642] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:109:0)
[20:34:34.642] Timer tick, asking Buildbucket for the build status
[20:34:34.878] Build 8904128048737902992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:34:34.878] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:110:0) after 1m0s
[20:35:34.904] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:110:0)
[20:35:34.904] Timer tick, asking Buildbucket for the build status
[20:35:35.809] Build 8904128048737902992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:35:35.809] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:111:0) after 1m0s
[20:36:35.841] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:111:0)
[20:36:35.841] Timer tick, asking Buildbucket for the build status
[20:36:36.044] Build 8904128048737902992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:36:36.044] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:112:0) after 1m0s
[20:37:36.270] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:112:0)
[20:37:36.270] Timer tick, asking Buildbucket for the build status
[20:37:36.641] Build 8904128048737902992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:37:36.641] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:113:0) after 1m0s
[20:38:36.669] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:113:0)
[20:38:36.669] Timer tick, asking Buildbucket for the build status
[20:38:37.121] Build 8904128048737902992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:38:37.121] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:114:0) after 1m0s
[20:39:37.145] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:114:0)
[20:39:37.145] Timer tick, asking Buildbucket for the build status
[20:39:37.355] Build 8904128048737902992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:39:37.355] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:115:0) after 1m0s
[20:40:37.379] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:115:0)
[20:40:37.380] Timer tick, asking Buildbucket for the build status
[20:40:37.559] Build 8904128048737902992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:40:37.559] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:116:0) after 1m0s
[20:41:37.640] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:116:0)
[20:41:37.640] Timer tick, asking Buildbucket for the build status
[20:41:39.601] Build 8904128048737902992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:41:39.601] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:117:0) after 1m0s
[20:42:39.646] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:117:0)
[20:42:39.646] Timer tick, asking Buildbucket for the build status
[20:42:40.180] Build 8904128048737902992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:42:40.180] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:118:0) after 1m0s
[20:43:40.267] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:118:0)
[20:43:40.267] Timer tick, asking Buildbucket for the build status
[20:43:40.430] Build 8904128048737902992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:43:40.430] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:119:0) after 1m0s
[20:44:40.540] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:119:0)
[20:44:40.540] Timer tick, asking Buildbucket for the build status
[20:44:43.035] Build 8904128048737902992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:44:43.035] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:120:0) after 1m0s
[20:45:43.194] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:120:0)
[20:45:43.194] Timer tick, asking Buildbucket for the build status
[20:45:43.395] Build 8904128048737902992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:45:43.395] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:121:0) after 1m0s
[20:46:43.749] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:121:0)
[20:46:43.749] Timer tick, asking Buildbucket for the build status
[20:46:44.206] Build 8904128048737902992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:46:44.206] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:122:0) after 1m0s
[20:47:44.315] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:122:0)
[20:47:44.315] Timer tick, asking Buildbucket for the build status
[20:47:44.590] Build 8904128048737902992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:47:44.590] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:123:0) after 1m0s
[20:48:44.617] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:123:0)
[20:48:44.617] Timer tick, asking Buildbucket for the build status
[20:48:44.897] Build 8904128048737902992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:48:44.897] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:124:0) after 1m0s
[20:49:45.008] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:124:0)
[20:49:45.008] Timer tick, asking Buildbucket for the build status
[20:49:46.656] Build 8904128048737902992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:49:46.656] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:125:0) after 1m0s
[20:50:46.759] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:125:0)
[20:50:46.759] Timer tick, asking Buildbucket for the build status
[20:50:47.599] Build 8904128048737902992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:50:47.599] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:126:0) after 1m0s
[20:51:47.626] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:126:0)
[20:51:47.626] Timer tick, asking Buildbucket for the build status
[20:51:47.829] Build 8904128048737902992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:51:47.829] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:127:0) after 1m0s
[20:52:47.890] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:127:0)
[20:52:47.891] Timer tick, asking Buildbucket for the build status
[20:52:48.054] Build 8904128048737902992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:52:48.054] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:128:0) after 1m0s
[20:53:48.096] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:128:0)
[20:53:48.096] Timer tick, asking Buildbucket for the build status
[20:53:50.397] Build 8904128048737902992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:53:50.397] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:129:0) after 1m0s
[20:54:50.435] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:129:0)
[20:54:50.435] Timer tick, asking Buildbucket for the build status
[20:54:50.695] Build 8904128048737902992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:54:50.695] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:130:0) after 1m0s
[20:55:50.823] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:130:0)
[20:55:50.823] Timer tick, asking Buildbucket for the build status
[20:55:51.700] Build 8904128048737902992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:55:51.700] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:131:0) after 1m0s
[20:56:51.794] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:131:0)
[20:56:51.794] Timer tick, asking Buildbucket for the build status
[20:56:51.948] Build 8904128048737902992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:56:51.948] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:132:0) after 1m0s
[20:57:51.974] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:132:0)
[20:57:51.974] Timer tick, asking Buildbucket for the build status
[20:57:52.523] Build 8904128048737902992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:57:52.523] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9069558111260066704:133:0) after 1m0s
[20:57:58.165] Received PubSub notification, asking Buildbucket for the build status
[20:57:58.660] Build 8904128048737902992: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[20:57:58.660] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1566766677072181",
"created_by": "project:chromium",
"created_ts": "1566758792134888",
"id": "8904128048737902992",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"8cbc7f21c51c7e9050da5b8459ca8f37d522d6a9\"}}",
"project": "chromium",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"$recipe_engine/path\": {\"cache_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\cache\", \"temp_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\tmp\\\\rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"bot_id\": \"windows-deterministic-0-bzrg\", \"branch\": \"refs/heads/master\", \"buildername\": \"Windows deterministic\", \"buildnumber\": 14533, \"got_angle_revision\": \"3c6b2e1613c844f7265895de5611c0528641d501\", \"got_dawn_revision\": \"25cc723823e3aa9bf6d7edfcd308bb82ab05b0fc\", \"got_nacl_revision\": \"058a26f57015051953a727add26e7e26348b9558\", \"got_revision\": \"8cbc7f21c51c7e9050da5b8459ca8f37d522d6a9\", \"got_revision_cp\": \"refs/heads/master@{#690240}\", \"got_swarming_client_revision\": \"96f125709acfd0b48fc1e5dae7d6ea42291726ac\", \"got_v8_revision\": \"40ba72e3195abfa78729d496ed5cc951d5941174\", \"got_v8_revision_cp\": \"refs/heads/7.8.189@{#1}\", \"got_webrtc_revision\": \"a2dae38ee7729ec1d6fcb7d22b7a597c627ad81a\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#28937}\", \"mastername\": \"chromium.win\", \"path_config\": \"generic\", \"recipe\": \"swarming/deterministic_build\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"8cbc7f21c51c7e9050da5b8459ca8f37d522d6a9\"}, \"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-bzrg\"], \"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\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1566758795295252",
"status": "COMPLETED",
"status_changed_ts": "1566766677557071",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/14533",
"builder:Windows deterministic",
"buildset:commit/git/8cbc7f21c51c7e9050da5b8459ca8f37d522d6a9",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/8cbc7f21c51c7e9050da5b8459ca8f37d522d6a9",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9069558111260066704",
"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/8904128048737902992/+/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:46e2eb77cd10bc10",
"user_agent:luci-scheduler"
],
"updated_ts": "1566766677564478",
"url": "https://ci.chromium.org/b/8904128048737902992",
"utcnow_ts": "1566766678636270"
}
}
[20:57:58.660] Invocation finished in 2h11m28.314372901s with status SUCCEEDED