[23:56:58.387] New invocation is queued and will start shortly
[23:56:59.559] Starting the invocation (attempt 1)
[23:56:59.606] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[23:56:59.608] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[23:56:59.608] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9104327813508738176",
"parameters_json": "{\"builder_name\":\"Windows deterministic\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src.git\",\"revision\":\"8d1651ed349b24996e4eb65f42832ede9067d8d8\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Windows deterministic",
"scheduler_invocation_id:9104327813508738176",
"scheduler_job_id:chromium/Windows deterministic",
"user_agent:luci-scheduler",
"buildset:commit/git/8d1651ed349b24996e4eb65f42832ede9067d8d8",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/8d1651ed349b24996e4eb65f42832ede9067d8d8",
"gitiles_ref:refs/heads/master"
]
}
[23:57:01.390] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1533599819762670",
"id": "8938897751351345536",
"lease_expiration_ts": "1533743820801630",
"lease_key": "155593304",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"8d1651ed349b24996e4eb65f42832ede9067d8d8\"}}",
"project": "chromium",
"result_details_json": "null",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1533599821188990",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/9671",
"builder:Windows deterministic",
"buildset:commit/git/8d1651ed349b24996e4eb65f42832ede9067d8d8",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/8d1651ed349b24996e4eb65f42832ede9067d8d8",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9104327813508738176",
"scheduler_job_id:chromium/Windows deterministic",
"swarming_dimension:builder:Windows deterministic",
"swarming_dimension:cores:8",
"swarming_dimension:cpu:x86-64",
"swarming_dimension:os:Windows-10",
"swarming_dimension:pool:luci.chromium.ci",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:build_address:luci.chromium.ci/Windows deterministic/9671",
"swarming_tag:buildbucket_bucket:luci.chromium.ci",
"swarming_tag:buildbucket_build_id:8938897751351345536",
"swarming_tag:buildbucket_hostname:cr-buildbucket.appspot.com",
"swarming_tag:buildbucket_template_canary:0",
"swarming_tag:buildbucket_template_revision:1843d7ecb7176e182855328359db08c38323a012",
"swarming_tag:builder:Windows deterministic",
"swarming_tag:buildset:commit/git/8d1651ed349b24996e4eb65f42832ede9067d8d8",
"swarming_tag:buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/8d1651ed349b24996e4eb65f42832ede9067d8d8",
"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/8938897751351345536/+/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:9104327813508738176",
"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:f9bb609e9e946f9a36e28b789542bbb7a338f795",
"swarming_tag:user:None",
"swarming_tag:user_agent:luci-scheduler",
"swarming_tag:vpython:native-python-wrapper",
"swarming_task_id:3f2a7db213a35b10",
"user_agent:luci-scheduler"
],
"updated_ts": "1533599821189140",
"url": "https://ci.chromium.org/p/chromium/builders/luci.chromium.ci/Windows deterministic/9671",
"utcnow_ts": "1533599821348600"
}
}
[23:57:01.390] Task URL: https://ci.chromium.org/p/chromium/builders/luci.chromium.ci/Windows deterministic/9671
[23:57:01.390] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104327813508738176:2:0) after 1m0s
[23:58:01.418] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104327813508738176:2:0)
[23:58:01.418] Timer tick, asking Buildbucket for the build status
[23:58:01.626] Build 8938897751351345536: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:58:01.626] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104327813508738176:3:0) after 1m0s
[23:59:01.647] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104327813508738176:3:0)
[23:59:01.647] Timer tick, asking Buildbucket for the build status
[23:59:01.774] Build 8938897751351345536: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:59:01.774] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104327813508738176:4:0) after 1m0s
[23:59:13.474] Received PubSub notification, asking Buildbucket for the build status
[23:59:13.523] Build 8938897751351345536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:00:01.793] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104327813508738176:4:0)
[00:00:01.793] Timer tick, asking Buildbucket for the build status
[00:00:01.845] Build 8938897751351345536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:00:01.845] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104327813508738176:6:0) after 1m0s
[00:01:01.873] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104327813508738176:6:0)
[00:01:01.873] Timer tick, asking Buildbucket for the build status
[00:01:02.078] Build 8938897751351345536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:01:02.078] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104327813508738176:7:0) after 1m0s
[00:02:02.109] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104327813508738176:7:0)
[00:02:02.109] Timer tick, asking Buildbucket for the build status
[00:02:02.621] Build 8938897751351345536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:02:02.621] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104327813508738176:8:0) after 1m0s
[00:03:02.645] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104327813508738176:8:0)
[00:03:02.645] Timer tick, asking Buildbucket for the build status
[00:03:02.753] Build 8938897751351345536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:03:02.753] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104327813508738176:9:0) after 1m0s
[00:04:02.772] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104327813508738176:9:0)
[00:04:02.772] Timer tick, asking Buildbucket for the build status
[00:04:02.859] Build 8938897751351345536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:04:02.859] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104327813508738176:10:0) after 1m0s
[00:05:02.877] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104327813508738176:10:0)
[00:05:02.877] Timer tick, asking Buildbucket for the build status
[00:05:03.029] Build 8938897751351345536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:05:03.029] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104327813508738176:11:0) after 1m0s
[00:06:03.081] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104327813508738176:11:0)
[00:06:03.081] Timer tick, asking Buildbucket for the build status
[00:06:03.184] Build 8938897751351345536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:06:03.184] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104327813508738176:12:0) after 1m0s
[00:07:03.206] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104327813508738176:12:0)
[00:07:03.206] Timer tick, asking Buildbucket for the build status
[00:07:03.275] Build 8938897751351345536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:07:03.275] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104327813508738176:13:0) after 1m0s
[00:08:03.300] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104327813508738176:13:0)
[00:08:03.300] Timer tick, asking Buildbucket for the build status
[00:08:03.475] Build 8938897751351345536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:08:03.475] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104327813508738176:14:0) after 1m0s
[00:09:03.613] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104327813508738176:14:0)
[00:09:03.613] Timer tick, asking Buildbucket for the build status
[00:09:03.662] Build 8938897751351345536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:09:03.662] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104327813508738176:15:0) after 1m0s
[00:10:03.683] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104327813508738176:15:0)
[00:10:03.683] Timer tick, asking Buildbucket for the build status
[00:10:04.102] Build 8938897751351345536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:10:04.102] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104327813508738176:16:0) after 1m0s
[00:11:04.288] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104327813508738176:16:0)
[00:11:04.288] Timer tick, asking Buildbucket for the build status
[00:11:04.397] Build 8938897751351345536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:11:04.397] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104327813508738176:17:0) after 1m0s
[00:12:04.417] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104327813508738176:17:0)
[00:12:04.417] Timer tick, asking Buildbucket for the build status
[00:12:04.464] Build 8938897751351345536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:12:04.464] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104327813508738176:18:0) after 1m0s
[00:13:04.491] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104327813508738176:18:0)
[00:13:04.491] Timer tick, asking Buildbucket for the build status
[00:13:04.548] Build 8938897751351345536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:13:04.548] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104327813508738176:19:0) after 1m0s
[00:14:04.568] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104327813508738176:19:0)
[00:14:04.568] Timer tick, asking Buildbucket for the build status
[00:14:04.605] Build 8938897751351345536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:14:04.605] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104327813508738176:20:0) after 1m0s
[00:15:04.630] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104327813508738176:20:0)
[00:15:04.630] Timer tick, asking Buildbucket for the build status
[00:15:04.838] Build 8938897751351345536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:15:04.838] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104327813508738176:21:0) after 1m0s
[00:16:04.922] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104327813508738176:21:0)
[00:16:04.923] Timer tick, asking Buildbucket for the build status
[00:16:05.006] Build 8938897751351345536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:16:05.006] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104327813508738176:22:0) after 1m0s
[00:17:05.027] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104327813508738176:22:0)
[00:17:05.027] Timer tick, asking Buildbucket for the build status
[00:17:05.121] Build 8938897751351345536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:17:05.121] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104327813508738176:23:0) after 1m0s
[00:18:05.147] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104327813508738176:23:0)
[00:18:05.147] Timer tick, asking Buildbucket for the build status
[00:18:05.226] Build 8938897751351345536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:18:05.226] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104327813508738176:24:0) after 1m0s
[00:19:05.251] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104327813508738176:24:0)
[00:19:05.251] Timer tick, asking Buildbucket for the build status
[00:19:05.321] Build 8938897751351345536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:19:05.321] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104327813508738176:25:0) after 1m0s
[00:20:05.344] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104327813508738176:25:0)
[00:20:05.344] Timer tick, asking Buildbucket for the build status
[00:20:05.798] Build 8938897751351345536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:20:05.798] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104327813508738176:26:0) after 1m0s
[00:21:05.835] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104327813508738176:26:0)
[00:21:05.835] Timer tick, asking Buildbucket for the build status
[00:21:05.888] Build 8938897751351345536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:21:05.888] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104327813508738176:27:0) after 1m0s
[00:22:05.914] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104327813508738176:27:0)
[00:22:05.914] Timer tick, asking Buildbucket for the build status
[00:22:05.980] Build 8938897751351345536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:22:05.980] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104327813508738176:28:0) after 1m0s
[00:23:06.011] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104327813508738176:28:0)
[00:23:06.011] Timer tick, asking Buildbucket for the build status
[00:23:06.186] Build 8938897751351345536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:23:06.186] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104327813508738176:29:0) after 1m0s
[00:24:06.208] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104327813508738176:29:0)
[00:24:06.208] Timer tick, asking Buildbucket for the build status
[00:24:06.267] Build 8938897751351345536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:24:06.267] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104327813508738176:30:0) after 1m0s
[00:25:06.292] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104327813508738176:30:0)
[00:25:06.292] Timer tick, asking Buildbucket for the build status
[00:25:06.427] Build 8938897751351345536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:25:06.427] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104327813508738176:31:0) after 1m0s
[00:26:06.463] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104327813508738176:31:0)
[00:26:06.463] Timer tick, asking Buildbucket for the build status
[00:26:06.539] Build 8938897751351345536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:26:06.539] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104327813508738176:32:0) after 1m0s
[00:27:06.562] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104327813508738176:32:0)
[00:27:06.562] Timer tick, asking Buildbucket for the build status
[00:27:06.606] Build 8938897751351345536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:27:06.606] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104327813508738176:33:0) after 1m0s
[00:28:06.628] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104327813508738176:33:0)
[00:28:06.628] Timer tick, asking Buildbucket for the build status
[00:28:07.409] Build 8938897751351345536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:28:07.409] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104327813508738176:34:0) after 1m0s
[00:29:07.452] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104327813508738176:34:0)
[00:29:07.452] Timer tick, asking Buildbucket for the build status
[00:29:07.497] Build 8938897751351345536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:29:07.497] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104327813508738176:35:0) after 1m0s
[00:30:07.568] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104327813508738176:35:0)
[00:30:07.568] Timer tick, asking Buildbucket for the build status
[00:30:07.607] Build 8938897751351345536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:30:07.607] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104327813508738176:36:0) after 1m0s
[00:31:07.626] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104327813508738176:36:0)
[00:31:07.626] Timer tick, asking Buildbucket for the build status
[00:31:07.830] Build 8938897751351345536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:31:07.830] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104327813508738176:37:0) after 1m0s
[00:32:07.886] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104327813508738176:37:0)
[00:32:07.886] Timer tick, asking Buildbucket for the build status
[00:32:07.922] Build 8938897751351345536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:32:07.922] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104327813508738176:38:0) after 1m0s
[00:33:07.942] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104327813508738176:38:0)
[00:33:07.942] Timer tick, asking Buildbucket for the build status
[00:33:09.947] Build 8938897751351345536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:33:09.947] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104327813508738176:39:0) after 1m0s
[00:34:10.086] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104327813508738176:39:0)
[00:34:10.086] Timer tick, asking Buildbucket for the build status
[00:34:10.174] Build 8938897751351345536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:34:10.174] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104327813508738176:40:0) after 1m0s
[00:35:10.220] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104327813508738176:40:0)
[00:35:10.220] Timer tick, asking Buildbucket for the build status
[00:35:10.295] Build 8938897751351345536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:35:10.295] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104327813508738176:41:0) after 1m0s
[00:36:10.481] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104327813508738176:41:0)
[00:36:10.481] Timer tick, asking Buildbucket for the build status
[00:36:10.555] Build 8938897751351345536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:36:10.555] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104327813508738176:42:0) after 1m0s
[00:37:10.588] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104327813508738176:42:0)
[00:37:10.588] Timer tick, asking Buildbucket for the build status
[00:37:10.626] Build 8938897751351345536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:37:10.626] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104327813508738176:43:0) after 1m0s
[00:38:10.650] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104327813508738176:43:0)
[00:38:10.650] Timer tick, asking Buildbucket for the build status
[00:38:10.714] Build 8938897751351345536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:38:10.714] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104327813508738176:44:0) after 1m0s
[00:39:10.735] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104327813508738176:44:0)
[00:39:10.735] Timer tick, asking Buildbucket for the build status
[00:39:10.770] Build 8938897751351345536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:39:10.770] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104327813508738176:45:0) after 1m0s
[00:40:10.793] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104327813508738176:45:0)
[00:40:10.793] Timer tick, asking Buildbucket for the build status
[00:40:10.996] Build 8938897751351345536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:40:10.996] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104327813508738176:46:0) after 1m0s
[00:41:11.018] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104327813508738176:46:0)
[00:41:11.018] Timer tick, asking Buildbucket for the build status
[00:41:11.482] Build 8938897751351345536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:41:11.482] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104327813508738176:47:0) after 1m0s
[00:42:11.531] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104327813508738176:47:0)
[00:42:11.531] Timer tick, asking Buildbucket for the build status
[00:42:11.580] Build 8938897751351345536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:42:11.580] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104327813508738176:48:0) after 1m0s
[00:43:11.601] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104327813508738176:48:0)
[00:43:11.601] Timer tick, asking Buildbucket for the build status
[00:43:11.642] Build 8938897751351345536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:43:11.642] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104327813508738176:49:0) after 1m0s
[00:44:11.681] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104327813508738176:49:0)
[00:44:11.681] Timer tick, asking Buildbucket for the build status
[00:44:11.735] Build 8938897751351345536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:44:11.735] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104327813508738176:50:0) after 1m0s
[00:45:11.759] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104327813508738176:50:0)
[00:45:11.759] Timer tick, asking Buildbucket for the build status
[00:45:11.811] Build 8938897751351345536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:45:11.811] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104327813508738176:51:0) after 1m0s
[00:46:11.836] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104327813508738176:51:0)
[00:46:11.836] Timer tick, asking Buildbucket for the build status
[00:46:11.903] Build 8938897751351345536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:46:11.903] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104327813508738176:52:0) after 1m0s
[00:47:11.925] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104327813508738176:52:0)
[00:47:11.925] Timer tick, asking Buildbucket for the build status
[00:47:12.001] Build 8938897751351345536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:47:12.001] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104327813508738176:53:0) after 1m0s
[00:48:12.072] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104327813508738176:53:0)
[00:48:12.072] Timer tick, asking Buildbucket for the build status
[00:48:12.143] Build 8938897751351345536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:48:12.143] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104327813508738176:54:0) after 1m0s
[00:49:12.168] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104327813508738176:54:0)
[00:49:12.168] Timer tick, asking Buildbucket for the build status
[00:49:12.249] Build 8938897751351345536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:49:12.249] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104327813508738176:55:0) after 1m0s
[00:50:12.273] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104327813508738176:55:0)
[00:50:12.273] Timer tick, asking Buildbucket for the build status
[00:50:12.401] Build 8938897751351345536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:50:12.401] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104327813508738176:56:0) after 1m0s
[00:51:12.488] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104327813508738176:56:0)
[00:51:12.488] Timer tick, asking Buildbucket for the build status
[00:51:12.539] Build 8938897751351345536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:51:12.539] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104327813508738176:57:0) after 1m0s
[00:52:12.574] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104327813508738176:57:0)
[00:52:12.574] Timer tick, asking Buildbucket for the build status
[00:52:12.613] Build 8938897751351345536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:52:12.613] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104327813508738176:58:0) after 1m0s
[00:53:12.639] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104327813508738176:58:0)
[00:53:12.639] Timer tick, asking Buildbucket for the build status
[00:53:12.683] Build 8938897751351345536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:53:12.683] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104327813508738176:59:0) after 1m0s
[00:54:12.892] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104327813508738176:59:0)
[00:54:12.892] Timer tick, asking Buildbucket for the build status
[00:54:13.009] Build 8938897751351345536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:54:13.009] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104327813508738176:60:0) after 1m0s
[00:55:13.033] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104327813508738176:60:0)
[00:55:13.033] Timer tick, asking Buildbucket for the build status
[00:55:13.209] Build 8938897751351345536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:55:13.209] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104327813508738176:61:0) after 1m0s
[00:56:13.287] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104327813508738176:61:0)
[00:56:13.287] Timer tick, asking Buildbucket for the build status
[00:56:13.338] Build 8938897751351345536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:56:13.338] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104327813508738176:62:0) after 1m0s
[00:57:13.360] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104327813508738176:62:0)
[00:57:13.360] Timer tick, asking Buildbucket for the build status
[00:57:13.431] Build 8938897751351345536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:57:13.431] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104327813508738176:63:0) after 1m0s
[00:58:13.500] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104327813508738176:63:0)
[00:58:13.500] Timer tick, asking Buildbucket for the build status
[00:58:13.676] Build 8938897751351345536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:58:13.676] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104327813508738176:64:0) after 1m0s
[00:59:13.795] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104327813508738176:64:0)
[00:59:13.795] Timer tick, asking Buildbucket for the build status
[00:59:13.839] Build 8938897751351345536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:59:13.839] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104327813508738176:65:0) after 1m0s
[01:00:13.866] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104327813508738176:65:0)
[01:00:13.866] Timer tick, asking Buildbucket for the build status
[01:00:13.958] Build 8938897751351345536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:00:13.958] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104327813508738176:66:0) after 1m0s
[01:01:13.982] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104327813508738176:66:0)
[01:01:13.982] Timer tick, asking Buildbucket for the build status
[01:01:14.064] Build 8938897751351345536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:01:14.064] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104327813508738176:67:0) after 1m0s
[01:02:14.090] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104327813508738176:67:0)
[01:02:14.090] Timer tick, asking Buildbucket for the build status
[01:02:14.243] Build 8938897751351345536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:02:14.243] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104327813508738176:68:0) after 1m0s
[01:03:14.283] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104327813508738176:68:0)
[01:03:14.283] Timer tick, asking Buildbucket for the build status
[01:03:14.621] Build 8938897751351345536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:03:14.621] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104327813508738176:69:0) after 1m0s
[01:04:14.689] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104327813508738176:69:0)
[01:04:14.689] Timer tick, asking Buildbucket for the build status
[01:04:14.754] Build 8938897751351345536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:04:14.754] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104327813508738176:70:0) after 1m0s
[01:05:14.784] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104327813508738176:70:0)
[01:05:14.784] Timer tick, asking Buildbucket for the build status
[01:05:14.945] Build 8938897751351345536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:05:14.945] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104327813508738176:71:0) after 1m0s
[01:06:14.967] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104327813508738176:71:0)
[01:06:14.967] Timer tick, asking Buildbucket for the build status
[01:06:15.066] Build 8938897751351345536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:06:15.066] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104327813508738176:72:0) after 1m0s
[01:07:15.091] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104327813508738176:72:0)
[01:07:15.091] Timer tick, asking Buildbucket for the build status
[01:07:15.179] Build 8938897751351345536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:07:15.179] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104327813508738176:73:0) after 1m0s
[01:08:15.249] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104327813508738176:73:0)
[01:08:15.249] Timer tick, asking Buildbucket for the build status
[01:08:15.292] Build 8938897751351345536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:08:15.292] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104327813508738176:74:0) after 1m0s
[01:09:15.345] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104327813508738176:74:0)
[01:09:15.345] Timer tick, asking Buildbucket for the build status
[01:09:15.412] Build 8938897751351345536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:09:15.413] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104327813508738176:75:0) after 1m0s
[01:10:15.435] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104327813508738176:75:0)
[01:10:15.435] Timer tick, asking Buildbucket for the build status
[01:10:15.601] Build 8938897751351345536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:10:15.601] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104327813508738176:76:0) after 1m0s
[01:11:15.624] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104327813508738176:76:0)
[01:11:15.624] Timer tick, asking Buildbucket for the build status
[01:11:15.682] Build 8938897751351345536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:11:15.682] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104327813508738176:77:0) after 1m0s
[01:12:15.719] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104327813508738176:77:0)
[01:12:15.719] Timer tick, asking Buildbucket for the build status
[01:12:15.944] Build 8938897751351345536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:12:15.944] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104327813508738176:78:0) after 1m0s
[01:13:15.970] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104327813508738176:78:0)
[01:13:15.970] Timer tick, asking Buildbucket for the build status
[01:13:16.017] Build 8938897751351345536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:13:16.017] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104327813508738176:79:0) after 1m0s
[01:14:16.058] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104327813508738176:79:0)
[01:14:16.058] Timer tick, asking Buildbucket for the build status
[01:14:16.107] Build 8938897751351345536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:14:16.107] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104327813508738176:80:0) after 1m0s
[01:15:16.149] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104327813508738176:80:0)
[01:15:16.149] Timer tick, asking Buildbucket for the build status
[01:15:16.447] Build 8938897751351345536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:15:16.447] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104327813508738176:81:0) after 1m0s
[01:16:16.509] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104327813508738176:81:0)
[01:16:16.509] Timer tick, asking Buildbucket for the build status
[01:16:16.623] Build 8938897751351345536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:16:16.623] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104327813508738176:82:0) after 1m0s
[01:17:16.645] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104327813508738176:82:0)
[01:17:16.645] Timer tick, asking Buildbucket for the build status
[01:17:16.740] Build 8938897751351345536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:17:16.740] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104327813508738176:83:0) after 1m0s
[01:18:16.763] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104327813508738176:83:0)
[01:18:16.763] Timer tick, asking Buildbucket for the build status
[01:18:16.812] Build 8938897751351345536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:18:16.812] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104327813508738176:84:0) after 1m0s
[01:19:16.834] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104327813508738176:84:0)
[01:19:16.834] Timer tick, asking Buildbucket for the build status
[01:19:16.906] Build 8938897751351345536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:19:16.906] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104327813508738176:85:0) after 1m0s
[01:20:16.927] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104327813508738176:85:0)
[01:20:16.927] Timer tick, asking Buildbucket for the build status
[01:20:17.100] Build 8938897751351345536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:20:17.100] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104327813508738176:86:0) after 1m0s
[01:21:17.120] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104327813508738176:86:0)
[01:21:17.120] Timer tick, asking Buildbucket for the build status
[01:21:17.266] Build 8938897751351345536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:21:17.266] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104327813508738176:87:0) after 1m0s
[01:22:17.306] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104327813508738176:87:0)
[01:22:17.306] Timer tick, asking Buildbucket for the build status
[01:22:17.377] Build 8938897751351345536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:22:17.377] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104327813508738176:88:0) after 1m0s
[01:23:17.403] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104327813508738176:88:0)
[01:23:17.403] Timer tick, asking Buildbucket for the build status
[01:23:17.585] Build 8938897751351345536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:23:17.585] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104327813508738176:89:0) after 1m0s
[01:24:17.621] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104327813508738176:89:0)
[01:24:17.621] Timer tick, asking Buildbucket for the build status
[01:24:17.687] Build 8938897751351345536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:24:17.687] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104327813508738176:90:0) after 1m0s
[01:25:17.711] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104327813508738176:90:0)
[01:25:17.712] Timer tick, asking Buildbucket for the build status
[01:25:17.777] Build 8938897751351345536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:25:17.777] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104327813508738176:91:0) after 1m0s
[01:26:17.799] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104327813508738176:91:0)
[01:26:17.800] Timer tick, asking Buildbucket for the build status
[01:26:17.916] Build 8938897751351345536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:26:17.916] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104327813508738176:92:0) after 1m0s
[01:27:17.947] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104327813508738176:92:0)
[01:27:17.947] Timer tick, asking Buildbucket for the build status
[01:27:18.184] Build 8938897751351345536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:27:18.184] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104327813508738176:93:0) after 1m0s
[01:28:18.210] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104327813508738176:93:0)
[01:28:18.210] Timer tick, asking Buildbucket for the build status
[01:28:18.305] Build 8938897751351345536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:28:18.305] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104327813508738176:94:0) after 1m0s
[01:29:18.336] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104327813508738176:94:0)
[01:29:18.336] Timer tick, asking Buildbucket for the build status
[01:29:18.418] Build 8938897751351345536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:29:18.418] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104327813508738176:95:0) after 1m0s
[01:30:18.446] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104327813508738176:95:0)
[01:30:18.446] Timer tick, asking Buildbucket for the build status
[01:30:18.537] Build 8938897751351345536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:30:18.537] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104327813508738176:96:0) after 1m0s
[01:31:18.558] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104327813508738176:96:0)
[01:31:18.558] Timer tick, asking Buildbucket for the build status
[01:31:18.657] Build 8938897751351345536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:31:18.657] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104327813508738176:97:0) after 1m0s
[01:32:18.681] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104327813508738176:97:0)
[01:32:18.681] Timer tick, asking Buildbucket for the build status
[01:32:18.767] Build 8938897751351345536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:32:18.767] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104327813508738176:98:0) after 1m0s
[01:33:18.847] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104327813508738176:98:0)
[01:33:18.847] Timer tick, asking Buildbucket for the build status
[01:33:18.917] Build 8938897751351345536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:33:18.917] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104327813508738176:99:0) after 1m0s
[01:34:18.944] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104327813508738176:99:0)
[01:34:18.944] Timer tick, asking Buildbucket for the build status
[01:34:19.001] Build 8938897751351345536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:34:19.001] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104327813508738176:100:0) after 1m0s
[01:35:19.025] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104327813508738176:100:0)
[01:35:19.025] Timer tick, asking Buildbucket for the build status
[01:35:19.215] Build 8938897751351345536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:35:19.215] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104327813508738176:101:0) after 1m0s
[01:36:19.239] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104327813508738176:101:0)
[01:36:19.239] Timer tick, asking Buildbucket for the build status
[01:36:19.359] Build 8938897751351345536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:36:19.359] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104327813508738176:102:0) after 1m0s
[01:37:19.379] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104327813508738176:102:0)
[01:37:19.379] Timer tick, asking Buildbucket for the build status
[01:37:19.451] Build 8938897751351345536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:37:19.451] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104327813508738176:103:0) after 1m0s
[01:38:19.592] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104327813508738176:103:0)
[01:38:19.592] Timer tick, asking Buildbucket for the build status
[01:38:19.629] Build 8938897751351345536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:38:19.629] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104327813508738176:104:0) after 1m0s
[01:39:19.654] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104327813508738176:104:0)
[01:39:19.654] Timer tick, asking Buildbucket for the build status
[01:39:19.693] Build 8938897751351345536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:39:19.693] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104327813508738176:105:0) after 1m0s
[01:40:19.787] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104327813508738176:105:0)
[01:40:19.787] Timer tick, asking Buildbucket for the build status
[01:40:19.848] Build 8938897751351345536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:40:19.848] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104327813508738176:106:0) after 1m0s
[01:41:19.873] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104327813508738176:106:0)
[01:41:19.873] Timer tick, asking Buildbucket for the build status
[01:41:19.941] Build 8938897751351345536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:41:19.941] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104327813508738176:107:0) after 1m0s
[01:42:19.988] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104327813508738176:107:0)
[01:42:19.988] Timer tick, asking Buildbucket for the build status
[01:42:20.026] Build 8938897751351345536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:42:20.026] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104327813508738176:108:0) after 1m0s
[01:43:20.063] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104327813508738176:108:0)
[01:43:20.063] Timer tick, asking Buildbucket for the build status
[01:43:20.155] Build 8938897751351345536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:43:20.155] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104327813508738176:109:0) after 1m0s
[01:44:20.203] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104327813508738176:109:0)
[01:44:20.203] Timer tick, asking Buildbucket for the build status
[01:44:20.301] Build 8938897751351345536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:44:20.301] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104327813508738176:110:0) after 1m0s
[01:45:20.442] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104327813508738176:110:0)
[01:45:20.442] Timer tick, asking Buildbucket for the build status
[01:45:20.561] Build 8938897751351345536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:45:20.561] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104327813508738176:111:0) after 1m0s
[01:46:20.584] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104327813508738176:111:0)
[01:46:20.584] Timer tick, asking Buildbucket for the build status
[01:46:20.691] Build 8938897751351345536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:46:20.691] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104327813508738176:112:0) after 1m0s
[01:47:20.786] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104327813508738176:112:0)
[01:47:20.786] Timer tick, asking Buildbucket for the build status
[01:47:25.400] Build 8938897751351345536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:47:25.400] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104327813508738176:113:0) after 1m0s
[01:48:25.427] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104327813508738176:113:0)
[01:48:25.427] Timer tick, asking Buildbucket for the build status
[01:48:25.723] Build 8938897751351345536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:48:25.723] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104327813508738176:114:0) after 1m0s
[01:49:25.787] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104327813508738176:114:0)
[01:49:25.787] Timer tick, asking Buildbucket for the build status
[01:49:25.835] Build 8938897751351345536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:49:25.835] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104327813508738176:115:0) after 1m0s
[01:50:25.864] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104327813508738176:115:0)
[01:50:25.864] Timer tick, asking Buildbucket for the build status
[01:50:25.907] Build 8938897751351345536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:50:25.907] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104327813508738176:116:0) after 1m0s
[01:51:25.958] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104327813508738176:116:0)
[01:51:25.958] Timer tick, asking Buildbucket for the build status
[01:51:26.084] Build 8938897751351345536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:51:26.084] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104327813508738176:117:0) after 1m0s
[01:52:26.117] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104327813508738176:117:0)
[01:52:26.117] Timer tick, asking Buildbucket for the build status
[01:52:26.178] Build 8938897751351345536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:52:26.178] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104327813508738176:118:0) after 1m0s
[01:53:26.251] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104327813508738176:118:0)
[01:53:26.251] Timer tick, asking Buildbucket for the build status
[01:53:26.357] Build 8938897751351345536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:53:26.357] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104327813508738176:119:0) after 1m0s
[01:54:26.469] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104327813508738176:119:0)
[01:54:26.469] Timer tick, asking Buildbucket for the build status
[01:54:26.547] Build 8938897751351345536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:54:26.547] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104327813508738176:120:0) after 1m0s
[01:55:26.591] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104327813508738176:120:0)
[01:55:26.591] Timer tick, asking Buildbucket for the build status
[01:55:26.698] Build 8938897751351345536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:55:26.698] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104327813508738176:121:0) after 1m0s
[01:56:26.725] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104327813508738176:121:0)
[01:56:26.725] Timer tick, asking Buildbucket for the build status
[01:56:28.044] Build 8938897751351345536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:56:28.044] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104327813508738176:122:0) after 1m0s
[01:57:28.073] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104327813508738176:122:0)
[01:57:28.073] Timer tick, asking Buildbucket for the build status
[01:57:28.341] Build 8938897751351345536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:57:28.341] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104327813508738176:123:0) after 1m0s
[01:58:28.365] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104327813508738176:123:0)
[01:58:28.365] Timer tick, asking Buildbucket for the build status
[01:58:28.452] Build 8938897751351345536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:58:28.452] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9104327813508738176:124:0) after 1m0s
[01:58:45.784] Received PubSub notification, asking Buildbucket for the build status
[01:58:45.883] Build 8938897751351345536: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[01:58:45.883] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"completed_ts": "1533607123927740",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1533599819762670",
"failure_reason": "BUILD_FAILURE",
"id": "8938897751351345536",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"8d1651ed349b24996e4eb65f42832ede9067d8d8\"}}",
"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/8938897751351345536/+/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\": [\"3712-19aca72\"], \"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\": \"64cb8d9d67fbe924ff2c48d1fd9c73437d735344\", \"got_swarming_client_revision\": \"486c9b53c4d54dd4b95bb6ce0e31160e600dfc11\", \"got_revision\": \"8d1651ed349b24996e4eb65f42832ede9067d8d8\", \"recipe\": \"swarming/deterministic_build\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#24199}\", \"$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@{#581042}\", \"branch\": \"refs/heads/master\", \"revision\": \"8d1651ed349b24996e4eb65f42832ede9067d8d8\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"buildername\": \"Windows deterministic\", \"got_webrtc_revision\": \"eb73a7bd16c725477ca2da6dc0e6fea236616d44\", \"mastername\": \"chromium.fyi\", \"got_angle_revision\": \"6ba9754f4a5668936976ca7d8f5ec69de30f9aee\", \"buildbucket\": {\"hostname\": \"cr-buildbucket.appspot.com\", \"build\": {\"created_ts\": 1533599819762670, \"tags\": [\"builder:Windows deterministic\", \"buildset:commit/git/8d1651ed349b24996e4eb65f42832ede9067d8d8\", \"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/8d1651ed349b24996e4eb65f42832ede9067d8d8\", \"gitiles_ref:refs/heads/master\", \"scheduler_invocation_id:9104327813508738176\", \"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\": \"8938897751351345536\"}}, \"got_v8_revision\": \"973f34ce78a25a64567f0e12348d4114429c7342\", \"got_v8_revision_cp\": \"refs/heads/7.0.159@{#1}\", \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"buildnumber\": 9671, \"path_config\": \"generic\", \"bot_id\": \"swarm2173-c4\", \"got_buildtools_revision\": \"9a90d9aaadeb5e04327ed05775f45132e4b3523f\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1533599952536050",
"status": "COMPLETED",
"status_changed_ts": "1533607125336720",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/9671",
"builder:Windows deterministic",
"buildset:commit/git/8d1651ed349b24996e4eb65f42832ede9067d8d8",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/8d1651ed349b24996e4eb65f42832ede9067d8d8",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9104327813508738176",
"scheduler_job_id:chromium/Windows deterministic",
"swarming_dimension:builder:Windows deterministic",
"swarming_dimension:cores:8",
"swarming_dimension:cpu:x86-64",
"swarming_dimension:os:Windows-10",
"swarming_dimension:pool:luci.chromium.ci",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:build_address:luci.chromium.ci/Windows deterministic/9671",
"swarming_tag:buildbucket_bucket:luci.chromium.ci",
"swarming_tag:buildbucket_build_id:8938897751351345536",
"swarming_tag:buildbucket_hostname:cr-buildbucket.appspot.com",
"swarming_tag:buildbucket_template_canary:0",
"swarming_tag:buildbucket_template_revision:1843d7ecb7176e182855328359db08c38323a012",
"swarming_tag:builder:Windows deterministic",
"swarming_tag:buildset:commit/git/8d1651ed349b24996e4eb65f42832ede9067d8d8",
"swarming_tag:buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/8d1651ed349b24996e4eb65f42832ede9067d8d8",
"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/8938897751351345536/+/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:9104327813508738176",
"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:f9bb609e9e946f9a36e28b789542bbb7a338f795",
"swarming_tag:user:None",
"swarming_tag:user_agent:luci-scheduler",
"swarming_tag:vpython:native-python-wrapper",
"swarming_task_id:3f2a7db213a35b10",
"user_agent:luci-scheduler"
],
"updated_ts": "1533607125338140",
"url": "https://ci.chromium.org/p/chromium/builders/luci.chromium.ci/Windows deterministic/9671",
"utcnow_ts": "1533607125870090"
}
}
[01:58:45.883] Invocation finished in 2h1m47.562118683s with status FAILED