[02:25:54.315] New invocation is queued and will start shortly
[02:25:55.606] Starting the invocation (attempt 1)
[02:25:55.717] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[02:25:55.717] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[02:25:55.717] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9099969789163175760",
"parameters_json": "{\"builder_name\":\"Windows deterministic\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src.git\",\"revision\":\"36e1dc20d9463b47c71bdb8f19c150a7e7bcea98\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Windows deterministic",
"scheduler_invocation_id:9099969789163175760",
"scheduler_job_id:chromium/Windows deterministic",
"user_agent:luci-scheduler",
"buildset:commit/git/36e1dc20d9463b47c71bdb8f19c150a7e7bcea98",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/36e1dc20d9463b47c71bdb8f19c150a7e7bcea98",
"gitiles_ref:refs/heads/master"
]
}
[02:25:57.260] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1537755955863022",
"id": "8934539726783445744",
"lease_expiration_ts": "1537899956999823",
"lease_key": "753308767",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"36e1dc20d9463b47c71bdb8f19c150a7e7bcea98\"}}",
"project": "chromium",
"result_details_json": "null",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1537755957155544",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/10181",
"builder:Windows deterministic",
"buildset:commit/git/36e1dc20d9463b47c71bdb8f19c150a7e7bcea98",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/36e1dc20d9463b47c71bdb8f19c150a7e7bcea98",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9099969789163175760",
"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/10181",
"swarming_tag:buildbucket_bucket:luci.chromium.ci",
"swarming_tag:buildbucket_build_id:8934539726783445744",
"swarming_tag:buildbucket_hostname:cr-buildbucket.appspot.com",
"swarming_tag:buildbucket_template_canary:0",
"swarming_tag:buildbucket_template_revision:4c790cbde9342069ed9b59b6709c8807b14f2913",
"swarming_tag:builder:Windows deterministic",
"swarming_tag:buildset:commit/git/36e1dc20d9463b47c71bdb8f19c150a7e7bcea98",
"swarming_tag:buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/36e1dc20d9463b47c71bdb8f19c150a7e7bcea98",
"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/8934539726783445744/+/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:9099969789163175760",
"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:ded6e667eedfba9ca83cacf95f0d2164f0246aeb",
"swarming_tag:user:None",
"swarming_tag:user_agent:luci-scheduler",
"swarming_tag:vpython:native-python-wrapper",
"swarming_task_id:4022374e36bd4210",
"user_agent:luci-scheduler"
],
"updated_ts": "1537755957155647",
"url": "https://ci.chromium.org/p/chromium/builders/luci.chromium.ci/Windows deterministic/10181",
"utcnow_ts": "1537755957241117"
}
}
[02:25:57.260] Task URL: https://ci.chromium.org/p/chromium/builders/luci.chromium.ci/Windows deterministic/10181
[02:25:57.260] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099969789163175760:2:0) after 1m0s
[02:25:58.696] Received PubSub notification, asking Buildbucket for the build status
[02:25:58.736] Build 8934539726783445744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:26:57.281] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099969789163175760:2:0)
[02:26:57.281] Timer tick, asking Buildbucket for the build status
[02:26:57.353] Build 8934539726783445744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:26:57.354] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099969789163175760:4:0) after 1m0s
[02:27:57.510] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099969789163175760:4:0)
[02:27:57.510] Timer tick, asking Buildbucket for the build status
[02:27:57.573] Build 8934539726783445744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:27:57.573] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099969789163175760:5:0) after 1m0s
[02:28:57.667] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099969789163175760:5:0)
[02:28:57.667] Timer tick, asking Buildbucket for the build status
[02:28:57.709] Build 8934539726783445744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:28:57.709] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099969789163175760:6:0) after 1m0s
[02:29:57.841] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099969789163175760:6:0)
[02:29:57.841] Timer tick, asking Buildbucket for the build status
[02:29:57.907] Build 8934539726783445744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:29:57.907] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099969789163175760:7:0) after 1m0s
[02:30:57.991] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099969789163175760:7:0)
[02:30:57.991] Timer tick, asking Buildbucket for the build status
[02:30:58.112] Build 8934539726783445744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:30:58.112] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099969789163175760:8:0) after 1m0s
[02:31:58.174] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099969789163175760:8:0)
[02:31:58.174] Timer tick, asking Buildbucket for the build status
[02:31:58.258] Build 8934539726783445744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:31:58.258] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099969789163175760:9:0) after 1m0s
[02:32:58.279] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099969789163175760:9:0)
[02:32:58.279] Timer tick, asking Buildbucket for the build status
[02:32:58.358] Build 8934539726783445744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:32:58.358] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099969789163175760:10:0) after 1m0s
[02:33:58.392] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099969789163175760:10:0)
[02:33:58.392] Timer tick, asking Buildbucket for the build status
[02:33:58.457] Build 8934539726783445744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:33:58.457] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099969789163175760:11:0) after 1m0s
[02:34:58.480] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099969789163175760:11:0)
[02:34:58.480] Timer tick, asking Buildbucket for the build status
[02:34:58.567] Build 8934539726783445744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:34:58.567] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099969789163175760:12:0) after 1m0s
[02:35:58.587] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099969789163175760:12:0)
[02:35:58.588] Timer tick, asking Buildbucket for the build status
[02:35:58.670] Build 8934539726783445744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:35:58.670] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099969789163175760:13:0) after 1m0s
[02:36:58.688] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099969789163175760:13:0)
[02:36:58.688] Timer tick, asking Buildbucket for the build status
[02:36:58.752] Build 8934539726783445744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:36:58.752] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099969789163175760:14:0) after 1m0s
[02:37:58.770] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099969789163175760:14:0)
[02:37:58.770] Timer tick, asking Buildbucket for the build status
[02:37:58.813] Build 8934539726783445744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:37:58.814] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099969789163175760:15:0) after 1m0s
[02:38:58.855] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099969789163175760:15:0)
[02:38:58.855] Timer tick, asking Buildbucket for the build status
[02:38:58.969] Build 8934539726783445744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:38:58.969] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099969789163175760:16:0) after 1m0s
[02:39:58.988] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099969789163175760:16:0)
[02:39:58.988] Timer tick, asking Buildbucket for the build status
[02:39:59.071] Build 8934539726783445744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:39:59.071] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099969789163175760:17:0) after 1m0s
[02:40:59.276] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099969789163175760:17:0)
[02:40:59.276] Timer tick, asking Buildbucket for the build status
[02:40:59.488] Build 8934539726783445744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:40:59.488] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099969789163175760:18:0) after 1m0s
[02:41:59.509] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099969789163175760:18:0)
[02:41:59.509] Timer tick, asking Buildbucket for the build status
[02:41:59.674] Build 8934539726783445744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:41:59.674] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099969789163175760:19:0) after 1m0s
[02:42:59.711] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099969789163175760:19:0)
[02:42:59.711] Timer tick, asking Buildbucket for the build status
[02:42:59.748] Build 8934539726783445744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:42:59.748] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099969789163175760:20:0) after 1m0s
[02:43:59.773] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099969789163175760:20:0)
[02:43:59.773] Timer tick, asking Buildbucket for the build status
[02:43:59.900] Build 8934539726783445744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:43:59.900] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099969789163175760:21:0) after 1m0s
[02:44:59.928] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099969789163175760:21:0)
[02:44:59.928] Timer tick, asking Buildbucket for the build status
[02:45:00.001] Build 8934539726783445744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:45:00.001] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099969789163175760:22:0) after 1m0s
[02:46:00.027] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099969789163175760:22:0)
[02:46:00.027] Timer tick, asking Buildbucket for the build status
[02:46:00.123] Build 8934539726783445744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:46:00.123] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099969789163175760:23:0) after 1m0s
[02:47:00.144] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099969789163175760:23:0)
[02:47:00.144] Timer tick, asking Buildbucket for the build status
[02:47:00.195] Build 8934539726783445744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:47:00.195] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099969789163175760:24:0) after 1m0s
[02:48:00.214] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099969789163175760:24:0)
[02:48:00.214] Timer tick, asking Buildbucket for the build status
[02:48:00.280] Build 8934539726783445744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:48:00.280] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099969789163175760:25:0) after 1m0s
[02:49:00.303] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099969789163175760:25:0)
[02:49:00.303] Timer tick, asking Buildbucket for the build status
[02:49:00.383] Build 8934539726783445744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:49:00.383] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099969789163175760:26:0) after 1m0s
[02:50:00.479] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099969789163175760:26:0)
[02:50:00.479] Timer tick, asking Buildbucket for the build status
[02:50:00.604] Build 8934539726783445744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:50:00.604] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099969789163175760:27:0) after 1m0s
[02:51:00.625] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099969789163175760:27:0)
[02:51:00.625] Timer tick, asking Buildbucket for the build status
[02:51:00.687] Build 8934539726783445744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:51:00.687] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099969789163175760:28:0) after 1m0s
[02:52:00.709] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099969789163175760:28:0)
[02:52:00.709] Timer tick, asking Buildbucket for the build status
[02:52:00.808] Build 8934539726783445744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:52:00.808] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099969789163175760:29:0) after 1m0s
[02:53:00.835] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099969789163175760:29:0)
[02:53:00.835] Timer tick, asking Buildbucket for the build status
[02:53:01.106] Build 8934539726783445744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:53:01.106] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099969789163175760:30:0) after 1m0s
[02:54:01.123] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099969789163175760:30:0)
[02:54:01.123] Timer tick, asking Buildbucket for the build status
[02:54:01.226] Build 8934539726783445744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:54:01.226] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099969789163175760:31:0) after 1m0s
[02:55:01.248] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099969789163175760:31:0)
[02:55:01.248] Timer tick, asking Buildbucket for the build status
[02:55:01.304] Build 8934539726783445744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:55:01.304] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099969789163175760:32:0) after 1m0s
[02:56:01.376] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099969789163175760:32:0)
[02:56:01.376] Timer tick, asking Buildbucket for the build status
[02:56:01.438] Build 8934539726783445744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:56:01.438] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099969789163175760:33:0) after 1m0s
[02:57:01.456] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099969789163175760:33:0)
[02:57:01.456] Timer tick, asking Buildbucket for the build status
[02:57:01.714] Build 8934539726783445744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:57:01.714] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099969789163175760:34:0) after 1m0s
[02:58:01.733] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099969789163175760:34:0)
[02:58:01.733] Timer tick, asking Buildbucket for the build status
[02:58:01.870] Build 8934539726783445744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:58:01.870] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099969789163175760:35:0) after 1m0s
[02:59:01.889] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099969789163175760:35:0)
[02:59:01.889] Timer tick, asking Buildbucket for the build status
[02:59:01.959] Build 8934539726783445744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:59:01.959] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099969789163175760:36:0) after 1m0s
[03:00:01.983] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099969789163175760:36:0)
[03:00:01.983] Timer tick, asking Buildbucket for the build status
[03:00:02.051] Build 8934539726783445744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:00:02.051] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099969789163175760:37:0) after 1m0s
[03:01:02.082] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099969789163175760:37:0)
[03:01:02.082] Timer tick, asking Buildbucket for the build status
[03:01:02.117] Build 8934539726783445744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:01:02.117] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099969789163175760:38:0) after 1m0s
[03:02:02.179] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099969789163175760:38:0)
[03:02:02.179] Timer tick, asking Buildbucket for the build status
[03:02:02.557] Build 8934539726783445744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:02:02.557] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099969789163175760:39:0) after 1m0s
[03:03:02.577] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099969789163175760:39:0)
[03:03:02.577] Timer tick, asking Buildbucket for the build status
[03:03:02.763] Build 8934539726783445744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:03:02.763] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099969789163175760:40:0) after 1m0s
[03:04:02.856] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099969789163175760:40:0)
[03:04:02.856] Timer tick, asking Buildbucket for the build status
[03:04:06.974] Build 8934539726783445744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:04:06.974] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099969789163175760:41:0) after 1m0s
[03:05:06.995] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099969789163175760:41:0)
[03:05:06.995] Timer tick, asking Buildbucket for the build status
[03:05:07.034] Build 8934539726783445744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:05:07.034] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099969789163175760:42:0) after 1m0s
[03:06:07.061] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099969789163175760:42:0)
[03:06:07.061] Timer tick, asking Buildbucket for the build status
[03:06:07.121] Build 8934539726783445744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:06:07.121] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099969789163175760:43:0) after 1m0s
[03:07:07.139] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099969789163175760:43:0)
[03:07:07.139] Timer tick, asking Buildbucket for the build status
[03:07:07.332] Build 8934539726783445744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:07:07.332] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099969789163175760:44:0) after 1m0s
[03:08:07.358] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099969789163175760:44:0)
[03:08:07.358] Timer tick, asking Buildbucket for the build status
[03:08:07.468] Build 8934539726783445744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:08:07.468] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099969789163175760:45:0) after 1m0s
[03:09:07.487] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099969789163175760:45:0)
[03:09:07.487] Timer tick, asking Buildbucket for the build status
[03:09:07.546] Build 8934539726783445744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:09:07.546] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099969789163175760:46:0) after 1m0s
[03:10:08.312] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099969789163175760:46:0)
[03:10:08.312] Timer tick, asking Buildbucket for the build status
[03:10:08.357] Build 8934539726783445744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:10:08.357] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099969789163175760:47:0) after 1m0s
[03:11:08.375] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099969789163175760:47:0)
[03:11:08.375] Timer tick, asking Buildbucket for the build status
[03:11:08.446] Build 8934539726783445744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:11:08.446] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099969789163175760:48:0) after 1m0s
[03:12:08.466] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099969789163175760:48:0)
[03:12:08.466] Timer tick, asking Buildbucket for the build status
[03:12:08.531] Build 8934539726783445744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:12:08.531] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099969789163175760:49:0) after 1m0s
[03:13:08.550] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099969789163175760:49:0)
[03:13:08.550] Timer tick, asking Buildbucket for the build status
[03:13:08.664] Build 8934539726783445744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:13:08.664] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099969789163175760:50:0) after 1m0s
[03:14:08.682] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099969789163175760:50:0)
[03:14:08.683] Timer tick, asking Buildbucket for the build status
[03:14:08.791] Build 8934539726783445744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:14:08.791] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099969789163175760:51:0) after 1m0s
[03:15:08.826] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099969789163175760:51:0)
[03:15:08.826] Timer tick, asking Buildbucket for the build status
[03:15:08.993] Build 8934539726783445744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:15:08.993] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099969789163175760:52:0) after 1m0s
[03:16:09.064] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099969789163175760:52:0)
[03:16:09.064] Timer tick, asking Buildbucket for the build status
[03:16:09.104] Build 8934539726783445744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:16:09.104] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099969789163175760:53:0) after 1m0s
[03:17:09.161] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099969789163175760:53:0)
[03:17:09.161] Timer tick, asking Buildbucket for the build status
[03:17:09.233] Build 8934539726783445744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:17:09.233] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099969789163175760:54:0) after 1m0s
[03:18:09.302] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099969789163175760:54:0)
[03:18:09.302] Timer tick, asking Buildbucket for the build status
[03:18:09.363] Build 8934539726783445744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:18:09.363] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099969789163175760:55:0) after 1m0s
[03:19:09.385] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099969789163175760:55:0)
[03:19:09.385] Timer tick, asking Buildbucket for the build status
[03:19:09.425] Build 8934539726783445744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:19:09.425] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099969789163175760:56:0) after 1m0s
[03:20:09.452] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099969789163175760:56:0)
[03:20:09.452] Timer tick, asking Buildbucket for the build status
[03:20:09.521] Build 8934539726783445744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:20:09.521] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099969789163175760:57:0) after 1m0s
[03:21:09.571] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099969789163175760:57:0)
[03:21:09.571] Timer tick, asking Buildbucket for the build status
[03:21:09.648] Build 8934539726783445744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:21:09.648] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099969789163175760:58:0) after 1m0s
[03:22:09.672] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099969789163175760:58:0)
[03:22:09.672] Timer tick, asking Buildbucket for the build status
[03:22:09.736] Build 8934539726783445744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:22:09.736] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099969789163175760:59:0) after 1m0s
[03:23:09.757] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099969789163175760:59:0)
[03:23:09.757] Timer tick, asking Buildbucket for the build status
[03:23:09.813] Build 8934539726783445744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:23:09.813] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099969789163175760:60:0) after 1m0s
[03:24:09.913] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099969789163175760:60:0)
[03:24:09.913] Timer tick, asking Buildbucket for the build status
[03:24:09.957] Build 8934539726783445744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:24:09.957] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099969789163175760:61:0) after 1m0s
[03:25:09.997] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099969789163175760:61:0)
[03:25:09.997] Timer tick, asking Buildbucket for the build status
[03:25:10.310] Build 8934539726783445744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:25:10.310] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099969789163175760:62:0) after 1m0s
[03:26:10.342] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099969789163175760:62:0)
[03:26:10.342] Timer tick, asking Buildbucket for the build status
[03:26:10.519] Build 8934539726783445744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:26:10.519] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099969789163175760:63:0) after 1m0s
[03:27:10.543] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099969789163175760:63:0)
[03:27:10.543] Timer tick, asking Buildbucket for the build status
[03:27:10.602] Build 8934539726783445744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:27:10.602] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099969789163175760:64:0) after 1m0s
[03:28:10.620] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099969789163175760:64:0)
[03:28:10.620] Timer tick, asking Buildbucket for the build status
[03:28:10.679] Build 8934539726783445744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:28:10.679] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099969789163175760:65:0) after 1m0s
[03:29:10.703] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099969789163175760:65:0)
[03:29:10.703] Timer tick, asking Buildbucket for the build status
[03:29:10.735] Build 8934539726783445744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:29:10.735] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099969789163175760:66:0) after 1m0s
[03:30:10.756] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099969789163175760:66:0)
[03:30:10.756] Timer tick, asking Buildbucket for the build status
[03:30:10.929] Build 8934539726783445744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:30:10.929] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099969789163175760:67:0) after 1m0s
[03:31:10.988] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099969789163175760:67:0)
[03:31:10.988] Timer tick, asking Buildbucket for the build status
[03:31:11.114] Build 8934539726783445744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:31:11.114] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099969789163175760:68:0) after 1m0s
[03:32:11.135] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099969789163175760:68:0)
[03:32:11.135] Timer tick, asking Buildbucket for the build status
[03:32:11.252] Build 8934539726783445744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:32:11.252] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099969789163175760:69:0) after 1m0s
[03:33:11.269] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099969789163175760:69:0)
[03:33:11.269] Timer tick, asking Buildbucket for the build status
[03:33:11.319] Build 8934539726783445744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:33:11.319] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099969789163175760:70:0) after 1m0s
[03:34:11.351] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099969789163175760:70:0)
[03:34:11.351] Timer tick, asking Buildbucket for the build status
[03:34:11.730] Build 8934539726783445744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:34:11.730] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099969789163175760:71:0) after 1m0s
[03:35:11.751] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099969789163175760:71:0)
[03:35:11.751] Timer tick, asking Buildbucket for the build status
[03:35:11.789] Build 8934539726783445744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:35:11.789] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099969789163175760:72:0) after 1m0s
[03:36:11.830] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099969789163175760:72:0)
[03:36:11.830] Timer tick, asking Buildbucket for the build status
[03:36:11.884] Build 8934539726783445744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:36:11.884] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099969789163175760:73:0) after 1m0s
[03:37:11.905] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099969789163175760:73:0)
[03:37:11.905] Timer tick, asking Buildbucket for the build status
[03:37:11.964] Build 8934539726783445744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:37:11.964] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099969789163175760:74:0) after 1m0s
[03:38:11.998] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099969789163175760:74:0)
[03:38:11.998] Timer tick, asking Buildbucket for the build status
[03:38:12.038] Build 8934539726783445744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:38:12.038] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099969789163175760:75:0) after 1m0s
[03:39:12.205] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099969789163175760:75:0)
[03:39:12.205] Timer tick, asking Buildbucket for the build status
[03:39:12.325] Build 8934539726783445744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:39:12.325] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099969789163175760:76:0) after 1m0s
[03:40:12.344] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099969789163175760:76:0)
[03:40:12.344] Timer tick, asking Buildbucket for the build status
[03:40:12.402] Build 8934539726783445744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:40:12.402] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099969789163175760:77:0) after 1m0s
[03:41:12.501] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099969789163175760:77:0)
[03:41:12.501] Timer tick, asking Buildbucket for the build status
[03:41:12.583] Build 8934539726783445744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:41:12.583] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099969789163175760:78:0) after 1m0s
[03:42:12.618] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099969789163175760:78:0)
[03:42:12.618] Timer tick, asking Buildbucket for the build status
[03:42:12.700] Build 8934539726783445744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:42:12.700] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099969789163175760:79:0) after 1m0s
[03:43:12.719] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099969789163175760:79:0)
[03:43:12.719] Timer tick, asking Buildbucket for the build status
[03:43:12.753] Build 8934539726783445744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:43:12.753] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099969789163175760:80:0) after 1m0s
[03:44:12.825] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099969789163175760:80:0)
[03:44:12.825] Timer tick, asking Buildbucket for the build status
[03:44:12.877] Build 8934539726783445744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:44:12.877] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099969789163175760:81:0) after 1m0s
[03:45:12.900] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099969789163175760:81:0)
[03:45:12.900] Timer tick, asking Buildbucket for the build status
[03:45:13.434] Build 8934539726783445744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:45:13.434] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099969789163175760:82:0) after 1m0s
[03:46:13.456] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099969789163175760:82:0)
[03:46:13.457] Timer tick, asking Buildbucket for the build status
[03:46:13.609] Build 8934539726783445744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:46:13.609] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099969789163175760:83:0) after 1m0s
[03:47:13.638] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099969789163175760:83:0)
[03:47:13.638] Timer tick, asking Buildbucket for the build status
[03:47:13.699] Build 8934539726783445744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:47:13.699] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099969789163175760:84:0) after 1m0s
[03:48:13.719] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099969789163175760:84:0)
[03:48:13.719] Timer tick, asking Buildbucket for the build status
[03:48:13.764] Build 8934539726783445744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:48:13.764] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099969789163175760:85:0) after 1m0s
[03:49:13.875] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099969789163175760:85:0)
[03:49:13.875] Timer tick, asking Buildbucket for the build status
[03:49:13.939] Build 8934539726783445744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:49:13.939] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099969789163175760:86:0) after 1m0s
[03:50:13.966] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099969789163175760:86:0)
[03:50:13.966] Timer tick, asking Buildbucket for the build status
[03:50:14.027] Build 8934539726783445744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:50:14.027] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099969789163175760:87:0) after 1m0s
[03:51:14.050] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099969789163175760:87:0)
[03:51:14.050] Timer tick, asking Buildbucket for the build status
[03:51:14.097] Build 8934539726783445744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:51:14.097] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099969789163175760:88:0) after 1m0s
[03:52:14.190] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099969789163175760:88:0)
[03:52:14.191] Timer tick, asking Buildbucket for the build status
[03:52:14.262] Build 8934539726783445744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:52:14.262] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099969789163175760:89:0) after 1m0s
[03:53:14.313] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099969789163175760:89:0)
[03:53:14.313] Timer tick, asking Buildbucket for the build status
[03:53:14.487] Build 8934539726783445744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:53:14.487] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099969789163175760:90:0) after 1m0s
[03:54:14.596] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099969789163175760:90:0)
[03:54:14.596] Timer tick, asking Buildbucket for the build status
[03:54:14.698] Build 8934539726783445744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:54:14.698] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099969789163175760:91:0) after 1m0s
[03:55:14.721] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099969789163175760:91:0)
[03:55:14.721] Timer tick, asking Buildbucket for the build status
[03:55:14.781] Build 8934539726783445744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:55:14.782] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099969789163175760:92:0) after 1m0s
[03:56:14.805] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099969789163175760:92:0)
[03:56:14.805] Timer tick, asking Buildbucket for the build status
[03:56:14.877] Build 8934539726783445744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:56:14.877] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099969789163175760:93:0) after 1m0s
[03:57:14.899] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099969789163175760:93:0)
[03:57:14.899] Timer tick, asking Buildbucket for the build status
[03:57:15.094] Build 8934539726783445744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:57:15.094] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099969789163175760:94:0) after 1m0s
[03:58:15.113] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099969789163175760:94:0)
[03:58:15.113] Timer tick, asking Buildbucket for the build status
[03:58:15.198] Build 8934539726783445744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:58:15.198] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099969789163175760:95:0) after 1m0s
[03:59:15.240] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099969789163175760:95:0)
[03:59:15.240] Timer tick, asking Buildbucket for the build status
[03:59:15.308] Build 8934539726783445744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:59:15.308] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099969789163175760:96:0) after 1m0s
[04:00:15.331] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099969789163175760:96:0)
[04:00:15.331] Timer tick, asking Buildbucket for the build status
[04:00:15.374] Build 8934539726783445744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:00:15.374] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099969789163175760:97:0) after 1m0s
[04:01:15.397] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099969789163175760:97:0)
[04:01:15.397] Timer tick, asking Buildbucket for the build status
[04:01:15.435] Build 8934539726783445744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:01:15.435] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099969789163175760:98:0) after 1m0s
[04:02:15.462] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099969789163175760:98:0)
[04:02:15.462] Timer tick, asking Buildbucket for the build status
[04:02:15.553] Build 8934539726783445744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:02:15.553] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099969789163175760:99:0) after 1m0s
[04:03:15.580] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099969789163175760:99:0)
[04:03:15.580] Timer tick, asking Buildbucket for the build status
[04:03:15.622] Build 8934539726783445744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:03:15.622] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099969789163175760:100:0) after 1m0s
[04:04:15.137] Received PubSub notification, asking Buildbucket for the build status
[04:04:15.268] Build 8934539726783445744: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[04:04:15.268] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"completed_ts": "1537761853921407",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1537755955863022",
"id": "8934539726783445744",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"36e1dc20d9463b47c71bdb8f19c150a7e7bcea98\"}}",
"project": "chromium",
"result": "SUCCESS",
"result_details_json": "{\"ui\": {\"info\": \"\"}, \"build_run_result\": {\"infraFailure\": null, \"recipeResult\": {\"jsonResult\": \"null\"}, \"recipeExitCode\": {\"value\": 0}, \"recipe\": {\"name\": \"swarming/deterministic_build\", \"repository\": \"\", \"revision\": \"\"}, \"annotationUrl\": \"logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8934539726783445744/+/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\": [\"3783-5e6adab\"], \"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\": \"9f85491d8830fe6f3b8e0efa2e954f71bd9c64af\", \"got_swarming_client_revision\": \"486c9b53c4d54dd4b95bb6ce0e31160e600dfc11\", \"got_revision\": \"36e1dc20d9463b47c71bdb8f19c150a7e7bcea98\", \"recipe\": \"swarming/deterministic_build\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#24780}\", \"$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@{#593471}\", \"branch\": \"refs/heads/master\", \"revision\": \"36e1dc20d9463b47c71bdb8f19c150a7e7bcea98\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"buildername\": \"Windows deterministic\", \"got_webrtc_revision\": \"0ad0c27a0bcee24ec9dc5c27df090034e152bd65\", \"mastername\": \"chromium.fyi\", \"got_angle_revision\": \"55e57f96fbf84a7d42e807d25a304362b76da917\", \"buildbucket\": {\"hostname\": \"cr-buildbucket.appspot.com\", \"build\": {\"created_ts\": 1537755955863022, \"tags\": [\"builder:Windows deterministic\", \"buildset:commit/git/36e1dc20d9463b47c71bdb8f19c150a7e7bcea98\", \"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/36e1dc20d9463b47c71bdb8f19c150a7e7bcea98\", \"gitiles_ref:refs/heads/master\", \"scheduler_invocation_id:9099969789163175760\", \"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\": \"8934539726783445744\"}}, \"got_v8_revision\": \"2f22d11156ccb1f88587896c27151944cad48cba\", \"got_v8_revision_cp\": \"refs/heads/7.1.163@{#1}\", \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"buildnumber\": 10181, \"swarm_hashes\": {\"shell_dialogs_unittests\": \"314761de097e67d00ada5f4aabf483290a4098d9\", \"notification_helper_unittests\": \"94b71958d94d3b5bb8e0e248f06332cf2291dd26\", \"gpu_perftests\": \"75887b9f09125b71b6706cf008b1414929b89a39\", \"vr_common_unittests\": \"dbb7100a52f7da78ecfcc61923d03701205c6658\", \"telemetry_unittests\": \"bbb9fef0c90bf6d687a76581941c93acc1e9d782\", \"load_library_perf_tests\": \"8d4762b7a4a084b6f986a705050f6849dcce96e3\", \"devtools_closure_compile\": \"4c14287de95e3ba0fa6108ba6cb04b37f0119f15\", \"chromedriver_py_tests\": \"af076b22dcbd0e0553c6d50b03afa70a3c8b8434\", \"mojo_core_unittests\": \"02df1ba071024bcd3fe54c557a7cfa47b55953a0\", \"install_static_unittests\": \"db30dd750715021e0d3ad0427f8c3c0e883f1ba2\", \"leveldb_service_unittests\": \"60d2d8ebd93e6a89baf85c42902c7ef909d5e9a4\", \"mini_installer_tests\": \"744f1e9681f039999b895d4c3a5787a04b1d7216\", \"jingle_unittests\": \"9a5e17036d95078b248f5658c58deea76da8d249\", \"sbox_integration_tests\": \"4a923b829898ab00ffdc84750a1e0beb2c6ca19a\", \"angle_unittests\": \"36283d923d4014be8d56671705c88c87ad601a01\", \"cast_unittests\": \"e1b1f30de57255190d2ed1315600ade46dcda94e\", \"cc_unittests\": \"90f1f0cd53c6120716dd4229eba2105efbdd7f0b\", \"blink_common_unittests\": \"a38df1deceffac52d9d3cd797fb7c43a70a5d25c\", \"crashpad_tests\": \"cb61d015b365751d1a356bd65f7cd1adfa4598b8\", \"telemetry_perf_tests_without_chrome\": \"5f732455f5709b06593695253ce677d5a1b59a4e\", \"metrics_python_tests\": \"e95e6ad6e22e16e8380bffda0940094e78415b5f\", \"message_center_unittests\": \"2054d1523d088d25b68ebea5f8e33ebceb39dd9c\", \"ppapi_unittests\": \"cc8dcfb37a6a92c3e495d4033e88e30ef516691f\", \"extensions_unittests\": \"92acbf9081e861561bf3778ceee98f814aa3507f\", \"gl_unittests_ozone\": \"cb33148ad4c6dfa98676664abc7a76028a7bdb44\", \"app_shell_unittests\": \"51e65c3da4963ad648817a2af512d02ce1e6173f\", \"chrome_app_unittests\": \"31e5c43c84e58c782353f69900884a0da5199f0d\", \"gl_unittests\": \"cb33148ad4c6dfa98676664abc7a76028a7bdb44\", \"gcm_unit_tests\": \"43ee812707947794de0fcc11f101d502c43340e8\", \"views_unittests\": \"a7643cfaa1fbd4ea7b85c5f93e97c594f67ea9f3\", \"content_shell_crash_test\": \"e4bcd457e04f63a5c92476c9fe7f01493ce5d6fc\", \"ui_base_unittests\": \"571ebd402575382f175e706dd6fd2362fd7920c0\", \"gles2_conform_test\": \"44e5605930fc986aa3d998038c43777ce4594430\", \"cronet_unittests\": \"bca59d50526c2b272553e57d3c9c64f066f561ef\", \"performance_test_suite\": \"c5ea85e8f88be6fd420ed6eb6a11b0a98b82d778\", \"wm_unittests\": \"6d81ccef56cbe8fc628870ba2ccbacb62a745030\", \"boringssl_crypto_tests\": \"42f0af3f82979a9893eac3c3643f76b84529b018\", \"gin_unittests\": \"73d32b07cf794fd1674c690567407f2576654b49\", \"performance_browser_tests\": \"1658b03e6d9d361577ef8c17b254de28b9e6c7c6\", \"extensions_browsertests\": \"9c8defa74f8d6f709d3c3db587ede5fb53ee0eb0\", \"webkit_python_tests\": \"d158224fa80fea9494613a4e2cc83c3710e1a2b2\", \"headless_unittests\": \"24bc41b0c1b8ece9867418b4da349048a85b0955\", \"webkit_layout_tests\": \"c2637382e5ded6974f0ec2f2d8f8a2293e46d440\", \"vr_pixeltests\": \"18571025801e841ae17a5633e9da8b5a903feb1b\", \"setup_unittests\": \"56b710e3858f41e578d21cafc12288fe8cede364\", \"webkit_unit_tests\": \"1e09335852d82703c2e40d41ac77abf72198d37d\", \"content_unittests\": \"f11dde6c90604d570904b098d500e03e4f4fccf5\", \"performance_webview_test_suite\": \"e367b92a19c8c33921a0070edd5218e8c61bfc1a\", \"wtf_unittests\": \"6330a5dd1e366167efe69357e7de6901969305ce\", \"webkit_layout_tests_exparchive\": \"0e367e309f97378989616a53784cd7239cd30beb\", \"chrome_cleaner_unittests\": \"c08eeb97bcd9c977b9325be165117172aaece46d\", \"traffic_annotation_auditor_unittests\": \"84f8f55a7ce53e8d25db995da87ee68e7f3a085e\", \"libjingle_xmpp_unittests\": \"c49e03c8a53c9da5f9ca94a7107852ee8b304bf9\", \"remoting_unittests\": \"ac84a51302a68180fb3b99ee9ed144c5d30785eb\", \"vr_common_perftests\": \"7caddf98068ab07fe120861961b82ed813caaabd\", \"filesystem_service_unittests\": \"610cb3d5b1811147f3b61b816795fecc7356feb7\", \"chrome_elf_import_unittests\": \"a257ac7d2c91175658dcf34961e455efd2f322c0\", \"installer_util_unittests\": \"cd79265da142add96585c3df73af1adff4eb1901\", \"gfx_unittests\": \"7fd8dd90526223ea5785a35af0b15416cdb84d12\", \"headless_browsertests\": \"7d18ba79dff5277bd9d19f1ae22bb2205630cfb6\", \"capture_unittests\": \"518d4b9475a17da1cde25e23e318b02a66eb4858\", \"chrome_elf_unittests\": \"801b299adeeb4261acbfc90054fcc43ca9b5c6f1\", \"telemetry_gpu_unittests\": \"3aaaf5d798381308d9d79d13c60251faf588e6c6\", \"command_buffer_perftests\": \"b97d04e37c33e2ae8740760b1e34f1de506c567e\", \"media_unittests\": \"92ad1c5fb53859e8a622d3f3ac8bc976a04969a1\", \"chromedriver_unittests\": \"6289c89c120f39fb739e8cf4be09af6d118199a7\", \"views_mus_interactive_ui_tests\": \"cf198b71a31f6941b38f18630473a5be6c1843f6\", \"storage_unittests\": \"dadf7bceb9932d1db651225b130c56c4e23da6ae\", \"media_service_unittests\": \"50f178e9d9b1242c24f9b6ccb84115fe5a70b37d\", \"compositor_unittests\": \"097352350326634427c0b03fc5b1abe842e9abf5\", \"latency_unittests\": \"69e025be9e309fddee93bf6d3b8c749fd19823ff\", \"video_decode_accelerator_unittest\": \"dc6a0e16949c563d8ebaf3df4a8609e283512761\", \"swiftshader_unittests\": \"878de22b3275f0ccb373e993ceeba1231407f3dd\", \"telemetry_perf_unittests\": \"15237cb11cee62c2e272ad95ad753765587eb55b\", \"media_blink_unittests\": \"94f0b7a68eb4986b8d058ef1235d52d483640ec3\", \"views_mus_unittests\": \"712caa446c8c3bbc32033f42156b86e77f844d4d\", \"components_unittests\": \"2e082d821d66a61d77984ad51f50317abb2dc1df\", \"snapshot_unittests\": \"0c39568ec9a82d1c59378202eb8c11557f4e7f2b\", \"accessibility_unittests\": \"d24f6f2b39eb6004958bfa2924e52e2d37e19c57\", \"net_perftests\": \"40ec6344ecd55d2db0b2cea9826770b5078170e2\", \"aura_unittests\": \"92c031e2a5bb760fb96150861f23e257ac994d70\", \"services_unittests\": \"badbe704ae8ff0f27315324d94058b90d6e9c387\", \"components_perftests\": \"d0e87bb1d3646777e9f666ed5b1f2caa71f83658\", \"events_unittests\": \"9255365359e51b52b292ff43300dbfd473fb1330\", \"tracing_perftests\": \"2e0057526c5b177d2e565b17036ddb960a1e0416\", \"chromedriver_replay_unittests\": \"3d75567c4cb5be67d5cf025731258bcd9f3d4b8a\", \"ui_touch_selection_unittests\": \"45db612be15175e731ce5d4e37e20b838e63b5ed\", \"cacheinvalidation_unittests\": \"470ce1496e645fb24338f72c0841750602a6619d\", \"viz_unittests\": \"19c20fc044f39caf1d775769d2832a01915bae0e\", \"browser_tests\": \"b29e7618c2e8af683033eaae905ef09cbcfa9b5d\", \"net_unittests\": \"4bbeec85fd7ae0ed2f8f7d9da683b137dc70fcd5\", \"midi_unittests\": \"9aae1a581541c8ef687cb66dae0e1ee7181debc8\", \"boringssl_ssl_tests\": \"0ba0fcaff6f265a48cc1c1f2b1babea798fea781\", \"sbox_validation_tests\": \"d15adb290a5bce94bc16392b15c5393ca62e6f1e\", \"elevation_service_unittests\": \"395c3b473b285e6c334363876bfaad28f60ff2e6\", \"gpu_unittests\": \"ea28aee3e71ff16fab6e13a05beb3c6c50f6dbcb\", \"native_theme_unittests\": \"253f2428c006be9f266ff2fa78e9e7485a3f8b36\", \"interactive_ui_tests\": \"16a5668e4661670a88df324a758e3b3da38db102\", \"mojo_unittests\": \"33620517a98a1b536e7e1fc928570830aa9e3d2b\", \"url_unittests\": \"09a06ab47fc2e88ae8de2230f772ff9ed5349be5\", \"media_perftests\": \"474fa4269211c0945306d30ce60507726c858dd0\", \"cronet_tests\": \"e7929eb7e48e0083193b7b9e67369af246b59f45\", \"google_apis_unittests\": \"5c675c442c67c89f956b23552d1b63cd9cd6c58d\", \"telemetry_gpu_integration_test\": \"cd1b12db2ded9be28161367577effca36a3d2178\", \"blink_heap_unittests\": \"85c8112be0e77bbd80c1c7814daff25a8168db05\", \"base_unittests\": \"efd123cb2f79dd20feca7195d05f59f9eb91e09a\", \"sync_integration_tests\": \"5fbc6fe45ce2342ad67f593f7ad434d5891a0b3a\", \"sql_unittests\": \"0c82cf8097d8ff4c73c58d453cda44d80ed15df7\", \"courgette_unittests\": \"82303ce9819980655b6189049f61d94926da0782\", \"blink_fuzzer_unittests\": \"2b6b63e1b4c9731668899658da4b95a862398a27\", \"gl_tests\": \"aad1fc63e0028fc93a81356594b16b19522e17cf\", \"zucchini_unittests\": \"5da8593b94100e441824f2ae1a55abfdbc07b9e4\", \"ipc_tests\": \"de87119a47f2b478e95081b6880e8a2e279c43a8\", \"angle_end2end_tests\": \"a8119e8ea4d599c672434baccf7f71381995a99a\", \"blink_platform_unittests\": \"c99ab4e92fa7c3ee452b09dedcd391afb41c4659\", \"components_browsertests\": \"101c87bb0c9114fead94b8c20a748e5582b6bfe9\", \"views_perftests\": \"00692d728f71d755a96ecb4a404c91a9ea7746fd\", \"angle_perftests\": \"55ec85e31e7aee4b59de1628a993aa9eff53b09a\", \"display_unittests\": \"5359341a8c6fa449ef4401f9abfb449039549f3b\", \"sbox_unittests\": \"258c2a9ba87697429803db4247c29be882a5b0d1\", \"service_manager_unittests\": \"7651c1a43b63d0f33b26cf2293b86a03c97b407a\", \"pdf_unittests\": \"4966aeff27af58f0eaca5bb09d8ee4d6307cc13a\", \"skia_unittests\": \"053a3987484fb4c0e54ec550e44662a99f84edd5\", \"angle_white_box_tests\": \"e167a97d329013427fd9085d2394caf7ef166b8d\", \"crypto_unittests\": \"6426c53042791fd08bea24507d020c953d5a5c62\", \"nacl_loader_unittests\": \"18669972236567a3fd260f7ad23569e63d520707\", \"unit_tests\": \"c2e44910edd85cc56647ea745d02624362a1ebf4\", \"content_browsertests\": \"3efa1f6e4c48f5a69836086c19122894ad8915d3\", \"printing_unittests\": \"88de371e60dd22e5eaf94b4d3d7cc0b6dc35699f\", \"device_unittests\": \"da3983d154898fe4c22ca15a9594a5c00937d84e\"}, \"path_config\": \"generic\", \"bot_id\": \"swarm2173-c4\", \"got_buildtools_revision\": \"2dff9c9c74e9d732e6fe57c84ef7fd044cc45d96\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1537755957677159",
"status": "COMPLETED",
"status_changed_ts": "1537761854406880",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/10181",
"builder:Windows deterministic",
"buildset:commit/git/36e1dc20d9463b47c71bdb8f19c150a7e7bcea98",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/36e1dc20d9463b47c71bdb8f19c150a7e7bcea98",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9099969789163175760",
"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/10181",
"swarming_tag:buildbucket_bucket:luci.chromium.ci",
"swarming_tag:buildbucket_build_id:8934539726783445744",
"swarming_tag:buildbucket_hostname:cr-buildbucket.appspot.com",
"swarming_tag:buildbucket_template_canary:0",
"swarming_tag:buildbucket_template_revision:4c790cbde9342069ed9b59b6709c8807b14f2913",
"swarming_tag:builder:Windows deterministic",
"swarming_tag:buildset:commit/git/36e1dc20d9463b47c71bdb8f19c150a7e7bcea98",
"swarming_tag:buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/36e1dc20d9463b47c71bdb8f19c150a7e7bcea98",
"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/8934539726783445744/+/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:9099969789163175760",
"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:ded6e667eedfba9ca83cacf95f0d2164f0246aeb",
"swarming_tag:user:None",
"swarming_tag:user_agent:luci-scheduler",
"swarming_tag:vpython:native-python-wrapper",
"swarming_task_id:4022374e36bd4210",
"user_agent:luci-scheduler"
],
"updated_ts": "1537761854408190",
"url": "https://ci.chromium.org/p/chromium/builders/luci.chromium.ci/Windows deterministic/10181",
"utcnow_ts": "1537761855218371"
}
}
[04:04:15.268] Invocation finished in 1h38m21.057956962s with status SUCCEEDED