[19:09:34.687] New invocation is queued and will start shortly
[19:09:35.820] Starting the invocation (attempt 1)
[19:09:35.865] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[19:09:35.865] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[19:09:35.865] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9084414562180141888",
"parameters_json": "{\"builder_name\":\"Windows deterministic\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src.git\",\"revision\":\"5cef02b5fd2a3b96b3e2d79f3a696541abb00c0c\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Windows deterministic",
"scheduler_invocation_id:9084414562180141888",
"scheduler_job_id:chromium/Windows deterministic",
"user_agent:luci-scheduler",
"buildset:commit/git/5cef02b5fd2a3b96b3e2d79f3a696541abb00c0c",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/5cef02b5fd2a3b96b3e2d79f3a696541abb00c0c",
"gitiles_ref:refs/heads/master"
]
}
[19:09:37.625] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"created_by": "project:chromium",
"created_ts": "1552590576136180",
"id": "8918984499995573200",
"lease_expiration_ts": "1552709377235114",
"lease_key": "1702353216",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"5cef02b5fd2a3b96b3e2d79f3a696541abb00c0c\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1552590577465379",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/12428",
"builder:Windows deterministic",
"buildset:commit/git/5cef02b5fd2a3b96b3e2d79f3a696541abb00c0c",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/5cef02b5fd2a3b96b3e2d79f3a696541abb00c0c",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9084414562180141888",
"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/8918984499995573200/+/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:43966da510396610",
"user_agent:luci-scheduler"
],
"updated_ts": "1552590577465498",
"url": "https://ci.chromium.org/b/8918984499995573200",
"utcnow_ts": "1552590577615714"
}
}
[19:09:37.625] Task URL: https://ci.chromium.org/b/8918984499995573200
[19:09:37.625] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084414562180141888:2:0) after 1m0s
[19:09:45.800] Received PubSub notification, asking Buildbucket for the build status
[19:09:45.899] Build 8918984499995573200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:10:37.644] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084414562180141888:2:0)
[19:10:37.644] Timer tick, asking Buildbucket for the build status
[19:10:37.767] Build 8918984499995573200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:10:37.767] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084414562180141888:4:0) after 1m0s
[19:11:37.744] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084414562180141888:4:0)
[19:11:37.744] Timer tick, asking Buildbucket for the build status
[19:11:38.171] Build 8918984499995573200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:11:38.171] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084414562180141888:5:0) after 1m0s
[19:12:38.197] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084414562180141888:5:0)
[19:12:38.197] Timer tick, asking Buildbucket for the build status
[19:12:38.296] Build 8918984499995573200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:12:38.296] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084414562180141888:6:0) after 1m0s
[19:13:38.314] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084414562180141888:6:0)
[19:13:38.314] Timer tick, asking Buildbucket for the build status
[19:13:38.424] Build 8918984499995573200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:13:38.424] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084414562180141888:7:0) after 1m0s
[19:14:38.446] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084414562180141888:7:0)
[19:14:38.446] Timer tick, asking Buildbucket for the build status
[19:14:38.533] Build 8918984499995573200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:14:38.533] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084414562180141888:8:0) after 1m0s
[19:15:38.553] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084414562180141888:8:0)
[19:15:38.553] Timer tick, asking Buildbucket for the build status
[19:15:39.256] Build 8918984499995573200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:15:39.256] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084414562180141888:9:0) after 1m0s
[19:16:39.277] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084414562180141888:9:0)
[19:16:39.277] Timer tick, asking Buildbucket for the build status
[19:16:39.610] Build 8918984499995573200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:16:39.610] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084414562180141888:10:0) after 1m0s
[19:17:39.630] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084414562180141888:10:0)
[19:17:39.630] Timer tick, asking Buildbucket for the build status
[19:17:40.158] Build 8918984499995573200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:17:40.158] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084414562180141888:11:0) after 1m0s
[19:18:40.155] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084414562180141888:11:0)
[19:18:40.155] Timer tick, asking Buildbucket for the build status
[19:18:40.377] Build 8918984499995573200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:18:40.377] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084414562180141888:12:0) after 1m0s
[19:19:40.403] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084414562180141888:12:0)
[19:19:40.403] Timer tick, asking Buildbucket for the build status
[19:19:40.479] Build 8918984499995573200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:19:40.479] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084414562180141888:13:0) after 1m0s
[19:20:40.470] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084414562180141888:13:0)
[19:20:40.470] Timer tick, asking Buildbucket for the build status
[19:20:40.616] Build 8918984499995573200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:20:40.616] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084414562180141888:14:0) after 1m0s
[19:21:40.612] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084414562180141888:14:0)
[19:21:40.612] Timer tick, asking Buildbucket for the build status
[19:21:41.166] Build 8918984499995573200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:21:41.166] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084414562180141888:15:0) after 1m0s
[19:22:41.159] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084414562180141888:15:0)
[19:22:41.159] Timer tick, asking Buildbucket for the build status
[19:22:41.413] Build 8918984499995573200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:22:41.413] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084414562180141888:16:0) after 1m0s
[19:23:41.387] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084414562180141888:16:0)
[19:23:41.387] Timer tick, asking Buildbucket for the build status
[19:23:41.541] Build 8918984499995573200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:23:41.541] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084414562180141888:17:0) after 1m0s
[19:24:41.564] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084414562180141888:17:0)
[19:24:41.564] Timer tick, asking Buildbucket for the build status
[19:24:41.729] Build 8918984499995573200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:24:41.729] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084414562180141888:18:0) after 1m0s
[19:25:41.757] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084414562180141888:18:0)
[19:25:41.757] Timer tick, asking Buildbucket for the build status
[19:25:42.590] Build 8918984499995573200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:25:42.590] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084414562180141888:19:0) after 1m0s
[19:26:42.588] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084414562180141888:19:0)
[19:26:42.588] Timer tick, asking Buildbucket for the build status
[19:26:42.741] Build 8918984499995573200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:26:42.741] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084414562180141888:20:0) after 1m0s
[19:27:42.760] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084414562180141888:20:0)
[19:27:42.760] Timer tick, asking Buildbucket for the build status
[19:27:43.138] Build 8918984499995573200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:27:43.138] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084414562180141888:21:0) after 1m0s
[19:28:43.186] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084414562180141888:21:0)
[19:28:43.186] Timer tick, asking Buildbucket for the build status
[19:28:43.324] Build 8918984499995573200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:28:43.324] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084414562180141888:22:0) after 1m0s
[19:29:43.346] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084414562180141888:22:0)
[19:29:43.346] Timer tick, asking Buildbucket for the build status
[19:29:43.747] Build 8918984499995573200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:29:43.747] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084414562180141888:23:0) after 1m0s
[19:30:43.725] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084414562180141888:23:0)
[19:30:43.725] Timer tick, asking Buildbucket for the build status
[19:30:44.308] Build 8918984499995573200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:30:44.308] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084414562180141888:24:0) after 1m0s
[19:31:44.285] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084414562180141888:24:0)
[19:31:44.285] Timer tick, asking Buildbucket for the build status
[19:31:44.394] Build 8918984499995573200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:31:44.394] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084414562180141888:25:0) after 1m0s
[19:32:44.414] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084414562180141888:25:0)
[19:32:44.414] Timer tick, asking Buildbucket for the build status
[19:32:44.582] Build 8918984499995573200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:32:44.582] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084414562180141888:26:0) after 1m0s
[19:33:44.595] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084414562180141888:26:0)
[19:33:44.595] Timer tick, asking Buildbucket for the build status
[19:33:44.681] Build 8918984499995573200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:33:44.681] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084414562180141888:27:0) after 1m0s
[19:34:44.693] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084414562180141888:27:0)
[19:34:44.693] Timer tick, asking Buildbucket for the build status
[19:34:44.784] Build 8918984499995573200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:34:44.784] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084414562180141888:28:0) after 1m0s
[19:35:44.806] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084414562180141888:28:0)
[19:35:44.806] Timer tick, asking Buildbucket for the build status
[19:35:44.902] Build 8918984499995573200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:35:44.902] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084414562180141888:29:0) after 1m0s
[19:36:44.934] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084414562180141888:29:0)
[19:36:44.934] Timer tick, asking Buildbucket for the build status
[19:36:45.836] Build 8918984499995573200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:36:45.836] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084414562180141888:30:0) after 1m0s
[19:37:45.849] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084414562180141888:30:0)
[19:37:45.849] Timer tick, asking Buildbucket for the build status
[19:37:46.387] Build 8918984499995573200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:37:46.387] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084414562180141888:31:0) after 1m0s
[19:38:46.407] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084414562180141888:31:0)
[19:38:46.407] Timer tick, asking Buildbucket for the build status
[19:38:46.610] Build 8918984499995573200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:38:46.610] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084414562180141888:32:0) after 1m0s
[19:39:46.609] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084414562180141888:32:0)
[19:39:46.609] Timer tick, asking Buildbucket for the build status
[19:39:46.700] Build 8918984499995573200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:39:46.700] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084414562180141888:33:0) after 1m0s
[19:40:46.732] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084414562180141888:33:0)
[19:40:46.732] Timer tick, asking Buildbucket for the build status
[19:40:46.875] Build 8918984499995573200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:40:46.875] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084414562180141888:34:0) after 1m0s
[19:41:46.850] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084414562180141888:34:0)
[19:41:46.850] Timer tick, asking Buildbucket for the build status
[19:41:46.984] Build 8918984499995573200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:41:46.984] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084414562180141888:35:0) after 1m0s
[19:42:47.003] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084414562180141888:35:0)
[19:42:47.003] Timer tick, asking Buildbucket for the build status
[19:42:47.799] Build 8918984499995573200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:42:47.799] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084414562180141888:36:0) after 1m0s
[19:43:47.812] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084414562180141888:36:0)
[19:43:47.812] Timer tick, asking Buildbucket for the build status
[19:43:47.957] Build 8918984499995573200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:43:47.957] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084414562180141888:37:0) after 1m0s
[19:44:47.967] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084414562180141888:37:0)
[19:44:47.967] Timer tick, asking Buildbucket for the build status
[19:44:48.054] Build 8918984499995573200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:44:48.054] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084414562180141888:38:0) after 1m0s
[19:45:48.081] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084414562180141888:38:0)
[19:45:48.081] Timer tick, asking Buildbucket for the build status
[19:45:48.490] Build 8918984499995573200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:45:48.490] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084414562180141888:39:0) after 1m0s
[19:46:48.500] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084414562180141888:39:0)
[19:46:48.500] Timer tick, asking Buildbucket for the build status
[19:46:48.664] Build 8918984499995573200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:46:48.664] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084414562180141888:40:0) after 1m0s
[19:47:48.664] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084414562180141888:40:0)
[19:47:48.664] Timer tick, asking Buildbucket for the build status
[19:47:49.015] Build 8918984499995573200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:47:49.015] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084414562180141888:41:0) after 1m0s
[19:48:49.041] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084414562180141888:41:0)
[19:48:49.041] Timer tick, asking Buildbucket for the build status
[19:48:49.143] Build 8918984499995573200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:48:49.143] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084414562180141888:42:0) after 1m0s
[19:49:49.142] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084414562180141888:42:0)
[19:49:49.142] Timer tick, asking Buildbucket for the build status
[19:49:49.272] Build 8918984499995573200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:49:49.272] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084414562180141888:43:0) after 1m0s
[19:50:49.286] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084414562180141888:43:0)
[19:50:49.286] Timer tick, asking Buildbucket for the build status
[19:50:49.692] Build 8918984499995573200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:50:49.692] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084414562180141888:44:0) after 1m0s
[19:51:49.703] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084414562180141888:44:0)
[19:51:49.703] Timer tick, asking Buildbucket for the build status
[19:51:49.830] Build 8918984499995573200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:51:49.830] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084414562180141888:45:0) after 1m0s
[19:52:49.872] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084414562180141888:45:0)
[19:52:49.872] Timer tick, asking Buildbucket for the build status
[19:52:50.552] Build 8918984499995573200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:52:50.552] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084414562180141888:46:0) after 1m0s
[19:53:50.573] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084414562180141888:46:0)
[19:53:50.573] Timer tick, asking Buildbucket for the build status
[19:53:51.045] Build 8918984499995573200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:53:51.045] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084414562180141888:47:0) after 1m0s
[19:54:51.044] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084414562180141888:47:0)
[19:54:51.044] Timer tick, asking Buildbucket for the build status
[19:54:51.828] Build 8918984499995573200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:54:51.828] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084414562180141888:48:0) after 1m0s
[19:55:51.857] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084414562180141888:48:0)
[19:55:51.857] Timer tick, asking Buildbucket for the build status
[19:55:51.956] Build 8918984499995573200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:55:51.956] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084414562180141888:49:0) after 1m0s
[19:56:51.979] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084414562180141888:49:0)
[19:56:51.979] Timer tick, asking Buildbucket for the build status
[19:56:52.123] Build 8918984499995573200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:56:52.123] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084414562180141888:50:0) after 1m0s
[19:57:52.101] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084414562180141888:50:0)
[19:57:52.101] Timer tick, asking Buildbucket for the build status
[19:57:52.384] Build 8918984499995573200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:57:52.385] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084414562180141888:51:0) after 1m0s
[19:58:52.407] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084414562180141888:51:0)
[19:58:52.407] Timer tick, asking Buildbucket for the build status
[19:58:52.524] Build 8918984499995573200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:58:52.524] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084414562180141888:52:0) after 1m0s
[19:59:52.514] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084414562180141888:52:0)
[19:59:52.514] Timer tick, asking Buildbucket for the build status
[19:59:52.656] Build 8918984499995573200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:59:52.656] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084414562180141888:53:0) after 1m0s
[20:00:52.678] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084414562180141888:53:0)
[20:00:52.678] Timer tick, asking Buildbucket for the build status
[20:00:52.782] Build 8918984499995573200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:00:52.782] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084414562180141888:54:0) after 1m0s
[20:01:52.807] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084414562180141888:54:0)
[20:01:52.807] Timer tick, asking Buildbucket for the build status
[20:01:52.943] Build 8918984499995573200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:01:52.943] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084414562180141888:55:0) after 1m0s
[20:02:52.964] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084414562180141888:55:0)
[20:02:52.964] Timer tick, asking Buildbucket for the build status
[20:02:53.068] Build 8918984499995573200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:02:53.068] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084414562180141888:56:0) after 1m0s
[20:03:53.053] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084414562180141888:56:0)
[20:03:53.053] Timer tick, asking Buildbucket for the build status
[20:03:53.222] Build 8918984499995573200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:03:53.222] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084414562180141888:57:0) after 1m0s
[20:04:53.259] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084414562180141888:57:0)
[20:04:53.259] Timer tick, asking Buildbucket for the build status
[20:04:53.411] Build 8918984499995573200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:04:53.411] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084414562180141888:58:0) after 1m0s
[20:05:53.431] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084414562180141888:58:0)
[20:05:53.431] Timer tick, asking Buildbucket for the build status
[20:05:53.513] Build 8918984499995573200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:05:53.513] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084414562180141888:59:0) after 1m0s
[20:06:53.511] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084414562180141888:59:0)
[20:06:53.511] Timer tick, asking Buildbucket for the build status
[20:06:53.775] Build 8918984499995573200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:06:53.775] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084414562180141888:60:0) after 1m0s
[20:07:53.780] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084414562180141888:60:0)
[20:07:53.780] Timer tick, asking Buildbucket for the build status
[20:07:53.906] Build 8918984499995573200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:07:53.906] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084414562180141888:61:0) after 1m0s
[20:08:53.994] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084414562180141888:61:0)
[20:08:53.994] Timer tick, asking Buildbucket for the build status
[20:08:54.113] Build 8918984499995573200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:08:54.113] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084414562180141888:62:0) after 1m0s
[20:09:54.138] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084414562180141888:62:0)
[20:09:54.138] Timer tick, asking Buildbucket for the build status
[20:09:54.266] Build 8918984499995573200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:09:54.266] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084414562180141888:63:0) after 1m0s
[20:10:54.290] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084414562180141888:63:0)
[20:10:54.290] Timer tick, asking Buildbucket for the build status
[20:10:54.500] Build 8918984499995573200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:10:54.500] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084414562180141888:64:0) after 1m0s
[20:11:54.522] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084414562180141888:64:0)
[20:11:54.522] Timer tick, asking Buildbucket for the build status
[20:11:54.622] Build 8918984499995573200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:11:54.622] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084414562180141888:65:0) after 1m0s
[20:12:54.637] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084414562180141888:65:0)
[20:12:54.637] Timer tick, asking Buildbucket for the build status
[20:12:54.778] Build 8918984499995573200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:12:54.778] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084414562180141888:66:0) after 1m0s
[20:13:54.807] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084414562180141888:66:0)
[20:13:54.807] Timer tick, asking Buildbucket for the build status
[20:13:55.095] Build 8918984499995573200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:13:55.095] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084414562180141888:67:0) after 1m0s
[20:14:55.147] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084414562180141888:67:0)
[20:14:55.147] Timer tick, asking Buildbucket for the build status
[20:14:55.533] Build 8918984499995573200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:14:55.533] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084414562180141888:68:0) after 1m0s
[20:15:55.518] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084414562180141888:68:0)
[20:15:55.518] Timer tick, asking Buildbucket for the build status
[20:15:55.610] Build 8918984499995573200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:15:55.610] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084414562180141888:69:0) after 1m0s
[20:16:55.640] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084414562180141888:69:0)
[20:16:55.640] Timer tick, asking Buildbucket for the build status
[20:16:56.139] Build 8918984499995573200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:16:56.139] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084414562180141888:70:0) after 1m0s
[20:17:56.150] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084414562180141888:70:0)
[20:17:56.150] Timer tick, asking Buildbucket for the build status
[20:17:56.324] Build 8918984499995573200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:17:56.324] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084414562180141888:71:0) after 1m0s
[20:18:56.326] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084414562180141888:71:0)
[20:18:56.326] Timer tick, asking Buildbucket for the build status
[20:18:56.462] Build 8918984499995573200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:18:56.462] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084414562180141888:72:0) after 1m0s
[20:19:56.483] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084414562180141888:72:0)
[20:19:56.483] Timer tick, asking Buildbucket for the build status
[20:19:58.252] Build 8918984499995573200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:19:58.252] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084414562180141888:73:0) after 1m0s
[20:20:58.284] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084414562180141888:73:0)
[20:20:58.284] Timer tick, asking Buildbucket for the build status
[20:20:59.007] Build 8918984499995573200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:20:59.007] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084414562180141888:74:0) after 1m0s
[20:21:59.007] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084414562180141888:74:0)
[20:21:59.007] Timer tick, asking Buildbucket for the build status
[20:21:59.136] Build 8918984499995573200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:21:59.136] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084414562180141888:75:0) after 1m0s
[20:22:59.144] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084414562180141888:75:0)
[20:22:59.144] Timer tick, asking Buildbucket for the build status
[20:22:59.278] Build 8918984499995573200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:22:59.278] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084414562180141888:76:0) after 1m0s
[20:23:59.376] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084414562180141888:76:0)
[20:23:59.376] Timer tick, asking Buildbucket for the build status
[20:23:59.497] Build 8918984499995573200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:23:59.497] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084414562180141888:77:0) after 1m0s
[20:24:59.511] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084414562180141888:77:0)
[20:24:59.511] Timer tick, asking Buildbucket for the build status
[20:24:59.600] Build 8918984499995573200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:24:59.600] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084414562180141888:78:0) after 1m0s
[20:25:59.600] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084414562180141888:78:0)
[20:25:59.600] Timer tick, asking Buildbucket for the build status
[20:25:59.712] Build 8918984499995573200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:25:59.712] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084414562180141888:79:0) after 1m0s
[20:26:59.702] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084414562180141888:79:0)
[20:26:59.702] Timer tick, asking Buildbucket for the build status
[20:26:59.961] Build 8918984499995573200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:26:59.961] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084414562180141888:80:0) after 1m0s
[20:27:59.985] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084414562180141888:80:0)
[20:27:59.985] Timer tick, asking Buildbucket for the build status
[20:28:00.116] Build 8918984499995573200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:28:00.116] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084414562180141888:81:0) after 1m0s
[20:29:00.140] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084414562180141888:81:0)
[20:29:00.140] Timer tick, asking Buildbucket for the build status
[20:29:00.234] Build 8918984499995573200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:29:00.234] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084414562180141888:82:0) after 1m0s
[20:30:00.224] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084414562180141888:82:0)
[20:30:00.224] Timer tick, asking Buildbucket for the build status
[20:30:00.312] Build 8918984499995573200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:30:00.312] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084414562180141888:83:0) after 1m0s
[20:31:00.347] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084414562180141888:83:0)
[20:31:00.347] Timer tick, asking Buildbucket for the build status
[20:31:00.789] Build 8918984499995573200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:31:00.789] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084414562180141888:84:0) after 1m0s
[20:32:00.811] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084414562180141888:84:0)
[20:32:00.811] Timer tick, asking Buildbucket for the build status
[20:32:01.178] Build 8918984499995573200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:32:01.178] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084414562180141888:85:0) after 1m0s
[20:33:01.206] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084414562180141888:85:0)
[20:33:01.206] Timer tick, asking Buildbucket for the build status
[20:33:01.309] Build 8918984499995573200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:33:01.309] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084414562180141888:86:0) after 1m0s
[20:34:01.335] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084414562180141888:86:0)
[20:34:01.335] Timer tick, asking Buildbucket for the build status
[20:34:01.683] Build 8918984499995573200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:34:01.683] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084414562180141888:87:0) after 1m0s
[20:35:01.704] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084414562180141888:87:0)
[20:35:01.704] Timer tick, asking Buildbucket for the build status
[20:35:05.964] Build 8918984499995573200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:35:05.964] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084414562180141888:88:0) after 1m0s
[20:36:05.984] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084414562180141888:88:0)
[20:36:05.984] Timer tick, asking Buildbucket for the build status
[20:36:06.161] Build 8918984499995573200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:36:06.161] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084414562180141888:89:0) after 1m0s
[20:37:06.191] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084414562180141888:89:0)
[20:37:06.192] Timer tick, asking Buildbucket for the build status
[20:37:06.301] Build 8918984499995573200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:37:06.301] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084414562180141888:90:0) after 1m0s
[20:38:06.312] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084414562180141888:90:0)
[20:38:06.312] Timer tick, asking Buildbucket for the build status
[20:38:06.405] Build 8918984499995573200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:38:06.405] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084414562180141888:91:0) after 1m0s
[20:39:06.433] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084414562180141888:91:0)
[20:39:06.433] Timer tick, asking Buildbucket for the build status
[20:39:06.648] Build 8918984499995573200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:39:06.648] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084414562180141888:92:0) after 1m0s
[20:40:06.653] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084414562180141888:92:0)
[20:40:06.653] Timer tick, asking Buildbucket for the build status
[20:40:07.174] Build 8918984499995573200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:40:07.174] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084414562180141888:93:0) after 1m0s
[20:41:07.183] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084414562180141888:93:0)
[20:41:07.183] Timer tick, asking Buildbucket for the build status
[20:41:07.447] Build 8918984499995573200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:41:07.447] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084414562180141888:94:0) after 1m0s
[20:42:07.476] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084414562180141888:94:0)
[20:42:07.476] Timer tick, asking Buildbucket for the build status
[20:42:07.570] Build 8918984499995573200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:42:07.570] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084414562180141888:95:0) after 1m0s
[20:43:07.553] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084414562180141888:95:0)
[20:43:07.553] Timer tick, asking Buildbucket for the build status
[20:43:07.661] Build 8918984499995573200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:43:07.661] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084414562180141888:96:0) after 1m0s
[20:44:07.652] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084414562180141888:96:0)
[20:44:07.652] Timer tick, asking Buildbucket for the build status
[20:44:07.879] Build 8918984499995573200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:44:07.879] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084414562180141888:97:0) after 1m0s
[20:45:07.906] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084414562180141888:97:0)
[20:45:07.906] Timer tick, asking Buildbucket for the build status
[20:45:08.013] Build 8918984499995573200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:45:08.013] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084414562180141888:98:0) after 1m0s
[20:46:08.008] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084414562180141888:98:0)
[20:46:08.008] Timer tick, asking Buildbucket for the build status
[20:46:08.183] Build 8918984499995573200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:46:08.183] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084414562180141888:99:0) after 1m0s
[20:47:08.233] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084414562180141888:99:0)
[20:47:08.233] Timer tick, asking Buildbucket for the build status
[20:47:08.275] Build 8918984499995573200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:47:08.275] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084414562180141888:100:0) after 1m0s
[20:48:08.279] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084414562180141888:100:0)
[20:48:08.279] Timer tick, asking Buildbucket for the build status
[20:48:08.342] Build 8918984499995573200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:48:08.342] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084414562180141888:101:0) after 1m0s
[20:49:08.388] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084414562180141888:101:0)
[20:49:08.388] Timer tick, asking Buildbucket for the build status
[20:49:08.427] Build 8918984499995573200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:49:08.427] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084414562180141888:102:0) after 1m0s
[20:50:08.459] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084414562180141888:102:0)
[20:50:08.459] Timer tick, asking Buildbucket for the build status
[20:50:08.594] Build 8918984499995573200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:50:08.594] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084414562180141888:103:0) after 1m0s
[20:51:08.619] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084414562180141888:103:0)
[20:51:08.619] Timer tick, asking Buildbucket for the build status
[20:51:08.717] Build 8918984499995573200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:51:08.717] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084414562180141888:104:0) after 1m0s
[20:52:08.699] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084414562180141888:104:0)
[20:52:08.699] Timer tick, asking Buildbucket for the build status
[20:52:08.737] Build 8918984499995573200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:52:08.737] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084414562180141888:105:0) after 1m0s
[20:53:08.831] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084414562180141888:105:0)
[20:53:08.831] Timer tick, asking Buildbucket for the build status
[20:53:08.932] Build 8918984499995573200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:53:08.932] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084414562180141888:106:0) after 1m0s
[20:54:08.984] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084414562180141888:106:0)
[20:54:08.984] Timer tick, asking Buildbucket for the build status
[20:54:09.075] Build 8918984499995573200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:54:09.075] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084414562180141888:107:0) after 1m0s
[20:55:09.108] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084414562180141888:107:0)
[20:55:09.109] Timer tick, asking Buildbucket for the build status
[20:55:09.152] Build 8918984499995573200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:55:09.152] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084414562180141888:108:0) after 1m0s
[20:56:09.179] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084414562180141888:108:0)
[20:56:09.179] Timer tick, asking Buildbucket for the build status
[20:56:09.225] Build 8918984499995573200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:56:09.225] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084414562180141888:109:0) after 1m0s
[20:57:09.253] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084414562180141888:109:0)
[20:57:09.253] Timer tick, asking Buildbucket for the build status
[20:57:09.292] Build 8918984499995573200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:57:09.292] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084414562180141888:110:0) after 1m0s
[20:58:09.322] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084414562180141888:110:0)
[20:58:09.322] Timer tick, asking Buildbucket for the build status
[20:58:09.412] Build 8918984499995573200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:58:09.412] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084414562180141888:111:0) after 1m0s
[20:59:09.443] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084414562180141888:111:0)
[20:59:09.443] Timer tick, asking Buildbucket for the build status
[20:59:09.487] Build 8918984499995573200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:59:09.487] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084414562180141888:112:0) after 1m0s
[21:00:09.506] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084414562180141888:112:0)
[21:00:09.506] Timer tick, asking Buildbucket for the build status
[21:00:09.549] Build 8918984499995573200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:00:09.549] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084414562180141888:113:0) after 1m0s
[21:01:09.552] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084414562180141888:113:0)
[21:01:09.552] Timer tick, asking Buildbucket for the build status
[21:01:09.686] Build 8918984499995573200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:01:09.686] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084414562180141888:114:0) after 1m0s
[21:02:09.698] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084414562180141888:114:0)
[21:02:09.698] Timer tick, asking Buildbucket for the build status
[21:02:09.966] Build 8918984499995573200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:02:09.966] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084414562180141888:115:0) after 1m0s
[21:03:09.997] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084414562180141888:115:0)
[21:03:09.997] Timer tick, asking Buildbucket for the build status
[21:03:10.194] Build 8918984499995573200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:03:10.194] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084414562180141888:116:0) after 1m0s
[21:04:10.183] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084414562180141888:116:0)
[21:04:10.183] Timer tick, asking Buildbucket for the build status
[21:04:10.226] Build 8918984499995573200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:04:10.226] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084414562180141888:117:0) after 1m0s
[21:05:10.240] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084414562180141888:117:0)
[21:05:10.240] Timer tick, asking Buildbucket for the build status
[21:05:10.376] Build 8918984499995573200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:05:10.376] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084414562180141888:118:0) after 1m0s
[21:06:10.410] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084414562180141888:118:0)
[21:06:10.410] Timer tick, asking Buildbucket for the build status
[21:06:10.477] Build 8918984499995573200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:06:10.477] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084414562180141888:119:0) after 1m0s
[21:07:10.501] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084414562180141888:119:0)
[21:07:10.501] Timer tick, asking Buildbucket for the build status
[21:07:10.539] Build 8918984499995573200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:07:10.539] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084414562180141888:120:0) after 1m0s
[21:07:38.075] Received PubSub notification, asking Buildbucket for the build status
[21:07:38.111] Build 8918984499995573200: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[21:07:38.111] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"completed_ts": "1552597656980855",
"created_by": "project:chromium",
"created_ts": "1552590576136180",
"id": "8918984499995573200",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"5cef02b5fd2a3b96b3e2d79f3a696541abb00c0c\"}}",
"project": "chromium",
"result": "SUCCESS",
"result_details_json": "{\"swarming\": {\"bot_dimensions\": {\"gce\": [\"1\"], \"machine_type\": [\"n1-standard-8\"], \"zone\": [\"us\", \"us-west\", \"us-west1\", \"us-west1-c\"], \"locale\": [\"en_US.cp1252\"], \"os\": [\"Windows\", \"Windows-10\", \"Windows-10-15063\"], \"builder\": [\"Windows deterministic\"], \"id\": [\"windows-deterministic-0-v3qq\"], \"python\": [\"2.7.13\"], \"server_version\": [\"4200-0235b36\"], \"image\": [\"chrome-win10-1703-2018-04-20-61acaf8983f\"], \"gpu\": [\"none\"], \"cores\": [\"8\"], \"integrity\": [\"high\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\"], \"pool\": [\"luci.chromium.ci\"], \"caches\": [\"builder_e8fffaf9fac16164b1ec16ebaa1f377b792d39a582f7640830518d8ff7303d0c_v2\", \"git\", \"goma_v2\", \"vpython\"]}}, \"ui\": {\"info\": \"\"}, \"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}, \"got_nacl_revision\": \"1ed9878b8eb68c1e0d0268754dafcf83e747fa8f\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#27121}\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"buildername\": \"Windows deterministic\", \"got_webrtc_revision\": \"d841ea6b586a81065f78d3eae7ab82c505cda1d8\", \"got_revision\": \"5cef02b5fd2a3b96b3e2d79f3a696541abb00c0c\", \"got_swarming_client_revision\": \"7a61cf37d6a0163f0ec02d495289a1d038e62457\", \"recipe\": \"swarming/deterministic_build\", \"mastername\": \"chromium.win\", \"got_angle_revision\": \"094c40dce6015add9e9524364a4e9b0523b7c31e\", \"got_v8_revision\": \"72287902200674b17fbc4557a6a9fb6c67f0542c\", \"got_revision_cp\": \"refs/heads/master@{#640862}\", \"got_dawn_revision\": \"5625b63202797c54ff1f5a40d9ee664ca23cfce8\", \"branch\": \"refs/heads/master\", \"buildnumber\": 12428, \"path_config\": \"generic\", \"revision\": \"5cef02b5fd2a3b96b3e2d79f3a696541abb00c0c\", \"got_v8_revision_cp\": \"refs/heads/7.5.42@{#1}\", \"bot_id\": \"windows-deterministic-0-v3qq\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1552590585113834",
"status": "COMPLETED",
"status_changed_ts": "1552597657695541",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/12428",
"builder:Windows deterministic",
"buildset:commit/git/5cef02b5fd2a3b96b3e2d79f3a696541abb00c0c",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/5cef02b5fd2a3b96b3e2d79f3a696541abb00c0c",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9084414562180141888",
"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/8918984499995573200/+/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:43966da510396610",
"user_agent:luci-scheduler"
],
"updated_ts": "1552597657712188",
"url": "https://ci.chromium.org/b/8918984499995573200",
"utcnow_ts": "1552597658101175"
}
}
[21:07:38.111] Invocation finished in 1h58m3.441873796s with status SUCCEEDED