[12:29:50.339] New invocation is queued and will start shortly
[12:29:51.604] Starting the invocation (attempt 1)
[12:29:51.636] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[12:29:51.636] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[12:29:51.636] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9102377910937587296",
"parameters_json": "{\"builder_name\":\"Windows deterministic\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src.git\",\"revision\":\"4336e2136befa094e0df1cf4d066e717959fa4ff\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Windows deterministic",
"scheduler_invocation_id:9102377910937587296",
"scheduler_job_id:chromium/Windows deterministic",
"user_agent:luci-scheduler",
"buildset:commit/git/4336e2136befa094e0df1cf4d066e717959fa4ff",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/4336e2136befa094e0df1cf4d066e717959fa4ff",
"gitiles_ref:refs/heads/master"
]
}
[12:29:53.437] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1535459391716530",
"id": "8936947848830541248",
"lease_expiration_ts": "1535603393141030",
"lease_key": "116610029",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"4336e2136befa094e0df1cf4d066e717959fa4ff\"}}",
"project": "chromium",
"result_details_json": "null",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1535459393352150",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/9902",
"builder:Windows deterministic",
"buildset:commit/git/4336e2136befa094e0df1cf4d066e717959fa4ff",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/4336e2136befa094e0df1cf4d066e717959fa4ff",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9102377910937587296",
"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/9902",
"swarming_tag:buildbucket_bucket:luci.chromium.ci",
"swarming_tag:buildbucket_build_id:8936947848830541248",
"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/4336e2136befa094e0df1cf4d066e717959fa4ff",
"swarming_tag:buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/4336e2136befa094e0df1cf4d066e717959fa4ff",
"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/8936947848830541248/+/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:9102377910937587296",
"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:aa3043a77f17df14977ac73e7af8d482524bb62a",
"swarming_tag:user:None",
"swarming_tag:user_agent:luci-scheduler",
"swarming_tag:vpython:native-python-wrapper",
"swarming_task_id:3f99548445252210",
"user_agent:luci-scheduler"
],
"updated_ts": "1535459393352280",
"url": "https://ci.chromium.org/p/chromium/builders/luci.chromium.ci/Windows deterministic/9902",
"utcnow_ts": "1535459393424760"
}
}
[12:29:53.437] Task URL: https://ci.chromium.org/p/chromium/builders/luci.chromium.ci/Windows deterministic/9902
[12:29:53.438] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102377910937587296:2:0) after 1m0s
[12:29:54.670] Received PubSub notification, asking Buildbucket for the build status
[12:29:54.741] Build 8936947848830541248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:30:53.456] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102377910937587296:2:0)
[12:30:53.456] Timer tick, asking Buildbucket for the build status
[12:30:53.662] Build 8936947848830541248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:30:53.662] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102377910937587296:4:0) after 1m0s
[12:31:53.683] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102377910937587296:4:0)
[12:31:53.683] Timer tick, asking Buildbucket for the build status
[12:31:53.754] Build 8936947848830541248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:31:53.754] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102377910937587296:5:0) after 1m0s
[12:32:53.776] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102377910937587296:5:0)
[12:32:53.776] Timer tick, asking Buildbucket for the build status
[12:32:53.849] Build 8936947848830541248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:32:53.849] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102377910937587296:6:0) after 1m0s
[12:33:53.924] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102377910937587296:6:0)
[12:33:53.924] Timer tick, asking Buildbucket for the build status
[12:33:53.960] Build 8936947848830541248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:33:53.960] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102377910937587296:7:0) after 1m0s
[12:34:53.984] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102377910937587296:7:0)
[12:34:53.984] Timer tick, asking Buildbucket for the build status
[12:34:54.039] Build 8936947848830541248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:34:54.039] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102377910937587296:8:0) after 1m0s
[12:35:54.081] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102377910937587296:8:0)
[12:35:54.081] Timer tick, asking Buildbucket for the build status
[12:35:54.210] Build 8936947848830541248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:35:54.210] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102377910937587296:9:0) after 1m0s
[12:36:54.272] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102377910937587296:9:0)
[12:36:54.272] Timer tick, asking Buildbucket for the build status
[12:36:54.434] Build 8936947848830541248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:36:54.435] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102377910937587296:10:0) after 1m0s
[12:37:54.456] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102377910937587296:10:0)
[12:37:54.456] Timer tick, asking Buildbucket for the build status
[12:37:54.530] Build 8936947848830541248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:37:54.530] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102377910937587296:11:0) after 1m0s
[12:38:54.548] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102377910937587296:11:0)
[12:38:54.548] Timer tick, asking Buildbucket for the build status
[12:38:54.611] Build 8936947848830541248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:38:54.611] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102377910937587296:12:0) after 1m0s
[12:39:54.645] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102377910937587296:12:0)
[12:39:54.645] Timer tick, asking Buildbucket for the build status
[12:39:54.982] Build 8936947848830541248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:39:54.982] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102377910937587296:13:0) after 1m0s
[12:40:54.999] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102377910937587296:13:0)
[12:40:54.999] Timer tick, asking Buildbucket for the build status
[12:40:55.130] Build 8936947848830541248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:40:55.130] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102377910937587296:14:0) after 1m0s
[12:41:55.149] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102377910937587296:14:0)
[12:41:55.149] Timer tick, asking Buildbucket for the build status
[12:41:55.239] Build 8936947848830541248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:41:55.239] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102377910937587296:15:0) after 1m0s
[12:42:55.257] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102377910937587296:15:0)
[12:42:55.257] Timer tick, asking Buildbucket for the build status
[12:42:55.323] Build 8936947848830541248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:42:55.323] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102377910937587296:16:0) after 1m0s
[12:43:55.410] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102377910937587296:16:0)
[12:43:55.410] Timer tick, asking Buildbucket for the build status
[12:43:55.480] Build 8936947848830541248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:43:55.480] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102377910937587296:17:0) after 1m0s
[12:44:55.500] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102377910937587296:17:0)
[12:44:55.500] Timer tick, asking Buildbucket for the build status
[12:44:55.812] Build 8936947848830541248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:44:55.812] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102377910937587296:18:0) after 1m0s
[12:45:55.831] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102377910937587296:18:0)
[12:45:55.831] Timer tick, asking Buildbucket for the build status
[12:45:55.908] Build 8936947848830541248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:45:55.908] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102377910937587296:19:0) after 1m0s
[12:46:55.934] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102377910937587296:19:0)
[12:46:55.934] Timer tick, asking Buildbucket for the build status
[12:46:56.004] Build 8936947848830541248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:46:56.004] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102377910937587296:20:0) after 1m0s
[12:47:56.021] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102377910937587296:20:0)
[12:47:56.021] Timer tick, asking Buildbucket for the build status
[12:47:56.087] Build 8936947848830541248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:47:56.087] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102377910937587296:21:0) after 1m0s
[12:48:56.108] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102377910937587296:21:0)
[12:48:56.108] Timer tick, asking Buildbucket for the build status
[12:48:56.220] Build 8936947848830541248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:48:56.220] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102377910937587296:22:0) after 1m0s
[12:49:56.238] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102377910937587296:22:0)
[12:49:56.238] Timer tick, asking Buildbucket for the build status
[12:49:56.438] Build 8936947848830541248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:49:56.438] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102377910937587296:23:0) after 1m0s
[12:50:56.483] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102377910937587296:23:0)
[12:50:56.483] Timer tick, asking Buildbucket for the build status
[12:50:56.527] Build 8936947848830541248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:50:56.527] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102377910937587296:24:0) after 1m0s
[12:51:56.547] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102377910937587296:24:0)
[12:51:56.547] Timer tick, asking Buildbucket for the build status
[12:51:56.654] Build 8936947848830541248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:51:56.654] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102377910937587296:25:0) after 1m0s
[12:52:56.674] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102377910937587296:25:0)
[12:52:56.674] Timer tick, asking Buildbucket for the build status
[12:52:56.815] Build 8936947848830541248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:52:56.815] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102377910937587296:26:0) after 1m0s
[12:53:56.833] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102377910937587296:26:0)
[12:53:56.833] Timer tick, asking Buildbucket for the build status
[12:53:56.926] Build 8936947848830541248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:53:56.926] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102377910937587296:27:0) after 1m0s
[12:54:56.945] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102377910937587296:27:0)
[12:54:56.945] Timer tick, asking Buildbucket for the build status
[12:54:57.117] Build 8936947848830541248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:54:57.117] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102377910937587296:28:0) after 1m0s
[12:55:57.280] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102377910937587296:28:0)
[12:55:57.280] Timer tick, asking Buildbucket for the build status
[12:55:57.420] Build 8936947848830541248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:55:57.420] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102377910937587296:29:0) after 1m0s
[12:56:57.439] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102377910937587296:29:0)
[12:56:57.439] Timer tick, asking Buildbucket for the build status
[12:56:57.569] Build 8936947848830541248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:56:57.569] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102377910937587296:30:0) after 1m0s
[12:57:57.588] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102377910937587296:30:0)
[12:57:57.588] Timer tick, asking Buildbucket for the build status
[12:57:57.664] Build 8936947848830541248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:57:57.664] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102377910937587296:31:0) after 1m0s
[12:58:57.684] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102377910937587296:31:0)
[12:58:57.684] Timer tick, asking Buildbucket for the build status
[12:58:57.747] Build 8936947848830541248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:58:57.747] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102377910937587296:32:0) after 1m0s
[12:59:57.811] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102377910937587296:32:0)
[12:59:57.811] Timer tick, asking Buildbucket for the build status
[12:59:57.899] Build 8936947848830541248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:59:57.899] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102377910937587296:33:0) after 1m0s
[13:00:57.989] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102377910937587296:33:0)
[13:00:57.989] Timer tick, asking Buildbucket for the build status
[13:00:58.118] Build 8936947848830541248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:00:58.118] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102377910937587296:34:0) after 1m0s
[13:01:58.140] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102377910937587296:34:0)
[13:01:58.140] Timer tick, asking Buildbucket for the build status
[13:01:58.178] Build 8936947848830541248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:01:58.178] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102377910937587296:35:0) after 1m0s
[13:02:58.195] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102377910937587296:35:0)
[13:02:58.195] Timer tick, asking Buildbucket for the build status
[13:02:58.313] Build 8936947848830541248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:02:58.313] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102377910937587296:36:0) after 1m0s
[13:03:58.334] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102377910937587296:36:0)
[13:03:58.334] Timer tick, asking Buildbucket for the build status
[13:03:58.393] Build 8936947848830541248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:03:58.393] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102377910937587296:37:0) after 1m0s
[13:04:58.415] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102377910937587296:37:0)
[13:04:58.415] Timer tick, asking Buildbucket for the build status
[13:04:58.477] Build 8936947848830541248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:04:58.477] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102377910937587296:38:0) after 1m0s
[13:05:58.496] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102377910937587296:38:0)
[13:05:58.496] Timer tick, asking Buildbucket for the build status
[13:05:58.534] Build 8936947848830541248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:05:58.534] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102377910937587296:39:0) after 1m0s
[13:06:58.552] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102377910937587296:39:0)
[13:06:58.552] Timer tick, asking Buildbucket for the build status
[13:06:58.692] Build 8936947848830541248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:06:58.692] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102377910937587296:40:0) after 1m0s
[13:07:58.712] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102377910937587296:40:0)
[13:07:58.712] Timer tick, asking Buildbucket for the build status
[13:07:58.771] Build 8936947848830541248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:07:58.771] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102377910937587296:41:0) after 1m0s
[13:08:58.793] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102377910937587296:41:0)
[13:08:58.793] Timer tick, asking Buildbucket for the build status
[13:08:58.849] Build 8936947848830541248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:08:58.849] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102377910937587296:42:0) after 1m0s
[13:09:58.877] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102377910937587296:42:0)
[13:09:58.877] Timer tick, asking Buildbucket for the build status
[13:09:58.907] Build 8936947848830541248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:09:58.907] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102377910937587296:43:0) after 1m0s
[13:10:58.931] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102377910937587296:43:0)
[13:10:58.931] Timer tick, asking Buildbucket for the build status
[13:10:59.020] Build 8936947848830541248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:10:59.020] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102377910937587296:44:0) after 1m0s
[13:11:59.048] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102377910937587296:44:0)
[13:11:59.048] Timer tick, asking Buildbucket for the build status
[13:11:59.193] Build 8936947848830541248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:11:59.193] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102377910937587296:45:0) after 1m0s
[13:12:59.236] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102377910937587296:45:0)
[13:12:59.236] Timer tick, asking Buildbucket for the build status
[13:12:59.329] Build 8936947848830541248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:12:59.329] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102377910937587296:46:0) after 1m0s
[13:13:59.350] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102377910937587296:46:0)
[13:13:59.350] Timer tick, asking Buildbucket for the build status
[13:13:59.442] Build 8936947848830541248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:13:59.442] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102377910937587296:47:0) after 1m0s
[13:14:59.625] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102377910937587296:47:0)
[13:14:59.625] Timer tick, asking Buildbucket for the build status
[13:14:59.664] Build 8936947848830541248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:14:59.664] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102377910937587296:48:0) after 1m0s
[13:15:59.714] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102377910937587296:48:0)
[13:15:59.714] Timer tick, asking Buildbucket for the build status
[13:15:59.761] Build 8936947848830541248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:15:59.761] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102377910937587296:49:0) after 1m0s
[13:16:59.783] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102377910937587296:49:0)
[13:16:59.783] Timer tick, asking Buildbucket for the build status
[13:16:59.860] Build 8936947848830541248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:16:59.861] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102377910937587296:50:0) after 1m0s
[13:17:59.883] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102377910937587296:50:0)
[13:17:59.883] Timer tick, asking Buildbucket for the build status
[13:17:59.955] Build 8936947848830541248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:17:59.956] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102377910937587296:51:0) after 1m0s
[13:18:59.992] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102377910937587296:51:0)
[13:18:59.993] Timer tick, asking Buildbucket for the build status
[13:19:00.070] Build 8936947848830541248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:19:00.070] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102377910937587296:52:0) after 1m0s
[13:20:00.092] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102377910937587296:52:0)
[13:20:00.092] Timer tick, asking Buildbucket for the build status
[13:20:00.572] Build 8936947848830541248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:20:00.572] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102377910937587296:53:0) after 1m0s
[13:21:00.592] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102377910937587296:53:0)
[13:21:00.592] Timer tick, asking Buildbucket for the build status
[13:21:00.657] Build 8936947848830541248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:21:00.657] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102377910937587296:54:0) after 1m0s
[13:22:00.737] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102377910937587296:54:0)
[13:22:00.737] Timer tick, asking Buildbucket for the build status
[13:22:00.912] Build 8936947848830541248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:22:00.912] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102377910937587296:55:0) after 1m0s
[13:23:00.932] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102377910937587296:55:0)
[13:23:00.932] Timer tick, asking Buildbucket for the build status
[13:23:00.976] Build 8936947848830541248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:23:00.976] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102377910937587296:56:0) after 1m0s
[13:24:01.003] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102377910937587296:56:0)
[13:24:01.003] Timer tick, asking Buildbucket for the build status
[13:24:01.067] Build 8936947848830541248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:24:01.067] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102377910937587296:57:0) after 1m0s
[13:25:01.097] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102377910937587296:57:0)
[13:25:01.097] Timer tick, asking Buildbucket for the build status
[13:25:01.200] Build 8936947848830541248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:25:01.200] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102377910937587296:58:0) after 1m0s
[13:26:01.226] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102377910937587296:58:0)
[13:26:01.226] Timer tick, asking Buildbucket for the build status
[13:26:01.309] Build 8936947848830541248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:26:01.309] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102377910937587296:59:0) after 1m0s
[13:27:01.371] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102377910937587296:59:0)
[13:27:01.371] Timer tick, asking Buildbucket for the build status
[13:27:01.503] Build 8936947848830541248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:27:01.503] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102377910937587296:60:0) after 1m0s
[13:28:01.533] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102377910937587296:60:0)
[13:28:01.533] Timer tick, asking Buildbucket for the build status
[13:28:01.798] Build 8936947848830541248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:28:01.798] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102377910937587296:61:0) after 1m0s
[13:29:01.818] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102377910937587296:61:0)
[13:29:01.818] Timer tick, asking Buildbucket for the build status
[13:29:01.868] Build 8936947848830541248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:29:01.868] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102377910937587296:62:0) after 1m0s
[13:30:01.886] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102377910937587296:62:0)
[13:30:01.886] Timer tick, asking Buildbucket for the build status
[13:30:01.984] Build 8936947848830541248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:30:01.984] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102377910937587296:63:0) after 1m0s
[13:31:02.161] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102377910937587296:63:0)
[13:31:02.161] Timer tick, asking Buildbucket for the build status
[13:31:02.262] Build 8936947848830541248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:31:02.262] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102377910937587296:64:0) after 1m0s
[13:32:02.635] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102377910937587296:64:0)
[13:32:02.635] Timer tick, asking Buildbucket for the build status
[13:32:05.620] Build 8936947848830541248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:32:05.620] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102377910937587296:65:0) after 1m0s
[13:33:05.656] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102377910937587296:65:0)
[13:33:05.656] Timer tick, asking Buildbucket for the build status
[13:33:05.759] Build 8936947848830541248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:33:05.759] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102377910937587296:66:0) after 1m0s
[13:34:05.778] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102377910937587296:66:0)
[13:34:05.778] Timer tick, asking Buildbucket for the build status
[13:34:05.855] Build 8936947848830541248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:34:05.855] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102377910937587296:67:0) after 1m0s
[13:35:05.875] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102377910937587296:67:0)
[13:35:05.875] Timer tick, asking Buildbucket for the build status
[13:35:05.942] Build 8936947848830541248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:35:05.942] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102377910937587296:68:0) after 1m0s
[13:36:06.020] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102377910937587296:68:0)
[13:36:06.020] Timer tick, asking Buildbucket for the build status
[13:36:06.097] Build 8936947848830541248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:36:06.097] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102377910937587296:69:0) after 1m0s
[13:37:06.189] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102377910937587296:69:0)
[13:37:06.189] Timer tick, asking Buildbucket for the build status
[13:37:06.256] Build 8936947848830541248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:37:06.256] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102377910937587296:70:0) after 1m0s
[13:38:06.279] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102377910937587296:70:0)
[13:38:06.279] Timer tick, asking Buildbucket for the build status
[13:38:06.396] Build 8936947848830541248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:38:06.396] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102377910937587296:71:0) after 1m0s
[13:39:06.431] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102377910937587296:71:0)
[13:39:06.431] Timer tick, asking Buildbucket for the build status
[13:39:06.518] Build 8936947848830541248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:39:06.518] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102377910937587296:72:0) after 1m0s
[13:40:06.566] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102377910937587296:72:0)
[13:40:06.566] Timer tick, asking Buildbucket for the build status
[13:40:06.622] Build 8936947848830541248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:40:06.622] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102377910937587296:73:0) after 1m0s
[13:41:06.646] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102377910937587296:73:0)
[13:41:06.646] Timer tick, asking Buildbucket for the build status
[13:41:06.685] Build 8936947848830541248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:41:06.685] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102377910937587296:74:0) after 1m0s
[13:42:06.735] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102377910937587296:74:0)
[13:42:06.735] Timer tick, asking Buildbucket for the build status
[13:42:06.802] Build 8936947848830541248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:42:06.802] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102377910937587296:75:0) after 1m0s
[13:43:06.824] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102377910937587296:75:0)
[13:43:06.824] Timer tick, asking Buildbucket for the build status
[13:43:06.922] Build 8936947848830541248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:43:06.922] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102377910937587296:76:0) after 1m0s
[13:44:06.976] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102377910937587296:76:0)
[13:44:06.976] Timer tick, asking Buildbucket for the build status
[13:44:07.060] Build 8936947848830541248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:44:07.060] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102377910937587296:77:0) after 1m0s
[13:45:07.084] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102377910937587296:77:0)
[13:45:07.084] Timer tick, asking Buildbucket for the build status
[13:45:07.152] Build 8936947848830541248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:45:07.152] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102377910937587296:78:0) after 1m0s
[13:46:07.173] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102377910937587296:78:0)
[13:46:07.173] Timer tick, asking Buildbucket for the build status
[13:46:07.274] Build 8936947848830541248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:46:07.274] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102377910937587296:79:0) after 1m0s
[13:47:07.302] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102377910937587296:79:0)
[13:47:07.302] Timer tick, asking Buildbucket for the build status
[13:47:07.381] Build 8936947848830541248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:47:07.381] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102377910937587296:80:0) after 1m0s
[13:48:07.403] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102377910937587296:80:0)
[13:48:07.403] Timer tick, asking Buildbucket for the build status
[13:48:07.480] Build 8936947848830541248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:48:07.480] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102377910937587296:81:0) after 1m0s
[13:49:07.503] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102377910937587296:81:0)
[13:49:07.503] Timer tick, asking Buildbucket for the build status
[13:49:07.538] Build 8936947848830541248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:49:07.538] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102377910937587296:82:0) after 1m0s
[13:50:07.560] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102377910937587296:82:0)
[13:50:07.560] Timer tick, asking Buildbucket for the build status
[13:50:07.638] Build 8936947848830541248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:50:07.638] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102377910937587296:83:0) after 1m0s
[13:51:07.660] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102377910937587296:83:0)
[13:51:07.660] Timer tick, asking Buildbucket for the build status
[13:51:07.928] Build 8936947848830541248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:51:07.928] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102377910937587296:84:0) after 1m0s
[13:52:07.960] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102377910937587296:84:0)
[13:52:07.960] Timer tick, asking Buildbucket for the build status
[13:52:08.072] Build 8936947848830541248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:52:08.072] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102377910937587296:85:0) after 1m0s
[13:53:08.093] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102377910937587296:85:0)
[13:53:08.093] Timer tick, asking Buildbucket for the build status
[13:53:08.230] Build 8936947848830541248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:53:08.230] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102377910937587296:86:0) after 1m0s
[13:54:08.249] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102377910937587296:86:0)
[13:54:08.249] Timer tick, asking Buildbucket for the build status
[13:54:08.447] Build 8936947848830541248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:54:08.447] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102377910937587296:87:0) after 1m0s
[13:55:08.467] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102377910937587296:87:0)
[13:55:08.467] Timer tick, asking Buildbucket for the build status
[13:55:08.527] Build 8936947848830541248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:55:08.527] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102377910937587296:88:0) after 1m0s
[13:56:08.552] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102377910937587296:88:0)
[13:56:08.552] Timer tick, asking Buildbucket for the build status
[13:56:08.679] Build 8936947848830541248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:56:08.679] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102377910937587296:89:0) after 1m0s
[13:57:08.701] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102377910937587296:89:0)
[13:57:08.701] Timer tick, asking Buildbucket for the build status
[13:57:08.743] Build 8936947848830541248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:57:08.743] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102377910937587296:90:0) after 1m0s
[13:58:08.764] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102377910937587296:90:0)
[13:58:08.764] Timer tick, asking Buildbucket for the build status
[13:58:08.822] Build 8936947848830541248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:58:08.822] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102377910937587296:91:0) after 1m0s
[13:59:08.850] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102377910937587296:91:0)
[13:59:08.850] Timer tick, asking Buildbucket for the build status
[13:59:09.111] Build 8936947848830541248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:59:09.111] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102377910937587296:92:0) after 1m0s
[14:00:09.138] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102377910937587296:92:0)
[14:00:09.138] Timer tick, asking Buildbucket for the build status
[14:00:09.197] Build 8936947848830541248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:00:09.197] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102377910937587296:93:0) after 1m0s
[14:01:09.221] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102377910937587296:93:0)
[14:01:09.221] Timer tick, asking Buildbucket for the build status
[14:01:09.442] Build 8936947848830541248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:01:09.442] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102377910937587296:94:0) after 1m0s
[14:02:09.463] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102377910937587296:94:0)
[14:02:09.463] Timer tick, asking Buildbucket for the build status
[14:02:09.719] Build 8936947848830541248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:02:09.719] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102377910937587296:95:0) after 1m0s
[14:03:09.741] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102377910937587296:95:0)
[14:03:09.741] Timer tick, asking Buildbucket for the build status
[14:03:10.125] Build 8936947848830541248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:03:10.125] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102377910937587296:96:0) after 1m0s
[14:04:10.224] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102377910937587296:96:0)
[14:04:10.224] Timer tick, asking Buildbucket for the build status
[14:04:10.375] Build 8936947848830541248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:04:10.375] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102377910937587296:97:0) after 1m0s
[14:05:10.400] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102377910937587296:97:0)
[14:05:10.400] Timer tick, asking Buildbucket for the build status
[14:05:10.559] Build 8936947848830541248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:05:10.559] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102377910937587296:98:0) after 1m0s
[14:06:10.580] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102377910937587296:98:0)
[14:06:10.580] Timer tick, asking Buildbucket for the build status
[14:06:10.640] Build 8936947848830541248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:06:10.640] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102377910937587296:99:0) after 1m0s
[14:07:10.703] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102377910937587296:99:0)
[14:07:10.703] Timer tick, asking Buildbucket for the build status
[14:07:10.737] Build 8936947848830541248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:07:10.737] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102377910937587296:100:0) after 1m0s
[14:08:10.759] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102377910937587296:100:0)
[14:08:10.759] Timer tick, asking Buildbucket for the build status
[14:08:10.917] Build 8936947848830541248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:08:10.917] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102377910937587296:101:0) after 1m0s
[14:09:10.936] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102377910937587296:101:0)
[14:09:10.936] Timer tick, asking Buildbucket for the build status
[14:09:11.009] Build 8936947848830541248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:09:11.009] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102377910937587296:102:0) after 1m0s
[14:10:11.057] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102377910937587296:102:0)
[14:10:11.057] Timer tick, asking Buildbucket for the build status
[14:10:11.233] Build 8936947848830541248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:10:11.233] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102377910937587296:103:0) after 1m0s
[14:11:11.258] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102377910937587296:103:0)
[14:11:11.258] Timer tick, asking Buildbucket for the build status
[14:11:11.420] Build 8936947848830541248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:11:11.420] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102377910937587296:104:0) after 1m0s
[14:12:11.449] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102377910937587296:104:0)
[14:12:11.449] Timer tick, asking Buildbucket for the build status
[14:12:11.703] Build 8936947848830541248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:12:11.703] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102377910937587296:105:0) after 1m0s
[14:13:11.726] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102377910937587296:105:0)
[14:13:11.726] Timer tick, asking Buildbucket for the build status
[14:13:11.813] Build 8936947848830541248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:13:11.813] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102377910937587296:106:0) after 1m0s
[14:14:11.838] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102377910937587296:106:0)
[14:14:11.838] Timer tick, asking Buildbucket for the build status
[14:14:11.984] Build 8936947848830541248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:14:11.984] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102377910937587296:107:0) after 1m0s
[14:15:12.013] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102377910937587296:107:0)
[14:15:12.013] Timer tick, asking Buildbucket for the build status
[14:15:12.050] Build 8936947848830541248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:15:12.050] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102377910937587296:108:0) after 1m0s
[14:16:12.128] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102377910937587296:108:0)
[14:16:12.128] Timer tick, asking Buildbucket for the build status
[14:16:12.238] Build 8936947848830541248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:16:12.238] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102377910937587296:109:0) after 1m0s
[14:17:12.261] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102377910937587296:109:0)
[14:17:12.261] Timer tick, asking Buildbucket for the build status
[14:17:12.386] Build 8936947848830541248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:17:12.386] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102377910937587296:110:0) after 1m0s
[14:18:12.500] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102377910937587296:110:0)
[14:18:12.500] Timer tick, asking Buildbucket for the build status
[14:18:12.740] Build 8936947848830541248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:18:12.740] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102377910937587296:111:0) after 1m0s
[14:19:12.768] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102377910937587296:111:0)
[14:19:12.769] Timer tick, asking Buildbucket for the build status
[14:19:12.828] Build 8936947848830541248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:19:12.828] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102377910937587296:112:0) after 1m0s
[14:20:12.856] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102377910937587296:112:0)
[14:20:12.856] Timer tick, asking Buildbucket for the build status
[14:20:12.931] Build 8936947848830541248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:20:12.931] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102377910937587296:113:0) after 1m0s
[14:21:12.953] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102377910937587296:113:0)
[14:21:12.953] Timer tick, asking Buildbucket for the build status
[14:21:13.094] Build 8936947848830541248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:21:13.094] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102377910937587296:114:0) after 1m0s
[14:22:13.117] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102377910937587296:114:0)
[14:22:13.117] Timer tick, asking Buildbucket for the build status
[14:22:13.174] Build 8936947848830541248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:22:13.174] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102377910937587296:115:0) after 1m0s
[14:23:13.208] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102377910937587296:115:0)
[14:23:13.208] Timer tick, asking Buildbucket for the build status
[14:23:13.266] Build 8936947848830541248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:23:13.266] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102377910937587296:116:0) after 1m0s
[14:24:13.289] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102377910937587296:116:0)
[14:24:13.289] Timer tick, asking Buildbucket for the build status
[14:24:13.326] Build 8936947848830541248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:24:13.326] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102377910937587296:117:0) after 1m0s
[14:24:47.171] Received PubSub notification, asking Buildbucket for the build status
[14:24:47.322] Build 8936947848830541248: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[14:24:47.322] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"completed_ts": "1535466286175720",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1535459391716530",
"failure_reason": "BUILD_FAILURE",
"id": "8936947848830541248",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"4336e2136befa094e0df1cf4d066e717959fa4ff\"}}",
"project": "chromium",
"result": "FAILURE",
"result_details_json": "{\"ui\": {\"info\": \"Failure Second build\"}, \"build_run_result\": {\"infraFailure\": null, \"recipeResult\": {\"failure\": {\"failure\": {\"step\": \"Second build\"}, \"humanReason\": \"Step('Second build') 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/8936947848830541248/+/annotations\"}, \"swarming\": {\"bot_dimensions\": {\"machine_type\": [\"n1-standard-8\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"], \"locale\": [\"en_US.cp1252\"], \"os\": [\"Windows\", \"Windows-10\", \"Windows-10-15063\"], \"builder\": [\"Windows deterministic\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Haswell_GCE\"], \"python\": [\"2.7.6\"], \"server_version\": [\"3740-90b257a\"], \"image\": [\"chrome-win10-1703-2018-04-20-61acaf8983f\"], \"gpu\": [\"none\"], \"cores\": [\"8\"], \"integrity\": [\"high\"], \"id\": [\"swarm2173-c4\"], \"pool\": [\"luci.chromium.ci\"], \"caches\": [\"git\", \"goma_v2\", \"vpython\"]}}, \"properties\": {\"got_nacl_revision\": \"9a87d6b9de35325efb08321640879e79ed0dfcc9\", \"got_swarming_client_revision\": \"486c9b53c4d54dd4b95bb6ce0e31160e600dfc11\", \"got_revision\": \"4336e2136befa094e0df1cf4d066e717959fa4ff\", \"recipe\": \"swarming/deterministic_build\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#24462}\", \"$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@{#586663}\", \"branch\": \"refs/heads/master\", \"revision\": \"4336e2136befa094e0df1cf4d066e717959fa4ff\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"buildername\": \"Windows deterministic\", \"got_webrtc_revision\": \"69b03e960ed6ebfda624c4ad9ef7159d8083ea62\", \"mastername\": \"chromium.fyi\", \"got_angle_revision\": \"47f6dd0d4a21564350d845720d5cf14b158dba12\", \"buildbucket\": {\"hostname\": \"cr-buildbucket.appspot.com\", \"build\": {\"created_ts\": 1535459391716530, \"tags\": [\"builder:Windows deterministic\", \"buildset:commit/git/4336e2136befa094e0df1cf4d066e717959fa4ff\", \"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/4336e2136befa094e0df1cf4d066e717959fa4ff\", \"gitiles_ref:refs/heads/master\", \"scheduler_invocation_id:9102377910937587296\", \"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\": \"8936947848830541248\"}}, \"got_v8_revision\": \"b72914434c3b864c2f6e17317c58b44038889a21\", \"got_v8_revision_cp\": \"refs/heads/7.0.277@{#1}\", \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"buildnumber\": 9902, \"path_config\": \"generic\", \"bot_id\": \"swarm2173-c4\", \"got_buildtools_revision\": \"2dff9c9c74e9d732e6fe57c84ef7fd044cc45d96\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1535459393924540",
"status": "COMPLETED",
"status_changed_ts": "1535466286850610",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/9902",
"builder:Windows deterministic",
"buildset:commit/git/4336e2136befa094e0df1cf4d066e717959fa4ff",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/4336e2136befa094e0df1cf4d066e717959fa4ff",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9102377910937587296",
"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/9902",
"swarming_tag:buildbucket_bucket:luci.chromium.ci",
"swarming_tag:buildbucket_build_id:8936947848830541248",
"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/4336e2136befa094e0df1cf4d066e717959fa4ff",
"swarming_tag:buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/4336e2136befa094e0df1cf4d066e717959fa4ff",
"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/8936947848830541248/+/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:9102377910937587296",
"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:aa3043a77f17df14977ac73e7af8d482524bb62a",
"swarming_tag:user:None",
"swarming_tag:user_agent:luci-scheduler",
"swarming_tag:vpython:native-python-wrapper",
"swarming_task_id:3f99548445252210",
"user_agent:luci-scheduler"
],
"updated_ts": "1535466286852140",
"url": "https://ci.chromium.org/p/chromium/builders/luci.chromium.ci/Windows deterministic/9902",
"utcnow_ts": "1535466287251910"
}
}
[14:24:47.322] Invocation finished in 1h54m56.99932177s with status FAILED