[09:46:40.203] New invocation is queued and will start shortly
[09:46:41.442] Starting the invocation (attempt 1)
[09:46:41.482] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[09:46:41.483] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[09:46:41.483] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9087077289317894048",
"parameters_json": "{\"builder_name\":\"Windows deterministic\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src.git\",\"revision\":\"d7f846f40773668d6e3d2f4861efd02f31e9e890\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Windows deterministic",
"scheduler_invocation_id:9087077289317894048",
"scheduler_job_id:chromium/Windows deterministic",
"user_agent:luci-scheduler",
"buildset:commit/git/d7f846f40773668d6e3d2f4861efd02f31e9e890",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/d7f846f40773668d6e3d2f4861efd02f31e9e890",
"gitiles_ref:refs/heads/master"
]
}
[09:46:43.138] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1550051201568404",
"id": "8921647227222968384",
"lease_expiration_ts": "1550170002793676",
"lease_key": "1532493769",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"d7f846f40773668d6e3d2f4861efd02f31e9e890\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1550051203019952",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/11952",
"builder:Windows deterministic",
"buildset:commit/git/d7f846f40773668d6e3d2f4861efd02f31e9e890",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/d7f846f40773668d6e3d2f4861efd02f31e9e890",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9087077289317894048",
"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/8921647227222968384/+/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:42ff11de08667310",
"user_agent:luci-scheduler"
],
"updated_ts": "1550051203020094",
"url": "https://ci.chromium.org/b/8921647227222968384",
"utcnow_ts": "1550051203127842"
}
}
[09:46:43.138] Task URL: https://ci.chromium.org/b/8921647227222968384
[09:46:43.138] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087077289317894048:2:0) after 1m0s
[09:46:45.041] Received PubSub notification, asking Buildbucket for the build status
[09:46:45.097] Build 8921647227222968384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:47:43.158] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087077289317894048:2:0)
[09:47:43.158] Timer tick, asking Buildbucket for the build status
[09:47:43.327] Build 8921647227222968384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:47:43.327] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087077289317894048:4:0) after 1m0s
[09:48:43.344] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087077289317894048:4:0)
[09:48:43.344] Timer tick, asking Buildbucket for the build status
[09:48:43.504] Build 8921647227222968384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:48:43.504] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087077289317894048:5:0) after 1m0s
[09:49:43.521] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087077289317894048:5:0)
[09:49:43.521] Timer tick, asking Buildbucket for the build status
[09:49:43.561] Build 8921647227222968384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:49:43.561] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087077289317894048:6:0) after 1m0s
[09:50:43.576] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087077289317894048:6:0)
[09:50:43.576] Timer tick, asking Buildbucket for the build status
[09:50:43.694] Build 8921647227222968384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:50:43.694] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087077289317894048:7:0) after 1m0s
[09:51:43.710] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087077289317894048:7:0)
[09:51:43.710] Timer tick, asking Buildbucket for the build status
[09:51:43.824] Build 8921647227222968384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:51:43.824] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087077289317894048:8:0) after 1m0s
[09:52:43.854] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087077289317894048:8:0)
[09:52:43.854] Timer tick, asking Buildbucket for the build status
[09:52:43.910] Build 8921647227222968384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:52:43.910] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087077289317894048:9:0) after 1m0s
[09:53:43.960] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087077289317894048:9:0)
[09:53:43.960] Timer tick, asking Buildbucket for the build status
[09:53:44.028] Build 8921647227222968384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:53:44.028] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087077289317894048:10:0) after 1m0s
[09:54:44.096] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087077289317894048:10:0)
[09:54:44.096] Timer tick, asking Buildbucket for the build status
[09:54:44.151] Build 8921647227222968384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:54:44.151] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087077289317894048:11:0) after 1m0s
[09:55:44.226] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087077289317894048:11:0)
[09:55:44.226] Timer tick, asking Buildbucket for the build status
[09:55:44.308] Build 8921647227222968384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:55:44.309] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087077289317894048:12:0) after 1m0s
[09:56:44.325] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087077289317894048:12:0)
[09:56:44.325] Timer tick, asking Buildbucket for the build status
[09:56:44.403] Build 8921647227222968384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:56:44.403] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087077289317894048:13:0) after 1m0s
[09:57:44.427] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087077289317894048:13:0)
[09:57:44.427] Timer tick, asking Buildbucket for the build status
[09:57:44.499] Build 8921647227222968384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:57:44.499] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087077289317894048:14:0) after 1m0s
[09:58:44.515] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087077289317894048:14:0)
[09:58:44.515] Timer tick, asking Buildbucket for the build status
[09:58:44.572] Build 8921647227222968384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:58:44.572] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087077289317894048:15:0) after 1m0s
[09:59:44.687] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087077289317894048:15:0)
[09:59:44.687] Timer tick, asking Buildbucket for the build status
[09:59:44.853] Build 8921647227222968384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:59:44.853] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087077289317894048:16:0) after 1m0s
[10:00:44.871] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087077289317894048:16:0)
[10:00:44.871] Timer tick, asking Buildbucket for the build status
[10:00:44.985] Build 8921647227222968384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:00:44.985] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087077289317894048:17:0) after 1m0s
[10:01:45.003] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087077289317894048:17:0)
[10:01:45.003] Timer tick, asking Buildbucket for the build status
[10:01:45.134] Build 8921647227222968384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:01:45.134] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087077289317894048:18:0) after 1m0s
[10:02:45.153] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087077289317894048:18:0)
[10:02:45.153] Timer tick, asking Buildbucket for the build status
[10:02:45.266] Build 8921647227222968384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:02:45.266] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087077289317894048:19:0) after 1m0s
[10:03:45.285] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087077289317894048:19:0)
[10:03:45.285] Timer tick, asking Buildbucket for the build status
[10:03:45.446] Build 8921647227222968384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:03:45.446] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087077289317894048:20:0) after 1m0s
[10:04:45.479] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087077289317894048:20:0)
[10:04:45.479] Timer tick, asking Buildbucket for the build status
[10:04:45.524] Build 8921647227222968384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:04:45.524] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087077289317894048:21:0) after 1m0s
[10:05:45.546] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087077289317894048:21:0)
[10:05:45.546] Timer tick, asking Buildbucket for the build status
[10:05:45.719] Build 8921647227222968384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:05:45.719] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087077289317894048:22:0) after 1m0s
[10:06:45.744] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087077289317894048:22:0)
[10:06:45.744] Timer tick, asking Buildbucket for the build status
[10:06:45.831] Build 8921647227222968384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:06:45.831] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087077289317894048:23:0) after 1m0s
[10:07:45.850] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087077289317894048:23:0)
[10:07:45.850] Timer tick, asking Buildbucket for the build status
[10:07:45.916] Build 8921647227222968384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:07:45.916] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087077289317894048:24:0) after 1m0s
[10:08:45.990] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087077289317894048:24:0)
[10:08:45.990] Timer tick, asking Buildbucket for the build status
[10:08:46.158] Build 8921647227222968384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:08:46.158] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087077289317894048:25:0) after 1m0s
[10:09:46.176] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087077289317894048:25:0)
[10:09:46.176] Timer tick, asking Buildbucket for the build status
[10:09:46.311] Build 8921647227222968384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:09:46.311] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087077289317894048:26:0) after 1m0s
[10:10:46.330] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087077289317894048:26:0)
[10:10:46.330] Timer tick, asking Buildbucket for the build status
[10:10:46.482] Build 8921647227222968384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:10:46.482] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087077289317894048:27:0) after 1m0s
[10:11:46.499] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087077289317894048:27:0)
[10:11:46.499] Timer tick, asking Buildbucket for the build status
[10:11:46.681] Build 8921647227222968384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:11:46.681] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087077289317894048:28:0) after 1m0s
[10:12:46.711] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087077289317894048:28:0)
[10:12:46.711] Timer tick, asking Buildbucket for the build status
[10:12:46.762] Build 8921647227222968384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:12:46.762] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087077289317894048:29:0) after 1m0s
[10:13:46.799] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087077289317894048:29:0)
[10:13:46.799] Timer tick, asking Buildbucket for the build status
[10:13:46.877] Build 8921647227222968384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:13:46.877] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087077289317894048:30:0) after 1m0s
[10:14:46.897] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087077289317894048:30:0)
[10:14:46.897] Timer tick, asking Buildbucket for the build status
[10:14:46.946] Build 8921647227222968384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:14:46.946] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087077289317894048:31:0) after 1m0s
[10:15:46.965] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087077289317894048:31:0)
[10:15:46.965] Timer tick, asking Buildbucket for the build status
[10:15:47.032] Build 8921647227222968384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:15:47.032] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087077289317894048:32:0) after 1m0s
[10:16:47.050] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087077289317894048:32:0)
[10:16:47.050] Timer tick, asking Buildbucket for the build status
[10:16:47.096] Build 8921647227222968384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:16:47.096] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087077289317894048:33:0) after 1m0s
[10:17:47.118] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087077289317894048:33:0)
[10:17:47.118] Timer tick, asking Buildbucket for the build status
[10:17:47.210] Build 8921647227222968384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:17:47.210] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087077289317894048:34:0) after 1m0s
[10:18:47.261] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087077289317894048:34:0)
[10:18:47.261] Timer tick, asking Buildbucket for the build status
[10:18:47.367] Build 8921647227222968384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:18:47.367] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087077289317894048:35:0) after 1m0s
[10:19:47.385] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087077289317894048:35:0)
[10:19:47.385] Timer tick, asking Buildbucket for the build status
[10:19:47.494] Build 8921647227222968384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:19:47.494] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087077289317894048:36:0) after 1m0s
[10:20:47.528] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087077289317894048:36:0)
[10:20:47.528] Timer tick, asking Buildbucket for the build status
[10:20:47.715] Build 8921647227222968384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:20:47.715] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087077289317894048:37:0) after 1m0s
[10:21:47.735] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087077289317894048:37:0)
[10:21:47.735] Timer tick, asking Buildbucket for the build status
[10:21:47.824] Build 8921647227222968384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:21:47.824] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087077289317894048:38:0) after 1m0s
[10:22:47.865] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087077289317894048:38:0)
[10:22:47.865] Timer tick, asking Buildbucket for the build status
[10:22:47.910] Build 8921647227222968384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:22:47.910] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087077289317894048:39:0) after 1m0s
[10:23:47.928] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087077289317894048:39:0)
[10:23:47.928] Timer tick, asking Buildbucket for the build status
[10:23:48.185] Build 8921647227222968384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:23:48.185] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087077289317894048:40:0) after 1m0s
[10:24:48.220] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087077289317894048:40:0)
[10:24:48.220] Timer tick, asking Buildbucket for the build status
[10:24:48.506] Build 8921647227222968384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:24:48.506] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087077289317894048:41:0) after 1m0s
[10:25:48.524] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087077289317894048:41:0)
[10:25:48.524] Timer tick, asking Buildbucket for the build status
[10:25:48.618] Build 8921647227222968384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:25:48.618] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087077289317894048:42:0) after 1m0s
[10:26:48.641] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087077289317894048:42:0)
[10:26:48.641] Timer tick, asking Buildbucket for the build status
[10:26:48.769] Build 8921647227222968384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:26:48.769] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087077289317894048:43:0) after 1m0s
[10:27:48.795] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087077289317894048:43:0)
[10:27:48.795] Timer tick, asking Buildbucket for the build status
[10:27:48.885] Build 8921647227222968384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:27:48.885] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087077289317894048:44:0) after 1m0s
[10:28:48.919] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087077289317894048:44:0)
[10:28:48.919] Timer tick, asking Buildbucket for the build status
[10:28:49.001] Build 8921647227222968384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:28:49.001] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087077289317894048:45:0) after 1m0s
[10:29:49.020] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087077289317894048:45:0)
[10:29:49.020] Timer tick, asking Buildbucket for the build status
[10:29:49.264] Build 8921647227222968384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:29:49.264] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087077289317894048:46:0) after 1m0s
[10:30:49.289] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087077289317894048:46:0)
[10:30:49.289] Timer tick, asking Buildbucket for the build status
[10:30:49.423] Build 8921647227222968384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:30:49.423] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087077289317894048:47:0) after 1m0s
[10:31:49.442] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087077289317894048:47:0)
[10:31:49.442] Timer tick, asking Buildbucket for the build status
[10:31:49.563] Build 8921647227222968384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:31:49.563] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087077289317894048:48:0) after 1m0s
[10:32:49.582] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087077289317894048:48:0)
[10:32:49.582] Timer tick, asking Buildbucket for the build status
[10:32:49.779] Build 8921647227222968384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:32:49.779] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087077289317894048:49:0) after 1m0s
[10:33:49.798] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087077289317894048:49:0)
[10:33:49.798] Timer tick, asking Buildbucket for the build status
[10:33:49.886] Build 8921647227222968384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:33:49.886] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087077289317894048:50:0) after 1m0s
[10:34:49.906] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087077289317894048:50:0)
[10:34:49.906] Timer tick, asking Buildbucket for the build status
[10:34:49.948] Build 8921647227222968384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:34:49.948] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087077289317894048:51:0) after 1m0s
[10:35:49.968] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087077289317894048:51:0)
[10:35:49.968] Timer tick, asking Buildbucket for the build status
[10:35:50.119] Build 8921647227222968384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:35:50.119] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087077289317894048:52:0) after 1m0s
[10:36:50.145] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087077289317894048:52:0)
[10:36:50.145] Timer tick, asking Buildbucket for the build status
[10:36:50.218] Build 8921647227222968384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:36:50.218] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087077289317894048:53:0) after 1m0s
[10:37:50.240] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087077289317894048:53:0)
[10:37:50.240] Timer tick, asking Buildbucket for the build status
[10:37:50.332] Build 8921647227222968384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:37:50.332] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087077289317894048:54:0) after 1m0s
[10:38:50.364] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087077289317894048:54:0)
[10:38:50.364] Timer tick, asking Buildbucket for the build status
[10:38:50.412] Build 8921647227222968384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:38:50.412] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087077289317894048:55:0) after 1m0s
[10:39:50.433] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087077289317894048:55:0)
[10:39:50.433] Timer tick, asking Buildbucket for the build status
[10:39:50.523] Build 8921647227222968384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:39:50.523] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087077289317894048:56:0) after 1m0s
[10:40:50.545] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087077289317894048:56:0)
[10:40:50.545] Timer tick, asking Buildbucket for the build status
[10:40:50.592] Build 8921647227222968384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:40:50.592] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087077289317894048:57:0) after 1m0s
[10:41:50.624] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087077289317894048:57:0)
[10:41:50.624] Timer tick, asking Buildbucket for the build status
[10:41:50.693] Build 8921647227222968384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:41:50.693] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087077289317894048:58:0) after 1m0s
[10:42:50.719] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087077289317894048:58:0)
[10:42:50.719] Timer tick, asking Buildbucket for the build status
[10:42:50.765] Build 8921647227222968384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:42:50.765] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087077289317894048:59:0) after 1m0s
[10:43:50.823] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087077289317894048:59:0)
[10:43:50.823] Timer tick, asking Buildbucket for the build status
[10:43:50.888] Build 8921647227222968384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:43:50.888] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087077289317894048:60:0) after 1m0s
[10:44:50.908] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087077289317894048:60:0)
[10:44:50.908] Timer tick, asking Buildbucket for the build status
[10:44:50.974] Build 8921647227222968384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:44:50.974] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087077289317894048:61:0) after 1m0s
[10:45:50.995] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087077289317894048:61:0)
[10:45:50.995] Timer tick, asking Buildbucket for the build status
[10:45:51.063] Build 8921647227222968384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:45:51.063] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087077289317894048:62:0) after 1m0s
[10:46:51.087] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087077289317894048:62:0)
[10:46:51.087] Timer tick, asking Buildbucket for the build status
[10:46:51.175] Build 8921647227222968384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:46:51.175] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087077289317894048:63:0) after 1m0s
[10:47:51.269] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087077289317894048:63:0)
[10:47:51.269] Timer tick, asking Buildbucket for the build status
[10:47:51.357] Build 8921647227222968384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:47:51.357] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087077289317894048:64:0) after 1m0s
[10:48:51.381] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087077289317894048:64:0)
[10:48:51.381] Timer tick, asking Buildbucket for the build status
[10:48:51.437] Build 8921647227222968384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:48:51.437] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087077289317894048:65:0) after 1m0s
[10:49:51.467] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087077289317894048:65:0)
[10:49:51.467] Timer tick, asking Buildbucket for the build status
[10:49:51.556] Build 8921647227222968384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:49:51.556] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087077289317894048:66:0) after 1m0s
[10:50:51.580] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087077289317894048:66:0)
[10:50:51.580] Timer tick, asking Buildbucket for the build status
[10:50:51.666] Build 8921647227222968384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:50:51.666] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087077289317894048:67:0) after 1m0s
[10:51:51.705] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087077289317894048:67:0)
[10:51:51.705] Timer tick, asking Buildbucket for the build status
[10:51:51.740] Build 8921647227222968384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:51:51.740] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087077289317894048:68:0) after 1m0s
[10:52:51.785] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087077289317894048:68:0)
[10:52:51.785] Timer tick, asking Buildbucket for the build status
[10:52:51.985] Build 8921647227222968384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:52:51.985] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087077289317894048:69:0) after 1m0s
[10:53:52.017] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087077289317894048:69:0)
[10:53:52.017] Timer tick, asking Buildbucket for the build status
[10:53:52.092] Build 8921647227222968384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:53:52.092] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087077289317894048:70:0) after 1m0s
[10:54:52.119] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087077289317894048:70:0)
[10:54:52.119] Timer tick, asking Buildbucket for the build status
[10:54:52.204] Build 8921647227222968384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:54:52.204] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087077289317894048:71:0) after 1m0s
[10:55:52.251] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087077289317894048:71:0)
[10:55:52.251] Timer tick, asking Buildbucket for the build status
[10:55:52.393] Build 8921647227222968384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:55:52.393] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087077289317894048:72:0) after 1m0s
[10:56:52.417] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087077289317894048:72:0)
[10:56:52.417] Timer tick, asking Buildbucket for the build status
[10:56:52.477] Build 8921647227222968384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:56:52.477] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087077289317894048:73:0) after 1m0s
[10:57:52.509] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087077289317894048:73:0)
[10:57:52.509] Timer tick, asking Buildbucket for the build status
[10:57:52.624] Build 8921647227222968384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:57:52.624] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087077289317894048:74:0) after 1m0s
[10:58:52.655] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087077289317894048:74:0)
[10:58:52.655] Timer tick, asking Buildbucket for the build status
[10:58:52.760] Build 8921647227222968384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:58:52.760] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087077289317894048:75:0) after 1m0s
[10:59:52.785] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087077289317894048:75:0)
[10:59:52.785] Timer tick, asking Buildbucket for the build status
[10:59:52.860] Build 8921647227222968384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:59:52.861] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087077289317894048:76:0) after 1m0s
[11:00:52.893] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087077289317894048:76:0)
[11:00:52.893] Timer tick, asking Buildbucket for the build status
[11:00:52.950] Build 8921647227222968384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:00:52.950] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087077289317894048:77:0) after 1m0s
[11:01:52.976] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087077289317894048:77:0)
[11:01:52.976] Timer tick, asking Buildbucket for the build status
[11:01:53.090] Build 8921647227222968384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:01:53.090] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087077289317894048:78:0) after 1m0s
[11:02:53.124] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087077289317894048:78:0)
[11:02:53.124] Timer tick, asking Buildbucket for the build status
[11:02:53.208] Build 8921647227222968384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:02:53.208] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087077289317894048:79:0) after 1m0s
[11:03:53.239] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087077289317894048:79:0)
[11:03:53.239] Timer tick, asking Buildbucket for the build status
[11:03:53.366] Build 8921647227222968384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:03:53.366] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087077289317894048:80:0) after 1m0s
[11:04:53.416] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087077289317894048:80:0)
[11:04:53.416] Timer tick, asking Buildbucket for the build status
[11:04:53.472] Build 8921647227222968384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:04:53.472] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087077289317894048:81:0) after 1m0s
[11:05:53.504] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087077289317894048:81:0)
[11:05:53.504] Timer tick, asking Buildbucket for the build status
[11:05:53.547] Build 8921647227222968384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:05:53.547] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087077289317894048:82:0) after 1m0s
[11:06:53.586] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087077289317894048:82:0)
[11:06:53.586] Timer tick, asking Buildbucket for the build status
[11:06:53.716] Build 8921647227222968384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:06:53.716] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087077289317894048:83:0) after 1m0s
[11:07:53.741] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087077289317894048:83:0)
[11:07:53.741] Timer tick, asking Buildbucket for the build status
[11:07:53.790] Build 8921647227222968384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:07:53.790] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087077289317894048:84:0) after 1m0s
[11:08:53.872] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087077289317894048:84:0)
[11:08:53.872] Timer tick, asking Buildbucket for the build status
[11:08:53.943] Build 8921647227222968384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:08:53.943] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087077289317894048:85:0) after 1m0s
[11:09:53.970] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087077289317894048:85:0)
[11:09:53.970] Timer tick, asking Buildbucket for the build status
[11:09:54.032] Build 8921647227222968384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:09:54.032] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087077289317894048:86:0) after 1m0s
[11:10:54.058] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087077289317894048:86:0)
[11:10:54.058] Timer tick, asking Buildbucket for the build status
[11:10:54.202] Build 8921647227222968384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:10:54.202] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087077289317894048:87:0) after 1m0s
[11:11:54.232] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087077289317894048:87:0)
[11:11:54.232] Timer tick, asking Buildbucket for the build status
[11:11:54.291] Build 8921647227222968384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:11:54.291] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087077289317894048:88:0) after 1m0s
[11:12:54.315] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087077289317894048:88:0)
[11:12:54.315] Timer tick, asking Buildbucket for the build status
[11:12:54.363] Build 8921647227222968384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:12:54.363] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087077289317894048:89:0) after 1m0s
[11:13:54.399] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087077289317894048:89:0)
[11:13:54.399] Timer tick, asking Buildbucket for the build status
[11:13:54.449] Build 8921647227222968384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:13:54.449] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087077289317894048:90:0) after 1m0s
[11:14:54.477] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087077289317894048:90:0)
[11:14:54.477] Timer tick, asking Buildbucket for the build status
[11:14:54.522] Build 8921647227222968384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:14:54.522] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087077289317894048:91:0) after 1m0s
[11:15:54.552] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087077289317894048:91:0)
[11:15:54.552] Timer tick, asking Buildbucket for the build status
[11:15:54.713] Build 8921647227222968384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:15:54.713] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087077289317894048:92:0) after 1m0s
[11:16:54.800] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087077289317894048:92:0)
[11:16:54.800] Timer tick, asking Buildbucket for the build status
[11:16:54.996] Build 8921647227222968384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:16:54.996] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087077289317894048:93:0) after 1m0s
[11:17:55.020] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087077289317894048:93:0)
[11:17:55.020] Timer tick, asking Buildbucket for the build status
[11:17:55.064] Build 8921647227222968384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:17:55.064] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087077289317894048:94:0) after 1m0s
[11:18:55.087] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087077289317894048:94:0)
[11:18:55.087] Timer tick, asking Buildbucket for the build status
[11:18:55.270] Build 8921647227222968384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:18:55.270] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087077289317894048:95:0) after 1m0s
[11:19:55.298] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087077289317894048:95:0)
[11:19:55.298] Timer tick, asking Buildbucket for the build status
[11:19:55.367] Build 8921647227222968384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:19:55.367] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087077289317894048:96:0) after 1m0s
[11:20:55.439] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087077289317894048:96:0)
[11:20:55.439] Timer tick, asking Buildbucket for the build status
[11:20:55.507] Build 8921647227222968384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:20:55.507] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087077289317894048:97:0) after 1m0s
[11:21:55.546] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087077289317894048:97:0)
[11:21:55.546] Timer tick, asking Buildbucket for the build status
[11:21:55.594] Build 8921647227222968384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:21:55.594] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087077289317894048:98:0) after 1m0s
[11:22:55.628] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087077289317894048:98:0)
[11:22:55.628] Timer tick, asking Buildbucket for the build status
[11:22:55.675] Build 8921647227222968384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:22:55.675] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087077289317894048:99:0) after 1m0s
[11:23:55.711] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087077289317894048:99:0)
[11:23:55.711] Timer tick, asking Buildbucket for the build status
[11:23:55.787] Build 8921647227222968384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:23:55.788] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087077289317894048:100:0) after 1m0s
[11:24:55.815] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087077289317894048:100:0)
[11:24:55.815] Timer tick, asking Buildbucket for the build status
[11:24:55.876] Build 8921647227222968384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:24:55.876] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087077289317894048:101:0) after 1m0s
[11:25:55.921] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087077289317894048:101:0)
[11:25:55.921] Timer tick, asking Buildbucket for the build status
[11:25:56.087] Build 8921647227222968384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:25:56.087] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087077289317894048:102:0) after 1m0s
[11:26:56.114] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087077289317894048:102:0)
[11:26:56.114] Timer tick, asking Buildbucket for the build status
[11:26:56.223] Build 8921647227222968384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:26:56.223] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087077289317894048:103:0) after 1m0s
[11:27:56.246] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087077289317894048:103:0)
[11:27:56.246] Timer tick, asking Buildbucket for the build status
[11:27:56.405] Build 8921647227222968384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:27:56.405] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087077289317894048:104:0) after 1m0s
[11:28:56.479] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087077289317894048:104:0)
[11:28:56.479] Timer tick, asking Buildbucket for the build status
[11:28:56.532] Build 8921647227222968384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:28:56.532] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087077289317894048:105:0) after 1m0s
[11:29:56.569] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087077289317894048:105:0)
[11:29:56.569] Timer tick, asking Buildbucket for the build status
[11:29:56.616] Build 8921647227222968384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:29:56.616] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087077289317894048:106:0) after 1m0s
[11:30:56.645] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087077289317894048:106:0)
[11:30:56.645] Timer tick, asking Buildbucket for the build status
[11:30:56.711] Build 8921647227222968384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:30:56.711] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087077289317894048:107:0) after 1m0s
[11:31:56.744] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087077289317894048:107:0)
[11:31:56.745] Timer tick, asking Buildbucket for the build status
[11:31:56.821] Build 8921647227222968384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:31:56.821] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087077289317894048:108:0) after 1m0s
[11:32:56.852] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087077289317894048:108:0)
[11:32:56.852] Timer tick, asking Buildbucket for the build status
[11:32:56.910] Build 8921647227222968384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:32:56.910] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087077289317894048:109:0) after 1m0s
[11:33:56.934] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087077289317894048:109:0)
[11:33:56.934] Timer tick, asking Buildbucket for the build status
[11:33:57.204] Build 8921647227222968384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:33:57.204] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087077289317894048:110:0) after 1m0s
[11:34:57.283] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087077289317894048:110:0)
[11:34:57.283] Timer tick, asking Buildbucket for the build status
[11:34:57.393] Build 8921647227222968384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:34:57.393] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087077289317894048:111:0) after 1m0s
[11:35:57.420] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087077289317894048:111:0)
[11:35:57.420] Timer tick, asking Buildbucket for the build status
[11:35:57.467] Build 8921647227222968384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:35:57.467] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087077289317894048:112:0) after 1m0s
[11:36:57.490] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087077289317894048:112:0)
[11:36:57.490] Timer tick, asking Buildbucket for the build status
[11:36:57.567] Build 8921647227222968384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:36:57.567] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087077289317894048:113:0) after 1m0s
[11:37:57.589] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087077289317894048:113:0)
[11:37:57.589] Timer tick, asking Buildbucket for the build status
[11:37:57.697] Build 8921647227222968384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:37:57.697] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087077289317894048:114:0) after 1m0s
[11:38:57.720] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087077289317894048:114:0)
[11:38:57.720] Timer tick, asking Buildbucket for the build status
[11:38:58.016] Build 8921647227222968384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:38:58.016] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087077289317894048:115:0) after 1m0s
[11:39:58.044] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087077289317894048:115:0)
[11:39:58.044] Timer tick, asking Buildbucket for the build status
[11:39:58.147] Build 8921647227222968384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:39:58.147] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087077289317894048:116:0) after 1m0s
[11:40:58.229] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087077289317894048:116:0)
[11:40:58.229] Timer tick, asking Buildbucket for the build status
[11:40:58.362] Build 8921647227222968384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:40:58.362] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087077289317894048:117:0) after 1m0s
[11:41:58.389] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087077289317894048:117:0)
[11:41:58.389] Timer tick, asking Buildbucket for the build status
[11:41:58.432] Build 8921647227222968384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:41:58.432] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087077289317894048:118:0) after 1m0s
[11:42:58.452] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087077289317894048:118:0)
[11:42:58.452] Timer tick, asking Buildbucket for the build status
[11:42:58.533] Build 8921647227222968384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:42:58.533] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087077289317894048:119:0) after 1m0s
[11:43:15.559] Received PubSub notification, asking Buildbucket for the build status
[11:43:15.600] Build 8921647227222968384: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[11:43:15.600] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"completed_ts": "1550058194479222",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1550051201568404",
"id": "8921647227222968384",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"d7f846f40773668d6e3d2f4861efd02f31e9e890\"}}",
"project": "chromium",
"result": "SUCCESS",
"result_details_json": "{\"swarming\": {\"bot_dimensions\": {\"gce\": [\"1\"], \"machine_type\": [\"n1-standard-8\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"], \"locale\": [\"en_US.cp1252\"], \"os\": [\"Windows\", \"Windows-10\", \"Windows-10-15063\"], \"builder\": [\"Windows deterministic\"], \"id\": [\"swarm2173-c4\"], \"python\": [\"2.7.6\"], \"server_version\": [\"4085-c81638b\"], \"image\": [\"chrome-win10-1703-2018-04-20-61acaf8983f\"], \"gpu\": [\"none\"], \"cores\": [\"8\"], \"integrity\": [\"high\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Haswell_GCE\"], \"pool\": [\"luci.chromium.ci\"], \"caches\": [\"builder_e8fffaf9fac16164b1ec16ebaa1f377b792d39a582f7640830518d8ff7303d0c_v2\", \"git\", \"goma_v2\", \"vpython\"]}}, \"ui\": {\"info\": \"\"}, \"properties\": {\"got_nacl_revision\": \"ff15c51d20785fa51c0ef03ccb480b87299acbeb\", \"got_swarming_client_revision\": \"0e3e1c4dc4e79f25a5b58fcbc135dc93183c0c54\", \"got_revision\": \"d7f846f40773668d6e3d2f4861efd02f31e9e890\", \"recipe\": \"swarming/deterministic_build\", \"got_dawn_revision\": \"bb5696bcd3b7a9ba5cd6a74cd1e9665bc74deb2c\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#26626}\", \"$recipe_engine/path\": {\"cache_dir\": \"C:\\\\b\\\\swarming\\\\w\\\\ir\\\\cache\", \"temp_dir\": \"C:\\\\b\\\\swarming\\\\w\\\\ir\\\\tmp\\\\rt\"}, \"got_revision_cp\": \"refs/heads/master@{#631615}\", \"branch\": \"refs/heads/master\", \"revision\": \"d7f846f40773668d6e3d2f4861efd02f31e9e890\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"buildername\": \"Windows deterministic\", \"got_webrtc_revision\": \"f13c2cd9ee41f4ca572232a4e397b05449474632\", \"mastername\": \"chromium.win\", \"got_angle_revision\": \"983e446921946734fe47217c345a8fe2f079319d\", \"buildbucket\": {\"hostname\": \"cr-buildbucket.appspot.com\", \"build\": {\"created_ts\": 1550051201568404, \"tags\": [\"build_address:luci.chromium.ci/Windows deterministic/11952\", \"builder:Windows deterministic\", \"buildset:commit/git/d7f846f40773668d6e3d2f4861efd02f31e9e890\", \"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/d7f846f40773668d6e3d2f4861efd02f31e9e890\", \"gitiles_ref:refs/heads/master\", \"scheduler_invocation_id:9087077289317894048\", \"scheduler_job_id:chromium/Windows deterministic\", \"user_agent:luci-scheduler\"], \"bucket\": \"luci.chromium.ci\", \"created_by\": \"user:luci-scheduler@appspot.gserviceaccount.com\", \"project\": \"chromium\", \"id\": \"8921647227222968384\"}}, \"got_v8_revision\": \"91594a65bbcc29d1691d68ec8273dd01f3bb7341\", \"got_v8_revision_cp\": \"refs/heads/7.4.152@{#1}\", \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"buildnumber\": 11952, \"$recipe_engine/buildbucket\": {\"build\": {\"tags\": [{\"value\": \"Windows deterministic\", \"key\": \"builder\"}, {\"value\": \"commit/git/d7f846f40773668d6e3d2f4861efd02f31e9e890\", \"key\": \"buildset\"}, {\"value\": \"commit/gitiles/chromium.googlesource.com/chromium/src/+/d7f846f40773668d6e3d2f4861efd02f31e9e890\", \"key\": \"buildset\"}, {\"value\": \"9087077289317894048\", \"key\": \"scheduler_invocation_id\"}, {\"value\": \"chromium/Windows deterministic\", \"key\": \"scheduler_job_id\"}, {\"value\": \"luci-scheduler\", \"key\": \"user_agent\"}], \"builder\": {\"project\": \"chromium\", \"builder\": \"Windows deterministic\", \"bucket\": \"ci\"}, \"number\": 11952, \"infra\": {\"buildbucket\": {\"serviceConfigRevision\": \"2acf9ddf085124300131f0a719a64564504c97a6\"}, \"recipe\": {\"name\": \"swarming/deterministic_build\", \"cipdPackage\": \"infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build\"}, \"swarming\": {\"hostname\": \"chromium-swarm.appspot.com\"}}, \"createdBy\": \"user:luci-scheduler@appspot.gserviceaccount.com\", \"id\": \"8921647227222968384\", \"input\": {\"gitilesCommit\": {\"project\": \"chromium/src\", \"host\": \"chromium.googlesource.com\", \"ref\": \"refs/heads/master\", \"id\": \"d7f846f40773668d6e3d2f4861efd02f31e9e890\"}}, \"createTime\": \"2019-02-13T09:46:41.568404Z\"}}, \"path_config\": \"generic\", \"bot_id\": \"swarm2173-c4\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1550051204188990",
"status": "COMPLETED",
"status_changed_ts": "1550058195206202",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/11952",
"builder:Windows deterministic",
"buildset:commit/git/d7f846f40773668d6e3d2f4861efd02f31e9e890",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/d7f846f40773668d6e3d2f4861efd02f31e9e890",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9087077289317894048",
"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/8921647227222968384/+/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:42ff11de08667310",
"user_agent:luci-scheduler"
],
"updated_ts": "1550058195207564",
"url": "https://ci.chromium.org/b/8921647227222968384",
"utcnow_ts": "1550058195591227"
}
}
[11:43:15.600] Invocation finished in 1h56m35.41462288s with status SUCCEEDED