[11:06:14.576] New invocation is queued and will start shortly
[11:06:15.688] Starting the invocation (attempt 1)
[11:06:15.724] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[11:06:15.724] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[11:06:15.724] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9093414070685922608",
"parameters_json": "{\"builder_name\":\"Windows deterministic\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src.git\",\"revision\":\"f67ff8b4f9f9deb5d72e682e3ba54c32191ac75d\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Windows deterministic",
"scheduler_invocation_id:9093414070685922608",
"scheduler_job_id:chromium/Windows deterministic",
"user_agent:luci-scheduler",
"buildset:commit/git/f67ff8b4f9f9deb5d72e682e3ba54c32191ac75d",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/f67ff8b4f9f9deb5d72e682e3ba54c32191ac75d",
"gitiles_ref:refs/heads/master"
]
}
[11:06:17.402] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1544007975796187",
"id": "8927984008730218624",
"lease_expiration_ts": "1544126777030305",
"lease_key": "786247338",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"f67ff8b4f9f9deb5d72e682e3ba54c32191ac75d\"}}",
"project": "chromium",
"result_details_json": "null",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1544007977287204",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/10969",
"builder:Windows deterministic",
"buildset:commit/git/f67ff8b4f9f9deb5d72e682e3ba54c32191ac75d",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/f67ff8b4f9f9deb5d72e682e3ba54c32191ac75d",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9093414070685922608",
"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/10969",
"swarming_tag:buildbucket_bucket:chromium/ci",
"swarming_tag:buildbucket_build_id:8927984008730218624",
"swarming_tag:buildbucket_hostname:cr-buildbucket.appspot.com",
"swarming_tag:buildbucket_template_canary:0",
"swarming_tag:buildbucket_template_revision:2f19301aebbc779351dc95ab1dc03603fa761240",
"swarming_tag:builder:Windows deterministic",
"swarming_tag:buildset:commit/git/f67ff8b4f9f9deb5d72e682e3ba54c32191ac75d",
"swarming_tag:buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/f67ff8b4f9f9deb5d72e682e3ba54c32191ac75d",
"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/8927984008730218624/+/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:9093414070685922608",
"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:d8eb52bb067d01743c45e7ff3aaecf9d9b3c0c38",
"swarming_tag:user:None",
"swarming_tag:user_agent:luci-scheduler",
"swarming_tag:vpython:native-python-wrapper",
"swarming_task_id:4196dd8e3acaa810",
"user_agent:luci-scheduler"
],
"updated_ts": "1544007977287305",
"url": "https://ci.chromium.org/b/8927984008730218624",
"utcnow_ts": "1544007977386557"
}
}
[11:06:17.402] Task URL: https://ci.chromium.org/b/8927984008730218624
[11:06:17.402] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093414070685922608:2:0) after 1m0s
[11:07:17.417] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093414070685922608:2:0)
[11:07:17.417] Timer tick, asking Buildbucket for the build status
[11:07:17.563] Build 8927984008730218624: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:07:17.563] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093414070685922608:3:0) after 1m0s
[11:08:17.613] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093414070685922608:3:0)
[11:08:17.613] Timer tick, asking Buildbucket for the build status
[11:08:17.746] Build 8927984008730218624: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:08:17.746] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093414070685922608:4:0) after 1m0s
[11:08:34.871] Received PubSub notification, asking Buildbucket for the build status
[11:08:34.924] Build 8927984008730218624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:09:17.767] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093414070685922608:4:0)
[11:09:17.767] Timer tick, asking Buildbucket for the build status
[11:09:17.902] Build 8927984008730218624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:09:17.902] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093414070685922608:6:0) after 1m0s
[11:10:17.933] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093414070685922608:6:0)
[11:10:17.933] Timer tick, asking Buildbucket for the build status
[11:10:18.101] Build 8927984008730218624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:10:18.101] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093414070685922608:7:0) after 1m0s
[11:11:18.135] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093414070685922608:7:0)
[11:11:18.135] Timer tick, asking Buildbucket for the build status
[11:11:18.176] Build 8927984008730218624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:11:18.177] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093414070685922608:8:0) after 1m0s
[11:12:18.198] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093414070685922608:8:0)
[11:12:18.198] Timer tick, asking Buildbucket for the build status
[11:12:18.259] Build 8927984008730218624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:12:18.259] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093414070685922608:9:0) after 1m0s
[11:13:18.277] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093414070685922608:9:0)
[11:13:18.277] Timer tick, asking Buildbucket for the build status
[11:13:18.364] Build 8927984008730218624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:13:18.364] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093414070685922608:10:0) after 1m0s
[11:14:18.476] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093414070685922608:10:0)
[11:14:18.476] Timer tick, asking Buildbucket for the build status
[11:14:18.531] Build 8927984008730218624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:14:18.531] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093414070685922608:11:0) after 1m0s
[11:15:18.551] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093414070685922608:11:0)
[11:15:18.551] Timer tick, asking Buildbucket for the build status
[11:15:18.586] Build 8927984008730218624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:15:18.586] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093414070685922608:12:0) after 1m0s
[11:16:18.625] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093414070685922608:12:0)
[11:16:18.625] Timer tick, asking Buildbucket for the build status
[11:16:18.662] Build 8927984008730218624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:16:18.662] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093414070685922608:13:0) after 1m0s
[11:17:18.686] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093414070685922608:13:0)
[11:17:18.686] Timer tick, asking Buildbucket for the build status
[11:17:18.729] Build 8927984008730218624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:17:18.729] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093414070685922608:14:0) after 1m0s
[11:18:18.780] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093414070685922608:14:0)
[11:18:18.780] Timer tick, asking Buildbucket for the build status
[11:18:18.882] Build 8927984008730218624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:18:18.882] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093414070685922608:15:0) after 1m0s
[11:19:18.978] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093414070685922608:15:0)
[11:19:18.978] Timer tick, asking Buildbucket for the build status
[11:19:19.026] Build 8927984008730218624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:19:19.026] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093414070685922608:16:0) after 1m0s
[11:20:19.045] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093414070685922608:16:0)
[11:20:19.045] Timer tick, asking Buildbucket for the build status
[11:20:19.202] Build 8927984008730218624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:20:19.202] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093414070685922608:17:0) after 1m0s
[11:21:19.220] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093414070685922608:17:0)
[11:21:19.220] Timer tick, asking Buildbucket for the build status
[11:21:19.275] Build 8927984008730218624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:21:19.275] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093414070685922608:18:0) after 1m0s
[11:22:19.295] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093414070685922608:18:0)
[11:22:19.295] Timer tick, asking Buildbucket for the build status
[11:22:19.349] Build 8927984008730218624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:22:19.349] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093414070685922608:19:0) after 1m0s
[11:23:19.367] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093414070685922608:19:0)
[11:23:19.367] Timer tick, asking Buildbucket for the build status
[11:23:19.451] Build 8927984008730218624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:23:19.451] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093414070685922608:20:0) after 1m0s
[11:24:19.469] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093414070685922608:20:0)
[11:24:19.469] Timer tick, asking Buildbucket for the build status
[11:24:19.501] Build 8927984008730218624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:24:19.501] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093414070685922608:21:0) after 1m0s
[11:25:19.525] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093414070685922608:21:0)
[11:25:19.525] Timer tick, asking Buildbucket for the build status
[11:25:19.582] Build 8927984008730218624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:25:19.582] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093414070685922608:22:0) after 1m0s
[11:26:19.613] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093414070685922608:22:0)
[11:26:19.613] Timer tick, asking Buildbucket for the build status
[11:26:19.647] Build 8927984008730218624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:26:19.647] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093414070685922608:23:0) after 1m0s
[11:27:19.668] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093414070685922608:23:0)
[11:27:19.668] Timer tick, asking Buildbucket for the build status
[11:27:19.721] Build 8927984008730218624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:27:19.721] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093414070685922608:24:0) after 1m0s
[11:28:19.910] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093414070685922608:24:0)
[11:28:19.910] Timer tick, asking Buildbucket for the build status
[11:28:20.018] Build 8927984008730218624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:28:20.018] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093414070685922608:25:0) after 1m0s
[11:29:20.077] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093414070685922608:25:0)
[11:29:20.077] Timer tick, asking Buildbucket for the build status
[11:29:20.224] Build 8927984008730218624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:29:20.224] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093414070685922608:26:0) after 1m0s
[11:30:20.245] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093414070685922608:26:0)
[11:30:20.245] Timer tick, asking Buildbucket for the build status
[11:30:20.297] Build 8927984008730218624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:30:20.297] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093414070685922608:27:0) after 1m0s
[11:31:20.314] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093414070685922608:27:0)
[11:31:20.314] Timer tick, asking Buildbucket for the build status
[11:31:20.354] Build 8927984008730218624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:31:20.354] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093414070685922608:28:0) after 1m0s
[11:32:20.377] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093414070685922608:28:0)
[11:32:20.377] Timer tick, asking Buildbucket for the build status
[11:32:20.427] Build 8927984008730218624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:32:20.427] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093414070685922608:29:0) after 1m0s
[11:33:20.447] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093414070685922608:29:0)
[11:33:20.447] Timer tick, asking Buildbucket for the build status
[11:33:20.538] Build 8927984008730218624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:33:20.538] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093414070685922608:30:0) after 1m0s
[11:34:20.556] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093414070685922608:30:0)
[11:34:20.556] Timer tick, asking Buildbucket for the build status
[11:34:20.616] Build 8927984008730218624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:34:20.616] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093414070685922608:31:0) after 1m0s
[11:35:20.634] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093414070685922608:31:0)
[11:35:20.634] Timer tick, asking Buildbucket for the build status
[11:35:20.693] Build 8927984008730218624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:35:20.693] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093414070685922608:32:0) after 1m0s
[11:36:20.709] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093414070685922608:32:0)
[11:36:20.709] Timer tick, asking Buildbucket for the build status
[11:36:20.767] Build 8927984008730218624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:36:20.767] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093414070685922608:33:0) after 1m0s
[11:37:20.798] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093414070685922608:33:0)
[11:37:20.798] Timer tick, asking Buildbucket for the build status
[11:37:20.884] Build 8927984008730218624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:37:20.884] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093414070685922608:34:0) after 1m0s
[11:38:20.936] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093414070685922608:34:0)
[11:38:20.936] Timer tick, asking Buildbucket for the build status
[11:38:20.991] Build 8927984008730218624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:38:20.991] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093414070685922608:35:0) after 1m0s
[11:39:21.043] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093414070685922608:35:0)
[11:39:21.043] Timer tick, asking Buildbucket for the build status
[11:39:21.121] Build 8927984008730218624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:39:21.121] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093414070685922608:36:0) after 1m0s
[11:40:21.139] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093414070685922608:36:0)
[11:40:21.139] Timer tick, asking Buildbucket for the build status
[11:40:21.204] Build 8927984008730218624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:40:21.204] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093414070685922608:37:0) after 1m0s
[11:41:21.224] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093414070685922608:37:0)
[11:41:21.224] Timer tick, asking Buildbucket for the build status
[11:41:21.328] Build 8927984008730218624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:41:21.328] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093414070685922608:38:0) after 1m0s
[11:42:21.351] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093414070685922608:38:0)
[11:42:21.351] Timer tick, asking Buildbucket for the build status
[11:42:21.403] Build 8927984008730218624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:42:21.403] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093414070685922608:39:0) after 1m0s
[11:43:21.430] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093414070685922608:39:0)
[11:43:21.430] Timer tick, asking Buildbucket for the build status
[11:43:21.549] Build 8927984008730218624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:43:21.549] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093414070685922608:40:0) after 1m0s
[11:44:21.577] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093414070685922608:40:0)
[11:44:21.577] Timer tick, asking Buildbucket for the build status
[11:44:21.655] Build 8927984008730218624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:44:21.655] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093414070685922608:41:0) after 1m0s
[11:45:21.679] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093414070685922608:41:0)
[11:45:21.679] Timer tick, asking Buildbucket for the build status
[11:45:21.730] Build 8927984008730218624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:45:21.730] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093414070685922608:42:0) after 1m0s
[11:46:21.751] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093414070685922608:42:0)
[11:46:21.751] Timer tick, asking Buildbucket for the build status
[11:46:21.864] Build 8927984008730218624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:46:21.864] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093414070685922608:43:0) after 1m0s
[11:47:21.886] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093414070685922608:43:0)
[11:47:21.886] Timer tick, asking Buildbucket for the build status
[11:47:21.943] Build 8927984008730218624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:47:21.943] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093414070685922608:44:0) after 1m0s
[11:48:21.992] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093414070685922608:44:0)
[11:48:21.992] Timer tick, asking Buildbucket for the build status
[11:48:22.059] Build 8927984008730218624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:48:22.059] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093414070685922608:45:0) after 1m0s
[11:49:22.077] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093414070685922608:45:0)
[11:49:22.077] Timer tick, asking Buildbucket for the build status
[11:49:22.133] Build 8927984008730218624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:49:22.133] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093414070685922608:46:0) after 1m0s
[11:50:22.154] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093414070685922608:46:0)
[11:50:22.154] Timer tick, asking Buildbucket for the build status
[11:50:22.221] Build 8927984008730218624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:50:22.221] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093414070685922608:47:0) after 1m0s
[11:51:22.239] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093414070685922608:47:0)
[11:51:22.239] Timer tick, asking Buildbucket for the build status
[11:51:22.310] Build 8927984008730218624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:51:22.310] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093414070685922608:48:0) after 1m0s
[11:52:22.343] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093414070685922608:48:0)
[11:52:22.343] Timer tick, asking Buildbucket for the build status
[11:52:22.412] Build 8927984008730218624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:52:22.412] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093414070685922608:49:0) after 1m0s
[11:53:22.435] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093414070685922608:49:0)
[11:53:22.435] Timer tick, asking Buildbucket for the build status
[11:53:22.671] Build 8927984008730218624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:53:22.671] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093414070685922608:50:0) after 1m0s
[11:54:22.725] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093414070685922608:50:0)
[11:54:22.725] Timer tick, asking Buildbucket for the build status
[11:54:22.808] Build 8927984008730218624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:54:22.808] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093414070685922608:51:0) after 1m0s
[11:55:22.827] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093414070685922608:51:0)
[11:55:22.827] Timer tick, asking Buildbucket for the build status
[11:55:22.863] Build 8927984008730218624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:55:22.863] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093414070685922608:52:0) after 1m0s
[11:56:22.883] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093414070685922608:52:0)
[11:56:22.883] Timer tick, asking Buildbucket for the build status
[11:56:22.934] Build 8927984008730218624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:56:22.934] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093414070685922608:53:0) after 1m0s
[11:57:22.966] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093414070685922608:53:0)
[11:57:22.966] Timer tick, asking Buildbucket for the build status
[11:57:23.031] Build 8927984008730218624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:57:23.031] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093414070685922608:54:0) after 1m0s
[11:58:23.052] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093414070685922608:54:0)
[11:58:23.052] Timer tick, asking Buildbucket for the build status
[11:58:23.113] Build 8927984008730218624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:58:23.113] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093414070685922608:55:0) after 1m0s
[11:59:23.134] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093414070685922608:55:0)
[11:59:23.134] Timer tick, asking Buildbucket for the build status
[11:59:23.166] Build 8927984008730218624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:59:23.166] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093414070685922608:56:0) after 1m0s
[12:00:23.187] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093414070685922608:56:0)
[12:00:23.187] Timer tick, asking Buildbucket for the build status
[12:00:23.257] Build 8927984008730218624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:00:23.257] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093414070685922608:57:0) after 1m0s
[12:01:23.276] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093414070685922608:57:0)
[12:01:23.276] Timer tick, asking Buildbucket for the build status
[12:01:23.562] Build 8927984008730218624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:01:23.562] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093414070685922608:58:0) after 1m0s
[12:02:23.603] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093414070685922608:58:0)
[12:02:23.603] Timer tick, asking Buildbucket for the build status
[12:02:23.657] Build 8927984008730218624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:02:23.657] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093414070685922608:59:0) after 1m0s
[12:03:23.683] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093414070685922608:59:0)
[12:03:23.683] Timer tick, asking Buildbucket for the build status
[12:03:23.728] Build 8927984008730218624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:03:23.728] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093414070685922608:60:0) after 1m0s
[12:04:23.754] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093414070685922608:60:0)
[12:04:23.754] Timer tick, asking Buildbucket for the build status
[12:04:23.786] Build 8927984008730218624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:04:23.786] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093414070685922608:61:0) after 1m0s
[12:05:23.810] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093414070685922608:61:0)
[12:05:23.810] Timer tick, asking Buildbucket for the build status
[12:05:23.864] Build 8927984008730218624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:05:23.864] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093414070685922608:62:0) after 1m0s
[12:06:23.907] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093414070685922608:62:0)
[12:06:23.907] Timer tick, asking Buildbucket for the build status
[12:06:23.960] Build 8927984008730218624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:06:23.960] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093414070685922608:63:0) after 1m0s
[12:07:23.996] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093414070685922608:63:0)
[12:07:23.996] Timer tick, asking Buildbucket for the build status
[12:07:24.196] Build 8927984008730218624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:07:24.196] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093414070685922608:64:0) after 1m0s
[12:08:24.216] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093414070685922608:64:0)
[12:08:24.216] Timer tick, asking Buildbucket for the build status
[12:08:24.272] Build 8927984008730218624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:08:24.272] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093414070685922608:65:0) after 1m0s
[12:09:24.292] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093414070685922608:65:0)
[12:09:24.292] Timer tick, asking Buildbucket for the build status
[12:09:24.358] Build 8927984008730218624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:09:24.358] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093414070685922608:66:0) after 1m0s
[12:10:24.378] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093414070685922608:66:0)
[12:10:24.378] Timer tick, asking Buildbucket for the build status
[12:10:24.456] Build 8927984008730218624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:10:24.456] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093414070685922608:67:0) after 1m0s
[12:11:24.478] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093414070685922608:67:0)
[12:11:24.478] Timer tick, asking Buildbucket for the build status
[12:11:24.523] Build 8927984008730218624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:11:24.523] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093414070685922608:68:0) after 1m0s
[12:12:24.547] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093414070685922608:68:0)
[12:12:24.547] Timer tick, asking Buildbucket for the build status
[12:12:24.729] Build 8927984008730218624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:12:24.729] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093414070685922608:69:0) after 1m0s
[12:13:24.752] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093414070685922608:69:0)
[12:13:24.752] Timer tick, asking Buildbucket for the build status
[12:13:24.815] Build 8927984008730218624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:13:24.815] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093414070685922608:70:0) after 1m0s
[12:14:24.847] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093414070685922608:70:0)
[12:14:24.847] Timer tick, asking Buildbucket for the build status
[12:14:24.885] Build 8927984008730218624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:14:24.885] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093414070685922608:71:0) after 1m0s
[12:15:24.906] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093414070685922608:71:0)
[12:15:24.906] Timer tick, asking Buildbucket for the build status
[12:15:24.976] Build 8927984008730218624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:15:24.976] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093414070685922608:72:0) after 1m0s
[12:16:25.000] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093414070685922608:72:0)
[12:16:25.000] Timer tick, asking Buildbucket for the build status
[12:16:25.124] Build 8927984008730218624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:16:25.124] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093414070685922608:73:0) after 1m0s
[12:17:25.146] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093414070685922608:73:0)
[12:17:25.146] Timer tick, asking Buildbucket for the build status
[12:17:25.212] Build 8927984008730218624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:17:25.212] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093414070685922608:74:0) after 1m0s
[12:18:25.237] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093414070685922608:74:0)
[12:18:25.237] Timer tick, asking Buildbucket for the build status
[12:18:25.373] Build 8927984008730218624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:18:25.373] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093414070685922608:75:0) after 1m0s
[12:19:25.421] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093414070685922608:75:0)
[12:19:25.421] Timer tick, asking Buildbucket for the build status
[12:19:25.523] Build 8927984008730218624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:19:25.523] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093414070685922608:76:0) after 1m0s
[12:20:25.544] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093414070685922608:76:0)
[12:20:25.544] Timer tick, asking Buildbucket for the build status
[12:20:25.581] Build 8927984008730218624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:20:25.581] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093414070685922608:77:0) after 1m0s
[12:21:25.599] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093414070685922608:77:0)
[12:21:25.599] Timer tick, asking Buildbucket for the build status
[12:21:25.664] Build 8927984008730218624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:21:25.664] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093414070685922608:78:0) after 1m0s
[12:22:25.700] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093414070685922608:78:0)
[12:22:25.700] Timer tick, asking Buildbucket for the build status
[12:22:25.781] Build 8927984008730218624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:22:25.781] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093414070685922608:79:0) after 1m0s
[12:23:25.801] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093414070685922608:79:0)
[12:23:25.801] Timer tick, asking Buildbucket for the build status
[12:23:25.910] Build 8927984008730218624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:23:25.910] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093414070685922608:80:0) after 1m0s
[12:24:25.988] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093414070685922608:80:0)
[12:24:25.988] Timer tick, asking Buildbucket for the build status
[12:24:26.047] Build 8927984008730218624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:24:26.048] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093414070685922608:81:0) after 1m0s
[12:25:26.077] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093414070685922608:81:0)
[12:25:26.077] Timer tick, asking Buildbucket for the build status
[12:25:26.129] Build 8927984008730218624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:25:26.129] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093414070685922608:82:0) after 1m0s
[12:26:26.154] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093414070685922608:82:0)
[12:26:26.154] Timer tick, asking Buildbucket for the build status
[12:26:26.280] Build 8927984008730218624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:26:26.280] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093414070685922608:83:0) after 1m0s
[12:27:26.302] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093414070685922608:83:0)
[12:27:26.302] Timer tick, asking Buildbucket for the build status
[12:27:26.371] Build 8927984008730218624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:27:26.371] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093414070685922608:84:0) after 1m0s
[12:28:26.389] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093414070685922608:84:0)
[12:28:26.389] Timer tick, asking Buildbucket for the build status
[12:28:26.476] Build 8927984008730218624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:28:26.476] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093414070685922608:85:0) after 1m0s
[12:29:26.537] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093414070685922608:85:0)
[12:29:26.537] Timer tick, asking Buildbucket for the build status
[12:29:26.579] Build 8927984008730218624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:29:26.579] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093414070685922608:86:0) after 1m0s
[12:30:26.598] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093414070685922608:86:0)
[12:30:26.598] Timer tick, asking Buildbucket for the build status
[12:30:26.812] Build 8927984008730218624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:30:26.812] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093414070685922608:87:0) after 1m0s
[12:31:26.845] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093414070685922608:87:0)
[12:31:26.845] Timer tick, asking Buildbucket for the build status
[12:31:26.907] Build 8927984008730218624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:31:26.907] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093414070685922608:88:0) after 1m0s
[12:32:26.926] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093414070685922608:88:0)
[12:32:26.926] Timer tick, asking Buildbucket for the build status
[12:32:26.976] Build 8927984008730218624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:32:26.976] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093414070685922608:89:0) after 1m0s
[12:33:27.004] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093414070685922608:89:0)
[12:33:27.004] Timer tick, asking Buildbucket for the build status
[12:33:27.093] Build 8927984008730218624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:33:27.093] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093414070685922608:90:0) after 1m0s
[12:34:27.110] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093414070685922608:90:0)
[12:34:27.110] Timer tick, asking Buildbucket for the build status
[12:34:27.233] Build 8927984008730218624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:34:27.233] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093414070685922608:91:0) after 1m0s
[12:35:27.308] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093414070685922608:91:0)
[12:35:27.308] Timer tick, asking Buildbucket for the build status
[12:35:27.361] Build 8927984008730218624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:35:27.361] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093414070685922608:92:0) after 1m0s
[12:36:27.426] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093414070685922608:92:0)
[12:36:27.426] Timer tick, asking Buildbucket for the build status
[12:36:27.564] Build 8927984008730218624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:36:27.564] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093414070685922608:93:0) after 1m0s
[12:37:27.588] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093414070685922608:93:0)
[12:37:27.588] Timer tick, asking Buildbucket for the build status
[12:37:27.686] Build 8927984008730218624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:37:27.686] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093414070685922608:94:0) after 1m0s
[12:38:27.716] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093414070685922608:94:0)
[12:38:27.716] Timer tick, asking Buildbucket for the build status
[12:38:27.772] Build 8927984008730218624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:38:27.772] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093414070685922608:95:0) after 1m0s
[12:39:27.795] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093414070685922608:95:0)
[12:39:27.795] Timer tick, asking Buildbucket for the build status
[12:39:27.864] Build 8927984008730218624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:39:27.864] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093414070685922608:96:0) after 1m0s
[12:40:27.886] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093414070685922608:96:0)
[12:40:27.886] Timer tick, asking Buildbucket for the build status
[12:40:27.967] Build 8927984008730218624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:40:27.967] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093414070685922608:97:0) after 1m0s
[12:41:27.990] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093414070685922608:97:0)
[12:41:27.990] Timer tick, asking Buildbucket for the build status
[12:41:28.028] Build 8927984008730218624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:41:28.028] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093414070685922608:98:0) after 1m0s
[12:42:28.055] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093414070685922608:98:0)
[12:42:28.055] Timer tick, asking Buildbucket for the build status
[12:42:28.140] Build 8927984008730218624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:42:28.140] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093414070685922608:99:0) after 1m0s
[12:43:28.177] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093414070685922608:99:0)
[12:43:28.177] Timer tick, asking Buildbucket for the build status
[12:43:28.259] Build 8927984008730218624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:43:28.259] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093414070685922608:100:0) after 1m0s
[12:44:28.295] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093414070685922608:100:0)
[12:44:28.295] Timer tick, asking Buildbucket for the build status
[12:44:28.414] Build 8927984008730218624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:44:28.414] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093414070685922608:101:0) after 1m0s
[12:45:28.493] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093414070685922608:101:0)
[12:45:28.493] Timer tick, asking Buildbucket for the build status
[12:45:28.826] Build 8927984008730218624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:45:28.826] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093414070685922608:102:0) after 1m0s
[12:46:28.861] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093414070685922608:102:0)
[12:46:28.861] Timer tick, asking Buildbucket for the build status
[12:46:28.904] Build 8927984008730218624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:46:28.904] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093414070685922608:103:0) after 1m0s
[12:47:28.982] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093414070685922608:103:0)
[12:47:28.982] Timer tick, asking Buildbucket for the build status
[12:47:29.044] Build 8927984008730218624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:47:29.044] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093414070685922608:104:0) after 1m0s
[12:48:29.067] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093414070685922608:104:0)
[12:48:29.067] Timer tick, asking Buildbucket for the build status
[12:48:29.148] Build 8927984008730218624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:48:29.148] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093414070685922608:105:0) after 1m0s
[12:49:29.171] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093414070685922608:105:0)
[12:49:29.171] Timer tick, asking Buildbucket for the build status
[12:49:29.249] Build 8927984008730218624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:49:29.249] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093414070685922608:106:0) after 1m0s
[12:50:29.270] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093414070685922608:106:0)
[12:50:29.270] Timer tick, asking Buildbucket for the build status
[12:50:29.358] Build 8927984008730218624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:50:29.358] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093414070685922608:107:0) after 1m0s
[12:51:29.489] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093414070685922608:107:0)
[12:51:29.489] Timer tick, asking Buildbucket for the build status
[12:51:29.626] Build 8927984008730218624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:51:29.626] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093414070685922608:108:0) after 1m0s
[12:52:29.649] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093414070685922608:108:0)
[12:52:29.649] Timer tick, asking Buildbucket for the build status
[12:52:29.710] Build 8927984008730218624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:52:29.710] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093414070685922608:109:0) after 1m0s
[12:53:29.738] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093414070685922608:109:0)
[12:53:29.738] Timer tick, asking Buildbucket for the build status
[12:53:29.812] Build 8927984008730218624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:53:29.812] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093414070685922608:110:0) after 1m0s
[12:54:29.929] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093414070685922608:110:0)
[12:54:29.929] Timer tick, asking Buildbucket for the build status
[12:54:29.983] Build 8927984008730218624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:54:29.983] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093414070685922608:111:0) after 1m0s
[12:54:39.200] Received PubSub notification, asking Buildbucket for the build status
[12:54:39.260] Build 8927984008730218624: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[12:54:39.260] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"completed_ts": "1544014478009520",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1544007975796187",
"failure_reason": "BUILD_FAILURE",
"id": "8927984008730218624",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"f67ff8b4f9f9deb5d72e682e3ba54c32191ac75d\"}}",
"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\": \"277022e0e51ae646448daf2d36a81502b8ac53b7\", \"got_swarming_client_revision\": \"0e3e1c4dc4e79f25a5b58fcbc135dc93183c0c54\", \"got_revision\": \"f67ff8b4f9f9deb5d72e682e3ba54c32191ac75d\", \"recipe\": \"swarming/deterministic_build\", \"got_dawn_revision\": \"66b024e499a11e2b98630d01c34543bd71f5c3c2\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#25898}\", \"$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@{#613934}\", \"branch\": \"refs/heads/master\", \"revision\": \"f67ff8b4f9f9deb5d72e682e3ba54c32191ac75d\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"buildername\": \"Windows deterministic\", \"got_webrtc_revision\": \"a713505c1888b86684054656e3d8801c8057c8d4\", \"mastername\": \"chromium.win\", \"got_angle_revision\": \"81a880aaeb5757a0199d21ed4835bb1964f8ad80\", \"buildbucket\": {\"hostname\": \"cr-buildbucket.appspot.com\", \"build\": {\"created_ts\": 1544007975796187, \"tags\": [\"builder:Windows deterministic\", \"buildset:commit/git/f67ff8b4f9f9deb5d72e682e3ba54c32191ac75d\", \"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/f67ff8b4f9f9deb5d72e682e3ba54c32191ac75d\", \"gitiles_ref:refs/heads/master\", \"scheduler_invocation_id:9093414070685922608\", \"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\": \"8927984008730218624\"}}, \"got_v8_revision\": \"a6b60a5ee6c631357bbfad3ae2295aae71dbaea5\", \"got_v8_revision_cp\": \"refs/heads/7.3.27@{#1}\", \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"buildnumber\": 10969, \"path_config\": \"generic\", \"bot_id\": \"swarm2173-c4\", \"got_buildtools_revision\": \"04161ec8d7c781e4498c699254c69ba0dd959fde\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1544008114184934",
"status": "COMPLETED",
"status_changed_ts": "1544014478895351",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/10969",
"builder:Windows deterministic",
"buildset:commit/git/f67ff8b4f9f9deb5d72e682e3ba54c32191ac75d",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/f67ff8b4f9f9deb5d72e682e3ba54c32191ac75d",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9093414070685922608",
"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/10969",
"swarming_tag:buildbucket_bucket:chromium/ci",
"swarming_tag:buildbucket_build_id:8927984008730218624",
"swarming_tag:buildbucket_hostname:cr-buildbucket.appspot.com",
"swarming_tag:buildbucket_template_canary:0",
"swarming_tag:buildbucket_template_revision:2f19301aebbc779351dc95ab1dc03603fa761240",
"swarming_tag:builder:Windows deterministic",
"swarming_tag:buildset:commit/git/f67ff8b4f9f9deb5d72e682e3ba54c32191ac75d",
"swarming_tag:buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/f67ff8b4f9f9deb5d72e682e3ba54c32191ac75d",
"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/8927984008730218624/+/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:9093414070685922608",
"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:d8eb52bb067d01743c45e7ff3aaecf9d9b3c0c38",
"swarming_tag:user:None",
"swarming_tag:user_agent:luci-scheduler",
"swarming_tag:vpython:native-python-wrapper",
"swarming_task_id:4196dd8e3acaa810",
"user_agent:luci-scheduler"
],
"updated_ts": "1544014478897230",
"url": "https://ci.chromium.org/b/8927984008730218624",
"utcnow_ts": "1544014479248380"
}
}
[12:54:39.260] Invocation finished in 1h48m24.713402194s with status FAILED