[15:45:22.853] New invocation is queued and will start shortly
[15:45:37.378] Starting the invocation (attempt 1)
[15:45:37.410] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[15:45:37.410] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[15:45:37.411] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9108979187050769952",
"parameters_json": "{\"builder_name\":\"Windows deterministic\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src.git\",\"revision\":\"2394d0acf8bf0b0236b2646e4c823a57de7bd941\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Windows deterministic",
"scheduler_invocation_id:9108979187050769952",
"scheduler_job_id:chromium/Windows deterministic",
"user_agent:luci-scheduler",
"buildset:commit/git/2394d0acf8bf0b0236b2646e4c823a57de7bd941",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/2394d0acf8bf0b0236b2646e4c823a57de7bd941",
"gitiles_ref:refs/heads/master"
]
}
[15:45:41.404] Failed to add buildbucket build - googleapi: got HTTP response code 500 with body: 500 Internal Server Error
The server has either erred or is incapable of performing the requested operation.
[15:45:41.405] The invocation will be retried
[15:45:47.192] Starting the invocation (attempt 2)
[15:45:47.252] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[15:45:47.253] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[15:45:47.253] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9108979187050769952",
"parameters_json": "{\"builder_name\":\"Windows deterministic\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src.git\",\"revision\":\"2394d0acf8bf0b0236b2646e4c823a57de7bd941\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Windows deterministic",
"scheduler_invocation_id:9108979187050769952",
"scheduler_job_id:chromium/Windows deterministic",
"user_agent:luci-scheduler",
"buildset:commit/git/2394d0acf8bf0b0236b2646e4c823a57de7bd941",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/2394d0acf8bf0b0236b2646e4c823a57de7bd941",
"gitiles_ref:refs/heads/master"
]
}
[15:45:47.253] The invocation will be retried
[15:45:49.812] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary": true,
"canary_preference": "AUTO",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1529163947342336",
"experimental": true,
"id": "8943549100710610320",
"lease_expiration_ts": "1529199947855043",
"lease_key": "222933767",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"2394d0acf8bf0b0236b2646e4c823a57de7bd941\"}}",
"project": "chromium",
"result_details_json": "null",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1529163949650094",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/47",
"builder:Windows deterministic",
"buildset:commit/git/2394d0acf8bf0b0236b2646e4c823a57de7bd941",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/2394d0acf8bf0b0236b2646e4c823a57de7bd941",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9108979187050769952",
"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/47",
"swarming_tag:buildbucket_bucket:luci.chromium.ci",
"swarming_tag:buildbucket_build_id:8943549100710610320",
"swarming_tag:buildbucket_hostname:cr-buildbucket.appspot.com",
"swarming_tag:buildbucket_template_canary:1",
"swarming_tag:buildbucket_template_revision:2a8c11ce5d733cead8b6ce80c31bfed950c5e864",
"swarming_tag:builder:Windows deterministic",
"swarming_tag:buildset:commit/git/2394d0acf8bf0b0236b2646e4c823a57de7bd941",
"swarming_tag:buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/2394d0acf8bf0b0236b2646e4c823a57de7bd941",
"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/8943549100710610320/+/annotations",
"swarming_tag:luci_project:chromium",
"swarming_tag:os:Windows-10",
"swarming_tag:pool:luci.chromium.ci",
"swarming_tag:priority:60",
"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:9108979187050769952",
"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:7b1b5d239a2e04e83d3627884ca58c970e37b2b0",
"swarming_tag:user:None",
"swarming_tag:user_agent:luci-scheduler",
"swarming_tag:vpython:native-python-wrapper",
"swarming_task_id:3e2217a6b1d30010",
"user_agent:luci-scheduler"
],
"updated_ts": "1529163949650171",
"url": "https://ci.chromium.org/p/chromium/builders/luci.chromium.ci/Windows deterministic/47",
"utcnow_ts": "1529163949794596"
}
}
[15:45:49.812] Task URL: https://ci.chromium.org/p/chromium/builders/luci.chromium.ci/Windows deterministic/47
[15:45:49.812] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:5:0) after 1m0s
[15:46:49.834] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:5:0)
[15:46:49.834] Timer tick, asking Buildbucket for the build status
[15:46:49.893] Build 8943549100710610320: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:46:49.893] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:6:0) after 1m0s
[15:46:56.604] Received PubSub notification, asking Buildbucket for the build status
[15:46:56.637] Build 8943549100710610320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:47:49.967] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:6:0)
[15:47:49.967] Timer tick, asking Buildbucket for the build status
[15:47:50.023] Build 8943549100710610320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:47:50.023] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:8:0) after 1m0s
[15:48:50.045] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:8:0)
[15:48:50.045] Timer tick, asking Buildbucket for the build status
[15:48:50.109] Build 8943549100710610320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:48:50.109] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:9:0) after 1m0s
[15:49:50.133] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:9:0)
[15:49:50.133] Timer tick, asking Buildbucket for the build status
[15:49:50.240] Build 8943549100710610320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:49:50.240] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:10:0) after 1m0s
[15:50:50.258] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:10:0)
[15:50:50.258] Timer tick, asking Buildbucket for the build status
[15:50:50.374] Build 8943549100710610320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:50:50.374] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:11:0) after 1m0s
[15:51:50.393] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:11:0)
[15:51:50.393] Timer tick, asking Buildbucket for the build status
[15:51:50.448] Build 8943549100710610320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:51:50.448] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:12:0) after 1m0s
[15:52:50.465] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:12:0)
[15:52:50.465] Timer tick, asking Buildbucket for the build status
[15:52:50.528] Build 8943549100710610320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:52:50.528] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:13:0) after 1m0s
[15:53:50.579] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:13:0)
[15:53:50.579] Timer tick, asking Buildbucket for the build status
[15:53:50.706] Build 8943549100710610320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:53:50.706] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:14:0) after 1m0s
[15:54:50.733] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:14:0)
[15:54:50.733] Timer tick, asking Buildbucket for the build status
[15:54:50.788] Build 8943549100710610320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:54:50.788] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:15:0) after 1m0s
[15:55:50.875] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:15:0)
[15:55:50.875] Timer tick, asking Buildbucket for the build status
[15:55:50.930] Build 8943549100710610320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:55:50.930] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:16:0) after 1m0s
[15:56:50.966] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:16:0)
[15:56:50.966] Timer tick, asking Buildbucket for the build status
[15:56:51.094] Build 8943549100710610320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:56:51.094] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:17:0) after 1m0s
[15:57:51.121] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:17:0)
[15:57:51.121] Timer tick, asking Buildbucket for the build status
[15:57:51.229] Build 8943549100710610320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:57:51.229] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:18:0) after 1m0s
[15:58:51.264] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:18:0)
[15:58:51.264] Timer tick, asking Buildbucket for the build status
[15:58:51.330] Build 8943549100710610320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:58:51.330] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:19:0) after 1m0s
[15:59:51.386] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:19:0)
[15:59:51.386] Timer tick, asking Buildbucket for the build status
[15:59:51.722] Build 8943549100710610320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:59:51.722] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:20:0) after 1m0s
[16:00:51.737] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:20:0)
[16:00:51.737] Timer tick, asking Buildbucket for the build status
[16:00:51.810] Build 8943549100710610320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:00:51.810] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:21:0) after 1m0s
[16:01:51.828] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:21:0)
[16:01:51.828] Timer tick, asking Buildbucket for the build status
[16:01:51.886] Build 8943549100710610320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:01:51.886] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:22:0) after 1m0s
[16:02:51.984] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:22:0)
[16:02:51.984] Timer tick, asking Buildbucket for the build status
[16:02:52.041] Build 8943549100710610320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:02:52.041] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:23:0) after 1m0s
[16:03:52.057] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:23:0)
[16:03:52.057] Timer tick, asking Buildbucket for the build status
[16:03:52.181] Build 8943549100710610320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:03:52.181] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:24:0) after 1m0s
[16:04:52.198] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:24:0)
[16:04:52.198] Timer tick, asking Buildbucket for the build status
[16:04:52.275] Build 8943549100710610320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:04:52.275] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:25:0) after 1m0s
[16:05:52.302] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:25:0)
[16:05:52.302] Timer tick, asking Buildbucket for the build status
[16:05:52.392] Build 8943549100710610320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:05:52.392] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:26:0) after 1m0s
[16:06:52.411] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:26:0)
[16:06:52.411] Timer tick, asking Buildbucket for the build status
[16:06:52.552] Build 8943549100710610320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:06:52.552] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:27:0) after 1m0s
[16:07:52.585] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:27:0)
[16:07:52.585] Timer tick, asking Buildbucket for the build status
[16:07:52.639] Build 8943549100710610320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:07:52.639] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:28:0) after 1m0s
[16:08:52.710] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:28:0)
[16:08:52.710] Timer tick, asking Buildbucket for the build status
[16:08:52.765] Build 8943549100710610320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:08:52.765] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:29:0) after 1m0s
[16:09:52.892] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:29:0)
[16:09:52.892] Timer tick, asking Buildbucket for the build status
[16:09:52.940] Build 8943549100710610320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:09:52.940] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:30:0) after 1m0s
[16:10:52.979] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:30:0)
[16:10:52.979] Timer tick, asking Buildbucket for the build status
[16:10:53.036] Build 8943549100710610320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:10:53.036] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:31:0) after 1m0s
[16:11:53.112] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:31:0)
[16:11:53.112] Timer tick, asking Buildbucket for the build status
[16:11:53.174] Build 8943549100710610320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:11:53.174] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:32:0) after 1m0s
[16:12:53.295] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:32:0)
[16:12:53.295] Timer tick, asking Buildbucket for the build status
[16:12:53.529] Build 8943549100710610320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:12:53.529] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:33:0) after 1m0s
[16:13:53.549] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:33:0)
[16:13:53.549] Timer tick, asking Buildbucket for the build status
[16:13:53.669] Build 8943549100710610320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:13:53.669] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:34:0) after 1m0s
[16:14:53.685] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:34:0)
[16:14:53.685] Timer tick, asking Buildbucket for the build status
[16:14:53.740] Build 8943549100710610320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:14:53.740] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:35:0) after 1m0s
[16:15:53.872] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:35:0)
[16:15:53.872] Timer tick, asking Buildbucket for the build status
[16:15:53.927] Build 8943549100710610320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:15:53.927] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:36:0) after 1m0s
[16:16:54.047] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:36:0)
[16:16:54.047] Timer tick, asking Buildbucket for the build status
[16:16:54.107] Build 8943549100710610320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:16:54.107] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:37:0) after 1m0s
[16:17:54.268] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:37:0)
[16:17:54.268] Timer tick, asking Buildbucket for the build status
[16:17:54.453] Build 8943549100710610320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:17:54.453] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:38:0) after 1m0s
[16:18:54.472] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:38:0)
[16:18:54.472] Timer tick, asking Buildbucket for the build status
[16:18:54.529] Build 8943549100710610320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:18:54.529] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:39:0) after 1m0s
[16:19:54.695] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:39:0)
[16:19:54.695] Timer tick, asking Buildbucket for the build status
[16:19:54.753] Build 8943549100710610320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:19:54.753] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:40:0) after 1m0s
[16:20:54.772] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:40:0)
[16:20:54.772] Timer tick, asking Buildbucket for the build status
[16:20:54.838] Build 8943549100710610320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:20:54.838] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:41:0) after 1m0s
[16:21:54.903] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:41:0)
[16:21:54.903] Timer tick, asking Buildbucket for the build status
[16:21:54.963] Build 8943549100710610320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:21:54.963] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:42:0) after 1m0s
[16:22:55.099] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:42:0)
[16:22:55.099] Timer tick, asking Buildbucket for the build status
[16:22:55.189] Build 8943549100710610320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:22:55.189] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:43:0) after 1m0s
[16:23:55.283] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:43:0)
[16:23:55.283] Timer tick, asking Buildbucket for the build status
[16:23:55.335] Build 8943549100710610320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:23:55.335] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:44:0) after 1m0s
[16:24:55.468] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:44:0)
[16:24:55.468] Timer tick, asking Buildbucket for the build status
[16:24:55.563] Build 8943549100710610320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:24:55.563] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:45:0) after 1m0s
[16:25:55.637] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:45:0)
[16:25:55.637] Timer tick, asking Buildbucket for the build status
[16:25:55.875] Build 8943549100710610320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:25:55.875] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:46:0) after 1m0s
[16:26:56.039] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:46:0)
[16:26:56.039] Timer tick, asking Buildbucket for the build status
[16:26:56.098] Build 8943549100710610320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:26:56.098] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:47:0) after 1m0s
[16:27:56.151] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:47:0)
[16:27:56.151] Timer tick, asking Buildbucket for the build status
[16:27:56.229] Build 8943549100710610320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:27:56.229] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:48:0) after 1m0s
[16:28:56.349] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:48:0)
[16:28:56.349] Timer tick, asking Buildbucket for the build status
[16:28:56.457] Build 8943549100710610320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:28:56.457] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:49:0) after 1m0s
[16:29:56.494] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:49:0)
[16:29:56.494] Timer tick, asking Buildbucket for the build status
[16:29:56.578] Build 8943549100710610320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:29:56.578] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:50:0) after 1m0s
[16:30:56.672] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:50:0)
[16:30:56.672] Timer tick, asking Buildbucket for the build status
[16:30:56.779] Build 8943549100710610320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:30:56.779] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:51:0) after 1m0s
[16:31:56.855] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:51:0)
[16:31:56.855] Timer tick, asking Buildbucket for the build status
[16:31:56.987] Build 8943549100710610320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:31:56.987] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:52:0) after 1m0s
[16:32:57.070] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:52:0)
[16:32:57.070] Timer tick, asking Buildbucket for the build status
[16:32:57.194] Build 8943549100710610320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:32:57.194] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:53:0) after 1m0s
[16:33:57.256] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:53:0)
[16:33:57.256] Timer tick, asking Buildbucket for the build status
[16:33:57.305] Build 8943549100710610320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:33:57.305] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:54:0) after 1m0s
[16:34:57.441] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:54:0)
[16:34:57.441] Timer tick, asking Buildbucket for the build status
[16:34:57.493] Build 8943549100710610320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:34:57.493] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:55:0) after 1m0s
[16:35:57.581] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:55:0)
[16:35:57.581] Timer tick, asking Buildbucket for the build status
[16:35:57.666] Build 8943549100710610320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:35:57.666] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:56:0) after 1m0s
[16:36:57.776] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:56:0)
[16:36:57.776] Timer tick, asking Buildbucket for the build status
[16:36:57.836] Build 8943549100710610320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:36:57.836] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:57:0) after 1m0s
[16:37:57.959] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:57:0)
[16:37:57.959] Timer tick, asking Buildbucket for the build status
[16:37:58.015] Build 8943549100710610320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:37:58.015] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:58:0) after 1m0s
[16:38:58.099] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:58:0)
[16:38:58.099] Timer tick, asking Buildbucket for the build status
[16:38:58.167] Build 8943549100710610320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:38:58.167] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:59:0) after 1m0s
[16:39:58.248] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:59:0)
[16:39:58.248] Timer tick, asking Buildbucket for the build status
[16:39:58.309] Build 8943549100710610320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:39:58.309] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:60:0) after 1m0s
[16:40:58.444] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:60:0)
[16:40:58.444] Timer tick, asking Buildbucket for the build status
[16:40:58.567] Build 8943549100710610320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:40:58.567] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:61:0) after 1m0s
[16:41:58.608] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:61:0)
[16:41:58.608] Timer tick, asking Buildbucket for the build status
[16:41:58.673] Build 8943549100710610320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:41:58.673] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:62:0) after 1m0s
[16:42:58.800] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:62:0)
[16:42:58.800] Timer tick, asking Buildbucket for the build status
[16:42:58.864] Build 8943549100710610320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:42:58.864] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:63:0) after 1m0s
[16:43:58.978] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:63:0)
[16:43:58.978] Timer tick, asking Buildbucket for the build status
[16:43:59.098] Build 8943549100710610320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:43:59.098] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:64:0) after 1m0s
[16:44:59.198] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:64:0)
[16:44:59.198] Timer tick, asking Buildbucket for the build status
[16:44:59.281] Build 8943549100710610320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:44:59.281] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:65:0) after 1m0s
[16:45:59.342] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:65:0)
[16:45:59.342] Timer tick, asking Buildbucket for the build status
[16:45:59.410] Build 8943549100710610320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:45:59.411] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:66:0) after 1m0s
[16:46:59.539] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:66:0)
[16:46:59.539] Timer tick, asking Buildbucket for the build status
[16:46:59.599] Build 8943549100710610320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:46:59.599] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:67:0) after 1m0s
[16:47:59.961] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:67:0)
[16:47:59.961] Timer tick, asking Buildbucket for the build status
[16:48:00.258] Build 8943549100710610320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:48:00.258] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:68:0) after 1m0s
[16:49:00.280] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:68:0)
[16:49:00.280] Timer tick, asking Buildbucket for the build status
[16:49:00.334] Build 8943549100710610320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:49:00.334] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:69:0) after 1m0s
[16:50:00.351] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:69:0)
[16:50:00.351] Timer tick, asking Buildbucket for the build status
[16:50:00.440] Build 8943549100710610320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:50:00.440] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:70:0) after 1m0s
[16:51:00.467] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:70:0)
[16:51:00.467] Timer tick, asking Buildbucket for the build status
[16:51:00.528] Build 8943549100710610320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:51:00.528] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:71:0) after 1m0s
[16:52:00.609] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:71:0)
[16:52:00.609] Timer tick, asking Buildbucket for the build status
[16:52:00.807] Build 8943549100710610320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:52:00.807] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:72:0) after 1m0s
[16:53:00.828] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:72:0)
[16:53:00.828] Timer tick, asking Buildbucket for the build status
[16:53:01.012] Build 8943549100710610320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:53:01.012] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:73:0) after 1m0s
[16:54:01.030] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:73:0)
[16:54:01.030] Timer tick, asking Buildbucket for the build status
[16:54:01.131] Build 8943549100710610320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:54:01.131] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:74:0) after 1m0s
[16:55:01.148] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:74:0)
[16:55:01.148] Timer tick, asking Buildbucket for the build status
[16:55:01.346] Build 8943549100710610320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:55:01.347] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:75:0) after 1m0s
[16:56:01.365] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:75:0)
[16:56:01.365] Timer tick, asking Buildbucket for the build status
[16:56:01.507] Build 8943549100710610320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:56:01.507] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:76:0) after 1m0s
[16:57:01.528] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:76:0)
[16:57:01.528] Timer tick, asking Buildbucket for the build status
[16:57:02.496] Build 8943549100710610320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:57:02.496] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:77:0) after 1m0s
[16:58:02.515] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:77:0)
[16:58:02.515] Timer tick, asking Buildbucket for the build status
[16:58:02.838] Build 8943549100710610320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:58:02.838] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:78:0) after 1m0s
[16:59:02.868] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:78:0)
[16:59:02.868] Timer tick, asking Buildbucket for the build status
[16:59:03.150] Build 8943549100710610320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:59:03.150] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:79:0) after 1m0s
[17:00:03.171] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:79:0)
[17:00:03.171] Timer tick, asking Buildbucket for the build status
[17:00:03.303] Build 8943549100710610320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:00:03.303] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:80:0) after 1m0s
[17:01:03.373] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:80:0)
[17:01:03.373] Timer tick, asking Buildbucket for the build status
[17:01:03.553] Build 8943549100710610320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:01:03.553] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:81:0) after 1m0s
[17:02:03.584] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:81:0)
[17:02:03.584] Timer tick, asking Buildbucket for the build status
[17:02:05.429] Build 8943549100710610320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:02:05.429] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:82:0) after 1m0s
[17:03:05.473] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:82:0)
[17:03:05.473] Timer tick, asking Buildbucket for the build status
[17:03:05.553] Build 8943549100710610320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:03:05.553] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:83:0) after 1m0s
[17:04:05.691] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:83:0)
[17:04:05.691] Timer tick, asking Buildbucket for the build status
[17:04:05.763] Build 8943549100710610320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:04:05.763] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:84:0) after 1m0s
[17:05:05.884] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:84:0)
[17:05:05.884] Timer tick, asking Buildbucket for the build status
[17:05:05.946] Build 8943549100710610320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:05:05.946] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:85:0) after 1m0s
[17:06:06.090] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:85:0)
[17:06:06.090] Timer tick, asking Buildbucket for the build status
[17:06:06.198] Build 8943549100710610320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:06:06.198] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:86:0) after 1m0s
[17:07:06.331] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:86:0)
[17:07:06.331] Timer tick, asking Buildbucket for the build status
[17:07:06.400] Build 8943549100710610320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:07:06.400] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:87:0) after 1m0s
[17:08:06.492] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:87:0)
[17:08:06.492] Timer tick, asking Buildbucket for the build status
[17:08:06.525] Build 8943549100710610320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:08:06.525] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:88:0) after 1m0s
[17:09:06.564] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:88:0)
[17:09:06.564] Timer tick, asking Buildbucket for the build status
[17:09:06.654] Build 8943549100710610320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:09:06.655] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:89:0) after 1m0s
[17:10:06.676] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:89:0)
[17:10:06.676] Timer tick, asking Buildbucket for the build status
[17:10:06.761] Build 8943549100710610320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:10:06.761] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:90:0) after 1m0s
[17:11:06.789] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:90:0)
[17:11:06.789] Timer tick, asking Buildbucket for the build status
[17:11:06.836] Build 8943549100710610320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:11:06.836] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:91:0) after 1m0s
[17:12:06.863] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:91:0)
[17:12:06.863] Timer tick, asking Buildbucket for the build status
[17:12:06.922] Build 8943549100710610320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:12:06.922] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:92:0) after 1m0s
[17:13:06.944] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:92:0)
[17:13:06.944] Timer tick, asking Buildbucket for the build status
[17:13:06.994] Build 8943549100710610320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:13:06.994] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:93:0) after 1m0s
[17:14:07.021] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:93:0)
[17:14:07.021] Timer tick, asking Buildbucket for the build status
[17:14:07.074] Build 8943549100710610320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:14:07.074] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:94:0) after 1m0s
[17:15:07.113] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:94:0)
[17:15:07.113] Timer tick, asking Buildbucket for the build status
[17:15:07.321] Build 8943549100710610320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:15:07.321] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:95:0) after 1m0s
[17:16:07.357] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:95:0)
[17:16:07.357] Timer tick, asking Buildbucket for the build status
[17:16:07.415] Build 8943549100710610320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:16:07.415] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:96:0) after 1m0s
[17:17:07.544] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:96:0)
[17:17:07.544] Timer tick, asking Buildbucket for the build status
[17:17:07.667] Build 8943549100710610320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:17:07.667] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:97:0) after 1m0s
[17:18:07.763] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:97:0)
[17:18:07.763] Timer tick, asking Buildbucket for the build status
[17:18:07.846] Build 8943549100710610320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:18:07.846] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:98:0) after 1m0s
[17:19:07.865] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:98:0)
[17:19:07.865] Timer tick, asking Buildbucket for the build status
[17:19:08.184] Build 8943549100710610320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:19:08.184] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:99:0) after 1m0s
[17:20:08.202] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:99:0)
[17:20:08.202] Timer tick, asking Buildbucket for the build status
[17:20:08.270] Build 8943549100710610320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:20:08.270] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:100:0) after 1m0s
[17:21:08.303] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:100:0)
[17:21:08.303] Timer tick, asking Buildbucket for the build status
[17:21:08.452] Build 8943549100710610320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:21:08.452] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:101:0) after 1m0s
[17:22:08.474] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:101:0)
[17:22:08.474] Timer tick, asking Buildbucket for the build status
[17:22:08.524] Build 8943549100710610320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:22:08.524] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:102:0) after 1m0s
[17:23:08.552] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:102:0)
[17:23:08.552] Timer tick, asking Buildbucket for the build status
[17:23:08.654] Build 8943549100710610320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:23:08.654] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:103:0) after 1m0s
[17:24:08.672] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:103:0)
[17:24:08.672] Timer tick, asking Buildbucket for the build status
[17:24:08.726] Build 8943549100710610320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:24:08.726] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:104:0) after 1m0s
[17:25:08.743] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:104:0)
[17:25:08.743] Timer tick, asking Buildbucket for the build status
[17:25:08.864] Build 8943549100710610320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:25:08.864] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:105:0) after 1m0s
[17:26:08.894] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:105:0)
[17:26:08.894] Timer tick, asking Buildbucket for the build status
[17:26:08.999] Build 8943549100710610320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:26:08.999] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:106:0) after 1m0s
[17:27:09.043] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:106:0)
[17:27:09.043] Timer tick, asking Buildbucket for the build status
[17:27:09.132] Build 8943549100710610320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:27:09.132] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:107:0) after 1m0s
[17:28:09.163] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:107:0)
[17:28:09.163] Timer tick, asking Buildbucket for the build status
[17:28:09.216] Build 8943549100710610320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:28:09.216] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:108:0) after 1m0s
[17:29:09.306] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:108:0)
[17:29:09.306] Timer tick, asking Buildbucket for the build status
[17:29:09.340] Build 8943549100710610320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:29:09.340] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:109:0) after 1m0s
[17:30:09.360] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:109:0)
[17:30:09.360] Timer tick, asking Buildbucket for the build status
[17:30:09.418] Build 8943549100710610320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:30:09.418] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:110:0) after 1m0s
[17:31:09.438] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:110:0)
[17:31:09.438] Timer tick, asking Buildbucket for the build status
[17:31:09.481] Build 8943549100710610320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:31:09.481] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:111:0) after 1m0s
[17:32:09.512] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:111:0)
[17:32:09.512] Timer tick, asking Buildbucket for the build status
[17:32:09.734] Build 8943549100710610320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:32:09.734] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:112:0) after 1m0s
[17:33:09.755] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:112:0)
[17:33:09.755] Timer tick, asking Buildbucket for the build status
[17:33:09.860] Build 8943549100710610320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:33:09.860] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:113:0) after 1m0s
[17:34:09.881] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:113:0)
[17:34:09.881] Timer tick, asking Buildbucket for the build status
[17:34:10.022] Build 8943549100710610320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:34:10.022] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:114:0) after 1m0s
[17:35:10.042] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:114:0)
[17:35:10.042] Timer tick, asking Buildbucket for the build status
[17:35:10.235] Build 8943549100710610320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:35:10.235] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:115:0) after 1m0s
[17:36:10.307] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:115:0)
[17:36:10.307] Timer tick, asking Buildbucket for the build status
[17:36:10.354] Build 8943549100710610320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:36:10.354] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:116:0) after 1m0s
[17:37:10.464] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:116:0)
[17:37:10.464] Timer tick, asking Buildbucket for the build status
[17:37:10.603] Build 8943549100710610320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:37:10.603] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:117:0) after 1m0s
[17:38:10.625] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:117:0)
[17:38:10.625] Timer tick, asking Buildbucket for the build status
[17:38:10.657] Build 8943549100710610320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:38:10.657] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:118:0) after 1m0s
[17:39:10.694] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:118:0)
[17:39:10.694] Timer tick, asking Buildbucket for the build status
[17:39:10.737] Build 8943549100710610320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:39:10.737] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:119:0) after 1m0s
[17:40:10.849] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:119:0)
[17:40:10.849] Timer tick, asking Buildbucket for the build status
[17:40:10.978] Build 8943549100710610320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:40:10.978] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:120:0) after 1m0s
[17:41:11.045] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:120:0)
[17:41:11.045] Timer tick, asking Buildbucket for the build status
[17:41:11.107] Build 8943549100710610320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:41:11.107] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:121:0) after 1m0s
[17:42:11.148] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:121:0)
[17:42:11.148] Timer tick, asking Buildbucket for the build status
[17:42:11.193] Build 8943549100710610320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:42:11.193] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:122:0) after 1m0s
[17:43:11.314] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:122:0)
[17:43:11.314] Timer tick, asking Buildbucket for the build status
[17:43:11.376] Build 8943549100710610320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:43:11.376] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:123:0) after 1m0s
[17:44:11.508] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:123:0)
[17:44:11.508] Timer tick, asking Buildbucket for the build status
[17:44:11.887] Build 8943549100710610320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:44:11.887] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:124:0) after 1m0s
[17:45:11.917] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:124:0)
[17:45:11.917] Timer tick, asking Buildbucket for the build status
[17:45:11.972] Build 8943549100710610320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:45:11.972] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:125:0) after 1m0s
[17:46:11.992] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:125:0)
[17:46:11.992] Timer tick, asking Buildbucket for the build status
[17:46:12.244] Build 8943549100710610320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:46:12.244] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:126:0) after 1m0s
[17:47:12.277] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:126:0)
[17:47:12.277] Timer tick, asking Buildbucket for the build status
[17:47:12.354] Build 8943549100710610320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:47:12.354] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:127:0) after 1m0s
[17:48:12.377] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:127:0)
[17:48:12.377] Timer tick, asking Buildbucket for the build status
[17:48:12.548] Build 8943549100710610320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:48:12.548] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:128:0) after 1m0s
[17:49:12.568] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:128:0)
[17:49:12.568] Timer tick, asking Buildbucket for the build status
[17:49:12.626] Build 8943549100710610320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:49:12.626] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:129:0) after 1m0s
[17:50:12.759] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:129:0)
[17:50:12.759] Timer tick, asking Buildbucket for the build status
[17:50:12.840] Build 8943549100710610320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:50:12.840] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:130:0) after 1m0s
[17:51:12.858] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:130:0)
[17:51:12.858] Timer tick, asking Buildbucket for the build status
[17:51:12.917] Build 8943549100710610320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:51:12.917] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:131:0) after 1m0s
[17:52:12.978] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:131:0)
[17:52:12.978] Timer tick, asking Buildbucket for the build status
[17:52:13.188] Build 8943549100710610320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:52:13.188] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:132:0) after 1m0s
[17:53:13.286] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:132:0)
[17:53:13.286] Timer tick, asking Buildbucket for the build status
[17:53:13.348] Build 8943549100710610320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:53:13.348] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:133:0) after 1m0s
[17:54:13.379] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:133:0)
[17:54:13.379] Timer tick, asking Buildbucket for the build status
[17:54:13.474] Build 8943549100710610320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:54:13.474] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:134:0) after 1m0s
[17:55:13.605] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:134:0)
[17:55:13.605] Timer tick, asking Buildbucket for the build status
[17:55:13.728] Build 8943549100710610320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:55:13.728] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:135:0) after 1m0s
[17:56:13.762] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:135:0)
[17:56:13.762] Timer tick, asking Buildbucket for the build status
[17:56:13.887] Build 8943549100710610320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:56:13.887] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:136:0) after 1m0s
[17:57:13.909] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:136:0)
[17:57:13.909] Timer tick, asking Buildbucket for the build status
[17:57:13.992] Build 8943549100710610320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:57:13.992] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:137:0) after 1m0s
[17:58:14.029] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:137:0)
[17:58:14.029] Timer tick, asking Buildbucket for the build status
[17:58:14.298] Build 8943549100710610320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:58:14.298] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:138:0) after 1m0s
[17:59:14.322] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:138:0)
[17:59:14.322] Timer tick, asking Buildbucket for the build status
[17:59:14.454] Build 8943549100710610320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:59:14.454] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:139:0) after 1m0s
[18:00:14.478] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:139:0)
[18:00:14.478] Timer tick, asking Buildbucket for the build status
[18:00:14.519] Build 8943549100710610320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:00:14.519] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:140:0) after 1m0s
[18:01:14.705] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:140:0)
[18:01:14.705] Timer tick, asking Buildbucket for the build status
[18:01:14.776] Build 8943549100710610320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:01:14.776] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:141:0) after 1m0s
[18:02:14.943] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:141:0)
[18:02:14.943] Timer tick, asking Buildbucket for the build status
[18:02:15.003] Build 8943549100710610320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:02:15.003] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:142:0) after 1m0s
[18:03:15.146] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:142:0)
[18:03:15.146] Timer tick, asking Buildbucket for the build status
[18:03:15.208] Build 8943549100710610320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:03:15.208] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:143:0) after 1m0s
[18:04:15.237] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:143:0)
[18:04:15.237] Timer tick, asking Buildbucket for the build status
[18:04:15.298] Build 8943549100710610320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:04:15.298] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:144:0) after 1m0s
[18:05:15.321] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:144:0)
[18:05:15.322] Timer tick, asking Buildbucket for the build status
[18:05:15.375] Build 8943549100710610320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:05:15.375] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:145:0) after 1m0s
[18:06:15.408] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:145:0)
[18:06:15.408] Timer tick, asking Buildbucket for the build status
[18:06:15.463] Build 8943549100710610320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:06:15.463] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:146:0) after 1m0s
[18:07:15.495] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:146:0)
[18:07:15.495] Timer tick, asking Buildbucket for the build status
[18:07:15.653] Build 8943549100710610320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:07:15.653] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:147:0) after 1m0s
[18:08:15.689] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:147:0)
[18:08:15.689] Timer tick, asking Buildbucket for the build status
[18:08:15.741] Build 8943549100710610320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:08:15.741] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:148:0) after 1m0s
[18:09:15.762] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:148:0)
[18:09:15.762] Timer tick, asking Buildbucket for the build status
[18:09:15.918] Build 8943549100710610320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:09:15.918] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:149:0) after 1m0s
[18:10:15.985] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:149:0)
[18:10:15.985] Timer tick, asking Buildbucket for the build status
[18:10:16.048] Build 8943549100710610320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:10:16.048] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:150:0) after 1m0s
[18:11:16.185] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:150:0)
[18:11:16.185] Timer tick, asking Buildbucket for the build status
[18:11:16.279] Build 8943549100710610320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:11:16.279] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:151:0) after 1m0s
[18:12:16.299] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:151:0)
[18:12:16.299] Timer tick, asking Buildbucket for the build status
[18:12:16.329] Build 8943549100710610320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:12:16.329] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:152:0) after 1m0s
[18:13:16.353] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:152:0)
[18:13:16.353] Timer tick, asking Buildbucket for the build status
[18:13:16.595] Build 8943549100710610320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:13:16.595] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:153:0) after 1m0s
[18:14:16.675] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:153:0)
[18:14:16.675] Timer tick, asking Buildbucket for the build status
[18:14:16.741] Build 8943549100710610320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:14:16.741] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:154:0) after 1m0s
[18:15:16.771] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:154:0)
[18:15:16.771] Timer tick, asking Buildbucket for the build status
[18:15:16.831] Build 8943549100710610320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:15:16.831] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:155:0) after 1m0s
[18:16:16.858] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:155:0)
[18:16:16.858] Timer tick, asking Buildbucket for the build status
[18:16:16.914] Build 8943549100710610320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:16:16.914] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:156:0) after 1m0s
[18:17:16.945] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:156:0)
[18:17:16.945] Timer tick, asking Buildbucket for the build status
[18:17:17.001] Build 8943549100710610320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:17:17.002] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:157:0) after 1m0s
[18:18:17.025] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:157:0)
[18:18:17.025] Timer tick, asking Buildbucket for the build status
[18:18:17.093] Build 8943549100710610320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:18:17.093] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:158:0) after 1m0s
[18:19:17.117] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:158:0)
[18:19:17.117] Timer tick, asking Buildbucket for the build status
[18:19:17.173] Build 8943549100710610320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:19:17.173] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:159:0) after 1m0s
[18:20:17.194] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:159:0)
[18:20:17.194] Timer tick, asking Buildbucket for the build status
[18:20:17.259] Build 8943549100710610320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:20:17.259] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:160:0) after 1m0s
[18:21:17.278] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:160:0)
[18:21:17.278] Timer tick, asking Buildbucket for the build status
[18:21:17.498] Build 8943549100710610320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:21:17.498] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:161:0) after 1m0s
[18:22:17.522] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:161:0)
[18:22:17.522] Timer tick, asking Buildbucket for the build status
[18:22:17.579] Build 8943549100710610320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:22:17.579] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:162:0) after 1m0s
[18:23:17.604] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:162:0)
[18:23:17.604] Timer tick, asking Buildbucket for the build status
[18:23:17.694] Build 8943549100710610320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:23:17.694] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:163:0) after 1m0s
[18:24:17.729] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:163:0)
[18:24:17.729] Timer tick, asking Buildbucket for the build status
[18:24:17.819] Build 8943549100710610320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:24:17.819] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:164:0) after 1m0s
[18:25:17.839] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:164:0)
[18:25:17.839] Timer tick, asking Buildbucket for the build status
[18:25:17.905] Build 8943549100710610320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:25:17.905] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:165:0) after 1m0s
[18:26:17.930] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:165:0)
[18:26:17.930] Timer tick, asking Buildbucket for the build status
[18:26:17.983] Build 8943549100710610320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:26:17.983] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:166:0) after 1m0s
[18:27:18.016] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:166:0)
[18:27:18.016] Timer tick, asking Buildbucket for the build status
[18:27:18.076] Build 8943549100710610320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:27:18.076] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:167:0) after 1m0s
[18:28:18.241] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:167:0)
[18:28:18.241] Timer tick, asking Buildbucket for the build status
[18:28:18.339] Build 8943549100710610320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:28:18.339] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:168:0) after 1m0s
[18:29:18.392] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:168:0)
[18:29:18.392] Timer tick, asking Buildbucket for the build status
[18:29:18.448] Build 8943549100710610320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:29:18.448] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:169:0) after 1m0s
[18:30:18.479] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:169:0)
[18:30:18.479] Timer tick, asking Buildbucket for the build status
[18:30:18.656] Build 8943549100710610320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:30:18.656] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:170:0) after 1m0s
[18:31:18.767] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:170:0)
[18:31:18.767] Timer tick, asking Buildbucket for the build status
[18:31:18.814] Build 8943549100710610320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:31:18.814] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:171:0) after 1m0s
[18:32:18.943] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:171:0)
[18:32:18.943] Timer tick, asking Buildbucket for the build status
[18:32:19.150] Build 8943549100710610320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:32:19.150] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:172:0) after 1m0s
[18:33:19.184] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:172:0)
[18:33:19.184] Timer tick, asking Buildbucket for the build status
[18:33:19.238] Build 8943549100710610320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:33:19.238] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:173:0) after 1m0s
[18:34:19.381] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:173:0)
[18:34:19.381] Timer tick, asking Buildbucket for the build status
[18:34:19.415] Build 8943549100710610320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:34:19.415] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:174:0) after 1m0s
[18:35:19.547] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:174:0)
[18:35:19.547] Timer tick, asking Buildbucket for the build status
[18:35:19.600] Build 8943549100710610320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:35:19.600] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:175:0) after 1m0s
[18:36:19.731] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:175:0)
[18:36:19.731] Timer tick, asking Buildbucket for the build status
[18:36:19.800] Build 8943549100710610320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:36:19.800] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:176:0) after 1m0s
[18:37:19.932] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:176:0)
[18:37:19.932] Timer tick, asking Buildbucket for the build status
[18:37:20.137] Build 8943549100710610320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:37:20.137] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:177:0) after 1m0s
[18:38:20.159] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:177:0)
[18:38:20.159] Timer tick, asking Buildbucket for the build status
[18:38:20.278] Build 8943549100710610320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:38:20.278] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:178:0) after 1m0s
[18:39:20.300] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:178:0)
[18:39:20.300] Timer tick, asking Buildbucket for the build status
[18:39:20.648] Build 8943549100710610320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:39:20.648] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:179:0) after 1m0s
[18:40:20.690] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:179:0)
[18:40:20.690] Timer tick, asking Buildbucket for the build status
[18:40:20.780] Build 8943549100710610320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:40:20.780] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:180:0) after 1m0s
[18:41:20.803] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:180:0)
[18:41:20.803] Timer tick, asking Buildbucket for the build status
[18:41:20.864] Build 8943549100710610320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:41:20.864] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:181:0) after 1m0s
[18:42:20.896] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:181:0)
[18:42:20.896] Timer tick, asking Buildbucket for the build status
[18:42:20.995] Build 8943549100710610320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:42:20.995] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:182:0) after 1m0s
[18:43:21.018] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:182:0)
[18:43:21.018] Timer tick, asking Buildbucket for the build status
[18:43:21.172] Build 8943549100710610320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:43:21.172] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:183:0) after 1m0s
[18:44:21.197] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:183:0)
[18:44:21.197] Timer tick, asking Buildbucket for the build status
[18:44:21.476] Build 8943549100710610320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:44:21.476] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:184:0) after 1m0s
[18:45:21.590] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:184:0)
[18:45:21.590] Timer tick, asking Buildbucket for the build status
[18:45:21.630] Build 8943549100710610320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:45:21.630] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:185:0) after 1m0s
[18:46:21.655] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:185:0)
[18:46:21.655] Timer tick, asking Buildbucket for the build status
[18:46:21.730] Build 8943549100710610320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:46:21.730] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:186:0) after 1m0s
[18:47:21.775] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:186:0)
[18:47:21.775] Timer tick, asking Buildbucket for the build status
[18:47:21.893] Build 8943549100710610320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:47:21.893] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108979187050769952:187:0) after 1m0s
[18:48:04.823] Received PubSub notification, asking Buildbucket for the build status
[18:48:04.860] Build 8943549100710610320: status "COMPLETED", result "FAILURE", failure_reason "INFRA_FAILURE", cancelation_reason ""
[18:48:04.860] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary": true,
"canary_preference": "AUTO",
"completed_ts": "1529174883712748",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1529163947342336",
"experimental": true,
"failure_reason": "INFRA_FAILURE",
"id": "8943549100710610320",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"2394d0acf8bf0b0236b2646e4c823a57de7bd941\"}}",
"project": "chromium",
"result": "FAILURE",
"result_details_json": "{\"swarming\": {\"task_result\": {\"run_id\": \"3e2217a6b1d30011\", \"created_ts\": \"2018-06-16T15:45:47.594584\", \"try_number\": \"1\", \"bot_version\": \"a05add8da0279746f7894db2d776781bf284a02fe59235097cc09761bd9a0330\", \"task_id\": \"3e2217a6b1d30010\", \"internal_failure\": true, \"current_task_slice\": \"0\", \"bot_dimensions\": [{\"value\": [\"Windows deterministic\"], \"key\": \"builder\"}, {\"value\": [\"git\", \"goma_v2\", \"vpython\"], \"key\": \"caches\"}, {\"value\": [\"8\"], \"key\": \"cores\"}, {\"value\": [\"x86\", \"x86-64\", \"x86-64-Haswell_GCE\"], \"key\": \"cpu\"}, {\"value\": [\"none\"], \"key\": \"gpu\"}, {\"value\": [\"swarm2173-c4\"], \"key\": \"id\"}, {\"value\": [\"chrome-win10-1703-2018-04-20-61acaf8983f\"], \"key\": \"image\"}, {\"value\": [\"high\"], \"key\": \"integrity\"}, {\"value\": [\"en_US.cp1252\"], \"key\": \"locale\"}, {\"value\": [\"n1-standard-8\"], \"key\": \"machine_type\"}, {\"value\": [\"Windows\", \"Windows-10\", \"Windows-10-15063\"], \"key\": \"os\"}, {\"value\": [\"luci.chromium.ci\"], \"key\": \"pool\"}, {\"value\": [\"2.7.6\"], \"key\": \"python\"}, {\"value\": [\"3649-5aeacfe\"], \"key\": \"server_version\"}, {\"value\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"], \"key\": \"zone\"}], \"server_versions\": [\"3649-5aeacfe\"], \"state\": \"BOT_DIED\", \"name\": \"bb-8943549100710610320-chromium-Windows deterministic\", \"abandoned_ts\": \"2018-06-16T18:48:03.712748\", \"failure\": false, \"costs_usd\": [2.2155191924630597], \"modified_ts\": \"2018-06-16T18:48:03.712748\", \"user\": \"\", \"started_ts\": \"2018-06-16T15:46:31.877276\", \"tags\": [\"build_address:luci.chromium.ci/Windows deterministic/47\", \"buildbucket_bucket:luci.chromium.ci\", \"buildbucket_build_id:8943549100710610320\", \"buildbucket_hostname:cr-buildbucket.appspot.com\", \"buildbucket_template_canary:1\", \"buildbucket_template_revision:2a8c11ce5d733cead8b6ce80c31bfed950c5e864\", \"builder:Windows deterministic\", \"buildset:commit/git/2394d0acf8bf0b0236b2646e4c823a57de7bd941\", \"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/2394d0acf8bf0b0236b2646e4c823a57de7bd941\", \"cores:8\", \"cpu:x86-64\", \"gitiles_ref:refs/heads/master\", \"log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8943549100710610320/+/annotations\", \"luci_project:chromium\", \"os:Windows-10\", \"pool:luci.chromium.ci\", \"priority:60\", \"recipe_name:swarming/deterministic_build\", \"recipe_package:infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build\", \"scheduler_invocation_id:9108979187050769952\", \"scheduler_job_id:chromium/Windows deterministic\", \"service_account:chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com\", \"swarming.pool.template:skip\", \"swarming.pool.version:7b1b5d239a2e04e83d3627884ca58c970e37b2b0\", \"user:None\", \"user_agent:luci-scheduler\", \"vpython:native-python-wrapper\"], \"bot_id\": \"swarm2173-c4\"}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1529163991877276",
"status": "COMPLETED",
"status_changed_ts": "1529174884080058",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/47",
"builder:Windows deterministic",
"buildset:commit/git/2394d0acf8bf0b0236b2646e4c823a57de7bd941",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/2394d0acf8bf0b0236b2646e4c823a57de7bd941",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9108979187050769952",
"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/47",
"swarming_tag:buildbucket_bucket:luci.chromium.ci",
"swarming_tag:buildbucket_build_id:8943549100710610320",
"swarming_tag:buildbucket_hostname:cr-buildbucket.appspot.com",
"swarming_tag:buildbucket_template_canary:1",
"swarming_tag:buildbucket_template_revision:2a8c11ce5d733cead8b6ce80c31bfed950c5e864",
"swarming_tag:builder:Windows deterministic",
"swarming_tag:buildset:commit/git/2394d0acf8bf0b0236b2646e4c823a57de7bd941",
"swarming_tag:buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/2394d0acf8bf0b0236b2646e4c823a57de7bd941",
"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/8943549100710610320/+/annotations",
"swarming_tag:luci_project:chromium",
"swarming_tag:os:Windows-10",
"swarming_tag:pool:luci.chromium.ci",
"swarming_tag:priority:60",
"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:9108979187050769952",
"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:7b1b5d239a2e04e83d3627884ca58c970e37b2b0",
"swarming_tag:user:None",
"swarming_tag:user_agent:luci-scheduler",
"swarming_tag:vpython:native-python-wrapper",
"swarming_task_id:3e2217a6b1d30010",
"user_agent:luci-scheduler"
],
"updated_ts": "1529174884080838",
"url": "https://ci.chromium.org/p/chromium/builders/luci.chromium.ci/Windows deterministic/47",
"utcnow_ts": "1529174884850666"
}
}
[18:48:04.860] Invocation finished in 3h2m42.022842768s with status FAILED