[02:24:22.270] New invocation is queued and will start shortly
[02:24:23.520] Starting the invocation (attempt 1)
[02:24:23.558] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[02:24:23.558] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[02:24:23.558] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9093537500979483248",
"parameters_json": "{\"builder_name\":\"Windows deterministic\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src.git\",\"revision\":\"853fa62383e9a474591f10e097cf961ee03ea431\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Windows deterministic",
"scheduler_invocation_id:9093537500979483248",
"scheduler_job_id:chromium/Windows deterministic",
"user_agent:luci-scheduler",
"buildset:commit/git/853fa62383e9a474591f10e097cf961ee03ea431",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/853fa62383e9a474591f10e097cf961ee03ea431",
"gitiles_ref:refs/heads/master"
]
}
[02:24:25.442] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1543890263757808",
"id": "8928107438749091072",
"lease_expiration_ts": "1544009065059119",
"lease_key": "1890203248",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"853fa62383e9a474591f10e097cf961ee03ea431\"}}",
"project": "chromium",
"result_details_json": "null",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1543890265305505",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/10951",
"builder:Windows deterministic",
"buildset:commit/git/853fa62383e9a474591f10e097cf961ee03ea431",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/853fa62383e9a474591f10e097cf961ee03ea431",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9093537500979483248",
"scheduler_job_id:chromium/Windows deterministic",
"swarming_dimension:builder:Windows deterministic",
"swarming_dimension:caches:builder_e8fffaf9fac16164b1ec16ebaa1f377b792d39a582f7640830518d8ff7303d0c_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/10951",
"swarming_tag:buildbucket_bucket:chromium/ci",
"swarming_tag:buildbucket_build_id:8928107438749091072",
"swarming_tag:buildbucket_hostname:cr-buildbucket.appspot.com",
"swarming_tag:buildbucket_template_canary:0",
"swarming_tag:buildbucket_template_revision:2f19301aebbc779351dc95ab1dc03603fa761240",
"swarming_tag:builder:Windows deterministic",
"swarming_tag:buildset:commit/git/853fa62383e9a474591f10e097cf961ee03ea431",
"swarming_tag:buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/853fa62383e9a474591f10e097cf961ee03ea431",
"swarming_tag:caches:builder_e8fffaf9fac16164b1ec16ebaa1f377b792d39a582f7640830518d8ff7303d0c_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/8928107438749091072/+/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:9093537500979483248",
"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:730145e9e1d37101d0f6001b294c592ab5c2023e",
"swarming_tag:user:None",
"swarming_tag:user_agent:luci-scheduler",
"swarming_tag:vpython:native-python-wrapper",
"swarming_task_id:418fd9697d709b10",
"user_agent:luci-scheduler"
],
"updated_ts": "1543890265305575",
"url": "https://ci.chromium.org/b/8928107438749091072",
"utcnow_ts": "1543890265430827"
}
}
[02:24:25.442] Task URL: https://ci.chromium.org/b/8928107438749091072
[02:24:25.442] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093537500979483248:2:0) after 1m0s
[02:25:25.463] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093537500979483248:2:0)
[02:25:25.463] Timer tick, asking Buildbucket for the build status
[02:25:25.529] Build 8928107438749091072: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:25:25.529] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093537500979483248:3:0) after 1m0s
[02:26:25.545] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093537500979483248:3:0)
[02:26:25.545] Timer tick, asking Buildbucket for the build status
[02:26:25.605] Build 8928107438749091072: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:26:25.605] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093537500979483248:4:0) after 1m0s
[02:26:32.092] Received PubSub notification, asking Buildbucket for the build status
[02:26:32.125] Build 8928107438749091072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:27:25.650] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093537500979483248:4:0)
[02:27:25.650] Timer tick, asking Buildbucket for the build status
[02:27:25.681] Build 8928107438749091072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:27:25.681] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093537500979483248:6:0) after 1m0s
[02:28:25.712] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093537500979483248:6:0)
[02:28:25.712] Timer tick, asking Buildbucket for the build status
[02:28:25.773] Build 8928107438749091072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:28:25.773] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093537500979483248:7:0) after 1m0s
[02:29:25.793] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093537500979483248:7:0)
[02:29:25.793] Timer tick, asking Buildbucket for the build status
[02:29:25.904] Build 8928107438749091072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:29:25.904] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093537500979483248:8:0) after 1m0s
[02:30:25.927] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093537500979483248:8:0)
[02:30:25.927] Timer tick, asking Buildbucket for the build status
[02:30:26.062] Build 8928107438749091072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:30:26.062] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093537500979483248:9:0) after 1m0s
[02:31:26.078] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093537500979483248:9:0)
[02:31:26.078] Timer tick, asking Buildbucket for the build status
[02:31:26.127] Build 8928107438749091072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:31:26.127] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093537500979483248:10:0) after 1m0s
[02:32:26.144] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093537500979483248:10:0)
[02:32:26.144] Timer tick, asking Buildbucket for the build status
[02:32:26.177] Build 8928107438749091072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:32:26.177] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093537500979483248:11:0) after 1m0s
[02:33:26.195] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093537500979483248:11:0)
[02:33:26.195] Timer tick, asking Buildbucket for the build status
[02:33:26.317] Build 8928107438749091072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:33:26.317] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093537500979483248:12:0) after 1m0s
[02:34:26.386] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093537500979483248:12:0)
[02:34:26.386] Timer tick, asking Buildbucket for the build status
[02:34:26.429] Build 8928107438749091072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:34:26.429] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093537500979483248:13:0) after 1m0s
[02:35:26.448] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093537500979483248:13:0)
[02:35:26.448] Timer tick, asking Buildbucket for the build status
[02:35:26.556] Build 8928107438749091072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:35:26.556] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093537500979483248:14:0) after 1m0s
[02:36:26.574] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093537500979483248:14:0)
[02:36:26.574] Timer tick, asking Buildbucket for the build status
[02:36:26.655] Build 8928107438749091072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:36:26.656] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093537500979483248:15:0) after 1m0s
[02:37:26.704] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093537500979483248:15:0)
[02:37:26.704] Timer tick, asking Buildbucket for the build status
[02:37:26.855] Build 8928107438749091072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:37:26.855] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093537500979483248:16:0) after 1m0s
[02:38:26.862] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093537500979483248:16:0)
[02:38:26.862] Timer tick, asking Buildbucket for the build status
[02:38:26.911] Build 8928107438749091072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:38:26.911] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093537500979483248:17:0) after 1m0s
[02:39:26.987] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093537500979483248:17:0)
[02:39:26.987] Timer tick, asking Buildbucket for the build status
[02:39:27.142] Build 8928107438749091072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:39:27.142] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093537500979483248:18:0) after 1m0s
[02:40:27.165] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093537500979483248:18:0)
[02:40:27.165] Timer tick, asking Buildbucket for the build status
[02:40:27.204] Build 8928107438749091072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:40:27.204] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093537500979483248:19:0) after 1m0s
[02:41:27.224] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093537500979483248:19:0)
[02:41:27.224] Timer tick, asking Buildbucket for the build status
[02:41:27.465] Build 8928107438749091072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:41:27.465] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093537500979483248:20:0) after 1m0s
[02:42:27.483] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093537500979483248:20:0)
[02:42:27.483] Timer tick, asking Buildbucket for the build status
[02:42:27.579] Build 8928107438749091072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:42:27.579] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093537500979483248:21:0) after 1m0s
[02:43:27.609] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093537500979483248:21:0)
[02:43:27.609] Timer tick, asking Buildbucket for the build status
[02:43:27.801] Build 8928107438749091072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:43:27.801] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093537500979483248:22:0) after 1m0s
[02:44:27.883] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093537500979483248:22:0)
[02:44:27.883] Timer tick, asking Buildbucket for the build status
[02:44:27.935] Build 8928107438749091072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:44:27.935] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093537500979483248:23:0) after 1m0s
[02:45:27.965] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093537500979483248:23:0)
[02:45:27.965] Timer tick, asking Buildbucket for the build status
[02:45:28.192] Build 8928107438749091072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:45:28.192] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093537500979483248:24:0) after 1m0s
[02:46:28.207] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093537500979483248:24:0)
[02:46:28.207] Timer tick, asking Buildbucket for the build status
[02:46:28.256] Build 8928107438749091072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:46:28.256] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093537500979483248:25:0) after 1m0s
[02:47:28.274] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093537500979483248:25:0)
[02:47:28.274] Timer tick, asking Buildbucket for the build status
[02:47:28.365] Build 8928107438749091072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:47:28.365] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093537500979483248:26:0) after 1m0s
[02:48:28.384] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093537500979483248:26:0)
[02:48:28.384] Timer tick, asking Buildbucket for the build status
[02:48:28.884] Build 8928107438749091072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:48:28.884] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093537500979483248:27:0) after 1m0s
[02:49:29.014] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093537500979483248:27:0)
[02:49:29.014] Timer tick, asking Buildbucket for the build status
[02:49:29.151] Build 8928107438749091072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:49:29.151] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093537500979483248:28:0) after 1m0s
[02:50:29.206] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093537500979483248:28:0)
[02:50:29.206] Timer tick, asking Buildbucket for the build status
[02:50:30.206] Build 8928107438749091072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:50:30.206] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093537500979483248:29:0) after 1m0s
[02:51:30.227] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093537500979483248:29:0)
[02:51:30.227] Timer tick, asking Buildbucket for the build status
[02:51:30.275] Build 8928107438749091072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:51:30.275] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093537500979483248:30:0) after 1m0s
[02:52:30.295] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093537500979483248:30:0)
[02:52:30.295] Timer tick, asking Buildbucket for the build status
[02:52:31.194] Build 8928107438749091072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:52:31.194] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093537500979483248:31:0) after 1m0s
[02:53:31.317] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093537500979483248:31:0)
[02:53:31.317] Timer tick, asking Buildbucket for the build status
[02:53:31.427] Build 8928107438749091072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:53:31.427] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093537500979483248:32:0) after 1m0s
[02:54:31.447] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093537500979483248:32:0)
[02:54:31.447] Timer tick, asking Buildbucket for the build status
[02:54:31.490] Build 8928107438749091072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:54:31.490] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093537500979483248:33:0) after 1m0s
[02:55:31.511] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093537500979483248:33:0)
[02:55:31.511] Timer tick, asking Buildbucket for the build status
[02:55:31.576] Build 8928107438749091072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:55:31.576] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093537500979483248:34:0) after 1m0s
[02:56:31.609] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093537500979483248:34:0)
[02:56:31.609] Timer tick, asking Buildbucket for the build status
[02:56:31.692] Build 8928107438749091072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:56:31.692] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093537500979483248:35:0) after 1m0s
[02:57:31.715] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093537500979483248:35:0)
[02:57:31.715] Timer tick, asking Buildbucket for the build status
[02:57:31.804] Build 8928107438749091072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:57:31.804] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093537500979483248:36:0) after 1m0s
[02:58:31.823] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093537500979483248:36:0)
[02:58:31.823] Timer tick, asking Buildbucket for the build status
[02:58:31.862] Build 8928107438749091072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:58:31.863] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093537500979483248:37:0) after 1m0s
[02:59:31.880] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093537500979483248:37:0)
[02:59:31.880] Timer tick, asking Buildbucket for the build status
[02:59:31.934] Build 8928107438749091072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:59:31.934] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093537500979483248:38:0) after 1m0s
[03:00:32.019] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093537500979483248:38:0)
[03:00:32.019] Timer tick, asking Buildbucket for the build status
[03:00:32.083] Build 8928107438749091072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:00:32.083] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093537500979483248:39:0) after 1m0s
[03:01:32.099] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093537500979483248:39:0)
[03:01:32.099] Timer tick, asking Buildbucket for the build status
[03:01:32.371] Build 8928107438749091072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:01:32.371] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093537500979483248:40:0) after 1m0s
[03:02:32.391] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093537500979483248:40:0)
[03:02:32.391] Timer tick, asking Buildbucket for the build status
[03:02:32.719] Build 8928107438749091072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:02:32.719] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093537500979483248:41:0) after 1m0s
[03:03:32.747] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093537500979483248:41:0)
[03:03:32.747] Timer tick, asking Buildbucket for the build status
[03:03:32.890] Build 8928107438749091072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:03:32.890] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093537500979483248:42:0) after 1m0s
[03:04:32.909] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093537500979483248:42:0)
[03:04:32.909] Timer tick, asking Buildbucket for the build status
[03:04:32.952] Build 8928107438749091072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:04:32.952] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093537500979483248:43:0) after 1m0s
[03:05:32.973] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093537500979483248:43:0)
[03:05:32.973] Timer tick, asking Buildbucket for the build status
[03:05:33.045] Build 8928107438749091072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:05:33.045] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093537500979483248:44:0) after 1m0s
[03:06:33.066] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093537500979483248:44:0)
[03:06:33.066] Timer tick, asking Buildbucket for the build status
[03:06:33.104] Build 8928107438749091072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:06:33.104] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093537500979483248:45:0) after 1m0s
[03:07:33.125] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093537500979483248:45:0)
[03:07:33.125] Timer tick, asking Buildbucket for the build status
[03:07:33.245] Build 8928107438749091072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:07:33.245] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093537500979483248:46:0) after 1m0s
[03:08:33.266] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093537500979483248:46:0)
[03:08:33.266] Timer tick, asking Buildbucket for the build status
[03:08:33.321] Build 8928107438749091072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:08:33.321] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093537500979483248:47:0) after 1m0s
[03:09:33.404] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093537500979483248:47:0)
[03:09:33.404] Timer tick, asking Buildbucket for the build status
[03:09:33.731] Build 8928107438749091072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:09:33.731] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093537500979483248:48:0) after 1m0s
[03:10:33.786] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093537500979483248:48:0)
[03:10:33.786] Timer tick, asking Buildbucket for the build status
[03:10:34.493] Build 8928107438749091072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:10:34.493] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093537500979483248:49:0) after 1m0s
[03:11:34.515] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093537500979483248:49:0)
[03:11:34.515] Timer tick, asking Buildbucket for the build status
[03:11:34.649] Build 8928107438749091072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:11:34.649] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093537500979483248:50:0) after 1m0s
[03:12:34.669] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093537500979483248:50:0)
[03:12:34.669] Timer tick, asking Buildbucket for the build status
[03:12:34.716] Build 8928107438749091072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:12:34.716] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093537500979483248:51:0) after 1m0s
[03:13:34.733] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093537500979483248:51:0)
[03:13:34.733] Timer tick, asking Buildbucket for the build status
[03:13:34.794] Build 8928107438749091072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:13:34.794] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093537500979483248:52:0) after 1m0s
[03:14:34.815] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093537500979483248:52:0)
[03:14:34.815] Timer tick, asking Buildbucket for the build status
[03:14:34.897] Build 8928107438749091072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:14:34.897] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093537500979483248:53:0) after 1m0s
[03:15:34.919] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093537500979483248:53:0)
[03:15:34.919] Timer tick, asking Buildbucket for the build status
[03:15:34.977] Build 8928107438749091072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:15:34.977] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093537500979483248:54:0) after 1m0s
[03:16:34.998] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093537500979483248:54:0)
[03:16:34.999] Timer tick, asking Buildbucket for the build status
[03:16:35.053] Build 8928107438749091072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:16:35.053] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093537500979483248:55:0) after 1m0s
[03:17:35.073] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093537500979483248:55:0)
[03:17:35.073] Timer tick, asking Buildbucket for the build status
[03:17:35.107] Build 8928107438749091072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:17:35.107] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093537500979483248:56:0) after 1m0s
[03:18:35.128] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093537500979483248:56:0)
[03:18:35.128] Timer tick, asking Buildbucket for the build status
[03:18:35.159] Build 8928107438749091072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:18:35.159] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093537500979483248:57:0) after 1m0s
[03:19:35.183] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093537500979483248:57:0)
[03:19:35.183] Timer tick, asking Buildbucket for the build status
[03:19:35.256] Build 8928107438749091072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:19:35.256] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093537500979483248:58:0) after 1m0s
[03:20:35.315] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093537500979483248:58:0)
[03:20:35.315] Timer tick, asking Buildbucket for the build status
[03:20:35.389] Build 8928107438749091072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:20:35.389] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093537500979483248:59:0) after 1m0s
[03:21:35.414] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093537500979483248:59:0)
[03:21:35.414] Timer tick, asking Buildbucket for the build status
[03:21:35.546] Build 8928107438749091072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:21:35.546] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093537500979483248:60:0) after 1m0s
[03:22:35.565] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093537500979483248:60:0)
[03:22:35.565] Timer tick, asking Buildbucket for the build status
[03:22:35.642] Build 8928107438749091072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:22:35.642] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093537500979483248:61:0) after 1m0s
[03:23:35.675] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093537500979483248:61:0)
[03:23:35.675] Timer tick, asking Buildbucket for the build status
[03:23:35.826] Build 8928107438749091072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:23:35.826] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093537500979483248:62:0) after 1m0s
[03:24:35.844] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093537500979483248:62:0)
[03:24:35.844] Timer tick, asking Buildbucket for the build status
[03:24:35.895] Build 8928107438749091072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:24:35.895] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093537500979483248:63:0) after 1m0s
[03:25:35.919] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093537500979483248:63:0)
[03:25:35.919] Timer tick, asking Buildbucket for the build status
[03:25:35.995] Build 8928107438749091072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:25:35.995] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093537500979483248:64:0) after 1m0s
[03:26:36.016] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093537500979483248:64:0)
[03:26:36.016] Timer tick, asking Buildbucket for the build status
[03:26:36.052] Build 8928107438749091072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:26:36.052] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093537500979483248:65:0) after 1m0s
[03:27:36.118] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093537500979483248:65:0)
[03:27:36.118] Timer tick, asking Buildbucket for the build status
[03:27:36.206] Build 8928107438749091072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:27:36.206] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093537500979483248:66:0) after 1m0s
[03:28:36.228] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093537500979483248:66:0)
[03:28:36.228] Timer tick, asking Buildbucket for the build status
[03:28:36.281] Build 8928107438749091072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:28:36.281] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093537500979483248:67:0) after 1m0s
[03:29:36.305] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093537500979483248:67:0)
[03:29:36.305] Timer tick, asking Buildbucket for the build status
[03:29:36.490] Build 8928107438749091072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:29:36.490] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093537500979483248:68:0) after 1m0s
[03:30:36.513] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093537500979483248:68:0)
[03:30:36.513] Timer tick, asking Buildbucket for the build status
[03:30:36.585] Build 8928107438749091072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:30:36.585] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093537500979483248:69:0) after 1m0s
[03:31:36.637] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093537500979483248:69:0)
[03:31:36.637] Timer tick, asking Buildbucket for the build status
[03:31:36.736] Build 8928107438749091072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:31:36.736] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093537500979483248:70:0) after 1m0s
[03:32:36.759] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093537500979483248:70:0)
[03:32:36.759] Timer tick, asking Buildbucket for the build status
[03:32:36.833] Build 8928107438749091072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:32:36.833] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093537500979483248:71:0) after 1m0s
[03:33:36.856] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093537500979483248:71:0)
[03:33:36.856] Timer tick, asking Buildbucket for the build status
[03:33:37.017] Build 8928107438749091072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:33:37.017] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093537500979483248:72:0) after 1m0s
[03:34:37.038] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093537500979483248:72:0)
[03:34:37.038] Timer tick, asking Buildbucket for the build status
[03:34:37.193] Build 8928107438749091072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:34:37.193] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093537500979483248:73:0) after 1m0s
[03:35:37.314] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093537500979483248:73:0)
[03:35:37.314] Timer tick, asking Buildbucket for the build status
[03:35:37.548] Build 8928107438749091072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:35:37.548] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093537500979483248:74:0) after 1m0s
[03:36:37.567] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093537500979483248:74:0)
[03:36:37.567] Timer tick, asking Buildbucket for the build status
[03:36:37.608] Build 8928107438749091072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:36:37.608] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093537500979483248:75:0) after 1m0s
[03:37:37.632] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093537500979483248:75:0)
[03:37:37.632] Timer tick, asking Buildbucket for the build status
[03:37:37.679] Build 8928107438749091072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:37:37.679] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093537500979483248:76:0) after 1m0s
[03:38:37.703] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093537500979483248:76:0)
[03:38:37.703] Timer tick, asking Buildbucket for the build status
[03:38:37.738] Build 8928107438749091072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:38:37.738] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093537500979483248:77:0) after 1m0s
[03:39:37.770] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093537500979483248:77:0)
[03:39:37.770] Timer tick, asking Buildbucket for the build status
[03:39:37.942] Build 8928107438749091072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:39:37.942] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093537500979483248:78:0) after 1m0s
[03:40:37.975] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093537500979483248:78:0)
[03:40:37.975] Timer tick, asking Buildbucket for the build status
[03:40:38.065] Build 8928107438749091072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:40:38.065] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093537500979483248:79:0) after 1m0s
[03:41:38.150] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093537500979483248:79:0)
[03:41:38.150] Timer tick, asking Buildbucket for the build status
[03:41:38.185] Build 8928107438749091072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:41:38.185] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093537500979483248:80:0) after 1m0s
[03:42:38.208] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093537500979483248:80:0)
[03:42:38.208] Timer tick, asking Buildbucket for the build status
[03:42:38.335] Build 8928107438749091072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:42:38.335] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093537500979483248:81:0) after 1m0s
[03:43:38.361] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093537500979483248:81:0)
[03:43:38.361] Timer tick, asking Buildbucket for the build status
[03:43:38.414] Build 8928107438749091072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:43:38.414] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093537500979483248:82:0) after 1m0s
[03:44:38.531] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093537500979483248:82:0)
[03:44:38.531] Timer tick, asking Buildbucket for the build status
[03:44:38.561] Build 8928107438749091072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:44:38.561] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093537500979483248:83:0) after 1m0s
[03:45:38.584] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093537500979483248:83:0)
[03:45:38.584] Timer tick, asking Buildbucket for the build status
[03:45:38.616] Build 8928107438749091072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:45:38.616] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093537500979483248:84:0) after 1m0s
[03:46:38.688] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093537500979483248:84:0)
[03:46:38.688] Timer tick, asking Buildbucket for the build status
[03:46:38.777] Build 8928107438749091072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:46:38.777] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093537500979483248:85:0) after 1m0s
[03:47:38.805] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093537500979483248:85:0)
[03:47:38.805] Timer tick, asking Buildbucket for the build status
[03:47:38.845] Build 8928107438749091072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:47:38.845] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093537500979483248:86:0) after 1m0s
[03:48:38.866] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093537500979483248:86:0)
[03:48:38.866] Timer tick, asking Buildbucket for the build status
[03:48:38.971] Build 8928107438749091072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:48:38.972] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093537500979483248:87:0) after 1m0s
[03:49:39.004] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093537500979483248:87:0)
[03:49:39.004] Timer tick, asking Buildbucket for the build status
[03:49:39.039] Build 8928107438749091072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:49:39.039] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093537500979483248:88:0) after 1m0s
[03:50:39.102] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093537500979483248:88:0)
[03:50:39.102] Timer tick, asking Buildbucket for the build status
[03:50:39.213] Build 8928107438749091072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:50:39.213] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093537500979483248:89:0) after 1m0s
[03:51:39.242] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093537500979483248:89:0)
[03:51:39.242] Timer tick, asking Buildbucket for the build status
[03:51:39.289] Build 8928107438749091072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:51:39.289] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093537500979483248:90:0) after 1m0s
[03:52:39.345] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093537500979483248:90:0)
[03:52:39.345] Timer tick, asking Buildbucket for the build status
[03:52:39.403] Build 8928107438749091072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:52:39.403] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093537500979483248:91:0) after 1m0s
[03:53:39.425] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093537500979483248:91:0)
[03:53:39.425] Timer tick, asking Buildbucket for the build status
[03:53:39.457] Build 8928107438749091072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:53:39.457] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093537500979483248:92:0) after 1m0s
[03:54:39.641] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093537500979483248:92:0)
[03:54:39.641] Timer tick, asking Buildbucket for the build status
[03:54:39.790] Build 8928107438749091072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:54:39.792] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093537500979483248:93:0) after 1m0s
[03:55:39.813] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093537500979483248:93:0)
[03:55:39.813] Timer tick, asking Buildbucket for the build status
[03:55:39.930] Build 8928107438749091072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:55:39.930] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093537500979483248:94:0) after 1m0s
[03:56:39.951] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093537500979483248:94:0)
[03:56:39.951] Timer tick, asking Buildbucket for the build status
[03:56:40.031] Build 8928107438749091072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:56:40.031] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093537500979483248:95:0) after 1m0s
[03:57:40.095] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093537500979483248:95:0)
[03:57:40.095] Timer tick, asking Buildbucket for the build status
[03:57:40.171] Build 8928107438749091072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:57:40.171] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093537500979483248:96:0) after 1m0s
[03:58:40.198] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093537500979483248:96:0)
[03:58:40.198] Timer tick, asking Buildbucket for the build status
[03:58:40.237] Build 8928107438749091072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:58:40.237] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093537500979483248:97:0) after 1m0s
[03:59:40.260] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093537500979483248:97:0)
[03:59:40.260] Timer tick, asking Buildbucket for the build status
[03:59:40.324] Build 8928107438749091072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:59:40.324] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093537500979483248:98:0) after 1m0s
[04:00:40.424] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093537500979483248:98:0)
[04:00:40.424] Timer tick, asking Buildbucket for the build status
[04:00:40.458] Build 8928107438749091072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:00:40.458] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093537500979483248:99:0) after 1m0s
[04:01:40.490] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093537500979483248:99:0)
[04:01:40.490] Timer tick, asking Buildbucket for the build status
[04:01:40.545] Build 8928107438749091072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:01:40.545] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093537500979483248:100:0) after 1m0s
[04:02:40.581] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093537500979483248:100:0)
[04:02:40.581] Timer tick, asking Buildbucket for the build status
[04:02:40.659] Build 8928107438749091072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:02:40.659] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093537500979483248:101:0) after 1m0s
[04:03:40.683] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093537500979483248:101:0)
[04:03:40.683] Timer tick, asking Buildbucket for the build status
[04:03:40.737] Build 8928107438749091072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:03:40.737] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093537500979483248:102:0) after 1m0s
[04:04:40.760] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093537500979483248:102:0)
[04:04:40.760] Timer tick, asking Buildbucket for the build status
[04:04:40.797] Build 8928107438749091072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:04:40.797] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093537500979483248:103:0) after 1m0s
[04:05:40.831] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093537500979483248:103:0)
[04:05:40.831] Timer tick, asking Buildbucket for the build status
[04:05:40.909] Build 8928107438749091072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:05:40.909] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093537500979483248:104:0) after 1m0s
[04:06:40.987] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093537500979483248:104:0)
[04:06:40.987] Timer tick, asking Buildbucket for the build status
[04:06:41.045] Build 8928107438749091072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:06:41.045] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093537500979483248:105:0) after 1m0s
[04:07:41.068] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093537500979483248:105:0)
[04:07:41.068] Timer tick, asking Buildbucket for the build status
[04:07:41.109] Build 8928107438749091072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:07:41.110] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093537500979483248:106:0) after 1m0s
[04:08:41.137] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093537500979483248:106:0)
[04:08:41.137] Timer tick, asking Buildbucket for the build status
[04:08:41.183] Build 8928107438749091072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:08:41.183] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093537500979483248:107:0) after 1m0s
[04:09:41.205] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093537500979483248:107:0)
[04:09:41.205] Timer tick, asking Buildbucket for the build status
[04:09:41.280] Build 8928107438749091072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:09:41.280] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093537500979483248:108:0) after 1m0s
[04:10:41.302] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093537500979483248:108:0)
[04:10:41.302] Timer tick, asking Buildbucket for the build status
[04:10:41.341] Build 8928107438749091072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:10:41.341] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093537500979483248:109:0) after 1m0s
[04:11:41.361] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093537500979483248:109:0)
[04:11:41.361] Timer tick, asking Buildbucket for the build status
[04:11:41.421] Build 8928107438749091072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:11:41.421] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093537500979483248:110:0) after 1m0s
[04:12:41.442] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093537500979483248:110:0)
[04:12:41.442] Timer tick, asking Buildbucket for the build status
[04:12:41.495] Build 8928107438749091072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:12:41.495] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093537500979483248:111:0) after 1m0s
[04:13:41.560] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093537500979483248:111:0)
[04:13:41.560] Timer tick, asking Buildbucket for the build status
[04:13:41.623] Build 8928107438749091072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:13:41.623] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093537500979483248:112:0) after 1m0s
[04:14:41.803] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093537500979483248:112:0)
[04:14:41.803] Timer tick, asking Buildbucket for the build status
[04:14:41.861] Build 8928107438749091072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:14:41.861] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093537500979483248:113:0) after 1m0s
[04:15:41.884] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093537500979483248:113:0)
[04:15:41.884] Timer tick, asking Buildbucket for the build status
[04:15:41.934] Build 8928107438749091072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:15:41.934] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093537500979483248:114:0) after 1m0s
[04:16:36.931] Received PubSub notification, asking Buildbucket for the build status
[04:16:36.970] Build 8928107438749091072: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[04:16:36.970] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"completed_ts": "1543896996121372",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1543890263757808",
"failure_reason": "BUILD_FAILURE",
"id": "8928107438749091072",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"853fa62383e9a474591f10e097cf961ee03ea431\"}}",
"project": "chromium",
"result": "FAILURE",
"result_details_json": "{\"ui\": {\"info\": \"Failure compare_build_artifacts\"}, \"swarming\": {\"bot_dimensions\": {\"gce\": [\"1\"], \"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\"], \"id\": [\"swarm2173-c4\"], \"python\": [\"2.7.6\"], \"server_version\": [\"3901-0f56bf6\"], \"image\": [\"chrome-win10-1703-2018-04-20-61acaf8983f\"], \"gpu\": [\"none\"], \"cores\": [\"8\"], \"integrity\": [\"high\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Haswell_GCE\"], \"pool\": [\"luci.chromium.ci\"], \"caches\": [\"builder_e8fffaf9fac16164b1ec16ebaa1f377b792d39a582f7640830518d8ff7303d0c_v2\", \"git\", \"goma_v2\", \"vpython\"]}}, \"properties\": {\"got_nacl_revision\": \"f701a90597fc85979319447c0cd44c3b52201c78\", \"got_swarming_client_revision\": \"0e3e1c4dc4e79f25a5b58fcbc135dc93183c0c54\", \"got_revision\": \"853fa62383e9a474591f10e097cf961ee03ea431\", \"recipe\": \"swarming/deterministic_build\", \"got_dawn_revision\": \"f0b761f116f663dbe344d70a785219af49f537d5\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#25882}\", \"$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@{#613396}\", \"branch\": \"refs/heads/master\", \"revision\": \"853fa62383e9a474591f10e097cf961ee03ea431\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"buildername\": \"Windows deterministic\", \"got_webrtc_revision\": \"ec086d842ce777a5cb8983458d85a44cde9622b4\", \"mastername\": \"chromium.win\", \"got_angle_revision\": \"ff03e51ea25ef9d4f99d529eb39e43edb1fdcc89\", \"buildbucket\": {\"hostname\": \"cr-buildbucket.appspot.com\", \"build\": {\"created_ts\": 1543890263757808, \"tags\": [\"builder:Windows deterministic\", \"buildset:commit/git/853fa62383e9a474591f10e097cf961ee03ea431\", \"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/853fa62383e9a474591f10e097cf961ee03ea431\", \"gitiles_ref:refs/heads/master\", \"scheduler_invocation_id:9093537500979483248\", \"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\": \"8928107438749091072\"}}, \"got_v8_revision\": \"d8a2cdcaea5e936d4b3cad37583110bf15b9e89b\", \"got_v8_revision_cp\": \"refs/heads/7.3.9@{#1}\", \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"buildnumber\": 10951, \"path_config\": \"generic\", \"bot_id\": \"swarm2173-c4\", \"got_buildtools_revision\": \"04161ec8d7c781e4498c699254c69ba0dd959fde\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1543890388895708",
"status": "COMPLETED",
"status_changed_ts": "1543896996584791",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/10951",
"builder:Windows deterministic",
"buildset:commit/git/853fa62383e9a474591f10e097cf961ee03ea431",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/853fa62383e9a474591f10e097cf961ee03ea431",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9093537500979483248",
"scheduler_job_id:chromium/Windows deterministic",
"swarming_dimension:builder:Windows deterministic",
"swarming_dimension:caches:builder_e8fffaf9fac16164b1ec16ebaa1f377b792d39a582f7640830518d8ff7303d0c_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/10951",
"swarming_tag:buildbucket_bucket:chromium/ci",
"swarming_tag:buildbucket_build_id:8928107438749091072",
"swarming_tag:buildbucket_hostname:cr-buildbucket.appspot.com",
"swarming_tag:buildbucket_template_canary:0",
"swarming_tag:buildbucket_template_revision:2f19301aebbc779351dc95ab1dc03603fa761240",
"swarming_tag:builder:Windows deterministic",
"swarming_tag:buildset:commit/git/853fa62383e9a474591f10e097cf961ee03ea431",
"swarming_tag:buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/853fa62383e9a474591f10e097cf961ee03ea431",
"swarming_tag:caches:builder_e8fffaf9fac16164b1ec16ebaa1f377b792d39a582f7640830518d8ff7303d0c_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/8928107438749091072/+/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:9093537500979483248",
"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:730145e9e1d37101d0f6001b294c592ab5c2023e",
"swarming_tag:user:None",
"swarming_tag:user_agent:luci-scheduler",
"swarming_tag:vpython:native-python-wrapper",
"swarming_task_id:418fd9697d709b10",
"user_agent:luci-scheduler"
],
"updated_ts": "1543896996585803",
"url": "https://ci.chromium.org/b/8928107438749091072",
"utcnow_ts": "1543896996955114"
}
}
[04:16:36.971] Invocation finished in 1h52m14.724293518s with status FAILED