[18:14:30.752] New invocation is queued and will start shortly
[18:14:31.853] Starting the invocation (attempt 1)
[18:14:31.914] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[18:14:31.914] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[18:14:31.914] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9078801014693358080",
"parameters_json": "{\"builder_name\":\"Windows deterministic\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src.git\",\"revision\":\"5f29a0e74877289c34de6246db434399e3827426\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Windows deterministic",
"scheduler_invocation_id:9078801014693358080",
"scheduler_job_id:chromium/Windows deterministic",
"user_agent:luci-scheduler",
"buildset:commit/git/5f29a0e74877289c34de6246db434399e3827426",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/5f29a0e74877289c34de6246db434399e3827426",
"gitiles_ref:refs/heads/master"
]
}
[18:14:32.879] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"created_by": "project:chromium",
"created_ts": "1557944072074253",
"id": "8913370952639461776",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"5f29a0e74877289c34de6246db434399e3827426\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1557944072496500",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/13387",
"builder:Windows deterministic",
"buildset:commit/git/5f29a0e74877289c34de6246db434399e3827426",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/5f29a0e74877289c34de6246db434399e3827426",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9078801014693358080",
"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/8913370952639461776/+/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": "1557944072496629",
"url": "https://ci.chromium.org/b/8913370952639461776",
"utcnow_ts": "1557944072866662"
}
}
[18:14:32.879] Task URL: https://ci.chromium.org/b/8913370952639461776
[18:14:32.879] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078801014693358080:2:0) after 1m0s
[18:14:45.822] Received PubSub notification, asking Buildbucket for the build status
[18:14:46.107] Build 8913370952639461776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:15:32.903] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078801014693358080:2:0)
[18:15:32.903] Timer tick, asking Buildbucket for the build status
[18:15:33.215] Build 8913370952639461776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:15:33.215] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078801014693358080:4:0) after 1m0s
[18:16:33.246] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078801014693358080:4:0)
[18:16:33.246] Timer tick, asking Buildbucket for the build status
[18:16:33.541] Build 8913370952639461776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:16:33.541] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078801014693358080:5:0) after 1m0s
[18:17:33.542] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078801014693358080:5:0)
[18:17:33.542] Timer tick, asking Buildbucket for the build status
[18:17:34.175] Build 8913370952639461776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:17:34.175] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078801014693358080:6:0) after 1m0s
[18:18:34.207] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078801014693358080:6:0)
[18:18:34.207] Timer tick, asking Buildbucket for the build status
[18:18:35.897] Build 8913370952639461776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:18:35.897] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078801014693358080:7:0) after 1m0s
[18:19:35.974] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078801014693358080:7:0)
[18:19:35.974] Timer tick, asking Buildbucket for the build status
[18:19:36.704] Build 8913370952639461776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:19:36.704] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078801014693358080:8:0) after 1m0s
[18:20:36.859] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078801014693358080:8:0)
[18:20:36.859] Timer tick, asking Buildbucket for the build status
[18:20:37.094] Build 8913370952639461776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:20:37.094] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078801014693358080:9:0) after 1m0s
[18:21:37.081] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078801014693358080:9:0)
[18:21:37.081] Timer tick, asking Buildbucket for the build status
[18:21:37.593] Build 8913370952639461776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:21:37.593] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078801014693358080:10:0) after 1m0s
[18:22:37.659] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078801014693358080:10:0)
[18:22:37.659] Timer tick, asking Buildbucket for the build status
[18:22:37.854] Build 8913370952639461776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:22:37.854] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078801014693358080:11:0) after 1m0s
[18:23:37.905] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078801014693358080:11:0)
[18:23:37.907] Timer tick, asking Buildbucket for the build status
[18:23:38.848] Build 8913370952639461776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:23:38.848] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078801014693358080:12:0) after 1m0s
[18:24:38.870] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078801014693358080:12:0)
[18:24:38.870] Timer tick, asking Buildbucket for the build status
[18:24:38.971] Build 8913370952639461776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:24:38.971] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078801014693358080:13:0) after 1m0s
[18:25:39.015] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078801014693358080:13:0)
[18:25:39.015] Timer tick, asking Buildbucket for the build status
[18:25:39.496] Build 8913370952639461776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:25:39.496] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078801014693358080:14:0) after 1m0s
[18:26:39.592] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078801014693358080:14:0)
[18:26:39.592] Timer tick, asking Buildbucket for the build status
[18:26:39.763] Build 8913370952639461776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:26:39.763] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078801014693358080:15:0) after 1m0s
[18:27:39.796] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078801014693358080:15:0)
[18:27:39.796] Timer tick, asking Buildbucket for the build status
[18:27:39.956] Build 8913370952639461776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:27:39.956] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078801014693358080:16:0) after 1m0s
[18:28:39.980] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078801014693358080:16:0)
[18:28:39.980] Timer tick, asking Buildbucket for the build status
[18:28:40.163] Build 8913370952639461776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:28:40.163] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078801014693358080:17:0) after 1m0s
[18:29:40.164] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078801014693358080:17:0)
[18:29:40.165] Timer tick, asking Buildbucket for the build status
[18:29:40.537] Build 8913370952639461776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:29:40.540] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078801014693358080:18:0) after 1m0s
[18:30:40.602] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078801014693358080:18:0)
[18:30:40.602] Timer tick, asking Buildbucket for the build status
[18:30:43.207] Build 8913370952639461776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:30:43.207] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078801014693358080:19:0) after 1m0s
[18:31:43.198] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078801014693358080:19:0)
[18:31:43.198] Timer tick, asking Buildbucket for the build status
[18:31:43.348] Build 8913370952639461776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:31:43.349] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078801014693358080:20:0) after 1m0s
[18:32:43.384] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078801014693358080:20:0)
[18:32:43.384] Timer tick, asking Buildbucket for the build status
[18:32:43.527] Build 8913370952639461776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:32:43.527] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078801014693358080:21:0) after 1m0s
[18:33:43.530] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078801014693358080:21:0)
[18:33:43.530] Timer tick, asking Buildbucket for the build status
[18:33:43.910] Build 8913370952639461776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:33:43.911] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078801014693358080:22:0) after 1m0s
[18:34:43.938] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078801014693358080:22:0)
[18:34:43.938] Timer tick, asking Buildbucket for the build status
[18:34:44.054] Build 8913370952639461776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:34:44.054] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078801014693358080:23:0) after 1m0s
[18:35:44.123] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078801014693358080:23:0)
[18:35:44.123] Timer tick, asking Buildbucket for the build status
[18:35:44.284] Build 8913370952639461776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:35:44.284] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078801014693358080:24:0) after 1m0s
[18:36:44.312] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078801014693358080:24:0)
[18:36:44.312] Timer tick, asking Buildbucket for the build status
[18:36:44.413] Build 8913370952639461776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:36:44.413] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078801014693358080:25:0) after 1m0s
[18:37:44.469] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078801014693358080:25:0)
[18:37:44.469] Timer tick, asking Buildbucket for the build status
[18:37:44.675] Build 8913370952639461776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:37:44.675] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078801014693358080:26:0) after 1m0s
[18:38:44.675] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078801014693358080:26:0)
[18:38:44.675] Timer tick, asking Buildbucket for the build status
[18:38:45.062] Build 8913370952639461776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:38:45.062] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078801014693358080:27:0) after 1m0s
[18:39:45.139] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078801014693358080:27:0)
[18:39:45.139] Timer tick, asking Buildbucket for the build status
[18:39:46.481] Build 8913370952639461776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:39:46.481] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078801014693358080:28:0) after 1m0s
[18:40:46.518] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078801014693358080:28:0)
[18:40:46.518] Timer tick, asking Buildbucket for the build status
[18:40:47.763] Build 8913370952639461776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:40:47.763] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078801014693358080:29:0) after 1m0s
[18:41:47.813] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078801014693358080:29:0)
[18:41:47.813] Timer tick, asking Buildbucket for the build status
[18:41:48.003] Build 8913370952639461776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:41:48.003] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078801014693358080:30:0) after 1m0s
[18:42:48.142] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078801014693358080:30:0)
[18:42:48.142] Timer tick, asking Buildbucket for the build status
[18:42:48.774] Build 8913370952639461776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:42:48.774] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078801014693358080:31:0) after 1m0s
[18:43:48.816] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078801014693358080:31:0)
[18:43:48.816] Timer tick, asking Buildbucket for the build status
[18:43:48.904] Build 8913370952639461776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:43:48.904] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078801014693358080:32:0) after 1m0s
[18:44:48.911] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078801014693358080:32:0)
[18:44:48.911] Timer tick, asking Buildbucket for the build status
[18:44:49.199] Build 8913370952639461776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:44:49.199] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078801014693358080:33:0) after 1m0s
[18:45:49.186] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078801014693358080:33:0)
[18:45:49.186] Timer tick, asking Buildbucket for the build status
[18:45:49.323] Build 8913370952639461776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:45:49.323] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078801014693358080:34:0) after 1m0s
[18:46:49.349] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078801014693358080:34:0)
[18:46:49.349] Timer tick, asking Buildbucket for the build status
[18:46:49.613] Build 8913370952639461776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:46:49.613] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078801014693358080:35:0) after 1m0s
[18:47:49.615] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078801014693358080:35:0)
[18:47:49.615] Timer tick, asking Buildbucket for the build status
[18:47:49.733] Build 8913370952639461776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:47:49.733] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078801014693358080:36:0) after 1m0s
[18:48:49.760] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078801014693358080:36:0)
[18:48:49.760] Timer tick, asking Buildbucket for the build status
[18:48:50.067] Build 8913370952639461776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:48:50.067] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078801014693358080:37:0) after 1m0s
[18:49:50.094] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078801014693358080:37:0)
[18:49:50.094] Timer tick, asking Buildbucket for the build status
[18:49:50.442] Build 8913370952639461776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:49:50.442] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078801014693358080:38:0) after 1m0s
[18:50:50.478] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078801014693358080:38:0)
[18:50:50.478] Timer tick, asking Buildbucket for the build status
[18:50:51.654] Build 8913370952639461776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:50:51.654] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078801014693358080:39:0) after 1m0s
[18:51:51.717] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078801014693358080:39:0)
[18:51:51.717] Timer tick, asking Buildbucket for the build status
[18:51:53.076] Build 8913370952639461776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:51:53.076] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078801014693358080:40:0) after 1m0s
[18:52:53.105] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078801014693358080:40:0)
[18:52:53.105] Timer tick, asking Buildbucket for the build status
[18:52:54.759] Build 8913370952639461776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:52:54.759] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078801014693358080:41:0) after 1m0s
[18:53:54.743] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078801014693358080:41:0)
[18:53:54.743] Timer tick, asking Buildbucket for the build status
[18:53:55.052] Build 8913370952639461776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:53:55.053] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078801014693358080:42:0) after 1m0s
[18:54:55.103] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078801014693358080:42:0)
[18:54:55.103] Timer tick, asking Buildbucket for the build status
[18:54:55.454] Build 8913370952639461776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:54:55.454] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078801014693358080:43:0) after 1m0s
[18:55:55.479] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078801014693358080:43:0)
[18:55:55.479] Timer tick, asking Buildbucket for the build status
[18:55:55.890] Build 8913370952639461776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:55:55.890] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078801014693358080:44:0) after 1m0s
[18:56:55.908] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078801014693358080:44:0)
[18:56:55.908] Timer tick, asking Buildbucket for the build status
[18:56:56.092] Build 8913370952639461776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:56:56.092] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078801014693358080:45:0) after 1m0s
[18:57:56.197] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078801014693358080:45:0)
[18:57:56.197] Timer tick, asking Buildbucket for the build status
[18:57:57.244] Build 8913370952639461776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:57:57.244] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078801014693358080:46:0) after 1m0s
[18:58:57.421] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078801014693358080:46:0)
[18:58:57.421] Timer tick, asking Buildbucket for the build status
[18:58:58.199] Build 8913370952639461776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:58:58.199] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078801014693358080:47:0) after 1m0s
[18:59:58.210] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078801014693358080:47:0)
[18:59:58.210] Timer tick, asking Buildbucket for the build status
[18:59:58.619] Build 8913370952639461776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:59:58.619] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078801014693358080:48:0) after 1m0s
[19:00:58.648] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078801014693358080:48:0)
[19:00:58.648] Timer tick, asking Buildbucket for the build status
[19:01:00.727] Build 8913370952639461776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:01:00.727] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078801014693358080:49:0) after 1m0s
[19:02:00.772] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078801014693358080:49:0)
[19:02:00.772] Timer tick, asking Buildbucket for the build status
[19:02:09.073] Build 8913370952639461776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:02:09.073] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078801014693358080:50:0) after 1m0s
[19:03:09.108] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078801014693358080:50:0)
[19:03:09.108] Timer tick, asking Buildbucket for the build status
[19:03:09.259] Build 8913370952639461776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:03:09.259] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078801014693358080:51:0) after 1m0s
[19:04:09.286] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078801014693358080:51:0)
[19:04:09.286] Timer tick, asking Buildbucket for the build status
[19:04:09.475] Build 8913370952639461776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:04:09.475] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078801014693358080:52:0) after 1m0s
[19:05:09.527] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078801014693358080:52:0)
[19:05:09.527] Timer tick, asking Buildbucket for the build status
[19:05:09.681] Build 8913370952639461776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:05:09.681] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078801014693358080:53:0) after 1m0s
[19:06:09.724] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078801014693358080:53:0)
[19:06:09.724] Timer tick, asking Buildbucket for the build status
[19:06:09.821] Build 8913370952639461776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:06:09.821] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078801014693358080:54:0) after 1m0s
[19:07:09.803] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078801014693358080:54:0)
[19:07:09.803] Timer tick, asking Buildbucket for the build status
[19:07:10.514] Build 8913370952639461776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:07:10.514] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078801014693358080:55:0) after 1m0s
[19:08:10.522] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078801014693358080:55:0)
[19:08:10.522] Timer tick, asking Buildbucket for the build status
[19:08:10.813] Build 8913370952639461776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:08:10.813] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078801014693358080:56:0) after 1m0s
[19:09:10.967] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078801014693358080:56:0)
[19:09:10.967] Timer tick, asking Buildbucket for the build status
[19:09:13.346] Build 8913370952639461776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:09:13.346] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078801014693358080:57:0) after 1m0s
[19:10:13.374] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078801014693358080:57:0)
[19:10:13.374] Timer tick, asking Buildbucket for the build status
[19:10:13.840] Build 8913370952639461776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:10:13.840] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078801014693358080:58:0) after 1m0s
[19:11:13.888] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078801014693358080:58:0)
[19:11:13.888] Timer tick, asking Buildbucket for the build status
[19:11:14.177] Build 8913370952639461776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:11:14.177] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078801014693358080:59:0) after 1m0s
[19:12:14.223] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078801014693358080:59:0)
[19:12:14.223] Timer tick, asking Buildbucket for the build status
[19:12:14.419] Build 8913370952639461776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:12:14.419] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078801014693358080:60:0) after 1m0s
[19:13:14.444] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078801014693358080:60:0)
[19:13:14.444] Timer tick, asking Buildbucket for the build status
[19:13:16.544] Build 8913370952639461776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:13:16.544] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078801014693358080:61:0) after 1m0s
[19:14:16.570] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078801014693358080:61:0)
[19:14:16.570] Timer tick, asking Buildbucket for the build status
[19:14:18.177] Build 8913370952639461776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:14:18.177] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078801014693358080:62:0) after 1m0s
[19:15:18.214] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078801014693358080:62:0)
[19:15:18.214] Timer tick, asking Buildbucket for the build status
[19:15:18.744] Build 8913370952639461776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:15:18.744] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078801014693358080:63:0) after 1m0s
[19:16:18.962] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078801014693358080:63:0)
[19:16:18.962] Timer tick, asking Buildbucket for the build status
[19:16:21.078] Build 8913370952639461776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:16:21.078] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078801014693358080:64:0) after 1m0s
[19:17:21.091] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078801014693358080:64:0)
[19:17:21.091] Timer tick, asking Buildbucket for the build status
[19:17:21.262] Build 8913370952639461776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:17:21.262] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078801014693358080:65:0) after 1m0s
[19:18:21.242] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078801014693358080:65:0)
[19:18:21.242] Timer tick, asking Buildbucket for the build status
[19:18:23.558] Build 8913370952639461776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:18:23.558] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078801014693358080:66:0) after 1m0s
[19:19:23.577] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078801014693358080:66:0)
[19:19:23.577] Timer tick, asking Buildbucket for the build status
[19:19:24.326] Build 8913370952639461776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:19:24.326] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078801014693358080:67:0) after 1m0s
[19:20:24.468] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078801014693358080:67:0)
[19:20:24.468] Timer tick, asking Buildbucket for the build status
[19:20:24.636] Build 8913370952639461776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:20:24.636] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078801014693358080:68:0) after 1m0s
[19:21:24.660] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078801014693358080:68:0)
[19:21:24.660] Timer tick, asking Buildbucket for the build status
[19:21:25.675] Build 8913370952639461776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:21:25.675] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078801014693358080:69:0) after 1m0s
[19:22:25.722] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078801014693358080:69:0)
[19:22:25.722] Timer tick, asking Buildbucket for the build status
[19:22:27.943] Build 8913370952639461776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:22:27.944] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078801014693358080:70:0) after 1m0s
[19:23:27.969] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078801014693358080:70:0)
[19:23:27.969] Timer tick, asking Buildbucket for the build status
[19:23:28.269] Build 8913370952639461776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:23:28.269] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078801014693358080:71:0) after 1m0s
[19:24:28.298] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078801014693358080:71:0)
[19:24:28.298] Timer tick, asking Buildbucket for the build status
[19:24:28.837] Build 8913370952639461776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:24:28.837] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078801014693358080:72:0) after 1m0s
[19:25:28.828] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078801014693358080:72:0)
[19:25:28.828] Timer tick, asking Buildbucket for the build status
[19:25:31.442] Build 8913370952639461776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:25:31.442] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078801014693358080:73:0) after 1m0s
[19:26:31.465] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078801014693358080:73:0)
[19:26:31.465] Timer tick, asking Buildbucket for the build status
[19:26:31.803] Build 8913370952639461776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:26:31.803] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078801014693358080:74:0) after 1m0s
[19:27:31.802] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078801014693358080:74:0)
[19:27:31.802] Timer tick, asking Buildbucket for the build status
[19:27:32.275] Build 8913370952639461776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:27:32.275] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078801014693358080:75:0) after 1m0s
[19:28:32.362] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078801014693358080:75:0)
[19:28:32.362] Timer tick, asking Buildbucket for the build status
[19:28:32.490] Build 8913370952639461776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:28:32.490] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078801014693358080:76:0) after 1m0s
[19:29:32.494] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078801014693358080:76:0)
[19:29:32.494] Timer tick, asking Buildbucket for the build status
[19:29:34.212] Build 8913370952639461776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:29:34.212] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078801014693358080:77:0) after 1m0s
[19:30:34.267] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078801014693358080:77:0)
[19:30:34.267] Timer tick, asking Buildbucket for the build status
[19:30:34.438] Build 8913370952639461776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:30:34.438] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078801014693358080:78:0) after 1m0s
[19:31:34.465] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078801014693358080:78:0)
[19:31:34.465] Timer tick, asking Buildbucket for the build status
[19:31:34.714] Build 8913370952639461776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:31:34.714] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078801014693358080:79:0) after 1m0s
[19:32:34.742] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078801014693358080:79:0)
[19:32:34.742] Timer tick, asking Buildbucket for the build status
[19:32:35.192] Build 8913370952639461776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:32:35.192] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078801014693358080:80:0) after 1m0s
[19:33:35.234] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078801014693358080:80:0)
[19:33:35.234] Timer tick, asking Buildbucket for the build status
[19:33:35.345] Build 8913370952639461776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:33:35.345] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078801014693358080:81:0) after 1m0s
[19:34:35.374] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078801014693358080:81:0)
[19:34:35.374] Timer tick, asking Buildbucket for the build status
[19:34:35.969] Build 8913370952639461776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:34:35.969] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078801014693358080:82:0) after 1m0s
[19:35:36.001] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078801014693358080:82:0)
[19:35:36.001] Timer tick, asking Buildbucket for the build status
[19:35:39.646] Build 8913370952639461776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:35:39.646] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078801014693358080:83:0) after 1m0s
[19:36:39.676] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078801014693358080:83:0)
[19:36:39.676] Timer tick, asking Buildbucket for the build status
[19:36:40.068] Build 8913370952639461776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:36:40.068] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078801014693358080:84:0) after 1m0s
[19:37:40.076] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078801014693358080:84:0)
[19:37:40.076] Timer tick, asking Buildbucket for the build status
[19:37:40.804] Build 8913370952639461776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:37:40.804] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078801014693358080:85:0) after 1m0s
[19:38:40.853] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078801014693358080:85:0)
[19:38:40.853] Timer tick, asking Buildbucket for the build status
[19:38:41.657] Build 8913370952639461776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:38:41.657] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078801014693358080:86:0) after 1m0s
[19:39:41.703] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078801014693358080:86:0)
[19:39:41.703] Timer tick, asking Buildbucket for the build status
[19:39:42.554] Build 8913370952639461776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:39:42.554] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078801014693358080:87:0) after 1m0s
[19:40:42.581] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078801014693358080:87:0)
[19:40:42.581] Timer tick, asking Buildbucket for the build status
[19:40:42.728] Build 8913370952639461776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:40:42.728] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078801014693358080:88:0) after 1m0s
[19:41:42.774] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078801014693358080:88:0)
[19:41:42.774] Timer tick, asking Buildbucket for the build status
[19:41:42.976] Build 8913370952639461776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:41:42.976] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078801014693358080:89:0) after 1m0s
[19:42:42.996] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078801014693358080:89:0)
[19:42:42.996] Timer tick, asking Buildbucket for the build status
[19:42:43.177] Build 8913370952639461776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:42:43.177] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078801014693358080:90:0) after 1m0s
[19:43:43.274] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078801014693358080:90:0)
[19:43:43.274] Timer tick, asking Buildbucket for the build status
[19:43:43.646] Build 8913370952639461776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:43:43.646] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078801014693358080:91:0) after 1m0s
[19:44:43.632] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078801014693358080:91:0)
[19:44:43.632] Timer tick, asking Buildbucket for the build status
[19:44:43.981] Build 8913370952639461776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:44:43.981] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078801014693358080:92:0) after 1m0s
[19:45:44.058] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078801014693358080:92:0)
[19:45:44.058] Timer tick, asking Buildbucket for the build status
[19:45:44.329] Build 8913370952639461776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:45:44.329] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078801014693358080:93:0) after 1m0s
[19:46:44.426] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078801014693358080:93:0)
[19:46:44.426] Timer tick, asking Buildbucket for the build status
[19:46:45.150] Build 8913370952639461776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:46:45.150] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078801014693358080:94:0) after 1m0s
[19:47:45.215] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078801014693358080:94:0)
[19:47:45.215] Timer tick, asking Buildbucket for the build status
[19:47:47.065] Build 8913370952639461776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:47:47.065] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078801014693358080:95:0) after 1m0s
[19:48:47.092] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078801014693358080:95:0)
[19:48:47.092] Timer tick, asking Buildbucket for the build status
[19:48:48.208] Build 8913370952639461776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:48:48.208] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078801014693358080:96:0) after 1m0s
[19:49:48.252] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078801014693358080:96:0)
[19:49:48.252] Timer tick, asking Buildbucket for the build status
[19:49:48.500] Build 8913370952639461776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:49:48.500] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078801014693358080:97:0) after 1m0s
[19:50:48.528] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078801014693358080:97:0)
[19:50:48.528] Timer tick, asking Buildbucket for the build status
[19:50:50.117] Build 8913370952639461776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:50:50.117] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078801014693358080:98:0) after 1m0s
[19:51:50.150] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078801014693358080:98:0)
[19:51:50.150] Timer tick, asking Buildbucket for the build status
[19:51:51.099] Build 8913370952639461776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:51:51.099] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078801014693358080:99:0) after 1m0s
[19:52:51.122] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078801014693358080:99:0)
[19:52:51.122] Timer tick, asking Buildbucket for the build status
[19:52:51.391] Build 8913370952639461776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:52:51.391] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078801014693358080:100:0) after 1m0s
[19:53:51.420] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078801014693358080:100:0)
[19:53:51.420] Timer tick, asking Buildbucket for the build status
[19:53:51.567] Build 8913370952639461776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:53:51.567] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078801014693358080:101:0) after 1m0s
[19:54:51.657] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078801014693358080:101:0)
[19:54:51.657] Timer tick, asking Buildbucket for the build status
[19:54:51.740] Build 8913370952639461776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:54:51.740] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078801014693358080:102:0) after 1m0s
[19:55:51.718] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078801014693358080:102:0)
[19:55:51.718] Timer tick, asking Buildbucket for the build status
[19:55:51.833] Build 8913370952639461776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:55:51.833] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078801014693358080:103:0) after 1m0s
[19:56:51.827] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078801014693358080:103:0)
[19:56:51.827] Timer tick, asking Buildbucket for the build status
[19:56:52.083] Build 8913370952639461776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:56:52.083] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078801014693358080:104:0) after 1m0s
[19:57:52.072] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078801014693358080:104:0)
[19:57:52.072] Timer tick, asking Buildbucket for the build status
[19:57:52.196] Build 8913370952639461776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:57:52.196] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078801014693358080:105:0) after 1m0s
[19:58:52.258] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078801014693358080:105:0)
[19:58:52.258] Timer tick, asking Buildbucket for the build status
[19:58:54.547] Build 8913370952639461776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:58:54.547] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078801014693358080:106:0) after 1m0s
[19:59:54.552] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078801014693358080:106:0)
[19:59:54.552] Timer tick, asking Buildbucket for the build status
[19:59:54.942] Build 8913370952639461776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:59:54.942] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078801014693358080:107:0) after 1m0s
[20:00:55.033] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078801014693358080:107:0)
[20:00:55.033] Timer tick, asking Buildbucket for the build status
[20:00:55.310] Build 8913370952639461776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:00:55.310] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078801014693358080:108:0) after 1m0s
[20:01:55.335] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078801014693358080:108:0)
[20:01:55.335] Timer tick, asking Buildbucket for the build status
[20:01:55.778] Build 8913370952639461776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:01:55.778] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078801014693358080:109:0) after 1m0s
[20:02:55.850] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078801014693358080:109:0)
[20:02:55.850] Timer tick, asking Buildbucket for the build status
[20:02:56.584] Build 8913370952639461776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:02:56.584] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078801014693358080:110:0) after 1m0s
[20:03:56.609] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078801014693358080:110:0)
[20:03:56.609] Timer tick, asking Buildbucket for the build status
[20:03:57.892] Build 8913370952639461776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:03:57.892] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078801014693358080:111:0) after 1m0s
[20:04:57.938] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078801014693358080:111:0)
[20:04:57.938] Timer tick, asking Buildbucket for the build status
[20:04:58.159] Build 8913370952639461776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:04:58.159] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078801014693358080:112:0) after 1m0s
[20:05:58.216] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078801014693358080:112:0)
[20:05:58.216] Timer tick, asking Buildbucket for the build status
[20:05:58.318] Build 8913370952639461776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:05:58.318] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078801014693358080:113:0) after 1m0s
[20:06:58.352] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078801014693358080:113:0)
[20:06:58.352] Timer tick, asking Buildbucket for the build status
[20:06:58.652] Build 8913370952639461776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:06:58.652] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078801014693358080:114:0) after 1m0s
[20:07:58.676] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078801014693358080:114:0)
[20:07:58.676] Timer tick, asking Buildbucket for the build status
[20:07:58.842] Build 8913370952639461776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:07:58.842] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078801014693358080:115:0) after 1m0s
[20:08:58.905] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078801014693358080:115:0)
[20:08:58.906] Timer tick, asking Buildbucket for the build status
[20:08:59.182] Build 8913370952639461776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:08:59.182] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078801014693358080:116:0) after 1m0s
[20:09:59.224] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078801014693358080:116:0)
[20:09:59.224] Timer tick, asking Buildbucket for the build status
[20:09:59.358] Build 8913370952639461776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:09:59.358] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078801014693358080:117:0) after 1m0s
[20:10:59.388] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078801014693358080:117:0)
[20:10:59.388] Timer tick, asking Buildbucket for the build status
[20:10:59.544] Build 8913370952639461776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:10:59.544] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078801014693358080:118:0) after 1m0s
[20:11:59.557] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078801014693358080:118:0)
[20:11:59.557] Timer tick, asking Buildbucket for the build status
[20:12:00.157] Build 8913370952639461776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:12:00.157] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078801014693358080:119:0) after 1m0s
[20:13:00.244] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078801014693358080:119:0)
[20:13:00.244] Timer tick, asking Buildbucket for the build status
[20:13:00.355] Build 8913370952639461776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:13:00.355] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078801014693358080:120:0) after 1m0s
[20:13:32.595] Received PubSub notification, asking Buildbucket for the build status
[20:13:32.995] Build 8913370952639461776: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[20:13:32.995] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"completed_ts": "1557951211902904",
"created_by": "project:chromium",
"created_ts": "1557944072074253",
"id": "8913370952639461776",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"5f29a0e74877289c34de6246db434399e3827426\"}}",
"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-2l8d\", \"branch\": \"refs/heads/master\", \"buildername\": \"Windows deterministic\", \"buildnumber\": 13387, \"got_angle_revision\": \"a253cff3c8ac98adce68c75ae03cb65616352d58\", \"got_dawn_revision\": \"0195dbf908071e7b7afaed7c9fb922910e85e5c2\", \"got_nacl_revision\": \"ca374f5112fca6b36fc5d73e117a60b82f394993\", \"got_revision\": \"5f29a0e74877289c34de6246db434399e3827426\", \"got_revision_cp\": \"refs/heads/master@{#660023}\", \"got_swarming_client_revision\": \"1b65f4e862045f3ba430a4cbd0643f5b1b66c230\", \"got_v8_revision\": \"9d8a97f7ece30ae9a93f6766960375fc514e78d1\", \"got_v8_revision_cp\": \"refs/heads/7.6.191@{#1}\", \"got_webrtc_revision\": \"7581ff73754e34d7b0f5c42e075a2a12087f5bfb\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#27950}\", \"mastername\": \"chromium.win\", \"path_config\": \"generic\", \"recipe\": \"swarming/deterministic_build\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"5f29a0e74877289c34de6246db434399e3827426\"}, \"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-2l8d\"], \"image\": [\"chrome-win10-1703-2018-04-20-61acaf8983f\"], \"integrity\": [\"high\"], \"locale\": [\"en_US.cp1252\"], \"machine_type\": [\"n1-standard-8\"], \"os\": [\"Windows\", \"Windows-10\", \"Windows-10-15063\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.13\"], \"server_version\": [\"4300-ce42b68\"], \"zone\": [\"us\", \"us-west\", \"us-west1\", \"us-west1-c\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1557944083434039",
"status": "COMPLETED",
"status_changed_ts": "1557951212186788",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/13387",
"builder:Windows deterministic",
"buildset:commit/git/5f29a0e74877289c34de6246db434399e3827426",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/5f29a0e74877289c34de6246db434399e3827426",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9078801014693358080",
"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/8913370952639461776/+/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:44d5858bd0804110",
"user_agent:luci-scheduler"
],
"updated_ts": "1557951212192205",
"url": "https://ci.chromium.org/b/8913370952639461776",
"utcnow_ts": "1557951212988574"
}
}
[20:13:32.995] Invocation finished in 1h59m2.264472057s with status SUCCEEDED