[18:39:26.925] New invocation is queued and will start shortly
[18:39:28.044] Starting the invocation (attempt 1)
[18:39:28.072] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[18:39:28.072] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[18:39:28.073] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9093385557428375824",
"parameters_json": "{\"builder_name\":\"Windows deterministic\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src.git\",\"revision\":\"cc19edbe4b1d7899d5e3f3cf93cca71b6d0e5603\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Windows deterministic",
"scheduler_invocation_id:9093385557428375824",
"scheduler_job_id:chromium/Windows deterministic",
"user_agent:luci-scheduler",
"buildset:commit/git/cc19edbe4b1d7899d5e3f3cf93cca71b6d0e5603",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/cc19edbe4b1d7899d5e3f3cf93cca71b6d0e5603",
"gitiles_ref:refs/heads/master"
]
}
[18:39:31.032] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1544035168142504",
"id": "8927955495488276912",
"lease_expiration_ts": "1544153970578038",
"lease_key": "2125830345",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"cc19edbe4b1d7899d5e3f3cf93cca71b6d0e5603\"}}",
"project": "chromium",
"result_details_json": "null",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1544035170951535",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/10973",
"builder:Windows deterministic",
"buildset:commit/git/cc19edbe4b1d7899d5e3f3cf93cca71b6d0e5603",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/cc19edbe4b1d7899d5e3f3cf93cca71b6d0e5603",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9093385557428375824",
"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/10973",
"swarming_tag:buildbucket_bucket:chromium/ci",
"swarming_tag:buildbucket_build_id:8927955495488276912",
"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/cc19edbe4b1d7899d5e3f3cf93cca71b6d0e5603",
"swarming_tag:buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/cc19edbe4b1d7899d5e3f3cf93cca71b6d0e5603",
"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/8927955495488276912/+/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:9093385557428375824",
"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:e1abadd4123c770a71ceacbcfd6afb2995a718ae",
"swarming_tag:user:None",
"swarming_tag:user_agent:luci-scheduler",
"swarming_tag:vpython:native-python-wrapper",
"swarming_task_id:41987c7db6503110",
"user_agent:luci-scheduler"
],
"updated_ts": "1544035170951624",
"url": "https://ci.chromium.org/b/8927955495488276912",
"utcnow_ts": "1544035171020060"
}
}
[18:39:31.032] Task URL: https://ci.chromium.org/b/8927955495488276912
[18:39:31.032] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093385557428375824:2:0) after 1m0s
[18:40:31.120] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093385557428375824:2:0)
[18:40:31.120] Timer tick, asking Buildbucket for the build status
[18:40:31.186] Build 8927955495488276912: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:40:31.186] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093385557428375824:3:0) after 1m0s
[18:41:31.205] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093385557428375824:3:0)
[18:41:31.205] Timer tick, asking Buildbucket for the build status
[18:41:31.240] Build 8927955495488276912: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:41:31.240] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093385557428375824:4:0) after 1m0s
[18:41:55.737] Received PubSub notification, asking Buildbucket for the build status
[18:41:55.807] Build 8927955495488276912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:42:31.283] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093385557428375824:4:0)
[18:42:31.283] Timer tick, asking Buildbucket for the build status
[18:42:31.316] Build 8927955495488276912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:42:31.316] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093385557428375824:6:0) after 1m0s
[18:43:31.333] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093385557428375824:6:0)
[18:43:31.333] Timer tick, asking Buildbucket for the build status
[18:43:31.594] Build 8927955495488276912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:43:31.594] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093385557428375824:7:0) after 1m0s
[18:44:31.619] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093385557428375824:7:0)
[18:44:31.619] Timer tick, asking Buildbucket for the build status
[18:44:31.655] Build 8927955495488276912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:44:31.655] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093385557428375824:8:0) after 1m0s
[18:45:31.674] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093385557428375824:8:0)
[18:45:31.674] Timer tick, asking Buildbucket for the build status
[18:45:31.747] Build 8927955495488276912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:45:31.747] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093385557428375824:9:0) after 1m0s
[18:46:31.766] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093385557428375824:9:0)
[18:46:31.766] Timer tick, asking Buildbucket for the build status
[18:46:31.854] Build 8927955495488276912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:46:31.854] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093385557428375824:10:0) after 1m0s
[18:47:31.877] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093385557428375824:10:0)
[18:47:31.877] Timer tick, asking Buildbucket for the build status
[18:47:31.952] Build 8927955495488276912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:47:31.952] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093385557428375824:11:0) after 1m0s
[18:48:31.984] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093385557428375824:11:0)
[18:48:31.984] Timer tick, asking Buildbucket for the build status
[18:48:32.085] Build 8927955495488276912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:48:32.085] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093385557428375824:12:0) after 1m0s
[18:49:32.105] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093385557428375824:12:0)
[18:49:32.105] Timer tick, asking Buildbucket for the build status
[18:49:32.222] Build 8927955495488276912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:49:32.222] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093385557428375824:13:0) after 1m0s
[18:50:32.246] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093385557428375824:13:0)
[18:50:32.246] Timer tick, asking Buildbucket for the build status
[18:50:32.341] Build 8927955495488276912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:50:32.341] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093385557428375824:14:0) after 1m0s
[18:51:32.389] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093385557428375824:14:0)
[18:51:32.389] Timer tick, asking Buildbucket for the build status
[18:51:32.470] Build 8927955495488276912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:51:32.470] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093385557428375824:15:0) after 1m0s
[18:52:32.508] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093385557428375824:15:0)
[18:52:32.508] Timer tick, asking Buildbucket for the build status
[18:52:32.662] Build 8927955495488276912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:52:32.662] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093385557428375824:16:0) after 1m0s
[18:53:32.684] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093385557428375824:16:0)
[18:53:32.684] Timer tick, asking Buildbucket for the build status
[18:53:32.722] Build 8927955495488276912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:53:32.722] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093385557428375824:17:0) after 1m0s
[18:54:32.745] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093385557428375824:17:0)
[18:54:32.745] Timer tick, asking Buildbucket for the build status
[18:54:32.804] Build 8927955495488276912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:54:32.804] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093385557428375824:18:0) after 1m0s
[18:55:32.834] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093385557428375824:18:0)
[18:55:32.834] Timer tick, asking Buildbucket for the build status
[18:55:32.889] Build 8927955495488276912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:55:32.889] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093385557428375824:19:0) after 1m0s
[18:56:32.948] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093385557428375824:19:0)
[18:56:32.948] Timer tick, asking Buildbucket for the build status
[18:56:33.002] Build 8927955495488276912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:56:33.002] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093385557428375824:20:0) after 1m0s
[18:57:33.033] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093385557428375824:20:0)
[18:57:33.033] Timer tick, asking Buildbucket for the build status
[18:57:33.092] Build 8927955495488276912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:57:33.092] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093385557428375824:21:0) after 1m0s
[18:58:33.116] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093385557428375824:21:0)
[18:58:33.116] Timer tick, asking Buildbucket for the build status
[18:58:33.200] Build 8927955495488276912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:58:33.200] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093385557428375824:22:0) after 1m0s
[18:59:33.219] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093385557428375824:22:0)
[18:59:33.219] Timer tick, asking Buildbucket for the build status
[18:59:33.361] Build 8927955495488276912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:59:33.361] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093385557428375824:23:0) after 1m0s
[19:00:33.379] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093385557428375824:23:0)
[19:00:33.379] Timer tick, asking Buildbucket for the build status
[19:00:33.488] Build 8927955495488276912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:00:33.488] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093385557428375824:24:0) after 1m0s
[19:01:33.514] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093385557428375824:24:0)
[19:01:33.514] Timer tick, asking Buildbucket for the build status
[19:01:33.605] Build 8927955495488276912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:01:33.605] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093385557428375824:25:0) after 1m0s
[19:02:33.629] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093385557428375824:25:0)
[19:02:33.629] Timer tick, asking Buildbucket for the build status
[19:02:33.701] Build 8927955495488276912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:02:33.701] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093385557428375824:26:0) after 1m0s
[19:03:33.721] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093385557428375824:26:0)
[19:03:33.721] Timer tick, asking Buildbucket for the build status
[19:03:33.781] Build 8927955495488276912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:03:33.781] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093385557428375824:27:0) after 1m0s
[19:04:33.818] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093385557428375824:27:0)
[19:04:33.818] Timer tick, asking Buildbucket for the build status
[19:04:33.930] Build 8927955495488276912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:04:33.930] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093385557428375824:28:0) after 1m0s
[19:05:33.953] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093385557428375824:28:0)
[19:05:33.953] Timer tick, asking Buildbucket for the build status
[19:05:33.994] Build 8927955495488276912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:05:33.994] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093385557428375824:29:0) after 1m0s
[19:06:34.237] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093385557428375824:29:0)
[19:06:34.237] Timer tick, asking Buildbucket for the build status
[19:06:34.360] Build 8927955495488276912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:06:34.360] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093385557428375824:30:0) after 1m0s
[19:07:34.387] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093385557428375824:30:0)
[19:07:34.387] Timer tick, asking Buildbucket for the build status
[19:07:34.463] Build 8927955495488276912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:07:34.463] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093385557428375824:31:0) after 1m0s
[19:08:34.481] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093385557428375824:31:0)
[19:08:34.481] Timer tick, asking Buildbucket for the build status
[19:08:34.559] Build 8927955495488276912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:08:34.559] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093385557428375824:32:0) after 1m0s
[19:09:34.631] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093385557428375824:32:0)
[19:09:34.631] Timer tick, asking Buildbucket for the build status
[19:09:34.678] Build 8927955495488276912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:09:34.678] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093385557428375824:33:0) after 1m0s
[19:10:34.708] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093385557428375824:33:0)
[19:10:34.708] Timer tick, asking Buildbucket for the build status
[19:10:34.773] Build 8927955495488276912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:10:34.773] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093385557428375824:34:0) after 1m0s
[19:11:34.802] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093385557428375824:34:0)
[19:11:34.802] Timer tick, asking Buildbucket for the build status
[19:11:34.871] Build 8927955495488276912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:11:34.871] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093385557428375824:35:0) after 1m0s
[19:12:34.897] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093385557428375824:35:0)
[19:12:34.897] Timer tick, asking Buildbucket for the build status
[19:12:35.039] Build 8927955495488276912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:12:35.039] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093385557428375824:36:0) after 1m0s
[19:13:35.064] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093385557428375824:36:0)
[19:13:35.064] Timer tick, asking Buildbucket for the build status
[19:13:35.108] Build 8927955495488276912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:13:35.108] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093385557428375824:37:0) after 1m0s
[19:14:35.186] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093385557428375824:37:0)
[19:14:35.186] Timer tick, asking Buildbucket for the build status
[19:14:35.299] Build 8927955495488276912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:14:35.299] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093385557428375824:38:0) after 1m0s
[19:15:35.330] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093385557428375824:38:0)
[19:15:35.330] Timer tick, asking Buildbucket for the build status
[19:15:35.444] Build 8927955495488276912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:15:35.444] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093385557428375824:39:0) after 1m0s
[19:16:35.468] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093385557428375824:39:0)
[19:16:35.468] Timer tick, asking Buildbucket for the build status
[19:16:35.538] Build 8927955495488276912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:16:35.538] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093385557428375824:40:0) after 1m0s
[19:17:35.556] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093385557428375824:40:0)
[19:17:35.556] Timer tick, asking Buildbucket for the build status
[19:17:35.613] Build 8927955495488276912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:17:35.613] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093385557428375824:41:0) after 1m0s
[19:18:35.632] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093385557428375824:41:0)
[19:18:35.632] Timer tick, asking Buildbucket for the build status
[19:18:35.678] Build 8927955495488276912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:18:35.678] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093385557428375824:42:0) after 1m0s
[19:19:35.702] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093385557428375824:42:0)
[19:19:35.702] Timer tick, asking Buildbucket for the build status
[19:19:35.803] Build 8927955495488276912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:19:35.803] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093385557428375824:43:0) after 1m0s
[19:20:35.821] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093385557428375824:43:0)
[19:20:35.821] Timer tick, asking Buildbucket for the build status
[19:20:35.935] Build 8927955495488276912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:20:35.935] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093385557428375824:44:0) after 1m0s
[19:21:35.964] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093385557428375824:44:0)
[19:21:35.964] Timer tick, asking Buildbucket for the build status
[19:21:36.024] Build 8927955495488276912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:21:36.024] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093385557428375824:45:0) after 1m0s
[19:22:36.043] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093385557428375824:45:0)
[19:22:36.043] Timer tick, asking Buildbucket for the build status
[19:22:36.110] Build 8927955495488276912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:22:36.110] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093385557428375824:46:0) after 1m0s
[19:23:36.131] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093385557428375824:46:0)
[19:23:36.131] Timer tick, asking Buildbucket for the build status
[19:23:36.222] Build 8927955495488276912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:23:36.222] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093385557428375824:47:0) after 1m0s
[19:24:36.259] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093385557428375824:47:0)
[19:24:36.259] Timer tick, asking Buildbucket for the build status
[19:24:36.336] Build 8927955495488276912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:24:36.336] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093385557428375824:48:0) after 1m0s
[19:25:36.354] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093385557428375824:48:0)
[19:25:36.354] Timer tick, asking Buildbucket for the build status
[19:25:36.460] Build 8927955495488276912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:25:36.460] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093385557428375824:49:0) after 1m0s
[19:26:36.480] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093385557428375824:49:0)
[19:26:36.480] Timer tick, asking Buildbucket for the build status
[19:26:36.568] Build 8927955495488276912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:26:36.568] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093385557428375824:50:0) after 1m0s
[19:27:36.585] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093385557428375824:50:0)
[19:27:36.585] Timer tick, asking Buildbucket for the build status
[19:27:36.651] Build 8927955495488276912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:27:36.651] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093385557428375824:51:0) after 1m0s
[19:28:36.671] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093385557428375824:51:0)
[19:28:36.671] Timer tick, asking Buildbucket for the build status
[19:28:36.711] Build 8927955495488276912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:28:36.711] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093385557428375824:52:0) after 1m0s
[19:29:36.730] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093385557428375824:52:0)
[19:29:36.730] Timer tick, asking Buildbucket for the build status
[19:29:36.770] Build 8927955495488276912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:29:36.770] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093385557428375824:53:0) after 1m0s
[19:30:36.793] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093385557428375824:53:0)
[19:30:36.793] Timer tick, asking Buildbucket for the build status
[19:30:36.854] Build 8927955495488276912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:30:36.854] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093385557428375824:54:0) after 1m0s
[19:31:36.881] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093385557428375824:54:0)
[19:31:36.881] Timer tick, asking Buildbucket for the build status
[19:31:36.935] Build 8927955495488276912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:31:36.935] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093385557428375824:55:0) after 1m0s
[19:32:36.956] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093385557428375824:55:0)
[19:32:36.956] Timer tick, asking Buildbucket for the build status
[19:32:36.998] Build 8927955495488276912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:32:36.999] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093385557428375824:56:0) after 1m0s
[19:33:37.027] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093385557428375824:56:0)
[19:33:37.027] Timer tick, asking Buildbucket for the build status
[19:33:37.075] Build 8927955495488276912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:33:37.075] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093385557428375824:57:0) after 1m0s
[19:34:37.103] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093385557428375824:57:0)
[19:34:37.103] Timer tick, asking Buildbucket for the build status
[19:34:37.201] Build 8927955495488276912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:34:37.201] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093385557428375824:58:0) after 1m0s
[19:35:37.249] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093385557428375824:58:0)
[19:35:37.249] Timer tick, asking Buildbucket for the build status
[19:35:37.292] Build 8927955495488276912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:35:37.292] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093385557428375824:59:0) after 1m0s
[19:36:37.325] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093385557428375824:59:0)
[19:36:37.325] Timer tick, asking Buildbucket for the build status
[19:36:37.386] Build 8927955495488276912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:36:37.386] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093385557428375824:60:0) after 1m0s
[19:37:37.408] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093385557428375824:60:0)
[19:37:37.408] Timer tick, asking Buildbucket for the build status
[19:37:37.463] Build 8927955495488276912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:37:37.463] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093385557428375824:61:0) after 1m0s
[19:38:37.487] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093385557428375824:61:0)
[19:38:37.487] Timer tick, asking Buildbucket for the build status
[19:38:37.559] Build 8927955495488276912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:38:37.559] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093385557428375824:62:0) after 1m0s
[19:39:37.588] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093385557428375824:62:0)
[19:39:37.588] Timer tick, asking Buildbucket for the build status
[19:39:37.620] Build 8927955495488276912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:39:37.620] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093385557428375824:63:0) after 1m0s
[19:40:37.738] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093385557428375824:63:0)
[19:40:37.738] Timer tick, asking Buildbucket for the build status
[19:40:37.797] Build 8927955495488276912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:40:37.797] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093385557428375824:64:0) after 1m0s
[19:41:37.881] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093385557428375824:64:0)
[19:41:37.881] Timer tick, asking Buildbucket for the build status
[19:41:37.918] Build 8927955495488276912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:41:37.918] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093385557428375824:65:0) after 1m0s
[19:42:37.943] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093385557428375824:65:0)
[19:42:37.943] Timer tick, asking Buildbucket for the build status
[19:42:38.090] Build 8927955495488276912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:42:38.090] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093385557428375824:66:0) after 1m0s
[19:43:38.110] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093385557428375824:66:0)
[19:43:38.110] Timer tick, asking Buildbucket for the build status
[19:43:38.317] Build 8927955495488276912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:43:38.317] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093385557428375824:67:0) after 1m0s
[19:44:38.340] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093385557428375824:67:0)
[19:44:38.340] Timer tick, asking Buildbucket for the build status
[19:44:38.420] Build 8927955495488276912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:44:38.420] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093385557428375824:68:0) after 1m0s
[19:45:38.443] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093385557428375824:68:0)
[19:45:38.443] Timer tick, asking Buildbucket for the build status
[19:45:38.513] Build 8927955495488276912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:45:38.513] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093385557428375824:69:0) after 1m0s
[19:46:38.538] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093385557428375824:69:0)
[19:46:38.538] Timer tick, asking Buildbucket for the build status
[19:46:38.626] Build 8927955495488276912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:46:38.626] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093385557428375824:70:0) after 1m0s
[19:47:38.660] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093385557428375824:70:0)
[19:47:38.660] Timer tick, asking Buildbucket for the build status
[19:47:38.697] Build 8927955495488276912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:47:38.697] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093385557428375824:71:0) after 1m0s
[19:48:38.738] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093385557428375824:71:0)
[19:48:38.738] Timer tick, asking Buildbucket for the build status
[19:48:38.813] Build 8927955495488276912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:48:38.813] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093385557428375824:72:0) after 1m0s
[19:49:38.865] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093385557428375824:72:0)
[19:49:38.865] Timer tick, asking Buildbucket for the build status
[19:49:38.928] Build 8927955495488276912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:49:38.928] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093385557428375824:73:0) after 1m0s
[19:50:39.058] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093385557428375824:73:0)
[19:50:39.058] Timer tick, asking Buildbucket for the build status
[19:50:39.146] Build 8927955495488276912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:50:39.146] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093385557428375824:74:0) after 1m0s
[19:51:39.166] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093385557428375824:74:0)
[19:51:39.166] Timer tick, asking Buildbucket for the build status
[19:51:39.221] Build 8927955495488276912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:51:39.221] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093385557428375824:75:0) after 1m0s
[19:52:39.242] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093385557428375824:75:0)
[19:52:39.242] Timer tick, asking Buildbucket for the build status
[19:52:39.340] Build 8927955495488276912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:52:39.340] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093385557428375824:76:0) after 1m0s
[19:53:39.374] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093385557428375824:76:0)
[19:53:39.374] Timer tick, asking Buildbucket for the build status
[19:53:39.478] Build 8927955495488276912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:53:39.478] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093385557428375824:77:0) after 1m0s
[19:54:39.517] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093385557428375824:77:0)
[19:54:39.517] Timer tick, asking Buildbucket for the build status
[19:54:39.632] Build 8927955495488276912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:54:39.632] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093385557428375824:78:0) after 1m0s
[19:55:39.655] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093385557428375824:78:0)
[19:55:39.655] Timer tick, asking Buildbucket for the build status
[19:55:39.697] Build 8927955495488276912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:55:39.697] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093385557428375824:79:0) after 1m0s
[19:56:39.770] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093385557428375824:79:0)
[19:56:39.770] Timer tick, asking Buildbucket for the build status
[19:56:39.936] Build 8927955495488276912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:56:39.936] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093385557428375824:80:0) after 1m0s
[19:57:39.977] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093385557428375824:80:0)
[19:57:39.977] Timer tick, asking Buildbucket for the build status
[19:57:40.021] Build 8927955495488276912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:57:40.021] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093385557428375824:81:0) after 1m0s
[19:58:40.052] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093385557428375824:81:0)
[19:58:40.052] Timer tick, asking Buildbucket for the build status
[19:58:40.176] Build 8927955495488276912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:58:40.176] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093385557428375824:82:0) after 1m0s
[19:59:40.219] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093385557428375824:82:0)
[19:59:40.219] Timer tick, asking Buildbucket for the build status
[19:59:40.286] Build 8927955495488276912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:59:40.286] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093385557428375824:83:0) after 1m0s
[20:00:40.337] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093385557428375824:83:0)
[20:00:40.337] Timer tick, asking Buildbucket for the build status
[20:00:40.488] Build 8927955495488276912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:00:40.488] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093385557428375824:84:0) after 1m0s
[20:01:40.510] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093385557428375824:84:0)
[20:01:40.510] Timer tick, asking Buildbucket for the build status
[20:01:40.572] Build 8927955495488276912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:01:40.572] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093385557428375824:85:0) after 1m0s
[20:02:40.615] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093385557428375824:85:0)
[20:02:40.615] Timer tick, asking Buildbucket for the build status
[20:02:40.657] Build 8927955495488276912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:02:40.657] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093385557428375824:86:0) after 1m0s
[20:03:40.683] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093385557428375824:86:0)
[20:03:40.683] Timer tick, asking Buildbucket for the build status
[20:03:40.743] Build 8927955495488276912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:03:40.743] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093385557428375824:87:0) after 1m0s
[20:04:40.773] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093385557428375824:87:0)
[20:04:40.773] Timer tick, asking Buildbucket for the build status
[20:04:40.805] Build 8927955495488276912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:04:40.805] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093385557428375824:88:0) after 1m0s
[20:05:40.835] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093385557428375824:88:0)
[20:05:40.835] Timer tick, asking Buildbucket for the build status
[20:05:40.908] Build 8927955495488276912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:05:40.908] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093385557428375824:89:0) after 1m0s
[20:06:40.931] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093385557428375824:89:0)
[20:06:40.931] Timer tick, asking Buildbucket for the build status
[20:06:40.973] Build 8927955495488276912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:06:40.973] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093385557428375824:90:0) after 1m0s
[20:07:41.013] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093385557428375824:90:0)
[20:07:41.013] Timer tick, asking Buildbucket for the build status
[20:07:41.217] Build 8927955495488276912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:07:41.217] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093385557428375824:91:0) after 1m0s
[20:08:41.263] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093385557428375824:91:0)
[20:08:41.263] Timer tick, asking Buildbucket for the build status
[20:08:41.361] Build 8927955495488276912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:08:41.361] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093385557428375824:92:0) after 1m0s
[20:09:41.385] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093385557428375824:92:0)
[20:09:41.385] Timer tick, asking Buildbucket for the build status
[20:09:41.422] Build 8927955495488276912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:09:41.422] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093385557428375824:93:0) after 1m0s
[20:10:41.451] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093385557428375824:93:0)
[20:10:41.451] Timer tick, asking Buildbucket for the build status
[20:10:41.566] Build 8927955495488276912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:10:41.566] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093385557428375824:94:0) after 1m0s
[20:11:41.589] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093385557428375824:94:0)
[20:11:41.589] Timer tick, asking Buildbucket for the build status
[20:11:41.682] Build 8927955495488276912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:11:41.682] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093385557428375824:95:0) after 1m0s
[20:12:41.724] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093385557428375824:95:0)
[20:12:41.724] Timer tick, asking Buildbucket for the build status
[20:12:41.774] Build 8927955495488276912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:12:41.774] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093385557428375824:96:0) after 1m0s
[20:13:41.809] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093385557428375824:96:0)
[20:13:41.809] Timer tick, asking Buildbucket for the build status
[20:13:41.841] Build 8927955495488276912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:13:41.841] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093385557428375824:97:0) after 1m0s
[20:14:41.868] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093385557428375824:97:0)
[20:14:41.868] Timer tick, asking Buildbucket for the build status
[20:14:42.006] Build 8927955495488276912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:14:42.006] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093385557428375824:98:0) after 1m0s
[20:15:42.047] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093385557428375824:98:0)
[20:15:42.047] Timer tick, asking Buildbucket for the build status
[20:15:42.104] Build 8927955495488276912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:15:42.104] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093385557428375824:99:0) after 1m0s
[20:16:42.129] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093385557428375824:99:0)
[20:16:42.129] Timer tick, asking Buildbucket for the build status
[20:16:42.229] Build 8927955495488276912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:16:42.229] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093385557428375824:100:0) after 1m0s
[20:17:42.267] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093385557428375824:100:0)
[20:17:42.267] Timer tick, asking Buildbucket for the build status
[20:17:42.308] Build 8927955495488276912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:17:42.308] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093385557428375824:101:0) after 1m0s
[20:18:42.334] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093385557428375824:101:0)
[20:18:42.334] Timer tick, asking Buildbucket for the build status
[20:18:42.481] Build 8927955495488276912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:18:42.481] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093385557428375824:102:0) after 1m0s
[20:19:42.505] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093385557428375824:102:0)
[20:19:42.505] Timer tick, asking Buildbucket for the build status
[20:19:42.578] Build 8927955495488276912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:19:42.578] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093385557428375824:103:0) after 1m0s
[20:20:42.611] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093385557428375824:103:0)
[20:20:42.611] Timer tick, asking Buildbucket for the build status
[20:20:42.653] Build 8927955495488276912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:20:42.653] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093385557428375824:104:0) after 1m0s
[20:21:42.686] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093385557428375824:104:0)
[20:21:42.686] Timer tick, asking Buildbucket for the build status
[20:21:42.768] Build 8927955495488276912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:21:42.768] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093385557428375824:105:0) after 1m0s
[20:22:42.797] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093385557428375824:105:0)
[20:22:42.797] Timer tick, asking Buildbucket for the build status
[20:22:42.927] Build 8927955495488276912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:22:42.927] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093385557428375824:106:0) after 1m0s
[20:23:42.955] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093385557428375824:106:0)
[20:23:42.955] Timer tick, asking Buildbucket for the build status
[20:23:43.038] Build 8927955495488276912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:23:43.038] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093385557428375824:107:0) after 1m0s
[20:24:43.061] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093385557428375824:107:0)
[20:24:43.061] Timer tick, asking Buildbucket for the build status
[20:24:43.092] Build 8927955495488276912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:24:43.092] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093385557428375824:108:0) after 1m0s
[20:25:43.117] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093385557428375824:108:0)
[20:25:43.117] Timer tick, asking Buildbucket for the build status
[20:25:43.276] Build 8927955495488276912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:25:43.276] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093385557428375824:109:0) after 1m0s
[20:26:43.300] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093385557428375824:109:0)
[20:26:43.300] Timer tick, asking Buildbucket for the build status
[20:26:43.358] Build 8927955495488276912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:26:43.358] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093385557428375824:110:0) after 1m0s
[20:27:43.383] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093385557428375824:110:0)
[20:27:43.383] Timer tick, asking Buildbucket for the build status
[20:27:43.420] Build 8927955495488276912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:27:43.420] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093385557428375824:111:0) after 1m0s
[20:28:43.464] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093385557428375824:111:0)
[20:28:43.464] Timer tick, asking Buildbucket for the build status
[20:28:43.538] Build 8927955495488276912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:28:43.538] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093385557428375824:112:0) after 1m0s
[20:29:43.560] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093385557428375824:112:0)
[20:29:43.560] Timer tick, asking Buildbucket for the build status
[20:29:43.613] Build 8927955495488276912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:29:43.613] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093385557428375824:113:0) after 1m0s
[20:30:43.635] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093385557428375824:113:0)
[20:30:43.635] Timer tick, asking Buildbucket for the build status
[20:30:43.765] Build 8927955495488276912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:30:43.765] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093385557428375824:114:0) after 1m0s
[20:31:43.786] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093385557428375824:114:0)
[20:31:43.786] Timer tick, asking Buildbucket for the build status
[20:31:43.938] Build 8927955495488276912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:31:43.938] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093385557428375824:115:0) after 1m0s
[20:34:29.107] Received PubSub notification, asking Buildbucket for the build status
[20:34:29.214] Build 8927955495488276912: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[20:34:29.214] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"completed_ts": "1544042067703721",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1544035168142504",
"failure_reason": "BUILD_FAILURE",
"id": "8927955495488276912",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"cc19edbe4b1d7899d5e3f3cf93cca71b6d0e5603\"}}",
"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\": [\"3910-a71d441\"], \"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\": \"277022e0e51ae646448daf2d36a81502b8ac53b7\", \"got_swarming_client_revision\": \"0e3e1c4dc4e79f25a5b58fcbc135dc93183c0c54\", \"got_revision\": \"cc19edbe4b1d7899d5e3f3cf93cca71b6d0e5603\", \"recipe\": \"swarming/deterministic_build\", \"got_dawn_revision\": \"7b3ea34b6b132efe7d93f0025dc7cbbe50641b9c\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#25904}\", \"$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@{#614036}\", \"branch\": \"refs/heads/master\", \"revision\": \"cc19edbe4b1d7899d5e3f3cf93cca71b6d0e5603\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"buildername\": \"Windows deterministic\", \"got_webrtc_revision\": \"c979c46bdaf206805c62cb687be450c93dbfb533\", \"mastername\": \"chromium.win\", \"got_angle_revision\": \"81a880aaeb5757a0199d21ed4835bb1964f8ad80\", \"buildbucket\": {\"hostname\": \"cr-buildbucket.appspot.com\", \"build\": {\"created_ts\": 1544035168142504, \"tags\": [\"builder:Windows deterministic\", \"buildset:commit/git/cc19edbe4b1d7899d5e3f3cf93cca71b6d0e5603\", \"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/cc19edbe4b1d7899d5e3f3cf93cca71b6d0e5603\", \"gitiles_ref:refs/heads/master\", \"scheduler_invocation_id:9093385557428375824\", \"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\": \"8927955495488276912\"}}, \"got_v8_revision\": \"d09fb9292fd649ae3b61d7ad928e3afb0dd493d2\", \"got_v8_revision_cp\": \"refs/heads/7.3.33@{#1}\", \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"buildnumber\": 10973, \"path_config\": \"generic\", \"bot_id\": \"swarm2173-c4\", \"got_buildtools_revision\": \"04161ec8d7c781e4498c699254c69ba0dd959fde\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1544035315146006",
"status": "COMPLETED",
"status_changed_ts": "1544042068625647",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/10973",
"builder:Windows deterministic",
"buildset:commit/git/cc19edbe4b1d7899d5e3f3cf93cca71b6d0e5603",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/cc19edbe4b1d7899d5e3f3cf93cca71b6d0e5603",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9093385557428375824",
"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/10973",
"swarming_tag:buildbucket_bucket:chromium/ci",
"swarming_tag:buildbucket_build_id:8927955495488276912",
"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/cc19edbe4b1d7899d5e3f3cf93cca71b6d0e5603",
"swarming_tag:buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/cc19edbe4b1d7899d5e3f3cf93cca71b6d0e5603",
"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/8927955495488276912/+/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:9093385557428375824",
"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:e1abadd4123c770a71ceacbcfd6afb2995a718ae",
"swarming_tag:user:None",
"swarming_tag:user_agent:luci-scheduler",
"swarming_tag:vpython:native-python-wrapper",
"swarming_task_id:41987c7db6503110",
"user_agent:luci-scheduler"
],
"updated_ts": "1544042068626994",
"url": "https://ci.chromium.org/b/8927955495488276912",
"utcnow_ts": "1544042069151792"
}
}
[20:34:29.214] Invocation finished in 1h55m2.305056593s with status FAILED