[19:39:29.236] New invocation is queued and will start shortly
[19:39:30.440] Starting the invocation (attempt 1)
[19:39:30.478] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[19:39:30.478] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[19:39:30.478] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9068920599202707856",
"parameters_json": "{\"builder_name\":\"Windows deterministic\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src.git\",\"revision\":\"806eded3cd02c547c52ffb0dd2de4b10a59046fc\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Windows deterministic",
"scheduler_invocation_id:9068920599202707856",
"scheduler_job_id:chromium/Windows deterministic",
"user_agent:luci-scheduler",
"buildset:commit/git/806eded3cd02c547c52ffb0dd2de4b10a59046fc",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/806eded3cd02c547c52ffb0dd2de4b10a59046fc",
"gitiles_ref:refs/heads/master"
]
}
[19:39:31.487] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary": true,
"canary_preference": "CANARY",
"created_by": "project:chromium",
"created_ts": "1567366770660507",
"id": "8903490537046280848",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"806eded3cd02c547c52ffb0dd2de4b10a59046fc\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1567366771146070",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/14599",
"builder:Windows deterministic",
"buildset:commit/git/806eded3cd02c547c52ffb0dd2de4b10a59046fc",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/806eded3cd02c547c52ffb0dd2de4b10a59046fc",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9068920599202707856",
"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/8903490537046280848/+/annotations",
"swarming_tag:luci_project:chromium",
"swarming_tag:recipe_name:swarming/deterministic_build",
"swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build",
"swarming_task_id:",
"user_agent:luci-scheduler"
],
"updated_ts": "1567366771146188",
"url": "https://ci.chromium.org/b/8903490537046280848",
"utcnow_ts": "1567366771479756"
}
}
[19:39:31.487] Task URL: https://ci.chromium.org/b/8903490537046280848
[19:39:31.487] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:2:0) after 1m0s
[19:39:33.978] Received PubSub notification, asking Buildbucket for the build status
[19:39:34.397] Build 8903490537046280848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:40:31.514] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:2:0)
[19:40:31.514] Timer tick, asking Buildbucket for the build status
[19:40:31.644] Build 8903490537046280848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:40:31.644] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:4:0) after 1m0s
[19:41:31.660] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:4:0)
[19:41:31.660] Timer tick, asking Buildbucket for the build status
[19:41:32.436] Build 8903490537046280848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:41:32.436] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:5:0) after 1m0s
[19:42:32.457] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:5:0)
[19:42:32.457] Timer tick, asking Buildbucket for the build status
[19:42:32.670] Build 8903490537046280848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:42:32.671] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:6:0) after 1m0s
[19:43:32.695] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:6:0)
[19:43:32.695] Timer tick, asking Buildbucket for the build status
[19:43:33.183] Build 8903490537046280848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:43:33.183] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:7:0) after 1m0s
[19:44:33.208] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:7:0)
[19:44:33.208] Timer tick, asking Buildbucket for the build status
[19:44:33.493] Build 8903490537046280848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:44:33.493] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:8:0) after 1m0s
[19:45:33.593] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:8:0)
[19:45:33.593] Timer tick, asking Buildbucket for the build status
[19:45:33.833] Build 8903490537046280848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:45:33.833] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:9:0) after 1m0s
[19:46:33.853] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:9:0)
[19:46:33.853] Timer tick, asking Buildbucket for the build status
[19:46:34.952] Build 8903490537046280848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:46:34.952] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:10:0) after 1m0s
[19:47:35.036] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:10:0)
[19:47:35.036] Timer tick, asking Buildbucket for the build status
[19:47:35.205] Build 8903490537046280848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:47:35.205] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:11:0) after 1m0s
[19:48:35.223] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:11:0)
[19:48:35.223] Timer tick, asking Buildbucket for the build status
[19:48:35.496] Build 8903490537046280848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:48:35.496] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:12:0) after 1m0s
[19:49:35.528] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:12:0)
[19:49:35.529] Timer tick, asking Buildbucket for the build status
[19:49:36.020] Build 8903490537046280848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:49:36.020] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:13:0) after 1m0s
[19:50:36.041] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:13:0)
[19:50:36.041] Timer tick, asking Buildbucket for the build status
[19:50:36.188] Build 8903490537046280848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:50:36.188] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:14:0) after 1m0s
[19:51:36.225] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:14:0)
[19:51:36.225] Timer tick, asking Buildbucket for the build status
[19:51:36.905] Build 8903490537046280848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:51:36.905] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:15:0) after 1m0s
[19:52:36.928] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:15:0)
[19:52:36.928] Timer tick, asking Buildbucket for the build status
[19:52:37.665] Build 8903490537046280848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:52:37.665] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:16:0) after 1m0s
[19:53:37.686] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:16:0)
[19:53:37.686] Timer tick, asking Buildbucket for the build status
[19:53:38.462] Build 8903490537046280848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:53:38.462] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:17:0) after 1m0s
[19:54:38.577] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:17:0)
[19:54:38.577] Timer tick, asking Buildbucket for the build status
[19:54:38.845] Build 8903490537046280848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:54:38.845] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:18:0) after 1m0s
[19:55:38.940] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:18:0)
[19:55:38.940] Timer tick, asking Buildbucket for the build status
[19:55:39.301] Build 8903490537046280848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:55:39.301] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:19:0) after 1m0s
[19:56:39.373] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:19:0)
[19:56:39.373] Timer tick, asking Buildbucket for the build status
[19:56:39.732] Build 8903490537046280848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:56:39.732] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:20:0) after 1m0s
[19:57:39.846] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:20:0)
[19:57:39.847] Timer tick, asking Buildbucket for the build status
[19:57:40.009] Build 8903490537046280848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:57:40.009] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:21:0) after 1m0s
[19:58:40.029] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:21:0)
[19:58:40.029] Timer tick, asking Buildbucket for the build status
[19:58:40.206] Build 8903490537046280848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:58:40.206] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:22:0) after 1m0s
[19:59:40.233] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:22:0)
[19:59:40.233] Timer tick, asking Buildbucket for the build status
[19:59:40.507] Build 8903490537046280848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:59:40.507] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:23:0) after 1m0s
[20:00:40.537] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:23:0)
[20:00:40.537] Timer tick, asking Buildbucket for the build status
[20:00:40.687] Build 8903490537046280848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:00:40.687] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:24:0) after 1m0s
[20:01:40.725] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:24:0)
[20:01:40.725] Timer tick, asking Buildbucket for the build status
[20:01:40.877] Build 8903490537046280848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:01:40.877] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:25:0) after 1m0s
[20:02:40.912] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:25:0)
[20:02:40.912] Timer tick, asking Buildbucket for the build status
[20:02:41.133] Build 8903490537046280848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:02:41.133] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:26:0) after 1m0s
[20:03:41.274] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:26:0)
[20:03:41.274] Timer tick, asking Buildbucket for the build status
[20:03:41.434] Build 8903490537046280848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:03:41.434] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:27:0) after 1m0s
[20:04:41.489] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:27:0)
[20:04:41.489] Timer tick, asking Buildbucket for the build status
[20:04:41.651] Build 8903490537046280848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:04:41.651] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:28:0) after 1m0s
[20:05:41.676] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:28:0)
[20:05:41.676] Timer tick, asking Buildbucket for the build status
[20:05:41.964] Build 8903490537046280848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:05:41.964] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:29:0) after 1m0s
[20:06:42.101] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:29:0)
[20:06:42.101] Timer tick, asking Buildbucket for the build status
[20:06:42.350] Build 8903490537046280848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:06:42.350] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:30:0) after 1m0s
[20:07:42.402] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:30:0)
[20:07:42.402] Timer tick, asking Buildbucket for the build status
[20:07:42.777] Build 8903490537046280848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:07:42.777] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:31:0) after 1m0s
[20:08:42.878] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:31:0)
[20:08:42.878] Timer tick, asking Buildbucket for the build status
[20:08:43.075] Build 8903490537046280848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:08:43.075] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:32:0) after 1m0s
[20:09:43.110] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:32:0)
[20:09:43.110] Timer tick, asking Buildbucket for the build status
[20:09:43.313] Build 8903490537046280848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:09:43.313] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:33:0) after 1m0s
[20:10:43.447] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:33:0)
[20:10:43.447] Timer tick, asking Buildbucket for the build status
[20:10:43.604] Build 8903490537046280848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:10:43.604] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:34:0) after 1m0s
[20:11:43.601] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:34:0)
[20:11:43.601] Timer tick, asking Buildbucket for the build status
[20:11:44.213] Build 8903490537046280848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:11:44.213] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:35:0) after 1m0s
[20:12:44.339] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:35:0)
[20:12:44.339] Timer tick, asking Buildbucket for the build status
[20:12:44.536] Build 8903490537046280848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:12:44.536] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:36:0) after 1m0s
[20:13:44.558] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:36:0)
[20:13:44.558] Timer tick, asking Buildbucket for the build status
[20:13:44.713] Build 8903490537046280848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:13:44.713] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:37:0) after 1m0s
[20:14:44.742] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:37:0)
[20:14:44.742] Timer tick, asking Buildbucket for the build status
[20:14:44.914] Build 8903490537046280848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:14:44.914] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:38:0) after 1m0s
[20:15:44.951] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:38:0)
[20:15:44.951] Timer tick, asking Buildbucket for the build status
[20:15:45.486] Build 8903490537046280848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:15:45.486] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:39:0) after 1m0s
[20:16:45.509] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:39:0)
[20:16:45.509] Timer tick, asking Buildbucket for the build status
[20:16:45.669] Build 8903490537046280848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:16:45.669] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:40:0) after 1m0s
[20:17:45.691] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:40:0)
[20:17:45.691] Timer tick, asking Buildbucket for the build status
[20:17:46.133] Build 8903490537046280848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:17:46.133] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:41:0) after 1m0s
[20:18:46.157] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:41:0)
[20:18:46.157] Timer tick, asking Buildbucket for the build status
[20:18:46.648] Build 8903490537046280848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:18:46.648] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:42:0) after 1m0s
[20:19:46.675] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:42:0)
[20:19:46.675] Timer tick, asking Buildbucket for the build status
[20:19:46.809] Build 8903490537046280848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:19:46.809] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:43:0) after 1m0s
[20:20:46.834] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:43:0)
[20:20:46.834] Timer tick, asking Buildbucket for the build status
[20:20:47.061] Build 8903490537046280848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:20:47.061] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:44:0) after 1m0s
[20:21:47.096] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:44:0)
[20:21:47.096] Timer tick, asking Buildbucket for the build status
[20:21:47.437] Build 8903490537046280848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:21:47.437] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:45:0) after 1m0s
[20:22:47.458] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:45:0)
[20:22:47.458] Timer tick, asking Buildbucket for the build status
[20:22:47.624] Build 8903490537046280848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:22:47.624] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:46:0) after 1m0s
[20:23:47.649] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:46:0)
[20:23:47.649] Timer tick, asking Buildbucket for the build status
[20:23:47.802] Build 8903490537046280848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:23:47.802] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:47:0) after 1m0s
[20:24:47.824] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:47:0)
[20:24:47.824] Timer tick, asking Buildbucket for the build status
[20:24:47.969] Build 8903490537046280848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:24:47.969] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:48:0) after 1m0s
[20:25:48.205] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:48:0)
[20:25:48.205] Timer tick, asking Buildbucket for the build status
[20:25:48.421] Build 8903490537046280848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:25:48.421] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:49:0) after 1m0s
[20:26:48.465] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:49:0)
[20:26:48.465] Timer tick, asking Buildbucket for the build status
[20:26:48.639] Build 8903490537046280848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:26:48.639] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:50:0) after 1m0s
[20:27:48.980] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:50:0)
[20:27:48.981] Timer tick, asking Buildbucket for the build status
[20:27:49.181] Build 8903490537046280848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:27:49.181] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:51:0) after 1m0s
[20:28:49.222] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:51:0)
[20:28:49.222] Timer tick, asking Buildbucket for the build status
[20:28:51.416] Build 8903490537046280848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:28:51.416] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:52:0) after 1m0s
[20:29:51.577] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:52:0)
[20:29:51.577] Timer tick, asking Buildbucket for the build status
[20:29:52.303] Build 8903490537046280848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:29:52.303] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:53:0) after 1m0s
[20:30:52.675] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:53:0)
[20:30:52.675] Timer tick, asking Buildbucket for the build status
[20:30:52.883] Build 8903490537046280848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:30:52.883] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:54:0) after 1m0s
[20:31:52.907] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:54:0)
[20:31:52.907] Timer tick, asking Buildbucket for the build status
[20:31:53.113] Build 8903490537046280848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:31:53.113] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:55:0) after 1m0s
[20:32:53.449] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:55:0)
[20:32:53.449] Timer tick, asking Buildbucket for the build status
[20:32:53.608] Build 8903490537046280848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:32:53.608] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:56:0) after 1m0s
[20:33:53.865] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:56:0)
[20:33:53.865] Timer tick, asking Buildbucket for the build status
[20:33:54.705] Build 8903490537046280848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:33:54.705] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:57:0) after 1m0s
[20:34:54.746] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:57:0)
[20:34:54.746] Timer tick, asking Buildbucket for the build status
[20:34:55.490] Build 8903490537046280848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:34:55.490] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:58:0) after 1m0s
[20:35:55.722] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:58:0)
[20:35:55.722] Timer tick, asking Buildbucket for the build status
[20:35:55.879] Build 8903490537046280848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:35:55.880] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:59:0) after 1m0s
[20:36:55.906] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:59:0)
[20:36:55.906] Timer tick, asking Buildbucket for the build status
[20:36:56.114] Build 8903490537046280848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:36:56.114] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:60:0) after 1m0s
[20:37:56.208] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:60:0)
[20:37:56.208] Timer tick, asking Buildbucket for the build status
[20:37:57.027] Build 8903490537046280848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:37:57.027] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:61:0) after 1m0s
[20:38:57.048] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:61:0)
[20:38:57.048] Timer tick, asking Buildbucket for the build status
[20:38:57.257] Build 8903490537046280848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:38:57.257] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:62:0) after 1m0s
[20:39:57.284] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:62:0)
[20:39:57.284] Timer tick, asking Buildbucket for the build status
[20:39:57.479] Build 8903490537046280848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:39:57.479] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:63:0) after 1m0s
[20:40:57.501] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:63:0)
[20:40:57.501] Timer tick, asking Buildbucket for the build status
[20:40:58.442] Build 8903490537046280848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:40:58.442] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:64:0) after 1m0s
[20:41:58.829] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:64:0)
[20:41:58.829] Timer tick, asking Buildbucket for the build status
[20:41:59.045] Build 8903490537046280848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:41:59.045] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:65:0) after 1m0s
[20:42:59.092] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:65:0)
[20:42:59.092] Timer tick, asking Buildbucket for the build status
[20:42:59.783] Build 8903490537046280848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:42:59.783] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:66:0) after 1m0s
[20:43:59.953] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:66:0)
[20:43:59.953] Timer tick, asking Buildbucket for the build status
[20:44:00.145] Build 8903490537046280848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:44:00.145] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:67:0) after 1m0s
[20:45:00.273] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:67:0)
[20:45:00.273] Timer tick, asking Buildbucket for the build status
[20:45:00.423] Build 8903490537046280848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:45:00.423] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:68:0) after 1m0s
[20:46:00.629] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:68:0)
[20:46:00.629] Timer tick, asking Buildbucket for the build status
[20:46:00.891] Build 8903490537046280848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:46:00.891] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:69:0) after 1m0s
[20:47:00.930] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:69:0)
[20:47:00.930] Timer tick, asking Buildbucket for the build status
[20:47:01.424] Build 8903490537046280848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:47:01.424] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:70:0) after 1m0s
[20:48:01.599] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:70:0)
[20:48:01.599] Timer tick, asking Buildbucket for the build status
[20:48:02.292] Build 8903490537046280848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:48:02.292] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:71:0) after 1m0s
[20:49:02.570] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:71:0)
[20:49:02.570] Timer tick, asking Buildbucket for the build status
[20:49:03.868] Build 8903490537046280848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:49:03.868] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:72:0) after 1m0s
[20:50:03.910] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:72:0)
[20:50:03.910] Timer tick, asking Buildbucket for the build status
[20:50:04.113] Build 8903490537046280848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:50:04.113] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:73:0) after 1m0s
[20:51:04.181] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:73:0)
[20:51:04.181] Timer tick, asking Buildbucket for the build status
[20:51:04.377] Build 8903490537046280848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:51:04.377] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:74:0) after 1m0s
[20:52:04.557] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:74:0)
[20:52:04.557] Timer tick, asking Buildbucket for the build status
[20:52:04.746] Build 8903490537046280848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:52:04.746] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:75:0) after 1m0s
[20:53:04.784] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:75:0)
[20:53:04.784] Timer tick, asking Buildbucket for the build status
[20:53:04.937] Build 8903490537046280848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:53:04.937] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:76:0) after 1m0s
[20:54:04.959] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:76:0)
[20:54:04.959] Timer tick, asking Buildbucket for the build status
[20:54:05.112] Build 8903490537046280848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:54:05.112] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:77:0) after 1m0s
[20:55:05.367] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:77:0)
[20:55:05.367] Timer tick, asking Buildbucket for the build status
[20:55:06.024] Build 8903490537046280848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:55:06.024] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:78:0) after 1m0s
[20:56:06.207] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:78:0)
[20:56:06.207] Timer tick, asking Buildbucket for the build status
[20:56:06.912] Build 8903490537046280848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:56:06.912] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:79:0) after 1m0s
[20:57:07.003] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:79:0)
[20:57:07.003] Timer tick, asking Buildbucket for the build status
[20:57:07.199] Build 8903490537046280848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:57:07.199] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:80:0) after 1m0s
[20:58:07.411] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:80:0)
[20:58:07.411] Timer tick, asking Buildbucket for the build status
[20:58:08.307] Build 8903490537046280848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:58:08.307] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:81:0) after 1m0s
[20:59:08.429] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:81:0)
[20:59:08.429] Timer tick, asking Buildbucket for the build status
[20:59:09.216] Build 8903490537046280848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:59:09.216] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:82:0) after 1m0s
[21:00:09.310] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:82:0)
[21:00:09.310] Timer tick, asking Buildbucket for the build status
[21:00:09.899] Build 8903490537046280848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:00:09.899] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:83:0) after 1m0s
[21:01:10.186] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:83:0)
[21:01:10.186] Timer tick, asking Buildbucket for the build status
[21:01:10.371] Build 8903490537046280848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:01:10.371] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:84:0) after 1m0s
[21:02:10.660] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:84:0)
[21:02:10.660] Timer tick, asking Buildbucket for the build status
[21:02:10.897] Build 8903490537046280848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:02:10.897] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:85:0) after 1m0s
[21:03:11.147] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:85:0)
[21:03:11.147] Timer tick, asking Buildbucket for the build status
[21:03:11.774] Build 8903490537046280848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:03:11.774] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:86:0) after 1m0s
[21:04:11.866] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:86:0)
[21:04:11.866] Timer tick, asking Buildbucket for the build status
[21:04:12.074] Build 8903490537046280848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:04:12.074] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:87:0) after 1m0s
[21:05:12.124] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:87:0)
[21:05:12.124] Timer tick, asking Buildbucket for the build status
[21:05:12.316] Build 8903490537046280848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:05:12.316] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:88:0) after 1m0s
[21:06:12.788] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:88:0)
[21:06:12.788] Timer tick, asking Buildbucket for the build status
[21:06:13.495] Build 8903490537046280848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:06:13.495] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:89:0) after 1m0s
[21:07:13.645] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:89:0)
[21:07:13.645] Timer tick, asking Buildbucket for the build status
[21:07:13.843] Build 8903490537046280848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:07:13.843] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:90:0) after 1m0s
[21:08:13.963] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:90:0)
[21:08:13.963] Timer tick, asking Buildbucket for the build status
[21:08:14.127] Build 8903490537046280848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:08:14.127] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:91:0) after 1m0s
[21:09:14.157] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:91:0)
[21:09:14.157] Timer tick, asking Buildbucket for the build status
[21:09:14.828] Build 8903490537046280848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:09:14.829] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:92:0) after 1m0s
[21:10:16.902] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:92:0)
[21:10:16.902] Timer tick, asking Buildbucket for the build status
[21:10:17.235] Build 8903490537046280848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:10:17.235] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:93:0) after 1m0s
[21:11:17.537] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:93:0)
[21:11:17.537] Timer tick, asking Buildbucket for the build status
[21:11:17.698] Build 8903490537046280848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:11:17.698] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:94:0) after 1m0s
[21:12:17.889] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:94:0)
[21:12:17.889] Timer tick, asking Buildbucket for the build status
[21:12:18.043] Build 8903490537046280848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:12:18.043] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:95:0) after 1m0s
[21:13:18.357] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:95:0)
[21:13:18.357] Timer tick, asking Buildbucket for the build status
[21:13:18.559] Build 8903490537046280848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:13:18.559] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:96:0) after 1m0s
[21:14:18.743] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:96:0)
[21:14:18.743] Timer tick, asking Buildbucket for the build status
[21:14:18.946] Build 8903490537046280848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:14:18.947] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:97:0) after 1m0s
[21:15:19.525] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:97:0)
[21:15:19.525] Timer tick, asking Buildbucket for the build status
[21:15:20.532] Build 8903490537046280848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:15:20.532] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:98:0) after 1m0s
[21:16:20.633] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:98:0)
[21:16:20.633] Timer tick, asking Buildbucket for the build status
[21:16:20.797] Build 8903490537046280848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:16:20.797] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:99:0) after 1m0s
[21:17:20.947] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:99:0)
[21:17:20.947] Timer tick, asking Buildbucket for the build status
[21:17:21.612] Build 8903490537046280848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:17:21.612] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:100:0) after 1m0s
[21:18:22.384] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:100:0)
[21:18:22.384] Timer tick, asking Buildbucket for the build status
[21:18:22.572] Build 8903490537046280848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:18:22.572] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:101:0) after 1m0s
[21:19:22.891] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:101:0)
[21:19:22.891] Timer tick, asking Buildbucket for the build status
[21:19:23.155] Build 8903490537046280848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:19:23.155] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:102:0) after 1m0s
[21:20:23.404] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:102:0)
[21:20:23.404] Timer tick, asking Buildbucket for the build status
[21:20:23.564] Build 8903490537046280848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:20:23.564] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:103:0) after 1m0s
[21:21:23.894] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:103:0)
[21:21:23.894] Timer tick, asking Buildbucket for the build status
[21:21:24.675] Build 8903490537046280848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:21:24.675] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:104:0) after 1m0s
[21:22:24.718] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:104:0)
[21:22:24.718] Timer tick, asking Buildbucket for the build status
[21:22:24.895] Build 8903490537046280848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:22:24.895] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:105:0) after 1m0s
[21:23:24.931] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:105:0)
[21:23:24.931] Timer tick, asking Buildbucket for the build status
[21:23:25.123] Build 8903490537046280848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:23:25.123] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:106:0) after 1m0s
[21:24:25.166] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:106:0)
[21:24:25.166] Timer tick, asking Buildbucket for the build status
[21:24:25.450] Build 8903490537046280848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:24:25.450] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:107:0) after 1m0s
[21:25:36.089] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:107:0)
[21:25:36.089] Timer tick, asking Buildbucket for the build status
[21:25:36.344] Build 8903490537046280848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:25:36.344] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:108:0) after 1m0s
[21:26:36.387] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:108:0)
[21:26:36.387] Timer tick, asking Buildbucket for the build status
[21:26:36.562] Build 8903490537046280848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:26:36.562] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:109:0) after 1m0s
[21:27:36.628] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:109:0)
[21:27:36.628] Timer tick, asking Buildbucket for the build status
[21:27:36.810] Build 8903490537046280848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:27:36.811] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:110:0) after 1m0s
[21:28:36.956] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:110:0)
[21:28:36.956] Timer tick, asking Buildbucket for the build status
[21:28:37.138] Build 8903490537046280848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:28:37.138] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:111:0) after 1m0s
[21:29:37.183] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:111:0)
[21:29:37.183] Timer tick, asking Buildbucket for the build status
[21:29:37.449] Build 8903490537046280848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:29:37.449] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:112:0) after 1m0s
[21:30:37.430] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:112:0)
[21:30:37.430] Timer tick, asking Buildbucket for the build status
[21:30:37.588] Build 8903490537046280848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:30:37.588] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:113:0) after 1m0s
[21:31:37.614] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:113:0)
[21:31:37.614] Timer tick, asking Buildbucket for the build status
[21:31:37.787] Build 8903490537046280848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:31:37.787] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:114:0) after 1m0s
[21:32:37.814] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:114:0)
[21:32:37.814] Timer tick, asking Buildbucket for the build status
[21:32:39.024] Build 8903490537046280848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:32:39.024] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:115:0) after 1m0s
[21:33:39.047] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:115:0)
[21:33:39.047] Timer tick, asking Buildbucket for the build status
[21:33:39.235] Build 8903490537046280848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:33:39.235] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:116:0) after 1m0s
[21:34:39.261] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:116:0)
[21:34:39.261] Timer tick, asking Buildbucket for the build status
[21:34:39.385] Build 8903490537046280848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:34:39.385] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:117:0) after 1m0s
[21:35:39.409] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:117:0)
[21:35:39.409] Timer tick, asking Buildbucket for the build status
[21:35:39.721] Build 8903490537046280848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:35:39.721] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:118:0) after 1m0s
[21:36:39.744] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:118:0)
[21:36:39.744] Timer tick, asking Buildbucket for the build status
[21:36:39.914] Build 8903490537046280848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:36:39.914] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:119:0) after 1m0s
[21:37:39.941] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:119:0)
[21:37:39.941] Timer tick, asking Buildbucket for the build status
[21:37:40.828] Build 8903490537046280848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:37:40.828] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:120:0) after 1m0s
[21:38:40.861] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:120:0)
[21:38:40.861] Timer tick, asking Buildbucket for the build status
[21:38:41.275] Build 8903490537046280848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:38:41.275] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:121:0) after 1m0s
[21:39:41.335] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:121:0)
[21:39:41.335] Timer tick, asking Buildbucket for the build status
[21:39:41.501] Build 8903490537046280848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:39:41.501] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:122:0) after 1m0s
[21:40:41.538] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:122:0)
[21:40:41.538] Timer tick, asking Buildbucket for the build status
[21:40:41.747] Build 8903490537046280848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:40:41.747] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:123:0) after 1m0s
[21:41:41.776] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:123:0)
[21:41:41.776] Timer tick, asking Buildbucket for the build status
[21:41:41.941] Build 8903490537046280848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:41:41.941] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:124:0) after 1m0s
[21:42:41.969] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:124:0)
[21:42:41.969] Timer tick, asking Buildbucket for the build status
[21:42:42.155] Build 8903490537046280848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:42:42.155] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:125:0) after 1m0s
[21:43:42.243] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:125:0)
[21:43:42.243] Timer tick, asking Buildbucket for the build status
[21:43:42.491] Build 8903490537046280848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:43:42.491] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:126:0) after 1m0s
[21:44:42.531] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:126:0)
[21:44:42.531] Timer tick, asking Buildbucket for the build status
[21:44:42.892] Build 8903490537046280848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:44:42.892] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:127:0) after 1m0s
[21:45:42.917] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:127:0)
[21:45:42.917] Timer tick, asking Buildbucket for the build status
[21:45:43.049] Build 8903490537046280848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:45:43.049] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:128:0) after 1m0s
[21:46:43.079] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:128:0)
[21:46:43.079] Timer tick, asking Buildbucket for the build status
[21:46:43.277] Build 8903490537046280848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:46:43.277] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:129:0) after 1m0s
[21:47:43.311] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:129:0)
[21:47:43.311] Timer tick, asking Buildbucket for the build status
[21:47:43.586] Build 8903490537046280848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:47:43.586] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:130:0) after 1m0s
[21:48:43.612] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:130:0)
[21:48:43.612] Timer tick, asking Buildbucket for the build status
[21:48:43.961] Build 8903490537046280848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:48:43.961] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068920599202707856:131:0) after 1m0s
[21:48:56.039] Received PubSub notification, asking Buildbucket for the build status
[21:48:56.317] Build 8903490537046280848: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[21:48:56.317] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary": true,
"canary_preference": "CANARY",
"completed_ts": "1567374535187476",
"created_by": "project:chromium",
"created_ts": "1567366770660507",
"id": "8903490537046280848",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"806eded3cd02c547c52ffb0dd2de4b10a59046fc\"}}",
"project": "chromium",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"$recipe_engine/path\": {\"cache_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\cache\", \"temp_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\tmp\\\\rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"bot_id\": \"windows-deterministic-0-bzrg\", \"branch\": \"refs/heads/master\", \"buildername\": \"Windows deterministic\", \"buildnumber\": 14599, \"got_angle_revision\": \"791ad7b8774a06c996467eb9e6e5137b1e4ef452\", \"got_dawn_revision\": \"824424fa351be2b2eccd78b2f31de1d5d1ad4183\", \"got_nacl_revision\": \"058a26f57015051953a727add26e7e26348b9558\", \"got_revision\": \"806eded3cd02c547c52ffb0dd2de4b10a59046fc\", \"got_revision_cp\": \"refs/heads/master@{#692384}\", \"got_swarming_client_revision\": \"96f125709acfd0b48fc1e5dae7d6ea42291726ac\", \"got_v8_revision\": \"69d20d247f62a3378d15ce0956ed8bf9665e6a44\", \"got_v8_revision_cp\": \"refs/heads/7.8.243@{#1}\", \"got_webrtc_revision\": \"ce6a0c8fb3f3a19caa797d5913b7e7c8ecf3ca9b\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#29029}\", \"mastername\": \"chromium.win\", \"path_config\": \"generic\", \"recipe\": \"swarming/deterministic_build\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"806eded3cd02c547c52ffb0dd2de4b10a59046fc\"}, \"swarming\": {\"bot_dimensions\": {\"builder\": [\"Windows deterministic\"], \"caches\": [\"builder_e8fffaf9fac16164b1ec16ebaa1f377b792d39a582f7640830518d8ff7303d0c_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"8\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\"], \"gce\": [\"1\"], \"gpu\": [\"none\"], \"id\": [\"windows-deterministic-0-bzrg\"], \"image\": [\"chrome-win10-1703-19072400-fedda89f1f1\"], \"integrity\": [\"high\"], \"locale\": [\"en_US.cp1252\"], \"machine_type\": [\"n1-standard-8\"], \"os\": [\"Windows\", \"Windows-10\", \"Windows-10-15063\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.13\"], \"server_version\": [\"4397-8786906\"], \"ssd\": [\"0\"], \"windows_client_version\": [\"10\"], \"zone\": [\"us\", \"us-west\", \"us-west1\", \"us-west1-c\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1567366773019634",
"status": "COMPLETED",
"status_changed_ts": "1567374535511692",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/14599",
"builder:Windows deterministic",
"buildset:commit/git/806eded3cd02c547c52ffb0dd2de4b10a59046fc",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/806eded3cd02c547c52ffb0dd2de4b10a59046fc",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9068920599202707856",
"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/8903490537046280848/+/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:4707287bbf84e210",
"user_agent:luci-scheduler"
],
"updated_ts": "1567374535530379",
"url": "https://ci.chromium.org/b/8903490537046280848",
"utcnow_ts": "1567374536308451"
}
}
[21:48:56.317] Invocation finished in 2h9m27.097203265s with status SUCCEEDED