[21:06:42.212] New invocation is queued and will start shortly
[21:06:43.506] Starting the invocation (attempt 1)
[21:06:43.544] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[21:06:43.544] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[21:06:43.544] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9077612420813685664",
"parameters_json": "{\"builder_name\":\"Windows deterministic\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src.git\",\"revision\":\"c2d74c6cea694e55e5dbca3779bee1003c44e351\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Windows deterministic",
"scheduler_invocation_id:9077612420813685664",
"scheduler_job_id:chromium/Windows deterministic",
"user_agent:luci-scheduler",
"buildset:commit/git/c2d74c6cea694e55e5dbca3779bee1003c44e351",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/c2d74c6cea694e55e5dbca3779bee1003c44e351",
"gitiles_ref:refs/heads/master"
]
}
[21:06:46.896] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"created_by": "project:chromium",
"created_ts": "1559077604545484",
"id": "8912182357694591888",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"c2d74c6cea694e55e5dbca3779bee1003c44e351\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1559077606159185",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/13591",
"builder:Windows deterministic",
"buildset:commit/git/c2d74c6cea694e55e5dbca3779bee1003c44e351",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/c2d74c6cea694e55e5dbca3779bee1003c44e351",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9077612420813685664",
"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/8912182357694591888/+/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": "1559077606159336",
"url": "https://ci.chromium.org/b/8912182357694591888",
"utcnow_ts": "1559077606548948"
}
}
[21:06:46.896] Task URL: https://ci.chromium.org/b/8912182357694591888
[21:06:46.896] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077612420813685664:2:0) after 1m0s
[21:06:55.688] Received PubSub notification, asking Buildbucket for the build status
[21:06:56.069] Build 8912182357694591888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:07:46.928] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077612420813685664:2:0)
[21:07:46.928] Timer tick, asking Buildbucket for the build status
[21:07:47.053] Build 8912182357694591888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:07:47.053] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077612420813685664:4:0) after 1m0s
[21:08:47.148] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077612420813685664:4:0)
[21:08:47.148] Timer tick, asking Buildbucket for the build status
[21:08:48.399] Build 8912182357694591888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:08:48.399] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077612420813685664:5:0) after 1m0s
[21:09:48.431] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077612420813685664:5:0)
[21:09:48.431] Timer tick, asking Buildbucket for the build status
[21:09:48.661] Build 8912182357694591888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:09:48.661] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077612420813685664:6:0) after 1m0s
[21:10:48.721] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077612420813685664:6:0)
[21:10:48.721] Timer tick, asking Buildbucket for the build status
[21:10:48.814] Build 8912182357694591888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:10:48.814] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077612420813685664:7:0) after 1m0s
[21:11:49.044] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077612420813685664:7:0)
[21:11:49.044] Timer tick, asking Buildbucket for the build status
[21:11:49.180] Build 8912182357694591888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:11:49.180] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077612420813685664:8:0) after 1m0s
[21:12:49.277] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077612420813685664:8:0)
[21:12:49.277] Timer tick, asking Buildbucket for the build status
[21:12:49.401] Build 8912182357694591888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:12:49.401] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077612420813685664:9:0) after 1m0s
[21:13:49.892] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077612420813685664:9:0)
[21:13:49.892] Timer tick, asking Buildbucket for the build status
[21:13:49.995] Build 8912182357694591888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:13:49.995] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077612420813685664:10:0) after 1m0s
[21:14:50.164] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077612420813685664:10:0)
[21:14:50.164] Timer tick, asking Buildbucket for the build status
[21:14:50.658] Build 8912182357694591888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:14:50.658] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077612420813685664:11:0) after 1m0s
[21:15:50.846] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077612420813685664:11:0)
[21:15:50.846] Timer tick, asking Buildbucket for the build status
[21:15:51.004] Build 8912182357694591888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:15:51.004] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077612420813685664:12:0) after 1m0s
[21:16:51.038] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077612420813685664:12:0)
[21:16:51.038] Timer tick, asking Buildbucket for the build status
[21:16:51.636] Build 8912182357694591888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:16:51.636] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077612420813685664:13:0) after 1m0s
[21:17:51.688] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077612420813685664:13:0)
[21:17:51.688] Timer tick, asking Buildbucket for the build status
[21:17:51.893] Build 8912182357694591888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:17:51.893] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077612420813685664:14:0) after 1m0s
[21:18:52.014] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077612420813685664:14:0)
[21:18:52.014] Timer tick, asking Buildbucket for the build status
[21:18:52.560] Build 8912182357694591888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:18:52.561] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077612420813685664:15:0) after 1m0s
[21:19:52.789] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077612420813685664:15:0)
[21:19:52.789] Timer tick, asking Buildbucket for the build status
[21:19:55.713] Build 8912182357694591888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:19:55.713] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077612420813685664:16:0) after 1m0s
[21:20:55.780] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077612420813685664:16:0)
[21:20:55.780] Timer tick, asking Buildbucket for the build status
[21:20:56.152] Build 8912182357694591888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:20:56.152] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077612420813685664:17:0) after 1m0s
[21:21:56.258] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077612420813685664:17:0)
[21:21:56.258] Timer tick, asking Buildbucket for the build status
[21:21:58.198] Build 8912182357694591888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:21:58.198] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077612420813685664:18:0) after 1m0s
[21:22:58.319] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077612420813685664:18:0)
[21:22:58.319] Timer tick, asking Buildbucket for the build status
[21:22:58.831] Build 8912182357694591888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:22:58.831] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077612420813685664:19:0) after 1m0s
[21:23:58.839] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077612420813685664:19:0)
[21:23:58.839] Timer tick, asking Buildbucket for the build status
[21:23:58.966] Build 8912182357694591888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:23:58.966] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077612420813685664:20:0) after 1m0s
[21:24:59.034] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077612420813685664:20:0)
[21:24:59.034] Timer tick, asking Buildbucket for the build status
[21:24:59.998] Build 8912182357694591888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:24:59.998] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077612420813685664:21:0) after 1m0s
[21:26:00.136] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077612420813685664:21:0)
[21:26:00.136] Timer tick, asking Buildbucket for the build status
[21:26:02.035] Build 8912182357694591888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:26:02.035] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077612420813685664:22:0) after 1m0s
[21:27:02.071] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077612420813685664:22:0)
[21:27:02.071] Timer tick, asking Buildbucket for the build status
[21:27:03.555] Build 8912182357694591888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:27:03.555] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077612420813685664:23:0) after 1m0s
[21:28:03.535] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077612420813685664:23:0)
[21:28:03.535] Timer tick, asking Buildbucket for the build status
[21:28:03.677] Build 8912182357694591888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:28:03.677] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077612420813685664:24:0) after 1m0s
[21:29:03.695] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077612420813685664:24:0)
[21:29:03.695] Timer tick, asking Buildbucket for the build status
[21:29:03.866] Build 8912182357694591888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:29:03.866] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077612420813685664:25:0) after 1m0s
[21:30:03.884] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077612420813685664:25:0)
[21:30:03.884] Timer tick, asking Buildbucket for the build status
[21:30:04.853] Build 8912182357694591888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:30:04.853] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077612420813685664:26:0) after 1m0s
[21:31:04.906] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077612420813685664:26:0)
[21:31:04.906] Timer tick, asking Buildbucket for the build status
[21:31:05.040] Build 8912182357694591888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:31:05.040] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077612420813685664:27:0) after 1m0s
[21:32:05.073] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077612420813685664:27:0)
[21:32:05.073] Timer tick, asking Buildbucket for the build status
[21:32:07.467] Build 8912182357694591888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:32:07.467] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077612420813685664:28:0) after 1m0s
[21:33:07.508] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077612420813685664:28:0)
[21:33:07.508] Timer tick, asking Buildbucket for the build status
[21:33:08.759] Build 8912182357694591888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:33:08.760] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077612420813685664:29:0) after 1m0s
[21:34:08.821] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077612420813685664:29:0)
[21:34:08.821] Timer tick, asking Buildbucket for the build status
[21:34:10.100] Build 8912182357694591888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:34:10.100] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077612420813685664:30:0) after 1m0s
[21:35:10.287] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077612420813685664:30:0)
[21:35:10.287] Timer tick, asking Buildbucket for the build status
[21:35:11.718] Build 8912182357694591888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:35:11.718] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077612420813685664:31:0) after 1m0s
[21:36:11.872] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077612420813685664:31:0)
[21:36:11.872] Timer tick, asking Buildbucket for the build status
[21:36:12.347] Build 8912182357694591888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:36:12.347] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077612420813685664:32:0) after 1m0s
[21:37:12.413] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077612420813685664:32:0)
[21:37:12.413] Timer tick, asking Buildbucket for the build status
[21:37:14.719] Build 8912182357694591888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:37:14.719] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077612420813685664:33:0) after 1m0s
[21:38:14.750] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077612420813685664:33:0)
[21:38:14.750] Timer tick, asking Buildbucket for the build status
[21:38:16.254] Build 8912182357694591888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:38:16.254] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077612420813685664:34:0) after 1m0s
[21:39:16.269] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077612420813685664:34:0)
[21:39:16.269] Timer tick, asking Buildbucket for the build status
[21:39:16.519] Build 8912182357694591888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:39:16.519] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077612420813685664:35:0) after 1m0s
[21:40:16.529] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077612420813685664:35:0)
[21:40:16.529] Timer tick, asking Buildbucket for the build status
[21:40:16.695] Build 8912182357694591888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:40:16.695] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077612420813685664:36:0) after 1m0s
[21:41:16.755] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077612420813685664:36:0)
[21:41:16.755] Timer tick, asking Buildbucket for the build status
[21:41:19.366] Build 8912182357694591888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:41:19.366] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077612420813685664:37:0) after 1m0s
[21:42:19.467] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077612420813685664:37:0)
[21:42:19.467] Timer tick, asking Buildbucket for the build status
[21:42:21.380] Build 8912182357694591888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:42:21.380] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077612420813685664:38:0) after 1m0s
[21:43:21.538] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077612420813685664:38:0)
[21:43:21.538] Timer tick, asking Buildbucket for the build status
[21:43:24.211] Build 8912182357694591888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:43:24.211] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077612420813685664:39:0) after 1m0s
[21:44:24.333] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077612420813685664:39:0)
[21:44:24.333] Timer tick, asking Buildbucket for the build status
[21:44:25.374] Build 8912182357694591888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:44:25.374] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077612420813685664:40:0) after 1m0s
[21:45:25.714] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077612420813685664:40:0)
[21:45:25.714] Timer tick, asking Buildbucket for the build status
[21:45:27.923] Build 8912182357694591888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:45:27.923] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077612420813685664:41:0) after 1m0s
[21:46:28.003] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077612420813685664:41:0)
[21:46:28.003] Timer tick, asking Buildbucket for the build status
[21:46:30.850] Build 8912182357694591888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:46:30.850] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077612420813685664:42:0) after 1m0s
[21:47:31.004] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077612420813685664:42:0)
[21:47:31.004] Timer tick, asking Buildbucket for the build status
[21:47:31.134] Build 8912182357694591888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:47:31.134] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077612420813685664:43:0) after 1m0s
[21:48:31.174] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077612420813685664:43:0)
[21:48:31.174] Timer tick, asking Buildbucket for the build status
[21:48:32.706] Build 8912182357694591888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:48:32.706] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077612420813685664:44:0) after 1m0s
[21:49:33.095] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077612420813685664:44:0)
[21:49:33.095] Timer tick, asking Buildbucket for the build status
[21:49:33.875] Build 8912182357694591888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:49:33.876] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077612420813685664:45:0) after 1m0s
[21:50:33.904] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077612420813685664:45:0)
[21:50:33.904] Timer tick, asking Buildbucket for the build status
[21:50:34.289] Build 8912182357694591888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:50:34.289] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077612420813685664:46:0) after 1m0s
[21:51:34.311] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077612420813685664:46:0)
[21:51:34.311] Timer tick, asking Buildbucket for the build status
[21:51:34.577] Build 8912182357694591888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:51:34.577] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077612420813685664:47:0) after 1m0s
[21:52:34.598] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077612420813685664:47:0)
[21:52:34.598] Timer tick, asking Buildbucket for the build status
[21:52:34.678] Build 8912182357694591888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:52:34.678] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077612420813685664:48:0) after 1m0s
[21:53:34.741] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077612420813685664:48:0)
[21:53:34.741] Timer tick, asking Buildbucket for the build status
[21:53:38.124] Build 8912182357694591888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:53:38.124] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077612420813685664:49:0) after 1m0s
[21:54:38.555] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077612420813685664:49:0)
[21:54:38.555] Timer tick, asking Buildbucket for the build status
[21:54:38.949] Build 8912182357694591888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:54:38.949] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077612420813685664:50:0) after 1m0s
[21:55:39.001] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077612420813685664:50:0)
[21:55:39.001] Timer tick, asking Buildbucket for the build status
[21:55:39.140] Build 8912182357694591888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:55:39.140] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077612420813685664:51:0) after 1m0s
[21:56:39.231] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077612420813685664:51:0)
[21:56:39.231] Timer tick, asking Buildbucket for the build status
[21:56:39.571] Build 8912182357694591888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:56:39.571] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077612420813685664:52:0) after 1m0s
[21:57:39.602] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077612420813685664:52:0)
[21:57:39.602] Timer tick, asking Buildbucket for the build status
[21:57:39.707] Build 8912182357694591888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:57:39.707] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077612420813685664:53:0) after 1m0s
[21:58:39.786] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077612420813685664:53:0)
[21:58:39.786] Timer tick, asking Buildbucket for the build status
[21:58:40.468] Build 8912182357694591888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:58:40.468] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077612420813685664:54:0) after 1m0s
[21:59:40.498] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077612420813685664:54:0)
[21:59:40.498] Timer tick, asking Buildbucket for the build status
[21:59:40.625] Build 8912182357694591888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:59:40.625] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077612420813685664:55:0) after 1m0s
[22:00:40.680] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077612420813685664:55:0)
[22:00:40.680] Timer tick, asking Buildbucket for the build status
[22:00:40.852] Build 8912182357694591888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:00:40.852] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077612420813685664:56:0) after 1m0s
[22:01:40.852] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077612420813685664:56:0)
[22:01:40.852] Timer tick, asking Buildbucket for the build status
[22:01:41.275] Build 8912182357694591888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:01:41.275] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077612420813685664:57:0) after 1m0s
[22:02:41.295] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077612420813685664:57:0)
[22:02:41.295] Timer tick, asking Buildbucket for the build status
[22:02:42.129] Build 8912182357694591888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:02:42.129] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077612420813685664:58:0) after 1m0s
[22:03:42.219] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077612420813685664:58:0)
[22:03:42.219] Timer tick, asking Buildbucket for the build status
[22:03:42.405] Build 8912182357694591888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:03:42.405] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077612420813685664:59:0) after 1m0s
[22:04:42.494] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077612420813685664:59:0)
[22:04:42.494] Timer tick, asking Buildbucket for the build status
[22:04:43.099] Build 8912182357694591888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:04:43.099] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077612420813685664:60:0) after 1m0s
[22:05:43.211] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077612420813685664:60:0)
[22:05:43.211] Timer tick, asking Buildbucket for the build status
[22:05:43.476] Build 8912182357694591888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:05:43.476] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077612420813685664:61:0) after 1m0s
[22:06:43.497] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077612420813685664:61:0)
[22:06:43.497] Timer tick, asking Buildbucket for the build status
[22:06:45.786] Build 8912182357694591888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:06:45.786] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077612420813685664:62:0) after 1m0s
[22:07:45.902] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077612420813685664:62:0)
[22:07:45.902] Timer tick, asking Buildbucket for the build status
[22:07:46.103] Build 8912182357694591888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:07:46.103] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077612420813685664:63:0) after 1m0s
[22:08:46.128] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077612420813685664:63:0)
[22:08:46.128] Timer tick, asking Buildbucket for the build status
[22:08:47.052] Build 8912182357694591888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:08:47.052] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077612420813685664:64:0) after 1m0s
[22:09:47.124] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077612420813685664:64:0)
[22:09:47.124] Timer tick, asking Buildbucket for the build status
[22:09:47.529] Build 8912182357694591888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:09:47.529] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077612420813685664:65:0) after 1m0s
[22:10:47.621] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077612420813685664:65:0)
[22:10:47.621] Timer tick, asking Buildbucket for the build status
[22:10:50.090] Build 8912182357694591888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:10:50.090] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077612420813685664:66:0) after 1m0s
[22:11:50.126] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077612420813685664:66:0)
[22:11:50.126] Timer tick, asking Buildbucket for the build status
[22:11:50.379] Build 8912182357694591888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:11:50.379] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077612420813685664:67:0) after 1m0s
[22:12:50.565] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077612420813685664:67:0)
[22:12:50.565] Timer tick, asking Buildbucket for the build status
[22:12:52.709] Build 8912182357694591888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:12:52.709] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077612420813685664:68:0) after 1m0s
[22:13:52.748] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077612420813685664:68:0)
[22:13:52.748] Timer tick, asking Buildbucket for the build status
[22:13:52.867] Build 8912182357694591888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:13:52.867] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077612420813685664:69:0) after 1m0s
[22:14:52.957] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077612420813685664:69:0)
[22:14:52.957] Timer tick, asking Buildbucket for the build status
[22:14:54.379] Build 8912182357694591888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:14:54.379] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077612420813685664:70:0) after 1m0s
[22:15:54.453] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077612420813685664:70:0)
[22:15:54.453] Timer tick, asking Buildbucket for the build status
[22:15:55.087] Build 8912182357694591888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:15:55.087] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077612420813685664:71:0) after 1m0s
[22:16:55.169] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077612420813685664:71:0)
[22:16:55.169] Timer tick, asking Buildbucket for the build status
[22:16:55.572] Build 8912182357694591888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:16:55.572] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077612420813685664:72:0) after 1m0s
[22:17:55.617] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077612420813685664:72:0)
[22:17:55.617] Timer tick, asking Buildbucket for the build status
[22:17:55.719] Build 8912182357694591888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:17:55.719] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077612420813685664:73:0) after 1m0s
[22:18:55.861] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077612420813685664:73:0)
[22:18:55.861] Timer tick, asking Buildbucket for the build status
[22:18:56.042] Build 8912182357694591888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:18:56.042] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077612420813685664:74:0) after 1m0s
[22:19:56.083] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077612420813685664:74:0)
[22:19:56.083] Timer tick, asking Buildbucket for the build status
[22:19:56.563] Build 8912182357694591888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:19:56.563] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077612420813685664:75:0) after 1m0s
[22:20:56.604] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077612420813685664:75:0)
[22:20:56.604] Timer tick, asking Buildbucket for the build status
[22:20:56.729] Build 8912182357694591888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:20:56.729] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077612420813685664:76:0) after 1m0s
[22:21:56.757] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077612420813685664:76:0)
[22:21:56.757] Timer tick, asking Buildbucket for the build status
[22:21:59.266] Build 8912182357694591888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:21:59.266] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077612420813685664:77:0) after 1m0s
[22:22:59.364] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077612420813685664:77:0)
[22:22:59.364] Timer tick, asking Buildbucket for the build status
[22:22:59.470] Build 8912182357694591888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:22:59.470] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077612420813685664:78:0) after 1m0s
[22:23:59.506] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077612420813685664:78:0)
[22:23:59.506] Timer tick, asking Buildbucket for the build status
[22:24:00.503] Build 8912182357694591888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:24:00.503] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077612420813685664:79:0) after 1m0s
[22:25:00.537] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077612420813685664:79:0)
[22:25:00.537] Timer tick, asking Buildbucket for the build status
[22:25:02.586] Build 8912182357694591888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:25:02.586] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077612420813685664:80:0) after 1m0s
[22:26:02.646] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077612420813685664:80:0)
[22:26:02.646] Timer tick, asking Buildbucket for the build status
[22:26:03.859] Build 8912182357694591888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:26:03.859] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077612420813685664:81:0) after 1m0s
[22:27:03.879] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077612420813685664:81:0)
[22:27:03.879] Timer tick, asking Buildbucket for the build status
[22:27:04.032] Build 8912182357694591888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:27:04.032] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077612420813685664:82:0) after 1m0s
[22:28:04.467] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077612420813685664:82:0)
[22:28:04.467] Timer tick, asking Buildbucket for the build status
[22:28:05.097] Build 8912182357694591888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:28:05.097] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077612420813685664:83:0) after 1m0s
[22:29:05.127] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077612420813685664:83:0)
[22:29:05.127] Timer tick, asking Buildbucket for the build status
[22:29:05.781] Build 8912182357694591888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:29:05.781] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077612420813685664:84:0) after 1m0s
[22:30:05.780] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077612420813685664:84:0)
[22:30:05.780] Timer tick, asking Buildbucket for the build status
[22:30:05.925] Build 8912182357694591888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:30:05.925] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077612420813685664:85:0) after 1m0s
[22:31:06.003] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077612420813685664:85:0)
[22:31:06.003] Timer tick, asking Buildbucket for the build status
[22:31:07.295] Build 8912182357694591888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:31:07.295] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077612420813685664:86:0) after 1m0s
[22:32:07.530] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077612420813685664:86:0)
[22:32:07.530] Timer tick, asking Buildbucket for the build status
[22:32:07.673] Build 8912182357694591888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:32:07.673] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077612420813685664:87:0) after 1m0s
[22:33:07.754] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077612420813685664:87:0)
[22:33:07.754] Timer tick, asking Buildbucket for the build status
[22:33:09.650] Build 8912182357694591888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:33:09.650] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077612420813685664:88:0) after 1m0s
[22:34:09.743] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077612420813685664:88:0)
[22:34:09.743] Timer tick, asking Buildbucket for the build status
[22:34:11.341] Build 8912182357694591888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:34:11.341] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077612420813685664:89:0) after 1m0s
[22:35:11.417] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077612420813685664:89:0)
[22:35:11.417] Timer tick, asking Buildbucket for the build status
[22:35:12.687] Build 8912182357694591888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:35:12.687] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077612420813685664:90:0) after 1m0s
[22:36:12.826] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077612420813685664:90:0)
[22:36:12.826] Timer tick, asking Buildbucket for the build status
[22:36:13.983] Build 8912182357694591888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:36:13.983] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077612420813685664:91:0) after 1m0s
[22:37:14.245] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077612420813685664:91:0)
[22:37:14.245] Timer tick, asking Buildbucket for the build status
[22:37:15.906] Build 8912182357694591888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:37:15.906] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077612420813685664:92:0) after 1m0s
[22:38:16.005] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077612420813685664:92:0)
[22:38:16.005] Timer tick, asking Buildbucket for the build status
[22:38:17.685] Build 8912182357694591888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:38:17.685] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077612420813685664:93:0) after 1m0s
[22:39:17.853] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077612420813685664:93:0)
[22:39:17.853] Timer tick, asking Buildbucket for the build status
[22:39:19.049] Build 8912182357694591888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:39:19.049] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077612420813685664:94:0) after 1m0s
[22:40:19.060] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077612420813685664:94:0)
[22:40:19.060] Timer tick, asking Buildbucket for the build status
[22:40:21.272] Build 8912182357694591888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:40:21.272] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077612420813685664:95:0) after 1m0s
[22:41:21.350] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077612420813685664:95:0)
[22:41:21.350] Timer tick, asking Buildbucket for the build status
[22:41:21.580] Build 8912182357694591888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:41:21.580] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077612420813685664:96:0) after 1m0s
[22:42:21.614] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077612420813685664:96:0)
[22:42:21.614] Timer tick, asking Buildbucket for the build status
[22:42:21.923] Build 8912182357694591888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:42:21.923] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077612420813685664:97:0) after 1m0s
[22:43:21.973] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077612420813685664:97:0)
[22:43:21.973] Timer tick, asking Buildbucket for the build status
[22:43:22.729] Build 8912182357694591888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:43:22.729] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077612420813685664:98:0) after 1m0s
[22:44:22.779] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077612420813685664:98:0)
[22:44:22.779] Timer tick, asking Buildbucket for the build status
[22:44:23.369] Build 8912182357694591888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:44:23.369] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077612420813685664:99:0) after 1m0s
[22:45:23.436] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077612420813685664:99:0)
[22:45:23.436] Timer tick, asking Buildbucket for the build status
[22:45:23.663] Build 8912182357694591888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:45:23.663] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077612420813685664:100:0) after 1m0s
[22:46:23.744] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077612420813685664:100:0)
[22:46:23.744] Timer tick, asking Buildbucket for the build status
[22:46:25.146] Build 8912182357694591888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:46:25.147] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077612420813685664:101:0) after 1m0s
[22:47:25.270] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077612420813685664:101:0)
[22:47:25.270] Timer tick, asking Buildbucket for the build status
[22:47:26.879] Build 8912182357694591888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:47:26.879] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077612420813685664:102:0) after 1m0s
[22:48:26.929] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077612420813685664:102:0)
[22:48:26.929] Timer tick, asking Buildbucket for the build status
[22:48:27.926] Build 8912182357694591888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:48:27.926] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077612420813685664:103:0) after 1m0s
[22:49:27.950] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077612420813685664:103:0)
[22:49:27.950] Timer tick, asking Buildbucket for the build status
[22:49:28.310] Build 8912182357694591888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:49:28.310] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077612420813685664:104:0) after 1m0s
[22:50:28.377] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077612420813685664:104:0)
[22:50:28.377] Timer tick, asking Buildbucket for the build status
[22:50:28.593] Build 8912182357694591888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:50:28.593] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077612420813685664:105:0) after 1m0s
[22:51:28.628] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077612420813685664:105:0)
[22:51:28.628] Timer tick, asking Buildbucket for the build status
[22:51:28.977] Build 8912182357694591888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:51:28.977] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077612420813685664:106:0) after 1m0s
[22:52:08.381] Received PubSub notification, asking Buildbucket for the build status
[22:52:08.737] Build 8912182357694591888: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[22:52:08.737] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"completed_ts": "1559083927331643",
"created_by": "project:chromium",
"created_ts": "1559077604545484",
"id": "8912182357694591888",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"c2d74c6cea694e55e5dbca3779bee1003c44e351\"}}",
"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-7052\", \"branch\": \"refs/heads/master\", \"buildername\": \"Windows deterministic\", \"buildnumber\": 13591, \"got_angle_revision\": \"5a808b86d8901b9cb7a4d174356c0c1b0e2fc0e3\", \"got_dawn_revision\": \"8b5fc36191d7bbe8da7af1b19d1cd509deda3880\", \"got_nacl_revision\": \"0ddc033406886a709b901e0c312872529f9705e8\", \"got_revision\": \"c2d74c6cea694e55e5dbca3779bee1003c44e351\", \"got_revision_cp\": \"refs/heads/master@{#663932}\", \"got_swarming_client_revision\": \"779c4f0f8488c64587b75dbb001d18c3c0c4cda9\", \"got_v8_revision\": \"bcf517c0366d4af6dfb600e5344ef37e65a09d0b\", \"got_v8_revision_cp\": \"refs/heads/7.6.302@{#1}\", \"got_webrtc_revision\": \"ce33b6a4cfa06ffa7faec4f33449569d6f319692\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#28090}\", \"mastername\": \"chromium.win\", \"path_config\": \"generic\", \"recipe\": \"swarming/deterministic_build\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"c2d74c6cea694e55e5dbca3779bee1003c44e351\"}, \"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-7052\"], \"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\": [\"4303-bae210e\"], \"zone\": [\"us\", \"us-west\", \"us-west1\", \"us-west1-c\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1559077614076258",
"status": "COMPLETED",
"status_changed_ts": "1559083927881186",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/13591",
"builder:Windows deterministic",
"buildset:commit/git/c2d74c6cea694e55e5dbca3779bee1003c44e351",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/c2d74c6cea694e55e5dbca3779bee1003c44e351",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9077612420813685664",
"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/8912182357694591888/+/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:451915e2015df810",
"user_agent:luci-scheduler"
],
"updated_ts": "1559083927887211",
"url": "https://ci.chromium.org/b/8912182357694591888",
"utcnow_ts": "1559083928727013"
}
}
[22:52:08.737] Invocation finished in 1h45m26.549933479s with status SUCCEEDED