[07:32:18.466] New invocation is queued and will start shortly
[07:32:19.717] Starting the invocation (attempt 1)
[07:32:19.747] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[07:32:19.747] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[07:32:19.747] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9106292299539484848",
"parameters_json": "{\"builder_name\":\"Windows deterministic\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src.git\",\"revision\":\"dab534357763fbe122191d6c7f749816e2c08696\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Windows deterministic",
"scheduler_invocation_id:9106292299539484848",
"scheduler_job_id:chromium/Windows deterministic",
"user_agent:luci-scheduler",
"buildset:commit/git/dab534357763fbe122191d6c7f749816e2c08696",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/dab534357763fbe122191d6c7f749816e2c08696",
"gitiles_ref:refs/heads/master"
]
}
[07:32:21.126] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1531726339818120",
"id": "8940862237457754480",
"lease_expiration_ts": "1531787540873670",
"lease_key": "504063396",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"dab534357763fbe122191d6c7f749816e2c08696\"}}",
"project": "chromium",
"result_details_json": "null",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1531726341034220",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/9517",
"builder:Windows deterministic",
"buildset:commit/git/dab534357763fbe122191d6c7f749816e2c08696",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/dab534357763fbe122191d6c7f749816e2c08696",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9106292299539484848",
"scheduler_job_id:chromium/Windows deterministic",
"swarming_dimension:builder:Windows deterministic",
"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/9517",
"swarming_tag:buildbucket_bucket:luci.chromium.ci",
"swarming_tag:buildbucket_build_id:8940862237457754480",
"swarming_tag:buildbucket_hostname:cr-buildbucket.appspot.com",
"swarming_tag:buildbucket_template_canary:0",
"swarming_tag:buildbucket_template_revision:4e8cd6081d2622e2214b4bbd0cdf5e2ba5406635",
"swarming_tag:builder:Windows deterministic",
"swarming_tag:buildset:commit/git/dab534357763fbe122191d6c7f749816e2c08696",
"swarming_tag:buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/dab534357763fbe122191d6c7f749816e2c08696",
"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/8940862237457754480/+/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:9106292299539484848",
"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:cb76dd9a681a4367362c098d4bb68c8c82db75c2",
"swarming_tag:user:None",
"swarming_tag:user_agent:luci-scheduler",
"swarming_tag:vpython:native-python-wrapper",
"swarming_task_id:3ebad2aa596e7910",
"user_agent:luci-scheduler"
],
"updated_ts": "1531726341034330",
"url": "https://ci.chromium.org/p/chromium/builders/luci.chromium.ci/Windows deterministic/9517",
"utcnow_ts": "1531726341115640"
}
}
[07:32:21.126] Task URL: https://ci.chromium.org/p/chromium/builders/luci.chromium.ci/Windows deterministic/9517
[07:32:21.126] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:2:0) after 1m0s
[07:33:21.152] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:2:0)
[07:33:21.152] Timer tick, asking Buildbucket for the build status
[07:33:21.549] Build 8940862237457754480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:33:21.549] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:3:0) after 1m0s
[07:33:56.337] Received PubSub notification, asking Buildbucket for the build status
[07:33:56.588] Build 8940862237457754480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:34:21.635] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:3:0)
[07:34:21.635] Timer tick, asking Buildbucket for the build status
[07:34:21.667] Build 8940862237457754480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:34:21.667] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:5:0) after 1m0s
[07:35:21.800] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:5:0)
[07:35:21.800] Timer tick, asking Buildbucket for the build status
[07:35:21.922] Build 8940862237457754480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:35:21.922] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:6:0) after 1m0s
[07:36:22.021] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:6:0)
[07:36:22.021] Timer tick, asking Buildbucket for the build status
[07:36:22.066] Build 8940862237457754480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:36:22.066] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:7:0) after 1m0s
[07:37:22.175] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:7:0)
[07:37:22.175] Timer tick, asking Buildbucket for the build status
[07:37:22.315] Build 8940862237457754480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:37:22.315] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:8:0) after 1m0s
[07:38:22.531] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:8:0)
[07:38:22.531] Timer tick, asking Buildbucket for the build status
[07:38:22.628] Build 8940862237457754480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:38:22.628] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:9:0) after 1m0s
[07:39:22.701] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:9:0)
[07:39:22.701] Timer tick, asking Buildbucket for the build status
[07:39:22.798] Build 8940862237457754480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:39:22.798] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:10:0) after 1m0s
[07:40:22.831] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:10:0)
[07:40:22.831] Timer tick, asking Buildbucket for the build status
[07:40:22.886] Build 8940862237457754480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:40:22.886] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:11:0) after 1m0s
[07:41:23.065] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:11:0)
[07:41:23.065] Timer tick, asking Buildbucket for the build status
[07:41:23.238] Build 8940862237457754480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:41:23.238] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:12:0) after 1m0s
[07:42:23.322] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:12:0)
[07:42:23.322] Timer tick, asking Buildbucket for the build status
[07:42:23.481] Build 8940862237457754480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:42:23.481] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:13:0) after 1m0s
[07:43:23.609] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:13:0)
[07:43:23.609] Timer tick, asking Buildbucket for the build status
[07:43:23.708] Build 8940862237457754480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:43:23.709] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:14:0) after 1m0s
[07:44:23.778] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:14:0)
[07:44:23.778] Timer tick, asking Buildbucket for the build status
[07:44:23.813] Build 8940862237457754480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:44:23.813] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:15:0) after 1m0s
[07:45:23.940] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:15:0)
[07:45:23.940] Timer tick, asking Buildbucket for the build status
[07:45:23.991] Build 8940862237457754480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:45:23.991] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:16:0) after 1m0s
[07:46:24.144] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:16:0)
[07:46:24.144] Timer tick, asking Buildbucket for the build status
[07:46:24.237] Build 8940862237457754480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:46:24.237] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:17:0) after 1m0s
[07:47:24.254] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:17:0)
[07:47:24.254] Timer tick, asking Buildbucket for the build status
[07:47:24.758] Build 8940862237457754480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:47:24.758] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:18:0) after 1m0s
[07:48:24.785] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:18:0)
[07:48:24.785] Timer tick, asking Buildbucket for the build status
[07:48:24.830] Build 8940862237457754480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:48:24.830] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:19:0) after 1m0s
[07:49:24.847] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:19:0)
[07:49:24.847] Timer tick, asking Buildbucket for the build status
[07:49:24.901] Build 8940862237457754480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:49:24.901] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:20:0) after 1m0s
[07:50:24.936] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:20:0)
[07:50:24.936] Timer tick, asking Buildbucket for the build status
[07:50:25.012] Build 8940862237457754480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:50:25.012] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:21:0) after 1m0s
[07:51:25.030] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:21:0)
[07:51:25.030] Timer tick, asking Buildbucket for the build status
[07:51:25.099] Build 8940862237457754480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:51:25.099] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:22:0) after 1m0s
[07:52:25.150] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:22:0)
[07:52:25.150] Timer tick, asking Buildbucket for the build status
[07:52:25.197] Build 8940862237457754480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:52:25.197] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:23:0) after 1m0s
[07:53:25.298] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:23:0)
[07:53:25.298] Timer tick, asking Buildbucket for the build status
[07:53:25.818] Build 8940862237457754480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:53:25.818] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:24:0) after 1m0s
[07:54:25.841] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:24:0)
[07:54:25.841] Timer tick, asking Buildbucket for the build status
[07:54:25.959] Build 8940862237457754480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:54:25.959] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:25:0) after 1m0s
[07:55:26.090] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:25:0)
[07:55:26.090] Timer tick, asking Buildbucket for the build status
[07:55:26.148] Build 8940862237457754480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:55:26.148] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:26:0) after 1m0s
[07:56:26.276] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:26:0)
[07:56:26.276] Timer tick, asking Buildbucket for the build status
[07:56:26.424] Build 8940862237457754480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:56:26.424] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:27:0) after 1m0s
[07:57:26.470] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:27:0)
[07:57:26.470] Timer tick, asking Buildbucket for the build status
[07:57:26.596] Build 8940862237457754480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:57:26.596] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:28:0) after 1m0s
[07:58:26.612] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:28:0)
[07:58:26.612] Timer tick, asking Buildbucket for the build status
[07:58:26.652] Build 8940862237457754480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:58:26.652] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:29:0) after 1m0s
[07:59:26.756] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:29:0)
[07:59:26.756] Timer tick, asking Buildbucket for the build status
[07:59:26.797] Build 8940862237457754480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:59:26.797] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:30:0) after 1m0s
[08:00:26.853] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:30:0)
[08:00:26.853] Timer tick, asking Buildbucket for the build status
[08:00:27.446] Build 8940862237457754480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:00:27.446] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:31:0) after 1m0s
[08:01:27.463] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:31:0)
[08:01:27.463] Timer tick, asking Buildbucket for the build status
[08:01:27.495] Build 8940862237457754480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:01:27.496] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:32:0) after 1m0s
[08:02:27.522] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:32:0)
[08:02:27.522] Timer tick, asking Buildbucket for the build status
[08:02:27.847] Build 8940862237457754480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:02:27.847] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:33:0) after 1m0s
[08:03:27.868] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:33:0)
[08:03:27.868] Timer tick, asking Buildbucket for the build status
[08:03:28.014] Build 8940862237457754480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:03:28.014] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:34:0) after 1m0s
[08:04:28.049] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:34:0)
[08:04:28.049] Timer tick, asking Buildbucket for the build status
[08:04:28.142] Build 8940862237457754480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:04:28.142] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:35:0) after 1m0s
[08:05:28.199] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:35:0)
[08:05:28.199] Timer tick, asking Buildbucket for the build status
[08:05:28.270] Build 8940862237457754480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:05:28.270] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:36:0) after 1m0s
[08:06:28.287] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:36:0)
[08:06:28.287] Timer tick, asking Buildbucket for the build status
[08:06:28.323] Build 8940862237457754480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:06:28.323] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:37:0) after 1m0s
[08:07:28.342] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:37:0)
[08:07:28.342] Timer tick, asking Buildbucket for the build status
[08:07:28.432] Build 8940862237457754480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:07:28.432] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:38:0) after 1m0s
[08:08:28.518] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:38:0)
[08:08:28.518] Timer tick, asking Buildbucket for the build status
[08:08:28.581] Build 8940862237457754480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:08:28.581] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:39:0) after 1m0s
[08:09:28.787] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:39:0)
[08:09:28.787] Timer tick, asking Buildbucket for the build status
[08:09:28.857] Build 8940862237457754480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:09:28.857] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:40:0) after 1m0s
[08:10:28.878] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:40:0)
[08:10:28.878] Timer tick, asking Buildbucket for the build status
[08:10:28.913] Build 8940862237457754480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:10:28.913] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:41:0) after 1m0s
[08:11:28.929] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:41:0)
[08:11:28.929] Timer tick, asking Buildbucket for the build status
[08:11:29.010] Build 8940862237457754480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:11:29.010] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:42:0) after 1m0s
[08:12:29.027] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:42:0)
[08:12:29.027] Timer tick, asking Buildbucket for the build status
[08:12:29.076] Build 8940862237457754480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:12:29.076] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:43:0) after 1m0s
[08:13:29.097] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:43:0)
[08:13:29.097] Timer tick, asking Buildbucket for the build status
[08:13:29.263] Build 8940862237457754480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:13:29.263] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:44:0) after 1m0s
[08:14:29.283] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:44:0)
[08:14:29.283] Timer tick, asking Buildbucket for the build status
[08:14:29.383] Build 8940862237457754480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:14:29.383] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:45:0) after 1m0s
[08:15:29.458] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:45:0)
[08:15:29.458] Timer tick, asking Buildbucket for the build status
[08:15:29.502] Build 8940862237457754480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:15:29.502] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:46:0) after 1m0s
[08:16:29.520] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:46:0)
[08:16:29.520] Timer tick, asking Buildbucket for the build status
[08:16:29.603] Build 8940862237457754480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:16:29.603] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:47:0) after 1m0s
[08:17:29.737] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:47:0)
[08:17:29.737] Timer tick, asking Buildbucket for the build status
[08:17:30.048] Build 8940862237457754480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:17:30.048] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:48:0) after 1m0s
[08:18:30.159] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:48:0)
[08:18:30.159] Timer tick, asking Buildbucket for the build status
[08:18:30.274] Build 8940862237457754480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:18:30.274] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:49:0) after 1m0s
[08:19:30.299] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:49:0)
[08:19:30.299] Timer tick, asking Buildbucket for the build status
[08:19:30.378] Build 8940862237457754480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:19:30.378] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:50:0) after 1m0s
[08:20:30.412] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:50:0)
[08:20:30.412] Timer tick, asking Buildbucket for the build status
[08:20:30.506] Build 8940862237457754480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:20:30.506] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:51:0) after 1m0s
[08:21:30.526] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:51:0)
[08:21:30.526] Timer tick, asking Buildbucket for the build status
[08:21:30.782] Build 8940862237457754480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:21:30.782] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:52:0) after 1m0s
[08:22:30.799] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:52:0)
[08:22:30.799] Timer tick, asking Buildbucket for the build status
[08:22:30.853] Build 8940862237457754480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:22:30.853] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:53:0) after 1m0s
[08:23:30.873] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:53:0)
[08:23:30.873] Timer tick, asking Buildbucket for the build status
[08:23:30.996] Build 8940862237457754480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:23:30.996] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:54:0) after 1m0s
[08:24:31.022] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:54:0)
[08:24:31.022] Timer tick, asking Buildbucket for the build status
[08:24:31.096] Build 8940862237457754480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:24:31.096] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:55:0) after 1m0s
[08:25:31.116] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:55:0)
[08:25:31.116] Timer tick, asking Buildbucket for the build status
[08:25:31.635] Build 8940862237457754480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:25:31.635] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:56:0) after 1m0s
[08:26:31.678] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:56:0)
[08:26:31.678] Timer tick, asking Buildbucket for the build status
[08:26:31.842] Build 8940862237457754480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:26:31.842] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:57:0) after 1m0s
[08:27:31.868] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:57:0)
[08:27:31.868] Timer tick, asking Buildbucket for the build status
[08:27:31.922] Build 8940862237457754480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:27:31.922] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:58:0) after 1m0s
[08:28:31.953] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:58:0)
[08:28:31.953] Timer tick, asking Buildbucket for the build status
[08:28:32.006] Build 8940862237457754480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:28:32.006] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:59:0) after 1m0s
[08:29:32.076] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:59:0)
[08:29:32.077] Timer tick, asking Buildbucket for the build status
[08:29:32.176] Build 8940862237457754480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:29:32.176] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:60:0) after 1m0s
[08:30:32.248] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:60:0)
[08:30:32.248] Timer tick, asking Buildbucket for the build status
[08:30:32.331] Build 8940862237457754480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:30:32.331] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:61:0) after 1m0s
[08:31:32.350] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:61:0)
[08:31:32.350] Timer tick, asking Buildbucket for the build status
[08:31:32.485] Build 8940862237457754480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:31:32.485] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:62:0) after 1m0s
[08:32:32.508] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:62:0)
[08:32:32.508] Timer tick, asking Buildbucket for the build status
[08:32:33.964] Build 8940862237457754480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:32:33.964] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:63:0) after 1m0s
[08:33:33.994] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:63:0)
[08:33:33.994] Timer tick, asking Buildbucket for the build status
[08:33:34.031] Build 8940862237457754480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:33:34.031] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:64:0) after 1m0s
[08:34:34.055] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:64:0)
[08:34:34.055] Timer tick, asking Buildbucket for the build status
[08:34:34.131] Build 8940862237457754480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:34:34.131] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:65:0) after 1m0s
[08:35:34.161] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:65:0)
[08:35:34.161] Timer tick, asking Buildbucket for the build status
[08:35:34.263] Build 8940862237457754480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:35:34.263] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:66:0) after 1m0s
[08:36:34.284] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:66:0)
[08:36:34.284] Timer tick, asking Buildbucket for the build status
[08:36:34.402] Build 8940862237457754480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:36:34.402] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:67:0) after 1m0s
[08:37:34.440] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:67:0)
[08:37:34.440] Timer tick, asking Buildbucket for the build status
[08:37:34.959] Build 8940862237457754480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:37:34.959] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:68:0) after 1m0s
[08:38:35.127] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:68:0)
[08:38:35.127] Timer tick, asking Buildbucket for the build status
[08:38:35.443] Build 8940862237457754480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:38:35.443] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:69:0) after 1m0s
[08:39:35.575] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:69:0)
[08:39:35.575] Timer tick, asking Buildbucket for the build status
[08:39:35.712] Build 8940862237457754480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:39:35.712] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:70:0) after 1m0s
[08:40:35.849] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:70:0)
[08:40:35.849] Timer tick, asking Buildbucket for the build status
[08:40:35.928] Build 8940862237457754480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:40:35.928] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:71:0) after 1m0s
[08:41:35.951] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:71:0)
[08:41:35.951] Timer tick, asking Buildbucket for the build status
[08:41:36.118] Build 8940862237457754480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:41:36.118] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:72:0) after 1m0s
[08:42:36.147] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:72:0)
[08:42:36.147] Timer tick, asking Buildbucket for the build status
[08:42:36.258] Build 8940862237457754480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:42:36.258] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:73:0) after 1m0s
[08:43:36.370] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:73:0)
[08:43:36.370] Timer tick, asking Buildbucket for the build status
[08:43:36.463] Build 8940862237457754480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:43:36.463] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:74:0) after 1m0s
[08:44:36.583] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:74:0)
[08:44:36.583] Timer tick, asking Buildbucket for the build status
[08:44:36.688] Build 8940862237457754480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:44:36.688] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:75:0) after 1m0s
[08:45:36.712] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:75:0)
[08:45:36.712] Timer tick, asking Buildbucket for the build status
[08:45:36.767] Build 8940862237457754480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:45:36.767] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:76:0) after 1m0s
[08:46:36.838] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:76:0)
[08:46:36.838] Timer tick, asking Buildbucket for the build status
[08:46:36.909] Build 8940862237457754480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:46:36.909] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:77:0) after 1m0s
[08:47:36.980] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:77:0)
[08:47:36.980] Timer tick, asking Buildbucket for the build status
[08:47:37.113] Build 8940862237457754480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:47:37.113] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:78:0) after 1m0s
[08:48:37.242] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:78:0)
[08:48:37.242] Timer tick, asking Buildbucket for the build status
[08:48:37.481] Build 8940862237457754480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:48:37.481] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:79:0) after 1m0s
[08:49:37.501] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:79:0)
[08:49:37.501] Timer tick, asking Buildbucket for the build status
[08:49:37.572] Build 8940862237457754480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:49:37.572] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:80:0) after 1m0s
[08:50:37.592] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:80:0)
[08:50:37.592] Timer tick, asking Buildbucket for the build status
[08:50:37.698] Build 8940862237457754480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:50:37.698] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:81:0) after 1m0s
[08:51:37.784] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:81:0)
[08:51:37.784] Timer tick, asking Buildbucket for the build status
[08:51:37.839] Build 8940862237457754480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:51:37.839] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:82:0) after 1m0s
[08:52:37.862] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:82:0)
[08:52:37.862] Timer tick, asking Buildbucket for the build status
[08:52:37.997] Build 8940862237457754480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:52:37.997] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:83:0) after 1m0s
[08:53:38.015] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:83:0)
[08:53:38.015] Timer tick, asking Buildbucket for the build status
[08:53:38.185] Build 8940862237457754480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:53:38.185] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:84:0) after 1m0s
[08:54:38.218] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:84:0)
[08:54:38.218] Timer tick, asking Buildbucket for the build status
[08:54:38.328] Build 8940862237457754480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:54:38.328] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:85:0) after 1m0s
[08:55:38.362] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:85:0)
[08:55:38.362] Timer tick, asking Buildbucket for the build status
[08:55:38.425] Build 8940862237457754480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:55:38.425] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:86:0) after 1m0s
[08:56:38.456] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:86:0)
[08:56:38.457] Timer tick, asking Buildbucket for the build status
[08:56:38.595] Build 8940862237457754480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:56:38.595] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:87:0) after 1m0s
[08:57:38.618] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:87:0)
[08:57:38.618] Timer tick, asking Buildbucket for the build status
[08:57:38.695] Build 8940862237457754480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:57:38.695] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:88:0) after 1m0s
[08:58:38.716] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:88:0)
[08:58:38.716] Timer tick, asking Buildbucket for the build status
[08:58:38.774] Build 8940862237457754480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:58:38.774] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:89:0) after 1m0s
[08:59:38.814] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:89:0)
[08:59:38.814] Timer tick, asking Buildbucket for the build status
[08:59:38.955] Build 8940862237457754480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:59:38.956] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:90:0) after 1m0s
[09:00:38.975] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:90:0)
[09:00:38.975] Timer tick, asking Buildbucket for the build status
[09:00:39.097] Build 8940862237457754480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:00:39.097] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:91:0) after 1m0s
[09:01:39.228] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:91:0)
[09:01:39.228] Timer tick, asking Buildbucket for the build status
[09:01:39.378] Build 8940862237457754480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:01:39.378] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:92:0) after 1m0s
[09:02:39.408] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:92:0)
[09:02:39.408] Timer tick, asking Buildbucket for the build status
[09:02:39.469] Build 8940862237457754480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:02:39.469] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:93:0) after 1m0s
[09:03:39.494] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:93:0)
[09:03:39.494] Timer tick, asking Buildbucket for the build status
[09:03:39.545] Build 8940862237457754480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:03:39.545] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:94:0) after 1m0s
[09:04:39.614] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:94:0)
[09:04:39.614] Timer tick, asking Buildbucket for the build status
[09:04:39.707] Build 8940862237457754480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:04:39.707] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:95:0) after 1m0s
[09:05:39.776] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:95:0)
[09:05:39.776] Timer tick, asking Buildbucket for the build status
[09:05:39.841] Build 8940862237457754480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:05:39.841] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:96:0) after 1m0s
[09:06:40.195] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:96:0)
[09:06:40.195] Timer tick, asking Buildbucket for the build status
[09:06:40.242] Build 8940862237457754480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:06:40.242] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:97:0) after 1m0s
[09:07:40.266] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:97:0)
[09:07:40.266] Timer tick, asking Buildbucket for the build status
[09:07:40.337] Build 8940862237457754480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:07:40.337] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:98:0) after 1m0s
[09:08:40.362] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:98:0)
[09:08:40.362] Timer tick, asking Buildbucket for the build status
[09:08:40.416] Build 8940862237457754480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:08:40.416] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:99:0) after 1m0s
[09:09:40.441] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:99:0)
[09:09:40.441] Timer tick, asking Buildbucket for the build status
[09:09:40.563] Build 8940862237457754480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:09:40.563] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:100:0) after 1m0s
[09:10:40.728] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:100:0)
[09:10:40.728] Timer tick, asking Buildbucket for the build status
[09:10:41.148] Build 8940862237457754480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:10:41.148] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:101:0) after 1m0s
[09:11:41.237] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:101:0)
[09:11:41.237] Timer tick, asking Buildbucket for the build status
[09:11:41.323] Build 8940862237457754480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:11:41.323] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:102:0) after 1m0s
[09:12:41.345] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:102:0)
[09:12:41.345] Timer tick, asking Buildbucket for the build status
[09:12:41.383] Build 8940862237457754480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:12:41.383] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:103:0) after 1m0s
[09:13:41.410] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:103:0)
[09:13:41.410] Timer tick, asking Buildbucket for the build status
[09:13:41.464] Build 8940862237457754480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:13:41.464] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:104:0) after 1m0s
[09:14:41.526] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:104:0)
[09:14:41.526] Timer tick, asking Buildbucket for the build status
[09:14:41.591] Build 8940862237457754480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:14:41.591] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:105:0) after 1m0s
[09:15:41.620] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:105:0)
[09:15:41.620] Timer tick, asking Buildbucket for the build status
[09:15:41.672] Build 8940862237457754480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:15:41.672] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:106:0) after 1m0s
[09:16:41.700] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:106:0)
[09:16:41.700] Timer tick, asking Buildbucket for the build status
[09:16:41.863] Build 8940862237457754480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:16:41.863] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:107:0) after 1m0s
[09:17:41.886] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:107:0)
[09:17:41.886] Timer tick, asking Buildbucket for the build status
[09:17:42.144] Build 8940862237457754480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:17:42.144] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:108:0) after 1m0s
[09:18:42.163] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:108:0)
[09:18:42.163] Timer tick, asking Buildbucket for the build status
[09:18:42.272] Build 8940862237457754480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:18:42.272] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:109:0) after 1m0s
[09:19:42.733] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:109:0)
[09:19:42.733] Timer tick, asking Buildbucket for the build status
[09:19:42.883] Build 8940862237457754480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:19:42.883] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:110:0) after 1m0s
[09:20:42.939] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:110:0)
[09:20:42.939] Timer tick, asking Buildbucket for the build status
[09:20:43.119] Build 8940862237457754480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:20:43.119] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:111:0) after 1m0s
[09:21:43.142] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:111:0)
[09:21:43.142] Timer tick, asking Buildbucket for the build status
[09:21:43.237] Build 8940862237457754480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:21:43.237] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:112:0) after 1m0s
[09:22:43.275] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:112:0)
[09:22:43.275] Timer tick, asking Buildbucket for the build status
[09:22:43.680] Build 8940862237457754480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:22:43.680] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:113:0) after 1m0s
[09:23:43.796] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:113:0)
[09:23:43.796] Timer tick, asking Buildbucket for the build status
[09:23:43.859] Build 8940862237457754480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:23:43.860] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:114:0) after 1m0s
[09:24:43.934] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:114:0)
[09:24:43.934] Timer tick, asking Buildbucket for the build status
[09:24:43.981] Build 8940862237457754480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:24:43.981] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:115:0) after 1m0s
[09:25:44.005] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:115:0)
[09:25:44.005] Timer tick, asking Buildbucket for the build status
[09:25:44.079] Build 8940862237457754480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:25:44.079] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:116:0) after 1m0s
[09:26:44.171] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:116:0)
[09:26:44.171] Timer tick, asking Buildbucket for the build status
[09:26:44.216] Build 8940862237457754480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:26:44.216] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:117:0) after 1m0s
[09:27:44.238] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:117:0)
[09:27:44.238] Timer tick, asking Buildbucket for the build status
[09:27:44.338] Build 8940862237457754480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:27:44.338] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:118:0) after 1m0s
[09:28:44.433] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:118:0)
[09:28:44.433] Timer tick, asking Buildbucket for the build status
[09:28:44.553] Build 8940862237457754480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:28:44.553] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:119:0) after 1m0s
[09:29:44.578] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:119:0)
[09:29:44.578] Timer tick, asking Buildbucket for the build status
[09:29:44.628] Build 8940862237457754480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:29:44.628] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:120:0) after 1m0s
[09:30:44.649] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:120:0)
[09:30:44.649] Timer tick, asking Buildbucket for the build status
[09:30:45.013] Build 8940862237457754480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:30:45.013] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:121:0) after 1m0s
[09:31:45.043] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:121:0)
[09:31:45.043] Timer tick, asking Buildbucket for the build status
[09:31:45.112] Build 8940862237457754480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:31:45.112] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:122:0) after 1m0s
[09:32:45.153] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:122:0)
[09:32:45.153] Timer tick, asking Buildbucket for the build status
[09:32:45.207] Build 8940862237457754480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:32:45.207] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:123:0) after 1m0s
[09:33:45.230] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:123:0)
[09:33:45.230] Timer tick, asking Buildbucket for the build status
[09:33:45.320] Build 8940862237457754480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:33:45.320] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:124:0) after 1m0s
[09:34:45.340] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:124:0)
[09:34:45.340] Timer tick, asking Buildbucket for the build status
[09:34:45.461] Build 8940862237457754480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:34:45.461] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:125:0) after 1m0s
[09:35:45.484] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:125:0)
[09:35:45.484] Timer tick, asking Buildbucket for the build status
[09:35:45.535] Build 8940862237457754480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:35:45.535] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:126:0) after 1m0s
[09:36:45.552] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:126:0)
[09:36:45.552] Timer tick, asking Buildbucket for the build status
[09:36:45.641] Build 8940862237457754480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:36:45.641] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:127:0) after 1m0s
[09:37:45.701] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:127:0)
[09:37:45.701] Timer tick, asking Buildbucket for the build status
[09:37:45.867] Build 8940862237457754480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:37:45.867] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:128:0) after 1m0s
[09:38:45.887] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:128:0)
[09:38:45.887] Timer tick, asking Buildbucket for the build status
[09:38:45.977] Build 8940862237457754480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:38:45.977] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:129:0) after 1m0s
[09:39:45.998] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:129:0)
[09:39:45.998] Timer tick, asking Buildbucket for the build status
[09:39:46.187] Build 8940862237457754480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:39:46.187] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:130:0) after 1m0s
[09:40:46.242] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:130:0)
[09:40:46.242] Timer tick, asking Buildbucket for the build status
[09:40:46.341] Build 8940862237457754480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:40:46.341] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:131:0) after 1m0s
[09:41:46.365] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:131:0)
[09:41:46.365] Timer tick, asking Buildbucket for the build status
[09:41:46.402] Build 8940862237457754480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:41:46.402] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:132:0) after 1m0s
[09:42:46.532] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:132:0)
[09:42:46.532] Timer tick, asking Buildbucket for the build status
[09:42:46.676] Build 8940862237457754480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:42:46.676] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:133:0) after 1m0s
[09:43:46.703] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:133:0)
[09:43:46.703] Timer tick, asking Buildbucket for the build status
[09:43:46.933] Build 8940862237457754480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:43:46.933] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:134:0) after 1m0s
[09:44:46.957] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:134:0)
[09:44:46.957] Timer tick, asking Buildbucket for the build status
[09:44:47.010] Build 8940862237457754480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:44:47.010] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:135:0) after 1m0s
[09:45:47.040] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:135:0)
[09:45:47.040] Timer tick, asking Buildbucket for the build status
[09:45:47.072] Build 8940862237457754480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:45:47.072] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:136:0) after 1m0s
[09:46:47.083] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:136:0)
[09:46:47.084] Timer tick, asking Buildbucket for the build status
[09:46:47.185] Build 8940862237457754480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:46:47.185] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:137:0) after 1m0s
[09:47:47.220] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:137:0)
[09:47:47.220] Timer tick, asking Buildbucket for the build status
[09:47:47.276] Build 8940862237457754480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:47:47.277] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:138:0) after 1m0s
[09:48:47.315] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:138:0)
[09:48:47.315] Timer tick, asking Buildbucket for the build status
[09:48:47.408] Build 8940862237457754480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:48:47.408] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:139:0) after 1m0s
[09:49:47.515] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:139:0)
[09:49:47.515] Timer tick, asking Buildbucket for the build status
[09:49:47.586] Build 8940862237457754480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:49:47.586] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:140:0) after 1m0s
[09:50:47.612] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:140:0)
[09:50:47.612] Timer tick, asking Buildbucket for the build status
[09:50:47.683] Build 8940862237457754480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:50:47.683] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:141:0) after 1m0s
[09:51:47.703] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:141:0)
[09:51:47.703] Timer tick, asking Buildbucket for the build status
[09:51:47.786] Build 8940862237457754480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:51:47.786] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:142:0) after 1m0s
[09:52:47.836] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:142:0)
[09:52:47.836] Timer tick, asking Buildbucket for the build status
[09:52:47.887] Build 8940862237457754480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:52:47.887] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:143:0) after 1m0s
[09:53:47.923] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:143:0)
[09:53:47.923] Timer tick, asking Buildbucket for the build status
[09:53:48.041] Build 8940862237457754480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:53:48.041] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:144:0) after 1m0s
[09:54:48.138] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:144:0)
[09:54:48.138] Timer tick, asking Buildbucket for the build status
[09:54:48.226] Build 8940862237457754480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:54:48.226] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:145:0) after 1m0s
[09:55:48.338] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:145:0)
[09:55:48.338] Timer tick, asking Buildbucket for the build status
[09:55:48.426] Build 8940862237457754480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:55:48.426] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:146:0) after 1m0s
[09:56:48.531] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:146:0)
[09:56:48.531] Timer tick, asking Buildbucket for the build status
[09:56:48.584] Build 8940862237457754480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:56:48.584] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:147:0) after 1m0s
[09:57:48.631] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:147:0)
[09:57:48.631] Timer tick, asking Buildbucket for the build status
[09:57:48.672] Build 8940862237457754480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:57:48.672] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:148:0) after 1m0s
[09:58:48.818] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:148:0)
[09:58:48.818] Timer tick, asking Buildbucket for the build status
[09:58:48.966] Build 8940862237457754480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:58:48.966] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:149:0) after 1m0s
[09:59:48.994] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:149:0)
[09:59:48.994] Timer tick, asking Buildbucket for the build status
[09:59:49.026] Build 8940862237457754480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:59:49.026] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:150:0) after 1m0s
[10:00:49.052] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:150:0)
[10:00:49.052] Timer tick, asking Buildbucket for the build status
[10:00:49.203] Build 8940862237457754480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:00:49.203] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:151:0) after 1m0s
[10:01:49.231] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:151:0)
[10:01:49.231] Timer tick, asking Buildbucket for the build status
[10:01:49.309] Build 8940862237457754480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:01:49.309] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:152:0) after 1m0s
[10:02:49.337] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:152:0)
[10:02:49.337] Timer tick, asking Buildbucket for the build status
[10:02:49.403] Build 8940862237457754480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:02:49.403] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:153:0) after 1m0s
[10:03:49.433] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:153:0)
[10:03:49.433] Timer tick, asking Buildbucket for the build status
[10:03:49.495] Build 8940862237457754480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:03:49.495] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:154:0) after 1m0s
[10:04:49.519] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:154:0)
[10:04:49.519] Timer tick, asking Buildbucket for the build status
[10:04:49.653] Build 8940862237457754480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:04:49.653] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:155:0) after 1m0s
[10:05:49.756] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:155:0)
[10:05:49.756] Timer tick, asking Buildbucket for the build status
[10:05:49.816] Build 8940862237457754480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:05:49.816] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:156:0) after 1m0s
[10:06:49.839] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:156:0)
[10:06:49.839] Timer tick, asking Buildbucket for the build status
[10:06:49.994] Build 8940862237457754480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:06:49.994] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:157:0) after 1m0s
[10:07:50.016] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:157:0)
[10:07:50.016] Timer tick, asking Buildbucket for the build status
[10:07:50.085] Build 8940862237457754480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:07:50.086] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:158:0) after 1m0s
[10:08:50.114] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:158:0)
[10:08:50.114] Timer tick, asking Buildbucket for the build status
[10:08:50.153] Build 8940862237457754480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:08:50.153] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:159:0) after 1m0s
[10:09:50.227] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:159:0)
[10:09:50.227] Timer tick, asking Buildbucket for the build status
[10:09:50.281] Build 8940862237457754480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:09:50.281] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:160:0) after 1m0s
[10:10:50.302] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:160:0)
[10:10:50.302] Timer tick, asking Buildbucket for the build status
[10:10:50.364] Build 8940862237457754480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:10:50.364] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:161:0) after 1m0s
[10:11:50.441] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:161:0)
[10:11:50.441] Timer tick, asking Buildbucket for the build status
[10:11:50.516] Build 8940862237457754480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:11:50.516] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:162:0) after 1m0s
[10:12:50.557] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:162:0)
[10:12:50.557] Timer tick, asking Buildbucket for the build status
[10:12:50.604] Build 8940862237457754480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:12:50.604] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:163:0) after 1m0s
[10:13:50.726] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:163:0)
[10:13:50.726] Timer tick, asking Buildbucket for the build status
[10:13:50.848] Build 8940862237457754480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:13:50.848] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:164:0) after 1m0s
[10:14:50.869] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:164:0)
[10:14:50.869] Timer tick, asking Buildbucket for the build status
[10:14:50.958] Build 8940862237457754480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:14:50.958] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:165:0) after 1m0s
[10:15:51.092] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:165:0)
[10:15:51.092] Timer tick, asking Buildbucket for the build status
[10:15:51.170] Build 8940862237457754480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:15:51.170] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:166:0) after 1m0s
[10:16:51.197] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:166:0)
[10:16:51.197] Timer tick, asking Buildbucket for the build status
[10:16:51.248] Build 8940862237457754480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:16:51.248] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:167:0) after 1m0s
[10:17:51.274] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:167:0)
[10:17:51.274] Timer tick, asking Buildbucket for the build status
[10:17:51.343] Build 8940862237457754480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:17:51.343] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:168:0) after 1m0s
[10:18:51.381] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:168:0)
[10:18:51.381] Timer tick, asking Buildbucket for the build status
[10:18:51.436] Build 8940862237457754480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:18:51.436] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:169:0) after 1m0s
[10:19:51.459] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:169:0)
[10:19:51.459] Timer tick, asking Buildbucket for the build status
[10:19:52.047] Build 8940862237457754480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:19:52.047] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:170:0) after 1m0s
[10:20:52.104] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:170:0)
[10:20:52.104] Timer tick, asking Buildbucket for the build status
[10:20:52.185] Build 8940862237457754480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:20:52.185] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:171:0) after 1m0s
[10:21:52.229] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:171:0)
[10:21:52.229] Timer tick, asking Buildbucket for the build status
[10:21:52.384] Build 8940862237457754480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:21:52.384] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:172:0) after 1m0s
[10:22:52.493] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:172:0)
[10:22:52.493] Timer tick, asking Buildbucket for the build status
[10:22:52.590] Build 8940862237457754480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:22:52.590] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:173:0) after 1m0s
[10:23:52.727] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:173:0)
[10:23:52.727] Timer tick, asking Buildbucket for the build status
[10:23:52.783] Build 8940862237457754480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:23:52.784] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:174:0) after 1m0s
[10:24:52.820] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:174:0)
[10:24:52.820] Timer tick, asking Buildbucket for the build status
[10:24:52.887] Build 8940862237457754480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:24:52.887] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:175:0) after 1m0s
[10:25:52.941] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:175:0)
[10:25:52.941] Timer tick, asking Buildbucket for the build status
[10:25:52.979] Build 8940862237457754480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:25:52.979] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:176:0) after 1m0s
[10:26:53.002] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:176:0)
[10:26:53.002] Timer tick, asking Buildbucket for the build status
[10:26:53.038] Build 8940862237457754480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:26:53.039] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:177:0) after 1m0s
[10:27:53.063] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:177:0)
[10:27:53.063] Timer tick, asking Buildbucket for the build status
[10:27:53.095] Build 8940862237457754480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:27:53.095] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:178:0) after 1m0s
[10:28:53.122] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:178:0)
[10:28:53.122] Timer tick, asking Buildbucket for the build status
[10:28:53.186] Build 8940862237457754480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:28:53.186] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106292299539484848:179:0) after 1m0s
[10:29:38.967] Received PubSub notification, asking Buildbucket for the build status
[10:29:39.005] Build 8940862237457754480: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[10:29:39.005] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"completed_ts": "1531736977322300",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1531726339818120",
"failure_reason": "BUILD_FAILURE",
"id": "8940862237457754480",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"dab534357763fbe122191d6c7f749816e2c08696\"}}",
"project": "chromium",
"result": "FAILURE",
"result_details_json": "{\"ui\": {\"info\": \"Failure compare_build_artifacts\\nFailure gsutil upload\"}, \"build_run_result\": {\"infraFailure\": null, \"recipeResult\": {\"failure\": {\"failure\": {\"step\": \"gsutil upload\"}, \"humanReason\": \"Infra Failure: Step('gsutil upload') returned 1\"}}, \"recipeExitCode\": {\"value\": 1}, \"recipe\": {\"name\": \"swarming/deterministic_build\", \"repository\": \"\", \"revision\": \"\"}, \"annotationUrl\": \"logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8940862237457754480/+/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\": [\"3684-658b5dc\"], \"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\": \"e6ce828ef60c4c1438867b535efbbb5d9a177c0e\", \"got_swarming_client_revision\": \"9a518d097dca20b7b00ce3bdfc5d418ccc79893a\", \"got_revision\": \"dab534357763fbe122191d6c7f749816e2c08696\", \"recipe\": \"swarming/deterministic_build\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#23976}\", \"$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@{#575202}\", \"branch\": \"refs/heads/master\", \"revision\": \"dab534357763fbe122191d6c7f749816e2c08696\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"buildername\": \"Windows deterministic\", \"got_webrtc_revision\": \"c528c0a07f85cc8ff0880f0401fb272878dfdf10\", \"mastername\": \"chromium.fyi\", \"got_angle_revision\": \"76c1d14b8e212db9822a6398343a344ff9028298\", \"buildbucket\": {\"hostname\": \"cr-buildbucket.appspot.com\", \"build\": {\"created_ts\": 1531726339818120, \"tags\": [\"builder:Windows deterministic\", \"buildset:commit/git/dab534357763fbe122191d6c7f749816e2c08696\", \"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/dab534357763fbe122191d6c7f749816e2c08696\", \"gitiles_ref:refs/heads/master\", \"scheduler_invocation_id:9106292299539484848\", \"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\": \"8940862237457754480\"}}, \"got_v8_revision\": \"0551e649579849729ba87ab38e80af5ecd66bbb3\", \"got_v8_revision_cp\": \"refs/heads/6.9.389@{#1}\", \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"buildnumber\": 9517, \"path_config\": \"generic\", \"bot_id\": \"swarm2173-c4\", \"got_buildtools_revision\": \"0dd5c6f980d22be96b728155249df2da355989d9\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1531726435257460",
"status": "COMPLETED",
"status_changed_ts": "1531736978595440",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/9517",
"builder:Windows deterministic",
"buildset:commit/git/dab534357763fbe122191d6c7f749816e2c08696",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/dab534357763fbe122191d6c7f749816e2c08696",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9106292299539484848",
"scheduler_job_id:chromium/Windows deterministic",
"swarming_dimension:builder:Windows deterministic",
"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/9517",
"swarming_tag:buildbucket_bucket:luci.chromium.ci",
"swarming_tag:buildbucket_build_id:8940862237457754480",
"swarming_tag:buildbucket_hostname:cr-buildbucket.appspot.com",
"swarming_tag:buildbucket_template_canary:0",
"swarming_tag:buildbucket_template_revision:4e8cd6081d2622e2214b4bbd0cdf5e2ba5406635",
"swarming_tag:builder:Windows deterministic",
"swarming_tag:buildset:commit/git/dab534357763fbe122191d6c7f749816e2c08696",
"swarming_tag:buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/dab534357763fbe122191d6c7f749816e2c08696",
"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/8940862237457754480/+/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:9106292299539484848",
"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:cb76dd9a681a4367362c098d4bb68c8c82db75c2",
"swarming_tag:user:None",
"swarming_tag:user_agent:luci-scheduler",
"swarming_tag:vpython:native-python-wrapper",
"swarming_task_id:3ebad2aa596e7910",
"user_agent:luci-scheduler"
],
"updated_ts": "1531736978597100",
"url": "https://ci.chromium.org/p/chromium/builders/luci.chromium.ci/Windows deterministic/9517",
"utcnow_ts": "1531736978995240"
}
}
[10:29:39.005] Invocation finished in 2h57m20.556156488s with status FAILED