[12:59:24.197] New invocation is queued and will start shortly
[12:59:25.564] Starting the invocation (attempt 1)
[12:59:25.596] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[12:59:25.596] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[12:59:25.596] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9102013663046486400",
"parameters_json": "{\"builder_name\":\"Windows deterministic\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src.git\",\"revision\":\"882608436a64c2a0d03ddc87e26aebe33f95db1b\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Windows deterministic",
"scheduler_invocation_id:9102013663046486400",
"scheduler_job_id:chromium/Windows deterministic",
"user_agent:luci-scheduler",
"buildset:commit/git/882608436a64c2a0d03ddc87e26aebe33f95db1b",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/882608436a64c2a0d03ddc87e26aebe33f95db1b",
"gitiles_ref:refs/heads/master"
]
}
[12:59:26.928] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1535806765670460",
"id": "8936583600839409408",
"lease_expiration_ts": "1535950766600460",
"lease_key": "1885049304",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"882608436a64c2a0d03ddc87e26aebe33f95db1b\"}}",
"project": "chromium",
"result_details_json": "null",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1535806766839300",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/9944",
"builder:Windows deterministic",
"buildset:commit/git/882608436a64c2a0d03ddc87e26aebe33f95db1b",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/882608436a64c2a0d03ddc87e26aebe33f95db1b",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9102013663046486400",
"scheduler_job_id:chromium/Windows deterministic",
"swarming_dimension:builder:Windows deterministic",
"swarming_dimension:caches:builder_d795d6e07fe37a299fcfc0e872e48757ad306282f024125f803c0465bfd1eeae_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/9944",
"swarming_tag:buildbucket_bucket:luci.chromium.ci",
"swarming_tag:buildbucket_build_id:8936583600839409408",
"swarming_tag:buildbucket_hostname:cr-buildbucket.appspot.com",
"swarming_tag:buildbucket_template_canary:0",
"swarming_tag:buildbucket_template_revision:ca2c7a741e04d0385e2bf62941ac932dc0521af3",
"swarming_tag:builder:Windows deterministic",
"swarming_tag:buildset:commit/git/882608436a64c2a0d03ddc87e26aebe33f95db1b",
"swarming_tag:buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/882608436a64c2a0d03ddc87e26aebe33f95db1b",
"swarming_tag:caches:builder_d795d6e07fe37a299fcfc0e872e48757ad306282f024125f803c0465bfd1eeae_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/8936583600839409408/+/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:9102013663046486400",
"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:7a77c69d050faf57eaf65e8f6cb7422326b6b83c",
"swarming_tag:user:None",
"swarming_tag:user_agent:luci-scheduler",
"swarming_tag:vpython:native-python-wrapper",
"swarming_task_id:3fae0904fec70610",
"user_agent:luci-scheduler"
],
"updated_ts": "1535806766839390",
"url": "https://ci.chromium.org/p/chromium/builders/luci.chromium.ci/Windows deterministic/9944",
"utcnow_ts": "1535806766914810"
}
}
[12:59:26.928] Task URL: https://ci.chromium.org/p/chromium/builders/luci.chromium.ci/Windows deterministic/9944
[12:59:26.928] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102013663046486400:2:0) after 1m0s
[13:00:26.947] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102013663046486400:2:0)
[13:00:26.947] Timer tick, asking Buildbucket for the build status
[13:00:27.020] Build 8936583600839409408: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:00:27.020] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102013663046486400:3:0) after 1m0s
[13:00:57.097] Received PubSub notification, asking Buildbucket for the build status
[13:00:57.160] Build 8936583600839409408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:01:27.040] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102013663046486400:3:0)
[13:01:27.040] Timer tick, asking Buildbucket for the build status
[13:01:27.081] Build 8936583600839409408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:01:27.081] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102013663046486400:5:0) after 1m0s
[13:02:27.100] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102013663046486400:5:0)
[13:02:27.100] Timer tick, asking Buildbucket for the build status
[13:02:27.158] Build 8936583600839409408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:02:27.158] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102013663046486400:6:0) after 1m0s
[13:03:27.188] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102013663046486400:6:0)
[13:03:27.188] Timer tick, asking Buildbucket for the build status
[13:03:27.252] Build 8936583600839409408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:03:27.252] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102013663046486400:7:0) after 1m0s
[13:04:27.328] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102013663046486400:7:0)
[13:04:27.328] Timer tick, asking Buildbucket for the build status
[13:04:27.525] Build 8936583600839409408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:04:27.525] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102013663046486400:8:0) after 1m0s
[13:05:27.566] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102013663046486400:8:0)
[13:05:27.566] Timer tick, asking Buildbucket for the build status
[13:05:27.656] Build 8936583600839409408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:05:27.656] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102013663046486400:9:0) after 1m0s
[13:06:27.673] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102013663046486400:9:0)
[13:06:27.673] Timer tick, asking Buildbucket for the build status
[13:06:27.729] Build 8936583600839409408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:06:27.729] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102013663046486400:10:0) after 1m0s
[13:07:27.792] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102013663046486400:10:0)
[13:07:27.792] Timer tick, asking Buildbucket for the build status
[13:07:27.947] Build 8936583600839409408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:07:27.947] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102013663046486400:11:0) after 1m0s
[13:08:28.031] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102013663046486400:11:0)
[13:08:28.031] Timer tick, asking Buildbucket for the build status
[13:08:28.086] Build 8936583600839409408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:08:28.086] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102013663046486400:12:0) after 1m0s
[13:09:28.107] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102013663046486400:12:0)
[13:09:28.107] Timer tick, asking Buildbucket for the build status
[13:09:28.167] Build 8936583600839409408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:09:28.181] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102013663046486400:13:0) after 1m0s
[13:10:28.202] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102013663046486400:13:0)
[13:10:28.202] Timer tick, asking Buildbucket for the build status
[13:10:28.320] Build 8936583600839409408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:10:28.320] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102013663046486400:14:0) after 1m0s
[13:11:28.341] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102013663046486400:14:0)
[13:11:28.341] Timer tick, asking Buildbucket for the build status
[13:11:28.378] Build 8936583600839409408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:11:28.378] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102013663046486400:15:0) after 1m0s
[13:12:28.404] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102013663046486400:15:0)
[13:12:28.404] Timer tick, asking Buildbucket for the build status
[13:12:28.490] Build 8936583600839409408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:12:28.490] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102013663046486400:16:0) after 1m0s
[13:13:28.508] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102013663046486400:16:0)
[13:13:28.508] Timer tick, asking Buildbucket for the build status
[13:13:28.626] Build 8936583600839409408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:13:28.626] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102013663046486400:17:0) after 1m0s
[13:14:28.644] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102013663046486400:17:0)
[13:14:28.644] Timer tick, asking Buildbucket for the build status
[13:14:28.677] Build 8936583600839409408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:14:28.677] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102013663046486400:18:0) after 1m0s
[13:15:28.711] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102013663046486400:18:0)
[13:15:28.711] Timer tick, asking Buildbucket for the build status
[13:15:28.776] Build 8936583600839409408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:15:28.776] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102013663046486400:19:0) after 1m0s
[13:16:28.792] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102013663046486400:19:0)
[13:16:28.792] Timer tick, asking Buildbucket for the build status
[13:16:28.849] Build 8936583600839409408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:16:28.849] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102013663046486400:20:0) after 1m0s
[13:17:28.866] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102013663046486400:20:0)
[13:17:28.866] Timer tick, asking Buildbucket for the build status
[13:17:28.957] Build 8936583600839409408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:17:28.957] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102013663046486400:21:0) after 1m0s
[13:18:28.977] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102013663046486400:21:0)
[13:18:28.977] Timer tick, asking Buildbucket for the build status
[13:18:29.077] Build 8936583600839409408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:18:29.077] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102013663046486400:22:0) after 1m0s
[13:19:29.094] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102013663046486400:22:0)
[13:19:29.094] Timer tick, asking Buildbucket for the build status
[13:19:29.157] Build 8936583600839409408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:19:29.157] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102013663046486400:23:0) after 1m0s
[13:20:29.189] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102013663046486400:23:0)
[13:20:29.189] Timer tick, asking Buildbucket for the build status
[13:20:29.260] Build 8936583600839409408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:20:29.260] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102013663046486400:24:0) after 1m0s
[13:21:29.279] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102013663046486400:24:0)
[13:21:29.279] Timer tick, asking Buildbucket for the build status
[13:21:29.344] Build 8936583600839409408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:21:29.344] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102013663046486400:25:0) after 1m0s
[13:22:29.361] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102013663046486400:25:0)
[13:22:29.361] Timer tick, asking Buildbucket for the build status
[13:22:29.421] Build 8936583600839409408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:22:29.421] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102013663046486400:26:0) after 1m0s
[13:23:29.440] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102013663046486400:26:0)
[13:23:29.440] Timer tick, asking Buildbucket for the build status
[13:23:29.482] Build 8936583600839409408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:23:29.482] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102013663046486400:27:0) after 1m0s
[13:24:29.500] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102013663046486400:27:0)
[13:24:29.500] Timer tick, asking Buildbucket for the build status
[13:24:29.572] Build 8936583600839409408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:24:29.572] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102013663046486400:28:0) after 1m0s
[13:25:29.599] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102013663046486400:28:0)
[13:25:29.599] Timer tick, asking Buildbucket for the build status
[13:25:29.671] Build 8936583600839409408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:25:29.671] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102013663046486400:29:0) after 1m0s
[13:26:29.692] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102013663046486400:29:0)
[13:26:29.692] Timer tick, asking Buildbucket for the build status
[13:26:29.758] Build 8936583600839409408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:26:29.758] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102013663046486400:30:0) after 1m0s
[13:27:29.778] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102013663046486400:30:0)
[13:27:29.778] Timer tick, asking Buildbucket for the build status
[13:27:29.974] Build 8936583600839409408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:27:29.974] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102013663046486400:31:0) after 1m0s
[13:28:29.991] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102013663046486400:31:0)
[13:28:29.991] Timer tick, asking Buildbucket for the build status
[13:28:30.056] Build 8936583600839409408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:28:30.056] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102013663046486400:32:0) after 1m0s
[13:29:30.125] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102013663046486400:32:0)
[13:29:30.125] Timer tick, asking Buildbucket for the build status
[13:29:30.243] Build 8936583600839409408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:29:30.243] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102013663046486400:33:0) after 1m0s
[13:30:30.265] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102013663046486400:33:0)
[13:30:30.265] Timer tick, asking Buildbucket for the build status
[13:30:30.355] Build 8936583600839409408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:30:30.355] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102013663046486400:34:0) after 1m0s
[13:31:30.376] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102013663046486400:34:0)
[13:31:30.376] Timer tick, asking Buildbucket for the build status
[13:31:30.414] Build 8936583600839409408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:31:30.414] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102013663046486400:35:0) after 1m0s
[13:32:30.432] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102013663046486400:35:0)
[13:32:30.432] Timer tick, asking Buildbucket for the build status
[13:32:30.518] Build 8936583600839409408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:32:30.519] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102013663046486400:36:0) after 1m0s
[13:33:30.540] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102013663046486400:36:0)
[13:33:30.540] Timer tick, asking Buildbucket for the build status
[13:33:30.598] Build 8936583600839409408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:33:30.598] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102013663046486400:37:0) after 1m0s
[13:34:30.673] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102013663046486400:37:0)
[13:34:30.673] Timer tick, asking Buildbucket for the build status
[13:34:30.752] Build 8936583600839409408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:34:30.752] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102013663046486400:38:0) after 1m0s
[13:35:30.771] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102013663046486400:38:0)
[13:35:30.771] Timer tick, asking Buildbucket for the build status
[13:35:30.911] Build 8936583600839409408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:35:30.911] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102013663046486400:39:0) after 1m0s
[13:36:30.934] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102013663046486400:39:0)
[13:36:30.935] Timer tick, asking Buildbucket for the build status
[13:36:31.323] Build 8936583600839409408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:36:31.323] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102013663046486400:40:0) after 1m0s
[13:37:31.344] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102013663046486400:40:0)
[13:37:31.344] Timer tick, asking Buildbucket for the build status
[13:37:31.658] Build 8936583600839409408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:37:31.658] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102013663046486400:41:0) after 1m0s
[13:38:31.685] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102013663046486400:41:0)
[13:38:31.685] Timer tick, asking Buildbucket for the build status
[13:38:31.830] Build 8936583600839409408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:38:31.830] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102013663046486400:42:0) after 1m0s
[13:39:31.869] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102013663046486400:42:0)
[13:39:31.869] Timer tick, asking Buildbucket for the build status
[13:39:31.941] Build 8936583600839409408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:39:31.941] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102013663046486400:43:0) after 1m0s
[13:40:31.959] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102013663046486400:43:0)
[13:40:31.960] Timer tick, asking Buildbucket for the build status
[13:40:32.064] Build 8936583600839409408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:40:32.064] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102013663046486400:44:0) after 1m0s
[13:41:32.083] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102013663046486400:44:0)
[13:41:32.083] Timer tick, asking Buildbucket for the build status
[13:41:32.194] Build 8936583600839409408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:41:32.194] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102013663046486400:45:0) after 1m0s
[13:42:32.219] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102013663046486400:45:0)
[13:42:32.219] Timer tick, asking Buildbucket for the build status
[13:42:32.282] Build 8936583600839409408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:42:32.282] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102013663046486400:46:0) after 1m0s
[13:43:32.300] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102013663046486400:46:0)
[13:43:32.300] Timer tick, asking Buildbucket for the build status
[13:43:32.511] Build 8936583600839409408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:43:32.511] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102013663046486400:47:0) after 1m0s
[13:44:32.532] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102013663046486400:47:0)
[13:44:32.532] Timer tick, asking Buildbucket for the build status
[13:44:32.597] Build 8936583600839409408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:44:32.597] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102013663046486400:48:0) after 1m0s
[13:45:32.616] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102013663046486400:48:0)
[13:45:32.616] Timer tick, asking Buildbucket for the build status
[13:45:32.929] Build 8936583600839409408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:45:32.929] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102013663046486400:49:0) after 1m0s
[13:46:32.948] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102013663046486400:49:0)
[13:46:32.948] Timer tick, asking Buildbucket for the build status
[13:46:32.987] Build 8936583600839409408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:46:32.987] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102013663046486400:50:0) after 1m0s
[13:47:33.007] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102013663046486400:50:0)
[13:47:33.007] Timer tick, asking Buildbucket for the build status
[13:47:33.061] Build 8936583600839409408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:47:33.061] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102013663046486400:51:0) after 1m0s
[13:48:33.094] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102013663046486400:51:0)
[13:48:33.094] Timer tick, asking Buildbucket for the build status
[13:48:33.222] Build 8936583600839409408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:48:33.222] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102013663046486400:52:0) after 1m0s
[13:49:33.241] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102013663046486400:52:0)
[13:49:33.241] Timer tick, asking Buildbucket for the build status
[13:49:33.314] Build 8936583600839409408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:49:33.314] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102013663046486400:53:0) after 1m0s
[13:50:33.349] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102013663046486400:53:0)
[13:50:33.349] Timer tick, asking Buildbucket for the build status
[13:50:33.460] Build 8936583600839409408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:50:33.460] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102013663046486400:54:0) after 1m0s
[13:51:33.480] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102013663046486400:54:0)
[13:51:33.480] Timer tick, asking Buildbucket for the build status
[13:51:33.632] Build 8936583600839409408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:51:33.632] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102013663046486400:55:0) after 1m0s
[13:52:34.239] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102013663046486400:55:0)
[13:52:34.239] Timer tick, asking Buildbucket for the build status
[13:52:34.310] Build 8936583600839409408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:52:34.310] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102013663046486400:56:0) after 1m0s
[13:53:34.396] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102013663046486400:56:0)
[13:53:34.396] Timer tick, asking Buildbucket for the build status
[13:53:34.581] Build 8936583600839409408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:53:34.581] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102013663046486400:57:0) after 1m0s
[13:54:34.602] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102013663046486400:57:0)
[13:54:34.602] Timer tick, asking Buildbucket for the build status
[13:54:34.726] Build 8936583600839409408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:54:34.726] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102013663046486400:58:0) after 1m0s
[13:55:34.791] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102013663046486400:58:0)
[13:55:34.791] Timer tick, asking Buildbucket for the build status
[13:55:34.885] Build 8936583600839409408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:55:34.885] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102013663046486400:59:0) after 1m0s
[13:56:34.905] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102013663046486400:59:0)
[13:56:34.905] Timer tick, asking Buildbucket for the build status
[13:56:35.053] Build 8936583600839409408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:56:35.053] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102013663046486400:60:0) after 1m0s
[13:57:35.074] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102013663046486400:60:0)
[13:57:35.074] Timer tick, asking Buildbucket for the build status
[13:57:35.270] Build 8936583600839409408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:57:35.271] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102013663046486400:61:0) after 1m0s
[13:58:35.302] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102013663046486400:61:0)
[13:58:35.302] Timer tick, asking Buildbucket for the build status
[13:58:35.370] Build 8936583600839409408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:58:35.370] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102013663046486400:62:0) after 1m0s
[13:59:35.390] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102013663046486400:62:0)
[13:59:35.390] Timer tick, asking Buildbucket for the build status
[13:59:35.483] Build 8936583600839409408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:59:35.483] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102013663046486400:63:0) after 1m0s
[14:00:35.503] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102013663046486400:63:0)
[14:00:35.503] Timer tick, asking Buildbucket for the build status
[14:00:35.559] Build 8936583600839409408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:00:35.560] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102013663046486400:64:0) after 1m0s
[14:01:35.585] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102013663046486400:64:0)
[14:01:35.585] Timer tick, asking Buildbucket for the build status
[14:01:35.620] Build 8936583600839409408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:01:35.620] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102013663046486400:65:0) after 1m0s
[14:02:35.671] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102013663046486400:65:0)
[14:02:35.671] Timer tick, asking Buildbucket for the build status
[14:02:35.707] Build 8936583600839409408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:02:35.707] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102013663046486400:66:0) after 1m0s
[14:03:35.727] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102013663046486400:66:0)
[14:03:35.727] Timer tick, asking Buildbucket for the build status
[14:03:35.867] Build 8936583600839409408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:03:35.867] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102013663046486400:67:0) after 1m0s
[14:04:35.887] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102013663046486400:67:0)
[14:04:35.887] Timer tick, asking Buildbucket for the build status
[14:04:35.967] Build 8936583600839409408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:04:35.967] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102013663046486400:68:0) after 1m0s
[14:05:35.987] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102013663046486400:68:0)
[14:05:35.987] Timer tick, asking Buildbucket for the build status
[14:05:36.050] Build 8936583600839409408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:05:36.050] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102013663046486400:69:0) after 1m0s
[14:06:36.500] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102013663046486400:69:0)
[14:06:36.500] Timer tick, asking Buildbucket for the build status
[14:06:36.639] Build 8936583600839409408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:06:36.639] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102013663046486400:70:0) after 1m0s
[14:07:36.858] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102013663046486400:70:0)
[14:07:36.858] Timer tick, asking Buildbucket for the build status
[14:07:36.919] Build 8936583600839409408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:07:36.919] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102013663046486400:71:0) after 1m0s
[14:08:37.037] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102013663046486400:71:0)
[14:08:37.037] Timer tick, asking Buildbucket for the build status
[14:08:37.125] Build 8936583600839409408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:08:37.125] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102013663046486400:72:0) after 1m0s
[14:09:37.942] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102013663046486400:72:0)
[14:09:37.942] Timer tick, asking Buildbucket for the build status
[14:09:38.045] Build 8936583600839409408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:09:38.045] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102013663046486400:73:0) after 1m0s
[14:10:38.861] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102013663046486400:73:0)
[14:10:38.861] Timer tick, asking Buildbucket for the build status
[14:10:38.959] Build 8936583600839409408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:10:38.959] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102013663046486400:74:0) after 1m0s
[14:11:39.996] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102013663046486400:74:0)
[14:11:39.996] Timer tick, asking Buildbucket for the build status
[14:11:40.083] Build 8936583600839409408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:11:40.083] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102013663046486400:75:0) after 1m0s
[14:12:41.096] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102013663046486400:75:0)
[14:12:41.096] Timer tick, asking Buildbucket for the build status
[14:12:41.165] Build 8936583600839409408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:12:41.165] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102013663046486400:76:0) after 1m0s
[14:13:42.514] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102013663046486400:76:0)
[14:13:42.515] Timer tick, asking Buildbucket for the build status
[14:13:42.583] Build 8936583600839409408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:13:42.583] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102013663046486400:77:0) after 1m0s
[14:14:42.815] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102013663046486400:77:0)
[14:14:42.815] Timer tick, asking Buildbucket for the build status
[14:14:42.871] Build 8936583600839409408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:14:42.871] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102013663046486400:78:0) after 1m0s
[14:15:44.003] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102013663046486400:78:0)
[14:15:44.003] Timer tick, asking Buildbucket for the build status
[14:15:44.065] Build 8936583600839409408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:15:44.065] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102013663046486400:79:0) after 1m0s
[14:16:45.435] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102013663046486400:79:0)
[14:16:45.435] Timer tick, asking Buildbucket for the build status
[14:16:45.520] Build 8936583600839409408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:16:45.520] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102013663046486400:80:0) after 1m0s
[14:17:45.545] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102013663046486400:80:0)
[14:17:45.545] Timer tick, asking Buildbucket for the build status
[14:17:45.624] Build 8936583600839409408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:17:45.624] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102013663046486400:81:0) after 1m0s
[14:18:45.647] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102013663046486400:81:0)
[14:18:45.647] Timer tick, asking Buildbucket for the build status
[14:18:45.808] Build 8936583600839409408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:18:45.808] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102013663046486400:82:0) after 1m0s
[14:19:45.903] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102013663046486400:82:0)
[14:19:45.903] Timer tick, asking Buildbucket for the build status
[14:19:46.057] Build 8936583600839409408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:19:46.058] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102013663046486400:83:0) after 1m0s
[14:20:46.082] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102013663046486400:83:0)
[14:20:46.082] Timer tick, asking Buildbucket for the build status
[14:20:46.139] Build 8936583600839409408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:20:46.139] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102013663046486400:84:0) after 1m0s
[14:21:46.240] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102013663046486400:84:0)
[14:21:46.240] Timer tick, asking Buildbucket for the build status
[14:21:46.374] Build 8936583600839409408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:21:46.374] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102013663046486400:85:0) after 1m0s
[14:22:46.571] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102013663046486400:85:0)
[14:22:46.571] Timer tick, asking Buildbucket for the build status
[14:22:46.660] Build 8936583600839409408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:22:46.660] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102013663046486400:86:0) after 1m0s
[14:23:46.830] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102013663046486400:86:0)
[14:23:46.830] Timer tick, asking Buildbucket for the build status
[14:23:47.217] Build 8936583600839409408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:23:47.217] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102013663046486400:87:0) after 1m0s
[14:24:47.732] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102013663046486400:87:0)
[14:24:47.732] Timer tick, asking Buildbucket for the build status
[14:24:47.801] Build 8936583600839409408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:24:47.801] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102013663046486400:88:0) after 1m0s
[14:25:48.265] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102013663046486400:88:0)
[14:25:48.265] Timer tick, asking Buildbucket for the build status
[14:25:48.404] Build 8936583600839409408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:25:48.405] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102013663046486400:89:0) after 1m0s
[14:26:48.984] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102013663046486400:89:0)
[14:26:48.984] Timer tick, asking Buildbucket for the build status
[14:26:49.221] Build 8936583600839409408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:26:49.221] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102013663046486400:90:0) after 1m0s
[14:27:49.907] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102013663046486400:90:0)
[14:27:49.907] Timer tick, asking Buildbucket for the build status
[14:27:49.958] Build 8936583600839409408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:27:49.958] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102013663046486400:91:0) after 1m0s
[14:28:50.832] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102013663046486400:91:0)
[14:28:50.832] Timer tick, asking Buildbucket for the build status
[14:28:50.898] Build 8936583600839409408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:28:50.898] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102013663046486400:92:0) after 1m0s
[14:29:51.515] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102013663046486400:92:0)
[14:29:51.515] Timer tick, asking Buildbucket for the build status
[14:29:51.621] Build 8936583600839409408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:29:51.621] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102013663046486400:93:0) after 1m0s
[14:30:52.332] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102013663046486400:93:0)
[14:30:52.332] Timer tick, asking Buildbucket for the build status
[14:30:52.865] Build 8936583600839409408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:30:52.865] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102013663046486400:94:0) after 1m0s
[14:31:53.469] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102013663046486400:94:0)
[14:31:53.469] Timer tick, asking Buildbucket for the build status
[14:31:53.681] Build 8936583600839409408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:31:53.681] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102013663046486400:95:0) after 1m0s
[14:32:54.397] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102013663046486400:95:0)
[14:32:54.397] Timer tick, asking Buildbucket for the build status
[14:32:54.460] Build 8936583600839409408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:32:54.460] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102013663046486400:96:0) after 1m0s
[14:33:55.379] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102013663046486400:96:0)
[14:33:55.379] Timer tick, asking Buildbucket for the build status
[14:33:55.447] Build 8936583600839409408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:33:55.447] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102013663046486400:97:0) after 1m0s
[14:34:56.291] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102013663046486400:97:0)
[14:34:56.291] Timer tick, asking Buildbucket for the build status
[14:34:56.345] Build 8936583600839409408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:34:56.345] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102013663046486400:98:0) after 1m0s
[14:35:56.365] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102013663046486400:98:0)
[14:35:56.365] Timer tick, asking Buildbucket for the build status
[14:35:56.657] Build 8936583600839409408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:35:56.657] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102013663046486400:99:0) after 1m0s
[14:36:57.459] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102013663046486400:99:0)
[14:36:57.459] Timer tick, asking Buildbucket for the build status
[14:36:57.616] Build 8936583600839409408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:36:57.616] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102013663046486400:100:0) after 1m0s
[14:37:58.505] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102013663046486400:100:0)
[14:37:58.505] Timer tick, asking Buildbucket for the build status
[14:37:58.635] Build 8936583600839409408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:37:58.635] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102013663046486400:101:0) after 1m0s
[14:38:59.554] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102013663046486400:101:0)
[14:38:59.554] Timer tick, asking Buildbucket for the build status
[14:38:59.585] Build 8936583600839409408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:38:59.585] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102013663046486400:102:0) after 1m0s
[14:40:00.515] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102013663046486400:102:0)
[14:40:00.515] Timer tick, asking Buildbucket for the build status
[14:40:00.646] Build 8936583600839409408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:40:00.646] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102013663046486400:103:0) after 1m0s
[14:40:53.127] Received PubSub notification, asking Buildbucket for the build status
[14:40:53.216] Build 8936583600839409408: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[14:40:53.217] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"completed_ts": "1535812850652110",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1535806765670460",
"failure_reason": "BUILD_FAILURE",
"id": "8936583600839409408",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"882608436a64c2a0d03ddc87e26aebe33f95db1b\"}}",
"project": "chromium",
"result": "FAILURE",
"result_details_json": "{\"ui\": {\"info\": \"Failure compare_build_artifacts\"}, \"build_run_result\": {\"infraFailure\": null, \"recipeResult\": {\"failure\": {\"failure\": {\"step\": \"compare_build_artifacts\"}, \"humanReason\": \"Step('compare_build_artifacts') failed with return_code 1\"}}, \"recipeExitCode\": {\"value\": 1}, \"recipe\": {\"name\": \"swarming/deterministic_build\", \"repository\": \"\", \"revision\": \"\"}, \"annotationUrl\": \"logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8936583600839409408/+/annotations\"}, \"swarming\": {\"bot_dimensions\": {\"machine_type\": [\"n1-standard-8\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"], \"locale\": [\"en_US.cp1252\"], \"os\": [\"Windows\", \"Windows-10\", \"Windows-10-15063\"], \"builder\": [\"Windows deterministic\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Haswell_GCE\"], \"python\": [\"2.7.6\"], \"server_version\": [\"3740-90b257a\"], \"image\": [\"chrome-win10-1703-2018-04-20-61acaf8983f\"], \"gpu\": [\"none\"], \"cores\": [\"8\"], \"integrity\": [\"high\"], \"id\": [\"swarm2173-c4\"], \"pool\": [\"luci.chromium.ci\"], \"caches\": [\"git\", \"goma_v2\", \"vpython\"]}}, \"properties\": {\"got_nacl_revision\": \"cb6ec4b1f91af259cbdfd1bd22bb98ed5551955e\", \"got_swarming_client_revision\": \"486c9b53c4d54dd4b95bb6ce0e31160e600dfc11\", \"got_revision\": \"882608436a64c2a0d03ddc87e26aebe33f95db1b\", \"recipe\": \"swarming/deterministic_build\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#24472}\", \"$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@{#588283}\", \"branch\": \"refs/heads/master\", \"revision\": \"882608436a64c2a0d03ddc87e26aebe33f95db1b\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"buildername\": \"Windows deterministic\", \"got_webrtc_revision\": \"f18b35284288ac851b77db88df3e2e8d2273db97\", \"mastername\": \"chromium.fyi\", \"got_angle_revision\": \"88fc6da3e28c9abb47b5329e4f241dc3cb8e84d7\", \"buildbucket\": {\"hostname\": \"cr-buildbucket.appspot.com\", \"build\": {\"created_ts\": 1535806765670460, \"tags\": [\"builder:Windows deterministic\", \"buildset:commit/git/882608436a64c2a0d03ddc87e26aebe33f95db1b\", \"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/882608436a64c2a0d03ddc87e26aebe33f95db1b\", \"gitiles_ref:refs/heads/master\", \"scheduler_invocation_id:9102013663046486400\", \"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\": \"8936583600839409408\"}}, \"got_v8_revision\": \"e0a58f83255d1dae907e2ba4564ad8928a7dedf4\", \"got_v8_revision_cp\": \"refs/branch-heads/7.0@{#6}\", \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"buildnumber\": 9944, \"swarm_hashes\": {\"shell_dialogs_unittests\": \"635504698c8e1cbfae24064df61b5cd2c88e9a8b\", \"notification_helper_unittests\": \"e9634cb554a0127e76126254d815d8730d6e2bbb\", \"gpu_perftests\": \"63554eb365d57150d22cc4963cd332a8c2c24fa1\", \"vr_common_unittests\": \"b0d934cba9ebcbb2467cd0121b03fca36a972837\", \"telemetry_unittests\": \"f70a33213e0454be106c8153a5b7bbacca263a5d\", \"load_library_perf_tests\": \"0c3917fe7d19deacd1db8d34ca5b5f803eb9a29c\", \"devtools_closure_compile\": \"b34cca3078dd0fb087691b975f49b95513d53f95\", \"chromedriver_py_tests\": \"f1bb8e152800280a9d7db47e8a431a82c920c375\", \"mojo_core_unittests\": \"41eefd3be52c0129c768278eaa0fb6ef82d32cd3\", \"install_static_unittests\": \"3a42bcfb0ed7838e5840c1086b5cb4dfdddb47f7\", \"leveldb_service_unittests\": \"4ab665f30f2f909ac2cf257a0e198d2b9565bc6a\", \"mini_installer_tests\": \"13d3353516c00191315c0f2e87e6f8cb6f9992c8\", \"jingle_unittests\": \"5152d4cd350b4164a7d456684b19184cb11c6ff7\", \"sbox_integration_tests\": \"e063849e376c70bd70f872e9abb7f448430ed9f6\", \"angle_unittests\": \"128a57cda1332ed31ba6bd1713d1fd3324049e5d\", \"cast_unittests\": \"cdb70d60d2a42e3f248e6d442f944ce9b17d86b9\", \"cc_unittests\": \"903bde8e85529282da0c61dbcd5230182dddf0e6\", \"blink_common_unittests\": \"31ad90caeb94478341b71d795e9fc37a726ce909\", \"crashpad_tests\": \"eeb0c494cb5d9e015303e2cbf5f5e7b6ab8a2cc3\", \"telemetry_perf_tests_without_chrome\": \"91e8dd0026ac9c4b595588dfb14c6080d6f555a6\", \"metrics_python_tests\": \"a54ccbdcb57a0d4e104d4d9469fa880fb724183b\", \"message_center_unittests\": \"7302295480bf573527e8aea514a3cf84dca0c627\", \"ppapi_unittests\": \"bc08c5fe86eb4b240abb2a2bed776f8a2f513206\", \"extensions_unittests\": \"2ba9b160825fe4a416a92506d87eb6e282411fa3\", \"gl_unittests_ozone\": \"9d46212ebae58c9b017649bdd2faae3f21380e9c\", \"app_shell_unittests\": \"157226afb28720dcbc6e6c08baed3ad8c1ca2bf7\", \"chrome_app_unittests\": \"a1ec1094d5ee6a648acb3ebc547fd30b3d60b6b6\", \"gl_unittests\": \"9d46212ebae58c9b017649bdd2faae3f21380e9c\", \"gcm_unit_tests\": \"fd5fcf47d8e8595d070d598b43bdfb42e93d8d4f\", \"views_unittests\": \"b85aedb0059436768fcf4b2d9e997bd8bdda7c08\", \"content_shell_crash_test\": \"9dd88731d491f415a6321576aa7f6b9a24767363\", \"ui_base_unittests\": \"13250b8f6e3ca26805346998d53775eaea2429d2\", \"gles2_conform_test\": \"6f8245b9e9d98a028b5529be65854305ba650c91\", \"cronet_unittests\": \"fefa44f37a5fa8f06e34872da830ab5bcb2f6271\", \"performance_test_suite\": \"ca14b46fa2e270f5639063b21270acc6de3826e8\", \"wm_unittests\": \"f758fa8a9d14428b353b77fe6576661fbbbe8744\", \"boringssl_crypto_tests\": \"9f8229d9c126f78ab1b2d0516c12406e2c42a72f\", \"gin_unittests\": \"75c0277ce0acfc96c1c5a5ac462825b581d0f499\", \"performance_browser_tests\": \"9138d51813a6994b99c69f25eea90226ea1c6a54\", \"extensions_browsertests\": \"9f7ab2dd862a5d05ecb78491d6439c8f53c56264\", \"webkit_python_tests\": \"acc53a78d44fcbdeb97af6a8be4ffaab8086f5c1\", \"headless_unittests\": \"db3223961d300c171e66d1dd47a584efce4c7021\", \"webkit_layout_tests\": \"4556f5a364013f704776cf684a4d3d74ec71fd07\", \"vr_pixeltests\": \"741752b85fc62f19f736385d3433045cb9c8a3af\", \"setup_unittests\": \"e2daf79a8e3fd9e25bbed3a97cb2215f3f0c700f\", \"webkit_unit_tests\": \"fdb8251c35bfc5bade436363e8d9a41c82453fb6\", \"content_unittests\": \"a3165043ac856099798dcb7b215cc65ea358d079\", \"performance_webview_test_suite\": \"df3928da192e9a5dd96a22cbcea3d1fea7375813\", \"wtf_unittests\": \"94e4a4dfe5af6fb61aaffce085aa752e7efd889d\", \"webkit_layout_tests_exparchive\": \"13dc35fd48080916385fe04a81315b1383cdb5b0\", \"chrome_cleaner_unittests\": \"dba77ac16a45f22885fbe820e424be1ba7ec673e\", \"traffic_annotation_auditor_unittests\": \"b06eca1a3f2675ad846f211e4b47c574fcf72e50\", \"libjingle_xmpp_unittests\": \"83d95937cebf05f0a781eab7f483121759b7e0e7\", \"remoting_unittests\": \"5e1a3d119e260b2417ca4d09095d031efac73624\", \"vr_common_perftests\": \"5035f7903cb1601523c5e799f1f6aea9a8baf5d9\", \"filesystem_service_unittests\": \"b30d92383a61a12608014eb5e59483c079793695\", \"chrome_elf_import_unittests\": \"9c556773e11ae57799d65590eb1d9a4752beb5eb\", \"installer_util_unittests\": \"d761600b88ea6810a729fc67f570730e3407ef12\", \"gfx_unittests\": \"7d9ae6f32e4e663512bf03bc531e19bb9c498435\", \"headless_browsertests\": \"8fa7818c3faee7e98a2c99a962edc0c677293a1b\", \"capture_unittests\": \"09a017ffff0061f8c56d21f2fb3b66e5f37d703f\", \"chrome_elf_unittests\": \"4bcd0c52f41fdd5a73a8b755c08cbd9f406f7a5a\", \"telemetry_gpu_unittests\": \"779ed602c7d9ab9f46e9b333bf61c506c6413751\", \"command_buffer_perftests\": \"ba3a4adbb08e1501793b6011e70d8180d8e87f47\", \"media_unittests\": \"6fc32e9e42c1be5769251f52da53914366cc5489\", \"chromedriver_unittests\": \"fb70f26820ce003307b21b768a229e6b3cd2f824\", \"views_mus_interactive_ui_tests\": \"c23f8e50b0c9065c7cd3f403484091dce1730f06\", \"storage_unittests\": \"6bc825bd825ef0a5f610806be096a2d37a099a6d\", \"media_service_unittests\": \"e94027c5b5cd3dbab3b5c81ad6674f43f2158ab7\", \"compositor_unittests\": \"b08a5a5c99e965c720d83ee680b56cbe1fa50dd7\", \"latency_unittests\": \"e364c416091795448987a7ffe6e212a096ebdf40\", \"video_decode_accelerator_unittest\": \"aa2bd5672950e7bf05116a488a89b63a709d6c51\", \"swiftshader_unittests\": \"3ba7c0d18c32f34b9a8745848e37f18b276096db\", \"telemetry_perf_unittests\": \"e70f4abeab78d51730bd7c8d43af98c88512792d\", \"media_blink_unittests\": \"a0eb70f4f47e2436dfdd8cc9374305f69b3d59e6\", \"views_mus_unittests\": \"138b4651719a9bea6e69ad2abd71b45b710eb8c4\", \"components_unittests\": \"7996b19ca377095961bc7aae127e907a0d7cadb7\", \"snapshot_unittests\": \"91ace6bcb618f5b6b9564ce073974b4a4795c0d8\", \"accessibility_unittests\": \"cb094fa3fb0b28fad0821d0b0972501237481c09\", \"net_perftests\": \"f9e66b996256b71ce2640c1152a295b0467f71d9\", \"aura_unittests\": \"9d6050a060ed21f1454264fb25b90308a1e2d226\", \"services_unittests\": \"f2771c0f3f67a11e39f3b6a12985e521a2d8b7ab\", \"components_perftests\": \"f746d6340d88f4a461c5fe7cf73eef321424eeb6\", \"events_unittests\": \"c90899f207a21c1f32a2baffe59f55b8e5f847b7\", \"tracing_perftests\": \"255bd980950ff598d5df5d84d98b3ae5d9bfd299\", \"chromedriver_replay_unittests\": \"e2ec5b5deadfbf1a0e00246e86e8f3e11e9cace0\", \"ui_touch_selection_unittests\": \"e3a5767b179ddd6f737d4350b8bb8b1540ba94ec\", \"cacheinvalidation_unittests\": \"d7df4006139baf0a7cc7690644d4991b67eb4e53\", \"viz_unittests\": \"ea33e4b9bd036e070c077a357bedcdb2fb7bf37c\", \"browser_tests\": \"4d89b57e13be211b3d3e4c638a8769c66ea4279b\", \"net_unittests\": \"d54de39545c695968f8f7f82bb17bec607b2cb50\", \"midi_unittests\": \"876dfe1f5984cdb3c70ac96ef62dbe4ca0ae49a2\", \"boringssl_ssl_tests\": \"626c84ee21eaa90916769e6944cbcb130f3d541d\", \"sbox_validation_tests\": \"00de59c0c34a14a1ca6c71c02114135dd2c89a4f\", \"elevation_service_unittests\": \"fdd429e86cf1ec8ae9270c70b9c9b5c139e09136\", \"gpu_unittests\": \"c5391bbd1e6f6b1cc9c4395f213d54c2892d70a9\", \"native_theme_unittests\": \"905128205818b1605fee1db0a0326d264c62aa34\", \"interactive_ui_tests\": \"50489e3639e10bc9dd73636d20a8a18d1fdd5385\", \"mojo_unittests\": \"ff10eca20d912b8a8ec896c1c1b1c8804c5dda32\", \"url_unittests\": \"6b808abfda23252eaf5eb87f4ab28cd4a29de771\", \"media_perftests\": \"8ea078ec2608f0f88edf43f20dd9f3210ba92961\", \"cronet_tests\": \"46a45c6ecefc4a7bf9af0c98e4eb1fbdce60ada8\", \"google_apis_unittests\": \"31331b9b62fa38805b69dc99afeecc9364186e04\", \"telemetry_gpu_integration_test\": \"e5082a49354007089dcd14067014ce2700944376\", \"blink_heap_unittests\": \"9307090183a1b1aa37f917541791fa35b3a4838a\", \"base_unittests\": \"3cc215f86fce468ac2b359e71c2aeb6803d8e3f6\", \"sync_integration_tests\": \"98ef120ea19c4bad0d1ff281454838b82a7f0ab7\", \"sql_unittests\": \"d4f9326b3b57051394fd400c6538703cbcb28bc5\", \"courgette_unittests\": \"c07e9ed4f7ec82f56dfcfd55bba180235ed5ceb8\", \"blink_fuzzer_unittests\": \"907df1716e8d48883ca1c29f91425aa7d33cdf3c\", \"gl_tests\": \"65c2def7bd1513316873c0ab6dd3a3602bfb9351\", \"zucchini_unittests\": \"e8a7254424dfff4ad5c7f6f06c0b7319f9f8216e\", \"ipc_tests\": \"491ca09e5f365e58fe8fa1b483482b3ab632d397\", \"angle_end2end_tests\": \"443e02aecc050ced557a5b037c37075f199b759a\", \"blink_platform_unittests\": \"7fccfdbe300fd787e5b24ce2969953fb5e6b0772\", \"components_browsertests\": \"0caa037fbdbc0e423e1bf7e94268bf7a9daaea08\", \"views_perftests\": \"5425616f983c8d97140f4de04f436574e22c1687\", \"angle_perftests\": \"8821fe429541fa22328c62f3981adfd5029b61f5\", \"display_unittests\": \"676823087e9a117965ec32e781369d0153554c81\", \"sbox_unittests\": \"3415d8e6cfa5f13e645f4bc3e8da0364bfe3eae8\", \"service_manager_unittests\": \"3b3cab8bf99c95f3226a3872437280bddc2648bb\", \"pdf_unittests\": \"7167e7f44b58ec6abbcb458bd10f132714158f26\", \"skia_unittests\": \"7e115f3cbcdff9c3d6c5e490229e214db1ad4552\", \"angle_white_box_tests\": \"20172edbaa224625789f4f00eca62d00e8871e73\", \"crypto_unittests\": \"5266c8f98113190a375974ecd713aafb03bb3615\", \"nacl_loader_unittests\": \"8a54fc1b2d10814c9ff3360d2f26bcf61f3211aa\", \"unit_tests\": \"4908b8a988fcd71d7d3bc830001867b0c6a3b68e\", \"content_browsertests\": \"f2ab9fb460917df4892224433a5a149432b5cad6\", \"printing_unittests\": \"95916b6bd70659a3e4d2d21838949edb978b95e9\", \"device_unittests\": \"f59d2daae5dec5ed8de92da434af4d72f353c4d0\"}, \"path_config\": \"generic\", \"bot_id\": \"swarm2173-c4\", \"got_buildtools_revision\": \"2dff9c9c74e9d732e6fe57c84ef7fd044cc45d96\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1535806856228910",
"status": "COMPLETED",
"status_changed_ts": "1535812852494150",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/9944",
"builder:Windows deterministic",
"buildset:commit/git/882608436a64c2a0d03ddc87e26aebe33f95db1b",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/882608436a64c2a0d03ddc87e26aebe33f95db1b",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9102013663046486400",
"scheduler_job_id:chromium/Windows deterministic",
"swarming_dimension:builder:Windows deterministic",
"swarming_dimension:caches:builder_d795d6e07fe37a299fcfc0e872e48757ad306282f024125f803c0465bfd1eeae_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/9944",
"swarming_tag:buildbucket_bucket:luci.chromium.ci",
"swarming_tag:buildbucket_build_id:8936583600839409408",
"swarming_tag:buildbucket_hostname:cr-buildbucket.appspot.com",
"swarming_tag:buildbucket_template_canary:0",
"swarming_tag:buildbucket_template_revision:ca2c7a741e04d0385e2bf62941ac932dc0521af3",
"swarming_tag:builder:Windows deterministic",
"swarming_tag:buildset:commit/git/882608436a64c2a0d03ddc87e26aebe33f95db1b",
"swarming_tag:buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/882608436a64c2a0d03ddc87e26aebe33f95db1b",
"swarming_tag:caches:builder_d795d6e07fe37a299fcfc0e872e48757ad306282f024125f803c0465bfd1eeae_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/8936583600839409408/+/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:9102013663046486400",
"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:7a77c69d050faf57eaf65e8f6cb7422326b6b83c",
"swarming_tag:user:None",
"swarming_tag:user_agent:luci-scheduler",
"swarming_tag:vpython:native-python-wrapper",
"swarming_task_id:3fae0904fec70610",
"user_agent:luci-scheduler"
],
"updated_ts": "1535812852499270",
"url": "https://ci.chromium.org/p/chromium/builders/luci.chromium.ci/Windows deterministic/9944",
"utcnow_ts": "1535812853189920"
}
}
[14:40:53.217] Invocation finished in 1h41m29.035893907s with status FAILED