[20:06:33.572] New invocation is queued and will start shortly
[20:06:34.818] Starting the invocation (attempt 1)
[20:06:34.862] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[20:06:34.862] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[20:06:34.862] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9088940825549346576",
"parameters_json": "{\"builder_name\":\"Windows deterministic\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src.git\",\"revision\":\"48d8a282b6ff0e545e638fb19098213200ea55f7\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Windows deterministic",
"scheduler_invocation_id:9088940825549346576",
"scheduler_job_id:chromium/Windows deterministic",
"user_agent:luci-scheduler",
"buildset:commit/git/48d8a282b6ff0e545e638fb19098213200ea55f7",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/48d8a282b6ff0e545e638fb19098213200ea55f7",
"gitiles_ref:refs/heads/master"
]
}
[20:06:40.578] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1548273995050925",
"id": "8923510763324649936",
"lease_expiration_ts": "1548392798994320",
"lease_key": "1988309425",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"48d8a282b6ff0e545e638fb19098213200ea55f7\"}}",
"project": "chromium",
"result_details_json": "null",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1548273999921286",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/11714",
"builder:Windows deterministic",
"buildset:commit/git/48d8a282b6ff0e545e638fb19098213200ea55f7",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/48d8a282b6ff0e545e638fb19098213200ea55f7",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9088940825549346576",
"scheduler_job_id:chromium/Windows deterministic",
"swarming_dimension:builder:Windows deterministic",
"swarming_dimension:caches:builder_e8fffaf9fac16164b1ec16ebaa1f377b792d39a582f7640830518d8ff7303d0c_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/11714",
"swarming_tag:buildbucket_bucket:chromium/ci",
"swarming_tag:buildbucket_build_id:8923510763324649936",
"swarming_tag:buildbucket_hostname:cr-buildbucket.appspot.com",
"swarming_tag:buildbucket_template_canary:0",
"swarming_tag:buildbucket_template_revision:01134cf47e8a2d5d7cea5ec1232ca64ed2f16a5c",
"swarming_tag:builder:Windows deterministic",
"swarming_tag:buildset:commit/git/48d8a282b6ff0e545e638fb19098213200ea55f7",
"swarming_tag:buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/48d8a282b6ff0e545e638fb19098213200ea55f7",
"swarming_tag:caches:builder_e8fffaf9fac16164b1ec16ebaa1f377b792d39a582f7640830518d8ff7303d0c_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/8923510763324649936/+/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:9088940825549346576",
"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:ccfa8acb724fee8943da2165ede757ca12003338",
"swarming_tag:user:None",
"swarming_tag:user_agent:luci-scheduler",
"swarming_tag:vpython:native-python-wrapper",
"swarming_task_id:429523e36daf5010",
"user_agent:luci-scheduler"
],
"updated_ts": "1548273999921305",
"url": "https://ci.chromium.org/b/8923510763324649936",
"utcnow_ts": "1548274000477473"
}
}
[20:06:40.578] Task URL: https://ci.chromium.org/b/8923510763324649936
[20:06:40.578] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:2:0) after 1m0s
[20:07:01.263] Received PubSub notification, asking Buildbucket for the build status
[20:07:01.477] Build 8923510763324649936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:07:40.611] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:2:0)
[20:07:40.611] Timer tick, asking Buildbucket for the build status
[20:07:40.851] Build 8923510763324649936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:07:40.851] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:4:0) after 1m0s
[20:08:40.894] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:4:0)
[20:08:40.894] Timer tick, asking Buildbucket for the build status
[20:08:41.392] Build 8923510763324649936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:08:41.392] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:5:0) after 1m0s
[20:09:41.415] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:5:0)
[20:09:41.415] Timer tick, asking Buildbucket for the build status
[20:09:41.522] Build 8923510763324649936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:09:41.523] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:6:0) after 1m0s
[20:10:41.555] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:6:0)
[20:10:41.555] Timer tick, asking Buildbucket for the build status
[20:10:41.626] Build 8923510763324649936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:10:41.626] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:7:0) after 1m0s
[20:11:41.652] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:7:0)
[20:11:41.652] Timer tick, asking Buildbucket for the build status
[20:11:41.696] Build 8923510763324649936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:11:41.696] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:8:0) after 1m0s
[20:12:41.719] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:8:0)
[20:12:41.719] Timer tick, asking Buildbucket for the build status
[20:12:41.762] Build 8923510763324649936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:12:41.762] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:9:0) after 1m0s
[20:13:41.783] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:9:0)
[20:13:41.783] Timer tick, asking Buildbucket for the build status
[20:13:41.819] Build 8923510763324649936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:13:41.819] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:10:0) after 1m0s
[20:14:41.845] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:10:0)
[20:14:41.845] Timer tick, asking Buildbucket for the build status
[20:14:41.910] Build 8923510763324649936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:14:41.910] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:11:0) after 1m0s
[20:15:41.937] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:11:0)
[20:15:41.937] Timer tick, asking Buildbucket for the build status
[20:15:41.973] Build 8923510763324649936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:15:41.973] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:12:0) after 1m0s
[20:16:42.002] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:12:0)
[20:16:42.002] Timer tick, asking Buildbucket for the build status
[20:16:42.047] Build 8923510763324649936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:16:42.047] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:13:0) after 1m0s
[20:17:42.141] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:13:0)
[20:17:42.141] Timer tick, asking Buildbucket for the build status
[20:17:42.229] Build 8923510763324649936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:17:42.229] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:14:0) after 1m0s
[20:18:42.248] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:14:0)
[20:18:42.248] Timer tick, asking Buildbucket for the build status
[20:18:42.339] Build 8923510763324649936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:18:42.339] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:15:0) after 1m0s
[20:19:42.367] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:15:0)
[20:19:42.367] Timer tick, asking Buildbucket for the build status
[20:19:42.478] Build 8923510763324649936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:19:42.478] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:16:0) after 1m0s
[20:20:42.524] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:16:0)
[20:20:42.524] Timer tick, asking Buildbucket for the build status
[20:20:42.582] Build 8923510763324649936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:20:42.582] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:17:0) after 1m0s
[20:21:42.607] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:17:0)
[20:21:42.607] Timer tick, asking Buildbucket for the build status
[20:21:42.660] Build 8923510763324649936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:21:42.660] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:18:0) after 1m0s
[20:22:42.679] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:18:0)
[20:22:42.679] Timer tick, asking Buildbucket for the build status
[20:22:42.782] Build 8923510763324649936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:22:42.783] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:19:0) after 1m0s
[20:23:42.804] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:19:0)
[20:23:42.804] Timer tick, asking Buildbucket for the build status
[20:23:42.849] Build 8923510763324649936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:23:42.849] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:20:0) after 1m0s
[20:24:42.872] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:20:0)
[20:24:42.872] Timer tick, asking Buildbucket for the build status
[20:24:43.090] Build 8923510763324649936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:24:43.090] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:21:0) after 1m0s
[20:25:43.114] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:21:0)
[20:25:43.114] Timer tick, asking Buildbucket for the build status
[20:25:43.157] Build 8923510763324649936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:25:43.157] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:22:0) after 1m0s
[20:26:43.181] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:22:0)
[20:26:43.181] Timer tick, asking Buildbucket for the build status
[20:26:43.255] Build 8923510763324649936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:26:43.255] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:23:0) after 1m0s
[20:27:43.252] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:23:0)
[20:27:43.252] Timer tick, asking Buildbucket for the build status
[20:27:43.324] Build 8923510763324649936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:27:43.324] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:24:0) after 1m0s
[20:28:43.347] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:24:0)
[20:28:43.347] Timer tick, asking Buildbucket for the build status
[20:28:43.400] Build 8923510763324649936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:28:43.400] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:25:0) after 1m0s
[20:29:43.433] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:25:0)
[20:29:43.433] Timer tick, asking Buildbucket for the build status
[20:29:43.478] Build 8923510763324649936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:29:43.478] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:26:0) after 1m0s
[20:30:43.506] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:26:0)
[20:30:43.506] Timer tick, asking Buildbucket for the build status
[20:30:43.537] Build 8923510763324649936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:30:43.537] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:27:0) after 1m0s
[20:31:43.641] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:27:0)
[20:31:43.641] Timer tick, asking Buildbucket for the build status
[20:31:43.718] Build 8923510763324649936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:31:43.718] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:28:0) after 1m0s
[20:32:43.848] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:28:0)
[20:32:43.848] Timer tick, asking Buildbucket for the build status
[20:32:43.954] Build 8923510763324649936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:32:43.954] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:29:0) after 1m0s
[20:33:43.995] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:29:0)
[20:33:43.995] Timer tick, asking Buildbucket for the build status
[20:33:44.041] Build 8923510763324649936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:33:44.041] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:30:0) after 1m0s
[20:34:44.119] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:30:0)
[20:34:44.119] Timer tick, asking Buildbucket for the build status
[20:34:44.156] Build 8923510763324649936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:34:44.156] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:31:0) after 1m0s
[20:35:44.212] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:31:0)
[20:35:44.212] Timer tick, asking Buildbucket for the build status
[20:35:44.246] Build 8923510763324649936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:35:44.246] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:32:0) after 1m0s
[20:36:44.271] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:32:0)
[20:36:44.271] Timer tick, asking Buildbucket for the build status
[20:36:44.326] Build 8923510763324649936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:36:44.326] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:33:0) after 1m0s
[20:37:44.360] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:33:0)
[20:37:44.360] Timer tick, asking Buildbucket for the build status
[20:37:44.400] Build 8923510763324649936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:37:44.400] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:34:0) after 1m0s
[20:38:44.432] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:34:0)
[20:38:44.432] Timer tick, asking Buildbucket for the build status
[20:38:44.570] Build 8923510763324649936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:38:44.570] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:35:0) after 1m0s
[20:39:44.596] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:35:0)
[20:39:44.596] Timer tick, asking Buildbucket for the build status
[20:39:44.728] Build 8923510763324649936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:39:44.728] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:36:0) after 1m0s
[20:40:44.764] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:36:0)
[20:40:44.764] Timer tick, asking Buildbucket for the build status
[20:40:44.800] Build 8923510763324649936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:40:44.800] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:37:0) after 1m0s
[20:41:44.846] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:37:0)
[20:41:44.846] Timer tick, asking Buildbucket for the build status
[20:41:44.926] Build 8923510763324649936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:41:44.926] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:38:0) after 1m0s
[20:42:44.974] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:38:0)
[20:42:44.974] Timer tick, asking Buildbucket for the build status
[20:42:45.048] Build 8923510763324649936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:42:45.048] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:39:0) after 1m0s
[20:43:45.096] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:39:0)
[20:43:45.096] Timer tick, asking Buildbucket for the build status
[20:43:45.235] Build 8923510763324649936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:43:45.235] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:40:0) after 1m0s
[20:44:45.255] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:40:0)
[20:44:45.255] Timer tick, asking Buildbucket for the build status
[20:44:45.360] Build 8923510763324649936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:44:45.360] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:41:0) after 1m0s
[20:45:45.377] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:41:0)
[20:45:45.377] Timer tick, asking Buildbucket for the build status
[20:45:45.419] Build 8923510763324649936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:45:45.419] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:42:0) after 1m0s
[20:46:45.478] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:42:0)
[20:46:45.478] Timer tick, asking Buildbucket for the build status
[20:46:45.600] Build 8923510763324649936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:46:45.600] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:43:0) after 1m0s
[20:47:45.624] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:43:0)
[20:47:45.624] Timer tick, asking Buildbucket for the build status
[20:47:45.661] Build 8923510763324649936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:47:45.661] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:44:0) after 1m0s
[20:48:45.685] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:44:0)
[20:48:45.685] Timer tick, asking Buildbucket for the build status
[20:48:45.743] Build 8923510763324649936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:48:45.743] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:45:0) after 1m0s
[20:49:45.768] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:45:0)
[20:49:45.768] Timer tick, asking Buildbucket for the build status
[20:49:46.191] Build 8923510763324649936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:49:46.191] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:46:0) after 1m0s
[20:50:46.217] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:46:0)
[20:50:46.217] Timer tick, asking Buildbucket for the build status
[20:50:46.334] Build 8923510763324649936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:50:46.334] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:47:0) after 1m0s
[20:51:46.404] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:47:0)
[20:51:46.404] Timer tick, asking Buildbucket for the build status
[20:51:46.537] Build 8923510763324649936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:51:46.537] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:48:0) after 1m0s
[20:52:46.565] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:48:0)
[20:52:46.565] Timer tick, asking Buildbucket for the build status
[20:52:46.745] Build 8923510763324649936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:52:46.745] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:49:0) after 1m0s
[20:53:46.765] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:49:0)
[20:53:46.765] Timer tick, asking Buildbucket for the build status
[20:53:46.799] Build 8923510763324649936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:53:46.799] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:50:0) after 1m0s
[20:54:46.822] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:50:0)
[20:54:46.822] Timer tick, asking Buildbucket for the build status
[20:54:46.890] Build 8923510763324649936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:54:46.890] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:51:0) after 1m0s
[20:55:46.927] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:51:0)
[20:55:46.927] Timer tick, asking Buildbucket for the build status
[20:55:47.035] Build 8923510763324649936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:55:47.035] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:52:0) after 1m0s
[20:56:47.087] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:52:0)
[20:56:47.087] Timer tick, asking Buildbucket for the build status
[20:56:47.128] Build 8923510763324649936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:56:47.128] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:53:0) after 1m0s
[20:57:47.150] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:53:0)
[20:57:47.150] Timer tick, asking Buildbucket for the build status
[20:57:47.235] Build 8923510763324649936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:57:47.235] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:54:0) after 1m0s
[20:58:47.255] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:54:0)
[20:58:47.255] Timer tick, asking Buildbucket for the build status
[20:58:47.287] Build 8923510763324649936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:58:47.287] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:55:0) after 1m0s
[20:59:47.330] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:55:0)
[20:59:47.330] Timer tick, asking Buildbucket for the build status
[20:59:47.383] Build 8923510763324649936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:59:47.383] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:56:0) after 1m0s
[21:00:47.427] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:56:0)
[21:00:47.427] Timer tick, asking Buildbucket for the build status
[21:00:47.533] Build 8923510763324649936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:00:47.533] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:57:0) after 1m0s
[21:01:47.559] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:57:0)
[21:01:47.559] Timer tick, asking Buildbucket for the build status
[21:01:47.598] Build 8923510763324649936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:01:47.598] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:58:0) after 1m0s
[21:02:47.619] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:58:0)
[21:02:47.619] Timer tick, asking Buildbucket for the build status
[21:02:47.673] Build 8923510763324649936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:02:47.673] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:59:0) after 1m0s
[21:03:47.698] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:59:0)
[21:03:47.698] Timer tick, asking Buildbucket for the build status
[21:03:47.735] Build 8923510763324649936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:03:47.735] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:60:0) after 1m0s
[21:04:47.761] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:60:0)
[21:04:47.761] Timer tick, asking Buildbucket for the build status
[21:04:47.848] Build 8923510763324649936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:04:47.848] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:61:0) after 1m0s
[21:05:47.873] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:61:0)
[21:05:47.873] Timer tick, asking Buildbucket for the build status
[21:05:47.930] Build 8923510763324649936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:05:47.931] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:62:0) after 1m0s
[21:06:47.954] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:62:0)
[21:06:47.954] Timer tick, asking Buildbucket for the build status
[21:06:48.012] Build 8923510763324649936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:06:48.012] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:63:0) after 1m0s
[21:07:48.036] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:63:0)
[21:07:48.036] Timer tick, asking Buildbucket for the build status
[21:07:48.111] Build 8923510763324649936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:07:48.111] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:64:0) after 1m0s
[21:08:48.137] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:64:0)
[21:08:48.137] Timer tick, asking Buildbucket for the build status
[21:08:48.271] Build 8923510763324649936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:08:48.271] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:65:0) after 1m0s
[21:09:48.295] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:65:0)
[21:09:48.295] Timer tick, asking Buildbucket for the build status
[21:09:48.364] Build 8923510763324649936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:09:48.364] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:66:0) after 1m0s
[21:10:48.395] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:66:0)
[21:10:48.395] Timer tick, asking Buildbucket for the build status
[21:10:48.545] Build 8923510763324649936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:10:48.545] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:67:0) after 1m0s
[21:11:48.590] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:67:0)
[21:11:48.590] Timer tick, asking Buildbucket for the build status
[21:11:48.654] Build 8923510763324649936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:11:48.654] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:68:0) after 1m0s
[21:12:48.774] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:68:0)
[21:12:48.774] Timer tick, asking Buildbucket for the build status
[21:12:48.909] Build 8923510763324649936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:12:48.909] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:69:0) after 1m0s
[21:13:48.931] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:69:0)
[21:13:48.931] Timer tick, asking Buildbucket for the build status
[21:13:48.971] Build 8923510763324649936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:13:48.971] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:70:0) after 1m0s
[21:14:48.999] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:70:0)
[21:14:48.999] Timer tick, asking Buildbucket for the build status
[21:14:49.077] Build 8923510763324649936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:14:49.077] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:71:0) after 1m0s
[21:15:49.108] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:71:0)
[21:15:49.108] Timer tick, asking Buildbucket for the build status
[21:15:49.161] Build 8923510763324649936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:15:49.161] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:72:0) after 1m0s
[21:16:49.193] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:72:0)
[21:16:49.193] Timer tick, asking Buildbucket for the build status
[21:16:49.307] Build 8923510763324649936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:16:49.307] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:73:0) after 1m0s
[21:17:49.346] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:73:0)
[21:17:49.346] Timer tick, asking Buildbucket for the build status
[21:17:49.461] Build 8923510763324649936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:17:49.461] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:74:0) after 1m0s
[21:18:49.491] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:74:0)
[21:18:49.491] Timer tick, asking Buildbucket for the build status
[21:18:49.583] Build 8923510763324649936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:18:49.583] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:75:0) after 1m0s
[21:19:49.610] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:75:0)
[21:19:49.610] Timer tick, asking Buildbucket for the build status
[21:19:49.652] Build 8923510763324649936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:19:49.652] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:76:0) after 1m0s
[21:20:49.681] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:76:0)
[21:20:49.681] Timer tick, asking Buildbucket for the build status
[21:20:49.717] Build 8923510763324649936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:20:49.717] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:77:0) after 1m0s
[21:21:49.739] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:77:0)
[21:21:49.739] Timer tick, asking Buildbucket for the build status
[21:21:50.001] Build 8923510763324649936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:21:50.001] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:78:0) after 1m0s
[21:22:50.089] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:78:0)
[21:22:50.089] Timer tick, asking Buildbucket for the build status
[21:22:50.159] Build 8923510763324649936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:22:50.159] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:79:0) after 1m0s
[21:23:50.182] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:79:0)
[21:23:50.182] Timer tick, asking Buildbucket for the build status
[21:23:50.220] Build 8923510763324649936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:23:50.220] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:80:0) after 1m0s
[21:24:50.260] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:80:0)
[21:24:50.260] Timer tick, asking Buildbucket for the build status
[21:24:50.404] Build 8923510763324649936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:24:50.404] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:81:0) after 1m0s
[21:25:50.429] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:81:0)
[21:25:50.429] Timer tick, asking Buildbucket for the build status
[21:25:50.525] Build 8923510763324649936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:25:50.525] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:82:0) after 1m0s
[21:26:50.554] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:82:0)
[21:26:50.554] Timer tick, asking Buildbucket for the build status
[21:26:50.690] Build 8923510763324649936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:26:50.690] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:83:0) after 1m0s
[21:27:50.752] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:83:0)
[21:27:50.752] Timer tick, asking Buildbucket for the build status
[21:27:50.932] Build 8923510763324649936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:27:50.932] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:84:0) after 1m0s
[21:28:50.959] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:84:0)
[21:28:50.959] Timer tick, asking Buildbucket for the build status
[21:28:51.015] Build 8923510763324649936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:28:51.015] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:85:0) after 1m0s
[21:29:51.036] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:85:0)
[21:29:51.036] Timer tick, asking Buildbucket for the build status
[21:29:51.119] Build 8923510763324649936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:29:51.119] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:86:0) after 1m0s
[21:30:51.148] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:86:0)
[21:30:51.148] Timer tick, asking Buildbucket for the build status
[21:30:51.218] Build 8923510763324649936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:30:51.218] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:87:0) after 1m0s
[21:31:51.279] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:87:0)
[21:31:51.279] Timer tick, asking Buildbucket for the build status
[21:31:51.340] Build 8923510763324649936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:31:51.340] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:88:0) after 1m0s
[21:32:51.361] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:88:0)
[21:32:51.361] Timer tick, asking Buildbucket for the build status
[21:32:51.495] Build 8923510763324649936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:32:51.495] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:89:0) after 1m0s
[21:33:51.584] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:89:0)
[21:33:51.584] Timer tick, asking Buildbucket for the build status
[21:33:51.652] Build 8923510763324649936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:33:51.652] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:90:0) after 1m0s
[21:34:51.727] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:90:0)
[21:34:51.727] Timer tick, asking Buildbucket for the build status
[21:34:51.789] Build 8923510763324649936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:34:51.789] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:91:0) after 1m0s
[21:35:51.818] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:91:0)
[21:35:51.818] Timer tick, asking Buildbucket for the build status
[21:35:51.858] Build 8923510763324649936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:35:51.858] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:92:0) after 1m0s
[21:36:51.887] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:92:0)
[21:36:51.887] Timer tick, asking Buildbucket for the build status
[21:36:52.036] Build 8923510763324649936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:36:52.037] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:93:0) after 1m0s
[21:37:52.118] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:93:0)
[21:37:52.118] Timer tick, asking Buildbucket for the build status
[21:37:52.188] Build 8923510763324649936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:37:52.188] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:94:0) after 1m0s
[21:38:52.209] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:94:0)
[21:38:52.209] Timer tick, asking Buildbucket for the build status
[21:38:52.266] Build 8923510763324649936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:38:52.266] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:95:0) after 1m0s
[21:39:52.304] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:95:0)
[21:39:52.304] Timer tick, asking Buildbucket for the build status
[21:39:52.366] Build 8923510763324649936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:39:52.366] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:96:0) after 1m0s
[21:40:52.453] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:96:0)
[21:40:52.453] Timer tick, asking Buildbucket for the build status
[21:40:52.492] Build 8923510763324649936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:40:52.492] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:97:0) after 1m0s
[21:41:52.517] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:97:0)
[21:41:52.517] Timer tick, asking Buildbucket for the build status
[21:41:52.610] Build 8923510763324649936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:41:52.610] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:98:0) after 1m0s
[21:42:52.641] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:98:0)
[21:42:52.641] Timer tick, asking Buildbucket for the build status
[21:42:52.774] Build 8923510763324649936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:42:52.774] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:99:0) after 1m0s
[21:43:52.811] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:99:0)
[21:43:52.811] Timer tick, asking Buildbucket for the build status
[21:43:52.857] Build 8923510763324649936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:43:52.857] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:100:0) after 1m0s
[21:44:52.880] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:100:0)
[21:44:52.880] Timer tick, asking Buildbucket for the build status
[21:44:52.930] Build 8923510763324649936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:44:52.930] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:101:0) after 1m0s
[21:45:52.964] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:101:0)
[21:45:52.964] Timer tick, asking Buildbucket for the build status
[21:45:53.278] Build 8923510763324649936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:45:53.278] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:102:0) after 1m0s
[21:46:53.301] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:102:0)
[21:46:53.301] Timer tick, asking Buildbucket for the build status
[21:46:53.442] Build 8923510763324649936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:46:53.442] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:103:0) after 1m0s
[21:47:53.464] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:103:0)
[21:47:53.464] Timer tick, asking Buildbucket for the build status
[21:47:53.551] Build 8923510763324649936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:47:53.551] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:104:0) after 1m0s
[21:48:53.576] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:104:0)
[21:48:53.576] Timer tick, asking Buildbucket for the build status
[21:48:53.606] Build 8923510763324649936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:48:53.606] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:105:0) after 1m0s
[21:49:53.635] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:105:0)
[21:49:53.635] Timer tick, asking Buildbucket for the build status
[21:49:53.713] Build 8923510763324649936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:49:53.713] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:106:0) after 1m0s
[21:50:53.749] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:106:0)
[21:50:53.749] Timer tick, asking Buildbucket for the build status
[21:50:53.785] Build 8923510763324649936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:50:53.785] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:107:0) after 1m0s
[21:51:53.835] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:107:0)
[21:51:53.835] Timer tick, asking Buildbucket for the build status
[21:51:53.924] Build 8923510763324649936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:51:53.924] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:108:0) after 1m0s
[21:52:53.989] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:108:0)
[21:52:53.989] Timer tick, asking Buildbucket for the build status
[21:52:54.029] Build 8923510763324649936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:52:54.029] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:109:0) after 1m0s
[21:53:54.067] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:109:0)
[21:53:54.067] Timer tick, asking Buildbucket for the build status
[21:53:54.118] Build 8923510763324649936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:53:54.118] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:110:0) after 1m0s
[21:54:54.146] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:110:0)
[21:54:54.146] Timer tick, asking Buildbucket for the build status
[21:54:54.258] Build 8923510763324649936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:54:54.258] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:111:0) after 1m0s
[21:55:54.290] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:111:0)
[21:55:54.290] Timer tick, asking Buildbucket for the build status
[21:55:54.324] Build 8923510763324649936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:55:54.324] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:112:0) after 1m0s
[21:56:54.346] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:112:0)
[21:56:54.347] Timer tick, asking Buildbucket for the build status
[21:56:54.818] Build 8923510763324649936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:56:54.818] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:113:0) after 1m0s
[21:57:54.843] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:113:0)
[21:57:54.843] Timer tick, asking Buildbucket for the build status
[21:57:54.908] Build 8923510763324649936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:57:54.908] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:114:0) after 1m0s
[21:58:54.942] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:114:0)
[21:58:54.942] Timer tick, asking Buildbucket for the build status
[21:58:55.014] Build 8923510763324649936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:58:55.014] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:115:0) after 1m0s
[21:59:55.086] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:115:0)
[21:59:55.086] Timer tick, asking Buildbucket for the build status
[21:59:55.162] Build 8923510763324649936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:59:55.162] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:116:0) after 1m0s
[22:00:55.206] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:116:0)
[22:00:55.206] Timer tick, asking Buildbucket for the build status
[22:00:55.419] Build 8923510763324649936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:00:55.419] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:117:0) after 1m0s
[22:01:55.463] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:117:0)
[22:01:55.463] Timer tick, asking Buildbucket for the build status
[22:01:55.502] Build 8923510763324649936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:01:55.502] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:118:0) after 1m0s
[22:02:55.581] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:118:0)
[22:02:55.581] Timer tick, asking Buildbucket for the build status
[22:02:55.651] Build 8923510763324649936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:02:55.651] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:119:0) after 1m0s
[22:03:55.684] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:119:0)
[22:03:55.684] Timer tick, asking Buildbucket for the build status
[22:03:55.787] Build 8923510763324649936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:03:55.787] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:120:0) after 1m0s
[22:04:55.820] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:120:0)
[22:04:55.820] Timer tick, asking Buildbucket for the build status
[22:04:55.881] Build 8923510763324649936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:04:55.881] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:121:0) after 1m0s
[22:05:55.910] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:121:0)
[22:05:55.910] Timer tick, asking Buildbucket for the build status
[22:05:55.982] Build 8923510763324649936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:05:55.982] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:122:0) after 1m0s
[22:06:56.033] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:122:0)
[22:06:56.033] Timer tick, asking Buildbucket for the build status
[22:06:56.123] Build 8923510763324649936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:06:56.123] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:123:0) after 1m0s
[22:07:56.153] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:123:0)
[22:07:56.153] Timer tick, asking Buildbucket for the build status
[22:07:56.219] Build 8923510763324649936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:07:56.219] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:124:0) after 1m0s
[22:08:56.265] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:124:0)
[22:08:56.265] Timer tick, asking Buildbucket for the build status
[22:08:56.298] Build 8923510763324649936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:08:56.298] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:125:0) after 1m0s
[22:09:56.324] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:125:0)
[22:09:56.324] Timer tick, asking Buildbucket for the build status
[22:09:57.973] Build 8923510763324649936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:09:57.973] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:126:0) after 1m0s
[22:10:58.010] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:126:0)
[22:10:58.010] Timer tick, asking Buildbucket for the build status
[22:10:58.071] Build 8923510763324649936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:10:58.071] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:127:0) after 1m0s
[22:11:58.117] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:127:0)
[22:11:58.117] Timer tick, asking Buildbucket for the build status
[22:11:58.399] Build 8923510763324649936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:11:58.399] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:128:0) after 1m0s
[22:12:58.430] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:128:0)
[22:12:58.430] Timer tick, asking Buildbucket for the build status
[22:12:58.561] Build 8923510763324649936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:12:58.561] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:129:0) after 1m0s
[22:13:58.602] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:129:0)
[22:13:58.602] Timer tick, asking Buildbucket for the build status
[22:13:58.642] Build 8923510763324649936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:13:58.642] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:130:0) after 1m0s
[22:14:58.687] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:130:0)
[22:14:58.687] Timer tick, asking Buildbucket for the build status
[22:14:58.768] Build 8923510763324649936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:14:58.768] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:131:0) after 1m0s
[22:15:58.799] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:131:0)
[22:15:58.799] Timer tick, asking Buildbucket for the build status
[22:15:58.834] Build 8923510763324649936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:15:58.834] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:132:0) after 1m0s
[22:16:58.862] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:132:0)
[22:16:58.862] Timer tick, asking Buildbucket for the build status
[22:16:58.930] Build 8923510763324649936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:16:58.930] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:133:0) after 1m0s
[22:17:59.033] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:133:0)
[22:17:59.033] Timer tick, asking Buildbucket for the build status
[22:17:59.061] Build 8923510763324649936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:17:59.061] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:134:0) after 1m0s
[22:18:59.111] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:134:0)
[22:18:59.111] Timer tick, asking Buildbucket for the build status
[22:18:59.148] Build 8923510763324649936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:18:59.148] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:135:0) after 1m0s
[22:19:59.224] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:135:0)
[22:19:59.224] Timer tick, asking Buildbucket for the build status
[22:19:59.269] Build 8923510763324649936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:19:59.269] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:136:0) after 1m0s
[22:20:59.367] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:136:0)
[22:20:59.367] Timer tick, asking Buildbucket for the build status
[22:20:59.400] Build 8923510763324649936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:20:59.400] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:137:0) after 1m0s
[22:21:59.508] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:137:0)
[22:21:59.508] Timer tick, asking Buildbucket for the build status
[22:21:59.596] Build 8923510763324649936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:21:59.597] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:138:0) after 1m0s
[22:22:59.627] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:138:0)
[22:22:59.627] Timer tick, asking Buildbucket for the build status
[22:22:59.666] Build 8923510763324649936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:22:59.666] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:139:0) after 1m0s
[22:23:59.689] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:139:0)
[22:23:59.689] Timer tick, asking Buildbucket for the build status
[22:23:59.777] Build 8923510763324649936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:23:59.777] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:140:0) after 1m0s
[22:24:59.814] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:140:0)
[22:24:59.814] Timer tick, asking Buildbucket for the build status
[22:24:59.894] Build 8923510763324649936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:24:59.894] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:141:0) after 1m0s
[22:25:59.967] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:141:0)
[22:25:59.967] Timer tick, asking Buildbucket for the build status
[22:26:00.025] Build 8923510763324649936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:26:00.025] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:142:0) after 1m0s
[22:27:00.054] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:142:0)
[22:27:00.054] Timer tick, asking Buildbucket for the build status
[22:27:00.108] Build 8923510763324649936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:27:00.108] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:143:0) after 1m0s
[22:28:00.150] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:143:0)
[22:28:00.150] Timer tick, asking Buildbucket for the build status
[22:28:00.192] Build 8923510763324649936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:28:00.192] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:144:0) after 1m0s
[22:29:00.231] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:144:0)
[22:29:00.231] Timer tick, asking Buildbucket for the build status
[22:29:00.305] Build 8923510763324649936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:29:00.305] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:145:0) after 1m0s
[22:30:00.333] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:145:0)
[22:30:00.333] Timer tick, asking Buildbucket for the build status
[22:30:00.375] Build 8923510763324649936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:30:00.375] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:146:0) after 1m0s
[22:31:00.400] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:146:0)
[22:31:00.400] Timer tick, asking Buildbucket for the build status
[22:31:00.446] Build 8923510763324649936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:31:00.446] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:147:0) after 1m0s
[22:32:00.474] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:147:0)
[22:32:00.474] Timer tick, asking Buildbucket for the build status
[22:32:00.504] Build 8923510763324649936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:32:00.504] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:148:0) after 1m0s
[22:33:00.538] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:148:0)
[22:33:00.538] Timer tick, asking Buildbucket for the build status
[22:33:00.660] Build 8923510763324649936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:33:00.660] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:149:0) after 1m0s
[22:34:00.683] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:149:0)
[22:34:00.683] Timer tick, asking Buildbucket for the build status
[22:34:00.725] Build 8923510763324649936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:34:00.725] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:150:0) after 1m0s
[22:35:00.809] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:150:0)
[22:35:00.809] Timer tick, asking Buildbucket for the build status
[22:35:00.909] Build 8923510763324649936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:35:00.909] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:151:0) after 1m0s
[22:36:00.947] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:151:0)
[22:36:00.947] Timer tick, asking Buildbucket for the build status
[22:36:00.999] Build 8923510763324649936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:36:00.999] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:152:0) after 1m0s
[22:37:01.058] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:152:0)
[22:37:01.058] Timer tick, asking Buildbucket for the build status
[22:37:01.127] Build 8923510763324649936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:37:01.127] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:153:0) after 1m0s
[22:38:01.157] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:153:0)
[22:38:01.157] Timer tick, asking Buildbucket for the build status
[22:38:01.243] Build 8923510763324649936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:38:01.243] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:154:0) after 1m0s
[22:39:01.277] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:154:0)
[22:39:01.277] Timer tick, asking Buildbucket for the build status
[22:39:01.331] Build 8923510763324649936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:39:01.331] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:155:0) after 1m0s
[22:40:01.360] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:155:0)
[22:40:01.360] Timer tick, asking Buildbucket for the build status
[22:40:01.462] Build 8923510763324649936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:40:01.462] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:156:0) after 1m0s
[22:41:01.484] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:156:0)
[22:41:01.484] Timer tick, asking Buildbucket for the build status
[22:41:01.651] Build 8923510763324649936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:41:01.651] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:157:0) after 1m0s
[22:42:01.711] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:157:0)
[22:42:01.711] Timer tick, asking Buildbucket for the build status
[22:42:01.775] Build 8923510763324649936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:42:01.775] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:158:0) after 1m0s
[22:43:01.818] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:158:0)
[22:43:01.818] Timer tick, asking Buildbucket for the build status
[22:43:01.856] Build 8923510763324649936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:43:01.856] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:159:0) after 1m0s
[22:44:01.908] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:159:0)
[22:44:01.908] Timer tick, asking Buildbucket for the build status
[22:44:01.971] Build 8923510763324649936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:44:01.971] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:160:0) after 1m0s
[22:45:02.027] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:160:0)
[22:45:02.027] Timer tick, asking Buildbucket for the build status
[22:45:02.061] Build 8923510763324649936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:45:02.061] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:161:0) after 1m0s
[22:46:02.167] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:161:0)
[22:46:02.167] Timer tick, asking Buildbucket for the build status
[22:46:02.217] Build 8923510763324649936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:46:02.217] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:162:0) after 1m0s
[22:47:02.246] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:162:0)
[22:47:02.246] Timer tick, asking Buildbucket for the build status
[22:47:02.419] Build 8923510763324649936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:47:02.419] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:163:0) after 1m0s
[22:48:02.448] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:163:0)
[22:48:02.448] Timer tick, asking Buildbucket for the build status
[22:48:02.493] Build 8923510763324649936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:48:02.493] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:164:0) after 1m0s
[22:49:02.527] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:164:0)
[22:49:02.527] Timer tick, asking Buildbucket for the build status
[22:49:02.565] Build 8923510763324649936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:49:02.565] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:165:0) after 1m0s
[22:50:02.669] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:165:0)
[22:50:02.669] Timer tick, asking Buildbucket for the build status
[22:50:02.761] Build 8923510763324649936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:50:02.761] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:166:0) after 1m0s
[22:51:02.790] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:166:0)
[22:51:02.790] Timer tick, asking Buildbucket for the build status
[22:51:02.825] Build 8923510763324649936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:51:02.825] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:167:0) after 1m0s
[22:52:02.855] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:167:0)
[22:52:02.856] Timer tick, asking Buildbucket for the build status
[22:52:02.888] Build 8923510763324649936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:52:02.888] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:168:0) after 1m0s
[22:53:02.915] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:168:0)
[22:53:02.915] Timer tick, asking Buildbucket for the build status
[22:53:02.980] Build 8923510763324649936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:53:02.980] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:169:0) after 1m0s
[22:54:03.085] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:169:0)
[22:54:03.085] Timer tick, asking Buildbucket for the build status
[22:54:03.179] Build 8923510763324649936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:54:03.179] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:170:0) after 1m0s
[22:55:03.416] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:170:0)
[22:55:03.416] Timer tick, asking Buildbucket for the build status
[22:55:03.582] Build 8923510763324649936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:55:03.582] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:171:0) after 1m0s
[22:56:03.703] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:171:0)
[22:56:03.703] Timer tick, asking Buildbucket for the build status
[22:56:03.761] Build 8923510763324649936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:56:03.761] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:172:0) after 1m0s
[22:57:03.830] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:172:0)
[22:57:03.830] Timer tick, asking Buildbucket for the build status
[22:57:03.921] Build 8923510763324649936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:57:03.921] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:173:0) after 1m0s
[22:58:03.999] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:173:0)
[22:58:03.999] Timer tick, asking Buildbucket for the build status
[22:58:04.086] Build 8923510763324649936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:58:04.086] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:174:0) after 1m0s
[22:59:04.129] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:174:0)
[22:59:04.129] Timer tick, asking Buildbucket for the build status
[22:59:04.598] Build 8923510763324649936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:59:04.598] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:175:0) after 1m0s
[23:00:04.660] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:175:0)
[23:00:04.660] Timer tick, asking Buildbucket for the build status
[23:00:04.717] Build 8923510763324649936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:00:04.717] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:176:0) after 1m0s
[23:01:04.788] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:176:0)
[23:01:04.788] Timer tick, asking Buildbucket for the build status
[23:01:04.873] Build 8923510763324649936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:01:04.873] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:177:0) after 1m0s
[23:02:04.948] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:177:0)
[23:02:04.948] Timer tick, asking Buildbucket for the build status
[23:02:04.993] Build 8923510763324649936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:02:04.993] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:178:0) after 1m0s
[23:03:05.027] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:178:0)
[23:03:05.027] Timer tick, asking Buildbucket for the build status
[23:03:05.133] Build 8923510763324649936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:03:05.133] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:179:0) after 1m0s
[23:04:05.159] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:179:0)
[23:04:05.159] Timer tick, asking Buildbucket for the build status
[23:04:05.206] Build 8923510763324649936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:04:05.206] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:180:0) after 1m0s
[23:05:05.240] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:180:0)
[23:05:05.240] Timer tick, asking Buildbucket for the build status
[23:05:05.363] Build 8923510763324649936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:05:05.363] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:181:0) after 1m0s
[23:06:05.551] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:181:0)
[23:06:05.551] Timer tick, asking Buildbucket for the build status
[23:06:05.584] Build 8923510763324649936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:06:05.584] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:182:0) after 1m0s
[23:07:05.614] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:182:0)
[23:07:05.614] Timer tick, asking Buildbucket for the build status
[23:07:05.675] Build 8923510763324649936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:07:05.675] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:183:0) after 1m0s
[23:08:05.708] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:183:0)
[23:08:05.708] Timer tick, asking Buildbucket for the build status
[23:08:05.770] Build 8923510763324649936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:08:05.770] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088940825549346576:184:0) after 1m0s
[23:08:42.352] Received PubSub notification, asking Buildbucket for the build status
[23:08:42.460] Build 8923510763324649936: status "COMPLETED", result "FAILURE", failure_reason "INFRA_FAILURE", cancelation_reason ""
[23:08:42.460] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"completed_ts": "1548284921540311",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1548273995050925",
"failure_reason": "INFRA_FAILURE",
"id": "8923510763324649936",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"48d8a282b6ff0e545e638fb19098213200ea55f7\"}}",
"project": "chromium",
"result": "FAILURE",
"result_details_json": "{\"swarming\": {\"bot_dimensions\": {\"gce\": [\"1\"], \"machine_type\": [\"n1-standard-8\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"], \"locale\": [\"en_US.cp1252\"], \"os\": [\"Windows\", \"Windows-10\", \"Windows-10-15063\"], \"builder\": [\"Windows deterministic\"], \"id\": [\"swarm2173-c4\"], \"python\": [\"2.7.6\"], \"server_version\": [\"4022-a2312a7\"], \"image\": [\"chrome-win10-1703-2018-04-20-61acaf8983f\"], \"gpu\": [\"none\"], \"cores\": [\"8\"], \"integrity\": [\"high\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Haswell_GCE\"], \"pool\": [\"luci.chromium.ci\"], \"caches\": [\"builder_e8fffaf9fac16164b1ec16ebaa1f377b792d39a582f7640830518d8ff7303d0c_v2\", \"git\", \"goma_v2\", \"vpython\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1548273999896981",
"status": "COMPLETED",
"status_changed_ts": "1548284921915867",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/11714",
"builder:Windows deterministic",
"buildset:commit/git/48d8a282b6ff0e545e638fb19098213200ea55f7",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/48d8a282b6ff0e545e638fb19098213200ea55f7",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9088940825549346576",
"scheduler_job_id:chromium/Windows deterministic",
"swarming_dimension:builder:Windows deterministic",
"swarming_dimension:caches:builder_e8fffaf9fac16164b1ec16ebaa1f377b792d39a582f7640830518d8ff7303d0c_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/11714",
"swarming_tag:buildbucket_bucket:chromium/ci",
"swarming_tag:buildbucket_build_id:8923510763324649936",
"swarming_tag:buildbucket_hostname:cr-buildbucket.appspot.com",
"swarming_tag:buildbucket_template_canary:0",
"swarming_tag:buildbucket_template_revision:01134cf47e8a2d5d7cea5ec1232ca64ed2f16a5c",
"swarming_tag:builder:Windows deterministic",
"swarming_tag:buildset:commit/git/48d8a282b6ff0e545e638fb19098213200ea55f7",
"swarming_tag:buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/48d8a282b6ff0e545e638fb19098213200ea55f7",
"swarming_tag:caches:builder_e8fffaf9fac16164b1ec16ebaa1f377b792d39a582f7640830518d8ff7303d0c_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/8923510763324649936/+/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:9088940825549346576",
"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:ccfa8acb724fee8943da2165ede757ca12003338",
"swarming_tag:user:None",
"swarming_tag:user_agent:luci-scheduler",
"swarming_tag:vpython:native-python-wrapper",
"swarming_task_id:429523e36daf5010",
"user_agent:luci-scheduler"
],
"updated_ts": "1548284921916978",
"url": "https://ci.chromium.org/b/8923510763324649936",
"utcnow_ts": "1548284922450744"
}
}
[23:08:42.460] Invocation finished in 3h2m8.914735538s with status FAILED