[00:57:32.317] New invocation is queued and will start shortly
[00:57:33.491] Starting the invocation (attempt 1)
[00:57:33.528] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[00:57:33.528] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[00:57:33.528] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9049965823165348272",
"parameters_json": "{\"builder_name\":\"Windows deterministic\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"a5e65090b123e918c66b747e90681467f5e1d932\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Windows deterministic",
"scheduler_invocation_id:9049965823165348272",
"scheduler_job_id:chromium/Windows deterministic",
"user_agent:luci-scheduler",
"buildset:commit/git/a5e65090b123e918c66b747e90681467f5e1d932",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/a5e65090b123e918c66b747e90681467f5e1d932",
"gitiles_ref:refs/heads/master"
]
}
[00:57:34.667] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1585443453702034",
"id": "8884535761048757104",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"a5e65090b123e918c66b747e90681467f5e1d932\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1585443454505867",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/16146",
"builder:Windows deterministic",
"buildset:commit/git/a5e65090b123e918c66b747e90681467f5e1d932",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/a5e65090b123e918c66b747e90681467f5e1d932",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9049965823165348272",
"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/8884535761048757104/+/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": "1585443454506057",
"url": "https://ci.chromium.org/b/8884535761048757104",
"utcnow_ts": "1585443454655603"
}
}
[00:57:34.667] Task URL: https://ci.chromium.org/b/8884535761048757104
[00:57:34.667] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:2:0) after 1m0s
[00:57:36.686] Received PubSub notification, asking Buildbucket for the build status
[00:57:36.776] Build 8884535761048757104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:58:34.706] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:2:0)
[00:58:34.706] Timer tick, asking Buildbucket for the build status
[00:58:34.857] Build 8884535761048757104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:58:34.857] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:4:0) after 1m0s
[00:59:34.885] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:4:0)
[00:59:34.885] Timer tick, asking Buildbucket for the build status
[00:59:35.049] Build 8884535761048757104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:59:35.049] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:5:0) after 1m0s
[01:00:35.085] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:5:0)
[01:00:35.085] Timer tick, asking Buildbucket for the build status
[01:00:35.259] Build 8884535761048757104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:00:35.259] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:6:0) after 1m0s
[01:01:35.328] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:6:0)
[01:01:35.328] Timer tick, asking Buildbucket for the build status
[01:01:35.446] Build 8884535761048757104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:01:35.446] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:7:0) after 1m0s
[01:02:35.510] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:7:0)
[01:02:35.510] Timer tick, asking Buildbucket for the build status
[01:02:35.612] Build 8884535761048757104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:02:35.612] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:8:0) after 1m0s
[01:03:35.713] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:8:0)
[01:03:35.713] Timer tick, asking Buildbucket for the build status
[01:03:36.255] Build 8884535761048757104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:03:36.255] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:9:0) after 1m0s
[01:04:36.344] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:9:0)
[01:04:36.344] Timer tick, asking Buildbucket for the build status
[01:04:36.472] Build 8884535761048757104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:04:36.472] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:10:0) after 1m0s
[01:05:36.491] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:10:0)
[01:05:36.491] Timer tick, asking Buildbucket for the build status
[01:05:36.619] Build 8884535761048757104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:05:36.619] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:11:0) after 1m0s
[01:06:36.649] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:11:0)
[01:06:36.649] Timer tick, asking Buildbucket for the build status
[01:06:36.903] Build 8884535761048757104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:06:36.903] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:12:0) after 1m0s
[01:07:36.921] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:12:0)
[01:07:36.921] Timer tick, asking Buildbucket for the build status
[01:07:37.173] Build 8884535761048757104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:07:37.173] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:13:0) after 1m0s
[01:08:37.213] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:13:0)
[01:08:37.214] Timer tick, asking Buildbucket for the build status
[01:08:38.945] Build 8884535761048757104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:08:38.945] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:14:0) after 1m0s
[01:09:38.977] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:14:0)
[01:09:38.977] Timer tick, asking Buildbucket for the build status
[01:09:39.090] Build 8884535761048757104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:09:39.090] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:15:0) after 1m0s
[01:10:39.117] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:15:0)
[01:10:39.117] Timer tick, asking Buildbucket for the build status
[01:10:39.365] Build 8884535761048757104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:10:39.365] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:16:0) after 1m0s
[01:11:39.406] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:16:0)
[01:11:39.406] Timer tick, asking Buildbucket for the build status
[01:11:39.569] Build 8884535761048757104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:11:39.569] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:17:0) after 1m0s
[01:12:39.590] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:17:0)
[01:12:39.590] Timer tick, asking Buildbucket for the build status
[01:12:39.754] Build 8884535761048757104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:12:39.754] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:18:0) after 1m0s
[01:13:39.829] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:18:0)
[01:13:39.829] Timer tick, asking Buildbucket for the build status
[01:13:40.103] Build 8884535761048757104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:13:40.103] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:19:0) after 1m0s
[01:14:40.131] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:19:0)
[01:14:40.131] Timer tick, asking Buildbucket for the build status
[01:14:40.326] Build 8884535761048757104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:14:40.326] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:20:0) after 1m0s
[01:15:40.350] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:20:0)
[01:15:40.350] Timer tick, asking Buildbucket for the build status
[01:15:40.779] Build 8884535761048757104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:15:40.779] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:21:0) after 1m0s
[01:16:40.811] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:21:0)
[01:16:40.811] Timer tick, asking Buildbucket for the build status
[01:16:40.967] Build 8884535761048757104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:16:40.967] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:22:0) after 1m0s
[01:17:40.988] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:22:0)
[01:17:40.988] Timer tick, asking Buildbucket for the build status
[01:17:41.108] Build 8884535761048757104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:17:41.108] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:23:0) after 1m0s
[01:18:41.130] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:23:0)
[01:18:41.130] Timer tick, asking Buildbucket for the build status
[01:18:41.477] Build 8884535761048757104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:18:41.477] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:24:0) after 1m0s
[01:19:41.513] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:24:0)
[01:19:41.513] Timer tick, asking Buildbucket for the build status
[01:19:41.633] Build 8884535761048757104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:19:41.633] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:25:0) after 1m0s
[01:20:41.654] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:25:0)
[01:20:41.654] Timer tick, asking Buildbucket for the build status
[01:20:41.778] Build 8884535761048757104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:20:41.778] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:26:0) after 1m0s
[01:21:41.803] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:26:0)
[01:21:41.804] Timer tick, asking Buildbucket for the build status
[01:21:42.196] Build 8884535761048757104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:21:42.196] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:27:0) after 1m0s
[01:22:42.239] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:27:0)
[01:22:42.239] Timer tick, asking Buildbucket for the build status
[01:22:42.400] Build 8884535761048757104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:22:42.400] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:28:0) after 1m0s
[01:23:47.946] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:28:0)
[01:23:47.946] Timer tick, asking Buildbucket for the build status
[01:23:48.112] Build 8884535761048757104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:23:48.112] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:29:0) after 1m0s
[01:24:48.176] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:29:0)
[01:24:48.176] Timer tick, asking Buildbucket for the build status
[01:24:48.572] Build 8884535761048757104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:24:48.572] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:30:0) after 1m0s
[01:25:48.698] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:30:0)
[01:25:48.698] Timer tick, asking Buildbucket for the build status
[01:25:48.904] Build 8884535761048757104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:25:48.904] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:31:0) after 1m0s
[01:26:48.934] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:31:0)
[01:26:48.934] Timer tick, asking Buildbucket for the build status
[01:26:49.195] Build 8884535761048757104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:26:49.195] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:32:0) after 1m0s
[01:27:49.170] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:32:0)
[01:27:49.170] Timer tick, asking Buildbucket for the build status
[01:27:49.364] Build 8884535761048757104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:27:49.364] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:33:0) after 1m0s
[01:28:49.470] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:33:0)
[01:28:49.470] Timer tick, asking Buildbucket for the build status
[01:28:49.623] Build 8884535761048757104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:28:49.623] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:34:0) after 1m0s
[01:29:49.711] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:34:0)
[01:29:49.711] Timer tick, asking Buildbucket for the build status
[01:29:49.823] Build 8884535761048757104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:29:49.823] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:35:0) after 1m0s
[01:30:49.860] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:35:0)
[01:30:49.860] Timer tick, asking Buildbucket for the build status
[01:30:50.038] Build 8884535761048757104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:30:50.038] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:36:0) after 1m0s
[01:31:50.129] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:36:0)
[01:31:50.129] Timer tick, asking Buildbucket for the build status
[01:31:50.364] Build 8884535761048757104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:31:50.364] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:37:0) after 1m0s
[01:32:50.446] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:37:0)
[01:32:50.446] Timer tick, asking Buildbucket for the build status
[01:32:50.669] Build 8884535761048757104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:32:50.669] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:38:0) after 1m0s
[01:33:50.662] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:38:0)
[01:33:50.662] Timer tick, asking Buildbucket for the build status
[01:33:50.767] Build 8884535761048757104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:33:50.767] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:39:0) after 1m0s
[01:34:50.794] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:39:0)
[01:34:50.794] Timer tick, asking Buildbucket for the build status
[01:34:50.943] Build 8884535761048757104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:34:50.943] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:40:0) after 1m0s
[01:35:50.965] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:40:0)
[01:35:50.965] Timer tick, asking Buildbucket for the build status
[01:35:51.083] Build 8884535761048757104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:35:51.083] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:41:0) after 1m0s
[01:36:51.145] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:41:0)
[01:36:51.145] Timer tick, asking Buildbucket for the build status
[01:36:51.233] Build 8884535761048757104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:36:51.233] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:42:0) after 1m0s
[01:37:51.247] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:42:0)
[01:37:51.247] Timer tick, asking Buildbucket for the build status
[01:37:51.355] Build 8884535761048757104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:37:51.355] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:43:0) after 1m0s
[01:38:51.415] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:43:0)
[01:38:51.415] Timer tick, asking Buildbucket for the build status
[01:38:51.538] Build 8884535761048757104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:38:51.538] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:44:0) after 1m0s
[01:39:51.579] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:44:0)
[01:39:51.579] Timer tick, asking Buildbucket for the build status
[01:39:51.917] Build 8884535761048757104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:39:51.917] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:45:0) after 1m0s
[01:40:51.938] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:45:0)
[01:40:51.938] Timer tick, asking Buildbucket for the build status
[01:40:52.109] Build 8884535761048757104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:40:52.109] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:46:0) after 1m0s
[01:41:52.135] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:46:0)
[01:41:52.135] Timer tick, asking Buildbucket for the build status
[01:41:52.471] Build 8884535761048757104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:41:52.472] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:47:0) after 1m0s
[01:42:52.546] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:47:0)
[01:42:52.546] Timer tick, asking Buildbucket for the build status
[01:42:52.821] Build 8884535761048757104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:42:52.821] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:48:0) after 1m0s
[01:43:52.893] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:48:0)
[01:43:52.893] Timer tick, asking Buildbucket for the build status
[01:43:53.340] Build 8884535761048757104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:43:53.340] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:49:0) after 1m0s
[01:44:53.366] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:49:0)
[01:44:53.366] Timer tick, asking Buildbucket for the build status
[01:44:53.532] Build 8884535761048757104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:44:53.532] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:50:0) after 1m0s
[01:45:53.592] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:50:0)
[01:45:53.592] Timer tick, asking Buildbucket for the build status
[01:45:53.736] Build 8884535761048757104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:45:53.736] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:51:0) after 1m0s
[01:46:53.787] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:51:0)
[01:46:53.787] Timer tick, asking Buildbucket for the build status
[01:46:54.068] Build 8884535761048757104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:46:54.068] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:52:0) after 1m0s
[01:47:54.101] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:52:0)
[01:47:54.101] Timer tick, asking Buildbucket for the build status
[01:47:54.487] Build 8884535761048757104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:47:54.487] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:53:0) after 1m0s
[01:48:54.534] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:53:0)
[01:48:54.534] Timer tick, asking Buildbucket for the build status
[01:48:54.741] Build 8884535761048757104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:48:54.741] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:54:0) after 1m0s
[01:49:54.784] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:54:0)
[01:49:54.784] Timer tick, asking Buildbucket for the build status
[01:49:54.909] Build 8884535761048757104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:49:54.909] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:55:0) after 1m0s
[01:50:54.939] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:55:0)
[01:50:54.939] Timer tick, asking Buildbucket for the build status
[01:50:55.049] Build 8884535761048757104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:50:55.049] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:56:0) after 1m0s
[01:51:55.076] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:56:0)
[01:51:55.076] Timer tick, asking Buildbucket for the build status
[01:51:55.322] Build 8884535761048757104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:51:55.322] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:57:0) after 1m0s
[01:52:55.349] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:57:0)
[01:52:55.349] Timer tick, asking Buildbucket for the build status
[01:52:55.535] Build 8884535761048757104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:52:55.535] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:58:0) after 1m0s
[01:53:55.562] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:58:0)
[01:53:55.562] Timer tick, asking Buildbucket for the build status
[01:53:55.694] Build 8884535761048757104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:53:55.694] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:59:0) after 1m0s
[01:54:55.724] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:59:0)
[01:54:55.724] Timer tick, asking Buildbucket for the build status
[01:54:55.901] Build 8884535761048757104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:54:55.901] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:60:0) after 1m0s
[01:55:56.044] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:60:0)
[01:55:56.044] Timer tick, asking Buildbucket for the build status
[01:55:56.182] Build 8884535761048757104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:55:56.182] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:61:0) after 1m0s
[01:56:56.221] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:61:0)
[01:56:56.221] Timer tick, asking Buildbucket for the build status
[01:56:56.521] Build 8884535761048757104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:56:56.521] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:62:0) after 1m0s
[01:57:56.700] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:62:0)
[01:57:56.700] Timer tick, asking Buildbucket for the build status
[01:57:56.840] Build 8884535761048757104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:57:56.840] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:63:0) after 1m0s
[01:58:57.091] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:63:0)
[01:58:57.091] Timer tick, asking Buildbucket for the build status
[01:58:57.202] Build 8884535761048757104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:58:57.202] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:64:0) after 1m0s
[01:59:57.358] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:64:0)
[01:59:57.358] Timer tick, asking Buildbucket for the build status
[01:59:57.459] Build 8884535761048757104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:59:57.459] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:65:0) after 1m0s
[02:00:57.495] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:65:0)
[02:00:57.495] Timer tick, asking Buildbucket for the build status
[02:00:57.877] Build 8884535761048757104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:00:57.877] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:66:0) after 1m0s
[02:01:57.872] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:66:0)
[02:01:57.872] Timer tick, asking Buildbucket for the build status
[02:01:57.972] Build 8884535761048757104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:01:57.972] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:67:0) after 1m0s
[02:02:58.008] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:67:0)
[02:02:58.008] Timer tick, asking Buildbucket for the build status
[02:02:58.263] Build 8884535761048757104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:02:58.263] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:68:0) after 1m0s
[02:03:58.315] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:68:0)
[02:03:58.315] Timer tick, asking Buildbucket for the build status
[02:03:58.432] Build 8884535761048757104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:03:58.432] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:69:0) after 1m0s
[02:04:58.488] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:69:0)
[02:04:58.488] Timer tick, asking Buildbucket for the build status
[02:04:58.597] Build 8884535761048757104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:04:58.597] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:70:0) after 1m0s
[02:05:58.668] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:70:0)
[02:05:58.668] Timer tick, asking Buildbucket for the build status
[02:05:58.893] Build 8884535761048757104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:05:58.893] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:71:0) after 1m0s
[02:06:59.025] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:71:0)
[02:06:59.025] Timer tick, asking Buildbucket for the build status
[02:06:59.304] Build 8884535761048757104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:06:59.304] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:72:0) after 1m0s
[02:07:59.443] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:72:0)
[02:07:59.443] Timer tick, asking Buildbucket for the build status
[02:07:59.574] Build 8884535761048757104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:07:59.574] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:73:0) after 1m0s
[02:08:59.602] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:73:0)
[02:08:59.602] Timer tick, asking Buildbucket for the build status
[02:08:59.704] Build 8884535761048757104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:08:59.704] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:74:0) after 1m0s
[02:09:59.767] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:74:0)
[02:09:59.767] Timer tick, asking Buildbucket for the build status
[02:10:00.350] Build 8884535761048757104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:10:00.350] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:75:0) after 1m0s
[02:11:00.434] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:75:0)
[02:11:00.434] Timer tick, asking Buildbucket for the build status
[02:11:00.695] Build 8884535761048757104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:11:00.695] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:76:0) after 1m0s
[02:12:00.769] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:76:0)
[02:12:00.769] Timer tick, asking Buildbucket for the build status
[02:12:01.105] Build 8884535761048757104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:12:01.105] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:77:0) after 1m0s
[02:13:01.172] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:77:0)
[02:13:01.172] Timer tick, asking Buildbucket for the build status
[02:13:01.274] Build 8884535761048757104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:13:01.274] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:78:0) after 1m0s
[02:14:01.323] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:78:0)
[02:14:01.323] Timer tick, asking Buildbucket for the build status
[02:14:02.065] Build 8884535761048757104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:14:02.065] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:79:0) after 1m0s
[02:15:02.162] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:79:0)
[02:15:02.162] Timer tick, asking Buildbucket for the build status
[02:15:02.289] Build 8884535761048757104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:15:02.289] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:80:0) after 1m0s
[02:16:02.339] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:80:0)
[02:16:02.339] Timer tick, asking Buildbucket for the build status
[02:16:02.460] Build 8884535761048757104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:16:02.460] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:81:0) after 1m0s
[02:17:02.599] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:81:0)
[02:17:02.599] Timer tick, asking Buildbucket for the build status
[02:17:02.813] Build 8884535761048757104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:17:02.813] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:82:0) after 1m0s
[02:18:02.891] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:82:0)
[02:18:02.891] Timer tick, asking Buildbucket for the build status
[02:18:03.184] Build 8884535761048757104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:18:03.184] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:83:0) after 1m0s
[02:19:03.210] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:83:0)
[02:19:03.210] Timer tick, asking Buildbucket for the build status
[02:19:03.574] Build 8884535761048757104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:19:03.574] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:84:0) after 1m0s
[02:20:03.618] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:84:0)
[02:20:03.618] Timer tick, asking Buildbucket for the build status
[02:20:03.764] Build 8884535761048757104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:20:03.764] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:85:0) after 1m0s
[02:21:03.822] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:85:0)
[02:21:03.822] Timer tick, asking Buildbucket for the build status
[02:21:04.003] Build 8884535761048757104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:21:04.003] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:86:0) after 1m0s
[02:22:04.046] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:86:0)
[02:22:04.046] Timer tick, asking Buildbucket for the build status
[02:22:04.375] Build 8884535761048757104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:22:04.375] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:87:0) after 1m0s
[02:23:04.607] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:87:0)
[02:23:04.607] Timer tick, asking Buildbucket for the build status
[02:23:04.997] Build 8884535761048757104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:23:04.997] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:88:0) after 1m0s
[02:24:05.110] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:88:0)
[02:24:05.110] Timer tick, asking Buildbucket for the build status
[02:24:05.235] Build 8884535761048757104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:24:05.235] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:89:0) after 1m0s
[02:25:05.401] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:89:0)
[02:25:05.401] Timer tick, asking Buildbucket for the build status
[02:25:05.596] Build 8884535761048757104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:25:05.596] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:90:0) after 1m0s
[02:26:05.675] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:90:0)
[02:26:05.675] Timer tick, asking Buildbucket for the build status
[02:26:07.463] Build 8884535761048757104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:26:07.463] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:91:0) after 1m0s
[02:27:07.556] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:91:0)
[02:27:07.556] Timer tick, asking Buildbucket for the build status
[02:27:07.675] Build 8884535761048757104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:27:07.675] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:92:0) after 1m0s
[02:28:07.743] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:92:0)
[02:28:07.743] Timer tick, asking Buildbucket for the build status
[02:28:07.860] Build 8884535761048757104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:28:07.860] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:93:0) after 1m0s
[02:29:07.943] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:93:0)
[02:29:07.943] Timer tick, asking Buildbucket for the build status
[02:29:08.092] Build 8884535761048757104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:29:08.093] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:94:0) after 1m0s
[02:30:08.141] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:94:0)
[02:30:08.141] Timer tick, asking Buildbucket for the build status
[02:30:08.448] Build 8884535761048757104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:30:08.448] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:95:0) after 1m0s
[02:31:08.486] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:95:0)
[02:31:08.486] Timer tick, asking Buildbucket for the build status
[02:31:08.617] Build 8884535761048757104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:31:08.617] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:96:0) after 1m0s
[02:32:08.697] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:96:0)
[02:32:08.697] Timer tick, asking Buildbucket for the build status
[02:32:08.998] Build 8884535761048757104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:32:08.998] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:97:0) after 1m0s
[02:33:09.061] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:97:0)
[02:33:09.061] Timer tick, asking Buildbucket for the build status
[02:33:09.160] Build 8884535761048757104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:33:09.160] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:98:0) after 1m0s
[02:34:09.183] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:98:0)
[02:34:09.183] Timer tick, asking Buildbucket for the build status
[02:34:09.300] Build 8884535761048757104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:34:09.300] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:99:0) after 1m0s
[02:35:09.341] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:99:0)
[02:35:09.341] Timer tick, asking Buildbucket for the build status
[02:35:09.629] Build 8884535761048757104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:35:09.629] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:100:0) after 1m0s
[02:36:09.711] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:100:0)
[02:36:09.711] Timer tick, asking Buildbucket for the build status
[02:36:09.910] Build 8884535761048757104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:36:09.910] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:101:0) after 1m0s
[02:37:09.940] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:101:0)
[02:37:09.940] Timer tick, asking Buildbucket for the build status
[02:37:10.355] Build 8884535761048757104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:37:10.355] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:102:0) after 1m0s
[02:38:10.467] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:102:0)
[02:38:10.467] Timer tick, asking Buildbucket for the build status
[02:38:10.842] Build 8884535761048757104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:38:10.842] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:103:0) after 1m0s
[02:39:10.923] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:103:0)
[02:39:10.923] Timer tick, asking Buildbucket for the build status
[02:39:11.037] Build 8884535761048757104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:39:11.037] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:104:0) after 1m0s
[02:40:11.087] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:104:0)
[02:40:11.087] Timer tick, asking Buildbucket for the build status
[02:40:11.264] Build 8884535761048757104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:40:11.264] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:105:0) after 1m0s
[02:41:11.289] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:105:0)
[02:41:11.289] Timer tick, asking Buildbucket for the build status
[02:41:11.443] Build 8884535761048757104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:41:11.443] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:106:0) after 1m0s
[02:42:11.565] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:106:0)
[02:42:11.565] Timer tick, asking Buildbucket for the build status
[02:42:11.795] Build 8884535761048757104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:42:11.795] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:107:0) after 1m0s
[02:43:11.824] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:107:0)
[02:43:11.824] Timer tick, asking Buildbucket for the build status
[02:43:11.982] Build 8884535761048757104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:43:11.982] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:108:0) after 1m0s
[02:44:12.011] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:108:0)
[02:44:12.011] Timer tick, asking Buildbucket for the build status
[02:44:12.264] Build 8884535761048757104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:44:12.264] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:109:0) after 1m0s
[02:45:12.341] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:109:0)
[02:45:12.341] Timer tick, asking Buildbucket for the build status
[02:45:12.445] Build 8884535761048757104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:45:12.445] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:110:0) after 1m0s
[02:46:12.477] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:110:0)
[02:46:12.477] Timer tick, asking Buildbucket for the build status
[02:46:12.668] Build 8884535761048757104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:46:12.668] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:111:0) after 1m0s
[02:47:12.744] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:111:0)
[02:47:12.744] Timer tick, asking Buildbucket for the build status
[02:47:12.860] Build 8884535761048757104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:47:12.860] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:112:0) after 1m0s
[02:48:12.951] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:112:0)
[02:48:12.952] Timer tick, asking Buildbucket for the build status
[02:48:13.127] Build 8884535761048757104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:48:13.127] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:113:0) after 1m0s
[02:49:13.171] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:113:0)
[02:49:13.171] Timer tick, asking Buildbucket for the build status
[02:49:13.779] Build 8884535761048757104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:49:13.779] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:114:0) after 1m0s
[02:50:13.848] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:114:0)
[02:50:13.848] Timer tick, asking Buildbucket for the build status
[02:50:13.941] Build 8884535761048757104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:50:13.941] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:115:0) after 1m0s
[02:51:13.967] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:115:0)
[02:51:13.967] Timer tick, asking Buildbucket for the build status
[02:51:14.096] Build 8884535761048757104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:51:14.096] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:116:0) after 1m0s
[02:52:14.135] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:116:0)
[02:52:14.135] Timer tick, asking Buildbucket for the build status
[02:52:14.333] Build 8884535761048757104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:52:14.333] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:117:0) after 1m0s
[02:53:14.467] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:117:0)
[02:53:14.467] Timer tick, asking Buildbucket for the build status
[02:53:14.915] Build 8884535761048757104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:53:14.915] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:118:0) after 1m0s
[02:54:14.990] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:118:0)
[02:54:14.990] Timer tick, asking Buildbucket for the build status
[02:54:15.107] Build 8884535761048757104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:54:15.107] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:119:0) after 1m0s
[02:55:15.149] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:119:0)
[02:55:15.149] Timer tick, asking Buildbucket for the build status
[02:55:15.261] Build 8884535761048757104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:55:15.261] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:120:0) after 1m0s
[02:56:15.684] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:120:0)
[02:56:15.684] Timer tick, asking Buildbucket for the build status
[02:56:15.798] Build 8884535761048757104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:56:15.798] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:121:0) after 1m0s
[02:57:16.195] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:121:0)
[02:57:16.195] Timer tick, asking Buildbucket for the build status
[02:57:16.478] Build 8884535761048757104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:57:16.478] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:122:0) after 1m0s
[02:58:16.807] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:122:0)
[02:58:16.807] Timer tick, asking Buildbucket for the build status
[02:58:16.920] Build 8884535761048757104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:58:16.920] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:123:0) after 1m0s
[02:59:16.984] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:123:0)
[02:59:16.984] Timer tick, asking Buildbucket for the build status
[02:59:17.491] Build 8884535761048757104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:59:17.491] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:124:0) after 1m0s
[03:00:17.629] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:124:0)
[03:00:17.629] Timer tick, asking Buildbucket for the build status
[03:00:17.753] Build 8884535761048757104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:00:17.753] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:125:0) after 1m0s
[03:01:18.972] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:125:0)
[03:01:18.972] Timer tick, asking Buildbucket for the build status
[03:01:19.282] Build 8884535761048757104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:01:19.282] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:126:0) after 1m0s
[03:02:19.322] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:126:0)
[03:02:19.322] Timer tick, asking Buildbucket for the build status
[03:02:19.527] Build 8884535761048757104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:02:19.527] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:127:0) after 1m0s
[03:03:19.627] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:127:0)
[03:03:19.627] Timer tick, asking Buildbucket for the build status
[03:03:19.805] Build 8884535761048757104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:03:19.805] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:128:0) after 1m0s
[03:04:19.835] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:128:0)
[03:04:19.835] Timer tick, asking Buildbucket for the build status
[03:04:20.029] Build 8884535761048757104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:04:20.029] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:129:0) after 1m0s
[03:05:20.146] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:129:0)
[03:05:20.146] Timer tick, asking Buildbucket for the build status
[03:05:20.323] Build 8884535761048757104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:05:20.323] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:130:0) after 1m0s
[03:06:20.453] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:130:0)
[03:06:20.453] Timer tick, asking Buildbucket for the build status
[03:06:20.952] Build 8884535761048757104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:06:20.952] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:131:0) after 1m0s
[03:07:20.995] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:131:0)
[03:07:20.995] Timer tick, asking Buildbucket for the build status
[03:07:21.144] Build 8884535761048757104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:07:21.144] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:132:0) after 1m0s
[03:08:21.217] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:132:0)
[03:08:21.217] Timer tick, asking Buildbucket for the build status
[03:08:21.705] Build 8884535761048757104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:08:21.705] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:133:0) after 1m0s
[03:09:21.779] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:133:0)
[03:09:21.779] Timer tick, asking Buildbucket for the build status
[03:09:22.026] Build 8884535761048757104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:09:22.026] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:134:0) after 1m0s
[03:10:22.058] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:134:0)
[03:10:22.058] Timer tick, asking Buildbucket for the build status
[03:10:22.293] Build 8884535761048757104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:10:22.293] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:135:0) after 1m0s
[03:11:22.371] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:135:0)
[03:11:22.371] Timer tick, asking Buildbucket for the build status
[03:11:22.501] Build 8884535761048757104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:11:22.501] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:136:0) after 1m0s
[03:12:22.580] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:136:0)
[03:12:22.580] Timer tick, asking Buildbucket for the build status
[03:12:22.690] Build 8884535761048757104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:12:22.690] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:137:0) after 1m0s
[03:13:22.755] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:137:0)
[03:13:22.755] Timer tick, asking Buildbucket for the build status
[03:13:22.897] Build 8884535761048757104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:13:22.897] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:138:0) after 1m0s
[03:14:22.979] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:138:0)
[03:14:22.979] Timer tick, asking Buildbucket for the build status
[03:14:23.241] Build 8884535761048757104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:14:23.241] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:139:0) after 1m0s
[03:15:23.253] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:139:0)
[03:15:23.253] Timer tick, asking Buildbucket for the build status
[03:15:23.900] Build 8884535761048757104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:15:23.900] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:140:0) after 1m0s
[03:16:24.051] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:140:0)
[03:16:24.051] Timer tick, asking Buildbucket for the build status
[03:16:24.245] Build 8884535761048757104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:16:24.245] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:141:0) after 1m0s
[03:17:24.326] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:141:0)
[03:17:24.326] Timer tick, asking Buildbucket for the build status
[03:17:24.821] Build 8884535761048757104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:17:24.821] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:142:0) after 1m0s
[03:18:24.822] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:142:0)
[03:18:24.822] Timer tick, asking Buildbucket for the build status
[03:18:24.971] Build 8884535761048757104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:18:24.971] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9049965823165348272:143:0) after 1m0s
[03:18:58.340] Received PubSub notification, asking Buildbucket for the build status
[03:18:58.437] Build 8884535761048757104: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[03:18:58.437] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1585451937530656",
"created_by": "project:chromium",
"created_ts": "1585443453702034",
"id": "8884535761048757104",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"a5e65090b123e918c66b747e90681467f5e1d932\"}}",
"project": "chromium",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"$build/goma\": {\"enable_ats\": true, \"rpc_extra_params\": \"?prod\", \"server_host\": \"goma.chromium.org\"}, \"$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-6ta7\", \"branch\": \"refs/heads/master\", \"buildername\": \"Windows deterministic\", \"buildnumber\": 16146, \"got_angle_revision\": \"1b2dd6f92a6ba4ac4738ae796692e4844bfd28c7\", \"got_dawn_revision\": \"81431f5034b4ce3a0cdc339709bd8ca8856fe427\", \"got_nacl_revision\": \"ebf11aaad97fde7f91b1c72e0dddaf1653d41f77\", \"got_revision\": \"a5e65090b123e918c66b747e90681467f5e1d932\", \"got_revision_cp\": \"refs/heads/master@{#754335}\", \"got_swarming_client_revision\": \"cc958279ffd6853e0a1b227a7e957ca334fe56af\", \"got_swiftshader_revision\": \"536e2c8007dd71fed8b8f2ac2d504388a50542af\", \"got_v8_revision\": \"5269c3133de0093c180c57cc51dac04406293e24\", \"got_v8_revision_cp\": \"refs/heads/8.3.102@{#1}\", \"got_webrtc_revision\": \"d19513f3ffbb939fd56b5377b678bb31d3154e14\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#30881}\", \"mastername\": \"chromium.win\", \"path_config\": \"generic\", \"recipe\": \"swarming/deterministic_build\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"a5e65090b123e918c66b747e90681467f5e1d932\"}, \"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\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"windows-deterministic-0-6ta7\"], \"image\": [\"chrome-win10-1703-19112100-3085293b610\"], \"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\": [\"4901-91f024a\"], \"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": "1585443455903731",
"status": "COMPLETED",
"status_changed_ts": "1585451937821722",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/16146",
"builder:Windows deterministic",
"buildset:commit/git/a5e65090b123e918c66b747e90681467f5e1d932",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/a5e65090b123e918c66b747e90681467f5e1d932",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9049965823165348272",
"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/8884535761048757104/+/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:4b3c9cc7e8c28b10",
"user_agent:luci-scheduler"
],
"updated_ts": "1585451937831422",
"url": "https://ci.chromium.org/b/8884535761048757104",
"utcnow_ts": "1585451938428147"
}
}
[03:18:58.437] Invocation finished in 2h21m26.136725117s with status SUCCEEDED