[12:39:42.495] New invocation is queued and will start shortly
[12:39:43.761] Starting the invocation (attempt 1)
[12:39:43.793] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[12:39:43.793] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[12:39:43.793] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9101380723393149424",
"parameters_json": "{\"builder_name\":\"Windows deterministic\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src.git\",\"revision\":\"254a5a0eca4152fb2a4d1f003c9a565b26b26ad7\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Windows deterministic",
"scheduler_invocation_id:9101380723393149424",
"scheduler_job_id:chromium/Windows deterministic",
"user_agent:luci-scheduler",
"buildset:commit/git/254a5a0eca4152fb2a4d1f003c9a565b26b26ad7",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/254a5a0eca4152fb2a4d1f003c9a565b26b26ad7",
"gitiles_ref:refs/heads/master"
]
}
[12:39:53.194] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1536410391722837",
"id": "8935950653047478560",
"lease_expiration_ts": "1536554392883606",
"lease_key": "1138798047",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"254a5a0eca4152fb2a4d1f003c9a565b26b26ad7\"}}",
"project": "chromium",
"result_details_json": "null",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1536410393011204",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/10017",
"builder:Windows deterministic",
"buildset:commit/git/254a5a0eca4152fb2a4d1f003c9a565b26b26ad7",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/254a5a0eca4152fb2a4d1f003c9a565b26b26ad7",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9101380723393149424",
"scheduler_job_id:chromium/Windows deterministic",
"swarming_dimension:builder:Windows deterministic",
"swarming_dimension:caches:builder_d795d6e07fe37a299fcfc0e872e48757ad306282f024125f803c0465bfd1eeae_v2",
"swarming_dimension:cores:8",
"swarming_dimension:cpu:x86-64",
"swarming_dimension:os:Windows-10",
"swarming_dimension:pool:luci.chromium.ci",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:build_address:luci.chromium.ci/Windows deterministic/10017",
"swarming_tag:buildbucket_bucket:luci.chromium.ci",
"swarming_tag:buildbucket_build_id:8935950653047478560",
"swarming_tag:buildbucket_hostname:cr-buildbucket.appspot.com",
"swarming_tag:buildbucket_template_canary:0",
"swarming_tag:buildbucket_template_revision:ca2c7a741e04d0385e2bf62941ac932dc0521af3",
"swarming_tag:builder:Windows deterministic",
"swarming_tag:buildset:commit/git/254a5a0eca4152fb2a4d1f003c9a565b26b26ad7",
"swarming_tag:buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/254a5a0eca4152fb2a4d1f003c9a565b26b26ad7",
"swarming_tag:caches:builder_d795d6e07fe37a299fcfc0e872e48757ad306282f024125f803c0465bfd1eeae_v2",
"swarming_tag:cores:8",
"swarming_tag:cpu:x86-64",
"swarming_tag:gitiles_ref:refs/heads/master",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8935950653047478560/+/annotations",
"swarming_tag:luci_project:chromium",
"swarming_tag:os:Windows-10",
"swarming_tag:pool:luci.chromium.ci",
"swarming_tag:priority:30",
"swarming_tag:recipe_name:swarming/deterministic_build",
"swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build",
"swarming_tag:scheduler_invocation_id:9101380723393149424",
"swarming_tag:scheduler_job_id:chromium/Windows deterministic",
"swarming_tag:service_account:chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"swarming_tag:swarming.pool.template:skip",
"swarming_tag:swarming.pool.version:71d843ea58cc76a6b4422ca80f2115179bb30d7f",
"swarming_tag:user:None",
"swarming_tag:user_agent:luci-scheduler",
"swarming_tag:vpython:native-python-wrapper",
"swarming_task_id:3fd203a00f1f3e10",
"user_agent:luci-scheduler"
],
"updated_ts": "1536410393011321",
"url": "https://ci.chromium.org/p/chromium/builders/luci.chromium.ci/Windows deterministic/10017",
"utcnow_ts": "1536410393083573"
}
}
[12:39:53.194] Task URL: https://ci.chromium.org/p/chromium/builders/luci.chromium.ci/Windows deterministic/10017
[12:39:53.194] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101380723393149424:2:0) after 1m0s
[12:40:53.211] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101380723393149424:2:0)
[12:40:53.211] Timer tick, asking Buildbucket for the build status
[12:40:53.278] Build 8935950653047478560: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:40:53.278] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101380723393149424:3:0) after 1m0s
[12:41:22.070] Received PubSub notification, asking Buildbucket for the build status
[12:41:22.127] Build 8935950653047478560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:41:53.321] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101380723393149424:3:0)
[12:41:53.321] Timer tick, asking Buildbucket for the build status
[12:41:53.506] Build 8935950653047478560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:41:53.506] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101380723393149424:5:0) after 1m0s
[12:42:53.524] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101380723393149424:5:0)
[12:42:53.524] Timer tick, asking Buildbucket for the build status
[12:42:53.656] Build 8935950653047478560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:42:53.656] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101380723393149424:6:0) after 1m0s
[12:43:53.679] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101380723393149424:6:0)
[12:43:53.679] Timer tick, asking Buildbucket for the build status
[12:43:53.752] Build 8935950653047478560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:43:53.752] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101380723393149424:7:0) after 1m0s
[12:44:53.792] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101380723393149424:7:0)
[12:44:53.792] Timer tick, asking Buildbucket for the build status
[12:44:53.950] Build 8935950653047478560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:44:53.950] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101380723393149424:8:0) after 1m0s
[12:45:53.990] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101380723393149424:8:0)
[12:45:53.990] Timer tick, asking Buildbucket for the build status
[12:45:54.040] Build 8935950653047478560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:45:54.040] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101380723393149424:9:0) after 1m0s
[12:46:54.058] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101380723393149424:9:0)
[12:46:54.058] Timer tick, asking Buildbucket for the build status
[12:46:54.196] Build 8935950653047478560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:46:54.196] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101380723393149424:10:0) after 1m0s
[12:47:54.217] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101380723393149424:10:0)
[12:47:54.217] Timer tick, asking Buildbucket for the build status
[12:47:54.280] Build 8935950653047478560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:47:54.280] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101380723393149424:11:0) after 1m0s
[12:48:54.304] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101380723393149424:11:0)
[12:48:54.304] Timer tick, asking Buildbucket for the build status
[12:48:54.381] Build 8935950653047478560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:48:54.381] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101380723393149424:12:0) after 1m0s
[12:49:54.467] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101380723393149424:12:0)
[12:49:54.467] Timer tick, asking Buildbucket for the build status
[12:49:54.522] Build 8935950653047478560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:49:54.522] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101380723393149424:13:0) after 1m0s
[12:50:54.642] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101380723393149424:13:0)
[12:50:54.642] Timer tick, asking Buildbucket for the build status
[12:50:55.023] Build 8935950653047478560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:50:55.023] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101380723393149424:14:0) after 1m0s
[12:51:55.050] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101380723393149424:14:0)
[12:51:55.050] Timer tick, asking Buildbucket for the build status
[12:51:55.142] Build 8935950653047478560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:51:55.142] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101380723393149424:15:0) after 1m0s
[12:52:55.161] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101380723393149424:15:0)
[12:52:55.161] Timer tick, asking Buildbucket for the build status
[12:52:55.238] Build 8935950653047478560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:52:55.238] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101380723393149424:16:0) after 1m0s
[12:53:55.275] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101380723393149424:16:0)
[12:53:55.275] Timer tick, asking Buildbucket for the build status
[12:53:55.430] Build 8935950653047478560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:53:55.430] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101380723393149424:17:0) after 1m0s
[12:54:55.450] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101380723393149424:17:0)
[12:54:55.450] Timer tick, asking Buildbucket for the build status
[12:54:55.618] Build 8935950653047478560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:54:55.618] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101380723393149424:18:0) after 1m0s
[12:55:55.637] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101380723393149424:18:0)
[12:55:55.637] Timer tick, asking Buildbucket for the build status
[12:55:55.703] Build 8935950653047478560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:55:55.703] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101380723393149424:19:0) after 1m0s
[12:56:55.854] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101380723393149424:19:0)
[12:56:55.854] Timer tick, asking Buildbucket for the build status
[12:56:56.007] Build 8935950653047478560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:56:56.007] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101380723393149424:20:0) after 1m0s
[12:57:56.071] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101380723393149424:20:0)
[12:57:56.071] Timer tick, asking Buildbucket for the build status
[12:57:56.194] Build 8935950653047478560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:57:56.194] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101380723393149424:21:0) after 1m0s
[12:58:56.349] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101380723393149424:21:0)
[12:58:56.349] Timer tick, asking Buildbucket for the build status
[12:58:56.445] Build 8935950653047478560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:58:56.445] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101380723393149424:22:0) after 1m0s
[12:59:56.468] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101380723393149424:22:0)
[12:59:56.468] Timer tick, asking Buildbucket for the build status
[12:59:56.733] Build 8935950653047478560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:59:56.733] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101380723393149424:23:0) after 1m0s
[13:00:56.761] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101380723393149424:23:0)
[13:00:56.761] Timer tick, asking Buildbucket for the build status
[13:00:56.883] Build 8935950653047478560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:00:56.883] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101380723393149424:24:0) after 1m0s
[13:01:57.004] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101380723393149424:24:0)
[13:01:57.004] Timer tick, asking Buildbucket for the build status
[13:01:57.067] Build 8935950653047478560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:01:57.067] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101380723393149424:25:0) after 1m0s
[13:02:57.122] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101380723393149424:25:0)
[13:02:57.122] Timer tick, asking Buildbucket for the build status
[13:02:57.286] Build 8935950653047478560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:02:57.286] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101380723393149424:26:0) after 1m0s
[13:03:57.374] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101380723393149424:26:0)
[13:03:57.374] Timer tick, asking Buildbucket for the build status
[13:03:57.478] Build 8935950653047478560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:03:57.478] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101380723393149424:27:0) after 1m0s
[13:04:57.598] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101380723393149424:27:0)
[13:04:57.599] Timer tick, asking Buildbucket for the build status
[13:04:57.745] Build 8935950653047478560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:04:57.745] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101380723393149424:28:0) after 1m0s
[13:05:57.772] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101380723393149424:28:0)
[13:05:57.772] Timer tick, asking Buildbucket for the build status
[13:05:57.863] Build 8935950653047478560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:05:57.863] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101380723393149424:29:0) after 1m0s
[13:06:57.955] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101380723393149424:29:0)
[13:06:57.955] Timer tick, asking Buildbucket for the build status
[13:06:58.248] Build 8935950653047478560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:06:58.248] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101380723393149424:30:0) after 1m0s
[13:07:58.275] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101380723393149424:30:0)
[13:07:58.275] Timer tick, asking Buildbucket for the build status
[13:07:58.334] Build 8935950653047478560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:07:58.334] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101380723393149424:31:0) after 1m0s
[13:08:58.398] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101380723393149424:31:0)
[13:08:58.398] Timer tick, asking Buildbucket for the build status
[13:08:58.458] Build 8935950653047478560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:08:58.458] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101380723393149424:32:0) after 1m0s
[13:09:58.586] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101380723393149424:32:0)
[13:09:58.587] Timer tick, asking Buildbucket for the build status
[13:09:58.666] Build 8935950653047478560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:09:58.666] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101380723393149424:33:0) after 1m0s
[13:10:58.768] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101380723393149424:33:0)
[13:10:58.768] Timer tick, asking Buildbucket for the build status
[13:10:58.892] Build 8935950653047478560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:10:58.892] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101380723393149424:34:0) after 1m0s
[13:11:59.017] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101380723393149424:34:0)
[13:11:59.017] Timer tick, asking Buildbucket for the build status
[13:11:59.721] Build 8935950653047478560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:11:59.721] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101380723393149424:35:0) after 1m0s
[13:12:59.755] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101380723393149424:35:0)
[13:12:59.755] Timer tick, asking Buildbucket for the build status
[13:12:59.837] Build 8935950653047478560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:12:59.837] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101380723393149424:36:0) after 1m0s
[13:13:59.860] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101380723393149424:36:0)
[13:13:59.860] Timer tick, asking Buildbucket for the build status
[13:13:59.936] Build 8935950653047478560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:13:59.936] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101380723393149424:37:0) after 1m0s
[13:14:59.956] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101380723393149424:37:0)
[13:14:59.956] Timer tick, asking Buildbucket for the build status
[13:15:00.299] Build 8935950653047478560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:15:00.299] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101380723393149424:38:0) after 1m0s
[13:16:00.345] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101380723393149424:38:0)
[13:16:00.345] Timer tick, asking Buildbucket for the build status
[13:16:00.769] Build 8935950653047478560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:16:00.769] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101380723393149424:39:0) after 1m0s
[13:17:00.789] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101380723393149424:39:0)
[13:17:00.789] Timer tick, asking Buildbucket for the build status
[13:17:01.221] Build 8935950653047478560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:17:01.221] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101380723393149424:40:0) after 1m0s
[13:18:01.247] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101380723393149424:40:0)
[13:18:01.247] Timer tick, asking Buildbucket for the build status
[13:18:01.403] Build 8935950653047478560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:18:01.403] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101380723393149424:41:0) after 1m0s
[13:19:01.447] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101380723393149424:41:0)
[13:19:01.447] Timer tick, asking Buildbucket for the build status
[13:19:03.503] Build 8935950653047478560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:19:03.503] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101380723393149424:42:0) after 1m0s
[13:20:03.558] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101380723393149424:42:0)
[13:20:03.558] Timer tick, asking Buildbucket for the build status
[13:20:03.715] Build 8935950653047478560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:20:03.715] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101380723393149424:43:0) after 1m0s
[13:21:03.862] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101380723393149424:43:0)
[13:21:03.862] Timer tick, asking Buildbucket for the build status
[13:21:04.231] Build 8935950653047478560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:21:04.231] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101380723393149424:44:0) after 1m0s
[13:22:04.344] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101380723393149424:44:0)
[13:22:04.344] Timer tick, asking Buildbucket for the build status
[13:22:04.427] Build 8935950653047478560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:22:04.427] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101380723393149424:45:0) after 1m0s
[13:23:04.577] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101380723393149424:45:0)
[13:23:04.577] Timer tick, asking Buildbucket for the build status
[13:23:04.656] Build 8935950653047478560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:23:04.657] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101380723393149424:46:0) after 1m0s
[13:24:04.792] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101380723393149424:46:0)
[13:24:04.792] Timer tick, asking Buildbucket for the build status
[13:24:04.877] Build 8935950653047478560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:24:04.877] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101380723393149424:47:0) after 1m0s
[13:25:04.999] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101380723393149424:47:0)
[13:25:04.999] Timer tick, asking Buildbucket for the build status
[13:25:05.132] Build 8935950653047478560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:25:05.132] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101380723393149424:48:0) after 1m0s
[13:26:05.153] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101380723393149424:48:0)
[13:26:05.153] Timer tick, asking Buildbucket for the build status
[13:26:05.200] Build 8935950653047478560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:26:05.200] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101380723393149424:49:0) after 1m0s
[13:27:05.218] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101380723393149424:49:0)
[13:27:05.218] Timer tick, asking Buildbucket for the build status
[13:27:05.283] Build 8935950653047478560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:27:05.283] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101380723393149424:50:0) after 1m0s
[13:28:05.302] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101380723393149424:50:0)
[13:28:05.302] Timer tick, asking Buildbucket for the build status
[13:28:05.365] Build 8935950653047478560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:28:05.365] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101380723393149424:51:0) after 1m0s
[13:29:05.387] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101380723393149424:51:0)
[13:29:05.387] Timer tick, asking Buildbucket for the build status
[13:29:05.444] Build 8935950653047478560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:29:05.444] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101380723393149424:52:0) after 1m0s
[13:30:05.611] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101380723393149424:52:0)
[13:30:05.611] Timer tick, asking Buildbucket for the build status
[13:30:05.658] Build 8935950653047478560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:30:05.658] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101380723393149424:53:0) after 1m0s
[13:31:05.843] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101380723393149424:53:0)
[13:31:05.843] Timer tick, asking Buildbucket for the build status
[13:31:05.904] Build 8935950653047478560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:31:05.904] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101380723393149424:54:0) after 1m0s
[13:32:05.984] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101380723393149424:54:0)
[13:32:05.984] Timer tick, asking Buildbucket for the build status
[13:32:06.068] Build 8935950653047478560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:32:06.068] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101380723393149424:55:0) after 1m0s
[13:33:06.183] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101380723393149424:55:0)
[13:33:06.183] Timer tick, asking Buildbucket for the build status
[13:33:06.298] Build 8935950653047478560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:33:06.298] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101380723393149424:56:0) after 1m0s
[13:34:06.558] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101380723393149424:56:0)
[13:34:06.558] Timer tick, asking Buildbucket for the build status
[13:34:06.645] Build 8935950653047478560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:34:06.645] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101380723393149424:57:0) after 1m0s
[13:35:06.666] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101380723393149424:57:0)
[13:35:06.666] Timer tick, asking Buildbucket for the build status
[13:35:06.732] Build 8935950653047478560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:35:06.732] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101380723393149424:58:0) after 1m0s
[13:36:06.755] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101380723393149424:58:0)
[13:36:06.755] Timer tick, asking Buildbucket for the build status
[13:36:06.892] Build 8935950653047478560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:36:06.892] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101380723393149424:59:0) after 1m0s
[13:37:06.935] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101380723393149424:59:0)
[13:37:06.935] Timer tick, asking Buildbucket for the build status
[13:37:07.007] Build 8935950653047478560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:37:07.007] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101380723393149424:60:0) after 1m0s
[13:38:07.028] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101380723393149424:60:0)
[13:38:07.028] Timer tick, asking Buildbucket for the build status
[13:38:07.095] Build 8935950653047478560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:38:07.095] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101380723393149424:61:0) after 1m0s
[13:39:07.146] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101380723393149424:61:0)
[13:39:07.146] Timer tick, asking Buildbucket for the build status
[13:39:07.526] Build 8935950653047478560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:39:07.526] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101380723393149424:62:0) after 1m0s
[13:40:07.549] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101380723393149424:62:0)
[13:40:07.549] Timer tick, asking Buildbucket for the build status
[13:40:07.601] Build 8935950653047478560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:40:07.601] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101380723393149424:63:0) after 1m0s
[13:41:07.620] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101380723393149424:63:0)
[13:41:07.620] Timer tick, asking Buildbucket for the build status
[13:41:07.680] Build 8935950653047478560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:41:07.680] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101380723393149424:64:0) after 1m0s
[13:42:07.699] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101380723393149424:64:0)
[13:42:07.699] Timer tick, asking Buildbucket for the build status
[13:42:07.965] Build 8935950653047478560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:42:07.965] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101380723393149424:65:0) after 1m0s
[13:43:07.985] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101380723393149424:65:0)
[13:43:07.985] Timer tick, asking Buildbucket for the build status
[13:43:08.058] Build 8935950653047478560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:43:08.058] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101380723393149424:66:0) after 1m0s
[13:44:08.090] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101380723393149424:66:0)
[13:44:08.090] Timer tick, asking Buildbucket for the build status
[13:44:08.184] Build 8935950653047478560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:44:08.184] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101380723393149424:67:0) after 1m0s
[13:45:08.202] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101380723393149424:67:0)
[13:45:08.202] Timer tick, asking Buildbucket for the build status
[13:45:08.246] Build 8935950653047478560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:45:08.246] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101380723393149424:68:0) after 1m0s
[13:46:08.266] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101380723393149424:68:0)
[13:46:08.266] Timer tick, asking Buildbucket for the build status
[13:46:08.339] Build 8935950653047478560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:46:08.339] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101380723393149424:69:0) after 1m0s
[13:47:08.372] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101380723393149424:69:0)
[13:47:08.372] Timer tick, asking Buildbucket for the build status
[13:47:08.437] Build 8935950653047478560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:47:08.437] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101380723393149424:70:0) after 1m0s
[13:48:08.457] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101380723393149424:70:0)
[13:48:08.457] Timer tick, asking Buildbucket for the build status
[13:48:09.015] Build 8935950653047478560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:48:09.015] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101380723393149424:71:0) after 1m0s
[13:49:09.050] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101380723393149424:71:0)
[13:49:09.050] Timer tick, asking Buildbucket for the build status
[13:49:09.111] Build 8935950653047478560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:49:09.111] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101380723393149424:72:0) after 1m0s
[13:50:09.131] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101380723393149424:72:0)
[13:50:09.131] Timer tick, asking Buildbucket for the build status
[13:50:09.170] Build 8935950653047478560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:50:09.170] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101380723393149424:73:0) after 1m0s
[13:51:09.257] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101380723393149424:73:0)
[13:51:09.258] Timer tick, asking Buildbucket for the build status
[13:51:09.643] Build 8935950653047478560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:51:09.643] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101380723393149424:74:0) after 1m0s
[13:52:09.662] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101380723393149424:74:0)
[13:52:09.662] Timer tick, asking Buildbucket for the build status
[13:52:09.709] Build 8935950653047478560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:52:09.709] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101380723393149424:75:0) after 1m0s
[13:53:09.882] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101380723393149424:75:0)
[13:53:09.882] Timer tick, asking Buildbucket for the build status
[13:53:11.559] Build 8935950653047478560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:53:11.559] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101380723393149424:76:0) after 1m0s
[13:54:11.588] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101380723393149424:76:0)
[13:54:11.588] Timer tick, asking Buildbucket for the build status
[13:54:11.656] Build 8935950653047478560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:54:11.656] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101380723393149424:77:0) after 1m0s
[13:55:11.891] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101380723393149424:77:0)
[13:55:11.891] Timer tick, asking Buildbucket for the build status
[13:55:11.957] Build 8935950653047478560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:55:11.957] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101380723393149424:78:0) after 1m0s
[13:56:12.042] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101380723393149424:78:0)
[13:56:12.042] Timer tick, asking Buildbucket for the build status
[13:56:12.191] Build 8935950653047478560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:56:12.191] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101380723393149424:79:0) after 1m0s
[13:57:12.271] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101380723393149424:79:0)
[13:57:12.271] Timer tick, asking Buildbucket for the build status
[13:57:12.345] Build 8935950653047478560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:57:12.345] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101380723393149424:80:0) after 1m0s
[13:58:12.470] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101380723393149424:80:0)
[13:58:12.470] Timer tick, asking Buildbucket for the build status
[13:58:12.683] Build 8935950653047478560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:58:12.683] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101380723393149424:81:0) after 1m0s
[13:59:12.744] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101380723393149424:81:0)
[13:59:12.744] Timer tick, asking Buildbucket for the build status
[13:59:12.806] Build 8935950653047478560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:59:12.806] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101380723393149424:82:0) after 1m0s
[14:00:12.843] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101380723393149424:82:0)
[14:00:12.843] Timer tick, asking Buildbucket for the build status
[14:00:12.908] Build 8935950653047478560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:00:12.908] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101380723393149424:83:0) after 1m0s
[14:01:12.989] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101380723393149424:83:0)
[14:01:12.989] Timer tick, asking Buildbucket for the build status
[14:01:13.061] Build 8935950653047478560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:01:13.061] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101380723393149424:84:0) after 1m0s
[14:02:13.081] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101380723393149424:84:0)
[14:02:13.081] Timer tick, asking Buildbucket for the build status
[14:02:13.283] Build 8935950653047478560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:02:13.283] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101380723393149424:85:0) after 1m0s
[14:03:13.339] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101380723393149424:85:0)
[14:03:13.339] Timer tick, asking Buildbucket for the build status
[14:03:13.410] Build 8935950653047478560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:03:13.410] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101380723393149424:86:0) after 1m0s
[14:04:13.444] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101380723393149424:86:0)
[14:04:13.444] Timer tick, asking Buildbucket for the build status
[14:04:13.484] Build 8935950653047478560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:04:13.485] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101380723393149424:87:0) after 1m0s
[14:05:13.573] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101380723393149424:87:0)
[14:05:13.573] Timer tick, asking Buildbucket for the build status
[14:05:13.622] Build 8935950653047478560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:05:13.622] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101380723393149424:88:0) after 1m0s
[14:06:13.642] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101380723393149424:88:0)
[14:06:13.642] Timer tick, asking Buildbucket for the build status
[14:06:13.714] Build 8935950653047478560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:06:13.714] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101380723393149424:89:0) after 1m0s
[14:07:13.755] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101380723393149424:89:0)
[14:07:13.755] Timer tick, asking Buildbucket for the build status
[14:07:13.846] Build 8935950653047478560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:07:13.846] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101380723393149424:90:0) after 1m0s
[14:08:13.866] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101380723393149424:90:0)
[14:08:13.866] Timer tick, asking Buildbucket for the build status
[14:08:14.009] Build 8935950653047478560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:08:14.009] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101380723393149424:91:0) after 1m0s
[14:09:14.035] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101380723393149424:91:0)
[14:09:14.035] Timer tick, asking Buildbucket for the build status
[14:09:14.087] Build 8935950653047478560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:09:14.087] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101380723393149424:92:0) after 1m0s
[14:10:14.110] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101380723393149424:92:0)
[14:10:14.110] Timer tick, asking Buildbucket for the build status
[14:10:14.184] Build 8935950653047478560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:10:14.184] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101380723393149424:93:0) after 1m0s
[14:11:14.216] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101380723393149424:93:0)
[14:11:14.216] Timer tick, asking Buildbucket for the build status
[14:11:14.264] Build 8935950653047478560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:11:14.264] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101380723393149424:94:0) after 1m0s
[14:12:14.286] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101380723393149424:94:0)
[14:12:14.286] Timer tick, asking Buildbucket for the build status
[14:12:14.517] Build 8935950653047478560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:12:14.517] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101380723393149424:95:0) after 1m0s
[14:13:14.647] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101380723393149424:95:0)
[14:13:14.647] Timer tick, asking Buildbucket for the build status
[14:13:14.724] Build 8935950653047478560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:13:14.724] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101380723393149424:96:0) after 1m0s
[14:14:14.751] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101380723393149424:96:0)
[14:14:14.751] Timer tick, asking Buildbucket for the build status
[14:14:14.816] Build 8935950653047478560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:14:14.816] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101380723393149424:97:0) after 1m0s
[14:15:14.846] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101380723393149424:97:0)
[14:15:14.846] Timer tick, asking Buildbucket for the build status
[14:15:14.919] Build 8935950653047478560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:15:14.919] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101380723393149424:98:0) after 1m0s
[14:16:14.975] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101380723393149424:98:0)
[14:16:14.975] Timer tick, asking Buildbucket for the build status
[14:16:15.170] Build 8935950653047478560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:16:15.170] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101380723393149424:99:0) after 1m0s
[14:17:15.200] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101380723393149424:99:0)
[14:17:15.200] Timer tick, asking Buildbucket for the build status
[14:17:15.288] Build 8935950653047478560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:17:15.288] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101380723393149424:100:0) after 1m0s
[14:18:15.321] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101380723393149424:100:0)
[14:18:15.321] Timer tick, asking Buildbucket for the build status
[14:18:15.396] Build 8935950653047478560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:18:15.396] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101380723393149424:101:0) after 1m0s
[14:19:10.241] Received PubSub notification, asking Buildbucket for the build status
[14:19:10.378] Build 8935950653047478560: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[14:19:10.378] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"completed_ts": "1536416348989841",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1536410391722837",
"id": "8935950653047478560",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"254a5a0eca4152fb2a4d1f003c9a565b26b26ad7\"}}",
"project": "chromium",
"result": "SUCCESS",
"result_details_json": "{\"ui\": {\"info\": \"\"}, \"build_run_result\": {\"infraFailure\": null, \"recipeResult\": {\"jsonResult\": \"null\"}, \"recipeExitCode\": {\"value\": 0}, \"recipe\": {\"name\": \"swarming/deterministic_build\", \"repository\": \"\", \"revision\": \"\"}, \"annotationUrl\": \"logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8935950653047478560/+/annotations\"}, \"swarming\": {\"bot_dimensions\": {\"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\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Haswell_GCE\"], \"python\": [\"2.7.6\"], \"server_version\": [\"3769-3c22b45\"], \"image\": [\"chrome-win10-1703-2018-04-20-61acaf8983f\"], \"gpu\": [\"none\"], \"cores\": [\"8\"], \"integrity\": [\"high\"], \"id\": [\"swarm2173-c4\"], \"pool\": [\"luci.chromium.ci\"], \"caches\": [\"git\", \"goma_v2\", \"vpython\"]}}, \"properties\": {\"got_nacl_revision\": \"cb6ec4b1f91af259cbdfd1bd22bb98ed5551955e\", \"got_swarming_client_revision\": \"486c9b53c4d54dd4b95bb6ce0e31160e600dfc11\", \"got_revision\": \"254a5a0eca4152fb2a4d1f003c9a565b26b26ad7\", \"recipe\": \"swarming/deterministic_build\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#24631}\", \"$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@{#589778}\", \"branch\": \"refs/heads/master\", \"revision\": \"254a5a0eca4152fb2a4d1f003c9a565b26b26ad7\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"buildername\": \"Windows deterministic\", \"got_webrtc_revision\": \"d7027dc081cb96791b51524210aa249eb301c056\", \"mastername\": \"chromium.fyi\", \"got_angle_revision\": \"3b9680534909944560e4a12e0cce6be5943cc3e8\", \"buildbucket\": {\"hostname\": \"cr-buildbucket.appspot.com\", \"build\": {\"created_ts\": 1536410391722837, \"tags\": [\"builder:Windows deterministic\", \"buildset:commit/git/254a5a0eca4152fb2a4d1f003c9a565b26b26ad7\", \"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/254a5a0eca4152fb2a4d1f003c9a565b26b26ad7\", \"gitiles_ref:refs/heads/master\", \"scheduler_invocation_id:9101380723393149424\", \"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\": \"8935950653047478560\"}}, \"got_v8_revision\": \"cbaaa59c093bf46727ef71341900ab1c5f345de8\", \"got_v8_revision_cp\": \"refs/heads/7.1.68@{#1}\", \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"buildnumber\": 10017, \"swarm_hashes\": {\"shell_dialogs_unittests\": \"e6d65e578dc7e2384db38e5a0ef9bd0db874ca67\", \"notification_helper_unittests\": \"7370890620e4fb9bf164505dc9b7029de1b08457\", \"gpu_perftests\": \"c7aeac88afd01cb6f17cefcbc40b734ad3fca09e\", \"vr_common_unittests\": \"98fe7f5650aec7186398e4b63c1113024337f649\", \"telemetry_unittests\": \"4a4b83425aaa435513c291cac0ca271ad969d852\", \"load_library_perf_tests\": \"79693da66d85ddb79e257de2a84a2ff2a280cad5\", \"devtools_closure_compile\": \"7d01ddf637cd20cfde26adfb03767a3010c537fd\", \"chromedriver_py_tests\": \"32fd1ce5e32fe43954da0f7a73d83152824a9025\", \"mojo_core_unittests\": \"18d6e8982f9a8659405126fe73a75fcb3beaf02a\", \"install_static_unittests\": \"4c71b2f5f698d5db1dd108449885d40e909e422c\", \"leveldb_service_unittests\": \"2ba2741d677b0718ff5e979fc3d7c759536ec3e0\", \"mini_installer_tests\": \"080452ad903c24b67505ce4f0a06fb20debfbd6a\", \"jingle_unittests\": \"cdede90dc367a426195460b0a41ae76ecc7c52d4\", \"sbox_integration_tests\": \"443d127db0570da7487115127a10f460e183e5ce\", \"angle_unittests\": \"7faf0dd868ba24b16643b52a456df5de71a06cce\", \"cast_unittests\": \"c47fc0cf5a2d8c9a6b308114b64aeaedee87e461\", \"cc_unittests\": \"ffd827323d4e3a890f071b7a6ee82e469ec30208\", \"blink_common_unittests\": \"f60fa49c73059425aac34f4b76fcdc5f82494771\", \"crashpad_tests\": \"040b8435ff88ebc4e3bcc29008ad4387b17d9680\", \"telemetry_perf_tests_without_chrome\": \"f4c82282197463340392199e803dcdb6d06cc2e0\", \"metrics_python_tests\": \"a54ccbdcb57a0d4e104d4d9469fa880fb724183b\", \"message_center_unittests\": \"52ed7230bc9ed6939ebfc72f127404e3268a3476\", \"ppapi_unittests\": \"79848de8f3bc08e578084051d70575e0c0055a0c\", \"extensions_unittests\": \"a8e4881aabb830e21a469fbcb0f6cdfe0ecab027\", \"gl_unittests_ozone\": \"a645b28bc0fa3f4cb5ac9bb459dce682c073c632\", \"app_shell_unittests\": \"68d9f4b00c13eb3c15f63f2ae6647e8fa810659a\", \"chrome_app_unittests\": \"bff8f33bbea1ed6194daae038d5f803d34981ae8\", \"gl_unittests\": \"a645b28bc0fa3f4cb5ac9bb459dce682c073c632\", \"gcm_unit_tests\": \"c8d71bc33a483c017d029ce2b135bc42f81a5fcd\", \"views_unittests\": \"b5590fc56c0649b7612523e4ae3b952c300ddb81\", \"content_shell_crash_test\": \"c94a0ffc27c11508f4cbe1b22ecd7294eb43eab9\", \"ui_base_unittests\": \"346c4fcc5266b515bddb56d1a124bcf92f361fa0\", \"gles2_conform_test\": \"1b3bb3b087d75aae464e4bb459288f25aeb87430\", \"cronet_unittests\": \"bc4cd5dd037caf4fcd2f17a2e12fa632d4aab5c3\", \"performance_test_suite\": \"d644aa068e08fc8b888f110de4b148586e682b38\", \"wm_unittests\": \"c312bc05456f73221e4de0bfccc5f3437d3607f9\", \"boringssl_crypto_tests\": \"13aaa4eef67c2d42e838bb81ba92099e1b8cb1f4\", \"gin_unittests\": \"ab316351541a0d09bc3d30958ea6b896ca6a65c2\", \"performance_browser_tests\": \"8b1f51b19549d7c40f9a7f73a720674176f96435\", \"extensions_browsertests\": \"f1b547853c791ed59604ad9dde3bcfda2ced1cbd\", \"webkit_python_tests\": \"7a8b2592d5a12e27fd1e379bf439887958ff880b\", \"headless_unittests\": \"62501bc8e05d7103bd049a9b37bc5ddf8c072fd9\", \"webkit_layout_tests\": \"7e5e8182212de9fcab8e9cdaa2f3244889908cf0\", \"vr_pixeltests\": \"a06303925d856648f63205b069bfb719c6ad81c1\", \"setup_unittests\": \"7a1c3867a26fffb992cc2a5210edf6f4d98c2c4d\", \"webkit_unit_tests\": \"1f7fc82eb3d29707d2caed3b1fa506b8f3516a72\", \"content_unittests\": \"4e4fd5699dfb25583cedbbf684fcfc2a7ecc1bed\", \"performance_webview_test_suite\": \"3495a9f57427f12506582652676b50b2d63a7757\", \"wtf_unittests\": \"3cbb65384ccca3da70f479578195ad5571ba4a58\", \"webkit_layout_tests_exparchive\": \"978c24e780e23bab3a87ab2e565fcf4805de5cda\", \"chrome_cleaner_unittests\": \"0cd5dc09cffbf359f9a665db86b15a2adfaecd1e\", \"traffic_annotation_auditor_unittests\": \"3728aa2b094340b589a5756263acf699e3469cc1\", \"libjingle_xmpp_unittests\": \"3b55440aa35a2630641c8bafea9308c2efce39d5\", \"remoting_unittests\": \"bad795739214a7f3a83a2b641507eeea05cafde3\", \"vr_common_perftests\": \"15887d274bbe00099114f2f976eaf8427d5e33db\", \"filesystem_service_unittests\": \"64e4af1cd8e501608830a14bad1d995c67cfa0b4\", \"chrome_elf_import_unittests\": \"4f531e85a33aa6b932e6c3170a5e863cee889688\", \"installer_util_unittests\": \"6a031312639ce2a98f5ac5b8a7d9c2f0fa1fc6f2\", \"gfx_unittests\": \"30235acd54f86e71615eae88ff2feccbf451bd6d\", \"headless_browsertests\": \"9ae9b39c467b9b1185beda6d2017f067a2a43bfc\", \"capture_unittests\": \"b73ae49916b5eeffeabf83454c5385d81ba758e4\", \"chrome_elf_unittests\": \"1e76f3d5dfdaa7673e6625848ff9c9f73d49a4ae\", \"telemetry_gpu_unittests\": \"ee42ac857a47d5767f48e05b5853a2624239ace0\", \"command_buffer_perftests\": \"ff44d2ad8727e82bcd2cc268be9a18c8ca654f8d\", \"media_unittests\": \"49d83f6742e60c1bfe3e35c2495a36e7da9ef1e4\", \"chromedriver_unittests\": \"27976d291152b4fb107387124e6766f929bfdd72\", \"views_mus_interactive_ui_tests\": \"b69eeb50ba5d5478af1e8b02da92baa106456ded\", \"storage_unittests\": \"525994c8372e2fd455d892a92747d6d6e584f350\", \"media_service_unittests\": \"6d3f40bb7c2cfb76ce7b05e0cd6fcebb541ea32f\", \"compositor_unittests\": \"161a9b03a67bd9280844dc311fcca30701fae642\", \"latency_unittests\": \"b343d66c55dd114f29444d5c9c14316606300b5b\", \"video_decode_accelerator_unittest\": \"9494568902f303f8effb5bd63e23a3dc9d92b560\", \"swiftshader_unittests\": \"153dd59cab6fc7c9cd42583fdaff6245002d7998\", \"telemetry_perf_unittests\": \"6db12e9095709aa534cf1f2c0da4b44c7af010b8\", \"media_blink_unittests\": \"b71c325dc85189a2043f754bf5d7dc9064ed3cf3\", \"views_mus_unittests\": \"b091724e4d34a058b98de1bd271fe68552b9e8ff\", \"components_unittests\": \"205071804c397b268cd91cde8a7ea4ff8aa08404\", \"snapshot_unittests\": \"e2a1fbe04e495b8e5945214f23e705aee267550c\", \"accessibility_unittests\": \"d7574ec5f1d4f8f0f5849e0293da442c29349666\", \"net_perftests\": \"f154d7c95d846cd170e28df986ad2a7302123663\", \"aura_unittests\": \"8b9ef6bddb1bbbcb889a05b5553b77a31a037526\", \"services_unittests\": \"42e9153717b2a78e7d6bc2362376ac8493f3e11b\", \"components_perftests\": \"9609f7c5667422e5b03ec5131da25d6d3a1004d8\", \"events_unittests\": \"924427b98c3976d7c930de1b385070a3eafcc1fa\", \"tracing_perftests\": \"d6838e77e8ed679885127c564aa5ab6919ab2750\", \"chromedriver_replay_unittests\": \"e2ec5b5deadfbf1a0e00246e86e8f3e11e9cace0\", \"ui_touch_selection_unittests\": \"39feac030f83d90ddf1b0b478633c13dadb1f59e\", \"cacheinvalidation_unittests\": \"eb2454fd001266d5190646697055756e9ac5893f\", \"viz_unittests\": \"94fe6807a16a40408aa2c4035d6a71c61c142a19\", \"browser_tests\": \"0a273268cc9ce836c91fcdeed66fdfd4c28cc32f\", \"net_unittests\": \"87a075312642fb8b92475a464beb4e8367487dbb\", \"midi_unittests\": \"bb90e47a25a45b064db15ea253add3942f5a9f7b\", \"boringssl_ssl_tests\": \"ea83f5d04662d93f2ae7e3c157d5dc3f86e48b67\", \"sbox_validation_tests\": \"e468a607e243b5fc020aa1c85d0a8776076bb8ae\", \"elevation_service_unittests\": \"042806a406e5a598e9354b76b5a2e2f07da13483\", \"gpu_unittests\": \"6d92ede168e0051abdb98b418259fd9266713a5b\", \"native_theme_unittests\": \"9ae1694f49121d2e678889f66129d97930005cdd\", \"interactive_ui_tests\": \"84e295cbcad2d08fefdad7a4e595365bfe6515e0\", \"mojo_unittests\": \"8613ee8a67a8e98165e02962e4517caf62e683a7\", \"url_unittests\": \"b3f295e5aa9e4fe2dc0cef6bea0dbb356b2a816b\", \"media_perftests\": \"35a9acf000203cda6f06b9f75329b3fc804d0155\", \"cronet_tests\": \"32f2eae7b55bdf5615eaf5eecce7fcaaca0b4114\", \"google_apis_unittests\": \"07c820a364d8d7d7a5abb306b3be33994437763a\", \"telemetry_gpu_integration_test\": \"39574c9b69407ff052a151c53fcf8fd28babf749\", \"blink_heap_unittests\": \"b22964c201055beeb4f679a0f16a17bc5d24510f\", \"base_unittests\": \"47db971430222fdd0bfda4194de056067c054401\", \"sync_integration_tests\": \"9aa51657cb0f70b62b0da7bc11a24ed88ef45abd\", \"sql_unittests\": \"d8006aebe0586a1f44e4b43033bb88b4cd70680b\", \"courgette_unittests\": \"a6f327d0bd8d587f54711e423b4bdb446eaf821c\", \"blink_fuzzer_unittests\": \"bff3862c951e84f8f189447a56c8988f2fed5e8c\", \"gl_tests\": \"e30775afc4f169464adada101e422f1cda64a8f2\", \"zucchini_unittests\": \"20debeae1ee5682b49062aa21ddea757a4881ff4\", \"ipc_tests\": \"d8b57449942c63ba6b5869dcacc2307e56df8022\", \"angle_end2end_tests\": \"80e8ff78adec9c16e52c2075a00f7060ca29eaf0\", \"blink_platform_unittests\": \"8a7800771e9ec9a8838e69839baf6621fed49d50\", \"components_browsertests\": \"c0515aeac2e6a17cba816916073f8b5efdb8d90d\", \"views_perftests\": \"ac5a55f80a1af34de66fa2d8ee120afca97d1ad6\", \"angle_perftests\": \"d37d931b2bcba01618a514fa5014f20bb5cd5338\", \"display_unittests\": \"2f083f36917d27d397113c51535155f1eb017679\", \"sbox_unittests\": \"e0be128e5a9086036586dd02f59cd6becb1b89fa\", \"service_manager_unittests\": \"8226c3f3353faae7d2b18f09d214ba1162d9f1fc\", \"pdf_unittests\": \"cbd68b2da5d968705e9918e55e1f075f3c48b5d3\", \"skia_unittests\": \"70992474c95b91063495210ef8f6261b69b11d99\", \"angle_white_box_tests\": \"63ed9bc8bb7e6a945d361a478f4e20d217e622ca\", \"crypto_unittests\": \"7619ea45429857866a3522137c632d2fa4fb613c\", \"nacl_loader_unittests\": \"02682174802984c04cd271ce0e646f1338fda5eb\", \"unit_tests\": \"387bac064d6c4565c38a9f79a19202c816bf6473\", \"content_browsertests\": \"6d09137647d57d99ed26a174dab384db282b8f6e\", \"printing_unittests\": \"a360c8795165df4d5f5e23df7de3beaa00e758d7\", \"device_unittests\": \"0ce7c68e64a2abcfc54ac9d11c40479bf19618e4\"}, \"path_config\": \"generic\", \"bot_id\": \"swarm2173-c4\", \"got_buildtools_revision\": \"2dff9c9c74e9d732e6fe57c84ef7fd044cc45d96\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1536410480709212",
"status": "COMPLETED",
"status_changed_ts": "1536416349786933",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/10017",
"builder:Windows deterministic",
"buildset:commit/git/254a5a0eca4152fb2a4d1f003c9a565b26b26ad7",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/254a5a0eca4152fb2a4d1f003c9a565b26b26ad7",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9101380723393149424",
"scheduler_job_id:chromium/Windows deterministic",
"swarming_dimension:builder:Windows deterministic",
"swarming_dimension:caches:builder_d795d6e07fe37a299fcfc0e872e48757ad306282f024125f803c0465bfd1eeae_v2",
"swarming_dimension:cores:8",
"swarming_dimension:cpu:x86-64",
"swarming_dimension:os:Windows-10",
"swarming_dimension:pool:luci.chromium.ci",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:build_address:luci.chromium.ci/Windows deterministic/10017",
"swarming_tag:buildbucket_bucket:luci.chromium.ci",
"swarming_tag:buildbucket_build_id:8935950653047478560",
"swarming_tag:buildbucket_hostname:cr-buildbucket.appspot.com",
"swarming_tag:buildbucket_template_canary:0",
"swarming_tag:buildbucket_template_revision:ca2c7a741e04d0385e2bf62941ac932dc0521af3",
"swarming_tag:builder:Windows deterministic",
"swarming_tag:buildset:commit/git/254a5a0eca4152fb2a4d1f003c9a565b26b26ad7",
"swarming_tag:buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/254a5a0eca4152fb2a4d1f003c9a565b26b26ad7",
"swarming_tag:caches:builder_d795d6e07fe37a299fcfc0e872e48757ad306282f024125f803c0465bfd1eeae_v2",
"swarming_tag:cores:8",
"swarming_tag:cpu:x86-64",
"swarming_tag:gitiles_ref:refs/heads/master",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8935950653047478560/+/annotations",
"swarming_tag:luci_project:chromium",
"swarming_tag:os:Windows-10",
"swarming_tag:pool:luci.chromium.ci",
"swarming_tag:priority:30",
"swarming_tag:recipe_name:swarming/deterministic_build",
"swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build",
"swarming_tag:scheduler_invocation_id:9101380723393149424",
"swarming_tag:scheduler_job_id:chromium/Windows deterministic",
"swarming_tag:service_account:chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"swarming_tag:swarming.pool.template:skip",
"swarming_tag:swarming.pool.version:71d843ea58cc76a6b4422ca80f2115179bb30d7f",
"swarming_tag:user:None",
"swarming_tag:user_agent:luci-scheduler",
"swarming_tag:vpython:native-python-wrapper",
"swarming_task_id:3fd203a00f1f3e10",
"user_agent:luci-scheduler"
],
"updated_ts": "1536416349789078",
"url": "https://ci.chromium.org/p/chromium/builders/luci.chromium.ci/Windows deterministic/10017",
"utcnow_ts": "1536416350311609"
}
}
[14:19:10.378] Invocation finished in 1h39m27.904822771s with status SUCCEEDED