[20:34:30.166] New invocation is queued and will start shortly
[20:34:31.452] Starting the invocation (attempt 1)
[20:34:31.517] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[20:34:31.517] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[20:34:31.518] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9093378318858377712",
"parameters_json": "{\"builder_name\":\"Windows deterministic\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src.git\",\"revision\":\"51667e7ebfcbbbc28d22a766260bb7511ceeec2d\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Windows deterministic",
"scheduler_invocation_id:9093378318858377712",
"scheduler_job_id:chromium/Windows deterministic",
"user_agent:luci-scheduler",
"buildset:commit/git/51667e7ebfcbbbc28d22a766260bb7511ceeec2d",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/51667e7ebfcbbbc28d22a766260bb7511ceeec2d",
"gitiles_ref:refs/heads/master"
]
}
[20:34:32.817] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary": true,
"canary_preference": "AUTO",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1544042071626195",
"id": "8927948256660546800",
"lease_expiration_ts": "1544160872352204",
"lease_key": "982125399",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"51667e7ebfcbbbc28d22a766260bb7511ceeec2d\"}}",
"project": "chromium",
"result_details_json": "null",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1544042072610082",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/10974",
"builder:Windows deterministic",
"buildset:commit/git/51667e7ebfcbbbc28d22a766260bb7511ceeec2d",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/51667e7ebfcbbbc28d22a766260bb7511ceeec2d",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9093378318858377712",
"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/10974",
"swarming_tag:buildbucket_bucket:chromium/ci",
"swarming_tag:buildbucket_build_id:8927948256660546800",
"swarming_tag:buildbucket_hostname:cr-buildbucket.appspot.com",
"swarming_tag:buildbucket_template_canary:1",
"swarming_tag:buildbucket_template_revision:2f19301aebbc779351dc95ab1dc03603fa761240",
"swarming_tag:builder:Windows deterministic",
"swarming_tag:buildset:commit/git/51667e7ebfcbbbc28d22a766260bb7511ceeec2d",
"swarming_tag:buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/51667e7ebfcbbbc28d22a766260bb7511ceeec2d",
"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/8927948256660546800/+/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:9093378318858377712",
"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:7be98d3a655353173e6c69dca4a3f5562afcd673",
"swarming_tag:user:None",
"swarming_tag:user_agent:luci-scheduler",
"swarming_tag:vpython:native-python-wrapper",
"swarming_task_id:4198e5d01da05d10",
"user_agent:luci-scheduler"
],
"updated_ts": "1544042072610194",
"url": "https://ci.chromium.org/b/8927948256660546800",
"utcnow_ts": "1544042072800259"
}
}
[20:34:32.817] Task URL: https://ci.chromium.org/b/8927948256660546800
[20:34:32.817] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:2:0) after 1m0s
[20:35:32.905] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:2:0)
[20:35:32.905] Timer tick, asking Buildbucket for the build status
[20:35:32.960] Build 8927948256660546800: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:35:32.960] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:3:0) after 1m0s
[20:36:33.053] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:3:0)
[20:36:33.053] Timer tick, asking Buildbucket for the build status
[20:36:33.094] Build 8927948256660546800: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:36:33.094] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:4:0) after 1m0s
[20:37:21.258] Received PubSub notification, asking Buildbucket for the build status
[20:37:21.295] Build 8927948256660546800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:37:33.158] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:4:0)
[20:37:33.158] Timer tick, asking Buildbucket for the build status
[20:37:33.211] Build 8927948256660546800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:37:33.211] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:6:0) after 1m0s
[20:38:33.265] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:6:0)
[20:38:33.265] Timer tick, asking Buildbucket for the build status
[20:38:33.321] Build 8927948256660546800: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:38:33.321] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:7:0) after 1m0s
[20:39:33.347] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:7:0)
[20:39:33.347] Timer tick, asking Buildbucket for the build status
[20:39:33.413] Build 8927948256660546800: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:39:33.413] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:8:0) after 1m0s
[20:39:39.111] Received PubSub notification, asking Buildbucket for the build status
[20:39:39.173] Build 8927948256660546800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:40:33.433] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:8:0)
[20:40:33.433] Timer tick, asking Buildbucket for the build status
[20:40:33.501] Build 8927948256660546800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:40:33.501] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:10:0) after 1m0s
[20:41:33.531] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:10:0)
[20:41:33.531] Timer tick, asking Buildbucket for the build status
[20:41:33.567] Build 8927948256660546800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:41:33.567] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:11:0) after 1m0s
[20:42:33.584] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:11:0)
[20:42:33.584] Timer tick, asking Buildbucket for the build status
[20:42:33.678] Build 8927948256660546800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:42:33.678] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:12:0) after 1m0s
[20:43:33.697] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:12:0)
[20:43:33.697] Timer tick, asking Buildbucket for the build status
[20:43:33.730] Build 8927948256660546800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:43:33.730] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:13:0) after 1m0s
[20:44:33.750] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:13:0)
[20:44:33.750] Timer tick, asking Buildbucket for the build status
[20:44:33.832] Build 8927948256660546800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:44:33.832] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:14:0) after 1m0s
[20:45:33.884] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:14:0)
[20:45:33.884] Timer tick, asking Buildbucket for the build status
[20:45:33.973] Build 8927948256660546800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:45:33.973] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:15:0) after 1m0s
[20:46:34.038] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:15:0)
[20:46:34.038] Timer tick, asking Buildbucket for the build status
[20:46:34.106] Build 8927948256660546800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:46:34.106] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:16:0) after 1m0s
[20:47:34.124] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:16:0)
[20:47:34.124] Timer tick, asking Buildbucket for the build status
[20:47:34.158] Build 8927948256660546800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:47:34.158] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:17:0) after 1m0s
[20:48:34.185] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:17:0)
[20:48:34.185] Timer tick, asking Buildbucket for the build status
[20:48:34.242] Build 8927948256660546800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:48:34.242] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:18:0) after 1m0s
[20:49:34.271] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:18:0)
[20:49:34.271] Timer tick, asking Buildbucket for the build status
[20:49:34.335] Build 8927948256660546800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:49:34.335] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:19:0) after 1m0s
[20:50:34.352] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:19:0)
[20:50:34.352] Timer tick, asking Buildbucket for the build status
[20:50:34.384] Build 8927948256660546800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:50:34.384] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:20:0) after 1m0s
[20:51:34.403] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:20:0)
[20:51:34.403] Timer tick, asking Buildbucket for the build status
[20:51:34.447] Build 8927948256660546800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:51:34.447] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:21:0) after 1m0s
[20:52:34.468] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:21:0)
[20:52:34.468] Timer tick, asking Buildbucket for the build status
[20:52:34.656] Build 8927948256660546800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:52:34.656] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:22:0) after 1m0s
[20:53:34.678] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:22:0)
[20:53:34.678] Timer tick, asking Buildbucket for the build status
[20:53:34.760] Build 8927948256660546800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:53:34.760] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:23:0) after 1m0s
[20:54:34.800] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:23:0)
[20:54:34.800] Timer tick, asking Buildbucket for the build status
[20:54:34.852] Build 8927948256660546800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:54:34.852] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:24:0) after 1m0s
[20:55:34.899] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:24:0)
[20:55:34.899] Timer tick, asking Buildbucket for the build status
[20:55:34.937] Build 8927948256660546800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:55:34.937] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:25:0) after 1m0s
[20:56:34.959] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:25:0)
[20:56:34.959] Timer tick, asking Buildbucket for the build status
[20:56:35.050] Build 8927948256660546800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:56:35.050] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:26:0) after 1m0s
[20:57:35.067] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:26:0)
[20:57:35.067] Timer tick, asking Buildbucket for the build status
[20:57:35.116] Build 8927948256660546800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:57:35.116] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:27:0) after 1m0s
[20:58:35.141] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:27:0)
[20:58:35.141] Timer tick, asking Buildbucket for the build status
[20:58:35.249] Build 8927948256660546800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:58:35.249] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:28:0) after 1m0s
[20:59:35.267] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:28:0)
[20:59:35.267] Timer tick, asking Buildbucket for the build status
[20:59:35.378] Build 8927948256660546800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:59:35.378] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:29:0) after 1m0s
[21:00:35.414] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:29:0)
[21:00:35.414] Timer tick, asking Buildbucket for the build status
[21:00:35.537] Build 8927948256660546800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:00:35.537] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:30:0) after 1m0s
[21:01:35.555] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:30:0)
[21:01:35.555] Timer tick, asking Buildbucket for the build status
[21:01:35.653] Build 8927948256660546800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:01:35.653] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:31:0) after 1m0s
[21:02:35.673] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:31:0)
[21:02:35.673] Timer tick, asking Buildbucket for the build status
[21:02:35.737] Build 8927948256660546800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:02:35.737] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:32:0) after 1m0s
[21:03:35.759] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:32:0)
[21:03:35.759] Timer tick, asking Buildbucket for the build status
[21:03:35.798] Build 8927948256660546800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:03:35.798] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:33:0) after 1m0s
[21:04:35.875] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:33:0)
[21:04:35.875] Timer tick, asking Buildbucket for the build status
[21:04:36.051] Build 8927948256660546800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:04:36.051] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:34:0) after 1m0s
[21:05:36.069] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:34:0)
[21:05:36.069] Timer tick, asking Buildbucket for the build status
[21:05:36.130] Build 8927948256660546800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:05:36.130] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:35:0) after 1m0s
[21:06:36.149] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:35:0)
[21:06:36.149] Timer tick, asking Buildbucket for the build status
[21:06:36.182] Build 8927948256660546800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:06:36.182] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:36:0) after 1m0s
[21:07:36.201] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:36:0)
[21:07:36.201] Timer tick, asking Buildbucket for the build status
[21:07:36.306] Build 8927948256660546800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:07:36.306] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:37:0) after 1m0s
[21:08:36.352] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:37:0)
[21:08:36.352] Timer tick, asking Buildbucket for the build status
[21:08:36.403] Build 8927948256660546800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:08:36.403] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:38:0) after 1m0s
[21:09:36.422] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:38:0)
[21:09:36.422] Timer tick, asking Buildbucket for the build status
[21:09:36.457] Build 8927948256660546800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:09:36.457] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:39:0) after 1m0s
[21:10:36.485] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:39:0)
[21:10:36.485] Timer tick, asking Buildbucket for the build status
[21:10:36.526] Build 8927948256660546800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:10:36.526] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:40:0) after 1m0s
[21:11:36.547] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:40:0)
[21:11:36.547] Timer tick, asking Buildbucket for the build status
[21:11:36.590] Build 8927948256660546800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:11:36.590] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:41:0) after 1m0s
[21:12:36.623] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:41:0)
[21:12:36.623] Timer tick, asking Buildbucket for the build status
[21:12:36.683] Build 8927948256660546800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:12:36.683] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:42:0) after 1m0s
[21:13:36.729] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:42:0)
[21:13:36.729] Timer tick, asking Buildbucket for the build status
[21:13:36.766] Build 8927948256660546800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:13:36.766] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:43:0) after 1m0s
[21:14:36.785] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:43:0)
[21:14:36.785] Timer tick, asking Buildbucket for the build status
[21:14:36.894] Build 8927948256660546800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:14:36.894] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:44:0) after 1m0s
[21:15:36.915] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:44:0)
[21:15:36.915] Timer tick, asking Buildbucket for the build status
[21:15:36.965] Build 8927948256660546800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:15:36.965] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:45:0) after 1m0s
[21:16:36.994] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:45:0)
[21:16:36.994] Timer tick, asking Buildbucket for the build status
[21:16:37.059] Build 8927948256660546800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:16:37.059] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:46:0) after 1m0s
[21:17:37.078] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:46:0)
[21:17:37.078] Timer tick, asking Buildbucket for the build status
[21:17:37.119] Build 8927948256660546800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:17:37.119] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:47:0) after 1m0s
[21:18:37.148] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:47:0)
[21:18:37.148] Timer tick, asking Buildbucket for the build status
[21:18:37.325] Build 8927948256660546800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:18:37.325] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:48:0) after 1m0s
[21:19:37.344] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:48:0)
[21:19:37.344] Timer tick, asking Buildbucket for the build status
[21:19:37.384] Build 8927948256660546800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:19:37.384] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:49:0) after 1m0s
[21:20:37.428] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:49:0)
[21:20:37.428] Timer tick, asking Buildbucket for the build status
[21:20:37.520] Build 8927948256660546800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:20:37.520] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:50:0) after 1m0s
[21:21:37.548] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:50:0)
[21:21:37.548] Timer tick, asking Buildbucket for the build status
[21:21:37.775] Build 8927948256660546800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:21:37.775] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:51:0) after 1m0s
[21:22:37.799] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:51:0)
[21:22:37.799] Timer tick, asking Buildbucket for the build status
[21:22:37.968] Build 8927948256660546800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:22:37.968] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:52:0) after 1m0s
[21:23:37.992] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:52:0)
[21:23:37.992] Timer tick, asking Buildbucket for the build status
[21:23:38.065] Build 8927948256660546800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:23:38.065] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:53:0) after 1m0s
[21:24:38.089] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:53:0)
[21:24:38.089] Timer tick, asking Buildbucket for the build status
[21:24:38.178] Build 8927948256660546800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:24:38.178] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:54:0) after 1m0s
[21:25:38.308] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:54:0)
[21:25:38.308] Timer tick, asking Buildbucket for the build status
[21:25:38.387] Build 8927948256660546800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:25:38.387] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:55:0) after 1m0s
[21:26:38.410] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:55:0)
[21:26:38.410] Timer tick, asking Buildbucket for the build status
[21:26:38.476] Build 8927948256660546800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:26:38.476] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:56:0) after 1m0s
[21:27:38.497] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:56:0)
[21:27:38.497] Timer tick, asking Buildbucket for the build status
[21:27:38.559] Build 8927948256660546800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:27:38.559] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:57:0) after 1m0s
[21:28:38.580] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:57:0)
[21:28:38.580] Timer tick, asking Buildbucket for the build status
[21:28:38.612] Build 8927948256660546800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:28:38.612] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:58:0) after 1m0s
[21:29:38.645] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:58:0)
[21:29:38.645] Timer tick, asking Buildbucket for the build status
[21:29:38.688] Build 8927948256660546800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:29:38.688] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:59:0) after 1m0s
[21:30:38.711] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:59:0)
[21:30:38.711] Timer tick, asking Buildbucket for the build status
[21:30:38.898] Build 8927948256660546800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:30:38.898] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:60:0) after 1m0s
[21:31:38.921] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:60:0)
[21:31:38.921] Timer tick, asking Buildbucket for the build status
[21:31:38.954] Build 8927948256660546800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:31:38.954] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:61:0) after 1m0s
[21:32:39.050] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:61:0)
[21:32:39.050] Timer tick, asking Buildbucket for the build status
[21:32:39.114] Build 8927948256660546800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:32:39.114] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:62:0) after 1m0s
[21:33:39.136] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:62:0)
[21:33:39.136] Timer tick, asking Buildbucket for the build status
[21:33:39.175] Build 8927948256660546800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:33:39.175] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:63:0) after 1m0s
[21:34:39.269] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:63:0)
[21:34:39.269] Timer tick, asking Buildbucket for the build status
[21:34:39.333] Build 8927948256660546800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:34:39.333] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:64:0) after 1m0s
[21:35:39.372] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:64:0)
[21:35:39.372] Timer tick, asking Buildbucket for the build status
[21:35:39.426] Build 8927948256660546800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:35:39.426] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:65:0) after 1m0s
[21:36:39.456] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:65:0)
[21:36:39.456] Timer tick, asking Buildbucket for the build status
[21:36:39.491] Build 8927948256660546800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:36:39.491] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:66:0) after 1m0s
[21:37:39.561] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:66:0)
[21:37:39.561] Timer tick, asking Buildbucket for the build status
[21:37:39.602] Build 8927948256660546800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:37:39.602] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:67:0) after 1m0s
[21:38:39.623] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:67:0)
[21:38:39.623] Timer tick, asking Buildbucket for the build status
[21:38:39.660] Build 8927948256660546800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:38:39.660] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:68:0) after 1m0s
[21:39:39.681] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:68:0)
[21:39:39.681] Timer tick, asking Buildbucket for the build status
[21:39:39.714] Build 8927948256660546800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:39:39.714] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:69:0) after 1m0s
[21:40:39.732] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:69:0)
[21:40:39.732] Timer tick, asking Buildbucket for the build status
[21:40:39.793] Build 8927948256660546800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:40:39.793] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:70:0) after 1m0s
[21:41:39.817] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:70:0)
[21:41:39.817] Timer tick, asking Buildbucket for the build status
[21:41:39.869] Build 8927948256660546800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:41:39.869] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:71:0) after 1m0s
[21:42:39.891] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:71:0)
[21:42:39.891] Timer tick, asking Buildbucket for the build status
[21:42:40.044] Build 8927948256660546800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:42:40.044] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:72:0) after 1m0s
[21:43:40.098] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:72:0)
[21:43:40.098] Timer tick, asking Buildbucket for the build status
[21:43:40.138] Build 8927948256660546800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:43:40.138] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:73:0) after 1m0s
[21:44:40.176] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:73:0)
[21:44:40.176] Timer tick, asking Buildbucket for the build status
[21:44:40.304] Build 8927948256660546800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:44:40.304] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:74:0) after 1m0s
[21:45:40.331] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:74:0)
[21:45:40.331] Timer tick, asking Buildbucket for the build status
[21:45:40.386] Build 8927948256660546800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:45:40.386] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:75:0) after 1m0s
[21:46:40.413] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:75:0)
[21:46:40.413] Timer tick, asking Buildbucket for the build status
[21:46:40.493] Build 8927948256660546800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:46:40.493] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:76:0) after 1m0s
[21:47:40.525] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:76:0)
[21:47:40.525] Timer tick, asking Buildbucket for the build status
[21:47:40.717] Build 8927948256660546800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:47:40.717] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:77:0) after 1m0s
[21:48:40.743] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:77:0)
[21:48:40.743] Timer tick, asking Buildbucket for the build status
[21:48:40.806] Build 8927948256660546800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:48:40.806] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:78:0) after 1m0s
[21:49:40.825] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:78:0)
[21:49:40.825] Timer tick, asking Buildbucket for the build status
[21:49:40.927] Build 8927948256660546800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:49:40.927] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:79:0) after 1m0s
[21:50:40.947] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:79:0)
[21:50:40.947] Timer tick, asking Buildbucket for the build status
[21:50:41.028] Build 8927948256660546800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:50:41.028] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:80:0) after 1m0s
[21:51:41.049] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:80:0)
[21:51:41.049] Timer tick, asking Buildbucket for the build status
[21:51:41.114] Build 8927948256660546800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:51:41.114] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:81:0) after 1m0s
[21:52:41.140] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:81:0)
[21:52:41.140] Timer tick, asking Buildbucket for the build status
[21:52:41.203] Build 8927948256660546800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:52:41.203] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:82:0) after 1m0s
[21:53:41.226] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:82:0)
[21:53:41.226] Timer tick, asking Buildbucket for the build status
[21:53:41.278] Build 8927948256660546800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:53:41.278] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:83:0) after 1m0s
[21:54:41.305] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:83:0)
[21:54:41.305] Timer tick, asking Buildbucket for the build status
[21:54:41.363] Build 8927948256660546800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:54:41.364] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:84:0) after 1m0s
[21:55:41.384] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:84:0)
[21:55:41.384] Timer tick, asking Buildbucket for the build status
[21:55:41.456] Build 8927948256660546800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:55:41.456] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:85:0) after 1m0s
[21:56:41.476] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:85:0)
[21:56:41.476] Timer tick, asking Buildbucket for the build status
[21:56:41.542] Build 8927948256660546800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:56:41.542] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:86:0) after 1m0s
[21:57:41.561] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:86:0)
[21:57:41.561] Timer tick, asking Buildbucket for the build status
[21:57:41.635] Build 8927948256660546800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:57:41.635] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:87:0) after 1m0s
[21:58:41.653] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:87:0)
[21:58:41.653] Timer tick, asking Buildbucket for the build status
[21:58:41.695] Build 8927948256660546800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:58:41.695] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:88:0) after 1m0s
[21:59:41.721] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:88:0)
[21:59:41.721] Timer tick, asking Buildbucket for the build status
[21:59:41.830] Build 8927948256660546800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:59:41.830] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:89:0) after 1m0s
[22:00:41.914] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:89:0)
[22:00:41.914] Timer tick, asking Buildbucket for the build status
[22:00:42.012] Build 8927948256660546800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:00:42.012] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:90:0) after 1m0s
[22:01:42.045] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:90:0)
[22:01:42.045] Timer tick, asking Buildbucket for the build status
[22:01:42.094] Build 8927948256660546800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:01:42.094] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:91:0) after 1m0s
[22:02:42.127] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:91:0)
[22:02:42.127] Timer tick, asking Buildbucket for the build status
[22:02:42.192] Build 8927948256660546800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:02:42.192] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:92:0) after 1m0s
[22:03:42.212] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:92:0)
[22:03:42.212] Timer tick, asking Buildbucket for the build status
[22:03:42.247] Build 8927948256660546800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:03:42.247] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:93:0) after 1m0s
[22:04:42.268] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:93:0)
[22:04:42.268] Timer tick, asking Buildbucket for the build status
[22:04:42.306] Build 8927948256660546800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:04:42.306] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:94:0) after 1m0s
[22:05:42.332] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:94:0)
[22:05:42.332] Timer tick, asking Buildbucket for the build status
[22:05:42.379] Build 8927948256660546800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:05:42.379] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:95:0) after 1m0s
[22:06:42.402] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:95:0)
[22:06:42.402] Timer tick, asking Buildbucket for the build status
[22:06:42.567] Build 8927948256660546800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:06:42.567] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:96:0) after 1m0s
[22:07:42.588] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:96:0)
[22:07:42.588] Timer tick, asking Buildbucket for the build status
[22:07:42.771] Build 8927948256660546800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:07:42.771] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:97:0) after 1m0s
[22:08:42.800] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:97:0)
[22:08:42.800] Timer tick, asking Buildbucket for the build status
[22:08:42.903] Build 8927948256660546800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:08:42.903] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:98:0) after 1m0s
[22:09:42.924] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:98:0)
[22:09:42.924] Timer tick, asking Buildbucket for the build status
[22:09:43.032] Build 8927948256660546800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:09:43.032] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:99:0) after 1m0s
[22:10:43.057] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:99:0)
[22:10:43.057] Timer tick, asking Buildbucket for the build status
[22:10:43.110] Build 8927948256660546800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:10:43.110] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:100:0) after 1m0s
[22:11:43.132] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:100:0)
[22:11:43.132] Timer tick, asking Buildbucket for the build status
[22:11:43.166] Build 8927948256660546800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:11:43.166] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:101:0) after 1m0s
[22:12:43.191] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:101:0)
[22:12:43.191] Timer tick, asking Buildbucket for the build status
[22:12:43.245] Build 8927948256660546800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:12:43.245] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:102:0) after 1m0s
[22:13:43.267] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:102:0)
[22:13:43.267] Timer tick, asking Buildbucket for the build status
[22:13:43.325] Build 8927948256660546800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:13:43.325] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:103:0) after 1m0s
[22:14:43.349] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:103:0)
[22:14:43.349] Timer tick, asking Buildbucket for the build status
[22:14:43.384] Build 8927948256660546800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:14:43.384] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:104:0) after 1m0s
[22:15:43.407] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:104:0)
[22:15:43.407] Timer tick, asking Buildbucket for the build status
[22:15:43.494] Build 8927948256660546800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:15:43.494] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:105:0) after 1m0s
[22:16:43.519] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:105:0)
[22:16:43.519] Timer tick, asking Buildbucket for the build status
[22:16:43.569] Build 8927948256660546800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:16:43.569] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:106:0) after 1m0s
[22:17:43.598] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:106:0)
[22:17:43.598] Timer tick, asking Buildbucket for the build status
[22:17:43.680] Build 8927948256660546800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:17:43.680] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:107:0) after 1m0s
[22:18:43.709] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:107:0)
[22:18:43.709] Timer tick, asking Buildbucket for the build status
[22:18:43.766] Build 8927948256660546800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:18:43.766] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:108:0) after 1m0s
[22:19:43.793] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:108:0)
[22:19:43.793] Timer tick, asking Buildbucket for the build status
[22:19:43.929] Build 8927948256660546800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:19:43.929] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:109:0) after 1m0s
[22:20:43.957] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:109:0)
[22:20:43.957] Timer tick, asking Buildbucket for the build status
[22:20:44.061] Build 8927948256660546800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:20:44.061] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:110:0) after 1m0s
[22:21:44.087] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:110:0)
[22:21:44.087] Timer tick, asking Buildbucket for the build status
[22:21:44.242] Build 8927948256660546800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:21:44.242] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:111:0) after 1m0s
[22:22:44.268] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:111:0)
[22:22:44.268] Timer tick, asking Buildbucket for the build status
[22:22:44.328] Build 8927948256660546800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:22:44.328] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:112:0) after 1m0s
[22:23:44.353] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:112:0)
[22:23:44.353] Timer tick, asking Buildbucket for the build status
[22:23:44.394] Build 8927948256660546800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:23:44.394] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:113:0) after 1m0s
[22:24:44.434] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:113:0)
[22:24:44.434] Timer tick, asking Buildbucket for the build status
[22:24:44.505] Build 8927948256660546800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:24:44.505] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:114:0) after 1m0s
[22:25:44.542] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:114:0)
[22:25:44.542] Timer tick, asking Buildbucket for the build status
[22:25:44.582] Build 8927948256660546800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:25:44.582] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:115:0) after 1m0s
[22:26:44.605] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:115:0)
[22:26:44.605] Timer tick, asking Buildbucket for the build status
[22:26:44.672] Build 8927948256660546800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:26:44.672] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:116:0) after 1m0s
[22:27:44.779] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:116:0)
[22:27:44.779] Timer tick, asking Buildbucket for the build status
[22:27:44.980] Build 8927948256660546800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:27:44.980] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:117:0) after 1m0s
[22:28:45.011] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:117:0)
[22:28:45.012] Timer tick, asking Buildbucket for the build status
[22:28:45.099] Build 8927948256660546800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:28:45.099] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:118:0) after 1m0s
[22:29:45.119] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:118:0)
[22:29:45.119] Timer tick, asking Buildbucket for the build status
[22:29:45.153] Build 8927948256660546800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:29:45.153] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:119:0) after 1m0s
[22:30:45.183] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:119:0)
[22:30:45.183] Timer tick, asking Buildbucket for the build status
[22:30:45.270] Build 8927948256660546800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:30:45.270] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:120:0) after 1m0s
[22:31:45.293] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:120:0)
[22:31:45.293] Timer tick, asking Buildbucket for the build status
[22:31:45.371] Build 8927948256660546800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:31:45.371] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:121:0) after 1m0s
[22:32:45.401] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:121:0)
[22:32:45.401] Timer tick, asking Buildbucket for the build status
[22:32:45.438] Build 8927948256660546800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:32:45.438] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:122:0) after 1m0s
[22:33:45.459] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:122:0)
[22:33:45.459] Timer tick, asking Buildbucket for the build status
[22:33:45.497] Build 8927948256660546800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:33:45.497] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:123:0) after 1m0s
[22:34:45.517] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:123:0)
[22:34:45.517] Timer tick, asking Buildbucket for the build status
[22:34:45.582] Build 8927948256660546800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:34:45.582] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:124:0) after 1m0s
[22:35:45.611] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:124:0)
[22:35:45.611] Timer tick, asking Buildbucket for the build status
[22:35:45.648] Build 8927948256660546800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:35:45.648] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:125:0) after 1m0s
[22:36:45.670] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:125:0)
[22:36:45.670] Timer tick, asking Buildbucket for the build status
[22:36:45.710] Build 8927948256660546800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:36:45.710] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093378318858377712:126:0) after 1m0s
[22:36:49.389] Received PubSub notification, asking Buildbucket for the build status
[22:36:49.432] Build 8927948256660546800: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[22:36:49.432] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary": true,
"canary_preference": "AUTO",
"completed_ts": "1544049408522667",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1544042071626195",
"failure_reason": "BUILD_FAILURE",
"id": "8927948256660546800",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"51667e7ebfcbbbc28d22a766260bb7511ceeec2d\"}}",
"project": "chromium",
"result": "FAILURE",
"result_details_json": "{\"ui\": {\"info\": \"Failure compare_build_artifacts\"}, \"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\": [\"3910-a71d441\"], \"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\"]}}, \"properties\": {\"got_nacl_revision\": \"17af1e818981ebf2c492a678d4d31c9e1ee37fc7\", \"got_swarming_client_revision\": \"0e3e1c4dc4e79f25a5b58fcbc135dc93183c0c54\", \"got_revision\": \"51667e7ebfcbbbc28d22a766260bb7511ceeec2d\", \"recipe\": \"swarming/deterministic_build\", \"got_dawn_revision\": \"7b3ea34b6b132efe7d93f0025dc7cbbe50641b9c\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#25906}\", \"$recipe_engine/path\": {\"cache_dir\": \"C:\\\\b\\\\swarming\\\\w\\\\ir\\\\cache\", \"temp_dir\": \"C:\\\\b\\\\swarming\\\\w\\\\ir\\\\tmp\\\\rt\"}, \"got_revision_cp\": \"refs/heads/master@{#614082}\", \"branch\": \"refs/heads/master\", \"revision\": \"51667e7ebfcbbbc28d22a766260bb7511ceeec2d\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"buildername\": \"Windows deterministic\", \"got_webrtc_revision\": \"dc107965bdbf153fb3c237a66bcd340e43343084\", \"mastername\": \"chromium.win\", \"got_angle_revision\": \"96bd8fdf36e58199ae3deb3c8108a55cec2d43a3\", \"buildbucket\": {\"hostname\": \"cr-buildbucket.appspot.com\", \"build\": {\"created_ts\": 1544042071626195, \"tags\": [\"builder:Windows deterministic\", \"buildset:commit/git/51667e7ebfcbbbc28d22a766260bb7511ceeec2d\", \"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/51667e7ebfcbbbc28d22a766260bb7511ceeec2d\", \"gitiles_ref:refs/heads/master\", \"scheduler_invocation_id:9093378318858377712\", \"scheduler_job_id:chromium/Windows deterministic\", \"user_agent:luci-scheduler\"], \"bucket\": \"luci.chromium.ci\", \"created_by\": \"user:luci-scheduler@appspot.gserviceaccount.com\", \"project\": \"chromium\", \"id\": \"8927948256660546800\"}}, \"got_v8_revision\": \"2e199b5829aaca75187cc1f841636ea2b0037462\", \"got_v8_revision_cp\": \"refs/heads/7.3.34@{#1}\", \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"buildnumber\": 10974, \"path_config\": \"generic\", \"bot_id\": \"swarm2173-c4\", \"got_buildtools_revision\": \"04161ec8d7c781e4498c699254c69ba0dd959fde\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1544042240573847",
"status": "COMPLETED",
"status_changed_ts": "1544049409141337",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/10974",
"builder:Windows deterministic",
"buildset:commit/git/51667e7ebfcbbbc28d22a766260bb7511ceeec2d",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/51667e7ebfcbbbc28d22a766260bb7511ceeec2d",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9093378318858377712",
"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/10974",
"swarming_tag:buildbucket_bucket:chromium/ci",
"swarming_tag:buildbucket_build_id:8927948256660546800",
"swarming_tag:buildbucket_hostname:cr-buildbucket.appspot.com",
"swarming_tag:buildbucket_template_canary:1",
"swarming_tag:buildbucket_template_revision:2f19301aebbc779351dc95ab1dc03603fa761240",
"swarming_tag:builder:Windows deterministic",
"swarming_tag:buildset:commit/git/51667e7ebfcbbbc28d22a766260bb7511ceeec2d",
"swarming_tag:buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/51667e7ebfcbbbc28d22a766260bb7511ceeec2d",
"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/8927948256660546800/+/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:9093378318858377712",
"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:7be98d3a655353173e6c69dca4a3f5562afcd673",
"swarming_tag:user:None",
"swarming_tag:user_agent:luci-scheduler",
"swarming_tag:vpython:native-python-wrapper",
"swarming_task_id:4198e5d01da05d10",
"user_agent:luci-scheduler"
],
"updated_ts": "1544049409142411",
"url": "https://ci.chromium.org/b/8927948256660546800",
"utcnow_ts": "1544049409423845"
}
}
[22:36:49.432] Invocation finished in 2h2m19.284712374s with status FAILED