[06:06:38.523] New invocation is queued and will start shortly
[06:06:39.723] Starting the invocation (attempt 1)
[06:06:39.756] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[06:06:39.756] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[06:06:39.756] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9087000535316686032",
"parameters_json": "{\"builder_name\":\"Windows deterministic\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src.git\",\"revision\":\"c826fc153564aa133ee41a27cc221bb64592413a\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Windows deterministic",
"scheduler_invocation_id:9087000535316686032",
"scheduler_job_id:chromium/Windows deterministic",
"user_agent:luci-scheduler",
"buildset:commit/git/c826fc153564aa133ee41a27cc221bb64592413a",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/c826fc153564aa133ee41a27cc221bb64592413a",
"gitiles_ref:refs/heads/master"
]
}
[06:06:41.447] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1550124399857408",
"id": "8921570473253865664",
"lease_expiration_ts": "1550243201171491",
"lease_key": "1857335566",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"c826fc153564aa133ee41a27cc221bb64592413a\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1550124401318213",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/11962",
"builder:Windows deterministic",
"buildset:commit/git/c826fc153564aa133ee41a27cc221bb64592413a",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/c826fc153564aa133ee41a27cc221bb64592413a",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9087000535316686032",
"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/8921570473253865664/+/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:43036ec8f5aa4b10",
"user_agent:luci-scheduler"
],
"updated_ts": "1550124401318315",
"url": "https://ci.chromium.org/b/8921570473253865664",
"utcnow_ts": "1550124401435205"
}
}
[06:06:41.447] Task URL: https://ci.chromium.org/b/8921570473253865664
[06:06:41.447] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087000535316686032:2:0) after 1m0s
[06:06:43.873] Received PubSub notification, asking Buildbucket for the build status
[06:06:43.926] Build 8921570473253865664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:07:41.466] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087000535316686032:2:0)
[06:07:41.466] Timer tick, asking Buildbucket for the build status
[06:07:41.521] Build 8921570473253865664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:07:41.521] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087000535316686032:4:0) after 1m0s
[06:08:41.518] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087000535316686032:4:0)
[06:08:41.518] Timer tick, asking Buildbucket for the build status
[06:08:41.691] Build 8921570473253865664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:08:41.691] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087000535316686032:5:0) after 1m0s
[06:09:41.715] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087000535316686032:5:0)
[06:09:41.715] Timer tick, asking Buildbucket for the build status
[06:09:41.814] Build 8921570473253865664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:09:41.814] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087000535316686032:6:0) after 1m0s
[06:10:41.840] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087000535316686032:6:0)
[06:10:41.840] Timer tick, asking Buildbucket for the build status
[06:10:41.888] Build 8921570473253865664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:10:41.888] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087000535316686032:7:0) after 1m0s
[06:11:41.913] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087000535316686032:7:0)
[06:11:41.913] Timer tick, asking Buildbucket for the build status
[06:11:41.993] Build 8921570473253865664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:11:41.993] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087000535316686032:8:0) after 1m0s
[06:12:42.012] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087000535316686032:8:0)
[06:12:42.012] Timer tick, asking Buildbucket for the build status
[06:12:42.105] Build 8921570473253865664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:12:42.105] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087000535316686032:9:0) after 1m0s
[06:13:42.124] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087000535316686032:9:0)
[06:13:42.124] Timer tick, asking Buildbucket for the build status
[06:13:42.292] Build 8921570473253865664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:13:42.292] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087000535316686032:10:0) after 1m0s
[06:14:42.315] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087000535316686032:10:0)
[06:14:42.315] Timer tick, asking Buildbucket for the build status
[06:14:42.391] Build 8921570473253865664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:14:42.391] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087000535316686032:11:0) after 1m0s
[06:15:42.415] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087000535316686032:11:0)
[06:15:42.415] Timer tick, asking Buildbucket for the build status
[06:15:42.518] Build 8921570473253865664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:15:42.518] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087000535316686032:12:0) after 1m0s
[06:16:42.590] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087000535316686032:12:0)
[06:16:42.590] Timer tick, asking Buildbucket for the build status
[06:16:42.649] Build 8921570473253865664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:16:42.649] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087000535316686032:13:0) after 1m0s
[06:17:42.665] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087000535316686032:13:0)
[06:17:42.665] Timer tick, asking Buildbucket for the build status
[06:17:42.728] Build 8921570473253865664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:17:42.728] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087000535316686032:14:0) after 1m0s
[06:18:42.747] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087000535316686032:14:0)
[06:18:42.747] Timer tick, asking Buildbucket for the build status
[06:18:42.825] Build 8921570473253865664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:18:42.825] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087000535316686032:15:0) after 1m0s
[06:19:42.909] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087000535316686032:15:0)
[06:19:42.909] Timer tick, asking Buildbucket for the build status
[06:19:42.953] Build 8921570473253865664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:19:42.953] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087000535316686032:16:0) after 1m0s
[06:20:42.978] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087000535316686032:16:0)
[06:20:42.978] Timer tick, asking Buildbucket for the build status
[06:20:43.225] Build 8921570473253865664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:20:43.225] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087000535316686032:17:0) after 1m0s
[06:21:43.251] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087000535316686032:17:0)
[06:21:43.251] Timer tick, asking Buildbucket for the build status
[06:21:43.471] Build 8921570473253865664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:21:43.471] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087000535316686032:18:0) after 1m0s
[06:22:43.487] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087000535316686032:18:0)
[06:22:43.487] Timer tick, asking Buildbucket for the build status
[06:22:43.644] Build 8921570473253865664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:22:43.644] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087000535316686032:19:0) after 1m0s
[06:23:43.664] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087000535316686032:19:0)
[06:23:43.664] Timer tick, asking Buildbucket for the build status
[06:23:43.743] Build 8921570473253865664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:23:43.743] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087000535316686032:20:0) after 1m0s
[06:24:43.760] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087000535316686032:20:0)
[06:24:43.760] Timer tick, asking Buildbucket for the build status
[06:24:43.859] Build 8921570473253865664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:24:43.859] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087000535316686032:21:0) after 1m0s
[06:25:43.877] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087000535316686032:21:0)
[06:25:43.877] Timer tick, asking Buildbucket for the build status
[06:25:43.954] Build 8921570473253865664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:25:43.954] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087000535316686032:22:0) after 1m0s
[06:26:43.970] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087000535316686032:22:0)
[06:26:43.970] Timer tick, asking Buildbucket for the build status
[06:26:44.015] Build 8921570473253865664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:26:44.015] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087000535316686032:23:0) after 1m0s
[06:27:44.056] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087000535316686032:23:0)
[06:27:44.056] Timer tick, asking Buildbucket for the build status
[06:27:44.148] Build 8921570473253865664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:27:44.148] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087000535316686032:24:0) after 1m0s
[06:28:44.202] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087000535316686032:24:0)
[06:28:44.202] Timer tick, asking Buildbucket for the build status
[06:28:44.292] Build 8921570473253865664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:28:44.292] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087000535316686032:25:0) after 1m0s
[06:29:44.318] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087000535316686032:25:0)
[06:29:44.318] Timer tick, asking Buildbucket for the build status
[06:29:44.431] Build 8921570473253865664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:29:44.431] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087000535316686032:26:0) after 1m0s
[06:30:44.505] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087000535316686032:26:0)
[06:30:44.505] Timer tick, asking Buildbucket for the build status
[06:30:44.595] Build 8921570473253865664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:30:44.595] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087000535316686032:27:0) after 1m0s
[06:31:44.612] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087000535316686032:27:0)
[06:31:44.612] Timer tick, asking Buildbucket for the build status
[06:31:44.658] Build 8921570473253865664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:31:44.658] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087000535316686032:28:0) after 1m0s
[06:32:44.674] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087000535316686032:28:0)
[06:32:44.674] Timer tick, asking Buildbucket for the build status
[06:32:44.731] Build 8921570473253865664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:32:44.731] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087000535316686032:29:0) after 1m0s
[06:33:44.753] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087000535316686032:29:0)
[06:33:44.753] Timer tick, asking Buildbucket for the build status
[06:33:44.822] Build 8921570473253865664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:33:44.823] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087000535316686032:30:0) after 1m0s
[06:34:44.841] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087000535316686032:30:0)
[06:34:44.841] Timer tick, asking Buildbucket for the build status
[06:34:44.898] Build 8921570473253865664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:34:44.898] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087000535316686032:31:0) after 1m0s
[06:35:44.920] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087000535316686032:31:0)
[06:35:44.920] Timer tick, asking Buildbucket for the build status
[06:35:44.976] Build 8921570473253865664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:35:44.976] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087000535316686032:32:0) after 1m0s
[06:36:45.087] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087000535316686032:32:0)
[06:36:45.087] Timer tick, asking Buildbucket for the build status
[06:36:45.148] Build 8921570473253865664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:36:45.148] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087000535316686032:33:0) after 1m0s
[06:37:45.178] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087000535316686032:33:0)
[06:37:45.178] Timer tick, asking Buildbucket for the build status
[06:37:45.239] Build 8921570473253865664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:37:45.239] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087000535316686032:34:0) after 1m0s
[06:38:45.304] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087000535316686032:34:0)
[06:38:45.304] Timer tick, asking Buildbucket for the build status
[06:38:45.352] Build 8921570473253865664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:38:45.352] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087000535316686032:35:0) after 1m0s
[06:39:45.397] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087000535316686032:35:0)
[06:39:45.397] Timer tick, asking Buildbucket for the build status
[06:39:45.832] Build 8921570473253865664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:39:45.832] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087000535316686032:36:0) after 1m0s
[06:40:45.836] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087000535316686032:36:0)
[06:40:45.836] Timer tick, asking Buildbucket for the build status
[06:40:45.910] Build 8921570473253865664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:40:45.910] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087000535316686032:37:0) after 1m0s
[06:41:45.955] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087000535316686032:37:0)
[06:41:45.956] Timer tick, asking Buildbucket for the build status
[06:41:46.052] Build 8921570473253865664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:41:46.052] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087000535316686032:38:0) after 1m0s
[06:42:46.075] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087000535316686032:38:0)
[06:42:46.075] Timer tick, asking Buildbucket for the build status
[06:42:46.290] Build 8921570473253865664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:42:46.290] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087000535316686032:39:0) after 1m0s
[06:43:46.311] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087000535316686032:39:0)
[06:43:46.311] Timer tick, asking Buildbucket for the build status
[06:43:46.416] Build 8921570473253865664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:43:46.416] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087000535316686032:40:0) after 1m0s
[06:44:46.434] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087000535316686032:40:0)
[06:44:46.434] Timer tick, asking Buildbucket for the build status
[06:44:46.499] Build 8921570473253865664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:44:46.499] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087000535316686032:41:0) after 1m0s
[06:45:46.519] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087000535316686032:41:0)
[06:45:46.519] Timer tick, asking Buildbucket for the build status
[06:45:46.608] Build 8921570473253865664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:45:46.608] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087000535316686032:42:0) after 1m0s
[06:46:46.633] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087000535316686032:42:0)
[06:46:46.633] Timer tick, asking Buildbucket for the build status
[06:46:46.718] Build 8921570473253865664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:46:46.718] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087000535316686032:43:0) after 1m0s
[06:47:46.736] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087000535316686032:43:0)
[06:47:46.736] Timer tick, asking Buildbucket for the build status
[06:47:46.925] Build 8921570473253865664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:47:46.925] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087000535316686032:44:0) after 1m0s
[06:48:46.949] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087000535316686032:44:0)
[06:48:46.949] Timer tick, asking Buildbucket for the build status
[06:48:47.079] Build 8921570473253865664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:48:47.079] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087000535316686032:45:0) after 1m0s
[06:49:47.099] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087000535316686032:45:0)
[06:49:47.099] Timer tick, asking Buildbucket for the build status
[06:49:47.218] Build 8921570473253865664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:49:47.218] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087000535316686032:46:0) after 1m0s
[06:50:47.343] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087000535316686032:46:0)
[06:50:47.343] Timer tick, asking Buildbucket for the build status
[06:50:47.406] Build 8921570473253865664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:50:47.406] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087000535316686032:47:0) after 1m0s
[06:51:47.425] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087000535316686032:47:0)
[06:51:47.425] Timer tick, asking Buildbucket for the build status
[06:51:47.654] Build 8921570473253865664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:51:47.654] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087000535316686032:48:0) after 1m0s
[06:52:47.673] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087000535316686032:48:0)
[06:52:47.673] Timer tick, asking Buildbucket for the build status
[06:52:47.736] Build 8921570473253865664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:52:47.736] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087000535316686032:49:0) after 1m0s
[06:53:47.754] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087000535316686032:49:0)
[06:53:47.754] Timer tick, asking Buildbucket for the build status
[06:53:47.888] Build 8921570473253865664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:53:47.888] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087000535316686032:50:0) after 1m0s
[06:54:47.914] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087000535316686032:50:0)
[06:54:47.914] Timer tick, asking Buildbucket for the build status
[06:54:47.977] Build 8921570473253865664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:54:47.977] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087000535316686032:51:0) after 1m0s
[06:55:47.995] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087000535316686032:51:0)
[06:55:47.995] Timer tick, asking Buildbucket for the build status
[06:55:48.073] Build 8921570473253865664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:55:48.073] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087000535316686032:52:0) after 1m0s
[06:56:48.105] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087000535316686032:52:0)
[06:56:48.105] Timer tick, asking Buildbucket for the build status
[06:56:48.184] Build 8921570473253865664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:56:48.184] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087000535316686032:53:0) after 1m0s
[06:57:48.204] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087000535316686032:53:0)
[06:57:48.204] Timer tick, asking Buildbucket for the build status
[06:57:48.381] Build 8921570473253865664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:57:48.381] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087000535316686032:54:0) after 1m0s
[06:58:48.403] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087000535316686032:54:0)
[06:58:48.403] Timer tick, asking Buildbucket for the build status
[06:58:48.505] Build 8921570473253865664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:58:48.505] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087000535316686032:55:0) after 1m0s
[06:59:48.525] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087000535316686032:55:0)
[06:59:48.525] Timer tick, asking Buildbucket for the build status
[06:59:48.574] Build 8921570473253865664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:59:48.574] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087000535316686032:56:0) after 1m0s
[07:00:48.643] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087000535316686032:56:0)
[07:00:48.643] Timer tick, asking Buildbucket for the build status
[07:00:48.691] Build 8921570473253865664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:00:48.691] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087000535316686032:57:0) after 1m0s
[07:01:48.737] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087000535316686032:57:0)
[07:01:48.737] Timer tick, asking Buildbucket for the build status
[07:01:48.799] Build 8921570473253865664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:01:48.799] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087000535316686032:58:0) after 1m0s
[07:02:48.829] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087000535316686032:58:0)
[07:02:48.829] Timer tick, asking Buildbucket for the build status
[07:02:48.877] Build 8921570473253865664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:02:48.877] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087000535316686032:59:0) after 1m0s
[07:03:48.912] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087000535316686032:59:0)
[07:03:48.912] Timer tick, asking Buildbucket for the build status
[07:03:48.989] Build 8921570473253865664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:03:48.989] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087000535316686032:60:0) after 1m0s
[07:04:49.007] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087000535316686032:60:0)
[07:04:49.007] Timer tick, asking Buildbucket for the build status
[07:04:49.071] Build 8921570473253865664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:04:49.071] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087000535316686032:61:0) after 1m0s
[07:05:49.092] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087000535316686032:61:0)
[07:05:49.092] Timer tick, asking Buildbucket for the build status
[07:05:49.341] Build 8921570473253865664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:05:49.342] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087000535316686032:62:0) after 1m0s
[07:06:49.365] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087000535316686032:62:0)
[07:06:49.365] Timer tick, asking Buildbucket for the build status
[07:06:49.413] Build 8921570473253865664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:06:49.413] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087000535316686032:63:0) after 1m0s
[07:07:49.431] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087000535316686032:63:0)
[07:07:49.431] Timer tick, asking Buildbucket for the build status
[07:07:49.480] Build 8921570473253865664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:07:49.480] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087000535316686032:64:0) after 1m0s
[07:08:49.500] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087000535316686032:64:0)
[07:08:49.500] Timer tick, asking Buildbucket for the build status
[07:08:49.550] Build 8921570473253865664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:08:49.550] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087000535316686032:65:0) after 1m0s
[07:09:49.570] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087000535316686032:65:0)
[07:09:49.571] Timer tick, asking Buildbucket for the build status
[07:09:49.696] Build 8921570473253865664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:09:49.696] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087000535316686032:66:0) after 1m0s
[07:10:49.726] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087000535316686032:66:0)
[07:10:49.726] Timer tick, asking Buildbucket for the build status
[07:10:49.800] Build 8921570473253865664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:10:49.800] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087000535316686032:67:0) after 1m0s
[07:11:49.821] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087000535316686032:67:0)
[07:11:49.821] Timer tick, asking Buildbucket for the build status
[07:11:49.935] Build 8921570473253865664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:11:49.935] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087000535316686032:68:0) after 1m0s
[07:12:49.957] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087000535316686032:68:0)
[07:12:49.957] Timer tick, asking Buildbucket for the build status
[07:12:50.120] Build 8921570473253865664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:12:50.120] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087000535316686032:69:0) after 1m0s
[07:13:50.139] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087000535316686032:69:0)
[07:13:50.139] Timer tick, asking Buildbucket for the build status
[07:13:50.223] Build 8921570473253865664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:13:50.223] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087000535316686032:70:0) after 1m0s
[07:14:50.241] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087000535316686032:70:0)
[07:14:50.241] Timer tick, asking Buildbucket for the build status
[07:14:50.327] Build 8921570473253865664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:14:50.327] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087000535316686032:71:0) after 1m0s
[07:15:50.351] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087000535316686032:71:0)
[07:15:50.351] Timer tick, asking Buildbucket for the build status
[07:15:50.430] Build 8921570473253865664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:15:50.430] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087000535316686032:72:0) after 1m0s
[07:16:50.562] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087000535316686032:72:0)
[07:16:50.562] Timer tick, asking Buildbucket for the build status
[07:16:50.611] Build 8921570473253865664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:16:50.611] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087000535316686032:73:0) after 1m0s
[07:17:50.630] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087000535316686032:73:0)
[07:17:50.630] Timer tick, asking Buildbucket for the build status
[07:17:51.048] Build 8921570473253865664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:17:51.049] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087000535316686032:74:0) after 1m0s
[07:18:51.068] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087000535316686032:74:0)
[07:18:51.068] Timer tick, asking Buildbucket for the build status
[07:18:51.121] Build 8921570473253865664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:18:51.121] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087000535316686032:75:0) after 1m0s
[07:19:51.197] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087000535316686032:75:0)
[07:19:51.197] Timer tick, asking Buildbucket for the build status
[07:19:51.248] Build 8921570473253865664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:19:51.248] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087000535316686032:76:0) after 1m0s
[07:20:51.284] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087000535316686032:76:0)
[07:20:51.284] Timer tick, asking Buildbucket for the build status
[07:20:51.354] Build 8921570473253865664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:20:51.354] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087000535316686032:77:0) after 1m0s
[07:21:51.375] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087000535316686032:77:0)
[07:21:51.375] Timer tick, asking Buildbucket for the build status
[07:21:51.426] Build 8921570473253865664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:21:51.426] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087000535316686032:78:0) after 1m0s
[07:22:51.553] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087000535316686032:78:0)
[07:22:51.553] Timer tick, asking Buildbucket for the build status
[07:22:51.612] Build 8921570473253865664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:22:51.612] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087000535316686032:79:0) after 1m0s
[07:23:51.634] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087000535316686032:79:0)
[07:23:51.634] Timer tick, asking Buildbucket for the build status
[07:23:51.738] Build 8921570473253865664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:23:51.738] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087000535316686032:80:0) after 1m0s
[07:24:51.759] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087000535316686032:80:0)
[07:24:51.759] Timer tick, asking Buildbucket for the build status
[07:24:51.828] Build 8921570473253865664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:24:51.828] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087000535316686032:81:0) after 1m0s
[07:25:51.851] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087000535316686032:81:0)
[07:25:51.851] Timer tick, asking Buildbucket for the build status
[07:25:51.924] Build 8921570473253865664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:25:51.924] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087000535316686032:82:0) after 1m0s
[07:26:51.944] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087000535316686032:82:0)
[07:26:51.944] Timer tick, asking Buildbucket for the build status
[07:26:52.014] Build 8921570473253865664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:26:52.014] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087000535316686032:83:0) after 1m0s
[07:27:52.067] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087000535316686032:83:0)
[07:27:52.067] Timer tick, asking Buildbucket for the build status
[07:27:52.177] Build 8921570473253865664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:27:52.177] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087000535316686032:84:0) after 1m0s
[07:28:52.215] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087000535316686032:84:0)
[07:28:52.215] Timer tick, asking Buildbucket for the build status
[07:28:52.281] Build 8921570473253865664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:28:52.281] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087000535316686032:85:0) after 1m0s
[07:29:52.297] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087000535316686032:85:0)
[07:29:52.297] Timer tick, asking Buildbucket for the build status
[07:29:52.376] Build 8921570473253865664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:29:52.376] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087000535316686032:86:0) after 1m0s
[07:30:52.422] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087000535316686032:86:0)
[07:30:52.422] Timer tick, asking Buildbucket for the build status
[07:30:53.140] Build 8921570473253865664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:30:53.140] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087000535316686032:87:0) after 1m0s
[07:31:53.158] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087000535316686032:87:0)
[07:31:53.158] Timer tick, asking Buildbucket for the build status
[07:31:53.203] Build 8921570473253865664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:31:53.203] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087000535316686032:88:0) after 1m0s
[07:32:53.229] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087000535316686032:88:0)
[07:32:53.229] Timer tick, asking Buildbucket for the build status
[07:32:53.371] Build 8921570473253865664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:32:53.371] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087000535316686032:89:0) after 1m0s
[07:33:53.414] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087000535316686032:89:0)
[07:33:53.414] Timer tick, asking Buildbucket for the build status
[07:33:53.501] Build 8921570473253865664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:33:53.501] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087000535316686032:90:0) after 1m0s
[07:34:53.531] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087000535316686032:90:0)
[07:34:53.531] Timer tick, asking Buildbucket for the build status
[07:34:53.606] Build 8921570473253865664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:34:53.606] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087000535316686032:91:0) after 1m0s
[07:35:53.628] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087000535316686032:91:0)
[07:35:53.628] Timer tick, asking Buildbucket for the build status
[07:35:53.913] Build 8921570473253865664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:35:53.913] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087000535316686032:92:0) after 1m0s
[07:36:53.938] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087000535316686032:92:0)
[07:36:53.938] Timer tick, asking Buildbucket for the build status
[07:36:54.045] Build 8921570473253865664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:36:54.045] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087000535316686032:93:0) after 1m0s
[07:37:54.067] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087000535316686032:93:0)
[07:37:54.067] Timer tick, asking Buildbucket for the build status
[07:37:54.219] Build 8921570473253865664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:37:54.219] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087000535316686032:94:0) after 1m0s
[07:38:54.264] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087000535316686032:94:0)
[07:38:54.264] Timer tick, asking Buildbucket for the build status
[07:38:54.307] Build 8921570473253865664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:38:54.307] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087000535316686032:95:0) after 1m0s
[07:39:54.332] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087000535316686032:95:0)
[07:39:54.332] Timer tick, asking Buildbucket for the build status
[07:39:54.661] Build 8921570473253865664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:39:54.661] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087000535316686032:96:0) after 1m0s
[07:40:54.682] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087000535316686032:96:0)
[07:40:54.682] Timer tick, asking Buildbucket for the build status
[07:40:54.764] Build 8921570473253865664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:40:54.764] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087000535316686032:97:0) after 1m0s
[07:41:54.805] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087000535316686032:97:0)
[07:41:54.805] Timer tick, asking Buildbucket for the build status
[07:41:55.112] Build 8921570473253865664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:41:55.112] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087000535316686032:98:0) after 1m0s
[07:42:55.131] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087000535316686032:98:0)
[07:42:55.131] Timer tick, asking Buildbucket for the build status
[07:42:55.213] Build 8921570473253865664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:42:55.213] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087000535316686032:99:0) after 1m0s
[07:43:55.278] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087000535316686032:99:0)
[07:43:55.278] Timer tick, asking Buildbucket for the build status
[07:43:55.338] Build 8921570473253865664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:43:55.338] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087000535316686032:100:0) after 1m0s
[07:44:55.367] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087000535316686032:100:0)
[07:44:55.367] Timer tick, asking Buildbucket for the build status
[07:44:55.439] Build 8921570473253865664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:44:55.439] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087000535316686032:101:0) after 1m0s
[07:45:55.460] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087000535316686032:101:0)
[07:45:55.460] Timer tick, asking Buildbucket for the build status
[07:45:55.795] Build 8921570473253865664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:45:55.795] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087000535316686032:102:0) after 1m0s
[07:46:55.817] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087000535316686032:102:0)
[07:46:55.817] Timer tick, asking Buildbucket for the build status
[07:46:55.857] Build 8921570473253865664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:46:55.857] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087000535316686032:103:0) after 1m0s
[07:47:55.879] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087000535316686032:103:0)
[07:47:55.879] Timer tick, asking Buildbucket for the build status
[07:47:55.956] Build 8921570473253865664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:47:55.956] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087000535316686032:104:0) after 1m0s
[07:48:55.979] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087000535316686032:104:0)
[07:48:55.979] Timer tick, asking Buildbucket for the build status
[07:48:56.036] Build 8921570473253865664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:48:56.036] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087000535316686032:105:0) after 1m0s
[07:49:56.063] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087000535316686032:105:0)
[07:49:56.063] Timer tick, asking Buildbucket for the build status
[07:49:56.138] Build 8921570473253865664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:49:56.138] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087000535316686032:106:0) after 1m0s
[07:50:56.169] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087000535316686032:106:0)
[07:50:56.170] Timer tick, asking Buildbucket for the build status
[07:50:56.328] Build 8921570473253865664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:50:56.328] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087000535316686032:107:0) after 1m0s
[07:51:56.352] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087000535316686032:107:0)
[07:51:56.352] Timer tick, asking Buildbucket for the build status
[07:51:56.569] Build 8921570473253865664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:51:56.569] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087000535316686032:108:0) after 1m0s
[07:52:56.588] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087000535316686032:108:0)
[07:52:56.588] Timer tick, asking Buildbucket for the build status
[07:52:56.678] Build 8921570473253865664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:52:56.678] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087000535316686032:109:0) after 1m0s
[07:53:56.700] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087000535316686032:109:0)
[07:53:56.700] Timer tick, asking Buildbucket for the build status
[07:53:56.750] Build 8921570473253865664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:53:56.750] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087000535316686032:110:0) after 1m0s
[07:54:56.773] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087000535316686032:110:0)
[07:54:56.773] Timer tick, asking Buildbucket for the build status
[07:54:56.869] Build 8921570473253865664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:54:56.869] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087000535316686032:111:0) after 1m0s
[07:55:56.892] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087000535316686032:111:0)
[07:55:56.892] Timer tick, asking Buildbucket for the build status
[07:55:56.988] Build 8921570473253865664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:55:56.988] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087000535316686032:112:0) after 1m0s
[07:56:57.008] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087000535316686032:112:0)
[07:56:57.008] Timer tick, asking Buildbucket for the build status
[07:56:57.122] Build 8921570473253865664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:56:57.122] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087000535316686032:113:0) after 1m0s
[07:57:57.143] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087000535316686032:113:0)
[07:57:57.143] Timer tick, asking Buildbucket for the build status
[07:57:57.275] Build 8921570473253865664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:57:57.275] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087000535316686032:114:0) after 1m0s
[07:58:57.298] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087000535316686032:114:0)
[07:58:57.298] Timer tick, asking Buildbucket for the build status
[07:58:57.345] Build 8921570473253865664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:58:57.345] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087000535316686032:115:0) after 1m0s
[07:59:57.379] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087000535316686032:115:0)
[07:59:57.379] Timer tick, asking Buildbucket for the build status
[07:59:57.463] Build 8921570473253865664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:59:57.463] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087000535316686032:116:0) after 1m0s
[08:00:57.497] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087000535316686032:116:0)
[08:00:57.497] Timer tick, asking Buildbucket for the build status
[08:00:57.639] Build 8921570473253865664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:00:57.639] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087000535316686032:117:0) after 1m0s
[08:01:57.661] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087000535316686032:117:0)
[08:01:57.661] Timer tick, asking Buildbucket for the build status
[08:01:57.703] Build 8921570473253865664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:01:57.703] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087000535316686032:118:0) after 1m0s
[08:02:57.726] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087000535316686032:118:0)
[08:02:57.726] Timer tick, asking Buildbucket for the build status
[08:02:57.773] Build 8921570473253865664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:02:57.773] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087000535316686032:119:0) after 1m0s
[08:03:57.821] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087000535316686032:119:0)
[08:03:57.821] Timer tick, asking Buildbucket for the build status
[08:03:57.910] Build 8921570473253865664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:03:57.910] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087000535316686032:120:0) after 1m0s
[08:04:57.932] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087000535316686032:120:0)
[08:04:57.932] Timer tick, asking Buildbucket for the build status
[08:04:58.018] Build 8921570473253865664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:04:58.018] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087000535316686032:121:0) after 1m0s
[08:05:06.932] Received PubSub notification, asking Buildbucket for the build status
[08:05:07.006] Build 8921570473253865664: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[08:05:07.006] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"completed_ts": "1550131503108997",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1550124399857408",
"id": "8921570473253865664",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"c826fc153564aa133ee41a27cc221bb64592413a\"}}",
"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\": [\"4098-34330fc\"], \"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\": \"c826fc153564aa133ee41a27cc221bb64592413a\", \"recipe\": \"swarming/deterministic_build\", \"got_dawn_revision\": \"b2207737abd32e20e39a1ac00987179626f050c7\", \"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@{#632106}\", \"branch\": \"refs/heads/master\", \"revision\": \"c826fc153564aa133ee41a27cc221bb64592413a\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"buildername\": \"Windows deterministic\", \"got_webrtc_revision\": \"f13c2cd9ee41f4ca572232a4e397b05449474632\", \"mastername\": \"chromium.win\", \"got_angle_revision\": \"52047de4d41f6861e35b7073dd180edffd64c540\", \"buildbucket\": {\"hostname\": \"cr-buildbucket.appspot.com\", \"build\": {\"created_ts\": 1550124399857408, \"tags\": [\"build_address:luci.chromium.ci/Windows deterministic/11962\", \"builder:Windows deterministic\", \"buildset:commit/git/c826fc153564aa133ee41a27cc221bb64592413a\", \"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/c826fc153564aa133ee41a27cc221bb64592413a\", \"gitiles_ref:refs/heads/master\", \"scheduler_invocation_id:9087000535316686032\", \"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\": \"8921570473253865664\"}}, \"got_v8_revision\": \"d34a141dae9be2c1f66509c7c449992ea34d058a\", \"got_v8_revision_cp\": \"refs/heads/7.4.166@{#1}\", \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"buildnumber\": 11962, \"$recipe_engine/buildbucket\": {\"build\": {\"tags\": [{\"value\": \"Windows deterministic\", \"key\": \"builder\"}, {\"value\": \"commit/git/c826fc153564aa133ee41a27cc221bb64592413a\", \"key\": \"buildset\"}, {\"value\": \"commit/gitiles/chromium.googlesource.com/chromium/src/+/c826fc153564aa133ee41a27cc221bb64592413a\", \"key\": \"buildset\"}, {\"value\": \"9087000535316686032\", \"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\": 11962, \"infra\": {\"buildbucket\": {\"serviceConfigRevision\": \"6aef5327da3458f30853917b657b22c0910618fa\"}, \"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\": \"8921570473253865664\", \"input\": {\"gitilesCommit\": {\"project\": \"chromium/src\", \"host\": \"chromium.googlesource.com\", \"ref\": \"refs/heads/master\", \"id\": \"c826fc153564aa133ee41a27cc221bb64592413a\"}}, \"createTime\": \"2019-02-14T06:06:39.857408Z\"}}, \"path_config\": \"generic\", \"bot_id\": \"swarm2173-c4\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1550124402928407",
"status": "COMPLETED",
"status_changed_ts": "1550131506444369",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/11962",
"builder:Windows deterministic",
"buildset:commit/git/c826fc153564aa133ee41a27cc221bb64592413a",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/c826fc153564aa133ee41a27cc221bb64592413a",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9087000535316686032",
"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/8921570473253865664/+/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:43036ec8f5aa4b10",
"user_agent:luci-scheduler"
],
"updated_ts": "1550131506446608",
"url": "https://ci.chromium.org/b/8921570473253865664",
"utcnow_ts": "1550131506995938"
}
}
[08:05:07.006] Invocation finished in 1h58m28.500329177s with status SUCCEEDED