[23:09:48.411] New invocation is queued and will start shortly
[23:09:49.730] Starting the invocation (attempt 1)
[23:09:49.766] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[23:09:49.767] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[23:09:49.767] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9086573775486655728",
"parameters_json": "{\"builder_name\":\"Windows deterministic\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src.git\",\"revision\":\"2f76aeef54efba5408522beb7b1dd14973c9338b\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Windows deterministic",
"scheduler_invocation_id:9086573775486655728",
"scheduler_job_id:chromium/Windows deterministic",
"user_agent:luci-scheduler",
"buildset:commit/git/2f76aeef54efba5408522beb7b1dd14973c9338b",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/2f76aeef54efba5408522beb7b1dd14973c9338b",
"gitiles_ref:refs/heads/master"
]
}
[23:09:50.789] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1550531389847578",
"id": "8921143713317616432",
"lease_expiration_ts": "1550650190522578",
"lease_key": "2067741032",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"2f76aeef54efba5408522beb7b1dd14973c9338b\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1550531390674578",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/12017",
"builder:Windows deterministic",
"buildset:commit/git/2f76aeef54efba5408522beb7b1dd14973c9338b",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/2f76aeef54efba5408522beb7b1dd14973c9338b",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9086573775486655728",
"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/8921143713317616432/+/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:431bb0f342827810",
"user_agent:luci-scheduler"
],
"updated_ts": "1550531390674707",
"url": "https://ci.chromium.org/b/8921143713317616432",
"utcnow_ts": "1550531390779783"
}
}
[23:09:50.789] Task URL: https://ci.chromium.org/b/8921143713317616432
[23:09:50.789] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086573775486655728:2:0) after 1m0s
[23:09:53.477] Received PubSub notification, asking Buildbucket for the build status
[23:09:53.528] Build 8921143713317616432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:10:50.805] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086573775486655728:2:0)
[23:10:50.805] Timer tick, asking Buildbucket for the build status
[23:10:50.893] Build 8921143713317616432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:10:50.893] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086573775486655728:4:0) after 1m0s
[23:11:50.911] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086573775486655728:4:0)
[23:11:50.911] Timer tick, asking Buildbucket for the build status
[23:11:51.006] Build 8921143713317616432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:11:51.006] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086573775486655728:5:0) after 1m0s
[23:12:51.025] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086573775486655728:5:0)
[23:12:51.025] Timer tick, asking Buildbucket for the build status
[23:12:51.176] Build 8921143713317616432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:12:51.176] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086573775486655728:6:0) after 1m0s
[23:13:51.193] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086573775486655728:6:0)
[23:13:51.193] Timer tick, asking Buildbucket for the build status
[23:13:51.450] Build 8921143713317616432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:13:51.450] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086573775486655728:7:0) after 1m0s
[23:14:51.468] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086573775486655728:7:0)
[23:14:51.468] Timer tick, asking Buildbucket for the build status
[23:14:51.526] Build 8921143713317616432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:14:51.526] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086573775486655728:8:0) after 1m0s
[23:15:51.546] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086573775486655728:8:0)
[23:15:51.546] Timer tick, asking Buildbucket for the build status
[23:15:51.614] Build 8921143713317616432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:15:51.614] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086573775486655728:9:0) after 1m0s
[23:16:51.640] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086573775486655728:9:0)
[23:16:51.640] Timer tick, asking Buildbucket for the build status
[23:16:51.686] Build 8921143713317616432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:16:51.686] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086573775486655728:10:0) after 1m0s
[23:17:51.705] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086573775486655728:10:0)
[23:17:51.705] Timer tick, asking Buildbucket for the build status
[23:17:51.857] Build 8921143713317616432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:17:51.857] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086573775486655728:11:0) after 1m0s
[23:18:51.874] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086573775486655728:11:0)
[23:18:51.874] Timer tick, asking Buildbucket for the build status
[23:18:51.968] Build 8921143713317616432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:18:51.969] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086573775486655728:12:0) after 1m0s
[23:19:51.987] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086573775486655728:12:0)
[23:19:51.987] Timer tick, asking Buildbucket for the build status
[23:19:52.045] Build 8921143713317616432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:19:52.045] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086573775486655728:13:0) after 1m0s
[23:20:52.093] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086573775486655728:13:0)
[23:20:52.093] Timer tick, asking Buildbucket for the build status
[23:20:52.189] Build 8921143713317616432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:20:52.189] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086573775486655728:14:0) after 1m0s
[23:21:52.224] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086573775486655728:14:0)
[23:21:52.224] Timer tick, asking Buildbucket for the build status
[23:21:52.581] Build 8921143713317616432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:21:52.581] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086573775486655728:15:0) after 1m0s
[23:22:52.599] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086573775486655728:15:0)
[23:22:52.599] Timer tick, asking Buildbucket for the build status
[23:22:53.000] Build 8921143713317616432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:22:53.000] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086573775486655728:16:0) after 1m0s
[23:23:53.016] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086573775486655728:16:0)
[23:23:53.016] Timer tick, asking Buildbucket for the build status
[23:23:53.066] Build 8921143713317616432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:23:53.066] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086573775486655728:17:0) after 1m0s
[23:24:53.099] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086573775486655728:17:0)
[23:24:53.099] Timer tick, asking Buildbucket for the build status
[23:24:53.184] Build 8921143713317616432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:24:53.184] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086573775486655728:18:0) after 1m0s
[23:25:53.202] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086573775486655728:18:0)
[23:25:53.202] Timer tick, asking Buildbucket for the build status
[23:25:53.250] Build 8921143713317616432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:25:53.250] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086573775486655728:19:0) after 1m0s
[23:26:53.267] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086573775486655728:19:0)
[23:26:53.267] Timer tick, asking Buildbucket for the build status
[23:26:53.498] Build 8921143713317616432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:26:53.498] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086573775486655728:20:0) after 1m0s
[23:27:53.514] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086573775486655728:20:0)
[23:27:53.514] Timer tick, asking Buildbucket for the build status
[23:27:53.624] Build 8921143713317616432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:27:53.624] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086573775486655728:21:0) after 1m0s
[23:28:53.639] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086573775486655728:21:0)
[23:28:53.639] Timer tick, asking Buildbucket for the build status
[23:28:53.703] Build 8921143713317616432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:28:53.703] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086573775486655728:22:0) after 1m0s
[23:29:53.720] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086573775486655728:22:0)
[23:29:53.720] Timer tick, asking Buildbucket for the build status
[23:29:53.891] Build 8921143713317616432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:29:53.891] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086573775486655728:23:0) after 1m0s
[23:30:53.910] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086573775486655728:23:0)
[23:30:53.910] Timer tick, asking Buildbucket for the build status
[23:30:53.960] Build 8921143713317616432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:30:53.960] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086573775486655728:24:0) after 1m0s
[23:31:53.980] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086573775486655728:24:0)
[23:31:53.980] Timer tick, asking Buildbucket for the build status
[23:31:54.028] Build 8921143713317616432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:31:54.028] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086573775486655728:25:0) after 1m0s
[23:32:54.044] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086573775486655728:25:0)
[23:32:54.044] Timer tick, asking Buildbucket for the build status
[23:32:54.182] Build 8921143713317616432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:32:54.182] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086573775486655728:26:0) after 1m0s
[23:33:54.201] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086573775486655728:26:0)
[23:33:54.201] Timer tick, asking Buildbucket for the build status
[23:33:54.295] Build 8921143713317616432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:33:54.295] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086573775486655728:27:0) after 1m0s
[23:34:54.313] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086573775486655728:27:0)
[23:34:54.313] Timer tick, asking Buildbucket for the build status
[23:34:54.362] Build 8921143713317616432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:34:54.362] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086573775486655728:28:0) after 1m0s
[23:35:54.377] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086573775486655728:28:0)
[23:35:54.377] Timer tick, asking Buildbucket for the build status
[23:35:54.444] Build 8921143713317616432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:35:54.444] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086573775486655728:29:0) after 1m0s
[23:36:54.461] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086573775486655728:29:0)
[23:36:54.461] Timer tick, asking Buildbucket for the build status
[23:36:54.538] Build 8921143713317616432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:36:54.538] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086573775486655728:30:0) after 1m0s
[23:37:54.559] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086573775486655728:30:0)
[23:37:54.559] Timer tick, asking Buildbucket for the build status
[23:37:54.872] Build 8921143713317616432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:37:54.873] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086573775486655728:31:0) after 1m0s
[23:38:54.996] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086573775486655728:31:0)
[23:38:54.996] Timer tick, asking Buildbucket for the build status
[23:38:55.180] Build 8921143713317616432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:38:55.180] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086573775486655728:32:0) after 1m0s
[23:39:55.197] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086573775486655728:32:0)
[23:39:55.197] Timer tick, asking Buildbucket for the build status
[23:39:55.283] Build 8921143713317616432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:39:55.283] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086573775486655728:33:0) after 1m0s
[23:40:55.301] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086573775486655728:33:0)
[23:40:55.301] Timer tick, asking Buildbucket for the build status
[23:40:55.376] Build 8921143713317616432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:40:55.377] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086573775486655728:34:0) after 1m0s
[23:41:55.392] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086573775486655728:34:0)
[23:41:55.392] Timer tick, asking Buildbucket for the build status
[23:41:55.511] Build 8921143713317616432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:41:55.511] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086573775486655728:35:0) after 1m0s
[23:42:55.545] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086573775486655728:35:0)
[23:42:55.545] Timer tick, asking Buildbucket for the build status
[23:42:55.610] Build 8921143713317616432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:42:55.610] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086573775486655728:36:0) after 1m0s
[23:43:55.627] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086573775486655728:36:0)
[23:43:55.627] Timer tick, asking Buildbucket for the build status
[23:43:55.698] Build 8921143713317616432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:43:55.698] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086573775486655728:37:0) after 1m0s
[23:44:55.721] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086573775486655728:37:0)
[23:44:55.721] Timer tick, asking Buildbucket for the build status
[23:44:55.812] Build 8921143713317616432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:44:55.812] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086573775486655728:38:0) after 1m0s
[23:45:55.832] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086573775486655728:38:0)
[23:45:55.832] Timer tick, asking Buildbucket for the build status
[23:45:56.098] Build 8921143713317616432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:45:56.098] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086573775486655728:39:0) after 1m0s
[23:46:56.157] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086573775486655728:39:0)
[23:46:56.157] Timer tick, asking Buildbucket for the build status
[23:46:56.216] Build 8921143713317616432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:46:56.216] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086573775486655728:40:0) after 1m0s
[23:47:56.277] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086573775486655728:40:0)
[23:47:56.277] Timer tick, asking Buildbucket for the build status
[23:47:56.355] Build 8921143713317616432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:47:56.355] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086573775486655728:41:0) after 1m0s
[23:48:56.422] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086573775486655728:41:0)
[23:48:56.422] Timer tick, asking Buildbucket for the build status
[23:48:56.468] Build 8921143713317616432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:48:56.468] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086573775486655728:42:0) after 1m0s
[23:49:56.491] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086573775486655728:42:0)
[23:49:56.491] Timer tick, asking Buildbucket for the build status
[23:49:56.558] Build 8921143713317616432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:49:56.558] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086573775486655728:43:0) after 1m0s
[23:50:56.600] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086573775486655728:43:0)
[23:50:56.600] Timer tick, asking Buildbucket for the build status
[23:50:56.660] Build 8921143713317616432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:50:56.660] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086573775486655728:44:0) after 1m0s
[23:51:56.682] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086573775486655728:44:0)
[23:51:56.682] Timer tick, asking Buildbucket for the build status
[23:51:56.751] Build 8921143713317616432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:51:56.751] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086573775486655728:45:0) after 1m0s
[23:52:56.769] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086573775486655728:45:0)
[23:52:56.769] Timer tick, asking Buildbucket for the build status
[23:52:56.831] Build 8921143713317616432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:52:56.831] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086573775486655728:46:0) after 1m0s
[23:53:56.848] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086573775486655728:46:0)
[23:53:56.848] Timer tick, asking Buildbucket for the build status
[23:53:56.969] Build 8921143713317616432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:53:56.969] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086573775486655728:47:0) after 1m0s
[23:54:56.991] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086573775486655728:47:0)
[23:54:56.991] Timer tick, asking Buildbucket for the build status
[23:54:57.055] Build 8921143713317616432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:54:57.055] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086573775486655728:48:0) after 1m0s
[23:55:57.086] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086573775486655728:48:0)
[23:55:57.086] Timer tick, asking Buildbucket for the build status
[23:55:57.219] Build 8921143713317616432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:55:57.219] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086573775486655728:49:0) after 1m0s
[23:56:57.310] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086573775486655728:49:0)
[23:56:57.310] Timer tick, asking Buildbucket for the build status
[23:56:57.560] Build 8921143713317616432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:56:57.560] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086573775486655728:50:0) after 1m0s
[23:57:57.580] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086573775486655728:50:0)
[23:57:57.581] Timer tick, asking Buildbucket for the build status
[23:57:57.669] Build 8921143713317616432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:57:57.669] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086573775486655728:51:0) after 1m0s
[23:58:57.711] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086573775486655728:51:0)
[23:58:57.711] Timer tick, asking Buildbucket for the build status
[23:58:58.276] Build 8921143713317616432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:58:58.276] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086573775486655728:52:0) after 1m0s
[23:59:58.299] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086573775486655728:52:0)
[23:59:58.299] Timer tick, asking Buildbucket for the build status
[23:59:58.472] Build 8921143713317616432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:59:58.472] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086573775486655728:53:0) after 1m0s
[00:00:58.492] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086573775486655728:53:0)
[00:00:58.492] Timer tick, asking Buildbucket for the build status
[00:00:58.539] Build 8921143713317616432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:00:58.539] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086573775486655728:54:0) after 1m0s
[00:01:58.608] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086573775486655728:54:0)
[00:01:58.608] Timer tick, asking Buildbucket for the build status
[00:01:58.674] Build 8921143713317616432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:01:58.674] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086573775486655728:55:0) after 1m0s
[00:02:58.701] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086573775486655728:55:0)
[00:02:58.701] Timer tick, asking Buildbucket for the build status
[00:02:58.773] Build 8921143713317616432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:02:58.773] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086573775486655728:56:0) after 1m0s
[00:03:58.859] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086573775486655728:56:0)
[00:03:58.859] Timer tick, asking Buildbucket for the build status
[00:03:59.062] Build 8921143713317616432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:03:59.062] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086573775486655728:57:0) after 1m0s
[00:04:59.129] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086573775486655728:57:0)
[00:04:59.129] Timer tick, asking Buildbucket for the build status
[00:04:59.321] Build 8921143713317616432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:04:59.321] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086573775486655728:58:0) after 1m0s
[00:05:59.412] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086573775486655728:58:0)
[00:05:59.412] Timer tick, asking Buildbucket for the build status
[00:05:59.722] Build 8921143713317616432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:05:59.722] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086573775486655728:59:0) after 1m0s
[00:06:59.742] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086573775486655728:59:0)
[00:06:59.742] Timer tick, asking Buildbucket for the build status
[00:06:59.981] Build 8921143713317616432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:06:59.981] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086573775486655728:60:0) after 1m0s
[00:08:00.004] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086573775486655728:60:0)
[00:08:00.004] Timer tick, asking Buildbucket for the build status
[00:08:00.470] Build 8921143713317616432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:08:00.470] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086573775486655728:61:0) after 1m0s
[00:09:00.519] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086573775486655728:61:0)
[00:09:00.519] Timer tick, asking Buildbucket for the build status
[00:09:00.731] Build 8921143713317616432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:09:00.731] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086573775486655728:62:0) after 1m0s
[00:10:00.750] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086573775486655728:62:0)
[00:10:00.750] Timer tick, asking Buildbucket for the build status
[00:10:00.996] Build 8921143713317616432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:10:00.996] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086573775486655728:63:0) after 1m0s
[00:11:01.022] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086573775486655728:63:0)
[00:11:01.022] Timer tick, asking Buildbucket for the build status
[00:11:01.264] Build 8921143713317616432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:11:01.264] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086573775486655728:64:0) after 1m0s
[00:12:01.289] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086573775486655728:64:0)
[00:12:01.289] Timer tick, asking Buildbucket for the build status
[00:12:01.382] Build 8921143713317616432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:12:01.382] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086573775486655728:65:0) after 1m0s
[00:13:01.408] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086573775486655728:65:0)
[00:13:01.408] Timer tick, asking Buildbucket for the build status
[00:13:01.577] Build 8921143713317616432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:13:01.577] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086573775486655728:66:0) after 1m0s
[00:14:01.615] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086573775486655728:66:0)
[00:14:01.615] Timer tick, asking Buildbucket for the build status
[00:14:01.914] Build 8921143713317616432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:14:01.914] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086573775486655728:67:0) after 1m0s
[00:15:01.946] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086573775486655728:67:0)
[00:15:01.946] Timer tick, asking Buildbucket for the build status
[00:15:02.034] Build 8921143713317616432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:15:02.034] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086573775486655728:68:0) after 1m0s
[00:16:02.066] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086573775486655728:68:0)
[00:16:02.066] Timer tick, asking Buildbucket for the build status
[00:16:02.206] Build 8921143713317616432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:16:02.206] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086573775486655728:69:0) after 1m0s
[00:17:02.233] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086573775486655728:69:0)
[00:17:02.233] Timer tick, asking Buildbucket for the build status
[00:17:02.322] Build 8921143713317616432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:17:02.323] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086573775486655728:70:0) after 1m0s
[00:18:02.317] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086573775486655728:70:0)
[00:18:02.317] Timer tick, asking Buildbucket for the build status
[00:18:02.474] Build 8921143713317616432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:18:02.474] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086573775486655728:71:0) after 1m0s
[00:19:02.548] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086573775486655728:71:0)
[00:19:02.548] Timer tick, asking Buildbucket for the build status
[00:19:02.778] Build 8921143713317616432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:19:02.778] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086573775486655728:72:0) after 1m0s
[00:20:02.877] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086573775486655728:72:0)
[00:20:02.877] Timer tick, asking Buildbucket for the build status
[00:20:02.956] Build 8921143713317616432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:20:02.956] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086573775486655728:73:0) after 1m0s
[00:21:02.980] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086573775486655728:73:0)
[00:21:02.980] Timer tick, asking Buildbucket for the build status
[00:21:03.055] Build 8921143713317616432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:21:03.055] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086573775486655728:74:0) after 1m0s
[00:22:03.097] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086573775486655728:74:0)
[00:22:03.097] Timer tick, asking Buildbucket for the build status
[00:22:03.246] Build 8921143713317616432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:22:03.246] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086573775486655728:75:0) after 1m0s
[00:23:03.265] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086573775486655728:75:0)
[00:23:03.265] Timer tick, asking Buildbucket for the build status
[00:23:03.423] Build 8921143713317616432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:23:03.423] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086573775486655728:76:0) after 1m0s
[00:24:03.445] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086573775486655728:76:0)
[00:24:03.445] Timer tick, asking Buildbucket for the build status
[00:24:03.702] Build 8921143713317616432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:24:03.702] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086573775486655728:77:0) after 1m0s
[00:25:03.761] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086573775486655728:77:0)
[00:25:03.761] Timer tick, asking Buildbucket for the build status
[00:25:03.830] Build 8921143713317616432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:25:03.831] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086573775486655728:78:0) after 1m0s
[00:26:03.860] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086573775486655728:78:0)
[00:26:03.860] Timer tick, asking Buildbucket for the build status
[00:26:03.930] Build 8921143713317616432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:26:03.930] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086573775486655728:79:0) after 1m0s
[00:27:03.947] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086573775486655728:79:0)
[00:27:03.948] Timer tick, asking Buildbucket for the build status
[00:27:04.338] Build 8921143713317616432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:27:04.338] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086573775486655728:80:0) after 1m0s
[00:28:04.371] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086573775486655728:80:0)
[00:28:04.372] Timer tick, asking Buildbucket for the build status
[00:28:04.462] Build 8921143713317616432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:28:04.462] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086573775486655728:81:0) after 1m0s
[00:29:04.481] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086573775486655728:81:0)
[00:29:04.481] Timer tick, asking Buildbucket for the build status
[00:29:04.554] Build 8921143713317616432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:29:04.554] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086573775486655728:82:0) after 1m0s
[00:30:04.626] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086573775486655728:82:0)
[00:30:04.626] Timer tick, asking Buildbucket for the build status
[00:30:04.683] Build 8921143713317616432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:30:04.683] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086573775486655728:83:0) after 1m0s
[00:31:04.707] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086573775486655728:83:0)
[00:31:04.707] Timer tick, asking Buildbucket for the build status
[00:31:04.760] Build 8921143713317616432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:31:04.760] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086573775486655728:84:0) after 1m0s
[00:32:04.783] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086573775486655728:84:0)
[00:32:04.783] Timer tick, asking Buildbucket for the build status
[00:32:04.893] Build 8921143713317616432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:32:04.893] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086573775486655728:85:0) after 1m0s
[00:33:04.911] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086573775486655728:85:0)
[00:33:04.911] Timer tick, asking Buildbucket for the build status
[00:33:05.316] Build 8921143713317616432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:33:05.316] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086573775486655728:86:0) after 1m0s
[00:34:05.340] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086573775486655728:86:0)
[00:34:05.340] Timer tick, asking Buildbucket for the build status
[00:34:05.389] Build 8921143713317616432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:34:05.389] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086573775486655728:87:0) after 1m0s
[00:35:05.412] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086573775486655728:87:0)
[00:35:05.412] Timer tick, asking Buildbucket for the build status
[00:35:05.469] Build 8921143713317616432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:35:05.469] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086573775486655728:88:0) after 1m0s
[00:36:05.529] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086573775486655728:88:0)
[00:36:05.529] Timer tick, asking Buildbucket for the build status
[00:36:05.597] Build 8921143713317616432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:36:05.597] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086573775486655728:89:0) after 1m0s
[00:37:05.615] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086573775486655728:89:0)
[00:37:05.615] Timer tick, asking Buildbucket for the build status
[00:37:05.777] Build 8921143713317616432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:37:05.777] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086573775486655728:90:0) after 1m0s
[00:38:05.798] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086573775486655728:90:0)
[00:38:05.798] Timer tick, asking Buildbucket for the build status
[00:38:05.864] Build 8921143713317616432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:38:05.864] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086573775486655728:91:0) after 1m0s
[00:39:05.940] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086573775486655728:91:0)
[00:39:05.940] Timer tick, asking Buildbucket for the build status
[00:39:05.997] Build 8921143713317616432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:39:05.997] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086573775486655728:92:0) after 1m0s
[00:40:06.109] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086573775486655728:92:0)
[00:40:06.109] Timer tick, asking Buildbucket for the build status
[00:40:06.218] Build 8921143713317616432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:40:06.218] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086573775486655728:93:0) after 1m0s
[00:41:06.311] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086573775486655728:93:0)
[00:41:06.311] Timer tick, asking Buildbucket for the build status
[00:41:06.360] Build 8921143713317616432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:41:06.360] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086573775486655728:94:0) after 1m0s
[00:42:06.389] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086573775486655728:94:0)
[00:42:06.389] Timer tick, asking Buildbucket for the build status
[00:42:06.643] Build 8921143713317616432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:42:06.643] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086573775486655728:95:0) after 1m0s
[00:43:06.681] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086573775486655728:95:0)
[00:43:06.681] Timer tick, asking Buildbucket for the build status
[00:43:06.856] Build 8921143713317616432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:43:06.856] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086573775486655728:96:0) after 1m0s
[00:44:06.879] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086573775486655728:96:0)
[00:44:06.879] Timer tick, asking Buildbucket for the build status
[00:44:06.956] Build 8921143713317616432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:44:06.956] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086573775486655728:97:0) after 1m0s
[00:45:06.974] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086573775486655728:97:0)
[00:45:06.975] Timer tick, asking Buildbucket for the build status
[00:45:07.052] Build 8921143713317616432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:45:07.052] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086573775486655728:98:0) after 1m0s
[00:46:07.072] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086573775486655728:98:0)
[00:46:07.072] Timer tick, asking Buildbucket for the build status
[00:46:07.204] Build 8921143713317616432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:46:07.204] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086573775486655728:99:0) after 1m0s
[00:47:07.223] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086573775486655728:99:0)
[00:47:07.223] Timer tick, asking Buildbucket for the build status
[00:47:07.347] Build 8921143713317616432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:47:07.347] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086573775486655728:100:0) after 1m0s
[00:48:07.370] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086573775486655728:100:0)
[00:48:07.370] Timer tick, asking Buildbucket for the build status
[00:48:07.440] Build 8921143713317616432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:48:07.440] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086573775486655728:101:0) after 1m0s
[00:49:07.459] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086573775486655728:101:0)
[00:49:07.459] Timer tick, asking Buildbucket for the build status
[00:49:07.641] Build 8921143713317616432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:49:07.641] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086573775486655728:102:0) after 1m0s
[00:50:07.661] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086573775486655728:102:0)
[00:50:07.661] Timer tick, asking Buildbucket for the build status
[00:50:07.709] Build 8921143713317616432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:50:07.709] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086573775486655728:103:0) after 1m0s
[00:51:07.741] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086573775486655728:103:0)
[00:51:07.741] Timer tick, asking Buildbucket for the build status
[00:51:07.799] Build 8921143713317616432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:51:07.799] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086573775486655728:104:0) after 1m0s
[00:52:07.822] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086573775486655728:104:0)
[00:52:07.822] Timer tick, asking Buildbucket for the build status
[00:52:07.900] Build 8921143713317616432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:52:07.900] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086573775486655728:105:0) after 1m0s
[00:53:07.919] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086573775486655728:105:0)
[00:53:07.919] Timer tick, asking Buildbucket for the build status
[00:53:08.114] Build 8921143713317616432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:53:08.114] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086573775486655728:106:0) after 1m0s
[00:54:08.162] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086573775486655728:106:0)
[00:54:08.162] Timer tick, asking Buildbucket for the build status
[00:54:08.402] Build 8921143713317616432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:54:08.402] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086573775486655728:107:0) after 1m0s
[00:55:08.430] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086573775486655728:107:0)
[00:55:08.430] Timer tick, asking Buildbucket for the build status
[00:55:08.766] Build 8921143713317616432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:55:08.766] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086573775486655728:108:0) after 1m0s
[00:56:08.787] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086573775486655728:108:0)
[00:56:08.787] Timer tick, asking Buildbucket for the build status
[00:56:08.948] Build 8921143713317616432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:56:08.948] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086573775486655728:109:0) after 1m0s
[00:57:08.969] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086573775486655728:109:0)
[00:57:08.969] Timer tick, asking Buildbucket for the build status
[00:57:09.040] Build 8921143713317616432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:57:09.041] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086573775486655728:110:0) after 1m0s
[00:58:09.062] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086573775486655728:110:0)
[00:58:09.062] Timer tick, asking Buildbucket for the build status
[00:58:09.125] Build 8921143713317616432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:58:09.125] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086573775486655728:111:0) after 1m0s
[00:59:09.153] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086573775486655728:111:0)
[00:59:09.153] Timer tick, asking Buildbucket for the build status
[00:59:09.225] Build 8921143713317616432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:59:09.225] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086573775486655728:112:0) after 1m0s
[01:00:09.275] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086573775486655728:112:0)
[01:00:09.275] Timer tick, asking Buildbucket for the build status
[01:00:09.353] Build 8921143713317616432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:00:09.353] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086573775486655728:113:0) after 1m0s
[01:01:09.374] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086573775486655728:113:0)
[01:01:09.374] Timer tick, asking Buildbucket for the build status
[01:01:09.446] Build 8921143713317616432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:01:09.446] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086573775486655728:114:0) after 1m0s
[01:02:09.468] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086573775486655728:114:0)
[01:02:09.468] Timer tick, asking Buildbucket for the build status
[01:02:09.518] Build 8921143713317616432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:02:09.518] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086573775486655728:115:0) after 1m0s
[01:03:09.543] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086573775486655728:115:0)
[01:03:09.543] Timer tick, asking Buildbucket for the build status
[01:03:09.713] Build 8921143713317616432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:03:09.713] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086573775486655728:116:0) after 1m0s
[01:04:09.753] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086573775486655728:116:0)
[01:04:09.753] Timer tick, asking Buildbucket for the build status
[01:04:09.835] Build 8921143713317616432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:04:09.835] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086573775486655728:117:0) after 1m0s
[01:05:09.871] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086573775486655728:117:0)
[01:05:09.871] Timer tick, asking Buildbucket for the build status
[01:05:10.104] Build 8921143713317616432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:05:10.104] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086573775486655728:118:0) after 1m0s
[01:06:10.152] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086573775486655728:118:0)
[01:06:10.152] Timer tick, asking Buildbucket for the build status
[01:06:10.199] Build 8921143713317616432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:06:10.199] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086573775486655728:119:0) after 1m0s
[01:07:10.222] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086573775486655728:119:0)
[01:07:10.222] Timer tick, asking Buildbucket for the build status
[01:07:10.273] Build 8921143713317616432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:07:10.273] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086573775486655728:120:0) after 1m0s
[01:08:10.299] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086573775486655728:120:0)
[01:08:10.299] Timer tick, asking Buildbucket for the build status
[01:08:10.440] Build 8921143713317616432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:08:10.441] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9086573775486655728:121:0) after 1m0s
[01:08:33.854] Received PubSub notification, asking Buildbucket for the build status
[01:08:33.917] Build 8921143713317616432: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[01:08:33.917] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"completed_ts": "1550538512802707",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1550531389847578",
"id": "8921143713317616432",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"2f76aeef54efba5408522beb7b1dd14973c9338b\"}}",
"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\": [\"4111-cd16c2f\"], \"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\": \"1ed9878b8eb68c1e0d0268754dafcf83e747fa8f\", \"got_swarming_client_revision\": \"0e3e1c4dc4e79f25a5b58fcbc135dc93183c0c54\", \"got_revision\": \"2f76aeef54efba5408522beb7b1dd14973c9338b\", \"recipe\": \"swarming/deterministic_build\", \"got_dawn_revision\": \"3499d3ee975e454b713322bfc7585b5eab2b03c3\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#26743}\", \"$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@{#633156}\", \"branch\": \"refs/heads/master\", \"revision\": \"2f76aeef54efba5408522beb7b1dd14973c9338b\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"buildername\": \"Windows deterministic\", \"got_webrtc_revision\": \"06c51455fc768d4655c9afadea9a0e8f682b3b88\", \"mastername\": \"chromium.win\", \"got_angle_revision\": \"028df5f51e45f50ebfef480fd93fc9325a7bf0b8\", \"buildbucket\": {\"hostname\": \"cr-buildbucket.appspot.com\", \"build\": {\"created_ts\": 1550531389847578, \"tags\": [\"build_address:luci.chromium.ci/Windows deterministic/12017\", \"builder:Windows deterministic\", \"buildset:commit/git/2f76aeef54efba5408522beb7b1dd14973c9338b\", \"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/2f76aeef54efba5408522beb7b1dd14973c9338b\", \"gitiles_ref:refs/heads/master\", \"scheduler_invocation_id:9086573775486655728\", \"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\": \"8921143713317616432\"}}, \"got_v8_revision\": \"c670249b587112de339a8fa49e7cea13f8f559b4\", \"got_v8_revision_cp\": \"refs/heads/7.4.194@{#1}\", \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"buildnumber\": 12017, \"$recipe_engine/buildbucket\": {\"build\": {\"tags\": [{\"value\": \"Windows deterministic\", \"key\": \"builder\"}, {\"value\": \"commit/git/2f76aeef54efba5408522beb7b1dd14973c9338b\", \"key\": \"buildset\"}, {\"value\": \"commit/gitiles/chromium.googlesource.com/chromium/src/+/2f76aeef54efba5408522beb7b1dd14973c9338b\", \"key\": \"buildset\"}, {\"value\": \"9086573775486655728\", \"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\": 12017, \"infra\": {\"buildbucket\": {\"serviceConfigRevision\": \"fa9032cf737cadc98196e2fd1dde76c697e0750a\"}, \"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\": \"8921143713317616432\", \"input\": {\"gitilesCommit\": {\"project\": \"chromium/src\", \"host\": \"chromium.googlesource.com\", \"ref\": \"refs/heads/master\", \"id\": \"2f76aeef54efba5408522beb7b1dd14973c9338b\"}}, \"createTime\": \"2019-02-18T23:09:49.847578Z\"}}, \"path_config\": \"generic\", \"bot_id\": \"swarm2173-c4\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1550531392854287",
"status": "COMPLETED",
"status_changed_ts": "1550538513506698",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/12017",
"builder:Windows deterministic",
"buildset:commit/git/2f76aeef54efba5408522beb7b1dd14973c9338b",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/2f76aeef54efba5408522beb7b1dd14973c9338b",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9086573775486655728",
"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/8921143713317616432/+/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:431bb0f342827810",
"user_agent:luci-scheduler"
],
"updated_ts": "1550538513508197",
"url": "https://ci.chromium.org/b/8921143713317616432",
"utcnow_ts": "1550538513903697"
}
}
[01:08:33.917] Invocation finished in 1h58m45.521801835s with status SUCCEEDED