[08:25:08.841] New invocation is queued and will start shortly
[08:25:09.962] Starting the invocation (attempt 1)
[08:25:09.995] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[08:25:09.995] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[08:25:09.995] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9101577933026053520",
"parameters_json": "{\"builder_name\":\"Windows deterministic\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src.git\",\"revision\":\"a4d8c399d911da54d3faa23ca55c67897f094cfc\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Windows deterministic",
"scheduler_invocation_id:9101577933026053520",
"scheduler_job_id:chromium/Windows deterministic",
"user_agent:luci-scheduler",
"buildset:commit/git/a4d8c399d911da54d3faa23ca55c67897f094cfc",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/a4d8c399d911da54d3faa23ca55c67897f094cfc",
"gitiles_ref:refs/heads/master"
]
}
[08:25:11.163] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1536222310076334",
"id": "8936147870948356032",
"lease_expiration_ts": "1536366310850236",
"lease_key": "367055199",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"a4d8c399d911da54d3faa23ca55c67897f094cfc\"}}",
"project": "chromium",
"result_details_json": "null",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1536222311067489",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/9992",
"builder:Windows deterministic",
"buildset:commit/git/a4d8c399d911da54d3faa23ca55c67897f094cfc",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/a4d8c399d911da54d3faa23ca55c67897f094cfc",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9101577933026053520",
"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/9992",
"swarming_tag:buildbucket_bucket:luci.chromium.ci",
"swarming_tag:buildbucket_build_id:8936147870948356032",
"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/a4d8c399d911da54d3faa23ca55c67897f094cfc",
"swarming_tag:buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/a4d8c399d911da54d3faa23ca55c67897f094cfc",
"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/8936147870948356032/+/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:9101577933026053520",
"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:c1f54e554be68337486f8ef663ba8af3d4928375",
"swarming_tag:user:None",
"swarming_tag:user_agent:luci-scheduler",
"swarming_tag:vpython:native-python-wrapper",
"swarming_task_id:3fc6cdb8ba54b510",
"user_agent:luci-scheduler"
],
"updated_ts": "1536222311067612",
"url": "https://ci.chromium.org/p/chromium/builders/luci.chromium.ci/Windows deterministic/9992",
"utcnow_ts": "1536222311148910"
}
}
[08:25:11.163] Task URL: https://ci.chromium.org/p/chromium/builders/luci.chromium.ci/Windows deterministic/9992
[08:25:11.163] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101577933026053520:2:0) after 1m0s
[08:26:11.180] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101577933026053520:2:0)
[08:26:11.180] Timer tick, asking Buildbucket for the build status
[08:26:11.248] Build 8936147870948356032: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:26:11.248] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101577933026053520:3:0) after 1m0s
[08:26:42.473] Received PubSub notification, asking Buildbucket for the build status
[08:26:42.568] Build 8936147870948356032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:27:11.268] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101577933026053520:3:0)
[08:27:11.268] Timer tick, asking Buildbucket for the build status
[08:27:11.309] Build 8936147870948356032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:27:11.309] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101577933026053520:5:0) after 1m0s
[08:28:11.325] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101577933026053520:5:0)
[08:28:11.325] Timer tick, asking Buildbucket for the build status
[08:28:11.369] Build 8936147870948356032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:28:11.369] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101577933026053520:6:0) after 1m0s
[08:29:11.388] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101577933026053520:6:0)
[08:29:11.388] Timer tick, asking Buildbucket for the build status
[08:29:11.594] Build 8936147870948356032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:29:11.594] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101577933026053520:7:0) after 1m0s
[08:30:11.612] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101577933026053520:7:0)
[08:30:11.612] Timer tick, asking Buildbucket for the build status
[08:30:11.742] Build 8936147870948356032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:30:11.742] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101577933026053520:8:0) after 1m0s
[08:31:11.802] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101577933026053520:8:0)
[08:31:11.802] Timer tick, asking Buildbucket for the build status
[08:31:11.872] Build 8936147870948356032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:31:11.872] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101577933026053520:9:0) after 1m0s
[08:32:12.093] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101577933026053520:9:0)
[08:32:12.093] Timer tick, asking Buildbucket for the build status
[08:32:12.186] Build 8936147870948356032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:32:12.186] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101577933026053520:10:0) after 1m0s
[08:33:12.206] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101577933026053520:10:0)
[08:33:12.206] Timer tick, asking Buildbucket for the build status
[08:33:12.280] Build 8936147870948356032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:33:12.280] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101577933026053520:11:0) after 1m0s
[08:34:12.298] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101577933026053520:11:0)
[08:34:12.298] Timer tick, asking Buildbucket for the build status
[08:34:12.369] Build 8936147870948356032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:34:12.369] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101577933026053520:12:0) after 1m0s
[08:35:12.395] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101577933026053520:12:0)
[08:35:12.395] Timer tick, asking Buildbucket for the build status
[08:35:12.466] Build 8936147870948356032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:35:12.466] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101577933026053520:13:0) after 1m0s
[08:36:12.485] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101577933026053520:13:0)
[08:36:12.485] Timer tick, asking Buildbucket for the build status
[08:36:12.542] Build 8936147870948356032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:36:12.542] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101577933026053520:14:0) after 1m0s
[08:37:12.597] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101577933026053520:14:0)
[08:37:12.597] Timer tick, asking Buildbucket for the build status
[08:37:12.794] Build 8936147870948356032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:37:12.794] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101577933026053520:15:0) after 1m0s
[08:38:12.922] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101577933026053520:15:0)
[08:38:12.922] Timer tick, asking Buildbucket for the build status
[08:38:13.011] Build 8936147870948356032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:38:13.011] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101577933026053520:16:0) after 1m0s
[08:39:13.052] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101577933026053520:16:0)
[08:39:13.052] Timer tick, asking Buildbucket for the build status
[08:39:13.113] Build 8936147870948356032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:39:13.113] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101577933026053520:17:0) after 1m0s
[08:40:13.253] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101577933026053520:17:0)
[08:40:13.253] Timer tick, asking Buildbucket for the build status
[08:40:13.361] Build 8936147870948356032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:40:13.361] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101577933026053520:18:0) after 1m0s
[08:41:13.381] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101577933026053520:18:0)
[08:41:13.381] Timer tick, asking Buildbucket for the build status
[08:41:13.447] Build 8936147870948356032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:41:13.447] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101577933026053520:19:0) after 1m0s
[08:42:13.467] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101577933026053520:19:0)
[08:42:13.467] Timer tick, asking Buildbucket for the build status
[08:42:13.537] Build 8936147870948356032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:42:13.537] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101577933026053520:20:0) after 1m0s
[08:43:13.556] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101577933026053520:20:0)
[08:43:13.556] Timer tick, asking Buildbucket for the build status
[08:43:13.623] Build 8936147870948356032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:43:13.623] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101577933026053520:21:0) after 1m0s
[08:44:13.721] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101577933026053520:21:0)
[08:44:13.721] Timer tick, asking Buildbucket for the build status
[08:44:13.889] Build 8936147870948356032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:44:13.889] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101577933026053520:22:0) after 1m0s
[08:45:14.022] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101577933026053520:22:0)
[08:45:14.022] Timer tick, asking Buildbucket for the build status
[08:45:14.060] Build 8936147870948356032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:45:14.060] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101577933026053520:23:0) after 1m0s
[08:46:14.147] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101577933026053520:23:0)
[08:46:14.147] Timer tick, asking Buildbucket for the build status
[08:46:14.242] Build 8936147870948356032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:46:14.242] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101577933026053520:24:0) after 1m0s
[08:47:14.262] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101577933026053520:24:0)
[08:47:14.262] Timer tick, asking Buildbucket for the build status
[08:47:14.333] Build 8936147870948356032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:47:14.333] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101577933026053520:25:0) after 1m0s
[08:48:14.418] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101577933026053520:25:0)
[08:48:14.418] Timer tick, asking Buildbucket for the build status
[08:48:14.561] Build 8936147870948356032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:48:14.561] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101577933026053520:26:0) after 1m0s
[08:49:14.673] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101577933026053520:26:0)
[08:49:14.673] Timer tick, asking Buildbucket for the build status
[08:49:14.751] Build 8936147870948356032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:49:14.751] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101577933026053520:27:0) after 1m0s
[08:50:14.772] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101577933026053520:27:0)
[08:50:14.772] Timer tick, asking Buildbucket for the build status
[08:50:14.897] Build 8936147870948356032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:50:14.897] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101577933026053520:28:0) after 1m0s
[08:51:14.915] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101577933026053520:28:0)
[08:51:14.915] Timer tick, asking Buildbucket for the build status
[08:51:14.977] Build 8936147870948356032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:51:14.977] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101577933026053520:29:0) after 1m0s
[08:52:15.002] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101577933026053520:29:0)
[08:52:15.002] Timer tick, asking Buildbucket for the build status
[08:52:15.076] Build 8936147870948356032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:52:15.076] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101577933026053520:30:0) after 1m0s
[08:53:15.094] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101577933026053520:30:0)
[08:53:15.094] Timer tick, asking Buildbucket for the build status
[08:53:15.134] Build 8936147870948356032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:53:15.134] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101577933026053520:31:0) after 1m0s
[08:54:15.153] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101577933026053520:31:0)
[08:54:15.153] Timer tick, asking Buildbucket for the build status
[08:54:15.216] Build 8936147870948356032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:54:15.216] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101577933026053520:32:0) after 1m0s
[08:55:15.241] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101577933026053520:32:0)
[08:55:15.241] Timer tick, asking Buildbucket for the build status
[08:55:15.384] Build 8936147870948356032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:55:15.384] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101577933026053520:33:0) after 1m0s
[08:56:15.441] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101577933026053520:33:0)
[08:56:15.441] Timer tick, asking Buildbucket for the build status
[08:56:15.539] Build 8936147870948356032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:56:15.539] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101577933026053520:34:0) after 1m0s
[08:57:15.559] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101577933026053520:34:0)
[08:57:15.559] Timer tick, asking Buildbucket for the build status
[08:57:15.635] Build 8936147870948356032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:57:15.635] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101577933026053520:35:0) after 1m0s
[08:58:15.710] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101577933026053520:35:0)
[08:58:15.710] Timer tick, asking Buildbucket for the build status
[08:58:15.777] Build 8936147870948356032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:58:15.777] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101577933026053520:36:0) after 1m0s
[08:59:15.811] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101577933026053520:36:0)
[08:59:15.811] Timer tick, asking Buildbucket for the build status
[08:59:16.057] Build 8936147870948356032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:59:16.057] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101577933026053520:37:0) after 1m0s
[09:00:16.077] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101577933026053520:37:0)
[09:00:16.077] Timer tick, asking Buildbucket for the build status
[09:00:16.135] Build 8936147870948356032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:00:16.135] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101577933026053520:38:0) after 1m0s
[09:01:16.250] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101577933026053520:38:0)
[09:01:16.250] Timer tick, asking Buildbucket for the build status
[09:01:16.302] Build 8936147870948356032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:01:16.302] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101577933026053520:39:0) after 1m0s
[09:02:16.320] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101577933026053520:39:0)
[09:02:16.320] Timer tick, asking Buildbucket for the build status
[09:02:16.626] Build 8936147870948356032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:02:16.626] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101577933026053520:40:0) after 1m0s
[09:03:16.644] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101577933026053520:40:0)
[09:03:16.644] Timer tick, asking Buildbucket for the build status
[09:03:16.727] Build 8936147870948356032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:03:16.727] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101577933026053520:41:0) after 1m0s
[09:04:16.749] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101577933026053520:41:0)
[09:04:16.749] Timer tick, asking Buildbucket for the build status
[09:04:16.886] Build 8936147870948356032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:04:16.886] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101577933026053520:42:0) after 1m0s
[09:05:16.945] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101577933026053520:42:0)
[09:05:16.945] Timer tick, asking Buildbucket for the build status
[09:05:17.043] Build 8936147870948356032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:05:17.043] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101577933026053520:43:0) after 1m0s
[09:06:17.068] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101577933026053520:43:0)
[09:06:17.068] Timer tick, asking Buildbucket for the build status
[09:06:17.135] Build 8936147870948356032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:06:17.135] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101577933026053520:44:0) after 1m0s
[09:07:17.308] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101577933026053520:44:0)
[09:07:17.308] Timer tick, asking Buildbucket for the build status
[09:07:17.397] Build 8936147870948356032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:07:17.397] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101577933026053520:45:0) after 1m0s
[09:08:17.439] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101577933026053520:45:0)
[09:08:17.439] Timer tick, asking Buildbucket for the build status
[09:08:17.509] Build 8936147870948356032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:08:17.509] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101577933026053520:46:0) after 1m0s
[09:09:17.537] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101577933026053520:46:0)
[09:09:17.537] Timer tick, asking Buildbucket for the build status
[09:09:17.582] Build 8936147870948356032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:09:17.582] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101577933026053520:47:0) after 1m0s
[09:10:17.602] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101577933026053520:47:0)
[09:10:17.602] Timer tick, asking Buildbucket for the build status
[09:10:17.713] Build 8936147870948356032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:10:17.713] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101577933026053520:48:0) after 1m0s
[09:11:17.745] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101577933026053520:48:0)
[09:11:17.745] Timer tick, asking Buildbucket for the build status
[09:11:17.845] Build 8936147870948356032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:11:17.845] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101577933026053520:49:0) after 1m0s
[09:12:17.951] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101577933026053520:49:0)
[09:12:17.951] Timer tick, asking Buildbucket for the build status
[09:12:18.083] Build 8936147870948356032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:12:18.083] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101577933026053520:50:0) after 1m0s
[09:13:18.117] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101577933026053520:50:0)
[09:13:18.117] Timer tick, asking Buildbucket for the build status
[09:13:18.168] Build 8936147870948356032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:13:18.168] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101577933026053520:51:0) after 1m0s
[09:14:18.252] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101577933026053520:51:0)
[09:14:18.252] Timer tick, asking Buildbucket for the build status
[09:14:18.339] Build 8936147870948356032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:14:18.339] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101577933026053520:52:0) after 1m0s
[09:15:18.396] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101577933026053520:52:0)
[09:15:18.396] Timer tick, asking Buildbucket for the build status
[09:15:18.480] Build 8936147870948356032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:15:18.480] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101577933026053520:53:0) after 1m0s
[09:16:18.500] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101577933026053520:53:0)
[09:16:18.500] Timer tick, asking Buildbucket for the build status
[09:16:18.559] Build 8936147870948356032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:16:18.559] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101577933026053520:54:0) after 1m0s
[09:17:18.578] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101577933026053520:54:0)
[09:17:18.578] Timer tick, asking Buildbucket for the build status
[09:17:18.613] Build 8936147870948356032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:17:18.613] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101577933026053520:55:0) after 1m0s
[09:18:18.964] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101577933026053520:55:0)
[09:18:18.964] Timer tick, asking Buildbucket for the build status
[09:18:19.052] Build 8936147870948356032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:18:19.052] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101577933026053520:56:0) after 1m0s
[09:19:19.072] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101577933026053520:56:0)
[09:19:19.072] Timer tick, asking Buildbucket for the build status
[09:19:19.121] Build 8936147870948356032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:19:19.121] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101577933026053520:57:0) after 1m0s
[09:20:19.175] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101577933026053520:57:0)
[09:20:19.175] Timer tick, asking Buildbucket for the build status
[09:20:19.337] Build 8936147870948356032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:20:19.337] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101577933026053520:58:0) after 1m0s
[09:21:19.358] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101577933026053520:58:0)
[09:21:19.358] Timer tick, asking Buildbucket for the build status
[09:21:19.401] Build 8936147870948356032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:21:19.401] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101577933026053520:59:0) after 1m0s
[09:22:19.419] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101577933026053520:59:0)
[09:22:19.419] Timer tick, asking Buildbucket for the build status
[09:22:19.508] Build 8936147870948356032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:22:19.508] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101577933026053520:60:0) after 1m0s
[09:23:19.536] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101577933026053520:60:0)
[09:23:19.536] Timer tick, asking Buildbucket for the build status
[09:23:19.601] Build 8936147870948356032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:23:19.601] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101577933026053520:61:0) after 1m0s
[09:24:19.693] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101577933026053520:61:0)
[09:24:19.693] Timer tick, asking Buildbucket for the build status
[09:24:19.758] Build 8936147870948356032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:24:19.758] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101577933026053520:62:0) after 1m0s
[09:25:19.777] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101577933026053520:62:0)
[09:25:19.777] Timer tick, asking Buildbucket for the build status
[09:25:19.819] Build 8936147870948356032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:25:19.819] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101577933026053520:63:0) after 1m0s
[09:26:20.082] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101577933026053520:63:0)
[09:26:20.082] Timer tick, asking Buildbucket for the build status
[09:26:20.158] Build 8936147870948356032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:26:20.158] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101577933026053520:64:0) after 1m0s
[09:27:20.178] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101577933026053520:64:0)
[09:27:20.178] Timer tick, asking Buildbucket for the build status
[09:27:20.267] Build 8936147870948356032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:27:20.267] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101577933026053520:65:0) after 1m0s
[09:28:20.298] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101577933026053520:65:0)
[09:28:20.298] Timer tick, asking Buildbucket for the build status
[09:28:20.387] Build 8936147870948356032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:28:20.387] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101577933026053520:66:0) after 1m0s
[09:29:20.487] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101577933026053520:66:0)
[09:29:20.487] Timer tick, asking Buildbucket for the build status
[09:29:20.599] Build 8936147870948356032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:29:20.599] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101577933026053520:67:0) after 1m0s
[09:30:20.640] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101577933026053520:67:0)
[09:30:20.640] Timer tick, asking Buildbucket for the build status
[09:30:20.704] Build 8936147870948356032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:30:20.704] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101577933026053520:68:0) after 1m0s
[09:31:20.724] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101577933026053520:68:0)
[09:31:20.724] Timer tick, asking Buildbucket for the build status
[09:31:20.828] Build 8936147870948356032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:31:20.828] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101577933026053520:69:0) after 1m0s
[09:32:21.621] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101577933026053520:69:0)
[09:32:21.621] Timer tick, asking Buildbucket for the build status
[09:32:21.814] Build 8936147870948356032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:32:21.814] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101577933026053520:70:0) after 1m0s
[09:33:21.839] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101577933026053520:70:0)
[09:33:21.839] Timer tick, asking Buildbucket for the build status
[09:33:21.893] Build 8936147870948356032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:33:21.893] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101577933026053520:71:0) after 1m0s
[09:34:22.015] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101577933026053520:71:0)
[09:34:22.015] Timer tick, asking Buildbucket for the build status
[09:34:22.055] Build 8936147870948356032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:34:22.055] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101577933026053520:72:0) after 1m0s
[09:35:22.081] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101577933026053520:72:0)
[09:35:22.081] Timer tick, asking Buildbucket for the build status
[09:35:22.152] Build 8936147870948356032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:35:22.152] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101577933026053520:73:0) after 1m0s
[09:36:22.187] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101577933026053520:73:0)
[09:36:22.187] Timer tick, asking Buildbucket for the build status
[09:36:22.249] Build 8936147870948356032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:36:22.249] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101577933026053520:74:0) after 1m0s
[09:37:22.268] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101577933026053520:74:0)
[09:37:22.268] Timer tick, asking Buildbucket for the build status
[09:37:22.315] Build 8936147870948356032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:37:22.315] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101577933026053520:75:0) after 1m0s
[09:38:22.335] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101577933026053520:75:0)
[09:38:22.335] Timer tick, asking Buildbucket for the build status
[09:38:22.399] Build 8936147870948356032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:38:22.399] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101577933026053520:76:0) after 1m0s
[09:39:22.420] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101577933026053520:76:0)
[09:39:22.420] Timer tick, asking Buildbucket for the build status
[09:39:22.487] Build 8936147870948356032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:39:22.487] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101577933026053520:77:0) after 1m0s
[09:40:22.507] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101577933026053520:77:0)
[09:40:22.507] Timer tick, asking Buildbucket for the build status
[09:40:22.560] Build 8936147870948356032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:40:22.560] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101577933026053520:78:0) after 1m0s
[09:41:22.588] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101577933026053520:78:0)
[09:41:22.588] Timer tick, asking Buildbucket for the build status
[09:41:22.628] Build 8936147870948356032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:41:22.628] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101577933026053520:79:0) after 1m0s
[09:42:22.646] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101577933026053520:79:0)
[09:42:22.646] Timer tick, asking Buildbucket for the build status
[09:42:22.769] Build 8936147870948356032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:42:22.769] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101577933026053520:80:0) after 1m0s
[09:43:22.792] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101577933026053520:80:0)
[09:43:22.792] Timer tick, asking Buildbucket for the build status
[09:43:22.881] Build 8936147870948356032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:43:22.881] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101577933026053520:81:0) after 1m0s
[09:44:22.906] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101577933026053520:81:0)
[09:44:22.906] Timer tick, asking Buildbucket for the build status
[09:44:22.951] Build 8936147870948356032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:44:22.951] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101577933026053520:82:0) after 1m0s
[09:45:22.984] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101577933026053520:82:0)
[09:45:22.984] Timer tick, asking Buildbucket for the build status
[09:45:23.051] Build 8936147870948356032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:45:23.051] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101577933026053520:83:0) after 1m0s
[09:46:23.073] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101577933026053520:83:0)
[09:46:23.073] Timer tick, asking Buildbucket for the build status
[09:46:23.159] Build 8936147870948356032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:46:23.159] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101577933026053520:84:0) after 1m0s
[09:47:23.180] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101577933026053520:84:0)
[09:47:23.180] Timer tick, asking Buildbucket for the build status
[09:47:23.237] Build 8936147870948356032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:47:23.237] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101577933026053520:85:0) after 1m0s
[09:48:23.257] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101577933026053520:85:0)
[09:48:23.257] Timer tick, asking Buildbucket for the build status
[09:48:23.385] Build 8936147870948356032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:48:23.385] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101577933026053520:86:0) after 1m0s
[09:49:23.406] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101577933026053520:86:0)
[09:49:23.406] Timer tick, asking Buildbucket for the build status
[09:49:23.462] Build 8936147870948356032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:49:23.462] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101577933026053520:87:0) after 1m0s
[09:50:23.491] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101577933026053520:87:0)
[09:50:23.491] Timer tick, asking Buildbucket for the build status
[09:50:23.533] Build 8936147870948356032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:50:23.533] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101577933026053520:88:0) after 1m0s
[09:51:23.559] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101577933026053520:88:0)
[09:51:23.559] Timer tick, asking Buildbucket for the build status
[09:51:23.727] Build 8936147870948356032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:51:23.727] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101577933026053520:89:0) after 1m0s
[09:52:23.747] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101577933026053520:89:0)
[09:52:23.747] Timer tick, asking Buildbucket for the build status
[09:52:23.845] Build 8936147870948356032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:52:23.845] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101577933026053520:90:0) after 1m0s
[09:53:23.869] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101577933026053520:90:0)
[09:53:23.869] Timer tick, asking Buildbucket for the build status
[09:53:23.940] Build 8936147870948356032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:53:23.940] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101577933026053520:91:0) after 1m0s
[09:54:23.964] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101577933026053520:91:0)
[09:54:23.965] Timer tick, asking Buildbucket for the build status
[09:54:24.012] Build 8936147870948356032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:54:24.012] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101577933026053520:92:0) after 1m0s
[09:55:24.036] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101577933026053520:92:0)
[09:55:24.036] Timer tick, asking Buildbucket for the build status
[09:55:24.116] Build 8936147870948356032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:55:24.116] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101577933026053520:93:0) after 1m0s
[09:56:24.137] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101577933026053520:93:0)
[09:56:24.137] Timer tick, asking Buildbucket for the build status
[09:56:24.219] Build 8936147870948356032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:56:24.219] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101577933026053520:94:0) after 1m0s
[09:57:24.239] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101577933026053520:94:0)
[09:57:24.239] Timer tick, asking Buildbucket for the build status
[09:57:24.404] Build 8936147870948356032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:57:24.404] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101577933026053520:95:0) after 1m0s
[09:58:24.426] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101577933026053520:95:0)
[09:58:24.426] Timer tick, asking Buildbucket for the build status
[09:58:24.832] Build 8936147870948356032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:58:24.832] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101577933026053520:96:0) after 1m0s
[09:59:24.926] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101577933026053520:96:0)
[09:59:24.926] Timer tick, asking Buildbucket for the build status
[09:59:25.025] Build 8936147870948356032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:59:25.025] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101577933026053520:97:0) after 1m0s
[10:00:25.045] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101577933026053520:97:0)
[10:00:25.046] Timer tick, asking Buildbucket for the build status
[10:00:25.087] Build 8936147870948356032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:00:25.087] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101577933026053520:98:0) after 1m0s
[10:01:25.112] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101577933026053520:98:0)
[10:01:25.112] Timer tick, asking Buildbucket for the build status
[10:01:25.301] Build 8936147870948356032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:01:25.301] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101577933026053520:99:0) after 1m0s
[10:02:25.436] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101577933026053520:99:0)
[10:02:25.436] Timer tick, asking Buildbucket for the build status
[10:02:25.505] Build 8936147870948356032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:02:25.505] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101577933026053520:100:0) after 1m0s
[10:03:25.528] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101577933026053520:100:0)
[10:03:25.528] Timer tick, asking Buildbucket for the build status
[10:03:25.636] Build 8936147870948356032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:03:25.636] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101577933026053520:101:0) after 1m0s
[10:04:25.660] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101577933026053520:101:0)
[10:04:25.660] Timer tick, asking Buildbucket for the build status
[10:04:25.795] Build 8936147870948356032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:04:25.795] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101577933026053520:102:0) after 1m0s
[10:05:25.814] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101577933026053520:102:0)
[10:05:25.814] Timer tick, asking Buildbucket for the build status
[10:05:25.880] Build 8936147870948356032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:05:25.880] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101577933026053520:103:0) after 1m0s
[10:05:47.654] Received PubSub notification, asking Buildbucket for the build status
[10:05:47.850] Build 8936147870948356032: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[10:05:47.850] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"completed_ts": "1536228346230997",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1536222310076334",
"failure_reason": "BUILD_FAILURE",
"id": "8936147870948356032",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"a4d8c399d911da54d3faa23ca55c67897f094cfc\"}}",
"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/8936147870948356032/+/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\": [\"3767-667810e\"], \"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\": \"a4d8c399d911da54d3faa23ca55c67897f094cfc\", \"recipe\": \"swarming/deterministic_build\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#24590}\", \"$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@{#589111}\", \"branch\": \"refs/heads/master\", \"revision\": \"a4d8c399d911da54d3faa23ca55c67897f094cfc\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"buildername\": \"Windows deterministic\", \"got_webrtc_revision\": \"b9bb9e3f6683a74acab9198359d17a3b68644338\", \"mastername\": \"chromium.fyi\", \"got_angle_revision\": \"7359954301e131a2b1f326ef391669dbd7562d0d\", \"buildbucket\": {\"hostname\": \"cr-buildbucket.appspot.com\", \"build\": {\"created_ts\": 1536222310076334, \"tags\": [\"builder:Windows deterministic\", \"buildset:commit/git/a4d8c399d911da54d3faa23ca55c67897f094cfc\", \"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/a4d8c399d911da54d3faa23ca55c67897f094cfc\", \"gitiles_ref:refs/heads/master\", \"scheduler_invocation_id:9101577933026053520\", \"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\": \"8936147870948356032\"}}, \"got_v8_revision\": \"1b63139d35984e457e9fce65b6240b5ea3fb8208\", \"got_v8_revision_cp\": \"refs/heads/7.1.37@{#1}\", \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"buildnumber\": 9992, \"swarm_hashes\": {\"shell_dialogs_unittests\": \"75dabdcd46a3f4f931760109cfa828385bb20735\", \"notification_helper_unittests\": \"3dea1899492b9dbeb8e711a5e4781da47ae4431f\", \"gpu_perftests\": \"4134ecc774c8020c780376dd225476813fb530fd\", \"vr_common_unittests\": \"00bf1a8665c560f1b31618812250cbc5cfef40bd\", \"telemetry_unittests\": \"1328f4448322e6f01854e4421e31599149060b2e\", \"load_library_perf_tests\": \"f6268e19dde854e98898e63880d55f0f7f7306f6\", \"devtools_closure_compile\": \"3c92b1019fd55fb6fccee350cebd46656910d3ed\", \"chromedriver_py_tests\": \"708cf92ebe81cad0db9aca59ad8090a44deb2ec1\", \"mojo_core_unittests\": \"78580d06e637ebfba46b0b6245e1e7128847abac\", \"install_static_unittests\": \"428414b372da2cadd09916d84ed688c689e71a22\", \"leveldb_service_unittests\": \"a7ca03586079638625355fc87e392fca5156d904\", \"mini_installer_tests\": \"d5bff6f567730ed543c72143b8080a198a6ae553\", \"jingle_unittests\": \"528901636febdaadd39ee9afa0843cca4ab5243e\", \"sbox_integration_tests\": \"2436251a8f7406e9fa3ad200a6fcd52ee299cece\", \"angle_unittests\": \"76e1adfbf13ee7844e6f613caaf63d699f9c1c82\", \"cast_unittests\": \"09100d41ffeea3ad5df7776f5fcab25cabf6d078\", \"cc_unittests\": \"5b46e8fe4072ebd7c63e4c71abb3fe6d8ceba7d2\", \"blink_common_unittests\": \"4f5418b3370d734c24b1bea4f7114073b3e4bb45\", \"crashpad_tests\": \"6ee868b846adb60bf52df7c0afa17ec5e52807ce\", \"telemetry_perf_tests_without_chrome\": \"380372ff321e7babdd250eedd08d3fdee716e453\", \"metrics_python_tests\": \"a54ccbdcb57a0d4e104d4d9469fa880fb724183b\", \"message_center_unittests\": \"bd349daecae6cea58375dcfab440fdd5720b929d\", \"ppapi_unittests\": \"4d47a27c53d197681567fe3dd448bd713e074531\", \"extensions_unittests\": \"f1a4979e07a31d26b4a8088cbd870a16371a8966\", \"gl_unittests_ozone\": \"7a99ee282c7da4fec8c3dd4aad4a7ea2ea2dd4e7\", \"app_shell_unittests\": \"8ea1e21872e64303f6cd84597d6c6adb103fb993\", \"chrome_app_unittests\": \"dcad53d8a61e6ecc7a68aab2cdd88953bf82962c\", \"gl_unittests\": \"7a99ee282c7da4fec8c3dd4aad4a7ea2ea2dd4e7\", \"gcm_unit_tests\": \"3603a6bccd3d5a0ead0ef87193534db833f352a4\", \"views_unittests\": \"259e4ff738e470414458dcd6d6aada57fd597b32\", \"content_shell_crash_test\": \"e0910853e63d6d4e599880be33fbf9b7a2b093eb\", \"ui_base_unittests\": \"f09d359a4463237ed904cad42d2e79f0a4058814\", \"gles2_conform_test\": \"54319d4d8f0e59c8ee9f1279617f2365657631ec\", \"cronet_unittests\": \"9aa1f018c0d4def727bb0e75ae52f4b0d5c9caa4\", \"performance_test_suite\": \"f37ec9d672451c110b5070e8ef0d46a0f0bf10f5\", \"wm_unittests\": \"95bad22751de4d5fb9b5606ae3b008f5da041d32\", \"boringssl_crypto_tests\": \"a26c09c8788042890acb538512eefdcf33001b3f\", \"gin_unittests\": \"ed6460ed93d35417afa157dc2ccafba4060a21b1\", \"performance_browser_tests\": \"05134f59a805967ba92c9281403018963f16b009\", \"extensions_browsertests\": \"9474bafeb42e1c99d5bd62888ea18dd4f9d9ab9e\", \"webkit_python_tests\": \"5653129e864cdafd62a953ae9269b50e0608dc4a\", \"headless_unittests\": \"89fb581fa05c105036f095299a01e04fac6f3b3e\", \"webkit_layout_tests\": \"e31f7107724285349dad2f77d258cf14ee1b68a5\", \"vr_pixeltests\": \"7cf8c0ba822cbeb20c8f43034b61b5e7039a9688\", \"setup_unittests\": \"88af2ec52abe6a1b6d93e8915eb5f1a58751a16a\", \"webkit_unit_tests\": \"293e2616f2fd851e572bb718ef8f96cd415e5bb5\", \"content_unittests\": \"e9efef23070d0803689ef5c8182dbedc1122a23e\", \"performance_webview_test_suite\": \"d18642afb49ab75b6443b4ffdd87c929de60e49a\", \"wtf_unittests\": \"ba0670d1a62aab2b94e360050928e059336a050f\", \"webkit_layout_tests_exparchive\": \"6160d073e8d43ee3233f1fcb793f8dba51fcaa58\", \"chrome_cleaner_unittests\": \"e9fe6e1e790b66018e20f8d93fc96879ae82105d\", \"traffic_annotation_auditor_unittests\": \"884115249c1df64c721bd0f9e310f4a0f9a3e379\", \"libjingle_xmpp_unittests\": \"2fd20819fdcc569bcdaf08df664490e02700f748\", \"remoting_unittests\": \"07bc275985b8f3efc8b9177cb2b4ad700b8ac368\", \"vr_common_perftests\": \"95641b5b4d484d6eef262222127500f2dae883b5\", \"filesystem_service_unittests\": \"ed41cc9d2141c6ae7c7d45c5c79749c358373598\", \"chrome_elf_import_unittests\": \"c016023f51cb57d8f057142ac997aceda7ff5ed4\", \"installer_util_unittests\": \"ac28c6b1deb6dc46027e25be499247330f89b3f5\", \"gfx_unittests\": \"7bfcc9a86a3bea3d0a08520933ad33061c4d5231\", \"headless_browsertests\": \"b2cf863717ae9f84feb09d42586420979afb6e40\", \"capture_unittests\": \"259a0e0bda18e0767bacfad7e42c97a4fa3344a0\", \"chrome_elf_unittests\": \"bab45161dcaf123f990b5ec8576482be6da5c450\", \"telemetry_gpu_unittests\": \"b7057bc691fd9095b1962f28c73e82bb1ac657db\", \"command_buffer_perftests\": \"e39cdd50255591c3c5c52d983d29b4d4941d196c\", \"media_unittests\": \"4f1dcb37a33ca2c13a339e7c973cec4b2a6c77d0\", \"chromedriver_unittests\": \"f26ce09120e1902687def5c61c27216b691da5f4\", \"views_mus_interactive_ui_tests\": \"bbfc896a04a6ff76139d0609c4a5b9497b601250\", \"storage_unittests\": \"23da5115c78b9bf311c179e43988e9b44420bae3\", \"media_service_unittests\": \"7f394cf7d979785a2a3929b24db649656a5bd39b\", \"compositor_unittests\": \"ec92833d800acb4ab3335c09d8a8f858c1b9ee9a\", \"latency_unittests\": \"de5645605949f9de185fa7ead075de24e3f0fe36\", \"video_decode_accelerator_unittest\": \"37ad6e3326ea26466e7259369a85d1281ee900a3\", \"swiftshader_unittests\": \"673255bd2c8874dfe20ba99204c557b2095a4a46\", \"telemetry_perf_unittests\": \"4ad1e079a46cde27c6d1575ba2ff7bcd453880a9\", \"media_blink_unittests\": \"08d099ee74a47e18ac6c870b0ce5a31db5a55b08\", \"views_mus_unittests\": \"e76910ca248f8d5b7b9c18bbec822e744de5902b\", \"components_unittests\": \"0320fc030385dc2026339732db6a891a1ce5139f\", \"snapshot_unittests\": \"3fa5bccd63f67c337ef644832d6a903d535fb885\", \"accessibility_unittests\": \"9e61018751618464d351074a6bc2e0bdcc58aec2\", \"net_perftests\": \"f1d0f879744a22886237cc1044a3515d6791fe2f\", \"aura_unittests\": \"fc671a80367a94f3cdcaa92334606900213eddf0\", \"services_unittests\": \"73593db7191d5ba38a441cc241ba97d95446cf16\", \"components_perftests\": \"7f62e682ea83a696e28bcb95960290278107c158\", \"events_unittests\": \"24991b2fa978d44108d8cbb7588e986bcdea98b1\", \"tracing_perftests\": \"5a781ca57445b8cb5d8c2116cbb3ca1fffa00bb4\", \"chromedriver_replay_unittests\": \"e2ec5b5deadfbf1a0e00246e86e8f3e11e9cace0\", \"ui_touch_selection_unittests\": \"226f3e9b911516db39878ff8848ebca7e74353fe\", \"cacheinvalidation_unittests\": \"fc2c482e30ba9398f53849efc8ebd5033c5b65a5\", \"viz_unittests\": \"69c2d5a5e2c1f8509da5f52cf2a6c5eea2fcddee\", \"browser_tests\": \"fddfce925f0aa70e592777e25e08948e0940578c\", \"net_unittests\": \"5df10163528ff2ca0eecee17be231213f909315b\", \"midi_unittests\": \"8845be83df278b628ea90964f86719bc3ad27489\", \"boringssl_ssl_tests\": \"a2a00d0f115348184df2b4aacc65c7b995ca14bb\", \"sbox_validation_tests\": \"bec900587de148cf679741d7dfed3f439e3ce6db\", \"elevation_service_unittests\": \"49671dbe799975dd7d783ffd585171328c1cb747\", \"gpu_unittests\": \"5fd8460be52603a478aa5ac8a715f3b7e71b19d8\", \"native_theme_unittests\": \"2bbd8cbb14be8cf7a7cff85517adc474530b947f\", \"interactive_ui_tests\": \"4bd3927f02d8ca5202c8322f2fb6d6e3a4646b15\", \"mojo_unittests\": \"8d2240c3db6d01098a552768394fd2c822bb5634\", \"url_unittests\": \"8029607d43d7e1bab21794ced7aff02e766d1226\", \"media_perftests\": \"6ae877f5072ae6c47e5db9af1d41a48022fd9db2\", \"cronet_tests\": \"64f4995f61223e83e55b45d96a833a3a32c3ca08\", \"google_apis_unittests\": \"747e7a6706f1633297dcb9402692e02e83ac5604\", \"telemetry_gpu_integration_test\": \"019ae0f9766270feeac2c07d3df94be99ac51997\", \"blink_heap_unittests\": \"a52552f4abfd0d2a4b233e8008bdc3010c3f7bfc\", \"base_unittests\": \"50516829c241988683bf2b39edee4f112fa2fe5e\", \"sync_integration_tests\": \"7b44c58af76aa8fc412f055a99501f5e1df565ed\", \"sql_unittests\": \"40e4d4b7498e81a034cf1161a7ee5c02e6ee9aba\", \"courgette_unittests\": \"3e4e13caa7c803e165e8a3b0dc4910ff2447b90a\", \"blink_fuzzer_unittests\": \"e3c2b13650bc6b70217656dc519d4c90200be897\", \"gl_tests\": \"480e03d72070b4a09d59b3b1175ae230e07dfcd9\", \"zucchini_unittests\": \"d455cb4cd104dd80a63dafb9d015aebb17974a5c\", \"ipc_tests\": \"69e882b29af75f81bdf7a1fa3a16ec2be8ce23bc\", \"angle_end2end_tests\": \"d120d6aac9f5ed0d953e1d0aa44ed3b23438973e\", \"blink_platform_unittests\": \"51b827a0f2251317b425f8073b4e5393d52454bb\", \"components_browsertests\": \"4536a01ebbad4dc8e9bd45ec61ec6dd290b3ddf8\", \"views_perftests\": \"ac8b69638b911705321cd460121b26855e9b9134\", \"angle_perftests\": \"2eee4b2a5746b3298ba67f5e4f74e1b877677a98\", \"display_unittests\": \"1e7f4858af7618dd1f5ff1e2c47d52cd700a9c7c\", \"sbox_unittests\": \"2c75429d7fe231c79e8bd0af2804ca1cd9ef33d8\", \"service_manager_unittests\": \"c678c7e7eb44617dfb22477fb8e369b979b986a2\", \"pdf_unittests\": \"7920d028d0768a2aa47b4c6690cf0e824e9a2e5a\", \"skia_unittests\": \"3987955b2bec7e1dccb7d61d7663c148abc3d6b3\", \"angle_white_box_tests\": \"d1eeeb368edd54a19312e2ce66797badb1322176\", \"crypto_unittests\": \"245baed0c06bed000051fde725fac8dadc8635ad\", \"nacl_loader_unittests\": \"3214dcf42e3243aa9da8424de0760ebef24d4370\", \"unit_tests\": \"dde5461f1fb07477bbcbb18966b463dfbd15923a\", \"content_browsertests\": \"a6f8afcb65f9af7ddf1eddb765224ee6f5b86d54\", \"printing_unittests\": \"99ca93574c83e3d95feb39554234a6fe397dbc52\", \"device_unittests\": \"79f759edc611bb67f9b27f0e32ecb8089c68294f\"}, \"path_config\": \"generic\", \"bot_id\": \"swarm2173-c4\", \"got_buildtools_revision\": \"2dff9c9c74e9d732e6fe57c84ef7fd044cc45d96\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1536222401666382",
"status": "COMPLETED",
"status_changed_ts": "1536228347112363",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/9992",
"builder:Windows deterministic",
"buildset:commit/git/a4d8c399d911da54d3faa23ca55c67897f094cfc",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/a4d8c399d911da54d3faa23ca55c67897f094cfc",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9101577933026053520",
"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/9992",
"swarming_tag:buildbucket_bucket:luci.chromium.ci",
"swarming_tag:buildbucket_build_id:8936147870948356032",
"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/a4d8c399d911da54d3faa23ca55c67897f094cfc",
"swarming_tag:buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/a4d8c399d911da54d3faa23ca55c67897f094cfc",
"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/8936147870948356032/+/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:9101577933026053520",
"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:c1f54e554be68337486f8ef663ba8af3d4928375",
"swarming_tag:user:None",
"swarming_tag:user_agent:luci-scheduler",
"swarming_tag:vpython:native-python-wrapper",
"swarming_task_id:3fc6cdb8ba54b510",
"user_agent:luci-scheduler"
],
"updated_ts": "1536228347115065",
"url": "https://ci.chromium.org/p/chromium/builders/luci.chromium.ci/Windows deterministic/9992",
"utcnow_ts": "1536228347832998"
}
}
[10:05:47.850] Invocation finished in 1h40m39.139351384s with status FAILED